Re: H323: Internal Error (H323 Interworking Error): IEC=1.1.127.5.21.0

Nikhil.ranjan · ‎03-21-2018

We are getting error on the call for H323 calls. These are generally the case when there is call for longer duration and gets disconnected. This is the only error that i see on the logs and we are not able to collect the logs for the calls are these are for the longer duration.

Please help !!

R0g22 · ‎03-21-2018

Setup a syslog to collect H.323 logs.

Nikhil.ranjan · ‎03-22-2018

Hello,

I wont be able to send you the complete logs. I am just attaching a part of it.

15323: Mar 22 11:44:25.997: ISDN Se0/1/0:15 Q931: RX <- ALERTING pd = 8 callref = 0x8CE2
015324: Mar 22 11:44:25.998: //10105/8078F3414A08/CCAPI/cc_api_call_alert:
Interface=0x7FA373D386B8, Progress Indication=NULL(0), Signal Indication=NOT PRESENT(255)
015325: Mar 22 11:44:25.998: //10105/8078F3414A08/CCAPI/cc_api_call_alert:
Call Entry(Retry Count=0, Responsed=TRUE)
015326: Mar 22 11:44:25.999: //10104/8078F3414A08/CCAPI/ccCallAlert:
Progress Indication=INBAND(8), Signal Indication=NOT PRESENT(255)
015327: Mar 22 11:44:25.999: //10104/8078F3414A08/CCAPI/ccCallAlert:
Call Entry(Responsed=TRUE, Alert Sent=TRUE)
015328: Mar 22 11:44:25.999: //10105/8078F3414A08/CCAPI/cc_api_get_called_ccm_detected:
CallInfo(ccm detected=0)
015329: Mar 22 11:44:25.999: //10104/8078F3414A08/CCAPI/cc_api_get_delay_xport:
CallInfo(delay xport=FALSE)
015330: Mar 22 11:44:26.498: ISDN Se0/1/0:15 Q931: RX <- CONNECT pd = 8 callref = 0x8CE1
Channel ID i = 0xA98382
Exclusive, Channel 2
015331: Mar 22 11:44:26.499: %ISDN-6-CONNECT: Interface Serial0/1/0:1 is now connected to 4620114 N/A
015332: Mar 22 11:44:26.499: ISDN Se0/1/0:15 Q931: TX -> CONNECT_ACK pd = 8 callref = 0x0CE1
015333: Mar 22 11:44:26.500: //10103/00F33D344908/CCAPI/cc_api_call_connected:
Interface=0x7FA373D386B8, Data Bitmask=0x1, Progress Indication=NULL(0),
Connection Handle=0
015334: Mar 22 11:44:26.500: //10103/00F33D344908/CCAPI/cc_api_call_connected:
Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0)
015335: Mar 22 11:44:26.501: //10102/00F33D344908/CCAPI/ccCallConnect:
Progress Indication=INBAND(8), Data Bitmask=0x1
015336: Mar 22 11:44:26.501: //10103/00F33D344908/CCAPI/cc_api_get_called_ccm_detected:
CallInfo(ccm detected=0)
015337: Mar 22 11:44:26.501: //10102/00F33D344908/CCAPI/ccCallConnect:
Call Entry(Connected=TRUE, Responsed=TRUE)
015338: Mar 22 11:44:26.501: //10102/00F33D344908/CCAPI/ccCallNotify:
Data Bitmask=0x7, Call Id=10102
015339: Mar 22 11:44:26.501: //10103/00F33D344908/CCAPI/cc_api_get_called_ccm_detected:
CallInfo(ccm detected=0)
015340: Mar 22 11:44:26.502: //10102/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

015341: Mar 22 11:44:26.502: cc_api_get_xcode_stream : 4977
015342: Mar 22 11:44:26.502: //10102/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

015343: Mar 22 11:44:26.502: cc_api_get_xcode_stream : 4977
015344: Mar 22 11:44:26.502: compose_TunnelledSignallingMessage_cisco Tunnelled Content:
ANM,
PRN,isdn*,,NET5*,

