 HylaFAX The world's
most advanced open source fax server
|
[Date Prev][Date Next][Thread Prev][Thread Next] [Date Index] [Thread Index]
Re: [hylafax-users] t38modem & hylafax issue
- To: Robert Branham <bbranham@xxxxxxxxxxxx>
- Subject: Re: [hylafax-users] t38modem & hylafax issue
- From: John Park <john@xxxxxxxxxxxxxxx>
- Cc: hylafax-users@xxxxxxxxxxx
- Date: Mon, 13 Dec 2010 13:16:41 +1100
Bugger, my email has been truncated. Is there any way to attach a log file to the mailing list?
I'll reduce the tracing level and resend the log.
On 13 December 2010 13:14, John Park <john@xxxxxxxxxxxxxxx> wrote:
My current issue is "NO CARRIER" when attempting an outbound fax.
My SIP settings for iiNet's iiNetPhone are:My t38modem command is:
t38modem -tttt -o /var/log/t38modem.log --sip-proxy <my_username>:<my_password>@sip.nsw.iinet.net.au --sip-register <my_username>@sip.nsw.iinet.net.au,<my_password>,<my_username>,iinetphone.iinet.net.au --no-h323 --ptty +/dev/ttyT38a,+/dev/ttyT38b,+/dev/ttyT38c --route 'modem:.*=sip:<dn>@sip.nsw.iinet.net.au' -u <my_username>
The call is being made to the destination and is being picked up. I can see this by looking at my VOIP call logs. The problem is that t38modem isn't detecting the fax tones and is reporting back "NO CARRIER". I have included output from my t38modem.log file and the hylafax log below. I have modified my config.ttyT38a to include a force t38 string:
ModemDialCmd: ATDF%s # user can override F by dial V
Output of my t38modem.log file:
2010/12/13 13:01:46.456 T38Modem Version 1.2.0 by Vyacheslav Frolov on Unix Linux (2.6.35-22-generic-pae-i686) with PTLib (v2.6.7) at 2010/12/13 13:01:46.456
2010/12/13 13:01:46.456 T38Modem T38Modem Version 1.2.0 (OPAL-3.6.8/3.6.8, PTLIB-2.6.7/2.6.7) on Unix Linux (2.6.35-22-generic-pae-i686)
2010/12/13 13:01:46.456 T38Modem Options: -tttt -o /var/log/t38modem.log --sip-proxy <my_username>:<my_password>@sip.nsw.iinet.net.au --sip-register <my_username>@sip.nsw.iinet.net.au,<my_password>,<my_username>,iinetphone.iinet.net.au --no-h323 --ptty +/dev/ttyT38a,+/dev/ttyT38b,+/dev/ttyT38c --route modem:.*=sip:<dn>@sip.nsw.iinet.net.au -u <my_username>
2010/12/13 13:01:46.456 T38Modem Config:
2010/12/13 13:01:46.456 T38Modem --no-h323=True
2010/12/13 13:01:46.456 T38Modem --sip-proxy=<my_username>:<my_password>@sip.nsw.iinet.net.au
2010/12/13 13:01:46.456 T38Modem --sip-register=<my_username>@sip.nsw.iinet.net.au,<my_password>,<my_username>,iinetphone.iinet.net.au
2010/12/13 13:01:46.456 T38Modem --ptty=+/dev/ttyT38a,+/dev/ttyT38b,+/dev/ttyT38c
2010/12/13 13:01:46.456 T38Modem --route=modem:.*=sip:<dn>@sip.nsw.iinet.net.au
2010/12/13 13:01:46.456 T38Modem --username=<my_username>
2010/12/13 13:01:46.456 T38Modem --trace=True
2010/12/13 13:01:46.456 T38Modem --output=/var/log/t38modem.log
2010/12/13 13:01:46.456 T38Modem Detecting V4L2 devices
2010/12/13 13:01:46.457 T38Modem Unable to detect v4l2 directory
2010/12/13 13:01:46.471 T38Modem PWLib File handle high water mark set: 9 Thread unblock pipe
2010/12/13 13:01:46.471 T38Modem PTLib Thread high water mark set: 3
2010/12/13 13:01:46.471 T38Modem OpalMan Created manager.
2010/12/13 13:01:46.471 T38Modem OpalMan Attached endpoint with prefix modem
2010/12/13 13:01:46.471 T38Modem OpalEP Created endpoint: modem
2010/12/13 13:01:46.471 T38Modem ModemEndPoint::ModemEndPoint
2010/12/13 13:01:46.471 T38Modem PWLib File handle high water mark set: 11 Thread unblock pipe
2010/12/13 13:01:46.471 T38Modem PseudoModemList::Append ttyT38a (0) OK
2010/12/13 13:01:46.471 T38Modem PTLib Thread high water mark set: 4
2010/12/13 13:01:46.471 T38Modem PWLib File handle high water mark set: 13 Thread unblock pipe
2010/12/13 13:01:46.471 T38Modem PseudoModemList::Append ttyT38b (1) OK
2010/12/13 13:01:46.471 T38Modem PTLib Thread high water mark set: 5
2010/12/13 13:01:46.471 T38Modem PWLib File handle high water mark set: 15 Thread unblock pipe
2010/12/13 13:01:46.472 T38Modem PseudoModemList::Append ttyT38c (2) OK
2010/12/13 13:01:46.472 T38Modem PTLib Thread high water mark set: 6
2010/12/13 13:01:46.472 T38Modem OpalMan Attached endpoint with prefix sip
2010/12/13 13:01:46.472 T38Modem OpalEP Created endpoint: sip
2010/12/13 13:01:46.472 T38Modem PWLib File handle high water mark set: 16 PUDPSocket
2010/12/13 13:01:46.472 T38Modem IfaceMon Initial interface list:2010/12/13 13:01:46.472 T38Modem PWLib File handle high water mark set: 17 Thread unblock pipe
127.0.0.1 [00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:01] <00-00-00-00-00-00> (lo)
10.11.12.22 [fe:80:00:00:00:00:00:00:02:0c:29:ff:fe:84:a8:55] <00-0C-29-84-A8-55> (eth0)
2010/12/13 13:01:46.472 T38Modem PTLib Thread high water mark set: 7
2010/12/13 13:01:46.472 T38Modem PWLib File handle high water mark set: 19 Thread unblock pipe
2010/12/13 13:01:46.472 T38Modem PTLib Thread high water mark set: 8
2010/12/13 13:01:46.473 T38Modem OpalMan Attached endpoint with prefix sips
2010/12/13 13:01:46.473 T38Modem SIP Created endpoint.
2010/12/13 13:01:46.473 T38Modem PWLib File handle high water mark set: 20 PUDPSocket
2010/12/13 13:01:46.473 T38Modem MonSock Created socket bundle for all interfaces.
2010/12/13 13:01:46.473 T38Modem PWLib File handle high water mark set: 21 PUDPSocket
2010/12/13 13:01:46.473 T38Modem MonSock Created bundled UDP socket 10.11.12.22:5060
2010/12/13 13:01:46.473 T38Modem PWLib File handle high water mark set: 23 Thread unblock pipe
2010/12/13 13:01:46.473 T38Modem PTLib Thread high water mark set: 9
2010/12/13 13:01:46.473 Opal Liste...0xb5bc3b70 Listen Started listening thread on udp$*:5060
2010/12/13 13:01:46.474 ttyT38a(b)...0xb5d08b70 RenameCurrentThread old ThreadName=PseudoModemPty:0xb5d08b70
2010/12/13 13:01:46.474 ttyT38a(b)...0xb5d08b70 Started for /dev/ttyT38a (accepts all)
2010/12/13 13:01:46.474 ttyT38a(b)...0xb5d08b70 ModemEndPoint::OnMyCallback command=addmodem extra=10
2010/12/13 13:01:46.474 ttyT38a(b)...0xb5d08b70 PseudoModemQ::Enqueue ttyT38a
2010/12/13 13:01:46.474 ttyT38a(b)...0xb5d08b70 ModemEndPoint::OnMyCallback request={
command=addmodem
response=confirm
modemtoken=ttyT38a
}
2010/12/13 13:01:46.474 ttyT38a(b)...0xb5d08b70 PseudoModemPty::OpenPty removed link /dev/ttyT38a -> /dev/pts/1
2010/12/13 13:01:46.474 ttyT38a(b)...0xb5d08b70 PseudoModemPty::OpenPty added link /dev/ttyT38a -> /dev/pts/1
2010/12/13 13:01:46.474 ttyT38a(b)...0xb5d08b70 PWLib File handle high water mark set: 26 Thread unblock pipe
2010/12/13 13:01:46.474 ttyT38a(b)...0xb5d08b70 PWLib File handle high water mark set: 28 Thread unblock pipe
2010/12/13 13:01:46.474 ttyT38a(b)...0xb5d08b70 PWLib File handle high water mark set: 30 Thread unblock pipe
2010/12/13 13:01:46.474 ttyT38a(b)...0xb5d08b70 PTLib Thread high water mark set: 10
2010/12/13 13:01:46.474 ttyT38a(b)...0xb5d08b70 PTLib Thread high water mark set: 11
2010/12/13 13:01:46.474 ttyT38a(b)...0xb5d08b70 PTLib Thread high water mark set: 12
2010/12/13 13:01:46.474 T38Modem PWLib File handle high water mark set: 31 PTCPSocket
2010/12/13 13:01:46.474 T38Modem PWLib File handle high water mark set: 33 Thread unblock pipe
2010/12/13 13:01:46.474 T38Modem PTLib Thread high water mark set: 13
2010/12/13 13:01:46.475 T38Modem PWLib File handle high water mark set: 34 PTCPSocket
2010/12/13 13:01:46.475 T38Modem PWLib File handle high water mark set: 36 Thread unblock pipe
2010/12/13 13:01:46.475 T38Modem PTLib Thread high water mark set: 14
2010/12/13 13:01:46.475 T38Modem SIP Start REGISTER2010/12/13 13:01:46.476 T38Modem PWLib File handle high water mark set: 37 PUDPSocket
aor=<my_username>@sip.nsw.iinet.net.au
registrar=
contact=<my_username>
authID=
realm=iinetphone.iinet.net.au
expire=300
restore=30
minRetry=596:31:23.647
maxRetry=596:31:23.647
2010/12/13 13:01:46.476 T38Modem SIP Changing REGISTER handler from Unavailable to Subscribing, target=sip:<my_username>@sip.nsw.iinet.net.au, id=b47fca9b-ca04-e011-9ed4-000c2984a855@xxxxxxxxxxxxxxxxxxx
2010/12/13 13:01:46.477 ttyT38b(b)...0xb5cc7b70 RenameCurrentThread old ThreadName=PseudoModemPty:0xb5cc7b70
2010/12/13 13:01:46.477 ttyT38b(b)...0xb5cc7b70 Started for /dev/ttyT38b (accepts all)
2010/12/13 13:01:46.477 ttyT38b(b)...0xb5cc7b70 ModemEndPoint::OnMyCallback command=addmodem extra=10
2010/12/13 13:01:46.477 ttyT38b(b)...0xb5cc7b70 PseudoModemQ::Enqueue ttyT38b
2010/12/13 13:01:46.477 ttyT38b(b)...0xb5cc7b70 ModemEndPoint::OnMyCallback request={
command=addmodem
response=confirm
modemtoken=ttyT38b
}
2010/12/13 13:01:46.477 ttyT38b(b)...0xb5cc7b70 PseudoModemPty::OpenPty removed link /dev/ttyT38b -> /dev/pts/2
2010/12/13 13:01:46.477 ttyT38b(b)...0xb5cc7b70 PseudoModemPty::OpenPty added link /dev/ttyT38b -> /dev/pts/2
2010/12/13 13:01:46.477 ttyT38b(b)...0xb5cc7b70 PWLib File handle high water mark set: 39 Thread unblock pipe
2010/12/13 13:01:46.477 ttyT38b(b)...0xb5cc7b70 PWLib File handle high water mark set: 41 Thread unblock pipe
2010/12/13 13:01:46.477 ttyT38b(b)...0xb5cc7b70 PWLib File handle high water mark set: 43 Thread unblock pipe
2010/12/13 13:01:46.477 ttyT38b(b)...0xb5cc7b70 PTLib Thread high water mark set: 15
2010/12/13 13:01:46.477 ttyT38b(b)...0xb5cc7b70 PTLib Thread high water mark set: 16
2010/12/13 13:01:46.477 ttyT38b(b)...0xb5cc7b70 PTLib Thread high water mark set: 17
2010/12/13 13:01:46.477 ttyT38c(b)...0xb5c86b70 RenameCurrentThread old ThreadName=PseudoModemPty:0xb5c86b70
2010/12/13 13:01:46.477 ttyT38c(b)...0xb5c86b70 Started for /dev/ttyT38c (accepts all)
2010/12/13 13:01:46.477 ttyT38c(b)...0xb5c86b70 ModemEndPoint::OnMyCallback command=addmodem extra=10
2010/12/13 13:01:46.477 ttyT38c(b)...0xb5c86b70 PseudoModemQ::Enqueue ttyT38c
2010/12/13 13:01:46.477 ttyT38c(b)...0xb5c86b70 ModemEndPoint::OnMyCallback request={
command=addmodem
response=confirm
modemtoken=ttyT38c
}
2010/12/13 13:01:46.477 ttyT38c(b)...0xb5c86b70 PseudoModemPty::OpenPty removed link /dev/ttyT38c -> /dev/pts/3
2010/12/13 13:01:46.477 ttyT38c(b)...0xb5c86b70 PseudoModemPty::OpenPty added link /dev/ttyT38c -> /dev/pts/3
2010/12/13 13:01:46.477 ttyT38c(b)...0xb5c86b70 PWLib File handle high water mark set: 46 Thread unblock pipe
2010/12/13 13:01:46.477 ttyT38c(b)...0xb5c86b70 PWLib File handle high water mark set: 48 Thread unblock pipe
2010/12/13 13:01:46.477 ttyT38c(b)...0xb5c86b70 PWLib File handle high water mark set: 50 Thread unblock pipe
2010/12/13 13:01:46.477 ttyT38c(b)...0xb5c86b70 PTLib Thread high water mark set: 18
2010/12/13 13:01:46.477 ttyT38c(b)...0xb5c86b70 PTLib Thread high water mark set: 19
2010/12/13 13:01:46.477 ttyT38c(b)...0xb5c86b70 PTLib Thread high water mark set: 20
2010/12/13 13:01:46.477 Network In...0xb5c45b70 IfaceMon Started interface monitor thread.
2010/12/13 13:01:46.477 Network In...0xb5c45b70 PWLib File handle high water mark set: 51 PUDPSocket
2010/12/13 13:01:46.478 ttyT38a(e)...0xb5b82b70 RenameCurrentThread old ThreadName=ModemEngine:0xb5b82b70
2010/12/13 13:01:46.478 ttyT38a(e)...0xb5b82b70 <-> Started
2010/12/13 13:01:46.478 ttyT38a(i)...0xb5b41b70 RenameCurrentThread old ThreadName=InPty:0xb5b41b70
2010/12/13 13:01:46.478 ttyT38a(i)...0xb5b41b70 --> Started
2010/12/13 13:01:46.478 ttyT38a(o)...0xb5b00b70 RenameCurrentThread old ThreadName=OutPty:0xb5b00b70
2010/12/13 13:01:46.478 ttyT38a(o)...0xb5b00b70 <-- Started
2010/12/13 13:01:46.478 Opal Liste...0xb5abfb70 Listen Started listening thread on tcp$*:5060
2010/12/13 13:01:46.478 Opal Liste...0xb5abfb70 Listen Waiting on socket accept on tcp$*:5060
2010/12/13 13:01:46.478 Opal Liste...0xb5a7eb70 Listen Started listening thread on tcps$*:5061
2010/12/13 13:01:46.478 Opal Liste...0xb5a7eb70 TCPS Waiting on socket accept on tcps$*:5061
2010/12/13 13:01:46.478 ttyT38b(e)...0xb5a31b70 RenameCurrentThread old ThreadName=ModemEngine:0xb5a31b70
2010/12/13 13:01:46.478 ttyT38b(e)...0xb5a31b70 <-> Started
2010/12/13 13:01:46.478 ttyT38b(i)...0xb59f0b70 RenameCurrentThread old ThreadName=InPty:0xb59f0b70
2010/12/13 13:01:46.478 ttyT38b(i)...0xb59f0b70 --> Started
2010/12/13 13:01:46.478 ttyT38b(o)...0xb59afb70 RenameCurrentThread old ThreadName=OutPty:0xb59afb70
2010/12/13 13:01:46.478 ttyT38b(o)...0xb59afb70 <-- Started
2010/12/13 13:01:46.478 ttyT38c(e)...0xb596eb70 RenameCurrentThread old ThreadName=ModemEngine:0xb596eb70
2010/12/13 13:01:46.478 ttyT38c(e)...0xb596eb70 <-> Started
2010/12/13 13:01:46.478 ttyT38c(i)...0xb592db70 RenameCurrentThread old ThreadName=InPty:0xb592db70
2010/12/13 13:01:46.478 ttyT38c(i)...0xb592db70 --> Started
2010/12/13 13:01:46.478 ttyT38c(o)...0xb58ecb70 RenameCurrentThread old ThreadName=OutPty:0xb58ecb70
2010/12/13 13:01:46.478 ttyT38c(o)...0xb58ecb70 <-- Started
2010/12/13 13:01:46.480 T38Modem OpalUDP Binding to interface: 0.0.0.0:5060
2010/12/13 13:01:46.480 T38Modem SIP Created transport udp$203.55.231.194:5060<if=udp$*:5060>
2010/12/13 13:01:46.481 T38Modem OpalUDP Started connect to 203.55.231.194:5060
2010/12/13 13:01:46.481 T38Modem OpalUDP Writing to interface 0 - "10.11.12.22%eth0"
2010/12/13 13:01:46.481 T38Modem SIP Transaction created.
2010/12/13 13:01:46.482 T38Modem SIP No SRV lookup as has explicit port number.
2010/12/13 13:01:46.482 T38Modem SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 13:01:46.482 T38Modem SIP Sending PDU (612 bytes) to: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0Via: SIP/2.0/UDP 10.11.12.22:5060;branch=z9hG4bKf678cb9b-ca04-e011-9ed4-000c2984a855;rportFrom: <sip:<my_username>@sip.nsw.iinet.net.au>;tag=448bca9b-ca04-e011-9ed4-000c2984a855
User-Agent: T38Modem/1.2.0
Call-ID: b47fca9b-ca04-e011-9ed4-000c2984a855@xxxxxxxxxxxxxxxxxxx2010/12/13 13:01:46.486 T38Modem OpalUDP Setting interface to 10.11.12.22%eth0
Organization: Vyacheslav Frolov
To: <sip:<my_username>@sip.nsw.iinet.net.au>
Contact: <sip:<my_username>>
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 300
Content-Length: 0
Max-Forwards: 70
2010/12/13 13:01:46.486 T38Modem SIP Transaction timers set: retry=0.500, completion=16.000
2010/12/13 13:01:46.486 T38Modem OpalMan Added route "modem:.*=sip:<dn>@sip.nsw.iinet.net.au"
2010/12/13 13:01:46.486 T38Modem PTLib Destroyed PXConfig 0x83a2808
2010/12/13 13:01:46.519 Opal Liste...0xb5bc3b70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 13:01:46.519 Opal Liste...0xb5bc3b70 SIP Waiting for PDU on udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
2010/12/13 13:01:46.519 Opal Liste...0xb5bc3b70 SIP PDU received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0CSeq: 1 REGISTER
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.11.12.22:5060;received=203.206.207.88;branch=z9hG4bKf678cb9b-ca04-e011-9ed4-000c2984a855;rport=39236
From: <sip:<my_username>@sip.nsw.iinet.net.au>;tag=448bca9b-ca04-e011-9ed4-000c2984a855
Call-ID: b47fca9b-ca04-e011-9ed4-000c2984a855@xxxxxxxxxxxxxxxxxxx
To: <sip:<my_username>@sip.nsw.iinet.net.au>;tag=SDip04099-
Content-Length: 0
WWW-Authenticate: DIGEST qop="auth",nonce="BroadWorksXghmpwxhlT2xccgiBW",algorithm=MD5,realm="iinetphone.iinet.net.au"
2010/12/13 13:01:46.520 Opal Liste...0xb5bc3b70 SIP Queueing PDU "1 REGISTER <401>", transaction=z9hG4bKf678cb9b-ca04-e011-9ed4-000c2984a855, token=b47fca9b-ca04-e011-9ed4-000c2984a855@xxxxxxxxxxxxxxxxxxx
2010/12/13 13:01:46.520 Opal Liste...0xb5bc3b70 PWLib File handle high water mark set: 52 Thread unblock pipe
2010/12/13 13:01:46.520 Opal Liste...0xb5bc3b70 PTLib Thread high water mark set: 21
2010/12/13 13:01:46.520 Opal Liste...0xb5bc3b70 Opal Transport clean up on termination
2010/12/13 13:01:46.520 Opal Liste...0xb5bc3b70 Opal Transport Close
2010/12/13 13:01:46.520 Opal Liste...0xb5bc3b70 PWLib File handle high water mark set: 53 PUDPSocket
2010/12/13 13:01:46.520 Opal Liste...0xb5bc3b70 Opal Deleted transport udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
2010/12/13 13:01:46.520 Pool:0xb58abb70 SIP Handling PDU "1 REGISTER <401>" for transaction=z9hG4bKf678cb9b-ca04-e011-9ed4-000c2984a855
2010/12/13 13:01:46.520 Pool:0xb58abb70 SIP REGISTER transaction id=z9hG4bKf678cb9b-ca04-e011-9ed4-000c2984a855 completed.
2010/12/13 13:01:46.520 Pool:0xb58abb70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 13:01:46.520 Pool:0xb58abb70 SIP Received Authentication Required response
2010/12/13 13:01:46.520 Pool:0xb58abb70 SIP Authentication contains qop-options auth
2010/12/13 13:01:46.521 Pool:0xb58abb70 SIP Located existing credentials for ID "<my_username>" at realm "iinetphone.iinet.net.au"
2010/12/13 13:01:46.521 Pool:0xb58abb70 SIP Found auth info for realm iinetphone.iinet.net.au
2010/12/13 13:01:46.521 Pool:0xb58abb70 SIP Changing REGISTER handler from Unavailable to Subscribing, target=sip:<my_username>@sip.nsw.iinet.net.au, id=b47fca9b-ca04-e011-9ed4-000c2984a855@xxxxxxxxxxxxxxxxxxx
2010/12/13 13:01:46.521 Pool:0xb58abb70 SIP Transaction created.
2010/12/13 13:01:46.522 Pool:0xb58abb70 SIP Adding authentication information for user "<my_username>" at realm "iinetphone.iinet.net.au"
2010/12/13 13:01:46.522 Pool:0xb58abb70 SIP No SRV lookup as has explicit port number.
2010/12/13 13:01:46.523 Pool:0xb58abb70 SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 13:01:46.523 Pool:0xb58abb70 SIP Sending PDU (890 bytes) to: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0Via: SIP/2.0/UDP 10.11.12.22:5060;branch=z9hG4bK9aa6d19b-ca04-e011-9ed4-000c2984a855;rportAuthorization: Digest username="<my_username>", realm="iinetphone.iinet.net.au", nonce="BroadWorksXghmpwxhlT2xccgiBW", uri="sip:sip.nsw.iinet.net.au", algorithm=MD5, response="003fb7a1996625afbfcb65f8f9ff37de", cnonce="245ed19b-ca04-e011-9ed4-000c2984a855", nc=00000001, qop=auth
User-Agent: T38Modem/1.2.0
From: <sip:<my_username>@sip.nsw.iinet.net.au>;tag=448bca9b-ca04-e011-9ed4-000c2984a855
Call-ID: b47fca9b-ca04-e011-9ed4-000c2984a855@xxxxxxxxxxxxxxxxxxx2010/12/13 13:01:46.523 Pool:0xb58abb70 OpalUDP Setting interface to 10.11.12.22%eth0
Organization: Vyacheslav Frolov
To: <sip:<my_username>@sip.nsw.iinet.net.au>
Contact: <sip:<my_username>>
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 300
Content-Length: 0
Max-Forwards: 70
2010/12/13 13:01:46.523 Pool:0xb58abb70 SIP Transaction timers set: retry=0.500, completion=16.000
2010/12/13 13:01:46.523 Pool:0xb58abb70 SIP Handled PDU "1 REGISTER <401>"
2010/12/13 13:01:46.562 Opal Liste...0xb5bc3b70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 13:01:46.562 Opal Liste...0xb5bc3b70 SIP Waiting for PDU on udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
2010/12/13 13:01:46.563 Opal Liste...0xb5bc3b70 SIP PDU received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0Via: SIP/2.0/UDP 10.11.12.22:5060;received=203.206.207.88;branch=z9hG4bK9aa6d19b-ca04-e011-9ed4-000c2984a855;rport=39236
SIP/2.0 200 OK
CSeq: 2 REGISTER
From: <sip:<my_username>@sip.nsw.iinet.net.au>;tag=448bca9b-ca04-e011-9ed4-000c2984a855
Call-ID: b47fca9b-ca04-e011-9ed4-000c2984a855@xxxxxxxxxxxxxxxxxxx
To: <sip:<my_username>@sip.nsw.iinet.net.au>;tag=SDip04099-860070855-1292205707958
Contact: <sip:<my_username>>;expires=3599;q=0.52010/12/13 13:01:46.563 Opal Liste...0xb5bc3b70 SIP Queueing PDU "2 REGISTER <200>", transaction=z9hG4bK9aa6d19b-ca04-e011-9ed4-000c2984a855, token=b47fca9b-ca04-e011-9ed4-000c2984a855@xxxxxxxxxxxxxxxxxxx
Allow-Events: call-info,line-seize,dialog,message-summary,as-feature-event
Content-Length: 0
2010/12/13 13:01:46.563 Opal Liste...0xb5bc3b70 Opal Transport clean up on termination
2010/12/13 13:01:46.563 Opal Liste...0xb5bc3b70 Opal Transport Close
2010/12/13 13:01:46.563 Opal Liste...0xb5bc3b70 Opal Deleted transport udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
2010/12/13 13:01:46.563 Pool:0xb58abb70 SIP Handling PDU "2 REGISTER <200>" for transaction=z9hG4bK9aa6d19b-ca04-e011-9ed4-000c2984a855
2010/12/13 13:01:46.563 Pool:0xb58abb70 SIP REGISTER transaction id=z9hG4bK9aa6d19b-ca04-e011-9ed4-000c2984a855 completed.
2010/12/13 13:01:46.563 Pool:0xb58abb70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 13:01:46.563 Pool:0xb58abb70 SIP No User-Agent or Server fields, Product Info unknown.
2010/12/13 13:01:46.563 Pool:0xb58abb70 SIP Changing REGISTER handler from Subscribing to Subscribed, target=sip:<my_username>@sip.nsw.iinet.net.au, id=b47fca9b-ca04-e011-9ed4-000c2984a855@xxxxxxxxxxxxxxxxxxx
2010/12/13 13:01:46.563 Pool:0xb58abb70 SIP Expiry time for REGISTER set to 3599 seconds.
2010/12/13 13:01:46.564 Pool:0xb58abb70 SIP No User-Agent or Server fields, Product Info unknown.
2010/12/13 13:01:46.564 Pool:0xb58abb70 SIP Handled PDU "2 REGISTER <200>"
2010/12/13 13:01:51.525 Housekeeper:0xb5c04b70 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bKf678cb9b-ca04-e011-9ed4-000c2984a855
2010/12/13 13:01:51.565 Housekeeper:0xb5c04b70 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bK9aa6d19b-ca04-e011-9ed4-000c2984a855
2010/12/13 13:01:52.479 Opal Garbage:0xb5d49b70 SIP Transaction id=z9hG4bK9aa6d19b-ca04-e011-9ed4-000c2984a855 destroyed.
2010/12/13 13:01:52.479 Opal Garbage:0xb5d49b70 SIP Transaction id=z9hG4bKf678cb9b-ca04-e011-9ed4-000c2984a855 destroyed.
2010/12/13 13:02:03.021 ttyT38a(i)...0xb5b41b70 --> read ERROR -1 Input/output error
2010/12/13 13:02:03.021 ttyT38a(i)...0xb5b41b70 --> Stopped
2010/12/13 13:02:03.022 ttyT38a(o)...0xb5b00b70 <-- Stopped
2010/12/13 13:02:03.032 ttyT38a(e)...0xb5b82b70 <-> Stopped
2010/12/13 13:02:03.043 ttyT38a(b)...0xb5d08b70 PWLib File handle low water mark set: 26 Thread unblock pipe
2010/12/13 13:02:03.043 ttyT38a(e)...0xb5b82b70 RenameCurrentThread old ThreadName=ModemEngine:0xb5b82b70
2010/12/13 13:02:03.043 ttyT38a(e)...0xb5b82b70 <-> Started
2010/12/13 13:02:03.043 ttyT38a(i)...0xb5b00b70 RenameCurrentThread old ThreadName=InPty:0xb5b00b70
2010/12/13 13:02:03.043 ttyT38a(i)...0xb5b00b70 --> Started
2010/12/13 13:02:03.043 ttyT38a(o)...0xb5b41b70 RenameCurrentThread old ThreadName=OutPty:0xb5b41b70
2010/12/13 13:02:03.044 ttyT38a(o)...0xb5b41b70 <-- Started
2010/12/13 13:02:04.036 ttyT38a(e)...0xb5b82b70 --> ATZ
2010/12/13 13:02:04.036 ttyT38a(e)...0xb5b82b70 <-- {2010/12/13 13:02:07.144 ttyT38a(e)...0xb5b82b70 --> ATS0=0
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:02:07.144 ttyT38a(e)...0xb5b82b70 <-- {2010/12/13 13:02:07.252 ttyT38a(e)...0xb5b82b70 --> ATE0
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:02:07.252 ttyT38a(e)...0xb5b82b70 <-- {2010/12/13 13:02:07.360 ttyT38a(e)...0xb5b82b70 --> ATV1
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:02:07.360 ttyT38a(e)...0xb5b82b70 <-- {2010/12/13 13:02:07.468 ttyT38a(e)...0xb5b82b70 --> ATQ0
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:02:07.468 ttyT38a(e)...0xb5b82b70 <-- {2010/12/13 13:02:07.577 ttyT38a(e)...0xb5b82b70 --> ATS8=2
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:02:07.577 ttyT38a(e)...0xb5b82b70 <-- {2010/12/13 13:02:07.684 ttyT38a(e)...0xb5b82b70 --> ATS7=60
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:02:07.684 ttyT38a(e)...0xb5b82b70 <-- {2010/12/13 13:02:07.792 ttyT38a(e)...0xb5b82b70 --> AT+FCLASS=?
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:02:07.792 ttyT38a(e)...0xb5b82b70 <-- {2010/12/13 13:02:07.900 ttyT38a(e)...0xb5b82b70 --> AT+FCLASS=1
0d 0a 31 2c 38 0d 0a 4f 4b 0d 0a ..1,8..OK.. }
2010/12/13 13:02:07.900 ttyT38a(e)...0xb5b82b70 <-- {2010/12/13 13:02:08.008 ttyT38a(e)...0xb5b82b70 --> ATI3
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:02:08.008 ttyT38a(e)...0xb5b82b70 <-- {2010/12/13 13:02:08.116 ttyT38a(e)...0xb5b82b70 --> ATI0
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.. }
2010/12/13 13:02:08.116 ttyT38a(e)...0xb5b82b70 <-- {2010/12/13 13:02:08.224 ttyT38a(e)...0xb5b82b70 --> AT+FREV?
0d 0a 54 33 38 46 41 58 0d 0a 4f 4b 0d 0a ..T38FAX..OK.. }
2010/12/13 13:02:08.224 ttyT38a(e)...0xb5b82b70 <-- {2010/12/13 13:02:08.332 ttyT38a(e)...0xb5b82b70 --> AT+FTM=?
0d 0a 31 2e 32 2e 30 0d 0a 4f 4b 0d 0a ..1.2.0..OK.. }
2010/12/13 13:02:08.332 ttyT38a(e)...0xb5b82b70 <-- {2010/12/13 13:02:08.441 ttyT38a(e)...0xb5b82b70 --> AT+FRM=?
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.. }
2010/12/13 13:02:08.441 ttyT38a(e)...0xb5b82b70 <-- {2010/12/13 13:02:08.548 ttyT38a(e)...0xb5b82b70 --> ATM0
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.. }
2010/12/13 13:02:08.548 ttyT38a(e)...0xb5b82b70 <-- {2010/12/13 13:02:34.184 ttyT38a(i)...0xb5b00b70 --> read ERROR -1 Input/output error
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:02:34.184 ttyT38a(i)...0xb5b00b70 --> Stopped
2010/12/13 13:02:34.184 ttyT38a(o)...0xb5b41b70 <-- Stopped
2010/12/13 13:02:34.194 ttyT38a(e)...0xb5b82b70 <-> Stopped
2010/12/13 13:02:34.205 ttyT38a(b)...0xb5d08b70 PseudoModemPty::OpenPty removed link /dev/ttyT38a -> /dev/pts/1
2010/12/13 13:02:34.205 ttyT38a(b)...0xb5d08b70 PseudoModemPty::OpenPty read ERROR -1 Input/output error
2010/12/13 13:02:34.205 ttyT38a(b)...0xb5d08b70 PseudoModemPty::OpenPty added link /dev/ttyT38a -> /dev/pts/1
2010/12/13 13:02:34.209 ttyT38a(e)...0xb5b82b70 RenameCurrentThread old ThreadName=ModemEngine:0xb5b82b70
2010/12/13 13:02:34.209 ttyT38a(e)...0xb5b82b70 <-> Started
2010/12/13 13:02:34.209 ttyT38a(i)...0xb5b41b70 RenameCurrentThread old ThreadName=InPty:0xb5b41b70
2010/12/13 13:02:34.209 ttyT38a(i)...0xb5b41b70 --> Started
2010/12/13 13:02:34.210 ttyT38a(o)...0xb5b00b70 RenameCurrentThread old ThreadName=OutPty:0xb5b00b70
2010/12/13 13:02:34.210 ttyT38a(o)...0xb5b00b70 <-- Started
2010/12/13 13:02:39.429 ttyT38a(e)...0xb5b82b70 --> ATZ
2010/12/13 13:02:39.429 ttyT38a(e)...0xb5b82b70 <-- {2010/12/13 13:02:42.540 ttyT38a(e)...0xb5b82b70 --> ATS0=0
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:02:42.540 ttyT38a(e)...0xb5b82b70 <-- {2010/12/13 13:02:42.648 ttyT38a(e)...0xb5b82b70 --> ATE0
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:02:42.648 ttyT38a(e)...0xb5b82b70 <-- {2010/12/13 13:02:42.756 ttyT38a(e)...0xb5b82b70 --> ATV1
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:02:42.756 ttyT38a(e)...0xb5b82b70 <-- {2010/12/13 13:02:42.864 ttyT38a(e)...0xb5b82b70 --> ATQ0
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:02:42.864 ttyT38a(e)...0xb5b82b70 <-- {2010/12/13 13:02:42.972 ttyT38a(e)...0xb5b82b70 --> ATS8=2
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:02:42.972 ttyT38a(e)...0xb5b82b70 <-- {2010/12/13 13:02:43.080 ttyT38a(e)...0xb5b82b70 --> ATS7=60
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:02:43.080 ttyT38a(e)...0xb5b82b70 <-- {2010/12/13 13:02:43.188 ttyT38a(e)...0xb5b82b70 --> AT+FCLASS=?
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:02:43.188 ttyT38a(e)...0xb5b82b70 <-- {2010/12/13 13:02:43.296 ttyT38a(e)...0xb5b82b70 --> AT+FCLASS=1
0d 0a 31 2c 38 0d 0a 4f 4b 0d 0a ..1,8..OK.. }
2010/12/13 13:02:43.296 ttyT38a(e)...0xb5b82b70 <-- {2010/12/13 13:02:43.404 ttyT38a(e)...0xb5b82b70 --> ATI3
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:02:43.404 ttyT38a(e)...0xb5b82b70 <-- {2010/12/13 13:02:43.512 ttyT38a(e)...0xb5b82b70 --> ATI0
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.. }
2010/12/13 13:02:43.512 ttyT38a(e)...0xb5b82b70 <-- {2010/12/13 13:02:43.620 ttyT38a(e)...0xb5b82b70 --> AT+FREV?
0d 0a 54 33 38 46 41 58 0d 0a 4f 4b 0d 0a ..T38FAX..OK.. }
2010/12/13 13:02:43.620 ttyT38a(e)...0xb5b82b70 <-- {2010/12/13 13:02:43.728 ttyT38a(e)...0xb5b82b70 --> AT+FTM=?
0d 0a 31 2e 32 2e 30 0d 0a 4f 4b 0d 0a ..1.2.0..OK.. }
2010/12/13 13:02:43.728 ttyT38a(e)...0xb5b82b70 <-- {2010/12/13 13:02:43.836 ttyT38a(e)...0xb5b82b70 --> AT+FRM=?
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.. }
2010/12/13 13:02:43.836 ttyT38a(e)...0xb5b82b70 <-- {2010/12/13 13:02:43.944 ttyT38a(e)...0xb5b82b70 --> ATM0
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.. }
2010/12/13 13:02:43.944 ttyT38a(e)...0xb5b82b70 <-- {2010/12/13 13:02:44.052 ttyT38a(e)...0xb5b82b70 --> AT+FCLASS=1
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:02:44.052 ttyT38a(e)...0xb5b82b70 <-- {2010/12/13 13:02:44.060 ttyT38a(e)...0xb5b82b70 --> ATDF0294396443
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:02:44.060 ttyT38a(e)...0xb5b82b70 ModemEndPoint::OnMyCallback command=dial extra=3
2010/12/13 13:02:44.060 ttyT38a(e)...0xb5b82b70 PseudoModemQ::Dequeue ttyT38a
2010/12/13 13:02:44.060 ttyT38a(e)...0xb5b82b70 MyManager::OnMyCallback SetUpCall(modem:, 0294396443@+/dev/ttyT38a)
2010/12/13 13:02:44.060 ttyT38a(e)...0xb5b82b70 OpalMan Set up call from modem: to 0294396443@+/dev/ttyT38a
2010/12/13 13:02:44.060 ttyT38a(e)...0xb5b82b70 Call Created Call[k5d40bf6b1]
2010/12/13 13:02:44.060 ttyT38a(e)...0xb5b82b70 OpalMan Set up connection to "modem:"
2010/12/13 13:02:44.060 ttyT38a(e)...0xb5b82b70 ModemEndPoint::MakeConnection modem:
2010/12/13 13:02:44.060 ttyT38a(e)...0xb5b82b70 OpalCon Created connection Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0]
2010/12/13 13:02:44.061 ttyT38a(e)...0xb5b82b70 ModemConnection::ModemConnection Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0]
2010/12/13 13:02:44.061 ttyT38a(e)...0xb5b82b70 ModemConnection::SetUpConnection Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0]
2010/12/13 13:02:44.061 ttyT38a(e)...0xb5b82b70 OpalCon SetPhase from UninitialisedPhase to SetUpPhase for Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0]
2010/12/13 13:02:44.061 ttyT38a(e)...0xb5b82b70 OpalMan OnIncoming connection Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0]
2010/12/13 13:02:44.061 ttyT38a(e)...0xb5b82b70 OpalCon Applying string options:
2010/12/13 13:02:44.061 ttyT38a(e)...0xb5b82b70 Call GetOtherPartyConnection Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0]
2010/12/13 13:02:44.061 ttyT38a(e)...0xb5b82b70 OpalMan Searching for route "modem:<my_username> 0294396443@+/dev/ttyT38a"
2010/12/13 13:02:44.061 ttyT38a(e)...0xb5b82b70 OpalMan Set up connection to "sip:0294396443@xxxxxxxxxxxxxxxxxxxx"
2010/12/13 13:02:44.061 ttyT38a(e)...0xb5b82b70 MySIPEndPoint::CreateConnection for Call[k5d40bf6b1]
2010/12/13 13:02:44.061 ttyT38a(e)...0xb5b82b70 OpalCon Created connection Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855]
2010/12/13 13:02:44.061 ttyT38a(e)...0xb5b82b70 RFC2833 Handler created
2010/12/13 13:02:44.061 ttyT38a(e)...0xb5b82b70 RFC2833 Handler created
2010/12/13 13:02:44.062 ttyT38a(e)...0xb5b82b70 SIP Created connection.
2010/12/13 13:02:44.062 ttyT38a(e)...0xb5b82b70 Call[k5d40bf6b1] from modem:<my_username> to 0294396443@+/dev/ttyT38a, route to sip:udp$sip.nsw.iinet.net.au:5060
2010/12/13 13:02:44.062 ttyT38a(e)...0xb5b82b70 Outgoing call routed to sip:0294396443@xxxxxxxxxxxxxxxxxxxx for Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0]
2010/12/13 13:02:44.062 ttyT38a(e)...0xb5b82b70 Call OnSetUp Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0]
2010/12/13 13:02:44.062 ttyT38a(e)...0xb5b82b70 MySIPConnection::SetUpConnection Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855] name=<my_username>
2010/12/13 13:02:44.062 ttyT38a(e)...0xb5b82b70 SIP SetUpConnection: sip:0294396443@xxxxxxxxxxxxxxxxxxxx
2010/12/13 13:02:44.062 ttyT38a(e)...0xb5b82b70 OpalCon SetPhase from UninitialisedPhase to SetUpPhase for Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855]
2010/12/13 13:02:44.062 ttyT38a(e)...0xb5b82b70 OpalCon Applying string options:
2010/12/13 13:02:44.062 ttyT38a(e)...0xb5b82b70 SIP Found registrar on domain sip.nsw.iinet.net.au, using interface 10.11.12.22%eth0
2010/12/13 13:02:44.062 ttyT38a(e)...0xb5b82b70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 13:02:44.062 ttyT38a(e)...0xb5b82b70 SIP Created transport udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
2010/12/13 13:02:44.063 ttyT38a(e)...0xb5b82b70 OpalUDP Started connect to 203.55.231.194:5060
2010/12/13 13:02:44.063 ttyT38a(e)...0xb5b82b70 SIP Updating dialog tag from "" to "aa5f1dbe-ca04-e011-9ed4-000c2984a855"
2010/12/13 13:02:44.066 ttyT38a(e)...0xb5b82b70 SIP INVITE transaction id=z9hG4bKfc1b1ebe-ca04-e011-9ed4-000c2984a855 created.
2010/12/13 13:02:44.066 ttyT38a(e)...0xb5b82b70 SIP Creating INVITE request
2010/12/13 13:02:44.066 ttyT38a(e)...0xb5b82b70 ModemConnection::GetMediaFormats Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0]
2010/12/13 13:02:44.066 ttyT38a(e)...0xb5b82b70 ModemEndPoint::GetMediaFormats
2010/12/13 13:02:44.068 ttyT38a(e)...0xb5b82b70 MediaFormat Removing codecs
2010/12/13 13:02:44.068 ttyT38a(e)...0xb5b82b70 MediaFormat Removing codecs
2010/12/13 13:02:44.069 ttyT38a(e)...0xb5b82b70 Call GetMediaFormats for Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855]2010/12/13 13:02:44.069 ttyT38a(e)...0xb5b82b70 SIP Offering media type audio in SDP with formats
G.711-uLaw-64k
G.711-ALaw-64k
PCM-16
T.382010/12/13 13:02:44.069 ttyT38a(e)...0xb5b82b70 Call IsMediaBypassPossible Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855] session 1
G.711-uLaw-64k,G.711-ALaw-64k,PCM-16,T.38
2010/12/13 13:02:44.069 ttyT38a(e)...0xb5b82b70 OpalMan IsMediaBypassPossible: session 1
2010/12/13 13:02:44.069 ttyT38a(e)...0xb5b82b70 OpalCon IsMediaBypassPossible: default returns false
2010/12/13 13:02:44.069 ttyT38a(e)...0xb5b82b70 RTP Cannot find media session 1
2010/12/13 13:02:44.069 ttyT38a(e)...0xb5b82b70 RTP Cannot find RTP session 1
2010/12/13 13:02:44.070 ttyT38a(e)...0xb5b82b70 RTP_UDP Session 1, created with NAT flag set to 0
2010/12/13 13:02:44.070 ttyT38a(e)...0xb5b82b70 PWLib File handle high water mark set: 54 PUDPSocket
2010/12/13 13:02:44.070 ttyT38a(e)...0xb5b82b70 PWLib File handle low water mark set: 53 PUDPSocket
2010/12/13 13:02:44.070 ttyT38a(e)...0xb5b82b70 RTP_UDP Session 1 created: 10.11.12.22:5000-5001 ssrc=1962876763
2010/12/13 13:02:44.070 ttyT38a(e)...0xb5b82b70 PWLib File handle high water mark set: 55 PUDPSocket
2010/12/13 13:02:44.070 ttyT38a(e)...0xb5b82b70 RTP Creating new session RTP_UDP
2010/12/13 13:02:44.070 ttyT38a(e)...0xb5b82b70 RTP Found existing media session 1
2010/12/13 13:02:44.071 ttyT38a(e)...0xb5b82b70 SIP Using default RTP payload [pt=101] for UserInput/RFC2833
2010/12/13 13:02:44.071 ttyT38a(e)...0xb5b82b70 SIP Using default RTP payload [pt=100] for NamedSignalEvent
2010/12/13 13:02:44.071 ttyT38a(e)...0xb5b82b70 ModemConnection::GetMediaFormats Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0]
2010/12/13 13:02:44.071 ttyT38a(e)...0xb5b82b70 ModemEndPoint::GetMediaFormats
2010/12/13 13:02:44.073 ttyT38a(e)...0xb5b82b70 MediaFormat Removing codecs
2010/12/13 13:02:44.073 ttyT38a(e)...0xb5b82b70 MediaFormat Removing codecs
2010/12/13 13:02:44.073 ttyT38a(e)...0xb5b82b70 Call GetMediaFormats for Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855]2010/12/13 13:02:44.073 ttyT38a(e)...0xb5b82b70 SIP No media formats of type video, not adding SDP
G.711-uLaw-64k
G.711-ALaw-64k
PCM-16
T.38
2010/12/13 13:02:44.074 ttyT38a(e)...0xb5b82b70 SIP No SRV lookup as has explicit port number.
2010/12/13 13:02:44.074 ttyT38a(e)...0xb5b82b70 SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 13:02:44.074 ttyT38a(e)...0xb5b82b70 SIP Sending PDU (980 bytes) to: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
INVITE sip:0294396443@xxxxxxxxxxxxxxxxxxxx SIP/2.0Date: Mon, 13 Dec 2010 02:02:44 GMT
Route: <sip:sip.nsw.iinet.net.au:5060;lr>
CSeq: 1 INVITE
Via: SIP/2.0/UDP 10.11.12.22:5060;branch=z9hG4bKfc1b1ebe-ca04-e011-9ed4-000c2984a855;rportFrom: "root" <sip:<my_username>@sip.nsw.iinet.net.au>;tag=aa5f1dbe-ca04-e011-9ed4-000c2984a855
User-Agent: T38Modem/1.2.0
Call-ID: 32691dbe-ca04-e011-9ed4-000c2984a855@xxxxxxxxxxxxxxxxxxx
Organization: Vyacheslav Frolov
To: <sip:0294396443@xxxxxxxxxxxxxxxxxxxx>o=- 1292205764 1 IN IP4 10.11.12.22
Contact: <sip:<my_username>@10.11.12.22>
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Content-Type: application/sdp
Content-Length: 286
Max-Forwards: 70
v=02010/12/13 13:02:44.075 ttyT38a(e)...0xb5b82b70 OpalUDP Setting interface to 10.11.12.22%eth0
s=Opal SIP Session
c=IN IP4 10.11.12.22
t=0 0
m=audio 5000 RTP/AVP 0 8 101 100
a=sendrecv
a=rtpmap:0 PCMU/8000/1
a=rtpmap:8 PCMA/8000/1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16,32,36
a=rtpmap:100 NSE/8000
a=fmtp:100 192-193
2010/12/13 13:02:44.075 ttyT38a(e)...0xb5b82b70 SIP Transaction timers set: retry=0.500, completion=32.000
2010/12/13 13:02:44.075 ttyT38a(e)...0xb5b82b70 OpalCon OnSetUpConnectionCall[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855]
2010/12/13 13:02:44.075 ttyT38a(e)...0xb5b82b70 OpalEP OnSetUpConnection Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855]
2010/12/13 13:02:44.075 ttyT38a(e)...0xb5b82b70 OpalMan SetUpCall succeeded, call=Call[k5d40bf6b1]
2010/12/13 13:02:44.075 ttyT38a(e)...0xb5b82b70 ttyT38a T38Engine::T38Engine
2010/12/13 13:02:44.075 ttyT38a(e)...0xb5b82b70 ModemEndPoint::OnMyCallback request={
calltoken=modem:/k5d40bf6b1/0number=0294396443
localpartyname=
command=dial
response=confirm
modemtoken=ttyT38a
}
2010/12/13 13:02:44.097 Opal Liste...0xb5bc3b70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 13:02:44.097 Opal Liste...0xb5bc3b70 SIP Waiting for PDU on udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
2010/12/13 13:02:44.098 Opal Liste...0xb5bc3b70 SIP No Content-Length present from udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>, reading till end of datagram/stream.
2010/12/13 13:02:44.098 Opal Liste...0xb5bc3b70 SIP PDU received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0Via: SIP/2.0/UDP 10.11.12.22:5060;received=203.206.207.88;branch=z9hG4bKfc1b1ebe-ca04-e011-9ed4-000c2984a855;rport=39236
SIP/2.0 100 Trying
CSeq: 1 INVITE
From: "root" <sip:<my_username>@sip.nsw.iinet.net.au>;tag=aa5f1dbe-ca04-e011-9ed4-000c2984a855
Call-ID: 32691dbe-ca04-e011-9ed4-000c2984a855@xxxxxxxxxxxxxxxxxxx
To: <sip:0294396443@xxxxxxxxxxxxxxxxxxxx>
2010/12/13 13:02:44.099 Opal Liste...0xb5bc3b70 SIP Queueing PDU "1 INVITE <100>", transaction=z9hG4bKfc1b1ebe-ca04-e011-9ed4-000c2984a855, token=aa5f1dbe-ca04-e011-9ed4-000c2984a855
2010/12/13 13:02:44.099 Opal Liste...0xb5bc3b70 PWLib File handle high water mark set: 56 Thread unblock pipe
2010/12/13 13:02:44.099 Opal Liste...0xb5bc3b70 PTLib Thread high water mark set: 22
2010/12/13 13:02:44.099 Opal Liste...0xb5bc3b70 Opal Transport clean up on termination
2010/12/13 13:02:44.099 Opal Liste...0xb5bc3b70 Opal Transport Close
2010/12/13 13:02:44.099 Opal Liste...0xb5bc3b70 PWLib File handle high water mark set: 57 PUDPSocket
2010/12/13 13:02:44.099 Opal Liste...0xb5bc3b70 Opal Deleted transport udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
2010/12/13 13:02:44.099 Pool:0xb586ab70 SIP Handling PDU "1 INVITE <100>" for transaction=z9hG4bKfc1b1ebe-ca04-e011-9ed4-000c2984a855
2010/12/13 13:02:44.100 Pool:0xb586ab70 SIP No User-Agent or Server fields, Product Info unknown.
2010/12/13 13:02:44.100 Pool:0xb586ab70 SIP INVITE transaction id=z9hG4bKfc1b1ebe-ca04-e011-9ed4-000c2984a855 proceeding.
2010/12/13 13:02:44.100 Pool:0xb586ab70 SIP Received Trying response
2010/12/13 13:02:44.100 Pool:0xb586ab70 OpalCon SetPhase from SetUpPhase to ProceedingPhase for Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855]
2010/12/13 13:02:44.100 Pool:0xb586ab70 OpalMan OnProceeding Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855]
2010/12/13 13:02:44.100 Pool:0xb586ab70 Call OnProceeding Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855]
2010/12/13 13:02:44.100 Pool:0xb586ab70 SIP Handled PDU "1 INVITE <100>"
2010/12/13 13:02:44.169 Opal Liste...0xb5bc3b70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 13:02:44.169 Opal Liste...0xb5bc3b70 SIP Waiting for PDU on udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
2010/12/13 13:02:44.169 Opal Liste...0xb5bc3b70 SIP PDU received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0Via: SIP/2.0/UDP 10.11.12.22:5060;received=203.206.207.88;branch=z9hG4bKfc1b1ebe-ca04-e011-9ed4-000c2984a855;rport=39236
SIP/2.0 401 Unauthorized
CSeq: 1 INVITE
From: "root" <sip:<my_username>@sip.nsw.iinet.net.au>;tag=aa5f1dbe-ca04-e011-9ed4-000c2984a855
Call-ID: 32691dbe-ca04-e011-9ed4-000c2984a855@xxxxxxxxxxxxxxxxxxx
To: <sip:0294396443@xxxxxxxxxxxxxxxxxxxx>;tag=SDj9kvc99-1212186191-1292205765567
Content-Length: 0
WWW-Authenticate: DIGEST qop="auth",nonce="BroadWorksXghmpy5z3Th3c5aqBW",algorithm=MD5,realm="iinetphone.iinet.net.au"
2010/12/13 13:02:44.170 Opal Liste...0xb5bc3b70 SIP Queueing PDU "1 INVITE <401>", transaction=z9hG4bKfc1b1ebe-ca04-e011-9ed4-000c2984a855, token=aa5f1dbe-ca04-e011-9ed4-000c2984a855
2010/12/13 13:02:44.170 Opal Liste...0xb5bc3b70 Opal Transport clean up on termination
2010/12/13 13:02:44.170 Opal Liste...0xb5bc3b70 Opal Transport Close
2010/12/13 13:02:44.170 Opal Liste...0xb5bc3b70 Opal Deleted transport udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
2010/12/13 13:02:44.170 Pool:0xb586ab70 SIP Handling PDU "1 INVITE <401>" for transaction=z9hG4bKfc1b1ebe-ca04-e011-9ed4-000c2984a855
2010/12/13 13:02:44.171 Pool:0xb586ab70 SIP Sending PDU (478 bytes) to: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
ACK sip:0294396443@xxxxxxxxxxxxxxxxxxxx SIP/2.0Via: SIP/2.0/UDP 10.11.12.22:5060;branch=z9hG4bKfc1b1ebe-ca04-e011-9ed4-000c2984a855;rport
Route: <sip:sip.nsw.iinet.net.au:5060;lr>
CSeq: 1 ACK
From: "root" <sip:<my_username>@sip.nsw.iinet.net.au>;tag=aa5f1dbe-ca04-e011-9ed4-000c2984a855
Call-ID: 32691dbe-ca04-e011-9ed4-000c2984a855@xxxxxxxxxxxxxxxxxxx
To: <sip:0294396443@xxxxxxxxxxxxxxxxxxxx>;tag=SDj9kvc99-1212186191-12922057655672010/12/13 13:02:44.172 Pool:0xb586ab70 OpalUDP Setting interface to 10.11.12.22%eth0
Content-Length: 0
Max-Forwards: 70
2010/12/13 13:02:44.172 Pool:0xb586ab70 SIP INVITE transaction id=z9hG4bKfc1b1ebe-ca04-e011-9ed4-000c2984a855 completed.
2010/12/13 13:02:44.172 Pool:0xb586ab70 SIP Received Authentication Required response
2010/12/13 13:02:44.172 Pool:0xb586ab70 SIP Authentication contains qop-options auth
2010/12/13 13:02:44.172 Pool:0xb586ab70 SIP Located existing credentials for ID "<my_username>" at realm "iinetphone.iinet.net.au"
2010/12/13 13:02:44.172 Pool:0xb586ab70 SIP Found auth info for realm "iinetphone.iinet.net.au", user "<my_username>"
2010/12/13 13:02:44.172 Pool:0xb586ab70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 13:02:44.175 Pool:0xb586ab70 SIP INVITE transaction id=z9hG4bK66ab2ebe-ca04-e011-9ed4-000c2984a855 created.
2010/12/13 13:02:44.175 Pool:0xb586ab70 SIP Creating INVITE request
2010/12/13 13:02:44.175 Pool:0xb586ab70 ModemConnection::GetMediaFormats Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0]
2010/12/13 13:02:44.175 Pool:0xb586ab70 ModemEndPoint::GetMediaFormats
2010/12/13 13:02:44.176 Pool:0xb586ab70 MediaFormat Removing codecs
2010/12/13 13:02:44.177 Pool:0xb586ab70 MediaFormat Removing codecs
2010/12/13 13:02:44.177 Pool:0xb586ab70 Call GetMediaFormats for Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855]2010/12/13 13:02:44.177 Pool:0xb586ab70 SIP Offering media type audio in SDP with formats
G.711-uLaw-64k
G.711-ALaw-64k
PCM-16
T.382010/12/13 13:02:44.177 Pool:0xb586ab70 Call IsMediaBypassPossible Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855] session 1
G.711-uLaw-64k,G.711-ALaw-64k,PCM-16,T.38
2010/12/13 13:02:44.177 Pool:0xb586ab70 OpalMan IsMediaBypassPossible: session 1
2010/12/13 13:02:44.177 Pool:0xb586ab70 OpalCon IsMediaBypassPossible: default returns false
2010/12/13 13:02:44.177 Pool:0xb586ab70 RTP Found existing media session 1
2010/12/13 13:02:44.177 Pool:0xb586ab70 RTP Found existing RTP session 1
2010/12/13 13:02:44.178 Pool:0xb586ab70 SIP Using default RTP payload [pt=101] for UserInput/RFC2833
2010/12/13 13:02:44.178 Pool:0xb586ab70 SIP Using default RTP payload [pt=100] for NamedSignalEvent
2010/12/13 13:02:44.178 Pool:0xb586ab70 ModemConnection::GetMediaFormats Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0]
2010/12/13 13:02:44.178 Pool:0xb586ab70 ModemEndPoint::GetMediaFormats
2010/12/13 13:02:44.180 Pool:0xb586ab70 MediaFormat Removing codecs
2010/12/13 13:02:44.180 Pool:0xb586ab70 MediaFormat Removing codecs
2010/12/13 13:02:44.180 Pool:0xb586ab70 Call GetMediaFormats for Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855]2010/12/13 13:02:44.180 Pool:0xb586ab70 SIP No media formats of type video, not adding SDP
G.711-uLaw-64k
G.711-ALaw-64k
PCM-16
T.38
2010/12/13 13:02:44.181 Pool:0xb586ab70 SIP Adding authentication information for user "<my_username>" at realm "iinetphone.iinet.net.au"
2010/12/13 13:02:44.181 Pool:0xb586ab70 SIP No SRV lookup as has explicit port number.
2010/12/13 13:02:44.181 Pool:0xb586ab70 SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 13:02:44.181 Pool:0xb586ab70 SIP Sending PDU (1269 bytes) to: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
INVITE sip:0294396443@xxxxxxxxxxxxxxxxxxxx SIP/2.0Date: Mon, 13 Dec 2010 02:02:44 GMT
Route: <sip:sip.nsw.iinet.net.au:5060;lr>
CSeq: 2 INVITE
Via: SIP/2.0/UDP 10.11.12.22:5060;branch=z9hG4bK66ab2ebe-ca04-e011-9ed4-000c2984a855;rportAuthorization: Digest username="<my_username>", realm="iinetphone.iinet.net.au", nonce="BroadWorksXghmpy5z3Th3c5aqBW", uri="sip:0294396443@xxxxxxxxxxxxxxxxxxxx", algorithm=MD5, response="a6bf12bdadf566a37c590ee283721a57", cnonce="1c502ebe-ca04-e011-9ed4-000c2984a855", nc=00000001, qop=auth
User-Agent: T38Modem/1.2.0
From: "root" <sip:<my_username>@sip.nsw.iinet.net.au>;tag=aa5f1dbe-ca04-e011-9ed4-000c2984a855
Call-ID: 32691dbe-ca04-e011-9ed4-000c2984a855@xxxxxxxxxxxxxxxxxxx
Organization: Vyacheslav Frolov
To: <sip:0294396443@xxxxxxxxxxxxxxxxxxxx>o=- 1292205764 1 IN IP4 10.11.12.22
Contact: <sip:<my_username>@10.11.12.22>
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Content-Type: application/sdp
Content-Length: 286
Max-Forwards: 70
v=02010/12/13 13:02:44.182 Pool:0xb586ab70 OpalUDP Setting interface to 10.11.12.22%eth0
s=Opal SIP Session
c=IN IP4 10.11.12.22
t=0 0
m=audio 5000 RTP/AVP 0 8 101 100
a=sendrecv
a=rtpmap:0 PCMU/8000/1
a=rtpmap:8 PCMA/8000/1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16,32,36
a=rtpmap:100 NSE/8000
a=fmtp:100 192-193
2010/12/13 13:02:44.182 Pool:0xb586ab70 SIP Transaction timers set: retry=0.500, completion=32.000
2010/12/13 13:02:44.182 Pool:0xb586ab70 SIP Handled PDU "1 INVITE <401>"
2010/12/13 13:02:44.247 Opal Liste...0xb5bc3b70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 13:02:44.247 Opal Liste...0xb5bc3b70 SIP Waiting for PDU on udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
2010/12/13 13:02:44.247 Opal Liste...0xb5bc3b70 SIP No Content-Length present from udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>, reading till end of datagram/stream.
2010/12/13 13:02:44.247 Opal Liste...0xb5bc3b70 SIP PDU received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0Via: SIP/2.0/UDP 10.11.12.22:5060;received=203.206.207.88;branch=z9hG4bK66ab2ebe-ca04-e011-9ed4-000c2984a855;rport=39236
SIP/2.0 100 Trying
CSeq: 2 INVITE
From: "root" <sip:<my_username>@sip.nsw.iinet.net.au>;tag=aa5f1dbe-ca04-e011-9ed4-000c2984a855
Call-ID: 32691dbe-ca04-e011-9ed4-000c2984a855@xxxxxxxxxxxxxxxxxxx
To: <sip:0294396443@xxxxxxxxxxxxxxxxxxxx>
2010/12/13 13:02:44.248 Opal Liste...0xb5bc3b70 SIP Queueing PDU "2 INVITE <100>", transaction=z9hG4bK66ab2ebe-ca04-e011-9ed4-000c2984a855, token=aa5f1dbe-ca04-e011-9ed4-000c2984a855
2010/12/13 13:02:44.248 Opal Liste...0xb5bc3b70 Opal Transport clean up on termination
2010/12/13 13:02:44.248 Opal Liste...0xb5bc3b70 Opal Transport Close
2010/12/13 13:02:44.248 Opal Liste...0xb5bc3b70 Opal Deleted transport udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
2010/12/13 13:02:44.248 Pool:0xb586ab70 SIP Handling PDU "2 INVITE <100>" for transaction=z9hG4bK66ab2ebe-ca04-e011-9ed4-000c2984a855
2010/12/13 13:02:44.249 Pool:0xb586ab70 SIP No User-Agent or Server fields, Product Info unknown.
2010/12/13 13:02:44.249 Pool:0xb586ab70 SIP INVITE transaction id=z9hG4bK66ab2ebe-ca04-e011-9ed4-000c2984a855 proceeding.
2010/12/13 13:02:44.249 Pool:0xb586ab70 SIP Received Trying response
2010/12/13 13:02:44.249 Pool:0xb586ab70 SIP Handled PDU "2 INVITE <100>"
2010/12/13 13:02:45.179 Opal Liste...0xb5bc3b70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 13:02:45.179 Opal Liste...0xb5bc3b70 SIP Waiting for PDU on udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
2010/12/13 13:02:45.180 Opal Liste...0xb5bc3b70 SIP PDU received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0Via: SIP/2.0/UDP 10.11.12.22:5060;received=203.206.207.88;branch=z9hG4bK66ab2ebe-ca04-e011-9ed4-000c2984a855;rport=39236
SIP/2.0 183 Session Progress
CSeq: 2 INVITE
From: "root" <sip:<my_username>@sip.nsw.iinet.net.au>;tag=aa5f1dbe-ca04-e011-9ed4-000c2984a855
Call-ID: 32691dbe-ca04-e011-9ed4-000c2984a855@xxxxxxxxxxxxxxxxxxx
Supported:
To: <sip:0294396443@xxxxxxxxxxxxxxxxxxxx>;tag=SDj9kvc99-17694676-1292205766573
Session: Media
Contact: <sip:0294396443@xxxxxxxxxxxxxx:5060;transport=udp>o=BroadWorks 110018254 1 IN IP4 203.55.231.194
Allow: ACK,BYE,CANCEL,INFO,INVITE,OPTIONS,PRACK,REFER,NOTIFY,UPDATE
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 224
v=0m=audio 35202 RTP/AVP 0 101
s=-
c=IN IP4 203.55.231.194
t=0 02010/12/13 13:02:45.180 Opal Liste...0xb5bc3b70 SIP Queueing PDU "2 INVITE <183>", transaction=z9hG4bK66ab2ebe-ca04-e011-9ed4-000c2984a855, token=aa5f1dbe-ca04-e011-9ed4-000c2984a855
c=IN IP4 203.55.231.194
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16,32,36
2010/12/13 13:02:45.180 Opal Liste...0xb5bc3b70 Opal Transport clean up on termination
2010/12/13 13:02:45.180 Opal Liste...0xb5bc3b70 Opal Transport Close
2010/12/13 13:02:45.180 Opal Liste...0xb5bc3b70 Opal Deleted transport udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
2010/12/13 13:02:45.181 Pool:0xb586ab70 SIP Handling PDU "2 INVITE <183>" for transaction=z9hG4bK66ab2ebe-ca04-e011-9ed4-000c2984a855
2010/12/13 13:02:45.181 Pool:0xb586ab70 SIP Updating dialog tag from "" to "SDj9kvc99-17694676-1292205766573"
2010/12/13 13:02:45.181 Pool:0xb586ab70 SDP Parsed connection address udp$203.55.231.194
2010/12/13 13:02:45.181 Pool:0xb586ab70 SDP Parsed connection address udp$203.55.231.194
2010/12/13 13:02:45.181 Pool:0xb586ab70 SDP Media session port=35202
2010/12/13 13:02:45.181 Pool:0xb586ab70 SDP Parsed connection address udp$203.55.231.194:35202
2010/12/13 13:02:45.182 Pool:0xb586ab70 SDP Parsed media session with 2 'audio' formats
2010/12/13 13:02:45.182 Pool:0xb586ab70 SIP No User-Agent or Server fields, Product Info unknown.
2010/12/13 13:02:45.182 Pool:0xb586ab70 SIP INVITE transaction id=z9hG4bK66ab2ebe-ca04-e011-9ed4-000c2984a855 proceeding.
2010/12/13 13:02:45.182 Pool:0xb586ab70 SIP Received Session Progress response
2010/12/13 13:02:45.182 Pool:0xb586ab70 SIP Processing received SDP media description for audio
2010/12/13 13:02:45.182 Pool:0xb586ab70 SIP RTP payload type PCMU matched to codec G.711-uLaw-64k
2010/12/13 13:02:45.182 Pool:0xb586ab70 MediaFormat Removing codecs
2010/12/13 13:02:45.182 Pool:0xb586ab70 Call IsMediaBypassPossible Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855] session 1
2010/12/13 13:02:45.182 Pool:0xb586ab70 OpalMan IsMediaBypassPossible: session 1
2010/12/13 13:02:45.182 Pool:0xb586ab70 OpalCon IsMediaBypassPossible: default returns false
2010/12/13 13:02:45.182 Pool:0xb586ab70 RTP Found existing RTP session 1
2010/12/13 13:02:45.183 Pool:0xb586ab70 RTP_UDP Session 1, SetRemoteSocketInfo: data channel, new=203.55.231.194:35202, local=10.11.12.22:5000-5001, remote=0.0.0.0:0-0
2010/12/13 13:02:45.183 Pool:0xb586ab70 RTP Found existing media session 1
2010/12/13 13:02:45.183 Pool:0xb586ab70 Call OpenSourceMediaStreams opening audio session 1 on Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855]
2010/12/13 13:02:45.183 Pool:0xb586ab70 ModemConnection::GetMediaFormats Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0]
2010/12/13 13:02:45.183 Pool:0xb586ab70 ModemEndPoint::GetMediaFormats
2010/12/13 13:02:45.183 Pool:0xb586ab70 ModemConnection::GetMediaFormats Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0]
2010/12/13 13:02:45.183 Pool:0xb586ab70 ModemEndPoint::GetMediaFormats
2010/12/13 13:02:45.185 Pool:0xb586ab70 MediaFormat Removing codecs
2010/12/13 13:02:45.185 Pool:0xb586ab70 MediaFormat Removing codecs
2010/12/13 13:02:45.185 Pool:0xb586ab70 Call GetMediaFormats for Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855]2010/12/13 13:02:45.185 Pool:0xb586ab70 MediaFormat Merging G.711-uLaw-64k into G.711-uLaw-64k
G.711-uLaw-64k
G.711-ALaw-64k
PCM-16
T.38
2010/12/13 13:02:45.185 Pool:0xb586ab70 MediaFormat Merging PCM-16 into PCM-16
2010/12/13 13:02:45.185 Pool:0xb586ab70 MediaFormat Merging PCM-16 into G.711-uLaw-64k
2010/12/13 13:02:45.186 Pool:0xb586ab70 MediaFormat Merging G.711-uLaw-64k into PCM-16
2010/12/13 13:02:45.186 Pool:0xb586ab70 MediaFormat Changed media option "Max Bit Rate" from 128000 to 64000
2010/12/13 13:02:45.186 Pool:0xb586ab70 Call Selected media formats G.711-uLaw-64k -> PCM-16
2010/12/13 13:02:45.186 Pool:0xb586ab70 Call IsMediaBypassPossible Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855] session 1
2010/12/13 13:02:45.186 Pool:0xb586ab70 OpalMan IsMediaBypassPossible: session 1
2010/12/13 13:02:45.186 Pool:0xb586ab70 OpalCon IsMediaBypassPossible: default returns false
2010/12/13 13:02:45.186 Pool:0xb586ab70 RTP Found existing media session 1
2010/12/13 13:02:45.186 Pool:0xb586ab70 OpalMan OnOpenMediaStream Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855],OpalRTPMediaStream-Source-G.711-uLaw-64k
2010/12/13 13:02:45.186 Pool:0xb586ab70 OpalCon Opened source stream k5d40bf6b1_1 with format G.711-uLaw-64k
2010/12/13 13:02:45.186 Pool:0xb586ab70 ModemConnection::CreateMediaStream Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0] mediaFormat=PCM-16 sessionID=1 isSource=0
2010/12/13 13:02:45.186 Pool:0xb586ab70 ModemEngineBody::Attach audioEngine stConnectWait
2010/12/13 13:02:45.186 Pool:0xb586ab70 ttyT38a AudioEngine::Attach
2010/12/13 13:02:45.186 Pool:0xb586ab70 ttyT38a AudioClass=FALSE
2010/12/13 13:02:45.186 Pool:0xb586ab70 ttyT38a AudioEngine::SendOnIdle 2
2010/12/13 13:02:45.186 Pool:0xb586ab70 ModemEngineBody::Attach audioEngine Attached
2010/12/13 13:02:45.186 Pool:0xb586ab70 OpalMan OnOpenMediaStream Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0],AudioModemMediaStream-Sink-PCM-16
2010/12/13 13:02:45.186 Pool:0xb586ab70 OpalCon Opened sink stream k5d40bf6b1_1 with format PCM-16
2010/12/13 13:02:45.186 Pool:0xb586ab70 Patch Created Sink: format=PCM-16
2010/12/13 13:02:45.186 Pool:0xb586ab70 Codec G711-uLaw-64k decoder created
2010/12/13 13:02:45.186 Pool:0xb586ab70 Patch Created primary codec G.711-uLaw-64k->PCM-16 with ID k5d40bf6b1_1
2010/12/13 13:02:45.186 Pool:0xb586ab70 Media Set data size from 16 to 3840
2010/12/13 13:02:45.186 Pool:0xb586ab70 Patch Added media stream sink AudioModemMediaStream-Sink-PCM-16 using transcoder G.711-uLaw-64k->PCM-16, data size=3840
2010/12/13 13:02:45.186 Pool:0xb586ab70 Media RTP data size cannot be changed to 8, fixed at 2048
2010/12/13 13:02:45.186 Pool:0xb586ab70 OpalCon Sink stream of connection Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0] uses patch Patch OpalRTPMediaStream-Source-G.711-uLaw-64k -> AudioModemMediaStream-Sink-PCM-16
2010/12/13 13:02:45.186 Pool:0xb586ab70 OpalCon Source stream of connection Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855] uses patch Patch OpalRTPMediaStream-Source-G.711-uLaw-64k -> AudioModemMediaStream-Sink-PCM-16
2010/12/13 13:02:45.186 Pool:0xb586ab70 RTPCon Adding RFC2833 receive handler
2010/12/13 13:02:45.186 Pool:0xb586ab70 RTPCon Adding Cisco NSE receive handler
2010/12/13 13:02:45.186 Pool:0xb586ab70 Call GetOtherPartyConnection Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855]
2010/12/13 13:02:45.186 Pool:0xb586ab70 Call OpenSourceMediaStreams opening audio session 1 on Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0]
2010/12/13 13:02:45.187 Pool:0xb586ab70 ModemConnection::GetMediaFormats Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0]
2010/12/13 13:02:45.187 Pool:0xb586ab70 ModemEndPoint::GetMediaFormats
2010/12/13 13:02:45.187 Pool:0xb586ab70 MediaFormat Removing codecs
2010/12/13 13:02:45.188 Pool:0xb586ab70 MediaFormat Removing codecs
2010/12/13 13:02:45.188 Pool:0xb586ab70 Call GetMediaFormats for Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0]2010/12/13 13:02:45.188 Pool:0xb586ab70 MediaFormat Merging PCM-16 into PCM-16
G.711-uLaw-64k
G.711-ALaw-64k
PCM-16
T.38
2010/12/13 13:02:45.188 Pool:0xb586ab70 MediaFormat Merging G.711-uLaw-64k into G.711-uLaw-64k
2010/12/13 13:02:45.188 Pool:0xb586ab70 MediaFormat Merging G.711-uLaw-64k into PCM-16
2010/12/13 13:02:45.188 Pool:0xb586ab70 MediaFormat Changed media option "Max Bit Rate" from 128000 to 64000
2010/12/13 13:02:45.188 Pool:0xb586ab70 MediaFormat Merging PCM-16 into G.711-uLaw-64k
2010/12/13 13:02:45.188 Pool:0xb586ab70 Call Selected media formats PCM-16 -> G.711-uLaw-64k
2010/12/13 13:02:45.188 Pool:0xb586ab70 ModemConnection::CreateMediaStream Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0] mediaFormat=PCM-16 sessionID=1 isSource=1
2010/12/13 13:02:45.188 Pool:0xb586ab70 OpalMan OnOpenMediaStream Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0],AudioModemMediaStream-Source-PCM-16
2010/12/13 13:02:45.188 Pool:0xb586ab70 OpalCon Opened source stream k5d40bf6b1_1 with format PCM-16
2010/12/13 13:02:45.188 Pool:0xb586ab70 Call IsMediaBypassPossible Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855] session 1
2010/12/13 13:02:45.188 Pool:0xb586ab70 OpalMan IsMediaBypassPossible: session 1
2010/12/13 13:02:45.188 Pool:0xb586ab70 OpalCon IsMediaBypassPossible: default returns false
2010/12/13 13:02:45.188 Pool:0xb586ab70 RTP Found existing media session 1
2010/12/13 13:02:45.189 Pool:0xb586ab70 OpalMan OnOpenMediaStream Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855],OpalRTPMediaStream-Sink-G.711-uLaw-64k
2010/12/13 13:02:45.189 Pool:0xb586ab70 OpalCon Opened sink stream k5d40bf6b1_1 with format G.711-uLaw-64k
2010/12/13 13:02:45.189 Pool:0xb586ab70 Patch Created Sink: format=G.711-uLaw-64k
2010/12/13 13:02:45.189 Pool:0xb586ab70 Codec G711-uLaw-64k encoder created
2010/12/13 13:02:45.189 Pool:0xb586ab70 Patch Created primary codec PCM-16->G.711-uLaw-64k with ID k5d40bf6b1_1
2010/12/13 13:02:45.189 Pool:0xb586ab70 Media RTP data size cannot be changed to 1920, fixed at 528
2010/12/13 13:02:45.189 Pool:0xb586ab70 Patch Added media stream sink OpalRTPMediaStream-Sink-G.711-uLaw-64k using transcoder PCM-16->G.711-uLaw-64k, data size=528
2010/12/13 13:02:45.189 Pool:0xb586ab70 Media Set data size from 16 to 320
2010/12/13 13:02:45.189 Pool:0xb586ab70 OpalCon Source stream of connection Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0] uses patch Patch AudioModemMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-G.711-uLaw-64k
2010/12/13 13:02:45.189 Pool:0xb586ab70 OpalCon Sink stream of connection Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855] uses patch Patch AudioModemMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-G.711-uLaw-64k
2010/12/13 13:02:45.189 Pool:0xb586ab70 OpalCon SetPhase from ProceedingPhase to AlertingPhase for Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855]
2010/12/13 13:02:45.189 Pool:0xb586ab70 OpalMan OnAlerting Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855]
2010/12/13 13:02:45.189 Pool:0xb586ab70 Call OnAlerting Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855]
2010/12/13 13:02:45.189 Pool:0xb586ab70 ModemConnection::SetAlerting Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0] 0294396443@xxxxxxxxxxxxxxxxxxxx 1
2010/12/13 13:02:45.189 Pool:0xb586ab70 OpalCon SetPhase from SetUpPhase to AlertingPhase for Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0]
2010/12/13 13:02:45.189 Pool:0xb586ab70 SIP Starting receive media to annunciate remote progress tones
2010/12/13 13:02:45.189 Pool:0xb586ab70 PWLib File handle high water mark set: 58 Thread unblock pipe
2010/12/13 13:02:45.189 Pool:0xb586ab70 PTLib Thread high water mark set: 23
2010/12/13 13:02:45.189 Media Patch:0xb5829b70 Patch Thread started for Patch OpalRTPMediaStream-Source-G.711-uLaw-64k -> AudioModemMediaStream-Sink-PCM-16
2010/12/13 13:02:45.189 Pool:0xb586ab70 Media Starting thread Media Patch:0xb5829b70
2010/12/13 13:02:45.189 Pool:0xb586ab70 PWLib File handle high water mark set: 60 Thread unblock pipe
2010/12/13 13:02:45.189 Pool:0xb586ab70 PTLib Thread high water mark set: 24
2010/12/13 13:02:45.190 Media Patch:0xb57e8b70 Patch Thread started for Patch AudioModemMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-G.711-uLaw-64k
2010/12/13 13:02:45.190 Media Patch:0xb57e8b70 RTP Session 1, first sent data: ver=2 pt=PCMU psz=160 m=1 x=0 seq=39023 ts=0 src="" ccnt=0
2010/12/13 13:02:45.190 Pool:0xb586ab70 Media Starting thread Media Patch:0xb57e8b70
2010/12/13 13:02:45.190 Pool:0xb586ab70 OpalCon Media stream threads started.
2010/12/13 13:02:45.190 Pool:0xb586ab70 SIP Handled PDU "2 INVITE <183>"
2010/12/13 13:02:45.401 Media Patch:0xb5829b70 RTP Session 1, first receive data: ver=2 pt=PCMU psz=160 m=1 x=0 seq=64012 ts=1548990808 src="" ccnt=0
2010/12/13 13:02:46.394 Media Patch:0xb5829b70 Patch Removing comfort noise frame with payload type CN
2010/12/13 13:02:46.419 Media Patch:0xb5829b70 Patch Removing comfort noise frame with payload type CN
2010/12/13 13:02:46.434 Media Patch:0xb5829b70 Patch Removing comfort noise frame with payload type CN
2010/12/13 13:02:46.464 Media Patch:0xb5829b70 Patch Removing comfort noise frame with payload type CN
2010/12/13 13:02:46.478 Network In...0xb5c45b70 PWLib File handle high water mark set: 61 PUDPSocket
2010/12/13 13:02:46.495 Media Patch:0xb5829b70 Patch Removing comfort noise frame with payload type CN
2010/12/13 13:02:47.190 Media Patch:0xb57e8b70 RTP Session 1, transmit statistics: packets=101 octets=16160 avgTime=20 maxTime=21 minTime=19
2010/12/13 13:02:47.453 Media Patch:0xb5829b70 RTP Session 1, receive statistics: packets=102 octets=15525 lost=0 tooLate=0 order=0 avgTime=20 maxTime=30 minTime=10 jitter=1 maxJitter=3
2010/12/13 13:02:48.284 Media Patch:0xb5829b70 Patch Removing comfort noise frame with payload type CN
2010/12/13 13:02:49.183 Housekeeper:0xb5c04b70 SIP Set state Terminated_Success for INVITE transaction id=z9hG4bKfc1b1ebe-ca04-e011-9ed4-000c2984a855
2010/12/13 13:02:49.190 Media Patch:0xb57e8b70 RTP Session 1, transmit statistics: packets=201 octets=32160 avgTime=20 maxTime=21 minTime=18
2010/12/13 13:02:50.860 Media Patch:0xb5829b70 RTP Session 1, receive statistics: packets=203 octets=31526 lost=0 tooLate=0 order=0 avgTime=19 maxTime=26 minTime=10 jitter=0 maxJitter=3
2010/12/13 13:02:51.067 Media Patch:0xb5829b70 Patch Removing comfort noise frame with payload type CN
2010/12/13 13:02:51.189 Media Patch:0xb57e8b70 RTP Session 1, transmit statistics: packets=301 octets=48160 avgTime=19 maxTime=21 minTime=19
2010/12/13 13:02:53.190 Media Patch:0xb57e8b70 RTP Session 1, transmit statistics: packets=401 octets=64160 avgTime=20 maxTime=23 minTime=17
2010/12/13 13:02:53.452 Opal Liste...0xb5bc3b70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 13:02:53.452 Opal Liste...0xb5bc3b70 SIP Waiting for PDU on udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
2010/12/13 13:02:53.452 Opal Liste...0xb5bc3b70 SIP PDU received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0CSeq: 2 INVITE
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.11.12.22:5060;received=203.206.207.88;branch=z9hG4bK66ab2ebe-ca04-e011-9ed4-000c2984a855;rport=39236
From: "root" <sip:<my_username>@sip.nsw.iinet.net.au>;tag=aa5f1dbe-ca04-e011-9ed4-000c2984a855
Call-ID: 32691dbe-ca04-e011-9ed4-000c2984a855@xxxxxxxxxxxxxxxxxxx
Supported:
To: <sip:0294396443@xxxxxxxxxxxxxxxxxxxx>;tag=SDj9kvc99-17694676-1292205766573
Contact: <sip:0294396443@xxxxxxxxxxxxxx:5060;transport=udp>
Accept: multipart/mixed,application/media_control+xml,application/sdp
Allow: ACK,BYE,CANCEL,INFO,INVITE,OPTIONS,PRACK,REFER,NOTIFY,UPDATE
Content-Type: application/sdpContent-Length: 224o=BroadWorks 110018254 1 IN IP4 203.55.231.194
v=0m=audio 35202 RTP/AVP 0 101
s=-
c=IN IP4 203.55.231.194
t=0 02010/12/13 13:02:53.453 Opal Liste...0xb5bc3b70 SIP Queueing PDU "2 INVITE <200>", transaction=z9hG4bK66ab2ebe-ca04-e011-9ed4-000c2984a855, token=aa5f1dbe-ca04-e011-9ed4-000c2984a855
c=IN IP4 203.55.231.194
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16,32,36
2010/12/13 13:02:53.453 Opal Liste...0xb5bc3b70 Opal Transport clean up on termination
2010/12/13 13:02:53.453 Opal Liste...0xb5bc3b70 Opal Transport Close
2010/12/13 13:02:53.453 Opal Liste...0xb5bc3b70 Opal Deleted transport udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
2010/12/13 13:02:53.453 Pool:0xb586ab70 SIP Handling PDU "2 INVITE <200>" for transaction=z9hG4bK66ab2ebe-ca04-e011-9ed4-000c2984a855
2010/12/13 13:02:53.453 Pool:0xb586ab70 SIP Set Request URI to sip:0294396443@xxxxxxxxxxxxxx:5060;transport=udp
2010/12/13 13:02:53.454 Pool:0xb586ab70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 13:02:53.454 Pool:0xb586ab70 SDP Parsed connection address udp$203.55.231.194
2010/12/13 13:02:53.454 Pool:0xb586ab70 SDP Parsed connection address udp$203.55.231.194
2010/12/13 13:02:53.454 Pool:0xb586ab70 SDP Media session port=35202
2010/12/13 13:02:53.454 Pool:0xb586ab70 SDP Parsed connection address udp$203.55.231.194:35202
2010/12/13 13:02:53.454 Pool:0xb586ab70 SDP Parsed media session with 2 'audio' formats
2010/12/13 13:02:53.454 Pool:0xb586ab70 SIP No User-Agent or Server fields, Product Info unknown.
2010/12/13 13:02:53.454 Pool:0xb586ab70 SIP Transaction remote address changed to udp$sip.nsw.iinet.net.au:5060
2010/12/13 13:02:53.457 Pool:0xb586ab70 SIP Adding authentication information for user "<my_username>" at realm "iinetphone.iinet.net.au"
2010/12/13 13:02:53.457 Pool:0xb586ab70 SIP Sending PDU (816 bytes) to: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
ACK sip:0294396443@xxxxxxxxxxxxxx:5060;transport=udp SIP/2.0CSeq: 2 ACK
Route: <sip:sip.nsw.iinet.net.au:5060;lr>
Via: SIP/2.0/UDP 10.11.12.22:5060;branch=z9hG4bKdeffb6c3-ca04-e011-9ed4-000c2984a855;rport
Authorization: Digest username="<my_username>", realm="iinetphone.iinet.net.au", nonce="BroadWorksXghmpy5z3Th3c5aqBW", uri="sip:0294396443@xxxxxxxxxxxxxx:5060", algorithm=MD5, response="65a0618c62d1642e9abe0c2bd1c9d2da", cnonce="1c502ebe-ca04-e011-9ed4-000c2984a855", nc=00000002, qop=auth
From: "root" <sip:<my_username>@sip.nsw.iinet.net.au>;tag=aa5f1dbe-ca04-e011-9ed4-000c2984a855
Call-ID: 32691dbe-ca04-e011-9ed4-000c2984a855@xxxxxxxxxxxxxxxxxxx
To: <sip:0294396443@xxxxxxxxxxxxxxxxxxxx>;tag=SDj9kvc99-17694676-1292205766573Content-Length: 02010/12/13 13:02:53.457 Pool:0xb586ab70 OpalUDP Setting interface to 10.11.12.22%eth0
Max-Forwards: 70
2010/12/13 13:02:53.457 Pool:0xb586ab70 SIP INVITE transaction id=z9hG4bK66ab2ebe-ca04-e011-9ed4-000c2984a855 completed.
2010/12/13 13:02:53.457 Pool:0xb586ab70 SIP Handling 200 OK response for INVITE
2010/12/13 13:02:53.458 Pool:0xb586ab70 SIP Received INVITE OK response
2010/12/13 13:02:53.458 Pool:0xb586ab70 SIP Processing received SDP media description for audio
2010/12/13 13:02:53.458 Pool:0xb586ab70 SIP RTP payload type PCMU matched to codec G.711-uLaw-64k
2010/12/13 13:02:53.458 Pool:0xb586ab70 MediaFormat Removing codecs
2010/12/13 13:02:53.458 Pool:0xb586ab70 Call IsMediaBypassPossible Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855] session 1
2010/12/13 13:02:53.458 Pool:0xb586ab70 OpalMan IsMediaBypassPossible: session 1
2010/12/13 13:02:53.458 Pool:0xb586ab70 OpalCon IsMediaBypassPossible: default returns false
2010/12/13 13:02:53.459 Pool:0xb586ab70 RTP Found existing RTP session 1
2010/12/13 13:02:53.459 Pool:0xb586ab70 RTP Found existing media session 1
2010/12/13 13:02:53.459 Pool:0xb586ab70 OpalCon SetPhase from AlertingPhase to ConnectedPhase for Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855]
2010/12/13 13:02:53.459 Pool:0xb586ab70 OpalCon OnConnected for Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855]
2010/12/13 13:02:53.459 Pool:0xb586ab70 OpalMan OnConnected Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855]
2010/12/13 13:02:53.459 Pool:0xb586ab70 Call OnConnected Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855]
2010/12/13 13:02:53.459 Pool:0xb586ab70 ModemConnection::SetConnected Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0]
2010/12/13 13:02:53.459 Pool:0xb586ab70 OpalCon SetConnected for Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0]
2010/12/13 13:02:53.459 Pool:0xb586ab70 OpalCon SetPhase from AlertingPhase to ConnectedPhase for Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0]
2010/12/13 13:02:53.459 Pool:0xb586ab70 OpalCon SetPhase from ConnectedPhase to EstablishedPhase for Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0]
2010/12/13 13:02:53.459 Pool:0xb586ab70 ModemConnection::OnEstablished Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0]
2010/12/13 13:02:53.459 Pool:0xb586ab70 ModemEngineBody::Request stConnectWait request={
calltoken=modem:/k5d40bf6b1/0
command=established
}
2010/12/13 13:02:53.459 Pool:0xb586ab70 OpalCon OnEstablished Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0]
2010/12/13 13:02:53.459 Pool:0xb586ab70 OpalCon Media stream threads started.
2010/12/13 13:02:53.459 Pool:0xb586ab70 OpalMan OnEstablished Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0]
2010/12/13 13:02:53.459 Pool:0xb586ab70 Call OnEstablished Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0]
2010/12/13 13:02:53.459 Pool:0xb586ab70 OpalCon Media stream threads started.
2010/12/13 13:02:53.460 Pool:0xb586ab70 OpalCon SetPhase from ConnectedPhase to EstablishedPhase for Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855]
2010/12/13 13:02:53.460 Pool:0xb586ab70 OpalCon OnEstablished Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855]
2010/12/13 13:02:53.460 Pool:0xb586ab70 OpalCon Media stream threads started.
2010/12/13 13:02:53.460 Pool:0xb586ab70 OpalMan OnEstablished Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855]
2010/12/13 13:02:53.460 Pool:0xb586ab70 Call OnEstablished Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855]
2010/12/13 13:02:53.460 Pool:0xb586ab70 OpalCon Media stream threads started.
2010/12/13 13:02:53.460 Pool:0xb586ab70 SIP Handled PDU "2 INVITE <200>"
2010/12/13 13:02:53.754 Media Patch:0xb5829b70 Patch Removing comfort noise frame with payload type CN
2010/12/13 13:02:54.461 Housekeeper:0xb5c04b70 ModemEngineBody::OnTimerCallback stConnectHandle Timeout 0
2010/12/13 13:02:54.461 ttyT38a(e)...0xb5b82b70 ModemEndPoint::OnMyCallback command=requestmode extra=4
2010/12/13 13:02:54.461 ttyT38a(e)...0xb5b82b70 Call GetOtherPartyConnection Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0]
2010/12/13 13:02:54.461 ttyT38a(e)...0xb5b82b70 PWLib File handle high water mark set: 62 Thread unblock pipe
2010/12/13 13:02:54.461 ttyT38a(e)...0xb5b82b70 PTLib Thread high water mark set: 25
2010/12/13 13:02:54.461 ttyT38a(e)...0xb5b82b70 MyManager::RequestModeChange(Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0], fax) RequestModeChange(Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855]) - OK
2010/12/13 13:02:54.461 ttyT38a(e)...0xb5b82b70 ModemEndPoint::OnMyCallback request={
calltoken=modem:/k5d40bf6b1/0
mode=fax
command=requestmode
response=confirm
modemtoken=ttyT38a
}
2010/12/13 13:02:54.469 PThread1Arg:0xb57a7b70 Call OpenSourceMediaStreams replacing fax session 1 on Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855]
2010/12/13 13:02:54.469 PThread1Arg:0xb57a7b70 ModemConnection::GetMediaFormats Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0]
2010/12/13 13:02:54.469 PThread1Arg:0xb57a7b70 ModemEndPoint::GetMediaFormats
2010/12/13 13:02:54.469 PThread1Arg:0xb57a7b70 ModemConnection::GetMediaFormats Call[k5d40bf6b1]-EP<modem>[modem:/k5d40bf6b1/0]
2010/12/13 13:02:54.469 PThread1Arg:0xb57a7b70 ModemEndPoint::GetMediaFormats
2010/12/13 13:02:54.471 PThread1Arg:0xb57a7b70 MediaFormat Removing codecs
2010/12/13 13:02:54.471 PThread1Arg:0xb57a7b70 MediaFormat Removing codecs
2010/12/13 13:02:54.471 PThread1Arg:0xb57a7b70 Call GetMediaFormats for Call[k5d40bf6b1]-EP<sip>[aa5f1dbe-ca04-e011-9ed4-000c2984a855]2010/12/13 13:02:54.471 PThread1Arg:0xb57a7b70 MediaFormat Merging T.38 into T.38
G.711-uLaw-64k
G.711-ALaw-64k
PCM-16
T.38
2010/12/13 13:02:54.472 PThread1Arg:0xb57a7b70 MediaFormat Merging T.38 into T.38
2010/12/13 13:02:54.472 PThread1Arg:0xb57a7b70 MediaFormat Merging T.38 into T.38
2010/12/13 13:02:54.472 PThread1Arg:0xb57a7b70 MediaFormat Merging T.38 into T.38
2010/12/13 13:02:54.472 PThread1Arg:0xb57a7b70 Call Selected media formats T.38 -> T.38
2010/12/13 13:02:54.472 PThread1Arg:0xb57a7b70 Media Closing stream AudioModemMediaStream-Source-PCM-16
2010/12/13 13:02:54.472 PThread1Arg:0xb57a7b70 Media Disconnecting AudioModemMediaStream-Source-PCM-16 from patch thread Patch AudioModemMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-G.711-uLaw-64k
2010/12/13 13:02:54.472 PThread1Arg:0xb57a7b70 Patch Closing media patch Patch AudioModemMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-G.711-uLaw-64k
2010/12/13 13:02:54.472 PThread1Arg:0xb57a7b70 Media Closing RTP for OpalRTPMediaStream-Sink-G.711-uLaw-64k
2010/12/13 13:02:54.472 PThread1Arg:0xb57a7b70 RTP_UDP Session 1, Shutting down write.
2010/12/13 13:02:54.472 PThread1Arg:0xb57a7b70 Media Closing stream OpalRTPMediaStream-Sink-G.711-uLaw-64k
2010/12/13 13:02:54.472 PThread1Arg:0xb57a7b70 Media Disconnecting OpalRTPMediaStream-Sink-G.711-uLaw-64k from patch thread Patch AudioModemMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-G.711-uLaw-64k
2010/12/13 13:02:54.472 PThread1Arg:0xb57a7b70 Patch Removing media stream sink OpalRTPMediaStream-Sink-G.711-uLaw-64k
2010/12/13 13:02:54.472 PThread1Arg:0xb57a7b70 OpalCon Removed media stream OpalRTPMediaStream-Sink-G.711-uLaw-64k
2010/12/13 13:02:54.472 PThread1Arg:0xb57a7b70 Patch Wai
- Follow-Ups:
- Re: [hylafax-users] t38modem & hylafax issue
- From: John Park
- Re: [hylafax-users] t38modem & hylafax issue
- References:
- Re: [hylafax-users] t38modem & hylafax issue
- From: John Park
- Re: [hylafax-users] t38modem & hylafax issue
- From: John Park
- Re: [hylafax-users] t38modem & hylafax issue
- From: John Park
- Re: [hylafax-users] t38modem & hylafax issue
- Prev by Date: Re: [hylafax-users] t38modem & hylafax issue
- Next by Date: Re: [hylafax-users] t38modem & hylafax issue
- Previous by thread: Re: [hylafax-users] t38modem & hylafax issue
- Next by thread: Re: [hylafax-users] t38modem & hylafax issue
- Index(es):
Project hosted by iFAX Solutions