t38modem / Discussion / Help: Communication with a fax number always fail with: EndedByRemoteCongestion

Hi. I've got a fax number that always we try to send it a fax always fail.

T38modem most ot times says:

Call end reason: EndedByRemoteCongestion

And sometimes says:

Call end reason: EndedByLocalUser

What does it mean?

We use the followin version of t38modem and libraries:

[root@presesfax log]# t38modem -version
T38Modem Version 3.15.2
 (OPAL-3.10.15/3.10.15 (svn:30298), PTLIB-2.10.15/2.10.15 (svn:30295)) by Frolov,Holtschneider,Davidson on Unix Linux (2.6.32-504.el6.x86_64-x86_64)

Follow it's a part of a call log:

2017/12/20 14:56:43.548 ttyx2(e):8...da334da700 --> ATDT0,925622487
2017/12/20 14:56:43.548 ttyx2(e):8...da334da700 Dial string: 0925622487@LV - OK
2017/12/20 14:56:43.548 ttyx2(e):8...da334da700 ModemEndPoint::OnMyCallback command=dial extra=5
2017/12/20 14:56:43.549 ttyx2(e):8...da334da700 PseudoModemQ::Dequeue ttyx2
2017/12/20 14:56:43.549 ttyx2(e):8...da334da700 MyManager::OnMyCallback SetUpCall(modem:, 0925622487@+ttyx2)
2017/12/20 14:56:43.549 ttyx2(e):8...da334da700 OpalMan Set up call from modem: to 0925622487@+ttyx2
2017/12/20 14:56:43.549 ttyx2(e):8...da334da700 Call    Created Call[Cd1c1dbc71]
2017/12/20 14:56:43.549 ttyx2(e):8...da334da700 OpalMan Set up connection to "modem:"
2017/12/20 14:56:43.549 ttyx2(e):8...da334da700 ModemEndPoint::MakeConnection modem:
2017/12/20 14:56:43.549 ttyx2(e):8...da334da700 OpalCon Created connection Call[Cd1c1dbc71]-EP<modem>[modem:/Cd1c1dbc71/0]
2017/12/20 14:56:43.549 ttyx2(e):8...da334da700 ModemConnection::SetUpConnection Call[Cd1c1dbc71]-EP<modem>[modem:/Cd1c1dbc71/0]
2017/12/20 14:56:43.549 ttyx2(e):8...da334da700 OpalCon SetPhase from UninitialisedPhase to SetUpPhase for Call[Cd1c1dbc71]-EP<modem>[modem:/Cd1c1dbc71/0]
2017/12/20 14:56:43.549 ttyx2(e):8...da334da700 OpalMan OnIncoming connection Call[Cd1c1dbc71]-EP<modem>[modem:/Cd1c1dbc71/0]
2017/12/20 14:56:43.550 ttyx2(e):8...da334da700 OpalMan Set up connection to "h323:0925622487@10.49.6.71"
2017/12/20 14:56:43.550 ttyx2(e):8...da334da700 H323    Making call to: h323:0925622487@10.49.6.71
2017/12/20 14:56:43.550 ttyx2(e):8...da334da700 MyH323EndPoint::CreateConnection for Call[Cd1c1dbc71]
2017/12/20 14:56:43.550 ttyx2(e):8...da334da700 OpalCon Created connection Call[Cd1c1dbc71]-EP<h323>[localhost/11754]
2017/12/20 14:56:43.551 ttyx2(e):8...da334da700 H323    Created new connection: localhost/11754
2017/12/20 14:56:43.552 ttyx2(e):8...da334da700 Call[Cd1c1dbc71] from modem:T38Modem%20v3.15.2 to 0925622487@+ttyx2, route to h323:0925622487@10.49.6.71:1720
2017/12/20 14:56:43.552 ttyx2(e):8...da334da700 Outgoing call routed to h323:0925622487@10.49.6.71:1720 for Call[Cd1c1dbc71]-EP<modem>[modem:/Cd1c1dbc71/0]
2017/12/20 14:56:43.552 ttyx2(e):8...da334da700 Call    OnSetUp Call[Cd1c1dbc71]-EP<modem>[modem:/Cd1c1dbc71/0]
2017/12/20 14:56:43.552 ttyx2(e):8...da334da700 MyH323Connection::SetUpConnection Call[Cd1c1dbc71]-EP<h323>[localhost/11754] name=T38Modem v3.15.2
2017/12/20 14:56:43.552 ttyx2(e):8...da334da700 OpalCon OnSetUpConnectionCall[Cd1c1dbc71]-EP<h323>[localhost/11754]
2017/12/20 14:56:43.552 ttyx2(e):8...da334da700 OpalEP  OnSetUpConnection Call[Cd1c1dbc71]-EP<h323>[localhost/11754]
2017/12/20 14:56:43.552 H225 Calle...da332d2700 H225    Started call thread
2017/12/20 14:56:43.552 ttyx2(e):8...da334da700 ModemEndPoint::OnMyCallback request={
calltoken=modem:/Cd1c1dbc71/0
localpartyname=
command=dial
response=confirm
number=0925622487
modemtoken=ttyx2
}
2017/12/20 14:56:43.559 H225 Calle...da332d2700 OpalTCP Started connection to 10.49.6.71:1720 (if=10.60.15.33:54243)
2017/12/20 14:56:43.560 H225 Calle...da332d2700 H225    Sending Setup PDU
2017/12/20 14:56:43.560 H225 Calle...da332d2700 ModemEndPoint::GetMediaFormats
2017/12/20 14:56:43.560 H225 Calle...da332d2700 ModemConnection::GetMediaFormats mediaFormats PCM-16T.38UserInput/RFC2833NamedSignalEvent
2017/12/20 14:56:43.566 H225 Calle...da332d2700 MyH323Connection::GetLocalMediaFormats Remove GSM-AMR
2017/12/20 14:56:43.566 H225 Calle...da332d2700 MyH323Connection::GetLocalMediaFormats Remove iLBC
2017/12/20 14:56:43.566 H225 Calle...da332d2700 MyH323Connection::GetLocalMediaFormats Remove GSM-06.10
2017/12/20 14:56:43.566 H225 Calle...da332d2700 MyH323Connection::GetLocalMediaFormats Remove G.726-40k
2017/12/20 14:56:43.567 H225 Calle...da332d2700 MyH323Connection::GetLocalMediaFormats Remove G.726-32k
2017/12/20 14:56:43.567 H225 Calle...da332d2700 MyH323Connection::GetLocalMediaFormats Remove G.726-24k
2017/12/20 14:56:43.567 H225 Calle...da332d2700 MyH323Connection::GetLocalMediaFormats Remove G.726-16k
2017/12/20 14:56:43.567 H225 Calle...da332d2700 MyH323Connection::GetLocalMediaFormats Remove LPC-10
2017/12/20 14:56:43.567 H225 Calle...da332d2700 MyH323Connection::GetLocalMediaFormats Remove MS-GSM
2017/12/20 14:56:43.567 H225 Calle...da332d2700 MyH323Connection::GetLocalMediaFormats Remove MS-IMA-ADPCM
2017/12/20 14:56:43.567 H225 Calle...da332d2700 MyH323Connection::GetLocalMediaFormats Remove SILK-8
2017/12/20 14:56:43.567 H225 Calle...da332d2700 MyH323Connection::GetLocalMediaFormats Remove SpeexIETFNarrow-11k
2017/12/20 14:56:43.567 H225 Calle...da332d2700 MyH323Connection::GetLocalMediaFormats Remove SpeexIETFNarrow-15k
2017/12/20 14:56:43.567 H225 Calle...da332d2700 MyH323Connection::GetLocalMediaFormats Remove SpeexIETFNarrow-18.2k
2017/12/20 14:56:43.567 H225 Calle...da332d2700 MyH323Connection::GetLocalMediaFormats Remove SpeexIETFNarrow-24.6k
2017/12/20 14:56:43.567 H225 Calle...da332d2700 MyH323Connection::GetLocalMediaFormats Remove SpeexIETFNarrow-5.95k
2017/12/20 14:56:43.567 H225 Calle...da332d2700 MyH323Connection::GetLocalMediaFormats Remove SpeexIETFNarrow-8k
2017/12/20 14:56:43.567 H225 Calle...da332d2700 MyH323Connection::GetLocalMediaFormats Remove SpeexNB
2017/12/20 14:56:43.568 H225 Calle...da332d2700 MyH323Connection::GetLocalMediaFormats Remove SpeexWNarrow-8k
2017/12/20 14:56:43.568 H225 Calle...da332d2700 MyH323Connection::GetLocalMediaFormats Remove iLBC-13k3
2017/12/20 14:56:43.568 H225 Calle...da332d2700 MyH323Connection::GetLocalMediaFormats Remove iLBC-15k2
2017/12/20 14:56:43.568 H225 Calle...da332d2700 MyH323Connection::GetLocalMediaFormats Remove NamedSignalEvent
2017/12/20 14:56:43.568 H225 Calle...da332d2700 H323    Added capability: G.711-uLaw-64k <1>
2017/12/20 14:56:43.568 H225 Calle...da332d2700 H323    Added capability: G.711-ALaw-64k <2>
2017/12/20 14:56:43.568 H225 Calle...da332d2700 H323    Added capability: T.38 <3>
2017/12/20 14:56:43.568 H225 Calle...da332d2700 H323    Found capability: G.711-uLaw-64k <1>
2017/12/20 14:56:43.569 H225 Calle...da332d2700 H323    Found capability: G.711-ALaw-64k <2>
2017/12/20 14:56:43.569 H225 Calle...da332d2700 H323    Found capability: T.38 <3>
2017/12/20 14:56:43.569 H225 Calle...da332d2700 H323    Added capability: UserInput/hookflash <4>
2017/12/20 14:56:43.569 H225 Calle...da332d2700 H323    Added capability: UserInput/basicString <5>
2017/12/20 14:56:43.569 H225 Calle...da332d2700 H323    Added capability: UserInput/dtmf <6>
2017/12/20 14:56:43.569 H225 Calle...da332d2700 H323    Added capability: UserInput/RFC2833 <7>
2017/12/20 14:56:43.569 H225 Calle...da332d2700 H323    Found capability: UserInput/RFC2833 <7>
2017/12/20 14:56:43.569 H225 Calle...da332d2700 H323    SetLocalCapabilities:
 Table:
   G.711-uLaw-64k <1>
   G.711-ALaw-64k <2>
   T.38 <3>
   UserInput/hookflash <4>
   UserInput/basicString <5>
   UserInput/dtmf <6>
   UserInput/RFC2833 <7>
 Set:
   0:
     0:
       G.711-uLaw-64k <1>
       G.711-ALaw-64k <2>
       T.38 <3>
     1:
       UserInput/hookflash <4>
     2:
       UserInput/basicString <5>
       UserInput/dtmf <6>
       UserInput/RFC2833 <7>

