login as: pi pi@192.168.1.26's password: The programs included with the Debian GNU/Linux system are free software; the exact distribution terms for each program are described in the individual files in /usr/share/doc/*/copyright. Debian GNU/Linux comes with ABSOLUTELY NO WARRANTY, to the extent permitted by applicable law. Last login: Sun Apr 24 12:51:03 2016 from elitebook-8460p.fritz.box pi@raspberrypi:~ $ sudo doorpi_cli --trace 2016-04-24 12:51:36,519 [INFO] [doorpi.main] ___ ___ _ / \___ ___ _ __ / _ (_) VoIP Door-Intercomstation with Raspberry Pi / /\ / _ \ / _ \| '__/ /_)/ | version: 2.5.0.2 / /_// (_) | (_) | | / ___/| | 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-04-24 12:51:36,521 [DEBUG] [doorpi.main] loaded with arguments: ['/usr/local/bin/doorpi_cli', '--trace'] 2016-04-24 12:51:36,522 [DEBUG] [doorpi.doorpi] run 2016-04-24 12:51:36,523 [DEBUG] [doorpi.doorpi] prepare 2016-04-24 12:51:36,524 [DEBUG] [doorpi.doorpi] given arguments argv: Namespace(configfile=None, debug=False, test=False, trace=True) 2016-04-24 12:51:36,530 [INFO] [doorpi.conf.config_object] use configfile: /usr/local/etc/DoorPi/conf/doorpi.ini 2016-04-24 12:51:36,541 [DEBUG] [doorpi.conf.config_object] __init__ 2016-04-24 12:51:36,542 [TRACE] [doorpi.conf.config_object] get_from_config 2016-04-24 12:51:36,549 [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-04-24 12:51:36,550 [TRACE] [doorpi.conf.config_object] get_string for key eventlog in section DoorPi (default: !BASEPATH!/conf/eventlog.db) returns !BASEPATH!/conf/eventlog.db 2016-04-24 12:51:36,552 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/conf/eventlog.db" to "/usr/local/etc/DoorPi/conf/eventlog.db" 2016-04-24 12:51:36,557 [TRACE] [doorpi.action.handler] register Event BeforeStartup from doorpi.doorpi 2016-04-24 12:51:36,558 [DEBUG] [doorpi.action.handler] event_source doorpi.doorpi was added 2016-04-24 12:51:36,559 [TRACE] [doorpi.action.handler] added event_name BeforeStartup and registered source doorpi.doorpi 2016-04-24 12:51:36,560 [TRACE] [doorpi.action.handler] register Event OnStartup from doorpi.doorpi 2016-04-24 12:51:36,560 [TRACE] [doorpi.action.handler] added event_name OnStartup and registered source doorpi.doorpi 2016-04-24 12:51:36,561 [TRACE] [doorpi.action.handler] register Event AfterStartup from doorpi.doorpi 2016-04-24 12:51:36,562 [TRACE] [doorpi.action.handler] added event_name AfterStartup and registered source doorpi.doorpi 2016-04-24 12:51:36,563 [TRACE] [doorpi.action.handler] register Event BeforeShutdown from doorpi.doorpi 2016-04-24 12:51:36,563 [TRACE] [doorpi.action.handler] added event_name BeforeShutdown and registered source doorpi.doorpi 2016-04-24 12:51:36,564 [TRACE] [doorpi.action.handler] register Event OnShutdown from doorpi.doorpi 2016-04-24 12:51:36,565 [TRACE] [doorpi.action.handler] added event_name OnShutdown and registered source doorpi.doorpi 2016-04-24 12:51:36,565 [TRACE] [doorpi.action.handler] register Event AfterShutdown from doorpi.doorpi 2016-04-24 12:51:36,566 [TRACE] [doorpi.action.handler] added event_name AfterShutdown and registered source doorpi.doorpi 2016-04-24 12:51:36,569 [DEBUG] [doorpi.action.SingleActions.time_tick] doorpi.action.SingleActions.time_tick loaded 2016-04-24 12:51:36,572 [DEBUG] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was added 2016-04-24 12:51:36,576 [TRACE] [doorpi.action.handler] action TimeTickDestroyAction with args () and kwargs {} was added to new evententry OnShutdown 2016-04-24 12:51:36,577 [TRACE] [doorpi.action.handler] action TimeTickAction with args ('!last_tick!',) and kwargs {} was added to new evententry OnTimeTick 2016-04-24 12:51:36,578 [TRACE] [doorpi.conf.config_object] get_string for key ip in section DoorPiWeb (default: ) returns 2016-04-24 12:51:36,579 [TRACE] [doorpi.conf.config_object] get_integer for key port in section DoorPiWeb (default: 80) returns 80 2016-04-24 12:51:36,583 [INFO] [doorpi.status.webserver] Initiating WebService at ip and port 80 2016-04-24 12:51:36,584 [TRACE] [doorpi.action.handler] register Event OnWebServerStart from doorpi.status.webserver 2016-04-24 12:51:36,585 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver was added 2016-04-24 12:51:36,586 [TRACE] [doorpi.action.handler] added event_name OnWebServerStart and registered source doorpi.status.webserver 2016-04-24 12:51:36,587 [TRACE] [doorpi.action.handler] register Event OnWebServerStop from doorpi.status.webserver 2016-04-24 12:51:36,587 [TRACE] [doorpi.action.handler] added event_name OnWebServerStop and registered source doorpi.status.webserver 2016-04-24 12:51:36,588 [TRACE] [doorpi.conf.config_object] get_string for key www in section DoorPiWeb (default: !BASEPATH!/../DoorPiWeb) returns !BASEPATH!/../DoorPiWeb 2016-04-24 12:51:36,590 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/../DoorPiWeb" to "/usr/local/etc/DoorPi/../DoorPiWeb" 2016-04-24 12:51:36,591 [TRACE] [doorpi.conf.config_object] get_string for key indexfile in section DoorPiWeb (default: index.html) returns index.html 2016-04-24 12:51:36,593 [DEBUG] [doorpi.conf.config_object] parse string "index.html" to "index.html" 2016-04-24 12:51:36,594 [TRACE] [doorpi.conf.config_object] get_string for key loginfile in section DoorPiWeb (default: login.html) returns login.html 2016-04-24 12:51:36,596 [DEBUG] [doorpi.conf.config_object] parse string "login.html" to "login.html" 2016-04-24 12:51:36,597 [TRACE] [doorpi.conf.config_object] get_string for key public in section DoorPiWeb (default: AREA_public) returns AREA_public 2016-04-24 12:51:36,599 [DEBUG] [doorpi.conf.config_object] parse string "AREA_public" to "AREA_public" 2016-04-24 12:51:36,600 [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-04-24 12:51:36,602 [DEBUG] [doorpi.conf.config_object] parse string "http://motom001.github.io/DoorPiWeb" to "http://motom001.github.io/DoorPiWeb" 2016-04-24 12:51:36,602 [TRACE] [doorpi.conf.config_object] get_keys for section WritePermission returns ['administrator'] 2016-04-24 12:51:36,603 [WARNING] [root] section ReadPermission not found in configfile 2016-04-24 12:51:36,604 [TRACE] [doorpi.conf.config_object] get_keys for section ReadPermission returns [] 2016-04-24 12:51:36,605 [TRACE] [doorpi.conf.config_object] get_keys for section Group returns ['administrator'] 2016-04-24 12:51:36,606 [TRACE] [doorpi.conf.config_object] get_keys for section User returns ['door'] 2016-04-24 12:51:36,607 [TRACE] [doorpi.conf.config_object] get_list for key administrator in section Group (default: []) returns ['door'] 2016-04-24 12:51:36,608 [TRACE] [doorpi.conf.config_object] get_sections returns ['onboardpins_OutputPins', 'onboardpins_keyboard', 'Group', 'keyboards', 'AdminNumbers', 'SIP-Phone', 'WritePermission', 'AREA_installer', 'EVENT_OnStartup', 'User', 'DoorPiWeb', 'onboardpins_InputPins', 'DoorPi'] 2016-04-24 12:51:36,609 [TRACE] [doorpi.conf.config_object] get_list for key administrator in section WritePermission (default: []) returns ['installer'] 2016-04-24 12:51:36,610 [ERROR] [doorpi.status.webserver] no ReadPermission found 2016-04-24 12:51:36,611 [TRACE] [doorpi.action.handler] action WebServerStartupAction with args () and kwargs {} was added to new evententry OnWebServerStart 2016-04-24 12:51:36,612 [TRACE] [doorpi.action.handler] action WebServerShutdownAction with args () and kwargs {} was added to event OnShutdown 2016-04-24 12:51:36,613 [TRACE] [doorpi.action.handler] fire Event OnWebServerStart from doorpi.status.webserver asyncron 2016-04-24 12:51:36,615 [TRACE] [doorpi.action.handler] register Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2016-04-24 12:51:36,616 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was added 2016-04-24 12:51:36,617 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequest and registered source doorpi.status.webserver_lib.request_handler 2016-04-24 12:51:36,618 [DEBUG] [doorpi.action.handler] [5ZJJLY] fire for event OnWebServerStart this actions [] 2016-04-24 12:51:36,619 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2016-04-24 12:51:36,620 [TRACE] [doorpi.action.handler] [5ZJJLY] try to fire action WebServerStartupAction with args () and kwargs {} 2016-04-24 12:51:36,621 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestGet and registered source doorpi.status.webserver_lib.request_handler 2016-04-24 12:51:36,621 [TRACE] [doorpi.action.base] run WebServerStartupAction with args () and kwargs {} 2016-04-24 12:51:36,622 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2016-04-24 12:51:36,624 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestPost and registered source doorpi.status.webserver_lib.request_handler 2016-04-24 12:51:36,625 [TRACE] [doorpi.action.handler] register Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2016-04-24 12:51:36,626 [TRACE] [doorpi.action.handler] added event_name OnWebServerVirtualResource and registered source doorpi.status.webserver_lib.request_handler 2016-04-24 12:51:36,626 [TRACE] [doorpi.action.handler] register Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler 2016-04-24 12:51:36,627 [TRACE] [doorpi.action.handler] added event_name OnWebServerRealResource and registered source doorpi.status.webserver_lib.request_handler 2016-04-24 12:51:36,628 [TRACE] [doorpi.action.handler] register Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2016-04-24 12:51:36,629 [TRACE] [doorpi.action.handler] added event_name OnFireEvent and registered source doorpi.status.webserver_lib.request_handler 2016-04-24 12:51:36,629 [TRACE] [doorpi.action.handler] register Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2016-04-24 12:51:36,630 [TRACE] [doorpi.action.handler] added event_name OnConfigKeySet and registered source doorpi.status.webserver_lib.request_handler 2016-04-24 12:51:36,631 [TRACE] [doorpi.action.handler] register Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2016-04-24 12:51:36,632 [TRACE] [doorpi.action.handler] added event_name OnConfigKeyDelete and registered source doorpi.status.webserver_lib.request_handler 2016-04-24 12:51:36,633 [TRACE] [doorpi.conf.config_object] get_keys for section keyboards returns ['onboardpins'] 2016-04-24 12:51:36,633 [INFO] [doorpi.keyboard.KeyboardInterface] using multi-keyboard mode (keyboards: onboardpins) 2016-04-24 12:51:36,634 [INFO] [doorpi.keyboard.KeyboardInterface] trying to add keyboard 'onboardpins' to handler 2016-04-24 12:51:36,635 [TRACE] [doorpi.conf.config_object] get_string for key onboardpins in section keyboards (default: dummy) returns gpio 2016-04-24 12:51:36,636 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_InputPins returns ['7'] 2016-04-24 12:51:36,637 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns ['11'] 2016-04-24 12:51:36,638 [TRACE] [doorpi.conf.config_object] get_integer for key bouncetime in section onboardpins_keyboard (default: 2000) returns 200.0 2016-04-24 12:51:36,639 [TRACE] [doorpi.conf.config_object] get_integer for key polarity in section onboardpins_keyboard (default: 0) returns 0 2016-04-24 12:51:36,640 [TRACE] [doorpi.conf.config_object] get_boolean for key pressed_on_keydown in section onboardpins_keyboard (default: True) returns True 2016-04-24 12:51:36,642 [DEBUG] [doorpi.keyboard.from_gpio] doorpi.keyboard.from_gpio loaded 2016-04-24 12:51:36,647 [DEBUG] [doorpi.keyboard.from_gpio] __init__(input_pins = ['7'], output_pins = ['11'], bouncetime = 200.0, polarity = 0) 2016-04-24 12:51:36,649 [DEBUG] [doorpi.conf.config_object] create new key mode in section onboardpins_keyboard with value 'BOARD' 2016-04-24 12:51:36,649 [TRACE] [doorpi.conf.config_object] get_string for key mode in section onboardpins_keyboard (default: BOARD) returns BOARD 2016-04-24 12:51:36,650 [DEBUG] [doorpi.conf.config_object] create new key pull_up_down in section onboardpins_keyboard with value 'PUD_OFF' 2016-04-24 12:51:36,651 [TRACE] [doorpi.conf.config_object] get_string for key pull_up_down in section onboardpins_keyboard (default: PUD_OFF) returns PUD_OFF 2016-04-24 12:51:36,653 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_gpio 2016-04-24 12:51:36,655 [DEBUG] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was added 2016-04-24 12:51:36,656 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed and registered source doorpi.keyboard.from_gpio 2016-04-24 12:51:36,657 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_7 from doorpi.keyboard.from_gpio 2016-04-24 12:51:36,659 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_7 and registered source doorpi.keyboard.from_gpio 2016-04-24 12:51:36,660 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.7 from doorpi.keyboard.from_gpio 2016-04-24 12:51:36,661 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.7 and registered source doorpi.keyboard.from_gpio 2016-04-24 12:51:36,662 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_gpio 2016-04-24 12:51:36,663 [TRACE] [doorpi.action.handler] added event_name OnKeyUp and registered source doorpi.keyboard.from_gpio 2016-04-24 12:51:36,664 [TRACE] [doorpi.action.handler] register Event OnKeyUp_7 from doorpi.keyboard.from_gpio 2016-04-24 12:51:36,666 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_7 and registered source doorpi.keyboard.from_gpio 2016-04-24 12:51:36,667 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.7 from doorpi.keyboard.from_gpio 2016-04-24 12:51:36,668 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.7 and registered source doorpi.keyboard.from_gpio 2016-04-24 12:51:36,669 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_gpio 2016-04-24 12:51:36,670 [TRACE] [doorpi.action.handler] added event_name OnKeyDown and registered source doorpi.keyboard.from_gpio 2016-04-24 12:51:36,672 [TRACE] [doorpi.action.handler] register Event OnKeyDown_7 from doorpi.keyboard.from_gpio 2016-04-24 12:51:36,673 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_7 and registered source doorpi.keyboard.from_gpio 2016-04-24 12:51:36,674 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.7 from doorpi.keyboard.from_gpio 2016-04-24 12:51:36,675 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.7 and registered source doorpi.keyboard.from_gpio 2016-04-24 12:51:36,678 [TRACE] [doorpi.action.handler] action KeyboardDestroyAction with args () and kwargs {} was added to event OnShutdown 2016-04-24 12:51:36,680 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns ['11'] 2016-04-24 12:51:36,681 [TRACE] [doorpi.conf.config_object] get_string for key 11 in section onboardpins_OutputPins (default: ) returns Blink LED 2016-04-24 12:51:36,684 [DEBUG] [doorpi.status.status_lib.environment] doorpi.status.status_lib.environment loaded 2016-04-24 12:51:36,688 [DEBUG] [doorpi.status.requirements_lib.req_config] doorpi.status.requirements_lib.req_config loaded 2016-04-24 12:51:36,691 [DEBUG] [doorpi.status.requirements_lib.req_sipphone] doorpi.status.requirements_lib.req_sipphone loaded 2016-04-24 12:51:36,694 [DEBUG] [doorpi.sipphone.AbstractBaseClass] doorpi.sipphone.AbstractBaseClass loaded 2016-04-24 12:51:36,752 [DEBUG] [doorpi.status.requirements_lib.req_event_handler] doorpi.status.requirements_lib.req_event_handler loaded 2016-04-24 12:51:36,756 [DEBUG] [doorpi.status.requirements_lib.req_webserver] doorpi.status.requirements_lib.req_webserver loaded 2016-04-24 12:51:36,761 [DEBUG] [doorpi.status.requirements_lib.req_keyboard] doorpi.status.requirements_lib.req_keyboard loaded 2016-04-24 12:51:36,781 [DEBUG] [doorpi.status.requirements_lib.req_system] doorpi.status.requirements_lib.req_system loaded 2016-04-24 12:51:36,791 [INFO] [doorpi.sipphone.SipphoneInterface] found installed sipphone "linphone" and use this as default 2016-04-24 12:51:36,793 [TRACE] [doorpi.conf.config_object] get_string for key sipphonetyp in section SIP-Phone (default: linphone) returns linphone 2016-04-24 12:51:36,795 [DEBUG] [doorpi.sipphone.from_linphone] doorpi.sipphone.from_linphone loaded 2016-04-24 12:51:36,799 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] doorpi.sipphone.linphone_lib.CallBacks loaded 2016-04-24 12:51:36,802 [DEBUG] [doorpi.sipphone.linphone_lib.Player] doorpi.sipphone.linphone_lib.Player loaded 2016-04-24 12:51:36,805 [DEBUG] [doorpi.sipphone.linphone_lib.Recorder] doorpi.sipphone.linphone_lib.Recorder loaded 2016-04-24 12:51:36,808 [DEBUG] [doorpi.sipphone.from_linphone] __init__ 2016-04-24 12:51:36,809 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnShutdown 2016-04-24 12:51:36,810 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2016-04-24 12:51:36,811 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was added 2016-04-24 12:51:36,812 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCreate and registered source doorpi.sipphone.from_linphone 2016-04-24 12:51:36,813 [TRACE] [doorpi.action.handler] register Event OnSipPhoneStart from doorpi.sipphone.from_linphone 2016-04-24 12:51:36,814 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneStart and registered source doorpi.sipphone.from_linphone 2016-04-24 12:51:36,814 [TRACE] [doorpi.action.handler] register Event OnSipPhoneDestroy from doorpi.sipphone.from_linphone 2016-04-24 12:51:36,815 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneDestroy and registered source doorpi.sipphone.from_linphone 2016-04-24 12:51:36,816 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderCreate from doorpi.sipphone.from_linphone 2016-04-24 12:51:36,817 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderCreate and registered source doorpi.sipphone.from_linphone 2016-04-24 12:51:36,818 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderDestroy from doorpi.sipphone.from_linphone 2016-04-24 12:51:36,819 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderDestroy and registered source doorpi.sipphone.from_linphone 2016-04-24 12:51:36,820 [TRACE] [doorpi.action.handler] register Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-04-24 12:51:36,821 [TRACE] [doorpi.action.handler] added event_name BeforeSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2016-04-24 12:51:36,822 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-04-24 12:51:36,822 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2016-04-24 12:51:36,823 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2016-04-24 12:51:36,824 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCallFailed and registered source doorpi.sipphone.from_linphone 2016-04-24 12:51:36,825 [TRACE] [doorpi.action.handler] register Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-04-24 12:51:36,826 [TRACE] [doorpi.action.handler] added event_name AfterSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2016-04-24 12:51:36,826 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutNoResponse from doorpi.sipphone.from_linphone 2016-04-24 12:51:36,827 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutNoResponse and registered source doorpi.sipphone.from_linphone 2016-04-24 12:51:36,828 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutMaxCalltime from doorpi.sipphone.from_linphone 2016-04-24 12:51:36,829 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutMaxCalltime and registered source doorpi.sipphone.from_linphone 2016-04-24 12:51:36,830 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.from_linphone 2016-04-24 12:51:36,831 [TRACE] [doorpi.action.handler] added event_name OnPlayerCreated and registered source doorpi.sipphone.from_linphone 2016-04-24 12:51:36,832 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] __init__ 2016-04-24 12:51:36,833 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneDestroy 2016-04-24 12:51:36,834 [TRACE] [doorpi.action.handler] register Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,834 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was added 2016-04-24 12:51:36,835 [TRACE] [doorpi.action.handler] added event_name OnCallMediaStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,836 [TRACE] [doorpi.action.handler] register Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,837 [TRACE] [doorpi.action.handler] added event_name OnMediaRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,838 [TRACE] [doorpi.action.handler] register Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,838 [TRACE] [doorpi.action.handler] added event_name OnMediaNotRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,839 [TRACE] [doorpi.action.handler] register Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,840 [TRACE] [doorpi.action.handler] added event_name OnCallStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,841 [TRACE] [doorpi.action.handler] register Event OnCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,841 [TRACE] [doorpi.action.handler] added event_name OnCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,842 [TRACE] [doorpi.action.handler] register Event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,843 [TRACE] [doorpi.action.handler] added event_name AfterCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,844 [TRACE] [doorpi.action.handler] register Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,844 [TRACE] [doorpi.action.handler] added event_name OnCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,845 [TRACE] [doorpi.action.handler] register Event AfterCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,846 [TRACE] [doorpi.action.handler] added event_name AfterCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,847 [TRACE] [doorpi.action.handler] register Event OnCallStateDismissed from doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,847 [TRACE] [doorpi.action.handler] added event_name OnCallStateDismissed and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,848 [TRACE] [doorpi.action.handler] register Event OnCallStateReject from doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,849 [TRACE] [doorpi.action.handler] added event_name OnCallStateReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,850 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,850 [TRACE] [doorpi.action.handler] added event_name OnCallStart and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,851 [TRACE] [doorpi.action.handler] register Event OnDTMF from doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,852 [TRACE] [doorpi.action.handler] added event_name OnDTMF and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,853 [WARNING] [root] section DTMF not found in configfile 2016-04-24 12:51:36,853 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns [] 2016-04-24 12:51:36,854 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,855 [TRACE] [doorpi.action.handler] nothing to do - event OnCallStart from source doorpi.sipphone.linphone_lib.CallBacks is already known 2016-04-24 12:51:36,856 [TRACE] [doorpi.action.handler] register Event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,856 [TRACE] [doorpi.action.handler] added event_name BeforeCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,857 [TRACE] [doorpi.action.handler] register Event OnCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,858 [TRACE] [doorpi.action.handler] added event_name OnCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,859 [TRACE] [doorpi.action.handler] register Event AfterCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,860 [TRACE] [doorpi.action.handler] added event_name AfterCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,860 [TRACE] [doorpi.action.handler] register Event OnCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,861 [TRACE] [doorpi.action.handler] added event_name OnCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,862 [TRACE] [doorpi.action.handler] register Event AfterCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,863 [TRACE] [doorpi.action.handler] added event_name AfterCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,863 [TRACE] [doorpi.action.handler] register Event OnCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,864 [TRACE] [doorpi.action.handler] added event_name OnCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,865 [TRACE] [doorpi.action.handler] register Event AfterCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,866 [TRACE] [doorpi.action.handler] added event_name AfterCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,866 [TRACE] [doorpi.action.handler] register Event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,867 [TRACE] [doorpi.action.handler] added event_name OnCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,868 [TRACE] [doorpi.action.handler] register Event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,869 [TRACE] [doorpi.action.handler] added event_name AfterCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,869 [TRACE] [doorpi.action.handler] fire Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks asyncron 2016-04-24 12:51:36,872 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_new() 2016-04-24 12:51:36,873 [DEBUG] [doorpi.action.handler] no actions for event OnCallStart - skip fire_event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2016-04-24 12:51:36,874 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_new -> 0x7482c698 2016-04-24 12:51:36,875 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_init() 2016-04-24 12:51:36,876 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_init -> 0x7482c698 2016-04-24 12:51:36,877 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_class_method_new(0x74845c90, "(null)", "(null)") 2016-04-24 12:51:36,878 [INFO] [doorpi.sipphone.from_linphone] Mediastreamer2 factory 2.12.0 (git: 3.9.0) initialized. 2016-04-24 12:51:36,879 [INFO] [doorpi.sipphone.from_linphone] CPU count set to 4 2016-04-24 12:51:36,880 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init() done: platform_tags=linux,arm,embedded 2016-04-24 12:51:36,881 [INFO] [doorpi.sipphone.from_linphone] srtp init 2016-04-24 12:51:36,931 [INFO] [doorpi.sipphone.from_linphone] Registering all soundcard handlers 2016-04-24 12:51:37,052 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: default device' added 2016-04-24 12:51:37,074 [INFO] [doorpi.sipphone.from_linphone] also error in pcm_hw.c:1557 - open '/dev/snd/pcmC0D0c' failed (-2) 2016-04-24 12:51:37,079 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: bcm2835 ALSA' added 2016-04-24 12:51:37,102 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: C-Media USB Headphone Set' added 2016-04-24 12:51:37,103 [INFO] [doorpi.sipphone.from_linphone] Registering all webcam handlers 2016-04-24 12:51:37,105 [INFO] [doorpi.sipphone.from_linphone] Webcam StaticImage: Static picture added 2016-04-24 12:51:37,106 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init_voip() done 2016-04-24 12:51:37,107 [INFO] [doorpi.sipphone.from_linphone] Loading ms plugins from [./lib/mediastreamer/plugins] 2016-04-24 12:51:37,108 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/mediastreamer/plugins: No such file or directory 2016-04-24 12:51:37,109 [INFO] [doorpi.sipphone.from_linphone] Initializing LinphoneCore 3.9.0 2016-04-24 12:51:37,111 [INFO] [doorpi.sipphone.from_linphone] Vtable [0xee4390] registered on core [0xee6e60] 2016-04-24 12:51:37,112 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0xee4390, 1, "Starting up") 2016-04-24 12:51:37,113 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2016-04-24 12:51:37,114 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0xee4390] notifying [global_state_changed] 2016-04-24 12:51:37,115 [INFO] [doorpi.sipphone.from_linphone] oRTP-0.25.0 initialized. 2016-04-24 12:51:37,116 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_base_init, because [2] ref 2016-04-24 12:51:37,117 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_voip_init, because [2] ref 2016-04-24 12:51:37,118 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_plugins_init, because [2] ref 2016-04-24 12:51:37,119 [INFO] [doorpi.sipphone.from_linphone] Codec opus/48000 fmtp=[useinbandfec=1] number=-1, enabled=1) added to default capabilities. 2016-04-24 12:51:37,120 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-04-24 12:51:37,121 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-04-24 12:51:37,123 [INFO] [doorpi.sipphone.from_linphone] Codec speex/16000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2016-04-24 12:51:37,124 [INFO] [doorpi.sipphone.from_linphone] Codec speex/8000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2016-04-24 12:51:37,125 [INFO] [doorpi.sipphone.from_linphone] Codec PCMU/8000 fmtp=[] number=0, enabled=1) added to default capabilities. 2016-04-24 12:51:37,126 [INFO] [doorpi.sipphone.from_linphone] Codec PCMA/8000 fmtp=[] number=8, enabled=1) added to default capabilities. 2016-04-24 12:51:37,127 [INFO] [doorpi.sipphone.from_linphone] Codec t140/1000 fmtp=[] number=96, enabled=1) added to default capabilities. 2016-04-24 12:51:37,128 [INFO] [doorpi.sipphone.from_linphone] Codec red/1000 fmtp=[] number=97, enabled=1) added to default capabilities. 2016-04-24 12:51:37,129 [INFO] [doorpi.sipphone.from_linphone] Codec GSM/8000 fmtp=[] number=3, enabled=0) added to default capabilities. 2016-04-24 12:51:37,130 [INFO] [doorpi.sipphone.from_linphone] Codec G722/8000 fmtp=[] number=9, enabled=0) added to default capabilities. 2016-04-24 12:51:37,132 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iLBC 2016-04-24 12:51:37,133 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iLBC 2016-04-24 12:51:37,134 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR 2016-04-24 12:51:37,135 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR 2016-04-24 12:51:37,136 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR-WB 2016-04-24 12:51:37,137 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR-WB 2016-04-24 12:51:37,138 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2016-04-24 12:51:37,139 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2016-04-24 12:51:37,140 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-04-24 12:51:37,141 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-04-24 12:51:37,142 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-04-24 12:51:37,143 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-04-24 12:51:37,144 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-04-24 12:51:37,145 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-04-24 12:51:37,146 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-04-24 12:51:37,148 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-04-24 12:51:37,148 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-04-24 12:51:37,150 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-04-24 12:51:37,151 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iSAC 2016-04-24 12:51:37,152 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iSAC 2016-04-24 12:51:37,153 [INFO] [doorpi.sipphone.from_linphone] Codec speex/32000 fmtp=[vbr=on] number=-1, enabled=0) added to default capabilities. 2016-04-24 12:51:37,154 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-04-24 12:51:37,155 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-04-24 12:51:37,156 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-04-24 12:51:37,157 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-04-24 12:51:37,158 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-04-24 12:51:37,159 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-04-24 12:51:37,160 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-16 2016-04-24 12:51:37,161 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-16 2016-04-24 12:51:37,162 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-24 2016-04-24 12:51:37,163 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-24 2016-04-24 12:51:37,164 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-32 2016-04-24 12:51:37,165 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-32 2016-04-24 12:51:37,166 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-40 2016-04-24 12:51:37,167 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-40 2016-04-24 12:51:37,168 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-16 2016-04-24 12:51:37,169 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-16 2016-04-24 12:51:37,170 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-24 2016-04-24 12:51:37,171 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-24 2016-04-24 12:51:37,173 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-32 2016-04-24 12:51:37,174 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-32 2016-04-24 12:51:37,175 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-40 2016-04-24 12:51:37,176 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-40 2016-04-24 12:51:37,177 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CODEC2 2016-04-24 12:51:37,178 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CODEC2 2016-04-24 12:51:37,179 [INFO] [doorpi.sipphone.from_linphone] Codec VP8/90000 fmtp=[] number=-1, enabled=1) added to default capabilities. 2016-04-24 12:51:37,180 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H264 2016-04-24 12:51:37,181 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H264 2016-04-24 12:51:37,182 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MP4V-ES 2016-04-24 12:51:37,183 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263-1998 2016-04-24 12:51:37,184 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2016-04-24 12:51:37,185 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for 1016 2016-04-24 12:51:37,186 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for 1016 2016-04-24 12:51:37,187 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G723 2016-04-24 12:51:37,188 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G723 2016-04-24 12:51:37,190 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for LPC 2016-04-24 12:51:37,191 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for LPC 2016-04-24 12:51:37,192 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=10, enabled=0) added to default capabilities. 2016-04-24 12:51:37,193 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=11, enabled=0) added to default capabilities. 2016-04-24 12:51:37,194 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CN 2016-04-24 12:51:37,195 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CN 2016-04-24 12:51:37,196 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2016-04-24 12:51:37,197 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2016-04-24 12:51:37,198 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H261 2016-04-24 12:51:37,199 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H261 2016-04-24 12:51:37,200 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MPV 2016-04-24 12:51:37,201 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for MPV 2016-04-24 12:51:37,202 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2016-04-24 12:51:37,204 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-04-24 12:51:37,205 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-04-24 12:51:37,206 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-04-24 12:51:37,207 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-04-24 12:51:37,208 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_configuring_status(0xee4390, 2, "(null)") 2016-04-24 12:51:37,209 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_configuring_status 2016-04-24 12:51:37,211 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0xee4390] notifying [configuring_status] 2016-04-24 12:51:37,212 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/liblinphone/plugins: No such file or directory 2016-04-24 12:51:37,213 [WARNING] [doorpi.sipphone.from_linphone] ./share/sounds/linphone/rings/oldphone.wav does not exist 2016-04-24 12:51:37,214 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_playback_gain_db(): no active call. 2016-04-24 12:51:37,215 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_mic_gain_db(): no active call. 2016-04-24 12:51:37,216 [INFO] [doorpi.sipphone.from_linphone] MTU is supposed to be 1300, rtp payload max size will be 1240 2016-04-24 12:51:37,217 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-04-24 12:51:37,218 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-04-24 12:51:37,219 [INFO] [doorpi.sipphone.from_linphone] Supported codec opus/48000 fmtp=useinbandfec=1 automatically added to codec list. 2016-04-24 12:51:37,220 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/16000 fmtp=vbr=on automatically added to codec list. 2016-04-24 12:51:37,221 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/8000 fmtp=vbr=on automatically added to codec list. 2016-04-24 12:51:37,222 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMU/8000 fmtp= automatically added to codec list. 2016-04-24 12:51:37,223 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMA/8000 fmtp= automatically added to codec list. 2016-04-24 12:51:37,224 [INFO] [doorpi.sipphone.from_linphone] Supported codec GSM/8000 fmtp= automatically added to codec list. 2016-04-24 12:51:37,225 [INFO] [doorpi.sipphone.from_linphone] Supported codec G722/8000 fmtp= automatically added to codec list. 2016-04-24 12:51:37,226 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/32000 fmtp=vbr=on automatically added to codec list. 2016-04-24 12:51:37,228 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2016-04-24 12:51:37,229 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2016-04-24 12:51:37,230 [INFO] [doorpi.sipphone.from_linphone] Supported codec VP8/90000 fmtp= automatically added to codec list. 2016-04-24 12:51:37,231 [INFO] [doorpi.sipphone.from_linphone] Supported codec t140/1000 fmtp= automatically added to codec list. 2016-04-24 12:51:37,232 [INFO] [doorpi.sipphone.from_linphone] Supported codec red/1000 fmtp= automatically added to codec list. 2016-04-24 12:51:37,233 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-04-24 12:51:37,234 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-04-24 12:51:37,235 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2016-04-24 12:51:37,236 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0xee5c18] on [sip:0.0.0.0:5060;transport=UDP] 2016-04-24 12:51:37,238 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0xee5e28] on [sip:0.0.0.0:5060;transport=TCP] 2016-04-24 12:51:37,239 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0xee6000] on [sip:0.0.0.0:-1;transport=TLS] 2016-04-24 12:51:37,239 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 56914 2016-04-24 12:51:37,240 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-04-24 12:51:37,241 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-04-24 12:51:37,246 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0xee4390, 2, "Ready") 2016-04-24 12:51:37,246 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2016-04-24 12:51:37,247 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0xee4390] notifying [global_state_changed] 2016-04-24 12:51:37,248 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_class_method_new -> 0x7482c698 2016-04-24 12:51:37,249 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2016-04-24 12:51:37,250 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_primary_contact(0x7482c698 [0xee4390], "DoorPi ") 2016-04-24 12:51:37,252 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_primary_contact -> 0 2016-04-24 12:51:37,253 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneCreate from doorpi.sipphone.from_linphone asyncron 2016-04-24 12:51:37,256 [TRACE] [doorpi.conf.config_object] get_integer for key ua.max_calls in section SIP-Phone (default: 2) returns 2 2016-04-24 12:51:37,256 [DEBUG] [doorpi.action.handler] no actions for event OnSipPhoneCreate - skip fire_event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2016-04-24 12:51:37,257 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_max_calls(0x7482c698 [0xee4390], 2) 2016-04-24 12:51:37,259 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_max_calls -> 0 2016-04-24 12:51:37,260 [TRACE] [doorpi.conf.config_object] get_boolean for key echo_cancellation_enabled in section SIP-Phone (default: False) returns False 2016-04-24 12:51:37,261 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_echo_cancellation(0x7482c698 [0xee4390], 0x2e0614 [0]) 2016-04-24 12:51:37,262 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_echo_cancellation -> 0 2016-04-24 12:51:37,263 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2016-04-24 12:51:37,264 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2016-04-24 12:51:37,265 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_new() 2016-04-24 12:51:37,266 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_new -> 0x7482c6b0 2016-04-24 12:51:37,267 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_sip_transports(0x7482c698 [0xee4390], 0x7482c6b0 [0x7482c6b8]) 2016-04-24 12:51:37,268 [INFO] [doorpi.sipphone.from_linphone] Listening point [0xee5c18] on [sip:0.0.0.0:5060;transport=UDP] destroyed 2016-04-24 12:51:37,269 [INFO] [doorpi.sipphone.from_linphone] Listening point [0xee5e28] on [sip:0.0.0.0:5060;transport=TCP] destroyed 2016-04-24 12:51:37,270 [INFO] [doorpi.sipphone.from_linphone] Listening point [0xee6000] on [sip:0.0.0.0:56914;transport=TLS] destroyed 2016-04-24 12:51:37,271 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2016-04-24 12:51:37,272 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0xefb200] on [sip:0.0.0.0:5060;transport=UDP] 2016-04-24 12:51:37,273 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0xee5508] on [sip:0.0.0.0:5060;transport=TCP] 2016-04-24 12:51:37,274 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0xefa340] on [sip:0.0.0.0:-1;transport=TLS] 2016-04-24 12:51:37,275 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 51155 2016-04-24 12:51:37,276 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-04-24 12:51:37,276 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-04-24 12:51:37,277 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_sip_transports -> 0 2016-04-24 12:51:37,278 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_dealloc(0x7482c6b0) 2016-04-24 12:51:37,279 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_dealloc 2016-04-24 12:51:37,280 [TRACE] [doorpi.conf.config_object] get_boolean for key video_display_enabled in section SIP-Phone (default: False) returns False 2016-04-24 12:51:37,281 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_display(0x7482c698 [0xee4390], 0x2e0614 [0]) 2016-04-24 12:51:37,282 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_display -> 0 2016-04-24 12:51:37,283 [TRACE] [doorpi.conf.config_object] get_string for key stun_server in section SIP-Phone (default: ) returns 2016-04-24 12:51:37,284 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_stun_server(0x7482c698 [0xee4390], "") 2016-04-24 12:51:37,286 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2016-04-24 12:51:37,286 [INFO] [doorpi.sipphone.from_linphone] 192.168.1.254 2016-04-24 12:51:37,288 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2016-04-24 12:51:37,289 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2016-04-24 12:51:37,290 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_stun_server -> 0 2016-04-24 12:51:37,291 [DEBUG] [doorpi.conf.config_object] create new key FirewallPolicy in section SIP-Phone with value 'PolicyNoFirewall' 2016-04-24 12:51:37,292 [TRACE] [doorpi.conf.config_object] get_string for key FirewallPolicy in section SIP-Phone (default: PolicyNoFirewall) returns PolicyNoFirewall 2016-04-24 12:51:37,293 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_firewall_policy(0x7482c698 [0xee4390], 0) 2016-04-24 12:51:37,294 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-04-24 12:51:37,294 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-04-24 12:51:37,297 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_firewall_policy -> 0 2016-04-24 12:51:37,298 [TRACE] [doorpi.conf.config_object] get_integer for key max_call_time in section SIP-Phone (default: 120) returns 120 2016-04-24 12:51:37,299 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_in_call_timeout(0x7482c698 [0xee4390], 120) 2016-04-24 12:51:37,300 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_in_call_timeout -> 0 2016-04-24 12:51:37,300 [TRACE] [doorpi.conf.config_object] get_integer for key call_timeout in section SIP-Phone (default: 15) returns 15 2016-04-24 12:51:37,301 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_inc_timeout(0x7482c698 [0xee4390], 15) 2016-04-24 12:51:37,302 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_inc_timeout -> 0 2016-04-24 12:51:37,303 [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-04-24 12:51:37,306 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/media/ShortDialTone.wav" to "/usr/local/etc/DoorPi/media/ShortDialTone.wav" 2016-04-24 12:51:37,306 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2016-04-24 12:51:37,309 [TRACE] [doorpi.conf.config_object] get_boolean for key dialtone_renew_every_start in section SIP-Phone (default: False) returns False 2016-04-24 12:51:37,310 [TRACE] [doorpi.action.handler] register Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2016-04-24 12:51:37,311 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was added 2016-04-24 12:51:37,312 [TRACE] [doorpi.action.handler] added event_name OnPlayerStarted and registered source doorpi.sipphone.linphone_lib.Player 2016-04-24 12:51:37,313 [TRACE] [doorpi.action.handler] register Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2016-04-24 12:51:37,313 [TRACE] [doorpi.action.handler] added event_name OnPlayerStopped and registered source doorpi.sipphone.linphone_lib.Player 2016-04-24 12:51:37,314 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2016-04-24 12:51:37,315 [TRACE] [doorpi.action.handler] added event_source doorpi.sipphone.linphone_lib.Player to existing event OnPlayerCreated 2016-04-24 12:51:37,316 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneMakeCall 2016-04-24 12:51:37,316 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateConnect 2016-04-24 12:51:37,317 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateDisconnect 2016-04-24 12:51:37,318 [TRACE] [doorpi.action.handler] fire Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player asyncron 2016-04-24 12:51:37,320 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_ringback(0x7482c698 [0xee4390], "/usr/local/etc/DoorPi/media/ShortDialTone.wav") 2016-04-24 12:51:37,321 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerCreated - skip fire_event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2016-04-24 12:51:37,322 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_ringback -> 0 2016-04-24 12:51:37,324 [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-04-24 12:51:37,325 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2016-04-24 12:51:37,326 [TRACE] [doorpi.action.handler] register Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder 2016-04-24 12:51:37,326 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was added 2016-04-24 12:51:37,327 [TRACE] [doorpi.action.handler] added event_name OnRecorderStarted and registered source doorpi.sipphone.linphone_lib.Recorder 2016-04-24 12:51:37,328 [TRACE] [doorpi.action.handler] register Event OnRecorderStopped from doorpi.sipphone.linphone_lib.Recorder 2016-04-24 12:51:37,329 [TRACE] [doorpi.action.handler] added event_name OnRecorderStopped and registered source doorpi.sipphone.linphone_lib.Recorder 2016-04-24 12:51:37,329 [TRACE] [doorpi.action.handler] register Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2016-04-24 12:51:37,330 [TRACE] [doorpi.action.handler] added event_name OnRecorderCreated and registered source doorpi.sipphone.linphone_lib.Recorder 2016-04-24 12:51:37,331 [TRACE] [doorpi.conf.config_object] get_boolean for key record_while_dialing in section SIP-Phone (default: False) returns False 2016-04-24 12:51:37,332 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateConnect 2016-04-24 12:51:37,333 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateDisconnect 2016-04-24 12:51:37,334 [TRACE] [doorpi.action.handler] fire Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder asyncron 2016-04-24 12:51:37,335 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x7482c698 [0xee4390]) 2016-04-24 12:51:37,337 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x747b7800 2016-04-24 12:51:37,338 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0x7482c698 [0xee4390]) 2016-04-24 12:51:37,339 [DEBUG] [doorpi.action.handler] no actions for event OnRecorderCreated - skip fire_event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2016-04-24 12:51:37,339 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0x74846e60 2016-04-24 12:51:37,341 [TRACE] [doorpi.conf.config_object] get_string for key capture_device in section SIP-Phone (default: ALSA: default device) returns C-Media USB Headphone Set 2016-04-24 12:51:37,342 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_capture_device(0x7482c698 [0xee4390], "C-Media USB Headphone Set") 2016-04-24 12:51:37,343 [WARNING] [doorpi.sipphone.from_linphone] no card with id C-Media USB Headphone Set 2016-04-24 12:51:37,343 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_capture_device -> 0 2016-04-24 12:51:37,344 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0x7482c698 [0xee4390]) 2016-04-24 12:51:37,345 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0x74846e60 2016-04-24 12:51:37,346 [TRACE] [doorpi.conf.config_object] get_string for key playback_device in section SIP-Phone (default: ALSA: default device) returns C-Media USB Headphone Set 2016-04-24 12:51:37,347 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_playback_device(0x7482c698 [0xee4390], "C-Media USB Headphone Set") 2016-04-24 12:51:37,348 [WARNING] [doorpi.sipphone.from_linphone] no card with id C-Media USB Headphone Set 2016-04-24 12:51:37,349 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_playback_device -> 0 2016-04-24 12:51:37,350 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x7482c698 [0xee4390]) 2016-04-24 12:51:37,350 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x747b77b0 2016-04-24 12:51:37,351 [INFO] [doorpi.sipphone.from_linphone] found 3 possible sounddevices: 2016-04-24 12:51:37,352 [DEBUG] [doorpi.sipphone.from_linphone] |rec|play| name 2016-04-24 12:51:37,353 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-04-24 12:51:37,354 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x7482c698 [0xee4390]) 2016-04-24 12:51:37,355 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x747b77b0 2016-04-24 12:51:37,356 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x7482c698 [0xee4390], "ALSA: default device") 2016-04-24 12:51:37,357 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0620 2016-04-24 12:51:37,357 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x7482c698 [0xee4390], "ALSA: default device") 2016-04-24 12:51:37,358 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2016-04-24 12:51:37,359 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: default device 2016-04-24 12:51:37,360 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x7482c698 [0xee4390], "ALSA: bcm2835 ALSA") 2016-04-24 12:51:37,361 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0614 2016-04-24 12:51:37,362 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x7482c698 [0xee4390], "ALSA: bcm2835 ALSA") 2016-04-24 12:51:37,362 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2016-04-24 12:51:37,363 [DEBUG] [doorpi.sipphone.from_linphone] | O | X | ALSA: bcm2835 ALSA 2016-04-24 12:51:37,364 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x7482c698 [0xee4390], "ALSA: C-Media USB Headphone Set") 2016-04-24 12:51:37,365 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0620 2016-04-24 12:51:37,366 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x7482c698 [0xee4390], "ALSA: C-Media USB Headphone Set") 2016-04-24 12:51:37,367 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2016-04-24 12:51:37,367 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: C-Media USB Headphone Set 2016-04-24 12:51:37,368 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-04-24 12:51:37,369 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0x7482c698 [0xee4390]) 2016-04-24 12:51:37,370 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0x74846ef0 2016-04-24 12:51:37,371 [DEBUG] [doorpi.sipphone.from_linphone] using capture_device: ALSA: default device 2016-04-24 12:51:37,372 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0x7482c698 [0xee4390]) 2016-04-24 12:51:37,372 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0x74846f20 2016-04-24 12:51:37,373 [DEBUG] [doorpi.sipphone.from_linphone] using playback_device: ALSA: default device 2016-04-24 12:51:37,374 [TRACE] [doorpi.conf.config_object] get_list for key audio_codecs in section SIP-Phone (default: PCMA,PCMU) returns ['PCMA', 'PCMU'] 2016-04-24 12:51:37,375 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_audio_codecs(0x7482c698 [0xee4390]) 2016-04-24 12:51:37,376 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xee4f78) 2016-04-24 12:51:37,377 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-04-24 12:51:37,378 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d143e0 2016-04-24 12:51:37,379 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-04-24 12:51:37,380 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d143e0 2016-04-24 12:51:37,380 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d143e0 2016-04-24 12:51:37,381 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xee4fe8) 2016-04-24 12:51:37,382 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-04-24 12:51:37,383 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d143f0 2016-04-24 12:51:37,384 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-04-24 12:51:37,385 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d143f0 2016-04-24 12:51:37,386 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d143f0 2016-04-24 12:51:37,387 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xee5050) 2016-04-24 12:51:37,388 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-04-24 12:51:37,388 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d14400 2016-04-24 12:51:37,389 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-04-24 12:51:37,390 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d14400 2016-04-24 12:51:37,391 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d14400 2016-04-24 12:51:37,392 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xee50b8) 2016-04-24 12:51:37,393 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-04-24 12:51:37,394 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d14410 2016-04-24 12:51:37,394 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-04-24 12:51:37,395 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d14410 2016-04-24 12:51:37,396 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d14410 2016-04-24 12:51:37,397 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xee5200) 2016-04-24 12:51:37,398 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-04-24 12:51:37,399 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d14420 2016-04-24 12:51:37,400 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-04-24 12:51:37,401 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d14420 2016-04-24 12:51:37,402 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d14420 2016-04-24 12:51:37,402 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xee5258) 2016-04-24 12:51:37,403 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-04-24 12:51:37,404 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d14430 2016-04-24 12:51:37,405 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-04-24 12:51:37,406 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d14430 2016-04-24 12:51:37,407 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d14430 2016-04-24 12:51:37,408 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xee52b0) 2016-04-24 12:51:37,409 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-04-24 12:51:37,409 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d14440 2016-04-24 12:51:37,410 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-04-24 12:51:37,411 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d14440 2016-04-24 12:51:37,412 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d14440 2016-04-24 12:51:37,413 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xee5308) 2016-04-24 12:51:37,414 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-04-24 12:51:37,415 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d14450 2016-04-24 12:51:37,415 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-04-24 12:51:37,416 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d14450 2016-04-24 12:51:37,417 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d14450 2016-04-24 12:51:37,418 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xee5370) 2016-04-24 12:51:37,419 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-04-24 12:51:37,420 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d14460 2016-04-24 12:51:37,421 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-04-24 12:51:37,422 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d14460 2016-04-24 12:51:37,423 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d14460 2016-04-24 12:51:37,424 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xee53c8) 2016-04-24 12:51:37,425 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-04-24 12:51:37,425 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d14470 2016-04-24 12:51:37,426 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-04-24 12:51:37,427 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d14470 2016-04-24 12:51:37,428 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d14470 2016-04-24 12:51:37,429 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_audio_codecs -> 0x747b78a0 2016-04-24 12:51:37,430 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d143e0 [0xee4f78]) 2016-04-24 12:51:37,431 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74847b20 2016-04-24 12:51:37,432 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d143e0 [0xee4f78]) 2016-04-24 12:51:37,433 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74847b20 2016-04-24 12:51:37,434 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec opus 2016-04-24 12:51:37,434 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x7482c698 [0xee4390], 0x76d143e0 [0xee4f78], 0x2e0614 [0]) 2016-04-24 12:51:37,436 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xd6f8bc 2016-04-24 12:51:37,437 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d143f0 [0xee4fe8]) 2016-04-24 12:51:37,438 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74847b00 2016-04-24 12:51:37,438 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d143f0 [0xee4fe8]) 2016-04-24 12:51:37,439 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74847b00 2016-04-24 12:51:37,440 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-04-24 12:51:37,441 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x7482c698 [0xee4390], 0x76d143f0 [0xee4fe8], 0x2e0614 [0]) 2016-04-24 12:51:37,442 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xd6f8bc 2016-04-24 12:51:37,443 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d14400 [0xee5050]) 2016-04-24 12:51:37,444 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74847b20 2016-04-24 12:51:37,445 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d14400 [0xee5050]) 2016-04-24 12:51:37,446 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74847b20 2016-04-24 12:51:37,446 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-04-24 12:51:37,447 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x7482c698 [0xee4390], 0x76d14400 [0xee5050], 0x2e0614 [0]) 2016-04-24 12:51:37,449 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xd6f8bc 2016-04-24 12:51:37,450 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d14410 [0xee50b8]) 2016-04-24 12:51:37,450 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74847b00 2016-04-24 12:51:37,451 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d14410 [0xee50b8]) 2016-04-24 12:51:37,452 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74847b00 2016-04-24 12:51:37,453 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMU 2016-04-24 12:51:37,454 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x7482c698 [0xee4390], 0x76d14410 [0xee50b8], 0x2e0620 [1]) 2016-04-24 12:51:37,455 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xd6f8bc 2016-04-24 12:51:37,456 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d14420 [0xee5200]) 2016-04-24 12:51:37,457 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74847b20 2016-04-24 12:51:37,458 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d14420 [0xee5200]) 2016-04-24 12:51:37,459 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74847b20 2016-04-24 12:51:37,459 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMA 2016-04-24 12:51:37,460 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x7482c698 [0xee4390], 0x76d14420 [0xee5200], 0x2e0620 [1]) 2016-04-24 12:51:37,461 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xd6f8bc 2016-04-24 12:51:37,462 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d14430 [0xee5258]) 2016-04-24 12:51:37,463 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7482c6b0 2016-04-24 12:51:37,464 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d14430 [0xee5258]) 2016-04-24 12:51:37,465 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7482c6b0 2016-04-24 12:51:37,466 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec GSM 2016-04-24 12:51:37,467 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x7482c698 [0xee4390], 0x76d14430 [0xee5258], 0x2e0614 [0]) 2016-04-24 12:51:37,468 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xd6f8bc 2016-04-24 12:51:37,469 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d14440 [0xee52b0]) 2016-04-24 12:51:37,469 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74847b20 2016-04-24 12:51:37,470 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d14440 [0xee52b0]) 2016-04-24 12:51:37,471 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74847b20 2016-04-24 12:51:37,472 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec G722 2016-04-24 12:51:37,473 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x7482c698 [0xee4390], 0x76d14440 [0xee52b0], 0x2e0614 [0]) 2016-04-24 12:51:37,474 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xd6f8bc 2016-04-24 12:51:37,475 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d14450 [0xee5308]) 2016-04-24 12:51:37,476 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74847b00 2016-04-24 12:51:37,477 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d14450 [0xee5308]) 2016-04-24 12:51:37,478 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74847b00 2016-04-24 12:51:37,478 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-04-24 12:51:37,479 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x7482c698 [0xee4390], 0x76d14450 [0xee5308], 0x2e0614 [0]) 2016-04-24 12:51:37,480 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xd6f8bc 2016-04-24 12:51:37,481 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d14460 [0xee5370]) 2016-04-24 12:51:37,482 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7482c6b0 2016-04-24 12:51:37,483 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d14460 [0xee5370]) 2016-04-24 12:51:37,484 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7482c6b0 2016-04-24 12:51:37,485 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2016-04-24 12:51:37,486 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x7482c698 [0xee4390], 0x76d14460 [0xee5370], 0x2e0614 [0]) 2016-04-24 12:51:37,487 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xd6f8bc 2016-04-24 12:51:37,488 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d14470 [0xee53c8]) 2016-04-24 12:51:37,488 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7482c6b0 2016-04-24 12:51:37,489 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d14470 [0xee53c8]) 2016-04-24 12:51:37,490 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7482c6b0 2016-04-24 12:51:37,491 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2016-04-24 12:51:37,492 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x7482c698 [0xee4390], 0x76d14470 [0xee53c8], 0x2e0614 [0]) 2016-04-24 12:51:37,493 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xd6f8bc 2016-04-24 12:51:37,494 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x7482c698 [0xee4390]) 2016-04-24 12:51:37,495 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x747b78f0 2016-04-24 12:51:37,496 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x7482c698 [0xee4390]) 2016-04-24 12:51:37,497 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x747b78f0 2016-04-24 12:51:37,497 [INFO] [doorpi.sipphone.from_linphone] found 1 possible videodevices: 2016-04-24 12:51:37,498 [DEBUG] [doorpi.sipphone.from_linphone] | name 2016-04-24 12:51:37,499 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-04-24 12:51:37,500 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x7482c698 [0xee4390]) 2016-04-24 12:51:37,501 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x747b78f0 2016-04-24 12:51:37,501 [DEBUG] [doorpi.sipphone.from_linphone] | StaticImage: Static picture 2016-04-24 12:51:37,502 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-04-24 12:51:37,503 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x7482c698 [0xee4390]) 2016-04-24 12:51:37,504 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x747b78f0 2016-04-24 12:51:37,505 [TRACE] [doorpi.conf.config_object] get_string for key video_device in section SIP-Phone (default: StaticImage: Static picture) returns StaticImage: Static picture 2016-04-24 12:51:37,506 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x7482c698 [0xee4390]) 2016-04-24 12:51:37,506 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x747b78f0 2016-04-24 12:51:37,507 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_capture(0x7482c698 [0xee4390], 0x2e0620 [1]) 2016-04-24 12:51:37,508 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_capture -> 0 2016-04-24 12:51:37,509 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_video_device(0x7482c698 [0xee4390], "StaticImage: Static picture") 2016-04-24 12:51:37,510 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_video_device -> 0 2016-04-24 12:51:37,511 [TRACE] [doorpi.conf.config_object] get_string for key video_size in section SIP-Phone (default: vga) returns vga 2016-04-24 12:51:37,512 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_preferred_video_size_by_name(0x7482c698 [0xee4390], "vga") 2016-04-24 12:51:37,513 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_preferred_video_size_by_name -> 0 2016-04-24 12:51:37,514 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_device(0x7482c698 [0xee4390]) 2016-04-24 12:51:37,515 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_device -> 0x747bd050 2016-04-24 12:51:37,515 [DEBUG] [doorpi.sipphone.from_linphone] using video_device: StaticImage: Static picture 2016-04-24 12:51:37,516 [TRACE] [doorpi.conf.config_object] get_list for key video_codecs in section SIP-Phone (default: VP8) returns ['VP8'] 2016-04-24 12:51:37,517 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_codecs(0x7482c698 [0xee4390]) 2016-04-24 12:51:37,518 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xee4b70) 2016-04-24 12:51:37,519 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-04-24 12:51:37,520 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d14480 2016-04-24 12:51:37,521 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-04-24 12:51:37,522 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d14480 2016-04-24 12:51:37,523 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d14480 2016-04-24 12:51:37,524 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_codecs -> 0x747b7878 2016-04-24 12:51:37,524 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d14480 [0xee4b70]) 2016-04-24 12:51:37,525 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7482c6b0 2016-04-24 12:51:37,526 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_video_capture_enabled(0x7482c698 [0xee4390]) 2016-04-24 12:51:37,527 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_video_capture_enabled -> 0x2e0620 2016-04-24 12:51:37,528 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d14480 [0xee4b70]) 2016-04-24 12:51:37,529 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7482c6b0 2016-04-24 12:51:37,530 [DEBUG] [doorpi.sipphone.from_linphone] enable video codec VP8 2016-04-24 12:51:37,531 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x7482c698 [0xee4390], 0x76d14480 [0xee4b70], 0x2e0620 [1]) 2016-04-24 12:51:37,532 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xd6f8bc 2016-04-24 12:51:37,533 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_server in section SIP-Phone (default: ) returns 192.168.1.4 2016-04-24 12:51:37,533 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_username in section SIP-Phone (default: ) returns Haupteingang 2016-04-24 12:51:37,534 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_password in section SIP-Phone (default: Haupteingang) returns ******* 2016-04-24 12:51:37,535 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_realm in section SIP-Phone (default: 192.168.1.4) returns 2016-04-24 12:51:37,536 [INFO] [doorpi.sipphone.from_linphone] using DoorPi with SIP-Server 2016-04-24 12:51:37,537 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_proxy_config(0x7482c698 [0xee4390]) 2016-04-24 12:51:37,538 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_from_native_ptr(0xf23750) 2016-04-24 12:51:37,539 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_new() 2016-04-24 12:51:37,540 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_new -> 0x76d14490 2016-04-24 12:51:37,540 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_init() 2016-04-24 12:51:37,541 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_init -> 0x76d14490 2016-04-24 12:51:37,542 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_from_native_ptr -> 0x76d14490 2016-04-24 12:51:37,543 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_proxy_config -> 0x76d14490 2016-04-24 12:51:37,544 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2016-04-24 12:51:37,545 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_class_method_new("DoorPi ") 2016-04-24 12:51:37,547 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0xf24400) 2016-04-24 12:51:37,548 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2016-04-24 12:51:37,549 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0x76d144a0 2016-04-24 12:51:37,550 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2016-04-24 12:51:37,551 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0x76d144a0 2016-04-24 12:51:37,552 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0x76d144a0 2016-04-24 12:51:37,553 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_class_method_new -> 0x76d144a0 2016-04-24 12:51:37,554 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_identity_address(0x76d14490 [0xf23750], 0x76d144a0 [0xf24400]) 2016-04-24 12:51:37,555 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_identity_address -> 0 2016-04-24 12:51:37,556 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_server_addr(0x76d14490 [0xf23750], "sip:192.168.1.4") 2016-04-24 12:51:37,558 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_server_addr -> 0 2016-04-24 12:51:37,559 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_enable_register(0x76d14490 [0xf23750], 0x2e0620 [1]) 2016-04-24 12:51:37,560 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_enable_register -> 0 2016-04-24 12:51:37,561 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_proxy_config(0x7482c698 [0xee4390], 0x76d14490 [0xf23750]) 2016-04-24 12:51:37,563 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_proxy_config -> 0xd6f8bc 2016-04-24 12:51:37,564 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_default_proxy_config(0x7482c698 [0xee4390], 0x76d14490 [0xf23750]) 2016-04-24 12:51:37,565 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_default_proxy_config -> 0 2016-04-24 12:51:37,565 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_auth_info(0x7482c698 [0xee4390], "Haupteingang", "(null)", "1234", "(null)", "(null)", "") 2016-04-24 12:51:37,566 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_from_native_ptr(0xf24850) 2016-04-24 12:51:37,567 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_new() 2016-04-24 12:51:37,568 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_new -> 0x76d144b0 2016-04-24 12:51:37,569 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_init() 2016-04-24 12:51:37,570 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_init -> 0x76d144b0 2016-04-24 12:51:37,571 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_from_native_ptr -> 0x76d144b0 2016-04-24 12:51:37,572 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_auth_info -> 0x76d144b0 2016-04-24 12:51:37,573 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_auth_info(0x7482c698 [0xee4390], 0x76d144b0 [0xf24850]) 2016-04-24 12:51:37,574 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_auth_info -> None 2016-04-24 12:51:37,575 [DEBUG] [doorpi.sipphone.from_linphone] start successfully 2016-04-24 12:51:37,575 [TRACE] [doorpi.conf.config_object] get_sections returns ['EVENT_OnStartup'] 2016-04-24 12:51:37,576 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnStartup' in configfile 2016-04-24 12:51:37,577 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnStartup returns ['10'] 2016-04-24 12:51:37,578 [INFO] [doorpi.doorpi] registering action '10' for event 'OnStartup' 2016-04-24 12:51:37,579 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnStartup (default: ) returns sleep:1 2016-04-24 12:51:37,581 [DEBUG] [doorpi.action.SingleActions.sleep] doorpi.action.SingleActions.sleep loaded 2016-04-24 12:51:37,582 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to new evententry OnStartup 2016-04-24 12:51:37,583 [TRACE] [doorpi.conf.config_object] get_string for key 7 in section onboardpins_InputPins (default: ) returns call:604 2016-04-24 12:51:37,585 [DEBUG] [doorpi.action.SingleActions.call] doorpi.action.SingleActions.call loaded 2016-04-24 12:51:37,586 [TRACE] [doorpi.action.handler] action CallAction with args () and kwargs {'number': '604'} was added to new evententry OnKeyPressed_onboardpins.7 2016-04-24 12:51:37,587 [WARNING] [root] section DTMF not found in configfile 2016-04-24 12:51:37,588 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns [] 2016-04-24 12:51:37,589 [TRACE] [doorpi.conf.config_object] get_string for key is_alive_led in section DoorPi (default: ) returns Blink LED 2016-04-24 12:51:37,590 [DEBUG] [doorpi.action.SingleActions.out] doorpi.action.SingleActions.out loaded 2016-04-24 12:51:37,592 [DEBUG] [doorpi.action.SingleActions.out_triggered] doorpi.action.SingleActions.out_triggered loaded 2016-04-24 12:51:37,594 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': 'False', 'value': 'HIGH', 'pin': 'Blink LED'} was added to new evententry OnTimeSecondEvenNumber 2016-04-24 12:51:37,595 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': 'False', 'value': 'LOW', 'pin': 'Blink LED'} was added to new evententry OnTimeSecondUnevenNumber 2016-04-24 12:51:37,596 [TRACE] [doorpi.action.handler] fire Event BeforeStartup from doorpi.doorpi asyncron 2016-04-24 12:51:37,600 [DEBUG] [doorpi.action.handler] no actions for event BeforeStartup - skip fire_event BeforeStartup from doorpi.doorpi 2016-04-24 12:51:37,601 [DEBUG] [doorpi.action.handler] [ZNVDFW] fire for event OnStartup this actions [] 2016-04-24 12:51:37,602 [TRACE] [doorpi.action.handler] [ZNVDFW] try to fire action SleepAction with args (1.0,) and kwargs {} 2016-04-24 12:51:37,603 [TRACE] [doorpi.action.base] run SleepAction with args (1.0,) and kwargs {} 2016-04-24 12:51:38,606 [TRACE] [doorpi.action.handler] [ZNVDFW] finished fire_event for event_name OnStartup 2016-04-24 12:51:38,608 [TRACE] [doorpi.action.handler] fire Event AfterStartup from doorpi.doorpi asyncron 2016-04-24 12:51:38,611 [INFO] [doorpi.doorpi] DoorPi started successfully 2016-04-24 12:51:38,612 [DEBUG] [doorpi.action.handler] no actions for event AfterStartup - skip fire_event AfterStartup from doorpi.doorpi 2016-04-24 12:51:38,613 [INFO] [doorpi.doorpi] BasePath is /usr/local/etc/DoorPi 2016-04-24 12:51:38,616 [INFO] [doorpi.doorpi] Weburl is http://raspberrypi/ 2016-04-24 12:51:38,619 [INFO] [doorpi.sipphone.from_linphone] resolved to 192.168.1.254 2016-04-24 12:51:38,621 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2016-04-24 12:51:38,623 [INFO] [doorpi.sipphone.from_linphone] New local ip address is 192.168.1.26 2016-04-24 12:51:38,625 [INFO] [doorpi.sipphone.from_linphone] Network state is now [UP] 2016-04-24 12:51:38,627 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2016-04-24 12:51:38,629 [INFO] [doorpi.sipphone.from_linphone] 192.168.1.254 2016-04-24 12:51:38,632 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2016-04-24 12:51:38,634 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2016-04-24 12:51:38,637 [INFO] [doorpi.sipphone.from_linphone] LinphoneProxyConfig [0xf23750] about to register (LinphoneCore version: 3.9.0) 2016-04-24 12:51:38,649 [INFO] [doorpi.sipphone.from_linphone] belle_sip_client_transaction_send_request(): waiting channel to be ready 2016-04-24 12:51:38,650 [INFO] [doorpi.sipphone.from_linphone] channel [0xefd088]: starting resolution of 192.168.1.4 2016-04-24 12:51:38,651 [INFO] [doorpi.sipphone.from_linphone] channel 0xefd088: state RES_IN_PROGRESS 2016-04-24 12:51:38,652 [INFO] [doorpi.sipphone.from_linphone] transaction [0xf21898] channel state changed to [RES_IN_PROGRESS] 2016-04-24 12:51:38,653 [INFO] [doorpi.sipphone.from_linphone] channel 0xefd088: state RES_DONE 2016-04-24 12:51:38,653 [INFO] [doorpi.sipphone.from_linphone] transaction [0xf21898] channel state changed to [RES_DONE] 2016-04-24 12:51:38,654 [INFO] [doorpi.sipphone.from_linphone] channel 0xefd088: state CONNECTING 2016-04-24 12:51:38,655 [INFO] [doorpi.sipphone.from_linphone] transaction [0xf21898] channel state changed to [CONNECTING] 2016-04-24 12:51:38,656 [INFO] [doorpi.sipphone.from_linphone] Trying to connect to [UDP://192.168.1.4:5060] 2016-04-24 12:51:38,657 [INFO] [doorpi.sipphone.from_linphone] belle_sip_get_src_addr_for(): af_inet6=0 2016-04-24 12:51:38,658 [INFO] [doorpi.sipphone.from_linphone] Channel has local address 192.168.1.26:5060 2016-04-24 12:51:38,659 [INFO] [doorpi.sipphone.from_linphone] channel 0xefd088: state READY 2016-04-24 12:51:38,660 [INFO] [doorpi.sipphone.from_linphone] transaction [0xf21898] channel state changed to [READY] 2016-04-24 12:51:38,661 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0xf21898], from state [INIT] to [TRYING] 2016-04-24 12:51:38,662 [INFO] [doorpi.sipphone.from_linphone] channel [0xefd088]: message sent to [UDP://192.168.1.4:5060], size: [531] bytes REGISTER sip:192.168.1.4 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.26:5060;branch=z9hG4bK.b7zBzbzCU;rport From: "DoorPi" ;tag=hlfZ77eFb To: "DoorPi" CSeq: 20 REGISTER Call-ID: 3XdT~ym8cF 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-04-24 12:51:38,664 [INFO] [doorpi.sipphone.from_linphone] Neither Expires header nor corresponding Contact header found, checking from original request 2016-04-24 12:51:38,665 [INFO] [doorpi.sipphone.from_linphone] Refresher [0xf27d98] takes ownership of transaction [0xf21898] 2016-04-24 12:51:38,665 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0xf23750] for identity ["DoorPi" ] moving from state [LinphoneRegistrationNone] to [LinphoneRegistrationProgress] 2016-04-24 12:51:38,666 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0xee4390, 0xf23750, 1, "Registration in progress") 2016-04-24 12:51:38,667 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2016-04-24 12:51:38,668 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0xee4390] notifying [registration_state_changed] 2016-04-24 12:51:38,720 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_network_reachable(0xee4390, 0x1) 2016-04-24 12:51:38,721 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_network_reachable 2016-04-24 12:51:38,722 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0xee4390] notifying [network_reachable] 2016-04-24 12:51:38,723 [INFO] [doorpi.sipphone.from_linphone] channel [0xefd088]: received [451] new bytes from [UDP://192.168.1.4:5060]: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.1.26:5060;branch=z9hG4bK.b7zBzbzCU;rport=5060;received=192.168.1.26 From: "DoorPi" ;tag=hlfZ77eFb To: "DoorPi" ;tag=2428637051 Call-ID: 3XdT~ym8cF CSeq: 20 REGISTER Content-Length: 0 Date: Sun, 04 Jan 1970 22:32:24 GMT WWW-Authenticate: Digest realm="192.168.1.4",nonce="21f2caf8210500013f310090331e0e69",qop="auth",algorithm=MD5 2016-04-24 12:51:38,737 [INFO] [doorpi.sipphone.from_linphone] channel [0xefd088] [451] bytes parsed 2016-04-24 12:51:38,738 [INFO] [doorpi.sipphone.from_linphone] channel [0xefd088]: discovered public ip and port are [192.168.1.26:5060] 2016-04-24 12:51:38,739 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2016-04-24 12:51:38,740 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0xf21898], from state [TRYING] to [COMPLETED] 2016-04-24 12:51:38,741 [INFO] [doorpi.sipphone.from_linphone] linphone_core_find_auth_info(): returning auth info username=Haupteingang, realm= 2016-04-24 12:51:38,742 [INFO] [doorpi.sipphone.from_linphone] Auth info found for [Haupteingang] realm [192.168.1.4] 2016-04-24 12:51:38,743 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0xf24390], from state [INIT] to [TRYING] 2016-04-24 12:51:38,745 [INFO] [doorpi.sipphone.from_linphone] channel [0xefd088]: message sent to [UDP://192.168.1.4:5060], size: [768] bytes REGISTER sip:192.168.1.4 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.26:5060;branch=z9hG4bK.saNUaCNp0;rport From: "DoorPi" ;tag=hlfZ77eFb To: "DoorPi" CSeq: 21 REGISTER Call-ID: 3XdT~ym8cF Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) Authorization: Digest realm="192.168.1.4", nonce="21f2caf8210500013f310090331e0e69", algorithm=MD5, username="Haupteingang", uri="sip:192.168.1.4", response="13d1ccca98871bca945de220b624d2ac", cnonce="87e4e680", nc=00000001, qop=auth 2016-04-24 12:51:38,747 [INFO] [doorpi.sipphone.from_linphone] resolved to 192.168.1.254 2016-04-24 12:51:38,748 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2016-04-24 12:51:38,800 [INFO] [doorpi.sipphone.from_linphone] channel [0xefd088]: received [692] new bytes from [UDP://192.168.1.4:5060]: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.26:5060;branch=z9hG4bK.saNUaCNp0;rport=5060;received=192.168.1.26 From: "DoorPi" ;tag=hlfZ77eFb To: "DoorPi" ;tag=2428637052 Call-ID: 3XdT~ym8cF CSeq: 21 REGISTER Contact: ;expires=120 Allow: REGISTER,SUBSCRIBE,NOTIFY,INVITE,ACK,PRACK,OPTIONS,BYE,CANCEL,REFER,INFO,UPDATE,PUBLISH Content-Length: 0 Date: Sun, 04 Jan 1970 22:32:24 GMT Expires: 120 Server: (innovaphone IP302/10.00 sr32 [10.1365/101365/306]) Allow-Events: reg,dialog,message-summary,presence Presence-State: register-action="added" P-Alias: 2,12,Haupteingang P-Alias: 0,3,602 2016-04-24 12:51:38,816 [INFO] [doorpi.sipphone.from_linphone] channel [0xefd088] [692] bytes parsed 2016-04-24 12:51:38,817 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2016-04-24 12:51:38,818 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0xf24390], from state [TRYING] to [COMPLETED] 2016-04-24 12:51:38,819 [INFO] [doorpi.sipphone.from_linphone] Refresher [0xf27d98]: has no contact for request [0xf21718]. 2016-04-24 12:51:38,820 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 108000 ms 2016-04-24 12:51:38,821 [INFO] [doorpi.sipphone.from_linphone] Register refresher [200] reason [OK] for proxy [sip:192.168.1.4] 2016-04-24 12:51:38,822 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0xf23750] for identity ["DoorPi" ] moving from state [LinphoneRegistrationProgress] to [LinphoneRegistrationOk] 2016-04-24 12:51:38,823 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0xee4390, 0xf23750, 2, "Registration successful") 2016-04-24 12:51:38,824 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2016-04-24 12:51:38,825 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0xee4390] notifying [registration_state_changed] 2016-04-24 12:51:43,788 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0xf21898], from state [COMPLETED] to [TERMINATED] 2016-04-24 12:51:43,791 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0xf21898] terminated 2016-04-24 12:51:43,843 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0xf24390], from state [COMPLETED] to [TERMINATED] 2016-04-24 12:51:43,845 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0xf24390] terminated