Hi All,
is there anyone that tried to work with the t38modem project integrated
with SIP through OPAL libraries in OpenSuse 10.2?
I followed the cookbook at
http://www.voip-info.org/wiki/index.php?page_id=5096 and I've a strange
behavior.
Firs of all when the t38modem starts, I've an error message that I think
is related to some library not present in my current OpenSuse
installation (but I'm not able to understand which library is still
requiring, if anyone is able to help me to understand what's happening
I'll be very happy to hear him). The message is:
"error loading avcodec - avcodec: cannot open shared object file: No
such file or directory"
Running a ldd ./t38modem all seems ok.
The next problem arises when I send faxes through an HT386 ATA and
asterisk 1.4.20.1. Looking at the network traffic through ethereal I can
see that the t38modem answer to the first INVITE message with a 100
TRYING message.. but it never send an ACK. At the same time, the
t38modem is producing the log I've attached below (sorry for the long
post).
Any help is appreciated.
Thank you.
Marco Signorini
2008/09/22 23:53:39.395 Opal Liste...er:80b95c8 SIP PDU Received on
udp$192.168.0.5:5060<if=udp$192.168.0.5:6060>
INVITE sip:910 at 192.168.0.5:6060 SIP/2.0
Date: Mon, 22 Sep 2008 21:53:39 GMT
CSeq: 102 INVITE
Via: SIP/2.0/UDP 192.168.0.5:5060;branch=z9hG4bK2409d6cb
User-Agent: Cadore 9 PBX
From: "Soggiorno2" <sip:16 at 192.168.0.5>;tag=as6bf57b61
Call-ID: 44eed97b12bfca1053d0f98b2bfcb751 at 192.168.0.5
Supported: replaces
To: <sip:910 at 192.168.0.5:6060>
Contact: <sip:16 at 192.168.0.5>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Type: application/sdp
Content-Length: 508
Max-Forwards: 70
v=0
o=root 3222 3222 IN IP4 192.168.0.5
s=session
c=IN IP4 192.168.0.5
t=0 0
m=audio 5018 RTP/AVP 0 97 3 8 112 5 10 7 18 111 101
a=rtpmap:0 PCMU/8000
a=rtpmap:97 iLBC/8000
a=fmtp:97 mode=30
a=rtpmap:3 GSM/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:112 G726-32/8000
a=rtpmap:5 DVI4/8000
a=rtpmap:10 L16/8000
a=rtpmap:7 LPC/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:111 G726-32/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv
2008/09/22 23:53:39.400 Opal Liste...er:80b95c8 SDP Media session
port=5018
2008/09/22 23:53:39.401 Opal Liste...er:80b95c8 SDP Adding media
session with 11 formats
2008/09/22 23:53:39.402 Opal Liste...er:80b95c8 SDP Unknown media
attribute silenceSupp:off - - - -
2008/09/22 23:53:39.405 Opal Liste...er:80b95c8 SIP Sending PDU on
udp$192.168.0.5:5060<if=udp$192.168.0.5:6060>
SIP/2.0 100 Trying
CSeq: 102 INVITE
Via: SIP/2.0/UDP 192.168.0.5:5060;branch=z9hG4bK2409d6cb
From: "Soggiorno2" <sip:16 at 192.168.0.5>;tag=as6bf57b61
Call-ID: 44eed97b12bfca1053d0f98b2bfcb751 at 192.168.0.5
To: <sip:910 at 192.168.0.5:6060>
Contact: <sip:910 at 192.168.0.5:6060;transport=udp>
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,NOTIFY,REFER,MESSAGE,INFO,PING,PUBLISH
Content-Length: 0
2008/09/22 23:53:39.408 Opal Liste...er:80b95c8 Call Created Call[4]
2008/09/22 23:53:39.409 Opal Liste...er:80b95c8
MySIPEndPoint::CreateConnection for Call[4]
2008/09/22 23:53:39.409 Opal Liste...er:80b95c8 OpalCon Created
connection Call[4]-EP<sip>[44eed97b12bfca1053d0f98b2bfcb751 at
192.168.0.5]
2008/09/22 23:53:39.410 Opal Liste...er:80b95c8 RFC2833 Handler created
2008/09/22 23:53:39.411 Opal Liste...er:80b95c8 RFC2833 Handler created
2008/09/22 23:53:39.415 Opal Liste...er:80b95c8 OpalUDP Binding to
interface: 192.168.0.5:5651
2008/09/22 23:53:39.416 Opal Liste...er:80b95c8 SIP Created
transport udp$0.0.0.0<if=udp$192.168.0.5:5651>
2008/09/22 23:53:39.417 Opal Liste...er:80b95c8 OpalUDP Started connect
to 192.168.0.5:6060
2008/09/22 23:53:39.418 Opal Liste...er:80b95c8 OpalUDP Connect on
pre-bound interface: 192.168.0.5
2008/09/22 23:53:39.419 Opal Liste...er:80b95c8 PWLib Created thread
0x80e1690 SIP Transport:%x
2008/09/22 23:53:39.420 Opal Liste...er:80b95c8 SIP Created connection.
2008/09/22 23:53:39.421 Opal Liste...er:80b95c8 SIP Queueing PDU:
102 INVITE sip:910 at 192.168.0.5:6060
2008/09/22 23:53:39.422 Opal Liste...er:80b95c8 PWLib Created thread
0x80e3190 SIP Handler:%x
2008/09/22 23:53:39.422 Opal Liste...er:80b95c8 Opal Transport clean
up on termination
2008/09/22 23:53:39.423 Opal Liste...er:80b95c8 OpalUDP Close
2008/09/22 23:53:39.423 Opal Liste...er:80b95c8 Opal Deleted
transport udp$192.168.0.5:5060<if=udp$192.168.0.5:6060>
2008/09/22 23:53:39.556 Opal Liste...er:80b95c8 Listen Waiting on UDP
packet on udp$192.168.0.5:6060
2008/09/22 23:53:39.557 SIP Transp...rt:80e1690 PWLib Started thread
0x80e1690 SIP Transport:80e1690
2008/09/22 23:53:39.557 SIP Transp...rt:80e1690 SIP Read thread started.
2008/09/22 23:53:39.558 SIP Transp...rt:80e1690 SIP Waiting for PDU
on udp$192.168.0.5:6060<if=udp$192.168.0.5:5651>
2008/09/22 23:53:39.559 SIP Handle...er:80e3190 PWLib Started thread
0x80e3190 SIP Handler:80e3190
2008/09/22 23:53:39.559 SIP Handle...er:80e3190 SIP PDU handler
thread started.
2008/09/22 23:53:39.560 SIP Handle...er:80e3190 SIP Awaiting next PDU.
2008/09/22 23:53:39.560 SIP Handle...er:80e3190 SIP Handling PDU 102
INVITE sip:910 at 192.168.0.5:6060
2008/09/22 23:53:39.563 SIP Handle...er:80e3190 SIP Set
targetAddress to sip:16 at 192.168.0.5
2008/09/22 23:53:39.564 SIP Handle...er:80e3190 OpalMan On incoming
connection Call[4]-EP<sip>[44eed97b12bfca1053d0f98b2bfcb751 at
192.168.0.5]
2008/09/22 23:53:39.564 SIP Handle...er:80e3190 Call
GetOtherPartyConnection
Call[4]-EP<sip>[44eed97b12bfca1053d0f98b2bfcb751 at 192.168.0.5]
2008/09/22 23:53:39.566 SIP Handle...er:80e3190
MySIPConnection::GetDestinationAddress
<sip:910 at 192.168.0.5:6060>;tag=a63a6595-5e87-dd11-8b87-00105a45da35
2008/09/22 23:53:39.566 SIP Handle...er:80e3190 OpalMan Searching for
route "sip:910"
2008/09/22 23:53:39.567 SIP Handle...er:80e3190
MySIPConnection::GetDestinationAddress
<sip:910 at 192.168.0.5:6060>;tag=a63a6595-5e87-dd11-8b87-00105a45da35
Call[4] from sip:16 at 192.168.0.5 to 910, route to modem:910
2008/09/22 23:53:39.568 SIP Handle...er:80e3190 Call[4] from
sip:16 at 192.168.0.5 to 910, route to modem:910
2008/09/22 23:53:39.568 SIP Handle...er:80e3190 OpalMan Set up
connection to "modem:910"
2008/09/22 23:53:39.569 SIP Handle...er:80e3190
ModemEndPoint::MakeConnection modem:910
2008/09/22 23:53:39.569 SIP Handle...er:80e3190 OpalCon Created
connection Call[4]-EP<modem>[modem:910/4/0]
2008/09/22 23:53:39.570 SIP Handle...er:80e3190 RFC2833 Handler created
2008/09/22 23:53:39.570 SIP Handle...er:80e3190 RFC2833 Handler created
2008/09/22 23:53:39.571 SIP Handle...er:80e3190
ModemConnection::ModemConnection Call[4]-EP<modem>[modem:910/4/0]
2008/09/22 23:53:39.572 SIP Handle...er:80e3190 SIP
OnIncomingConnection succeeded for INVITE from sip:910 at 192.168.0.5:6060
for Call[4]-EP<sip>[44eed97b12bfca1053d0f98b2bfcb751 at 192.168.0.5]
2008/09/22 23:53:39.572 SIP Handle...er:80e3190 OpalCon SetPhase from
UninitialisedPhase to SetUpPhase
2008/09/22 23:53:39.573 SIP Handle...er:80e3190 SIP RTP payload type
PCMU matched to codec G.711-uLaw-64k
2008/09/22 23:53:39.574 SIP Handle...er:80e3190 SIP RTP payload type
[pt=97] matched to codec iLBC-13k3
2008/09/22 23:53:39.575 SIP Handle...er:80e3190 SIP RTP payload type
GSM matched to codec GSM-06.10
2008/09/22 23:53:39.576 SIP Handle...er:80e3190 SIP RTP payload type
PCMA matched to codec G.711-ALaw-64k
2008/09/22 23:53:39.577 SIP Handle...er:80e3190 SIP RTP payload type
[pt=112] matched to codec G.726-32k
2008/09/22 23:53:39.578 SIP Handle...er:80e3190 SIP RTP payload type
DVI4_8k not matched to audio codec
2008/09/22 23:53:39.579 SIP Handle...er:80e3190 SIP RTP payload type
L16_Stereo not matched to audio codec
2008/09/22 23:53:39.580 SIP Handle...er:80e3190 SIP RTP payload type
LPC not matched to audio codec
2008/09/22 23:53:39.581 SIP Handle...er:80e3190 SIP RTP payload type
G729 not matched to audio codec
2008/09/22 23:53:39.582 SIP Handle...er:80e3190 SIP RTP payload type
[pt=111] matched to codec G.726-32k
2008/09/22 23:53:39.584 SIP Handle...er:80e3190 Call
GetOtherPartyConnection
Call[4]-EP<sip>[44eed97b12bfca1053d0f98b2bfcb751 at 192.168.0.5]
2008/09/22 23:53:39.614 SIP Handle...er:80e3190 Call OnSetUp
Call[4]-EP<sip>[44eed97b12bfca1053d0f98b2bfcb751 at 192.168.0.5]
2008/09/22 23:53:39.625 SIP Handle...er:80e3190
ModemConnection::SetUpConnection Call[4]-EP<modem>[modem:910/4/0]
2008/09/22 23:53:39.626 SIP Handle...er:80e3190 OpalCon SetPhase from
UninitialisedPhase to SetUpPhase
2008/09/22 23:53:39.627 SIP Handle...er:80e3190 Call
GetOtherPartyConnection Call[4]-EP<modem>[modem:910/4/0]
2008/09/22 23:53:39.628 SIP Handle...er:80e3190
ModemConnection::SetUpConnection dstNum=910 srcNum= ...
2008/09/22 23:53:39.628 SIP Handle...er:80e3190
PseudoModemQ::DequeueWithRoute ttyT38-1
2008/09/22 23:53:39.629 SIP Handle...er:80e3190 ttyT38-1
T38Engine::T38Engine
2008/09/22 23:53:39.630 SIP Handle...er:80e3190 ModemEngineBody::Request
request={
calltoken=modem:910/4/0
dstnum=910
srcnumcommand=call
}
2008/09/22 23:53:39.631 SIP Handle...er:80e3190
ModemEngineBody::OnTimerCallback Timeout 1
2008/09/22 23:53:39.632 ttyT38-1(e...3053325200 <-- {
0d 0a 52 49 4e 47 0d 0a ..RING.. }
2008/09/22 23:53:39.632 SIP Handle...er:80e3190 ... Ok
2008/09/22 23:53:39.633 SIP Handle...er:80e3190 OpalCon SetPhase from
SetUpPhase to AlertingPhase
2008/09/22 23:53:39.633 SIP Handle...er:80e3190 OpalMan OnAlerting
Call[4]-EP<modem>[modem:910/4/0]
2008/09/22 23:53:39.634 SIP Handle...er:80e3190 Call OnAlerting
Call[4]-EP<modem>[modem:910/4/0]
2008/09/22 23:53:39.634 SIP Handle...er:80e3190 SIP SetAlerting
2008/09/22 23:53:39.635 SIP Handle...er:80e3190 OpalCon SetPhase from
SetUpPhase to AlertingPhase
2008/09/22 23:53:39.635 SIP Handle...er:80e3190 OpalCon
OnSetUpConnectionCall[4]-EP<modem>[modem:910/4/0]
2008/09/22 23:53:39.636 SIP Handle...er:80e3190 OpalEP
OnSetUpConnection Call[4]-EP<modem>[modem:910/4/0]
2008/09/22 23:53:39.636 SIP Handle...er:80e3190 SIP SetAlerting
2008/09/22 23:53:39.637 SIP Handle...er:80e3190 SIP Awaiting next PDU.
2008/09/22 23:53:44.637 Housekeeper ModemEngineBody::OnTimerCallback
Timeout 1
2008/09/22 23:53:44.638 ttyT38-1(e...3053325200 <-- {
0d 0a 52 49 4e 47 0d 0a ..RING.. }
2008/09/22 23:53:49.641 Housekeeper ModemEngineBody::OnTimerCallback
Timeout 1
2008/09/22 23:53:49.642 ttyT38-1(e...3053325200 <-- {
0d 0a 52 49 4e 47 0d 0a ..RING.. }
2008/09/22 23:53:54.645 Housekeeper ModemEngineBody::OnTimerCallback
Timeout 1
2008/09/22 23:53:54.646 ttyT38-1(e...3053325200 <-- {
0d 0a 52 49 4e 47 0d 0a ..RING.. }
2008/09/22 23:53:59.649 Housekeeper ModemEngineBody::OnTimerCallback
Timeout 1
2008/09/22 23:53:59.650 ttyT38-1(e...3053325200 <-- {
0d 0a 52 49 4e 47 0d 0a ..RING.. }
2008/09/22 23:54:03.581 Housekeeper SIP NAT Binding refresh started.
2008/09/22 23:54:04.653 Housekeeper ModemEngineBody::OnTimerCallback
Timeout 1
2008/09/22 23:54:04.654 ttyT38-1(e...3053325200 <-- {
0d 0a 52 49 4e 47 0d 0a ..RING.. }
2008/09/22 23:54:09.658 Housekeeper ModemEngineBody::OnTimerCallback
Timeout 1
2008/09/22 23:54:09.659 ttyT38-1(e...3053325200 <-- {
0d 0a 52 49 4e 47 0d 0a ..RING.. }