Thanks again for your response Robert.
I managed to get the SIP registered so this is mainly for people doing google searches for t38modem on iinet. The arguments I used to get t38modem registering with iinet's iiphone service 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/ttyT38-1,+/dev/ttyT38-2,+/dev/ttyT38-3 --route 'modem:.*=sip:<dn>@sip.nsw.iinet.net.au' -u <my_username>
Note the extra arguments required for --sip-register
Now I can successfully make a call to my mobile and I can hear the fax tones but now the fax won't send...
Logs below:
2010/12/13 10:10:57.126 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 10:10:57.126
2010/12/13 10:10:57.126 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 10:10:57.126 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/ttyT38-1,+/dev/ttyT38-2,+/dev/ttyT38-3 --route modem:.*=sip:<dn>@sip.nsw.iinet.net.au -u <my_username>
2010/12/13 10:10:57.126 T38Modem Config:
2010/12/13 10:10:57.126 T38Modem --no-h323=True
2010/12/13 10:10:57.126 T38Modem --sip-proxy=<my_username>:<my_password>@sip.nsw.iinet.net.au
2010/12/13 10:10:57.126 T38Modem --sip-register=<my_username>@sip.nsw.iinet.net.au,<my_password>,<my_username>,iinetphone.iinet.net.au,<my_username>
2010/12/13 10:10:57.126 T38Modem --ptty=+/dev/ttyT38-1,+/dev/ttyT38-2,+/dev/ttyT38-3
2010/12/13 10:10:57.127 T38Modem --route=modem:.*=sip:<dn>@sip.nsw.iinet.net.au
2010/12/13 10:10:57.127 T38Modem --username=<my_username>
2010/12/13 10:10:57.127 T38Modem --trace=True
2010/12/13 10:10:57.127 T38Modem --output=/var/log/t38modem.log
2010/12/13 10:10:57.127 T38Modem Detecting V4L2 devices
2010/12/13 10:10:57.127 T38Modem Unable to detect v4l2 directory
2010/12/13 10:10:57.127 PXConfigWr...0xb5d04b70 PTLib Config file cache write back thread started.
2010/12/13 10:10:57.140 T38Modem PWLib File handle high water mark set: 9 Thread unblock pipe
2010/12/13 10:10:57.140 T38Modem PTLib Thread high water mark set: 3
2010/12/13 10:10:57.140 T38Modem OpalMan Created manager.
2010/12/13 10:10:57.140 T38Modem OpalMan Attached endpoint with prefix modem
2010/12/13 10:10:57.140 T38Modem OpalEP Created endpoint: modem
2010/12/13 10:10:57.140 T38Modem ModemEndPoint::ModemEndPoint
2010/12/13 10:10:57.140 T38Modem PWLib File handle high water mark set: 11 Thread unblock pipe
2010/12/13 10:10:57.140 T38Modem PseudoModemList::Append ttyT38-1 (0) OK
2010/12/13 10:10:57.140 T38Modem PTLib Thread high water mark set: 4
2010/12/13 10:10:57.140 T38Modem PWLib File handle high water mark set: 13 Thread unblock pipe
2010/12/13 10:10:57.140 T38Modem PseudoModemList::Append ttyT38-2 (1) OK
2010/12/13 10:10:57.140 T38Modem PTLib Thread high water mark set: 5
2010/12/13 10:10:57.141 T38Modem PWLib File handle high water mark set: 15 Thread unblock pipe
2010/12/13 10:10:57.141 T38Modem PseudoModemList::Append ttyT38-3 (2) OK
2010/12/13 10:10:57.141 T38Modem PTLib Thread high water mark set: 6
2010/12/13 10:10:57.141 T38Modem OpalMan Attached endpoint with prefix sip
2010/12/13 10:10:57.141 T38Modem OpalEP Created endpoint: sip
2010/12/13 10:10:57.141 T38Modem PWLib File handle high water mark set: 16 PUDPSocket
2010/12/13 10:10:57.141 T38Modem IfaceMon Initial interface list:
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 10:10:57.141 T38Modem PWLib File handle high water mark set: 17 Thread unblock pipe
2010/12/13 10:10:57.141 T38Modem PTLib Thread high water mark set: 7
2010/12/13 10:10:57.141 T38Modem PWLib File handle high water mark set: 19 Thread unblock pipe
2010/12/13 10:10:57.141 T38Modem PTLib Thread high water mark set: 8
2010/12/13 10:10:57.141 T38Modem OpalMan Attached endpoint with prefix sips
2010/12/13 10:10:57.141 T38Modem SIP Created endpoint.
2010/12/13 10:10:57.142 T38Modem PWLib File handle high water mark set: 20 PUDPSocket
2010/12/13 10:10:57.142 T38Modem MonSock Created socket bundle for all interfaces.
2010/12/13 10:10:57.142 T38Modem PWLib File handle high water mark set: 21 PUDPSocket
2010/12/13 10:10:57.142 T38Modem MonSock Created bundled UDP socket 10.11.12.22:5060
2010/12/13 10:10:57.142 T38Modem PWLib File handle high water mark set: 23 Thread unblock pipe
2010/12/13 10:10:57.142 T38Modem PTLib Thread high water mark set: 9
2010/12/13 10:10:57.142 Opal Liste...0xb5b3db70 Listen Started listening thread on udp$*:5060
2010/12/13 10:10:57.142 ttyT38-1(b...0xb5c82b70 RenameCurrentThread old ThreadName=PseudoModemPty:0xb5c82b70
2010/12/13 10:10:57.142 ttyT38-1(b...0xb5c82b70 Started for /dev/ttyT38-1 (accepts all)
2010/12/13 10:10:57.142 ttyT38-1(b...0xb5c82b70 ModemEndPoint::OnMyCallback command=addmodem extra=10
2010/12/13 10:10:57.143 ttyT38-1(b...0xb5c82b70 PseudoModemQ::Enqueue ttyT38-1
2010/12/13 10:10:57.143 ttyT38-1(b...0xb5c82b70 ModemEndPoint::OnMyCallback request={
command=addmodem
response=confirm
modemtoken=ttyT38-1
}
2010/12/13 10:10:57.143 ttyT38-1(b...0xb5c82b70 PseudoModemPty::OpenPty removed link /dev/ttyT38-1 -> /dev/pts/1
2010/12/13 10:10:57.143 ttyT38-1(b...0xb5c82b70 PseudoModemPty::OpenPty added link /dev/ttyT38-1 -> /dev/pts/1
2010/12/13 10:10:57.143 ttyT38-1(b...0xb5c82b70 PWLib File handle high water mark set: 26 Thread unblock pipe
2010/12/13 10:10:57.143 ttyT38-1(b...0xb5c82b70 PWLib File handle high water mark set: 28 Thread unblock pipe
2010/12/13 10:10:57.143 ttyT38-1(b...0xb5c82b70 PWLib File handle high water mark set: 30 Thread unblock pipe
2010/12/13 10:10:57.143 ttyT38-1(b...0xb5c82b70 PTLib Thread high water mark set: 10
2010/12/13 10:10:57.143 ttyT38-1(b...0xb5c82b70 PTLib Thread high water mark set: 11
2010/12/13 10:10:57.143 ttyT38-1(b...0xb5c82b70 PTLib Thread high water mark set: 12
2010/12/13 10:10:57.143 T38Modem PWLib File handle high water mark set: 31 PTCPSocket
2010/12/13 10:10:57.143 T38Modem PWLib File handle high water mark set: 33 Thread unblock pipe
2010/12/13 10:10:57.143 T38Modem PTLib Thread high water mark set: 13
2010/12/13 10:10:57.144 T38Modem PWLib File handle high water mark set: 34 PTCPSocket
2010/12/13 10:10:57.144 T38Modem PWLib File handle high water mark set: 36 Thread unblock pipe
2010/12/13 10:10:57.144 T38Modem PTLib Thread high water mark set: 14
2010/12/13 10:10:57.144 T38Modem SIP Start REGISTER contact=<my_username>
authID= expire=300
restore=30
minRetry=596:31:23.647
maxRetry=596:31:23.647
2010/12/13 10:10:57.144 T38Modem PWLib File handle high water mark set: 37 PUDPSocket
2010/12/13 10:10:57.144 T38Modem SIP Changing REGISTER handler from Unavailable to Subscribing, target=sip:<my_username>@sip.nsw.iinet.net.au, id=d6a7b6be-b204-e011-962e-000c2984a855@xxxxxxxxxxxxxxxxxxx
2010/12/13 10:10:57.145 ttyT38-2(b...0xb5c41b70 RenameCurrentThread old ThreadName=PseudoModemPty:0xb5c41b70
2010/12/13 10:10:57.145 ttyT38-2(b...0xb5c41b70 Started for /dev/ttyT38-2 (accepts all)
2010/12/13 10:10:57.145 ttyT38-2(b...0xb5c41b70 ModemEndPoint::OnMyCallback command=addmodem extra=10
2010/12/13 10:10:57.145 ttyT38-2(b...0xb5c41b70 PseudoModemQ::Enqueue ttyT38-2
2010/12/13 10:10:57.145 ttyT38-2(b...0xb5c41b70 ModemEndPoint::OnMyCallback request={
command=addmodem
response=confirm
modemtoken=ttyT38-2
}
2010/12/13 10:10:57.145 ttyT38-2(b...0xb5c41b70 PseudoModemPty::OpenPty removed link /dev/ttyT38-2 -> /dev/pts/2
2010/12/13 10:10:57.145 ttyT38-2(b...0xb5c41b70 PseudoModemPty::OpenPty added link /dev/ttyT38-2 -> /dev/pts/2
2010/12/13 10:10:57.145 ttyT38-2(b...0xb5c41b70 PWLib File handle high water mark set: 40 Thread unblock pipe
2010/12/13 10:10:57.146 ttyT38-2(b...0xb5c41b70 PWLib File handle high water mark set: 42 Thread unblock pipe
2010/12/13 10:10:57.146 ttyT38-2(b...0xb5c41b70 PWLib File handle high water mark set: 44 Thread unblock pipe
2010/12/13 10:10:57.146 ttyT38-2(b...0xb5c41b70 PTLib Thread high water mark set: 15
2010/12/13 10:10:57.146 ttyT38-2(b...0xb5c41b70 PTLib Thread high water mark set: 16
2010/12/13 10:10:57.146 ttyT38-2(b...0xb5c41b70 PTLib Thread high water mark set: 17
2010/12/13 10:10:57.146 ttyT38-3(b...0xb5c00b70 RenameCurrentThread old ThreadName=PseudoModemPty:0xb5c00b70
2010/12/13 10:10:57.146 ttyT38-3(b...0xb5c00b70 Started for /dev/ttyT38-3 (accepts all)
2010/12/13 10:10:57.146 ttyT38-3(b...0xb5c00b70 ModemEndPoint::OnMyCallback command=addmodem extra=10
2010/12/13 10:10:57.146 ttyT38-3(b...0xb5c00b70 PseudoModemQ::Enqueue ttyT38-3
2010/12/13 10:10:57.146 ttyT38-3(b...0xb5c00b70 ModemEndPoint::OnMyCallback request={
command=addmodem
response=confirm
modemtoken=ttyT38-3
}
2010/12/13 10:10:57.146 ttyT38-3(b...0xb5c00b70 PseudoModemPty::OpenPty removed link /dev/ttyT38-3 -> /dev/pts/3
2010/12/13 10:10:57.146 ttyT38-3(b...0xb5c00b70 PseudoModemPty::OpenPty added link /dev/ttyT38-3 -> /dev/pts/3
2010/12/13 10:10:57.146 ttyT38-3(b...0xb5c00b70 PWLib File handle high water mark set: 47 Thread unblock pipe
2010/12/13 10:10:57.146 ttyT38-3(b...0xb5c00b70 PWLib File handle high water mark set: 49 Thread unblock pipe
2010/12/13 10:10:57.146 ttyT38-3(b...0xb5c00b70 PWLib File handle high water mark set: 51 Thread unblock pipe
2010/12/13 10:10:57.146 ttyT38-3(b...0xb5c00b70 PTLib Thread high water mark set: 18
2010/12/13 10:10:57.146 ttyT38-3(b...0xb5c00b70 PTLib Thread high water mark set: 19
2010/12/13 10:10:57.146 ttyT38-3(b...0xb5c00b70 PTLib Thread high water mark set: 20
2010/12/13 10:10:57.146 Network In...0xb5bbfb70 IfaceMon Started interface monitor thread.
2010/12/13 10:10:57.146 Network In...0xb5bbfb70 PWLib File handle high water mark set: 52 PUDPSocket
2010/12/13 10:10:57.146 ttyT38-1(e...0xb5afcb70 RenameCurrentThread old ThreadName=ModemEngine:0xb5afcb70
2010/12/13 10:10:57.146 ttyT38-1(e...0xb5afcb70 <-> Started
2010/12/13 10:10:57.146 ttyT38-1(i...0xb5abbb70 RenameCurrentThread old ThreadName=InPty:0xb5abbb70
2010/12/13 10:10:57.146 ttyT38-1(i...0xb5abbb70 --> Started
2010/12/13 10:10:57.146 ttyT38-1(o...0xb5a7ab70 RenameCurrentThread old ThreadName=OutPty:0xb5a7ab70
2010/12/13 10:10:57.146 ttyT38-1(o...0xb5a7ab70 <-- Started
2010/12/13 10:10:57.147 Opal Liste...0xb5a39b70 Listen Started listening thread on tcp$*:5060
2010/12/13 10:10:57.147 Opal Liste...0xb5a39b70 Listen Waiting on socket accept on tcp$*:5060
2010/12/13 10:10:57.147 Opal Liste...0xb59f8b70 Listen Started listening thread on tcps$*:5061
2010/12/13 10:10:57.147 Opal Liste...0xb59f8b70 TCPS Waiting on socket accept on tcps$*:5061
2010/12/13 10:10:57.147 ttyT38-2(e...0xb59abb70 RenameCurrentThread old ThreadName=ModemEngine:0xb59abb70
2010/12/13 10:10:57.147 ttyT38-2(e...0xb59abb70 <-> Started
2010/12/13 10:10:57.147 ttyT38-2(i...0xb596ab70 RenameCurrentThread old ThreadName=InPty:0xb596ab70
2010/12/13 10:10:57.147 ttyT38-2(i...0xb596ab70 --> Started
2010/12/13 10:10:57.147 ttyT38-2(o...0xb5929b70 RenameCurrentThread old ThreadName=OutPty:0xb5929b70
2010/12/13 10:10:57.147 ttyT38-2(o...0xb5929b70 <-- Started
2010/12/13 10:10:57.147 ttyT38-3(e...0xb58e8b70 RenameCurrentThread old ThreadName=ModemEngine:0xb58e8b70
2010/12/13 10:10:57.147 ttyT38-3(e...0xb58e8b70 <-> Started
2010/12/13 10:10:57.147 ttyT38-3(i...0xb58a7b70 RenameCurrentThread old ThreadName=InPty:0xb58a7b70
2010/12/13 10:10:57.147 ttyT38-3(i...0xb58a7b70 --> Started
2010/12/13 10:10:57.147 ttyT38-3(o...0xb5866b70 RenameCurrentThread old ThreadName=OutPty:0xb5866b70
2010/12/13 10:10:57.147 ttyT38-3(o...0xb5866b70 <-- Started
2010/12/13 10:10:57.148 T38Modem OpalUDP Binding to interface: 0.0.0.0:5060
2010/12/13 10:10:57.148 T38Modem PWLib File handle low water mark set: 37 PUDPSocket
2010/12/13 10:10:57.148 T38Modem SIP Created transport udp$203.55.231.194:5060<if=udp$*:5060>
2010/12/13 10:10:57.148 T38Modem OpalUDP Started connect to 203.55.231.194:5060
2010/12/13 10:10:57.148 T38Modem OpalUDP Writing to interface 0 - "10.11.12.22%eth0"
2010/12/13 10:10:57.148 T38Modem SIP Transaction created.
2010/12/13 10:10:57.149 T38Modem SIP No SRV lookup as has explicit port number.
2010/12/13 10:10:57.149 T38Modem SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 10:10:57.150 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=z9hG4bK126bb7be-b204-e011-962e-000c2984a855;rport
User-Agent: T38Modem/1.2.0
From: <sip:<my_username>@sip.nsw.iinet.net.au>;tag=c6b2b6be-b204-e011-962e-000c2984a855
Call-ID: d6a7b6be-b204-e011-962e-000c2984a855@xxxxxxxxxxxxxxxxxxx
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 10:10:57.150 T38Modem OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 10:10:57.150 T38Modem SIP Transaction timers set: retry=0.500, completion=16.000
2010/12/13 10:10:57.150 T38Modem OpalMan Added route "modem:.*=sip:<dn>@sip.nsw.iinet.net.au"
2010/12/13 10:10:57.150 T38Modem PTLib Destroyed PXConfig 0x88bb810
2010/12/13 10:10:57.173 Opal Liste...0xb5b3db70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 10:10:57.173 Opal Liste...0xb5b3db70 SIP Waiting for PDU on udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
2010/12/13 10:10:57.174 Opal Liste...0xb5b3db70 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 10:10:57.174 Opal Liste...0xb5b3db70 SIP PDU received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
SIP/2.0 200 OK
CSeq: 1 REGISTER
Via: SIP/2.0/UDP 10.11.12.22:5060;received=203.206.207.88;branch=z9hG4bK126bb7be-b204-e011-962e-000c2984a855;rport=39236
From: <sip:<my_username>@sip.nsw.iinet.net.au>;tag=c6b2b6be-b204-e011-962e-000c2984a855
Call-ID: d6a7b6be-b204-e011-962e-000c2984a855@xxxxxxxxxxxxxxxxxxx
To: <sip:<my_username>@sip.nsw.iinet.net.au>;tag=aprqdegpo42-quaclv2000020
Contact: <sip:<my_username>>;expires=30
2010/12/13 10:10:57.174 Opal Liste...0xb5b3db70 SIP Queueing PDU "1 REGISTER <200>", transaction=z9hG4bK126bb7be-b204-e011-962e-000c2984a855, token=d6a7b6be-b204-e011-962e-000c2984a855@xxxxxxxxxxxxxxxxxxx
2010/12/13 10:10:57.174 Opal Liste...0xb5b3db70 PTLib Thread high water mark set: 21
2010/12/13 10:10:57.174 Opal Liste...0xb5b3db70 Opal Transport clean up on termination
2010/12/13 10:10:57.174 Opal Liste...0xb5b3db70 Opal Transport Close
2010/12/13 10:10:57.174 Opal Liste...0xb5b3db70 PWLib File handle high water mark set: 53 PUDPSocket
2010/12/13 10:10:57.174 Opal Liste...0xb5b3db70 Opal Deleted transport udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
2010/12/13 10:10:57.175 Pool:0xb5825b70 SIP Handling PDU "1 REGISTER <200>" for transaction=z9hG4bK126bb7be-b204-e011-962e-000c2984a855
2010/12/13 10:10:57.175 Pool:0xb5825b70 SIP REGISTER transaction id=z9hG4bK126bb7be-b204-e011-962e-000c2984a855 completed.
2010/12/13 10:10:57.175 Pool:0xb5825b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 10:10:57.175 Pool:0xb5825b70 SIP No User-Agent or Server fields, Product Info unknown.
2010/12/13 10:10:57.175 Pool:0xb5825b70 SIP Changing REGISTER handler from Subscribing to Subscribed, target=sip:<my_username>@sip.nsw.iinet.net.au, id=d6a7b6be-b204-e011-962e-000c2984a855@xxxxxxxxxxxxxxxxxxx
2010/12/13 10:10:57.175 Pool:0xb5825b70 SIP Expiry time for REGISTER set to 30 seconds.
2010/12/13 10:10:57.175 Pool:0xb5825b70 SIP No User-Agent or Server fields, Product Info unknown.
2010/12/13 10:10:57.175 Pool:0xb5825b70 SIP Handled PDU "1 REGISTER <200>"
2010/12/13 10:11:02.175 Housekeeper:0xb5b7eb70 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bK126bb7be-b204-e011-962e-000c2984a855
2010/12/13 10:11:03.150 Opal Garbage:0xb5cc3b70 SIP Transaction id=z9hG4bK126bb7be-b204-e011-962e-000c2984a855 destroyed.
2010/12/13 10:11:12.175 Housekeeper:0xb5b7eb70 SIP Starting REGISTER for binding refresh
2010/12/13 10:11:12.175 Housekeeper:0xb5b7eb70 SIP Changing REGISTER handler from Subscribed to Refreshing, target=sip:<my_username>@sip.nsw.iinet.net.au, id=d6a7b6be-b204-e011-962e-000c2984a855@xxxxxxxxxxxxxxxxxxx
2010/12/13 10:11:12.175 Housekeeper:0xb5b7eb70 SIP Transaction created.
2010/12/13 10:11:12.176 Housekeeper:0xb5b7eb70 SIP No SRV lookup as has explicit port number.
2010/12/13 10:11:12.176 Housekeeper:0xb5b7eb70 SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 10:11:12.176 Housekeeper:0xb5b7eb70 SIP Sending PDU (611 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=z9hG4bK8851acc7-b204-e011-962e-000c2984a855;rport
User-Agent: T38Modem/1.2.0
From: <sip:<my_username>@sip.nsw.iinet.net.au>;tag=c6b2b6be-b204-e011-962e-000c2984a855
Call-ID: d6a7b6be-b204-e011-962e-000c2984a855@xxxxxxxxxxxxxxxxxxx
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: 30
Content-Length: 0
Max-Forwards: 70
2010/12/13 10:11:12.176 Housekeeper:0xb5b7eb70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 10:11:12.177 Housekeeper:0xb5b7eb70 SIP Transaction timers set: retry=0.500, completion=16.000
2010/12/13 10:11:12.200 Opal Liste...0xb5b3db70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 10:11:12.200 Opal Liste...0xb5b3db70 SIP Waiting for PDU on udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
2010/12/13 10:11:12.200 Opal Liste...0xb5b3db70 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 10:11:12.200 Opal Liste...0xb5b3db70 SIP PDU received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
SIP/2.0 200 OK
CSeq: 2 REGISTER
Via: SIP/2.0/UDP 10.11.12.22:5060;received=203.206.207.88;branch=z9hG4bK8851acc7-b204-e011-962e-000c2984a855;rport=39236
From: <sip:<my_username>@sip.nsw.iinet.net.au>;tag=c6b2b6be-b204-e011-962e-000c2984a855
Call-ID: d6a7b6be-b204-e011-962e-000c2984a855@xxxxxxxxxxxxxxxxxxx
To: <sip:<my_username>@sip.nsw.iinet.net.au>;tag=aprqdegpo42-quaclv2000040
Contact: <sip:<my_username>>;expires=30
2010/12/13 10:11:12.200 Opal Liste...0xb5b3db70 SIP Queueing PDU "2 REGISTER <200>", transaction=z9hG4bK8851acc7-b204-e011-962e-000c2984a855, token=d6a7b6be-b204-e011-962e-000c2984a855@xxxxxxxxxxxxxxxxxxx
2010/12/13 10:11:12.200 Opal Liste...0xb5b3db70 Opal Transport clean up on termination
2010/12/13 10:11:12.200 Opal Liste...0xb5b3db70 Opal Transport Close
2010/12/13 10:11:12.200 Opal Liste...0xb5b3db70 Opal Deleted transport udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
2010/12/13 10:11:12.201 Pool:0xb5825b70 SIP Handling PDU "2 REGISTER <200>" for transaction=z9hG4bK8851acc7-b204-e011-962e-000c2984a855
2010/12/13 10:11:12.201 Pool:0xb5825b70 SIP REGISTER transaction id=z9hG4bK8851acc7-b204-e011-962e-000c2984a855 completed.
2010/12/13 10:11:12.201 Pool:0xb5825b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 10:11:12.201 Pool:0xb5825b70 SIP No User-Agent or Server fields, Product Info unknown.
2010/12/13 10:11:12.201 Pool:0xb5825b70 SIP Changing REGISTER handler from Refreshing to Subscribed, target=sip:<my_username>@sip.nsw.iinet.net.au, id=d6a7b6be-b204-e011-962e-000c2984a855@xxxxxxxxxxxxxxxxxxx
2010/12/13 10:11:12.201 Pool:0xb5825b70 SIP Expiry time for REGISTER set to 30 seconds.
2010/12/13 10:11:12.201 Pool:0xb5825b70 SIP No User-Agent or Server fields, Product Info unknown.
2010/12/13 10:11:12.201 Pool:0xb5825b70 SIP Handled PDU "2 REGISTER <200>"
2010/12/13 10:11:16.219 ttyT38-1(e...0xb5afcb70 --> ATZ
2010/12/13 10:11:16.219 ttyT38-1(e...0xb5afcb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 10:11:17.202 Housekeeper:0xb5b7eb70 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bK8851acc7-b204-e011-962e-000c2984a855
2010/12/13 10:11:18.165 Opal Garbage:0xb5cc3b70 SIP Transaction id=z9hG4bK8851acc7-b204-e011-962e-000c2984a855 destroyed.
2010/12/13 10:11:19.327 ttyT38-1(e...0xb5afcb70 --> ATS0=0
2010/12/13 10:11:19.327 ttyT38-1(e...0xb5afcb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 10:11:19.435 ttyT38-1(e...0xb5afcb70 --> ATE0
2010/12/13 10:11:19.435 ttyT38-1(e...0xb5afcb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 10:11:19.543 ttyT38-1(e...0xb5afcb70 --> ATV1
2010/12/13 10:11:19.543 ttyT38-1(e...0xb5afcb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 10:11:19.651 ttyT38-1(e...0xb5afcb70 --> ATQ0
2010/12/13 10:11:19.651 ttyT38-1(e...0xb5afcb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 10:11:19.759 ttyT38-1(e...0xb5afcb70 --> ATS8=2
2010/12/13 10:11:19.759 ttyT38-1(e...0xb5afcb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 10:11:19.867 ttyT38-1(e...0xb5afcb70 --> ATS7=60
2010/12/13 10:11:19.867 ttyT38-1(e...0xb5afcb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 10:11:19.975 ttyT38-1(e...0xb5afcb70 --> AT+FCLASS=?
2010/12/13 10:11:19.975 ttyT38-1(e...0xb5afcb70 <-- {
0d 0a 31 2c 38 0d 0a 4f 4b 0d 0a ..1,8..OK.. }
2010/12/13 10:11:20.083 ttyT38-1(e...0xb5afcb70 --> AT+FCLASS=1
2010/12/13 10:11:20.083 ttyT38-1(e...0xb5afcb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 10:11:20.191 ttyT38-1(e...0xb5afcb70 --> ATI3
2010/12/13 10:11:20.191 ttyT38-1(e...0xb5afcb70 <-- {
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 10:11:20.299 ttyT38-1(e...0xb5afcb70 --> ATI0
2010/12/13 10:11:20.299 ttyT38-1(e...0xb5afcb70 <-- {
0d 0a 54 33 38 46 41 58 0d 0a 4f 4b 0d 0a ..T38FAX..OK.. }
2010/12/13 10:11:20.407 ttyT38-1(e...0xb5afcb70 --> AT+FREV?
2010/12/13 10:11:20.407 ttyT38-1(e...0xb5afcb70 <-- {
0d 0a 31 2e 32 2e 30 0d 0a 4f 4b 0d 0a ..1.2.0..OK.. }
2010/12/13 10:11:20.515 ttyT38-1(e...0xb5afcb70 --> AT+FTM=?
2010/12/13 10:11:20.515 ttyT38-1(e...0xb5afcb70 <-- {
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 10:11:20.623 ttyT38-1(e...0xb5afcb70 --> AT+FRM=?
2010/12/13 10:11:20.623 ttyT38-1(e...0xb5afcb70 <-- {
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 10:11:20.731 ttyT38-1(e...0xb5afcb70 --> ATM0
2010/12/13 10:11:20.731 ttyT38-1(e...0xb5afcb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 10:11:27.201 Housekeeper:0xb5b7eb70 SIP Starting REGISTER for binding refresh
2010/12/13 10:11:27.202 Housekeeper:0xb5b7eb70 SIP Changing REGISTER handler from Subscribed to Refreshing, target=sip:<my_username>@sip.nsw.iinet.net.au, id=d6a7b6be-b204-e011-962e-000c2984a855@xxxxxxxxxxxxxxxxxxx
2010/12/13 10:11:27.202 Housekeeper:0xb5b7eb70 SIP Transaction created.
2010/12/13 10:11:27.203 Housekeeper:0xb5b7eb70 SIP No SRV lookup as has explicit port number.
2010/12/13 10:11:27.203 Housekeeper:0xb5b7eb70 SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 10:11:27.204 Housekeeper:0xb5b7eb70 SIP Sending PDU (611 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=z9hG4bKa252a1d0-b204-e011-962e-000c2984a855;rport
User-Agent: T38Modem/1.2.0
From: <sip:<my_username>@sip.nsw.iinet.net.au>;tag=c6b2b6be-b204-e011-962e-000c2984a855
Call-ID: d6a7b6be-b204-e011-962e-000c2984a855@xxxxxxxxxxxxxxxxxxx
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: 30
Content-Length: 0
Max-Forwards: 70
2010/12/13 10:11:27.204 Housekeeper:0xb5b7eb70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 10:11:27.204 Housekeeper:0xb5b7eb70 SIP Transaction timers set: retry=0.500, completion=16.000
2010/12/13 10:11:27.225 Opal Liste...0xb5b3db70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 10:11:27.225 Opal Liste...0xb5b3db70 SIP Waiting for PDU on udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
2010/12/13 10:11:27.225 Opal Liste...0xb5b3db70 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 10:11:27.226 Opal Liste...0xb5b3db70 SIP PDU received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
SIP/2.0 200 OK
CSeq: 3 REGISTER
Via: SIP/2.0/UDP 10.11.12.22:5060;received=203.206.207.88;branch=z9hG4bKa252a1d0-b204-e011-962e-000c2984a855;rport=39236
From: <sip:<my_username>@sip.nsw.iinet.net.au>;tag=c6b2b6be-b204-e011-962e-000c2984a855
Call-ID: d6a7b6be-b204-e011-962e-000c2984a855@xxxxxxxxxxxxxxxxxxx
To: <sip:<my_username>@sip.nsw.iinet.net.au>;tag=aprqdegpo42-quaclv2000060
Contact: <sip:<my_username>>;expires=30
2010/12/13 10:11:27.226 Opal Liste...0xb5b3db70 SIP Queueing PDU "3 REGISTER <200>", transaction=z9hG4bKa252a1d0-b204-e011-962e-000c2984a855, token=d6a7b6be-b204-e011-962e-000c2984a855@xxxxxxxxxxxxxxxxxxx
2010/12/13 10:11:27.226 Opal Liste...0xb5b3db70 Opal Transport clean up on termination
2010/12/13 10:11:27.226 Opal Liste...0xb5b3db70 Opal Transport Close
2010/12/13 10:11:27.226 Opal Liste...0xb5b3db70 Opal Deleted transport udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
2010/12/13 10:11:27.226 Pool:0xb5825b70 SIP Handling PDU "3 REGISTER <200>" for transaction=z9hG4bKa252a1d0-b204-e011-962e-000c2984a855
2010/12/13 10:11:27.226 Pool:0xb5825b70 SIP REGISTER transaction id=z9hG4bKa252a1d0-b204-e011-962e-000c2984a855 completed.
2010/12/13 10:11:27.226 Pool:0xb5825b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 10:11:27.226 Pool:0xb5825b70 SIP No User-Agent or Server fields, Product Info unknown.
2010/12/13 10:11:27.226 Pool:0xb5825b70 SIP Changing REGISTER handler from Refreshing to Subscribed, target=sip:<my_username>@sip.nsw.iinet.net.au, id=d6a7b6be-b204-e011-962e-000c2984a855@xxxxxxxxxxxxxxxxxxx
2010/12/13 10:11:27.227 Pool:0xb5825b70 SIP Expiry time for REGISTER set to 30 seconds.
2010/12/13 10:11:27.227 Pool:0xb5825b70 SIP No User-Agent or Server fields, Product Info unknown.
2010/12/13 10:11:27.227 Pool:0xb5825b70 SIP Handled PDU "3 REGISTER <200>"
2010/12/13 10:11:32.228 Housekeeper:0xb5b7eb70 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bKa252a1d0-b204-e011-962e-000c2984a855
2010/12/13 10:11:33.181 Opal Garbage:0xb5cc3b70 SIP Transaction id=z9hG4bKa252a1d0-b204-e011-962e-000c2984a855 destroyed.
2010/12/13 10:11:42.230 Housekeeper:0xb5b7eb70 SIP Starting REGISTER for binding refresh
2010/12/13 10:11:42.230 Housekeeper:0xb5b7eb70 SIP Changing REGISTER handler from Subscribed to Refreshing, target=sip:<my_username>@sip.nsw.iinet.net.au, id=d6a7b6be-b204-e011-962e-000c2984a855@xxxxxxxxxxxxxxxxxxx
2010/12/13 10:11:42.230 Housekeeper:0xb5b7eb70 SIP Transaction created.
2010/12/13 10:11:42.231 Housekeeper:0xb5b7eb70 SIP No SRV lookup as has explicit port number.
2010/12/13 10:11:42.231 Housekeeper:0xb5b7eb70 SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 10:11:42.232 Housekeeper:0xb5b7eb70 SIP Sending PDU (611 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=z9hG4bKb26796d9-b204-e011-962e-000c2984a855;rport
User-Agent: T38Modem/1.2.0
From: <sip:<my_username>@sip.nsw.iinet.net.au>;tag=c6b2b6be-b204-e011-962e-000c2984a855
Call-ID: d6a7b6be-b204-e011-962e-000c2984a855@xxxxxxxxxxxxxxxxxxx
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: 30
Content-Length: 0
Max-Forwards: 70
2010/12/13 10:11:42.232 Housekeeper:0xb5b7eb70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 10:11:42.232 Housekeeper:0xb5b7eb70 SIP Transaction timers set: retry=0.500, completion=16.000
2010/12/13 10:11:42.253 Opal Liste...0xb5b3db70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 10:11:42.253 Opal Liste...0xb5b3db70 SIP Waiting for PDU on udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
2010/12/13 10:11:42.254 Opal Liste...0xb5b3db70 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 10:11:42.254 Opal Liste...0xb5b3db70 SIP PDU received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
SIP/2.0 200 OK
CSeq: 4 REGISTER
Via: SIP/2.0/UDP 10.11.12.22:5060;received=203.206.207.88;branch=z9hG4bKb26796d9-b204-e011-962e-000c2984a855;rport=39236
From: <sip:<my_username>@sip.nsw.iinet.net.au>;tag=c6b2b6be-b204-e011-962e-000c2984a855
Call-ID: d6a7b6be-b204-e011-962e-000c2984a855@xxxxxxxxxxxxxxxxxxx
To: <sip:<my_username>@sip.nsw.iinet.net.au>;tag=aprqdegpo42-quaclv2000080
Contact: <sip:<my_username>>;expires=30
2010/12/13 10:11:42.254 Opal Liste...0xb5b3db70 SIP Queueing PDU "4 REGISTER <200>", transaction=z9hG4bKb26796d9-b204-e011-962e-000c2984a855, token=d6a7b6be-b204-e011-962e-000c2984a855@xxxxxxxxxxxxxxxxxxx
2010/12/13 10:11:42.254 Opal Liste...0xb5b3db70 Opal Transport clean up on termination
2010/12/13 10:11:42.254 Opal Liste...0xb5b3db70 Opal Transport Close
2010/12/13 10:11:42.254 Opal Liste...0xb5b3db70 Opal Deleted transport udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
2010/12/13 10:11:42.254 Pool:0xb5825b70 SIP Handling PDU "4 REGISTER <200>" for transaction=z9hG4bKb26796d9-b204-e011-962e-000c2984a855
2010/12/13 10:11:42.254 Pool:0xb5825b70 SIP REGISTER transaction id=z9hG4bKb26796d9-b204-e011-962e-000c2984a855 completed.
2010/12/13 10:11:42.254 Pool:0xb5825b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 10:11:42.255 Pool:0xb5825b70 SIP No User-Agent or Server fields, Product Info unknown.
2010/12/13 10:11:42.255 Pool:0xb5825b70 SIP Changing REGISTER handler from Refreshing to Subscribed, target=sip:<my_username>@sip.nsw.iinet.net.au, id=d6a7b6be-b204-e011-962e-000c2984a855@xxxxxxxxxxxxxxxxxxx
2010/12/13 10:11:42.255 Pool:0xb5825b70 SIP Expiry time for REGISTER set to 30 seconds.
2010/12/13 10:11:42.255 Pool:0xb5825b70 SIP No User-Agent or Server fields, Product Info unknown.
2010/12/13 10:11:42.255 Pool:0xb5825b70 SIP Handled PDU "4 REGISTER <200>"
2010/12/13 10:11:43.498 ttyT38-1(i...0xb5abbb70 --> read ERROR -1 Input/output error
2010/12/13 10:11:43.498 ttyT38-1(i...0xb5abbb70 --> Stopped
2010/12/13 10:11:43.499 ttyT38-1(o...0xb5a7ab70 <-- Stopped
2010/12/13 10:11:43.509 ttyT38-1(e...0xb5afcb70 <-> Stopped
2010/12/13 10:11:43.521 ttyT38-1(b...0xb5c82b70 PseudoModemPty::OpenPty removed link /dev/ttyT38-1 -> /dev/pts/1
2010/12/13 10:11:43.521 ttyT38-1(b...0xb5c82b70 PseudoModemPty::OpenPty read ERROR -1 Input/output error
2010/12/13 10:11:43.521 ttyT38-1(b...0xb5c82b70 PseudoModemPty::OpenPty added link /dev/ttyT38-1 -> /dev/pts/1
2010/12/13 10:11:43.521 ttyT38-1(b...0xb5c82b70 PWLib File handle low water mark set: 26 Thread unblock pipe
2010/12/13 10:11:43.526 ttyT38-1(e...0xb5afcb70 RenameCurrentThread old ThreadName=ModemEngine:0xb5afcb70
2010/12/13 10:11:43.526 ttyT38-1(e...0xb5afcb70 <-> Started
2010/12/13 10:11:43.526 ttyT38-1(i...0xb5a7ab70 RenameCurrentThread old ThreadName=InPty:0xb5a7ab70
2010/12/13 10:11:43.529 ttyT38-1(i...0xb5a7ab70 --> Started
2010/12/13 10:11:43.529 ttyT38-1(o...0xb5abbb70 RenameCurrentThread old ThreadName=OutPty:0xb5abbb70
2010/12/13 10:11:43.529 ttyT38-1(o...0xb5abbb70 <-- Started
2010/12/13 10:11:47.255 Housekeeper:0xb5b7eb70 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bKb26796d9-b204-e011-962e-000c2984a855
2010/12/13 10:11:48.210 Opal Garbage:0xb5cc3b70 SIP Transaction id=z9hG4bKb26796d9-b204-e011-962e-000c2984a855 destroyed.
2010/12/13 10:11:48.787 ttyT38-1(e...0xb5afcb70 --> ATZ
2010/12/13 10:11:48.787 ttyT38-1(e...0xb5afcb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 10:11:51.895 ttyT38-1(e...0xb5afcb70 --> ATS0=0
2010/12/13 10:11:51.895 ttyT38-1(e...0xb5afcb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 10:11:52.003 ttyT38-1(e...0xb5afcb70 --> ATE0
2010/12/13 10:11:52.003 ttyT38-1(e...0xb5afcb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 10:11:52.111 ttyT38-1(e...0xb5afcb70 --> ATV1
2010/12/13 10:11:52.111 ttyT38-1(e...0xb5afcb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 10:11:52.220 ttyT38-1(e...0xb5afcb70 --> ATQ0
2010/12/13 10:11:52.220 ttyT38-1(e...0xb5afcb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 10:11:52.327 ttyT38-1(e...0xb5afcb70 --> ATS8=2
2010/12/13 10:11:52.327 ttyT38-1(e...0xb5afcb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 10:11:52.435 ttyT38-1(e...0xb5afcb70 --> ATS7=60
2010/12/13 10:11:52.435 ttyT38-1(e...0xb5afcb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 10:11:52.543 ttyT38-1(e...0xb5afcb70 --> AT+FCLASS=?
2010/12/13 10:11:52.543 ttyT38-1(e...0xb5afcb70 <-- {
0d 0a 31 2c 38 0d 0a 4f 4b 0d 0a ..1,8..OK.. }
2010/12/13 10:11:52.651 ttyT38-1(e...0xb5afcb70 --> AT+FCLASS=1
2010/12/13 10:11:52.651 ttyT38-1(e...0xb5afcb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 10:11:52.759 ttyT38-1(e...0xb5afcb70 --> ATI3
2010/12/13 10:11:52.759 ttyT38-1(e...0xb5afcb70 <-- {
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 10:11:52.867 ttyT38-1(e...0xb5afcb70 --> ATI0
2010/12/13 10:11:52.867 ttyT38-1(e...0xb5afcb70 <-- {
0d 0a 54 33 38 46 41 58 0d 0a 4f 4b 0d 0a ..T38FAX..OK.. }
2010/12/13 10:11:52.975 ttyT38-1(e...0xb5afcb70 --> AT+FREV?
2010/12/13 10:11:52.975 ttyT38-1(e...0xb5afcb70 <-- {
0d 0a 31 2e 32 2e 30 0d 0a 4f 4b 0d 0a ..1.2.0..OK.. }
2010/12/13 10:11:53.083 ttyT38-1(e...0xb5afcb70 --> AT+FTM=?
2010/12/13 10:11:53.083 ttyT38-1(e...0xb5afcb70 <-- {
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 10:11:53.191 ttyT38-1(e...0xb5afcb70 --> AT+FRM=?
2010/12/13 10:11:53.191 ttyT38-1(e...0xb5afcb70 <-- {
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 10:11:53.299 ttyT38-1(e...0xb5afcb70 --> ATM0
2010/12/13 10:11:53.299 ttyT38-1(e...0xb5afcb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 10:11:53.407 ttyT38-1(e...0xb5afcb70 --> AT+FCLASS=1
2010/12/13 10:11:53.407 ttyT38-1(e...0xb5afcb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 10:11:53.442 ttyT38-1(e...0xb5afcb70 --> ATDT0294824222
2010/12/13 10:11:53.442 ttyT38-1(e...0xb5afcb70 ModemEndPoint::OnMyCallback command=dial extra=3
2010/12/13 10:11:53.442 ttyT38-1(e...0xb5afcb70 PseudoModemQ::Dequeue ttyT38-1
2010/12/13 10:11:53.442 ttyT38-1(e...0xb5afcb70 MyManager::OnMyCallback SetUpCall(modem:, 0294824222@+/dev/ttyT38-1)
2010/12/13 10:11:53.442 ttyT38-1(e...0xb5afcb70 OpalMan Set up call from modem: to 0294824222@+/dev/ttyT38-1
2010/12/13 10:11:53.446 ttyT38-1(e...0xb5afcb70 Call Created Call[d7d54c08b1]
2010/12/13 10:11:53.446 ttyT38-1(e...0xb5afcb70 OpalMan Set up connection to "modem:"
2010/12/13 10:11:53.446 ttyT38-1(e...0xb5afcb70 ModemEndPoint::MakeConnection modem:
2010/12/13 10:11:53.446 ttyT38-1(e...0xb5afcb70 OpalCon Created connection Call[d7d54c08b1]-EP<modem>[modem:/d7d54c08b1/0]
2010/12/13 10:11:53.446 ttyT38-1(e...0xb5afcb70 ModemConnection::ModemConnection Call[d7d54c08b1]-EP<modem>[modem:/d7d54c08b1/0]
2010/12/13 10:11:53.446 ttyT38-1(e...0xb5afcb70 ModemConnection::SetUpConnection Call[d7d54c08b1]-EP<modem>[modem:/d7d54c08b1/0]
2010/12/13 10:11:53.446 ttyT38-1(e...0xb5afcb70 OpalCon SetPhase from UninitialisedPhase to SetUpPhase for Call[d7d54c08b1]-EP<modem>[modem:/d7d54c08b1/0]
2010/12/13 10:11:53.446 ttyT38-1(e...0xb5afcb70 OpalMan OnIncoming connection Call[d7d54c08b1]-EP<modem>[modem:/d7d54c08b1/0]
2010/12/13 10:11:53.446 ttyT38-1(e...0xb5afcb70 OpalCon Applying string options:
2010/12/13 10:11:53.446 ttyT38-1(e...0xb5afcb70 Call GetOtherPartyConnection Call[d7d54c08b1]-EP<modem>[modem:/d7d54c08b1/0]
2010/12/13 10:11:53.446 ttyT38-1(e...0xb5afcb70 OpalMan Searching for route "modem:<my_username> 0294824222@+/dev/ttyT38-1"
2010/12/13 10:11:53.446 ttyT38-1(e...0xb5afcb70 OpalMan Set up connection to "sip:0294824222@xxxxxxxxxxxxxxxxxxxx"
2010/12/13 10:11:53.446 ttyT38-1(e...0xb5afcb70 MySIPEndPoint::CreateConnection for Call[d7d54c08b1]
2010/12/13 10:11:53.446 ttyT38-1(e...0xb5afcb70 OpalCon Created connection Call[d7d54c08b1]-EP<sip>[aaaf45e0-b204-e011-962e-000c2984a855]
2010/12/13 10:11:53.446 ttyT38-1(e...0xb5afcb70 RFC2833 Handler created
2010/12/13 10:11:53.446 ttyT38-1(e...0xb5afcb70 RFC2833 Handler created
2010/12/13 10:11:53.447 ttyT38-1(e...0xb5afcb70 SIP Created connection.
2010/12/13 10:11:53.449 ttyT38-1(e...0xb5afcb70 Call[d7d54c08b1] from modem:<my_username> to 0294824222@+/dev/ttyT38-1, route to sip:udp$sip.nsw.iinet.net.au:5060
2010/12/13 10:11:53.449 ttyT38-1(e...0xb5afcb70 Outgoing call routed to sip:0294824222@xxxxxxxxxxxxxxxxxxxx for Call[d7d54c08b1]-EP<modem>[modem:/d7d54c08b1/0]
2010/12/13 10:11:53.449 ttyT38-1(e...0xb5afcb70 Call OnSetUp Call[d7d54c08b1]-EP<modem>[modem:/d7d54c08b1/0]
2010/12/13 10:11:53.449 ttyT38-1(e...0xb5afcb70 MySIPConnection::SetUpConnection Call[d7d54c08b1]-EP<sip>[aaaf45e0-b204-e011-962e-000c2984a855] name=<my_username>
2010/12/13 10:11:53.449 ttyT38-1(e...0xb5afcb70 SIP SetUpConnection: sip:0294824222@xxxxxxxxxxxxxxxxxxxx
2010/12/13 10:11:53.449 ttyT38-1(e...0xb5afcb70 OpalCon SetPhase from UninitialisedPhase to SetUpPhase for Call[d7d54c08b1]-EP<sip>[aaaf45e0-b204-e011-962e-000c2984a855]
2010/12/13 10:11:53.449 ttyT38-1(e...0xb5afcb70 OpalCon Applying string options:
2010/12/13 10:11:53.449 ttyT38-1(e...0xb5afcb70 SIP Found registrar on domain sip.nsw.iinet.net.au, using interface 10.11.12.22%eth0
2010/12/13 10:11:53.449 ttyT38-1(e...0xb5afcb70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 10:11:53.449 ttyT38-1(e...0xb5afcb70 SIP Created transport udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
2010/12/13 10:11:53.450 ttyT38-1(e...0xb5afcb70 OpalUDP Started connect to 203.55.231.194:5060
2010/12/13 10:11:53.450 ttyT38-1(e...0xb5afcb70 SIP Updating dialog tag from "" to "aaaf45e0-b204-e011-962e-000c2984a855"
2010/12/13 10:11:53.452 ttyT38-1(e...0xb5afcb70 SIP INVITE transaction id=z9hG4bKe49746e0-b204-e011-962e-000c2984a855 created.
2010/12/13 10:11:53.452 ttyT38-1(e...0xb5afcb70 SIP Creating INVITE request
2010/12/13 10:11:53.452 ttyT38-1(e...0xb5afcb70 ModemConnection::GetMediaFormats Call[d7d54c08b1]-EP<modem>[modem:/d7d54c08b1/0]
2010/12/13 10:11:53.452 ttyT38-1(e...0xb5afcb70 ModemEndPoint::GetMediaFormats
2010/12/13 10:11:53.454 ttyT38-1(e...0xb5afcb70 MediaFormat Removing codecs
2010/12/13 10:11:53.454 ttyT38-1(e...0xb5afcb70 MediaFormat Removing codecs
2010/12/13 10:11:53.454 ttyT38-1(e...0xb5afcb70 Call GetMediaFormats for Call[d7d54c08b1]-EP<sip>[aaaf45e0-b204-e011-962e-000c2984a855]
G.711-uLaw-64k
G.711-ALaw-64k
PCM-16
T.38
2010/12/13 10:11:53.454 ttyT38-1(e...0xb5afcb70 SIP Offering media type audio in SDP with formats
G.711-uLaw-64k,G.711-ALaw-64k,PCM-16,T.38
2010/12/13 10:11:53.454 ttyT38-1(e...0xb5afcb70 Call IsMediaBypassPossible Call[d7d54c08b1]-EP<sip>[aaaf45e0-b204-e011-962e-000c2984a855] session 1
2010/12/13 10:11:53.454 ttyT38-1(e...0xb5afcb70 OpalMan IsMediaBypassPossible: session 1
2010/12/13 10:11:53.454 ttyT38-1(e...0xb5afcb70 OpalCon IsMediaBypassPossible: default returns false
2010/12/13 10:11:53.454 ttyT38-1(e...0xb5afcb70 RTP Cannot find media session 1
2010/12/13 10:11:53.455 ttyT38-1(e...0xb5afcb70 RTP Cannot find RTP session 1
2010/12/13 10:11:53.455 ttyT38-1(e...0xb5afcb70 RTP_UDP Session 1, created with NAT flag set to 0
2010/12/13 10:11:53.455 ttyT38-1(e...0xb5afcb70 PWLib File handle high water mark set: 54 PUDPSocket
2010/12/13 10:11:53.455 ttyT38-1(e...0xb5afcb70 PWLib File handle low water mark set: 53 PUDPSocket
2010/12/13 10:11:53.455 ttyT38-1(e...0xb5afcb70 RTP_UDP Session 1 created: 10.11.12.22:5000-5001 ssrc=1878469150
2010/12/13 10:11:53.455 ttyT38-1(e...0xb5afcb70 PWLib File handle high water mark set: 55 PUDPSocket
2010/12/13 10:11:53.455 ttyT38-1(e...0xb5afcb70 RTP Creating new session RTP_UDP
2010/12/13 10:11:53.455 ttyT38-1(e...0xb5afcb70 RTP Found existing media session 1
2010/12/13 10:11:53.456 ttyT38-1(e...0xb5afcb70 SIP Using default RTP payload [pt=101] for UserInput/RFC2833
2010/12/13 10:11:53.456 ttyT38-1(e...0xb5afcb70 SIP Using default RTP payload [pt=100] for NamedSignalEvent
2010/12/13 10:11:53.456 ttyT38-1(e...0xb5afcb70 ModemConnection::GetMediaFormats Call[d7d54c08b1]-EP<modem>[modem:/d7d54c08b1/0]
2010/12/13 10:11:53.456 ttyT38-1(e...0xb5afcb70 ModemEndPoint::GetMediaFormats
2010/12/13 10:11:53.457 ttyT38-1(e...0xb5afcb70 MediaFormat Removing codecs
2010/12/13 10:11:53.458 ttyT38-1(e...0xb5afcb70 MediaFormat Removing codecs
2010/12/13 10:11:53.458 ttyT38-1(e...0xb5afcb70 Call GetMediaFormats for Call[d7d54c08b1]-EP<sip>[aaaf45e0-b204-e011-962e-000c2984a855]
G.711-uLaw-64k
G.711-ALaw-64k
PCM-16
T.38
2010/12/13 10:11:53.458 ttyT38-1(e...0xb5afcb70 SIP No media formats of type video, not adding SDP
2010/12/13 10:11:53.458 ttyT38-1(e...0xb5afcb70 SIP No SRV lookup as has explicit port number.
2010/12/13 10:11:53.458 ttyT38-1(e...0xb5afcb70 SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 10:11:53.459 ttyT38-1(e...0xb5afcb70 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:0294824222@xxxxxxxxxxxxxxxxxxxx SIP/2.0
Route: <sip:sip.nsw.iinet.net.au:5060;lr>
Date: Sun, 12 Dec 2010 23:11:53 GMT
CSeq: 1 INVITE
Via: SIP/2.0/UDP 10.11.12.22:5060;branch=z9hG4bKe49746e0-b204-e011-962e-000c2984a855;rport
User-Agent: T38Modem/1.2.0
From: "root" <sip:<my_username>@sip.nsw.iinet.net.au>;tag=aaaf45e0-b204-e011-962e-000c2984a855
Call-ID: 02b745e0-b204-e011-962e-000c2984a855@xxxxxxxxxxxxxxxxxxx
Organization: Vyacheslav Frolov
To: <sip:0294824222@xxxxxxxxxxxxxxxxxxxx>
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Content-Type: application/sdp
Content-Length: 286
Max-Forwards: 70
v=0
o=- 1292195513 1 IN IP4 10.11.12.22
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 10:11:53.459 ttyT38-1(e...0xb5afcb70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 10:11:53.459 ttyT38-1(e...0xb5afcb70 SIP Transaction timers set: retry=0.500, completion=32.000
2010/12/13 10:11:53.459 ttyT38-1(e...0xb5afcb70 OpalCon OnSetUpConnectionCall[d7d54c08b1]-EP<sip>[aaaf45e0-b204-e011-962e-000c2984a855]
2010/12/13 10:11:53.459 ttyT38-1(e...0xb5afcb70 OpalEP OnSetUpConnection Call[d7d54c08b1]-EP<sip>[aaaf45e0-b204-e011-962e-000c2984a855]
2010/12/13 10:11:53.459 ttyT38-1(e...0xb5afcb70 OpalMan SetUpCall succeeded, call=Call[d7d54c08b1]
2010/12/13 10:11:53.459 ttyT38-1(e...0xb5afcb70 ttyT38-1 T38Engine::T38Engine
2010/12/13 10:11:53.459 ttyT38-1(e...0xb5afcb70 ModemEndPoint::OnMyCallback request={
calltoken=modem:/d7d54c08b1/0
localpartyname=
command=dial
response=confirm
number=0294824222
modemtoken=ttyT38-1
}
2010/12/13 10:11:53.485 Opal Liste...0xb5b3db70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 10:11:53.485 Opal Liste...0xb5b3db70 SIP Waiting for PDU on udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
2010/12/13 10:11:53.485 Opal Liste...0xb5b3db70 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 10:11:53.485 Opal Liste...0xb5b3db70 SIP PDU received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
SIP/2.0 100 Trying
CSeq: 1 INVITE
Via: SIP/2.0/UDP 10.11.12.22:5060;received=203.206.207.88;branch=z9hG4bKe49746e0-b204-e011-962e-000c2984a855;rport=39236
From: "root" <sip:<my_username>@sip.nsw.iinet.net.au>;tag=aaaf45e0-b204-e011-962e-000c2984a855
Call-ID: 02b745e0-b204-e011-962e-000c2984a855@xxxxxxxxxxxxxxxxxxx
To: <sip:0294824222@xxxxxxxxxxxxxxxxxxxx>
2010/12/13 10:11:53.485 Opal Liste...0xb5b3db70 SIP Queueing PDU "1 INVITE <100>", transaction=z9hG4bKe49746e0-b204-e011-962e-000c2984a855, token=aaaf45e0-b204-e011-962e-000c2984a855
2010/12/13 10:11:53.486 Opal Liste...0xb5b3db70 PWLib File handle high water mark set: 56 Thread unblock pipe
2010/12/13 10:11:53.486 Opal Liste...0xb5b3db70 PTLib Thread high water mark set: 22
2010/12/13 10:11:53.486 Opal Liste...0xb5b3db70 Opal Transport clean up on termination
2010/12/13 10:11:53.486 Opal Liste...0xb5b3db70 Opal Transport Close
2010/12/13 10:11:53.486 Opal Liste...0xb5b3db70 PWLib File handle high water mark set: 57 PUDPSocket
2010/12/13 10:11:53.486 Opal Liste...0xb5b3db70 Opal Deleted transport udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
2010/12/13 10:11:53.486 Pool:0xb57e4b70 SIP Handling PDU "1 INVITE <100>" for transaction=z9hG4bKe49746e0-b204-e011-962e-000c2984a855
2010/12/13 10:11:53.486 Pool:0xb57e4b70 SIP No User-Agent or Server fields, Product Info unknown.
2010/12/13 10:11:53.486 Pool:0xb57e4b70 SIP INVITE transaction id=z9hG4bKe49746e0-b204-e011-962e-000c2984a855 proceeding.
2010/12/13 10:11:53.486 Pool:0xb57e4b70 SIP Received Trying response
2010/12/13 10:11:53.486 Pool:0xb57e4b70 OpalCon SetPhase from SetUpPhase to ProceedingPhase for Call[d7d54c08b1]-EP<sip>[aaaf45e0-b204-e011-962e-000c2984a855]
2010/12/13 10:11:53.486 Pool:0xb57e4b70 OpalMan OnProceeding Call[d7d54c08b1]-EP<sip>[aaaf45e0-b204-e011-962e-000c2984a855]
2010/12/13 10:11:53.486 Pool:0xb57e4b70 Call OnProceeding Call[d7d54c08b1]-EP<sip>[aaaf45e0-b204-e011-962e-000c2984a855]
2010/12/13 10:11:53.486 Pool:0xb57e4b70 SIP Handled PDU "1 INVITE <100>"
2010/12/13 10:11:53.516 Opal Liste...0xb5b3db70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 10:11:53.516 Opal Liste...0xb5b3db70 SIP Waiting for PDU on udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
2010/12/13 10:11:53.516 Opal Liste...0xb5b3db70 SIP PDU received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
SIP/2.0 401 Unauthorized
CSeq: 1 INVITE
Via: SIP/2.0/UDP 10.11.12.22:5060;received=203.206.207.88;branch=z9hG4bKe49746e0-b204-e011-962e-000c2984a855;rport=39236
From: "root" <sip:<my_username>@sip.nsw.iinet.net.au>;tag=aaaf45e0-b204-e011-962e-000c2984a855
Call-ID: 02b745e0-b204-e011-962e-000c2984a855@xxxxxxxxxxxxxxxxxxx
To: <sip:0294824222@xxxxxxxxxxxxxxxxxxxx>;tag=SD188l999-364347881-1292195515639
Content-Length: 0
WWW-Authenticate: DIGEST qop="auth",nonce="BroadWorksXghmjuh2vTls14hjBW",algorithm=MD5,realm="iinetphone.iinet.net.au"
2010/12/13 10:11:53.516 Opal Liste...0xb5b3db70 SIP Queueing PDU "1 INVITE <401>", transaction=z9hG4bKe49746e0-b204-e011-962e-000c2984a855, token=aaaf45e0-b204-e011-962e-000c2984a855
2010/12/13 10:11:53.516 Opal Liste...0xb5b3db70 Opal Transport clean up on termination
2010/12/13 10:11:53.517 Opal Liste...0xb5b3db70 Opal Transport Close
2010/12/13 10:11:53.517 Opal Liste...0xb5b3db70 Opal Deleted transport udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
2010/12/13 10:11:53.517 Pool:0xb57e4b70 SIP Handling PDU "1 INVITE <401>" for transaction=z9hG4bKe49746e0-b204-e011-962e-000c2984a855
2010/12/13 10:11:53.518 Pool:0xb57e4b70 SIP Sending PDU (477 bytes) to: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
ACK sip:0294824222@xxxxxxxxxxxxxxxxxxxx SIP/2.0
Route: <sip:sip.nsw.iinet.net.au:5060;lr>
CSeq: 1 ACK
Via: SIP/2.0/UDP 10.11.12.22:5060;branch=z9hG4bKe49746e0-b204-e011-962e-000c2984a855;rport
From: "root" <sip:<my_username>@sip.nsw.iinet.net.au>;tag=aaaf45e0-b204-e011-962e-000c2984a855
Call-ID: 02b745e0-b204-e011-962e-000c2984a855@xxxxxxxxxxxxxxxxxxx
To: <sip:0294824222@xxxxxxxxxxxxxxxxxxxx>;tag=SD188l999-364347881-1292195515639
Content-Length: 0
Max-Forwards: 70
2010/12/13 10:11:53.518 Pool:0xb57e4b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 10:11:53.518 Pool:0xb57e4b70 SIP INVITE transaction id=z9hG4bKe49746e0-b204-e011-962e-000c2984a855 completed.
2010/12/13 10:11:53.518 Pool:0xb57e4b70 SIP Received Authentication Required response
2010/12/13 10:11:53.518 Pool:0xb57e4b70 SIP Authentication contains qop-options auth
2010/12/13 10:11:53.519 Pool:0xb57e4b70 SIP Located existing credentials for ID "<my_username>" at realm "iinetphone.iinet.net.au"
2010/12/13 10:11:53.519 Pool:0xb57e4b70 SIP Found auth info for realm "iinetphone.iinet.net.au", user "<my_username>"
2010/12/13 10:11:53.519 Pool:0xb57e4b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 10:11:53.521 Pool:0xb57e4b70 SIP INVITE transaction id=z9hG4bKf20b51e0-b204-e011-962e-000c2984a855 created.
2010/12/13 10:11:53.521 Pool:0xb57e4b70 SIP Creating INVITE request
2010/12/13 10:11:53.521 Pool:0xb57e4b70 ModemConnection::GetMediaFormats Call[d7d54c08b1]-EP<modem>[modem:/d7d54c08b1/0]
2010/12/13 10:11:53.521 Pool:0xb57e4b70 ModemEndPoint::GetMediaFormats
2010/12/13 10:11:53.522 Pool:0xb57e4b70 MediaFormat Removing codecs
2010/12/13 10:11:53.522 Pool:0xb57e4b70 MediaFormat Removing codecs
2010/12/13 10:11:53.523 Pool:0xb57e4b70 Call GetMediaFormats for Call[d7d54c08b1]-EP<sip>[aaaf45e0-b204-e011-962e-000c2984a855]
G.711-uLaw-64k
G.711-ALaw-64k
PCM-16
T.38
2010/12/13 10:11:53.523 Pool:0xb57e4b70 SIP Offering media type audio in SDP with formats
G.711-uLaw-64k,G.711-ALaw-64k,PCM-16,T.38
2010/12/13 10:11:53.523 Pool:0xb57e4b70 Call IsMediaBypassPossible Call[d7d54c08b1]-EP<sip>[aaaf45e0-b204-e011-962e-000c2984a855] session 1
2010/12/13 10:11:53.523 Pool:0xb57e4b70 OpalMan IsMediaBypassPossible: session 1
2010/12/13 10:11:53.523 Pool:0xb57e4b70 OpalCon IsMediaBypassPossible: default returns false
2010/12/13 10:11:53.523 Pool:0xb57e4b70 RTP Found existing media session 1
2010/12/13 10:11:53.523 Pool:0xb57e4b70 RTP Found existing RTP session 1
2010/12/13 10:11:53.523 Pool:0xb57e4b70 SIP Using default RTP payload [pt=101] for UserInput/RFC2833
2010/12/13 10:11:53.523 Pool:0xb57e4b70 SIP Using default RTP payload [pt=100] for NamedSignalEvent
2010/12/13 10:11:53.523 Pool:0xb57e4b70 ModemConnection::GetMediaFormats Call[d7d54c08b1]-EP<modem>[modem:/d7d54c08b1/0]
2010/12/13 10:11:53.523 Pool:0xb57e4b70 ModemEndPoint::GetMediaFormats
2010/12/13 10:11:53.525 Pool:0xb57e4b70 MediaFormat Removing codecs
2010/12/13 10:11:53.525 Pool:0xb57e4b70 MediaFormat Removing codecs
2010/12/13 10:11:53.525 Pool:0xb57e4b70 Call GetMediaFormats for Call[d7d54c08b1]-EP<sip>[aaaf45e0-b204-e011-962e-000c2984a855]
G.711-uLaw-64k
G.711-ALaw-64k
PCM-16
T.38
2010/12/13 10:11:53.525 Pool:0xb57e4b70 SIP No media formats of type video, not adding SDP
2010/12/13 10:11:53.525 Pool:0xb57e4b70 SIP Adding authentication information for user "<my_username>" at realm "iinetphone.iinet.net.au"
2010/12/13 10:11:53.525 Pool:0xb57e4b70 SIP No SRV lookup as has explicit port number.
2010/12/13 10:11:53.525 Pool:0xb57e4b70 SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 10:11:53.526 Pool:0xb57e4b70 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:0294824222@xxxxxxxxxxxxxxxxxxxx SIP/2.0
Route: <sip:sip.nsw.iinet.net.au:5060;lr>
Date: Sun, 12 Dec 2010 23:11:53 GMT
CSeq: 2 INVITE
Via: SIP/2.0/UDP 10.11.12.22:5060;branch=z9hG4bKf20b51e0-b204-e011-962e-000c2984a855;rport
User-Agent: T38Modem/1.2.0
Authorization: Digest username="<my_username>", realm="iinetphone.iinet.net.au", nonce="BroadWorksXghmjuh2vTls14hjBW", uri="sip:0294824222@xxxxxxxxxxxxxxxxxxxx", algorithm=MD5, response="f40b4b6a8336476993f7116d145263a3", cnonce="98a750e0-b204-e011-962e-000c2984a855", nc=00000001, qop=auth
From: "root" <sip:<my_username>@sip.nsw.iinet.net.au>;tag=aaaf45e0-b204-e011-962e-000c2984a855
Call-ID: 02b745e0-b204-e011-962e-000c2984a855@xxxxxxxxxxxxxxxxxxx
Organization: Vyacheslav Frolov
To: <sip:0294824222@xxxxxxxxxxxxxxxxxxxx>
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Content-Type: application/sdp
Content-Length: 286
Max-Forwards: 70
v=0
o=- 1292195513 1 IN IP4 10.11.12.22
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 10:11:53.526 Pool:0xb57e4b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 10:11:53.526 Pool:0xb57e4b70 SIP Transaction timers set: retry=0.500, completion=32.000
2010/12/13 10:11:53.526 Pool:0xb57e4b70 SIP Handled PDU "1 INVITE <401>"
2010/12/13 10:11:53.618 Opal Liste...0xb5b3db70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 10:11:53.618 Opal Liste...0xb5b3db70 SIP Waiting for PDU on udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
2010/12/13 10:11:53.619 Opal Liste...0xb5b3db70 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 10:11:53.619 Opal Liste...0xb5b3db70 SIP PDU received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
SIP/2.0 100 Trying
CSeq: 2 INVITE
Via: SIP/2.0/UDP 10.11.12.22:5060;received=203.206.207.88;branch=z9hG4bKf20b51e0-b204-e011-962e-000c2984a855;rport=39236
From: "root" <sip:<my_username>@sip.nsw.iinet.net.au>;tag=aaaf45e0-b204-e011-962e-000c2984a855
Call-ID: 02b745e0-b204-e011-962e-000c2984a855@xxxxxxxxxxxxxxxxxxx
To: <sip:0294824222@xxxxxxxxxxxxxxxxxxxx>
2010/12/13 10:11:53.619 Opal Liste...0xb5b3db70 SIP Queueing PDU "2 INVITE <100>", transaction=z9hG4bKf20b51e0-b204-e011-962e-000c2984a855, token=aaaf45e0-b204-e011-962e-000c2984a855
2010/12/13 10:11:53.619 Opal Liste...0xb5b3db70 Opal Transport clean up on termination
2010/12/13 10:11:53.619 Opal Liste...0xb5b3db70 Opal Transport Close
2010/12/13 10:11:53.619 Opal Liste...0xb5b3db70 Opal Deleted transport udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
2010/12/13 10:11:53.619 Pool:0xb57e4b70 SIP Handling PDU "2 INVITE <100>" for transaction=z9hG4bKf20b51e0-b204-e011-962e-000c2984a855
2010/12/13 10:11:53.620 Pool:0xb57e4b70 SIP No User-Agent or Server fields, Product Info unknown.
2010/12/13 10:11:53.620 Pool:0xb57e4b70 SIP INVITE transaction id=z9hG4bKf20b51e0-b204-e011-962e-000c2984a855 proceeding.
2010/12/13 10:11:53.620 Pool:0xb57e4b70 SIP Received Trying response
2010/12/13 10:11:53.620 Pool:0xb57e4b70 SIP Handled PDU "2 INVITE <100>"
2010/12/13 10:11:55.232 Opal Liste...0xb5b3db70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 10:11:55.232 Opal Liste...0xb5b3db70 SIP Waiting for PDU on udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
2010/12/13 10:11:55.232 Opal Liste...0xb5b3db70 SIP PDU received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
SIP/2.0 183 Session Progress
CSeq: 2 INVITE
Via: SIP/2.0/UDP 10.11.12.22:5060;received=203.206.207.88;branch=z9hG4bKf20b51e0-b204-e011-962e-000c2984a855;rport=39236
From: "root" <sip:<my_username>@sip.nsw.iinet.net.au>;tag=aaaf45e0-b204-e011-962e-000c2984a855
Call-ID: 02b745e0-b204-e011-962e-000c2984a855@xxxxxxxxxxxxxxxxxxx
Supported:
To: <sip:0294824222@xxxxxxxxxxxxxxxxxxxx>;tag=SD188l999-108772045-1292195517361
Session: Media
Contact: <sip:0294824222@xxxxxxxxxxxxxx:5060;transport=udp>
Allow: ACK,BYE,CANCEL,INFO,INVITE,OPTIONS,PRACK,REFER,NOTIFY,UPDATE
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 224
v=0
o=BroadWorks 109648391 1 IN IP4 203.55.231.194
s=-
c=IN IP4 203.55.231.194
t=0 0
m=audio 42438 RTP/AVP 0 101
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 10:11:55.233 Opal Liste...0xb5b3db70 SIP Queueing PDU "2 INVITE <183>", transaction=z9hG4bKf20b51e0-b204-e011-962e-000c2984a855, token=aaaf45e0-b204-e011-962e-000c2984a855
2010/12/13 10:11:55.233 Opal Liste...0xb5b3db70 Opal Transport clean up on termination
2010/12/13 10:11:55.233 Opal Liste...0xb5b3db70 Opal Transport Close
2010/12/13 10:11:55.233 Opal Liste...0xb5b3db70 Opal Deleted transport udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
2010/12/13 10:11:55.233 Pool:0xb57e4b70 SIP Handling PDU "2 INVITE <183>" for transaction=z9hG4bKf20b51e0-b204-e011-962e-000c2984a855
2010/12/13 10:11:55.233 Pool:0xb57e4b70 SIP Updating dialog tag from "" to "SD188l999-108772045-1292195517361"
2010/12/13 10:11:55.233 Pool:0xb57e4b70 SDP Parsed connection address udp$203.55.231.194
2010/12/13 10:11:55.233 Pool:0xb57e4b70 SDP Parsed connection address udp$203.55.231.194
2010/12/13 10:11:55.233 Pool:0xb57e4b70 SDP Media session port=42438
2010/12/13 10:11:55.233 Pool:0xb57e4b70 SDP Parsed connection address udp$203.55.231.194:42438
2010/12/13 10:11:55.233 Pool:0xb57e4b70 SDP Parsed media session with 2 'audio' formats
2010/12/13 10:11:55.234 Pool:0xb57e4b70 SIP No User-Agent or Server fields, Product Info unknown.
2010/12/13 10:11:55.234 Pool:0xb57e4b70 SIP INVITE transaction id=z9hG4bKf20b51e0-b204-e011-962e-000c2984a855 proceeding.
2010/12/13 10:11:55.234 Pool:0xb57e4b70 SIP Received Session Progress response
2010/12/13 10:11:55.234 Pool:0xb57e4b70 SIP Processing received SDP media description for audio
2010/12/13 10:11:55.234 Pool:0xb57e4b70 SIP RTP payload type PCMU matched to codec G.711-uLaw-64k
2010/12/13 10:11:55.234 Pool:0xb57e4b70 MediaFormat Removing codecs
2010/12/13 10:11:55.234 Pool:0xb57e4b70 Call IsMediaBypassPossible Call[d7d54c08b1]-EP<sip>[aaaf45e0-b204-e011-962e-000c2984a855] session 1
2010/12/13 10:11:55.234 Pool:0xb57e4b70 OpalMan IsMediaBypassPossible: session 1
2010/12/13 10:11:55.234 Pool:0xb57e4b70 OpalCon IsMediaBypassPossible: default returns false
2010/12/13 10:11:55.234 Pool:0xb57e4b70 RTP Found existing RTP session 1
2010/12/13 10:11:55.234 Pool:0xb57e4b70 RTP_UDP Session 1, SetRemoteSocketInfo: data channel, new=203.55.231.194:42438, local=10.11.12.22:5000-5001, remote=0.0.0.0:0-0
2010/12/13 10:11:55.234 Pool:0xb57e4b70 RTP Found existing media session 1
2010/12/13 10:11:55.234 Pool:0xb57e4b70 Call OpenSourceMediaStreams opening audio session 1 on Call[d7d54c08b1]-EP<sip>[aaaf45e0-b204-e011-962e-000c2984a855]
2010/12/13 10:11:55.234 Pool:0xb57e4b70 ModemConnection::GetMediaFormats Call[d7d54c08b1]-EP<modem>[modem:/d7d54c08b1/0]
2010/12/13 10:11:55.234 Pool:0xb57e4b70 ModemEndPoint::GetMediaFormats
2010/12/13 10:11:55.234 Pool:0xb57e4b70 ModemConnection::GetMediaFormats Call[d7d54c08b1]-EP<modem>[modem:/d7d54c08b1/0]
2010/12/13 10:11:55.234 Pool:0xb57e4b70 ModemEndPoint::GetMediaFormats
2010/12/13 10:11:55.236 Pool:0xb57e4b70 MediaFormat Removing codecs
2010/12/13 10:11:55.236 Pool:0xb57e4b70 MediaFormat Removing codecs
2010/12/13 10:11:55.236 Pool:0xb57e4b70 Call GetMediaFormats for Call[d7d54c08b1]-EP<sip>[aaaf45e0-b204-e011-962e-000c2984a855]
G.711-uLaw-64k
G.711-ALaw-64k
PCM-16
T.38
2010/12/13 10:11:55.236 Pool:0xb57e4b70 MediaFormat Merging G.711-uLaw-64k into G.711-uLaw-64k
2010/12/13 10:11:55.236 Pool:0xb57e4b70 MediaFormat Merging PCM-16 into PCM-16
2010/12/13 10:11:55.236 Pool:0xb57e4b70 MediaFormat Merging PCM-16 into G.711-uLaw-64k
2010/12/13 10:11:55.236 Pool:0xb57e4b70 MediaFormat Merging G.711-uLaw-64k into PCM-16
2010/12/13 10:11:55.236 Pool:0xb57e4b70 MediaFormat Changed media option "Max Bit Rate" from 128000 to 64000
2010/12/13 10:11:55.236 Pool:0xb57e4b70 Call Selected media formats G.711-uLaw-64k -> PCM-16
2010/12/13 10:11:55.236 Pool:0xb57e4b70 Call IsMediaBypassPossible Call[d7d54c08b1]-EP<sip>[aaaf45e0-b204-e011-962e-000c2984a855] session 1
2010/12/13 10:11:55.236 Pool:0xb57e4b70 OpalMan IsMediaBypassPossible: session 1
2010/12/13 10:11:55.236 Pool:0xb57e4b70 OpalCon IsMediaBypassPossible: default returns false
2010/12/13 10:11:55.237 Pool:0xb57e4b70 RTP Found existing media session 1
2010/12/13 10:11:55.237 Pool:0xb57e4b70 OpalMan OnOpenMediaStream Call[d7d54c08b1]-EP<sip>[aaaf45e0-b204-e011-962e-000c2984a855],OpalRTPMediaStream-Source-G.711-uLaw-64k
2010/12/13 10:11:55.237 Pool:0xb57e4b70 OpalCon Opened source stream d7d54c08b1_1 with format G.711-uLaw-64k
2010/12/13 10:11:55.237 Pool:0xb57e4b70 ModemConnection::CreateMediaStream Call[d7d54c08b1]-EP<modem>[modem:/d7d54c08b1/0] mediaFormat=PCM-16 sessionID=1 isSource=0
2010/12/13 10:11:55.237 Pool:0xb57e4b70 ModemEngineBody::Attach audioEngine stConnectWait
2010/12/13 10:11:55.237 Pool:0xb57e4b70 ttyT38-1 AudioEngine::Attach
2010/12/13 10:11:55.237 Pool:0xb57e4b70 ttyT38-1 AudioClass=FALSE
2010/12/13 10:11:55.237 Pool:0xb57e4b70 ttyT38-1 AudioEngine::SendOnIdle 2
2010/12/13 10:11:55.237 Pool:0xb57e4b70 ModemEngineBody::Attach audioEngine Attached
2010/12/13 10:11:55.237 Pool:0xb57e4b70 OpalMan OnOpenMediaStream Call[d7d54c08b1]-EP<modem>[modem:/d7d54c08b1/0],AudioModemMediaStream-Sink-PCM-16
2010/12/13 10:11:55.237 Pool:0xb57e4b70 OpalCon Opened sink stream d7d54c08b1_1 with format PCM-16
2010/12/13 10:11:55.237 Pool:0xb57e4b70 Patch Created Sink: format=PCM-16
2010/12/13 10:11:55.237 Pool:0xb57e4b70 Codec G711-uLaw-64k decoder created
2010/12/13 10:11:55.237 Pool:0xb57e4b70 Patch Created primary codec G.711-uLaw-64k->PCM-16 with ID d7d54c08b1_1
2010/12/13 10:11:55.237 Pool:0xb57e4b70 Media Set data size from 16 to 3840
2010/12/13 10:11:55.237 Pool:0xb57e4b70 Patch Added media stream sink AudioModemMediaStream-Sink-PCM-16 using transcoder G.711-uLaw-64k->PCM-16, data size=3840
2010/12/13 10:11:55.237 Pool:0xb57e4b70 Media RTP data size cannot be changed to 8, fixed at 2048
2010/12/13 10:11:55.237 Pool:0xb57e4b70 OpalCon Sink stream of connection Call[d7d54c08b1]-EP<modem>[modem:/d7d54c08b1/0] uses patch Patch OpalRTPMediaStream-Source-G.711-uLaw-64k -> AudioModemMediaStream-Sink-PCM-16
2010/12/13 10:11:55.237 Pool:0xb57e4b70 OpalCon Source stream of connection Call[d7d54c08b1]-EP<sip>[aaaf45e0-b204-e011-962e-000c2984a855] uses patch Patch OpalRTPMediaStream-Source-G.711-uLaw-64k -> AudioModemMediaStream-Sink-PCM-16
2010/12/13 10:11:55.237 Pool:0xb57e4b70 RTPCon Adding RFC2833 receive handler
2010/12/13 10:11:55.237 Pool:0xb57e4b70 RTPCon Adding Cisco NSE receive handler
2010/12/13 10:11:55.237 Pool:0xb57e4b70 Call GetOtherPartyConnection Call[d7d54c08b1]-EP<sip>[aaaf45e0-b204-e011-962e-000c2984a855]
2010/12/13 10:11:55.237 Pool:0xb57e4b70 Call OpenSourceMediaStreams opening audio session 1 on Call[d7d54c08b1]-EP<modem>[modem:/d7d54c08b1/0]
2010/12/13 10:11:55.237 Pool:0xb57e4b70 ModemConnection::GetMediaFormats Call[d7d54c08b1]-EP<modem>[modem:/d7d54c08b1/0]
2010/12/13 10:11:55.237 Pool:0xb57e4b70 ModemEndPoint::GetMediaFormats
2010/12/13 10:11:55.238 Pool:0xb57e4b70 MediaFormat Removing codecs
2010/12/13 10:11:55.238 Pool:0xb57e4b70 MediaFormat Removing codecs
2010/12/13 10:11:55.238 Pool:0xb57e4b70 Call GetMediaFormats for Call[d7d54c08b1]-EP<modem>[modem:/d7d54c08b1/0]
G.711-uLaw-64k
G.711-ALaw-64k
PCM-16
T.38
2010/12/13 10:11:55.238 Pool:0xb57e4b70 MediaFormat Merging PCM-16 into PCM-16
2010/12/13 10:11:55.238 Pool:0xb57e4b70 MediaFormat Merging G.711-uLaw-64k into G.711-uLaw-64k
2010/12/13 10:11:55.238 Pool:0xb57e4b70 MediaFormat Merging G.711-uLaw-64k into PCM-16
2010/12/13 10:11:55.238 Pool:0xb57e4b70 MediaFormat Changed media option "Max Bit Rate" from 128000 to 64000
2010/12/13 10:11:55.238 Pool:0xb57e4b70 MediaFormat Merging PCM-16 into G.711-uLaw-64k
2010/12/13 10:11:55.238 Pool:0xb57e4b70 Call Selected media formats PCM-16 -> G.711-uLaw-64k
2010/12/13 10:11:55.238 Pool:0xb57e4b70 ModemConnection::CreateMediaStream Call[d7d54c08b1]-EP<modem>[modem:/d7d54c08b1/0] mediaFormat=PCM-16 sessionID=1 isSource=1
2010/12/13 10:11:55.239 Pool:0xb57e4b70 OpalMan OnOpenMediaStream Call[d7d54c08b1]-EP<modem>[modem:/d7d54c08b1/0],AudioModemMediaStream-Source-PCM-16
2010/12/13 10:11:55.239 Pool:0xb57e4b70 OpalCon Opened source stream d7d54c08b1_1 with format PCM-16
2010/12/13 10:11:55.239 Pool:0xb57e4b70 Call IsMediaBypassPossible Call[d7d54c08b1]-EP<sip>[aaaf45e0-b204-e011-962e-000c2984a855] session 1
2010/12/13 10:11:55.239 Pool:0xb57e4b70 OpalMan IsMediaBypassPossible: session 1
2010/12/13 10:11:55.239 Pool:0xb57e4b70 OpalCon IsMediaBypassPossible: default returns false
2010/12/13 10:11:55.239 Pool:0xb57e4b70 RTP Found existing media session 1
2010/12/13 10:11:55.239 Pool:0xb57e4b70 OpalMan OnOpenMediaStream Call[d7d54c08b1]-EP<sip>[aaaf45e0-b204-e011-962e-000c2984a855],OpalRTPMediaStream-Sink-G.711-uLaw-64k
2010/12/13 10:11:55.239 Pool:0xb57e4b70 OpalCon Opened sink stream d7d54c08b1_1 with format G.711-uLaw-64k
2010/12/13 10:11:55.239 Pool:0xb57e4b70 Patch Created Sink: format=G.711-uLaw-64k
2010/12/13 10:11:55.239 Pool:0xb57e4b70 Codec G711-uLaw-64k encoder created
2010/12/13 10:11:55.239 Pool:0xb57e4b70 Patch Created primary codec PCM-16->G.711-uLaw-64k with ID d7d54c08b1_1
2010/12/13 10:11:55.239 Pool:0xb57e4b70 Media RTP data size cannot be changed to 1920, fixed at 528
2010/12/13 10:11:55.239 Pool:0xb57e4b70 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 10:11:55.239 Pool:0xb57e4b70 Media Set data size from 16 to 320
2010/12/13 10:11:55.239 Pool:0xb57e4b70 OpalCon Source stream of connection Call[d7d54c08b1]-EP<modem>[modem:/d7d54c08b1/0] uses patch Patch AudioModemMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-G.711-uLaw-64k
2010/12/13 10:11:55.239 Pool:0xb57e4b70 OpalCon Sink stream of connection Call[d7d54c08b1]-EP<sip>[aaaf45e0-b204-e011-962e-000c2984a855] uses patch Patch AudioModemMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-G.711-uLaw-64k
2010/12/13 10:11:55.239 Pool:0xb57e4b70 OpalCon SetPhase from ProceedingPhase to AlertingPhase for Call[d7d54c08b1]-EP<sip>[aaaf45e0-b204-e011-962e-000c2984a855]
2010/12/13 10:11:55.239 Pool:0xb57e4b70 OpalMan OnAlerting Call[d7d54c08b1]-EP<sip>[aaaf45e0-b204-e011-962e-000c2984a855]
2010/12/13 10:11:55.239 Pool:0xb57e4b70 Call OnAlerting Call[d7d54c08b1]-EP<sip>[aaaf45e0-b204-e011-962e-000c2984a855]
2010/12/13 10:11:55.239 Pool:0xb57e4b70 ModemConnection::SetAlerting Call[d7d54c08b1]-EP<modem>[modem:/d7d54c08b1/0] 0294824222@xxxxxxxxxxxxxxxxxxxx 1
2010/12/13 10:11:55.239 Pool:0xb57e4b70 OpalCon SetPhase from SetUpPhase to AlertingPhase for Call[d7d54c08b1]-EP<modem>[modem:/d7d54c08b1/0]
2010/12/13 10:11:55.239 Pool:0xb57e4b70 SIP Starting receive media to annunciate remote progress tones
2010/12/13 10:11:55.239 Pool:0xb57e4b70 PWLib File handle high water mark set: 58 Thread unblock pipe
2010/12/13 10:11:55.239 Pool:0xb57e4b70 PTLib Thread high water mark set: 23
2010/12/13 10:11:55.239 Media Patch:0xb57a3b70 Patch Thread started for Patch OpalRTPMediaStream-Source-G.711-uLaw-64k -> AudioModemMediaStream-Sink-PCM-16
2010/12/13 10:11:55.239 Pool:0xb57e4b70 Media Starting thread Media Patch:0xb57a3b70
2010/12/13 10:11:55.239 Pool:0xb57e4b70 PWLib File handle high water mark set: 60 Thread unblock pipe
2010/12/13 10:11:55.239 Pool:0xb57e4b70 PTLib Thread high water mark set: 24
2010/12/13 10:11:55.239 Media Patch:0xb5762b70 Patch Thread started for Patch AudioModemMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-G.711-uLaw-64k
2010/12/13 10:11:55.239 Media Patch:0xb5762b70 RTP Session 1, first sent data: ver=2 pt=PCMU psz=160 m=1 x=0 seq=63378 ts=0 src="" ccnt=0
2010/12/13 10:11:55.240 Pool:0xb57e4b70 Media Starting thread Media Patch:0xb5762b70
2010/12/13 10:11:55.240 Pool:0xb57e4b70 OpalCon Media stream threads started.
2010/12/13 10:11:55.240 Pool:0xb57e4b70 SIP Handled PDU "2 INVITE <183>"
2010/12/13 10:11:55.424 Media Patch:0xb57a3b70 RTP Session 1, first receive data: ver=2 pt=PCMU psz=160 m=1 x=0 seq=12620 ts=1829611662 src="" ccnt=0
2010/12/13 10:11:56.351 Opal Liste...0xb5b3db70 PWLib File handle high water mark set: 61 PUDPSocket
2010/12/13 10:11:56.351 Opal Liste...0xb5b3db70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 10:11:56.351 Opal Liste...0xb5b3db70 SIP Waiting for PDU on udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
2010/12/13 10:11:56.352 Opal Liste...0xb5b3db70 SIP PDU received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
SIP/2.0 183 Session Progress
CSeq: 2 INVITE
Via: SIP/2.0/UDP 10.11.12.22:5060;received=203.206.207.88;branch=z9hG4bKf20b51e0-b204-e011-962e-000c2984a855;rport=39236
From: "root" <sip:<my_username>@sip.nsw.iinet.net.au>;tag=aaaf45e0-b204-e011-962e-000c2984a855
Call-ID: 02b745e0-b204-e011-962e-000c2984a855@xxxxxxxxxxxxxxxxxxx
Supported:
To: <sip:0294824222@xxxxxxxxxxxxxxxxxxxx>;tag=SD188l999-108772045-1292195517361
Session: Media
Contact: <sip:0294824222@xxxxxxxxxxxxxx:5060;transport=udp>
Allow: ACK,BYE,CANCEL,INFO,INVITE,OPTIONS,PRACK,REFER,NOTIFY,UPDATE
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 224
v=0
o=BroadWorks 109648391 1 IN IP4 203.55.231.194
s=-
c=IN IP4 203.55.231.194
t=0 0
m=audio 42438 RTP/AVP 0 101
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 10:11:56.352 Opal Liste...0xb5b3db70 SIP Queueing PDU "2 INVITE <183>", transaction=z9hG4bKf20b51e0-b204-e011-962e-000c2984a855, token=aaaf45e0-b204-e011-962e-000c2984a855
2010/12/13 10:11:56.352 Opal Liste...0xb5b3db70 Opal Transport clean up on termination
2010/12/13 10:11:56.352 Opal Liste...0xb5b3db70 Opal Transport Close
2010/12/13 10:11:56.352 Opal Liste...0xb5b3db70 Opal Deleted transport udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
2010/12/13 10:11:56.352 Pool:0xb57e4b70 SIP Handling PDU "2 INVITE <183>" for transaction=z9hG4bKf20b51e0-b204-e011-962e-000c2984a855
2010/12/13 10:11:56.353 Pool:0xb57e4b70 SDP Parsed connection address udp$203.55.231.194
2010/12/13 10:11:56.353 Pool:0xb57e4b70 SDP Parsed connection address udp$203.55.231.194
2010/12/13 10:11:56.353 Pool:0xb57e4b70 SDP Media session port=42438
2010/12/13 10:11:56.353 Pool:0xb57e4b70 SDP Parsed connection address udp$203.55.231.194:42438
2010/12/13 10:11:56.353 Pool:0xb57e4b70 SDP Parsed media session with 2 'audio' formats
2010/12/13 10:11:56.353 Pool:0xb57e4b70 SIP No User-Agent or Server fields, Product Info unknown.
2010/12/13 10:11:56.353 Pool:0xb57e4b70 SIP INVITE transaction id=z9hG4bKf20b51e0-b204-e011-962e-000c2984a855 proceeding.
2010/12/13 10:11:56.353 Pool:0xb57e4b70 SIP Received Session Progress response
2010/12/13 10:11:56.353 Pool:0xb57e4b70 SIP Processing received SDP media description for audio
2010/12/13 10:11:56.353 Pool:0xb57e4b70 SIP RTP payload type PCMU matched to codec G.711-uLaw-64k
2010/12/13 10:11:56.353 Pool:0xb57e4b70 MediaFormat Removing codecs
2010/12/13 10:11:56.353 Pool:0xb57e4b70 Call IsMediaBypassPossible Call[d7d54c08b1]-EP<sip>[aaaf45e0-b204-e011-962e-000c2984a855] session 1
2010/12/13 10:11:56.353 Pool:0xb57e4b70 OpalMan IsMediaBypassPossible: session 1
2010/12/13 10:11:56.353 Pool:0xb57e4b70 OpalCon IsMediaBypassPossible: default returns false
2010/12/13 10:11:56.353 Pool:0xb57e4b70 RTP Found existing RTP session 1
2010/12/13 10:11:56.354 Pool:0xb57e4b70 RTP Found existing media session 1
2010/12/13 10:11:56.354 Pool:0xb57e4b70 SIP Starting receive media to annunciate remote progress tones
2010/12/13 10:11:56.354 Pool:0xb57e4b70 OpalCon Media stream threads started.
2010/12/13 10:11:56.354 Pool:0xb57e4b70 SIP Handled PDU "2 INVITE <183>"
2010/12/13 10:11:56.412 Media Patch:0xb57a3b70 Patch Removing comfort noise frame with payload type CN
2010/12/13 10:11:57.195 Opal Liste...0xb5b3db70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 10:11:57.195 Opal Liste...0xb5b3db70 SIP Waiting for PDU on udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
2010/12/13 10:11:57.196 Opal Liste...0xb5b3db70 SIP PDU received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
SIP/2.0 183 Session Progress
CSeq: 2 INVITE
Via: SIP/2.0/UDP 10.11.12.22:5060;received=203.206.207.88;branch=z9hG4bKf20b51e0-b204-e011-962e-000c2984a855;rport=39236
From: "root" <sip:<my_username>@sip.nsw.iinet.net.au>;tag=aaaf45e0-b204-e011-962e-000c2984a855
Call-ID: 02b745e0-b204-e011-962e-000c2984a855@xxxxxxxxxxxxxxxxxxx
Supported:
To: <sip:0294824222@xxxxxxxxxxxxxxxxxxxx>;tag=SD188l999-108772045-1292195517361
Session: Media
Contact: <sip:0294824222@xxxxxxxxxxxxxx:5060;transport=udp>
Allow: ACK,BYE,CANCEL,INFO,INVITE,OPTIONS,PRACK,REFER,NOTIFY,UPDATE
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 224
v=0
o=BroadWorks 109648391 1 IN IP4 203.55.231.194
s=-
c=IN IP4 20