T.38 Fax to Asterisk via SIP, OXE R9.1 MG.

Billy · Post by **Billy** » 28 Feb 2011 12:08

Hi there,
I've tried all the options I could invent, but got finally stuck into the error 49 on the asterisk side:

    -- Executing [h@t38-recvfax:3] System("SIP/trunk-ns1-0000001d", "rm /home/fax/599-20110228-1935-1866322863.tiff") in new stack
    -- Executing [h@t38-recvfax:4] Hangup("SIP/trunk-ns1-0000001d", "") in new stack
  == Spawn extension (t38-recvfax, h, 4) exited non-zero on 'SIP/trunk-ns1-0000001d'
  == Using SIP RTP TOS bits 184
  == Using SIP RTP CoS mark 5
  == Using UDPTL TOS bits 184
  == Using UDPTL CoS mark 5
    -- Executing [1904@from-trunk:1] Macro("SIP/trunk-ns1-0000001e", "incoming_fxx") in new stack
    -- Executing [s@macro-incoming_fxx:1] Set("SIP/trunk-ns1-0000001e", "CDR(userfield)=Inbound") in new stack
    -- Executing [s@macro-incoming_fxx:2] GotoIf("SIP/trunk-ns1-0000001e", "0?Blacklist-Handle,s,1") in new stack
    -- Executing [s@macro-incoming_fxx:3] Macro("SIP/trunk-ns1-0000001e", "timecondition,t38-recvfax,599,1,t38-recvfax,599,1") in new stack
    -- Executing [s@macro-timecondition:1] GotoIf("SIP/trunk-ns1-0000001e", "1?2:24") in new stack
    -- Goto (macro-timecondition,s,2)
    -- Executing [s@macro-timecondition:2] GotoIfTime("SIP/trunk-ns1-0000001e", "09:00-18:30,mon,*,*?t38-recvfax,599,1") in new stack
    -- Executing [s@macro-timecondition:3] GotoIfTime("SIP/trunk-ns1-0000001e", "22:00-09:00,mon,*,*?t38-recvfax,599,1") in new stack
    -- Executing [s@macro-timecondition:4] GotoIfTime("SIP/trunk-ns1-0000001e", "00:00-00:00,mon,*,*?t38-recvfax,599,1") in new stack
    -- Executing [s@macro-timecondition:5] GotoIfTime("SIP/trunk-ns1-0000001e", "09:00-18:30,tue,*,*?t38-recvfax,599,1") in new stack
    -- Executing [s@macro-timecondition:6] GotoIfTime("SIP/trunk-ns1-0000001e", "22:00-09:00,tue,*,*?t38-recvfax,599,1") in new stack
    -- Executing [s@macro-timecondition:7] GotoIfTime("SIP/trunk-ns1-0000001e", "00:00-00:00,tue,*,*?t38-recvfax,599,1") in new stack
    -- Executing [s@macro-timecondition:8] GotoIfTime("SIP/trunk-ns1-0000001e", "09:00-18:30,wed,*,*?t38-recvfax,599,1") in new stack
    -- Executing [s@macro-timecondition:9] GotoIfTime("SIP/trunk-ns1-0000001e", "22:00-09:00,wed,*,*?t38-recvfax,599,1") in new stack
    -- Executing [s@macro-timecondition:10] GotoIfTime("SIP/trunk-ns1-0000001e", "00:00-00:00,wed,*,*?t38-recvfax,599,1") in new stack
    -- Executing [s@macro-timecondition:11] GotoIfTime("SIP/trunk-ns1-0000001e", "09:00-18:30,thu,*,*?t38-recvfax,599,1") in new stack
    -- Executing [s@macro-timecondition:12] GotoIfTime("SIP/trunk-ns1-0000001e", "22:00-09:00,thu,*,*?t38-recvfax,599,1") in new stack
    -- Executing [s@macro-timecondition:13] GotoIfTime("SIP/trunk-ns1-0000001e", "00:00-00:00,thu,*,*?t38-recvfax,599,1") in new stack
    -- Executing [s@macro-timecondition:14] GotoIfTime("SIP/trunk-ns1-0000001e", "09:00-18:30,fri,*,*?t38-recvfax,599,1") in new stack
    -- Executing [s@macro-timecondition:15] GotoIfTime("SIP/trunk-ns1-0000001e", "22:00-09:00,fri,*,*?t38-recvfax,599,1") in new stack
    -- Executing [s@macro-timecondition:16] GotoIfTime("SIP/trunk-ns1-0000001e", "00:00-00:00,fri,*,*?t38-recvfax,599,1") in new stack
    -- Executing [s@macro-timecondition:17] GotoIfTime("SIP/trunk-ns1-0000001e", "22:00-10:00,sat,*,*?t38-recvfax,599,1") in new stack
    -- Executing [s@macro-timecondition:18] GotoIfTime("SIP/trunk-ns1-0000001e", "00:00-00:00,sat,*,*?t38-recvfax,599,1") in new stack
    -- Executing [s@macro-timecondition:19] GotoIfTime("SIP/trunk-ns1-0000001e", "00:00-00:00,sat,*,*?t38-recvfax,599,1") in new stack
    -- Executing [s@macro-timecondition:20] GotoIfTime("SIP/trunk-ns1-0000001e", "22:00-10:00,sun,*,*?t38-recvfax,599,1") in new stack
    -- Executing [s@macro-timecondition:21] GotoIfTime("SIP/trunk-ns1-0000001e", "00:00-00:00,sun,*,*?t38-recvfax,599,1") in new stack
    -- Executing [s@macro-timecondition:22] GotoIfTime("SIP/trunk-ns1-0000001e", "00:00-00:00,sun,*,*?t38-recvfax,599,1") in new stack
    -- Executing [s@macro-timecondition:23] Goto("SIP/trunk-ns1-0000001e", "t38-recvfax,599,1") in new stack
    -- Goto (t38-recvfax,599,1)
  == Channel 'SIP/trunk-ns1-0000001e' jumping out of macro 'timecondition'
  == Channel 'SIP/trunk-ns1-0000001e' jumping out of macro 'incoming_fxx'
    -- Executing [599@t38-recvfax:1] Answer("SIP/trunk-ns1-0000001e", "") in new stack
    -- Executing [599@t38-recvfax:2] Wait("SIP/trunk-ns1-0000001e", "3") in new stack
    -- Executing [599@t38-recvfax:3] Set("SIP/trunk-ns1-0000001e", "SENDMAIL=0") in new stack
    -- Executing [599@t38-recvfax:4] Set("SIP/trunk-ns1-0000001e", "MAILOWNER=599") in new stack
    -- Executing [599@t38-recvfax:5] Set("SIP/trunk-ns1-0000001e", "FAXFILE=/home/fax/599-20110228-1936-355944517.tiff") in new stack
    -- Executing [599@t38-recvfax:6] ReceiveFAX("SIP/trunk-ns1-0000001e", "/home/fax/599-20110228-1936-355944517.tiff") in new stack
