Bug#706883: marked as done (t38modem: segfault at 558 ip 00007fcd6f3a5604 sp 00007fcd55ec6cd0 error 4 in libopal.so.3.10.4)

Wed, 21 Nov 2018 13:52:37 -0800 

Your message dated Wed, 21 Nov 2018 21:49:52 +0000
with message-id <[email protected]>
and subject line Bug#911596: Removed package(s) from unstable
has caused the Debian Bug report #706883,
regarding t38modem: segfault at 558 ip 00007fcd6f3a5604 sp 00007fcd55ec6cd0 
error 4 in libopal.so.3.10.4
to be marked as done.

This means that you claim that the problem has been dealt with.
If this is not the case it is now your responsibility to reopen the
Bug report if necessary, and/or fix the problem forthwith.

(NB: If you are a system administrator and have no idea what this
message is talking about, this may indicate a serious mail system
misconfiguration somewhere. Please contact [email protected]
immediately.)


-- 
706883: https://bugs.debian.org/cgi-bin/bugreport.cgi?bug=706883
Debian Bug Tracking System
Contact [email protected] with problems
--- Begin Message --- 
Package: t38modem
Version: 2.0.0-3
Severity: important

-----BEGIN PGP SIGNED MESSAGE-----
Hash: SHA1

Dear Maintainer,


t38modem segfaults here. Syslog says: 
May  5 21:09:33 ceramic kernel: [ 7830.372934] t38modem[21847]: segfault at 558 
ip 00007fcd6f3a5604 sp 00007fcd55ec6cd0 error 4 in 
libopal.so.3.10.4[7fcd6e811000+ec0000]


I try to use t38modem as SIP-Modem for hylafax. Setup worked on Squeeze somehow
but not very reliable. 

I registered t38modem on my ATA which "speaks" T.38 (Fritz.Box 7390 by AVM). 
The FritzBox
registers at my SIP provider. 


t38modem is started by an init script. 


the command is

/usr/bin/t38modem -tttt -o /var/log/t38modem.log  --no-h323  --sip-listen 
192.168.0.21 --sip-register [email protected],topsecret --sip-old-asn 
--ptty +/dev/ttyT38-1 --route modem:.*=sip:<dn>@${192.168.0.222} --route 
sip:.*=modem:<dn>"

192.168.0.21 is the IP address of the maschine running t38modem
192.168.0.222 is the IP address of the ATA ("Fritz Box").

The ATA shows a phone call of one second duration, so at least something works 
correctly. 

I attached the t38modem logfile which may help to debug.


Thank you very much!


Sebastian





- -- System Information:
Debian Release: 7.0
  APT prefers stable
  APT policy: (500, 'stable')
Architecture: amd64 (x86_64)
Foreign Architectures: i386

Kernel: Linux 3.2.0-4-amd64 (SMP w/3 CPU cores)
Locale: LANG=de_DE.UTF-8, LC_CTYPE=de_DE.UTF-8 (charmap=UTF-8)
Shell: /bin/sh linked to /bin/dash

Versions of packages t38modem depends on:
ii  libc6          2.13-38
ii  libgcc1        1:4.7.2-5
ii  libopal3.10.4  3.10.4~dfsg-3
ii  libpt2.10.4    2.10.4~dfsg-1
ii  libspeexdsp1   1.2~rc1-7
ii  libstdc++6     4.7.2-5

t38modem recommends no packages.

Versions of packages t38modem suggests:
ii  hylafax-server  3:6.0.6-5

- -- debconf-show failed