2017/12/20 14:56:43.570 H225 Calle...da332d2700 H225    Check for Fast start by local endpoint
2017/12/20 14:56:43.570 H225 Calle...da332d2700 H245    Default OnSelectLogicalChannels, FastStartDisabled
2017/12/20 14:56:43.570 H225 Calle...da332d2700 Call    OpenSourceMediaStreams open for audio session 1 on Call[Cd1c1dbc71]-EP<modem>[modem:/Cd1c1dbc71/0]
2017/12/20 14:56:43.570 H225 Calle...da332d2700 Call    OpenSourceMediaStreams failed with no sink formats for audio session 1 on Call[Cd1c1dbc71]-EP<modem>[modem:/Cd1c1dbc71/0]
2017/12/20 14:56:43.570 H225 Calle...da332d2700 H323    SelectLogicalChannel(1) could not start media stream.
2017/12/20 14:56:43.570 H225 Calle...da332d2700 Call    OpenSourceMediaStreams open for video session 2 on Call[Cd1c1dbc71]-EP<modem>[modem:/Cd1c1dbc71/0]
2017/12/20 14:56:43.570 H225 Calle...da332d2700 Call    OpenSourceMediaStreams failed with no sink formats for video session 2 on Call[Cd1c1dbc71]-EP<modem>[modem:/Cd1c1dbc71/0]
2017/12/20 14:56:43.570 H225 Calle...da332d2700 H323    SelectLogicalChannel(2) could not start media stream.
2017/12/20 14:56:43.571 H225 Calle...da332d2700 H225    Sending PDU: setup
2017/12/20 14:56:43.571 H225 Calle...da332d2700 OpalCon SetPhase from UninitialisedPhase to SetUpPhase for Call[Cd1c1dbc71]-EP<h323>[localhost/11754]
2017/12/20 14:56:43.571 H225 Calle...da332d2700 H225    Reading PDUs: callRef=11754
2017/12/20 14:56:43.581 H225 Calle...da332d2700 H225    Receiving PDU: callProceeding
2017/12/20 14:56:43.581 H225 Calle...da332d2700 H225    Handling PDU: CallProceeding callRef=11754
2017/12/20 14:56:43.581 H225 Calle...da332d2700 H225    Set remote application name: "Cisco IOS 12.x    181/18  "
2017/12/20 14:56:43.581 H225 Calle...da332d2700 H225    Set protocol version to 5 and implying H.245 version 9
2017/12/20 14:56:43.582 H225 Calle...da332d2700 H225    Set remote party name: "10.49.6.71"
2017/12/20 14:56:43.582 H225 Calle...da332d2700 H225    Set remote application name: "CiscoCallManager  1   181/18  "
2017/12/20 14:56:43.582 H225 Calle...da332d2700 OpalCon SetPhase from SetUpPhase to ProceedingPhase for Call[Cd1c1dbc71]-EP<h323>[localhost/11754]
2017/12/20 14:56:43.582 H225 Calle...da332d2700 OpalMan OnProceeding Call[Cd1c1dbc71]-EP<h323>[localhost/11754]
2017/12/20 14:56:43.582 H225 Calle...da332d2700 Call    OnProceeding Call[Cd1c1dbc71]-EP<h323>[localhost/11754]
2017/12/20 14:56:43.582 H225 Calle...da332d2700 H323    InternalEstablishedConnectionCheck: connectionState=AwaitingSignalConnect fastStartState=FastStartDisabled H.245 is unavailable
2017/12/20 14:56:45.917 H225 Calle...da332d2700 H225    Receiving PDU: alerting
2017/12/20 14:56:45.918 H225 Calle...da332d2700 H225    Handling PDU: Alerting callRef=11754
2017/12/20 14:56:45.918 H225 Calle...da332d2700 OpalCon SetPhase from ProceedingPhase to AlertingPhase for Call[Cd1c1dbc71]-EP<h323>[localhost/11754]
2017/12/20 14:56:45.918 H225 Calle...da332d2700 H225    Set protocol version to 5 and implying H.245 version 9
2017/12/20 14:56:45.918 H225 Calle...da332d2700 H225    Set remote party name: "10.49.6.71"
2017/12/20 14:56:45.918 H225 Calle...da332d2700 H225    Set remote application name: "CiscoCallManager  1   181/18  "
2017/12/20 14:56:45.918 H225 Calle...da332d2700 H225    CreateOutgoingControlChannel h245Address = ipAddress {
        ip =  4 octets {
          0a 31 06 47                                        .1.G
        }
        port = 58525
      }