....
015345: Mar 22 11:44:26.502: The length of message content is:[27]
Hex representation of the CONNECT TPKT to send.: 080288490704038090A21E02808828007E00B40522C0060008914A0004000A1F1F0F52BA2800B500001240013C0501000000F33D34A74931AB49080E030A1FE94B1D0C00110000F33D34A74931AB49080E030A1FE94B0100010010A80100450140B50000123EE001010001182D1803A983821C269E8100036774640000001B414E4D2C0D0A50524E2C6973646E2A2C2C4E4554352A2C0D0A0D0A18800006000400000008224204677464011B414E4D2C0D0A50524E2C6973646E2A2C2C4E4554352A2C0D0A0D0A
h225SetupResponse: Q.931 CONNECT sent from fd=7
015346: Mar 22 11:44:26.502: //10102/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

015347: Mar 22 11:44:26.502: cc_api_get_xcode_stream : 4977
015348: Mar 22 11:44:26.503: //10102/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

015349: Mar 22 11:44:26.503: cc_api_get_xcode_stream : 4977
015350: Mar 22 11:44:26.503: //10102/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

015351: Mar 22 11:44:26.503: cc_api_get_xcode_stream : 4977
015352: Mar 22 11:44:26.503: //10102/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

015353: Mar 22 11:44:26.503: cc_api_get_xcode_stream : 4977
015354: Mar 22 11:44:26.503: //10102/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

015355: Mar 22 11:44:26.503: cc_api_get_xcode_stream : 4977
015356: Mar 22 11:44:26.503: //10102/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

015357: Mar 22 11:44:26.503: cc_api_get_xcode_stream : 4977
015358: Mar 22 11:44:26.503: compose_TunnelledSignallingMessage_ciscoNo tunnelled content.
Hex representation of the NOTIFY TPKT to send.: 080288496E2701F128004C090000343632303131347E00210528501900060008914A00040000F33D34A74931AB49080E030A1FE94B10800100
h225NotifyRequest: Q.931 NOTIFY sent from fd=7
015359: Mar 22 11:44:54.256: ISDN Se0/1/0:15 Q931: RX <- STATUS_ENQ pd = 8 callref = 0x8CDC
015360: Mar 22 11:44:54.256: ISDN Se0/1/0:15 Q931: TX -> STATUS pd = 8 callref = 0x0CDC
Cause i = 0x809E - Response to STATUS ENQUIRY or number unassigned
Call State i = 0x0A
015361: Mar 22 11:45:11.921: h323chan_chn_process_read_socket: fd=13 of type ACCEPTED has data

015362: Mar 22 11:45:11.922: //10104/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

015363: Mar 22 11:45:11.922: cc_api_get_xcode_stream : 4977
015364: Mar 22 11:45:11.922: //10104/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

015365: Mar 22 11:45:11.922: cc_api_get_xcode_stream : 4977
015366: Mar 22 11:45:11.923: h323chan_chn_process_read_socket: fd=13 of type ACCEPTED has data

015367: Mar 22 11:45:11.927: h323chan_chn_process_read_socket: fd=5 of type ACCEPTED has data

Hex representation of the SETUP TPKT received: 0802084A5A080280907E0022052580060008914A0005110011008078F341BE4931AB4A080F030A1FEA5F10800100
h225ParseData: Q.931 RELEASE COMPLETE received on fd=5
015368: Mar 22 11:45:11.928: //10104/8078F3414A08/CCAPI/cc_api_call_disconnected:
Cause Value=16, Interface=0x7FA369C9A140, Call Id=10104
015369: Mar 22 11:45:11.928: //10104/8078F3414A08/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=TRUE, Cause Value=16, Retry Count=0)
015370: Mar 22 11:45:11.928: h323chan_chn_process_read_socket: fd=13 of type ACCEPTED has data

