Hello Edson Pineiro, Fax Over IP Problem

Unanswered Question
Oct 10th, 2013
User Badges:

          Hello Edson Pineiro,

I have problem with FAXOVERIP. When I try to send a fax , I can call number,  it is ringing but while negotiating  the other side is cut the connection.

I took trace from the CM there is an error from CM side

calling number: 7069

called number:3409309


( NOTE: infact that it was working before the restart publisher)

XXXXXXXXXXXXXXXXXXXXXXXX


ia: SIP/2.0/UDP 10.45.120.165:5060;branch=z9hG4bK0761f739fc3ce80798463aa11972b3d2
From: <sip:[email protected]>;tag=53432d39
To: <sip:[email protected]>;tag=44445~d0d388f8-7598-40a3-a824-772cef395b29-21982739
Date: Thu, 10 Oct 2013 13:41:07 GMT
Call-ID: [email protected]
CSeq: 3 INVITE
Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY
Allow-Events: presence
Record-Route: <sip:10.45.120.165:5060;lr>
Supported: replaces
Supported: X-cisco-srtp-fallback
Supported: Geolocation
Session-Expires:  1800;refresher=uas
Require:  timer
P-Preferred-Identity: <sip:[email protected]>
Remote-Party-ID: <sip:[email protected]>;party=called;screen=no;privacy=off
Contact: <sip:[email protected]:5060>
Content-Type: application/sdp
Content-Length: 178

v=0
o=CiscoSystemsCCM-SIP 44445 2 IN IP4 10.44.200.1
s=SIP Call
c=IN IP4 10.20.200.1
b=TIAS:64000
b=AS:64
t=0 0
m=audio 30490 RTP/AVP 0
a=rtpmap:0 PCMU/8000
a=ptime:20
|1,100,230,1.42324^10.45.120.165^*
16:41:07.586 |//SIP/Stack/Info/0x0/httpish_msg_free, #### Freed msg=0xa302a6b8|1,100,71,1.1^*^*
16:41:07.586 |EnvProcessUdpPort - EnvProcessUdpHandler::fireSignal() varId = 0|1,100,230,1.42324^10.45.120.165^*
16:41:07.586 |EnvProcessUdpHandler::fireSignal - SEND: index = 0, handler = 0xa7fd1150|*^*^*
16:41:07.586 |EnvProcessUdpPort::fireSignal - SEND, destination = 10.45.120.165:5060|*^*^*
16:41:07.586 |EnvProcessUdpPort - EnvProcessUdpHandler::send(buff, 1041, 10.45.120.165:5060)|*^*^*
16:41:07.586 |//SIP/Stack/Info/0x19dc5a78/Sent Response, start ConnectTimer|1,100,230,1.42324^10.45.120.165^*
16:41:07.586 |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_CONNECT value=500 retries=6|1,100,230,1.42324^10.45.120.165^*
16:41:07.587 |//SIP/SIPHandler/ccbId=0/scbId=0/sip_start_timer: type=SIP_TIMER_CONNECT value=500 retries=6|1,100,230,1.42324^10.45.120.165^*
16:41:07.587 |EnvProcessUdpHandler::handle_input - handle = 341|*^*^*
16:41:07.587 |EnvProcessUdpHandler::handle_input   Status: 0, Id: 0|*^*^*
16:41:07.588 |//SIP/Stack/Info/0x0/httpish_msg_create, #### created msg=0xa6f7dd38 with refCount = 1|1,100,71,1.1^*^*
16:41:07.588 |//SIP/Stack/Error/0x0/DROPPING unregistered header X-XCAPI-UUID: 5E91FE1601CED50EA53623BE84E16CD6|1,100,71,1.1^*^*
16:41:07.588 |//SIP/SIPUdp/wait_UdpDataInd: Incoming SIP UDP message size 603 from 10.45.120.165:[5060]:
[136613,NET]
ACK sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP 10.45.120.165:5060;branch=z9hG4bK1870b522b189e9a0ab6ff5b2fdaecaf4
From: <sip:[email protected]>;tag=53432d39
To: <sip:[email protected]>;tag=44445~d0d388f8-7598-40a3-a824-772cef395b29-21982739
Call-Id: [email protected]
CSeq: 3 ACK
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, REFER
Contact: <sip:[email protected]:5060>
Content-Length: 0
Max-Forwards: 70
Supported: timer
Supported: ms-bypass
User-Agent: www.te-systems.de XCAPI V3.4.0.0
X-XCAPI-UUID: 5E91FE1601CED50EA53623BE84E16CD6

