 HylaFAX The world's
most advanced open source fax server
|
[Date Prev][Date Next][Thread Prev][Thread Next] [Date Index] [Thread Index]
Re: [hylafax-users] t38modem & hylafax issue
- To: Robert Branham <bbranham@xxxxxxxxxxxx>
- Subject: Re: [hylafax-users] t38modem & hylafax issue
- From: John Park <john@xxxxxxxxxxxxxxx>
- Cc: hylafax-users@xxxxxxxxxxx
- Date: Mon, 13 Dec 2010 13:37:59 +1100
Here is the log file...
2010/12/13 13:33:43.222 T38Modem Version 1.2.0 by Vyacheslav Frolov on Unix Linux (2.6.35-22-generic-pae-i686) with PTLib (v2.6.7) at 2010/12/13 13:33:43.222
2010/12/13 13:33:43.222 T38Modem T38Modem Version 1.2.0 (OPAL-3.6.8/3.6.8, PTLIB-2.6.7/2.6.7) on Unix Linux (2.6.35-22-generic-pae-i686)
2010/12/13 13:33:43.222 T38Modem Options: -ttt -o /var/log/t38modem.log --sip-proxy <username>:<password>@sip.nsw.iinet.net.au --sip-register <username>@sip.nsw.iinet.net.au,<password>,<username>,iinetphone.iinet.net.au --no-h323 --ptty +/dev/ttyT38a,+/dev/ttyT38b,+/dev/ttyT38c --route modem:.*=sip:<dn>@sip.nsw.iinet.net.au -u <username>
2010/12/13 13:33:43.222 T38Modem Config:
2010/12/13 13:33:43.222 T38Modem --no-h323=True
2010/12/13 13:33:43.222 T38Modem --sip-proxy=<username>:<password>@sip.nsw.iinet.net.au
2010/12/13 13:33:43.222 T38Modem --sip-register=<username>@sip.nsw.iinet.net.au,<password>,<username>,iinetphone.iinet.net.au
2010/12/13 13:33:43.222 T38Modem --ptty=+/dev/ttyT38a,+/dev/ttyT38b,+/dev/ttyT38c
2010/12/13 13:33:43.222 T38Modem --route=modem:.*=sip:<dn>@sip.nsw.iinet.net.au
2010/12/13 13:33:43.222 T38Modem --username=<username>
2010/12/13 13:33:43.222 T38Modem --trace=True
2010/12/13 13:33:43.222 T38Modem --output=/var/log/t38modem.log
2010/12/13 13:33:43.222 T38Modem Detecting V4L2 devices
2010/12/13 13:33:43.223 T38Modem Unable to detect v4l2 directory
2010/12/13 13:33:43.236 T38Modem OpalMan Attached endpoint with prefix modem
2010/12/13 13:33:43.236 T38Modem ModemEndPoint::ModemEndPoint
2010/12/13 13:33:43.237 T38Modem PseudoModemList::Append ttyT38a (0) OK
2010/12/13 13:33:43.237 T38Modem PseudoModemList::Append ttyT38b (1) OK
2010/12/13 13:33:43.237 T38Modem PseudoModemList::Append ttyT38c (2) OK
2010/12/13 13:33:43.237 T38Modem OpalMan Attached endpoint with prefix sip
2010/12/13 13:33:43.237 T38Modem OpalMan Attached endpoint with prefix sips
2010/12/13 13:33:43.238 Opal Liste...0xb5bcab70 Listen Started listening thread on udp$*:5060
2010/12/13 13:33:43.241 ttyT38a(b)...0xb5d0fb70 RenameCurrentThread old ThreadName=PseudoModemPty:0xb5d0fb70
2010/12/13 13:33:43.241 ttyT38a(b)...0xb5d0fb70 Started for /dev/ttyT38a (accepts all)
2010/12/13 13:33:43.241 ttyT38a(b)...0xb5d0fb70 ModemEndPoint::OnMyCallback command=addmodem extra=10
2010/12/13 13:33:43.241 ttyT38a(b)...0xb5d0fb70 PseudoModemQ::Enqueue ttyT38a
2010/12/13 13:33:43.241 ttyT38a(b)...0xb5d0fb70 ModemEndPoint::OnMyCallback request={
command=addmodem
response=confirm
modemtoken=ttyT38a
}
2010/12/13 13:33:43.241 ttyT38a(b)...0xb5d0fb70 PseudoModemPty::OpenPty removed link /dev/ttyT38a -> /dev/pts/1
2010/12/13 13:33:43.241 ttyT38a(b)...0xb5d0fb70 PseudoModemPty::OpenPty added link /dev/ttyT38a -> /dev/pts/1
2010/12/13 13:33:43.241 ttyT38b(b)...0xb5cceb70 RenameCurrentThread old ThreadName=PseudoModemPty:0xb5cceb70
2010/12/13 13:33:43.241 ttyT38b(b)...0xb5cceb70 Started for /dev/ttyT38b (accepts all)
2010/12/13 13:33:43.241 ttyT38b(b)...0xb5cceb70 ModemEndPoint::OnMyCallback command=addmodem extra=10
2010/12/13 13:33:43.241 ttyT38b(b)...0xb5cceb70 PseudoModemQ::Enqueue ttyT38b
2010/12/13 13:33:43.241 ttyT38b(b)...0xb5cceb70 ModemEndPoint::OnMyCallback request={
command=addmodem
response=confirm
modemtoken=ttyT38b
}
2010/12/13 13:33:43.241 ttyT38b(b)...0xb5cceb70 PseudoModemPty::OpenPty removed link /dev/ttyT38b -> /dev/pts/2
2010/12/13 13:33:43.241 ttyT38b(b)...0xb5cceb70 PseudoModemPty::OpenPty added link /dev/ttyT38b -> /dev/pts/2
2010/12/13 13:33:43.241 ttyT38c(b)...0xb5c8db70 RenameCurrentThread old ThreadName=PseudoModemPty:0xb5c8db70
2010/12/13 13:33:43.241 ttyT38c(b)...0xb5c8db70 Started for /dev/ttyT38c (accepts all)
2010/12/13 13:33:43.241 ttyT38c(b)...0xb5c8db70 ModemEndPoint::OnMyCallback command=addmodem extra=10
2010/12/13 13:33:43.241 ttyT38c(b)...0xb5c8db70 PseudoModemQ::Enqueue ttyT38c
2010/12/13 13:33:43.241 ttyT38c(b)...0xb5c8db70 ModemEndPoint::OnMyCallback request={
command=addmodem
response=confirm
modemtoken=ttyT38c
}
2010/12/13 13:33:43.241 ttyT38c(b)...0xb5c8db70 PseudoModemPty::OpenPty removed link /dev/ttyT38c -> /dev/pts/3
2010/12/13 13:33:43.241 ttyT38c(b)...0xb5c8db70 PseudoModemPty::OpenPty added link /dev/ttyT38c -> /dev/pts/3
2010/12/13 13:33:43.242 Opal Liste...0xb5b89b70 Listen Started listening thread on tcp$*:5060
2010/12/13 13:33:43.242 Opal Liste...0xb5b48b70 Listen Started listening thread on tcps$*:5061
2010/12/13 13:33:43.242 ttyT38a(e)...0xb5afbb70 RenameCurrentThread old ThreadName=ModemEngine:0xb5afbb70
2010/12/13 13:33:43.242 ttyT38a(e)...0xb5afbb70 <-> Started
2010/12/13 13:33:43.242 ttyT38a(i)...0xb5abab70 RenameCurrentThread old ThreadName=InPty:0xb5abab70
2010/12/13 13:33:43.242 ttyT38a(i)...0xb5abab70 --> Started
2010/12/13 13:33:43.242 ttyT38a(o)...0xb5a79b70 RenameCurrentThread old ThreadName=OutPty:0xb5a79b70
2010/12/13 13:33:43.242 ttyT38a(o)...0xb5a79b70 <-- Started
2010/12/13 13:33:43.242 ttyT38b(e)...0xb5a38b70 RenameCurrentThread old ThreadName=ModemEngine:0xb5a38b70
2010/12/13 13:33:43.242 ttyT38b(e)...0xb5a38b70 <-> Started
2010/12/13 13:33:43.242 ttyT38b(i)...0xb59f7b70 RenameCurrentThread old ThreadName=InPty:0xb59f7b70
2010/12/13 13:33:43.242 ttyT38b(i)...0xb59f7b70 --> Started
2010/12/13 13:33:43.242 ttyT38b(o)...0xb59b6b70 RenameCurrentThread old ThreadName=OutPty:0xb59b6b70
2010/12/13 13:33:43.242 ttyT38b(o)...0xb59b6b70 <-- Started
2010/12/13 13:33:43.242 ttyT38c(e)...0xb5975b70 RenameCurrentThread old ThreadName=ModemEngine:0xb5975b70
2010/12/13 13:33:43.242 ttyT38c(e)...0xb5975b70 <-> Started
2010/12/13 13:33:43.242 ttyT38c(i)...0xb5934b70 RenameCurrentThread old ThreadName=InPty:0xb5934b70
2010/12/13 13:33:43.242 ttyT38c(i)...0xb5934b70 --> Started
2010/12/13 13:33:43.243 ttyT38c(o)...0xb58f3b70 RenameCurrentThread old ThreadName=OutPty:0xb58f3b70
2010/12/13 13:33:43.243 ttyT38c(o)...0xb58f3b70 <-- Started
2010/12/13 13:33:43.243 T38Modem OpalUDP Binding to interface: 0.0.0.0:5060
2010/12/13 13:33:43.243 T38Modem OpalUDP Started connect to 203.55.231.194:5060
2010/12/13 13:33:43.245 T38Modem SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 13:33:43.245 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 13:33:43.245 T38Modem OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 13:33:43.266 Opal Liste...0xb5bcab70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 13:33:43.266 Opal Liste...0xb5bcab70 SIP No Content-Length present from udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>, reading till end of datagram/stream.
2010/12/13 13:33:43.266 Opal Liste...0xb5bcab70 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 13:33:43.267 Opal Liste...0xb5bcab70 Opal Transport clean up on termination
2010/12/13 13:33:43.267 Pool:0xb58b2b70 SIP REGISTER transaction id=z9hG4bK123a4612-cf04-e011-8029-000c2984a855 completed.
2010/12/13 13:33:43.267 Pool:0xb58b2b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 13:33:43.267 Pool:0xb58b2b70 SIP Expiry time for REGISTER set to 30 seconds.
2010/12/13 13:33:48.268 Housekeeper:0xb5c0bb70 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bK123a4612-cf04-e011-8029-000c2984a855
2010/12/13 13:33:54.664 ttyT38a(e)...0xb5afbb70 --> ATZ
2010/12/13 13:33:54.664 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:33:57.772 ttyT38a(e)...0xb5afbb70 --> ATS0=0
2010/12/13 13:33:57.772 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:33:57.880 ttyT38a(e)...0xb5afbb70 --> ATE0
2010/12/13 13:33:57.880 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:33:57.988 ttyT38a(e)...0xb5afbb70 --> ATV1
2010/12/13 13:33:57.988 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:33:58.096 ttyT38a(e)...0xb5afbb70 --> ATQ0
2010/12/13 13:33:58.096 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:33:58.204 ttyT38a(e)...0xb5afbb70 --> ATS8=2
2010/12/13 13:33:58.204 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:33:58.267 Housekeeper:0xb5c0bb70 SIP Starting REGISTER for binding refresh
2010/12/13 13:33:58.269 Housekeeper:0xb5c0bb70 SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 13:33:58.269 Housekeeper:0xb5c0bb70 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 13:33:58.269 Housekeeper:0xb5c0bb70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 13:33:58.299 Opal Liste...0xb5bcab70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 13:33:58.299 Opal Liste...0xb5bcab70 SIP No Content-Length present from udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>, reading till end of datagram/stream.
2010/12/13 13:33:58.300 Opal Liste...0xb5bcab70 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 13:33:58.300 Opal Liste...0xb5bcab70 Opal Transport clean up on termination
2010/12/13 13:33:58.300 Pool:0xb58b2b70 SIP REGISTER transaction id=z9hG4bK76ba3a1b-cf04-e011-8029-000c2984a855 completed.
2010/12/13 13:33:58.300 Pool:0xb58b2b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 13:33:58.300 Pool:0xb58b2b70 SIP Expiry time for REGISTER set to 30 seconds.
2010/12/13 13:33:58.312 ttyT38a(e)...0xb5afbb70 --> ATS7=60
2010/12/13 13:33:58.312 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:33:58.420 ttyT38a(e)...0xb5afbb70 --> AT+FCLASS=?
2010/12/13 13:33:58.420 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 31 2c 38 0d 0a 4f 4b 0d 0a ..1,8..OK.. }
2010/12/13 13:33:58.528 ttyT38a(e)...0xb5afbb70 --> AT+FCLASS=1
2010/12/13 13:33:58.528 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:33:58.636 ttyT38a(e)...0xb5afbb70 --> ATI3
2010/12/13 13:33:58.636 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 56 79 61 63 68 65 73 6c 61 76 20 46 72 6f ..Vyacheslav Fro
6c 6f 76 0d 0a 4f 4b 0d 0a lov..OK.. }
2010/12/13 13:33:58.745 ttyT38a(e)...0xb5afbb70 --> ATI0
2010/12/13 13:33:58.745 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 54 33 38 46 41 58 0d 0a 4f 4b 0d 0a ..T38FAX..OK.. }
2010/12/13 13:33:58.852 ttyT38a(e)...0xb5afbb70 --> AT+FREV?
2010/12/13 13:33:58.852 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 31 2e 32 2e 30 0d 0a 4f 4b 0d 0a ..1.2.0..OK.. }
2010/12/13 13:33:58.960 ttyT38a(e)...0xb5afbb70 --> AT+FTM=?
2010/12/13 13:33:58.960 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 32 34 2c 34 38 2c 37 32 2c 37 33 2c 37 34 ..24,48,72,73,74
2c 39 36 2c 39 37 2c 39 38 2c 31 32 31 2c 31 32 ,96,97,98,121,12
32 2c 31 34 35 2c 31 34 36 0d 0a 4f 4b 0d 0a 2,145,146..OK.. }
2010/12/13 13:33:59.068 ttyT38a(e)...0xb5afbb70 --> AT+FRM=?
2010/12/13 13:33:59.069 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 32 34 2c 34 38 2c 37 32 2c 37 33 2c 37 34 ..24,48,72,73,74
2c 39 36 2c 39 37 2c 39 38 2c 31 32 31 2c 31 32 ,96,97,98,121,12
32 2c 31 34 35 2c 31 34 36 0d 0a 4f 4b 0d 0a 2,145,146..OK.. }
2010/12/13 13:33:59.176 ttyT38a(e)...0xb5afbb70 --> ATM0
2010/12/13 13:33:59.176 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:34:03.301 Housekeeper:0xb5c0bb70 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bK76ba3a1b-cf04-e011-8029-000c2984a855
2010/12/13 13:34:08.537 ttyT38a(i)...0xb5abab70 --> read ERROR -1 Input/output error
2010/12/13 13:34:08.537 ttyT38a(i)...0xb5abab70 --> Stopped
2010/12/13 13:34:08.537 ttyT38a(o)...0xb5a79b70 <-- Stopped
2010/12/13 13:34:08.548 ttyT38a(e)...0xb5afbb70 <-> Stopped
2010/12/13 13:34:08.559 ttyT38a(b)...0xb5d0fb70 PseudoModemPty::OpenPty removed link /dev/ttyT38a -> /dev/pts/1
2010/12/13 13:34:08.559 ttyT38a(b)...0xb5d0fb70 PseudoModemPty::OpenPty read ERROR -1 Input/output error
2010/12/13 13:34:08.559 ttyT38a(b)...0xb5d0fb70 PseudoModemPty::OpenPty added link /dev/ttyT38a -> /dev/pts/1
2010/12/13 13:34:08.565 ttyT38a(e)...0xb5afbb70 RenameCurrentThread old ThreadName=ModemEngine:0xb5afbb70
2010/12/13 13:34:08.565 ttyT38a(e)...0xb5afbb70 <-> Started
2010/12/13 13:34:08.565 ttyT38a(i)...0xb5a79b70 RenameCurrentThread old ThreadName=InPty:0xb5a79b70
2010/12/13 13:34:08.565 ttyT38a(i)...0xb5a79b70 --> Started
2010/12/13 13:34:08.565 ttyT38a(o)...0xb5abab70 RenameCurrentThread old ThreadName=OutPty:0xb5abab70
2010/12/13 13:34:08.565 ttyT38a(o)...0xb5abab70 <-- Started
2010/12/13 13:34:13.301 Housekeeper:0xb5c0bb70 SIP Starting REGISTER for binding refresh
2010/12/13 13:34:13.303 Housekeeper:0xb5c0bb70 SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 13:34:13.303 Housekeeper:0xb5c0bb70 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 13:34:13.303 Housekeeper:0xb5c0bb70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 13:34:13.328 Opal Liste...0xb5bcab70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 13:34:13.328 Opal Liste...0xb5bcab70 SIP No Content-Length present from udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>, reading till end of datagram/stream.
2010/12/13 13:34:13.328 Opal Liste...0xb5bcab70 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 13:34:13.329 Opal Liste...0xb5bcab70 Opal Transport clean up on termination
2010/12/13 13:34:13.329 Pool:0xb58b2b70 SIP REGISTER transaction id=z9hG4bKd8bd3024-cf04-e011-8029-000c2984a855 completed.
2010/12/13 13:34:13.329 Pool:0xb58b2b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 13:34:13.329 Pool:0xb58b2b70 SIP Expiry time for REGISTER set to 30 seconds.
2010/12/13 13:34:13.760 ttyT38a(e)...0xb5afbb70 --> ATZ
2010/12/13 13:34:13.760 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:34:16.868 ttyT38a(e)...0xb5afbb70 --> ATS0=0
2010/12/13 13:34:16.868 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:34:16.976 ttyT38a(e)...0xb5afbb70 --> ATE0
2010/12/13 13:34:16.976 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:34:17.084 ttyT38a(e)...0xb5afbb70 --> ATV1
2010/12/13 13:34:17.084 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:34:17.192 ttyT38a(e)...0xb5afbb70 --> ATQ0
2010/12/13 13:34:17.192 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:34:17.300 ttyT38a(e)...0xb5afbb70 --> ATS8=2
2010/12/13 13:34:17.300 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:34:17.408 ttyT38a(e)...0xb5afbb70 --> ATS7=60
2010/12/13 13:34:17.408 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:34:17.516 ttyT38a(e)...0xb5afbb70 --> AT+FCLASS=?
2010/12/13 13:34:17.516 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 31 2c 38 0d 0a 4f 4b 0d 0a ..1,8..OK.. }
2010/12/13 13:34:17.624 ttyT38a(e)...0xb5afbb70 --> AT+FCLASS=1
2010/12/13 13:34:17.624 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:34:17.732 ttyT38a(e)...0xb5afbb70 --> ATI3
2010/12/13 13:34:17.732 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 56 79 61 63 68 65 73 6c 61 76 20 46 72 6f ..Vyacheslav Fro
6c 6f 76 0d 0a 4f 4b 0d 0a lov..OK.. }
2010/12/13 13:34:17.840 ttyT38a(e)...0xb5afbb70 --> ATI0
2010/12/13 13:34:17.840 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 54 33 38 46 41 58 0d 0a 4f 4b 0d 0a ..T38FAX..OK.. }
2010/12/13 13:34:17.948 ttyT38a(e)...0xb5afbb70 --> AT+FREV?
2010/12/13 13:34:17.948 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 31 2e 32 2e 30 0d 0a 4f 4b 0d 0a ..1.2.0..OK.. }
2010/12/13 13:34:18.056 ttyT38a(e)...0xb5afbb70 --> AT+FTM=?
2010/12/13 13:34:18.056 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 32 34 2c 34 38 2c 37 32 2c 37 33 2c 37 34 ..24,48,72,73,74
2c 39 36 2c 39 37 2c 39 38 2c 31 32 31 2c 31 32 ,96,97,98,121,12
32 2c 31 34 35 2c 31 34 36 0d 0a 4f 4b 0d 0a 2,145,146..OK.. }
2010/12/13 13:34:18.164 ttyT38a(e)...0xb5afbb70 --> AT+FRM=?
2010/12/13 13:34:18.164 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 32 34 2c 34 38 2c 37 32 2c 37 33 2c 37 34 ..24,48,72,73,74
2c 39 36 2c 39 37 2c 39 38 2c 31 32 31 2c 31 32 ,96,97,98,121,12
32 2c 31 34 35 2c 31 34 36 0d 0a 4f 4b 0d 0a 2,145,146..OK.. }
2010/12/13 13:34:18.272 ttyT38a(e)...0xb5afbb70 --> ATM0
2010/12/13 13:34:18.272 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:34:18.329 Housekeeper:0xb5c0bb70 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bKd8bd3024-cf04-e011-8029-000c2984a855
2010/12/13 13:34:18.380 ttyT38a(e)...0xb5afbb70 --> AT+FCLASS=1
2010/12/13 13:34:18.380 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:34:18.388 ttyT38a(e)...0xb5afbb70 --> ATDT0294396443
2010/12/13 13:34:18.388 ttyT38a(e)...0xb5afbb70 ModemEndPoint::OnMyCallback command=dial extra=3
2010/12/13 13:34:18.388 ttyT38a(e)...0xb5afbb70 PseudoModemQ::Dequeue ttyT38a
2010/12/13 13:34:18.388 ttyT38a(e)...0xb5afbb70 MyManager::OnMyCallback SetUpCall(modem:, 0294396443@+/dev/ttyT38a)
2010/12/13 13:34:18.388 ttyT38a(e)...0xb5afbb70 OpalMan Set up call from modem: to 0294396443@+/dev/ttyT38a
2010/12/13 13:34:18.389 ttyT38a(e)...0xb5afbb70 Call Created Call[r84331c671]
2010/12/13 13:34:18.389 ttyT38a(e)...0xb5afbb70 OpalMan Set up connection to "modem:"
2010/12/13 13:34:18.389 ttyT38a(e)...0xb5afbb70 ModemEndPoint::MakeConnection modem:
2010/12/13 13:34:18.389 ttyT38a(e)...0xb5afbb70 OpalCon Created connection Call[r84331c671]-EP<modem>[modem:/r84331c671/0]
2010/12/13 13:34:18.389 ttyT38a(e)...0xb5afbb70 ModemConnection::ModemConnection Call[r84331c671]-EP<modem>[modem:/r84331c671/0]
2010/12/13 13:34:18.389 ttyT38a(e)...0xb5afbb70 ModemConnection::SetUpConnection Call[r84331c671]-EP<modem>[modem:/r84331c671/0]
2010/12/13 13:34:18.389 ttyT38a(e)...0xb5afbb70 OpalCon SetPhase from UninitialisedPhase to SetUpPhase for Call[r84331c671]-EP<modem>[modem:/r84331c671/0]
2010/12/13 13:34:18.389 ttyT38a(e)...0xb5afbb70 OpalMan OnIncoming connection Call[r84331c671]-EP<modem>[modem:/r84331c671/0]
2010/12/13 13:34:18.389 ttyT38a(e)...0xb5afbb70 Call GetOtherPartyConnection Call[r84331c671]-EP<modem>[modem:/r84331c671/0]
2010/12/13 13:34:18.389 ttyT38a(e)...0xb5afbb70 OpalMan Set up connection to "sip:0294396443@xxxxxxxxxxxxxxxxxxxx"
2010/12/13 13:34:18.389 ttyT38a(e)...0xb5afbb70 MySIPEndPoint::CreateConnection for Call[r84331c671]
2010/12/13 13:34:18.389 ttyT38a(e)...0xb5afbb70 OpalCon Created connection Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855]
2010/12/13 13:34:18.390 ttyT38a(e)...0xb5afbb70 Call[r84331c671] from modem:<username> to 0294396443@+/dev/ttyT38a, route to sip:udp$sip.nsw.iinet.net.au:5060
2010/12/13 13:34:18.390 ttyT38a(e)...0xb5afbb70 Outgoing call routed to sip:0294396443@xxxxxxxxxxxxxxxxxxxx for Call[r84331c671]-EP<modem>[modem:/r84331c671/0]
2010/12/13 13:34:18.390 ttyT38a(e)...0xb5afbb70 Call OnSetUp Call[r84331c671]-EP<modem>[modem:/r84331c671/0]
2010/12/13 13:34:18.390 ttyT38a(e)...0xb5afbb70 MySIPConnection::SetUpConnection Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855] name=<username>
2010/12/13 13:34:18.390 ttyT38a(e)...0xb5afbb70 SIP SetUpConnection: sip:0294396443@xxxxxxxxxxxxxxxxxxxx
2010/12/13 13:34:18.390 ttyT38a(e)...0xb5afbb70 OpalCon SetPhase from UninitialisedPhase to SetUpPhase for Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855]
2010/12/13 13:34:18.390 ttyT38a(e)...0xb5afbb70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 13:34:18.391 ttyT38a(e)...0xb5afbb70 OpalUDP Started connect to 203.55.231.194:5060
2010/12/13 13:34:18.394 ttyT38a(e)...0xb5afbb70 SIP Creating INVITE request
2010/12/13 13:34:18.394 ttyT38a(e)...0xb5afbb70 ModemConnection::GetMediaFormats Call[r84331c671]-EP<modem>[modem:/r84331c671/0]
2010/12/13 13:34:18.394 ttyT38a(e)...0xb5afbb70 ModemEndPoint::GetMediaFormats
2010/12/13 13:34:18.396 ttyT38a(e)...0xb5afbb70 SIP Offering media type audio in SDP with formats
G.711-uLaw-64k,G.711-ALaw-64k,PCM-16,T.38
2010/12/13 13:34:18.396 ttyT38a(e)...0xb5afbb70 Call IsMediaBypassPossible Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855] session 1
2010/12/13 13:34:18.396 ttyT38a(e)...0xb5afbb70 OpalMan IsMediaBypassPossible: session 1
2010/12/13 13:34:18.396 ttyT38a(e)...0xb5afbb70 RTP Cannot find media session 1
2010/12/13 13:34:18.396 ttyT38a(e)...0xb5afbb70 RTP Cannot find RTP session 1
2010/12/13 13:34:18.397 ttyT38a(e)...0xb5afbb70 RTP_UDP Session 1 created: 10.11.12.22:5000-5001 ssrc=3045242925
2010/12/13 13:34:18.397 ttyT38a(e)...0xb5afbb70 RTP Creating new session RTP_UDP
2010/12/13 13:34:18.397 ttyT38a(e)...0xb5afbb70 RTP Found existing media session 1
2010/12/13 13:34:18.398 ttyT38a(e)...0xb5afbb70 SIP Using default RTP payload [pt=101] for UserInput/RFC2833
2010/12/13 13:34:18.398 ttyT38a(e)...0xb5afbb70 SIP Using default RTP payload [pt=100] for NamedSignalEvent
2010/12/13 13:34:18.398 ttyT38a(e)...0xb5afbb70 ModemConnection::GetMediaFormats Call[r84331c671]-EP<modem>[modem:/r84331c671/0]
2010/12/13 13:34:18.398 ttyT38a(e)...0xb5afbb70 ModemEndPoint::GetMediaFormats
2010/12/13 13:34:18.400 ttyT38a(e)...0xb5afbb70 SIP No media formats of type video, not adding SDP
2010/12/13 13:34:18.401 ttyT38a(e)...0xb5afbb70 SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 13:34:18.401 ttyT38a(e)...0xb5afbb70 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 13:34:18.402 ttyT38a(e)...0xb5afbb70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 13:34:18.402 ttyT38a(e)...0xb5afbb70 OpalCon OnSetUpConnectionCall[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855]
2010/12/13 13:34:18.402 ttyT38a(e)...0xb5afbb70 OpalEP OnSetUpConnection Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855]
2010/12/13 13:34:18.402 ttyT38a(e)...0xb5afbb70 OpalMan SetUpCall succeeded, call=Call[r84331c671]
2010/12/13 13:34:18.402 ttyT38a(e)...0xb5afbb70 ttyT38a T38Engine::T38Engine
2010/12/13 13:34:18.402 ttyT38a(e)...0xb5afbb70 ModemEndPoint::OnMyCallback request={
calltoken=modem:/r84331c671/0
localpartyname=
command=dial
response=confirm
number=0294396443
modemtoken=ttyT38a
}
2010/12/13 13:34:18.425 Opal Liste...0xb5bcab70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 13:34:18.425 Opal Liste...0xb5bcab70 SIP No Content-Length present from udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>, reading till end of datagram/stream.
2010/12/13 13:34:18.426 Opal Liste...0xb5bcab70 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 13:34:18.426 Opal Liste...0xb5bcab70 Opal Transport clean up on termination
2010/12/13 13:34:18.426 Pool:0xb5871b70 SIP INVITE transaction id=z9hG4bKc48e3927-cf04-e011-8029-000c2984a855 proceeding.
2010/12/13 13:34:18.426 Pool:0xb5871b70 SIP Received Trying response
2010/12/13 13:34:18.427 Pool:0xb5871b70 OpalCon SetPhase from SetUpPhase to ProceedingPhase for Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855]
2010/12/13 13:34:18.427 Pool:0xb5871b70 OpalMan OnProceeding Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855]
2010/12/13 13:34:18.427 Pool:0xb5871b70 Call OnProceeding Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855]
2010/12/13 13:34:18.444 Opal Liste...0xb5bcab70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 13:34:18.445 Opal Liste...0xb5bcab70 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 13:34:18.445 Opal Liste...0xb5bcab70 Opal Transport clean up on termination
2010/12/13 13:34:18.446 Pool:0xb5871b70 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 13:34:18.447 Pool:0xb5871b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 13:34:18.447 Pool:0xb5871b70 SIP INVITE transaction id=z9hG4bKc48e3927-cf04-e011-8029-000c2984a855 completed.
2010/12/13 13:34:18.447 Pool:0xb5871b70 SIP Received Authentication Required response
2010/12/13 13:34:18.447 Pool:0xb5871b70 SIP Authentication contains qop-options auth
2010/12/13 13:34:18.448 Pool:0xb5871b70 SIP Found auth info for realm "iinetphone.iinet.net.au", user "<username>"
2010/12/13 13:34:18.448 Pool:0xb5871b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 13:34:18.450 Pool:0xb5871b70 SIP Creating INVITE request
2010/12/13 13:34:18.450 Pool:0xb5871b70 ModemConnection::GetMediaFormats Call[r84331c671]-EP<modem>[modem:/r84331c671/0]
2010/12/13 13:34:18.450 Pool:0xb5871b70 ModemEndPoint::GetMediaFormats
2010/12/13 13:34:18.453 Pool:0xb5871b70 SIP Offering media type audio in SDP with formats
G.711-uLaw-64k,G.711-ALaw-64k,PCM-16,T.38
2010/12/13 13:34:18.453 Pool:0xb5871b70 Call IsMediaBypassPossible Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855] session 1
2010/12/13 13:34:18.453 Pool:0xb5871b70 OpalMan IsMediaBypassPossible: session 1
2010/12/13 13:34:18.453 Pool:0xb5871b70 RTP Found existing media session 1
2010/12/13 13:34:18.453 Pool:0xb5871b70 RTP Found existing RTP session 1
2010/12/13 13:34:18.453 Pool:0xb5871b70 SIP Using default RTP payload [pt=101] for UserInput/RFC2833
2010/12/13 13:34:18.453 Pool:0xb5871b70 SIP Using default RTP payload [pt=100] for NamedSignalEvent
2010/12/13 13:34:18.453 Pool:0xb5871b70 ModemConnection::GetMediaFormats Call[r84331c671]-EP<modem>[modem:/r84331c671/0]
2010/12/13 13:34:18.453 Pool:0xb5871b70 ModemEndPoint::GetMediaFormats
2010/12/13 13:34:18.456 Pool:0xb5871b70 SIP No media formats of type video, not adding SDP
2010/12/13 13:34:18.456 Pool:0xb5871b70 SIP Adding authentication information for user "<username>" at realm "iinetphone.iinet.net.au"
2010/12/13 13:34:18.456 Pool:0xb5871b70 SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 13:34:18.456 Pool:0xb5871b70 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 13:34:18.457 Pool:0xb5871b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 13:34:18.556 Opal Liste...0xb5bcab70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 13:34:18.556 Opal Liste...0xb5bcab70 SIP No Content-Length present from udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>, reading till end of datagram/stream.
2010/12/13 13:34:18.557 Opal Liste...0xb5bcab70 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 13:34:18.557 Opal Liste...0xb5bcab70 Opal Transport clean up on termination
2010/12/13 13:34:18.557 Pool:0xb5871b70 SIP INVITE transaction id=z9hG4bK4a2d4227-cf04-e011-8029-000c2984a855 proceeding.
2010/12/13 13:34:18.557 Pool:0xb5871b70 SIP Received Trying response
2010/12/13 13:34:19.265 Opal Liste...0xb5bcab70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 13:34:19.265 Opal Liste...0xb5bcab70 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 13:34:19.265 Opal Liste...0xb5bcab70 Opal Transport clean up on termination
2010/12/13 13:34:19.266 Pool:0xb5871b70 SDP Parsed media session with 2 'audio' formats
2010/12/13 13:34:19.266 Pool:0xb5871b70 SIP INVITE transaction id=z9hG4bK4a2d4227-cf04-e011-8029-000c2984a855 proceeding.
2010/12/13 13:34:19.266 Pool:0xb5871b70 SIP Received Session Progress response
2010/12/13 13:34:19.266 Pool:0xb5871b70 SIP RTP payload type PCMU matched to codec G.711-uLaw-64k
2010/12/13 13:34:19.266 Pool:0xb5871b70 Call IsMediaBypassPossible Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855] session 1
2010/12/13 13:34:19.266 Pool:0xb5871b70 OpalMan IsMediaBypassPossible: session 1
2010/12/13 13:34:19.266 Pool:0xb5871b70 RTP Found existing RTP session 1
2010/12/13 13:34:19.267 Pool:0xb5871b70 RTP_UDP Session 1, SetRemoteSocketInfo: data channel, new=203.55.231.194:38784, local=10.11.12.22:5000-5001, remote=0.0.0.0:0-0
2010/12/13 13:34:19.267 Pool:0xb5871b70 RTP Found existing media session 1
2010/12/13 13:34:19.267 Pool:0xb5871b70 Call OpenSourceMediaStreams opening audio session 1 on Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855]
2010/12/13 13:34:19.267 Pool:0xb5871b70 ModemConnection::GetMediaFormats Call[r84331c671]-EP<modem>[modem:/r84331c671/0]
2010/12/13 13:34:19.267 Pool:0xb5871b70 ModemEndPoint::GetMediaFormats
2010/12/13 13:34:19.267 Pool:0xb5871b70 ModemConnection::GetMediaFormats Call[r84331c671]-EP<modem>[modem:/r84331c671/0]
2010/12/13 13:34:19.267 Pool:0xb5871b70 ModemEndPoint::GetMediaFormats
2010/12/13 13:34:19.270 Pool:0xb5871b70 Call Selected media formats G.711-uLaw-64k -> PCM-16
2010/12/13 13:34:19.270 Pool:0xb5871b70 Call IsMediaBypassPossible Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855] session 1
2010/12/13 13:34:19.270 Pool:0xb5871b70 OpalMan IsMediaBypassPossible: session 1
2010/12/13 13:34:19.270 Pool:0xb5871b70 RTP Found existing media session 1
2010/12/13 13:34:19.270 Pool:0xb5871b70 OpalMan OnOpenMediaStream Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855],OpalRTPMediaStream-Source-G.711-uLaw-64k
2010/12/13 13:34:19.270 Pool:0xb5871b70 OpalCon Opened source stream r84331c671_1 with format G.711-uLaw-64k
2010/12/13 13:34:19.270 Pool:0xb5871b70 ModemConnection::CreateMediaStream Call[r84331c671]-EP<modem>[modem:/r84331c671/0] mediaFormat=PCM-16 sessionID=1 isSource=0
2010/12/13 13:34:19.270 Pool:0xb5871b70 ModemEngineBody::Attach audioEngine stConnectWait
2010/12/13 13:34:19.270 Pool:0xb5871b70 ttyT38a AudioEngine::Attach
2010/12/13 13:34:19.270 Pool:0xb5871b70 ttyT38a AudioClass=FALSE
2010/12/13 13:34:19.270 Pool:0xb5871b70 ttyT38a AudioEngine::SendOnIdle 2
2010/12/13 13:34:19.270 Pool:0xb5871b70 ModemEngineBody::Attach audioEngine Attached
2010/12/13 13:34:19.270 Pool:0xb5871b70 OpalMan OnOpenMediaStream Call[r84331c671]-EP<modem>[modem:/r84331c671/0],AudioModemMediaStream-Sink-PCM-16
2010/12/13 13:34:19.270 Pool:0xb5871b70 OpalCon Opened sink stream r84331c671_1 with format PCM-16
2010/12/13 13:34:19.270 Pool:0xb5871b70 Patch Created Sink: format=PCM-16
2010/12/13 13:34:19.270 Pool:0xb5871b70 Codec G711-uLaw-64k decoder created
2010/12/13 13:34:19.271 Pool:0xb5871b70 Patch Added media stream sink AudioModemMediaStream-Sink-PCM-16 using transcoder G.711-uLaw-64k->PCM-16, data size=3840
2010/12/13 13:34:19.271 Pool:0xb5871b70 Media RTP data size cannot be changed to 8, fixed at 2048
2010/12/13 13:34:19.271 Pool:0xb5871b70 OpalCon Sink stream of connection Call[r84331c671]-EP<modem>[modem:/r84331c671/0] uses patch Patch OpalRTPMediaStream-Source-G.711-uLaw-64k -> AudioModemMediaStream-Sink-PCM-16
2010/12/13 13:34:19.271 Pool:0xb5871b70 OpalCon Source stream of connection Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855] uses patch Patch OpalRTPMediaStream-Source-G.711-uLaw-64k -> AudioModemMediaStream-Sink-PCM-16
2010/12/13 13:34:19.271 Pool:0xb5871b70 RTPCon Adding RFC2833 receive handler
2010/12/13 13:34:19.271 Pool:0xb5871b70 RTPCon Adding Cisco NSE receive handler
2010/12/13 13:34:19.271 Pool:0xb5871b70 Call GetOtherPartyConnection Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855]
2010/12/13 13:34:19.271 Pool:0xb5871b70 Call OpenSourceMediaStreams opening audio session 1 on Call[r84331c671]-EP<modem>[modem:/r84331c671/0]
2010/12/13 13:34:19.271 Pool:0xb5871b70 ModemConnection::GetMediaFormats Call[r84331c671]-EP<modem>[modem:/r84331c671/0]
2010/12/13 13:34:19.271 Pool:0xb5871b70 ModemEndPoint::GetMediaFormats
2010/12/13 13:34:19.272 Pool:0xb5871b70 Call Selected media formats PCM-16 -> G.711-uLaw-64k
2010/12/13 13:34:19.272 Pool:0xb5871b70 ModemConnection::CreateMediaStream Call[r84331c671]-EP<modem>[modem:/r84331c671/0] mediaFormat=PCM-16 sessionID=1 isSource=1
2010/12/13 13:34:19.273 Pool:0xb5871b70 OpalMan OnOpenMediaStream Call[r84331c671]-EP<modem>[modem:/r84331c671/0],AudioModemMediaStream-Source-PCM-16
2010/12/13 13:34:19.273 Pool:0xb5871b70 OpalCon Opened source stream r84331c671_1 with format PCM-16
2010/12/13 13:34:19.273 Pool:0xb5871b70 Call IsMediaBypassPossible Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855] session 1
2010/12/13 13:34:19.273 Pool:0xb5871b70 OpalMan IsMediaBypassPossible: session 1
2010/12/13 13:34:19.273 Pool:0xb5871b70 RTP Found existing media session 1
2010/12/13 13:34:19.273 Pool:0xb5871b70 OpalMan OnOpenMediaStream Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855],OpalRTPMediaStream-Sink-G.711-uLaw-64k
2010/12/13 13:34:19.273 Pool:0xb5871b70 OpalCon Opened sink stream r84331c671_1 with format G.711-uLaw-64k
2010/12/13 13:34:19.273 Pool:0xb5871b70 Patch Created Sink: format=G.711-uLaw-64k
2010/12/13 13:34:19.273 Pool:0xb5871b70 Codec G711-uLaw-64k encoder created
2010/12/13 13:34:19.273 Pool:0xb5871b70 Media RTP data size cannot be changed to 1920, fixed at 528
2010/12/13 13:34:19.273 Pool:0xb5871b70 Patch Added media stream sink OpalRTPMediaStream-Sink-G.711-uLaw-64k using transcoder PCM-16->G.711-uLaw-64k, data size=528
2010/12/13 13:34:19.273 Pool:0xb5871b70 OpalCon Source stream of connection Call[r84331c671]-EP<modem>[modem:/r84331c671/0] uses patch Patch AudioModemMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-G.711-uLaw-64k
2010/12/13 13:34:19.273 Pool:0xb5871b70 OpalCon Sink stream of connection Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855] uses patch Patch AudioModemMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-G.711-uLaw-64k
2010/12/13 13:34:19.273 Pool:0xb5871b70 OpalCon SetPhase from ProceedingPhase to AlertingPhase for Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855]
2010/12/13 13:34:19.273 Pool:0xb5871b70 OpalMan OnAlerting Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855]
2010/12/13 13:34:19.273 Pool:0xb5871b70 Call OnAlerting Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855]
2010/12/13 13:34:19.273 Pool:0xb5871b70 ModemConnection::SetAlerting Call[r84331c671]-EP<modem>[modem:/r84331c671/0] 0294396443@xxxxxxxxxxxxxxxxxxxx 1
2010/12/13 13:34:19.273 Pool:0xb5871b70 OpalCon SetPhase from SetUpPhase to AlertingPhase for Call[r84331c671]-EP<modem>[modem:/r84331c671/0]
2010/12/13 13:34:19.274 Media Patch:0xb57efb70 RTP Session 1, first sent data: ver=2 pt=PCMU psz=160 m=1 x=0 seq=48211 ts=0 src="" ccnt=0
2010/12/13 13:34:19.274 Pool:0xb5871b70 OpalCon Media stream threads started.
2010/12/13 13:34:19.403 Media Patch:0xb5830b70 RTP Session 1, first receive data: ver=2 pt=PCMU psz=160 m=1 x=0 seq=55606 ts=1849373611 src="" ccnt=0
2010/12/13 13:34:21.273 Media Patch:0xb57efb70 RTP Session 1, transmit statistics: packets=101 octets=16160 avgTime=20 maxTime=21 minTime=19
2010/12/13 13:34:23.274 Media Patch:0xb57efb70 RTP Session 1, transmit statistics: packets=201 octets=32160 avgTime=20 maxTime=21 minTime=19
2010/12/13 13:34:23.458 Housekeeper:0xb5c0bb70 SIP Set state Terminated_Success for INVITE transaction id=z9hG4bKc48e3927-cf04-e011-8029-000c2984a855
2010/12/13 13:34:25.274 Media Patch:0xb57efb70 RTP Session 1, transmit statistics: packets=301 octets=48160 avgTime=20 maxTime=21 minTime=19
2010/12/13 13:34:27.274 Media Patch:0xb57efb70 RTP Session 1, transmit statistics: packets=401 octets=64160 avgTime=20 maxTime=21 minTime=19
2010/12/13 13:34:27.687 Opal Liste...0xb5bcab70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 13:34:27.687 Opal Liste...0xb5bcab70 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 13:34:27.688 Opal Liste...0xb5bcab70 Opal Transport clean up on termination
2010/12/13 13:34:27.688 Pool:0xb5871b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 13:34:27.688 Pool:0xb5871b70 SDP Parsed media session with 2 'audio' formats
2010/12/13 13:34:27.691 Pool:0xb5871b70 SIP Adding authentication information for user "<username>" at realm "iinetphone.iinet.net.au"
2010/12/13 13:34:27.691 Pool:0xb5871b70 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 13:34:27.692 Pool:0xb5871b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 13:34:27.692 Pool:0xb5871b70 SIP INVITE transaction id=z9hG4bK4a2d4227-cf04-e011-8029-000c2984a855 completed.
2010/12/13 13:34:27.692 Pool:0xb5871b70 SIP Handling 200 OK response for INVITE
2010/12/13 13:34:27.692 Pool:0xb5871b70 SIP Received INVITE OK response
2010/12/13 13:34:27.692 Pool:0xb5871b70 SIP RTP payload type PCMU matched to codec G.711-uLaw-64k
2010/12/13 13:34:27.692 Pool:0xb5871b70 Call IsMediaBypassPossible Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855] session 1
2010/12/13 13:34:27.692 Pool:0xb5871b70 OpalMan IsMediaBypassPossible: session 1
2010/12/13 13:34:27.692 Pool:0xb5871b70 RTP Found existing RTP session 1
2010/12/13 13:34:27.693 Pool:0xb5871b70 RTP Found existing media session 1
2010/12/13 13:34:27.693 Pool:0xb5871b70 OpalCon SetPhase from AlertingPhase to ConnectedPhase for Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855]
2010/12/13 13:34:27.693 Pool:0xb5871b70 OpalCon OnConnected for Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855]
2010/12/13 13:34:27.693 Pool:0xb5871b70 OpalMan OnConnected Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855]
2010/12/13 13:34:27.693 Pool:0xb5871b70 Call OnConnected Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855]
2010/12/13 13:34:27.693 Pool:0xb5871b70 ModemConnection::SetConnected Call[r84331c671]-EP<modem>[modem:/r84331c671/0]
2010/12/13 13:34:27.693 Pool:0xb5871b70 OpalCon SetConnected for Call[r84331c671]-EP<modem>[modem:/r84331c671/0]
2010/12/13 13:34:27.693 Pool:0xb5871b70 OpalCon SetPhase from AlertingPhase to ConnectedPhase for Call[r84331c671]-EP<modem>[modem:/r84331c671/0]
2010/12/13 13:34:27.693 Pool:0xb5871b70 OpalCon SetPhase from ConnectedPhase to EstablishedPhase for Call[r84331c671]-EP<modem>[modem:/r84331c671/0]
2010/12/13 13:34:27.693 Pool:0xb5871b70 ModemConnection::OnEstablished Call[r84331c671]-EP<modem>[modem:/r84331c671/0]
2010/12/13 13:34:27.693 Pool:0xb5871b70 ModemEngineBody::Request stConnectWait request={
calltoken=modem:/r84331c671/0
command=established
}
2010/12/13 13:34:27.693 Pool:0xb5871b70 OpalCon OnEstablished Call[r84331c671]-EP<modem>[modem:/r84331c671/0]
2010/12/13 13:34:27.693 Pool:0xb5871b70 OpalCon Media stream threads started.
2010/12/13 13:34:27.693 Pool:0xb5871b70 OpalMan OnEstablished Call[r84331c671]-EP<modem>[modem:/r84331c671/0]
2010/12/13 13:34:27.693 Pool:0xb5871b70 Call OnEstablished Call[r84331c671]-EP<modem>[modem:/r84331c671/0]
2010/12/13 13:34:27.693 Pool:0xb5871b70 OpalCon Media stream threads started.
2010/12/13 13:34:27.693 Pool:0xb5871b70 OpalCon SetPhase from ConnectedPhase to EstablishedPhase for Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855]
2010/12/13 13:34:27.693 Pool:0xb5871b70 OpalCon OnEstablished Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855]
2010/12/13 13:34:27.693 Pool:0xb5871b70 OpalCon Media stream threads started.
2010/12/13 13:34:27.694 Pool:0xb5871b70 OpalMan OnEstablished Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855]
2010/12/13 13:34:27.694 Pool:0xb5871b70 Call OnEstablished Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855]
2010/12/13 13:34:27.694 Pool:0xb5871b70 OpalCon Media stream threads started.
2010/12/13 13:34:28.331 Housekeeper:0xb5c0bb70 SIP Starting REGISTER for binding refresh
2010/12/13 13:34:28.332 Housekeeper:0xb5c0bb70 SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 13:34:28.333 Housekeeper:0xb5c0bb70 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 13:34:28.333 Housekeeper:0xb5c0bb70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 13:34:28.355 Opal Liste...0xb5bcab70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 13:34:28.355 Opal Liste...0xb5bcab70 SIP No Content-Length present from udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>, reading till end of datagram/stream.
2010/12/13 13:34:28.355 Opal Liste...0xb5bcab70 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 13:34:28.356 Opal Liste...0xb5bcab70 Opal Transport clean up on termination
2010/12/13 13:34:28.356 Pool:0xb58b2b70 SIP REGISTER transaction id=z9hG4bK1816262d-cf04-e011-8029-000c2984a855 completed.
2010/12/13 13:34:28.356 Pool:0xb58b2b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 13:34:28.356 Pool:0xb58b2b70 SIP Expiry time for REGISTER set to 30 seconds.
2010/12/13 13:34:29.274 Media Patch:0xb57efb70 RTP Session 1, transmit statistics: packets=501 octets=80160 avgTime=20 maxTime=21 minTime=19
2010/12/13 13:34:30.398 Media Patch:0xb5830b70 RTP Session 1, SentSenderReport: ssrc=3045242925 ntp=3501196470.1711678574 rtp=0 psent=557 osent=89120
2010/12/13 13:34:30.398 Media Patch:0xb5830b70 RTP Session 1, SentReceiverReport: ssrc=512817249 fraction=0 lost=0 last_seq=0 jitter=13 lsr=0 dlsr=0
2010/12/13 13:34:30.398 Media Patch:0xb5830b70 RTP Session 1, sending SDES: root@xxxxxxxxxxxxxxxxxxx
2010/12/13 13:34:31.274 Media Patch:0xb57efb70 RTP Session 1, transmit statistics: packets=601 octets=96160 avgTime=20 maxTime=21 minTime=19
2010/12/13 13:34:32.052 Media Patch:0xb5830b70 RTP Session 1, OnRxSenderReport: ssrc=512817249 ntp=2010/12/13-13:34:33.454805 rtp=0 psent=633 osent=101280
RR: ssrc=101280 fraction=181 lost=8566829 last_seq=0 jitter=48849 lsr=0.016 dlsr=1439:17:22.228
2010/12/13 13:34:32.052 Media Patch:0xb5830b70 RTP Session 1, OnSourceDescription: 1 entries
ssrc=512817249
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 13:34:32.694 Housekeeper:0xb5c0bb70 SIP Set state Terminated_Success for INVITE transaction id=z9hG4bK4a2d4227-cf04-e011-8029-000c2984a855
2010/12/13 13:34:33.274 Media Patch:0xb57efb70 RTP Session 1, transmit statistics: packets=701 octets=112160 avgTime=20 maxTime=21 minTime=19
2010/12/13 13:34:33.357 Housekeeper:0xb5c0bb70 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bK1816262d-cf04-e011-8029-000c2984a855
2010/12/13 13:34:35.274 Media Patch:0xb57efb70 RTP Session 1, transmit statistics: packets=801 octets=128160 avgTime=20 maxTime=27 minTime=14
2010/12/13 13:34:36.099 Media Patch:0xb5830b70 RTP Session 1, OnRxSenderReport: ssrc=512817249 ntp=2010/12/13-13:34:37.502741 rtp=0 psent=836 osent=133760
RR: ssrc=133760 fraction=181 lost=8566829 last_seq=0 jitter=49051 lsr=0.032 dlsr=1439:17:22.228
2010/12/13 13:34:36.099 Media Patch:0xb5830b70 RTP Session 1, OnSourceDescription: 1 entries
ssrc=512817249
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 13:34:37.274 Media Patch:0xb57efb70 RTP Session 1, transmit statistics: packets=901 octets=144160 avgTime=20 maxTime=21 minTime=18
2010/12/13 13:34:38.434 Media Patch:0xb57efb70 RTP Session 1, SentSenderReport: ssrc=3045242925 ntp=3501196478.1867211548 rtp=0 psent=959 osent=153440
2010/12/13 13:34:38.434 Media Patch:0xb57efb70 RTP Session 1, SentReceiverReport: ssrc=512817249 fraction=0 lost=0 last_seq=55965 jitter=1753 lsr=0 dlsr=0
2010/12/13 13:34:38.434 Media Patch:0xb57efb70 RTP Session 1, sending SDES: root@xxxxxxxxxxxxxxxxxxx
2010/12/13 13:34:39.274 Media Patch:0xb57efb70 RTP Session 1, transmit statistics: packets=1001 octets=160160 avgTime=20 maxTime=21 minTime=18
2010/12/13 13:34:40.607 Media Patch:0xb5830b70 RTP Session 1, OnRxSenderReport: ssrc=512817249 ntp=2010/12/13-13:34:42.009449 rtp=0 psent=1061 osent=169760
RR: ssrc=169760 fraction=181 lost=8566829 last_seq=0 jitter=49276 lsr=0.024 dlsr=1448:52:37.483
2010/12/13 13:34:40.607 Media Patch:0xb5830b70 RTP Session 1, OnSourceDescription: 1 entries
ssrc=512817249
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 13:34:41.274 Media Patch:0xb57efb70 RTP Session 1, transmit statistics: packets=1101 octets=176160 avgTime=20 maxTime=21 minTime=19
2010/12/13 13:34:43.274 Media Patch:0xb57efb70 RTP Session 1, transmit statistics: packets=1201 octets=192160 avgTime=19 maxTime=21 minTime=18
2010/12/13 13:34:43.358 Housekeeper:0xb5c0bb70 SIP Starting REGISTER for binding refresh
2010/12/13 13:34:43.359 Housekeeper:0xb5c0bb70 SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 13:34:43.359 Housekeeper:0xb5c0bb70 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 13:34:43.359 Housekeeper:0xb5c0bb70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 13:34:43.379 Opal Liste...0xb5bcab70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 13:34:43.380 Opal Liste...0xb5bcab70 SIP No Content-Length present from udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>, reading till end of datagram/stream.
2010/12/13 13:34:43.380 Opal Liste...0xb5bcab70 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 13:34:43.380 Opal Liste...0xb5bcab70 Opal Transport clean up on termination
2010/12/13 13:34:43.380 Pool:0xb58b2b70 SIP REGISTER transaction id=z9hG4bK68f01a36-cf04-e011-8029-000c2984a855 completed.
2010/12/13 13:34:43.380 Pool:0xb58b2b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 13:34:43.380 Pool:0xb58b2b70 SIP Expiry time for REGISTER set to 30 seconds.
2010/12/13 13:34:44.044 Media Patch:0xb5830b70 RTP Session 1, OnRxSenderReport: ssrc=512817249 ntp=2010/12/13-13:34:45.447797 rtp=0 psent=1233 osent=197280
RR: ssrc=197280 fraction=181 lost=8566829 last_seq=0 jitter=49448 lsr=0.024 dlsr=1448:52:37.483
2010/12/13 13:34:44.044 Media Patch:0xb5830b70 RTP Session 1, OnSourceDescription: 1 entries
ssrc=512817249
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 13:34:45.274 Media Patch:0xb57efb70 RTP Session 1, transmit statistics: packets=1301 octets=208160 avgTime=20 maxTime=21 minTime=18
2010/12/13 13:34:46.640 Media Patch:0xb5830b70 RTP Session 1, OnRxSenderReport: ssrc=512817249 ntp=2010/12/13-13:34:48.043775 rtp=0 psent=1363 osent=218080
RR: ssrc=218080 fraction=181 lost=8566829 last_seq=0 jitter=49578 lsr=0.032 dlsr=1448:52:37.483
2010/12/13 13:34:46.640 Media Patch:0xb5830b70 RTP Session 1, OnSourceDescription: 1 entries
ssrc=512817249
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 13:34:47.274 Media Patch:0xb57efb70 RTP Session 1, transmit statistics: packets=1401 octets=224160 avgTime=20 maxTime=21 minTime=19
2010/12/13 13:34:48.381 Housekeeper:0xb5c0bb70 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bK68f01a36-cf04-e011-8029-000c2984a855
2010/12/13 13:34:49.274 Media Patch:0xb57efb70 RTP Session 1, transmit statistics: packets=1501 octets=240160 avgTime=20 maxTime=21 minTime=19
2010/12/13 13:34:51.274 Media Patch:0xb57efb70 RTP Session 1, transmit statistics: packets=1601 octets=256160 avgTime=20 maxTime=22 minTime=18
2010/12/13 13:34:53.274 Media Patch:0xb57efb70 RTP Session 1, transmit statistics: packets=1701 octets=272160 avgTime=20 maxTime=21 minTime=19
2010/12/13 13:34:53.472 Media Patch:0xb5830b70 RTP Session 1, SentSenderReport: ssrc=3045242925 ntp=3501196493.2028799062 rtp=0 psent=1710 osent=273600
2010/12/13 13:34:53.472 Media Patch:0xb5830b70 RTP Session 1, SentReceiverReport: ssrc=512817249 fraction=0 lost=0 last_seq=56190 jitter=14 lsr=0 dlsr=0
2010/12/13 13:34:53.472 Media Patch:0xb5830b70 RTP Session 1, sending SDES: root@xxxxxxxxxxxxxxxxxxx
2010/12/13 13:34:53.991 Media Patch:0xb5830b70 RTP Session 1, OnRxSenderReport: ssrc=512817249 ntp=2010/12/13-13:34:55.394074 rtp=0 psent=1730 osent=276800
RR: ssrc=276800 fraction=181 lost=8566829 last_seq=0 jitter=49945 lsr=0.024 dlsr=1466:49:03.604
2010/12/13 13:34:53.991 Media Patch:0xb5830b70 RTP Session 1, OnSourceDescription: 1 entries
ssrc=512817249
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 13:34:55.274 Media Patch:0xb57efb70 RTP Session 1, transmit statistics: packets=1801 octets=288160 avgTime=20 maxTime=21 minTime=19
2010/12/13 13:34:57.274 Media Patch:0xb57efb70 RTP Session 1, transmit statistics: packets=1901 octets=304160 avgTime=20 maxTime=20 minTime=20
2010/12/13 13:34:58.381 Housekeeper:0xb5c0bb70 SIP Starting REGISTER for binding refresh
2010/12/13 13:34:58.383 Housekeeper:0xb5c0bb70 SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 13:34:58.383 Housekeeper:0xb5c0bb70 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 13:34:58.384 Housekeeper:0xb5c0bb70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 13:34:58.404 Opal Liste...0xb5bcab70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 13:34:58.405 Opal Liste...0xb5bcab70 SIP No Content-Length present from udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>, reading till end of datagram/stream.
2010/12/13 13:34:58.405 Opal Liste...0xb5bcab70 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 13:34:58.405 Opal Liste...0xb5bcab70 Opal Transport clean up on termination
2010/12/13 13:34:58.405 Pool:0xb58b2b70 SIP REGISTER transaction id=z9hG4bK546b0f3f-cf04-e011-8029-000c2984a855 completed.
2010/12/13 13:34:58.405 Pool:0xb58b2b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 13:34:58.405 Pool:0xb58b2b70 SIP Expiry time for REGISTER set to 30 seconds.
2010/12/13 13:34:59.274 Media Patch:0xb57efb70 RTP Session 1, transmit statistics: packets=2001 octets=320160 avgTime=20 maxTime=21 minTime=19
2010/12/13 13:35:01.028 Media Patch:0xb5830b70 RTP Session 1, OnRxSenderReport: ssrc=512817249 ntp=2010/12/13-13:35:2.431626 rtp=0 psent=2082 osent=333120
RR: ssrc=333120 fraction=181 lost=8566829 last_seq=0 jitter=50297 lsr=0.032 dlsr=1466:49:03.604
2010/12/13 13:35:01.028 Media Patch:0xb5830b70 RTP Session 1, OnSourceDescription: 1 entries
ssrc=512817249
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 13:35:01.274 Media Patch:0xb57efb70 RTP Session 1, transmit statistics: packets=2101 octets=336160 avgTime=20 maxTime=21 minTime=19
2010/12/13 13:35:02.551 Media Patch:0xb5830b70 RTP Session 1, adjusting sequence numbers to expect 56680 ssrc=512817249
2010/12/13 13:35:02.561 Media Patch:0xb5830b70 RTP Session 1, out of order packet, received 56678 expected 56680 ssrc=512817249
2010/12/13 13:35:03.273 Media Patch:0xb57efb70 RTP Session 1, transmit statistics: packets=2201 octets=352160 avgTime=19 maxTime=22 minTime=18
2010/12/13 13:35:03.407 Housekeeper:0xb5c0bb70 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bK546b0f3f-cf04-e011-8029-000c2984a855
2010/12/13 13:35:04.774 Media Patch:0xb5830b70 RTP Session 1, OnRxSenderReport: ssrc=512817249 ntp=2010/12/13-13:35:6.177076 rtp=0 psent=2270 osent=363200
RR: ssrc=363200 fraction=181 lost=8566829 last_seq=0 jitter=50485 lsr=0.016 dlsr=1466:49:03.604
2010/12/13 13:35:04.774 Media Patch:0xb5830b70 RTP Session 1, OnSourceDescription: 1 entries
ssrc=512817249
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 13:35:05.274 Media Patch:0xb57efb70 RTP Session 1, transmit statistics: packets=2301 octets=368160 avgTime=20 maxTime=21 minTime=19
2010/12/13 13:35:07.274 Media Patch:0xb57efb70 RTP Session 1, transmit statistics: packets=2401 octets=384160 avgTime=20 maxTime=21 minTime=19
2010/12/13 13:35:08.570 Media Patch:0xb5830b70 RTP Session 1, SentSenderReport: ssrc=3045242925 ntp=3501196508.2449516594 rtp=0 psent=2465 osent=394400
2010/12/13 13:35:08.570 Media Patch:0xb5830b70 RTP Session 1, SentReceiverReport: ssrc=512817249 fraction=0 lost=0 last_seq=56447 jitter=17 lsr=0 dlsr=0
2010/12/13 13:35:08.570 Media Patch:0xb5830b70 RTP Session 1, sending SDES: root@xxxxxxxxxxxxxxxxxxx
2010/12/13 13:35:09.274 Media Patch:0xb57efb70 RTP Session 1, transmit statistics: packets=2501 octets=400160 avgTime=20 maxTime=21 minTime=19
2010/12/13 13:35:10.465 Media Patch:0xb5830b70 RTP Session 1, OnRxSenderReport: ssrc=512817249 ntp=2010/12/13-13:35:11.868781 rtp=0 psent=2554 osent=408640
RR: ssrc=408640 fraction=181 lost=8566829 last_seq=0 jitter=50769 lsr=0.024 dlsr=1484:49:48.789
2010/12/13 13:35:10.465 Media Patch:0xb5830b70 RTP Session 1, OnSourceDescription: 1 entries
ssrc=512817249
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 13:35:11.274 Media Patch:0xb57efb70 RTP Session 1, transmit statistics: packets=2601 octets=416160 avgTime=20 maxTime=21 minTime=19
2010/12/13 13:35:13.274 Media Patch:0xb57efb70 RTP Session 1, transmit statistics: packets=2701 octets=432160 avgTime=20 maxTime=22 minTime=19
2010/12/13 13:35:13.406 Housekeeper:0xb5c0bb70 SIP Starting REGISTER for binding refresh
2010/12/13 13:35:13.407 Housekeeper:0xb5c0bb70 SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 13:35:13.407 Housekeeper:0xb5c0bb70 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 13:35:13.408 Housekeeper:0xb5c0bb70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 13:35:13.434 Opal Liste...0xb5bcab70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 13:35:13.434 Opal Liste...0xb5bcab70 SIP No Content-Length present from udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>, reading till end of datagram/stream.
2010/12/13 13:35:13.435 Opal Liste...0xb5bcab70 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 13:35:13.435 Opal Liste...0xb5bcab70 Opal Transport clean up on termination
2010/12/13 13:35:13.435 Pool:0xb58b2b70 SIP REGISTER transaction id=z9hG4bK5cf70348-cf04-e011-8029-000c2984a855 completed.
2010/12/13 13:35:13.435 Pool:0xb58b2b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 13:35:13.435 Pool:0xb58b2b70 SIP Expiry time for REGISTER set to 30 seconds.
2010/12/13 13:35:13.898 Media Patch:0xb5830b70 RTP Session 1, OnRxSenderReport: ssrc=512817249 ntp=2010/12/13-13:35:15.301716 rtp=0 psent=2726 osent=436160
RR: ssrc=436160 fraction=181 lost=8566829 last_seq=0 jitter=50941 lsr=0.032 dlsr=1484:49:48.789
2010/12/13 13:35:13.898 Media Patch:0xb5830b70 RTP Session 1, OnSourceDescription: 1 entries
ssrc=512817249
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 13:35:15.274 Media Patch:0xb57efb70 RTP Session 1, transmit statistics: packets=2801 octets=448160 avgTime=20 maxTime=21 minTime=19
2010/12/13 13:35:15.982 Opal Liste...0xb5bcab70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 13:35:15.982 Opal Liste...0xb5bcab70 SIP PDU INVITE 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 13:35:15.984 Opal Liste...0xb5bcab70 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 13:35:15.984 Opal Liste...0xb5bcab70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 13:35:15.984 Opal Liste...0xb5bcab70 Opal Transport clean up on termination
2010/12/13 13:35:15.984 Pool:0xb5871b70 SIP Received re-INVITE from sip:<username>@10.11.12.22 for Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855]
2010/12/13 13:35:15.985 Pool:0xb5871b70 SDP Connection address of 0.0.0.0 specified for HOLD request.
2010/12/13 13:35:15.985 Pool:0xb5871b70 SDP Connection address of 0.0.0.0 specified for HOLD request.
2010/12/13 13:35:15.985 Pool:0xb5871b70 SDP Parsed media session with 2 'audio' formats
2010/12/13 13:35:15.985 Pool:0xb5871b70 SIP Remote hold detected
2010/12/13 13:35:15.985 Pool:0xb5871b70 OpalMan On Hold from remote on Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855]
2010/12/13 13:35:15.985 Pool:0xb5871b70 Call IsMediaBypassPossible Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855] session 1
2010/12/13 13:35:15.985 Pool:0xb5871b70 OpalMan IsMediaBypassPossible: session 1
2010/12/13 13:35:15.985 Pool:0xb5871b70 RTP Found existing RTP session 1
2010/12/13 13:35:15.986 Pool:0xb5871b70 RTP Found existing media session 1
2010/12/13 13:35:15.986 Pool:0xb5871b70 SIP RTP payload type PCMU matched to codec G.711-uLaw-64k
2010/12/13 13:35:15.986 Pool:0xb5871b70 Media Paused stream OpalRTPMediaStream-Sink-G.711-uLaw-64k
2010/12/13 13:35:15.986 Pool:0xb5871b70 Media Paused stream OpalRTPMediaStream-Source-G.711-uLaw-64k
2010/12/13 13:35:15.986 Pool:0xb5871b70 Call GetOtherPartyConnection Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855]
2010/12/13 13:35:15.986 Pool:0xb5871b70 SIP Using handler RTP payload [pt=101] for UserInput/RFC2833
2010/12/13 13:35:15.987 Pool:0xb5871b70 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 13:35:15.987 Pool:0xb5871b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 13:35:16.065 Opal Liste...0xb5bcab70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 13:35:16.065 Opal Liste...0xb5bcab70 SIP PDU ACK 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 13:35:16.065 Opal Liste...0xb5bcab70 Opal Transport clean up on termination
2010/12/13 13:35:16.065 Pool:0xb5871b70 SIP ACK received: EstablishedPhase
2010/12/13 13:35:16.065 Pool:0xb5871b70 OpalCon Media stream threads started.
2010/12/13 13:35:17.533 Media Patch:0xb5830b70 RTP Session 1, OnReceiverReport: ssrc=512817249
RR: ssrc=3045242925 fraction=0 lost=0 last_seq=51045 jitter=32 lsr=22:39:24.160 dlsr=10:40:31.358
2010/12/13 13:35:17.533 Media Patch:0xb5830b70 RTP Session 1, OnSourceDescription: 1 entries
ssrc=512817249
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 13:35:18.436 Housekeeper:0xb5c0bb70 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bK5cf70348-cf04-e011-8029-000c2984a855
2010/12/13 13:35:22.254 Media Patch:0xb5830b70 RTP Session 1, SentSenderReport: ssrc=3045242925 ntp=3501196522.1094815416 rtp=0 psent=2836 osent=453760
2010/12/13 13:35:22.255 Media Patch:0xb5830b70 RTP Session 1, SentReceiverReport: ssrc=512817249 fraction=0 lost=0 last_seq=56756 jitter=9 lsr=0 dlsr=0
2010/12/13 13:35:22.255 Media Patch:0xb5830b70 RTP Session 1, sending SDES: root@xxxxxxxxxxxxxxxxxxx
2010/12/13 13:35:22.468 Media Patch:0xb5830b70 RTP Session 1, OnReceiverReport: ssrc=512817249
2010/12/13 13:35:22.468 Media Patch:0xb5830b70 RTP Session 1, OnSourceDescription: 1 entries
ssrc=512817249
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 13:35:27.695 Housekeeper:0xb5c0bb70 ModemEngineBody::OnTimerCallback stReqModeAckWait Timeout 0
2010/12/13 13:35:27.695 ttyT38a(e)...0xb5afbb70 ModemEndPoint::OnMyCallback command=clearcall extra=1
2010/12/13 13:35:27.696 ttyT38a(e)...0xb5afbb70 OpalCon Call end reason for Call[r84331c671]-EP<modem>[modem:/r84331c671/0] set to EndedByLocalUser
2010/12/13 13:35:27.696 ttyT38a(e)...0xb5afbb70 Call Clearing Call[r84331c671] reason=EndedByLocalUser
2010/12/13 13:35:27.696 ttyT38a(e)...0xb5afbb70 OpalCon SetPhase from EstablishedPhase to ReleasingPhase for Call[r84331c671]-EP<modem>[modem:/r84331c671/0]
2010/12/13 13:35:27.696 ttyT38a(e)...0xb5afbb70 OpalCon Releasing Call[r84331c671]-EP<modem>[modem:/r84331c671/0]
2010/12/13 13:35:27.696 ttyT38a(e)...0xb5afbb70 ModemConnection::OnReleased Call[r84331c671]-EP<modem>[modem:/r84331c671/0]
2010/12/13 13:35:27.696 ttyT38a(e)...0xb5afbb70 OpalCon OnReleased Call[r84331c671]-EP<modem>[modem:/r84331c671/0]
2010/12/13 13:35:27.696 ttyT38a(e)...0xb5afbb70 OpalMan OnReleased Call[r84331c671]-EP<modem>[modem:/r84331c671/0]
2010/12/13 13:35:27.696 ttyT38a(e)...0xb5afbb70 Call OnReleased Call[r84331c671]-EP<modem>[modem:/r84331c671/0]
2010/12/13 13:35:27.696 ttyT38a(e)...0xb5afbb70 OpalCon SetPhase from EstablishedPhase to ReleasingPhase for Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855]
2010/12/13 13:35:27.696 ttyT38a(e)...0xb5afbb70 OpalCon Releasing Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855]
2010/12/13 13:35:27.696 ttyT38a(e)...0xb5afbb70 OpalCon Call end reason for Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855] set to EndedByLocalUser
2010/12/13 13:35:27.696 ttyT38a(e)...0xb5afbb70 Patch Removing media stream sink AudioModemMediaStream-Sink-PCM-16
2010/12/13 13:35:27.696 ttyT38a(e)...0xb5afbb70 OpalCon Removed media stream AudioModemMediaStream-Sink-PCM-16
2010/12/13 13:35:27.696 ttyT38a(e)...0xb5afbb70 Call GetOtherPartyConnection Call[r84331c671]-EP<modem>[modem:/r84331c671/0]
2010/12/13 13:35:27.696 ttyT38a(e)...0xb5afbb70 Patch Closing media patch Patch AudioModemMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-G.711-uLaw-64k
2010/12/13 13:35:27.696 ttyT38a(e)...0xb5afbb70 Media Closing RTP for OpalRTPMediaStream-Sink-G.711-uLaw-64k
2010/12/13 13:35:27.696 ttyT38a(e)...0xb5afbb70 RTP_UDP Session 1, Shutting down write.
2010/12/13 13:35:27.696 ttyT38a(e)...0xb5afbb70 Patch Removing media stream sink OpalRTPMediaStream-Sink-G.711-uLaw-64k
2010/12/13 13:35:27.696 ttyT38a(e)...0xb5afbb70 OpalCon Removed media stream OpalRTPMediaStream-Sink-G.711-uLaw-64k
2010/12/13 13:35:27.696 OnRelease:0xb57aeb70 SIP OnReleased: Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855], phase = ReleasingPhase
2010/12/13 13:35:27.696 OnRelease:0xb57aeb70 OpalCon SetPhase from ReleasingPhase to ReleasingPhase for Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855]
2010/12/13 13:35:27.698 OnRelease:0xb57aeb70 Media Closing RTP for OpalRTPMediaStream-Source-G.711-uLaw-64k
2010/12/13 13:35:27.698 OnRelease:0xb57aeb70 RTP_UDP Session 1, Shutting down read.
2010/12/13 13:35:27.698 Media Patch:0xb5830b70 RTP_UDP Session 1, Read shutdown.
2010/12/13 13:35:27.698 OnRelease:0xb57aeb70 Patch Closing media patch Patch OpalRTPMediaStream-Source-G.711-uLaw-64k
2010/12/13 13:35:27.699 OnRelease:0xb57aeb70 OpalCon Removed media stream OpalRTPMediaStream-Source-G.711-uLaw-64k
2010/12/13 13:35:27.699 OnRelease:0xb57aeb70 OpalCon Media streams closed.
2010/12/13 13:35:27.699 OnRelease:0xb57aeb70 SIP Adding authentication information for user "<username>" at realm "iinetphone.iinet.net.au"
2010/12/13 13:35:27.699 OnRelease:0xb57aeb70 SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 13:35:27.699 OnRelease:0xb57aeb70 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 13:35:27.699 OnRelease:0xb57aeb70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 13:35:27.718 ttyT38a(e)...0xb5afbb70 OpalCon Removed media stream AudioModemMediaStream-Source-PCM-16
2010/12/13 13:35:27.718 ttyT38a(e)...0xb5afbb70 OpalCon Media streams closed.
2010/12/13 13:35:27.718 ttyT38a(e)...0xb5afbb70 ModemEndPoint::OnMyCallback request={
calltoken=modem:/r84331c671/0
command=clearcall
response=confirm
modemtoken=ttyT38a
}
2010/12/13 13:35:27.718 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 4e 4f 20 43 41 52 52 49 45 52 0d 0a ..NO CARRIER.. }
2010/12/13 13:35:27.724 Opal Liste...0xb5bcab70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 13:35:27.724 Opal Liste...0xb5bcab70 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 13:35:27.724 Opal Liste...0xb5bcab70 Opal Transport clean up on termination
2010/12/13 13:35:27.724 Pool:0xb5871b70 SIP BYE transaction id=z9hG4bK1c9b8850-cf04-e011-8029-000c2984a855 completed.
2010/12/13 13:35:27.724 OnRelease:0xb57aeb70 OpalCon SetPhase from ReleasingPhase to ReleasedPhase for Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855]
2010/12/13 13:35:27.724 OnRelease:0xb57aeb70 OpalCon OnReleased Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855]
2010/12/13 13:35:27.724 OnRelease:0xb57aeb70 OpalMan OnReleased Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855]
2010/12/13 13:35:27.724 OnRelease:0xb57aeb70 Call OnReleased Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855]
2010/12/13 13:35:27.724 OnRelease:0xb57aeb70 Call[r84331c671] cleared
2010/12/13 13:35:27.724 OnRelease:0xb57aeb70 OpalMan OnClearedCall Call[r84331c671] from "modem:" to "sip:0294396443@xxxxxxxxxxxxxxxxxxxx"
2010/12/13 13:35:27.724 OnRelease:0xb57aeb70 OPAL Attempting to close recording on call r84331c671 when not recording.
2010/12/13 13:35:27.724 OnRelease:0xb57aeb70 OpalCon Media streams closed.
2010/12/13 13:35:28.378 Opal Garbage:0xb5d50b70 ModemConnection::~ModemConnection Call[r84331c671]-EP<modem>[modem:/r84331c671/0]
2010/12/13 13:35:28.378 Opal Garbage:0xb5d50b70 ModemEngineBody::_Detach No t38engine was Attached
2010/12/13 13:35:28.378 Opal Garbage:0xb5d50b70 ttyT38a AudioEngine::Detach
2010/12/13 13:35:28.378 Opal Garbage:0xb5d50b70 ttyT38a AudioEngine::Detach Detached
2010/12/13 13:35:28.378 Opal Garbage:0xb5d50b70 ModemEngineBody::_Detach audioEngine Detached
2010/12/13 13:35:28.378 Opal Garbage:0xb5d50b70 ModemEngineBody::Request stCommand request={
calltoken=modem:/r84331c671/0
command=clearcall
}
2010/12/13 13:35:28.378 Opal Garbage:0xb5d50b70 ModemEngineBody::Request not in use
2010/12/13 13:35:28.378 Opal Garbage:0xb5d50b70 PseudoModemQ::Enqueue ttyT38a
2010/12/13 13:35:28.378 Opal Garbage:0xb5d50b70 ttyT38a T38Engine::~T38Engine
2010/12/13 13:35:28.378 Opal Garbage:0xb5d50b70 OpalCon Connection Call[r84331c671]-EP<modem>[modem:/r84331c671/0] destroyed.
2010/12/13 13:35:28.436 Housekeeper:0xb5c0bb70 SIP Starting REGISTER for binding refresh
2010/12/13 13:35:28.437 Housekeeper:0xb5c0bb70 SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 13:35:28.437 Housekeeper:0xb5c0bb70 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 13:35:28.437 Housekeeper:0xb5c0bb70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 13:35:28.460 Opal Liste...0xb5bcab70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 13:35:28.460 Opal Liste...0xb5bcab70 SIP No Content-Length present from udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>, reading till end of datagram/stream.
2010/12/13 13:35:28.460 Opal Liste...0xb5bcab70 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 13:35:28.460 Opal Liste...0xb5bcab70 Opal Transport clean up on termination
2010/12/13 13:35:28.460 Pool:0xb58b2b70 SIP REGISTER transaction id=z9hG4bKd453f950-cf04-e011-8029-000c2984a855 completed.
2010/12/13 13:35:28.460 Pool:0xb58b2b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 13:35:28.461 Pool:0xb58b2b70 SIP Expiry time for REGISTER set to 30 seconds.
2010/12/13 13:35:28.725 ttyT38a(e)...0xb5afbb70 --> ATH0
2010/12/13 13:35:28.725 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:35:28.731 ttyT38a(i)...0xb5a79b70 --> read ERROR -1 Input/output error
2010/12/13 13:35:28.731 ttyT38a(i)...0xb5a79b70 --> Stopped
2010/12/13 13:35:28.731 ttyT38a(o)...0xb5abab70 <-- Stopped
2010/12/13 13:35:28.742 ttyT38a(e)...0xb5afbb70 <-> Stopped
2010/12/13 13:35:28.753 ttyT38a(b)...0xb5d0fb70 PseudoModemPty::OpenPty removed link /dev/ttyT38a -> /dev/pts/1
2010/12/13 13:35:28.753 ttyT38a(b)...0xb5d0fb70 PseudoModemPty::OpenPty read ERROR -1 Input/output error
2010/12/13 13:35:28.753 ttyT38a(b)...0xb5d0fb70 PseudoModemPty::OpenPty added link /dev/ttyT38a -> /dev/pts/1
2010/12/13 13:35:28.757 ttyT38a(e)...0xb5afbb70 RenameCurrentThread old ThreadName=ModemEngine:0xb5afbb70
2010/12/13 13:35:28.757 ttyT38a(e)...0xb5afbb70 <-> Started
2010/12/13 13:35:28.757 ttyT38a(i)...0xb5abab70 RenameCurrentThread old ThreadName=InPty:0xb5abab70
2010/12/13 13:35:28.757 ttyT38a(i)...0xb5abab70 --> Started
2010/12/13 13:35:28.757 ttyT38a(o)...0xb5a79b70 RenameCurrentThread old ThreadName=OutPty:0xb5a79b70
2010/12/13 13:35:28.757 ttyT38a(o)...0xb5a79b70 <-- Started
2010/12/13 13:35:32.725 Housekeeper:0xb5c0bb70 SIP Set state Terminated_Success for BYE transaction id=z9hG4bK1c9b8850-cf04-e011-8029-000c2984a855
2010/12/13 13:35:33.383 Opal Garbage:0xb5d50b70 Opal Transport clean up on termination
2010/12/13 13:35:33.383 Opal Garbage:0xb5d50b70 Opal Transport clean up on termination
2010/12/13 13:35:33.384 Opal Garbage:0xb5d50b70 RTP Closing session 1
2010/12/13 13:35:33.384 Opal Garbage:0xb5d50b70 RTP Deleting session 1
2010/12/13 13:35:33.384 Opal Garbage:0xb5d50b70 RTP_UDP Session 1, Shutting down write.
2010/12/13 13:35:33.384 Opal Garbage:0xb5d50b70 RTP Session 1, final statistics:
packetsSent = 2836
octetsSent = 453760
averageSendTime = 20
maximumSendTime = 21
minimumSendTime = 19
packetsReceived = 1289
octetsReceived = 204173
packetsLost = 0
packetsTooLate = 0
packetOverruns = 0
packetsOutOfOrder = 1
averageReceiveTime= 19
maximumReceiveTime= 26
minimumReceiveTime= 10
averageJitter = 1
maximumJitter = 421
2010/12/13 13:35:33.384 Opal Garbage:0xb5d50b70 OpalCon Connection Call[r84331c671]-EP<sip>[fee63827-cf04-e011-8029-000c2984a855] destroyed.
2010/12/13 13:35:33.461 Housekeeper:0xb5c0bb70 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bKd453f950-cf04-e011-8029-000c2984a855
2010/12/13 13:35:34.385 Opal Garbage:0xb5d50b70 Call Call[r84331c671] destroyed.
2010/12/13 13:35:43.308 ttyT38a(e)...0xb5afbb70 --> ATZ
2010/12/13 13:35:43.309 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:35:43.462 Housekeeper:0xb5c0bb70 SIP Starting REGISTER for binding refresh
2010/12/13 13:35:43.463 Housekeeper:0xb5c0bb70 SIP Transaction remote address is udp$sip.nsw.iinet.net.au:5060
2010/12/13 13:35:43.463 Housekeeper:0xb5c0bb70 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 13:35:43.463 Housekeeper:0xb5c0bb70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 13:35:43.485 Opal Liste...0xb5bcab70 OpalUDP Binding to interface: 10.11.12.22:5060
2010/12/13 13:35:43.485 Opal Liste...0xb5bcab70 SIP No Content-Length present from udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>, reading till end of datagram/stream.
2010/12/13 13:35:43.486 Opal Liste...0xb5bcab70 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 13:35:43.486 Opal Liste...0xb5bcab70 Opal Transport clean up on termination
2010/12/13 13:35:43.486 Pool:0xb58b2b70 SIP REGISTER transaction id=z9hG4bK1c13ee59-cf04-e011-8029-000c2984a855 completed.
2010/12/13 13:35:43.486 Pool:0xb58b2b70 OpalUDP Setting interface to 10.11.12.22%eth0
2010/12/13 13:35:43.486 Pool:0xb58b2b70 SIP Expiry time for REGISTER set to 30 seconds.
2010/12/13 13:35:46.317 ttyT38a(e)...0xb5afbb70 --> ATS0=0
2010/12/13 13:35:46.317 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:35:46.325 ttyT38a(e)...0xb5afbb70 --> ATE0
2010/12/13 13:35:46.325 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:35:46.333 ttyT38a(e)...0xb5afbb70 --> ATV1
2010/12/13 13:35:46.333 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:35:46.341 ttyT38a(e)...0xb5afbb70 --> ATQ0
2010/12/13 13:35:46.341 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:35:46.349 ttyT38a(e)...0xb5afbb70 --> ATS8=2
2010/12/13 13:35:46.349 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:35:46.357 ttyT38a(e)...0xb5afbb70 --> ATS7=60
2010/12/13 13:35:46.357 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:35:46.365 ttyT38a(e)...0xb5afbb70 --> AT+FCLASS=?
2010/12/13 13:35:46.365 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 31 2c 38 0d 0a 4f 4b 0d 0a ..1,8..OK.. }
2010/12/13 13:35:46.373 ttyT38a(e)...0xb5afbb70 --> AT+FCLASS=1
2010/12/13 13:35:46.373 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:35:46.381 ttyT38a(e)...0xb5afbb70 --> ATI3
2010/12/13 13:35:46.381 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 56 79 61 63 68 65 73 6c 61 76 20 46 72 6f ..Vyacheslav Fro
6c 6f 76 0d 0a 4f 4b 0d 0a lov..OK.. }
2010/12/13 13:35:46.389 ttyT38a(e)...0xb5afbb70 --> ATI0
2010/12/13 13:35:46.389 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 54 33 38 46 41 58 0d 0a 4f 4b 0d 0a ..T38FAX..OK.. }
2010/12/13 13:35:46.398 ttyT38a(e)...0xb5afbb70 --> AT+FREV?
2010/12/13 13:35:46.398 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 31 2e 32 2e 30 0d 0a 4f 4b 0d 0a ..1.2.0..OK.. }
2010/12/13 13:35:46.405 ttyT38a(e)...0xb5afbb70 --> AT+FTM=?
2010/12/13 13:35:46.405 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 32 34 2c 34 38 2c 37 32 2c 37 33 2c 37 34 ..24,48,72,73,74
2c 39 36 2c 39 37 2c 39 38 2c 31 32 31 2c 31 32 ,96,97,98,121,12
32 2c 31 34 35 2c 31 34 36 0d 0a 4f 4b 0d 0a 2,145,146..OK.. }
2010/12/13 13:35:46.413 ttyT38a(e)...0xb5afbb70 --> AT+FRM=?
2010/12/13 13:35:46.413 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 32 34 2c 34 38 2c 37 32 2c 37 33 2c 37 34 ..24,48,72,73,74
2c 39 36 2c 39 37 2c 39 38 2c 31 32 31 2c 31 32 ,96,97,98,121,12
32 2c 31 34 35 2c 31 34 36 0d 0a 4f 4b 0d 0a 2,145,146..OK.. }
2010/12/13 13:35:46.421 ttyT38a(e)...0xb5afbb70 --> ATM0
2010/12/13 13:35:46.421 ttyT38a(e)...0xb5afbb70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/13 13:35:48.487 Housekeeper:0xb5c0bb70 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bK1c13ee59-cf04-e011-8029-000c2984a855
- References:
- Re: [hylafax-users] t38modem & hylafax issue
- From: John Park
- Re: [hylafax-users] t38modem & hylafax issue
- From: John Park
- Re: [hylafax-users] t38modem & hylafax issue
- From: John Park
- Re: [hylafax-users] t38modem & hylafax issue
- From: John Park
- Re: [hylafax-users] t38modem & hylafax issue
- Prev by Date: Re: [hylafax-users] t38modem & hylafax issue
- Next by Date: [hylafax-users] t38modem issue with NO CARRIER
- Previous by thread: Re: [hylafax-users] t38modem & hylafax issue
- Next by thread: [hylafax-users] t38modem issue with NO CARRIER
- Index(es):
Project hosted by iFAX Solutions