Re: [Ekiga-list] No incoming traffic problem
- From: Aramil <aramil freemail gr>
- To: Ekiga mailing list <ekiga-list gnome org>
- Subject: Re: [Ekiga-list] No incoming traffic problem
- Date: Sat, 06 Nov 2010 01:55:14 +0200
Ok so I opened Ekiga, made a call from my cellphone to my VoIP account
and here is the output using -d 4 2.
2010/11/06 01:11:05.406 0:00.046
Version 3.2.6 by on Unix Linux (2.6.32-25-generic-i686) with PTLib
(v2.6.5) at 2010/11/6 1:11:05.406
2010/11/06 01:11:05.406 0:00.046
Ekiga git revision: unknown
2010/11/06 01:11:05.407 0:00.047
Ekiga registered on D-Bus: org.ekiga.Ekiga
2010/11/06 01:11:05.407 0:00.047
PWLib File handle high water mark set: 17 Thread unblock pipe
2010/11/06 01:11:05.407 0:00.047
PTLib Thread high water mark set: 2
2010/11/06 01:11:05.407 0:00.047
PWLib File handle high water mark set: 19 Thread unblock pipe
2010/11/06 01:11:05.407 0:00.048
PTLib Thread high water mark set: 3
2010/11/06 01:11:05.407 0:00.048
PWLib File handle high water mark set: 21 Thread unblock pipe
2010/11/06 01:11:05.408 0:00.048
PWLib File handle high water mark set: 23 Thread unblock pipe
2010/11/06 01:11:05.408 0:00.049
PTLib Thread high water mark set: 4
2010/11/06 01:11:05.409 0:00.049
HalManager_dbus Initialising HAL Manager
2010/11/06 01:11:05.410 0:00.050
HalManager_dbus Populating device list
2010/11/06 01:11:05.497 0:00.137
HalManager_dbus Populated device list with 32 devices
2010/11/06 01:11:05.497 0:00.138
HalManager_dbus Populating interface list
2010/11/06 01:11:05.498 0:00.138
HalManager_dbus Populating full interface list failed - Method
"getDevices" with signature "" on interface
"org.freedesktop.NetworkManager" doesn't exist
2010/11/06 01:11:05.508 0:00.149
Detecting V4L2 devices
2010/11/06 01:11:05.509 0:00.149
Unable to detect v4l2 directory
2010/11/06 01:11:05.519 0:00.159
PWLib File handle high water mark set: 28 Thread unblock pipe
2010/11/06 01:11:05.519 0:00.159
PTLib Thread high water mark set: 5
2010/11/06 01:11:05.519 0:00.159
OpalMan Created manager.
2010/11/06 01:11:05.519 0:00.159
OpalMan Attached endpoint with prefix pc
2010/11/06 01:11:05.519 0:00.159
OpalEP Created endpoint: pc
2010/11/06 01:11:05.564 0:00.204
PCSS Created PC sound system endpoint.
Players:
Default
SB Live! 5.1
SB Live! 5.1 (1)
SB Live! 5.1 (2)
HDA Intel
HDA Intel (1)
EKIGA
*.wav
/dev/dsp
/dev/dsp1
Recorders:
Default
SB Live! 5.1
SB Live! 5.1 (1)
SB Live! 5.1 (2)
HDA Intel
HDA Intel (1)
EKIGA
*.wav
/dev/dsp
/dev/dsp1
2010/11/06 01:11:05.564 0:00.205
OPAL SetMediaFormatOrder()
2010/11/06 01:11:05.564 0:00.205
OPAL SetMediaFormatMask()
2010/11/06 01:11:05.565 0:00.205
OpalMan Attached endpoint with prefix sip
2010/11/06 01:11:05.565 0:00.205
OpalEP Created endpoint: sip
2010/11/06 01:11:05.565 0:00.205
PWLib File handle high water mark set: 29 PUDPSocket
2010/11/06 01:11:05.565 0:00.205
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)
200.95.160.253 [fe:80:00:00:00:00:00:00:02:25:22:ff:fe:12:42:14]
<00-25-22-12-42-14> (eth0)
2010/11/06 01:11:05.565 0:00.205
PWLib File handle high water mark set: 30 Thread unblock pipe
2010/11/06 01:11:05.565 0:00.205
PTLib Thread high water mark set: 6
2010/11/06 01:11:05.565 0:00.205 Network In...0xb7472b70
IfaceMon Started interface monitor thread.
2010/11/06 01:11:05.565 0:00.205
PWLib File handle high water mark set: 32 Thread unblock pipe
2010/11/06 01:11:05.565 0:00.205
PTLib Thread high water mark set: 7
2010/11/06 01:11:05.565 0:00.205 Network In...0xb7472b70
PWLib File handle high water mark set: 33 PUDPSocket
2010/11/06 01:11:05.565 0:00.206
OpalMan Attached endpoint with prefix sips
2010/11/06 01:11:05.565 0:00.206
SIP Created endpoint.
2010/11/06 01:11:05.566 0:00.206
MonSock Created socket bundle for all interfaces.
2010/11/06 01:11:05.566 0:00.206
PWLib File handle high water mark set: 34 PUDPSocket
2010/11/06 01:11:05.566 0:00.206
MonSock Created bundled UDP socket 200.95.160.253:5060
2010/11/06 01:11:05.566 0:00.206
PWLib File handle high water mark set: 36 Thread unblock pipe
2010/11/06 01:11:05.566 0:00.206
PTLib Thread high water mark set: 8
2010/11/06 01:11:05.566 0:00.206 Opal Liste...0xb72beb70
Listen Started listening thread on udp$*:5060
2010/11/06 01:11:05.566 0:00.206
OpalMan Added route "sip:.*=pc:*"
2010/11/06 01:11:05.566 0:00.206
OpalMan Added route "pc:.*=sip:<da>"
2010/11/06 01:11:05.566 0:00.206
OpalMan Attached endpoint with prefix h323
2010/11/06 01:11:05.566 0:00.206
OpalEP Created endpoint: h323
2010/11/06 01:11:05.566 0:00.206
OpalMan Attached endpoint with prefix h323s
2010/11/06 01:11:05.566 0:00.206
H323 Created endpoint.
2010/11/06 01:11:05.566 0:00.206
PWLib File handle high water mark set: 37 PTCPSocket
2010/11/06 01:11:05.566 0:00.207
PWLib File handle high water mark set: 39 Thread unblock pipe
2010/11/06 01:11:05.566 0:00.207
PTLib Thread high water mark set: 9
2010/11/06 01:11:05.566 0:00.207 Opal Liste...0xb727db70
Listen Started listening thread on tcp$*:1720
2010/11/06 01:11:05.566 0:00.207
OpalMan Added route "h323:.*=pc:<db>"
2010/11/06 01:11:05.566 0:00.207 Opal Liste...0xb727db70
Listen Waiting on socket accept on tcp$*:1720
2010/11/06 01:11:05.567 0:00.207
OpalMan Added route "pc:.*=h323:<da>"
2010/11/06 01:11:05.573 0:00.213
MediaFormat Removing codecs
GSM-06.10,G.726-16k,G.726-24k,G.726-32k,G.726-40k,theora,H.261,H.261-CIF,H.261-QCIF
2010/11/06 01:11:05.573 0:00.213
OPAL
SetMediaFormatMask(PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,G.722-64k,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,PCM-16,G.711-ALaw-64k,G.711-uLaw-64k,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz)
2010/11/06 01:11:05.573 0:00.213
OPAL
SetMediaFormatOrder(GSM-06.10,G.726-16k,G.726-24k,G.726-32k,G.726-40k,theora,H.261,H.261-CIF,H.261-QCIF)
2010/11/06 01:11:05.579 0:00.219
MediaFormat Removing codecs
GSM-06.10,G.726-16k,G.726-24k,G.726-32k,G.726-40k,theora,H.261,H.261-CIF,H.261-QCIF
2010/11/06 01:11:05.579 0:00.219
OPAL
SetMediaFormatMask(PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,G.722-64k,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,PCM-16,G.711-ALaw-64k,G.711-uLaw-64k,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz)
2010/11/06 01:11:05.579 0:00.219
OPAL
SetMediaFormatOrder(GSM-06.10,G.726-16k,G.726-24k,G.726-32k,G.726-40k,theora,H.261,H.261-CIF,H.261-QCIF)
2010/11/06 01:11:05.588 0:00.228
PWLib File handle high water mark set: 41 Thread unblock pipe
2010/11/06 01:11:05.588 0:00.229
PTLib Thread high water mark set: 10
2010/11/06 01:11:05.588 0:00.229 StunDetector:0xb723cb70
PWLib File handle high water mark set: 43 PUDPSocket
2010/11/06 01:11:05.624 0:00.264
PWLib File handle high water mark set: 54 PUDPSocket
2010/11/06 01:11:05.624 0:00.264
SIP Changing SUBSCRIBE handler from Unavailable to Subscribing,
target=sip:500 ekiga net, id=b8d97ba2-9fe7-df11-94e6-002522124214 test
2010/11/06 01:11:05.624 0:00.264
DNS SRV Lookup ekiga.net service _sip._udp
2010/11/06 01:11:05.728 0:00.369
SIP No SRV record found.
2010/11/06 01:11:05.737 0:00.377
OpalUDP Binding to interface: 0.0.0.0:5060
2010/11/06 01:11:05.737 0:00.377
SIP Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2010/11/06 01:11:05.737 0:00.377
OpalUDP Started connect to 86.64.162.35:5060
2010/11/06 01:11:05.737 0:00.378
OpalUDP Writing to interface 0 - "200.95.160.253%eth0"
2010/11/06 01:11:05.738 0:00.378
OpalMan Listener interfaces: associated transport=None
udp$200.95.160.253:5060
2010/11/06 01:11:10.603 0:05.244 StunDetector:0xb723cb70
STUN Poll of secondary server 132.177.123.14:3479 failed, NAT
partially blocked by firwall rules.
2010/11/06 01:11:10.603 0:05.244 StunDetector:0xb723cb70
PWLib File handle low water mark set: 43 PUDPSocket
2010/11/06 01:11:10.744 0:05.384
STUN Poll of secondary server 132.177.123.14:3479 failed, NAT
partially blocked by firwall rules.
2010/11/06 01:11:10.802 0:05.443 StunDetector:0xb723cb70
OPAL STUN server "stunserver.org" replies Partially Blocked, external
IP 178.128.41.154
2010/11/06 01:11:10.945 0:05.585
PWLib File handle low water mark set: 40 PUDPSocket
2010/11/06 01:11:10.945 0:05.585
SIP Transaction created.
2010/11/06 01:11:10.947 0:05.587
DNS SRV Lookup ekiga.net service _sip._udp
2010/11/06 01:11:11.037 0:05.677
SIP No SRV record found.
2010/11/06 01:11:11.037 0:05.677
SIP Transaction remote address is udp$ekiga.net:5060
2010/11/06 01:11:11.037 0:05.678
SIP Sending PDU (598 bytes) to:
rem=udp$86.64.162.35:5060,local=udp$200.95.160.253:5060,if=200.95.160.253%eth0
SUBSCRIBE sip:500 ekiga net SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP
178.128.41.154:5060;branch=z9hG4bKcceba7a5-9fe7-df11-94e6-002522124214;rport
User-Agent: Ekiga/3.2.6
From: "test"
<sip:test 178 128 41 154>;tag=36bea7a5-9fe7-df11-94e6-002522124214
Call-ID: b8d97ba2-9fe7-df11-94e6-002522124214 test
To: <sip:500 ekiga net>
Contact: <sip:test 178 128 41 154>
Accept: application/pidf+xml
Allow:
INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 300
Event: presence
Content-Length: 0
Max-Forwards: 70
2010/11/06 01:11:11.038 0:05.678
OpalUDP Setting interface to 200.95.160.253%eth0
2010/11/06 01:11:11.038 0:05.678
SIP Transaction timers set: retry=0.500, completion=6.000
2010/11/06 01:11:11.038 0:05.678
SIP Changing SUBSCRIBE handler from Unavailable to Subscribing,
target=sip:500 ekiga net, id=bcf2b5a5-9fe7-df11-94e6-002522124214 test
2010/11/06 01:11:11.038 0:05.678
DNS SRV Lookup ekiga.net service _sip._udp
2010/11/06 01:11:11.133 0:05.773
SIP No SRV record found.
2010/11/06 01:11:11.133 0:05.773
OpalUDP Binding to interface: 0.0.0.0:5060
2010/11/06 01:11:11.133 0:05.773
SIP Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2010/11/06 01:11:11.134 0:05.774
OpalUDP Started connect to 86.64.162.35:5060
2010/11/06 01:11:11.134 0:05.774
OpalUDP Writing to interface 0 - "200.95.160.253%eth0"
2010/11/06 01:11:11.134 0:05.774
OpalMan Listener interfaces: associated transport=None
udp$200.95.160.253:5060
2010/11/06 01:11:11.135 0:05.775
SIP Transaction created.
2010/11/06 01:11:11.137 0:05.777
DNS SRV Lookup ekiga.net service _sip._udp
2010/11/06 01:11:11.149 0:05.789 Opal Liste...0xb72beb70
OpalUDP Binding to interface: 200.95.160.253:5060
2010/11/06 01:11:11.149 0:05.789 Opal Liste...0xb72beb70
SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$200.95.160.253:5060>
2010/11/06 01:11:11.149 0:05.789 Opal Liste...0xb72beb70
SIP PDU received:
rem=udp$86.64.162.35:5060,local=udp$200.95.160.253:5060,if=200.95.160.253%eth0
SIP/2.0 489 Event Not Supported
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP
178.128.41.154:5060;branch=z9hG4bKcceba7a5-9fe7-df11-94e6-002522124214;rport=55458
Server: Kamailio (1.5.3-notls (i386/linux))
From: "test"
<sip:test 192 168 1 65>;tag=36bea7a5-9fe7-df11-94e6-002522124214
Call-ID: b8d97ba2-9fe7-df11-94e6-002522124214 test
To: <sip:500 ekiga net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.6210
Content-Length: 0
2010/11/06 01:11:11.150 0:05.790 Opal Liste...0xb72beb70
SIP Queueing PDU "2 SUBSCRIBE <489>",
transaction=z9hG4bKcceba7a5-9fe7-df11-94e6-002522124214,
token=b8d97ba2-9fe7-df11-94e6-002522124214 test
2010/11/06 01:11:11.150 0:05.790 Opal Liste...0xb72beb70
Opal Transport clean up on termination
2010/11/06 01:11:11.150 0:05.790 Opal Liste...0xb72beb70
Opal Transport Close
2010/11/06 01:11:11.150 0:05.790 Pool:0xb723cb70
SIP Handling PDU "2 SUBSCRIBE <489>" for
transaction=z9hG4bKcceba7a5-9fe7-df11-94e6-002522124214
2010/11/06 01:11:11.150 0:05.790 Pool:0xb723cb70
SIP SUBSCRIBE transaction
id=z9hG4bKcceba7a5-9fe7-df11-94e6-002522124214 completed.
2010/11/06 01:11:11.150 0:05.790 Pool:0xb723cb70
OpalUDP Setting interface to 200.95.160.253%eth0
2010/11/06 01:11:11.150 0:05.790 Opal Liste...0xb72beb70
PWLib File handle high water mark set: 55 PUDPSocket
2010/11/06 01:11:11.150 0:05.790 Pool:0xb723cb70
SIP Not retrying SUBSCRIBE due to error response 489 Bad Event
2010/11/06 01:11:11.150 0:05.790 Pool:0xb723cb70
SIP Changing SUBSCRIBE handler from Subscribing to Unsubscribed,
target=sip:500 ekiga net, id=b8d97ba2-9fe7-df11-94e6-002522124214 test
2010/11/06 01:11:11.150 0:05.790 Opal Liste...0xb72beb70
Opal Deleted transport udp$86.64.162.35:5060<if=udp$200.95.160.253:5060>
2010/11/06 01:11:11.150 0:05.790 Pool:0xb723cb70
SIP Handled PDU "2 SUBSCRIBE <489>"
2010/11/06 01:11:11.240 0:05.881
SIP No SRV record found.
2010/11/06 01:11:11.241 0:05.881
SIP Transaction remote address is udp$ekiga.net:5060
2010/11/06 01:11:11.241 0:05.881
PWLib File handle low water mark set: 40 PUDPSocket
2010/11/06 01:11:11.241 0:05.881
SIP Sending PDU (599 bytes) to:
rem=udp$86.64.162.35:5060,local=udp$200.95.160.253:5060,if=200.95.160.253%eth0
SUBSCRIBE sip:500 ekiga net SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP
178.128.41.154:5060;branch=z9hG4bK08e8c4a5-9fe7-df11-94e6-002522124214;rport
User-Agent: Ekiga/3.2.6
From: "test"
<sip:test 178 128 41 154>;tag=52bcc4a5-9fe7-df11-94e6-002522124214
Call-ID: bcf2b5a5-9fe7-df11-94e6-002522124214 test
To: <sip:500 ekiga net>
Contact: <sip:500 178 128 41 154>
Accept: application/dialog-info+xml
Allow:
INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 300
Event: dialog;sla;ma
Content-Length: 0
Max-Forwards: 70
2010/11/06 01:11:11.241 0:05.881
OpalUDP Setting interface to 200.95.160.253%eth0
2010/11/06 01:11:11.241 0:05.881
SIP Transaction timers set: retry=0.500, completion=6.000
2010/11/06 01:11:11.242 0:05.882
SIP Changing SUBSCRIBE handler from Unavailable to Subscribing,
target=sip:501 ekiga net, id=eaffd4a5-9fe7-df11-94e6-002522124214 test
2010/11/06 01:11:11.242 0:05.882
DNS SRV Lookup ekiga.net service _sip._udp
2010/11/06 01:11:11.348 0:05.989
SIP No SRV record found.
2010/11/06 01:11:11.349 0:05.989
OpalUDP Binding to interface: 0.0.0.0:5060
2010/11/06 01:11:11.349 0:05.989
SIP Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2010/11/06 01:11:11.349 0:05.989
OpalUDP Started connect to 86.64.162.35:5060
2010/11/06 01:11:11.349 0:05.989
OpalUDP Writing to interface 0 - "200.95.160.253%eth0"
2010/11/06 01:11:11.349 0:05.990
OpalMan Listener interfaces: associated transport=None
udp$200.95.160.253:5060
2010/11/06 01:11:11.350 0:05.991
SIP Transaction created.
2010/11/06 01:11:11.353 0:05.993
DNS SRV Lookup ekiga.net service _sip._udp
2010/11/06 01:11:11.363 0:06.003 Opal Liste...0xb72beb70
OpalUDP Binding to interface: 200.95.160.253:5060
2010/11/06 01:11:11.363 0:06.004 Opal Liste...0xb72beb70
SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$200.95.160.253:5060>
2010/11/06 01:11:11.364 0:06.004 Opal Liste...0xb72beb70
SIP PDU received:
rem=udp$86.64.162.35:5060,local=udp$200.95.160.253:5060,if=200.95.160.253%eth0
SIP/2.0 489 Event Not Supported
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP
178.128.41.154:5060;branch=z9hG4bK08e8c4a5-9fe7-df11-94e6-002522124214;rport=55458
Server: Kamailio (1.5.3-notls (i386/linux))
From: "test"
<sip:test 192 168 1 65>;tag=52bcc4a5-9fe7-df11-94e6-002522124214
Call-ID: bcf2b5a5-9fe7-df11-94e6-002522124214 test
To: <sip:500 ekiga net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.7465
Content-Length: 0
2010/11/06 01:11:11.364 0:06.004 Opal Liste...0xb72beb70
SIP Queueing PDU "2 SUBSCRIBE <489>",
transaction=z9hG4bK08e8c4a5-9fe7-df11-94e6-002522124214,
token=bcf2b5a5-9fe7-df11-94e6-002522124214 test
2010/11/06 01:11:11.364 0:06.004 Opal Liste...0xb72beb70
Opal Transport clean up on termination
2010/11/06 01:11:11.364 0:06.004 Opal Liste...0xb72beb70
Opal Transport Close
2010/11/06 01:11:11.364 0:06.005 Pool:0xb723cb70
SIP Handling PDU "2 SUBSCRIBE <489>" for
transaction=z9hG4bK08e8c4a5-9fe7-df11-94e6-002522124214
2010/11/06 01:11:11.364 0:06.005 Pool:0xb723cb70
SIP SUBSCRIBE transaction
id=z9hG4bK08e8c4a5-9fe7-df11-94e6-002522124214 completed.
2010/11/06 01:11:11.364 0:06.005 Pool:0xb723cb70
OpalUDP Setting interface to 200.95.160.253%eth0
2010/11/06 01:11:11.364 0:06.005 Opal Liste...0xb72beb70
Opal Deleted transport udp$86.64.162.35:5060<if=udp$200.95.160.253:5060>
2010/11/06 01:11:11.365 0:06.005 Pool:0xb723cb70
SIP Not retrying SUBSCRIBE due to error response 489 Bad Event
2010/11/06 01:11:11.365 0:06.005 Pool:0xb723cb70
SIP Changing SUBSCRIBE handler from Subscribing to Unsubscribed,
target=sip:500 ekiga net, id=bcf2b5a5-9fe7-df11-94e6-002522124214 test
2010/11/06 01:11:11.365 0:06.005 Pool:0xb723cb70
SIP Handled PDU "2 SUBSCRIBE <489>"
2010/11/06 01:11:11.444 0:06.084
SIP No SRV record found.
2010/11/06 01:11:11.444 0:06.085
SIP Transaction remote address is udp$ekiga.net:5060
2010/11/06 01:11:11.445 0:06.085
SIP Sending PDU (598 bytes) to:
rem=udp$86.64.162.35:5060,local=udp$200.95.160.253:5060,if=200.95.160.253%eth0
SUBSCRIBE sip:501 ekiga net SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP
178.128.41.154:5060;branch=z9hG4bK92c9e5a5-9fe7-df11-94e6-002522124214;rport
User-Agent: Ekiga/3.2.6
From: "test"
<sip:test 178 128 41 154>;tag=d29de5a5-9fe7-df11-94e6-002522124214
Call-ID: eaffd4a5-9fe7-df11-94e6-002522124214 test
To: <sip:501 ekiga net>
Contact: <sip:test 178 128 41 154>
Accept: application/pidf+xml
Allow:
INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 300
Event: presence
Content-Length: 0
Max-Forwards: 70
2010/11/06 01:11:11.445 0:06.085
OpalUDP Setting interface to 200.95.160.253%eth0
2010/11/06 01:11:11.445 0:06.085
SIP Transaction timers set: retry=0.500, completion=6.000
2010/11/06 01:11:11.445 0:06.086
SIP Changing SUBSCRIBE handler from Unavailable to Subscribing,
target=sip:501 ekiga net, id=b416f4a5-9fe7-df11-94e6-002522124214 test
2010/11/06 01:11:11.445 0:06.086
DNS SRV Lookup ekiga.net service _sip._udp
2010/11/06 01:11:11.519 0:06.160 Opal Garbage:0xb74b3b70
SIP Deleting SIPSubscribeHandler sip:500 ekiga net
2010/11/06 01:11:11.520 0:06.160 Opal Garbage:0xb74b3b70
Opal Transport clean up on termination
2010/11/06 01:11:11.520 0:06.160 Opal Garbage:0xb74b3b70
Opal Transport Close
2010/11/06 01:11:11.520 0:06.160 Opal Garbage:0xb74b3b70
Opal Transport clean up on termination
2010/11/06 01:11:11.520 0:06.160 Opal Garbage:0xb74b3b70
Opal Transport Close
2010/11/06 01:11:11.520 0:06.160 Opal Garbage:0xb74b3b70
Opal Deleted transport udp$86.64.162.35:5060<if=udp$200.95.160.253:5060>
2010/11/06 01:11:11.520 0:06.160 Opal Garbage:0xb74b3b70
SIP Deleted handler.
2010/11/06 01:11:11.520 0:06.160 Opal Garbage:0xb74b3b70
SIP Deleting SIPSubscribeHandler sip:500 ekiga net
2010/11/06 01:11:11.520 0:06.160 Opal Garbage:0xb74b3b70
Opal Transport clean up on termination
2010/11/06 01:11:11.520 0:06.160 Opal Garbage:0xb74b3b70
Opal Transport Close
2010/11/06 01:11:11.520 0:06.160 Opal Garbage:0xb74b3b70
Opal Transport clean up on termination
2010/11/06 01:11:11.520 0:06.161 Opal Garbage:0xb74b3b70
Opal Transport Close
2010/11/06 01:11:11.520 0:06.161 Opal Garbage:0xb74b3b70
Opal Deleted transport udp$86.64.162.35:5060<if=udp$200.95.160.253:5060>
2010/11/06 01:11:11.521 0:06.161 Opal Garbage:0xb74b3b70
SIP Deleted handler.
2010/11/06 01:11:11.543 0:06.183
SIP No SRV record found.
2010/11/06 01:11:11.543 0:06.183
OpalUDP Binding to interface: 0.0.0.0:5060
2010/11/06 01:11:11.543 0:06.183
SIP Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2010/11/06 01:11:11.544 0:06.184
OpalUDP Started connect to 86.64.162.35:5060
2010/11/06 01:11:11.544 0:06.184
OpalUDP Writing to interface 0 - "200.95.160.253%eth0"
2010/11/06 01:11:11.544 0:06.184
OpalMan Listener interfaces: associated transport=None
udp$200.95.160.253:5060
2010/11/06 01:11:11.545 0:06.185
SIP Transaction created.
2010/11/06 01:11:11.547 0:06.187
DNS SRV Lookup ekiga.net service _sip._udp
2010/11/06 01:11:11.556 0:06.196 Opal Liste...0xb72beb70
OpalUDP Binding to interface: 200.95.160.253:5060
2010/11/06 01:11:11.556 0:06.196 Opal Liste...0xb72beb70
SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$200.95.160.253:5060>
2010/11/06 01:11:11.556 0:06.196 Opal Liste...0xb72beb70
SIP PDU received:
rem=udp$86.64.162.35:5060,local=udp$200.95.160.253:5060,if=200.95.160.253%eth0
SIP/2.0 489 Event Not Supported
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP
178.128.41.154:5060;branch=z9hG4bK92c9e5a5-9fe7-df11-94e6-002522124214;rport=55458
Server: Kamailio (1.5.3-notls (i386/linux))
From: "test"
<sip:test 192 168 1 65>;tag=d29de5a5-9fe7-df11-94e6-002522124214
Call-ID: eaffd4a5-9fe7-df11-94e6-002522124214 test
To: <sip:501 ekiga net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.a419
Content-Length: 0
2010/11/06 01:11:11.556 0:06.197 Opal Liste...0xb72beb70
SIP Queueing PDU "2 SUBSCRIBE <489>",
transaction=z9hG4bK92c9e5a5-9fe7-df11-94e6-002522124214,
token=eaffd4a5-9fe7-df11-94e6-002522124214 test
2010/11/06 01:11:11.556 0:06.197 Opal Liste...0xb72beb70
Opal Transport clean up on termination
2010/11/06 01:11:11.557 0:06.197 Opal Liste...0xb72beb70
Opal Transport Close
2010/11/06 01:11:11.557 0:06.197 Pool:0xb723cb70
SIP Handling PDU "2 SUBSCRIBE <489>" for
transaction=z9hG4bK92c9e5a5-9fe7-df11-94e6-002522124214
2010/11/06 01:11:11.557 0:06.197 Pool:0xb723cb70
SIP SUBSCRIBE transaction
id=z9hG4bK92c9e5a5-9fe7-df11-94e6-002522124214 completed.
2010/11/06 01:11:11.557 0:06.197 Pool:0xb723cb70
OpalUDP Setting interface to 200.95.160.253%eth0
2010/11/06 01:11:11.557 0:06.197 Opal Liste...0xb72beb70
Opal Deleted transport udp$86.64.162.35:5060<if=udp$200.95.160.253:5060>
2010/11/06 01:11:11.557 0:06.197 Pool:0xb723cb70
SIP Not retrying SUBSCRIBE due to error response 489 Bad Event
2010/11/06 01:11:11.557 0:06.197 Pool:0xb723cb70
SIP Changing SUBSCRIBE handler from Subscribing to Unsubscribed,
target=sip:501 ekiga net, id=eaffd4a5-9fe7-df11-94e6-002522124214 test
2010/11/06 01:11:11.557 0:06.197 Pool:0xb723cb70
SIP Handled PDU "2 SUBSCRIBE <489>"
2010/11/06 01:11:11.641 0:06.281
SIP No SRV record found.
2010/11/06 01:11:11.641 0:06.281
SIP Transaction remote address is udp$ekiga.net:5060
2010/11/06 01:11:11.641 0:06.281
SIP Sending PDU (599 bytes) to:
rem=udp$86.64.162.35:5060,local=udp$200.95.160.253:5060,if=200.95.160.253%eth0
SUBSCRIBE sip:501 ekiga net SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP
178.128.41.154:5060;branch=z9hG4bKc47403a6-9fe7-df11-94e6-002522124214;rport
User-Agent: Ekiga/3.2.6
From: "test"
<sip:test 178 128 41 154>;tag=684903a6-9fe7-df11-94e6-002522124214
Call-ID: b416f4a5-9fe7-df11-94e6-002522124214 test
To: <sip:501 ekiga net>
Contact: <sip:501 178 128 41 154>
Accept: application/dialog-info+xml
Allow:
INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 300
Event: dialog;sla;ma
Content-Length: 0
Max-Forwards: 70
2010/11/06 01:11:11.641 0:06.282
OpalUDP Setting interface to 200.95.160.253%eth0
2010/11/06 01:11:11.641 0:06.282
SIP Transaction timers set: retry=0.500, completion=6.000
2010/11/06 01:11:11.642 0:06.282
VideoOutputCoreConfBridge Updating video view
2010/11/06 01:11:11.642 0:06.282
VideoOutputCoreConfBridge Updating zoom
2010/11/06 01:11:11.642 0:06.282
VideoOutputCoreConfBridge Updating Video Settings
2010/11/06 01:11:11.643 0:06.283
VideoOutputCoreConfBridge Updating Video Settings
2010/11/06 01:11:11.643 0:06.283
VideoOutputCoreConfBridge Updating Video Settings
2010/11/06 01:11:11.643 0:06.283
VideoOutputCoreConfBridge Updating Video Settings
2010/11/06 01:11:11.643 0:06.283
VidInputCoreConfBridge Updating preview size and fps
2010/11/06 01:11:11.643 0:06.283
VidInputCore Setting new preview config: 320x240/30
2010/11/06 01:11:11.645 0:06.285
VidInputCoreConfBridge Updating preview size and fps
2010/11/06 01:11:11.645 0:06.285
VidInputCore Setting new preview config: 320x240/30
2010/11/06 01:11:11.647 0:06.287
VidInputCoreConfBridge Updating device
2010/11/06 01:11:11.647 0:06.288
VidInputCore Setting device: Moving Logo (Moving Logo/Moving Logo)
2010/11/06 01:11:11.647 0:06.288
GMVideoInputManager_mlogo Setting Device Moving Logo
2010/11/06 01:11:11.647 0:06.288
VidInputCoreConfBridge Updating device
2010/11/06 01:11:11.647 0:06.288
VidInputCore Setting device: Moving Logo (Moving Logo/Moving Logo)
2010/11/06 01:11:11.648 0:06.288
GMVideoInputManager_mlogo Setting Device Moving Logo
2010/11/06 01:11:11.648 0:06.288
VidInputCoreConfBridge Updating device
2010/11/06 01:11:11.648 0:06.288
VidInputCore Setting device: Moving Logo (Moving Logo/Moving Logo)
2010/11/06 01:11:11.648 0:06.288
GMVideoInputManager_mlogo Setting Device Moving Logo
2010/11/06 01:11:11.648 0:06.288
VidInputCoreConfBridge Updating image
2010/11/06 01:11:11.648 0:06.288
VidInputCoreConfBridge Updating preview
2010/11/06 01:11:11.648 0:06.288
VidInputCore Stopping Preview
2010/11/06 01:11:11.648 0:06.288
AudioOutputCoreConfBridge Updating device
2010/11/06 01:11:11.648 0:06.288
AudioOutputCore Setting device[0]: SB Live! 5.1 (PTLIB/ALSA)
2010/11/06 01:11:11.648 0:06.288
GMAudioOutputManager_ptlib Setting Device[0] SB Live! 5.1 (PTLIB/ALSA)
2010/11/06 01:11:11.648 0:06.289
AudioOutputCoreConfBridge Updating device
2010/11/06 01:11:11.648 0:06.289
AudioOutputCore Setting device[1]: SILENT (Ekiga/Ekiga)
2010/11/06 01:11:11.649 0:06.289
GMAudioOutputManager_null Setting Device[1] SILENT (Ekiga/Ekiga)
2010/11/06 01:11:11.650 0:06.290
AudioInputCoreConfBridge Updating device
2010/11/06 01:11:11.650 0:06.290
AudioInputCore Setting device: HDA Intel (1) (PTLIB/ALSA)
2010/11/06 01:11:11.650 0:06.290
GMAudioInputManager_ptlib Setting Device HDA Intel (1) (PTLIB/ALSA)
2010/11/06 01:11:11.723 0:06.363
Ekiga version 3.2.6
2010/11/06 01:11:11.723 0:06.363 OPAL
version 3.6.6
2010/11/06 01:11:11.723 0:06.363
PTLIB version 2.6.5
2010/11/06 01:11:11.723 0:06.363
GNOME support disabled
2010/11/06 01:11:11.723 0:06.363
Accelerated rendering support enabled
2010/11/06 01:11:11.723 0:06.363 DBUS
support enabled
2010/11/06 01:11:11.723 0:06.363
GConf support enabled
2010/11/06 01:11:11.723 0:06.363
ESound support disabled
2010/11/06 01:11:11.758 0:06.398 Opal Liste...0xb72beb70
OpalUDP Binding to interface: 200.95.160.253:5060
2010/11/06 01:11:11.758 0:06.398 Opal Liste...0xb72beb70
SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$200.95.160.253:5060>
2010/11/06 01:11:11.758 0:06.398 Opal Liste...0xb72beb70
SIP PDU received:
rem=udp$86.64.162.35:5060,local=udp$200.95.160.253:5060,if=200.95.160.253%eth0
SIP/2.0 489 Event Not Supported
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP
178.128.41.154:5060;branch=z9hG4bKc47403a6-9fe7-df11-94e6-002522124214;rport=55458
Server: Kamailio (1.5.3-notls (i386/linux))
From: "test"
<sip:test 192 168 1 65>;tag=684903a6-9fe7-df11-94e6-002522124214
Call-ID: b416f4a5-9fe7-df11-94e6-002522124214 test
To: <sip:501 ekiga net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.766d
Content-Length: 0
2010/11/06 01:11:11.758 0:06.398 Opal Liste...0xb72beb70
SIP Queueing PDU "2 SUBSCRIBE <489>",
transaction=z9hG4bKc47403a6-9fe7-df11-94e6-002522124214,
token=b416f4a5-9fe7-df11-94e6-002522124214 test
2010/11/06 01:11:11.758 0:06.399 Opal Liste...0xb72beb70
Opal Transport clean up on termination
2010/11/06 01:11:11.758 0:06.399 Opal Liste...0xb72beb70
Opal Transport Close
2010/11/06 01:11:11.758 0:06.399 Pool:0xb723cb70
SIP Handling PDU "2 SUBSCRIBE <489>" for
transaction=z9hG4bKc47403a6-9fe7-df11-94e6-002522124214
2010/11/06 01:11:11.758 0:06.399 Pool:0xb723cb70
SIP SUBSCRIBE transaction
id=z9hG4bKc47403a6-9fe7-df11-94e6-002522124214 completed.
2010/11/06 01:11:11.758 0:06.399 Opal Liste...0xb72beb70
Opal Deleted transport udp$86.64.162.35:5060<if=udp$200.95.160.253:5060>
2010/11/06 01:11:11.759 0:06.399 Pool:0xb723cb70
OpalUDP Setting interface to 200.95.160.253%eth0
2010/11/06 01:11:11.759 0:06.399 Pool:0xb723cb70
SIP Not retrying SUBSCRIBE due to error response 489 Bad Event
2010/11/06 01:11:11.759 0:06.399 Pool:0xb723cb70
SIP Changing SUBSCRIBE handler from Subscribing to Unsubscribed,
target=sip:501 ekiga net, id=b416f4a5-9fe7-df11-94e6-002522124214 test
2010/11/06 01:11:11.759 0:06.399 Pool:0xb723cb70
SIP Handled PDU "2 SUBSCRIBE <489>"
2010/11/06 01:11:12.150 0:06.790 Housekeeper:0xb72ffb70
SIP Set state Terminated_Success for SUBSCRIBE transaction
id=z9hG4bKcceba7a5-9fe7-df11-94e6-002522124214
2010/11/06 01:11:12.365 0:07.005 Housekeeper:0xb72ffb70
SIP Set state Terminated_Success for SUBSCRIBE transaction
id=z9hG4bK08e8c4a5-9fe7-df11-94e6-002522124214
2010/11/06 01:11:12.521 0:07.161 Opal Garbage:0xb74b3b70
SIP Transaction id=z9hG4bK08e8c4a5-9fe7-df11-94e6-002522124214 destroyed.
2010/11/06 01:11:12.521 0:07.161 Opal Garbage:0xb74b3b70
SIP Transaction id=z9hG4bKcceba7a5-9fe7-df11-94e6-002522124214 destroyed.
2010/11/06 01:11:12.521 0:07.161 Opal Garbage:0xb74b3b70
SIP Deleting SIPSubscribeHandler sip:501 ekiga net
2010/11/06 01:11:12.521 0:07.161 Opal Garbage:0xb74b3b70
Opal Transport clean up on termination
2010/11/06 01:11:12.521 0:07.161 Opal Garbage:0xb74b3b70
Opal Transport Close
2010/11/06 01:11:12.521 0:07.162 Opal Garbage:0xb74b3b70
Opal Transport clean up on termination
2010/11/06 01:11:12.521 0:07.162 Opal Garbage:0xb74b3b70
Opal Transport Close
2010/11/06 01:11:12.521 0:07.162 Opal Garbage:0xb74b3b70
Opal Deleted transport udp$86.64.162.35:5060<if=udp$200.95.160.253:5060>
2010/11/06 01:11:12.522 0:07.162 Opal Garbage:0xb74b3b70
SIP Deleted handler.
2010/11/06 01:11:12.522 0:07.162 Opal Garbage:0xb74b3b70
SIP Deleting SIPSubscribeHandler sip:501 ekiga net
2010/11/06 01:11:12.522 0:07.162 Opal Garbage:0xb74b3b70
Opal Transport clean up on termination
2010/11/06 01:11:12.522 0:07.162 Opal Garbage:0xb74b3b70
Opal Transport Close
2010/11/06 01:11:12.522 0:07.162 Opal Garbage:0xb74b3b70
Opal Transport clean up on termination
2010/11/06 01:11:12.522 0:07.162 Opal Garbage:0xb74b3b70
Opal Transport Close
2010/11/06 01:11:12.522 0:07.162 Opal Garbage:0xb74b3b70
Opal Deleted transport udp$86.64.162.35:5060<if=udp$200.95.160.253:5060>
2010/11/06 01:11:12.522 0:07.163 Opal Garbage:0xb74b3b70
SIP Deleted handler.
2010/11/06 01:11:12.557 0:07.197 Housekeeper:0xb72ffb70
SIP Set state Terminated_Success for SUBSCRIBE transaction
id=z9hG4bK92c9e5a5-9fe7-df11-94e6-002522124214
2010/11/06 01:11:12.759 0:07.399 Housekeeper:0xb72ffb70
SIP Set state Terminated_Success for SUBSCRIBE transaction
id=z9hG4bKc47403a6-9fe7-df11-94e6-002522124214
2010/11/06 01:11:13.397 0:08.038
PWLib File handle high water mark set: 57 Thread unblock pipe
2010/11/06 01:11:13.398 0:08.038
PTLib Thread high water mark set: 11
2010/11/06 01:11:13.398 0:08.038 subscriber:0xb53a5b70
SIP Start REGISTER
aor=208426 sip omnivoice eu
registrar=sip.omnivoice.eu
contact=
authID=208426
realm=
expire=3600
restore=30
minRetry=0.000
maxRetry=0.000
2010/11/06 01:11:13.398 0:08.038 subscriber:0xb53a5b70
SIP Changing REGISTER handler from Unavailable to Subscribing,
target=sip:208426 sip omnivoice eu,
id=24031ea7-9fe7-df11-94e6-002522124214 test
2010/11/06 01:11:13.398 0:08.038 subscriber:0xb53a5b70
DNS SRV Lookup sip.omnivoice.eu service _sip._udp
2010/11/06 01:11:13.464 0:08.104 subscriber:0xb53a5b70
SIP Attempting SRV record entry 0: 78.46.99.163:5060
2010/11/06 01:11:13.464 0:08.104 subscriber:0xb53a5b70
OpalUDP Binding to interface: 0.0.0.0:5060
2010/11/06 01:11:13.464 0:08.104 subscriber:0xb53a5b70
PWLib File handle high water mark set: 58 PUDPSocket
2010/11/06 01:11:13.464 0:08.104 subscriber:0xb53a5b70
SIP Created transport udp$78.46.99.163:5060<if=udp$*:5060>
2010/11/06 01:11:13.464 0:08.104 subscriber:0xb53a5b70
OpalUDP Started connect to 78.46.99.163:5060
2010/11/06 01:11:13.464 0:08.105 subscriber:0xb53a5b70
OpalUDP Writing to interface 0 - "200.95.160.253%eth0"
2010/11/06 01:11:13.523 0:08.163 Opal Garbage:0xb74b3b70
SIP Transaction id=z9hG4bK92c9e5a5-9fe7-df11-94e6-002522124214 destroyed.
2010/11/06 01:11:13.523 0:08.163 Opal Garbage:0xb74b3b70
SIP Transaction id=z9hG4bKc47403a6-9fe7-df11-94e6-002522124214 destroyed.
2010/11/06 01:11:13.661 0:08.301 subscriber:0xb53a5b70
OpalMan Listener interfaces: associated transport=udp$200.95.160.253:5060
udp$178.128.41.154:5060,udp$200.95.160.253:5060
2010/11/06 01:11:13.662 0:08.302 subscriber:0xb53a5b70
SIP Transaction created.
2010/11/06 01:11:13.663 0:08.303 subscriber:0xb53a5b70
DNS SRV Lookup sip.omnivoice.eu service _sip._udp
2010/11/06 01:11:13.707 0:08.347 subscriber:0xb53a5b70
SIP Attempting SRV record entry 0: 78.46.99.163:5060
2010/11/06 01:11:13.707 0:08.348 subscriber:0xb53a5b70
SIP Transaction remote address is udp$78.46.99.163:5060
2010/11/06 01:11:13.708 0:08.348 subscriber:0xb53a5b70
SIP Sending PDU (564 bytes) to:
rem=udp$78.46.99.163:5060,local=udp$200.95.160.253:5060,if=200.95.160.253%eth0
REGISTER sip:sip.omnivoice.eu SIP/2.0
CSeq: 1 REGISTER
Via: SIP/2.0/UDP
178.128.41.154:5060;branch=z9hG4bK4a7346a7-9fe7-df11-94e6-002522124214;rport
User-Agent: Ekiga/3.2.6
From: <sip:208426 sip omnivoice eu>;tag=ba081ea7-9fe7-df11-94e6-002522124214
Call-ID: 24031ea7-9fe7-df11-94e6-002522124214 test
To: <sip:208426 sip omnivoice eu>
Contact: <sip:208426 178 128 41 154>;q=1,
<sip:208426 200 95 160 253>;q=0.500
Allow:
INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 3600
Content-Length: 0
Max-Forwards: 70
2010/11/06 01:11:13.708 0:08.348 subscriber:0xb53a5b70
OpalUDP Setting interface to 200.95.160.253%eth0
2010/11/06 01:11:13.708 0:08.348 subscriber:0xb53a5b70
SIP Transaction timers set: retry=0.500, completion=6.000
2010/11/06 01:11:13.814 0:08.454 Opal Liste...0xb72beb70
PWLib File handle low water mark set: 55 PUDPSocket
2010/11/06 01:11:13.814 0:08.454 Opal Liste...0xb72beb70
OpalUDP Binding to interface: 200.95.160.253:5060
2010/11/06 01:11:13.814 0:08.454 Opal Liste...0xb72beb70
SIP Waiting for PDU on udp$78.46.99.163:5060<if=udp$200.95.160.253:5060>
2010/11/06 01:11:13.814 0:08.455 Opal Liste...0xb72beb70
SIP PDU received:
rem=udp$78.46.99.163:5060,local=udp$200.95.160.253:5060,if=200.95.160.253%eth0
SIP/2.0 401 Unauthorized
CSeq: 1 REGISTER
Via: SIP/2.0/UDP
192.168.1.65:5060;branch=z9hG4bK4a7346a7-9fe7-df11-94e6-002522124214;rport;received=192.168.1.65
Server: OmniVoice
From: <sip:208426 sip omnivoice eu>;tag=ba081ea7-9fe7-df11-94e6-002522124214
Call-ID: 24031ea7-9fe7-df11-94e6-002522124214 test
Supported: replaces, timer
To: <sip:208426 sip omnivoice eu>;tag=as45c2435e
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Content-Length: 0
WWW-Authenticate: Digest algorithm=MD5, realm="omnivoice.eu",
nonce="2f369892"
2010/11/06 01:11:13.815 0:08.455 Opal Liste...0xb72beb70
SIP Queueing PDU "1 REGISTER <401>",
transaction=z9hG4bK4a7346a7-9fe7-df11-94e6-002522124214,
token=24031ea7-9fe7-df11-94e6-002522124214 test
2010/11/06 01:11:13.815 0:08.455 Opal Liste...0xb72beb70
Opal Transport clean up on termination
2010/11/06 01:11:13.815 0:08.455 Opal Liste...0xb72beb70
Opal Transport Close
2010/11/06 01:11:13.815 0:08.455 Opal Liste...0xb72beb70
Opal Deleted transport udp$78.46.99.163:5060<if=udp$200.95.160.253:5060>
2010/11/06 01:11:13.815 0:08.455 Pool:0xb723cb70
SIP Handling PDU "1 REGISTER <401>" for
transaction=z9hG4bK4a7346a7-9fe7-df11-94e6-002522124214
2010/11/06 01:11:13.815 0:08.455 Pool:0xb723cb70
SIP REGISTER transaction
id=z9hG4bK4a7346a7-9fe7-df11-94e6-002522124214 completed.
2010/11/06 01:11:13.815 0:08.456 Pool:0xb723cb70
OpalUDP Setting interface to 200.95.160.253%eth0
2010/11/06 01:11:13.815 0:08.456 Pool:0xb723cb70
SIP Received Authentication Required response
2010/11/06 01:11:13.815 0:08.456 Pool:0xb723cb70
SIP Found auth info for realm omnivoice.eu
2010/11/06 01:11:13.816 0:08.456 Pool:0xb723cb70
SIP Changing REGISTER handler from Unavailable to Subscribing,
target=sip:208426 sip omnivoice eu,
id=24031ea7-9fe7-df11-94e6-002522124214 test
2010/11/06 01:11:13.817 0:08.457 Pool:0xb723cb70
OpalMan Listener interfaces: associated transport=udp$200.95.160.253:5060
udp$178.128.41.154:5060,udp$200.95.160.253:5060
2010/11/06 01:11:13.817 0:08.457 Pool:0xb723cb70
SIP Transaction created.
2010/11/06 01:11:13.818 0:08.459 Pool:0xb723cb70
SIP Adding authentication information
2010/11/06 01:11:13.819 0:08.459 Pool:0xb723cb70
DNS SRV Lookup sip.omnivoice.eu service _sip._udp
2010/11/06 01:11:13.863 0:08.504 Pool:0xb723cb70
SIP Attempting SRV record entry 0: 78.46.99.163:5060
2010/11/06 01:11:13.864 0:08.504 Pool:0xb723cb70
SIP Transaction remote address is udp$78.46.99.163:5060
2010/11/06 01:11:13.864 0:08.504 Pool:0xb723cb70
SIP Sending PDU (733 bytes) to:
rem=udp$78.46.99.163:5060,local=udp$200.95.160.253:5060,if=200.95.160.253%eth0
REGISTER sip:sip.omnivoice.eu SIP/2.0
CSeq: 2 REGISTER
Via: SIP/2.0/UDP
178.128.41.154:5060;branch=z9hG4bKee2f5ea7-9fe7-df11-94e6-002522124214;rport
User-Agent: Ekiga/3.2.6
Authorization: Digest username="208426", realm="omnivoice.eu",
nonce="2f369892", uri="sip:sip.omnivoice.eu", algorithm=MD5,
response="ecb4cfab32ed3a6b21aa2d0ef29ba1b8"
From: <sip:208426 sip omnivoice eu>;tag=ba081ea7-9fe7-df11-94e6-002522124214
Call-ID: 24031ea7-9fe7-df11-94e6-002522124214 test
To: <sip:208426 sip omnivoice eu>
Contact: <sip:208426 178 128 41 154>;q=1,
<sip:208426 200 95 160 253>;q=0.500
Allow:
INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 3600
Content-Length: 0
Max-Forwards: 70
2010/11/06 01:11:13.864 0:08.504 Pool:0xb723cb70
OpalUDP Setting interface to 200.95.160.253%eth0
2010/11/06 01:11:13.864 0:08.504 Pool:0xb723cb70
SIP Transaction timers set: retry=0.500, completion=6.000
2010/11/06 01:11:13.864 0:08.504 Pool:0xb723cb70
SIP Handled PDU "1 REGISTER <401>"
2010/11/06 01:11:13.967 0:08.608 Opal Liste...0xb72beb70
OpalUDP Binding to interface: 200.95.160.253:5060
2010/11/06 01:11:13.967 0:08.608 Opal Liste...0xb72beb70
SIP Waiting for PDU on udp$78.46.99.163:5060<if=udp$200.95.160.253:5060>
2010/11/06 01:11:13.968 0:08.608 Opal Liste...0xb72beb70
SIP PDU received:
rem=udp$78.46.99.163:5060,local=udp$200.95.160.253:5060,if=200.95.160.253%eth0
SIP/2.0 200 OK
Date: Fri, 05 Nov 2010 23:11:17 GMT
CSeq: 2 REGISTER
Via: SIP/2.0/UDP
192.168.1.65:5060;branch=z9hG4bKee2f5ea7-9fe7-df11-94e6-002522124214;rport;received=192.168.1.65
Server: OmniVoice
From: <sip:208426 sip omnivoice eu>;tag=ba081ea7-9fe7-df11-94e6-002522124214
Call-ID: 24031ea7-9fe7-df11-94e6-002522124214 test
Supported: replaces, timer
To: <sip:208426 sip omnivoice eu>;tag=as45c2435e
Contact: <sip:208426 192 168 1 65:55458>;expires=3600
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Expires: 3600
Content-Length: 0
2010/11/06 01:11:13.968 0:08.608 Opal Liste...0xb72beb70
SIP Queueing PDU "2 REGISTER <200>",
transaction=z9hG4bKee2f5ea7-9fe7-df11-94e6-002522124214,
token=24031ea7-9fe7-df11-94e6-002522124214 test
2010/11/06 01:11:13.968 0:08.609 Opal Liste...0xb72beb70
Opal Transport clean up on termination
2010/11/06 01:11:13.968 0:08.609 Opal Liste...0xb72beb70
Opal Transport Close
2010/11/06 01:11:13.968 0:08.609 Opal Liste...0xb72beb70
Opal Deleted transport udp$78.46.99.163:5060<if=udp$200.95.160.253:5060>
2010/11/06 01:11:13.969 0:08.609 Pool:0xb723cb70
SIP Handling PDU "2 REGISTER <200>" for
transaction=z9hG4bKee2f5ea7-9fe7-df11-94e6-002522124214
2010/11/06 01:11:13.969 0:08.609 Pool:0xb723cb70
SIP REGISTER transaction
id=z9hG4bKee2f5ea7-9fe7-df11-94e6-002522124214 completed.
2010/11/06 01:11:13.969 0:08.609 Pool:0xb723cb70
OpalUDP Setting interface to 200.95.160.253%eth0
2010/11/06 01:11:13.969 0:08.609 Pool:0xb723cb70
SIP Product Info: name="OmniVoice", version="", vendor="", comments=""
2010/11/06 01:11:13.969 0:08.609 Pool:0xb723cb70
SIP Changing REGISTER handler from Subscribing to Subscribed,
target=sip:208426 sip omnivoice eu,
id=24031ea7-9fe7-df11-94e6-002522124214 test
2010/11/06 01:11:13.969 0:08.610 Pool:0xb723cb70
SIP Product Info: name="OmniVoice", version="", vendor="", comments=""
2010/11/06 01:11:13.970 0:08.610 Pool:0xb723cb70
SIP Changing SUBSCRIBE handler from Unavailable to Subscribing,
target=sip:208426 sip omnivoice eu,
id=0c4675a7-9fe7-df11-94e6-002522124214 test
2010/11/06 01:11:13.970 0:08.610 Pool:0xb723cb70
DNS SRV Lookup sip.omnivoice.eu service _sip._udp
2010/11/06 01:11:14.015 0:08.655 Pool:0xb723cb70
SIP Attempting SRV record entry 0: 78.46.99.163:5060
2010/11/06 01:11:14.015 0:08.655 Pool:0xb723cb70
OpalUDP Binding to interface: 0.0.0.0:5060
2010/11/06 01:11:14.015 0:08.655 Pool:0xb723cb70
SIP Created transport udp$78.46.99.163:5060<if=udp$*:5060>
2010/11/06 01:11:14.015 0:08.655 Pool:0xb723cb70
OpalUDP Started connect to 78.46.99.163:5060
2010/11/06 01:11:14.015 0:08.655 Pool:0xb723cb70
OpalUDP Writing to interface 0 - "200.95.160.253%eth0"
2010/11/06 01:11:14.015 0:08.656 Pool:0xb723cb70
SIP Transaction created.
2010/11/06 01:11:14.018 0:08.658 Pool:0xb723cb70
DNS SRV Lookup sip.omnivoice.eu service _sip._udp
2010/11/06 01:11:14.063 0:08.703 Pool:0xb723cb70
SIP Attempting SRV record entry 0: 78.46.99.163:5060
2010/11/06 01:11:14.063 0:08.703 Pool:0xb723cb70
SIP Transaction remote address is udp$78.46.99.163:5060
2010/11/06 01:11:14.063 0:08.703 Pool:0xb723cb70
SIP Sending PDU (600 bytes) to:
rem=udp$78.46.99.163:5060,local=udp$200.95.160.253:5060,if=200.95.160.253%eth0
SUBSCRIBE sip:208426 sip omnivoice eu SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP
178.128.41.154:5060;branch=z9hG4bKe26d7ca7-9fe7-df11-94e6-002522124214;rport
User-Agent: Ekiga/3.2.6
From: <sip:208426 sip omnivoice eu>;tag=60407ca7-9fe7-df11-94e6-002522124214
Call-ID: 0c4675a7-9fe7-df11-94e6-002522124214 test
To: <sip:208426 sip omnivoice eu>
Contact: <sip:208426 178 128 41 154>
Accept: application/simple-message-summary
Allow:
INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 3600
Event: message-summary
Content-Length: 0
Max-Forwards: 70
2010/11/06 01:11:14.063 0:08.703 Pool:0xb723cb70
OpalUDP Setting interface to 200.95.160.253%eth0
2010/11/06 01:11:14.063 0:08.704 Pool:0xb723cb70
SIP Transaction timers set: retry=0.500, completion=6.000
2010/11/06 01:11:14.063 0:08.704 Pool:0xb723cb70
SIP Handled PDU "2 REGISTER <200>"
2010/11/06 01:11:14.106 0:08.746
SIP Changing PUBLISH handler from Unavailable to Subscribing,
target=sip:208426 sip omnivoice eu,
id=68168aa7-9fe7-df11-94e6-002522124214 test
2010/11/06 01:11:14.106 0:08.746
DNS SRV Lookup sip.omnivoice.eu service _sip._udp
2010/11/06 01:11:14.163 0:08.804 Opal Liste...0xb72beb70
OpalUDP Binding to interface: 200.95.160.253:5060
2010/11/06 01:11:14.163 0:08.804 Opal Liste...0xb72beb70
SIP Waiting for PDU on udp$78.46.99.163:5060<if=udp$200.95.160.253:5060>
2010/11/06 01:11:14.164 0:08.804 Opal Liste...0xb72beb70
SIP PDU received:
rem=udp$78.46.99.163:5060,local=udp$200.95.160.253:5060,if=200.95.160.253%eth0
SIP/2.0 403 Forbidden (policy)
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP
192.168.1.65:5060;branch=z9hG4bKe26d7ca7-9fe7-df11-94e6-002522124214;received=192.168.1.65;rport=5060
Server: OmniVoice
From: <sip:208426 sip omnivoice eu>;tag=60407ca7-9fe7-df11-94e6-002522124214
Call-ID: 0c4675a7-9fe7-df11-94e6-002522124214 test
Supported: replaces, timer
To: <sip:208426 sip omnivoice eu>;tag=as5a02bab8
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Content-Length: 0
2010/11/06 01:11:14.164 0:08.805 Opal Liste...0xb72beb70
SIP Queueing PDU "2 SUBSCRIBE <403>",
transaction=z9hG4bKe26d7ca7-9fe7-df11-94e6-002522124214,
token=0c4675a7-9fe7-df11-94e6-002522124214 test
2010/11/06 01:11:14.164 0:08.805 Opal Liste...0xb72beb70
Opal Transport clean up on termination
2010/11/06 01:11:14.164 0:08.805 Opal Liste...0xb72beb70
Opal Transport Close
2010/11/06 01:11:14.164 0:08.805 Opal Liste...0xb72beb70
Opal Deleted transport udp$78.46.99.163:5060<if=udp$200.95.160.253:5060>
2010/11/06 01:11:14.165 0:08.805 Pool:0xb723cb70
SIP Handling PDU "2 SUBSCRIBE <403>" for
transaction=z9hG4bKe26d7ca7-9fe7-df11-94e6-002522124214
2010/11/06 01:11:14.165 0:08.805 Pool:0xb723cb70
SIP SUBSCRIBE transaction
id=z9hG4bKe26d7ca7-9fe7-df11-94e6-002522124214 completed.
2010/11/06 01:11:14.165 0:08.805 Pool:0xb723cb70
OpalUDP Setting interface to 200.95.160.253%eth0
2010/11/06 01:11:14.165 0:08.805 Pool:0xb723cb70
SIP Not retrying SUBSCRIBE due to error response 403 Forbidden
2010/11/06 01:11:14.165 0:08.805 Pool:0xb723cb70
SIP Changing SUBSCRIBE handler from Subscribing to Unsubscribed,
target=sip:208426 sip omnivoice eu,
id=0c4675a7-9fe7-df11-94e6-002522124214 test
2010/11/06 01:11:14.165 0:08.805 Pool:0xb723cb70
SIP Handled PDU "2 SUBSCRIBE <403>"
2010/11/06 01:11:14.238 0:08.879
SIP Attempting SRV record entry 0: 78.46.99.163:5060
2010/11/06 01:11:14.239 0:08.879
OpalUDP Binding to interface: 0.0.0.0:5060
2010/11/06 01:11:14.239 0:08.879
SIP Created transport udp$78.46.99.163:5060<if=udp$*:5060>
2010/11/06 01:11:14.239 0:08.879
OpalUDP Started connect to 78.46.99.163:5060
2010/11/06 01:11:14.239 0:08.879
OpalUDP Writing to interface 0 - "200.95.160.253%eth0"
2010/11/06 01:11:14.239 0:08.879
SIP Transaction created.
2010/11/06 01:11:14.242 0:08.882
DNS SRV Lookup sip.omnivoice.eu service _sip._udp
2010/11/06 01:11:14.422 0:09.062
SIP Attempting SRV record entry 0: 78.46.99.163:5060
2010/11/06 01:11:14.422 0:09.062
SIP Transaction remote address is udp$78.46.99.163:5060
2010/11/06 01:11:14.422 0:09.062
SIP Sending PDU (857 bytes) to:
rem=udp$78.46.99.163:5060,local=udp$200.95.160.253:5060,if=200.95.160.253%eth0
PUBLISH sip:208426 sip omnivoice eu SIP/2.0
CSeq: 3 PUBLISH
Via: SIP/2.0/UDP
178.128.41.154:5060;branch=z9hG4bK46979ea7-9fe7-df11-94e6-002522124214;rport
User-Agent: Ekiga/3.2.6
From: <sip:208426 sip omnivoice eu>;tag=ca6d9ea7-9fe7-df11-94e6-002522124214
Call-ID: 68168aa7-9fe7-df11-94e6-002522124214 test
To: <sip:208426 sip omnivoice eu>
Contact: <sip:208426 178 128 41 154>
Expires: 500
Event: presence
Content-Type: application/pidf+xml
Content-Length: 354
Max-Forwards: 70
<?xml version="1.0" encoding="UTF-8"?>
<presence xmlns="urn:ietf:params:xml:ns:pidf"
entity="pres:208426 sip omnivoice eu">
<tuple id="sip:208426 sip omnivoice eu_on_test">
<note>online - I'm online using Ekiga</note>
<status>
<basic>open</basic>
</status>
<contact priority="1">208426 sip omnivoice eu</contact>
</tuple>
</presence>
2010/11/06 01:11:14.422 0:09.063
OpalUDP Setting interface to 200.95.160.253%eth0
2010/11/06 01:11:14.423 0:09.063
SIP Transaction timers set: retry=0.500, completion=6.000
2010/11/06 01:11:14.523 0:09.163 Opal Liste...0xb72beb70
OpalUDP Binding to interface: 200.95.160.253:5060
2010/11/06 01:11:14.523 0:09.164 Opal Garbage:0xb74b3b70
SIP Deleting SIPSubscribeHandler sip:208426 sip omnivoice eu
2010/11/06 01:11:14.524 0:09.164 Opal Garbage:0xb74b3b70
Opal Transport clean up on termination
2010/11/06 01:11:14.524 0:09.164 Opal Garbage:0xb74b3b70
Opal Transport Close
2010/11/06 01:11:14.523 0:09.164 Opal Liste...0xb72beb70
SIP Waiting for PDU on udp$78.46.99.163:5060<if=udp$200.95.160.253:5060>
2010/11/06 01:11:14.524 0:09.164 Opal Garbage:0xb74b3b70
Opal Transport clean up on termination
2010/11/06 01:11:14.524 0:09.164 Opal Garbage:0xb74b3b70
Opal Transport Close
2010/11/06 01:11:14.524 0:09.164 Opal Garbage:0xb74b3b70
Opal Deleted transport udp$78.46.99.163:5060<if=udp$200.95.160.253:5060>
2010/11/06 01:11:14.524 0:09.164 Opal Garbage:0xb74b3b70
SIP Deleted handler.
2010/11/06 01:11:14.524 0:09.164 Opal Liste...0xb72beb70
SIP PDU received:
rem=udp$78.46.99.163:5060,local=udp$200.95.160.253:5060,if=200.95.160.253%eth0
SIP/2.0 501 Method Not Implemented
CSeq: 3 PUBLISH
Via: SIP/2.0/UDP
192.168.1.65:5060;branch=z9hG4bK46979ea7-9fe7-df11-94e6-002522124214;received=192.168.1.65;rport=5060
Server: OmniVoice
From: <sip:208426 sip omnivoice eu>;tag=ca6d9ea7-9fe7-df11-94e6-002522124214
Call-ID: 68168aa7-9fe7-df11-94e6-002522124214 test
Supported: replaces, timer
To: <sip:208426 sip omnivoice eu>;tag=as330ceb75
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Content-Length: 0
2010/11/06 01:11:14.524 0:09.164 Opal Liste...0xb72beb70
SIP Queueing PDU "3 PUBLISH <501>",
transaction=z9hG4bK46979ea7-9fe7-df11-94e6-002522124214,
token=68168aa7-9fe7-df11-94e6-002522124214 test
2010/11/06 01:11:14.524 0:09.164 Opal Liste...0xb72beb70
Opal Transport clean up on termination
2010/11/06 01:11:14.524 0:09.165 Opal Liste...0xb72beb70
Opal Transport Close
2010/11/06 01:11:14.524 0:09.165 Pool:0xb723cb70
SIP Handling PDU "3 PUBLISH <501>" for
transaction=z9hG4bK46979ea7-9fe7-df11-94e6-002522124214
2010/11/06 01:11:14.524 0:09.165 Pool:0xb723cb70
SIP PUBLISH transaction
id=z9hG4bK46979ea7-9fe7-df11-94e6-002522124214 completed.
2010/11/06 01:11:14.525 0:09.165 Pool:0xb723cb70
OpalUDP Setting interface to 200.95.160.253%eth0
2010/11/06 01:11:14.525 0:09.165 Pool:0xb723cb70
SIP Not retrying PUBLISH due to error response 501 Not Implemented
2010/11/06 01:11:14.525 0:09.165 Pool:0xb723cb70
SIP Changing PUBLISH handler from Subscribing to Unsubscribed,
target=sip:208426 sip omnivoice eu,
id=68168aa7-9fe7-df11-94e6-002522124214 test
2010/11/06 01:11:14.524 0:09.165 Opal Liste...0xb72beb70
Opal Deleted transport udp$78.46.99.163:5060<if=udp$200.95.160.253:5060>
2010/11/06 01:11:14.525 0:09.165 Pool:0xb723cb70
SIP Handled PDU "3 PUBLISH <501>"
2010/11/06 01:11:14.864 0:09.504 Housekeeper:0xb72ffb70
SIP Set state Terminated_Success for REGISTER transaction
id=z9hG4bK4a7346a7-9fe7-df11-94e6-002522124214
2010/11/06 01:11:15.064 0:09.704 Housekeeper:0xb72ffb70
SIP Set state Terminated_Success for REGISTER transaction
id=z9hG4bKee2f5ea7-9fe7-df11-94e6-002522124214
2010/11/06 01:11:15.165 0:09.805 Housekeeper:0xb72ffb70
SIP Set state Terminated_Success for SUBSCRIBE transaction
id=z9hG4bKe26d7ca7-9fe7-df11-94e6-002522124214
2010/11/06 01:11:15.524 0:10.165 Opal Garbage:0xb74b3b70
SIP Transaction id=z9hG4bK4a7346a7-9fe7-df11-94e6-002522124214 destroyed.
2010/11/06 01:11:15.524 0:10.165 Housekeeper:0xb72ffb70
SIP Set state Terminated_Success for PUBLISH transaction
id=z9hG4bK46979ea7-9fe7-df11-94e6-002522124214
2010/11/06 01:11:15.525 0:10.165 Opal Garbage:0xb74b3b70
SIP Transaction id=z9hG4bKee2f5ea7-9fe7-df11-94e6-002522124214 destroyed.
2010/11/06 01:11:15.525 0:10.165 Opal Garbage:0xb74b3b70
SIP Transaction id=z9hG4bKe26d7ca7-9fe7-df11-94e6-002522124214 destroyed.
2010/11/06 01:11:15.525 0:10.165 Opal Garbage:0xb74b3b70
SIP Deleting SIPPublishHandler sip:208426 sip omnivoice eu
2010/11/06 01:11:15.525 0:10.165 Opal Garbage:0xb74b3b70
Opal Transport clean up on termination
2010/11/06 01:11:15.525 0:10.165 Opal Garbage:0xb74b3b70
Opal Transport Close
2010/11/06 01:11:15.525 0:10.165 Opal Garbage:0xb74b3b70
Opal Transport clean up on termination
2010/11/06 01:11:15.525 0:10.165 Opal Garbage:0xb74b3b70
Opal Transport Close
2010/11/06 01:11:15.525 0:10.165 Opal Garbage:0xb74b3b70
Opal Deleted transport udp$78.46.99.163:5060<if=udp$200.95.160.253:5060>
2010/11/06 01:11:15.525 0:10.166 Opal Garbage:0xb74b3b70
SIP Deleted handler.
2010/11/06 01:11:16.526 0:11.166 Opal Garbage:0xb74b3b70
SIP Transaction id=z9hG4bK46979ea7-9fe7-df11-94e6-002522124214 destroyed.
2010/11/06 01:23:09.543 12:04.183 Opal Liste...0xb72beb70
OpalUDP Binding to interface: 200.95.160.253:5060
2010/11/06 01:23:09.543 12:04.183 Opal Liste...0xb72beb70
SIP Waiting for PDU on udp$78.46.99.163:5060<if=udp$200.95.160.253:5060>
2010/11/06 01:23:09.544 12:04.184 Opal Liste...0xb72beb70
SIP PDU received:
rem=udp$78.46.99.163:5060,local=udp$200.95.160.253:5060,if=200.95.160.253%eth0
INVITE sip:208426 192 168 1 65:5060 SIP/2.0
Date: Fri, 05 Nov 2010 23:23:13 GMT
CSeq: 102 INVITE
Via: SIP/2.0/UDP 78.46.99.163:5060;branch=z9hG4bK3db8966b
User-Agent: OmniVoice
From: "6988888888" <sip:6988888888 78 46 99 163>;tag=as29beea19
Call-ID: 1df719a6632e00073d32a9ca0ddde50a 78 46 99 163
Supported: replaces, timer
To: <sip:208426 192 168 1 65:5060>
Contact: <sip:6988888888 78 46 99 163>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Content-Type: application/sdp
Content-Length: 422
Max-Forwards: 70
Remote-Party-ID: "6988888888"
<sip:6988888888 78 46 99 163>;privacy=off;screen=no
v=0
o=root 2019184497 2019184497 IN IP4 78.46.99.163
s=OmniVoice
c=IN IP4 78.46.99.163
t=0 0
m=audio 16942 RTP/AVP 18 8 0 3 97 111 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:3 GSM/8000
a=rtpmap:97 iLBC/8000
a=fmtp:97 mode=30
a=rtpmap:111 G726-32/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv
2010/11/06 01:23:09.751 12:04.391 Opal Liste...0xb72beb70
SIP Sending PDU (313 bytes) to:
rem=udp$78.46.99.163:5060,local=udp$200.95.160.253:5060,if=200.95.160.253%eth0
SIP/2.0 100 Trying
CSeq: 102 INVITE
Via: SIP/2.0/UDP 78.46.99.163:5060;branch=z9hG4bK3db8966b
From: "6988888888" <sip:6988888888 78 46 99 163>;tag=as29beea19
Call-ID: 1df719a6632e00073d32a9ca0ddde50a 78 46 99 163
To: <sip:208426 192 168 1 65:5060>
Contact: <sip:208426 178 128 41 154>
Content-Length: 0
2010/11/06 01:23:09.751 12:04.391 Opal Liste...0xb72beb70
OpalUDP Setting interface to 200.95.160.253%eth0
2010/11/06 01:23:09.752 12:04.392 Opal Liste...0xb72beb70
OpalUDP Binding to interface: 200.95.160.253:5060
2010/11/06 01:23:09.752 12:04.392 Opal Liste...0xb72beb70
SIP Created transport udp$78.46.99.163:5060<if=udp$200.95.160.253:5060>
2010/11/06 01:23:09.752 12:04.392 Opal Liste...0xb72beb70
OpalUDP Started connect to 78.46.99.163:5060
2010/11/06 01:23:09.752 12:04.392 Opal Liste...0xb72beb70
Call Created Call[aee4a2a281]
2010/11/06 01:23:09.752 12:04.392 Opal Liste...0xb72beb70
OpalCon Created connection
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214]
2010/11/06 01:23:09.752 12:04.393 Opal Liste...0xb72beb70
RFC2833 Handler created
2010/11/06 01:23:09.752 12:04.393 Opal Liste...0xb72beb70
RFC2833 Handler created
2010/11/06 01:23:09.753 12:04.393 Opal Liste...0xb72beb70
SIP Created connection.
2010/11/06 01:23:09.753 12:04.393 Opal Liste...0xb72beb70
SIP Queueing PDU "102 INVITE sip:208426 192 168 1 65:5060",
transaction=z9hG4bK3db8966b, token=28071952-a1e7-df11-94e6-002522124214
2010/11/06 01:23:09.753 12:04.393 Opal Liste...0xb72beb70
Opal Transport clean up on termination
2010/11/06 01:23:09.753 12:04.393 Opal Liste...0xb72beb70
Opal Transport Close
2010/11/06 01:23:09.753 12:04.393 Pool:0xb53a5b70
SIP Handling PDU "102 INVITE sip:208426 192 168 1 65:5060" for
token=28071952-a1e7-df11-94e6-002522124214
2010/11/06 01:23:09.753 12:04.393 Pool:0xb53a5b70
SIP Initial INVITE from sip:208426 192 168 1 65:5060
2010/11/06 01:23:09.753 12:04.393 Pool:0xb53a5b70
SIP Set Request URI to sip:6988888888 78 46 99 163
2010/11/06 01:23:09.753 12:04.393 Opal Liste...0xb72beb70
Opal Deleted transport udp$78.46.99.163:5060<if=udp$200.95.160.253:5060>
2010/11/06 01:23:09.753 12:04.394 Pool:0xb53a5b70
SIP Updating dialog tag from "" to "as29beea19"
2010/11/06 01:23:09.754 12:04.394 Pool:0xb53a5b70
SIP Product Info: name="OmniVoice", version="", vendor="", comments=""
2010/11/06 01:23:09.754 12:04.394 Pool:0xb53a5b70
OPAL Checking incoming call for NAT: local=200.95.160.253,
peer=78.46.99.163, sig=78.46.99.163
2010/11/06 01:23:09.754 12:04.394 Pool:0xb53a5b70
OpalCon SetPhase from UninitialisedPhase to SetUpPhase for
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214]
2010/11/06 01:23:09.754 12:04.394 Pool:0xb53a5b70
Opal::Sip::EndPoint Incoming connection
2010/11/06 01:23:09.754 12:04.394 Pool:0xb53a5b70
OpalMan OnIncoming connection
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214]
2010/11/06 01:23:09.754 12:04.394 Pool:0xb53a5b70
OpalCon Applying string options:
2010/11/06 01:23:09.754 12:04.395 Pool:0xb53a5b70
Call GetOtherPartyConnection
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214]
2010/11/06 01:23:09.754 12:04.395 Pool:0xb53a5b70
OpalMan Searching for route "sip:208426 192 168 1 65:5060
sip:208426 192 168 1 65:5060"
2010/11/06 01:23:09.755 12:04.395 Pool:0xb53a5b70
OpalMan Set up connection to "pc:*"
2010/11/06 01:23:09.755 12:04.395 Pool:0xb53a5b70
OpalCon Created connection Call[aee4a2a281]-EP<pc>[h10691d182]
2010/11/06 01:23:09.755 12:04.395 Pool:0xb53a5b70
Silence Parameters set: mode=NoSilenceDetection, threshold=0,
silencedb=3200, signaldb=80, period=4800
2010/11/06 01:23:09.755 12:04.395 Pool:0xb53a5b70
Silence Handler created
2010/11/06 01:23:09.755 12:04.395 Pool:0xb53a5b70 Echo
Canceler Handler created
2010/11/06 01:23:09.755 12:04.395 Pool:0xb53a5b70
PCSS Created PC sound system connection: token="h10691d182"
player="EKIGA" recorder="EKIGA"
2010/11/06 01:23:09.755 12:04.395 Pool:0xb53a5b70
SIP OnIncomingConnection succeeded for INVITE from
sip:208426 192 168 1 65:5060 for
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214]
2010/11/06 01:23:09.755 12:04.395 Pool:0xb53a5b70
Call GetOtherPartyConnection
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214]
2010/11/06 01:23:09.755 12:04.395 Pool:0xb53a5b70
PWLib File handle high water mark set: 59 Thread unblock pipe
2010/11/06 01:23:09.755 12:04.396 Pool:0xb53a5b70
PTLib Thread high water mark set: 12
2010/11/06 01:23:09.755 12:04.396 Pool:0xb53a5b70
OpalMan OnAnswerCall
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214]
2010/11/06 01:23:09.755 12:04.396 CallSetup:0xb5364b70
Call OnSetUp
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214]
2010/11/06 01:23:09.756 12:04.396 Pool:0xb53a5b70
Call OnAnswerCall
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214] caller
"udp$78.46.99.163:5060"
2010/11/06 01:23:09.756 12:04.396 Pool:0xb53a5b70
OpalCon Answering call: AnswerCallPending
2010/11/06 01:23:09.756 12:04.396 Pool:0xb53a5b70
SIP SetAlerting
2010/11/06 01:23:09.756 12:04.396 Pool:0xb53a5b70
PWLib File handle high water mark set: 60 PUDPSocket
2010/11/06 01:23:09.756 12:04.396 Pool:0xb53a5b70
SIP Sending PDU (421 bytes) to:
rem=udp$78.46.99.163:5060,local=udp$200.95.160.253:5060,if=200.95.160.253%eth0
SIP/2.0 180 Ringing
CSeq: 102 INVITE
Via: SIP/2.0/UDP 78.46.99.163:5060;branch=z9hG4bK3db8966b
User-Agent: Ekiga/3.2.6
From: "6988888888" <sip:6988888888 78 46 99 163>;tag=as29beea19
Call-ID: 1df719a6632e00073d32a9ca0ddde50a 78 46 99 163
To: <sip:208426 192 168 1 65:5060>;tag=28071952-a1e7-df11-94e6-002522124214
Allow:
INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Content-Length: 0
2010/11/06 01:23:09.756 12:04.396 Pool:0xb53a5b70
OpalUDP Setting interface to 200.95.160.253%eth0
2010/11/06 01:23:09.756 12:04.397 Pool:0xb53a5b70
OpalCon SetPhase from SetUpPhase to AlertingPhase for
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214]
2010/11/06 01:23:09.757 12:04.397 Pool:0xb53a5b70
SIP Handled PDU "102 INVITE sip:208426 192 168 1 65:5060"
2010/11/06 01:23:09.757 12:04.397 CallSetup:0xb5364b70
PCSS SetUpConnection(6988888888)
2010/11/06 01:23:09.757 12:04.397 CallSetup:0xb5364b70
OpalCon SetPhase from UninitialisedPhase to AlertingPhase for
Call[aee4a2a281]-EP<pc>[h10691d182]
2010/11/06 01:23:09.757 12:04.397 CallSetup:0xb5364b70
OpalMan OnAlerting Call[aee4a2a281]-EP<pc>[h10691d182]
2010/11/06 01:23:09.757 12:04.397 CallSetup:0xb5364b70
Call OnAlerting Call[aee4a2a281]-EP<pc>[h10691d182]
2010/11/06 01:23:09.757 12:04.397 CallSetup:0xb5364b70
SIP SetAlerting
2010/11/06 01:23:09.757 12:04.397 CallSetup:0xb5364b70
OpalCon OnSetUpConnectionCall[aee4a2a281]-EP<pc>[h10691d182]
2010/11/06 01:23:09.757 12:04.397 CallSetup:0xb5364b70
OpalEP OnSetUpConnection Call[aee4a2a281]-EP<pc>[h10691d182]
2010/11/06 01:23:09.822 12:04.462
AEScheduler Adding Event incoming_call_sound 4000/256 to queue
2010/11/06 01:23:09.822 12:04.462 AudioEvent...0xb7535b70
AEScheduler Checking pending list with 1 elements
2010/11/06 01:23:09.822 12:04.462 AudioEvent...0xb7535b70
AEScheduler Trying to load /usr/share/sounds/ekiga/ring.wav for event
incoming_call_sound
2010/11/06 01:23:09.832 12:04.473 AudioEvent...0xb7535b70
PWLib File handle low water mark set: 58 PWAVFile
2010/11/06 01:23:09.843 12:04.483 AudioEvent...0xb7535b70
AudioOutputCore Opening device[1] with 2-44100/16
2010/11/06 01:23:09.843 12:04.483 AudioEvent...0xb7535b70
GMAudioOutputManager_null Opening Device[1] SILENT (Ekiga/Ekiga)
2010/11/06 01:23:09.843 12:04.483 AudioEvent...0xb7535b70
GMAudioOutputManager_null Opening Device with 2-44100/16
2010/11/06 01:23:11.651 12:06.291
OpalCon SetPhase from AlertingPhase to ConnectedPhase for
Call[aee4a2a281]-EP<pc>[h10691d182]
2010/11/06 01:23:11.651 12:06.291
OpalCon OnConnected for Call[aee4a2a281]-EP<pc>[h10691d182]
2010/11/06 01:23:11.651 12:06.291
OpalMan OnConnected Call[aee4a2a281]-EP<pc>[h10691d182]
2010/11/06 01:23:11.651 12:06.291
Call OnConnected Call[aee4a2a281]-EP<pc>[h10691d182]
2010/11/06 01:23:11.651 12:06.291
SIP SetConnected
2010/11/06 01:23:11.854 12:06.494
SDP Parsed connection address udp$78.46.99.163
2010/11/06 01:23:11.854 12:06.494
SDP Parsed connection address udp$78.46.99.163
2010/11/06 01:23:11.854 12:06.494
SDP Media session port=16942
2010/11/06 01:23:11.854 12:06.494
SDP Could not find media format for "G729", pt=G729, clock=8000
2010/11/06 01:23:11.854 12:06.495
SDP Could not find media format for "iLBC", pt=[pt=97], clock=8000
2010/11/06 01:23:11.854 12:06.495
SDP Unknown media attribute silenceSupp
2010/11/06 01:23:11.854 12:06.495
SDP Unknown media attribute ptime
2010/11/06 01:23:11.855 12:06.495
SDP Parsed media session with 7 'audio' formats
2010/11/06 01:23:11.855 12:06.495
SDP Could not find media format for "G729", pt=G729, clock=8000
2010/11/06 01:23:11.855 12:06.495
SDP Could not find media format for "iLBC", pt=[pt=97], clock=8000
2010/11/06 01:23:11.855 12:06.495
Call IsMediaBypassPossible
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214] session 1
2010/11/06 01:23:11.855 12:06.495
OpalMan IsMediaBypassPossible: session 1
2010/11/06 01:23:11.855 12:06.495
OpalCon IsMediaBypassPossible: default returns false
2010/11/06 01:23:11.855 12:06.495
RTP Cannot find RTP session 1
2010/11/06 01:23:11.856 12:06.496
RTP_UDP Session 1, created with NAT flag set to 0
2010/11/06 01:23:11.856 12:06.496
RTP_UDP Session 1 created: 200.95.160.253:5062-5063 ssrc=1169797636
2010/11/06 01:23:11.856 12:06.496
RTP Creating new session RTP_UDP
2010/11/06 01:23:11.857 12:06.497
RTP_UDP Session 1, SetRemoteSocketInfo: data channel,
new=78.46.99.163:16942, local=178.128.41.154:5062-5063, remote=0.0.0.0:0-0
2010/11/06 01:23:11.857 12:06.497
RTP Found existing media session 1
2010/11/06 01:23:11.857 12:06.497
SIP RTP payload type PCMA matched to codec G.711-ALaw-64k
2010/11/06 01:23:11.857 12:06.497
SIP RTP payload type PCMU matched to codec G.711-uLaw-64k
2010/11/06 01:23:11.857 12:06.497
SIP RTP payload type GSM matched to codec GSM-06.10
2010/11/06 01:23:11.857 12:06.497
SIP RTP payload type [pt=111] matched to codec G.726-32k
2010/11/06 01:23:11.857 12:06.498
MediaFormat Removing codecs
PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,G.722-64k,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,PCM-16,G.711-ALaw-64k,G.711-uLaw-64k,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2010/11/06 01:23:11.858 12:06.498
Call OpenSourceMediaStreams opening audio session 1 on
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214]
2010/11/06 01:23:11.861 12:06.501
MediaFormat Removing codecs
PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,G.722-64k,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,PCM-16,G.711-ALaw-64k,G.711-uLaw-64k,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2010/11/06 01:23:11.861 12:06.502
MediaFormat Removing codecs
PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,G.722-64k,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,PCM-16,G.711-ALaw-64k,G.711-uLaw-64k,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2010/11/06 01:23:11.862 12:06.502
Call GetMediaFormats for
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214]
GSM-06.10
G.726-16k
G.726-24k
G.726-32k
G.726-40k
theora
H.261
H.261-CIF
H.261-QCIF
2010/11/06 01:23:11.862 12:06.502
MediaFormat Merging GSM-06.10 into GSM-06.10
2010/11/06 01:23:11.862 12:06.502
MediaFormat Merging PCM-16 into GSM-06.10
2010/11/06 01:23:11.862 12:06.502
MediaFormat Merging GSM-06.10 into PCM-16
2010/11/06 01:23:11.862 12:06.503
MediaFormat Changed media option "Max Bit Rate" from 128000 to 13200
2010/11/06 01:23:11.862 12:06.503
Call Selected media formats GSM-06.10 -> PCM-16
2010/11/06 01:23:11.862 12:06.503
Call IsMediaBypassPossible
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214] session 1
2010/11/06 01:23:11.862 12:06.503
OpalMan IsMediaBypassPossible: session 1
2010/11/06 01:23:11.863 12:06.503
OpalCon IsMediaBypassPossible: default returns false
2010/11/06 01:23:11.863 12:06.503
RTP Found existing media session 1
2010/11/06 01:23:11.863 12:06.503
OpalMan OnOpenMediaStream
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214],OpalRTPMediaStream-Source-GSM-06.10
2010/11/06 01:23:11.863 12:06.503
OpalCon Opened source stream aee4a2a281_1 with format GSM-06.10
2010/11/06 01:23:11.863 12:06.503
GMAudioOutputManager_ptlib Setting Device[0] SB Live! 5.1 (PTLIB/ALSA)
2010/11/06 01:23:11.863 12:06.503
AudioOutputCore Opening device[0] with 1-8000/16
2010/11/06 01:23:11.863 12:06.503
GMAudioOutputManager_ptlib Opening Device SB Live! 5.1 (PTLIB/ALSA)
2010/11/06 01:23:11.863 12:06.503
GMAudioOutputManager_ptlib Opening Device with 1-8000/16
2010/11/06 01:23:11.864 12:06.504
ALSA SetHardwareParams Player channels=1 sample rate=8000
2010/11/06 01:23:11.864 12:06.505
ALSA Successfully set period size to 160
2010/11/06 01:23:11.864 12:06.505
ALSA Successfully set periods to 2
2010/11/06 01:23:11.865 12:06.505
ALSA parameters set ok
2010/11/06 01:23:11.865 12:06.505
ALSA Device plughw:0 Opened
2010/11/06 01:23:11.866 12:06.506
ALSA Got volume 23
2010/11/06 01:23:11.866 12:06.506
PCSS Opened mono sound channel "EKIGA" for playing at 8.0 kHz.
2010/11/06 01:23:11.866 12:06.506
OpalMan OnOpenMediaStream
Call[aee4a2a281]-EP<pc>[h10691d182],OpalAudioMediaStream-Sink-PCM-16
2010/11/06 01:23:11.866 12:06.506
OpalCon SetPhase from ConnectedPhase to EstablishedPhase for
Call[aee4a2a281]-EP<pc>[h10691d182]
2010/11/06 01:23:11.866 12:06.506
OpalCon OnEstablished Call[aee4a2a281]-EP<pc>[h10691d182]
2010/11/06 01:23:11.866 12:06.506
OpalCon Media stream threads started.
2010/11/06 01:23:11.866 12:06.506
OpalMan OnEstablished Call[aee4a2a281]-EP<pc>[h10691d182]
2010/11/06 01:23:11.866 12:06.507
Call OnEstablished Call[aee4a2a281]-EP<pc>[h10691d182]
2010/11/06 01:23:11.866 12:06.507
OpalCon Media stream threads started.
2010/11/06 01:23:11.866 12:06.507
OpalCon Opened sink stream aee4a2a281_1 with format PCM-16
2010/11/06 01:23:11.866 12:06.507
Patch Created Sink: format=PCM-16
2010/11/06 01:23:11.867 12:06.507
OpalPlugin Setting decoder options:
Format Name = GSM-06.10
Media Type = audio
Payload Type = GSM
Encoding Name = gsm
Channels (R/W) = 1 UnsignedInt
Clock Rate (R/O) = 8000 UnsignedInt
Frame Time (R/O) = 160 UnsignedInt
Max Bit Rate (R/O) = 13200 UnsignedInt
Max Frame Size (R/O) = 33 UnsignedInt
Max Frames Per Packet (R/O) = 7 UnsignedInt
Needs Jitter (R/O) = 1 Boolean
Protocol (R/O) = String
Rx Frames Per Packet (R/W) = 7 UnsignedInt
Tx Frames Per Packet (R/W) = 1 UnsignedInt
2010/11/06 01:23:11.867 12:06.507
Patch Created primary codec GSM-06.10->PCM-16 with ID aee4a2a281_1
2010/11/06 01:23:11.867 12:06.507
Media Audio sink data size set to 320, buffer size set to 160 and 5
buffers.
2010/11/06 01:23:11.867 12:06.507
Media Set data size from 16 to 320
2010/11/06 01:23:11.867 12:06.507
GMAudioOutputManager_ptlib Setting buffer size of device[0] 160/5
2010/11/06 01:23:11.867 12:06.507
ALSA SetBuffers direction=Player size=160 count=5
2010/11/06 01:23:11.867 12:06.507
ALSA SetHardwareParams Player channels=1 sample rate=8000
2010/11/06 01:23:11.868 12:06.508
ALSA Successfully set period size to 80
2010/11/06 01:23:11.868 12:06.508
ALSA Successfully set periods to 5
2010/11/06 01:23:11.868 12:06.508
ALSA parameters set ok
2010/11/06 01:23:11.868 12:06.508
Patch Added media stream sink OpalAudioMediaStream-Sink-PCM-16 using
transcoder GSM-06.10->PCM-16, data size=320
2010/11/06 01:23:11.868 12:06.508
Media RTP data size cannot be changed to 33, fixed at 2048
2010/11/06 01:23:11.868 12:06.508
OpalCon Source stream of connection
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214] uses
patch Patch OpalRTPMediaStream-Source-GSM-06.10 ->
OpalAudioMediaStream-Sink-PCM-16
2010/11/06 01:23:11.868 12:06.508
RTPCon Adding RFC2833 receive handler
2010/11/06 01:23:11.868 12:06.508
RTPCon Adding Cisco NSE receive handler
2010/11/06 01:23:11.868 12:06.508
PCSS Adding filters to patch
2010/11/06 01:23:11.868 12:06.509
Call GetOtherPartyConnection
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214]
2010/11/06 01:23:11.868 12:06.509
Call OpenSourceMediaStreams opening audio session 1 on
Call[aee4a2a281]-EP<pc>[h10691d182]
2010/11/06 01:23:11.870 12:06.510
MediaFormat Removing codecs
PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,G.722-64k,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,PCM-16,G.711-ALaw-64k,G.711-uLaw-64k,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2010/11/06 01:23:11.870 12:06.510
MediaFormat Removing codecs
PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,G.722-64k,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,PCM-16,G.711-ALaw-64k,G.711-uLaw-64k,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2010/11/06 01:23:11.870 12:06.510
Call GetMediaFormats for Call[aee4a2a281]-EP<pc>[h10691d182]
GSM-06.10
G.726-16k
G.726-24k
G.726-32k
G.726-40k
T.38
UserInput/RFC2833
2010/11/06 01:23:11.870 12:06.510
MediaFormat Merging GSM-06.10 into GSM-06.10
2010/11/06 01:23:11.870 12:06.511
MediaFormat Merging GSM-06.10 into PCM-16
2010/11/06 01:23:11.870 12:06.511
MediaFormat Changed media option "Max Bit Rate" from 128000 to 13200
2010/11/06 01:23:11.870 12:06.511
MediaFormat Merging PCM-16 into GSM-06.10
2010/11/06 01:23:11.871 12:06.511
Call Selected media formats PCM-16 -> GSM-06.10
2010/11/06 01:23:11.871 12:06.511
AudioInputCore Starting stream 1x8000/16
2010/11/06 01:23:11.871 12:06.511
GMAudioInputManager_ptlib Setting Device HDA Intel (1) (PTLIB/ALSA)
2010/11/06 01:23:11.871 12:06.511
AudioInputCore Opening device with 1-8000/16
2010/11/06 01:23:11.871 12:06.511
GMAudioInputManager_ptlib Opening Device HDA Intel (1) (PTLIB/ALSA)
2010/11/06 01:23:11.871 12:06.511
GMAudioInputManager_ptlib Opening Device with 1-8000/16
2010/11/06 01:23:11.871 12:06.511
ALSA SetHardwareParams Recorder channels=1 sample rate=8000
2010/11/06 01:23:11.871 12:06.511
ALSA Successfully set period size to 160
2010/11/06 01:23:11.871 12:06.511
ALSA Successfully set periods to 2
2010/11/06 01:23:11.888 12:06.529
ALSA parameters set ok
2010/11/06 01:23:11.888 12:06.529
ALSA Device plughw:1 Opened
2010/11/06 01:23:11.889 12:06.529
ALSA Got volume 12
2010/11/06 01:23:11.889 12:06.529
PCSS Opened mono sound channel "EKIGA" for recording at 8.0 kHz.
2010/11/06 01:23:11.889 12:06.529
OpalMan OnOpenMediaStream
Call[aee4a2a281]-EP<pc>[h10691d182],OpalAudioMediaStream-Source-PCM-16
2010/11/06 01:23:11.889 12:06.529
OpalCon Opened source stream aee4a2a281_1 with format PCM-16
2010/11/06 01:23:11.889 12:06.529
Call IsMediaBypassPossible
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214] session 1
2010/11/06 01:23:11.889 12:06.529
OpalMan IsMediaBypassPossible: session 1
2010/11/06 01:23:11.889 12:06.529
OpalCon IsMediaBypassPossible: default returns false
2010/11/06 01:23:11.889 12:06.529
RTP Found existing media session 1
2010/11/06 01:23:11.889 12:06.530
OpalMan OnOpenMediaStream
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214],OpalRTPMediaStream-Sink-GSM-06.10
2010/11/06 01:23:11.889 12:06.530
OpalCon Opened sink stream aee4a2a281_1 with format GSM-06.10
2010/11/06 01:23:11.890 12:06.530
Patch Created Sink: format=GSM-06.10
2010/11/06 01:23:11.890 12:06.530
OpalPlugin Setting encoder options:
Format Name = GSM-06.10
Media Type = audio
Payload Type = GSM
Encoding Name = gsm
Channels (R/W) = 1 UnsignedInt
Clock Rate (R/O) = 8000 UnsignedInt
Frame Time (R/O) = 160 UnsignedInt
Max Bit Rate (R/O) = 13200 UnsignedInt
Max Frame Size (R/O) = 33 UnsignedInt
Max Frames Per Packet (R/O) = 7 UnsignedInt
Needs Jitter (R/O) = 1 Boolean
Protocol (R/O) = SIP String
Rx Frames Per Packet (R/W) = 7 UnsignedInt
Tx Frames Per Packet (R/W) = 1 UnsignedInt
2010/11/06 01:23:11.890 12:06.530
Patch Created primary codec PCM-16->GSM-06.10 with ID aee4a2a281_1
2010/11/06 01:23:11.890 12:06.530
Media RTP data size cannot be changed to 33, fixed at 528
2010/11/06 01:23:11.890 12:06.530
Patch Added media stream sink OpalRTPMediaStream-Sink-GSM-06.10 using
transcoder PCM-16->GSM-06.10, data size=528
2010/11/06 01:23:11.890 12:06.530
Media Audio source data size set to 320, buffer size set to 160 and 5
buffers.
2010/11/06 01:23:11.890 12:06.530
Media Set data size from 16 to 320
2010/11/06 01:23:11.890 12:06.530
AudioInputCore Setting stream buffer size 5/160
2010/11/06 01:23:11.890 12:06.530
GMAudioInputManager_ptlib Setting buffer size to 160/5
2010/11/06 01:23:11.890 12:06.530
ALSA SetBuffers direction=Recorder size=160 count=5
2010/11/06 01:23:11.890 12:06.530
ALSA SetHardwareParams Recorder channels=1 sample rate=8000
2010/11/06 01:23:11.891 12:06.531
ALSA Successfully set period size to 80
2010/11/06 01:23:11.891 12:06.531
ALSA Successfully set periods to 5
2010/11/06 01:23:11.896 12:06.537
ALSA parameters set ok
2010/11/06 01:23:11.896 12:06.537
OpalCon Sink stream of connection
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214] uses
patch Patch OpalAudioMediaStream-Source-PCM-16 ->
OpalRTPMediaStream-Sink-GSM-06.10
2010/11/06 01:23:11.896 12:06.537
PCSS Adding filters to patch
2010/11/06 01:23:11.896 12:06.537
Media Media format updated on OpalRTPMediaStream-Source-GSM-06.10
2010/11/06 01:23:11.897 12:06.537
OpalPlugin Setting decoder options:
Format Name = GSM-06.10
Media Type = audio
Payload Type = GSM
Encoding Name = gsm
Channels (R/W) = 1 UnsignedInt
Clock Rate (R/O) = 8000 UnsignedInt
Frame Time (R/O) = 160 UnsignedInt
Max Bit Rate (R/O) = 13200 UnsignedInt
Max Frame Size (R/O) = 33 UnsignedInt
Max Frames Per Packet (R/O) = 7 UnsignedInt
Needs Jitter (R/O) = 1 Boolean
Protocol (R/O) = String
Rx Frames Per Packet (R/W) = 7 UnsignedInt
Tx Frames Per Packet (R/W) = 1 UnsignedInt
2010/11/06 01:23:11.897 12:06.537
MediaFormat Merging GSM-06.10 into PCM-16
2010/11/06 01:23:11.897 12:06.537
Patch Updated Sink: format=GSM-06.10 ok=1
2010/11/06 01:23:11.897 12:06.537
SIP Using handler RTP payload [pt=101] for UserInput/RFC2833
2010/11/06 01:23:11.906 12:06.546
SIP Sending PDU (705 bytes) to:
rem=udp$78.46.99.163:5060,local=udp$200.95.160.253:5060,if=200.95.160.253%eth0
SIP/2.0 200 OK
CSeq: 102 INVITE
Via: SIP/2.0/UDP 78.46.99.163:5060;branch=z9hG4bK3db8966b
User-Agent: Ekiga/3.2.6
From: "6988888888" <sip:6988888888 78 46 99 163>;tag=as29beea19
Call-ID: 1df719a6632e00073d32a9ca0ddde50a 78 46 99 163
To: <sip:208426 192 168 1 65:5060>;tag=28071952-a1e7-df11-94e6-002522124214
Contact: <sip:208426 178 128 41 154>
Allow:
INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Content-Type: application/sdp
Content-Length: 218
v=0
o=- 1288999389 1 IN IP4 178.128.41.154
s=Opal SIP Session
c=IN IP4 178.128.41.154
t=0 0
m=audio 5062 RTP/AVP 3 101
a=sendrecv
a=rtpmap:3 gsm/8000/1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16,32,36
2010/11/06 01:23:11.907 12:06.547
OpalUDP Setting interface to 200.95.160.253%eth0
2010/11/06 01:23:11.907 12:06.547
OpalCon SetConnected for
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214]
2010/11/06 01:23:11.907 12:06.547
OpalCon SetPhase from AlertingPhase to ConnectedPhase for
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214]
2010/11/06 01:23:11.907 12:06.547
OpalCon SetPhase from ConnectedPhase to EstablishedPhase for
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214]
2010/11/06 01:23:11.907 12:06.547
OpalCon OnEstablished
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214]
2010/11/06 01:23:11.907 12:06.547
PWLib File handle high water mark set: 63 Thread unblock pipe
2010/11/06 01:23:11.907 12:06.547
Media Starting thread Media Patch:0xb5364b70
2010/11/06 01:23:11.907 12:06.547 Media Patch:0xb5364b70
Patch Thread started for Patch OpalRTPMediaStream-Source-GSM-06.10 ->
OpalAudioMediaStream-Sink-PCM-16
2010/11/06 01:23:11.907 12:06.548
PWLib File handle high water mark set: 65 Thread unblock pipe
2010/11/06 01:23:11.907 12:06.548 Media Patch:0xb5364b70
InfLID Setting jitter buffer time from 160 to 1360
2010/11/06 01:23:11.907 12:06.548
PTLib Thread high water mark set: 13
2010/11/06 01:23:11.907 12:06.548
Media Starting thread Media Patch
2010/11/06 01:23:11.908 12:06.548
OpalCon Media stream threads started.
2010/11/06 01:23:11.908 12:06.548
OpalMan OnEstablished
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214]
2010/11/06 01:23:11.908 12:06.548
RTP Found existing RTP session 1
2010/11/06 01:23:11.908 12:06.548
Call OnEstablished
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214]
2010/11/06 01:23:11.908 12:06.548
OpalCon Media stream threads started.
2010/11/06 01:23:11.908 12:06.548
AEScheduler Removing Event incoming_call_sound from queue
2010/11/06 01:23:11.909 12:06.549 Media Patch:0xb52dcb70
Patch Thread started for Patch OpalAudioMediaStream-Source-PCM-16 ->
OpalRTPMediaStream-Sink-GSM-06.10
2010/11/06 01:23:11.907 12:06.548 Media Patch:0xb5364b70
RTP Opal jitter buffer created: size=35 delay=20-170 ms /160 (20ms)
obj=0xb7309558
2010/11/06 01:23:11.909 12:06.549 Media Patch:0xb5364b70
PWLib File handle high water mark set: 67 Thread unblock pipe
2010/11/06 01:23:11.909 12:06.549 Media Patch:0xb5364b70
PTLib Thread high water mark set: 14
2010/11/06 01:23:11.909 12:06.549 RTP Jitter:0xb529bb70
RTP Jitter RTP receive thread started: 0xb7309558
2010/11/06 01:23:11.910 12:06.550
AEScheduler Removing Event incoming_call_sound from queue
2010/11/06 01:23:11.910 12:06.550
AEScheduler Removing Event ring_tone_sound from queue
2010/11/06 01:23:11.955 12:06.595 Media Patch:0xb52dcb70
RTP Session 1, first sent data: ver=2 pt=GSM psz=33 m=1 x=0 seq=4614
ts=0 src=45b9b204 ccnt=0
2010/11/06 01:23:12.009 12:06.650 Opal Liste...0xb72beb70
PWLib File handle high water mark set: 68 PUDPSocket
2010/11/06 01:23:12.010 12:06.650 Opal Liste...0xb72beb70
OpalUDP Binding to interface: 200.95.160.253:5060
2010/11/06 01:23:12.010 12:06.650 Opal Liste...0xb72beb70
SIP Waiting for PDU on udp$78.46.99.163:5060<if=udp$200.95.160.253:5060>
2010/11/06 01:23:12.010 12:06.650 Opal Liste...0xb72beb70
SIP PDU received:
rem=udp$78.46.99.163:5060,local=udp$200.95.160.253:5060,if=200.95.160.253%eth0
ACK sip:208426 192 168 1 65 SIP/2.0
CSeq: 102 ACK
Via: SIP/2.0/UDP 78.46.99.163:5060;branch=z9hG4bK75ed6d7f
User-Agent: OmniVoice
From: "6988888888" <sip:6988888888 78 46 99 163>;tag=as29beea19
Call-ID: 1df719a6632e00073d32a9ca0ddde50a 78 46 99 163
To:
<sip:208426 178 128 41 154:55458>;tag=28071952-a1e7-df11-94e6-002522124214
Contact: <sip:6988888888 78 46 99 163>
Content-Length: 0
Max-Forwards: 70
Remote-Party-ID: "6988888888"
<sip:6988888888 78 46 99 163>;privacy=off;screen=no
2010/11/06 01:23:12.011 12:06.651 Opal Liste...0xb72beb70
SIP Queueing PDU "102 ACK sip:208426 192 168 1 65",
transaction=z9hG4bK75ed6d7f, token=28071952-a1e7-df11-94e6-002522124214
2010/11/06 01:23:12.011 12:06.651 Opal Liste...0xb72beb70
Opal Transport clean up on termination
2010/11/06 01:23:12.011 12:06.651 Opal Liste...0xb72beb70
Opal Transport Close
2010/11/06 01:23:12.011 12:06.651 Pool:0xb53a5b70
SIP Handling PDU "102 ACK sip:208426 192 168 1 65" for
token=28071952-a1e7-df11-94e6-002522124214
2010/11/06 01:23:12.011 12:06.651 Pool:0xb53a5b70
SIP ACK received: EstablishedPhase
2010/11/06 01:23:12.011 12:06.651 Pool:0xb53a5b70
OpalCon Media stream threads started.
2010/11/06 01:23:12.011 12:06.651 Pool:0xb53a5b70
SIP Handled PDU "102 ACK sip:208426 192 168 1 65"
2010/11/06 01:23:12.011 12:06.651 Opal Liste...0xb72beb70
Opal Deleted transport udp$78.46.99.163:5060<if=udp$200.95.160.253:5060>
2010/11/06 01:23:12.749 12:07.390 AudioEvent...0xb7535b70
AudioOutputCore Closing current device
2010/11/06 01:23:12.955 12:07.595 Media Patch:0xb52dcb70
RTP Session 1, transmit statistics: packets=51 octets=1683
avgTime=20 maxTime=30 minTime=10
2010/11/06 01:23:13.954 12:08.595 Media Patch:0xb52dcb70
RTP Session 1, transmit statistics: packets=101 octets=3333
avgTime=20 maxTime=30 minTime=10
2010/11/06 01:23:14.954 12:09.594 Media Patch:0xb52dcb70
RTP Session 1, transmit statistics: packets=151 octets=4983
avgTime=19 maxTime=30 minTime=10
2010/11/06 01:23:15.954 12:10.594 Media Patch:0xb52dcb70
RTP Session 1, transmit statistics: packets=201 octets=6633
avgTime=20 maxTime=30 minTime=10
2010/11/06 01:23:16.954 12:11.594 Media Patch:0xb52dcb70
RTP Session 1, transmit statistics: packets=251 octets=8283
avgTime=20 maxTime=30 minTime=10
2010/11/06 01:23:17.954 12:12.594 Media Patch:0xb52dcb70
RTP Session 1, transmit statistics: packets=301 octets=9933
avgTime=20 maxTime=30 minTime=10
2010/11/06 01:23:18.953 12:13.594 Media Patch:0xb52dcb70
RTP Session 1, transmit statistics: packets=351 octets=11583
avgTime=20 maxTime=30 minTime=10
2010/11/06 01:23:19.953 12:14.593 Media Patch:0xb52dcb70
RTP Session 1, transmit statistics: packets=401 octets=13233
avgTime=19 maxTime=30 minTime=10
2010/11/06 01:23:20.953 12:15.593 Media Patch:0xb52dcb70
RTP Session 1, transmit statistics: packets=451 octets=14883
avgTime=20 maxTime=30 minTime=10
2010/11/06 01:23:21.507 12:16.148 Opal Liste...0xb72beb70
OpalUDP Binding to interface: 200.95.160.253:5060
2010/11/06 01:23:21.508 12:16.148 Opal Liste...0xb72beb70
SIP Waiting for PDU on udp$78.46.99.163:5060<if=udp$200.95.160.253:5060>
2010/11/06 01:23:21.508 12:16.148 Opal Liste...0xb72beb70
SIP PDU received:
rem=udp$78.46.99.163:5060,local=udp$200.95.160.253:5060,if=200.95.160.253%eth0
BYE sip:208426 192 168 1 65 SIP/2.0
CSeq: 103 BYE
Via: SIP/2.0/UDP 78.46.99.163:5060;branch=z9hG4bK7ccc4787
User-Agent: OmniVoice
From: "6988888888" <sip:6988888888 78 46 99 163>;tag=as29beea19
Call-ID: 1df719a6632e00073d32a9ca0ddde50a 78 46 99 163
To:
<sip:208426 178 128 41 154:55458>;tag=28071952-a1e7-df11-94e6-002522124214
X-Asterisk-HangupCause: Normal Clearing
X-Asterisk-HangupCauseCode: 16
Content-Length: 0
Max-Forwards: 70
Remote-Party-ID: "6988888888"
<sip:6988888888 78 46 99 163>;privacy=off;screen=no
2010/11/06 01:23:21.509 12:16.149 Opal Liste...0xb72beb70
SIP Queueing PDU "103 BYE sip:208426 192 168 1 65",
transaction=z9hG4bK7ccc4787, token=28071952-a1e7-df11-94e6-002522124214
2010/11/06 01:23:21.509 12:16.149 Opal Liste...0xb72beb70
Opal Transport clean up on termination
2010/11/06 01:23:21.509 12:16.149 Opal Liste...0xb72beb70
Opal Transport Close
2010/11/06 01:23:21.509 12:16.149 Pool:0xb53a5b70
SIP Handling PDU "103 BYE sip:208426 192 168 1 65" for
token=28071952-a1e7-df11-94e6-002522124214
2010/11/06 01:23:21.509 12:16.149 Pool:0xb53a5b70
SIP BYE received for call 1df719a6632e00073d32a9ca0ddde50a 78 46 99 163
2010/11/06 01:23:21.509 12:16.149 Opal Liste...0xb72beb70
Opal Deleted transport udp$78.46.99.163:5060<if=udp$200.95.160.253:5060>
2010/11/06 01:23:21.509 12:16.149 Pool:0xb53a5b70
SIP Sending PDU (312 bytes) to:
rem=udp$78.46.99.163:5060,local=udp$200.95.160.253:5060,if=200.95.160.253%eth0
SIP/2.0 200 OK
CSeq: 103 BYE
Via: SIP/2.0/UDP 78.46.99.163:5060;branch=z9hG4bK7ccc4787
From: "6988888888" <sip:6988888888 78 46 99 163>;tag=as29beea19
Call-ID: 1df719a6632e00073d32a9ca0ddde50a 78 46 99 163
To:
<sip:208426 178 128 41 154:55458>;tag=28071952-a1e7-df11-94e6-002522124214
Content-Length: 0
2010/11/06 01:23:21.509 12:16.150 Pool:0xb53a5b70
OpalUDP Setting interface to 200.95.160.253%eth0
2010/11/06 01:23:21.510 12:16.150 Pool:0xb53a5b70
SIP Product Info: name="OmniVoice", version="", vendor="", comments=""
2010/11/06 01:23:21.510 12:16.150 Pool:0xb53a5b70
OpalCon SetPhase from EstablishedPhase to ReleasingPhase for
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214]
2010/11/06 01:23:21.510 12:16.150 Pool:0xb53a5b70
OpalCon Releasing
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214]
2010/11/06 01:23:21.510 12:16.150 Pool:0xb53a5b70
OpalCon Call end reason for
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214] set to
EndedByRemoteUser
2010/11/06 01:23:21.510 12:16.150 Pool:0xb53a5b70
PWLib File handle high water mark set: 69 Thread unblock pipe
2010/11/06 01:23:21.510 12:16.150 Pool:0xb53a5b70
PTLib Thread high water mark set: 15
2010/11/06 01:23:21.510 12:16.150 Pool:0xb53a5b70
SIP Handled PDU "103 BYE sip:208426 192 168 1 65"
2010/11/06 01:23:21.510 12:16.150 OnRelease:0xb525ab70
SIP OnReleased:
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214], phase =
ReleasingPhase
2010/11/06 01:23:21.510 12:16.150 OnRelease:0xb525ab70
OpalCon SetPhase from ReleasingPhase to ReleasingPhase for
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214]
2010/11/06 01:23:21.510 12:16.151 OnRelease:0xb525ab70
Media Closing RTP for OpalRTPMediaStream-Source-GSM-06.10
2010/11/06 01:23:21.510 12:16.151 OnRelease:0xb525ab70
RTP_UDP Session 1, Shutting down read.
2010/11/06 01:23:21.511 12:16.151 RTP Jitter:0xb529bb70
RTP_UDP Session 1, Read shutdown.
2010/11/06 01:23:21.511 12:16.151 RTP Jitter:0xb529bb70
RTP Jitter RTP receive thread ended
2010/11/06 01:23:21.510 12:16.151 OnRelease:0xb525ab70
Media Closing stream OpalRTPMediaStream-Source-GSM-06.10
2010/11/06 01:23:21.511 12:16.151 RTP Jitter:0xb529bb70
RTP Jitter RTP receive thread finished: 0xb7309558
2010/11/06 01:23:21.511 12:16.151 Media Patch:0xb5364b70
Jitter Buffer shutting down.
2010/11/06 01:23:21.511 12:16.151 Media Patch:0xb5364b70
Patch Thread ended because source read failed
2010/11/06 01:23:21.511 12:16.151 Media Patch:0xb5364b70
Patch Thread ended for Patch OpalRTPMediaStream-Source-GSM-06.10 ->
OpalAudioMediaStream-Sink-PCM-16
2010/11/06 01:23:21.511 12:16.151 OnRelease:0xb525ab70
Media Disconnecting OpalRTPMediaStream-Source-GSM-06.10 from patch
thread Patch OpalRTPMediaStream-Source-GSM-06.10 ->
OpalAudioMediaStream-Sink-PCM-16
2010/11/06 01:23:21.511 12:16.151 OnRelease:0xb525ab70
Patch Closing media patch Patch OpalRTPMediaStream-Source-GSM-06.10
-> OpalAudioMediaStream-Sink-PCM-16
2010/11/06 01:23:21.511 12:16.151 OnRelease:0xb525ab70
AudioOutputCore Closing current device
2010/11/06 01:23:21.511 12:16.151 OnRelease:0xb525ab70
GMAudioOutputManager_ptlib Closing device[0] SB Live! 5.1 (PTLIB/ALSA)
2010/11/06 01:23:21.511 12:16.151 OnRelease:0xb525ab70
ALSA Closing plughw:0
2010/11/06 01:23:21.511 12:16.152 OnRelease:0xb525ab70
GMAudioOutputManager_ptlib Setting Device[0] SB Live! 5.1 (PTLIB/ALSA)
2010/11/06 01:23:21.511 12:16.152 OnRelease:0xb525ab70
Media Closing stream OpalAudioMediaStream-Sink-PCM-16
2010/11/06 01:23:21.511 12:16.152 OnRelease:0xb525ab70
Media Disconnecting OpalAudioMediaStream-Sink-PCM-16 from patch
thread Patch OpalRTPMediaStream-Source-GSM-06.10 ->
OpalAudioMediaStream-Sink-PCM-16
2010/11/06 01:23:21.511 12:16.152 OnRelease:0xb525ab70
Patch Removing media stream sink OpalAudioMediaStream-Sink-PCM-16
2010/11/06 01:23:21.512 12:16.152 OnRelease:0xb525ab70
OpalCon Removed media stream OpalAudioMediaStream-Sink-PCM-16
2010/11/06 01:23:21.512 12:16.152 OnRelease:0xb525ab70
Patch Waiting for media patch thread to stop Patch
OpalRTPMediaStream-Source-GSM-06.10
2010/11/06 01:23:21.512 12:16.152 OnRelease:0xb525ab70
OpalCon Removed media stream OpalRTPMediaStream-Source-GSM-06.10
2010/11/06 01:23:21.512 12:16.152 OnRelease:0xb525ab70
Media Closing RTP for OpalRTPMediaStream-Sink-GSM-06.10
2010/11/06 01:23:21.512 12:16.152 OnRelease:0xb525ab70
RTP_UDP Session 1, Shutting down write.
2010/11/06 01:23:21.512 12:16.152 OnRelease:0xb525ab70
Media Closing stream OpalRTPMediaStream-Sink-GSM-06.10
2010/11/06 01:23:21.512 12:16.152 OnRelease:0xb525ab70
Media Disconnecting OpalRTPMediaStream-Sink-GSM-06.10 from patch
thread Patch OpalAudioMediaStream-Source-PCM-16 ->
OpalRTPMediaStream-Sink-GSM-06.10
2010/11/06 01:23:21.512 12:16.152 OnRelease:0xb525ab70
Patch Removing media stream sink OpalRTPMediaStream-Sink-GSM-06.10
2010/11/06 01:23:21.513 12:16.153 Media Patch:0xb52dcb70
RTP_UDP Session 1, write shutdown.
2010/11/06 01:23:21.513 12:16.153 Media Patch:0xb52dcb70
Patch WriteFrame failed
2010/11/06 01:23:21.513 12:16.153 Media Patch:0xb52dcb70
Patch Thread ended because all sink writes failed failed
2010/11/06 01:23:21.513 12:16.153 Media Patch:0xb52dcb70
Patch Thread ended for Patch OpalAudioMediaStream-Source-PCM-16
2010/11/06 01:23:21.513 12:16.153 OnRelease:0xb525ab70
OpalCon Removed media stream OpalRTPMediaStream-Sink-GSM-06.10
2010/11/06 01:23:21.513 12:16.153 OnRelease:0xb525ab70
Call GetOtherPartyConnection
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214]
2010/11/06 01:23:21.513 12:16.153 OnRelease:0xb525ab70
AudioInputCore Stopping Stream
2010/11/06 01:23:21.513 12:16.154 OnRelease:0xb525ab70
AudioInputCore Closing current device
2010/11/06 01:23:21.513 12:16.154 OnRelease:0xb525ab70
GMAudioInputManager_ptlib Closing device HDA Intel (1) (PTLIB/ALSA)
2010/11/06 01:23:21.513 12:16.154 OnRelease:0xb525ab70
ALSA Closing plughw:1
2010/11/06 01:23:21.514 12:16.154 OnRelease:0xb525ab70
GMAudioInputManager_ptlib Setting Device HDA Intel (1) (PTLIB/ALSA)
2010/11/06 01:23:21.514 12:16.154 OnRelease:0xb525ab70
Media Closing stream OpalAudioMediaStream-Source-PCM-16
2010/11/06 01:23:21.514 12:16.154 OnRelease:0xb525ab70
Media Disconnecting OpalAudioMediaStream-Source-PCM-16 from patch
thread Patch OpalAudioMediaStream-Source-PCM-16
2010/11/06 01:23:21.514 12:16.154 OnRelease:0xb525ab70
Patch Closing media patch Patch OpalAudioMediaStream-Source-PCM-16
2010/11/06 01:23:21.514 12:16.154 OnRelease:0xb525ab70
Patch Waiting for media patch thread to stop Patch
OpalAudioMediaStream-Source-PCM-16
2010/11/06 01:23:21.514 12:16.154 OnRelease:0xb525ab70
OpalCon Removed media stream OpalAudioMediaStream-Source-PCM-16
2010/11/06 01:23:21.514 12:16.154 OnRelease:0xb525ab70
OpalCon Media streams closed.
2010/11/06 01:23:21.514 12:16.154 OnRelease:0xb525ab70
OpalCon SetPhase from ReleasingPhase to ReleasedPhase for
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214]
2010/11/06 01:23:21.514 12:16.154 OnRelease:0xb525ab70
OpalCon OnReleased
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214]
2010/11/06 01:23:21.514 12:16.154 OnRelease:0xb525ab70
OpalEP OnReleased
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214]
2010/11/06 01:23:21.514 12:16.154 OnRelease:0xb525ab70
OpalMan OnReleased
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214]
2010/11/06 01:23:21.514 12:16.155 OnRelease:0xb525ab70
Call OnReleased
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214]
2010/11/06 01:23:21.514 12:16.155 OnRelease:0xb525ab70
OpalCon SetPhase from EstablishedPhase to ReleasingPhase for
Call[aee4a2a281]-EP<pc>[h10691d182]
2010/11/06 01:23:21.514 12:16.155 OnRelease:0xb525ab70
OpalCon Releasing Call[aee4a2a281]-EP<pc>[h10691d182]
2010/11/06 01:23:21.515 12:16.155 OnRelease:0xb525ab70
OpalCon Call end reason for Call[aee4a2a281]-EP<pc>[h10691d182] set
to EndedByRemoteUser
2010/11/06 01:23:21.515 12:16.155 OnRelease:0xb525ab70
OpalCon OnReleased Call[aee4a2a281]-EP<pc>[h10691d182]
2010/11/06 01:23:21.515 12:16.155 OnRelease:0xb525ab70
OpalEP OnReleased Call[aee4a2a281]-EP<pc>[h10691d182]
2010/11/06 01:23:21.515 12:16.155 OnRelease:0xb525ab70
OpalMan OnReleased Call[aee4a2a281]-EP<pc>[h10691d182]
2010/11/06 01:23:21.515 12:16.155 OnRelease:0xb525ab70
Call OnReleased Call[aee4a2a281]-EP<pc>[h10691d182]
2010/11/06 01:23:21.515 12:16.155 OnRelease:0xb525ab70
OpalCon Media streams closed.
2010/11/06 01:23:21.515 12:16.155 OnRelease:0xb525ab70
OpalCon Media streams closed.
2010/11/06 01:23:21.515 12:16.155 OnRelease:0xb525ab70
OpalCon OnRelease thread completed for
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214]
2010/11/06 01:23:21.575 12:16.216 Opal Garbage:0xb74b3b70
PCSS Deleted PC sound system connection.
2010/11/06 01:23:21.575 12:16.216 Opal Garbage:0xb74b3b70
OpalCon Connection Call[aee4a2a281]-EP<pc>[h10691d182] destroyed.
2010/11/06 01:23:21.576 12:16.216 Opal Garbage:0xb74b3b70
Opal Transport clean up on termination
2010/11/06 01:23:21.576 12:16.216 Opal Garbage:0xb74b3b70
Opal Transport Close
2010/11/06 01:23:21.576 12:16.216 Opal Garbage:0xb74b3b70
Opal Transport clean up on termination
2010/11/06 01:23:21.576 12:16.216 Opal Garbage:0xb74b3b70
Opal Transport Close
2010/11/06 01:23:21.576 12:16.216 Opal Garbage:0xb74b3b70
PWLib File handle low water mark set: 60 PUDPSocket
2010/11/06 01:23:21.576 12:16.216 Opal Garbage:0xb74b3b70
Opal Deleted transport udp$78.46.99.163:5060<if=udp$200.95.160.253:5060>
2010/11/06 01:23:21.576 12:16.216 Opal Garbage:0xb74b3b70
SIP Deleted connection.
2010/11/06 01:23:21.576 12:16.217 Opal Garbage:0xb74b3b70
RTP Closing session 1
2010/11/06 01:23:21.577 12:16.217 Opal Garbage:0xb74b3b70
RTP Deleting session 1
2010/11/06 01:23:21.577 12:16.217 Opal Garbage:0xb74b3b70
InfLID Switching off jitter buffer.
2010/11/06 01:23:21.577 12:16.217 Opal Garbage:0xb74b3b70
RTP Removing jitter buffer 0xb7309558 RTP Jitter:0xb529bb70
2010/11/06 01:23:21.577 12:16.217 Opal Garbage:0xb74b3b70
RTP_UDP Session 1, Shutting down write.
2010/11/06 01:23:21.577 12:16.217 Opal Garbage:0xb74b3b70
RTP Session 1, final statistics:
packetsSent = 478
octetsSent = 15774
averageSendTime = 20
maximumSendTime = 30
minimumSendTime = 10
packetsReceived = 0
octetsReceived = 0
packetsLost = 0
packetsTooLate = 0
packetOverruns = 0
packetsOutOfOrder = 0
averageReceiveTime= 0
maximumReceiveTime= 0
minimumReceiveTime= 0
averageJitter = 0
maximumJitter = 0
2010/11/06 01:23:21.577 12:16.217 Opal Garbage:0xb74b3b70
OpalCon Connection
Call[aee4a2a281]-EP<sip>[28071952-a1e7-df11-94e6-002522124214] destroyed.
2010/11/06 01:23:21.607 12:16.247
AEScheduler Removing Event incoming_call_sound from queue
2010/11/06 01:23:21.607 12:16.247
AEScheduler Removing Event ring_tone_sound from queue
2010/11/06 01:23:21.607 12:16.247
OpalMan OnClearedCall Call[aee4a2a281] from
"sip:6988888888 78 46 99 163" to "sip:208426 192 168 1 65:5060"
2010/11/06 01:23:21.607 12:16.247
OPAL Attempting to close recording on call aee4a2a281 when not recording.
2010/11/06 01:23:21.607 12:16.247
AEScheduler Removing Event incoming_call_sound from queue
2010/11/06 01:23:21.607 12:16.247
AEScheduler Removing Event ring_tone_sound from queue
2010/11/06 01:23:22.607 12:17.247
Call Call[aee4a2a281] destroyed.
2010/11/06 01:23:25.614 12:20.255 Housekeeper:0xb72ffb70
PWLib File handle low water mark set: 58 PUDPSocket
2010/11/06 01:24:10.855 13:05.495 Network In...0xb7472b70
IfaceMon Finished interface monitor thread.
For more pleasant reading I have uploaded the output on pastebin :
http://pastebin.com/1FrBbCxS
Any help will be really appreciated :D
[
Date Prev][
Date Next] [
Thread Prev][
Thread Next]
[
Thread Index]
[
Date Index]
[
Author Index]