|1,100,230,1.42325^10.45.120.165^*
16:41:07.588 |//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 2 for event 1|1,100,230,1.42325^10.45.120.165^*
16:41:07.588 |//SIP/Stack/Transport/0x0/context=(nil)|1,100,230,1.42325^10.45.120.165^*
16:41:07.588 |//SIP/Stack/Info/0x0/Checking Invite Dialog|1,100,230,1.42325^10.45.120.165^*
16:41:07.588 |//SIP/Stack/Info/0x19dc5a78/*****CCB found in UAS Response table. ccb=0x19dc5a78|1,100,230,1.42325^10.45.120.165^*
16:41:07.588 |//SIP/Stack/Info/0x0/sipConstructContainerContext #### Created container=0xad17e1d0|1,100,230,1.42325^10.45.120.165^*
16:41:07.588 |//SIP/Stack/Info/0x0/httpish_msg_lock, Increment msg (0xa6f7dd38) counter, current msg->refCount = 2|1,100,230,1.42325^10.45.120.165^*
16:41:07.588 |//SIP/Stack/Transport/0x0/Subsq Transaction Address 10.45.120.165,Port 5060, Transport 1, SentBy Port 5060|1,100,230,1.42325^10.45.120.165^*
16:41:07.588 |//SIP/Stack/Transport/0x0/Subsq Transaction Address 10.45.120.165,Port 5060, Transport 1, SentBy Port 5060|1,100,230,1.42325^10.45.120.165^*
16:41:07.588 |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_CONNECT value=500 retries=6|1,100,230,1.42325^10.45.120.165^*
16:41:07.588 |//SIP/Stack/Info/0x19dc5a78/Processing MidCall ACK|1,100,230,1.42325^10.45.120.165^*
16:41:07.588 |//SIP/Stack/States/0x19dc5a78/0x19dc5a78 : State change from (STATE_MIDCALL_LOCAL_RESP_PENDING, SUBSTATE_NONE)  to (STATE_ACTIVE, SUBSTATE_NONE)|1,100,230,1.42325^10.45.120.165^*
16:41:07.588 |//SIP/Stack/Info/0x19dc5a78/sip_spi_handle_media_inact_timer not implemented on this platform. |1,100,230,1.42325^10.45.120.165^*
16:41:07.588 |//SIP/SIPHandler/ccbId=44445/scbId=0/findDevicePID: Routed to SIPD by ccbId/scbId|1,100,230,1.42325^10.45.120.165^*
16:41:07.588 |//SIP/SIPD(1,73,11)/ccbId=0/scbId=0/getKeyBasedOnCiAndBranch: AddressingElement branch is 0 and ci is 21982739  mapKey is 21982739|1,100,230,1.42325^10.45.120.165^*
16:41:07.588 |//SIP/SIPD(1,73,11)/ccbId=0/scbId=0/getCdpcPidGivenCcbidAndCi: found Cdpc Pid (1,100,74,8924) for mapKey 21982739|1,100,230,1.42325^10.45.120.165^*
16:41:07.588 |//SIP/Stack/Info/0x0/sipDestructContainerContext #### Deleted container=0xad17e1d0|1,100,230,1.42325^10.45.120.165^*
16:41:07.588 |//SIP/Stack/Info/0x0/httpish_msg_free, Decrement msg (0xa6f7dd38) counter, current msg->refCount = 1|1,100,230,1.42325^10.45.120.165^*
16:41:07.588 |//SIP/Stack/Info/0x19dc5a78/ccsip_api_modify_session_done returned: SIP_SUCCESS|1,100,230,1.42325^10.45.120.165^*
16:41:07.588 |//SIP/Stack/Info/0x0/httpish_msg_free, #### Freed msg=0xa6f7dd38|1,100,230,1.42325^10.45.120.165^*
16:41:07.604 |SMDMSharedData::findSubscriptionIdExistsInSubscribeeState - SubscriptionId=1|0|4610 found|5,100,17,147766.12342^10.140.6.7^SEP00260BD83D2A
16:41:07.604 |SMDMSharedData::updateSubscribeeStateAndNotify - updated state entry for outSubId = 1|0|4610, NotifyMsg = SNFNotifyMsg: state = 0, reason = 0, retryAfter = -1, subscriptionType =  REGISTRATION  BUSY_AVAILABLE_IDLE , content = SNFLegacyContent with content data set to: available, cepn = 28556df9-2aee-869c-e64b-5ef94126f356, mSubscribeePid = (5,164,68872)
, and sent notify to subscribers|5,100,17,147766.12342^10.140.6.7^SEP00260BD83D2A