015371: Mar 22 11:45:11.928: h323chan_recvdata: Connection lost fd=13
015372: Mar 22 11:45:11.928: //10104/8078F3414A08/CCAPI/ccConferenceDestroy:
Conference Id=0x12CE, Tag=0x0
015373: Mar 22 11:45:11.928: //10104/8078F3414A08/CCAPI/ccConferenceDestroy:

015374: Mar 22 11:45:11.928: confID:0x12CE; callEntry1 callID1:0x2778, type:1; callEntry2 callID2:0x2779, type:6

015375: Mar 22 11:45:11.929: //10104/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

015376: Mar 22 11:45:11.929: cc_api_get_xcode_stream : 4977
015377: Mar 22 11:45:11.929: //10104/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

015378: Mar 22 11:45:11.929: cc_api_get_xcode_stream : 4977
015379: Mar 22 11:45:11.929: //10104/8078F3414A08/CCAPI/cc_api_bridge_drop_done:
Conference Id=0x12CE, Source Interface=0x7FA369C9A140, Source Call Id=10104,
Destination Call Id=10105, Disposition=0x0, Tag=0x0
015380: Mar 22 11:45:11.929: //10105/8078F3414A08/CCAPI/cc_api_bridge_drop_done:
Conference Id=0x12CE, Source Interface=0x7FA373D386B8, Source Call Id=10105,
Destination Call Id=10104, Disposition=0x0, Tag=0x0
015381: Mar 22 11:45:11.929: //10104/8078F3414A08/CCAPI/cc_generic_bridge_done:
Conference Id=0x12CE, Source Interface=0x7FA373D386B8, Source Call Id=10105,
Destination Call Id=10104, Disposition=0x0, Tag=0x0
015382: Mar 22 11:45:11.930: //10105/8078F3414A08/CCAPI/ccCallDisconnect:
Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
015383: Mar 22 11:45:11.930: //10105/8078F3414A08/CCAPI/ccCallDisconnect:
Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
015384: Mar 22 11:45:11.930: //10105/8078F3414A08/CCAPI/cc_api_get_transfer_info:
Transfer Number=NULL
015385: Mar 22 11:45:11.931: //10104/8078F3414A08/CCAPI/ccCallDisconnect:
Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16)
015386: Mar 22 11:45:11.931: //10104/8078F3414A08/CCAPI/ccCallDisconnect:
Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
015387: Mar 22 11:45:11.931: //10104/8078F3414A08/CCAPI/cc_api_get_transfer_info:
Transfer Number=NULL
015388: Mar 22 11:45:11.941: //10104/8078F3414A08/CCAPI/cc_api_get_transfer_info:
Transfer Number=NULL
015389: Mar 22 11:45:11.941: compose_TunnelledSignallingMessage_ciscoNo tunnelled content.
Hex representation of the RELEASE COMPLETE TPKT to send.: 0802884A5A080280907E0022052580060008914A0004110011008078F341BE4931AB4A080F030A1FEA5F10800100
h225TerminateRequest: Q.931 RELEASE COMPLETE sent from fd=5. Call state changed to [Null].
015390: Mar 22 11:45:11.942: h323chan_close: TCP connection from fd=5 closed
015391: Mar 22 11:45:11.942: h323chan_close: TCP connection from fd=13 closed
015392: Mar 22 11:45:11.942: h323chan_close: TCP connection from fd=6 closed
015393: Mar 22 11:45:11.942: //10104/8078F3414A08/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x7FA369C9A140, Tag=0x0, Call Id=10104,
Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
015394: Mar 22 11:45:11.942: //10104/8078F3414A08/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
015395: Mar 22 11:45:11.943: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

015396: Mar 22 11:45:11.943: :cc_free_feature_vsa freeing 7FA36F5CB0C0
015397: Mar 22 11:45:11.943: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

