2022-03-02 18:47:54,441 [INFO] [doorpi.main] ___ ___ _ / \___ ___ _ __ / _ (_) VoIP Door-Intercomstation with Raspberry Pi / /\ / _ \ / _ \| '__/ /_)/ | version: 2.5.1 / /_// (_) | (_) | | / ___/| | license: CC BY-NC 4.0 /___,' \___/ \___/|_| \/ |_| URL: Authors: Thomas Meissner Supporter: Phillip Munz Hermann Dötsch Dennis Häußler Hubert Nusser Michael Hauer Andreas Schwarz Max Rößler missing someone? -> sorry -> mail me 2022-03-02 18:47:54,442 [DEBUG] [doorpi.main] loaded with arguments: ['/usr/local/bin/doorpi_cli', '--trace'] 2022-03-02 18:47:54,443 [DEBUG] [doorpi.doorpi] run 2022-03-02 18:47:54,443 [DEBUG] [doorpi.doorpi] prepare 2022-03-02 18:47:54,443 [DEBUG] [doorpi.doorpi] given arguments argv: Namespace(configfile=None, debug=False, test=False, trace=True) 2022-03-02 18:47:54,445 [INFO] [doorpi.conf.config_object] use configfile: /usr/local/etc/DoorPi/conf/doorpi.ini 2022-03-02 18:47:54,450 [DEBUG] [doorpi.conf.config_object] __init__ 2022-03-02 18:47:54,450 [TRACE] [doorpi.conf.config_object] get_from_config 2022-03-02 18:47:54,454 [TRACE] [doorpi.conf.config_object] get_string for key base_path in section DoorPi (default: /usr/local/etc/DoorPi) returns /usr/local/etc/DoorPi 2022-03-02 18:47:54,455 [TRACE] [doorpi.conf.config_object] get_string for key eventlog in section DoorPi (default: !BASEPATH!/conf/eventlog.db) returns !BASEPATH!/conf/eventlog.db 2022-03-02 18:47:54,455 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/conf/eventlog.db" to "/usr/local/etc/DoorPi/conf/eventlog.db" 2022-03-02 18:47:54,457 [TRACE] [doorpi.action.handler] register Event BeforeStartup from doorpi.doorpi 2022-03-02 18:47:54,458 [DEBUG] [doorpi.action.handler] event_source doorpi.doorpi was added 2022-03-02 18:47:54,458 [TRACE] [doorpi.action.handler] added event_name BeforeStartup and registered source doorpi.doorpi 2022-03-02 18:47:54,458 [TRACE] [doorpi.action.handler] register Event OnStartup from doorpi.doorpi 2022-03-02 18:47:54,458 [TRACE] [doorpi.action.handler] added event_name OnStartup and registered source doorpi.doorpi 2022-03-02 18:47:54,459 [TRACE] [doorpi.action.handler] register Event AfterStartup from doorpi.doorpi 2022-03-02 18:47:54,459 [TRACE] [doorpi.action.handler] added event_name AfterStartup and registered source doorpi.doorpi 2022-03-02 18:47:54,459 [TRACE] [doorpi.action.handler] register Event BeforeShutdown from doorpi.doorpi 2022-03-02 18:47:54,459 [TRACE] [doorpi.action.handler] added event_name BeforeShutdown and registered source doorpi.doorpi 2022-03-02 18:47:54,459 [TRACE] [doorpi.action.handler] register Event OnShutdown from doorpi.doorpi 2022-03-02 18:47:54,460 [TRACE] [doorpi.action.handler] added event_name OnShutdown and registered source doorpi.doorpi 2022-03-02 18:47:54,460 [TRACE] [doorpi.action.handler] register Event AfterShutdown from doorpi.doorpi 2022-03-02 18:47:54,460 [TRACE] [doorpi.action.handler] added event_name AfterShutdown and registered source doorpi.doorpi 2022-03-02 18:47:54,461 [DEBUG] [doorpi.action.SingleActions.time_tick] doorpi.action.SingleActions.time_tick loaded 2022-03-02 18:47:54,462 [DEBUG] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was added 2022-03-02 18:47:54,464 [TRACE] [doorpi.action.handler] action TimeTickDestroyAction with args () and kwargs {} was added to new evententry OnShutdown 2022-03-02 18:47:54,464 [TRACE] [doorpi.action.handler] action TimeTickAction with args ('!last_tick!',) and kwargs {} was added to new evententry OnTimeTick 2022-03-02 18:47:54,464 [TRACE] [doorpi.conf.config_object] get_string for key ip in section DoorPiWeb (default: ) returns 2022-03-02 18:47:54,464 [TRACE] [doorpi.conf.config_object] get_integer for key port in section DoorPiWeb (default: 80) returns 80 2022-03-02 18:47:54,466 [INFO] [doorpi.status.webserver] Initiating WebService at ip and port 80 2022-03-02 18:47:54,467 [TRACE] [doorpi.action.handler] register Event OnWebServerStart from doorpi.status.webserver 2022-03-02 18:47:54,467 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver was added 2022-03-02 18:47:54,467 [TRACE] [doorpi.action.handler] added event_name OnWebServerStart and registered source doorpi.status.webserver 2022-03-02 18:47:54,467 [TRACE] [doorpi.action.handler] register Event OnWebServerStop from doorpi.status.webserver 2022-03-02 18:47:54,468 [TRACE] [doorpi.action.handler] added event_name OnWebServerStop and registered source doorpi.status.webserver 2022-03-02 18:47:54,468 [TRACE] [doorpi.conf.config_object] get_string for key www in section DoorPiWeb (default: !BASEPATH!/../DoorPiWeb) returns !BASEPATH!/../DoorPiWeb 2022-03-02 18:47:54,468 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/../DoorPiWeb" to "/usr/local/etc/DoorPi/../DoorPiWeb" 2022-03-02 18:47:54,469 [TRACE] [doorpi.conf.config_object] get_string for key indexfile in section DoorPiWeb (default: index.html) returns index.html 2022-03-02 18:47:54,469 [DEBUG] [doorpi.conf.config_object] parse string "index.html" to "index.html" 2022-03-02 18:47:54,469 [TRACE] [doorpi.conf.config_object] get_string for key loginfile in section DoorPiWeb (default: login.html) returns login.html 2022-03-02 18:47:54,470 [DEBUG] [doorpi.conf.config_object] parse string "login.html" to "login.html" 2022-03-02 18:47:54,470 [TRACE] [doorpi.conf.config_object] get_string for key public in section DoorPiWeb (default: AREA_public) returns AREA_public 2022-03-02 18:47:54,471 [DEBUG] [doorpi.conf.config_object] parse string "AREA_public" to "AREA_public" 2022-03-02 18:47:54,471 [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 2022-03-02 18:47:54,472 [DEBUG] [doorpi.conf.config_object] parse string "http://motom001.github.io/DoorPiWeb" to "http://motom001.github.io/DoorPiWeb" 2022-03-02 18:47:54,472 [TRACE] [doorpi.conf.config_object] get_keys for section WritePermission returns ['administrator'] 2022-03-02 18:47:54,472 [WARNING] [root] section ReadPermission not found in configfile 2022-03-02 18:47:54,473 [TRACE] [doorpi.conf.config_object] get_keys for section ReadPermission returns [] 2022-03-02 18:47:54,473 [TRACE] [doorpi.conf.config_object] get_keys for section Group returns ['administrator'] 2022-03-02 18:47:54,473 [TRACE] [doorpi.conf.config_object] get_keys for section User returns ['door'] 2022-03-02 18:47:54,473 [TRACE] [doorpi.conf.config_object] get_list for key administrator in section Group (default: []) returns ['door'] 2022-03-02 18:47:54,474 [TRACE] [doorpi.conf.config_object] get_sections returns ['onboardpins_OutputPins', 'webservice_keyboard', 'onboardpins_keyboard', 'Group', 'keyboards', 'AdminNumbers', 'SIP-Phone', 'WritePermission', 'AREA_installer', 'EVENT_OnCallStateDisconnect', 'EVENT_OnStartup', 'User', 'DoorPiWeb', 'EVENT_OnKeyUp_onboardpins.36', 'EVENT_OnKeyUp_onboardpins.31', 'onboardpins_InputPins', 'EVENT_OnCallStateConnect', 'DoorPi'] 2022-03-02 18:47:54,474 [TRACE] [doorpi.conf.config_object] get_list for key administrator in section WritePermission (default: []) returns ['installer'] 2022-03-02 18:47:54,474 [ERROR] [doorpi.status.webserver] no ReadPermission found 2022-03-02 18:47:54,474 [TRACE] [doorpi.action.handler] action WebServerStartupAction with args () and kwargs {} was added to new evententry OnWebServerStart 2022-03-02 18:47:54,475 [TRACE] [doorpi.action.handler] action WebServerShutdownAction with args () and kwargs {} was added to event OnShutdown 2022-03-02 18:47:54,475 [TRACE] [doorpi.action.handler] fire Event OnWebServerStart from doorpi.status.webserver asyncron 2022-03-02 18:47:54,476 [TRACE] [doorpi.action.handler] register Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2022-03-02 18:47:54,477 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was added 2022-03-02 18:47:54,477 [DEBUG] [doorpi.action.handler] [21D85I] fire for event OnWebServerStart this actions [] 2022-03-02 18:47:54,477 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequest and registered source doorpi.status.webserver_lib.request_handler 2022-03-02 18:47:54,478 [TRACE] [doorpi.action.handler] [21D85I] try to fire action WebServerStartupAction with args () and kwargs {} 2022-03-02 18:47:54,478 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2022-03-02 18:47:54,478 [TRACE] [doorpi.action.base] run WebServerStartupAction with args () and kwargs {} 2022-03-02 18:47:54,478 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestGet and registered source doorpi.status.webserver_lib.request_handler 2022-03-02 18:47:54,479 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2022-03-02 18:47:54,479 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestPost and registered source doorpi.status.webserver_lib.request_handler 2022-03-02 18:47:54,479 [TRACE] [doorpi.action.handler] register Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2022-03-02 18:47:54,480 [TRACE] [doorpi.action.handler] added event_name OnWebServerVirtualResource and registered source doorpi.status.webserver_lib.request_handler 2022-03-02 18:47:54,480 [TRACE] [doorpi.action.handler] register Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler 2022-03-02 18:47:54,480 [TRACE] [doorpi.action.handler] added event_name OnWebServerRealResource and registered source doorpi.status.webserver_lib.request_handler 2022-03-02 18:47:54,480 [TRACE] [doorpi.action.handler] register Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2022-03-02 18:47:54,480 [TRACE] [doorpi.action.handler] added event_name OnFireEvent and registered source doorpi.status.webserver_lib.request_handler 2022-03-02 18:47:54,481 [TRACE] [doorpi.action.handler] register Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2022-03-02 18:47:54,481 [TRACE] [doorpi.action.handler] added event_name OnConfigKeySet and registered source doorpi.status.webserver_lib.request_handler 2022-03-02 18:47:54,481 [TRACE] [doorpi.action.handler] register Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2022-03-02 18:47:54,481 [TRACE] [doorpi.action.handler] added event_name OnConfigKeyDelete and registered source doorpi.status.webserver_lib.request_handler 2022-03-02 18:47:54,482 [TRACE] [doorpi.conf.config_object] get_keys for section keyboards returns ['onboardpins'] 2022-03-02 18:47:54,482 [INFO] [doorpi.keyboard.KeyboardInterface] using multi-keyboard mode (keyboards: onboardpins) 2022-03-02 18:47:54,482 [INFO] [doorpi.keyboard.KeyboardInterface] trying to add keyboard 'onboardpins' to handler 2022-03-02 18:47:54,482 [TRACE] [doorpi.conf.config_object] get_string for key onboardpins in section keyboards (default: dummy) returns gpio 2022-03-02 18:47:54,483 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_InputPins returns ['31', '36'] 2022-03-02 18:47:54,483 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns ['32', '29'] 2022-03-02 18:47:54,483 [TRACE] [doorpi.conf.config_object] get_integer for key bouncetime in section onboardpins_keyboard (default: 2000) returns 200.0 2022-03-02 18:47:54,483 [TRACE] [doorpi.conf.config_object] get_integer for key polarity in section onboardpins_keyboard (default: 0) returns 0 2022-03-02 18:47:54,484 [TRACE] [doorpi.conf.config_object] get_boolean for key pressed_on_keydown in section onboardpins_keyboard (default: True) returns True 2022-03-02 18:47:54,484 [DEBUG] [doorpi.keyboard.from_gpio] doorpi.keyboard.from_gpio loaded 2022-03-02 18:47:54,494 [DEBUG] [doorpi.keyboard.from_gpio] __init__(input_pins = ['31', '36'], output_pins = ['32', '29'], bouncetime = 200.0, polarity = 0) 2022-03-02 18:47:54,495 [TRACE] [doorpi.conf.config_object] get_string for key mode in section onboardpins_keyboard (default: BOARD) returns BOARD 2022-03-02 18:47:54,496 [TRACE] [doorpi.conf.config_object] get_string for key pull_up_down in section onboardpins_keyboard (default: PUD_OFF) returns PUD_OFF 2022-03-02 18:47:54,499 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_gpio 2022-03-02 18:47:54,499 [DEBUG] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was added 2022-03-02 18:47:54,500 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed and registered source doorpi.keyboard.from_gpio 2022-03-02 18:47:54,500 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_31 from doorpi.keyboard.from_gpio 2022-03-02 18:47:54,500 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_31 and registered source doorpi.keyboard.from_gpio 2022-03-02 18:47:54,500 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.31 from doorpi.keyboard.from_gpio 2022-03-02 18:47:54,501 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.31 and registered source doorpi.keyboard.from_gpio 2022-03-02 18:47:54,501 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_gpio 2022-03-02 18:47:54,501 [TRACE] [doorpi.action.handler] added event_name OnKeyUp and registered source doorpi.keyboard.from_gpio 2022-03-02 18:47:54,502 [TRACE] [doorpi.action.handler] register Event OnKeyUp_31 from doorpi.keyboard.from_gpio 2022-03-02 18:47:54,502 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_31 and registered source doorpi.keyboard.from_gpio 2022-03-02 18:47:54,502 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.31 from doorpi.keyboard.from_gpio 2022-03-02 18:47:54,503 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.31 and registered source doorpi.keyboard.from_gpio 2022-03-02 18:47:54,503 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_gpio 2022-03-02 18:47:54,503 [TRACE] [doorpi.action.handler] added event_name OnKeyDown and registered source doorpi.keyboard.from_gpio 2022-03-02 18:47:54,503 [TRACE] [doorpi.action.handler] register Event OnKeyDown_31 from doorpi.keyboard.from_gpio 2022-03-02 18:47:54,504 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_31 and registered source doorpi.keyboard.from_gpio 2022-03-02 18:47:54,504 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.31 from doorpi.keyboard.from_gpio 2022-03-02 18:47:54,504 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.31 and registered source doorpi.keyboard.from_gpio 2022-03-02 18:47:54,505 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_gpio 2022-03-02 18:47:54,506 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_gpio is already known 2022-03-02 18:47:54,506 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_36 from doorpi.keyboard.from_gpio 2022-03-02 18:47:54,507 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_36 and registered source doorpi.keyboard.from_gpio 2022-03-02 18:47:54,508 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.36 from doorpi.keyboard.from_gpio 2022-03-02 18:47:54,508 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.36 and registered source doorpi.keyboard.from_gpio 2022-03-02 18:47:54,509 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_gpio 2022-03-02 18:47:54,509 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_gpio is already known 2022-03-02 18:47:54,510 [TRACE] [doorpi.action.handler] register Event OnKeyUp_36 from doorpi.keyboard.from_gpio 2022-03-02 18:47:54,510 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_36 and registered source doorpi.keyboard.from_gpio 2022-03-02 18:47:54,510 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.36 from doorpi.keyboard.from_gpio 2022-03-02 18:47:54,511 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.36 and registered source doorpi.keyboard.from_gpio 2022-03-02 18:47:54,511 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_gpio 2022-03-02 18:47:54,513 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_gpio is already known 2022-03-02 18:47:54,513 [TRACE] [doorpi.action.handler] register Event OnKeyDown_36 from doorpi.keyboard.from_gpio 2022-03-02 18:47:54,514 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_36 and registered source doorpi.keyboard.from_gpio 2022-03-02 18:47:54,514 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.36 from doorpi.keyboard.from_gpio 2022-03-02 18:47:54,514 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.36 and registered source doorpi.keyboard.from_gpio 2022-03-02 18:47:54,517 [TRACE] [doorpi.action.handler] action KeyboardDestroyAction with args () and kwargs {} was added to event OnShutdown 2022-03-02 18:47:54,517 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns ['32', '29'] 2022-03-02 18:47:54,518 [TRACE] [doorpi.conf.config_object] get_string for key 32 in section onboardpins_OutputPins (default: ) returns LEDTalk 2022-03-02 18:47:54,519 [TRACE] [doorpi.conf.config_object] get_string for key 29 in section onboardpins_OutputPins (default: ) returns NamLight 2022-03-02 18:47:54,520 [DEBUG] [doorpi.status.status_lib.environment] doorpi.status.status_lib.environment loaded 2022-03-02 18:47:54,522 [DEBUG] [doorpi.status.requirements_lib.req_config] doorpi.status.requirements_lib.req_config loaded 2022-03-02 18:47:54,524 [DEBUG] [doorpi.status.requirements_lib.req_sipphone] doorpi.status.requirements_lib.req_sipphone loaded 2022-03-02 18:47:54,525 [DEBUG] [doorpi.sipphone.AbstractBaseClass] doorpi.sipphone.AbstractBaseClass loaded 2022-03-02 18:47:54,560 [DEBUG] [doorpi.status.requirements_lib.req_event_handler] doorpi.status.requirements_lib.req_event_handler loaded 2022-03-02 18:47:54,561 [DEBUG] [doorpi.status.requirements_lib.req_webserver] doorpi.status.requirements_lib.req_webserver loaded 2022-03-02 18:47:54,563 [DEBUG] [doorpi.status.requirements_lib.req_keyboard] doorpi.status.requirements_lib.req_keyboard loaded 2022-03-02 18:47:54,612 [DEBUG] [doorpi.status.requirements_lib.req_system] doorpi.status.requirements_lib.req_system loaded 2022-03-02 18:47:54,619 [INFO] [doorpi.sipphone.SipphoneInterface] found installed sipphone "linphone" and use this as default 2022-03-02 18:47:54,620 [TRACE] [doorpi.conf.config_object] get_string for key sipphonetyp in section SIP-Phone (default: linphone) returns linphone 2022-03-02 18:47:54,621 [DEBUG] [doorpi.sipphone.from_linphone] doorpi.sipphone.from_linphone loaded 2022-03-02 18:47:54,625 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] doorpi.sipphone.linphone_lib.CallBacks loaded 2022-03-02 18:47:54,627 [DEBUG] [doorpi.sipphone.linphone_lib.Player] doorpi.sipphone.linphone_lib.Player loaded 2022-03-02 18:47:54,630 [DEBUG] [doorpi.sipphone.linphone_lib.Recorder] doorpi.sipphone.linphone_lib.Recorder loaded 2022-03-02 18:47:54,631 [DEBUG] [doorpi.sipphone.from_linphone] __init__ 2022-03-02 18:47:54,632 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnShutdown 2022-03-02 18:47:54,633 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2022-03-02 18:47:54,633 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was added 2022-03-02 18:47:54,634 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCreate and registered source doorpi.sipphone.from_linphone 2022-03-02 18:47:54,634 [TRACE] [doorpi.action.handler] register Event OnSipPhoneStart from doorpi.sipphone.from_linphone 2022-03-02 18:47:54,635 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneStart and registered source doorpi.sipphone.from_linphone 2022-03-02 18:47:54,635 [TRACE] [doorpi.action.handler] register Event OnSipPhoneDestroy from doorpi.sipphone.from_linphone 2022-03-02 18:47:54,636 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneDestroy and registered source doorpi.sipphone.from_linphone 2022-03-02 18:47:54,636 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderCreate from doorpi.sipphone.from_linphone 2022-03-02 18:47:54,636 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderCreate and registered source doorpi.sipphone.from_linphone 2022-03-02 18:47:54,637 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderDestroy from doorpi.sipphone.from_linphone 2022-03-02 18:47:54,637 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderDestroy and registered source doorpi.sipphone.from_linphone 2022-03-02 18:47:54,638 [TRACE] [doorpi.action.handler] register Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2022-03-02 18:47:54,639 [TRACE] [doorpi.action.handler] added event_name BeforeSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2022-03-02 18:47:54,639 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone 2022-03-02 18:47:54,640 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2022-03-02 18:47:54,640 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2022-03-02 18:47:54,641 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCallFailed and registered source doorpi.sipphone.from_linphone 2022-03-02 18:47:54,641 [TRACE] [doorpi.action.handler] register Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2022-03-02 18:47:54,641 [TRACE] [doorpi.action.handler] added event_name AfterSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2022-03-02 18:47:54,642 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutNoResponse from doorpi.sipphone.from_linphone 2022-03-02 18:47:54,642 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutNoResponse and registered source doorpi.sipphone.from_linphone 2022-03-02 18:47:54,643 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutMaxCalltime from doorpi.sipphone.from_linphone 2022-03-02 18:47:54,643 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutMaxCalltime and registered source doorpi.sipphone.from_linphone 2022-03-02 18:47:54,644 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.from_linphone 2022-03-02 18:47:54,644 [TRACE] [doorpi.action.handler] added event_name OnPlayerCreated and registered source doorpi.sipphone.from_linphone 2022-03-02 18:47:54,645 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] __init__ 2022-03-02 18:47:54,645 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneDestroy 2022-03-02 18:47:54,646 [TRACE] [doorpi.action.handler] register Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,646 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was added 2022-03-02 18:47:54,647 [TRACE] [doorpi.action.handler] added event_name OnCallMediaStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,647 [TRACE] [doorpi.action.handler] register Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,648 [TRACE] [doorpi.action.handler] added event_name OnMediaRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,648 [TRACE] [doorpi.action.handler] register Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,649 [TRACE] [doorpi.action.handler] added event_name OnMediaNotRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,649 [TRACE] [doorpi.action.handler] register Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,650 [TRACE] [doorpi.action.handler] added event_name OnCallStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,650 [TRACE] [doorpi.action.handler] register Event OnCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,651 [TRACE] [doorpi.action.handler] added event_name OnCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,651 [TRACE] [doorpi.action.handler] register Event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,652 [TRACE] [doorpi.action.handler] added event_name AfterCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,653 [TRACE] [doorpi.action.handler] register Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,653 [TRACE] [doorpi.action.handler] added event_name OnCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,654 [TRACE] [doorpi.action.handler] register Event AfterCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,654 [TRACE] [doorpi.action.handler] added event_name AfterCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,655 [TRACE] [doorpi.action.handler] register Event OnCallStateDismissed from doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,655 [TRACE] [doorpi.action.handler] added event_name OnCallStateDismissed and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,656 [TRACE] [doorpi.action.handler] register Event OnCallStateReject from doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,656 [TRACE] [doorpi.action.handler] added event_name OnCallStateReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,657 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,657 [TRACE] [doorpi.action.handler] added event_name OnCallStart and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,658 [TRACE] [doorpi.action.handler] register Event OnDTMF from doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,658 [TRACE] [doorpi.action.handler] added event_name OnDTMF and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,659 [WARNING] [root] section DTMF not found in configfile 2022-03-02 18:47:54,659 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns [] 2022-03-02 18:47:54,660 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,660 [TRACE] [doorpi.action.handler] nothing to do - event OnCallStart from source doorpi.sipphone.linphone_lib.CallBacks is already known 2022-03-02 18:47:54,661 [TRACE] [doorpi.action.handler] register Event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,661 [TRACE] [doorpi.action.handler] added event_name BeforeCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,662 [TRACE] [doorpi.action.handler] register Event OnCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,662 [TRACE] [doorpi.action.handler] added event_name OnCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,663 [TRACE] [doorpi.action.handler] register Event AfterCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,663 [TRACE] [doorpi.action.handler] added event_name AfterCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,664 [TRACE] [doorpi.action.handler] register Event OnCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,664 [TRACE] [doorpi.action.handler] added event_name OnCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,665 [TRACE] [doorpi.action.handler] register Event AfterCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,665 [TRACE] [doorpi.action.handler] added event_name AfterCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,666 [TRACE] [doorpi.action.handler] register Event OnCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,666 [TRACE] [doorpi.action.handler] added event_name OnCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,667 [TRACE] [doorpi.action.handler] register Event AfterCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,667 [TRACE] [doorpi.action.handler] added event_name AfterCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,668 [TRACE] [doorpi.action.handler] register Event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,668 [TRACE] [doorpi.action.handler] added event_name OnCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,669 [TRACE] [doorpi.action.handler] register Event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,669 [TRACE] [doorpi.action.handler] added event_name AfterCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,670 [TRACE] [doorpi.action.handler] fire Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks asyncron 2022-03-02 18:47:54,673 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_new() 2022-03-02 18:47:54,674 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_new -> 0x74048830 2022-03-02 18:47:54,675 [DEBUG] [doorpi.action.handler] no actions for event OnCallStart - skip fire_event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2022-03-02 18:47:54,675 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_init() 2022-03-02 18:47:54,676 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_init -> 0x74048830 2022-03-02 18:47:54,677 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_class_method_new(0x7406b390, "(null)", "(null)") 2022-03-02 18:47:54,677 [INFO] [doorpi.sipphone.from_linphone] Mediastreamer2 factory 2.12.0 (git: 3.9.0) initialized. 2022-03-02 18:47:54,679 [INFO] [doorpi.sipphone.from_linphone] CPU count set to 4 2022-03-02 18:47:54,680 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init() done: platform_tags=linux,arm,embedded 2022-03-02 18:47:54,680 [INFO] [doorpi.sipphone.from_linphone] srtp init 2022-03-02 18:47:54,699 [INFO] [doorpi.sipphone.from_linphone] Registering all soundcard handlers 2022-03-02 18:47:54,746 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: default device' added 2022-03-02 18:47:54,764 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: IQaudIOCODEC' added 2022-03-02 18:47:54,765 [INFO] [doorpi.sipphone.from_linphone] Registering all webcam handlers 2022-03-02 18:47:54,766 [INFO] [doorpi.sipphone.from_linphone] Webcam V4L2: /dev/video0 added 2022-03-02 18:47:54,767 [INFO] [doorpi.sipphone.from_linphone] Webcam StaticImage: Static picture added 2022-03-02 18:47:54,767 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init_voip() done 2022-03-02 18:47:54,768 [INFO] [doorpi.sipphone.from_linphone] Loading ms plugins from [./lib/mediastreamer/plugins] 2022-03-02 18:47:54,769 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/mediastreamer/plugins: No such file or directory 2022-03-02 18:47:54,769 [INFO] [doorpi.sipphone.from_linphone] Initializing LinphoneCore 3.9.0 2022-03-02 18:47:54,770 [INFO] [doorpi.sipphone.from_linphone] Vtable [0x1ecae28] registered on core [0x1ebf6f0] 2022-03-02 18:47:54,770 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x1ecae28, 1, "Starting up") 2022-03-02 18:47:54,771 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2022-03-02 18:47:54,771 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1ecae28] notifying [global_state_changed] 2022-03-02 18:47:54,772 [INFO] [doorpi.sipphone.from_linphone] oRTP-0.25.0 initialized. 2022-03-02 18:47:54,773 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_base_init, because [2] ref 2022-03-02 18:47:54,773 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_voip_init, because [2] ref 2022-03-02 18:47:54,774 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_plugins_init, because [2] ref 2022-03-02 18:47:54,774 [INFO] [doorpi.sipphone.from_linphone] Codec opus/48000 fmtp=[useinbandfec=1] number=-1, enabled=1) added to default capabilities. 2022-03-02 18:47:54,775 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2022-03-02 18:47:54,776 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2022-03-02 18:47:54,776 [INFO] [doorpi.sipphone.from_linphone] Codec speex/16000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2022-03-02 18:47:54,777 [INFO] [doorpi.sipphone.from_linphone] Codec speex/8000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2022-03-02 18:47:54,777 [INFO] [doorpi.sipphone.from_linphone] Codec PCMU/8000 fmtp=[] number=0, enabled=1) added to default capabilities. 2022-03-02 18:47:54,778 [INFO] [doorpi.sipphone.from_linphone] Codec PCMA/8000 fmtp=[] number=8, enabled=1) added to default capabilities. 2022-03-02 18:47:54,778 [INFO] [doorpi.sipphone.from_linphone] Codec t140/1000 fmtp=[] number=96, enabled=1) added to default capabilities. 2022-03-02 18:47:54,779 [INFO] [doorpi.sipphone.from_linphone] Codec red/1000 fmtp=[] number=97, enabled=1) added to default capabilities. 2022-03-02 18:47:54,779 [INFO] [doorpi.sipphone.from_linphone] Codec GSM/8000 fmtp=[] number=3, enabled=0) added to default capabilities. 2022-03-02 18:47:54,780 [INFO] [doorpi.sipphone.from_linphone] Codec G722/8000 fmtp=[] number=9, enabled=0) added to default capabilities. 2022-03-02 18:47:54,781 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iLBC 2022-03-02 18:47:54,781 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iLBC 2022-03-02 18:47:54,782 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR 2022-03-02 18:47:54,783 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR 2022-03-02 18:47:54,783 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR-WB 2022-03-02 18:47:54,784 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR-WB 2022-03-02 18:47:54,784 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2022-03-02 18:47:54,785 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2022-03-02 18:47:54,785 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2022-03-02 18:47:54,786 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2022-03-02 18:47:54,786 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2022-03-02 18:47:54,787 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2022-03-02 18:47:54,787 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2022-03-02 18:47:54,788 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2022-03-02 18:47:54,789 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2022-03-02 18:47:54,789 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2022-03-02 18:47:54,790 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2022-03-02 18:47:54,790 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2022-03-02 18:47:54,791 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iSAC 2022-03-02 18:47:54,791 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iSAC 2022-03-02 18:47:54,792 [INFO] [doorpi.sipphone.from_linphone] Codec speex/32000 fmtp=[vbr=on] number=-1, enabled=0) added to default capabilities. 2022-03-02 18:47:54,793 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2022-03-02 18:47:54,793 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2022-03-02 18:47:54,794 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2022-03-02 18:47:54,794 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2022-03-02 18:47:54,795 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2022-03-02 18:47:54,795 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2022-03-02 18:47:54,796 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-16 2022-03-02 18:47:54,796 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-16 2022-03-02 18:47:54,797 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-24 2022-03-02 18:47:54,797 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-24 2022-03-02 18:47:54,799 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-32 2022-03-02 18:47:54,799 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-32 2022-03-02 18:47:54,800 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-40 2022-03-02 18:47:54,800 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-40 2022-03-02 18:47:54,801 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-16 2022-03-02 18:47:54,803 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-16 2022-03-02 18:47:54,804 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-24 2022-03-02 18:47:54,805 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-24 2022-03-02 18:47:54,805 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-32 2022-03-02 18:47:54,806 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-32 2022-03-02 18:47:54,806 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-40 2022-03-02 18:47:54,807 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-40 2022-03-02 18:47:54,807 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CODEC2 2022-03-02 18:47:54,808 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CODEC2 2022-03-02 18:47:54,809 [INFO] [doorpi.sipphone.from_linphone] Codec VP8/90000 fmtp=[] number=-1, enabled=1) added to default capabilities. 2022-03-02 18:47:54,809 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H264 2022-03-02 18:47:54,810 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H264 2022-03-02 18:47:54,810 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MP4V-ES 2022-03-02 18:47:54,811 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263-1998 2022-03-02 18:47:54,811 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2022-03-02 18:47:54,812 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for 1016 2022-03-02 18:47:54,812 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for 1016 2022-03-02 18:47:54,813 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G723 2022-03-02 18:47:54,814 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G723 2022-03-02 18:47:54,814 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for LPC 2022-03-02 18:47:54,815 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for LPC 2022-03-02 18:47:54,815 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=10, enabled=0) added to default capabilities. 2022-03-02 18:47:54,816 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=11, enabled=0) added to default capabilities. 2022-03-02 18:47:54,816 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CN 2022-03-02 18:47:54,817 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CN 2022-03-02 18:47:54,817 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2022-03-02 18:47:54,818 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2022-03-02 18:47:54,819 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H261 2022-03-02 18:47:54,819 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H261 2022-03-02 18:47:54,820 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MPV 2022-03-02 18:47:54,820 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for MPV 2022-03-02 18:47:54,821 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2022-03-02 18:47:54,821 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2022-03-02 18:47:54,822 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2022-03-02 18:47:54,823 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2022-03-02 18:47:54,823 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2022-03-02 18:47:54,824 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_configuring_status(0x1ecae28, 2, "(null)") 2022-03-02 18:47:54,824 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_configuring_status 2022-03-02 18:47:54,825 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1ecae28] notifying [configuring_status] 2022-03-02 18:47:54,825 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/liblinphone/plugins: No such file or directory 2022-03-02 18:47:54,826 [WARNING] [doorpi.sipphone.from_linphone] ./share/sounds/linphone/rings/oldphone.wav does not exist 2022-03-02 18:47:54,827 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_playback_gain_db(): no active call. 2022-03-02 18:47:54,827 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_mic_gain_db(): no active call. 2022-03-02 18:47:54,828 [INFO] [doorpi.sipphone.from_linphone] MTU is supposed to be 1300, rtp payload max size will be 1240 2022-03-02 18:47:54,828 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2022-03-02 18:47:54,829 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2022-03-02 18:47:54,829 [INFO] [doorpi.sipphone.from_linphone] Supported codec opus/48000 fmtp=useinbandfec=1 automatically added to codec list. 2022-03-02 18:47:54,830 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/16000 fmtp=vbr=on automatically added to codec list. 2022-03-02 18:47:54,831 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/8000 fmtp=vbr=on automatically added to codec list. 2022-03-02 18:47:54,831 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMU/8000 fmtp= automatically added to codec list. 2022-03-02 18:47:54,832 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMA/8000 fmtp= automatically added to codec list. 2022-03-02 18:47:54,832 [INFO] [doorpi.sipphone.from_linphone] Supported codec GSM/8000 fmtp= automatically added to codec list. 2022-03-02 18:47:54,833 [INFO] [doorpi.sipphone.from_linphone] Supported codec G722/8000 fmtp= automatically added to codec list. 2022-03-02 18:47:54,833 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/32000 fmtp=vbr=on automatically added to codec list. 2022-03-02 18:47:54,834 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2022-03-02 18:47:54,835 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2022-03-02 18:47:54,835 [INFO] [doorpi.sipphone.from_linphone] Supported codec VP8/90000 fmtp= automatically added to codec list. 2022-03-02 18:47:54,836 [INFO] [doorpi.sipphone.from_linphone] Supported codec t140/1000 fmtp= automatically added to codec list. 2022-03-02 18:47:54,836 [INFO] [doorpi.sipphone.from_linphone] Supported codec red/1000 fmtp= automatically added to codec list. 2022-03-02 18:47:54,837 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2022-03-02 18:47:54,837 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2022-03-02 18:47:54,839 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2022-03-02 18:47:54,839 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x1e97f28] on [sip:0.0.0.0:5060;transport=UDP] 2022-03-02 18:47:54,840 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x1e9ae28] on [sip:0.0.0.0:5060;transport=TCP] 2022-03-02 18:47:54,841 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x1ee6020] on [sip:0.0.0.0:-1;transport=TLS] 2022-03-02 18:47:54,842 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 33777 2022-03-02 18:47:54,842 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2022-03-02 18:47:54,843 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2022-03-02 18:47:54,848 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x1ecae28, 2, "Ready") 2022-03-02 18:47:54,848 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2022-03-02 18:47:54,849 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1ecae28] notifying [global_state_changed] 2022-03-02 18:47:54,850 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_class_method_new -> 0x74048830 2022-03-02 18:47:54,850 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2022-03-02 18:47:54,851 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_primary_contact(0x74048830 [0x1ecae28], "DoorPi ") 2022-03-02 18:47:54,852 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_primary_contact -> 0 2022-03-02 18:47:54,853 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneCreate from doorpi.sipphone.from_linphone asyncron 2022-03-02 18:47:54,855 [TRACE] [doorpi.conf.config_object] get_integer for key ua.max_calls in section SIP-Phone (default: 2) returns 2 2022-03-02 18:47:54,856 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_max_calls(0x74048830 [0x1ecae28], 2) 2022-03-02 18:47:54,856 [DEBUG] [doorpi.action.handler] no actions for event OnSipPhoneCreate - skip fire_event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2022-03-02 18:47:54,857 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_max_calls -> 0 2022-03-02 18:47:54,858 [TRACE] [doorpi.conf.config_object] get_boolean for key echo_cancellation_enabled in section SIP-Phone (default: False) returns False 2022-03-02 18:47:54,858 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_echo_cancellation(0x74048830 [0x1ecae28], 0x2a3420 [0]) 2022-03-02 18:47:54,859 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_echo_cancellation -> 0 2022-03-02 18:47:54,859 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2022-03-02 18:47:54,860 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2022-03-02 18:47:54,860 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_new() 2022-03-02 18:47:54,861 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_new -> 0x74048848 2022-03-02 18:47:54,861 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_sip_transports(0x74048830 [0x1ecae28], 0x74048848 [0x74048850]) 2022-03-02 18:47:54,862 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x1e97f28] on [sip:0.0.0.0:5060;transport=UDP] destroyed 2022-03-02 18:47:54,863 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x1e9ae28] on [sip:0.0.0.0:5060;transport=TCP] destroyed 2022-03-02 18:47:54,863 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x1ee6020] on [sip:0.0.0.0:33777;transport=TLS] destroyed 2022-03-02 18:47:54,864 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2022-03-02 18:47:54,864 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x1e97f28] on [sip:0.0.0.0:5060;transport=UDP] 2022-03-02 18:47:54,865 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x1ed8fd8] on [sip:0.0.0.0:5060;transport=TCP] 2022-03-02 18:47:54,866 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x1ee5b00] on [sip:0.0.0.0:-1;transport=TLS] 2022-03-02 18:47:54,866 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 34455 2022-03-02 18:47:54,867 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2022-03-02 18:47:54,867 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2022-03-02 18:47:54,868 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_sip_transports -> 0 2022-03-02 18:47:54,868 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_dealloc(0x74048848) 2022-03-02 18:47:54,869 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_dealloc 2022-03-02 18:47:54,869 [TRACE] [doorpi.conf.config_object] get_boolean for key video_display_enabled in section SIP-Phone (default: False) returns False 2022-03-02 18:47:54,870 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_display(0x74048830 [0x1ecae28], 0x2a3420 [0]) 2022-03-02 18:47:54,870 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_display -> 0 2022-03-02 18:47:54,871 [TRACE] [doorpi.conf.config_object] get_string for key stun_server in section SIP-Phone (default: ) returns 2022-03-02 18:47:54,871 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_stun_server(0x74048830 [0x1ecae28], "") 2022-03-02 18:47:54,872 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2022-03-02 18:47:54,873 [INFO] [doorpi.sipphone.from_linphone] XXX.XXX.XXX.XXX 2022-03-02 18:47:54,874 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2022-03-02 18:47:54,875 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2022-03-02 18:47:54,875 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_stun_server -> 0 2022-03-02 18:47:54,876 [DEBUG] [doorpi.conf.config_object] create new key FirewallPolicy in section SIP-Phone with value 'PolicyNoFirewall' 2022-03-02 18:47:54,876 [TRACE] [doorpi.conf.config_object] get_string for key FirewallPolicy in section SIP-Phone (default: PolicyNoFirewall) returns PolicyNoFirewall 2022-03-02 18:47:54,877 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_firewall_policy(0x74048830 [0x1ecae28], 0) 2022-03-02 18:47:54,877 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2022-03-02 18:47:54,878 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2022-03-02 18:47:54,880 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_firewall_policy -> 0 2022-03-02 18:47:54,881 [TRACE] [doorpi.conf.config_object] get_integer for key max_call_time in section SIP-Phone (default: 120) returns 120 2022-03-02 18:47:54,882 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_in_call_timeout(0x74048830 [0x1ecae28], 120) 2022-03-02 18:47:54,882 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_in_call_timeout -> 0 2022-03-02 18:47:54,883 [TRACE] [doorpi.conf.config_object] get_integer for key call_timeout in section SIP-Phone (default: 15) returns 15 2022-03-02 18:47:54,883 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_inc_timeout(0x74048830 [0x1ecae28], 15) 2022-03-02 18:47:54,884 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_inc_timeout -> 0 2022-03-02 18:47:54,884 [TRACE] [doorpi.conf.config_object] get_string for key dialtone in section SIP-Phone (default: !BASEPATH!/media/ShortDialTone.wav) returns !BASEPATH!/media/ShortDialTone.wav 2022-03-02 18:47:54,886 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/media/ShortDialTone.wav" to "/usr/local/etc/DoorPi/media/ShortDialTone.wav" 2022-03-02 18:47:54,887 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2022-03-02 18:47:54,888 [TRACE] [doorpi.conf.config_object] get_boolean for key dialtone_renew_every_start in section SIP-Phone (default: False) returns False 2022-03-02 18:47:54,889 [TRACE] [doorpi.action.handler] register Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2022-03-02 18:47:54,889 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was added 2022-03-02 18:47:54,890 [TRACE] [doorpi.action.handler] added event_name OnPlayerStarted and registered source doorpi.sipphone.linphone_lib.Player 2022-03-02 18:47:54,890 [TRACE] [doorpi.action.handler] register Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2022-03-02 18:47:54,891 [TRACE] [doorpi.action.handler] added event_name OnPlayerStopped and registered source doorpi.sipphone.linphone_lib.Player 2022-03-02 18:47:54,891 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2022-03-02 18:47:54,892 [TRACE] [doorpi.action.handler] added event_source doorpi.sipphone.linphone_lib.Player to existing event OnPlayerCreated 2022-03-02 18:47:54,892 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneMakeCall 2022-03-02 18:47:54,893 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateConnect 2022-03-02 18:47:54,893 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateDisconnect 2022-03-02 18:47:54,894 [TRACE] [doorpi.action.handler] fire Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player asyncron 2022-03-02 18:47:54,895 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_ringback(0x74048830 [0x1ecae28], "/usr/local/etc/DoorPi/media/ShortDialTone.wav") 2022-03-02 18:47:54,896 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_ringback -> 0 2022-03-02 18:47:54,897 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerCreated - skip fire_event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2022-03-02 18:47:54,897 [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 2022-03-02 18:47:54,898 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2022-03-02 18:47:54,899 [TRACE] [doorpi.action.handler] register Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder 2022-03-02 18:47:54,899 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was added 2022-03-02 18:47:54,900 [TRACE] [doorpi.action.handler] added event_name OnRecorderStarted and registered source doorpi.sipphone.linphone_lib.Recorder 2022-03-02 18:47:54,900 [TRACE] [doorpi.action.handler] register Event OnRecorderStopped from doorpi.sipphone.linphone_lib.Recorder 2022-03-02 18:47:54,900 [TRACE] [doorpi.action.handler] added event_name OnRecorderStopped and registered source doorpi.sipphone.linphone_lib.Recorder 2022-03-02 18:47:54,901 [TRACE] [doorpi.action.handler] register Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2022-03-02 18:47:54,901 [TRACE] [doorpi.action.handler] added event_name OnRecorderCreated and registered source doorpi.sipphone.linphone_lib.Recorder 2022-03-02 18:47:54,902 [TRACE] [doorpi.conf.config_object] get_boolean for key record_while_dialing in section SIP-Phone (default: False) returns False 2022-03-02 18:47:54,903 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateConnect 2022-03-02 18:47:54,903 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateDisconnect 2022-03-02 18:47:54,904 [TRACE] [doorpi.action.handler] fire Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder asyncron 2022-03-02 18:47:54,905 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x74048830 [0x1ecae28]) 2022-03-02 18:47:54,906 [DEBUG] [doorpi.action.handler] no actions for event OnRecorderCreated - skip fire_event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2022-03-02 18:47:54,906 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x74069d28 2022-03-02 18:47:54,907 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0x74048830 [0x1ecae28]) 2022-03-02 18:47:54,908 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0x736c01a0 2022-03-02 18:47:54,908 [TRACE] [doorpi.conf.config_object] get_string for key capture_device in section SIP-Phone (default: ALSA: default device) returns ALSA:USB Audio Device 2022-03-02 18:47:54,909 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_capture_device(0x74048830 [0x1ecae28], "ALSA:USB Audio Device") 2022-03-02 18:47:54,909 [WARNING] [doorpi.sipphone.from_linphone] no card with id ALSA:USB Audio Device 2022-03-02 18:47:54,910 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_capture_device -> 0 2022-03-02 18:47:54,910 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0x74048830 [0x1ecae28]) 2022-03-02 18:47:54,911 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0x736c01a0 2022-03-02 18:47:54,911 [TRACE] [doorpi.conf.config_object] get_string for key playback_device in section SIP-Phone (default: ALSA: default device) returns ALSA:USB Audio Device 2022-03-02 18:47:54,912 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_playback_device(0x74048830 [0x1ecae28], "ALSA:USB Audio Device") 2022-03-02 18:47:54,912 [WARNING] [doorpi.sipphone.from_linphone] no card with id ALSA:USB Audio Device 2022-03-02 18:47:54,913 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_playback_device -> 0 2022-03-02 18:47:54,913 [TRACE] [doorpi.conf.config_object] get_integer for key mic_gain_db in section SIP-Phone (default: 0) returns 0.0 2022-03-02 18:47:54,914 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_mic_gain_db(0x74048830 [0x1ecae28], 0.000000) 2022-03-02 18:47:54,914 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_mic_gain_db(): no active call. 2022-03-02 18:47:54,915 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_mic_gain_db -> 0 2022-03-02 18:47:54,915 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x74048830 [0x1ecae28]) 2022-03-02 18:47:54,916 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x74069d28 2022-03-02 18:47:54,916 [INFO] [doorpi.sipphone.from_linphone] found 2 possible sounddevices: 2022-03-02 18:47:54,917 [DEBUG] [doorpi.sipphone.from_linphone] |rec|play| name 2022-03-02 18:47:54,917 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2022-03-02 18:47:54,918 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x74048830 [0x1ecae28]) 2022-03-02 18:47:54,919 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x74069d28 2022-03-02 18:47:54,920 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x74048830 [0x1ecae28], "ALSA: default device") 2022-03-02 18:47:54,920 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2a3414 2022-03-02 18:47:54,921 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x74048830 [0x1ecae28], "ALSA: default device") 2022-03-02 18:47:54,921 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2a3414 2022-03-02 18:47:54,921 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: default device 2022-03-02 18:47:54,922 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x74048830 [0x1ecae28], "ALSA: IQaudIOCODEC") 2022-03-02 18:47:54,922 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2a3414 2022-03-02 18:47:54,923 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x74048830 [0x1ecae28], "ALSA: IQaudIOCODEC") 2022-03-02 18:47:54,923 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2a3414 2022-03-02 18:47:54,924 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: IQaudIOCODEC 2022-03-02 18:47:54,924 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2022-03-02 18:47:54,925 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0x74048830 [0x1ecae28]) 2022-03-02 18:47:54,925 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0x736c0260 2022-03-02 18:47:54,926 [DEBUG] [doorpi.sipphone.from_linphone] using capture_device: ALSA: default device 2022-03-02 18:47:54,926 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0x74048830 [0x1ecae28]) 2022-03-02 18:47:54,927 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0x736c0230 2022-03-02 18:47:54,927 [DEBUG] [doorpi.sipphone.from_linphone] using playback_device: ALSA: default device 2022-03-02 18:47:54,928 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_mic_gain_db(0x74048830 [0x1ecae28]) 2022-03-02 18:47:54,928 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_mic_gain_db -> 0x1c31d58 2022-03-02 18:47:54,929 [DEBUG] [doorpi.sipphone.from_linphone] mic_gain_db: 0.0 2022-03-02 18:47:54,929 [TRACE] [doorpi.conf.config_object] get_list for key audio_codecs in section SIP-Phone (default: PCMA,PCMU) returns ['PCMA', 'PCMU'] 2022-03-02 18:47:54,930 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_audio_codecs(0x74048830 [0x1ecae28]) 2022-03-02 18:47:54,930 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1e74700) 2022-03-02 18:47:54,931 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2022-03-02 18:47:54,931 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d63b90 2022-03-02 18:47:54,932 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2022-03-02 18:47:54,933 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d63b90 2022-03-02 18:47:54,933 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d63b90 2022-03-02 18:47:54,933 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1ed8d40) 2022-03-02 18:47:54,934 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2022-03-02 18:47:54,934 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d63ba0 2022-03-02 18:47:54,935 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2022-03-02 18:47:54,935 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d63ba0 2022-03-02 18:47:54,936 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d63ba0 2022-03-02 18:47:54,936 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1ed8da8) 2022-03-02 18:47:54,937 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2022-03-02 18:47:54,937 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d63bb0 2022-03-02 18:47:54,938 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2022-03-02 18:47:54,938 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d63bb0 2022-03-02 18:47:54,939 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d63bb0 2022-03-02 18:47:54,939 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1ed8e10) 2022-03-02 18:47:54,940 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2022-03-02 18:47:54,940 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d63bc0 2022-03-02 18:47:54,940 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2022-03-02 18:47:54,941 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d63bc0 2022-03-02 18:47:54,941 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d63bc0 2022-03-02 18:47:54,942 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1ed8e68) 2022-03-02 18:47:54,942 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2022-03-02 18:47:54,943 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d63bd0 2022-03-02 18:47:54,943 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2022-03-02 18:47:54,944 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d63bd0 2022-03-02 18:47:54,944 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d63bd0 2022-03-02 18:47:54,945 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1ed8ec0) 2022-03-02 18:47:54,945 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2022-03-02 18:47:54,946 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d63be0 2022-03-02 18:47:54,946 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2022-03-02 18:47:54,947 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d63be0 2022-03-02 18:47:54,947 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d63be0 2022-03-02 18:47:54,948 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1ed8f18) 2022-03-02 18:47:54,948 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2022-03-02 18:47:54,949 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d63bf0 2022-03-02 18:47:54,949 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2022-03-02 18:47:54,950 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d63bf0 2022-03-02 18:47:54,950 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d63bf0 2022-03-02 18:47:54,950 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1ed8f70) 2022-03-02 18:47:54,951 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2022-03-02 18:47:54,951 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d63c00 2022-03-02 18:47:54,952 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2022-03-02 18:47:54,952 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d63c00 2022-03-02 18:47:54,953 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d63c00 2022-03-02 18:47:54,953 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1ed3990) 2022-03-02 18:47:54,954 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2022-03-02 18:47:54,954 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d63c10 2022-03-02 18:47:54,955 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2022-03-02 18:47:54,955 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d63c10 2022-03-02 18:47:54,956 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d63c10 2022-03-02 18:47:54,956 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1ed39e8) 2022-03-02 18:47:54,957 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2022-03-02 18:47:54,957 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d63c20 2022-03-02 18:47:54,958 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2022-03-02 18:47:54,958 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d63c20 2022-03-02 18:47:54,958 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d63c20 2022-03-02 18:47:54,959 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_audio_codecs -> 0x74069bc0 2022-03-02 18:47:54,959 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d63b90 [0x1e74700]) 2022-03-02 18:47:54,960 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x740684e0 2022-03-02 18:47:54,960 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d63b90 [0x1e74700]) 2022-03-02 18:47:54,962 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x740684e0 2022-03-02 18:47:54,962 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec opus 2022-03-02 18:47:54,963 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x74048830 [0x1ecae28], 0x76d63b90 [0x1e74700], 0x2a3420 [0]) 2022-03-02 18:47:54,963 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x1bfc7ac 2022-03-02 18:47:54,964 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d63ba0 [0x1ed8d40]) 2022-03-02 18:47:54,964 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74068820 2022-03-02 18:47:54,965 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d63ba0 [0x1ed8d40]) 2022-03-02 18:47:54,965 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74068820 2022-03-02 18:47:54,966 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2022-03-02 18:47:54,966 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x74048830 [0x1ecae28], 0x76d63ba0 [0x1ed8d40], 0x2a3420 [0]) 2022-03-02 18:47:54,967 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x1bfc7ac 2022-03-02 18:47:54,968 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d63bb0 [0x1ed8da8]) 2022-03-02 18:47:54,968 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x740684e0 2022-03-02 18:47:54,968 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d63bb0 [0x1ed8da8]) 2022-03-02 18:47:54,969 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x740684e0 2022-03-02 18:47:54,969 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2022-03-02 18:47:54,970 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x74048830 [0x1ecae28], 0x76d63bb0 [0x1ed8da8], 0x2a3420 [0]) 2022-03-02 18:47:54,970 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x1bfc7ac 2022-03-02 18:47:54,971 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d63bc0 [0x1ed8e10]) 2022-03-02 18:47:54,971 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74068820 2022-03-02 18:47:54,972 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d63bc0 [0x1ed8e10]) 2022-03-02 18:47:54,972 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74068820 2022-03-02 18:47:54,973 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMU 2022-03-02 18:47:54,973 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x74048830 [0x1ecae28], 0x76d63bc0 [0x1ed8e10], 0x2a3414 [1]) 2022-03-02 18:47:54,974 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x1bfc7ac 2022-03-02 18:47:54,975 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d63bd0 [0x1ed8e68]) 2022-03-02 18:47:54,975 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x740684e0 2022-03-02 18:47:54,976 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d63bd0 [0x1ed8e68]) 2022-03-02 18:47:54,976 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x740684e0 2022-03-02 18:47:54,976 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMA 2022-03-02 18:47:54,977 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x74048830 [0x1ecae28], 0x76d63bd0 [0x1ed8e68], 0x2a3414 [1]) 2022-03-02 18:47:54,978 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x1bfc7ac 2022-03-02 18:47:54,978 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d63be0 [0x1ed8ec0]) 2022-03-02 18:47:54,979 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74048848 2022-03-02 18:47:54,979 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d63be0 [0x1ed8ec0]) 2022-03-02 18:47:54,980 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74048848 2022-03-02 18:47:54,980 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec GSM 2022-03-02 18:47:54,980 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x74048830 [0x1ecae28], 0x76d63be0 [0x1ed8ec0], 0x2a3420 [0]) 2022-03-02 18:47:54,981 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x1bfc7ac 2022-03-02 18:47:54,982 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d63bf0 [0x1ed8f18]) 2022-03-02 18:47:54,982 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x740684e0 2022-03-02 18:47:54,983 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d63bf0 [0x1ed8f18]) 2022-03-02 18:47:54,983 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x740684e0 2022-03-02 18:47:54,984 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec G722 2022-03-02 18:47:54,984 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x74048830 [0x1ecae28], 0x76d63bf0 [0x1ed8f18], 0x2a3420 [0]) 2022-03-02 18:47:54,985 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x1bfc7ac 2022-03-02 18:47:54,985 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d63c00 [0x1ed8f70]) 2022-03-02 18:47:54,986 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74068820 2022-03-02 18:47:54,986 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d63c00 [0x1ed8f70]) 2022-03-02 18:47:54,987 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74068820 2022-03-02 18:47:54,987 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2022-03-02 18:47:54,988 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x74048830 [0x1ecae28], 0x76d63c00 [0x1ed8f70], 0x2a3420 [0]) 2022-03-02 18:47:54,988 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x1bfc7ac 2022-03-02 18:47:54,989 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d63c10 [0x1ed3990]) 2022-03-02 18:47:54,989 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74048848 2022-03-02 18:47:54,990 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d63c10 [0x1ed3990]) 2022-03-02 18:47:54,990 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74048848 2022-03-02 18:47:54,991 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2022-03-02 18:47:54,991 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x74048830 [0x1ecae28], 0x76d63c10 [0x1ed3990], 0x2a3420 [0]) 2022-03-02 18:47:54,992 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x1bfc7ac 2022-03-02 18:47:54,992 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d63c20 [0x1ed39e8]) 2022-03-02 18:47:54,993 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74048848 2022-03-02 18:47:54,993 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d63c20 [0x1ed39e8]) 2022-03-02 18:47:54,994 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74048848 2022-03-02 18:47:54,994 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2022-03-02 18:47:54,995 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x74048830 [0x1ecae28], 0x76d63c20 [0x1ed39e8], 0x2a3420 [0]) 2022-03-02 18:47:54,995 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x1bfc7ac 2022-03-02 18:47:54,996 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x74048830 [0x1ecae28]) 2022-03-02 18:47:54,996 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x74069d78 2022-03-02 18:47:54,997 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x74048830 [0x1ecae28]) 2022-03-02 18:47:54,997 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x74069d78 2022-03-02 18:47:54,998 [INFO] [doorpi.sipphone.from_linphone] found 2 possible videodevices: 2022-03-02 18:47:54,999 [DEBUG] [doorpi.sipphone.from_linphone] | name 2022-03-02 18:47:54,999 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2022-03-02 18:47:55,000 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x74048830 [0x1ecae28]) 2022-03-02 18:47:55,000 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x74069d78 2022-03-02 18:47:55,001 [DEBUG] [doorpi.sipphone.from_linphone] | V4L2: /dev/video0 2022-03-02 18:47:55,001 [DEBUG] [doorpi.sipphone.from_linphone] | StaticImage: Static picture 2022-03-02 18:47:55,002 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2022-03-02 18:47:55,002 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x74048830 [0x1ecae28]) 2022-03-02 18:47:55,003 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x74069d78 2022-03-02 18:47:55,003 [TRACE] [doorpi.conf.config_object] get_string for key video_device in section SIP-Phone (default: V4L2: /dev/video0) returns V4L2: /dev/video0 2022-03-02 18:47:55,004 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x74048830 [0x1ecae28]) 2022-03-02 18:47:55,004 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x74069d78 2022-03-02 18:47:55,005 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_capture(0x74048830 [0x1ecae28], 0x2a3414 [1]) 2022-03-02 18:47:55,005 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_capture -> 0 2022-03-02 18:47:55,006 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_video_device(0x74048830 [0x1ecae28], "V4L2: /dev/video0") 2022-03-02 18:47:55,006 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_video_device -> 0 2022-03-02 18:47:55,007 [TRACE] [doorpi.conf.config_object] get_string for key video_size in section SIP-Phone (default: vga) returns vga 2022-03-02 18:47:55,007 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_preferred_video_size_by_name(0x74048830 [0x1ecae28], "vga") 2022-03-02 18:47:55,008 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_preferred_video_size_by_name -> 0 2022-03-02 18:47:55,008 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_device(0x74048830 [0x1ecae28]) 2022-03-02 18:47:55,008 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_device -> 0x74069e80 2022-03-02 18:47:55,009 [DEBUG] [doorpi.sipphone.from_linphone] using video_device: V4L2: /dev/video0 2022-03-02 18:47:55,009 [TRACE] [doorpi.conf.config_object] get_list for key video_codecs in section SIP-Phone (default: VP8) returns ['VP8'] 2022-03-02 18:47:55,010 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_codecs(0x74048830 [0x1ecae28]) 2022-03-02 18:47:55,010 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1ed3a40) 2022-03-02 18:47:55,011 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2022-03-02 18:47:55,011 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d63c30 2022-03-02 18:47:55,012 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2022-03-02 18:47:55,012 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d63c30 2022-03-02 18:47:55,013 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d63c30 2022-03-02 18:47:55,013 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_codecs -> 0x74069d50 2022-03-02 18:47:55,014 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d63c30 [0x1ed3a40]) 2022-03-02 18:47:55,014 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74048848 2022-03-02 18:47:55,015 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_video_capture_enabled(0x74048830 [0x1ecae28]) 2022-03-02 18:47:55,015 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_video_capture_enabled -> 0x2a3414 2022-03-02 18:47:55,016 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d63c30 [0x1ed3a40]) 2022-03-02 18:47:55,016 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74048848 2022-03-02 18:47:55,017 [DEBUG] [doorpi.sipphone.from_linphone] enable video codec VP8 2022-03-02 18:47:55,017 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x74048830 [0x1ecae28], 0x76d63c30 [0x1ed3a40], 0x2a3414 [1]) 2022-03-02 18:47:55,018 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x1bfc7ac 2022-03-02 18:47:55,018 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_server in section SIP-Phone (default: ) returns XXX.XXX.XXX.XXX 2022-03-02 18:47:55,019 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_username in section SIP-Phone (default: ) returns TuerSprechVorn 2022-03-02 18:47:55,019 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_password in section SIP-Phone (default: TuerSprechVorn) returns ******* 2022-03-02 18:47:55,020 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_realm in section SIP-Phone (default: XXX.XXX.XXX.XXX) returns XXX.XXX.XXX.XXX 2022-03-02 18:47:55,020 [INFO] [doorpi.sipphone.from_linphone] using DoorPi with SIP-Server 2022-03-02 18:47:55,021 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_proxy_config(0x74048830 [0x1ecae28]) 2022-03-02 18:47:55,021 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_from_native_ptr(0x1c29400) 2022-03-02 18:47:55,021 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_new() 2022-03-02 18:47:55,022 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_new -> 0x76d63c40 2022-03-02 18:47:55,023 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_init() 2022-03-02 18:47:55,023 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_init -> 0x76d63c40 2022-03-02 18:47:55,023 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_from_native_ptr -> 0x76d63c40 2022-03-02 18:47:55,024 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_proxy_config -> 0x76d63c40 2022-03-02 18:47:55,024 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2022-03-02 18:47:55,025 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_class_method_new("DoorPi ") 2022-03-02 18:47:55,027 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0x1e9ae28) 2022-03-02 18:47:55,027 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2022-03-02 18:47:55,027 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0x76d63c50 2022-03-02 18:47:55,028 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2022-03-02 18:47:55,028 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0x76d63c50 2022-03-02 18:47:55,029 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0x76d63c50 2022-03-02 18:47:55,029 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_class_method_new -> 0x76d63c50 2022-03-02 18:47:55,030 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_identity_address(0x76d63c40 [0x1c29400], 0x76d63c50 [0x1e9ae28]) 2022-03-02 18:47:55,030 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_identity_address -> 0 2022-03-02 18:47:55,031 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_server_addr(0x76d63c40 [0x1c29400], "sip:XXX.XXX.XXX.XXX") 2022-03-02 18:47:55,032 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_server_addr -> 0 2022-03-02 18:47:55,033 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_enable_register(0x76d63c40 [0x1c29400], 0x2a3414 [1]) 2022-03-02 18:47:55,033 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_enable_register -> 0 2022-03-02 18:47:55,034 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_proxy_config(0x74048830 [0x1ecae28], 0x76d63c40 [0x1c29400]) 2022-03-02 18:47:55,035 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_proxy_config -> 0x1bfc7ac 2022-03-02 18:47:55,036 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_default_proxy_config(0x74048830 [0x1ecae28], 0x76d63c40 [0x1c29400]) 2022-03-02 18:47:55,036 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_default_proxy_config -> 0 2022-03-02 18:47:55,037 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_auth_info(0x74048830 [0x1ecae28], "TuerSprechVorn", "(null)", "!eS8EDr9c5!e", "(null)", "(null)", "XXX.XXX.XXX.XXX") 2022-03-02 18:47:55,037 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_from_native_ptr(0x1e74798) 2022-03-02 18:47:55,038 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_new() 2022-03-02 18:47:55,039 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_new -> 0x76d63c60 2022-03-02 18:47:55,039 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_init() 2022-03-02 18:47:55,040 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_init -> 0x76d63c60 2022-03-02 18:47:55,040 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_from_native_ptr -> 0x76d63c60 2022-03-02 18:47:55,041 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_auth_info -> 0x76d63c60 2022-03-02 18:47:55,041 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_auth_info(0x74048830 [0x1ecae28], 0x76d63c60 [0x1e74798]) 2022-03-02 18:47:55,042 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_auth_info -> None 2022-03-02 18:47:55,042 [DEBUG] [doorpi.sipphone.from_linphone] start successfully 2022-03-02 18:47:55,043 [TRACE] [doorpi.conf.config_object] get_sections returns ['EVENT_OnCallStateDisconnect', 'EVENT_OnStartup', 'EVENT_OnKeyUp_onboardpins.36', 'EVENT_OnKeyUp_onboardpins.31', 'EVENT_OnCallStateConnect'] 2022-03-02 18:47:55,043 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnCallStateDisconnect' in configfile 2022-03-02 18:47:55,044 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnCallStateDisconnect returns ['10', '30', '20'] 2022-03-02 18:47:55,044 [INFO] [doorpi.doorpi] registering action '10' for event 'OnCallStateDisconnect' 2022-03-02 18:47:55,045 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnCallStateDisconnect (default: ) returns os_execute:sudo /home/pi/mjpg-streamer.sh stop 2022-03-02 18:47:55,046 [DEBUG] [doorpi.action.SingleActions.os_execute] doorpi.action.SingleActions.os_execute loaded 2022-03-02 18:47:55,048 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': 'sudo /home/pi/mjpg-streamer.sh stop'} was added to event OnCallStateDisconnect 2022-03-02 18:47:55,049 [INFO] [doorpi.doorpi] registering action '20' for event 'OnCallStateDisconnect' 2022-03-02 18:47:55,049 [TRACE] [doorpi.conf.config_object] get_string for key 20 in section EVENT_OnCallStateDisconnect (default: ) returns out:LEDTalk,0 2022-03-02 18:47:55,050 [DEBUG] [doorpi.action.SingleActions.out] doorpi.action.SingleActions.out loaded 2022-03-02 18:47:55,051 [DEBUG] [doorpi.action.SingleActions.out_triggered] doorpi.action.SingleActions.out_triggered loaded 2022-03-02 18:47:55,052 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': True, 'value': '0', 'pin': 'LEDTalk'} was added to event OnCallStateDisconnect 2022-03-02 18:47:55,053 [INFO] [doorpi.doorpi] registering action '30' for event 'OnCallStateDisconnect' 2022-03-02 18:47:55,053 [TRACE] [doorpi.conf.config_object] get_string for key 30 in section EVENT_OnCallStateDisconnect (default: ) returns out:NamLight,0 2022-03-02 18:47:55,054 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': True, 'value': '0', 'pin': 'NamLight'} was added to event OnCallStateDisconnect 2022-03-02 18:47:55,054 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnStartup' in configfile 2022-03-02 18:47:55,055 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnStartup returns ['10'] 2022-03-02 18:47:55,055 [INFO] [doorpi.doorpi] registering action '10' for event 'OnStartup' 2022-03-02 18:47:55,056 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnStartup (default: ) returns sleep:1 2022-03-02 18:47:55,057 [DEBUG] [doorpi.action.SingleActions.sleep] doorpi.action.SingleActions.sleep loaded 2022-03-02 18:47:55,058 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to new evententry OnStartup 2022-03-02 18:47:55,058 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnKeyUp_onboardpins.36' in configfile 2022-03-02 18:47:55,059 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnKeyUp_onboardpins.36 returns ['10', '20'] 2022-03-02 18:47:55,059 [INFO] [doorpi.doorpi] registering action '10' for event 'OnKeyUp_onboardpins.36' 2022-03-02 18:47:55,060 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnKeyUp_onboardpins.36 (default: ) returns os_execute:sudo /home/pi/mjpg-streamer.sh start 2022-03-02 18:47:55,061 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': 'sudo /home/pi/mjpg-streamer.sh start'} was added to new evententry OnKeyUp_onboardpins.36 2022-03-02 18:47:55,062 [INFO] [doorpi.doorpi] registering action '20' for event 'OnKeyUp_onboardpins.36' 2022-03-02 18:47:55,063 [TRACE] [doorpi.conf.config_object] get_string for key 20 in section EVENT_OnKeyUp_onboardpins.36 (default: ) returns out:NamLight,1 2022-03-02 18:47:55,063 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': True, 'value': '1', 'pin': 'NamLight'} was added to event OnKeyUp_onboardpins.36 2022-03-02 18:47:55,064 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnKeyUp_onboardpins.31' in configfile 2022-03-02 18:47:55,064 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnKeyUp_onboardpins.31 returns ['10'] 2022-03-02 18:47:55,065 [INFO] [doorpi.doorpi] registering action '10' for event 'OnKeyUp_onboardpins.31' 2022-03-02 18:47:55,065 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnKeyUp_onboardpins.31 (default: ) returns out:NamLight,1,0,5 2022-03-02 18:47:55,066 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 5.0, 'pin': 'NamLight', 'start_value': '1'} was added to new evententry OnKeyUp_onboardpins.31 2022-03-02 18:47:55,066 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnCallStateConnect' in configfile 2022-03-02 18:47:55,067 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnCallStateConnect returns ['10', '20'] 2022-03-02 18:47:55,067 [INFO] [doorpi.doorpi] registering action '10' for event 'OnCallStateConnect' 2022-03-02 18:47:55,067 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnCallStateConnect (default: ) returns out:LEDTalk,1 2022-03-02 18:47:55,068 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': True, 'value': '1', 'pin': 'LEDTalk'} was added to event OnCallStateConnect 2022-03-02 18:47:55,068 [INFO] [doorpi.doorpi] registering action '20' for event 'OnCallStateConnect' 2022-03-02 18:47:55,069 [TRACE] [doorpi.conf.config_object] get_string for key 20 in section EVENT_OnCallStateConnect (default: ) returns out:NamLight,1 2022-03-02 18:47:55,069 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': True, 'value': '1', 'pin': 'NamLight'} was added to event OnCallStateConnect 2022-03-02 18:47:55,070 [TRACE] [doorpi.conf.config_object] get_string for key 31 in section onboardpins_InputPins (default: ) returns sleep:0 2022-03-02 18:47:55,071 [TRACE] [doorpi.action.handler] action SleepAction with args (0.0,) and kwargs {} was added to new evententry OnKeyPressed_onboardpins.31 2022-03-02 18:47:55,071 [TRACE] [doorpi.conf.config_object] get_string for key 36 in section onboardpins_InputPins (default: ) returns call:**610#611 # hier belassen und nicht nach EVENT verschieben. Klingeltaster mussd ann 2 x betätigt werden 2022-03-02 18:47:55,072 [DEBUG] [doorpi.action.SingleActions.call] doorpi.action.SingleActions.call loaded 2022-03-02 18:47:55,073 [TRACE] [doorpi.action.handler] action CallAction with args () and kwargs {'number': '**610#611 # hier belassen und nicht nach EVENT verschieben. Klingeltaster mussd ann 2 x bet\xc3\xa4tigt werden'} was added to new evententry OnKeyPressed_onboardpins.36 2022-03-02 18:47:55,074 [WARNING] [root] section DTMF not found in configfile 2022-03-02 18:47:55,074 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns [] 2022-03-02 18:47:55,075 [TRACE] [doorpi.conf.config_object] get_string for key is_alive_led in section DoorPi (default: ) returns 2022-03-02 18:47:55,075 [TRACE] [doorpi.action.handler] fire Event BeforeStartup from doorpi.doorpi asyncron 2022-03-02 18:47:55,077 [DEBUG] [doorpi.action.handler] [Q4EAPE] fire for event OnStartup this actions [] 2022-03-02 18:47:55,078 [DEBUG] [doorpi.action.handler] no actions for event BeforeStartup - skip fire_event BeforeStartup from doorpi.doorpi 2022-03-02 18:47:55,079 [TRACE] [doorpi.action.handler] [Q4EAPE] try to fire action SleepAction with args (1.0,) and kwargs {} 2022-03-02 18:47:55,080 [TRACE] [doorpi.action.base] run SleepAction with args (1.0,) and kwargs {} 2022-03-02 18:47:56,082 [TRACE] [doorpi.action.handler] [Q4EAPE] finished fire_event for event_name OnStartup 2022-03-02 18:47:56,083 [TRACE] [doorpi.action.handler] fire Event AfterStartup from doorpi.doorpi asyncron 2022-03-02 18:47:56,084 [INFO] [doorpi.doorpi] DoorPi started successfully 2022-03-02 18:47:56,085 [INFO] [doorpi.doorpi] BasePath is /usr/local/etc/DoorPi 2022-03-02 18:47:56,086 [INFO] [doorpi.doorpi] Weburl is http://DoorPi/ 2022-03-02 18:47:56,086 [DEBUG] [doorpi.action.handler] no actions for event AfterStartup - skip fire_event AfterStartup from doorpi.doorpi 2022-03-02 18:47:56,087 [INFO] [doorpi.sipphone.from_linphone] resolved to XXX.XXX.XXX.XXX 2022-03-02 18:47:56,089 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2022-03-02 18:47:56,089 [INFO] [doorpi.sipphone.from_linphone] New local ip address is XXX.XXX.XXX.XXX 2022-03-02 18:47:56,090 [INFO] [doorpi.sipphone.from_linphone] Network state is now [UP] 2022-03-02 18:47:56,091 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2022-03-02 18:47:56,091 [INFO] [doorpi.sipphone.from_linphone] XXX.XXX.XXX.XXX 2022-03-02 18:47:56,093 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2022-03-02 18:47:56,094 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2022-03-02 18:47:56,095 [INFO] [doorpi.sipphone.from_linphone] LinphoneProxyConfig [0x1c29400] about to register (LinphoneCore version: 3.9.0) 2022-03-02 18:47:56,105 [INFO] [doorpi.sipphone.from_linphone] belle_sip_client_transaction_send_request(): waiting channel to be ready 2022-03-02 18:47:56,106 [INFO] [doorpi.sipphone.from_linphone] channel [0x1ee73b0]: starting resolution of XXX.XXX.XXX.XXX 2022-03-02 18:47:56,106 [INFO] [doorpi.sipphone.from_linphone] channel 0x1ee73b0: state RES_IN_PROGRESS 2022-03-02 18:47:56,107 [INFO] [doorpi.sipphone.from_linphone] transaction [0x1bff8c8] channel state changed to [RES_IN_PROGRESS] 2022-03-02 18:47:56,107 [INFO] [doorpi.sipphone.from_linphone] channel 0x1ee73b0: state RES_DONE 2022-03-02 18:47:56,108 [INFO] [doorpi.sipphone.from_linphone] transaction [0x1bff8c8] channel state changed to [RES_DONE] 2022-03-02 18:47:56,108 [INFO] [doorpi.sipphone.from_linphone] channel 0x1ee73b0: state CONNECTING 2022-03-02 18:47:56,109 [INFO] [doorpi.sipphone.from_linphone] transaction [0x1bff8c8] channel state changed to [CONNECTING] 2022-03-02 18:47:56,110 [INFO] [doorpi.sipphone.from_linphone] Trying to connect to [UDP://XXX.XXX.XXX.XXX:5060] 2022-03-02 18:47:56,110 [INFO] [doorpi.sipphone.from_linphone] belle_sip_get_src_addr_for(): af_inet6=0 2022-03-02 18:47:56,111 [INFO] [doorpi.sipphone.from_linphone] Channel has local address XXX.XXX.XXX.XXX:5060 2022-03-02 18:47:56,111 [INFO] [doorpi.sipphone.from_linphone] channel 0x1ee73b0: state READY 2022-03-02 18:47:56,112 [INFO] [doorpi.sipphone.from_linphone] transaction [0x1bff8c8] channel state changed to [READY] 2022-03-02 18:47:56,113 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x1bff8c8], from state [INIT] to [TRYING] 2022-03-02 18:47:56,114 [INFO] [doorpi.sipphone.from_linphone] channel [0x1ee73b0]: message sent to [UDP://XXX.XXX.XXX.XXX:5060], size: [530] bytes REGISTER sip:XXX.XXX.XXX.XXX SIP/2.0 Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK.3WQAFJCG~;rport From: "DoorPi" ;tag=o0ANn-xDc To: "DoorPi" CSeq: 20 REGISTER Call-ID: WXZdfH00sm 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) 2022-03-02 18:47:56,114 [INFO] [doorpi.sipphone.from_linphone] Neither Expires header nor corresponding Contact header found, checking from original request 2022-03-02 18:47:56,115 [INFO] [doorpi.sipphone.from_linphone] Refresher [0x1ed83e0] takes ownership of transaction [0x1bff8c8] 2022-03-02 18:47:56,115 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0x1c29400] for identity ["DoorPi" ] moving from state [LinphoneRegistrationNone] to [LinphoneRegistrationProgress] 2022-03-02 18:47:56,116 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0x1ecae28, 0x1c29400, 1, "Registration in progress") 2022-03-02 18:47:56,116 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2022-03-02 18:47:56,117 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1ecae28] notifying [registration_state_changed] 2022-03-02 18:47:56,168 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_network_reachable(0x1ecae28, 0x1) 2022-03-02 18:47:56,169 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_network_reachable 2022-03-02 18:47:56,170 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1ecae28] notifying [network_reachable] 2022-03-02 18:47:56,171 [INFO] [doorpi.sipphone.from_linphone] channel [0x1ee73b0]: received [378] new bytes from [UDP://XXX.XXX.XXX.XXX:5060]: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK.3WQAFJCG~;rport=5060 From: "DoorPi" ;tag=o0ANn-xDc To: "DoorPi" ;tag=DED04FCD2CB8477D Call-ID: WXZdfH00sm CSeq: 20 REGISTER WWW-Authenticate: Digest realm="fritz.box", nonce="34F7CE88F598FFC9" User-Agent: FRITZ!OS Content-Length: 0 2022-03-02 18:47:56,181 [INFO] [doorpi.sipphone.from_linphone] channel [0x1ee73b0] [378] bytes parsed 2022-03-02 18:47:56,182 [INFO] [doorpi.sipphone.from_linphone] channel [0x1ee73b0]: discovered public ip and port are [XXX.XXX.XXX.XXX:5060] 2022-03-02 18:47:56,183 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2022-03-02 18:47:56,183 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x1bff8c8], from state [TRYING] to [COMPLETED] 2022-03-02 18:47:56,184 [INFO] [doorpi.sipphone.from_linphone] linphone_core_find_auth_info(): returning auth info username=TuerSprechVorn, realm= 2022-03-02 18:47:56,185 [INFO] [doorpi.sipphone.from_linphone] Auth info found for [TuerSprechVorn] realm [fritz.box] 2022-03-02 18:47:56,185 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x1ecd470], from state [INIT] to [TRYING] 2022-03-02 18:47:56,186 [INFO] [doorpi.sipphone.from_linphone] channel [0x1ee73b0]: message sent to [UDP://XXX.XXX.XXX.XXX:5060], size: [693] bytes REGISTER sip:XXX.XXX.XXX.XXX SIP/2.0 Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK.eEhGnQFHm;rport From: "DoorPi" ;tag=o0ANn-xDc To: "DoorPi" CSeq: 21 REGISTER Call-ID: WXZdfH00sm 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="fritz.box", nonce="34F7CE88F598FFC9", username="TuerSprechVorn", uri="sip:XXX.XXX.XXX.XXX", response="12044717e64f17be4f84a4cf73785774" 2022-03-02 18:47:56,187 [INFO] [doorpi.sipphone.from_linphone] resolved to XXX.XXX.XXX.XXX 2022-03-02 18:47:56,188 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2022-03-02 18:47:56,239 [INFO] [doorpi.sipphone.from_linphone] channel [0x1ee73b0]: received [689] new bytes from [UDP://XXX.XXX.XXX.XXX:5060]: SIP/2.0 200 OK Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK.eEhGnQFHm;rport=5060 From: "DoorPi" ;tag=o0ANn-xDc To: "DoorPi" ;tag=B0ACBA24A723DF6D Call-ID: WXZdfH00sm CSeq: 21 REGISTER Contact: ;+sip.instance="";expires=300 User-Agent: AVM FRITZ!Box 7490 113.07.29 (Oct 26 2021) Supported: 100rel,replaces,timer Allow-Events: telephone-event,refer,reg Allow: INVITE,ACK,OPTIONS,CANCEL,BYE,UPDATE,PRACK,INFO,SUBSCRIBE,NOTIFY,REFER,MESSAGE,PUBLISH Accept: application/sdp, multipart/mixed Accept-Encoding: identity Content-Length: 0 2022-03-02 18:47:56,254 [INFO] [doorpi.sipphone.from_linphone] channel [0x1ee73b0] [689] bytes parsed 2022-03-02 18:47:56,255 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2022-03-02 18:47:56,256 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x1ecd470], from state [TRYING] to [COMPLETED] 2022-03-02 18:47:56,257 [INFO] [doorpi.sipphone.from_linphone] Refresher [0x1ed83e0]: has no contact for request [0x1ecaa70]. 2022-03-02 18:47:56,257 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 270000 ms 2022-03-02 18:47:56,258 [INFO] [doorpi.sipphone.from_linphone] Register refresher [200] reason [OK] for proxy [sip:XXX.XXX.XXX.XXX] 2022-03-02 18:47:56,258 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0x1c29400] for identity ["DoorPi" ] moving from state [LinphoneRegistrationProgress] to [LinphoneRegistrationOk] 2022-03-02 18:47:56,259 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0x1ecae28, 0x1c29400, 2, "Registration successful") 2022-03-02 18:47:56,259 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2022-03-02 18:47:56,260 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1ecae28] notifying [registration_state_changed] 2022-03-02 18:48:01,215 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x1bff8c8], from state [COMPLETED] to [TERMINATED] 2022-03-02 18:48:01,215 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x1bff8c8] terminated 2022-03-02 18:48:01,267 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x1ecd470], from state [COMPLETED] to [TERMINATED] 2022-03-02 18:48:01,267 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x1ecd470] terminated 2022-03-02 18:51:31,020 [INFO] [doorpi.sipphone.from_linphone] Network state is now [DOWN] 2022-03-02 18:51:31,021 [INFO] [doorpi.sipphone.from_linphone] Refresher [0x1ed83e0] stopped. 2022-03-02 18:51:31,021 [INFO] [doorpi.sipphone.from_linphone] Destroying op [0x1cd9670] of type [SalOpRegister] 2022-03-02 18:51:31,022 [INFO] [doorpi.sipphone.from_linphone] Refresher [0x1ed83e0] stopped. 2022-03-02 18:51:31,022 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0x1c29400] for identity ["DoorPi" ] moving from state [LinphoneRegistrationOk] to [LinphoneRegistrationNone] 2022-03-02 18:51:31,023 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0x1ecae28, 0x1c29400, 0, "Registration impossible (network down)") 2022-03-02 18:51:31,023 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2022-03-02 18:51:31,023 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1ecae28] notifying [registration_state_changed] 2022-03-02 18:51:31,023 [INFO] [doorpi.sipphone.from_linphone] Reseting transports 2022-03-02 18:51:31,023 [INFO] [doorpi.sipphone.from_linphone] Listening point destroying [1] channels 2022-03-02 18:51:31,024 [INFO] [doorpi.sipphone.from_linphone] channel 0x1ee73b0: state DISCONNECTED 2022-03-02 18:51:31,024 [INFO] [doorpi.sipphone.from_linphone] Channel [0x1ee73b0] destroyed 2022-03-02 18:51:31,075 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_network_reachable(0x1ecae28, (nil)) 2022-03-02 18:51:31,075 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_network_reachable 2022-03-02 18:51:31,075 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1ecae28] notifying [network_reachable] 2022-03-02 18:51:41,051 [INFO] [doorpi.sipphone.from_linphone] New local ip address is XXX.XXX.XXX.XXX 2022-03-02 18:51:41,052 [INFO] [doorpi.sipphone.from_linphone] Network state is now [UP] 2022-03-02 18:51:41,053 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2022-03-02 18:51:41,055 [INFO] [doorpi.sipphone.from_linphone] XXX.XXX.XXX.XXX 2022-03-02 18:51:41,056 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2022-03-02 18:51:41,056 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2022-03-02 18:51:41,058 [INFO] [doorpi.sipphone.from_linphone] LinphoneProxyConfig [0x1c29400] about to register (LinphoneCore version: 3.9.0) 2022-03-02 18:51:41,069 [INFO] [doorpi.sipphone.from_linphone] belle_sip_client_transaction_send_request(): waiting channel to be ready 2022-03-02 18:51:41,069 [INFO] [doorpi.sipphone.from_linphone] channel [0x1f24ee8]: starting resolution of XXX.XXX.XXX.XXX 2022-03-02 18:51:41,070 [INFO] [doorpi.sipphone.from_linphone] channel 0x1f24ee8: state RES_IN_PROGRESS 2022-03-02 18:51:41,071 [INFO] [doorpi.sipphone.from_linphone] transaction [0x1ecee98] channel state changed to [RES_IN_PROGRESS] 2022-03-02 18:51:41,071 [INFO] [doorpi.sipphone.from_linphone] channel 0x1f24ee8: state RES_DONE 2022-03-02 18:51:41,072 [INFO] [doorpi.sipphone.from_linphone] transaction [0x1ecee98] channel state changed to [RES_DONE] 2022-03-02 18:51:41,072 [INFO] [doorpi.sipphone.from_linphone] channel 0x1f24ee8: state CONNECTING 2022-03-02 18:51:41,073 [INFO] [doorpi.sipphone.from_linphone] transaction [0x1ecee98] channel state changed to [CONNECTING] 2022-03-02 18:51:41,074 [INFO] [doorpi.sipphone.from_linphone] Trying to connect to [UDP://XXX.XXX.XXX.XXX:5060] 2022-03-02 18:51:41,074 [INFO] [doorpi.sipphone.from_linphone] belle_sip_get_src_addr_for(): af_inet6=0 2022-03-02 18:51:41,075 [INFO] [doorpi.sipphone.from_linphone] Channel has local address XXX.XXX.XXX.XXX:5060 2022-03-02 18:51:41,075 [INFO] [doorpi.sipphone.from_linphone] channel 0x1f24ee8: state READY 2022-03-02 18:51:41,076 [INFO] [doorpi.sipphone.from_linphone] transaction [0x1ecee98] channel state changed to [READY] 2022-03-02 18:51:41,076 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x1ecee98], from state [INIT] to [TRYING] 2022-03-02 18:51:41,078 [INFO] [doorpi.sipphone.from_linphone] channel [0x1f24ee8]: message sent to [UDP://XXX.XXX.XXX.XXX:5060], size: [530] bytes REGISTER sip:XXX.XXX.XXX.XXX SIP/2.0 Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK.PB34yjxmk;rport From: "DoorPi" ;tag=Ses0etNnx To: "DoorPi" CSeq: 20 REGISTER Call-ID: fK2SKEyc0m 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) 2022-03-02 18:51:41,079 [INFO] [doorpi.sipphone.from_linphone] Neither Expires header nor corresponding Contact header found, checking from original request 2022-03-02 18:51:41,079 [INFO] [doorpi.sipphone.from_linphone] Refresher [0x1f24cb8] takes ownership of transaction [0x1ecee98] 2022-03-02 18:51:41,080 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0x1c29400] for identity ["DoorPi" ] moving from state [LinphoneRegistrationNone] to [LinphoneRegistrationProgress] 2022-03-02 18:51:41,080 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0x1ecae28, 0x1c29400, 1, "Registration in progress") 2022-03-02 18:51:41,081 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2022-03-02 18:51:41,081 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1ecae28] notifying [registration_state_changed] 2022-03-02 18:51:41,132 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_network_reachable(0x1ecae28, 0x1) 2022-03-02 18:51:41,133 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_network_reachable 2022-03-02 18:51:41,134 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1ecae28] notifying [network_reachable] 2022-03-02 18:51:41,135 [INFO] [doorpi.sipphone.from_linphone] channel [0x1f24ee8]: received [378] new bytes from [UDP://XXX.XXX.XXX.XXX:5060]: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK.PB34yjxmk;rport=5060 From: "DoorPi" ;tag=Ses0etNnx To: "DoorPi" ;tag=93C46C6EA289B095 Call-ID: fK2SKEyc0m CSeq: 20 REGISTER WWW-Authenticate: Digest realm="fritz.box", nonce="E4870FFF7C0DA1B4" User-Agent: FRITZ!OS Content-Length: 0 2022-03-02 18:51:41,145 [INFO] [doorpi.sipphone.from_linphone] channel [0x1f24ee8] [378] bytes parsed 2022-03-02 18:51:41,146 [INFO] [doorpi.sipphone.from_linphone] channel [0x1f24ee8]: discovered public ip and port are [XXX.XXX.XXX.XXX:5060] 2022-03-02 18:51:41,146 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2022-03-02 18:51:41,147 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x1ecee98], from state [TRYING] to [COMPLETED] 2022-03-02 18:51:41,148 [INFO] [doorpi.sipphone.from_linphone] linphone_core_find_auth_info(): returning auth info username=TuerSprechVorn, realm=fritz.box 2022-03-02 18:51:41,148 [INFO] [doorpi.sipphone.from_linphone] Auth info found for [TuerSprechVorn] realm [fritz.box] 2022-03-02 18:51:41,149 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x1ec8780], from state [INIT] to [TRYING] 2022-03-02 18:51:41,150 [INFO] [doorpi.sipphone.from_linphone] channel [0x1f24ee8]: message sent to [UDP://XXX.XXX.XXX.XXX:5060], size: [693] bytes REGISTER sip:XXX.XXX.XXX.XXX SIP/2.0 Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK.P8Z3wWBQw;rport From: "DoorPi" ;tag=Ses0etNnx To: "DoorPi" CSeq: 21 REGISTER Call-ID: fK2SKEyc0m 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="fritz.box", nonce="E4870FFF7C0DA1B4", username="TuerSprechVorn", uri="sip:XXX.XXX.XXX.XXX", response="da8c6b67054c8dbe7fcc9393e9cf1674" 2022-03-02 18:51:41,151 [INFO] [doorpi.sipphone.from_linphone] resolved to XXX.XXX.XXX.XXX 2022-03-02 18:51:41,152 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2022-03-02 18:51:41,204 [INFO] [doorpi.sipphone.from_linphone] channel [0x1f24ee8]: received [689] new bytes from [UDP://XXX.XXX.XXX.XXX:5060]: SIP/2.0 200 OK Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK.P8Z3wWBQw;rport=5060 From: "DoorPi" ;tag=Ses0etNnx To: "DoorPi" ;tag=BA8AD8BEE56215A0 Call-ID: fK2SKEyc0m CSeq: 21 REGISTER Contact: ;+sip.instance="";expires=300 User-Agent: AVM FRITZ!Box 7490 113.07.29 (Oct 26 2021) Supported: 100rel,replaces,timer Allow-Events: telephone-event,refer,reg Allow: INVITE,ACK,OPTIONS,CANCEL,BYE,UPDATE,PRACK,INFO,SUBSCRIBE,NOTIFY,REFER,MESSAGE,PUBLISH Accept: application/sdp, multipart/mixed Accept-Encoding: identity Content-Length: 0 2022-03-02 18:51:41,218 [INFO] [doorpi.sipphone.from_linphone] channel [0x1f24ee8] [689] bytes parsed 2022-03-02 18:51:41,219 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2022-03-02 18:51:41,220 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x1ec8780], from state [TRYING] to [COMPLETED] 2022-03-02 18:51:41,221 [INFO] [doorpi.sipphone.from_linphone] Refresher [0x1f24cb8]: has no contact for request [0x1ecbd68]. 2022-03-02 18:51:41,221 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 270000 ms 2022-03-02 18:51:41,222 [INFO] [doorpi.sipphone.from_linphone] Register refresher [200] reason [OK] for proxy [sip:XXX.XXX.XXX.XXX] 2022-03-02 18:51:41,222 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0x1c29400] for identity ["DoorPi" ] moving from state [LinphoneRegistrationProgress] to [LinphoneRegistrationOk] 2022-03-02 18:51:41,223 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0x1ecae28, 0x1c29400, 2, "Registration successful") 2022-03-02 18:51:41,224 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2022-03-02 18:51:41,224 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1ecae28] notifying [registration_state_changed] 2022-03-02 18:51:46,196 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x1ecee98], from state [COMPLETED] to [TERMINATED] 2022-03-02 18:51:46,197 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x1ecee98] terminated 2022-03-02 18:51:46,248 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x1ec8780], from state [COMPLETED] to [TERMINATED] 2022-03-02 18:51:46,249 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x1ec8780] terminated