log del khomp
T|12/07/2019 15:36:55.000|API | EV_SIP_INVITE, 2834, "dest_addr="15306314" orig_addr="494265" network_orig_addr="192.168.16.9" orig_port="5060" dest_request_uri_addr="15306314" network_request_uri_addr="192.168.16.8" request_uri_port="5060" orig_via_addr="192.168.16.9" orig_via_port="5060" sip_transport_type="0" voip_audio_transport_type="0" sip_call_id="1de523eb1d2e0c9355b7e91c3d397391@192.168.16.9:5060" voip_codecs="1" sip_via_sent_by="192.168.16.9:5060"" on [99, -1]
T|12/07/2019 15:36:55.002|SYSTEM | Resolved NAP[VoIP] as origin for event EV_SIP_INVITE, 2834, "dest_addr="15306314" orig_addr="494265" network_orig_addr="192.168.16.9" orig_port="5060" dest_request_uri_addr="15306314" network_request_uri_addr="192.168.16.8" request_uri_port="5060" orig_via_addr="192.168.16.9" orig_via_port="5060" sip_transport_type="0" voip_audio_transport_type="0" sip_call_id="1de523eb1d2e0c9355b7e91c3d397391@192.168.16.9:5060" voip_codecs="1" sip_via_sent_by="192.168.16.9:5060""
T|12/07/2019 15:36:55.002|SYSTEM | Candidate origin NAP[VoIP] to associate SIP channel to incoming call
T|12/07/2019 15:36:55.002|API |D 99 C 0| Channel state changed IDLE -> ALLOCATED
T|12/07/2019 15:36:55.003|ALLOCATOR|FIRST-FREE|Allocated channel [D 99 C 0]
T|12/07/2019 15:36:55.004|API |D 99 C 0| I will be the incoming channel for SIP call 2834
T|12/07/2019 15:36:55.004|API |D 99 C 0| Channel state changed ALLOCATED -> SEIZED
T|12/07/2019 15:36:55.027|SYSTEM | Resolved NAP[VoIP] as origin for event EV_NEW_CALL, 0, "dest_addr="15306314" orig_addr="494265" network_orig_addr="192.168.16.9" orig_port="5060" call_id="2834" dest_request_uri_addr="15306314" network_request_uri_addr="192.168.16.8" request_uri_port="5060" orig_via_addr="192.168.16.9" orig_via_port="5060" sip_transport_type="0" voip_audio_transport_type="0" sip_call_id="1de523eb1d2e0c9355b7e91c3d397391@192.168.16.9:5060" voip_codecs="1" sip_via_sent_by="192.168.16.9:5060""
T|12/07/2019 15:36:55.027|API |0000990001562956615698265475| Calls Counter=1, Max Simultaneous Calls=0
T|12/07/2019 15:36:55.028|MATCH Looking route for call [D 99 C 0][EV_NEW_CALL, 0, "dest_addr="15306314" orig_addr="494265" network_orig_addr="192.168.16.9" orig_port="5060" call_id="2834" dest_request_uri_addr="15306314" network_request_uri_addr="192.168.16.8" request_uri_port="5060" orig_via_addr="192.168.16.9" orig_via_port="5060" sip_transport_type="0" voip_audio_transport_type="0" sip_call_id="1de523eb1d2e0c9355b7e91c3d397391@192.168.16.9:5060" voip_codecs="1" sip_via_sent_by="192.168.16.9:5060""]
T|12/07/2019 15:36:55.028|MATCH Matching route:[VoIP-Link] - channel[D 99 C 0] origin_nap[VoIP] called[] caller[] mcalled[] mcaller[] destnap[Link] timerest[] codecprofile[] origin_collect_call_filter[NO_FILTER] destination_collect_call_indication[SET_TO_NORMAL_CALL]
T|12/07/2019 15:36:55.029|API |D60429 C 29| Channel state changed IDLE -> ALLOCATED
T|12/07/2019 15:36:55.030|ALLOCATOR|FIRST-FREE|Allocated channel [D60429 C 29]
T|12/07/2019 15:36:55.030|ROUTER Routing from [VoIP 99 C 0] to [Link60429 C 29]. Route[VoIP-Link] Remap dest[15306314 > 15306314] orig[494265 > 494265]. Route priority[11].
T|12/07/2019 15:36:55.032|API |D60429 C 29| Starting audio events detection
T|12/07/2019 15:36:55.034|API |D60429 C 29| Channel state changed ALLOCATED -> SEIZED
T|12/07/2019 15:36:55.034|API |0000990001562956615698265475| Routing call [EV_NEW_CALL, 0, "dest_addr="15306314" orig_addr="494265" network_orig_addr="192.168.16.9" orig_port="5060" call_id="2834" dest_request_uri_addr="15306314" network_request_uri_addr="192.168.16.8" request_uri_port="5060" orig_via_addr="192.168.16.9" orig_via_port="5060" sip_transport_type="0" voip_audio_transport_type="0" sip_call_id="1de523eb1d2e0c9355b7e91c3d397391@192.168.16.9:5060" voip_codecs="1" sip_via_sent_by="192.168.16.9:5060""] from channel [D 99 C 0] (profile VoIP) to [D60429 C 29] (profile Pass-through (fax))
T|12/07/2019 15:36:55.390|API |0000990001562956615698265475| EV_TONE_BEGIN[0]: Orig: [D 99 C 0], Dest: [D60429 C 29], event on DESTINATION
T|12/07/2019 15:36:55.590|API |0000990001562956615698265475| EV_TONE_BEGIN[0]: Orig: [D 99 C 0], Dest: [D60429 C 29], event on DESTINATION
T|12/07/2019 15:36:55.810|API |0000990001562956615698265475| EV_TONE_BEGIN[0]: Orig: [D 99 C 0], Dest: [D60429 C 29], event on DESTINATION
T|12/07/2019 15:36:55.990|API |0000990001562956615698265475| EV_TONE_BEGIN[0]: Orig: [D 99 C 0], Dest: [D60429 C 29], event on DESTINATION
T|12/07/2019 15:36:56.190|API |0000990001562956615698265475| EV_TONE_BEGIN[0]: Orig: [D 99 C 0], Dest: [D60429 C 29], event on DESTINATION
T|12/07/2019 15:36:56.410|API |0000990001562956615698265475| EV_TONE_BEGIN[0]: Orig: [D 99 C 0], Dest: [D60429 C 29], event on DESTINATION
T|12/07/2019 15:36:56.650|API |0000990001562956615698265475| EV_TONE_BEGIN[0]: Orig: [D 99 C 0], Dest: [D60429 C 29], event on DESTINATION
T|12/07/2019 15:36:56.850|API |0000990001562956615698265475| EV_TONE_BEGIN[0]: Orig: [D 99 C 0], Dest: [D60429 C 29], event on DESTINATION
T|12/07/2019 15:36:57.070|API |0000990001562956615698265475| EV_TONE_BEGIN[0]: Orig: [D 99 C 0], Dest: [D60429 C 29], event on DESTINATION
T|12/07/2019 15:36:57.270|API |0000990001562956615698265475| EV_TONE_BEGIN[0]: Orig: [D 99 C 0], Dest: [D60429 C 29], event on DESTINATION
T|12/07/2019 15:36:57.490|API |0000990001562956615698265475| EV_TONE_BEGIN[0]: Orig: [D 99 C 0], Dest: [D60429 C 29], event on DESTINATION
T|12/07/2019 15:36:57.670|API |0000990001562956615698265475| EV_TONE_BEGIN[0]: Orig: [D 99 C 0], Dest: [D60429 C 29], event on DESTINATION
T|12/07/2019 15:36:57.870|API |0000990001562956615698265475| EV_TONE_BEGIN[0]: Orig: [D 99 C 0], Dest: [D60429 C 29], event on DESTINATION
T|12/07/2019 15:36:58.110|API |0000990001562956615698265475| EV_TONE_BEGIN[0]: Orig: [D 99 C 0], Dest: [D60429 C 29], event on DESTINATION
T|12/07/2019 15:36:58.350|API |0000990001562956615698265475| EV_TONE_BEGIN[0]: Orig: [D 99 C 0], Dest: [D60429 C 29], event on DESTINATION
T|12/07/2019 15:36:58.570|API |0000990001562956615698265475| EV_TONE_BEGIN[0]: Orig: [D 99 C 0], Dest: [D60429 C 29], event on DESTINATION
T|12/07/2019 15:36:58.870|API |0000990001562956615698265475| EV_CALL_SUCCESS[6]: Orig: [D 99 C 0], Dest: [D60429 C 29], event on DESTINATION
T|12/07/2019 15:36:58.881|API |D 99 C 0| Channel state changed SEIZED -> RINGING
T|12/07/2019 15:36:58.915|API |D 99 C 0| Features: disable 0x0002, enable 0x0001, supported 0x0003, current 0x0001
T|12/07/2019 15:36:58.921|API |D 99 C 0| Starting cadence tone generation: RINGBACK
T|12/07/2019 15:36:58.921|API |0000990001562956615698265475| EV_SIP_MEDIA_START[0]: Orig: [D 99 C 0], Dest: [D60429 C 29], event on ORIGIN
T|12/07/2019 15:36:58.922|API |0000990001562956615698265475| EV_TONE_BEGIN[0]: Orig: [D 99 C 0], Dest: [D60429 C 29], event on DESTINATION
T|12/07/2019 15:36:58.991|API |0000990001562956615698265475| EV_TONE_BEGIN[1]: Orig: [D 99 C 0], Dest: [D60429 C 29], event on DESTINATION
T|12/07/2019 15:36:58.991|API |D60429 C 29| Stopping audio events detection
T|12/07/2019 15:36:58.994|API |D 99 C 0| Stopping cadence tone generation
T|12/07/2019 15:36:58.998|API |D 99 C 0| Features: disable 0x0002, enable 0x0001, supported 0x0003, current 0x0001
T|12/07/2019 15:37:05.140|API |0000990001562956615698265475| EV_CALL_FAIL[487]: Orig: [D 99 C 0], Dest: [D60429 C 29], event on ORIGIN
T|12/07/2019 15:37:05.146|API |D60429 C 29| Disconnecting with Q.850 127
T|12/07/2019 15:37:05.146|API |D60429 C 29| Not switched, ignoring clear
T|12/07/2019 15:37:05.146|API |D60429 C 29| Channel state changed SEIZED -> CLOSING
T|12/07/2019 15:37:05.160|API |D60429 C 29| CM_DISCONNECT( isdn_cause='27' ) ignored, CanSendCommand rejected with InvalidState, _State=CLOSING
T|12/07/2019 15:37:05.160|SYSTEM | KCallManager::ReleaseCall - call id='0000990001562956615698265475' is releasing channel ORIGIN (D 99 C 0)
T|12/07/2019 15:37:05.160|API |0000990001562956615698265475| Releasing channel D 99 C 0, ORIG=D 99 C 0, DEST=D60429 C 29
T|12/07/2019 15:37:05.161|API |D60429 C 29| Not switched, ignoring clear
T|12/07/2019 15:37:05.163|API |D 99 C 0| Not switched, ignoring clear
T|12/07/2019 15:37:05.164|API |D 99 C 0| Features: disable 0xFFFF, enable 0x0000, supported 0x0003, current 0x0000
T|12/07/2019 15:37:05.164|API |D 99 C 0| Channel state changed RINGING -> IDLE
T|12/07/2019 15:37:05.350|SYSTEM | KCallManager::ReleaseCall - call id='0000990001562956615698265475' is releasing channel DESTINATION (D60429 C 29)
T|12/07/2019 15:37:05.350|API |0000990001562956615698265475| Releasing channel D60429 C 29, ORIG=(null), DEST=D60429 C 29
T|12/07/2019 15:37:05.350|API |D60429 C 29| Not switched, ignoring clear
T|12/07/2019 15:37:05.350|API |D60429 C 29| Not switched, ignoring clear
T|12/07/2019 15:37:05.350|API |D60429 C 29| Features: disable 0xFFFF, enable 0x0000, supported 0xFFFE, current 0x0000
T|12/07/2019 15:37:05.351|API |D60429 C 29| Channel state changed CLOSING -> IDLE