015398: Mar 22 11:45:11.943: vsacount in free is 7
015399: Mar 22 11:45:11.944: ISDN Se0/1/0:15 Q931: TX -> DISCONNECT pd = 8 callref = 0x0CE2
Cause i = 0x8090 - Normal call clearing
015400: Mar 22 11:45:12.020: ISDN Se0/1/0:15 Q931: RX <- RELEASE pd = 8 callref = 0x8CE2
Cause i = 0x8090 - Normal call clearing
015401: Mar 22 11:45:12.020: ISDN Se0/1/0:15 Q931: TX -> RELEASE_COMP pd = 8 callref = 0x0CE2
015402: Mar 22 11:45:12.023: //10105/8078F3414A08/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x7FA373D386B8, Tag=0x0, Call Id=10105,
Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
015403: Mar 22 11:45:12.023: //10105/8078F3414A08/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
015404: Mar 22 11:45:12.023: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

015405: Mar 22 11:45:12.023: :cc_free_feature_vsa freeing 7FA36F5C9220
015406: Mar 22 11:45:12.023: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

015407: Mar 22 11:45:12.023: vsacount in free is 6
015408: Mar 22 11:45:16.609: ISDN Se0/1/0:15 Q931: RX <- DISCONNECT pd = 8 callref = 0x8CDC
Cause i = 0x8090 - Normal call clearing
Progress Ind i = 0x8288 - In-band info or appropriate now available
015409: Mar 22 11:45:16.610: %ISDN-6-DISCONNECT: Interface Serial0/1/0:0 disconnected from 8373996267 , call lasted 322 seconds
015410: Mar 22 11:45:16.610: ISDN Se0/1/0:15 Q931: call_disc: PI received in disconnect; Postpone sending RELEASE for callid 0x8C5D
015411: Mar 22 11:45:16.610: //10089/0046769A4408/CCAPI/cc_api_call_disc_prog_ind:
Cause Value=16, Progress Indication=INBAND(8), Interface=0x7FA373D386B8
015412: Mar 22 11:45:16.611: //10089/0046769A4408/CCAPI/cc_api_call_disc_prog_ind:
Event(Progress Indication=0x8), Call Entry(Responsed=TRUE)
015413: Mar 22 11:45:16.611: //10088/0046769A4408/CCAPI/ccCallDiscProgInd:
Cause Value=16, Progress Indication=INBAND(8)
015414: Mar 22 11:45:16.611: //10088/0046769A4408/CCAPI/ccCallDiscProgInd:
Call Entry(Responsed=TRUE, Disconnect Cause=16)
015415: Mar 22 11:45:16.611: %VOICE_IEC-3-GW: H323: Internal Error (H323 Interworking Error): IEC=1.1.127.5.21.0 on callID 10088 GUID=718DE41A2CCE11E893C86CB2AE05D630
015416: Mar 22 11:45:16.611: compose_TunnelledSignallingMessage_cisco Tunnelled Content:
REL,
PRN,isdn*,,NET5*,

....
015417: Mar 22 11:45:16.611: The length of message content is:[27]
Hex representation of the RELEASE COMPLETE TPKT to send.: 080288445A080280907E008E052580060008914A0004110011000046769AA54831AB440801030A1FEA6410A80100480140B500001241E0010100010830080280901E0282881C269E8100036774640000001B52454C2C0D0A50524E2C6973646E2A2C2C4E4554352A2C0D0A0D0A18800006000400000008224204677464011B52454C2C0D0A50524E2C6973646E2A2C2C4E4554352A2C0D0A0D0A
h225TerminateRequest: Q.931 RELEASE COMPLETE sent from fd=2. Call state changed to [Null].
015418: Mar 22 11:45:16.614: h323chan_chn_process_read_socket: fd=4 of type ACCEPTED has data

015419: Mar 22 11:45:16.615: //10088/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

015420: Mar 22 11:45:16.615: cc_api_get_xcode_stream : 4977
015421: Mar 22 11:45:16.615: //10088/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

