2016-03-23 15:38:06,445 [DEBUG] [doorpi.main] loaded with arguments: ['/usr/local/bin/doorpi_cli', '--trace'] 2016-03-23 15:38:06,448 [DEBUG] [doorpi.doorpi] run 2016-03-23 15:38:06,450 [DEBUG] [doorpi.doorpi] prepare 2016-03-23 15:38:06,451 [DEBUG] [doorpi.doorpi] given arguments argv: Namespace(configfile=None, debug=False, test=False, trace=True) 2016-03-23 15:38:06,455 [INFO] [doorpi.conf.config_object] use configfile: /usr/local/etc/DoorPi/conf/doorpi.ini 2016-03-23 15:38:06,478 [DEBUG] [doorpi.conf.config_object] __init__ 2016-03-23 15:38:06,479 [TRACE] [doorpi.conf.config_object] get_from_config 2016-03-23 15:38:06,492 [TRACE] [doorpi.conf.config_object] get_string for key base_path in section DoorPi (default: /usr/local/etc/DoorPi) returns /usr/local/etc/DoorPi 2016-03-23 15:38:06,493 [TRACE] [doorpi.conf.config_object] get_string for key eventlog in section DoorPi (default: !BASEPATH!/conf/eventlog.db) returns !BASEPATH!/conf/eventlog.db 2016-03-23 15:38:06,494 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/conf/eventlog.db" to "/usr/local/etc/DoorPi/conf/eventlog.db" 2016-03-23 15:38:06,499 [TRACE] [doorpi.action.handler] register Event BeforeStartup from doorpi.doorpi 2016-03-23 15:38:06,500 [DEBUG] [doorpi.action.handler] event_source doorpi.doorpi was added 2016-03-23 15:38:06,501 [TRACE] [doorpi.action.handler] added event_name BeforeStartup and registered source doorpi.doorpi 2016-03-23 15:38:06,502 [TRACE] [doorpi.action.handler] register Event OnStartup from doorpi.doorpi 2016-03-23 15:38:06,503 [TRACE] [doorpi.action.handler] added event_name OnStartup and registered source doorpi.doorpi 2016-03-23 15:38:06,503 [TRACE] [doorpi.action.handler] register Event AfterStartup from doorpi.doorpi 2016-03-23 15:38:06,504 [TRACE] [doorpi.action.handler] added event_name AfterStartup and registered source doorpi.doorpi 2016-03-23 15:38:06,505 [TRACE] [doorpi.action.handler] register Event BeforeShutdown from doorpi.doorpi 2016-03-23 15:38:06,506 [TRACE] [doorpi.action.handler] added event_name BeforeShutdown and registered source doorpi.doorpi 2016-03-23 15:38:06,507 [TRACE] [doorpi.action.handler] register Event OnShutdown from doorpi.doorpi 2016-03-23 15:38:06,507 [TRACE] [doorpi.action.handler] added event_name OnShutdown and registered source doorpi.doorpi 2016-03-23 15:38:06,508 [TRACE] [doorpi.action.handler] register Event AfterShutdown from doorpi.doorpi 2016-03-23 15:38:06,509 [TRACE] [doorpi.action.handler] added event_name AfterShutdown and registered source doorpi.doorpi 2016-03-23 15:38:06,512 [DEBUG] [doorpi.action.SingleActions.time_tick] doorpi.action.SingleActions.time_tick loaded 2016-03-23 15:38:06,514 [DEBUG] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was added 2016-03-23 15:38:06,518 [TRACE] [doorpi.action.handler] action TimeTickDestroyAction with args () and kwargs {} was added to new evententry OnShutdown 2016-03-23 15:38:06,519 [TRACE] [doorpi.action.handler] action TimeTickAction with args ('!last_tick!',) and kwargs {} was added to new evententry OnTimeTick 2016-03-23 15:38:06,520 [TRACE] [doorpi.conf.config_object] get_string for key ip in section DoorPiWeb (default: ) returns 2016-03-23 15:38:06,521 [TRACE] [doorpi.conf.config_object] get_integer for key port in section DoorPiWeb (default: 80) returns 80 2016-03-23 15:38:06,525 [INFO] [doorpi.status.webserver] Initiating WebService at ip and port 80 2016-03-23 15:38:06,526 [TRACE] [doorpi.action.handler] register Event OnWebServerStart from doorpi.status.webserver 2016-03-23 15:38:06,527 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver was added 2016-03-23 15:38:06,528 [TRACE] [doorpi.action.handler] added event_name OnWebServerStart and registered source doorpi.status.webserver 2016-03-23 15:38:06,528 [TRACE] [doorpi.action.handler] register Event OnWebServerStop from doorpi.status.webserver 2016-03-23 15:38:06,529 [TRACE] [doorpi.action.handler] added event_name OnWebServerStop and registered source doorpi.status.webserver 2016-03-23 15:38:06,530 [TRACE] [doorpi.conf.config_object] get_string for key www in section DoorPiWeb (default: !BASEPATH!/../DoorPiWeb) returns !BASEPATH!/../DoorPiWeb 2016-03-23 15:38:06,532 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/../DoorPiWeb" to "/usr/local/etc/DoorPi/../DoorPiWeb" 2016-03-23 15:38:06,533 [TRACE] [doorpi.conf.config_object] get_string for key indexfile in section DoorPiWeb (default: index.html) returns index.html 2016-03-23 15:38:06,534 [DEBUG] [doorpi.conf.config_object] parse string "index.html" to "index.html" 2016-03-23 15:38:06,535 [TRACE] [doorpi.conf.config_object] get_string for key loginfile in section DoorPiWeb (default: login.html) returns login.html 2016-03-23 15:38:06,536 [DEBUG] [doorpi.conf.config_object] parse string "login.html" to "login.html" 2016-03-23 15:38:06,537 [TRACE] [doorpi.conf.config_object] get_string for key public in section DoorPiWeb (default: AREA_public) returns AREA_public 2016-03-23 15:38:06,538 [DEBUG] [doorpi.conf.config_object] parse string "AREA_public" to "AREA_public" 2016-03-23 15:38:06,539 [TRACE] [doorpi.conf.config_object] get_string for key online_fallback in section DoorPiWeb (default: http://motom001.github.io/DoorPiWeb) returns http://motom001.github.io/DoorPiWeb 2016-03-23 15:38:06,540 [DEBUG] [doorpi.conf.config_object] parse string "http://motom001.github.io/DoorPiWeb" to "http://motom001.github.io/DoorPiWeb" 2016-03-23 15:38:06,541 [TRACE] [doorpi.conf.config_object] get_keys for section WritePermission returns ['administrators'] 2016-03-23 15:38:06,542 [TRACE] [doorpi.conf.config_object] get_keys for section ReadPermission returns ['administrators', 'guest'] 2016-03-23 15:38:06,543 [TRACE] [doorpi.conf.config_object] get_keys for section Group returns ['administrators', 'guests'] 2016-03-23 15:38:06,544 [TRACE] [doorpi.conf.config_object] get_keys for section User returns ['pi', 'root', 'guest'] 2016-03-23 15:38:06,545 [TRACE] [doorpi.conf.config_object] get_list for key administrators in section Group (default: []) returns ['installer', ' pi', ' root'] 2016-03-23 15:38:06,546 [TRACE] [doorpi.conf.config_object] get_list for key guests in section Group (default: []) returns ['guests'] 2016-03-23 15:38:06,547 [TRACE] [doorpi.conf.config_object] get_sections returns ['Group', 'ReadPermission', 'DTMF', 'EVENT_OnMediaNotRequired', 'prodsystem_OutputPins', 'EVENT_OnStartup', 'EVENT_OnKeyUp_prodsystem.7', 'DoorPiWeb', 'OnCallIncomming', 'EVENT_OnKeyUp_prodsystem.33', 'AREA_public', 'prodsystem_keyboard', 'WritePermission', 'EVENT_OnKeyUp_prodsystem.15', 'EVENT_OnKeyUp_prodsystem.31', 'EVENT_OnKeyUp_prodsystem.11', 'User', 'EVENT_OnKeyUp_prodsystem.13', 'EVENT_OnCallStateConnect', 'EVENT_OnKeyUp_prodsystem.35', 'prodsystem_InputPins', 'EVENT_OnMediaRequired', 'keyboards', 'AdminNumbers', 'SIP-Phone', 'DoorPi'] 2016-03-23 15:38:06,548 [TRACE] [doorpi.conf.config_object] get_list for key administrators in section WritePermission (default: []) returns ['dashboard', 'status', 'config'] 2016-03-23 15:38:06,549 [TRACE] [doorpi.conf.config_object] get_list for key administrators in section ReadPermission (default: []) returns ['dashboard', 'status', 'config'] 2016-03-23 15:38:06,550 [TRACE] [doorpi.conf.config_object] get_list for key guest in section ReadPermission (default: []) returns ['dashboard'] 2016-03-23 15:38:06,551 [ERROR] [doorpi.status.webserver] group guest doesn't exist but is assigned to ReadPermission 2016-03-23 15:38:06,552 [ERROR] [doorpi.status.webserver] user installer is assigned to group administrators but doesn't exist as user 2016-03-23 15:38:06,553 [ERROR] [doorpi.status.webserver] user pi is assigned to group administrators but doesn't exist as user 2016-03-23 15:38:06,553 [ERROR] [doorpi.status.webserver] user root is assigned to group administrators but doesn't exist as user 2016-03-23 15:38:06,554 [ERROR] [doorpi.status.webserver] user guests is assigned to group guests but doesn't exist as user 2016-03-23 15:38:06,555 [ERROR] [doorpi.status.webserver] module dashboard doesn't exist but is assigned to group administrators in WritePermission 2016-03-23 15:38:06,556 [ERROR] [doorpi.status.webserver] module status doesn't exist but is assigned to group administrators in WritePermission 2016-03-23 15:38:06,556 [ERROR] [doorpi.status.webserver] module config doesn't exist but is assigned to group administrators in WritePermission 2016-03-23 15:38:06,557 [ERROR] [doorpi.status.webserver] module dashboard doesn't exist but is assigned to group administrators in ReadPermission 2016-03-23 15:38:06,558 [ERROR] [doorpi.status.webserver] module status doesn't exist but is assigned to group administrators in ReadPermission 2016-03-23 15:38:06,558 [ERROR] [doorpi.status.webserver] module config doesn't exist but is assigned to group administrators in ReadPermission 2016-03-23 15:38:06,559 [ERROR] [doorpi.status.webserver] module dashboard doesn't exist but is assigned to group guest in ReadPermission 2016-03-23 15:38:06,560 [TRACE] [doorpi.action.handler] action WebServerStartupAction with args () and kwargs {} was added to new evententry OnWebServerStart 2016-03-23 15:38:06,561 [TRACE] [doorpi.action.handler] action WebServerShutdownAction with args () and kwargs {} was added to event OnShutdown 2016-03-23 15:38:06,562 [TRACE] [doorpi.action.handler] fire Event OnWebServerStart from doorpi.status.webserver asyncron 2016-03-23 15:38:06,565 [TRACE] [doorpi.action.handler] register Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2016-03-23 15:38:06,566 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was added 2016-03-23 15:38:06,567 [DEBUG] [doorpi.action.handler] [IRSCTJ] fire for event OnWebServerStart this actions [] 2016-03-23 15:38:06,567 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequest and registered source doorpi.status.webserver_lib.request_handler 2016-03-23 15:38:06,568 [TRACE] [doorpi.action.handler] [IRSCTJ] try to fire action WebServerStartupAction with args () and kwargs {} 2016-03-23 15:38:06,569 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2016-03-23 15:38:06,570 [TRACE] [doorpi.action.base] run WebServerStartupAction with args () and kwargs {} 2016-03-23 15:38:06,571 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestGet and registered source doorpi.status.webserver_lib.request_handler 2016-03-23 15:38:06,573 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2016-03-23 15:38:06,574 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestPost and registered source doorpi.status.webserver_lib.request_handler 2016-03-23 15:38:06,575 [TRACE] [doorpi.action.handler] register Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2016-03-23 15:38:06,575 [TRACE] [doorpi.action.handler] added event_name OnWebServerVirtualResource and registered source doorpi.status.webserver_lib.request_handler 2016-03-23 15:38:06,576 [TRACE] [doorpi.action.handler] register Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler 2016-03-23 15:38:06,577 [TRACE] [doorpi.action.handler] added event_name OnWebServerRealResource and registered source doorpi.status.webserver_lib.request_handler 2016-03-23 15:38:06,578 [TRACE] [doorpi.action.handler] register Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2016-03-23 15:38:06,578 [TRACE] [doorpi.action.handler] added event_name OnFireEvent and registered source doorpi.status.webserver_lib.request_handler 2016-03-23 15:38:06,579 [TRACE] [doorpi.action.handler] register Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2016-03-23 15:38:06,580 [TRACE] [doorpi.action.handler] added event_name OnConfigKeySet and registered source doorpi.status.webserver_lib.request_handler 2016-03-23 15:38:06,581 [TRACE] [doorpi.action.handler] register Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2016-03-23 15:38:06,582 [TRACE] [doorpi.action.handler] added event_name OnConfigKeyDelete and registered source doorpi.status.webserver_lib.request_handler 2016-03-23 15:38:06,582 [TRACE] [doorpi.conf.config_object] get_keys for section keyboards returns ['pull_up_down', 'prodsystem'] 2016-03-23 15:38:06,583 [INFO] [doorpi.keyboard.KeyboardInterface] using multi-keyboard mode (keyboards: pull_up_down, prodsystem) 2016-03-23 15:38:06,584 [INFO] [doorpi.keyboard.KeyboardInterface] trying to add keyboard 'pull_up_down' to handler 2016-03-23 15:38:06,585 [TRACE] [doorpi.conf.config_object] get_string for key pull_up_down in section keyboards (default: dummy) returns PUD_UP 2016-03-23 15:38:06,586 [WARNING] [root] section pull_up_down_InputPins not found in configfile 2016-03-23 15:38:06,587 [TRACE] [doorpi.conf.config_object] get_keys for section pull_up_down_InputPins returns [] 2016-03-23 15:38:06,588 [WARNING] [root] section pull_up_down_OutputPins not found in configfile 2016-03-23 15:38:06,589 [TRACE] [doorpi.conf.config_object] get_keys for section pull_up_down_OutputPins returns [] 2016-03-23 15:38:06,590 [TRACE] [doorpi.conf.config_object] get_integer for key bouncetime in section pull_up_down_keyboard (default: 2000) returns 2000.0 2016-03-23 15:38:06,591 [TRACE] [doorpi.conf.config_object] get_integer for key polarity in section pull_up_down_keyboard (default: 0) returns 0 2016-03-23 15:38:06,592 [TRACE] [doorpi.conf.config_object] get_boolean for key pressed_on_keydown in section pull_up_down_keyboard (default: True) returns True 2016-03-23 15:38:06,593 [ERROR] [doorpi.keyboard.KeyboardInterface] keyboard pull_up_down not found @ keyboard.from_pud_up (msg: No module named from_pud_up) Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/DoorPi-2.5.0.0-py2.7.egg/doorpi/keyboard/KeyboardInterface.py", line 42, in load_single_keyboard keyboard = importlib.import_module('doorpi.keyboard.from_'+keyboard_type).get( File "/usr/lib/python2.7/importlib/__init__.py", line 37, in import_module __import__(name) ImportError: No module named from_pud_up 2016-03-23 15:38:06,596 [ERROR] [doorpi.keyboard.KeyboardInterface] couldn't load keyboard pull_up_down 2016-03-23 15:38:06,597 [INFO] [doorpi.keyboard.KeyboardInterface] trying to add keyboard 'prodsystem' to handler 2016-03-23 15:38:06,598 [TRACE] [doorpi.conf.config_object] get_string for key prodsystem in section keyboards (default: dummy) returns gpio 2016-03-23 15:38:06,599 [TRACE] [doorpi.conf.config_object] get_keys for section prodsystem_InputPins returns ['11', '13', '15', '33', '31', '35', '7'] 2016-03-23 15:38:06,599 [TRACE] [doorpi.conf.config_object] get_keys for section prodsystem_OutputPins returns ['12', '22', '16', '18', '36', '40', '38', '32'] 2016-03-23 15:38:06,600 [TRACE] [doorpi.conf.config_object] get_integer for key bouncetime in section prodsystem_keyboard (default: 2000) returns 80.0 2016-03-23 15:38:06,601 [TRACE] [doorpi.conf.config_object] get_integer for key polarity in section prodsystem_keyboard (default: 0) returns 1 2016-03-23 15:38:06,602 [TRACE] [doorpi.conf.config_object] get_boolean for key pressed_on_keydown in section prodsystem_keyboard (default: True) returns True 2016-03-23 15:38:06,604 [DEBUG] [doorpi.keyboard.from_gpio] doorpi.keyboard.from_gpio loaded 2016-03-23 15:38:06,611 [DEBUG] [doorpi.keyboard.from_gpio] __init__(input_pins = ['11', '13', '15', '33', '31', '35', '7'], output_pins = ['12', '22', '16', '18', '36', '40', '38', '32'], bouncetime = 80.0, polarity = 1) 2016-03-23 15:38:06,614 [DEBUG] [doorpi.conf.config_object] create new key mode in section prodsystem_keyboard with value 'BOARD' 2016-03-23 15:38:06,616 [TRACE] [doorpi.conf.config_object] get_string for key mode in section prodsystem_keyboard (default: BOARD) returns BOARD 2016-03-23 15:38:06,618 [TRACE] [doorpi.conf.config_object] get_string for key pull_up_down in section prodsystem_keyboard (default: PUD_OFF) returns PUD_UP 2016-03-23 15:38:06,624 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,627 [DEBUG] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was added 2016-03-23 15:38:06,631 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,633 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_11 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,634 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_11 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,635 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_prodsystem.11 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,636 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_prodsystem.11 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,638 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,639 [TRACE] [doorpi.action.handler] added event_name OnKeyUp and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,640 [TRACE] [doorpi.action.handler] register Event OnKeyUp_11 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,641 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_11 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,642 [TRACE] [doorpi.action.handler] register Event OnKeyUp_prodsystem.11 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,643 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_prodsystem.11 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,645 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,646 [TRACE] [doorpi.action.handler] added event_name OnKeyDown and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,647 [TRACE] [doorpi.action.handler] register Event OnKeyDown_11 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,648 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_11 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,649 [TRACE] [doorpi.action.handler] register Event OnKeyDown_prodsystem.11 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,651 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_prodsystem.11 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,653 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,655 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_gpio is already known 2016-03-23 15:38:06,656 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_13 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,657 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_13 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,659 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_prodsystem.13 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,660 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_prodsystem.13 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,662 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,663 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_gpio is already known 2016-03-23 15:38:06,665 [TRACE] [doorpi.action.handler] register Event OnKeyUp_13 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,666 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_13 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,667 [TRACE] [doorpi.action.handler] register Event OnKeyUp_prodsystem.13 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,669 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_prodsystem.13 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,670 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,672 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_gpio is already known 2016-03-23 15:38:06,673 [TRACE] [doorpi.action.handler] register Event OnKeyDown_13 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,674 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_13 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,676 [TRACE] [doorpi.action.handler] register Event OnKeyDown_prodsystem.13 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,677 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_prodsystem.13 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,680 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,681 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_gpio is already known 2016-03-23 15:38:06,683 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_15 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,684 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_15 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,685 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_prodsystem.15 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,687 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_prodsystem.15 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,688 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,690 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_gpio is already known 2016-03-23 15:38:06,691 [TRACE] [doorpi.action.handler] register Event OnKeyUp_15 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,692 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_15 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,694 [TRACE] [doorpi.action.handler] register Event OnKeyUp_prodsystem.15 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,695 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_prodsystem.15 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,697 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,698 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_gpio is already known 2016-03-23 15:38:06,699 [TRACE] [doorpi.action.handler] register Event OnKeyDown_15 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,701 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_15 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,702 [TRACE] [doorpi.action.handler] register Event OnKeyDown_prodsystem.15 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,704 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_prodsystem.15 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,706 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,708 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_gpio is already known 2016-03-23 15:38:06,709 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_33 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,711 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_33 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,713 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_prodsystem.33 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,714 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_prodsystem.33 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,716 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,717 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_gpio is already known 2016-03-23 15:38:06,719 [TRACE] [doorpi.action.handler] register Event OnKeyUp_33 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,720 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_33 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,721 [TRACE] [doorpi.action.handler] register Event OnKeyUp_prodsystem.33 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,723 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_prodsystem.33 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,724 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,726 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_gpio is already known 2016-03-23 15:38:06,727 [TRACE] [doorpi.action.handler] register Event OnKeyDown_33 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,729 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_33 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,730 [TRACE] [doorpi.action.handler] register Event OnKeyDown_prodsystem.33 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,732 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_prodsystem.33 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,735 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,736 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_gpio is already known 2016-03-23 15:38:06,738 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_31 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,740 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_31 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,741 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_prodsystem.31 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,743 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_prodsystem.31 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,744 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,746 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_gpio is already known 2016-03-23 15:38:06,747 [TRACE] [doorpi.action.handler] register Event OnKeyUp_31 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,748 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_31 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,750 [TRACE] [doorpi.action.handler] register Event OnKeyUp_prodsystem.31 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,751 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_prodsystem.31 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,753 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,754 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_gpio is already known 2016-03-23 15:38:06,756 [TRACE] [doorpi.action.handler] register Event OnKeyDown_31 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,757 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_31 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,759 [TRACE] [doorpi.action.handler] register Event OnKeyDown_prodsystem.31 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,760 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_prodsystem.31 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,763 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,765 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_gpio is already known 2016-03-23 15:38:06,766 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_35 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,768 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_35 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,769 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_prodsystem.35 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,770 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_prodsystem.35 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,772 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,773 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_gpio is already known 2016-03-23 15:38:06,775 [TRACE] [doorpi.action.handler] register Event OnKeyUp_35 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,776 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_35 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,777 [TRACE] [doorpi.action.handler] register Event OnKeyUp_prodsystem.35 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,779 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_prodsystem.35 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,780 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,782 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_gpio is already known 2016-03-23 15:38:06,784 [TRACE] [doorpi.action.handler] register Event OnKeyDown_35 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,785 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_35 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,786 [TRACE] [doorpi.action.handler] register Event OnKeyDown_prodsystem.35 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,788 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_prodsystem.35 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,791 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,792 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_gpio is already known 2016-03-23 15:38:06,793 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_7 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,794 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_7 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,796 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_prodsystem.7 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,797 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_prodsystem.7 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,799 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,800 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_gpio is already known 2016-03-23 15:38:06,802 [TRACE] [doorpi.action.handler] register Event OnKeyUp_7 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,804 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_7 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,805 [TRACE] [doorpi.action.handler] register Event OnKeyUp_prodsystem.7 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,806 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_prodsystem.7 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,808 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,809 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_gpio is already known 2016-03-23 15:38:06,811 [TRACE] [doorpi.action.handler] register Event OnKeyDown_7 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,812 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_7 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,814 [TRACE] [doorpi.action.handler] register Event OnKeyDown_prodsystem.7 from doorpi.keyboard.from_gpio 2016-03-23 15:38:06,815 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_prodsystem.7 and registered source doorpi.keyboard.from_gpio 2016-03-23 15:38:06,827 [TRACE] [doorpi.action.handler] action KeyboardDestroyAction with args () and kwargs {} was added to event OnShutdown 2016-03-23 15:38:06,829 [TRACE] [doorpi.conf.config_object] get_keys for section prodsystem_OutputPins returns ['12', '22', '16', '18', '36', '40', '38', '32'] 2016-03-23 15:38:06,831 [TRACE] [doorpi.conf.config_object] get_string for key 12 in section prodsystem_OutputPins (default: ) returns Verstaerker 2016-03-23 15:38:06,832 [TRACE] [doorpi.conf.config_object] get_string for key 22 in section prodsystem_OutputPins (default: ) returns Gong-Keller 2016-03-23 15:38:06,834 [TRACE] [doorpi.conf.config_object] get_string for key 16 in section prodsystem_OutputPins (default: ) returns Tueroeffner 2016-03-23 15:38:06,835 [TRACE] [doorpi.conf.config_object] get_string for key 18 in section prodsystem_OutputPins (default: ) returns Licht 2016-03-23 15:38:06,837 [TRACE] [doorpi.conf.config_object] get_string for key 36 in section prodsystem_OutputPins (default: ) returns Gong-Erhard 2016-03-23 15:38:06,839 [TRACE] [doorpi.conf.config_object] get_string for key 40 in section prodsystem_OutputPins (default: ) returns Blink LED 2016-03-23 15:38:06,841 [TRACE] [doorpi.conf.config_object] get_string for key 38 in section prodsystem_OutputPins (default: ) returns Gong-Andreas 2016-03-23 15:38:06,842 [TRACE] [doorpi.conf.config_object] get_string for key 32 in section prodsystem_OutputPins (default: ) returns Gong-Ernst 2016-03-23 15:38:06,847 [DEBUG] [doorpi.status.status_lib.environment] doorpi.status.status_lib.environment loaded 2016-03-23 15:38:06,851 [DEBUG] [doorpi.status.requirements_lib.req_config] doorpi.status.requirements_lib.req_config loaded 2016-03-23 15:38:06,854 [DEBUG] [doorpi.status.requirements_lib.req_sipphone] doorpi.status.requirements_lib.req_sipphone loaded 2016-03-23 15:38:06,857 [DEBUG] [doorpi.sipphone.AbstractBaseClass] doorpi.sipphone.AbstractBaseClass loaded 2016-03-23 15:38:06,925 [DEBUG] [doorpi.status.requirements_lib.req_event_handler] doorpi.status.requirements_lib.req_event_handler loaded 2016-03-23 15:38:06,929 [DEBUG] [doorpi.status.requirements_lib.req_webserver] doorpi.status.requirements_lib.req_webserver loaded 2016-03-23 15:38:06,933 [DEBUG] [doorpi.status.requirements_lib.req_keyboard] doorpi.status.requirements_lib.req_keyboard loaded 2016-03-23 15:38:07,013 [DEBUG] [doorpi.status.requirements_lib.req_system] doorpi.status.requirements_lib.req_system loaded 2016-03-23 15:38:07,024 [INFO] [doorpi.sipphone.SipphoneInterface] found installed sipphone "linphone" and use this as default 2016-03-23 15:38:07,025 [TRACE] [doorpi.conf.config_object] get_string for key sipphonetyp in section SIP-Phone (default: linphone) returns linphone 2016-03-23 15:38:07,028 [DEBUG] [doorpi.sipphone.from_linphone] doorpi.sipphone.from_linphone loaded 2016-03-23 15:38:07,031 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] doorpi.sipphone.linphone_lib.CallBacks loaded 2016-03-23 15:38:07,034 [DEBUG] [doorpi.sipphone.linphone_lib.Player] doorpi.sipphone.linphone_lib.Player loaded 2016-03-23 15:38:07,038 [DEBUG] [doorpi.sipphone.linphone_lib.Recorder] doorpi.sipphone.linphone_lib.Recorder loaded 2016-03-23 15:38:07,040 [DEBUG] [doorpi.sipphone.from_linphone] __init__ 2016-03-23 15:38:07,041 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnShutdown 2016-03-23 15:38:07,042 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2016-03-23 15:38:07,043 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was added 2016-03-23 15:38:07,044 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCreate and registered source doorpi.sipphone.from_linphone 2016-03-23 15:38:07,045 [TRACE] [doorpi.action.handler] register Event OnSipPhoneStart from doorpi.sipphone.from_linphone 2016-03-23 15:38:07,046 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneStart and registered source doorpi.sipphone.from_linphone 2016-03-23 15:38:07,047 [TRACE] [doorpi.action.handler] register Event OnSipPhoneDestroy from doorpi.sipphone.from_linphone 2016-03-23 15:38:07,048 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneDestroy and registered source doorpi.sipphone.from_linphone 2016-03-23 15:38:07,048 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderCreate from doorpi.sipphone.from_linphone 2016-03-23 15:38:07,049 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderCreate and registered source doorpi.sipphone.from_linphone 2016-03-23 15:38:07,050 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderDestroy from doorpi.sipphone.from_linphone 2016-03-23 15:38:07,051 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderDestroy and registered source doorpi.sipphone.from_linphone 2016-03-23 15:38:07,052 [TRACE] [doorpi.action.handler] register Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-03-23 15:38:07,052 [TRACE] [doorpi.action.handler] added event_name BeforeSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2016-03-23 15:38:07,053 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-03-23 15:38:07,054 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2016-03-23 15:38:07,055 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2016-03-23 15:38:07,056 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCallFailed and registered source doorpi.sipphone.from_linphone 2016-03-23 15:38:07,056 [TRACE] [doorpi.action.handler] register Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-03-23 15:38:07,057 [TRACE] [doorpi.action.handler] added event_name AfterSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2016-03-23 15:38:07,058 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutNoResponse from doorpi.sipphone.from_linphone 2016-03-23 15:38:07,059 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutNoResponse and registered source doorpi.sipphone.from_linphone 2016-03-23 15:38:07,059 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutMaxCalltime from doorpi.sipphone.from_linphone 2016-03-23 15:38:07,061 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutMaxCalltime and registered source doorpi.sipphone.from_linphone 2016-03-23 15:38:07,061 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.from_linphone 2016-03-23 15:38:07,062 [TRACE] [doorpi.action.handler] added event_name OnPlayerCreated and registered source doorpi.sipphone.from_linphone 2016-03-23 15:38:07,063 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] __init__ 2016-03-23 15:38:07,064 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneDestroy 2016-03-23 15:38:07,065 [TRACE] [doorpi.action.handler] register Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,066 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was added 2016-03-23 15:38:07,067 [TRACE] [doorpi.action.handler] added event_name OnCallMediaStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,067 [TRACE] [doorpi.action.handler] register Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,068 [TRACE] [doorpi.action.handler] added event_name OnMediaRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,069 [TRACE] [doorpi.action.handler] register Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,070 [TRACE] [doorpi.action.handler] added event_name OnMediaNotRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,071 [TRACE] [doorpi.action.handler] register Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,071 [TRACE] [doorpi.action.handler] added event_name OnCallStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,072 [TRACE] [doorpi.action.handler] register Event OnCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,073 [TRACE] [doorpi.action.handler] added event_name OnCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,074 [TRACE] [doorpi.action.handler] register Event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,075 [TRACE] [doorpi.action.handler] added event_name AfterCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,075 [TRACE] [doorpi.action.handler] register Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,076 [TRACE] [doorpi.action.handler] added event_name OnCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,077 [TRACE] [doorpi.action.handler] register Event AfterCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,078 [TRACE] [doorpi.action.handler] added event_name AfterCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,079 [TRACE] [doorpi.action.handler] register Event OnCallStateDismissed from doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,079 [TRACE] [doorpi.action.handler] added event_name OnCallStateDismissed and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,080 [TRACE] [doorpi.action.handler] register Event OnCallStateReject from doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,081 [TRACE] [doorpi.action.handler] added event_name OnCallStateReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,082 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,083 [TRACE] [doorpi.action.handler] added event_name OnCallStart and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,084 [TRACE] [doorpi.action.handler] register Event OnDTMF from doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,084 [TRACE] [doorpi.action.handler] added event_name OnDTMF and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,085 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns ['"1"', '"**001*"', '"**002*"', '"#"'] 2016-03-23 15:38:07,086 [TRACE] [doorpi.action.handler] register Event OnDTMF_"1" from doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,087 [TRACE] [doorpi.action.handler] added event_name OnDTMF_"1" and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,088 [TRACE] [doorpi.action.handler] register Event OnDTMF_"**001*" from doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,089 [TRACE] [doorpi.action.handler] added event_name OnDTMF_"**001*" and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,089 [TRACE] [doorpi.action.handler] register Event OnDTMF_"**002*" from doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,090 [TRACE] [doorpi.action.handler] added event_name OnDTMF_"**002*" and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,091 [TRACE] [doorpi.action.handler] register Event OnDTMF_"#" from doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,092 [TRACE] [doorpi.action.handler] added event_name OnDTMF_"#" and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,093 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,093 [TRACE] [doorpi.action.handler] nothing to do - event OnCallStart from source doorpi.sipphone.linphone_lib.CallBacks is already known 2016-03-23 15:38:07,094 [TRACE] [doorpi.action.handler] register Event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,095 [TRACE] [doorpi.action.handler] added event_name BeforeCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,096 [TRACE] [doorpi.action.handler] register Event OnCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,097 [TRACE] [doorpi.action.handler] added event_name OnCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,097 [TRACE] [doorpi.action.handler] register Event AfterCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,098 [TRACE] [doorpi.action.handler] added event_name AfterCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,099 [TRACE] [doorpi.action.handler] register Event OnCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,100 [TRACE] [doorpi.action.handler] added event_name OnCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,101 [TRACE] [doorpi.action.handler] register Event AfterCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,101 [TRACE] [doorpi.action.handler] added event_name AfterCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,102 [TRACE] [doorpi.action.handler] register Event OnCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,103 [TRACE] [doorpi.action.handler] added event_name OnCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,104 [TRACE] [doorpi.action.handler] register Event AfterCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,105 [TRACE] [doorpi.action.handler] added event_name AfterCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,106 [TRACE] [doorpi.action.handler] register Event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,106 [TRACE] [doorpi.action.handler] added event_name OnCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,107 [TRACE] [doorpi.action.handler] register Event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,108 [TRACE] [doorpi.action.handler] added event_name AfterCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,109 [TRACE] [doorpi.action.handler] fire Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks asyncron 2016-03-23 15:38:07,111 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_new() 2016-03-23 15:38:07,112 [DEBUG] [doorpi.action.handler] no actions for event OnCallStart - skip fire_event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2016-03-23 15:38:07,113 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_new -> 0x7464df38 2016-03-23 15:38:07,115 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_init() 2016-03-23 15:38:07,116 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_init -> 0x7464df38 2016-03-23 15:38:07,117 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_class_method_new(0x745a5810, "(null)", "(null)") 2016-03-23 15:38:07,118 [INFO] [doorpi.sipphone.from_linphone] Mediastreamer2 factory 2.12.0 (git: 3.9.0) initialized. 2016-03-23 15:38:07,119 [INFO] [doorpi.sipphone.from_linphone] CPU count set to 4 2016-03-23 15:38:07,120 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init() done: platform_tags=linux,arm,embedded 2016-03-23 15:38:07,121 [INFO] [doorpi.sipphone.from_linphone] srtp init 2016-03-23 15:38:07,170 [INFO] [doorpi.sipphone.from_linphone] Registering all soundcard handlers 2016-03-23 15:38:07,226 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: default device' added 2016-03-23 15:38:07,236 [INFO] [doorpi.sipphone.from_linphone] also error in pcm_hw.c:1557 - open '/dev/snd/pcmC0D0c' failed (-2) 2016-03-23 15:38:07,240 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: bcm2835 ALSA' added 2016-03-23 15:38:07,241 [INFO] [doorpi.sipphone.from_linphone] Registering all webcam handlers 2016-03-23 15:38:07,242 [INFO] [doorpi.sipphone.from_linphone] Webcam StaticImage: Static picture added 2016-03-23 15:38:07,243 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init_voip() done 2016-03-23 15:38:07,244 [INFO] [doorpi.sipphone.from_linphone] Loading ms plugins from [./lib/mediastreamer/plugins] 2016-03-23 15:38:07,245 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/mediastreamer/plugins: No such file or directory 2016-03-23 15:38:07,247 [INFO] [doorpi.sipphone.from_linphone] Initializing LinphoneCore 3.9.0 2016-03-23 15:38:07,247 [INFO] [doorpi.sipphone.from_linphone] Vtable [0x1081970] registered on core [0x1075480] 2016-03-23 15:38:07,248 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x1081970, 1, "Starting up") 2016-03-23 15:38:07,249 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2016-03-23 15:38:07,250 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1081970] notifying [global_state_changed] 2016-03-23 15:38:07,251 [INFO] [doorpi.sipphone.from_linphone] oRTP-0.25.0 initialized. 2016-03-23 15:38:07,252 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_base_init, because [2] ref 2016-03-23 15:38:07,253 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_voip_init, because [2] ref 2016-03-23 15:38:07,254 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_plugins_init, because [2] ref 2016-03-23 15:38:07,255 [INFO] [doorpi.sipphone.from_linphone] Codec opus/48000 fmtp=[useinbandfec=1] number=-1, enabled=1) added to default capabilities. 2016-03-23 15:38:07,256 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-03-23 15:38:07,257 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-03-23 15:38:07,257 [INFO] [doorpi.sipphone.from_linphone] Codec speex/16000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2016-03-23 15:38:07,258 [INFO] [doorpi.sipphone.from_linphone] Codec speex/8000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2016-03-23 15:38:07,259 [INFO] [doorpi.sipphone.from_linphone] Codec PCMU/8000 fmtp=[] number=0, enabled=1) added to default capabilities. 2016-03-23 15:38:07,260 [INFO] [doorpi.sipphone.from_linphone] Codec PCMA/8000 fmtp=[] number=8, enabled=1) added to default capabilities. 2016-03-23 15:38:07,261 [INFO] [doorpi.sipphone.from_linphone] Codec t140/1000 fmtp=[] number=96, enabled=1) added to default capabilities. 2016-03-23 15:38:07,262 [INFO] [doorpi.sipphone.from_linphone] Codec red/1000 fmtp=[] number=97, enabled=1) added to default capabilities. 2016-03-23 15:38:07,263 [INFO] [doorpi.sipphone.from_linphone] Codec GSM/8000 fmtp=[] number=3, enabled=0) added to default capabilities. 2016-03-23 15:38:07,264 [INFO] [doorpi.sipphone.from_linphone] Codec G722/8000 fmtp=[] number=9, enabled=0) added to default capabilities. 2016-03-23 15:38:07,265 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iLBC 2016-03-23 15:38:07,266 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iLBC 2016-03-23 15:38:07,267 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR 2016-03-23 15:38:07,268 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR 2016-03-23 15:38:07,268 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR-WB 2016-03-23 15:38:07,269 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR-WB 2016-03-23 15:38:07,270 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2016-03-23 15:38:07,271 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2016-03-23 15:38:07,272 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-03-23 15:38:07,273 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-03-23 15:38:07,274 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-03-23 15:38:07,274 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-03-23 15:38:07,275 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-03-23 15:38:07,276 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-03-23 15:38:07,277 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-03-23 15:38:07,278 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-03-23 15:38:07,279 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-03-23 15:38:07,280 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-03-23 15:38:07,281 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iSAC 2016-03-23 15:38:07,282 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iSAC 2016-03-23 15:38:07,282 [INFO] [doorpi.sipphone.from_linphone] Codec speex/32000 fmtp=[vbr=on] number=-1, enabled=0) added to default capabilities. 2016-03-23 15:38:07,283 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-03-23 15:38:07,284 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-03-23 15:38:07,285 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-03-23 15:38:07,286 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-03-23 15:38:07,287 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-03-23 15:38:07,288 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-03-23 15:38:07,289 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-16 2016-03-23 15:38:07,289 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-16 2016-03-23 15:38:07,290 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-24 2016-03-23 15:38:07,291 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-24 2016-03-23 15:38:07,292 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-32 2016-03-23 15:38:07,293 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-32 2016-03-23 15:38:07,294 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-40 2016-03-23 15:38:07,295 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-40 2016-03-23 15:38:07,296 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-16 2016-03-23 15:38:07,296 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-16 2016-03-23 15:38:07,297 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-24 2016-03-23 15:38:07,298 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-24 2016-03-23 15:38:07,299 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-32 2016-03-23 15:38:07,300 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-32 2016-03-23 15:38:07,301 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-40 2016-03-23 15:38:07,301 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-40 2016-03-23 15:38:07,302 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CODEC2 2016-03-23 15:38:07,303 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CODEC2 2016-03-23 15:38:07,304 [INFO] [doorpi.sipphone.from_linphone] Codec VP8/90000 fmtp=[] number=-1, enabled=1) added to default capabilities. 2016-03-23 15:38:07,305 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H264 2016-03-23 15:38:07,306 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H264 2016-03-23 15:38:07,307 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MP4V-ES 2016-03-23 15:38:07,308 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263-1998 2016-03-23 15:38:07,309 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2016-03-23 15:38:07,309 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for 1016 2016-03-23 15:38:07,310 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for 1016 2016-03-23 15:38:07,311 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G723 2016-03-23 15:38:07,312 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G723 2016-03-23 15:38:07,313 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for LPC 2016-03-23 15:38:07,314 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for LPC 2016-03-23 15:38:07,315 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=10, enabled=0) added to default capabilities. 2016-03-23 15:38:07,316 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=11, enabled=0) added to default capabilities. 2016-03-23 15:38:07,317 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CN 2016-03-23 15:38:07,317 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CN 2016-03-23 15:38:07,318 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2016-03-23 15:38:07,319 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2016-03-23 15:38:07,320 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H261 2016-03-23 15:38:07,321 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H261 2016-03-23 15:38:07,322 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MPV 2016-03-23 15:38:07,323 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for MPV 2016-03-23 15:38:07,324 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2016-03-23 15:38:07,325 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-03-23 15:38:07,326 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-23 15:38:07,327 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-23 15:38:07,328 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-23 15:38:07,328 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_configuring_status(0x1081970, 2, "(null)") 2016-03-23 15:38:07,329 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_configuring_status 2016-03-23 15:38:07,330 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1081970] notifying [configuring_status] 2016-03-23 15:38:07,331 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/liblinphone/plugins: No such file or directory 2016-03-23 15:38:07,332 [WARNING] [doorpi.sipphone.from_linphone] ./share/sounds/linphone/rings/oldphone.wav does not exist 2016-03-23 15:38:07,333 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_playback_gain_db(): no active call. 2016-03-23 15:38:07,334 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_mic_gain_db(): no active call. 2016-03-23 15:38:07,335 [INFO] [doorpi.sipphone.from_linphone] MTU is supposed to be 1300, rtp payload max size will be 1240 2016-03-23 15:38:07,336 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-03-23 15:38:07,337 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-03-23 15:38:07,337 [INFO] [doorpi.sipphone.from_linphone] Supported codec opus/48000 fmtp=useinbandfec=1 automatically added to codec list. 2016-03-23 15:38:07,338 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/16000 fmtp=vbr=on automatically added to codec list. 2016-03-23 15:38:07,339 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/8000 fmtp=vbr=on automatically added to codec list. 2016-03-23 15:38:07,340 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMU/8000 fmtp= automatically added to codec list. 2016-03-23 15:38:07,341 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMA/8000 fmtp= automatically added to codec list. 2016-03-23 15:38:07,342 [INFO] [doorpi.sipphone.from_linphone] Supported codec GSM/8000 fmtp= automatically added to codec list. 2016-03-23 15:38:07,343 [INFO] [doorpi.sipphone.from_linphone] Supported codec G722/8000 fmtp= automatically added to codec list. 2016-03-23 15:38:07,344 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/32000 fmtp=vbr=on automatically added to codec list. 2016-03-23 15:38:07,344 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2016-03-23 15:38:07,345 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2016-03-23 15:38:07,346 [INFO] [doorpi.sipphone.from_linphone] Supported codec VP8/90000 fmtp= automatically added to codec list. 2016-03-23 15:38:07,347 [INFO] [doorpi.sipphone.from_linphone] Supported codec t140/1000 fmtp= automatically added to codec list. 2016-03-23 15:38:07,348 [INFO] [doorpi.sipphone.from_linphone] Supported codec red/1000 fmtp= automatically added to codec list. 2016-03-23 15:38:07,349 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-03-23 15:38:07,350 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-23 15:38:07,351 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2016-03-23 15:38:07,352 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x10824c8] on [sip:0.0.0.0:5060;transport=UDP] 2016-03-23 15:38:07,353 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x108f5f0] on [sip:0.0.0.0:5060;transport=TCP] 2016-03-23 15:38:07,354 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x108f838] on [sip:0.0.0.0:-1;transport=TLS] 2016-03-23 15:38:07,355 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 44100 2016-03-23 15:38:07,356 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-23 15:38:07,357 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-23 15:38:07,363 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x1081970, 2, "Ready") 2016-03-23 15:38:07,364 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2016-03-23 15:38:07,365 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1081970] notifying [global_state_changed] 2016-03-23 15:38:07,366 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_class_method_new -> 0x7464df38 2016-03-23 15:38:07,367 [DEBUG] [doorpi.conf.config_object] create new key identity in section SIP-Phone with value 'DoorPi' 2016-03-23 15:38:07,368 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2016-03-23 15:38:07,369 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_primary_contact(0x7464df38 [0x1081970], "DoorPi ") 2016-03-23 15:38:07,371 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_primary_contact -> 0 2016-03-23 15:38:07,372 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneCreate from doorpi.sipphone.from_linphone asyncron 2016-03-23 15:38:07,375 [TRACE] [doorpi.conf.config_object] get_integer for key ua.max_calls in section SIP-Phone (default: 2) returns 1 2016-03-23 15:38:07,375 [DEBUG] [doorpi.action.handler] no actions for event OnSipPhoneCreate - skip fire_event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2016-03-23 15:38:07,376 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_max_calls(0x7464df38 [0x1081970], 1) 2016-03-23 15:38:07,380 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_max_calls -> 0 2016-03-23 15:38:07,382 [TRACE] [doorpi.conf.config_object] get_boolean for key echo_cancellation_enabled in section SIP-Phone (default: False) returns False 2016-03-23 15:38:07,384 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_echo_cancellation(0x7464df38 [0x1081970], 0x2e0614 [0]) 2016-03-23 15:38:07,386 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_echo_cancellation -> 0 2016-03-23 15:38:07,389 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2016-03-23 15:38:07,390 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2016-03-23 15:38:07,392 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_new() 2016-03-23 15:38:07,394 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_new -> 0x7464df50 2016-03-23 15:38:07,397 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_sip_transports(0x7464df38 [0x1081970], 0x7464df50 [0x7464df58]) 2016-03-23 15:38:07,400 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x10824c8] on [sip:0.0.0.0:5060;transport=UDP] destroyed 2016-03-23 15:38:07,402 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x108f5f0] on [sip:0.0.0.0:5060;transport=TCP] destroyed 2016-03-23 15:38:07,404 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x108f838] on [sip:0.0.0.0:44100;transport=TLS] destroyed 2016-03-23 15:38:07,406 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2016-03-23 15:38:07,408 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x1076fd0] on [sip:0.0.0.0:5060;transport=UDP] 2016-03-23 15:38:07,409 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x1076f50] on [sip:0.0.0.0:5060;transport=TCP] 2016-03-23 15:38:07,412 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x1082408] on [sip:0.0.0.0:-1;transport=TLS] 2016-03-23 15:38:07,414 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 59875 2016-03-23 15:38:07,415 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-23 15:38:07,417 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-23 15:38:07,419 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_sip_transports -> 0 2016-03-23 15:38:07,422 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_dealloc(0x7464df50) 2016-03-23 15:38:07,424 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_dealloc 2016-03-23 15:38:07,426 [TRACE] [doorpi.conf.config_object] get_boolean for key video_display_enabled in section SIP-Phone (default: False) returns False 2016-03-23 15:38:07,428 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_display(0x7464df38 [0x1081970], 0x2e0614 [0]) 2016-03-23 15:38:07,431 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_display -> 0 2016-03-23 15:38:07,433 [TRACE] [doorpi.conf.config_object] get_string for key stun_server in section SIP-Phone (default: ) returns 2016-03-23 15:38:07,435 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_stun_server(0x7464df38 [0x1081970], "") 2016-03-23 15:38:07,438 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2016-03-23 15:38:07,440 [INFO] [doorpi.sipphone.from_linphone] 192.168.178.1 2016-03-23 15:38:07,443 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2016-03-23 15:38:07,445 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2016-03-23 15:38:07,447 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_stun_server -> 0 2016-03-23 15:38:07,449 [DEBUG] [doorpi.conf.config_object] create new key FirewallPolicy in section SIP-Phone with value 'PolicyNoFirewall' 2016-03-23 15:38:07,451 [TRACE] [doorpi.conf.config_object] get_string for key FirewallPolicy in section SIP-Phone (default: PolicyNoFirewall) returns PolicyNoFirewall 2016-03-23 15:38:07,453 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_firewall_policy(0x7464df38 [0x1081970], 0) 2016-03-23 15:38:07,455 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-03-23 15:38:07,457 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-03-23 15:38:07,460 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_firewall_policy -> 0 2016-03-23 15:38:07,463 [TRACE] [doorpi.conf.config_object] get_integer for key max_call_time in section SIP-Phone (default: 120) returns 120 2016-03-23 15:38:07,465 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_in_call_timeout(0x7464df38 [0x1081970], 120) 2016-03-23 15:38:07,467 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_in_call_timeout -> 0 2016-03-23 15:38:07,469 [TRACE] [doorpi.conf.config_object] get_integer for key call_timeout in section SIP-Phone (default: 15) returns 15 2016-03-23 15:38:07,471 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_inc_timeout(0x7464df38 [0x1081970], 15) 2016-03-23 15:38:07,473 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_inc_timeout -> 0 2016-03-23 15:38:07,475 [TRACE] [doorpi.conf.config_object] get_string for key dialtone in section SIP-Phone (default: !BASEPATH!/media/ShortDialTone.wav) returns !BASEPATH!/media/ShortDialTone.wav 2016-03-23 15:38:07,479 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/media/ShortDialTone.wav" to "/usr/local/etc/DoorPi/media/ShortDialTone.wav" 2016-03-23 15:38:07,481 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2016-03-23 15:38:07,484 [TRACE] [doorpi.conf.config_object] get_boolean for key dialtone_renew_every_start in section SIP-Phone (default: False) returns False 2016-03-23 15:38:07,486 [TRACE] [doorpi.action.handler] register Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2016-03-23 15:38:07,488 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was added 2016-03-23 15:38:07,490 [TRACE] [doorpi.action.handler] added event_name OnPlayerStarted and registered source doorpi.sipphone.linphone_lib.Player 2016-03-23 15:38:07,492 [TRACE] [doorpi.action.handler] register Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2016-03-23 15:38:07,493 [TRACE] [doorpi.action.handler] added event_name OnPlayerStopped and registered source doorpi.sipphone.linphone_lib.Player 2016-03-23 15:38:07,495 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2016-03-23 15:38:07,497 [TRACE] [doorpi.action.handler] added event_source doorpi.sipphone.linphone_lib.Player to existing event OnPlayerCreated 2016-03-23 15:38:07,499 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneMakeCall 2016-03-23 15:38:07,501 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateConnect 2016-03-23 15:38:07,502 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateDisconnect 2016-03-23 15:38:07,504 [TRACE] [doorpi.action.handler] fire Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player asyncron 2016-03-23 15:38:07,508 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_ringback(0x7464df38 [0x1081970], "/usr/local/etc/DoorPi/media/ShortDialTone.wav") 2016-03-23 15:38:07,509 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerCreated - skip fire_event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2016-03-23 15:38:07,510 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_ringback -> 0 2016-03-23 15:38:07,514 [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 /backup/record-audio/!LastKey!/%Y-%m-%d_%H-%M-%S.wav 2016-03-23 15:38:07,516 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2016-03-23 15:38:07,518 [TRACE] [doorpi.action.handler] register Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder 2016-03-23 15:38:07,519 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was added 2016-03-23 15:38:07,522 [TRACE] [doorpi.action.handler] added event_name OnRecorderStarted and registered source doorpi.sipphone.linphone_lib.Recorder 2016-03-23 15:38:07,523 [TRACE] [doorpi.action.handler] register Event OnRecorderStopped from doorpi.sipphone.linphone_lib.Recorder 2016-03-23 15:38:07,525 [TRACE] [doorpi.action.handler] added event_name OnRecorderStopped and registered source doorpi.sipphone.linphone_lib.Recorder 2016-03-23 15:38:07,527 [TRACE] [doorpi.action.handler] register Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2016-03-23 15:38:07,529 [TRACE] [doorpi.action.handler] added event_name OnRecorderCreated and registered source doorpi.sipphone.linphone_lib.Recorder 2016-03-23 15:38:07,530 [TRACE] [doorpi.conf.config_object] get_boolean for key record_while_dialing in section SIP-Phone (default: False) returns False 2016-03-23 15:38:07,532 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateConnect 2016-03-23 15:38:07,534 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateDisconnect 2016-03-23 15:38:07,536 [TRACE] [doorpi.action.handler] fire Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder asyncron 2016-03-23 15:38:07,539 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x7464df38 [0x1081970]) 2016-03-23 15:38:07,540 [DEBUG] [doorpi.action.handler] no actions for event OnRecorderCreated - skip fire_event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2016-03-23 15:38:07,542 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x745ad670 2016-03-23 15:38:07,547 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0x7464df38 [0x1081970]) 2016-03-23 15:38:07,549 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0x745a4c50 2016-03-23 15:38:07,552 [TRACE] [doorpi.conf.config_object] get_string for key capture_device in section SIP-Phone (default: ALSA: default device) returns ALSA: USB Audio Device 2016-03-23 15:38:07,554 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_capture_device(0x7464df38 [0x1081970], "ALSA: USB Audio Device") 2016-03-23 15:38:07,556 [WARNING] [doorpi.sipphone.from_linphone] no card with id ALSA: USB Audio Device 2016-03-23 15:38:07,558 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_capture_device -> 0 2016-03-23 15:38:07,560 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0x7464df38 [0x1081970]) 2016-03-23 15:38:07,562 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0x745a4c50 2016-03-23 15:38:07,565 [TRACE] [doorpi.conf.config_object] get_string for key playback_device in section SIP-Phone (default: ALSA: default device) returns ALSA: bcm2835 ALSA 2016-03-23 15:38:07,566 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_playback_device(0x7464df38 [0x1081970], "ALSA: bcm2835 ALSA") 2016-03-23 15:38:07,569 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_playback_device -> 0 2016-03-23 15:38:07,571 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x7464df38 [0x1081970]) 2016-03-23 15:38:07,574 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x745ad670 2016-03-23 15:38:07,576 [INFO] [doorpi.sipphone.from_linphone] found 2 possible sounddevices: 2016-03-23 15:38:07,578 [DEBUG] [doorpi.sipphone.from_linphone] |rec|play| name 2016-03-23 15:38:07,580 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-03-23 15:38:07,581 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x7464df38 [0x1081970]) 2016-03-23 15:38:07,584 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x745ad670 2016-03-23 15:38:07,586 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x7464df38 [0x1081970], "ALSA: default device") 2016-03-23 15:38:07,588 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0620 2016-03-23 15:38:07,591 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x7464df38 [0x1081970], "ALSA: default device") 2016-03-23 15:38:07,593 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2016-03-23 15:38:07,595 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: default device 2016-03-23 15:38:07,597 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x7464df38 [0x1081970], "ALSA: bcm2835 ALSA") 2016-03-23 15:38:07,599 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0614 2016-03-23 15:38:07,601 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x7464df38 [0x1081970], "ALSA: bcm2835 ALSA") 2016-03-23 15:38:07,604 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2016-03-23 15:38:07,606 [DEBUG] [doorpi.sipphone.from_linphone] | O | X | ALSA: bcm2835 ALSA 2016-03-23 15:38:07,607 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-03-23 15:38:07,609 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0x7464df38 [0x1081970]) 2016-03-23 15:38:07,611 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0x745a4d10 2016-03-23 15:38:07,614 [DEBUG] [doorpi.sipphone.from_linphone] using capture_device: ALSA: default device 2016-03-23 15:38:07,615 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0x7464df38 [0x1081970]) 2016-03-23 15:38:07,618 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0x745ad700 2016-03-23 15:38:07,620 [DEBUG] [doorpi.sipphone.from_linphone] using playback_device: ALSA: bcm2835 ALSA 2016-03-23 15:38:07,622 [TRACE] [doorpi.conf.config_object] get_list for key audio_codecs in section SIP-Phone (default: PCMA,PCMU) returns ['PCMA', 'PCMU'] 2016-03-23 15:38:07,624 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_audio_codecs(0x7464df38 [0x1081970]) 2016-03-23 15:38:07,626 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1081f38) 2016-03-23 15:38:07,628 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-23 15:38:07,631 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76ccd830 2016-03-23 15:38:07,634 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-23 15:38:07,636 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76ccd830 2016-03-23 15:38:07,639 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76ccd830 2016-03-23 15:38:07,641 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1081fa8) 2016-03-23 15:38:07,643 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-23 15:38:07,645 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76ccd840 2016-03-23 15:38:07,647 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-23 15:38:07,649 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76ccd840 2016-03-23 15:38:07,651 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76ccd840 2016-03-23 15:38:07,653 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1082010) 2016-03-23 15:38:07,655 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-23 15:38:07,656 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76ccd850 2016-03-23 15:38:07,659 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-23 15:38:07,661 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76ccd850 2016-03-23 15:38:07,662 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76ccd850 2016-03-23 15:38:07,664 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1082078) 2016-03-23 15:38:07,666 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-23 15:38:07,668 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76ccd860 2016-03-23 15:38:07,670 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-23 15:38:07,672 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76ccd860 2016-03-23 15:38:07,674 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76ccd860 2016-03-23 15:38:07,676 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x10820d0) 2016-03-23 15:38:07,678 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-23 15:38:07,680 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76ccd870 2016-03-23 15:38:07,682 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-23 15:38:07,684 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76ccd870 2016-03-23 15:38:07,686 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76ccd870 2016-03-23 15:38:07,688 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1082128) 2016-03-23 15:38:07,690 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-23 15:38:07,692 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76ccd880 2016-03-23 15:38:07,694 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-23 15:38:07,696 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76ccd880 2016-03-23 15:38:07,697 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76ccd880 2016-03-23 15:38:07,699 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1082180) 2016-03-23 15:38:07,701 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-23 15:38:07,702 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76ccd890 2016-03-23 15:38:07,703 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-23 15:38:07,704 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76ccd890 2016-03-23 15:38:07,705 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76ccd890 2016-03-23 15:38:07,706 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x10821d8) 2016-03-23 15:38:07,707 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-23 15:38:07,708 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76ccd8a0 2016-03-23 15:38:07,709 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-23 15:38:07,710 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76ccd8a0 2016-03-23 15:38:07,711 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76ccd8a0 2016-03-23 15:38:07,712 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1082240) 2016-03-23 15:38:07,713 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-23 15:38:07,714 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76ccd8b0 2016-03-23 15:38:07,715 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-23 15:38:07,715 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76ccd8b0 2016-03-23 15:38:07,716 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76ccd8b0 2016-03-23 15:38:07,717 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1082298) 2016-03-23 15:38:07,718 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-23 15:38:07,719 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76ccd8c0 2016-03-23 15:38:07,720 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-23 15:38:07,721 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76ccd8c0 2016-03-23 15:38:07,722 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76ccd8c0 2016-03-23 15:38:07,723 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_audio_codecs -> 0x745ad508 2016-03-23 15:38:07,724 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76ccd830 [0x1081f38]) 2016-03-23 15:38:07,725 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745ab320 2016-03-23 15:38:07,726 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76ccd830 [0x1081f38]) 2016-03-23 15:38:07,727 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745ab320 2016-03-23 15:38:07,727 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec opus 2016-03-23 15:38:07,728 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x7464df38 [0x1081970], 0x76ccd830 [0x1081f38], 0x2e0614 [0]) 2016-03-23 15:38:07,730 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xeac8bc 2016-03-23 15:38:07,731 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76ccd840 [0x1081fa8]) 2016-03-23 15:38:07,732 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745ab320 2016-03-23 15:38:07,733 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76ccd840 [0x1081fa8]) 2016-03-23 15:38:07,733 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745ab320 2016-03-23 15:38:07,734 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-03-23 15:38:07,735 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x7464df38 [0x1081970], 0x76ccd840 [0x1081fa8], 0x2e0614 [0]) 2016-03-23 15:38:07,736 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xeac8bc 2016-03-23 15:38:07,737 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76ccd850 [0x1082010]) 2016-03-23 15:38:07,738 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745ab320 2016-03-23 15:38:07,739 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76ccd850 [0x1082010]) 2016-03-23 15:38:07,740 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745ab320 2016-03-23 15:38:07,741 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-03-23 15:38:07,742 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x7464df38 [0x1081970], 0x76ccd850 [0x1082010], 0x2e0614 [0]) 2016-03-23 15:38:07,743 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xeac8bc 2016-03-23 15:38:07,744 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76ccd860 [0x1082078]) 2016-03-23 15:38:07,745 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745ab320 2016-03-23 15:38:07,746 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76ccd860 [0x1082078]) 2016-03-23 15:38:07,747 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745ab320 2016-03-23 15:38:07,748 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMU 2016-03-23 15:38:07,748 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x7464df38 [0x1081970], 0x76ccd860 [0x1082078], 0x2e0620 [1]) 2016-03-23 15:38:07,750 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xeac8bc 2016-03-23 15:38:07,751 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76ccd870 [0x10820d0]) 2016-03-23 15:38:07,752 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745ab320 2016-03-23 15:38:07,753 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76ccd870 [0x10820d0]) 2016-03-23 15:38:07,753 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745ab320 2016-03-23 15:38:07,754 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMA 2016-03-23 15:38:07,755 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x7464df38 [0x1081970], 0x76ccd870 [0x10820d0], 0x2e0620 [1]) 2016-03-23 15:38:07,756 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xeac8bc 2016-03-23 15:38:07,757 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76ccd880 [0x1082128]) 2016-03-23 15:38:07,758 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7464df50 2016-03-23 15:38:07,759 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76ccd880 [0x1082128]) 2016-03-23 15:38:07,760 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7464df50 2016-03-23 15:38:07,761 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec GSM 2016-03-23 15:38:07,762 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x7464df38 [0x1081970], 0x76ccd880 [0x1082128], 0x2e0614 [0]) 2016-03-23 15:38:07,763 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xeac8bc 2016-03-23 15:38:07,764 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76ccd890 [0x1082180]) 2016-03-23 15:38:07,765 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745ab320 2016-03-23 15:38:07,766 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76ccd890 [0x1082180]) 2016-03-23 15:38:07,767 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745ab320 2016-03-23 15:38:07,767 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec G722 2016-03-23 15:38:07,768 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x7464df38 [0x1081970], 0x76ccd890 [0x1082180], 0x2e0614 [0]) 2016-03-23 15:38:07,770 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xeac8bc 2016-03-23 15:38:07,770 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76ccd8a0 [0x10821d8]) 2016-03-23 15:38:07,771 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745ab320 2016-03-23 15:38:07,772 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76ccd8a0 [0x10821d8]) 2016-03-23 15:38:07,773 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745ab320 2016-03-23 15:38:07,774 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-03-23 15:38:07,775 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x7464df38 [0x1081970], 0x76ccd8a0 [0x10821d8], 0x2e0614 [0]) 2016-03-23 15:38:07,776 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xeac8bc 2016-03-23 15:38:07,777 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76ccd8b0 [0x1082240]) 2016-03-23 15:38:07,778 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7464df50 2016-03-23 15:38:07,779 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76ccd8b0 [0x1082240]) 2016-03-23 15:38:07,780 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7464df50 2016-03-23 15:38:07,781 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2016-03-23 15:38:07,782 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x7464df38 [0x1081970], 0x76ccd8b0 [0x1082240], 0x2e0614 [0]) 2016-03-23 15:38:07,783 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xeac8bc 2016-03-23 15:38:07,784 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76ccd8c0 [0x1082298]) 2016-03-23 15:38:07,785 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7464df50 2016-03-23 15:38:07,785 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76ccd8c0 [0x1082298]) 2016-03-23 15:38:07,786 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7464df50 2016-03-23 15:38:07,787 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2016-03-23 15:38:07,788 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x7464df38 [0x1081970], 0x76ccd8c0 [0x1082298], 0x2e0614 [0]) 2016-03-23 15:38:07,789 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xeac8bc 2016-03-23 15:38:07,790 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x7464df38 [0x1081970]) 2016-03-23 15:38:07,791 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x745ad6c0 2016-03-23 15:38:07,792 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x7464df38 [0x1081970]) 2016-03-23 15:38:07,793 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x745ad6c0 2016-03-23 15:38:07,794 [INFO] [doorpi.sipphone.from_linphone] found 1 possible videodevices: 2016-03-23 15:38:07,795 [DEBUG] [doorpi.sipphone.from_linphone] | name 2016-03-23 15:38:07,796 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-03-23 15:38:07,796 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x7464df38 [0x1081970]) 2016-03-23 15:38:07,797 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x745ad6c0 2016-03-23 15:38:07,798 [DEBUG] [doorpi.sipphone.from_linphone] | StaticImage: Static picture 2016-03-23 15:38:07,799 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-03-23 15:38:07,800 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x7464df38 [0x1081970]) 2016-03-23 15:38:07,801 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x745ad6c0 2016-03-23 15:38:07,802 [DEBUG] [doorpi.conf.config_object] create new key video_device in section SIP-Phone with value 'StaticImage: Static picture' 2016-03-23 15:38:07,803 [TRACE] [doorpi.conf.config_object] get_string for key video_device in section SIP-Phone (default: StaticImage: Static picture) returns StaticImage: Static picture 2016-03-23 15:38:07,804 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x7464df38 [0x1081970]) 2016-03-23 15:38:07,805 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x745ad6c0 2016-03-23 15:38:07,806 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_capture(0x7464df38 [0x1081970], 0x2e0620 [1]) 2016-03-23 15:38:07,807 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_capture -> 0 2016-03-23 15:38:07,807 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_video_device(0x7464df38 [0x1081970], "StaticImage: Static picture") 2016-03-23 15:38:07,808 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_video_device -> 0 2016-03-23 15:38:07,809 [DEBUG] [doorpi.conf.config_object] create new key video_size in section SIP-Phone with value 'vga' 2016-03-23 15:38:07,810 [TRACE] [doorpi.conf.config_object] get_string for key video_size in section SIP-Phone (default: vga) returns vga 2016-03-23 15:38:07,811 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_preferred_video_size_by_name(0x7464df38 [0x1081970], "vga") 2016-03-23 15:38:07,812 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_preferred_video_size_by_name -> 0 2016-03-23 15:38:07,813 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_device(0x7464df38 [0x1081970]) 2016-03-23 15:38:07,814 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_device -> 0x745a4dd0 2016-03-23 15:38:07,815 [DEBUG] [doorpi.sipphone.from_linphone] using video_device: StaticImage: Static picture 2016-03-23 15:38:07,816 [TRACE] [doorpi.conf.config_object] get_list for key video_codecs in section SIP-Phone (default: VP8) returns ['VP8'] 2016-03-23 15:38:07,817 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_codecs(0x7464df38 [0x1081970]) 2016-03-23 15:38:07,818 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1081e98) 2016-03-23 15:38:07,819 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-23 15:38:07,820 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76ccd8d0 2016-03-23 15:38:07,821 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-23 15:38:07,822 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76ccd8d0 2016-03-23 15:38:07,823 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76ccd8d0 2016-03-23 15:38:07,824 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_codecs -> 0x745ad698 2016-03-23 15:38:07,824 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76ccd8d0 [0x1081e98]) 2016-03-23 15:38:07,825 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7464df50 2016-03-23 15:38:07,826 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_video_capture_enabled(0x7464df38 [0x1081970]) 2016-03-23 15:38:07,827 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_video_capture_enabled -> 0x2e0620 2016-03-23 15:38:07,828 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76ccd8d0 [0x1081e98]) 2016-03-23 15:38:07,829 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7464df50 2016-03-23 15:38:07,830 [DEBUG] [doorpi.sipphone.from_linphone] enable video codec VP8 2016-03-23 15:38:07,831 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x7464df38 [0x1081970], 0x76ccd8d0 [0x1081e98], 0x2e0620 [1]) 2016-03-23 15:38:07,832 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xeac8bc 2016-03-23 15:38:07,833 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_server in section SIP-Phone (default: ) returns 192.168.178.1 2016-03-23 15:38:07,834 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_username in section SIP-Phone (default: ) returns 620 2016-03-23 15:38:07,835 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_password in section SIP-Phone (default: 620) returns ******* 2016-03-23 15:38:07,835 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_realm in section SIP-Phone (default: 192.168.178.1) returns fritz.box 2016-03-23 15:38:07,836 [INFO] [doorpi.sipphone.from_linphone] using DoorPi with SIP-Server 2016-03-23 15:38:07,837 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_proxy_config(0x7464df38 [0x1081970]) 2016-03-23 15:38:07,838 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_from_native_ptr(0x1027150) 2016-03-23 15:38:07,839 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_new() 2016-03-23 15:38:07,840 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_new -> 0x76ccd8e0 2016-03-23 15:38:07,841 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_init() 2016-03-23 15:38:07,842 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_init -> 0x76ccd8e0 2016-03-23 15:38:07,843 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_from_native_ptr -> 0x76ccd8e0 2016-03-23 15:38:07,844 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_proxy_config -> 0x76ccd8e0 2016-03-23 15:38:07,845 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2016-03-23 15:38:07,846 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_class_method_new("DoorPi ") 2016-03-23 15:38:07,848 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0x1025cc0) 2016-03-23 15:38:07,849 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2016-03-23 15:38:07,850 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0x76ccd8f0 2016-03-23 15:38:07,851 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2016-03-23 15:38:07,852 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0x76ccd8f0 2016-03-23 15:38:07,853 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0x76ccd8f0 2016-03-23 15:38:07,854 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_class_method_new -> 0x76ccd8f0 2016-03-23 15:38:07,855 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_identity_address(0x76ccd8e0 [0x1027150], 0x76ccd8f0 [0x1025cc0]) 2016-03-23 15:38:07,856 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_identity_address -> 0 2016-03-23 15:38:07,856 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_server_addr(0x76ccd8e0 [0x1027150], "sip:192.168.178.1") 2016-03-23 15:38:07,858 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_server_addr -> 0 2016-03-23 15:38:07,859 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_enable_register(0x76ccd8e0 [0x1027150], 0x2e0620 [1]) 2016-03-23 15:38:07,861 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_enable_register -> 0 2016-03-23 15:38:07,861 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_proxy_config(0x7464df38 [0x1081970], 0x76ccd8e0 [0x1027150]) 2016-03-23 15:38:07,864 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_proxy_config -> 0xeac8bc 2016-03-23 15:38:07,865 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_default_proxy_config(0x7464df38 [0x1081970], 0x76ccd8e0 [0x1027150]) 2016-03-23 15:38:07,866 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_default_proxy_config -> 0 2016-03-23 15:38:07,867 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_auth_info(0x7464df38 [0x1081970], "620", "(null)", "nea1102", "(null)", "(null)", "fritz.box") 2016-03-23 15:38:07,867 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_from_native_ptr(0x1090b60) 2016-03-23 15:38:07,868 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_new() 2016-03-23 15:38:07,869 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_new -> 0x76ccd900 2016-03-23 15:38:07,870 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_init() 2016-03-23 15:38:07,871 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_init -> 0x76ccd900 2016-03-23 15:38:07,872 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_from_native_ptr -> 0x76ccd900 2016-03-23 15:38:07,873 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_auth_info -> 0x76ccd900 2016-03-23 15:38:07,874 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_auth_info(0x7464df38 [0x1081970], 0x76ccd900 [0x1090b60]) 2016-03-23 15:38:07,875 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_auth_info -> None 2016-03-23 15:38:07,876 [DEBUG] [doorpi.sipphone.from_linphone] start successfully 2016-03-23 15:38:07,877 [TRACE] [doorpi.conf.config_object] get_sections returns ['EVENT_OnMediaNotRequired', 'EVENT_OnStartup', 'EVENT_OnKeyUp_prodsystem.7', 'EVENT_OnKeyUp_prodsystem.33', 'EVENT_OnKeyUp_prodsystem.15', 'EVENT_OnKeyUp_prodsystem.31', 'EVENT_OnKeyUp_prodsystem.11', 'EVENT_OnKeyUp_prodsystem.13', 'EVENT_OnCallStateConnect', 'EVENT_OnKeyUp_prodsystem.35', 'EVENT_OnMediaRequired'] 2016-03-23 15:38:07,878 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnMediaNotRequired' in configfile 2016-03-23 15:38:07,879 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnMediaNotRequired returns ['10', '30', '20', '50', '40'] 2016-03-23 15:38:07,879 [INFO] [doorpi.doorpi] registering action '10' for event 'OnMediaNotRequired' 2016-03-23 15:38:07,881 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnMediaNotRequired (default: ) returns out:Gong-Keller,0 2016-03-23 15:38:07,882 [DEBUG] [doorpi.action.SingleActions.out] doorpi.action.SingleActions.out loaded 2016-03-23 15:38:07,884 [DEBUG] [doorpi.action.SingleActions.out_triggered] doorpi.action.SingleActions.out_triggered loaded 2016-03-23 15:38:07,886 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': True, 'value': '0', 'pin': 'Gong-Keller'} was added to new evententry OnMediaNotRequired 2016-03-23 15:38:07,887 [INFO] [doorpi.doorpi] registering action '20' for event 'OnMediaNotRequired' 2016-03-23 15:38:07,888 [TRACE] [doorpi.conf.config_object] get_string for key 20 in section EVENT_OnMediaNotRequired (default: ) returns out:Gong-Ernst,0 2016-03-23 15:38:07,889 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': True, 'value': '0', 'pin': 'Gong-Ernst'} was added to event OnMediaNotRequired 2016-03-23 15:38:07,890 [INFO] [doorpi.doorpi] registering action '30' for event 'OnMediaNotRequired' 2016-03-23 15:38:07,891 [TRACE] [doorpi.conf.config_object] get_string for key 30 in section EVENT_OnMediaNotRequired (default: ) returns out:Gong-Erhard,0 2016-03-23 15:38:07,892 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': True, 'value': '0', 'pin': 'Gong-Erhard'} was added to event OnMediaNotRequired 2016-03-23 15:38:07,893 [INFO] [doorpi.doorpi] registering action '40' for event 'OnMediaNotRequired' 2016-03-23 15:38:07,894 [TRACE] [doorpi.conf.config_object] get_string for key 40 in section EVENT_OnMediaNotRequired (default: ) returns out:Gong-Andreas,0 2016-03-23 15:38:07,895 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': True, 'value': '0', 'pin': 'Gong-Andreas'} was added to event OnMediaNotRequired 2016-03-23 15:38:07,895 [INFO] [doorpi.doorpi] registering action '50' for event 'OnMediaNotRequired' 2016-03-23 15:38:07,896 [TRACE] [doorpi.conf.config_object] get_string for key 50 in section EVENT_OnMediaNotRequired (default: ) returns out:Verstaerker,0 2016-03-23 15:38:07,897 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': True, 'value': '0', 'pin': 'Verstaerker'} was added to event OnMediaNotRequired 2016-03-23 15:38:07,898 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnStartup' in configfile 2016-03-23 15:38:07,899 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnStartup returns ['10'] 2016-03-23 15:38:07,900 [INFO] [doorpi.doorpi] registering action '10' for event 'OnStartup' 2016-03-23 15:38:07,901 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnStartup (default: ) returns sleep:1 2016-03-23 15:38:07,903 [DEBUG] [doorpi.action.SingleActions.sleep] doorpi.action.SingleActions.sleep loaded 2016-03-23 15:38:07,904 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to new evententry OnStartup 2016-03-23 15:38:07,905 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnKeyUp_prodsystem.7' in configfile 2016-03-23 15:38:07,906 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnKeyUp_prodsystem.7 returns ['10', '20', '30', '50', '40', '60', '70', '90', '80'] 2016-03-23 15:38:07,907 [INFO] [doorpi.doorpi] registering action '10' for event 'OnKeyUp_prodsystem.7' 2016-03-23 15:38:07,908 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnKeyUp_prodsystem.7 (default: ) returns os_execute:php /usr/local/etc/DoorPi/tools/take_snapshot_doorpi.php 2016-03-23 15:38:07,909 [DEBUG] [doorpi.action.SingleActions.os_execute] doorpi.action.SingleActions.os_execute loaded 2016-03-23 15:38:07,913 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': 'php /usr/local/etc/DoorPi/tools/take_snapshot_doorpi.php'} was added to new evententry OnKeyUp_prodsystem.7 2016-03-23 15:38:07,914 [INFO] [doorpi.doorpi] registering action '20' for event 'OnKeyUp_prodsystem.7' 2016-03-23 15:38:07,914 [TRACE] [doorpi.conf.config_object] get_string for key 20 in section EVENT_OnKeyUp_prodsystem.7 (default: ) returns out:Gong-Keller,1,0,0.5 2016-03-23 15:38:07,916 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 0.5, 'pin': 'Gong-Keller', 'start_value': '1'} was added to event OnKeyUp_prodsystem.7 2016-03-23 15:38:07,917 [INFO] [doorpi.doorpi] registering action '30' for event 'OnKeyUp_prodsystem.7' 2016-03-23 15:38:07,917 [TRACE] [doorpi.conf.config_object] get_string for key 30 in section EVENT_OnKeyUp_prodsystem.7 (default: ) returns call:**621 2016-03-23 15:38:07,919 [DEBUG] [doorpi.action.SingleActions.call] doorpi.action.SingleActions.call loaded 2016-03-23 15:38:07,920 [TRACE] [doorpi.action.handler] action CallAction with args () and kwargs {'number': '**621'} was added to event OnKeyUp_prodsystem.7 2016-03-23 15:38:07,922 [INFO] [doorpi.doorpi] registering action '40' for event 'OnKeyUp_prodsystem.7' 2016-03-23 15:38:07,923 [TRACE] [doorpi.conf.config_object] get_string for key 40 in section EVENT_OnKeyUp_prodsystem.7 (default: ) returns os_execute:sudo /usr/bin/sendEmail -f doorpi@schwarz-ketsch.de -t andreas@schwarz-ketsch.de -u Klingel -m Es steht jemand vor der Tuer -s smtp.1und1.de:587 -xu doorpi@schwarz-ketsch.de -xp schnillo1102 -o tls=yes &>/dev/null & 2016-03-23 15:38:07,925 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': 'sudo /usr/bin/sendEmail -f doorpi@schwarz-ketsch.de -t andreas@schwarz-ketsch.de -u Klingel -m Es steht jemand vor der Tuer -s smtp.1und1.de:587 -xu doorpi@schwarz-ketsch.de -xp schnillo1102 -o tls=yes &>/dev/null &'} was added to event OnKeyUp_prodsystem.7 2016-03-23 15:38:07,926 [INFO] [doorpi.doorpi] registering action '50' for event 'OnKeyUp_prodsystem.7' 2016-03-23 15:38:07,927 [TRACE] [doorpi.conf.config_object] get_string for key 50 in section EVENT_OnKeyUp_prodsystem.7 (default: ) returns sleep:2 2016-03-23 15:38:07,928 [TRACE] [doorpi.action.handler] action SleepAction with args (2.0,) and kwargs {} was added to event OnKeyUp_prodsystem.7 2016-03-23 15:38:07,929 [INFO] [doorpi.doorpi] registering action '60' for event 'OnKeyUp_prodsystem.7' 2016-03-23 15:38:07,930 [TRACE] [doorpi.conf.config_object] get_string for key 60 in section EVENT_OnKeyUp_prodsystem.7 (default: ) returns os_execute:sudo /usr/local/bin/mail-bild.sh 2016-03-23 15:38:07,932 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': 'sudo /usr/local/bin/mail-bild.sh'} was added to event OnKeyUp_prodsystem.7 2016-03-23 15:38:07,933 [INFO] [doorpi.doorpi] registering action '70' for event 'OnKeyUp_prodsystem.7' 2016-03-23 15:38:07,934 [TRACE] [doorpi.conf.config_object] get_string for key 70 in section EVENT_OnKeyUp_prodsystem.7 (default: ) returns os_execute:php /usr/local/etc/DoorPi/tools/take_snapshot_doorpi.php 2016-03-23 15:38:07,936 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': 'php /usr/local/etc/DoorPi/tools/take_snapshot_doorpi.php'} was added to event OnKeyUp_prodsystem.7 2016-03-23 15:38:07,937 [INFO] [doorpi.doorpi] registering action '80' for event 'OnKeyUp_prodsystem.7' 2016-03-23 15:38:07,938 [TRACE] [doorpi.conf.config_object] get_string for key 80 in section EVENT_OnKeyUp_prodsystem.7 (default: ) returns sleep:2 2016-03-23 15:38:07,939 [TRACE] [doorpi.action.handler] action SleepAction with args (2.0,) and kwargs {} was added to event OnKeyUp_prodsystem.7 2016-03-23 15:38:07,940 [INFO] [doorpi.doorpi] registering action '90' for event 'OnKeyUp_prodsystem.7' 2016-03-23 15:38:07,941 [TRACE] [doorpi.conf.config_object] get_string for key 90 in section EVENT_OnKeyUp_prodsystem.7 (default: ) returns os_execute:php /usr/local/etc/DoorPi/tools/take_snapshot_doorpi.php 2016-03-23 15:38:07,944 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': 'php /usr/local/etc/DoorPi/tools/take_snapshot_doorpi.php'} was added to event OnKeyUp_prodsystem.7 2016-03-23 15:38:07,944 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnKeyUp_prodsystem.33' in configfile 2016-03-23 15:38:07,945 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnKeyUp_prodsystem.33 returns ['10'] 2016-03-23 15:38:07,946 [INFO] [doorpi.doorpi] registering action '10' for event 'OnKeyUp_prodsystem.33' 2016-03-23 15:38:07,947 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnKeyUp_prodsystem.33 (default: ) returns out:Licht,1,0,0.1 2016-03-23 15:38:07,948 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 0.1, 'pin': 'Licht', 'start_value': '1'} was added to new evententry OnKeyUp_prodsystem.33 2016-03-23 15:38:07,949 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnKeyUp_prodsystem.15' in configfile 2016-03-23 15:38:07,950 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnKeyUp_prodsystem.15 returns ['10', '20', '30', '50', '40', '60', '70', '80'] 2016-03-23 15:38:07,951 [INFO] [doorpi.doorpi] registering action '10' for event 'OnKeyUp_prodsystem.15' 2016-03-23 15:38:07,952 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnKeyUp_prodsystem.15 (default: ) returns os_execute:php /usr/local/etc/DoorPi/tools/take_snapshot_doorpi.php 2016-03-23 15:38:07,954 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': 'php /usr/local/etc/DoorPi/tools/take_snapshot_doorpi.php'} was added to new evententry OnKeyUp_prodsystem.15 2016-03-23 15:38:07,955 [INFO] [doorpi.doorpi] registering action '20' for event 'OnKeyUp_prodsystem.15' 2016-03-23 15:38:07,956 [TRACE] [doorpi.conf.config_object] get_string for key 20 in section EVENT_OnKeyUp_prodsystem.15 (default: ) returns out:Gong-Andreas,1,0,0.5 2016-03-23 15:38:07,957 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 0.5, 'pin': 'Gong-Andreas', 'start_value': '1'} was added to event OnKeyUp_prodsystem.15 2016-03-23 15:38:07,958 [INFO] [doorpi.doorpi] registering action '30' for event 'OnKeyUp_prodsystem.15' 2016-03-23 15:38:07,959 [TRACE] [doorpi.conf.config_object] get_string for key 30 in section EVENT_OnKeyUp_prodsystem.15 (default: ) returns call:**624 2016-03-23 15:38:07,960 [TRACE] [doorpi.action.handler] action CallAction with args () and kwargs {'number': '**624'} was added to event OnKeyUp_prodsystem.15 2016-03-23 15:38:07,961 [INFO] [doorpi.doorpi] registering action '40' for event 'OnKeyUp_prodsystem.15' 2016-03-23 15:38:07,962 [TRACE] [doorpi.conf.config_object] get_string for key 40 in section EVENT_OnKeyUp_prodsystem.15 (default: ) returns sleep:2 2016-03-23 15:38:07,963 [TRACE] [doorpi.action.handler] action SleepAction with args (2.0,) and kwargs {} was added to event OnKeyUp_prodsystem.15 2016-03-23 15:38:07,964 [INFO] [doorpi.doorpi] registering action '50' for event 'OnKeyUp_prodsystem.15' 2016-03-23 15:38:07,965 [TRACE] [doorpi.conf.config_object] get_string for key 50 in section EVENT_OnKeyUp_prodsystem.15 (default: ) returns os_execute:sudo /usr/local/bin/mail-bild.sh 2016-03-23 15:38:07,967 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': 'sudo /usr/local/bin/mail-bild.sh'} was added to event OnKeyUp_prodsystem.15 2016-03-23 15:38:07,968 [INFO] [doorpi.doorpi] registering action '60' for event 'OnKeyUp_prodsystem.15' 2016-03-23 15:38:07,969 [TRACE] [doorpi.conf.config_object] get_string for key 60 in section EVENT_OnKeyUp_prodsystem.15 (default: ) returns os_execute:php /usr/local/etc/DoorPi/tools/take_snapshot_doorpi.php 2016-03-23 15:38:07,972 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': 'php /usr/local/etc/DoorPi/tools/take_snapshot_doorpi.php'} was added to event OnKeyUp_prodsystem.15 2016-03-23 15:38:07,972 [INFO] [doorpi.doorpi] registering action '70' for event 'OnKeyUp_prodsystem.15' 2016-03-23 15:38:07,973 [TRACE] [doorpi.conf.config_object] get_string for key 70 in section EVENT_OnKeyUp_prodsystem.15 (default: ) returns sleep:2 2016-03-23 15:38:07,974 [TRACE] [doorpi.action.handler] action SleepAction with args (2.0,) and kwargs {} was added to event OnKeyUp_prodsystem.15 2016-03-23 15:38:07,975 [INFO] [doorpi.doorpi] registering action '80' for event 'OnKeyUp_prodsystem.15' 2016-03-23 15:38:07,976 [TRACE] [doorpi.conf.config_object] get_string for key 80 in section EVENT_OnKeyUp_prodsystem.15 (default: ) returns os_execute:php /usr/local/etc/DoorPi/tools/take_snapshot_doorpi.php 2016-03-23 15:38:07,978 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': 'php /usr/local/etc/DoorPi/tools/take_snapshot_doorpi.php'} was added to event OnKeyUp_prodsystem.15 2016-03-23 15:38:07,979 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnKeyUp_prodsystem.31' in configfile 2016-03-23 15:38:07,980 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnKeyUp_prodsystem.31 returns ['10', '20'] 2016-03-23 15:38:07,981 [INFO] [doorpi.doorpi] registering action '10' for event 'OnKeyUp_prodsystem.31' 2016-03-23 15:38:07,982 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnKeyUp_prodsystem.31 (default: ) returns out:Licht,1,0,300 2016-03-23 15:38:07,983 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 300.0, 'pin': 'Licht', 'start_value': '1'} was added to new evententry OnKeyUp_prodsystem.31 2016-03-23 15:38:07,984 [INFO] [doorpi.doorpi] registering action '20' for event 'OnKeyUp_prodsystem.31' 2016-03-23 15:38:07,985 [TRACE] [doorpi.conf.config_object] get_string for key 20 in section EVENT_OnKeyUp_prodsystem.31 (default: ) returns sleep:1 2016-03-23 15:38:07,986 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to event OnKeyUp_prodsystem.31 2016-03-23 15:38:07,987 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnKeyUp_prodsystem.11' in configfile 2016-03-23 15:38:07,988 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnKeyUp_prodsystem.11 returns ['10', '20'] 2016-03-23 15:38:07,989 [INFO] [doorpi.doorpi] registering action '10' for event 'OnKeyUp_prodsystem.11' 2016-03-23 15:38:07,990 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnKeyUp_prodsystem.11 (default: ) returns out:Gong-Ernst,1,0,0.5 2016-03-23 15:38:07,991 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 0.5, 'pin': 'Gong-Ernst', 'start_value': '1'} was added to new evententry OnKeyUp_prodsystem.11 2016-03-23 15:38:07,992 [INFO] [doorpi.doorpi] registering action '20' for event 'OnKeyUp_prodsystem.11' 2016-03-23 15:38:07,993 [TRACE] [doorpi.conf.config_object] get_string for key 20 in section EVENT_OnKeyUp_prodsystem.11 (default: ) returns call:**622 2016-03-23 15:38:07,994 [TRACE] [doorpi.action.handler] action CallAction with args () and kwargs {'number': '**622'} was added to event OnKeyUp_prodsystem.11 2016-03-23 15:38:07,995 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnKeyUp_prodsystem.13' in configfile 2016-03-23 15:38:07,996 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnKeyUp_prodsystem.13 returns ['10', '20'] 2016-03-23 15:38:07,996 [INFO] [doorpi.doorpi] registering action '10' for event 'OnKeyUp_prodsystem.13' 2016-03-23 15:38:07,997 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnKeyUp_prodsystem.13 (default: ) returns out:Gong-Erhard,1,0,0.5 2016-03-23 15:38:07,998 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 0.5, 'pin': 'Gong-Erhard', 'start_value': '1'} was added to new evententry OnKeyUp_prodsystem.13 2016-03-23 15:38:07,999 [INFO] [doorpi.doorpi] registering action '20' for event 'OnKeyUp_prodsystem.13' 2016-03-23 15:38:08,000 [TRACE] [doorpi.conf.config_object] get_string for key 20 in section EVENT_OnKeyUp_prodsystem.13 (default: ) returns call:**623 2016-03-23 15:38:08,001 [TRACE] [doorpi.action.handler] action CallAction with args () and kwargs {'number': '**623'} was added to event OnKeyUp_prodsystem.13 2016-03-23 15:38:08,002 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnCallStateConnect' in configfile 2016-03-23 15:38:08,003 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnCallStateConnect returns ['10'] 2016-03-23 15:38:08,004 [INFO] [doorpi.doorpi] registering action '10' for event 'OnCallStateConnect' 2016-03-23 15:38:08,005 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnCallStateConnect (default: ) returns out:Verstaerker,1,0,200 2016-03-23 15:38:08,006 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 200.0, 'pin': 'Verstaerker', 'start_value': '1'} was added to event OnCallStateConnect 2016-03-23 15:38:08,007 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnKeyUp_prodsystem.35' in configfile 2016-03-23 15:38:08,008 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnKeyUp_prodsystem.35 returns ['10', '20'] 2016-03-23 15:38:08,009 [INFO] [doorpi.doorpi] registering action '10' for event 'OnKeyUp_prodsystem.35' 2016-03-23 15:38:08,009 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnKeyUp_prodsystem.35 (default: ) returns out:Licht,1,0,21600 2016-03-23 15:38:08,011 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 21600.0, 'pin': 'Licht', 'start_value': '1'} was added to new evententry OnKeyUp_prodsystem.35 2016-03-23 15:38:08,012 [INFO] [doorpi.doorpi] registering action '20' for event 'OnKeyUp_prodsystem.35' 2016-03-23 15:38:08,013 [TRACE] [doorpi.conf.config_object] get_string for key 20 in section EVENT_OnKeyUp_prodsystem.35 (default: ) returns sleep:1 2016-03-23 15:38:08,014 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to event OnKeyUp_prodsystem.35 2016-03-23 15:38:08,014 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnMediaRequired' in configfile 2016-03-23 15:38:08,015 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnMediaRequired returns [] 2016-03-23 15:38:08,016 [TRACE] [doorpi.conf.config_object] get_string for key 11 in section prodsystem_InputPins (default: ) returns sleep:0.1 2016-03-23 15:38:08,017 [TRACE] [doorpi.action.handler] action SleepAction with args (0.1,) and kwargs {} was added to new evententry OnKeyPressed_prodsystem.11 2016-03-23 15:38:08,018 [TRACE] [doorpi.conf.config_object] get_string for key 13 in section prodsystem_InputPins (default: ) returns sleep:0.1 2016-03-23 15:38:08,019 [TRACE] [doorpi.action.handler] action SleepAction with args (0.1,) and kwargs {} was added to new evententry OnKeyPressed_prodsystem.13 2016-03-23 15:38:08,020 [TRACE] [doorpi.conf.config_object] get_string for key 15 in section prodsystem_InputPins (default: ) returns sleep:0.1 2016-03-23 15:38:08,021 [TRACE] [doorpi.action.handler] action SleepAction with args (0.1,) and kwargs {} was added to new evententry OnKeyPressed_prodsystem.15 2016-03-23 15:38:08,023 [TRACE] [doorpi.conf.config_object] get_string for key 33 in section prodsystem_InputPins (default: ) returns sleep:0.1 2016-03-23 15:38:08,024 [TRACE] [doorpi.action.handler] action SleepAction with args (0.1,) and kwargs {} was added to new evententry OnKeyPressed_prodsystem.33 2016-03-23 15:38:08,025 [TRACE] [doorpi.conf.config_object] get_string for key 31 in section prodsystem_InputPins (default: ) returns Licht-Bewegungssensor-in 2016-03-23 15:38:08,026 [ERROR] [doorpi.action.base] error while creating SingleAction from config string: Licht-Bewegungssensor-in Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/DoorPi-2.5.0.0-py2.7.egg/doorpi/action/base.py", line 64, in from_string return importlib.import_module('doorpi.action.SingleActions.'+action_name).get( File "/usr/lib/python2.7/importlib/__init__.py", line 37, in import_module __import__(name) ImportError: No module named Licht-Bewegungssensor-in 2016-03-23 15:38:08,028 [ERROR] [doorpi.action.handler] action_object is None 2016-03-23 15:38:08,029 [TRACE] [doorpi.conf.config_object] get_string for key 35 in section prodsystem_InputPins (default: ) returns Licht-manuell 2016-03-23 15:38:08,030 [ERROR] [doorpi.action.base] error while creating SingleAction from config string: Licht-manuell Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/DoorPi-2.5.0.0-py2.7.egg/doorpi/action/base.py", line 64, in from_string return importlib.import_module('doorpi.action.SingleActions.'+action_name).get( File "/usr/lib/python2.7/importlib/__init__.py", line 37, in import_module __import__(name) ImportError: No module named Licht-manuell 2016-03-23 15:38:08,032 [ERROR] [doorpi.action.handler] action_object is None 2016-03-23 15:38:08,033 [TRACE] [doorpi.conf.config_object] get_string for key 7 in section prodsystem_InputPins (default: ) returns sleep:0.1 2016-03-23 15:38:08,034 [TRACE] [doorpi.action.handler] action SleepAction with args (0.1,) and kwargs {} was added to new evententry OnKeyPressed_prodsystem.7 2016-03-23 15:38:08,035 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns ['"1"', '"**001*"', '"**002*"', '"#"'] 2016-03-23 15:38:08,036 [TRACE] [doorpi.conf.config_object] get_string for key "#" in section DTMF (default: ) returns out:Tueroeffner,1,0,2 2016-03-23 15:38:08,037 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 2.0, 'pin': 'Tueroeffner', 'start_value': '1'} was added to new evententry OnDTMF_"#" 2016-03-23 15:38:08,038 [TRACE] [doorpi.conf.config_object] get_string for key "**001*" in section DTMF (default: ) returns out:Verstaerker,0 2016-03-23 15:38:08,039 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': True, 'value': '0', 'pin': 'Verstaerker'} was added to new evententry OnDTMF_"**001*" 2016-03-23 15:38:08,040 [TRACE] [doorpi.conf.config_object] get_string for key "**002*" in section DTMF (default: ) returns out:Verstaerker,1 2016-03-23 15:38:08,041 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': True, 'value': '1', 'pin': 'Verstaerker'} was added to new evententry OnDTMF_"**002*" 2016-03-23 15:38:08,042 [TRACE] [doorpi.conf.config_object] get_string for key "1" in section DTMF (default: ) returns out:Licht,1,0,300 2016-03-23 15:38:08,043 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 300.0, 'pin': 'Licht', 'start_value': '1'} was added to new evententry OnDTMF_"1" 2016-03-23 15:38:08,044 [TRACE] [doorpi.conf.config_object] get_string for key is_alive_led in section DoorPi (default: ) returns Blink LED 2016-03-23 15:38:08,045 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': 'False', 'value': 'HIGH', 'pin': 'Blink LED'} was added to new evententry OnTimeSecondEvenNumber 2016-03-23 15:38:08,046 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': 'False', 'value': 'LOW', 'pin': 'Blink LED'} was added to new evententry OnTimeSecondUnevenNumber 2016-03-23 15:38:08,047 [TRACE] [doorpi.action.handler] fire Event BeforeStartup from doorpi.doorpi asyncron 2016-03-23 15:38:08,050 [DEBUG] [doorpi.action.handler] [UFIZO9] fire for event OnStartup this actions [] 2016-03-23 15:38:08,051 [DEBUG] [doorpi.action.handler] no actions for event BeforeStartup - skip fire_event BeforeStartup from doorpi.doorpi 2016-03-23 15:38:08,052 [TRACE] [doorpi.action.handler] [UFIZO9] try to fire action SleepAction with args (1.0,) and kwargs {} 2016-03-23 15:38:08,054 [TRACE] [doorpi.action.base] run SleepAction with args (1.0,) and kwargs {} 2016-03-23 15:38:09,056 [TRACE] [doorpi.action.handler] [UFIZO9] finished fire_event for event_name OnStartup 2016-03-23 15:38:09,059 [TRACE] [doorpi.action.handler] fire Event AfterStartup from doorpi.doorpi asyncron 2016-03-23 15:38:09,061 [INFO] [doorpi.doorpi] DoorPi started successfully 2016-03-23 15:38:09,064 [INFO] [doorpi.doorpi] BasePath is /usr/local/etc/DoorPi 2016-03-23 15:38:09,064 [DEBUG] [doorpi.action.handler] no actions for event AfterStartup - skip fire_event AfterStartup from doorpi.doorpi 2016-03-23 15:38:09,066 [INFO] [doorpi.doorpi] Weburl is http://raspberrypi/ 2016-03-23 15:38:09,069 [INFO] [doorpi.sipphone.from_linphone] resolved to 192.168.178.1 2016-03-23 15:38:09,071 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2016-03-23 15:38:09,074 [INFO] [doorpi.sipphone.from_linphone] New local ip address is 192.168.178.241 2016-03-23 15:38:09,075 [INFO] [doorpi.sipphone.from_linphone] Network state is now [UP] 2016-03-23 15:38:09,078 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2016-03-23 15:38:09,080 [INFO] [doorpi.sipphone.from_linphone] 192.168.178.1 2016-03-23 15:38:09,083 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2016-03-23 15:38:09,085 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2016-03-23 15:38:09,088 [INFO] [doorpi.sipphone.from_linphone] LinphoneProxyConfig [0x1027150] about to register (LinphoneCore version: 3.9.0) 2016-03-23 15:38:09,099 [INFO] [doorpi.sipphone.from_linphone] belle_sip_client_transaction_send_request(): waiting channel to be ready 2016-03-23 15:38:09,101 [INFO] [doorpi.sipphone.from_linphone] channel [0x1090c58]: starting resolution of 192.168.178.1 2016-03-23 15:38:09,103 [INFO] [doorpi.sipphone.from_linphone] channel 0x1090c58: state RES_IN_PROGRESS 2016-03-23 15:38:09,105 [INFO] [doorpi.sipphone.from_linphone] transaction [0x1090ab8] channel state changed to [RES_IN_PROGRESS] 2016-03-23 15:38:09,107 [INFO] [doorpi.sipphone.from_linphone] channel 0x1090c58: state RES_DONE 2016-03-23 15:38:09,108 [INFO] [doorpi.sipphone.from_linphone] transaction [0x1090ab8] channel state changed to [RES_DONE] 2016-03-23 15:38:09,110 [INFO] [doorpi.sipphone.from_linphone] channel 0x1090c58: state CONNECTING 2016-03-23 15:38:09,112 [INFO] [doorpi.sipphone.from_linphone] transaction [0x1090ab8] channel state changed to [CONNECTING] 2016-03-23 15:38:09,114 [INFO] [doorpi.sipphone.from_linphone] Trying to connect to [UDP://192.168.178.1:5060] 2016-03-23 15:38:09,116 [INFO] [doorpi.sipphone.from_linphone] belle_sip_get_src_addr_for(): af_inet6=0 2016-03-23 15:38:09,118 [INFO] [doorpi.sipphone.from_linphone] Channel has local address 192.168.178.241:5060 2016-03-23 15:38:09,120 [INFO] [doorpi.sipphone.from_linphone] channel 0x1090c58: state READY 2016-03-23 15:38:09,122 [INFO] [doorpi.sipphone.from_linphone] transaction [0x1090ab8] channel state changed to [READY] 2016-03-23 15:38:09,124 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x1090ab8], from state [INIT] to [TRYING] 2016-03-23 15:38:09,126 [INFO] [doorpi.sipphone.from_linphone] channel [0x1090c58]: message sent to [UDP://192.168.178.1:5060], size: [516] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.241:5060;branch=z9hG4bK.YPHgMO1tv;rport From: "DoorPi" ;tag=fJPxkWYzC To: "DoorPi" CSeq: 20 REGISTER Call-ID: sIQQGfe1aR Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2016-03-23 15:38:09,135 [INFO] [doorpi.sipphone.from_linphone] Neither Expires header nor corresponding Contact header found, checking from original request 2016-03-23 15:38:09,137 [INFO] [doorpi.sipphone.from_linphone] Refresher [0x107fcd0] takes ownership of transaction [0x1090ab8] 2016-03-23 15:38:09,138 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0x1027150] for identity ["DoorPi" ] moving from state [LinphoneRegistrationNone] to [LinphoneRegistrationProgress] 2016-03-23 15:38:09,140 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0x1081970, 0x1027150, 1, "Registration in progress") 2016-03-23 15:38:09,143 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2016-03-23 15:38:09,145 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1081970] notifying [registration_state_changed] 2016-03-23 15:38:09,198 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_network_reachable(0x1081970, 0x1) 2016-03-23 15:38:09,200 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_network_reachable 2016-03-23 15:38:09,203 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1081970] notifying [network_reachable] 2016-03-23 15:38:09,205 [INFO] [doorpi.sipphone.from_linphone] resolved to 192.168.178.1 2016-03-23 15:38:09,207 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2016-03-23 15:38:09,663 [INFO] [doorpi.sipphone.from_linphone] nict_on_timer_E: sending retransmission 2016-03-23 15:38:09,666 [INFO] [doorpi.sipphone.from_linphone] channel [0x1090c58]: message sent to [UDP://192.168.178.1:5060], size: [516] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.241:5060;branch=z9hG4bK.YPHgMO1tv;rport From: "DoorPi" ;tag=fJPxkWYzC To: "DoorPi" CSeq: 20 REGISTER Call-ID: sIQQGfe1aR Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2016-03-23 15:38:10,638 [INFO] [doorpi.sipphone.from_linphone] nict_on_timer_E: sending retransmission 2016-03-23 15:38:10,640 [INFO] [doorpi.sipphone.from_linphone] channel [0x1090c58]: message sent to [UDP://192.168.178.1:5060], size: [516] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.241:5060;branch=z9hG4bK.YPHgMO1tv;rport From: "DoorPi" ;tag=fJPxkWYzC To: "DoorPi" CSeq: 20 REGISTER Call-ID: sIQQGfe1aR Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2016-03-23 15:38:12,670 [INFO] [doorpi.sipphone.from_linphone] nict_on_timer_E: sending retransmission 2016-03-23 15:38:12,672 [INFO] [doorpi.sipphone.from_linphone] channel [0x1090c58]: message sent to [UDP://192.168.178.1:5060], size: [516] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.241:5060;branch=z9hG4bK.YPHgMO1tv;rport From: "DoorPi" ;tag=fJPxkWYzC To: "DoorPi" CSeq: 20 REGISTER Call-ID: sIQQGfe1aR Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2016-03-23 15:38:16,675 [INFO] [doorpi.sipphone.from_linphone] nict_on_timer_E: sending retransmission 2016-03-23 15:38:16,678 [INFO] [doorpi.sipphone.from_linphone] channel [0x1090c58]: message sent to [UDP://192.168.178.1:5060], size: [516] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.241:5060;branch=z9hG4bK.YPHgMO1tv;rport From: "DoorPi" ;tag=fJPxkWYzC To: "DoorPi" CSeq: 20 REGISTER Call-ID: sIQQGfe1aR Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2016-03-23 15:38:20,634 [INFO] [doorpi.sipphone.from_linphone] nict_on_timer_E: sending retransmission 2016-03-23 15:38:20,636 [INFO] [doorpi.sipphone.from_linphone] channel [0x1090c58]: message sent to [UDP://192.168.178.1:5060], size: [516] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.241:5060;branch=z9hG4bK.YPHgMO1tv;rport From: "DoorPi" ;tag=fJPxkWYzC To: "DoorPi" CSeq: 20 REGISTER Call-ID: sIQQGfe1aR Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2016-03-23 15:38:24,638 [INFO] [doorpi.sipphone.from_linphone] nict_on_timer_E: sending retransmission 2016-03-23 15:38:24,641 [INFO] [doorpi.sipphone.from_linphone] channel [0x1090c58]: message sent to [UDP://192.168.178.1:5060], size: [516] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.241:5060;branch=z9hG4bK.YPHgMO1tv;rport From: "DoorPi" ;tag=fJPxkWYzC To: "DoorPi" CSeq: 20 REGISTER Call-ID: sIQQGfe1aR Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2016-03-23 15:38:28,653 [INFO] [doorpi.sipphone.from_linphone] nict_on_timer_E: sending retransmission 2016-03-23 15:38:28,655 [INFO] [doorpi.sipphone.from_linphone] channel [0x1090c58]: message sent to [UDP://192.168.178.1:5060], size: [516] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.241:5060;branch=z9hG4bK.YPHgMO1tv;rport From: "DoorPi" ;tag=fJPxkWYzC To: "DoorPi" CSeq: 20 REGISTER Call-ID: sIQQGfe1aR Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2016-03-23 15:38:32,661 [INFO] [doorpi.sipphone.from_linphone] nict_on_timer_E: sending retransmission 2016-03-23 15:38:32,663 [INFO] [doorpi.sipphone.from_linphone] channel [0x1090c58]: message sent to [UDP://192.168.178.1:5060], size: [516] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.241:5060;branch=z9hG4bK.YPHgMO1tv;rport From: "DoorPi" ;tag=fJPxkWYzC To: "DoorPi" CSeq: 20 REGISTER Call-ID: sIQQGfe1aR Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2016-03-23 15:38:36,664 [INFO] [doorpi.sipphone.from_linphone] nict_on_timer_E: sending retransmission 2016-03-23 15:38:36,665 [INFO] [doorpi.sipphone.from_linphone] channel [0x1090c58]: message sent to [UDP://192.168.178.1:5060], size: [516] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.241:5060;branch=z9hG4bK.YPHgMO1tv;rport From: "DoorPi" ;tag=fJPxkWYzC To: "DoorPi" CSeq: 20 REGISTER Call-ID: sIQQGfe1aR Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2016-03-23 15:38:40,670 [INFO] [doorpi.sipphone.from_linphone] nict_on_timer_E: sending retransmission 2016-03-23 15:38:40,674 [INFO] [doorpi.sipphone.from_linphone] channel [0x1090c58]: message sent to [UDP://192.168.178.1:5060], size: [516] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.241:5060;branch=z9hG4bK.YPHgMO1tv;rport From: "DoorPi" ;tag=fJPxkWYzC To: "DoorPi" CSeq: 20 REGISTER Call-ID: sIQQGfe1aR Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2016-03-23 15:38:41,142 [INFO] [doorpi.sipphone.from_linphone] A timeout related to this channel occured and no message received during last 60 seconds. This channel is suspect, moving to error state 2016-03-23 15:38:41,143 [INFO] [doorpi.sipphone.from_linphone] channel 0x1090c58: state ERROR 2016-03-23 15:38:41,144 [WARNING] [doorpi.sipphone.from_linphone] Transaction [0x1090ab8] reporting timeout, reporting to channel. 2016-03-23 15:38:41,196 [INFO] [doorpi.sipphone.from_linphone] transaction [0x1090ab8] channel state changed to [ERROR] 2016-03-23 15:38:41,197 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 500 ms 2016-03-23 15:38:41,198 [INFO] [doorpi.sipphone.from_linphone] Register refresher [503] reason [io error] for proxy [sip:192.168.178.1] 2016-03-23 15:38:41,199 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0x1027150] for identity ["DoorPi" ] moving from state [LinphoneRegistrationProgress] to [LinphoneRegistrationFailed] 2016-03-23 15:38:41,199 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0x1081970, 0x1027150, 4, "io error") 2016-03-23 15:38:41,201 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2016-03-23 15:38:41,201 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1081970] notifying [registration_state_changed] 2016-03-23 15:38:41,202 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 2000 ms 2016-03-23 15:38:41,203 [INFO] [doorpi.sipphone.from_linphone] Register refresher [408] reason [timeout] for proxy [sip:192.168.178.1] 2016-03-23 15:38:41,204 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x1090ab8], from state [TRYING] to [TERMINATED] 2016-03-23 15:38:41,205 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x1090ab8] terminated 2016-03-23 15:38:43,233 [INFO] [doorpi.sipphone.from_linphone] belle_sip_client_transaction_send_request(): waiting channel to be ready 2016-03-23 15:38:43,234 [INFO] [doorpi.sipphone.from_linphone] channel [0x10a0d60]: starting resolution of 192.168.178.1 2016-03-23 15:38:43,235 [INFO] [doorpi.sipphone.from_linphone] channel 0x10a0d60: state RES_IN_PROGRESS 2016-03-23 15:38:43,236 [INFO] [doorpi.sipphone.from_linphone] transaction [0x107c348] channel state changed to [RES_IN_PROGRESS] 2016-03-23 15:38:43,237 [INFO] [doorpi.sipphone.from_linphone] channel 0x10a0d60: state RES_DONE 2016-03-23 15:38:43,238 [INFO] [doorpi.sipphone.from_linphone] transaction [0x107c348] channel state changed to [RES_DONE] 2016-03-23 15:38:43,239 [INFO] [doorpi.sipphone.from_linphone] channel 0x10a0d60: state CONNECTING 2016-03-23 15:38:43,240 [INFO] [doorpi.sipphone.from_linphone] transaction [0x107c348] channel state changed to [CONNECTING] 2016-03-23 15:38:43,241 [INFO] [doorpi.sipphone.from_linphone] Trying to connect to [UDP://192.168.178.1:5060] 2016-03-23 15:38:43,242 [INFO] [doorpi.sipphone.from_linphone] belle_sip_get_src_addr_for(): af_inet6=0 2016-03-23 15:38:43,243 [INFO] [doorpi.sipphone.from_linphone] Channel has local address 192.168.178.241:5060 2016-03-23 15:38:43,244 [INFO] [doorpi.sipphone.from_linphone] channel 0x10a0d60: state READY 2016-03-23 15:38:43,245 [INFO] [doorpi.sipphone.from_linphone] transaction [0x107c348] channel state changed to [READY] 2016-03-23 15:38:43,246 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x107c348], from state [INIT] to [TRYING] 2016-03-23 15:38:43,247 [INFO] [doorpi.sipphone.from_linphone] channel [0x10a0d60]: message sent to [UDP://192.168.178.1:5060], size: [516] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.241:5060;branch=z9hG4bK.-4IirSizS;rport From: "DoorPi" ;tag=fJPxkWYzC To: "DoorPi" CSeq: 21 REGISTER Call-ID: sIQQGfe1aR Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2016-03-23 15:38:43,754 [INFO] [doorpi.sipphone.from_linphone] nict_on_timer_E: sending retransmission 2016-03-23 15:38:43,755 [INFO] [doorpi.sipphone.from_linphone] channel [0x10a0d60]: message sent to [UDP://192.168.178.1:5060], size: [516] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.241:5060;branch=z9hG4bK.-4IirSizS;rport From: "DoorPi" ;tag=fJPxkWYzC To: "DoorPi" CSeq: 21 REGISTER Call-ID: sIQQGfe1aR Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2016-03-23 15:38:44,772 [INFO] [doorpi.sipphone.from_linphone] nict_on_timer_E: sending retransmission 2016-03-23 15:38:44,776 [INFO] [doorpi.sipphone.from_linphone] channel [0x10a0d60]: message sent to [UDP://192.168.178.1:5060], size: [516] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.241:5060;branch=z9hG4bK.-4IirSizS;rport From: "DoorPi" ;tag=fJPxkWYzC To: "DoorPi" CSeq: 21 REGISTER Call-ID: sIQQGfe1aR Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2)