XXXXXXXXXXXXXXXXXXXXXXXXXXXXX

ERROR

|1,100,230,1.42324^10.45.120.165^*

16:41:07.581 |//SIP/Stack/Info/0x0/httpish_msg_free, #### Freed msg=0xa5ae2e10|1,100,71,1.1^*^*

16:41:07.581 |EnvProcessUdpPort - EnvProcessUdpHandler::fireSignal() varId = 0|1,100,230,1.42324^10.45.120.165^*

16:41:07.581 |EnvProcessUdpHandler::fireSignal - SEND: index = 0, handler = 0xa7fd1150|*^*^*

16:41:07.581 |EnvProcessUdpPort::fireSignal - SEND, destination = 10.45.120.165:5060|*^*^*

16:41:07.581 |EnvProcessUdpPort - EnvProcessUdpHandler::send(buff, 399, 10.45.120.165:5060)|*^*^*

16:41:07.581 |//SIP/Stack/Info/0x0/httpish_msg_free, Decrement msg (0xa5aca4c8) counter, current msg->refCount = 1|1,100,230,1.42324^10.45.120.165^*

16:41:07.586 |DET-AgenaInterfaceBase-(11909)::updateIpAddrandVideoCallFlagifNeeded IpAddrType=0, negotiated addrMode=0|1,100,230,1.42324^10.45.120.165^*

16:41:07.586 |DET-MTPAgenaInterface-(11909)::waitForFarEndAnswer_MXFarEndAnswer, preMode=0, peerMode=0|1,100,230,1.42324^10.45.120.165^*

16:41:07.586 |DET-AgenaInterfaceBase-(11909)::updateIpAddrandVideoCallFlagifNeeded IpAddrType=0, negotiated addrMode=0|1,100,230,1.42324^10.45.120.165^*

16:41:07.586 |DET-MTPAgenaInterface-(11909) - handleSendRecvMXFarEndAnswer, prevAudio(0), oneWay(0), ipAddrType(0) ipAddrTypeVideo(0)|1,100,230,1.42324^10.45.120.165^*

16:41:07.586 |DET-AgenaInterfaceBase-(11909)::updateIpAddrandVideoCallFlagifNeeded IpAddrType=0, negotiated addrMode=0|1,100,230,1.42324^10.45.120.165^*

16:41:07.586 |DET-AgenaInterfaceBase-(11909)::updateIpAddrandVideoCallFlagifNeeded IpAddrType=0, negotiated addrMode=0|1,100,230,1.42324^10.45.120.165^*

16:41:07.586 |DET-AgenaInterfaceBase-(11909)::updateIpAddrandVideoCallFlagifNeeded IpAddrType=0, negotiated addrMode=0|1,100,230,1.42324^10.45.120.165^*

16:41:07.586 |DET-AgenaInterfaceBase-(11909)::updateIpAddrandVideoCallFlagifNeeded IpAddrType=0, negotiated addrMode=0|1,100,230,1.42324^10.45.120.165^*

16:41:07.586 |DET-AgenaInterfaceBase-(11909)::updateIpAddrandVideoCallFlagifNeeded IpAddrType=0, negotiated addrMode=0|1,100,230,1.42324^10.45.120.165^*