015422: Mar 22 11:45:16.615: cc_api_get_xcode_stream : 4977
015423: Mar 22 11:45:16.616: h323chan_chn_process_read_socket: fd=4 of type ACCEPTED has data

015424: Mar 22 11:45:16.619: h323chan_chn_process_read_socket: fd=4 of type ACCEPTED has data

015425: Mar 22 11:45:16.619: h323chan_recvdata: Connection lost fd=4
015426: Mar 22 11:45:16.724: h323chan_chn_process_read_socket: fd=2 of type ACCEPTED has data

015427: Mar 22 11:45:16.724: h323chan_recvdata: Connection lost fd=2h323chan_chn_close: Calls[1] Exist on socketfd=2 Owner[2]

015428: Mar 22 11:45:16.724: h323chan_close: TCP connection from fd=2 closed
015429: Mar 22 11:45:16.724: //10088/0046769A4408/CCAPI/cc_api_call_disconnected:
Cause Value=16, Interface=0x7FA369C9A140, Call Id=10088
015430: Mar 22 11:45:16.724: //10088/0046769A4408/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=TRUE, Cause Value=16, Retry Count=0)
015431: Mar 22 11:45:16.725: //10088/0046769A4408/CCAPI/ccConferenceDestroy:
Conference Id=0x12C6, Tag=0x0
015432: Mar 22 11:45:16.725: //10088/0046769A4408/CCAPI/ccConferenceDestroy:

015433: Mar 22 11:45:16.725: confID:0x12C6; callEntry1 callID1:0x2768, type:1; callEntry2 callID2:0x2769, type:6

015434: Mar 22 11:45:16.725: //10088/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

015435: Mar 22 11:45:16.725: cc_api_get_xcode_stream : 4977
015436: Mar 22 11:45:16.725: //10088/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

015437: Mar 22 11:45:16.725: cc_api_get_xcode_stream : 4977
015438: Mar 22 11:45:16.725: //10088/0046769A4408/CCAPI/cc_api_bridge_drop_done:
Conference Id=0x12C6, Source Interface=0x7FA369C9A140, Source Call Id=10088,
Destination Call Id=10089, Disposition=0x0, Tag=0x0
015439: Mar 22 11:45:16.725: //10089/0046769A4408/CCAPI/cc_api_bridge_drop_done:
Conference Id=0x12C6, Source Interface=0x7FA373D386B8, Source Call Id=10089,
Destination Call Id=10088, Disposition=0x0, Tag=0x0
015440: Mar 22 11:45:16.725: //10088/0046769A4408/CCAPI/cc_generic_bridge_done:
Conference Id=0x12C6, Source Interface=0x7FA373D386B8, Source Call Id=10089,
Destination Call Id=10088, Disposition=0x0, Tag=0x0
015441: Mar 22 11:45:16.726: //10088/0046769A4408/CCAPI/ccCallDisconnect:
Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16)
015442: Mar 22 11:45:16.726: //10088/0046769A4408/CCAPI/ccCallDisconnect:
Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
015443: Mar 22 11:45:16.726: //10088/0046769A4408/CCAPI/cc_api_get_transfer_info:
Transfer Number=NULL
015444: Mar 22 11:45:16.726: //10089/0046769A4408/CCAPI/ccCallDisconnect:
Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16)
015445: Mar 22 11:45:16.727: //10089/0046769A4408/CCAPI/ccCallDisconnect:
Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
015446: Mar 22 11:45:16.727: //10089/0046769A4408/CCAPI/cc_api_get_transfer_info:
Transfer Number=NULL
015447: Mar 22 11:45:16.760: //10089/0046769A4408/CCAPI/cc_api_call_disc_cause_update:
Cause Value=16
015448: Mar 22 11:45:16.760: //10089/0046769A4408/CCAPI/cc_api_call_disc_cause_update:
Call Entry(Disconnect Cause=16)
015449: Mar 22 11:45:16.760: //10088/0046769A4408/CCAPI/cc_api_get_transfer_info:
Transfer Number=NULL
015450: Mar 22 11:45:16.761: h323chan_close: TCP connection from fd=4 closed
015451: Mar 22 11:45:16.761: h323chan_close: TCP connection from fd=3 closed
015452: Mar 22 11:45:16.761: //10088/0046769A4408/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x7FA369C9A140, Tag=0x0, Call Id=10088,
Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
015453: Mar 22 11:45:16.761: //10088/0046769A4408/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
015454: Mar 22 11:45:16.761: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

