2020-03-12 20:04:45,136 [TRACE] [doorpi.action.handler] [ZC7YGU] trfire action > with args () and kwargs { 2020-03-12 20:04:45,138 [DEBUG] [doorpi.sipphone.from_linphone] PYLINPHONE] <<< pylinphone_Core_callback_call_state_changed 2020-03-12 20:04:45,139 [TRACE] [doorpi.action.base] run SingleActith args () and kwargs {} 2020-03-12 20:04:45,139 [DEBUG] [doorpi.sipphone.from_linphone] PYLINPHONE] <<< pylinphone_Core_instance_method_terminate_all_calls -> 0x19 2020-03-12 20:04:45,141 [TRACE] [doorpi.action.handler] [ZC7YGU] fid fire_event for event_name OnCallStateDisconnect 2020-03-12 20:04:45,141 [TRACE] [doorpi.action.handler] fire Event eSipPhoneMakeCall from doorpi.sipphone.from_linphone asyncron 2020-03-12 20:04:45,143 [DEBUG] [doorpi.sipphone.from_linphone] cal610#611#612#613#614#615) 2020-03-12 20:04:45,144 [DEBUG] [doorpi.action.handler] no actions vent BeforeSipPhoneMakeCall - skip fire_event BeforeSipPhoneMakeCall from d.sipphone.from_linphone 2020-03-12 20:04:45,144 [DEBUG] [doorpi.sipphone.from_linphone] no nt call -> start new call 2020-03-12 20:04:45,146 [DEBUG] [doorpi.sipphone.from_linphone] resrrent call start datetime to 2020-03-12 19:04:45.146057 2020-03-12 20:04:45,146 [DEBUG] [doorpi.sipphone.from_linphone] PYLINPHONE] >>> pylinphone_Core_instance_method_create_call_params(0x73fad2x1a94f20], 0x2a2810 [(nil)]) 2020-03-12 20:04:45,147 [DEBUG] [doorpi.sipphone.from_linphone] PYLINPHONE] >>> pylinphone_CallParams_from_native_ptr(0x722a7348) 2020-03-12 20:04:45,148 [WARNING] [doorpi.sipphone.from_linphone] The no object pool created in thread [1893725280]. Use belle_sip_object_pool_p to create one. Unowned objects not unref'd will be leaked. 2020-03-12 20:04:45,148 [DEBUG] [doorpi.sipphone.from_linphone] PYLINPHONE] >>> pylinphone_CallParams_new() 2020-03-12 20:04:45,148 [INFO] [doorpi.sipphone.from_linphone] Tere call [0x72ce6548] which is currently in state LinphoneCallOutgoingProgres 2020-03-12 20:04:45,149 [DEBUG] [doorpi.sipphone.from_linphone] PYLINPHONE] <<< pylinphone_CallParams_new -> 0x72def320 2020-03-12 20:04:45,149 [INFO] [doorpi.sipphone.from_linphone] ms_r_unlink: MSRtpRecv:0x72c984e8,0-->MSVoidSink:0x72ca7d30,0 2020-03-12 20:04:45,150 [DEBUG] [doorpi.sipphone.from_linphone] PYLINPHONE] >>> pylinphone_CallParams_init() 2020-03-12 20:04:45,150 [INFO] [doorpi.sipphone.from_linphone] ====================================================== 2020-03-12 20:04:45,151 [DEBUG] [doorpi.sipphone.from_linphone] PYLINPHONE] <<< pylinphone_CallParams_init -> 0x72def320 2020-03-12 20:04:45,151 [INFO] [doorpi.sipphone.from_linphone] FILTER USAGE STATISTICS 2020-03-12 20:04:45,152 [DEBUG] [doorpi.sipphone.from_linphone] PYLINPHONE] <<< pylinphone_CallParams_from_native_ptr -> 0x72def320 2020-03-12 20:04:45,152 [INFO] [doorpi.sipphone.from_linphone] Nam Count Time/tick (ms) CPU Usage 2020-03-12 20:04:45,153 [DEBUG] [doorpi.sipphone.from_linphone] PYLINPHONE] <<< pylinphone_Core_instance_method_create_call_params -> 0x72d 2020-03-12 20:04:45,154 [INFO] [doorpi.sipphone.from_linphone] ------------------------------------------------------ 2020-03-12 20:04:45,156 [INFO] [doorpi.sipphone.from_linphone] MSRv 15 0,023174 99,9997 2020-03-12 20:04:45,156 [INFO] [doorpi.sipphone.from_linphone] MSFayer 0 0 0 2020-03-12 20:04:45,156 [DEBUG] [doorpi.sipphone.from_linphone] PYLINPHONE] >>> pylinphone_CallParams_set_record_file(0x72def320 [0x722a734/usr/local/etc/DoorPi/records/2020-03-12_20-04-45.wav") 2020-03-12 20:04:45,157 [INFO] [doorpi.sipphone.from_linphone] MSGcPLC 0 0 0 2020-03-12 20:04:45,157 [DEBUG] [doorpi.sipphone.from_linphone] PYLINPHONE] <<< pylinphone_CallParams_set_record_file -> 0 2020-03-12 20:04:45,158 [INFO] [doorpi.sipphone.from_linphone] MSEzer 0 0 0 2020-03-12 20:04:45,158 [DEBUG] [doorpi.sipphone.from_linphone] PYLINPHONE] >>> pylinphone_CallParams_enable_video(0x72def320 [0x722a7348],3128 [1]) 2020-03-12 20:04:45,159 [INFO] [doorpi.sipphone.from_linphone] MSOc 0 0 0 2020-03-12 20:04:45,159 [DEBUG] [doorpi.sipphone.from_linphone] PYLINPHONE] <<< pylinphone_CallParams_enable_video -> 0 2020-03-12 20:04:45,160 [INFO] [doorpi.sipphone.from_linphone] MSRle 0 0 0 2020-03-12 20:04:45,160 [DEBUG] [doorpi.sipphone.from_linphone] PYLINPHONE] >>> pylinphone_Core_instance_method_invite_with_params(0x73fad2x1a94f20], "**610#611#612#613#614#615", 0x72def320 [0x722a7348]) 2020-03-12 20:04:45,161 [INFO] [doorpi.sipphone.from_linphone] MSIrce 0 0 0 2020-03-12 20:04:45,165 [INFO] [doorpi.sipphone.from_linphone] MSMorder 0 0 0 2020-03-12 20:04:45,165 [DEBUG] [doorpi.sipphone.from_linphone] PYLINPHONE] >>> pylinphone_Core_callback_call_state_changed(0x1a94f20, 0x720, 2, "Starting outgoing call") 2020-03-12 20:04:45,165 [INFO] [doorpi.sipphone.from_linphone] MSFc 0 0 0 2020-03-12 20:04:45,166 [DEBUG] [doorpi.sipphone.from_linphone] PYLINPHONE] >>> pylinphone_Call_new() 2020-03-12 20:04:45,167 [INFO] [doorpi.sipphone.from_linphone] MSAixer 0 0 0 2020-03-12 20:04:45,167 [DEBUG] [doorpi.sipphone.from_linphone] PYLINPHONE] <<< pylinphone_Call_new -> 0x72def330 2020-03-12 20:04:45,167 [INFO] [doorpi.sipphone.from_linphone] MSV 0 0 0 2020-03-12 20:04:45,168 [DEBUG] [doorpi.sipphone.from_linphone] PYLINPHONE] >>> pylinphone_Call_init() 2020-03-12 20:04:45,168 [INFO] [doorpi.sipphone.from_linphone] MSUc 0 0 0 2020-03-12 20:04:45,169 [DEBUG] [doorpi.sipphone.from_linphone] PYLINPHONE] <<< pylinphone_Call_init -> 0x72def330 2020-03-12 20:04:45,169 [INFO] [doorpi.sipphone.from_linphone] MSUc 0 0 0 2020-03-12 20:04:45,171 [INFO] [doorpi.sipphone.from_linphone] MSAite 0 0 0 2020-03-12 20:04:45,170 [DEBUG] [doorpi.sipphone.linphone_lib.CallB call_state_changed (2 - Starting outgoing call) 2020-03-12 20:04:45,171 [INFO] [doorpi.sipphone.from_linphone] MSAad 0 0 0 2020-03-12 20:04:45,172 [DEBUG] [doorpi.sipphone.from_linphone] PYLINPHONE] >>> pylinphone_Call_get_remote_address(0x72def330 [0x722e0f80]) 2020-03-12 20:04:45,172 [INFO] [doorpi.sipphone.from_linphone] MSDn 0 0 0 2020-03-12 20:04:45,173 [DEBUG] [doorpi.sipphone.from_linphone] PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0x722a74d0) 2020-03-12 20:04:45,173 [INFO] [doorpi.sipphone.from_linphone] MSVnk 0 0 0 2020-03-12 20:04:45,174 [DEBUG] [doorpi.sipphone.from_linphone] PYLINPHONE] >>> pylinphone_Address_new() 2020-03-12 20:04:45,174 [INFO] [doorpi.sipphone.from_linphone] MSIk 0 0 0 2020-03-12 20:04:45,175 [DEBUG] [doorpi.sipphone.from_linphone] PYLINPHONE] <<< pylinphone_Address_new -> 0x72def340 2020-03-12 20:04:45,176 [INFO] [doorpi.sipphone.from_linphone] MST 0 0 0 2020-03-12 20:04:45,176 [DEBUG] [doorpi.sipphone.from_linphone] PYLINPHONE] >>> pylinphone_Address_init() 2020-03-12 20:04:45,177 [INFO] [doorpi.sipphone.from_linphone] MSSC 0 0 0 2020-03-12 20:04:45,177 [DEBUG] [doorpi.sipphone.from_linphone] PYLINPHONE] <<< pylinphone_Address_init -> 0x72def340 2020-03-12 20:04:45,178 [INFO] [doorpi.sipphone.from_linphone] MSRd 0 0 0 2020-03-12 20:04:45,178 [DEBUG] [doorpi.sipphone.from_linphone] PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0x72def340 2020-03-12 20:04:45,179 [INFO] [doorpi.sipphone.from_linphone] ====================================================== 2020-03-12 20:04:45,180 [DEBUG] [doorpi.sipphone.from_linphone] PYLINPHONE] <<< pylinphone_Call_get_remote_address -> 0x72def340 2020-03-12 20:04:45,180 [INFO] [doorpi.sipphone.from_linphone] Cal2ce6548: moving from state LinphoneCallOutgoingProgress to LinphoneCallEnd 2020-03-12 20:04:45,181 [DEBUG] [doorpi.sipphone.from_linphone] PYLINPHONE] >>> pylinphone_Address_instance_method_as_string_uri_only(0x72d [0x722a74d0]) 2020-03-12 20:04:45,181 [INFO] [doorpi.sipphone.from_linphone] MSAMSTicker thread exiting 2020-03-12 20:04:45,182 [DEBUG] [doorpi.sipphone.from_linphone] PYLINPHONE] <<< pylinphone_Address_instance_method_as_string_uri_only -> 0xf70 2020-03-12 20:04:45,182 [INFO] [doorpi.sipphone.from_linphone] Lin core [0x1a94f20] notifying [call_log_updated] 2020-03-12 20:04:45,184 [INFO] [doorpi.sipphone.from_linphone] Resg the current call 2020-03-12 20:04:45,183 [TRACE] [doorpi.action.handler] fire Event lStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-03-12 20:04:45,184 [INFO] [doorpi.sipphone.from_linphone] Lin core [0x1a94f20] notifying [call_state_changed] 2020-03-12 20:04:45,185 [INFO] [doorpi.sipphone.from_linphone] cha[0x1cdfc50]: received [330] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.178.23:5060;branch=z9hG4bK.lg6Bumcvp;rport=5060 From: "Klingel" ;tag=dgEe5psQa To: Call-ID: ZIqtRcVs0C CSeq: 21 INVITE User-Agent: AVM FRITZ!Box 7490 113.07.12 (Jul 3 2019) Content-Length: 0 2020-03-12 20:04:45,190 [INFO] [doorpi.sipphone.from_linphone] NewhoneCall [0x722e0f80] initialized (LinphoneCore version: 3.9.0) 2020-03-12 20:04:45,191 [INFO] [doorpi.sipphone.from_linphone] Cal22e0f80: moving from state LinphoneCallIdle to LinphoneCallOutgoingInit 2020-03-12 20:04:45,192 [DEBUG] [doorpi.sipphone.from_linphone] PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x73fad230 [0x1a94f20]) 2020-03-12 20:04:45,192 [INFO] [doorpi.sipphone.from_linphone] cha[0x1cdfc50] [330] bytes parsed 2020-03-12 20:04:45,192 [DEBUG] [doorpi.action.handler] no actions vent OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipplinphone_lib.CallBacks 2020-03-12 20:04:45,193 [DEBUG] [doorpi.sipphone.from_linphone] PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x19e4760 2020-03-12 20:04:45,193 [INFO] [doorpi.sipphone.from_linphone] Fouansaction matching response. 2020-03-12 20:04:45,194 [TRACE] [doorpi.action.handler] fire Event iaRequired from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-03-12 20:04:45,195 [INFO] [doorpi.sipphone.from_linphone] Cha [client] [INVITE] transaction [0x1dcdc40], from state [CALLING] to [PROCEE 2020-03-12 20:04:45,196 [INFO] [doorpi.sipphone.from_linphone] op cb3618] : set_or_update_dialog() current=[(nil)] new=[(nil)] 2020-03-12 20:04:45,197 [INFO] [doorpi.sipphone.from_linphone] Op cb3618] receiving call response [100], dialog is [(nil)] in state [BELLE_SILOG_NULL] 2020-03-12 20:04:45,197 [DEBUG] [doorpi.action.handler] no actions vent OnMediaRequired - skip fire_event OnMediaRequired from doorpi.sipphonehone_lib.CallBacks 2020-03-12 20:04:45,198 [INFO] [doorpi.sipphone.from_linphone] Canng INVITE request from ["Klingel" ] to [sip:**6611%23612%23613%23614%23615@192.168.178.1] 2020-03-12 20:04:45,198 [DEBUG] [doorpi.sipphone.from_linphone] PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x73fad230 [0x1a94f20]) 2020-03-12 20:04:45,199 [INFO] [doorpi.sipphone.from_linphone] Fouansaction [0x1dcdc40] matching request. 2020-03-12 20:04:45,200 [DEBUG] [doorpi.sipphone.from_linphone] PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x19e4760 2020-03-12 20:04:45,200 [INFO] [doorpi.sipphone.from_linphone] Cha [client] [CANCEL] transaction [0x1d40e10], from state [INIT] to [TRYING] 2020-03-12 20:04:45,201 [DEBUG] [doorpi.sipphone.from_linphone] PYLINPHONE] <<< pylinphone_Core_callback_call_state_changed 2020-03-12 20:04:45,202 [INFO] [doorpi.sipphone.from_linphone] cha[0x1cdfc50]: message sent to [UDP://192.168.178.1:5060], size: [347] bytes CANCEL sip:**610%23611%23612%23613%23614%23615@192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.23:5060;branch=z9hG4bK.lg6Bumcvp;rport Call-ID: ZIqtRcVs0C From: "Klingel" ;tag=dgEe5psQa To: sip:**610%23611%23612%23613%23614%23615@192.168.178.1 Max-Forwards: 70 CSeq: 21 CANCEL User-Agent: (belle-sip/1.4.2) Speicherzugriffsfehler pi@raspberrypi:~ $