16:41:07.586 |DET-allowIncomingOLCTowardsDevice-allowOneWay(0) ccCallConnType(3) peerCCCallConntype(3) ignoreSDP(0),ret=1|*^*^*

16:41:07.586 |DET-AgenaInterfaceBase-(11909)::updateIpAddrandVideoCallFlagifNeeded IpAddrType=0, negotiated addrMode=0|1,100,230,1.42324^10.45.120.165^*

16:41:07.586 |DET-shouldInitiateOLC-(1) AllowOneWayMedia(0) ConnType(3 3 3)|*^*^*

16:41:07.586 |!!ERROR!! -SDPMsg- - getT38faxMLine, size=0, idx=0, returning System Default |*^*^*

16:41:07.586 |DET-MTPAgenaInterface-(11909)::canMakeAnswer=1, sdpMode(0,0), video(1), mAnswerNeeded=1, inChanOK=1, outChanOpened=1, device&FarEndAck(1,0),faxMode=0, olcAckTimer=0, bCheckVideo=0|1,100,230,1.42324^10.45.120.165^*

16:41:07.586 |DET-AgenaInterfaceBase-(11909)::updateIpAddrandVideoCallFlagifNeeded IpAddrType=0, negotiated addrMode=0|1,100,230,1.42324^10.45.120.165^*

16:41:07.586 |DET-AgenaInterfaceBase-(11909)::updateIpAddrandVideoCallFlagifNeeded IpAddrType=0, negotiated addrMode=0|1,100,230,1.42324^10.45.120.165^*

16:41:07.586 |DET-MTPAgenaInterface-(11909) - sendMXAnswerToParent, farEndMode=0, connType=3, myIP=|1,100,230,1.42324^10.45.120.165^*

16:41:07.586 |DET-AgenaInterfaceBase-(11909)::getChannelDynamicPayload, both channels are not open.|1,100,230,1.42324^10.45.120.165^*

16:41:07.586 |DET-AgenaInterfaceBase-(11909)::getChannelDynamicPayload, get DP = 0 from channel table|1,100,230,1.42324^10.45.120.165^*

16:41:07.586 |DET-AgenaInterfaceBase-(11909)::getChannelDynamicPayload, get DP = 0 from opposite channel|1,100,230,1.42324^10.45.120.165^*

16:41:07.586 |DET-AgenaInterfaceBase-(11909)::updateIpAddrandVideoCallFlagifNeeded IpAddrType=0, negotiated addrMode=0|1,100,230,1.42324^10.45.120.165^*

16:41:07.586 |DET-AgenaInterfaceBase-(11909)::updateIpAddrandVideoCallFlagifNeeded IpAddrType=0, negotiated addrMode=0|1,100,230,1.42324^10.45.120.165^*

16:41:07.586 |DET-AgenaInterfaceBase-(11909)::updateIpAddrandVideoCallFlagifNeeded IpAddrType=0, negotiated addrMode=0|1,100,230,1.42324^10.45.120.165^*

16:41:07.586 |!!ERROR!! -SDPMsg- - getVideoMLine, size=0, idx=0, returning System Default |*^*^*

16:41:07.586 |!!ERROR!! -SDPMsg- - getVideoMLine, size=0, idx=0, returning System Default |*^*^*

16:41:07.586 |DET-MTPAgenaInterface-(11909)::sendMXAnswerToParent(), mPrevAnsWithFP = 0, FarEndCcConnType = 3|1,100,230,1.42324^10.45.120.165^*

16:41:07.586 |DET-SIPInterface-(2285)::sessionEstablished_MXAnswer, FC=0|1,100,230,1.42324^10.45.120.165^*

16:41:07.586 |DET-SIPInterface-(2285)::BFCP is disabled:Filter out Presentation Video and BFCP|1,100,230,1.42324^10.45.120.165^*

