HylaFAX The world's most advanced open source fax server |
Still seems that you are having a registration issue. You see in the logs that the sip goes from unavailable to subscribing but then returns back to unavailable.
The modem then connects to your t38 that is unavailable.
Thank You
Robert Branham
President
Branham IT Solutions LLC
314-565-7582
Website www.bits-stl.com
Linkedin www.linkedin.com/in/bobbranham
Yelp www.yelp.com/biz/branham-it-solutions-llc-high-ridge
From: John Park [mailto:john@xxxxxxxxxxxxxxx]
Sent: Sunday, December 12, 2010 12:46 AM
To: Robert Branham
Cc: hylafax-users@xxxxxxxxxxx
Subject: Re: [hylafax-users] t38modem & hylafax issue
Hi Robert,
Thanks for your response. I think the unauthorized error may have ocurred because I was already registering on this sip at my VOIP router. I unregistered the connection there and retried but still no success.
Now I'm getting "NO DIALTONE"...
This error looks interesting from the logs below....
2010/12/12 17:36:20.226 T38Modem SIP No compatible listener to create transport for udp$sip.nsw.iinet.net.au:5060
Any thoughts on this one Robert (or anyone else?)
Most recent log:
2010/12/12 17:36:20.208 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/12 17:36:20.208
2010/12/12 17:36:20.208 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/12 17:36:20.208 T38Modem Options: -tttt -o /var/log/t38modem.log --sip-proxy <my_username>:<my_password>@sip.nsw.iinet.net.au --sip-register <my_username>@sip.nsw.iinet.net.au,<my_password> --no-h323 --ptty +/dev/ttyT38-1,+/dev/ttyT38-2,+/dev/ttyT38-3 --route modem:.*=sip:<dn>@sip.nsw.iinet.net.au -u <my_username>
2010/12/12 17:36:20.209 T38Modem Config:
2010/12/12 17:36:20.209 T38Modem --no-h323=True
2010/12/12 17:36:20.209 T38Modem --sip-proxy=<my_username>:<my_password>@sip.nsw.iinet.net.au
2010/12/12 17:36:20.209 T38Modem --sip-register=<my_username>@sip.nsw.iinet.net.au,<my_password>
2010/12/12 17:36:20.209 T38Modem --sip-no-listen=True
2010/12/12 17:36:20.209 T38Modem --ptty=+/dev/ttyT38-1,+/dev/ttyT38-2,+/dev/ttyT38-3
2010/12/12 17:36:20.209 T38Modem --route=modem:.*=sip:<dn>@sip.nsw.iinet.net.au
2010/12/12 17:36:20.209 T38Modem --username=<my_username>
2010/12/12 17:36:20.209 T38Modem --trace=True
2010/12/12 17:36:20.209 T38Modem --output=/var/log/t38modem.log
2010/12/12 17:36:20.209 T38Modem Detecting V4L2 devices
2010/12/12 17:36:20.209 T38Modem Unable to detect v4l2 directory
2010/12/12 17:36:20.223 T38Modem PWLib File handle high water mark set: 9 Thread unblock pipe
2010/12/12 17:36:20.223 T38Modem PTLib Thread high water mark set: 3
2010/12/12 17:36:20.223 T38Modem OpalMan Created manager.
2010/12/12 17:36:20.223 T38Modem OpalMan Attached endpoint with prefix modem
2010/12/12 17:36:20.223 T38Modem OpalEP Created endpoint: modem
2010/12/12 17:36:20.223 T38Modem ModemEndPoint::ModemEndPoint
2010/12/12 17:36:20.223 T38Modem PWLib File handle high water mark set: 11 Thread unblock pipe
2010/12/12 17:36:20.223 T38Modem PseudoModemList::Append ttyT38-1 (0) OK
2010/12/12 17:36:20.223 T38Modem PTLib Thread high water mark set: 4
2010/12/12 17:36:20.223 T38Modem PWLib File handle high water mark set: 13 Thread unblock pipe
2010/12/12 17:36:20.223 T38Modem PseudoModemList::Append ttyT38-2 (1) OK
2010/12/12 17:36:20.224 T38Modem PTLib Thread high water mark set: 5
2010/12/12 17:36:20.224 T38Modem PWLib File handle high water mark set: 15 Thread unblock pipe
2010/12/12 17:36:20.224 T38Modem PseudoModemList::Append ttyT38-3 (2) OK
2010/12/12 17:36:20.224 T38Modem PTLib Thread high water mark set: 6
2010/12/12 17:36:20.224 T38Modem OpalMan Attached endpoint with prefix sip
2010/12/12 17:36:20.224 T38Modem OpalEP Created endpoint: sip
2010/12/12 17:36:20.224 T38Modem PWLib File handle high water mark set: 16 PUDPSocket
2010/12/12 17:36:20.224 T38Modem IfaceMon Initial interface list:
127.0.0.1 [00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:01] <00-00-00-00-00-00> (lo)
10.11.12.22 [fe:80:00:00:00:00:00:00:02:0c:29:ff:fe:84:a8:55] <00-0C-29-84-A8-55> (eth0)
2010/12/12 17:36:20.225 T38Modem PWLib File handle high water mark set: 17 Thread unblock pipe
2010/12/12 17:36:20.225 T38Modem PTLib Thread high water mark set: 7
2010/12/12 17:36:20.225 T38Modem PWLib File handle high water mark set: 19 Thread unblock pipe
2010/12/12 17:36:20.225 T38Modem PTLib Thread high water mark set: 8
2010/12/12 17:36:20.225 T38Modem OpalMan Attached endpoint with prefix sips
2010/12/12 17:36:20.225 T38Modem SIP Created endpoint.
2010/12/12 17:36:20.225 T38Modem SIP Start REGISTER
aor=<my_username>@sip.nsw.iinet.net.au
registrar=
contact=
authID=
realm=
expire=300
restore=30
minRetry=596:31:23.647
maxRetry=596:31:23.647
2010/12/12 17:36:20.225 T38Modem PWLib File handle high water mark set: 20 PUDPSocket
2010/12/12 17:36:20.226 T38Modem SIP Changing REGISTER handler from Unavailable to Subscribing, target=sip:<my_username>@sip.nsw.iinet.net.au, id=4c7d7fcc-2704-e011-8c59-000c2984a855@xxxxxxxxxxxxxxxxxxx
2010/12/12 17:36:20.226 T38Modem SIP No compatible listener to create transport for udp$sip.nsw.iinet.net.au:5060
2010/12/12 17:36:20.226 T38Modem SIP Changing REGISTER handler from Subscribing to Unavailable, target=sip:<my_username>@sip.nsw.iinet.net.au, id=4c7d7fcc-2704-e011-8c59-000c2984a855@xxxxxxxxxxxxxxxxxxx
2010/12/12 17:36:20.226 T38Modem SIP Retrying REGISTER in 30 seconds.
2010/12/12 17:36:20.226 T38Modem OpalMan Added route "modem:.*=sip:<dn>@sip.nsw.iinet.net.au"
2010/12/12 17:36:20.226 T38Modem PTLib Destroyed PXConfig 0x85f8a60
2010/12/12 17:36:20.226 ttyT38-1(b...0xb5dddb70 RenameCurrentThread old ThreadName=PseudoModemPty:0xb5dddb70
2010/12/12 17:36:20.226 ttyT38-1(b...0xb5dddb70 Started for /dev/ttyT38-1 (accepts all)
2010/12/12 17:36:20.226 ttyT38-1(b...0xb5dddb70 ModemEndPoint::OnMyCallback command=addmodem extra=10
2010/12/12 17:36:20.226 ttyT38-1(b...0xb5dddb70 PseudoModemQ::Enqueue ttyT38-1
2010/12/12 17:36:20.226 ttyT38-1(b...0xb5dddb70 ModemEndPoint::OnMyCallback request={
command=addmodem
response=confirm
modemtoken=ttyT38-1
}
2010/12/12 17:36:20.226 ttyT38-1(b...0xb5dddb70 PseudoModemPty::OpenPty removed link /dev/ttyT38-1 -> /dev/pts/1
2010/12/12 17:36:20.226 ttyT38-1(b...0xb5dddb70 PseudoModemPty::OpenPty added link /dev/ttyT38-1 -> /dev/pts/1
2010/12/12 17:36:20.226 ttyT38-1(b...0xb5dddb70 PWLib File handle high water mark set: 22 Thread unblock pipe
2010/12/12 17:36:20.226 ttyT38-1(b...0xb5dddb70 PWLib File handle high water mark set: 24 Thread unblock pipe
2010/12/12 17:36:20.226 ttyT38-1(b...0xb5dddb70 PWLib File handle high water mark set: 26 Thread unblock pipe
2010/12/12 17:36:20.226 ttyT38-1(b...0xb5dddb70 PTLib Thread high water mark set: 9
2010/12/12 17:36:20.226 ttyT38-1(b...0xb5dddb70 PTLib Thread high water mark set: 10
2010/12/12 17:36:20.226 ttyT38-1(b...0xb5dddb70 PTLib Thread high water mark set: 11
2010/12/12 17:36:20.226 ttyT38-2(b...0xb5d9cb70 RenameCurrentThread old ThreadName=PseudoModemPty:0xb5d9cb70
2010/12/12 17:36:20.226 ttyT38-2(b...0xb5d9cb70 Started for /dev/ttyT38-2 (accepts all)
2010/12/12 17:36:20.226 ttyT38-2(b...0xb5d9cb70 ModemEndPoint::OnMyCallback command=addmodem extra=10
2010/12/12 17:36:20.226 ttyT38-2(b...0xb5d9cb70 PseudoModemQ::Enqueue ttyT38-2
2010/12/12 17:36:20.226 ttyT38-2(b...0xb5d9cb70 ModemEndPoint::OnMyCallback request={
command=addmodem
response=confirm
modemtoken=ttyT38-2
}
2010/12/12 17:36:20.227 ttyT38-2(b...0xb5d9cb70 PseudoModemPty::OpenPty removed link /dev/ttyT38-2 -> /dev/pts/2
2010/12/12 17:36:20.227 ttyT38-2(b...0xb5d9cb70 PseudoModemPty::OpenPty added link /dev/ttyT38-2 -> /dev/pts/2
2010/12/12 17:36:20.227 ttyT38-2(b...0xb5d9cb70 PWLib File handle high water mark set: 29 Thread unblock pipe
2010/12/12 17:36:20.227 ttyT38-2(b...0xb5d9cb70 PWLib File handle high water mark set: 31 Thread unblock pipe
2010/12/12 17:36:20.227 ttyT38-2(b...0xb5d9cb70 PWLib File handle high water mark set: 33 Thread unblock pipe
2010/12/12 17:36:20.227 ttyT38-2(b...0xb5d9cb70 PTLib Thread high water mark set: 12
2010/12/12 17:36:20.227 ttyT38-2(b...0xb5d9cb70 PTLib Thread high water mark set: 13
2010/12/12 17:36:20.227 ttyT38-2(b...0xb5d9cb70 PTLib Thread high water mark set: 14
2010/12/12 17:36:20.227 ttyT38-3(b...0xb5d5bb70 RenameCurrentThread old ThreadName=PseudoModemPty:0xb5d5bb70
2010/12/12 17:36:20.227 ttyT38-3(b...0xb5d5bb70 Started for /dev/ttyT38-3 (accepts all)
2010/12/12 17:36:20.227 ttyT38-3(b...0xb5d5bb70 ModemEndPoint::OnMyCallback command=addmodem extra=10
2010/12/12 17:36:20.227 ttyT38-3(b...0xb5d5bb70 PseudoModemQ::Enqueue ttyT38-3
2010/12/12 17:36:20.227 ttyT38-3(b...0xb5d5bb70 ModemEndPoint::OnMyCallback request={
command=addmodem
response=confirm
modemtoken=ttyT38-3
}
2010/12/12 17:36:20.227 ttyT38-3(b...0xb5d5bb70 PseudoModemPty::OpenPty removed link /dev/ttyT38-3 -> /dev/pts/3
2010/12/12 17:36:20.227 ttyT38-3(b...0xb5d5bb70 PseudoModemPty::OpenPty added link /dev/ttyT38-3 -> /dev/pts/3
2010/12/12 17:36:20.227 ttyT38-3(b...0xb5d5bb70 PWLib File handle high water mark set: 36 Thread unblock pipe
2010/12/12 17:36:20.227 ttyT38-3(b...0xb5d5bb70 PWLib File handle high water mark set: 38 Thread unblock pipe
2010/12/12 17:36:20.227 ttyT38-3(b...0xb5d5bb70 PWLib File handle high water mark set: 40 Thread unblock pipe
2010/12/12 17:36:20.227 ttyT38-3(b...0xb5d5bb70 PTLib Thread high water mark set: 15
2010/12/12 17:36:20.227 ttyT38-3(b...0xb5d5bb70 PTLib Thread high water mark set: 16
2010/12/12 17:36:20.227 ttyT38-3(b...0xb5d5bb70 PTLib Thread high water mark set: 17
2010/12/12 17:36:20.227 Network In...0xb5d1ab70 IfaceMon Started interface monitor thread.
2010/12/12 17:36:20.227 Network In...0xb5d1ab70 PWLib File handle high water mark set: 41 PUDPSocket
2010/12/12 17:36:20.228 ttyT38-1(e...0xb5c98b70 RenameCurrentThread old ThreadName=ModemEngine:0xb5c98b70
2010/12/12 17:36:20.228 ttyT38-1(e...0xb5c98b70 <-> Started
2010/12/12 17:36:20.228 ttyT38-1(i...0xb5c57b70 RenameCurrentThread old ThreadName=InPty:0xb5c57b70
2010/12/12 17:36:20.228 ttyT38-1(i...0xb5c57b70 --> Started
2010/12/12 17:36:20.228 ttyT38-1(o...0xb5c16b70 RenameCurrentThread old ThreadName=OutPty:0xb5c16b70
2010/12/12 17:36:20.228 ttyT38-1(o...0xb5c16b70 <-- Started
2010/12/12 17:36:20.228 ttyT38-2(e...0xb5bd5b70 RenameCurrentThread old ThreadName=ModemEngine:0xb5bd5b70
2010/12/12 17:36:20.228 ttyT38-2(e...0xb5bd5b70 <-> Started
2010/12/12 17:36:20.228 ttyT38-2(i...0xb5b94b70 RenameCurrentThread old ThreadName=InPty:0xb5b94b70
2010/12/12 17:36:20.228 ttyT38-2(i...0xb5b94b70 --> Started
2010/12/12 17:36:20.228 ttyT38-2(o...0xb5b53b70 RenameCurrentThread old ThreadName=OutPty:0xb5b53b70
2010/12/12 17:36:20.228 ttyT38-2(o...0xb5b53b70 <-- Started
2010/12/12 17:36:20.228 ttyT38-3(e...0xb5b12b70 RenameCurrentThread old ThreadName=ModemEngine:0xb5b12b70
2010/12/12 17:36:20.228 ttyT38-3(e...0xb5b12b70 <-> Started
2010/12/12 17:36:20.228 ttyT38-3(i...0xb5ad1b70 RenameCurrentThread old ThreadName=InPty:0xb5ad1b70
2010/12/12 17:36:20.228 ttyT38-3(i...0xb5ad1b70 --> Started
2010/12/12 17:36:20.228 ttyT38-3(o...0xb5a90b70 RenameCurrentThread old ThreadName=OutPty:0xb5a90b70
2010/12/12 17:36:20.228 ttyT38-3(o...0xb5a90b70 <-- Started
2010/12/12 17:36:50.227 Housekeeper:0xb5cd9b70 SIP Starting REGISTER for offline retry
2010/12/12 17:36:50.228 Housekeeper:0xb5cd9b70 SIP Changing REGISTER handler from Unavailable to Restoring, target=sip:<my_username>@sip.nsw.iinet.net.au, id=4c7d7fcc-2704-e011-8c59-000c2984a855@xxxxxxxxxxxxxxxxxxx
2010/12/12 17:36:50.228 Housekeeper:0xb5cd9b70 SIP No compatible listener to create transport for udp$sip.nsw.iinet.net.au:5060
2010/12/12 17:36:50.228 Housekeeper:0xb5cd9b70 SIP Changing REGISTER handler from Restoring to Unavailable, target=sip:<my_username>@sip.nsw.iinet.net.au, id=4c7d7fcc-2704-e011-8c59-000c2984a855@xxxxxxxxxxxxxxxxxxx
2010/12/12 17:36:50.228 Housekeeper:0xb5cd9b70 SIP Retrying REGISTER in 30 seconds.
2010/12/12 17:36:52.717 ttyT38-1(e...0xb5c98b70 --> ATZ
2010/12/12 17:36:52.717 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/12 17:36:55.725 ttyT38-1(e...0xb5c98b70 --> ATS0=0
2010/12/12 17:36:55.725 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/12 17:36:55.733 ttyT38-1(e...0xb5c98b70 --> ATE0
2010/12/12 17:36:55.733 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/12 17:36:55.741 ttyT38-1(e...0xb5c98b70 --> ATV1
2010/12/12 17:36:55.741 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/12 17:36:55.749 ttyT38-1(e...0xb5c98b70 --> ATQ0
2010/12/12 17:36:55.749 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/12 17:36:55.757 ttyT38-1(e...0xb5c98b70 --> ATS8=2
2010/12/12 17:36:55.757 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/12 17:36:55.765 ttyT38-1(e...0xb5c98b70 --> ATS7=60
2010/12/12 17:36:55.765 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/12 17:36:55.773 ttyT38-1(e...0xb5c98b70 --> AT+FCLASS=?
2010/12/12 17:36:55.773 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 31 2c 38 0d 0a 4f 4b 0d 0a ..1,8..OK.. }
2010/12/12 17:36:55.781 ttyT38-1(e...0xb5c98b70 --> AT+FCLASS=1
2010/12/12 17:36:55.781 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/12 17:36:55.789 ttyT38-1(e...0xb5c98b70 --> ATI3
2010/12/12 17:36:55.789 ttyT38-1(e...0xb5c98b70 <-- {
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/12 17:36:55.797 ttyT38-1(e...0xb5c98b70 --> ATI0
2010/12/12 17:36:55.797 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 54 33 38 46 41 58 0d 0a 4f 4b 0d 0a ..T38FAX..OK.. }
2010/12/12 17:36:55.805 ttyT38-1(e...0xb5c98b70 --> AT+FREV?
2010/12/12 17:36:55.805 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 31 2e 32 2e 30 0d 0a 4f 4b 0d 0a ..1.2.0..OK.. }
2010/12/12 17:36:55.813 ttyT38-1(e...0xb5c98b70 --> AT+FTM=?
2010/12/12 17:36:55.813 ttyT38-1(e...0xb5c98b70 <-- {
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/12 17:36:55.821 ttyT38-1(e...0xb5c98b70 --> AT+FRM=?
2010/12/12 17:36:55.821 ttyT38-1(e...0xb5c98b70 <-- {
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/12 17:36:55.829 ttyT38-1(e...0xb5c98b70 --> ATM0
2010/12/12 17:36:55.829 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/12 17:37:20.239 Housekeeper:0xb5cd9b70 SIP Starting REGISTER for offline retry
2010/12/12 17:37:20.239 Housekeeper:0xb5cd9b70 SIP Changing REGISTER handler from Unavailable to Restoring, target=sip:<my_username>@sip.nsw.iinet.net.au, id=4c7d7fcc-2704-e011-8c59-000c2984a855@xxxxxxxxxxxxxxxxxxx
2010/12/12 17:37:20.239 Housekeeper:0xb5cd9b70 SIP No compatible listener to create transport for udp$sip.nsw.iinet.net.au:5060
2010/12/12 17:37:20.239 Housekeeper:0xb5cd9b70 SIP Changing REGISTER handler from Restoring to Unavailable, target=sip:<my_username>@sip.nsw.iinet.net.au, id=4c7d7fcc-2704-e011-8c59-000c2984a855@xxxxxxxxxxxxxxxxxxx
2010/12/12 17:37:20.239 Housekeeper:0xb5cd9b70 SIP Retrying REGISTER in 30 seconds.
2010/12/12 17:37:50.239 Housekeeper:0xb5cd9b70 SIP Starting REGISTER for offline retry
2010/12/12 17:37:50.240 Housekeeper:0xb5cd9b70 SIP Changing REGISTER handler from Unavailable to Restoring, target=sip:<my_username>@sip.nsw.iinet.net.au, id=4c7d7fcc-2704-e011-8c59-000c2984a855@xxxxxxxxxxxxxxxxxxx
2010/12/12 17:37:50.240 Housekeeper:0xb5cd9b70 SIP No compatible listener to create transport for udp$sip.nsw.iinet.net.au:5060
2010/12/12 17:37:50.240 Housekeeper:0xb5cd9b70 SIP Changing REGISTER handler from Restoring to Unavailable, target=sip:<my_username>@sip.nsw.iinet.net.au, id=4c7d7fcc-2704-e011-8c59-000c2984a855@xxxxxxxxxxxxxxxxxxx
2010/12/12 17:37:50.240 Housekeeper:0xb5cd9b70 SIP Retrying REGISTER in 30 seconds.
2010/12/12 17:38:20.240 Housekeeper:0xb5cd9b70 SIP Starting REGISTER for offline retry
2010/12/12 17:38:20.240 Housekeeper:0xb5cd9b70 SIP Changing REGISTER handler from Unavailable to Restoring, target=sip:<my_username>@sip.nsw.iinet.net.au, id=4c7d7fcc-2704-e011-8c59-000c2984a855@xxxxxxxxxxxxxxxxxxx
2010/12/12 17:38:20.240 Housekeeper:0xb5cd9b70 SIP No compatible listener to create transport for udp$sip.nsw.iinet.net.au:5060
2010/12/12 17:38:20.240 Housekeeper:0xb5cd9b70 SIP Changing REGISTER handler from Restoring to Unavailable, target=sip:<my_username>@sip.nsw.iinet.net.au, id=4c7d7fcc-2704-e011-8c59-000c2984a855@xxxxxxxxxxxxxxxxxxx
2010/12/12 17:38:20.240 Housekeeper:0xb5cd9b70 SIP Retrying REGISTER in 30 seconds.
2010/12/12 17:38:29.206 ttyT38-1(i...0xb5c57b70 --> read ERROR -1 Input/output error
2010/12/12 17:38:29.206 ttyT38-1(i...0xb5c57b70 --> Stopped
2010/12/12 17:38:29.207 ttyT38-1(o...0xb5c16b70 <-- Stopped
2010/12/12 17:38:29.217 ttyT38-1(e...0xb5c98b70 <-> Stopped
2010/12/12 17:38:29.228 ttyT38-1(b...0xb5dddb70 PseudoModemPty::OpenPty removed link /dev/ttyT38-1 -> /dev/pts/1
2010/12/12 17:38:29.228 ttyT38-1(b...0xb5dddb70 PseudoModemPty::OpenPty read ERROR -1 Input/output error
2010/12/12 17:38:29.229 ttyT38-1(b...0xb5dddb70 PseudoModemPty::OpenPty added link /dev/ttyT38-1 -> /dev/pts/1
2010/12/12 17:38:29.229 ttyT38-1(b...0xb5dddb70 PWLib File handle low water mark set: 22 Thread unblock pipe
2010/12/12 17:38:29.234 ttyT38-1(e...0xb5c98b70 RenameCurrentThread old ThreadName=ModemEngine:0xb5c98b70
2010/12/12 17:38:29.234 ttyT38-1(e...0xb5c98b70 <-> Started
2010/12/12 17:38:29.234 ttyT38-1(i...0xb5c16b70 RenameCurrentThread old ThreadName=InPty:0xb5c16b70
2010/12/12 17:38:29.234 ttyT38-1(i...0xb5c16b70 --> Started
2010/12/12 17:38:29.234 ttyT38-1(o...0xb5c57b70 RenameCurrentThread old ThreadName=OutPty:0xb5c57b70
2010/12/12 17:38:29.234 ttyT38-1(o...0xb5c57b70 <-- Started
2010/12/12 17:38:34.181 ttyT38-1(e...0xb5c98b70 --> ATZ
2010/12/12 17:38:34.181 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/12 17:38:37.293 ttyT38-1(e...0xb5c98b70 --> ATS0=0
2010/12/12 17:38:37.293 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/12 17:38:37.401 ttyT38-1(e...0xb5c98b70 --> ATE0
2010/12/12 17:38:37.401 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/12 17:38:37.509 ttyT38-1(e...0xb5c98b70 --> ATV1
2010/12/12 17:38:37.509 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/12 17:38:37.617 ttyT38-1(e...0xb5c98b70 --> ATQ0
2010/12/12 17:38:37.617 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/12 17:38:37.725 ttyT38-1(e...0xb5c98b70 --> ATS8=2
2010/12/12 17:38:37.725 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/12 17:38:37.833 ttyT38-1(e...0xb5c98b70 --> ATS7=60
2010/12/12 17:38:37.833 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/12 17:38:37.941 ttyT38-1(e...0xb5c98b70 --> AT+FCLASS=?
2010/12/12 17:38:37.941 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 31 2c 38 0d 0a 4f 4b 0d 0a ..1,8..OK.. }
2010/12/12 17:38:38.049 ttyT38-1(e...0xb5c98b70 --> AT+FCLASS=1
2010/12/12 17:38:38.049 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/12 17:38:38.157 ttyT38-1(e...0xb5c98b70 --> ATI3
2010/12/12 17:38:38.157 ttyT38-1(e...0xb5c98b70 <-- {
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/12 17:38:38.265 ttyT38-1(e...0xb5c98b70 --> ATI0
2010/12/12 17:38:38.265 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 54 33 38 46 41 58 0d 0a 4f 4b 0d 0a ..T38FAX..OK.. }
2010/12/12 17:38:38.373 ttyT38-1(e...0xb5c98b70 --> AT+FREV?
2010/12/12 17:38:38.375 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 31 2e 32 2e 30 0d 0a 4f 4b 0d 0a ..1.2.0..OK.. }
2010/12/12 17:38:38.481 ttyT38-1(e...0xb5c98b70 --> AT+FTM=?
2010/12/12 17:38:38.481 ttyT38-1(e...0xb5c98b70 <-- {
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/12 17:38:38.589 ttyT38-1(e...0xb5c98b70 --> AT+FRM=?
2010/12/12 17:38:38.589 ttyT38-1(e...0xb5c98b70 <-- {
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/12 17:38:38.697 ttyT38-1(e...0xb5c98b70 --> ATM0
2010/12/12 17:38:38.697 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/12 17:38:38.805 ttyT38-1(e...0xb5c98b70 --> AT+FCLASS=1
2010/12/12 17:38:38.805 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/12 17:38:38.813 ttyT38-1(e...0xb5c98b70 --> ATDT0406136534
2010/12/12 17:38:38.813 ttyT38-1(e...0xb5c98b70 ModemEndPoint::OnMyCallback command=dial extra=3
2010/12/12 17:38:38.813 ttyT38-1(e...0xb5c98b70 PseudoModemQ::Dequeue ttyT38-1
2010/12/12 17:38:38.813 ttyT38-1(e...0xb5c98b70 MyManager::OnMyCallback SetUpCall(modem:, 0406136534@+/dev/ttyT38-1)
2010/12/12 17:38:38.813 ttyT38-1(e...0xb5c98b70 OpalMan Set up call from modem: to 0406136534@+/dev/ttyT38-1
2010/12/12 17:38:38.813 ttyT38-1(e...0xb5c98b70 Call Created Call[jc73cf4e01]
2010/12/12 17:38:38.813 ttyT38-1(e...0xb5c98b70 OpalMan Set up connection to "modem:"
2010/12/12 17:38:38.813 ttyT38-1(e...0xb5c98b70 ModemEndPoint::MakeConnection modem:
2010/12/12 17:38:38.813 ttyT38-1(e...0xb5c98b70 OpalCon Created connection Call[jc73cf4e01]-EP<modem>[modem:/jc73cf4e01/0]
2010/12/12 17:38:38.813 ttyT38-1(e...0xb5c98b70 ModemConnection::ModemConnection Call[jc73cf4e01]-EP<modem>[modem:/jc73cf4e01/0]
2010/12/12 17:38:38.813 ttyT38-1(e...0xb5c98b70 ModemConnection::SetUpConnection Call[jc73cf4e01]-EP<modem>[modem:/jc73cf4e01/0]
2010/12/12 17:38:38.813 ttyT38-1(e...0xb5c98b70 OpalCon SetPhase from UninitialisedPhase to SetUpPhase for Call[jc73cf4e01]-EP<modem>[modem:/jc73cf4e01/0]
2010/12/12 17:38:38.813 ttyT38-1(e...0xb5c98b70 OpalMan OnIncoming connection Call[jc73cf4e01]-EP<modem>[modem:/jc73cf4e01/0]
2010/12/12 17:38:38.813 ttyT38-1(e...0xb5c98b70 OpalCon Applying string options:
2010/12/12 17:38:38.813 ttyT38-1(e...0xb5c98b70 Call GetOtherPartyConnection Call[jc73cf4e01]-EP<modem>[modem:/jc73cf4e01/0]
2010/12/12 17:38:38.814 ttyT38-1(e...0xb5c98b70 OpalMan Searching for route "modem:<my_username> 0406136534@+/dev/ttyT38-1"
2010/12/12 17:38:38.814 ttyT38-1(e...0xb5c98b70 OpalMan Set up connection to "sip:0406136534@xxxxxxxxxxxxxxxxxxxx"
2010/12/12 17:38:38.814 ttyT38-1(e...0xb5c98b70 OpalMan Could not use endpoint for protocol "sip"
2010/12/12 17:38:38.814 ttyT38-1(e...0xb5c98b70 OpalMan Searching for route "modem:<my_username> sip:0406136534@xxxxxxxxxxxxxxxxxxxx"
2010/12/12 17:38:38.814 ttyT38-1(e...0xb5c98b70 OpalMan Searching for route "modem:<my_username> sip:0406136534@xxxxxxxxxxxxxxxxxxxx"
2010/12/12 17:38:38.814 ttyT38-1(e...0xb5c98b70 OpalMan Set up connection to "sip:0406136534@xxxxxxxxxxxxxxxxxxxx"
2010/12/12 17:38:38.814 ttyT38-1(e...0xb5c98b70 OpalMan Could not use endpoint for protocol "sip"
2010/12/12 17:38:38.814 ttyT38-1(e...0xb5c98b70 Call[jc73cf4e01] from modem:<my_username> to sip:0406136534@xxxxxxxxxxxxxxxxxxxx, no route!
2010/12/12 17:38:38.814 ttyT38-1(e...0xb5c98b70 OpalMan Searching for route "modem:<my_username> 0406136534@+/dev/ttyT38-1"
2010/12/12 17:38:38.814 ttyT38-1(e...0xb5c98b70 OpalMan Could not route a="modem:<my_username>", b="0406136534@+/dev/ttyT38-1, call=Call[jc73cf4e01]
2010/12/12 17:38:38.814 ttyT38-1(e...0xb5c98b70 Call[jc73cf4e01] from modem:<my_username> to 0406136534@+/dev/ttyT38-1, no route!
2010/12/12 17:38:38.814 ttyT38-1(e...0xb5c98b70 OpalCon SetPhase from SetUpPhase to ReleasingPhase for Call[jc73cf4e01]-EP<modem>[modem:/jc73cf4e01/0]
2010/12/12 17:38:38.814 ttyT38-1(e...0xb5c98b70 OpalCon Releasing Call[jc73cf4e01]-EP<modem>[modem:/jc73cf4e01/0]
2010/12/12 17:38:38.814 ttyT38-1(e...0xb5c98b70 OpalCon Call end reason for Call[jc73cf4e01]-EP<modem>[modem:/jc73cf4e01/0] set to EndedByCallerAbort
2010/12/12 17:38:38.814 ttyT38-1(e...0xb5c98b70 ModemConnection::OnReleased Call[jc73cf4e01]-EP<modem>[modem:/jc73cf4e01/0]
2010/12/12 17:38:38.814 ttyT38-1(e...0xb5c98b70 OpalCon OnReleased Call[jc73cf4e01]-EP<modem>[modem:/jc73cf4e01/0]
2010/12/12 17:38:38.814 ttyT38-1(e...0xb5c98b70 OpalEP OnReleased Call[jc73cf4e01]-EP<modem>[modem:/jc73cf4e01/0]
2010/12/12 17:38:38.814 ttyT38-1(e...0xb5c98b70 OpalMan OnReleased Call[jc73cf4e01]-EP<modem>[modem:/jc73cf4e01/0]
2010/12/12 17:38:38.814 ttyT38-1(e...0xb5c98b70 Call OnReleased Call[jc73cf4e01]-EP<modem>[modem:/jc73cf4e01/0]
2010/12/12 17:38:38.814 ttyT38-1(e...0xb5c98b70 Call[jc73cf4e01] cleared
2010/12/12 17:38:38.814 ttyT38-1(e...0xb5c98b70 OpalMan OnClearedCall Call[jc73cf4e01] from "" to "0406136534@+/dev/ttyT38-1"
2010/12/12 17:38:38.814 ttyT38-1(e...0xb5c98b70 OPAL Attempting to close recording on call jc73cf4e01 when not recording.
2010/12/12 17:38:38.814 ttyT38-1(e...0xb5c98b70 OpalCon Media streams closed.
2010/12/12 17:38:38.814 ttyT38-1(e...0xb5c98b70 Call Clearing Call[jc73cf4e01] reason=EndedByTemporaryFailure
2010/12/12 17:38:38.814 ttyT38-1(e...0xb5c98b70 PseudoModemQ::Enqueue ttyT38-1
2010/12/12 17:38:38.814 ttyT38-1(e...0xb5c98b70 ModemEndPoint::OnMyCallback request={
localpartyname=
command=dial
response=reject
number=0406136534
modemtoken=ttyT38-1
}
2010/12/12 17:38:38.814 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 4e 4f 20 44 49 41 4c 54 4f 4e 45 0d 0a ..NO DIALTONE.. }
2010/12/12 17:38:39.403 Opal Garbage:0xb5e1eb70 ModemConnection::~ModemConnection Call[jc73cf4e01]-EP<modem>[modem:/jc73cf4e01/0]
2010/12/12 17:38:39.403 Opal Garbage:0xb5e1eb70 OpalCon Connection Call[jc73cf4e01]-EP<modem>[modem:/jc73cf4e01/0] destroyed.
2010/12/12 17:38:39.820 ttyT38-1(e...0xb5c98b70 --> ATH0
2010/12/12 17:38:39.820 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/12 17:38:39.824 ttyT38-1(i...0xb5c16b70 --> read ERROR -1 Input/output error
2010/12/12 17:38:39.824 ttyT38-1(i...0xb5c16b70 --> Stopped
2010/12/12 17:38:39.824 ttyT38-1(o...0xb5c57b70 <-- Stopped
2010/12/12 17:38:39.835 ttyT38-1(e...0xb5c98b70 <-> Stopped
2010/12/12 17:38:39.846 ttyT38-1(b...0xb5dddb70 PseudoModemPty::OpenPty removed link /dev/ttyT38-1 -> /dev/pts/1
2010/12/12 17:38:39.846 ttyT38-1(b...0xb5dddb70 PseudoModemPty::OpenPty read ERROR -1 Input/output error
2010/12/12 17:38:39.846 ttyT38-1(b...0xb5dddb70 PseudoModemPty::OpenPty added link /dev/ttyT38-1 -> /dev/pts/1
2010/12/12 17:38:39.848 ttyT38-1(e...0xb5c98b70 RenameCurrentThread old ThreadName=ModemEngine:0xb5c98b70
2010/12/12 17:38:39.848 ttyT38-1(e...0xb5c98b70 <-> Started
2010/12/12 17:38:39.848 ttyT38-1(i...0xb5c57b70 RenameCurrentThread old ThreadName=InPty:0xb5c57b70
2010/12/12 17:38:39.849 ttyT38-1(i...0xb5c57b70 --> Started
2010/12/12 17:38:39.849 ttyT38-1(o...0xb5c16b70 RenameCurrentThread old ThreadName=OutPty:0xb5c16b70
2010/12/12 17:38:39.849 ttyT38-1(o...0xb5c16b70 <-- Started
2010/12/12 17:38:40.404 Opal Garbage:0xb5e1eb70 Call Call[jc73cf4e01] destroyed.
2010/12/12 17:38:50.240 Housekeeper:0xb5cd9b70 SIP Starting REGISTER for offline retry
2010/12/12 17:38:50.240 Housekeeper:0xb5cd9b70 SIP Changing REGISTER handler from Unavailable to Restoring, target=sip:<my_username>@sip.nsw.iinet.net.au, id=4c7d7fcc-2704-e011-8c59-000c2984a855@xxxxxxxxxxxxxxxxxxx
2010/12/12 17:38:50.240 Housekeeper:0xb5cd9b70 SIP No compatible listener to create transport for udp$sip.nsw.iinet.net.au:5060
2010/12/12 17:38:50.241 Housekeeper:0xb5cd9b70 SIP Changing REGISTER handler from Restoring to Unavailable, target=sip:<my_username>@sip.nsw.iinet.net.au, id=4c7d7fcc-2704-e011-8c59-000c2984a855@xxxxxxxxxxxxxxxxxxx
2010/12/12 17:38:50.241 Housekeeper:0xb5cd9b70 SIP Retrying REGISTER in 30 seconds.
2010/12/12 17:39:03.912 ttyT38-1(e...0xb5c98b70 --> ATZ
2010/12/12 17:39:03.912 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/12 17:39:06.920 ttyT38-1(e...0xb5c98b70 --> ATS0=0
2010/12/12 17:39:06.920 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/12 17:39:06.928 ttyT38-1(e...0xb5c98b70 --> ATE0
2010/12/12 17:39:06.928 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/12 17:39:06.936 ttyT38-1(e...0xb5c98b70 --> ATV1
2010/12/12 17:39:06.936 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/12 17:39:06.944 ttyT38-1(e...0xb5c98b70 --> ATQ0
2010/12/12 17:39:06.944 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/12 17:39:06.952 ttyT38-1(e...0xb5c98b70 --> ATS8=2
2010/12/12 17:39:06.952 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/12 17:39:06.960 ttyT38-1(e...0xb5c98b70 --> ATS7=60
2010/12/12 17:39:06.960 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/12 17:39:06.968 ttyT38-1(e...0xb5c98b70 --> AT+FCLASS=?
2010/12/12 17:39:06.968 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 31 2c 38 0d 0a 4f 4b 0d 0a ..1,8..OK.. }
2010/12/12 17:39:06.976 ttyT38-1(e...0xb5c98b70 --> AT+FCLASS=1
2010/12/12 17:39:06.976 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
2010/12/12 17:39:06.984 ttyT38-1(e...0xb5c98b70 --> ATI3
2010/12/12 17:39:06.984 ttyT38-1(e...0xb5c98b70 <-- {
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/12 17:39:06.992 ttyT38-1(e...0xb5c98b70 --> ATI0
2010/12/12 17:39:06.992 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 54 33 38 46 41 58 0d 0a 4f 4b 0d 0a ..T38FAX..OK.. }
2010/12/12 17:39:07.000 ttyT38-1(e...0xb5c98b70 --> AT+FREV?
2010/12/12 17:39:07.000 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 31 2e 32 2e 30 0d 0a 4f 4b 0d 0a ..1.2.0..OK.. }
2010/12/12 17:39:07.008 ttyT38-1(e...0xb5c98b70 --> AT+FTM=?
2010/12/12 17:39:07.008 ttyT38-1(e...0xb5c98b70 <-- {
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/12 17:39:07.016 ttyT38-1(e...0xb5c98b70 --> AT+FRM=?
2010/12/12 17:39:07.016 ttyT38-1(e...0xb5c98b70 <-- {
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/12 17:39:07.024 ttyT38-1(e...0xb5c98b70 --> ATM0
2010/12/12 17:39:07.024 ttyT38-1(e...0xb5c98b70 <-- {
0d 0a 4f 4b 0d 0a ..OK.. }
On 11 December 2010 08:29, Robert Branham <bbranham@xxxxxxxxxxxx> wrote:
> I think I would first look into why you get
>
> SIP/2.0 401 Unauthorized
>
> In the sip logs.
>
> Make sure you are following your SIP isp's requirements for connection
> and registry.
>
> Thank You
> Robert Branham
> President
> Branham IT Solutions LLC
> 314-565-7582
> Website www.bits-stl.com
> Linkedin www.linkedin.com/in/bobbranham
> Yelp www.yelp.com/biz/branham-it-solutions-llc-high-ridge
>
>
>
> -----Original Message-----
> From: John Park [mailto:john@xxxxxxxxxxxxxxx]
> Sent: Friday, December 10, 2010 5:52 AM
> To: hylafax-users@xxxxxxxxxxx
> Subject: [hylafax-users] t38modem & hylafax issue
>
> Hi everyone,
>
> I'm hoping someone might be able to help me. I've been at this for 48
> hours and I'm at my wits end!!! Any help would be greatly appreciate -
> in fact, I'm so desperate I'm happy to paypal $10 to someone who can
> help me fix this problem...
>
> I'm running ubuntu 10.10 and have installed hylafax and t38modem via:
>
> apt-get install hylafax-server t38modem
>
> Both hylafax and t38modem seem to be running...
>
> HylaFAX scheduler on hylafax.gyrex.local: Running
> Modem ttyT38-1 (+61 2 8097 7379): Running and idle
>
> I'm running t38modem with the following parameters:
>
> t38modem -tttt -o /var/log/t38modem.log --sip-proxy
> <my_username>:<my_password>@sip.nsw.iinet.net.au --sip-register
> <my_username>@sip.nsw.iinet.net.au,<my_password> --no-h323 --ptty
> +/dev/ttyT38-1 --route 'modem:.*=sip:<dn>@sip.nsw.iinet.net.au' -u
> <my_username>
>
> From t38modem's output file it appears as though t38modem is trying to
> create a SIP connection but it's not actually dialing out and is
> returning a BUSY signal to hylafax without actually attempting to
> dial.
>
> Here is the output from the t38modem log (sorry for the length!):
>
> Many thanks in advance and if you can help me fix this I'll honour my
> $10 paypal payment....
>
>
>
> 2010/12/10 22:24:31.591 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/10 22:24:31.591
> 2010/12/10 22:24:31.591 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/10 22:24:31.591 T38Modem Options: -tttt
> -o
> /var/log/t38modem.log --sip-proxy
> <my_username>:<my_password>@sip.nsw.iinet.net.au --sip-register
> <my_username>@sip.nsw.iinet.net.au,<my_password> --no-h323 --ptty
> +/dev/ttyT38-1 --route modem:.*=sip:<dn>@sip.nsw.iinet.net.au -u
> <my_username>
> 2010/12/10 22:24:31.591 T38Modem Config:
> 2010/12/10 22:24:31.591 T38Modem --no-h323=True
> 2010/12/10 22:24:31.591 T38Modem
> --sip-proxy=<my_username>:<my_password>@sip.nsw.iinet.net.au
> 2010/12/10 22:24:31.591 T38Modem
> --sip-register=<my_username>@sip.nsw.iinet.net.au,<my_password>
> 2010/12/10 22:24:31.591 T38Modem
> --ptty=+/dev/ttyT38-1,+/dev/ttyT38-2,+/dev/ttyT38-3
> 2010/12/10 22:24:31.591 T38Modem
> --route=modem:.*=sip:<dn>@iinetphone.iinet.net.au
> 2010/12/10 22:24:31.592 PXConfigWr...0xb5e15b70 PTLib Config
> file
> cache write back thread started.
> 2010/12/10 22:24:31.591 T38Modem
> --username=<my_username>
> 2010/12/10 22:24:31.592 T38Modem Detecting V4L2
> devices
> 2010/12/10 22:24:31.592 T38Modem Unable to detect
> v4l2 directory
> 2010/12/10 22:24:31.606 T38Modem PWLib File
> handle high
> water mark set: 9 Thread unblock pipe
> 2010/12/10 22:24:31.606 T38Modem PTLib Thread
> high
> water mark set: 3
> 2010/12/10 22:24:31.606 T38Modem OpalMan
> Created manager.
> 2010/12/10 22:24:31.606 T38Modem OpalMan
> Attached
> endpoint with prefix modem
> 2010/12/10 22:24:31.606 T38Modem OpalEP
> Created endpoint: modem
> 2010/12/10 22:24:31.606 T38Modem
> ModemEndPoint::ModemEndPoint
> 2010/12/10 22:24:31.606 T38Modem PWLib File
> handle high
> water mark set: 11 Thread unblock pipe
> 2010/12/10 22:24:31.606
> T38Modem PseudoModemList::Append ttyT38-1 (0) OK
> 2010/12/10 22:24:31.606 T38Modem PTLib Thread
> high
> water mark set: 4
> 2010/12/10 22:24:31.607 T38Modem OpalMan
> Attached
> endpoint with prefix sip
> 2010/12/10 22:24:31.607 T38Modem OpalEP
> Created endpoint: sip
> 2010/12/10 22:24:31.607 T38Modem PWLib File
> handle high
> water mark set: 12 PUDPSocket
> 2010/12/10 22:24:31.607 T38Modem IfaceMon
> Initial interface list:
> 127.0.0.1 [00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:01]
> <00-00-00-00-00-00> (lo)
> 10.11.12.22 [fe:80:00:00:00:00:00:00:02:0c:29:ff:fe:84:a8:55]
> <00-0C-29-84-A8-55> (eth0)
>
> 2010/12/10 22:24:31.607 T38Modem PWLib File
> handle high
> water mark set: 13 Thread unblock pipe
> 2010/12/10 22:24:31.607 T38Modem PTLib Thread
> high
> water mark set: 5
> 2010/12/10 22:24:31.607 T38Modem PWLib File
> handle high
> water mark set: 15 Thread unblock pipe
> 2010/12/10 22:24:31.607 T38Modem PTLib Thread
> high
> water mark set: 6
> 2010/12/10 22:24:31.607 T38Modem OpalMan
> Attached
> endpoint with prefix sips
> 2010/12/10 22:24:31.607 T38Modem SIP Created
> endpoint.
> 2010/12/10 22:24:31.608 T38Modem PWLib File
> handle high
> water mark set: 16 PUDPSocket
> 2010/12/10 22:24:31.608 T38Modem MonSock
> Created socket
> bundle for all interfaces.
> 2010/12/10 22:24:31.608 T38Modem PWLib File
> handle high
> water mark set: 17 PUDPSocket
> 2010/12/10 22:24:31.608 T38Modem MonSock
> Created
> bundled UDP socket 10.11.12.22:5060
> 2010/12/10 22:24:31.608 T38Modem PWLib File
> handle high
> water mark set: 19 Thread unblock pipe
> 2010/12/10 22:24:31.608 T38Modem PTLib Thread
> high
> water mark set: 7
> 2010/12/10 22:24:31.608 Opal Liste...0xb5cd0b70 Listen
> Started
> listening thread on udp$*:5060
> 2010/12/10 22:24:31.608 T38Modem PWLib File
> handle high
> water mark set: 20 PTCPSocket
> 2010/12/10 22:24:31.608 T38Modem PWLib File
> handle high
> water mark set: 22 Thread unblock pipe
> 2010/12/10 22:24:31.608 T38Modem PTLib Thread
> high
> water mark set: 8
> 2010/12/10 22:24:31.611 ttyT38-1(b...0xb5d93b70
> RenameCurrentThread
> old ThreadName=PseudoModemPty:0xb5d93b70
> 2010/12/10 22:24:31.611 ttyT38-1(b...0xb5d93b70 Started for
> /dev/ttyT38-1 (accepts all)
> 2010/12/10 22:24:31.612 ttyT38-1(b...0xb5d93b70
> ModemEndPoint::OnMyCallback
> command=addmodem extra=10
> 2010/12/10 22:24:31.612 ttyT38-1(b...0xb5d93b70
> PseudoModemQ::Enqueue ttyT38-1
> 2010/12/10 22:24:31.612 ttyT38-1(b...0xb5d93b70
> ModemEndPoint::OnMyCallback
> request={
> command=addmodem
> response=confirm
> modemtoken=ttyT38-1
> }
> 2010/12/10 22:24:31.612 ttyT38-1(b...0xb5d93b70
> PseudoModemPty::OpenPty
> removed link /dev/ttyT38-1 -> /dev/pts/1
> 2010/12/10 22:24:31.612 ttyT38-1(b...0xb5d93b70
> PseudoModemPty::OpenPty
> added link /dev/ttyT38-1 -> /dev/pts/1
> 2010/12/10 22:24:31.612 ttyT38-1(b...0xb5d93b70 PWLib File
> handle high
> water mark set: 25 Thread unblock pipe
> 2010/12/10 22:24:31.612 ttyT38-1(b...0xb5d93b70 PWLib File
> handle high
> water mark set: 27 Thread unblock pipe
> 2010/12/10 22:24:31.612 ttyT38-1(b...0xb5d93b70 PWLib File
> handle high
> water mark set: 29 Thread unblock pipe
> 2010/12/10 22:24:31.612 ttyT38-1(b...0xb5d93b70 PTLib Thread
> high
> water mark set: 9
> 2010/12/10 22:24:31.612 ttyT38-1(b...0xb5d93b70 PTLib Thread
> high
> water mark set: 10
> 2010/12/10 22:24:31.612 ttyT38-1(b...0xb5d93b70 PTLib Thread
> high
> water mark set: 11
> 2010/12/10 22:24:31.612 Network In...0xb5d52b70 IfaceMon
> Started
> interface monitor thread.
> 2010/12/10 22:24:31.612 Network In...0xb5d52b70 PWLib File
> handle high
> water mark set: 30 PUDPSocket
> 2010/12/10 22:24:31.612 Opal Liste...0xb5c8fb70 Listen
> Started
> listening thread on tcp$*:5060
> 2010/12/10 22:24:31.612 Opal Liste...0xb5c8fb70 Listen
> Waiting on
> socket accept on tcp$*:5060
> 2010/12/10 22:24:31.612 ttyT38-1(e...0xb5c4eb70
> RenameCurrentThread
> old ThreadName=ModemEngine:0xb5c4eb70
> 2010/12/10 22:24:31.612 ttyT38-1(e...0xb5c4eb70 <-> Started
> 2010/12/10 22:24:31.612 ttyT38-1(i...0xb5c0db70
> RenameCurrentThread
> old ThreadName=InPty:0xb5c0db70
> 2010/12/10 22:24:31.612 ttyT38-1(i...0xb5c0db70 --> Started
> 2010/12/10 22:24:31.612 ttyT38-1(o...0xb5bccb70
> RenameCurrentThread
> old ThreadName=OutPty:0xb5bccb70
> 2010/12/10 22:24:31.612 ttyT38-1(o...0xb5bccb70 <-- Started
> 2010/12/10 22:24:31.946 T38Modem OpalTCPS
> Could not
> load certificate "server.pem"
> 2010/12/10 22:24:31.946 T38Modem PWLib File
> handle high
> water mark set: 32 Thread unblock pipe
> 2010/12/10 22:24:31.946 T38Modem PTLib Thread
> high
> water mark set: 12
> 2010/12/10 22:24:31.946 T38Modem SIP Start
> REGISTER
> aor=<my_username>@sip.nsw.iinet.net.au
> registrar=
> contact=
> authID=
> realm=
> expire=300
> restore=30
> minRetry=596:31:23.647
> maxRetry=596:31:23.647
> 2010/12/10 22:24:31.947 T38Modem PWLib File
> handle high
> water mark set: 33 PUDPSocket
> 2010/12/10 22:24:31.947 T38Modem SIP Changing
> REGISTER
> handler from Unavailable to Subscribing,
> target=sip:<my_username>@sip.nsw.iinet.net.au,
> id=1a4157ba-bd02-e011-9de3-000c2984a855@xxxxxxxxxxxxxxxxxxx
> 2010/12/10 22:24:31.948 Opal Liste...0xb5b8bb70 Listen
> Started
> listening thread on tcps$*:5061
> 2010/12/10 22:24:31.948 Opal Liste...0xb5b8bb70 TCPS Waiting
> on socket
> accept on tcps$*:5061
> 2010/12/10 22:24:31.949 T38Modem OpalUDP
> Binding to
> interface: 0.0.0.0:5060
> 2010/12/10 22:24:31.949 T38Modem SIP Created
> transport
> udp$203.55.231.194:5060<if=udp$*:5060>
> 2010/12/10 22:24:31.950 T38Modem OpalUDP
> Started
> connect to 203.55.231.194:5060
> 2010/12/10 22:24:31.950 T38Modem OpalUDP
> Writing to
> interface 0 - "10.11.12.22%eth0"
> 2010/12/10 22:24:31.951 T38Modem OpalMan
> Listener
> interfaces: associated transport=udp$10.11.12.22:5060
> udp$10.11.12.22:5060,tcp$10.11.12.22:5060,tcps$10.11.12.22:5061
> 2010/12/10 22:24:31.952 T38Modem SIP
> Transaction created.
> 2010/12/10 22:24:31.953 T38Modem SIP No SRV
> lookup as
> has explicit port number.
> 2010/12/10 22:24:31.953 T38Modem SIP
> Transaction remote
> address is udp$sip.nsw.iinet.net.au:5060
> 2010/12/10 22:24:31.953 T38Modem SIP Sending
> PDU (719
> bytes) to:
> rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%et
> h0
> REGISTER sip:sip.nsw.iinet.net.au SIP/2.0
> Route: <sip:sip.nsw.iinet.net.au:5060;lr>
> CSeq: 1 REGISTER
> Via: SIP/2.0/UDP
> 10.11.12.22:5060;branch=z9hG4bKdc3758ba-bd02-e011-9de3-000c2984a855;rpor
> t
> User-Agent: T38Modem/1.2.0
> From:
> <sip:<my_username>@sip.nsw.iinet.net.au>;tag=544d57ba-bd02-e011-9de3-000
> c2984a855
> Call-ID: 1a4157ba-bd02-e011-9de3-000c2984a855@xxxxxxxxxxxxxxxxxxx
> Organization: Vyacheslav Frolov
> To: <sip:<my_username>@sip.nsw.iinet.net.au>
> Contact: <sip:<my_username>@10.11.12.22>;q=1,
> <sip:<my_username>@10.11.12.22;transport=tcp>;q=0.667,
> <sips:<my_username>@10.11.12.22>;q=0.334
> Allow:
> INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
> Expires: 300
> Content-Length: 0
> Max-Forwards: 70
>
>
> 2010/12/10 22:24:31.954 T38Modem OpalUDP
> Setting
> interface to 10.11.12.22%eth0
> 2010/12/10 22:24:31.954 T38Modem SIP
> Transaction timers
> set: retry=0.500, completion=16.000
> 2010/12/10 22:24:31.954 T38Modem OpalMan
> Added route
> "modem:.*=sip:<dn>@sip.nsw.iinet.net.au"
> 2010/12/10 22:24:31.954 T38Modem PTLib
> Destroyed
> PXConfig 0x9ba4750
> 2010/12/10 22:24:32.001 Opal Liste...0xb5cd0b70 OpalUDP
> Binding to
> interface: 10.11.12.22:5060
> 2010/12/10 22:24:32.002 Opal Liste...0xb5cd0b70 SIP Waiting
> for PDU on
> udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
> 2010/12/10 22:24:32.002 Opal Liste...0xb5cd0b70 SIP PDU
> received:
> rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%et
> h0
> SIP/2.0 401 Unauthorized
> CSeq: 1 REGISTER
> Via: SIP/2.0/UDP
> 10.11.12.22:5060;received=203.206.207.88;branch=z9hG4bKdc3758ba-bd02-e01
> 1-9de3-000c2984a855;rport=39236
> From:
> <sip:<my_username>@sip.nsw.iinet.net.au>;tag=544d57ba-bd02-e011-9de3-000
> c2984a855
> Call-ID: 1a4157ba-bd02-e011-9de3-000c2984a855@xxxxxxxxxxxxxxxxxxx
> To: <sip:<my_username>@sip.nsw.iinet.net.au>;tag=SD4g06c99-
> Content-Length: 0
> WWW-Authenticate: DIGEST
> qop="auth",nonce="BroadWorksXghizpl3aTgszluwBW",algorithm=MD5,realm="iin
> etphone.iinet.net.au"
>
>
> 2010/12/10 22:24:32.002 Opal Liste...0xb5cd0b70 SIP Queueing
> PDU "1
> REGISTER <401>",
> transaction=z9hG4bKdc3758ba-bd02-e011-9de3-000c2984a855,
> token=1a4157ba-bd02-e011-9de3-000c2984a855@xxxxxxxxxxxxxxxxxxx
> 2010/12/10 22:24:32.002 Opal Liste...0xb5cd0b70 PWLib File
> handle high
> water mark set: 34 Thread unblock pipe
> 2010/12/10 22:24:32.002 Opal Liste...0xb5cd0b70 PTLib Thread
> high
> water mark set: 13
> 2010/12/10 22:24:32.002 Opal Liste...0xb5cd0b70 Opal
> Transport clean
> up on termination
> 2010/12/10 22:24:32.002 Opal Liste...0xb5cd0b70 Opal
> Transport Close
> 2010/12/10 22:24:32.003 Opal Liste...0xb5cd0b70 PWLib File
> handle high
> water mark set: 35 PUDPSocket
> 2010/12/10 22:24:32.002 Opal Liste...0xb5cd0b70 Opal Deleted
> transport
> udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
> 2010/12/10 22:24:32.003 Pool:0xb5b3eb70 SIP Handling
> PDU "1
> REGISTER <401>" for
> transaction=z9hG4bKdc3758ba-bd02-e011-9de3-000c2984a855
> 2010/12/10 22:24:32.003 Pool:0xb5b3eb70 SIP REGISTER
> transaction id=z9hG4bKdc3758ba-bd02-e011-9de3-000c2984a855 completed.
> 2010/12/10 22:24:32.003 Pool:0xb5b3eb70 OpalUDP
> Setting
> interface to 10.11.12.22%eth0
> 2010/12/10 22:24:32.003 Pool:0xb5b3eb70 SIP Received
> Authentication Required response
> 2010/12/10 22:24:32.003 Pool:0xb5b3eb70 SIP
> Authentication
> contains qop-options auth
> 2010/12/10 22:24:32.005 Pool:0xb5b3eb70 SIP Located
> existing
> credentials for ID "<my_username>" at realm "iinetphone.iinet.net.au"
> 2010/12/10 22:24:32.005 Pool:0xb5b3eb70 SIP Found
> auth info
> for realm iinetphone.iinet.net.au
> 2010/12/10 22:24:32.005 Pool:0xb5b3eb70 SIP Changing
> REGISTER
> handler from Unavailable to Subscribing,
> target=sip:<my_username>@sip.nsw.iinet.net.au,
> id=1a4157ba-bd02-e011-9de3-000c2984a855@xxxxxxxxxxxxxxxxxxx
> 2010/12/10 22:24:32.007 Pool:0xb5b3eb70 OpalMan
> Listener
> interfaces: associated transport=udp$10.11.12.22:5060
> udp$10.11.12.22:5060,tcp$10.11.12.22:5060,tcps$10.11.12.22:5061
> 2010/12/10 22:24:32.007 Pool:0xb5b3eb70 SIP
> Transaction created.
> 2010/12/10 22:24:32.009 Pool:0xb5b3eb70 SIP Adding
> authentication information for user "<my_username>" at realm
> "iinetphone.iinet.net.au"
> 2010/12/10 22:24:32.009 Pool:0xb5b3eb70 SIP No SRV
> lookup as
> has explicit port number.
> 2010/12/10 22:24:32.009 Pool:0xb5b3eb70 SIP
> Transaction remote
> address is udp$sip.nsw.iinet.net.au:5060
> 2010/12/10 22:24:32.009 Pool:0xb5b3eb70 SIP Sending
> PDU (997
> bytes) to:
> rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%et
> h0
> REGISTER sip:sip.nsw.iinet.net.au SIP/2.0
> Route: <sip:sip.nsw.iinet.net.au:5060;lr>
> CSeq: 2 REGISTER
> Via: SIP/2.0/UDP
> 10.11.12.22:5060;branch=z9hG4bK7abe60ba-bd02-e011-9de3-000c2984a855;rpor
> t
> User-Agent: T38Modem/1.2.0
> Authorization: Digest username="<my_username>",
> realm="iinetphone.iinet.net.au", nonce="BroadWorksXghizpl3aTgszluwBW",
> uri="sip:sip.nsw.iinet.net.au", algorithm=MD5,
> response="cbd0cacad1316fdeb252610a23125ee7",
> cnonce="6ef35fba-bd02-e011-9de3-000c2984a855", nc=00000001, qop=auth
> From:
> <sip:<my_username>@sip.nsw.iinet.net.au>;tag=544d57ba-bd02-e011-9de3-000
> c2984a855
> Call-ID: 1a4157ba-bd02-e011-9de3-000c2984a855@xxxxxxxxxxxxxxxxxxx
> Organization: Vyacheslav Frolov
> To: <sip:<my_username>@sip.nsw.iinet.net.au>
> Contact: <sip:<my_username>@10.11.12.22>;q=1,
> <sip:<my_username>@10.11.12.22;transport=tcp>;q=0.667,
> <sips:<my_username>@10.11.12.22>;q=0.334
> Allow:
> INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
> Expires: 300
> Content-Length: 0
> Max-Forwards: 70
>
>
> 2010/12/10 22:24:32.009 Pool:0xb5b3eb70 OpalUDP
> Setting
> interface to 10.11.12.22%eth0
> 2010/12/10 22:24:32.009 Pool:0xb5b3eb70 SIP
> Transaction timers
> set: retry=0.500, completion=16.000
> 2010/12/10 22:24:32.010 Pool:0xb5b3eb70 SIP Handled
> PDU "1
> REGISTER <401>"
> 2010/12/10 22:24:32.046 Opal Liste...0xb5cd0b70 OpalUDP
> Binding to
> interface: 10.11.12.22:5060
> 2010/12/10 22:24:32.046 Opal Liste...0xb5cd0b70 SIP Waiting
> for PDU on
> udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
> 2010/12/10 22:24:32.046 Opal Liste...0xb5cd0b70 SIP PDU
> received:
> rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%et
> h0
> SIP/2.0 200 OK
> CSeq: 2 REGISTER
> Via: SIP/2.0/UDP
> 10.11.12.22:5060;received=203.206.207.88;branch=z9hG4bK7abe60ba-bd02-e01
> 1-9de3-000c2984a855;rport=39236
> From:
> <sip:<my_username>@sip.nsw.iinet.net.au>;tag=544d57ba-bd02-e011-9de3-000
> c2984a855
> Call-ID: 1a4157ba-bd02-e011-9de3-000c2984a855@xxxxxxxxxxxxxxxxxxx
> To:
> <sip:<my_username>@sip.nsw.iinet.net.au>;tag=SD4g06c99-773930199-1291980
> 296758
> Contact:
> <sip:<my_username>-h4ku1sr8979u4@xxxxxxxxxx:5060;transport=udp>;q=1;expi
> res=3599
> Allow-Events:
> call-info,line-seize,dialog,message-summary,as-feature-event
> Content-Length: 0
>
>
> 2010/12/10 22:24:32.046 Opal Liste...0xb5cd0b70 SIP Queueing
> PDU "2
> REGISTER <200>",
> transaction=z9hG4bK7abe60ba-bd02-e011-9de3-000c2984a855,
> token=1a4157ba-bd02-e011-9de3-000c2984a855@xxxxxxxxxxxxxxxxxxx
> 2010/12/10 22:24:32.046 Opal Liste...0xb5cd0b70 Opal
> Transport clean
> up on termination
> 2010/12/10 22:24:32.046 Opal Liste...0xb5cd0b70 Opal
> Transport Close
> 2010/12/10 22:24:32.046 Opal Liste...0xb5cd0b70 Opal Deleted
> transport
> udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
> 2010/12/10 22:24:32.047 Pool:0xb5b3eb70 SIP Handling
> PDU "2
> REGISTER <200>" for
> transaction=z9hG4bK7abe60ba-bd02-e011-9de3-000c2984a855
> 2010/12/10 22:24:32.047 Pool:0xb5b3eb70 SIP REGISTER
> transaction id=z9hG4bK7abe60ba-bd02-e011-9de3-000c2984a855 completed.
> 2010/12/10 22:24:32.047 Pool:0xb5b3eb70 OpalUDP
> Setting
> interface to 10.11.12.22%eth0
> 2010/12/10 22:24:32.047 Pool:0xb5b3eb70 SIP No
> User-Agent or
> Server fields, Product Info unknown.
> 2010/12/10 22:24:32.047 Pool:0xb5b3eb70 SIP Changing
> REGISTER
> handler from Subscribing to Subscribed,
> target=sip:<my_username>@sip.nsw.iinet.net.au,
> id=1a4157ba-bd02-e011-9de3-000c2984a855@xxxxxxxxxxxxxxxxxxx
> 2010/12/10 22:24:32.047 Pool:0xb5b3eb70 SIP No
> User-Agent or
> Server fields, Product Info unknown.
> 2010/12/10 22:24:32.047 Pool:0xb5b3eb70 SIP Handled
> PDU "2
> REGISTER <200>"
> 2010/12/10 22:24:37.011 Housekeeper:0xb5d11b70 SIP Set
> state
> Terminated_Success for REGISTER transaction
> id=z9hG4bKdc3758ba-bd02-e011-9de3-000c2984a855
> 2010/12/10 22:24:37.048 Housekeeper:0xb5d11b70 SIP Set
> state
> Terminated_Success for REGISTER transaction
> id=z9hG4bK7abe60ba-bd02-e011-9de3-000c2984a855
> 2010/12/10 22:24:37.614 Opal Garbage:0xb5dd4b70 SIP
> Transaction
> id=z9hG4bK7abe60ba-bd02-e011-9de3-000c2984a855 destroyed.
> 2010/12/10 22:24:37.614 Opal Garbage:0xb5dd4b70 SIP
> Transaction
> id=z9hG4bKdc3758ba-bd02-e011-9de3-000c2984a855 destroyed.
> 2010/12/10 22:24:44.808 ttyT38-1(e...0xb5c4eb70 --> ATZ
> 2010/12/10 22:24:44.808 ttyT38-1(e...0xb5c4eb70 <-- {
> 0d 0a 4f 4b 0d 0a ..OK.. }
> 2010/12/10 22:24:47.916 ttyT38-1(e...0xb5c4eb70 --> ATS0=0
> 2010/12/10 22:24:47.916 ttyT38-1(e...0xb5c4eb70 <-- {
> 0d 0a 4f 4b 0d 0a ..OK.. }
> 2010/12/10 22:24:48.024 ttyT38-1(e...0xb5c4eb70 --> ATE0
> 2010/12/10 22:24:48.024 ttyT38-1(e...0xb5c4eb70 <-- {
> 0d 0a 4f 4b 0d 0a ..OK.. }
> 2010/12/10 22:24:48.132 ttyT38-1(e...0xb5c4eb70 --> ATV1
> 2010/12/10 22:24:48.132 ttyT38-1(e...0xb5c4eb70 <-- {
> 0d 0a 4f 4b 0d 0a ..OK.. }
> 2010/12/10 22:24:48.240 ttyT38-1(e...0xb5c4eb70 --> ATQ0
> 2010/12/10 22:24:48.240 ttyT38-1(e...0xb5c4eb70 <-- {
> 0d 0a 4f 4b 0d 0a ..OK.. }
> 2010/12/10 22:24:48.348 ttyT38-1(e...0xb5c4eb70 --> ATS8=2
> 2010/12/10 22:24:48.348 ttyT38-1(e...0xb5c4eb70 <-- {
> 0d 0a 4f 4b 0d 0a ..OK.. }
> 2010/12/10 22:24:48.456 ttyT38-1(e...0xb5c4eb70 --> ATS7=60
> 2010/12/10 22:24:48.456 ttyT38-1(e...0xb5c4eb70 <-- {
> 0d 0a 4f 4b 0d 0a ..OK.. }
> 2010/12/10 22:24:48.564 ttyT38-1(e...0xb5c4eb70 --> AT+FCLASS=?
> 2010/12/10 22:24:48.564 ttyT38-1(e...0xb5c4eb70 <-- {
> 0d 0a 31 2c 38 0d 0a 4f 4b 0d 0a ..1,8..OK.. }
> 2010/12/10 22:24:48.672 ttyT38-1(e...0xb5c4eb70 --> AT+FCLASS=1
> 2010/12/10 22:24:48.672 ttyT38-1(e...0xb5c4eb70 <-- {
> 0d 0a 4f 4b 0d 0a ..OK.. }
> 2010/12/10 22:24:48.780 ttyT38-1(e...0xb5c4eb70 --> ATI3
> 2010/12/10 22:24:48.780 ttyT38-1(e...0xb5c4eb70 <-- {
> 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/10 22:24:48.888 ttyT38-1(e...0xb5c4eb70 --> ATI0
> 2010/12/10 22:24:48.888 ttyT38-1(e...0xb5c4eb70 <-- {
> 0d 0a 54 33 38 46 41 58 0d 0a 4f 4b 0d 0a ..T38FAX..OK.. }
> 2010/12/10 22:24:48.996 ttyT38-1(e...0xb5c4eb70 --> AT+FREV?
> 2010/12/10 22:24:48.996 ttyT38-1(e...0xb5c4eb70 <-- {
> 0d 0a 31 2e 32 2e 30 0d 0a 4f 4b 0d 0a ..1.2.0..OK.. }
> 2010/12/10 22:24:49.104 ttyT38-1(e...0xb5c4eb70 --> AT+FTM=?
> 2010/12/10 22:24:49.104 ttyT38-1(e...0xb5c4eb70 <-- {
> 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/10 22:24:49.212 ttyT38-1(e...0xb5c4eb70 --> AT+FRM=?
> 2010/12/10 22:24:49.212 ttyT38-1(e...0xb5c4eb70 <-- {
> 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/10 22:24:49.320 ttyT38-1(e...0xb5c4eb70 --> ATM0
> 2010/12/10 22:24:49.320 ttyT38-1(e...0xb5c4eb70 <-- {
> 0d 0a 4f 4b 0d 0a ..OK.. }
> 2010/12/10 22:25:23.339 ttyT38-1(i...0xb5c0db70 --> read ERROR
> -1
> Input/output error
> 2010/12/10 22:25:23.339 ttyT38-1(i...0xb5c0db70 --> Stopped
> 2010/12/10 22:25:23.339 ttyT38-1(o...0xb5bccb70 <-- Stopped
> 2010/12/10 22:25:23.350 ttyT38-1(e...0xb5c4eb70 <-> Stopped
> 2010/12/10 22:25:23.361 ttyT38-1(b...0xb5d93b70
> PseudoModemPty::OpenPty
> removed link /dev/ttyT38-1 -> /dev/pts/1
> 2010/12/10 22:25:23.361 ttyT38-1(b...0xb5d93b70
> PseudoModemPty::OpenPty
> read ERROR -1 Input/output error
> 2010/12/10 22:25:23.361 ttyT38-1(b...0xb5d93b70
> PseudoModemPty::OpenPty
> added link /dev/ttyT38-1 -> /dev/pts/1
> 2010/12/10 22:25:23.361 ttyT38-1(b...0xb5d93b70 PWLib File
> handle low
> water mark set: 25 Thread unblock pipe
> 2010/12/10 22:25:23.366 ttyT38-1(e...0xb5c4eb70
> RenameCurrentThread
> old ThreadName=ModemEngine:0xb5c4eb70
> 2010/12/10 22:25:23.366 ttyT38-1(e...0xb5c4eb70 <-> Started
> 2010/12/10 22:25:23.366 ttyT38-1(i...0xb5bccb70
> RenameCurrentThread
> old ThreadName=InPty:0xb5bccb70
> 2010/12/10 22:25:23.366 ttyT38-1(i...0xb5bccb70 --> Started
> 2010/12/10 22:25:23.366 ttyT38-1(o...0xb5c0db70
> RenameCurrentThread
> old ThreadName=OutPty:0xb5c0db70
> 2010/12/10 22:25:23.366 ttyT38-1(o...0xb5c0db70 <-- Started
> 2010/12/10 22:25:28.552 ttyT38-1(e...0xb5c4eb70 --> ATZ
> 2010/12/10 22:25:28.552 ttyT38-1(e...0xb5c4eb70 <-- {
> 0d 0a 4f 4b 0d 0a ..OK.. }
> 2010/12/10 22:25:31.663 ttyT38-1(e...0xb5c4eb70 --> ATS0=0
> 2010/12/10 22:25:31.663 ttyT38-1(e...0xb5c4eb70 <-- {
> 0d 0a 4f 4b 0d 0a ..OK.. }
> 2010/12/10 22:25:31.771 ttyT38-1(e...0xb5c4eb70 --> ATE0
> 2010/12/10 22:25:31.771 ttyT38-1(e...0xb5c4eb70 <-- {
> 0d 0a 4f 4b 0d 0a ..OK.. }
> 2010/12/10 22:25:31.880 ttyT38-1(e...0xb5c4eb70 --> ATV1
> 2010/12/10 22:25:31.880 ttyT38-1(e...0xb5c4eb70 <-- {
> 0d 0a 4f 4b 0d 0a ..OK.. }
> 2010/12/10 22:25:31.988 ttyT38-1(e...0xb5c4eb70 --> ATQ0
> 2010/12/10 22:25:31.988 ttyT38-1(e...0xb5c4eb70 <-- {
> 0d 0a 4f 4b 0d 0a ..OK.. }
> 2010/12/10 22:25:32.095 ttyT38-1(e...0xb5c4eb70 --> ATS8=2
> 2010/12/10 22:25:32.095 ttyT38-1(e...0xb5c4eb70 <-- {
> 0d 0a 4f 4b 0d 0a ..OK.. }
> 2010/12/10 22:25:32.203 ttyT38-1(e...0xb5c4eb70 --> ATS7=60
> 2010/12/10 22:25:32.204 ttyT38-1(e...0xb5c4eb70 <-- {
> 0d 0a 4f 4b 0d 0a ..OK.. }
> 2010/12/10 22:25:32.311 ttyT38-1(e...0xb5c4eb70 --> AT+FCLASS=?
> 2010/12/10 22:25:32.311 ttyT38-1(e...0xb5c4eb70 <-- {
> 0d 0a 31 2c 38 0d 0a 4f 4b 0d 0a ..1,8..OK.. }
> 2010/12/10 22:25:32.419 ttyT38-1(e...0xb5c4eb70 --> AT+FCLASS=1
> 2010/12/10 22:25:32.419 ttyT38-1(e...0xb5c4eb70 <-- {
> 0d 0a 4f 4b 0d 0a ..OK.. }
> 2010/12/10 22:25:32.528 ttyT38-1(e...0xb5c4eb70 --> ATI3
> 2010/12/10 22:25:32.530 ttyT38-1(e...0xb5c4eb70 <-- {
> 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/10 22:25:32.635 ttyT38-1(e...0xb5c4eb70 --> ATI0
> 2010/12/10 22:25:32.636 ttyT38-1(e...0xb5c4eb70 <-- {
> 0d 0a 54 33 38 46 41 58 0d 0a 4f 4b 0d 0a ..T38FAX..OK.. }
> 2010/12/10 22:25:32.744 ttyT38-1(e...0xb5c4eb70 --> AT+FREV?
> 2010/12/10 22:25:32.744 ttyT38-1(e...0xb5c4eb70 <-- {
> 0d 0a 31 2e 32 2e 30 0d 0a 4f 4b 0d 0a ..1.2.0..OK.. }
> 2010/12/10 22:25:32.851 ttyT38-1(e...0xb5c4eb70 --> AT+FTM=?
> 2010/12/10 22:25:32.852 ttyT38-1(e...0xb5c4eb70 <-- {
> 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/10 22:25:32.959 ttyT38-1(e...0xb5c4eb70 --> AT+FRM=?
> 2010/12/10 22:25:32.960 ttyT38-1(e...0xb5c4eb70 <-- {
> 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/10 22:25:33.068 ttyT38-1(e...0xb5c4eb70 --> ATM0
> 2010/12/10 22:25:33.068 ttyT38-1(e...0xb5c4eb70 <-- {
> 0d 0a 4f 4b 0d 0a ..OK.. }
> 2010/12/10 22:25:33.175 ttyT38-1(e...0xb5c4eb70 --> AT+FCLASS=1
> 2010/12/10 22:25:33.176 ttyT38-1(e...0xb5c4eb70 <-- {
> 0d 0a 4f 4b 0d 0a ..OK.. }
> 2010/12/10 22:25:33.183 ttyT38-1(e...0xb5c4eb70 -->
> ATDT0296400300
> 2010/12/10 22:25:33.184 ttyT38-1(e...0xb5c4eb70
> ModemEndPoint::OnMyCallback
> command=dial extra=3
> 2010/12/10 22:25:33.184 ttyT38-1(e...0xb5c4eb70
> PseudoModemQ::Dequeue ttyT38-1
> 2010/12/10 22:25:33.184 ttyT38-1(e...0xb5c4eb70
> MyManager::OnMyCallback
> SetUpCall(modem:, 0296400300@+/dev/ttyT38-1)
> 2010/12/10 22:25:33.184 ttyT38-1(e...0xb5c4eb70 OpalMan Set
> up call
> from modem: to 0296400300@+/dev/ttyT38-1
> 2010/12/10 22:25:33.184 ttyT38-1(e...0xb5c4eb70 Call Created
> Call[ncdf19dd31]
> 2010/12/10 22:25:33.184 ttyT38-1(e...0xb5c4eb70 OpalMan Set
> up
> connection to "modem:"
> 2010/12/10 22:25:33.184 ttyT38-1(e...0xb5c4eb70
> ModemEndPoint::MakeConnection
> modem:
> 2010/12/10 22:25:33.184 ttyT38-1(e...0xb5c4eb70 OpalCon
> Created
> connection Call[ncdf19dd31]-EP<modem>[modem:/ncdf19dd31/0]
> 2010/12/10 22:25:33.184 ttyT38-1(e...0xb5c4eb70
> ModemConnection::ModemConnection
> Call[ncdf19dd31]-EP<modem>[modem:/ncdf19dd31/0]
> 2010/12/10 22:25:33.184 ttyT38-1(e...0xb5c4eb70
> ModemConnection::SetUpConnection
> Call[ncdf19dd31]-EP<modem>[modem:/ncdf19dd31/0]
> 2010/12/10 22:25:33.184 ttyT38-1(e...0xb5c4eb70 OpalCon
> SetPhase from
> UninitialisedPhase to SetUpPhase for
> Call[ncdf19dd31]-EP<modem>[modem:/ncdf19dd31/0]
> 2010/12/10 22:25:33.184 ttyT38-1(e...0xb5c4eb70 OpalMan
> OnIncoming
> connection Call[ncdf19dd31]-EP<modem>[modem:/ncdf19dd31/0]
> 2010/12/10 22:25:33.184 ttyT38-1(e...0xb5c4eb70 OpalCon
> Applying string options:
>
>
> 2010/12/10 22:25:33.184 ttyT38-1(e...0xb5c4eb70 Call
> GetOtherPartyConnection
> Call[ncdf19dd31]-EP<modem>[modem:/ncdf19dd31/0]
> 2010/12/10 22:25:33.184 ttyT38-1(e...0xb5c4eb70 OpalMan
> Searching for
> route "modem:<my_username> 0296400300@+/dev/ttyT38-1"
> 2010/12/10 22:25:33.184 ttyT38-1(e...0xb5c4eb70 OpalMan Set
> up
> connection to "sip:0296400300@xxxxxxxxxxxxxxxxxxxx"
> 2010/12/10 22:25:33.184 ttyT38-1(e...0xb5c4eb70
> MySIPEndPoint::CreateConnection
> for Call[ncdf19dd31]
> 2010/12/10 22:25:33.184 ttyT38-1(e...0xb5c4eb70 OpalCon
> Created
> connection
> Call[ncdf19dd31]-EP<sip>[0665d7de-bd02-e011-9de3-000c2984a855]
> 2010/12/10 22:25:33.184 ttyT38-1(e...0xb5c4eb70 RFC2833
> Handler created
> 2010/12/10 22:25:33.184 ttyT38-1(e...0xb5c4eb70 RFC2833
> Handler created
> 2010/12/10 22:25:33.185 ttyT38-1(e...0xb5c4eb70 SIP Created
> connection.
> 2010/12/10 22:25:33.185 ttyT38-1(e...0xb5c4eb70 Call[ncdf19dd31]
> from
> modem:<my_username> to 0296400300@+/dev/ttyT38-1, route to
> sip:udp$sip.nsw.iinet.net.au:5060
> 2010/12/10 22:25:33.185 ttyT38-1(e...0xb5c4eb70 Outgoing call
> routed
> to sip:0296400300@xxxxxxxxxxxxxxxxxxxx for
> Call[ncdf19dd31]-EP<modem>[modem:/ncdf19dd31/0]
> 2010/12/10 22:25:33.185 ttyT38-1(e...0xb5c4eb70 Call OnSetUp
> Call[ncdf19dd31]-EP<modem>[modem:/ncdf19dd31/0]
> 2010/12/10 22:25:33.185 ttyT38-1(e...0xb5c4eb70
> MySIPConnection::SetUpConnection
> Call[ncdf19dd31]-EP<sip>[0665d7de-bd02-e011-9de3-000c2984a855]
> name=<my_username>
> 2010/12/10 22:25:33.185 ttyT38-1(e...0xb5c4eb70 SIP
> SetUpConnection:
> sip:0296400300@xxxxxxxxxxxxxxxxxxxx
> 2010/12/10 22:25:33.185 ttyT38-1(e...0xb5c4eb70 OpalCon
> SetPhase from
> UninitialisedPhase to SetUpPhase for
> Call[ncdf19dd31]-EP<sip>[0665d7de-bd02-e011-9de3-000c2984a855]
> 2010/12/10 22:25:33.185 ttyT38-1(e...0xb5c4eb70 OpalCon
> Applying string options:
>
>
> 2010/12/10 22:25:33.185 ttyT38-1(e...0xb5c4eb70 SIP Found
> registrar on
> domain sip.nsw.iinet.net.au, using interface 10.11.12.22%eth0
> 2010/12/10 22:25:33.185 ttyT38-1(e...0xb5c4eb70 OpalUDP
> Binding to
> interface: 10.11.12.22:5060
> 2010/12/10 22:25:33.185 ttyT38-1(e...0xb5c4eb70 SIP Created
> transport
> udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
> 2010/12/10 22:25:33.186 ttyT38-1(e...0xb5c4eb70 OpalUDP
> Started
> connect to 203.55.231.194:5060
> 2010/12/10 22:25:33.186 ttyT38-1(e...0xb5c4eb70 SIP Updating
> dialog
> tag from "" to "0665d7de-bd02-e011-9de3-000c2984a855"
> 2010/12/10 22:25:33.188 ttyT38-1(e...0xb5c4eb70 SIP INVITE
> transaction
> id=z9hG4bKaaf7d7de-bd02-e011-9de3-000c2984a855 created.
> 2010/12/10 22:25:33.188 ttyT38-1(e...0xb5c4eb70 SIP Creating
> INVITE request
> 2010/12/10 22:25:33.188 ttyT38-1(e...0xb5c4eb70
> ModemConnection::GetMediaFormats
> Call[ncdf19dd31]-EP<modem>[modem:/ncdf19dd31/0]
> 2010/12/10 22:25:33.188 ttyT38-1(e...0xb5c4eb70
> ModemEndPoint::GetMediaFormats
> 2010/12/10 22:25:33.190 ttyT38-1(e...0xb5c4eb70 MediaFormat
> Removing codecs
> 2010/12/10 22:25:33.190 ttyT38-1(e...0xb5c4eb70 MediaFormat
> Removing codecs
> 2010/12/10 22:25:33.190 ttyT38-1(e...0xb5c4eb70 Call
> GetMediaFormats
> for Call[ncdf19dd31]-EP<sip>[0665d7de-bd02-e011-9de3-000c2984a855]
> G.711-uLaw-64k
> G.711-ALaw-64k
> PCM-16
> T.38
>
> 2010/12/10 22:25:33.190 ttyT38-1(e...0xb5c4eb70 SIP Offering
> media
> type audio in SDP with formats
> G.711-uLaw-64k,G.711-ALaw-64k,PCM-16,T.38
> 2010/12/10 22:25:33.190 ttyT38-1(e...0xb5c4eb70 Call
> IsMediaBypassPossible
> Call[ncdf19dd31]-EP<sip>[0665d7de-bd02-e011-9de3-000c2984a855] session
> 1
> 2010/12/10 22:25:33.190 ttyT38-1(e...0xb5c4eb70 OpalMan
> IsMediaBypassPossible:
> session 1
> 2010/12/10 22:25:33.190 ttyT38-1(e...0xb5c4eb70 OpalCon
> IsMediaBypassPossible:
> default returns false
> 2010/12/10 22:25:33.190 ttyT38-1(e...0xb5c4eb70 RTP Cannot
> find media session 1
> 2010/12/10 22:25:33.190 ttyT38-1(e...0xb5c4eb70 RTP Cannot
> find RTP session 1
> 2010/12/10 22:25:33.191 ttyT38-1(e...0xb5c4eb70 RTP_UDP
> Session 1,
> created with NAT flag set to 0
> 2010/12/10 22:25:33.191 ttyT38-1(e...0xb5c4eb70 PWLib File
> handle high
> water mark set: 36 PUDPSocket
> 2010/12/10 22:25:33.191 ttyT38-1(e...0xb5c4eb70 PWLib File
> handle low
> water mark set: 35 PUDPSocket
> 2010/12/10 22:25:33.191 ttyT38-1(e...0xb5c4eb70 RTP_UDP
> Session 1
> created: 10.11.12.22:5000-5001 ssrc=2988156518
> 2010/12/10 22:25:33.191 ttyT38-1(e...0xb5c4eb70 PWLib File
> handle high
> water mark set: 37 PUDPSocket
> 2010/12/10 22:25:33.191 ttyT38-1(e...0xb5c4eb70 RTP Creating
> new session RTP_UDP
> 2010/12/10 22:25:33.191 ttyT38-1(e...0xb5c4eb70 RTP Found
> existing
> media session 1
> 2010/12/10 22:25:33.192 ttyT38-1(e...0xb5c4eb70 SIP Using
> default RTP
> payload [pt=101] for UserInput/RFC2833
> 2010/12/10 22:25:33.192 ttyT38-1(e...0xb5c4eb70 SIP Using
> default RTP
> payload [pt=100] for NamedSignalEvent
> 2010/12/10 22:25:33.192 ttyT38-1(e...0xb5c4eb70
> ModemConnection::GetMediaFormats
> Call[ncdf19dd31]-EP<modem>[modem:/ncdf19dd31/0]
> 2010/12/10 22:25:33.192 ttyT38-1(e...0xb5c4eb70
> ModemEndPoint::GetMediaFormats
> 2010/12/10 22:25:33.193 ttyT38-1(e...0xb5c4eb70 MediaFormat
> Removing codecs
> 2010/12/10 22:25:33.193 ttyT38-1(e...0xb5c4eb70 MediaFormat
> Removing codecs
> 2010/12/10 22:25:33.194 ttyT38-1(e...0xb5c4eb70 Call
> GetMediaFormats
> for Call[ncdf19dd31]-EP<sip>[0665d7de-bd02-e011-9de3-000c2984a855]
> G.711-uLaw-64k
> G.711-ALaw-64k
> PCM-16
> T.38
>
> 2010/12/10 22:25:33.194 ttyT38-1(e...0xb5c4eb70 SIP No media
> formats
> of type video, not adding SDP
> 2010/12/10 22:25:33.194 ttyT38-1(e...0xb5c4eb70 SIP No SRV
> lookup as
> has explicit port number.
> 2010/12/10 22:25:33.194 ttyT38-1(e...0xb5c4eb70 SIP
> Transaction remote
> address is udp$sip.nsw.iinet.net.au:5060
> 2010/12/10 22:25:33.194 ttyT38-1(e...0xb5c4eb70 SIP Sending
> PDU (980
> bytes) to:
> rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%et
> h0
> INVITE sip:0296400300@xxxxxxxxxxxxxxxxxxxx SIP/2.0
> Route: <sip:sip.nsw.iinet.net.au:5060;lr>
> Date: Fri, 10 Dec 2010 11:25:33 GMT
> CSeq: 1 INVITE
> Via: SIP/2.0/UDP
> 10.11.12.22:5060;branch=z9hG4bKaaf7d7de-bd02-e011-9de3-000c2984a855;rpor
> t
> User-Agent: T38Modem/1.2.0
> From: "root"
> <sip:<my_username>@sip.nsw.iinet.net.au>;tag=0665d7de-bd02-e011-9de3-000
> c2984a855
> Call-ID: 866cd7de-bd02-e011-9de3-000c2984a855@xxxxxxxxxxxxxxxxxxx
> Organization: Vyacheslav Frolov
> To: <sip:0296400300@xxxxxxxxxxxxxxxxxxxx>
> Contact: <sip:<my_username>@10.11.12.22>
> Allow:
> INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
> Content-Type: application/sdp
> Content-Length: 286
> Max-Forwards: 70
>
> v=0
> o=- 1291980333 1 IN IP4 10.11.12.22
> s=Opal SIP Session
> c=IN IP4 10.11.12.22
> t=0 0
> m=audio 5000 RTP/AVP 0 8 101 100
> a=sendrecv
> a=rtpmap:0 PCMU/8000/1
> a=rtpmap:8 PCMA/8000/1
> a=rtpmap:101 telephone-event/8000
> a=fmtp:101 0-16,32,36
> a=rtpmap:100 NSE/8000
> a=fmtp:100 192-193
>
> 2010/12/10 22:25:33.194 ttyT38-1(e...0xb5c4eb70 OpalUDP
> Setting
> interface to 10.11.12.22%eth0
> 2010/12/10 22:25:33.195 ttyT38-1(e...0xb5c4eb70 SIP
> Transaction timers
> set: retry=0.500, completion=32.000
> 2010/12/10 22:25:33.195 ttyT38-1(e...0xb5c4eb70 OpalCon
> OnSetUpConnectionCall[ncdf19dd31]-EP<sip>[0665d7de-bd02-e011-9de3-000c29
> 84a855]
> 2010/12/10 22:25:33.195 ttyT38-1(e...0xb5c4eb70 OpalEP
> OnSetUpConnection
> Call[ncdf19dd31]-EP<sip>[0665d7de-bd02-e011-9de3-000c2984a855]
> 2010/12/10 22:25:33.195 ttyT38-1(e...0xb5c4eb70 OpalMan
> SetUpCall
> succeeded, call=Call[ncdf19dd31]
> 2010/12/10 22:25:33.195 ttyT38-1(e...0xb5c4eb70 ttyT38-1
> T38Engine::T38Engine
> 2010/12/10 22:25:33.195 ttyT38-1(e...0xb5c4eb70
> ModemEndPoint::OnMyCallback
> request={
> calltoken=modem:/ncdf19dd31/0
> localpartyname=
> command=dial
> response=confirm
> number=0296400300
> modemtoken=ttyT38-1
> }
> 2010/12/10 22:25:33.217 Opal Liste...0xb5cd0b70 OpalUDP
> Binding to
> interface: 10.11.12.22:5060
> 2010/12/10 22:25:33.217 Opal Liste...0xb5cd0b70 SIP Waiting
> for PDU on
> udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
> 2010/12/10 22:25:33.217 Opal Liste...0xb5cd0b70 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/10 22:25:33.218 Opal Liste...0xb5cd0b70 SIP PDU
> received:
> rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%et
> h0
> SIP/2.0 100 Trying
> CSeq: 1 INVITE
> Via: SIP/2.0/UDP
> 10.11.12.22:5060;received=203.206.207.88;branch=z9hG4bKaaf7d7de-bd02-e01
> 1-9de3-000c2984a855;rport=39236
> From: "root"
> <sip:<my_username>@sip.nsw.iinet.net.au>;tag=0665d7de-bd02-e011-9de3-000
> c2984a855
> Call-ID: 866cd7de-bd02-e011-9de3-000c2984a855@xxxxxxxxxxxxxxxxxxx
> To: <sip:0296400300@xxxxxxxxxxxxxxxxxxxx>
>
>
> 2010/12/10 22:25:33.218 Opal Liste...0xb5cd0b70 SIP Queueing
> PDU "1
> INVITE <100>", transaction=z9hG4bKaaf7d7de-bd02-e011-9de3-000c2984a855,
> token=0665d7de-bd02-e011-9de3-000c2984a855
> 2010/12/10 22:25:33.218 Opal Liste...0xb5cd0b70 PWLib File
> handle high
> water mark set: 38 Thread unblock pipe
> 2010/12/10 22:25:33.218 Opal Liste...0xb5cd0b70 PTLib Thread
> high
> water mark set: 14
> 2010/12/10 22:25:33.218 Opal Liste...0xb5cd0b70 Opal
> Transport clean
> up on termination
> 2010/12/10 22:25:33.218 Opal Liste...0xb5cd0b70 Opal
> Transport Close
> 2010/12/10 22:25:33.218 Opal Liste...0xb5cd0b70 PWLib File
> handle high
> water mark set: 39 PUDPSocket
> 2010/12/10 22:25:33.218 Opal Liste...0xb5cd0b70 Opal Deleted
> transport
> udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
> 2010/12/10 22:25:33.219 Opal Liste...0xb5cd0b70 OpalUDP
> Binding to
> interface: 10.11.12.22:5060
> 2010/12/10 22:25:33.219 Opal Liste...0xb5cd0b70 SIP Waiting
> for PDU on
> udp$203.55.231.194:5060<if=udp$10.11.12.22:5060>
> 2010/12/10 22:25:33.219 Opal Liste...0xb5cd0b70 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/10 22:25:33.219 Opal Liste...0xb5cd0b70 SIP PDU
> received:
> rem=udp$203.55.231.194:5060,local=udp$10.11.12.22:5060,if=10.11.12.22%et
> h0
> SIP/2.0 403 Forbidden
> CSeq: 1 INVITE
> Via: SIP/2.0/UDP
> 10.11.12.22:5060;received=203.206.207.88;branch=z9hG4bKaaf7d7de-bd02-e01
> 1-9de3-000c2984a855;rport=39236
> From: "root"
> <sip:<my_username>@sip.nsw.iinet.net.au>;tag=06