015455: Mar 22 11:45:16.761: :cc_free_feature_vsa freeing 7FA36F5CB600
015456: Mar 22 11:45:16.761: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

015457: Mar 22 11:45:16.761: vsacount in free is 5
015458: Mar 22 11:45:16.762: ISDN Se0/1/0:15 Q931: TX -> RELEASE pd = 8 callref = 0x0CDC
015459: Mar 22 11:45:16.765: //10089/0046769A4408/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x7FA373D386B8, Tag=0x0, Call Id=10089,
Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
015460: Mar 22 11:45:16.765: //10089/0046769A4408/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
015461: Mar 22 11:45:16.765: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

015462: Mar 22 11:45:16.765: :cc_free_feature_vsa freeing 7FA36F5CB8A0
015463: Mar 22 11:45:16.765: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

015464: Mar 22 11:45:16.765: vsacount in free is 4
015465: Mar 22 11:45:16.808: ISDN Se0/1/0:15 Q931: RX <- RELEASE_COMP pd = 8 callref = 0x8CDC
015466: Mar 22 11:45:25.068: ISDN Se0/1/0:15 Q931: RX <- STATUS_ENQ pd = 8 callref = 0x8CDF
015467: Mar 22 11:45:25.069: ISDN Se0/1/0:15 Q931: TX -> STATUS pd = 8 callref = 0x0CDF
Cause i = 0x809E - Response to STATUS ENQUIRY or number unassigned
Call State i = 0x0A
015468: Mar 22 11:45:38.390: h323chan_chn_process_read_socket: fd=9 of type ACCEPTED has data

015469: Mar 22 11:45:38.390: //10102/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

015470: Mar 22 11:45:38.390: cc_api_get_xcode_stream : 4977
015471: Mar 22 11:45:38.390: //10102/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

015472: Mar 22 11:45:38.390: cc_api_get_xcode_stream : 4977
015473: Mar 22 11:45:38.391: h323chan_chn_process_read_socket: fd=9 of type ACCEPTED has data

015474: Mar 22 11:45:38.393: h323chan_chn_process_read_socket: fd=7 of type ACCEPTED has data

Hex representation of the SETUP TPKT received: 080208495A080280907E0022052580060008914A00051100110000F33D34A74931AB49080E030A1FE94B10800100
h225ParseData: Q.931 RELEASE COMPLETE received on fd=7
015475: Mar 22 11:45:38.394: //10102/00F33D344908/CCAPI/cc_api_call_disconnected:
Cause Value=16, Interface=0x7FA369C9A140, Call Id=10102
015476: Mar 22 11:45:38.394: //10102/00F33D344908/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=TRUE, Cause Value=16, Retry Count=0)
015477: Mar 22 11:45:38.394: //10102/00F33D344908/CCAPI/ccConferenceDestroy:
Conference Id=0x12CD, Tag=0x0
015478: Mar 22 11:45:38.394: //10102/00F33D344908/CCAPI/ccConferenceDestroy:

015479: Mar 22 11:45:38.394: confID:0x12CD; callEntry1 callID1:0x2776, type:1; callEntry2 callID2:0x2777, type:6

015480: Mar 22 11:45:38.394: //10102/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

015481: Mar 22 11:45:38.394: cc_api_get_xcode_stream : 4977
015482: Mar 22 11:45:38.394: //10102/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