16:41:07.586 |DET-SDPMsg-getBWFromSession: 0 0,0,0|*^*^*|1,100,230,1.42324^10.45.120.165^*
16:41:07.581 |//SIP/Stack/Info/0x0/httpish_msg_free, #### Freed msg=0xa5ae2e10|1,100,71,1.1^*^*
16:41:07.581 |EnvProcessUdpPort - EnvProcessUdpHandler::fireSignal() varId = 0|1,100,230,1.42324^10.45.120.165^*
16:41:07.581 |EnvProcessUdpHandler::fireSignal - SEND: index = 0, handler = 0xa7fd1150|*^*^*
16:41:07.581 |EnvProcessUdpPort::fireSignal - SEND, destination = 10.45.120.165:5060|*^*^*
16:41:07.581 |EnvProcessUdpPort - EnvProcessUdpHandler::send(buff, 399, 10.45.120.165:5060)|*^*^*
16:41:07.581 |//SIP/Stack/Info/0x0/httpish_msg_free, Decrement msg (0xa5aca4c8) counter, current msg->refCount = 1|1,100,230,1.42324^10.45.120.165^*
16:41:07.586 |DET-AgenaInterfaceBase-(11909)::updateIpAddrandVideoCallFlagifNeeded IpAddrType=0, negotiated addrMode=0|1,100,230,1.42324^10.45.120.165^*
16:41:07.586 |DET-MTPAgenaInterface-(11909)::waitForFarEndAnswer_MXFarEndAnswer, preMode=0, peerMode=0|1,100,230,1.42324^10.45.120.165^*
16:41:07.586 |DET-AgenaInterfaceBase-(11909)::updateIpAddrandVideoCallFlagifNeeded IpAddrType=0, negotiated addrMode=0|1,100,230,1.42324^10.45.120.165^*
16:41:07.586 |DET-MTPAgenaInterface-(11909) - handleSendRecvMXFarEndAnswer, prevAudio(0), oneWay(0), ipAddrType(0) ipAddrTypeVideo(0)|1,100,230,1.42324^10.45.120.165^*
16:41:07.586 |DET-AgenaInterfaceBase-(11909)::updateIpAddrandVideoCallFlagifNeeded IpAddrType=0, negotiated addrMode=0|1,100,230,1.42324^10.45.120.165^*
16:41:07.586 |DET-AgenaInterfaceBase-(11909)::updateIpAddrandVideoCallFlagifNeeded IpAddrType=0, negotiated addrMode=0|1,100,230,1.42324^10.45.120.165^*
16:41:07.586 |DET-AgenaInterfaceBase-(11909)::updateIpAddrandVideoCallFlagifNeeded IpAddrType=0, negotiated addrMode=0|1,100,230,1.42324^10.45.120.165^*
16:41:07.586 |DET-AgenaInterfaceBase-(11909)::updateIpAddrandVideoCallFlagifNeeded IpAddrType=0, negotiated addrMode=0|1,100,230,1.42324^10.45.120.165^*
16:41:07.586 |DET-AgenaInterfaceBase-(11909)::updateIpAddrandVideoCallFlagifNeeded IpAddrType=0, negotiated addrMode=0|1,100,230,1.42324^10.45.120.165^*
16:41:07.586 |DET-allowIncomingOLCTowardsDevice-allowOneWay(0) ccCallConnType(3) peerCCCallConntype(3) ignoreSDP(0),ret=1|*^*^*
16:41:07.586 |DET-AgenaInterfaceBase-(11909)::updateIpAddrandVideoCallFlagifNeeded IpAddrType=0, negotiated addrMode=0|1,100,230,1.42324^10.45.120.165^*
16:41:07.586 |DET-shouldInitiateOLC-(1) AllowOneWayMedia(0) ConnType(3 3 3)|*^*^*
16:41:07.586 |!!ERROR!! -SDPMsg- - getT38faxMLine, size=0, idx=0, returning System Default |*^*^*
16:41:07.586 |DET-MTPAgenaInterface-(11909)::canMakeAnswer=1, sdpMode(0,0), video(1), mAnswerNeeded=1, inChanOK=1, outChanOpened=1, device&FarEndAck(1,0),faxMode=0, olcAckTimer=0, bCheckVideo=0|1,100,230,1.42324^10.45.120.165^*
16:41:07.586 |DET-AgenaInterfaceBase-(11909)::updateIpAddrandVideoCallFlagifNeeded IpAddrType=0, negotiated addrMode=0|1,100,230,1.42324^10.45.120.165^*
16:41:07.586 |DET-AgenaInterfaceBase-(11909)::updateIpAddrandVideoCallFlagifNeeded IpAddrType=0, negotiated addrMode=0|1,100,230,1.42324^10.45.120.165^*
16:41:07.586 |DET-MTPAgenaInterface-(11909) - sendMXAnswerToParent, farEndMode=0, connType=3, myIP=|1,100,230,1.42324^10.45.120.165^*
16:41:07.586 |DET-AgenaInterfaceBase-(11909)::getChannelDynamicPayload, both channels are not open.|1,100,230,1.42324^10.45.120.165^*
16:41:07.586 |DET-AgenaInterfaceBase-(11909)::getChannelDynamicPayload, get DP = 0 from channel table|1,100,230,1.42324^10.45.120.165^*
16:41:07.586 |DET-AgenaInterfaceBase-(11909)::getChannelDynamicPayload, get DP = 0 from opposite channel|1,100,230,1.42324^10.45.120.165^*
16:41:07.586 |DET-AgenaInterfaceBase-(11909)::updateIpAddrandVideoCallFlagifNeeded IpAddrType=0, negotiated addrMode=0|1,100,230,1.42324^10.45.120.165^*
16:41:07.586 |DET-AgenaInterfaceBase-(11909)::updateIpAddrandVideoCallFlagifNeeded IpAddrType=0, negotiated addrMode=0|1,100,230,1.42324^10.45.120.165^*
16:41:07.586 |DET-AgenaInterfaceBase-(11909)::updateIpAddrandVideoCallFlagifNeeded IpAddrType=0, negotiated addrMode=0|1,100,230,1.42324^10.45.120.165^*
16:41:07.586 |!!ERROR!! -SDPMsg- - getVideoMLine, size=0, idx=0, returning System Default |*^*^*
16:41:07.586 |!!ERROR!! -SDPMsg- - getVideoMLine, size=0, idx=0, returning System Default |*^*^*
16:41:07.586 |DET-MTPAgenaInterface-(11909)::sendMXAnswerToParent(), mPrevAnsWithFP = 0, FarEndCcConnType = 3|1,100,230,1.42324^10.45.120.165^*
16:41:07.586 |DET-SIPInterface-(2285)::sessionEstablished_MXAnswer, FC=0|1,100,230,1.42324^10.45.120.165^*
16:41:07.586 |DET-SIPInterface-(2285)::BFCP is disabled:Filter out Presentation Video and BFCP|1,100,230,1.42324^10.45.120.165^*
16:41:07.586 |DET-SDPMsg-getBWFromSession: 0 0,0,0|*^*^*