2017/12/20 14:56:45.925 H225 Calle...da332d2700 OpalTCP Started connection to 10.49.6.71:58525 (if=10.60.15.33:44461)
2017/12/20 14:56:45.925 H225 Calle...da332d2700 H225    Received alerting PDU.
2017/12/20 14:56:45.925 H225 Calle...da332d2700 OpalMan OnAlerting Call[Cd1c1dbc71]-EP<h323>[localhost/11754]
2017/12/20 14:56:45.925 H225 Calle...da332d2700 Call    OnAlerting Call[Cd1c1dbc71]-EP<h323>[localhost/11754]
2017/12/20 14:56:45.925 H225 Calle...da332d2700 ModemConnection::SetAlerting Call[Cd1c1dbc71]-EP<modem>[modem:/Cd1c1dbc71/0] 10.49.6.71 0
2017/12/20 14:56:45.925 H225 Calle...da332d2700 OpalCon SetPhase from SetUpPhase to AlertingPhase for Call[Cd1c1dbc71]-EP<modem>[modem:/Cd1c1dbc71/0]
2017/12/20 14:56:45.925 H225 Calle...da332d2700 ModemEngineBody::Request: stConnectWait request={
calltoken=modem:/Cd1c1dbc71/0
command=alerting
}
2017/12/20 14:56:45.925 H225 Calle...da332d2700 H323    InternalEstablishedConnectionCheck: connectionState=AwaitingSignalConnect fastStartState=FastStartDisabled H.245 is unavailable
2017/12/20 14:56:45.925 H.245 Hand...da33291700 H245    Started control channel
2017/12/20 14:56:45.925 H.245 Hand...da33291700 MyH323Connection::GetLocalMediaFormats Remove GSM-AMR
2017/12/20 14:56:45.926 H225 Calle...da332d2700 H225    Receiving PDU: notify
2017/12/20 14:56:45.926 H225 Calle...da332d2700 H225    Handling PDU: NotifyMsg callRef=11754
2017/12/20 14:56:45.926 H.245 Hand...da33291700 MyH323Connection::GetLocalMediaFormats Remove iLBC
2017/12/20 14:56:45.926 H.245 Hand...da33291700 MyH323Connection::GetLocalMediaFormats Remove GSM-06.10
2017/12/20 14:56:45.926 H.245 Hand...da33291700 MyH323Connection::GetLocalMediaFormats Remove G.726-40k
2017/12/20 14:56:45.926 H.245 Hand...da33291700 MyH323Connection::GetLocalMediaFormats Remove G.726-32k
2017/12/20 14:56:45.926 H.245 Hand...da33291700 MyH323Connection::GetLocalMediaFormats Remove G.726-24k
2017/12/20 14:56:45.926 H.245 Hand...da33291700 MyH323Connection::GetLocalMediaFormats Remove G.726-16k
2017/12/20 14:56:45.926 H.245 Hand...da33291700 MyH323Connection::GetLocalMediaFormats Remove LPC-10
2017/12/20 14:56:45.926 H.245 Hand...da33291700 MyH323Connection::GetLocalMediaFormats Remove MS-GSM
2017/12/20 14:56:45.926 H.245 Hand...da33291700 MyH323Connection::GetLocalMediaFormats Remove MS-IMA-ADPCM
2017/12/20 14:56:45.926 H.245 Hand...da33291700 MyH323Connection::GetLocalMediaFormats Remove SILK-8
2017/12/20 14:56:45.926 H.245 Hand...da33291700 MyH323Connection::GetLocalMediaFormats Remove SpeexIETFNarrow-11k
2017/12/20 14:56:45.926 H.245 Hand...da33291700 MyH323Connection::GetLocalMediaFormats Remove SpeexIETFNarrow-15k
2017/12/20 14:56:45.926 H.245 Hand...da33291700 MyH323Connection::GetLocalMediaFormats Remove SpeexIETFNarrow-18.2k
2017/12/20 14:56:45.926 H.245 Hand...da33291700 MyH323Connection::GetLocalMediaFormats Remove SpeexIETFNarrow-24.6k
2017/12/20 14:56:45.927 H.245 Hand...da33291700 MyH323Connection::GetLocalMediaFormats Remove SpeexIETFNarrow-5.95k
2017/12/20 14:56:45.927 H.245 Hand...da33291700 MyH323Connection::GetLocalMediaFormats Remove SpeexIETFNarrow-8k
2017/12/20 14:56:45.927 H.245 Hand...da33291700 MyH323Connection::GetLocalMediaFormats Remove SpeexNB
2017/12/20 14:56:45.927 H.245 Hand...da33291700 MyH323Connection::GetLocalMediaFormats Remove SpeexWNarrow-8k
2017/12/20 14:56:45.927 H.245 Hand...da33291700 MyH323Connection::GetLocalMediaFormats Remove iLBC-13k3
2017/12/20 14:56:45.927 H.245 Hand...da33291700 MyH323Connection::GetLocalMediaFormats Remove iLBC-15k2
2017/12/20 14:56:45.927 H.245 Hand...da33291700 MyH323Connection::GetLocalMediaFormats Remove NamedSignalEvent
2017/12/20 14:56:45.927 H.245 Hand...da33291700 H323    Removing capability: UserInput/hookflash <4>
2017/12/20 14:56:45.927 H.245 Hand...da33291700 H323    Removing capability: UserInput/basicString <5>
2017/12/20 14:56:45.927 H.245 Hand...da33291700 H323    Removing capability: UserInput/dtmf <6>
2017/12/20 14:56:45.927 H.245 Hand...da33291700 H323    Removing capability: UserInput/RFC2833 <7>
2017/12/20 14:56:45.927 H.245 Hand...da33291700 H323    Found capability: G.711-uLaw-64k <1>
2017/12/20 14:56:45.927 H.245 Hand...da33291700 H323    Found capability: G.711-ALaw-64k <2>
2017/12/20 14:56:45.927 H.245 Hand...da33291700 H323    Found capability: T.38 <3>
2017/12/20 14:56:45.927 H.245 Hand...da33291700 H323    Found capability: G.711-uLaw-64k <1>
2017/12/20 14:56:45.928 H.245 Hand...da33291700 H323    Found capability: G.711-ALaw-64k <2>
2017/12/20 14:56:45.928 H.245 Hand...da33291700 H323    Found capability: T.38 <3>
2017/12/20 14:56:45.928 H.245 Hand...da33291700 H323    Added capability: UserInput/hookflash <4>
2017/12/20 14:56:45.928 H.245 Hand...da33291700 H323    Added capability: UserInput/basicString <5>
2017/12/20 14:56:45.928 H.245 Hand...da33291700 H323    Added capability: UserInput/dtmf <6>
2017/12/20 14:56:45.928 H.245 Hand...da33291700 H323    Added capability: UserInput/RFC2833 <7>
2017/12/20 14:56:45.928 H.245 Hand...da33291700 H323    Found capability: UserInput/RFC2833 <7>
2017/12/20 14:56:45.928 H.245 Hand...da33291700 H323    SetLocalCapabilities:
 Table:
   G.711-uLaw-64k <1>
   G.711-ALaw-64k <2>
   T.38 <3>
   UserInput/hookflash <4>
   UserInput/basicString <5>
   UserInput/dtmf <6>
   UserInput/RFC2833 <7>
 Set:
   0:
     0:
       G.711-uLaw-64k <1>
       G.711-ALaw-64k <2>
       T.38 <3>
     1:
       UserInput/hookflash <4>
     2:
       UserInput/basicString <5>
       UserInput/dtmf <6>
       UserInput/RFC2833 <7>

