rendered paste body2011/05/16 13:14:32.806 0:00.038 Version 3.2.6 by on Unix Linux (2.6.32-31-generic-i686) with PTLib (v2.6.5) at 2011/5/16 13:14:32.806
2011/05/16 13:14:32.807 0:00.039 Ekiga git revision: unknown
2011/05/16 13:14:32.808 0:00.040 Ekiga registered on D-Bus: org.ekiga.Ekiga
2011/05/16 13:14:32.808 0:00.040 PWLib File handle high water mark set: 17 Thread unblock pipe
2011/05/16 13:14:32.808 0:00.040 PTLib Thread high water mark set: 2
2011/05/16 13:14:32.809 0:00.041 PWLib File handle high water mark set: 19 Thread unblock pipe
2011/05/16 13:14:32.809 0:00.041 PTLib Thread high water mark set: 3
2011/05/16 13:14:32.809 0:00.041 PWLib File handle high water mark set: 21 Thread unblock pipe
2011/05/16 13:14:32.810 0:00.042 PWLib File handle high water mark set: 23 Thread unblock pipe
2011/05/16 13:14:32.810 0:00.042 PTLib Thread high water mark set: 4
2011/05/16 13:14:32.811 0:00.043 HalManager_dbus Initialising HAL Manager
2011/05/16 13:14:32.812 0:00.044 HalManager_dbus Populating device list
2011/05/16 13:14:32.828 0:00.060 HalManager_dbus Detected V4L capabilities on /dev/video0 name: USB 2.0 Camera
2011/05/16 13:14:32.828 0:00.060 HalManager_dbus Detected V4L2 capabilities on /dev/video0 name: USB 2.0 Camera
2011/05/16 13:14:32.957 0:00.189 HalManager_dbus Populated device list with 17 devices
2011/05/16 13:14:32.958 0:00.190 HalManager_dbus Populating interface list
2011/05/16 13:14:32.958 0:00.190 HalManager_dbus Populating full interface list failed - Method "getDevices" with signature "" on interface "org.freedesktop.NetworkManager" doesn't exist
2011/05/16 13:14:32.959 0:00.191 Detecting V4L2 devices
2011/05/16 13:14:32.959 0:00.191 PV4L2Plugin detected device metadata at /sys/class/video4linux/
2011/05/16 13:14:32.995 0:00.227 PWLib File handle high water mark set: 28 Thread unblock pipe
2011/05/16 13:14:32.995 0:00.227 PTLib Thread high water mark set: 5
2011/05/16 13:14:32.995 0:00.227 OpalMan Created manager.
2011/05/16 13:14:32.996 0:00.228 OpalMan Attached endpoint with prefix pc
2011/05/16 13:14:32.996 0:00.228 OpalEP Created endpoint: pc
2011/05/16 13:14:33.055 0:00.287 PCSS Created PC sound system endpoint.
Players:
Default
HDA Intel
HDA Intel (1)
HDA Intel (2)
EKIGA
*.wav
/dev/dsp
Recorders:
Default
HDA Intel
EKIGA
*.wav
/dev/dsp
2011/05/16 13:14:33.055 0:00.287 OPAL SetMediaFormatOrder()
2011/05/16 13:14:33.055 0:00.287 OPAL SetMediaFormatMask()
2011/05/16 13:14:33.056 0:00.288 OpalMan Attached endpoint with prefix sip
2011/05/16 13:14:33.056 0:00.288 OpalEP Created endpoint: sip
2011/05/16 13:14:33.056 0:00.288 PWLib File handle high water mark set: 29 PUDPSocket
2011/05/16 13:14:33.056 0:00.288 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)
88.178.126.137 [fe:80:00:00:00:00:00:00:02:21:6b:ff:fe:cd:37:f8] <00-21-6B-CD-37-F8> (wlan0)
2011/05/16 13:14:33.056 0:00.288 PWLib File handle high water mark set: 30 Thread unblock pipe
2011/05/16 13:14:33.056 0:00.288 PTLib Thread high water mark set: 6
2011/05/16 13:14:33.056 0:00.288 PWLib File handle high water mark set: 32 Thread unblock pipe
2011/05/16 13:14:33.056 0:00.288 Network In...0xb4600b70 IfaceMon Started interface monitor thread.
2011/05/16 13:14:33.057 0:00.289 PTLib Thread high water mark set: 7
2011/05/16 13:14:33.057 0:00.289 OpalMan Attached endpoint with prefix sips
2011/05/16 13:14:33.057 0:00.289 SIP Created endpoint.
2011/05/16 13:14:33.057 0:00.289 Network In...0xb4600b70 PWLib File handle high water mark set: 33 PUDPSocket
2011/05/16 13:14:33.057 0:00.289 MonSock Created socket bundle for all interfaces.
2011/05/16 13:14:33.057 0:00.289 PWLib File handle high water mark set: 34 PUDPSocket
2011/05/16 13:14:33.057 0:00.289 MonSock Created bundled UDP socket 88.178.126.137:5060
2011/05/16 13:14:33.057 0:00.289 PWLib File handle high water mark set: 36 Thread unblock pipe
2011/05/16 13:14:33.057 0:00.289 PTLib Thread high water mark set: 8
2011/05/16 13:14:33.057 0:00.289 Opal Liste...0xb457eb70 Listen Started listening thread on udp$*:5060
2011/05/16 13:14:33.058 0:00.290 OpalMan Added route "sip:.*=pc:*"
2011/05/16 13:14:33.058 0:00.290 OpalMan Added route "pc:.*=sip:<da>"
2011/05/16 13:14:33.058 0:00.290 OpalMan Attached endpoint with prefix h323
2011/05/16 13:14:33.058 0:00.290 OpalEP Created endpoint: h323
2011/05/16 13:14:33.058 0:00.290 OpalMan Attached endpoint with prefix h323s
2011/05/16 13:14:33.058 0:00.290 H323 Created endpoint.
2011/05/16 13:14:33.058 0:00.290 PWLib File handle high water mark set: 37 PTCPSocket
2011/05/16 13:14:33.058 0:00.290 PWLib File handle high water mark set: 39 Thread unblock pipe
2011/05/16 13:14:33.058 0:00.290 PTLib Thread high water mark set: 9
2011/05/16 13:14:33.058 0:00.290 OpalMan Added route "h323:.*=pc:<db>"
2011/05/16 13:14:33.058 0:00.290 Opal Liste...0xb43ffb70 Listen Started listening thread on tcp$*:1720
2011/05/16 13:14:33.058 0:00.290 OpalMan Added route "pc:.*=h323:<da>"
2011/05/16 13:14:33.058 0:00.291 Opal Liste...0xb43ffb70 Listen Waiting on socket accept on tcp$*:1720
2011/05/16 13:14:33.066 0:00.298 MediaFormat Removing codecs G.711-ALaw-64k,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.722-64k,theora,H.261,H.261-CIF,H.261-QCIF
2011/05/16 13:14:33.067 0:00.299 OPAL SetMediaFormatMask(PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,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)
2011/05/16 13:14:33.067 0:00.299 OPAL SetMediaFormatOrder(G.711-ALaw-64k,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.722-64k,theora,H.261,H.261-CIF,H.261-QCIF)
2011/05/16 13:14:33.073 0:00.305 MediaFormat Removing codecs G.711-ALaw-64k,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.722-64k,theora,H.261,H.261-CIF,H.261-QCIF
2011/05/16 13:14:33.074 0:00.306 OPAL SetMediaFormatMask(PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,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)
2011/05/16 13:14:33.074 0:00.306 OPAL SetMediaFormatOrder(G.711-ALaw-64k,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.722-64k,theora,H.261,H.261-CIF,H.261-QCIF)
2011/05/16 13:14:33.085 0:00.317 PWLib File handle high water mark set: 41 Thread unblock pipe
2011/05/16 13:14:33.085 0:00.317 PTLib Thread high water mark set: 10
2011/05/16 13:14:33.086 0:00.318 StunDetector:0xb43beb70 PWLib File handle high water mark set: 42 PUDPSocket
2011/05/16 13:14:33.121 0:00.353 PWLib File handle high water mark set: 46 PUDPSocket
2011/05/16 13:14:33.121 0:00.353 SIP Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:500@ekiga.net, id=2c7f4458-1b7e-e011-893b-00216bcd37f8@visio-laptop
2011/05/16 13:14:33.121 0:00.353 DNS SRV Lookup ekiga.net service _sip._udp
2011/05/16 13:14:33.193 0:00.425 SIP No SRV record found.
2011/05/16 13:14:33.263 0:00.495 OpalUDP Binding to interface: 0.0.0.0:5060
2011/05/16 13:14:33.263 0:00.495 SIP Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2011/05/16 13:14:33.263 0:00.495 OpalUDP Started connect to 86.64.162.35:5060
2011/05/16 13:14:33.264 0:00.496 OpalUDP Writing to interface 0 - "88.178.126.137%wlan0"
2011/05/16 13:14:33.264 0:00.496 OpalMan Listener interfaces: associated transport=None
udp$88.178.126.137:5060
2011/05/16 13:14:38.408 0:05.640 StunDetector:0xb43beb70 PWLib File handle low water mark set: 44 PUDPSocket
2011/05/16 13:14:38.525 0:05.757 StunDetector:0xb43beb70 PWLib File handle low water mark set: 42 PUDPSocket
2011/05/16 13:14:38.642 0:05.874 StunDetector:0xb43beb70 OPAL STUN server "stun.ekiga.net" replies Open NAT, external IP 88.178.126.137
2011/05/16 13:14:38.653 0:05.885 PWLib File handle low water mark set: 40 PUDPSocket
2011/05/16 13:14:38.653 0:05.885 SIP Transaction created.
2011/05/16 13:14:38.656 0:05.888 DNS SRV Lookup ekiga.net service _sip._udp
2011/05/16 13:14:38.725 0:05.957 SIP No SRV record found.
2011/05/16 13:14:38.725 0:05.957 SIP Transaction remote address is udp$ekiga.net:5060
2011/05/16 13:14:38.725 0:05.957 SIP Sending PDU (560 bytes) to: rem=udp$86.64.162.35:5060,local=udp$88.178.126.137:5060,if=88.178.126.137%wlan0
SUBSCRIBE sip:500@ekiga.net SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 88.178.126.137:5060;branch=z9hG4bK7ad7905b-1b7e-e011-893b-00216bcd37f8;rport
User-Agent: Ekiga/3.2.6
From: "visio" <sip:visio@88.178.126.137>;tag=9a9e905b-1b7e-e011-893b-00216bcd37f8
Call-ID: 2c7f4458-1b7e-e011-893b-00216bcd37f8@visio-laptop
To: <sip:500@ekiga.net>
Contact: <sip:visio@88.178.126.137>
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
2011/05/16 13:14:38.726 0:05.958 OpalUDP Setting interface to 88.178.126.137%wlan0
2011/05/16 13:14:38.726 0:05.958 SIP Transaction timers set: retry=0.500, completion=6.000
2011/05/16 13:14:38.726 0:05.958 SIP Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:500@ekiga.net, id=f0c49b5b-1b7e-e011-893b-00216bcd37f8@visio-laptop
2011/05/16 13:14:38.726 0:05.958 DNS SRV Lookup ekiga.net service _sip._udp
2011/05/16 13:14:38.766 0:05.998 Opal Liste...0xb457eb70 OpalUDP Binding to interface: 88.178.126.137:5060
2011/05/16 13:14:38.766 0:05.998 Opal Liste...0xb457eb70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$88.178.126.137:5060>
2011/05/16 13:14:38.767 0:05.999 Opal Liste...0xb457eb70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$88.178.126.137:5060,if=88.178.126.137%wlan0
SIP/2.0 489 Event Not Supported
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 88.178.126.137:5060;branch=z9hG4bK7ad7905b-1b7e-e011-893b-00216bcd37f8;rport=5060
Server: Kamailio (1.5.3-notls (i386/linux))
From: "visio" <sip:visio@88.178.126.137>;tag=9a9e905b-1b7e-e011-893b-00216bcd37f8
Call-ID: 2c7f4458-1b7e-e011-893b-00216bcd37f8@visio-laptop
To: <sip:500@ekiga.net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.b7c7
Content-Length: 0
2011/05/16 13:14:38.767 0:05.999 Opal Liste...0xb457eb70 SIP Queueing PDU "2 SUBSCRIBE <489>", transaction=z9hG4bK7ad7905b-1b7e-e011-893b-00216bcd37f8, token=2c7f4458-1b7e-e011-893b-00216bcd37f8@visio-laptop
2011/05/16 13:14:38.767 0:05.999 Pool:0xb43beb70 SIP Handling PDU "2 SUBSCRIBE <489>" for transaction=z9hG4bK7ad7905b-1b7e-e011-893b-00216bcd37f8
2011/05/16 13:14:38.767 0:05.999 Opal Liste...0xb457eb70 Opal Transport clean up on termination
2011/05/16 13:14:38.768 0:06.000 Opal Liste...0xb457eb70 Opal Transport Close
2011/05/16 13:14:38.768 0:06.000 Pool:0xb43beb70 SIP SUBSCRIBE transaction id=z9hG4bK7ad7905b-1b7e-e011-893b-00216bcd37f8 completed.
2011/05/16 13:14:38.768 0:06.000 Pool:0xb43beb70 OpalUDP Setting interface to 88.178.126.137%wlan0
2011/05/16 13:14:38.768 0:06.000 Pool:0xb43beb70 SIP Not retrying SUBSCRIBE due to error response 489 Bad Event
2011/05/16 13:14:38.768 0:06.000 Pool:0xb43beb70 SIP Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:500@ekiga.net, id=2c7f4458-1b7e-e011-893b-00216bcd37f8@visio-laptop
2011/05/16 13:14:38.768 0:06.000 Opal Liste...0xb457eb70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$88.178.126.137:5060>
2011/05/16 13:14:38.768 0:06.000 Pool:0xb43beb70 SIP Handled PDU "2 SUBSCRIBE <489>"
2011/05/16 13:14:38.798 0:06.030 SIP No SRV record found.
2011/05/16 13:14:38.798 0:06.030 OpalUDP Binding to interface: 0.0.0.0:5060
2011/05/16 13:14:38.798 0:06.030 SIP Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2011/05/16 13:14:38.799 0:06.031 OpalUDP Started connect to 86.64.162.35:5060
2011/05/16 13:14:38.799 0:06.031 OpalUDP Writing to interface 0 - "88.178.126.137%wlan0"
2011/05/16 13:14:38.799 0:06.031 OpalMan Listener interfaces: associated transport=None
udp$88.178.126.137:5060
2011/05/16 13:14:38.801 0:06.033 SIP Transaction created.
2011/05/16 13:14:38.803 0:06.035 DNS SRV Lookup ekiga.net service _sip._udp
2011/05/16 13:14:38.869 0:06.101 SIP No SRV record found.
2011/05/16 13:14:38.869 0:06.101 SIP Transaction remote address is udp$ekiga.net:5060
2011/05/16 13:14:38.869 0:06.101 SIP Sending PDU (570 bytes) to: rem=udp$86.64.162.35:5060,local=udp$88.178.126.137:5060,if=88.178.126.137%wlan0
SUBSCRIBE sip:500@ekiga.net SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 88.178.126.137:5060;branch=z9hG4bKbc51a75b-1b7e-e011-893b-00216bcd37f8;rport
User-Agent: Ekiga/3.2.6
From: "visio" <sip:visio@88.178.126.137>;tag=701ba75b-1b7e-e011-893b-00216bcd37f8
Call-ID: f0c49b5b-1b7e-e011-893b-00216bcd37f8@visio-laptop
To: <sip:500@ekiga.net>
Contact: <sip:500@88.178.126.137>
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
2011/05/16 13:14:38.869 0:06.101 OpalUDP Setting interface to 88.178.126.137%wlan0
2011/05/16 13:14:38.870 0:06.102 SIP Transaction timers set: retry=0.500, completion=6.000
2011/05/16 13:14:38.870 0:06.102 SIP Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:501@ekiga.net, id=80b5b15b-1b7e-e011-893b-00216bcd37f8@visio-laptop
2011/05/16 13:14:38.870 0:06.102 DNS SRV Lookup ekiga.net service _sip._udp
2011/05/16 13:14:38.918 0:06.150 Opal Liste...0xb457eb70 OpalUDP Binding to interface: 88.178.126.137:5060
2011/05/16 13:14:38.918 0:06.150 Opal Liste...0xb457eb70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$88.178.126.137:5060>
2011/05/16 13:14:38.919 0:06.151 Opal Liste...0xb457eb70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$88.178.126.137:5060,if=88.178.126.137%wlan0
SIP/2.0 489 Event Not Supported
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 88.178.126.137:5060;branch=z9hG4bKbc51a75b-1b7e-e011-893b-00216bcd37f8;rport=5060
Server: Kamailio (1.5.3-notls (i386/linux))
From: "visio" <sip:visio@88.178.126.137>;tag=701ba75b-1b7e-e011-893b-00216bcd37f8
Call-ID: f0c49b5b-1b7e-e011-893b-00216bcd37f8@visio-laptop
To: <sip:500@ekiga.net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.8c21
Content-Length: 0
2011/05/16 13:14:38.919 0:06.151 Opal Liste...0xb457eb70 SIP Queueing PDU "2 SUBSCRIBE <489>", transaction=z9hG4bKbc51a75b-1b7e-e011-893b-00216bcd37f8, token=f0c49b5b-1b7e-e011-893b-00216bcd37f8@visio-laptop
2011/05/16 13:14:38.919 0:06.151 Opal Liste...0xb457eb70 Opal Transport clean up on termination
2011/05/16 13:14:38.919 0:06.152 Opal Liste...0xb457eb70 Opal Transport Close
2011/05/16 13:14:38.920 0:06.152 Pool:0xb43beb70 SIP Handling PDU "2 SUBSCRIBE <489>" for transaction=z9hG4bKbc51a75b-1b7e-e011-893b-00216bcd37f8
2011/05/16 13:14:38.920 0:06.152 Pool:0xb43beb70 SIP SUBSCRIBE transaction id=z9hG4bKbc51a75b-1b7e-e011-893b-00216bcd37f8 completed.
2011/05/16 13:14:38.920 0:06.152 Pool:0xb43beb70 OpalUDP Setting interface to 88.178.126.137%wlan0
2011/05/16 13:14:38.920 0:06.152 Opal Liste...0xb457eb70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$88.178.126.137:5060>
2011/05/16 13:14:38.920 0:06.152 Pool:0xb43beb70 SIP Not retrying SUBSCRIBE due to error response 489 Bad Event
2011/05/16 13:14:38.920 0:06.152 Pool:0xb43beb70 SIP Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:500@ekiga.net, id=f0c49b5b-1b7e-e011-893b-00216bcd37f8@visio-laptop
2011/05/16 13:14:38.920 0:06.152 Pool:0xb43beb70 SIP Handled PDU "2 SUBSCRIBE <489>"
2011/05/16 13:14:38.951 0:06.183 SIP No SRV record found.
2011/05/16 13:14:38.951 0:06.183 OpalUDP Binding to interface: 0.0.0.0:5060
2011/05/16 13:14:38.951 0:06.183 SIP Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2011/05/16 13:14:38.952 0:06.184 OpalUDP Started connect to 86.64.162.35:5060
2011/05/16 13:14:38.952 0:06.184 OpalUDP Writing to interface 0 - "88.178.126.137%wlan0"
2011/05/16 13:14:38.952 0:06.184 OpalMan Listener interfaces: associated transport=None
udp$88.178.126.137:5060
2011/05/16 13:14:38.953 0:06.185 SIP Transaction created.
2011/05/16 13:14:38.956 0:06.188 DNS SRV Lookup ekiga.net service _sip._udp
2011/05/16 13:14:39.029 0:06.261 SIP No SRV record found.
2011/05/16 13:14:39.030 0:06.262 SIP Transaction remote address is udp$ekiga.net:5060
2011/05/16 13:14:39.030 0:06.262 SIP Sending PDU (560 bytes) to: rem=udp$86.64.162.35:5060,local=udp$88.178.126.137:5060,if=88.178.126.137%wlan0
SUBSCRIBE sip:501@ekiga.net SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 88.178.126.137:5060;branch=z9hG4bKf8a3be5b-1b7e-e011-893b-00216bcd37f8;rport
User-Agent: Ekiga/3.2.6
From: "visio" <sip:visio@88.178.126.137>;tag=346dbe5b-1b7e-e011-893b-00216bcd37f8
Call-ID: 80b5b15b-1b7e-e011-893b-00216bcd37f8@visio-laptop
To: <sip:501@ekiga.net>
Contact: <sip:visio@88.178.126.137>
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
2011/05/16 13:14:39.030 0:06.262 OpalUDP Setting interface to 88.178.126.137%wlan0
2011/05/16 13:14:39.030 0:06.262 SIP Transaction timers set: retry=0.500, completion=6.000
2011/05/16 13:14:39.031 0:06.263 Opal Garbage:0xb4641b70 SIP Deleting SIPSubscribeHandler sip:500@ekiga.net
2011/05/16 13:14:39.031 0:06.263 Opal Garbage:0xb4641b70 Opal Transport clean up on termination
2011/05/16 13:14:39.031 0:06.263 Opal Garbage:0xb4641b70 Opal Transport Close
2011/05/16 13:14:39.031 0:06.263 Opal Garbage:0xb4641b70 Opal Transport clean up on termination
2011/05/16 13:14:39.031 0:06.263 Opal Garbage:0xb4641b70 Opal Transport Close
2011/05/16 13:14:39.031 0:06.263 Opal Garbage:0xb4641b70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$88.178.126.137:5060>
2011/05/16 13:14:39.031 0:06.263 Opal Garbage:0xb4641b70 SIP Deleted handler.
2011/05/16 13:14:39.031 0:06.263 Opal Garbage:0xb4641b70 SIP Deleting SIPSubscribeHandler sip:500@ekiga.net
2011/05/16 13:14:39.032 0:06.264 Opal Garbage:0xb4641b70 Opal Transport clean up on termination
2011/05/16 13:14:39.032 0:06.264 Opal Garbage:0xb4641b70 Opal Transport Close
2011/05/16 13:14:39.032 0:06.264 Opal Garbage:0xb4641b70 Opal Transport clean up on termination
2011/05/16 13:14:39.032 0:06.264 Opal Garbage:0xb4641b70 Opal Transport Close
2011/05/16 13:14:39.032 0:06.264 SIP Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:501@ekiga.net, id=f261ca5b-1b7e-e011-893b-00216bcd37f8@visio-laptop
2011/05/16 13:14:39.032 0:06.264 DNS SRV Lookup ekiga.net service _sip._udp
2011/05/16 13:14:39.032 0:06.264 Opal Garbage:0xb4641b70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$88.178.126.137:5060>
2011/05/16 13:14:39.032 0:06.264 Opal Garbage:0xb4641b70 SIP Deleted handler.
2011/05/16 13:14:39.070 0:06.302 Opal Liste...0xb457eb70 OpalUDP Binding to interface: 88.178.126.137:5060
2011/05/16 13:14:39.070 0:06.302 Opal Liste...0xb457eb70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$88.178.126.137:5060>
2011/05/16 13:14:39.070 0:06.303 Opal Liste...0xb457eb70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$88.178.126.137:5060,if=88.178.126.137%wlan0
SIP/2.0 489 Event Not Supported
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 88.178.126.137:5060;branch=z9hG4bKf8a3be5b-1b7e-e011-893b-00216bcd37f8;rport=5060
Server: Kamailio (1.5.3-notls (i386/linux))
From: "visio" <sip:visio@88.178.126.137>;tag=346dbe5b-1b7e-e011-893b-00216bcd37f8
Call-ID: 80b5b15b-1b7e-e011-893b-00216bcd37f8@visio-laptop
To: <sip:501@ekiga.net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.7e28
Content-Length: 0
2011/05/16 13:14:39.071 0:06.303 Opal Liste...0xb457eb70 SIP Queueing PDU "2 SUBSCRIBE <489>", transaction=z9hG4bKf8a3be5b-1b7e-e011-893b-00216bcd37f8, token=80b5b15b-1b7e-e011-893b-00216bcd37f8@visio-laptop
2011/05/16 13:14:39.071 0:06.303 Opal Liste...0xb457eb70 Opal Transport clean up on termination
2011/05/16 13:14:39.071 0:06.303 Opal Liste...0xb457eb70 Opal Transport Close
2011/05/16 13:14:39.071 0:06.303 Pool:0xb43beb70 SIP Handling PDU "2 SUBSCRIBE <489>" for transaction=z9hG4bKf8a3be5b-1b7e-e011-893b-00216bcd37f8
2011/05/16 13:14:39.071 0:06.303 Pool:0xb43beb70 SIP SUBSCRIBE transaction id=z9hG4bKf8a3be5b-1b7e-e011-893b-00216bcd37f8 completed.
2011/05/16 13:14:39.071 0:06.303 Pool:0xb43beb70 OpalUDP Setting interface to 88.178.126.137%wlan0
2011/05/16 13:14:39.071 0:06.303 Opal Liste...0xb457eb70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$88.178.126.137:5060>
2011/05/16 13:14:39.072 0:06.304 Pool:0xb43beb70 SIP Not retrying SUBSCRIBE due to error response 489 Bad Event
2011/05/16 13:14:39.072 0:06.304 Pool:0xb43beb70 SIP Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:501@ekiga.net, id=80b5b15b-1b7e-e011-893b-00216bcd37f8@visio-laptop
2011/05/16 13:14:39.072 0:06.304 Pool:0xb43beb70 SIP Handled PDU "2 SUBSCRIBE <489>"
2011/05/16 13:14:39.101 0:06.333 SIP No SRV record found.
2011/05/16 13:14:39.102 0:06.334 OpalUDP Binding to interface: 0.0.0.0:5060
2011/05/16 13:14:39.102 0:06.334 SIP Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2011/05/16 13:14:39.102 0:06.334 OpalUDP Started connect to 86.64.162.35:5060
2011/05/16 13:14:39.102 0:06.334 OpalUDP Writing to interface 0 - "88.178.126.137%wlan0"
2011/05/16 13:14:39.103 0:06.335 OpalMan Listener interfaces: associated transport=None
udp$88.178.126.137:5060
2011/05/16 13:14:39.104 0:06.336 SIP Transaction created.
2011/05/16 13:14:39.107 0:06.339 DNS SRV Lookup ekiga.net service _sip._udp
2011/05/16 13:14:39.173 0:06.405 SIP No SRV record found.
2011/05/16 13:14:39.173 0:06.405 SIP Transaction remote address is udp$ekiga.net:5060
2011/05/16 13:14:39.173 0:06.405 SIP Sending PDU (570 bytes) to: rem=udp$86.64.162.35:5060,local=udp$88.178.126.137:5060,if=88.178.126.137%wlan0
SUBSCRIBE sip:501@ekiga.net SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 88.178.126.137:5060;branch=z9hG4bKb89fd55b-1b7e-e011-893b-00216bcd37f8;rport
User-Agent: Ekiga/3.2.6
From: "visio" <sip:visio@88.178.126.137>;tag=7669d55b-1b7e-e011-893b-00216bcd37f8
Call-ID: f261ca5b-1b7e-e011-893b-00216bcd37f8@visio-laptop
To: <sip:501@ekiga.net>
Contact: <sip:501@88.178.126.137>
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
2011/05/16 13:14:39.174 0:06.406 OpalUDP Setting interface to 88.178.126.137%wlan0
2011/05/16 13:14:39.174 0:06.406 SIP Transaction timers set: retry=0.500, completion=6.000
2011/05/16 13:14:39.174 0:06.406 VideoOutputCoreConfBridge Updating video view
2011/05/16 13:14:39.175 0:06.407 VideoOutputCoreConfBridge Updating zoom
2011/05/16 13:14:39.175 0:06.407 VideoOutputCoreConfBridge Updating Video Settings
2011/05/16 13:14:39.176 0:06.408 VideoOutputCoreConfBridge Updating Video Settings
2011/05/16 13:14:39.176 0:06.408 VideoOutputCoreConfBridge Updating Video Settings
2011/05/16 13:14:39.176 0:06.408 VideoOutputCoreConfBridge Updating Video Settings
2011/05/16 13:14:39.176 0:06.408 VidInputCoreConfBridge Updating preview size and fps
2011/05/16 13:14:39.176 0:06.408 VidInputCore Setting new preview config: 176x144/30
2011/05/16 13:14:39.178 0:06.411 VidInputCoreConfBridge Updating preview size and fps
2011/05/16 13:14:39.179 0:06.411 VidInputCore Setting new preview config: 176x144/30
2011/05/16 13:14:39.181 0:06.413 VidInputCoreConfBridge Updating device
2011/05/16 13:14:39.182 0:06.414 VidInputCore Setting device: USB 2.0 Camera (PTLIB/V4L2)
2011/05/16 13:14:39.182 0:06.414 GMVideoInputManager_ptlib Setting Device USB 2.0 Camera (PTLIB/V4L2)
2011/05/16 13:14:39.182 0:06.414 VidInputCoreConfBridge Updating device
2011/05/16 13:14:39.182 0:06.414 VidInputCore Setting device: USB 2.0 Camera (PTLIB/V4L2)
2011/05/16 13:14:39.182 0:06.414 GMVideoInputManager_ptlib Setting Device USB 2.0 Camera (PTLIB/V4L2)
2011/05/16 13:14:39.182 0:06.414 VidInputCoreConfBridge Updating device
2011/05/16 13:14:39.182 0:06.414 VidInputCore Setting device: USB 2.0 Camera (PTLIB/V4L2)
2011/05/16 13:14:39.182 0:06.414 GMVideoInputManager_ptlib Setting Device USB 2.0 Camera (PTLIB/V4L2)
2011/05/16 13:14:39.182 0:06.414 VidInputCoreConfBridge Updating image
2011/05/16 13:14:39.183 0:06.415 VidInputCoreConfBridge Updating preview
2011/05/16 13:14:39.183 0:06.415 VidInputCore Starting preview 176x144/30
2011/05/16 13:14:39.183 0:06.415 VidInputCore Opening device with 176x144/30
2011/05/16 13:14:39.183 0:06.415 GMVideoInputManager_ptlib Opening Device USB 2.0 Camera (PTLIB/V4L2)
2011/05/16 13:14:39.183 0:06.415 GMVideoInputManager_ptlib Opening Device with 176x144/30
2011/05/16 13:14:39.183 0:06.415 Detecting V4L2 devices
2011/05/16 13:14:39.183 0:06.415 PV4L2Plugin detected device metadata at /sys/class/video4linux/
2011/05/16 13:14:39.199 0:06.431 PVidInDev Open() videoFd:-1
2011/05/16 13:14:39.199 0:06.431 PVidInDev Close() videoFd:-1 started:0
2011/05/16 13:14:39.199 0:06.431 Detecting V4L2 devices
2011/05/16 13:14:39.200 0:06.432 PV4L2Plugin detected device metadata at /sys/class/video4linux/
2011/05/16 13:14:39.214 0:06.446 Opal Liste...0xb457eb70 OpalUDP Binding to interface: 88.178.126.137:5060
2011/05/16 13:14:39.214 0:06.446 PVidInDev Open() devName:/dev/video0 videoFd:-1
2011/05/16 13:14:39.214 0:06.446 Opal Liste...0xb457eb70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$88.178.126.137:5060>
2011/05/16 13:14:39.214 0:06.447 Opal Liste...0xb457eb70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$88.178.126.137:5060,if=88.178.126.137%wlan0
SIP/2.0 489 Event Not Supported
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 88.178.126.137:5060;branch=z9hG4bKb89fd55b-1b7e-e011-893b-00216bcd37f8;rport=5060
Server: Kamailio (1.5.3-notls (i386/linux))
From: "visio" <sip:visio@88.178.126.137>;tag=7669d55b-1b7e-e011-893b-00216bcd37f8
Call-ID: f261ca5b-1b7e-e011-893b-00216bcd37f8@visio-laptop
To: <sip:501@ekiga.net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.91e6
Content-Length: 0
2011/05/16 13:14:39.215 0:06.447 Opal Liste...0xb457eb70 SIP Queueing PDU "2 SUBSCRIBE <489>", transaction=z9hG4bKb89fd55b-1b7e-e011-893b-00216bcd37f8, token=f261ca5b-1b7e-e011-893b-00216bcd37f8@visio-laptop
2011/05/16 13:14:39.215 0:06.447 Opal Liste...0xb457eb70 Opal Transport clean up on termination
2011/05/16 13:14:39.215 0:06.447 Opal Liste...0xb457eb70 Opal Transport Close
2011/05/16 13:14:39.215 0:06.447 Opal Liste...0xb457eb70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$88.178.126.137:5060>
2011/05/16 13:14:39.216 0:06.448 Pool:0xb43beb70 SIP Handling PDU "2 SUBSCRIBE <489>" for transaction=z9hG4bKb89fd55b-1b7e-e011-893b-00216bcd37f8
2011/05/16 13:14:39.216 0:06.448 Pool:0xb43beb70 SIP SUBSCRIBE transaction id=z9hG4bKb89fd55b-1b7e-e011-893b-00216bcd37f8 completed.
2011/05/16 13:14:39.216 0:06.448 Pool:0xb43beb70 OpalUDP Setting interface to 88.178.126.137%wlan0
2011/05/16 13:14:39.216 0:06.448 Pool:0xb43beb70 SIP Not retrying SUBSCRIBE due to error response 489 Bad Event
2011/05/16 13:14:39.216 0:06.448 Pool:0xb43beb70 SIP Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:501@ekiga.net, id=f261ca5b-1b7e-e011-893b-00216bcd37f8@visio-laptop
2011/05/16 13:14:39.216 0:06.448 Pool:0xb43beb70 SIP Handled PDU "2 SUBSCRIBE <489>"
2011/05/16 13:14:39.217 0:06.449 VideoInputDevice S_STD failed : Argument invalide
2011/05/16 13:14:39.227 0:06.459 PVidDev SetColourFormatConverter success for native YUV420P
2011/05/16 13:14:39.441 0:06.673 PreviewManager Starting Preview
2011/05/16 13:14:39.442 0:06.674 AudioOutputCoreConfBridge Updating device
2011/05/16 13:14:39.442 0:06.674 AudioOutputCore Setting device[0]: Default (PTLIB/ALSA)
2011/05/16 13:14:39.442 0:06.674 GMAudioOutputManager_ptlib Setting Device[0] Default (PTLIB/ALSA)
2011/05/16 13:14:39.442 0:06.674 AudioOutputCoreConfBridge Updating device
2011/05/16 13:14:39.442 0:06.674 AudioOutputCore Setting device[1]: Default (PTLIB/ALSA)
2011/05/16 13:14:39.444 0:06.676 AudioInputCoreConfBridge Updating device
2011/05/16 13:14:39.444 0:06.676 AudioInputCore Setting device: Default (PTLIB/ALSA)
2011/05/16 13:14:39.444 0:06.676 GMAudioInputManager_ptlib Setting Device Default (PTLIB/ALSA)
2011/05/16 13:14:39.499 0:06.731 GMVideoOut...0xb4682b70 GMVideoOutputManager_X Widget not yet realized or gconf info not yet set, not opening display
2011/05/16 13:14:39.537 0:06.769 GMVideoOut...0xb4682b70 GMVideoOutputManager_X Widget not yet realized or gconf info not yet set, not opening display
2011/05/16 13:14:39.539 0:06.771 Ekiga version 3.2.6
2011/05/16 13:14:39.539 0:06.771 OPAL version 3.6.6
2011/05/16 13:14:39.539 0:06.771 PTLIB version 2.6.5
2011/05/16 13:14:39.539 0:06.771 GNOME support disabled
2011/05/16 13:14:39.539 0:06.771 Accelerated rendering support enabled
2011/05/16 13:14:39.539 0:06.771 DBUS support enabled
2011/05/16 13:14:39.539 0:06.771 GConf support enabled
2011/05/16 13:14:39.539 0:06.771 ESound support disabled
2011/05/16 13:14:39.571 0:06.803 GMVideoOut...0xb4682b70 GMVideoOutputManager_X Widget not yet realized or gconf info not yet set, not opening display
2011/05/16 13:14:39.605 0:06.837 GMVideoOut...0xb4682b70 GMVideoOutputManager_X Widget not yet realized or gconf info not yet set, not opening display
2011/05/16 13:14:39.639 0:06.871 GMVideoOut...0xb4682b70 GMVideoOutputManager_X Widget not yet realized or gconf info not yet set, not opening display
2011/05/16 13:14:39.671 0:06.903 GMVideoOut...0xb4682b70 GMVideoOutputManager_X Widget not yet realized or gconf info not yet set, not opening display
2011/05/16 13:14:39.705 0:06.937 GMVideoOut...0xb4682b70 GMVideoOutputManager_X Widget not yet realized or gconf info not yet set, not opening display
2011/05/16 13:14:39.739 0:06.971 GMVideoOut...0xb4682b70 GMVideoOutputManager_X Opening VO_MODE_LOCAL display with image of 176x144
2011/05/16 13:14:39.739 0:06.971 GMVideoOut...0xb4682b70 XVideo Initializing XV window with 176x144 at 26,26
2011/05/16 13:14:39.743 0:06.975 GMVideoOut...0xb4682b70 XVideo XvQueryExtension: Version: 2 Release: 2 Request Base: 133 Event Base: 89 Error Base: 154
2011/05/16 13:14:39.743 0:06.975 GMVideoOut...0xb4682b70 XVideo #0, Adaptor: NV17 Video Texture, type: input | image | , ports: 32, first port: 310
2011/05/16 13:14:39.743 0:06.975 GMVideoOut...0xb4682b70 XVideo Encoding List for Port 310: id=0 name=XV_IMAGE size=2046x2046 numerator=1 denominator=1
2011/05/16 13:14:39.743 0:06.975 GMVideoOut...0xb4682b70 XVideo Attribute List for Port 310:
2011/05/16 13:14:39.744 0:06.976 GMVideoOut...0xb4682b70 name: XV_SET_DEFAULTS
2011/05/16 13:14:39.744 0:06.976 GMVideoOut...0xb4682b70 flags: set
2011/05/16 13:14:39.744 0:06.976 GMVideoOut...0xb4682b70 min_color: 0
2011/05/16 13:14:39.744 0:06.976 GMVideoOut...0xb4682b70 max_color: 0
2011/05/16 13:14:39.744 0:06.976 GMVideoOut...0xb4682b70 name: XV_ITURBT_709
2011/05/16 13:14:39.744 0:06.976 GMVideoOut...0xb4682b70 flags: get set
2011/05/16 13:14:39.744 0:06.976 GMVideoOut...0xb4682b70 min_color: 0
2011/05/16 13:14:39.744 0:06.976 GMVideoOut...0xb4682b70 max_color: 1
2011/05/16 13:14:39.744 0:06.976 GMVideoOut...0xb4682b70 name: XV_SYNC_TO_VBLANK
2011/05/16 13:14:39.744 0:06.976 GMVideoOut...0xb4682b70 flags: get set
2011/05/16 13:14:39.744 0:06.976 GMVideoOut...0xb4682b70 min_color: 0
2011/05/16 13:14:39.744 0:06.976 GMVideoOut...0xb4682b70 max_color: 1
2011/05/16 13:14:39.744 0:06.976 GMVideoOut...0xb4682b70 name: XV_BRIGHTNESS
2011/05/16 13:14:39.744 0:06.976 GMVideoOut...0xb4682b70 flags: get set
2011/05/16 13:14:39.744 0:06.976 GMVideoOut...0xb4682b70 min_color: -1000
2011/05/16 13:14:39.744 0:06.976 GMVideoOut...0xb4682b70 max_color: 1000
2011/05/16 13:14:39.744 0:06.976 GMVideoOut...0xb4682b70 name: XV_CONTRAST
2011/05/16 13:14:39.744 0:06.976 GMVideoOut...0xb4682b70 flags: get set
2011/05/16 13:14:39.744 0:06.976 GMVideoOut...0xb4682b70 min_color: -1000
2011/05/16 13:14:39.744 0:06.976 GMVideoOut...0xb4682b70 max_color: 1000
2011/05/16 13:14:39.744 0:06.976 GMVideoOut...0xb4682b70 name: XV_SATURATION
2011/05/16 13:14:39.744 0:06.977 GMVideoOut...0xb4682b70 flags: get set
2011/05/16 13:14:39.745 0:06.977 GMVideoOut...0xb4682b70 min_color: -1000
2011/05/16 13:14:39.745 0:06.977 GMVideoOut...0xb4682b70 max_color: 1000
2011/05/16 13:14:39.745 0:06.977 GMVideoOut...0xb4682b70 name: XV_HUE
2011/05/16 13:14:39.745 0:06.977 GMVideoOut...0xb4682b70 flags: get set
2011/05/16 13:14:39.745 0:06.977 GMVideoOut...0xb4682b70 min_color: -1000
2011/05/16 13:14:39.745 0:06.977 GMVideoOut...0xb4682b70 max_color: 1000
2011/05/16 13:14:39.745 0:06.977 GMVideoOut...0xb4682b70 XVideo Image format list for Port 310:
2011/05/16 13:14:39.745 0:06.977 GMVideoOut...0xb4682b70 0x32595559 (YUY2) packed, order: YUYV
2011/05/16 13:14:39.745 0:06.977 GMVideoOut...0xb4682b70 0x32315659 (YV12) planar, order: YVU
2011/05/16 13:14:39.745 0:06.977 GMVideoOut...0xb4682b70 0x59565955 (UYVY) packed, order: UYVY
2011/05/16 13:14:39.745 0:06.977 GMVideoOut...0xb4682b70 0x30323449 (I420) planar, order: YUV
2011/05/16 13:14:39.745 0:06.977 GMVideoOut...0xb4682b70 XVideo Grabbed Port: 310
2011/05/16 13:14:39.745 0:06.977 GMVideoOut...0xb4682b70 XVideo Using XVideo port: 310
2011/05/16 13:14:39.747 0:06.979 GMVideoOut...0xb4682b70 XVideo Found visual with colordepth of 24bits per pixel
2011/05/16 13:14:39.747 0:06.979 GMVideoOut...0xb4682b70 X11 Created Window with ID 67108866
2011/05/16 13:14:39.747 0:06.979 GMVideoOut...0xb4682b70 XVideo Colorkey method: NONE
2011/05/16 13:14:39.747 0:06.979 GMVideoOut...0xb4682b70 XVideo Vertical sync successfully activated
2011/05/16 13:14:39.748 0:06.980 GMVideoOut...0xb4682b70 XVideo XQueryShmExtension success
2011/05/16 13:14:39.748 0:06.980 GMVideoOut...0xb4682b70 XVideo Created XvImage (176x144, data size: 38016, num_planes: 3
2011/05/16 13:14:39.748 0:06.980 GMVideoOut...0xb4682b70 XVideo Plane 0: pitch=176, offset=0
2011/05/16 13:14:39.748 0:06.980 GMVideoOut...0xb4682b70 XVideo Plane 1: pitch=88, offset=25344
2011/05/16 13:14:39.748 0:06.980 GMVideoOut...0xb4682b70 XVideo Plane 2: pitch=88, offset=31680
2011/05/16 13:14:39.748 0:06.980 GMVideoOut...0xb4682b70 XVideo Using SHM extension
2011/05/16 13:14:39.748 0:06.980 GMVideoOut...0xb4682b70 X11 Unknown wm type...
2011/05/16 13:14:39.748 0:06.980 GMVideoOut...0xb4682b70 GMVideoOutputManager_X VO_MODE_LOCAL: Successfully opened XV Window
2011/05/16 13:14:39.748 0:06.980 GMVideoOut...0xb4682b70 X11 Unknown X Event 19 received
2011/05/16 13:14:39.768 0:07.000 Housekeeper:0xb45bfb70 SIP Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK7ad7905b-1b7e-e011-893b-00216bcd37f8
2011/05/16 13:14:39.921 0:07.153 Housekeeper:0xb45bfb70 SIP Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bKbc51a75b-1b7e-e011-893b-00216bcd37f8
2011/05/16 13:14:40.033 0:07.265 Opal Garbage:0xb4641b70 SIP Transaction id=z9hG4bK7ad7905b-1b7e-e011-893b-00216bcd37f8 destroyed.
2011/05/16 13:14:40.033 0:07.265 Opal Garbage:0xb4641b70 SIP Transaction id=z9hG4bKbc51a75b-1b7e-e011-893b-00216bcd37f8 destroyed.
2011/05/16 13:14:40.033 0:07.265 Opal Garbage:0xb4641b70 SIP Deleting SIPSubscribeHandler sip:501@ekiga.net
2011/05/16 13:14:40.033 0:07.265 Opal Garbage:0xb4641b70 Opal Transport clean up on termination
2011/05/16 13:14:40.033 0:07.265 Opal Garbage:0xb4641b70 Opal Transport Close
2011/05/16 13:14:40.033 0:07.265 Opal Garbage:0xb4641b70 Opal Transport clean up on termination
2011/05/16 13:14:40.033 0:07.265 Opal Garbage:0xb4641b70 Opal Transport Close
2011/05/16 13:14:40.033 0:07.265 Opal Garbage:0xb4641b70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$88.178.126.137:5060>
2011/05/16 13:14:40.034 0:07.266 Opal Garbage:0xb4641b70 SIP Deleted handler.
2011/05/16 13:14:40.034 0:07.266 Opal Garbage:0xb4641b70 SIP Deleting SIPSubscribeHandler sip:501@ekiga.net
2011/05/16 13:14:40.034 0:07.266 Opal Garbage:0xb4641b70 Opal Transport clean up on termination
2011/05/16 13:14:40.034 0:07.266 Opal Garbage:0xb4641b70 Opal Transport Close
2011/05/16 13:14:40.034 0:07.266 Opal Garbage:0xb4641b70 Opal Transport clean up on termination
2011/05/16 13:14:40.034 0:07.266 Opal Garbage:0xb4641b70 Opal Transport Close
2011/05/16 13:14:40.034 0:07.266 Opal Garbage:0xb4641b70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$88.178.126.137:5060>
2011/05/16 13:14:40.035 0:07.267 Opal Garbage:0xb4641b70 SIP Deleted handler.
2011/05/16 13:14:40.072 0:07.304 Housekeeper:0xb45bfb70 SIP Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bKf8a3be5b-1b7e-e011-893b-00216bcd37f8
2011/05/16 13:14:40.216 0:07.448 Housekeeper:0xb45bfb70 SIP Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bKb89fd55b-1b7e-e011-893b-00216bcd37f8
2011/05/16 13:14:41.035 0:08.267 Opal Garbage:0xb4641b70 SIP Transaction id=z9hG4bKf8a3be5b-1b7e-e011-893b-00216bcd37f8 destroyed.
2011/05/16 13:14:41.035 0:08.267 Opal Garbage:0xb4641b70 SIP Transaction id=z9hG4bKb89fd55b-1b7e-e011-893b-00216bcd37f8 destroyed.
2011/05/16 13:14:41.204 0:08.436 Listen Stopping listening thread on udp$*:5060
2011/05/16 13:14:41.204 0:08.436 MonSock Closed UDP socket 0x8868498
2011/05/16 13:14:41.204 0:08.436 Opal Liste...0xb457eb70 Listen UDP read error.
2011/05/16 13:14:41.224 0:08.456 MonSock Deleting UDP socket 0x8868498
2011/05/16 13:14:41.225 0:08.457 PWLib File handle low water mark set: 33 PUDPSocket
2011/05/16 13:14:41.225 0:08.457 MonSock Created socket bundle for all interfaces.
2011/05/16 13:14:41.225 0:08.457 MonSock Created bundled UDP socket 88.178.126.137:5060
2011/05/16 13:14:41.225 0:08.457 Listen Stopping listening thread on tcp$*:1720
2011/05/16 13:14:41.225 0:08.457 Opal Liste...0xb457eb70 Listen Started listening thread on udp$*:5060
2011/05/16 13:14:41.235 0:08.467 Opal Liste...0xb43ffb70 Listen Started listening thread on tcp$*:1720
2011/05/16 13:14:41.236 0:08.468 PWLib File handle high water mark set: 47 Thread unblock pipe
2011/05/16 13:14:41.236 0:08.468 Opal Liste...0xb43ffb70 Listen Waiting on socket accept on tcp$*:1720
2011/05/16 13:14:41.236 0:08.468 PTLib Thread high water mark set: 11
2011/05/16 13:14:41.236 0:08.468 subscriber:0xaf136b70 SIP Start REGISTER
aor=testvisio@ekiga.net
registrar=ekiga.net
contact=
authID=testvisio
realm=
expire=3600
restore=30
minRetry=0.000
maxRetry=0.000
2011/05/16 13:14:41.237 0:08.469 subscriber:0xaf136b70 SIP Changing REGISTER handler from Unavailable to Subscribing, target=sip:testvisio@ekiga.net, id=32c61a5d-1b7e-e011-893b-00216bcd37f8@visio-laptop
2011/05/16 13:14:41.237 0:08.469 subscriber:0xaf136b70 DNS SRV Lookup ekiga.net service _sip._udp
2011/05/16 13:14:41.305 0:08.537 subscriber:0xaf136b70 SIP No SRV record found.
2011/05/16 13:14:41.305 0:08.537 subscriber:0xaf136b70 OpalUDP Binding to interface: 0.0.0.0:5060
2011/05/16 13:14:41.306 0:08.538 subscriber:0xaf136b70 PWLib File handle high water mark set: 48 PUDPSocket
2011/05/16 13:14:41.305 0:08.537 subscriber:0xaf136b70 SIP Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2011/05/16 13:14:41.306 0:08.538 subscriber:0xaf136b70 OpalUDP Started connect to 86.64.162.35:5060
2011/05/16 13:14:41.306 0:08.538 subscriber:0xaf136b70 OpalUDP Writing to interface 0 - "88.178.126.137%wlan0"
2011/05/16 13:14:41.425 0:08.657 subscriber:0xaf136b70 OpalMan Listener interfaces: associated transport=udp$88.178.126.137:5060
udp$88.178.126.137:5060
2011/05/16 13:14:41.426 0:08.658 subscriber:0xaf136b70 SIP Transaction created.
2011/05/16 13:14:41.427 0:08.659 subscriber:0xaf136b70 DNS SRV Lookup ekiga.net service _sip._udp
2011/05/16 13:14:41.493 0:08.725 subscriber:0xaf136b70 SIP No SRV record found.
2011/05/16 13:14:41.493 0:08.725 subscriber:0xaf136b70 SIP Transaction remote address is udp$ekiga.net:5060
2011/05/16 13:14:41.493 0:08.725 subscriber:0xaf136b70 SIP Sending PDU (513 bytes) to: rem=udp$86.64.162.35:5060,local=udp$88.178.126.137:5060,if=88.178.126.137%wlan0
REGISTER sip:ekiga.net SIP/2.0
CSeq: 1 REGISTER
Via: SIP/2.0/UDP 88.178.126.137:5060;branch=z9hG4bK50ea375d-1b7e-e011-893b-00216bcd37f8;rport
User-Agent: Ekiga/3.2.6
From: <sip:testvisio@ekiga.net>;tag=c0ce1a5d-1b7e-e011-893b-00216bcd37f8
Call-ID: 32c61a5d-1b7e-e011-893b-00216bcd37f8@visio-laptop
To: <sip:testvisio@ekiga.net>
Contact: <sip:testvisio@88.178.126.137>;q=1
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 3600
Content-Length: 0
Max-Forwards: 70
2011/05/16 13:14:41.494 0:08.726 subscriber:0xaf136b70 OpalUDP Setting interface to 88.178.126.137%wlan0
2011/05/16 13:14:41.494 0:08.726 subscriber:0xaf136b70 SIP Transaction timers set: retry=0.500, completion=6.000
2011/05/16 13:14:41.533 0:08.765 Opal Liste...0xb457eb70 PWLib File handle low water mark set: 46 PUDPSocket
2011/05/16 13:14:41.533 0:08.765 Opal Liste...0xb457eb70 OpalUDP Binding to interface: 88.178.126.137:5060
2011/05/16 13:14:41.533 0:08.766 Opal Liste...0xb457eb70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$88.178.126.137:5060>
2011/05/16 13:14:41.534 0:08.766 Opal Liste...0xb457eb70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$88.178.126.137:5060,if=88.178.126.137%wlan0
SIP/2.0 401 Unauthorized
CSeq: 1 REGISTER
Via: SIP/2.0/UDP 88.178.126.137:5060;branch=z9hG4bK50ea375d-1b7e-e011-893b-00216bcd37f8;rport=5060
Server: Kamailio (1.5.3-notls (i386/linux))
From: <sip:testvisio@ekiga.net>;tag=c0ce1a5d-1b7e-e011-893b-00216bcd37f8
Call-ID: 32c61a5d-1b7e-e011-893b-00216bcd37f8@visio-laptop
To: <sip:testvisio@ekiga.net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.1c3c
Content-Length: 0
WWW-Authenticate: Digest realm="ekiga.net", nonce="4dd1073f0000b06373d4ccd27addd2416dc1cfced3771458"
2011/05/16 13:14:41.535 0:08.767 Opal Liste...0xb457eb70 SIP Queueing PDU "1 REGISTER <401>", transaction=z9hG4bK50ea375d-1b7e-e011-893b-00216bcd37f8, token=32c61a5d-1b7e-e011-893b-00216bcd37f8@visio-laptop
2011/05/16 13:14:41.535 0:08.767 Opal Liste...0xb457eb70 Opal Transport clean up on termination
2011/05/16 13:14:41.535 0:08.767 Opal Liste...0xb457eb70 Opal Transport Close
2011/05/16 13:14:41.535 0:08.767 Pool:0xb43beb70 SIP Handling PDU "1 REGISTER <401>" for transaction=z9hG4bK50ea375d-1b7e-e011-893b-00216bcd37f8
2011/05/16 13:14:41.535 0:08.767 Pool:0xb43beb70 SIP REGISTER transaction id=z9hG4bK50ea375d-1b7e-e011-893b-00216bcd37f8 completed.
2011/05/16 13:14:41.535 0:08.767 Pool:0xb43beb70 OpalUDP Setting interface to 88.178.126.137%wlan0
2011/05/16 13:14:41.535 0:08.767 Pool:0xb43beb70 SIP Received Authentication Required response
2011/05/16 13:14:41.535 0:08.767 Opal Liste...0xb457eb70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$88.178.126.137:5060>
2011/05/16 13:14:41.535 0:08.767 Pool:0xb43beb70 SIP Found auth info for realm ekiga.net
2011/05/16 13:14:41.535 0:08.767 Pool:0xb43beb70 SIP Changing REGISTER handler from Unavailable to Subscribing, target=sip:testvisio@ekiga.net, id=32c61a5d-1b7e-e011-893b-00216bcd37f8@visio-laptop
2011/05/16 13:14:41.537 0:08.769 Pool:0xb43beb70 OpalMan Listener interfaces: associated transport=udp$88.178.126.137:5060
udp$88.178.126.137:5060
2011/05/16 13:14:41.537 0:08.769 Pool:0xb43beb70 SIP Transaction created.
2011/05/16 13:14:41.539 0:08.771 Pool:0xb43beb70 SIP Adding authentication information
2011/05/16 13:14:41.539 0:08.771 Pool:0xb43beb70 DNS SRV Lookup ekiga.net service _sip._udp
2011/05/16 13:14:41.615 0:08.847 Pool:0xb43beb70 SIP No SRV record found.
2011/05/16 13:14:41.616 0:08.848 Pool:0xb43beb70 SIP Transaction remote address is udp$ekiga.net:5060
2011/05/16 13:14:41.616 0:08.848 Pool:0xb43beb70 SIP Sending PDU (715 bytes) to: rem=udp$86.64.162.35:5060,local=udp$88.178.126.137:5060,if=88.178.126.137%wlan0
REGISTER sip:ekiga.net SIP/2.0
CSeq: 2 REGISTER
Via: SIP/2.0/UDP 88.178.126.137:5060;branch=z9hG4bK58ef485d-1b7e-e011-893b-00216bcd37f8;rport
User-Agent: Ekiga/3.2.6
Authorization: Digest username="testvisio", realm="ekiga.net", nonce="4dd1073f0000b06373d4ccd27addd2416dc1cfced3771458", uri="sip:ekiga.net", algorithm=MD5, response="dea7f86159d8e228726524dec5f59486"
From: <sip:testvisio@ekiga.net>;tag=c0ce1a5d-1b7e-e011-893b-00216bcd37f8
Call-ID: 32c61a5d-1b7e-e011-893b-00216bcd37f8@visio-laptop
To: <sip:testvisio@ekiga.net>
Contact: <sip:testvisio@88.178.126.137>;q=1
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 3600
Content-Length: 0
Max-Forwards: 70
2011/05/16 13:14:41.616 0:08.848 Pool:0xb43beb70 OpalUDP Setting interface to 88.178.126.137%wlan0
2011/05/16 13:14:41.617 0:08.849 Pool:0xb43beb70 SIP Transaction timers set: retry=0.500, completion=6.000
2011/05/16 13:14:41.617 0:08.849 Pool:0xb43beb70 SIP Handled PDU "1 REGISTER <401>"
2011/05/16 13:14:41.660 0:08.892 Opal Liste...0xb457eb70 OpalUDP Binding to interface: 88.178.126.137:5060
2011/05/16 13:14:41.660 0:08.892 Opal Liste...0xb457eb70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$88.178.126.137:5060>
2011/05/16 13:14:41.661 0:08.893 Opal Liste...0xb457eb70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$88.178.126.137:5060,if=88.178.126.137%wlan0
SIP/2.0 200 OK
CSeq: 2 REGISTER
Via: SIP/2.0/UDP 88.178.126.137:5060;branch=z9hG4bK58ef485d-1b7e-e011-893b-00216bcd37f8;rport=5060
Server: Kamailio (1.5.3-notls (i386/linux))
From: <sip:testvisio@ekiga.net>;tag=c0ce1a5d-1b7e-e011-893b-00216bcd37f8
Call-ID: 32c61a5d-1b7e-e011-893b-00216bcd37f8@visio-laptop
To: <sip:testvisio@ekiga.net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.66d7
Contact: <sip:testvisio@88.178.126.137>;q=1;expires=1200
Content-Length: 0
2011/05/16 13:14:41.661 0:08.893 Opal Liste...0xb457eb70 SIP Queueing PDU "2 REGISTER <200>", transaction=z9hG4bK58ef485d-1b7e-e011-893b-00216bcd37f8, token=32c61a5d-1b7e-e011-893b-00216bcd37f8@visio-laptop
2011/05/16 13:14:41.661 0:08.893 Opal Liste...0xb457eb70 Opal Transport clean up on termination
2011/05/16 13:14:41.662 0:08.894 Opal Liste...0xb457eb70 Opal Transport Close
2011/05/16 13:14:41.662 0:08.894 Pool:0xb43beb70 SIP Handling PDU "2 REGISTER <200>" for transaction=z9hG4bK58ef485d-1b7e-e011-893b-00216bcd37f8
2011/05/16 13:14:41.662 0:08.894 Pool:0xb43beb70 SIP REGISTER transaction id=z9hG4bK58ef485d-1b7e-e011-893b-00216bcd37f8 completed.
2011/05/16 13:14:41.662 0:08.894 Pool:0xb43beb70 OpalUDP Setting interface to 88.178.126.137%wlan0
2011/05/16 13:14:41.662 0:08.894 Opal Liste...0xb457eb70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$88.178.126.137:5060>
2011/05/16 13:14:41.662 0:08.894 Pool:0xb43beb70 SIP Product Info: name="Kamailio", version="", vendor="", comments="(1.5.3-notls (i386/linux))"
2011/05/16 13:14:41.662 0:08.894 Pool:0xb43beb70 SIP Changing REGISTER handler from Subscribing to Subscribed, target=sip:testvisio@ekiga.net, id=32c61a5d-1b7e-e011-893b-00216bcd37f8@visio-laptop
2011/05/16 13:14:41.662 0:08.894 Pool:0xb43beb70 SIP Expiry time for REGISTER set to 1200 seconds.
2011/05/16 13:14:41.662 0:08.894 Pool:0xb43beb70 SIP Product Info: name="Kamailio", version="", vendor="", comments="(1.5.3-notls (i386/linux))"
2011/05/16 13:14:41.663 0:08.895 Pool:0xb43beb70 SIP Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:testvisio@ekiga.net, id=2ce05b5d-1b7e-e011-893b-00216bcd37f8@visio-laptop
2011/05/16 13:14:41.663 0:08.895 Pool:0xb43beb70 DNS SRV Lookup ekiga.net service _sip._udp
2011/05/16 13:14:41.728 0:08.960 Pool:0xb43beb70 SIP No SRV record found.
2011/05/16 13:14:41.728 0:08.960 Pool:0xb43beb70 OpalUDP Binding to interface: 0.0.0.0:5060
2011/05/16 13:14:41.728 0:08.960 Pool:0xb43beb70 SIP Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2011/05/16 13:14:41.729 0:08.961 Pool:0xb43beb70 OpalUDP Started connect to 86.64.162.35:5060
2011/05/16 13:14:41.729 0:08.961 Pool:0xb43beb70 OpalUDP Writing to interface 0 - "88.178.126.137%wlan0"
2011/05/16 13:14:41.729 0:08.961 Pool:0xb43beb70 SIP Transaction created.
2011/05/16 13:14:41.732 0:08.964 Pool:0xb43beb70 DNS SRV Lookup ekiga.net service _sip._udp
2011/05/16 13:14:41.797 0:09.029 Pool:0xb43beb70 SIP No SRV record found.
2011/05/16 13:14:41.797 0:09.029 Pool:0xb43beb70 SIP Transaction remote address is udp$ekiga.net:5060
2011/05/16 13:14:41.797 0:09.029 Pool:0xb43beb70 SIP Sending PDU (589 bytes) to: rem=udp$86.64.162.35:5060,local=udp$88.178.126.137:5060,if=88.178.126.137%wlan0
SUBSCRIBE sip:testvisio@ekiga.net SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 88.178.126.137:5060;branch=z9hG4bK842d665d-1b7e-e011-893b-00216bcd37f8;rport
User-Agent: Ekiga/3.2.6
From: <sip:testvisio@ekiga.net>;tag=c6f5655d-1b7e-e011-893b-00216bcd37f8
Call-ID: 2ce05b5d-1b7e-e011-893b-00216bcd37f8@visio-laptop
To: <sip:testvisio@ekiga.net>
Contact: <sip:testvisio@88.178.126.137>
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
2011/05/16 13:14:41.798 0:09.030 Pool:0xb43beb70 OpalUDP Setting interface to 88.178.126.137%wlan0
2011/05/16 13:14:41.798 0:09.030 Pool:0xb43beb70 SIP Transaction timers set: retry=0.500, completion=6.000
2011/05/16 13:14:41.798 0:09.030 Pool:0xb43beb70 SIP Handled PDU "2 REGISTER <200>"
2011/05/16 13:14:41.838 0:09.070 SIP Changing PUBLISH handler from Unavailable to Subscribing, target=sip:testvisio@ekiga.net, id=4089765d-1b7e-e011-893b-00216bcd37f8@visio-laptop
2011/05/16 13:14:41.838 0:09.070 DNS SRV Lookup ekiga.net service _sip._udp
2011/05/16 13:14:41.838 0:09.070 Opal Liste...0xb457eb70 OpalUDP Binding to interface: 88.178.126.137:5060
2011/05/16 13:14:41.838 0:09.070 Opal Liste...0xb457eb70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$88.178.126.137:5060>
2011/05/16 13:14:41.839 0:09.071 Opal Liste...0xb457eb70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$88.178.126.137:5060,if=88.178.126.137%wlan0
SIP/2.0 489 Event Not Supported
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 88.178.126.137:5060;branch=z9hG4bK842d665d-1b7e-e011-893b-00216bcd37f8;rport=5060
Server: Kamailio (1.5.3-notls (i386/linux))
From: <sip:testvisio@ekiga.net>;tag=c6f5655d-1b7e-e011-893b-00216bcd37f8
Call-ID: 2ce05b5d-1b7e-e011-893b-00216bcd37f8@visio-laptop
To: <sip:testvisio@ekiga.net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.bf0c
Content-Length: 0
2011/05/16 13:14:41.839 0:09.071 Opal Liste...0xb457eb70 SIP Queueing PDU "2 SUBSCRIBE <489>", transaction=z9hG4bK842d665d-1b7e-e011-893b-00216bcd37f8, token=2ce05b5d-1b7e-e011-893b-00216bcd37f8@visio-laptop
2011/05/16 13:14:41.840 0:09.072 Opal Liste...0xb457eb70 Opal Transport clean up on termination
2011/05/16 13:14:41.840 0:09.072 Opal Liste...0xb457eb70 Opal Transport Close
2011/05/16 13:14:41.840 0:09.072 Pool:0xb43beb70 SIP Handling PDU "2 SUBSCRIBE <489>" for transaction=z9hG4bK842d665d-1b7e-e011-893b-00216bcd37f8
2011/05/16 13:14:41.840 0:09.072 Pool:0xb43beb70 SIP SUBSCRIBE transaction id=z9hG4bK842d665d-1b7e-e011-893b-00216bcd37f8 completed.
2011/05/16 13:14:41.840 0:09.072 Pool:0xb43beb70 OpalUDP Setting interface to 88.178.126.137%wlan0
2011/05/16 13:14:41.840 0:09.072 Pool:0xb43beb70 SIP Not retrying SUBSCRIBE due to error response 489 Bad Event
2011/05/16 13:14:41.840 0:09.072 Pool:0xb43beb70 SIP Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:testvisio@ekiga.net, id=2ce05b5d-1b7e-e011-893b-00216bcd37f8@visio-laptop
2011/05/16 13:14:41.840 0:09.072 Pool:0xb43beb70 SIP Handled PDU "2 SUBSCRIBE <489>"
2011/05/16 13:14:41.840 0:09.072 Opal Liste...0xb457eb70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$88.178.126.137:5060>
2011/05/16 13:14:41.903 0:09.136 SIP No SRV record found.
2011/05/16 13:14:41.904 0:09.136 OpalUDP Binding to interface: 0.0.0.0:5060
2011/05/16 13:14:41.904 0:09.136 SIP Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2011/05/16 13:14:41.904 0:09.136 OpalUDP Started connect to 86.64.162.35:5060
2011/05/16 13:14:41.905 0:09.137 OpalUDP Writing to interface 0 - "88.178.126.137%wlan0"
2011/05/16 13:14:41.905 0:09.137 SIP Transaction created.
2011/05/16 13:14:41.908 0:09.140 DNS SRV Lookup ekiga.net service _sip._udp
2011/05/16 13:14:41.972 0:09.204 SIP No SRV record found.
2011/05/16 13:14:41.973 0:09.205 SIP Transaction remote address is udp$ekiga.net:5060
2011/05/16 13:14:41.973 0:09.205 SIP Sending PDU (832 bytes) to: rem=udp$86.64.162.35:5060,local=udp$88.178.126.137:5060,if=88.178.126.137%wlan0
PUBLISH sip:testvisio@ekiga.net SIP/2.0
CSeq: 3 PUBLISH
Via: SIP/2.0/UDP 88.178.126.137:5060;branch=z9hG4bKeefd805d-1b7e-e011-893b-00216bcd37f8;rport
User-Agent: Ekiga/3.2.6
From: <sip:testvisio@ekiga.net>;tag=a0c9805d-1b7e-e011-893b-00216bcd37f8
Call-ID: 4089765d-1b7e-e011-893b-00216bcd37f8@visio-laptop
To: <sip:testvisio@ekiga.net>
Contact: <sip:testvisio@88.178.126.137>
Expires: 500
Event: presence
Content-Type: application/pidf+xml
Content-Length: 340
Max-Forwards: 70
<?xml version="1.0" encoding="UTF-8"?>
<presence xmlns="urn:ietf:params:xml:ns:pidf" entity="pres:testvisio@ekiga.net">
<tuple id="sip:testvisio@ekiga.net_on_visio-laptop">
<note>online - I'm online using Ekiga</note>
<status>
<basic>open</basic>
</status>
<contact priority="1">testvisio@ekiga.net</contact>
</tuple>
</presence>
2011/05/16 13:14:41.973 0:09.205 OpalUDP Setting interface to 88.178.126.137%wlan0
2011/05/16 13:14:41.974 0:09.206 SIP Transaction timers set: retry=0.500, completion=6.000
2011/05/16 13:14:42.016 0:09.248 Opal Liste...0xb457eb70 OpalUDP Binding to interface: 88.178.126.137:5060
2011/05/16 13:14:42.017 0:09.249 Opal Liste...0xb457eb70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$88.178.126.137:5060>
2011/05/16 13:14:42.017 0:09.249 Opal Liste...0xb457eb70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$88.178.126.137:5060,if=88.178.126.137%wlan0
SIP/2.0 200 OK
CSeq: 3 PUBLISH
Via: SIP/2.0/UDP 88.178.126.137:5060;branch=z9hG4bKeefd805d-1b7e-e011-893b-00216bcd37f8;rport=5060
Server: Kamailio (1.5.3-notls (i386/linux))
From: <sip:testvisio@ekiga.net>;tag=a0c9805d-1b7e-e011-893b-00216bcd37f8
Call-ID: 4089765d-1b7e-e011-893b-00216bcd37f8@visio-laptop
To: <sip:testvisio@ekiga.net>;tag=f85b0bd16aaafa8479586ac9f88b3198-d506
Expires: 495
Content-Length: 0
SIP-ETag: a.1303844361.1030.1756670.0
2011/05/16 13:14:42.018 0:09.250 Opal Liste...0xb457eb70 SIP Queueing PDU "3 PUBLISH <200>", transaction=z9hG4bKeefd805d-1b7e-e011-893b-00216bcd37f8, token=4089765d-1b7e-e011-893b-00216bcd37f8@visio-laptop
2011/05/16 13:14:42.018 0:09.250 Opal Liste...0xb457eb70 Opal Transport clean up on termination
2011/05/16 13:14:42.018 0:09.250 Opal Liste...0xb457eb70 Opal Transport Close
2011/05/16 13:14:42.018 0:09.250 Pool:0xb43beb70 SIP Handling PDU "3 PUBLISH <200>" for transaction=z9hG4bKeefd805d-1b7e-e011-893b-00216bcd37f8
2011/05/16 13:14:42.018 0:09.250 Pool:0xb43beb70 SIP PUBLISH transaction id=z9hG4bKeefd805d-1b7e-e011-893b-00216bcd37f8 completed.
2011/05/16 13:14:42.018 0:09.250 Pool:0xb43beb70 OpalUDP Setting interface to 88.178.126.137%wlan0
2011/05/16 13:14:42.018 0:09.250 Opal Liste...0xb457eb70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$88.178.126.137:5060>
2011/05/16 13:14:42.018 0:09.250 Pool:0xb43beb70 SIP Expiry time for PUBLISH set to 495 seconds.
2011/05/16 13:14:42.018 0:09.250 Pool:0xb43beb70 SIP Product Info: name="Kamailio", version="", vendor="", comments="(1.5.3-notls (i386/linux))"
2011/05/16 13:14:42.019 0:09.251 Pool:0xb43beb70 SIP Changing PUBLISH handler from Subscribing to Subscribed, target=sip:testvisio@ekiga.net, id=4089765d-1b7e-e011-893b-00216bcd37f8@visio-laptop
2011/05/16 13:14:42.019 0:09.251 Pool:0xb43beb70 SIP Handled PDU "3 PUBLISH <200>"
2011/05/16 13:14:42.036 0:09.268 Opal Garbage:0xb4641b70 SIP Deleting SIPSubscribeHandler sip:testvisio@ekiga.net
2011/05/16 13:14:42.036 0:09.268 Opal Garbage:0xb4641b70 Opal Transport clean up on termination
2011/05/16 13:14:42.036 0:09.268 Opal Garbage:0xb4641b70 Opal Transport Close
2011/05/16 13:14:42.036 0:09.268 Opal Garbage:0xb4641b70 Opal Transport clean up on termination
2011/05/16 13:14:42.036 0:09.268 Opal Garbage:0xb4641b70 Opal Transport Close
2011/05/16 13:14:42.036 0:09.268 Opal Garbage:0xb4641b70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$88.178.126.137:5060>
2011/05/16 13:14:42.037 0:09.269 Opal Garbage:0xb4641b70 SIP Deleted handler.
2011/05/16 13:14:42.617 0:09.849 Housekeeper:0xb45bfb70 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bK50ea375d-1b7e-e011-893b-00216bcd37f8
2011/05/16 13:14:42.798 0:10.030 Housekeeper:0xb45bfb70 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bK58ef485d-1b7e-e011-893b-00216bcd37f8
2011/05/16 13:14:42.840 0:10.072 Housekeeper:0xb45bfb70 SIP Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK842d665d-1b7e-e011-893b-00216bcd37f8
2011/05/16 13:14:43.020 0:10.252 Housekeeper:0xb45bfb70 SIP Set state Terminated_Success for PUBLISH transaction id=z9hG4bKeefd805d-1b7e-e011-893b-00216bcd37f8
2011/05/16 13:14:43.037 0:10.269 Opal Garbage:0xb4641b70 SIP Transaction id=z9hG4bK842d665d-1b7e-e011-893b-00216bcd37f8 destroyed.
2011/05/16 13:14:43.037 0:10.269 Opal Garbage:0xb4641b70 SIP Transaction id=z9hG4bK50ea375d-1b7e-e011-893b-00216bcd37f8 destroyed.
2011/05/16 13:14:43.037 0:10.269 Opal Garbage:0xb4641b70 SIP Transaction id=z9hG4bK58ef485d-1b7e-e011-893b-00216bcd37f8 destroyed.
2011/05/16 13:14:43.038 0:10.270 Opal Garbage:0xb4641b70 SIP Transaction id=z9hG4bKeefd805d-1b7e-e011-893b-00216bcd37f8 destroyed.
2011/05/16 13:14:44.847 0:12.079 OpalMan Set up call from pc:* to sip:500@ekiga.net
2011/05/16 13:14:44.847 0:12.079 Call Created Call[u969f1e621]
2011/05/16 13:14:44.847 0:12.079 OpalMan Set up connection to "pc:*"
2011/05/16 13:14:44.848 0:12.080 OpalCon Created connection Call[u969f1e621]-EP<pc>[c70c0faf42]
2011/05/16 13:14:44.848 0:12.080 Silence Parameters set: mode=AdaptiveSilenceDetection, threshold=0, silencedb=3200, signaldb=80, period=4800
2011/05/16 13:14:44.848 0:12.080 Silence Handler created
2011/05/16 13:14:44.848 0:12.080 Echo Canceler Handler created
2011/05/16 13:14:44.848 0:12.080 PCSS Created PC sound system connection: token="c70c0faf42" player="EKIGA" recorder="EKIGA"
2011/05/16 13:14:44.848 0:12.080 OpalCon SetPhase from UninitialisedPhase to SetUpPhase for Call[u969f1e621]-EP<pc>[c70c0faf42]
2011/05/16 13:14:44.848 0:12.080 OpalMan OnIncoming connection Call[u969f1e621]-EP<pc>[c70c0faf42]
2011/05/16 13:14:44.848 0:12.080 OpalCon Applying string options:
2011/05/16 13:14:44.848 0:12.080 Call GetOtherPartyConnection Call[u969f1e621]-EP<pc>[c70c0faf42]
2011/05/16 13:14:44.848 0:12.080 OpalMan Searching for route "pc:visio sip:500@ekiga.net"
2011/05/16 13:14:44.849 0:12.081 OpalMan Set up connection to "sip:500@ekiga.net"
2011/05/16 13:14:44.849 0:12.081 OpalCon Created connection Call[u969f1e621]-EP<sip>[96fc415f-1b7e-e011-893b-00216bcd37f8]
2011/05/16 13:14:44.849 0:12.081 RFC2833 Handler created
2011/05/16 13:14:44.849 0:12.081 RFC2833 Handler created
2011/05/16 13:14:44.849 0:12.081 SIP Created connection.
2011/05/16 13:14:44.849 0:12.081 PCSS Outgoing call routed to sip:500@ekiga.net for Call[u969f1e621]-EP<pc>[c70c0faf42]
2011/05/16 13:14:44.849 0:12.081 OpalMan SetUpCall succeeded, call=Call[u969f1e621]
2011/05/16 13:14:44.850 0:12.082 CallSetup:0xaf136b70 Call OnSetUp Call[u969f1e621]-EP<pc>[c70c0faf42]
2011/05/16 13:14:44.850 0:12.082 CallSetup:0xaf136b70 SIP SetUpConnection: sip:500@ekiga.net
2011/05/16 13:14:44.850 0:12.082 CallSetup:0xaf136b70 OpalCon SetPhase from UninitialisedPhase to SetUpPhase for Call[u969f1e621]-EP<sip>[96fc415f-1b7e-e011-893b-00216bcd37f8]
2011/05/16 13:14:44.850 0:12.082 CallSetup:0xaf136b70 OpalCon Applying string options:
2011/05/16 13:14:44.850 0:12.082 CallSetup:0xaf136b70 DNS SRV Lookup ekiga.net service _sip._udp
2011/05/16 13:14:44.925 0:12.157 CallSetup:0xaf136b70 SIP No SRV record found.
2011/05/16 13:14:44.925 0:12.157 CallSetup:0xaf136b70 SIP Connecting to sip:500@ekiga.net via sip:500@ekiga.net
2011/05/16 13:14:44.925 0:12.157 CallSetup:0xaf136b70 SIP Found registrar on domain ekiga.net, using interface 88.178.126.137%wlan0
2011/05/16 13:14:44.926 0:12.158 CallSetup:0xaf136b70 OpalUDP Binding to interface: 88.178.126.137:5060
2011/05/16 13:14:44.926 0:12.158 CallSetup:0xaf136b70 SIP Created transport udp$86.64.162.35:5060<if=udp$88.178.126.137:5060>
2011/05/16 13:14:44.926 0:12.158 CallSetup:0xaf136b70 OpalUDP Started connect to 86.64.162.35:5060
2011/05/16 13:14:44.926 0:12.158 CallSetup:0xaf136b70 SIP Updating dialog tag from "" to "96fc415f-1b7e-e011-893b-00216bcd37f8"
2011/05/16 13:14:45.064 0:12.296 CallSetup:0xaf136b70 SIP INVITE transaction id=z9hG4bK3eca625f-1b7e-e011-893b-00216bcd37f8 created.
2011/05/16 13:14:45.064 0:12.296 CallSetup:0xaf136b70 SIP Creating INVITE request
2011/05/16 13:14:45.068 0:12.300 CallSetup:0xaf136b70 MediaFormat Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,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
2011/05/16 13:14:45.069 0:12.301 CallSetup:0xaf136b70 MediaFormat Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,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
2011/05/16 13:14:45.069 0:12.301 CallSetup:0xaf136b70 Call GetMediaFormats for Call[u969f1e621]-EP<sip>[96fc415f-1b7e-e011-893b-00216bcd37f8]
G.711-ALaw-64k
SpeexIETFWide-20.6k
SpeexWB
SpeexWide-20.6k
G.711-uLaw-64k
G.722-64k
theora
H.261
H.261-CIF
H.261-QCIF
2011/05/16 13:14:45.069 0:12.301 CallSetup:0xaf136b70 SIP Offering media type audio in SDP with formats
G.711-ALaw-64k,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.722-64k,theora,H.261,H.261-CIF,H.261-QCIF
2011/05/16 13:14:45.070 0:12.302 CallSetup:0xaf136b70 Call IsMediaBypassPossible Call[u969f1e621]-EP<sip>[96fc415f-1b7e-e011-893b-00216bcd37f8] session 1
2011/05/16 13:14:45.070 0:12.302 CallSetup:0xaf136b70 OpalMan IsMediaBypassPossible: session 1
2011/05/16 13:14:45.070 0:12.302 CallSetup:0xaf136b70 OpalCon IsMediaBypassPossible: default returns false
2011/05/16 13:14:45.070 0:12.302 CallSetup:0xaf136b70 RTP Cannot find media session 1
2011/05/16 13:14:45.070 0:12.302 CallSetup:0xaf136b70 RTP Cannot find RTP session 1
2011/05/16 13:14:45.071 0:12.303 CallSetup:0xaf136b70 RTP_UDP Session 1, created with NAT flag set to 0
2011/05/16 13:14:45.071 0:12.303 CallSetup:0xaf136b70 PWLib File handle high water mark set: 49 PUDPSocket
2011/05/16 13:14:45.071 0:12.303 CallSetup:0xaf136b70 PWLib File handle low water mark set: 48 PUDPSocket
2011/05/16 13:14:45.071 0:12.303 CallSetup:0xaf136b70 RTP_UDP Session 1 created: 88.178.126.137:5062-5063 ssrc=948309023
2011/05/16 13:14:45.071 0:12.303 CallSetup:0xaf136b70 PWLib File handle high water mark set: 50 PUDPSocket
2011/05/16 13:14:45.071 0:12.303 CallSetup:0xaf136b70 RTP Creating new session RTP_UDP
2011/05/16 13:14:45.072 0:12.304 CallSetup:0xaf136b70 RTP Found existing media session 1
2011/05/16 13:14:45.072 0:12.304 CallSetup:0xaf136b70 SDP SDP not including SpeexIETFWide-20.6k as it is not a SIP transportable format
2011/05/16 13:14:45.072 0:12.304 CallSetup:0xaf136b70 SDP SDP not including SpeexWide-20.6k as it is not a SIP transportable format
2011/05/16 13:14:45.072 0:12.304 CallSetup:0xaf136b70 SIP Using default RTP payload [pt=101] for UserInput/RFC2833
2011/05/16 13:14:45.072 0:12.305 CallSetup:0xaf136b70 SIP Using default RTP payload [pt=120] for NamedSignalEvent
2011/05/16 13:14:45.076 0:12.308 CallSetup:0xaf136b70 MediaFormat Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,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
2011/05/16 13:14:45.077 0:12.309 CallSetup:0xaf136b70 MediaFormat Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,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
2011/05/16 13:14:45.077 0:12.309 CallSetup:0xaf136b70 Call GetMediaFormats for Call[u969f1e621]-EP<sip>[96fc415f-1b7e-e011-893b-00216bcd37f8]
G.711-ALaw-64k
SpeexIETFWide-20.6k
SpeexWB
SpeexWide-20.6k
G.711-uLaw-64k
G.722-64k
theora
H.261
H.261-CIF
H.261-QCIF
2011/05/16 13:14:45.077 0:12.309 CallSetup:0xaf136b70 SIP Offering media type video in SDP with formats
G.711-ALaw-64k,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.722-64k,theora,H.261,H.261-CIF,H.261-QCIF
2011/05/16 13:14:45.077 0:12.309 CallSetup:0xaf136b70 Call IsMediaBypassPossible Call[u969f1e621]-EP<sip>[96fc415f-1b7e-e011-893b-00216bcd37f8] session 2
2011/05/16 13:14:45.077 0:12.309 CallSetup:0xaf136b70 OpalMan IsMediaBypassPossible: session 2
2011/05/16 13:14:45.077 0:12.309 CallSetup:0xaf136b70 OpalCon IsMediaBypassPossible: default returns false
2011/05/16 13:14:45.077 0:12.309 CallSetup:0xaf136b70 RTP Cannot find media session 2
2011/05/16 13:14:45.077 0:12.309 CallSetup:0xaf136b70 RTP Cannot find RTP session 2
2011/05/16 13:14:45.078 0:12.310 CallSetup:0xaf136b70 RTP_UDP Session 2, created with NAT flag set to 0
2011/05/16 13:14:45.078 0:12.310 CallSetup:0xaf136b70 PWLib File handle high water mark set: 51 PUDPSocket
2011/05/16 13:14:45.078 0:12.310 CallSetup:0xaf136b70 PWLib File handle low water mark set: 50 PUDPSocket
2011/05/16 13:14:45.078 0:12.310 CallSetup:0xaf136b70 RTP_UDP Session 2 created: 88.178.126.137:5064-5065 ssrc=4087635700
2011/05/16 13:14:45.078 0:12.310 CallSetup:0xaf136b70 PWLib File handle high water mark set: 52 PUDPSocket
2011/05/16 13:14:45.079 0:12.311 CallSetup:0xaf136b70 RTP Creating new session RTP_UDP
2011/05/16 13:14:45.079 0:12.311 CallSetup:0xaf136b70 RTP Found existing media session 2
2011/05/16 13:14:45.079 0:12.311 CallSetup:0xaf136b70 SDP SDP not including H.261-CIF as it is not a SIP transportable format
2011/05/16 13:14:45.079 0:12.311 CallSetup:0xaf136b70 SDP SDP not including H.261-QCIF as it is not a SIP transportable format
2011/05/16 13:14:45.080 0:12.312 CallSetup:0xaf136b70 DNS SRV Lookup ekiga.net service _sip._udp
2011/05/16 13:14:45.145 0:12.377 CallSetup:0xaf136b70 SIP No SRV record found.
2011/05/16 13:14:45.145 0:12.377 CallSetup:0xaf136b70 SIP Transaction remote address is udp$ekiga.net:5060
2011/05/16 13:14:45.146 0:12.378 CallSetup:0xaf136b70 OpalPlugin to_customised_options: theora
2011/05/16 13:14:45.147 0:12.379 CallSetup:0xaf136b70 OpalPlugin to_customised_options: H.261
2011/05/16 13:14:45.148 0:12.380 CallSetup:0xaf136b70 SIP Sending PDU (1122 bytes) to: rem=udp$86.64.162.35:5060,local=udp$88.178.126.137:5060,if=88.178.126.137%wlan0
INVITE sip:500@ekiga.net SIP/2.0
Date: Mon, 16 May 2011 11:14:45 GMT
CSeq: 1 INVITE
Via: SIP/2.0/UDP 88.178.126.137:5060;branch=z9hG4bK3eca625f-1b7e-e011-893b-00216bcd37f8;rport
User-Agent: Ekiga/3.2.6
From: "visio" <sip:testvisio@ekiga.net>;tag=96fc415f-1b7e-e011-893b-00216bcd37f8
Call-ID: 1c08425f-1b7e-e011-893b-00216bcd37f8@visio-laptop
To: <sip:500@ekiga.net>
Contact: <sip:testvisio@88.178.126.137>
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Content-Type: application/sdp
Content-Length: 556
Max-Forwards: 70
v=0
o=- 1305544484 1 IN IP4 88.178.126.137
s=Opal SIP Session
c=IN IP4 88.178.126.137
t=0 0
m=audio 5062 RTP/AVP 8 110 0 9 101 120
a=sendrecv
a=rtpmap:8 PCMA/8000/1
a=rtpmap:110 Speex/16000/1
a=fmtp:110 sr=16000,mode=any
a=rtpmap:0 PCMU/8000/1
a=rtpmap:9 G722/8000/1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16,32,36
a=rtpmap:120 NSE/8000
a=fmtp:120 192-193
m=video 5064 RTP/AVP 119 31
b=AS:4096
b=TIAS:4096000
a=sendrecv
a=rtpmap:119 theora/90000
a=fmtp:119 height=576;width=704
a=rtpmap:31 h261/90000
a=fmtp:31 CIF=1;QCIF=1
2011/05/16 13:14:45.148 0:12.380 CallSetup:0xaf136b70 OpalUDP Setting interface to 88.178.126.137%wlan0
2011/05/16 13:14:45.148 0:12.380 CallSetup:0xaf136b70 SIP Transaction timers set: retry=0.500, completion=1:00.000
2011/05/16 13:14:45.148 0:12.380 CallSetup:0xaf136b70 OpalCon OnSetUpConnectionCall[u969f1e621]-EP<sip>[96fc415f-1b7e-e011-893b-00216bcd37f8]
2011/05/16 13:14:45.148 0:12.380 CallSetup:0xaf136b70 OpalEP OnSetUpConnection Call[u969f1e621]-EP<sip>[96fc415f-1b7e-e011-893b-00216bcd37f8]
2011/05/16 13:14:45.193 0:12.425 Opal Liste...0xb457eb70 PWLib File handle low water mark set: 46 PUDPSocket
2011/05/16 13:14:45.193 0:12.425 Opal Liste...0xb457eb70 OpalUDP Binding to interface: 88.178.126.137:5060
2011/05/16 13:14:45.193 0:12.426 Opal Liste...0xb457eb70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$88.178.126.137:5060>
2011/05/16 13:14:45.194 0:12.426 Opal Liste...0xb457eb70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$88.178.126.137:5060,if=88.178.126.137%wlan0
SIP/2.0 407 Proxy Authentication Required
CSeq: 1 INVITE
Via: SIP/2.0/UDP 88.178.126.137:5060;branch=z9hG4bK3eca625f-1b7e-e011-893b-00216bcd37f8;rport=5060
Server: Kamailio (1.5.3-notls (i386/linux))
From: "visio" <sip:testvisio@ekiga.net>;tag=96fc415f-1b7e-e011-893b-00216bcd37f8
Call-ID: 1c08425f-1b7e-e011-893b-00216bcd37f8@visio-laptop
To: <sip:500@ekiga.net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.ee3f
Proxy-Authenticate: Digest realm="ekiga.net", nonce="4dd107420000b06ef89183cc0bc54aec584fe7ccdd327d6c"
Content-Length: 0
2011/05/16 13:14:45.195 0:12.427 Opal Liste...0xb457eb70 SIP Queueing PDU "1 INVITE <407>", transaction=z9hG4bK3eca625f-1b7e-e011-893b-00216bcd37f8, token=96fc415f-1b7e-e011-893b-00216bcd37f8
2011/05/16 13:14:45.195 0:12.427 Opal Liste...0xb457eb70 Opal Transport clean up on termination
2011/05/16 13:14:45.195 0:12.427 Opal Liste...0xb457eb70 Opal Transport Close
2011/05/16 13:14:45.195 0:12.427 Pool:0xaf136b70 SIP Handling PDU "1 INVITE <407>" for transaction=z9hG4bK3eca625f-1b7e-e011-893b-00216bcd37f8
2011/05/16 13:14:45.195 0:12.427 Opal Liste...0xb457eb70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$88.178.126.137:5060>
2011/05/16 13:14:45.197 0:12.429 Pool:0xaf136b70 SIP Sending PDU (387 bytes) to: rem=udp$86.64.162.35:5060,local=udp$88.178.126.137:5060,if=88.178.126.137%wlan0
ACK sip:500@ekiga.net SIP/2.0
CSeq: 1 ACK
Via: SIP/2.0/UDP 88.178.126.137:5060;branch=z9hG4bK3eca625f-1b7e-e011-893b-00216bcd37f8;rport
From: "visio" <sip:testvisio@ekiga.net>;tag=96fc415f-1b7e-e011-893b-00216bcd37f8
Call-ID: 1c08425f-1b7e-e011-893b-00216bcd37f8@visio-laptop
To: <sip:500@ekiga.net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.ee3f
Content-Length: 0
Max-Forwards: 70
2011/05/16 13:14:45.197 0:12.429 Pool:0xaf136b70 OpalUDP Setting interface to 88.178.126.137%wlan0
2011/05/16 13:14:45.197 0:12.429 Pool:0xaf136b70 SIP INVITE transaction id=z9hG4bK3eca625f-1b7e-e011-893b-00216bcd37f8 completed.
2011/05/16 13:14:45.197 0:12.429 Pool:0xaf136b70 SIP Received Proxy Authentication Required response
2011/05/16 13:14:45.197 0:12.429 Pool:0xaf136b70 SIP Found auth info for realm ekiga.net
2011/05/16 13:14:45.197 0:12.429 Pool:0xaf136b70 OpalUDP Setting interface to 88.178.126.137%wlan0
2011/05/16 13:14:45.201 0:12.433 Pool:0xaf136b70 SIP INVITE transaction id=z9hG4bK00ad775f-1b7e-e011-893b-00216bcd37f8 created.
2011/05/16 13:14:45.201 0:12.433 Pool:0xaf136b70 SIP Creating INVITE request
2011/05/16 13:14:45.205 0:12.437 Pool:0xaf136b70 MediaFormat Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,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
2011/05/16 13:14:45.206 0:12.438 Pool:0xaf136b70 MediaFormat Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,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
2011/05/16 13:14:45.207 0:12.439 Pool:0xaf136b70 Call GetMediaFormats for Call[u969f1e621]-EP<sip>[96fc415f-1b7e-e011-893b-00216bcd37f8]
G.711-ALaw-64k
SpeexIETFWide-20.6k
SpeexWB
SpeexWide-20.6k
G.711-uLaw-64k
G.722-64k
theora
H.261
H.261-CIF
H.261-QCIF
2011/05/16 13:14:45.207 0:12.439 Pool:0xaf136b70 SIP Offering media type audio in SDP with formats
G.711-ALaw-64k,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.722-64k,theora,H.261,H.261-CIF,H.261-QCIF
2011/05/16 13:14:45.207 0:12.439 Pool:0xaf136b70 Call IsMediaBypassPossible Call[u969f1e621]-EP<sip>[96fc415f-1b7e-e011-893b-00216bcd37f8] session 1
2011/05/16 13:14:45.207 0:12.439 Pool:0xaf136b70 OpalMan IsMediaBypassPossible: session 1
2011/05/16 13:14:45.207 0:12.439 Pool:0xaf136b70 OpalCon IsMediaBypassPossible: default returns false
2011/05/16 13:14:45.207 0:12.439 Pool:0xaf136b70 RTP Found existing media session 1
2011/05/16 13:14:45.207 0:12.439 Pool:0xaf136b70 RTP Found existing RTP session 1
2011/05/16 13:14:45.208 0:12.440 Pool:0xaf136b70 SDP SDP not including SpeexIETFWide-20.6k as it is not a SIP transportable format
2011/05/16 13:14:45.208 0:12.440 Pool:0xaf136b70 SDP SDP not including SpeexWide-20.6k as it is not a SIP transportable format
2011/05/16 13:14:45.208 0:12.440 Pool:0xaf136b70 SIP Using default RTP payload [pt=101] for UserInput/RFC2833
2011/05/16 13:14:45.208 0:12.440 Pool:0xaf136b70 SIP Using default RTP payload [pt=120] for NamedSignalEvent
2011/05/16 13:14:45.212 0:12.444 Pool:0xaf136b70 MediaFormat Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,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
2011/05/16 13:14:45.213 0:12.445 Pool:0xaf136b70 MediaFormat Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,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
2011/05/16 13:14:45.214 0:12.446 Pool:0xaf136b70 Call GetMediaFormats for Call[u969f1e621]-EP<sip>[96fc415f-1b7e-e011-893b-00216bcd37f8]
G.711-ALaw-64k
SpeexIETFWide-20.6k
SpeexWB
SpeexWide-20.6k
G.711-uLaw-64k
G.722-64k
theora
H.261
H.261-CIF
H.261-QCIF
2011/05/16 13:14:45.214 0:12.446 Pool:0xaf136b70 SIP Offering media type video in SDP with formats
G.711-ALaw-64k,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.722-64k,theora,H.261,H.261-CIF,H.261-QCIF
2011/05/16 13:14:45.214 0:12.446 Pool:0xaf136b70 Call IsMediaBypassPossible Call[u969f1e621]-EP<sip>[96fc415f-1b7e-e011-893b-00216bcd37f8] session 2
2011/05/16 13:14:45.214 0:12.446 Pool:0xaf136b70 OpalMan IsMediaBypassPossible: session 2
2011/05/16 13:14:45.214 0:12.446 Pool:0xaf136b70 OpalCon IsMediaBypassPossible: default returns false
2011/05/16 13:14:45.214 0:12.446 Pool:0xaf136b70 RTP Found existing media session 2
2011/05/16 13:14:45.214 0:12.446 Pool:0xaf136b70 RTP Found existing RTP session 2
2011/05/16 13:14:45.215 0:12.447 Pool:0xaf136b70 SDP SDP not including H.261-CIF as it is not a SIP transportable format
2011/05/16 13:14:45.216 0:12.448 Pool:0xaf136b70 SDP SDP not including H.261-QCIF as it is not a SIP transportable format
2011/05/16 13:14:45.216 0:12.448 Pool:0xaf136b70 SIP Adding authentication information
2011/05/16 13:14:45.216 0:12.448 Pool:0xaf136b70 DNS SRV Lookup ekiga.net service _sip._udp
2011/05/16 13:14:45.281 0:12.513 Pool:0xaf136b70 SIP No SRV record found.
2011/05/16 13:14:45.281 0:12.514 Pool:0xaf136b70 SIP Transaction remote address is udp$ekiga.net:5060
2011/05/16 13:14:45.282 0:12.514 Pool:0xaf136b70 OpalPlugin to_customised_options: theora
2011/05/16 13:14:45.283 0:12.515 Pool:0xaf136b70 OpalPlugin to_customised_options: H.261
2011/05/16 13:14:45.284 0:12.516 Pool:0xaf136b70 SIP Sending PDU (1334 bytes) to: rem=udp$86.64.162.35:5060,local=udp$88.178.126.137:5060,if=88.178.126.137%wlan0
INVITE sip:500@ekiga.net SIP/2.0
Date: Mon, 16 May 2011 11:14:45 GMT
CSeq: 2 INVITE
Via: SIP/2.0/UDP 88.178.126.137:5060;branch=z9hG4bK00ad775f-1b7e-e011-893b-00216bcd37f8;rport
User-Agent: Ekiga/3.2.6
From: "visio" <sip:testvisio@ekiga.net>;tag=96fc415f-1b7e-e011-893b-00216bcd37f8
Call-ID: 1c08425f-1b7e-e011-893b-00216bcd37f8@visio-laptop
To: <sip:500@ekiga.net>
Contact: <sip:testvisio@88.178.126.137>
Proxy-Authorization: Digest username="testvisio", realm="ekiga.net", nonce="4dd107420000b06ef89183cc0bc54aec584fe7ccdd327d6c", uri="sip:500@ekiga.net", algorithm=MD5, response="cbe638ede96cf19ff76d9119218b3a2d"
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Content-Type: application/sdp
Content-Length: 556
Max-Forwards: 70
v=0
o=- 1305544484 1 IN IP4 88.178.126.137
s=Opal SIP Session
c=IN IP4 88.178.126.137
t=0 0
m=audio 5062 RTP/AVP 8 110 0 9 101 120
a=sendrecv
a=rtpmap:8 PCMA/8000/1
a=rtpmap:110 Speex/16000/1
a=fmtp:110 sr=16000,mode=any
a=rtpmap:0 PCMU/8000/1
a=rtpmap:9 G722/8000/1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16,32,36
a=rtpmap:120 NSE/8000
a=fmtp:120 192-193
m=video 5064 RTP/AVP 119 31
b=AS:4096
b=TIAS:4096000
a=sendrecv
a=rtpmap:119 theora/90000
a=fmtp:119 height=576;width=704
a=rtpmap:31 h261/90000
a=fmtp:31 CIF=1;QCIF=1
2011/05/16 13:14:45.284 0:12.516 Pool:0xaf136b70 OpalUDP Setting interface to 88.178.126.137%wlan0
2011/05/16 13:14:45.285 0:12.517 Pool:0xaf136b70 SIP Transaction timers set: retry=0.500, completion=1:00.000
2011/05/16 13:14:45.285 0:12.517 Pool:0xaf136b70 SIP Handled PDU "1 INVITE <407>"
2011/05/16 13:14:45.332 0:12.564 Opal Liste...0xb457eb70 OpalUDP Binding to interface: 88.178.126.137:5060
2011/05/16 13:14:45.332 0:12.564 Opal Liste...0xb457eb70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$88.178.126.137:5060>
2011/05/16 13:14:45.332 0:12.564 Opal Liste...0xb457eb70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$88.178.126.137:5060,if=88.178.126.137%wlan0
SIP/2.0 100 Giving a try
CSeq: 2 INVITE
Via: SIP/2.0/UDP 88.178.126.137:5060;branch=z9hG4bK00ad775f-1b7e-e011-893b-00216bcd37f8;rport=5060
Server: Kamailio (1.5.3-notls (i386/linux))
From: "visio" <sip:testvisio@ekiga.net>;tag=96fc415f-1b7e-e011-893b-00216bcd37f8
Call-ID: 1c08425f-1b7e-e011-893b-00216bcd37f8@visio-laptop
To: <sip:500@ekiga.net>
Content-Length: 0
2011/05/16 13:14:45.333 0:12.565 Opal Liste...0xb457eb70 SIP Queueing PDU "2 INVITE <100>", transaction=z9hG4bK00ad775f-1b7e-e011-893b-00216bcd37f8, token=96fc415f-1b7e-e011-893b-00216bcd37f8
2011/05/16 13:14:45.333 0:12.565 Opal Liste...0xb457eb70 Opal Transport clean up on termination
2011/05/16 13:14:45.333 0:12.565 Opal Liste...0xb457eb70 Opal Transport Close
2011/05/16 13:14:45.333 0:12.565 Pool:0xaf136b70 SIP Handling PDU "2 INVITE <100>" for transaction=z9hG4bK00ad775f-1b7e-e011-893b-00216bcd37f8
2011/05/16 13:14:45.333 0:12.565 Opal Liste...0xb457eb70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$88.178.126.137:5060>
2011/05/16 13:14:45.334 0:12.566 Pool:0xaf136b70 SIP Product Info: name="Kamailio", version="", vendor="", comments="(1.5.3-notls (i386/linux))"
2011/05/16 13:14:45.334 0:12.566 Pool:0xaf136b70 SIP INVITE transaction id=z9hG4bK00ad775f-1b7e-e011-893b-00216bcd37f8 proceeding.
2011/05/16 13:14:45.334 0:12.566 Pool:0xaf136b70 SIP Received Trying response
2011/05/16 13:14:45.334 0:12.566 Opal Liste...0xb457eb70 OpalUDP Binding to interface: 88.178.126.137:5060
2011/05/16 13:14:45.334 0:12.566 Pool:0xaf136b70 OpalCon SetPhase from SetUpPhase to ProceedingPhase for Call[u969f1e621]-EP<sip>[96fc415f-1b7e-e011-893b-00216bcd37f8]
2011/05/16 13:14:45.334 0:12.566 Pool:0xaf136b70 OpalMan OnProceeding Call[u969f1e621]-EP<sip>[96fc415f-1b7e-e011-893b-00216bcd37f8]
2011/05/16 13:14:45.334 0:12.566 Pool:0xaf136b70 Call OnProceeding Call[u969f1e621]-EP<sip>[96fc415f-1b7e-e011-893b-00216bcd37f8]
2011/05/16 13:14:45.334 0:12.566 Pool:0xaf136b70 SIP Handled PDU "2 INVITE <100>"
2011/05/16 13:14:45.334 0:12.566 Opal Liste...0xb457eb70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$88.178.126.137:5060>
2011/05/16 13:14:45.335 0:12.567 Opal Liste...0xb457eb70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$88.178.126.137:5060,if=88.178.126.137%wlan0
SIP/2.0 500 Server internal error
CSeq: 2 INVITE
Via: SIP/2.0/UDP 88.178.126.137:5060;received=88.178.126.137;branch=z9hG4bK00ad775f-1b7e-e011-893b-00216bcd37f8;rport=5060
User-Agent: Ekiga.NET
From: "visio" <sip:testvisio@ekiga.net>;tag=96fc415f-1b7e-e011-893b-00216bcd37f8
Call-ID: 1c08425f-1b7e-e011-893b-00216bcd37f8@visio-laptop
Supported: replaces
To: <sip:500@ekiga.net>;tag=as09e65212
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0
2011/05/16 13:14:45.335 0:12.567 Opal Liste...0xb457eb70 SIP Queueing PDU "2 INVITE <500>", transaction=z9hG4bK00ad775f-1b7e-e011-893b-00216bcd37f8, token=96fc415f-1b7e-e011-893b-00216bcd37f8
2011/05/16 13:14:45.335 0:12.567 Opal Liste...0xb457eb70 Opal Transport clean up on termination
2011/05/16 13:14:45.335 0:12.567 Opal Liste...0xb457eb70 Opal Transport Close
2011/05/16 13:14:45.335 0:12.567 Pool:0xaf136b70 SIP Handling PDU "2 INVITE <500>" for transaction=z9hG4bK00ad775f-1b7e-e011-893b-00216bcd37f8
2011/05/16 13:14:45.336 0:12.568 Opal Liste...0xb457eb70 PWLib File handle high water mark set: 53 PUDPSocket
2011/05/16 13:14:45.336 0:12.568 Pool:0xaf136b70 PWLib File handle low water mark set: 52 PUDPSocket
2011/05/16 13:14:45.335 0:12.567 Opal Liste...0xb457eb70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$88.178.126.137:5060>
2011/05/16 13:14:45.337 0:12.569 Pool:0xaf136b70 SIP Adding authentication information
2011/05/16 13:14:45.337 0:12.569 Pool:0xaf136b70 SIP Sending PDU (572 bytes) to: rem=udp$86.64.162.35:5060,local=udp$88.178.126.137:5060,if=88.178.126.137%wlan0
ACK sip:500@ekiga.net SIP/2.0
CSeq: 2 ACK
Via: SIP/2.0/UDP 88.178.126.137:5060;branch=z9hG4bK00ad775f-1b7e-e011-893b-00216bcd37f8;rport
From: "visio" <sip:testvisio@ekiga.net>;tag=96fc415f-1b7e-e011-893b-00216bcd37f8
Call-ID: 1c08425f-1b7e-e011-893b-00216bcd37f8@visio-laptop
To: <sip:500@ekiga.net>;tag=as09e65212
Proxy-Authorization: Digest username="testvisio", realm="ekiga.net", nonce="4dd107420000b06ef89183cc0bc54aec584fe7ccdd327d6c", uri="sip:500@ekiga.net", algorithm=MD5, response="6f9cf2a56c04fe86d655917236fe2f66"
Content-Length: 0
Max-Forwards: 70
2011/05/16 13:14:45.338 0:12.570 Pool:0xaf136b70 OpalUDP Setting interface to 88.178.126.137%wlan0
2011/05/16 13:14:45.338 0:12.570 Pool:0xaf136b70 SIP INVITE transaction id=z9hG4bK00ad775f-1b7e-e011-893b-00216bcd37f8 completed.
2011/05/16 13:14:45.338 0:12.570 Pool:0xaf136b70 OpalCon SetPhase from ProceedingPhase to ReleasingPhase for Call[u969f1e621]-EP<sip>[96fc415f-1b7e-e011-893b-00216bcd37f8]
2011/05/16 13:14:45.338 0:12.570 Pool:0xaf136b70 OpalCon Releasing Call[u969f1e621]-EP<sip>[96fc415f-1b7e-e011-893b-00216bcd37f8]
2011/05/16 13:14:45.338 0:12.570 Pool:0xaf136b70 OpalCon Call end reason for Call[u969f1e621]-EP<sip>[96fc415f-1b7e-e011-893b-00216bcd37f8] set to EndedByQ931Cause
2011/05/16 13:14:45.338 0:12.570 Pool:0xaf136b70 PTLib Thread high water mark set: 12
2011/05/16 13:14:45.338 0:12.570 Pool:0xaf136b70 SIP Handled PDU "2 INVITE <500>"
2011/05/16 13:14:45.338 0:12.570 OnRelease:0xaf0f5b70 SIP OnReleased: Call[u969f1e621]-EP<sip>[96fc415f-1b7e-e011-893b-00216bcd37f8], phase = ReleasingPhase
2011/05/16 13:14:45.338 0:12.570 OnRelease:0xaf0f5b70 OpalCon SetPhase from ReleasingPhase to ReleasingPhase for Call[u969f1e621]-EP<sip>[96fc415f-1b7e-e011-893b-00216bcd37f8]
2011/05/16 13:14:45.339 0:12.571 OnRelease:0xaf0f5b70 OpalCon Media streams closed.
2011/05/16 13:14:45.339 0:12.571 OnRelease:0xaf0f5b70 SIP Awaiting forked INVITE transaction completion, id=z9hG4bK3eca625f-1b7e-e011-893b-00216bcd37f8
2011/05/16 13:14:45.339 0:12.571 OnRelease:0xaf0f5b70 SIP Awaiting forked INVITE transaction completion, id=z9hG4bK00ad775f-1b7e-e011-893b-00216bcd37f8
2011/05/16 13:14:45.339 0:12.571 OnRelease:0xaf0f5b70 OpalCon SetPhase from ReleasingPhase to ReleasedPhase for Call[u969f1e621]-EP<sip>[96fc415f-1b7e-e011-893b-00216bcd37f8]
2011/05/16 13:14:45.339 0:12.571 OnRelease:0xaf0f5b70 OpalCon OnReleased Call[u969f1e621]-EP<sip>[96fc415f-1b7e-e011-893b-00216bcd37f8]
2011/05/16 13:14:45.339 0:12.571 OnRelease:0xaf0f5b70 OpalEP OnReleased Call[u969f1e621]-EP<sip>[96fc415f-1b7e-e011-893b-00216bcd37f8]
2011/05/16 13:14:45.339 0:12.571 OnRelease:0xaf0f5b70 OpalMan OnReleased Call[u969f1e621]-EP<sip>[96fc415f-1b7e-e011-893b-00216bcd37f8]
2011/05/16 13:14:45.339 0:12.571 OnRelease:0xaf0f5b70 Call OnReleased Call[u969f1e621]-EP<sip>[96fc415f-1b7e-e011-893b-00216bcd37f8]
2011/05/16 13:14:45.339 0:12.571 OnRelease:0xaf0f5b70 OpalCon SetPhase from SetUpPhase to ReleasingPhase for Call[u969f1e621]-EP<pc>[c70c0faf42]
2011/05/16 13:14:45.339 0:12.571 OnRelease:0xaf0f5b70 OpalCon Releasing Call[u969f1e621]-EP<pc>[c70c0faf42]
2011/05/16 13:14:45.339 0:12.571 OnRelease:0xaf0f5b70 OpalCon Call end reason for Call[u969f1e621]-EP<pc>[c70c0faf42] set to EndedByQ931Cause
2011/05/16 13:14:45.339 0:12.571 OnRelease:0xaf0f5b70 OpalCon OnReleased Call[u969f1e621]-EP<pc>[c70c0faf42]
2011/05/16 13:14:45.340 0:12.572 OnRelease:0xaf0f5b70 OpalEP OnReleased Call[u969f1e621]-EP<pc>[c70c0faf42]
2011/05/16 13:14:45.340 0:12.572 OnRelease:0xaf0f5b70 OpalMan OnReleased Call[u969f1e621]-EP<pc>[c70c0faf42]
2011/05/16 13:14:45.340 0:12.572 OnRelease:0xaf0f5b70 Call OnReleased Call[u969f1e621]-EP<pc>[c70c0faf42]
2011/05/16 13:14:45.340 0:12.572 OnRelease:0xaf0f5b70 OpalCon Media streams closed.
2011/05/16 13:14:45.340 0:12.572 OnRelease:0xaf0f5b70 OpalCon Media streams closed.
2011/05/16 13:14:45.340 0:12.572 OnRelease:0xaf0f5b70 OpalCon OnRelease thread completed for Call[u969f1e621]-EP<sip>[96fc415f-1b7e-e011-893b-00216bcd37f8]
2011/05/16 13:14:45.404 0:12.636 AEScheduler Removing Event incoming_call_sound from queue
2011/05/16 13:14:45.404 0:12.636 AEScheduler Removing Event ring_tone_sound from queue
2011/05/16 13:14:45.404 0:12.636 OpalMan OnClearedCall Call[u969f1e621] from "pc:visio" to "sip:500@ekiga.net"
2011/05/16 13:14:45.404 0:12.636 OPAL Attempting to close recording on call u969f1e621 when not recording.
2011/05/16 13:14:46.038 0:13.270 Opal Garbage:0xb4641b70 PCSS Deleted PC sound system connection.
2011/05/16 13:14:46.038 0:13.270 Opal Garbage:0xb4641b70 OpalCon Connection Call[u969f1e621]-EP<pc>[c70c0faf42] destroyed.
2011/05/16 13:14:46.285 0:13.517 Housekeeper:0xb45bfb70 SIP Set state Terminated_Success for INVITE transaction id=z9hG4bK3eca625f-1b7e-e011-893b-00216bcd37f8
2011/05/16 13:14:46.338 0:13.570 Housekeeper:0xb45bfb70 SIP Set state Terminated_Success for INVITE transaction id=z9hG4bK00ad775f-1b7e-e011-893b-00216bcd37f8
2011/05/16 13:14:47.039 0:14.271 Opal Garbage:0xb4641b70 SIP Transaction id=z9hG4bK3eca625f-1b7e-e011-893b-00216bcd37f8 destroyed.
2011/05/16 13:14:47.039 0:14.271 Opal Garbage:0xb4641b70 RTP Closing session 1
2011/05/16 13:14:47.039 0:14.271 Opal Garbage:0xb4641b70 RTP Deleting session 1
2011/05/16 13:14:47.039 0:14.271 Opal Garbage:0xb4641b70 RTP_UDP Session 1, Shutting down read.
2011/05/16 13:14:47.040 0:14.272 Opal Garbage:0xb4641b70 RTP_UDP Session 1, Shutting down write.
2011/05/16 13:14:47.040 0:14.272 Opal Garbage:0xb4641b70 RTP Closing session 2
2011/05/16 13:14:47.040 0:14.272 Opal Garbage:0xb4641b70 RTP Deleting session 2
2011/05/16 13:14:47.040 0:14.272 Opal Garbage:0xb4641b70 RTP_UDP Session 2, Shutting down read.
2011/05/16 13:14:47.040 0:14.272 Opal Garbage:0xb4641b70 RTP_UDP Session 2, Shutting down write.
2011/05/16 13:14:47.040 0:14.272 Opal Garbage:0xb4641b70 SIP Transaction id=z9hG4bK00ad775f-1b7e-e011-893b-00216bcd37f8 destroyed.
2011/05/16 13:14:47.040 0:14.272 Opal Garbage:0xb4641b70 Opal Transport clean up on termination
2011/05/16 13:14:47.040 0:14.272 Opal Garbage:0xb4641b70 Opal Transport Close
2011/05/16 13:14:47.040 0:14.272 Opal Garbage:0xb4641b70 Opal Transport clean up on termination
2011/05/16 13:14:47.040 0:14.272 Opal Garbage:0xb4641b70 Opal Transport Close
2011/05/16 13:14:47.041 0:14.273 Opal Garbage:0xb4641b70 PWLib File handle low water mark set: 48 PUDPSocket
2011/05/16 13:14:47.041 0:14.273 Opal Garbage:0xb4641b70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$88.178.126.137:5060>
2011/05/16 13:14:47.041 0:14.273 Opal Garbage:0xb4641b70 SIP Deleted connection.
2011/05/16 13:14:47.042 0:14.274 Opal Garbage:0xb4641b70 OpalCon Connection Call[u969f1e621]-EP<sip>[96fc415f-1b7e-e011-893b-00216bcd37f8] destroyed.
2011/05/16 13:14:48.103 0:15.335 Call Call[u969f1e621] destroyed.
2011/05/16 13:15:04.536 0:31.768 PreviewManager Stopping Preview
2011/05/16 13:15:04.541 0:31.773 GMVideoOut...0xb4682b70 X11 Unmapping and destroying Window with ID 67108866
2011/05/16 13:15:04.542 0:31.774 Network In...0xb4600b70 IfaceMon Finished interface monitor thread.