2016-05-30 21:55:11,442 [INFO] [doorpi.main] ___ ___ _ / \___ ___ _ __ / _ (_) VoIP Door-Intercomstation with Raspberry Pi / /\ / _ \ / _ \| '__/ /_)/ | version: 2.5.0.4 / /_// (_) | (_) | | / ___/| | license: CC BY-NC 4.0 /___,' \___/ \___/|_| \/ |_| URL: Authors: Thomas Meissner Supporter: Phillip Munz Hermann Dötsch Dennis Häußler Hubert Nusser Michael Hauer Andreas Schwarz Max Rößler missing someone? -> sorry -> mail me 2016-05-30 21:55:11,443 [DEBUG] [doorpi.main] loaded with arguments: ['/usr/local/bin/doorpi_cli', '--trace'] 2016-05-30 21:55:11,444 [DEBUG] [doorpi.doorpi] run 2016-05-30 21:55:11,445 [DEBUG] [doorpi.doorpi] prepare 2016-05-30 21:55:11,445 [DEBUG] [doorpi.doorpi] given arguments argv: Namespace(configfile=None, debug=False, test=False, trace=True) 2016-05-30 21:55:11,449 [INFO] [doorpi.conf.config_object] use configfile: /usr/local/etc/DoorPi/conf/doorpi.ini 2016-05-30 21:55:11,458 [DEBUG] [doorpi.conf.config_object] __init__ 2016-05-30 21:55:11,458 [TRACE] [doorpi.conf.config_object] get_from_config 2016-05-30 21:55:11,465 [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-05-30 21:55:11,466 [TRACE] [doorpi.conf.config_object] get_string for key eventlog in section DoorPi (default: !BASEPATH!/conf/eventlog.db) returns !BASEPATH!/conf/eventlog.db 2016-05-30 21:55:11,467 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/conf/eventlog.db" to "/usr/local/etc/DoorPi/conf/eventlog.db" 2016-05-30 21:55:11,470 [TRACE] [doorpi.action.handler] register Event BeforeStartup from doorpi.doorpi 2016-05-30 21:55:11,471 [DEBUG] [doorpi.action.handler] event_source doorpi.doorpi was added 2016-05-30 21:55:11,471 [TRACE] [doorpi.action.handler] added event_name BeforeStartup and registered source doorpi.doorpi 2016-05-30 21:55:11,472 [TRACE] [doorpi.action.handler] register Event OnStartup from doorpi.doorpi 2016-05-30 21:55:11,472 [TRACE] [doorpi.action.handler] added event_name OnStartup and registered source doorpi.doorpi 2016-05-30 21:55:11,472 [TRACE] [doorpi.action.handler] register Event AfterStartup from doorpi.doorpi 2016-05-30 21:55:11,473 [TRACE] [doorpi.action.handler] added event_name AfterStartup and registered source doorpi.doorpi 2016-05-30 21:55:11,473 [TRACE] [doorpi.action.handler] register Event BeforeShutdown from doorpi.doorpi 2016-05-30 21:55:11,473 [TRACE] [doorpi.action.handler] added event_name BeforeShutdown and registered source doorpi.doorpi 2016-05-30 21:55:11,474 [TRACE] [doorpi.action.handler] register Event OnShutdown from doorpi.doorpi 2016-05-30 21:55:11,474 [TRACE] [doorpi.action.handler] added event_name OnShutdown and registered source doorpi.doorpi 2016-05-30 21:55:11,475 [TRACE] [doorpi.action.handler] register Event AfterShutdown from doorpi.doorpi 2016-05-30 21:55:11,475 [TRACE] [doorpi.action.handler] added event_name AfterShutdown and registered source doorpi.doorpi 2016-05-30 21:55:11,477 [DEBUG] [doorpi.action.SingleActions.time_tick] doorpi.action.SingleActions.time_tick loaded 2016-05-30 21:55:11,478 [DEBUG] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was added 2016-05-30 21:55:11,480 [TRACE] [doorpi.action.handler] action TimeTickDestroyAction with args () and kwargs {} was added to new evententry OnShutdown 2016-05-30 21:55:11,481 [TRACE] [doorpi.action.handler] action TimeTickAction with args ('!last_tick!',) and kwargs {} was added to new evententry OnTimeTick 2016-05-30 21:55:11,481 [TRACE] [doorpi.conf.config_object] get_string for key ip in section DoorPiWeb (default: ) returns 2016-05-30 21:55:11,482 [TRACE] [doorpi.conf.config_object] get_integer for key port in section DoorPiWeb (default: 80) returns 80 2016-05-30 21:55:11,485 [INFO] [doorpi.status.webserver] Initiating WebService at ip and port 80 2016-05-30 21:55:11,485 [TRACE] [doorpi.action.handler] register Event OnWebServerStart from doorpi.status.webserver 2016-05-30 21:55:11,486 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver was added 2016-05-30 21:55:11,486 [TRACE] [doorpi.action.handler] added event_name OnWebServerStart and registered source doorpi.status.webserver 2016-05-30 21:55:11,487 [TRACE] [doorpi.action.handler] register Event OnWebServerStop from doorpi.status.webserver 2016-05-30 21:55:11,487 [TRACE] [doorpi.action.handler] added event_name OnWebServerStop and registered source doorpi.status.webserver 2016-05-30 21:55:11,487 [TRACE] [doorpi.conf.config_object] get_string for key www in section DoorPiWeb (default: !BASEPATH!/../DoorPiWeb) returns !BASEPATH!/../DoorPiWeb 2016-05-30 21:55:11,489 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/../DoorPiWeb" to "/usr/local/etc/DoorPi/../DoorPiWeb" 2016-05-30 21:55:11,489 [TRACE] [doorpi.conf.config_object] get_string for key indexfile in section DoorPiWeb (default: index.html) returns index.html 2016-05-30 21:55:11,490 [DEBUG] [doorpi.conf.config_object] parse string "index.html" to "index.html" 2016-05-30 21:55:11,491 [TRACE] [doorpi.conf.config_object] get_string for key loginfile in section DoorPiWeb (default: login.html) returns login.html 2016-05-30 21:55:11,492 [DEBUG] [doorpi.conf.config_object] parse string "login.html" to "login.html" 2016-05-30 21:55:11,492 [TRACE] [doorpi.conf.config_object] get_string for key public in section DoorPiWeb (default: AREA_public) returns AREA_public 2016-05-30 21:55:11,493 [DEBUG] [doorpi.conf.config_object] parse string "AREA_public" to "AREA_public" 2016-05-30 21:55:11,494 [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-05-30 21:55:11,495 [DEBUG] [doorpi.conf.config_object] parse string "http://motom001.github.io/DoorPiWeb" to "http://motom001.github.io/DoorPiWeb" 2016-05-30 21:55:11,495 [TRACE] [doorpi.conf.config_object] get_keys for section WritePermission returns ['administrator'] 2016-05-30 21:55:11,496 [WARNING] [root] section ReadPermission not found in configfile 2016-05-30 21:55:11,496 [TRACE] [doorpi.conf.config_object] get_keys for section ReadPermission returns [] 2016-05-30 21:55:11,497 [TRACE] [doorpi.conf.config_object] get_keys for section Group returns ['administrator'] 2016-05-30 21:55:11,497 [TRACE] [doorpi.conf.config_object] get_keys for section User returns ['door'] 2016-05-30 21:55:11,497 [TRACE] [doorpi.conf.config_object] get_list for key administrator in section Group (default: []) returns ['door'] 2016-05-30 21:55:11,498 [TRACE] [doorpi.conf.config_object] get_sections returns ['onboardpins_OutputPins', 'onboardpins_keyboard', 'Group', 'keyboards', '[NONE', 'AdminNumbers', 'DTMF', 'SIP-Phone', 'WritePermission', 'AREA_installer', 'EVENT_OnKeyPressed_onboardpins.1', 'EVENT_OnKeyPressed_onboardpins.0', 'EVENT_OnStartup', 'User', 'DoorPiWeb', 'onboardpins_InputPins', 'DoorPi'] 2016-05-30 21:55:11,499 [TRACE] [doorpi.conf.config_object] get_list for key administrator in section WritePermission (default: []) returns ['installer'] 2016-05-30 21:55:11,499 [ERROR] [doorpi.status.webserver] no ReadPermission found 2016-05-30 21:55:11,499 [TRACE] [doorpi.action.handler] action WebServerStartupAction with args () and kwargs {} was added to new evententry OnWebServerStart 2016-05-30 21:55:11,500 [TRACE] [doorpi.action.handler] action WebServerShutdownAction with args () and kwargs {} was added to event OnShutdown 2016-05-30 21:55:11,500 [TRACE] [doorpi.action.handler] fire Event OnWebServerStart from doorpi.status.webserver asyncron 2016-05-30 21:55:11,502 [TRACE] [doorpi.action.handler] register Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2016-05-30 21:55:11,503 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was added 2016-05-30 21:55:11,504 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequest and registered source doorpi.status.webserver_lib.request_handler 2016-05-30 21:55:11,504 [DEBUG] [doorpi.action.handler] [65II1U] fire for event OnWebServerStart this actions [] 2016-05-30 21:55:11,504 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2016-05-30 21:55:11,505 [TRACE] [doorpi.action.handler] [65II1U] try to fire action WebServerStartupAction with args () and kwargs {} 2016-05-30 21:55:11,506 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestGet and registered source doorpi.status.webserver_lib.request_handler 2016-05-30 21:55:11,506 [TRACE] [doorpi.action.base] run WebServerStartupAction with args () and kwargs {} 2016-05-30 21:55:11,507 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2016-05-30 21:55:11,508 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestPost and registered source doorpi.status.webserver_lib.request_handler 2016-05-30 21:55:11,508 [TRACE] [doorpi.action.handler] register Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2016-05-30 21:55:11,508 [TRACE] [doorpi.action.handler] added event_name OnWebServerVirtualResource and registered source doorpi.status.webserver_lib.request_handler 2016-05-30 21:55:11,509 [TRACE] [doorpi.action.handler] register Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler 2016-05-30 21:55:11,509 [TRACE] [doorpi.action.handler] added event_name OnWebServerRealResource and registered source doorpi.status.webserver_lib.request_handler 2016-05-30 21:55:11,509 [TRACE] [doorpi.action.handler] register Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2016-05-30 21:55:11,510 [TRACE] [doorpi.action.handler] added event_name OnFireEvent and registered source doorpi.status.webserver_lib.request_handler 2016-05-30 21:55:11,510 [TRACE] [doorpi.action.handler] register Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2016-05-30 21:55:11,510 [TRACE] [doorpi.action.handler] added event_name OnConfigKeySet and registered source doorpi.status.webserver_lib.request_handler 2016-05-30 21:55:11,511 [TRACE] [doorpi.action.handler] register Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2016-05-30 21:55:11,511 [TRACE] [doorpi.action.handler] added event_name OnConfigKeyDelete and registered source doorpi.status.webserver_lib.request_handler 2016-05-30 21:55:11,512 [TRACE] [doorpi.conf.config_object] get_keys for section keyboards returns ['onboardpins'] 2016-05-30 21:55:11,512 [INFO] [doorpi.keyboard.KeyboardInterface] using multi-keyboard mode (keyboards: onboardpins) 2016-05-30 21:55:11,512 [INFO] [doorpi.keyboard.KeyboardInterface] trying to add keyboard 'onboardpins' to handler 2016-05-30 21:55:11,513 [TRACE] [doorpi.conf.config_object] get_string for key onboardpins in section keyboards (default: dummy) returns piface 2016-05-30 21:55:11,513 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_InputPins returns ['1', '0'] 2016-05-30 21:55:11,514 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns ['1', '0', '3'] 2016-05-30 21:55:11,514 [TRACE] [doorpi.conf.config_object] get_integer for key bouncetime in section onboardpins_keyboard (default: 2000) returns 2000.0 2016-05-30 21:55:11,515 [TRACE] [doorpi.conf.config_object] get_integer for key polarity in section onboardpins_keyboard (default: 0) returns 0 2016-05-30 21:55:11,515 [TRACE] [doorpi.conf.config_object] get_boolean for key pressed_on_keydown in section onboardpins_keyboard (default: True) returns True 2016-05-30 21:55:11,516 [DEBUG] [doorpi.keyboard.from_piface] doorpi.keyboard.from_piface loaded 2016-05-30 21:55:11,562 [DEBUG] [doorpi.keyboard.from_piface] __init__(input_pins = ['1', '0'], output_pins = ['1', '0', '3'], polarity = 0) 2016-05-30 21:55:11,638 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2016-05-30 21:55:11,639 [DEBUG] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was added 2016-05-30 21:55:11,639 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed and registered source doorpi.keyboard.from_piface 2016-05-30 21:55:11,640 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_1 from doorpi.keyboard.from_piface 2016-05-30 21:55:11,640 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_1 and registered source doorpi.keyboard.from_piface 2016-05-30 21:55:11,640 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.1 from doorpi.keyboard.from_piface 2016-05-30 21:55:11,641 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.1 and registered source doorpi.keyboard.from_piface 2016-05-30 21:55:11,641 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2016-05-30 21:55:11,641 [TRACE] [doorpi.action.handler] added event_name OnKeyUp and registered source doorpi.keyboard.from_piface 2016-05-30 21:55:11,642 [TRACE] [doorpi.action.handler] register Event OnKeyUp_1 from doorpi.keyboard.from_piface 2016-05-30 21:55:11,642 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_1 and registered source doorpi.keyboard.from_piface 2016-05-30 21:55:11,643 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.1 from doorpi.keyboard.from_piface 2016-05-30 21:55:11,643 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.1 and registered source doorpi.keyboard.from_piface 2016-05-30 21:55:11,643 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2016-05-30 21:55:11,644 [TRACE] [doorpi.action.handler] added event_name OnKeyDown and registered source doorpi.keyboard.from_piface 2016-05-30 21:55:11,644 [TRACE] [doorpi.action.handler] register Event OnKeyDown_1 from doorpi.keyboard.from_piface 2016-05-30 21:55:11,645 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_1 and registered source doorpi.keyboard.from_piface 2016-05-30 21:55:11,645 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.1 from doorpi.keyboard.from_piface 2016-05-30 21:55:11,645 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.1 and registered source doorpi.keyboard.from_piface 2016-05-30 21:55:11,646 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2016-05-30 21:55:11,646 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2016-05-30 21:55:11,647 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_0 from doorpi.keyboard.from_piface 2016-05-30 21:55:11,647 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_0 and registered source doorpi.keyboard.from_piface 2016-05-30 21:55:11,647 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.0 from doorpi.keyboard.from_piface 2016-05-30 21:55:11,648 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.0 and registered source doorpi.keyboard.from_piface 2016-05-30 21:55:11,648 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2016-05-30 21:55:11,648 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2016-05-30 21:55:11,649 [TRACE] [doorpi.action.handler] register Event OnKeyUp_0 from doorpi.keyboard.from_piface 2016-05-30 21:55:11,649 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_0 and registered source doorpi.keyboard.from_piface 2016-05-30 21:55:11,649 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.0 from doorpi.keyboard.from_piface 2016-05-30 21:55:11,650 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.0 and registered source doorpi.keyboard.from_piface 2016-05-30 21:55:11,650 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2016-05-30 21:55:11,651 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2016-05-30 21:55:11,651 [TRACE] [doorpi.action.handler] register Event OnKeyDown_0 from doorpi.keyboard.from_piface 2016-05-30 21:55:11,651 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_0 and registered source doorpi.keyboard.from_piface 2016-05-30 21:55:11,652 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.0 from doorpi.keyboard.from_piface 2016-05-30 21:55:11,652 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.0 and registered source doorpi.keyboard.from_piface 2016-05-30 21:55:11,675 [TRACE] [doorpi.action.handler] action KeyboardDestroyAction with args () and kwargs {} was added to event OnShutdown 2016-05-30 21:55:11,676 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns ['1', '0', '3'] 2016-05-30 21:55:11,677 [TRACE] [doorpi.conf.config_object] get_string for key 1 in section onboardpins_OutputPins (default: ) returns tuer1 2016-05-30 21:55:11,677 [TRACE] [doorpi.conf.config_object] get_string for key 0 in section onboardpins_OutputPins (default: ) returns tuer 2016-05-30 21:55:11,678 [TRACE] [doorpi.conf.config_object] get_string for key 3 in section onboardpins_OutputPins (default: ) returns led 2016-05-30 21:55:11,680 [DEBUG] [doorpi.status.status_lib.environment] doorpi.status.status_lib.environment loaded 2016-05-30 21:55:11,683 [DEBUG] [doorpi.status.requirements_lib.req_config] doorpi.status.requirements_lib.req_config loaded 2016-05-30 21:55:11,686 [DEBUG] [doorpi.status.requirements_lib.req_sipphone] doorpi.status.requirements_lib.req_sipphone loaded 2016-05-30 21:55:11,688 [DEBUG] [doorpi.sipphone.AbstractBaseClass] doorpi.sipphone.AbstractBaseClass loaded 2016-05-30 21:55:11,729 [DEBUG] [doorpi.status.requirements_lib.req_event_handler] doorpi.status.requirements_lib.req_event_handler loaded 2016-05-30 21:55:11,732 [DEBUG] [doorpi.status.requirements_lib.req_webserver] doorpi.status.requirements_lib.req_webserver loaded 2016-05-30 21:55:11,735 [DEBUG] [doorpi.status.requirements_lib.req_keyboard] doorpi.status.requirements_lib.req_keyboard loaded 2016-05-30 21:55:11,753 [DEBUG] [doorpi.status.requirements_lib.req_system] doorpi.status.requirements_lib.req_system loaded 2016-05-30 21:55:11,760 [INFO] [doorpi.sipphone.SipphoneInterface] found installed sipphone "linphone" and use this as default 2016-05-30 21:55:11,761 [TRACE] [doorpi.conf.config_object] get_string for key sipphonetyp in section SIP-Phone (default: linphone) returns linphone 2016-05-30 21:55:11,763 [DEBUG] [doorpi.sipphone.from_linphone] doorpi.sipphone.from_linphone loaded 2016-05-30 21:55:11,765 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] doorpi.sipphone.linphone_lib.CallBacks loaded 2016-05-30 21:55:11,767 [DEBUG] [doorpi.sipphone.linphone_lib.Player] doorpi.sipphone.linphone_lib.Player loaded 2016-05-30 21:55:11,771 [DEBUG] [doorpi.sipphone.linphone_lib.Recorder] doorpi.sipphone.linphone_lib.Recorder loaded 2016-05-30 21:55:11,772 [DEBUG] [doorpi.sipphone.from_linphone] __init__ 2016-05-30 21:55:11,773 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnShutdown 2016-05-30 21:55:11,773 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2016-05-30 21:55:11,774 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was added 2016-05-30 21:55:11,774 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCreate and registered source doorpi.sipphone.from_linphone 2016-05-30 21:55:11,775 [TRACE] [doorpi.action.handler] register Event OnSipPhoneStart from doorpi.sipphone.from_linphone 2016-05-30 21:55:11,775 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneStart and registered source doorpi.sipphone.from_linphone 2016-05-30 21:55:11,776 [TRACE] [doorpi.action.handler] register Event OnSipPhoneDestroy from doorpi.sipphone.from_linphone 2016-05-30 21:55:11,776 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneDestroy and registered source doorpi.sipphone.from_linphone 2016-05-30 21:55:11,776 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderCreate from doorpi.sipphone.from_linphone 2016-05-30 21:55:11,777 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderCreate and registered source doorpi.sipphone.from_linphone 2016-05-30 21:55:11,777 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderDestroy from doorpi.sipphone.from_linphone 2016-05-30 21:55:11,777 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderDestroy and registered source doorpi.sipphone.from_linphone 2016-05-30 21:55:11,778 [TRACE] [doorpi.action.handler] register Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-05-30 21:55:11,778 [TRACE] [doorpi.action.handler] added event_name BeforeSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2016-05-30 21:55:11,778 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-05-30 21:55:11,779 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2016-05-30 21:55:11,779 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2016-05-30 21:55:11,780 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCallFailed and registered source doorpi.sipphone.from_linphone 2016-05-30 21:55:11,780 [TRACE] [doorpi.action.handler] register Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-05-30 21:55:11,780 [TRACE] [doorpi.action.handler] added event_name AfterSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2016-05-30 21:55:11,781 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutNoResponse from doorpi.sipphone.from_linphone 2016-05-30 21:55:11,781 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutNoResponse and registered source doorpi.sipphone.from_linphone 2016-05-30 21:55:11,781 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutMaxCalltime from doorpi.sipphone.from_linphone 2016-05-30 21:55:11,782 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutMaxCalltime and registered source doorpi.sipphone.from_linphone 2016-05-30 21:55:11,782 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.from_linphone 2016-05-30 21:55:11,782 [TRACE] [doorpi.action.handler] added event_name OnPlayerCreated and registered source doorpi.sipphone.from_linphone 2016-05-30 21:55:11,783 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] __init__ 2016-05-30 21:55:11,784 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneDestroy 2016-05-30 21:55:11,784 [TRACE] [doorpi.action.handler] register Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,785 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was added 2016-05-30 21:55:11,785 [TRACE] [doorpi.action.handler] added event_name OnCallMediaStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,785 [TRACE] [doorpi.action.handler] register Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,786 [TRACE] [doorpi.action.handler] added event_name OnMediaRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,786 [TRACE] [doorpi.action.handler] register Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,787 [TRACE] [doorpi.action.handler] added event_name OnMediaNotRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,787 [TRACE] [doorpi.action.handler] register Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,787 [TRACE] [doorpi.action.handler] added event_name OnCallStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,788 [TRACE] [doorpi.action.handler] register Event OnCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,788 [TRACE] [doorpi.action.handler] added event_name OnCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,788 [TRACE] [doorpi.action.handler] register Event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,789 [TRACE] [doorpi.action.handler] added event_name AfterCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,789 [TRACE] [doorpi.action.handler] register Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,790 [TRACE] [doorpi.action.handler] added event_name OnCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,790 [TRACE] [doorpi.action.handler] register Event AfterCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,790 [TRACE] [doorpi.action.handler] added event_name AfterCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,791 [TRACE] [doorpi.action.handler] register Event OnCallStateDismissed from doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,791 [TRACE] [doorpi.action.handler] added event_name OnCallStateDismissed and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,791 [TRACE] [doorpi.action.handler] register Event OnCallStateReject from doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,792 [TRACE] [doorpi.action.handler] added event_name OnCallStateReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,792 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,792 [TRACE] [doorpi.action.handler] added event_name OnCallStart and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,793 [TRACE] [doorpi.action.handler] register Event OnDTMF from doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,793 [TRACE] [doorpi.action.handler] added event_name OnDTMF and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,794 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns ['123'] 2016-05-30 21:55:11,794 [TRACE] [doorpi.action.handler] register Event OnDTMF_123 from doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,794 [TRACE] [doorpi.action.handler] added event_name OnDTMF_123 and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,795 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,795 [TRACE] [doorpi.action.handler] nothing to do - event OnCallStart from source doorpi.sipphone.linphone_lib.CallBacks is already known 2016-05-30 21:55:11,795 [TRACE] [doorpi.action.handler] register Event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,796 [TRACE] [doorpi.action.handler] added event_name BeforeCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,796 [TRACE] [doorpi.action.handler] register Event OnCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,797 [TRACE] [doorpi.action.handler] added event_name OnCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,797 [TRACE] [doorpi.action.handler] register Event AfterCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,797 [TRACE] [doorpi.action.handler] added event_name AfterCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,798 [TRACE] [doorpi.action.handler] register Event OnCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,798 [TRACE] [doorpi.action.handler] added event_name OnCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,798 [TRACE] [doorpi.action.handler] register Event AfterCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,799 [TRACE] [doorpi.action.handler] added event_name AfterCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,799 [TRACE] [doorpi.action.handler] register Event OnCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,799 [TRACE] [doorpi.action.handler] added event_name OnCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,800 [TRACE] [doorpi.action.handler] register Event AfterCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,800 [TRACE] [doorpi.action.handler] added event_name AfterCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,800 [TRACE] [doorpi.action.handler] register Event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,801 [TRACE] [doorpi.action.handler] added event_name OnCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,801 [TRACE] [doorpi.action.handler] register Event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,802 [TRACE] [doorpi.action.handler] added event_name AfterCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,802 [TRACE] [doorpi.action.handler] fire Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks asyncron 2016-05-30 21:55:11,804 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_new() 2016-05-30 21:55:11,805 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_new -> 0x75a79cf8 2016-05-30 21:55:11,806 [DEBUG] [doorpi.action.handler] no actions for event OnCallStart - skip fire_event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2016-05-30 21:55:11,806 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_init() 2016-05-30 21:55:11,807 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_init -> 0x75a79cf8 2016-05-30 21:55:11,808 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_class_method_new(0x74560f60, "(null)", "(null)") 2016-05-30 21:55:11,808 [INFO] [doorpi.sipphone.from_linphone] Mediastreamer2 factory 2.12.0 (git: 3.9.0) initialized. 2016-05-30 21:55:11,809 [INFO] [doorpi.sipphone.from_linphone] CPU count set to 4 2016-05-30 21:55:11,809 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init() done: platform_tags=linux,arm,embedded 2016-05-30 21:55:11,810 [INFO] [doorpi.sipphone.from_linphone] srtp init 2016-05-30 21:55:11,841 [INFO] [doorpi.sipphone.from_linphone] Registering all soundcard handlers 2016-05-30 21:55:11,951 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: default device' added 2016-05-30 21:55:11,977 [INFO] [doorpi.sipphone.from_linphone] also error in pcm_hw.c:1557 - open '/dev/snd/pcmC1D0p' failed (-2) 2016-05-30 21:55:11,978 [INFO] [doorpi.sipphone.from_linphone] also error in pcm_dmix.c:1022 - unable to open slave 2016-05-30 21:55:11,979 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: USB Camera' added 2016-05-30 21:55:11,989 [INFO] [doorpi.sipphone.from_linphone] also error in pcm_hw.c:1557 - open '/dev/snd/pcmC1D0p' failed (-2) 2016-05-30 21:55:11,990 [INFO] [doorpi.sipphone.from_linphone] also error in pcm_dmix.c:1022 - unable to open slave 2016-05-30 21:55:11,991 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: USB Camera' added 2016-05-30 21:55:11,992 [INFO] [doorpi.sipphone.from_linphone] also error in pcm_hw.c:1557 - open '/dev/snd/pcmC2D0c' failed (-2) 2016-05-30 21:55:11,995 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: bcm2835 ALSA' added 2016-05-30 21:55:11,996 [INFO] [doorpi.sipphone.from_linphone] Registering all webcam handlers 2016-05-30 21:55:11,997 [INFO] [doorpi.sipphone.from_linphone] Webcam V4L2: /dev/video0 added 2016-05-30 21:55:11,997 [INFO] [doorpi.sipphone.from_linphone] Webcam StaticImage: Static picture added 2016-05-30 21:55:11,998 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init_voip() done 2016-05-30 21:55:11,998 [INFO] [doorpi.sipphone.from_linphone] Loading ms plugins from [./lib/mediastreamer/plugins] 2016-05-30 21:55:11,999 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/mediastreamer/plugins: No such file or directory 2016-05-30 21:55:11,999 [INFO] [doorpi.sipphone.from_linphone] Initializing LinphoneCore 3.9.0 2016-05-30 21:55:12,000 [INFO] [doorpi.sipphone.from_linphone] Vtable [0x18aec10] registered on core [0x18ae348] 2016-05-30 21:55:12,000 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x18aec10, 1, "Starting up") 2016-05-30 21:55:12,001 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2016-05-30 21:55:12,001 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x18aec10] notifying [global_state_changed] 2016-05-30 21:55:12,001 [INFO] [doorpi.sipphone.from_linphone] oRTP-0.25.0 initialized. 2016-05-30 21:55:12,002 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_base_init, because [2] ref 2016-05-30 21:55:12,002 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_voip_init, because [2] ref 2016-05-30 21:55:12,003 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_plugins_init, because [2] ref 2016-05-30 21:55:12,003 [INFO] [doorpi.sipphone.from_linphone] Codec opus/48000 fmtp=[useinbandfec=1] number=-1, enabled=1) added to default capabilities. 2016-05-30 21:55:12,004 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-05-30 21:55:12,004 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-05-30 21:55:12,005 [INFO] [doorpi.sipphone.from_linphone] Codec speex/16000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2016-05-30 21:55:12,005 [INFO] [doorpi.sipphone.from_linphone] Codec speex/8000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2016-05-30 21:55:12,006 [INFO] [doorpi.sipphone.from_linphone] Codec PCMU/8000 fmtp=[] number=0, enabled=1) added to default capabilities. 2016-05-30 21:55:12,006 [INFO] [doorpi.sipphone.from_linphone] Codec PCMA/8000 fmtp=[] number=8, enabled=1) added to default capabilities. 2016-05-30 21:55:12,007 [INFO] [doorpi.sipphone.from_linphone] Codec t140/1000 fmtp=[] number=96, enabled=1) added to default capabilities. 2016-05-30 21:55:12,007 [INFO] [doorpi.sipphone.from_linphone] Codec red/1000 fmtp=[] number=97, enabled=1) added to default capabilities. 2016-05-30 21:55:12,007 [INFO] [doorpi.sipphone.from_linphone] Codec GSM/8000 fmtp=[] number=3, enabled=0) added to default capabilities. 2016-05-30 21:55:12,008 [INFO] [doorpi.sipphone.from_linphone] Codec G722/8000 fmtp=[] number=9, enabled=0) added to default capabilities. 2016-05-30 21:55:12,008 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iLBC 2016-05-30 21:55:12,009 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iLBC 2016-05-30 21:55:12,009 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR 2016-05-30 21:55:12,009 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR 2016-05-30 21:55:12,010 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR-WB 2016-05-30 21:55:12,010 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR-WB 2016-05-30 21:55:12,011 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2016-05-30 21:55:12,011 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2016-05-30 21:55:12,011 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-05-30 21:55:12,012 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-05-30 21:55:12,012 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-05-30 21:55:12,013 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-05-30 21:55:12,013 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-05-30 21:55:12,013 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-05-30 21:55:12,014 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-05-30 21:55:12,014 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-05-30 21:55:12,015 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-05-30 21:55:12,015 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-05-30 21:55:12,016 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iSAC 2016-05-30 21:55:12,016 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iSAC 2016-05-30 21:55:12,016 [INFO] [doorpi.sipphone.from_linphone] Codec speex/32000 fmtp=[vbr=on] number=-1, enabled=0) added to default capabilities. 2016-05-30 21:55:12,017 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-05-30 21:55:12,017 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-05-30 21:55:12,018 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-05-30 21:55:12,018 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-05-30 21:55:12,018 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-05-30 21:55:12,019 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-05-30 21:55:12,019 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-16 2016-05-30 21:55:12,019 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-16 2016-05-30 21:55:12,020 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-24 2016-05-30 21:55:12,020 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-24 2016-05-30 21:55:12,021 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-32 2016-05-30 21:55:12,021 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-32 2016-05-30 21:55:12,021 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-40 2016-05-30 21:55:12,022 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-40 2016-05-30 21:55:12,022 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-16 2016-05-30 21:55:12,023 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-16 2016-05-30 21:55:12,023 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-24 2016-05-30 21:55:12,024 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-24 2016-05-30 21:55:12,024 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-32 2016-05-30 21:55:12,024 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-32 2016-05-30 21:55:12,025 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-40 2016-05-30 21:55:12,025 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-40 2016-05-30 21:55:12,026 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CODEC2 2016-05-30 21:55:12,026 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CODEC2 2016-05-30 21:55:12,027 [INFO] [doorpi.sipphone.from_linphone] Codec VP8/90000 fmtp=[] number=-1, enabled=1) added to default capabilities. 2016-05-30 21:55:12,027 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H264 2016-05-30 21:55:12,027 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H264 2016-05-30 21:55:12,028 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MP4V-ES 2016-05-30 21:55:12,028 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263-1998 2016-05-30 21:55:12,029 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2016-05-30 21:55:12,029 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for 1016 2016-05-30 21:55:12,029 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for 1016 2016-05-30 21:55:12,030 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G723 2016-05-30 21:55:12,030 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G723 2016-05-30 21:55:12,031 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for LPC 2016-05-30 21:55:12,031 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for LPC 2016-05-30 21:55:12,031 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=10, enabled=0) added to default capabilities. 2016-05-30 21:55:12,032 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=11, enabled=0) added to default capabilities. 2016-05-30 21:55:12,032 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CN 2016-05-30 21:55:12,033 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CN 2016-05-30 21:55:12,033 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2016-05-30 21:55:12,033 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2016-05-30 21:55:12,034 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H261 2016-05-30 21:55:12,034 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H261 2016-05-30 21:55:12,035 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MPV 2016-05-30 21:55:12,035 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for MPV 2016-05-30 21:55:12,036 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2016-05-30 21:55:12,036 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-05-30 21:55:12,037 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-05-30 21:55:12,037 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-05-30 21:55:12,037 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-05-30 21:55:12,038 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_configuring_status(0x18aec10, 2, "(null)") 2016-05-30 21:55:12,038 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_configuring_status 2016-05-30 21:55:12,039 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x18aec10] notifying [configuring_status] 2016-05-30 21:55:12,039 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/liblinphone/plugins: No such file or directory 2016-05-30 21:55:12,040 [WARNING] [doorpi.sipphone.from_linphone] ./share/sounds/linphone/rings/oldphone.wav does not exist 2016-05-30 21:55:12,040 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_playback_gain_db(): no active call. 2016-05-30 21:55:12,041 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_mic_gain_db(): no active call. 2016-05-30 21:55:12,041 [INFO] [doorpi.sipphone.from_linphone] MTU is supposed to be 1300, rtp payload max size will be 1240 2016-05-30 21:55:12,041 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-05-30 21:55:12,042 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-05-30 21:55:12,042 [INFO] [doorpi.sipphone.from_linphone] Supported codec opus/48000 fmtp=useinbandfec=1 automatically added to codec list. 2016-05-30 21:55:12,043 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/16000 fmtp=vbr=on automatically added to codec list. 2016-05-30 21:55:12,043 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/8000 fmtp=vbr=on automatically added to codec list. 2016-05-30 21:55:12,044 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMU/8000 fmtp= automatically added to codec list. 2016-05-30 21:55:12,044 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMA/8000 fmtp= automatically added to codec list. 2016-05-30 21:55:12,044 [INFO] [doorpi.sipphone.from_linphone] Supported codec GSM/8000 fmtp= automatically added to codec list. 2016-05-30 21:55:12,045 [INFO] [doorpi.sipphone.from_linphone] Supported codec G722/8000 fmtp= automatically added to codec list. 2016-05-30 21:55:12,045 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/32000 fmtp=vbr=on automatically added to codec list. 2016-05-30 21:55:12,046 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2016-05-30 21:55:12,046 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2016-05-30 21:55:12,046 [INFO] [doorpi.sipphone.from_linphone] Supported codec VP8/90000 fmtp= automatically added to codec list. 2016-05-30 21:55:12,047 [INFO] [doorpi.sipphone.from_linphone] Supported codec t140/1000 fmtp= automatically added to codec list. 2016-05-30 21:55:12,047 [INFO] [doorpi.sipphone.from_linphone] Supported codec red/1000 fmtp= automatically added to codec list. 2016-05-30 21:55:12,048 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-05-30 21:55:12,048 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-05-30 21:55:12,049 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2016-05-30 21:55:12,049 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x18b0480] on [sip:0.0.0.0:5060;transport=UDP] 2016-05-30 21:55:12,050 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x18b0690] on [sip:0.0.0.0:5060;transport=TCP] 2016-05-30 21:55:12,050 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x18b0868] on [sip:0.0.0.0:-1;transport=TLS] 2016-05-30 21:55:12,051 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 33605 2016-05-30 21:55:12,051 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-05-30 21:55:12,052 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-05-30 21:55:12,055 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x18aec10, 2, "Ready") 2016-05-30 21:55:12,056 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2016-05-30 21:55:12,057 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x18aec10] notifying [global_state_changed] 2016-05-30 21:55:12,057 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_class_method_new -> 0x75a79cf8 2016-05-30 21:55:12,057 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2016-05-30 21:55:12,058 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_primary_contact(0x75a79cf8 [0x18aec10], "DoorPi ") 2016-05-30 21:55:12,059 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_primary_contact -> 0 2016-05-30 21:55:12,060 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneCreate from doorpi.sipphone.from_linphone asyncron 2016-05-30 21:55:12,061 [TRACE] [doorpi.conf.config_object] get_integer for key ua.max_calls in section SIP-Phone (default: 2) returns 2 2016-05-30 21:55:12,062 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_max_calls(0x75a79cf8 [0x18aec10], 2) 2016-05-30 21:55:12,063 [DEBUG] [doorpi.action.handler] no actions for event OnSipPhoneCreate - skip fire_event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2016-05-30 21:55:12,063 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_max_calls -> 0 2016-05-30 21:55:12,064 [TRACE] [doorpi.conf.config_object] get_boolean for key echo_cancellation_enabled in section SIP-Phone (default: False) returns True 2016-05-30 21:55:12,065 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_echo_cancellation(0x75a79cf8 [0x18aec10], 0x2e0620 [1]) 2016-05-30 21:55:12,065 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_echo_cancellation -> 0 2016-05-30 21:55:12,066 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2016-05-30 21:55:12,066 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2016-05-30 21:55:12,067 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_new() 2016-05-30 21:55:12,067 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_new -> 0x75a79d10 2016-05-30 21:55:12,067 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_sip_transports(0x75a79cf8 [0x18aec10], 0x75a79d10 [0x75a79d18]) 2016-05-30 21:55:12,068 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x18b0480] on [sip:0.0.0.0:5060;transport=UDP] destroyed 2016-05-30 21:55:12,069 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x18b0690] on [sip:0.0.0.0:5060;transport=TCP] destroyed 2016-05-30 21:55:12,069 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x18b0868] on [sip:0.0.0.0:33605;transport=TLS] destroyed 2016-05-30 21:55:12,069 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2016-05-30 21:55:12,070 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x18c4740] on [sip:0.0.0.0:5060;transport=UDP] 2016-05-30 21:55:12,071 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x18b03e0] on [sip:0.0.0.0:5060;transport=TCP] 2016-05-30 21:55:12,071 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x18c3880] on [sip:0.0.0.0:-1;transport=TLS] 2016-05-30 21:55:12,072 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 38552 2016-05-30 21:55:12,072 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-05-30 21:55:12,073 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-05-30 21:55:12,073 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_sip_transports -> 0 2016-05-30 21:55:12,073 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_dealloc(0x75a79d10) 2016-05-30 21:55:12,074 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_dealloc 2016-05-30 21:55:12,074 [TRACE] [doorpi.conf.config_object] get_boolean for key video_display_enabled in section SIP-Phone (default: False) returns True 2016-05-30 21:55:12,075 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_display(0x75a79cf8 [0x18aec10], 0x2e0620 [1]) 2016-05-30 21:55:12,075 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_display -> 0 2016-05-30 21:55:12,076 [TRACE] [doorpi.conf.config_object] get_string for key stun_server in section SIP-Phone (default: ) returns 2016-05-30 21:55:12,076 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_stun_server(0x75a79cf8 [0x18aec10], "") 2016-05-30 21:55:12,077 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2016-05-30 21:55:12,078 [INFO] [doorpi.sipphone.from_linphone] 192.168.178.1 2016-05-30 21:55:12,079 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2016-05-30 21:55:12,079 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2016-05-30 21:55:12,080 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_stun_server -> 0 2016-05-30 21:55:12,081 [DEBUG] [doorpi.conf.config_object] create new key FirewallPolicy in section SIP-Phone with value 'PolicyNoFirewall' 2016-05-30 21:55:12,081 [TRACE] [doorpi.conf.config_object] get_string for key FirewallPolicy in section SIP-Phone (default: PolicyNoFirewall) returns PolicyNoFirewall 2016-05-30 21:55:12,081 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_firewall_policy(0x75a79cf8 [0x18aec10], 0) 2016-05-30 21:55:12,082 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-05-30 21:55:12,082 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-05-30 21:55:12,084 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_firewall_policy -> 0 2016-05-30 21:55:12,084 [TRACE] [doorpi.conf.config_object] get_integer for key max_call_time in section SIP-Phone (default: 120) returns 120 2016-05-30 21:55:12,085 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_in_call_timeout(0x75a79cf8 [0x18aec10], 120) 2016-05-30 21:55:12,085 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_in_call_timeout -> 0 2016-05-30 21:55:12,086 [TRACE] [doorpi.conf.config_object] get_integer for key call_timeout in section SIP-Phone (default: 15) returns 15 2016-05-30 21:55:12,086 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_inc_timeout(0x75a79cf8 [0x18aec10], 15) 2016-05-30 21:55:12,087 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_inc_timeout -> 0 2016-05-30 21:55:12,087 [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-05-30 21:55:12,089 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/media/ShortDialTone.wav" to "/usr/local/etc/DoorPi/media/ShortDialTone.wav" 2016-05-30 21:55:12,090 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2016-05-30 21:55:12,091 [TRACE] [doorpi.conf.config_object] get_boolean for key dialtone_renew_every_start in section SIP-Phone (default: False) returns False 2016-05-30 21:55:12,092 [TRACE] [doorpi.action.handler] register Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2016-05-30 21:55:12,092 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was added 2016-05-30 21:55:12,093 [TRACE] [doorpi.action.handler] added event_name OnPlayerStarted and registered source doorpi.sipphone.linphone_lib.Player 2016-05-30 21:55:12,093 [TRACE] [doorpi.action.handler] register Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2016-05-30 21:55:12,094 [TRACE] [doorpi.action.handler] added event_name OnPlayerStopped and registered source doorpi.sipphone.linphone_lib.Player 2016-05-30 21:55:12,094 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2016-05-30 21:55:12,094 [TRACE] [doorpi.action.handler] added event_source doorpi.sipphone.linphone_lib.Player to existing event OnPlayerCreated 2016-05-30 21:55:12,095 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneMakeCall 2016-05-30 21:55:12,095 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateConnect 2016-05-30 21:55:12,096 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateDisconnect 2016-05-30 21:55:12,096 [TRACE] [doorpi.action.handler] fire Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player asyncron 2016-05-30 21:55:12,098 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_ringback(0x75a79cf8 [0x18aec10], "/usr/local/etc/DoorPi/media/ShortDialTone.wav") 2016-05-30 21:55:12,099 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_ringback -> 0 2016-05-30 21:55:12,099 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerCreated - skip fire_event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2016-05-30 21:55:12,099 [TRACE] [doorpi.conf.config_object] get_string for key records in section SIP-Phone (default: !BASEPATH!/records/%Y-%m-%d_%H-%M-%S.wav) returns !BASEPATH!/records/%Y-%m-%d_%H-%M-%S.wav 2016-05-30 21:55:12,101 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2016-05-30 21:55:12,101 [TRACE] [doorpi.action.handler] register Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder 2016-05-30 21:55:12,101 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was added 2016-05-30 21:55:12,102 [TRACE] [doorpi.action.handler] added event_name OnRecorderStarted and registered source doorpi.sipphone.linphone_lib.Recorder 2016-05-30 21:55:12,102 [TRACE] [doorpi.action.handler] register Event OnRecorderStopped from doorpi.sipphone.linphone_lib.Recorder 2016-05-30 21:55:12,103 [TRACE] [doorpi.action.handler] added event_name OnRecorderStopped and registered source doorpi.sipphone.linphone_lib.Recorder 2016-05-30 21:55:12,103 [TRACE] [doorpi.action.handler] register Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2016-05-30 21:55:12,104 [TRACE] [doorpi.action.handler] added event_name OnRecorderCreated and registered source doorpi.sipphone.linphone_lib.Recorder 2016-05-30 21:55:12,104 [TRACE] [doorpi.conf.config_object] get_boolean for key record_while_dialing in section SIP-Phone (default: False) returns False 2016-05-30 21:55:12,105 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateConnect 2016-05-30 21:55:12,105 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateDisconnect 2016-05-30 21:55:12,105 [TRACE] [doorpi.action.handler] fire Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder asyncron 2016-05-30 21:55:12,107 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x75a79cf8 [0x18aec10]) 2016-05-30 21:55:12,107 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x7456d3c8 2016-05-30 21:55:12,108 [DEBUG] [doorpi.action.handler] no actions for event OnRecorderCreated - skip fire_event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2016-05-30 21:55:12,108 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0x75a79cf8 [0x18aec10]) 2016-05-30 21:55:12,109 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0x74567c50 2016-05-30 21:55:12,110 [TRACE] [doorpi.conf.config_object] get_string for key capture_device in section SIP-Phone (default: ALSA: default device) returns ALSA: USB Camera 2016-05-30 21:55:12,110 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_capture_device(0x75a79cf8 [0x18aec10], "ALSA: USB Camera") 2016-05-30 21:55:12,111 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_capture_device -> 0 2016-05-30 21:55:12,111 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0x75a79cf8 [0x18aec10]) 2016-05-30 21:55:12,112 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0x74567c20 2016-05-30 21:55:12,112 [TRACE] [doorpi.conf.config_object] get_string for key playback_device in section SIP-Phone (default: ALSA: default device) returns ALSA: USB PnP Sound Device 2016-05-30 21:55:12,112 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_playback_device(0x75a79cf8 [0x18aec10], "ALSA: USB PnP Sound Device") 2016-05-30 21:55:12,113 [WARNING] [doorpi.sipphone.from_linphone] no card with id ALSA: USB PnP Sound Device 2016-05-30 21:55:12,113 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_playback_device -> 0 2016-05-30 21:55:12,114 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x75a79cf8 [0x18aec10]) 2016-05-30 21:55:12,114 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x7456d2b0 2016-05-30 21:55:12,115 [INFO] [doorpi.sipphone.from_linphone] found 4 possible sounddevices: 2016-05-30 21:55:12,116 [DEBUG] [doorpi.sipphone.from_linphone] |rec|play| name 2016-05-30 21:55:12,116 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-05-30 21:55:12,116 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x75a79cf8 [0x18aec10]) 2016-05-30 21:55:12,117 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x7456d2b0 2016-05-30 21:55:12,117 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x75a79cf8 [0x18aec10], "ALSA: default device") 2016-05-30 21:55:12,118 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0620 2016-05-30 21:55:12,118 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x75a79cf8 [0x18aec10], "ALSA: default device") 2016-05-30 21:55:12,119 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2016-05-30 21:55:12,119 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: default device 2016-05-30 21:55:12,119 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x75a79cf8 [0x18aec10], "ALSA: USB Camera") 2016-05-30 21:55:12,120 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0620 2016-05-30 21:55:12,120 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x75a79cf8 [0x18aec10], "ALSA: USB Camera") 2016-05-30 21:55:12,121 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0614 2016-05-30 21:55:12,121 [DEBUG] [doorpi.sipphone.from_linphone] | X | O | ALSA: USB Camera 2016-05-30 21:55:12,121 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x75a79cf8 [0x18aec10], "ALSA: USB Camera") 2016-05-30 21:55:12,122 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0620 2016-05-30 21:55:12,122 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x75a79cf8 [0x18aec10], "ALSA: USB Camera") 2016-05-30 21:55:12,122 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0614 2016-05-30 21:55:12,123 [DEBUG] [doorpi.sipphone.from_linphone] | X | O | ALSA: USB Camera 2016-05-30 21:55:12,123 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x75a79cf8 [0x18aec10], "ALSA: bcm2835 ALSA") 2016-05-30 21:55:12,124 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0614 2016-05-30 21:55:12,125 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x75a79cf8 [0x18aec10], "ALSA: bcm2835 ALSA") 2016-05-30 21:55:12,125 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2016-05-30 21:55:12,125 [DEBUG] [doorpi.sipphone.from_linphone] | O | X | ALSA: bcm2835 ALSA 2016-05-30 21:55:12,126 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-05-30 21:55:12,126 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0x75a79cf8 [0x18aec10]) 2016-05-30 21:55:12,127 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0x7456d5c0 2016-05-30 21:55:12,127 [DEBUG] [doorpi.sipphone.from_linphone] using capture_device: ALSA: USB Camera 2016-05-30 21:55:12,127 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0x75a79cf8 [0x18aec10]) 2016-05-30 21:55:12,128 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0x74567ce0 2016-05-30 21:55:12,128 [DEBUG] [doorpi.sipphone.from_linphone] using playback_device: ALSA: default device 2016-05-30 21:55:12,129 [TRACE] [doorpi.conf.config_object] get_list for key audio_codecs in section SIP-Phone (default: PCMA,PCMU) returns ['PCMA', 'PCMU'] 2016-05-30 21:55:12,129 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_audio_codecs(0x75a79cf8 [0x18aec10]) 2016-05-30 21:55:12,130 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x18af7e0) 2016-05-30 21:55:12,130 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-05-30 21:55:12,130 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76caf450 2016-05-30 21:55:12,131 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-05-30 21:55:12,131 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76caf450 2016-05-30 21:55:12,132 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76caf450 2016-05-30 21:55:12,132 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x18b63e8) 2016-05-30 21:55:12,133 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-05-30 21:55:12,133 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76caf440 2016-05-30 21:55:12,134 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-05-30 21:55:12,134 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76caf440 2016-05-30 21:55:12,134 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76caf440 2016-05-30 21:55:12,135 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x18b6450) 2016-05-30 21:55:12,135 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-05-30 21:55:12,136 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76caf460 2016-05-30 21:55:12,136 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-05-30 21:55:12,137 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76caf460 2016-05-30 21:55:12,137 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76caf460 2016-05-30 21:55:12,137 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x18b64b8) 2016-05-30 21:55:12,138 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-05-30 21:55:12,138 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76caf470 2016-05-30 21:55:12,139 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-05-30 21:55:12,139 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76caf470 2016-05-30 21:55:12,139 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76caf470 2016-05-30 21:55:12,140 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x18b6510) 2016-05-30 21:55:12,140 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-05-30 21:55:12,141 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76caf480 2016-05-30 21:55:12,141 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-05-30 21:55:12,141 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76caf480 2016-05-30 21:55:12,142 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76caf480 2016-05-30 21:55:12,142 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x18b6568) 2016-05-30 21:55:12,143 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-05-30 21:55:12,143 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76caf490 2016-05-30 21:55:12,143 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-05-30 21:55:12,144 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76caf490 2016-05-30 21:55:12,144 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76caf490 2016-05-30 21:55:12,145 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x18b65c0) 2016-05-30 21:55:12,145 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-05-30 21:55:12,145 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76caf4a0 2016-05-30 21:55:12,146 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-05-30 21:55:12,146 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76caf4a0 2016-05-30 21:55:12,147 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76caf4a0 2016-05-30 21:55:12,147 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x18b0190) 2016-05-30 21:55:12,147 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-05-30 21:55:12,148 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76caf4b0 2016-05-30 21:55:12,148 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-05-30 21:55:12,149 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76caf4b0 2016-05-30 21:55:12,149 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76caf4b0 2016-05-30 21:55:12,149 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x18b01f8) 2016-05-30 21:55:12,150 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-05-30 21:55:12,150 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76caf4c0 2016-05-30 21:55:12,151 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-05-30 21:55:12,151 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76caf4c0 2016-05-30 21:55:12,151 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76caf4c0 2016-05-30 21:55:12,152 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x18b0250) 2016-05-30 21:55:12,152 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-05-30 21:55:12,153 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76caf4d0 2016-05-30 21:55:12,153 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-05-30 21:55:12,153 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76caf4d0 2016-05-30 21:55:12,154 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76caf4d0 2016-05-30 21:55:12,154 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_audio_codecs -> 0x7456d440 2016-05-30 21:55:12,155 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76caf450 [0x18af7e0]) 2016-05-30 21:55:12,155 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745688a0 2016-05-30 21:55:12,155 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76caf450 [0x18af7e0]) 2016-05-30 21:55:12,156 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745688a0 2016-05-30 21:55:12,156 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec opus 2016-05-30 21:55:12,157 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75a79cf8 [0x18aec10], 0x76caf450 [0x18af7e0], 0x2e0614 [0]) 2016-05-30 21:55:12,157 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x17138bc 2016-05-30 21:55:12,158 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76caf440 [0x18b63e8]) 2016-05-30 21:55:12,158 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74568aa0 2016-05-30 21:55:12,159 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76caf440 [0x18b63e8]) 2016-05-30 21:55:12,159 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74568aa0 2016-05-30 21:55:12,159 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-05-30 21:55:12,160 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75a79cf8 [0x18aec10], 0x76caf440 [0x18b63e8], 0x2e0614 [0]) 2016-05-30 21:55:12,160 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x17138bc 2016-05-30 21:55:12,161 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76caf460 [0x18b6450]) 2016-05-30 21:55:12,161 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745688a0 2016-05-30 21:55:12,161 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76caf460 [0x18b6450]) 2016-05-30 21:55:12,162 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745688a0 2016-05-30 21:55:12,162 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-05-30 21:55:12,163 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75a79cf8 [0x18aec10], 0x76caf460 [0x18b6450], 0x2e0614 [0]) 2016-05-30 21:55:12,163 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x17138bc 2016-05-30 21:55:12,164 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76caf470 [0x18b64b8]) 2016-05-30 21:55:12,164 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74568aa0 2016-05-30 21:55:12,165 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76caf470 [0x18b64b8]) 2016-05-30 21:55:12,165 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74568aa0 2016-05-30 21:55:12,165 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMU 2016-05-30 21:55:12,166 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75a79cf8 [0x18aec10], 0x76caf470 [0x18b64b8], 0x2e0620 [1]) 2016-05-30 21:55:12,167 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x17138bc 2016-05-30 21:55:12,167 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76caf480 [0x18b6510]) 2016-05-30 21:55:12,167 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745688a0 2016-05-30 21:55:12,168 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76caf480 [0x18b6510]) 2016-05-30 21:55:12,168 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745688a0 2016-05-30 21:55:12,168 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMA 2016-05-30 21:55:12,169 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75a79cf8 [0x18aec10], 0x76caf480 [0x18b6510], 0x2e0620 [1]) 2016-05-30 21:55:12,169 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x17138bc 2016-05-30 21:55:12,170 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76caf490 [0x18b6568]) 2016-05-30 21:55:12,170 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x75a79d10 2016-05-30 21:55:12,171 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76caf490 [0x18b6568]) 2016-05-30 21:55:12,171 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x75a79d10 2016-05-30 21:55:12,171 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec GSM 2016-05-30 21:55:12,172 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75a79cf8 [0x18aec10], 0x76caf490 [0x18b6568], 0x2e0614 [0]) 2016-05-30 21:55:12,172 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x17138bc 2016-05-30 21:55:12,173 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76caf4a0 [0x18b65c0]) 2016-05-30 21:55:12,173 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745688a0 2016-05-30 21:55:12,174 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76caf4a0 [0x18b65c0]) 2016-05-30 21:55:12,174 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745688a0 2016-05-30 21:55:12,175 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec G722 2016-05-30 21:55:12,175 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75a79cf8 [0x18aec10], 0x76caf4a0 [0x18b65c0], 0x2e0614 [0]) 2016-05-30 21:55:12,176 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x17138bc 2016-05-30 21:55:12,176 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76caf4b0 [0x18b0190]) 2016-05-30 21:55:12,177 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74568aa0 2016-05-30 21:55:12,177 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76caf4b0 [0x18b0190]) 2016-05-30 21:55:12,177 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74568aa0 2016-05-30 21:55:12,178 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-05-30 21:55:12,178 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75a79cf8 [0x18aec10], 0x76caf4b0 [0x18b0190], 0x2e0614 [0]) 2016-05-30 21:55:12,179 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x17138bc 2016-05-30 21:55:12,179 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76caf4c0 [0x18b01f8]) 2016-05-30 21:55:12,180 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x75a79d10 2016-05-30 21:55:12,180 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76caf4c0 [0x18b01f8]) 2016-05-30 21:55:12,180 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x75a79d10 2016-05-30 21:55:12,181 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2016-05-30 21:55:12,181 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75a79cf8 [0x18aec10], 0x76caf4c0 [0x18b01f8], 0x2e0614 [0]) 2016-05-30 21:55:12,182 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x17138bc 2016-05-30 21:55:12,182 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76caf4d0 [0x18b0250]) 2016-05-30 21:55:12,183 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x75a79d10 2016-05-30 21:55:12,183 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76caf4d0 [0x18b0250]) 2016-05-30 21:55:12,183 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x75a79d10 2016-05-30 21:55:12,184 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2016-05-30 21:55:12,184 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75a79cf8 [0x18aec10], 0x76caf4d0 [0x18b0250], 0x2e0614 [0]) 2016-05-30 21:55:12,185 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x17138bc 2016-05-30 21:55:12,185 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x75a79cf8 [0x18aec10]) 2016-05-30 21:55:12,186 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x7456d3c8 2016-05-30 21:55:12,186 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x75a79cf8 [0x18aec10]) 2016-05-30 21:55:12,186 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x7456d3c8 2016-05-30 21:55:12,187 [INFO] [doorpi.sipphone.from_linphone] found 2 possible videodevices: 2016-05-30 21:55:12,187 [DEBUG] [doorpi.sipphone.from_linphone] | name 2016-05-30 21:55:12,188 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-05-30 21:55:12,188 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x75a79cf8 [0x18aec10]) 2016-05-30 21:55:12,188 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x7456d3c8 2016-05-30 21:55:12,189 [DEBUG] [doorpi.sipphone.from_linphone] | V4L2: /dev/video0 2016-05-30 21:55:12,189 [DEBUG] [doorpi.sipphone.from_linphone] | StaticImage: Static picture 2016-05-30 21:55:12,190 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-05-30 21:55:12,190 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x75a79cf8 [0x18aec10]) 2016-05-30 21:55:12,190 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x7456d3c8 2016-05-30 21:55:12,191 [TRACE] [doorpi.conf.config_object] get_string for key video_device in section SIP-Phone (default: V4L2: /dev/video0) returns V4L2: /dev/video0 2016-05-30 21:55:12,191 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x75a79cf8 [0x18aec10]) 2016-05-30 21:55:12,192 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x7456d3c8 2016-05-30 21:55:12,192 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_capture(0x75a79cf8 [0x18aec10], 0x2e0620 [1]) 2016-05-30 21:55:12,192 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_capture -> 0 2016-05-30 21:55:12,193 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_video_device(0x75a79cf8 [0x18aec10], "V4L2: /dev/video0") 2016-05-30 21:55:12,193 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_video_device -> 0 2016-05-30 21:55:12,194 [TRACE] [doorpi.conf.config_object] get_string for key video_size in section SIP-Phone (default: vga) returns vga 2016-05-30 21:55:12,194 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_preferred_video_size_by_name(0x75a79cf8 [0x18aec10], "vga") 2016-05-30 21:55:12,195 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_preferred_video_size_by_name -> 0 2016-05-30 21:55:12,195 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_device(0x75a79cf8 [0x18aec10]) 2016-05-30 21:55:12,195 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_device -> 0x7456d660 2016-05-30 21:55:12,196 [DEBUG] [doorpi.sipphone.from_linphone] using video_device: V4L2: /dev/video0 2016-05-30 21:55:12,196 [TRACE] [doorpi.conf.config_object] get_list for key video_codecs in section SIP-Phone (default: VP8) returns ['VP8'] 2016-05-30 21:55:12,197 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_codecs(0x75a79cf8 [0x18aec10]) 2016-05-30 21:55:12,197 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x18af740) 2016-05-30 21:55:12,198 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-05-30 21:55:12,198 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76caf4e0 2016-05-30 21:55:12,198 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-05-30 21:55:12,199 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76caf4e0 2016-05-30 21:55:12,199 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76caf4e0 2016-05-30 21:55:12,200 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_codecs -> 0x7456d3f0 2016-05-30 21:55:12,200 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76caf4e0 [0x18af740]) 2016-05-30 21:55:12,200 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x75a79d10 2016-05-30 21:55:12,201 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_video_capture_enabled(0x75a79cf8 [0x18aec10]) 2016-05-30 21:55:12,201 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_video_capture_enabled -> 0x2e0620 2016-05-30 21:55:12,202 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76caf4e0 [0x18af740]) 2016-05-30 21:55:12,202 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x75a79d10 2016-05-30 21:55:12,202 [DEBUG] [doorpi.sipphone.from_linphone] enable video codec VP8 2016-05-30 21:55:12,203 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75a79cf8 [0x18aec10], 0x76caf4e0 [0x18af740], 0x2e0620 [1]) 2016-05-30 21:55:12,203 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x17138bc 2016-05-30 21:55:12,204 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_server in section SIP-Phone (default: ) returns 192.168.178.1 2016-05-30 21:55:12,204 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_username in section SIP-Phone (default: ) returns 620 2016-05-30 21:55:12,205 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_password in section SIP-Phone (default: 620) returns ******* 2016-05-30 21:55:12,205 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_realm in section SIP-Phone (default: 192.168.178.1) returns 2016-05-30 21:55:12,205 [INFO] [doorpi.sipphone.from_linphone] using DoorPi with SIP-Server 2016-05-30 21:55:12,206 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_proxy_config(0x75a79cf8 [0x18aec10]) 2016-05-30 21:55:12,206 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_from_native_ptr(0x18ecb70) 2016-05-30 21:55:12,207 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_new() 2016-05-30 21:55:12,207 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_new -> 0x76caf4f0 2016-05-30 21:55:12,207 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_init() 2016-05-30 21:55:12,208 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_init -> 0x76caf4f0 2016-05-30 21:55:12,208 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_from_native_ptr -> 0x76caf4f0 2016-05-30 21:55:12,209 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_proxy_config -> 0x76caf4f0 2016-05-30 21:55:12,209 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2016-05-30 21:55:12,209 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_class_method_new("DoorPi ") 2016-05-30 21:55:12,211 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0x18ee4f8) 2016-05-30 21:55:12,211 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2016-05-30 21:55:12,212 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0x76caf500 2016-05-30 21:55:12,212 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2016-05-30 21:55:12,212 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0x76caf500 2016-05-30 21:55:12,213 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0x76caf500 2016-05-30 21:55:12,213 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_class_method_new -> 0x76caf500 2016-05-30 21:55:12,214 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_identity_address(0x76caf4f0 [0x18ecb70], 0x76caf500 [0x18ee4f8]) 2016-05-30 21:55:12,214 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_identity_address -> 0 2016-05-30 21:55:12,215 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_server_addr(0x76caf4f0 [0x18ecb70], "sip:192.168.178.1") 2016-05-30 21:55:12,216 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_server_addr -> 0 2016-05-30 21:55:12,216 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_enable_register(0x76caf4f0 [0x18ecb70], 0x2e0620 [1]) 2016-05-30 21:55:12,217 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_enable_register -> 0 2016-05-30 21:55:12,217 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_proxy_config(0x75a79cf8 [0x18aec10], 0x76caf4f0 [0x18ecb70]) 2016-05-30 21:55:12,218 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_proxy_config -> 0x17138bc 2016-05-30 21:55:12,219 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_default_proxy_config(0x75a79cf8 [0x18aec10], 0x76caf4f0 [0x18ecb70]) 2016-05-30 21:55:12,219 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_default_proxy_config -> 0 2016-05-30 21:55:12,220 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_auth_info(0x75a79cf8 [0x18aec10], "620", "(null)", "raspberry", "(null)", "(null)", "") 2016-05-30 21:55:12,220 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_from_native_ptr(0x18c2bf0) 2016-05-30 21:55:12,221 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_new() 2016-05-30 21:55:12,221 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_new -> 0x76caf510 2016-05-30 21:55:12,221 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_init() 2016-05-30 21:55:12,222 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_init -> 0x76caf510 2016-05-30 21:55:12,222 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_from_native_ptr -> 0x76caf510 2016-05-30 21:55:12,223 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_auth_info -> 0x76caf510 2016-05-30 21:55:12,223 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_auth_info(0x75a79cf8 [0x18aec10], 0x76caf510 [0x18c2bf0]) 2016-05-30 21:55:12,224 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_auth_info -> None 2016-05-30 21:55:12,224 [DEBUG] [doorpi.sipphone.from_linphone] start successfully 2016-05-30 21:55:12,224 [TRACE] [doorpi.conf.config_object] get_sections returns ['EVENT_OnKeyPressed_onboardpins.1', 'EVENT_OnKeyPressed_onboardpins.0', 'EVENT_OnStartup'] 2016-05-30 21:55:12,225 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnKeyPressed_onboardpins.1' in configfile 2016-05-30 21:55:12,225 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnKeyPressed_onboardpins.1 returns ['10'] 2016-05-30 21:55:12,226 [INFO] [doorpi.doorpi] registering action '10' for event 'OnKeyPressed_onboardpins.1' 2016-05-30 21:55:12,226 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnKeyPressed_onboardpins.1 (default: ) returns take_snapshot:http://192.168.178.29:9000/?action=snapshot 2016-05-30 21:55:12,230 [DEBUG] [doorpi.action.SingleActions.take_snapshot] doorpi.action.SingleActions.take_snapshot loaded 2016-05-30 21:55:12,231 [TRACE] [doorpi.conf.config_object] get_string for key snapshot_path in section DoorPi (default: /tmp) returns !BASEPATH!/../DoorPiWeb/snapshots/ 2016-05-30 21:55:12,233 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/../DoorPiWeb/snapshots/" to "/usr/local/etc/DoorPi/../DoorPiWeb/snapshots/" 2016-05-30 21:55:12,233 [TRACE] [doorpi.action.handler] action SnapShotAction with args () and kwargs {'url': 'http://192.168.178.29:9000/?action=snapshot', 'snapshot_path': '/usr/local/etc/DoorPi/../DoorPiWeb/snapshots/'} was added to new evententry OnKeyPressed_onboardpins.1 2016-05-30 21:55:12,234 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnKeyPressed_onboardpins.0' in configfile 2016-05-30 21:55:12,234 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnKeyPressed_onboardpins.0 returns ['10'] 2016-05-30 21:55:12,235 [INFO] [doorpi.doorpi] registering action '10' for event 'OnKeyPressed_onboardpins.0' 2016-05-30 21:55:12,235 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnKeyPressed_onboardpins.0 (default: ) returns take_snapshot:http://192.168.178.29:9000/?action=snapshot 2016-05-30 21:55:12,236 [TRACE] [doorpi.conf.config_object] get_string for key snapshot_path in section DoorPi (default: /tmp) returns !BASEPATH!/../DoorPiWeb/snapshots/ 2016-05-30 21:55:12,237 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/../DoorPiWeb/snapshots/" to "/usr/local/etc/DoorPi/../DoorPiWeb/snapshots/" 2016-05-30 21:55:12,238 [TRACE] [doorpi.action.handler] action SnapShotAction with args () and kwargs {'url': 'http://192.168.178.29:9000/?action=snapshot', 'snapshot_path': '/usr/local/etc/DoorPi/../DoorPiWeb/snapshots/'} was added to new evententry OnKeyPressed_onboardpins.0 2016-05-30 21:55:12,238 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnStartup' in configfile 2016-05-30 21:55:12,239 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnStartup returns ['10'] 2016-05-30 21:55:12,239 [INFO] [doorpi.doorpi] registering action '10' for event 'OnStartup' 2016-05-30 21:55:12,239 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnStartup (default: ) returns sleep:1 2016-05-30 21:55:12,241 [DEBUG] [doorpi.action.SingleActions.sleep] doorpi.action.SingleActions.sleep loaded 2016-05-30 21:55:12,241 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to new evententry OnStartup 2016-05-30 21:55:12,242 [TRACE] [doorpi.conf.config_object] get_string for key 1 in section onboardpins_InputPins (default: ) returns call:620 2016-05-30 21:55:12,243 [DEBUG] [doorpi.action.SingleActions.call] doorpi.action.SingleActions.call loaded 2016-05-30 21:55:12,244 [TRACE] [doorpi.action.handler] action CallAction with args () and kwargs {'number': '620'} was added to event OnKeyPressed_onboardpins.1 2016-05-30 21:55:12,244 [TRACE] [doorpi.conf.config_object] get_string for key 0 in section onboardpins_InputPins (default: ) returns call:610 2016-05-30 21:55:12,245 [TRACE] [doorpi.action.handler] action CallAction with args () and kwargs {'number': '610'} was added to event OnKeyPressed_onboardpins.0 2016-05-30 21:55:12,245 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns ['123'] 2016-05-30 21:55:12,246 [TRACE] [doorpi.conf.config_object] get_string for key 123 in section DTMF (default: ) returns out:tuer1,1,0,3 2016-05-30 21:55:12,247 [DEBUG] [doorpi.action.SingleActions.out] doorpi.action.SingleActions.out loaded 2016-05-30 21:55:12,248 [DEBUG] [doorpi.action.SingleActions.out_triggered] doorpi.action.SingleActions.out_triggered loaded 2016-05-30 21:55:12,249 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 3.0, 'pin': 'tuer1', 'start_value': '1'} was added to new evententry OnDTMF_123 2016-05-30 21:55:12,249 [TRACE] [doorpi.conf.config_object] get_string for key is_alive_led in section DoorPi (default: ) returns 2016-05-30 21:55:12,250 [TRACE] [doorpi.action.handler] fire Event BeforeStartup from doorpi.doorpi asyncron 2016-05-30 21:55:12,251 [DEBUG] [doorpi.action.handler] no actions for event BeforeStartup - skip fire_event BeforeStartup from doorpi.doorpi 2016-05-30 21:55:12,252 [DEBUG] [doorpi.action.handler] [EW0MNF] fire for event OnStartup this actions [] 2016-05-30 21:55:12,253 [TRACE] [doorpi.action.handler] [EW0MNF] try to fire action SleepAction with args (1.0,) and kwargs {} 2016-05-30 21:55:12,254 [TRACE] [doorpi.action.base] run SleepAction with args (1.0,) and kwargs {} 2016-05-30 21:55:13,256 [TRACE] [doorpi.action.handler] [EW0MNF] finished fire_event for event_name OnStartup 2016-05-30 21:55:13,257 [TRACE] [doorpi.action.handler] fire Event AfterStartup from doorpi.doorpi asyncron 2016-05-30 21:55:13,258 [INFO] [doorpi.doorpi] DoorPi started successfully 2016-05-30 21:55:13,259 [INFO] [doorpi.doorpi] BasePath is /usr/local/etc/DoorPi 2016-05-30 21:55:13,259 [DEBUG] [doorpi.action.handler] no actions for event AfterStartup - skip fire_event AfterStartup from doorpi.doorpi 2016-05-30 21:55:13,260 [INFO] [doorpi.doorpi] Weburl is http://raspberrypi/ 2016-05-30 21:55:13,261 [INFO] [doorpi.sipphone.from_linphone] resolved to 192.168.178.1 2016-05-30 21:55:13,262 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2016-05-30 21:55:13,262 [INFO] [doorpi.sipphone.from_linphone] New local ip address is 192.168.178.29 2016-05-30 21:55:13,263 [INFO] [doorpi.sipphone.from_linphone] Network state is now [UP] 2016-05-30 21:55:13,264 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2016-05-30 21:55:13,265 [INFO] [doorpi.sipphone.from_linphone] 192.168.178.1 2016-05-30 21:55:13,266 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2016-05-30 21:55:13,266 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2016-05-30 21:55:13,268 [INFO] [doorpi.sipphone.from_linphone] LinphoneProxyConfig [0x18ecb70] about to register (LinphoneCore version: 3.9.0) 2016-05-30 21:55:13,274 [INFO] [doorpi.sipphone.from_linphone] belle_sip_client_transaction_send_request(): waiting channel to be ready 2016-05-30 21:55:13,275 [INFO] [doorpi.sipphone.from_linphone] channel [0x18c7158]: starting resolution of 192.168.178.1 2016-05-30 21:55:13,275 [INFO] [doorpi.sipphone.from_linphone] channel 0x18c7158: state RES_IN_PROGRESS 2016-05-30 21:55:13,276 [INFO] [doorpi.sipphone.from_linphone] transaction [0x18edf38] channel state changed to [RES_IN_PROGRESS] 2016-05-30 21:55:13,276 [INFO] [doorpi.sipphone.from_linphone] channel 0x18c7158: state RES_DONE 2016-05-30 21:55:13,277 [INFO] [doorpi.sipphone.from_linphone] transaction [0x18edf38] channel state changed to [RES_DONE] 2016-05-30 21:55:13,277 [INFO] [doorpi.sipphone.from_linphone] channel 0x18c7158: state CONNECTING 2016-05-30 21:55:13,278 [INFO] [doorpi.sipphone.from_linphone] transaction [0x18edf38] channel state changed to [CONNECTING] 2016-05-30 21:55:13,278 [INFO] [doorpi.sipphone.from_linphone] Trying to connect to [UDP://192.168.178.1:5060] 2016-05-30 21:55:13,278 [INFO] [doorpi.sipphone.from_linphone] belle_sip_get_src_addr_for(): af_inet6=0 2016-05-30 21:55:13,279 [INFO] [doorpi.sipphone.from_linphone] Channel has local address 192.168.178.29:5060 2016-05-30 21:55:13,279 [INFO] [doorpi.sipphone.from_linphone] channel 0x18c7158: state READY 2016-05-30 21:55:13,280 [INFO] [doorpi.sipphone.from_linphone] transaction [0x18edf38] channel state changed to [READY] 2016-05-30 21:55:13,280 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x18edf38], from state [INIT] to [TRYING] 2016-05-30 21:55:13,281 [INFO] [doorpi.sipphone.from_linphone] channel [0x18c7158]: message sent to [UDP://192.168.178.1:5060], size: [514] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.29:5060;branch=z9hG4bK.2y1I9GtOW;rport From: "DoorPi" ;tag=Fk0h1EWpt To: "DoorPi" CSeq: 20 REGISTER Call-ID: H43d2WbOHY 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-05-30 21:55:13,282 [INFO] [doorpi.sipphone.from_linphone] Neither Expires header nor corresponding Contact header found, checking from original request 2016-05-30 21:55:13,282 [INFO] [doorpi.sipphone.from_linphone] Refresher [0x18f07f8] takes ownership of transaction [0x18edf38] 2016-05-30 21:55:13,283 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0x18ecb70] for identity ["DoorPi" ] moving from state [LinphoneRegistrationNone] to [LinphoneRegistrationProgress] 2016-05-30 21:55:13,283 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0x18aec10, 0x18ecb70, 1, "Registration in progress") 2016-05-30 21:55:13,284 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2016-05-30 21:55:13,284 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x18aec10] notifying [registration_state_changed] 2016-05-30 21:55:13,335 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_network_reachable(0x18aec10, 0x1) 2016-05-30 21:55:13,336 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_network_reachable 2016-05-30 21:55:13,336 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x18aec10] notifying [network_reachable] 2016-05-30 21:55:13,337 [INFO] [doorpi.sipphone.from_linphone] channel [0x18c7158]: received [366] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.178.29:5060;branch=z9hG4bK.2y1I9GtOW;rport=5060 From: "DoorPi" ;tag=Fk0h1EWpt To: "DoorPi" ;tag=66F06BF6CB4D8E5D Call-ID: H43d2WbOHY CSeq: 20 REGISTER WWW-Authenticate: Digest realm="fritz.box", nonce="5C939986DD3F3826" User-Agent: FRITZ!OS Content-Length: 0