2017/12/20 14:56:45.928 H.245 Hand...da33291700 H245    Sending TerminalCapabilitySet: outSeq=1
2017/12/20 14:56:45.929 H.245 Hand...da33291700 H323T38 OnSendingPDU for capability
2017/12/20 14:56:45.929 H.245 Hand...da33291700 H245    Sending PDU: request terminalCapabilitySet
2017/12/20 14:56:45.930 H.245 Hand...da33291700 H245    Sending MasterSlaveDetermination
2017/12/20 14:56:45.930 H.245 Hand...da33291700 H245    Sending PDU: request masterSlaveDetermination
2017/12/20 14:56:45.930 H225 Calle...da332d2700 H225    Set protocol version to 5 and implying H.245 version 9
2017/12/20 14:56:45.930 H225 Calle...da332d2700 H323    InternalEstablishedConnectionCheck: connectionState=AwaitingSignalConnect fastStartState=FastStartDisabled H.245 is unavailable
2017/12/20 14:56:45.936 H.245 Hand...da33291700 H245    Receiving PDU: request terminalCapabilitySet
2017/12/20 14:56:45.936 H.245 Hand...da33291700 H245    Set protocol version to 10
2017/12/20 14:56:45.936 H.245 Hand...da33291700 H245    Received TerminalCapabilitySet: state=InProgress pduSeq=1 inSeq=4294967295
2017/12/20 14:56:45.936 H.245 Hand...da33291700 H323    Added capability: G.711-uLaw-64k <1>
2017/12/20 14:56:45.936 H.245 Hand...da33291700 H323    Added capability: G.711-ALaw-64k <2>
2017/12/20 14:56:45.937 H.245 Hand...da33291700 H323    Added capability: T.38 <3>
2017/12/20 14:56:45.937 H.245 Hand...da33291700 H323    Added capability: UserInput/hookflash <4>
2017/12/20 14:56:45.937 H.245 Hand...da33291700 H323    Added capability: UserInput/basicString <5>
2017/12/20 14:56:45.937 H.245 Hand...da33291700 H323    Added capability: UserInput/dtmf <6>
2017/12/20 14:56:45.937 H.245 Hand...da33291700 H323    Added capability: UserInput/RFC2833 <7>
2017/12/20 14:56:45.937 H.245 Hand...da33291700 H323    Found capability: G.711-uLaw-64k <1>
2017/12/20 14:56:45.937 H.245 Hand...da33291700 H323    Found capability: G.711-ALaw-64k <2>
2017/12/20 14:56:45.937 H.245 Hand...da33291700 H323    Found capability: T.38 <3>
2017/12/20 14:56:45.938 H.245 Hand...da33291700 H323    Found capability: UserInput/hookflash <4>
2017/12/20 14:56:45.938 H.245 Hand...da33291700 H323    Found capability: UserInput/basicString <5>
2017/12/20 14:56:45.938 H.245 Hand...da33291700 H323    Found capability: UserInput/dtmf <6>
2017/12/20 14:56:45.938 H.245 Hand...da33291700 H323    Found capability: UserInput/RFC2833 <7>
2017/12/20 14:56:45.938 H.245 Hand...da33291700 H323    Found capability: G.711-ALaw-64k <2>
2017/12/20 14:56:45.938 H.245 Hand...da33291700 H323    Found capability: G.711-uLaw-64k <1>
2017/12/20 14:56:45.938 H.245 Hand...da33291700 H323    Added capability: G.722-64k <8>
2017/12/20 14:56:45.938 H.245 Hand...da33291700 H323    Added capability: G.722.1-24k <9>
2017/12/20 14:56:45.939 H.245 Hand...da33291700 H323    Added capability: G.722.1-32k <10>
2017/12/20 14:56:45.939 H.245 Hand...da33291700 H323    Added capability: G.722.2 <11>
2017/12/20 14:56:45.939 H.245 Hand...da33291700 H323    Added capability: G.726-16k <12>
2017/12/20 14:56:45.939 H.245 Hand...da33291700 H323    Added capability: G.726-24k <13>
2017/12/20 14:56:45.939 H.245 Hand...da33291700 H323    Added capability: G.726-32k <14>
2017/12/20 14:56:45.939 H.245 Hand...da33291700 H323    Added capability: G.726-40k <15>
2017/12/20 14:56:45.939 H.245 Hand...da33291700 H323    Added capability: GSM-06.10 <16>
2017/12/20 14:56:45.940 H.245 Hand...da33291700 H323    Added capability: GSM-AMR <17>
2017/12/20 14:56:45.940 H.245 Hand...da33291700 H323    Added capability: H.261 <18>
2017/12/20 14:56:45.940 H.245 Hand...da33291700 H323    Added capability: H.263 <19>
2017/12/20 14:56:45.940 H.245 Hand...da33291700 H323    Added capability: H.263plus <20>
2017/12/20 14:56:45.940 H.245 Hand...da33291700 H323    Added capability: H.264 <21>
2017/12/20 14:56:45.941 H.245 Hand...da33291700 H323    Added capability: LPC-10 <22>
2017/12/20 14:56:45.941 H.245 Hand...da33291700 H323    Added capability: MPEG4 <23>
2017/12/20 14:56:45.941 H.245 Hand...da33291700 H323    Added capability: MS-GSM <24>
2017/12/20 14:56:45.941 H.245 Hand...da33291700 H323    Added capability: MS-IMA-ADPCM <25>
2017/12/20 14:56:45.941 H.245 Hand...da33291700 H323    Added capability: SpeexIETFNarrow-11k <26>
2017/12/20 14:56:45.941 H.245 Hand...da33291700 H323    Added capability: SpeexIETFNarrow-15k <27>
2017/12/20 14:56:45.942 H.245 Hand...da33291700 H323    Added capability: SpeexIETFNarrow-18.2k <28>
2017/12/20 14:56:45.942 H.245 Hand...da33291700 H323    Added capability: SpeexIETFNarrow-24.6k <29>
2017/12/20 14:56:45.942 H.245 Hand...da33291700 H323    Added capability: SpeexIETFNarrow-5.95k <30>
2017/12/20 14:56:45.942 H.245 Hand...da33291700 H323    Added capability: SpeexIETFNarrow-8k <31>
2017/12/20 14:56:45.942 H.245 Hand...da33291700 H323    Added capability: SpeexIETFWide-20.6k <32>
2017/12/20 14:56:45.942 H.245 Hand...da33291700 H323    Added capability: SpeexWNarrow-8k <33>
2017/12/20 14:56:45.943 H.245 Hand...da33291700 H323    Added capability: SpeexWide-20.6k <34>
2017/12/20 14:56:45.943 H.245 Hand...da33291700 H323    Found capability: T.38 <3>
2017/12/20 14:56:45.943 H.245 Hand...da33291700 H323    Found capability: UserInput/RFC2833 <7>
2017/12/20 14:56:45.943 H.245 Hand...da33291700 H323    Found capability: UserInput/basicString <5>
2017/12/20 14:56:45.943 H.245 Hand...da33291700 H323    Found capability: UserInput/dtmf <6>
2017/12/20 14:56:45.943 H.245 Hand...da33291700 H323    Added capability: UserInput/generalString <35>
2017/12/20 14:56:45.943 H.245 Hand...da33291700 H323    Found capability: UserInput/hookflash <4>
2017/12/20 14:56:45.944 H.245 Hand...da33291700 H323    Added capability: UserInput/iA5String <36>
2017/12/20 14:56:45.944 H.245 Hand...da33291700 H323    Added capability: iLBC <37>
2017/12/20 14:56:45.944 H.245 Hand...da33291700 H323    Added capability: iLBC-13k3 <38>
2017/12/20 14:56:45.944 H.245 Hand...da33291700 H323    Added capability: iLBC-15k2 <39>
2017/12/20 14:56:45.944 H.245 Hand...da33291700 H323    Found capability: UserInput/hookflash <4>
2017/12/20 14:56:45.944 H.245 Hand...da33291700 H323    Found capability: UserInput/basicString <5>
2017/12/20 14:56:45.945 H.245 Hand...da33291700 H323    Found capability: UserInput/dtmf <6>
2017/12/20 14:56:45.945 H.245 Hand...da33291700 H323    Found capability: UserInput/RFC2833 <7>
2017/12/20 14:56:45.945 H.245 Hand...da33291700 H323    Added capability: H.239-Video+H.239-Video <40>
2017/12/20 14:56:45.945 H.245 Hand...da33291700 H323    Added capability: H.239-Control <41>
2017/12/20 14:56:45.946 H.245 Hand...da33291700 H323    Added capability: G.711-uLaw-64k <3>
2017/12/20 14:56:45.946 H.245 Hand...da33291700 H323    Added capability: G.711-ALaw-64k <4>
2017/12/20 14:56:45.947 H.245 Hand...da33291700 H323    Added capability: UserInput/dtmf <5>
2017/12/20 14:56:45.947 H.245 Hand...da33291700 H323    Added capability: UserInput/basicString <6>
2017/12/20 14:56:45.947 H.245 Hand...da33291700 H323    Added capability: UserInput/hookflash <44>
2017/12/20 14:56:45.947 H.245 Hand...da33291700 H323    Found capability: G.711-uLaw-64k <3>
2017/12/20 14:56:45.947 H.245 Hand...da33291700 H323    Found capability: G.711-ALaw-64k <4>
2017/12/20 14:56:45.947 H.245 Hand...da33291700 H323    Found capability: UserInput/dtmf <5>
2017/12/20 14:56:45.947 H.245 Hand...da33291700 H323    Found capability: UserInput/basicString <6>
2017/12/20 14:56:45.947 H.245 Hand...da33291700 H323    Found capability: UserInput/hookflash <44>
2017/12/20 14:56:45.947 H.245 Hand...da33291700 H323    Received capability set, is accepted
2017/12/20 14:56:45.947 H.245 Hand...da33291700 H245    TerminalCapabilitySet already in progress: outSeq=1
2017/12/20 14:56:45.948 H.245 Hand...da33291700 H245    Sending PDU: response terminalCapabilitySetAck
2017/12/20 14:56:45.948 H.245 Hand...da33291700 H323    InternalEstablishedConnectionCheck: connectionState=AwaitingSignalConnect fastStartState=FastStartDisabled H.245 is unavailable
2017/12/20 14:56:45.948 H.245 Hand...da33291700 H245    Receiving PDU: response terminalCapabilitySetAck
2017/12/20 14:56:45.948 H.245 Hand...da33291700 H245    Received TerminalCapabilitySetAck: state=InProgress pduSeq=1 outSeq=1
2017/12/20 14:56:45.948 H.245 Hand...da33291700 H245    TerminalCapabilitySet Sent.
2017/12/20 14:56:45.948 H.245 Hand...da33291700 H323    InternalEstablishedConnectionCheck: connectionState=AwaitingSignalConnect fastStartState=FastStartDisabled H.245 is unavailable
2017/12/20 14:56:45.954 H.245 Hand...da33291700 H245    Receiving PDU: response masterSlaveDeterminationAck
2017/12/20 14:56:45.954 H.245 Hand...da33291700 H245    Received MasterSlaveDeterminationAck: state=Outgoing
2017/12/20 14:56:45.954 H.245 Hand...da33291700 H245    MasterSlaveDetermination: remote is master
2017/12/20 14:56:45.954 H.245 Hand...da33291700 H245    Sending PDU: response masterSlaveDeterminationAck
2017/12/20 14:56:45.954 H.245 Hand...da33291700 H323    InternalEstablishedConnectionCheck: connectionState=AwaitingSignalConnect fastStartState=FastStartDisabled H.245 is ready
2017/12/20 14:56:45.961 H.245 Hand...da33291700 H245    Receiving PDU: request openLogicalChannel
2017/12/20 14:56:45.961 H.245 Hand...da33291700 H245    Received open channel: R-1, state=Released
2017/12/20 14:56:45.961 H.245 Hand...da33291700 H323    CreateLogicalChannel - forward channel
2017/12/20 14:56:45.961 H.245 Hand...da33291700 Call    IsMediaBypassPossible Call[Cd1c1dbc71]-EP<h323>[localhost/11754] session 1
2017/12/20 14:56:45.961 H.245 Hand...da33291700 OpalMan IsMediaBypassPossible: session 1
2017/12/20 14:56:45.962 H.245 Hand...da33291700 RTP Cannot find RTP session 1
2017/12/20 14:56:45.962 H.245 Hand...da33291700 RTP_UDP Session 1 created: 10.60.15.33:10000-10001 ssrc=3517056168
2017/12/20 14:56:45.962 H.245 Hand...da33291700 RTP Creating new session RTP_UDP
2017/12/20 14:56:45.962 H.245 Hand...da33291700 Call    IsMediaBypassPossible Call[Cd1c1dbc71]-EP<h323>[localhost/11754] session 1
2017/12/20 14:56:45.962 H.245 Hand...da33291700 OpalMan IsMediaBypassPossible: session 1
2017/12/20 14:56:45.962 H.245 Hand...da33291700 RTP Found existing RTP session 1
2017/12/20 14:56:45.963 H.245 Hand...da33291700 RTP Found existing media session 1
2017/12/20 14:56:45.963 H.245 Hand...da33291700 H323RTP Receiver created using session 1
2017/12/20 14:56:45.963 H.245 Hand...da33291700 LogChan Bandwidth requested/used = 64.0/0.0 kb/s
2017/12/20 14:56:45.963 H.245 Hand...da33291700 OpalCon Bandwidth request of 64.0kb/s, available: 10000.0kb/s
2017/12/20 14:56:45.963 H.245 Hand...da33291700 H323RTP OnReceivedPDU for channel: R-1
2017/12/20 14:56:45.963 H.245 Hand...da33291700 RTP_UDP Session 1, SetRemoteSocketInfo: control channel, new=10.49.6.71:4001, local=10.60.15.33:10000-10001, remote=0.0.0.0:0-0
2017/12/20 14:56:45.963 H.245 Hand...da33291700 QOS ApplyQoS DSCP=-1
2017/12/20 14:56:45.963 H.245 Hand...da33291700 QOS ApplyQoS -1 Not Defined
2017/12/20 14:56:45.963 H.245 Hand...da33291700 QOS ApplyQoS DSCP=-1
2017/12/20 14:56:45.963 H.245 Hand...da33291700 QOS ApplyQoS -1 Not Defined
2017/12/20 14:56:45.964 H.245 Hand...da33291700 H323RTP OnSendOpenAck
2017/12/20 14:56:45.964 H.245 Hand...da33291700 RTP OnSendingAckPDU
2017/12/20 14:56:45.964 H.245 Hand...da33291700 H323RTP Sending open logical channel ACK: sessionID=1
2017/12/20 14:56:45.964 H.245 Hand...da33291700 Call    OpenSourceMediaStreams open for audio session 1 (G.711-uLaw-64k) on Call[Cd1c1dbc71]-EP<h323>[localhost/11754]
2017/12/20 14:56:45.964 H.245 Hand...da33291700 ModemEndPoint::GetMediaFormats
2017/12/20 14:56:45.964 H.245 Hand...da33291700 ModemConnection::GetMediaFormats mediaFormats PCM-16T.38UserInput/RFC2833NamedSignalEvent
2017/12/20 14:56:45.966 H.245 Hand...da33291700 Call    Selected media formats G.711-uLaw-64k -> PCM-16
2017/12/20 14:56:45.966 H.245 Hand...da33291700 H323    OpenMediaStream using channel R-1 for session 1
2017/12/20 14:56:45.966 H.245 Hand...da33291700 RTP Found existing RTP session 1
2017/12/20 14:56:45.967 H.245 Hand...da33291700 OpalMan OnOpenMediaStream Call[Cd1c1dbc71]-EP<h323>[localhost/11754],OpalRTPMediaStream[0x7fd9e8011bb0]-Source-G.711-uLaw-64k
2017/12/20 14:56:45.967 H.245 Hand...da33291700 RTP_UDP Session 1, Shutting down read.
2017/12/20 14:56:45.967 H.245 Hand...da33291700 RTP_UDP Session 1 reopened for reading
2017/12/20 14:56:45.967 H.245 Hand...da33291700 ModemConnection::CreateMediaStream Call[Cd1c1dbc71]-EP<modem>[modem:/Cd1c1dbc71/0] mediaFormat=PCM-16 sessionID=1 isSource=0
2017/12/20 14:56:45.967 H.245 Hand...da33291700 ttyx2 AudioEngine AudioEngine
2017/12/20 14:56:45.967 H.245 Hand...da33291700 ttyx2 AudioEngine Attach
2017/12/20 14:56:45.967 H.245 Hand...da33291700 ttyx2 AudioEngine OnAttach Attached
2017/12/20 14:56:45.967 H.245 Hand...da33291700 ttyx2 AudioEngine OnResetModemState
2017/12/20 14:56:45.967 H.245 Hand...da33291700 ttyx2 AudioEngine ChangeModemClass to mcFax
2017/12/20 14:56:45.967 H.245 Hand...da33291700 ttyx2 AudioEngine OnChangeModemClass to mcFax
2017/12/20 14:56:45.967 H.245 Hand...da33291700 ttyx2 AudioEngine SendOnIdle dtNone
2017/12/20 14:56:45.967 H.245 Hand...da33291700 ModemEngineBody::_AttachEngine Attached mceAudio
2017/12/20 14:56:45.968 H.245 Hand...da33291700 ModemEngineBody::NewPtrEngine created pointer for engine mceAudio
2017/12/20 14:56:45.968 H.245 Hand...da33291700 AudioModemMediaStream::Open AudioModemMediaStream[0x7fd9e8017cc0]-Sink-PCM-16
2017/12/20 14:56:45.968 H.245 Hand...da33291700 ttyx2 AudioEngine OpenIn: open 0x7fd9e8017cc0
2017/12/20 14:56:45.968 H.245 Hand...da33291700 OpalMan OnOpenMediaStream Call[Cd1c1dbc71]-EP<modem>[modem:/Cd1c1dbc71/0],AudioModemMediaStream[0x7fd9e8017cc0]-Sink-PCM-16
2017/12/20 14:56:45.968 H.245 Hand...da33291700 OpalCon Opened sink stream Cd1c1dbc71_1 with format PCM-16
2017/12/20 14:56:45.968 H.245 Hand...da33291700 RTP_UDP Session 1, Shutting down read.
2017/12/20 14:56:45.968 H.245 Hand...da33291700 RTP_UDP Session 1 reopened for reading
2017/12/20 14:56:45.968 H.245 Hand...da33291700 Patch   Created Sink: format=PCM-16
2017/12/20 14:56:45.968 H.245 Hand...da33291700 Codec   G711-uLaw-64k decoder created
2017/12/20 14:56:45.969 H.245 Hand...da33291700 Patch   Added media stream sink AudioModemMediaStream[0x7fd9e8017cc0]-Sink-PCM-16 using transcoder G.711-uLaw-64k->PCM-16, data size=3840
2017/12/20 14:56:45.969 H.245 Hand...da33291700 Media   RTP data size cannot be changed to 160, fixed at 2048
2017/12/20 14:56:45.969 H.245 Hand...da33291700 OpalCon Sink stream of connection Call[Cd1c1dbc71]-EP<modem>[modem:/Cd1c1dbc71/0] uses patch Patch[0x7fd9e8018080] OpalRTPMediaStream[0x7fd9e8011bb0]-Source-G.711-uLaw-64k -> AudioModemMediaStream[0x7fd9e8017cc0]-Sink-PCM-16
2017/12/20 14:56:45.969 H.245 Hand...da33291700 OpalCon Source stream of connection Call[Cd1c1dbc71]-EP<h323>[localhost/11754] uses patch Patch[0x7fd9e8018080] OpalRTPMediaStream[0x7fd9e8011bb0]-Source-G.711-uLaw-64k -> AudioModemMediaStream[0x7fd9e8017cc0]-Sink-PCM-16
2017/12/20 14:56:45.969 H.245 Hand...da33291700 RTPCon  Adding RFC2833 receive handler
2017/12/20 14:56:45.969 H.245 Hand...da33291700 RTPCon  Adding Cisco NSE receive handler
2017/12/20 14:56:45.969 H.245 Hand...da33291700 H323    Started receiving logical channel: G.711-uLaw-64k <1>
2017/12/20 14:56:45.970 H.245 Hand...da33291700 H245    Sending PDU: response openLogicalChannelAck
2017/12/20 14:56:45.970 H.245 Hand...da33291700 H323    InternalEstablishedConnectionCheck: connectionState=AwaitingSignalConnect fastStartState=FastStartDisabled H.245 is ready
2017/12/20 14:56:45.990 Media Patc...da33250700 RTP Session 1, first receive data: ver=2 pt=PCMU psz=160 m=1 x=0 seq=39049 ts=160 src=79889 ccnt=0
2017/12/20 14:56:57.927 H.245 Hand...da33291700 H245    Receiving PDU: request closeLogicalChannel
2017/12/20 14:56:57.927 H.245 Hand...da33291700 H245    Received close channel: R-1, state=Established
2017/12/20 14:56:57.927 H225 Calle...da332d2700 H225    Receiving PDU: releaseComplete
2017/12/20 14:56:57.927 H225 Calle...da332d2700 H225    Handling PDU: ReleaseComplete callRef=11754
2017/12/20 14:56:57.927 H.245 Hand...da33291700 RTP_UDP Session 1, Shutting down read.
2017/12/20 14:56:57.927 H.245 Hand...da33291700 OpalCon No filter to remove for stage PCM-16
2017/12/20 14:56:57.927 H.245 Hand...da33291700 OpalCon No filter to remove for stage YUV420P
2017/12/20 14:56:57.927 H.245 Hand...da33291700 OpalCon No filter to remove for stage PCM-16
2017/12/20 14:56:57.927 H.245 Hand...da33291700 Patch   Closing media patch Patch[0x7fd9e8018080] OpalRTPMediaStream[0x7fd9e8011bb0]-Source-G.711-uLaw-64k -> AudioModemMediaStream[0x7fd9e8017cc0]-Sink-PCM-16
2017/12/20 14:56:57.927 H.245 Hand...da33291700 OpalCon No filter to remove for stage PCM-16
2017/12/20 14:56:57.927 H.245 Hand...da33291700 OpalCon No filter to remove for stage YUV420P
2017/12/20 14:56:57.927 H.245 Hand...da33291700 OpalCon No filter to remove for stage PCM-16
2017/12/20 14:56:57.927 Media Patc...da33250700 PTLib   Thread ended: name="Media Patch:0x7fda33250700", real=11.958, kernel=0.010 (0%), user=0.100 (0.8%), both=0.110 (0.9%)
2017/12/20 14:56:57.928 H.245 Hand...da33291700 OpalCon No filter to remove for stage PCM-16
2017/12/20 14:56:57.928 H.245 Hand...da33291700 Patch   Removing sink AudioModemMediaStream[0x7fd9e8017cc0]-Sink-PCM-16 from Patch[0x7fd9e8018080] OpalRTPMediaStream[0x7fd9e8011bb0]-Source-G.711-uLaw-64k -> AudioModemMediaStream[0x7fd9e8017cc0]-Sink-PCM-16
2017/12/20 14:56:57.928 H.245 Hand...da33291700 OpalCon Removed media stream AudioModemMediaStream[0x7fd9e8017cc0]-Sink-PCM-16
2017/12/20 14:56:57.928 H.245 Hand...da33291700 OpalCon Removed media stream OpalRTPMediaStream[0x7fd9e8011bb0]-Source-G.711-uLaw-64k
2017/12/20 14:56:57.928 H.245 Hand...da33291700 OpalCon Removed media stream OpalRTPMediaStream[0x7fd9e8011bb0]-Source-G.711-uLaw-64k
2017/12/20 14:56:57.928 H.245 Hand...da33291700 H323    Stopped receiving logical channel: G.711-uLaw-64k <1>
2017/12/20 14:56:57.928 H.245 Hand...da33291700 OpalCon Bandwidth release of 64.0kb/s
2017/12/20 14:56:57.928 H.245 Hand...da33291700 H245    Sending PDU: response closeLogicalChannelAck
2017/12/20 14:56:57.928 H.245 Hand...da33291700 H323    InternalEstablishedConnectionCheck: connectionState=AwaitingSignalConnect fastStartState=FastStartDisabled H.245 is ready
2017/12/20 14:56:57.928 H225 Calle...da332d2700 H225    Set protocol version to 5
2017/12/20 14:56:57.928 H225 Calle...da332d2700 OpalCon SetPhase from AlertingPhase to ReleasingPhase for Call[Cd1c1dbc71]-EP<h323>[localhost/11754]
2017/12/20 14:56:57.928 H225 Calle...da332d2700 OpalCon Call end reason for Call[Cd1c1dbc71]-EP<h323>[localhost/11754] set to EndedByRemoteCongestion
2017/12/20 14:56:57.928 H225 Calle...da332d2700 OpalCon Releasing asynchronously Call[Cd1c1dbc71]-EP<h323>[localhost/11754]
2017/12/20 14:56:57.928 H225 Calle...da332d2700 OpalCon Already released Call[Cd1c1dbc71]-EP<h323>[localhost/11754]
2017/12/20 14:56:57.928 OnRelease:...da33250700 H225    Sending release complete PDU: callRef=11754
2017/12/20 14:56:57.928 H225 Calle...da332d2700 H225    Signal channel closed.
2017/12/20 14:56:57.929 OnRelease:...da33250700 H245    Sending PDU: command endSessionCommand
2017/12/20 14:56:57.929 H225 Calle...da332d2700 PTLib   Thread ended: name="H225 Caller:0x7fda332d2700", real=14.376, kernel=0.000 (0%), user=0.010 (0%), both=0.010 (0%)
2017/12/20 14:56:57.929 OnRelease:...da33250700 H225    Sending PDU: releaseComplete
2017/12/20 14:56:57.929 OnRelease:...da33250700 Opal    Transport clean up on termination
2017/12/20 14:56:57.929 H.245 Hand...da33291700 H245    Read error: Interrupted system call
2017/12/20 14:56:57.929 H.245 Hand...da33291700 OpalCon Already released Call[Cd1c1dbc71]-EP<h323>[localhost/11754]
2017/12/20 14:56:57.929 H.245 Hand...da33291700 H245    Control channel closed.
2017/12/20 14:56:57.929 H.245 Hand...da33291700 PTLib   Thread ended: name="H.245 Handler:0x7fda33291700", real=12.004, kernel=0.000 (0%), user=0.010 (0%), both=0.010 (0%)
2017/12/20 14:56:57.934 OnRelease:...da33250700 Opal    Transport clean up on termination
2017/12/20 14:56:57.940 OnRelease:...da33250700 OpalCon Media streams closed.
2017/12/20 14:56:57.940 OnRelease:...da33250700 OpalMan OnReleased Call[Cd1c1dbc71]-EP<h323>[localhost/11754]
2017/12/20 14:56:57.940 OnRelease:...da33250700 Call    OnReleased Call[Cd1c1dbc71]-EP<h323>[localhost/11754]
2017/12/20 14:56:57.940 OnRelease:...da33250700 OpalCon SetPhase from AlertingPhase to ReleasingPhase for Call[Cd1c1dbc71]-EP<modem>[modem:/Cd1c1dbc71/0]
2017/12/20 14:56:57.940 OnRelease:...da33250700 OpalCon Call end reason for Call[Cd1c1dbc71]-EP<modem>[modem:/Cd1c1dbc71/0] set to EndedByRemoteCongestion
2017/12/20 14:56:57.940 OnRelease:...da33250700 OpalCon Releasing synchronously Call[Cd1c1dbc71]-EP<modem>[modem:/Cd1c1dbc71/0]
2017/12/20 14:56:57.941 OnRelease:...da33250700 ModemConnection::OnReleased Call[Cd1c1dbc71]-EP<modem>[modem:/Cd1c1dbc71/0]
2017/12/20 14:56:57.941 OnRelease:...da33250700 OpalCon Media streams closed.
2017/12/20 14:56:57.941 OnRelease:...da33250700 OpalMan OnReleased Call[Cd1c1dbc71]-EP<modem>[modem:/Cd1c1dbc71/0]
2017/12/20 14:56:57.941 OnRelease:...da33250700 Call    OnReleased Call[Cd1c1dbc71]-EP<modem>[modem:/Cd1c1dbc71/0]
2017/12/20 14:56:57.941 OnRelease:...da33250700 Call[Cd1c1dbc71] cleared (EndedByRemoteCongestion)
2017/12/20 14:56:57.941 OnRelease:...da33250700 OpalMan OnClearedCall Call[Cd1c1dbc71] from "modem:" to "h323:0925622487@10.49.6.71:1720"
2017/12/20 14:56:57.941 OnRelease:...da33250700 OpalCon SetPhase from ReleasingPhase to ReleasedPhase for Call[Cd1c1dbc71]-EP<modem>[modem:/Cd1c1dbc71/0]
2017/12/20 14:56:57.941 OnRelease:...da33250700 OpalCon Connection Call[Cd1c1dbc71]-EP<modem>[modem:/Cd1c1dbc71/0] released
        Initial Time: Wed, 20 Dec 2017 14:56:43 +01:00
          SetUpPhase: 0.000
     ProceedingPhase: N/A
       AlertingPhase: 2.375
      ConnectedPhase: N/A
    EstablishedPhase: N/A
     ForwardingPhase: N/A
      ReleasingPhase: 14.391
       ReleasedPhase: 14.391
     Call end reason: EndedByRemoteCongestion

