Скрипт запущен Вск 12 Июл 2009 19:44:49 [?1034h[iron@estaf ~]$ ekiga -d 4 dyna.cxx(111) MPEG4 DYNA Successfully loaded 'libavcodec.so.52' dyna.cxx(337) MPEG4 DYNA Successfully loaded libavcodec library and verified functions mpeg4.cxx(1993) MPEG4 Codec Enabled dyna.cxx(111) H263+ DYNA Successfully loaded 'libavcodec.so.52' dyna.cxx(337) H263+ DYNA Successfully loaded libavcodec library and verified functions h263-1998.cxx(2094) H.263 Codec Enabled with 4 definitions dyna.cxx(111) H264 DYNA Successfully loaded 'libavcodec.so.52' dyna.cxx(337) H264 DYNA Successfully loaded libavcodec library and verified functions h264pipe_unix.cxx(301) H264 IPC PP: Found GPL process executable in /usr/lib/opal-3.6.2/codecs/video/h264_video_pwplugin_helper x264loader_unix.cxx(114) H264 DYNA Trying to open x264 library libx264.so.67 x264loader_unix.cxx(131) H264 DYNA Successfully loaded libx264.so.67 x264loader_unix.cxx(103) H264 DYNA Loader was compiled with x264 build 67 present x264loader_unix.cxx(106) H264 DYNA Successfully loaded libx264 library and verified functions h264pipe_unix.cxx(118) H264 IPC PP: Successfully forked child process 29569 and established communication h264-x264.cxx(853) H264 Codec Enabled 2009/07/12 19:44:58.410 0:00.104 Version 3.2.4 by on Unix Linux (2.6.30-ARCH-i686) with PTLib (v2.6.2) at 2009/7/12 19:44:58.410 2009/07/12 19:44:58.410 0:00.104 Ekiga git revision: unknown 2009/07/12 19:44:58.411 0:00.105 Ekiga registered on D-Bus: org.ekiga.Ekiga 2009/07/12 19:44:58.412 0:00.106 PWLib File handle high water mark set: 17 Thread unblock pipe 2009/07/12 19:44:58.412 0:00.106 PTLib Thread high water mark set: 2 2009/07/12 19:44:58.412 0:00.106 PWLib File handle high water mark set: 19 Thread unblock pipe 2009/07/12 19:44:58.412 0:00.106 PTLib Thread high water mark set: 3 2009/07/12 19:44:58.412 0:00.106 PWLib File handle high water mark set: 23 Thread unblock pipe 2009/07/12 19:44:58.413 0:00.107 PWLib File handle high water mark set: 25 Thread unblock pipe 2009/07/12 19:44:58.452 0:00.146 PTLib Thread high water mark set: 4 2009/07/12 19:44:58.452 0:00.146 HalManager_dbus Initialising HAL Manager 2009/07/12 19:44:58.455 0:00.149 HalManager_dbus Populating device list 2009/07/12 19:44:58.588 0:00.282 HalManager_dbus Populated device list with 19 devices 2009/07/12 19:44:58.588 0:00.282 HalManager_dbus Populating interface list 2009/07/12 19:44:58.589 0:00.283 HalManager_dbus Populating full interface list failed - The name org.freedesktop.NetworkManager was not provided by any .service files 2009/07/12 19:44:58.632 0:00.326 Detecting V4L2 devices 2009/07/12 19:44:58.632 0:00.326 Unable to detect v4l2 directory 2009/07/12 19:44:58.675 0:00.369 PWLib File handle high water mark set: 30 Thread unblock pipe 2009/07/12 19:44:58.675 0:00.369 PTLib Thread high water mark set: 5 2009/07/12 19:44:58.675 0:00.369 OpalMan Created manager. 2009/07/12 19:44:58.676 0:00.370 OpalMan Registered endpoint with prefix pc 2009/07/12 19:44:58.676 0:00.370 OpalEP Created endpoint: pc 2009/07/12 19:44:58.679 0:00.373 PCSS Created PC sound system endpoint. Players: Default /dev/sound/dsp CA0106 (1) CA0106 (2) CA0106 (3) EKIGA *.wav CA0106 Recorders: Default /dev/sound/dsp CA0106 (1) CA0106 (2) CA0106 (3) EKIGA *.wav CA0106 2009/07/12 19:44:58.679 0:00.373 OPAL SetMediaFormatOrder() 2009/07/12 19:44:58.679 0:00.373 OPAL SetMediaFormatMask() 2009/07/12 19:44:58.680 0:00.374 OpalMan Registered endpoint with prefix sip 2009/07/12 19:44:58.680 0:00.374 OpalEP Created endpoint: sip 2009/07/12 19:44:58.680 0:00.374 PWLib File handle high water mark set: 31 PUDPSocket 2009/07/12 19:44:58.680 0:00.374 IfaceMon Initial interface list: 127.0.0.1 [00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:01] <00-00-00-00-00-00> (lo) 10.32.76.254 [fe:80:00:00:00:00:00:00:02:80:48:ff:fe:54:16:c1] <00-80-48-54-16-C1> (eth0) 192.168.42.1 [fe:80:00:00:00:00:00:00:02:50:56:ff:fe:c0:00:01] <00-50-56-C0-00-01> (vmnet1) 172.16.105.1 [fe:80:00:00:00:00:00:00:02:50:56:ff:fe:c0:00:08] <00-50-56-C0-00-08> (vmnet8) 84.51.100.146 <00-00-00-00-00-00> (ppp0) 2009/07/12 19:44:58.680 0:00.374 PWLib File handle high water mark set: 32 Thread unblock pipe 2009/07/12 19:44:58.681 0:00.375 PTLib Thread high water mark set: 6 2009/07/12 19:44:58.681 0:00.375 PWLib File handle high water mark set: 34 Thread unblock pipe 2009/07/12 19:44:58.681 0:00.375 Network In...0xb479ab70 IfaceMon Started interface monitor thread. 2009/07/12 19:44:58.681 0:00.375 PTLib Thread high water mark set: 7 2009/07/12 19:44:58.681 0:00.375 Network In...0xb479ab70 PWLib File handle high water mark set: 35 PUDPSocket 2009/07/12 19:44:58.681 0:00.375 OpalMan Registered endpoint with prefix sips 2009/07/12 19:44:58.681 0:00.375 SIP Created endpoint. 2009/07/12 19:44:58.681 0:00.375 MonSock Created socket bundle for all interfaces. 2009/07/12 19:44:58.681 0:00.375 PWLib File handle high water mark set: 36 PUDPSocket 2009/07/12 19:44:58.681 0:00.375 MonSock Created bundled UDP socket 10.32.76.254:5060 2009/07/12 19:44:58.682 0:00.376 PWLib File handle high water mark set: 37 PUDPSocket 2009/07/12 19:44:58.682 0:00.376 MonSock Created bundled UDP socket 192.168.42.1:5060 2009/07/12 19:44:58.682 0:00.376 PWLib File handle high water mark set: 38 PUDPSocket 2009/07/12 19:44:58.682 0:00.376 MonSock Created bundled UDP socket 172.16.105.1:5060 2009/07/12 19:44:58.682 0:00.376 PWLib File handle high water mark set: 39 PUDPSocket 2009/07/12 19:44:58.682 0:00.376 MonSock Created bundled UDP socket 84.51.100.146:5060 2009/07/12 19:44:58.682 0:00.376 PWLib File handle high water mark set: 41 Thread unblock pipe 2009/07/12 19:44:58.682 0:00.376 PTLib Thread high water mark set: 8 2009/07/12 19:44:58.682 0:00.376 Opal Liste...0xb45ffb70 Listen Started listening thread on udp$*:5060 2009/07/12 19:44:58.682 0:00.376 OpalMan Added route "sip:.*=pc:*" 2009/07/12 19:44:58.683 0:00.377 OpalMan Added route "pc:.*=sip:" 2009/07/12 19:44:58.683 0:00.377 OpalMan Registered endpoint with prefix h323 2009/07/12 19:44:58.683 0:00.377 OpalEP Created endpoint: h323 2009/07/12 19:44:58.683 0:00.377 OpalMan Registered endpoint with prefix h323s 2009/07/12 19:44:58.683 0:00.377 H323 Created endpoint. 2009/07/12 19:44:58.683 0:00.377 PWLib File handle high water mark set: 42 PTCPSocket 2009/07/12 19:44:58.683 0:00.377 PWLib File handle high water mark set: 44 Thread unblock pipe 2009/07/12 19:44:58.683 0:00.377 PTLib Thread high water mark set: 9 2009/07/12 19:44:58.683 0:00.377 Opal Liste...0xb45beb70 Listen Started listening thread on tcp$*:1720 2009/07/12 19:44:58.683 0:00.377 OpalMan Added route "h323:.*=pc:" 2009/07/12 19:44:58.683 0:00.377 Opal Liste...0xb45beb70 Listen Waiting on socket accept on tcp$*:1720 2009/07/12 19:44:58.683 0:00.377 OpalMan Added route "pc:.*=h323:" 2009/07/12 19:44:58.695 0:00.389 MediaFormat Removing codecs SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,iLBC,G.711-uLaw-64k,G.711-ALaw-64k,G.722-64k,H.264,theora,H.261,H.261-CIF,H.261-QCIF,H.263,H.263P,MPEG4 2009/07/12 19:44:58.696 0:00.390 OPAL SetMediaFormatMask(PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,iLBC-13k3,iLBC-15k2,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz) 2009/07/12 19:44:58.696 0:00.390 OPAL SetMediaFormatOrder(SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,iLBC,G.711-uLaw-64k,G.711-ALaw-64k,G.722-64k,H.264,theora,H.261,H.261-CIF,H.261-QCIF,H.263,H.263P,MPEG4) 2009/07/12 19:44:58.706 0:00.400 MediaFormat Removing codecs SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,iLBC,G.711-uLaw-64k,G.711-ALaw-64k,G.722-64k,H.264,theora,H.261,H.261-CIF,H.261-QCIF,H.263,H.263P,MPEG4 2009/07/12 19:44:58.706 0:00.400 OPAL SetMediaFormatMask(PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,iLBC-13k3,iLBC-15k2,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz) 2009/07/12 19:44:58.706 0:00.400 OPAL SetMediaFormatOrder(SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,iLBC,G.711-uLaw-64k,G.711-ALaw-64k,G.722-64k,H.264,theora,H.261,H.261-CIF,H.261-QCIF,H.263,H.263P,MPEG4) 2009/07/12 19:44:58.724 0:00.418 PWLib File handle high water mark set: 46 Thread unblock pipe 2009/07/12 19:44:58.724 0:00.418 PTLib Thread high water mark set: 10 2009/07/12 19:44:58.725 0:00.419 StunDetector:0xb457db70 PWLib File handle high water mark set: 47 PUDPSocket 2009/07/12 19:44:58.760 0:00.454 VideoOutputCoreConfBridge Updating video view 2009/07/12 19:44:58.760 0:00.454 VideoOutputCoreConfBridge Updating zoom 2009/07/12 19:44:58.760 0:00.454 VideoOutputCoreConfBridge Updating Video Settings 2009/07/12 19:44:58.761 0:00.455 VideoOutputCoreConfBridge Updating Video Settings 2009/07/12 19:44:58.761 0:00.455 VideoOutputCoreConfBridge Updating Video Settings 2009/07/12 19:44:58.761 0:00.455 VideoOutputCoreConfBridge Updating Video Settings 2009/07/12 19:44:58.761 0:00.455 VidInputCoreConfBridge Updating preview size and fps 2009/07/12 19:44:58.761 0:00.455 VidInputCore Setting new preview config: 320x240/30 2009/07/12 19:44:58.764 0:00.458 VidInputCoreConfBridge Updating preview size and fps 2009/07/12 19:44:58.764 0:00.458 VidInputCore Setting new preview config: 320x240/30 2009/07/12 19:44:58.767 0:00.461 VidInputCoreConfBridge Updating device 2009/07/12 19:44:58.767 0:00.461 VidInputCore Setting device: Web-Camera (PTLIB/V4L) 2009/07/12 19:44:58.767 0:00.461 GMVideoInputManager_ptlib Setting Device Web-Camera (PTLIB/V4L) 2009/07/12 19:44:58.767 0:00.461 VidInputCoreConfBridge Updating device 2009/07/12 19:44:58.767 0:00.461 VidInputCore Setting device: Web-Camera (PTLIB/V4L) 2009/07/12 19:44:58.767 0:00.461 GMVideoInputManager_ptlib Setting Device Web-Camera (PTLIB/V4L) 2009/07/12 19:44:58.767 0:00.461 VidInputCoreConfBridge Updating device 2009/07/12 19:44:58.767 0:00.461 VidInputCore Setting device: Web-Camera (PTLIB/V4L) 2009/07/12 19:44:58.767 0:00.461 GMVideoInputManager_ptlib Setting Device Web-Camera (PTLIB/V4L) 2009/07/12 19:44:58.768 0:00.462 VidInputCoreConfBridge Updating image 2009/07/12 19:44:58.768 0:00.462 VidInputCoreConfBridge Updating preview 2009/07/12 19:44:58.768 0:00.462 VidInputCore Stopping Preview 2009/07/12 19:44:58.768 0:00.462 AudioOutputCoreConfBridge Updating device 2009/07/12 19:44:58.768 0:00.462 AudioOutputCore Setting device[0]: Default (PTLIB/ALSA) 2009/07/12 19:44:58.768 0:00.462 GMAudioOutputManager_ptlib Setting Device[0] Default (PTLIB/ALSA) 2009/07/12 19:44:58.768 0:00.462 AudioOutputCoreConfBridge Updating device 2009/07/12 19:44:58.768 0:00.462 AudioOutputCore Setting device[1]: SILENT (Ekiga/Ekiga) 2009/07/12 19:44:58.768 0:00.462 GMAudioOutputManager_null Setting Device[1] SILENT (Ekiga/Ekiga) 2009/07/12 19:44:58.770 0:00.464 AudioInputCoreConfBridge Updating device 2009/07/12 19:44:58.770 0:00.464 AudioInputCore Setting device: SILENT (Ekiga/Ekiga) 2009/07/12 19:44:58.770 0:00.464 GMAudioInputManager_null Setting Device SILENT (Ekiga/Ekiga) 2009/07/12 19:44:59.023 0:00.717 Ekiga version 3.2.4 2009/07/12 19:44:59.023 0:00.717 OPAL version 3.6.2 2009/07/12 19:44:59.023 0:00.717 PTLIB version 2.6.2 2009/07/12 19:44:59.023 0:00.717 GNOME support disabled 2009/07/12 19:44:59.023 0:00.717 Accelerated rendering support enabled 2009/07/12 19:44:59.023 0:00.717 DBUS support enabled 2009/07/12 19:44:59.023 0:00.717 GConf support enabled 2009/07/12 19:44:59.023 0:00.717 ESound support disabled 2009/07/12 19:44:59.796 0:01.490 StunDetector:0xb457db70 PWLib File handle high water mark set: 49 PUDPSocket 2009/07/12 19:44:59.796 0:01.490 StunDetector:0xb457db70 PWLib File handle high water mark set: 52 PUDPSocket 2009/07/12 19:44:59.796 0:01.490 StunDetector:0xb457db70 PWLib File handle high water mark set: 53 PUDPSocket 2009/07/12 19:44:59.962 0:01.656 StunDetector:0xb457db70 PWLib File handle high water mark set: 54 PUDPSocket 2009/07/12 19:45:02.365 0:04.059 StunDetector:0xb457db70 PWLib File handle low water mark set: 47 PUDPSocket 2009/07/12 19:45:02.531 0:04.225 StunDetector:0xb457db70 OPAL STUN server "stun.ekiga.net" replies Symmetric Firewall, external IP 84.51.100.146 2009/07/12 19:45:02.531 0:04.225 StunDetector:0xb457db70 PTLib Destroyed thread 0x8a05430 StunDetector:0xb457db70(id = b457db70) 2009/07/12 19:45:03.263 0:04.957 OpalMan Set up call from pc:* to sip:500@ekiga.net 2009/07/12 19:45:03.264 0:04.958 Call Created Call[k883572a61] 2009/07/12 19:45:03.264 0:04.958 OpalMan Set up connection to "pc:*" 2009/07/12 19:45:03.264 0:04.958 OpalCon Created connection Call[k883572a61]-EP[c21de68d42] 2009/07/12 19:45:03.264 0:04.958 Silence Parameters set: mode=NoSilenceDetection, threshold=0, silencedb=3200, signaldb=80, period=4800 2009/07/12 19:45:03.264 0:04.958 Silence Handler created 2009/07/12 19:45:03.264 0:04.958 Echo Canceler Handler created 2009/07/12 19:45:03.264 0:04.958 PCSS Created PC sound system connection: token="c21de68d42" player="EKIGA" recorder="EKIGA" 2009/07/12 19:45:03.264 0:04.958 OpalCon SetPhase from UninitialisedPhase to SetUpPhase for Call[k883572a61]-EP[c21de68d42] 2009/07/12 19:45:03.264 0:04.958 OpalMan OnIncoming connection Call[k883572a61]-EP[c21de68d42] 2009/07/12 19:45:03.264 0:04.958 OpalCon Applying string options: 2009/07/12 19:45:03.264 0:04.958 Call GetOtherPartyConnection Call[k883572a61]-EP[c21de68d42] 2009/07/12 19:45:03.264 0:04.958 OpalMan Searching for route "pc:iron sip:500@ekiga.net" 2009/07/12 19:45:03.264 0:04.958 OpalMan Set up connection to "sip:500@ekiga.net" 2009/07/12 19:45:03.265 0:04.959 PWLib File handle low water mark set: 45 PUDPSocket 2009/07/12 19:45:03.265 0:04.959 OpalCon Created connection Call[k883572a61]-EP[6a192ea2-686d-de11-9d8e-0080485416c1] 2009/07/12 19:45:03.265 0:04.959 RFC2833 Handler created 2009/07/12 19:45:03.265 0:04.959 RFC2833 Handler created 2009/07/12 19:45:03.265 0:04.959 SIP Created connection. 2009/07/12 19:45:03.265 0:04.959 PCSS Outgoing call routed to sip:500@ekiga.net for Call[k883572a61]-EP[c21de68d42] 2009/07/12 19:45:03.266 0:04.960 OpalMan SetUpCall succeeded, call=Call[k883572a61] 2009/07/12 19:45:03.266 0:04.960 CallSetup:0xb457db70 Call OnSetUp Call[k883572a61]-EP[c21de68d42] 2009/07/12 19:45:03.266 0:04.960 CallSetup:0xb457db70 SIP SetUpConnection: sip:500@ekiga.net 2009/07/12 19:45:03.266 0:04.960 CallSetup:0xb457db70 OpalCon SetPhase from UninitialisedPhase to SetUpPhase for Call[k883572a61]-EP[6a192ea2-686d-de11-9d8e-0080485416c1] 2009/07/12 19:45:03.266 0:04.960 CallSetup:0xb457db70 OpalCon Applying string options: 2009/07/12 19:45:03.266 0:04.960 CallSetup:0xb457db70 DNS SRV Lookup ekiga.net service _sip._udp 2009/07/12 19:45:03.346 0:05.040 Listen Stopping listening thread on udp$*:5060 2009/07/12 19:45:03.346 0:05.040 MonSock Closed UDP socket 0x8b24940 2009/07/12 19:45:03.346 0:05.040 Opal Liste...0xb45ffb70 Listen UDP read error. 2009/07/12 19:45:03.367 0:05.061 MonSock Deleting UDP socket 0x8b24940 2009/07/12 19:45:03.367 0:05.061 MonSock Closed UDP socket 0x8b24f18 2009/07/12 19:45:03.367 0:05.061 MonSock Deleting UDP socket 0x8b24f18 2009/07/12 19:45:03.367 0:05.061 MonSock Closed UDP socket 0x8b24ba8 2009/07/12 19:45:03.367 0:05.061 MonSock Deleting UDP socket 0x8b24ba8 2009/07/12 19:45:03.367 0:05.061 MonSock Closed UDP socket 0x8b251f8 2009/07/12 19:45:03.367 0:05.061 MonSock Deleting UDP socket 0x8b251f8 2009/07/12 19:45:03.367 0:05.061 PTLib Destroyed thread 0x8b254d8 Opal Listener:0xb45ffb70(id = b45ffb70) 2009/07/12 19:45:03.367 0:05.061 PWLib File handle low water mark set: 35 PUDPSocket 2009/07/12 19:45:03.367 0:05.061 MonSock Created socket bundle for all interfaces. 2009/07/12 19:45:03.367 0:05.061 MonSock Created bundled UDP socket 10.32.76.254:5060 2009/07/12 19:45:03.367 0:05.061 MonSock Created bundled UDP socket 192.168.42.1:5060 2009/07/12 19:45:03.367 0:05.061 MonSock Created bundled UDP socket 172.16.105.1:5060 2009/07/12 19:45:03.367 0:05.061 MonSock Created bundled UDP socket 84.51.100.146:5060 2009/07/12 19:45:03.368 0:05.062 Listen Stopping listening thread on tcp$*:1720 2009/07/12 19:45:03.368 0:05.062 Opal Liste...0xb45ffb70 Listen Started listening thread on udp$*:5060 2009/07/12 19:45:03.368 0:05.062 PTLib Destroyed thread 0xb46029f0 Opal Listener:0xb45beb70(id = b45beb70) 2009/07/12 19:45:03.368 0:05.062 Opal Liste...0xb45beb70 Listen Started listening thread on tcp$*:1720 2009/07/12 19:45:03.368 0:05.062 PTLib Thread high water mark set: 11 2009/07/12 19:45:03.368 0:05.062 Opal Liste...0xb45beb70 Listen Waiting on socket accept on tcp$*:1720 2009/07/12 19:45:03.368 0:05.062 subscriber:0xb268eb70 SIP Start REGISTER aor=estaf@ekiga.net registrar=ekiga.net contact= authID=estaf realm= expire=3600 restore=30 minRetry=0.000 maxRetry=0.000 2009/07/12 19:45:03.369 0:05.063 subscriber:0xb268eb70 SIP Changing REGISTER handler from Unavailable to Subscribing, target=sip:estaf@ekiga.net, id=1cf83da2-686d-de11-9d8e-0080485416c1@estaf 2009/07/12 19:45:03.408 0:05.102 CallSetup:0xb457db70 SIP No SRV record found. 2009/07/12 19:45:03.408 0:05.102 CallSetup:0xb457db70 SIP Connecting to sip:500@ekiga.net via sip:500@ekiga.net 2009/07/12 19:45:03.793 0:05.487 subscriber:0xb268eb70 OpalUDP Binding to interface: 0.0.0.0:5060 2009/07/12 19:45:03.793 0:05.487 subscriber:0xb268eb70 SIP Created transport udp$86.64.162.35:5060 2009/07/12 19:45:03.794 0:05.488 subscriber:0xb268eb70 OpalUDP Started connect to 86.64.162.35:5060 2009/07/12 19:45:03.794 0:05.488 subscriber:0xb268eb70 OpalUDP Writing to interface 0 - "10.32.76.254%eth0" 2009/07/12 19:45:03.961 0:05.655 subscriber:0xb268eb70 OpalMan Listener interfaces: associated transport=udp$10.32.76.254:5060 udp$10.32.76.254:5060,udp$192.168.42.1:5060,udp$172.16.105.1:5060,udp$84.51.100.146:5060 2009/07/12 19:45:03.962 0:05.656 subscriber:0xb268eb70 SIP Transaction created. 2009/07/12 19:45:03.964 0:05.658 subscriber:0xb268eb70 DNS SRV Lookup ekiga.net service _sip._udp 2009/07/12 19:45:03.966 0:05.660 subscriber:0xb268eb70 SIP No SRV record found. 2009/07/12 19:45:03.966 0:05.660 subscriber:0xb268eb70 SIP Transaction remote address is udp$ekiga.net:5060 2009/07/12 19:45:03.967 0:05.661 subscriber:0xb268eb70 SIP Sending PDU (594 bytes) to: rem=udp$86.64.162.35:5060,local=udp$10.32.76.254:5060,if=10.32.76.254%eth0 REGISTER sip:ekiga.net SIP/2.0 CSeq: 1 REGISTER Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bK30c098a2-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 From: ;tag=88ff3da2-686d-de11-9d8e-0080485416c1 Call-ID: 1cf83da2-686d-de11-9d8e-0080485416c1@estaf To: Contact: ;q=1, ;q=0.750, ;q=0.500, ;q=0.250 Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING Expires: 3600 Content-Length: 0 Max-Forwards: 70 2009/07/12 19:45:03.968 0:05.662 subscriber:0xb268eb70 OpalUDP Setting interface to 10.32.76.254%eth0 2009/07/12 19:45:03.968 0:05.662 subscriber:0xb268eb70 SIP Transaction timers set: retry=0.500, completion=6.000 2009/07/12 19:45:03.968 0:05.662 subscriber:0xb268eb70 OpalUDP Writing to interface 1 - "192.168.42.1%vmnet1" 2009/07/12 19:45:03.969 0:05.663 subscriber:0xb268eb70 OpalMan Listener interfaces: associated transport=udp$192.168.42.1:5060 udp$10.32.76.254:5060,udp$192.168.42.1:5060,udp$172.16.105.1:5060,udp$84.51.100.146:5060 2009/07/12 19:45:03.970 0:05.664 subscriber:0xb268eb70 SIP Transaction created. 2009/07/12 19:45:03.971 0:05.665 subscriber:0xb268eb70 DNS SRV Lookup ekiga.net service _sip._udp 2009/07/12 19:45:03.977 0:05.671 subscriber:0xb268eb70 SIP No SRV record found. 2009/07/12 19:45:03.977 0:05.671 subscriber:0xb268eb70 SIP Transaction remote address is udp$ekiga.net:5060 2009/07/12 19:45:03.977 0:05.671 subscriber:0xb268eb70 SIP Sending PDU (594 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.42.1:5060,if=192.168.42.1%vmnet1 REGISTER sip:ekiga.net SIP/2.0 CSeq: 1 REGISTER Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bKa0ea99a2-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 From: ;tag=88ff3da2-686d-de11-9d8e-0080485416c1 Call-ID: 1cf83da2-686d-de11-9d8e-0080485416c1@estaf To: Contact: ;q=1, ;q=0.750, ;q=0.500, ;q=0.250 Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING Expires: 3600 Content-Length: 0 Max-Forwards: 70 2009/07/12 19:45:03.978 0:05.672 subscriber:0xb268eb70 OpalUDP Setting interface to 192.168.42.1%vmnet1 2009/07/12 19:45:03.978 0:05.672 subscriber:0xb268eb70 SIP Transaction timers set: retry=0.500, completion=6.000 2009/07/12 19:45:03.978 0:05.672 subscriber:0xb268eb70 OpalUDP Writing to interface 2 - "172.16.105.1%vmnet8" 2009/07/12 19:45:03.980 0:05.674 subscriber:0xb268eb70 OpalMan Listener interfaces: associated transport=udp$172.16.105.1:5060 udp$10.32.76.254:5060,udp$192.168.42.1:5060,udp$172.16.105.1:5060,udp$84.51.100.146:5060 2009/07/12 19:45:03.980 0:05.674 subscriber:0xb268eb70 SIP Transaction created. 2009/07/12 19:45:03.982 0:05.676 subscriber:0xb268eb70 DNS SRV Lookup ekiga.net service _sip._udp 2009/07/12 19:45:03.984 0:05.678 subscriber:0xb268eb70 SIP No SRV record found. 2009/07/12 19:45:03.984 0:05.678 subscriber:0xb268eb70 SIP Transaction remote address is udp$ekiga.net:5060 2009/07/12 19:45:03.985 0:05.679 subscriber:0xb268eb70 SIP Sending PDU (594 bytes) to: rem=udp$86.64.162.35:5060,local=udp$172.16.105.1:5060,if=172.16.105.1%vmnet8 REGISTER sip:ekiga.net SIP/2.0 CSeq: 1 REGISTER Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bKca829ba2-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 From: ;tag=88ff3da2-686d-de11-9d8e-0080485416c1 Call-ID: 1cf83da2-686d-de11-9d8e-0080485416c1@estaf To: Contact: ;q=1, ;q=0.750, ;q=0.500, ;q=0.250 Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING Expires: 3600 Content-Length: 0 Max-Forwards: 70 2009/07/12 19:45:03.986 0:05.680 subscriber:0xb268eb70 OpalUDP Setting interface to 172.16.105.1%vmnet8 2009/07/12 19:45:03.986 0:05.680 subscriber:0xb268eb70 SIP Transaction timers set: retry=0.500, completion=6.000 2009/07/12 19:45:03.986 0:05.680 subscriber:0xb268eb70 OpalUDP Writing to interface 3 - "84.51.100.146%ppp0" 2009/07/12 19:45:03.991 0:05.685 subscriber:0xb268eb70 OpalMan Listener interfaces: associated transport=udp$84.51.100.146:5060 udp$84.51.100.146:5060,udp$10.32.76.254:5060,udp$192.168.42.1:5060,udp$172.16.105.1:5060 2009/07/12 19:45:03.992 0:05.686 subscriber:0xb268eb70 SIP Transaction created. 2009/07/12 19:45:03.993 0:05.687 subscriber:0xb268eb70 DNS SRV Lookup ekiga.net service _sip._udp 2009/07/12 19:45:03.995 0:05.689 subscriber:0xb268eb70 SIP No SRV record found. 2009/07/12 19:45:03.995 0:05.689 subscriber:0xb268eb70 SIP Transaction remote address is udp$ekiga.net:5060 2009/07/12 19:45:03.995 0:05.689 subscriber:0xb268eb70 SIP Sending PDU (594 bytes) to: rem=udp$86.64.162.35:5060,local=udp$84.51.100.146:5060,if=84.51.100.146%ppp0 REGISTER sip:ekiga.net SIP/2.0 CSeq: 1 REGISTER Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bK003c9da2-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 From: ;tag=88ff3da2-686d-de11-9d8e-0080485416c1 Call-ID: 1cf83da2-686d-de11-9d8e-0080485416c1@estaf To: Contact: ;q=1, ;q=0.750, ;q=0.500, ;q=0.250 Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING Expires: 3600 Content-Length: 0 Max-Forwards: 70 2009/07/12 19:45:03.996 0:05.690 subscriber:0xb268eb70 OpalUDP Setting interface to 84.51.100.146%ppp0 2009/07/12 19:45:03.996 0:05.690 subscriber:0xb268eb70 SIP Transaction timers set: retry=0.500, completion=6.000 2009/07/12 19:45:03.996 0:05.690 subscriber:0xb268eb70 PTLib Destroyed thread 0xb4605898 subscriber:0xb268eb70(id = b268eb70) 2009/07/12 19:45:03.996 0:05.690 CallSetup:0xb457db70 SIP Found registrar on domain ekiga.net, using interface 84.51.100.146%ppp0 2009/07/12 19:45:03.997 0:05.691 CallSetup:0xb457db70 OpalUDP Binding to interface: 84.51.100.146:5060 2009/07/12 19:45:03.997 0:05.691 CallSetup:0xb457db70 SIP Created transport udp$86.64.162.35:5060 2009/07/12 19:45:03.997 0:05.691 CallSetup:0xb457db70 OpalUDP Started connect to 86.64.162.35:5060 2009/07/12 19:45:03.997 0:05.691 CallSetup:0xb457db70 SIP Updating dialog tag from "" to "6a192ea2-686d-de11-9d8e-0080485416c1" 2009/07/12 19:45:04.000 0:05.694 CallSetup:0xb457db70 SIP Transaction 1 INVITE created. 2009/07/12 19:45:04.004 0:05.698 CallSetup:0xb457db70 MediaFormat Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,iLBC-13k3,iLBC-15k2,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz 2009/07/12 19:45:04.005 0:05.699 CallSetup:0xb457db70 MediaFormat Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,iLBC-13k3,iLBC-15k2,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz 2009/07/12 19:45:04.006 0:05.700 CallSetup:0xb457db70 Call GetMediaFormats for Call[k883572a61]-EP[6a192ea2-686d-de11-9d8e-0080485416c1] SpeexIETFWide-20.6k SpeexWB SpeexWide-20.6k iLBC G.711-uLaw-64k G.711-ALaw-64k G.722-64k H.264 theora H.261 H.261-CIF H.261-QCIF H.263 H.263P MPEG4 2009/07/12 19:45:04.006 0:05.700 CallSetup:0xb457db70 SIP Offering media type audio in SDP with formats SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,iLBC,G.711-uLaw-64k,G.711-ALaw-64k,G.722-64k,H.264,theora,H.261,H.261-CIF,H.261-QCIF,H.263,H.263P,MPEG4 2009/07/12 19:45:04.006 0:05.700 CallSetup:0xb457db70 Call IsMediaBypassPossible Call[k883572a61]-EP[6a192ea2-686d-de11-9d8e-0080485416c1] session 1 2009/07/12 19:45:04.006 0:05.700 CallSetup:0xb457db70 OpalMan IsMediaBypassPossible: session 1 2009/07/12 19:45:04.006 0:05.700 CallSetup:0xb457db70 OpalCon IsMediaBypassPossible: default returns false 2009/07/12 19:45:04.006 0:05.700 CallSetup:0xb457db70 RTP Cannot find media session 1 2009/07/12 19:45:04.006 0:05.700 CallSetup:0xb457db70 RTP Cannot find RTP session 1 2009/07/12 19:45:04.007 0:05.701 CallSetup:0xb457db70 RTP_UDP Session 1, created with NAT flag set to 0 2009/07/12 19:45:04.007 0:05.701 CallSetup:0xb457db70 RTP_UDP Session 1 created: 84.51.100.146:5062-5063 ssrc=580279316 2009/07/12 19:45:04.007 0:05.701 CallSetup:0xb457db70 RTP Creating new session RTP_UDP 2009/07/12 19:45:04.007 0:05.701 CallSetup:0xb457db70 RTP Found existing media session 1 2009/07/12 19:45:04.008 0:05.702 CallSetup:0xb457db70 SDP SDP not including SpeexIETFWide-20.6k as it is not a SIP transportable format 2009/07/12 19:45:04.008 0:05.702 CallSetup:0xb457db70 SDP SDP not including SpeexWide-20.6k as it is not a SIP transportable format 2009/07/12 19:45:04.008 0:05.702 CallSetup:0xb457db70 SIP Using default RTP payload [pt=101] for UserInput/RFC2833 2009/07/12 19:45:04.008 0:05.702 CallSetup:0xb457db70 SIP Using default RTP payload [pt=126] for NamedSignalEvent 2009/07/12 19:45:04.013 0:05.707 CallSetup:0xb457db70 MediaFormat Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,iLBC-13k3,iLBC-15k2,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz 2009/07/12 19:45:04.013 0:05.707 CallSetup:0xb457db70 MediaFormat Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,iLBC-13k3,iLBC-15k2,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz 2009/07/12 19:45:04.014 0:05.708 CallSetup:0xb457db70 Call GetMediaFormats for Call[k883572a61]-EP[6a192ea2-686d-de11-9d8e-0080485416c1] SpeexIETFWide-20.6k SpeexWB SpeexWide-20.6k iLBC G.711-uLaw-64k G.711-ALaw-64k G.722-64k H.264 theora H.261 H.261-CIF H.261-QCIF H.263 H.263P MPEG4 2009/07/12 19:45:04.014 0:05.708 CallSetup:0xb457db70 SIP Offering media type video in SDP with formats SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,iLBC,G.711-uLaw-64k,G.711-ALaw-64k,G.722-64k,H.264,theora,H.261,H.261-CIF,H.261-QCIF,H.263,H.263P,MPEG4 2009/07/12 19:45:04.014 0:05.708 CallSetup:0xb457db70 Call IsMediaBypassPossible Call[k883572a61]-EP[6a192ea2-686d-de11-9d8e-0080485416c1] session 2 2009/07/12 19:45:04.014 0:05.708 CallSetup:0xb457db70 OpalMan IsMediaBypassPossible: session 2 2009/07/12 19:45:04.014 0:05.708 CallSetup:0xb457db70 OpalCon IsMediaBypassPossible: default returns false 2009/07/12 19:45:04.014 0:05.708 CallSetup:0xb457db70 RTP Cannot find media session 2 2009/07/12 19:45:04.014 0:05.708 CallSetup:0xb457db70 RTP Cannot find RTP session 2 2009/07/12 19:45:04.015 0:05.709 CallSetup:0xb457db70 RTP_UDP Session 2, created with NAT flag set to 0 2009/07/12 19:45:04.015 0:05.709 CallSetup:0xb457db70 RTP_UDP Session 2 created: 84.51.100.146:5064-5065 ssrc=1351688300 2009/07/12 19:45:04.016 0:05.710 CallSetup:0xb457db70 RTP Creating new session RTP_UDP 2009/07/12 19:45:04.016 0:05.710 CallSetup:0xb457db70 RTP Found existing media session 2 2009/07/12 19:45:04.016 0:05.710 CallSetup:0xb457db70 SDP SDP not including H.261-CIF as it is not a SIP transportable format 2009/07/12 19:45:04.016 0:05.710 CallSetup:0xb457db70 SDP SDP not including H.261-QCIF as it is not a SIP transportable format 2009/07/12 19:45:04.016 0:05.710 CallSetup:0xb457db70 SIP Creating INVITE request 2009/07/12 19:45:04.017 0:05.711 CallSetup:0xb457db70 DNS SRV Lookup ekiga.net service _sip._udp 2009/07/12 19:45:04.066 0:05.760 Opal Liste...0xb45ffb70 PWLib File handle high water mark set: 55 PUDPSocket 2009/07/12 19:45:04.067 0:05.761 Opal Liste...0xb45ffb70 OpalUDP Binding to interface: 84.51.100.146:5060 2009/07/12 19:45:04.067 0:05.761 Opal Liste...0xb45ffb70 SIP Waiting for PDU on udp$86.64.162.35:5060 2009/07/12 19:45:04.067 0:05.761 Opal Liste...0xb45ffb70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$84.51.100.146:5060,if=84.51.100.146%ppp0 SIP/2.0 401 Unauthorized CSeq: 1 REGISTER Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bK003c9da2-686d-de11-9d8e-0080485416c1;rport=5060 Server: Kamailio (1.4.0-notls (i386/linux)) From: ;tag=88ff3da2-686d-de11-9d8e-0080485416c1 Call-ID: 1cf83da2-686d-de11-9d8e-0080485416c1@estaf To: ;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.58d5 Content-Length: 0 WWW-Authenticate: Digest realm="ekiga.net", nonce="4a5a0630000000cb75d58abfa3c3414da0fe64fe4bd9b61a" 2009/07/12 19:45:04.068 0:05.762 Opal Liste...0xb45ffb70 PWLib File handle high water mark set: 56 Thread unblock pipe 2009/07/12 19:45:04.068 0:05.762 Pool:0xb268eb70 SIP Started processing PDU 2009/07/12 19:45:04.068 0:05.762 Opal Liste...0xb45ffb70 Opal Transport clean up on termination 2009/07/12 19:45:04.068 0:05.762 Opal Liste...0xb45ffb70 Opal Transport Close 2009/07/12 19:45:04.068 0:05.762 Pool:0xb268eb70 SIP Handling PDU "1 REGISTER <401>" for transaction=z9hG4bK003c9da2-686d-de11-9d8e-0080485416c1 2009/07/12 19:45:04.068 0:05.762 Pool:0xb268eb70 SIP Transaction 1 REGISTER completed. 2009/07/12 19:45:04.068 0:05.762 Opal Liste...0xb45ffb70 PWLib File handle high water mark set: 57 PUDPSocket 2009/07/12 19:45:04.068 0:05.762 Pool:0xb268eb70 SIP Received Authentication Required response 2009/07/12 19:45:04.068 0:05.763 Pool:0xb268eb70 SIP Set state Terminated_Aborted for transaction 1 REGISTER 2009/07/12 19:45:04.069 0:05.763 Pool:0xb268eb70 SIP Set state Terminated_Aborted for transaction 1 REGISTER 2009/07/12 19:45:04.069 0:05.763 Pool:0xb268eb70 SIP Set state Terminated_Aborted for transaction 1 REGISTER 2009/07/12 19:45:04.069 0:05.763 Pool:0xb268eb70 OpalUDP Setting interface to 84.51.100.146%ppp0 2009/07/12 19:45:04.069 0:05.763 Pool:0xb268eb70 SIP Changing REGISTER handler from Subscribing to Subscribing, target=sip:estaf@ekiga.net, id=1cf83da2-686d-de11-9d8e-0080485416c1@estaf 2009/07/12 19:45:04.068 0:05.762 Opal Liste...0xb45ffb70 Opal Deleted transport udp$86.64.162.35:5060 2009/07/12 19:45:04.070 0:05.764 Pool:0xb268eb70 OpalMan Listener interfaces: associated transport=udp$84.51.100.146:5060 udp$84.51.100.146:5060,udp$10.32.76.254:5060,udp$192.168.42.1:5060,udp$172.16.105.1:5060 2009/07/12 19:45:04.071 0:05.765 Pool:0xb268eb70 SIP Transaction created. 2009/07/12 19:45:04.073 0:05.767 Pool:0xb268eb70 SIP Adding authentication information 2009/07/12 19:45:04.073 0:05.767 Pool:0xb268eb70 DNS SRV Lookup ekiga.net service _sip._udp 2009/07/12 19:45:09.441 0:11.136 CallSetup:0xb457db70 SIP No SRV record found. 2009/07/12 19:45:09.442 0:11.136 Pool:0xb268eb70 SIP No SRV record found. 2009/07/12 19:45:09.442 0:11.136 Pool:0xb268eb70 SIP Transaction remote address is udp$ekiga.net:5060 2009/07/12 19:45:09.442 0:11.136 CallSetup:0xb457db70 SIP Transaction remote address is udp$ekiga.net:5060 2009/07/12 19:45:09.442 0:11.136 Pool:0xb268eb70 PWLib File handle low water mark set: 54 PUDPSocket 2009/07/12 19:45:09.442 0:11.136 Pool:0xb268eb70 SIP Sending PDU (792 bytes) to: rem=udp$86.64.162.35:5060,local=udp$84.51.100.146:5060,if=84.51.100.146%ppp0 REGISTER sip:ekiga.net SIP/2.0 CSeq: 2 REGISTER Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bKba6ba9a2-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 Authorization: Digest username="estaf", realm="ekiga.net", nonce="4a5a0630000000cb75d58abfa3c3414da0fe64fe4bd9b61a", uri="sip:ekiga.net", algorithm=MD5, response="f73d95bdc2b7fa8c05bfbe03a010eec7" From: ;tag=88ff3da2-686d-de11-9d8e-0080485416c1 Call-ID: 1cf83da2-686d-de11-9d8e-0080485416c1@estaf To: Contact: ;q=1, ;q=0.750, ;q=0.500, ;q=0.250 Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING Expires: 3600 Content-Length: 0 Max-Forwards: 70 2009/07/12 19:45:09.442 0:11.136 CallSetup:0xb457db70 OpalPlugin to_customised_options: H.264 2009/07/12 19:45:09.442 0:11.136 Pool:0xb268eb70 OpalUDP Setting interface to 84.51.100.146%ppp0 2009/07/12 19:45:09.443 0:11.137 Pool:0xb268eb70 SIP Transaction timers set: retry=0.500, completion=6.000 2009/07/12 19:45:09.443 0:11.137 Pool:0xb268eb70 SIP Finished processing PDU 2009/07/12 19:45:09.443 0:11.137 CallSetup:0xb457db70 OpalPlugin to_customised_options: theora 2009/07/12 19:45:09.444 0:11.138 CallSetup:0xb457db70 OpalPlugin to_customised_options: H.261 2009/07/12 19:45:09.444 0:11.138 CallSetup:0xb457db70 OpalPlugin to_customised_options changed option "Frame Width" from "320" to "176" 2009/07/12 19:45:09.444 0:11.138 CallSetup:0xb457db70 OpalPlugin to_customised_options changed option "Frame Height" from "240" to "144" 2009/07/12 19:45:09.444 0:11.138 CallSetup:0xb457db70 OpalPlugin to_customised_options: H.263 2009/07/12 19:45:09.445 0:11.139 CallSetup:0xb457db70 OpalPlugin to_customised_options changed option "Min Rx Frame Width" from "160" to "176" 2009/07/12 19:45:09.445 0:11.139 CallSetup:0xb457db70 OpalPlugin to_customised_options changed option "Min Rx Frame Height" from "120" to "144" 2009/07/12 19:45:09.445 0:11.139 CallSetup:0xb457db70 OpalPlugin to_customised_options changed option "Max Rx Frame Width" from "1920" to "704" 2009/07/12 19:45:09.445 0:11.139 CallSetup:0xb457db70 OpalPlugin to_customised_options changed option "Max Rx Frame Height" from "1088" to "576" 2009/07/12 19:45:09.445 0:11.139 CallSetup:0xb457db70 OpalPlugin to_customised_options changed option "MaxBR" from "0" to "40960" 2009/07/12 19:45:09.445 0:11.139 CallSetup:0xb457db70 OpalPlugin to_customised_options changed option "SQCIF MPI" from "1" to "33" 2009/07/12 19:45:09.445 0:11.139 CallSetup:0xb457db70 OpalPlugin to_customised_options changed option "CIF16 MPI" from "1" to "33" 2009/07/12 19:45:09.445 0:11.139 CallSetup:0xb457db70 OpalPlugin to_customised_options: H.263P 2009/07/12 19:45:09.446 0:11.140 CallSetup:0xb457db70 OpalPlugin to_customised_options changed option "Min Rx Frame Width" from "160" to "176" 2009/07/12 19:45:09.446 0:11.140 CallSetup:0xb457db70 OpalPlugin to_customised_options changed option "Min Rx Frame Height" from "120" to "144" 2009/07/12 19:45:09.447 0:11.141 CallSetup:0xb457db70 OpalPlugin to_customised_options changed option "Max Rx Frame Width" from "1920" to "704" 2009/07/12 19:45:09.447 0:11.141 CallSetup:0xb457db70 OpalPlugin to_customised_options changed option "Max Rx Frame Height" from "1088" to "576" 2009/07/12 19:45:09.447 0:11.141 CallSetup:0xb457db70 OpalPlugin to_customised_options changed option "SQCIF MPI" from "1" to "33" 2009/07/12 19:45:09.447 0:11.141 CallSetup:0xb457db70 OpalPlugin to_customised_options changed option "CIF16 MPI" from "1" to "33" 2009/07/12 19:45:09.447 0:11.142 CallSetup:0xb457db70 SIP PDU is too large (1543 bytes) trying compact form. 2009/07/12 19:45:09.448 0:11.142 CallSetup:0xb457db70 OpalPlugin to_customised_options: H.264 2009/07/12 19:45:09.448 0:11.142 CallSetup:0xb457db70 OpalPlugin to_customised_options: theora 2009/07/12 19:45:09.449 0:11.143 CallSetup:0xb457db70 OpalPlugin to_customised_options: H.261 2009/07/12 19:45:09.449 0:11.143 CallSetup:0xb457db70 OpalPlugin to_customised_options: H.263 2009/07/12 19:45:09.450 0:11.144 CallSetup:0xb457db70 OpalPlugin to_customised_options changed option "MaxBR" from "0" to "40960" 2009/07/12 19:45:09.450 0:11.144 CallSetup:0xb457db70 OpalPlugin to_customised_options: H.263P 2009/07/12 19:45:09.452 0:11.146 CallSetup:0xb457db70 SIP PDU is likely too large (1501 bytes) for UDP datagram. 2009/07/12 19:45:09.452 0:11.146 CallSetup:0xb457db70 SIP Sending PDU (1501 bytes) to: rem=udp$86.64.162.35:5060,local=udp$84.51.100.146:5060,if=84.51.100.146%ppp0 INVITE sip:500@ekiga.net SIP/2.0 Date: Sun, 12 Jul 2009 15:45:04 GMT CSeq: 1 INVITE v: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bK38499ea2-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 f: "Dmitry Nesterov" ;tag=6a192ea2-686d-de11-9d8e-0080485416c1 i: ce2d2ea2-686d-de11-9d8e-0080485416c1@estaf t: m: Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING c: application/sdp l: 985 Max-Forwards: 70 v=0 o=- 1247413504 1247413504 IN IP4 84.51.100.146 s=Opal SIP Session c=IN IP4 84.51.100.146 t=0 0 m=audio 5062 RTP/AVP 125 111 0 8 9 101 126 c=IN IP4 84.51.100.146 a=sendrecv a=rtpmap:125 Speex/16000/1 a=fmtp:125 sr=16000,mode=any a=rtpmap:111 iLBC/8000/1 a=fmtp:111 mode=20 a=rtpmap:0 PCMU/8000/1 a=rtpmap:8 PCMA/8000/1 a=rtpmap:9 G722/8000/1 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16,32,36 a=rtpmap:126 NSE/8000 a=fmtp:126 192-193 m=video 5064 RTP/AVP 103 124 31 34 102 99 c=IN IP4 84.51.100.146 b=AS:4096 b=TIAS:4096000 a=sendrecv a=rtpmap:103 h264/90000 a=fmtp:103 packetization-mode=1;profile-level-id=42C01E a=rtpmap:124 theora/90000 a=fmtp:124 height=576;width=704 a=rtpmap:31 h261/90000 a=fmtp:31 CIF=1;QCIF=1 a=rtpmap:34 h263/90000 a=fmtp:34 F=1;CIF=1;CIF4=1;QCIF=1 a=rtpmap:102 h263-1998/90000 a=fmtp:102 D=1;F=1;I=1;J=1;CIF=1;CIF4=1;QCIF=1;CUSTOM=320,240,1;CUSTOM=640,480,1 a=rtpmap:99 MP4V-ES/90000 a=fmtp:99 profile-level-id=5 2009/07/12 19:45:09.453 0:11.147 CallSetup:0xb457db70 OpalUDP Setting interface to 84.51.100.146%ppp0 2009/07/12 19:45:09.453 0:11.147 CallSetup:0xb457db70 SIP Transaction timers set: retry=0.500, completion=1:00.000 2009/07/12 19:45:09.453 0:11.147 CallSetup:0xb457db70 OpalCon OnSetUpConnectionCall[k883572a61]-EP[6a192ea2-686d-de11-9d8e-0080485416c1] 2009/07/12 19:45:09.453 0:11.147 CallSetup:0xb457db70 OpalEP OnSetUpConnection Call[k883572a61]-EP[6a192ea2-686d-de11-9d8e-0080485416c1] 2009/07/12 19:45:09.453 0:11.147 CallSetup:0xb457db70 PTLib Destroyed thread 0x8b14380 CallSetup:0xb457db70(id = b457db70) 2009/07/12 19:45:09.453 0:11.147 Opal Garbage:0xb47dbb70 SIP Transaction 1 REGISTER destroyed. 2009/07/12 19:45:09.453 0:11.147 Opal Garbage:0xb47dbb70 SIP Transaction 1 REGISTER destroyed. 2009/07/12 19:45:09.453 0:11.147 Opal Garbage:0xb47dbb70 SIP Transaction 1 REGISTER destroyed. 2009/07/12 19:45:09.514 0:11.208 Opal Liste...0xb45ffb70 PWLib File handle low water mark set: 45 PUDPSocket 2009/07/12 19:45:09.515 0:11.209 Opal Liste...0xb45ffb70 OpalUDP Binding to interface: 84.51.100.146:5060 2009/07/12 19:45:09.515 0:11.209 Opal Liste...0xb45ffb70 SIP Waiting for PDU on udp$86.64.162.35:5060 2009/07/12 19:45:09.515 0:11.209 Opal Liste...0xb45ffb70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$84.51.100.146:5060,if=84.51.100.146%ppp0 SIP/2.0 200 OK CSeq: 2 REGISTER Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bKba6ba9a2-686d-de11-9d8e-0080485416c1;rport=5060 Server: Kamailio (1.4.0-notls (i386/linux)) From: ;tag=88ff3da2-686d-de11-9d8e-0080485416c1 Call-ID: 1cf83da2-686d-de11-9d8e-0080485416c1@estaf To: ;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.f8ff Contact: ;q=1;expires=1200, ;q=0.75;expires=1200, ;q=0.5;expires=1200, ;q=0.25;expires=1200 Content-Length: 0 2009/07/12 19:45:09.516 0:11.210 Opal Liste...0xb45ffb70 Opal Transport clean up on termination 2009/07/12 19:45:09.516 0:11.210 Opal Liste...0xb45ffb70 Opal Transport Close 2009/07/12 19:45:09.516 0:11.210 Opal Liste...0xb45ffb70 Opal Deleted transport udp$86.64.162.35:5060 2009/07/12 19:45:09.516 0:11.210 Pool:0xb268eb70 SIP Started processing PDU 2009/07/12 19:45:09.516 0:11.210 Pool:0xb268eb70 SIP Handling PDU "2 REGISTER <200>" for transaction=z9hG4bKba6ba9a2-686d-de11-9d8e-0080485416c1 2009/07/12 19:45:09.516 0:11.210 Pool:0xb268eb70 SIP Transaction 2 REGISTER completed. 2009/07/12 19:45:09.516 0:11.210 Pool:0xb268eb70 SIP Product Info: name="Kamailio", version="", vendor="", comments="(1.4.0-notls (i386/linux))" 2009/07/12 19:45:09.516 0:11.210 Pool:0xb268eb70 SIP Changing REGISTER handler from Subscribing to Subscribed, target=sip:estaf@ekiga.net, id=1cf83da2-686d-de11-9d8e-0080485416c1@estaf 2009/07/12 19:45:09.516 0:11.210 Pool:0xb268eb70 OpalUDP Setting interface to 84.51.100.146%ppp0 2009/07/12 19:45:09.517 0:11.211 Pool:0xb268eb70 SIP Expiry time for REGISTER set to 1200 seconds. 2009/07/12 19:45:09.517 0:11.211 Pool:0xb268eb70 SIP Expiry time for REGISTER set to 1200 seconds. 2009/07/12 19:45:09.517 0:11.211 Pool:0xb268eb70 SIP Expiry time for REGISTER set to 1200 seconds. 2009/07/12 19:45:09.517 0:11.211 Pool:0xb268eb70 SIP Expiry time for REGISTER set to 1200 seconds. 2009/07/12 19:45:09.517 0:11.211 Pool:0xb268eb70 SIP Product Info: name="Kamailio", version="", vendor="", comments="(1.4.0-notls (i386/linux))" 2009/07/12 19:45:09.518 0:11.212 Pool:0xb268eb70 SIP Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:500@ekiga.net, id=6a3de8a5-686d-de11-9d8e-0080485416c1@estaf 2009/07/12 19:45:09.518 0:11.212 Pool:0xb268eb70 OpalUDP Binding to interface: 0.0.0.0:5060 2009/07/12 19:45:09.518 0:11.212 Pool:0xb268eb70 SIP Created transport udp$86.64.162.35:5060 2009/07/12 19:45:09.518 0:11.212 Pool:0xb268eb70 OpalUDP Started connect to 86.64.162.35:5060 2009/07/12 19:45:09.518 0:11.212 Pool:0xb268eb70 OpalUDP Writing to interface 0 - "10.32.76.254%eth0" 2009/07/12 19:45:09.519 0:11.213 Pool:0xb268eb70 SIP Transaction created. 2009/07/12 19:45:09.686 0:11.380 Pool:0xb268eb70 DNS SRV Lookup ekiga.net service _sip._udp 2009/07/12 19:45:09.688 0:11.382 Pool:0xb268eb70 SIP No SRV record found. 2009/07/12 19:45:09.688 0:11.382 Pool:0xb268eb70 SIP Transaction remote address is udp$ekiga.net:5060 2009/07/12 19:45:09.689 0:11.383 Pool:0xb268eb70 SIP Sending PDU (556 bytes) to: rem=udp$86.64.162.35:5060,local=udp$10.32.76.254:5060,if=10.32.76.254%eth0 SUBSCRIBE sip:500@ekiga.net SIP/2.0 CSeq: 1 SUBSCRIBE Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bKccb701a6-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 From: "Dmitry Nesterov" ;tag=8662e8a5-686d-de11-9d8e-0080485416c1 Call-ID: 6a3de8a5-686d-de11-9d8e-0080485416c1@estaf To: Contact: 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 2009/07/12 19:45:09.689 0:11.383 Pool:0xb268eb70 OpalUDP Setting interface to 10.32.76.254%eth0 2009/07/12 19:45:09.689 0:11.383 Pool:0xb268eb70 SIP Transaction timers set: retry=0.500, completion=6.000 2009/07/12 19:45:09.689 0:11.383 Pool:0xb268eb70 OpalUDP Writing to interface 1 - "192.168.42.1%vmnet1" 2009/07/12 19:45:09.690 0:11.384 Pool:0xb268eb70 SIP Transaction created. 2009/07/12 19:45:09.692 0:11.386 Pool:0xb268eb70 DNS SRV Lookup ekiga.net service _sip._udp 2009/07/12 19:45:09.694 0:11.388 Pool:0xb268eb70 SIP No SRV record found. 2009/07/12 19:45:09.694 0:11.388 Pool:0xb268eb70 SIP Transaction remote address is udp$ekiga.net:5060 2009/07/12 19:45:09.695 0:11.389 Pool:0xb268eb70 SIP Sending PDU (556 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.42.1:5060,if=192.168.42.1%vmnet1 SUBSCRIBE sip:500@ekiga.net SIP/2.0 CSeq: 2 SUBSCRIBE Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bK32a602a6-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 From: "Dmitry Nesterov" ;tag=8662e8a5-686d-de11-9d8e-0080485416c1 Call-ID: 6a3de8a5-686d-de11-9d8e-0080485416c1@estaf To: Contact: 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 2009/07/12 19:45:09.695 0:11.389 Pool:0xb268eb70 OpalUDP Setting interface to 192.168.42.1%vmnet1 2009/07/12 19:45:09.695 0:11.389 Pool:0xb268eb70 SIP Transaction timers set: retry=0.500, completion=6.000 2009/07/12 19:45:09.695 0:11.389 Pool:0xb268eb70 OpalUDP Writing to interface 2 - "172.16.105.1%vmnet8" 2009/07/12 19:45:09.695 0:11.389 Pool:0xb268eb70 SIP Transaction created. 2009/07/12 19:45:09.697 0:11.391 Pool:0xb268eb70 DNS SRV Lookup ekiga.net service _sip._udp 2009/07/12 19:45:09.699 0:11.393 Pool:0xb268eb70 SIP No SRV record found. 2009/07/12 19:45:09.699 0:11.393 Pool:0xb268eb70 SIP Transaction remote address is udp$ekiga.net:5060 2009/07/12 19:45:09.700 0:11.394 Pool:0xb268eb70 SIP Sending PDU (556 bytes) to: rem=udp$86.64.162.35:5060,local=udp$172.16.105.1:5060,if=172.16.105.1%vmnet8 SUBSCRIBE sip:500@ekiga.net SIP/2.0 CSeq: 3 SUBSCRIBE Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bK208503a6-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 From: "Dmitry Nesterov" ;tag=8662e8a5-686d-de11-9d8e-0080485416c1 Call-ID: 6a3de8a5-686d-de11-9d8e-0080485416c1@estaf To: Contact: 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 2009/07/12 19:45:09.700 0:11.394 Pool:0xb268eb70 OpalUDP Setting interface to 172.16.105.1%vmnet8 2009/07/12 19:45:09.700 0:11.394 Pool:0xb268eb70 SIP Transaction timers set: retry=0.500, completion=6.000 2009/07/12 19:45:09.700 0:11.394 Pool:0xb268eb70 OpalUDP Writing to interface 3 - "84.51.100.146%ppp0" 2009/07/12 19:45:09.700 0:11.394 Pool:0xb268eb70 SIP Transaction created. 2009/07/12 19:45:09.703 0:11.397 Pool:0xb268eb70 DNS SRV Lookup ekiga.net service _sip._udp 2009/07/12 19:45:09.705 0:11.399 Pool:0xb268eb70 SIP No SRV record found. 2009/07/12 19:45:09.705 0:11.399 Pool:0xb268eb70 SIP Transaction remote address is udp$ekiga.net:5060 2009/07/12 19:45:09.705 0:11.400 Pool:0xb268eb70 SIP Sending PDU (556 bytes) to: rem=udp$86.64.162.35:5060,local=udp$84.51.100.146:5060,if=84.51.100.146%ppp0 SUBSCRIBE sip:500@ekiga.net SIP/2.0 CSeq: 4 SUBSCRIBE Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bK924904a6-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 From: "Dmitry Nesterov" ;tag=8662e8a5-686d-de11-9d8e-0080485416c1 Call-ID: 6a3de8a5-686d-de11-9d8e-0080485416c1@estaf To: Contact: 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 2009/07/12 19:45:09.706 0:11.400 Pool:0xb268eb70 OpalUDP Setting interface to 84.51.100.146%ppp0 2009/07/12 19:45:09.706 0:11.400 Pool:0xb268eb70 SIP Transaction timers set: retry=0.500, completion=6.000 2009/07/12 19:45:09.706 0:11.400 Pool:0xb268eb70 SIP Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:500@ekiga.net, id=4c0705a6-686d-de11-9d8e-0080485416c1@estaf 2009/07/12 19:45:09.706 0:11.400 Pool:0xb268eb70 OpalUDP Binding to interface: 0.0.0.0:5060 2009/07/12 19:45:09.707 0:11.401 Pool:0xb268eb70 SIP Created transport udp$86.64.162.35:5060 2009/07/12 19:45:09.707 0:11.401 Pool:0xb268eb70 OpalUDP Started connect to 86.64.162.35:5060 2009/07/12 19:45:09.707 0:11.401 Pool:0xb268eb70 OpalUDP Writing to interface 0 - "10.32.76.254%eth0" 2009/07/12 19:45:09.707 0:11.401 Pool:0xb268eb70 SIP Transaction created. 2009/07/12 19:45:09.709 0:11.403 Pool:0xb268eb70 DNS SRV Lookup ekiga.net service _sip._udp 2009/07/12 19:45:09.713 0:11.407 Pool:0xb268eb70 SIP No SRV record found. 2009/07/12 19:45:09.713 0:11.407 Pool:0xb268eb70 SIP Transaction remote address is udp$ekiga.net:5060 2009/07/12 19:45:09.713 0:11.407 Pool:0xb268eb70 SIP Sending PDU (566 bytes) to: rem=udp$86.64.162.35:5060,local=udp$10.32.76.254:5060,if=10.32.76.254%eth0 SUBSCRIBE sip:500@ekiga.net SIP/2.0 CSeq: 1 SUBSCRIBE Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bK665605a6-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 From: "Dmitry Nesterov" ;tag=aa2b05a6-686d-de11-9d8e-0080485416c1 Call-ID: 4c0705a6-686d-de11-9d8e-0080485416c1@estaf To: Contact: 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 2009/07/12 19:45:09.714 0:11.408 Pool:0xb268eb70 OpalUDP Setting interface to 10.32.76.254%eth0 2009/07/12 19:45:09.714 0:11.408 Pool:0xb268eb70 SIP Transaction timers set: retry=0.500, completion=6.000 2009/07/12 19:45:09.714 0:11.408 Pool:0xb268eb70 OpalUDP Writing to interface 1 - "192.168.42.1%vmnet1" 2009/07/12 19:45:09.714 0:11.408 Pool:0xb268eb70 SIP Transaction created. 2009/07/12 19:45:09.716 0:11.410 Pool:0xb268eb70 DNS SRV Lookup ekiga.net service _sip._udp 2009/07/12 19:45:09.718 0:11.412 Pool:0xb268eb70 SIP No SRV record found. 2009/07/12 19:45:09.718 0:11.412 Pool:0xb268eb70 SIP Transaction remote address is udp$ekiga.net:5060 2009/07/12 19:45:09.718 0:11.412 Pool:0xb268eb70 SIP Sending PDU (566 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.42.1:5060,if=192.168.42.1%vmnet1 SUBSCRIBE sip:500@ekiga.net SIP/2.0 CSeq: 2 SUBSCRIBE Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bK746006a6-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 From: "Dmitry Nesterov" ;tag=aa2b05a6-686d-de11-9d8e-0080485416c1 Call-ID: 4c0705a6-686d-de11-9d8e-0080485416c1@estaf To: Contact: 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 2009/07/12 19:45:09.718 0:11.412 Pool:0xb268eb70 OpalUDP Setting interface to 192.168.42.1%vmnet1 2009/07/12 19:45:09.719 0:11.413 Pool:0xb268eb70 SIP Transaction timers set: retry=0.500, completion=6.000 2009/07/12 19:45:09.719 0:11.413 Pool:0xb268eb70 OpalUDP Writing to interface 2 - "172.16.105.1%vmnet8" 2009/07/12 19:45:09.719 0:11.413 Pool:0xb268eb70 SIP Transaction created. 2009/07/12 19:45:09.721 0:11.415 Pool:0xb268eb70 DNS SRV Lookup ekiga.net service _sip._udp 2009/07/12 19:45:09.722 0:11.416 Housekeeper:0xb4759b70 SIP Set state Terminated_Success for transaction 1 REGISTER 2009/07/12 19:45:09.723 0:11.417 Pool:0xb268eb70 SIP No SRV record found. 2009/07/12 19:45:09.723 0:11.417 Pool:0xb268eb70 SIP Transaction remote address is udp$ekiga.net:5060 2009/07/12 19:45:09.723 0:11.417 Pool:0xb268eb70 SIP Sending PDU (566 bytes) to: rem=udp$86.64.162.35:5060,local=udp$172.16.105.1:5060,if=172.16.105.1%vmnet8 SUBSCRIBE sip:500@ekiga.net SIP/2.0 CSeq: 3 SUBSCRIBE Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bK241907a6-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 From: "Dmitry Nesterov" ;tag=aa2b05a6-686d-de11-9d8e-0080485416c1 Call-ID: 4c0705a6-686d-de11-9d8e-0080485416c1@estaf To: Contact: 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 2009/07/12 19:45:09.724 0:11.418 Pool:0xb268eb70 OpalUDP Setting interface to 172.16.105.1%vmnet8 2009/07/12 19:45:09.724 0:11.418 Pool:0xb268eb70 SIP Transaction timers set: retry=0.500, completion=6.000 2009/07/12 19:45:09.724 0:11.418 Pool:0xb268eb70 OpalUDP Writing to interface 3 - "84.51.100.146%ppp0" 2009/07/12 19:45:09.724 0:11.418 Pool:0xb268eb70 SIP Transaction created. 2009/07/12 19:45:09.726 0:11.420 Pool:0xb268eb70 DNS SRV Lookup ekiga.net service _sip._udp 2009/07/12 19:45:09.731 0:11.425 Pool:0xb268eb70 SIP No SRV record found. 2009/07/12 19:45:09.731 0:11.425 Pool:0xb268eb70 SIP Transaction remote address is udp$ekiga.net:5060 2009/07/12 19:45:09.731 0:11.425 Pool:0xb268eb70 SIP Sending PDU (566 bytes) to: rem=udp$86.64.162.35:5060,local=udp$84.51.100.146:5060,if=84.51.100.146%ppp0 SUBSCRIBE sip:500@ekiga.net SIP/2.0 CSeq: 4 SUBSCRIBE Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bK38eb07a6-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 From: "Dmitry Nesterov" ;tag=aa2b05a6-686d-de11-9d8e-0080485416c1 Call-ID: 4c0705a6-686d-de11-9d8e-0080485416c1@estaf To: Contact: 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 2009/07/12 19:45:09.732 0:11.426 Pool:0xb268eb70 OpalUDP Setting interface to 84.51.100.146%ppp0 2009/07/12 19:45:09.732 0:11.426 Pool:0xb268eb70 SIP Transaction timers set: retry=0.500, completion=6.000 2009/07/12 19:45:09.733 0:11.427 Pool:0xb268eb70 SIP Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:501@ekiga.net, id=d00b09a6-686d-de11-9d8e-0080485416c1@estaf 2009/07/12 19:45:09.733 0:11.427 Pool:0xb268eb70 OpalUDP Binding to interface: 0.0.0.0:5060 2009/07/12 19:45:09.733 0:11.427 Pool:0xb268eb70 SIP Created transport udp$86.64.162.35:5060 2009/07/12 19:45:09.733 0:11.427 Pool:0xb268eb70 OpalUDP Started connect to 86.64.162.35:5060 2009/07/12 19:45:09.734 0:11.428 Pool:0xb268eb70 OpalUDP Writing to interface 0 - "10.32.76.254%eth0" 2009/07/12 19:45:09.734 0:11.428 Pool:0xb268eb70 SIP Transaction created. 2009/07/12 19:45:09.736 0:11.430 Pool:0xb268eb70 DNS SRV Lookup ekiga.net service _sip._udp 2009/07/12 19:45:09.738 0:11.432 Pool:0xb268eb70 SIP No SRV record found. 2009/07/12 19:45:09.738 0:11.432 Pool:0xb268eb70 SIP Transaction remote address is udp$ekiga.net:5060 2009/07/12 19:45:09.738 0:11.432 Pool:0xb268eb70 SIP Sending PDU (556 bytes) to: rem=udp$86.64.162.35:5060,local=udp$10.32.76.254:5060,if=10.32.76.254%eth0 SUBSCRIBE sip:501@ekiga.net SIP/2.0 CSeq: 1 SUBSCRIBE Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bK1c6a09a6-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 From: "Dmitry Nesterov" ;tag=ee3809a6-686d-de11-9d8e-0080485416c1 Call-ID: d00b09a6-686d-de11-9d8e-0080485416c1@estaf To: Contact: 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 2009/07/12 19:45:09.739 0:11.433 Pool:0xb268eb70 OpalUDP Setting interface to 10.32.76.254%eth0 2009/07/12 19:45:09.739 0:11.433 Pool:0xb268eb70 SIP Transaction timers set: retry=0.500, completion=6.000 2009/07/12 19:45:09.739 0:11.433 Pool:0xb268eb70 OpalUDP Writing to interface 1 - "192.168.42.1%vmnet1" 2009/07/12 19:45:09.740 0:11.434 Pool:0xb268eb70 SIP Transaction created. 2009/07/12 19:45:09.742 0:11.436 Pool:0xb268eb70 DNS SRV Lookup ekiga.net service _sip._udp 2009/07/12 19:45:09.744 0:11.438 Pool:0xb268eb70 SIP No SRV record found. 2009/07/12 19:45:09.744 0:11.438 Pool:0xb268eb70 SIP Transaction remote address is udp$ekiga.net:5060 2009/07/12 19:45:09.744 0:11.438 Pool:0xb268eb70 SIP Sending PDU (556 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.42.1:5060,if=192.168.42.1%vmnet1 SUBSCRIBE sip:501@ekiga.net SIP/2.0 CSeq: 2 SUBSCRIBE Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bK36450aa6-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 From: "Dmitry Nesterov" ;tag=ee3809a6-686d-de11-9d8e-0080485416c1 Call-ID: d00b09a6-686d-de11-9d8e-0080485416c1@estaf To: Contact: 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 2009/07/12 19:45:09.745 0:11.439 Pool:0xb268eb70 OpalUDP Setting interface to 192.168.42.1%vmnet1 2009/07/12 19:45:09.745 0:11.439 Pool:0xb268eb70 SIP Transaction timers set: retry=0.500, completion=6.000 2009/07/12 19:45:09.745 0:11.439 Pool:0xb268eb70 OpalUDP Writing to interface 2 - "172.16.105.1%vmnet8" 2009/07/12 19:45:09.745 0:11.439 Pool:0xb268eb70 SIP Transaction created. 2009/07/12 19:45:09.747 0:11.441 Pool:0xb268eb70 DNS SRV Lookup ekiga.net service _sip._udp 2009/07/12 19:45:09.749 0:11.443 Pool:0xb268eb70 SIP No SRV record found. 2009/07/12 19:45:09.749 0:11.443 Pool:0xb268eb70 SIP Transaction remote address is udp$ekiga.net:5060 2009/07/12 19:45:09.750 0:11.444 Pool:0xb268eb70 SIP Sending PDU (556 bytes) to: rem=udp$86.64.162.35:5060,local=udp$172.16.105.1:5060,if=172.16.105.1%vmnet8 SUBSCRIBE sip:501@ekiga.net SIP/2.0 CSeq: 3 SUBSCRIBE Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bK94220ba6-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 From: "Dmitry Nesterov" ;tag=ee3809a6-686d-de11-9d8e-0080485416c1 Call-ID: d00b09a6-686d-de11-9d8e-0080485416c1@estaf To: Contact: 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 2009/07/12 19:45:09.750 0:11.444 Pool:0xb268eb70 OpalUDP Setting interface to 172.16.105.1%vmnet8 2009/07/12 19:45:09.750 0:11.444 Pool:0xb268eb70 SIP Transaction timers set: retry=0.500, completion=6.000 2009/07/12 19:45:09.750 0:11.444 Pool:0xb268eb70 OpalUDP Writing to interface 3 - "84.51.100.146%ppp0" 2009/07/12 19:45:09.751 0:11.445 Pool:0xb268eb70 SIP Transaction created. 2009/07/12 19:45:09.753 0:11.447 Pool:0xb268eb70 DNS SRV Lookup ekiga.net service _sip._udp 2009/07/12 19:45:09.755 0:11.449 Pool:0xb268eb70 SIP No SRV record found. 2009/07/12 19:45:09.755 0:11.449 Pool:0xb268eb70 SIP Transaction remote address is udp$ekiga.net:5060 2009/07/12 19:45:09.755 0:11.449 Pool:0xb268eb70 SIP Sending PDU (556 bytes) to: rem=udp$86.64.162.35:5060,local=udp$84.51.100.146:5060,if=84.51.100.146%ppp0 SUBSCRIBE sip:501@ekiga.net SIP/2.0 CSeq: 4 SUBSCRIBE Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bK1efc0ba6-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 From: "Dmitry Nesterov" ;tag=ee3809a6-686d-de11-9d8e-0080485416c1 Call-ID: d00b09a6-686d-de11-9d8e-0080485416c1@estaf To: Contact: 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 2009/07/12 19:45:09.756 0:11.450 Pool:0xb268eb70 OpalUDP Setting interface to 84.51.100.146%ppp0 2009/07/12 19:45:09.756 0:11.450 Pool:0xb268eb70 SIP Transaction timers set: retry=0.500, completion=6.000 2009/07/12 19:45:09.756 0:11.450 Pool:0xb268eb70 SIP Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:501@ekiga.net, id=f6aa0ca6-686d-de11-9d8e-0080485416c1@estaf 2009/07/12 19:45:09.757 0:11.451 Pool:0xb268eb70 OpalUDP Binding to interface: 0.0.0.0:5060 2009/07/12 19:45:09.757 0:11.451 Pool:0xb268eb70 SIP Created transport udp$86.64.162.35:5060 2009/07/12 19:45:09.757 0:11.451 Pool:0xb268eb70 OpalUDP Started connect to 86.64.162.35:5060 2009/07/12 19:45:09.757 0:11.451 Pool:0xb268eb70 OpalUDP Writing to interface 0 - "10.32.76.254%eth0" 2009/07/12 19:45:09.757 0:11.451 Pool:0xb268eb70 SIP Transaction created. 2009/07/12 19:45:09.759 0:11.453 Pool:0xb268eb70 DNS SRV Lookup ekiga.net service _sip._udp 2009/07/12 19:45:09.762 0:11.456 Pool:0xb268eb70 SIP No SRV record found. 2009/07/12 19:45:09.762 0:11.456 Pool:0xb268eb70 SIP Transaction remote address is udp$ekiga.net:5060 2009/07/12 19:45:09.762 0:11.456 Pool:0xb268eb70 SIP Sending PDU (566 bytes) to: rem=udp$86.64.162.35:5060,local=udp$10.32.76.254:5060,if=10.32.76.254%eth0 SUBSCRIBE sip:501@ekiga.net SIP/2.0 CSeq: 1 SUBSCRIBE Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bK42ff0ca6-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 From: "Dmitry Nesterov" ;tag=56d70ca6-686d-de11-9d8e-0080485416c1 Call-ID: f6aa0ca6-686d-de11-9d8e-0080485416c1@estaf To: Contact: 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 2009/07/12 19:45:09.763 0:11.457 Pool:0xb268eb70 OpalUDP Setting interface to 10.32.76.254%eth0 2009/07/12 19:45:09.763 0:11.457 Pool:0xb268eb70 SIP Transaction timers set: retry=0.500, completion=6.000 2009/07/12 19:45:09.763 0:11.457 Pool:0xb268eb70 OpalUDP Writing to interface 1 - "192.168.42.1%vmnet1" 2009/07/12 19:45:09.763 0:11.457 Pool:0xb268eb70 SIP Transaction created. 2009/07/12 19:45:09.766 0:11.460 Pool:0xb268eb70 DNS SRV Lookup ekiga.net service _sip._udp 2009/07/12 19:45:09.768 0:11.462 Pool:0xb268eb70 SIP No SRV record found. 2009/07/12 19:45:09.768 0:11.462 Pool:0xb268eb70 SIP Transaction remote address is udp$ekiga.net:5060 2009/07/12 19:45:09.768 0:11.462 Pool:0xb268eb70 SIP Sending PDU (566 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.42.1:5060,if=192.168.42.1%vmnet1 SUBSCRIBE sip:501@ekiga.net SIP/2.0 CSeq: 2 SUBSCRIBE Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bK3ee90da6-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 From: "Dmitry Nesterov" ;tag=56d70ca6-686d-de11-9d8e-0080485416c1 Call-ID: f6aa0ca6-686d-de11-9d8e-0080485416c1@estaf To: Contact: 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 2009/07/12 19:45:09.769 0:11.463 Pool:0xb268eb70 OpalUDP Setting interface to 192.168.42.1%vmnet1 2009/07/12 19:45:09.769 0:11.463 Pool:0xb268eb70 SIP Transaction timers set: retry=0.500, completion=6.000 2009/07/12 19:45:09.769 0:11.463 Pool:0xb268eb70 OpalUDP Writing to interface 2 - "172.16.105.1%vmnet8" 2009/07/12 19:45:09.769 0:11.463 Pool:0xb268eb70 SIP Transaction created. 2009/07/12 19:45:09.771 0:11.465 Pool:0xb268eb70 DNS SRV Lookup ekiga.net service _sip._udp 2009/07/12 19:45:09.776 0:11.470 Opal Liste...0xb45ffb70 OpalUDP Binding to interface: 84.51.100.146:5060 2009/07/12 19:45:09.776 0:11.470 Opal Liste...0xb45ffb70 SIP Waiting for PDU on udp$86.64.162.35:5060 2009/07/12 19:45:09.776 0:11.471 Opal Liste...0xb45ffb70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$84.51.100.146:5060,if=84.51.100.146%ppp0 SIP/2.0 489 Event Not Supported CSeq: 4 SUBSCRIBE Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bK924904a6-686d-de11-9d8e-0080485416c1;rport=5060 Server: Kamailio (1.4.0-notls (i386/linux)) From: "Dmitry Nesterov" ;tag=8662e8a5-686d-de11-9d8e-0080485416c1 Call-ID: 6a3de8a5-686d-de11-9d8e-0080485416c1@estaf To: ;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.5c5e Content-Length: 0 2009/07/12 19:45:09.777 0:11.471 Opal Liste...0xb45ffb70 Opal Transport clean up on termination 2009/07/12 19:45:09.777 0:11.471 Opal Liste...0xb45ffb70 Opal Transport Close 2009/07/12 19:45:09.777 0:11.471 Opal Liste...0xb45ffb70 Opal Deleted transport udp$86.64.162.35:5060 2009/07/12 19:45:09.778 0:11.472 Pool:0xb268eb70 SIP No SRV record found. 2009/07/12 19:45:09.779 0:11.473 Pool:0xb268eb70 SIP Transaction remote address is udp$ekiga.net:5060 2009/07/12 19:45:09.779 0:11.473 Pool:0xb268eb70 SIP Sending PDU (566 bytes) to: rem=udp$86.64.162.35:5060,local=udp$172.16.105.1:5060,if=172.16.105.1%vmnet8 SUBSCRIBE sip:501@ekiga.net SIP/2.0 CSeq: 3 SUBSCRIBE Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bK3ec50ea6-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 From: "Dmitry Nesterov" ;tag=56d70ca6-686d-de11-9d8e-0080485416c1 Call-ID: f6aa0ca6-686d-de11-9d8e-0080485416c1@estaf To: Contact: 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 2009/07/12 19:45:09.780 0:11.474 Pool:0xb268eb70 OpalUDP Setting interface to 172.16.105.1%vmnet8 2009/07/12 19:45:09.780 0:11.474 Pool:0xb268eb70 SIP Transaction timers set: retry=0.500, completion=6.000 2009/07/12 19:45:09.780 0:11.474 Pool:0xb268eb70 OpalUDP Writing to interface 3 - "84.51.100.146%ppp0" 2009/07/12 19:45:09.780 0:11.474 Pool:0xb268eb70 SIP Transaction created. 2009/07/12 19:45:09.783 0:11.477 Pool:0xb268eb70 DNS SRV Lookup ekiga.net service _sip._udp 2009/07/12 19:45:09.785 0:11.479 Pool:0xb268eb70 SIP No SRV record found. 2009/07/12 19:45:09.785 0:11.479 Pool:0xb268eb70 SIP Transaction remote address is udp$ekiga.net:5060 2009/07/12 19:45:09.786 0:11.480 Pool:0xb268eb70 SIP Sending PDU (566 bytes) to: rem=udp$86.64.162.35:5060,local=udp$84.51.100.146:5060,if=84.51.100.146%ppp0 SUBSCRIBE sip:501@ekiga.net SIP/2.0 CSeq: 4 SUBSCRIBE Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bK0e9410a6-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 From: "Dmitry Nesterov" ;tag=56d70ca6-686d-de11-9d8e-0080485416c1 Call-ID: f6aa0ca6-686d-de11-9d8e-0080485416c1@estaf To: Contact: 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 2009/07/12 19:45:09.786 0:11.480 Pool:0xb268eb70 OpalUDP Setting interface to 84.51.100.146%ppp0 2009/07/12 19:45:09.786 0:11.480 Pool:0xb268eb70 SIP Transaction timers set: retry=0.500, completion=6.000 2009/07/12 19:45:09.787 0:11.481 Pool:0xb268eb70 SIP Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:estaf@ekiga.net, id=285b11a6-686d-de11-9d8e-0080485416c1@estaf 2009/07/12 19:45:09.788 0:11.482 Pool:0xb268eb70 OpalUDP Binding to interface: 0.0.0.0:5060 2009/07/12 19:45:09.788 0:11.482 Pool:0xb268eb70 SIP Created transport udp$86.64.162.35:5060 2009/07/12 19:45:09.788 0:11.482 Pool:0xb268eb70 OpalUDP Started connect to 86.64.162.35:5060 2009/07/12 19:45:09.788 0:11.482 Pool:0xb268eb70 OpalUDP Writing to interface 0 - "10.32.76.254%eth0" 2009/07/12 19:45:09.789 0:11.483 Pool:0xb268eb70 SIP Transaction created. 2009/07/12 19:45:09.792 0:11.486 Pool:0xb268eb70 DNS SRV Lookup ekiga.net service _sip._udp 2009/07/12 19:45:09.794 0:11.488 Pool:0xb268eb70 SIP No SRV record found. 2009/07/12 19:45:09.794 0:11.488 Pool:0xb268eb70 SIP Transaction remote address is udp$ekiga.net:5060 2009/07/12 19:45:09.794 0:11.488 Pool:0xb268eb70 SIP Sending PDU (582 bytes) to: rem=udp$86.64.162.35:5060,local=udp$10.32.76.254:5060,if=10.32.76.254%eth0 SUBSCRIBE sip:estaf@ekiga.net SIP/2.0 CSeq: 1 SUBSCRIBE Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bK86c011a6-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 From: "Dmitry Nesterov" ;tag=709a11a6-686d-de11-9d8e-0080485416c1 Call-ID: 285b11a6-686d-de11-9d8e-0080485416c1@estaf To: Contact: 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 2009/07/12 19:45:09.795 0:11.489 Pool:0xb268eb70 OpalUDP Setting interface to 10.32.76.254%eth0 2009/07/12 19:45:09.796 0:11.490 Pool:0xb268eb70 SIP Transaction timers set: retry=0.500, completion=6.000 2009/07/12 19:45:09.796 0:11.490 Pool:0xb268eb70 OpalUDP Writing to interface 1 - "192.168.42.1%vmnet1" 2009/07/12 19:45:09.796 0:11.490 Pool:0xb268eb70 SIP Transaction created. 2009/07/12 19:45:09.799 0:11.493 Pool:0xb268eb70 DNS SRV Lookup ekiga.net service _sip._udp 2009/07/12 19:45:09.801 0:11.495 Pool:0xb268eb70 SIP No SRV record found. 2009/07/12 19:45:09.801 0:11.495 Pool:0xb268eb70 SIP Transaction remote address is udp$ekiga.net:5060 2009/07/12 19:45:09.802 0:11.496 Pool:0xb268eb70 SIP Sending PDU (582 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.42.1:5060,if=192.168.42.1%vmnet1 SUBSCRIBE sip:estaf@ekiga.net SIP/2.0 CSeq: 2 SUBSCRIBE Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bKcaf312a6-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 From: "Dmitry Nesterov" ;tag=709a11a6-686d-de11-9d8e-0080485416c1 Call-ID: 285b11a6-686d-de11-9d8e-0080485416c1@estaf To: Contact: 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 2009/07/12 19:45:09.802 0:11.496 Pool:0xb268eb70 OpalUDP Setting interface to 192.168.42.1%vmnet1 2009/07/12 19:45:09.803 0:11.497 Pool:0xb268eb70 SIP Transaction timers set: retry=0.500, completion=6.000 2009/07/12 19:45:09.803 0:11.497 Pool:0xb268eb70 OpalUDP Writing to interface 2 - "172.16.105.1%vmnet8" 2009/07/12 19:45:09.803 0:11.497 Pool:0xb268eb70 SIP Transaction created. 2009/07/12 19:45:09.805 0:11.499 Pool:0xb268eb70 DNS SRV Lookup ekiga.net service _sip._udp 2009/07/12 19:45:09.806 0:11.500 Opal Liste...0xb45ffb70 OpalUDP Binding to interface: 84.51.100.146:5060 2009/07/12 19:45:09.806 0:11.500 Opal Liste...0xb45ffb70 SIP Waiting for PDU on udp$86.64.162.35:5060 2009/07/12 19:45:09.806 0:11.500 Opal Liste...0xb45ffb70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$84.51.100.146:5060,if=84.51.100.146%ppp0 SIP/2.0 489 Event Not Supported CSeq: 4 SUBSCRIBE Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bK38eb07a6-686d-de11-9d8e-0080485416c1;rport=5060 Server: Kamailio (1.4.0-notls (i386/linux)) From: "Dmitry Nesterov" ;tag=aa2b05a6-686d-de11-9d8e-0080485416c1 Call-ID: 4c0705a6-686d-de11-9d8e-0080485416c1@estaf To: ;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.7e51 Content-Length: 0 2009/07/12 19:45:09.807 0:11.501 Opal Liste...0xb45ffb70 Opal Transport clean up on termination 2009/07/12 19:45:09.807 0:11.501 Opal Liste...0xb45ffb70 Opal Transport Close 2009/07/12 19:45:09.807 0:11.501 Opal Liste...0xb45ffb70 Opal Deleted transport udp$86.64.162.35:5060 2009/07/12 19:45:09.807 0:11.501 Pool:0xb457db70 SIP Started processing PDU 2009/07/12 19:45:09.807 0:11.501 Pool:0xb457db70 SIP Handling PDU "4 SUBSCRIBE <489>" for transaction=z9hG4bK38eb07a6-686d-de11-9d8e-0080485416c1 2009/07/12 19:45:09.808 0:11.502 Pool:0xb457db70 SIP Transaction 4 SUBSCRIBE completed. 2009/07/12 19:45:09.808 0:11.502 Pool:0xb268eb70 SIP No SRV record found. 2009/07/12 19:45:09.808 0:11.502 Pool:0xb268eb70 SIP Transaction remote address is udp$ekiga.net:5060 2009/07/12 19:45:09.808 0:11.502 Pool:0xb268eb70 SIP Sending PDU (582 bytes) to: rem=udp$86.64.162.35:5060,local=udp$172.16.105.1:5060,if=172.16.105.1%vmnet8 SUBSCRIBE sip:estaf@ekiga.net SIP/2.0 CSeq: 3 SUBSCRIBE Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bK16ed13a6-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 From: "Dmitry Nesterov" ;tag=709a11a6-686d-de11-9d8e-0080485416c1 Call-ID: 285b11a6-686d-de11-9d8e-0080485416c1@estaf To: Contact: 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 2009/07/12 19:45:09.809 0:11.503 Pool:0xb268eb70 OpalUDP Setting interface to 172.16.105.1%vmnet8 2009/07/12 19:45:09.809 0:11.503 Pool:0xb268eb70 SIP Transaction timers set: retry=0.500, completion=6.000 2009/07/12 19:45:09.809 0:11.503 Pool:0xb268eb70 OpalUDP Writing to interface 3 - "84.51.100.146%ppp0" 2009/07/12 19:45:09.809 0:11.503 Pool:0xb268eb70 SIP Transaction created. 2009/07/12 19:45:09.812 0:11.506 Pool:0xb268eb70 DNS SRV Lookup ekiga.net service _sip._udp 2009/07/12 19:45:09.814 0:11.508 Pool:0xb268eb70 SIP No SRV record found. 2009/07/12 19:45:09.814 0:11.508 Pool:0xb268eb70 SIP Transaction remote address is udp$ekiga.net:5060 2009/07/12 19:45:09.814 0:11.508 Pool:0xb268eb70 SIP Sending PDU (582 bytes) to: rem=udp$86.64.162.35:5060,local=udp$84.51.100.146:5060,if=84.51.100.146%ppp0 SUBSCRIBE sip:estaf@ekiga.net SIP/2.0 CSeq: 4 SUBSCRIBE Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bKa0e914a6-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 From: "Dmitry Nesterov" ;tag=709a11a6-686d-de11-9d8e-0080485416c1 Call-ID: 285b11a6-686d-de11-9d8e-0080485416c1@estaf To: Contact: 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 2009/07/12 19:45:09.815 0:11.509 Pool:0xb268eb70 OpalUDP Setting interface to 84.51.100.146%ppp0 2009/07/12 19:45:09.815 0:11.509 Pool:0xb268eb70 SIP Transaction timers set: retry=0.500, completion=6.000 2009/07/12 19:45:09.815 0:11.509 Pool:0xb268eb70 SIP Finished processing PDU 2009/07/12 19:45:09.815 0:11.509 Pool:0xb268eb70 SIP Started processing PDU 2009/07/12 19:45:09.815 0:11.509 Pool:0xb457db70 SIP Not retrying SUBSCRIBE due to error response 489 Bad Event 2009/07/12 19:45:09.815 0:11.509 Pool:0xb457db70 SIP Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:500@ekiga.net, id=4c0705a6-686d-de11-9d8e-0080485416c1@estaf 2009/07/12 19:45:09.815 0:11.509 Pool:0xb268eb70 SIP Handling PDU "4 SUBSCRIBE <489>" for transaction=z9hG4bK924904a6-686d-de11-9d8e-0080485416c1 2009/07/12 19:45:09.815 0:11.509 Pool:0xb268eb70 SIP Transaction 4 SUBSCRIBE completed. 2009/07/12 19:45:09.815 0:11.509 Pool:0xb457db70 SIP Set state Terminated_Aborted for transaction 1 SUBSCRIBE 2009/07/12 19:45:09.815 0:11.509 Pool:0xb268eb70 SIP Not retrying SUBSCRIBE due to error response 489 Bad Event 2009/07/12 19:45:09.815 0:11.509 Pool:0xb268eb70 SIP Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:500@ekiga.net, id=6a3de8a5-686d-de11-9d8e-0080485416c1@estaf 2009/07/12 19:45:09.815 0:11.509 Pool:0xb268eb70 SIP Set state Terminated_Aborted for transaction 1 SUBSCRIBE 2009/07/12 19:45:09.816 0:11.510 Pool:0xb268eb70 SIP Not retrying SUBSCRIBE due to error response 487 Request Terminated 2009/07/12 19:45:09.816 0:11.510 Pool:0xb268eb70 SIP Changing SUBSCRIBE handler from Unsubscribed to Unsubscribed, target=sip:500@ekiga.net, id=6a3de8a5-686d-de11-9d8e-0080485416c1@estaf 2009/07/12 19:45:09.816 0:11.510 Pool:0xb268eb70 SIP Set state Terminated_Aborted for transaction 2 SUBSCRIBE 2009/07/12 19:45:09.816 0:11.510 Pool:0xb268eb70 SIP Not retrying SUBSCRIBE due to error response 487 Request Terminated 2009/07/12 19:45:09.816 0:11.510 Pool:0xb268eb70 SIP Changing SUBSCRIBE handler from Unsubscribed to Unsubscribed, target=sip:500@ekiga.net, id=6a3de8a5-686d-de11-9d8e-0080485416c1@estaf 2009/07/12 19:45:09.816 0:11.510 Pool:0xb268eb70 SIP Set state Terminated_Aborted for transaction 3 SUBSCRIBE 2009/07/12 19:45:09.816 0:11.510 Pool:0xb268eb70 SIP Not retrying SUBSCRIBE due to error response 487 Request Terminated 2009/07/12 19:45:09.816 0:11.510 Pool:0xb268eb70 SIP Changing SUBSCRIBE handler from Unsubscribed to Unsubscribed, target=sip:500@ekiga.net, id=6a3de8a5-686d-de11-9d8e-0080485416c1@estaf 2009/07/12 19:45:09.816 0:11.510 Pool:0xb268eb70 SIP Set state Terminated_Aborted for transaction 4 SUBSCRIBE 2009/07/12 19:45:09.816 0:11.510 Pool:0xb268eb70 SIP Not retrying SUBSCRIBE due to error response 487 Request Terminated 2009/07/12 19:45:09.816 0:11.510 Pool:0xb268eb70 SIP Changing SUBSCRIBE handler from Unsubscribed to Unsubscribed, target=sip:500@ekiga.net, id=6a3de8a5-686d-de11-9d8e-0080485416c1@estaf 2009/07/12 19:45:09.816 0:11.510 Pool:0xb268eb70 SIP Finished processing PDU 2009/07/12 19:45:09.816 0:11.510 Pool:0xb457db70 SIP Not retrying SUBSCRIBE due to error response 487 Request Terminated 2009/07/12 19:45:09.816 0:11.510 Pool:0xb457db70 SIP Changing SUBSCRIBE handler from Unsubscribed to Unsubscribed, target=sip:500@ekiga.net, id=4c0705a6-686d-de11-9d8e-0080485416c1@estaf 2009/07/12 19:45:09.816 0:11.510 Pool:0xb457db70 SIP Set state Terminated_Aborted for transaction 2 SUBSCRIBE 2009/07/12 19:45:09.816 0:11.510 Pool:0xb457db70 SIP Not retrying SUBSCRIBE due to error response 487 Request Terminated 2009/07/12 19:45:09.816 0:11.510 Pool:0xb457db70 SIP Changing SUBSCRIBE handler from Unsubscribed to Unsubscribed, target=sip:500@ekiga.net, id=4c0705a6-686d-de11-9d8e-0080485416c1@estaf 2009/07/12 19:45:09.817 0:11.511 Pool:0xb457db70 SIP Set state Terminated_Aborted for transaction 3 SUBSCRIBE 2009/07/12 19:45:09.817 0:11.511 Pool:0xb457db70 SIP Not retrying SUBSCRIBE due to error response 487 Request Terminated 2009/07/12 19:45:09.817 0:11.511 Pool:0xb457db70 SIP Changing SUBSCRIBE handler from Unsubscribed to Unsubscribed, target=sip:500@ekiga.net, id=4c0705a6-686d-de11-9d8e-0080485416c1@estaf 2009/07/12 19:45:09.817 0:11.511 Pool:0xb457db70 SIP Set state Terminated_Aborted for transaction 4 SUBSCRIBE 2009/07/12 19:45:09.817 0:11.511 Pool:0xb457db70 SIP Not retrying SUBSCRIBE due to error response 487 Request Terminated 2009/07/12 19:45:09.817 0:11.511 Pool:0xb457db70 SIP Changing SUBSCRIBE handler from Unsubscribed to Unsubscribed, target=sip:500@ekiga.net, id=4c0705a6-686d-de11-9d8e-0080485416c1@estaf 2009/07/12 19:45:09.817 0:11.511 Pool:0xb457db70 SIP Finished processing PDU 2009/07/12 19:45:09.826 0:11.520 Opal Liste...0xb45ffb70 OpalUDP Binding to interface: 84.51.100.146:5060 2009/07/12 19:45:09.826 0:11.520 Opal Liste...0xb45ffb70 SIP Waiting for PDU on udp$86.64.162.35:5060 2009/07/12 19:45:09.827 0:11.521 Opal Liste...0xb45ffb70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$84.51.100.146:5060,if=84.51.100.146%ppp0 SIP/2.0 489 Event Not Supported CSeq: 4 SUBSCRIBE Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bK1efc0ba6-686d-de11-9d8e-0080485416c1;rport=5060 Server: Kamailio (1.4.0-notls (i386/linux)) From: "Dmitry Nesterov" ;tag=ee3809a6-686d-de11-9d8e-0080485416c1 Call-ID: d00b09a6-686d-de11-9d8e-0080485416c1@estaf To: ;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.1f61 Content-Length: 0 2009/07/12 19:45:09.827 0:11.521 Opal Liste...0xb45ffb70 Opal Transport clean up on termination 2009/07/12 19:45:09.827 0:11.521 Opal Liste...0xb45ffb70 Opal Transport Close 2009/07/12 19:45:09.827 0:11.521 Opal Liste...0xb45ffb70 Opal Deleted transport udp$86.64.162.35:5060 2009/07/12 19:45:09.827 0:11.521 Pool:0xb268eb70 SIP Started processing PDU 2009/07/12 19:45:09.828 0:11.522 Pool:0xb268eb70 SIP Handling PDU "4 SUBSCRIBE <489>" for transaction=z9hG4bK1efc0ba6-686d-de11-9d8e-0080485416c1 2009/07/12 19:45:09.828 0:11.522 Pool:0xb268eb70 SIP Transaction 4 SUBSCRIBE completed. 2009/07/12 19:45:09.828 0:11.522 Pool:0xb268eb70 SIP Not retrying SUBSCRIBE due to error response 489 Bad Event 2009/07/12 19:45:09.828 0:11.522 Pool:0xb268eb70 SIP Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:501@ekiga.net, id=d00b09a6-686d-de11-9d8e-0080485416c1@estaf 2009/07/12 19:45:09.828 0:11.522 Pool:0xb268eb70 SIP Set state Terminated_Aborted for transaction 1 SUBSCRIBE 2009/07/12 19:45:09.828 0:11.522 Pool:0xb268eb70 SIP Not retrying SUBSCRIBE due to error response 487 Request Terminated 2009/07/12 19:45:09.828 0:11.522 Pool:0xb268eb70 SIP Changing SUBSCRIBE handler from Unsubscribed to Unsubscribed, target=sip:501@ekiga.net, id=d00b09a6-686d-de11-9d8e-0080485416c1@estaf 2009/07/12 19:45:09.828 0:11.522 Pool:0xb268eb70 SIP Set state Terminated_Aborted for transaction 2 SUBSCRIBE 2009/07/12 19:45:09.828 0:11.522 Pool:0xb268eb70 SIP Not retrying SUBSCRIBE due to error response 487 Request Terminated 2009/07/12 19:45:09.828 0:11.522 Pool:0xb268eb70 SIP Changing SUBSCRIBE handler from Unsubscribed to Unsubscribed, target=sip:501@ekiga.net, id=d00b09a6-686d-de11-9d8e-0080485416c1@estaf 2009/07/12 19:45:09.828 0:11.522 Pool:0xb268eb70 SIP Set state Terminated_Aborted for transaction 3 SUBSCRIBE 2009/07/12 19:45:09.828 0:11.522 Pool:0xb268eb70 SIP Not retrying SUBSCRIBE due to error response 487 Request Terminated 2009/07/12 19:45:09.828 0:11.522 Pool:0xb268eb70 SIP Changing SUBSCRIBE handler from Unsubscribed to Unsubscribed, target=sip:501@ekiga.net, id=d00b09a6-686d-de11-9d8e-0080485416c1@estaf 2009/07/12 19:45:09.836 0:11.530 Pool:0xb268eb70 SIP Set state Terminated_Aborted for transaction 4 SUBSCRIBE 2009/07/12 19:45:09.836 0:11.530 Pool:0xb268eb70 SIP Not retrying SUBSCRIBE due to error response 487 Request Terminated 2009/07/12 19:45:09.836 0:11.530 Pool:0xb268eb70 SIP Changing SUBSCRIBE handler from Unsubscribed to Unsubscribed, target=sip:501@ekiga.net, id=d00b09a6-686d-de11-9d8e-0080485416c1@estaf 2009/07/12 19:45:09.836 0:11.530 Pool:0xb268eb70 SIP Finished processing PDU 2009/07/12 19:45:09.856 0:11.550 Opal Liste...0xb45ffb70 OpalUDP Binding to interface: 84.51.100.146:5060 2009/07/12 19:45:09.857 0:11.551 Opal Liste...0xb45ffb70 SIP Waiting for PDU on udp$86.64.162.35:5060 2009/07/12 19:45:09.857 0:11.551 Opal Liste...0xb45ffb70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$84.51.100.146:5060,if=84.51.100.146%ppp0 SIP/2.0 489 Event Not Supported CSeq: 4 SUBSCRIBE Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bK0e9410a6-686d-de11-9d8e-0080485416c1;rport=5060 Server: Kamailio (1.4.0-notls (i386/linux)) From: "Dmitry Nesterov" ;tag=56d70ca6-686d-de11-9d8e-0080485416c1 Call-ID: f6aa0ca6-686d-de11-9d8e-0080485416c1@estaf To: ;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.cd40 Content-Length: 0 2009/07/12 19:45:09.857 0:11.551 Opal Liste...0xb45ffb70 Opal Transport clean up on termination 2009/07/12 19:45:09.857 0:11.551 Opal Liste...0xb45ffb70 Opal Transport Close 2009/07/12 19:45:09.858 0:11.552 Opal Liste...0xb45ffb70 Opal Deleted transport udp$86.64.162.35:5060 2009/07/12 19:45:09.858 0:11.552 Pool:0xb268eb70 SIP Started processing PDU 2009/07/12 19:45:09.858 0:11.552 Pool:0xb268eb70 SIP Handling PDU "4 SUBSCRIBE <489>" for transaction=z9hG4bK0e9410a6-686d-de11-9d8e-0080485416c1 2009/07/12 19:45:09.858 0:11.552 Pool:0xb268eb70 SIP Transaction 4 SUBSCRIBE completed. 2009/07/12 19:45:09.858 0:11.552 Pool:0xb268eb70 SIP Not retrying SUBSCRIBE due to error response 489 Bad Event 2009/07/12 19:45:09.858 0:11.552 Pool:0xb268eb70 SIP Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:501@ekiga.net, id=f6aa0ca6-686d-de11-9d8e-0080485416c1@estaf 2009/07/12 19:45:09.858 0:11.552 Pool:0xb268eb70 SIP Set state Terminated_Aborted for transaction 1 SUBSCRIBE 2009/07/12 19:45:09.858 0:11.552 Pool:0xb268eb70 SIP Not retrying SUBSCRIBE due to error response 487 Request Terminated 2009/07/12 19:45:09.858 0:11.552 Pool:0xb268eb70 SIP Changing SUBSCRIBE handler from Unsubscribed to Unsubscribed, target=sip:501@ekiga.net, id=f6aa0ca6-686d-de11-9d8e-0080485416c1@estaf 2009/07/12 19:45:09.858 0:11.552 Pool:0xb268eb70 SIP Set state Terminated_Aborted for transaction 2 SUBSCRIBE 2009/07/12 19:45:09.858 0:11.553 Pool:0xb268eb70 SIP Not retrying SUBSCRIBE due to error response 487 Request Terminated 2009/07/12 19:45:09.859 0:11.553 Pool:0xb268eb70 SIP Changing SUBSCRIBE handler from Unsubscribed to Unsubscribed, target=sip:501@ekiga.net, id=f6aa0ca6-686d-de11-9d8e-0080485416c1@estaf 2009/07/12 19:45:09.859 0:11.553 Pool:0xb268eb70 SIP Set state Terminated_Aborted for transaction 3 SUBSCRIBE 2009/07/12 19:45:09.859 0:11.553 Pool:0xb268eb70 SIP Not retrying SUBSCRIBE due to error response 487 Request Terminated 2009/07/12 19:45:09.859 0:11.553 Pool:0xb268eb70 SIP Changing SUBSCRIBE handler from Unsubscribed to Unsubscribed, target=sip:501@ekiga.net, id=f6aa0ca6-686d-de11-9d8e-0080485416c1@estaf 2009/07/12 19:45:09.859 0:11.553 Pool:0xb268eb70 SIP Set state Terminated_Aborted for transaction 4 SUBSCRIBE 2009/07/12 19:45:09.859 0:11.553 Pool:0xb268eb70 SIP Not retrying SUBSCRIBE due to error response 487 Request Terminated 2009/07/12 19:45:09.859 0:11.553 Pool:0xb268eb70 SIP Changing SUBSCRIBE handler from Unsubscribed to Unsubscribed, target=sip:501@ekiga.net, id=f6aa0ca6-686d-de11-9d8e-0080485416c1@estaf 2009/07/12 19:45:09.859 0:11.553 Pool:0xb268eb70 SIP Finished processing PDU 2009/07/12 19:45:09.871 0:11.565 SIP Changing PUBLISH handler from Unavailable to Subscribing, target=sip:estaf@ekiga.net, id=30191ea6-686d-de11-9d8e-0080485416c1@estaf 2009/07/12 19:45:09.871 0:11.565 OpalUDP Binding to interface: 0.0.0.0:5060 2009/07/12 19:45:09.871 0:11.565 SIP Created transport udp$86.64.162.35:5060 2009/07/12 19:45:09.871 0:11.565 OpalUDP Started connect to 86.64.162.35:5060 2009/07/12 19:45:09.871 0:11.565 OpalUDP Writing to interface 0 - "10.32.76.254%eth0" 2009/07/12 19:45:09.871 0:11.565 SIP Expiry time for PUBLISH set to 500 seconds. 2009/07/12 19:45:09.872 0:11.566 SIP Transaction created. 2009/07/12 19:45:09.874 0:11.568 DNS SRV Lookup ekiga.net service _sip._udp 2009/07/12 19:45:09.875 0:11.569 SIP No SRV record found. 2009/07/12 19:45:09.876 0:11.570 SIP Transaction remote address is udp$ekiga.net:5060 2009/07/12 19:45:09.876 0:11.570 SIP Sending PDU (788 bytes) to: rem=udp$86.64.162.35:5060,local=udp$10.32.76.254:5060,if=10.32.76.254%eth0 PUBLISH sip:estaf@ekiga.net SIP/2.0 CSeq: 3 PUBLISH Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bKa26a1ea6-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 From: ;tag=5e451ea6-686d-de11-9d8e-0080485416c1 Call-ID: 30191ea6-686d-de11-9d8e-0080485416c1@estaf To: Contact: Expires: 500 Event: presence Content-Type: application/pidf+xml Content-Length: 321 Max-Forwards: 70 online - I'm online using Ekiga open sip:estaf@ekiga.net 2009/07/12 19:45:09.877 0:11.571 OpalUDP Setting interface to 10.32.76.254%eth0 2009/07/12 19:45:09.877 0:11.571 SIP Transaction timers set: retry=0.500, completion=6.000 2009/07/12 19:45:09.877 0:11.571 OpalUDP Writing to interface 1 - "192.168.42.1%vmnet1" 2009/07/12 19:45:09.877 0:11.571 SIP Expiry time for PUBLISH set to 500 seconds. 2009/07/12 19:45:09.877 0:11.571 SIP Transaction created. 2009/07/12 19:45:09.879 0:11.573 DNS SRV Lookup ekiga.net service _sip._udp 2009/07/12 19:45:09.881 0:11.575 SIP No SRV record found. 2009/07/12 19:45:09.881 0:11.575 SIP Transaction remote address is udp$ekiga.net:5060 2009/07/12 19:45:09.881 0:11.575 SIP Sending PDU (788 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.42.1:5060,if=192.168.42.1%vmnet1 PUBLISH sip:estaf@ekiga.net SIP/2.0 CSeq: 4 PUBLISH Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bK863a1fa6-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 From: ;tag=80131fa6-686d-de11-9d8e-0080485416c1 Call-ID: 30191ea6-686d-de11-9d8e-0080485416c1@estaf To: Contact: Expires: 500 Event: presence Content-Type: application/pidf+xml Content-Length: 321 Max-Forwards: 70 online - I'm online using Ekiga open sip:estaf@ekiga.net 2009/07/12 19:45:09.882 0:11.576 OpalUDP Setting interface to 192.168.42.1%vmnet1 2009/07/12 19:45:09.882 0:11.576 SIP Transaction timers set: retry=0.500, completion=6.000 2009/07/12 19:45:09.882 0:11.576 OpalUDP Writing to interface 2 - "172.16.105.1%vmnet8" 2009/07/12 19:45:09.882 0:11.576 SIP Expiry time for PUBLISH set to 500 seconds. 2009/07/12 19:45:09.882 0:11.576 SIP Transaction created. 2009/07/12 19:45:09.884 0:11.578 DNS SRV Lookup ekiga.net service _sip._udp 2009/07/12 19:45:09.885 0:11.579 Opal Liste...0xb45ffb70 OpalUDP Binding to interface: 84.51.100.146:5060 2009/07/12 19:45:09.885 0:11.579 Opal Liste...0xb45ffb70 SIP Waiting for PDU on udp$86.64.162.35:5060 2009/07/12 19:45:09.885 0:11.579 Opal Liste...0xb45ffb70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$84.51.100.146:5060,if=84.51.100.146%ppp0 SIP/2.0 489 Event Not Supported CSeq: 4 SUBSCRIBE Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bKa0e914a6-686d-de11-9d8e-0080485416c1;rport=5060 Server: Kamailio (1.4.0-notls (i386/linux)) From: "Dmitry Nesterov" ;tag=709a11a6-686d-de11-9d8e-0080485416c1 Call-ID: 285b11a6-686d-de11-9d8e-0080485416c1@estaf To: ;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.4a5a Content-Length: 0 2009/07/12 19:45:09.886 0:11.580 Opal Liste...0xb45ffb70 Opal Transport clean up on termination 2009/07/12 19:45:09.886 0:11.580 Opal Liste...0xb45ffb70 Opal Transport Close 2009/07/12 19:45:09.886 0:11.580 Opal Liste...0xb45ffb70 Opal Deleted transport udp$86.64.162.35:5060 2009/07/12 19:45:09.886 0:11.580 Pool:0xb268eb70 SIP Started processing PDU 2009/07/12 19:45:09.886 0:11.580 SIP No SRV record found. 2009/07/12 19:45:09.886 0:11.580 Pool:0xb268eb70 SIP Handling PDU "4 SUBSCRIBE <489>" for transaction=z9hG4bKa0e914a6-686d-de11-9d8e-0080485416c1 2009/07/12 19:45:09.886 0:11.580 SIP Transaction remote address is udp$ekiga.net:5060 2009/07/12 19:45:09.886 0:11.580 Pool:0xb268eb70 SIP Transaction 4 SUBSCRIBE completed. 2009/07/12 19:45:09.886 0:11.580 Pool:0xb268eb70 SIP Not retrying SUBSCRIBE due to error response 489 Bad Event 2009/07/12 19:45:09.886 0:11.580 Pool:0xb268eb70 SIP Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:estaf@ekiga.net, id=285b11a6-686d-de11-9d8e-0080485416c1@estaf 2009/07/12 19:45:09.886 0:11.580 Pool:0xb268eb70 SIP Set state Terminated_Aborted for transaction 1 SUBSCRIBE 2009/07/12 19:45:09.887 0:11.581 Pool:0xb268eb70 SIP Not retrying SUBSCRIBE due to error response 487 Request Terminated 2009/07/12 19:45:09.887 0:11.581 Pool:0xb268eb70 SIP Changing SUBSCRIBE handler from Unsubscribed to Unsubscribed, target=sip:estaf@ekiga.net, id=285b11a6-686d-de11-9d8e-0080485416c1@estaf 2009/07/12 19:45:09.887 0:11.581 Pool:0xb268eb70 SIP Set state Terminated_Aborted for transaction 2 SUBSCRIBE 2009/07/12 19:45:09.887 0:11.581 Pool:0xb268eb70 SIP Not retrying SUBSCRIBE due to error response 487 Request Terminated 2009/07/12 19:45:09.887 0:11.581 Pool:0xb268eb70 SIP Changing SUBSCRIBE handler from Unsubscribed to Unsubscribed, target=sip:estaf@ekiga.net, id=285b11a6-686d-de11-9d8e-0080485416c1@estaf 2009/07/12 19:45:09.887 0:11.581 Pool:0xb268eb70 SIP Set state Terminated_Aborted for transaction 3 SUBSCRIBE 2009/07/12 19:45:09.887 0:11.581 SIP Sending PDU (788 bytes) to: rem=udp$86.64.162.35:5060,local=udp$172.16.105.1:5060,if=172.16.105.1%vmnet8 PUBLISH sip:estaf@ekiga.net SIP/2.0 CSeq: 5 PUBLISH Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bKa00b20a6-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 From: ;tag=bee31fa6-686d-de11-9d8e-0080485416c1 Call-ID: 30191ea6-686d-de11-9d8e-0080485416c1@estaf To: Contact: Expires: 500 Event: presence Content-Type: application/pidf+xml Content-Length: 321 Max-Forwards: 70 online - I'm online using Ekiga open sip:estaf@ekiga.net 2009/07/12 19:45:09.887 0:11.581 Pool:0xb268eb70 SIP Not retrying SUBSCRIBE due to error response 487 Request Terminated 2009/07/12 19:45:09.887 0:11.581 Pool:0xb268eb70 SIP Changing SUBSCRIBE handler from Unsubscribed to Unsubscribed, target=sip:estaf@ekiga.net, id=285b11a6-686d-de11-9d8e-0080485416c1@estaf 2009/07/12 19:45:09.887 0:11.581 OpalUDP Setting interface to 172.16.105.1%vmnet8 2009/07/12 19:45:09.887 0:11.581 Pool:0xb268eb70 SIP Set state Terminated_Aborted for transaction 4 SUBSCRIBE 2009/07/12 19:45:09.887 0:11.581 SIP Transaction timers set: retry=0.500, completion=6.000 2009/07/12 19:45:09.887 0:11.581 OpalUDP Writing to interface 3 - "84.51.100.146%ppp0" 2009/07/12 19:45:09.887 0:11.581 Pool:0xb268eb70 SIP Not retrying SUBSCRIBE due to error response 487 Request Terminated 2009/07/12 19:45:09.887 0:11.581 Pool:0xb268eb70 SIP Changing SUBSCRIBE handler from Unsubscribed to Unsubscribed, target=sip:estaf@ekiga.net, id=285b11a6-686d-de11-9d8e-0080485416c1@estaf 2009/07/12 19:45:09.887 0:11.581 Pool:0xb268eb70 SIP Finished processing PDU 2009/07/12 19:45:09.887 0:11.581 SIP Expiry time for PUBLISH set to 500 seconds. 2009/07/12 19:45:09.887 0:11.581 SIP Transaction created. 2009/07/12 19:45:09.890 0:11.584 DNS SRV Lookup ekiga.net service _sip._udp 2009/07/12 19:45:09.892 0:11.586 SIP No SRV record found. 2009/07/12 19:45:09.892 0:11.586 SIP Transaction remote address is udp$ekiga.net:5060 2009/07/12 19:45:09.892 0:11.586 SIP Sending PDU (788 bytes) to: rem=udp$86.64.162.35:5060,local=udp$84.51.100.146:5060,if=84.51.100.146%ppp0 PUBLISH sip:estaf@ekiga.net SIP/2.0 CSeq: 6 PUBLISH Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bK96e220a6-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 From: ;tag=24af20a6-686d-de11-9d8e-0080485416c1 Call-ID: 30191ea6-686d-de11-9d8e-0080485416c1@estaf To: Contact: Expires: 500 Event: presence Content-Type: application/pidf+xml Content-Length: 321 Max-Forwards: 70 online - I'm online using Ekiga open sip:estaf@ekiga.net 2009/07/12 19:45:09.893 0:11.587 OpalUDP Setting interface to 84.51.100.146%ppp0 2009/07/12 19:45:09.893 0:11.587 SIP Transaction timers set: retry=0.500, completion=6.000 2009/07/12 19:45:09.954 0:11.648 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.264 2009/07/12 19:45:09.955 0:11.649 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: theora 2009/07/12 19:45:09.955 0:11.649 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.261 2009/07/12 19:45:09.956 0:11.650 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.263 2009/07/12 19:45:09.956 0:11.650 Housekeeper:0xb4759b70 OpalPlugin to_customised_options changed option "MaxBR" from "0" to "40960" 2009/07/12 19:45:09.957 0:11.651 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.263P 2009/07/12 19:45:09.958 0:11.652 Housekeeper:0xb4759b70 SIP PDU is too large (1543 bytes) trying compact form. 2009/07/12 19:45:09.958 0:11.652 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.264 2009/07/12 19:45:09.959 0:11.653 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: theora 2009/07/12 19:45:09.959 0:11.653 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.261 2009/07/12 19:45:09.960 0:11.654 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.263 2009/07/12 19:45:09.961 0:11.655 Housekeeper:0xb4759b70 OpalPlugin to_customised_options changed option "MaxBR" from "0" to "40960" 2009/07/12 19:45:09.961 0:11.655 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.263P 2009/07/12 19:45:09.962 0:11.656 Housekeeper:0xb4759b70 SIP PDU is likely too large (1501 bytes) for UDP datagram. 2009/07/12 19:45:09.962 0:11.656 Housekeeper:0xb4759b70 SIP Sending PDU (1501 bytes) to: rem=udp$86.64.162.35:5060,local=udp$84.51.100.146:5060,if=84.51.100.146%ppp0 INVITE sip:500@ekiga.net SIP/2.0 Date: Sun, 12 Jul 2009 15:45:04 GMT CSeq: 1 INVITE v: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bK38499ea2-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 f: "Dmitry Nesterov" ;tag=6a192ea2-686d-de11-9d8e-0080485416c1 i: ce2d2ea2-686d-de11-9d8e-0080485416c1@estaf t: m: Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING c: application/sdp l: 985 Max-Forwards: 70 v=0 o=- 1247413504 1247413504 IN IP4 84.51.100.146 s=Opal SIP Session c=IN IP4 84.51.100.146 t=0 0 m=audio 5062 RTP/AVP 125 111 0 8 9 101 126 c=IN IP4 84.51.100.146 a=sendrecv a=rtpmap:125 Speex/16000/1 a=fmtp:125 sr=16000,mode=any a=rtpmap:111 iLBC/8000/1 a=fmtp:111 mode=20 a=rtpmap:0 PCMU/8000/1 a=rtpmap:8 PCMA/8000/1 a=rtpmap:9 G722/8000/1 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16,32,36 a=rtpmap:126 NSE/8000 a=fmtp:126 192-193 m=video 5064 RTP/AVP 103 124 31 34 102 99 c=IN IP4 84.51.100.146 b=AS:4096 b=TIAS:4096000 a=sendrecv a=rtpmap:103 h264/90000 a=fmtp:103 packetization-mode=1;profile-level-id=42C01E a=rtpmap:124 theora/90000 a=fmtp:124 height=576;width=704 a=rtpmap:31 h261/90000 a=fmtp:31 CIF=1;QCIF=1 a=rtpmap:34 h263/90000 a=fmtp:34 F=1;CIF=1;CIF4=1;QCIF=1 a=rtpmap:102 h263-1998/90000 a=fmtp:102 D=1;F=1;I=1;J=1;CIF=1;CIF4=1;QCIF=1;CUSTOM=320,240,1;CUSTOM=640,480,1 a=rtpmap:99 MP4V-ES/90000 a=fmtp:99 profile-level-id=5 2009/07/12 19:45:09.963 0:11.657 Housekeeper:0xb4759b70 OpalUDP Setting interface to 84.51.100.146%ppp0 2009/07/12 19:45:09.963 0:11.657 Housekeeper:0xb4759b70 SIP Transaction 1 INVITE timeout, making retry 1, timeout 1.000 2009/07/12 19:45:09.965 0:11.659 Opal Liste...0xb45ffb70 OpalUDP Binding to interface: 84.51.100.146:5060 2009/07/12 19:45:09.965 0:11.659 Opal Liste...0xb45ffb70 SIP Waiting for PDU on udp$86.64.162.35:5060 2009/07/12 19:45:09.966 0:11.660 Opal Liste...0xb45ffb70 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$84.51.100.146:5060,if=84.51.100.146%ppp0 SIP/2.0 200 OK CSeq: 6 PUBLISH Via: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bK96e220a6-686d-de11-9d8e-0080485416c1;rport=5060 Server: Kamailio (1.4.0-notls (i386/linux)) From: ;tag=24af20a6-686d-de11-9d8e-0080485416c1 Call-ID: 30191ea6-686d-de11-9d8e-0080485416c1@estaf To: ;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.5d25 Expires: 495 Content-Length: 0 SIP-ETag: a.1247394429.18587.83325.0 2009/07/12 19:45:09.966 0:11.660 Opal Liste...0xb45ffb70 Opal Transport clean up on termination 2009/07/12 19:45:09.966 0:11.660 Opal Liste...0xb45ffb70 Opal Transport Close 2009/07/12 19:45:09.966 0:11.660 Opal Liste...0xb45ffb70 Opal Deleted transport udp$86.64.162.35:5060 2009/07/12 19:45:09.967 0:11.661 Pool:0xb268eb70 SIP Started processing PDU 2009/07/12 19:45:09.967 0:11.661 Pool:0xb268eb70 SIP Handling PDU "6 PUBLISH <200>" for transaction=z9hG4bK96e220a6-686d-de11-9d8e-0080485416c1 2009/07/12 19:45:09.967 0:11.661 Pool:0xb268eb70 SIP Transaction 6 PUBLISH completed. 2009/07/12 19:45:09.967 0:11.661 Pool:0xb268eb70 SIP Expiry time for PUBLISH set to 495 seconds. 2009/07/12 19:45:09.967 0:11.661 Pool:0xb268eb70 SIP Product Info: name="Kamailio", version="", vendor="", comments="(1.4.0-notls (i386/linux))" 2009/07/12 19:45:09.967 0:11.661 Pool:0xb268eb70 SIP Changing PUBLISH handler from Subscribing to Subscribed, target=sip:estaf@ekiga.net, id=30191ea6-686d-de11-9d8e-0080485416c1@estaf 2009/07/12 19:45:09.967 0:11.661 Pool:0xb268eb70 SIP Set state Terminated_Aborted for transaction 3 PUBLISH 2009/07/12 19:45:09.967 0:11.661 Pool:0xb268eb70 SIP Set state Terminated_Aborted for transaction 4 PUBLISH 2009/07/12 19:45:09.967 0:11.661 Pool:0xb268eb70 SIP Set state Terminated_Aborted for transaction 5 PUBLISH 2009/07/12 19:45:09.967 0:11.661 Pool:0xb268eb70 OpalUDP Setting interface to 84.51.100.146%ppp0 2009/07/12 19:45:09.967 0:11.661 Pool:0xb268eb70 SIP Finished processing PDU 2009/07/12 19:45:10.454 0:12.148 Opal Garbage:0xb47dbb70 SIP Transaction 1 SUBSCRIBE destroyed. 2009/07/12 19:45:10.454 0:12.148 Opal Garbage:0xb47dbb70 SIP Transaction 4 PUBLISH destroyed. 2009/07/12 19:45:10.455 0:12.149 Opal Garbage:0xb47dbb70 SIP Transaction 4 SUBSCRIBE destroyed. 2009/07/12 19:45:10.455 0:12.149 Opal Garbage:0xb47dbb70 SIP Transaction 3 SUBSCRIBE destroyed. 2009/07/12 19:45:10.455 0:12.149 Opal Garbage:0xb47dbb70 SIP Transaction 1 SUBSCRIBE destroyed. 2009/07/12 19:45:10.455 0:12.149 Opal Garbage:0xb47dbb70 SIP Transaction 2 SUBSCRIBE destroyed. 2009/07/12 19:45:10.455 0:12.149 Opal Garbage:0xb47dbb70 SIP Transaction 1 SUBSCRIBE destroyed. 2009/07/12 19:45:10.455 0:12.149 Opal Garbage:0xb47dbb70 SIP Transaction 3 SUBSCRIBE destroyed. 2009/07/12 19:45:10.455 0:12.149 Opal Garbage:0xb47dbb70 SIP Transaction 3 SUBSCRIBE destroyed. 2009/07/12 19:45:10.455 0:12.149 Opal Garbage:0xb47dbb70 SIP Transaction 2 SUBSCRIBE destroyed. 2009/07/12 19:45:10.455 0:12.149 Opal Garbage:0xb47dbb70 SIP Transaction 4 SUBSCRIBE destroyed. 2009/07/12 19:45:10.455 0:12.149 Opal Garbage:0xb47dbb70 SIP Transaction 2 SUBSCRIBE destroyed. 2009/07/12 19:45:10.456 0:12.150 Opal Garbage:0xb47dbb70 SIP Transaction 2 SUBSCRIBE destroyed. 2009/07/12 19:45:10.456 0:12.150 Opal Garbage:0xb47dbb70 SIP Transaction 3 SUBSCRIBE destroyed. 2009/07/12 19:45:10.456 0:12.150 Opal Garbage:0xb47dbb70 SIP Transaction 1 REGISTER destroyed. 2009/07/12 19:45:10.456 0:12.150 Opal Garbage:0xb47dbb70 SIP Transaction 4 SUBSCRIBE destroyed. 2009/07/12 19:45:10.456 0:12.150 Opal Garbage:0xb47dbb70 SIP Transaction 1 SUBSCRIBE destroyed. 2009/07/12 19:45:10.456 0:12.150 Opal Garbage:0xb47dbb70 SIP Transaction 4 SUBSCRIBE destroyed. 2009/07/12 19:45:10.456 0:12.150 Opal Garbage:0xb47dbb70 SIP Transaction 3 SUBSCRIBE destroyed. 2009/07/12 19:45:10.456 0:12.150 Opal Garbage:0xb47dbb70 SIP Transaction 1 SUBSCRIBE destroyed. 2009/07/12 19:45:10.456 0:12.150 Opal Garbage:0xb47dbb70 SIP Transaction 2 SUBSCRIBE destroyed. 2009/07/12 19:45:10.456 0:12.150 Opal Garbage:0xb47dbb70 SIP Transaction 4 SUBSCRIBE destroyed. 2009/07/12 19:45:10.457 0:12.151 Opal Garbage:0xb47dbb70 SIP Transaction 3 PUBLISH destroyed. 2009/07/12 19:45:10.457 0:12.151 Opal Garbage:0xb47dbb70 SIP Transaction 5 PUBLISH destroyed. 2009/07/12 19:45:10.457 0:12.151 Opal Garbage:0xb47dbb70 SIP Deleting SIPSubscribeHandler sip:500@ekiga.net 2009/07/12 19:45:10.457 0:12.151 Opal Garbage:0xb47dbb70 Opal Transport clean up on termination 2009/07/12 19:45:10.457 0:12.151 Opal Garbage:0xb47dbb70 Opal Transport Close 2009/07/12 19:45:10.457 0:12.151 Opal Garbage:0xb47dbb70 Opal Transport clean up on termination 2009/07/12 19:45:10.457 0:12.151 Opal Garbage:0xb47dbb70 Opal Transport Close 2009/07/12 19:45:10.457 0:12.151 Opal Garbage:0xb47dbb70 Opal Deleted transport udp$86.64.162.35:5060 2009/07/12 19:45:10.457 0:12.152 Opal Garbage:0xb47dbb70 SIP Deleted handler. 2009/07/12 19:45:10.458 0:12.152 Opal Garbage:0xb47dbb70 SIP Deleting SIPSubscribeHandler sip:500@ekiga.net 2009/07/12 19:45:10.458 0:12.152 Opal Garbage:0xb47dbb70 Opal Transport clean up on termination 2009/07/12 19:45:10.458 0:12.152 Opal Garbage:0xb47dbb70 Opal Transport Close 2009/07/12 19:45:10.458 0:12.152 Opal Garbage:0xb47dbb70 Opal Transport clean up on termination 2009/07/12 19:45:10.458 0:12.152 Opal Garbage:0xb47dbb70 Opal Transport Close 2009/07/12 19:45:10.458 0:12.152 Opal Garbage:0xb47dbb70 Opal Deleted transport udp$86.64.162.35:5060 2009/07/12 19:45:10.458 0:12.152 Opal Garbage:0xb47dbb70 SIP Deleted handler. 2009/07/12 19:45:10.458 0:12.152 Opal Garbage:0xb47dbb70 SIP Deleting SIPSubscribeHandler sip:501@ekiga.net 2009/07/12 19:45:10.458 0:12.152 Opal Garbage:0xb47dbb70 Opal Transport clean up on termination 2009/07/12 19:45:10.458 0:12.152 Opal Garbage:0xb47dbb70 Opal Transport Close 2009/07/12 19:45:10.458 0:12.152 Opal Garbage:0xb47dbb70 Opal Transport clean up on termination 2009/07/12 19:45:10.458 0:12.152 Opal Garbage:0xb47dbb70 Opal Transport Close 2009/07/12 19:45:10.458 0:12.152 Opal Garbage:0xb47dbb70 Opal Deleted transport udp$86.64.162.35:5060 2009/07/12 19:45:10.459 0:12.153 Opal Garbage:0xb47dbb70 SIP Deleted handler. 2009/07/12 19:45:10.459 0:12.153 Opal Garbage:0xb47dbb70 SIP Deleting SIPSubscribeHandler sip:501@ekiga.net 2009/07/12 19:45:10.459 0:12.153 Opal Garbage:0xb47dbb70 Opal Transport clean up on termination 2009/07/12 19:45:10.459 0:12.153 Opal Garbage:0xb47dbb70 Opal Transport Close 2009/07/12 19:45:10.459 0:12.153 Opal Garbage:0xb47dbb70 Opal Transport clean up on termination 2009/07/12 19:45:10.459 0:12.153 Opal Garbage:0xb47dbb70 Opal Transport Close 2009/07/12 19:45:10.459 0:12.153 Opal Garbage:0xb47dbb70 Opal Deleted transport udp$86.64.162.35:5060 2009/07/12 19:45:10.459 0:12.153 Opal Garbage:0xb47dbb70 SIP Deleted handler. 2009/07/12 19:45:10.459 0:12.153 Opal Garbage:0xb47dbb70 SIP Deleting SIPSubscribeHandler sip:estaf@ekiga.net 2009/07/12 19:45:10.459 0:12.153 Opal Garbage:0xb47dbb70 Opal Transport clean up on termination 2009/07/12 19:45:10.459 0:12.153 Opal Garbage:0xb47dbb70 Opal Transport Close 2009/07/12 19:45:10.459 0:12.153 Opal Garbage:0xb47dbb70 Opal Transport clean up on termination 2009/07/12 19:45:10.460 0:12.154 Opal Garbage:0xb47dbb70 Opal Transport Close 2009/07/12 19:45:10.460 0:12.154 Opal Garbage:0xb47dbb70 Opal Deleted transport udp$86.64.162.35:5060 2009/07/12 19:45:10.460 0:12.154 Opal Garbage:0xb47dbb70 SIP Deleted handler. 2009/07/12 19:45:10.815 0:12.509 Housekeeper:0xb4759b70 SIP Set state Terminated_Success for transaction 2 REGISTER 2009/07/12 19:45:10.955 0:12.649 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.264 2009/07/12 19:45:10.955 0:12.649 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: theora 2009/07/12 19:45:10.956 0:12.650 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.261 2009/07/12 19:45:10.956 0:12.650 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.263 2009/07/12 19:45:10.957 0:12.651 Housekeeper:0xb4759b70 OpalPlugin to_customised_options changed option "MaxBR" from "0" to "40960" 2009/07/12 19:45:10.957 0:12.651 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.263P 2009/07/12 19:45:10.959 0:12.653 Housekeeper:0xb4759b70 SIP PDU is too large (1543 bytes) trying compact form. 2009/07/12 19:45:10.959 0:12.653 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.264 2009/07/12 19:45:10.960 0:12.654 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: theora 2009/07/12 19:45:10.960 0:12.654 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.261 2009/07/12 19:45:10.961 0:12.655 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.263 2009/07/12 19:45:10.961 0:12.656 Housekeeper:0xb4759b70 OpalPlugin to_customised_options changed option "MaxBR" from "0" to "40960" 2009/07/12 19:45:10.962 0:12.656 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.263P 2009/07/12 19:45:10.963 0:12.657 Housekeeper:0xb4759b70 SIP PDU is likely too large (1501 bytes) for UDP datagram. 2009/07/12 19:45:10.963 0:12.657 Housekeeper:0xb4759b70 SIP Sending PDU (1501 bytes) to: rem=udp$86.64.162.35:5060,local=udp$84.51.100.146:5060,if=84.51.100.146%ppp0 INVITE sip:500@ekiga.net SIP/2.0 Date: Sun, 12 Jul 2009 15:45:04 GMT CSeq: 1 INVITE v: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bK38499ea2-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 f: "Dmitry Nesterov" ;tag=6a192ea2-686d-de11-9d8e-0080485416c1 i: ce2d2ea2-686d-de11-9d8e-0080485416c1@estaf t: m: Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING c: application/sdp l: 985 Max-Forwards: 70 v=0 o=- 1247413504 1247413504 IN IP4 84.51.100.146 s=Opal SIP Session c=IN IP4 84.51.100.146 t=0 0 m=audio 5062 RTP/AVP 125 111 0 8 9 101 126 c=IN IP4 84.51.100.146 a=sendrecv a=rtpmap:125 Speex/16000/1 a=fmtp:125 sr=16000,mode=any a=rtpmap:111 iLBC/8000/1 a=fmtp:111 mode=20 a=rtpmap:0 PCMU/8000/1 a=rtpmap:8 PCMA/8000/1 a=rtpmap:9 G722/8000/1 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16,32,36 a=rtpmap:126 NSE/8000 a=fmtp:126 192-193 m=video 5064 RTP/AVP 103 124 31 34 102 99 c=IN IP4 84.51.100.146 b=AS:4096 b=TIAS:4096000 a=sendrecv a=rtpmap:103 h264/90000 a=fmtp:103 packetization-mode=1;profile-level-id=42C01E a=rtpmap:124 theora/90000 a=fmtp:124 height=576;width=704 a=rtpmap:31 h261/90000 a=fmtp:31 CIF=1;QCIF=1 a=rtpmap:34 h263/90000 a=fmtp:34 F=1;CIF=1;CIF4=1;QCIF=1 a=rtpmap:102 h263-1998/90000 a=fmtp:102 D=1;F=1;I=1;J=1;CIF=1;CIF4=1;QCIF=1;CUSTOM=320,240,1;CUSTOM=640,480,1 a=rtpmap:99 MP4V-ES/90000 a=fmtp:99 profile-level-id=5 2009/07/12 19:45:10.964 0:12.658 Housekeeper:0xb4759b70 OpalUDP Setting interface to 84.51.100.146%ppp0 2009/07/12 19:45:10.964 0:12.658 Housekeeper:0xb4759b70 SIP Transaction 1 INVITE timeout, making retry 2, timeout 2.000 2009/07/12 19:45:10.977 0:12.671 Housekeeper:0xb4759b70 SIP Set state Terminated_Success for transaction 6 PUBLISH 2009/07/12 19:45:11.460 0:13.154 Opal Garbage:0xb47dbb70 SIP Transaction 6 PUBLISH destroyed. 2009/07/12 19:45:11.460 0:13.154 Opal Garbage:0xb47dbb70 SIP Transaction 2 REGISTER destroyed. 2009/07/12 19:45:12.955 0:14.649 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.264 2009/07/12 19:45:12.956 0:14.650 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: theora 2009/07/12 19:45:12.956 0:14.650 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.261 2009/07/12 19:45:12.957 0:14.651 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.263 2009/07/12 19:45:12.958 0:14.652 Housekeeper:0xb4759b70 OpalPlugin to_customised_options changed option "MaxBR" from "0" to "40960" 2009/07/12 19:45:12.958 0:14.652 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.263P 2009/07/12 19:45:12.959 0:14.653 Housekeeper:0xb4759b70 SIP PDU is too large (1543 bytes) trying compact form. 2009/07/12 19:45:12.960 0:14.654 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.264 2009/07/12 19:45:12.960 0:14.654 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: theora 2009/07/12 19:45:12.961 0:14.655 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.261 2009/07/12 19:45:12.961 0:14.655 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.263 2009/07/12 19:45:12.962 0:14.656 Housekeeper:0xb4759b70 OpalPlugin to_customised_options changed option "MaxBR" from "0" to "40960" 2009/07/12 19:45:12.962 0:14.656 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.263P 2009/07/12 19:45:12.964 0:14.658 Housekeeper:0xb4759b70 SIP PDU is likely too large (1501 bytes) for UDP datagram. 2009/07/12 19:45:12.964 0:14.658 Housekeeper:0xb4759b70 SIP Sending PDU (1501 bytes) to: rem=udp$86.64.162.35:5060,local=udp$84.51.100.146:5060,if=84.51.100.146%ppp0 INVITE sip:500@ekiga.net SIP/2.0 Date: Sun, 12 Jul 2009 15:45:04 GMT CSeq: 1 INVITE v: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bK38499ea2-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 f: "Dmitry Nesterov" ;tag=6a192ea2-686d-de11-9d8e-0080485416c1 i: ce2d2ea2-686d-de11-9d8e-0080485416c1@estaf t: m: Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING c: application/sdp l: 985 Max-Forwards: 70 v=0 o=- 1247413504 1247413504 IN IP4 84.51.100.146 s=Opal SIP Session c=IN IP4 84.51.100.146 t=0 0 m=audio 5062 RTP/AVP 125 111 0 8 9 101 126 c=IN IP4 84.51.100.146 a=sendrecv a=rtpmap:125 Speex/16000/1 a=fmtp:125 sr=16000,mode=any a=rtpmap:111 iLBC/8000/1 a=fmtp:111 mode=20 a=rtpmap:0 PCMU/8000/1 a=rtpmap:8 PCMA/8000/1 a=rtpmap:9 G722/8000/1 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16,32,36 a=rtpmap:126 NSE/8000 a=fmtp:126 192-193 m=video 5064 RTP/AVP 103 124 31 34 102 99 c=IN IP4 84.51.100.146 b=AS:4096 b=TIAS:4096000 a=sendrecv a=rtpmap:103 h264/90000 a=fmtp:103 packetization-mode=1;profile-level-id=42C01E a=rtpmap:124 theora/90000 a=fmtp:124 height=576;width=704 a=rtpmap:31 h261/90000 a=fmtp:31 CIF=1;QCIF=1 a=rtpmap:34 h263/90000 a=fmtp:34 F=1;CIF=1;CIF4=1;QCIF=1 a=rtpmap:102 h263-1998/90000 a=fmtp:102 D=1;F=1;I=1;J=1;CIF=1;CIF4=1;QCIF=1;CUSTOM=320,240,1;CUSTOM=640,480,1 a=rtpmap:99 MP4V-ES/90000 a=fmtp:99 profile-level-id=5 2009/07/12 19:45:12.964 0:14.658 Housekeeper:0xb4759b70 OpalUDP Setting interface to 84.51.100.146%ppp0 2009/07/12 19:45:12.964 0:14.658 Housekeeper:0xb4759b70 SIP Transaction 1 INVITE timeout, making retry 3, timeout 4.000 2009/07/12 19:45:16.956 0:18.650 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.264 2009/07/12 19:45:16.957 0:18.651 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: theora 2009/07/12 19:45:16.957 0:18.651 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.261 2009/07/12 19:45:16.958 0:18.652 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.263 2009/07/12 19:45:16.959 0:18.653 Housekeeper:0xb4759b70 OpalPlugin to_customised_options changed option "MaxBR" from "0" to "40960" 2009/07/12 19:45:16.959 0:18.653 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.263P 2009/07/12 19:45:16.960 0:18.654 Housekeeper:0xb4759b70 SIP PDU is too large (1543 bytes) trying compact form. 2009/07/12 19:45:16.961 0:18.655 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.264 2009/07/12 19:45:16.961 0:18.655 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: theora 2009/07/12 19:45:16.962 0:18.656 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.261 2009/07/12 19:45:16.962 0:18.656 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.263 2009/07/12 19:45:16.963 0:18.657 Housekeeper:0xb4759b70 OpalPlugin to_customised_options changed option "MaxBR" from "0" to "40960" 2009/07/12 19:45:16.963 0:18.657 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.263P 2009/07/12 19:45:16.964 0:18.658 Housekeeper:0xb4759b70 SIP PDU is likely too large (1501 bytes) for UDP datagram. 2009/07/12 19:45:16.965 0:18.659 Housekeeper:0xb4759b70 SIP Sending PDU (1501 bytes) to: rem=udp$86.64.162.35:5060,local=udp$84.51.100.146:5060,if=84.51.100.146%ppp0 INVITE sip:500@ekiga.net SIP/2.0 Date: Sun, 12 Jul 2009 15:45:04 GMT CSeq: 1 INVITE v: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bK38499ea2-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 f: "Dmitry Nesterov" ;tag=6a192ea2-686d-de11-9d8e-0080485416c1 i: ce2d2ea2-686d-de11-9d8e-0080485416c1@estaf t: m: Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING c: application/sdp l: 985 Max-Forwards: 70 v=0 o=- 1247413504 1247413504 IN IP4 84.51.100.146 s=Opal SIP Session c=IN IP4 84.51.100.146 t=0 0 m=audio 5062 RTP/AVP 125 111 0 8 9 101 126 c=IN IP4 84.51.100.146 a=sendrecv a=rtpmap:125 Speex/16000/1 a=fmtp:125 sr=16000,mode=any a=rtpmap:111 iLBC/8000/1 a=fmtp:111 mode=20 a=rtpmap:0 PCMU/8000/1 a=rtpmap:8 PCMA/8000/1 a=rtpmap:9 G722/8000/1 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16,32,36 a=rtpmap:126 NSE/8000 a=fmtp:126 192-193 m=video 5064 RTP/AVP 103 124 31 34 102 99 c=IN IP4 84.51.100.146 b=AS:4096 b=TIAS:4096000 a=sendrecv a=rtpmap:103 h264/90000 a=fmtp:103 packetization-mode=1;profile-level-id=42C01E a=rtpmap:124 theora/90000 a=fmtp:124 height=576;width=704 a=rtpmap:31 h261/90000 a=fmtp:31 CIF=1;QCIF=1 a=rtpmap:34 h263/90000 a=fmtp:34 F=1;CIF=1;CIF4=1;QCIF=1 a=rtpmap:102 h263-1998/90000 a=fmtp:102 D=1;F=1;I=1;J=1;CIF=1;CIF4=1;QCIF=1;CUSTOM=320,240,1;CUSTOM=640,480,1 a=rtpmap:99 MP4V-ES/90000 a=fmtp:99 profile-level-id=5 2009/07/12 19:45:16.965 0:18.659 Housekeeper:0xb4759b70 OpalUDP Setting interface to 84.51.100.146%ppp0 2009/07/12 19:45:16.965 0:18.659 Housekeeper:0xb4759b70 SIP Transaction 1 INVITE timeout, making retry 4, timeout 4.000 2009/07/12 19:45:20.957 0:22.651 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.264 2009/07/12 19:45:20.958 0:22.652 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: theora 2009/07/12 19:45:20.958 0:22.652 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.261 2009/07/12 19:45:20.959 0:22.653 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.263 2009/07/12 19:45:20.960 0:22.654 Housekeeper:0xb4759b70 OpalPlugin to_customised_options changed option "MaxBR" from "0" to "40960" 2009/07/12 19:45:20.960 0:22.654 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.263P 2009/07/12 19:45:20.961 0:22.655 Housekeeper:0xb4759b70 SIP PDU is too large (1543 bytes) trying compact form. 2009/07/12 19:45:20.962 0:22.656 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.264 2009/07/12 19:45:20.962 0:22.656 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: theora 2009/07/12 19:45:20.963 0:22.657 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.261 2009/07/12 19:45:20.963 0:22.657 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.263 2009/07/12 19:45:20.964 0:22.658 Housekeeper:0xb4759b70 OpalPlugin to_customised_options changed option "MaxBR" from "0" to "40960" 2009/07/12 19:45:20.964 0:22.658 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.263P 2009/07/12 19:45:20.966 0:22.660 Housekeeper:0xb4759b70 SIP PDU is likely too large (1501 bytes) for UDP datagram. 2009/07/12 19:45:20.966 0:22.660 Housekeeper:0xb4759b70 SIP Sending PDU (1501 bytes) to: rem=udp$86.64.162.35:5060,local=udp$84.51.100.146:5060,if=84.51.100.146%ppp0 INVITE sip:500@ekiga.net SIP/2.0 Date: Sun, 12 Jul 2009 15:45:04 GMT CSeq: 1 INVITE v: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bK38499ea2-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 f: "Dmitry Nesterov" ;tag=6a192ea2-686d-de11-9d8e-0080485416c1 i: ce2d2ea2-686d-de11-9d8e-0080485416c1@estaf t: m: Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING c: application/sdp l: 985 Max-Forwards: 70 v=0 o=- 1247413504 1247413504 IN IP4 84.51.100.146 s=Opal SIP Session c=IN IP4 84.51.100.146 t=0 0 m=audio 5062 RTP/AVP 125 111 0 8 9 101 126 c=IN IP4 84.51.100.146 a=sendrecv a=rtpmap:125 Speex/16000/1 a=fmtp:125 sr=16000,mode=any a=rtpmap:111 iLBC/8000/1 a=fmtp:111 mode=20 a=rtpmap:0 PCMU/8000/1 a=rtpmap:8 PCMA/8000/1 a=rtpmap:9 G722/8000/1 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16,32,36 a=rtpmap:126 NSE/8000 a=fmtp:126 192-193 m=video 5064 RTP/AVP 103 124 31 34 102 99 c=IN IP4 84.51.100.146 b=AS:4096 b=TIAS:4096000 a=sendrecv a=rtpmap:103 h264/90000 a=fmtp:103 packetization-mode=1;profile-level-id=42C01E a=rtpmap:124 theora/90000 a=fmtp:124 height=576;width=704 a=rtpmap:31 h261/90000 a=fmtp:31 CIF=1;QCIF=1 a=rtpmap:34 h263/90000 a=fmtp:34 F=1;CIF=1;CIF4=1;QCIF=1 a=rtpmap:102 h263-1998/90000 a=fmtp:102 D=1;F=1;I=1;J=1;CIF=1;CIF4=1;QCIF=1;CUSTOM=320,240,1;CUSTOM=640,480,1 a=rtpmap:99 MP4V-ES/90000 a=fmtp:99 profile-level-id=5 2009/07/12 19:45:20.966 0:22.660 Housekeeper:0xb4759b70 OpalUDP Setting interface to 84.51.100.146%ppp0 2009/07/12 19:45:20.966 0:22.660 Housekeeper:0xb4759b70 SIP Transaction 1 INVITE timeout, making retry 5, timeout 4.000 2009/07/12 19:45:24.967 0:26.661 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.264 2009/07/12 19:45:24.967 0:26.661 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: theora 2009/07/12 19:45:24.968 0:26.662 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.261 2009/07/12 19:45:24.968 0:26.662 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.263 2009/07/12 19:45:24.969 0:26.663 Housekeeper:0xb4759b70 OpalPlugin to_customised_options changed option "MaxBR" from "0" to "40960" 2009/07/12 19:45:24.969 0:26.663 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.263P 2009/07/12 19:45:24.971 0:26.665 Housekeeper:0xb4759b70 SIP PDU is too large (1543 bytes) trying compact form. 2009/07/12 19:45:24.971 0:26.665 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.264 2009/07/12 19:45:24.972 0:26.666 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: theora 2009/07/12 19:45:24.972 0:26.666 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.261 2009/07/12 19:45:24.973 0:26.667 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.263 2009/07/12 19:45:24.973 0:26.667 Housekeeper:0xb4759b70 OpalPlugin to_customised_options changed option "MaxBR" from "0" to "40960" 2009/07/12 19:45:24.974 0:26.668 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.263P 2009/07/12 19:45:24.975 0:26.669 Housekeeper:0xb4759b70 SIP PDU is likely too large (1501 bytes) for UDP datagram. 2009/07/12 19:45:24.975 0:26.669 Housekeeper:0xb4759b70 SIP Sending PDU (1501 bytes) to: rem=udp$86.64.162.35:5060,local=udp$84.51.100.146:5060,if=84.51.100.146%ppp0 INVITE sip:500@ekiga.net SIP/2.0 Date: Sun, 12 Jul 2009 15:45:04 GMT CSeq: 1 INVITE v: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bK38499ea2-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 f: "Dmitry Nesterov" ;tag=6a192ea2-686d-de11-9d8e-0080485416c1 i: ce2d2ea2-686d-de11-9d8e-0080485416c1@estaf t: m: Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING c: application/sdp l: 985 Max-Forwards: 70 v=0 o=- 1247413504 1247413504 IN IP4 84.51.100.146 s=Opal SIP Session c=IN IP4 84.51.100.146 t=0 0 m=audio 5062 RTP/AVP 125 111 0 8 9 101 126 c=IN IP4 84.51.100.146 a=sendrecv a=rtpmap:125 Speex/16000/1 a=fmtp:125 sr=16000,mode=any a=rtpmap:111 iLBC/8000/1 a=fmtp:111 mode=20 a=rtpmap:0 PCMU/8000/1 a=rtpmap:8 PCMA/8000/1 a=rtpmap:9 G722/8000/1 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16,32,36 a=rtpmap:126 NSE/8000 a=fmtp:126 192-193 m=video 5064 RTP/AVP 103 124 31 34 102 99 c=IN IP4 84.51.100.146 b=AS:4096 b=TIAS:4096000 a=sendrecv a=rtpmap:103 h264/90000 a=fmtp:103 packetization-mode=1;profile-level-id=42C01E a=rtpmap:124 theora/90000 a=fmtp:124 height=576;width=704 a=rtpmap:31 h261/90000 a=fmtp:31 CIF=1;QCIF=1 a=rtpmap:34 h263/90000 a=fmtp:34 F=1;CIF=1;CIF4=1;QCIF=1 a=rtpmap:102 h263-1998/90000 a=fmtp:102 D=1;F=1;I=1;J=1;CIF=1;CIF4=1;QCIF=1;CUSTOM=320,240,1;CUSTOM=640,480,1 a=rtpmap:99 MP4V-ES/90000 a=fmtp:99 profile-level-id=5 2009/07/12 19:45:24.976 0:26.670 Housekeeper:0xb4759b70 OpalUDP Setting interface to 84.51.100.146%ppp0 2009/07/12 19:45:24.976 0:26.670 Housekeeper:0xb4759b70 SIP Transaction 1 INVITE timeout, making retry 6, timeout 4.000 2009/07/12 19:45:28.968 0:30.662 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.264 2009/07/12 19:45:28.968 0:30.662 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: theora 2009/07/12 19:45:28.969 0:30.663 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.261 2009/07/12 19:45:28.970 0:30.664 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.263 2009/07/12 19:45:28.970 0:30.664 Housekeeper:0xb4759b70 OpalPlugin to_customised_options changed option "MaxBR" from "0" to "40960" 2009/07/12 19:45:28.970 0:30.664 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.263P 2009/07/12 19:45:28.972 0:30.666 Housekeeper:0xb4759b70 SIP PDU is too large (1543 bytes) trying compact form. 2009/07/12 19:45:28.972 0:30.666 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.264 2009/07/12 19:45:28.973 0:30.667 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: theora 2009/07/12 19:45:28.973 0:30.667 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.261 2009/07/12 19:45:28.974 0:30.668 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.263 2009/07/12 19:45:28.974 0:30.668 Housekeeper:0xb4759b70 OpalPlugin to_customised_options changed option "MaxBR" from "0" to "40960" 2009/07/12 19:45:28.975 0:30.669 Housekeeper:0xb4759b70 OpalPlugin to_customised_options: H.263P 2009/07/12 19:45:28.976 0:30.670 Housekeeper:0xb4759b70 SIP PDU is likely too large (1501 bytes) for UDP datagram. 2009/07/12 19:45:28.976 0:30.670 Housekeeper:0xb4759b70 SIP Sending PDU (1501 bytes) to: rem=udp$86.64.162.35:5060,local=udp$84.51.100.146:5060,if=84.51.100.146%ppp0 INVITE sip:500@ekiga.net SIP/2.0 Date: Sun, 12 Jul 2009 15:45:04 GMT CSeq: 1 INVITE v: SIP/2.0/UDP 84.51.100.146:5060;branch=z9hG4bK38499ea2-686d-de11-9d8e-0080485416c1;rport User-Agent: Ekiga/3.2.4 f: "Dmitry Nesterov" ;tag=6a192ea2-686d-de11-9d8e-0080485416c1 i: ce2d2ea2-686d-de11-9d8e-0080485416c1@estaf t: m: Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING c: application/sdp l: 985 Max-Forwards: 70 v=0 o=- 1247413504 1247413504 IN IP4 84.51.100.146 s=Opal SIP Session c=IN IP4 84.51.100.146 t=0 0 m=audio 5062 RTP/AVP 125 111 0 8 9 101 126 c=IN IP4 84.51.100.146 a=sendrecv a=rtpmap:125 Speex/16000/1 a=fmtp:125 sr=16000,mode=any a=rtpmap:111 iLBC/8000/1 a=fmtp:111 mode=20 a=rtpmap:0 PCMU/8000/1 a=rtpmap:8 PCMA/8000/1 a=rtpmap:9 G722/8000/1 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16,32,36 a=rtpmap:126 NSE/8000 a=fmtp:126 192-193 m=video 5064 RTP/AVP 103 124 31 34 102 99 c=IN IP4 84.51.100.146 b=AS:4096 b=TIAS:4096000 a=sendrecv a=rtpmap:103 h264/90000 a=fmtp:103 packetization-mode=1;profile-level-id=42C01E a=rtpmap:124 theora/90000 a=fmtp:124 height=576;width=704 a=rtpmap:31 h261/90000 a=fmtp:31 CIF=1;QCIF=1 a=rtpmap:34 h263/90000 a=fmtp:34 F=1;CIF=1;CIF4=1;QCIF=1 a=rtpmap:102 h263-1998/90000 a=fmtp:102 D=1;F=1;I=1;J=1;CIF=1;CIF4=1;QCIF=1;CUSTOM=320,240,1;CUSTOM=640,480,1 a=rtpmap:99 MP4V-ES/90000 a=fmtp:99 profile-level-id=5 2009/07/12 19:45:28.977 0:30.671 Housekeeper:0xb4759b70 OpalUDP Setting interface to 84.51.100.146%ppp0 2009/07/12 19:45:28.977 0:30.671 Housekeeper:0xb4759b70 SIP Transaction 1 INVITE timeout, making retry 7, timeout 4.000 2009/07/12 19:45:32.977 0:34.671 Housekeeper:0xb4759b70 SIP Set state Terminated_RetriesExceeded for transaction 1 INVITE 2009/07/12 19:45:32.977 0:34.671 Housekeeper:0xb4759b70 OpalCon SetPhase from SetUpPhase to ReleasingPhase for Call[k883572a61]-EP[6a192ea2-686d-de11-9d8e-0080485416c1] 2009/07/12 19:45:32.977 0:34.671 Housekeeper:0xb4759b70 OpalCon Releasing Call[k883572a61]-EP[6a192ea2-686d-de11-9d8e-0080485416c1] 2009/07/12 19:45:32.977 0:34.671 Housekeeper:0xb4759b70 OpalCon Call end reason for Call[k883572a61]-EP[6a192ea2-686d-de11-9d8e-0080485416c1] set to EndedByTemporaryFailure 2009/07/12 19:45:32.978 0:34.672 Housekeeper:0xb4759b70 PTLib Thread high water mark set: 12 2009/07/12 19:45:32.978 0:34.672 OnRelease:0xb264db70 SIP OnReleased: Call[k883572a61]-EP[6a192ea2-686d-de11-9d8e-0080485416c1], phase = ReleasingPhase 2009/07/12 19:45:32.978 0:34.672 OnRelease:0xb264db70 OpalCon SetPhase from ReleasingPhase to ReleasingPhase for Call[k883572a61]-EP[6a192ea2-686d-de11-9d8e-0080485416c1] 2009/07/12 19:45:32.978 0:34.672 OnRelease:0xb264db70 SIP Cancelling 0 transactions. 2009/07/12 19:45:32.978 0:34.672 OnRelease:0xb264db70 OpalCon Media streams closed. 2009/07/12 19:45:32.978 0:34.672 OnRelease:0xb264db70 OpalCon SetPhase from ReleasingPhase to ReleasedPhase for Call[k883572a61]-EP[6a192ea2-686d-de11-9d8e-0080485416c1] 2009/07/12 19:45:32.978 0:34.672 OnRelease:0xb264db70 OpalCon OnReleased Call[k883572a61]-EP[6a192ea2-686d-de11-9d8e-0080485416c1] 2009/07/12 19:45:32.978 0:34.672 OnRelease:0xb264db70 OpalEP OnReleased Call[k883572a61]-EP[6a192ea2-686d-de11-9d8e-0080485416c1] 2009/07/12 19:45:32.978 0:34.672 OnRelease:0xb264db70 OpalMan OnReleased Call[k883572a61]-EP[6a192ea2-686d-de11-9d8e-0080485416c1] 2009/07/12 19:45:32.979 0:34.673 OnRelease:0xb264db70 Call OnReleased Call[k883572a61]-EP[6a192ea2-686d-de11-9d8e-0080485416c1] 2009/07/12 19:45:32.979 0:34.673 OnRelease:0xb264db70 OpalCon SetPhase from SetUpPhase to ReleasingPhase for Call[k883572a61]-EP[c21de68d42] 2009/07/12 19:45:32.979 0:34.673 OnRelease:0xb264db70 OpalCon Releasing Call[k883572a61]-EP[c21de68d42] 2009/07/12 19:45:32.979 0:34.673 OnRelease:0xb264db70 OpalCon Call end reason for Call[k883572a61]-EP[c21de68d42] set to EndedByTemporaryFailure 2009/07/12 19:45:32.979 0:34.673 OnRelease:0xb264db70 OpalCon OnReleased Call[k883572a61]-EP[c21de68d42] 2009/07/12 19:45:32.979 0:34.673 OnRelease:0xb264db70 OpalEP OnReleased Call[k883572a61]-EP[c21de68d42] 2009/07/12 19:45:32.979 0:34.673 OnRelease:0xb264db70 OpalMan OnReleased Call[k883572a61]-EP[c21de68d42] 2009/07/12 19:45:32.979 0:34.673 OnRelease:0xb264db70 Call OnReleased Call[k883572a61]-EP[c21de68d42] 2009/07/12 19:45:32.979 0:34.673 OnRelease:0xb264db70 OpalCon Media streams closed. 2009/07/12 19:45:32.979 0:34.673 OnRelease:0xb264db70 OpalCon Media streams closed. 2009/07/12 19:45:32.979 0:34.673 OnRelease:0xb264db70 OpalCon OnRelease thread completed for Call[k883572a61]-EP[6a192ea2-686d-de11-9d8e-0080485416c1] 2009/07/12 19:45:32.979 0:34.673 OnRelease:0xb264db70 PTLib Destroyed thread 0x8e5d948 OnRelease:0xb264db70(id = b264db70) (ekiga:29568): Gdk-CRITICAL **: gdk_x11_atom_to_xatom_for_display: assertion `atom != GDK_NONE' failed 2009/07/12 19:45:33.075 0:34.769 AEScheduler Removing Event incoming_call_sound from queue 2009/07/12 19:45:33.075 0:34.769 AEScheduler Removing Event ring_tone_sound from queue 2009/07/12 19:45:33.075 0:34.770 OpalMan OnClearedCall Call[k883572a61] from "pc:iron" to "sip:500@ekiga.net" 2009/07/12 19:45:33.076 0:34.770 OPAL Attempting to close recording on call k883572a61 when not recording. 2009/07/12 19:45:33.466 0:35.160 Opal Garbage:0xb47dbb70 PCSS Deleted PC sound system connection. 2009/07/12 19:45:33.466 0:35.160 Opal Garbage:0xb47dbb70 OpalCon Connection Call[k883572a61]-EP[c21de68d42] destroyed. 2009/07/12 19:45:33.466 0:35.160 Opal Garbage:0xb47dbb70 RTP Closing session 1 2009/07/12 19:45:33.466 0:35.160 Opal Garbage:0xb47dbb70 RTP Deleting session 1 2009/07/12 19:45:33.466 0:35.160 Opal Garbage:0xb47dbb70 RTP_UDP Session 1, Shutting down read. 2009/07/12 19:45:33.466 0:35.160 Opal Garbage:0xb47dbb70 RTP_UDP Session 1, Shutting down write. 2009/07/12 19:45:33.466 0:35.160 Opal Garbage:0xb47dbb70 RTP Closing session 2 2009/07/12 19:45:33.466 0:35.160 Opal Garbage:0xb47dbb70 RTP Deleting session 2 2009/07/12 19:45:33.466 0:35.160 Opal Garbage:0xb47dbb70 RTP_UDP Session 2, Shutting down read. 2009/07/12 19:45:33.466 0:35.160 Opal Garbage:0xb47dbb70 RTP_UDP Session 2, Shutting down write. 2009/07/12 19:45:33.466 0:35.160 Opal Garbage:0xb47dbb70 SIP Transaction 1 INVITE destroyed. 2009/07/12 19:45:33.467 0:35.161 Opal Garbage:0xb47dbb70 Opal Transport clean up on termination 2009/07/12 19:45:33.467 0:35.161 Opal Garbage:0xb47dbb70 Opal Transport Close 2009/07/12 19:45:33.467 0:35.161 Opal Garbage:0xb47dbb70 Opal Transport clean up on termination 2009/07/12 19:45:33.467 0:35.161 Opal Garbage:0xb47dbb70 Opal Transport Close 2009/07/12 19:45:33.467 0:35.161 Opal Garbage:0xb47dbb70 Opal Deleted transport udp$86.64.162.35:5060 2009/07/12 19:45:33.467 0:35.161 Opal Garbage:0xb47dbb70 SIP Deleted connection. 2009/07/12 19:45:33.468 0:35.162 Opal Garbage:0xb47dbb70 OpalCon Connection Call[k883572a61]-EP[6a192ea2-686d-de11-9d8e-0080485416c1] destroyed. 2009/07/12 19:45:34.476 0:36.170 Call Call[k883572a61] destroyed. 2009/07/12 19:45:41.269 0:42.963 PTLib Destroyed thread 0x8a9e7fc AudioEventScheduler:0xb485db70(id = b485db70) 2009/07/12 19:45:41.270 0:42.964 PTLib Destroyed thread 0x8a9ebc8 PreviewManager(id = 0) 2009/07/12 19:45:41.284 0:42.978 PTLib Destroyed thread 0x8a9f5a0 GMVideoOutputManager:0xb481cb70(id = b481cb70) 2009/07/12 19:45:41.284 0:42.978 PTLib Destroyed thread 0x8a9e4b0 VideoPreviewManager:0xb489eb70(id = b489eb70) 2009/07/12 19:45:41.285 0:42.979 Network In...0xb479ab70 IfaceMon Finished interface monitor thread. 2009/07/12 19:45:41.296 0:42.990 WaitForTermination on 3027872624 finished 2009/07/12 19:45:41.296 0:42.990 PTLib Destroyed thread 0x8b22fb8 Network Interface Monitor:0xb479ab70(id = b479ab70) 2009/07/12 19:45:41.307 0:43.001 WaitForTermination on 3027606384 finished 2009/07/12 19:45:41.307 0:43.001 PTLib Destroyed thread 0x8b23148 Housekeeper:0xb4759b70(id = b4759b70) h264helper_unix.cxx(72) H264 IPC CP: Terminating [iron@estaf ~]$ exit exit Скрипт выполнен Вск 12 Июл 2009 19:45:45