On 10/01/11 18:21, Nikolai Cassanova wrote:Look at http://wiki.ekiga.org/index.php/Debugging_Ekiga#How_to_get_a_debug_output
Would love to help as I use sipdroid (version 1.6 beta, don't like version
2.0.1 as in that version on my device the registration timeout for my pbxes
account happens frequently while in 1.6 on my device it last longer and
never times out but does sometimes re-register)
I have android 2.2.1 with cyanogenmod rom version 6.1.0 (on HTC
Magic/Sapphire) I can do the test as I have run this same test for another
user on this mailing list that indicated that placing a sip call to ekiga
from sipdroid didn't work for him, but it works for me (I don't know if that
is because I am using ekiga version 3.2.7 on ubuntu 10.10) and vice versa, I
can just put ekiga 3.2.7 on windows xp laptop that i have and test to see if
it works or not the only thing is I don't know how to get -d 4 output to see
what is going on while the call occur.
--
Eugen
_______________________________________________
ekiga-list mailing list
ekiga-list gnome org
http://mail.gnome.org/mailman/listinfo/ekiga-list
2011/01/17 12:52:46.861 0:01.167 Version 3.2.7 by on Unix Linux (2.6.35-24-generic-i686) with PTLib (v2.6.7) at 2011/1/17 12:52:46.861
2011/01/17 12:52:46.861 0:01.167 Ekiga git revision: unknown
2011/01/17 12:52:46.868 0:01.174 Ekiga registered on D-Bus: org.ekiga.Ekiga
2011/01/17 12:52:46.869 0:01.175 PWLib File handle high water mark set: 19 Thread unblock pipe
2011/01/17 12:52:46.869 0:01.175 PTLib No permission to set priority level 4
2011/01/17 12:52:46.869 0:01.175 PTLib Thread high water mark set: 2
2011/01/17 12:52:46.870 0:01.175 PWLib File handle high water mark set: 21 Thread unblock pipe
2011/01/17 12:52:46.870 0:01.175 PTLib No permission to set priority level 4
2011/01/17 12:52:46.870 0:01.176 PTLib Thread high water mark set: 3
2011/01/17 12:52:46.870 0:01.176 PWLib File handle high water mark set: 23 Thread unblock pipe
2011/01/17 12:52:46.903 0:01.209 PWLib File handle high water mark set: 25 Thread unblock pipe
2011/01/17 12:52:46.905 0:01.211 PTLib No permission to set priority level 4
2011/01/17 12:52:46.905 0:01.211 PTLib Thread high water mark set: 4
2011/01/17 12:52:46.906 0:01.212 HalManager_dbus Initialising HAL Manager
2011/01/17 12:52:46.909 0:01.215 HalManager_dbus Populating device list
2011/01/17 12:52:47.855 0:02.161 HalManager_dbus Populated device list with 8 devices
2011/01/17 12:52:47.856 0:02.162 HalManager_dbus Populating interface list
2011/01/17 12:52:47.857 0:02.162 HalManager_dbus Populating full interface list failed - Method "getDevices" with signature "" on interface "org.freedesktop.NetworkManager" doesn't exist
2011/01/17 12:52:47.857 0:02.163 Detecting V4L2 devices
2011/01/17 12:52:47.857 0:02.163 Unable to detect v4l2 directory
2011/01/17 12:52:47.888 0:02.194 PWLib File handle high water mark set: 30 Thread unblock pipe
2011/01/17 12:52:47.888 0:02.194 PTLib Thread high water mark set: 5
2011/01/17 12:52:47.888 0:02.194 OpalMan Created manager.
2011/01/17 12:52:47.889 0:02.194 OpalMan Attached endpoint with prefix pc
2011/01/17 12:52:47.889 0:02.194 OpalEP Created endpoint: pc
2011/01/17 12:52:47.956 0:02.261 PCSS Created PC sound system endpoint.
Players:
Default
HDA Intel
HDA Intel (1)
EKIGA
*.wav
Recorders:
Default
HDA Intel
EKIGA
*.wav
2011/01/17 12:52:47.956 0:02.262 OPAL SetMediaFormatOrder()
2011/01/17 12:52:47.956 0:02.262 OPAL SetMediaFormatMask()
2011/01/17 12:52:48.006 0:02.312 OpalMan Attached endpoint with prefix sip
2011/01/17 12:52:48.006 0:02.312 OpalEP Created endpoint: sip
2011/01/17 12:52:48.007 0:02.313 PWLib File handle high water mark set: 31 PUDPSocket
2011/01/17 12:52:48.007 0:02.313 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)
192.168.1.3 [fe:80:00:00:00:00:00:00:02:13:02:ff:fe:9a:3f:df] <00-13-02-9A-3F-DF> (wlan0)
2011/01/17 12:52:48.007 0:02.313 PWLib File handle high water mark set: 32 Thread unblock pipe
2011/01/17 12:52:48.007 0:02.313 PTLib Thread high water mark set: 6
2011/01/17 12:52:48.007 0:02.313 PWLib File handle high water mark set: 34 Thread unblock pipe
2011/01/17 12:52:48.008 0:02.313 Network In...0xb6d54b70 IfaceMon Started interface monitor thread.
2011/01/17 12:52:48.008 0:02.314 PTLib Thread high water mark set: 7
2011/01/17 12:52:48.008 0:02.314 Network In...0xb6d54b70 PWLib File handle high water mark set: 35 PUDPSocket
2011/01/17 12:52:48.008 0:02.314 OpalMan Attached endpoint with prefix sips
2011/01/17 12:52:48.008 0:02.314 SIP Created endpoint.
2011/01/17 12:52:48.009 0:02.314 MonSock Created socket bundle for all interfaces.
2011/01/17 12:52:48.009 0:02.315 PWLib File handle high water mark set: 36 PUDPSocket
2011/01/17 12:52:48.009 0:02.315 MonSock Created bundled UDP socket 192.168.1.3:5060
2011/01/17 12:52:48.009 0:02.315 PWLib File handle high water mark set: 38 Thread unblock pipe
2011/01/17 12:52:48.009 0:02.315 PTLib Thread high water mark set: 8
2011/01/17 12:52:48.009 0:02.315 PTLib No permission to set priority level 4
2011/01/17 12:52:48.010 0:02.315 Opal Liste...0xb6bbeb70 Listen Started listening thread on udp$*:5060
2011/01/17 12:52:48.010 0:02.316 OpalMan Added route "sip:.*=pc:*"
2011/01/17 12:52:48.010 0:02.316 OpalMan Added route "pc:.*=sip:<da>"
2011/01/17 12:52:48.028 0:02.334 OpalMan Attached endpoint with prefix h323
2011/01/17 12:52:48.028 0:02.334 OpalEP Created endpoint: h323
2011/01/17 12:52:48.029 0:02.334 OpalMan Attached endpoint with prefix h323s
2011/01/17 12:52:48.029 0:02.334 H323 Created endpoint.
2011/01/17 12:52:48.029 0:02.335 PWLib File handle high water mark set: 39 PTCPSocket
2011/01/17 12:52:48.029 0:02.335 PWLib File handle high water mark set: 41 Thread unblock pipe
2011/01/17 12:52:48.029 0:02.335 PTLib Thread high water mark set: 9
2011/01/17 12:52:48.029 0:02.335 Opal Liste...0xb6b7db70 Listen Started listening thread on tcp$*:1720
2011/01/17 12:52:48.029 0:02.335 OpalMan Added route "h323:.*=pc:<db>"
2011/01/17 12:52:48.030 0:02.335 OpalMan Added route "pc:.*=h323:<da>"
2011/01/17 12:52:48.030 0:02.335 Opal Liste...0xb6b7db70 Listen Waiting on socket accept on tcp$*:1720
2011/01/17 12:52:48.078 0:02.383 MediaFormat Removing codecs G.722-64k,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-ALaw-64k,G.711-uLaw-64k,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,MS-GSM,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,CELT-48K,CELT-32K,H.261,H.261-CIF,H.261-QCIF,theora
2011/01/17 12:52:48.079 0:02.384 OPAL SetMediaFormatMask(PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,GSM-AMR,LPC-10,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,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/01/17 12:52:48.079 0:02.384 OPAL SetMediaFormatOrder(G.722-64k,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-ALaw-64k,G.711-uLaw-64k,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,MS-GSM,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,CELT-48K,CELT-32K,H.261,H.261-CIF,H.261-QCIF,theora)
2011/01/17 12:52:48.088 0:02.394 MediaFormat Removing codecs G.722-64k,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-ALaw-64k,G.711-uLaw-64k,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,MS-GSM,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,CELT-48K,CELT-32K,H.261,H.261-CIF,H.261-QCIF,theora
2011/01/17 12:52:48.089 0:02.395 OPAL SetMediaFormatMask(PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,GSM-AMR,LPC-10,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,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/01/17 12:52:48.089 0:02.395 OPAL SetMediaFormatOrder(G.722-64k,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-ALaw-64k,G.711-uLaw-64k,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,MS-GSM,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,CELT-48K,CELT-32K,H.261,H.261-CIF,H.261-QCIF,theora)
2011/01/17 12:52:48.130 0:02.435 PWLib File handle high water mark set: 43 Thread unblock pipe
2011/01/17 12:52:48.130 0:02.436 PTLib Thread high water mark set: 10
2011/01/17 12:52:48.131 0:02.437 StunDetector:0xb6b3cb70 PWLib File handle high water mark set: 45 PUDPSocket
2011/01/17 12:52:48.353 0:02.658 PWLib File handle high water mark set: 48 PUDPSocket
2011/01/17 12:52:48.353 0:02.659 SIP Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:500 ekiga net, id=c44ec743-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:48.353 0:02.659 DNS SRV Lookup ekiga.net service _sip._udp
2011/01/17 12:52:48.375 0:02.680 StunDetector:0xb6b3cb70 PWLib File handle low water mark set: 45 PUDPSocket
2011/01/17 12:52:48.382 0:02.688 SIP No SRV record found.
2011/01/17 12:52:48.945 0:03.251 OpalUDP Binding to interface: 0.0.0.0:5060
2011/01/17 12:52:48.945 0:03.251 SIP Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2011/01/17 12:52:48.946 0:03.252 OpalUDP Started connect to 86.64.162.35:5060
2011/01/17 12:52:48.947 0:03.252 OpalUDP Writing to interface 0 - "192.168.1.3%wlan0"
2011/01/17 12:52:48.947 0:03.253 OpalMan Listener interfaces: associated transport=None
udp$192.168.1.3:5060
2011/01/17 12:52:50.869 0:05.175 StunDetector:0xb6b3cb70 OPAL STUN server "stun.ekiga.net" replies Restricted NAT, external IP 173.68.138.36
2011/01/17 12:52:51.440 0:05.746 PWLib File handle low water mark set: 42 PUDPSocket
2011/01/17 12:52:51.456 0:05.762 SIP Transaction created.
2011/01/17 12:52:51.461 0:05.767 DNS SRV Lookup ekiga.net service _sip._udp
2011/01/17 12:52:51.486 0:05.792 SIP No SRV record found.
2011/01/17 12:52:51.487 0:05.792 SIP Transaction remote address is udp$ekiga.net:5060
2011/01/17 12:52:51.487 0:05.793 SIP Sending PDU (566 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.1.3:5060,if=192.168.1.3%wlan0
SUBSCRIBE sip:500 ekiga net SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 173.68.138.36:5060;branch=z9hG4bKee2fa145-d020-e011-9c53-0013029a3fdf;rport
User-Agent: Ekiga/3.2.7
From: "Nikolai Cassanova" <sip:king@173.68.138.36>;tag=10619e45-d020-e011-9c53-0013029a3fdf
Call-ID: c44ec743-d020-e011-9c53-0013029a3fdf@king-laptop
To: <sip:500 ekiga net>
Contact: <sip:king@173.68.138.36>
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/01/17 12:52:51.488 0:05.794 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:52:51.488 0:05.794 SIP Transaction timers set: retry=0.500, completion=6.000
2011/01/17 12:52:51.489 0:05.795 SIP Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:500 ekiga net, id=82cea545-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:51.489 0:05.795 DNS SRV Lookup ekiga.net service _sip._udp
2011/01/17 12:52:51.515 0:05.821 SIP No SRV record found.
2011/01/17 12:52:51.516 0:05.822 OpalUDP Binding to interface: 0.0.0.0:5060
2011/01/17 12:52:51.516 0:05.822 SIP Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2011/01/17 12:52:51.517 0:05.823 OpalUDP Started connect to 86.64.162.35:5060
2011/01/17 12:52:51.517 0:05.823 OpalUDP Writing to interface 0 - "192.168.1.3%wlan0"
2011/01/17 12:52:51.518 0:05.823 OpalMan Listener interfaces: associated transport=None
udp$192.168.1.3:5060
2011/01/17 12:52:51.520 0:05.825 SIP Transaction created.
2011/01/17 12:52:51.525 0:05.831 DNS SRV Lookup ekiga.net service _sip._udp
2011/01/17 12:52:51.550 0:05.856 SIP No SRV record found.
2011/01/17 12:52:51.550 0:05.856 SIP Transaction remote address is udp$ekiga.net:5060
2011/01/17 12:52:51.551 0:05.857 SIP Sending PDU (577 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.1.3:5060,if=192.168.1.3%wlan0
SUBSCRIBE sip:500 ekiga net SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 173.68.138.36:5060;branch=z9hG4bKc0e5aa45-d020-e011-9c53-0013029a3fdf;rport
User-Agent: Ekiga/3.2.7
From: "Nikolai Cassanova" <sip:king@173.68.138.36>;tag=5475aa45-d020-e011-9c53-0013029a3fdf
Call-ID: 82cea545-d020-e011-9c53-0013029a3fdf@king-laptop
To: <sip:500 ekiga net>
Contact: <sip:500@173.68.138.36>
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/01/17 12:52:51.552 0:05.857 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:52:51.552 0:05.858 SIP Transaction timers set: retry=0.500, completion=6.000
2011/01/17 12:52:51.553 0:05.858 SIP Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:501 ekiga net, id=aa7eaf45-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:51.553 0:05.859 DNS SRV Lookup ekiga.net service _sip._udp
2011/01/17 12:52:51.581 0:05.887 SIP No SRV record found.
2011/01/17 12:52:51.581 0:05.887 OpalUDP Binding to interface: 0.0.0.0:5060
2011/01/17 12:52:51.581 0:05.887 SIP Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2011/01/17 12:52:51.582 0:05.888 OpalUDP Started connect to 86.64.162.35:5060
2011/01/17 12:52:51.583 0:05.888 OpalUDP Writing to interface 0 - "192.168.1.3%wlan0"
2011/01/17 12:52:51.583 0:05.889 OpalMan Listener interfaces: associated transport=None
udp$192.168.1.3:5060
2011/01/17 12:52:51.585 0:05.891 SIP Transaction created.
2011/01/17 12:52:51.588 0:05.894 Opal Liste...0xb6bbeb70 OpalUDP Binding to interface: 192.168.1.3:5060
2011/01/17 12:52:51.589 0:05.894 Opal Liste...0xb6bbeb70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$192.168.1.3:5060>
2011/01/17 12:52:51.590 0:05.896 DNS SRV Lookup ekiga.net service _sip._udp
2011/01/17 12:52:51.590 0:05.896 Opal Liste...0xb6bbeb70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$192.168.1.3:5060,if=192.168.1.3%wlan0
SIP/2.0 489 Event Not Supported
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 173.68.138.36:5060;branch=z9hG4bKee2fa145-d020-e011-9c53-0013029a3fdf;rport=5060
Server: Kamailio (1.5.3-notls (i386/linux))
From: "Nikolai Cassanova" <sip:king@173.68.138.36>;tag=10619e45-d020-e011-9c53-0013029a3fdf
Call-ID: c44ec743-d020-e011-9c53-0013029a3fdf@king-laptop
To: <sip:500 ekiga net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.98bd
Content-Length: 0
2011/01/17 12:52:51.591 0:05.897 Opal Liste...0xb6bbeb70 SIP Queueing PDU "2 SUBSCRIBE <489>", transaction=z9hG4bKee2fa145-d020-e011-9c53-0013029a3fdf, token=c44ec743-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:51.591 0:05.897 Opal Liste...0xb6bbeb70 PTLib No permission to set priority level 3
2011/01/17 12:52:51.591 0:05.897 Opal Liste...0xb6bbeb70 Opal Transport clean up on termination
2011/01/17 12:52:51.591 0:05.897 Opal Liste...0xb6bbeb70 Opal Transport Close
2011/01/17 12:52:51.591 0:05.897 Pool:0xb6b3cb70 SIP Handling PDU "2 SUBSCRIBE <489>" for transaction=z9hG4bKee2fa145-d020-e011-9c53-0013029a3fdf
2011/01/17 12:52:51.592 0:05.898 Pool:0xb6b3cb70 SIP SUBSCRIBE transaction id=z9hG4bKee2fa145-d020-e011-9c53-0013029a3fdf completed.
2011/01/17 12:52:51.592 0:05.898 Pool:0xb6b3cb70 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:52:51.592 0:05.898 Pool:0xb6b3cb70 SIP Not retrying SUBSCRIBE due to error response 489 Bad Event
2011/01/17 12:52:51.592 0:05.898 Pool:0xb6b3cb70 SIP Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:500 ekiga net, id=c44ec743-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:51.592 0:05.898 Pool:0xb6b3cb70 SIP Handled PDU "2 SUBSCRIBE <489>"
2011/01/17 12:52:51.592 0:05.897 Opal Liste...0xb6bbeb70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$192.168.1.3:5060>
2011/01/17 12:52:51.615 0:05.921 SIP No SRV record found.
2011/01/17 12:52:51.615 0:05.921 SIP Transaction remote address is udp$ekiga.net:5060
2011/01/17 12:52:51.616 0:05.922 SIP Sending PDU (566 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.1.3:5060,if=192.168.1.3%wlan0
SUBSCRIBE sip:501 ekiga net SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 173.68.138.36:5060;branch=z9hG4bK1ed8b445-d020-e011-9c53-0013029a3fdf;rport
User-Agent: Ekiga/3.2.7
From: "Nikolai Cassanova" <sip:king@173.68.138.36>;tag=3071b445-d020-e011-9c53-0013029a3fdf
Call-ID: aa7eaf45-d020-e011-9c53-0013029a3fdf@king-laptop
To: <sip:501 ekiga net>
Contact: <sip:king@173.68.138.36>
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/01/17 12:52:51.617 0:05.923 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:52:51.617 0:05.923 SIP Transaction timers set: retry=0.500, completion=6.000
2011/01/17 12:52:51.618 0:05.924 SIP Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:501 ekiga net, id=de77b945-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:51.618 0:05.924 DNS SRV Lookup ekiga.net service _sip._udp
2011/01/17 12:52:51.643 0:05.949 SIP No SRV record found.
2011/01/17 12:52:51.644 0:05.949 OpalUDP Binding to interface: 0.0.0.0:5060
2011/01/17 12:52:51.644 0:05.950 SIP Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2011/01/17 12:52:51.645 0:05.950 OpalUDP Started connect to 86.64.162.35:5060
2011/01/17 12:52:51.645 0:05.951 OpalUDP Writing to interface 0 - "192.168.1.3%wlan0"
2011/01/17 12:52:51.645 0:05.951 OpalMan Listener interfaces: associated transport=None
udp$192.168.1.3:5060
2011/01/17 12:52:51.647 0:05.953 SIP Transaction created.
2011/01/17 12:52:51.652 0:05.957 DNS SRV Lookup ekiga.net service _sip._udp
2011/01/17 12:52:51.652 0:05.958 Opal Liste...0xb6bbeb70 OpalUDP Binding to interface: 192.168.1.3:5060
2011/01/17 12:52:51.652 0:05.958 Opal Liste...0xb6bbeb70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$192.168.1.3:5060>
2011/01/17 12:52:51.653 0:05.959 Opal Liste...0xb6bbeb70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$192.168.1.3:5060,if=192.168.1.3%wlan0
SIP/2.0 489 Event Not Supported
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 173.68.138.36:5060;branch=z9hG4bKc0e5aa45-d020-e011-9c53-0013029a3fdf;rport=5060
Server: Kamailio (1.5.3-notls (i386/linux))
From: "Nikolai Cassanova" <sip:king@173.68.138.36>;tag=5475aa45-d020-e011-9c53-0013029a3fdf
Call-ID: 82cea545-d020-e011-9c53-0013029a3fdf@king-laptop
To: <sip:500 ekiga net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.80a4
Content-Length: 0
2011/01/17 12:52:51.654 0:05.960 Opal Liste...0xb6bbeb70 SIP Queueing PDU "2 SUBSCRIBE <489>", transaction=z9hG4bKc0e5aa45-d020-e011-9c53-0013029a3fdf, token=82cea545-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:51.654 0:05.960 Opal Liste...0xb6bbeb70 Opal Transport clean up on termination
2011/01/17 12:52:51.654 0:05.960 Opal Liste...0xb6bbeb70 Opal Transport Close
2011/01/17 12:52:51.655 0:05.960 Pool:0xb6b3cb70 SIP Handling PDU "2 SUBSCRIBE <489>" for transaction=z9hG4bKc0e5aa45-d020-e011-9c53-0013029a3fdf
2011/01/17 12:52:51.655 0:05.961 Pool:0xb6b3cb70 SIP SUBSCRIBE transaction id=z9hG4bKc0e5aa45-d020-e011-9c53-0013029a3fdf completed.
2011/01/17 12:52:51.655 0:05.961 Pool:0xb6b3cb70 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:52:51.655 0:05.961 Pool:0xb6b3cb70 SIP Not retrying SUBSCRIBE due to error response 489 Bad Event
2011/01/17 12:52:51.655 0:05.961 Pool:0xb6b3cb70 SIP Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:500 ekiga net, id=82cea545-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:51.655 0:05.961 Pool:0xb6b3cb70 SIP Handled PDU "2 SUBSCRIBE <489>"
2011/01/17 12:52:51.655 0:05.961 Opal Liste...0xb6bbeb70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$192.168.1.3:5060>
2011/01/17 12:52:51.700 0:06.006 SIP No SRV record found.
2011/01/17 12:52:51.701 0:06.006 SIP Transaction remote address is udp$ekiga.net:5060
2011/01/17 12:52:51.701 0:06.007 SIP Sending PDU (577 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.1.3:5060,if=192.168.1.3%wlan0
SUBSCRIBE sip:501 ekiga net SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 173.68.138.36:5060;branch=z9hG4bKe246be45-d020-e011-9c53-0013029a3fdf;rport
User-Agent: Ekiga/3.2.7
From: "Nikolai Cassanova" <sip:king@173.68.138.36>;tag=0ef3bd45-d020-e011-9c53-0013029a3fdf
Call-ID: de77b945-d020-e011-9c53-0013029a3fdf@king-laptop
To: <sip:501 ekiga net>
Contact: <sip:501@173.68.138.36>
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/01/17 12:52:51.702 0:06.007 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:52:51.702 0:06.008 SIP Transaction timers set: retry=0.500, completion=6.000
2011/01/17 12:52:51.702 0:06.008 SIP Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:slim ekiga net, id=7858c645-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:51.702 0:06.008 DNS SRV Lookup ekiga.net service _sip._udp
2011/01/17 12:52:51.716 0:06.022 Opal Liste...0xb6bbeb70 OpalUDP Binding to interface: 192.168.1.3:5060
2011/01/17 12:52:51.717 0:06.022 Opal Liste...0xb6bbeb70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$192.168.1.3:5060>
2011/01/17 12:52:51.718 0:06.023 Opal Liste...0xb6bbeb70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$192.168.1.3:5060,if=192.168.1.3%wlan0
SIP/2.0 489 Event Not Supported
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 173.68.138.36:5060;branch=z9hG4bK1ed8b445-d020-e011-9c53-0013029a3fdf;rport=5060
Server: Kamailio (1.5.3-notls (i386/linux))
From: "Nikolai Cassanova" <sip:king@173.68.138.36>;tag=3071b445-d020-e011-9c53-0013029a3fdf
Call-ID: aa7eaf45-d020-e011-9c53-0013029a3fdf@king-laptop
To: <sip:501 ekiga net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.d75d
Content-Length: 0
2011/01/17 12:52:51.719 0:06.024 Opal Liste...0xb6bbeb70 SIP Queueing PDU "2 SUBSCRIBE <489>", transaction=z9hG4bK1ed8b445-d020-e011-9c53-0013029a3fdf, token=aa7eaf45-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:51.719 0:06.025 Pool:0xb6b3cb70 SIP Handling PDU "2 SUBSCRIBE <489>" for transaction=z9hG4bK1ed8b445-d020-e011-9c53-0013029a3fdf
2011/01/17 12:52:51.719 0:06.025 Pool:0xb6b3cb70 SIP SUBSCRIBE transaction id=z9hG4bK1ed8b445-d020-e011-9c53-0013029a3fdf completed.
2011/01/17 12:52:51.719 0:06.025 Pool:0xb6b3cb70 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:52:51.719 0:06.025 Pool:0xb6b3cb70 SIP Not retrying SUBSCRIBE due to error response 489 Bad Event
2011/01/17 12:52:51.719 0:06.025 Pool:0xb6b3cb70 SIP Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:501 ekiga net, id=aa7eaf45-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:51.720 0:06.025 Pool:0xb6b3cb70 SIP Handled PDU "2 SUBSCRIBE <489>"
2011/01/17 12:52:51.720 0:06.025 Opal Liste...0xb6bbeb70 Opal Transport clean up on termination
2011/01/17 12:52:51.720 0:06.026 Opal Liste...0xb6bbeb70 Opal Transport Close
2011/01/17 12:52:51.720 0:06.026 Opal Liste...0xb6bbeb70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$192.168.1.3:5060>
2011/01/17 12:52:51.729 0:06.035 SIP No SRV record found.
2011/01/17 12:52:51.729 0:06.035 OpalUDP Binding to interface: 0.0.0.0:5060
2011/01/17 12:52:51.730 0:06.035 SIP Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2011/01/17 12:52:51.730 0:06.036 OpalUDP Started connect to 86.64.162.35:5060
2011/01/17 12:52:51.731 0:06.036 OpalUDP Writing to interface 0 - "192.168.1.3%wlan0"
2011/01/17 12:52:51.731 0:06.037 OpalMan Listener interfaces: associated transport=None
udp$192.168.1.3:5060
2011/01/17 12:52:51.733 0:06.039 SIP Transaction created.
2011/01/17 12:52:51.737 0:06.043 DNS SRV Lookup ekiga.net service _sip._udp
2011/01/17 12:52:51.763 0:06.069 SIP No SRV record found.
2011/01/17 12:52:51.763 0:06.069 SIP Transaction remote address is udp$ekiga.net:5060
2011/01/17 12:52:51.764 0:06.070 SIP Sending PDU (568 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.1.3:5060,if=192.168.1.3%wlan0
SUBSCRIBE sip:slim ekiga net SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 173.68.138.36:5060;branch=z9hG4bK4261cb45-d020-e011-9c53-0013029a3fdf;rport
User-Agent: Ekiga/3.2.7
From: "Nikolai Cassanova" <sip:king@173.68.138.36>;tag=aa0dcb45-d020-e011-9c53-0013029a3fdf
Call-ID: 7858c645-d020-e011-9c53-0013029a3fdf@king-laptop
To: <sip:slim ekiga net>
Contact: <sip:king@173.68.138.36>
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/01/17 12:52:51.765 0:06.070 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:52:51.765 0:06.071 SIP Transaction timers set: retry=0.500, completion=6.000
2011/01/17 12:52:51.766 0:06.071 SIP Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:slim ekiga net, id=72facf45-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:51.766 0:06.071 DNS SRV Lookup ekiga.net service _sip._udp
2011/01/17 12:52:51.792 0:06.098 SIP No SRV record found.
2011/01/17 12:52:51.792 0:06.098 OpalUDP Binding to interface: 0.0.0.0:5060
2011/01/17 12:52:51.793 0:06.098 SIP Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2011/01/17 12:52:51.793 0:06.099 OpalUDP Started connect to 86.64.162.35:5060
2011/01/17 12:52:51.794 0:06.099 OpalUDP Writing to interface 0 - "192.168.1.3%wlan0"
2011/01/17 12:52:51.794 0:06.100 OpalMan Listener interfaces: associated transport=None
udp$192.168.1.3:5060
2011/01/17 12:52:51.796 0:06.102 SIP Transaction created.
2011/01/17 12:52:51.800 0:06.106 DNS SRV Lookup ekiga.net service _sip._udp
2011/01/17 12:52:51.802 0:06.108 Opal Liste...0xb6bbeb70 OpalUDP Binding to interface: 192.168.1.3:5060
2011/01/17 12:52:51.803 0:06.109 Opal Liste...0xb6bbeb70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$192.168.1.3:5060>
2011/01/17 12:52:51.804 0:06.109 Opal Liste...0xb6bbeb70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$192.168.1.3:5060,if=192.168.1.3%wlan0
SIP/2.0 489 Event Not Supported
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 173.68.138.36:5060;branch=z9hG4bKe246be45-d020-e011-9c53-0013029a3fdf;rport=5060
Server: Kamailio (1.5.3-notls (i386/linux))
From: "Nikolai Cassanova" <sip:king@173.68.138.36>;tag=0ef3bd45-d020-e011-9c53-0013029a3fdf
Call-ID: de77b945-d020-e011-9c53-0013029a3fdf@king-laptop
To: <sip:501 ekiga net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.7464
Content-Length: 0
2011/01/17 12:52:51.805 0:06.110 Opal Liste...0xb6bbeb70 SIP Queueing PDU "2 SUBSCRIBE <489>", transaction=z9hG4bKe246be45-d020-e011-9c53-0013029a3fdf, token=de77b945-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:51.805 0:06.110 Opal Liste...0xb6bbeb70 Opal Transport clean up on termination
2011/01/17 12:52:51.805 0:06.111 Opal Liste...0xb6bbeb70 Opal Transport Close
2011/01/17 12:52:51.805 0:06.111 Pool:0xb6b3cb70 SIP Handling PDU "2 SUBSCRIBE <489>" for transaction=z9hG4bKe246be45-d020-e011-9c53-0013029a3fdf
2011/01/17 12:52:51.805 0:06.111 Pool:0xb6b3cb70 SIP SUBSCRIBE transaction id=z9hG4bKe246be45-d020-e011-9c53-0013029a3fdf completed.
2011/01/17 12:52:51.805 0:06.111 Pool:0xb6b3cb70 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:52:51.805 0:06.111 Pool:0xb6b3cb70 SIP Not retrying SUBSCRIBE due to error response 489 Bad Event
2011/01/17 12:52:51.805 0:06.111 Opal Liste...0xb6bbeb70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$192.168.1.3:5060>
2011/01/17 12:52:51.805 0:06.111 Pool:0xb6b3cb70 SIP Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:501 ekiga net, id=de77b945-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:51.806 0:06.111 Pool:0xb6b3cb70 SIP Handled PDU "2 SUBSCRIBE <489>"
2011/01/17 12:52:51.867 0:06.173 Opal Liste...0xb6bbeb70 OpalUDP Binding to interface: 192.168.1.3:5060
2011/01/17 12:52:51.867 0:06.173 Opal Liste...0xb6bbeb70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$192.168.1.3:5060>
2011/01/17 12:52:51.868 0:06.174 Opal Liste...0xb6bbeb70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$192.168.1.3:5060,if=192.168.1.3%wlan0
SIP/2.0 202 OK
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 173.68.138.36:5060;branch=z9hG4bK4261cb45-d020-e011-9c53-0013029a3fdf;rport=5060
Server: Kamailio (1.5.3-notls (i386/linux))
From: "Nikolai Cassanova" <sip:king@173.68.138.36>;tag=aa0dcb45-d020-e011-9c53-0013029a3fdf
Call-ID: 7858c645-d020-e011-9c53-0013029a3fdf@king-laptop
To: <sip:slim ekiga net>;tag=f85b0bd16aaafa8479586ac9f88b3198-6596
Contact: <sip:86.64.162.35:5060>
Expires: 300
Content-Length: 0
2011/01/17 12:52:51.869 0:06.175 Opal Liste...0xb6bbeb70 SIP Queueing PDU "2 SUBSCRIBE <202>", transaction=z9hG4bK4261cb45-d020-e011-9c53-0013029a3fdf, token=7858c645-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:51.869 0:06.175 Pool:0xb6b3cb70 SIP Handling PDU "2 SUBSCRIBE <202>" for transaction=z9hG4bK4261cb45-d020-e011-9c53-0013029a3fdf
2011/01/17 12:52:51.869 0:06.175 Pool:0xb6b3cb70 SIP SUBSCRIBE transaction id=z9hG4bK4261cb45-d020-e011-9c53-0013029a3fdf completed.
2011/01/17 12:52:51.870 0:06.175 Pool:0xb6b3cb70 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:52:51.870 0:06.176 Pool:0xb6b3cb70 SIP Expiry time for SUBSCRIBE set to 300 seconds.
2011/01/17 12:52:51.870 0:06.176 Pool:0xb6b3cb70 SIP Product Info: name="Kamailio", version="", vendor="", comments="(1.5.3-notls (i386/linux))"
2011/01/17 12:52:51.870 0:06.176 Pool:0xb6b3cb70 SIP Changing SUBSCRIBE handler from Subscribing to Subscribed, target=sip:slim ekiga net, id=7858c645-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:51.870 0:06.176 Opal Liste...0xb6bbeb70 Opal Transport clean up on termination
2011/01/17 12:52:51.870 0:06.176 Opal Liste...0xb6bbeb70 Opal Transport Close
2011/01/17 12:52:51.871 0:06.176 Pool:0xb6b3cb70 SIP Set Request URI to sip:86.64.162.35:5060
2011/01/17 12:52:51.871 0:06.177 Pool:0xb6b3cb70 SIP Updating dialog tag from "" to "f85b0bd16aaafa8479586ac9f88b3198-6596"
2011/01/17 12:52:51.870 0:06.176 Opal Liste...0xb6bbeb70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$192.168.1.3:5060>
2011/01/17 12:52:51.871 0:06.177 Pool:0xb6b3cb70 SIP Product Info: name="Kamailio", version="", vendor="", comments="(1.5.3-notls (i386/linux))"
2011/01/17 12:52:51.871 0:06.177 Pool:0xb6b3cb70 SIP Handled PDU "2 SUBSCRIBE <202>"
2011/01/17 12:52:51.872 0:06.178 Opal Liste...0xb6bbeb70 OpalUDP Binding to interface: 192.168.1.3:5060
2011/01/17 12:52:51.872 0:06.178 Opal Liste...0xb6bbeb70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$192.168.1.3:5060>
2011/01/17 12:52:51.873 0:06.179 Opal Liste...0xb6bbeb70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$192.168.1.3:5060,if=192.168.1.3%wlan0
NOTIFY sip:king@173.68.138.36 SIP/2.0
CSeq: 1 NOTIFY
Via: SIP/2.0/UDP 86.64.162.35;branch=z9hG4bKb077.f0ad3512.0
User-Agent: Kamailio (1.5.3-notls (i386/linux))
From: sip:slim ekiga net;tag=f85b0bd16aaafa8479586ac9f88b3198-6596
Call-ID: 7858c645-d020-e011-9c53-0013029a3fdf@king-laptop
To: sip:king@173.68.138.36;tag=aa0dcb45-d020-e011-9c53-0013029a3fdf
Contact: <sip:86.64.162.35:5060>
Subscription-State: active;expires=370
Event: presence
Content-Length: 0
Max-Forwards: 70
2011/01/17 12:52:51.874 0:06.180 Opal Liste...0xb6bbeb70 SIP Received NOTIFY presence
2011/01/17 12:52:51.875 0:06.180 Opal Liste...0xb6bbeb70 SIP Found a SUBSCRIBE corresponding to the NOTIFY presence
2011/01/17 12:52:51.875 0:06.180 Opal Liste...0xb6bbeb70 SIP Dialog initial sequence number 1
2011/01/17 12:52:51.875 0:06.181 Opal Liste...0xb6bbeb70 SIP Subscription is active;expires=370
2011/01/17 12:52:51.893 0:06.199 Opal Liste...0xb6bbeb70 SIP Sending PDU (345 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.1.3:5060,if=192.168.1.3%wlan0
SIP/2.0 200 OK
CSeq: 1 NOTIFY
Via: SIP/2.0/UDP 86.64.162.35;branch=z9hG4bKb077.f0ad3512.0
From: sip:slim ekiga net;tag=f85b0bd16aaafa8479586ac9f88b3198-6596
Call-ID: 7858c645-d020-e011-9c53-0013029a3fdf@king-laptop
To: sip:king@173.68.138.36;tag=aa0dcb45-d020-e011-9c53-0013029a3fdf
Contact: <sip:king@173.68.138.36>
Content-Length: 0
2011/01/17 12:52:51.894 0:06.200 Opal Liste...0xb6bbeb70 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:52:51.894 0:06.200 Opal Liste...0xb6bbeb70 Opal Transport clean up on termination
2011/01/17 12:52:51.895 0:06.200 Opal Liste...0xb6bbeb70 Opal Transport Close
2011/01/17 12:52:51.895 0:06.200 Opal Liste...0xb6bbeb70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$192.168.1.3:5060>
2011/01/17 12:52:52.305 0:06.611 SIP No SRV record found.
2011/01/17 12:52:52.306 0:06.611 SIP Transaction remote address is udp$ekiga.net:5060
2011/01/17 12:52:52.306 0:06.612 SIP Sending PDU (580 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.1.3:5060,if=192.168.1.3%wlan0
SUBSCRIBE sip:slim ekiga net SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 173.68.138.36:5060;branch=z9hG4bK98f7d445-d020-e011-9c53-0013029a3fdf;rport
User-Agent: Ekiga/3.2.7
From: "Nikolai Cassanova" <sip:king@173.68.138.36>;tag=6aa3d445-d020-e011-9c53-0013029a3fdf
Call-ID: 72facf45-d020-e011-9c53-0013029a3fdf@king-laptop
To: <sip:slim ekiga net>
Contact: <sip:slim@173.68.138.36>
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/01/17 12:52:52.307 0:06.613 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:52:52.307 0:06.613 SIP Transaction timers set: retry=0.500, completion=6.000
2011/01/17 12:52:52.308 0:06.614 Opal Garbage:0xb6d95b70 SIP Deleting SIPSubscribeHandler sip:500 ekiga net
2011/01/17 12:52:52.308 0:06.614 Opal Garbage:0xb6d95b70 Opal Transport clean up on termination
2011/01/17 12:52:52.308 0:06.614 Opal Garbage:0xb6d95b70 Opal Transport Close
2011/01/17 12:52:52.308 0:06.614 Opal Garbage:0xb6d95b70 Opal Transport clean up on termination
2011/01/17 12:52:52.308 0:06.614 Opal Garbage:0xb6d95b70 Opal Transport Close
2011/01/17 12:52:52.308 0:06.614 Opal Garbage:0xb6d95b70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$192.168.1.3:5060>
2011/01/17 12:52:52.309 0:06.615 Opal Garbage:0xb6d95b70 SIP Deleted handler.
2011/01/17 12:52:52.309 0:06.615 Opal Garbage:0xb6d95b70 SIP Deleting SIPSubscribeHandler sip:500 ekiga net
2011/01/17 12:52:52.309 0:06.615 Opal Garbage:0xb6d95b70 Opal Transport clean up on termination
2011/01/17 12:52:52.309 0:06.615 Opal Garbage:0xb6d95b70 Opal Transport Close
2011/01/17 12:52:52.310 0:06.615 Opal Garbage:0xb6d95b70 Opal Transport clean up on termination
2011/01/17 12:52:52.310 0:06.615 Opal Garbage:0xb6d95b70 Opal Transport Close
2011/01/17 12:52:52.310 0:06.615 Opal Garbage:0xb6d95b70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$192.168.1.3:5060>
2011/01/17 12:52:52.310 0:06.616 Opal Garbage:0xb6d95b70 SIP Deleted handler.
2011/01/17 12:52:52.311 0:06.616 Opal Garbage:0xb6d95b70 SIP Deleting SIPSubscribeHandler sip:501 ekiga net
2011/01/17 12:52:52.311 0:06.617 Opal Garbage:0xb6d95b70 Opal Transport clean up on termination
2011/01/17 12:52:52.311 0:06.617 Opal Garbage:0xb6d95b70 Opal Transport Close
2011/01/17 12:52:52.311 0:06.617 Opal Garbage:0xb6d95b70 Opal Transport clean up on termination
2011/01/17 12:52:52.311 0:06.617 Opal Garbage:0xb6d95b70 Opal Transport Close
2011/01/17 12:52:52.311 0:06.617 Opal Garbage:0xb6d95b70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$192.168.1.3:5060>
2011/01/17 12:52:52.312 0:06.618 Opal Garbage:0xb6d95b70 SIP Deleted handler.
2011/01/17 12:52:52.312 0:06.618 Opal Garbage:0xb6d95b70 SIP Deleting SIPSubscribeHandler sip:501 ekiga net
2011/01/17 12:52:52.312 0:06.618 Opal Garbage:0xb6d95b70 Opal Transport clean up on termination
2011/01/17 12:52:52.312 0:06.618 Opal Garbage:0xb6d95b70 Opal Transport Close
2011/01/17 12:52:52.312 0:06.618 Opal Garbage:0xb6d95b70 Opal Transport clean up on termination
2011/01/17 12:52:52.313 0:06.618 Opal Garbage:0xb6d95b70 Opal Transport Close
2011/01/17 12:52:52.313 0:06.619 Opal Garbage:0xb6d95b70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$192.168.1.3:5060>
2011/01/17 12:52:52.313 0:06.619 Opal Garbage:0xb6d95b70 SIP Deleted handler.
2011/01/17 12:52:52.378 0:06.683 VideoOutputCoreConfBridge Updating video view
2011/01/17 12:52:52.378 0:06.684 VideoOutputCoreConfBridge Updating zoom
2011/01/17 12:52:52.379 0:06.684 VideoOutputCoreConfBridge Updating Video Settings
2011/01/17 12:52:52.380 0:06.685 VideoOutputCoreConfBridge Updating Video Settings
2011/01/17 12:52:52.380 0:06.685 VideoOutputCoreConfBridge Updating Video Settings
2011/01/17 12:52:52.380 0:06.686 VideoOutputCoreConfBridge Updating Video Settings
2011/01/17 12:52:52.380 0:06.686 VidInputCoreConfBridge Updating preview size and fps
2011/01/17 12:52:52.380 0:06.686 VidInputCore Setting new preview config: 176x144/30
2011/01/17 12:52:52.385 0:06.691 VidInputCoreConfBridge Updating preview size and fps
2011/01/17 12:52:52.385 0:06.691 VidInputCore Setting new preview config: 176x144/30
2011/01/17 12:52:52.390 0:06.696 VidInputCoreConfBridge Updating device
2011/01/17 12:52:52.391 0:06.697 VidInputCore Setting device: USB Camera (0733:0401) (PTLIB/V4L2)
2011/01/17 12:52:52.391 0:06.697 GMVideoInputManager_ptlib Setting Device USB Camera (0733:0401) (PTLIB/V4L2)
2011/01/17 12:52:52.391 0:06.697 VidInputCoreConfBridge Updating device
2011/01/17 12:52:52.392 0:06.697 VidInputCore Setting device: USB Camera (0733:0401) (PTLIB/V4L2)
2011/01/17 12:52:52.392 0:06.697 GMVideoInputManager_ptlib Setting Device USB Camera (0733:0401) (PTLIB/V4L2)
2011/01/17 12:52:52.392 0:06.698 VidInputCoreConfBridge Updating device
2011/01/17 12:52:52.392 0:06.698 VidInputCore Setting device: USB Camera (0733:0401) (PTLIB/V4L2)
2011/01/17 12:52:52.392 0:06.698 GMVideoInputManager_ptlib Setting Device USB Camera (0733:0401) (PTLIB/V4L2)
2011/01/17 12:52:52.392 0:06.698 VidInputCoreConfBridge Updating image
2011/01/17 12:52:52.393 0:06.698 VidInputCoreConfBridge Updating preview
2011/01/17 12:52:52.393 0:06.699 VidInputCore Stopping Preview
2011/01/17 12:52:52.403 0:06.709 AudioOutputCoreConfBridge Updating device
2011/01/17 12:52:52.403 0:06.709 AudioOutputCore Setting device[0]: Default (PTLIB/ALSA)
2011/01/17 12:52:52.403 0:06.709 GMAudioOutputManager_ptlib Setting Device[0] Default (PTLIB/ALSA)
2011/01/17 12:52:52.409 0:06.715 Opal Liste...0xb6bbeb70 OpalUDP Binding to interface: 192.168.1.3:5060
2011/01/17 12:52:52.409 0:06.715 Opal Liste...0xb6bbeb70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$192.168.1.3:5060>
2011/01/17 12:52:52.410 0:06.716 Opal Liste...0xb6bbeb70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$192.168.1.3:5060,if=192.168.1.3%wlan0
SIP/2.0 202 OK
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 173.68.138.36:5060;branch=z9hG4bK98f7d445-d020-e011-9c53-0013029a3fdf;rport=5060
Server: Kamailio (1.5.3-notls (i386/linux))
From: "Nikolai Cassanova" <sip:king@173.68.138.36>;tag=6aa3d445-d020-e011-9c53-0013029a3fdf
Call-ID: 72facf45-d020-e011-9c53-0013029a3fdf@king-laptop
To: <sip:slim ekiga net>;tag=f85b0bd16aaafa8479586ac9f88b3198-3480
Contact: <sip:86.64.162.35:5060>
Expires: 300
Content-Length: 0
2011/01/17 12:52:52.411 0:06.717 Opal Liste...0xb6bbeb70 SIP Queueing PDU "2 SUBSCRIBE <202>", transaction=z9hG4bK98f7d445-d020-e011-9c53-0013029a3fdf, token=72facf45-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:52.411 0:06.717 Opal Liste...0xb6bbeb70 Opal Transport clean up on termination
2011/01/17 12:52:52.411 0:06.717 Opal Liste...0xb6bbeb70 Opal Transport Close
2011/01/17 12:52:52.411 0:06.717 Pool:0xb6b3cb70 SIP Handling PDU "2 SUBSCRIBE <202>" for transaction=z9hG4bK98f7d445-d020-e011-9c53-0013029a3fdf
2011/01/17 12:52:52.411 0:06.717 Pool:0xb6b3cb70 SIP SUBSCRIBE transaction id=z9hG4bK98f7d445-d020-e011-9c53-0013029a3fdf completed.
2011/01/17 12:52:52.412 0:06.717 Pool:0xb6b3cb70 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:52:52.412 0:06.718 Pool:0xb6b3cb70 SIP Expiry time for SUBSCRIBE set to 300 seconds.
2011/01/17 12:52:52.411 0:06.717 Opal Liste...0xb6bbeb70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$192.168.1.3:5060>
2011/01/17 12:52:52.412 0:06.718 Pool:0xb6b3cb70 SIP Product Info: name="Kamailio", version="", vendor="", comments="(1.5.3-notls (i386/linux))"
2011/01/17 12:52:52.412 0:06.718 Pool:0xb6b3cb70 SIP Changing SUBSCRIBE handler from Subscribing to Subscribed, target=sip:slim ekiga net, id=72facf45-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:52.412 0:06.718 Pool:0xb6b3cb70 SIP Set Request URI to sip:86.64.162.35:5060
2011/01/17 12:52:52.412 0:06.718 Pool:0xb6b3cb70 SIP Updating dialog tag from "" to "f85b0bd16aaafa8479586ac9f88b3198-3480"
2011/01/17 12:52:52.413 0:06.719 Pool:0xb6b3cb70 SIP Product Info: name="Kamailio", version="", vendor="", comments="(1.5.3-notls (i386/linux))"
2011/01/17 12:52:52.413 0:06.719 Pool:0xb6b3cb70 SIP Handled PDU "2 SUBSCRIBE <202>"
2011/01/17 12:52:52.414 0:06.720 Opal Liste...0xb6bbeb70 OpalUDP Binding to interface: 192.168.1.3:5060
2011/01/17 12:52:52.414 0:06.720 Opal Liste...0xb6bbeb70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$192.168.1.3:5060>
2011/01/17 12:52:52.415 0:06.721 Opal Liste...0xb6bbeb70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$192.168.1.3:5060,if=192.168.1.3%wlan0
NOTIFY sip:slim@173.68.138.36 SIP/2.0
CSeq: 1 NOTIFY
Via: SIP/2.0/UDP 86.64.162.35;branch=z9hG4bKf616.e815c277.0
User-Agent: Kamailio (1.5.3-notls (i386/linux))
From: sip:slim ekiga net;tag=f85b0bd16aaafa8479586ac9f88b3198-3480
Call-ID: 72facf45-d020-e011-9c53-0013029a3fdf@king-laptop
To: sip:king@173.68.138.36;tag=6aa3d445-d020-e011-9c53-0013029a3fdf
Contact: <sip:86.64.162.35:5060>
Subscription-State: active;expires=370
Event: dialog;sla
Content-Length: 0
Max-Forwards: 70
2011/01/17 12:52:52.416 0:06.722 Opal Liste...0xb6bbeb70 SIP Received NOTIFY dialog;sla
2011/01/17 12:52:52.416 0:06.722 Opal Liste...0xb6bbeb70 SIP Found a SUBSCRIBE corresponding to the NOTIFY dialog;sla
2011/01/17 12:52:52.417 0:06.722 Opal Liste...0xb6bbeb70 SIP Dialog initial sequence number 1
2011/01/17 12:52:52.417 0:06.722 Opal Liste...0xb6bbeb70 SIP Subscription is active;expires=370
2011/01/17 12:52:52.419 0:06.725 AudioOutputCoreConfBridge Updating device
2011/01/17 12:52:52.419 0:06.725 AudioOutputCore Setting device[1]: Default (PTLIB/ALSA)
2011/01/17 12:52:52.419 0:06.725 Opal Liste...0xb6bbeb70 SIP Sending PDU (345 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.1.3:5060,if=192.168.1.3%wlan0
SIP/2.0 200 OK
CSeq: 1 NOTIFY
Via: SIP/2.0/UDP 86.64.162.35;branch=z9hG4bKf616.e815c277.0
From: sip:slim ekiga net;tag=f85b0bd16aaafa8479586ac9f88b3198-3480
Call-ID: 72facf45-d020-e011-9c53-0013029a3fdf@king-laptop
To: sip:king@173.68.138.36;tag=6aa3d445-d020-e011-9c53-0013029a3fdf
Contact: <sip:king@173.68.138.36>
Content-Length: 0
2011/01/17 12:52:52.420 0:06.726 Opal Liste...0xb6bbeb70 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:52:52.420 0:06.726 Opal Liste...0xb6bbeb70 Opal Transport clean up on termination
2011/01/17 12:52:52.421 0:06.726 Opal Liste...0xb6bbeb70 Opal Transport Close
2011/01/17 12:52:52.421 0:06.726 Opal Liste...0xb6bbeb70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$192.168.1.3:5060>
2011/01/17 12:52:52.424 0:06.730 AudioInputCoreConfBridge Updating device
2011/01/17 12:52:52.424 0:06.730 AudioInputCore Setting device: Default (PTLIB/ALSA)
2011/01/17 12:52:52.424 0:06.730 GMAudioInputManager_ptlib Setting Device Default (PTLIB/ALSA)
2011/01/17 12:52:52.592 0:06.898 Housekeeper:0xb6bffb70 SIP Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bKee2fa145-d020-e011-9c53-0013029a3fdf
2011/01/17 12:52:52.656 0:06.961 Housekeeper:0xb6bffb70 SIP Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bKc0e5aa45-d020-e011-9c53-0013029a3fdf
2011/01/17 12:52:52.720 0:07.026 Housekeeper:0xb6bffb70 SIP Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK1ed8b445-d020-e011-9c53-0013029a3fdf
2011/01/17 12:52:52.805 0:07.111 Housekeeper:0xb6bffb70 SIP Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bKe246be45-d020-e011-9c53-0013029a3fdf
2011/01/17 12:52:52.828 0:07.134 Ekiga version 3.2.7
2011/01/17 12:52:52.828 0:07.134 OPAL version 3.6.8
2011/01/17 12:52:52.828 0:07.134 PTLIB version 2.6.7
2011/01/17 12:52:52.829 0:07.134 GNOME support disabled
2011/01/17 12:52:52.829 0:07.134 Accelerated rendering support enabled
2011/01/17 12:52:52.829 0:07.135 DBUS support enabled
2011/01/17 12:52:52.829 0:07.135 GConf support enabled
2011/01/17 12:52:52.829 0:07.135 ESound support disabled
2011/01/17 12:52:52.872 0:07.177 Housekeeper:0xb6bffb70 SIP Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK4261cb45-d020-e011-9c53-0013029a3fdf
2011/01/17 12:52:53.314 0:07.620 Opal Garbage:0xb6d95b70 SIP Transaction id=z9hG4bK4261cb45-d020-e011-9c53-0013029a3fdf destroyed.
2011/01/17 12:52:53.314 0:07.620 Opal Garbage:0xb6d95b70 SIP Transaction id=z9hG4bK1ed8b445-d020-e011-9c53-0013029a3fdf destroyed.
2011/01/17 12:52:53.314 0:07.620 Opal Garbage:0xb6d95b70 SIP Transaction id=z9hG4bKee2fa145-d020-e011-9c53-0013029a3fdf destroyed.
2011/01/17 12:52:53.315 0:07.620 Opal Garbage:0xb6d95b70 SIP Transaction id=z9hG4bKe246be45-d020-e011-9c53-0013029a3fdf destroyed.
2011/01/17 12:52:53.315 0:07.621 Opal Garbage:0xb6d95b70 SIP Transaction id=z9hG4bKc0e5aa45-d020-e011-9c53-0013029a3fdf destroyed.
2011/01/17 12:52:53.413 0:07.719 Housekeeper:0xb6bffb70 SIP Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK98f7d445-d020-e011-9c53-0013029a3fdf
2011/01/17 12:52:53.973 0:08.279 Listen Stopping listening thread on udp$*:5060
2011/01/17 12:52:53.973 0:08.279 MonSock Closed UDP socket 0xb6c0db18
2011/01/17 12:52:53.974 0:08.279 Opal Liste...0xb6bbeb70 Listen UDP read error.
2011/01/17 12:52:53.994 0:08.300 MonSock Deleting UDP socket 0xb6c0db18
2011/01/17 12:52:53.994 0:08.300 PWLib File handle low water mark set: 35 PUDPSocket
2011/01/17 12:52:53.994 0:08.300 MonSock Created socket bundle for all interfaces.
2011/01/17 12:52:54.315 0:08.621 Opal Garbage:0xb6d95b70 SIP Transaction id=z9hG4bK98f7d445-d020-e011-9c53-0013029a3fdf destroyed.
2011/01/17 12:52:54.816 0:09.121 MonSock Created bundled UDP socket via STUN, internal=192.168.1.3:5060, external=173.68.138.36:5060
2011/01/17 12:52:54.816 0:09.122 PTLib No permission to set priority level 4
2011/01/17 12:52:54.816 0:09.122 Listen Stopping listening thread on tcp$*:1720
2011/01/17 12:52:54.816 0:09.122 Opal Liste...0xb6bbeb70 Listen Started listening thread on udp$*:5060
2011/01/17 12:52:54.817 0:09.122 PWLib File handle high water mark set: 53 Thread unblock pipe
2011/01/17 12:52:54.817 0:09.123 Opal Liste...0xb6b7db70 Listen Started listening thread on tcp$*:1720
2011/01/17 12:52:54.817 0:09.123 PTLib Thread high water mark set: 11
2011/01/17 12:52:54.817 0:09.123 Opal Liste...0xb6b7db70 Listen Waiting on socket accept on tcp$*:1720
2011/01/17 12:52:54.817 0:09.123 subscriber:0xaf6a3b70 SIP Start REGISTER
aor=ghostnik11 iptel org
registrar=iptel.org
contact=
authID=ghostnik11
realm=
expire=3600
restore=30
minRetry=0.000
maxRetry=0.000
2011/01/17 12:52:54.818 0:09.124 PWLib File handle high water mark set: 55 Thread unblock pipe
2011/01/17 12:52:54.818 0:09.124 subscriber:0xaf6a3b70 SIP Changing REGISTER handler from Unavailable to Subscribing, target=sip:ghostnik11 iptel org, id=deb0a147-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:54.818 0:09.124 PTLib Thread high water mark set: 12
2011/01/17 12:52:54.818 0:09.124 subscriber:0xaf6a3b70 DNS SRV Lookup iptel.org service _sip._udp
2011/01/17 12:52:54.819 0:09.124 subscriber:0xaf662b70 SIP Start REGISTER
aor=ghostnik11 ekiga net
registrar=ekiga.net
contact=
authID=ghostnik11
realm=
expire=3600
restore=30
minRetry=0.000
maxRetry=0.000
2011/01/17 12:52:54.820 0:09.126 subscriber:0xaf662b70 SIP Changing REGISTER handler from Unavailable to Subscribing, target=sip:ghostnik11 ekiga net, id=34e7a147-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:54.820 0:09.126 subscriber:0xaf662b70 DNS SRV Lookup ekiga.net service _sip._udp
2011/01/17 12:52:55.905 0:10.211 subscriber:0xaf662b70 SIP No SRV record found.
2011/01/17 12:52:55.906 0:10.212 subscriber:0xaf662b70 OpalUDP Binding to interface: 0.0.0.0:5060
2011/01/17 12:52:55.906 0:10.212 subscriber:0xaf662b70 PWLib File handle high water mark set: 57 PUDPSocket
2011/01/17 12:52:55.906 0:10.212 subscriber:0xaf662b70 SIP Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2011/01/17 12:52:55.907 0:10.213 subscriber:0xaf662b70 OpalUDP Started connect to 86.64.162.35:5060
2011/01/17 12:52:55.907 0:10.213 subscriber:0xaf662b70 OpalUDP Writing to interface 0 - "192.168.1.3%wlan0"
2011/01/17 12:52:55.908 0:10.214 subscriber:0xaf662b70 PWLib File handle low water mark set: 56 PUDPSocket
2011/01/17 12:52:55.908 0:10.214 subscriber:0xaf6a3b70 SIP Attempting SRV record entry 0: 213.192.59.75:5060
2011/01/17 12:52:55.908 0:10.214 subscriber:0xaf6a3b70 OpalUDP Binding to interface: 0.0.0.0:5060
2011/01/17 12:52:55.909 0:10.214 subscriber:0xaf6a3b70 SIP Created transport udp$213.192.59.75:5060<if=udp$*:5060>
2011/01/17 12:52:55.909 0:10.215 subscriber:0xaf6a3b70 OpalUDP Started connect to 213.192.59.75:5060
2011/01/17 12:52:55.909 0:10.215 subscriber:0xaf6a3b70 OpalUDP Writing to interface 0 - "192.168.1.3%wlan0"
2011/01/17 12:52:55.926 0:10.232 subscriber:0xaf662b70 PWLib File handle high water mark set: 58 PUDPSocket
2011/01/17 12:52:55.926 0:10.232 subscriber:0xaf6a3b70 PWLib File handle low water mark set: 56 PUDPSocket
2011/01/17 12:52:55.927 0:10.232 subscriber:0xaf662b70 OpalMan Listener interfaces: associated transport=udp$173.68.138.36:5060
udp$173.68.138.36:5060,udp$192.168.1.3:5060
2011/01/17 12:52:55.927 0:10.233 subscriber:0xaf6a3b70 OpalMan Listener interfaces: associated transport=udp$173.68.138.36:5060
udp$173.68.138.36:5060,udp$192.168.1.3:5060
2011/01/17 12:52:55.928 0:10.234 subscriber:0xaf662b70 SIP Transaction created.
2011/01/17 12:52:55.928 0:10.234 subscriber:0xaf6a3b70 SIP Transaction created.
2011/01/17 12:52:55.931 0:10.236 subscriber:0xaf662b70 DNS SRV Lookup ekiga.net service _sip._udp
2011/01/17 12:52:55.931 0:10.237 subscriber:0xaf6a3b70 DNS SRV Lookup iptel.org service _sip._udp
2011/01/17 12:52:56.197 0:10.503 subscriber:0xaf6a3b70 SIP Attempting SRV record entry 0: 213.192.59.75:5060
2011/01/17 12:52:56.197 0:10.503 subscriber:0xaf6a3b70 SIP Transaction remote address is udp$213.192.59.75:5060
2011/01/17 12:52:56.198 0:10.503 subscriber:0xaf6a3b70 SIP Sending PDU (551 bytes) to: rem=udp$213.192.59.75:5060,local=udp$173.68.138.36:5060,if=192.168.1.3%wlan0
REGISTER sip:iptel.org SIP/2.0
CSeq: 1 REGISTER
Via: SIP/2.0/UDP 173.68.138.36:5060;branch=z9hG4bKec894b48-d020-e011-9c53-0013029a3fdf;rport
User-Agent: Ekiga/3.2.7
From: <sip:ghostnik11 iptel org>;tag=2ec0a147-d020-e011-9c53-0013029a3fdf
Call-ID: deb0a147-d020-e011-9c53-0013029a3fdf@king-laptop
To: <sip:ghostnik11 iptel org>
Contact: <sip:ghostnik11@173.68.138.36>;q=1, <sip:ghostnik11@192.168.1.3>;q=0.500
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 3600
Content-Length: 0
Max-Forwards: 70
2011/01/17 12:52:56.198 0:10.504 subscriber:0xaf6a3b70 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:52:56.199 0:10.505 subscriber:0xaf6a3b70 SIP Transaction timers set: retry=0.500, completion=6.000
2011/01/17 12:52:56.209 0:10.515 subscriber:0xaf662b70 SIP No SRV record found.
2011/01/17 12:52:56.210 0:10.515 subscriber:0xaf662b70 SIP Transaction remote address is udp$ekiga.net:5060
2011/01/17 12:52:56.211 0:10.516 subscriber:0xaf662b70 PWLib File handle low water mark set: 52 PUDPSocket
2011/01/17 12:52:56.210 0:10.516 subscriber:0xaf662b70 SIP Sending PDU (551 bytes) to: rem=udp$86.64.162.35:5060,local=udp$173.68.138.36:5060,if=192.168.1.3%wlan0
REGISTER sip:ekiga.net SIP/2.0
CSeq: 2 REGISTER
Via: SIP/2.0/UDP 173.68.138.36:5060;branch=z9hG4bK38844b48-d020-e011-9c53-0013029a3fdf;rport
User-Agent: Ekiga/3.2.7
From: <sip:ghostnik11 ekiga net>;tag=4814a247-d020-e011-9c53-0013029a3fdf
Call-ID: 34e7a147-d020-e011-9c53-0013029a3fdf@king-laptop
To: <sip:ghostnik11 ekiga net>
Contact: <sip:ghostnik11@173.68.138.36>;q=1, <sip:ghostnik11@192.168.1.3>;q=0.500
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 3600
Content-Length: 0
Max-Forwards: 70
2011/01/17 12:52:56.211 0:10.517 subscriber:0xaf662b70 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:52:56.212 0:10.517 subscriber:0xaf662b70 SIP Transaction timers set: retry=0.500, completion=6.000
2011/01/17 12:52:56.310 0:10.616 Opal Liste...0xb6bbeb70 OpalUDP Binding to interface: 173.68.138.36:5060
2011/01/17 12:52:56.311 0:10.616 Opal Liste...0xb6bbeb70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$173.68.138.36:5060>
2011/01/17 12:52:56.312 0:10.617 Opal Liste...0xb6bbeb70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$173.68.138.36:5060,if=192.168.1.3%wlan0
SIP/2.0 401 Unauthorized
CSeq: 2 REGISTER
Via: SIP/2.0/UDP 173.68.138.36:5060;branch=z9hG4bK38844b48-d020-e011-9c53-0013029a3fdf;rport=5060
Server: Kamailio (1.5.3-notls (i386/linux))
From: <sip:ghostnik11 ekiga net>;tag=4814a247-d020-e011-9c53-0013029a3fdf
Call-ID: 34e7a147-d020-e011-9c53-0013029a3fdf@king-laptop
To: <sip:ghostnik11 ekiga net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.5cbb
Content-Length: 0
WWW-Authenticate: Digest realm="ekiga.net", nonce="4d34821600015490916a1aef6403d5101a3e1cf896200b43"
2011/01/17 12:52:56.313 0:10.618 Opal Liste...0xb6bbeb70 SIP Queueing PDU "2 REGISTER <401>", transaction=z9hG4bK38844b48-d020-e011-9c53-0013029a3fdf, token=34e7a147-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:56.313 0:10.618 Opal Liste...0xb6bbeb70 Opal Transport clean up on termination
2011/01/17 12:52:56.313 0:10.619 Opal Liste...0xb6bbeb70 Opal Transport Close
2011/01/17 12:52:56.313 0:10.619 Pool:0xb6b3cb70 SIP Handling PDU "2 REGISTER <401>" for transaction=z9hG4bK38844b48-d020-e011-9c53-0013029a3fdf
2011/01/17 12:52:56.313 0:10.619 Pool:0xb6b3cb70 SIP REGISTER transaction id=z9hG4bK38844b48-d020-e011-9c53-0013029a3fdf completed.
2011/01/17 12:52:56.313 0:10.619 Pool:0xb6b3cb70 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:52:56.313 0:10.619 Opal Liste...0xb6bbeb70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$173.68.138.36:5060>
2011/01/17 12:52:56.314 0:10.619 Pool:0xb6b3cb70 SIP Received Authentication Required response
2011/01/17 12:52:56.314 0:10.620 Pool:0xb6b3cb70 SIP Located existing credentials for ID "ghostnik11" at realm "ekiga.net"
2011/01/17 12:52:56.314 0:10.620 Pool:0xb6b3cb70 SIP Found auth info for realm ekiga.net
2011/01/17 12:52:56.314 0:10.620 Pool:0xb6b3cb70 SIP Changing REGISTER handler from Unavailable to Subscribing, target=sip:ghostnik11 ekiga net, id=34e7a147-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:56.316 0:10.622 Pool:0xb6b3cb70 OpalMan Listener interfaces: associated transport=udp$173.68.138.36:5060
udp$173.68.138.36:5060,udp$192.168.1.3:5060
2011/01/17 12:52:56.317 0:10.623 Pool:0xb6b3cb70 SIP Transaction created.
2011/01/17 12:52:56.320 0:10.626 Pool:0xb6b3cb70 SIP Adding authentication information for user "ghostnik11" at realm "ekiga.net"
2011/01/17 12:52:56.321 0:10.627 Pool:0xb6b3cb70 DNS SRV Lookup ekiga.net service _sip._udp
2011/01/17 12:52:56.323 0:10.629 Opal Liste...0xb6bbeb70 OpalUDP Binding to interface: 173.68.138.36:5060
2011/01/17 12:52:56.324 0:10.629 Opal Liste...0xb6bbeb70 SIP Waiting for PDU on udp$213.192.59.75:5060<if=udp$173.68.138.36:5060>
2011/01/17 12:52:56.325 0:10.630 Opal Liste...0xb6bbeb70 SIP PDU received: rem=udp$213.192.59.75:5060,local=udp$173.68.138.36:5060,if=192.168.1.3%wlan0
SIP/2.0 401 Unauthorized
CSeq: 1 REGISTER
Via: SIP/2.0/UDP 173.68.138.36:5060;branch=z9hG4bKec894b48-d020-e011-9c53-0013029a3fdf;rport=5060
Server: ser (3.1.0-pre1 (i386/linux))
From: <sip:ghostnik11 iptel org>;tag=2ec0a147-d020-e011-9c53-0013029a3fdf
Call-ID: deb0a147-d020-e011-9c53-0013029a3fdf@king-laptop
Min-Expires: 240
To: <sip:ghostnik11 iptel org>;tag=b98f1230ac1a7949bf499aa7f9ea7038.0ee3
Warning: 392 213.192.59.75:5060 "Noisy feedback tells: pid=6554 req_src_ip=173.68.138.36 req_src_port=5060 in_uri=sip:iptel.org out_uri=sip:iptel.org via_cnt==1"
Expires: 600
Content-Length: 0
WWW-Authenticate: Digest realm="iptel.org", nonce="TTSCAE00gfaIntzirefmsDITOaOUPVjC"
2011/01/17 12:52:56.326 0:10.632 Opal Liste...0xb6bbeb70 SIP Queueing PDU "1 REGISTER <401>", transaction=z9hG4bKec894b48-d020-e011-9c53-0013029a3fdf, token=deb0a147-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:56.326 0:10.632 Opal Liste...0xb6bbeb70 PTLib No permission to set priority level 3
2011/01/17 12:52:56.326 0:10.632 Opal Liste...0xb6bbeb70 Opal Transport clean up on termination
2011/01/17 12:52:56.326 0:10.632 Opal Liste...0xb6bbeb70 Opal Transport Close
2011/01/17 12:52:56.326 0:10.632 Pool:0xaf662b70 SIP Handling PDU "1 REGISTER <401>" for transaction=z9hG4bKec894b48-d020-e011-9c53-0013029a3fdf
2011/01/17 12:52:56.327 0:10.632 Pool:0xaf662b70 SIP REGISTER transaction id=z9hG4bKec894b48-d020-e011-9c53-0013029a3fdf completed.
2011/01/17 12:52:56.327 0:10.632 Pool:0xaf662b70 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:52:56.326 0:10.632 Opal Liste...0xb6bbeb70 Opal Deleted transport udp$213.192.59.75:5060<if=udp$173.68.138.36:5060>
2011/01/17 12:52:56.327 0:10.633 Pool:0xaf662b70 SIP Received Authentication Required response
2011/01/17 12:52:56.796 0:11.102 Pool:0xb6b3cb70 SIP No SRV record found.
2011/01/17 12:52:56.796 0:11.102 Pool:0xb6b3cb70 SIP Transaction remote address is udp$ekiga.net:5060
2011/01/17 12:52:56.797 0:11.103 Pool:0xaf662b70 SIP Located existing credentials for ID "ghostnik11" at realm "iptel.org"
2011/01/17 12:52:56.797 0:11.103 Pool:0xaf662b70 SIP Found auth info for realm iptel.org
2011/01/17 12:52:56.797 0:11.103 Pool:0xaf662b70 SIP Changing REGISTER handler from Unavailable to Subscribing, target=sip:ghostnik11 iptel org, id=deb0a147-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:56.797 0:11.103 Pool:0xb6b3cb70 SIP Sending PDU (754 bytes) to: rem=udp$86.64.162.35:5060,local=udp$173.68.138.36:5060,if=192.168.1.3%wlan0
REGISTER sip:ekiga.net SIP/2.0
CSeq: 3 REGISTER
Via: SIP/2.0/UDP 173.68.138.36:5060;branch=z9hG4bK62008748-d020-e011-9c53-0013029a3fdf;rport
User-Agent: Ekiga/3.2.7
Authorization: Digest username="ghostnik11", realm="ekiga.net", nonce="4d34821600015490916a1aef6403d5101a3e1cf896200b43", uri="sip:ekiga.net", algorithm=MD5, response="f5e616fd9c4b386737fc9a132a39e1fb"
From: <sip:ghostnik11 ekiga net>;tag=4814a247-d020-e011-9c53-0013029a3fdf
Call-ID: 34e7a147-d020-e011-9c53-0013029a3fdf@king-laptop
To: <sip:ghostnik11 ekiga net>
Contact: <sip:ghostnik11@173.68.138.36>;q=1, <sip:ghostnik11@192.168.1.3>;q=0.500
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 3600
Content-Length: 0
Max-Forwards: 70
2011/01/17 12:52:56.798 0:11.104 Pool:0xb6b3cb70 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:52:56.798 0:11.104 Pool:0xb6b3cb70 SIP Transaction timers set: retry=0.500, completion=6.000
2011/01/17 12:52:56.798 0:11.104 Pool:0xb6b3cb70 SIP Handled PDU "2 REGISTER <401>"
2011/01/17 12:52:56.800 0:11.105 Pool:0xaf662b70 OpalMan Listener interfaces: associated transport=udp$173.68.138.36:5060
udp$173.68.138.36:5060,udp$192.168.1.3:5060
2011/01/17 12:52:56.801 0:11.107 Pool:0xaf662b70 SIP Transaction created.
2011/01/17 12:52:56.803 0:11.109 Pool:0xaf662b70 SIP Adding authentication information for user "ghostnik11" at realm "iptel.org"
2011/01/17 12:52:56.804 0:11.110 Pool:0xaf662b70 DNS SRV Lookup iptel.org service _sip._udp
2011/01/17 12:52:56.815 0:11.121 Pool:0xaf662b70 SIP Attempting SRV record entry 0: 213.192.59.75:5060
2011/01/17 12:52:56.815 0:11.121 Pool:0xaf662b70 SIP Transaction remote address is udp$213.192.59.75:5060
2011/01/17 12:52:56.816 0:11.122 Pool:0xaf662b70 SIP Sending PDU (738 bytes) to: rem=udp$213.192.59.75:5060,local=udp$173.68.138.36:5060,if=192.168.1.3%wlan0
REGISTER sip:iptel.org SIP/2.0
CSeq: 4 REGISTER
Via: SIP/2.0/UDP 173.68.138.36:5060;branch=z9hG4bK58b6d048-d020-e011-9c53-0013029a3fdf;rport
User-Agent: Ekiga/3.2.7
Authorization: Digest username="ghostnik11", realm="iptel.org", nonce="TTSCAE00gfaIntzirefmsDITOaOUPVjC", uri="sip:iptel.org", algorithm=MD5, response="1528c97f56737c4455256bafa128bae5"
From: <sip:ghostnik11 iptel org>;tag=2ec0a147-d020-e011-9c53-0013029a3fdf
Call-ID: deb0a147-d020-e011-9c53-0013029a3fdf@king-laptop
To: <sip:ghostnik11 iptel org>
Contact: <sip:ghostnik11@173.68.138.36>;q=1, <sip:ghostnik11@192.168.1.3>;q=0.500
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 3600
Content-Length: 0
Max-Forwards: 70
2011/01/17 12:52:56.817 0:11.122 Pool:0xaf662b70 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:52:56.817 0:11.123 Pool:0xaf662b70 SIP Transaction timers set: retry=0.500, completion=6.000
2011/01/17 12:52:56.817 0:11.123 Pool:0xaf662b70 SIP Handled PDU "1 REGISTER <401>"
2011/01/17 12:52:56.944 0:11.250 Opal Liste...0xb6bbeb70 OpalUDP Binding to interface: 173.68.138.36:5060
2011/01/17 12:52:56.944 0:11.250 Opal Liste...0xb6bbeb70 SIP Waiting for PDU on udp$213.192.59.75:5060<if=udp$173.68.138.36:5060>
2011/01/17 12:52:56.946 0:11.251 Opal Liste...0xb6bbeb70 SIP PDU received: rem=udp$213.192.59.75:5060,local=udp$173.68.138.36:5060,if=192.168.1.3%wlan0
SIP/2.0 200 OK
CSeq: 4 REGISTER
Via: SIP/2.0/UDP 173.68.138.36:5060;branch=z9hG4bK58b6d048-d020-e011-9c53-0013029a3fdf;rport=5060
Server: ser (3.1.0-pre1 (i386/linux))
From: <sip:ghostnik11 iptel org>;tag=2ec0a147-d020-e011-9c53-0013029a3fdf
Call-ID: deb0a147-d020-e011-9c53-0013029a3fdf@king-laptop
Min-Expires: 240
To: <sip:ghostnik11 iptel org>;tag=b98f1230ac1a7949bf499aa7f9ea7038.ca41
Contact: <sip:ghostnik11@173.68.138.36>;q=1;expires=600;received="sip:173.68.138.36:5060;dstip=213.192.59.75;dstport=5060", <sip:ghostnik11@192.168.1.3>;q=0.5;expires=600;received="sip:173.68.138.36:5060;dstip=213.192.59.75;dstport=5060"
Warning: 392 213.192.59.75:5060 "Noisy feedback tells: pid=6549 req_src_ip=173.68.138.36 req_src_port=5060 in_uri=sip:iptel.org out_uri=sip:iptel.org via_cnt==1"
Expires: 600
Content-Length: 0
2011/01/17 12:52:56.947 0:11.252 Opal Liste...0xb6bbeb70 SIP Queueing PDU "4 REGISTER <200>", transaction=z9hG4bK58b6d048-d020-e011-9c53-0013029a3fdf, token=deb0a147-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:56.947 0:11.252 Opal Liste...0xb6bbeb70 Opal Transport clean up on termination
2011/01/17 12:52:56.947 0:11.253 Opal Liste...0xb6bbeb70 Opal Transport Close
2011/01/17 12:52:56.947 0:11.253 Pool:0xb6b3cb70 SIP Handling PDU "4 REGISTER <200>" for transaction=z9hG4bK58b6d048-d020-e011-9c53-0013029a3fdf
2011/01/17 12:52:56.947 0:11.253 Pool:0xb6b3cb70 SIP REGISTER transaction id=z9hG4bK58b6d048-d020-e011-9c53-0013029a3fdf completed.
2011/01/17 12:52:56.947 0:11.253 Pool:0xb6b3cb70 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:52:56.947 0:11.253 Pool:0xb6b3cb70 SIP Product Info: name="ser", version="", vendor="", comments="(3.1.0-pre1 (i386/linux))"
2011/01/17 12:52:56.948 0:11.253 Pool:0xb6b3cb70 SIP Changing REGISTER handler from Subscribing to Subscribed, target=sip:ghostnik11 iptel org, id=deb0a147-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:56.947 0:11.253 Opal Liste...0xb6bbeb70 Opal Deleted transport udp$213.192.59.75:5060<if=udp$173.68.138.36:5060>
2011/01/17 12:52:56.948 0:11.254 Pool:0xb6b3cb70 SIP Expiry time for REGISTER set to 600 seconds.
2011/01/17 12:52:56.949 0:11.254 Pool:0xb6b3cb70 SIP Expiry time for REGISTER set to 600 seconds.
2011/01/17 12:52:56.949 0:11.255 Pool:0xb6b3cb70 SIP Product Info: name="ser", version="", vendor="", comments="(3.1.0-pre1 (i386/linux))"
2011/01/17 12:52:56.950 0:11.255 Pool:0xb6b3cb70 SIP Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:ghostnik11 iptel org, id=0200e748-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:56.950 0:11.256 Pool:0xb6b3cb70 DNS SRV Lookup iptel.org service _sip._udp
2011/01/17 12:52:56.962 0:11.268 Pool:0xb6b3cb70 SIP Attempting SRV record entry 0: 213.192.59.75:5060
2011/01/17 12:52:56.963 0:11.268 Pool:0xb6b3cb70 OpalUDP Binding to interface: 0.0.0.0:5060
2011/01/17 12:52:56.963 0:11.269 Pool:0xb6b3cb70 SIP Created transport udp$213.192.59.75:5060<if=udp$*:5060>
2011/01/17 12:52:56.963 0:11.269 Pool:0xb6b3cb70 OpalUDP Started connect to 213.192.59.75:5060
2011/01/17 12:52:56.964 0:11.269 Pool:0xb6b3cb70 OpalUDP Writing to interface 0 - "192.168.1.3%wlan0"
2011/01/17 12:52:56.964 0:11.270 Pool:0xb6b3cb70 SIP Transaction created.
2011/01/17 12:52:56.968 0:11.274 Pool:0xb6b3cb70 DNS SRV Lookup iptel.org service _sip._udp
2011/01/17 12:52:56.974 0:11.279 Opal Liste...0xb6bbeb70 OpalUDP Binding to interface: 173.68.138.36:5060
2011/01/17 12:52:56.974 0:11.279 Opal Liste...0xb6bbeb70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$173.68.138.36:5060>
2011/01/17 12:52:56.975 0:11.280 Opal Liste...0xb6bbeb70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$173.68.138.36:5060,if=192.168.1.3%wlan0
SIP/2.0 200 OK
CSeq: 3 REGISTER
Via: SIP/2.0/UDP 173.68.138.36:5060;branch=z9hG4bK62008748-d020-e011-9c53-0013029a3fdf;rport=5060
Server: Kamailio (1.5.3-notls (i386/linux))
From: <sip:ghostnik11 ekiga net>;tag=4814a247-d020-e011-9c53-0013029a3fdf
Call-ID: 34e7a147-d020-e011-9c53-0013029a3fdf@king-laptop
To: <sip:ghostnik11 ekiga net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.8a01
Contact: <sip:ghostnik11@173.68.138.36>;q=1;expires=1200, <sip:ghostnik11@192.168.1.3>;q=0.5;expires=1200
Content-Length: 0
2011/01/17 12:52:56.976 0:11.281 Opal Liste...0xb6bbeb70 SIP Queueing PDU "3 REGISTER <200>", transaction=z9hG4bK62008748-d020-e011-9c53-0013029a3fdf, token=34e7a147-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:56.976 0:11.281 Opal Liste...0xb6bbeb70 Opal Transport clean up on termination
2011/01/17 12:52:56.976 0:11.282 Opal Liste...0xb6bbeb70 Opal Transport Close
2011/01/17 12:52:56.976 0:11.282 Pool:0xaf662b70 SIP Handling PDU "3 REGISTER <200>" for transaction=z9hG4bK62008748-d020-e011-9c53-0013029a3fdf
2011/01/17 12:52:56.976 0:11.282 Pool:0xaf662b70 SIP REGISTER transaction id=z9hG4bK62008748-d020-e011-9c53-0013029a3fdf completed.
2011/01/17 12:52:56.976 0:11.282 Pool:0xaf662b70 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:52:56.977 0:11.282 Pool:0xaf662b70 SIP Product Info: name="Kamailio", version="", vendor="", comments="(1.5.3-notls (i386/linux))"
2011/01/17 12:52:56.977 0:11.282 Pool:0xaf662b70 SIP Changing REGISTER handler from Subscribing to Subscribed, target=sip:ghostnik11 ekiga net, id=34e7a147-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:56.976 0:11.282 Opal Liste...0xb6bbeb70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$173.68.138.36:5060>
2011/01/17 12:52:56.977 0:11.283 Pool:0xaf662b70 SIP Expiry time for REGISTER set to 1200 seconds.
2011/01/17 12:52:56.978 0:11.283 Pool:0xaf662b70 SIP Expiry time for REGISTER set to 1200 seconds.
2011/01/17 12:52:56.978 0:11.284 Pool:0xaf662b70 SIP Product Info: name="Kamailio", version="", vendor="", comments="(1.5.3-notls (i386/linux))"
2011/01/17 12:52:56.979 0:11.284 Pool:0xaf662b70 SIP Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:ghostnik11 ekiga net, id=f66beb48-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:56.979 0:11.285 Pool:0xaf662b70 DNS SRV Lookup ekiga.net service _sip._udp
2011/01/17 12:52:57.233 0:11.538 Pool:0xb6b3cb70 SIP Attempting SRV record entry 0: 213.192.59.75:5060
2011/01/17 12:52:57.233 0:11.539 Pool:0xb6b3cb70 SIP Transaction remote address is udp$213.192.59.75:5060
2011/01/17 12:52:57.233 0:11.539 Pool:0xb6b3cb70 SIP Sending PDU (590 bytes) to: rem=udp$213.192.59.75:5060,local=udp$173.68.138.36:5060,if=192.168.1.3%wlan0
SUBSCRIBE sip:ghostnik11 iptel org SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 173.68.138.36:5060;branch=z9hG4bK3e8fe948-d020-e011-9c53-0013029a3fdf;rport
User-Agent: Ekiga/3.2.7
From: <sip:ghostnik11 iptel org>;tag=b239e948-d020-e011-9c53-0013029a3fdf
Call-ID: 0200e748-d020-e011-9c53-0013029a3fdf@king-laptop
To: <sip:ghostnik11 iptel org>
Contact: <sip:ghostnik11@173.68.138.36>
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/01/17 12:52:57.234 0:11.540 Pool:0xb6b3cb70 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:52:57.235 0:11.540 Pool:0xb6b3cb70 SIP Transaction timers set: retry=0.500, completion=6.000
2011/01/17 12:52:57.235 0:11.541 Pool:0xb6b3cb70 SIP Handled PDU "4 REGISTER <200>"
2011/01/17 12:52:57.254 0:11.560 Pool:0xaf662b70 SIP No SRV record found.
2011/01/17 12:52:57.254 0:11.560 Pool:0xaf662b70 OpalUDP Binding to interface: 0.0.0.0:5060
2011/01/17 12:52:57.254 0:11.560 Pool:0xaf662b70 SIP Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2011/01/17 12:52:57.255 0:11.561 Pool:0xaf662b70 OpalUDP Started connect to 86.64.162.35:5060
2011/01/17 12:52:57.255 0:11.561 Pool:0xaf662b70 OpalUDP Writing to interface 0 - "192.168.1.3%wlan0"
2011/01/17 12:52:57.256 0:11.561 Pool:0xaf662b70 SIP Transaction created.
2011/01/17 12:52:57.260 0:11.566 Pool:0xaf662b70 DNS SRV Lookup ekiga.net service _sip._udp
2011/01/17 12:52:57.292 0:11.598 Pool:0xaf662b70 SIP No SRV record found.
2011/01/17 12:52:57.292 0:11.598 Pool:0xaf662b70 SIP Transaction remote address is udp$ekiga.net:5060
2011/01/17 12:52:57.293 0:11.599 Pool:0xaf662b70 SIP Sending PDU (590 bytes) to: rem=udp$86.64.162.35:5060,local=udp$173.68.138.36:5060,if=192.168.1.3%wlan0
SUBSCRIBE sip:ghostnik11 ekiga net SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 173.68.138.36:5060;branch=z9hG4bK1e0f1649-d020-e011-9c53-0013029a3fdf;rport
User-Agent: Ekiga/3.2.7
From: <sip:ghostnik11 ekiga net>;tag=e4b71549-d020-e011-9c53-0013029a3fdf
Call-ID: f66beb48-d020-e011-9c53-0013029a3fdf@king-laptop
To: <sip:ghostnik11 ekiga net>
Contact: <sip:ghostnik11@173.68.138.36>
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/01/17 12:52:57.294 0:11.600 Pool:0xaf662b70 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:52:57.294 0:11.600 Pool:0xaf662b70 SIP Transaction timers set: retry=0.500, completion=6.000
2011/01/17 12:52:57.294 0:11.600 Pool:0xaf662b70 SIP Handled PDU "3 REGISTER <200>"
2011/01/17 12:52:57.325 0:11.631 SIP Changing PUBLISH handler from Unavailable to Subscribing, target=sip:ghostnik11 ekiga net, id=24542049-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:57.326 0:11.631 DNS SRV Lookup ekiga.net service _sip._udp
2011/01/17 12:52:57.350 0:11.655 SIP No SRV record found.
2011/01/17 12:52:57.350 0:11.656 OpalUDP Binding to interface: 0.0.0.0:5060
2011/01/17 12:52:57.350 0:11.656 SIP Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2011/01/17 12:52:57.351 0:11.657 OpalUDP Started connect to 86.64.162.35:5060
2011/01/17 12:52:57.351 0:11.657 OpalUDP Writing to interface 0 - "192.168.1.3%wlan0"
2011/01/17 12:52:57.351 0:11.657 SIP Transaction created.
2011/01/17 12:52:57.356 0:11.662 DNS SRV Lookup ekiga.net service _sip._udp
2011/01/17 12:52:57.360 0:11.666 Opal Liste...0xb6bbeb70 OpalUDP Binding to interface: 173.68.138.36:5060
2011/01/17 12:52:57.360 0:11.666 Opal Liste...0xb6bbeb70 SIP Waiting for PDU on udp$213.192.59.75:5060<if=udp$173.68.138.36:5060>
2011/01/17 12:52:57.361 0:11.667 Opal Liste...0xb6bbeb70 SIP PDU received: rem=udp$213.192.59.75:5060,local=udp$173.68.138.36:5060,if=192.168.1.3%wlan0
SIP/2.0 407 Proxy Authentication Required
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 173.68.138.36:5060;branch=z9hG4bK3e8fe948-d020-e011-9c53-0013029a3fdf;rport=5060
Server: ser (3.1.0-pre1 (i386/linux))
From: <sip:ghostnik11 iptel org>;tag=b239e948-d020-e011-9c53-0013029a3fdf
Call-ID: 0200e748-d020-e011-9c53-0013029a3fdf@king-laptop
To: <sip:ghostnik11 iptel org>;tag=b98f1230ac1a7949bf499aa7f9ea7038.e1af
Proxy-Authenticate: Digest realm="iptel.org", nonce="TTSCAU00gfe33Qc2xgqRx4eXtWxWfVTH"
Warning: 392 213.192.59.75:5060 "Noisy feedback tells: pid=6554 req_src_ip=173.68.138.36 req_src_port=5060 in_uri=sip:ghostnik11 iptel org out_uri=sip:ghostnik11 iptel org via_cnt==1"
Content-Length: 0
2011/01/17 12:52:57.362 0:11.668 Opal Liste...0xb6bbeb70 SIP Queueing PDU "2 SUBSCRIBE <407>", transaction=z9hG4bK3e8fe948-d020-e011-9c53-0013029a3fdf, token=0200e748-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:57.363 0:11.668 Opal Liste...0xb6bbeb70 Opal Transport clean up on termination
2011/01/17 12:52:57.363 0:11.668 Opal Liste...0xb6bbeb70 Opal Transport Close
2011/01/17 12:52:57.363 0:11.669 Pool:0xb6b3cb70 SIP Handling PDU "2 SUBSCRIBE <407>" for transaction=z9hG4bK3e8fe948-d020-e011-9c53-0013029a3fdf
2011/01/17 12:52:57.363 0:11.669 Pool:0xb6b3cb70 SIP SUBSCRIBE transaction id=z9hG4bK3e8fe948-d020-e011-9c53-0013029a3fdf completed.
2011/01/17 12:52:57.363 0:11.669 Pool:0xb6b3cb70 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:52:57.363 0:11.669 Pool:0xb6b3cb70 SIP Received Proxy Authentication Required response
2011/01/17 12:52:57.363 0:11.669 Opal Liste...0xb6bbeb70 Opal Deleted transport udp$213.192.59.75:5060<if=udp$173.68.138.36:5060>
2011/01/17 12:52:57.364 0:11.669 Pool:0xb6b3cb70 SIP Located existing credentials for realm "iptel.org"
2011/01/17 12:52:57.364 0:11.669 Pool:0xb6b3cb70 SIP Found auth info for realm iptel.org
2011/01/17 12:52:57.364 0:11.669 Pool:0xb6b3cb70 SIP Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:ghostnik11 iptel org, id=0200e748-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:57.364 0:11.670 Pool:0xb6b3cb70 SIP Transaction created.
2011/01/17 12:52:57.368 0:11.674 Pool:0xb6b3cb70 SIP Adding authentication information for user "ghostnik11" at realm "iptel.org"
2011/01/17 12:52:57.368 0:11.674 Pool:0xb6b3cb70 DNS SRV Lookup iptel.org service _sip._udp
2011/01/17 12:52:57.382 0:11.688 SIP No SRV record found.
2011/01/17 12:52:57.382 0:11.688 SIP Transaction remote address is udp$ekiga.net:5060
2011/01/17 12:52:57.383 0:11.689 Pool:0xb6b3cb70 SIP Attempting SRV record entry 0: 213.192.59.75:5060
2011/01/17 12:52:57.383 0:11.689 Pool:0xb6b3cb70 SIP Transaction remote address is udp$213.192.59.75:5060
2011/01/17 12:52:57.383 0:11.688 SIP Sending PDU (835 bytes) to: rem=udp$86.64.162.35:5060,local=udp$173.68.138.36:5060,if=192.168.1.3%wlan0
PUBLISH sip:ghostnik11 ekiga net SIP/2.0
CSeq: 5 PUBLISH
Via: SIP/2.0/UDP 173.68.138.36:5060;branch=z9hG4bKd2b02449-d020-e011-9c53-0013029a3fdf;rport
User-Agent: Ekiga/3.2.7
From: <sip:ghostnik11 ekiga net>;tag=64602449-d020-e011-9c53-0013029a3fdf
Call-ID: 24542049-d020-e011-9c53-0013029a3fdf@king-laptop
To: <sip:ghostnik11 ekiga net>
Contact: <sip:ghostnik11@173.68.138.36>
Expires: 500
Event: presence
Content-Type: application/pidf+xml
Content-Length: 342
Max-Forwards: 70
<?xml version="1.0" encoding="UTF-8"?>
<presence xmlns="urn:ietf:params:xml:ns:pidf" entity="pres:ghostnik11 ekiga net">
<tuple id="sip:ghostnik11@ekiga.net_on_king-laptop">
<note>online - I'm online using Ekiga</note>
<status>
<basic>open</basic>
</status>
<contact priority="1">ghostnik11 ekiga net</contact>
</tuple>
</presence>
2011/01/17 12:52:57.383 0:11.689 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:52:57.384 0:11.690 SIP Transaction timers set: retry=0.500, completion=6.000
2011/01/17 12:52:57.383 0:11.689 Pool:0xb6b3cb70 SIP Sending PDU (794 bytes) to: rem=udp$213.192.59.75:5060,local=udp$173.68.138.36:5060,if=192.168.1.3%wlan0
SUBSCRIBE sip:ghostnik11 iptel org SIP/2.0
CSeq: 3 SUBSCRIBE
Via: SIP/2.0/UDP 173.68.138.36:5060;branch=z9hG4bK70932649-d020-e011-9c53-0013029a3fdf;rport
User-Agent: Ekiga/3.2.7
From: <sip:ghostnik11 iptel org>;tag=b239e948-d020-e011-9c53-0013029a3fdf
Call-ID: 0200e748-d020-e011-9c53-0013029a3fdf@king-laptop
To: <sip:ghostnik11 iptel org>
Contact: <sip:ghostnik11@173.68.138.36>
Accept: application/simple-message-summary
Proxy-Authorization: Digest username="ghostnik11", realm="iptel.org", nonce="TTSCAU00gfe33Qc2xgqRx4eXtWxWfVTH", uri="sip:ghostnik11 iptel org", algorithm=MD5, response="1e34efb6e17280aa7b1f15cf7d1475bb"
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 3600
Event: message-summary
Content-Length: 0
Max-Forwards: 70
2011/01/17 12:52:57.384 0:11.690 Pool:0xb6b3cb70 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:52:57.385 0:11.690 SIP Changing PUBLISH handler from Unavailable to Subscribing, target=sip:ghostnik11 iptel org, id=e4532949-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:57.385 0:11.690 DNS SRV Lookup iptel.org service _sip._udp
2011/01/17 12:52:57.385 0:11.691 Pool:0xb6b3cb70 SIP Transaction timers set: retry=0.500, completion=6.000
2011/01/17 12:52:57.385 0:11.691 Pool:0xb6b3cb70 SIP Handled PDU "2 SUBSCRIBE <407>"
2011/01/17 12:52:57.402 0:11.708 SIP Attempting SRV record entry 0: 213.192.59.75:5060
2011/01/17 12:52:57.402 0:11.708 OpalUDP Binding to interface: 0.0.0.0:5060
2011/01/17 12:52:57.403 0:11.708 SIP Created transport udp$213.192.59.75:5060<if=udp$*:5060>
2011/01/17 12:52:57.403 0:11.709 OpalUDP Started connect to 213.192.59.75:5060
2011/01/17 12:52:57.404 0:11.709 OpalUDP Writing to interface 0 - "192.168.1.3%wlan0"
2011/01/17 12:52:57.404 0:11.709 SIP Transaction created.
2011/01/17 12:52:57.408 0:11.714 DNS SRV Lookup iptel.org service _sip._udp
2011/01/17 12:52:57.411 0:11.717 Opal Liste...0xb6bbeb70 OpalUDP Binding to interface: 173.68.138.36:5060
2011/01/17 12:52:57.411 0:11.717 Opal Liste...0xb6bbeb70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$173.68.138.36:5060>
2011/01/17 12:52:57.412 0:11.718 Opal Liste...0xb6bbeb70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$173.68.138.36:5060,if=192.168.1.3%wlan0
SIP/2.0 489 Event Not Supported
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 173.68.138.36:5060;branch=z9hG4bK1e0f1649-d020-e011-9c53-0013029a3fdf;rport=5060
Server: Kamailio (1.5.3-notls (i386/linux))
From: <sip:ghostnik11 ekiga net>;tag=e4b71549-d020-e011-9c53-0013029a3fdf
Call-ID: f66beb48-d020-e011-9c53-0013029a3fdf@king-laptop
To: <sip:ghostnik11 ekiga net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.1853
Content-Length: 0
2011/01/17 12:52:57.413 0:11.719 Opal Liste...0xb6bbeb70 SIP Queueing PDU "2 SUBSCRIBE <489>", transaction=z9hG4bK1e0f1649-d020-e011-9c53-0013029a3fdf, token=f66beb48-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:57.413 0:11.719 Opal Liste...0xb6bbeb70 Opal Transport clean up on termination
2011/01/17 12:52:57.413 0:11.719 Opal Liste...0xb6bbeb70 Opal Transport Close
2011/01/17 12:52:57.413 0:11.719 Pool:0xb6b3cb70 SIP Handling PDU "2 SUBSCRIBE <489>" for transaction=z9hG4bK1e0f1649-d020-e011-9c53-0013029a3fdf
2011/01/17 12:52:57.414 0:11.719 Pool:0xb6b3cb70 SIP SUBSCRIBE transaction id=z9hG4bK1e0f1649-d020-e011-9c53-0013029a3fdf completed.
2011/01/17 12:52:57.414 0:11.720 Pool:0xb6b3cb70 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:52:57.414 0:11.720 Pool:0xb6b3cb70 SIP Not retrying SUBSCRIBE due to error response 489 Bad Event
2011/01/17 12:52:57.414 0:11.720 Pool:0xb6b3cb70 SIP Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:ghostnik11 ekiga net, id=f66beb48-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:57.414 0:11.720 Pool:0xb6b3cb70 SIP Handled PDU "2 SUBSCRIBE <489>"
2011/01/17 12:52:57.413 0:11.719 Opal Liste...0xb6bbeb70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$173.68.138.36:5060>
2011/01/17 12:52:57.421 0:11.726 SIP Attempting SRV record entry 0: 213.192.59.75:5060
2011/01/17 12:52:57.421 0:11.727 SIP Transaction remote address is udp$213.192.59.75:5060
2011/01/17 12:52:57.421 0:11.727 SIP Sending PDU (835 bytes) to: rem=udp$213.192.59.75:5060,local=udp$173.68.138.36:5060,if=192.168.1.3%wlan0
PUBLISH sip:ghostnik11 iptel org SIP/2.0
CSeq: 6 PUBLISH
Via: SIP/2.0/UDP 173.68.138.36:5060;branch=z9hG4bK18a92c49-d020-e011-9c53-0013029a3fdf;rport
User-Agent: Ekiga/3.2.7
From: <sip:ghostnik11 iptel org>;tag=04592c49-d020-e011-9c53-0013029a3fdf
Call-ID: e4532949-d020-e011-9c53-0013029a3fdf@king-laptop
To: <sip:ghostnik11 iptel org>
Contact: <sip:ghostnik11@173.68.138.36>
Expires: 500
Event: presence
Content-Type: application/pidf+xml
Content-Length: 342
Max-Forwards: 70
<?xml version="1.0" encoding="UTF-8"?>
<presence xmlns="urn:ietf:params:xml:ns:pidf" entity="pres:ghostnik11 iptel org">
<tuple id="sip:ghostnik11@iptel.org_on_king-laptop">
<note>online - I'm online using Ekiga</note>
<status>
<basic>open</basic>
</status>
<contact priority="1">ghostnik11 iptel org</contact>
</tuple>
</presence>
2011/01/17 12:52:57.422 0:11.728 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:52:57.423 0:11.728 SIP Transaction timers set: retry=0.500, completion=6.000
2011/01/17 12:52:57.426 0:11.731 SIP Can't send Subscribing request for PUBLISH handler while in Subscribing state, target=sip:ghostnik11 ekiga net, id=24542049-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:57.486 0:11.792 Opal Liste...0xb6bbeb70 OpalUDP Binding to interface: 173.68.138.36:5060
2011/01/17 12:52:57.486 0:11.792 Opal Liste...0xb6bbeb70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$173.68.138.36:5060>
2011/01/17 12:52:57.487 0:11.793 Opal Liste...0xb6bbeb70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$173.68.138.36:5060,if=192.168.1.3%wlan0
SIP/2.0 200 OK
CSeq: 5 PUBLISH
Via: SIP/2.0/UDP 173.68.138.36:5060;branch=z9hG4bKd2b02449-d020-e011-9c53-0013029a3fdf;rport=5060
Server: Kamailio (1.5.3-notls (i386/linux))
From: <sip:ghostnik11 ekiga net>;tag=64602449-d020-e011-9c53-0013029a3fdf
Call-ID: 24542049-d020-e011-9c53-0013029a3fdf@king-laptop
To: <sip:ghostnik11 ekiga net>;tag=f85b0bd16aaafa8479586ac9f88b3198-7862
Expires: 495
Content-Length: 0
SIP-ETag: a.1291020865.19098.5238391.0
2011/01/17 12:52:57.488 0:11.794 Opal Liste...0xb6bbeb70 SIP Queueing PDU "5 PUBLISH <200>", transaction=z9hG4bKd2b02449-d020-e011-9c53-0013029a3fdf, token=24542049-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:57.488 0:11.794 Opal Liste...0xb6bbeb70 Opal Transport clean up on termination
2011/01/17 12:52:57.488 0:11.794 Opal Liste...0xb6bbeb70 Opal Transport Close
2011/01/17 12:52:57.488 0:11.794 Pool:0xb6b3cb70 SIP Handling PDU "5 PUBLISH <200>" for transaction=z9hG4bKd2b02449-d020-e011-9c53-0013029a3fdf
2011/01/17 12:52:57.488 0:11.794 Pool:0xb6b3cb70 SIP PUBLISH transaction id=z9hG4bKd2b02449-d020-e011-9c53-0013029a3fdf completed.
2011/01/17 12:52:57.488 0:11.794 Opal Liste...0xb6bbeb70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$173.68.138.36:5060>
2011/01/17 12:52:57.526 0:11.832 SIP Can't send Subscribing request for PUBLISH handler while in Subscribing state, target=sip:ghostnik11 ekiga net, id=24542049-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:57.550 0:11.856 Opal Liste...0xb6bbeb70 OpalUDP Binding to interface: 173.68.138.36:5060
2011/01/17 12:52:57.550 0:11.856 Opal Liste...0xb6bbeb70 SIP Waiting for PDU on udp$213.192.59.75:5060<if=udp$173.68.138.36:5060>
2011/01/17 12:52:57.551 0:11.857 Opal Liste...0xb6bbeb70 SIP PDU received: rem=udp$213.192.59.75:5060,local=udp$173.68.138.36:5060,if=192.168.1.3%wlan0
SIP/2.0 407 Proxy Authentication Required
CSeq: 6 PUBLISH
Via: SIP/2.0/UDP 173.68.138.36:5060;branch=z9hG4bK18a92c49-d020-e011-9c53-0013029a3fdf;rport=5060
Server: ser (3.1.0-pre1 (i386/linux))
From: <sip:ghostnik11 iptel org>;tag=04592c49-d020-e011-9c53-0013029a3fdf
Call-ID: e4532949-d020-e011-9c53-0013029a3fdf@king-laptop
To: <sip:ghostnik11 iptel org>;tag=b98f1230ac1a7949bf499aa7f9ea7038.05a9
Proxy-Authenticate: Digest realm="iptel.org", nonce="TTSCAk00gfhKH20SeO7KDNZwgGUQTsqn"
Warning: 392 213.192.59.75:5060 "Noisy feedback tells: pid=6550 req_src_ip=173.68.138.36 req_src_port=5060 in_uri=sip:ghostnik11 iptel org out_uri=sip:ghostnik11 iptel org via_cnt==1"
Content-Length: 0
2011/01/17 12:52:57.552 0:11.858 Opal Liste...0xb6bbeb70 SIP Queueing PDU "6 PUBLISH <407>", transaction=z9hG4bK18a92c49-d020-e011-9c53-0013029a3fdf, token=e4532949-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:57.552 0:11.858 Pool:0xaf662b70 SIP Handling PDU "6 PUBLISH <407>" for transaction=z9hG4bK18a92c49-d020-e011-9c53-0013029a3fdf
2011/01/17 12:52:57.552 0:11.858 Pool:0xaf662b70 SIP PUBLISH transaction id=z9hG4bK18a92c49-d020-e011-9c53-0013029a3fdf completed.
2011/01/17 12:52:57.552 0:11.858 Pool:0xaf662b70 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:52:57.553 0:11.858 Pool:0xaf662b70 SIP Received Proxy Authentication Required response
2011/01/17 12:52:57.553 0:11.859 Pool:0xaf662b70 SIP Located existing credentials for realm "iptel.org"
2011/01/17 12:52:57.553 0:11.859 Pool:0xaf662b70 SIP Found auth info for realm iptel.org
2011/01/17 12:52:57.553 0:11.859 Pool:0xaf662b70 SIP Changing PUBLISH handler from Unavailable to Subscribing, target=sip:ghostnik11 iptel org, id=e4532949-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:57.553 0:11.859 Opal Liste...0xb6bbeb70 Opal Transport clean up on termination
2011/01/17 12:52:57.553 0:11.859 Opal Liste...0xb6bbeb70 Opal Transport Close
2011/01/17 12:52:57.553 0:11.859 Pool:0xaf662b70 SIP Transaction created.
2011/01/17 12:52:57.553 0:11.859 Opal Liste...0xb6bbeb70 Opal Deleted transport udp$213.192.59.75:5060<if=udp$173.68.138.36:5060>
2011/01/17 12:52:57.558 0:11.863 Pool:0xaf662b70 SIP Adding authentication information for user "ghostnik11" at realm "iptel.org"
2011/01/17 12:52:57.558 0:11.863 Pool:0xaf662b70 DNS SRV Lookup iptel.org service _sip._udp
2011/01/17 12:52:57.570 0:11.875 Pool:0xaf662b70 SIP Attempting SRV record entry 0: 213.192.59.75:5060
2011/01/17 12:52:57.570 0:11.876 Pool:0xaf662b70 SIP Transaction remote address is udp$213.192.59.75:5060
2011/01/17 12:52:57.570 0:11.876 Pool:0xaf662b70 SIP Sending PDU (1039 bytes) to: rem=udp$213.192.59.75:5060,local=udp$173.68.138.36:5060,if=192.168.1.3%wlan0
PUBLISH sip:ghostnik11 iptel org SIP/2.0
CSeq: 7 PUBLISH
Via: SIP/2.0/UDP 173.68.138.36:5060;branch=z9hG4bKe07e4349-d020-e011-9c53-0013029a3fdf;rport
User-Agent: Ekiga/3.2.7
From: <sip:ghostnik11 iptel org>;tag=402e4349-d020-e011-9c53-0013029a3fdf
Call-ID: e4532949-d020-e011-9c53-0013029a3fdf@king-laptop
To: <sip:ghostnik11 iptel org>
Contact: <sip:ghostnik11@173.68.138.36>
Proxy-Authorization: Digest username="ghostnik11", realm="iptel.org", nonce="TTSCAk00gfhKH20SeO7KDNZwgGUQTsqn", uri="sip:ghostnik11 iptel org", algorithm=MD5, response="f936b5f4960f465842032abb327b8159"
Expires: 500
Event: presence
Content-Type: application/pidf+xml
Content-Length: 342
Max-Forwards: 70
<?xml version="1.0" encoding="UTF-8"?>
<presence xmlns="urn:ietf:params:xml:ns:pidf" entity="pres:ghostnik11 iptel org">
<tuple id="sip:ghostnik11@iptel.org_on_king-laptop">
<note>online - I'm online using Ekiga</note>
<status>
<basic>open</basic>
</status>
<contact priority="1">ghostnik11 iptel org</contact>
</tuple>
</presence>
2011/01/17 12:52:57.571 0:11.877 Pool:0xaf662b70 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:52:57.571 0:11.877 Pool:0xaf662b70 SIP Transaction timers set: retry=0.500, completion=6.000
2011/01/17 12:52:57.572 0:11.877 Pool:0xaf662b70 SIP Handled PDU "6 PUBLISH <407>"
2011/01/17 12:52:57.626 0:11.932 SIP Can't send Subscribing request for PUBLISH handler while in Subscribing state, target=sip:ghostnik11 ekiga net, id=24542049-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:57.727 0:12.033 SIP Can't send Subscribing request for PUBLISH handler while in Subscribing state, target=sip:ghostnik11 ekiga net, id=24542049-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:57.735 0:12.041 Opal Liste...0xb6bbeb70 OpalUDP Binding to interface: 173.68.138.36:5060
2011/01/17 12:52:57.736 0:12.041 Opal Liste...0xb6bbeb70 SIP Waiting for PDU on udp$213.192.59.75:5060<if=udp$173.68.138.36:5060>
2011/01/17 12:52:57.737 0:12.043 Opal Liste...0xb6bbeb70 SIP PDU received: rem=udp$213.192.59.75:5060,local=udp$173.68.138.36:5060,if=192.168.1.3%wlan0
SIP/2.0 404 Not found
CSeq: 3 SUBSCRIBE
Via: SIP/2.0/UDP 173.68.138.36:5060;branch=z9hG4bK70932649-d020-e011-9c53-0013029a3fdf;rport=5060
User-Agent: PBX
From: <sip:ghostnik11 iptel org>;tag=b239e948-d020-e011-9c53-0013029a3fdf
Call-ID: 0200e748-d020-e011-9c53-0013029a3fdf@king-laptop
To: <sip:ghostnik11 iptel org>;tag=as4332336d
Contact: <sip:ghostnik11@188.40.65.170:5060>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0
2011/01/17 12:52:57.738 0:12.044 Opal Liste...0xb6bbeb70 SIP Queueing PDU "3 SUBSCRIBE <404>", transaction=z9hG4bK70932649-d020-e011-9c53-0013029a3fdf, token=0200e748-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:57.738 0:12.044 Opal Liste...0xb6bbeb70 Opal Transport clean up on termination
2011/01/17 12:52:57.738 0:12.044 Opal Liste...0xb6bbeb70 Opal Transport Close
2011/01/17 12:52:57.738 0:12.044 Pool:0xaf662b70 SIP Handling PDU "3 SUBSCRIBE <404>" for transaction=z9hG4bK70932649-d020-e011-9c53-0013029a3fdf
2011/01/17 12:52:57.739 0:12.044 Pool:0xaf662b70 SIP SUBSCRIBE transaction id=z9hG4bK70932649-d020-e011-9c53-0013029a3fdf completed.
2011/01/17 12:52:57.739 0:12.045 Pool:0xaf662b70 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:52:57.738 0:12.044 Opal Liste...0xb6bbeb70 Opal Deleted transport udp$213.192.59.75:5060<if=udp$173.68.138.36:5060>
2011/01/17 12:52:57.739 0:12.045 Pool:0xaf662b70 SIP Not retrying SUBSCRIBE due to error response 404 Not Found
2011/01/17 12:52:57.739 0:12.045 Pool:0xaf662b70 SIP Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:ghostnik11 iptel org, id=0200e748-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:57.739 0:12.045 Pool:0xaf662b70 SIP Handled PDU "3 SUBSCRIBE <404>"
2011/01/17 12:52:57.798 0:12.104 Housekeeper:0xb6bffb70 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bK38844b48-d020-e011-9c53-0013029a3fdf
2011/01/17 12:52:57.818 0:12.124 Housekeeper:0xb6bffb70 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bKec894b48-d020-e011-9c53-0013029a3fdf
2011/01/17 12:52:57.827 0:12.133 SIP Can't send Subscribing request for PUBLISH handler while in Subscribing state, target=sip:ghostnik11 ekiga net, id=24542049-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:57.855 0:12.160 Opal Liste...0xb6bbeb70 OpalUDP Binding to interface: 173.68.138.36:5060
2011/01/17 12:52:57.855 0:12.161 Opal Liste...0xb6bbeb70 SIP Waiting for PDU on udp$213.192.59.75:5060<if=udp$173.68.138.36:5060>
2011/01/17 12:52:57.856 0:12.162 Opal Liste...0xb6bbeb70 SIP PDU received: rem=udp$213.192.59.75:5060,local=udp$173.68.138.36:5060,if=192.168.1.3%wlan0
SIP/2.0 501 Method Not Implemented
CSeq: 7 PUBLISH
Via: SIP/2.0/UDP 173.68.138.36:5060;branch=z9hG4bKe07e4349-d020-e011-9c53-0013029a3fdf;rport=5060
User-Agent: PBX
From: <sip:ghostnik11 iptel org>;tag=402e4349-d020-e011-9c53-0013029a3fdf
Call-ID: e4532949-d020-e011-9c53-0013029a3fdf@king-laptop
To: <sip:ghostnik11 iptel org>;tag=as714f0d6f
Accept: application/sdp
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0
2011/01/17 12:52:57.857 0:12.163 Opal Liste...0xb6bbeb70 SIP Queueing PDU "7 PUBLISH <501>", transaction=z9hG4bKe07e4349-d020-e011-9c53-0013029a3fdf, token=e4532949-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:57.857 0:12.163 Opal Liste...0xb6bbeb70 Opal Transport clean up on termination
2011/01/17 12:52:57.857 0:12.163 Opal Liste...0xb6bbeb70 Opal Transport Close
2011/01/17 12:52:57.857 0:12.163 Pool:0xaf662b70 SIP Handling PDU "7 PUBLISH <501>" for transaction=z9hG4bKe07e4349-d020-e011-9c53-0013029a3fdf
2011/01/17 12:52:57.857 0:12.163 Pool:0xaf662b70 SIP PUBLISH transaction id=z9hG4bKe07e4349-d020-e011-9c53-0013029a3fdf completed.
2011/01/17 12:52:57.858 0:12.163 Pool:0xaf662b70 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:52:57.858 0:12.164 Pool:0xaf662b70 SIP Not retrying PUBLISH due to error response 501 Not Implemented
2011/01/17 12:52:57.858 0:12.164 Pool:0xaf662b70 SIP Changing PUBLISH handler from Subscribing to Unsubscribed, target=sip:ghostnik11 iptel org, id=e4532949-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:57.858 0:12.164 Pool:0xaf662b70 SIP Handled PDU "7 PUBLISH <501>"
2011/01/17 12:52:57.857 0:12.163 Opal Liste...0xb6bbeb70 Opal Deleted transport udp$213.192.59.75:5060<if=udp$173.68.138.36:5060>
2011/01/17 12:52:57.928 0:12.234 SIP Can't send Subscribing request for PUBLISH handler while in Subscribing state, target=sip:ghostnik11 ekiga net, id=24542049-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:58.028 0:12.334 SIP Can't send Subscribing request for PUBLISH handler while in Subscribing state, target=sip:ghostnik11 ekiga net, id=24542049-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:58.126 0:12.432 Housekeeper:0xb6bffb70 Opal Transport clean up on termination
2011/01/17 12:52:58.127 0:12.432 Housekeeper:0xb6bffb70 Opal Transport Close
2011/01/17 12:52:58.127 0:12.432 Housekeeper:0xb6bffb70 Opal Transport clean up on termination
2011/01/17 12:52:58.127 0:12.433 Housekeeper:0xb6bffb70 Opal Transport Close
2011/01/17 12:52:58.127 0:12.433 Housekeeper:0xb6bffb70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$192.168.1.3:5060>
2011/01/17 12:52:58.128 0:12.434 Housekeeper:0xb6bffb70 DNS SRV Lookup ekiga.net service _sip._udp
2011/01/17 12:52:58.128 0:12.434 SIP Can't send Subscribing request for PUBLISH handler while in Subscribing state, target=sip:ghostnik11 ekiga net, id=24542049-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:58.229 0:12.535 SIP Can't send Subscribing request for PUBLISH handler while in Subscribing state, target=sip:ghostnik11 ekiga net, id=24542049-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:58.329 0:12.635 SIP Can't send Subscribing request for PUBLISH handler while in Subscribing state, target=sip:ghostnik11 ekiga net, id=24542049-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:58.430 0:12.735 SIP Can't send Subscribing request for PUBLISH handler while in Subscribing state, target=sip:ghostnik11 ekiga net, id=24542049-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:58.430 0:12.736 SIP Already doing Unsubscribed request for PUBLISH handler, target=sip:ghostnik11 iptel org, id=e4532949-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:58.430 0:12.736 Pool:0xb6b3cb70 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:52:58.431 0:12.736 Pool:0xb6b3cb70 SIP Expiry time for PUBLISH set to 495 seconds.
2011/01/17 12:52:58.431 0:12.736 Pool:0xb6b3cb70 SIP Product Info: name="Kamailio", version="", vendor="", comments="(1.5.3-notls (i386/linux))"
2011/01/17 12:52:58.431 0:12.737 Pool:0xb6b3cb70 SIP Changing PUBLISH handler from Subscribing to Subscribed, target=sip:ghostnik11 ekiga net, id=24542049-d020-e011-9c53-0013029a3fdf@king-laptop
2011/01/17 12:52:58.431 0:12.737 Pool:0xb6b3cb70 SIP Handled PDU "5 PUBLISH <200>"
2011/01/17 12:52:58.431 0:12.737 Opal Garbage:0xb6d95b70 SIP Transaction id=z9hG4bK38844b48-d020-e011-9c53-0013029a3fdf destroyed.
2011/01/17 12:52:58.691 0:12.996 Housekeeper:0xb6bffb70 SIP No SRV record found.
2011/01/17 12:52:58.691 0:12.997 Housekeeper:0xb6bffb70 OpalUDP Binding to interface: 0.0.0.0:5060
2011/01/17 12:52:58.691 0:12.997 Housekeeper:0xb6bffb70 SIP Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2011/01/17 12:52:58.692 0:12.998 Housekeeper:0xb6bffb70 OpalUDP Started connect to 86.64.162.35:5060
2011/01/17 12:52:58.693 0:12.999 Housekeeper:0xb6bffb70 Opal Transport clean up on termination
2011/01/17 12:52:58.693 0:12.999 Housekeeper:0xb6bffb70 Opal Transport Close
2011/01/17 12:52:58.693 0:12.999 Housekeeper:0xb6bffb70 Opal Transport clean up on termination
2011/01/17 12:52:58.693 0:12.999 Housekeeper:0xb6bffb70 Opal Transport Close
2011/01/17 12:52:58.693 0:12.999 Housekeeper:0xb6bffb70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$192.168.1.3:5060>
2011/01/17 12:52:58.694 0:13.000 Housekeeper:0xb6bffb70 DNS SRV Lookup ekiga.net service _sip._udp
2011/01/17 12:52:58.721 0:13.027 Housekeeper:0xb6bffb70 SIP No SRV record found.
2011/01/17 12:52:58.721 0:13.027 Housekeeper:0xb6bffb70 OpalUDP Binding to interface: 0.0.0.0:5060
2011/01/17 12:52:58.722 0:13.027 Housekeeper:0xb6bffb70 SIP Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2011/01/17 12:52:58.722 0:13.028 Housekeeper:0xb6bffb70 OpalUDP Started connect to 86.64.162.35:5060
2011/01/17 12:52:58.725 0:13.031 Housekeeper:0xb6bffb70 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bK62008748-d020-e011-9c53-0013029a3fdf
2011/01/17 12:52:58.725 0:13.031 Housekeeper:0xb6bffb70 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bK58b6d048-d020-e011-9c53-0013029a3fdf
2011/01/17 12:52:58.725 0:13.031 Housekeeper:0xb6bffb70 SIP Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK3e8fe948-d020-e011-9c53-0013029a3fdf
2011/01/17 12:52:58.725 0:13.031 Housekeeper:0xb6bffb70 SIP Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK1e0f1649-d020-e011-9c53-0013029a3fdf
2011/01/17 12:52:58.726 0:13.031 Housekeeper:0xb6bffb70 SIP Set state Terminated_Success for PUBLISH transaction id=z9hG4bK18a92c49-d020-e011-9c53-0013029a3fdf
2011/01/17 12:52:58.726 0:13.032 Opal Garbage:0xb6d95b70 SIP Transaction id=z9hG4bKec894b48-d020-e011-9c53-0013029a3fdf destroyed.
2011/01/17 12:52:58.726 0:13.032 Opal Garbage:0xb6d95b70 SIP Deleting SIPSubscribeHandler sip:ghostnik11 iptel org
2011/01/17 12:52:58.726 0:13.032 Opal Garbage:0xb6d95b70 Opal Transport clean up on termination
2011/01/17 12:52:58.726 0:13.032 Opal Garbage:0xb6d95b70 Opal Transport Close
2011/01/17 12:52:58.727 0:13.032 Opal Garbage:0xb6d95b70 Opal Transport clean up on termination
2011/01/17 12:52:58.727 0:13.032 Opal Garbage:0xb6d95b70 Opal Transport Close
2011/01/17 12:52:58.727 0:13.033 Opal Garbage:0xb6d95b70 Opal Deleted transport udp$213.192.59.75:5060<if=udp$173.68.138.36:5060>
2011/01/17 12:52:58.727 0:13.033 Opal Garbage:0xb6d95b70 SIP Deleted handler.
2011/01/17 12:52:58.728 0:13.034 Opal Garbage:0xb6d95b70 SIP Deleting SIPSubscribeHandler sip:ghostnik11 ekiga net
2011/01/17 12:52:58.728 0:13.034 Opal Garbage:0xb6d95b70 Opal Transport clean up on termination
2011/01/17 12:52:58.728 0:13.034 Opal Garbage:0xb6d95b70 Opal Transport Close
2011/01/17 12:52:58.728 0:13.034 Opal Garbage:0xb6d95b70 Opal Transport clean up on termination
2011/01/17 12:52:58.728 0:13.034 Opal Garbage:0xb6d95b70 Opal Transport Close
2011/01/17 12:52:58.728 0:13.034 Opal Garbage:0xb6d95b70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$173.68.138.36:5060>
2011/01/17 12:52:58.729 0:13.035 Opal Garbage:0xb6d95b70 SIP Deleted handler.
2011/01/17 12:52:58.729 0:13.035 Opal Garbage:0xb6d95b70 SIP Deleting SIPPublishHandler sip:ghostnik11 iptel org
2011/01/17 12:52:58.729 0:13.035 Opal Garbage:0xb6d95b70 Opal Transport clean up on termination
2011/01/17 12:52:58.729 0:13.035 Opal Garbage:0xb6d95b70 Opal Transport Close
2011/01/17 12:52:58.729 0:13.035 Opal Garbage:0xb6d95b70 Opal Transport clean up on termination
2011/01/17 12:52:58.730 0:13.035 Opal Garbage:0xb6d95b70 Opal Transport Close
2011/01/17 12:52:58.730 0:13.035 Opal Garbage:0xb6d95b70 Opal Deleted transport udp$213.192.59.75:5060<if=udp$173.68.138.36:5060>
2011/01/17 12:52:58.731 0:13.036 Opal Garbage:0xb6d95b70 SIP Deleted handler.
2011/01/17 12:52:58.739 0:13.045 Housekeeper:0xb6bffb70 SIP Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK70932649-d020-e011-9c53-0013029a3fdf
2011/01/17 12:52:58.858 0:13.164 Housekeeper:0xb6bffb70 SIP Set state Terminated_Success for PUBLISH transaction id=z9hG4bKe07e4349-d020-e011-9c53-0013029a3fdf
2011/01/17 12:52:59.127 0:13.433 Housekeeper:0xb6bffb70 SIP Set state Terminated_Success for PUBLISH transaction id=z9hG4bKd2b02449-d020-e011-9c53-0013029a3fdf
2011/01/17 12:52:59.731 0:14.037 Opal Garbage:0xb6d95b70 SIP Transaction id=z9hG4bK62008748-d020-e011-9c53-0013029a3fdf destroyed.
2011/01/17 12:52:59.732 0:14.038 Opal Garbage:0xb6d95b70 SIP Transaction id=z9hG4bK70932649-d020-e011-9c53-0013029a3fdf destroyed.
2011/01/17 12:52:59.732 0:14.038 Opal Garbage:0xb6d95b70 SIP Transaction id=z9hG4bK58b6d048-d020-e011-9c53-0013029a3fdf destroyed.
2011/01/17 12:52:59.732 0:14.038 Opal Garbage:0xb6d95b70 SIP Transaction id=z9hG4bK3e8fe948-d020-e011-9c53-0013029a3fdf destroyed.
2011/01/17 12:52:59.733 0:14.039 Opal Garbage:0xb6d95b70 SIP Transaction id=z9hG4bK1e0f1649-d020-e011-9c53-0013029a3fdf destroyed.
2011/01/17 12:52:59.733 0:14.039 Opal Garbage:0xb6d95b70 SIP Transaction id=z9hG4bK18a92c49-d020-e011-9c53-0013029a3fdf destroyed.
2011/01/17 12:52:59.734 0:14.039 Opal Garbage:0xb6d95b70 SIP Transaction id=z9hG4bKd2b02449-d020-e011-9c53-0013029a3fdf destroyed.
2011/01/17 12:52:59.734 0:14.040 Opal Garbage:0xb6d95b70 SIP Transaction id=z9hG4bKe07e4349-d020-e011-9c53-0013029a3fdf destroyed.
2011/01/17 12:53:55.014 1:09.319 Opal Liste...0xb6bbeb70 OpalUDP Binding to interface: 173.68.138.36:5060
2011/01/17 12:53:55.014 1:09.320 Opal Liste...0xb6bbeb70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$173.68.138.36:5060>
2011/01/17 12:53:55.015 1:09.321 Opal Liste...0xb6bbeb70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$173.68.138.36:5060,if=192.168.1.3%wlan0
INVITE sip:ghostnik11@173.68.138.36 SIP/2.0
Date: Mon, 17 Jan 2011 17:53:38 GMT
CSeq: 102 INVITE
P-src-ip: 208.54.45.69
Via: SIP/2.0/UDP 86.64.162.35;branch=z9hG4bK0a1.e1abe491.0
Via: SIP/2.0/UDP 67.231.245.210:29892;received=67.231.245.210;branch=z9hG4bK0ea066ec;rport=29892
User-Agent: PBX
From: "ncassanova11-200" <sip:ncassanova11-200@67.231.245.210:29892>;tag=as5199f9b5
Call-ID: 5f55fac6618cfa4e224b506765e1d29b@67.231.245.210
To: <sip:ghostnik11 ekiga net>
Contact: <sip:ncassanova11-200@67.231.245.210:29892>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Type: application/sdp
Content-Length: 410
Max-Forwards: 69
Record-Route: <sip:86.64.162.35;lr=on;did=29a.1c0d8331>
v=0
o=root 51919 51919 IN IP4 67.231.245.210
s=session
c=IN IP4 67.231.245.210
t=0 0
m=audio 41014 RTP/AVP 8 0 111 97 110 3 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:111 G726-32/8000
a=rtpmap:97 iLBC/8000
a=rtpmap:110 speex/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 38628 RTP/AVP 103 34
a=rtpmap:103 h263-1998/90000
a=rtpmap:34 H263/90000
2011/01/17 12:53:55.019 1:09.324 Opal Liste...0xb6bbeb70 SIP Sending PDU (490 bytes) to: rem=udp$86.64.162.35:5060,local=udp$173.68.138.36:5060,if=192.168.1.3%wlan0
SIP/2.0 100 Trying
CSeq: 102 INVITE
Via: SIP/2.0/UDP 86.64.162.35;branch=z9hG4bK0a1.e1abe491.0
Via: SIP/2.0/UDP 67.231.245.210:29892;received=67.231.245.210;branch=z9hG4bK0ea066ec;rport=29892
From: "ncassanova11-200" <sip:ncassanova11-200@67.231.245.210:29892>;tag=as5199f9b5
Call-ID: 5f55fac6618cfa4e224b506765e1d29b@67.231.245.210
To: <sip:ghostnik11 ekiga net>
Contact: <sip:ghostnik11@173.68.138.36>
Content-Length: 0
Record-Route: <sip:86.64.162.35;lr=on;did=29a.1c0d8331>
2011/01/17 12:53:55.019 1:09.325 Opal Liste...0xb6bbeb70 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:53:55.021 1:09.327 Opal Liste...0xb6bbeb70 OpalUDP Binding to interface: 192.168.1.3:5060
2011/01/17 12:53:55.021 1:09.327 Opal Liste...0xb6bbeb70 SIP Created transport udp$86.64.162.35:5060<if=udp$173.68.138.36:5060>
2011/01/17 12:53:55.022 1:09.327 Opal Liste...0xb6bbeb70 OpalUDP Started connect to 86.64.162.35:5060
2011/01/17 12:53:55.022 1:09.328 Opal Liste...0xb6bbeb70 Call Created Call[v2713261c1]
2011/01/17 12:53:55.022 1:09.328 Opal Liste...0xb6bbeb70 OpalCon Created connection Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf]
2011/01/17 12:53:55.022 1:09.328 Opal Liste...0xb6bbeb70 RFC2833 Handler created
2011/01/17 12:53:55.022 1:09.328 Opal Liste...0xb6bbeb70 RFC2833 Handler created
2011/01/17 12:53:55.023 1:09.329 Opal Liste...0xb6bbeb70 SIP Created connection.
2011/01/17 12:53:55.023 1:09.329 Opal Liste...0xb6bbeb70 SIP Queueing PDU "102 INVITE sip:ghostnik11@173.68.138.36", transaction=z9hG4bK0a1.e1abe491.0, token=722a846b-d020-e011-9c53-0013029a3fdf
2011/01/17 12:53:55.023 1:09.329 Opal Liste...0xb6bbeb70 PTLib No permission to set priority level 3
2011/01/17 12:53:55.024 1:09.329 Opal Liste...0xb6bbeb70 Opal Transport clean up on termination
2011/01/17 12:53:55.024 1:09.329 Opal Liste...0xb6bbeb70 Opal Transport Close
2011/01/17 12:53:55.024 1:09.330 Pool:0xaf6a3b70 SIP Handling PDU "102 INVITE sip:ghostnik11@173.68.138.36" for token=722a846b-d020-e011-9c53-0013029a3fdf
2011/01/17 12:53:55.024 1:09.330 Pool:0xaf6a3b70 SIP Initial INVITE from sip:ghostnik11@173.68.138.36
2011/01/17 12:53:55.024 1:09.330 Pool:0xaf6a3b70 SIP Set Request URI to sip:ncassanova11-200@67.231.245.210:29892
2011/01/17 12:53:55.024 1:09.330 Opal Liste...0xb6bbeb70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$173.68.138.36:5060>
2011/01/17 12:53:55.025 1:09.330 Pool:0xaf6a3b70 SIP Updating dialog tag from "" to "as5199f9b5"
2011/01/17 12:53:55.025 1:09.331 Pool:0xaf6a3b70 SIP Product Info: name="PBX", version="", vendor="", comments=""
2011/01/17 12:53:55.026 1:09.332 Pool:0xaf6a3b70 OPAL Checking incoming call for NAT: local=173.68.138.36, peer=86.64.162.35, sig=67.231.245.210
2011/01/17 12:53:55.026 1:09.332 Pool:0xaf6a3b70 OpalCon SetPhase from UninitialisedPhase to SetUpPhase for Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf]
2011/01/17 12:53:55.026 1:09.332 Pool:0xaf6a3b70 Opal::Sip::EndPoint Incoming connection
2011/01/17 12:53:55.026 1:09.332 Pool:0xaf6a3b70 OpalMan OnIncoming connection Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf]
2011/01/17 12:53:55.027 1:09.332 Pool:0xaf6a3b70 OpalCon Applying string options:
2011/01/17 12:53:55.027 1:09.332 Pool:0xaf6a3b70 Call GetOtherPartyConnection Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf]
2011/01/17 12:53:55.027 1:09.333 Pool:0xaf6a3b70 OpalMan Searching for route "sip:ghostnik11 ekiga net sip:ghostnik11@173.68.138.36"
2011/01/17 12:53:55.027 1:09.333 Pool:0xaf6a3b70 OpalMan Set up connection to "pc:*"
2011/01/17 12:53:55.028 1:09.333 Pool:0xaf6a3b70 OpalCon Created connection Call[v2713261c1]-EP<pc>[m736b49fb2]
2011/01/17 12:53:55.028 1:09.333 Pool:0xaf6a3b70 Silence Parameters set: mode=NoSilenceDetection, threshold=0, silencedb=3200, signaldb=80, period=4800
2011/01/17 12:53:55.028 1:09.334 Pool:0xaf6a3b70 Silence Handler created
2011/01/17 12:53:55.028 1:09.334 Pool:0xaf6a3b70 Echo Canceler Handler created
2011/01/17 12:53:55.028 1:09.334 Pool:0xaf6a3b70 PCSS Created PC sound system connection: token="m736b49fb2" player="EKIGA" recorder="EKIGA"
2011/01/17 12:53:55.028 1:09.334 Pool:0xaf6a3b70 SIP OnIncomingConnection succeeded for INVITE from sip:ghostnik11@173.68.138.36 for Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf]
2011/01/17 12:53:55.028 1:09.334 Pool:0xaf6a3b70 Call GetOtherPartyConnection Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf]
2011/01/17 12:53:55.029 1:09.335 Pool:0xaf6a3b70 PTLib Thread high water mark set: 13
2011/01/17 12:53:55.029 1:09.335 Pool:0xaf6a3b70 OpalMan OnAnswerCall Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf]
2011/01/17 12:53:55.029 1:09.335 CallSetup:0xaf621b70 Call OnSetUp Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf]
2011/01/17 12:53:55.029 1:09.335 Pool:0xaf6a3b70 Call OnAnswerCall Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf] caller "udp$67.231.245.210:29892"
2011/01/17 12:53:55.029 1:09.335 Pool:0xaf6a3b70 OpalCon Answering call: AnswerCallPending
2011/01/17 12:53:55.029 1:09.335 Pool:0xaf6a3b70 SIP SetAlerting
2011/01/17 12:53:55.030 1:09.336 Pool:0xaf6a3b70 SIP Sending PDU (595 bytes) to: rem=udp$86.64.162.35:5060,local=udp$173.68.138.36:5060,if=192.168.1.3%wlan0
SIP/2.0 180 Ringing
CSeq: 102 INVITE
Via: SIP/2.0/UDP 86.64.162.35;branch=z9hG4bK0a1.e1abe491.0
Via: SIP/2.0/UDP 67.231.245.210:29892;received=67.231.245.210;branch=z9hG4bK0ea066ec;rport=29892
User-Agent: Ekiga/3.2.7
From: "ncassanova11-200" <sip:ncassanova11-200@67.231.245.210:29892>;tag=as5199f9b5
Call-ID: 5f55fac6618cfa4e224b506765e1d29b@67.231.245.210
To: <sip:ghostnik11 ekiga net>;tag=722a846b-d020-e011-9c53-0013029a3fdf
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Content-Length: 0
Record-Route: <sip:86.64.162.35;lr=on;did=29a.1c0d8331>
2011/01/17 12:53:55.031 1:09.337 Pool:0xaf6a3b70 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:53:55.031 1:09.337 Pool:0xaf6a3b70 OpalCon SetPhase from SetUpPhase to AlertingPhase for Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf]
2011/01/17 12:53:55.032 1:09.337 Pool:0xaf6a3b70 SIP Handled PDU "102 INVITE sip:ghostnik11@173.68.138.36"
2011/01/17 12:53:55.032 1:09.337 CallSetup:0xaf621b70 PCSS SetUpConnection(ncassanova11-200)
2011/01/17 12:53:55.032 1:09.338 CallSetup:0xaf621b70 OpalCon SetPhase from UninitialisedPhase to AlertingPhase for Call[v2713261c1]-EP<pc>[m736b49fb2]
2011/01/17 12:53:55.032 1:09.338 CallSetup:0xaf621b70 OpalMan OnAlerting Call[v2713261c1]-EP<pc>[m736b49fb2]
2011/01/17 12:53:55.032 1:09.338 CallSetup:0xaf621b70 Call OnAlerting Call[v2713261c1]-EP<pc>[m736b49fb2]
2011/01/17 12:53:55.032 1:09.338 CallSetup:0xaf621b70 SIP SetAlerting
2011/01/17 12:53:55.032 1:09.338 CallSetup:0xaf621b70 OpalCon OnSetUpConnectionCall[v2713261c1]-EP<pc>[m736b49fb2]
2011/01/17 12:53:55.032 1:09.338 CallSetup:0xaf621b70 OpalEP OnSetUpConnection Call[v2713261c1]-EP<pc>[m736b49fb2]
2011/01/17 12:53:55.033 1:09.339 AEScheduler Adding Event incoming_call_sound 4000/256 to queue
2011/01/17 12:53:55.033 1:09.339 AudioEvent...0xb6e17b70 AEScheduler Checking pending list with 1 elements
2011/01/17 12:53:55.033 1:09.339 AudioEvent...0xb6e17b70 AEScheduler Trying to load /usr/share/sounds/ekiga/ring.wav for event incoming_call_sound
2011/01/17 12:53:55.063 1:09.368 AudioEvent...0xb6e17b70 AudioOutputCore No secondary audiooutput device defined, trying primary
2011/01/17 12:53:55.063 1:09.368 AudioEvent...0xb6e17b70 AudioOutputCore Opening device[0] with 2-44100/16
2011/01/17 12:53:55.063 1:09.369 AudioEvent...0xb6e17b70 GMAudioOutputManager_ptlib Opening Device Default (PTLIB/ALSA)
2011/01/17 12:53:55.063 1:09.369 AudioEvent...0xb6e17b70 GMAudioOutputManager_ptlib Opening Device with 2-44100/16
2011/01/17 12:53:55.068 1:09.373 AudioEvent...0xb6e17b70 ALSA SetHardwareParams Player channels=2 sample rate=44100
2011/01/17 12:53:55.068 1:09.373 AudioEvent...0xb6e17b70 ALSA Successfully set period size to 80
2011/01/17 12:53:55.068 1:09.374 AudioEvent...0xb6e17b70 ALSA Successfully set periods to 3
2011/01/17 12:53:55.069 1:09.375 AudioEvent...0xb6e17b70 ALSA parameters set ok
2011/01/17 12:53:55.070 1:09.375 AudioEvent...0xb6e17b70 ALSA Device default Opened
2011/01/17 12:53:55.104 1:09.410 AudioEvent...0xb6e17b70 ALSA Got volume 50197
2011/01/17 12:53:55.105 1:09.411 AudioEvent...0xb6e17b70 GMAudioOutputManager_ptlib Setting buffer size of device[0] 1764/4
2011/01/17 12:53:55.105 1:09.411 AudioEvent...0xb6e17b70 ALSA SetBuffers direction=Player size=1764 count=4
2011/01/17 12:53:55.105 1:09.411 AudioEvent...0xb6e17b70 ALSA SetHardwareParams Player channels=2 sample rate=44100
2011/01/17 12:53:55.105 1:09.411 AudioEvent...0xb6e17b70 ALSA Successfully set period size to 441
2011/01/17 12:53:55.106 1:09.411 AudioEvent...0xb6e17b70 ALSA Successfully set periods to 4
2011/01/17 12:53:55.108 1:09.414 AudioEvent...0xb6e17b70 ALSA parameters set ok
2011/01/17 12:53:56.528 1:10.834 AudioEvent...0xb6e17b70 AudioOutputCore Closing current device
2011/01/17 12:53:56.529 1:10.834 AudioEvent...0xb6e17b70 GMAudioOutputManager_ptlib Closing device[0] Default (PTLIB/ALSA)
2011/01/17 12:53:56.529 1:10.835 AudioEvent...0xb6e17b70 ALSA Closing default
2011/01/17 12:53:58.200 1:12.506 OpalCon SetPhase from AlertingPhase to ConnectedPhase for Call[v2713261c1]-EP<pc>[m736b49fb2]
2011/01/17 12:53:58.200 1:12.506 OpalCon OnConnected for Call[v2713261c1]-EP<pc>[m736b49fb2]
2011/01/17 12:53:58.201 1:12.506 OpalMan OnConnected Call[v2713261c1]-EP<pc>[m736b49fb2]
2011/01/17 12:53:58.201 1:12.506 Call OnConnected Call[v2713261c1]-EP<pc>[m736b49fb2]
2011/01/17 12:53:58.201 1:12.507 SIP SetConnected
2011/01/17 12:53:58.202 1:12.508 SDP Parsed connection address udp$67.231.245.210
2011/01/17 12:53:58.202 1:12.508 SDP Parsed connection address udp$67.231.245.210
2011/01/17 12:53:58.203 1:12.508 SDP Media session port=41014
2011/01/17 12:53:58.203 1:12.509 SDP Parsed media session with 7 'audio' formats
2011/01/17 12:53:58.204 1:12.510 SDP Could not find media format for "iLBC", pt=[pt=97], clock=8000
2011/01/17 12:53:58.204 1:12.510 SDP Media session port=38628
2011/01/17 12:53:58.205 1:12.510 SDP Parsed media session with 2 'video' formats
2011/01/17 12:53:58.205 1:12.511 SDP Could not find media format for "h263-1998", pt=[pt=103], clock=90000
2011/01/17 12:53:58.205 1:12.511 SDP Could not find media format for "H263", pt=H263, clock=90000
2011/01/17 12:53:58.205 1:12.511 Call IsMediaBypassPossible Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf] session 1
2011/01/17 12:53:58.205 1:12.511 OpalMan IsMediaBypassPossible: session 1
2011/01/17 12:53:58.205 1:12.511 OpalCon IsMediaBypassPossible: default returns false
2011/01/17 12:53:58.205 1:12.511 RTP Cannot find RTP session 1
2011/01/17 12:53:58.207 1:12.513 RTP_UDP Session 1, created with NAT flag set to 0
2011/01/17 12:53:58.207 1:12.513 PWLib File handle high water mark set: 59 PUDPSocket
2011/01/17 12:53:58.271 1:12.577 RTP Session 1, STUN created STUN RTP/RTCP socket pair.
2011/01/17 12:53:58.271 1:12.577 RTP_UDP Session 1 created: 173.68.138.36:5062-5063 ssrc=2502985037
2011/01/17 12:53:58.272 1:12.578 PWLib File handle low water mark set: 56 PUDPSocket
2011/01/17 12:53:58.272 1:12.578 RTP Creating new session RTP_UDP
2011/01/17 12:53:58.273 1:12.579 RTP_UDP Session 1, SetRemoteSocketInfo: data channel, new=67.231.245.210:41014, local=173.68.138.36:5062-5063, remote=0.0.0.0:0-0
2011/01/17 12:53:58.273 1:12.579 RTP_UDP Session 1, sending empty datagrams to open local Port Restricted NAT
2011/01/17 12:53:58.274 1:12.579 RTP Found existing media session 1
2011/01/17 12:53:58.274 1:12.579 SIP RTP payload type PCMA matched to codec G.711-ALaw-64k
2011/01/17 12:53:58.274 1:12.580 SIP RTP payload type PCMU matched to codec G.711-uLaw-64k
2011/01/17 12:53:58.274 1:12.580 SIP RTP payload type [pt=111] matched to codec G.726-32k
2011/01/17 12:53:58.274 1:12.580 SIP RTP payload type [pt=110] matched to codec SpeexNB
2011/01/17 12:53:58.275 1:12.580 SIP RTP payload type GSM matched to codec GSM-06.10
2011/01/17 12:53:58.275 1:12.580 MediaFormat Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,GSM-AMR,LPC-10,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,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/01/17 12:53:58.275 1:12.581 Call OpenSourceMediaStreams opening audio session 1 on Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf]
2011/01/17 12:53:58.284 1:12.590 MediaFormat Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,GSM-AMR,LPC-10,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,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/01/17 12:53:58.287 1:12.592 MediaFormat Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,GSM-AMR,LPC-10,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,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/01/17 12:53:58.288 1:12.594 Call GetMediaFormats for Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf]
G.722-64k
SpeexIETFWide-20.6k
SpeexWB
SpeexWide-20.6k
G.711-ALaw-64k
G.711-uLaw-64k
G.726-16k
G.726-24k
G.726-32k
G.726-40k
GSM-06.10
MS-GSM
SpeexIETFNarrow-8k
SpeexNB
SpeexWNarrow-8k
CELT-48K
CELT-32K
H.261
H.261-CIF
H.261-QCIF
theora
2011/01/17 12:53:58.289 1:12.595 MediaFormat Merging G.711-ALaw-64k into G.711-ALaw-64k
2011/01/17 12:53:58.289 1:12.595 MediaFormat Merging PCM-16 into G.711-ALaw-64k
2011/01/17 12:53:58.289 1:12.595 MediaFormat Merging G.711-ALaw-64k into PCM-16
2011/01/17 12:53:58.290 1:12.595 MediaFormat Changed media option "Max Bit Rate" from 128000 to 64000
2011/01/17 12:53:58.290 1:12.595 Call Selected media formats G.711-ALaw-64k -> PCM-16
2011/01/17 12:53:58.290 1:12.595 Call IsMediaBypassPossible Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf] session 1
2011/01/17 12:53:58.290 1:12.596 OpalMan IsMediaBypassPossible: session 1
2011/01/17 12:53:58.290 1:12.596 OpalCon IsMediaBypassPossible: default returns false
2011/01/17 12:53:58.290 1:12.596 RTP Found existing media session 1
2011/01/17 12:53:58.290 1:12.596 OpalMan OnOpenMediaStream Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf],OpalRTPMediaStream-Source-G.711-ALaw-64k
2011/01/17 12:53:58.290 1:12.596 OpalCon Opened source stream v2713261c1_1 with format G.711-ALaw-64k
2011/01/17 12:53:58.290 1:12.596 GMAudioOutputManager_ptlib Setting Device[0] Default (PTLIB/ALSA)
2011/01/17 12:53:58.290 1:12.596 AudioOutputCore Opening device[0] with 1-8000/16
2011/01/17 12:53:58.290 1:12.596 GMAudioOutputManager_ptlib Opening Device Default (PTLIB/ALSA)
2011/01/17 12:53:58.290 1:12.596 GMAudioOutputManager_ptlib Opening Device with 1-8000/16
2011/01/17 12:53:58.294 1:12.600 ALSA SetHardwareParams Player channels=1 sample rate=8000
2011/01/17 12:53:58.294 1:12.600 ALSA Successfully set period size to 160
2011/01/17 12:53:58.294 1:12.600 ALSA Successfully set periods to 3
2011/01/17 12:53:58.297 1:12.602 ALSA parameters set ok
2011/01/17 12:53:58.297 1:12.602 ALSA Device default Opened
2011/01/17 12:53:58.311 1:12.617 ALSA Got volume 50197
2011/01/17 12:53:58.311 1:12.617 PCSS Opened mono sound channel "EKIGA" for playing at 8.0 kHz.
2011/01/17 12:53:58.311 1:12.617 OpalMan OnOpenMediaStream Call[v2713261c1]-EP<pc>[m736b49fb2],OpalAudioMediaStream-Sink-PCM-16
2011/01/17 12:53:58.312 1:12.617 OpalCon SetPhase from ConnectedPhase to EstablishedPhase for Call[v2713261c1]-EP<pc>[m736b49fb2]
2011/01/17 12:53:58.312 1:12.617 OpalCon OnEstablished Call[v2713261c1]-EP<pc>[m736b49fb2]
2011/01/17 12:53:58.312 1:12.617 OpalCon Media stream threads started.
2011/01/17 12:53:58.312 1:12.618 OpalMan OnEstablished Call[v2713261c1]-EP<pc>[m736b49fb2]
2011/01/17 12:53:58.312 1:12.618 Call OnEstablished Call[v2713261c1]-EP<pc>[m736b49fb2]
2011/01/17 12:53:58.312 1:12.618 OpalCon Media stream threads started.
2011/01/17 12:53:58.312 1:12.618 OpalCon Opened sink stream v2713261c1_1 with format PCM-16
2011/01/17 12:53:58.312 1:12.618 Patch Created Sink: format=PCM-16
2011/01/17 12:53:58.312 1:12.618 Codec G711-ALaw-64k decoder created
2011/01/17 12:53:58.312 1:12.618 Patch Created primary codec G.711-ALaw-64k->PCM-16 with ID v2713261c1_1
2011/01/17 12:53:58.312 1:12.618 Media Audio sink data size set to 3840, buffer size set to 160 and 24 buffers.
2011/01/17 12:53:58.312 1:12.618 Media Set data size from 16 to 3840
2011/01/17 12:53:58.312 1:12.618 GMAudioOutputManager_ptlib Setting buffer size of device[0] 160/24
2011/01/17 12:53:58.312 1:12.618 ALSA SetBuffers direction=Player size=160 count=24
2011/01/17 12:53:58.312 1:12.618 ALSA SetHardwareParams Player channels=1 sample rate=8000
2011/01/17 12:53:58.313 1:12.618 ALSA Successfully set period size to 80
2011/01/17 12:53:58.313 1:12.618 ALSA Successfully set periods to 24
2011/01/17 12:53:58.315 1:12.621 ALSA parameters set ok
2011/01/17 12:53:58.316 1:12.621 Patch Added media stream sink OpalAudioMediaStream-Sink-PCM-16 using transcoder G.711-ALaw-64k->PCM-16, data size=3840
2011/01/17 12:53:58.316 1:12.621 Media RTP data size cannot be changed to 8, fixed at 2048
2011/01/17 12:53:58.316 1:12.621 OpalCon Source stream of connection Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf] uses patch Patch OpalRTPMediaStream-Source-G.711-ALaw-64k -> OpalAudioMediaStream-Sink-PCM-16
2011/01/17 12:53:58.316 1:12.622 RTPCon Adding RFC2833 receive handler
2011/01/17 12:53:58.316 1:12.622 RTPCon Adding Cisco NSE receive handler
2011/01/17 12:53:58.316 1:12.622 PCSS Adding filters to patch
2011/01/17 12:53:58.316 1:12.622 Call GetOtherPartyConnection Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf]
2011/01/17 12:53:58.316 1:12.622 Call OpenSourceMediaStreams opening audio session 1 on Call[v2713261c1]-EP<pc>[m736b49fb2]
2011/01/17 12:53:58.321 1:12.627 MediaFormat Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,GSM-AMR,LPC-10,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,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/01/17 12:53:58.321 1:12.627 MediaFormat Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,GSM-AMR,LPC-10,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,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/01/17 12:53:58.322 1:12.628 Call GetMediaFormats for Call[v2713261c1]-EP<pc>[m736b49fb2]
G.711-ALaw-64k
G.711-uLaw-64k
G.726-16k
G.726-24k
G.726-32k
G.726-40k
GSM-06.10
MS-GSM
SpeexIETFNarrow-8k
SpeexNB
SpeexWNarrow-8k
T.38
UserInput/RFC2833
2011/01/17 12:53:58.322 1:12.628 MediaFormat Merging G.711-ALaw-64k into G.711-ALaw-64k
2011/01/17 12:53:58.322 1:12.628 MediaFormat Merging G.711-ALaw-64k into PCM-16
2011/01/17 12:53:58.322 1:12.628 MediaFormat Changed media option "Max Bit Rate" from 128000 to 64000
2011/01/17 12:53:58.322 1:12.628 MediaFormat Merging PCM-16 into G.711-ALaw-64k
2011/01/17 12:53:58.323 1:12.628 Call Selected media formats PCM-16 -> G.711-ALaw-64k
2011/01/17 12:53:58.323 1:12.629 AudioInputCore Starting stream 1x8000/16
2011/01/17 12:53:58.323 1:12.629 GMAudioInputManager_ptlib Setting Device Default (PTLIB/ALSA)
2011/01/17 12:53:58.323 1:12.629 AudioInputCore Opening device with 1-8000/16
2011/01/17 12:53:58.323 1:12.629 GMAudioInputManager_ptlib Opening Device Default (PTLIB/ALSA)
2011/01/17 12:53:58.323 1:12.629 GMAudioInputManager_ptlib Opening Device with 1-8000/16
2011/01/17 12:53:58.325 1:12.631 ALSA SetHardwareParams Recorder channels=1 sample rate=8000
2011/01/17 12:53:58.326 1:12.631 ALSA Successfully set period size to 160
2011/01/17 12:53:58.326 1:12.631 ALSA Successfully set periods to 3
2011/01/17 12:53:58.342 1:12.648 ALSA parameters set ok
2011/01/17 12:53:58.343 1:12.648 ALSA Device default Opened
2011/01/17 12:53:58.364 1:12.670 ALSA Got volume 48059
2011/01/17 12:53:58.365 1:12.670 PCSS Opened mono sound channel "EKIGA" for recording at 8.0 kHz.
2011/01/17 12:53:58.365 1:12.671 OpalMan OnOpenMediaStream Call[v2713261c1]-EP<pc>[m736b49fb2],OpalAudioMediaStream-Source-PCM-16
2011/01/17 12:53:58.365 1:12.671 OpalCon Opened source stream v2713261c1_1 with format PCM-16
2011/01/17 12:53:58.365 1:12.671 Call IsMediaBypassPossible Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf] session 1
2011/01/17 12:53:58.365 1:12.671 OpalMan IsMediaBypassPossible: session 1
2011/01/17 12:53:58.365 1:12.671 OpalCon IsMediaBypassPossible: default returns false
2011/01/17 12:53:58.365 1:12.671 RTP Found existing media session 1
2011/01/17 12:53:58.366 1:12.672 OpalMan OnOpenMediaStream Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf],OpalRTPMediaStream-Sink-G.711-ALaw-64k
2011/01/17 12:53:58.366 1:12.672 OpalCon Opened sink stream v2713261c1_1 with format G.711-ALaw-64k
2011/01/17 12:53:58.366 1:12.672 Patch Created Sink: format=G.711-ALaw-64k
2011/01/17 12:53:58.366 1:12.672 Codec G711-ALaw-64k encoder created
2011/01/17 12:53:58.366 1:12.672 Patch Created primary codec PCM-16->G.711-ALaw-64k with ID v2713261c1_1
2011/01/17 12:53:58.366 1:12.672 Media RTP data size cannot be changed to 1920, fixed at 528
2011/01/17 12:53:58.366 1:12.672 Patch Added media stream sink OpalRTPMediaStream-Sink-G.711-ALaw-64k using transcoder PCM-16->G.711-ALaw-64k, data size=528
2011/01/17 12:53:58.367 1:12.672 Media Audio source data size set to 320, buffer size set to 160 and 5 buffers.
2011/01/17 12:53:58.367 1:12.672 Media Set data size from 16 to 320
2011/01/17 12:53:58.367 1:12.672 AudioInputCore Setting stream buffer size 5/160
2011/01/17 12:53:58.367 1:12.673 GMAudioInputManager_ptlib Setting buffer size to 160/5
2011/01/17 12:53:58.367 1:12.673 ALSA SetBuffers direction=Recorder size=160 count=5
2011/01/17 12:53:58.367 1:12.673 ALSA SetHardwareParams Recorder channels=1 sample rate=8000
2011/01/17 12:53:58.367 1:12.673 ALSA Successfully set period size to 80
2011/01/17 12:53:58.367 1:12.673 ALSA Successfully set periods to 5
2011/01/17 12:53:58.370 1:12.676 ALSA parameters set ok
2011/01/17 12:53:58.370 1:12.676 OpalCon Sink stream of connection Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf] uses patch Patch OpalAudioMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-G.711-ALaw-64k
2011/01/17 12:53:58.370 1:12.676 PCSS Adding filters to patch
2011/01/17 12:53:58.371 1:12.676 Media Media format updated on OpalRTPMediaStream-Source-G.711-ALaw-64k
2011/01/17 12:53:58.371 1:12.676 MediaFormat Merging G.711-ALaw-64k into PCM-16
2011/01/17 12:53:58.371 1:12.677 Patch Updated Sink: format=G.711-ALaw-64k ok=1
2011/01/17 12:53:58.371 1:12.677 SIP Using handler RTP payload [pt=101] for UserInput/RFC2833
2011/01/17 12:53:58.371 1:12.677 Call IsMediaBypassPossible Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf] session 2
2011/01/17 12:53:58.371 1:12.677 OpalMan IsMediaBypassPossible: session 2
2011/01/17 12:53:58.371 1:12.677 OpalCon IsMediaBypassPossible: default returns false
2011/01/17 12:53:58.371 1:12.677 RTP Cannot find RTP session 2
2011/01/17 12:53:58.372 1:12.678 PWLib File handle high water mark set: 68 PUDPSocket
2011/01/17 12:53:58.373 1:12.679 RTP_UDP Session 2, created with NAT flag set to 0
2011/01/17 12:53:58.374 1:12.679 PWLib File handle high water mark set: 69 PUDPSocket
2011/01/17 12:53:58.374 1:12.680 PWLib File handle high water mark set: 70 PUDPSocket
2011/01/17 12:53:58.374 1:12.680 PWLib File handle high water mark set: 71 PUDPSocket
2011/01/17 12:53:58.449 1:12.754 RTP Session 2, STUN created STUN RTP/RTCP socket pair.
2011/01/17 12:53:58.449 1:12.755 RTP_UDP Session 2 created: 173.68.138.36:5066-5067 ssrc=1905545659
2011/01/17 12:53:58.449 1:12.755 PWLib File handle low water mark set: 68 PUDPSocket
2011/01/17 12:53:58.450 1:12.755 RTP Creating new session RTP_UDP
2011/01/17 12:53:58.451 1:12.757 RTP_UDP Session 2, SetRemoteSocketInfo: data channel, new=67.231.245.210:38628, local=173.68.138.36:5066-5067, remote=0.0.0.0:0-0
2011/01/17 12:53:58.451 1:12.757 RTP_UDP Session 2, sending empty datagrams to open local Port Restricted NAT
2011/01/17 12:53:58.451 1:12.757 RTP Found existing media session 2
2011/01/17 12:53:58.451 1:12.757 MediaFormat Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,GSM-AMR,LPC-10,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,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/01/17 12:53:58.452 1:12.758 SIP Could not find media formats in SDP media description for session 2
2011/01/17 12:53:58.455 1:12.761 SIP Sending PDU (881 bytes) to: rem=udp$86.64.162.35:5060,local=udp$173.68.138.36:5060,if=192.168.1.3%wlan0
SIP/2.0 200 OK
CSeq: 102 INVITE
Via: SIP/2.0/UDP 86.64.162.35;branch=z9hG4bK0a1.e1abe491.0
Via: SIP/2.0/UDP 67.231.245.210:29892;received=67.231.245.210;branch=z9hG4bK0ea066ec;rport=29892
User-Agent: Ekiga/3.2.7
From: "ncassanova11-200" <sip:ncassanova11-200@67.231.245.210:29892>;tag=as5199f9b5
Call-ID: 5f55fac6618cfa4e224b506765e1d29b@67.231.245.210
To: <sip:ghostnik11 ekiga net>;tag=722a846b-d020-e011-9c53-0013029a3fdf
Contact: <sip:ghostnik11@173.68.138.36>
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Content-Type: application/sdp
Content-Length: 217
Record-Route: <sip:86.64.162.35;lr=on;did=29a.1c0d8331>
v=0
o=- 1295286835 1 IN IP4 173.68.138.36
s=Opal SIP Session
c=IN IP4 173.68.138.36
t=0 0
m=audio 5062 RTP/AVP 8 101
a=sendrecv
a=rtpmap:8 PCMA/8000/1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16,32,36
2011/01/17 12:53:58.456 1:12.762 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:53:58.457 1:12.762 OpalCon SetConnected for Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf]
2011/01/17 12:53:58.457 1:12.763 OpalCon SetPhase from AlertingPhase to ConnectedPhase for Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf]
2011/01/17 12:53:58.457 1:12.763 OpalCon SetPhase from ConnectedPhase to EstablishedPhase for Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf]
2011/01/17 12:53:58.457 1:12.763 OpalCon OnEstablished Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf]
2011/01/17 12:53:58.457 1:12.763 PTLib No permission to set priority level 3
2011/01/17 12:53:58.457 1:12.763 Media Starting thread Media Patch
2011/01/17 12:53:58.457 1:12.763 Media Patch:0xaf621b70 Patch Thread started for Patch OpalRTPMediaStream-Source-G.711-ALaw-64k -> OpalAudioMediaStream-Sink-PCM-16
2011/01/17 12:53:58.458 1:12.763 PWLib File handle high water mark set: 73 Thread unblock pipe
2011/01/17 12:53:58.458 1:12.763 PTLib No permission to set priority level 3
2011/01/17 12:53:58.458 1:12.764 Media Patch:0xaf621b70 InfLID Setting jitter buffer time from 160 to 4000
2011/01/17 12:53:58.459 1:12.764 PTLib Thread high water mark set: 14
2011/01/17 12:53:58.459 1:12.765 Media Starting thread Media Patch
2011/01/17 12:53:58.459 1:12.765 OpalCon Media stream threads started.
2011/01/17 12:53:58.459 1:12.765 OpalMan OnEstablished Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf]
2011/01/17 12:53:58.459 1:12.765 Media Patch:0xa1d9ab70 Patch Thread started for Patch OpalAudioMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-G.711-ALaw-64k
2011/01/17 12:53:58.459 1:12.765 RTP Found existing RTP session 1
2011/01/17 12:53:58.459 1:12.765 Call OnEstablished Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf]
2011/01/17 12:53:58.460 1:12.765 OpalCon Media stream threads started.
2011/01/17 12:53:58.460 1:12.766 AEScheduler Removing Event incoming_call_sound from queue
2011/01/17 12:53:58.461 1:12.767 Media Patch:0xaf621b70 RTP Opal jitter buffer created: size=101 delay=20-500 ms /160 (20ms) obj=0xa41e948
2011/01/17 12:53:58.461 1:12.767 Media Patch:0xaf621b70 PWLib File handle high water mark set: 75 Thread unblock pipe
2011/01/17 12:53:58.461 1:12.767 Media Patch:0xaf621b70 PTLib Thread high water mark set: 15
2011/01/17 12:53:58.462 1:12.767 RTP Jitter:0xa1d59b70 RTP Jitter RTP receive thread started: 0xa41e948
2011/01/17 12:53:58.464 1:12.770 AEScheduler Removing Event incoming_call_sound from queue
2011/01/17 12:53:58.464 1:12.770 AEScheduler Removing Event ring_tone_sound from queue
2011/01/17 12:53:58.477 1:12.783 Media Patch:0xa1d9ab70 RTP Session 1, first sent data: ver=2 pt=PCMA psz=160 m=1 x=0 seq=11138 ts=0 src=9530854d ccnt=0
2011/01/17 12:53:58.749 1:13.054 Opal Liste...0xb6bbeb70 PWLib File handle high water mark set: 76 PUDPSocket
2011/01/17 12:53:58.749 1:13.055 Opal Liste...0xb6bbeb70 OpalUDP Binding to interface: 173.68.138.36:5060
2011/01/17 12:53:58.749 1:13.055 Opal Liste...0xb6bbeb70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$173.68.138.36:5060>
2011/01/17 12:53:58.750 1:13.056 Opal Liste...0xb6bbeb70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$173.68.138.36:5060,if=192.168.1.3%wlan0
ACK sip:ghostnik11@173.68.138.36 SIP/2.0
CSeq: 102 ACK
Via: SIP/2.0/UDP 86.64.162.35;branch=z9hG4bK0a1.e1abe491.3
Via: SIP/2.0/UDP 67.231.245.210:29892;received=67.231.245.210;branch=z9hG4bK6f45e335;rport=29892
User-Agent: PBX
From: "ncassanova11-200" <sip:ncassanova11-200@67.231.245.210:29892>;tag=as5199f9b5
Call-ID: 5f55fac6618cfa4e224b506765e1d29b@67.231.245.210
To: <sip:ghostnik11 ekiga net>;tag=722a846b-d020-e011-9c53-0013029a3fdf
Contact: <sip:ncassanova11-200@67.231.245.210:29892>
Content-Length: 0
Max-Forwards: 69
2011/01/17 12:53:58.752 1:13.057 Opal Liste...0xb6bbeb70 SIP Queueing PDU "102 ACK sip:ghostnik11@173.68.138.36", transaction=z9hG4bK0a1.e1abe491.3, token=722a846b-d020-e011-9c53-0013029a3fdf
2011/01/17 12:53:58.752 1:13.057 Opal Liste...0xb6bbeb70 Opal Transport clean up on termination
2011/01/17 12:53:58.752 1:13.058 Opal Liste...0xb6bbeb70 Opal Transport Close
2011/01/17 12:53:58.752 1:13.058 Pool:0xaf6a3b70 SIP Handling PDU "102 ACK sip:ghostnik11@173.68.138.36" for token=722a846b-d020-e011-9c53-0013029a3fdf
2011/01/17 12:53:58.752 1:13.058 Pool:0xaf6a3b70 SIP ACK received: EstablishedPhase
2011/01/17 12:53:58.752 1:13.058 Pool:0xaf6a3b70 OpalCon Media stream threads started.
2011/01/17 12:53:58.752 1:13.058 Pool:0xaf6a3b70 SIP Handled PDU "102 ACK sip:ghostnik11@173.68.138.36"
2011/01/17 12:53:58.752 1:13.058 Opal Liste...0xb6bbeb70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$173.68.138.36:5060>
2011/01/17 12:53:59.034 1:13.339 AudioEvent...0xb6e17b70 AEScheduler Checking pending list with 0 elements
2011/01/17 12:53:59.402 1:13.708 Media Patch:0xa1d9ab70 RTP Session 1, transmit statistics: packets=51 octets=8160 avgTime=18 maxTime=21 minTime=10
2011/01/17 12:54:00.398 1:14.704 Media Patch:0xa1d9ab70 RTP Session 1, transmit statistics: packets=101 octets=16160 avgTime=19 maxTime=25 minTime=10
2011/01/17 12:54:01.393 1:15.699 Media Patch:0xa1d9ab70 RTP Session 1, transmit statistics: packets=151 octets=24160 avgTime=19 maxTime=26 minTime=10
2011/01/17 12:54:01.615 1:15.921 RTP Jitter:0xa1d59b70 RTP Session 1, first receive data: ver=2 pt=PCMA psz=160 m=0 x=0 seq=39056 ts=0 src=4a442a6 ccnt=0
2011/01/17 12:54:01.680 1:15.986 Media Patch:0xaf621b70 RTP Jitter buffer length exceeded
2011/01/17 12:54:01.681 1:15.986 Media Patch:0xaf621b70 RTP Jitter buffer size increased to 1440 (180ms)
2011/01/17 12:54:01.753 1:16.059 Media Patch:0xaf621b70 RTP Jitter buffer length exceeded
2011/01/17 12:54:01.753 1:16.059 Media Patch:0xaf621b70 RTP Jitter buffer size increased to 1600 (200ms)
2011/01/17 12:54:02.389 1:16.695 Media Patch:0xa1d9ab70 RTP Session 1, transmit statistics: packets=201 octets=32160 avgTime=19 maxTime=25 minTime=10
2011/01/17 12:54:02.453 1:16.758 RTP Jitter:0xa1d59b70 RTP Session 1, receive statistics: packets=51 octets=8160 lost=0 tooLate=0 order=0 avgTime=16 maxTime=60 minTime=0 jitter=9 maxJitter=10
2011/01/17 12:54:03.239 1:17.545 Media Patch:0xaf621b70 RTP Jitter buffer length exceeded
2011/01/17 12:54:03.239 1:17.545 Media Patch:0xaf621b70 RTP Jitter buffer size increased to 1760 (220ms)
2011/01/17 12:54:03.329 1:17.635 RTP Jitter:0xa1d59b70 RTP Session 1, adjusting sequence numbers to expect 39152 ssrc=77873830
2011/01/17 12:54:03.384 1:17.690 Media Patch:0xa1d9ab70 RTP Session 1, transmit statistics: packets=251 octets=40160 avgTime=19 maxTime=26 minTime=10
2011/01/17 12:54:03.501 1:17.807 RTP Jitter:0xa1d59b70 RTP Session 1, dropped 1 packet(s) at 39159, ssrc=77873830
2011/01/17 12:54:03.535 1:17.841 RTP Jitter:0xa1d59b70 RTP Session 1, receive statistics: packets=103 octets=16480 lost=1 tooLate=0 order=0 avgTime=19 maxTime=45 minTime=4 jitter=11 maxJitter=11
2011/01/17 12:54:03.770 1:18.076 RTP Jitter:0xa1d59b70 RTP Session 1, dropped 1 packet(s) at 39172, ssrc=77873830
2011/01/17 12:54:03.801 1:18.107 RTP Jitter:0xa1d59b70 RTP Session 1, dropped 1 packet(s) at 39174, ssrc=77873830
2011/01/17 12:54:04.389 1:18.695 Media Patch:0xa1d9ab70 RTP Session 1, transmit statistics: packets=301 octets=48160 avgTime=20 maxTime=26 minTime=10
2011/01/17 12:54:04.587 1:18.893 RTP Jitter:0xa1d59b70 RTP Session 1, receive statistics: packets=155 octets=24800 lost=3 tooLate=0 order=0 avgTime=19 maxTime=37 minTime=4 jitter=11 maxJitter=12
2011/01/17 12:54:05.321 1:19.627 RTP Jitter:0xa1d59b70 RTP Session 1, dropped 1 packet(s) at 39251, ssrc=77873830
2011/01/17 12:54:05.390 1:19.695 Media Patch:0xa1d9ab70 RTP Session 1, transmit statistics: packets=351 octets=56160 avgTime=20 maxTime=26 minTime=10
2011/01/17 12:54:05.641 1:19.946 RTP Jitter:0xa1d59b70 RTP Session 1, receive statistics: packets=206 octets=32960 lost=4 tooLate=0 order=0 avgTime=20 maxTime=53 minTime=0 jitter=14 maxJitter=14
2011/01/17 12:54:05.757 1:20.063 RTP Jitter:0xa1d59b70 RTP Session 1, dropped 1 packet(s) at 39272, ssrc=77873830
2011/01/17 12:54:06.385 1:20.691 Media Patch:0xa1d9ab70 RTP Session 1, transmit statistics: packets=401 octets=64160 avgTime=19 maxTime=26 minTime=9
2011/01/17 12:54:06.671 1:20.976 RTP Jitter:0xa1d59b70 RTP Session 1, receive statistics: packets=257 octets=41120 lost=5 tooLate=0 order=0 avgTime=19 maxTime=55 minTime=0 jitter=12 maxJitter=15
2011/01/17 12:54:07.017 1:21.323 RTP Jitter:0xa1d59b70 RTP Session 1, dropped 1 packet(s) at 39335, ssrc=77873830
2011/01/17 12:54:07.380 1:21.686 Media Patch:0xa1d9ab70 RTP Session 1, transmit statistics: packets=451 octets=72160 avgTime=19 maxTime=26 minTime=10
2011/01/17 12:54:07.709 1:22.014 RTP Jitter:0xa1d59b70 RTP Session 1, receive statistics: packets=308 octets=49280 lost=6 tooLate=0 order=0 avgTime=20 maxTime=46 minTime=1 jitter=11 maxJitter=15
2011/01/17 12:54:08.248 1:22.554 Media Patch:0xaf621b70 RTP Jitter buffer target size decreased to 880 (110ms)
2011/01/17 12:54:08.272 1:22.578 Media Patch:0xaf621b70 RTP Jitter buffer size decreased to 880 (110ms)
2011/01/17 12:54:08.381 1:22.687 Media Patch:0xa1d9ab70 RTP Session 1, transmit statistics: packets=501 octets=80160 avgTime=20 maxTime=25 minTime=10
2011/01/17 12:54:08.710 1:23.016 RTP Jitter:0xa1d59b70 RTP Session 1, receive statistics: packets=358 octets=57280 lost=6 tooLate=0 order=0 avgTime=20 maxTime=68 minTime=1 jitter=15 maxJitter=15
2011/01/17 12:54:09.381 1:23.687 Media Patch:0xa1d9ab70 RTP Session 1, transmit statistics: packets=551 octets=88160 avgTime=20 maxTime=25 minTime=10
2011/01/17 12:54:09.732 1:24.037 RTP Jitter:0xa1d59b70 RTP Session 1, receive statistics: packets=408 octets=65280 lost=6 tooLate=0 order=0 avgTime=20 maxTime=49 minTime=0 jitter=14 maxJitter=16
2011/01/17 12:54:10.209 1:24.515 RTP Jitter:0xa1d59b70 RTP Session 1, SentSenderReport: ssrc=2502985037 ntp=3504275650.899030486 rtp=0 psent=592 osent=94720
2011/01/17 12:54:10.209 1:24.515 RTP Jitter:0xa1d59b70 RTP Session 1, SentReceiverReport: ssrc=77873830 fraction=0 lost=6 last_seq=0 jitter=92 lsr=0 dlsr=0
2011/01/17 12:54:10.209 1:24.515 RTP Jitter:0xa1d59b70 RTP Session 1, sending SDES: king@king-laptop
2011/01/17 12:54:10.382 1:24.687 Media Patch:0xa1d9ab70 RTP Session 1, transmit statistics: packets=601 octets=96160 avgTime=20 maxTime=26 minTime=10
2011/01/17 12:54:10.724 1:25.029 RTP Jitter:0xa1d59b70 RTP Session 1, receive statistics: packets=458 octets=73280 lost=6 tooLate=0 order=0 avgTime=19 maxTime=52 minTime=0 jitter=9 maxJitter=17
2011/01/17 12:54:10.883 1:25.189 RTP Jitter:0xa1d59b70 RTP Session 1, dropped 1 packet(s) at 39529, ssrc=77873830
2011/01/17 12:54:10.924 1:25.229 RTP Jitter:0xa1d59b70 RTP Session 1, dropped 1 packet(s) at 39531, ssrc=77873830
2011/01/17 12:54:11.382 1:25.687 Media Patch:0xa1d9ab70 RTP Session 1, transmit statistics: packets=651 octets=104160 avgTime=20 maxTime=21 minTime=15
2011/01/17 12:54:11.786 1:26.092 RTP Jitter:0xa1d59b70 RTP Session 1, receive statistics: packets=510 octets=81600 lost=8 tooLate=0 order=0 avgTime=20 maxTime=38 minTime=0 jitter=10 maxJitter=17
2011/01/17 12:54:12.381 1:26.687 Media Patch:0xa1d9ab70 RTP Session 1, transmit statistics: packets=701 octets=112160 avgTime=20 maxTime=26 minTime=10
2011/01/17 12:54:12.801 1:27.107 RTP Jitter:0xa1d59b70 RTP Session 1, receive statistics: packets=560 octets=89600 lost=8 tooLate=0 order=0 avgTime=20 maxTime=57 minTime=2 jitter=9 maxJitter=17
2011/01/17 12:54:13.383 1:27.688 Media Patch:0xa1d9ab70 RTP Session 1, transmit statistics: packets=751 octets=120160 avgTime=20 maxTime=26 minTime=10
2011/01/17 12:54:13.550 1:27.855 Media Patch:0xaf621b70 RTP Jitter buffer target size decreased to 740 (92ms)
2011/01/17 12:54:13.574 1:27.880 Media Patch:0xaf621b70 RTP Jitter buffer size decreased to 740 (92ms)
2011/01/17 12:54:13.581 1:27.887 RTP Jitter:0xa1d59b70 RTP Session 1, dropped 1 packet(s) at 39664, ssrc=77873830
2011/01/17 12:54:13.869 1:28.174 RTP Jitter:0xa1d59b70 RTP Session 1, receive statistics: packets=611 octets=97760 lost=9 tooLate=0 order=0 avgTime=20 maxTime=45 minTime=1 jitter=10 maxJitter=17
2011/01/17 12:54:14.378 1:28.683 Media Patch:0xa1d9ab70 RTP Session 1, transmit statistics: packets=801 octets=128160 avgTime=19 maxTime=26 minTime=10
2011/01/17 12:54:14.831 1:29.137 RTP Jitter:0xa1d59b70 RTP Session 1, receive statistics: packets=661 octets=105760 lost=9 tooLate=0 order=0 avgTime=19 maxTime=61 minTime=0 jitter=11 maxJitter=17
2011/01/17 12:54:14.883 1:29.189 RTP Jitter:0xa1d59b70 RTP Session 1, dropped 1 packet(s) at 39730, ssrc=77873830
2011/01/17 12:54:15.378 1:29.684 Media Patch:0xa1d9ab70 RTP Session 1, transmit statistics: packets=851 octets=136160 avgTime=20 maxTime=26 minTime=10
2011/01/17 12:54:15.883 1:30.189 RTP Jitter:0xa1d59b70 RTP Session 1, receive statistics: packets=712 octets=113920 lost=10 tooLate=0 order=0 avgTime=20 maxTime=44 minTime=0 jitter=11 maxJitter=17
2011/01/17 12:54:16.378 1:30.683 RTP Jitter:0xa1d59b70 RTP Session 1, dropped 1 packet(s) at 39801, ssrc=77873830
2011/01/17 12:54:16.384 1:30.690 Media Patch:0xa1d9ab70 RTP Session 1, transmit statistics: packets=901 octets=144160 avgTime=20 maxTime=25 minTime=11
2011/01/17 12:54:16.914 1:31.220 RTP Jitter:0xa1d59b70 RTP Session 1, receive statistics: packets=763 octets=122080 lost=11 tooLate=0 order=0 avgTime=19 maxTime=76 minTime=0 jitter=9 maxJitter=17
2011/01/17 12:54:17.030 1:31.335 Opal Liste...0xb6bbeb70 OpalUDP Binding to interface: 173.68.138.36:5060
2011/01/17 12:54:17.030 1:31.336 Opal Liste...0xb6bbeb70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$173.68.138.36:5060>
2011/01/17 12:54:17.031 1:31.337 Opal Liste...0xb6bbeb70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$173.68.138.36:5060,if=192.168.1.3%wlan0
NOTIFY sip:slim@173.68.138.36 SIP/2.0
CSeq: 2 NOTIFY
Via: SIP/2.0/UDP 86.64.162.35;branch=z9hG4bK2ad.4c162b41.0
User-Agent: Kamailio (1.5.3-notls (i386/linux))
From: sip:slim ekiga net;tag=f85b0bd16aaafa8479586ac9f88b3198-8912
Call-ID: e6d66a9c-cf20-e011-9113-0013029a3fdf@king-laptop
To: sip:king@173.68.138.36;tag=5e5a6f9c-cf20-e011-9113-0013029a3fdf
Contact: <sip:86.64.162.35:5060>
Subscription-State: terminated;reason=timeout
Event: dialog;sla
Content-Length: 0
Max-Forwards: 70
2011/01/17 12:54:17.032 1:31.338 Opal Liste...0xb6bbeb70 SIP Received NOTIFY dialog;sla
2011/01/17 12:54:17.032 1:31.338 Opal Liste...0xb6bbeb70 SIP Could not find a SUBSCRIBE corresponding to the NOTIFY dialog;sla
2011/01/17 12:54:17.035 1:31.341 Opal Liste...0xb6bbeb70 SIP Sending PDU (377 bytes) to: rem=udp$86.64.162.35:5060,local=udp$173.68.138.36:5060,if=192.168.1.3%wlan0
SIP/2.0 481 Call Leg/Transaction Does Not Exist
CSeq: 2 NOTIFY
Via: SIP/2.0/UDP 86.64.162.35;branch=z9hG4bK2ad.4c162b41.0
From: sip:slim ekiga net;tag=f85b0bd16aaafa8479586ac9f88b3198-8912
Call-ID: e6d66a9c-cf20-e011-9113-0013029a3fdf@king-laptop
To: sip:king@173.68.138.36;tag=5e5a6f9c-cf20-e011-9113-0013029a3fdf
Contact: <sip:king@173.68.138.36>
Content-Length: 0
2011/01/17 12:54:17.036 1:31.342 Opal Liste...0xb6bbeb70 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:54:17.036 1:31.342 Opal Liste...0xb6bbeb70 Opal Transport clean up on termination
2011/01/17 12:54:17.036 1:31.342 Opal Liste...0xb6bbeb70 Opal Transport Close
2011/01/17 12:54:17.036 1:31.342 Opal Liste...0xb6bbeb70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$173.68.138.36:5060>
2011/01/17 12:54:17.384 1:31.689 Media Patch:0xa1d9ab70 RTP Session 1, transmit statistics: packets=951 octets=152160 avgTime=19 maxTime=25 minTime=10
2011/01/17 12:54:17.870 1:32.175 RTP Jitter:0xa1d59b70 RTP Session 1, dropped 1 packet(s) at 39875, ssrc=77873830
2011/01/17 12:54:17.945 1:32.251 Media Patch:0xaf621b70 RTP Jitter buffer length exceeded
2011/01/17 12:54:17.945 1:32.251 Media Patch:0xaf621b70 RTP Jitter buffer size increased to 800 (100ms)
2011/01/17 12:54:17.959 1:32.265 RTP Jitter:0xa1d59b70 RTP Session 1, receive statistics: packets=814 octets=130240 lost=12 tooLate=0 order=0 avgTime=19 maxTime=58 minTime=0 jitter=9 maxJitter=17
2011/01/17 12:54:18.070 1:32.376 Media Patch:0xaf621b70 RTP Jitter buffer length exceeded
2011/01/17 12:54:18.070 1:32.376 Media Patch:0xaf621b70 RTP Jitter buffer size increased to 960 (120ms)
2011/01/17 12:54:18.384 1:32.690 Media Patch:0xa1d9ab70 RTP Session 1, transmit statistics: packets=1001 octets=160160 avgTime=20 maxTime=26 minTime=10
2011/01/17 12:54:18.447 1:32.753 Media Patch:0xaf621b70 RTP Jitter buffer length exceeded
2011/01/17 12:54:18.447 1:32.753 Media Patch:0xaf621b70 RTP Jitter buffer size increased to 2080 (260ms)
2011/01/17 12:54:18.545 1:32.850 Media Patch:0xaf621b70 RTP Jitter buffer length exceeded
2011/01/17 12:54:18.545 1:32.851 Media Patch:0xaf621b70 RTP Jitter buffer size increased to 2240 (280ms)
2011/01/17 12:54:18.962 1:33.268 RTP Jitter:0xa1d59b70 RTP Session 1, receive statistics: packets=864 octets=138240 lost=12 tooLate=0 order=0 avgTime=20 maxTime=203 minTime=0 jitter=10 maxJitter=29
2011/01/17 12:54:19.375 1:33.681 Media Patch:0xa1d9ab70 RTP Session 1, transmit statistics: packets=1051 octets=168160 avgTime=19 maxTime=25 minTime=10
2011/01/17 12:54:19.979 1:34.284 RTP Jitter:0xa1d59b70 RTP Session 1, receive statistics: packets=914 octets=146240 lost=12 tooLate=0 order=0 avgTime=20 maxTime=44 minTime=1 jitter=11 maxJitter=29
2011/01/17 12:54:20.378 1:34.683 Media Patch:0xa1d9ab70 RTP Session 1, transmit statistics: packets=1101 octets=176160 avgTime=20 maxTime=25 minTime=10
2011/01/17 12:54:20.941 1:35.247 RTP Jitter:0xa1d59b70 RTP Session 1, receive statistics: packets=964 octets=154240 lost=12 tooLate=0 order=0 avgTime=19 maxTime=50 minTime=1 jitter=7 maxJitter=29
2011/01/17 12:54:21.382 1:35.688 Media Patch:0xa1d9ab70 RTP Session 1, transmit statistics: packets=1151 octets=184160 avgTime=20 maxTime=25 minTime=10
2011/01/17 12:54:21.933 1:36.238 RTP Jitter:0xa1d59b70 RTP Session 1, receive statistics: packets=1014 octets=162240 lost=12 tooLate=0 order=0 avgTime=19 maxTime=48 minTime=0 jitter=10 maxJitter=29
2011/01/17 12:54:22.376 1:36.682 Media Patch:0xa1d9ab70 RTP Session 1, transmit statistics: packets=1201 octets=192160 avgTime=19 maxTime=25 minTime=10
2011/01/17 12:54:22.619 1:36.925 Media Patch:0xa1d9ab70 RTP Session 1, SentSenderReport: ssrc=2502985037 ntp=3504275662.2661721780 rtp=0 psent=1213 osent=194080
2011/01/17 12:54:22.620 1:36.925 Media Patch:0xa1d9ab70 RTP Session 1, SentReceiverReport: ssrc=77873830 fraction=2 lost=12 last_seq=39494 jitter=82 lsr=0 dlsr=0
2011/01/17 12:54:22.620 1:36.926 Media Patch:0xa1d9ab70 RTP Session 1, sending SDES: king@king-laptop
2011/01/17 12:54:22.989 1:37.295 Opal Liste...0xb6bbeb70 OpalUDP Binding to interface: 173.68.138.36:5060
2011/01/17 12:54:22.989 1:37.295 Opal Liste...0xb6bbeb70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$173.68.138.36:5060>
2011/01/17 12:54:22.990 1:37.296 Opal Liste...0xb6bbeb70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$173.68.138.36:5060,if=192.168.1.3%wlan0
BYE sip:ghostnik11@173.68.138.36 SIP/2.0
CSeq: 103 BYE
Via: SIP/2.0/UDP 86.64.162.35;branch=z9hG4bK1a1.9a86e833.0
Via: SIP/2.0/UDP 67.231.245.210:29892;received=67.231.245.210;branch=z9hG4bK66af2410;rport=29892
User-Agent: PBX
From: "ncassanova11-200" <sip:ncassanova11-200@67.231.245.210:29892>;tag=as5199f9b5
Call-ID: 5f55fac6618cfa4e224b506765e1d29b@67.231.245.210
To: <sip:ghostnik11 ekiga net>;tag=722a846b-d020-e011-9c53-0013029a3fdf
Contact: <sip:ncassanova11-200@67.231.245.210:29892>
Content-Length: 0
Max-Forwards: 69
2011/01/17 12:54:22.991 1:37.297 Opal Liste...0xb6bbeb70 SIP Queueing PDU "103 BYE sip:ghostnik11@173.68.138.36", transaction=z9hG4bK1a1.9a86e833.0, token=722a846b-d020-e011-9c53-0013029a3fdf
2011/01/17 12:54:22.992 1:37.297 Pool:0xaf6a3b70 SIP Handling PDU "103 BYE sip:ghostnik11@173.68.138.36" for token=722a846b-d020-e011-9c53-0013029a3fdf
2011/01/17 12:54:22.992 1:37.298 Pool:0xaf6a3b70 SIP BYE received for call 5f55fac6618cfa4e224b506765e1d29b@67.231.245.210
2011/01/17 12:54:22.992 1:37.298 Pool:0xaf6a3b70 SIP Sending PDU (426 bytes) to: rem=udp$86.64.162.35:5060,local=udp$173.68.138.36:5060,if=192.168.1.3%wlan0
SIP/2.0 200 OK
CSeq: 103 BYE
Via: SIP/2.0/UDP 86.64.162.35;branch=z9hG4bK1a1.9a86e833.0
Via: SIP/2.0/UDP 67.231.245.210:29892;received=67.231.245.210;branch=z9hG4bK66af2410;rport=29892
From: "ncassanova11-200" <sip:ncassanova11-200@67.231.245.210:29892>;tag=as5199f9b5
Call-ID: 5f55fac6618cfa4e224b506765e1d29b@67.231.245.210
To: <sip:ghostnik11 ekiga net>;tag=722a846b-d020-e011-9c53-0013029a3fdf
Content-Length: 0
2011/01/17 12:54:22.993 1:37.299 Pool:0xaf6a3b70 OpalUDP Setting interface to 192.168.1.3%wlan0
2011/01/17 12:54:22.994 1:37.299 Pool:0xaf6a3b70 SIP Set Request URI to sip:ncassanova11-200@67.231.245.210:29892
2011/01/17 12:54:22.994 1:37.300 Pool:0xaf6a3b70 SIP Product Info: name="PBX", version="", vendor="", comments=""
2011/01/17 12:54:22.994 1:37.300 Pool:0xaf6a3b70 OpalCon SetPhase from EstablishedPhase to ReleasingPhase for Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf]
2011/01/17 12:54:22.994 1:37.300 Pool:0xaf6a3b70 OpalCon Releasing Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf]
2011/01/17 12:54:22.994 1:37.300 Pool:0xaf6a3b70 OpalCon Call end reason for Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf] set to EndedByRemoteUser
2011/01/17 12:54:22.995 1:37.300 Pool:0xaf6a3b70 PWLib File handle high water mark set: 77 Thread unblock pipe
2011/01/17 12:54:22.995 1:37.300 Pool:0xaf6a3b70 PTLib Thread high water mark set: 16
2011/01/17 12:54:22.995 1:37.301 OnRelease:0xa659cb70 SIP OnReleased: Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf], phase = ReleasingPhase
2011/01/17 12:54:22.995 1:37.301 OnRelease:0xa659cb70 OpalCon SetPhase from ReleasingPhase to ReleasingPhase for Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf]
2011/01/17 12:54:22.995 1:37.301 Pool:0xaf6a3b70 SIP Handled PDU "103 BYE sip:ghostnik11@173.68.138.36"
2011/01/17 12:54:22.995 1:37.301 Opal Liste...0xb6bbeb70 Opal Transport clean up on termination
2011/01/17 12:54:22.995 1:37.301 Opal Liste...0xb6bbeb70 Opal Transport Close
2011/01/17 12:54:22.996 1:37.301 OnRelease:0xa659cb70 Media Closing RTP for OpalRTPMediaStream-Source-G.711-ALaw-64k
2011/01/17 12:54:22.996 1:37.301 OnRelease:0xa659cb70 RTP_UDP Session 1, Shutting down read.
2011/01/17 12:54:22.996 1:37.302 OnRelease:0xa659cb70 Media Closing stream OpalRTPMediaStream-Source-G.711-ALaw-64k
2011/01/17 12:54:22.996 1:37.302 OnRelease:0xa659cb70 Media Disconnecting OpalRTPMediaStream-Source-G.711-ALaw-64k from patch thread Patch OpalRTPMediaStream-Source-G.711-ALaw-64k -> OpalAudioMediaStream-Sink-PCM-16
2011/01/17 12:54:22.996 1:37.302 OnRelease:0xa659cb70 Patch Closing media patch Patch OpalRTPMediaStream-Source-G.711-ALaw-64k -> OpalAudioMediaStream-Sink-PCM-16
2011/01/17 12:54:22.996 1:37.302 Opal Liste...0xb6bbeb70 PWLib File handle high water mark set: 78 PUDPSocket
2011/01/17 12:54:22.995 1:37.301 Opal Liste...0xb6bbeb70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$173.68.138.36:5060>
2011/01/17 12:54:22.997 1:37.303 RTP Jitter:0xa1d59b70 RTP_UDP Session 1, Read shutdown.
2011/01/17 12:54:22.997 1:37.303 RTP Jitter:0xa1d59b70 RTP Jitter RTP receive thread ended
2011/01/17 12:54:22.997 1:37.303 RTP Jitter:0xa1d59b70 RTP Jitter RTP receive thread finished: 0xa41e948
2011/01/17 12:54:23.003 1:37.308 Media Patch:0xa1d9ab70 RTP_UDP Session 1, data port on remote not ready 1 time
2011/01/17 12:54:23.004 1:37.310 Media Patch:0xaf621b70 Patch Thread ended for Patch OpalRTPMediaStream-Source-G.711-ALaw-64k -> OpalAudioMediaStream-Sink-PCM-16
2011/01/17 12:54:23.004 1:37.310 OnRelease:0xa659cb70 AudioOutputCore Closing current device
2011/01/17 12:54:23.004 1:37.310 OnRelease:0xa659cb70 GMAudioOutputManager_ptlib Closing device[0] Default (PTLIB/ALSA)
2011/01/17 12:54:23.004 1:37.310 OnRelease:0xa659cb70 ALSA Closing default
2011/01/17 12:54:23.007 1:37.313 OnRelease:0xa659cb70 GMAudioOutputManager_ptlib Setting Device[0] Default (PTLIB/ALSA)
2011/01/17 12:54:23.007 1:37.313 OnRelease:0xa659cb70 Media Closing stream OpalAudioMediaStream-Sink-PCM-16
2011/01/17 12:54:23.007 1:37.313 OnRelease:0xa659cb70 Media Disconnecting OpalAudioMediaStream-Sink-PCM-16 from patch thread Patch OpalRTPMediaStream-Source-G.711-ALaw-64k -> OpalAudioMediaStream-Sink-PCM-16
2011/01/17 12:54:23.007 1:37.313 OnRelease:0xa659cb70 Patch Removing media stream sink OpalAudioMediaStream-Sink-PCM-16
2011/01/17 12:54:23.008 1:37.314 OnRelease:0xa659cb70 OpalCon Removed media stream OpalAudioMediaStream-Sink-PCM-16
2011/01/17 12:54:23.008 1:37.314 OnRelease:0xa659cb70 Patch Waiting for media patch thread to stop Patch OpalRTPMediaStream-Source-G.711-ALaw-64k
2011/01/17 12:54:23.009 1:37.314 OnRelease:0xa659cb70 OpalCon Removed media stream OpalRTPMediaStream-Source-G.711-ALaw-64k
2011/01/17 12:54:23.009 1:37.315 OnRelease:0xa659cb70 Media Closing RTP for OpalRTPMediaStream-Sink-G.711-ALaw-64k
2011/01/17 12:54:23.009 1:37.315 OnRelease:0xa659cb70 RTP_UDP Session 1, Shutting down write.
2011/01/17 12:54:23.009 1:37.315 OnRelease:0xa659cb70 Media Closing stream OpalRTPMediaStream-Sink-G.711-ALaw-64k
2011/01/17 12:54:23.009 1:37.315 OnRelease:0xa659cb70 Media Disconnecting OpalRTPMediaStream-Sink-G.711-ALaw-64k from patch thread Patch OpalAudioMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-G.711-ALaw-64k
2011/01/17 12:54:23.009 1:37.315 OnRelease:0xa659cb70 Patch Removing media stream sink OpalRTPMediaStream-Sink-G.711-ALaw-64k
2011/01/17 12:54:23.009 1:37.315 OnRelease:0xa659cb70 OpalCon Removed media stream OpalRTPMediaStream-Sink-G.711-ALaw-64k
2011/01/17 12:54:23.010 1:37.315 OnRelease:0xa659cb70 Call GetOtherPartyConnection Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf]
2011/01/17 12:54:23.021 1:37.327 OnRelease:0xa659cb70 AudioInputCore Stopping Stream
2011/01/17 12:54:23.022 1:37.327 OnRelease:0xa659cb70 AudioInputCore Closing current device
2011/01/17 12:54:23.022 1:37.327 OnRelease:0xa659cb70 GMAudioInputManager_ptlib Closing device Default (PTLIB/ALSA)
2011/01/17 12:54:23.022 1:37.328 OnRelease:0xa659cb70 ALSA Closing default
2011/01/17 12:54:23.023 1:37.329 OnRelease:0xa659cb70 GMAudioInputManager_ptlib Setting Device Default (PTLIB/ALSA)
2011/01/17 12:54:23.023 1:37.329 OnRelease:0xa659cb70 Media Closing stream OpalAudioMediaStream-Source-PCM-16
2011/01/17 12:54:23.023 1:37.329 OnRelease:0xa659cb70 Media Disconnecting OpalAudioMediaStream-Source-PCM-16 from patch thread Patch OpalAudioMediaStream-Source-PCM-16
2011/01/17 12:54:23.024 1:37.329 OnRelease:0xa659cb70 Patch Closing media patch Patch OpalAudioMediaStream-Source-PCM-16
2011/01/17 12:54:23.032 1:37.338 Media Patch:0xa1d9ab70 Patch Thread ended because all sink writes failed failed
2011/01/17 12:54:23.032 1:37.338 OnRelease:0xa659cb70 Patch Waiting for media patch thread to stop Patch OpalAudioMediaStream-Source-PCM-16
2011/01/17 12:54:23.032 1:37.338 Media Patch:0xa1d9ab70 Patch Thread ended for Patch OpalAudioMediaStream-Source-PCM-16
2011/01/17 12:54:23.033 1:37.338 OnRelease:0xa659cb70 OpalCon Removed media stream OpalAudioMediaStream-Source-PCM-16
2011/01/17 12:54:23.033 1:37.339 OnRelease:0xa659cb70 OpalCon Media streams closed.
2011/01/17 12:54:23.033 1:37.339 OnRelease:0xa659cb70 OpalCon SetPhase from ReleasingPhase to ReleasedPhase for Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf]
2011/01/17 12:54:23.033 1:37.339 OnRelease:0xa659cb70 OpalCon OnReleased Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf]
2011/01/17 12:54:23.033 1:37.339 OnRelease:0xa659cb70 OpalEP OnReleased Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf]
2011/01/17 12:54:23.033 1:37.339 OnRelease:0xa659cb70 OpalMan OnReleased Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf]
2011/01/17 12:54:23.033 1:37.339 OnRelease:0xa659cb70 Call OnReleased Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf]
2011/01/17 12:54:23.034 1:37.339 OnRelease:0xa659cb70 OpalCon SetPhase from EstablishedPhase to ReleasingPhase for Call[v2713261c1]-EP<pc>[m736b49fb2]
2011/01/17 12:54:23.034 1:37.339 OnRelease:0xa659cb70 OpalCon Releasing Call[v2713261c1]-EP<pc>[m736b49fb2]
2011/01/17 12:54:23.034 1:37.340 OnRelease:0xa659cb70 OpalCon Call end reason for Call[v2713261c1]-EP<pc>[m736b49fb2] set to EndedByRemoteUser
2011/01/17 12:54:23.034 1:37.340 OnRelease:0xa659cb70 OpalCon OnReleased Call[v2713261c1]-EP<pc>[m736b49fb2]
2011/01/17 12:54:23.034 1:37.340 OnRelease:0xa659cb70 OpalEP OnReleased Call[v2713261c1]-EP<pc>[m736b49fb2]
2011/01/17 12:54:23.034 1:37.340 OnRelease:0xa659cb70 OpalMan OnReleased Call[v2713261c1]-EP<pc>[m736b49fb2]
2011/01/17 12:54:23.034 1:37.340 OnRelease:0xa659cb70 Call OnReleased Call[v2713261c1]-EP<pc>[m736b49fb2]
2011/01/17 12:54:23.034 1:37.340 OnRelease:0xa659cb70 OpalCon Media streams closed.
2011/01/17 12:54:23.034 1:37.340 OnRelease:0xa659cb70 OpalCon Media streams closed.
2011/01/17 12:54:23.035 1:37.340 OnRelease:0xa659cb70 OpalCon OnRelease thread completed for Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf]
2011/01/17 12:54:23.089 1:37.395 AEScheduler Removing Event incoming_call_sound from queue
2011/01/17 12:54:23.089 1:37.395 AEScheduler Removing Event ring_tone_sound from queue
2011/01/17 12:54:23.089 1:37.395 OpalMan OnClearedCall Call[v2713261c1] from "sip:ncassanova11-200@67.231.245.210:29892" to "sip:ghostnik11@173.68.138.36"
2011/01/17 12:54:23.090 1:37.395 OPAL Attempting to close recording on call v2713261c1 when not recording.
2011/01/17 12:54:23.090 1:37.395 AEScheduler Removing Event incoming_call_sound from queue
2011/01/17 12:54:23.090 1:37.395 AEScheduler Removing Event ring_tone_sound from queue
2011/01/17 12:54:23.749 1:38.055 Opal Garbage:0xb6d95b70 PCSS Deleted PC sound system connection.
2011/01/17 12:54:23.750 1:38.055 Opal Garbage:0xb6d95b70 OpalCon Connection Call[v2713261c1]-EP<pc>[m736b49fb2] destroyed.
2011/01/17 12:54:23.750 1:38.056 Opal Garbage:0xb6d95b70 Opal Transport clean up on termination
2011/01/17 12:54:23.750 1:38.056 Opal Garbage:0xb6d95b70 Opal Transport Close
2011/01/17 12:54:23.750 1:38.056 Opal Garbage:0xb6d95b70 Opal Transport clean up on termination
2011/01/17 12:54:23.750 1:38.056 Opal Garbage:0xb6d95b70 Opal Transport Close
2011/01/17 12:54:23.751 1:38.057 Opal Garbage:0xb6d95b70 PWLib File handle low water mark set: 56 PUDPSocket
2011/01/17 12:54:23.750 1:38.056 Opal Garbage:0xb6d95b70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$173.68.138.36:5060>
2011/01/17 12:54:23.751 1:38.057 Opal Garbage:0xb6d95b70 SIP Deleted connection.
2011/01/17 12:54:23.753 1:38.058 Opal Garbage:0xb6d95b70 RTP Closing session 1
2011/01/17 12:54:23.753 1:38.059 Opal Garbage:0xb6d95b70 RTP Deleting session 1
2011/01/17 12:54:23.753 1:38.059 Opal Garbage:0xb6d95b70 InfLID Switching off jitter buffer.
2011/01/17 12:54:23.753 1:38.059 Opal Garbage:0xb6d95b70 RTP Removing jitter buffer 0xa41e948 RTP Jitter:0xa1d59b70
2011/01/17 12:54:23.753 1:38.059 Opal Garbage:0xb6d95b70 RTP_UDP Session 1, Shutting down write.
2011/01/17 12:54:23.753 1:38.059 Opal Garbage:0xb6d95b70 RTP Session 1, final statistics:
packetsSent = 1232
octetsSent = 197120
averageSendTime = 19
maximumSendTime = 25
minimumSendTime = 10
packetsReceived = 1030
octetsReceived = 164800
packetsLost = 12
packetsTooLate = 0
packetOverruns = 0
packetsOutOfOrder = 0
averageReceiveTime= 19
maximumReceiveTime= 48
minimumReceiveTime= 0
averageJitter = 10
maximumJitter = 29
2011/01/17 12:54:23.754 1:38.060 Opal Garbage:0xb6d95b70 RTP Closing session 2
2011/01/17 12:54:23.754 1:38.060 Opal Garbage:0xb6d95b70 RTP Deleting session 2
2011/01/17 12:54:23.754 1:38.060 Opal Garbage:0xb6d95b70 RTP_UDP Session 2, Shutting down read.
2011/01/17 12:54:23.754 1:38.060 Opal Garbage:0xb6d95b70 RTP_UDP Session 2, Shutting down write.
2011/01/17 12:54:23.754 1:38.060 Opal Garbage:0xb6d95b70 OpalCon Connection Call[v2713261c1]-EP<sip>[722a846b-d020-e011-9c53-0013029a3fdf] destroyed.
2011/01/17 12:54:24.820 1:39.126 Call Call[v2713261c1] destroyed.
** (ekiga:2032): CRITICAL **: dbus_g_proxy_disconnect_signal: assertion `!DBUS_G_PROXY_DESTROYED (proxy)' failed
** (ekiga:2032): CRITICAL **: dbus_g_proxy_disconnect_signal: assertion `!DBUS_G_PROXY_DESTROYED (proxy)' failed
2011/01/17 12:54:29.526 1:43.831 Network In...0xb6d54b70 IfaceMon Finished interface monitor thread.
Attachment:
output
Description: Binary data