2017/12/20 14:56:57.941 OnRelease:...da33250700 OpalCon SetPhase from ReleasingPhase to ReleasedPhase for Call[Cd1c1dbc71]-EP<h323>[localhost/11754]
2017/12/20 14:56:57.941 OnRelease:...da33250700 OpalCon Connection Call[Cd1c1dbc71]-EP<h323>[localhost/11754] released
        Initial Time: Wed, 20 Dec 2017 14:56:43 +01:00
          SetUpPhase: 0.020
     ProceedingPhase: 0.031
       AlertingPhase: 2.367
      ConnectedPhase: N/A
    EstablishedPhase: N/A
     ForwardingPhase: N/A
      ReleasingPhase: 14.377
       ReleasedPhase: 14.391
     Call end reason: EndedByRemoteCongestion

2017/12/20 14:56:57.942 OnRelease:...da33250700 RTP Closing session 1
2017/12/20 14:56:57.942 OnRelease:...da33250700 RTP Closing session 1
2017/12/20 14:56:57.942 OnRelease:...da33250700 RTP Session 1, SentReceiverReport: ssrc=0 fraction=0 lost=0 last_seq=0 jitter=3 lsr=0 dlsr=0
2017/12/20 14:56:57.942 OnRelease:...da33250700 PTLib   Thread ended: name="OnRelease:0x7fda33250700", real=0.014, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%)
2017/12/20 14:56:57.990 Housekeepe...da33313700 RTP Session 1, SentReceiverReport: ssrc=0 fraction=0 lost=0 last_seq=39646 jitter=3 lsr=0 dlsr=0
2017/12/20 14:56:57.991 Housekeepe...da33313700 RTP Session 1, sending SDES: root@presesfax
2017/12/20 14:56:57.991 Housekeepe...da33313700 RTP Session 1, SentExtendedReport: ssrc=3517056168 loss_rate=0 discard_rate=0 burst_density=128 gap_density=0 burst_duration=11918 gap_duration=41 round_trip_delay=0 end_system_delay=0 gmin=16 r_factor=94 mos_lq=45 mos_cq=45 jb_nominal_delay=0 jb_maximum_delay=0 jb_absolute_delay=0
2017/12/20 14:56:58.528 Opal Garba...da336e2700 PseudoModemQ::Enqueue ttyx2
2017/12/20 14:56:58.528 Opal Garba...da336e2700 ModemEngineBody::Request: stConnectWait request={
calltoken=modem:/Cd1c1dbc71/0
command=clearcall
}
2017/12/20 14:56:58.528 Opal Garba...da336e2700 ModemEngineBody::OnTimerCallback stConnectWait Timeout 1
2017/12/20 14:56:58.528 Opal Garba...da336e2700 ttyx2 AudioEngine RecvOnIdle dtBusy
2017/12/20 14:56:58.528 Opal Garba...da336e2700 OpalCon Connection Call[Cd1c1dbc71]-EP<modem>[modem:/Cd1c1dbc71/0] destroyed.
2017/12/20 14:56:58.528 ttyx2(e):8...da334da700 ttyx2 AudioEngine Detach
2017/12/20 14:56:58.528 ttyx2(e):8...da334da700 ttyx2 AudioEngine OnChangeModemClass to mcUndefined
2017/12/20 14:56:58.528 ttyx2(e):8...da334da700 ttyx2 AudioEngine OnDetach Detached
2017/12/20 14:56:58.528 ttyx2(e):8...da334da700 ttyx2 AudioEngine OnResetModemState
2017/12/20 14:56:58.528 ttyx2(e):8...da334da700 ttyx2 AudioEngine ~AudioEngine
2017/12/20 14:56:58.528 ttyx2(e):8...da334da700 ttyx2 AudioEngine ~EngineBase WARNING: (In) still open by 0x7fd9e8017cc0
2017/12/20 14:56:58.528 Opal Garba...da336e2700 Opal    Transport clean up on termination
2017/12/20 14:56:58.528 Opal Garba...da336e2700 Opal    Transport clean up on termination
2017/12/20 14:56:58.528 ttyx2(e):8...da334da700 ModemEngineBody::_DetachEngine Detached mceAudio
2017/12/20 14:56:58.529 ttyx2(e):8...da334da700 <--  {
  0d 0a 42 55 53 59 0d 0a                            ..BUSY.. }