XXXXXXXXXXXXXXX

Our CallManager version: System version: 8.6.2.22900-9

SIP TRUNK ( XCAPI Apllication ver:3.4

ANd Fax Aplication: GFI FAX maker.

Voice gateway:10.44.200.44


XXXXXXXXXXXX

Do you have an idea?

  • 1
  • 2
  • 3
  • 4
  • 5
Overall Rating: 0 (0 ratings)
Loading.
Prashanthi Velpula Wed, 10/16/2013 - 18:46
User Badges:
  • Cisco Employee,

Hi Oznur,


Can you please confirm if below is the call flow ?


FAX server --- SIP ? --- CUCM --- SIP --- CUBE/Voice gateway ---- SIP/ISDN ? ---- Provider


Is the FAX T38 protocol based ?

Incoming fax to FAX server fails ?

Outgoing fax to FAX server fails ?


And issue is ONLY seen after re-starting the CUCM publisher ?

++ If the FAX is T38 protocol-based, ensure this call doesnt have CUCM software MTP being allocated, if so the fax will fail.


Is it possible for you to provide the relevant detailed CUCM trace file ( Both SDI and SDL trace ) ? Because from above output, I am seeing the error for T38, which needs to be looked at ?

!

16:41:07.586 |!!ERROR!! -SDPMsg- - getT38faxMLine, size=0, idx=0, returning System Default |*^*^


Regards

Prashanthi Velpula

Actions

This Discussion