015483: Mar 22 11:45:38.395: cc_api_get_xcode_stream : 4977
015484: Mar 22 11:45:38.395: //10102/00F33D344908/CCAPI/cc_api_bridge_drop_done:
Conference Id=0x12CD, Source Interface=0x7FA369C9A140, Source Call Id=10102,
Destination Call Id=10103, Disposition=0x0, Tag=0x0
015485: Mar 22 11:45:38.395: //10103/00F33D344908/CCAPI/cc_api_bridge_drop_done:
Conference Id=0x12CD, Source Interface=0x7FA373D386B8, Source Call Id=10103,
Destination Call Id=10102, Disposition=0x0, Tag=0x0
015486: Mar 22 11:45:38.395: //10102/00F33D344908/CCAPI/cc_generic_bridge_done:
Conference Id=0x12CD, Source Interface=0x7FA373D386B8, Source Call Id=10103,
Destination Call Id=10102, Disposition=0x0, Tag=0x0
015487: Mar 22 11:45:38.396: h323chan_chn_process_read_socket: fd=9 of type ACCEPTED has data

015488: Mar 22 11:45:38.396: h323chan_recvdata: Connection lost fd=9
015489: Mar 22 11:45:38.396: //10102/00F33D344908/CCAPI/ccCallDisconnect:
Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16)
015490: Mar 22 11:45:38.396: //10102/00F33D344908/CCAPI/ccCallDisconnect:
Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
015491: Mar 22 11:45:38.396: //10102/00F33D344908/CCAPI/cc_api_get_transfer_info:
Transfer Number=NULL
015492: Mar 22 11:45:38.396: //10103/00F33D344908/CCAPI/ccCallDisconnect:
Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
015493: Mar 22 11:45:38.396: //10103/00F33D344908/CCAPI/ccCallDisconnect:
Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
015494: Mar 22 11:45:38.396: //10103/00F33D344908/CCAPI/cc_api_get_transfer_info:
Transfer Number=NULL
015495: Mar 22 11:45:38.425: //10102/00F33D344908/CCAPI/cc_api_get_transfer_info:
Transfer Number=NULL
015496: Mar 22 11:45:38.425: compose_TunnelledSignallingMessage_ciscoNo tunnelled content.
Hex representation of the RELEASE COMPLETE TPKT to send.: 080288495A080280907E0022052580060008914A00041100110000F33D34A74931AB49080E030A1FE94B10800100
h225TerminateRequest: Q.931 RELEASE COMPLETE sent from fd=7. Call state changed to [Null].
015497: Mar 22 11:45:38.426: h323chan_close: TCP connection from fd=7 closed
015498: Mar 22 11:45:38.426: h323chan_close: TCP connection from fd=9 closed
015499: Mar 22 11:45:38.426: h323chan_close: TCP connection from fd=8 closed
015500: Mar 22 11:45:38.426: //10102/00F33D344908/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x7FA369C9A140, Tag=0x0, Call Id=10102,

R0g22 · ‎03-22-2018

I believe those IEC are being generated by the IOS post receiving a disconnect with PI -

015408: Mar 22 11:45:16.609: ISDN Se0/1/0:15 Q931: RX <- DISCONNECT pd = 8 callref = 0x8CDC
Cause i = 0x8090 - Normal call clearing
Progress Ind i = 0x8288 - In-band info or appropriate now available

015410: Mar 22 11:45:16.610: ISDN Se0/1/0:15 Q931: call_disc: PI received in disconnect; Postpone sending RELEASE for callid 0x8C5D

This is after a disconnect so not necessarily a call drop or failure issue.

That call lasted for about 322 seconds -

015409: Mar 22 11:45:16.610: %ISDN-6-DISCONNECT: Interface Serial0/1/0:0 disconnected from 8373996267 , call lasted 322 seconds

Now it is odd for your Telco to play a disconnect tone after an active call is being disconnected. This is usually seen when the external cell phone is switched off/DnD etc. So, your issue seems to be with the Telco.

Contact your ISP.