2017/12/20 14:56:58.529 Opal Garba...da336e2700 RTP_UDP Session 1, shutting down write.
2017/12/20 14:56:58.529 Opal Garba...da336e2700 VoIP Metrics    RTCP_XR_Metrics final statistics:
   R Factor = ^
   MOS = 4.42423
2017/12/20 14:56:58.529 Opal Garba...da336e2700 RTP Session 1, final statistics:
    firstPacketSent    = 
    packetsSent        = 0
    octetsSent         = 0
    bitRateSent        = 0
    averageSendTime    = 0
    maximumSendTime    = 0
    minimumSendTime    = 0
    packetsLostByRemote= 0
    jitterLevelOnRemote= 0
    firstPacketReceived= Wed, 20 Dec 2017 14:56:45 +01:00
    packetsReceived    = 597
    octetsReceived     = 95520
    bitRateReceived    = 63680
    packetsLost        = 0
    packetsTooLate     = 0
    packetOverruns     = 0
    packetsOutOfOrder  = 0
    averageReceiveTime = 19
    maximumReceiveTime = 21
    minimumReceiveTime = 16
    averageJitter      = 0
    maximumJitter      = 1
2017/12/20 14:56:58.529 Opal Garba...da336e2700 OpalCon Connection Call[Cd1c1dbc71]-EP<h323>[localhost/11754] destroyed.
2017/12/20 14:56:59.530 Opal Garba...da336e2700 Call    Destroyed Call[Cd1c1dbc71]
2017/12/20 14:56:59.531 ttyx2(e):8...da334da700 --> ATH0
2017/12/20 14:56:59.531 ttyx2(e):8...da334da700 <--  {
  0d 0a 4f 4b 0d 0a                                  ..OK.. }