*** /tmp/t38modem.log
2013/05/05 21:07:58.756                T38Modem         Version 2.0.0 by 
Vyacheslav Frolov on Unix Linux (3.2.0-4-amd64-x86_64) with PTLib (v2.10.4 
(svn:26606)) at 2013/5/5 21:07:58.756
2013/05/05 21:07:58.756                T38Modem T38Modem Version 2.0.0 
(OPAL-3.10.4/3.10.4 (svn:26616), PTLIB-2.10.4/2.10.4 (svn:26606)) on Unix Linux 
(3.2.0-4-amd64-x86_64)
2013/05/05 21:07:58.756                T38Modem Options: -tttt -o 
/var/log/t38modem.log --no-h323 --sip-listen 192.168.0.21 --sip-register 
[email protected],topsecret --sip-old-asn --ptty +/dev/ttyT38-0 --route 
modem:.*=sip:<dn>@192.168.0.222 --route sip:.*=modem:<dn>
2013/05/05 21:07:58.756                T38Modem Detecting V4L2 devices
2013/05/05 21:07:58.756                T38Modem Unable to detect v4l2 directory
2013/05/05 21:07:58.761                T38Modem PWLib   File handle high water 
mark set: 10 Thread unblock pipe
2013/05/05 21:07:58.761                T38Modem PTLib   Thread high water mark 
set: 3
2013/05/05 21:07:58.761                T38Modem OpalMan Created manager.
2013/05/05 21:07:58.762                T38Modem OpalMan Attached endpoint with 
prefix modem
2013/05/05 21:07:58.762                T38Modem OpalEP  Created endpoint: modem
2013/05/05 21:07:58.762                T38Modem ModemEndPoint::ModemEndPoint
2013/05/05 21:07:58.762                T38Modem PWLib   File handle high water 
mark set: 14 Thread unblock pipe
2013/05/05 21:07:58.762                T38Modem PseudoModemList::Append 
ttyT38-0 (0) OK
2013/05/05 21:07:58.762                T38Modem PTLib   Thread high water mark 
set: 4
2013/05/05 21:07:58.762                T38Modem OpalMan Attached endpoint with 
prefix sip
2013/05/05 21:07:58.762                T38Modem OpalEP  Created endpoint: sip
2013/05/05 21:07:58.762 ttyT38-0(b...0x6fa12700 RenameCurrentThread old 
ThreadName=PseudoModemPty:0x6fa12700
2013/05/05 21:07:58.762                T38Modem PWLib   File handle high water 
mark set: 15 PUDPSocket
2013/05/05 21:07:58.762 ttyT38-0(b...0x6fa12700 Started for /dev/ttyT38-0 
(accepts all)
2013/05/05 21:07:58.762 ttyT38-0(b...0x6fa12700 ModemEndPoint::OnMyCallback 
command=addmodem extra=10
2013/05/05 21:07:58.762                T38Modem IfaceMon        Initial 
interface list:
127.0.0.1 <00-00-00-00-00-00> (lo)
192.168.0.21 <6C-62-6D-B6-9C-09> (eth0)
10.8.0.1 <00-00-00-00-00-00> (tun0)
fe80::ffff:ffff:ffff <00-00-00-00-00-00> (teredo)
2001:0:53aa:64c:cde:673e:adac:d617 <00-00-00-00-00-00> (teredo)
fe80::6e62:6dff:feb6:9c09 <6C-62-6D-B6-9C-09> (eth0)
::1 <00-00-00-00-00-00> (lo)

2013/05/05 21:07:58.762 ttyT38-0(b...0x6fa12700 PseudoModemQ::Enqueue ttyT38-0
2013/05/05 21:07:58.762                T38Modem PTLIB   Opened NetLink socket
2013/05/05 21:07:58.762                T38Modem PWLib   File handle high water 
mark set: 19 Thread unblock pipe
2013/05/05 21:07:58.763                T38Modem PTLib   Thread high water mark 
set: 5
2013/05/05 21:07:58.763 ttyT38-0(b...0x6fa12700 ModemEndPoint::OnMyCallback 
request={
command=addmodem
response=confirm
modemtoken=ttyT38-0
}
2013/05/05 21:07:58.763                T38Modem PWLib   File handle high water 
mark set: 21 Thread unblock pipe
2013/05/05 21:07:58.763                T38Modem PTLib   Thread high water mark 
set: 6
2013/05/05 21:07:58.763 ttyT38-0(b...0x6fa12700 PseudoModemPty::OpenPty removed 
link /dev/ttyT38-0 -> /dev/pts/9
2013/05/05 21:07:58.763 ttyT38-0(b...0x6fa12700 PseudoModemPty::OpenPty added 
link /dev/ttyT38-0 -> /dev/pts/9
2013/05/05 21:07:58.763                T38Modem OpalMan Attached endpoint with 
prefix sips
2013/05/05 21:07:58.763                T38Modem SIP     Created endpoint.
2013/05/05 21:07:58.763 ttyT38-0(b...0x6fa12700 PWLib   File handle high water 
mark set: 24 Thread unblock pipe
2013/05/05 21:07:58.763 ttyT38-0(b...0x6fa12700 PWLib   File handle high water 
mark set: 26 Thread unblock pipe
2013/05/05 21:07:58.763 ttyT38-0(b...0x6fa12700 PWLib   File handle high water 
mark set: 28 Thread unblock pipe
2013/05/05 21:07:58.763                T38Modem PWLib   File handle high water 
mark set: 29 PUDPSocket
2013/05/05 21:07:58.763                T38Modem MonSock Created monitored 
socket for interfaces 192.168.0.21
2013/05/05 21:07:58.763 Network In...0x60189700 IfaceMon        Started 
interface monitor thread.
2013/05/05 21:07:58.764                T38Modem PWLib   File handle high water 
mark set: 30 PUDPSocket
2013/05/05 21:07:58.764 ttyT38-0(b...0x6fa12700 PTLib   Thread high water mark 
set: 7
2013/05/05 21:07:58.764                T38Modem MonSock Created bundled UDP 
socket 192.168.0.21:5060
2013/05/05 21:07:58.764                T38Modem PWLib   File handle high water 
mark set: 32 Thread unblock pipe
2013/05/05 21:07:58.764 ttyT38-0(b...0x6fa12700 PTLib   Thread high water mark 
set: 8
2013/05/05 21:07:58.764                T38Modem PTLib   Thread high water mark 
set: 9
2013/05/05 21:07:58.764 ttyT38-0(e...0x60107700 RenameCurrentThread old 
ThreadName=ModemEngine:0x60107700
2013/05/05 21:07:58.764 ttyT38-0(e...0x60107700 <-> Started
2013/05/05 21:07:58.764 ttyT38-0(b...0x6fa12700 PTLib   Thread high water mark 
set: 10
2013/05/05 21:07:58.764                T38Modem PWLib   File handle high water 
mark set: 33 PTCPSocket
2013/05/05 21:07:58.764 Opal Liste...0x60085700 Listen  Started listening 
thread on udp$192.168.0.21:5060
2013/05/05 21:07:58.764                T38Modem PWLib   File handle high water 
mark set: 35 Thread unblock pipe
2013/05/05 21:07:58.765                T38Modem PTLib   Thread high water mark 
set: 11
2013/05/05 21:07:58.765                T38Modem OpalEP  Could not create 
listener: tcps$:5061192.168.0.21
2013/05/05 21:07:58.765 ttyT38-0(o...0x60044700 RenameCurrentThread old 
ThreadName=OutPty:0x60044700
2013/05/05 21:07:58.765 Opal Liste...0x55f4a700 Listen  Started listening 
thread on tcp$192.168.0.21:5060
2013/05/05 21:07:58.765                T38Modem SIP     Start REGISTER
          [email protected]
       remote=
        local=
      contact=
       authID=
        realm=
       expire=300
      restore=30
     minRetry=default
     maxRetry=default
compatibility=FullyCompliant
2013/05/05 21:07:58.765 ttyT38-0(i...0x600c6700 RenameCurrentThread old 
ThreadName=InPty:0x600c6700
2013/05/05 21:07:58.765 ttyT38-0(o...0x60044700 <-- Started
2013/05/05 21:07:58.765 ttyT38-0(i...0x600c6700 --> Started
2013/05/05 21:07:58.765 Opal Liste...0x55f4a700 Listen  Waiting on socket 
accept on tcp$192.168.0.21:5060
2013/05/05 21:07:58.766                T38Modem PWLib   File handle high water 
mark set: 36 PUDPSocket
2013/05/05 21:07:58.766                T38Modem SIP     Constructed REGISTER 
handler for sip:[email protected]
2013/05/05 21:07:58.767                T38Modem SIP     Executing state change 
to Subscribing for REGISTER handler, target=sip:[email protected], 
id=72e6c5c4-24b4-e211-9edb-6c626db69c09@ceramic
2013/05/05 21:07:58.767                T38Modem SIP     Changing REGISTER 
handler from Unavailable to Subscribing, target=sip:[email protected], 
id=72e6c5c4-24b4-e211-9edb-6c626db69c09@ceramic
2013/05/05 21:07:58.768                T38Modem OpalUDP Binding to interface: 
192.168.0.21:5060
2013/05/05 21:07:58.768                T38Modem SIP     Created transport 
udp$192.168.0.222:5060<if=udp$192.168.0.21:5060>
2013/05/05 21:07:58.768                T38Modem OpalUDP Started connect to 
192.168.0.222:5060
2013/05/05 21:07:58.768                T38Modem OpalUDP Writing to interface 0 
- "192.168.0.21%eth0"
2013/05/05 21:07:58.770                T38Modem OpalMan Listener interfaces: 
associated transport=udp$192.168.0.21:5060
    udp$192.168.0.21:5060,tcp$192.168.0.21:5060
2013/05/05 21:07:58.771                T38Modem SIP     Transaction created.
2013/05/05 21:07:58.774                T38Modem SIP     Transaction remote 
address is udp$192.168.0.222:5060
2013/05/05 21:07:58.774                T38Modem SIP     Sending PDU (595 bytes) 
to: rem=udp$192.168.0.222:5060,local=udp$192.168.0.21:5060,if=192.168.0.21%eth0
REGISTER sip:192.168.0.222 SIP/2.0
CSeq: 1 REGISTER
Via: SIP/2.0/UDP 
192.168.0.21:5060;branch=z9hG4bKb4e4c6c4-24b4-e211-9edb-6c626db69c09;rport
User-Agent: T38Modem/2.0.0
From: <sip:[email protected]>;tag=f20bc6c4-24b4-e211-9edb-6c626db69c09
Call-ID: 72e6c5c4-24b4-e211-9edb-6c626db69c09@ceramic
Organization: Vyacheslav Frolov
To: <sip:[email protected]>
Contact: <sip:[email protected]:5060>;q=1, 
<sip:[email protected]:5060;transport=tcp>;q=0.500
Allow: 
INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK
Expires: 300
Content-Length: 0
Max-Forwards: 70


2013/05/05 21:07:58.775                T38Modem OpalUDP Setting interface to 
192.168.0.21%eth0
2013/05/05 21:07:58.775                T38Modem SIP     Transaction timers set: 
retry=0.500, completion=16.000
2013/05/05 21:07:58.775                T38Modem OpalMan Added route 
"modem:.*=sip:<dn>@192.168.0.222"
2013/05/05 21:07:58.775                T38Modem OpalMan Added route 
"sip:.*=modem:<dn>"
2013/05/05 21:07:58.775                T38Modem PTLib   Destroyed PXConfig 
0xffabf0
2013/05/05 21:07:58.778 Opal Liste...0x60085700 OpalUDP Binding to interface: 
192.168.0.21:5060
2013/05/05 21:07:58.779 Opal Liste...0x60085700 SIP     Waiting for PDU on 
udp$192.168.0.222:5060<if=udp$192.168.0.21:5060>
2013/05/05 21:07:58.779 Opal Liste...0x60085700 SIP     PDU received: 
rem=udp$192.168.0.222:5060,local=udp$192.168.0.21:5060,if=192.168.0.21%eth0
SIP/2.0 401 Unauthorized
CSeq: 1 REGISTER
Via: SIP/2.0/UDP 
192.168.0.21:5060;branch=z9hG4bKb4e4c6c4-24b4-e211-9edb-6c626db69c09;rport=5060
User-Agent: AVM FRITZ!Box Fon WLAN 7390 (UI) 84.05.50 (Dec 11 2012)
From: <sip:[email protected]>;tag=f20bc6c4-24b4-e211-9edb-6c626db69c09
Call-ID: 72e6c5c4-24b4-e211-9edb-6c626db69c09@ceramic
To: <sip:[email protected]>;tag=0092F42E6D62559B
Content-Length: 0
WWW-Authenticate: Digest realm="fritz.box", nonce="91F5F34845F27E77"


2013/05/05 21:07:58.780 Opal Liste...0x60085700 SIP     Queueing PDU "1 
REGISTER <401>", transaction=z9hG4bKb4e4c6c4-24b4-e211-9edb-6c626db69c09, 
token=72e6c5c4-24b4-e211-9edb-6c626db69c09@ceramic
2013/05/05 21:07:58.780 Opal Liste...0x60085700 PWLib   File handle high water 
mark set: 37 Thread unblock pipe
2013/05/05 21:07:58.780 Opal Liste...0x60085700 PTLib   Thread high water mark 
set: 12
2013/05/05 21:07:58.780 Opal Liste...0x60085700 Opal    Transport clean up on 
termination
2013/05/05 21:07:58.780 Opal Liste...0x60085700 Opal    Transport Close
2013/05/05 21:07:58.780 Opal Liste...0x60085700 Opal    Deleted transport 
udp$192.168.0.222:5060<if=udp$192.168.0.21:5060>
2013/05/05 21:07:58.781         Pool:0x55f09700 SIP     Handling PDU "1 
REGISTER <401>" for transaction=z9hG4bKb4e4c6c4-24b4-e211-9edb-6c626db69c09
2013/05/05 21:07:58.781         Pool:0x55f09700 SIP     REGISTER transaction 
id=z9hG4bKb4e4c6c4-24b4-e211-9edb-6c626db69c09 completing.
2013/05/05 21:07:58.781         Pool:0x55f09700 OpalUDP Setting interface to 
192.168.0.21%eth0
2013/05/05 21:07:58.781         Pool:0x55f09700 SIP     Received Authentication 
Required response
2013/05/05 21:07:58.781         Pool:0x55f09700 SIP     Updating authentication 
credentials.
2013/05/05 21:07:58.781         Pool:0x55f09700 SIP     Auth realm set to 
fritz.box
2013/05/05 21:07:58.783         Pool:0x55f09700 OpalMan Listener interfaces: 
associated transport=udp$192.168.0.21:5060
    udp$192.168.0.21:5060,tcp$192.168.0.21:5060
2013/05/05 21:07:58.785         Pool:0x55f09700 SIP     Transaction created.
2013/05/05 21:07:58.786         Pool:0x55f09700 HTTP    Adding authentication 
information
2013/05/05 21:07:58.787         Pool:0x55f09700 SIP     Transaction remote 
address is udp$192.168.0.222:5060
2013/05/05 21:07:58.787         Pool:0x55f09700 SIP     Sending PDU (763 bytes) 
to: rem=udp$192.168.0.222:5060,local=udp$192.168.0.21:5060,if=192.168.0.21%eth0
REGISTER sip:192.168.0.222 SIP/2.0
CSeq: 2 REGISTER
Via: SIP/2.0/UDP 
192.168.0.21:5060;branch=z9hG4bK32ecc8c4-24b4-e211-9edb-6c626db69c09;rport
User-Agent: T38Modem/2.0.0
Authorization: Digest username="620", realm="fritz.box", 
nonce="91F5F34845F27E77", uri="sip:192.168.0.222", algorithm=MD5, 
response="ae404500825431e8f5d0d5e6a1439a59"
From: <sip:[email protected]>;tag=f20bc6c4-24b4-e211-9edb-6c626db69c09
Call-ID: 72e6c5c4-24b4-e211-9edb-6c626db69c09@ceramic
Organization: Vyacheslav Frolov
To: <sip:[email protected]>
Contact: <sip:[email protected]:5060>;q=1, 
<sip:[email protected]:5060;transport=tcp>;q=0.500
Allow: 
INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK
Expires: 300
Content-Length: 0
Max-Forwards: 70


2013/05/05 21:07:58.788         Pool:0x55f09700 OpalUDP Setting interface to 
192.168.0.21%eth0
2013/05/05 21:07:58.788         Pool:0x55f09700 SIP     Transaction timers set: 
retry=0.500, completion=16.000
2013/05/05 21:07:58.788         Pool:0x55f09700 SIP     REGISTER transaction 
id=z9hG4bKb4e4c6c4-24b4-e211-9edb-6c626db69c09 completed.
2013/05/05 21:07:58.788         Pool:0x55f09700 SIP     Handled PDU "1 REGISTER 
<401>"
2013/05/05 21:07:58.794 Opal Liste...0x60085700 OpalUDP Binding to interface: 
192.168.0.21:5060
2013/05/05 21:07:58.794 Opal Liste...0x60085700 SIP     Waiting for PDU on 
udp$192.168.0.222:5060<if=udp$192.168.0.21:5060>
2013/05/05 21:07:58.795 Opal Liste...0x60085700 SIP     PDU received: 
rem=udp$192.168.0.222:5060,local=udp$192.168.0.21:5060,if=192.168.0.21%eth0
SIP/2.0 200 OK
CSeq: 2 REGISTER
Via: SIP/2.0/UDP 
192.168.0.21:5060;branch=z9hG4bK32ecc8c4-24b4-e211-9edb-6c626db69c09;rport=5060
User-Agent: AVM FRITZ!Box Fon WLAN 7390 (UI) 84.05.50 (Dec 11 2012)
From: <sip:[email protected]>;tag=f20bc6c4-24b4-e211-9edb-6c626db69c09
Call-ID: 72e6c5c4-24b4-e211-9edb-6c626db69c09@ceramic
Supported: 100rel,replaces,timer
To: <sip:[email protected]>;tag=7888ACD42C67A55E
Contact: <sip:[email protected]:5060>;q=1;expires=300
Contact: <sip:[email protected]:5060>;q=0.934;expires=261
Contact: <sip:620@[fe80::ffff:ffff:ffff]:5060>;q=0.868;expires=261
Contact: <sip:620@[2001:0:53aa:64c:cde:673e:adac:d617]:5060>;q=0.802;expires=261
Contact: <sip:620@[fe80::6e62:6dff:feb6:9c09]:5060>;q=0.736;expires=261
Accept: application/sdp, multipart/mixed
Allow: 
INVITE,ACK,OPTIONS,CANCEL,BYE,UPDATE,PRACK,INFO,SUBSCRIBE,NOTIFY,REFER,MESSAGE,PUBLISH
Allow-Events: telephone-event,refer,reg
Content-Length: 0
Accept-Encoding: identity


2013/05/05 21:07:58.795 Opal Liste...0x60085700 SIP     Queueing PDU "2 
REGISTER <200>", transaction=z9hG4bK32ecc8c4-24b4-e211-9edb-6c626db69c09, 
token=72e6c5c4-24b4-e211-9edb-6c626db69c09@ceramic
2013/05/05 21:07:58.795 Opal Liste...0x60085700 Opal    Transport clean up on 
termination
2013/05/05 21:07:58.795 Opal Liste...0x60085700 Opal    Transport Close
2013/05/05 21:07:58.795 Opal Liste...0x60085700 Opal    Deleted transport 
udp$192.168.0.222:5060<if=udp$192.168.0.21:5060>
2013/05/05 21:07:58.796         Pool:0x55f09700 SIP     Handling PDU "2 
REGISTER <200>" for transaction=z9hG4bK32ecc8c4-24b4-e211-9edb-6c626db69c09
2013/05/05 21:07:58.796         Pool:0x55f09700 SIP     REGISTER transaction 
id=z9hG4bK32ecc8c4-24b4-e211-9edb-6c626db69c09 completing.
2013/05/05 21:07:58.796         Pool:0x55f09700 OpalUDP Setting interface to 
192.168.0.21%eth0
2013/05/05 21:07:58.796         Pool:0x55f09700 SIP     Product Info: 
name="AVM", version="", vendor="", comments="FRITZ!Box Fon WLAN 7390 (UI) 
84.05.50 (Dec 11 2012)"
2013/05/05 21:07:58.798         Pool:0x55f09700 SIP     Expiry time for 
REGISTER set to 300 seconds.
2013/05/05 21:07:58.798         Pool:0x55f09700 SIP     Changing REGISTER 
handler from Subscribing to Subscribed, target=sip:[email protected], 
id=72e6c5c4-24b4-e211-9edb-6c626db69c09@ceramic
2013/05/05 21:07:58.798         Pool:0x55f09700 SIP     REGISTER transaction 
id=z9hG4bK32ecc8c4-24b4-e211-9edb-6c626db69c09 completed.
2013/05/05 21:07:58.798         Pool:0x55f09700 SIP     Handled PDU "2 REGISTER 
<200>"
2013/05/05 21:08:03.789  Housekeeper:0x60148700 SIP     Set state 
Terminated_Success for REGISTER transaction 
id=z9hG4bKb4e4c6c4-24b4-e211-9edb-6c626db69c09
2013/05/05 21:08:03.789  Housekeeper:0x60148700 SIP     Completed state for 
transaction id=z9hG4bKb4e4c6c4-24b4-e211-9edb-6c626db69c09
2013/05/05 21:08:03.814  Housekeeper:0x60148700 SIP     Set state 
Terminated_Success for REGISTER transaction 
id=z9hG4bK32ecc8c4-24b4-e211-9edb-6c626db69c09
2013/05/05 21:08:03.814  Housekeeper:0x60148700 SIP     Completed state for 
transaction id=z9hG4bK32ecc8c4-24b4-e211-9edb-6c626db69c09
2013/05/05 21:08:04.763 Opal Garbage:0x6fa53700 SIP     Transaction 
id=z9hG4bK32ecc8c4-24b4-e211-9edb-6c626db69c09 destroyed.
2013/05/05 21:08:04.763 Opal Garbage:0x6fa53700 SIP     Transaction 
id=z9hG4bKb4e4c6c4-24b4-e211-9edb-6c626db69c09 destroyed.
2013/05/05 21:08:58.815 Network In...0x60189700 PWLib   File handle high water 
mark set: 38 PUDPSocket
2013/05/05 21:09:11.208 ttyT38-0(e...0x60107700 --> ATZ
2013/05/05 21:09:11.208 ttyT38-0(e...0x60107700 <--  {
  0d 0a 4f 4b 0d 0a                                  ..OK.. }
2013/05/05 21:09:14.305 ttyT38-0(e...0x60107700 --> ATS0=0
2013/05/05 21:09:14.306 ttyT38-0(e...0x60107700 <--  {
  0d 0a 4f 4b 0d 0a                                  ..OK.. }
2013/05/05 21:09:14.405 ttyT38-0(e...0x60107700 --> ATE0
2013/05/05 21:09:14.405 ttyT38-0(e...0x60107700 <--  {
  0d 0a 4f 4b 0d 0a                                  ..OK.. }
2013/05/05 21:09:14.505 ttyT38-0(e...0x60107700 --> ATV1
2013/05/05 21:09:14.505 ttyT38-0(e...0x60107700 <--  {
  0d 0a 4f 4b 0d 0a                                  ..OK.. }
2013/05/05 21:09:14.605 ttyT38-0(e...0x60107700 --> ATQ0
2013/05/05 21:09:14.605 ttyT38-0(e...0x60107700 <--  {
  0d 0a 4f 4b 0d 0a                                  ..OK.. }
2013/05/05 21:09:14.705 ttyT38-0(e...0x60107700 --> ATS8=2
2013/05/05 21:09:14.705 ttyT38-0(e...0x60107700 <--  {
  0d 0a 4f 4b 0d 0a                                  ..OK.. }
2013/05/05 21:09:14.805 ttyT38-0(e...0x60107700 --> ATS7=60
2013/05/05 21:09:14.805 ttyT38-0(e...0x60107700 <--  {
  0d 0a 4f 4b 0d 0a                                  ..OK.. }
2013/05/05 21:09:14.905 ttyT38-0(e...0x60107700 --> AT+FCLASS=?
2013/05/05 21:09:14.905 ttyT38-0(e...0x60107700 <--  {
  0d 0a 31 2c 38 0d 0a 4f  4b 0d 0a                  ..1,8..OK.. }
2013/05/05 21:09:15.005 ttyT38-0(e...0x60107700 --> AT+FCLASS=1
2013/05/05 21:09:15.005 ttyT38-0(e...0x60107700 <--  {
  0d 0a 4f 4b 0d 0a                                  ..OK.. }
2013/05/05 21:09:15.105 ttyT38-0(e...0x60107700 --> ATI3
2013/05/05 21:09:15.105 ttyT38-0(e...0x60107700 <--  {
  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.. }
2013/05/05 21:09:15.205 ttyT38-0(e...0x60107700 --> ATI0
2013/05/05 21:09:15.205 ttyT38-0(e...0x60107700 <--  {
  0d 0a 54 33 38 46 41 58  0d 0a 4f 4b 0d 0a         ..T38FAX..OK.. }
2013/05/05 21:09:15.305 ttyT38-0(e...0x60107700 --> AT+FREV?
2013/05/05 21:09:15.305 ttyT38-0(e...0x60107700 <--  {
  0d 0a 32 2e 30 2e 30 0d  0a 4f 4b 0d 0a            ..2.0.0..OK.. }
2013/05/05 21:09:15.405 ttyT38-0(e...0x60107700 --> AT+FTM=?
2013/05/05 21:09:15.405 ttyT38-0(e...0x60107700 <--  {
  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.. }
2013/05/05 21:09:15.505 ttyT38-0(e...0x60107700 --> AT+FRM=?
2013/05/05 21:09:15.505 ttyT38-0(e...0x60107700 <--  {
  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.. }
2013/05/05 21:09:15.605 ttyT38-0(e...0x60107700 --> ATM0
2013/05/05 21:09:15.605 ttyT38-0(e...0x60107700 <--  {
  0d 0a 4f 4b 0d 0a                                  ..OK.. }
2013/05/05 21:09:15.706 ttyT38-0(i...0x600c6700 --> read ERROR -1 Input/output 
error
2013/05/05 21:09:15.706 ttyT38-0(i...0x600c6700 --> Stopped
2013/05/05 21:09:15.706 ttyT38-0(i...0x600c6700 PTLib   Thread ended: 
name="ttyT38-0(i):1611425536:0x600c6700", real=76.942, kernel=0.000 (0%), 
user=0.000 (0%), both=0.000 (0%)
2013/05/05 21:09:15.716 ttyT38-0(o...0x60044700 <-- Stopped
2013/05/05 21:09:15.717 ttyT38-0(o...0x60044700 PWLib   File handle low water 
mark set: 23 PTextFile
2013/05/05 21:09:15.717 ttyT38-0(o...0x60044700 PTLib   Thread ended: 
name="ttyT38-0(o):1610893056:0x60044700", real=76.952, kernel=0.000 (0%), 
user=0.000 (0%), both=0.000 (0%)
2013/05/05 21:09:15.727 ttyT38-0(e...0x60107700 <-> Stopped
2013/05/05 21:09:15.727 ttyT38-0(e...0x60107700 PTLib   Thread ended: 
name="ttyT38-0(e):1611691776:0x60107700", real=76.963, kernel=0.000 (0%), 
user=0.000 (0%), both=0.000 (0%)
2013/05/05 21:09:15.737 ttyT38-0(o...0x600c6700 RenameCurrentThread old 
ThreadName=OutPty:0x600c6700
2013/05/05 21:09:15.737 ttyT38-0(o...0x600c6700 <-- Started
2013/05/05 21:09:15.737 ttyT38-0(e...0x60107700 RenameCurrentThread old 
ThreadName=ModemEngine:0x60107700
2013/05/05 21:09:15.738 ttyT38-0(e...0x60107700 <-> Started
2013/05/05 21:09:15.737 ttyT38-0(i...0x60044700 RenameCurrentThread old 
ThreadName=InPty:0x60044700
2013/05/05 21:09:15.738 ttyT38-0(i...0x60044700 --> Started
2013/05/05 21:09:19.393 ttyT38-0(i...0x60044700 --> read ERROR -1 Input/output 
error
2013/05/05 21:09:19.393 ttyT38-0(i...0x60044700 --> Stopped
2013/05/05 21:09:19.394 ttyT38-0(i...0x60044700 PTLib   Thread ended: 
name="ttyT38-0(i):1610893056:0x60044700", real=3.656, kernel=0.000 (0%), 
user=0.000 (0%), both=0.000 (0%)
2013/05/05 21:09:19.404 ttyT38-0(o...0x600c6700 <-- Stopped
2013/05/05 21:09:19.404 ttyT38-0(o...0x600c6700 PTLib   Thread ended: 
name="ttyT38-0(o):1611425536:0x600c6700", real=3.666, kernel=0.000 (0%), 
user=0.000 (0%), both=0.000 (0%)
2013/05/05 21:09:19.414 ttyT38-0(e...0x60107700 <-> Stopped
2013/05/05 21:09:19.414 ttyT38-0(e...0x60107700 PTLib   Thread ended: 
name="ttyT38-0(e):1611691776:0x60107700", real=3.677, kernel=0.000 (0%), 
user=0.000 (0%), both=0.000 (0%)
2013/05/05 21:09:19.424 ttyT38-0(o...0x60044700 RenameCurrentThread old 
ThreadName=OutPty:0x60044700
2013/05/05 21:09:19.425 ttyT38-0(o...0x60044700 <-- Started
2013/05/05 21:09:19.424 ttyT38-0(e...0x60107700 RenameCurrentThread old 
ThreadName=ModemEngine:0x60107700
2013/05/05 21:09:19.425 ttyT38-0(e...0x60107700 <-> Started
2013/05/05 21:09:19.425 ttyT38-0(i...0x600c6700 RenameCurrentThread old 
ThreadName=InPty:0x600c6700
2013/05/05 21:09:19.425 ttyT38-0(i...0x600c6700 --> Started
2013/05/05 21:09:20.404 ttyT38-0(e...0x60107700 --> ATZ
2013/05/05 21:09:20.404 ttyT38-0(e...0x60107700 <--  {
  0d 0a 4f 4b 0d 0a                                  ..OK.. }
2013/05/05 21:09:23.502 ttyT38-0(e...0x60107700 --> ATS0=0
2013/05/05 21:09:23.502 ttyT38-0(e...0x60107700 <--  {
  0d 0a 4f 4b 0d 0a                                  ..OK.. }
2013/05/05 21:09:23.601 ttyT38-0(e...0x60107700 --> ATE0
2013/05/05 21:09:23.601 ttyT38-0(e...0x60107700 <--  {
  0d 0a 4f 4b 0d 0a                                  ..OK.. }
2013/05/05 21:09:23.701 ttyT38-0(e...0x60107700 --> ATV1
2013/05/05 21:09:23.701 ttyT38-0(e...0x60107700 <--  {
  0d 0a 4f 4b 0d 0a                                  ..OK.. }
2013/05/05 21:09:23.801 ttyT38-0(e...0x60107700 --> ATQ0
2013/05/05 21:09:23.801 ttyT38-0(e...0x60107700 <--  {
  0d 0a 4f 4b 0d 0a                                  ..OK.. }
2013/05/05 21:09:23.901 ttyT38-0(e...0x60107700 --> ATS8=2
2013/05/05 21:09:23.901 ttyT38-0(e...0x60107700 <--  {
  0d 0a 4f 4b 0d 0a                                  ..OK.. }
2013/05/05 21:09:24.001 ttyT38-0(e...0x60107700 --> ATS7=60
2013/05/05 21:09:24.001 ttyT38-0(e...0x60107700 <--  {
  0d 0a 4f 4b 0d 0a                                  ..OK.. }
2013/05/05 21:09:24.101 ttyT38-0(e...0x60107700 --> AT+FCLASS=?
2013/05/05 21:09:24.101 ttyT38-0(e...0x60107700 <--  {
  0d 0a 31 2c 38 0d 0a 4f  4b 0d 0a                  ..1,8..OK.. }
2013/05/05 21:09:24.201 ttyT38-0(e...0x60107700 --> AT+FCLASS=1
2013/05/05 21:09:24.201 ttyT38-0(e...0x60107700 <--  {
  0d 0a 4f 4b 0d 0a                                  ..OK.. }
2013/05/05 21:09:24.301 ttyT38-0(e...0x60107700 --> ATI3
2013/05/05 21:09:24.301 ttyT38-0(e...0x60107700 <--  {
  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.. }
2013/05/05 21:09:24.405 ttyT38-0(e...0x60107700 --> ATI0
2013/05/05 21:09:24.405 ttyT38-0(e...0x60107700 <--  {
  0d 0a 54 33 38 46 41 58  0d 0a 4f 4b 0d 0a         ..T38FAX..OK.. }
2013/05/05 21:09:24.513 ttyT38-0(e...0x60107700 --> AT+FREV?
2013/05/05 21:09:24.513 ttyT38-0(e...0x60107700 <--  {
  0d 0a 32 2e 30 2e 30 0d  0a 4f 4b 0d 0a            ..2.0.0..OK.. }
2013/05/05 21:09:24.613 ttyT38-0(e...0x60107700 --> AT+FTM=?
2013/05/05 21:09:24.613 ttyT38-0(e...0x60107700 <--  {
  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.. }
2013/05/05 21:09:24.713 ttyT38-0(e...0x60107700 --> AT+FRM=?
2013/05/05 21:09:24.713 ttyT38-0(e...0x60107700 <--  {
  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.. }
2013/05/05 21:09:24.813 ttyT38-0(e...0x60107700 --> ATM0
2013/05/05 21:09:24.813 ttyT38-0(e...0x60107700 <--  {
  0d 0a 4f 4b 0d 0a                                  ..OK.. }
2013/05/05 21:09:24.914 ttyT38-0(e...0x60107700 --> AT+FCLASS=1
2013/05/05 21:09:24.915 ttyT38-0(e...0x60107700 <--  {
  0d 0a 4f 4b 0d 0a                                  ..OK.. }
2013/05/05 21:09:24.915 ttyT38-0(e...0x60107700 --> ATDT189659
2013/05/05 21:09:24.915 ttyT38-0(e...0x60107700 ModemEngineBody::OffHook: 
off-hook cstCleared stCommand
2013/05/05 21:09:24.915 ttyT38-0(e...0x60107700 Dial string: 189659@LV - OK
2013/05/05 21:09:24.916 ttyT38-0(e...0x60107700 ModemEngineBody::SetState: 
off-hook cstCleared stDial
2013/05/05 21:09:24.916 ttyT38-0(e...0x60107700 ModemEngineBody::SetCallState: 
off-hook cstDialing stDial
2013/05/05 21:09:24.916 ttyT38-0(e...0x60107700 ModemEngineBody::SetState: 
off-hook cstDialing stConnectWait
2013/05/05 21:09:24.916 ttyT38-0(e...0x60107700 ModemEndPoint::OnMyCallback 
command=dial extra=5
2013/05/05 21:09:24.916 ttyT38-0(e...0x60107700 PseudoModemQ::Dequeue ttyT38-0
2013/05/05 21:09:24.916 ttyT38-0(e...0x60107700 MyManager::OnMyCallback 
SetUpCall(modem:, 189659@+/dev/ttyT38-0)
2013/05/05 21:09:24.916 ttyT38-0(e...0x60107700 OpalMan Set up call from modem: 
to 189659@+/dev/ttyT38-0
2013/05/05 21:09:24.916 ttyT38-0(e...0x60107700 Call    Created Call[C3ff77e3a1]
2013/05/05 21:09:24.916 ttyT38-0(e...0x60107700 OpalMan Set up connection to 
"modem:"
2013/05/05 21:09:24.916 ttyT38-0(e...0x60107700 ModemEndPoint::MakeConnection 
modem:
2013/05/05 21:09:24.916 ttyT38-0(e...0x60107700 OpalCon Created connection 
Call[C3ff77e3a1]-EP<modem>[modem:/C3ff77e3a1/0]
2013/05/05 21:09:24.916 ttyT38-0(e...0x60107700 
ModemConnection::ModemConnection Call[C3ff77e3a1]-EP<modem>[modem:/C3ff77e3a1/0]
2013/05/05 21:09:24.917 ttyT38-0(e...0x60107700 
ModemConnection::SetUpConnection Call[C3ff77e3a1]-EP<modem>[modem:/C3ff77e3a1/0]
2013/05/05 21:09:24.917 ttyT38-0(e...0x60107700 OpalCon SetPhase from 
UninitialisedPhase to SetUpPhase for 
Call[C3ff77e3a1]-EP<modem>[modem:/C3ff77e3a1/0]
2013/05/05 21:09:24.917 ttyT38-0(e...0x60107700 OpalMan OnIncoming connection 
Call[C3ff77e3a1]-EP<modem>[modem:/C3ff77e3a1/0]
2013/05/05 21:09:24.917 ttyT38-0(e...0x60107700 Call    GetOtherPartyConnection 
Call[C3ff77e3a1]-EP<modem>[modem:/C3ff77e3a1/0]
2013/05/05 21:09:24.917 ttyT38-0(e...0x60107700 OpalMan Searching for route 
"modem:T38Modem%20v2.0.0    189659@+/dev/ttyT38-0"
2013/05/05 21:09:24.917 ttyT38-0(e...0x60107700 OpalMan Matched regex 
"^modem:.*        .*$" ("modem:.*")
2013/05/05 21:09:24.917 ttyT38-0(e...0x60107700 OpalMan Set up connection to 
"sip:[email protected]"
2013/05/05 21:09:24.918 ttyT38-0(e...0x60107700 MySIPEndPoint::CreateConnection 
for Call[C3ff77e3a1]
2013/05/05 21:09:24.918 ttyT38-0(e...0x60107700 OpalCon Created connection 
Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09]
2013/05/05 21:09:24.918 ttyT38-0(e...0x60107700 RFC2833 Handler created
2013/05/05 21:09:24.918 ttyT38-0(e...0x60107700 RFC2833 Handler created
2013/05/05 21:09:24.919 ttyT38-0(e...0x60107700 SIP     Created connection.
2013/05/05 21:09:24.919 ttyT38-0(e...0x60107700 OpalCon Applying string options:
T38-UDPTL-Redundancy-Interval=50
T38-UDPTL-Optimise-On-Retransmit=true
T38-UDPTL-Redundancy=
T38-UDPTL-Keep-Alive-Interval=0

2013/05/05 21:09:24.920 ttyT38-0(e...0x60107700 
MySIPConnection::OnApplyStringOptions Enabled formats (in preference order):
G.711-uLaw-64k
G.711-ALaw-64k
T.38
UserInput/RFC2833

2013/05/05 21:09:24.920 ttyT38-0(e...0x60107700 OpalCon Applying string options:
T38-UDPTL-Redundancy-Interval=50
T38-UDPTL-Optimise-On-Retransmit=true
T38-UDPTL-Redundancy=
T38-UDPTL-Keep-Alive-Interval=0

2013/05/05 21:09:24.920 ttyT38-0(e...0x60107700 
MySIPConnection::OnApplyStringOptions Enabled formats (in preference order):
G.711-uLaw-64k
G.711-ALaw-64k
T.38
UserInput/RFC2833

2013/05/05 21:09:24.920 ttyT38-0(e...0x60107700 Call[C3ff77e3a1] from 
modem:T38Modem%20v2.0.0 to 189659@+/dev/ttyT38-0, route to 
sip:[email protected]
2013/05/05 21:09:24.921 ttyT38-0(e...0x60107700 Outgoing call routed to 
sip:[email protected] for Call[C3ff77e3a1]-EP<modem>[modem:/C3ff77e3a1/0]
2013/05/05 21:09:24.921 ttyT38-0(e...0x60107700 Call    OnSetUp 
Call[C3ff77e3a1]-EP<modem>[modem:/C3ff77e3a1/0]
2013/05/05 21:09:24.921 ttyT38-0(e...0x60107700 
MySIPConnection::SetUpConnection 
Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09] name=T38Modem 
v2.0.0
2013/05/05 21:09:24.921 ttyT38-0(e...0x60107700 SIP     SetUpConnection: 
sip:[email protected]
2013/05/05 21:09:24.921 ttyT38-0(e...0x60107700 OpalCon Applying string options:
T38-UDPTL-Redundancy-Interval=50
T38-UDPTL-Optimise-On-Retransmit=true
T38-UDPTL-Redundancy=
T38-UDPTL-Keep-Alive-Interval=0

2013/05/05 21:09:24.921 ttyT38-0(e...0x60107700 
MySIPConnection::OnApplyStringOptions Enabled formats (in preference order):
G.711-uLaw-64k
G.711-ALaw-64k
T.38
UserInput/RFC2833

2013/05/05 21:09:24.921 ttyT38-0(e...0x60107700 SIP     Connecting to 
sip:[email protected] via sip:[email protected]
2013/05/05 21:09:24.921 ttyT38-0(e...0x60107700 SIP     Setting new transport 
for destination "sip:[email protected]"
2013/05/05 21:09:24.921 ttyT38-0(e...0x60107700 SIP     Found registrar on 
domain 192.168.0.222, using interface 192.168.0.21%eth0
2013/05/05 21:09:24.922 ttyT38-0(e...0x60107700 OpalUDP Binding to interface: 
192.168.0.21:5060
2013/05/05 21:09:24.922 ttyT38-0(e...0x60107700 SIP     Created transport 
udp$192.168.0.222:5060<if=udp$192.168.0.21:5060>
2013/05/05 21:09:24.922 ttyT38-0(e...0x60107700 OpalUDP Started connect to 
192.168.0.222:5060
2013/05/05 21:09:24.922 ttyT38-0(e...0x60107700 
ModemConnection::GetMediaFormats Call[C3ff77e3a1]-EP<modem>[modem:/C3ff77e3a1/0]
2013/05/05 21:09:24.922 ttyT38-0(e...0x60107700 ModemEndPoint::GetMediaFormats
2013/05/05 21:09:24.935 ttyT38-0(e...0x60107700 Call    GetMediaFormats for 
Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09]
    
GSM-AMR,GSM-06.10,G.726-40k,G.726-32k,G.726-24k,G.726-16k,G.711-uLaw-64k,G.711-ALaw-64k,LPC-10,MS-GSM,MS-IMA-ADPCM,SILK-8,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,T.38,UserInput/RFC2833
2013/05/05 21:09:24.935 ttyT38-0(e...0x60107700 SIP     Local media formats set:
    
GSM-AMR,GSM-06.10,G.726-40k,G.726-32k,G.726-24k,G.726-16k,G.711-uLaw-64k,G.711-ALaw-64k,LPC-10,MS-GSM,MS-IMA-ADPCM,SILK-8,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,T.38,UserInput/RFC2833
2013/05/05 21:09:24.935 ttyT38-0(e...0x60107700 
MySIPConnection::GetLocalMediaFormats:
GSM-AMR
GSM-06.10
G.726-40k
G.726-32k
G.726-24k
G.726-16k
G.711-uLaw-64k
G.711-ALaw-64k
LPC-10
MS-GSM
MS-IMA-ADPCM
SILK-8
SpeexIETFNarrow-11k
SpeexIETFNarrow-15k
SpeexIETFNarrow-18.2k
SpeexIETFNarrow-24.6k
SpeexIETFNarrow-5.95k
SpeexIETFNarrow-8k
SpeexNB
SpeexWNarrow-8k
T.38
UserInput/RFC2833

2013/05/05 21:09:24.935 ttyT38-0(e...0x60107700 
MySIPConnection::GetLocalMediaFormats Remove GSM-AMR
2013/05/05 21:09:24.935 ttyT38-0(e...0x60107700 
MySIPConnection::GetLocalMediaFormats Remove GSM-06.10
2013/05/05 21:09:24.935 ttyT38-0(e...0x60107700 
MySIPConnection::GetLocalMediaFormats Remove G.726-40k
2013/05/05 21:09:24.935 ttyT38-0(e...0x60107700 
MySIPConnection::GetLocalMediaFormats Remove G.726-32k
2013/05/05 21:09:24.935 ttyT38-0(e...0x60107700 
MySIPConnection::GetLocalMediaFormats Remove G.726-24k
2013/05/05 21:09:24.935 ttyT38-0(e...0x60107700 
MySIPConnection::GetLocalMediaFormats Remove G.726-16k
2013/05/05 21:09:24.935 ttyT38-0(e...0x60107700 
MySIPConnection::GetLocalMediaFormats Remove LPC-10
2013/05/05 21:09:24.935 ttyT38-0(e...0x60107700 
MySIPConnection::GetLocalMediaFormats Remove MS-GSM
2013/05/05 21:09:24.935 ttyT38-0(e...0x60107700 
MySIPConnection::GetLocalMediaFormats Remove MS-IMA-ADPCM
2013/05/05 21:09:24.935 ttyT38-0(e...0x60107700 
MySIPConnection::GetLocalMediaFormats Remove SILK-8
2013/05/05 21:09:24.935 ttyT38-0(e...0x60107700 
MySIPConnection::GetLocalMediaFormats Remove SpeexIETFNarrow-11k
2013/05/05 21:09:24.935 ttyT38-0(e...0x60107700 
MySIPConnection::GetLocalMediaFormats Remove SpeexIETFNarrow-15k
2013/05/05 21:09:24.935 ttyT38-0(e...0x60107700 
MySIPConnection::GetLocalMediaFormats Remove SpeexIETFNarrow-18.2k
2013/05/05 21:09:24.935 ttyT38-0(e...0x60107700 
MySIPConnection::GetLocalMediaFormats Remove SpeexIETFNarrow-24.6k
2013/05/05 21:09:24.935 ttyT38-0(e...0x60107700 
MySIPConnection::GetLocalMediaFormats Remove SpeexIETFNarrow-5.95k
2013/05/05 21:09:24.935 ttyT38-0(e...0x60107700 
MySIPConnection::GetLocalMediaFormats Remove SpeexIETFNarrow-8k
2013/05/05 21:09:24.935 ttyT38-0(e...0x60107700 
MySIPConnection::GetLocalMediaFormats Remove SpeexNB
2013/05/05 21:09:24.935 ttyT38-0(e...0x60107700 
MySIPConnection::GetLocalMediaFormats Remove SpeexWNarrow-8k
2013/05/05 21:09:24.935 ttyT38-0(e...0x60107700 
MySIPConnection::GetLocalMediaFormats:
G.711-uLaw-64k
G.711-ALaw-64k
T.38
UserInput/RFC2833

2013/05/05 21:09:24.935 ttyT38-0(e...0x60107700 SIP     Remote media formats 
set:
    G.711-uLaw-64k,G.711-ALaw-64k,T.38,UserInput/RFC2833
2013/05/05 21:09:24.935 ttyT38-0(e...0x60107700 SIP     Getting local URI from 
registeration: sip:[email protected]
2013/05/05 21:09:24.935 ttyT38-0(e...0x60107700 SIP     Updating dialog tag 
from "" to "7aa61ff8-24b4-e211-9edb-6c626db69c09"
2013/05/05 21:09:24.935 ttyT38-0(e...0x60107700 SIP     Remote dialog address 
from target: sip:[email protected]
2013/05/05 21:09:24.936 ttyT38-0(e...0x60107700 SIP     INVITE transaction 
id=z9hG4bK946122f8-24b4-e211-9edb-6c626db69c09 created.
2013/05/05 21:09:24.936 ttyT38-0(e...0x60107700 SIP     Creating INVITE request
2013/05/05 21:09:24.936 ttyT38-0(e...0x60107700 SIP     Offering all configured 
media:
    
GSM-AMR,GSM-06.10,G.726-40k,G.726-32k,G.726-24k,G.726-16k,G.711-uLaw-64k,G.711-ALaw-64k,LPC-10,MS-GSM,MS-IMA-ADPCM,SILK-8,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,T.38,UserInput/RFC2833
2013/05/05 21:09:24.936 ttyT38-0(e...0x60107700 SIP     Offering media type 
audio in SDP
2013/05/05 21:09:24.936 ttyT38-0(e...0x60107700 Call    IsMediaBypassPossible 
Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09] session 1
2013/05/05 21:09:24.936 ttyT38-0(e...0x60107700 OpalMan IsMediaBypassPossible: 
session 1
2013/05/05 21:09:24.936 ttyT38-0(e...0x60107700 OpalCon IsMediaBypassPossible: 
default returns false
2013/05/05 21:09:24.936 ttyT38-0(e...0x60107700 RTP     Cannot find media 
session 1
2013/05/05 21:09:24.936 ttyT38-0(e...0x60107700 RTP     Cannot find RTP session 
1
2013/05/05 21:09:24.936 ttyT38-0(e...0x60107700 PTLib   Created PXConfig 
0x7fcd50018ad0
2013/05/05 21:09:24.936 ttyT38-0(e...0x60107700 VoIP Metrics    RTCP_XR_Metrics 
created.
2013/05/05 21:09:24.937 ttyT38-0(e...0x60107700 RTP_UDP Session 1, created with 
NAT flag set to 0
2013/05/05 21:09:24.937 ttyT38-0(e...0x60107700 PWLib   File handle high water 
mark set: 39 PUDPSocket
2013/05/05 21:09:24.937 ttyT38-0(e...0x60107700 PWLib   File handle low water 
mark set: 38 PUDPSocket
2013/05/05 21:09:24.937 ttyT38-0(e...0x60107700 RTP_UDP Session 1 created: 
192.168.0.21:5000-5001 ssrc=453989732
2013/05/05 21:09:24.937 ttyT38-0(e...0x60107700 RTP     Creating new session 
RTP_UDP
2013/05/05 21:09:24.937 ttyT38-0(e...0x60107700 RTPEp   Session 1, remembering 
local RTP port 5000 on connection 
Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09]
2013/05/05 21:09:24.937 ttyT38-0(e...0x60107700 RTP     Found existing media 
session 1
2013/05/05 21:09:24.937 ttyT38-0(e...0x60107700 SDP     SDP not including 
MS-GSM as it is not a SIP transportable format
2013/05/05 21:09:24.937 ttyT38-0(e...0x60107700 SDP     SDP not including 
MS-IMA-ADPCM as it is not a SIP transportable format
2013/05/05 21:09:24.937 ttyT38-0(e...0x60107700 SDP     SDP not including 
SpeexIETFNarrow-11k as it is not a SIP transportable format
2013/05/05 21:09:24.937 ttyT38-0(e...0x60107700 SDP     SDP not including 
SpeexIETFNarrow-15k as it is not a SIP transportable format
2013/05/05 21:09:24.937 ttyT38-0(e...0x60107700 SDP     SDP not including 
SpeexIETFNarrow-18.2k as it is not a SIP transportable format
2013/05/05 21:09:24.937 ttyT38-0(e...0x60107700 SDP     SDP not including 
SpeexIETFNarrow-24.6k as it is not a SIP transportable format
2013/05/05 21:09:24.937 ttyT38-0(e...0x60107700 SDP     SDP not including 
SpeexIETFNarrow-5.95k as it is not a SIP transportable format
2013/05/05 21:09:24.937 ttyT38-0(e...0x60107700 SDP     SDP not including 
SpeexIETFNarrow-8k as it is not a SIP transportable format
2013/05/05 21:09:24.937 ttyT38-0(e...0x60107700 SDP     SDP not including 
SpeexWNarrow-8k as it is not a SIP transportable format
2013/05/05 21:09:24.937 ttyT38-0(e...0x60107700 MediaFormat     Merging 
UserInput/RFC2833 into UserInput/RFC2833
2013/05/05 21:09:24.938 ttyT38-0(e...0x60107700 RFC2833 Set tx pt=[pt=101], 
events="0-16,32,36" for UserInput/RFC2833
2013/05/05 21:09:24.938 ttyT38-0(e...0x60107700 RFC2833 Set rx pt=[pt=101], 
events="0-16,32,36" for UserInput/RFC2833
2013/05/05 21:09:24.938 ttyT38-0(e...0x60107700 RFC2833 Set tx pt=[pt=128], 
events="" for NamedSignalEvent
2013/05/05 21:09:24.938 ttyT38-0(e...0x60107700 SIP     No media formats of 
type video, not adding SDP
2013/05/05 21:09:24.938 ttyT38-0(e...0x60107700 SIP     Transaction remote 
address is udp$192.168.0.222:5060
2013/05/05 21:09:24.938 ttyT38-0(e...0x60107700 OpalPlugin      
to_customised_options: SILK-8
2013/05/05 21:09:24.939 ttyT38-0(e...0x60107700 SIP     Sending PDU (1139 
bytes) to: 
rem=udp$192.168.0.222:5060,local=udp$192.168.0.21:5060,if=192.168.0.21%eth0
INVITE sip:[email protected] SIP/2.0
CSeq: 1 INVITE
Via: SIP/2.0/UDP 
192.168.0.21:5060;branch=z9hG4bK946122f8-24b4-e211-9edb-6c626db69c09;rport
User-Agent: T38Modem/2.0.0
From: "root" <sip:[email protected]>;tag=7aa61ff8-24b4-e211-9edb-6c626db69c09
Call-ID: dab91ff8-24b4-e211-9edb-6c626db69c09@ceramic
Supported: 100rel,replaces
Organization: Vyacheslav Frolov
To: <sip:[email protected]>
Contact: "root" <sip:[email protected]:5060>
Allow: 
INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK
Content-Length: 532
Content-Type: application/sdp
Max-Forwards: 70

v=0
o=- 1367780964 1 IN IP4 192.168.0.21
s=T38Modem/2.0.0
c=IN IP4 192.168.0.21
t=0 0
m=audio 5000 RTP/AVP 120 3 102 103 104 105 0 8 126 121 118 101
a=sendrecv
a=rtpmap:120 AMR/8000/1
a=rtpmap:3 gsm/8000/1
a=rtpmap:102 G726-40/8000/1
a=rtpmap:103 G726-32/8000/1
a=rtpmap:104 G726-24/8000/1
a=rtpmap:105 G726-16/8000/1
a=rtpmap:0 PCMU/8000/1
a=rtpmap:8 PCMA/8000/1
a=rtpmap:126 lpc10/8000/1
a=rtpmap:121 SILK/8000/1
a=rtpmap:118 Speex/8000/1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16,32,36
a=maxptime:22

2013/05/05 21:09:24.939 ttyT38-0(e...0x60107700 OpalUDP Setting interface to 
192.168.0.21%eth0
2013/05/05 21:09:24.939 ttyT38-0(e...0x60107700 SIP     Transaction timers set: 
retry=0.500, completion=32.000
2013/05/05 21:09:24.939 ttyT38-0(e...0x60107700 OpalCon SetPhase from 
UninitialisedPhase to SetUpPhase for 
Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09]
2013/05/05 21:09:24.939 ttyT38-0(e...0x60107700 OpalCon 
OnSetUpConnectionCall[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09]
2013/05/05 21:09:24.939 ttyT38-0(e...0x60107700 OpalEP  OnSetUpConnection 
Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09]
2013/05/05 21:09:24.939 ttyT38-0(e...0x60107700 OpalMan SetUpCall succeeded, 
call=Call[C3ff77e3a1]
2013/05/05 21:09:24.939 ttyT38-0(e...0x60107700 OpalCon Applying string options:
Try-Next-Count=1
Original-Party-B=189659@+/dev/ttyT38-0

2013/05/05 21:09:24.939 ttyT38-0(e...0x60107700 ModemEndPoint::OnMyCallback 
request={
calltoken=modem:/C3ff77e3a1/0
localpartyname=
command=dial
response=confirm
number=189659
modemtoken=ttyT38-0
}
2013/05/05 21:09:24.943 Opal Liste...0x60085700 OpalUDP Binding to interface: 
192.168.0.21:5060
2013/05/05 21:09:24.944 Opal Liste...0x60085700 SIP     Waiting for PDU on 
udp$192.168.0.222:5060<if=udp$192.168.0.21:5060>
2013/05/05 21:09:24.944 Opal Liste...0x60085700 SIP     PDU received: 
rem=udp$192.168.0.222:5060,local=udp$192.168.0.21:5060,if=192.168.0.21%eth0
SIP/2.0 401 Unauthorized
CSeq: 1 INVITE
Via: SIP/2.0/UDP 
192.168.0.21:5060;branch=z9hG4bK946122f8-24b4-e211-9edb-6c626db69c09;rport=5060
User-Agent: AVM FRITZ!Box Fon WLAN 7390 (UI) 84.05.50 (Dec 11 2012)
From: "root" <sip:[email protected]>;tag=7aa61ff8-24b4-e211-9edb-6c626db69c09
Call-ID: dab91ff8-24b4-e211-9edb-6c626db69c09@ceramic
To: <sip:[email protected]>;tag=81DB78A3B1AE49EE
Content-Length: 0
WWW-Authenticate: Digest realm="fritz.box", nonce="66B56F740C61018A"


2013/05/05 21:09:24.945 Opal Liste...0x60085700 SIP     Queueing PDU "1 INVITE 
<401>", transaction=z9hG4bK946122f8-24b4-e211-9edb-6c626db69c09, 
token=7aa61ff8-24b4-e211-9edb-6c626db69c09
2013/05/05 21:09:24.945 Opal Liste...0x60085700 PWLib   File handle high water 
mark set: 41 Thread unblock pipe
2013/05/05 21:09:24.945 Opal Liste...0x60085700 PTLib   Thread high water mark 
set: 13
2013/05/05 21:09:24.945 Opal Liste...0x60085700 Opal    Transport clean up on 
termination
2013/05/05 21:09:24.945 Opal Liste...0x60085700 Opal    Transport Close
2013/05/05 21:09:24.945 Opal Liste...0x60085700 Opal    Deleted transport 
udp$192.168.0.222:5060<if=udp$192.168.0.21:5060>
2013/05/05 21:09:24.945         Pool:0x55ec8700 SIP     Handling PDU "1 INVITE 
<401>" for transaction=z9hG4bK946122f8-24b4-e211-9edb-6c626db69c09
2013/05/05 21:09:24.948         Pool:0x55ec8700 SIP     Sending PDU (370 bytes) 
to: rem=udp$192.168.0.222:5060,local=udp$192.168.0.21:5060,if=192.168.0.21%eth0
ACK sip:[email protected] SIP/2.0
CSeq: 1 ACK
Via: SIP/2.0/UDP 
192.168.0.21:5060;branch=z9hG4bK946122f8-24b4-e211-9edb-6c626db69c09;rport
From: "root" <sip:[email protected]>;tag=7aa61ff8-24b4-e211-9edb-6c626db69c09
Call-ID: dab91ff8-24b4-e211-9edb-6c626db69c09@ceramic
To: <sip:[email protected]>;tag=81DB78A3B1AE49EE
Content-Length: 0
Max-Forwards: 70


2013/05/05 21:09:24.948         Pool:0x55ec8700 OpalUDP Setting interface to 
192.168.0.21%eth0
2013/05/05 21:09:24.948         Pool:0x55ec8700 SIP     INVITE transaction 
id=z9hG4bK946122f8-24b4-e211-9edb-6c626db69c09 completing.
2013/05/05 21:09:24.948         Pool:0x55ec8700 SIP     Received Authentication 
Required response for 1 INVITE sip:[email protected]
2013/05/05 21:09:24.949         Pool:0x55ec8700 SIP     Located existing 
credentials for ID "620" at realm "fritz.box"
2013/05/05 21:09:24.949         Pool:0x55ec8700 SIP     Found auth info for 
realm "fritz.box", user "620"
2013/05/05 21:09:24.949         Pool:0x55ec8700 OpalUDP Setting interface to 
192.168.0.21%eth0
2013/05/05 21:09:24.949         Pool:0x55ec8700 SIP     Remote dialog address 
from target: sip:[email protected]
2013/05/05 21:09:24.951         Pool:0x55ec8700 SIP     INVITE transaction 
id=z9hG4bK729024f8-24b4-e211-9edb-6c626db69c09 created.
2013/05/05 21:09:24.951         Pool:0x55ec8700 SIP     Creating INVITE request
2013/05/05 21:09:24.951         Pool:0x55ec8700 SIP     Offering all configured 
media:
    
GSM-AMR,GSM-06.10,G.726-40k,G.726-32k,G.726-24k,G.726-16k,G.711-uLaw-64k,G.711-ALaw-64k,LPC-10,MS-GSM,MS-IMA-ADPCM,SILK-8,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,T.38,UserInput/RFC2833
2013/05/05 21:09:24.952         Pool:0x55ec8700 SIP     Offering media type 
audio in SDP
2013/05/05 21:09:24.952         Pool:0x55ec8700 Call    IsMediaBypassPossible 
Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09] session 1
2013/05/05 21:09:24.952         Pool:0x55ec8700 OpalMan IsMediaBypassPossible: 
session 1
2013/05/05 21:09:24.952         Pool:0x55ec8700 OpalCon IsMediaBypassPossible: 
default returns false
2013/05/05 21:09:24.952         Pool:0x55ec8700 RTP     Found existing media 
session 1
2013/05/05 21:09:24.952         Pool:0x55ec8700 RTP     Found existing RTP 
session 1
2013/05/05 21:09:24.953         Pool:0x55ec8700 SDP     SDP not including 
MS-GSM as it is not a SIP transportable format
2013/05/05 21:09:24.953         Pool:0x55ec8700 SDP     SDP not including 
MS-IMA-ADPCM as it is not a SIP transportable format
2013/05/05 21:09:24.953         Pool:0x55ec8700 SDP     SDP not including 
SpeexIETFNarrow-11k as it is not a SIP transportable format
2013/05/05 21:09:24.953         Pool:0x55ec8700 SDP     SDP not including 
SpeexIETFNarrow-15k as it is not a SIP transportable format
2013/05/05 21:09:24.953         Pool:0x55ec8700 SDP     SDP not including 
SpeexIETFNarrow-18.2k as it is not a SIP transportable format
2013/05/05 21:09:24.953         Pool:0x55ec8700 SDP     SDP not including 
SpeexIETFNarrow-24.6k as it is not a SIP transportable format
2013/05/05 21:09:24.953         Pool:0x55ec8700 SDP     SDP not including 
SpeexIETFNarrow-5.95k as it is not a SIP transportable format
2013/05/05 21:09:24.953         Pool:0x55ec8700 SDP     SDP not including 
SpeexIETFNarrow-8k as it is not a SIP transportable format
2013/05/05 21:09:24.953         Pool:0x55ec8700 SDP     SDP not including 
SpeexWNarrow-8k as it is not a SIP transportable format
2013/05/05 21:09:24.953         Pool:0x55ec8700 MediaFormat     Merging 
UserInput/RFC2833 into UserInput/RFC2833
2013/05/05 21:09:24.954         Pool:0x55ec8700 RFC2833 Set tx pt=[pt=101], 
events="0-16,32,36" for UserInput/RFC2833
2013/05/05 21:09:24.954         Pool:0x55ec8700 RFC2833 Set rx pt=[pt=101], 
events="0-16,32,36" for UserInput/RFC2833
2013/05/05 21:09:24.954         Pool:0x55ec8700 RFC2833 Set tx pt=[pt=128], 
events="" for NamedSignalEvent
2013/05/05 21:09:24.954         Pool:0x55ec8700 SIP     No media formats of 
type video, not adding SDP
2013/05/05 21:09:24.954         Pool:0x55ec8700 HTTP    Adding authentication 
information
2013/05/05 21:09:24.954         Pool:0x55ec8700 SIP     Transaction remote 
address is udp$192.168.0.222:5060
2013/05/05 21:09:24.955         Pool:0x55ec8700 OpalPlugin      
to_customised_options: SILK-8
2013/05/05 21:09:24.958         Pool:0x55ec8700 SIP     PDU is too large (1314 
bytes) trying compact form.
2013/05/05 21:09:24.958         Pool:0x55ec8700 SIP     Sending PDU (1264 
bytes) to: 
rem=udp$192.168.0.222:5060,local=udp$192.168.0.21:5060,if=192.168.0.21%eth0
INVITE sip:[email protected] SIP/2.0
CSeq: 2 INVITE
v: SIP/2.0/UDP 
192.168.0.21:5060;branch=z9hG4bK729024f8-24b4-e211-9edb-6c626db69c09;rport
User-Agent: T38Modem/2.0.0
Authorization: Digest username="620", realm="fritz.box", 
nonce="66B56F740C61018A", uri="sip:[email protected]", algorithm=MD5, 
response="c9be58d0c8de5a66ccf28b5457b12835"
f: "root" <sip:[email protected]>;tag=7aa61ff8-24b4-e211-9edb-6c626db69c09
i: dab91ff8-24b4-e211-9edb-6c626db69c09@ceramic
k: 100rel,replaces
Organization: Vyacheslav Frolov
t: <sip:[email protected]>
m: "root" <sip:[email protected]:5060>
Allow: 
INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK
l: 532
c: application/sdp
Max-Forwards: 70

v=0
o=- 1367780964 1 IN IP4 192.168.0.21
s=T38Modem/2.0.0
c=IN IP4 192.168.0.21
t=0 0
m=audio 5000 RTP/AVP 120 3 102 103 104 105 0 8 126 121 118 101
a=sendrecv
a=rtpmap:120 AMR/8000/1
a=rtpmap:3 gsm/8000/1
a=rtpmap:102 G726-40/8000/1
a=rtpmap:103 G726-32/8000/1
a=rtpmap:104 G726-24/8000/1
a=rtpmap:105 G726-16/8000/1
a=rtpmap:0 PCMU/8000/1
a=rtpmap:8 PCMA/8000/1
a=rtpmap:126 lpc10/8000/1
a=rtpmap:121 SILK/8000/1
a=rtpmap:118 Speex/8000/1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16,32,36
a=maxptime:22

2013/05/05 21:09:24.959         Pool:0x55ec8700 OpalUDP Setting interface to 
192.168.0.21%eth0
2013/05/05 21:09:24.959         Pool:0x55ec8700 SIP     Transaction timers set: 
retry=0.500, completion=32.000
2013/05/05 21:09:24.959         Pool:0x55ec8700 SIP     INVITE transaction 
id=z9hG4bK946122f8-24b4-e211-9edb-6c626db69c09 completed.
2013/05/05 21:09:24.959         Pool:0x55ec8700 SIP     Handled PDU "1 INVITE 
<401>"
2013/05/05 21:09:24.976 Opal Liste...0x60085700 OpalUDP Binding to interface: 
192.168.0.21:5060
2013/05/05 21:09:24.976 Opal Liste...0x60085700 SIP     Waiting for PDU on 
udp$192.168.0.222:5060<if=udp$192.168.0.21:5060>
2013/05/05 21:09:24.977 Opal Liste...0x60085700 SIP     PDU received: 
rem=udp$192.168.0.222:5060,local=udp$192.168.0.21:5060,if=192.168.0.21%eth0
SIP/2.0 100 Trying
CSeq: 2 INVITE
Via: SIP/2.0/UDP 
192.168.0.21:5060;branch=z9hG4bK729024f8-24b4-e211-9edb-6c626db69c09;rport=5060
User-Agent: AVM FRITZ!Box Fon WLAN 7390 (UI) 84.05.50 (Dec 11 2012)
From: "root" <sip:[email protected]>;tag=7aa61ff8-24b4-e211-9edb-6c626db69c09
Call-ID: dab91ff8-24b4-e211-9edb-6c626db69c09@ceramic
To: <sip:[email protected]>
Content-Length: 0


2013/05/05 21:09:24.977 Opal Liste...0x60085700 SIP     Queueing PDU "2 INVITE 
<100>", transaction=z9hG4bK729024f8-24b4-e211-9edb-6c626db69c09, 
token=7aa61ff8-24b4-e211-9edb-6c626db69c09
2013/05/05 21:09:24.977 Opal Liste...0x60085700 Opal    Transport clean up on 
termination
2013/05/05 21:09:24.977 Opal Liste...0x60085700 Opal    Transport Close
2013/05/05 21:09:24.977 Opal Liste...0x60085700 Opal    Deleted transport 
udp$192.168.0.222:5060<if=udp$192.168.0.21:5060>
2013/05/05 21:09:24.978         Pool:0x55ec8700 SIP     Handling PDU "2 INVITE 
<100>" for transaction=z9hG4bK729024f8-24b4-e211-9edb-6c626db69c09
2013/05/05 21:09:24.978         Pool:0x55ec8700 SIP     Route set is 
2013/05/05 21:09:24.979         Pool:0x55ec8700 SIP     Product Info: 
name="AVM", version="", vendor="", comments="FRITZ!Box Fon WLAN 7390 (UI) 
84.05.50 (Dec 11 2012)"
2013/05/05 21:09:24.979         Pool:0x55ec8700 SIP     INVITE transaction 
id=z9hG4bK729024f8-24b4-e211-9edb-6c626db69c09 proceeding.
2013/05/05 21:09:24.979         Pool:0x55ec8700 SIP     Received Trying response
2013/05/05 21:09:24.979         Pool:0x55ec8700 OpalCon SetPhase from 
SetUpPhase to ProceedingPhase for 
Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09]
2013/05/05 21:09:24.979         Pool:0x55ec8700 OpalMan OnProceeding 
Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09]
2013/05/05 21:09:24.979         Pool:0x55ec8700 Call    OnProceeding 
Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09]
2013/05/05 21:09:24.979         Pool:0x55ec8700 SIP     Handled PDU "2 INVITE 
<100>"
2013/05/05 21:09:24.993 Opal Liste...0x60085700 OpalUDP Binding to interface: 
192.168.0.21:5060
2013/05/05 21:09:24.993 Opal Liste...0x60085700 SIP     Waiting for PDU on 
udp$192.168.0.222:5060<if=udp$192.168.0.21:5060>
2013/05/05 21:09:24.993 Opal Liste...0x60085700 SIP     PDU received: 
rem=udp$192.168.0.222:5060,local=udp$192.168.0.21:5060,if=192.168.0.21%eth0
SIP/2.0 183 Session Progress
CSeq: 2 INVITE
Via: SIP/2.0/UDP 
192.168.0.21:5060;branch=z9hG4bK729024f8-24b4-e211-9edb-6c626db69c09;rport=5060
User-Agent: AVM FRITZ!Box Fon WLAN 7390 (UI) 84.05.50 (Dec 11 2012)
From: "root" <sip:[email protected]>;tag=7aa61ff8-24b4-e211-9edb-6c626db69c09
Call-ID: dab91ff8-24b4-e211-9edb-6c626db69c09@ceramic
To: <sip:[email protected]>;tag=4E5A5E865B23136B
Contact: <sip:[email protected]>
Content-Type: application/sdp
Content-Length: 353

v=0
o=user 4295806 4295806 IN IP4 192.168.0.222
s=T38Modem/2.0.0
c=IN IP4 192.168.0.222
t=0 0
m=audio 7078 RTP/AVP 102 103 104 0 8 101
a=rtpmap:102 G726-40/8000/1
a=rtpmap:103 G726-32/8000/1
a=rtpmap:104 G726-24/8000/1
a=rtpmap:0 PCMU/8000/1
a=rtpmap:8 PCMA/8000/1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
a=rtcp:7079

2013/05/05 21:09:24.994 Opal Liste...0x60085700 SIP     Queueing PDU "2 INVITE 
<183>", transaction=z9hG4bK729024f8-24b4-e211-9edb-6c626db69c09, 
token=7aa61ff8-24b4-e211-9edb-6c626db69c09
2013/05/05 21:09:24.994 Opal Liste...0x60085700 Opal    Transport clean up on 
termination
2013/05/05 21:09:24.994 Opal Liste...0x60085700 Opal    Transport Close
2013/05/05 21:09:24.994 Opal Liste...0x60085700 Opal    Deleted transport 
udp$192.168.0.222:5060<if=udp$192.168.0.21:5060>
2013/05/05 21:09:24.994         Pool:0x55ec8700 SIP     Handling PDU "2 INVITE 
<183>" for transaction=z9hG4bK729024f8-24b4-e211-9edb-6c626db69c09
2013/05/05 21:09:24.994         Pool:0x55ec8700 SIP     Route set is 
2013/05/05 21:09:24.995         Pool:0x55ec8700 SIP     Updating dialog tag 
from "" to "4E5A5E865B23136B"
2013/05/05 21:09:24.995         Pool:0x55ec8700 SIP     Product Info: 
name="AVM", version="", vendor="", comments="FRITZ!Box Fon WLAN 7390 (UI) 
84.05.50 (Dec 11 2012)"
2013/05/05 21:09:24.996         Pool:0x55ec8700 SDP     Parsed connection 
address udp$192.168.0.222
2013/05/05 21:09:24.996         Pool:0x55ec8700 SDP     Parsed connection 
address udp$192.168.0.222
2013/05/05 21:09:24.996         Pool:0x55ec8700 SDP     Media session port=7078
2013/05/05 21:09:24.996         Pool:0x55ec8700 SDP     Unknown media attribute 
rtcp
2013/05/05 21:09:24.997         Pool:0x55ec8700 SDP     Parsed final media 
session with 6 'audio' formats
2013/05/05 21:09:24.997         Pool:0x55ec8700 SIP     RTP payload type 
G726-40 matched to codec G.726-40k
2013/05/05 21:09:24.997         Pool:0x55ec8700 SIP     RTP payload type 
G726-32 matched to codec G.726-32k
2013/05/05 21:09:24.998         Pool:0x55ec8700 SIP     RTP payload type 
G726-24 matched to codec G.726-24k
2013/05/05 21:09:24.998         Pool:0x55ec8700 SIP     RTP payload type PCMU 
matched to codec G.711-uLaw-64k
2013/05/05 21:09:24.998         Pool:0x55ec8700 SIP     RTP payload type PCMA 
matched to codec G.711-ALaw-64k
2013/05/05 21:09:24.999         Pool:0x55ec8700 SIP     RTP payload type 
telephone-event matched to codec UserInput/RFC2833
2013/05/05 21:09:24.999         Pool:0x55ec8700 SIP     INVITE transaction 
id=z9hG4bK729024f8-24b4-e211-9edb-6c626db69c09 proceeding.
2013/05/05 21:09:24.999         Pool:0x55ec8700 SIP     Received Session 
Progress response
2013/05/05 21:09:24.999         Pool:0x55ec8700 SIP     Processing received SDP 
media description for audio
2013/05/05 21:09:24.999         Pool:0x55ec8700 Call    IsMediaBypassPossible 
Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09] session 1
2013/05/05 21:09:24.999         Pool:0x55ec8700 OpalMan IsMediaBypassPossible: 
session 1
2013/05/05 21:09:25.000         Pool:0x55ec8700 OpalCon IsMediaBypassPossible: 
default returns false
2013/05/05 21:09:25.000         Pool:0x55ec8700 RTP     Found existing RTP 
session 1
2013/05/05 21:09:25.000         Pool:0x55ec8700 RTP_UDP Session 1, 
SetRemoteSocketInfo: data channel, new=192.168.0.222:7078, 
local=192.168.0.21:5000-5001, remote=0.0.0.0:0-0
2013/05/05 21:09:25.000         Pool:0x55ec8700 RTP     Found existing media 
session 1
2013/05/05 21:09:25.000         Pool:0x55ec8700 Call    OpenSourceMediaStreams 
open for audio session 1 on 
Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09]
2013/05/05 21:09:25.000         Pool:0x55ec8700 
ModemConnection::GetMediaFormats Call[C3ff77e3a1]-EP<modem>[modem:/C3ff77e3a1/0]
2013/05/05 21:09:25.000         Pool:0x55ec8700 ModemEndPoint::GetMediaFormats
2013/05/05 21:09:25.001         Pool:0x55ec8700 SIP     Using offered media 
format list
2013/05/05 21:09:25.001         Pool:0x55ec8700 
MySIPConnection::GetMediaFormats:
G.726-40k
G.726-32k
G.726-24k
G.711-uLaw-64k
G.711-ALaw-64k
UserInput/RFC2833
T.38

2013/05/05 21:09:25.001         Pool:0x55ec8700 
MySIPConnection::GetMediaFormats Remove G.726-40k
2013/05/05 21:09:25.001         Pool:0x55ec8700 
MySIPConnection::GetMediaFormats Remove G.726-32k
2013/05/05 21:09:25.001         Pool:0x55ec8700 
MySIPConnection::GetMediaFormats Remove G.726-24k
2013/05/05 21:09:25.001         Pool:0x55ec8700 
MySIPConnection::GetMediaFormats:
G.711-uLaw-64k
G.711-ALaw-64k
UserInput/RFC2833
T.38

2013/05/05 21:09:25.003         Pool:0x55ec8700 MediaFormat     Merging 
G.711-uLaw-64k into G.711-uLaw-64k
2013/05/05 21:09:25.004         Pool:0x55ec8700 MediaFormat     Merging PCM-16 
into PCM-16
2013/05/05 21:09:25.004         Pool:0x55ec8700 MediaFormat     Merging PCM-16 
into G.711-uLaw-64k
2013/05/05 21:09:25.004         Pool:0x55ec8700 MediaFormat     Merging 
G.711-uLaw-64k into PCM-16
2013/05/05 21:09:25.004         Pool:0x55ec8700 MediaFormat     Changed media 
option "Max Bit Rate" from "128000" to "64000"
2013/05/05 21:09:25.004         Pool:0x55ec8700 Call    Selected media formats 
G.711-uLaw-64k -> PCM-16
2013/05/05 21:09:25.004         Pool:0x55ec8700 Call    IsMediaBypassPossible 
Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09] session 1
2013/05/05 21:09:25.004         Pool:0x55ec8700 OpalMan IsMediaBypassPossible: 
session 1
2013/05/05 21:09:25.004         Pool:0x55ec8700 OpalCon IsMediaBypassPossible: 
default returns false
2013/05/05 21:09:25.005         Pool:0x55ec8700 RTP     Found existing RTP 
session 1
2013/05/05 21:09:25.005         Pool:0x55ec8700 RTP     Found existing media 
session 1
2013/05/05 21:09:25.005         Pool:0x55ec8700 RTP_UDP Session 1 reopened for 
reading
2013/05/05 21:09:25.005         Pool:0x55ec8700 
MySIPConnection::OnOpenMediaStream: OpalRTPMediaStream-Source-G.711-uLaw-64k
2013/05/05 21:09:25.005         Pool:0x55ec8700 RTP     Found existing RTP 
session 1
2013/05/05 21:09:25.005         Pool:0x55ec8700 OpalMan OnOpenMediaStream 
Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09],OpalRTPMediaStream-Source-G.711-uLaw-64k
2013/05/05 21:09:25.005         Pool:0x55ec8700 OpalCon Opened source stream 
C3ff77e3a1_1 with format G.711-uLaw-64k
2013/05/05 21:09:25.005         Pool:0x55ec8700 
ModemConnection::CreateMediaStream 
Call[C3ff77e3a1]-EP<modem>[modem:/C3ff77e3a1/0] mediaFormat=PCM-16 sessionID=1 
isSource=0
2013/05/05 21:09:25.005         Pool:0x55ec8700 ttyT38-0 AudioEngine AudioEngine
2013/05/05 21:09:25.005         Pool:0x55ec8700 ttyT38-0 AudioEngine Attach
2013/05/05 21:09:25.005         Pool:0x55ec8700 ttyT38-0 AudioEngine OnAttach 
Attached
2013/05/05 21:09:25.005         Pool:0x55ec8700 ttyT38-0 AudioEngine 
OnResetModemState
2013/05/05 21:09:25.005         Pool:0x55ec8700 ttyT38-0 AudioEngine 
ChangeModemClass to mcFax
2013/05/05 21:09:25.005         Pool:0x55ec8700 ttyT38-0 AudioEngine 
OnChangeModemClass to mcFax
2013/05/05 21:09:25.006         Pool:0x55ec8700 ttyT38-0 AudioEngine SendOnIdle 
dtNone
2013/05/05 21:09:25.006         Pool:0x55ec8700 ModemEngineBody::_AttachEngine 
Attached mceAudio
2013/05/05 21:09:25.006         Pool:0x55ec8700 ModemEngineBody::NewPtrEngine 
created pointer for engine mceAudio
2013/05/05 21:09:25.006         Pool:0x55ec8700 
AudioModemMediaStream::AudioModemMediaStream AudioModemMediaStream-Sink-PCM-16
2013/05/05 21:09:25.006         Pool:0x55ec8700 AudioModemMediaStream::Open 
AudioModemMediaStream-Sink-PCM-16
2013/05/05 21:09:25.006         Pool:0x55ec8700 ttyT38-0 AudioEngine OpenIn: 
open 0x103a2a0
2013/05/05 21:09:25.006         Pool:0x55ec8700 
ModemEngineBody::OnEngineCallback AudioEngine cbpUpdateState (0, stConnectWait)
2013/05/05 21:09:25.006         Pool:0x55ec8700 OpalMan OnOpenMediaStream 
Call[C3ff77e3a1]-EP<modem>[modem:/C3ff77e3a1/0],AudioModemMediaStream-Sink-PCM-16
2013/05/05 21:09:25.006         Pool:0x55ec8700 OpalCon Opened sink stream 
C3ff77e3a1_1 with format PCM-16
2013/05/05 21:09:25.007         Pool:0x55ec8700 PWLib   File handle high water 
mark set: 42 PUDPSocket
2013/05/05 21:09:25.007         Pool:0x55ec8700 Adding patch Patch[0x103a610] 
OpalRTPMediaStream-Source-G.711-uLaw-64k on stream 
OpalRTPMediaStream-Source-G.711-uLaw-64k
2013/05/05 21:09:25.007         Pool:0x55ec8700 Adding patch Patch[0x103a610] 
OpalRTPMediaStream-Source-G.711-uLaw-64k on stream 
AudioModemMediaStream-Sink-PCM-16
2013/05/05 21:09:25.007         Pool:0x55ec8700 Patch   Created Sink: 
format=PCM-16
2013/05/05 21:09:25.007         Pool:0x55ec8700 Codec   G711-uLaw-64k decoder 
created
2013/05/05 21:09:25.007         Pool:0x55ec8700 MediaFormat     Merging 
G.711-uLaw-64k into G.711-uLaw-64k
2013/05/05 21:09:25.008         Pool:0x55ec8700 MediaFormat     Merging PCM-16 
into PCM-16
2013/05/05 21:09:25.008         Pool:0x55ec8700 Patch   Created primary codec 
G.711-uLaw-64k->PCM-16 with ID C3ff77e3a1_1
2013/05/05 21:09:25.008         Pool:0x55ec8700 Media   Set data size from 16 
to 3840
2013/05/05 21:09:25.008         Pool:0x55ec8700 Patch   Added media stream sink 
AudioModemMediaStream-Sink-PCM-16 using transcoder G.711-uLaw-64k->PCM-16, data 
size=3840
2013/05/05 21:09:25.008         Pool:0x55ec8700 Media   RTP data size cannot be 
changed to 160, fixed at 2048
2013/05/05 21:09:25.008         Pool:0x55ec8700 OpalCon Added send DTMF filter 
on connection Call[C3ff77e3a1]-EP<modem>[modem:/C3ff77e3a1/0], patch 
Patch[0x103a610] OpalRTPMediaStream-Source-G.711-uLaw-64k -> 
AudioModemMediaStream-Sink-PCM-16
2013/05/05 21:09:25.008         Pool:0x55ec8700 OpalCon Sink stream of 
connection Call[C3ff77e3a1]-EP<modem>[modem:/C3ff77e3a1/0] uses patch 
Patch[0x103a610] OpalRTPMediaStream-Source-G.711-uLaw-64k -> 
AudioModemMediaStream-Sink-PCM-16
2013/05/05 21:09:25.008         Pool:0x55ec8700 OpalCon Source stream of 
connection Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09] uses 
patch Patch[0x103a610] OpalRTPMediaStream-Source-G.711-uLaw-64k -> 
AudioModemMediaStream-Sink-PCM-16
2013/05/05 21:09:25.009         Pool:0x55ec8700 RTPCon  Adding RFC2833 receive 
handler
2013/05/05 21:09:25.009         Pool:0x55ec8700 RTPCon  Adding Cisco NSE 
receive handler
2013/05/05 21:09:25.009         Pool:0x55ec8700 PWLib   File handle high water 
mark set: 43 Thread unblock pipe
2013/05/05 21:09:25.009         Pool:0x55ec8700 PTLib   Thread high water mark 
set: 14
2013/05/05 21:09:25.009  Media Patch:0x55e87700 Patch   Thread started for 
Patch[0x103a610] OpalRTPMediaStream-Source-G.711-uLaw-64k -> 
AudioModemMediaStream-Sink-PCM-16
2013/05/05 21:09:25.009  Media Patch:0x55e87700 RTP_UDP Session 1, flushed 1 
RTP data packets on startup
2013/05/05 21:09:25.009         Pool:0x55ec8700 Media   Starting thread Media 
Patch:0x55e87700
2013/05/05 21:09:25.009         Pool:0x55ec8700 Call    GetOtherPartyConnection 
Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09]
2013/05/05 21:09:25.010         Pool:0x55ec8700 Call    OpenSourceMediaStreams 
open for audio session 1 on Call[C3ff77e3a1]-EP<modem>[modem:/C3ff77e3a1/0]
2013/05/05 21:09:25.010         Pool:0x55ec8700 SIP     Using offered media 
format list
2013/05/05 21:09:25.010         Pool:0x55ec8700 
MySIPConnection::GetMediaFormats:
G.726-40k
G.726-32k
G.726-24k
G.711-uLaw-64k
G.711-ALaw-64k
UserInput/RFC2833
T.38

2013/05/05 21:09:25.010         Pool:0x55ec8700 
MySIPConnection::GetMediaFormats Remove G.726-40k
2013/05/05 21:09:25.010         Pool:0x55ec8700 
MySIPConnection::GetMediaFormats Remove G.726-32k
2013/05/05 21:09:25.010         Pool:0x55ec8700 
MySIPConnection::GetMediaFormats Remove G.726-24k
2013/05/05 21:09:25.010         Pool:0x55ec8700 
MySIPConnection::GetMediaFormats:
G.711-uLaw-64k
G.711-ALaw-64k
UserInput/RFC2833
T.38

2013/05/05 21:09:25.010         Pool:0x55ec8700 
ModemConnection::GetMediaFormats Call[C3ff77e3a1]-EP<modem>[modem:/C3ff77e3a1/0]
2013/05/05 21:09:25.010         Pool:0x55ec8700 ModemEndPoint::GetMediaFormats
2013/05/05 21:09:25.013         Pool:0x55ec8700 MediaFormat     Merging PCM-16 
into PCM-16
2013/05/05 21:09:25.013         Pool:0x55ec8700 MediaFormat     Merging 
G.711-uLaw-64k into G.711-uLaw-64k
2013/05/05 21:09:25.013         Pool:0x55ec8700 MediaFormat     Merging 
G.711-uLaw-64k into PCM-16
2013/05/05 21:09:25.013         Pool:0x55ec8700 MediaFormat     Changed media 
option "Max Bit Rate" from "128000" to "64000"
2013/05/05 21:09:25.013         Pool:0x55ec8700 MediaFormat     Merging PCM-16 
into G.711-uLaw-64k
2013/05/05 21:09:25.013         Pool:0x55ec8700 Call    Selected media formats 
PCM-16 -> G.711-uLaw-64k
2013/05/05 21:09:25.013         Pool:0x55ec8700 
ModemConnection::CreateMediaStream 
Call[C3ff77e3a1]-EP<modem>[modem:/C3ff77e3a1/0] mediaFormat=PCM-16 sessionID=1 
isSource=1
2013/05/05 21:09:25.014         Pool:0x55ec8700 ttyT38-0 AudioEngine SendOnIdle 
dtNone
2013/05/05 21:09:25.014         Pool:0x55ec8700 ModemEngineBody::_AttachEngine 
Attached mceAudio
2013/05/05 21:09:25.014         Pool:0x55ec8700 ModemEngineBody::NewPtrEngine 
created pointer for engine mceAudio
2013/05/05 21:09:25.014         Pool:0x55ec8700 
AudioModemMediaStream::AudioModemMediaStream AudioModemMediaStream-Source-PCM-16
2013/05/05 21:09:25.014         Pool:0x55ec8700 AudioModemMediaStream::Open 
AudioModemMediaStream-Source-PCM-16
2013/05/05 21:09:25.014         Pool:0x55ec8700 ttyT38-0 AudioEngine OpenOut: 
open 0x1042150
2013/05/05 21:09:25.014         Pool:0x55ec8700 
ModemEngineBody::OnEngineCallback AudioEngine cbpUpdateState (0, stConnectWait)
2013/05/05 21:09:25.014         Pool:0x55ec8700 OpalMan OnOpenMediaStream 
Call[C3ff77e3a1]-EP<modem>[modem:/C3ff77e3a1/0],AudioModemMediaStream-Source-PCM-16
2013/05/05 21:09:25.014         Pool:0x55ec8700 OpalCon Opened source stream 
C3ff77e3a1_1 with format PCM-16
2013/05/05 21:09:25.014         Pool:0x55ec8700 Call    IsMediaBypassPossible 
Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09] session 1
2013/05/05 21:09:25.014         Pool:0x55ec8700 OpalMan IsMediaBypassPossible: 
session 1
2013/05/05 21:09:25.014         Pool:0x55ec8700 OpalCon IsMediaBypassPossible: 
default returns false
2013/05/05 21:09:25.014         Pool:0x55ec8700 RTP     Found existing RTP 
session 1
2013/05/05 21:09:25.014         Pool:0x55ec8700 RTP     Found existing media 
session 1
2013/05/05 21:09:25.014         Pool:0x55ec8700 RTP_UDP Session 1 reopened for 
writing
2013/05/05 21:09:25.015         Pool:0x55ec8700 
MySIPConnection::OnOpenMediaStream: OpalRTPMediaStream-Sink-G.711-uLaw-64k
2013/05/05 21:09:25.015         Pool:0x55ec8700 RTP     Found existing RTP 
session 1
2013/05/05 21:09:25.015         Pool:0x55ec8700 OpalMan OnOpenMediaStream 
Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09],OpalRTPMediaStream-Sink-G.711-uLaw-64k
2013/05/05 21:09:25.015         Pool:0x55ec8700 OpalCon Opened sink stream 
C3ff77e3a1_1 with format G.711-uLaw-64k
2013/05/05 21:09:25.015         Pool:0x55ec8700 PWLib   File handle high water 
mark set: 44 PUDPSocket
2013/05/05 21:09:25.015         Pool:0x55ec8700 Adding patch Patch[0x1042840] 
AudioModemMediaStream-Source-PCM-16 on stream 
AudioModemMediaStream-Source-PCM-16
2013/05/05 21:09:25.016         Pool:0x55ec8700 Adding patch Patch[0x1042840] 
AudioModemMediaStream-Source-PCM-16 on stream 
OpalRTPMediaStream-Sink-G.711-uLaw-64k
2013/05/05 21:09:25.016         Pool:0x55ec8700 Patch   Created Sink: 
format=G.711-uLaw-64k
2013/05/05 21:09:25.016         Pool:0x55ec8700 Codec   G711-uLaw-64k encoder 
created
2013/05/05 21:09:25.016         Pool:0x55ec8700 MediaFormat     Merging PCM-16 
into PCM-16
2013/05/05 21:09:25.016         Pool:0x55ec8700 MediaFormat     Merging 
G.711-uLaw-64k into G.711-uLaw-64k
2013/05/05 21:09:25.016         Pool:0x55ec8700 Patch   Created primary codec 
PCM-16->G.711-uLaw-64k with ID C3ff77e3a1_1
2013/05/05 21:09:25.016         Pool:0x55ec8700 Media   RTP data size cannot be 
changed to 1920, fixed at 528
2013/05/05 21:09:25.016         Pool:0x55ec8700 Patch   Added media stream sink 
OpalRTPMediaStream-Sink-G.711-uLaw-64k using transcoder PCM-16->G.711-uLaw-64k, 
data size=528
2013/05/05 21:09:25.016         Pool:0x55ec8700 Media   Set data size from 16 
to 320
2013/05/05 21:09:25.017         Pool:0x55ec8700 OpalCon Source stream of 
connection Call[C3ff77e3a1]-EP<modem>[modem:/C3ff77e3a1/0] uses patch 
Patch[0x1042840] AudioModemMediaStream-Source-PCM-16 -> 
OpalRTPMediaStream-Sink-G.711-uLaw-64k
2013/05/05 21:09:25.017         Pool:0x55ec8700 OpalCon Added send DTMF filter 
on connection Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09], 
patch Patch[0x1042840] AudioModemMediaStream-Source-PCM-16 -> 
OpalRTPMediaStream-Sink-G.711-uLaw-64k
2013/05/05 21:09:25.017         Pool:0x55ec8700 OpalCon Sink stream of 
connection Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09] uses 
patch Patch[0x1042840] AudioModemMediaStream-Source-PCM-16 -> 
OpalRTPMediaStream-Sink-G.711-uLaw-64k
2013/05/05 21:09:25.017         Pool:0x55ec8700 PWLib   File handle high water 
mark set: 45 Thread unblock pipe
2013/05/05 21:09:25.017         Pool:0x55ec8700 PTLib   Thread high water mark 
set: 15
2013/05/05 21:09:25.017  Media Patch:0x55e46700 Patch   Thread started for 
Patch[0x1042840] AudioModemMediaStream-Source-PCM-16 -> 
OpalRTPMediaStream-Sink-G.711-uLaw-64k
2013/05/05 21:09:25.017  Media Patch:0x55e46700 
ModemEngineBody::OnEngineCallback AudioEngine cbpUpdateState (0, stConnectWait)
2013/05/05 21:09:25.018  Media Patch:0x55e46700 RTP     Session 1, first sent 
data: ver=2 pt=PCMU psz=160 m=1 x=0 seq=51164 ts=0 src=1b0f5564 ccnt=0
2013/05/05 21:09:25.018  Media Patch:0x55e46700 PWLib   File handle high water 
mark set: 46 PTextFile
2013/05/05 21:09:25.018         Pool:0x55ec8700 Media   Starting thread Media 
Patch:0x55e46700
2013/05/05 21:09:25.018         Pool:0x55ec8700 MediaFormat     Merging 
UserInput/RFC2833 into UserInput/RFC2833
2013/05/05 21:09:25.019         Pool:0x55ec8700 RFC2833 Set tx pt=[pt=101], 
events="0-15" for UserInput/RFC2833
2013/05/05 21:09:25.019         Pool:0x55ec8700 RFC2833 Set tx pt=[pt=128], 
events="" for NamedSignalEvent
2013/05/05 21:09:25.019         Pool:0x55ec8700 SIP     Starting re-INVITE to 
resolve multiple codecs in answer
2013/05/05 21:09:25.019         Pool:0x55ec8700 SIP     Remote dialog address 
from target: sip:[email protected]
2013/05/05 21:09:25.021         Pool:0x55ec8700 SIP     INVITE transaction 
id=z9hG4bK68442ff8-24b4-e211-9edb-6c626db69c09 created.
2013/05/05 21:09:25.022         Pool:0x55ec8700 SIP     Creating INVITE request
2013/05/05 21:09:25.022         Pool:0x55ec8700 SIP     Offering only current 
media streams in Re-INVITE
2013/05/05 21:09:25.022         Pool:0x55ec8700 SIP     Offering media type 
audio in SDP
2013/05/05 21:09:25.022         Pool:0x55ec8700 Call    IsMediaBypassPossible 
Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09] session 1
2013/05/05 21:09:25.022         Pool:0x55ec8700 OpalMan IsMediaBypassPossible: 
session 1
2013/05/05 21:09:25.022         Pool:0x55ec8700 OpalCon IsMediaBypassPossible: 
default returns false
2013/05/05 21:09:25.022         Pool:0x55ec8700 RTP     Found existing media 
session 1
2013/05/05 21:09:25.022         Pool:0x55ec8700 RTP     Found existing RTP 
session 1
2013/05/05 21:09:25.023         Pool:0x55ec8700 MediaFormat     Merging 
UserInput/RFC2833 into UserInput/RFC2833
2013/05/05 21:09:25.023         Pool:0x55ec8700 RFC2833 Set tx pt=[pt=101], 
events="0-16,32,36" for UserInput/RFC2833
2013/05/05 21:09:25.023         Pool:0x55ec8700 RFC2833 Set rx pt=[pt=101], 
events="0-16,32,36" for UserInput/RFC2833
2013/05/05 21:09:25.023         Pool:0x55ec8700 RFC2833 Set tx pt=[pt=128], 
events="" for NamedSignalEvent
2013/05/05 21:09:25.023         Pool:0x55ec8700 HTTP    Adding authentication 
information
2013/05/05 21:09:25.023         Pool:0x55ec8700 SIP     Transaction remote 
address is udp$192.168.0.222:5060
2013/05/05 21:09:25.025         Pool:0x55ec8700 SIP     Sending PDU (1032 
bytes) to: 
rem=udp$192.168.0.222:5060,local=udp$192.168.0.21:5060,if=192.168.0.21%eth0
INVITE sip:[email protected] SIP/2.0
CSeq: 3 INVITE
Via: SIP/2.0/UDP 
192.168.0.21:5060;branch=z9hG4bK68442ff8-24b4-e211-9edb-6c626db69c09;rport
User-Agent: T38Modem/2.0.0
Authorization: Digest username="620", realm="fritz.box", 
nonce="66B56F740C61018A", uri="sip:[email protected]", algorithm=MD5, 
response="c9be58d0c8de5a66ccf28b5457b12835"
From: "root" <sip:[email protected]>;tag=7aa61ff8-24b4-e211-9edb-6c626db69c09
Call-ID: dab91ff8-24b4-e211-9edb-6c626db69c09@ceramic
Supported: 100rel,replaces
Organization: Vyacheslav Frolov
To: <sip:[email protected]>;tag=4E5A5E865B23136B
Contact: "root" <sip:[email protected]:5060>
Allow: 
INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK
Content-Length: 229
Content-Type: application/sdp
Max-Forwards: 70

v=0
o=- 1367780964 2 IN IP4 192.168.0.21
s=T38Modem/2.0.0
c=IN IP4 192.168.0.21
t=0 0
m=audio 5000 RTP/AVP 0 101
a=sendrecv
a=rtpmap:0 PCMU/8000/1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16,32,36
a=maxptime:240

2013/05/05 21:09:25.025         Pool:0x55ec8700 OpalUDP Setting interface to 
192.168.0.21%eth0
2013/05/05 21:09:25.025         Pool:0x55ec8700 SIP     Transaction timers set: 
retry=0.500, completion=32.000
2013/05/05 21:09:25.026         Pool:0x55ec8700 OpalCon SetPhase from 
ProceedingPhase to AlertingPhase for 
Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09]
2013/05/05 21:09:25.026         Pool:0x55ec8700 OpalMan OnAlerting 
Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09]
2013/05/05 21:09:25.026         Pool:0x55ec8700 Call    OnAlerting 
Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09]
2013/05/05 21:09:25.026         Pool:0x55ec8700 ModemConnection::SetAlerting 
Call[C3ff77e3a1]-EP<modem>[modem:/C3ff77e3a1/0] sip:[email protected] 1
2013/05/05 21:09:25.026         Pool:0x55ec8700 OpalCon SetPhase from 
SetUpPhase to AlertingPhase for Call[C3ff77e3a1]-EP<modem>[modem:/C3ff77e3a1/0]
2013/05/05 21:09:25.026         Pool:0x55ec8700 ModemEngineBody::Request: 
stConnectWait request={
calltoken=modem:/C3ff77e3a1/0
command=alerting
}
2013/05/05 21:09:25.026         Pool:0x55ec8700 ModemEngineBody::SetCallState: 
off-hook cstAlerted stConnectWait
2013/05/05 21:09:25.026         Pool:0x55ec8700 SIP     Starting receive media 
to annunciate remote progress tones
2013/05/05 21:09:25.026         Pool:0x55ec8700 Media   Starting stream 
AudioModemMediaStream-Sink-PCM-16
2013/05/05 21:09:25.027         Pool:0x55ec8700 Media   Starting stream 
AudioModemMediaStream-Source-PCM-16
2013/05/05 21:09:25.027         Pool:0x55ec8700 OpalCon Media stream threads 
started for Call[C3ff77e3a1]-EP<modem>[modem:/C3ff77e3a1/0]
2013/05/05 21:09:25.027         Pool:0x55ec8700 Media   Starting stream 
OpalRTPMediaStream-Source-G.711-uLaw-64k
2013/05/05 21:09:25.027         Pool:0x55ec8700 Media   Starting stream 
OpalRTPMediaStream-Sink-G.711-uLaw-64k
2013/05/05 21:09:25.027         Pool:0x55ec8700 OpalCon Media stream threads 
started for Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09]
2013/05/05 21:09:25.027         Pool:0x55ec8700 SIP     Handled PDU "2 INVITE 
<183>"
2013/05/05 21:09:25.027  Media Patch:0x55e87700 RTP     Session 1, first 
receive data: ver=2 pt=[pt=102] psz=100 m=0 x=0 seq=2 ts=320 src=4d1a32b3 ccnt=0
2013/05/05 21:09:25.027  Media Patch:0x55e87700 VoIP Metrics    No Ie and Bpl 
data for payload type [pt=102], unable to calculate R Factor and MOS score.
2013/05/05 21:09:25.027  Media Patch:0x55e87700 Patch   Removing frame with 
mismatched payload type [pt=102] - should be PCMU
2013/05/05 21:09:25.027  Media Patch:0x55e87700 
ModemEngineBody::OnEngineCallback AudioEngine cbpUpdateState (0, stConnectWait)
2013/05/05 21:09:25.030 Opal Liste...0x60085700 OpalUDP Binding to interface: 
192.168.0.21:5060
2013/05/05 21:09:25.030 Opal Liste...0x60085700 SIP     Waiting for PDU on 
udp$192.168.0.222:5060<if=udp$192.168.0.21:5060>
2013/05/05 21:09:25.031 Opal Liste...0x60085700 SIP     PDU received: 
rem=udp$192.168.0.222:5060,local=udp$192.168.0.21:5060,if=192.168.0.21%eth0
SIP/2.0 491 Request Pending
CSeq: 3 INVITE
Via: SIP/2.0/UDP 
192.168.0.21:5060;branch=z9hG4bK68442ff8-24b4-e211-9edb-6c626db69c09;rport=5060
User-Agent: AVM FRITZ!Box Fon WLAN 7390 (UI) 84.05.50 (Dec 11 2012)
From: "root" <sip:[email protected]>;tag=7aa61ff8-24b4-e211-9edb-6c626db69c09
Call-ID: dab91ff8-24b4-e211-9edb-6c626db69c09@ceramic
To: <sip:[email protected]>;tag=4E5A5E865B23136B
Content-Length: 0


2013/05/05 21:09:25.031 Opal Liste...0x60085700 SIP     Queueing PDU "3 INVITE 
<491>", transaction=z9hG4bK68442ff8-24b4-e211-9edb-6c626db69c09, 
token=7aa61ff8-24b4-e211-9edb-6c626db69c09
2013/05/05 21:09:25.031 Opal Liste...0x60085700 Opal    Transport clean up on 
termination
2013/05/05 21:09:25.031 Opal Liste...0x60085700 Opal    Transport Close
2013/05/05 21:09:25.031 Opal Liste...0x60085700 Opal    Deleted transport 
udp$192.168.0.222:5060<if=udp$192.168.0.21:5060>
2013/05/05 21:09:25.032         Pool:0x55ec8700 SIP     Handling PDU "3 INVITE 
<491>" for transaction=z9hG4bK68442ff8-24b4-e211-9edb-6c626db69c09
2013/05/05 21:09:25.034         Pool:0x55ec8700 HTTP    Adding authentication 
information
2013/05/05 21:09:25.034         Pool:0x55ec8700 SIP     Sending PDU (545 bytes) 
to: rem=udp$192.168.0.222:5060,local=udp$192.168.0.21:5060,if=192.168.0.21%eth0
ACK sip:[email protected] SIP/2.0
CSeq: 3 ACK
Via: SIP/2.0/UDP 
192.168.0.21:5060;branch=z9hG4bK68442ff8-24b4-e211-9edb-6c626db69c09;rport
Authorization: Digest username="620", realm="fritz.box", 
nonce="66B56F740C61018A", uri="sip:[email protected]", algorithm=MD5, 
response="5478b106b8487ed189d44aab9d4968a9"
From: "root" <sip:[email protected]>;tag=7aa61ff8-24b4-e211-9edb-6c626db69c09
Call-ID: dab91ff8-24b4-e211-9edb-6c626db69c09@ceramic
To: <sip:[email protected]>;tag=4E5A5E865B23136B
Content-Length: 0
Max-Forwards: 70


2013/05/05 21:09:25.034         Pool:0x55ec8700 OpalUDP Setting interface to 
192.168.0.21%eth0
2013/05/05 21:09:25.035         Pool:0x55ec8700 SIP     INVITE transaction 
id=z9hG4bK68442ff8-24b4-e211-9edb-6c626db69c09 completing.
2013/05/05 21:09:25.035         Pool:0x55ec8700 SIP     Queueing re-INVITE to 
retry after getting 491 Request Pending
2013/05/05 21:09:25.035         Pool:0x55ec8700 SIP     Remote dialog address 
from target: sip:[email protected]
2013/05/05 21:09:25.037         Pool:0x55ec8700 SIP     INVITE transaction 
id=z9hG4bK74ae31f8-24b4-e211-9edb-6c626db69c09 created.
2013/05/05 21:09:25.037         Pool:0x55ec8700 SIP     Creating INVITE request
2013/05/05 21:09:25.037         Pool:0x55ec8700 SIP     Offering only current 
media streams in Re-INVITE
2013/05/05 21:09:25.038         Pool:0x55ec8700 SIP     Offering media type 
audio in SDP
2013/05/05 21:09:25.038         Pool:0x55ec8700 Call    IsMediaBypassPossible 
Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09] session 1
2013/05/05 21:09:25.038         Pool:0x55ec8700 OpalMan IsMediaBypassPossible: 
session 1
2013/05/05 21:09:25.038         Pool:0x55ec8700 OpalCon IsMediaBypassPossible: 
default returns false
2013/05/05 21:09:25.038         Pool:0x55ec8700 RTP     Found existing media 
session 1
2013/05/05 21:09:25.038         Pool:0x55ec8700 RTP     Found existing RTP 
session 1
2013/05/05 21:09:25.038         Pool:0x55ec8700 MediaFormat     Merging 
UserInput/RFC2833 into UserInput/RFC2833
2013/05/05 21:09:25.039         Pool:0x55ec8700 RFC2833 Set tx pt=[pt=101], 
events="0-16,32,36" for UserInput/RFC2833
2013/05/05 21:09:25.039         Pool:0x55ec8700 RFC2833 Set rx pt=[pt=101], 
events="0-16,32,36" for UserInput/RFC2833
2013/05/05 21:09:25.039         Pool:0x55ec8700 RFC2833 Set tx pt=[pt=128], 
events="" for NamedSignalEvent
2013/05/05 21:09:25.039         Pool:0x55ec8700 SIP     INVITE transaction 
id=z9hG4bK68442ff8-24b4-e211-9edb-6c626db69c09 completed.
2013/05/05 21:09:25.039         Pool:0x55ec8700 SIP     Handled PDU "3 INVITE 
<491>"
2013/05/05 21:09:25.046  Media Patch:0x55e87700 Patch   Removing frame with 
mismatched payload type [pt=102] - should be PCMU
2013/05/05 21:09:25.066  Media Patch:0x55e87700 Patch   Removing frame with 
mismatched payload type [pt=102] - should be PCMU
2013/05/05 21:09:25.109  Media Patch:0x55e87700 Patch   Removing frame with 
mismatched payload type [pt=102] - should be PCMU
2013/05/05 21:09:25.110  Media Patch:0x55e87700 Patch   Removing frame with 
mismatched payload type [pt=102] - should be PCMU
2013/05/05 21:09:25.126  Media Patch:0x55e87700 Patch   Removing frame with 
mismatched payload type [pt=102] - should be PCMU
2013/05/05 21:09:25.146  Media Patch:0x55e87700 Patch   Removing frame with 
mismatched payload type [pt=102] - should be PCMU
2013/05/05 21:09:25.166  Media Patch:0x55e87700 Patch   Removing frame with 
mismatched payload type [pt=102] - should be PCMU
2013/05/05 21:09:25.186  Media Patch:0x55e87700 Patch   Removing frame with 
mismatched payload type [pt=102] - should be PCMU
2013/05/05 21:09:25.207  Media Patch:0x55e87700 Patch   Removing frame with 
mismatched payload type [pt=102] - should be PCMU
2013/05/05 21:09:25.229  Media Patch:0x55e87700 Patch   Removing frame with 
mismatched payload type [pt=102] - should be PCMU
2013/05/05 21:09:25.247  Media Patch:0x55e87700 Patch   Consecutive mismatched 
payload type, was expecting PCMU, now using [pt=102]
2013/05/05 21:09:25.247  Media Patch:0x55e87700 MediaFormat     Merging 
G.711-uLaw-64k into G.711-uLaw-64k
2013/05/05 21:09:25.247  Media Patch:0x55e87700 MediaFormat     Changing 
payload type from PCMU to [pt=102] in G.711-uLaw-64k
2013/05/05 21:09:27.017  Media Patch:0x55e46700 RTP     Session 1, transmit 
statistics:  packets=101 octets=16160 avgTime=19 maxTime=22 minTime=18
2013/05/05 21:09:27.026  Media Patch:0x55e87700 RTP     Session 1, receive 
statistics: packets=101 octets=10100 lost=0 tooLate=0 order=0 avgTime=19 
maxTime=43 minTime=1 jitter=68389 maxJitter=33554431
2013/05/05 21:09:27.479  Media Patch:0x55e87700 RTP     Session 1, 
OnRxSenderReport: ssrc=1293562547 ntp=2013/5/5-21:9:27.477047 rtp=19840 
psent=124 osent=12400
  RR: ssrc=453989732 fraction=0 lost=0 last_seq=51286 jitter=21 lsr=0.000 
dlsr=0.000

2013/05/05 21:09:27.479  Media Patch:0x55e87700 RTP     Session 1, 
OnExtendedReport: ssrc=1293562547

2013/05/05 21:09:27.479  Media Patch:0x55e87700 RTP     Session 1, 
SourceDescription item 0x1049c80, current size = 22
2013/05/05 21:09:27.479  Media Patch:0x55e87700 RTP     Session 1, 
OnSourceDescription: 1 entries
  ssrc=1293562547
  item[0]: type=CNAME data="192.168.0.222%5:5060"
2013/05/05 21:09:29.017  Media Patch:0x55e46700 RTP     Session 1, transmit 
statistics:  packets=201 octets=32160 avgTime=20 maxTime=21 minTime=19
2013/05/05 21:09:29.026  Media Patch:0x55e87700 RTP     Session 1, receive 
statistics: packets=201 octets=20100 lost=0 tooLate=0 order=0 avgTime=20 
maxTime=22 minTime=18 jitter=107 maxJitter=33554431
2013/05/05 21:09:29.960  Housekeeper:0x60148700 SIP     Set state 
Terminated_Success for INVITE transaction 
id=z9hG4bK946122f8-24b4-e211-9edb-6c626db69c09
2013/05/05 21:09:29.960  Housekeeper:0x60148700 SIP     Completed state for 
transaction id=z9hG4bK946122f8-24b4-e211-9edb-6c626db69c09
2013/05/05 21:09:30.040  Housekeeper:0x60148700 SIP     Set state 
Terminated_Success for INVITE transaction 
id=z9hG4bK68442ff8-24b4-e211-9edb-6c626db69c09
2013/05/05 21:09:30.040  Housekeeper:0x60148700 SIP     Completed state for 
transaction id=z9hG4bK68442ff8-24b4-e211-9edb-6c626db69c09
2013/05/05 21:09:31.017  Media Patch:0x55e46700 RTP     Session 1, transmit 
statistics:  packets=301 octets=48160 avgTime=20 maxTime=21 minTime=19
2013/05/05 21:09:31.026  Media Patch:0x55e87700 RTP     Session 1, receive 
statistics: packets=301 octets=30100 lost=0 tooLate=0 order=0 avgTime=20 
maxTime=22 minTime=18 jitter=0 maxJitter=33554431
2013/05/05 21:09:31.054  Media Patch:0x55e87700 RTP     Session 1, 
OnRxSenderReport: ssrc=1293562547 ntp=2013/5/5-21:9:31.052682 rtp=48480 
psent=303 osent=30300
  RR: ssrc=453989732 fraction=0 lost=0 last_seq=51465 jitter=21 lsr=0.000 
dlsr=0.000

2013/05/05 21:09:31.055  Media Patch:0x55e87700 RTP     Session 1, 
OnExtendedReport: ssrc=1293562547

2013/05/05 21:09:31.055  Media Patch:0x55e87700 RTP     Session 1, 
SourceDescription item 0x1049c80, current size = 22
2013/05/05 21:09:31.055  Media Patch:0x55e87700 RTP     Session 1, 
OnSourceDescription: 1 entries
  ssrc=1293562547
  item[0]: type=CNAME data="192.168.0.222%5:5060"
2013/05/05 21:09:32.946 Opal Liste...0x60085700 OpalUDP Binding to interface: 
192.168.0.21:5060
2013/05/05 21:09:32.946 Opal Liste...0x60085700 SIP     Waiting for PDU on 
udp$192.168.0.222:5060<if=udp$192.168.0.21:5060>
2013/05/05 21:09:32.947 Opal Liste...0x60085700 SIP     PDU received: 
rem=udp$192.168.0.222:5060,local=udp$192.168.0.21:5060,if=192.168.0.21%eth0
SIP/2.0 200 OK
CSeq: 2 INVITE
Via: SIP/2.0/UDP 
192.168.0.21:5060;branch=z9hG4bK729024f8-24b4-e211-9edb-6c626db69c09;rport=5060
User-Agent: AVM FRITZ!Box Fon WLAN 7390 (UI) 84.05.50 (Dec 11 2012)
From: "root" <sip:[email protected]>;tag=7aa61ff8-24b4-e211-9edb-6c626db69c09
Call-ID: dab91ff8-24b4-e211-9edb-6c626db69c09@ceramic
Supported: 100rel,replaces,timer
To: <sip:[email protected]>;tag=4E5A5E865B23136B
Contact: <sip:[email protected]>
Accept: application/sdp, multipart/mixed
Allow: 
INVITE,ACK,OPTIONS,CANCEL,BYE,UPDATE,PRACK,INFO,SUBSCRIBE,NOTIFY,REFER,MESSAGE,PUBLISH
Allow-Events: telephone-event,refer
Content-Type: application/sdp
Content-Length: 353
Accept-Encoding: identity

v=0
o=user 4295806 4295806 IN IP4 192.168.0.222
s=T38Modem/2.0.0
c=IN IP4 192.168.0.222
t=0 0
m=audio 7078 RTP/AVP 102 103 104 0 8 101
a=rtpmap:102 G726-40/8000/1
a=rtpmap:103 G726-32/8000/1
a=rtpmap:104 G726-24/8000/1
a=rtpmap:0 PCMU/8000/1
a=rtpmap:8 PCMA/8000/1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
a=rtcp:7079

2013/05/05 21:09:32.948 Opal Liste...0x60085700 SIP     Queueing PDU "2 INVITE 
<200>", transaction=z9hG4bK729024f8-24b4-e211-9edb-6c626db69c09, 
token=7aa61ff8-24b4-e211-9edb-6c626db69c09
2013/05/05 21:09:32.948 Opal Liste...0x60085700 Opal    Transport clean up on 
termination
2013/05/05 21:09:32.948 Opal Liste...0x60085700 Opal    Transport Close
2013/05/05 21:09:32.948 Opal Liste...0x60085700 Opal    Deleted transport 
udp$192.168.0.222:5060<if=udp$192.168.0.21:5060>
2013/05/05 21:09:32.948         Pool:0x55ec8700 SIP     Handling PDU "2 INVITE 
<200>" for transaction=z9hG4bK729024f8-24b4-e211-9edb-6c626db69c09
2013/05/05 21:09:32.948         Pool:0x55ec8700 SIP     Route set is 
2013/05/05 21:09:32.949         Pool:0x55ec8700 SIP     Set Request URI to 
sip:[email protected]
2013/05/05 21:09:32.949         Pool:0x55ec8700 OpalUDP Setting interface to 
192.168.0.21%eth0
2013/05/05 21:09:32.950         Pool:0x55ec8700 SIP     Product Info: 
name="AVM", version="", vendor="", comments="FRITZ!Box Fon WLAN 7390 (UI) 
84.05.50 (Dec 11 2012)"
2013/05/05 21:09:32.950         Pool:0x55ec8700 SDP     Parsed connection 
address udp$192.168.0.222
2013/05/05 21:09:32.950         Pool:0x55ec8700 SDP     Parsed connection 
address udp$192.168.0.222
2013/05/05 21:09:32.950         Pool:0x55ec8700 SDP     Media session port=7078
2013/05/05 21:09:32.951         Pool:0x55ec8700 SDP     Unknown media attribute 
rtcp
2013/05/05 21:09:32.951         Pool:0x55ec8700 SDP     Parsed final media 
session with 6 'audio' formats
2013/05/05 21:09:32.951         Pool:0x55ec8700 SIP     RTP payload type 
G726-40 matched to codec G.726-40k
2013/05/05 21:09:32.952         Pool:0x55ec8700 SIP     RTP payload type 
G726-32 matched to codec G.726-32k
2013/05/05 21:09:32.952         Pool:0x55ec8700 SIP     RTP payload type 
G726-24 matched to codec G.726-24k
2013/05/05 21:09:32.952         Pool:0x55ec8700 SIP     RTP payload type PCMU 
matched to codec G.711-uLaw-64k
2013/05/05 21:09:32.953         Pool:0x55ec8700 SIP     RTP payload type PCMA 
matched to codec G.711-ALaw-64k
2013/05/05 21:09:32.953         Pool:0x55ec8700 SIP     RTP payload type 
telephone-event matched to codec UserInput/RFC2833
2013/05/05 21:09:32.953         Pool:0x55ec8700 SIP     Remote dialog address 
from target: sip:[email protected]
2013/05/05 21:09:32.953         Pool:0x55ec8700 SIP     Transaction remote 
address changed to udp$192.168.0.222:5060
2013/05/05 21:09:32.956         Pool:0x55ec8700 HTTP    Adding authentication 
information
2013/05/05 21:09:32.956         Pool:0x55ec8700 SIP     Sending PDU (636 bytes) 
to: rem=udp$192.168.0.222:5060,local=udp$192.168.0.21:5060,if=192.168.0.21%eth0
ACK sip:[email protected] SIP/2.0
CSeq: 2 ACK
Via: SIP/2.0/UDP 
192.168.0.21:5060;branch=z9hG4bK6af6e9fc-24b4-e211-9edb-6c626db69c09;rport
Authorization: Digest username="620", realm="fritz.box", 
nonce="66B56F740C61018A", 
uri="sip:[email protected]", algorithm=MD5, 
response="c2853a0560184fc257d6175093bfef35"
From: "root" <sip:[email protected]>;tag=7aa61ff8-24b4-e211-9edb-6c626db69c09
Call-ID: dab91ff8-24b4-e211-9edb-6c626db69c09@ceramic
To: <sip:[email protected]>;tag=4E5A5E865B23136B
Contact: "root" <sip:[email protected]:5060>
Content-Length: 0
Max-Forwards: 70


2013/05/05 21:09:32.957         Pool:0x55ec8700 OpalUDP Setting interface to 
192.168.0.21%eth0
2013/05/05 21:09:32.957         Pool:0x55ec8700 SIP     INVITE transaction 
id=z9hG4bK729024f8-24b4-e211-9edb-6c626db69c09 completing.
2013/05/05 21:09:32.957         Pool:0x55ec8700 SIP     Received INVITE OK 
response for INVITE
2013/05/05 21:09:32.958         Pool:0x55ec8700 SIP     Processing received SDP 
media description for audio
2013/05/05 21:09:32.958         Pool:0x55ec8700 Call    IsMediaBypassPossible 
Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09] session 1
2013/05/05 21:09:32.958         Pool:0x55ec8700 OpalMan IsMediaBypassPossible: 
session 1
2013/05/05 21:09:32.958         Pool:0x55ec8700 OpalCon IsMediaBypassPossible: 
default returns false
2013/05/05 21:09:32.958         Pool:0x55ec8700 RTP     Found existing RTP 
session 1
2013/05/05 21:09:32.959         Pool:0x55ec8700 RTP     Found existing media 
session 1
2013/05/05 21:09:32.959         Pool:0x55ec8700 MediaFormat     Merging 
G.711-uLaw-64k into PCM-16
2013/05/05 21:09:32.959         Pool:0x55ec8700 MediaFormat     Changed media 
option "Max Bit Rate" from "128000" to "64000"
2013/05/05 21:09:32.959         Pool:0x55ec8700 Media   Media format updated on 
AudioModemMediaStream-Source-PCM-16
2013/05/05 21:09:32.959         Pool:0x55ec8700 MediaFormat     Merging 
G.711-uLaw-64k into G.711-uLaw-64k
2013/05/05 21:09:32.959         Pool:0x55ec8700 MediaFormat     Merging 
G.711-uLaw-64k into G.711-uLaw-64k
2013/05/05 21:09:32.960         Pool:0x55ec8700 Media   Media format updated on 
OpalRTPMediaStream-Sink-G.711-uLaw-64k
2013/05/05 21:09:32.960         Pool:0x55ec8700 Patch   Updated Sink: 
format=G.711-uLaw-64k ok=1
2013/05/05 21:09:32.960         Pool:0x55ec8700 SIP     INVITE change needs to 
resume stream OpalRTPMediaStream-Sink-G.711-uLaw-64k
2013/05/05 21:09:32.960         Pool:0x55ec8700 MediaFormat     Merging 
G.711-uLaw-64k into G.711-uLaw-64k
2013/05/05 21:09:32.960         Pool:0x55ec8700 Media   Media format updated on 
OpalRTPMediaStream-Source-G.711-uLaw-64k
2013/05/05 21:09:32.960         Pool:0x55ec8700 MediaFormat     Merging 
G.711-uLaw-64k into G.711-uLaw-64k
2013/05/05 21:09:32.960         Pool:0x55ec8700 MediaFormat     Changing 
payload type from [pt=102] to PCMU in G.711-uLaw-64k
2013/05/05 21:09:32.960         Pool:0x55ec8700 MediaFormat     Merging 
G.711-uLaw-64k into PCM-16
2013/05/05 21:09:32.961         Pool:0x55ec8700 MediaFormat     Changed media 
option "Max Bit Rate" from "128000" to "64000"
2013/05/05 21:09:32.961         Pool:0x55ec8700 Media   Media format updated on 
AudioModemMediaStream-Sink-PCM-16
2013/05/05 21:09:32.961         Pool:0x55ec8700 Patch   Updated Sink: 
format=G.711-uLaw-64k ok=1
2013/05/05 21:09:32.961         Pool:0x55ec8700 SIP     INVITE change needs to 
resume stream OpalRTPMediaStream-Source-G.711-uLaw-64k
2013/05/05 21:09:32.961         Pool:0x55ec8700 MediaFormat     Merging 
UserInput/RFC2833 into UserInput/RFC2833
2013/05/05 21:09:32.961         Pool:0x55ec8700 RFC2833 Set tx pt=[pt=101], 
events="0-15" for UserInput/RFC2833
2013/05/05 21:09:32.961         Pool:0x55ec8700 RFC2833 Set tx pt=[pt=128], 
events="" for NamedSignalEvent
2013/05/05 21:09:32.961         Pool:0x55ec8700 SIP     Queueing re-INVITE to 
resolve multiple codecs in answer
2013/05/05 21:09:32.962         Pool:0x55ec8700 SIP     Remote dialog address 
from target: sip:[email protected]
2013/05/05 21:09:32.964         Pool:0x55ec8700 SIP     INVITE transaction 
id=z9hG4bK6635ebfc-24b4-e211-9edb-6c626db69c09 created.
2013/05/05 21:09:32.964         Pool:0x55ec8700 SIP     Creating INVITE request
2013/05/05 21:09:32.964         Pool:0x55ec8700 SIP     Offering only current 
media streams in Re-INVITE
2013/05/05 21:09:32.964         Pool:0x55ec8700 SIP     Offering media type 
audio in SDP
2013/05/05 21:09:32.964         Pool:0x55ec8700 Call    IsMediaBypassPossible 
Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09] session 1
2013/05/05 21:09:32.964         Pool:0x55ec8700 OpalMan IsMediaBypassPossible: 
session 1
2013/05/05 21:09:32.964         Pool:0x55ec8700 OpalCon IsMediaBypassPossible: 
default returns false
2013/05/05 21:09:32.964         Pool:0x55ec8700 RTP     Found existing media 
session 1
2013/05/05 21:09:32.965         Pool:0x55ec8700 RTP     Found existing RTP 
session 1
2013/05/05 21:09:32.965         Pool:0x55ec8700 MediaFormat     Merging 
UserInput/RFC2833 into UserInput/RFC2833
2013/05/05 21:09:32.965         Pool:0x55ec8700 RFC2833 Set tx pt=[pt=101], 
events="0-16,32,36" for UserInput/RFC2833
2013/05/05 21:09:32.966         Pool:0x55ec8700 RFC2833 Set rx pt=[pt=101], 
events="0-16,32,36" for UserInput/RFC2833
2013/05/05 21:09:32.966         Pool:0x55ec8700 RFC2833 Set tx pt=[pt=128], 
events="" for NamedSignalEvent
2013/05/05 21:09:32.966         Pool:0x55ec8700 OpalCon SetPhase from 
AlertingPhase to ConnectedPhase for 
Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09]
2013/05/05 21:09:32.966         Pool:0x55ec8700 OpalCon OnConnected for 
Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09]
2013/05/05 21:09:32.966         Pool:0x55ec8700 OpalMan OnConnected 
Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09]
2013/05/05 21:09:32.966         Pool:0x55ec8700 Call    OnConnected 
Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09]
2013/05/05 21:09:32.966         Pool:0x55ec8700 ModemConnection::SetConnected 
Call[C3ff77e3a1]-EP<modem>[modem:/C3ff77e3a1/0]
2013/05/05 21:09:32.966         Pool:0x55ec8700 OpalCon SetConnected for 
Call[C3ff77e3a1]-EP<modem>[modem:/C3ff77e3a1/0]
2013/05/05 21:09:32.966         Pool:0x55ec8700 OpalCon SetPhase from 
AlertingPhase to ConnectedPhase for 
Call[C3ff77e3a1]-EP<modem>[modem:/C3ff77e3a1/0]
2013/05/05 21:09:32.966         Pool:0x55ec8700 OpalCon SetPhase from 
ConnectedPhase to EstablishedPhase for 
Call[C3ff77e3a1]-EP<modem>[modem:/C3ff77e3a1/0]
2013/05/05 21:09:32.966         Pool:0x55ec8700 ModemConnection::OnEstablished 
Call[C3ff77e3a1]-EP<modem>[modem:/C3ff77e3a1/0]
2013/05/05 21:09:32.966         Pool:0x55ec8700 ModemEngineBody::Request: 
stConnectWait request={
calltoken=modem:/C3ff77e3a1/0
command=established
}
2013/05/05 21:09:32.966         Pool:0x55ec8700 ttyT38-0 AudioEngine RecvOnIdle 
dtNone
2013/05/05 21:09:32.966         Pool:0x55ec8700 ModemEngineBody::SetCallState: 
off-hook cstEstablished stConnectWait
2013/05/05 21:09:32.966         Pool:0x55ec8700 ModemEngineBody::SetState: 
off-hook cstEstablished stConnectHandle.chConnected
2013/05/05 21:09:32.967         Pool:0x55ec8700 OpalCon OnEstablished 
Call[C3ff77e3a1]-EP<modem>[modem:/C3ff77e3a1/0]
2013/05/05 21:09:32.967 ttyT38-0(e...0x60107700 ModemEngineBody::SetSubState: 
off-hook cstEstablished stConnectHandle.chAudioEngineAttached
2013/05/05 21:09:32.967 ttyT38-0(e...0x60107700 ModemEngineBody::SetSubState: 
off-hook cstEstablished stConnectHandle.chTonePlayed
2013/05/05 21:09:32.967 ttyT38-0(e...0x60107700 ModemEngineBody::SetSubState: 
off-hook cstEstablished stConnectHandle.chConnectionEstablishDelay
2013/05/05 21:09:32.967         Pool:0x55ec8700 Media   Starting stream 
AudioModemMediaStream-Sink-PCM-16
2013/05/05 21:09:32.967         Pool:0x55ec8700 Media   Starting stream 
AudioModemMediaStream-Source-PCM-16
2013/05/05 21:09:32.967         Pool:0x55ec8700 OpalCon Media stream threads 
started for Call[C3ff77e3a1]-EP<modem>[modem:/C3ff77e3a1/0]
2013/05/05 21:09:32.967         Pool:0x55ec8700 Media   Starting stream 
OpalRTPMediaStream-Source-G.711-uLaw-64k
2013/05/05 21:09:32.967         Pool:0x55ec8700 Media   Starting stream 
OpalRTPMediaStream-Sink-G.711-uLaw-64k
2013/05/05 21:09:32.967         Pool:0x55ec8700 OpalCon Media stream threads 
started for Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09]
2013/05/05 21:09:32.967         Pool:0x55ec8700 OpalMan OnEstablished 
Call[C3ff77e3a1]-EP<modem>[modem:/C3ff77e3a1/0]
2013/05/05 21:09:32.967         Pool:0x55ec8700 Call    OnEstablished 
Call[C3ff77e3a1]-EP<modem>[modem:/C3ff77e3a1/0]
2013/05/05 21:09:32.967         Pool:0x55ec8700 Media   Starting stream 
AudioModemMediaStream-Sink-PCM-16
2013/05/05 21:09:32.967         Pool:0x55ec8700 Media   Starting stream 
AudioModemMediaStream-Source-PCM-16
2013/05/05 21:09:32.967         Pool:0x55ec8700 OpalCon Media stream threads 
started for Call[C3ff77e3a1]-EP<modem>[modem:/C3ff77e3a1/0]
2013/05/05 21:09:32.967         Pool:0x55ec8700 OpalCon SetPhase from 
ConnectedPhase to EstablishedPhase for 
Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09]
2013/05/05 21:09:32.968         Pool:0x55ec8700 OpalCon OnEstablished 
Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09]
2013/05/05 21:09:32.968         Pool:0x55ec8700 Media   Starting stream 
AudioModemMediaStream-Sink-PCM-16
2013/05/05 21:09:32.968         Pool:0x55ec8700 Media   Starting stream 
AudioModemMediaStream-Source-PCM-16
2013/05/05 21:09:32.968         Pool:0x55ec8700 OpalCon Media stream threads 
started for Call[C3ff77e3a1]-EP<modem>[modem:/C3ff77e3a1/0]
2013/05/05 21:09:32.968         Pool:0x55ec8700 Media   Starting stream 
OpalRTPMediaStream-Source-G.711-uLaw-64k
2013/05/05 21:09:32.968         Pool:0x55ec8700 Media   Starting stream 
OpalRTPMediaStream-Sink-G.711-uLaw-64k
2013/05/05 21:09:32.968         Pool:0x55ec8700 OpalCon Media stream threads 
started for Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09]
2013/05/05 21:09:32.968         Pool:0x55ec8700 OpalMan OnEstablished 
Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09]
2013/05/05 21:09:32.968         Pool:0x55ec8700 Call    OnEstablished 
Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09]
2013/05/05 21:09:32.968         Pool:0x55ec8700 Media   Starting stream 
OpalRTPMediaStream-Source-G.711-uLaw-64k
2013/05/05 21:09:32.968         Pool:0x55ec8700 Media   Starting stream 
OpalRTPMediaStream-Sink-G.711-uLaw-64k
2013/05/05 21:09:32.968         Pool:0x55ec8700 OpalCon Media stream threads 
started for Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09]
2013/05/05 21:09:32.968         Pool:0x55ec8700 Call    Established 
Call[C3ff77e3a1]
2013/05/05 21:09:32.968         Pool:0x55ec8700 HTTP    Adding authentication 
information
2013/05/05 21:09:32.968         Pool:0x55ec8700 SIP     Transaction remote 
address is udp$192.168.0.222:5060
2013/05/05 21:09:32.970         Pool:0x55ec8700 SIP     Sending PDU (1032 
bytes) to: 
rem=udp$192.168.0.222:5060,local=udp$192.168.0.21:5060,if=192.168.0.21%eth0
INVITE sip:[email protected] SIP/2.0
CSeq: 4 INVITE
Via: SIP/2.0/UDP 
192.168.0.21:5060;branch=z9hG4bK74ae31f8-24b4-e211-9edb-6c626db69c09;rport
User-Agent: T38Modem/2.0.0
Authorization: Digest username="620", realm="fritz.box", 
nonce="66B56F740C61018A", uri="sip:[email protected]", algorithm=MD5, 
response="c9be58d0c8de5a66ccf28b5457b12835"
From: "root" <sip:[email protected]>;tag=7aa61ff8-24b4-e211-9edb-6c626db69c09
Call-ID: dab91ff8-24b4-e211-9edb-6c626db69c09@ceramic
Supported: 100rel,replaces
Organization: Vyacheslav Frolov
To: <sip:[email protected]>;tag=4E5A5E865B23136B
Contact: "root" <sip:[email protected]:5060>
Allow: 
INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK
Content-Length: 229
Content-Type: application/sdp
Max-Forwards: 70

v=0
o=- 1367780964 3 IN IP4 192.168.0.21
s=T38Modem/2.0.0
c=IN IP4 192.168.0.21
t=0 0
m=audio 5000 RTP/AVP 0 101
a=sendrecv
a=rtpmap:0 PCMU/8000/1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16,32,36
a=maxptime:240

2013/05/05 21:09:32.970         Pool:0x55ec8700 OpalUDP Setting interface to 
192.168.0.21%eth0
2013/05/05 21:09:32.970         Pool:0x55ec8700 SIP     Transaction timers set: 
retry=0.500, completion=32.000
2013/05/05 21:09:32.971         Pool:0x55ec8700 SIP     INVITE transaction 
id=z9hG4bK729024f8-24b4-e211-9edb-6c626db69c09 completed.
2013/05/05 21:09:32.971         Pool:0x55ec8700 SIP     Handled PDU "2 INVITE 
<200>"
2013/05/05 21:09:32.971  Media Patch:0x55e87700 Patch   Consecutive mismatched 
payload type, was expecting PCMU, now using [pt=102]
2013/05/05 21:09:32.971  Media Patch:0x55e87700 MediaFormat     Merging 
G.711-uLaw-64k into G.711-uLaw-64k
2013/05/05 21:09:32.971  Media Patch:0x55e87700 MediaFormat     Changing 
payload type from PCMU to [pt=102] in G.711-uLaw-64k
2013/05/05 21:09:32.991 Opal Liste...0x60085700 OpalUDP Binding to interface: 
192.168.0.21:5060
2013/05/05 21:09:32.991 Opal Liste...0x60085700 SIP     Waiting for PDU on 
udp$192.168.0.222:5060<if=udp$192.168.0.21:5060>
2013/05/05 21:09:32.991 Opal Liste...0x60085700 SIP     PDU received: 
rem=udp$192.168.0.222:5060,local=udp$192.168.0.21:5060,if=192.168.0.21%eth0
SIP/2.0 200 OK
CSeq: 4 INVITE
Via: SIP/2.0/UDP 
192.168.0.21:5060;branch=z9hG4bK74ae31f8-24b4-e211-9edb-6c626db69c09;rport=5060
User-Agent: AVM FRITZ!Box Fon WLAN 7390 (UI) 84.05.50 (Dec 11 2012)
From: "root" <sip:[email protected]>;tag=7aa61ff8-24b4-e211-9edb-6c626db69c09
Call-ID: dab91ff8-24b4-e211-9edb-6c626db69c09@ceramic
Supported: 100rel,replaces,timer
To: <sip:[email protected]>;tag=4E5A5E865B23136B
Contact: <sip:[email protected]>
Accept: application/sdp, multipart/mixed
Allow: 
INVITE,ACK,OPTIONS,CANCEL,BYE,UPDATE,PRACK,INFO,SUBSCRIBE,NOTIFY,REFER,MESSAGE,PUBLISH
Allow-Events: telephone-event,refer
Content-Type: application/sdp
Content-Length: 228
Accept-Encoding: identity

v=0
o=user 4295806 4295807 IN IP4 192.168.0.222
s=T38Modem/2.0.0
c=IN IP4 192.168.0.222
t=0 0
m=audio 7078 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000/1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
a=rtcp:7079

2013/05/05 21:09:32.992 Opal Liste...0x60085700 SIP     Queueing PDU "4 INVITE 
<200>", transaction=z9hG4bK74ae31f8-24b4-e211-9edb-6c626db69c09, 
token=7aa61ff8-24b4-e211-9edb-6c626db69c09
2013/05/05 21:09:32.992 Opal Liste...0x60085700 Opal    Transport clean up on 
termination
2013/05/05 21:09:32.992 Opal Liste...0x60085700 Opal    Transport Close
2013/05/05 21:09:32.992 Opal Liste...0x60085700 Opal    Deleted transport 
udp$192.168.0.222:5060<if=udp$192.168.0.21:5060>
2013/05/05 21:09:32.993         Pool:0x55ec8700 SIP     Handling PDU "4 INVITE 
<200>" for transaction=z9hG4bK74ae31f8-24b4-e211-9edb-6c626db69c09
2013/05/05 21:09:32.993         Pool:0x55ec8700 SIP     Route set is 
2013/05/05 21:09:32.993         Pool:0x55ec8700 SIP     Set Request URI to 
sip:[email protected]
2013/05/05 21:09:32.994         Pool:0x55ec8700 SIP     Remote dialog address 
from target: sip:[email protected]
2013/05/05 21:09:32.994         Pool:0x55ec8700 SIP     Transaction remote 
address changed to udp$192.168.0.222:5060
2013/05/05 21:09:32.996         Pool:0x55ec8700 HTTP    Adding authentication 
information
2013/05/05 21:09:32.997         Pool:0x55ec8700 SIP     Sending PDU (636 bytes) 
to: rem=udp$192.168.0.222:5060,local=udp$192.168.0.21:5060,if=192.168.0.21%eth0
ACK sip:[email protected] SIP/2.0
CSeq: 4 ACK
Via: SIP/2.0/UDP 
192.168.0.21:5060;branch=z9hG4bKa22bf0fc-24b4-e211-9edb-6c626db69c09;rport
Authorization: Digest username="620", realm="fritz.box", 
nonce="66B56F740C61018A", 
uri="sip:[email protected]", algorithm=MD5, 
response="c2853a0560184fc257d6175093bfef35"
From: "root" <sip:[email protected]>;tag=7aa61ff8-24b4-e211-9edb-6c626db69c09
Call-ID: dab91ff8-24b4-e211-9edb-6c626db69c09@ceramic
To: <sip:[email protected]>;tag=4E5A5E865B23136B
Contact: "root" <sip:[email protected]:5060>
Content-Length: 0
Max-Forwards: 70


2013/05/05 21:09:32.997         Pool:0x55ec8700 OpalUDP Setting interface to 
192.168.0.21%eth0
2013/05/05 21:09:32.998         Pool:0x55ec8700 SIP     INVITE transaction 
id=z9hG4bK74ae31f8-24b4-e211-9edb-6c626db69c09 completing.
2013/05/05 21:09:32.998         Pool:0x55ec8700 SIP     Received INVITE OK 
response for INVITE
2013/05/05 21:09:32.999         Pool:0x55ec8700 SDP     Parsed connection 
address udp$192.168.0.222
2013/05/05 21:09:32.999         Pool:0x55ec8700 SDP     Parsed connection 
address udp$192.168.0.222
2013/05/05 21:09:32.999         Pool:0x55ec8700 SDP     Media session port=7078
2013/05/05 21:09:32.999         Pool:0x55ec8700 SDP     Unknown media attribute 
rtcp
2013/05/05 21:09:32.999         Pool:0x55ec8700 SDP     Parsed final media 
session with 2 'audio' formats
2013/05/05 21:09:33.000         Pool:0x55ec8700 SIP     RTP payload type PCMU 
matched to codec G.711-uLaw-64k
2013/05/05 21:09:33.000         Pool:0x55ec8700 SIP     RTP payload type 
telephone-event matched to codec UserInput/RFC2833
2013/05/05 21:09:33.000         Pool:0x55ec8700 SIP     Processing received SDP 
media description for audio
2013/05/05 21:09:33.000         Pool:0x55ec8700 Call    IsMediaBypassPossible 
Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09] session 1
2013/05/05 21:09:33.000         Pool:0x55ec8700 OpalMan IsMediaBypassPossible: 
session 1
2013/05/05 21:09:33.000         Pool:0x55ec8700 OpalCon IsMediaBypassPossible: 
default returns false
2013/05/05 21:09:33.000         Pool:0x55ec8700 RTP     Found existing RTP 
session 1
2013/05/05 21:09:33.001         Pool:0x55ec8700 RTP     Found existing media 
session 1
2013/05/05 21:09:33.001         Pool:0x55ec8700 MediaFormat     Merging 
G.711-uLaw-64k into PCM-16
2013/05/05 21:09:33.001         Pool:0x55ec8700 Media   Media format updated on 
AudioModemMediaStream-Source-PCM-16
2013/05/05 21:09:33.001         Pool:0x55ec8700 MediaFormat     Merging 
G.711-uLaw-64k into G.711-uLaw-64k
2013/05/05 21:09:33.002         Pool:0x55ec8700 MediaFormat     Merging 
G.711-uLaw-64k into G.711-uLaw-64k
2013/05/05 21:09:33.002         Pool:0x55ec8700 Media   Media format updated on 
OpalRTPMediaStream-Sink-G.711-uLaw-64k
2013/05/05 21:09:33.002         Pool:0x55ec8700 Patch   Updated Sink: 
format=G.711-uLaw-64k ok=1
2013/05/05 21:09:33.002         Pool:0x55ec8700 SIP     INVITE change needs to 
resume stream OpalRTPMediaStream-Sink-G.711-uLaw-64k
2013/05/05 21:09:33.002         Pool:0x55ec8700 MediaFormat     Merging 
G.711-uLaw-64k into G.711-uLaw-64k
2013/05/05 21:09:33.002         Pool:0x55ec8700 Media   Media format updated on 
OpalRTPMediaStream-Source-G.711-uLaw-64k
2013/05/05 21:09:33.002         Pool:0x55ec8700 MediaFormat     Merging 
G.711-uLaw-64k into G.711-uLaw-64k
2013/05/05 21:09:33.002         Pool:0x55ec8700 MediaFormat     Changing 
payload type from [pt=102] to PCMU in G.711-uLaw-64k
2013/05/05 21:09:33.003         Pool:0x55ec8700 MediaFormat     Merging 
G.711-uLaw-64k into PCM-16
2013/05/05 21:09:33.003         Pool:0x55ec8700 Media   Media format updated on 
AudioModemMediaStream-Sink-PCM-16
2013/05/05 21:09:33.003         Pool:0x55ec8700 Patch   Updated Sink: 
format=G.711-uLaw-64k ok=1
2013/05/05 21:09:33.003         Pool:0x55ec8700 SIP     INVITE change needs to 
resume stream OpalRTPMediaStream-Source-G.711-uLaw-64k
2013/05/05 21:09:33.003         Pool:0x55ec8700 MediaFormat     Merging 
UserInput/RFC2833 into UserInput/RFC2833
2013/05/05 21:09:33.003         Pool:0x55ec8700 RFC2833 Set tx pt=[pt=101], 
events="0-15" for UserInput/RFC2833
2013/05/05 21:09:33.003         Pool:0x55ec8700 RFC2833 Set tx pt=[pt=128], 
events="" for NamedSignalEvent
2013/05/05 21:09:33.003         Pool:0x55ec8700 Media   Starting stream 
AudioModemMediaStream-Sink-PCM-16
2013/05/05 21:09:33.004         Pool:0x55ec8700 Media   Starting stream 
AudioModemMediaStream-Source-PCM-16
2013/05/05 21:09:33.004         Pool:0x55ec8700 OpalCon Media stream threads 
started for Call[C3ff77e3a1]-EP<modem>[modem:/C3ff77e3a1/0]
2013/05/05 21:09:33.004         Pool:0x55ec8700 Media   Starting stream 
OpalRTPMediaStream-Source-G.711-uLaw-64k
2013/05/05 21:09:33.004         Pool:0x55ec8700 Media   Starting stream 
OpalRTPMediaStream-Sink-G.711-uLaw-64k
2013/05/05 21:09:33.004         Pool:0x55ec8700 OpalCon Media stream threads 
started for Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09]
2013/05/05 21:09:33.004         Pool:0x55ec8700 HTTP    Adding authentication 
information
2013/05/05 21:09:33.004         Pool:0x55ec8700 SIP     Transaction remote 
address is udp$192.168.0.222:5060
2013/05/05 21:09:33.005         Pool:0x55ec8700 SIP     Sending PDU (1078 
bytes) to: 
rem=udp$192.168.0.222:5060,local=udp$192.168.0.21:5060,if=192.168.0.21%eth0
INVITE sip:[email protected] SIP/2.0
CSeq: 5 INVITE
Via: SIP/2.0/UDP 
192.168.0.21:5060;branch=z9hG4bK6635ebfc-24b4-e211-9edb-6c626db69c09;rport
User-Agent: T38Modem/2.0.0
Authorization: Digest username="620", realm="fritz.box", 
nonce="66B56F740C61018A", 
uri="sip:[email protected]", algorithm=MD5, 
response="d5f21e243d42cd10181601ee557ee8fc"
From: "root" <sip:[email protected]>;tag=7aa61ff8-24b4-e211-9edb-6c626db69c09
Call-ID: dab91ff8-24b4-e211-9edb-6c626db69c09@ceramic
Supported: 100rel,replaces
Organization: Vyacheslav Frolov
To: <sip:[email protected]>;tag=4E5A5E865B23136B
Contact: "root" <sip:[email protected]:5060>
Allow: 
INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK
Content-Length: 229
Content-Type: application/sdp
Max-Forwards: 70

v=0
o=- 1367780964 4 IN IP4 192.168.0.21
s=T38Modem/2.0.0
c=IN IP4 192.168.0.21
t=0 0
m=audio 5000 RTP/AVP 0 101
a=sendrecv
a=rtpmap:0 PCMU/8000/1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16,32,36
a=maxptime:240

2013/05/05 21:09:33.006         Pool:0x55ec8700 OpalUDP Setting interface to 
192.168.0.21%eth0
2013/05/05 21:09:33.006         Pool:0x55ec8700 SIP     Transaction timers set: 
retry=0.500, completion=32.000
2013/05/05 21:09:33.006         Pool:0x55ec8700 SIP     INVITE transaction 
id=z9hG4bK74ae31f8-24b4-e211-9edb-6c626db69c09 completed.
2013/05/05 21:09:33.006         Pool:0x55ec8700 SIP     Handled PDU "4 INVITE 
<200>"
2013/05/05 21:09:33.017  Media Patch:0x55e46700 RTP     Session 1, transmit 
statistics:  packets=401 octets=64160 avgTime=20 maxTime=30 minTime=11
2013/05/05 21:09:33.025 Opal Liste...0x60085700 OpalUDP Binding to interface: 
192.168.0.21:5060
2013/05/05 21:09:33.025 Opal Liste...0x60085700 SIP     Waiting for PDU on 
udp$192.168.0.222:5060<if=udp$192.168.0.21:5060>
2013/05/05 21:09:33.026 Opal Liste...0x60085700 SIP     PDU received: 
rem=udp$192.168.0.222:5060,local=udp$192.168.0.21:5060,if=192.168.0.21%eth0
SIP/2.0 200 OK
CSeq: 5 INVITE
Via: SIP/2.0/UDP 
192.168.0.21:5060;branch=z9hG4bK6635ebfc-24b4-e211-9edb-6c626db69c09;rport=5060
User-Agent: AVM FRITZ!Box Fon WLAN 7390 (UI) 84.05.50 (Dec 11 2012)
From: "root" <sip:[email protected]>;tag=7aa61ff8-24b4-e211-9edb-6c626db69c09
Call-ID: dab91ff8-24b4-e211-9edb-6c626db69c09@ceramic
Supported: 100rel,replaces,timer
To: <sip:[email protected]>;tag=4E5A5E865B23136B
Contact: <sip:[email protected]>
Accept: application/sdp, multipart/mixed
Allow: 
INVITE,ACK,OPTIONS,CANCEL,BYE,UPDATE,PRACK,INFO,SUBSCRIBE,NOTIFY,REFER,MESSAGE,PUBLISH
Allow-Events: telephone-event,refer
Content-Type: application/sdp
Content-Length: 228
Accept-Encoding: identity

v=0
o=user 4295806 4295807 IN IP4 192.168.0.222
s=T38Modem/2.0.0
c=IN IP4 192.168.0.222
t=0 0
m=audio 7078 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000/1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
a=rtcp:7079

2013/05/05 21:09:33.026 Opal Liste...0x60085700 SIP     Queueing PDU "5 INVITE 
<200>", transaction=z9hG4bK6635ebfc-24b4-e211-9edb-6c626db69c09, 
token=7aa61ff8-24b4-e211-9edb-6c626db69c09
2013/05/05 21:09:33.026 Opal Liste...0x60085700 Opal    Transport clean up on 
termination
2013/05/05 21:09:33.026 Opal Liste...0x60085700 Opal    Transport Close
2013/05/05 21:09:33.027 Opal Liste...0x60085700 Opal    Deleted transport 
udp$192.168.0.222:5060<if=udp$192.168.0.21:5060>
2013/05/05 21:09:33.027         Pool:0x55ec8700 SIP     Handling PDU "5 INVITE 
<200>" for transaction=z9hG4bK6635ebfc-24b4-e211-9edb-6c626db69c09
2013/05/05 21:09:33.027         Pool:0x55ec8700 SIP     Route set is 
2013/05/05 21:09:33.027         Pool:0x55ec8700 SIP     Set Request URI to 
sip:[email protected]
2013/05/05 21:09:33.028         Pool:0x55ec8700 SIP     Remote dialog address 
from target: sip:[email protected]
2013/05/05 21:09:33.028         Pool:0x55ec8700 SIP     Transaction remote 
address changed to udp$192.168.0.222:5060
2013/05/05 21:09:33.031         Pool:0x55ec8700 HTTP    Adding authentication 
information
2013/05/05 21:09:33.031         Pool:0x55ec8700 SIP     Sending PDU (636 bytes) 
to: rem=udp$192.168.0.222:5060,local=udp$192.168.0.21:5060,if=192.168.0.21%eth0
ACK sip:[email protected] SIP/2.0
CSeq: 5 ACK
Via: SIP/2.0/UDP 
192.168.0.21:5060;branch=z9hG4bKdc64f5fc-24b4-e211-9edb-6c626db69c09;rport
Authorization: Digest username="620", realm="fritz.box", 
nonce="66B56F740C61018A", 
uri="sip:[email protected]", algorithm=MD5, 
response="c2853a0560184fc257d6175093bfef35"
From: "root" <sip:[email protected]>;tag=7aa61ff8-24b4-e211-9edb-6c626db69c09
Call-ID: dab91ff8-24b4-e211-9edb-6c626db69c09@ceramic
To: <sip:[email protected]>;tag=4E5A5E865B23136B
Contact: "root" <sip:[email protected]:5060>
Content-Length: 0
Max-Forwards: 70


2013/05/05 21:09:33.032         Pool:0x55ec8700 OpalUDP Setting interface to 
192.168.0.21%eth0
2013/05/05 21:09:33.032         Pool:0x55ec8700 SIP     INVITE transaction 
id=z9hG4bK6635ebfc-24b4-e211-9edb-6c626db69c09 completing.
2013/05/05 21:09:33.032         Pool:0x55ec8700 SIP     Received INVITE OK 
response for INVITE
2013/05/05 21:09:33.033         Pool:0x55ec8700 SDP     Parsed connection 
address udp$192.168.0.222
2013/05/05 21:09:33.033         Pool:0x55ec8700 SDP     Parsed connection 
address udp$192.168.0.222
2013/05/05 21:09:33.033         Pool:0x55ec8700 SDP     Media session port=7078
2013/05/05 21:09:33.033         Pool:0x55ec8700 SDP     Unknown media attribute 
rtcp
2013/05/05 21:09:33.033         Pool:0x55ec8700 SDP     Parsed final media 
session with 2 'audio' formats
2013/05/05 21:09:33.034         Pool:0x55ec8700 SIP     RTP payload type PCMU 
matched to codec G.711-uLaw-64k
2013/05/05 21:09:33.034         Pool:0x55ec8700 SIP     RTP payload type 
telephone-event matched to codec UserInput/RFC2833
2013/05/05 21:09:33.034         Pool:0x55ec8700 SIP     Processing received SDP 
media description for audio
2013/05/05 21:09:33.034         Pool:0x55ec8700 Call    IsMediaBypassPossible 
Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09] session 1
2013/05/05 21:09:33.034         Pool:0x55ec8700 OpalMan IsMediaBypassPossible: 
session 1
2013/05/05 21:09:33.034         Pool:0x55ec8700 OpalCon IsMediaBypassPossible: 
default returns false
2013/05/05 21:09:33.034         Pool:0x55ec8700 RTP     Found existing RTP 
session 1
2013/05/05 21:09:33.035         Pool:0x55ec8700 RTP     Found existing media 
session 1
2013/05/05 21:09:33.035         Pool:0x55ec8700 MediaFormat     Merging 
G.711-uLaw-64k into PCM-16
2013/05/05 21:09:33.035         Pool:0x55ec8700 Media   Media format updated on 
AudioModemMediaStream-Source-PCM-16
2013/05/05 21:09:33.035         Pool:0x55ec8700 MediaFormat     Merging 
G.711-uLaw-64k into G.711-uLaw-64k
2013/05/05 21:09:33.035         Pool:0x55ec8700 MediaFormat     Merging 
G.711-uLaw-64k into G.711-uLaw-64k
2013/05/05 21:09:33.036         Pool:0x55ec8700 Media   Media format updated on 
OpalRTPMediaStream-Sink-G.711-uLaw-64k
2013/05/05 21:09:33.036         Pool:0x55ec8700 Patch   Updated Sink: 
format=G.711-uLaw-64k ok=1
2013/05/05 21:09:33.036         Pool:0x55ec8700 SIP     INVITE change needs to 
resume stream OpalRTPMediaStream-Sink-G.711-uLaw-64k
2013/05/05 21:09:33.036         Pool:0x55ec8700 MediaFormat     Merging 
G.711-uLaw-64k into G.711-uLaw-64k
2013/05/05 21:09:33.036         Pool:0x55ec8700 Media   Media format updated on 
OpalRTPMediaStream-Source-G.711-uLaw-64k
2013/05/05 21:09:33.036         Pool:0x55ec8700 MediaFormat     Merging 
G.711-uLaw-64k into G.711-uLaw-64k
2013/05/05 21:09:33.036         Pool:0x55ec8700 MediaFormat     Merging 
G.711-uLaw-64k into PCM-16
2013/05/05 21:09:33.037         Pool:0x55ec8700 Media   Media format updated on 
AudioModemMediaStream-Sink-PCM-16
2013/05/05 21:09:33.037         Pool:0x55ec8700 Patch   Updated Sink: 
format=G.711-uLaw-64k ok=1
2013/05/05 21:09:33.037         Pool:0x55ec8700 SIP     INVITE change needs to 
resume stream OpalRTPMediaStream-Source-G.711-uLaw-64k
2013/05/05 21:09:33.037         Pool:0x55ec8700 MediaFormat     Merging 
UserInput/RFC2833 into UserInput/RFC2833
2013/05/05 21:09:33.037         Pool:0x55ec8700 RFC2833 Set tx pt=[pt=101], 
events="0-15" for UserInput/RFC2833
2013/05/05 21:09:33.037         Pool:0x55ec8700 RFC2833 Set tx pt=[pt=128], 
events="" for NamedSignalEvent
2013/05/05 21:09:33.037         Pool:0x55ec8700 Media   Starting stream 
AudioModemMediaStream-Sink-PCM-16
2013/05/05 21:09:33.037         Pool:0x55ec8700 Media   Starting stream 
AudioModemMediaStream-Source-PCM-16
2013/05/05 21:09:33.037         Pool:0x55ec8700 OpalCon Media stream threads 
started for Call[C3ff77e3a1]-EP<modem>[modem:/C3ff77e3a1/0]
2013/05/05 21:09:33.038         Pool:0x55ec8700 Media   Starting stream 
OpalRTPMediaStream-Source-G.711-uLaw-64k
2013/05/05 21:09:33.038         Pool:0x55ec8700 Media   Starting stream 
OpalRTPMediaStream-Sink-G.711-uLaw-64k
2013/05/05 21:09:33.038         Pool:0x55ec8700 OpalCon Media stream threads 
started for Call[C3ff77e3a1]-EP<sip>[7aa61ff8-24b4-e211-9edb-6c626db69c09]
2013/05/05 21:09:33.038         Pool:0x55ec8700 PWLib   Assertion fail: Null 
pointer reference, file /usr/include/ptlib/safecoll.h, line 851, Error=107

-----BEGIN PGP SIGNATURE-----
Version: GnuPG v1.4.12 (GNU/Linux)

iEYEARECAAYFAlGGtiAACgkQqVj5VlP9Od1XtwCgpvGKu+dDX0Y6fysf5mq/3xBB
zLEAn1LI1G8XMjhvliNVGTNHLGaI7k2x
=3/EA
-----END PGP SIGNATURE-----

--- End Message ---
--- Begin Message --- 
Version: 2.0.0-4+rm

Dear submitter,

as the package t38modem has just been removed from the Debian archive
unstable we hereby close the associated bug reports.  We are sorry
that we couldn't deal with your issue properly.

For details on the removal, please see https://bugs.debian.org/911596

The version of this package that was in Debian prior to this removal
can still be found using http://snapshot.debian.org/.

This message was generated automatically; if you believe that there is
a problem with it please contact the archive administrators by mailing
[email protected].

Debian distribution maintenance software
pp.
Scott Kitterman (the ftpmaster behind the curtain)

--- End Message ---

Reply via email to