 HylaFAX The world's
most advanced open source fax server
|
[Date Prev][Date Next][Thread Prev][Thread Next] [Date Index] [Thread Index]
[hylafax-users] t38modem issue with NO CARRIER
- To: hylafax-users@xxxxxxxxxxx
- Subject: [hylafax-users] t38modem issue with NO CARRIER
- From: John Park <john@xxxxxxxxxxxxxxx>
- Date: Mon, 13 Dec 2010 22:41:59 +1100
Hi everyone,
I've been trying to get t38modem working with hylafax for the past 2 days and I've hit a wall. I've been able to configure t38modem to the point where it initiates a call via hylafax but I can't seem to get it to handshake/connect with a remote fax machine via my SIP provider. When I dial a landline I can hear "beeps" when I pick up the call. The trace log reveals NO CARRIER.
I'm hoping that someone here might be able to help me get this working. I'd really appreciate some help!!!
My t38modem arguments are:
t38modem -ttt -o /var/log/t38modem.log --sip-proxy <sip_username>:<sip_password>@sip.nsw.iinet.net.au --sip-register <sip_username>@sip.nsw.iinet.net.au,<sip_password>,<sip_username>,iinetphone.iinet.net.au --no-h323 --ptty +/dev/ttyT38a --route 'modem:.*=sip:<dn>@sip.nsw.iinet.net.au' -u <sip_username>
My trace log is below:
2010/12/13 22:29:06.090 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 22:29:06.090
2010/12/13 22:29:06.090 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 22:29:06.090 T38Modem Options: -ttt -o /var/log/t38modem.log --sip-proxy <sip_username>:<sip_password>@sip.nsw.iinet.net.au --sip-register <sip_username>@sip.nsw.iinet.net.au,<sip_password>,<sip_username>,iinetphone.iinet.net.au --no-h323 --ptty +/dev/ttyT38a --route modem:.*=sip:<dn>@sip.nsw.iinet.net.au -u <sip_username>
2010/12/13 22:29:06.090 T38Modem Config:
2010/12/13 22:29:06.090 T38Modem --no-h323=True
2010/12/13 22:29:06.090 T38Modem --sip-proxy=<sip_username>:<sip_password>@sip.nsw.iinet.net.au
2010/12/13 22:29:06.090 T38Modem --sip-register=<sip_username>@sip.nsw.iinet.net.au,<sip_password>,<sip_username>,iinetphone.iinet.net.au
2010/12/13 22:29:06.090 T38Modem --ptty=+/dev/ttyT38a
2010/12/13 22:29:06.090 T38Modem --route=modem:.*=sip:<dn>@sip.nsw.iinet.net.au
2010/12/13 22:29:06.090 T38Modem --username=<sip_username>
2010/12/13 22:29:06.090 T38Modem --trace=True
2010/12/13 22:29:06.090 T38Modem --output=/var/log/t38modem.log
2010/12/13 22:29:06.091 T38Modem Detecting V4L2 devices
2010/12/13 22:29:06.091 T38Modem Unable to detect v4l2 directory
2010/12/13 22:29:06.104 T38Modem OpalMan Attached endpoint with prefix modem
2010/12/13 22:29:06.104 T38Modem ModemEndPoint::ModemEndPoint
2010/12/13 22:29:06.105 T38Modem PseudoModemList::Append ttyT38a (0) OK
2010/12/13 22:29:06.105 T38Modem OpalMan Attached endpoint with prefix sip
2010/12/13 22:29:06.105 T38Modem OpalMan Attached endpoint with prefix sips
2010/12/13 22:29:06.106 Opal Liste...0xb5d76b70 Listen Started listening thread on udp$*:5060
2010/12/13 22:29:06.107 ttyT38a(b)...0xb5e39b70 RenameCurrentThread old ThreadName=PseudoModemPty:0xb5e39b70
2010/12/13 22:29:06.107 ttyT38a(b)...0xb5e39b70 Started for /dev/ttyT38a (accepts all)
2010/12/13 22:29:06.107 ttyT38a(b)...0xb5e39b70 ModemEndPoint::OnMyCallback command=addmodem extra=10
2010/12/13 22:29:06.107 ttyT38a(b)...0xb5e39b70 PseudoModemQ::Enqueue ttyT38a
2010/12/13 22:29:06.107 ttyT38a(b)...0xb5e39b70 ModemEndPoint::OnMyCallback request={
command=addmodem
response=confirm
modemtoken=ttyT38a
}
2010/12/13 22:29:06.107 ttyT38a(b)...0xb5e39b70 PseudoModemPty::OpenPty removed link /dev/ttyT38a -> /dev/pts/1
2010/12/13 22:29:06.107 ttyT38a(b)...0xb5e39b70 PseudoModemPty::OpenPty added link /dev/ttyT38a -> /dev/pts/1
2010/12/13 22:29:06.107 Opal Liste...0xb5d35b70 Listen Started listening thread on tcp$*:5060
2010/12/13 22:29:06.107 ttyT38a(e)...0xb5cf3b70 RenameCurrentThread old ThreadName=ModemEngine:0xb5cf3b70
2010/12/13 22:29:06.107 ttyT38a(e)...0xb5cf3b70 <-> Started
2010/12/13 22:29:06.107 ttyT38a(i)...0xb5cb2b70 RenameCurrentThread old ThreadName=InPty:0xb5cb2b70
2010/12/13 22:29:06.107 ttyT38a(i)...0xb5cb2b70 --> Started
2010/12/13 22:29:06.108 ttyT38a(o)...0xb5c71b70 RenameCurrentThread old ThreadName=OutPty:0xb5c71b70
2010/12/13 22:29:06.108 ttyT38a(o)...0xb5c71b70 <-- Started
2010/12/13 22:29:06.109 Opal Liste...0xb5c30b70 Listen Started listening thread on tcps$*:5061
2010/12/13 22:29:06.110 T38Modem OpalUDP Binding to interface: 0.0.0.0:5060
2010/12/13 22:29:06.110 T38Modem OpalUDP Started connect to 203.55.231.194:5060
2010/12/13 22:29:06.112 T38Modem SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 22:29:06.112 T38Modem SIP Sending PDU REGISTER sip:sip.nsw.iinet.net.au (612 bytes) to: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:29:06.112 T38Modem OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 22:29:06.133 Opal Liste...0xb5d76b70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 22:29:06.133 Opal Liste...0xb5d76b70 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 22:29:06.133 Opal Liste...0xb5d76b70 SIP PDU 200 OK received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:29:06.134 Opal Liste...0xb5d76b70 Opal Transport clean up on termination
2010/12/13 22:29:06.134 Pool:0xb5be9b70 SIP REGISTER transaction id=z9hG4bK54bbfedc-1905-e011-83f2-000c2984a855 completed.
2010/12/13 22:29:06.134 Pool:0xb5be9b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 22:29:06.134 Pool:0xb5be9b70 SIP Expiry time for REGISTER set to 30 seconds.
2010/12/13 22:29:11.135 Housekeeper:0xb5db7b70 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bK54bbfedc-1905-e011-83f2-000c2984a855
2010/12/13 22:29:21.134 Housekeeper:0xb5db7b70 SIP Starting REGISTER for binding refresh
2010/12/13 22:29:21.136 Housekeeper:0xb5db7b70 SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 22:29:21.136 Housekeeper:0xb5db7b70 SIP Sending PDU REGISTER sip:sip.nsw.iinet.net.au (611 bytes) to: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:29:21.136 Housekeeper:0xb5db7b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 22:29:21.156 Opal Liste...0xb5d76b70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 22:29:21.157 Opal Liste...0xb5d76b70 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 22:29:21.157 Opal Liste...0xb5d76b70 SIP PDU 200 OK received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:29:21.157 Opal Liste...0xb5d76b70 Opal Transport clean up on termination
2010/12/13 22:29:21.157 Pool:0xb5be9b70 SIP REGISTER transaction id=z9hG4bKb043f3e5-1905-e011-83f2-000c2984a855 completed.
2010/12/13 22:29:21.157 Pool:0xb5be9b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 22:29:21.158 Pool:0xb5be9b70 SIP Expiry time for REGISTER set to 30 seconds.
2010/12/13 22:29:26.158 Housekeeper:0xb5db7b70 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bKb043f3e5-1905-e011-83f2-000c2984a855
2010/12/13 22:29:27.781 ttyT38a(i)...0xb5cb2b70 --> read ERROR -1 Input/output error
2010/12/13 22:29:27.781 ttyT38a(i)...0xb5cb2b70 --> Stopped
2010/12/13 22:29:27.815 ttyT38a(o)...0xb5c71b70 <-- Stopped
2010/12/13 22:29:27.825 ttyT38a(e)...0xb5cf3b70 <-> Stopped
2010/12/13 22:29:27.826 ttyT38a(e)...0xb5cf3b70 RenameCurrentThread old ThreadName=ModemEngine:0xb5cf3b70
2010/12/13 22:29:27.826 ttyT38a(e)...0xb5cf3b70 <-> Started
2010/12/13 22:29:27.826 ttyT38a(i)...0xb5c71b70 RenameCurrentThread old ThreadName=InPty:0xb5c71b70
2010/12/13 22:29:27.826 ttyT38a(i)...0xb5c71b70 --> Started
2010/12/13 22:29:27.826 ttyT38a(o)...0xb5cb2b70 RenameCurrentThread old ThreadName=OutPty:0xb5cb2b70
2010/12/13 22:29:27.826 ttyT38a(o)...0xb5cb2b70 <-- Started
2010/12/13 22:29:28.795 ttyT38a(e)...0xb5cf3b70 --> ATZ
2010/12/13 22:29:28.795 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 22:29:31.903 ttyT38a(e)...0xb5cf3b70 --> ATS0=0
2010/12/13 22:29:31.903 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 22:29:32.011 ttyT38a(e)...0xb5cf3b70 --> ATE0
2010/12/13 22:29:32.011 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 22:29:32.119 ttyT38a(e)...0xb5cf3b70 --> ATV1
2010/12/13 22:29:32.119 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 22:29:32.227 ttyT38a(e)...0xb5cf3b70 --> ATQ0
2010/12/13 22:29:32.227 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 22:29:32.335 ttyT38a(e)...0xb5cf3b70 --> ATS8=2
2010/12/13 22:29:32.335 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 22:29:32.443 ttyT38a(e)...0xb5cf3b70 --> ATS7=60
2010/12/13 22:29:32.443 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 22:29:32.551 ttyT38a(e)...0xb5cf3b70 --> AT+FCLASS=?
2010/12/13 22:29:32.551 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 31 2c 38 0d 0a 4f 4b 0d 0a ..1,8..OK.. }
2010/12/13 22:29:32.659 ttyT38a(e)...0xb5cf3b70 --> AT+FCLASS=1
2010/12/13 22:29:32.659 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 22:29:32.768 ttyT38a(e)...0xb5cf3b70 --> ATI3
2010/12/13 22:29:32.768 ttyT38a(e)...0xb5cf3b70 <-- {
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 22:29:32.875 ttyT38a(e)...0xb5cf3b70 --> ATI0
2010/12/13 22:29:32.875 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 54 33 38 46 41 58 0d 0a 4f 4b 0d 0a ..T38FAX..OK.. }
2010/12/13 22:29:32.983 ttyT38a(e)...0xb5cf3b70 --> AT+FREV?
2010/12/13 22:29:32.983 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 31 2e 32 2e 30 0d 0a 4f 4b 0d 0a ..1.2.0..OK.. }
2010/12/13 22:29:33.091 ttyT38a(e)...0xb5cf3b70 --> AT+FTM=?
2010/12/13 22:29:33.091 ttyT38a(e)...0xb5cf3b70 <-- {
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 22:29:33.199 ttyT38a(e)...0xb5cf3b70 --> AT+FRM=?
2010/12/13 22:29:33.199 ttyT38a(e)...0xb5cf3b70 <-- {
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 22:29:33.307 ttyT38a(e)...0xb5cf3b70 --> ATM0
2010/12/13 22:29:33.307 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 22:29:36.158 Housekeeper:0xb5db7b70 SIP Starting REGISTER for binding refresh
2010/12/13 22:29:36.159 Housekeeper:0xb5db7b70 SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 22:29:36.159 Housekeeper:0xb5db7b70 SIP Sending PDU REGISTER sip:sip.nsw.iinet.net.au (611 bytes) to: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:29:36.160 Housekeeper:0xb5db7b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 22:29:36.181 Opal Liste...0xb5d76b70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 22:29:36.181 Opal Liste...0xb5d76b70 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 22:29:36.181 Opal Liste...0xb5d76b70 SIP PDU 200 OK received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:29:36.182 Opal Liste...0xb5d76b70 Opal Transport clean up on termination
2010/12/13 22:29:36.182 Pool:0xb5be9b70 SIP REGISTER transaction id=z9hG4bKaca4e7ee-1905-e011-83f2-000c2984a855 completed.
2010/12/13 22:29:36.182 Pool:0xb5be9b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 22:29:36.184 Pool:0xb5be9b70 SIP Expiry time for REGISTER set to 30 seconds.
2010/12/13 22:29:41.185 Housekeeper:0xb5db7b70 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bKaca4e7ee-1905-e011-83f2-000c2984a855
2010/12/13 22:29:46.454 ttyT38a(i)...0xb5c71b70 --> read ERROR -1 Input/output error
2010/12/13 22:29:46.454 ttyT38a(i)...0xb5c71b70 --> Stopped
2010/12/13 22:29:46.455 ttyT38a(o)...0xb5cb2b70 <-- Stopped
2010/12/13 22:29:46.465 ttyT38a(e)...0xb5cf3b70 <-> Stopped
2010/12/13 22:29:46.475 ttyT38a(b)...0xb5e39b70 PseudoModemPty::OpenPty removed link /dev/ttyT38a -> /dev/pts/1
2010/12/13 22:29:46.475 ttyT38a(b)...0xb5e39b70 PseudoModemPty::OpenPty read ERROR -1 Input/output error
2010/12/13 22:29:46.476 ttyT38a(b)...0xb5e39b70 PseudoModemPty::OpenPty added link /dev/ttyT38a -> /dev/pts/1
2010/12/13 22:29:46.476 ttyT38a(e)...0xb5cf3b70 RenameCurrentThread old ThreadName=ModemEngine:0xb5cf3b70
2010/12/13 22:29:46.476 ttyT38a(e)...0xb5cf3b70 <-> Started
2010/12/13 22:29:46.476 ttyT38a(i)...0xb5cb2b70 RenameCurrentThread old ThreadName=InPty:0xb5cb2b70
2010/12/13 22:29:46.476 ttyT38a(i)...0xb5cb2b70 --> Started
2010/12/13 22:29:46.476 ttyT38a(o)...0xb5c71b70 RenameCurrentThread old ThreadName=OutPty:0xb5c71b70
2010/12/13 22:29:46.476 ttyT38a(o)...0xb5c71b70 <-- Started
2010/12/13 22:29:51.185 Housekeeper:0xb5db7b70 SIP Starting REGISTER for binding refresh
2010/12/13 22:29:51.186 Housekeeper:0xb5db7b70 SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 22:29:51.186 Housekeeper:0xb5db7b70 SIP Sending PDU REGISTER sip:sip.nsw.iinet.net.au (611 bytes) to: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:29:51.186 Housekeeper:0xb5db7b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 22:29:51.207 Opal Liste...0xb5d76b70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 22:29:51.207 Opal Liste...0xb5d76b70 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 22:29:51.207 Opal Liste...0xb5d76b70 SIP PDU 200 OK received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:29:51.207 Opal Liste...0xb5d76b70 Opal Transport clean up on termination
2010/12/13 22:29:51.207 Pool:0xb5be9b70 SIP REGISTER transaction id=z9hG4bK008adcf7-1905-e011-83f2-000c2984a855 completed.
2010/12/13 22:29:51.207 Pool:0xb5be9b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 22:29:51.208 Pool:0xb5be9b70 SIP Expiry time for REGISTER set to 30 seconds.
2010/12/13 22:29:52.403 ttyT38a(e)...0xb5cf3b70 --> ATZ
2010/12/13 22:29:52.403 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 22:29:55.511 ttyT38a(e)...0xb5cf3b70 --> ATS0=0
2010/12/13 22:29:55.511 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 22:29:55.619 ttyT38a(e)...0xb5cf3b70 --> ATE0
2010/12/13 22:29:55.619 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 22:29:55.743 ttyT38a(e)...0xb5cf3b70 --> ATV1
2010/12/13 22:29:55.743 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 22:29:55.851 ttyT38a(e)...0xb5cf3b70 --> ATQ0
2010/12/13 22:29:55.851 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 22:29:55.959 ttyT38a(e)...0xb5cf3b70 --> ATS8=2
2010/12/13 22:29:55.959 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 22:29:56.067 ttyT38a(e)...0xb5cf3b70 --> ATS7=60
2010/12/13 22:29:56.067 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 22:29:56.175 ttyT38a(e)...0xb5cf3b70 --> AT+FCLASS=?
2010/12/13 22:29:56.175 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 31 2c 38 0d 0a 4f 4b 0d 0a ..1,8..OK.. }
2010/12/13 22:29:56.208 Housekeeper:0xb5db7b70 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bK008adcf7-1905-e011-83f2-000c2984a855
2010/12/13 22:29:56.283 ttyT38a(e)...0xb5cf3b70 --> AT+FCLASS=1
2010/12/13 22:29:56.283 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 22:29:56.391 ttyT38a(e)...0xb5cf3b70 --> ATI3
2010/12/13 22:29:56.391 ttyT38a(e)...0xb5cf3b70 <-- {
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 22:29:56.499 ttyT38a(e)...0xb5cf3b70 --> ATI0
2010/12/13 22:29:56.499 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 54 33 38 46 41 58 0d 0a 4f 4b 0d 0a ..T38FAX..OK.. }
2010/12/13 22:29:56.607 ttyT38a(e)...0xb5cf3b70 --> AT+FREV?
2010/12/13 22:29:56.607 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 31 2e 32 2e 30 0d 0a 4f 4b 0d 0a ..1.2.0..OK.. }
2010/12/13 22:29:56.715 ttyT38a(e)...0xb5cf3b70 --> AT+FTM=?
2010/12/13 22:29:56.715 ttyT38a(e)...0xb5cf3b70 <-- {
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 22:29:56.823 ttyT38a(e)...0xb5cf3b70 --> AT+FRM=?
2010/12/13 22:29:56.823 ttyT38a(e)...0xb5cf3b70 <-- {
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 22:29:56.931 ttyT38a(e)...0xb5cf3b70 --> ATM0
2010/12/13 22:29:56.931 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 22:29:57.042 ttyT38a(e)...0xb5cf3b70 --> AT+FCLASS=1
2010/12/13 22:29:57.042 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 22:29:57.059 ttyT38a(e)...0xb5cf3b70 --> ATDT0294396443
2010/12/13 22:29:57.059 ttyT38a(e)...0xb5cf3b70 ModemEndPoint::OnMyCallback command=dial extra=3
2010/12/13 22:29:57.059 ttyT38a(e)...0xb5cf3b70 PseudoModemQ::Dequeue ttyT38a
2010/12/13 22:29:57.059 ttyT38a(e)...0xb5cf3b70 MyManager::OnMyCallback SetUpCall(modem:, 0294396443@+/dev/ttyT38a)
2010/12/13 22:29:57.059 ttyT38a(e)...0xb5cf3b70 OpalMan Set up call from modem: to 0294396443@+/dev/ttyT38a
2010/12/13 22:29:57.059 ttyT38a(e)...0xb5cf3b70 Call Created Call[v1fc960211]
2010/12/13 22:29:57.059 ttyT38a(e)...0xb5cf3b70 OpalMan Set up connection to "modem:"
2010/12/13 22:29:57.059 ttyT38a(e)...0xb5cf3b70 ModemEndPoint::MakeConnection modem:
2010/12/13 22:29:57.059 ttyT38a(e)...0xb5cf3b70 OpalCon Created connection Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0]
2010/12/13 22:29:57.059 ttyT38a(e)...0xb5cf3b70 ModemConnection::ModemConnection Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0]
2010/12/13 22:29:57.059 ttyT38a(e)...0xb5cf3b70 ModemConnection::SetUpConnection Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0]
2010/12/13 22:29:57.059 ttyT38a(e)...0xb5cf3b70 OpalCon SetPhase from UninitialisedPhase to SetUpPhase for Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0]
2010/12/13 22:29:57.059 ttyT38a(e)...0xb5cf3b70 OpalMan OnIncoming connection Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0]
2010/12/13 22:29:57.059 ttyT38a(e)...0xb5cf3b70 Call GetOtherPartyConnection Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0]
2010/12/13 22:29:57.060 ttyT38a(e)...0xb5cf3b70 OpalMan Set up connection to "sip:0294396443@xxxxxxxxxxxxxxxxxxxx"
2010/12/13 22:29:57.060 ttyT38a(e)...0xb5cf3b70 MySIPEndPoint::CreateConnection for Call[v1fc960211]
2010/12/13 22:29:57.060 ttyT38a(e)...0xb5cf3b70 OpalCon Created connection Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855]
2010/12/13 22:29:57.060 ttyT38a(e)...0xb5cf3b70 Call[v1fc960211] from modem:<sip_username> to 0294396443@+/dev/ttyT38a, route to sip:udp$sip.nsw.iinet.net.au:5060
2010/12/13 22:29:57.060 ttyT38a(e)...0xb5cf3b70 Outgoing call routed to sip:0294396443@xxxxxxxxxxxxxxxxxxxx for Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0]
2010/12/13 22:29:57.060 ttyT38a(e)...0xb5cf3b70 Call OnSetUp Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0]
2010/12/13 22:29:57.060 ttyT38a(e)...0xb5cf3b70 MySIPConnection::SetUpConnection Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855] name=<sip_username>
2010/12/13 22:29:57.060 ttyT38a(e)...0xb5cf3b70 SIP SetUpConnection: sip:0294396443@xxxxxxxxxxxxxxxxxxxx
2010/12/13 22:29:57.060 ttyT38a(e)...0xb5cf3b70 OpalCon SetPhase from UninitialisedPhase to SetUpPhase for Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855]
2010/12/13 22:29:57.061 ttyT38a(e)...0xb5cf3b70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 22:29:57.061 ttyT38a(e)...0xb5cf3b70 OpalUDP Started connect to 203.55.231.194:5060
2010/12/13 22:29:57.063 ttyT38a(e)...0xb5cf3b70 SIP Creating INVITE request
2010/12/13 22:29:57.063 ttyT38a(e)...0xb5cf3b70 ModemConnection::GetMediaFormats Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0]
2010/12/13 22:29:57.063 ttyT38a(e)...0xb5cf3b70 ModemEndPoint::GetMediaFormats
2010/12/13 22:29:57.065 ttyT38a(e)...0xb5cf3b70 SIP Offering media type audio in SDP with formats
G.711-uLaw-64k,G.711-ALaw-64k,PCM-16,T.38
2010/12/13 22:29:57.065 ttyT38a(e)...0xb5cf3b70 Call IsMediaBypassPossible Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855] session 1
2010/12/13 22:29:57.065 ttyT38a(e)...0xb5cf3b70 OpalMan IsMediaBypassPossible: session 1
2010/12/13 22:29:57.065 ttyT38a(e)...0xb5cf3b70 RTP Cannot find media session 1
2010/12/13 22:29:57.065 ttyT38a(e)...0xb5cf3b70 RTP Cannot find RTP session 1
2010/12/13 22:29:57.066 ttyT38a(e)...0xb5cf3b70 RTP_UDP Session 1 created: 10.11.12.22:5000-5001 ssrc=2910563838
2010/12/13 22:29:57.066 ttyT38a(e)...0xb5cf3b70 RTP Creating new session RTP_UDP
2010/12/13 22:29:57.066 ttyT38a(e)...0xb5cf3b70 RTP Found existing media session 1
2010/12/13 22:29:57.066 ttyT38a(e)...0xb5cf3b70 SIP Using default RTP payload [pt=101] for UserInput/RFC2833
2010/12/13 22:29:57.066 ttyT38a(e)...0xb5cf3b70 SIP Using default RTP payload [pt=100] for NamedSignalEvent
2010/12/13 22:29:57.066 ttyT38a(e)...0xb5cf3b70 ModemConnection::GetMediaFormats Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0]
2010/12/13 22:29:57.066 ttyT38a(e)...0xb5cf3b70 ModemEndPoint::GetMediaFormats
2010/12/13 22:29:57.068 ttyT38a(e)...0xb5cf3b70 SIP No media formats of type video, not adding SDP
2010/12/13 22:29:57.069 ttyT38a(e)...0xb5cf3b70 SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 22:29:57.069 ttyT38a(e)...0xb5cf3b70 SIP Sending PDU INVITE sip:0294396443@xxxxxxxxxxxxxxxxxxxx (980 bytes) to: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:29:57.069 ttyT38a(e)...0xb5cf3b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 22:29:57.069 ttyT38a(e)...0xb5cf3b70 OpalCon OnSetUpConnectionCall[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855]
2010/12/13 22:29:57.069 ttyT38a(e)...0xb5cf3b70 OpalEP OnSetUpConnection Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855]
2010/12/13 22:29:57.069 ttyT38a(e)...0xb5cf3b70 OpalMan SetUpCall succeeded, call=Call[v1fc960211]
2010/12/13 22:29:57.070 ttyT38a(e)...0xb5cf3b70 ttyT38a T38Engine::T38Engine
2010/12/13 22:29:57.070 ttyT38a(e)...0xb5cf3b70 ModemEndPoint::OnMyCallback request={
calltoken=modem:/v1fc960211/0
localpartyname=
command=dial
response=confirm
number=0294396443
modemtoken=ttyT38a
}
2010/12/13 22:29:57.092 Opal Liste...0xb5d76b70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 22:29:57.092 Opal Liste...0xb5d76b70 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 22:29:57.093 Opal Liste...0xb5d76b70 SIP PDU 100 Trying received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:29:57.093 Opal Liste...0xb5d76b70 Opal Transport clean up on termination
2010/12/13 22:29:57.093 Pool:0xb5ba8b70 SIP INVITE transaction id=z9hG4bK804e5dfb-1905-e011-83f2-000c2984a855 proceeding.
2010/12/13 22:29:57.093 Pool:0xb5ba8b70 SIP Received Trying response
2010/12/13 22:29:57.093 Pool:0xb5ba8b70 OpalCon SetPhase from SetUpPhase to ProceedingPhase for Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855]
2010/12/13 22:29:57.093 Pool:0xb5ba8b70 OpalMan OnProceeding Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855]
2010/12/13 22:29:57.093 Pool:0xb5ba8b70 Call OnProceeding Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855]
2010/12/13 22:29:57.166 Opal Liste...0xb5d76b70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 22:29:57.166 Opal Liste...0xb5d76b70 SIP PDU 401 Unauthorized received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:29:57.166 Opal Liste...0xb5d76b70 Opal Transport clean up on termination
2010/12/13 22:29:57.167 Pool:0xb5ba8b70 SIP Sending PDU ACK sip:0294396443@xxxxxxxxxxxxxxxxxxxx (477 bytes) to: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:29:57.168 Pool:0xb5ba8b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 22:29:57.168 Pool:0xb5ba8b70 SIP INVITE transaction id=z9hG4bK804e5dfb-1905-e011-83f2-000c2984a855 completed.
2010/12/13 22:29:57.168 Pool:0xb5ba8b70 SIP Received Authentication Required response
2010/12/13 22:29:57.168 Pool:0xb5ba8b70 SIP Authentication contains qop-options auth
2010/12/13 22:29:57.169 Pool:0xb5ba8b70 SIP Found auth info for realm "iinetphone.iinet.net.au", user "<sip_username>"
2010/12/13 22:29:57.169 Pool:0xb5ba8b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 22:29:57.170 Pool:0xb5ba8b70 SIP Creating INVITE request
2010/12/13 22:29:57.170 Pool:0xb5ba8b70 ModemConnection::GetMediaFormats Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0]
2010/12/13 22:29:57.170 Pool:0xb5ba8b70 ModemEndPoint::GetMediaFormats
2010/12/13 22:29:57.172 Pool:0xb5ba8b70 SIP Offering media type audio in SDP with formats
G.711-uLaw-64k,G.711-ALaw-64k,PCM-16,T.38
2010/12/13 22:29:57.172 Pool:0xb5ba8b70 Call IsMediaBypassPossible Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855] session 1
2010/12/13 22:29:57.172 Pool:0xb5ba8b70 OpalMan IsMediaBypassPossible: session 1
2010/12/13 22:29:57.172 Pool:0xb5ba8b70 RTP Found existing media session 1
2010/12/13 22:29:57.172 Pool:0xb5ba8b70 RTP Found existing RTP session 1
2010/12/13 22:29:57.173 Pool:0xb5ba8b70 SIP Using default RTP payload [pt=101] for UserInput/RFC2833
2010/12/13 22:29:57.173 Pool:0xb5ba8b70 SIP Using default RTP payload [pt=100] for NamedSignalEvent
2010/12/13 22:29:57.173 Pool:0xb5ba8b70 ModemConnection::GetMediaFormats Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0]
2010/12/13 22:29:57.173 Pool:0xb5ba8b70 ModemEndPoint::GetMediaFormats
2010/12/13 22:29:57.174 Pool:0xb5ba8b70 SIP No media formats of type video, not adding SDP
2010/12/13 22:29:57.175 Pool:0xb5ba8b70 SIP Adding authentication information for user "<sip_username>" at realm "iinetphone.iinet.net.au"
2010/12/13 22:29:57.175 Pool:0xb5ba8b70 SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 22:29:57.175 Pool:0xb5ba8b70 SIP Sending PDU INVITE sip:0294396443@xxxxxxxxxxxxxxxxxxxx (1269 bytes) to: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:29:57.175 Pool:0xb5ba8b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 22:29:57.227 Opal Liste...0xb5d76b70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 22:29:57.227 Opal Liste...0xb5d76b70 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 22:29:57.227 Opal Liste...0xb5d76b70 SIP PDU 100 Trying received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:29:57.227 Opal Liste...0xb5d76b70 Opal Transport clean up on termination
2010/12/13 22:29:57.228 Pool:0xb5ba8b70 SIP INVITE transaction id=z9hG4bKaeb56dfb-1905-e011-83f2-000c2984a855 proceeding.
2010/12/13 22:29:57.228 Pool:0xb5ba8b70 SIP Received Trying response
2010/12/13 22:29:57.895 Opal Liste...0xb5d76b70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 22:29:57.895 Opal Liste...0xb5d76b70 SIP PDU 183 Session Progress received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:29:57.895 Opal Liste...0xb5d76b70 Opal Transport clean up on termination
2010/12/13 22:29:57.896 Pool:0xb5ba8b70 SDP Parsed media session with 2 'audio' formats
2010/12/13 22:29:57.896 Pool:0xb5ba8b70 SIP INVITE transaction id=z9hG4bKaeb56dfb-1905-e011-83f2-000c2984a855 proceeding.
2010/12/13 22:29:57.896 Pool:0xb5ba8b70 SIP Received Session Progress response
2010/12/13 22:29:57.896 Pool:0xb5ba8b70 SIP RTP payload type PCMU matched to codec G.711-uLaw-64k
2010/12/13 22:29:57.896 Pool:0xb5ba8b70 Call IsMediaBypassPossible Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855] session 1
2010/12/13 22:29:57.896 Pool:0xb5ba8b70 OpalMan IsMediaBypassPossible: session 1
2010/12/13 22:29:57.896 Pool:0xb5ba8b70 RTP Found existing RTP session 1
2010/12/13 22:29:57.896 Pool:0xb5ba8b70 RTP_UDP Session 1, SetRemoteSocketInfo: data channel, new=203.55.231.194:39410, local=10.11.12.22:5000-5001, remote=0.0.0.0:0-0
2010/12/13 22:29:57.897 Pool:0xb5ba8b70 RTP Found existing media session 1
2010/12/13 22:29:57.897 Pool:0xb5ba8b70 Call OpenSourceMediaStreams opening audio session 1 on Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855]
2010/12/13 22:29:57.897 Pool:0xb5ba8b70 ModemConnection::GetMediaFormats Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0]
2010/12/13 22:29:57.897 Pool:0xb5ba8b70 ModemEndPoint::GetMediaFormats
2010/12/13 22:29:57.897 Pool:0xb5ba8b70 ModemConnection::GetMediaFormats Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0]
2010/12/13 22:29:57.897 Pool:0xb5ba8b70 ModemEndPoint::GetMediaFormats
2010/12/13 22:29:57.899 Pool:0xb5ba8b70 Call Selected media formats G.711-uLaw-64k -> PCM-16
2010/12/13 22:29:57.899 Pool:0xb5ba8b70 Call IsMediaBypassPossible Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855] session 1
2010/12/13 22:29:57.899 Pool:0xb5ba8b70 OpalMan IsMediaBypassPossible: session 1
2010/12/13 22:29:57.899 Pool:0xb5ba8b70 RTP Found existing media session 1
2010/12/13 22:29:57.899 Pool:0xb5ba8b70 OpalMan OnOpenMediaStream Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855],OpalRTPMediaStream-Source-G.711-uLaw-64k
2010/12/13 22:29:57.899 Pool:0xb5ba8b70 OpalCon Opened source stream v1fc960211_1 with format G.711-uLaw-64k
2010/12/13 22:29:57.899 Pool:0xb5ba8b70 ModemConnection::CreateMediaStream Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0] mediaFormat=PCM-16 sessionID=1 isSource=0
2010/12/13 22:29:57.899 Pool:0xb5ba8b70 ModemEngineBody::Attach audioEngine stConnectWait
2010/12/13 22:29:57.899 Pool:0xb5ba8b70 ttyT38a AudioEngine::Attach
2010/12/13 22:29:57.899 Pool:0xb5ba8b70 ttyT38a AudioClass=FALSE
2010/12/13 22:29:57.899 Pool:0xb5ba8b70 ttyT38a AudioEngine::SendOnIdle 2
2010/12/13 22:29:57.899 Pool:0xb5ba8b70 ModemEngineBody::Attach audioEngine Attached
2010/12/13 22:29:57.899 Pool:0xb5ba8b70 OpalMan OnOpenMediaStream Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0],AudioModemMediaStream-Sink-PCM-16
2010/12/13 22:29:57.899 Pool:0xb5ba8b70 OpalCon Opened sink stream v1fc960211_1 with format PCM-16
2010/12/13 22:29:57.899 Pool:0xb5ba8b70 Patch Created Sink: format=PCM-16
2010/12/13 22:29:57.899 Pool:0xb5ba8b70 Codec G711-uLaw-64k decoder created
2010/12/13 22:29:57.899 Pool:0xb5ba8b70 Patch Added media stream sink AudioModemMediaStream-Sink-PCM-16 using transcoder G.711-uLaw-64k->PCM-16, data size=3840
2010/12/13 22:29:57.899 Pool:0xb5ba8b70 Media RTP data size cannot be changed to 8, fixed at 2048
2010/12/13 22:29:57.899 Pool:0xb5ba8b70 OpalCon Sink stream of connection Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0] uses patch Patch OpalRTPMediaStream-Source-G.711-uLaw-64k -> AudioModemMediaStream-Sink-PCM-16
2010/12/13 22:29:57.899 Pool:0xb5ba8b70 OpalCon Source stream of connection Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855] uses patch Patch OpalRTPMediaStream-Source-G.711-uLaw-64k -> AudioModemMediaStream-Sink-PCM-16
2010/12/13 22:29:57.899 Pool:0xb5ba8b70 RTPCon Adding RFC2833 receive handler
2010/12/13 22:29:57.899 Pool:0xb5ba8b70 RTPCon Adding Cisco NSE receive handler
2010/12/13 22:29:57.899 Pool:0xb5ba8b70 Call GetOtherPartyConnection Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855]
2010/12/13 22:29:57.899 Pool:0xb5ba8b70 Call OpenSourceMediaStreams opening audio session 1 on Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0]
2010/12/13 22:29:57.899 Pool:0xb5ba8b70 ModemConnection::GetMediaFormats Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0]
2010/12/13 22:29:57.899 Pool:0xb5ba8b70 ModemEndPoint::GetMediaFormats
2010/12/13 22:29:57.900 Pool:0xb5ba8b70 Call Selected media formats PCM-16 -> G.711-uLaw-64k
2010/12/13 22:29:57.900 Pool:0xb5ba8b70 ModemConnection::CreateMediaStream Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0] mediaFormat=PCM-16 sessionID=1 isSource=1
2010/12/13 22:29:57.901 Pool:0xb5ba8b70 OpalMan OnOpenMediaStream Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0],AudioModemMediaStream-Source-PCM-16
2010/12/13 22:29:57.901 Pool:0xb5ba8b70 OpalCon Opened source stream v1fc960211_1 with format PCM-16
2010/12/13 22:29:57.901 Pool:0xb5ba8b70 Call IsMediaBypassPossible Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855] session 1
2010/12/13 22:29:57.901 Pool:0xb5ba8b70 OpalMan IsMediaBypassPossible: session 1
2010/12/13 22:29:57.901 Pool:0xb5ba8b70 RTP Found existing media session 1
2010/12/13 22:29:57.901 Pool:0xb5ba8b70 OpalMan OnOpenMediaStream Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855],OpalRTPMediaStream-Sink-G.711-uLaw-64k
2010/12/13 22:29:57.901 Pool:0xb5ba8b70 OpalCon Opened sink stream v1fc960211_1 with format G.711-uLaw-64k
2010/12/13 22:29:57.901 Pool:0xb5ba8b70 Patch Created Sink: format=G.711-uLaw-64k
2010/12/13 22:29:57.901 Pool:0xb5ba8b70 Codec G711-uLaw-64k encoder created
2010/12/13 22:29:57.901 Pool:0xb5ba8b70 Media RTP data size cannot be changed to 1920, fixed at 528
2010/12/13 22:29:57.901 Pool:0xb5ba8b70 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 22:29:57.901 Pool:0xb5ba8b70 OpalCon Source stream of connection Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0] uses patch Patch AudioModemMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-G.711-uLaw-64k
2010/12/13 22:29:57.901 Pool:0xb5ba8b70 OpalCon Sink stream of connection Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855] uses patch Patch AudioModemMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-G.711-uLaw-64k
2010/12/13 22:29:57.901 Pool:0xb5ba8b70 OpalCon SetPhase from ProceedingPhase to AlertingPhase for Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855]
2010/12/13 22:29:57.901 Pool:0xb5ba8b70 OpalMan OnAlerting Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855]
2010/12/13 22:29:57.901 Pool:0xb5ba8b70 Call OnAlerting Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855]
2010/12/13 22:29:57.901 Pool:0xb5ba8b70 ModemConnection::SetAlerting Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0] 0294396443@xxxxxxxxxxxxxxxxxxxx 1
2010/12/13 22:29:57.901 Pool:0xb5ba8b70 OpalCon SetPhase from SetUpPhase to AlertingPhase for Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0]
2010/12/13 22:29:57.901 Media Patch:0xb5b26b70 RTP Session 1, first sent data: ver=2 pt=PCMU psz=160 m=1 x=0 seq=4192 ts=0 src="" ccnt=0
2010/12/13 22:29:57.901 Pool:0xb5ba8b70 OpalCon Media stream threads started.
2010/12/13 22:29:58.053 Media Patch:0xb5b67b70 RTP Session 1, first receive data: ver=2 pt=PCMU psz=160 m=1 x=0 seq=33048 ts=1940309016 src="" ccnt=0
2010/12/13 22:29:59.902 Media Patch:0xb5b26b70 RTP Session 1, transmit statistics: packets=101 octets=16160 avgTime=20 maxTime=21 minTime=18
2010/12/13 22:30:01.902 Media Patch:0xb5b26b70 RTP Session 1, transmit statistics: packets=201 octets=32160 avgTime=20 maxTime=21 minTime=18
2010/12/13 22:30:02.176 Housekeeper:0xb5db7b70 SIP Set state Terminated_Success for INVITE transaction id=z9hG4bK804e5dfb-1905-e011-83f2-000c2984a855
2010/12/13 22:30:03.901 Media Patch:0xb5b26b70 RTP Session 1, transmit statistics: packets=301 octets=48160 avgTime=19 maxTime=22 minTime=18
2010/12/13 22:30:05.901 Media Patch:0xb5b26b70 RTP Session 1, transmit statistics: packets=401 octets=64160 avgTime=20 maxTime=21 minTime=19
2010/12/13 22:30:06.208 Housekeeper:0xb5db7b70 SIP Starting REGISTER for binding refresh
2010/12/13 22:30:06.210 Housekeeper:0xb5db7b70 SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 22:30:06.210 Housekeeper:0xb5db7b70 SIP Sending PDU REGISTER sip:sip.nsw.iinet.net.au (611 bytes) to: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:30:06.211 Housekeeper:0xb5db7b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 22:30:06.230 Opal Liste...0xb5d76b70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 22:30:06.231 Opal Liste...0xb5d76b70 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 22:30:06.231 Opal Liste...0xb5d76b70 SIP PDU 200 OK received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:30:06.231 Opal Liste...0xb5d76b70 Opal Transport clean up on termination
2010/12/13 22:30:06.231 Pool:0xb5be9b70 SIP REGISTER transaction id=z9hG4bK3606d100-1a05-e011-83f2-000c2984a855 completed.
2010/12/13 22:30:06.231 Pool:0xb5be9b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 22:30:06.232 Pool:0xb5be9b70 SIP Expiry time for REGISTER set to 30 seconds.
2010/12/13 22:30:06.352 Opal Liste...0xb5d76b70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 22:30:06.352 Opal Liste...0xb5d76b70 SIP PDU 200 OK received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:30:06.353 Opal Liste...0xb5d76b70 Opal Transport clean up on termination
2010/12/13 22:30:06.353 Pool:0xb5ba8b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 22:30:06.354 Pool:0xb5ba8b70 SDP Parsed media session with 2 'audio' formats
2010/12/13 22:30:06.358 Pool:0xb5ba8b70 SIP Adding authentication information for user "<sip_username>" at realm "iinetphone.iinet.net.au"
2010/12/13 22:30:06.358 Pool:0xb5ba8b70 SIP Sending PDU ACK sip:0294396443@xxxxxxxxxxxxxx:5060;transport=udp (818 bytes) to: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:30:06.358 Pool:0xb5ba8b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 22:30:06.358 Pool:0xb5ba8b70 SIP INVITE transaction id=z9hG4bKaeb56dfb-1905-e011-83f2-000c2984a855 completed.
2010/12/13 22:30:06.358 Pool:0xb5ba8b70 SIP Handling 200 OK response for INVITE
2010/12/13 22:30:06.359 Pool:0xb5ba8b70 SIP Received INVITE OK response
2010/12/13 22:30:06.359 Pool:0xb5ba8b70 SIP RTP payload type PCMU matched to codec G.711-uLaw-64k
2010/12/13 22:30:06.359 Pool:0xb5ba8b70 Call IsMediaBypassPossible Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855] session 1
2010/12/13 22:30:06.359 Pool:0xb5ba8b70 OpalMan IsMediaBypassPossible: session 1
2010/12/13 22:30:06.359 Pool:0xb5ba8b70 RTP Found existing RTP session 1
2010/12/13 22:30:06.360 Pool:0xb5ba8b70 RTP Found existing media session 1
2010/12/13 22:30:06.360 Pool:0xb5ba8b70 OpalCon SetPhase from AlertingPhase to ConnectedPhase for Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855]
2010/12/13 22:30:06.360 Pool:0xb5ba8b70 OpalCon OnConnected for Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855]
2010/12/13 22:30:06.360 Pool:0xb5ba8b70 OpalMan OnConnected Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855]
2010/12/13 22:30:06.360 Pool:0xb5ba8b70 Call OnConnected Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855]
2010/12/13 22:30:06.360 Pool:0xb5ba8b70 ModemConnection::SetConnected Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0]
2010/12/13 22:30:06.360 Pool:0xb5ba8b70 OpalCon SetConnected for Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0]
2010/12/13 22:30:06.360 Pool:0xb5ba8b70 OpalCon SetPhase from AlertingPhase to ConnectedPhase for Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0]
2010/12/13 22:30:06.360 Pool:0xb5ba8b70 OpalCon SetPhase from ConnectedPhase to EstablishedPhase for Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0]
2010/12/13 22:30:06.360 Pool:0xb5ba8b70 ModemConnection::OnEstablished Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0]
2010/12/13 22:30:06.360 Pool:0xb5ba8b70 ModemEngineBody::Request stConnectWait request={
calltoken=modem:/v1fc960211/0
command=established
}
2010/12/13 22:30:06.360 Pool:0xb5ba8b70 OpalCon OnEstablished Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0]
2010/12/13 22:30:06.360 Pool:0xb5ba8b70 OpalCon Media stream threads started.
2010/12/13 22:30:06.360 Pool:0xb5ba8b70 OpalMan OnEstablished Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0]
2010/12/13 22:30:06.360 Pool:0xb5ba8b70 Call OnEstablished Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0]
2010/12/13 22:30:06.360 Pool:0xb5ba8b70 OpalCon Media stream threads started.
2010/12/13 22:30:06.360 Pool:0xb5ba8b70 OpalCon SetPhase from ConnectedPhase to EstablishedPhase for Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855]
2010/12/13 22:30:06.360 Pool:0xb5ba8b70 OpalCon OnEstablished Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855]
2010/12/13 22:30:06.360 Pool:0xb5ba8b70 OpalCon Media stream threads started.
2010/12/13 22:30:06.360 Pool:0xb5ba8b70 OpalMan OnEstablished Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855]
2010/12/13 22:30:06.361 Pool:0xb5ba8b70 Call OnEstablished Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855]
2010/12/13 22:30:06.361 Pool:0xb5ba8b70 OpalCon Media stream threads started.
2010/12/13 22:30:07.901 Media Patch:0xb5b26b70 RTP Session 1, transmit statistics: packets=501 octets=80160 avgTime=20 maxTime=21 minTime=19
2010/12/13 22:30:09.081 Media Patch:0xb5b26b70 RTP Session 1, SentSenderReport: ssrc=2910563838 ntp=3501228609.351996356 rtp=0 psent=560 osent=89600
2010/12/13 22:30:09.082 Media Patch:0xb5b26b70 RTP Session 1, SentReceiverReport: ssrc=94500961 fraction=0 lost=0 last_seq=0 jitter=9 lsr=0 dlsr=0
2010/12/13 22:30:09.082 Media Patch:0xb5b26b70 RTP Session 1, sending SDES: root@xxxxxxxxxxxxxxxxxxx
2010/12/13 22:30:09.902 Media Patch:0xb5b26b70 RTP Session 1, transmit statistics: packets=601 octets=96160 avgTime=20 maxTime=24 minTime=16
2010/12/13 22:30:11.233 Housekeeper:0xb5db7b70 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bK3606d100-1a05-e011-83f2-000c2984a855
2010/12/13 22:30:11.362 Housekeeper:0xb5db7b70 SIP Set state Terminated_Success for INVITE transaction id=z9hG4bKaeb56dfb-1905-e011-83f2-000c2984a855
2010/12/13 22:30:11.387 Media Patch:0xb5b67b70 RTP Session 1, OnRxSenderReport: ssrc=94500961 ntp=2010/12/13-22:30:13.495407 rtp=0 psent=668 osent=106880
RR: ssrc=106880 fraction=173 lost=8105470 last_seq=0 jitter=4865 lsr=0.048 dlsr=39782:13:56.478
2010/12/13 22:30:11.387 Media Patch:0xb5b67b70 RTP Session 1, OnSourceDescription: 1 entries
ssrc=94500961
item[0]: type=CNAME data="" href="mailto:0.0.0@xxxxxxxxxxxx">0.0.0@xxxxxxxxxxxx"
item[1]: type=NAME data="" IOS, VoIP Gateway"
item[2]: type=TOOL data="" IOS, VoIP Gateway"
2010/12/13 22:30:11.902 Media Patch:0xb5b26b70 RTP Session 1, transmit statistics: packets=701 octets=112160 avgTime=20 maxTime=21 minTime=19
2010/12/13 22:30:13.902 Media Patch:0xb5b26b70 RTP Session 1, transmit statistics: packets=801 octets=128160 avgTime=20 maxTime=20 minTime=20
2010/12/13 22:30:15.902 Media Patch:0xb5b26b70 RTP Session 1, transmit statistics: packets=901 octets=144160 avgTime=20 maxTime=21 minTime=19
2010/12/13 22:30:16.599 Media Patch:0xb5b67b70 RTP Session 1, OnRxSenderReport: ssrc=94500961 ntp=2010/12/13-22:30:18.708109 rtp=0 psent=929 osent=148640
RR: ssrc=148640 fraction=173 lost=8105470 last_seq=0 jitter=5126 lsr=0.040 dlsr=39782:13:56.478
2010/12/13 22:30:16.600 Media Patch:0xb5b67b70 RTP Session 1, OnSourceDescription: 1 entries
ssrc=94500961
item[0]: type=CNAME data="" href="mailto:0.0.0@xxxxxxxxxxxx">0.0.0@xxxxxxxxxxxx"
item[1]: type=NAME data="" IOS, VoIP Gateway"
item[2]: type=TOOL data="" IOS, VoIP Gateway"
2010/12/13 22:30:17.902 Media Patch:0xb5b26b70 RTP Session 1, transmit statistics: packets=1001 octets=160160 avgTime=20 maxTime=21 minTime=19
2010/12/13 22:30:19.200 Media Patch:0xb5b67b70 RTP Session 1, OnRxSenderReport: ssrc=94500961 ntp=2010/12/13-22:30:21.309278 rtp=0 psent=1059 osent=169440
RR: ssrc=169440 fraction=173 lost=8105470 last_seq=0 jitter=5256 lsr=0.032 dlsr=39782:13:56.478
2010/12/13 22:30:19.200 Media Patch:0xb5b67b70 RTP Session 1, OnSourceDescription: 1 entries
ssrc=94500961
item[0]: type=CNAME data="" href="mailto:0.0.0@xxxxxxxxxxxx">0.0.0@xxxxxxxxxxxx"
item[1]: type=NAME data="" IOS, VoIP Gateway"
item[2]: type=TOOL data="" IOS, VoIP Gateway"
2010/12/13 22:30:19.857 Media Patch:0xb5b67b70 RTP Session 1, SentSenderReport: ssrc=2910563838 ntp=3501228619.3681121674 rtp=0 psent=1098 osent=175680
2010/12/13 22:30:19.857 Media Patch:0xb5b67b70 RTP Session 1, SentReceiverReport: ssrc=94500961 fraction=0 lost=0 last_seq=33382 jitter=15 lsr=0 dlsr=0
2010/12/13 22:30:19.857 Media Patch:0xb5b67b70 RTP Session 1, sending SDES: root@xxxxxxxxxxxxxxxxxxx
2010/12/13 22:30:19.902 Media Patch:0xb5b26b70 RTP Session 1, transmit statistics: packets=1101 octets=176160 avgTime=20 maxTime=21 minTime=19
2010/12/13 22:30:21.233 Housekeeper:0xb5db7b70 SIP Starting REGISTER for binding refresh
2010/12/13 22:30:21.234 Housekeeper:0xb5db7b70 SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 22:30:21.234 Housekeeper:0xb5db7b70 SIP Sending PDU REGISTER sip:sip.nsw.iinet.net.au (611 bytes) to: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:30:21.235 Housekeeper:0xb5db7b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 22:30:21.255 Opal Liste...0xb5d76b70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 22:30:21.255 Opal Liste...0xb5d76b70 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 22:30:21.255 Opal Liste...0xb5d76b70 SIP PDU 200 OK received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:30:21.255 Opal Liste...0xb5d76b70 Opal Transport clean up on termination
2010/12/13 22:30:21.255 Pool:0xb5be9b70 SIP REGISTER transaction id=z9hG4bK1093c509-1a05-e011-83f2-000c2984a855 completed.
2010/12/13 22:30:21.255 Pool:0xb5be9b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 22:30:21.256 Pool:0xb5be9b70 SIP Expiry time for REGISTER set to 30 seconds.
2010/12/13 22:30:21.902 Media Patch:0xb5b26b70 RTP Session 1, transmit statistics: packets=1201 octets=192160 avgTime=20 maxTime=24 minTime=16
2010/12/13 22:30:23.901 Media Patch:0xb5b26b70 RTP Session 1, transmit statistics: packets=1301 octets=208160 avgTime=19 maxTime=33 minTime=8
2010/12/13 22:30:25.906 Media Patch:0xb5b26b70 RTP Session 1, transmit statistics: packets=1401 octets=224160 avgTime=20 maxTime=24 minTime=18
2010/12/13 22:30:26.256 Housekeeper:0xb5db7b70 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bK1093c509-1a05-e011-83f2-000c2984a855
2010/12/13 22:30:26.668 Media Patch:0xb5b67b70 RTP Session 1, OnRxSenderReport: ssrc=94500961 ntp=2010/12/13-22:30:28.775426 rtp=0 psent=1432 osent=229120
RR: ssrc=229120 fraction=173 lost=8105470 last_seq=0 jitter=5629 lsr=0.032 dlsr=39795:05:15.249
2010/12/13 22:30:26.669 Media Patch:0xb5b67b70 RTP Session 1, OnSourceDescription: 1 entries
ssrc=94500961
item[0]: type=CNAME data="" href="mailto:0.0.0@xxxxxxxxxxxx">0.0.0@xxxxxxxxxxxx"
item[1]: type=NAME data="" IOS, VoIP Gateway"
item[2]: type=TOOL data="" IOS, VoIP Gateway"
2010/12/13 22:30:27.902 Media Patch:0xb5b26b70 RTP Session 1, transmit statistics: packets=1501 octets=240160 avgTime=19 maxTime=20 minTime=16
2010/12/13 22:30:29.313 Media Patch:0xb5b67b70 RTP Session 1, OnRxSenderReport: ssrc=94500961 ntp=2010/12/13-22:30:31.421967 rtp=0 psent=1564 osent=250240
RR: ssrc=250240 fraction=173 lost=8105470 last_seq=0 jitter=5761 lsr=0.040 dlsr=39795:05:15.249
2010/12/13 22:30:29.313 Media Patch:0xb5b67b70 RTP Session 1, OnSourceDescription: 1 entries
ssrc=94500961
item[0]: type=CNAME data="" href="mailto:0.0.0@xxxxxxxxxxxx">0.0.0@xxxxxxxxxxxx"
item[1]: type=NAME data="" IOS, VoIP Gateway"
item[2]: type=TOOL data="" IOS, VoIP Gateway"
2010/12/13 22:30:29.901 Media Patch:0xb5b26b70 RTP Session 1, transmit statistics: packets=1601 octets=256160 avgTime=20 maxTime=22 minTime=18
2010/12/13 22:30:31.902 Media Patch:0xb5b26b70 RTP Session 1, transmit statistics: packets=1701 octets=272160 avgTime=20 maxTime=23 minTime=17
2010/12/13 22:30:33.513 Media Patch:0xb5b67b70 RTP Session 1, SentSenderReport: ssrc=2910563838 ntp=3501228633.2206098322 rtp=0 psent=1781 osent=284960
2010/12/13 22:30:33.513 Media Patch:0xb5b67b70 RTP Session 1, SentReceiverReport: ssrc=94500961 fraction=0 lost=0 last_seq=33697 jitter=17 lsr=0 dlsr=0
2010/12/13 22:30:33.513 Media Patch:0xb5b67b70 RTP Session 1, sending SDES: root@xxxxxxxxxxxxxxxxxxx
2010/12/13 22:30:33.902 Media Patch:0xb5b26b70 RTP Session 1, transmit statistics: packets=1801 octets=288160 avgTime=20 maxTime=35 minTime=5
2010/12/13 22:30:35.635 Media Patch:0xb5b67b70 RTP Session 1, OnRxSenderReport: ssrc=94500961 ntp=2010/12/13-22:30:37.743789 rtp=0 psent=1880 osent=300800
RR: ssrc=300800 fraction=173 lost=8105470 last_seq=0 jitter=6077 lsr=0.024 dlsr=39811:22:49.773
2010/12/13 22:30:35.635 Media Patch:0xb5b67b70 RTP Session 1, OnSourceDescription: 1 entries
ssrc=94500961
item[0]: type=CNAME data="" href="mailto:0.0.0@xxxxxxxxxxxx">0.0.0@xxxxxxxxxxxx"
item[1]: type=NAME data="" IOS, VoIP Gateway"
item[2]: type=TOOL data="" IOS, VoIP Gateway"
2010/12/13 22:30:35.902 Media Patch:0xb5b26b70 RTP Session 1, transmit statistics: packets=1901 octets=304160 avgTime=20 maxTime=20 minTime=20
2010/12/13 22:30:36.256 Housekeeper:0xb5db7b70 SIP Starting REGISTER for binding refresh
2010/12/13 22:30:36.257 Housekeeper:0xb5db7b70 SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 22:30:36.257 Housekeeper:0xb5db7b70 SIP Sending PDU REGISTER sip:sip.nsw.iinet.net.au (611 bytes) to: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:30:36.258 Housekeeper:0xb5db7b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 22:30:36.278 Opal Liste...0xb5d76b70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 22:30:36.278 Opal Liste...0xb5d76b70 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 22:30:36.279 Opal Liste...0xb5d76b70 SIP PDU 200 OK received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:30:36.279 Opal Liste...0xb5d76b70 Opal Transport clean up on termination
2010/12/13 22:30:36.279 Pool:0xb5be9b70 SIP REGISTER transaction id=z9hG4bK84e0b912-1a05-e011-83f2-000c2984a855 completed.
2010/12/13 22:30:36.279 Pool:0xb5be9b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 22:30:36.279 Pool:0xb5be9b70 SIP Expiry time for REGISTER set to 30 seconds.
2010/12/13 22:30:37.902 Media Patch:0xb5b26b70 RTP Session 1, transmit statistics: packets=2001 octets=320160 avgTime=20 maxTime=29 minTime=10
2010/12/13 22:30:39.901 Media Patch:0xb5b26b70 RTP Session 1, transmit statistics: packets=2101 octets=336160 avgTime=20 maxTime=23 minTime=17
2010/12/13 22:30:41.280 Housekeeper:0xb5db7b70 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bK84e0b912-1a05-e011-83f2-000c2984a855
2010/12/13 22:30:41.876 Media Patch:0xb5b67b70 RTP Session 1, OnRxSenderReport: ssrc=94500961 ntp=2010/12/13-22:30:43.984956 rtp=0 psent=2192 osent=350720
RR: ssrc=350720 fraction=173 lost=8105470 last_seq=0 jitter=6389 lsr=0.040 dlsr=39811:22:49.773
2010/12/13 22:30:41.876 Media Patch:0xb5b67b70 RTP Session 1, OnSourceDescription: 1 entries
ssrc=94500961
item[0]: type=CNAME data="" href="mailto:0.0.0@xxxxxxxxxxxx">0.0.0@xxxxxxxxxxxx"
item[1]: type=NAME data="" IOS, VoIP Gateway"
item[2]: type=TOOL data="" IOS, VoIP Gateway"
2010/12/13 22:30:41.902 Media Patch:0xb5b26b70 RTP Session 1, transmit statistics: packets=2201 octets=352160 avgTime=20 maxTime=23 minTime=18
2010/12/13 22:30:43.902 Media Patch:0xb5b26b70 RTP Session 1, transmit statistics: packets=2301 octets=368160 avgTime=20 maxTime=23 minTime=15
2010/12/13 22:30:45.062 Media Patch:0xb5b67b70 RTP Session 1, OnRxSenderReport: ssrc=94500961 ntp=2010/12/13-22:30:47.170840 rtp=0 psent=2352 osent=376320
RR: ssrc=376320 fraction=173 lost=8105470 last_seq=0 jitter=6549 lsr=0.048 dlsr=39811:22:49.773
2010/12/13 22:30:45.062 Media Patch:0xb5b67b70 RTP Session 1, OnSourceDescription: 1 entries
ssrc=94500961
item[0]: type=CNAME data="" href="mailto:0.0.0@xxxxxxxxxxxx">0.0.0@xxxxxxxxxxxx"
item[1]: type=NAME data="" IOS, VoIP Gateway"
item[2]: type=TOOL data="" IOS, VoIP Gateway"
2010/12/13 22:30:45.901 Media Patch:0xb5b26b70 RTP Session 1, transmit statistics: packets=2401 octets=384160 avgTime=19 maxTime=21 minTime=19
2010/12/13 22:30:47.432 Media Patch:0xb5b67b70 RTP Session 1, SentSenderReport: ssrc=2910563838 ntp=3501228647.1858554844 rtp=0 psent=2477 osent=396320
2010/12/13 22:30:47.432 Media Patch:0xb5b67b70 RTP Session 1, SentReceiverReport: ssrc=94500961 fraction=0 lost=0 last_seq=33932 jitter=15 lsr=0 dlsr=0
2010/12/13 22:30:47.432 Media Patch:0xb5b67b70 RTP Session 1, sending SDES: root@xxxxxxxxxxxxxxxxxxx
2010/12/13 22:30:47.901 Media Patch:0xb5b26b70 RTP Session 1, transmit statistics: packets=2501 octets=400160 avgTime=20 maxTime=21 minTime=19
2010/12/13 22:30:48.276 Media Patch:0xb5b67b70 RTP Session 1, OnRxSenderReport: ssrc=94500961 ntp=2010/12/13-22:30:50.382076 rtp=0 psent=2512 osent=401920
RR: ssrc=401920 fraction=173 lost=8105470 last_seq=0 jitter=6709 lsr=0.064 dlsr=39827:59:11.777
2010/12/13 22:30:48.276 Media Patch:0xb5b67b70 RTP Session 1, OnSourceDescription: 1 entries
ssrc=94500961
item[0]: type=CNAME data="" href="mailto:0.0.0@xxxxxxxxxxxx">0.0.0@xxxxxxxxxxxx"
item[1]: type=NAME data="" IOS, VoIP Gateway"
item[2]: type=TOOL data="" IOS, VoIP Gateway"
2010/12/13 22:30:49.901 Media Patch:0xb5b26b70 RTP Session 1, transmit statistics: packets=2601 octets=416160 avgTime=20 maxTime=21 minTime=19
2010/12/13 22:30:51.177 Media Patch:0xb5b67b70 RTP Session 1, OnRxSenderReport: ssrc=94500961 ntp=2010/12/13-22:30:53.285847 rtp=0 psent=2657 osent=425120
RR: ssrc=425120 fraction=173 lost=8105470 last_seq=0 jitter=6854 lsr=0.048 dlsr=39827:59:11.777
2010/12/13 22:30:51.177 Media Patch:0xb5b67b70 RTP Session 1, OnSourceDescription: 1 entries
ssrc=94500961
item[0]: type=CNAME data="" href="mailto:0.0.0@xxxxxxxxxxxx">0.0.0@xxxxxxxxxxxx"
item[1]: type=NAME data="" IOS, VoIP Gateway"
item[2]: type=TOOL data="" IOS, VoIP Gateway"
2010/12/13 22:30:51.281 Housekeeper:0xb5db7b70 SIP Starting REGISTER for binding refresh
2010/12/13 22:30:51.282 Housekeeper:0xb5db7b70 SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 22:30:51.282 Housekeeper:0xb5db7b70 SIP Sending PDU REGISTER sip:sip.nsw.iinet.net.au (611 bytes) to: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:30:51.282 Housekeeper:0xb5db7b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 22:30:51.306 Opal Liste...0xb5d76b70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 22:30:51.306 Opal Liste...0xb5d76b70 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 22:30:51.307 Opal Liste...0xb5d76b70 SIP PDU 200 OK received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:30:51.307 Opal Liste...0xb5d76b70 Opal Transport clean up on termination
2010/12/13 22:30:51.307 Pool:0xb5be9b70 SIP REGISTER transaction id=z9hG4bK6278ae1b-1a05-e011-83f2-000c2984a855 completed.
2010/12/13 22:30:51.307 Pool:0xb5be9b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 22:30:51.307 Pool:0xb5be9b70 SIP Expiry time for REGISTER set to 30 seconds.
2010/12/13 22:30:51.902 Media Patch:0xb5b26b70 RTP Session 1, transmit statistics: packets=2701 octets=432160 avgTime=20 maxTime=23 minTime=17
2010/12/13 22:30:53.902 Media Patch:0xb5b26b70 RTP Session 1, transmit statistics: packets=2801 octets=448160 avgTime=20 maxTime=21 minTime=19
2010/12/13 22:30:54.698 Opal Liste...0xb5d76b70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 22:30:54.698 Opal Liste...0xb5d76b70 SIP PDU INVITE sip:<sip_username>@10.11.12.22 received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:30:54.699 Opal Liste...0xb5d76b70 SIP Sending PDU 100 Trying (430 bytes) to: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:30:54.699 Opal Liste...0xb5d76b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 22:30:54.700 Opal Liste...0xb5d76b70 Opal Transport clean up on termination
2010/12/13 22:30:54.700 Pool:0xb5ba8b70 SIP Received re-INVITE from sip:<sip_username>@10.11.12.22 for Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855]
2010/12/13 22:30:54.700 Pool:0xb5ba8b70 SDP Connection address of 0.0.0.0 specified for HOLD request.
2010/12/13 22:30:54.701 Pool:0xb5ba8b70 SDP Connection address of 0.0.0.0 specified for HOLD request.
2010/12/13 22:30:54.701 Pool:0xb5ba8b70 SDP Parsed media session with 2 'audio' formats
2010/12/13 22:30:54.701 Pool:0xb5ba8b70 SIP Remote hold detected
2010/12/13 22:30:54.701 Pool:0xb5ba8b70 OpalMan On Hold from remote on Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855]
2010/12/13 22:30:54.701 Pool:0xb5ba8b70 Call IsMediaBypassPossible Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855] session 1
2010/12/13 22:30:54.701 Pool:0xb5ba8b70 OpalMan IsMediaBypassPossible: session 1
2010/12/13 22:30:54.701 Pool:0xb5ba8b70 RTP Found existing RTP session 1
2010/12/13 22:30:54.701 Pool:0xb5ba8b70 RTP Found existing media session 1
2010/12/13 22:30:54.701 Pool:0xb5ba8b70 SIP RTP payload type PCMU matched to codec G.711-uLaw-64k
2010/12/13 22:30:54.701 Pool:0xb5ba8b70 Media Paused stream OpalRTPMediaStream-Sink-G.711-uLaw-64k
2010/12/13 22:30:54.701 Pool:0xb5ba8b70 Media Paused stream OpalRTPMediaStream-Source-G.711-uLaw-64k
2010/12/13 22:30:54.701 Pool:0xb5ba8b70 Call GetOtherPartyConnection Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855]
2010/12/13 22:30:54.701 Pool:0xb5ba8b70 SIP Using handler RTP payload [pt=101] for UserInput/RFC2833
2010/12/13 22:30:54.702 Pool:0xb5ba8b70 SIP Sending PDU 200 OK (812 bytes) to: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:30:54.703 Pool:0xb5ba8b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 22:30:54.764 Opal Liste...0xb5d76b70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 22:30:54.764 Opal Liste...0xb5d76b70 SIP PDU ACK sip:<sip_username>@10.11.12.22 received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:30:54.764 Opal Liste...0xb5d76b70 Opal Transport clean up on termination
2010/12/13 22:30:54.765 Pool:0xb5ba8b70 SIP ACK received: EstablishedPhase
2010/12/13 22:30:54.765 Pool:0xb5ba8b70 OpalCon Media stream threads started.
2010/12/13 22:30:55.321 Media Patch:0xb5b67b70 RTP Session 1, OnReceiverReport: ssrc=94500961
RR: ssrc=2910563838 fraction=0 lost=0 last_seq=7030 jitter=48 lsr=607:43:36.647 dlsr=9:23:29.956
2010/12/13 22:30:55.321 Media Patch:0xb5b67b70 RTP Session 1, OnSourceDescription: 1 entries
ssrc=94500961
item[0]: type=CNAME data="" href="mailto:0.0.0@xxxxxxxxxxxx">0.0.0@xxxxxxxxxxxx"
item[1]: type=NAME data="" IOS, VoIP Gateway"
item[2]: type=TOOL data="" IOS, VoIP Gateway"
2010/12/13 22:30:56.308 Housekeeper:0xb5db7b70 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bK6278ae1b-1a05-e011-83f2-000c2984a855
2010/12/13 22:31:01.463 Media Patch:0xb5b67b70 RTP Session 1, SentSenderReport: ssrc=2910563838 ntp=3501228661.1990256118 rtp=0 psent=2840 osent=454400
2010/12/13 22:31:01.463 Media Patch:0xb5b67b70 RTP Session 1, SentReceiverReport: ssrc=94500961 fraction=0 lost=0 last_seq=34180 jitter=15 lsr=0 dlsr=0
2010/12/13 22:31:01.463 Media Patch:0xb5b67b70 RTP Session 1, sending SDES: root@xxxxxxxxxxxxxxxxxxx
2010/12/13 22:31:02.615 Media Patch:0xb5b67b70 RTP Session 1, OnReceiverReport: ssrc=94500961
2010/12/13 22:31:02.615 Media Patch:0xb5b67b70 RTP Session 1, OnSourceDescription: 1 entries
ssrc=94500961
item[0]: type=CNAME data="" href="mailto:0.0.0@xxxxxxxxxxxx">0.0.0@xxxxxxxxxxxx"
item[1]: type=NAME data="" IOS, VoIP Gateway"
item[2]: type=TOOL data="" IOS, VoIP Gateway"
2010/12/13 22:31:06.308 Housekeeper:0xb5db7b70 SIP Starting REGISTER for binding refresh
2010/12/13 22:31:06.310 Housekeeper:0xb5db7b70 SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 22:31:06.310 Housekeeper:0xb5db7b70 SIP Sending PDU REGISTER sip:sip.nsw.iinet.net.au (611 bytes) to: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:31:06.310 Housekeeper:0xb5db7b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 22:31:06.330 Opal Liste...0xb5d76b70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 22:31:06.331 Opal Liste...0xb5d76b70 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 22:31:06.331 Opal Liste...0xb5d76b70 SIP PDU 200 OK received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:31:06.331 Opal Liste...0xb5d76b70 Opal Transport clean up on termination
2010/12/13 22:31:06.331 Pool:0xb5be9b70 SIP REGISTER transaction id=z9hG4bKce81a324-1a05-e011-83f2-000c2984a855 completed.
2010/12/13 22:31:06.331 Pool:0xb5be9b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 22:31:06.331 Pool:0xb5be9b70 SIP Expiry time for REGISTER set to 30 seconds.
2010/12/13 22:31:06.361 Housekeeper:0xb5db7b70 ModemEngineBody::OnTimerCallback stReqModeAckWait Timeout 0
2010/12/13 22:31:06.361 ttyT38a(e)...0xb5cf3b70 ModemEndPoint::OnMyCallback command=clearcall extra=1
2010/12/13 22:31:06.361 ttyT38a(e)...0xb5cf3b70 OpalCon Call end reason for Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0] set to EndedByLocalUser
2010/12/13 22:31:06.361 ttyT38a(e)...0xb5cf3b70 Call Clearing Call[v1fc960211] reason=EndedByLocalUser
2010/12/13 22:31:06.361 ttyT38a(e)...0xb5cf3b70 OpalCon SetPhase from EstablishedPhase to ReleasingPhase for Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0]
2010/12/13 22:31:06.361 ttyT38a(e)...0xb5cf3b70 OpalCon Releasing Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0]
2010/12/13 22:31:06.361 ttyT38a(e)...0xb5cf3b70 ModemConnection::OnReleased Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0]
2010/12/13 22:31:06.361 ttyT38a(e)...0xb5cf3b70 OpalCon OnReleased Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0]
2010/12/13 22:31:06.361 ttyT38a(e)...0xb5cf3b70 OpalMan OnReleased Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0]
2010/12/13 22:31:06.361 ttyT38a(e)...0xb5cf3b70 Call OnReleased Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0]
2010/12/13 22:31:06.362 ttyT38a(e)...0xb5cf3b70 OpalCon SetPhase from EstablishedPhase to ReleasingPhase for Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855]
2010/12/13 22:31:06.362 ttyT38a(e)...0xb5cf3b70 OpalCon Releasing Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855]
2010/12/13 22:31:06.362 ttyT38a(e)...0xb5cf3b70 OpalCon Call end reason for Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855] set to EndedByLocalUser
2010/12/13 22:31:06.362 ttyT38a(e)...0xb5cf3b70 Patch Removing media stream sink AudioModemMediaStream-Sink-PCM-16
2010/12/13 22:31:06.362 ttyT38a(e)...0xb5cf3b70 OpalCon Removed media stream AudioModemMediaStream-Sink-PCM-16
2010/12/13 22:31:06.362 ttyT38a(e)...0xb5cf3b70 Call GetOtherPartyConnection Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0]
2010/12/13 22:31:06.362 ttyT38a(e)...0xb5cf3b70 Patch Closing media patch Patch AudioModemMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-G.711-uLaw-64k
2010/12/13 22:31:06.362 ttyT38a(e)...0xb5cf3b70 Media Closing RTP for OpalRTPMediaStream-Sink-G.711-uLaw-64k
2010/12/13 22:31:06.362 ttyT38a(e)...0xb5cf3b70 RTP_UDP Session 1, Shutting down write.
2010/12/13 22:31:06.362 ttyT38a(e)...0xb5cf3b70 Patch Removing media stream sink OpalRTPMediaStream-Sink-G.711-uLaw-64k
2010/12/13 22:31:06.362 ttyT38a(e)...0xb5cf3b70 OpalCon Removed media stream OpalRTPMediaStream-Sink-G.711-uLaw-64k
2010/12/13 22:31:06.362 OnRelease:0xb5ae5b70 SIP OnReleased: Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855], phase = ReleasingPhase
2010/12/13 22:31:06.362 OnRelease:0xb5ae5b70 OpalCon SetPhase from ReleasingPhase to ReleasingPhase for Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855]
2010/12/13 22:31:06.364 OnRelease:0xb5ae5b70 Media Closing RTP for OpalRTPMediaStream-Source-G.711-uLaw-64k
2010/12/13 22:31:06.364 OnRelease:0xb5ae5b70 RTP_UDP Session 1, Shutting down read.
2010/12/13 22:31:06.364 Media Patch:0xb5b67b70 RTP_UDP Session 1, Read shutdown.
2010/12/13 22:31:06.364 OnRelease:0xb5ae5b70 Patch Closing media patch Patch OpalRTPMediaStream-Source-G.711-uLaw-64k
2010/12/13 22:31:06.364 OnRelease:0xb5ae5b70 OpalCon Removed media stream OpalRTPMediaStream-Source-G.711-uLaw-64k
2010/12/13 22:31:06.364 OnRelease:0xb5ae5b70 OpalCon Media streams closed.
2010/12/13 22:31:06.364 OnRelease:0xb5ae5b70 SIP Adding authentication information for user "<sip_username>" at realm "iinetphone.iinet.net.au"
2010/12/13 22:31:06.365 OnRelease:0xb5ae5b70 SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 22:31:06.365 OnRelease:0xb5ae5b70 SIP Sending PDU BYE sip:0294396443@xxxxxxxxxxxxxx:5060;transport=udp (818 bytes) to: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:31:06.365 OnRelease:0xb5ae5b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 22:31:06.372 ttyT38a(e)...0xb5cf3b70 OpalCon Removed media stream AudioModemMediaStream-Source-PCM-16
2010/12/13 22:31:06.372 ttyT38a(e)...0xb5cf3b70 OpalCon Media streams closed.
2010/12/13 22:31:06.372 ttyT38a(e)...0xb5cf3b70 ModemEndPoint::OnMyCallback request={
calltoken=modem:/v1fc960211/0
command=clearcall
response=confirm
modemtoken=ttyT38a
}
2010/12/13 22:31:06.372 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 4e 4f 20 43 41 52 52 49 45 52 0d 0a ..NO CARRIER.. }
2010/12/13 22:31:06.390 Opal Liste...0xb5d76b70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 22:31:06.390 Opal Liste...0xb5d76b70 SIP PDU 200 OK received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:31:06.391 Opal Liste...0xb5d76b70 Opal Transport clean up on termination
2010/12/13 22:31:06.391 Pool:0xb5ba8b70 SIP BYE transaction id=z9hG4bK2ed3ab24-1a05-e011-83f2-000c2984a855 completed.
2010/12/13 22:31:06.391 OnRelease:0xb5ae5b70 OpalCon SetPhase from ReleasingPhase to ReleasedPhase for Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855]
2010/12/13 22:31:06.391 OnRelease:0xb5ae5b70 OpalCon OnReleased Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855]
2010/12/13 22:31:06.391 OnRelease:0xb5ae5b70 OpalMan OnReleased Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855]
2010/12/13 22:31:06.391 OnRelease:0xb5ae5b70 Call OnReleased Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855]
2010/12/13 22:31:06.391 OnRelease:0xb5ae5b70 Call[v1fc960211] cleared
2010/12/13 22:31:06.391 OnRelease:0xb5ae5b70 OpalMan OnClearedCall Call[v1fc960211] from "modem:" to "sip:0294396443@xxxxxxxxxxxxxxxxxxxx"
2010/12/13 22:31:06.391 OnRelease:0xb5ae5b70 OPAL Attempting to close recording on call v1fc960211 when not recording.
2010/12/13 22:31:06.391 OnRelease:0xb5ae5b70 OpalCon Media streams closed.
2010/12/13 22:31:07.233 Opal Garbage:0xb5e7ab70 ModemConnection::~ModemConnection Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0]
2010/12/13 22:31:07.233 Opal Garbage:0xb5e7ab70 ModemEngineBody::_Detach No t38engine was Attached
2010/12/13 22:31:07.233 Opal Garbage:0xb5e7ab70 ttyT38a AudioEngine::Detach
2010/12/13 22:31:07.233 Opal Garbage:0xb5e7ab70 ttyT38a AudioEngine::Detach Detached
2010/12/13 22:31:07.233 Opal Garbage:0xb5e7ab70 ModemEngineBody::_Detach audioEngine Detached
2010/12/13 22:31:07.234 Opal Garbage:0xb5e7ab70 ModemEngineBody::Request stCommand request={
calltoken=modem:/v1fc960211/0
command=clearcall
}
2010/12/13 22:31:07.234 Opal Garbage:0xb5e7ab70 ModemEngineBody::Request not in use
2010/12/13 22:31:07.234 Opal Garbage:0xb5e7ab70 PseudoModemQ::Enqueue ttyT38a
2010/12/13 22:31:07.234 Opal Garbage:0xb5e7ab70 ttyT38a T38Engine::~T38Engine
2010/12/13 22:31:07.234 Opal Garbage:0xb5e7ab70 OpalCon Connection Call[v1fc960211]-EP<modem>[modem:/v1fc960211/0] destroyed.
2010/12/13 22:31:07.378 ttyT38a(e)...0xb5cf3b70 --> ATH0
2010/12/13 22:31:07.378 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 22:31:07.383 ttyT38a(i)...0xb5cb2b70 --> read ERROR -1 Input/output error
2010/12/13 22:31:07.383 ttyT38a(i)...0xb5cb2b70 --> Stopped
2010/12/13 22:31:07.383 ttyT38a(o)...0xb5c71b70 <-- Stopped
2010/12/13 22:31:07.393 ttyT38a(e)...0xb5cf3b70 <-> Stopped
2010/12/13 22:31:07.404 ttyT38a(b)...0xb5e39b70 PseudoModemPty::OpenPty removed link /dev/ttyT38a -> /dev/pts/1
2010/12/13 22:31:07.404 ttyT38a(b)...0xb5e39b70 PseudoModemPty::OpenPty read ERROR -1 Input/output error
2010/12/13 22:31:07.404 ttyT38a(b)...0xb5e39b70 PseudoModemPty::OpenPty added link /dev/ttyT38a -> /dev/pts/1
2010/12/13 22:31:07.405 ttyT38a(e)...0xb5cf3b70 RenameCurrentThread old ThreadName=ModemEngine:0xb5cf3b70
2010/12/13 22:31:07.405 ttyT38a(e)...0xb5cf3b70 <-> Started
2010/12/13 22:31:07.405 ttyT38a(i)...0xb5c71b70 RenameCurrentThread old ThreadName=InPty:0xb5c71b70
2010/12/13 22:31:07.405 ttyT38a(i)...0xb5c71b70 --> Started
2010/12/13 22:31:07.405 ttyT38a(o)...0xb5cb2b70 RenameCurrentThread old ThreadName=OutPty:0xb5cb2b70
2010/12/13 22:31:07.405 ttyT38a(o)...0xb5cb2b70 <-- Started
2010/12/13 22:31:11.333 Housekeeper:0xb5db7b70 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bKce81a324-1a05-e011-83f2-000c2984a855
2010/12/13 22:31:11.391 Housekeeper:0xb5db7b70 SIP Set state Terminated_Success for BYE transaction id=z9hG4bK2ed3ab24-1a05-e011-83f2-000c2984a855
2010/12/13 22:31:12.237 Opal Garbage:0xb5e7ab70 Opal Transport clean up on termination
2010/12/13 22:31:12.237 Opal Garbage:0xb5e7ab70 Opal Transport clean up on termination
2010/12/13 22:31:12.238 Opal Garbage:0xb5e7ab70 RTP Closing session 1
2010/12/13 22:31:12.283 Opal Garbage:0xb5e7ab70 RTP Deleting session 1
2010/12/13 22:31:12.283 Opal Garbage:0xb5e7ab70 RTP_UDP Session 1, Shutting down write.
2010/12/13 22:31:12.283 Opal Garbage:0xb5e7ab70 RTP Session 1, final statistics:
packetsSent = 2840
octetsSent = 454400
averageSendTime = 20
maximumSendTime = 21
minimumSendTime = 19
packetsReceived = 1263
octetsReceived = 200172
packetsLost = 0
packetsTooLate = 0
packetOverruns = 0
packetsOutOfOrder = 0
averageReceiveTime= 19
maximumReceiveTime= 37
minimumReceiveTime= 3
averageJitter = 1
maximumJitter = 416
2010/12/13 22:31:12.283 Opal Garbage:0xb5e7ab70 OpalCon Connection Call[v1fc960211]-EP<sip>[a4da5cfb-1905-e011-83f2-000c2984a855] destroyed.
2010/12/13 22:31:13.284 Opal Garbage:0xb5e7ab70 Call Call[v1fc960211] destroyed.
2010/12/13 22:31:21.227 ttyT38a(e)...0xb5cf3b70 --> ATZ
2010/12/13 22:31:21.227 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 22:31:21.332 Housekeeper:0xb5db7b70 SIP Starting REGISTER for binding refresh
2010/12/13 22:31:21.333 Housekeeper:0xb5db7b70 SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 22:31:21.333 Housekeeper:0xb5db7b70 SIP Sending PDU REGISTER sip:sip.nsw.iinet.net.au (612 bytes) to: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:31:21.334 Housekeeper:0xb5db7b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 22:31:21.354 Opal Liste...0xb5d76b70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 22:31:21.354 Opal Liste...0xb5d76b70 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 22:31:21.355 Opal Liste...0xb5d76b70 SIP PDU 200 OK received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:31:21.355 Opal Liste...0xb5d76b70 Opal Transport clean up on termination
2010/12/13 22:31:21.355 Pool:0xb5be9b70 SIP REGISTER transaction id=z9hG4bK00f3972d-1a05-e011-83f2-000c2984a855 completed.
2010/12/13 22:31:21.355 Pool:0xb5be9b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 22:31:21.355 Pool:0xb5be9b70 SIP Expiry time for REGISTER set to 30 seconds.
2010/12/13 22:31:24.235 ttyT38a(e)...0xb5cf3b70 --> ATS0=0
2010/12/13 22:31:24.235 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 22:31:24.243 ttyT38a(e)...0xb5cf3b70 --> ATE0
2010/12/13 22:31:24.243 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 22:31:24.251 ttyT38a(e)...0xb5cf3b70 --> ATV1
2010/12/13 22:31:24.251 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 22:31:24.259 ttyT38a(e)...0xb5cf3b70 --> ATQ0
2010/12/13 22:31:24.259 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 22:31:24.267 ttyT38a(e)...0xb5cf3b70 --> ATS8=2
2010/12/13 22:31:24.267 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 22:31:24.275 ttyT38a(e)...0xb5cf3b70 --> ATS7=60
2010/12/13 22:31:24.275 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 22:31:24.283 ttyT38a(e)...0xb5cf3b70 --> AT+FCLASS=?
2010/12/13 22:31:24.283 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 31 2c 38 0d 0a 4f 4b 0d 0a ..1,8..OK.. }
2010/12/13 22:31:24.291 ttyT38a(e)...0xb5cf3b70 --> AT+FCLASS=1
2010/12/13 22:31:24.291 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 22:31:24.299 ttyT38a(e)...0xb5cf3b70 --> ATI3
2010/12/13 22:31:24.299 ttyT38a(e)...0xb5cf3b70 <-- {
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 22:31:24.307 ttyT38a(e)...0xb5cf3b70 --> ATI0
2010/12/13 22:31:24.307 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 54 33 38 46 41 58 0d 0a 4f 4b 0d 0a ..T38FAX..OK.. }
2010/12/13 22:31:24.315 ttyT38a(e)...0xb5cf3b70 --> AT+FREV?
2010/12/13 22:31:24.315 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 31 2e 32 2e 30 0d 0a 4f 4b 0d 0a ..1.2.0..OK.. }
2010/12/13 22:31:24.323 ttyT38a(e)...0xb5cf3b70 --> AT+FTM=?
2010/12/13 22:31:24.323 ttyT38a(e)...0xb5cf3b70 <-- {
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 22:31:24.331 ttyT38a(e)...0xb5cf3b70 --> AT+FRM=?
2010/12/13 22:31:24.331 ttyT38a(e)...0xb5cf3b70 <-- {
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 22:31:24.339 ttyT38a(e)...0xb5cf3b70 --> ATM0
2010/12/13 22:31:24.339 ttyT38a(e)...0xb5cf3b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 22:31:26.356 Housekeeper:0xb5db7b70 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bK00f3972d-1a05-e011-83f2-000c2984a855
2010/12/13 22:31:36.356 Housekeeper:0xb5db7b70 SIP Starting REGISTER for binding refresh
2010/12/13 22:31:36.357 Housekeeper:0xb5db7b70 SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 22:31:36.357 Housekeeper:0xb5db7b70 SIP Sending PDU REGISTER sip:sip.nsw.iinet.net.au (612 bytes) to: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:31:36.357 Housekeeper:0xb5db7b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 22:31:36.378 Opal Liste...0xb5d76b70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 22:31:36.379 Opal Liste...0xb5d76b70 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 22:31:36.379 Opal Liste...0xb5d76b70 SIP PDU 200 OK received: rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%eth0
2010/12/13 22:31:36.379 Opal Liste...0xb5d76b70 Opal Transport clean up on termination
2010/12/13 22:31:36.379 Pool:0xb5be9b70 SIP REGISTER transaction id=z9hG4bK10638c36-1a05-e011-83f2-000c2984a855 completed.
2010/12/13 22:31:36.379 Pool:0xb5be9b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 22:31:36.379 Pool:0xb5be9b70 SIP Expiry time for REGISTER set to 30 seconds.
- Prev by Date: Re: [hylafax-users] t38modem & hylafax issue
- Next by Date: [hylafax-users] Sending faxes using the Page Range option
- Previous by thread: Re: [hylafax-users] t38modem & hylafax issue
- Next by thread: [hylafax-users] Sending faxes using the Page Range option
- Index(es):
Project hosted by iFAX Solutions