2017/12/20 14:56:59.532 ttyx2(i):8...da33499700 --> read ERROR -1 Input/output error
2017/12/20 14:56:59.532 ttyx2(i):8...da33499700 --> Stopped
2017/12/20 14:56:59.532 ttyx2(i):8...da33499700 PTLib   Thread ended: name="ttyx2(i):860460800:0x7fda33499700", real=25.181, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%)
2017/12/20 14:56:59.542 ttyx2(o):8...da33458700 <-- Stopped
2017/12/20 14:56:59.543 ttyx2(o):8...da33458700 PTLib   Thread ended: name="ttyx2(o):860194560:0x7fda33458700", real=25.191, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%)
2017/12/20 14:56:59.553 ttyx2(e):8...da334da700 <-> Stopped
2017/12/20 14:56:59.553 ttyx2(e):8...da334da700 PTLib   Thread ended: name="ttyx2(e):860727040:0x7fda334da700", real=25.201, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%)
2017/12/20 14:56:59.563 ttyx2(b):8...da3361f700 PseudoModemPty::OpenPty removed link /dev/ttyx2 -> /dev/pts/6
2017/12/20 14:56:59.563 ttyx2(b):8...da3361f700 PseudoModemPty::OpenPty read ERROR -1 Input/output error
2017/12/20 14:56:59.563 ttyx2(b):8...da3361f700 PseudoModemPty::OpenPty added link /dev/ttyx2 -> /dev/pts/1
2017/12/20 14:56:59.563 ttyx2(e):8...da334da700 RenameCurrentThread old ThreadName=ModemEngine:0x7fda334da700
2017/12/20 14:56:59.563 ttyx2(e):8...da334da700 <-> Started
2017/12/20 14:56:59.563 ttyx2(i):8...da33458700 RenameCurrentThread old ThreadName=InPty:0x7fda33458700
2017/12/20 14:56:59.563 ttyx2(i):8...da33458700 --> Started
2017/12/20 14:56:59.563 ttyx2(o):8...da33499700 RenameCurrentThread old ThreadName=OutPty:0x7fda33499700
2017/12/20 14:56:59.564 ttyx2(o):8...da33499700 <-- Started
2017/12/20 14:57:09.040 ttyx2(e):8...da334da700 --> ATZ
2017/12/20 14:57:09.040 ttyx2(e):8...da334da700 <--  {
  0d 0a 4f 4b 0d 0a                                  ..OK.. }
