Wie schon geschrieben funktioniert auch das Anrufen der DoorPi vom iPhone (intern) = **621 und vom Wohnzimmer = **611 trotz eingetragener "AdminNumber" nicht!
2016-01-26 18:18:00,111 [INFO] [doorpi.sipphone.from_linphone] nict_on_timer_E: sending retransmission
2016-01-26 18:18:00,116 [INFO] [doorpi.sipphone.from_linphone] channel [0x171b958]: message sent to [UDP://192.168.1.200:5060], size: [494] bytes
REGISTER sip:192.168.1.200 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.76:5060;branch=z9hG4bK.oDs28GpHZ;rport
From: "DoorPi" <sip:620@192.168.1.200>;tag=giaGZuGKJ
To: "DoorPi" <sip:620@192.168.1.200>
CSeq: 46 REGISTER
Call-ID: 81AUbtmKPR
Max-Forwards: 70
Supported: outbound
Accept: application/sdp, text/plain, application/vnd.gsma.rcs-ft-http+xml
Contact: <sip:620@192.168.1.76>;+sip.instance="<urn:uuid:8196ebb7-b296-4425-a41c-18711fb1d1e4>"
Expires: 3600
User-Agent: (belle-sip/1.4.1)
2016-01-26 18:18:00,715 [TRACE] [doorpi.action.handler] fire Event OnKeyDown from doorpi.keyboard.from_piface asyncron
2016-01-26 18:18:00,731 [TRACE] [doorpi.action.handler] fire Event OnKeyDown_0 from doorpi.keyboard.from_piface asyncron
2016-01-26 18:18:00,737 [DEBUG] [doorpi.action.handler] no actions for event OnKeyDown - skip fire_event OnKeyDown from doorpi.keyboard.from_piface
2016-01-26 18:18:00,753 [DEBUG] [doorpi.action.handler] no actions for event OnKeyDown_0 - skip fire_event OnKeyDown_0 from doorpi.keyboard.from_piface
2016-01-26 18:18:00,755 [TRACE] [doorpi.action.handler] fire Event OnKeyDown_onboardpins.0 from doorpi.keyboard.from_piface asyncron
2016-01-26 18:18:00,813 [DEBUG] [doorpi.action.handler] [HCF50A] fire for event OnKeyDown_onboardpins.0 this actions [<doorpi.action.SingleActions.call.CallAction instance at 0x15ef5d0>, <doorpi.action.SingleActions.call.CallAction instance at 0x15ef6e8>]
2016-01-26 18:18:00,817 [TRACE] [doorpi.action.handler] [HCF50A] try to fire action CallAction with args () and kwargs {'number': '**611'}
2016-01-26 18:18:00,851 [TRACE] [doorpi.action.handler] fire Event OnKeyPressed from doorpi.keyboard.from_piface asyncron
2016-01-26 18:18:00,858 [TRACE] [doorpi.action.base] run CallAction with args () and kwargs {'number': '**611'}
2016-01-26 18:18:00,872 [TRACE] [doorpi.action.handler] fire Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone asyncron
2016-01-26 18:18:00,889 [TRACE] [doorpi.action.handler] fire Event OnKeyPressed_0 from doorpi.keyboard.from_piface asyncron
2016-01-26 18:18:00,879 [DEBUG] [doorpi.action.handler] no actions for event OnKeyPressed - skip fire_event OnKeyPressed from doorpi.keyboard.from_piface
2016-01-26 18:18:00,905 [DEBUG] [doorpi.action.handler] no actions for event OnKeyPressed_0 - skip fire_event OnKeyPressed_0 from doorpi.keyboard.from_piface
2016-01-26 18:18:00,922 [TRACE] [doorpi.action.handler] fire Event OnKeyPressed_onboardpins.0 from doorpi.keyboard.from_piface asyncron
2016-01-26 18:18:00,961 [DEBUG] [doorpi.sipphone.from_linphone] call (**611)
2016-01-26 18:18:00,965 [DEBUG] [doorpi.sipphone.from_linphone] no current call -> start new call
2016-01-26 18:18:00,929 [DEBUG] [doorpi.action.handler] no actions for event BeforeSipPhoneMakeCall - skip fire_event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone
2016-01-26 18:18:00,984 [DEBUG] [doorpi.action.handler] [UIM4KV] fire for event OnKeyPressed_onboardpins.0 this actions [<doorpi.action.SingleActions.call.CallAction instance at 0x15ef800>]
2016-01-26 18:18:00,987 [TRACE] [doorpi.action.handler] [UIM4KV] try to fire action CallAction with args () and kwargs {'number': '**611'}
2016-01-26 18:18:00,969 [DEBUG] [doorpi.sipphone.from_linphone] reset current call start datetime to 2016-01-26 17:18:00.968001
2016-01-26 18:18:01,025 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_call_params(0x1611560 [0x1706250], 0x27c688 [(nil)])
2016-01-26 18:18:01,028 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_from_native_ptr(0x17d07d0)
2016-01-26 18:18:01,062 [TRACE] [doorpi.action.base] run CallAction with args () and kwargs {'number': '**611'}
2016-01-26 18:18:01,073 [TRACE] [doorpi.action.handler] fire Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone asyncron
2016-01-26 18:18:01,081 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_new()
2016-01-26 18:18:01,084 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_new -> 0xb6d46fd0
2016-01-26 18:18:01,087 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_init()
2016-01-26 18:18:01,123 [DEBUG] [doorpi.action.handler] no actions for event BeforeSipPhoneMakeCall - skip fire_event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone
2016-01-26 18:18:01,142 [DEBUG] [doorpi.sipphone.from_linphone] call (**611)
2016-01-26 18:18:01,146 [DEBUG] [doorpi.sipphone.from_linphone] no current call -> start new call
2016-01-26 18:18:01,161 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_init -> 0xb6d46fd0
2016-01-26 18:18:01,164 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_from_native_ptr -> 0xb6d46fd0
2016-01-26 18:18:01,167 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_call_params -> 0xb6d46fd0
2016-01-26 18:18:01,149 [DEBUG] [doorpi.sipphone.from_linphone] reset current call start datetime to 2016-01-26 17:18:01.149133
2016-01-26 18:18:01,203 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_call_params(0x1611560 [0x1706250], 0x27c688 [(nil)])
2016-01-26 18:18:01,207 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_from_native_ptr(0x1757658)
2016-01-26 18:18:01,261 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_new()
2016-01-26 18:18:01,265 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_new -> 0xb6d46fe0
2016-01-26 18:18:01,268 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_init()
2016-01-26 18:18:01,250 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_set_record_file(0xb6d46fd0 [0x17d07d0], "/usr/local/etc/DoorPi/records/onboardpins.0/2016-01-26_18-18-01.wav")
2016-01-26 18:18:01,303 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_set_record_file -> 0
2016-01-26 18:18:01,306 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_enable_video(0xb6d46fd0 [0x17d07d0], 0x27c6a0 [1])
2016-01-26 18:18:01,342 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_init -> 0xb6d46fe0
2016-01-26 18:18:01,347 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_from_native_ptr -> 0xb6d46fe0
2016-01-26 18:18:01,309 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_enable_video -> 0
2016-01-26 18:18:01,364 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_invite_with_params(0x1611560 [0x1706250], "**611", 0xb6d46fd0 [0x17d07d0])
2016-01-26 18:18:01,410 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_call_params -> 0xb6d46fe0
2016-01-26 18:18:01,398 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_call_state_changed(0x1706250, 0x17e4618, 2, "Starting outgoing call")
2016-01-26 18:18:01,494 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_new()
2016-01-26 18:18:01,497 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_new -> 0xb6d46e40
2016-01-26 18:18:01,512 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_set_record_file(0xb6d46fe0 [0x1757658], "/usr/local/etc/DoorPi/records/onboardpins.0/2016-01-26_18-18-01.wav")
2016-01-26 18:18:01,516 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_set_record_file -> 0
2016-01-26 18:18:01,511 [WARNING] [doorpi.sipphone.from_linphone] There is no object pool created in thread [3013567600]. Use belle_sip_object_pool_push() to create one. Unowned objects not unref'd will be leaked.
2016-01-26 18:18:01,519 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_enable_video(0xb6d46fe0 [0x1757658], 0x27c6a0 [1])
2016-01-26 18:18:01,534 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_init()
2016-01-26 18:18:01,536 [TRACE] [doorpi.action.handler] fire Event OnKeyUp from doorpi.keyboard.from_piface asyncron
2016-01-26 18:18:01,540 [INFO] [doorpi.sipphone.from_linphone] New LinphoneCall [0x17e4618] initialized (LinphoneCore version: 3.8.5-425-g787501e)
2016-01-26 18:18:01,576 [INFO] [doorpi.sipphone.from_linphone] Call 0x17e4618: moving from state LinphoneCallIdle to LinphoneCallOutgoingInit
2016-01-26 18:18:01,556 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_init -> 0xb6d46e40
2016-01-26 18:18:01,571 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_enable_video -> 0
2016-01-26 18:18:01,579 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1706250] notifying [call_state_changed]
2016-01-26 18:18:01,597 [TRACE] [doorpi.action.handler] fire Event OnKeyUp_0 from doorpi.keyboard.from_piface asyncron
2016-01-26 18:18:01,593 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (2 - Starting outgoing call)
2016-01-26 18:18:01,596 [DEBUG] [doorpi.action.handler] no actions for event OnKeyUp - skip fire_event OnKeyUp from doorpi.keyboard.from_piface
2016-01-26 18:18:01,613 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_invite_with_params(0x1611560 [0x1706250], "**611", 0xb6d46fe0 [0x1757658])
2016-01-26 18:18:01,671 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_get_remote_address(0xb6d46e40 [0x17e4618])
2016-01-26 18:18:01,675 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0x1405690)
2016-01-26 18:18:01,678 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new()
2016-01-26 18:18:01,691 [TRACE] [doorpi.action.handler] fire Event OnKeyUp_onboardpins.0 from doorpi.keyboard.from_piface asyncron
2016-01-26 18:18:01,659 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_new()
2016-01-26 18:18:01,714 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_new -> 0xb6d46fc0
2016-01-26 18:18:01,696 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_call_state_changed(0x1706250, 0x1756588, 2, "Starting outgoing call")
2016-01-26 18:18:01,694 [DEBUG] [doorpi.action.handler] no actions for event OnKeyUp_0 - skip fire_event OnKeyUp_0 from doorpi.keyboard.from_piface
2016-01-26 18:18:01,732 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0x17f6020
2016-01-26 18:18:01,736 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init()
2016-01-26 18:18:01,752 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_new()
2016-01-26 18:18:01,756 [DEBUG] [doorpi.action.handler] no actions for event OnKeyUp_onboardpins.0 - skip fire_event OnKeyUp_onboardpins.0 from doorpi.keyboard.from_piface
2016-01-26 18:18:01,753 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_init()
2016-01-26 18:18:01,777 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_init -> 0xb6d46fc0
2016-01-26 18:18:01,760 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_new -> 0x17f6060
2016-01-26 18:18:01,739 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0x17f6020
2016-01-26 18:18:01,796 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0x17f6020
2016-01-26 18:18:01,811 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_init()
2016-01-26 18:18:01,814 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_init -> 0x17f6060
2016-01-26 18:18:01,818 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (2 - Starting outgoing call)
2016-01-26 18:18:01,791 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_get_state(0xb6d46fc0 [0x1756588])
2016-01-26 18:18:01,799 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_get_remote_address -> 0x17f6020
2016-01-26 18:18:01,837 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_instance_method_as_string_uri_only(0x17f6020 [0x1405690])
2016-01-26 18:18:01,851 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_get_state -> 0x12410a4
2016-01-26 18:18:01,855 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_inc_timeout(0x1611560 [0x1706250])
2016-01-26 18:18:01,858 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_inc_timeout -> 0x1241008
2016-01-26 18:18:01,871 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_get_remote_address(0x17f6060 [0x1756588])
2016-01-26 18:18:01,850 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_instance_method_as_string_uri_only -> 0x178ed70
2016-01-26 18:18:01,880 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron
2016-01-26 18:18:01,878 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0x17cd4f0)
2016-01-26 18:18:01,899 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new()
2016-01-26 18:18:01,924 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0x17f6070
2016-01-26 18:18:01,921 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x1611560 [0x1706250])
2016-01-26 18:18:01,908 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks
2016-01-26 18:18:01,944 [WARNING] [doorpi.sipphone.from_linphone] There is no object pool created in thread [2977952880]. Use belle_sip_object_pool_push() to create one. Unowned objects not unref'd will be leaked.
2016-01-26 18:18:01,948 [INFO] [doorpi.sipphone.from_linphone] Pausing automatically the current call.
2016-01-26 18:18:01,951 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x12410a4
2016-01-26 18:18:01,960 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init()
2016-01-26 18:18:01,971 [WARNING] [doorpi.sipphone.from_linphone] Cannot pause this call, it is not active.
2016-01-26 18:18:01,974 [INFO] [doorpi.sipphone.from_linphone] New LinphoneCall [0x1756588] initialized (LinphoneCore version: 3.8.5-425-g787501e)
2016-01-26 18:18:01,977 [INFO] [doorpi.sipphone.from_linphone] Call 0x1756588: moving from state LinphoneCallIdle to LinphoneCallOutgoingInit
2016-01-26 18:18:01,966 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0x17f6070
2016-01-26 18:18:02,002 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0x17f6070
2016-01-26 18:18:02,006 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_get_remote_address -> 0x17f6070
2016-01-26 18:18:01,991 [TRACE] [doorpi.action.handler] fire Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks asyncron
2016-01-26 18:18:01,991 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1706250] notifying [call_state_changed]
2016-01-26 18:18:02,029 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_get_state(0x17f6060 [0x1756588])
2016-01-26 18:18:02,009 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_instance_method_as_string_uri_only(0x17f6070 [0x17cd4f0])
2016-01-26 18:18:02,063 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_instance_method_as_string_uri_only -> 0x178eec0
2016-01-26 18:18:02,045 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x1611560 [0x1706250])
2016-01-26 18:18:02,043 [DEBUG] [doorpi.action.handler] no actions for event OnMediaRequired - skip fire_event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks
2016-01-26 18:18:02,070 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_get_state -> 0x12410a4
2016-01-26 18:18:02,075 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_inc_timeout(0x1611560 [0x1706250])
2016-01-26 18:18:02,078 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_inc_timeout -> 0x1241008
2016-01-26 18:18:02,091 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x12410a4
2016-01-26 18:18:02,093 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron
2016-01-26 18:18:02,111 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_call_state_changed
2016-01-26 18:18:02,143 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks
2016-01-26 18:18:02,161 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x1611560 [0x1706250])
2016-01-26 18:18:02,165 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x12410a4
2016-01-26 18:18:02,168 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x1611560 [0x1706250])
2016-01-26 18:18:02,135 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_call_state_changed(0x1706250, 0x17e4618, 3, "Outgoing call in progress")
2016-01-26 18:18:02,187 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (3 - Outgoing call in progress)
2016-01-26 18:18:02,183 [INFO] [doorpi.sipphone.from_linphone] Cannot determine multicast role for stream type [audio] on call [0x17e4618]
2016-01-26 18:18:02,203 [INFO] [doorpi.sipphone.from_linphone] RtpSession bound to [0.0.0.0] ports [7078] [7079]
2016-01-26 18:18:02,207 [INFO] [doorpi.sipphone.from_linphone] Setting DSCP to 46 for MSAudio stream.
2016-01-26 18:18:02,215 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x12410a4
2016-01-26 18:18:02,201 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_get_remote_address(0xb6d46e40 [0x17e4618])
2016-01-26 18:18:02,234 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0x1405690)
2016-01-26 18:18:02,237 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new()
2016-01-26 18:18:02,219 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x1611560 [0x1706250])
2016-01-26 18:18:02,254 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x12410a4
2016-01-26 18:18:02,258 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_call_state_changed
2016-01-26 18:18:02,214 [INFO] [doorpi.sipphone.from_linphone] Equalizer location: hp
2016-01-26 18:18:02,275 [INFO] [doorpi.sipphone.from_linphone] cannot set noise gate mode to [0] because no volume send
2016-01-26 18:18:02,278 [INFO] [doorpi.sipphone.from_linphone] Cannot determine multicast role for stream type [video] on call [0x17e4618]
2016-01-26 18:18:02,271 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0x17f6050
2016-01-26 18:18:02,316 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init()
2016-01-26 18:18:02,312 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_call_state_changed(0x1706250, 0x1756588, 3, "Outgoing call in progress")
2016-01-26 18:18:02,337 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (3 - Outgoing call in progress)
2016-01-26 18:18:02,331 [INFO] [doorpi.sipphone.from_linphone] RtpSession bound to [0.0.0.0] ports [9078] [9079]
2016-01-26 18:18:02,319 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0x17f6050
2016-01-26 18:18:02,355 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0x17f6050
2016-01-26 18:18:02,358 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_get_remote_address -> 0x17f6050
2016-01-26 18:18:02,370 [INFO] [doorpi.sipphone.from_linphone] Setting DSCP to 0 for MSVideo stream.
2016-01-26 18:18:02,372 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_get_remote_address(0x17f6060 [0x1756588])
2016-01-26 18:18:02,383 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0x17cd4f0)
2016-01-26 18:18:02,387 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new()
2016-01-26 18:18:02,401 [INFO] [doorpi.sipphone.from_linphone] Contact has been fixed using proxy
2016-01-26 18:18:02,405 [INFO] [doorpi.sipphone.from_linphone] ms_filter_link: MSRtpRecv:0x1737eb8,0-->MSVoidSink:0x1731e88,0
2016-01-26 18:18:02,408 [INFO] [doorpi.sipphone.from_linphone] Priority used: 99
2016-01-26 18:18:02,390 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0x17f6030
2016-01-26 18:18:02,421 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_instance_method_as_string_uri_only(0x17f6050 [0x1405690])
2016-01-26 18:18:02,438 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_instance_method_as_string_uri_only -> 0x178ed40
2016-01-26 18:18:02,451 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init()
2016-01-26 18:18:02,453 [INFO] [doorpi.sipphone.from_linphone] MSAudio MSTicker priority set to SCHED_RR and value (99)
2016-01-26 18:18:02,459 [INFO] [doorpi.sipphone.from_linphone] ["DoorPi" <sip:620@192.168.1.200>] calling [sip:**611@192.168.1.200] on op [0x1763378]
2016-01-26 18:18:02,472 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron
2016-01-26 18:18:02,475 [INFO] [doorpi.sipphone.from_linphone] Skipping top route of initial route-set because same as request-uri.
2016-01-26 18:18:02,471 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0x17f6030
2016-01-26 18:18:02,496 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0x17f6030
2016-01-26 18:18:02,512 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [INVITE] transaction [0x17cb9f0], from state [INIT] to [CALLING]
2016-01-26 18:18:02,515 [INFO] [doorpi.sipphone.from_linphone] channel [0x171b958]: message sent to [UDP://192.168.1.200:5060], size: [807] bytes
INVITE sip:**611@192.168.1.200 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.76:5060;branch=z9hG4bK.sqwp2f~Wu;rport
From: "DoorPi" <sip:620@192.168.1.200>;tag=JcBzTPRik
To: sip:**611@192.168.1.200
CSeq: 20 INVITE
Call-ID: WO6lbiJp2I
Max-Forwards: 70
Supported: outbound
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
Content-Type: application/sdp
Content-Length: 267
Contact: <sip:620@192.168.1.76>;+sip.instance="<urn:uuid:8196ebb7-b296-4425-a41c-18711fb1d1e4>"
User-Agent: (belle-sip/1.4.1)
v=0
o=620 2106 2142 IN IP4 192.168.1.76
s=Talk
c=IN IP4 192.168.1.76
t=0 0
a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
m=audio 7078 RTP/AVP 0 8 101
a=rtpmap:101 telephone-event/8000
m=video 9078 RTP/AVP 96
a=rtpmap:96 VP8/90000
2016-01-26 18:18:02,499 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_get_remote_address -> 0x17f6030
2016-01-26 18:18:02,533 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x1611560 [0x1706250])
2016-01-26 18:18:02,519 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1706250] notifying [display_status]
2016-01-26 18:18:02,534 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks
2016-01-26 18:18:02,542 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_instance_method_as_string_uri_only(0x17f6030 [0x17cd4f0])
2016-01-26 18:18:02,547 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x12410a4
2016-01-26 18:18:02,581 [INFO] [doorpi.sipphone.from_linphone] Call 0x17e4618: moving from state LinphoneCallOutgoingInit to LinphoneCallOutgoingProgress
2016-01-26 18:18:02,588 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1706250] notifying [call_state_changed]
2016-01-26 18:18:02,569 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_instance_method_as_string_uri_only -> 0x178ed40
2016-01-26 18:18:02,584 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x1611560 [0x1706250])
2016-01-26 18:18:02,608 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x12410a4
2016-01-26 18:18:02,621 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_get_state(0x17f6060 [0x1756588])
2016-01-26 18:18:02,628 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_get_state -> 0x1241098
2016-01-26 18:18:02,624 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x1611560 [0x1706250])
2016-01-26 18:18:02,621 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron
2016-01-26 18:18:02,649 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x12410a4
2016-01-26 18:18:02,663 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_call_state_changed
2016-01-26 18:18:02,667 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_invite_with_params -> 0xb6d46e40
2016-01-26 18:18:02,681 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_inc_timeout(0x1611560 [0x1706250])
2016-01-26 18:18:02,684 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_inc_timeout -> 0x1241008
2016-01-26 18:18:02,701 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x1611560 [0x1706250])
2016-01-26 18:18:02,706 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x12410a4
2016-01-26 18:18:02,690 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks
2016-01-26 18:18:02,702 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone asyncron
2016-01-26 18:18:02,709 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x1611560 [0x1706250])
2016-01-26 18:18:02,743 [DEBUG] [doorpi.action.handler] [LNRRJ6] fire for event OnSipPhoneMakeCall this actions [<doorpi.action.base.SingleAction instance at 0x15ef1c0>, <doorpi.action.base.SingleAction instance at 0x15ef350>]
2016-01-26 18:18:02,747 [TRACE] [doorpi.action.handler] [LNRRJ6] try to fire action <bound method LinphonePlayer.start of <doorpi.sipphone.linphone_lib.Player.LinphonePlayer object at 0x15f4f90>> with args () and kwargs {}
2016-01-26 18:18:02,730 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x12410a4
2016-01-26 18:18:02,740 [INFO] [doorpi.sipphone.from_linphone] Cannot determine multicast role for stream type [audio] on call [0x1756588]
2016-01-26 18:18:02,776 [INFO] [doorpi.sipphone.from_linphone] RtpSession bound to [0.0.0.0] ports [7080] [7081]
2016-01-26 18:18:02,761 [TRACE] [doorpi.action.handler] fire Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone asyncron
2016-01-26 18:18:02,795 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x1611560 [0x1706250])
2016-01-26 18:18:02,804 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x12410a4
2016-01-26 18:18:02,807 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_call_state_changed
2016-01-26 18:18:02,796 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {}
2016-01-26 18:18:02,824 [DEBUG] [doorpi.action.handler] no actions for event AfterSipPhoneMakeCall - skip fire_event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone
2016-01-26 18:18:02,780 [INFO] [doorpi.sipphone.from_linphone] Setting DSCP to 46 for MSAudio stream.
2016-01-26 18:18:02,833 [TRACE] [doorpi.action.handler] [HCF50A] try to fire action CallAction with args () and kwargs {'number': '11'}
2016-01-26 18:18:02,853 [TRACE] [doorpi.action.base] run CallAction with args () and kwargs {'number': '11'}
2016-01-26 18:18:02,856 [TRACE] [doorpi.action.handler] fire Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone asyncron
2016-01-26 18:18:02,882 [TRACE] [doorpi.action.handler] fire Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player asyncron
2016-01-26 18:18:02,886 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_invite_with_params -> 0x17f6060
2016-01-26 18:18:02,881 [INFO] [doorpi.sipphone.from_linphone] Equalizer location: hp
2016-01-26 18:18:02,904 [INFO] [doorpi.sipphone.from_linphone] cannot set noise gate mode to [0] because no volume send
2016-01-26 18:18:02,907 [INFO] [doorpi.sipphone.from_linphone] Cannot determine multicast role for stream type [video] on call [0x1756588]
2016-01-26 18:18:02,894 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone asyncron
2016-01-26 18:18:02,910 [INFO] [doorpi.sipphone.from_linphone] RtpSession bound to [0.0.0.0] ports [9080] [9081]
2016-01-26 18:18:02,934 [INFO] [doorpi.sipphone.from_linphone] Setting DSCP to 0 for MSVideo stream.
2016-01-26 18:18:02,937 [INFO] [doorpi.sipphone.from_linphone] Contact has been fixed using proxy
2016-01-26 18:18:02,951 [DEBUG] [doorpi.sipphone.from_linphone] call (11)
2016-01-26 18:18:02,955 [DEBUG] [doorpi.action.handler] [U9CKU5] fire for event OnSipPhoneMakeCall this actions [<doorpi.action.base.SingleAction instance at 0x15ef1c0>, <doorpi.action.base.SingleAction instance at 0x15ef350>]
2016-01-26 18:18:02,970 [TRACE] [doorpi.action.handler] [LNRRJ6] try to fire action <bound method LinphoneRecorder.start of <doorpi.sipphone.linphone_lib.Recorder.LinphoneRecorder object at 0x15f4f50>> with args () and kwargs {}
2016-01-26 18:18:02,974 [DEBUG] [doorpi.action.handler] no actions for event BeforeSipPhoneMakeCall - skip fire_event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone
2016-01-26 18:18:02,976 [TRACE] [doorpi.action.handler] fire Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone asyncron
2016-01-26 18:18:02,992 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerStarted - skip fire_event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player
2016-01-26 18:18:02,999 [TRACE] [doorpi.action.handler] [U9CKU5] try to fire action <bound method LinphonePlayer.start of <doorpi.sipphone.linphone_lib.Player.LinphonePlayer object at 0x15f4f90>> with args () and kwargs {}
2016-01-26 18:18:03,035 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {}
2016-01-26 18:18:03,038 [TRACE] [doorpi.action.handler] fire Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player asyncron
2016-01-26 18:18:03,051 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {}
2016-01-26 18:18:03,053 [INFO] [doorpi.sipphone.from_linphone] ms_filter_link: MSRtpRecv:0x17d75f8,0-->MSVoidSink:0x17e2bf0,0
2016-01-26 18:18:03,055 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_get_remote_address(0x17f6060 [0x1756588])
2016-01-26 18:18:03,059 [DEBUG] [doorpi.action.handler] no actions for event AfterSipPhoneMakeCall - skip fire_event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone
2016-01-26 18:18:03,064 [TRACE] [doorpi.action.handler] [UIM4KV] finished fire_event for event_name OnKeyPressed_onboardpins.0
2016-01-26 18:18:03,104 [INFO] [doorpi.sipphone.from_linphone] ["DoorPi" <sip:620@192.168.1.200>] calling [sip:**611@192.168.1.200] on op [0x17e7920]
2016-01-26 18:18:03,107 [INFO] [doorpi.sipphone.from_linphone] Skipping top route of initial route-set because same as request-uri.
2016-01-26 18:18:03,121 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0x17cd4f0)
2016-01-26 18:18:03,145 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new()
2016-01-26 18:18:03,148 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0x17f60e0
2016-01-26 18:18:03,110 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [INVITE] transaction [0x17d84b0], from state [INIT] to [CALLING]
2016-01-26 18:18:03,141 [DEBUG] [doorpi.sipphone.linphone_lib.Recorder] starting recording to /usr/local/etc/DoorPi/records/onboardpins.0/2016-01-26_18-18-03.wav
2016-01-26 18:18:03,187 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_instance_method_start_recording(0x17f6060 [0x1756588])
2016-01-26 18:18:03,164 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerStarted - skip fire_event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player
2016-01-26 18:18:03,166 [TRACE] [doorpi.action.handler] [U9CKU5] try to fire action <bound method LinphoneRecorder.start of <doorpi.sipphone.linphone_lib.Recorder.LinphoneRecorder object at 0x15f4f50>> with args () and kwargs {}
2016-01-26 18:18:03,206 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {}
2016-01-26 18:18:03,221 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init()
2016-01-26 18:18:03,225 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0x17f60e0
2016-01-26 18:18:03,228 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0x17f60e0
2016-01-26 18:18:03,201 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_instance_method_start_recording -> None
2016-01-26 18:18:03,240 [INFO] [doorpi.sipphone.from_linphone] Priority used: 99
2016-01-26 18:18:03,268 [INFO] [doorpi.sipphone.from_linphone] MSAudio MSTicker priority set to SCHED_RR and value (99)
2016-01-26 18:18:03,280 [TRACE] [doorpi.action.handler] fire Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder asyncron
2016-01-26 18:18:03,283 [DEBUG] [doorpi.sipphone.linphone_lib.Recorder] starting recording to /usr/local/etc/DoorPi/records/onboardpins.0/2016-01-26_18-18-03.wav
2016-01-26 18:18:03,285 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_get_remote_address -> 0x17f60e0
2016-01-26 18:18:03,306 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_instance_method_as_string_uri_only(0x17f60e0 [0x17cd4f0])
2016-01-26 18:18:03,287 [INFO] [doorpi.sipphone.from_linphone] channel [0x171b958]: message sent to [UDP://192.168.1.200:5060], size: [807] bytes
INVITE sip:**611@192.168.1.200 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.76:5060;branch=z9hG4bK.1yuot1ug0;rport
From: "DoorPi" <sip:620@192.168.1.200>;tag=dAYAakMbR
To: sip:**611@192.168.1.200
CSeq: 20 INVITE
Call-ID: IvvgwbaHUC
Max-Forwards: 70
Supported: outbound
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
Content-Type: application/sdp
Content-Length: 267
Contact: <sip:620@192.168.1.76>;+sip.instance="<urn:uuid:8196ebb7-b296-4425-a41c-18711fb1d1e4>"
User-Agent: (belle-sip/1.4.1)
v=0
o=620 1456 2233 IN IP4 192.168.1.76
s=Talk
c=IN IP4 192.168.1.76
t=0 0
a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
m=audio 7080 RTP/AVP 0 8 101
a=rtpmap:101 telephone-event/8000
m=video 9080 RTP/AVP 96
a=rtpmap:96 VP8/90000
2016-01-26 18:18:03,309 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_instance_method_as_string_uri_only -> 0x178ea70
2016-01-26 18:18:03,324 [TRACE] [doorpi.action.handler] [LNRRJ6] finished fire_event for event_name OnSipPhoneMakeCall
2016-01-26 18:18:03,322 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_instance_method_start_recording(0x17f6060 [0x1756588])
2016-01-26 18:18:03,357 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_instance_method_start_recording -> None
2016-01-26 18:18:03,326 [DEBUG] [doorpi.action.handler] no actions for event OnRecorderStarted - skip fire_event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder
2016-01-26 18:18:03,334 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1706250] notifying [display_status]
2016-01-26 18:18:03,378 [INFO] [doorpi.sipphone.from_linphone] Call 0x1756588: moving from state LinphoneCallOutgoingInit to LinphoneCallOutgoingProgress
2016-01-26 18:18:03,372 [DEBUG] [doorpi.sipphone.from_linphone] press twice with call duration > 1 second? Want to hangup current call? OK...
2016-01-26 18:18:03,397 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_terminate_all_calls(0x1611560 [0x1706250])
2016-01-26 18:18:03,392 [TRACE] [doorpi.action.handler] fire Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder asyncron
2016-01-26 18:18:03,411 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1706250] notifying [call_state_changed]
2016-01-26 18:18:03,418 [INFO] [doorpi.sipphone.from_linphone] channel [0x171b958]: message sent to [UDP://192.168.1.200:5060], size: [807] bytes
INVITE sip:**611@192.168.1.200 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.76:5060;branch=z9hG4bK.sqwp2f~Wu;rport
From: "DoorPi" <sip:620@192.168.1.200>;tag=JcBzTPRik
To: sip:**611@192.168.1.200
CSeq: 20 INVITE
Call-ID: WO6lbiJp2I
Max-Forwards: 70
Supported: outbound
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
Content-Type: application/sdp
Content-Length: 267
Contact: <sip:620@192.168.1.76>;+sip.instance="<urn:uuid:8196ebb7-b296-4425-a41c-18711fb1d1e4>"
User-Agent: (belle-sip/1.4.1)
v=0
o=620 2106 2142 IN IP4 192.168.1.76
s=Talk
c=IN IP4 192.168.1.76
t=0 0
a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
m=audio 7078 RTP/AVP 0 8 101
a=rtpmap:101 telephone-event/8000
m=video 9078 RTP/AVP 96
a=rtpmap:96 VP8/90000
2016-01-26 18:18:03,441 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_call_log_updated(0x1706250, 0x17d41d0)
2016-01-26 18:18:03,464 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_call_log_updated
2016-01-26 18:18:03,452 [TRACE] [doorpi.action.handler] [U9CKU5] finished fire_event for event_name OnSipPhoneMakeCall
2016-01-26 18:18:03,445 [DEBUG] [doorpi.action.handler] no actions for event OnRecorderStarted - skip fire_event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder
2016-01-26 18:18:03,481 [INFO] [doorpi.sipphone.from_linphone] ms_filter_unlink: MSRtpRecv:0x1737eb8,0-->MSVoidSink:0x1731e88,0
2016-01-26 18:18:03,484 [INFO] [doorpi.sipphone.from_linphone] ===========================================================
2016-01-26 18:18:03,487 [INFO] [doorpi.sipphone.from_linphone] FILTER USAGE STATISTICS
2016-01-26 18:18:03,513 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_call_state_changed(0x1706250, 0x17e4618, 13, "Call terminated")
2016-01-26 18:18:03,517 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (13 - Call terminated)
2016-01-26 18:18:03,522 [INFO] [doorpi.sipphone.from_linphone] Name Count Time/tick (ms) CPU Usage
2016-01-26 18:18:03,525 [INFO] [doorpi.sipphone.from_linphone] -----------------------------------------------------------
2016-01-26 18:18:03,529 [INFO] [doorpi.sipphone.from_linphone] MSRtpRecv 225 0.0692021 100
2016-01-26 18:18:03,522 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_get_remote_address(0xb6d46e40 [0x17e4618])
2016-01-26 18:18:03,554 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0x1405690)
2016-01-26 18:18:03,557 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new()
2016-01-26 18:18:03,551 [INFO] [doorpi.sipphone.from_linphone] MSVoidSink 0 0 0
2016-01-26 18:18:03,574 [INFO] [doorpi.sipphone.from_linphone] MSItcSink 0 0 0
2016-01-26 18:18:03,577 [INFO] [doorpi.sipphone.from_linphone] MSTee 0 0 0
2016-01-26 18:18:03,591 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0xb6d46ef0
2016-01-26 18:18:03,595 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init()
2016-01-26 18:18:03,598 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0xb6d46ef0
2016-01-26 18:18:03,591 [INFO] [doorpi.sipphone.from_linphone] MSSpeexEC 0 0 0
2016-01-26 18:18:03,615 [INFO] [doorpi.sipphone.from_linphone] MSRtpSend 0 0 0
2016-01-26 18:18:03,618 [INFO] [doorpi.sipphone.from_linphone] ===========================================================
2016-01-26 18:18:03,631 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0xb6d46ef0
2016-01-26 18:18:03,635 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_get_remote_address -> 0xb6d46ef0
2016-01-26 18:18:03,638 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_instance_method_as_string_uri_only(0xb6d46ef0 [0x1405690])
2016-01-26 18:18:03,651 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1706250] notifying [display_status]
2016-01-26 18:18:03,657 [INFO] [doorpi.sipphone.from_linphone] Call 0x17e4618: moving from state LinphoneCallOutgoingProgress to LinphoneCallEnd
2016-01-26 18:18:03,671 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_instance_method_as_string_uri_only -> 0x17f7500
2016-01-26 18:18:03,675 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron
2016-01-26 18:18:03,660 [INFO] [doorpi.sipphone.from_linphone] MSAudio MSTicker thread exiting
2016-01-26 18:18:03,684 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1706250] notifying [call_log_updated]
2016-01-26 18:18:03,712 [TRACE] [doorpi.action.handler] fire Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks asyncron
2016-01-26 18:18:03,718 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks
2016-01-26 18:18:03,689 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_get_state(0x17f6060 [0x1756588])
2016-01-26 18:18:03,724 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_get_state -> 0x1241098
2016-01-26 18:18:03,729 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_inc_timeout(0x1611560 [0x1706250])
2016-01-26 18:18:03,752 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x1611560 [0x1706250])
2016-01-26 18:18:03,755 [DEBUG] [doorpi.action.handler] [HK0811] fire for event OnCallStateDisconnect this actions [<doorpi.action.base.SingleAction instance at 0x15ef0d0>, <doorpi.action.base.SingleAction instance at 0x15ef3a0>]
2016-01-26 18:18:03,775 [TRACE] [doorpi.action.handler] [HK0811] try to fire action <bound method LinphonePlayer.stop of <doorpi.sipphone.linphone_lib.Player.LinphonePlayer object at 0x15f4f90>> with args () and kwargs {}
2016-01-26 18:18:03,779 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {}
2016-01-26 18:18:03,771 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x12410b0
2016-01-26 18:18:03,791 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_inc_timeout -> 0x1241008
2016-01-26 18:18:03,798 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x1611560 [0x1706250])
2016-01-26 18:18:03,807 [TRACE] [doorpi.action.handler] fire Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player asyncron
2016-01-26 18:18:03,810 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x12410b0
2016-01-26 18:18:03,823 [TRACE] [doorpi.action.handler] fire Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks asyncron
2016-01-26 18:18:03,843 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerStopped - skip fire_event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player
2016-01-26 18:18:03,850 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1706250] notifying [call_state_changed]
2016-01-26 18:18:03,864 [INFO] [doorpi.sipphone.from_linphone] channel [0x171b958]: message sent to [UDP://192.168.1.200:5060], size: [807] bytes
INVITE sip:**611@192.168.1.200 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.76:5060;branch=z9hG4bK.sqwp2f~Wu;rport
From: "DoorPi" <sip:620@192.168.1.200>;tag=JcBzTPRik
To: sip:**611@192.168.1.200
CSeq: 20 INVITE
Call-ID: WO6lbiJp2I
Max-Forwards: 70
Supported: outbound
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
Content-Type: application/sdp
Content-Length: 267
Contact: <sip:620@192.168.1.76>;+sip.instance="<urn:uuid:8196ebb7-b296-4425-a41c-18711fb1d1e4>"
User-Agent: (belle-sip/1.4.1)
v=0
o=620 2106 2142 IN IP4 192.168.1.76
s=Talk
c=IN IP4 192.168.1.76
t=0 0
a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
m=audio 7078 RTP/AVP 0 8 101
a=rtpmap:101 telephone-event/8000
m=video 9078 RTP/AVP 96
a=rtpmap:96 VP8/90000
2016-01-26 18:18:03,868 [INFO] [doorpi.sipphone.from_linphone] channel [0x171b958]: message sent to [UDP://192.168.1.200:5060], size: [807] bytes
INVITE sip:**611@192.168.1.200 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.76:5060;branch=z9hG4bK.1yuot1ug0;rport
From: "DoorPi" <sip:620@192.168.1.200>;tag=dAYAakMbR
To: sip:**611@192.168.1.200
CSeq: 20 INVITE
Call-ID: IvvgwbaHUC
Max-Forwards: 70
Supported: outbound
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
Content-Type: application/sdp
Content-Length: 267
Contact: <sip:620@192.168.1.76>;+sip.instance="<urn:uuid:8196ebb7-b296-4425-a41c-18711fb1d1e4>"
User-Agent: (belle-sip/1.4.1)
v=0
o=620 1456 2233 IN IP4 192.168.1.76
s=Talk
c=IN IP4 192.168.1.76
t=0 0
a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
m=audio 7080 RTP/AVP 0 8 101
a=rtpmap:101 telephone-event/8000
m=video 9080 RTP/AVP 96
a=rtpmap:96 VP8/90000
2016-01-26 18:18:03,848 [TRACE] [doorpi.action.handler] [HK0811] try to fire action <bound method LinphoneRecorder.stop of <doorpi.sipphone.linphone_lib.Recorder.LinphoneRecorder object at 0x15f4f50>> with args () and kwargs {}
2016-01-26 18:18:03,884 [DEBUG] [doorpi.action.handler] no actions for event OnMediaNotRequired - skip fire_event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks
2016-01-26 18:18:03,886 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x1611560 [0x1706250])
2016-01-26 18:18:03,905 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x12410b0
2016-01-26 18:18:03,909 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_call_state_changed
2016-01-26 18:18:03,921 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {}
2016-01-26 18:18:03,924 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_get_state(0x17f6060 [0x1756588])
2016-01-26 18:18:03,947 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_get_state -> 0x1241020
2016-01-26 18:18:03,962 [DEBUG] [doorpi.sipphone.linphone_lib.Recorder] stopping recording to /usr/local/etc/DoorPi/records/onboardpins.0/2016-01-26_18-18-03.wav
2016-01-26 18:18:03,966 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_instance_method_stop_recording(0x17f6060 [0x1756588])
2016-01-26 18:18:03,981 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_call_log_updated(0x1706250, 0x17e4a08)
2016-01-26 18:18:03,961 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_in_call_timeout(0x1611560 [0x1706250])
2016-01-26 18:18:03,989 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_in_call_timeout -> 0x12412d4
2016-01-26 18:18:04,004 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_call_log_updated
2016-01-26 18:18:04,021 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_instance_method_stop_recording -> None
2016-01-26 18:18:04,028 [TRACE] [doorpi.action.handler] fire Event OnRecorderStopped from doorpi.sipphone.linphone_lib.Recorder asyncron
2016-01-26 18:18:04,009 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_call_state_changed(0x1706250, 0x1756588, 13, "Call terminated")
2016-01-26 18:18:04,066 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (13 - Call terminated)
2016-01-26 18:18:04,076 [INFO] [doorpi.sipphone.from_linphone] ms_filter_unlink: MSRtpRecv:0x17d75f8,0-->MSVoidSink:0x17e2bf0,0
2016-01-26 18:18:04,079 [INFO] [doorpi.sipphone.from_linphone] ===========================================================
2016-01-26 18:18:04,097 [TRACE] [doorpi.action.handler] [HK0811] finished fire_event for event_name OnCallStateDisconnect
2016-01-26 18:18:04,094 [DEBUG] [doorpi.action.handler] no actions for event OnRecorderStopped - skip fire_event OnRecorderStopped from doorpi.sipphone.linphone_lib.Recorder
2016-01-26 18:18:04,070 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_get_remote_address(0x17f6060 [0x1756588])
2016-01-26 18:18:04,127 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0x17cd4f0)
2016-01-26 18:18:04,103 [INFO] [doorpi.sipphone.from_linphone] FILTER USAGE STATISTICS
2016-01-26 18:18:04,143 [INFO] [doorpi.sipphone.from_linphone] Name Count Time/tick (ms) CPU Usage
2016-01-26 18:18:04,147 [INFO] [doorpi.sipphone.from_linphone] -----------------------------------------------------------
2016-01-26 18:18:04,161 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new()
2016-01-26 18:18:04,164 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0xb6d46ff0
2016-01-26 18:18:04,167 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init()
2016-01-26 18:18:04,150 [INFO] [doorpi.sipphone.from_linphone] MSRtpRecv 276 0.070161 100
2016-01-26 18:18:04,183 [INFO] [doorpi.sipphone.from_linphone] MSVoidSink 0 0 0
2016-01-26 18:18:04,187 [INFO] [doorpi.sipphone.from_linphone] MSItcSink 0 0 0
2016-01-26 18:18:04,181 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0xb6d46ff0
2016-01-26 18:18:04,204 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0xb6d46ff0
2016-01-26 18:18:04,207 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_get_remote_address -> 0xb6d46ff0
2016-01-26 18:18:04,220 [INFO] [doorpi.sipphone.from_linphone] MSTee 0 0 0
2016-01-26 18:18:04,225 [INFO] [doorpi.sipphone.from_linphone] MSSpeexEC 0 0 0
2016-01-26 18:18:04,228 [INFO] [doorpi.sipphone.from_linphone] MSRtpSend 0 0 0
2016-01-26 18:18:04,241 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_instance_method_as_string_uri_only(0xb6d46ff0 [0x17cd4f0])
2016-01-26 18:18:04,245 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_instance_method_as_string_uri_only -> 0x17f7680
2016-01-26 18:18:04,248 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron
2016-01-26 18:18:04,243 [INFO] [doorpi.sipphone.from_linphone] ===========================================================
2016-01-26 18:18:04,265 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1706250] notifying [display_status]
2016-01-26 18:18:04,268 [INFO] [doorpi.sipphone.from_linphone] Call 0x1756588: moving from state LinphoneCallOutgoingProgress to LinphoneCallEnd
2016-01-26 18:18:04,284 [INFO] [doorpi.sipphone.from_linphone] MSAudio MSTicker thread exiting
2016-01-26 18:18:04,287 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1706250] notifying [call_log_updated]
2016-01-26 18:18:04,302 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks
2016-01-26 18:18:04,307 [TRACE] [doorpi.action.handler] fire Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks asyncron
2016-01-26 18:18:04,290 [INFO] [doorpi.sipphone.from_linphone] Resetting the current call
2016-01-26 18:18:04,324 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1706250] notifying [call_state_changed]
2016-01-26 18:18:04,327 [INFO] [doorpi.sipphone.from_linphone] nict_on_timer_E: sending retransmission
2016-01-26 18:18:04,343 [INFO] [doorpi.sipphone.from_linphone] channel [0x171b958]: message sent to [UDP://192.168.1.200:5060], size: [494] bytes
REGISTER sip:192.168.1.200 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.76:5060;branch=z9hG4bK.oDs28GpHZ;rport
From: "DoorPi" <sip:620@192.168.1.200>;tag=giaGZuGKJ
To: "DoorPi" <sip:620@192.168.1.200>
CSeq: 46 REGISTER
Call-ID: 81AUbtmKPR
Max-Forwards: 70
Supported: outbound
Accept: application/sdp, text/plain, application/vnd.gsma.rcs-ft-http+xml
Contact: <sip:620@192.168.1.76>;+sip.instance="<urn:uuid:8196ebb7-b296-4425-a41c-18711fb1d1e4>"
Expires: 3600
User-Agent: (belle-sip/1.4.1)
2016-01-26 18:18:04,347 [INFO] [doorpi.sipphone.from_linphone] channel [0x171b958]: message sent to [UDP://192.168.1.200:5060], size: [807] bytes
INVITE sip:**611@192.168.1.200 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.76:5060;branch=z9hG4bK.1yuot1ug0;rport
From: "DoorPi" <sip:620@192.168.1.200>;tag=dAYAakMbR
To: sip:**611@192.168.1.200
CSeq: 20 INVITE
Call-ID: IvvgwbaHUC
Max-Forwards: 70
Supported: outbound
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
Content-Type: application/sdp
Content-Length: 267
Contact: <sip:620@192.168.1.76>;+sip.instance="<urn:uuid:8196ebb7-b296-4425-a41c-18711fb1d1e4>"
User-Agent: (belle-sip/1.4.1)
v=0
o=620 1456 2233 IN IP4 192.168.1.76
s=Talk
c=IN IP4 192.168.1.76
t=0 0
a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
m=audio 7080 RTP/AVP 0 8 101
a=rtpmap:101 telephone-event/8000
m=video 9080 RTP/AVP 96
a=rtpmap:96 VP8/90000
2016-01-26 18:18:04,363 [DEBUG] [doorpi.action.handler] [88RXXE] fire for event OnCallStateDisconnect this actions [<doorpi.action.base.SingleAction instance at 0x15ef0d0>, <doorpi.action.base.SingleAction instance at 0x15ef3a0>]
2016-01-26 18:18:04,367 [TRACE] [doorpi.action.handler] [88RXXE] try to fire action <bound method LinphonePlayer.stop of <doorpi.sipphone.linphone_lib.Player.LinphonePlayer object at 0x15f4f90>> with args () and kwargs {}
2016-01-26 18:18:04,381 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x1611560 [0x1706250])
2016-01-26 18:18:04,385 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x12410bc
2016-01-26 18:18:04,388 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x1611560 [0x1706250])
2016-01-26 18:18:04,380 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {}
2016-01-26 18:18:04,406 [TRACE] [doorpi.action.handler] fire Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player asyncron
2016-01-26 18:18:04,421 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x12410bc
2016-01-26 18:18:04,424 [TRACE] [doorpi.action.handler] fire Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks asyncron
2016-01-26 18:18:04,433 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerStopped - skip fire_event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player
2016-01-26 18:18:04,453 [TRACE] [doorpi.action.handler] [88RXXE] try to fire action <bound method LinphoneRecorder.stop of <doorpi.sipphone.linphone_lib.Recorder.LinphoneRecorder object at 0x15f4f50>> with args () and kwargs {}
2016-01-26 18:18:04,457 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {}
2016-01-26 18:18:04,474 [DEBUG] [doorpi.action.handler] no actions for event OnMediaNotRequired - skip fire_event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks
2016-01-26 18:18:04,478 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x1611560 [0x1706250])
2016-01-26 18:18:04,492 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x12410bc
2016-01-26 18:18:04,495 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_call_state_changed
2016-01-26 18:18:04,498 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_terminate_all_calls -> 0x12410bc
2016-01-26 18:18:04,505 [TRACE] [doorpi.action.handler] [88RXXE] finished fire_event for event_name OnCallStateDisconnect
2016-01-26 18:18:04,504 [INFO] [doorpi.sipphone.from_linphone] Notifying soundcard that we don't need it anymore for calls.
2016-01-26 18:18:04,525 [TRACE] [doorpi.action.handler] fire Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone asyncron
2016-01-26 18:18:04,543 [DEBUG] [doorpi.action.handler] no actions for event AfterSipPhoneMakeCall - skip fire_event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone
2016-01-26 18:18:04,549 [TRACE] [doorpi.action.handler] [HCF50A] finished fire_event for event_name OnKeyDown_onboardpins.0
2016-01-26 18:18:05,661 [INFO] [doorpi.sipphone.from_linphone] channel [0x171b958]: message sent to [UDP://192.168.1.200:5060], size: [807] bytes
INVITE sip:**611@192.168.1.200 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.76:5060;branch=z9hG4bK.sqwp2f~Wu;rport
From: "DoorPi" <sip:620@192.168.1.200>;tag=JcBzTPRik
To: sip:**611@192.168.1.200
CSeq: 20 INVITE
Call-ID: WO6lbiJp2I
Max-Forwards: 70
Supported: outbound
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
Content-Type: application/sdp
Content-Length: 267
Contact: <sip:620@192.168.1.76>;+sip.instance="<urn:uuid:8196ebb7-b296-4425-a41c-18711fb1d1e4>"
User-Agent: (belle-sip/1.4.1)
v=0
o=620 2106 2142 IN IP4 192.168.1.76
s=Talk
c=IN IP4 192.168.1.76
t=0 0
a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
m=audio 7078 RTP/AVP 0 8 101
a=rtpmap:101 telephone-event/8000
m=video 9078 RTP/AVP 96
a=rtpmap:96 VP8/90000
2016-01-26 18:18:05,820 [INFO] [doorpi.sipphone.from_linphone] channel [0x171b958]: message sent to [UDP://192.168.1.200:5060], size: [807] bytes
INVITE sip:**611@192.168.1.200 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.76:5060;branch=z9hG4bK.1yuot1ug0;rport
From: "DoorPi" <sip:620@192.168.1.200>;tag=dAYAakMbR
To: sip:**611@192.168.1.200
CSeq: 20 INVITE
Call-ID: IvvgwbaHUC
Max-Forwards: 70
Supported: outbound
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
Content-Type: application/sdp
Content-Length: 267
Contact: <sip:620@192.168.1.76>;+sip.instance="<urn:uuid:8196ebb7-b296-4425-a41c-18711fb1d1e4>"
User-Agent: (belle-sip/1.4.1)
v=0
o=620 1456 2233 IN IP4 192.168.1.76
s=Talk
c=IN IP4 192.168.1.76
t=0 0
a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
m=audio 7080 RTP/AVP 0 8 101
a=rtpmap:101 telephone-event/8000
m=video 9080 RTP/AVP 96
a=rtpmap:96 VP8/90000
2016-01-26 18:18:08,094 [INFO] [doorpi.sipphone.from_linphone] nict_on_timer_E: sending retransmission
2016-01-26 18:18:08,098 [INFO] [doorpi.sipphone.from_linphone] channel [0x171b958]: message sent to [UDP://192.168.1.200:5060], size: [494] bytes
REGISTER sip:192.168.1.200 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.76:5060;branch=z9hG4bK.oDs28GpHZ;rport
From: "DoorPi" <sip:620@192.168.1.200>;tag=giaGZuGKJ
To: "DoorPi" <sip:620@192.168.1.200>
CSeq: 46 REGISTER
Call-ID: 81AUbtmKPR
Max-Forwards: 70
Supported: outbound
Accept: application/sdp, text/plain, application/vnd.gsma.rcs-ft-http+xml
Contact: <sip:620@192.168.1.76>;+sip.instance="<urn:uuid:8196ebb7-b296-4425-a41c-18711fb1d1e4>"
Expires: 3600
User-Agent: (belle-sip/1.4.1)
2016-01-26 18:18:09,662 [INFO] [doorpi.sipphone.from_linphone] channel [0x171b958]: message sent to [UDP://192.168.1.200:5060], size: [807] bytes
INVITE sip:**611@192.168.1.200 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.76:5060;branch=z9hG4bK.sqwp2f~Wu;rport
From: "DoorPi" <sip:620@192.168.1.200>;tag=JcBzTPRik
To: sip:**611@192.168.1.200
CSeq: 20 INVITE
Call-ID: WO6lbiJp2I
Max-Forwards: 70
Supported: outbound
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
Content-Type: application/sdp
Content-Length: 267
Contact: <sip:620@192.168.1.76>;+sip.instance="<urn:uuid:8196ebb7-b296-4425-a41c-18711fb1d1e4>"
User-Agent: (belle-sip/1.4.1)
v=0
o=620 2106 2142 IN IP4 192.168.1.76
s=Talk
c=IN IP4 192.168.1.76
t=0 0
a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
m=audio 7078 RTP/AVP 0 8 101
a=rtpmap:101 telephone-event/8000
m=video 9078 RTP/AVP 96
a=rtpmap:96 VP8/90000
2016-01-26 18:18:09,821 [INFO] [doorpi.sipphone.from_linphone] channel [0x171b958]: message sent to [UDP://192.168.1.200:5060], size: [807] bytes
INVITE sip:**611@192.168.1.200 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.76:5060;branch=z9hG4bK.1yuot1ug0;rport
From: "DoorPi" <sip:620@192.168.1.200>;tag=dAYAakMbR
To: sip:**611@192.168.1.200
CSeq: 20 INVITE
Call-ID: IvvgwbaHUC
Max-Forwards: 70
Supported: outbound
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
Content-Type: application/sdp
Content-Length: 267
Contact: <sip:620@192.168.1.76>;+sip.instance="<urn:uuid:8196ebb7-b296-4425-a41c-18711fb1d1e4>"
User-Agent: (belle-sip/1.4.1)
v=0
o=620 1456 2233 IN IP4 192.168.1.76
s=Talk
c=IN IP4 192.168.1.76
t=0 0
a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
m=audio 7080 RTP/AVP 0 8 101
a=rtpmap:101 telephone-event/8000
m=video 9080 RTP/AVP 96
a=rtpmap:96 VP8/90000
2016-01-26 18:18:12,095 [INFO] [doorpi.sipphone.from_linphone] nict_on_timer_E: sending retransmission
2016-01-26 18:18:12,099 [INFO] [doorpi.sipphone.from_linphone] channel [0x171b958]: message sent to [UDP://192.168.1.200:5060], size: [494] bytes
REGISTER sip:192.168.1.200 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.76:5060;branch=z9hG4bK.oDs28GpHZ;rport
From: "DoorPi" <sip:620@192.168.1.200>;tag=giaGZuGKJ
To: "DoorPi" <sip:620@192.168.1.200>
CSeq: 46 REGISTER
Call-ID: 81AUbtmKPR
Max-Forwards: 70
Supported: outbound
Accept: application/sdp, text/plain, application/vnd.gsma.rcs-ft-http+xml
Contact: <sip:620@192.168.1.76>;+sip.instance="<urn:uuid:8196ebb7-b296-4425-a41c-18711fb1d1e4>"
Expires: 3600
User-Agent: (belle-sip/1.4.1)
2016-01-26 18:18:16,118 [INFO] [doorpi.sipphone.from_linphone] nict_on_timer_E: sending retransmission
2016-01-26 18:18:16,123 [INFO] [doorpi.sipphone.from_linphone] channel [0x171b958]: message sent to [UDP://192.168.1.200:5060], size: [494] bytes
REGISTER sip:192.168.1.200 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.76:5060;branch=z9hG4bK.oDs28GpHZ;rport
From: "DoorPi" <sip:620@192.168.1.200>;tag=giaGZuGKJ
To: "DoorPi" <sip:620@192.168.1.200>
CSeq: 46 REGISTER
Call-ID: 81AUbtmKPR
Max-Forwards: 70
Supported: outbound
Accept: application/sdp, text/plain, application/vnd.gsma.rcs-ft-http+xml
Contact: <sip:620@192.168.1.76>;+sip.instance="<urn:uuid:8196ebb7-b296-4425-a41c-18711fb1d1e4>"
Expires: 3600
User-Agent: (belle-sip/1.4.1)
2016-01-26 18:18:17,641 [INFO] [doorpi.sipphone.from_linphone] channel [0x171b958]: message sent to [UDP://192.168.1.200:5060], size: [807] bytes
INVITE sip:**611@192.168.1.200 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.76:5060;branch=z9hG4bK.sqwp2f~Wu;rport
From: "DoorPi" <sip:620@192.168.1.200>;tag=JcBzTPRik
To: sip:**611@192.168.1.200
CSeq: 20 INVITE
Call-ID: WO6lbiJp2I
Max-Forwards: 70
Supported: outbound
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
Content-Type: application/sdp
Content-Length: 267
Contact: <sip:620@192.168.1.76>;+sip.instance="<urn:uuid:8196ebb7-b296-4425-a41c-18711fb1d1e4>"
User-Agent: (belle-sip/1.4.1)
v=0
o=620 2106 2142 IN IP4 192.168.1.76
s=Talk
c=IN IP4 192.168.1.76
t=0 0
a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
m=audio 7078 RTP/AVP 0 8 101
a=rtpmap:101 telephone-event/8000
m=video 9078 RTP/AVP 96
a=rtpmap:96 VP8/90000
2016-01-26 18:18:17,851 [INFO] [doorpi.sipphone.from_linphone] channel [0x171b958]: message sent to [UDP://192.168.1.200:5060], size: [807] bytes
INVITE sip:**611@192.168.1.200 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.76:5060;branch=z9hG4bK.1yuot1ug0;rport
From: "DoorPi" <sip:620@192.168.1.200>;tag=dAYAakMbR
To: sip:**611@192.168.1.200
CSeq: 20 INVITE
Call-ID: IvvgwbaHUC
Max-Forwards: 70
Supported: outbound
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
Content-Type: application/sdp
Content-Length: 267
Contact: <sip:620@192.168.1.76>;+sip.instance="<urn:uuid:8196ebb7-b296-4425-a41c-18711fb1d1e4>"
User-Agent: (belle-sip/1.4.1)
v=0
o=620 1456 2233 IN IP4 192.168.1.76
s=Talk
c=IN IP4 192.168.1.76
t=0 0
a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
m=audio 7080 RTP/AVP 0 8 101
a=rtpmap:101 telephone-event/8000
m=video 9080 RTP/AVP 96
a=rtpmap:96 VP8/90000
2016-01-26 18:18:20,074 [INFO] [doorpi.sipphone.from_linphone] nict_on_timer_E: sending retransmission
2016-01-26 18:18:20,078 [INFO] [doorpi.sipphone.from_linphone] channel [0x171b958]: message sent to [UDP://192.168.1.200:5060], size: [494] bytes
REGISTER sip:192.168.1.200 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.76:5060;branch=z9hG4bK.oDs28GpHZ;rport
From: "DoorPi" <sip:620@192.168.1.200>;tag=giaGZuGKJ
To: "DoorPi" <sip:620@192.168.1.200>
CSeq: 46 REGISTER
Call-ID: 81AUbtmKPR
Max-Forwards: 70
Supported: outbound
Accept: application/sdp, text/plain, application/vnd.gsma.rcs-ft-http+xml
Contact: <sip:620@192.168.1.76>;+sip.instance="<urn:uuid:8196ebb7-b296-4425-a41c-18711fb1d1e4>"
Expires: 3600
User-Agent: (belle-sip/1.4.1)
2016-01-26 18:18:20,608 [INFO] [doorpi.sipphone.from_linphone] A timeout related to this channel occured and no message received during last 60 seconds. This channel is suspect, moving to error state
2016-01-26 18:18:20,612 [INFO] [doorpi.sipphone.from_linphone] channel 0x171b958: state ERROR
2016-01-26 18:18:20,615 [WARNING] [doorpi.sipphone.from_linphone] Transaction [0x17655a8] reporting timeout, reporting to channel.
2016-01-26 18:18:20,670 [INFO] [doorpi.sipphone.from_linphone] transaction [0x17655a8] channel state changed to [ERROR]
2016-01-26 18:18:20,674 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 60000 ms
2016-01-26 18:18:20,677 [INFO] [doorpi.sipphone.from_linphone] Register refresher [503] reason [io error] for proxy [sip:192.168.1.200]
2016-01-26 18:18:20,680 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1706250] notifying [display_status]
2016-01-26 18:18:20,693 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 2000 ms
2016-01-26 18:18:20,697 [INFO] [doorpi.sipphone.from_linphone] Register refresher [408] reason [timeout] for proxy [sip:192.168.1.200]
2016-01-26 18:18:20,700 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1706250] notifying [display_status]
2016-01-26 18:18:20,714 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x17655a8], from state [TRYING] to [TERMINATED]
2016-01-26 18:18:20,717 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x17655a8] terminated
2016-01-26 18:18:20,731 [INFO] [doorpi.sipphone.from_linphone] transaction [0x17cb9f0] channel state changed to [ERROR]
2016-01-26 18:18:20,734 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1706250] notifying [show]
2016-01-26 18:18:20,737 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1706250] notifying [display_status]
2016-01-26 18:18:20,751 [INFO] [doorpi.sipphone.from_linphone] Call 0x17e4618: moving from state LinphoneCallEnd to LinphoneCallReleased
2016-01-26 18:18:20,754 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1706250] notifying [call_state_changed]
2016-01-26 18:18:20,757 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_call_state_changed(0x1706250, 0x17e4618, 18, "Call released")
2016-01-26 18:18:20,771 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (18 - Call released)
2016-01-26 18:18:20,774 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_get_remote_address(0xb6d46e40 [0x17e4618])
2016-01-26 18:18:20,778 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0x1405690)
2016-01-26 18:18:20,791 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new()
2016-01-26 18:18:20,794 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0xb6d46f90
2016-01-26 18:18:20,798 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init()
2016-01-26 18:18:20,811 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0xb6d46f90
2016-01-26 18:18:20,814 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0xb6d46f90
2016-01-26 18:18:20,817 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_get_remote_address -> 0xb6d46f90
2016-01-26 18:18:20,831 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_instance_method_as_string_uri_only(0xb6d46f90 [0x1405690])
2016-01-26 18:18:20,834 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_instance_method_as_string_uri_only -> 0x178e800
2016-01-26 18:18:20,837 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron
2016-01-26 18:18:20,853 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x1611560 [0x1706250])
2016-01-26 18:18:20,856 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x12410bc
2016-01-26 18:18:20,859 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x1611560 [0x1706250])
2016-01-26 18:18:20,873 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks
2016-01-26 18:18:20,893 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x12410bc
2016-01-26 18:18:20,896 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x1611560 [0x1706250])
2016-01-26 18:18:20,900 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x12410bc
2016-01-26 18:18:20,914 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_call_state_changed
2016-01-26 18:18:20,917 [INFO] [doorpi.sipphone.from_linphone] Notifying soundcard that we don't need it anymore for calls.
2016-01-26 18:18:20,930 [INFO] [doorpi.sipphone.from_linphone] op [0x1763378] : set_or_update_dialog() current=[(nil)] new=[(nil)]
2016-01-26 18:18:20,934 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [INVITE] transaction [0x17cb9f0], from state [CALLING] to [TERMINATED]
2016-01-26 18:18:20,937 [INFO] [doorpi.sipphone.from_linphone] Client INVITE transaction [0x17cb9f0] terminated
2016-01-26 18:18:20,948 [INFO] [doorpi.sipphone.from_linphone] Destroying op [0x1763378] of type [SalOpCall]
2016-01-26 18:18:20,952 [INFO] [doorpi.sipphone.from_linphone] transaction [0x17d84b0] channel state changed to [ERROR]
2016-01-26 18:18:20,955 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1706250] notifying [show]
2016-01-26 18:18:20,958 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1706250] notifying [display_status]
2016-01-26 18:18:20,982 [INFO] [doorpi.sipphone.from_linphone] Call 0x1756588: moving from state LinphoneCallEnd to LinphoneCallReleased
2016-01-26 18:18:20,985 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1706250] notifying [call_state_changed]
2016-01-26 18:18:20,988 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_call_state_changed(0x1706250, 0x1756588, 18, "Call released")
2016-01-26 18:18:21,002 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (18 - Call released)
2016-01-26 18:18:21,006 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_get_remote_address(0x17f6060 [0x1756588])
2016-01-26 18:18:21,009 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0x17cd4f0)
2016-01-26 18:18:21,023 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new()
2016-01-26 18:18:21,026 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0xb6d46f70
2016-01-26 18:18:21,029 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init()
2016-01-26 18:18:21,043 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0xb6d46f70
2016-01-26 18:18:21,046 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0xb6d46f70
2016-01-26 18:18:21,049 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_get_remote_address -> 0xb6d46f70
2016-01-26 18:18:21,063 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_instance_method_as_string_uri_only(0xb6d46f70 [0x17cd4f0])
2016-01-26 18:18:21,066 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_instance_method_as_string_uri_only -> 0x178e800
2016-01-26 18:18:21,070 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron
2016-01-26 18:18:21,085 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x1611560 [0x1706250])
2016-01-26 18:18:21,089 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x12410bc
2016-01-26 18:18:21,106 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x1611560 [0x1706250])
2016-01-26 18:18:21,103 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks
2016-01-26 18:18:21,109 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x12410bc
2016-01-26 18:18:21,125 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0x1611560 [0x1706250])
2016-01-26 18:18:21,128 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x12410bc
2016-01-26 18:18:21,142 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_call_state_changed
2016-01-26 18:18:21,145 [INFO] [doorpi.sipphone.from_linphone] Notifying soundcard that we don't need it anymore for calls.
2016-01-26 18:18:21,149 [INFO] [doorpi.sipphone.from_linphone] op [0x17e7920] : set_or_update_dialog() current=[(nil)] new=[(nil)]
2016-01-26 18:18:21,162 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [INVITE] transaction [0x17d84b0], from state [CALLING] to [TERMINATED]
2016-01-26 18:18:21,165 [INFO] [doorpi.sipphone.from_linphone] Client INVITE transaction [0x17d84b0] terminated
2016-01-26 18:18:21,168 [INFO] [doorpi.sipphone.from_linphone] Destroying op [0x17e7920] of type [SalOpCall]
2016-01-26 18:18:22,739 [INFO] [doorpi.sipphone.from_linphone] Channel [0x171b958] destroyed
2016-01-26 18:18:22,744 [INFO] [doorpi.sipphone.from_linphone] belle_sip_client_transaction_send_request(): waiting channel to be ready
2016-01-26 18:18:22,747 [INFO] [doorpi.sipphone.from_linphone] channel 0x171b958: state RES_IN_PROGRESS
2016-01-26 18:18:22,750 [INFO] [doorpi.sipphone.from_linphone] transaction [0x17cbda8] channel state changed to [RES_IN_PROGRESS]
2016-01-26 18:18:22,774 [INFO] [doorpi.sipphone.from_linphone] channel 0x171b958: state RES_DONE
2016-01-26 18:18:22,777 [INFO] [doorpi.sipphone.from_linphone] transaction [0x17cbda8] channel state changed to [RES_DONE]
2016-01-26 18:18:22,791 [INFO] [doorpi.sipphone.from_linphone] channel 0x171b958: state CONNECTING
2016-01-26 18:18:22,794 [INFO] [doorpi.sipphone.from_linphone] transaction [0x17cbda8] channel state changed to [CONNECTING]
2016-01-26 18:18:22,798 [INFO] [doorpi.sipphone.from_linphone] Trying to connect to [UDP://192.168.1.200:5060]
2016-01-26 18:18:22,811 [INFO] [doorpi.sipphone.from_linphone] belle_sip_get_src_addr_for(): af_inet6=0
2016-01-26 18:18:22,814 [INFO] [doorpi.sipphone.from_linphone] Channel has local address 192.168.1.76:5060
2016-01-26 18:18:22,818 [INFO] [doorpi.sipphone.from_linphone] channel 0x171b958: state READY
2016-01-26 18:18:22,831 [INFO] [doorpi.sipphone.from_linphone] transaction [0x17cbda8] channel state changed to [READY]
2016-01-26 18:18:22,835 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x17cbda8], from state [INIT] to [TRYING]
2016-01-26 18:18:22,839 [INFO] [doorpi.sipphone.from_linphone] channel [0x171b958]: message sent to [UDP://192.168.1.200:5060], size: [494] bytes
REGISTER sip:192.168.1.200 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.76:5060;branch=z9hG4bK.U0NJ~4pRu;rport
From: "DoorPi" <sip:620@192.168.1.200>;tag=giaGZuGKJ
To: "DoorPi" <sip:620@192.168.1.200>
CSeq: 47 REGISTER
Call-ID: 81AUbtmKPR
Max-Forwards: 70
Supported: outbound
Accept: application/sdp, text/plain, application/vnd.gsma.rcs-ft-http+xml
Contact: <sip:620@192.168.1.76>;+sip.instance="<urn:uuid:8196ebb7-b296-4425-a41c-18711fb1d1e4>"
Expires: 3600
User-Agent: (belle-sip/1.4.1)
2016-01-26 18:18:23,369 [INFO] [doorpi.sipphone.from_linphone] nict_on_timer_E: sending retransmission
2016-01-26 18:18:23,374 [INFO] [doorpi.sipphone.from_linphone] channel [0x171b958]: message sent to [UDP://192.168.1.200:5060], size: [494] bytes
REGISTER sip:192.168.1.200 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.76:5060;branch=z9hG4bK.U0NJ~4pRu;rport
From: "DoorPi" <sip:620@192.168.1.200>;tag=giaGZuGKJ
To: "DoorPi" <sip:620@192.168.1.200>
CSeq: 47 REGISTER
Call-ID: 81AUbtmKPR
Max-Forwards: 70
Supported: outbound
Accept: application/sdp, text/plain, application/vnd.gsma.rcs-ft-http+xml
Contact: <sip:620@192.168.1.76>;+sip.instance="<urn:uuid:8196ebb7-b296-4425-a41c-18711fb1d1e4>"
Expires: 3600
User-Agent: (belle-sip/1.4.1)
2016-01-26 18:18:24,355 [INFO] [doorpi.sipphone.from_linphone] nict_on_timer_E: sending retransmission
2016-01-26 18:18:24,359 [INFO] [doorpi.sipphone.from_linphone] channel [0x171b958]: message sent to [UDP://192.168.1.200:5060], size: [494] bytes
REGISTER sip:192.168.1.200 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.76:5060;branch=z9hG4bK.U0NJ~4pRu;rport
From: "DoorPi" <sip:620@192.168.1.200>;tag=giaGZuGKJ
To: "DoorPi" <sip:620@192.168.1.200>
CSeq: 47 REGISTER
Call-ID: 81AUbtmKPR
Max-Forwards: 70
Supported: outbound
Accept: application/sdp, text/plain, application/vnd.gsma.rcs-ft-http+xml
Contact: <sip:620@192.168.1.76>;+sip.instance="<urn:uuid:8196ebb7-b296-4425-a41c-18711fb1d1e4>"
Expires: 3600
User-Agent: (belle-sip/1.4.1)
2016-01-26 18:18:26,345 [INFO] [doorpi.sipphone.from_linphone] nict_on_timer_E: sending retransmission
2016-01-26 18:18:26,349 [INFO] [doorpi.sipphone.from_linphone] channel [0x171b958]: message sent to [UDP://192.168.1.200:5060], size: [494] bytes
REGISTER sip:192.168.1.200 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.76:5060;branch=z9hG4bK.U0NJ~4pRu;rport
From: "DoorPi" <sip:620@192.168.1.200>;tag=giaGZuGKJ
To: "DoorPi" <sip:620@192.168.1.200>
CSeq: 47 REGISTER
Call-ID: 81AUbtmKPR
Max-Forwards: 70
Supported: outbound
Accept: application/sdp, text/plain, application/vnd.gsma.rcs-ft-http+xml
Contact: <sip:620@192.168.1.76>;+sip.instance="<urn:uuid:8196ebb7-b296-4425-a41c-18711fb1d1e4>"
Expires: 3600
User-Agent: (belle-sip/1.4.1)
2016-01-26 18:18:30,365 [INFO] [doorpi.sipphone.from_linphone] nict_on_timer_E: sending retransmission
2016-01-26 18:18:30,369 [INFO] [doorpi.sipphone.from_linphone] channel [0x171b958]: message sent to [UDP://192.168.1.200:5060], size: [494] bytes
REGISTER sip:192.168.1.200 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.76:5060;branch=z9hG4bK.U0NJ~4pRu;rport
From: "DoorPi" <sip:620@192.168.1.200>;tag=giaGZuGKJ
To: "DoorPi" <sip:620@192.168.1.200>
CSeq: 47 REGISTER
Call-ID: 81AUbtmKPR
Max-Forwards: 70
Supported: outbound
Accept: application/sdp, text/plain, application/vnd.gsma.rcs-ft-http+xml
Contact: <sip:620@192.168.1.76>;+sip.instance="<urn:uuid:8196ebb7-b296-4425-a41c-18711fb1d1e4>"
Expires: 3600
User-Agent: (belle-sip/1.4.1)
Display More