2020-02-13 10:34:40.037 WARNING sip_coder.c( 556) : Decode SIP failed in get_next_line_impl2
2020-02-13 10:34:40.037 WARNING sip_coder.c( 558) : Position around 0 in line:
2020-02-13 10:34:43.702 APP_INFO iwu.c(3193) : iwu_call_create(mps_call=0x00000000, sip_call=0x032FDAA0)
2020-02-13 10:34:43.703 APP_INFO iwu.c( 738) : get_codec_audio_mask(call=8389) configured codec mask = ulaw|alaw|g729|g726_32|dtmf|g722
2020-02-13 10:34:43.703 APP_INFO iwu.c(3265) : iwu_call_create(user=20) returns call=8389
2020-02-13 10:34:43.703 APP_INFO iwu.c(2796) : iwu_cb_sip_call_license_alloc(call=8389)
2020-02-13 10:34:43.753 APP_INFO sip_handler.c(1283) : sip_call_state(call=8389, state=initiate)
2020-02-13 10:34:43.753 APP_INFO iwu.c(2821) : iwu_cb_sip_call_state(call=8389): initiate
2020-02-13 10:34:43.753 APP_INFO iwu.c(1521) : handle_sip_call_initiate(call=8389): 20 -> 01714158875
2020-02-13 10:34:43.753 APP_INFO media_handler.c(2461) : media_call_initiate(call=8389)
2020-02-13 10:34:43.754 APP_INFO media_handler.c(1922) : audio_session_prepare(call=8389): local codecs = alaw,ulaw,g722,g729,g726_32,dtmf
2020-02-13 10:34:43.754 APP_INFO media_handler.c(1924) : audio_session_prepare(call=8389): remote codecs = alaw,dtmf
2020-02-13 10:34:43.754 APP_INFO media_handler.c(1938) : audio_session_prepare(call=8389): intersect codecs = alaw,dtmf
2020-02-13 10:34:43.754 APP_INFO media_handler.c(1940) : audio_session_prepare(call=8389): order codecs = default
2020-02-13 10:34:43.754 APP_INFO media_handler.c(1005) : dsp_session_open_udp(call=8389, local_port=10784, remote=192.168.66.2:1,39000000): fd=36
2020-02-13 10:34:43.754 APP_INFO nat_handler.c( 542) : nat_session_open(call=8389, local_port=10784, remote=192.168.66.2,39000000): use_nat=0
2020-02-13 10:34:43.755 APP_INFO media_handler.c(1099) : dsp_session_open_dsp(call=8389): fd=37
2020-02-13 10:34:43.755 APP_INFO media_handler.c(1154) : dsp_session_open_link(call=8389): link=709
2020-02-13 10:34:43.755 APP_INFO media_handler.c( 679) : dsp_session_msg_send(call=8389, type=mux)
2020-02-13 10:34:43.755 APP_INFO media_handler.c(1005) : dsp_session_open_udp(call=18389, local_port=10785, remote=192.168.66.2:1,39000000): fd=38
2020-02-13 10:34:43.755 APP_INFO nat_handler.c( 542) : nat_session_open(call=18389, local_port=10785, remote=192.168.66.2,39000000): use_nat=0
2020-02-13 10:34:43.756 APP_INFO media_handler.c(1099) : dsp_session_open_dsp(call=18389): fd=39
2020-02-13 10:34:43.756 APP_INFO media_handler.c(1154) : dsp_session_open_link(call=18389): link=615
2020-02-13 10:34:43.756 APP_INFO media_handler.c( 679) : dsp_session_msg_send(call=18389, type=mux)
2020-02-13 10:34:43.756 APP_INFO media_handler.c( 679) : dsp_session_msg_send(call=8389, type=net[local_port=10784, remote=192.168.66.2:5006,39000000])
2020-02-13 10:34:43.756 APP_INFO media_handler.c( 679) : dsp_session_msg_send(call=18389, type=net[local_port=10785, remote=192.168.66.2:5007,39000000])
2020-02-13 10:34:43.756 APP_INFO media_handler.c(2026) : media_call_state(call=8389, new_state=initiate, old_state=idle)
2020-02-13 10:34:43.756 APP_INFO iwu.c(2972) : iwu_cb_media_call_state(call=8389): initiate
2020-02-13 10:34:43.756 APP_INFO mps_handler.c(2924) : mps_call_initiate(call=8389, number=01714158875)
2020-02-13 10:34:43.756 APP_INFO mps_handler.c( 955) : msg_send(call=8389, msg=CC_CALL_EST_REQ) IWU_LINE->MPS for device=line
2020-02-13 10:34:43.758 APP_INFO mps_handler.c( 433) : mps_call_state(call=8389, new_state=initiate, old_state=idle)
2020-02-13 10:34:43.758 APP_INFO iwu.c(2510) : iwu_cb_mps_call_state(call=8389): initiate
2020-02-13 10:34:43.758 APP_INFO mib_handler.c(1473) : trap_handler_call_transaction(ev=0xFFFFFFFF)
2020-02-13 10:34:43.758 APP_INFO iwu.c(2475) : iwu_cb_mib_call_state(call_id=8389, number=20, number_peer=, direction_is_initiator=1, state=active)
2020-02-13 10:34:43.760 APP_INFO mps_handler.c(2275) : msg_recv(call=8389, msg=CC_CHANNEL_INFO) MPS->IWU_LINE for device=line
2020-02-13 10:34:43.760 APP_INFO mps_handler.c( 578) : call_update_channel(call=8389, old_num=0, new_num=81)
2020-02-13 10:34:43.768 APP_INFO mps_handler.c(2275) : msg_recv(call=8389, msg=LOG_ID_REQUEST) MPS->IWU_TRUNK for device=trunk
2020-02-13 10:34:43.768 APP_INFO mps_handler.c( 951) : msg_send(call=8389, msg=LOG_ID_ALLOCATE) => 9206, IWU_TRUNK->MPS for device=trunk
2020-02-13 10:34:43.776 APP_INFO mps_handler.c(2275) : msg_recv(call=9206, msg=GW_CONNECT) MPS->IWU_TRUNK for device=trunk
2020-02-13 10:34:43.776 APP_INFO mps_handler.c( 578) : call_update_channel(call=9206, old_num=0, new_num=82)
2020-02-13 10:34:43.776 APP_INFO mps_handler.c( 556) : call_set_dst_log_id(call=9206, dst_call=8389) peer reference established
2020-02-13 10:34:43.780 APP_INFO mps_handler.c(2275) : msg_recv(call=9206, msg=CC_CALL_EST_REQ) MPS->IWU_TRUNK for device=trunk
2020-02-13 10:34:43.780 APP_INFO iwu.c(3193) : iwu_call_create(mps_call=0x033A1C80, sip_call=0x00000000)
2020-02-13 10:34:43.780 APP_INFO iwu.c(2796) : iwu_cb_sip_call_license_alloc(call=9206)
2020-02-13 10:34:43.780 APP_INFO iwu.c( 738) : get_codec_audio_mask(call=9206) configured codec mask = alaw|dtmf
2020-02-13 10:34:43.781 APP_INFO stun_handler.c( 301) : stun_session_create(call=9206) no STUN used
2020-02-13 10:34:43.781 APP_INFO iwu.c(3265) : iwu_call_create(user=[EMAIL][email protected][/EMAIL]) returns call=9206
2020-02-13 10:34:43.781 APP_INFO mps_handler.c( 955) : msg_send(call=9206, msg=CC_CHANNEL_INFO) IWU_TRUNK->MPS for device=trunk
2020-02-13 10:34:43.781 APP_INFO mps_handler.c( 433) : mps_call_state(call=9206, new_state=initiate, old_state=idle)
2020-02-13 10:34:43.781 APP_INFO iwu.c(2510) : iwu_cb_mps_call_state(call=9206): initiate
2020-02-13 10:34:43.781 APP_INFO iwu.c(1155) : handle_mps_call_initiate(call=9206): 0049831522000 -> 01714158875
2020-02-13 10:34:43.781 APP_INFO iwu.c( 399) : user_patch_number_mps2sip(number=0049831522000) -> +49831522000
2020-02-13 10:34:43.781 APP_INFO iwu.c( 399) : user_patch_number_mps2sip(number=01714158875) -> +491714158875
2020-02-13 10:34:43.781 APP_INFO iwu.c( 980) : call_get_intersected_codec_order(call=9206): own=best quality, peer=default: result=best quality
2020-02-13 10:34:43.781 APP_INFO iwu.c( 738) : get_codec_audio_mask(call=9206) configured codec mask = alaw|dtmf
2020-02-13 10:34:43.782 APP_INFO media_handler.c(1825) : audio_session_set_peer_codecs(call=9206): set remote from peer (call=8389) intersect codecs
2020-02-13 10:34:43.782 APP_INFO media_handler.c(2461) : media_call_initiate(call=9206)
2020-02-13 10:34:43.782 APP_INFO media_handler.c(1922) : audio_session_prepare(call=9206): local codecs = alaw,dtmf
2020-02-13 10:34:43.782 APP_INFO media_handler.c(1924) : audio_session_prepare(call=9206): remote codecs = alaw,dtmf
2020-02-13 10:34:43.782 APP_INFO media_handler.c(1938) : audio_session_prepare(call=9206): intersect codecs = alaw,dtmf (copied)
2020-02-13 10:34:43.782 APP_INFO media_handler.c(1940) : audio_session_prepare(call=9206): order codecs = best quality
2020-02-13 10:34:43.782 APP_INFO media_handler.c(1005) : dsp_session_open_udp(call=9206, local_port=14052, remote=217.0.26.69:0): fd=40
2020-02-13 10:34:43.783 APP_INFO nat_handler.c( 542) : nat_session_open(call=9206, local_port=14052, remote=217.0.26.69): use_nat=1
2020-02-13 10:34:43.783 APP_INFO media_handler.c(1099) : dsp_session_open_dsp(call=9206): fd=41
2020-02-13 10:34:43.783 APP_INFO media_handler.c(1154) : dsp_session_open_link(call=9206): link=759
2020-02-13 10:34:43.783 APP_INFO media_handler.c( 679) : dsp_session_msg_send(call=9206, type=mux)
2020-02-13 10:34:43.783 APP_INFO media_handler.c(1005) : dsp_session_open_udp(call=19206, local_port=14053, remote=217.0.26.69:1): fd=42
2020-02-13 10:34:43.784 APP_INFO nat_handler.c( 542) : nat_session_open(call=19206, local_port=14053, remote=217.0.26.69): use_nat=1
2020-02-13 10:34:43.784 APP_INFO media_handler.c(1099) : dsp_session_open_dsp(call=19206): fd=43
2020-02-13 10:34:43.784 APP_INFO media_handler.c(1154) : dsp_session_open_link(call=19206): link=713
2020-02-13 10:34:43.785 APP_INFO media_handler.c( 679) : dsp_session_msg_send(call=19206, type=mux)
2020-02-13 10:34:43.785 APP_INFO media_handler.c( 679) : dsp_session_msg_send(call=9206, type=net[local_port=14052, remote=217.0.26.69:0])
2020-02-13 10:34:43.785 APP_INFO media_handler.c( 679) : dsp_session_msg_send(call=19206, type=net[local_port=14053, remote=217.0.26.69:0])
2020-02-13 10:34:43.785 APP_INFO media_handler.c(2026) : media_call_state(call=9206, new_state=initiate, old_state=idle)
2020-02-13 10:34:43.785 APP_INFO iwu.c(2972) : iwu_cb_media_call_state(call=9206): initiate
2020-02-13 10:34:43.785 APP_INFO sip_handler.c(2917) : sip_call_initiate(call=9206, number=+491714158875)
2020-02-13 10:34:43.787 APP_INFO sip_handler.c(1283) : sip_call_state(call=9206, state=initiate)
2020-02-13 10:34:43.787 APP_INFO iwu.c(2821) : iwu_cb_sip_call_state(call=9206): initiate
2020-02-13 10:34:45.346 APP_INFO sip_handler.c(1283) : sip_call_state(call=9206, state=progress)
2020-02-13 10:34:45.346 APP_INFO iwu.c(2821) : iwu_cb_sip_call_state(call=9206): progress
2020-02-13 10:34:45.346 APP_INFO nat_handler.c( 571) : nat_session_close(call=9206)
2020-02-13 10:34:45.347 APP_INFO nat_handler.c( 542) : nat_session_open(call=9206, local_port=14052, remote=217.0.132.55): use_nat=1
2020-02-13 10:34:45.347 APP_INFO nat_handler.c( 571) : nat_session_close(call=19206)
2020-02-13 10:34:45.347 APP_INFO nat_handler.c( 542) : nat_session_open(call=19206, local_port=14053, remote=217.0.132.55): use_nat=1
2020-02-13 10:34:45.348 APP_INFO media_handler.c( 679) : dsp_session_msg_send(call=9206, type=net[local_port=14052, remote=217.0.132.55:25786])
2020-02-13 10:34:45.348 APP_INFO media_handler.c( 679) : dsp_session_msg_send(call=19206, type=net[local_port=14053, remote=217.0.132.55:25787])
2020-02-13 10:34:45.348 APP_INFO media_handler.c(2483) : media_call_active(call=9206)
2020-02-13 10:34:45.348 APP_INFO media_handler.c(1922) : audio_session_prepare(call=9206): local codecs = alaw,dtmf
2020-02-13 10:34:45.348 APP_INFO media_handler.c(1924) : audio_session_prepare(call=9206): remote codecs = alaw,dtmf
2020-02-13 10:34:45.348 APP_INFO media_handler.c(1938) : audio_session_prepare(call=9206): intersect codecs = alaw,dtmf
2020-02-13 10:34:45.348 APP_INFO media_handler.c(1940) : audio_session_prepare(call=9206): order codecs = best quality
2020-02-13 10:34:45.349 APP_INFO media_handler.c( 679) : dsp_session_msg_send(call=9206, type=net[local_port=14052, remote=217.0.132.55:25786])
2020-02-13 10:34:45.349 APP_INFO media_handler.c( 679) : dsp_session_msg_send(call=19206, type=net[local_port=14053, remote=217.0.132.55:25787])
2020-02-13 10:34:45.349 APP_INFO media_handler.c( 679) : dsp_session_msg_send(call=9206, type=cod[alaw,dtmf])
2020-02-13 10:34:45.349 APP_INFO media_handler.c(2026) : media_call_state(call=9206, new_state=active, old_state=initiate)
2020-02-13 10:34:45.349 APP_INFO iwu.c(2972) : iwu_cb_media_call_state(call=9206): active
2020-02-13 10:34:45.349 APP_INFO mps_handler.c(2958) : mps_call_progress(call=9206)
2020-02-13 10:34:45.349 APP_INFO mps_handler.c( 955) : msg_send(call=9206, msg=CC_EARLY_MEDIA) IWU_TRUNK->MPS for device=trunk
2020-02-13 10:34:45.350 APP_INFO mps_handler.c( 433) : mps_call_state(call=9206, new_state=progress, old_state=initiate)
2020-02-13 10:34:45.350 APP_INFO iwu.c(2510) : iwu_cb_mps_call_state(call=9206): progress
2020-02-13 10:34:45.353 APP_INFO mps_handler.c(2275) : msg_recv(call=8389, msg=CC_ADDRESS_COMPLETE) MPS->IWU_LINE for device=line
2020-02-13 10:34:45.452 APP_INFO mps_handler.c(2275) : msg_recv(call=8389, msg=CC_EARLY_MEDIA) MPS->IWU_LINE for device=line
2020-02-13 10:34:45.452 APP_INFO mps_handler.c( 433) : mps_call_state(call=8389, new_state=progress, old_state=initiate)
2020-02-13 10:34:45.452 APP_INFO iwu.c(2510) : iwu_cb_mps_call_state(call=8389): progress
2020-02-13 10:34:45.453 APP_INFO iwu.c( 980) : call_get_intersected_codec_order(call=8389): own=default, peer=best quality: result=best quality
2020-02-13 10:34:45.453 APP_INFO iwu.c( 738) : get_codec_audio_mask(call=8389) configured codec mask = ulaw|alaw|g729|g726_32|dtmf|g722
2020-02-13 10:34:45.453 APP_INFO media_handler.c(1819) : audio_session_set_peer_codecs(call=8389): set remote from intersect codecs and peer (call=9206) intersect codecs
2020-02-13 10:34:45.453 APP_INFO media_handler.c(2483) : media_call_active(call=8389)
2020-02-13 10:34:45.453 APP_INFO media_handler.c(1922) : audio_session_prepare(call=8389): local codecs = g722,alaw,ulaw,g729,g726_32,dtmf
2020-02-13 10:34:45.453 APP_INFO media_handler.c(1924) : audio_session_prepare(call=8389): remote codecs = alaw,dtmf
2020-02-13 10:34:45.453 APP_INFO media_handler.c(1938) : audio_session_prepare(call=8389): intersect codecs = alaw,dtmf
2020-02-13 10:34:45.453 APP_INFO media_handler.c(1940) : audio_session_prepare(call=8389): order codecs = best quality
2020-02-13 10:34:45.453 APP_INFO media_handler.c( 679) : dsp_session_msg_send(call=8389, type=net[local_port=10784, remote=192.168.66.2:5006,39000000])
2020-02-13 10:34:45.453 APP_INFO media_handler.c( 679) : dsp_session_msg_send(call=18389, type=net[local_port=10785, remote=192.168.66.2:5007,39000000])
2020-02-13 10:34:45.454 APP_INFO media_handler.c( 679) : dsp_session_msg_send(call=8389, type=cod[alaw,dtmf])
2020-02-13 10:34:45.454 APP_INFO media_handler.c(2026) : media_call_state(call=8389, new_state=active, old_state=initiate)
2020-02-13 10:34:45.454 APP_INFO iwu.c(2972) : iwu_cb_media_call_state(call=8389): active
2020-02-13 10:34:45.454 APP_INFO sip_handler.c(2979) : sip_call_progress(call=8389)
2020-02-13 10:34:45.455 APP_INFO sip_handler.c(1283) : sip_call_state(call=8389, state=progress)
2020-02-13 10:34:45.455 APP_INFO iwu.c(2821) : iwu_cb_sip_call_state(call=8389): progress
2020-02-13 10:34:46.111 APP_INFO sip_handler.c(1317) : sip_service_state(call=9206, state=update_request)
2020-02-13 10:34:46.111 APP_INFO iwu.c(2846) : iwu_cb_sip_service_state(call=9206): update_request
2020-02-13 10:34:46.111 APP_INFO sip_handler.c(2604) : sip_call_has_remote_media_changed(call=9206) returns true
2020-02-13 10:34:46.111 APP_INFO media_handler.c(2503) : media_call_update(call=9206)
2020-02-13 10:34:46.111 APP_INFO media_handler.c(1922) : audio_session_prepare(call=9206): local codecs = alaw,dtmf
2020-02-13 10:34:46.111 APP_INFO media_handler.c(1924) : audio_session_prepare(call=9206): remote codecs = alaw,dtmf
2020-02-13 10:34:46.111 APP_INFO media_handler.c(1938) : audio_session_prepare(call=9206): intersect codecs = alaw,dtmf
2020-02-13 10:34:46.111 APP_INFO media_handler.c(1940) : audio_session_prepare(call=9206): order codecs = best quality
2020-02-13 10:34:46.112 APP_INFO sip_handler.c(3055) : sip_call_media_change_confirm(call=9206)
2020-02-13 10:34:46.112 APP_INFO sip_handler.c(1317) : sip_service_state(call=9206, state=update_confirm)
2020-02-13 10:34:46.113 APP_INFO sip_handler.c(1317) : sip_service_state(call=9206, state=idle)
2020-02-13 10:34:46.113 APP_INFO iwu.c(2846) : iwu_cb_sip_service_state(call=9206): idle
2020-02-13 10:34:46.445 APP_INFO sip_handler.c(1283) : sip_call_state(call=9206, state=ring)
2020-02-13 10:34:46.445 APP_INFO iwu.c(2821) : iwu_cb_sip_call_state(call=9206): ring
2020-02-13 10:34:46.445 APP_INFO mps_handler.c(2939) : mps_call_ring(call=9206)
2020-02-13 10:34:46.445 APP_INFO mps_handler.c( 955) : msg_send(call=9206, msg=CC_CALL_DELIVERED) IWU_TRUNK->MPS for device=trunk
2020-02-13 10:34:46.445 APP_INFO mps_handler.c( 433) : mps_call_state(call=9206, new_state=ring, old_state=progress)
2020-02-13 10:34:46.446 APP_INFO iwu.c(2510) : iwu_cb_mps_call_state(call=9206): ring
2020-02-13 10:34:46.452 APP_INFO mps_handler.c(2275) : msg_recv(call=8389, msg=CC_CALL_DELIVERED) MPS->IWU_LINE for device=line
2020-02-13 10:34:46.452 APP_INFO mps_handler.c( 433) : mps_call_state(call=8389, new_state=ring, old_state=progress)
2020-02-13 10:34:46.452 APP_INFO iwu.c(2510) : iwu_cb_mps_call_state(call=8389): ring
2020-02-13 10:34:46.452 APP_INFO sip_handler.c(2971) : sip_call_ring(call=8389)
2020-02-13 10:34:46.454 APP_INFO sip_handler.c(1283) : sip_call_state(call=8389, state=ring)
2020-02-13 10:34:46.454 APP_INFO iwu.c(2821) : iwu_cb_sip_call_state(call=8389): ring
2020-02-13 10:35:00.054 WARNING sip_coder.c( 556) : Decode SIP failed in get_next_line_impl2
2020-02-13 10:35:00.055 WARNING sip_coder.c( 558) : Position around 0 in line:
2020-02-13 10:35:01.104 APP_INFO sip_handler.c(1354) : sip_service_indication(call=9206, indication=forward indication)
2020-02-13 10:35:01.172 APP_INFO sip_handler.c(1283) : sip_call_state(call=9206, state=progress)
2020-02-13 10:35:01.173 APP_INFO iwu.c(2821) : iwu_cb_sip_call_state(call=9206): progress
2020-02-13 10:35:01.173 APP_INFO sip_handler.c(2604) : sip_call_has_remote_media_changed(call=9206) returns true
2020-02-13 10:35:01.173 APP_INFO nat_handler.c( 571) : nat_session_close(call=9206)
2020-02-13 10:35:01.173 APP_INFO nat_handler.c( 542) : nat_session_open(call=9206, local_port=14052, remote=217.0.132.51): use_nat=1
2020-02-13 10:35:01.174 APP_INFO nat_handler.c( 571) : nat_session_close(call=19206)
2020-02-13 10:35:01.174 APP_INFO nat_handler.c( 542) : nat_session_open(call=19206, local_port=14053, remote=217.0.132.51): use_nat=1
2020-02-13 10:35:01.174 APP_INFO media_handler.c( 679) : dsp_session_msg_send(call=9206, type=net[local_port=14052, remote=217.0.132.51:13230])
2020-02-13 10:35:01.174 APP_INFO media_handler.c( 679) : dsp_session_msg_send(call=19206, type=net[local_port=14053, remote=217.0.132.51:13231])
2020-02-13 10:35:01.175 APP_INFO media_handler.c(2483) : media_call_active(call=9206)
2020-02-13 10:35:01.175 APP_INFO media_handler.c(1922) : audio_session_prepare(call=9206): local codecs = alaw,dtmf
2020-02-13 10:35:01.175 APP_INFO media_handler.c(1924) : audio_session_prepare(call=9206): remote codecs = alaw,dtmf
2020-02-13 10:35:01.175 APP_INFO media_handler.c(1938) : audio_session_prepare(call=9206): intersect codecs = alaw,dtmf
2020-02-13 10:35:01.175 APP_INFO media_handler.c(1940) : audio_session_prepare(call=9206): order codecs = best quality
2020-02-13 10:35:01.175 APP_INFO media_handler.c( 679) : dsp_session_msg_send(call=9206, type=net[local_port=14052, remote=217.0.132.51:13230])
2020-02-13 10:35:01.175 APP_INFO media_handler.c( 679) : dsp_session_msg_send(call=19206, type=net[local_port=14053, remote=217.0.132.51:13231])
2020-02-13 10:35:01.176 APP_INFO media_handler.c( 679) : dsp_session_msg_send(call=9206, type=cod[alaw,dtmf])
2020-02-13 10:35:01.176 APP_INFO mps_handler.c(2958) : mps_call_progress(call=9206)
2020-02-13 10:35:01.176 APP_INFO mps_handler.c( 955) : msg_send(call=9206, msg=CC_EARLY_MEDIA) IWU_TRUNK->MPS for device=trunk
2020-02-13 10:35:01.176 APP_INFO mps_handler.c( 433) : mps_call_state(call=9206, new_state=progress, old_state=ring)
2020-02-13 10:35:01.177 APP_INFO iwu.c(2510) : iwu_cb_mps_call_state(call=9206): progress
2020-02-13 10:35:01.761 APP_INFO sip_handler.c(1317) : sip_service_state(call=9206, state=update_request)
2020-02-13 10:35:01.762 APP_INFO iwu.c(2846) : iwu_cb_sip_service_state(call=9206): update_request
2020-02-13 10:35:01.762 APP_INFO sip_handler.c(2604) : sip_call_has_remote_media_changed(call=9206) returns true
2020-02-13 10:35:01.762 APP_INFO nat_handler.c( 571) : nat_session_close(call=9206)
2020-02-13 10:35:01.762 APP_INFO nat_handler.c( 542) : nat_session_open(call=9206, local_port=14052, remote=217.0.132.55): use_nat=1
2020-02-13 10:35:01.762 APP_INFO nat_handler.c( 571) : nat_session_close(call=19206)
2020-02-13 10:35:01.763 APP_INFO nat_handler.c( 542) : nat_session_open(call=19206, local_port=14053, remote=217.0.132.55): use_nat=1
2020-02-13 10:35:01.763 APP_INFO media_handler.c( 679) : dsp_session_msg_send(call=9206, type=net[local_port=14052, remote=217.0.132.55:25786])
2020-02-13 10:35:01.763 APP_INFO media_handler.c( 679) : dsp_session_msg_send(call=19206, type=net[local_port=14053, remote=217.0.132.55:25787])
2020-02-13 10:35:01.764 APP_INFO media_handler.c(2503) : media_call_update(call=9206)
2020-02-13 10:35:01.764 APP_INFO media_handler.c(1922) : audio_session_prepare(call=9206): local codecs = alaw,dtmf
2020-02-13 10:35:01.764 APP_INFO media_handler.c(1924) : audio_session_prepare(call=9206): remote codecs = alaw,dtmf
2020-02-13 10:35:01.764 APP_INFO media_handler.c(1938) : audio_session_prepare(call=9206): intersect codecs = alaw,dtmf
2020-02-13 10:35:01.764 APP_INFO media_handler.c(1940) : audio_session_prepare(call=9206): order codecs = best quality
2020-02-13 10:35:01.764 APP_INFO sip_handler.c(3055) : sip_call_media_change_confirm(call=9206)
2020-02-13 10:35:01.765 APP_INFO sip_handler.c(1317) : sip_service_state(call=9206, state=update_confirm)
2020-02-13 10:35:01.765 APP_INFO sip_handler.c(1317) : sip_service_state(call=9206, state=idle)
2020-02-13 10:35:01.765 APP_INFO iwu.c(2846) : iwu_cb_sip_service_state(call=9206): idle
2020-02-13 10:35:01.860 WARNING sip_coder.c( 594) : Check SIP warning in check_unsupporteds with result MISSING_UNSUPPORTED
2020-02-13 10:35:01.861 APP_INFO sip_handler.c(1283) : sip_call_state(call=9206, state=terminated)
2020-02-13 10:35:01.861 APP_INFO iwu.c(2821) : iwu_cb_sip_call_state(call=9206): terminated
2020-02-13 10:35:01.862 APP_INFO mps_handler.c(3006) : mps_call_terminate(call=9206)
2020-02-13 10:35:01.862 APP_INFO mps_handler.c( 955) : msg_send(call=9206, msg=CC_CALL_REL_REQ) IWU_TRUNK->MPS for device=trunk
2020-02-13 10:35:01.862 APP_INFO mps_handler.c( 433) : mps_call_state(call=9206, new_state=terminate, old_state=progress)
2020-02-13 10:35:01.862 APP_INFO iwu.c(2510) : iwu_cb_mps_call_state(call=9206): terminate
2020-02-13 10:35:01.862 APP_INFO sip_handler.c(3000) : sip_call_terminate(call=9206)
2020-02-13 10:35:01.862 APP_INFO media_handler.c(2515) : media_call_terminate(call=9206)
2020-02-13 10:35:01.862 APP_INFO media_handler.c(1172) : dsp_session_close_link(call=9206): link=759
2020-02-13 10:35:01.862 APP_INFO nat_handler.c( 571) : nat_session_close(call=9206)
2020-02-13 10:35:01.862 APP_INFO media_handler.c(1042) : dsp_session_close_udp(call=9206): fd=40
2020-02-13 10:35:01.862 APP_INFO media_handler.c(1124) : dsp_session_close_dsp(call=9206): fd=41
2020-02-13 10:35:01.862 APP_INFO media_handler.c(1172) : dsp_session_close_link(call=19206): link=713
2020-02-13 10:35:01.863 APP_INFO nat_handler.c( 571) : nat_session_close(call=19206)
2020-02-13 10:35:01.863 APP_INFO media_handler.c(1042) : dsp_session_close_udp(call=19206): fd=42
2020-02-13 10:35:01.863 APP_INFO media_handler.c(1124) : dsp_session_close_dsp(call=19206): fd=43
2020-02-13 10:35:01.863 APP_INFO media_handler.c(2026) : media_call_state(call=9206, new_state=terminate, old_state=active)
2020-02-13 10:35:01.863 APP_INFO iwu.c(2972) : iwu_cb_media_call_state(call=9206): terminate
2020-02-13 10:35:01.863 APP_INFO media_handler.c(2026) : media_call_state(call=9206, new_state=idle, old_state=terminate)
2020-02-13 10:35:01.863 APP_INFO iwu.c(2972) : iwu_cb_media_call_state(call=9206): idle
2020-02-13 10:35:01.863 APP_INFO iwu.c( 670) : call terminated(9206): mps_cause=none, sip_cause=none
2020-02-13 10:35:01.864 APP_INFO sip_handler.c(1283) : sip_call_state(call=9206, state=idle)
2020-02-13 10:35:01.864 APP_INFO iwu.c(2821) : iwu_cb_sip_call_state(call=9206): idle
2020-02-13 10:35:01.869 APP_INFO mps_handler.c(2275) : msg_recv(call=8389, msg=CC_CALL_REL_REQ) MPS->IWU_LINE for device=line
2020-02-13 10:35:01.869 APP_INFO mps_handler.c( 433) : mps_call_state(call=8389, new_state=terminated, old_state=ring)
2020-02-13 10:35:01.869 APP_INFO iwu.c(2510) : iwu_cb_mps_call_state(call=8389): terminated
2020-02-13 10:35:01.869 APP_INFO mps_handler.c(3006) : mps_call_terminate(call=8389)
2020-02-13 10:35:01.869 APP_INFO sip_handler.c(3000) : sip_call_terminate(call=8389)
2020-02-13 10:35:01.870 APP_INFO sip_handler.c(1283) : sip_call_state(call=8389, state=terminate)
2020-02-13 10:35:01.870 APP_INFO iwu.c(2821) : iwu_cb_sip_call_state(call=8389): terminate
2020-02-13 10:35:01.870 APP_INFO media_handler.c(2515) : media_call_terminate(call=8389)
2020-02-13 10:35:01.870 APP_INFO media_handler.c(1172) : dsp_session_close_link(call=8389): link=709
2020-02-13 10:35:01.870 APP_INFO nat_handler.c( 571) : nat_session_close(call=8389)
2020-02-13 10:35:01.870 APP_INFO media_handler.c(1042) : dsp_session_close_udp(call=8389): fd=36
2020-02-13 10:35:01.870 APP_INFO media_handler.c(1124) : dsp_session_close_dsp(call=8389): fd=37
2020-02-13 10:35:01.870 APP_INFO media_handler.c(1172) : dsp_session_close_link(call=18389): link=615
2020-02-13 10:35:01.870 APP_INFO nat_handler.c( 571) : nat_session_close(call=18389)
2020-02-13 10:35:01.870 APP_INFO media_handler.c(1042) : dsp_session_close_udp(call=18389): fd=38
2020-02-13 10:35:01.870 APP_INFO media_handler.c(1124) : dsp_session_close_dsp(call=18389): fd=39
2020-02-13 10:35:01.871 APP_INFO media_handler.c(2026) : media_call_state(call=8389, new_state=terminate, old_state=active)
2020-02-13 10:35:01.871 APP_INFO iwu.c(2972) : iwu_cb_media_call_state(call=8389): terminate
2020-02-13 10:35:01.871 APP_INFO media_handler.c(2026) : media_call_state(call=8389, new_state=idle, old_state=terminate)
2020-02-13 10:35:01.871 APP_INFO iwu.c(2972) : iwu_cb_media_call_state(call=8389): idle
2020-02-13 10:35:01.871 APP_INFO iwu.c( 670) : call terminated(8389): mps_cause=none, sip_cause=none
2020-02-13 10:35:01.871 APP_INFO mps_handler.c( 955) : msg_send(call=8389, msg=CC_CALL_RELEASED) IWU_LINE->MPS for device=line
2020-02-13 10:35:01.871 APP_INFO mps_handler.c( 955) : msg_send(call=8389, msg=LOG_ID_CLEARED) IWU_LINE->MPS for device=line
2020-02-13 10:35:01.871 APP_INFO mps_handler.c( 433) : mps_call_state(call=8389, new_state=idle, old_state=terminated)
2020-02-13 10:35:01.871 APP_INFO iwu.c(2510) : iwu_cb_mps_call_state(call=8389): idle
2020-02-13 10:35:01.871 APP_INFO iwu.c(3273) : iwu_call_delete(call=8389)
2020-02-13 10:35:01.871 APP_INFO iwu.c(2808) : iwu_cb_sip_call_license_free(call=8389)
2020-02-13 10:35:01.872 APP_INFO mps_handler.c( 544) : call_set_dst_log_id(call=8389, dst_call=9206) peer reference released
2020-02-13 10:35:01.872 APP_INFO mps_handler.c(2275) : msg_recv(call=9206, msg=CC_CALL_RELEASED) MPS->IWU_TRUNK for device=trunk
2020-02-13 10:35:01.872 APP_INFO mps_handler.c( 433) : mps_call_state(call=9206, new_state=terminated, old_state=terminate)
2020-02-13 10:35:01.872 APP_INFO iwu.c(2510) : iwu_cb_mps_call_state(call=9206): terminated
2020-02-13 10:35:01.872 APP_INFO mps_handler.c(3006) : mps_call_terminate(call=9206)
2020-02-13 10:35:01.873 APP_INFO sip_handler.c(3000) : sip_call_terminate(call=9206)
2020-02-13 10:35:01.873 APP_INFO media_handler.c(2515) : media_call_terminate(call=9206)
2020-02-13 10:35:01.873 APP_INFO iwu.c( 670) : call terminated(9206): mps_cause=none, sip_cause=none
2020-02-13 10:35:01.873 APP_INFO mps_handler.c( 955) : msg_send(call=9206, msg=LOG_ID_CLEARED) IWU_TRUNK->MPS for device=trunk
2020-02-13 10:35:01.873 APP_INFO mps_handler.c( 433) : mps_call_state(call=9206, new_state=idle, old_state=terminated)
2020-02-13 10:35:01.873 APP_INFO iwu.c(2510) : iwu_cb_mps_call_state(call=9206): idle
2020-02-13 10:35:01.873 APP_INFO iwu.c(3273) : iwu_call_delete(call=9206)
2020-02-13 10:35:01.873 APP_INFO iwu.c(2808) : iwu_cb_sip_call_license_free(call=9206)
2020-02-13 10:35:01.876 APP_INFO mib_handler.c(1473) : trap_handler_call_transaction(ev=0x00000100)
2020-02-13 10:35:01.876 APP_INFO iwu.c(2475) : iwu_cb_mib_call_state(call_id=8389, number=20, number_peer=, direction_is_initiator=1, state=terminated)
2020-02-13 10:35:01.881 APP_INFO mib_handler.c(1473) : trap_handler_call_transaction(ev=0x00000100)
2020-02-13 10:35:01.881 APP_INFO iwu.c(2475) : iwu_cb_mib_call_state(call_id=8389, number=20, number_peer=, direction_is_initiator=1, state=idle)
2020-02-13 10:35:01.888 APP_INFO mib_handler.c(1473) : trap_handler_call_transaction(ev=0x00000000)
2020-02-13 10:35:08.182 APP_INFO mib_handler.c(1517) : trap_handler_voip(ev=0x00001000)
2020-02-13 10:35:08.182 APP_INFO iwu.c(2257) : iwu_cb_mib_trace_status(status=0, mode=2)