Re: [Ekiga-list] Echo Test Failing
- From: Rob Stone <robstone mira net>
- To: ekiga-list gnome org
- Subject: Re: [Ekiga-list] Echo Test Failing
- Date: Tue, 05 Jul 2011 04:13:15 +1000
On 04/07/11 11:34, Robert M. Riches Jr. wrote:
Date: Mon, 04 Jul 2011 09:57:03 +1000
From: Rob Stone<robstone mira net>
To: Ekiga mailing list<ekiga-list gnome org>
On 04/07/11 01:06, Eugen Dedu wrote:
On 01/07/11 19:36, Rob Stone wrote:
Running Ekiga 3.2.7 on Debian Linux Toshiba A200 laptop.
Output of uname -a
Linux roblaptop 2.6.39-2-686-pae #1 SMP Wed Jun 8 11:33:14 UTC 2011 i686
GNU/Linux
roblaptop:/var/log# lsusb
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 002 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 003 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 004 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 005 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 006 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 007 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 005 Device 002: ID 046d:0a01 Logitech, Inc. USB Headset
Bus 006 Device 002: ID 15ca:00c3 Textech International Ltd. Mini Optical
Mouse
Bus 006 Device 003: ID 046d:089d Logitech, Inc. QuickCam E2500 series
Bus 002 Device 005: ID 03f0:5b11 Hewlett-Packard OfficeJet J2100 series
roblaptop:/var/log#
Start up Ekiga and it finally loads / appears. Initially it displays
that it cannot register my sip address, then it seems to register and
then it displays it cannot register another sip address
1 sip diamondcard us which has nothing to do with me.
You have certainly entered a "1" during Assistant Configuration by
error. Please remove that account from Edit->Accounts dialog.
If I then try to run the echo test nothing happens and the message "call
failed" appears.
There is nothing in the logs to indicate exactly what "failed".
So, I cannot test out speech latency or whether the web cam is
functioning.
Please advise what other information you need.
We need -d 4 output to analyse the problem.
Hi Eugen,
I ran Ekiga as suggested and the output is humungous. I tried "catting"
it to a file but nothing was written to the file.
Even with the terminal window at its deepest, I can't copy and paste the
content from the very beginning.
As a rough guess, I'd say the last 4/5ths of the output is copyable.
Is that enough??
Cheers,
Rob
It appears Ekiga writes its debug to stderr. The method to
direct stderr to a file is shell-dependent. If you're using csh
or tcsh, this works:
ekiga -d 4> & ztest2
If you're using bash or one of its close relatives, this works:
ekiga -d 4 2&>ztest6
With a bit of luck, that will give you the complete output.
HTH
Robert Riches
rm riches jacob21819 net
_______________________________________________
ekiga-list mailing list
ekiga-list gnome org
http://mail.gnome.org/mailman/listinfo/ekiga-list
O.K. Debug log file attached.
After Ekiga started up I clicked on the "Echo Test", the little handset
image turned green and a few seconds later turned red and the message
"Call Completed" appeared in the status bar.
I then did Ctl+Q to end the program.
Hope this helps.
Cheers,
Rob
2011/07/05 04:01:30.515 0:00.053 Version 3.2.7 by on Unix Linux (2.6.39-2-686-pae-i686) with PTLib (v2.6.7) at 2011/7/5 4:01:30.515
2011/07/05 04:01:30.515 0:00.053 Ekiga git revision: unknown
2011/07/05 04:01:30.518 0:00.056 Ekiga registered on D-Bus: org.ekiga.Ekiga
2011/07/05 04:01:30.519 0:00.057 PWLib File handle high water mark set: 19 Thread unblock pipe
2011/07/05 04:01:30.519 0:00.057 PTLib No permission to set priority level 4
2011/07/05 04:01:30.519 0:00.057 PTLib Thread high water mark set: 2
2011/07/05 04:01:30.519 0:00.057 PWLib File handle high water mark set: 21 Thread unblock pipe
2011/07/05 04:01:30.519 0:00.057 PTLib No permission to set priority level 4
2011/07/05 04:01:30.519 0:00.057 PTLib Thread high water mark set: 3
2011/07/05 04:01:30.519 0:00.057 PWLib File handle high water mark set: 23 Thread unblock pipe
2011/07/05 04:01:30.520 0:00.058 PWLib File handle high water mark set: 25 Thread unblock pipe
2011/07/05 04:01:30.521 0:00.059 PTLib No permission to set priority level 4
2011/07/05 04:01:30.521 0:00.059 PTLib Thread high water mark set: 4
2011/07/05 04:01:30.521 0:00.060 HalManager_dbus Initialising HAL Manager
2011/07/05 04:01:30.524 0:00.062 HalManager_dbus Populating device list
2011/07/05 04:01:30.545 0:00.083 HalManager_dbus Skipped V4L1 device /dev/video0without name
2011/07/05 04:01:30.545 0:00.083 HalManager_dbus Detected V4L2 capabilities on /dev/video0 name: USB Camera (046d:089d)
2011/07/05 04:01:30.699 0:00.237 HalManager_dbus Populated device list with 22 devices
2011/07/05 04:01:30.700 0:00.238 HalManager_dbus Populating interface list
2011/07/05 04:01:30.701 0:00.239 HalManager_dbus Populating full interface list failed - Method "getDevices" with signature "" on interface "org.freedesktop.NetworkManager" doesn't exist
2011/07/05 04:01:30.713 0:00.251 Detecting V4L2 devices
2011/07/05 04:01:30.713 0:00.251 PV4L2Plugin detected device metadata at /sys/class/video4linux/
2011/07/05 04:01:30.722 0:00.261 PWLib File handle high water mark set: 30 Thread unblock pipe
2011/07/05 04:01:30.723 0:00.261 PTLib Thread high water mark set: 5
2011/07/05 04:01:30.723 0:00.261 OpalMan Created manager.
2011/07/05 04:01:30.723 0:00.261 OpalMan Attached endpoint with prefix pc
2011/07/05 04:01:30.723 0:00.261 OpalEP Created endpoint: pc
2011/07/05 04:01:30.764 0:00.302 PCSS Created PC sound system endpoint.
Players:
Default
Logitech USB Headset
HDA Intel
EKIGA
*.wav
/dev/dsp
/dev/dsp1
/dev/dsp2
Recorders:
Default
Logitech USB Headset
HDA Intel
EKIGA
*.wav
USB Device 0x46d:0x89d
/dev/dsp
/dev/dsp1
/dev/dsp2
2011/07/05 04:01:30.764 0:00.302 OPAL SetMediaFormatOrder()
2011/07/05 04:01:30.764 0:00.303 OPAL SetMediaFormatMask()
2011/07/05 04:01:30.765 0:00.303 OpalMan Attached endpoint with prefix sip
2011/07/05 04:01:30.765 0:00.303 OpalEP Created endpoint: sip
2011/07/05 04:01:30.765 0:00.304 PWLib File handle high water mark set: 31 PUDPSocket
2011/07/05 04:01:30.765 0:00.304 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)
187.65.248.7 [fe:80:00:00:00:00:00:00:02:a0:d1:ff:fe:7b:f4:e9] <00-A0-D1-7B-F4-E9> (eth0)
2011/07/05 04:01:30.766 0:00.304 PWLib File handle high water mark set: 32 Thread unblock pipe
2011/07/05 04:01:30.766 0:00.304 PTLib Thread high water mark set: 6
2011/07/05 04:01:30.766 0:00.304 PWLib File handle high water mark set: 34 Thread unblock pipe
2011/07/05 04:01:30.766 0:00.304 Network In...0xb3d88b70 IfaceMon Started interface monitor thread.
2011/07/05 04:01:30.766 0:00.304 PTLib Thread high water mark set: 7
2011/07/05 04:01:30.766 0:00.304 Network In...0xb3d88b70 PWLib File handle high water mark set: 35 PUDPSocket
2011/07/05 04:01:30.766 0:00.304 OpalMan Attached endpoint with prefix sips
2011/07/05 04:01:30.766 0:00.304 SIP Created endpoint.
2011/07/05 04:01:30.766 0:00.305 MonSock Created socket bundle for all interfaces.
2011/07/05 04:01:30.767 0:00.305 PWLib File handle high water mark set: 36 PUDPSocket
2011/07/05 04:01:30.767 0:00.305 MonSock Created bundled UDP socket 187.65.248.7:5060
2011/07/05 04:01:30.767 0:00.305 PWLib File handle high water mark set: 38 Thread unblock pipe
2011/07/05 04:01:30.767 0:00.305 PTLib Thread high water mark set: 8
2011/07/05 04:01:30.767 0:00.305 PTLib No permission to set priority level 4
2011/07/05 04:01:30.767 0:00.305 Opal Liste...0xb3d06b70 Listen Started listening thread on udp$*:5060
2011/07/05 04:01:30.767 0:00.305 OpalMan Added route "sip:.*=pc:*"
2011/07/05 04:01:30.767 0:00.305 OpalMan Added route "pc:.*=sip:<da>"
2011/07/05 04:01:30.768 0:00.306 OpalMan Attached endpoint with prefix h323
2011/07/05 04:01:30.768 0:00.306 OpalEP Created endpoint: h323
2011/07/05 04:01:30.768 0:00.306 OpalMan Attached endpoint with prefix h323s
2011/07/05 04:01:30.768 0:00.306 H323 Created endpoint.
2011/07/05 04:01:30.768 0:00.306 PWLib File handle high water mark set: 39 PTCPSocket
2011/07/05 04:01:30.768 0:00.306 Listen Open (REUSEADDR) on 0.0.0.0:1720 failed: Address already in use
2011/07/05 04:01:30.768 0:00.306 Listen Stopping listening thread on tcp$*:1720
2011/07/05 04:01:30.768 0:00.306 OpalEP Could not start listener: tcp$*:1720
2011/07/05 04:01:30.768 0:00.306 OpalMan Added route "h323:.*=pc:<db>"
2011/07/05 04:01:30.768 0:00.306 OpalMan Added route "pc:.*=h323:<da>"
2011/07/05 04:01:30.780 0:00.318 MediaFormat Removing codecs SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,theora,H.261,H.261-CIF,H.261-QCIF
2011/07/05 04:01:30.780 0:00.319 OPAL SetMediaFormatMask(PCM-16-48kHz,CELT-48K,PCM-16-32kHz,CELT-32K,PCM-16-16kHz,G.722-64k,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz)
2011/07/05 04:01:30.780 0:00.319 OPAL SetMediaFormatOrder(SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,theora,H.261,H.261-CIF,H.261-QCIF)
2011/07/05 04:01:30.791 0:00.329 MediaFormat Removing codecs SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,theora,H.261,H.261-CIF,H.261-QCIF
2011/07/05 04:01:30.791 0:00.329 OPAL SetMediaFormatMask(PCM-16-48kHz,CELT-48K,PCM-16-32kHz,CELT-32K,PCM-16-16kHz,G.722-64k,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz)
2011/07/05 04:01:30.791 0:00.329 OPAL SetMediaFormatOrder(SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,theora,H.261,H.261-CIF,H.261-QCIF)
2011/07/05 04:01:30.808 0:00.347 PWLib File handle high water mark set: 40 Thread unblock pipe
2011/07/05 04:01:30.809 0:00.347 PTLib Thread high water mark set: 9
2011/07/05 04:01:30.809 0:00.347 StunDetector:0xb3cbeb70 PWLib File handle high water mark set: 42 PUDPSocket
(ekiga:5391): libebook-WARNING **: e-book.c:3420: unmatched error name org.gnome.evolution.dataserver.addressbook.BookFactory.E_DATA_BOOK_STATUS_NO_SUCH_BOOK
(ekiga:5391): libebook-WARNING **: e-book.c:3095: cannot get book from factory: Invalid source
(ekiga:5391): GLib-GObject-CRITICAL **: g_object_ref: assertion `G_IS_OBJECT (object)' failed
(ekiga:5391): libebook-CRITICAL **: e_book_is_opened: assertion `E_IS_BOOK (book)' failed
(ekiga:5391): libebook-CRITICAL **: file e-book.c: line 2475 (e_book_async_open): assertion `E_IS_BOOK (book)' failed
(ekiga:5391): GLib-GObject-CRITICAL **: g_object_unref: assertion `G_IS_OBJECT (object)' failed
(ekiga:5391): libebook-CRITICAL **: e_book_get_source: assertion `E_IS_BOOK (book)' failed
2011/07/05 04:01:30.876 0:00.414 PWLib File handle high water mark set: 48 PUDPSocket
2011/07/05 04:01:30.876 0:00.414 SIP Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:500 ekiga net, id=3afb9f50-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
2011/07/05 04:01:30.876 0:00.415 DNS SRV Lookup ekiga.net service _sip._udp
2011/07/05 04:01:31.287 0:00.826 SIP No SRV record found.
2011/07/05 04:01:31.712 0:01.250 OpalUDP Binding to interface: 0.0.0.0:5060
2011/07/05 04:01:31.712 0:01.250 SIP Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2011/07/05 04:01:31.713 0:01.251 OpalUDP Started connect to 86.64.162.35:5060
2011/07/05 04:01:31.713 0:01.251 OpalUDP Writing to interface 0 - "187.65.248.7%eth0"
2011/07/05 04:01:31.713 0:01.252 OpalMan Listener interfaces: associated transport=None
udp$187.65.248.7:5060
2011/07/05 04:01:36.250 0:05.788 StunDetector:0xb3cbeb70 PWLib File handle low water mark set: 42 PUDPSocket
2011/07/05 04:01:37.117 0:06.655 StunDetector:0xb3cbeb70 OPAL STUN server "stun.ekiga.net" replies Open NAT, external IP 187.65.248.7
2011/07/05 04:01:37.274 0:06.812 PWLib File handle low water mark set: 39 PUDPSocket
2011/07/05 04:01:41.072 0:10.611 STUN STUN server 77.72.169.166:3478 unexpectedly went offline.
2011/07/05 04:01:41.074 0:10.612 SIP Transaction created.
2011/07/05 04:01:42.976 0:12.514 DNS SRV Lookup ekiga.net service _sip._udp
2011/07/05 04:01:43.371 0:12.909 SIP No SRV record found.
2011/07/05 04:01:43.371 0:12.910 SIP Transaction remote address is udp$ekiga.net:5060
2011/07/05 04:01:43.372 0:12.910 SIP Sending PDU (546 bytes) to: rem=udp$86.64.162.35:5060,local=udp$187.65.248.7:5060,if=187.65.248.7%eth0
SUBSCRIBE sip:500 ekiga net SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 187.65.248.7:5060;branch=z9hG4bK9ae0d557-d5a4-e011-9f66-00a0d17bf4e9;rport
User-Agent: Ekiga/3.2.7
From: "Rob Stone" <sip:rob@0.0.0.0>;tag=f2fbb356-d5a4-e011-9f66-00a0d17bf4e9
Call-ID: 3afb9f50-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
To: <sip:500 ekiga net>
Contact: <sip:rob@187.65.248.7>
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/07/05 04:01:43.373 0:12.911 OpalUDP Setting interface to 187.65.248.7%eth0
2011/07/05 04:01:43.373 0:12.911 SIP Transaction timers set: retry=0.500, completion=6.000
2011/07/05 04:01:43.374 0:12.912 SIP Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:500 ekiga net, id=b2ef1258-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
2011/07/05 04:01:43.374 0:12.912 DNS SRV Lookup ekiga.net service _sip._udp
2011/07/05 04:01:43.623 0:13.161 Opal Liste...0xb3d06b70 OpalUDP Binding to interface: 187.65.248.7:5060
2011/07/05 04:01:43.623 0:13.161 Opal Liste...0xb3d06b70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$187.65.248.7:5060>
2011/07/05 04:01:43.624 0:13.162 Opal Liste...0xb3d06b70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$187.65.248.7:5060,if=187.65.248.7%eth0
SIP/2.0 489 Event Not Supported
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 187.65.248.7:5060;branch=z9hG4bK9ae0d557-d5a4-e011-9f66-00a0d17bf4e9;rport=5060
Server: Kamailio (1.5.3-notls (i386/linux))
From: "Rob Stone" <sip:rob@0.0.0.0>;tag=f2fbb356-d5a4-e011-9f66-00a0d17bf4e9
Call-ID: 3afb9f50-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
To: <sip:500 ekiga net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.ae5c
Content-Length: 0
2011/07/05 04:01:43.625 0:13.163 Opal Liste...0xb3d06b70 SIP Queueing PDU "2 SUBSCRIBE <489>", transaction=z9hG4bK9ae0d557-d5a4-e011-9f66-00a0d17bf4e9, token=3afb9f50-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
2011/07/05 04:01:43.625 0:13.163 Opal Liste...0xb3d06b70 PTLib No permission to set priority level 3
2011/07/05 04:01:43.625 0:13.164 Opal Liste...0xb3d06b70 Opal Transport clean up on termination
2011/07/05 04:01:43.625 0:13.164 Opal Liste...0xb3d06b70 Opal Transport Close
2011/07/05 04:01:43.626 0:13.164 Pool:0xb3cbeb70 SIP Handling PDU "2 SUBSCRIBE <489>" for transaction=z9hG4bK9ae0d557-d5a4-e011-9f66-00a0d17bf4e9
2011/07/05 04:01:43.626 0:13.164 Pool:0xb3cbeb70 SIP SUBSCRIBE transaction id=z9hG4bK9ae0d557-d5a4-e011-9f66-00a0d17bf4e9 completed.
2011/07/05 04:01:43.626 0:13.164 Pool:0xb3cbeb70 OpalUDP Setting interface to 187.65.248.7%eth0
2011/07/05 04:01:43.626 0:13.164 Pool:0xb3cbeb70 SIP Not retrying SUBSCRIBE due to error response 489 Bad Event
2011/07/05 04:01:43.626 0:13.164 Pool:0xb3cbeb70 SIP Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:500 ekiga net, id=3afb9f50-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
2011/07/05 04:01:43.626 0:13.165 Pool:0xb3cbeb70 SIP Handled PDU "2 SUBSCRIBE <489>"
2011/07/05 04:01:43.626 0:13.164 Opal Liste...0xb3d06b70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$187.65.248.7:5060>
2011/07/05 04:01:43.725 0:13.263 Opal Garbage:0xb3dc9b70 SIP Deleting SIPSubscribeHandler sip:500 ekiga net
2011/07/05 04:01:43.725 0:13.263 Opal Garbage:0xb3dc9b70 Opal Transport clean up on termination
2011/07/05 04:01:43.725 0:13.263 Opal Garbage:0xb3dc9b70 Opal Transport Close
2011/07/05 04:01:43.725 0:13.264 Opal Garbage:0xb3dc9b70 Opal Transport clean up on termination
2011/07/05 04:01:43.725 0:13.264 Opal Garbage:0xb3dc9b70 Opal Transport Close
2011/07/05 04:01:43.725 0:13.264 Opal Garbage:0xb3dc9b70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$187.65.248.7:5060>
2011/07/05 04:01:43.726 0:13.264 Opal Garbage:0xb3dc9b70 SIP Deleted handler.
2011/07/05 04:01:43.785 0:13.324 SIP No SRV record found.
2011/07/05 04:01:43.786 0:13.324 OpalUDP Binding to interface: 0.0.0.0:5060
2011/07/05 04:01:43.786 0:13.324 SIP Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2011/07/05 04:01:43.787 0:13.325 OpalUDP Started connect to 86.64.162.35:5060
2011/07/05 04:01:43.787 0:13.325 OpalUDP Writing to interface 0 - "187.65.248.7%eth0"
2011/07/05 04:01:43.787 0:13.326 OpalMan Listener interfaces: associated transport=None
udp$187.65.248.7:5060
2011/07/05 04:01:43.790 0:13.328 SIP Transaction created.
2011/07/05 04:01:43.794 0:13.332 DNS SRV Lookup ekiga.net service _sip._udp
2011/07/05 04:01:44.192 0:13.731 SIP No SRV record found.
2011/07/05 04:01:44.192 0:13.731 SIP Transaction remote address is udp$ekiga.net:5060
2011/07/05 04:01:44.193 0:13.731 SIP Sending PDU (563 bytes) to: rem=udp$86.64.162.35:5060,local=udp$187.65.248.7:5060,if=187.65.248.7%eth0
SUBSCRIBE sip:500 ekiga net SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 187.65.248.7:5060;branch=z9hG4bKe4c35258-d5a4-e011-9f66-00a0d17bf4e9;rport
User-Agent: Ekiga/3.2.7
From: "Rob Stone" <sip:rob@187.65.248.7>;tag=64675258-d5a4-e011-9f66-00a0d17bf4e9
Call-ID: b2ef1258-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
To: <sip:500 ekiga net>
Contact: <sip:500@187.65.248.7>
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/07/05 04:01:44.194 0:13.732 OpalUDP Setting interface to 187.65.248.7%eth0
2011/07/05 04:01:44.194 0:13.732 SIP Transaction timers set: retry=0.500, completion=6.000
2011/07/05 04:01:44.195 0:13.733 SIP Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:501 ekiga net, id=64389058-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
2011/07/05 04:01:44.195 0:13.733 DNS SRV Lookup ekiga.net service _sip._udp
2011/07/05 04:01:44.449 0:13.987 Opal Liste...0xb3d06b70 OpalUDP Binding to interface: 187.65.248.7:5060
2011/07/05 04:01:44.449 0:13.987 Opal Liste...0xb3d06b70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$187.65.248.7:5060>
2011/07/05 04:01:44.450 0:13.988 Opal Liste...0xb3d06b70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$187.65.248.7:5060,if=187.65.248.7%eth0
SIP/2.0 489 Event Not Supported
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 187.65.248.7:5060;branch=z9hG4bKe4c35258-d5a4-e011-9f66-00a0d17bf4e9;rport=5060
Server: Kamailio (1.5.3-notls (i386/linux))
From: "Rob Stone" <sip:rob@187.65.248.7>;tag=64675258-d5a4-e011-9f66-00a0d17bf4e9
Call-ID: b2ef1258-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
To: <sip:500 ekiga net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.1162
Content-Length: 0
2011/07/05 04:01:44.451 0:13.989 Opal Liste...0xb3d06b70 SIP Queueing PDU "2 SUBSCRIBE <489>", transaction=z9hG4bKe4c35258-d5a4-e011-9f66-00a0d17bf4e9, token=b2ef1258-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
2011/07/05 04:01:44.451 0:13.989 Opal Liste...0xb3d06b70 Opal Transport clean up on termination
2011/07/05 04:01:44.451 0:13.989 Opal Liste...0xb3d06b70 Opal Transport Close
2011/07/05 04:01:44.451 0:13.989 Pool:0xb3cbeb70 SIP Handling PDU "2 SUBSCRIBE <489>" for transaction=z9hG4bKe4c35258-d5a4-e011-9f66-00a0d17bf4e9
2011/07/05 04:01:44.451 0:13.989 Pool:0xb3cbeb70 SIP SUBSCRIBE transaction id=z9hG4bKe4c35258-d5a4-e011-9f66-00a0d17bf4e9 completed.
2011/07/05 04:01:44.451 0:13.990 Pool:0xb3cbeb70 OpalUDP Setting interface to 187.65.248.7%eth0
2011/07/05 04:01:44.451 0:13.990 Pool:0xb3cbeb70 SIP Not retrying SUBSCRIBE due to error response 489 Bad Event
2011/07/05 04:01:44.452 0:13.990 Pool:0xb3cbeb70 SIP Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:500 ekiga net, id=b2ef1258-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
2011/07/05 04:01:44.451 0:13.989 Opal Liste...0xb3d06b70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$187.65.248.7:5060>
2011/07/05 04:01:44.452 0:13.990 Pool:0xb3cbeb70 SIP Handled PDU "2 SUBSCRIBE <489>"
2011/07/05 04:01:44.589 0:14.127 SIP No SRV record found.
2011/07/05 04:01:44.590 0:14.128 OpalUDP Binding to interface: 0.0.0.0:5060
2011/07/05 04:01:44.590 0:14.128 SIP Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2011/07/05 04:01:44.590 0:14.129 OpalUDP Started connect to 86.64.162.35:5060
2011/07/05 04:01:44.591 0:14.129 OpalUDP Writing to interface 0 - "187.65.248.7%eth0"
2011/07/05 04:01:44.591 0:14.129 OpalMan Listener interfaces: associated transport=None
udp$187.65.248.7:5060
2011/07/05 04:01:44.627 0:14.165 Housekeeper:0xb3d47b70 SIP Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK9ae0d557-d5a4-e011-9f66-00a0d17bf4e9
2011/07/05 04:01:44.727 0:14.265 Opal Garbage:0xb3dc9b70 SIP Transaction id=z9hG4bK9ae0d557-d5a4-e011-9f66-00a0d17bf4e9 destroyed.
2011/07/05 04:01:44.727 0:14.265 Opal Garbage:0xb3dc9b70 SIP Deleting SIPSubscribeHandler sip:500 ekiga net
2011/07/05 04:01:44.727 0:14.265 Opal Garbage:0xb3dc9b70 Opal Transport clean up on termination
2011/07/05 04:01:44.727 0:14.265 Opal Garbage:0xb3dc9b70 Opal Transport Close
2011/07/05 04:01:44.727 0:14.266 Opal Garbage:0xb3dc9b70 Opal Transport clean up on termination
2011/07/05 04:01:44.727 0:14.266 Opal Garbage:0xb3dc9b70 Opal Transport Close
2011/07/05 04:01:44.727 0:14.266 Opal Garbage:0xb3dc9b70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$187.65.248.7:5060>
2011/07/05 04:01:44.728 0:14.266 Opal Garbage:0xb3dc9b70 SIP Deleted handler.
2011/07/05 04:01:44.887 0:14.425 SIP Transaction created.
2011/07/05 04:01:44.891 0:14.429 DNS SRV Lookup ekiga.net service _sip._udp
2011/07/05 04:01:45.294 0:14.832 SIP No SRV record found.
2011/07/05 04:01:45.294 0:14.832 SIP Transaction remote address is udp$ekiga.net:5060
2011/07/05 04:01:45.295 0:14.833 SIP Sending PDU (551 bytes) to: rem=udp$86.64.162.35:5060,local=udp$187.65.248.7:5060,if=187.65.248.7%eth0
SUBSCRIBE sip:501 ekiga net SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 187.65.248.7:5060;branch=z9hG4bK3c28fa58-d5a4-e011-9f66-00a0d17bf4e9;rport
User-Agent: Ekiga/3.2.7
From: "Rob Stone" <sip:rob@187.65.248.7>;tag=4ecbf958-d5a4-e011-9f66-00a0d17bf4e9
Call-ID: 64389058-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
To: <sip:501 ekiga net>
Contact: <sip:rob@187.65.248.7>
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/07/05 04:01:45.295 0:14.834 OpalUDP Setting interface to 187.65.248.7%eth0
2011/07/05 04:01:45.296 0:14.834 SIP Transaction timers set: retry=0.500, completion=6.000
2011/07/05 04:01:45.296 0:14.835 SIP Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:501 ekiga net, id=58503859-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
2011/07/05 04:01:45.296 0:14.835 DNS SRV Lookup ekiga.net service _sip._udp
2011/07/05 04:01:45.452 0:14.990 Housekeeper:0xb3d47b70 SIP Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bKe4c35258-d5a4-e011-9f66-00a0d17bf4e9
2011/07/05 04:01:45.546 0:15.084 Opal Liste...0xb3d06b70 OpalUDP Binding to interface: 187.65.248.7:5060
2011/07/05 04:01:45.546 0:15.085 Opal Liste...0xb3d06b70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$187.65.248.7:5060>
2011/07/05 04:01:45.547 0:15.086 Opal Liste...0xb3d06b70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$187.65.248.7:5060,if=187.65.248.7%eth0
SIP/2.0 489 Event Not Supported
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 187.65.248.7:5060;branch=z9hG4bK3c28fa58-d5a4-e011-9f66-00a0d17bf4e9;rport=5060
Server: Kamailio (1.5.3-notls (i386/linux))
From: "Rob Stone" <sip:rob@187.65.248.7>;tag=4ecbf958-d5a4-e011-9f66-00a0d17bf4e9
Call-ID: 64389058-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
To: <sip:501 ekiga net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.2499
Content-Length: 0
2011/07/05 04:01:45.548 0:15.086 Opal Liste...0xb3d06b70 SIP Queueing PDU "2 SUBSCRIBE <489>", transaction=z9hG4bK3c28fa58-d5a4-e011-9f66-00a0d17bf4e9, token=64389058-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
2011/07/05 04:01:45.548 0:15.087 Pool:0xb3cbeb70 SIP Handling PDU "2 SUBSCRIBE <489>" for transaction=z9hG4bK3c28fa58-d5a4-e011-9f66-00a0d17bf4e9
2011/07/05 04:01:45.548 0:15.087 Pool:0xb3cbeb70 SIP SUBSCRIBE transaction id=z9hG4bK3c28fa58-d5a4-e011-9f66-00a0d17bf4e9 completed.
2011/07/05 04:01:45.548 0:15.087 Opal Liste...0xb3d06b70 Opal Transport clean up on termination
2011/07/05 04:01:45.549 0:15.087 Opal Liste...0xb3d06b70 Opal Transport Close
2011/07/05 04:01:45.549 0:15.087 Pool:0xb3cbeb70 OpalUDP Setting interface to 187.65.248.7%eth0
2011/07/05 04:01:45.549 0:15.087 Pool:0xb3cbeb70 SIP Not retrying SUBSCRIBE due to error response 489 Bad Event
2011/07/05 04:01:45.549 0:15.087 Pool:0xb3cbeb70 SIP Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:501 ekiga net, id=64389058-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
2011/07/05 04:01:45.549 0:15.087 Pool:0xb3cbeb70 SIP Handled PDU "2 SUBSCRIBE <489>"
2011/07/05 04:01:45.549 0:15.087 Opal Liste...0xb3d06b70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$187.65.248.7:5060>
2011/07/05 04:01:45.702 0:15.241 SIP No SRV record found.
2011/07/05 04:01:45.703 0:15.241 OpalUDP Binding to interface: 0.0.0.0:5060
2011/07/05 04:01:45.703 0:15.241 SIP Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2011/07/05 04:01:45.703 0:15.241 OpalUDP Started connect to 86.64.162.35:5060
2011/07/05 04:01:45.703 0:15.241 OpalUDP Writing to interface 0 - "187.65.248.7%eth0"
2011/07/05 04:01:45.704 0:15.242 OpalMan Listener interfaces: associated transport=None
udp$187.65.248.7:5060
2011/07/05 04:01:45.705 0:15.243 SIP Transaction created.
2011/07/05 04:01:45.708 0:15.246 DNS SRV Lookup ekiga.net service _sip._udp
2011/07/05 04:01:46.125 0:15.663 SIP No SRV record found.
2011/07/05 04:01:46.125 0:15.663 SIP Transaction remote address is udp$ekiga.net:5060
2011/07/05 04:01:46.126 0:15.664 SIP Sending PDU (563 bytes) to: rem=udp$86.64.162.35:5060,local=udp$187.65.248.7:5060,if=187.65.248.7%eth0
SUBSCRIBE sip:501 ekiga net SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 187.65.248.7:5060;branch=z9hG4bK12e97659-d5a4-e011-9f66-00a0d17bf4e9;rport
User-Agent: Ekiga/3.2.7
From: "Rob Stone" <sip:rob@187.65.248.7>;tag=5ca97659-d5a4-e011-9f66-00a0d17bf4e9
Call-ID: 58503859-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
To: <sip:501 ekiga net>
Contact: <sip:501@187.65.248.7>
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/07/05 04:01:46.126 0:15.664 OpalUDP Setting interface to 187.65.248.7%eth0
2011/07/05 04:01:46.126 0:15.664 SIP Transaction timers set: retry=0.500, completion=6.000
2011/07/05 04:01:46.126 0:15.665 Opal Garbage:0xb3dc9b70 SIP Transaction id=z9hG4bKe4c35258-d5a4-e011-9f66-00a0d17bf4e9 destroyed.
2011/07/05 04:01:46.126 0:15.665 Opal Garbage:0xb3dc9b70 SIP Deleting SIPSubscribeHandler sip:501 ekiga net
2011/07/05 04:01:46.127 0:15.665 Opal Garbage:0xb3dc9b70 Opal Transport clean up on termination
2011/07/05 04:01:46.127 0:15.665 Opal Garbage:0xb3dc9b70 Opal Transport Close
2011/07/05 04:01:46.127 0:15.665 Opal Garbage:0xb3dc9b70 Opal Transport clean up on termination
2011/07/05 04:01:46.127 0:15.665 Opal Garbage:0xb3dc9b70 Opal Transport Close
2011/07/05 04:01:46.127 0:15.665 VideoOutputCoreConfBridge Updating video view
2011/07/05 04:01:46.127 0:15.665 VideoOutputCoreConfBridge Updating zoom
2011/07/05 04:01:46.127 0:15.665 Opal Garbage:0xb3dc9b70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$187.65.248.7:5060>
2011/07/05 04:01:46.127 0:15.666 Opal Garbage:0xb3dc9b70 SIP Deleted handler.
2011/07/05 04:01:46.127 0:15.666 VideoOutputCoreConfBridge Updating Video Settings
2011/07/05 04:01:46.128 0:15.667 VideoOutputCoreConfBridge Updating Video Settings
2011/07/05 04:01:46.128 0:15.667 VideoOutputCoreConfBridge Updating Video Settings
2011/07/05 04:01:46.129 0:15.667 VideoOutputCoreConfBridge Updating Video Settings
2011/07/05 04:01:46.129 0:15.667 VidInputCoreConfBridge Updating preview size and fps
2011/07/05 04:01:46.129 0:15.667 VidInputCore Setting new preview config: 176x144/30
2011/07/05 04:01:46.133 0:15.671 VidInputCoreConfBridge Updating preview size and fps
2011/07/05 04:01:46.133 0:15.672 VidInputCore Setting new preview config: 176x144/30
2011/07/05 04:01:46.138 0:15.676 VidInputCoreConfBridge Updating device
2011/07/05 04:01:46.139 0:15.677 VidInputCore Setting device: USB Camera (046d:089d) (PTLIB/V4L2)
2011/07/05 04:01:46.139 0:15.677 GMVideoInputManager_ptlib Setting Device USB Camera (046d:089d) (PTLIB/V4L2)
2011/07/05 04:01:46.139 0:15.677 VidInputCoreConfBridge Updating device
2011/07/05 04:01:46.139 0:15.677 VidInputCore Setting device: USB Camera (046d:089d) (PTLIB/V4L2)
2011/07/05 04:01:46.139 0:15.677 GMVideoInputManager_ptlib Setting Device USB Camera (046d:089d) (PTLIB/V4L2)
2011/07/05 04:01:46.139 0:15.678 VidInputCoreConfBridge Updating device
2011/07/05 04:01:46.139 0:15.678 VidInputCore Setting device: USB Camera (046d:089d) (PTLIB/V4L2)
2011/07/05 04:01:46.140 0:15.678 GMVideoInputManager_ptlib Setting Device USB Camera (046d:089d) (PTLIB/V4L2)
2011/07/05 04:01:46.140 0:15.678 VidInputCoreConfBridge Updating image
2011/07/05 04:01:46.140 0:15.678 VidInputCoreConfBridge Updating preview
2011/07/05 04:01:46.140 0:15.678 VidInputCore Stopping Preview
2011/07/05 04:01:46.141 0:15.679 AudioOutputCoreConfBridge Updating device
2011/07/05 04:01:46.141 0:15.679 AudioOutputCore Setting device[0]: Logitech USB Headset (PTLIB/ALSA)
2011/07/05 04:01:46.141 0:15.679 GMAudioOutputManager_ptlib Setting Device[0] Logitech USB Headset (PTLIB/ALSA)
2011/07/05 04:01:46.141 0:15.679 AudioOutputCoreConfBridge Updating device
2011/07/05 04:01:46.141 0:15.679 AudioOutputCore Setting device[1]: Default (PTLIB/ALSA)
2011/07/05 04:01:46.141 0:15.679 GMAudioOutputManager_ptlib Setting Device[1] Default (PTLIB/ALSA)
2011/07/05 04:01:46.144 0:15.682 AudioInputCoreConfBridge Updating device
2011/07/05 04:01:46.144 0:15.683 AudioInputCore Setting device: Logitech USB Headset (PTLIB/ALSA)
2011/07/05 04:01:46.144 0:15.683 GMAudioInputManager_ptlib Setting Device Logitech USB Headset (PTLIB/ALSA)
2011/07/05 04:01:46.255 0:15.794 Ekiga version 3.2.7
2011/07/05 04:01:46.255 0:15.794 OPAL version 3.6.8
2011/07/05 04:01:46.255 0:15.794 PTLIB version 2.6.7
2011/07/05 04:01:46.255 0:15.794 GNOME support disabled
2011/07/05 04:01:46.256 0:15.794 Accelerated rendering support enabled
2011/07/05 04:01:46.256 0:15.794 DBUS support enabled
2011/07/05 04:01:46.256 0:15.794 GConf support enabled
2011/07/05 04:01:46.256 0:15.794 ESound support disabled
2011/07/05 04:01:46.382 0:15.920 Opal Liste...0xb3d06b70 OpalUDP Binding to interface: 187.65.248.7:5060
2011/07/05 04:01:46.382 0:15.921 Opal Liste...0xb3d06b70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$187.65.248.7:5060>
2011/07/05 04:01:46.383 0:15.921 Opal Liste...0xb3d06b70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$187.65.248.7:5060,if=187.65.248.7%eth0
SIP/2.0 489 Event Not Supported
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 187.65.248.7:5060;branch=z9hG4bK12e97659-d5a4-e011-9f66-00a0d17bf4e9;rport=5060
Server: Kamailio (1.5.3-notls (i386/linux))
From: "Rob Stone" <sip:rob@187.65.248.7>;tag=5ca97659-d5a4-e011-9f66-00a0d17bf4e9
Call-ID: 58503859-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
To: <sip:501 ekiga net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.cb81
Content-Length: 0
2011/07/05 04:01:46.383 0:15.922 Opal Liste...0xb3d06b70 SIP Queueing PDU "2 SUBSCRIBE <489>", transaction=z9hG4bK12e97659-d5a4-e011-9f66-00a0d17bf4e9, token=58503859-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
2011/07/05 04:01:46.384 0:15.922 Pool:0xb3cbeb70 SIP Handling PDU "2 SUBSCRIBE <489>" for transaction=z9hG4bK12e97659-d5a4-e011-9f66-00a0d17bf4e9
2011/07/05 04:01:46.384 0:15.922 Pool:0xb3cbeb70 SIP SUBSCRIBE transaction id=z9hG4bK12e97659-d5a4-e011-9f66-00a0d17bf4e9 completed.
2011/07/05 04:01:46.384 0:15.922 Pool:0xb3cbeb70 OpalUDP Setting interface to 187.65.248.7%eth0
2011/07/05 04:01:46.384 0:15.922 Pool:0xb3cbeb70 SIP Not retrying SUBSCRIBE due to error response 489 Bad Event
2011/07/05 04:01:46.384 0:15.922 Pool:0xb3cbeb70 SIP Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:501 ekiga net, id=58503859-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
2011/07/05 04:01:46.384 0:15.922 Pool:0xb3cbeb70 SIP Handled PDU "2 SUBSCRIBE <489>"
2011/07/05 04:01:46.384 0:15.922 Opal Liste...0xb3d06b70 Opal Transport clean up on termination
2011/07/05 04:01:46.384 0:15.922 Opal Liste...0xb3d06b70 Opal Transport Close
2011/07/05 04:01:46.384 0:15.922 Opal Liste...0xb3d06b70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$187.65.248.7:5060>
2011/07/05 04:01:46.549 0:16.087 Housekeeper:0xb3d47b70 SIP Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK3c28fa58-d5a4-e011-9f66-00a0d17bf4e9
2011/07/05 04:01:47.128 0:16.666 Opal Garbage:0xb3dc9b70 SIP Transaction id=z9hG4bK3c28fa58-d5a4-e011-9f66-00a0d17bf4e9 destroyed.
2011/07/05 04:01:47.128 0:16.666 Opal Garbage:0xb3dc9b70 SIP Deleting SIPSubscribeHandler sip:501 ekiga net
2011/07/05 04:01:47.128 0:16.666 Opal Garbage:0xb3dc9b70 Opal Transport clean up on termination
2011/07/05 04:01:47.128 0:16.666 Opal Garbage:0xb3dc9b70 Opal Transport Close
2011/07/05 04:01:47.128 0:16.666 Opal Garbage:0xb3dc9b70 Opal Transport clean up on termination
2011/07/05 04:01:47.128 0:16.666 Opal Garbage:0xb3dc9b70 Opal Transport Close
2011/07/05 04:01:47.128 0:16.666 Opal Garbage:0xb3dc9b70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$187.65.248.7:5060>
2011/07/05 04:01:47.129 0:16.667 Opal Garbage:0xb3dc9b70 SIP Deleted handler.
2011/07/05 04:01:47.384 0:16.922 Housekeeper:0xb3d47b70 SIP Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK12e97659-d5a4-e011-9f66-00a0d17bf4e9
2011/07/05 04:01:47.933 0:17.471 Listen Stopping listening thread on udp$*:5060
2011/07/05 04:01:47.933 0:17.471 MonSock Closed UDP socket 0xb4410930
2011/07/05 04:01:47.933 0:17.471 Opal Liste...0xb3d06b70 Listen UDP read error.
2011/07/05 04:01:47.953 0:17.492 MonSock Deleting UDP socket 0xb4410930
2011/07/05 04:01:47.954 0:17.492 PWLib File handle low water mark set: 35 PUDPSocket
2011/07/05 04:01:47.954 0:17.492 MonSock Created socket bundle for all interfaces.
2011/07/05 04:01:47.954 0:17.492 MonSock Created bundled UDP socket 187.65.248.7:5060
2011/07/05 04:01:47.954 0:17.493 PTLib No permission to set priority level 4
2011/07/05 04:01:47.954 0:17.493 Opal Liste...0xb3d06b70 Listen Started listening thread on udp$*:5060
2011/07/05 04:01:47.955 0:17.493 PTLib Thread high water mark set: 10
2011/07/05 04:01:47.955 0:17.493 subscriber:0xb32ceb70 SIP Start REGISTER
aor=stoneman66 ekiga net
registrar=ekiga.net
contact=
authID=stoneman66
realm=
expire=3600
restore=30
minRetry=0.000
maxRetry=0.000
2011/07/05 04:01:47.956 0:17.494 subscriber:0xb32ceb70 SIP Changing REGISTER handler from Unavailable to Subscribing, target=sip:stoneman66 ekiga net, id=4c06ce5a-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
2011/07/05 04:01:47.956 0:17.494 subscriber:0xb32ceb70 DNS SRV Lookup ekiga.net service _sip._udp
2011/07/05 04:01:48.129 0:17.667 Opal Garbage:0xb3dc9b70 SIP Transaction id=z9hG4bK12e97659-d5a4-e011-9f66-00a0d17bf4e9 destroyed.
2011/07/05 04:01:48.373 0:17.911 subscriber:0xb32ceb70 SIP No SRV record found.
2011/07/05 04:01:48.373 0:17.911 subscriber:0xb32ceb70 OpalUDP Binding to interface: 0.0.0.0:5060
2011/07/05 04:01:48.373 0:17.912 subscriber:0xb32ceb70 PWLib File handle high water mark set: 49 PUDPSocket
2011/07/05 04:01:48.373 0:17.911 subscriber:0xb32ceb70 SIP Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2011/07/05 04:01:48.374 0:17.912 subscriber:0xb32ceb70 OpalUDP Started connect to 86.64.162.35:5060
2011/07/05 04:01:48.374 0:17.912 subscriber:0xb32ceb70 OpalUDP Writing to interface 0 - "187.65.248.7%eth0"
2011/07/05 04:01:50.777 0:20.316 subscriber:0xb32ceb70 STUN STUN server 77.72.169.166:3478 unexpectedly went offline.
2011/07/05 04:01:50.779 0:20.317 subscriber:0xb32ceb70 OpalMan Listener interfaces: associated transport=udp$187.65.248.7:5060
udp$*:5060,udp$187.65.248.7:5060
2011/07/05 04:01:50.780 0:20.318 subscriber:0xb32ceb70 SIP Transaction created.
2011/07/05 04:01:51.880 0:21.418 subscriber:0xb32ceb70 DNS SRV Lookup ekiga.net service _sip._udp
2011/07/05 04:01:52.298 0:21.836 subscriber:0xb32ceb70 SIP No SRV record found.
2011/07/05 04:01:52.298 0:21.837 subscriber:0xb32ceb70 SIP Transaction remote address is udp$ekiga.net:5060
2011/07/05 04:01:52.299 0:21.837 subscriber:0xb32ceb70 SIP Sending PDU (543 bytes) to: rem=udp$86.64.162.35:5060,local=udp$187.65.248.7:5060,if=187.65.248.7%eth0
REGISTER sip:ekiga.net SIP/2.0
CSeq: 1 REGISTER
Via: SIP/2.0/UDP 187.65.248.7:5060;branch=z9hG4bK44d3245d-d5a4-e011-9f66-00a0d17bf4e9;rport
User-Agent: Ekiga/3.2.7
From: <sip:stoneman66 ekiga net>;tag=2c17ce5a-d5a4-e011-9f66-00a0d17bf4e9
Call-ID: 4c06ce5a-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
To: <sip:stoneman66 ekiga net>
Contact: <sip:stoneman66@0.0.0.0>;q=1, <sip:stoneman66@187.65.248.7>;q=0.500
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 3600
Content-Length: 0
Max-Forwards: 70
2011/07/05 04:01:52.300 0:21.838 subscriber:0xb32ceb70 OpalUDP Setting interface to 187.65.248.7%eth0
2011/07/05 04:01:52.300 0:21.838 subscriber:0xb32ceb70 SIP Transaction timers set: retry=0.500, completion=6.000
2011/07/05 04:01:52.550 0:22.088 Opal Liste...0xb3d06b70 PWLib File handle low water mark set: 43 PUDPSocket
2011/07/05 04:01:52.550 0:22.088 Opal Liste...0xb3d06b70 OpalUDP Binding to interface: 187.65.248.7:5060
2011/07/05 04:01:52.550 0:22.088 Opal Liste...0xb3d06b70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$187.65.248.7:5060>
2011/07/05 04:01:52.551 0:22.089 Opal Liste...0xb3d06b70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$187.65.248.7:5060,if=187.65.248.7%eth0
SIP/2.0 401 Unauthorized
CSeq: 1 REGISTER
Via: SIP/2.0/UDP 187.65.248.7:5060;branch=z9hG4bK44d3245d-d5a4-e011-9f66-00a0d17bf4e9;rport=5060
Server: Kamailio (1.5.3-notls (i386/linux))
From: <sip:stoneman66 ekiga net>;tag=2c17ce5a-d5a4-e011-9f66-00a0d17bf4e9
Call-ID: 4c06ce5a-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
To: <sip:stoneman66 ekiga net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.1817
Content-Length: 0
WWW-Authenticate: Digest realm="ekiga.net", nonce="4e12002e00004ac58ffafe25455ae0c11afed18cf5201af2"
2011/07/05 04:01:52.551 0:22.090 Opal Liste...0xb3d06b70 SIP Queueing PDU "1 REGISTER <401>", transaction=z9hG4bK44d3245d-d5a4-e011-9f66-00a0d17bf4e9, token=4c06ce5a-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
2011/07/05 04:01:52.551 0:22.090 Opal Liste...0xb3d06b70 Opal Transport clean up on termination
2011/07/05 04:01:52.551 0:22.090 Opal Liste...0xb3d06b70 Opal Transport Close
2011/07/05 04:01:52.551 0:22.090 Pool:0xb3cbeb70 SIP Handling PDU "1 REGISTER <401>" for transaction=z9hG4bK44d3245d-d5a4-e011-9f66-00a0d17bf4e9
2011/07/05 04:01:52.552 0:22.090 Pool:0xb3cbeb70 SIP REGISTER transaction id=z9hG4bK44d3245d-d5a4-e011-9f66-00a0d17bf4e9 completed.
2011/07/05 04:01:52.552 0:22.090 Pool:0xb3cbeb70 OpalUDP Setting interface to 187.65.248.7%eth0
2011/07/05 04:01:52.552 0:22.090 Pool:0xb3cbeb70 SIP Received Authentication Required response
2011/07/05 04:01:52.551 0:22.090 Opal Liste...0xb3d06b70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$187.65.248.7:5060>
2011/07/05 04:01:52.552 0:22.090 Pool:0xb3cbeb70 SIP Located existing credentials for ID "stoneman66" at realm "ekiga.net"
2011/07/05 04:01:52.552 0:22.090 Pool:0xb3cbeb70 SIP Found auth info for realm ekiga.net
2011/07/05 04:01:52.552 0:22.090 Pool:0xb3cbeb70 SIP Changing REGISTER handler from Unavailable to Subscribing, target=sip:stoneman66 ekiga net, id=4c06ce5a-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
2011/07/05 04:01:52.553 0:22.091 Pool:0xb3cbeb70 OpalMan Listener interfaces: associated transport=udp$187.65.248.7:5060
udp$187.65.248.7:5060
2011/07/05 04:01:52.554 0:22.092 Pool:0xb3cbeb70 SIP Transaction created.
2011/07/05 04:01:52.555 0:22.094 Pool:0xb3cbeb70 SIP Adding authentication information for user "stoneman66" at realm "ekiga.net"
2011/07/05 04:01:52.556 0:22.094 Pool:0xb3cbeb70 DNS SRV Lookup ekiga.net service _sip._udp
2011/07/05 04:01:52.974 0:22.512 Pool:0xb3cbeb70 SIP No SRV record found.
2011/07/05 04:01:52.974 0:22.512 Pool:0xb3cbeb70 SIP Transaction remote address is udp$ekiga.net:5060
2011/07/05 04:01:52.975 0:22.513 Pool:0xb3cbeb70 SIP Sending PDU (712 bytes) to: rem=udp$86.64.162.35:5060,local=udp$187.65.248.7:5060,if=187.65.248.7%eth0
REGISTER sip:ekiga.net SIP/2.0
CSeq: 2 REGISTER
Via: SIP/2.0/UDP 187.65.248.7:5060;branch=z9hG4bK30f38b5d-d5a4-e011-9f66-00a0d17bf4e9;rport
User-Agent: Ekiga/3.2.7
Authorization: Digest username="stoneman66", realm="ekiga.net", nonce="4e12002e00004ac58ffafe25455ae0c11afed18cf5201af2", uri="sip:ekiga.net", algorithm=MD5, response="d549f2070aa3e46cfd0ab99b2b1081c0"
From: <sip:stoneman66 ekiga net>;tag=2c17ce5a-d5a4-e011-9f66-00a0d17bf4e9
Call-ID: 4c06ce5a-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
To: <sip:stoneman66 ekiga net>
Contact: <sip:stoneman66@187.65.248.7>;q=1
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 3600
Content-Length: 0
Max-Forwards: 70
2011/07/05 04:01:52.975 0:22.514 Pool:0xb3cbeb70 OpalUDP Setting interface to 187.65.248.7%eth0
2011/07/05 04:01:52.976 0:22.514 Pool:0xb3cbeb70 SIP Transaction timers set: retry=0.500, completion=6.000
2011/07/05 04:01:52.976 0:22.514 Pool:0xb3cbeb70 SIP Handled PDU "1 REGISTER <401>"
2011/07/05 04:01:53.229 0:22.767 Opal Liste...0xb3d06b70 OpalUDP Binding to interface: 187.65.248.7:5060
2011/07/05 04:01:53.229 0:22.767 Opal Liste...0xb3d06b70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$187.65.248.7:5060>
2011/07/05 04:01:53.230 0:22.768 Opal Liste...0xb3d06b70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$187.65.248.7:5060,if=187.65.248.7%eth0
SIP/2.0 200 OK
CSeq: 2 REGISTER
Via: SIP/2.0/UDP 187.65.248.7:5060;branch=z9hG4bK30f38b5d-d5a4-e011-9f66-00a0d17bf4e9;rport=5060
Server: Kamailio (1.5.3-notls (i386/linux))
From: <sip:stoneman66 ekiga net>;tag=2c17ce5a-d5a4-e011-9f66-00a0d17bf4e9
Call-ID: 4c06ce5a-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
To: <sip:stoneman66 ekiga net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.0c58
Contact: <sip:stoneman66@187.65.248.7>;q=1;expires=1200
Content-Length: 0
2011/07/05 04:01:53.231 0:22.769 Opal Liste...0xb3d06b70 SIP Queueing PDU "2 REGISTER <200>", transaction=z9hG4bK30f38b5d-d5a4-e011-9f66-00a0d17bf4e9, token=4c06ce5a-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
2011/07/05 04:01:53.231 0:22.769 Opal Liste...0xb3d06b70 Opal Transport clean up on termination
2011/07/05 04:01:53.231 0:22.769 Opal Liste...0xb3d06b70 Opal Transport Close
2011/07/05 04:01:53.231 0:22.769 Pool:0xb3cbeb70 SIP Handling PDU "2 REGISTER <200>" for transaction=z9hG4bK30f38b5d-d5a4-e011-9f66-00a0d17bf4e9
2011/07/05 04:01:53.231 0:22.769 Pool:0xb3cbeb70 SIP REGISTER transaction id=z9hG4bK30f38b5d-d5a4-e011-9f66-00a0d17bf4e9 completed.
2011/07/05 04:01:53.231 0:22.770 Pool:0xb3cbeb70 OpalUDP Setting interface to 187.65.248.7%eth0
2011/07/05 04:01:53.231 0:22.770 Pool:0xb3cbeb70 SIP Product Info: name="Kamailio", version="", vendor="", comments="(1.5.3-notls (i386/linux))"
2011/07/05 04:01:53.232 0:22.770 Pool:0xb3cbeb70 SIP Changing REGISTER handler from Subscribing to Subscribed, target=sip:stoneman66 ekiga net, id=4c06ce5a-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
2011/07/05 04:01:53.231 0:22.769 Opal Liste...0xb3d06b70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$187.65.248.7:5060>
2011/07/05 04:01:53.232 0:22.771 Pool:0xb3cbeb70 SIP Expiry time for REGISTER set to 1200 seconds.
2011/07/05 04:01:53.232 0:22.771 Pool:0xb3cbeb70 SIP Product Info: name="Kamailio", version="", vendor="", comments="(1.5.3-notls (i386/linux))"
2011/07/05 04:01:53.233 0:22.771 Pool:0xb3cbeb70 SIP Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:stoneman66 ekiga net, id=c460f35d-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
2011/07/05 04:01:53.233 0:22.772 Pool:0xb3cbeb70 DNS SRV Lookup ekiga.net service _sip._udp
2011/07/05 04:01:53.665 0:23.203 Pool:0xb3cbeb70 SIP No SRV record found.
2011/07/05 04:01:53.665 0:23.203 Pool:0xb3cbeb70 OpalUDP Binding to interface: 0.0.0.0:5060
2011/07/05 04:01:53.665 0:23.203 Pool:0xb3cbeb70 SIP Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2011/07/05 04:01:53.665 0:23.204 Pool:0xb3cbeb70 OpalUDP Started connect to 86.64.162.35:5060
2011/07/05 04:01:53.666 0:23.204 Pool:0xb3cbeb70 OpalUDP Writing to interface 0 - "187.65.248.7%eth0"
2011/07/05 04:01:53.666 0:23.204 Pool:0xb3cbeb70 SIP Transaction created.
2011/07/05 04:01:53.970 0:23.508 Pool:0xb3cbeb70 DNS SRV Lookup ekiga.net service _sip._udp
2011/07/05 04:01:53.976 0:23.514 Housekeeper:0xb3d47b70 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bK44d3245d-d5a4-e011-9f66-00a0d17bf4e9
2011/07/05 04:01:54.376 0:23.914 Pool:0xb3cbeb70 SIP No SRV record found.
2011/07/05 04:01:54.376 0:23.914 Pool:0xb3cbeb70 SIP Transaction remote address is udp$ekiga.net:5060
2011/07/05 04:01:54.377 0:23.915 Pool:0xb3cbeb70 SIP Sending PDU (586 bytes) to: rem=udp$86.64.162.35:5060,local=udp$187.65.248.7:5060,if=187.65.248.7%eth0
SUBSCRIBE sip:stoneman66 ekiga net SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 187.65.248.7:5060;branch=z9hG4bK2670635e-d5a4-e011-9f66-00a0d17bf4e9;rport
User-Agent: Ekiga/3.2.7
From: <sip:stoneman66 ekiga net>;tag=046b355e-d5a4-e011-9f66-00a0d17bf4e9
Call-ID: c460f35d-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
To: <sip:stoneman66 ekiga net>
Contact: <sip:stoneman66@187.65.248.7>
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/07/05 04:01:54.377 0:23.916 Pool:0xb3cbeb70 OpalUDP Setting interface to 187.65.248.7%eth0
2011/07/05 04:01:54.378 0:23.916 Pool:0xb3cbeb70 SIP Transaction timers set: retry=0.500, completion=6.000
2011/07/05 04:01:54.378 0:23.916 Pool:0xb3cbeb70 SIP Handled PDU "2 REGISTER <200>"
2011/07/05 04:01:54.475 0:24.013 SIP Changing PUBLISH handler from Unavailable to Subscribing, target=sip:stoneman66 ekiga net, id=acd8b05e-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
2011/07/05 04:01:54.475 0:24.013 DNS SRV Lookup ekiga.net service _sip._udp
2011/07/05 04:01:54.644 0:24.182 Opal Liste...0xb3d06b70 OpalUDP Binding to interface: 187.65.248.7:5060
2011/07/05 04:01:54.644 0:24.183 Opal Liste...0xb3d06b70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$187.65.248.7:5060>
2011/07/05 04:01:54.645 0:24.183 Opal Liste...0xb3d06b70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$187.65.248.7:5060,if=187.65.248.7%eth0
SIP/2.0 489 Event Not Supported
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 187.65.248.7:5060;branch=z9hG4bK2670635e-d5a4-e011-9f66-00a0d17bf4e9;rport=5060
Server: Kamailio (1.5.3-notls (i386/linux))
From: <sip:stoneman66 ekiga net>;tag=046b355e-d5a4-e011-9f66-00a0d17bf4e9
Call-ID: c460f35d-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
To: <sip:stoneman66 ekiga net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.73c2
Content-Length: 0
2011/07/05 04:01:54.645 0:24.184 Opal Liste...0xb3d06b70 SIP Queueing PDU "2 SUBSCRIBE <489>", transaction=z9hG4bK2670635e-d5a4-e011-9f66-00a0d17bf4e9, token=c460f35d-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
2011/07/05 04:01:54.645 0:24.184 Opal Liste...0xb3d06b70 Opal Transport clean up on termination
2011/07/05 04:01:54.645 0:24.184 Opal Liste...0xb3d06b70 Opal Transport Close
2011/07/05 04:01:54.646 0:24.184 Pool:0xb3cbeb70 SIP Handling PDU "2 SUBSCRIBE <489>" for transaction=z9hG4bK2670635e-d5a4-e011-9f66-00a0d17bf4e9
2011/07/05 04:01:54.646 0:24.184 Pool:0xb3cbeb70 SIP SUBSCRIBE transaction id=z9hG4bK2670635e-d5a4-e011-9f66-00a0d17bf4e9 completed.
2011/07/05 04:01:54.646 0:24.184 Pool:0xb3cbeb70 OpalUDP Setting interface to 187.65.248.7%eth0
2011/07/05 04:01:54.646 0:24.184 Pool:0xb3cbeb70 SIP Not retrying SUBSCRIBE due to error response 489 Bad Event
2011/07/05 04:01:54.646 0:24.184 Pool:0xb3cbeb70 SIP Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:stoneman66 ekiga net, id=c460f35d-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
2011/07/05 04:01:54.646 0:24.184 Pool:0xb3cbeb70 SIP Handled PDU "2 SUBSCRIBE <489>"
2011/07/05 04:01:54.646 0:24.184 Opal Liste...0xb3d06b70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$187.65.248.7:5060>
2011/07/05 04:01:54.904 0:24.442 SIP No SRV record found.
2011/07/05 04:01:54.904 0:24.443 OpalUDP Binding to interface: 0.0.0.0:5060
2011/07/05 04:01:54.905 0:24.443 SIP Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2011/07/05 04:01:54.905 0:24.444 OpalUDP Started connect to 86.64.162.35:5060
2011/07/05 04:01:54.905 0:24.444 OpalUDP Writing to interface 0 - "187.65.248.7%eth0"
2011/07/05 04:01:54.906 0:24.444 SIP Transaction created.
2011/07/05 04:01:54.910 0:24.449 DNS SRV Lookup ekiga.net service _sip._udp
2011/07/05 04:01:55.313 0:24.852 SIP No SRV record found.
2011/07/05 04:01:55.313 0:24.852 SIP Transaction remote address is udp$ekiga.net:5060
2011/07/05 04:01:55.314 0:24.852 SIP Sending PDU (829 bytes) to: rem=udp$86.64.162.35:5060,local=udp$187.65.248.7:5060,if=187.65.248.7%eth0
PUBLISH sip:stoneman66 ekiga net SIP/2.0
CSeq: 3 PUBLISH
Via: SIP/2.0/UDP 187.65.248.7:5060;branch=z9hG4bKfcfaf25e-d5a4-e011-9f66-00a0d17bf4e9;rport
User-Agent: Ekiga/3.2.7
From: <sip:stoneman66 ekiga net>;tag=f4a3f25e-d5a4-e011-9f66-00a0d17bf4e9
Call-ID: acd8b05e-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
To: <sip:stoneman66 ekiga net>
Contact: <sip:stoneman66@187.65.248.7>
Expires: 500
Event: presence
Content-Type: application/pidf+xml
Content-Length: 340
Max-Forwards: 70
<?xml version="1.0" encoding="UTF-8"?>
<presence xmlns="urn:ietf:params:xml:ns:pidf" entity="pres:stoneman66 ekiga net">
<tuple id="sip:stoneman66@ekiga.net_on_roblaptop">
<note>online - I'm online using Ekiga</note>
<status>
<basic>open</basic>
</status>
<contact priority="1">stoneman66 ekiga net</contact>
</tuple>
</presence>
2011/07/05 04:01:55.314 0:24.853 OpalUDP Setting interface to 187.65.248.7%eth0
2011/07/05 04:01:55.314 0:24.853 SIP Transaction timers set: retry=0.500, completion=6.000
2011/07/05 04:01:55.378 0:24.916 Housekeeper:0xb3d47b70 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bK30f38b5d-d5a4-e011-9f66-00a0d17bf4e9
2011/07/05 04:01:55.378 0:24.916 Opal Garbage:0xb3dc9b70 SIP Transaction id=z9hG4bK44d3245d-d5a4-e011-9f66-00a0d17bf4e9 destroyed.
2011/07/05 04:01:55.378 0:24.917 Opal Garbage:0xb3dc9b70 SIP Transaction id=z9hG4bK30f38b5d-d5a4-e011-9f66-00a0d17bf4e9 destroyed.
2011/07/05 04:01:55.379 0:24.917 Opal Garbage:0xb3dc9b70 SIP Deleting SIPSubscribeHandler sip:stoneman66 ekiga net
2011/07/05 04:01:55.379 0:24.917 Opal Garbage:0xb3dc9b70 Opal Transport clean up on termination
2011/07/05 04:01:55.379 0:24.917 Opal Garbage:0xb3dc9b70 Opal Transport Close
2011/07/05 04:01:55.379 0:24.917 Opal Garbage:0xb3dc9b70 Opal Transport clean up on termination
2011/07/05 04:01:55.379 0:24.917 Opal Garbage:0xb3dc9b70 Opal Transport Close
2011/07/05 04:01:55.379 0:24.917 Opal Garbage:0xb3dc9b70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$187.65.248.7:5060>
2011/07/05 04:01:55.380 0:24.918 Opal Garbage:0xb3dc9b70 SIP Deleted handler.
2011/07/05 04:01:55.568 0:25.106 Opal Liste...0xb3d06b70 OpalUDP Binding to interface: 187.65.248.7:5060
2011/07/05 04:01:55.568 0:25.107 Opal Liste...0xb3d06b70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$187.65.248.7:5060>
2011/07/05 04:01:55.569 0:25.107 Opal Liste...0xb3d06b70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$187.65.248.7:5060,if=187.65.248.7%eth0
SIP/2.0 200 OK
CSeq: 3 PUBLISH
Via: SIP/2.0/UDP 187.65.248.7:5060;branch=z9hG4bKfcfaf25e-d5a4-e011-9f66-00a0d17bf4e9;rport=5060
Server: Kamailio (1.5.3-notls (i386/linux))
From: <sip:stoneman66 ekiga net>;tag=f4a3f25e-d5a4-e011-9f66-00a0d17bf4e9
Call-ID: acd8b05e-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
To: <sip:stoneman66 ekiga net>;tag=f85b0bd16aaafa8479586ac9f88b3198-2a31
Expires: 495
Content-Length: 0
SIP-ETag: a.1303844361.1027.6735103.0
2011/07/05 04:01:55.569 0:25.108 Opal Liste...0xb3d06b70 SIP Queueing PDU "3 PUBLISH <200>", transaction=z9hG4bKfcfaf25e-d5a4-e011-9f66-00a0d17bf4e9, token=acd8b05e-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
2011/07/05 04:01:55.569 0:25.108 Opal Liste...0xb3d06b70 Opal Transport clean up on termination
2011/07/05 04:01:55.569 0:25.108 Opal Liste...0xb3d06b70 Opal Transport Close
2011/07/05 04:01:55.569 0:25.108 Pool:0xb3cbeb70 SIP Handling PDU "3 PUBLISH <200>" for transaction=z9hG4bKfcfaf25e-d5a4-e011-9f66-00a0d17bf4e9
2011/07/05 04:01:55.570 0:25.108 Pool:0xb3cbeb70 SIP PUBLISH transaction id=z9hG4bKfcfaf25e-d5a4-e011-9f66-00a0d17bf4e9 completed.
2011/07/05 04:01:55.570 0:25.108 Pool:0xb3cbeb70 OpalUDP Setting interface to 187.65.248.7%eth0
2011/07/05 04:01:55.570 0:25.108 Pool:0xb3cbeb70 SIP Expiry time for PUBLISH set to 495 seconds.
2011/07/05 04:01:55.570 0:25.108 Pool:0xb3cbeb70 SIP Product Info: name="Kamailio", version="", vendor="", comments="(1.5.3-notls (i386/linux))"
2011/07/05 04:01:55.570 0:25.108 Pool:0xb3cbeb70 SIP Changing PUBLISH handler from Subscribing to Subscribed, target=sip:stoneman66 ekiga net, id=acd8b05e-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
2011/07/05 04:01:55.570 0:25.108 Pool:0xb3cbeb70 SIP Handled PDU "3 PUBLISH <200>"
2011/07/05 04:01:55.570 0:25.108 Opal Liste...0xb3d06b70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$187.65.248.7:5060>
2011/07/05 04:01:55.646 0:25.184 Housekeeper:0xb3d47b70 SIP Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK2670635e-d5a4-e011-9f66-00a0d17bf4e9
2011/07/05 04:01:56.380 0:25.919 Opal Garbage:0xb3dc9b70 SIP Transaction id=z9hG4bK2670635e-d5a4-e011-9f66-00a0d17bf4e9 destroyed.
2011/07/05 04:01:56.570 0:26.108 Housekeeper:0xb3d47b70 SIP Set state Terminated_Success for PUBLISH transaction id=z9hG4bKfcfaf25e-d5a4-e011-9f66-00a0d17bf4e9
2011/07/05 04:01:57.383 0:26.922 Opal Garbage:0xb3dc9b70 SIP Transaction id=z9hG4bKfcfaf25e-d5a4-e011-9f66-00a0d17bf4e9 destroyed.
2011/07/05 04:01:59.680 0:29.219 OpalMan Set up call from pc:* to sip:500 ekiga net
2011/07/05 04:01:59.680 0:29.219 Call Created Call[m4f1b4f3c1]
2011/07/05 04:01:59.681 0:29.219 OpalMan Set up connection to "pc:*"
2011/07/05 04:01:59.681 0:29.219 OpalCon Created connection Call[m4f1b4f3c1]-EP<pc>[gc1475bf22]
2011/07/05 04:01:59.681 0:29.219 Silence Parameters set: mode=NoSilenceDetection, threshold=0, silencedb=3200, signaldb=80, period=4800
2011/07/05 04:01:59.681 0:29.219 Silence Handler created
2011/07/05 04:01:59.681 0:29.219 Echo Canceler Handler created
2011/07/05 04:01:59.681 0:29.220 PCSS Created PC sound system connection: token="gc1475bf22" player="EKIGA" recorder="EKIGA"
2011/07/05 04:01:59.681 0:29.220 OpalCon SetPhase from UninitialisedPhase to SetUpPhase for Call[m4f1b4f3c1]-EP<pc>[gc1475bf22]
2011/07/05 04:01:59.682 0:29.220 OpalMan OnIncoming connection Call[m4f1b4f3c1]-EP<pc>[gc1475bf22]
2011/07/05 04:01:59.682 0:29.220 OpalCon Applying string options:
2011/07/05 04:01:59.682 0:29.220 Call GetOtherPartyConnection Call[m4f1b4f3c1]-EP<pc>[gc1475bf22]
2011/07/05 04:01:59.682 0:29.220 OpalMan Searching for route "pc:rob sip:500 ekiga net"
2011/07/05 04:01:59.682 0:29.220 OpalMan Set up connection to "sip:500 ekiga net"
2011/07/05 04:01:59.682 0:29.221 OpalCon Created connection Call[m4f1b4f3c1]-EP<sip>[1c73cb61-d5a4-e011-9f66-00a0d17bf4e9]
2011/07/05 04:01:59.682 0:29.221 RFC2833 Handler created
2011/07/05 04:01:59.683 0:29.221 RFC2833 Handler created
2011/07/05 04:01:59.683 0:29.221 SIP Created connection.
2011/07/05 04:01:59.683 0:29.222 PCSS Outgoing call routed to sip:500 ekiga net for Call[m4f1b4f3c1]-EP<pc>[gc1475bf22]
2011/07/05 04:01:59.683 0:29.222 OpalMan SetUpCall succeeded, call=Call[m4f1b4f3c1]
2011/07/05 04:01:59.684 0:29.222 CallSetup:0xb32ceb70 Call OnSetUp Call[m4f1b4f3c1]-EP<pc>[gc1475bf22]
2011/07/05 04:01:59.684 0:29.222 CallSetup:0xb32ceb70 SIP SetUpConnection: sip:500 ekiga net
2011/07/05 04:01:59.684 0:29.222 CallSetup:0xb32ceb70 OpalCon SetPhase from UninitialisedPhase to SetUpPhase for Call[m4f1b4f3c1]-EP<sip>[1c73cb61-d5a4-e011-9f66-00a0d17bf4e9]
2011/07/05 04:01:59.684 0:29.222 CallSetup:0xb32ceb70 OpalCon Applying string options:
2011/07/05 04:01:59.684 0:29.222 CallSetup:0xb32ceb70 DNS SRV Lookup ekiga.net service _sip._udp
2011/07/05 04:02:00.106 0:29.645 CallSetup:0xb32ceb70 SIP No SRV record found.
2011/07/05 04:02:00.107 0:29.645 CallSetup:0xb32ceb70 SIP Connecting to sip:500 ekiga net via sip:500 ekiga net
2011/07/05 04:02:00.107 0:29.645 CallSetup:0xb32ceb70 SIP Found registrar on domain ekiga.net, using interface 187.65.248.7%eth0
2011/07/05 04:02:00.108 0:29.646 CallSetup:0xb32ceb70 OpalUDP Binding to interface: 187.65.248.7:5060
2011/07/05 04:02:00.108 0:29.646 CallSetup:0xb32ceb70 SIP Created transport udp$86.64.162.35:5060<if=udp$187.65.248.7:5060>
2011/07/05 04:02:00.108 0:29.647 CallSetup:0xb32ceb70 OpalUDP Started connect to 86.64.162.35:5060
2011/07/05 04:02:00.109 0:29.647 CallSetup:0xb32ceb70 SIP Updating dialog tag from "" to "1c73cb61-d5a4-e011-9f66-00a0d17bf4e9"
2011/07/05 04:02:00.423 0:29.961 CallSetup:0xb32ceb70 SIP INVITE transaction id=z9hG4bK96783c62-d5a4-e011-9f66-00a0d17bf4e9 created.
2011/07/05 04:02:00.423 0:29.962 CallSetup:0xb32ceb70 SIP Creating INVITE request
2011/07/05 04:02:00.431 0:29.969 CallSetup:0xb32ceb70 MediaFormat Removing codecs PCM-16-48kHz,CELT-48K,PCM-16-32kHz,CELT-32K,PCM-16-16kHz,G.722-64k,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2011/07/05 04:02:00.432 0:29.970 CallSetup:0xb32ceb70 MediaFormat Removing codecs PCM-16-48kHz,CELT-48K,PCM-16-32kHz,CELT-32K,PCM-16-16kHz,G.722-64k,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2011/07/05 04:02:00.432 0:29.971 CallSetup:0xb32ceb70 Call GetMediaFormats for Call[m4f1b4f3c1]-EP<sip>[1c73cb61-d5a4-e011-9f66-00a0d17bf4e9]
SpeexIETFWide-20.6k
SpeexWB
SpeexWide-20.6k
G.711-uLaw-64k
G.711-ALaw-64k
theora
H.261
H.261-CIF
H.261-QCIF
2011/07/05 04:02:00.432 0:29.971 CallSetup:0xb32ceb70 SIP Offering media type audio in SDP with formats
SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,theora,H.261,H.261-CIF,H.261-QCIF
2011/07/05 04:02:00.433 0:29.971 CallSetup:0xb32ceb70 Call IsMediaBypassPossible Call[m4f1b4f3c1]-EP<sip>[1c73cb61-d5a4-e011-9f66-00a0d17bf4e9] session 1
2011/07/05 04:02:00.433 0:29.971 CallSetup:0xb32ceb70 OpalMan IsMediaBypassPossible: session 1
2011/07/05 04:02:00.433 0:29.971 CallSetup:0xb32ceb70 OpalCon IsMediaBypassPossible: default returns false
2011/07/05 04:02:00.433 0:29.971 CallSetup:0xb32ceb70 RTP Cannot find media session 1
2011/07/05 04:02:00.433 0:29.971 CallSetup:0xb32ceb70 RTP Cannot find RTP session 1
2011/07/05 04:02:00.434 0:29.972 CallSetup:0xb32ceb70 RTP_UDP Session 1, created with NAT flag set to 0
2011/07/05 04:02:00.434 0:29.972 CallSetup:0xb32ceb70 PWLib File handle high water mark set: 50 PUDPSocket
2011/07/05 04:02:00.434 0:29.972 CallSetup:0xb32ceb70 PWLib File handle low water mark set: 49 PUDPSocket
2011/07/05 04:02:00.434 0:29.972 CallSetup:0xb32ceb70 RTP_UDP Session 1 created: 187.65.248.7:5062-5063 ssrc=4290305691
2011/07/05 04:02:00.434 0:29.973 CallSetup:0xb32ceb70 PWLib File handle high water mark set: 51 PUDPSocket
2011/07/05 04:02:00.435 0:29.973 CallSetup:0xb32ceb70 RTP Creating new session RTP_UDP
2011/07/05 04:02:00.435 0:29.973 CallSetup:0xb32ceb70 RTP Found existing media session 1
2011/07/05 04:02:00.436 0:29.974 CallSetup:0xb32ceb70 SDP SDP not including SpeexIETFWide-20.6k as it is not a SIP transportable format
2011/07/05 04:02:00.436 0:29.974 CallSetup:0xb32ceb70 SDP SDP not including SpeexWide-20.6k as it is not a SIP transportable format
2011/07/05 04:02:00.436 0:29.974 CallSetup:0xb32ceb70 SIP Using default RTP payload [pt=101] for UserInput/RFC2833
2011/07/05 04:02:00.436 0:29.974 CallSetup:0xb32ceb70 SIP Using default RTP payload [pt=122] for NamedSignalEvent
2011/07/05 04:02:00.440 0:29.979 CallSetup:0xb32ceb70 MediaFormat Removing codecs PCM-16-48kHz,CELT-48K,PCM-16-32kHz,CELT-32K,PCM-16-16kHz,G.722-64k,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2011/07/05 04:02:00.441 0:29.980 CallSetup:0xb32ceb70 MediaFormat Removing codecs PCM-16-48kHz,CELT-48K,PCM-16-32kHz,CELT-32K,PCM-16-16kHz,G.722-64k,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2011/07/05 04:02:00.442 0:29.980 CallSetup:0xb32ceb70 Call GetMediaFormats for Call[m4f1b4f3c1]-EP<sip>[1c73cb61-d5a4-e011-9f66-00a0d17bf4e9]
SpeexIETFWide-20.6k
SpeexWB
SpeexWide-20.6k
G.711-uLaw-64k
G.711-ALaw-64k
theora
H.261
H.261-CIF
H.261-QCIF
2011/07/05 04:02:00.442 0:29.980 CallSetup:0xb32ceb70 SIP Offering media type video in SDP with formats
SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,theora,H.261,H.261-CIF,H.261-QCIF
2011/07/05 04:02:00.442 0:29.980 CallSetup:0xb32ceb70 Call IsMediaBypassPossible Call[m4f1b4f3c1]-EP<sip>[1c73cb61-d5a4-e011-9f66-00a0d17bf4e9] session 2
2011/07/05 04:02:00.442 0:29.980 CallSetup:0xb32ceb70 OpalMan IsMediaBypassPossible: session 2
2011/07/05 04:02:00.442 0:29.980 CallSetup:0xb32ceb70 OpalCon IsMediaBypassPossible: default returns false
2011/07/05 04:02:00.442 0:29.980 CallSetup:0xb32ceb70 RTP Cannot find media session 2
2011/07/05 04:02:00.442 0:29.980 CallSetup:0xb32ceb70 RTP Cannot find RTP session 2
2011/07/05 04:02:00.443 0:29.981 CallSetup:0xb32ceb70 RTP_UDP Session 2, created with NAT flag set to 0
2011/07/05 04:02:00.443 0:29.981 CallSetup:0xb32ceb70 PWLib File handle high water mark set: 52 PUDPSocket
2011/07/05 04:02:00.443 0:29.981 CallSetup:0xb32ceb70 PWLib File handle low water mark set: 51 PUDPSocket
2011/07/05 04:02:00.443 0:29.981 CallSetup:0xb32ceb70 RTP_UDP Session 2 created: 187.65.248.7:5064-5065 ssrc=50500197
2011/07/05 04:02:00.443 0:29.982 CallSetup:0xb32ceb70 PWLib File handle high water mark set: 53 PUDPSocket
2011/07/05 04:02:00.444 0:29.982 CallSetup:0xb32ceb70 RTP Creating new session RTP_UDP
2011/07/05 04:02:00.444 0:29.982 CallSetup:0xb32ceb70 RTP Found existing media session 2
2011/07/05 04:02:00.445 0:29.983 CallSetup:0xb32ceb70 SDP SDP not including H.261-CIF as it is not a SIP transportable format
2011/07/05 04:02:00.445 0:29.983 CallSetup:0xb32ceb70 SDP SDP not including H.261-QCIF as it is not a SIP transportable format
2011/07/05 04:02:00.445 0:29.984 CallSetup:0xb32ceb70 DNS SRV Lookup ekiga.net service _sip._udp
2011/07/05 04:02:00.767 0:30.305 Network In...0xb3d88b70 PWLib File handle high water mark set: 54 PUDPSocket
2011/07/05 04:02:00.915 0:30.453 CallSetup:0xb32ceb70 SIP No SRV record found.
2011/07/05 04:02:00.915 0:30.453 CallSetup:0xb32ceb70 SIP Transaction remote address is udp$ekiga.net:5060
2011/07/05 04:02:00.916 0:30.454 CallSetup:0xb32ceb70 OpalPlugin to_customised_options: theora
2011/07/05 04:02:00.917 0:30.456 CallSetup:0xb32ceb70 OpalPlugin to_customised_options: H.261
2011/07/05 04:02:00.920 0:30.458 CallSetup:0xb32ceb70 PWLib File handle low water mark set: 53 PUDPSocket
2011/07/05 04:02:00.919 0:30.457 CallSetup:0xb32ceb70 SIP Sending PDU (1091 bytes) to: rem=udp$86.64.162.35:5060,local=udp$187.65.248.7:5060,if=187.65.248.7%eth0
INVITE sip:500 ekiga net SIP/2.0
Date: Mon, 04 Jul 2011 18:02:00 GMT
CSeq: 1 INVITE
Via: SIP/2.0/UDP 187.65.248.7:5060;branch=z9hG4bK96783c62-d5a4-e011-9f66-00a0d17bf4e9;rport
User-Agent: Ekiga/3.2.7
From: "Rob Stone" <sip:stoneman66 ekiga net>;tag=1c73cb61-d5a4-e011-9f66-00a0d17bf4e9
Call-ID: e482cb61-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
To: <sip:500 ekiga net>
Contact: <sip:stoneman66@187.65.248.7>
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Content-Type: application/sdp
Content-Length: 526
Max-Forwards: 70
v=0
o=- 1309802519 1 IN IP4 187.65.248.7
s=Opal SIP Session
c=IN IP4 187.65.248.7
t=0 0
m=audio 5062 RTP/AVP 119 0 8 101 122
a=sendrecv
a=rtpmap:119 Speex/16000/1
a=fmtp:119 sr=16000,mode=any
a=rtpmap:0 PCMU/8000/1
a=rtpmap:8 PCMA/8000/1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16,32,36
a=rtpmap:122 NSE/8000
a=fmtp:122 192-193
m=video 5064 RTP/AVP 121 31
b=AS:4096
b=TIAS:4096000
a=sendrecv
a=rtpmap:121 theora/90000
a=fmtp:121 height=576;width=704
a=rtpmap:31 h261/90000
a=fmtp:31 CIF=1;QCIF=1
2011/07/05 04:02:00.920 0:30.458 CallSetup:0xb32ceb70 OpalUDP Setting interface to 187.65.248.7%eth0
2011/07/05 04:02:00.920 0:30.459 CallSetup:0xb32ceb70 SIP Transaction timers set: retry=0.500, completion=1:00.000
2011/07/05 04:02:00.920 0:30.459 CallSetup:0xb32ceb70 OpalCon OnSetUpConnectionCall[m4f1b4f3c1]-EP<sip>[1c73cb61-d5a4-e011-9f66-00a0d17bf4e9]
2011/07/05 04:02:00.921 0:30.459 CallSetup:0xb32ceb70 OpalEP OnSetUpConnection Call[m4f1b4f3c1]-EP<sip>[1c73cb61-d5a4-e011-9f66-00a0d17bf4e9]
2011/07/05 04:02:01.171 0:30.709 Opal Liste...0xb3d06b70 PWLib File handle low water mark set: 43 PUDPSocket
2011/07/05 04:02:01.171 0:30.709 Opal Liste...0xb3d06b70 OpalUDP Binding to interface: 187.65.248.7:5060
2011/07/05 04:02:01.171 0:30.710 Opal Liste...0xb3d06b70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$187.65.248.7:5060>
2011/07/05 04:02:01.172 0:30.711 Opal Liste...0xb3d06b70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$187.65.248.7:5060,if=187.65.248.7%eth0
SIP/2.0 407 Proxy Authentication Required
CSeq: 1 INVITE
Via: SIP/2.0/UDP 187.65.248.7:5060;branch=z9hG4bK96783c62-d5a4-e011-9f66-00a0d17bf4e9;rport=5060
Server: Kamailio (1.5.3-notls (i386/linux))
From: "Rob Stone" <sip:stoneman66 ekiga net>;tag=1c73cb61-d5a4-e011-9f66-00a0d17bf4e9
Call-ID: e482cb61-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
To: <sip:500 ekiga net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.7641
Proxy-Authenticate: Digest realm="ekiga.net", nonce="4e12003700004afadb89c29929483e4d1b445c660fe4e4af"
Content-Length: 0
2011/07/05 04:02:01.173 0:30.711 Opal Liste...0xb3d06b70 SIP Queueing PDU "1 INVITE <407>", transaction=z9hG4bK96783c62-d5a4-e011-9f66-00a0d17bf4e9, token=1c73cb61-d5a4-e011-9f66-00a0d17bf4e9
2011/07/05 04:02:01.173 0:30.712 Opal Liste...0xb3d06b70 PTLib No permission to set priority level 3
2011/07/05 04:02:01.173 0:30.712 Opal Liste...0xb3d06b70 Opal Transport clean up on termination
2011/07/05 04:02:01.173 0:30.712 Opal Liste...0xb3d06b70 Opal Transport Close
2011/07/05 04:02:01.174 0:30.712 Pool:0xb32ceb70 SIP Handling PDU "1 INVITE <407>" for transaction=z9hG4bK96783c62-d5a4-e011-9f66-00a0d17bf4e9
2011/07/05 04:02:01.174 0:30.712 Opal Liste...0xb3d06b70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$187.65.248.7:5060>
2011/07/05 04:02:01.176 0:30.715 Pool:0xb32ceb70 SIP Sending PDU (387 bytes) to: rem=udp$86.64.162.35:5060,local=udp$187.65.248.7:5060,if=187.65.248.7%eth0
ACK sip:500 ekiga net SIP/2.0
CSeq: 1 ACK
Via: SIP/2.0/UDP 187.65.248.7:5060;branch=z9hG4bK96783c62-d5a4-e011-9f66-00a0d17bf4e9;rport
From: "Rob Stone" <sip:stoneman66 ekiga net>;tag=1c73cb61-d5a4-e011-9f66-00a0d17bf4e9
Call-ID: e482cb61-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
To: <sip:500 ekiga net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.7641
Content-Length: 0
Max-Forwards: 70
2011/07/05 04:02:01.177 0:30.715 Pool:0xb32ceb70 OpalUDP Setting interface to 187.65.248.7%eth0
2011/07/05 04:02:01.177 0:30.715 Pool:0xb32ceb70 SIP INVITE transaction id=z9hG4bK96783c62-d5a4-e011-9f66-00a0d17bf4e9 completed.
2011/07/05 04:02:01.177 0:30.716 Pool:0xb32ceb70 SIP Received Proxy Authentication Required response
2011/07/05 04:02:01.178 0:30.716 Pool:0xb32ceb70 SIP Located existing credentials for ID "stoneman66" at realm "ekiga.net"
2011/07/05 04:02:01.178 0:30.716 Pool:0xb32ceb70 SIP Found auth info for realm "ekiga.net", user "stoneman66"
2011/07/05 04:02:01.178 0:30.716 Pool:0xb32ceb70 OpalUDP Setting interface to 187.65.248.7%eth0
2011/07/05 04:02:01.183 0:30.721 Pool:0xb32ceb70 SIP INVITE transaction id=z9hG4bK4c6cb062-d5a4-e011-9f66-00a0d17bf4e9 created.
2011/07/05 04:02:01.183 0:30.721 Pool:0xb32ceb70 SIP Creating INVITE request
2011/07/05 04:02:01.189 0:30.728 Pool:0xb32ceb70 MediaFormat Removing codecs PCM-16-48kHz,CELT-48K,PCM-16-32kHz,CELT-32K,PCM-16-16kHz,G.722-64k,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2011/07/05 04:02:01.190 0:30.728 Pool:0xb32ceb70 MediaFormat Removing codecs PCM-16-48kHz,CELT-48K,PCM-16-32kHz,CELT-32K,PCM-16-16kHz,G.722-64k,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2011/07/05 04:02:01.191 0:30.729 Pool:0xb32ceb70 Call GetMediaFormats for Call[m4f1b4f3c1]-EP<sip>[1c73cb61-d5a4-e011-9f66-00a0d17bf4e9]
SpeexIETFWide-20.6k
SpeexWB
SpeexWide-20.6k
G.711-uLaw-64k
G.711-ALaw-64k
theora
H.261
H.261-CIF
H.261-QCIF
2011/07/05 04:02:01.191 0:30.729 Pool:0xb32ceb70 SIP Offering media type audio in SDP with formats
SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,theora,H.261,H.261-CIF,H.261-QCIF
2011/07/05 04:02:01.191 0:30.729 Pool:0xb32ceb70 Call IsMediaBypassPossible Call[m4f1b4f3c1]-EP<sip>[1c73cb61-d5a4-e011-9f66-00a0d17bf4e9] session 1
2011/07/05 04:02:01.191 0:30.729 Pool:0xb32ceb70 OpalMan IsMediaBypassPossible: session 1
2011/07/05 04:02:01.191 0:30.729 Pool:0xb32ceb70 OpalCon IsMediaBypassPossible: default returns false
2011/07/05 04:02:01.191 0:30.729 Pool:0xb32ceb70 RTP Found existing media session 1
2011/07/05 04:02:01.191 0:30.729 Pool:0xb32ceb70 RTP Found existing RTP session 1
2011/07/05 04:02:01.192 0:30.730 Pool:0xb32ceb70 SDP SDP not including SpeexIETFWide-20.6k as it is not a SIP transportable format
2011/07/05 04:02:01.192 0:30.730 Pool:0xb32ceb70 SDP SDP not including SpeexWide-20.6k as it is not a SIP transportable format
2011/07/05 04:02:01.192 0:30.730 Pool:0xb32ceb70 SIP Using default RTP payload [pt=101] for UserInput/RFC2833
2011/07/05 04:02:01.192 0:30.730 Pool:0xb32ceb70 SIP Using default RTP payload [pt=122] for NamedSignalEvent
2011/07/05 04:02:01.196 0:30.735 Pool:0xb32ceb70 MediaFormat Removing codecs PCM-16-48kHz,CELT-48K,PCM-16-32kHz,CELT-32K,PCM-16-16kHz,G.722-64k,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2011/07/05 04:02:01.197 0:30.736 Pool:0xb32ceb70 MediaFormat Removing codecs PCM-16-48kHz,CELT-48K,PCM-16-32kHz,CELT-32K,PCM-16-16kHz,G.722-64k,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2011/07/05 04:02:01.198 0:30.736 Pool:0xb32ceb70 Call GetMediaFormats for Call[m4f1b4f3c1]-EP<sip>[1c73cb61-d5a4-e011-9f66-00a0d17bf4e9]
SpeexIETFWide-20.6k
SpeexWB
SpeexWide-20.6k
G.711-uLaw-64k
G.711-ALaw-64k
theora
H.261
H.261-CIF
H.261-QCIF
2011/07/05 04:02:01.198 0:30.736 Pool:0xb32ceb70 SIP Offering media type video in SDP with formats
SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,theora,H.261,H.261-CIF,H.261-QCIF
2011/07/05 04:02:01.198 0:30.736 Pool:0xb32ceb70 Call IsMediaBypassPossible Call[m4f1b4f3c1]-EP<sip>[1c73cb61-d5a4-e011-9f66-00a0d17bf4e9] session 2
2011/07/05 04:02:01.198 0:30.736 Pool:0xb32ceb70 OpalMan IsMediaBypassPossible: session 2
2011/07/05 04:02:01.198 0:30.736 Pool:0xb32ceb70 OpalCon IsMediaBypassPossible: default returns false
2011/07/05 04:02:01.198 0:30.736 Pool:0xb32ceb70 RTP Found existing media session 2
2011/07/05 04:02:01.198 0:30.736 Pool:0xb32ceb70 RTP Found existing RTP session 2
2011/07/05 04:02:01.199 0:30.737 Pool:0xb32ceb70 SDP SDP not including H.261-CIF as it is not a SIP transportable format
2011/07/05 04:02:01.199 0:30.737 Pool:0xb32ceb70 SDP SDP not including H.261-QCIF as it is not a SIP transportable format
2011/07/05 04:02:01.199 0:30.737 Pool:0xb32ceb70 SIP Adding authentication information for user "stoneman66" at realm "ekiga.net"
2011/07/05 04:02:01.199 0:30.737 Pool:0xb32ceb70 DNS SRV Lookup ekiga.net service _sip._udp
2011/07/05 04:02:01.633 0:31.172 Pool:0xb32ceb70 SIP No SRV record found.
2011/07/05 04:02:01.633 0:31.172 Pool:0xb32ceb70 SIP Transaction remote address is udp$ekiga.net:5060
2011/07/05 04:02:01.634 0:31.173 Pool:0xb32ceb70 OpalPlugin to_customised_options: theora
2011/07/05 04:02:01.636 0:31.174 Pool:0xb32ceb70 OpalPlugin to_customised_options: H.261
2011/07/05 04:02:01.638 0:31.176 Pool:0xb32ceb70 SIP Sending PDU (1304 bytes) to: rem=udp$86.64.162.35:5060,local=udp$187.65.248.7:5060,if=187.65.248.7%eth0
INVITE sip:500 ekiga net SIP/2.0
Date: Mon, 04 Jul 2011 18:02:01 GMT
CSeq: 2 INVITE
Via: SIP/2.0/UDP 187.65.248.7:5060;branch=z9hG4bK4c6cb062-d5a4-e011-9f66-00a0d17bf4e9;rport
User-Agent: Ekiga/3.2.7
From: "Rob Stone" <sip:stoneman66 ekiga net>;tag=1c73cb61-d5a4-e011-9f66-00a0d17bf4e9
Call-ID: e482cb61-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
To: <sip:500 ekiga net>
Contact: <sip:stoneman66@187.65.248.7>
Proxy-Authorization: Digest username="stoneman66", realm="ekiga.net", nonce="4e12003700004afadb89c29929483e4d1b445c660fe4e4af", uri="sip:500 ekiga net", algorithm=MD5, response="177dbbed0168d3d7ddfa84e212cf046f"
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Content-Type: application/sdp
Content-Length: 526
Max-Forwards: 70
v=0
o=- 1309802519 1 IN IP4 187.65.248.7
s=Opal SIP Session
c=IN IP4 187.65.248.7
t=0 0
m=audio 5062 RTP/AVP 119 0 8 101 122
a=sendrecv
a=rtpmap:119 Speex/16000/1
a=fmtp:119 sr=16000,mode=any
a=rtpmap:0 PCMU/8000/1
a=rtpmap:8 PCMA/8000/1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16,32,36
a=rtpmap:122 NSE/8000
a=fmtp:122 192-193
m=video 5064 RTP/AVP 121 31
b=AS:4096
b=TIAS:4096000
a=sendrecv
a=rtpmap:121 theora/90000
a=fmtp:121 height=576;width=704
a=rtpmap:31 h261/90000
a=fmtp:31 CIF=1;QCIF=1
2011/07/05 04:02:01.639 0:31.177 Pool:0xb32ceb70 OpalUDP Setting interface to 187.65.248.7%eth0
2011/07/05 04:02:01.639 0:31.177 Pool:0xb32ceb70 SIP Transaction timers set: retry=0.500, completion=1:00.000
2011/07/05 04:02:01.639 0:31.177 Pool:0xb32ceb70 SIP Handled PDU "1 INVITE <407>"
2011/07/05 04:02:01.892 0:31.431 Opal Liste...0xb3d06b70 OpalUDP Binding to interface: 187.65.248.7:5060
2011/07/05 04:02:01.893 0:31.431 Opal Liste...0xb3d06b70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$187.65.248.7:5060>
2011/07/05 04:02:01.894 0:31.432 Opal Liste...0xb3d06b70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$187.65.248.7:5060,if=187.65.248.7%eth0
SIP/2.0 100 Giving a try
CSeq: 2 INVITE
Via: SIP/2.0/UDP 187.65.248.7:5060;branch=z9hG4bK4c6cb062-d5a4-e011-9f66-00a0d17bf4e9;rport=5060
Server: Kamailio (1.5.3-notls (i386/linux))
From: "Rob Stone" <sip:stoneman66 ekiga net>;tag=1c73cb61-d5a4-e011-9f66-00a0d17bf4e9
Call-ID: e482cb61-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
To: <sip:500 ekiga net>
Content-Length: 0
2011/07/05 04:02:01.894 0:31.433 Opal Liste...0xb3d06b70 SIP Queueing PDU "2 INVITE <100>", transaction=z9hG4bK4c6cb062-d5a4-e011-9f66-00a0d17bf4e9, token=1c73cb61-d5a4-e011-9f66-00a0d17bf4e9
2011/07/05 04:02:01.895 0:31.433 Pool:0xb32ceb70 SIP Handling PDU "2 INVITE <100>" for transaction=z9hG4bK4c6cb062-d5a4-e011-9f66-00a0d17bf4e9
2011/07/05 04:02:01.896 0:31.434 Pool:0xb32ceb70 SIP Product Info: name="Kamailio", version="", vendor="", comments="(1.5.3-notls (i386/linux))"
2011/07/05 04:02:01.896 0:31.434 Pool:0xb32ceb70 SIP INVITE transaction id=z9hG4bK4c6cb062-d5a4-e011-9f66-00a0d17bf4e9 proceeding.
2011/07/05 04:02:01.896 0:31.434 Pool:0xb32ceb70 SIP Received Trying response
2011/07/05 04:02:01.896 0:31.434 Pool:0xb32ceb70 OpalCon SetPhase from SetUpPhase to ProceedingPhase for Call[m4f1b4f3c1]-EP<sip>[1c73cb61-d5a4-e011-9f66-00a0d17bf4e9]
2011/07/05 04:02:01.896 0:31.435 Pool:0xb32ceb70 OpalMan OnProceeding Call[m4f1b4f3c1]-EP<sip>[1c73cb61-d5a4-e011-9f66-00a0d17bf4e9]
2011/07/05 04:02:01.896 0:31.435 Pool:0xb32ceb70 Call OnProceeding Call[m4f1b4f3c1]-EP<sip>[1c73cb61-d5a4-e011-9f66-00a0d17bf4e9]
2011/07/05 04:02:01.896 0:31.435 Pool:0xb32ceb70 SIP Handled PDU "2 INVITE <100>"
2011/07/05 04:02:01.897 0:31.435 Opal Liste...0xb3d06b70 Opal Transport clean up on termination
2011/07/05 04:02:01.897 0:31.435 Opal Liste...0xb3d06b70 Opal Transport Close
2011/07/05 04:02:01.897 0:31.435 Opal Liste...0xb3d06b70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$187.65.248.7:5060>
2011/07/05 04:02:01.899 0:31.437 Opal Liste...0xb3d06b70 OpalUDP Binding to interface: 187.65.248.7:5060
2011/07/05 04:02:01.899 0:31.437 Opal Liste...0xb3d06b70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$187.65.248.7:5060>
2011/07/05 04:02:01.900 0:31.438 Opal Liste...0xb3d06b70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$187.65.248.7:5060,if=187.65.248.7%eth0
SIP/2.0 500 Server internal error
CSeq: 2 INVITE
Via: SIP/2.0/UDP 187.65.248.7:5060;received=187.65.248.7;branch=z9hG4bK4c6cb062-d5a4-e011-9f66-00a0d17bf4e9;rport=5060
User-Agent: Ekiga.NET
From: "Rob Stone" <sip:stoneman66 ekiga net>;tag=1c73cb61-d5a4-e011-9f66-00a0d17bf4e9
Call-ID: e482cb61-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
Supported: replaces
To: <sip:500 ekiga net>;tag=as31f30f4c
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0
2011/07/05 04:02:01.900 0:31.439 Opal Liste...0xb3d06b70 SIP Queueing PDU "2 INVITE <500>", transaction=z9hG4bK4c6cb062-d5a4-e011-9f66-00a0d17bf4e9, token=1c73cb61-d5a4-e011-9f66-00a0d17bf4e9
2011/07/05 04:02:01.901 0:31.439 Opal Liste...0xb3d06b70 Opal Transport clean up on termination
2011/07/05 04:02:01.901 0:31.439 Opal Liste...0xb3d06b70 Opal Transport Close
2011/07/05 04:02:01.901 0:31.439 Pool:0xb32ceb70 SIP Handling PDU "2 INVITE <500>" for transaction=z9hG4bK4c6cb062-d5a4-e011-9f66-00a0d17bf4e9
2011/07/05 04:02:01.901 0:31.439 Opal Liste...0xb3d06b70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$187.65.248.7:5060>
2011/07/05 04:02:02.383 0:31.921 Opal Liste...0xb3d06b70 OpalUDP Binding to interface: 187.65.248.7:5060
2011/07/05 04:02:02.383 0:31.922 Opal Liste...0xb3d06b70 SIP Waiting for PDU on udp$86.64.162.35:5060<if=udp$187.65.248.7:5060>
2011/07/05 04:02:02.384 0:31.922 Opal Liste...0xb3d06b70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$187.65.248.7:5060,if=187.65.248.7%eth0
SIP/2.0 500 Server internal error
CSeq: 2 INVITE
Via: SIP/2.0/UDP 187.65.248.7:5060;received=187.65.248.7;branch=z9hG4bK4c6cb062-d5a4-e011-9f66-00a0d17bf4e9;rport=5060
User-Agent: Ekiga.NET
From: "Rob Stone" <sip:stoneman66 ekiga net>;tag=1c73cb61-d5a4-e011-9f66-00a0d17bf4e9
Call-ID: e482cb61-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
Supported: replaces
To: <sip:500 ekiga net>;tag=as31f30f4c
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0
2011/07/05 04:02:02.384 0:31.923 Opal Liste...0xb3d06b70 SIP Queueing PDU "2 INVITE <500>", transaction=z9hG4bK4c6cb062-d5a4-e011-9f66-00a0d17bf4e9, token=1c73cb61-d5a4-e011-9f66-00a0d17bf4e9
2011/07/05 04:02:02.385 0:31.923 Opal Liste...0xb3d06b70 ThreadPool Allocated worker thread by group Id 1c73cb61-d5a4-e011-9f66-00a0d17bf4e9
2011/07/05 04:02:02.385 0:31.923 Opal Liste...0xb3d06b70 Opal Transport clean up on termination
2011/07/05 04:02:02.385 0:31.923 Opal Liste...0xb3d06b70 Opal Transport Close
2011/07/05 04:02:02.385 0:31.923 Opal Liste...0xb3d06b70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$187.65.248.7:5060>
2011/07/05 04:02:02.639 0:32.177 Housekeeper:0xb3d47b70 SIP Set state Terminated_Success for INVITE transaction id=z9hG4bK96783c62-d5a4-e011-9f66-00a0d17bf4e9
2011/07/05 04:02:02.996 0:32.534 Pool:0xb32ceb70 SIP Adding authentication information for user "stoneman66" at realm "ekiga.net"
2011/07/05 04:02:02.997 0:32.535 Pool:0xb32ceb70 SIP Sending PDU (573 bytes) to: rem=udp$86.64.162.35:5060,local=udp$187.65.248.7:5060,if=187.65.248.7%eth0
ACK sip:500 ekiga net SIP/2.0
CSeq: 2 ACK
Via: SIP/2.0/UDP 187.65.248.7:5060;branch=z9hG4bK4c6cb062-d5a4-e011-9f66-00a0d17bf4e9;rport
From: "Rob Stone" <sip:stoneman66 ekiga net>;tag=1c73cb61-d5a4-e011-9f66-00a0d17bf4e9
Call-ID: e482cb61-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
To: <sip:500 ekiga net>;tag=as31f30f4c
Proxy-Authorization: Digest username="stoneman66", realm="ekiga.net", nonce="4e12003700004afadb89c29929483e4d1b445c660fe4e4af", uri="sip:500 ekiga net", algorithm=MD5, response="365239bb948e8c770a28f70b1fe98479"
Content-Length: 0
Max-Forwards: 70
2011/07/05 04:02:02.997 0:32.536 Pool:0xb32ceb70 OpalUDP Setting interface to 187.65.248.7%eth0
2011/07/05 04:02:02.998 0:32.536 Pool:0xb32ceb70 SIP INVITE transaction id=z9hG4bK4c6cb062-d5a4-e011-9f66-00a0d17bf4e9 completed.
2011/07/05 04:02:02.998 0:32.536 Pool:0xb32ceb70 OpalCon SetPhase from ProceedingPhase to ReleasingPhase for Call[m4f1b4f3c1]-EP<sip>[1c73cb61-d5a4-e011-9f66-00a0d17bf4e9]
2011/07/05 04:02:02.998 0:32.536 Pool:0xb32ceb70 OpalCon Releasing Call[m4f1b4f3c1]-EP<sip>[1c73cb61-d5a4-e011-9f66-00a0d17bf4e9]
2011/07/05 04:02:02.998 0:32.536 Pool:0xb32ceb70 OpalCon Call end reason for Call[m4f1b4f3c1]-EP<sip>[1c73cb61-d5a4-e011-9f66-00a0d17bf4e9] set to EndedByQ931Cause
2011/07/05 04:02:02.998 0:32.536 Pool:0xb32ceb70 PTLib Thread high water mark set: 11
2011/07/05 04:02:02.998 0:32.537 Pool:0xb32ceb70 SIP Handled PDU "2 INVITE <500>"
2011/07/05 04:02:02.998 0:32.537 OnRelease:0xb328db70 SIP OnReleased: Call[m4f1b4f3c1]-EP<sip>[1c73cb61-d5a4-e011-9f66-00a0d17bf4e9], phase = ReleasingPhase
2011/07/05 04:02:02.999 0:32.537 OnRelease:0xb328db70 OpalCon SetPhase from ReleasingPhase to ReleasingPhase for Call[m4f1b4f3c1]-EP<sip>[1c73cb61-d5a4-e011-9f66-00a0d17bf4e9]
2011/07/05 04:02:02.999 0:32.537 Pool:0xb32ceb70 SIP Handling PDU "2 INVITE <500>" for transaction=z9hG4bK4c6cb062-d5a4-e011-9f66-00a0d17bf4e9
2011/07/05 04:02:02.999 0:32.537 OnRelease:0xb328db70 OpalCon Media streams closed.
2011/07/05 04:02:02.999 0:32.537 OnRelease:0xb328db70 SIP Awaiting forked INVITE transaction completion, id=z9hG4bK4c6cb062-d5a4-e011-9f66-00a0d17bf4e9
2011/07/05 04:02:02.999 0:32.537 OnRelease:0xb328db70 OpalCon SetPhase from ReleasingPhase to ReleasedPhase for Call[m4f1b4f3c1]-EP<sip>[1c73cb61-d5a4-e011-9f66-00a0d17bf4e9]
2011/07/05 04:02:02.999 0:32.537 OnRelease:0xb328db70 OpalCon OnReleased Call[m4f1b4f3c1]-EP<sip>[1c73cb61-d5a4-e011-9f66-00a0d17bf4e9]
2011/07/05 04:02:02.999 0:32.537 Pool:0xb32ceb70 PWLib File handle high water mark set: 55 PUDPSocket
2011/07/05 04:02:02.999 0:32.538 OnRelease:0xb328db70 OpalEP OnReleased Call[m4f1b4f3c1]-EP<sip>[1c73cb61-d5a4-e011-9f66-00a0d17bf4e9]
2011/07/05 04:02:02.999 0:32.538 OnRelease:0xb328db70 OpalMan OnReleased Call[m4f1b4f3c1]-EP<sip>[1c73cb61-d5a4-e011-9f66-00a0d17bf4e9]
2011/07/05 04:02:03.000 0:32.538 OnRelease:0xb328db70 Call OnReleased Call[m4f1b4f3c1]-EP<sip>[1c73cb61-d5a4-e011-9f66-00a0d17bf4e9]
2011/07/05 04:02:03.000 0:32.538 OnRelease:0xb328db70 OpalCon SetPhase from SetUpPhase to ReleasingPhase for Call[m4f1b4f3c1]-EP<pc>[gc1475bf22]
2011/07/05 04:02:03.000 0:32.538 OnRelease:0xb328db70 OpalCon Releasing Call[m4f1b4f3c1]-EP<pc>[gc1475bf22]
2011/07/05 04:02:03.000 0:32.538 OnRelease:0xb328db70 OpalCon Call end reason for Call[m4f1b4f3c1]-EP<pc>[gc1475bf22] set to EndedByQ931Cause
2011/07/05 04:02:03.000 0:32.538 OnRelease:0xb328db70 OpalCon OnReleased Call[m4f1b4f3c1]-EP<pc>[gc1475bf22]
2011/07/05 04:02:03.000 0:32.538 OnRelease:0xb328db70 OpalEP OnReleased Call[m4f1b4f3c1]-EP<pc>[gc1475bf22]
2011/07/05 04:02:03.000 0:32.539 OnRelease:0xb328db70 OpalMan OnReleased Call[m4f1b4f3c1]-EP<pc>[gc1475bf22]
2011/07/05 04:02:03.000 0:32.539 OnRelease:0xb328db70 Call OnReleased Call[m4f1b4f3c1]-EP<pc>[gc1475bf22]
2011/07/05 04:02:03.000 0:32.539 OnRelease:0xb328db70 OpalCon Media streams closed.
2011/07/05 04:02:03.001 0:32.539 OnRelease:0xb328db70 OpalCon Media streams closed.
2011/07/05 04:02:03.001 0:32.539 OnRelease:0xb328db70 OpalCon OnRelease thread completed for Call[m4f1b4f3c1]-EP<sip>[1c73cb61-d5a4-e011-9f66-00a0d17bf4e9]
2011/07/05 04:02:03.001 0:32.539 Pool:0xb32ceb70 PWLib File handle low water mark set: 53 PUDPSocket
2011/07/05 04:02:03.001 0:32.540 Pool:0xb32ceb70 SIP Adding authentication information for user "stoneman66" at realm "ekiga.net"
2011/07/05 04:02:03.002 0:32.540 Pool:0xb32ceb70 SIP Sending PDU (573 bytes) to: rem=udp$86.64.162.35:5060,local=udp$187.65.248.7:5060,if=187.65.248.7%eth0
ACK sip:500 ekiga net SIP/2.0
CSeq: 2 ACK
Via: SIP/2.0/UDP 187.65.248.7:5060;branch=z9hG4bK4c6cb062-d5a4-e011-9f66-00a0d17bf4e9;rport
From: "Rob Stone" <sip:stoneman66 ekiga net>;tag=1c73cb61-d5a4-e011-9f66-00a0d17bf4e9
Call-ID: e482cb61-d5a4-e011-9f66-00a0d17bf4e9@roblaptop
To: <sip:500 ekiga net>;tag=as31f30f4c
Proxy-Authorization: Digest username="stoneman66", realm="ekiga.net", nonce="4e12003700004afadb89c29929483e4d1b445c660fe4e4af", uri="sip:500 ekiga net", algorithm=MD5, response="365239bb948e8c770a28f70b1fe98479"
Content-Length: 0
Max-Forwards: 70
2011/07/05 04:02:03.002 0:32.541 Pool:0xb32ceb70 OpalUDP Setting interface to 187.65.248.7%eth0
2011/07/05 04:02:03.003 0:32.541 Pool:0xb32ceb70 SIP Handled PDU "2 INVITE <500>"
2011/07/05 04:02:03.102 0:32.640 AEScheduler Removing Event incoming_call_sound from queue
2011/07/05 04:02:03.102 0:32.640 AEScheduler Removing Event ring_tone_sound from queue
2011/07/05 04:02:03.102 0:32.640 OpalMan OnClearedCall Call[m4f1b4f3c1] from "pc:rob" to "sip:500 ekiga net"
2011/07/05 04:02:03.102 0:32.640 OPAL Attempting to close recording on call m4f1b4f3c1 when not recording.
2011/07/05 04:02:03.998 0:33.536 Opal Garbage:0xb3dc9b70 PCSS Deleted PC sound system connection.
2011/07/05 04:02:03.998 0:33.536 Opal Garbage:0xb3dc9b70 OpalCon Connection Call[m4f1b4f3c1]-EP<pc>[gc1475bf22] destroyed.
2011/07/05 04:02:03.998 0:33.536 Opal Garbage:0xb3dc9b70 SIP Transaction id=z9hG4bK96783c62-d5a4-e011-9f66-00a0d17bf4e9 destroyed.
2011/07/05 04:02:04.002 0:33.541 Housekeeper:0xb3d47b70 SIP Set state Terminated_Success for INVITE transaction id=z9hG4bK4c6cb062-d5a4-e011-9f66-00a0d17bf4e9
2011/07/05 04:02:04.999 0:34.537 Opal Garbage:0xb3dc9b70 RTP Closing session 1
2011/07/05 04:02:04.999 0:34.537 Opal Garbage:0xb3dc9b70 RTP Deleting session 1
2011/07/05 04:02:04.999 0:34.537 Opal Garbage:0xb3dc9b70 RTP_UDP Session 1, Shutting down read.
2011/07/05 04:02:04.999 0:34.537 Opal Garbage:0xb3dc9b70 RTP_UDP Session 1, Shutting down write.
2011/07/05 04:02:04.999 0:34.537 Opal Garbage:0xb3dc9b70 RTP Closing session 2
2011/07/05 04:02:04.999 0:34.537 Opal Garbage:0xb3dc9b70 RTP Deleting session 2
2011/07/05 04:02:04.999 0:34.537 Opal Garbage:0xb3dc9b70 RTP_UDP Session 2, Shutting down read.
2011/07/05 04:02:04.999 0:34.538 Opal Garbage:0xb3dc9b70 RTP_UDP Session 2, Shutting down write.
2011/07/05 04:02:04.999 0:34.538 Opal Garbage:0xb3dc9b70 SIP Transaction id=z9hG4bK4c6cb062-d5a4-e011-9f66-00a0d17bf4e9 destroyed.
2011/07/05 04:02:05.000 0:34.538 Opal Garbage:0xb3dc9b70 Opal Transport clean up on termination
2011/07/05 04:02:05.000 0:34.538 Opal Garbage:0xb3dc9b70 Opal Transport Close
2011/07/05 04:02:05.000 0:34.538 Opal Garbage:0xb3dc9b70 Opal Transport clean up on termination
2011/07/05 04:02:05.000 0:34.538 Opal Garbage:0xb3dc9b70 Opal Transport Close
2011/07/05 04:02:05.000 0:34.539 Opal Garbage:0xb3dc9b70 PWLib File handle low water mark set: 49 PUDPSocket
2011/07/05 04:02:05.000 0:34.538 Opal Garbage:0xb3dc9b70 Opal Deleted transport udp$86.64.162.35:5060<if=udp$187.65.248.7:5060>
2011/07/05 04:02:05.000 0:34.539 Opal Garbage:0xb3dc9b70 SIP Deleted connection.
2011/07/05 04:02:05.001 0:34.539 Opal Garbage:0xb3dc9b70 OpalCon Connection Call[m4f1b4f3c1]-EP<sip>[1c73cb61-d5a4-e011-9f66-00a0d17bf4e9] destroyed.
2011/07/05 04:02:06.006 0:35.544 Call Call[m4f1b4f3c1] destroyed.
(ekiga:5391): GLib-GObject-CRITICAL **: g_object_unref: assertion `G_IS_OBJECT (object)' failed
2011/07/05 04:02:20.038 0:49.576 Network In...0xb3d88b70 IfaceMon Finished interface monitor thread.
[
Date Prev][
Date Next] [
Thread Prev][
Thread Next]
[
Thread Index]
[
Date Index]
[
Author Index]