2017/12/20 14:57:12.045 ttyx2(e):8...da334da700 --> ATS0=0
2017/12/20 14:57:12.045 ttyx2(e):8...da334da700 <--  {
  0d 0a 4f 4b 0d 0a                                  ..OK.. }
2017/12/20 14:57:12.047 ttyx2(e):8...da334da700 --> ATE0
2017/12/20 14:57:12.047 ttyx2(e):8...da334da700 <--  {
  0d 0a 4f 4b 0d 0a                                  ..OK.. }
2017/12/20 14:57:12.049 ttyx2(e):8...da334da700 --> ATV1
2017/12/20 14:57:12.049 ttyx2(e):8...da334da700 <--  {
  0d 0a 4f 4b 0d 0a                                  ..OK.. }
2017/12/20 14:57:12.051 ttyx2(e):8...da334da700 --> ATQ0
2017/12/20 14:57:12.051 ttyx2(e):8...da334da700 <--  {
  0d 0a 4f 4b 0d 0a                                  ..OK.. }
2017/12/20 14:57:12.053 ttyx2(e):8...da334da700 --> ATS8=2
2017/12/20 14:57:12.053 ttyx2(e):8...da334da700 <--  {
  0d 0a 4f 4b 0d 0a                                  ..OK.. }
2017/12/20 14:57:12.055 ttyx2(e):8...da334da700 --> ATS7=60
2017/12/20 14:57:12.055 ttyx2(e):8...da334da700 <--  {
  0d 0a 4f 4b 0d 0a                                  ..OK.. }
2017/12/20 14:57:12.057 ttyx2(e):8...da334da700 --> AT+FCLASS=?
2017/12/20 14:57:12.057 ttyx2(e):8...da334da700 <--  {
  0d 0a 31 2c 38 0d 0a 4f  4b 0d 0a                  ..1,8..OK.. }
2017/12/20 14:57:12.059 ttyx2(e):8...da334da700 --> AT+FCLASS=1
2017/12/20 14:57:12.059 ttyx2(e):8...da334da700 <--  {
  0d 0a 4f 4b 0d 0a                                  ..OK.. }
2017/12/20 14:57:12.061 ttyx2(e):8...da334da700 --> ATI3
2017/12/20 14:57:12.061 ttyx2(e):8...da334da700 <--  {
  0d 0a 56 79 61 63 68 65  73 6c 61 76 20 46 72 6f   ..Vyacheslav Fro
  6c 6f 76 0d 0a 4f 4b 0d  0a                        lov..OK.. }
2017/12/20 14:57:12.063 ttyx2(e):8...da334da700 --> ATI0
2017/12/20 14:57:12.063 ttyx2(e):8...da334da700 <--  {
  0d 0a 54 33 38 46 41 58  0d 0a 4f 4b 0d 0a         ..T38FAX..OK.. }
2017/12/20 14:57:12.065 ttyx2(e):8...da334da700 --> AT+FREV?
2017/12/20 14:57:12.065 ttyx2(e):8...da334da700 <--  {
  0d 0a 33 2e 31 35 2e 32  0d 0a 4f 4b 0d 0a         ..3.15.2..OK.. }
2017/12/20 14:57:12.067 ttyx2(e):8...da334da700 --> AT+FTM=?
2017/12/20 14:57:12.067 ttyx2(e):8...da334da700 <--  {
  0d 0a 32 34 2c 34 38 2c  37 32 2c 37 33 2c 37 34   ..24,48,72,73,74
  2c 39 36 2c 39 37 2c 39  38 2c 31 32 31 2c 31 32   ,96,97,98,121,12
  32 2c 31 34 35 2c 31 34  36 0d 0a 4f 4b 0d 0a      2,145,146..OK.. }
2017/12/20 14:57:12.069 ttyx2(e):8...da334da700 --> AT+FRM=?
2017/12/20 14:57:12.069 ttyx2(e):8...da334da700 <--  {
  0d 0a 32 34 2c 34 38 2c  37 32 2c 37 33 2c 37 34   ..24,48,72,73,74
  2c 39 36 2c 39 37 2c 39  38 2c 31 32 31 2c 31 32   ,96,97,98,121,12
  32 2c 31 34 35 2c 31 34  36 0d 0a 4f 4b 0d 0a      2,145,146..OK.. }
2017/12/20 14:57:12.071 ttyx2(e):8...da334da700 --> ATM0
2017/12/20 14:57:12.071 ttyx2(e):8...da334da700 <--  {
  0d 0a 4f 4b 0d 0a                                  ..OK.. }

Communication with a fax number always fail with: EndedByRemoteCongestion

Forums

Help

Communication with a fax number always fail with: EndedByRemoteCongestion document.SUBSCRIPTION_OPTIONS = { "thing": "topic", "subscribed": false, "url": "subscribe", "icon": { "css": "fa fa-envelope-o" } };

Communication with a fax number always fail with: EndedByRemoteCongestion