[color=#FF0000][Feb 28 19:36:33] WARNING[3705]: app_fax.c:437 transmit_audio: channel 'SIP/trunk-ns1-0000001e' refused to negotiate T.38[/color]
[Feb 28 19:36:59] NOTICE[329]: utils.c:297 HostPoolUpdater: host pool update...
[Feb 28 19:37:07] WARNING[3705]: app_fax.c:228 phase_e_handler: Error transmitting fax. result=49: The call dropped prematurely.
[Feb 28 19:37:07] WARNING[3705]: app_fax.c:822 transmit: Transmission error
  == Spawn extension (t38-recvfax, 599, 6) exited non-zero on 'SIP/trunk-ns1-0000001e'
    -- Executing [h@t38-recvfax:1] GotoIf("SIP/trunk-ns1-0000001e", "1?2:3") in new stack
    -- Goto (t38-recvfax,h,2)
    -- Executing [h@t38-recvfax:2] System("SIP/trunk-ns1-0000001e", "/bin/ccsmtp cu6apum@gmail.com billy@newsys.ru 2002 /home/fax/599-20110228-1936-355944517.tiff") in new stack
    -- Executing [h@t38-recvfax:3] System("SIP/trunk-ns1-0000001e", "rm /home/fax/599-20110228-1936-355944517.tiff") in new stack
    -- Executing [h@t38-recvfax:4] Hangup("SIP/trunk-ns1-0000001e", "") in new stack

Not too informational, so I jumped to the traced in oxe and found the following. As I see, some timer J has fired (???) and the call turned to termination. Can anybody please help?
Thank you!

Code: Select all

----------------------utf8-----------------------
INVITE sip:1904@192.168.11.48 SIP/2.0

Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE, INFO

Supported: replaces,timer,100rel

User-Agent: OmniPCX Enterprise R9.1 i1.605.21

Session-Expires: 1800;refresher=uac

Min-SE: 900

Content-Type: application/sdp

To: "sippp04" <sip:1904@192.168.11.42;user=phone>

From: "test2" <sip:2002@192.168.11.42;user=phone>;tag=696481e00b43af9959ab49984175d0b9

Contact: <sip:2002@192.168.11.42;transport=UDP>

Call-ID: c6db6be5e68b8f9bb7119a409fc71307@192.168.11.42

CSeq: 168824252 INVITE

Via: SIP/2.0/UDP 192.168.11.42;branch=z9hG4bK39ee0fc1561657884444bfd764c4f5ca

Max-Forwards: 70

Content-Length: 218



v=0

o=OXE 1298910988 1298910988 IN IP4 192.168.11.42

s=abs

c=IN IP4 192.168.11.52

t=0 0

m=audio 32544 RTP/AVP 8 101

a=sendrecv

a=rtpmap:8 PCMA/8000

a=ptime:20

a=maxptime:30

a=rtpmap:101 telephone-event/8000

-------------------------------------------------
1298910989 -> [CTransport::findConnection] pConnection = f002
1298910989 -> [CTransport::getConnection] RemoteHost=(192.168.11.48 5060) already exist, using connection f002
1298910989 ->  udp_send fd:3
1298910989 -> udp_send fd:3 port 5060 iBytesSend:897 message:INVITE sip:1904@192.168.11.48 SIP/2.0

Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE, INFO

Supported: replaces,timer,100rel

User-Agent: OmniPCX Enterprise R9.1 i1.605.21

Session-Expires: 1800;refresher=uac

Min-SE: 900

Content-Type: application/sdp

To: "sippp04" <sip:1904@192.168.11.42;user=phone>

From: "test2" <sip:2002@192.168.11.42;user=phone>;tag=696481e00b43af9959ab49984175d0b9

Contact: <sip:2002@192.168.11.42;transport=UDP>

Call-ID: c6db6be5e68b8f9bb7119a409fc71307@192.168.11.42

CSeq: 168824252 INVITE

Via: SIP/2.0/UDP 192.168.11.42;branch=z9hG4bK39ee0fc1561657884444bfd764c4f5ca

Max-Forwards: 70

Content-Length: 218



v=0

o=OXE 1298910988 1298910988 IN IP4 192.168.11.42

s=abs

c=IN IP4 192.168.11.52

t=0 0

m=audio 32544 RTP/AVP 8 101

a=sendrecv

a=rtpmap:8 PCMA/8000

a=ptime:20

a=maxptime:30

a=rtpmap:101 telephone-event/8000


1298910989 -> f002 [CConnection::resetKeepAliveTimer] Creating the keep alive timer
1298910989 -> 21a0 [CTransInitialState::createRequest] Transaction changes to Calling state
1298910989 -> 21a0 [CTransaction::changeState] STATE CHANGED TO CALLING
1298910989 -> 21a0 [CTransaction::changeState] notifying the parent dialog
1298910989 -> 15c6 [CDialog::onTransactionState(pTrans = 21a0, previousState = Initial, currentState = Calling, reason = Request creation]
1298910989 -> 21a0 [CTransaction::startTimer] Timer A is started (delay = 500 ms)
1298910989 -> 21a0 [CTransaction::startTimer] Timer B is started (delay = 8000 ms)
1298910989 -> 15c6 [CDialog::addTransaction] added transaction 21a0 with branch z9hG4bK39ee0fc1561657884444bfd764c4f5ca, with CSeq 168824252
1298910989 -> [CSessionTimerContext::CSessionTimerContext] New CSessionTimerContext from request (Client, UA)
1298910989 -> [CSessionTimerContext::updateAfterRefreshCreation] Update CSessionTimerContext (refresh creation)
1298910989 -> [CSessionTimerContext::updateSupported] Request timer supported 
1298910989 -> [CSessionTimerContext::updateMinSE] Min-SE updated : 900
1298910989 -> [CSessionTimerContext::updateSessionExpires] header value : 1800
1298910989 -> [CSessionTimerContext::updateSessionExpires] updated
1298910989 -> [CSessionTimerContext::updateSessionExpires] Refresher updated : i
1298910989 -> [CSessionTimerContext::updateSessionExpires] Session-Expires updated : 1800
1298910989 -> 11a7[CMotorCall::makeInitialMotorRequest] nb contact=1.
1298910989 -> [CForkedBranch::CForkedBranch] context = 0x402064f4.
1298910989 -> 11a7[CMotorCall::branchAllocation] built branch number=1.
1298910989 -> 11a7[CMotorCall::branchAllocation] pForkedBranch = 0x4022e9b8.
1298910989 -> 11a7[CMotorCall::makeInitialMotorRequest] reqUri duplication  pForkedBranch = 0x4022e9b8.
1298910989 -> 11a7[CMotorCall::makeInitialMotorRequest] reqUri duplication2.
1298910989 -> [onIncomingEvent] CallID must be inserted.
1298910989 -> [onIncomingEvent] CallID inserted.
1298910989 -> [exec_ipc] in end 19023.
1298910989 -> udp_recv fd:3
1298910989 -> udp_recv fd:3 iBytesRead:530 message:SIP/2.0 100 Trying

Via: SIP/2.0/UDP 192.168.11.42;branch=z9hG4bK39ee0fc1561657884444bfd764c4f5ca;received=192.168.11.42

From: "test2" <sip:2002@192.168.11.42;user=phone>;tag=696481e00b43af9959ab49984175d0b9

To: "sippp04" <sip:1904@192.168.11.42;user=phone>

Call-ID: c6db6be5e68b8f9bb7119a409fc71307@192.168.11.42

CSeq: 168824252 INVITE

Server: Asterisk PBX 1.6.2.6

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO

Supported: replaces, timer

Contact: <sip:1904@192.168.11.48>

Content-Length: 0




1298910989 -> UdpInputProc iSock:3 iBytesRead:530 Host:192.168.11.48 Port:5060
1298910989 -> [CTransportUdp::receiveMessage] from 192.168.11.48:5060 rc:530
1298910989 -> [CTransport::findConnection] pConnection = f002
1298910989 -> f002 [CConnection::dOSTest](192.168.11.48,5060,0) MaxNbMsgByPeriod:25 Period:3
1298910989 -> f002 [CConnection::dOSTest](192.168.11.48,5060,0) NbMsg:17 < seuil
1298910989 -> f002 [CConnection::resetKeepAliveTimer] Creating the keep alive timer
1298910989 -> RECEIVE MESSAGE FROM NETWORK (192.168.11.48:5060 [UDP])
----------------------utf8-----------------------
SIP/2.0 100 Trying

Via: SIP/2.0/UDP 192.168.11.42;branch=z9hG4bK39ee0fc1561657884444bfd764c4f5ca;received=192.168.11.42

From: "test2" <sip:2002@192.168.11.42;user=phone>;tag=696481e00b43af9959ab49984175d0b9

To: "sippp04" <sip:1904@192.168.11.42;user=phone>

Call-ID: c6db6be5e68b8f9bb7119a409fc71307@192.168.11.42

CSeq: 168824252 INVITE

Server: Asterisk PBX 1.6.2.6

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO

Supported: replaces, timer

Contact: <sip:1904@192.168.11.48>

Content-Length: 0



-------------------------------------------------

1298910989 -> [CIOCom::receiveResponse] CResponse 380 ref=1
1298910989 -> [CDispatcher::onIncomingResponse]
1298910989 -> [CDispatcher::processResponseFromManagerIndex] start at index 0
1298910989 ->  102 [CCallManager::onIncomingResponse]
1298910989 -> 11a7 [CCall::receiveResponse] 100
1298910989 -> 11a7 [CCall::receiveResponse]Trying to find the right dialog
1298910989 -> 11a7 [CCall::getDialog] Confirmed Dialog is not found (ID = 696481e00b43af9959ab49984175d0b9;)
1298910989 -> 11a7 [CCall::getDialog] Returning Initial Dialog Client
1298910989 -> 11a7 [CCall::receiveResponse]One dialog found
1298910989 -> 15c6 [CDialog::receiveResponse]
1298910989 -> 21a0 [CTransCallingState::receiveResponse] Provisional : Transaction changes to Proceeding state
1298910989 -> 21a0 [CTransaction::changeState] STATE CHANGED TO PROCEEDING
1298910989 -> 21a0 [CTransaction::changeState] notifying the parent dialog
1298910989 -> 15c6 [CDialog::onTransactionState(pTrans = 21a0, previousState = Calling, currentState = Proceeding, reason = 1xx response reception]
1298910989 -> 21a0 [CTransaction::freeTimerToken] Timer A is freed
1298910989 -> 21a0 [CTransaction::freeTimerToken] Timer B is freed
1298910989 -> [receiveInformationalMessage] Call: c6db6be5e68b8f9bb7119a409fc71307@192.168.11.42 eqt: 1662 PROCEEDING_STATE received a message.
1298910989 -> udp_recv fd:3
1298910989 -> udp_recv fd:3 iBytesRead:812 message:SIP/2.0 200 OK

Via: SIP/2.0/UDP 192.168.11.42;branch=z9hG4bK39ee0fc1561657884444bfd764c4f5ca;received=192.168.11.42

From: "test2" <sip:2002@192.168.11.42;user=phone>;tag=696481e00b43af9959ab49984175d0b9

To: "sippp04" <sip:1904@192.168.11.42;user=phone>;tag=as28cced4a

Call-ID: c6db6be5e68b8f9bb7119a409fc71307@192.168.11.42

CSeq: 168824252 INVITE

Server: Asterisk PBX 1.6.2.6

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO

Supported: replaces, timer

Contact: <sip:1904@192.168.11.48>

Content-Type: application/sdp

Content-Length: 238



v=0

o=root 1649086647 1649086647 IN IP4 192.168.11.48

s=Asterisk PBX 1.6.2.6

c=IN IP4 192.168.11.48

t=0 0

m=audio 38178 RTP/AVP 8 101

a=rtpmap:8 PCMA/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=ptime:20

a=sendrecv


1298910989 -> UdpInputProc iSock:3 iBytesRead:812 Host:192.168.11.48 Port:5060
1298910989 -> [CTransportUdp::receiveMessage] from 192.168.11.48:5060 rc:812
1298910989 -> [CTransport::findConnection] pConnection = f002
1298910989 -> f002 [CConnection::dOSTest](192.168.11.48,5060,0) MaxNbMsgByPeriod:25 Period:3
1298910989 -> f002 [CConnection::dOSTest](192.168.11.48,5060,0) NbMsg:18 < seuil
1298910989 -> f002 [CConnection::resetKeepAliveTimer] Creating the keep alive timer
1298910989 -> RECEIVE MESSAGE FROM NETWORK (192.168.11.48:5060 [UDP])
----------------------utf8-----------------------
SIP/2.0 200 OK

Via: SIP/2.0/UDP 192.168.11.42;branch=z9hG4bK39ee0fc1561657884444bfd764c4f5ca;received=192.168.11.42

From: "test2" <sip:2002@192.168.11.42;user=phone>;tag=696481e00b43af9959ab49984175d0b9

To: "sippp04" <sip:1904@192.168.11.42;user=phone>;tag=as28cced4a

Call-ID: c6db6be5e68b8f9bb7119a409fc71307@192.168.11.42

CSeq: 168824252 INVITE

Server: Asterisk PBX 1.6.2.6

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO

Supported: replaces, timer

Contact: <sip:1904@192.168.11.48>

Content-Type: application/sdp

Content-Length: 238



v=0

o=root 1649086647 1649086647 IN IP4 192.168.11.48

s=Asterisk PBX 1.6.2.6

c=IN IP4 192.168.11.48

t=0 0

m=audio 38178 RTP/AVP 8 101

a=rtpmap:8 PCMA/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=ptime:20

a=sendrecv

-------------------------------------------------

1298910989 -> [CIOCom::receiveResponse] CResponse 381 ref=1
1298910989 -> [CDispatcher::onIncomingResponse]
1298910989 -> [CDispatcher::processResponseFromManagerIndex] start at index 0
1298910989 ->  102 [CCallManager::onIncomingResponse]
1298910989 -> 11a7 [CCall::receiveResponse] 200
1298910989 -> 11a7 [CCall::receiveResponse]Trying to find the right dialog
1298910989 -> 11a7 [CCall::getDialog] Confirmed Dialog is not found (ID = 696481e00b43af9959ab49984175d0b9;as28cced4a)
1298910989 -> 11a7 [CCall::getDialog] Returning Initial Dialog Client
1298910989 -> 11a7 [CCall::receiveResponse]One dialog found
1298910989 -> 15c6 [CDialog::receiveResponse]
1298910989 -> 15c6 [CDialog::receiveResponse] create a CONFIRMED dialog
1298910989 -> 15c7 [CDialog::CDialog] look for the transaction #0, transaction key = z9hG4bK39ee0fc1561657884444bfd764c4f5ca
1298910989 -> 15c7 [CDialog::CDialog] copy the transaction #0, transaction key = z9hG4bK39ee0fc1561657884444bfd764c4f5ca
1298910989 -> 21a1 [CTransaction::CTransaction] Transaction is cloned in 4 state
1298910989 -> 15c7 [CDialog::addTransaction] added transaction 21a1 with branch z9hG4bK39ee0fc1561657884444bfd764c4f5ca, with CSeq 168824252
1298910989 -> 15c7 [CDialog::startDialogTTL]
1298910989 -> 15c7 [CDialog::receiveResponse]
1298910989 -> 21a1 [CTransProceedingState::receiveResponse] Final : Transaction changes to Completed state
1298910989 -> 21a1 [CTransaction::changeState] STATE CHANGED TO COMPLETED
1298910989 -> 21a1 [CTransaction::changeState] notifying the parent dialog
1298910989 -> 15c7 [CDialog::onTransactionState(pTrans = 21a1, previousState = Proceeding, currentState = Completed, reason = Final resp reception]
1298910989 -> 21a1 [CTransaction::startTimer] Timer D is started (delay = 32129 ms)
1298910989 -> [CSessionTimerContext::CSessionTimerContext] New CSessionTimerContext from response (Client, UA)
1298910989 -> [CSessionTimerContext::updateAfterRefreshCreation] Update CSessionTimerContext (refresh creation)
1298910989 -> [CSessionTimerContext::updateSupported] Request timer supported 
1298910989 -> [CSessionTimerContext::updateMinSE] Min-SE updated : 900
1298910989 -> [CSessionTimerContext::updateSessionExpires] header value : 1800
1298910989 -> [CSessionTimerContext::updateSessionExpires] updated
1298910989 -> [CSessionTimerContext::updateSessionExpires] Refresher updated : i
1298910989 -> [CSessionTimerContext::updateSessionExpires] Session-Expires updated : 1800
1298910989 -> [CSessionTimerContext::updateAfterResponseRefreshReception] Update CSessionTimerContext (response refresh reception)
1298910989 -> [CSessionTimerContext::updateSessionExpires] Session-Expires updated : 1800
1298910989 -> [CSessionTimerContext::startTimers] Start session timer expiration : 1800000
1298910989 -> [CSessionTimerContext::startTimers] Start session timer will expires : 900000
1298910989 -> [CSessionTimerContext::setRefreshMethod] Allow refreshMethod=INVITE
1298910989 -> 15c7 [CDialog::updateRouteSet] there are 0 record-route headers
1298910989 -> 11a7 [CCall::insertInDialogMap] added dialog #15c7 with the key 696481e00b43af9959ab49984175d0b9;as28cced4a

1298910989 -> 15c6 [CDialog::getTransactionByCSeq] CSeq = 168824253
1298910989 -> 15c6 [CDialog::updateRouteSet] there are 0 record-route headers
1298910989 ->         [CForkedBranch::setFinalResponse] pResponse=0x4022f668.
1298910989 ->         [CForkedBranch::setFinalResponse] response=200.
1298910989 -> 11a7[CMotorCall::responseToInviteOrCancel] call chooseBestResponse.
1298910989 -> 11a7[CMotorCall::chooseBestResponse] pForkedBranch=0x4022e9b8 iResponseCode=2000.
1298910989 ->         [CForkedBranch::getResponseCode] response=200.
1298910989 ->         [CForkedBranch::getResponseCode] response=200.
1298910989 -> 11a7[CMotorCall::chooseBestResponse] last iResponseCode=200.
1298910989 ->         [CForkedBranch::setFinalResponse] pResponse=0x4022f668.
1298910989 -> 11a7[CMotorCall::chooseBestResponse] next branch.
1298910989 -> 11a7[CMotorCall::responseToInviteOrCancel] inviteResponse => update the lastInviteContext.
1298910989 -> [receiveSuccessfulMessage] Call: c6db6be5e68b8f9bb7119a409fc71307@192.168.11.42 eqt: 1662 PROCEEDING_STATE received a message.
1298910989 -> 11a7[CMotorCall::analyseSessionTimer] exit with  uacGatewayMode=1 uacRefresher=1.
1298910989 -> [checkSdpValidity] Sdp contains 1 media description(s).
1298910989 -> [checkSdpValidity] Media 0 type 1 contains 2 formats.
1298910989 -> [checkSdpValidity] Format : 8.
1298910989 -> [convertSdpIntoTsdp] Sdp contains 1 media description(s).
1298910989 -> [convertSdpIntoTsdp] default T38 config.
1298910989 -> [convertSdpIntoTsdp] No Direction in the session part.
1298910989 -> [convertSdpIntoTsdp] Check the direction in Session part - result:0.
1298910989 -> [convertSdpIntoTsdp] The audio media contains  2 format(s).
1298910989 -> [convertSdpIntoTsdp] Format 0 is 8.
1298910989 -> [convertSdpIntoTsdp] Format 1 is 101.
1298910989 -> [convertSdpIntoTsdp] Get the direction.
1298910989 -> [convertSdpIntoTsdp] Direction is sendrecv.
1298910989 -> [convertSdpIntoTsdp] The connection is at session level.
1298910989 -> [convertSdpIntoTsdp] Connection address retrieved in sdp: 192.168.11.48.
1298910989 -> [convertIPStrIntoTuipv] 192.168.11.48 => 192.168.11.48
1298910989 -> [methodAllowedByDistant] INFO allowed by distant.
1298910989 -> 11a7[emitEventToMonitel] content size (lginfo): 656
1298910989 -> 11a7[emitEventToMonitel] CAC = 0 eqtCAC = -1 
1298910989 -> 11a7[emitEventToMonitel] CAC = 0 
1298910989 -> ** SEPLOS **
1298910989 -> [sendLgEvtSip] Event sent on eqt : 1662 Id :1
1298910989 -> [display_ipc_out] ------------ Begin ---------------
1298910989 -> Id : 1
1298910989 -> SUCCESSFUL
1298910989 -> xx :        0
1298910989 -> 	RELATIVE REQUEST : INVITE
1298910989 -> CLIR : 0
1298910989 -> COLP : 0
1298910989 -> SDP :
1298910989 ->   ADDRESS : 192.168.11.48 :38178
1298910989 ->   ALGOS :
1298910989 ->     PCMA
1298910989 ->     101
1298910989 ->   DIRECTION : SEND & RECEIVE
1298910989 -> [display_ipc_out] ------------- End ----------------
1298910989 -> [ipc_thread] IPC Thread : Ipc reception 19024.
1298910989 -> [ipc_thread] IPC Thread : TCL thread signaled.
1298910989 -> 11a7[CMotorCall::onReceiveResponse] INVITE case => cancelAllCancelableForkedBranch.
1298910989 -> 11a7[CMotorCall::cancelAllCancelableForkedBranch] nb branch=1.
1298910989 ->         [CForkedBranch::getResponseCode] response=200.
1298910989 ->         [CForkedBranch::getResponseCode] response=200.
1298910989 -> [exec_ipc] in 19023.
1298910989 -> [display_ipc_in] ------------ Begin ---------------
1298910989 -> neqt : 1662 Id : 1
1298910989 -> ACK
1298910989 -> [display_ipc_in] ------------- End ----------------
1298910989 -> [CMotorCallManager::onIncomingEvent] an event 10754 arrived on the eqt 1662-1.
1298910989 -> [CMotorCall::onIncomingEvent] seplos station.
1298910989 -> [CMotorCallManager::onIncomingEvent] state = ACCEPTED_STATE.
1298910989 -> [onIncomingEvent] ACK arrived.
1298910989 -> [receiveAckEvent] Call: c6db6be5e68b8f9bb7119a409fc71307@192.168.11.42 eqt: 1662 ACCEPTED_STATE received an event.
1298910989 -> 11a7[CMotorCall::inviteBuildContact]  Contact: sip:192.168.11.42
1298910989 -> 11a7[CMotorCall::emitAckMessage] begin.
1298910989 -> 11a7[CMotorCall::emitAckMessage] SDP ok.
1298910989 -> 11a7 [CCall::makeGenericRequest] ACK
1298910989 -> 11a7 [CCall::getDialog] Confirmed Dialog found
1298910989 -> 15c7 [CDialog::createRequest]
1298910989 -> 15c7 [CDialog::buildServicesForAllRequest]
1298910989 -> 15c7 [CDialog::createAckRequest]
1298910989 -> 15c7 [CDialog::createAckRequest], previous trans 21a1 is found
1298910989 -> 15c7 [CDialog::createAckRequest], previous trans 21a1 is client
1298910989 -> 372 [CRequest::CRequest] Creation of a request
1298910989 -> 15c7 [CDialog::buildServicesForAck]
1298910989 -> 372 [CMessage::send] sip_sendMessage (192.168.11.48:5060)
1298910989 -> SEND MESSAGE TO NETWORK (192.168.11.48:5060 [UDP]) (BUFF LEN = 461)
----------------------utf8-----------------------
ACK sip:1904@192.168.11.48 SIP/2.0

Contact: sip:192.168.11.42

User-Agent: OmniPCX Enterprise R9.1 i1.605.21

To: "sippp04" <sip:1904@192.168.11.42;user=phone>;tag=as28cced4a

From: "test2" <sip:2002@192.168.11.42;user=phone>;tag=696481e00b43af9959ab49984175d0b9

Call-ID: c6db6be5e68b8f9bb7119a409fc71307@192.168.11.42

CSeq: 168824252 ACK

Via: SIP/2.0/UDP 192.168.11.42;branch=z9hG4bK70d6d8fdd0d0b3b2acde6687ae1b0929

Max-Forwards: 70

Content-Length: 0



-------------------------------------------------
1298910989 -> [CTransport::findConnection] pConnection = f002
1298910989 -> [CTransport::getConnection] RemoteHost=(192.168.11.48 5060) already exist, using connection f002
1298910989 ->  udp_send fd:3
1298910989 -> udp_send fd:3 port 5060 iBytesSend:461 message:ACK sip:1904@192.168.11.48 SIP/2.0

Contact: sip:192.168.11.42

User-Agent: OmniPCX Enterprise R9.1 i1.605.21

To: "sippp04" <sip:1904@192.168.11.42;user=phone>;tag=as28cced4a

From: "test2" <sip:2002@192.168.11.42;user=phone>;tag=696481e00b43af9959ab49984175d0b9

Call-ID: c6db6be5e68b8f9bb7119a409fc71307@192.168.11.42

CSeq: 168824252 ACK

Via: SIP/2.0/UDP 192.168.11.42;branch=z9hG4bK70d6d8fdd0d0b3b2acde6687ae1b0929

Max-Forwards: 70

Content-Length: 0




1298910989 -> f002 [CConnection::resetKeepAliveTimer] Creating the keep alive timer
1298910989 -> 21a1 [CTransaction::changeState] STATE CHANGED TO TERMINATED
1298910989 -> 21a1 [CTransaction::freeTimerToken] Timer D is freed
1298910989 -> 21a1 [CTransaction::changeState] no transaction state, terminated reason : Request creation
1298910989 -> 15c7 [CDialog::createAckRequest] successful INVITE is terminated after the ACK is sent
1298910989 -> [exec_ipc] in end 19023.
1298910989 -> 219d [CTransCompletedState::timerFires] Timer J fires
1298910989 -> 219d [CTransCompletedState::timerFires] Timer J : Transaction changes to Terminated state
1298910989 -> 219d [CTransaction::changeState] STATE CHANGED TO TERMINATED
1298910989 -> 219d [CTransaction::changeState] no transaction state, terminated reason : Timer J fires
1298910989 -> [CTransaction::notifyDialog]
1298910989 -> [CTransaction::notifyDialog] notify parent dialog
1298910989 -> 15c7 [CDialog::onTransactionState(pTrans = 21a1, previousState = null, currentState = Terminated, reason = Request creation]
1298910989 -> 21A1 [~CTransaction] transaction 168824252 INVITE
1298910989 -> [CTransaction::notifyDialog]
1298910989 -> [CTransaction::notifyDialog] notify parent dialog
1298910989 -> 15c3 [CDialog::onTransactionState(pTrans = 219d, previousState = null, currentState = Terminated, reason = Timer J fires]
1298910989 -> 15c3 [CDialog::onTransactionState] dialog is terminated
1298910989 -> 15c3 [CDialog::onTransactionState] onDialogClosed
1298910989 -> 11a4 [CCall::onDialogClosed]
1298910989 -> 11a4 [CCall::onDialogClosed] only initial *********
1298910989 -> 11a4 [CCall::onDialogClosed] All the initial dialogs in the Call are closed
1298910989 -> 11a4 [CCall] closed
1298910989 ->  102 [CCallManager::onCallClosed]
1298910989 ->  102 [CCallManager::onCallClosed()] Call 11a4 closed. Removing call 1076011776 from the maps
1298910989 -> [CMotorCallManager::onNotifyCallClosed] Call : 4d564eb6221b06096dd70a8f7129c93c@192.168.11.48 is closed.
1298910989 -> [CMotorCallManager::onNotifyCallClosed] Call : 4d564eb6221b06096dd70a8f7129c93c@192.168.11.48 is closed.
1298910989 -> [CMotorCallManager::onNotifyCallClosed] Remove eqt from the map.
1298910989 -> 219D [~CTransaction] transaction 102 OPTIONS
1298910989 -> 219e [CTransCompletedState::timerFires] Timer J fires
1298910989 -> 219e [CTransCompletedState::timerFires] Timer J : Transaction changes to Terminated state
1298910989 -> 219e [CTransaction::changeState] STATE CHANGED TO TERMINATED
1298910989 -> 219e [CTransaction::changeState] no transaction state, terminated reason : Timer J fires
1298910989 -> [CTransaction::notifyDialog]
1298910989 -> [CTransaction::notifyDialog] notify parent dialog
1298910989 -> 15c4 [CDialog::onTransactionState(pTrans = 219e, previousState = null, currentState = Terminated, reason = Timer J fires]
1298910989 -> 15c4 [CDialog::onTransactionState] dialog is terminated
1298910989 -> 15c4 [CDialog::onTransactionState] onDialogClosed
1298910989 -> 11a5 [CCall::onDialogClosed]
1298910989 -> 11a5 [CCall::onDialogClosed] only initial *********
1298910989 -> 11a5 [CCall::onDialogClosed] All the initial dialogs in the Call are closed
1298910989 -> 11a5 [CCall] closed
1298910989 ->  102 [CCallManager::onCallClosed]
1298910989 ->  102 [CCallManager::onCallClosed()] Call 11a5 closed. Removing call 1076002680 from the maps
1298910989 -> [CMotorCallManager::onNotifyCallClosed] Call : 41cdff2b3eda08862f055da238ff2c1d@192.168.11.48 is closed.
1298910989 -> [CMotorCallManager::onNotifyCallClosed] Call : 41cdff2b3eda08862f055da238ff2c1d@192.168.11.48 is closed.
1298910989 -> [CMotorCallManager::onNotifyCallClosed] Remove eqt from the map.
1298910989 -> 219E [~CTransaction] transaction 102 OPTIONS
1298910990 ->  102 [CCallManager::removeGarbage] at index i = 1
1298910990 -> 11a5[CMotorCall::~CMotorCall] Call : 41cdff2b3eda08862f055da238ff2c1d@192.168.11.48 is being destructed.
1298910990 -> 11a5 [CCall::~CCall]
1298910990 -> 11a5 [CCall::removeGarbage]
1298910990 -> 15c4 [CDialog::~CDialog]
1298910990 -> 37E [~CResponse] resp(200) 41cdff2b3eda08862f055da238ff2c1d@192.168.11.48 102 OPTIONS
1298910990 -> 36F [~CRequest] req 41cdff2b3eda08862f055da238ff2c1d@192.168.11.48 102 OPTIONS
1298910990 ->  102 [CCallManager::removeGarbage] at index i = 0
1298910990 -> 11a4[CMotorCall::~CMotorCall] Call : 4d564eb6221b06096dd70a8f7129c93c@192.168.11.48 is being destructed.
1298910990 -> 11a4 [CCall::~CCall]
1298910990 -> 11a4 [CCall::removeGarbage]
1298910990 -> 15c3 [CDialog::~CDialog]
1298910990 -> 37D [~CResponse] resp(200) 4d564eb6221b06096dd70a8f7129c93c@192.168.11.48 102 OPTIONS
1298910990 -> 36E [~CRequest] req 4d564eb6221b06096dd70a8f7129c93c@192.168.11.48 102 OPTIONS
1298910991 -> 219f [CTransCompletedState::timerFires] Timer J fires
1298910991 -> 219f [CTransCompletedState::timerFires] Timer J : Transaction changes to Terminated state
1298910991 -> 219f [CTransaction::changeState] STATE CHANGED TO TERMINATED
1298910991 -> 219f [CTransaction::changeState] no transaction state, terminated reason : Timer J fires
1298910992 -> [CTransaction::notifyDialog]
1298910992 -> [CTransaction::notifyDialog] notify parent dialog
1298910992 -> 15c5 [CDialog::onTransactionState(pTrans = 219f, previousState = null, currentState = Terminated, reason = Timer J fires]
1298910992 -> 15c5 [CDialog::onTransactionState] dialog is terminated
1298910992 -> 15c5 [CDialog::onTransactionState] onDialogClosed
1298910992 -> 11a6 [CCall::onDialogClosed]
1298910992 -> 11a6 [CCall::onDialogClosed] only initial *********
1298910992 -> 11a6 [CCall::onDialogClosed] All the initial dialogs in the Call are closed
1298910992 -> 11a6 [CCall] closed
1298910992 ->  102 [CCallManager::onCallClosed]
1298910992 ->  102 [CCallManager::onCallClosed()] Call 11a6 closed. Removing call 1076060992 from the maps
1298910992 -> [CMotorCallManager::onNotifyCallClosed] Call : 2d5dc8b823cda4686e267db955f6d270@10.0.0.6 is closed.
1298910992 -> [CMotorCallManager::onNotifyCallClosed] Call : 2d5dc8b823cda4686e267db955f6d270@10.0.0.6 is closed.
1298910992 -> [CMotorCallManager::onNotifyCallClosed] Remove eqt from the map.
1298910992 -> 219F [~CTransaction] transaction 102 OPTIONS
1298910992 -> udp_recv fd:3
1298910992 -> udp_recv fd:3 iBytesRead:882 message:INVITE sip:2002@192.168.11.42;transport=UDP SIP/2.0

Via: SIP/2.0/UDP 192.168.11.48:5060;branch=z9hG4bK03fdca2c;rport

Max-Forwards: 70

From: "sippp04" <sip:1904@192.168.11.42;user=phone>;tag=as28cced4a

To: "test2" <sip:2002@192.168.11.42;user=phone>;tag=696481e00b43af9959ab49984175d0b9

Contact: <sip:1904@192.168.11.48>

Call-ID: c6db6be5e68b8f9bb7119a409fc71307@192.168.11.42

CSeq: 102 INVITE

User-Agent: Asterisk PBX 1.6.2.6

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO

Supported: replaces, timer

Content-Type: application/sdp

Content-Length: 291



v=0

o=root 1649086647 1649086648 IN IP4 192.168.11.48

s=Asterisk PBX 1.6.2.6

c=IN IP4 192.168.11.48

t=0 0

m=image 4516 udptl t38

a=T38FaxVersion:0

a=T38MaxBitRate:14400

a=T38FaxFillBitRemoval

a=T38FaxRateManagement:transferredTCF

a=T38FaxMaxDatagram:1400

a=T38FaxUdpEC:t38UDPFEC


1298910992 -> UdpInputProc iSock:3 iBytesRead:882 Host:192.168.11.48 Port:5060
1298910992 -> [CTransportUdp::receiveMessage] from 192.168.11.48:5060 rc:882
1298910992 -> [CTransport::findConnection] pConnection = f002
1298910992 -> f002 [CConnection::dOSTest](192.168.11.48,5060,0) MaxNbMsgByPeriod:25 Period:3
1298910992 -> f002 [CConnection::dOSTest](192.168.11.48,5060,0) NbMsg:19 < seuil
1298910992 -> f002 [CConnection::resetKeepAliveTimer] Creating the keep alive timer
1298910992 -> RECEIVE MESSAGE FROM NETWORK (192.168.11.48:5060 [UDP])
----------------------utf8-----------------------
INVITE sip:2002@192.168.11.42;transport=UDP SIP/2.0

Via: SIP/2.0/UDP 192.168.11.48:5060;branch=z9hG4bK03fdca2c;rport

Max-Forwards: 70

From: "sippp04" <sip:1904@192.168.11.42;user=phone>;tag=as28cced4a

To: "test2" <sip:2002@192.168.11.42;user=phone>;tag=696481e00b43af9959ab49984175d0b9

Contact: <sip:1904@192.168.11.48>

Call-ID: c6db6be5e68b8f9bb7119a409fc71307@192.168.11.42

CSeq: 102 INVITE

User-Agent: Asterisk PBX 1.6.2.6

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO

Supported: replaces, timer

Content-Type: application/sdp

Content-Length: 291



v=0

o=root 1649086647 1649086648 IN IP4 192.168.11.48

s=Asterisk PBX 1.6.2.6

c=IN IP4 192.168.11.48

t=0 0

m=image 4516 udptl t38

a=T38FaxVersion:0

a=T38MaxBitRate:14400

a=T38FaxFillBitRemoval

a=T38FaxRateManagement:transferredTCF

a=T38FaxMaxDatagram:1400

a=T38FaxUdpEC:t38UDPFEC

-------------------------------------------------

1298910992 -> 373 [CRequest::CRequest] Creation of a request (RECEIVED FROM NETWORK)
1298910992 -> [CIOCom::receiveRequest] Received CRequest 373 ref=1
1298910992 -> [CIOCom::checkHostInBlackList] host=192.168.11.48
1298910992 -> [CAdnsMngt::checkHostInBlackList] host=192.168.11.48 alcResult= 0
1298910992 -> [CIOCom::checkHostInBlackList] host=192.168.11.48
1298910992 -> [CAdnsMngt::checkHostInBlackList] host=192.168.11.48 alcResult= 0
1298910992 -> [CIOCom::receiveRequest] rport1 empty 
1298910992 -> [CIOCom::receiveRequest] Set received param on first Via header : 192.168.11.48
1298910992 -> [CDispatcher::onIncomingRequest()]
1298910992 -> [CDispatcher::processRequestFromManagerIndex()] start at index 0
1298910992 ->  102 [CCallManager::onIncomingRequest()]
1298910992 -> 11a7 [CCall::receiveRequest] INVITE
1298910992 -> 11a7 [CCall::getDialog] Confirmed Dialog found
1298910992 -> 15c7 [CDialog::receiveRequest]
1298910992 -> 15c7 [CDialog::receiveInviteRequest]
1298910992 -> 21a2 [CTransaction::changeState] STATE CHANGED TO INITIAL
1298910992 -> 21a2 [CTransaction::changeState] notifying the parent dialog
1298910992 -> 15c7 [CDialog::onTransactionState(pTrans = 21a2, previousState = Terminated, currentState = Initial, reason = None]
1298910992 -> 21a2 [CTransInitialState::receiveRequest] Transaction changes to Proceeding state
1298910992 -> 21a2 [CTransaction::changeState] STATE CHANGED TO PROCEEDING
1298910992 -> 21a2 [CTransaction::changeState] notifying the parent dialog
1298910992 -> 15c7 [CDialog::onTransactionState(pTrans = 21a2, previousState = Initial, currentState = Proceeding, reason = Request reception]
1298910992 -> 21a2 [CTransProceedingState::createResponse] CResponse 382 ref=1
1298910992 -> 382 [CMessage::send] sip_sendMessage (192.168.11.48:5060)
1298910992 -> SEND MESSAGE TO NETWORK (192.168.11.48:5060 [UDP]) (BUFF LEN = 364)
----------------------utf8-----------------------
SIP/2.0 100 Trying

To: "test2" <sip:2002@192.168.11.42;user=phone>;tag=696481e00b43af9959ab49984175d0b9

From: "sippp04" <sip:1904@192.168.11.42;user=phone>;tag=as28cced4a

Call-ID: c6db6be5e68b8f9bb7119a409fc71307@192.168.11.42

CSeq: 102 INVITE

Via: SIP/2.0/UDP 192.168.11.48:5060;received=192.168.11.48;branch=z9hG4bK03fdca2c;rport=5060

Content-Length: 0



-------------------------------------------------
1298910992 -> [CTransport::findConnection] pConnection = f002
1298910992 -> [CTransport::getConnection] RemoteHost=(192.168.11.48 5060) already exist, using connection f002
1298910992 ->  udp_send fd:3
1298910992 -> udp_send fd:3 port 5060 iBytesSend:364 message:SIP/2.0 100 Trying

To: "test2" <sip:2002@192.168.11.42;user=phone>;tag=696481e00b43af9959ab49984175d0b9

From: "sippp04" <sip:1904@192.168.11.42;user=phone>;tag=as28cced4a

Call-ID: c6db6be5e68b8f9bb7119a409fc71307@192.168.11.42

CSeq: 102 INVITE

Via: SIP/2.0/UDP 192.168.11.48:5060;received=192.168.11.48;branch=z9hG4bK03fdca2c;rport=5060

Content-Length: 0




1298910992 -> f002 [CConnection::resetKeepAliveTimer] Creating the keep alive timer
1298910992 -> 15c7 [CDialog::receiveInviteRequest]  a new Server INVITE transaction is in progress
1298910992 -> [CSessionTimerContext::updateAfterRefreshReception] Update CSessionTimerContext (refresh reception)
1298910992 -> [CSessionTimerContext::updateSupported] Request timer supported 
1298910992 -> [CSessionTimerContext::updateSessionExpires] Session-Expires updated : 1800
1298910992 -> 15c7 [CDialog::addTransaction] added transaction 21a2 with branch z9hG4bK03fdca2c, with CSeq 102
1298910992 -> 11a7 [CCall::checkAuthentication] INVITE
1298910992 -> 11a7[CMotorCall::controlAuthentication] ---*--- AUTH ---*--- Control authentication
1298910992 -> 11a7[CMotorCall::controlAuthentication] ---*--- END ---*--- Control authentication
1298910992 ->    0[CMotorCall::onReceiveRequest] route already analyzed.
1298910992 -> 11a7[CMotorCall::onReceiveRequest] request for gateway.
1298910992 -> 11a7[CMotorCall::onReceiveRequest] state = COMPLETED_STATE.
1298910992 -> 11a7[CMotorCall::methodInviteReceived] nb available licenses=60 .
1298910992 -> [isUserDeclared] 1904@192.168.11.42
1298910992 -> [isUserDeclared]  found in the dictionnary.
1298910992 -> [CGwServAuthenticate::isUserDeclared]  found in the dictionnary.
1298910992 -> 11a7[CMotorCall::compareDialogFromContext] same Dialog
1298910992 -> 11a7[CMotorCall::methodInviteReceived] Invite => update the inviteContext.
1298910992 -> [CForkedBranch::CForkedBranch] context = 0x402064f4.
1298910992 -> 11a7[CMotorCall::methodInviteReceived] reqUri stored: sip:1904@192.168.11.48
1298910992 -> 11a7[CMotorCall::methodInviteReceived] CAC = 0 
1298910992 -> [receiveInviteMessage] Call: c6db6be5e68b8f9bb7119a409fc71307@192.168.11.42 eqt: 1662 COMPLETED_STATE received a message.
1298910992 -> [receiveInviteMessage] INFO present in Allow header
1298910992 -> [receiveInviteMessage] Allow_INFO_received=TRUE
1298910992 -> [checkSdpValidity] Sdp contains 1 media description(s).
1298910992 -> [checkSdpValidity] Media 0 type 3 contains 1 formats.
1298910992 -> [checkSdpValidity] Format 0 is t38.
1298910992 -> [checkSdpValidity] Format is T38.
1298910992 -> [convertSdpIntoTsdp] Sdp contains 1 media description(s).
1298910992 -> [convertSdpIntoTsdp] default T38 config.
1298910992 -> [convertSdpIntoTsdp] No Direction in the session part.
1298910992 -> [convertSdpIntoTsdp] Check the direction in Session part - result:0.
1298910992 -> [convertSdpIntoTsdp] media IMAGE detected.
1298910992 -> [convertSdpIntoTsdp] Format 0 is t38.
1298910992 -> [convertIPStrIntoTuipv] 192.168.11.48 => 192.168.11.48
1298910992 -> 11a7[CMotorCall::analyseSessionTimer] exit with  uacGatewayMode=0 uacRefresher=0.
1298910992 -> 11a7[emitEventToMonitel] content size (lginfo): 656
1298910992 -> 11a7[emitEventToMonitel] CAC = 0 eqtCAC = -1 
1298910992 -> 11a7[emitEventToMonitel] CAC = 0 
1298910992 -> ** SEPLOS **
1298910992 -> [sendLgEvtSip] Event sent on eqt : 1662 Id :1
1298910992 -> [display_ipc_out] ------------ Begin ---------------
1298910992 -> Id : 1
1298910992 -> RE_INVITE
1298910992 -> SDP :
1298910992 ->   ADDRESS : 192.168.11.48 :4516
1298910992 ->   ALGOS :
1298910992 ->   DIRECTION : SEND & RECEIVE
1298910992 ->   T38 parameters:
1298910992 ->       udp            : 1
1298910992 ->       transfTCF      : 1
1298910992 ->       TCPbidir       : 0
1298910992 ->       UDPredundancy  : 1
1298910992 ->       FillBitRmoval  : 1
1298910992 ->       TranscodingMMR : 0
1298910992 ->       TranscodingJBIG: 0
1298910992 ->       maxbitrate     : 14400
1298910992 ->       maxbuffer      : 256
1298910992 ->       maxdatagram    : 1400
1298910992 ->       version        : 0
1298910992 -> [display_ipc_out] ------------- End ----------------
1298910992 -> [ipc_thread] IPC Thread : Ipc reception 19024.
1298910992 -> [ipc_thread] IPC Thread : TCL thread signaled.
1298910993 -> [exec_ipc] in 19023.
1298910993 -> [display_ipc_in] ------------ Begin ---------------
1298910993 -> neqt : 1662 Id : 1
1298910993 -> SUCCESSFUL
1298910993 -> xx :        0
1298910993 -> 	RELATIVE REQUEST : RE_INVITE
1298910993 -> CLIR : 0
1298910993 -> COLP : 1
1298910993 -> SDP :
1298910993 ->   ADDRESS : 192.168.11.52 :32544
1298910993 ->   ALGOS :
1298910993 ->     PCMA
1298910993 ->     101
1298910993 ->   DIRECTION : SEND & RECEIVE
1298910993 -> [display_ipc_in] ------------- End ----------------
1298910993 -> [CMotorCallManager::onIncomingEvent] an event 10765 arrived on the eqt 1662-1.
1298910993 -> [CMotorCall::onIncomingEvent] seplos station.
1298910993 -> [CMotorCallManager::onIncomingEvent] state = MODIFICATION_PROCEEDING_STATE.
1298910993 -> [onIncomingEvent] SUCCESSFUL arrived.
1298910993 -> [onIncomingEvent] relative_request = 10753
1298910993 -> [receiveSuccessfulEvent] Call: c6db6be5e68b8f9bb7119a409fc71307@192.168.11.42 eqt: 1662 MODIFICATION_PROCEEDING_STATE received an event.
1298910993 -> [quoteString] => "test2"
1298910993 -> 11a7[CMotorCall::emitSuccessfulMessage] P-Asserted-Identity: "test2" <sip:2002@192.168.11.42;user=phone>
1298910993 -> 11a7[CMotorCall::inviteBuildContact]  Contact: sip:192.168.11.42
1298910993 -> 11a7[CMotorCall::emitSuccessfullMessage] uacGatewayMode=0 uacRefresher=0.
1298910993 -> 11a7[CMotorCall::makeResponseSdp] Begin sdp.
1298910993 -> 11a7[CMotorCall::makeResponseSdp] Audio media.
1298910993 -> 11a7[CMotorCall::makeResponseSdp] format 101
1298910993 -> 11a7[CMotorCall::makeResponseSdp] fromSdp.getMediaDesciprionCount :1
1298910993 -> 11a7[CMotorCall::makeResponseSdp] Media image.
1298910993 -> 11a7[CMotorCall::makeResponseSdp] Media accepted 0 : m=audio 32544 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=ptime:20
a=maxptime:30
a=rtpmap:101 telephone-event/8000
a=sendrecv
.
1298910993 -> 11a7[CMotorCall::makeResponseSdp] Final Sdp.
1298910993 -> 11a7[CMotorCall::emitSuccesfulMessage] Answer sdp.
1298910993 -> 11a7 [CCall::makeGenericResponse] 200
1298910993 -> 11a7 [CCall::getDialog] Confirmed Dialog found
1298910993 -> 15c7 [CDialog::createResponse] 200
1298910993 -> 21a2 [CTransProceedingState::createResponse] CResponse 383 ref=1
1298910993 -> 383 [CMessage::send] sip_sendMessage (192.168.11.48:5060)
1298910993 -> SEND MESSAGE TO NETWORK (192.168.11.48:5060 [UDP]) (BUFF LEN = 906)
----------------------utf8-----------------------
SIP/2.0 200 OK

Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE

Contact: sip:192.168.11.42

Supported: replaces,timer,100rel

User-Agent: OmniPCX Enterprise R9.1 i1.605.21

Session-Expires: 1800;refresher=uas

P-Asserted-Identity: "test2" <sip:2002@192.168.11.42;user=phone>

Content-Type: application/sdp

To: "test2" <sip:2002@192.168.11.42;user=phone>;tag=696481e00b43af9959ab49984175d0b9

From: "sippp04" <sip:1904@192.168.11.42;user=phone>;tag=as28cced4a

Call-ID: c6db6be5e68b8f9bb7119a409fc71307@192.168.11.42

CSeq: 102 INVITE

Via: SIP/2.0/UDP 192.168.11.48:5060;received=192.168.11.48;branch=z9hG4bK03fdca2c;rport=5060

Content-Length: 218



v=0

o=OXE 1298910988 1298910989 IN IP4 192.168.11.42

s=abs

c=IN IP4 192.168.11.52

t=0 0

m=audio 32544 RTP/AVP 8 101

a=rtpmap:8 PCMA/8000

a=ptime:20

a=maxptime:30

a=rtpmap:101 telephone-event/8000

a=sendrecv

-------------------------------------------------
1298910993 -> [CTransport::findConnection] pConnection = f002
1298910993 -> [CTransport::getConnection] RemoteHost=(192.168.11.48 5060) already exist, using connection f002
1298910993 ->  udp_send fd:3
1298910993 -> udp_send fd:3 port 5060 iBytesSend:906 message:SIP/2.0 200 OK

Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE

Contact: sip:192.168.11.42

Supported: replaces,timer,100rel

User-Agent: OmniPCX Enterprise R9.1 i1.605.21

Session-Expires: 1800;refresher=uas

P-Asserted-Identity: "test2" <sip:2002@192.168.11.42;user=phone>

Content-Type: application/sdp

To: "test2" <sip:2002@192.168.11.42;user=phone>;tag=696481e00b43af9959ab49984175d0b9

From: "sippp04" <sip:1904@192.168.11.42;user=phone>;tag=as28cced4a

Call-ID: c6db6be5e68b8f9bb7119a409fc71307@192.168.11.42

CSeq: 102 INVITE

Via: SIP/2.0/UDP 192.168.11.48:5060;received=192.168.11.48;branch=z9hG4bK03fdca2c;rport=5060

Content-Length: 218



v=0

o=OXE 1298910988 1298910989 IN IP4 192.168.11.42

s=abs

c=IN IP4 192.168.11.52

t=0 0

m=audio 32544 RTP/AVP 8 101

a=rtpmap:8 PCMA/8000

a=ptime:20

a=maxptime:30

a=rtpmap:101 telephone-event/8000

a=sendrecv


1298910993 -> f002 [CConnection::resetKeepAliveTimer] Creating the keep alive timer
1298910993 -> 382 [~CResponse] resp(100) c6db6be5e68b8f9bb7119a409fc71307@192.168.11.42 102 INVITE
1298910993 -> 21a2 [CTransProceedingState::createResponse] Final : Transaction changes to Completed state
1298910993 -> 21a2 [CTransaction::changeState] STATE CHANGED TO COMPLETED
1298910993 -> 21a2 [CTransaction::changeState] notifying the parent dialog
1298910993 -> 15c7 [CDialog::onTransactionState(pTrans = 21a2, previousState = Proceeding, currentState = Completed, reason = Final resp creation]
1298910993 -> 21a2 [CTransaction::startTimer] Timer G is started (delay = 500 ms)
1298910993 -> 21a2 [CTransaction::startTimer] Timer H is started (delay = 32000 ms)
1298910993 -> 15c7 [CDialog::updateRouteSet] there are 0 record-route headers
1298910993 -> [CSessionTimerContext::updateAfterResponseRefreshCreation] Update CSessionTimerContext (response refresh creation)
1298910993 -> [CSessionTimerContext::updateSessionExpires] header value : 1800
1298910993 -> [CSessionTimerContext::updateSessionExpires] Refresher updated : i
1298910993 -> [CSessionTimerContext::updateSessionExpires] Session-Expires updated : 1800
1298910993 -> [CSessionTimerContext::startTimers] Start session timer expiration : 1800000
1298910993 -> [CSessionTimerContext::startTimers] Start session timer will expires : 900000
1298910993 -> [exec_ipc] in end 19023.
1298910993 -> udp_recv fd:3
1298910993 -> udp_recv fd:3 iBytesRead:450 message:ACK sip:2002@192.168.11.42;transport=UDP SIP/2.0

Via: SIP/2.0/UDP 192.168.11.48:5060;branch=z9hG4bK2d90c51d;rport

Max-Forwards: 70

From: "sippp04" <sip:1904@192.168.11.42;user=phone>;tag=as28cced4a

To: "test2" <sip:2002@192.168.11.42;user=phone>;tag=696481e00b43af9959ab49984175d0b9

Contact: <sip:1904@192.168.11.48>

Call-ID: c6db6be5e68b8f9bb7119a409fc71307@192.168.11.42

CSeq: 102 ACK

User-Agent: Asterisk PBX 1.6.2.6

Content-Length: 0




1298910993 -> UdpInputProc iSock:3 iBytesRead:450 Host:192.168.11.48 Port:5060
1298910993 -> [CTransportUdp::receiveMessage] from 192.168.11.48:5060 rc:450
1298910993 -> [CTransport::findConnection] pConnection = f002
1298910993 -> f002 [CConnection::dOSTest](192.168.11.48,5060,0) MaxNbMsgByPeriod:25 Period:3
1298910993 -> f002 [CConnection::dOSTest](192.168.11.48,5060,0) NbMsg:20 < seuil
1298910993 -> f002 [CConnection::resetKeepAliveTimer] Creating the keep alive timer

Billy · Post by **Billy** » 04 Mar 2011 08:44

FIXED!!!

1. Delete SIP Extension 1904.
2. Create SIP DEVICE 1904 with the same parameters!
3. Add the following to *'s sip.conf unless it's there already:
[general]
t38pt_udptl=yes,fec
canreinvite = yes
session-timers=refuse

4. Voila.

krzysioD · Post by **krzysioD** » 04 Mar 2011 10:17

I use simple BRA / PRA for fax.
Much more reliable and faster ( 14.4 kbps).
Faster=> cheaper for sender to send facsimile.

Billy · Post by **Billy** » 04 Mar 2011 10:24

krzysioD wrote:I use simple BRA / PRA for fax.
Much more reliable and faster ( 14.4 kbps).
Faster=> cheaper for sender to send facsimile.

Not my task. I have an asterisk microPBX at client's side which serves 12 analog and 30 SIP lines, DISA, fax, voicemail system, and costs $500 - less than one PRA card, nothing to say about a whole TDM box.
BTW, 9.1 can handle 14400 over t.38, despite it's not stated in the documentation.

krzysioD · Post by **krzysioD** » 04 Mar 2011 10:39

I just love when ALU does something, but does not care to drop a single line in system's doc.

root · Post by **root** » 09 Apr 2011 19:32

Billy wrote:
krzysioD wrote:BTW, 9.1 can handle 14400 over t.38, despite it's not stated in the documentation.

yes, but only to other sip devices and not the GD/INTIP

krzysioD · Post by **krzysioD** » 10 Apr 2011 03:11

still, to have just a chance of JBIG and 33600 bps faxing, you go with TDM.
If you send a lot of facsmiles, even if 40% is above 9600 and next 10% is above 1440 you earn in terms of cents per minutes of call duration, as if you mainly receive it does not calculate in cents so easy, but if you decide how much B channel whould be used ad one given time, then well you could have only use card with 4 ports with TDM and v.34, when with 9600 and FoIP / T.38 it will be a 8 or 12 or even 16 channels.

The transmission time of an test-page is about 3 minutes (9600 bps) to 1 minute (V17, MMR) , to 17 seconds when using V.34 and JBIG.
So i stick with TDM fax-boards....

Billy · Post by **Billy** » 10 Apr 2011 07:40

Well, my 'Fixed!' looks a bit preliminary as I see now.
Faxes are coming ok from an analog extension within the same OXE where the receiving SIP user is located; but incoming fax calls from PSTN fail...
Digging the problem now.

Alcatel Unleashed

T.38 Fax to Asterisk via SIP, OXE R9.1 MG.

T.38 Fax to Asterisk via SIP, OXE R9.1 MG.

Re: T.38 Fax to Asterisk via SIP, OXE R9.1 MG.

Re: T.38 Fax to Asterisk via SIP, OXE R9.1 MG.

Re: T.38 Fax to Asterisk via SIP, OXE R9.1 MG.

Re: T.38 Fax to Asterisk via SIP, OXE R9.1 MG.

Re: T.38 Fax to Asterisk via SIP, OXE R9.1 MG.

Re: T.38 Fax to Asterisk via SIP, OXE R9.1 MG.

Re: T.38 Fax to Asterisk via SIP, OXE R9.1 MG.