2020-04-05 12:54:42,387 [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 2020-04-05 12:54:42,388 [DEBUG] [doorpi.main] loaded with arguments: ['/usr/local/bin/doorpi_cli', '--trace'] 2020-04-05 12:54:42,388 [DEBUG] [doorpi.doorpi] run 2020-04-05 12:54:42,389 [DEBUG] [doorpi.doorpi] prepare 2020-04-05 12:54:42,389 [DEBUG] [doorpi.doorpi] given arguments argv: Namespace(configfile=None, debug=False, test=False, trace=True) 2020-04-05 12:54:42,391 [INFO] [doorpi.conf.config_object] use configfile: /usr/local/etc/DoorPi/conf/doorpi.ini 2020-04-05 12:54:42,396 [DEBUG] [doorpi.conf.config_object] __init__ 2020-04-05 12:54:42,397 [TRACE] [doorpi.conf.config_object] get_from_config 2020-04-05 12:54:42,401 [TRACE] [doorpi.conf.config_object] get_string for key base_path in section DoorPi (default: /usr/local/etc/DoorPi) returns /usr/local/etc/DoorPi 2020-04-05 12:54:42,402 [TRACE] [doorpi.conf.config_object] get_string for key eventlog in section DoorPi (default: !BASEPATH!/conf/eventlog.db) returns !BASEPATH!/conf/eventlog.db 2020-04-05 12:54:42,402 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/conf/eventlog.db" to "/usr/local/etc/DoorPi/conf/eventlog.db" 2020-04-05 12:54:42,405 [TRACE] [doorpi.action.handler] register Event BeforeStartup from doorpi.doorpi 2020-04-05 12:54:42,405 [DEBUG] [doorpi.action.handler] event_source doorpi.doorpi was added 2020-04-05 12:54:42,406 [TRACE] [doorpi.action.handler] added event_name BeforeStartup and registered source doorpi.doorpi 2020-04-05 12:54:42,406 [TRACE] [doorpi.action.handler] register Event OnStartup from doorpi.doorpi 2020-04-05 12:54:42,406 [TRACE] [doorpi.action.handler] added event_name OnStartup and registered source doorpi.doorpi 2020-04-05 12:54:42,407 [TRACE] [doorpi.action.handler] register Event AfterStartup from doorpi.doorpi 2020-04-05 12:54:42,407 [TRACE] [doorpi.action.handler] added event_name AfterStartup and registered source doorpi.doorpi 2020-04-05 12:54:42,407 [TRACE] [doorpi.action.handler] register Event BeforeShutdown from doorpi.doorpi 2020-04-05 12:54:42,407 [TRACE] [doorpi.action.handler] added event_name BeforeShutdown and registered source doorpi.doorpi 2020-04-05 12:54:42,408 [TRACE] [doorpi.action.handler] register Event OnShutdown from doorpi.doorpi 2020-04-05 12:54:42,408 [TRACE] [doorpi.action.handler] added event_name OnShutdown and registered source doorpi.doorpi 2020-04-05 12:54:42,408 [TRACE] [doorpi.action.handler] register Event AfterShutdown from doorpi.doorpi 2020-04-05 12:54:42,408 [TRACE] [doorpi.action.handler] added event_name AfterShutdown and registered source doorpi.doorpi 2020-04-05 12:54:42,409 [DEBUG] [doorpi.action.SingleActions.time_tick] doorpi.action.SingleActions.time_tick loaded 2020-04-05 12:54:42,410 [DEBUG] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was added 2020-04-05 12:54:42,412 [TRACE] [doorpi.action.handler] action TimeTickDestroyAction with args () and kwargs {} was added to new evententry OnShutdown 2020-04-05 12:54:42,412 [TRACE] [doorpi.action.handler] action TimeTickAction with args ('!last_tick!',) and kwargs {} was added to new evententry OnTimeTick 2020-04-05 12:54:42,412 [TRACE] [doorpi.conf.config_object] get_string for key ip in section DoorPiWeb (default: ) returns 2020-04-05 12:54:42,413 [TRACE] [doorpi.conf.config_object] get_integer for key port in section DoorPiWeb (default: 80) returns 80 2020-04-05 12:54:42,414 [INFO] [doorpi.status.webserver] Initiating WebService at ip and port 80 2020-04-05 12:54:42,415 [TRACE] [doorpi.action.handler] register Event OnWebServerStart from doorpi.status.webserver 2020-04-05 12:54:42,415 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver was added 2020-04-05 12:54:42,415 [TRACE] [doorpi.action.handler] added event_name OnWebServerStart and registered source doorpi.status.webserver 2020-04-05 12:54:42,415 [TRACE] [doorpi.action.handler] register Event OnWebServerStop from doorpi.status.webserver 2020-04-05 12:54:42,416 [TRACE] [doorpi.action.handler] added event_name OnWebServerStop and registered source doorpi.status.webserver 2020-04-05 12:54:42,416 [TRACE] [doorpi.conf.config_object] get_string for key www in section DoorPiWeb (default: !BASEPATH!/../DoorPiWeb) returns !BASEPATH!/../DoorPiWeb 2020-04-05 12:54:42,417 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/../DoorPiWeb" to "/usr/local/etc/DoorPi/../DoorPiWeb" 2020-04-05 12:54:42,417 [TRACE] [doorpi.conf.config_object] get_string for key indexfile in section DoorPiWeb (default: index.html) returns index.html 2020-04-05 12:54:42,417 [DEBUG] [doorpi.conf.config_object] parse string "index.html" to "index.html" 2020-04-05 12:54:42,418 [TRACE] [doorpi.conf.config_object] get_string for key loginfile in section DoorPiWeb (default: login.html) returns login.html 2020-04-05 12:54:42,418 [DEBUG] [doorpi.conf.config_object] parse string "login.html" to "login.html" 2020-04-05 12:54:42,419 [TRACE] [doorpi.conf.config_object] get_string for key public in section DoorPiWeb (default: AREA_public) returns AREA_public 2020-04-05 12:54:42,419 [DEBUG] [doorpi.conf.config_object] parse string "AREA_public" to "AREA_public" 2020-04-05 12:54:42,419 [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 2020-04-05 12:54:42,420 [DEBUG] [doorpi.conf.config_object] parse string "http://motom001.github.io/DoorPiWeb" to "http://motom001.github.io/DoorPiWeb" 2020-04-05 12:54:42,420 [TRACE] [doorpi.conf.config_object] get_keys for section WritePermission returns ['administrator'] 2020-04-05 12:54:42,421 [WARNING] [root] section ReadPermission not found in configfile 2020-04-05 12:54:42,421 [TRACE] [doorpi.conf.config_object] get_keys for section ReadPermission returns [] 2020-04-05 12:54:42,421 [TRACE] [doorpi.conf.config_object] get_keys for section Group returns ['administrator'] 2020-04-05 12:54:42,422 [TRACE] [doorpi.conf.config_object] get_keys for section User returns ['door'] 2020-04-05 12:54:42,422 [TRACE] [doorpi.conf.config_object] get_list for key administrator in section Group (default: []) returns ['door'] 2020-04-05 12:54:42,422 [TRACE] [doorpi.conf.config_object] get_sections returns ['onboardpins_OutputPins', 'onboardpins_keyboard', 'Group', 'keyboards', 'AdminNumbers', 'EVENT_OnMediaRequired', 'DTMF', 'SIP-Phone', 'WritePermission', 'AREA_installer', 'EVENT_OnMediaNotRequired', 'EVENT_OnCallStateDisconnect', 'EVENT_OnStartup', 'User', 'DoorPiWeb', 'EVENT_OnCallStateConnect', 'onboardpins_InputPins', 'DoorPi'] 2020-04-05 12:54:42,423 [TRACE] [doorpi.conf.config_object] get_list for key administrator in section WritePermission (default: []) returns ['installer'] 2020-04-05 12:54:42,423 [ERROR] [doorpi.status.webserver] no ReadPermission found 2020-04-05 12:54:42,423 [TRACE] [doorpi.action.handler] action WebServerStartupAction with args () and kwargs {} was added to new evententry OnWebServerStart 2020-04-05 12:54:42,424 [TRACE] [doorpi.action.handler] action WebServerShutdownAction with args () and kwargs {} was added to event OnShutdown 2020-04-05 12:54:42,424 [TRACE] [doorpi.action.handler] fire Event OnWebServerStart from doorpi.status.webserver asyncron 2020-04-05 12:54:42,425 [TRACE] [doorpi.action.handler] register Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2020-04-05 12:54:42,426 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was added 2020-04-05 12:54:42,426 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequest and registered source doorpi.status.webserver_lib.request_handler 2020-04-05 12:54:42,426 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2020-04-05 12:54:42,427 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestGet and registered source doorpi.status.webserver_lib.request_handler 2020-04-05 12:54:42,427 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2020-04-05 12:54:42,427 [DEBUG] [doorpi.action.handler] [PZLWRM] fire for event OnWebServerStart this actions [] 2020-04-05 12:54:42,428 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestPost and registered source doorpi.status.webserver_lib.request_handler 2020-04-05 12:54:42,428 [TRACE] [doorpi.action.handler] [PZLWRM] try to fire action WebServerStartupAction with args () and kwargs {} 2020-04-05 12:54:42,428 [TRACE] [doorpi.action.handler] register Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2020-04-05 12:54:42,429 [TRACE] [doorpi.action.base] run WebServerStartupAction with args () and kwargs {} 2020-04-05 12:54:42,429 [TRACE] [doorpi.action.handler] added event_name OnWebServerVirtualResource and registered source doorpi.status.webserver_lib.request_handler 2020-04-05 12:54:42,430 [TRACE] [doorpi.action.handler] register Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler 2020-04-05 12:54:42,430 [TRACE] [doorpi.action.handler] added event_name OnWebServerRealResource and registered source doorpi.status.webserver_lib.request_handler 2020-04-05 12:54:42,430 [TRACE] [doorpi.action.handler] register Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2020-04-05 12:54:42,430 [TRACE] [doorpi.action.handler] added event_name OnFireEvent and registered source doorpi.status.webserver_lib.request_handler 2020-04-05 12:54:42,431 [TRACE] [doorpi.action.handler] register Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2020-04-05 12:54:42,431 [TRACE] [doorpi.action.handler] added event_name OnConfigKeySet and registered source doorpi.status.webserver_lib.request_handler 2020-04-05 12:54:42,431 [TRACE] [doorpi.action.handler] register Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2020-04-05 12:54:42,431 [TRACE] [doorpi.action.handler] added event_name OnConfigKeyDelete and registered source doorpi.status.webserver_lib.request_handler 2020-04-05 12:54:42,432 [TRACE] [doorpi.conf.config_object] get_keys for section keyboards returns ['onboardpins'] 2020-04-05 12:54:42,432 [INFO] [doorpi.keyboard.KeyboardInterface] using multi-keyboard mode (keyboards: onboardpins) 2020-04-05 12:54:42,432 [INFO] [doorpi.keyboard.KeyboardInterface] trying to add keyboard 'onboardpins' to handler 2020-04-05 12:54:42,432 [TRACE] [doorpi.conf.config_object] get_string for key onboardpins in section keyboards (default: dummy) returns gpio 2020-04-05 12:54:42,433 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_InputPins returns ['18', '16'] 2020-04-05 12:54:42,433 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns ['11', '12'] 2020-04-05 12:54:42,433 [TRACE] [doorpi.conf.config_object] get_integer for key bouncetime in section onboardpins_keyboard (default: 2000) returns 5000.0 2020-04-05 12:54:42,433 [TRACE] [doorpi.conf.config_object] get_integer for key polarity in section onboardpins_keyboard (default: 0) returns 0 2020-04-05 12:54:42,434 [TRACE] [doorpi.conf.config_object] get_boolean for key pressed_on_keydown in section onboardpins_keyboard (default: True) returns True 2020-04-05 12:54:42,434 [DEBUG] [doorpi.keyboard.from_gpio] doorpi.keyboard.from_gpio loaded 2020-04-05 12:54:42,436 [DEBUG] [doorpi.keyboard.from_gpio] __init__(input_pins = ['18', '16'], output_pins = ['11', '12'], bouncetime = 5000.0, polarity = 0) 2020-04-05 12:54:42,437 [TRACE] [doorpi.conf.config_object] get_string for key mode in section onboardpins_keyboard (default: BOARD) returns BOARD 2020-04-05 12:54:42,437 [TRACE] [doorpi.conf.config_object] get_string for key pull_up_down in section onboardpins_keyboard (default: PUD_OFF) returns PUD_DOWN 2020-04-05 12:54:42,438 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_gpio 2020-04-05 12:54:42,439 [DEBUG] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was added 2020-04-05 12:54:42,439 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed and registered source doorpi.keyboard.from_gpio 2020-04-05 12:54:42,439 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_18 from doorpi.keyboard.from_gpio 2020-04-05 12:54:42,440 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_18 and registered source doorpi.keyboard.from_gpio 2020-04-05 12:54:42,440 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.18 from doorpi.keyboard.from_gpio 2020-04-05 12:54:42,440 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.18 and registered source doorpi.keyboard.from_gpio 2020-04-05 12:54:42,440 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_gpio 2020-04-05 12:54:42,441 [TRACE] [doorpi.action.handler] added event_name OnKeyUp and registered source doorpi.keyboard.from_gpio 2020-04-05 12:54:42,441 [TRACE] [doorpi.action.handler] register Event OnKeyUp_18 from doorpi.keyboard.from_gpio 2020-04-05 12:54:42,441 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_18 and registered source doorpi.keyboard.from_gpio 2020-04-05 12:54:42,442 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.18 from doorpi.keyboard.from_gpio 2020-04-05 12:54:42,442 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.18 and registered source doorpi.keyboard.from_gpio 2020-04-05 12:54:42,442 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_gpio 2020-04-05 12:54:42,443 [TRACE] [doorpi.action.handler] added event_name OnKeyDown and registered source doorpi.keyboard.from_gpio 2020-04-05 12:54:42,443 [TRACE] [doorpi.action.handler] register Event OnKeyDown_18 from doorpi.keyboard.from_gpio 2020-04-05 12:54:42,443 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_18 and registered source doorpi.keyboard.from_gpio 2020-04-05 12:54:42,444 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.18 from doorpi.keyboard.from_gpio 2020-04-05 12:54:42,445 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.18 and registered source doorpi.keyboard.from_gpio 2020-04-05 12:54:42,447 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_gpio 2020-04-05 12:54:42,447 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_gpio is already known 2020-04-05 12:54:42,448 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_16 from doorpi.keyboard.from_gpio 2020-04-05 12:54:42,449 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_16 and registered source doorpi.keyboard.from_gpio 2020-04-05 12:54:42,449 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.16 from doorpi.keyboard.from_gpio 2020-04-05 12:54:42,450 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.16 and registered source doorpi.keyboard.from_gpio 2020-04-05 12:54:42,450 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_gpio 2020-04-05 12:54:42,451 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_gpio is already known 2020-04-05 12:54:42,451 [TRACE] [doorpi.action.handler] register Event OnKeyUp_16 from doorpi.keyboard.from_gpio 2020-04-05 12:54:42,452 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_16 and registered source doorpi.keyboard.from_gpio 2020-04-05 12:54:42,452 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.16 from doorpi.keyboard.from_gpio 2020-04-05 12:54:42,453 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.16 and registered source doorpi.keyboard.from_gpio 2020-04-05 12:54:42,454 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_gpio 2020-04-05 12:54:42,454 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_gpio is already known 2020-04-05 12:54:42,455 [TRACE] [doorpi.action.handler] register Event OnKeyDown_16 from doorpi.keyboard.from_gpio 2020-04-05 12:54:42,455 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_16 and registered source doorpi.keyboard.from_gpio 2020-04-05 12:54:42,456 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.16 from doorpi.keyboard.from_gpio 2020-04-05 12:54:42,457 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.16 and registered source doorpi.keyboard.from_gpio 2020-04-05 12:54:42,459 [TRACE] [doorpi.action.handler] action KeyboardDestroyAction with args () and kwargs {} was added to event OnShutdown 2020-04-05 12:54:42,460 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns ['11', '12'] 2020-04-05 12:54:42,461 [TRACE] [doorpi.conf.config_object] get_string for key 11 in section onboardpins_OutputPins (default: ) returns Oeffner 2020-04-05 12:54:42,462 [TRACE] [doorpi.conf.config_object] get_string for key 12 in section onboardpins_OutputPins (default: ) returns Amp 2020-04-05 12:54:42,463 [DEBUG] [doorpi.status.status_lib.environment] doorpi.status.status_lib.environment loaded 2020-04-05 12:54:42,465 [DEBUG] [doorpi.status.requirements_lib.req_config] doorpi.status.requirements_lib.req_config loaded 2020-04-05 12:54:42,467 [DEBUG] [doorpi.status.requirements_lib.req_sipphone] doorpi.status.requirements_lib.req_sipphone loaded 2020-04-05 12:54:42,468 [DEBUG] [doorpi.sipphone.AbstractBaseClass] doorpi.sipphone.AbstractBaseClass loaded 2020-04-05 12:54:42,498 [DEBUG] [doorpi.status.requirements_lib.req_event_handler] doorpi.status.requirements_lib.req_event_handler loaded 2020-04-05 12:54:42,499 [DEBUG] [doorpi.status.requirements_lib.req_webserver] doorpi.status.requirements_lib.req_webserver loaded 2020-04-05 12:54:42,501 [DEBUG] [doorpi.status.requirements_lib.req_keyboard] doorpi.status.requirements_lib.req_keyboard loaded 2020-04-05 12:54:42,533 [DEBUG] [doorpi.status.requirements_lib.req_system] doorpi.status.requirements_lib.req_system loaded 2020-04-05 12:54:42,537 [INFO] [doorpi.sipphone.SipphoneInterface] found installed sipphone "linphone" and use this as default 2020-04-05 12:54:42,538 [TRACE] [doorpi.conf.config_object] get_string for key sipphonetyp in section SIP-Phone (default: linphone) returns linphone 2020-04-05 12:54:42,539 [DEBUG] [doorpi.sipphone.from_linphone] doorpi.sipphone.from_linphone loaded 2020-04-05 12:54:42,540 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] doorpi.sipphone.linphone_lib.CallBacks loaded 2020-04-05 12:54:42,541 [DEBUG] [doorpi.sipphone.linphone_lib.Player] doorpi.sipphone.linphone_lib.Player loaded 2020-04-05 12:54:42,543 [DEBUG] [doorpi.sipphone.linphone_lib.Recorder] doorpi.sipphone.linphone_lib.Recorder loaded 2020-04-05 12:54:42,544 [DEBUG] [doorpi.sipphone.from_linphone] __init__ 2020-04-05 12:54:42,544 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnShutdown 2020-04-05 12:54:42,545 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2020-04-05 12:54:42,545 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was added 2020-04-05 12:54:42,545 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCreate and registered source doorpi.sipphone.from_linphone 2020-04-05 12:54:42,546 [TRACE] [doorpi.action.handler] register Event OnSipPhoneStart from doorpi.sipphone.from_linphone 2020-04-05 12:54:42,546 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneStart and registered source doorpi.sipphone.from_linphone 2020-04-05 12:54:42,546 [TRACE] [doorpi.action.handler] register Event OnSipPhoneDestroy from doorpi.sipphone.from_linphone 2020-04-05 12:54:42,546 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneDestroy and registered source doorpi.sipphone.from_linphone 2020-04-05 12:54:42,547 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderCreate from doorpi.sipphone.from_linphone 2020-04-05 12:54:42,547 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderCreate and registered source doorpi.sipphone.from_linphone 2020-04-05 12:54:42,547 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderDestroy from doorpi.sipphone.from_linphone 2020-04-05 12:54:42,547 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderDestroy and registered source doorpi.sipphone.from_linphone 2020-04-05 12:54:42,548 [TRACE] [doorpi.action.handler] register Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2020-04-05 12:54:42,548 [TRACE] [doorpi.action.handler] added event_name BeforeSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2020-04-05 12:54:42,548 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone 2020-04-05 12:54:42,549 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2020-04-05 12:54:42,549 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2020-04-05 12:54:42,549 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCallFailed and registered source doorpi.sipphone.from_linphone 2020-04-05 12:54:42,549 [TRACE] [doorpi.action.handler] register Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2020-04-05 12:54:42,550 [TRACE] [doorpi.action.handler] added event_name AfterSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2020-04-05 12:54:42,550 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutNoResponse from doorpi.sipphone.from_linphone 2020-04-05 12:54:42,550 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutNoResponse and registered source doorpi.sipphone.from_linphone 2020-04-05 12:54:42,550 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutMaxCalltime from doorpi.sipphone.from_linphone 2020-04-05 12:54:42,551 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutMaxCalltime and registered source doorpi.sipphone.from_linphone 2020-04-05 12:54:42,551 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.from_linphone 2020-04-05 12:54:42,551 [TRACE] [doorpi.action.handler] added event_name OnPlayerCreated and registered source doorpi.sipphone.from_linphone 2020-04-05 12:54:42,551 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] __init__ 2020-04-05 12:54:42,552 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneDestroy 2020-04-05 12:54:42,552 [TRACE] [doorpi.action.handler] register Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,552 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was added 2020-04-05 12:54:42,552 [TRACE] [doorpi.action.handler] added event_name OnCallMediaStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,553 [TRACE] [doorpi.action.handler] register Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,553 [TRACE] [doorpi.action.handler] added event_name OnMediaRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,553 [TRACE] [doorpi.action.handler] register Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,554 [TRACE] [doorpi.action.handler] added event_name OnMediaNotRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,554 [TRACE] [doorpi.action.handler] register Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,554 [TRACE] [doorpi.action.handler] added event_name OnCallStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,554 [TRACE] [doorpi.action.handler] register Event OnCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,555 [TRACE] [doorpi.action.handler] added event_name OnCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,555 [TRACE] [doorpi.action.handler] register Event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,555 [TRACE] [doorpi.action.handler] added event_name AfterCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,555 [TRACE] [doorpi.action.handler] register Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,556 [TRACE] [doorpi.action.handler] added event_name OnCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,556 [TRACE] [doorpi.action.handler] register Event AfterCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,556 [TRACE] [doorpi.action.handler] added event_name AfterCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,557 [TRACE] [doorpi.action.handler] register Event OnCallStateDismissed from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,557 [TRACE] [doorpi.action.handler] added event_name OnCallStateDismissed and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,557 [TRACE] [doorpi.action.handler] register Event OnCallStateReject from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,557 [TRACE] [doorpi.action.handler] added event_name OnCallStateReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,558 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,558 [TRACE] [doorpi.action.handler] added event_name OnCallStart and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,558 [TRACE] [doorpi.action.handler] register Event OnDTMF from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,558 [TRACE] [doorpi.action.handler] added event_name OnDTMF and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,559 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns ['"#404"', '"#500"', '"#1"'] 2020-04-05 12:54:42,559 [TRACE] [doorpi.action.handler] register Event OnDTMF_"#404" from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,559 [TRACE] [doorpi.action.handler] added event_name OnDTMF_"#404" and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,559 [TRACE] [doorpi.action.handler] register Event OnDTMF_"#500" from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,560 [TRACE] [doorpi.action.handler] added event_name OnDTMF_"#500" and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,560 [TRACE] [doorpi.action.handler] register Event OnDTMF_"#1" from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,560 [TRACE] [doorpi.action.handler] added event_name OnDTMF_"#1" and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,560 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,561 [TRACE] [doorpi.action.handler] nothing to do - event OnCallStart from source doorpi.sipphone.linphone_lib.CallBacks is already known 2020-04-05 12:54:42,561 [TRACE] [doorpi.action.handler] register Event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,561 [TRACE] [doorpi.action.handler] added event_name BeforeCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,561 [TRACE] [doorpi.action.handler] register Event OnCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,562 [TRACE] [doorpi.action.handler] added event_name OnCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,562 [TRACE] [doorpi.action.handler] register Event AfterCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,562 [TRACE] [doorpi.action.handler] added event_name AfterCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,562 [TRACE] [doorpi.action.handler] register Event OnCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,563 [TRACE] [doorpi.action.handler] added event_name OnCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,563 [TRACE] [doorpi.action.handler] register Event AfterCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,563 [TRACE] [doorpi.action.handler] added event_name AfterCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,563 [TRACE] [doorpi.action.handler] register Event OnCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,563 [TRACE] [doorpi.action.handler] added event_name OnCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,564 [TRACE] [doorpi.action.handler] register Event AfterCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,564 [TRACE] [doorpi.action.handler] added event_name AfterCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,564 [TRACE] [doorpi.action.handler] register Event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,564 [TRACE] [doorpi.action.handler] added event_name OnCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,565 [TRACE] [doorpi.action.handler] register Event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,565 [TRACE] [doorpi.action.handler] added event_name AfterCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,565 [TRACE] [doorpi.action.handler] fire Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-04-05 12:54:42,567 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_new() 2020-04-05 12:54:42,567 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_new -> 0x745c6f68 2020-04-05 12:54:42,568 [DEBUG] [doorpi.action.handler] no actions for event OnCallStart - skip fire_event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:54:42,568 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_init() 2020-04-05 12:54:42,569 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_init -> 0x745c6f68 2020-04-05 12:54:42,569 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_class_method_new(0x7452fb70, "(null)", "(null)") 2020-04-05 12:54:42,569 [INFO] [doorpi.sipphone.from_linphone] Mediastreamer2 factory 2.12.1 (git: 3.9.1) initialized. 2020-04-05 12:54:42,570 [INFO] [doorpi.sipphone.from_linphone] CPU count set to 4 2020-04-05 12:54:42,570 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init() done: platform_tags=linux,arm,embedded 2020-04-05 12:54:42,570 [INFO] [doorpi.sipphone.from_linphone] srtp init 2020-04-05 12:54:42,577 [INFO] [doorpi.sipphone.from_linphone] Registering all soundcard handlers 2020-04-05 12:54:42,624 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: default device' added 2020-04-05 12:54:42,641 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: USB Audio Device' added 2020-04-05 12:54:42,642 [INFO] [doorpi.sipphone.from_linphone] also error in pcm_hw.c:1602 - open '/dev/snd/pcmC1D0c' failed (-2) 2020-04-05 12:54:42,644 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: bcm2835 ALSA' added 2020-04-05 12:54:42,645 [INFO] [doorpi.sipphone.from_linphone] Registering all webcam handlers 2020-04-05 12:54:42,645 [INFO] [doorpi.sipphone.from_linphone] Webcam V4L2: /dev/video0 added 2020-04-05 12:54:42,646 [INFO] [doorpi.sipphone.from_linphone] Webcam StaticImage: Static picture added 2020-04-05 12:54:42,646 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init_voip() done 2020-04-05 12:54:42,646 [INFO] [doorpi.sipphone.from_linphone] Loading ms plugins from [./lib/mediastreamer/plugins] 2020-04-05 12:54:42,647 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/mediastreamer/plugins: No such file or directory 2020-04-05 12:54:42,647 [INFO] [doorpi.sipphone.from_linphone] Initializing LinphoneCore 3.9.1 2020-04-05 12:54:42,647 [INFO] [doorpi.sipphone.from_linphone] Vtable [0x18be990] registered on core [0x18c62b8] 2020-04-05 12:54:42,648 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x18be990, 1, "Starting up") 2020-04-05 12:54:42,648 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2020-04-05 12:54:42,648 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x18be990] notifying [global_state_changed] 2020-04-05 12:54:42,648 [INFO] [doorpi.sipphone.from_linphone] oRTP-0.25.0 initialized. 2020-04-05 12:54:42,649 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_base_init, because [2] ref 2020-04-05 12:54:42,649 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_voip_init, because [2] ref 2020-04-05 12:54:42,649 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_plugins_init, because [2] ref 2020-04-05 12:54:42,650 [INFO] [doorpi.sipphone.from_linphone] Codec opus/48000 fmtp=[useinbandfec=1] number=-1, enabled=1) added to default capabilities. 2020-04-05 12:54:42,650 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2020-04-05 12:54:42,650 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2020-04-05 12:54:42,650 [INFO] [doorpi.sipphone.from_linphone] Codec speex/16000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2020-04-05 12:54:42,651 [INFO] [doorpi.sipphone.from_linphone] Codec speex/8000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2020-04-05 12:54:42,651 [INFO] [doorpi.sipphone.from_linphone] Codec PCMU/8000 fmtp=[] number=0, enabled=1) added to default capabilities. 2020-04-05 12:54:42,651 [INFO] [doorpi.sipphone.from_linphone] Codec PCMA/8000 fmtp=[] number=8, enabled=1) added to default capabilities. 2020-04-05 12:54:42,652 [INFO] [doorpi.sipphone.from_linphone] Codec t140/1000 fmtp=[] number=96, enabled=1) added to default capabilities. 2020-04-05 12:54:42,652 [INFO] [doorpi.sipphone.from_linphone] Codec red/1000 fmtp=[] number=97, enabled=1) added to default capabilities. 2020-04-05 12:54:42,652 [INFO] [doorpi.sipphone.from_linphone] Codec GSM/8000 fmtp=[] number=3, enabled=0) added to default capabilities. 2020-04-05 12:54:42,653 [INFO] [doorpi.sipphone.from_linphone] Codec G722/8000 fmtp=[] number=9, enabled=0) added to default capabilities. 2020-04-05 12:54:42,653 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iLBC 2020-04-05 12:54:42,653 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iLBC 2020-04-05 12:54:42,654 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR 2020-04-05 12:54:42,654 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR 2020-04-05 12:54:42,654 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR-WB 2020-04-05 12:54:42,654 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR-WB 2020-04-05 12:54:42,655 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2020-04-05 12:54:42,655 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2020-04-05 12:54:42,655 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2020-04-05 12:54:42,656 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2020-04-05 12:54:42,656 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2020-04-05 12:54:42,656 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2020-04-05 12:54:42,656 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2020-04-05 12:54:42,657 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2020-04-05 12:54:42,657 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2020-04-05 12:54:42,657 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2020-04-05 12:54:42,658 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2020-04-05 12:54:42,658 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2020-04-05 12:54:42,658 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iSAC 2020-04-05 12:54:42,658 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iSAC 2020-04-05 12:54:42,659 [INFO] [doorpi.sipphone.from_linphone] Codec speex/32000 fmtp=[vbr=on] number=-1, enabled=0) added to default capabilities. 2020-04-05 12:54:42,659 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2020-04-05 12:54:42,659 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2020-04-05 12:54:42,660 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2020-04-05 12:54:42,660 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2020-04-05 12:54:42,660 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2020-04-05 12:54:42,661 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2020-04-05 12:54:42,661 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-16 2020-04-05 12:54:42,661 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-16 2020-04-05 12:54:42,661 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-24 2020-04-05 12:54:42,662 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-24 2020-04-05 12:54:42,662 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-32 2020-04-05 12:54:42,662 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-32 2020-04-05 12:54:42,663 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-40 2020-04-05 12:54:42,663 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-40 2020-04-05 12:54:42,663 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-16 2020-04-05 12:54:42,664 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-16 2020-04-05 12:54:42,664 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-24 2020-04-05 12:54:42,664 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-24 2020-04-05 12:54:42,664 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-32 2020-04-05 12:54:42,665 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-32 2020-04-05 12:54:42,665 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-40 2020-04-05 12:54:42,666 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-40 2020-04-05 12:54:42,666 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CODEC2 2020-04-05 12:54:42,666 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CODEC2 2020-04-05 12:54:42,667 [INFO] [doorpi.sipphone.from_linphone] Codec VP8/90000 fmtp=[] number=-1, enabled=1) added to default capabilities. 2020-04-05 12:54:42,667 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H264 2020-04-05 12:54:42,667 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H264 2020-04-05 12:54:42,668 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MP4V-ES 2020-04-05 12:54:42,668 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263-1998 2020-04-05 12:54:42,668 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2020-04-05 12:54:42,668 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for 1016 2020-04-05 12:54:42,669 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for 1016 2020-04-05 12:54:42,669 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G723 2020-04-05 12:54:42,669 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G723 2020-04-05 12:54:42,670 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for LPC 2020-04-05 12:54:42,670 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for LPC 2020-04-05 12:54:42,670 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=10, enabled=0) added to default capabilities. 2020-04-05 12:54:42,671 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=11, enabled=0) added to default capabilities. 2020-04-05 12:54:42,671 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CN 2020-04-05 12:54:42,671 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CN 2020-04-05 12:54:42,671 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2020-04-05 12:54:42,672 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2020-04-05 12:54:42,672 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H261 2020-04-05 12:54:42,672 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H261 2020-04-05 12:54:42,672 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MPV 2020-04-05 12:54:42,673 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for MPV 2020-04-05 12:54:42,673 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2020-04-05 12:54:42,673 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2020-04-05 12:54:42,673 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2020-04-05 12:54:42,674 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2020-04-05 12:54:42,674 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2020-04-05 12:54:42,674 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_configuring_status(0x18be990, 2, "(null)") 2020-04-05 12:54:42,675 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_configuring_status 2020-04-05 12:54:42,675 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x18be990] notifying [configuring_status] 2020-04-05 12:54:42,675 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/liblinphone/plugins: No such file or directory 2020-04-05 12:54:42,676 [WARNING] [doorpi.sipphone.from_linphone] ./share/sounds/linphone/rings/oldphone.wav does not exist 2020-04-05 12:54:42,676 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_playback_gain_db(): no active call. 2020-04-05 12:54:42,676 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_mic_gain_db(): no active call. 2020-04-05 12:54:42,677 [INFO] [doorpi.sipphone.from_linphone] MTU is supposed to be 1300, rtp payload max size will be 1240 2020-04-05 12:54:42,677 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2020-04-05 12:54:42,677 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2020-04-05 12:54:42,677 [INFO] [doorpi.sipphone.from_linphone] Supported codec opus/48000 fmtp=useinbandfec=1 automatically added to codec list. 2020-04-05 12:54:42,678 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/16000 fmtp=vbr=on automatically added to codec list. 2020-04-05 12:54:42,678 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/8000 fmtp=vbr=on automatically added to codec list. 2020-04-05 12:54:42,678 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMU/8000 fmtp= automatically added to codec list. 2020-04-05 12:54:42,679 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMA/8000 fmtp= automatically added to codec list. 2020-04-05 12:54:42,679 [INFO] [doorpi.sipphone.from_linphone] Supported codec GSM/8000 fmtp= automatically added to codec list. 2020-04-05 12:54:42,679 [INFO] [doorpi.sipphone.from_linphone] Supported codec G722/8000 fmtp= automatically added to codec list. 2020-04-05 12:54:42,679 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/32000 fmtp=vbr=on automatically added to codec list. 2020-04-05 12:54:42,680 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2020-04-05 12:54:42,680 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2020-04-05 12:54:42,680 [INFO] [doorpi.sipphone.from_linphone] Supported codec VP8/90000 fmtp= automatically added to codec list. 2020-04-05 12:54:42,680 [INFO] [doorpi.sipphone.from_linphone] Supported codec t140/1000 fmtp= automatically added to codec list. 2020-04-05 12:54:42,681 [INFO] [doorpi.sipphone.from_linphone] Supported codec red/1000 fmtp= automatically added to codec list. 2020-04-05 12:54:42,681 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2020-04-05 12:54:42,681 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2020-04-05 12:54:42,682 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2020-04-05 12:54:42,682 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x18c61b8] on [sip:0.0.0.0:5060;transport=UDP] 2020-04-05 12:54:42,683 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x18c1508] on [sip:0.0.0.0:5060;transport=TCP] 2020-04-05 12:54:42,683 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x18c16a8] on [sip:0.0.0.0:-1;transport=TLS] 2020-04-05 12:54:42,683 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 41513 2020-04-05 12:54:42,684 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2020-04-05 12:54:42,684 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2020-04-05 12:54:42,686 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x18be990, 2, "Ready") 2020-04-05 12:54:42,687 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2020-04-05 12:54:42,687 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x18be990] notifying [global_state_changed] 2020-04-05 12:54:42,688 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_class_method_new -> 0x745c6f68 2020-04-05 12:54:42,688 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2020-04-05 12:54:42,688 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_primary_contact(0x745c6f68 [0x18be990], "DoorPi ") 2020-04-05 12:54:42,689 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_primary_contact -> 0 2020-04-05 12:54:42,689 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneCreate from doorpi.sipphone.from_linphone asyncron 2020-04-05 12:54:42,690 [TRACE] [doorpi.conf.config_object] get_integer for key ua.max_calls in section SIP-Phone (default: 2) returns 2 2020-04-05 12:54:42,691 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_max_calls(0x745c6f68 [0x18be990], 2) 2020-04-05 12:54:42,691 [DEBUG] [doorpi.action.handler] no actions for event OnSipPhoneCreate - skip fire_event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2020-04-05 12:54:42,692 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_max_calls -> 0 2020-04-05 12:54:42,692 [TRACE] [doorpi.conf.config_object] get_boolean for key echo_cancellation_enabled in section SIP-Phone (default: False) returns True 2020-04-05 12:54:42,693 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_echo_cancellation(0x745c6f68 [0x18be990], 0x2d769c [1]) 2020-04-05 12:54:42,693 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_echo_cancellation -> 0 2020-04-05 12:54:42,693 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2020-04-05 12:54:42,694 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2020-04-05 12:54:42,694 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_new() 2020-04-05 12:54:42,694 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_new -> 0x745c6f80 2020-04-05 12:54:42,694 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_sip_transports(0x745c6f68 [0x18be990], 0x745c6f80 [0x745c6f88]) 2020-04-05 12:54:42,695 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x18c61b8] on [sip:0.0.0.0:5060;transport=UDP] destroyed 2020-04-05 12:54:42,695 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x18c1508] on [sip:0.0.0.0:5060;transport=TCP] destroyed 2020-04-05 12:54:42,696 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x18c16a8] on [sip:0.0.0.0:41513;transport=TLS] destroyed 2020-04-05 12:54:42,696 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2020-04-05 12:54:42,696 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x18d7c28] on [sip:0.0.0.0:5060;transport=UDP] 2020-04-05 12:54:42,697 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x18c6118] on [sip:0.0.0.0:5060;transport=TCP] 2020-04-05 12:54:42,697 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x18d6d68] on [sip:0.0.0.0:-1;transport=TLS] 2020-04-05 12:54:42,698 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 44139 2020-04-05 12:54:42,698 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2020-04-05 12:54:42,698 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2020-04-05 12:54:42,699 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_sip_transports -> 0 2020-04-05 12:54:42,699 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_dealloc(0x745c6f80) 2020-04-05 12:54:42,699 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_dealloc 2020-04-05 12:54:42,699 [TRACE] [doorpi.conf.config_object] get_boolean for key video_display_enabled in section SIP-Phone (default: False) returns False 2020-04-05 12:54:42,700 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_display(0x745c6f68 [0x18be990], 0x2d7690 [0]) 2020-04-05 12:54:42,700 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_display -> 0 2020-04-05 12:54:42,700 [TRACE] [doorpi.conf.config_object] get_string for key stun_server in section SIP-Phone (default: ) returns 2020-04-05 12:54:42,701 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_stun_server(0x745c6f68 [0x18be990], "") 2020-04-05 12:54:42,701 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2020-04-05 12:54:42,702 [INFO] [doorpi.sipphone.from_linphone] 192.168.1.1 2020-04-05 12:54:42,702 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2020-04-05 12:54:42,703 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2020-04-05 12:54:42,703 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_stun_server -> 0 2020-04-05 12:54:42,703 [DEBUG] [doorpi.conf.config_object] create new key FirewallPolicy in section SIP-Phone with value 'PolicyNoFirewall' 2020-04-05 12:54:42,703 [TRACE] [doorpi.conf.config_object] get_string for key FirewallPolicy in section SIP-Phone (default: PolicyNoFirewall) returns PolicyNoFirewall 2020-04-05 12:54:42,704 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_firewall_policy(0x745c6f68 [0x18be990], 0) 2020-04-05 12:54:42,704 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2020-04-05 12:54:42,704 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2020-04-05 12:54:42,706 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_firewall_policy -> 0 2020-04-05 12:54:42,706 [TRACE] [doorpi.conf.config_object] get_integer for key max_call_time in section SIP-Phone (default: 120) returns 120 2020-04-05 12:54:42,706 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_in_call_timeout(0x745c6f68 [0x18be990], 120) 2020-04-05 12:54:42,707 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_in_call_timeout -> 0 2020-04-05 12:54:42,707 [TRACE] [doorpi.conf.config_object] get_integer for key call_timeout in section SIP-Phone (default: 15) returns 30 2020-04-05 12:54:42,707 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_inc_timeout(0x745c6f68 [0x18be990], 30) 2020-04-05 12:54:42,708 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_inc_timeout -> 0 2020-04-05 12:54:42,708 [TRACE] [doorpi.conf.config_object] get_string for key dialtone in section SIP-Phone (default: !BASEPATH!/media/ShortDialTone.wav) returns 2020-04-05 12:54:42,709 [DEBUG] [doorpi.conf.config_object] parse string "" to "" 2020-04-05 12:54:42,709 [DEBUG] [doorpi.sipphone.linphone_lib.Player] no player found in config at section DoorPi and key dialtone 2020-04-05 12:54:42,710 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_ringback(0x745c6f68 [0x18be990], "") 2020-04-05 12:54:42,710 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_ringback -> 0 2020-04-05 12:54:42,710 [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 2020-04-05 12:54:42,711 [DEBUG] [doorpi.sipphone.linphone_lib.Recorder] no recorder found in config at section DoorPi and key records 2020-04-05 12:54:42,711 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x745c6f68 [0x18be990]) 2020-04-05 12:54:42,711 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x74534580 2020-04-05 12:54:42,712 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0x745c6f68 [0x18be990]) 2020-04-05 12:54:42,712 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0x7452dda0 2020-04-05 12:54:42,712 [TRACE] [doorpi.conf.config_object] get_string for key capture_device in section SIP-Phone (default: ALSA: default device) returns ALSA: USB Audio Device 2020-04-05 12:54:42,713 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_capture_device(0x745c6f68 [0x18be990], "ALSA: USB Audio Device") 2020-04-05 12:54:42,713 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_capture_device -> 0 2020-04-05 12:54:42,713 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0x745c6f68 [0x18be990]) 2020-04-05 12:54:42,714 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0x7452dd40 2020-04-05 12:54:42,714 [TRACE] [doorpi.conf.config_object] get_string for key playback_device in section SIP-Phone (default: ALSA: default device) returns ALSA: USB Audio Device 2020-04-05 12:54:42,714 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_playback_device(0x745c6f68 [0x18be990], "ALSA: USB Audio Device") 2020-04-05 12:54:42,714 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_playback_device -> 0 2020-04-05 12:54:42,715 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x745c6f68 [0x18be990]) 2020-04-05 12:54:42,715 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x74534580 2020-04-05 12:54:42,716 [INFO] [doorpi.sipphone.from_linphone] found 3 possible sounddevices: 2020-04-05 12:54:42,716 [DEBUG] [doorpi.sipphone.from_linphone] |rec|play| name 2020-04-05 12:54:42,716 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2020-04-05 12:54:42,717 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x745c6f68 [0x18be990]) 2020-04-05 12:54:42,717 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x74534580 2020-04-05 12:54:42,717 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x745c6f68 [0x18be990], "ALSA: default device") 2020-04-05 12:54:42,717 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2d769c 2020-04-05 12:54:42,718 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x745c6f68 [0x18be990], "ALSA: default device") 2020-04-05 12:54:42,718 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2d769c 2020-04-05 12:54:42,718 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: default device 2020-04-05 12:54:42,719 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x745c6f68 [0x18be990], "ALSA: USB Audio Device") 2020-04-05 12:54:42,719 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2d769c 2020-04-05 12:54:42,719 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x745c6f68 [0x18be990], "ALSA: USB Audio Device") 2020-04-05 12:54:42,719 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2d769c 2020-04-05 12:54:42,720 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: USB Audio Device 2020-04-05 12:54:42,720 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x745c6f68 [0x18be990], "ALSA: bcm2835 ALSA") 2020-04-05 12:54:42,720 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2d7690 2020-04-05 12:54:42,720 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x745c6f68 [0x18be990], "ALSA: bcm2835 ALSA") 2020-04-05 12:54:42,721 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2d769c 2020-04-05 12:54:42,721 [DEBUG] [doorpi.sipphone.from_linphone] | O | X | ALSA: bcm2835 ALSA 2020-04-05 12:54:42,721 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2020-04-05 12:54:42,722 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0x745c6f68 [0x18be990]) 2020-04-05 12:54:42,722 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0x7452de60 2020-04-05 12:54:42,722 [DEBUG] [doorpi.sipphone.from_linphone] using capture_device: ALSA: USB Audio Device 2020-04-05 12:54:42,722 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0x745c6f68 [0x18be990]) 2020-04-05 12:54:42,723 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0x7452de90 2020-04-05 12:54:42,723 [DEBUG] [doorpi.sipphone.from_linphone] using playback_device: ALSA: USB Audio Device 2020-04-05 12:54:42,723 [TRACE] [doorpi.conf.config_object] get_list for key audio_codecs in section SIP-Phone (default: PCMA,PCMU) returns ['PCMA', 'PCMU'] 2020-04-05 12:54:42,724 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_audio_codecs(0x745c6f68 [0x18be990]) 2020-04-05 12:54:42,724 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1838e18) 2020-04-05 12:54:42,724 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2020-04-05 12:54:42,725 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d18410 2020-04-05 12:54:42,725 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2020-04-05 12:54:42,725 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d18410 2020-04-05 12:54:42,726 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d18410 2020-04-05 12:54:42,726 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x18be2c8) 2020-04-05 12:54:42,726 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2020-04-05 12:54:42,726 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d18420 2020-04-05 12:54:42,727 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2020-04-05 12:54:42,727 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d18420 2020-04-05 12:54:42,727 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d18420 2020-04-05 12:54:42,728 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x18be330) 2020-04-05 12:54:42,728 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2020-04-05 12:54:42,728 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d18430 2020-04-05 12:54:42,728 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2020-04-05 12:54:42,729 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d18430 2020-04-05 12:54:42,729 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d18430 2020-04-05 12:54:42,729 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x18be398) 2020-04-05 12:54:42,730 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2020-04-05 12:54:42,730 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d18440 2020-04-05 12:54:42,730 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2020-04-05 12:54:42,730 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d18440 2020-04-05 12:54:42,731 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d18440 2020-04-05 12:54:42,731 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x18be3f0) 2020-04-05 12:54:42,731 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2020-04-05 12:54:42,732 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d18450 2020-04-05 12:54:42,732 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2020-04-05 12:54:42,732 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d18450 2020-04-05 12:54:42,732 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d18450 2020-04-05 12:54:42,733 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x18bec60) 2020-04-05 12:54:42,733 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2020-04-05 12:54:42,733 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d18460 2020-04-05 12:54:42,734 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2020-04-05 12:54:42,734 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d18460 2020-04-05 12:54:42,734 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d18460 2020-04-05 12:54:42,734 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x18becb8) 2020-04-05 12:54:42,735 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2020-04-05 12:54:42,735 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d18470 2020-04-05 12:54:42,735 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2020-04-05 12:54:42,736 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d18470 2020-04-05 12:54:42,736 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d18470 2020-04-05 12:54:42,736 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x18bed10) 2020-04-05 12:54:42,736 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2020-04-05 12:54:42,737 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d18480 2020-04-05 12:54:42,737 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2020-04-05 12:54:42,737 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d18480 2020-04-05 12:54:42,738 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d18480 2020-04-05 12:54:42,738 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x18bed78) 2020-04-05 12:54:42,738 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2020-04-05 12:54:42,739 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d18490 2020-04-05 12:54:42,739 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2020-04-05 12:54:42,739 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d18490 2020-04-05 12:54:42,739 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d18490 2020-04-05 12:54:42,740 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x18bedd0) 2020-04-05 12:54:42,740 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2020-04-05 12:54:42,740 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d184a0 2020-04-05 12:54:42,740 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2020-04-05 12:54:42,741 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d184a0 2020-04-05 12:54:42,741 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d184a0 2020-04-05 12:54:42,741 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_audio_codecs -> 0x745345f8 2020-04-05 12:54:42,742 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d18410 [0x1838e18]) 2020-04-05 12:54:42,742 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745332e0 2020-04-05 12:54:42,742 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d18410 [0x1838e18]) 2020-04-05 12:54:42,743 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745332e0 2020-04-05 12:54:42,743 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec opus 2020-04-05 12:54:42,743 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x745c6f68 [0x18be990], 0x76d18410 [0x1838e18], 0x2d7690 [0]) 2020-04-05 12:54:42,744 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x172d8cc 2020-04-05 12:54:42,744 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d18420 [0x18be2c8]) 2020-04-05 12:54:42,744 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74533400 2020-04-05 12:54:42,744 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d18420 [0x18be2c8]) 2020-04-05 12:54:42,745 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74533400 2020-04-05 12:54:42,745 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2020-04-05 12:54:42,745 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x745c6f68 [0x18be990], 0x76d18420 [0x18be2c8], 0x2d7690 [0]) 2020-04-05 12:54:42,746 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x172d8cc 2020-04-05 12:54:42,746 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d18430 [0x18be330]) 2020-04-05 12:54:42,747 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745332e0 2020-04-05 12:54:42,747 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d18430 [0x18be330]) 2020-04-05 12:54:42,747 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745332e0 2020-04-05 12:54:42,747 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2020-04-05 12:54:42,748 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x745c6f68 [0x18be990], 0x76d18430 [0x18be330], 0x2d7690 [0]) 2020-04-05 12:54:42,748 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x172d8cc 2020-04-05 12:54:42,748 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d18440 [0x18be398]) 2020-04-05 12:54:42,749 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74533400 2020-04-05 12:54:42,749 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d18440 [0x18be398]) 2020-04-05 12:54:42,749 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74533400 2020-04-05 12:54:42,749 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMU 2020-04-05 12:54:42,750 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x745c6f68 [0x18be990], 0x76d18440 [0x18be398], 0x2d769c [1]) 2020-04-05 12:54:42,750 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x172d8cc 2020-04-05 12:54:42,751 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d18450 [0x18be3f0]) 2020-04-05 12:54:42,751 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745332e0 2020-04-05 12:54:42,751 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d18450 [0x18be3f0]) 2020-04-05 12:54:42,751 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745332e0 2020-04-05 12:54:42,752 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMA 2020-04-05 12:54:42,752 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x745c6f68 [0x18be990], 0x76d18450 [0x18be3f0], 0x2d769c [1]) 2020-04-05 12:54:42,752 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x172d8cc 2020-04-05 12:54:42,753 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d18460 [0x18bec60]) 2020-04-05 12:54:42,753 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745c6f80 2020-04-05 12:54:42,753 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d18460 [0x18bec60]) 2020-04-05 12:54:42,754 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745c6f80 2020-04-05 12:54:42,754 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec GSM 2020-04-05 12:54:42,754 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x745c6f68 [0x18be990], 0x76d18460 [0x18bec60], 0x2d7690 [0]) 2020-04-05 12:54:42,754 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x172d8cc 2020-04-05 12:54:42,755 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d18470 [0x18becb8]) 2020-04-05 12:54:42,755 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745332e0 2020-04-05 12:54:42,755 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d18470 [0x18becb8]) 2020-04-05 12:54:42,756 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745332e0 2020-04-05 12:54:42,756 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec G722 2020-04-05 12:54:42,756 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x745c6f68 [0x18be990], 0x76d18470 [0x18becb8], 0x2d7690 [0]) 2020-04-05 12:54:42,756 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x172d8cc 2020-04-05 12:54:42,757 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d18480 [0x18bed10]) 2020-04-05 12:54:42,757 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74533400 2020-04-05 12:54:42,757 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d18480 [0x18bed10]) 2020-04-05 12:54:42,757 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74533400 2020-04-05 12:54:42,758 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2020-04-05 12:54:42,758 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x745c6f68 [0x18be990], 0x76d18480 [0x18bed10], 0x2d7690 [0]) 2020-04-05 12:54:42,758 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x172d8cc 2020-04-05 12:54:42,758 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d18490 [0x18bed78]) 2020-04-05 12:54:42,759 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745c6f80 2020-04-05 12:54:42,759 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d18490 [0x18bed78]) 2020-04-05 12:54:42,759 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745c6f80 2020-04-05 12:54:42,759 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2020-04-05 12:54:42,760 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x745c6f68 [0x18be990], 0x76d18490 [0x18bed78], 0x2d7690 [0]) 2020-04-05 12:54:42,760 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x172d8cc 2020-04-05 12:54:42,760 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d184a0 [0x18bedd0]) 2020-04-05 12:54:42,761 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745c6f80 2020-04-05 12:54:42,761 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d184a0 [0x18bedd0]) 2020-04-05 12:54:42,761 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745c6f80 2020-04-05 12:54:42,761 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2020-04-05 12:54:42,762 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x745c6f68 [0x18be990], 0x76d184a0 [0x18bedd0], 0x2d7690 [0]) 2020-04-05 12:54:42,762 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x172d8cc 2020-04-05 12:54:42,762 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x745c6f68 [0x18be990]) 2020-04-05 12:54:42,763 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x74534530 2020-04-05 12:54:42,763 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x745c6f68 [0x18be990]) 2020-04-05 12:54:42,763 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x74534530 2020-04-05 12:54:42,763 [INFO] [doorpi.sipphone.from_linphone] found 2 possible videodevices: 2020-04-05 12:54:42,764 [DEBUG] [doorpi.sipphone.from_linphone] | name 2020-04-05 12:54:42,764 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2020-04-05 12:54:42,764 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x745c6f68 [0x18be990]) 2020-04-05 12:54:42,764 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x74534530 2020-04-05 12:54:42,765 [DEBUG] [doorpi.sipphone.from_linphone] | V4L2: /dev/video0 2020-04-05 12:54:42,765 [DEBUG] [doorpi.sipphone.from_linphone] | StaticImage: Static picture 2020-04-05 12:54:42,765 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2020-04-05 12:54:42,766 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x745c6f68 [0x18be990]) 2020-04-05 12:54:42,766 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x74534530 2020-04-05 12:54:42,766 [TRACE] [doorpi.conf.config_object] get_string for key video_device in section SIP-Phone (default: V4L2: /dev/video0) returns StaticImage: Static picture 2020-04-05 12:54:42,767 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x745c6f68 [0x18be990]) 2020-04-05 12:54:42,767 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x74534530 2020-04-05 12:54:42,767 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_capture(0x745c6f68 [0x18be990], 0x2d769c [1]) 2020-04-05 12:54:42,767 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_capture -> 0 2020-04-05 12:54:42,768 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_video_device(0x745c6f68 [0x18be990], "StaticImage: Static picture") 2020-04-05 12:54:42,768 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_video_device -> 0 2020-04-05 12:54:42,768 [TRACE] [doorpi.conf.config_object] get_string for key video_size in section SIP-Phone (default: vga) returns vga 2020-04-05 12:54:42,769 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_preferred_video_size_by_name(0x745c6f68 [0x18be990], "vga") 2020-04-05 12:54:42,769 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_preferred_video_size_by_name -> 0 2020-04-05 12:54:42,769 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_device(0x745c6f68 [0x18be990]) 2020-04-05 12:54:42,770 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_device -> 0x7452df80 2020-04-05 12:54:42,770 [DEBUG] [doorpi.sipphone.from_linphone] using video_device: StaticImage: Static picture 2020-04-05 12:54:42,770 [TRACE] [doorpi.conf.config_object] get_list for key video_codecs in section SIP-Phone (default: VP8) returns ['VP8'] 2020-04-05 12:54:42,771 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_codecs(0x745c6f68 [0x18be990]) 2020-04-05 12:54:42,771 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1838d78) 2020-04-05 12:54:42,771 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2020-04-05 12:54:42,771 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d184b0 2020-04-05 12:54:42,772 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2020-04-05 12:54:42,772 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d184b0 2020-04-05 12:54:42,772 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d184b0 2020-04-05 12:54:42,773 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_codecs -> 0x745346c0 2020-04-05 12:54:42,773 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d184b0 [0x1838d78]) 2020-04-05 12:54:42,773 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745c6f80 2020-04-05 12:54:42,773 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_video_capture_enabled(0x745c6f68 [0x18be990]) 2020-04-05 12:54:42,774 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_video_capture_enabled -> 0x2d769c 2020-04-05 12:54:42,774 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d184b0 [0x1838d78]) 2020-04-05 12:54:42,774 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745c6f80 2020-04-05 12:54:42,775 [DEBUG] [doorpi.sipphone.from_linphone] enable video codec VP8 2020-04-05 12:54:42,775 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x745c6f68 [0x18be990], 0x76d184b0 [0x1838d78], 0x2d769c [1]) 2020-04-05 12:54:42,775 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x172d8cc 2020-04-05 12:54:42,776 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_server in section SIP-Phone (default: ) returns 192.168.1.1 2020-04-05 12:54:42,776 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_username in section SIP-Phone (default: ) returns DoorPiUser620 2020-04-05 12:54:42,776 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_password in section SIP-Phone (default: DoorPiUser620) returns ******* 2020-04-05 12:54:42,777 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_realm in section SIP-Phone (default: 192.168.1.1) returns fritz.box 2020-04-05 12:54:42,777 [INFO] [doorpi.sipphone.from_linphone] using DoorPi with SIP-Server 2020-04-05 12:54:42,777 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_proxy_config(0x745c6f68 [0x18be990]) 2020-04-05 12:54:42,777 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_from_native_ptr(0x18d6560) 2020-04-05 12:54:42,778 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_new() 2020-04-05 12:54:42,778 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_new -> 0x76d184c0 2020-04-05 12:54:42,778 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_init() 2020-04-05 12:54:42,779 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_init -> 0x76d184c0 2020-04-05 12:54:42,779 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_from_native_ptr -> 0x76d184c0 2020-04-05 12:54:42,779 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_proxy_config -> 0x76d184c0 2020-04-05 12:54:42,780 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2020-04-05 12:54:42,780 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_class_method_new("DoorPi ") 2020-04-05 12:54:42,781 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0x18c2370) 2020-04-05 12:54:42,781 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2020-04-05 12:54:42,781 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0x76d184d0 2020-04-05 12:54:42,782 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2020-04-05 12:54:42,782 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0x76d184d0 2020-04-05 12:54:42,782 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0x76d184d0 2020-04-05 12:54:42,782 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_class_method_new -> 0x76d184d0 2020-04-05 12:54:42,783 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_identity_address(0x76d184c0 [0x18d6560], 0x76d184d0 [0x18c2370]) 2020-04-05 12:54:42,783 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_identity_address -> 0 2020-04-05 12:54:42,783 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_server_addr(0x76d184c0 [0x18d6560], "sip:192.168.1.1") 2020-04-05 12:54:42,784 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_server_addr -> 0 2020-04-05 12:54:42,784 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_enable_register(0x76d184c0 [0x18d6560], 0x2d769c [1]) 2020-04-05 12:54:42,785 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_enable_register -> 0 2020-04-05 12:54:42,785 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_proxy_config(0x745c6f68 [0x18be990], 0x76d184c0 [0x18d6560]) 2020-04-05 12:54:42,786 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_proxy_config -> 0x172d8cc 2020-04-05 12:54:42,786 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_default_proxy_config(0x745c6f68 [0x18be990], 0x76d184c0 [0x18d6560]) 2020-04-05 12:54:42,787 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_default_proxy_config -> 0 2020-04-05 12:54:42,787 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_auth_info(0x745c6f68 [0x18be990], "DoorPiUser620", "(null)", "DoorPiUserPW620", "(null)", "(null)", "fritz.box") 2020-04-05 12:54:42,787 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_from_native_ptr(0x18c23d8) 2020-04-05 12:54:42,787 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_new() 2020-04-05 12:54:42,788 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_new -> 0x76d184e0 2020-04-05 12:54:42,788 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_init() 2020-04-05 12:54:42,788 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_init -> 0x76d184e0 2020-04-05 12:54:42,789 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_from_native_ptr -> 0x76d184e0 2020-04-05 12:54:42,789 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_auth_info -> 0x76d184e0 2020-04-05 12:54:42,789 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_auth_info(0x745c6f68 [0x18be990], 0x76d184e0 [0x18c23d8]) 2020-04-05 12:54:42,790 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_auth_info -> None 2020-04-05 12:54:42,790 [DEBUG] [doorpi.sipphone.from_linphone] start successfully 2020-04-05 12:54:42,790 [TRACE] [doorpi.conf.config_object] get_sections returns ['EVENT_OnMediaRequired', 'EVENT_OnMediaNotRequired', 'EVENT_OnCallStateDisconnect', 'EVENT_OnStartup', 'EVENT_OnCallStateConnect'] 2020-04-05 12:54:42,791 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnMediaRequired' in configfile 2020-04-05 12:54:42,791 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnMediaRequired returns ['10'] 2020-04-05 12:54:42,791 [INFO] [doorpi.doorpi] registering action '10' for event 'OnMediaRequired' 2020-04-05 12:54:42,791 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnMediaRequired (default: ) returns os_execute:sudo service RelaisIN4 start 2020-04-05 12:54:42,792 [DEBUG] [doorpi.action.SingleActions.os_execute] doorpi.action.SingleActions.os_execute loaded 2020-04-05 12:54:42,793 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': 'sudo service RelaisIN4 start'} was added to new evententry OnMediaRequired 2020-04-05 12:54:42,794 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnMediaNotRequired' in configfile 2020-04-05 12:54:42,794 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnMediaNotRequired returns ['10', '20'] 2020-04-05 12:54:42,794 [INFO] [doorpi.doorpi] registering action '10' for event 'OnMediaNotRequired' 2020-04-05 12:54:42,795 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnMediaNotRequired (default: ) returns sleep:10 2020-04-05 12:54:42,795 [DEBUG] [doorpi.action.SingleActions.sleep] doorpi.action.SingleActions.sleep loaded 2020-04-05 12:54:42,796 [TRACE] [doorpi.action.handler] action SleepAction with args (10.0,) and kwargs {} was added to new evententry OnMediaNotRequired 2020-04-05 12:54:42,796 [INFO] [doorpi.doorpi] registering action '20' for event 'OnMediaNotRequired' 2020-04-05 12:54:42,797 [TRACE] [doorpi.conf.config_object] get_string for key 20 in section EVENT_OnMediaNotRequired (default: ) returns os_execute:sudo service RelaisIN4 stop 2020-04-05 12:54:42,798 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': 'sudo service RelaisIN4 stop'} was added to event OnMediaNotRequired 2020-04-05 12:54:42,798 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnCallStateDisconnect' in configfile 2020-04-05 12:54:42,799 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnCallStateDisconnect returns ['10'] 2020-04-05 12:54:42,799 [INFO] [doorpi.doorpi] registering action '10' for event 'OnCallStateDisconnect' 2020-04-05 12:54:42,799 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnCallStateDisconnect (default: ) returns out:Amp,0 2020-04-05 12:54:42,800 [DEBUG] [doorpi.action.SingleActions.out] doorpi.action.SingleActions.out loaded 2020-04-05 12:54:42,800 [DEBUG] [doorpi.action.SingleActions.out_triggered] doorpi.action.SingleActions.out_triggered loaded 2020-04-05 12:54:42,801 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': True, 'value': '0', 'pin': 'Amp'} was added to new evententry OnCallStateDisconnect 2020-04-05 12:54:42,801 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnStartup' in configfile 2020-04-05 12:54:42,802 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnStartup returns ['10', '30', '20', '40'] 2020-04-05 12:54:42,802 [INFO] [doorpi.doorpi] registering action '10' for event 'OnStartup' 2020-04-05 12:54:42,802 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnStartup (default: ) returns sleep:1 2020-04-05 12:54:42,803 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to new evententry OnStartup 2020-04-05 12:54:42,803 [INFO] [doorpi.doorpi] registering action '20' for event 'OnStartup' 2020-04-05 12:54:42,803 [TRACE] [doorpi.conf.config_object] get_string for key 20 in section EVENT_OnStartup (default: ) returns out:Amp,0 2020-04-05 12:54:42,804 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': True, 'value': '0', 'pin': 'Amp'} was added to event OnStartup 2020-04-05 12:54:42,804 [INFO] [doorpi.doorpi] registering action '30' for event 'OnStartup' 2020-04-05 12:54:42,804 [TRACE] [doorpi.conf.config_object] get_string for key 30 in section EVENT_OnStartup (default: ) returns out:Oeffner,1 2020-04-05 12:54:42,805 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': True, 'value': '1', 'pin': 'Oeffner'} was added to event OnStartup 2020-04-05 12:54:42,805 [INFO] [doorpi.doorpi] registering action '40' for event 'OnStartup' 2020-04-05 12:54:42,805 [TRACE] [doorpi.conf.config_object] get_string for key 40 in section EVENT_OnStartup (default: ) returns os_execute:sudo service RelaisIN4 stop 2020-04-05 12:54:42,806 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': 'sudo service RelaisIN4 stop'} was added to event OnStartup 2020-04-05 12:54:42,807 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnCallStateConnect' in configfile 2020-04-05 12:54:42,807 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnCallStateConnect returns ['10'] 2020-04-05 12:54:42,807 [INFO] [doorpi.doorpi] registering action '10' for event 'OnCallStateConnect' 2020-04-05 12:54:42,808 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnCallStateConnect (default: ) returns out:Amp,1 2020-04-05 12:54:42,808 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': True, 'value': '1', 'pin': 'Amp'} was added to new evententry OnCallStateConnect 2020-04-05 12:54:42,808 [TRACE] [doorpi.conf.config_object] get_string for key 18 in section onboardpins_InputPins (default: ) returns call:12 2020-04-05 12:54:42,809 [DEBUG] [doorpi.action.SingleActions.call] doorpi.action.SingleActions.call loaded 2020-04-05 12:54:42,809 [TRACE] [doorpi.action.handler] action CallAction with args () and kwargs {'number': '12'} was added to new evententry OnKeyPressed_onboardpins.18 2020-04-05 12:54:42,810 [TRACE] [doorpi.conf.config_object] get_string for key 16 in section onboardpins_InputPins (default: ) returns call:11 2020-04-05 12:54:42,810 [TRACE] [doorpi.action.handler] action CallAction with args () and kwargs {'number': '11'} was added to new evententry OnKeyPressed_onboardpins.16 2020-04-05 12:54:42,811 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns ['"#404"', '"#500"', '"#1"'] 2020-04-05 12:54:42,811 [TRACE] [doorpi.conf.config_object] get_string for key "#1" in section DTMF (default: ) returns out:Oeffner,0,1,5 2020-04-05 12:54:42,811 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '1', 'timeout': 5.0, 'pin': 'Oeffner', 'start_value': '0'} was added to new evententry OnDTMF_"#1" 2020-04-05 12:54:42,812 [TRACE] [doorpi.conf.config_object] get_string for key "#404" in section DTMF (default: ) returns os_execute:sudo reboot 2020-04-05 12:54:42,813 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': 'sudo reboot'} was added to new evententry OnDTMF_"#404" 2020-04-05 12:54:42,813 [TRACE] [doorpi.conf.config_object] get_string for key "#500" in section DTMF (default: ) returns os_execute:sudo shutdown 2020-04-05 12:54:42,814 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': 'sudo shutdown'} was added to new evententry OnDTMF_"#500" 2020-04-05 12:54:42,814 [TRACE] [doorpi.conf.config_object] get_string for key is_alive_led in section DoorPi (default: ) returns 2020-04-05 12:54:42,815 [TRACE] [doorpi.action.handler] fire Event BeforeStartup from doorpi.doorpi asyncron 2020-04-05 12:54:42,816 [DEBUG] [doorpi.action.handler] [1GN8GH] fire for event OnStartup this actions [, , , ] 2020-04-05 12:54:42,816 [DEBUG] [doorpi.action.handler] no actions for event BeforeStartup - skip fire_event BeforeStartup from doorpi.doorpi 2020-04-05 12:54:42,816 [TRACE] [doorpi.action.handler] [1GN8GH] try to fire action SleepAction with args (1.0,) and kwargs {} 2020-04-05 12:54:42,817 [TRACE] [doorpi.action.base] run SleepAction with args (1.0,) and kwargs {} 2020-04-05 12:54:43,819 [TRACE] [doorpi.action.handler] [1GN8GH] try to fire action OutAction with args () and kwargs {'log_output': True, 'value': '0', 'pin': 'Amp'} 2020-04-05 12:54:43,820 [TRACE] [doorpi.action.base] run OutAction with args () and kwargs {'log_output': True, 'value': '0', 'pin': 'Amp'} 2020-04-05 12:54:43,823 [DEBUG] [doorpi.keyboard.from_gpio] out(pin = 12, value = False, log_output = True) 2020-04-05 12:54:43,824 [TRACE] [doorpi.action.handler] [1GN8GH] try to fire action OutAction with args () and kwargs {'log_output': True, 'value': '1', 'pin': 'Oeffner'} 2020-04-05 12:54:43,825 [TRACE] [doorpi.action.base] run OutAction with args () and kwargs {'log_output': True, 'value': '1', 'pin': 'Oeffner'} 2020-04-05 12:54:43,827 [DEBUG] [doorpi.keyboard.from_gpio] out(pin = 11, value = True, log_output = True) 2020-04-05 12:54:43,828 [TRACE] [doorpi.action.handler] [1GN8GH] try to fire action OsExecuteAction with args () and kwargs {'command': 'sudo service RelaisIN4 stop'} 2020-04-05 12:54:43,829 [TRACE] [doorpi.action.base] run OsExecuteAction with args () and kwargs {'command': 'sudo service RelaisIN4 stop'} 2020-04-05 12:54:43,836 [TRACE] [doorpi.action.handler] fire Event OnKeyUp from doorpi.keyboard.from_gpio asyncron 2020-04-05 12:54:43,842 [TRACE] [doorpi.action.handler] fire Event OnKeyUp_18 from doorpi.keyboard.from_gpio asyncron 2020-04-05 12:54:43,843 [DEBUG] [doorpi.action.handler] no actions for event OnKeyUp - skip fire_event OnKeyUp from doorpi.keyboard.from_gpio 2020-04-05 12:54:43,849 [TRACE] [doorpi.action.handler] fire Event OnKeyUp_onboardpins.18 from doorpi.keyboard.from_gpio asyncron 2020-04-05 12:54:43,850 [DEBUG] [doorpi.action.handler] no actions for event OnKeyUp_18 - skip fire_event OnKeyUp_18 from doorpi.keyboard.from_gpio 2020-04-05 12:54:43,854 [TRACE] [doorpi.action.handler] fire Event OnKeyUp from doorpi.keyboard.from_gpio asyncron 2020-04-05 12:54:43,855 [DEBUG] [doorpi.action.handler] no actions for event OnKeyUp_onboardpins.18 - skip fire_event OnKeyUp_onboardpins.18 from doorpi.keyboard.from_gpio 2020-04-05 12:54:43,860 [DEBUG] [doorpi.action.handler] no actions for event OnKeyUp - skip fire_event OnKeyUp from doorpi.keyboard.from_gpio 2020-04-05 12:54:43,862 [TRACE] [doorpi.action.handler] fire Event OnKeyUp_16 from doorpi.keyboard.from_gpio asyncron 2020-04-05 12:54:43,866 [TRACE] [doorpi.action.handler] fire Event OnKeyUp_onboardpins.16 from doorpi.keyboard.from_gpio asyncron 2020-04-05 12:54:43,870 [DEBUG] [doorpi.action.handler] no actions for event OnKeyUp_onboardpins.16 - skip fire_event OnKeyUp_onboardpins.16 from doorpi.keyboard.from_gpio 2020-04-05 12:54:43,872 [DEBUG] [doorpi.action.handler] no actions for event OnKeyUp_16 - skip fire_event OnKeyUp_16 from doorpi.keyboard.from_gpio 2020-04-05 12:54:44,624 [TRACE] [doorpi.action.handler] [1GN8GH] finished fire_event for event_name OnStartup 2020-04-05 12:54:44,624 [TRACE] [doorpi.action.handler] fire Event AfterStartup from doorpi.doorpi asyncron 2020-04-05 12:54:44,625 [INFO] [doorpi.doorpi] DoorPi started successfully 2020-04-05 12:54:44,626 [INFO] [doorpi.doorpi] BasePath is /usr/local/etc/DoorPi 2020-04-05 12:54:44,626 [DEBUG] [doorpi.action.handler] no actions for event AfterStartup - skip fire_event AfterStartup from doorpi.doorpi 2020-04-05 12:54:44,627 [INFO] [doorpi.doorpi] Weburl is http://raspberrypi/ 2020-04-05 12:54:44,628 [INFO] [doorpi.sipphone.from_linphone] resolved to 192.168.1.1 2020-04-05 12:54:44,628 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2020-04-05 12:54:44,629 [INFO] [doorpi.sipphone.from_linphone] New local ip address is 192.168.1.9 2020-04-05 12:54:44,629 [INFO] [doorpi.sipphone.from_linphone] Network state is now [UP] 2020-04-05 12:54:44,630 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2020-04-05 12:54:44,630 [INFO] [doorpi.sipphone.from_linphone] 192.168.1.1 2020-04-05 12:54:44,630 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2020-04-05 12:54:44,631 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2020-04-05 12:54:44,632 [INFO] [doorpi.sipphone.from_linphone] LinphoneProxyConfig [0x18d6560] about to register (LinphoneCore version: 3.9.1) 2020-04-05 12:54:44,636 [INFO] [doorpi.sipphone.from_linphone] belle_sip_client_transaction_send_request(): waiting channel to be ready 2020-04-05 12:54:44,636 [INFO] [doorpi.sipphone.from_linphone] channel [0x18f2dc8]: starting resolution of 192.168.1.1 2020-04-05 12:54:44,636 [INFO] [doorpi.sipphone.from_linphone] channel 0x18f2dc8: state RES_IN_PROGRESS 2020-04-05 12:54:44,637 [INFO] [doorpi.sipphone.from_linphone] transaction [0x18e3d58] channel state changed to [RES_IN_PROGRESS] 2020-04-05 12:54:44,637 [INFO] [doorpi.sipphone.from_linphone] channel 0x18f2dc8: state RES_DONE 2020-04-05 12:54:44,637 [INFO] [doorpi.sipphone.from_linphone] transaction [0x18e3d58] channel state changed to [RES_DONE] 2020-04-05 12:54:44,638 [INFO] [doorpi.sipphone.from_linphone] channel 0x18f2dc8: state CONNECTING 2020-04-05 12:54:44,638 [INFO] [doorpi.sipphone.from_linphone] transaction [0x18e3d58] channel state changed to [CONNECTING] 2020-04-05 12:54:44,638 [INFO] [doorpi.sipphone.from_linphone] Trying to connect to [UDP://192.168.1.1:5060] 2020-04-05 12:54:44,639 [INFO] [doorpi.sipphone.from_linphone] belle_sip_get_src_addr_for(): af_inet6=0 2020-04-05 12:54:44,639 [INFO] [doorpi.sipphone.from_linphone] Channel has local address 192.168.1.9:5060 2020-04-05 12:54:44,639 [INFO] [doorpi.sipphone.from_linphone] channel 0x18f2dc8: state READY 2020-04-05 12:54:44,640 [INFO] [doorpi.sipphone.from_linphone] transaction [0x18e3d58] channel state changed to [READY] 2020-04-05 12:54:44,640 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x18e3d58], from state [INIT] to [TRYING] 2020-04-05 12:54:44,640 [INFO] [doorpi.sipphone.from_linphone] channel [0x18f2dc8]: message sent to [UDP://192.168.1.1:5060], size: [532] bytes REGISTER sip:192.168.1.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.9:5060;branch=z9hG4bK.YZ7LxF8AF;rport From: "DoorPi" ;tag=me9BMeEaF To: "DoorPi" CSeq: 20 REGISTER Call-ID: mcoiLkPhq9 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) 2020-04-05 12:54:44,641 [INFO] [doorpi.sipphone.from_linphone] Neither Expires header nor corresponding Contact header found, checking from original request 2020-04-05 12:54:44,641 [INFO] [doorpi.sipphone.from_linphone] Refresher [0x18dc048] takes ownership of transaction [0x18e3d58] 2020-04-05 12:54:44,641 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0x18d6560] for identity ["DoorPi" ] moving from state [LinphoneRegistrationNone] to [LinphoneRegistrationProgress] 2020-04-05 12:54:44,642 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0x18be990, 0x18d6560, 1, "Registration in progress") 2020-04-05 12:54:44,642 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2020-04-05 12:54:44,642 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x18be990] notifying [registration_state_changed] 2020-04-05 12:54:44,693 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_network_reachable(0x18be990, 0x1) 2020-04-05 12:54:44,693 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_network_reachable 2020-04-05 12:54:44,694 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x18be990] notifying [network_reachable] 2020-04-05 12:54:44,694 [INFO] [doorpi.sipphone.from_linphone] channel [0x18f2dc8]: received [379] new bytes from [UDP://192.168.1.1:5060]: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.1.9:5060;branch=z9hG4bK.YZ7LxF8AF;rport=5060 From: "DoorPi" ;tag=me9BMeEaF To: "DoorPi" ;tag=8D01E453191F16E2 Call-ID: mcoiLkPhq9 CSeq: 20 REGISTER WWW-Authenticate: Digest realm="fritz.box", nonce="FE312306D9DF5459" User-Agent: FRITZ!OS Content-Length: 0 2020-04-05 12:54:44,699 [INFO] [doorpi.sipphone.from_linphone] channel [0x18f2dc8] [379] bytes parsed 2020-04-05 12:54:44,699 [INFO] [doorpi.sipphone.from_linphone] channel [0x18f2dc8]: discovered public ip and port are [192.168.1.9:5060] 2020-04-05 12:54:44,700 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2020-04-05 12:54:44,700 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x18e3d58], from state [TRYING] to [COMPLETED] 2020-04-05 12:54:44,700 [INFO] [doorpi.sipphone.from_linphone] linphone_core_find_auth_info(): returning auth info username=DoorPiUser620, realm= 2020-04-05 12:54:44,701 [INFO] [doorpi.sipphone.from_linphone] Auth info found for [DoorPiUser620] realm [fritz.box] 2020-04-05 12:54:44,701 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x18f29c0], from state [INIT] to [TRYING] 2020-04-05 12:54:44,702 [INFO] [doorpi.sipphone.from_linphone] channel [0x18f2dc8]: message sent to [UDP://192.168.1.1:5060], size: [695] bytes REGISTER sip:192.168.1.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.9:5060;branch=z9hG4bK.UtRO9X-1Y;rport From: "DoorPi" ;tag=me9BMeEaF To: "DoorPi" CSeq: 21 REGISTER Call-ID: mcoiLkPhq9 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="FE312306D9DF5459", username="DoorPiUser620", uri="sip:192.168.1.1", response="0f73e60202ce66986ae61689c197aecf" 2020-04-05 12:54:44,702 [INFO] [doorpi.sipphone.from_linphone] resolved to 192.168.1.1 2020-04-05 12:54:44,702 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2020-04-05 12:54:44,753 [INFO] [doorpi.sipphone.from_linphone] channel [0x18f2dc8]: received [706] new bytes from [UDP://192.168.1.1:5060]: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.9:5060;branch=z9hG4bK.UtRO9X-1Y;rport=5060 From: "DoorPi" ;tag=me9BMeEaF To: "DoorPi" ;tag=6FFE9509F0460B51 Call-ID: mcoiLkPhq9 CSeq: 21 REGISTER Contact: ;+sip.instance="";expires=300 User-Agent: AVM FRITZ!Box 6591 Cable (lgi) 161.07.13 TAL (Jan 29 2020) 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 2020-04-05 12:54:44,760 [INFO] [doorpi.sipphone.from_linphone] channel [0x18f2dc8] [706] bytes parsed 2020-04-05 12:54:44,760 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2020-04-05 12:54:44,761 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x18f29c0], from state [TRYING] to [COMPLETED] 2020-04-05 12:54:44,761 [INFO] [doorpi.sipphone.from_linphone] Refresher [0x18dc048]: has no contact for request [0x19516c8]. 2020-04-05 12:54:44,761 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 270000 ms 2020-04-05 12:54:44,762 [INFO] [doorpi.sipphone.from_linphone] Register refresher [200] reason [OK] for proxy [sip:192.168.1.1] 2020-04-05 12:54:44,762 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0x18d6560] for identity ["DoorPi" ] moving from state [LinphoneRegistrationProgress] to [LinphoneRegistrationOk] 2020-04-05 12:54:44,762 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0x18be990, 0x18d6560, 2, "Registration successful") 2020-04-05 12:54:44,763 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2020-04-05 12:54:44,763 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x18be990] notifying [registration_state_changed] 2020-04-05 12:54:49,725 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x18e3d58], from state [COMPLETED] to [TERMINATED] 2020-04-05 12:54:49,725 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x18e3d58] terminated 2020-04-05 12:54:49,777 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x18f29c0], from state [COMPLETED] to [TERMINATED] 2020-04-05 12:54:49,778 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x18f29c0] terminated 2020-04-05 12:55:14,067 [INFO] [doorpi.sipphone.from_linphone] belle_sip_get_src_addr_for(): af_inet6=0 2020-04-05 12:55:14,069 [INFO] [doorpi.sipphone.from_linphone] Channel has local address 192.168.1.9:5060 2020-04-05 12:55:14,071 [INFO] [doorpi.sipphone.from_linphone] channel 0x192fd48: state READY 2020-04-05 12:55:14,073 [INFO] [doorpi.sipphone.from_linphone] udp_listening_point: new channel created to 69.162.69.50:51716 2020-04-05 12:55:14,074 [INFO] [doorpi.sipphone.from_linphone] channel [0x192fd48]: received [699] new bytes from [UDP://69.162.69.50:51716]: INVITE sip:000442037695128@109.192.148.164 SIP/2.0 Via: SIP/2.0/UDP 69.162.69.50:51716;branch=z9hG4bK385809918 Max-Forwards: 70 From: ;tag=647247954 To: Call-ID: 1280683237-1497652882-996808794 CSeq: 1 INVITE Contact: Content-Type: application/sdp Content-Length: 208 Allow: ACK, BYE, CANCEL, INFO, INVITE, MESSAGE, NOTIFY, OPTIONS, PRACK, REFER, REGISTER, SUBSCRIBE, UPDATE, PUBLISH v=0 o=5353 16264 18299 IN IP4 192.168.1.83 s=call c=IN IP4 192.168.1.83 t=0 0 m=audio 25282 RTP/AVP 0 101 a=rtpmap:0 pcmu/8000 a=rtpmap:8 pcma/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-11 2020-04-05 12:55:14,095 [INFO] [doorpi.sipphone.from_linphone] channel [0x192fd48] [491] bytes parsed 2020-04-05 12:55:14,098 [INFO] [doorpi.sipphone.from_linphone] channel [0x192fd48] read [208] bytes of body from [69.162.69.50:51716] 2020-04-05 12:55:14,104 [INFO] [doorpi.sipphone.from_linphone] Changing [server] [INVITE] transaction [0x18f0280], from state [INIT] to [PROCEEDING] 2020-04-05 12:55:14,107 [INFO] [doorpi.sipphone.from_linphone] channel [0x192fd48]: message sent to [UDP://69.162.69.50:51716], size: [230] bytes SIP/2.0 100 Trying Via: SIP/2.0/UDP 69.162.69.50:51716;branch=z9hG4bK385809918 From: ;tag=647247954 To: sip:000442037695128@109.192.148.164 Call-ID: 1280683237-1497652882-996808794 CSeq: 1 INVITE 2020-04-05 12:55:14,111 [INFO] [doorpi.sipphone.from_linphone] New server dialog [0x18f01f8] , local tag [], remote tag [647247954] 2020-04-05 12:55:14,112 [INFO] [doorpi.sipphone.from_linphone] op [0x18dacf8] : set_or_update_dialog() current=[(nil)] new=[0x18f01f8] 2020-04-05 12:55:14,113 [INFO] [doorpi.sipphone.from_linphone] new incoming call from [sip:5353@109.192.148.164] to [sip:000442037695128@109.192.148.164] 2020-04-05 12:55:14,118 [INFO] [doorpi.sipphone.from_linphone] Found payload pcmu/8000 fmtp= 2020-04-05 12:55:14,120 [INFO] [doorpi.sipphone.from_linphone] Found payload telephone-event/8000 fmtp=0-11 2020-04-05 12:55:14,124 [INFO] [doorpi.sipphone.from_linphone] Searching for already_a_call_with_remote_address. 2020-04-05 12:55:14,125 [INFO] [doorpi.sipphone.from_linphone] New LinphoneCall [0x19437c8] initialized (LinphoneCore version: 3.9.1) 2020-04-05 12:55:14,127 [INFO] [doorpi.sipphone.from_linphone] audio stream index found: 0, updating main audio stream index 2020-04-05 12:55:14,129 [INFO] [doorpi.sipphone.from_linphone] Call [0x19437c8]: disabling video in our call params because the remote doesn't want it. 2020-04-05 12:55:14,131 [INFO] [doorpi.sipphone.from_linphone] RtpSession bound to [0.0.0.0] ports [7078] [7079] 2020-04-05 12:55:14,134 [INFO] [doorpi.sipphone.from_linphone] Setting DSCP to 46 for MSAudio stream. 2020-04-05 12:55:14,136 [INFO] [doorpi.sipphone.from_linphone] Equalizer location: hp 2020-04-05 12:55:14,141 [INFO] [doorpi.sipphone.from_linphone] cannot set noise gate mode to [0] because no volume send 2020-04-05 12:55:14,143 [INFO] [doorpi.sipphone.from_linphone] Cannot determine multicast role for stream type [video] on call [0x19437c8] 2020-04-05 12:55:14,146 [INFO] [doorpi.sipphone.from_linphone] RtpSession bound to [0.0.0.0] ports [9078] [9079] 2020-04-05 12:55:14,148 [INFO] [doorpi.sipphone.from_linphone] Setting DSCP to 0 for MSVideo stream. 2020-04-05 12:55:14,150 [INFO] [doorpi.sipphone.from_linphone] Don't put video stream on local offer for call [0x19437c8] 2020-04-05 12:55:14,151 [INFO] [doorpi.sipphone.from_linphone] Don't put text stream on local offer for call [0x19437c8] 2020-04-05 12:55:14,154 [INFO] [doorpi.sipphone.from_linphone] Doing SDP offer/answer process of type incoming 2020-04-05 12:55:14,156 [INFO] [doorpi.sipphone.from_linphone] Starting local ring... 2020-04-05 12:55:14,159 [INFO] [doorpi.sipphone.from_linphone] speex_lib_ctl init with neon ? 0 2020-04-05 12:55:14,161 [WARNING] [doorpi.sipphone.from_linphone] MSFilePlayer[0x192b220]: failed to open ./share/sounds/linphone/rings/oldphone.wav: No such file or directory 2020-04-05 12:55:14,162 [INFO] [doorpi.sipphone.from_linphone] configuring resampler output to rate=[8000], nchannels=[1] 2020-04-05 12:55:14,164 [INFO] [doorpi.sipphone.from_linphone] ms_filter_link: MSFilePlayer:0x192b220,0-->MSDtmfGen:0x18dabf8,0 2020-04-05 12:55:14,166 [INFO] [doorpi.sipphone.from_linphone] Priority used: 99 2020-04-05 12:55:14,168 [INFO] [doorpi.sipphone.from_linphone] Ring MSTicker priority set to SCHED_RR and value (99) 2020-04-05 12:55:14,169 [INFO] [doorpi.sipphone.from_linphone] ms_filter_link: MSDtmfGen:0x18dabf8,0-->MSResample:0x192b280,0 2020-04-05 12:55:14,170 [INFO] [doorpi.sipphone.from_linphone] ms_filter_link: MSResample:0x192b280,0-->MSAlsaWrite:0x18e9db0,0 2020-04-05 12:55:14,171 [INFO] [doorpi.sipphone.from_linphone] Initializing speex resampler in mode [min] 2020-04-05 12:55:14,171 [INFO] [doorpi.sipphone.from_linphone] Call 0x19437c8: moving from state LinphoneCallIdle to LinphoneCallIncomingReceived 2020-04-05 12:55:14,172 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_call_state_changed(0x18be990, 0x19437c8, 1, "Incoming call") 2020-04-05 12:55:14,173 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_new() 2020-04-05 12:55:14,175 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_new -> 0x76d18530 2020-04-05 12:55:14,177 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_init() 2020-04-05 12:55:14,178 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_init -> 0x76d18530 2020-04-05 12:55:14,179 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (1 - Incoming call) 2020-04-05 12:55:14,179 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_get_remote_address(0x76d18530 [0x19437c8]) 2020-04-05 12:55:14,180 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0x192d120) 2020-04-05 12:55:14,181 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2020-04-05 12:55:14,182 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0x76d18500 2020-04-05 12:55:14,183 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2020-04-05 12:55:14,184 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0x76d18500 2020-04-05 12:55:14,185 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0x76d18500 2020-04-05 12:55:14,186 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_get_remote_address -> 0x76d18500 2020-04-05 12:55:14,188 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_instance_method_as_string_uri_only(0x76d18500 [0x192d120]) 2020-04-05 12:55:14,189 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_instance_method_as_string_uri_only -> 0x7453a830 2020-04-05 12:55:14,190 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-04-05 12:55:14,192 [TRACE] [doorpi.action.handler] fire Event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-04-05 12:55:14,192 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:14,194 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_get_state(0x76d18530 [0x19437c8]) 2020-04-05 12:55:14,195 [DEBUG] [doorpi.action.handler] no actions for event BeforeCallIncoming - skip fire_event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:14,195 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_get_state -> 0x172d8c0 2020-04-05 12:55:14,196 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] is_admin_number (sip:5353@109.192.148.164) 2020-04-05 12:55:14,197 [TRACE] [doorpi.conf.config_object] get_keys for section AdminNumbers returns ['**610', '**612', '**614', '**615'] 2020-04-05 12:55:14,198 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] sip:5353@109.192.148.164 is not an adminnumber 2020-04-05 12:55:14,198 [TRACE] [doorpi.action.handler] fire Event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-04-05 12:55:14,199 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_decline_call(0x745c6f68 [0x18be990], 0x76d18530 [0x19437c8], 2) 2020-04-05 12:55:14,200 [DEBUG] [doorpi.action.handler] no actions for event OnCallReject - skip fire_event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:14,201 [INFO] [doorpi.sipphone.from_linphone] channel [0x192fd48]: message sent to [UDP://69.162.69.50:51716], size: [299] bytes SIP/2.0 403 Forbidden Via: SIP/2.0/UDP 69.162.69.50:51716;branch=z9hG4bK385809918 From: ;tag=647247954 To: ;tag=WF40qqV Call-ID: 1280683237-1497652882-996808794 CSeq: 1 INVITE User-Agent: (belle-sip/1.4.2) Supported: outbound 2020-04-05 12:55:14,205 [INFO] [doorpi.sipphone.from_linphone] Changing [server] [INVITE] transaction [0x18f0280], from state [PROCEEDING] to [COMPLETED] 2020-04-05 12:55:14,206 [INFO] [doorpi.sipphone.from_linphone] Dialog [0x18f01f8]: now updated by transaction [0x18f0280]. 2020-04-05 12:55:14,207 [INFO] [doorpi.sipphone.from_linphone] dialog [0x18f01f8] deleted. 2020-04-05 12:55:14,207 [INFO] [doorpi.sipphone.from_linphone] ms_filter_unlink: MSFilePlayer:0x192b220,0-->MSDtmfGen:0x18dabf8,0 2020-04-05 12:55:14,208 [INFO] [doorpi.sipphone.from_linphone] ms_filter_unlink: MSDtmfGen:0x18dabf8,0-->MSResample:0x192b280,0 2020-04-05 12:55:14,208 [INFO] [doorpi.sipphone.from_linphone] ms_filter_unlink: MSResample:0x192b280,0-->MSAlsaWrite:0x18e9db0,0 2020-04-05 12:55:14,215 [INFO] [doorpi.sipphone.from_linphone] Ring MSTicker thread exiting 2020-04-05 12:55:14,216 [INFO] [doorpi.sipphone.from_linphone] =========================================================== 2020-04-05 12:55:14,217 [INFO] [doorpi.sipphone.from_linphone] FILTER USAGE STATISTICS 2020-04-05 12:55:14,218 [INFO] [doorpi.sipphone.from_linphone] Name Count Time/tick (ms) CPU Usage 2020-04-05 12:55:14,218 [INFO] [doorpi.sipphone.from_linphone] ----------------------------------------------------------- 2020-04-05 12:55:14,219 [INFO] [doorpi.sipphone.from_linphone] MSFilePlayer 4 0.0011666 58.0167 2020-04-05 12:55:14,220 [INFO] [doorpi.sipphone.from_linphone] MSDtmfGen 4 0.000844 41.9733 2020-04-05 12:55:14,221 [INFO] [doorpi.sipphone.from_linphone] MSResample 0 0 0 2020-04-05 12:55:14,222 [INFO] [doorpi.sipphone.from_linphone] MSAlsaWrite 0 0 0 2020-04-05 12:55:14,222 [INFO] [doorpi.sipphone.from_linphone] MSItcSink 0 0 0 2020-04-05 12:55:14,223 [INFO] [doorpi.sipphone.from_linphone] MSTee 0 0 0 2020-04-05 12:55:14,224 [INFO] [doorpi.sipphone.from_linphone] MSSpeexEC 0 0 0 2020-04-05 12:55:14,225 [INFO] [doorpi.sipphone.from_linphone] MSRtpSend 0 0 0 2020-04-05 12:55:14,226 [INFO] [doorpi.sipphone.from_linphone] =========================================================== 2020-04-05 12:55:14,226 [INFO] [doorpi.sipphone.from_linphone] Call 0x19437c8: moving from state LinphoneCallIncomingReceived to LinphoneCallEnd 2020-04-05 12:55:14,227 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_call_log_updated(0x18be990, 0x19507b0) 2020-04-05 12:55:14,228 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_call_log_updated 2020-04-05 12:55:14,229 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x18be990] notifying [call_log_updated] 2020-04-05 12:55:14,229 [INFO] [doorpi.sipphone.from_linphone] Resetting the current call 2020-04-05 12:55:14,230 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_call_state_changed(0x18be990, 0x19437c8, 13, "Call terminated") 2020-04-05 12:55:14,231 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (13 - Call terminated) 2020-04-05 12:55:14,231 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_get_remote_address(0x76d18530 [0x19437c8]) 2020-04-05 12:55:14,232 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0x192d120) 2020-04-05 12:55:14,234 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2020-04-05 12:55:14,236 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0x76d18560 2020-04-05 12:55:14,237 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2020-04-05 12:55:14,239 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0x76d18560 2020-04-05 12:55:14,240 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0x76d18560 2020-04-05 12:55:14,242 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_get_remote_address -> 0x76d18560 2020-04-05 12:55:14,243 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_instance_method_as_string_uri_only(0x76d18560 [0x192d120]) 2020-04-05 12:55:14,244 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_instance_method_as_string_uri_only -> 0x7453aa10 2020-04-05 12:55:14,245 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-04-05 12:55:14,247 [TRACE] [doorpi.action.handler] fire Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-04-05 12:55:14,247 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:14,248 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x745c6f68 [0x18be990]) 2020-04-05 12:55:14,249 [DEBUG] [doorpi.action.handler] [VECAGN] fire for event OnCallStateDisconnect this actions [] 2020-04-05 12:55:14,250 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x172d8cc 2020-04-05 12:55:14,250 [TRACE] [doorpi.action.handler] [VECAGN] try to fire action OutAction with args () and kwargs {'log_output': True, 'value': '0', 'pin': 'Amp'} 2020-04-05 12:55:14,251 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x745c6f68 [0x18be990]) 2020-04-05 12:55:14,251 [TRACE] [doorpi.action.base] run OutAction with args () and kwargs {'log_output': True, 'value': '0', 'pin': 'Amp'} 2020-04-05 12:55:14,254 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x172d8cc 2020-04-05 12:55:14,255 [DEBUG] [doorpi.keyboard.from_gpio] out(pin = 12, value = False, log_output = True) 2020-04-05 12:55:14,257 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x745c6f68 [0x18be990]) 2020-04-05 12:55:14,258 [TRACE] [doorpi.action.handler] [VECAGN] finished fire_event for event_name OnCallStateDisconnect 2020-04-05 12:55:14,259 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x172d8cc 2020-04-05 12:55:14,261 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_call_state_changed 2020-04-05 12:55:14,262 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x18be990] notifying [call_state_changed] 2020-04-05 12:55:14,263 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_decline_call -> 0x172d8cc 2020-04-05 12:55:14,264 [TRACE] [doorpi.action.handler] fire Event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-04-05 12:55:14,265 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x745c6f68 [0x18be990]) 2020-04-05 12:55:14,265 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x172d8cc 2020-04-05 12:55:14,266 [DEBUG] [doorpi.action.handler] no actions for event AfterCallReject - skip fire_event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:14,266 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x745c6f68 [0x18be990]) 2020-04-05 12:55:14,266 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x172d8cc 2020-04-05 12:55:14,267 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x745c6f68 [0x18be990]) 2020-04-05 12:55:14,267 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x172d8cc 2020-04-05 12:55:14,267 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_call_state_changed 2020-04-05 12:55:14,268 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x18be990] notifying [call_state_changed] 2020-04-05 12:55:14,318 [INFO] [doorpi.sipphone.from_linphone] Dialog [0x18f01f8] terminated for op [0x18dacf8] 2020-04-05 12:55:14,369 [INFO] [doorpi.sipphone.from_linphone] Call 0x19437c8: moving from state LinphoneCallEnd to LinphoneCallReleased 2020-04-05 12:55:14,370 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_call_state_changed(0x18be990, 0x19437c8, 18, "Call released") 2020-04-05 12:55:14,370 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (18 - Call released) 2020-04-05 12:55:14,371 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_get_remote_address(0x76d18530 [0x19437c8]) 2020-04-05 12:55:14,371 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0x192d120) 2020-04-05 12:55:14,371 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2020-04-05 12:55:14,372 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0x76d18550 2020-04-05 12:55:14,372 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2020-04-05 12:55:14,372 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0x76d18550 2020-04-05 12:55:14,372 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0x76d18550 2020-04-05 12:55:14,373 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_get_remote_address -> 0x76d18550 2020-04-05 12:55:14,373 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_instance_method_as_string_uri_only(0x76d18550 [0x192d120]) 2020-04-05 12:55:14,373 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_instance_method_as_string_uri_only -> 0x7453abf0 2020-04-05 12:55:14,374 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-04-05 12:55:14,375 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x745c6f68 [0x18be990]) 2020-04-05 12:55:14,375 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:14,375 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x172d8cc 2020-04-05 12:55:14,376 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x745c6f68 [0x18be990]) 2020-04-05 12:55:14,376 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x172d8cc 2020-04-05 12:55:14,377 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x745c6f68 [0x18be990]) 2020-04-05 12:55:14,377 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x172d8cc 2020-04-05 12:55:14,377 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_call_state_changed 2020-04-05 12:55:14,378 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x18be990] notifying [call_state_changed] 2020-04-05 12:55:14,378 [INFO] [doorpi.sipphone.from_linphone] op [0x18dacf8] : set_or_update_dialog() current=[0x18f01f8] new=[(nil)] 2020-04-05 12:55:14,731 [INFO] [doorpi.sipphone.from_linphone] belle_sip_get_src_addr_for(): af_inet6=0 2020-04-05 12:55:14,732 [INFO] [doorpi.sipphone.from_linphone] Channel has local address 192.168.1.9:5060 2020-04-05 12:55:14,732 [INFO] [doorpi.sipphone.from_linphone] channel 0x1951a98: state READY 2020-04-05 12:55:14,733 [INFO] [doorpi.sipphone.from_linphone] udp_listening_point: new channel created to 69.162.69.50:52015 2020-04-05 12:55:14,733 [INFO] [doorpi.sipphone.from_linphone] channel [0x1951a98]: received [700] new bytes from [UDP://69.162.69.50:52015]: INVITE sip:000441519470626@109.192.148.164 SIP/2.0 Via: SIP/2.0/UDP 69.162.69.50:52015;branch=z9hG4bK2001995019 Max-Forwards: 70 From: ;tag=1415431335 To: Call-ID: 1670951707-234022977-860068121 CSeq: 1 INVITE Contact: Content-Type: application/sdp Content-Length: 208 Allow: ACK, BYE, CANCEL, INFO, INVITE, MESSAGE, NOTIFY, OPTIONS, PRACK, REFER, REGISTER, SUBSCRIBE, UPDATE, PUBLISH v=0 o=5253 16264 18299 IN IP4 192.168.1.83 s=call c=IN IP4 192.168.1.83 t=0 0 m=audio 25282 RTP/AVP 0 101 a=rtpmap:0 pcmu/8000 a=rtpmap:8 pcma/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-11 2020-04-05 12:55:14,739 [INFO] [doorpi.sipphone.from_linphone] channel [0x1951a98] [492] bytes parsed 2020-04-05 12:55:14,740 [INFO] [doorpi.sipphone.from_linphone] channel [0x1951a98] read [208] bytes of body from [69.162.69.50:52015] 2020-04-05 12:55:14,742 [INFO] [doorpi.sipphone.from_linphone] Changing [server] [INVITE] transaction [0x18e9d30], from state [INIT] to [PROCEEDING] 2020-04-05 12:55:14,743 [INFO] [doorpi.sipphone.from_linphone] channel [0x1951a98]: message sent to [UDP://69.162.69.50:52015], size: [231] bytes SIP/2.0 100 Trying Via: SIP/2.0/UDP 69.162.69.50:52015;branch=z9hG4bK2001995019 From: ;tag=1415431335 To: sip:000441519470626@109.192.148.164 Call-ID: 1670951707-234022977-860068121 CSeq: 1 INVITE 2020-04-05 12:55:14,743 [INFO] [doorpi.sipphone.from_linphone] New server dialog [0x18f01f8] , local tag [], remote tag [1415431335] 2020-04-05 12:55:14,744 [INFO] [doorpi.sipphone.from_linphone] op [0x18e17a0] : set_or_update_dialog() current=[(nil)] new=[0x18f01f8] 2020-04-05 12:55:14,744 [INFO] [doorpi.sipphone.from_linphone] new incoming call from [sip:5253@109.192.148.164] to [sip:000441519470626@109.192.148.164] 2020-04-05 12:55:14,746 [INFO] [doorpi.sipphone.from_linphone] Found payload pcmu/8000 fmtp= 2020-04-05 12:55:14,747 [INFO] [doorpi.sipphone.from_linphone] Found payload telephone-event/8000 fmtp=0-11 2020-04-05 12:55:14,748 [INFO] [doorpi.sipphone.from_linphone] Searching for already_a_call_with_remote_address. 2020-04-05 12:55:14,749 [INFO] [doorpi.sipphone.from_linphone] New LinphoneCall [0x18d8850] initialized (LinphoneCore version: 3.9.1) 2020-04-05 12:55:14,749 [INFO] [doorpi.sipphone.from_linphone] audio stream index found: 0, updating main audio stream index 2020-04-05 12:55:14,750 [INFO] [doorpi.sipphone.from_linphone] Call [0x18d8850]: disabling video in our call params because the remote doesn't want it. 2020-04-05 12:55:14,750 [INFO] [doorpi.sipphone.from_linphone] RtpSession bound to [0.0.0.0] ports [7078] [7079] 2020-04-05 12:55:14,751 [INFO] [doorpi.sipphone.from_linphone] Setting DSCP to 46 for MSAudio stream. 2020-04-05 12:55:14,751 [INFO] [doorpi.sipphone.from_linphone] Equalizer location: hp 2020-04-05 12:55:14,753 [INFO] [doorpi.sipphone.from_linphone] cannot set noise gate mode to [0] because no volume send 2020-04-05 12:55:14,754 [INFO] [doorpi.sipphone.from_linphone] Cannot determine multicast role for stream type [video] on call [0x18d8850] 2020-04-05 12:55:14,754 [INFO] [doorpi.sipphone.from_linphone] RtpSession bound to [0.0.0.0] ports [9078] [9079] 2020-04-05 12:55:14,755 [INFO] [doorpi.sipphone.from_linphone] Setting DSCP to 0 for MSVideo stream. 2020-04-05 12:55:14,755 [INFO] [doorpi.sipphone.from_linphone] Don't put video stream on local offer for call [0x18d8850] 2020-04-05 12:55:14,756 [INFO] [doorpi.sipphone.from_linphone] Don't put text stream on local offer for call [0x18d8850] 2020-04-05 12:55:14,756 [INFO] [doorpi.sipphone.from_linphone] Doing SDP offer/answer process of type incoming 2020-04-05 12:55:14,757 [INFO] [doorpi.sipphone.from_linphone] Starting local ring... 2020-04-05 12:55:14,757 [INFO] [doorpi.sipphone.from_linphone] speex_lib_ctl init with neon ? 0 2020-04-05 12:55:14,758 [WARNING] [doorpi.sipphone.from_linphone] MSFilePlayer[0x18da470]: failed to open ./share/sounds/linphone/rings/oldphone.wav: No such file or directory 2020-04-05 12:55:14,758 [INFO] [doorpi.sipphone.from_linphone] configuring resampler output to rate=[8000], nchannels=[1] 2020-04-05 12:55:14,758 [INFO] [doorpi.sipphone.from_linphone] ms_filter_link: MSFilePlayer:0x18da470,0-->MSDtmfGen:0x1943fc0,0 2020-04-05 12:55:14,759 [INFO] [doorpi.sipphone.from_linphone] Priority used: 99 2020-04-05 12:55:14,759 [INFO] [doorpi.sipphone.from_linphone] Ring MSTicker priority set to SCHED_RR and value (99) 2020-04-05 12:55:14,759 [INFO] [doorpi.sipphone.from_linphone] ms_filter_link: MSDtmfGen:0x1943fc0,0-->MSResample:0x1942690,0 2020-04-05 12:55:14,759 [INFO] [doorpi.sipphone.from_linphone] ms_filter_link: MSResample:0x1942690,0-->MSAlsaWrite:0x19458d0,0 2020-04-05 12:55:14,760 [INFO] [doorpi.sipphone.from_linphone] Initializing speex resampler in mode [min] 2020-04-05 12:55:14,760 [INFO] [doorpi.sipphone.from_linphone] Call 0x18d8850: moving from state LinphoneCallIdle to LinphoneCallIncomingReceived 2020-04-05 12:55:14,760 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_call_state_changed(0x18be990, 0x18d8850, 1, "Incoming call") 2020-04-05 12:55:14,761 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_new() 2020-04-05 12:55:14,761 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_new -> 0x76d18540 2020-04-05 12:55:14,761 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_init() 2020-04-05 12:55:14,761 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_init -> 0x76d18540 2020-04-05 12:55:14,762 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (1 - Incoming call) 2020-04-05 12:55:14,762 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_get_remote_address(0x76d18540 [0x18d8850]) 2020-04-05 12:55:14,762 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0x193ff08) 2020-04-05 12:55:14,763 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2020-04-05 12:55:14,763 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0x76d18520 2020-04-05 12:55:14,763 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2020-04-05 12:55:14,763 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0x76d18520 2020-04-05 12:55:14,764 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0x76d18520 2020-04-05 12:55:14,764 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_get_remote_address -> 0x76d18520 2020-04-05 12:55:14,764 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_instance_method_as_string_uri_only(0x76d18520 [0x193ff08]) 2020-04-05 12:55:14,765 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_instance_method_as_string_uri_only -> 0x7453ac20 2020-04-05 12:55:14,765 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-04-05 12:55:14,766 [TRACE] [doorpi.action.handler] fire Event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-04-05 12:55:14,767 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_get_state(0x76d18540 [0x18d8850]) 2020-04-05 12:55:14,767 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:14,768 [DEBUG] [doorpi.action.handler] no actions for event BeforeCallIncoming - skip fire_event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:14,768 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_get_state -> 0x172d8c0 2020-04-05 12:55:14,769 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] is_admin_number (sip:5253@109.192.148.164) 2020-04-05 12:55:14,769 [TRACE] [doorpi.conf.config_object] get_keys for section AdminNumbers returns ['**610', '**612', '**614', '**615'] 2020-04-05 12:55:14,769 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] sip:5253@109.192.148.164 is not an adminnumber 2020-04-05 12:55:14,770 [TRACE] [doorpi.action.handler] fire Event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-04-05 12:55:14,771 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_decline_call(0x745c6f68 [0x18be990], 0x76d18540 [0x18d8850], 2) 2020-04-05 12:55:14,771 [INFO] [doorpi.sipphone.from_linphone] channel [0x1951a98]: message sent to [UDP://69.162.69.50:52015], size: [300] bytes SIP/2.0 403 Forbidden Via: SIP/2.0/UDP 69.162.69.50:52015;branch=z9hG4bK2001995019 From: ;tag=1415431335 To: ;tag=b3AGePA Call-ID: 1670951707-234022977-860068121 CSeq: 1 INVITE User-Agent: (belle-sip/1.4.2) Supported: outbound 2020-04-05 12:55:14,772 [DEBUG] [doorpi.action.handler] no actions for event OnCallReject - skip fire_event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:14,772 [INFO] [doorpi.sipphone.from_linphone] Changing [server] [INVITE] transaction [0x18e9d30], from state [PROCEEDING] to [COMPLETED] 2020-04-05 12:55:14,773 [INFO] [doorpi.sipphone.from_linphone] Dialog [0x18f01f8]: now updated by transaction [0x18e9d30]. 2020-04-05 12:55:14,773 [INFO] [doorpi.sipphone.from_linphone] dialog [0x18f01f8] deleted. 2020-04-05 12:55:14,774 [INFO] [doorpi.sipphone.from_linphone] ms_filter_unlink: MSFilePlayer:0x18da470,0-->MSDtmfGen:0x1943fc0,0 2020-04-05 12:55:14,774 [INFO] [doorpi.sipphone.from_linphone] ms_filter_unlink: MSDtmfGen:0x1943fc0,0-->MSResample:0x1942690,0 2020-04-05 12:55:14,774 [INFO] [doorpi.sipphone.from_linphone] ms_filter_unlink: MSResample:0x1942690,0-->MSAlsaWrite:0x19458d0,0 2020-04-05 12:55:14,779 [INFO] [doorpi.sipphone.from_linphone] Ring MSTicker thread exiting 2020-04-05 12:55:14,779 [INFO] [doorpi.sipphone.from_linphone] =========================================================== 2020-04-05 12:55:14,779 [INFO] [doorpi.sipphone.from_linphone] FILTER USAGE STATISTICS 2020-04-05 12:55:14,779 [INFO] [doorpi.sipphone.from_linphone] Name Count Time/tick (ms) CPU Usage 2020-04-05 12:55:14,780 [INFO] [doorpi.sipphone.from_linphone] ----------------------------------------------------------- 2020-04-05 12:55:14,780 [INFO] [doorpi.sipphone.from_linphone] MSFilePlayer 1 0.0009375 60.9954 2020-04-05 12:55:14,780 [INFO] [doorpi.sipphone.from_linphone] MSDtmfGen 1 0.000599 38.972 2020-04-05 12:55:14,781 [INFO] [doorpi.sipphone.from_linphone] MSResample 0 0 0 2020-04-05 12:55:14,781 [INFO] [doorpi.sipphone.from_linphone] MSAlsaWrite 0 0 0 2020-04-05 12:55:14,781 [INFO] [doorpi.sipphone.from_linphone] MSItcSink 0 0 0 2020-04-05 12:55:14,781 [INFO] [doorpi.sipphone.from_linphone] MSTee 0 0 0 2020-04-05 12:55:14,782 [INFO] [doorpi.sipphone.from_linphone] MSSpeexEC 0 0 0 2020-04-05 12:55:14,782 [INFO] [doorpi.sipphone.from_linphone] MSRtpSend 0 0 0 2020-04-05 12:55:14,782 [INFO] [doorpi.sipphone.from_linphone] =========================================================== 2020-04-05 12:55:14,783 [INFO] [doorpi.sipphone.from_linphone] Call 0x18d8850: moving from state LinphoneCallIncomingReceived to LinphoneCallEnd 2020-04-05 12:55:14,783 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_call_log_updated(0x18be990, 0x192e708) 2020-04-05 12:55:14,784 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_call_log_updated 2020-04-05 12:55:14,784 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x18be990] notifying [call_log_updated] 2020-04-05 12:55:14,784 [INFO] [doorpi.sipphone.from_linphone] Resetting the current call 2020-04-05 12:55:14,784 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_call_state_changed(0x18be990, 0x18d8850, 13, "Call terminated") 2020-04-05 12:55:14,785 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (13 - Call terminated) 2020-04-05 12:55:14,785 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_get_remote_address(0x76d18540 [0x18d8850]) 2020-04-05 12:55:14,786 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0x193ff08) 2020-04-05 12:55:14,786 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2020-04-05 12:55:14,786 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0x76d18590 2020-04-05 12:55:14,786 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2020-04-05 12:55:14,787 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0x76d18590 2020-04-05 12:55:14,787 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0x76d18590 2020-04-05 12:55:14,787 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_get_remote_address -> 0x76d18590 2020-04-05 12:55:14,788 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_instance_method_as_string_uri_only(0x76d18590 [0x193ff08]) 2020-04-05 12:55:14,788 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_instance_method_as_string_uri_only -> 0x7453ad70 2020-04-05 12:55:14,788 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-04-05 12:55:14,789 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:14,790 [TRACE] [doorpi.action.handler] fire Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-04-05 12:55:14,791 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x745c6f68 [0x18be990]) 2020-04-05 12:55:14,792 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x172d8cc 2020-04-05 12:55:14,792 [DEBUG] [doorpi.action.handler] [91N231] fire for event OnCallStateDisconnect this actions [] 2020-04-05 12:55:14,792 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x745c6f68 [0x18be990]) 2020-04-05 12:55:14,793 [TRACE] [doorpi.action.handler] [91N231] try to fire action OutAction with args () and kwargs {'log_output': True, 'value': '0', 'pin': 'Amp'} 2020-04-05 12:55:14,793 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x172d8cc 2020-04-05 12:55:14,793 [TRACE] [doorpi.action.base] run OutAction with args () and kwargs {'log_output': True, 'value': '0', 'pin': 'Amp'} 2020-04-05 12:55:14,794 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x745c6f68 [0x18be990]) 2020-04-05 12:55:14,795 [DEBUG] [doorpi.keyboard.from_gpio] out(pin = 12, value = False, log_output = True) 2020-04-05 12:55:14,796 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x172d8cc 2020-04-05 12:55:14,797 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_call_state_changed 2020-04-05 12:55:14,797 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x18be990] notifying [call_state_changed] 2020-04-05 12:55:14,797 [TRACE] [doorpi.action.handler] [91N231] finished fire_event for event_name OnCallStateDisconnect 2020-04-05 12:55:14,798 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_decline_call -> 0x172d8cc 2020-04-05 12:55:14,798 [TRACE] [doorpi.action.handler] fire Event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-04-05 12:55:14,799 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x745c6f68 [0x18be990]) 2020-04-05 12:55:14,800 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x172d8cc 2020-04-05 12:55:14,800 [DEBUG] [doorpi.action.handler] no actions for event AfterCallReject - skip fire_event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:14,800 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x745c6f68 [0x18be990]) 2020-04-05 12:55:14,801 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x172d8cc 2020-04-05 12:55:14,801 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x745c6f68 [0x18be990]) 2020-04-05 12:55:14,801 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x172d8cc 2020-04-05 12:55:14,802 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_call_state_changed 2020-04-05 12:55:14,802 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x18be990] notifying [call_state_changed] 2020-04-05 12:55:14,802 [INFO] [doorpi.sipphone.from_linphone] channel [0x192fd48]: message sent to [UDP://69.162.69.50:51716], size: [299] bytes SIP/2.0 403 Forbidden Via: SIP/2.0/UDP 69.162.69.50:51716;branch=z9hG4bK385809918 From: ;tag=647247954 To: ;tag=WF40qqV Call-ID: 1280683237-1497652882-996808794 CSeq: 1 INVITE User-Agent: (belle-sip/1.4.2) Supported: outbound 2020-04-05 12:55:14,853 [INFO] [doorpi.sipphone.from_linphone] Dialog [0x18f01f8] terminated for op [0x18e17a0] 2020-04-05 12:55:14,904 [INFO] [doorpi.sipphone.from_linphone] Call 0x18d8850: moving from state LinphoneCallEnd to LinphoneCallReleased 2020-04-05 12:55:14,905 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_call_state_changed(0x18be990, 0x18d8850, 18, "Call released") 2020-04-05 12:55:14,906 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (18 - Call released) 2020-04-05 12:55:14,907 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_get_remote_address(0x76d18540 [0x18d8850]) 2020-04-05 12:55:14,907 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0x193ff08) 2020-04-05 12:55:14,908 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2020-04-05 12:55:14,909 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0x76d18510 2020-04-05 12:55:14,909 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2020-04-05 12:55:14,910 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0x76d18510 2020-04-05 12:55:14,910 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0x76d18510 2020-04-05 12:55:14,911 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_get_remote_address -> 0x76d18510 2020-04-05 12:55:14,912 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_instance_method_as_string_uri_only(0x76d18510 [0x193ff08]) 2020-04-05 12:55:14,912 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_instance_method_as_string_uri_only -> 0x7453ae60 2020-04-05 12:55:14,913 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-04-05 12:55:14,915 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x745c6f68 [0x18be990]) 2020-04-05 12:55:14,916 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x172d8cc 2020-04-05 12:55:14,917 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:14,917 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x745c6f68 [0x18be990]) 2020-04-05 12:55:14,918 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x172d8cc 2020-04-05 12:55:14,919 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x745c6f68 [0x18be990]) 2020-04-05 12:55:14,920 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x172d8cc 2020-04-05 12:55:14,920 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_call_state_changed 2020-04-05 12:55:14,921 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x18be990] notifying [call_state_changed] 2020-04-05 12:55:14,922 [INFO] [doorpi.sipphone.from_linphone] op [0x18e17a0] : set_or_update_dialog() current=[0x18f01f8] new=[(nil)] 2020-04-05 12:55:15,279 [INFO] [doorpi.sipphone.from_linphone] channel [0x1951a98]: message sent to [UDP://69.162.69.50:52015], size: [300] bytes SIP/2.0 403 Forbidden Via: SIP/2.0/UDP 69.162.69.50:52015;branch=z9hG4bK2001995019 From: ;tag=1415431335 To: ;tag=b3AGePA Call-ID: 1670951707-234022977-860068121 CSeq: 1 INVITE User-Agent: (belle-sip/1.4.2) Supported: outbound 2020-04-05 12:55:15,736 [INFO] [doorpi.sipphone.from_linphone] channel [0x192fd48]: message sent to [UDP://69.162.69.50:51716], size: [299] bytes SIP/2.0 403 Forbidden Via: SIP/2.0/UDP 69.162.69.50:51716;branch=z9hG4bK385809918 From: ;tag=647247954 To: ;tag=WF40qqV Call-ID: 1280683237-1497652882-996808794 CSeq: 1 INVITE User-Agent: (belle-sip/1.4.2) Supported: outbound 2020-04-05 12:55:16,295 [INFO] [doorpi.sipphone.from_linphone] channel [0x1951a98]: message sent to [UDP://69.162.69.50:52015], size: [300] bytes SIP/2.0 403 Forbidden Via: SIP/2.0/UDP 69.162.69.50:52015;branch=z9hG4bK2001995019 From: ;tag=1415431335 To: ;tag=b3AGePA Call-ID: 1670951707-234022977-860068121 CSeq: 1 INVITE User-Agent: (belle-sip/1.4.2) Supported: outbound 2020-04-05 12:55:17,714 [INFO] [doorpi.sipphone.from_linphone] channel [0x192fd48]: message sent to [UDP://69.162.69.50:51716], size: [299] bytes SIP/2.0 403 Forbidden Via: SIP/2.0/UDP 69.162.69.50:51716;branch=z9hG4bK385809918 From: ;tag=647247954 To: ;tag=WF40qqV Call-ID: 1280683237-1497652882-996808794 CSeq: 1 INVITE User-Agent: (belle-sip/1.4.2) Supported: outbound 2020-04-05 12:55:18,323 [INFO] [doorpi.sipphone.from_linphone] channel [0x1951a98]: message sent to [UDP://69.162.69.50:52015], size: [300] bytes SIP/2.0 403 Forbidden Via: SIP/2.0/UDP 69.162.69.50:52015;branch=z9hG4bK2001995019 From: ;tag=1415431335 To: ;tag=b3AGePA Call-ID: 1670951707-234022977-860068121 CSeq: 1 INVITE User-Agent: (belle-sip/1.4.2) Supported: outbound ^C2020-04-05 12:55:19,336 [INFO] [doorpi.main] KeyboardInterrupt -> DoorPi will shutdown 2020-04-05 12:55:19,337 [DEBUG] [doorpi.doorpi] destroy doorpi 2020-04-05 12:55:19,338 [DEBUG] [doorpi.doorpi] Threads before starting shutdown: [<_MainThread(MainThread, started 1996111872)>, , <_DummyThread(Dummy-1, started daemon 1974355056)>] 2020-04-05 12:55:19,339 [TRACE] [doorpi.action.handler] fire Event BeforeShutdown from doorpi.doorpi asyncron 2020-04-05 12:55:19,341 [DEBUG] [doorpi.action.handler] [ZO2Z3L] fire for event OnShutdown this actions [, , , ] 2020-04-05 12:55:19,342 [DEBUG] [doorpi.action.handler] no actions for event BeforeShutdown - skip fire_event BeforeShutdown from doorpi.doorpi 2020-04-05 12:55:19,342 [TRACE] [doorpi.action.handler] [ZO2Z3L] try to fire action TimeTickDestroyAction with args () and kwargs {} 2020-04-05 12:55:19,344 [TRACE] [doorpi.action.base] run TimeTickDestroyAction with args () and kwargs {} 2020-04-05 12:55:19,344 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.action.SingleActions.time_tick and force_unregister is True 2020-04-05 12:55:19,345 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute59 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,346 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute59 - remove event too 2020-04-05 12:55:19,346 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute59 2020-04-05 12:55:19,347 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute58 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,347 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute58 - remove event too 2020-04-05 12:55:19,348 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute58 2020-04-05 12:55:19,348 [TRACE] [doorpi.action.handler] unregister Event OnTimeWeekEvenNumber from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,349 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeWeekEvenNumber - remove event too 2020-04-05 12:55:19,350 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeWeekEvenNumber 2020-04-05 12:55:19,350 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute51 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,351 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute51 - remove event too 2020-04-05 12:55:19,351 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute51 2020-04-05 12:55:19,352 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute50 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,352 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute50 - remove event too 2020-04-05 12:55:19,353 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute50 2020-04-05 12:55:19,353 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute53 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,354 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute53 - remove event too 2020-04-05 12:55:19,354 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute53 2020-04-05 12:55:19,355 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute52 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,355 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute52 - remove event too 2020-04-05 12:55:19,356 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute52 2020-04-05 12:55:19,356 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute55 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,357 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute55 - remove event too 2020-04-05 12:55:19,357 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute55 2020-04-05 12:55:19,358 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute54 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,358 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute54 - remove event too 2020-04-05 12:55:19,359 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute54 2020-04-05 12:55:19,360 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute57 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,360 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute57 - remove event too 2020-04-05 12:55:19,361 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute57 2020-04-05 12:55:19,361 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute56 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,362 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute56 - remove event too 2020-04-05 12:55:19,363 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute56 2020-04-05 12:55:19,363 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinuteEvenNumber from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,364 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinuteEvenNumber - remove event too 2020-04-05 12:55:19,364 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinuteEvenNumber 2020-04-05 12:55:19,365 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,365 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour - remove event too 2020-04-05 12:55:19,366 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour 2020-04-05 12:55:19,367 [TRACE] [doorpi.action.handler] unregister Event OnTimeMonth from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,367 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMonth - remove event too 2020-04-05 12:55:19,368 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMonth 2020-04-05 12:55:19,368 [TRACE] [doorpi.action.handler] unregister Event OnTimeYearUnevenNumber from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,369 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeYearUnevenNumber - remove event too 2020-04-05 12:55:19,369 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeYearUnevenNumber 2020-04-05 12:55:19,370 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour19 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,370 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour19 - remove event too 2020-04-05 12:55:19,371 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour19 2020-04-05 12:55:19,371 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour18 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,372 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour18 - remove event too 2020-04-05 12:55:19,372 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour18 2020-04-05 12:55:19,373 [TRACE] [doorpi.action.handler] unregister Event OnTimeYearEvenNumber from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,373 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeYearEvenNumber - remove event too 2020-04-05 12:55:19,374 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeYearEvenNumber 2020-04-05 12:55:19,375 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour11 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,375 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour11 - remove event too 2020-04-05 12:55:19,376 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour11 2020-04-05 12:55:19,376 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour13 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,377 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour13 - remove event too 2020-04-05 12:55:19,377 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour13 2020-04-05 12:55:19,378 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour12 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,378 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour12 - remove event too 2020-04-05 12:55:19,379 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour12 2020-04-05 12:55:19,379 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour15 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,380 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour15 - remove event too 2020-04-05 12:55:19,380 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour15 2020-04-05 12:55:19,381 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour14 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,381 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour14 - remove event too 2020-04-05 12:55:19,382 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour14 2020-04-05 12:55:19,383 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour17 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,383 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour17 - remove event too 2020-04-05 12:55:19,384 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour17 2020-04-05 12:55:19,384 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour16 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,385 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour16 - remove event too 2020-04-05 12:55:19,386 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour16 2020-04-05 12:55:19,386 [TRACE] [doorpi.action.handler] unregister Event OnTimeSecondUnevenNumber from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,387 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeSecondUnevenNumber - remove event too 2020-04-05 12:55:19,387 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeSecondUnevenNumber 2020-04-05 12:55:19,388 [TRACE] [doorpi.action.handler] unregister Event OnTimeDay from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,388 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeDay - remove event too 2020-04-05 12:55:19,389 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeDay 2020-04-05 12:55:19,390 [TRACE] [doorpi.action.handler] unregister Event OnTimeMonthEvenNumber from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,390 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMonthEvenNumber - remove event too 2020-04-05 12:55:19,391 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMonthEvenNumber 2020-04-05 12:55:19,391 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour9 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,392 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour9 - remove event too 2020-04-05 12:55:19,393 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour9 2020-04-05 12:55:19,393 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour8 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,394 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour8 - remove event too 2020-04-05 12:55:19,394 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour8 2020-04-05 12:55:19,395 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour10 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,395 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour10 - remove event too 2020-04-05 12:55:19,396 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour10 2020-04-05 12:55:19,396 [TRACE] [doorpi.action.handler] unregister Event OnTimeSecond from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,397 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeSecond - remove event too 2020-04-05 12:55:19,398 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeSecond 2020-04-05 12:55:19,398 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour4 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,399 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour4 - remove event too 2020-04-05 12:55:19,399 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour4 2020-04-05 12:55:19,400 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour7 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,400 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour7 - remove event too 2020-04-05 12:55:19,401 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour7 2020-04-05 12:55:19,401 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour6 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,402 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour6 - remove event too 2020-04-05 12:55:19,403 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour6 2020-04-05 12:55:19,403 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour1 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,404 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour1 - remove event too 2020-04-05 12:55:19,404 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour1 2020-04-05 12:55:19,405 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour0 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,405 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour0 - remove event too 2020-04-05 12:55:19,406 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour0 2020-04-05 12:55:19,407 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour3 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,407 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour3 - remove event too 2020-04-05 12:55:19,408 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour3 2020-04-05 12:55:19,408 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour2 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,409 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour2 - remove event too 2020-04-05 12:55:19,409 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour2 2020-04-05 12:55:19,410 [TRACE] [doorpi.action.handler] unregister Event OnTimeMonthUnevenNumber from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,411 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMonthUnevenNumber - remove event too 2020-04-05 12:55:19,411 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMonthUnevenNumber 2020-04-05 12:55:19,412 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute5 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,412 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute5 - remove event too 2020-04-05 12:55:19,413 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute5 2020-04-05 12:55:19,413 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute4 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,414 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute4 - remove event too 2020-04-05 12:55:19,415 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute4 2020-04-05 12:55:19,415 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute7 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,416 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute7 - remove event too 2020-04-05 12:55:19,416 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute7 2020-04-05 12:55:19,417 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute6 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,417 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute6 - remove event too 2020-04-05 12:55:19,418 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute6 2020-04-05 12:55:19,419 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute1 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,419 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute1 - remove event too 2020-04-05 12:55:19,420 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute1 2020-04-05 12:55:19,420 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute0 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,421 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute0 - remove event too 2020-04-05 12:55:19,421 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute0 2020-04-05 12:55:19,422 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute3 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,423 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute3 - remove event too 2020-04-05 12:55:19,423 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute3 2020-04-05 12:55:19,424 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute2 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,424 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute2 - remove event too 2020-04-05 12:55:19,425 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute2 2020-04-05 12:55:19,426 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute9 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,426 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute9 - remove event too 2020-04-05 12:55:19,427 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute9 2020-04-05 12:55:19,427 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute8 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,428 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute8 - remove event too 2020-04-05 12:55:19,428 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute8 2020-04-05 12:55:19,429 [TRACE] [doorpi.action.handler] unregister Event OnTimeWeek from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,430 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeWeek - remove event too 2020-04-05 12:55:19,430 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeWeek 2020-04-05 12:55:19,431 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute15 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,431 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute15 - remove event too 2020-04-05 12:55:19,432 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute15 2020-04-05 12:55:19,432 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute14 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,433 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute14 - remove event too 2020-04-05 12:55:19,434 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute14 2020-04-05 12:55:19,434 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute17 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,435 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute17 - remove event too 2020-04-05 12:55:19,435 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute17 2020-04-05 12:55:19,436 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute16 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,437 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute16 - remove event too 2020-04-05 12:55:19,437 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute16 2020-04-05 12:55:19,438 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute11 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,438 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute11 - remove event too 2020-04-05 12:55:19,439 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute11 2020-04-05 12:55:19,439 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute10 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,440 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute10 - remove event too 2020-04-05 12:55:19,440 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute10 2020-04-05 12:55:19,440 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute13 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,440 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute13 - remove event too 2020-04-05 12:55:19,441 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute13 2020-04-05 12:55:19,441 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute12 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,441 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute12 - remove event too 2020-04-05 12:55:19,442 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute12 2020-04-05 12:55:19,442 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute19 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,442 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute19 - remove event too 2020-04-05 12:55:19,442 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute19 2020-04-05 12:55:19,443 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute18 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,443 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute18 - remove event too 2020-04-05 12:55:19,443 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute18 2020-04-05 12:55:19,443 [TRACE] [doorpi.action.handler] unregister Event OnTimeWeekUnevenNumber from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,444 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeWeekUnevenNumber - remove event too 2020-04-05 12:55:19,444 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeWeekUnevenNumber 2020-04-05 12:55:19,444 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinuteEvery5 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,444 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinuteEvery5 - remove event too 2020-04-05 12:55:19,445 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinuteEvery5 2020-04-05 12:55:19,445 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour20 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,445 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour20 - remove event too 2020-04-05 12:55:19,446 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour20 2020-04-05 12:55:19,446 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour21 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,446 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour21 - remove event too 2020-04-05 12:55:19,446 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour21 2020-04-05 12:55:19,447 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour22 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,447 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour22 - remove event too 2020-04-05 12:55:19,447 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour22 2020-04-05 12:55:19,447 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour5 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,448 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour5 - remove event too 2020-04-05 12:55:19,448 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour5 2020-04-05 12:55:19,448 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute20 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,448 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute20 - remove event too 2020-04-05 12:55:19,449 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute20 2020-04-05 12:55:19,449 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute21 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,449 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute21 - remove event too 2020-04-05 12:55:19,449 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute21 2020-04-05 12:55:19,450 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute22 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,450 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute22 - remove event too 2020-04-05 12:55:19,450 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute22 2020-04-05 12:55:19,451 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute23 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,451 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute23 - remove event too 2020-04-05 12:55:19,451 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute23 2020-04-05 12:55:19,451 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute24 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,452 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute24 - remove event too 2020-04-05 12:55:19,452 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute24 2020-04-05 12:55:19,452 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute25 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,452 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute25 - remove event too 2020-04-05 12:55:19,453 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute25 2020-04-05 12:55:19,453 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute26 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,453 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute26 - remove event too 2020-04-05 12:55:19,454 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute26 2020-04-05 12:55:19,454 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute27 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,454 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute27 - remove event too 2020-04-05 12:55:19,454 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute27 2020-04-05 12:55:19,455 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute28 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,455 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute28 - remove event too 2020-04-05 12:55:19,455 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute28 2020-04-05 12:55:19,455 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute29 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,456 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute29 - remove event too 2020-04-05 12:55:19,456 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute29 2020-04-05 12:55:19,456 [TRACE] [doorpi.action.handler] unregister Event OnTimeDayEvenNumber from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,456 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeDayEvenNumber - remove event too 2020-04-05 12:55:19,457 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeDayEvenNumber 2020-04-05 12:55:19,457 [TRACE] [doorpi.action.handler] unregister Event OnTimeDayUnevenNumber from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,457 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeDayUnevenNumber - remove event too 2020-04-05 12:55:19,458 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeDayUnevenNumber 2020-04-05 12:55:19,458 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute39 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,458 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute39 - remove event too 2020-04-05 12:55:19,458 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute39 2020-04-05 12:55:19,458 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute38 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,459 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute38 - remove event too 2020-04-05 12:55:19,459 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute38 2020-04-05 12:55:19,459 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute37 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,459 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute37 - remove event too 2020-04-05 12:55:19,460 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute37 2020-04-05 12:55:19,460 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute36 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,460 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute36 - remove event too 2020-04-05 12:55:19,460 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute36 2020-04-05 12:55:19,461 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute35 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,461 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute35 - remove event too 2020-04-05 12:55:19,461 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute35 2020-04-05 12:55:19,461 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute34 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,461 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute34 - remove event too 2020-04-05 12:55:19,462 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute34 2020-04-05 12:55:19,462 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute33 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,462 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute33 - remove event too 2020-04-05 12:55:19,462 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute33 2020-04-05 12:55:19,463 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute32 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,463 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute32 - remove event too 2020-04-05 12:55:19,463 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute32 2020-04-05 12:55:19,463 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute31 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,464 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute31 - remove event too 2020-04-05 12:55:19,464 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute31 2020-04-05 12:55:19,464 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute30 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,464 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute30 - remove event too 2020-04-05 12:55:19,465 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute30 2020-04-05 12:55:19,465 [TRACE] [doorpi.action.handler] unregister Event OnTimeHourUnevenNumber from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,465 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHourUnevenNumber - remove event too 2020-04-05 12:55:19,465 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHourUnevenNumber 2020-04-05 12:55:19,466 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,466 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute - remove event too 2020-04-05 12:55:19,466 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute 2020-04-05 12:55:19,466 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute48 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,467 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute48 - remove event too 2020-04-05 12:55:19,467 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute48 2020-04-05 12:55:19,467 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute49 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,468 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute49 - remove event too 2020-04-05 12:55:19,468 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute49 2020-04-05 12:55:19,468 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute42 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,468 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute42 - remove event too 2020-04-05 12:55:19,469 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute42 2020-04-05 12:55:19,469 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute43 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,469 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute43 - remove event too 2020-04-05 12:55:19,469 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute43 2020-04-05 12:55:19,470 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute40 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,470 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute40 - remove event too 2020-04-05 12:55:19,470 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute40 2020-04-05 12:55:19,470 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute41 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,471 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute41 - remove event too 2020-04-05 12:55:19,471 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute41 2020-04-05 12:55:19,471 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute46 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,471 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute46 - remove event too 2020-04-05 12:55:19,472 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute46 2020-04-05 12:55:19,472 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute47 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,472 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute47 - remove event too 2020-04-05 12:55:19,472 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute47 2020-04-05 12:55:19,473 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute44 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,473 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute44 - remove event too 2020-04-05 12:55:19,473 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute44 2020-04-05 12:55:19,473 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute45 from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,474 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute45 - remove event too 2020-04-05 12:55:19,474 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute45 2020-04-05 12:55:19,474 [TRACE] [doorpi.action.handler] unregister Event OnTimeHourEvenNumber from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,474 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHourEvenNumber - remove event too 2020-04-05 12:55:19,475 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHourEvenNumber 2020-04-05 12:55:19,475 [TRACE] [doorpi.action.handler] unregister Event OnTimeSecondEvenNumber from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,475 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeSecondEvenNumber - remove event too 2020-04-05 12:55:19,476 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeSecondEvenNumber 2020-04-05 12:55:19,476 [TRACE] [doorpi.action.handler] unregister Event OnTimeYear from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,476 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeYear - remove event too 2020-04-05 12:55:19,476 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeYear 2020-04-05 12:55:19,477 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinuteUnevenNumber from doorpi.action.SingleActions.time_tick 2020-04-05 12:55:19,477 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinuteUnevenNumber - remove event too 2020-04-05 12:55:19,477 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinuteUnevenNumber 2020-04-05 12:55:19,477 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed 2020-04-05 12:55:19,478 [TRACE] [doorpi.action.handler] [ZO2Z3L] try to fire action WebServerShutdownAction with args () and kwargs {} 2020-04-05 12:55:19,478 [TRACE] [doorpi.action.base] run WebServerShutdownAction with args () and kwargs {} 2020-04-05 12:55:19,479 [TRACE] [doorpi.action.handler] fire Event OnWebServerStop from doorpi.status.webserver asyncron 2020-04-05 12:55:19,479 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.status.webserver_lib.request_handler and force_unregister is True 2020-04-05 12:55:19,480 [TRACE] [doorpi.action.handler] unregister Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2020-04-05 12:55:19,480 [DEBUG] [doorpi.action.handler] no actions for event OnWebServerStop - skip fire_event OnWebServerStop from doorpi.status.webserver 2020-04-05 12:55:19,480 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerVirtualResource - remove event too 2020-04-05 12:55:19,481 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerVirtualResource 2020-04-05 12:55:19,481 [TRACE] [doorpi.action.handler] unregister Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler 2020-04-05 12:55:19,481 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerRealResource - remove event too 2020-04-05 12:55:19,482 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerRealResource 2020-04-05 12:55:19,482 [TRACE] [doorpi.action.handler] unregister Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2020-04-05 12:55:19,482 [DEBUG] [doorpi.action.handler] no more sources for event OnConfigKeySet - remove event too 2020-04-05 12:55:19,483 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnConfigKeySet 2020-04-05 12:55:19,483 [TRACE] [doorpi.action.handler] unregister Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2020-04-05 12:55:19,483 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerRequestGet - remove event too 2020-04-05 12:55:19,483 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerRequestGet 2020-04-05 12:55:19,484 [TRACE] [doorpi.action.handler] unregister Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2020-04-05 12:55:19,484 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerRequestPost - remove event too 2020-04-05 12:55:19,484 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerRequestPost 2020-04-05 12:55:19,484 [TRACE] [doorpi.action.handler] unregister Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2020-04-05 12:55:19,485 [DEBUG] [doorpi.action.handler] no more sources for event OnConfigKeyDelete - remove event too 2020-04-05 12:55:19,485 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnConfigKeyDelete 2020-04-05 12:55:19,485 [TRACE] [doorpi.action.handler] unregister Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2020-04-05 12:55:19,486 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerRequest - remove event too 2020-04-05 12:55:19,486 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerRequest 2020-04-05 12:55:19,486 [TRACE] [doorpi.action.handler] unregister Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2020-04-05 12:55:19,486 [DEBUG] [doorpi.action.handler] no more sources for event OnFireEvent - remove event too 2020-04-05 12:55:19,487 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnFireEvent 2020-04-05 12:55:19,487 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed 2020-04-05 12:55:19,492 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.status.webserver and force_unregister is True 2020-04-05 12:55:19,493 [TRACE] [doorpi.action.handler] unregister Event OnWebServerStop from doorpi.status.webserver 2020-04-05 12:55:19,494 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerStop - remove event too 2020-04-05 12:55:19,494 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver was removed for event OnWebServerStop 2020-04-05 12:55:19,495 [TRACE] [doorpi.action.handler] unregister Event OnWebServerStart from doorpi.status.webserver 2020-04-05 12:55:19,496 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerStart - remove event too 2020-04-05 12:55:19,496 [TRACE] [doorpi.action.handler] [PZLWRM] finished fire_event for event_name OnWebServerStart 2020-04-05 12:55:19,497 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver was removed for event OnWebServerStart 2020-04-05 12:55:19,497 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver was removed 2020-04-05 12:55:19,498 [TRACE] [doorpi.action.handler] [ZO2Z3L] try to fire action KeyboardDestroyAction with args () and kwargs {} 2020-04-05 12:55:19,498 [TRACE] [doorpi.action.base] run KeyboardDestroyAction with args () and kwargs {} 2020-04-05 12:55:19,499 [DEBUG] [doorpi.keyboard.from_gpio] destroy 2020-04-05 12:55:19,502 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.keyboard.from_gpio and force_unregister is True 2020-04-05 12:55:19,502 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_16 from doorpi.keyboard.from_gpio 2020-04-05 12:55:19,503 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_16 - remove event too 2020-04-05 12:55:19,504 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyDown_16 2020-04-05 12:55:19,504 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_onboardpins.18 from doorpi.keyboard.from_gpio 2020-04-05 12:55:19,505 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_onboardpins.18 - remove event too 2020-04-05 12:55:19,505 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyUp_onboardpins.18 2020-04-05 12:55:19,506 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_onboardpins.16 from doorpi.keyboard.from_gpio 2020-04-05 12:55:19,506 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_onboardpins.16 - remove event too 2020-04-05 12:55:19,507 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyUp_onboardpins.16 2020-04-05 12:55:19,507 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_18 from doorpi.keyboard.from_gpio 2020-04-05 12:55:19,508 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_18 - remove event too 2020-04-05 12:55:19,508 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyPressed_18 2020-04-05 12:55:19,509 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_18 from doorpi.keyboard.from_gpio 2020-04-05 12:55:19,509 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_18 - remove event too 2020-04-05 12:55:19,510 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyDown_18 2020-04-05 12:55:19,511 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed from doorpi.keyboard.from_gpio 2020-04-05 12:55:19,511 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed - remove event too 2020-04-05 12:55:19,511 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyPressed 2020-04-05 12:55:19,512 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp from doorpi.keyboard.from_gpio 2020-04-05 12:55:19,512 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp - remove event too 2020-04-05 12:55:19,513 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyUp 2020-04-05 12:55:19,513 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_onboardpins.18 from doorpi.keyboard.from_gpio 2020-04-05 12:55:19,514 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_onboardpins.18 - remove event too 2020-04-05 12:55:19,514 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyDown_onboardpins.18 2020-04-05 12:55:19,514 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown from doorpi.keyboard.from_gpio 2020-04-05 12:55:19,515 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown - remove event too 2020-04-05 12:55:19,515 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyDown 2020-04-05 12:55:19,516 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_16 from doorpi.keyboard.from_gpio 2020-04-05 12:55:19,516 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_16 - remove event too 2020-04-05 12:55:19,517 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyUp_16 2020-04-05 12:55:19,517 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_onboardpins.16 from doorpi.keyboard.from_gpio 2020-04-05 12:55:19,517 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_onboardpins.16 - remove event too 2020-04-05 12:55:19,518 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyDown_onboardpins.16 2020-04-05 12:55:19,518 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_16 from doorpi.keyboard.from_gpio 2020-04-05 12:55:19,518 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_16 - remove event too 2020-04-05 12:55:19,519 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyPressed_16 2020-04-05 12:55:19,520 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_onboardpins.16 from doorpi.keyboard.from_gpio 2020-04-05 12:55:19,520 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_onboardpins.16 - remove event too 2020-04-05 12:55:19,521 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyPressed_onboardpins.16 2020-04-05 12:55:19,521 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_18 from doorpi.keyboard.from_gpio 2020-04-05 12:55:19,521 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_18 - remove event too 2020-04-05 12:55:19,522 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyUp_18 2020-04-05 12:55:19,522 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_onboardpins.18 from doorpi.keyboard.from_gpio 2020-04-05 12:55:19,523 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_onboardpins.18 - remove event too 2020-04-05 12:55:19,523 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyPressed_onboardpins.18 2020-04-05 12:55:19,524 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed 2020-04-05 12:55:19,525 [TRACE] [doorpi.action.handler] [ZO2Z3L] try to fire action > with args () and kwargs {} 2020-04-05 12:55:19,525 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2020-04-05 12:55:19,526 [DEBUG] [doorpi.sipphone.from_linphone] destroy 2020-04-05 12:55:19,527 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_terminate_all_calls(0x745c6f68 [0x18be990]) 2020-04-05 12:55:19,527 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_terminate_all_calls -> 0x172d8cc 2020-04-05 12:55:19,529 [DEBUG] [doorpi.action.handler] [7ZUAEF] fire for event OnSipPhoneDestroy this actions [] 2020-04-05 12:55:19,529 [TRACE] [doorpi.action.handler] [7ZUAEF] try to fire action > with args () and kwargs {} 2020-04-05 12:55:19,530 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2020-04-05 12:55:19,530 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] destroy 2020-04-05 12:55:19,531 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.sipphone.linphone_lib.CallBacks and force_unregister is True 2020-04-05 12:55:19,532 [TRACE] [doorpi.action.handler] unregister Event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:19,532 [DEBUG] [doorpi.action.handler] no more sources for event OnCallReject - remove event too 2020-04-05 12:55:19,533 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallReject 2020-04-05 12:55:19,533 [TRACE] [doorpi.action.handler] unregister Event OnDTMF from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:19,534 [DEBUG] [doorpi.action.handler] no more sources for event OnDTMF - remove event too 2020-04-05 12:55:19,535 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnDTMF 2020-04-05 12:55:19,536 [TRACE] [doorpi.action.handler] unregister Event OnDTMF_"#1" from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:19,536 [DEBUG] [doorpi.action.handler] no more sources for event OnDTMF_"#1" - remove event too 2020-04-05 12:55:19,537 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnDTMF_"#1" 2020-04-05 12:55:19,537 [TRACE] [doorpi.action.handler] unregister Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:19,538 [DEBUG] [doorpi.action.handler] no more sources for event OnCallMediaStateChange - remove event too 2020-04-05 12:55:19,538 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallMediaStateChange 2020-04-05 12:55:19,538 [TRACE] [doorpi.action.handler] unregister Event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:19,539 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallStateConnect - remove event too 2020-04-05 12:55:19,539 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallStateConnect 2020-04-05 12:55:19,539 [TRACE] [doorpi.action.handler] unregister Event OnCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:19,539 [DEBUG] [doorpi.action.handler] no more sources for event OnCallBusy - remove event too 2020-04-05 12:55:19,540 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallBusy 2020-04-05 12:55:19,540 [TRACE] [doorpi.action.handler] unregister Event AfterCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:19,540 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallBusy - remove event too 2020-04-05 12:55:19,541 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallBusy 2020-04-05 12:55:19,541 [TRACE] [doorpi.action.handler] unregister Event AfterCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:19,541 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallStateDisconnect - remove event too 2020-04-05 12:55:19,541 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallStateDisconnect 2020-04-05 12:55:19,542 [TRACE] [doorpi.action.handler] unregister Event OnDTMF_"#404" from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:19,542 [DEBUG] [doorpi.action.handler] no more sources for event OnDTMF_"#404" - remove event too 2020-04-05 12:55:19,542 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnDTMF_"#404" 2020-04-05 12:55:19,542 [TRACE] [doorpi.action.handler] unregister Event OnCallStateReject from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:19,543 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateReject - remove event too 2020-04-05 12:55:19,543 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateReject 2020-04-05 12:55:19,543 [TRACE] [doorpi.action.handler] unregister Event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:19,543 [DEBUG] [doorpi.action.handler] no more sources for event BeforeCallIncoming - remove event too 2020-04-05 12:55:19,544 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event BeforeCallIncoming 2020-04-05 12:55:19,544 [TRACE] [doorpi.action.handler] unregister Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:19,544 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateChange - remove event too 2020-04-05 12:55:19,544 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateChange 2020-04-05 12:55:19,545 [TRACE] [doorpi.action.handler] unregister Event OnCallStateDismissed from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:19,545 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateDismissed - remove event too 2020-04-05 12:55:19,545 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateDismissed 2020-04-05 12:55:19,546 [TRACE] [doorpi.action.handler] unregister Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:19,546 [DEBUG] [doorpi.action.handler] no more sources for event OnMediaNotRequired - remove event too 2020-04-05 12:55:19,546 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnMediaNotRequired 2020-04-05 12:55:19,546 [TRACE] [doorpi.action.handler] unregister Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:19,547 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateDisconnect - remove event too 2020-04-05 12:55:19,547 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateDisconnect 2020-04-05 12:55:19,547 [TRACE] [doorpi.action.handler] unregister Event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:19,548 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallReject - remove event too 2020-04-05 12:55:19,548 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallReject 2020-04-05 12:55:19,548 [TRACE] [doorpi.action.handler] unregister Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:19,548 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStart - remove event too 2020-04-05 12:55:19,549 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStart 2020-04-05 12:55:19,549 [TRACE] [doorpi.action.handler] unregister Event AfterCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:19,549 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallReconnect - remove event too 2020-04-05 12:55:19,549 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallReconnect 2020-04-05 12:55:19,550 [TRACE] [doorpi.action.handler] unregister Event OnCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:19,550 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateConnect - remove event too 2020-04-05 12:55:19,550 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateConnect 2020-04-05 12:55:19,550 [TRACE] [doorpi.action.handler] unregister Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:19,551 [DEBUG] [doorpi.action.handler] no more sources for event OnMediaRequired - remove event too 2020-04-05 12:55:19,551 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnMediaRequired 2020-04-05 12:55:19,551 [TRACE] [doorpi.action.handler] unregister Event OnDTMF_"#500" from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:19,551 [DEBUG] [doorpi.action.handler] no more sources for event OnDTMF_"#500" - remove event too 2020-04-05 12:55:19,552 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnDTMF_"#500" 2020-04-05 12:55:19,552 [TRACE] [doorpi.action.handler] unregister Event OnCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:19,552 [DEBUG] [doorpi.action.handler] no more sources for event OnCallIncoming - remove event too 2020-04-05 12:55:19,552 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallIncoming 2020-04-05 12:55:19,553 [TRACE] [doorpi.action.handler] unregister Event AfterCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:19,553 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallIncoming - remove event too 2020-04-05 12:55:19,553 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallIncoming 2020-04-05 12:55:19,553 [TRACE] [doorpi.action.handler] unregister Event OnCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2020-04-05 12:55:19,554 [DEBUG] [doorpi.action.handler] no more sources for event OnCallReconnect - remove event too 2020-04-05 12:55:19,554 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallReconnect 2020-04-05 12:55:19,554 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed 2020-04-05 12:55:19,555 [TRACE] [doorpi.action.handler] [7ZUAEF] finished fire_event for event_name OnSipPhoneDestroy 2020-04-05 12:55:19,555 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.sipphone.from_linphone and force_unregister is True 2020-04-05 12:55:19,555 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneRecorderCreate from doorpi.sipphone.from_linphone 2020-04-05 12:55:19,556 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneRecorderCreate - remove event too 2020-04-05 12:55:19,556 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneRecorderCreate 2020-04-05 12:55:19,556 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone 2020-04-05 12:55:19,557 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneMakeCall - remove event too 2020-04-05 12:55:19,557 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneMakeCall 2020-04-05 12:55:19,557 [TRACE] [doorpi.action.handler] unregister Event OnPlayerCreated from doorpi.sipphone.from_linphone 2020-04-05 12:55:19,557 [DEBUG] [doorpi.action.handler] no more sources for event OnPlayerCreated - remove event too 2020-04-05 12:55:19,558 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnPlayerCreated 2020-04-05 12:55:19,558 [TRACE] [doorpi.action.handler] unregister Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2020-04-05 12:55:19,558 [DEBUG] [doorpi.action.handler] no more sources for event BeforeSipPhoneMakeCall - remove event too 2020-04-05 12:55:19,558 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event BeforeSipPhoneMakeCall 2020-04-05 12:55:19,559 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneRecorderDestroy from doorpi.sipphone.from_linphone 2020-04-05 12:55:19,559 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneRecorderDestroy - remove event too 2020-04-05 12:55:19,559 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneRecorderDestroy 2020-04-05 12:55:19,559 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneStart from doorpi.sipphone.from_linphone 2020-04-05 12:55:19,560 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneStart - remove event too 2020-04-05 12:55:19,560 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneStart 2020-04-05 12:55:19,560 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneDestroy from doorpi.sipphone.from_linphone 2020-04-05 12:55:19,560 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneDestroy - remove event too 2020-04-05 12:55:19,561 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneDestroy 2020-04-05 12:55:19,561 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneCallTimeoutMaxCalltime from doorpi.sipphone.from_linphone 2020-04-05 12:55:19,561 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneCallTimeoutMaxCalltime - remove event too 2020-04-05 12:55:19,562 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneCallTimeoutMaxCalltime 2020-04-05 12:55:19,562 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneCallTimeoutNoResponse from doorpi.sipphone.from_linphone 2020-04-05 12:55:19,562 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneCallTimeoutNoResponse - remove event too 2020-04-05 12:55:19,562 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneCallTimeoutNoResponse 2020-04-05 12:55:19,563 [TRACE] [doorpi.action.handler] unregister Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2020-04-05 12:55:19,563 [DEBUG] [doorpi.action.handler] no more sources for event AfterSipPhoneMakeCall - remove event too 2020-04-05 12:55:19,563 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event AfterSipPhoneMakeCall 2020-04-05 12:55:19,563 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2020-04-05 12:55:19,564 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneMakeCallFailed - remove event too 2020-04-05 12:55:19,564 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneMakeCallFailed 2020-04-05 12:55:19,564 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2020-04-05 12:55:19,564 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneCreate - remove event too 2020-04-05 12:55:19,565 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneCreate 2020-04-05 12:55:19,565 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed 2020-04-05 12:55:19,566 [TRACE] [doorpi.action.handler] [ZO2Z3L] finished fire_event for event_name OnShutdown 2020-04-05 12:55:19,566 [TRACE] [doorpi.action.handler] fire Event AfterShutdown from doorpi.doorpi asyncron 2020-04-05 12:55:19,567 [DEBUG] [doorpi.action.handler] no actions for event AfterShutdown - skip fire_event AfterShutdown from doorpi.doorpi 2020-04-05 12:55:20,068 [DEBUG] [doorpi.doorpi] wait 5 seconds for threads 1 and 1 event 2020-04-05 12:55:20,068 [TRACE] [doorpi.doorpi] still existing threads: [<_DummyThread(Dummy-1, started daemon 1974355056)>] 2020-04-05 12:55:20,069 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi'] 2020-04-05 12:55:20,570 [DEBUG] [doorpi.doorpi] wait 4.5 seconds for threads 1 and 1 event 2020-04-05 12:55:20,571 [TRACE] [doorpi.doorpi] still existing threads: [<_DummyThread(Dummy-1, started daemon 1974355056)>] 2020-04-05 12:55:20,571 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi'] 2020-04-05 12:55:21,073 [DEBUG] [doorpi.doorpi] wait 4.0 seconds for threads 1 and 1 event 2020-04-05 12:55:21,074 [TRACE] [doorpi.doorpi] still existing threads: [<_DummyThread(Dummy-1, started daemon 1974355056)>] 2020-04-05 12:55:21,075 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi'] 2020-04-05 12:55:21,576 [DEBUG] [doorpi.doorpi] wait 3.5 seconds for threads 1 and 1 event 2020-04-05 12:55:21,577 [TRACE] [doorpi.doorpi] still existing threads: [<_DummyThread(Dummy-1, started daemon 1974355056)>] 2020-04-05 12:55:21,578 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi'] 2020-04-05 12:55:22,079 [DEBUG] [doorpi.doorpi] wait 3.0 seconds for threads 1 and 1 event 2020-04-05 12:55:22,080 [TRACE] [doorpi.doorpi] still existing threads: [<_DummyThread(Dummy-1, started daemon 1974355056)>] 2020-04-05 12:55:22,081 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi'] 2020-04-05 12:55:22,583 [DEBUG] [doorpi.doorpi] wait 2.5 seconds for threads 1 and 1 event 2020-04-05 12:55:22,584 [TRACE] [doorpi.doorpi] still existing threads: [<_DummyThread(Dummy-1, started daemon 1974355056)>] 2020-04-05 12:55:22,584 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi'] 2020-04-05 12:55:23,086 [DEBUG] [doorpi.doorpi] wait 2.0 seconds for threads 1 and 1 event 2020-04-05 12:55:23,087 [TRACE] [doorpi.doorpi] still existing threads: [<_DummyThread(Dummy-1, started daemon 1974355056)>] 2020-04-05 12:55:23,088 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi'] 2020-04-05 12:55:23,589 [DEBUG] [doorpi.doorpi] wait 1.5 seconds for threads 1 and 1 event 2020-04-05 12:55:23,590 [TRACE] [doorpi.doorpi] still existing threads: [<_DummyThread(Dummy-1, started daemon 1974355056)>] 2020-04-05 12:55:23,591 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi'] 2020-04-05 12:55:24,092 [DEBUG] [doorpi.doorpi] wait 1.0 seconds for threads 1 and 1 event 2020-04-05 12:55:24,093 [TRACE] [doorpi.doorpi] still existing threads: [<_DummyThread(Dummy-1, started daemon 1974355056)>] 2020-04-05 12:55:24,094 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi'] 2020-04-05 12:55:24,596 [DEBUG] [doorpi.doorpi] wait 0.5 seconds for threads 1 and 1 event 2020-04-05 12:55:24,597 [TRACE] [doorpi.doorpi] still existing threads: [<_DummyThread(Dummy-1, started daemon 1974355056)>] 2020-04-05 12:55:24,597 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi'] 2020-04-05 12:55:25,099 [WARNING] [doorpi.doorpi] waiting for threads to time out - there are still threads: [<_DummyThread(Dummy-1, started daemon 1974355056)>] 2020-04-05 12:55:25,100 [INFO] [doorpi.doorpi] ======== DoorPi successfully shutdown ========