2009/11/22 11:44:38.121 0:00.037 Version 3.2.6 by on Unix Linux (2.6.31-14-server-x86_64) with PTLib (v2.6.5) at 2009/11/22 11:44:38.121 2009/11/22 11:44:38.121 0:00.037 Ekiga git revision: unknown 2009/11/22 11:44:38.122 0:00.038 Ekiga registered on D-Bus: org.ekiga.Ekiga 2009/11/22 11:44:38.122 0:00.038 PWLib File handle high water mark set: 17 Thread unblock pipe 2009/11/22 11:44:38.123 0:00.038 PTLib Thread high water mark set: 2 2009/11/22 11:44:38.123 0:00.038 PWLib File handle high water mark set: 19 Thread unblock pipe 2009/11/22 11:44:38.123 0:00.038 PTLib Thread high water mark set: 3 2009/11/22 11:44:38.123 0:00.038 PWLib File handle high water mark set: 21 Thread unblock pipe 2009/11/22 11:44:38.123 0:00.039 PWLib File handle high water mark set: 23 Thread unblock pipe 2009/11/22 11:44:38.124 0:00.040 PTLib Thread high water mark set: 4 2009/11/22 11:44:38.124 0:00.040 HalManager_dbus Initialising HAL Manager 2009/11/22 11:44:38.125 0:00.040 HalManager_dbus Populating device list 2009/11/22 11:44:38.195 0:00.110 HalManager_dbus Populated device list with 14 devices 2009/11/22 11:44:38.195 0:00.111 HalManager_dbus Populating interface list 2009/11/22 11:44:38.196 0:00.111 HalManager_dbus Populating full interface list failed - The name org.freedesktop.NetworkManager was not provided by any .service files 2009/11/22 11:44:38.208 0:00.124 Detecting V4L2 devices 2009/11/22 11:44:38.208 0:00.124 Unable to detect v4l2 directory 2009/11/22 11:44:38.220 0:00.136 PWLib File handle high water mark set: 28 Thread unblock pipe 2009/11/22 11:44:38.221 0:00.136 PTLib Thread high water mark set: 5 2009/11/22 11:44:38.221 0:00.136 OpalMan Created manager. 2009/11/22 11:44:38.221 0:00.136 OpalMan Attached endpoint with prefix pc 2009/11/22 11:44:38.221 0:00.136 OpalEP Created endpoint: pc 2009/11/22 11:44:38.281 0:00.197 PCSS Created PC sound system endpoint. Players: Default HDA Intel HDA Intel (1) EKIGA *.wav /dev/dsp Recorders: Default HDA Intel HDA Intel (1) EKIGA *.wav /dev/dsp 2009/11/22 11:44:38.281 0:00.197 OPAL SetMediaFormatOrder() 2009/11/22 11:44:38.281 0:00.197 OPAL SetMediaFormatMask() 2009/11/22 11:44:38.282 0:00.197 OpalMan Attached endpoint with prefix sip 2009/11/22 11:44:38.282 0:00.198 OpalEP Created endpoint: sip 2009/11/22 11:44:38.282 0:00.198 PWLib File handle high water mark set: 29 PUDPSocket 2009/11/22 11:44:38.283 0:00.198 IfaceMon Initial interface list: 127.0.0.1 [00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:01] <00-00-00-00-00-00> (lo) 192.168.178.10 [fe:80:00:00:00:00:00:00:02:1b:fc:ff:fe:5b:fc:70] <00-1B-FC-5B-FC-70> (eth0) 172.16.104.3 [fe:80:00:00:00:00:00:00:ec:9a:82:ff:fe:66:ea:e6] (tap0) 2009/11/22 11:44:38.283 0:00.198 PWLib File handle high water mark set: 30 Thread unblock pipe 2009/11/22 11:44:38.283 0:00.198 PTLib Thread high water mark set: 6 2009/11/22 11:44:38.283 0:00.198 Network In...0x5ad76910 IfaceMon Started interface monitor thread. 2009/11/22 11:44:38.283 0:00.198 PWLib File handle high water mark set: 33 Thread unblock pipe 2009/11/22 11:44:38.283 0:00.198 Network In...0x5ad76910 PWLib File handle low water mark set: 31 PUDPSocket 2009/11/22 11:44:38.283 0:00.199 PTLib Thread high water mark set: 7 2009/11/22 11:44:38.283 0:00.199 OpalMan Attached endpoint with prefix sips 2009/11/22 11:44:38.283 0:00.199 SIP Created endpoint. 2009/11/22 11:44:38.283 0:00.199 MonSock Created socket bundle for all interfaces. 2009/11/22 11:44:38.284 0:00.199 PWLib File handle high water mark set: 34 PUDPSocket 2009/11/22 11:44:38.284 0:00.199 MonSock Created bundled UDP socket 192.168.178.10:5060 2009/11/22 11:44:38.284 0:00.199 PWLib File handle high water mark set: 35 PUDPSocket 2009/11/22 11:44:38.284 0:00.199 MonSock Created bundled UDP socket 172.16.104.3:5060 2009/11/22 11:44:38.284 0:00.199 PWLib File handle high water mark set: 37 Thread unblock pipe 2009/11/22 11:44:38.284 0:00.199 PTLib Thread high water mark set: 8 2009/11/22 11:44:38.284 0:00.199 OpalMan Added route "sip:.*=pc:*" 2009/11/22 11:44:38.284 0:00.200 OpalMan Added route "pc:.*=sip:" 2009/11/22 11:44:38.284 0:00.200 Opal Liste...0x5acf4910 Listen Started listening thread on udp$*:5060 2009/11/22 11:44:38.284 0:00.200 OpalMan Attached endpoint with prefix h323 2009/11/22 11:44:38.284 0:00.200 OpalEP Created endpoint: h323 2009/11/22 11:44:38.284 0:00.200 OpalMan Attached endpoint with prefix h323s 2009/11/22 11:44:38.284 0:00.200 H323 Created endpoint. 2009/11/22 11:44:38.285 0:00.200 PWLib File handle high water mark set: 38 PTCPSocket 2009/11/22 11:44:38.285 0:00.200 PWLib File handle high water mark set: 40 Thread unblock pipe 2009/11/22 11:44:38.285 0:00.200 PTLib Thread high water mark set: 9 2009/11/22 11:44:38.285 0:00.200 OpalMan Added route "h323:.*=pc:" 2009/11/22 11:44:38.285 0:00.200 OpalMan Added route "pc:.*=h323:" 2009/11/22 11:44:38.285 0:00.200 Opal Liste...0x5acb3910 Listen Started listening thread on tcp$*:1720 2009/11/22 11:44:38.285 0:00.200 Opal Liste...0x5acb3910 Listen Waiting on socket accept on tcp$*:1720 2009/11/22 11:44:38.291 0:00.206 MediaFormat Removing codecs SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,GSM-06.10,G.726-16k,G.726-24k,G.726-32k,G.726-40k,G.722-64k,theora,H.261,H.261-CIF,H.261-QCIF 2009/11/22 11:44:38.291 0:00.207 OPAL SetMediaFormatMask(PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz) 2009/11/22 11:44:38.291 0:00.207 OPAL SetMediaFormatOrder(SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,GSM-06.10,G.726-16k,G.726-24k,G.726-32k,G.726-40k,G.722-64k,theora,H.261,H.261-CIF,H.261-QCIF) 2009/11/22 11:44:38.296 0:00.212 MediaFormat Removing codecs SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,GSM-06.10,G.726-16k,G.726-24k,G.726-32k,G.726-40k,G.722-64k,theora,H.261,H.261-CIF,H.261-QCIF 2009/11/22 11:44:38.297 0:00.212 OPAL SetMediaFormatMask(PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz) 2009/11/22 11:44:38.297 0:00.212 OPAL SetMediaFormatOrder(SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,GSM-06.10,G.726-16k,G.726-24k,G.726-32k,G.726-40k,G.722-64k,theora,H.261,H.261-CIF,H.261-QCIF) 2009/11/22 11:44:38.307 0:00.222 PWLib File handle high water mark set: 42 Thread unblock pipe 2009/11/22 11:44:38.307 0:00.222 PTLib Thread high water mark set: 10 2009/11/22 11:44:38.307 0:00.223 StunDetector:0x5ac72910 PWLib File handle high water mark set: 43 PUDPSocket 2009/11/22 11:44:38.317 0:00.232 StunDetector:0x5ac72910 PWLib File handle high water mark set: 45 PUDPSocket 2009/11/22 11:44:38.345 0:00.261 PWLib File handle high water mark set: 57 PUDPSocket 2009/11/22 11:44:38.345 0:00.261 SIP Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:500@ekiga.net, id=ac736eb3-c1d5-de11-9049-001bfc5bfc70@bear 2009/11/22 11:44:38.345 0:00.261 DNS SRV Lookup ekiga.net service _sip._udp 2009/11/22 11:44:38.477 0:00.393 StunDetector:0x5ac72910 PWLib File handle high water mark set: 58 PUDPSocket 2009/11/22 11:44:39.388 0:01.303 SIP No SRV record found. 2009/11/22 11:44:39.394 0:01.309 OpalUDP Binding to interface: 0.0.0.0:5060 2009/11/22 11:44:39.394 0:01.310 PWLib File handle low water mark set: 57 PUDPSocket 2009/11/22 11:44:39.394 0:01.310 SIP Created transport udp$86.64.162.35:5060 2009/11/22 11:44:39.394 0:01.310 OpalUDP Started connect to 86.64.162.35:5060 2009/11/22 11:44:39.395 0:01.310 OpalUDP Writing to interface 0 - "192.168.178.10%eth0" 2009/11/22 11:44:39.395 0:01.310 OpalMan Listener interfaces: associated transport=None udp$192.168.178.10:5060,udp$172.16.104.3:5060 2009/11/22 11:44:39.555 0:01.471 PWLib File handle high water mark set: 59 PUDPSocket 2009/11/22 11:44:41.200 0:03.116 StunDetector:0x5ac72910 PWLib File handle low water mark set: 43 PUDPSocket 2009/11/22 11:44:41.360 0:03.275 StunDetector:0x5ac72910 OPAL STUN server "stun.ekiga.net" replies Restricted NAT, external IP 85.178.17.5 2009/11/22 11:44:42.276 0:04.192 PWLib File handle low water mark set: 41 PUDPSocket 2009/11/22 11:44:42.276 0:04.192 SIP Transaction created. 2009/11/22 11:44:42.278 0:04.194 DNS SRV Lookup ekiga.net service _sip._udp 2009/11/22 11:44:42.283 0:04.199 SIP No SRV record found. 2009/11/22 11:44:42.284 0:04.199 SIP Transaction remote address is udp$ekiga.net:5060 2009/11/22 11:44:42.284 0:04.199 SIP Sending PDU (544 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.178.10:5060,if=192.168.178.10%eth0 SUBSCRIBE sip:500@ekiga.net SIP/2.0 CSeq: 2 SUBSCRIBE Via: SIP/2.0/UDP 85.178.17.5:5060;branch=z9hG4bK066fc6b5-c1d5-de11-9049-001bfc5bfc70;rport User-Agent: Ekiga/3.2.6 From: "Ingo Busse" ;tag=2249c6b5-c1d5-de11-9049-001bfc5bfc70 Call-ID: ac736eb3-c1d5-de11-9049-001bfc5bfc70@bear 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/11/22 11:44:42.284 0:04.199 OpalUDP Setting interface to 192.168.178.10%eth0 2009/11/22 11:44:42.284 0:04.200 SIP Transaction timers set: retry=0.500, completion=6.000 2009/11/22 11:44:42.284 0:04.200 OpalUDP Writing to interface 1 - "172.16.104.3%tap0" 2009/11/22 11:44:42.284 0:04.200 OpalMan Listener interfaces: associated transport=None udp$192.168.178.10:5060,udp$172.16.104.3:5060 2009/11/22 11:44:42.285 0:04.201 SIP Transaction created. 2009/11/22 11:44:42.287 0:04.203 DNS SRV Lookup ekiga.net service _sip._udp 2009/11/22 11:44:42.296 0:04.212 SIP No SRV record found. 2009/11/22 11:44:42.296 0:04.212 SIP Transaction remote address is udp$ekiga.net:5060 2009/11/22 11:44:42.296 0:04.212 SIP Sending PDU (544 bytes) to: rem=udp$86.64.162.35:5060,local=udp$172.16.104.3:5060,if=172.16.104.3%tap0 SUBSCRIBE sip:500@ekiga.net SIP/2.0 CSeq: 3 SUBSCRIBE Via: SIP/2.0/UDP 85.178.17.5:5060;branch=z9hG4bK16c7c7b5-c1d5-de11-9049-001bfc5bfc70;rport User-Agent: Ekiga/3.2.6 From: "Ingo Busse" ;tag=2249c6b5-c1d5-de11-9049-001bfc5bfc70 Call-ID: ac736eb3-c1d5-de11-9049-001bfc5bfc70@bear 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/11/22 11:44:42.297 0:04.212 OpalUDP Setting interface to 172.16.104.3%tap0 2009/11/22 11:44:42.297 0:04.212 SIP Transaction timers set: retry=0.500, completion=6.000 2009/11/22 11:44:42.297 0:04.213 SIP Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:500@ekiga.net, id=4870c9b5-c1d5-de11-9049-001bfc5bfc70@bear 2009/11/22 11:44:42.297 0:04.213 DNS SRV Lookup ekiga.net service _sip._udp 2009/11/22 11:44:42.302 0:04.218 SIP No SRV record found. 2009/11/22 11:44:42.303 0:04.218 OpalUDP Binding to interface: 0.0.0.0:5060 2009/11/22 11:44:42.303 0:04.218 SIP Created transport udp$86.64.162.35:5060 2009/11/22 11:44:42.303 0:04.218 OpalUDP Started connect to 86.64.162.35:5060 2009/11/22 11:44:42.303 0:04.219 OpalUDP Writing to interface 0 - "192.168.178.10%eth0" 2009/11/22 11:44:42.303 0:04.219 OpalMan Listener interfaces: associated transport=None udp$192.168.178.10:5060,udp$172.16.104.3:5060 2009/11/22 11:44:42.304 0:04.220 SIP Transaction created. 2009/11/22 11:44:42.306 0:04.221 DNS SRV Lookup ekiga.net service _sip._udp 2009/11/22 11:44:42.310 0:04.225 SIP No SRV record found. 2009/11/22 11:44:42.310 0:04.225 SIP Transaction remote address is udp$ekiga.net:5060 2009/11/22 11:44:42.310 0:04.225 SIP Sending PDU (556 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.178.10:5060,if=192.168.178.10%eth0 SUBSCRIBE sip:500@ekiga.net SIP/2.0 CSeq: 2 SUBSCRIBE Via: SIP/2.0/UDP 85.178.17.5:5060;branch=z9hG4bKbea8cab5-c1d5-de11-9049-001bfc5bfc70;rport User-Agent: Ekiga/3.2.6 From: "Ingo Busse" ;tag=0a85cab5-c1d5-de11-9049-001bfc5bfc70 Call-ID: 4870c9b5-c1d5-de11-9049-001bfc5bfc70@bear 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/11/22 11:44:42.310 0:04.226 OpalUDP Setting interface to 192.168.178.10%eth0 2009/11/22 11:44:42.310 0:04.226 SIP Transaction timers set: retry=0.500, completion=6.000 2009/11/22 11:44:42.311 0:04.226 OpalUDP Writing to interface 1 - "172.16.104.3%tap0" 2009/11/22 11:44:42.311 0:04.226 OpalMan Listener interfaces: associated transport=None udp$192.168.178.10:5060,udp$172.16.104.3:5060 2009/11/22 11:44:42.312 0:04.227 SIP Transaction created. 2009/11/22 11:44:42.313 0:04.229 DNS SRV Lookup ekiga.net service _sip._udp 2009/11/22 11:44:42.318 0:04.234 SIP No SRV record found. 2009/11/22 11:44:42.319 0:04.234 SIP Transaction remote address is udp$ekiga.net:5060 2009/11/22 11:44:42.319 0:04.234 SIP Sending PDU (556 bytes) to: rem=udp$86.64.162.35:5060,local=udp$172.16.104.3:5060,if=172.16.104.3%tap0 SUBSCRIBE sip:500@ekiga.net SIP/2.0 CSeq: 3 SUBSCRIBE Via: SIP/2.0/UDP 85.178.17.5:5060;branch=z9hG4bKd2cacbb5-c1d5-de11-9049-001bfc5bfc70;rport User-Agent: Ekiga/3.2.6 From: "Ingo Busse" ;tag=0a85cab5-c1d5-de11-9049-001bfc5bfc70 Call-ID: 4870c9b5-c1d5-de11-9049-001bfc5bfc70@bear 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/11/22 11:44:42.319 0:04.235 OpalUDP Setting interface to 172.16.104.3%tap0 2009/11/22 11:44:42.319 0:04.235 SIP Transaction timers set: retry=0.500, completion=6.000 2009/11/22 11:44:42.319 0:04.235 SIP Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:501@ekiga.net, id=dcd8ccb5-c1d5-de11-9049-001bfc5bfc70@bear 2009/11/22 11:44:42.319 0:04.235 DNS SRV Lookup ekiga.net service _sip._udp 2009/11/22 11:44:42.328 0:04.243 SIP No SRV record found. 2009/11/22 11:44:42.328 0:04.244 OpalUDP Binding to interface: 0.0.0.0:5060 2009/11/22 11:44:42.328 0:04.244 SIP Created transport udp$86.64.162.35:5060 2009/11/22 11:44:42.329 0:04.244 OpalUDP Started connect to 86.64.162.35:5060 2009/11/22 11:44:42.329 0:04.244 OpalUDP Writing to interface 0 - "192.168.178.10%eth0" 2009/11/22 11:44:42.329 0:04.244 OpalMan Listener interfaces: associated transport=None udp$192.168.178.10:5060,udp$172.16.104.3:5060 2009/11/22 11:44:42.330 0:04.245 SIP Transaction created. 2009/11/22 11:44:42.331 0:04.247 DNS SRV Lookup ekiga.net service _sip._udp 2009/11/22 11:44:42.338 0:04.254 SIP No SRV record found. 2009/11/22 11:44:42.338 0:04.254 SIP Transaction remote address is udp$ekiga.net:5060 2009/11/22 11:44:42.339 0:04.254 SIP Sending PDU (544 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.178.10:5060,if=192.168.178.10%eth0 SUBSCRIBE sip:501@ekiga.net SIP/2.0 CSeq: 2 SUBSCRIBE Via: SIP/2.0/UDP 85.178.17.5:5060;branch=z9hG4bK988eceb5-c1d5-de11-9049-001bfc5bfc70;rport User-Agent: Ekiga/3.2.6 From: "Ingo Busse" ;tag=806aceb5-c1d5-de11-9049-001bfc5bfc70 Call-ID: dcd8ccb5-c1d5-de11-9049-001bfc5bfc70@bear 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/11/22 11:44:42.339 0:04.254 OpalUDP Setting interface to 192.168.178.10%eth0 2009/11/22 11:44:42.339 0:04.254 SIP Transaction timers set: retry=0.500, completion=6.000 2009/11/22 11:44:42.339 0:04.255 OpalUDP Writing to interface 1 - "172.16.104.3%tap0" 2009/11/22 11:44:42.339 0:04.255 OpalMan Listener interfaces: associated transport=None udp$192.168.178.10:5060,udp$172.16.104.3:5060 2009/11/22 11:44:42.340 0:04.256 SIP Transaction created. 2009/11/22 11:44:42.342 0:04.257 DNS SRV Lookup ekiga.net service _sip._udp 2009/11/22 11:44:42.345 0:04.260 Opal Liste...0x5acf4910 OpalUDP Binding to interface: 192.168.178.10:5060 2009/11/22 11:44:42.345 0:04.260 Opal Liste...0x5acf4910 SIP Waiting for PDU on udp$86.64.162.35:5060 2009/11/22 11:44:42.345 0:04.261 Opal Liste...0x5acf4910 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$192.168.178.10:5060,if=192.168.178.10%eth0 SIP/2.0 489 Event Not Supported CSeq: 2 SUBSCRIBE Via: SIP/2.0/UDP 85.178.17.5:5060;branch=z9hG4bK066fc6b5-c1d5-de11-9049-001bfc5bfc70;rport=61001 Server: Kamailio (1.5.3-notls (i386/linux)) From: "Ingo Busse" ;tag=2249c6b5-c1d5-de11-9049-001bfc5bfc70 Call-ID: ac736eb3-c1d5-de11-9049-001bfc5bfc70@bear To: ;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.ad03 Content-Length: 0 2009/11/22 11:44:42.346 0:04.261 Opal Liste...0x5acf4910 SIP Queueing PDU "2 SUBSCRIBE <489>", transaction=z9hG4bK066fc6b5-c1d5-de11-9049-001bfc5bfc70, token=ac736eb3-c1d5-de11-9049-001bfc5bfc70@bear 2009/11/22 11:44:42.346 0:04.261 Opal Liste...0x5acf4910 Opal Transport clean up on termination 2009/11/22 11:44:42.346 0:04.261 Opal Liste...0x5acf4910 Opal Transport Close 2009/11/22 11:44:42.346 0:04.261 Pool:0x5ac72910 SIP Handling PDU "2 SUBSCRIBE <489>" for transaction=z9hG4bK066fc6b5-c1d5-de11-9049-001bfc5bfc70 2009/11/22 11:44:42.346 0:04.261 Pool:0x5ac72910 SIP SUBSCRIBE transaction id=z9hG4bK066fc6b5-c1d5-de11-9049-001bfc5bfc70 completed. 2009/11/22 11:44:42.346 0:04.262 Pool:0x5ac72910 SIP SUBSCRIBE transaction id=z9hG4bK16c7c7b5-c1d5-de11-9049-001bfc5bfc70 aborted. 2009/11/22 11:44:42.346 0:04.262 Pool:0x5ac72910 SIP Set state Terminated_Aborted for SUBSCRIBE transaction id=z9hG4bK16c7c7b5-c1d5-de11-9049-001bfc5bfc70 2009/11/22 11:44:42.346 0:04.262 Pool:0x5ac72910 OpalUDP Setting interface to 192.168.178.10%eth0 2009/11/22 11:44:42.346 0:04.261 Opal Liste...0x5acf4910 Opal Deleted transport udp$86.64.162.35:5060 2009/11/22 11:44:42.346 0:04.262 Pool:0x5ac72910 SIP Not retrying SUBSCRIBE due to error response 489 Bad Event 2009/11/22 11:44:42.346 0:04.262 Pool:0x5ac72910 SIP Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:500@ekiga.net, id=ac736eb3-c1d5-de11-9049-001bfc5bfc70@bear 2009/11/22 11:44:42.346 0:04.262 Pool:0x5ac72910 SIP Handled PDU "2 SUBSCRIBE <489>" 2009/11/22 11:44:42.350 0:04.265 SIP No SRV record found. 2009/11/22 11:44:42.350 0:04.265 SIP Transaction remote address is udp$ekiga.net:5060 2009/11/22 11:44:42.350 0:04.266 SIP Sending PDU (544 bytes) to: rem=udp$86.64.162.35:5060,local=udp$172.16.104.3:5060,if=172.16.104.3%tap0 SUBSCRIBE sip:501@ekiga.net SIP/2.0 CSeq: 3 SUBSCRIBE Via: SIP/2.0/UDP 85.178.17.5:5060;branch=z9hG4bKc627d0b5-c1d5-de11-9049-001bfc5bfc70;rport User-Agent: Ekiga/3.2.6 From: "Ingo Busse" ;tag=806aceb5-c1d5-de11-9049-001bfc5bfc70 Call-ID: dcd8ccb5-c1d5-de11-9049-001bfc5bfc70@bear 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/11/22 11:44:42.351 0:04.266 OpalUDP Setting interface to 172.16.104.3%tap0 2009/11/22 11:44:42.351 0:04.266 SIP Transaction timers set: retry=0.500, completion=6.000 2009/11/22 11:44:42.351 0:04.266 SIP Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:501@ekiga.net, id=d2a6d1b5-c1d5-de11-9049-001bfc5bfc70@bear 2009/11/22 11:44:42.351 0:04.266 DNS SRV Lookup ekiga.net service _sip._udp 2009/11/22 11:44:42.360 0:04.275 SIP No SRV record found. 2009/11/22 11:44:42.360 0:04.275 OpalUDP Binding to interface: 0.0.0.0:5060 2009/11/22 11:44:42.360 0:04.275 SIP Created transport udp$86.64.162.35:5060 2009/11/22 11:44:42.360 0:04.276 OpalUDP Started connect to 86.64.162.35:5060 2009/11/22 11:44:42.360 0:04.276 OpalUDP Writing to interface 0 - "192.168.178.10%eth0" 2009/11/22 11:44:42.361 0:04.276 OpalMan Listener interfaces: associated transport=None udp$192.168.178.10:5060,udp$172.16.104.3:5060 2009/11/22 11:44:42.371 0:04.286 Opal Liste...0x5acf4910 OpalUDP Binding to interface: 192.168.178.10:5060 2009/11/22 11:44:42.371 0:04.287 Opal Liste...0x5acf4910 SIP Waiting for PDU on udp$86.64.162.35:5060 2009/11/22 11:44:42.371 0:04.287 Opal Liste...0x5acf4910 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$192.168.178.10:5060,if=192.168.178.10%eth0 SIP/2.0 489 Event Not Supported CSeq: 2 SUBSCRIBE Via: SIP/2.0/UDP 85.178.17.5:5060;branch=z9hG4bKbea8cab5-c1d5-de11-9049-001bfc5bfc70;rport=61001 Server: Kamailio (1.5.3-notls (i386/linux)) From: "Ingo Busse" ;tag=0a85cab5-c1d5-de11-9049-001bfc5bfc70 Call-ID: 4870c9b5-c1d5-de11-9049-001bfc5bfc70@bear To: ;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.9aae Content-Length: 0 2009/11/22 11:44:42.372 0:04.287 Opal Liste...0x5acf4910 SIP Queueing PDU "2 SUBSCRIBE <489>", transaction=z9hG4bKbea8cab5-c1d5-de11-9049-001bfc5bfc70, token=4870c9b5-c1d5-de11-9049-001bfc5bfc70@bear 2009/11/22 11:44:42.372 0:04.287 Opal Liste...0x5acf4910 Opal Transport clean up on termination 2009/11/22 11:44:42.372 0:04.287 Opal Liste...0x5acf4910 Opal Transport Close 2009/11/22 11:44:42.372 0:04.287 Pool:0x5ac72910 SIP Handling PDU "2 SUBSCRIBE <489>" for transaction=z9hG4bKbea8cab5-c1d5-de11-9049-001bfc5bfc70 2009/11/22 11:44:42.372 0:04.287 Pool:0x5ac72910 SIP SUBSCRIBE transaction id=z9hG4bKbea8cab5-c1d5-de11-9049-001bfc5bfc70 completed. 2009/11/22 11:44:42.372 0:04.288 Pool:0x5ac72910 SIP SUBSCRIBE transaction id=z9hG4bKd2cacbb5-c1d5-de11-9049-001bfc5bfc70 aborted. 2009/11/22 11:44:42.372 0:04.288 Pool:0x5ac72910 SIP Set state Terminated_Aborted for SUBSCRIBE transaction id=z9hG4bKd2cacbb5-c1d5-de11-9049-001bfc5bfc70 2009/11/22 11:44:42.372 0:04.288 Pool:0x5ac72910 OpalUDP Setting interface to 192.168.178.10%eth0 2009/11/22 11:44:42.372 0:04.288 Pool:0x5ac72910 SIP Not retrying SUBSCRIBE due to error response 489 Bad Event 2009/11/22 11:44:42.372 0:04.288 Pool:0x5ac72910 SIP Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:500@ekiga.net, id=4870c9b5-c1d5-de11-9049-001bfc5bfc70@bear 2009/11/22 11:44:42.372 0:04.287 Opal Liste...0x5acf4910 Opal Deleted transport udp$86.64.162.35:5060 2009/11/22 11:44:42.372 0:04.288 Pool:0x5ac72910 SIP Handled PDU "2 SUBSCRIBE <489>" 2009/11/22 11:44:42.401 0:04.316 Opal Liste...0x5acf4910 OpalUDP Binding to interface: 192.168.178.10:5060 2009/11/22 11:44:42.401 0:04.316 Opal Liste...0x5acf4910 SIP Waiting for PDU on udp$86.64.162.35:5060 2009/11/22 11:44:42.401 0:04.317 Opal Liste...0x5acf4910 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$192.168.178.10:5060,if=192.168.178.10%eth0 SIP/2.0 489 Event Not Supported CSeq: 2 SUBSCRIBE Via: SIP/2.0/UDP 85.178.17.5:5060;branch=z9hG4bK988eceb5-c1d5-de11-9049-001bfc5bfc70;rport=61001 Server: Kamailio (1.5.3-notls (i386/linux)) From: "Ingo Busse" ;tag=806aceb5-c1d5-de11-9049-001bfc5bfc70 Call-ID: dcd8ccb5-c1d5-de11-9049-001bfc5bfc70@bear To: ;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.7a99 Content-Length: 0 2009/11/22 11:44:42.402 0:04.317 Opal Liste...0x5acf4910 SIP Queueing PDU "2 SUBSCRIBE <489>", transaction=z9hG4bK988eceb5-c1d5-de11-9049-001bfc5bfc70, token=dcd8ccb5-c1d5-de11-9049-001bfc5bfc70@bear 2009/11/22 11:44:42.402 0:04.317 Opal Liste...0x5acf4910 Opal Transport clean up on termination 2009/11/22 11:44:42.402 0:04.317 Opal Liste...0x5acf4910 Opal Transport Close 2009/11/22 11:44:42.402 0:04.317 Pool:0x5ac72910 SIP Handling PDU "2 SUBSCRIBE <489>" for transaction=z9hG4bK988eceb5-c1d5-de11-9049-001bfc5bfc70 2009/11/22 11:44:42.402 0:04.317 Pool:0x5ac72910 SIP SUBSCRIBE transaction id=z9hG4bK988eceb5-c1d5-de11-9049-001bfc5bfc70 completed. 2009/11/22 11:44:42.402 0:04.317 Pool:0x5ac72910 SIP SUBSCRIBE transaction id=z9hG4bKc627d0b5-c1d5-de11-9049-001bfc5bfc70 aborted. 2009/11/22 11:44:42.402 0:04.317 Pool:0x5ac72910 SIP Set state Terminated_Aborted for SUBSCRIBE transaction id=z9hG4bKc627d0b5-c1d5-de11-9049-001bfc5bfc70 2009/11/22 11:44:42.402 0:04.317 Pool:0x5ac72910 OpalUDP Setting interface to 192.168.178.10%eth0 2009/11/22 11:44:42.402 0:04.317 Pool:0x5ac72910 SIP Not retrying SUBSCRIBE due to error response 489 Bad Event 2009/11/22 11:44:42.402 0:04.317 Opal Liste...0x5acf4910 Opal Deleted transport udp$86.64.162.35:5060 2009/11/22 11:44:42.402 0:04.317 Pool:0x5ac72910 SIP Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:501@ekiga.net, id=dcd8ccb5-c1d5-de11-9049-001bfc5bfc70@bear 2009/11/22 11:44:42.402 0:04.318 Pool:0x5ac72910 SIP Handled PDU "2 SUBSCRIBE <489>" 2009/11/22 11:44:42.520 0:04.436 SIP Transaction created. 2009/11/22 11:44:42.522 0:04.438 DNS SRV Lookup ekiga.net service _sip._udp 2009/11/22 11:44:42.529 0:04.444 SIP No SRV record found. 2009/11/22 11:44:42.529 0:04.444 SIP Transaction remote address is udp$ekiga.net:5060 2009/11/22 11:44:42.529 0:04.444 SIP Sending PDU (556 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.178.10:5060,if=192.168.178.10%eth0 SUBSCRIBE sip:501@ekiga.net SIP/2.0 CSeq: 2 SUBSCRIBE Via: SIP/2.0/UDP 85.178.17.5:5060;branch=z9hG4bK48a8ebb5-c1d5-de11-9049-001bfc5bfc70;rport User-Agent: Ekiga/3.2.6 From: "Ingo Busse" ;tag=e682ebb5-c1d5-de11-9049-001bfc5bfc70 Call-ID: d2a6d1b5-c1d5-de11-9049-001bfc5bfc70@bear 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/11/22 11:44:42.529 0:04.445 OpalUDP Setting interface to 192.168.178.10%eth0 2009/11/22 11:44:42.529 0:04.445 SIP Transaction timers set: retry=0.500, completion=6.000 2009/11/22 11:44:42.529 0:04.445 OpalUDP Writing to interface 1 - "172.16.104.3%tap0" 2009/11/22 11:44:42.529 0:04.445 OpalMan Listener interfaces: associated transport=None udp$192.168.178.10:5060,udp$172.16.104.3:5060 2009/11/22 11:44:42.530 0:04.446 SIP Transaction created. 2009/11/22 11:44:42.532 0:04.448 DNS SRV Lookup ekiga.net service _sip._udp 2009/11/22 11:44:42.539 0:04.454 SIP No SRV record found. 2009/11/22 11:44:42.539 0:04.454 SIP Transaction remote address is udp$ekiga.net:5060 2009/11/22 11:44:42.539 0:04.454 SIP Sending PDU (556 bytes) to: rem=udp$86.64.162.35:5060,local=udp$172.16.104.3:5060,if=172.16.104.3%tap0 SUBSCRIBE sip:501@ekiga.net SIP/2.0 CSeq: 3 SUBSCRIBE Via: SIP/2.0/UDP 85.178.17.5:5060;branch=z9hG4bK8a2dedb5-c1d5-de11-9049-001bfc5bfc70;rport User-Agent: Ekiga/3.2.6 From: "Ingo Busse" ;tag=e682ebb5-c1d5-de11-9049-001bfc5bfc70 Call-ID: d2a6d1b5-c1d5-de11-9049-001bfc5bfc70@bear 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/11/22 11:44:42.539 0:04.455 OpalUDP Setting interface to 172.16.104.3%tap0 2009/11/22 11:44:42.539 0:04.455 SIP Transaction timers set: retry=0.500, completion=6.000 2009/11/22 11:44:42.540 0:04.455 VideoOutputCoreConfBridge Updating video view 2009/11/22 11:44:42.540 0:04.456 VideoOutputCoreConfBridge Updating zoom 2009/11/22 11:44:42.540 0:04.456 VideoOutputCoreConfBridge Updating Video Settings 2009/11/22 11:44:42.541 0:04.456 VideoOutputCoreConfBridge Updating Video Settings 2009/11/22 11:44:42.541 0:04.456 VideoOutputCoreConfBridge Updating Video Settings 2009/11/22 11:44:42.541 0:04.456 VideoOutputCoreConfBridge Updating Video Settings 2009/11/22 11:44:42.541 0:04.456 VidInputCoreConfBridge Updating preview size and fps 2009/11/22 11:44:42.541 0:04.457 VidInputCore Setting new preview config: 320x240/30 2009/11/22 11:44:42.542 0:04.458 VidInputCoreConfBridge Updating preview size and fps 2009/11/22 11:44:42.543 0:04.458 VidInputCore Setting new preview config: 320x240/30 2009/11/22 11:44:42.544 0:04.459 VidInputCoreConfBridge Updating device 2009/11/22 11:44:42.544 0:04.460 VidInputCore Setting device: Moving Logo (Moving Logo/Moving Logo) 2009/11/22 11:44:42.544 0:04.460 GMVideoInputManager_mlogo Setting Device Moving Logo 2009/11/22 11:44:42.544 0:04.460 VidInputCoreConfBridge Updating device 2009/11/22 11:44:42.544 0:04.460 VidInputCore Setting device: Moving Logo (Moving Logo/Moving Logo) 2009/11/22 11:44:42.544 0:04.460 GMVideoInputManager_mlogo Setting Device Moving Logo 2009/11/22 11:44:42.544 0:04.460 VidInputCoreConfBridge Updating device 2009/11/22 11:44:42.544 0:04.460 VidInputCore Setting device: Moving Logo (Moving Logo/Moving Logo) 2009/11/22 11:44:42.545 0:04.460 GMVideoInputManager_mlogo Setting Device Moving Logo 2009/11/22 11:44:42.545 0:04.460 VidInputCoreConfBridge Updating image 2009/11/22 11:44:42.545 0:04.460 VidInputCoreConfBridge Updating preview 2009/11/22 11:44:42.545 0:04.460 VidInputCore Stopping Preview 2009/11/22 11:44:42.545 0:04.461 AudioOutputCoreConfBridge Updating device 2009/11/22 11:44:42.545 0:04.461 AudioOutputCore Setting device[0]: Default (PTLIB/ALSA) 2009/11/22 11:44:42.545 0:04.461 GMAudioOutputManager_ptlib Setting Device[0] Default (PTLIB/ALSA) 2009/11/22 11:44:42.545 0:04.461 AudioOutputCoreConfBridge Updating device 2009/11/22 11:44:42.545 0:04.461 AudioOutputCore Setting device[1]: Default (PTLIB/ALSA) 2009/11/22 11:44:42.547 0:04.462 AudioInputCoreConfBridge Updating device 2009/11/22 11:44:42.547 0:04.462 AudioInputCore Setting device: Default (PTLIB/ALSA) 2009/11/22 11:44:42.547 0:04.462 GMAudioInputManager_ptlib Setting Device Default (PTLIB/ALSA) 2009/11/22 11:44:42.589 0:04.505 Opal Liste...0x5acf4910 OpalUDP Binding to interface: 192.168.178.10:5060 2009/11/22 11:44:42.589 0:04.505 Opal Liste...0x5acf4910 SIP Waiting for PDU on udp$86.64.162.35:5060 2009/11/22 11:44:42.590 0:04.505 Opal Liste...0x5acf4910 SIP PDU received: rem=udp$86.64.162.35:5060,local=udp$192.168.178.10:5060,if=192.168.178.10%eth0 SIP/2.0 489 Event Not Supported CSeq: 2 SUBSCRIBE Via: SIP/2.0/UDP 85.178.17.5:5060;branch=z9hG4bK48a8ebb5-c1d5-de11-9049-001bfc5bfc70;rport=61001 Server: Kamailio (1.5.3-notls (i386/linux)) From: "Ingo Busse" ;tag=e682ebb5-c1d5-de11-9049-001bfc5bfc70 Call-ID: d2a6d1b5-c1d5-de11-9049-001bfc5bfc70@bear To: ;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.ec76 Content-Length: 0 2009/11/22 11:44:42.590 0:04.505 Opal Liste...0x5acf4910 SIP Queueing PDU "2 SUBSCRIBE <489>", transaction=z9hG4bK48a8ebb5-c1d5-de11-9049-001bfc5bfc70, token=d2a6d1b5-c1d5-de11-9049-001bfc5bfc70@bear 2009/11/22 11:44:42.590 0:04.506 Opal Liste...0x5acf4910 Opal Transport clean up on termination 2009/11/22 11:44:42.590 0:04.506 Opal Liste...0x5acf4910 Opal Transport Close 2009/11/22 11:44:42.590 0:04.506 Opal Liste...0x5acf4910 Opal Deleted transport udp$86.64.162.35:5060 2009/11/22 11:44:42.594 0:04.509 Pool:0x5ac72910 SIP Handling PDU "2 SUBSCRIBE <489>" for transaction=z9hG4bK48a8ebb5-c1d5-de11-9049-001bfc5bfc70 2009/11/22 11:44:42.594 0:04.509 Pool:0x5ac72910 SIP SUBSCRIBE transaction id=z9hG4bK48a8ebb5-c1d5-de11-9049-001bfc5bfc70 completed. 2009/11/22 11:44:42.594 0:04.509 Pool:0x5ac72910 SIP SUBSCRIBE transaction id=z9hG4bK8a2dedb5-c1d5-de11-9049-001bfc5bfc70 aborted. 2009/11/22 11:44:42.594 0:04.509 Pool:0x5ac72910 SIP Set state Terminated_Aborted for SUBSCRIBE transaction id=z9hG4bK8a2dedb5-c1d5-de11-9049-001bfc5bfc70 2009/11/22 11:44:42.594 0:04.509 Pool:0x5ac72910 OpalUDP Setting interface to 192.168.178.10%eth0 2009/11/22 11:44:42.594 0:04.509 Pool:0x5ac72910 SIP Not retrying SUBSCRIBE due to error response 489 Bad Event 2009/11/22 11:44:42.594 0:04.509 Pool:0x5ac72910 SIP Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:501@ekiga.net, id=d2a6d1b5-c1d5-de11-9049-001bfc5bfc70@bear 2009/11/22 11:44:42.594 0:04.509 Pool:0x5ac72910 SIP Handled PDU "2 SUBSCRIBE <489>" 2009/11/22 11:44:42.623 0:04.539 Ekiga version 3.2.6 2009/11/22 11:44:42.623 0:04.539 OPAL version 3.6.6 2009/11/22 11:44:42.623 0:04.539 PTLIB version 2.6.5 2009/11/22 11:44:42.623 0:04.539 GNOME support disabled 2009/11/22 11:44:42.623 0:04.539 Accelerated rendering support enabled 2009/11/22 11:44:42.623 0:04.539 DBUS support enabled 2009/11/22 11:44:42.623 0:04.539 GConf support enabled 2009/11/22 11:44:42.623 0:04.539 ESound support disabled WARNING: Unhandled message: interface=org.freedesktop.DBus.Introspectable, path=/, member=Introspect WARNING: Unhandled message: interface=org.freedesktop.DBus.Introspectable, path=/, member=Introspect 2009/11/22 11:44:43.227 0:05.142 Opal Garbage:0x5adb7910 SIP Transaction id=z9hG4bK8a2dedb5-c1d5-de11-9049-001bfc5bfc70 destroyed. 2009/11/22 11:44:43.227 0:05.142 Opal Garbage:0x5adb7910 SIP Transaction id=z9hG4bK16c7c7b5-c1d5-de11-9049-001bfc5bfc70 destroyed. 2009/11/22 11:44:43.227 0:05.142 Opal Garbage:0x5adb7910 SIP Transaction id=z9hG4bKd2cacbb5-c1d5-de11-9049-001bfc5bfc70 destroyed. 2009/11/22 11:44:43.227 0:05.142 Opal Garbage:0x5adb7910 SIP Transaction id=z9hG4bKc627d0b5-c1d5-de11-9049-001bfc5bfc70 destroyed. 2009/11/22 11:44:43.227 0:05.143 Opal Garbage:0x5adb7910 SIP Deleting SIPSubscribeHandler sip:500@ekiga.net 2009/11/22 11:44:43.227 0:05.143 Opal Garbage:0x5adb7910 Opal Transport clean up on termination 2009/11/22 11:44:43.227 0:05.143 Opal Garbage:0x5adb7910 Opal Transport Close 2009/11/22 11:44:43.227 0:05.143 Opal Garbage:0x5adb7910 Opal Transport clean up on termination 2009/11/22 11:44:43.227 0:05.143 Opal Garbage:0x5adb7910 Opal Transport Close 2009/11/22 11:44:43.227 0:05.143 Opal Garbage:0x5adb7910 Opal Deleted transport udp$86.64.162.35:5060 2009/11/22 11:44:43.228 0:05.143 Opal Garbage:0x5adb7910 SIP Deleted handler. 2009/11/22 11:44:43.228 0:05.143 Opal Garbage:0x5adb7910 SIP Deleting SIPSubscribeHandler sip:500@ekiga.net 2009/11/22 11:44:43.228 0:05.143 Opal Garbage:0x5adb7910 Opal Transport clean up on termination 2009/11/22 11:44:43.228 0:05.143 Opal Garbage:0x5adb7910 Opal Transport Close 2009/11/22 11:44:43.228 0:05.143 Opal Garbage:0x5adb7910 Opal Transport clean up on termination 2009/11/22 11:44:43.228 0:05.143 Opal Garbage:0x5adb7910 Opal Transport Close 2009/11/22 11:44:43.228 0:05.143 Opal Garbage:0x5adb7910 Opal Deleted transport udp$86.64.162.35:5060 2009/11/22 11:44:43.228 0:05.144 Opal Garbage:0x5adb7910 SIP Deleted handler. 2009/11/22 11:44:43.228 0:05.144 Opal Garbage:0x5adb7910 SIP Deleting SIPSubscribeHandler sip:501@ekiga.net 2009/11/22 11:44:43.228 0:05.144 Opal Garbage:0x5adb7910 Opal Transport clean up on termination 2009/11/22 11:44:43.228 0:05.144 Opal Garbage:0x5adb7910 Opal Transport Close 2009/11/22 11:44:43.228 0:05.144 Opal Garbage:0x5adb7910 Opal Transport clean up on termination 2009/11/22 11:44:43.229 0:05.144 Opal Garbage:0x5adb7910 Opal Transport Close 2009/11/22 11:44:43.229 0:05.144 Opal Garbage:0x5adb7910 Opal Deleted transport udp$86.64.162.35:5060 2009/11/22 11:44:43.229 0:05.144 Opal Garbage:0x5adb7910 SIP Deleted handler. 2009/11/22 11:44:43.229 0:05.144 Opal Garbage:0x5adb7910 SIP Deleting SIPSubscribeHandler sip:501@ekiga.net 2009/11/22 11:44:43.229 0:05.144 Opal Garbage:0x5adb7910 Opal Transport clean up on termination 2009/11/22 11:44:43.229 0:05.144 Opal Garbage:0x5adb7910 Opal Transport Close 2009/11/22 11:44:43.229 0:05.144 Opal Garbage:0x5adb7910 Opal Transport clean up on termination 2009/11/22 11:44:43.229 0:05.144 Opal Garbage:0x5adb7910 Opal Transport Close 2009/11/22 11:44:43.229 0:05.145 Opal Garbage:0x5adb7910 Opal Deleted transport udp$86.64.162.35:5060 2009/11/22 11:44:43.229 0:05.145 Opal Garbage:0x5adb7910 SIP Deleted handler. 2009/11/22 11:44:43.347 0:05.262 Housekeeper:0x5ad35910 SIP Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK066fc6b5-c1d5-de11-9049-001bfc5bfc70 2009/11/22 11:44:43.373 0:05.288 Housekeeper:0x5ad35910 SIP Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bKbea8cab5-c1d5-de11-9049-001bfc5bfc70 2009/11/22 11:44:43.403 0:05.318 Housekeeper:0x5ad35910 SIP Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK988eceb5-c1d5-de11-9049-001bfc5bfc70 2009/11/22 11:44:43.602 0:05.517 Housekeeper:0x5ad35910 SIP Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK48a8ebb5-c1d5-de11-9049-001bfc5bfc70 2009/11/22 11:44:43.805 0:05.721 Listen Stopping listening thread on udp$*:5060 2009/11/22 11:44:43.805 0:05.721 MonSock Closed UDP socket 0x7f2f54003890 2009/11/22 11:44:43.805 0:05.721 Opal Liste...0x5acf4910 Listen UDP read error. 2009/11/22 11:44:43.826 0:05.741 MonSock Deleting UDP socket 0x7f2f54003890 2009/11/22 11:44:43.826 0:05.741 MonSock Closed UDP socket 0x7f2f54003220 2009/11/22 11:44:43.826 0:05.741 MonSock Deleting UDP socket 0x7f2f54003220 2009/11/22 11:44:43.826 0:05.741 PWLib File handle low water mark set: 31 PUDPSocket 2009/11/22 11:44:43.826 0:05.741 MonSock Created socket bundle for all interfaces. 2009/11/22 11:44:43.986 0:05.902 MonSock Created bundled UDP socket via STUN, internal=192.168.178.10:5060, external=85.178.17.5:61001 2009/11/22 11:44:43.986 0:05.902 MonSock Created bundled UDP socket 172.16.104.3:5060 2009/11/22 11:44:43.986 0:05.902 Listen Stopping listening thread on tcp$*:1720 2009/11/22 11:44:43.986 0:05.902 Opal Liste...0x5acf4910 Listen Started listening thread on udp$*:5060 2009/11/22 11:44:43.987 0:05.902 Opal Liste...0x5acb3910 Listen Started listening thread on tcp$*:1720 2009/11/22 11:44:43.987 0:05.902 Opal Liste...0x5acb3910 Listen Waiting on socket accept on tcp$*:1720 2009/11/22 11:44:43.987 0:05.902 PTLib Thread high water mark set: 11 2009/11/22 11:44:43.987 0:05.902 subscriber:0x514c6910 SIP Start REGISTER aor=620@192.168.178.1 registrar=192.168.178.1:5060 contact= authID=620 realm= expire=3600 restore=30 minRetry=0.000 maxRetry=0.000 2009/11/22 11:44:43.987 0:05.903 subscriber:0x514c6910 SIP Changing REGISTER handler from Unavailable to Subscribing, target=sip:620@192.168.178.1, id=9c4bcbb6-c1d5-de11-9049-001bfc5bfc70@bear 2009/11/22 11:44:43.987 0:05.903 subscriber:0x514c6910 OpalUDP Binding to interface: 0.0.0.0:5060 2009/11/22 11:44:43.987 0:05.903 subscriber:0x514c6910 SIP Created transport udp$192.168.178.1:5060 2009/11/22 11:44:43.988 0:05.903 subscriber:0x514c6910 OpalUDP Started connect to 192.168.178.1:5060 2009/11/22 11:44:43.988 0:05.903 subscriber:0x514c6910 OpalUDP Writing to interface 0 - "192.168.178.10%eth0" 2009/11/22 11:44:43.988 0:05.904 subscriber:0x514c6910 OpalMan Listener interfaces: associated transport=udp$192.168.178.10:5060 udp$192.168.178.10:5060,udp$172.16.104.3:5060 2009/11/22 11:44:43.989 0:05.904 subscriber:0x514c6910 SIP Transaction created. 2009/11/22 11:44:43.989 0:05.905 subscriber:0x514c6910 SIP Transaction remote address is udp$192.168.178.1:5060 2009/11/22 11:44:43.989 0:05.905 subscriber:0x514c6910 SIP Sending PDU (531 bytes) to: rem=udp$192.168.178.1:5060,local=udp$192.168.178.10:5060,if=192.168.178.10%eth0 REGISTER sip:192.168.178.1 SIP/2.0 CSeq: 1 REGISTER Via: SIP/2.0/UDP 192.168.178.10:5060;branch=z9hG4bK229dcbb6-c1d5-de11-9049-001bfc5bfc70;rport User-Agent: Ekiga/3.2.6 From: ;tag=1451cbb6-c1d5-de11-9049-001bfc5bfc70 Call-ID: 9c4bcbb6-c1d5-de11-9049-001bfc5bfc70@bear To: Contact: ;q=1, ;q=0.500 Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING Expires: 3600 Content-Length: 0 Max-Forwards: 70 2009/11/22 11:44:43.989 0:05.905 subscriber:0x514c6910 OpalUDP Setting interface to 192.168.178.10%eth0 2009/11/22 11:44:43.989 0:05.905 subscriber:0x514c6910 SIP Transaction timers set: retry=0.500, completion=6.000 2009/11/22 11:44:43.989 0:05.905 subscriber:0x514c6910 OpalUDP Writing to interface 1 - "172.16.104.3%tap0" 2009/11/22 11:44:43.990 0:05.905 subscriber:0x514c6910 OpalMan Listener interfaces: associated transport=udp$172.16.104.3:5060 udp$172.16.104.3:5060,udp$192.168.178.10:5060 2009/11/22 11:44:43.990 0:05.906 subscriber:0x514c6910 SIP Transaction created. 2009/11/22 11:44:43.991 0:05.906 subscriber:0x514c6910 SIP Transaction remote address is udp$192.168.178.1:5060 2009/11/22 11:44:43.991 0:05.906 subscriber:0x514c6910 SIP Sending PDU (529 bytes) to: rem=udp$192.168.178.1:5060,local=udp$172.16.104.3:5060,if=172.16.104.3%tap0 REGISTER sip:192.168.178.1 SIP/2.0 CSeq: 1 REGISTER Via: SIP/2.0/UDP 172.16.104.3:5060;branch=z9hG4bKa6e1cbb6-c1d5-de11-9049-001bfc5bfc70;rport User-Agent: Ekiga/3.2.6 From: ;tag=1451cbb6-c1d5-de11-9049-001bfc5bfc70 Call-ID: 9c4bcbb6-c1d5-de11-9049-001bfc5bfc70@bear To: Contact: ;q=1, ;q=0.500 Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING Expires: 3600 Content-Length: 0 Max-Forwards: 70 2009/11/22 11:44:43.991 0:05.907 subscriber:0x514c6910 OpalUDP Setting interface to 172.16.104.3%tap0 2009/11/22 11:44:43.991 0:05.907 subscriber:0x514c6910 SIP Transaction timers set: retry=0.500, completion=6.000 2009/11/22 11:44:44.002 0:05.917 Opal Liste...0x5acf4910 OpalUDP Binding to interface: 192.168.178.10:5060 2009/11/22 11:44:44.002 0:05.917 Opal Liste...0x5acf4910 SIP Waiting for PDU on udp$192.168.178.1:5060 2009/11/22 11:44:44.002 0:05.917 Opal Liste...0x5acf4910 SIP PDU received: rem=udp$192.168.178.1:5060,local=udp$192.168.178.10:5060,if=192.168.178.10%eth0 SIP/2.0 401 Unauthorized CSeq: 1 REGISTER Via: SIP/2.0/UDP 192.168.178.10:5060;branch=z9hG4bK229dcbb6-c1d5-de11-9049-001bfc5bfc70;rport=5060 User-Agent: AVM FRITZ!Box Fon WLAN 7170 29.04.76 (Jul 13 2009) From: ;tag=1451cbb6-c1d5-de11-9049-001bfc5bfc70 Call-ID: 9c4bcbb6-c1d5-de11-9049-001bfc5bfc70@bear To: ;tag=67B06751D2554A4B Content-Length: 0 WWW-Authenticate: Digest realm="fritz.box", nonce="EC08B38FEDA02086" 2009/11/22 11:44:44.002 0:05.918 Opal Liste...0x5acf4910 SIP Queueing PDU "1 REGISTER <401>", transaction=z9hG4bK229dcbb6-c1d5-de11-9049-001bfc5bfc70, token=9c4bcbb6-c1d5-de11-9049-001bfc5bfc70@bear 2009/11/22 11:44:44.002 0:05.918 Opal Liste...0x5acf4910 Opal Transport clean up on termination 2009/11/22 11:44:44.002 0:05.918 Opal Liste...0x5acf4910 Opal Transport Close 2009/11/22 11:44:44.002 0:05.918 Opal Liste...0x5acf4910 Opal Deleted transport udp$192.168.178.1:5060 2009/11/22 11:44:44.002 0:05.918 Pool:0x5ac72910 SIP Handling PDU "1 REGISTER <401>" for transaction=z9hG4bK229dcbb6-c1d5-de11-9049-001bfc5bfc70 2009/11/22 11:44:44.002 0:05.918 Pool:0x5ac72910 SIP REGISTER transaction id=z9hG4bK229dcbb6-c1d5-de11-9049-001bfc5bfc70 completed. 2009/11/22 11:44:44.002 0:05.918 Pool:0x5ac72910 SIP REGISTER transaction id=z9hG4bKa6e1cbb6-c1d5-de11-9049-001bfc5bfc70 aborted. 2009/11/22 11:44:44.003 0:05.918 Pool:0x5ac72910 SIP Set state Terminated_Aborted for REGISTER transaction id=z9hG4bKa6e1cbb6-c1d5-de11-9049-001bfc5bfc70 2009/11/22 11:44:44.003 0:05.918 Pool:0x5ac72910 OpalUDP Setting interface to 192.168.178.10%eth0 2009/11/22 11:44:44.003 0:05.918 Pool:0x5ac72910 SIP Received Authentication Required response 2009/11/22 11:44:44.003 0:05.918 Pool:0x5ac72910 SIP Found auth info for realm fritz.box 2009/11/22 11:44:44.003 0:05.918 Pool:0x5ac72910 SIP Changing REGISTER handler from Unavailable to Subscribing, target=sip:620@192.168.178.1, id=9c4bcbb6-c1d5-de11-9049-001bfc5bfc70@bear 2009/11/22 11:44:44.003 0:05.919 Pool:0x5ac72910 OpalMan Listener interfaces: associated transport=udp$192.168.178.10:5060 udp$192.168.178.10:5060,udp$172.16.104.3:5060 2009/11/22 11:44:44.004 0:05.919 Pool:0x5ac72910 SIP Transaction created. 2009/11/22 11:44:44.004 0:05.920 Pool:0x5ac72910 SIP Adding authentication information 2009/11/22 11:44:44.004 0:05.920 Pool:0x5ac72910 SIP Transaction remote address is udp$192.168.178.1:5060 2009/11/22 11:44:44.004 0:05.920 Pool:0x5ac72910 SIP Sending PDU (699 bytes) to: rem=udp$192.168.178.1:5060,local=udp$192.168.178.10:5060,if=192.168.178.10%eth0 REGISTER sip:192.168.178.1 SIP/2.0 CSeq: 2 REGISTER Via: SIP/2.0/UDP 192.168.178.10:5060;branch=z9hG4bKbce7cdb6-c1d5-de11-9049-001bfc5bfc70;rport User-Agent: Ekiga/3.2.6 Authorization: Digest username="620", realm="fritz.box", nonce="EC08B38FEDA02086", uri="sip:192.168.178.1", algorithm=MD5, response="477633fb4ff8ef9c70773430463b0bb9" From: ;tag=1451cbb6-c1d5-de11-9049-001bfc5bfc70 Call-ID: 9c4bcbb6-c1d5-de11-9049-001bfc5bfc70@bear To: Contact: ;q=1, ;q=0.500 Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING Expires: 3600 Content-Length: 0 Max-Forwards: 70 2009/11/22 11:44:44.004 0:05.920 Pool:0x5ac72910 OpalUDP Setting interface to 192.168.178.10%eth0 2009/11/22 11:44:44.005 0:05.920 Pool:0x5ac72910 SIP Transaction timers set: retry=0.500, completion=6.000 2009/11/22 11:44:44.005 0:05.920 Pool:0x5ac72910 SIP Handled PDU "1 REGISTER <401>" 2009/11/22 11:44:44.026 0:05.941 Opal Liste...0x5acf4910 OpalUDP Binding to interface: 192.168.178.10:5060 2009/11/22 11:44:44.026 0:05.941 Opal Liste...0x5acf4910 SIP Waiting for PDU on udp$192.168.178.1:5060 2009/11/22 11:44:44.026 0:05.942 Opal Liste...0x5acf4910 SIP PDU received: rem=udp$192.168.178.1:5060,local=udp$192.168.178.10:5060,if=192.168.178.10%eth0 SIP/2.0 401 Unauthorized CSeq: 2 REGISTER Via: SIP/2.0/UDP 192.168.178.10:5060;branch=z9hG4bKbce7cdb6-c1d5-de11-9049-001bfc5bfc70;rport=5060 User-Agent: AVM FRITZ!Box Fon WLAN 7170 29.04.76 (Jul 13 2009) From: ;tag=1451cbb6-c1d5-de11-9049-001bfc5bfc70 Call-ID: 9c4bcbb6-c1d5-de11-9049-001bfc5bfc70@bear To: ;tag=F38C3FC41F9F8D7D Content-Length: 0 WWW-Authenticate: Digest realm="fritz.box", nonce="BBDACF372E20DE47" 2009/11/22 11:44:44.026 0:05.942 Opal Liste...0x5acf4910 SIP Queueing PDU "2 REGISTER <401>", transaction=z9hG4bKbce7cdb6-c1d5-de11-9049-001bfc5bfc70, token=9c4bcbb6-c1d5-de11-9049-001bfc5bfc70@bear 2009/11/22 11:44:44.026 0:05.942 Opal Liste...0x5acf4910 Opal Transport clean up on termination 2009/11/22 11:44:44.026 0:05.942 Opal Liste...0x5acf4910 Opal Transport Close 2009/11/22 11:44:44.026 0:05.942 Opal Liste...0x5acf4910 Opal Deleted transport udp$192.168.178.1:5060 2009/11/22 11:44:44.027 0:05.942 Pool:0x5ac72910 SIP Handling PDU "2 REGISTER <401>" for transaction=z9hG4bKbce7cdb6-c1d5-de11-9049-001bfc5bfc70 2009/11/22 11:44:44.027 0:05.942 Pool:0x5ac72910 SIP REGISTER transaction id=z9hG4bKbce7cdb6-c1d5-de11-9049-001bfc5bfc70 completed. 2009/11/22 11:44:44.027 0:05.942 Pool:0x5ac72910 OpalUDP Setting interface to 192.168.178.10%eth0 2009/11/22 11:44:44.027 0:05.942 Pool:0x5ac72910 SIP Received Authentication Required response 2009/11/22 11:44:44.027 0:05.942 Pool:0x5ac72910 SIP Found auth info for realm fritz.box 2009/11/22 11:44:44.027 0:05.942 Pool:0x5ac72910 SIP Authentication already performed using current credentials, not trying again. 2009/11/22 11:44:44.027 0:05.942 Pool:0x5ac72910 SIP Handled PDU "2 REGISTER <401>" 2009/11/22 11:44:44.230 0:06.145 Opal Garbage:0x5adb7910 SIP Transaction id=z9hG4bK988eceb5-c1d5-de11-9049-001bfc5bfc70 destroyed. 2009/11/22 11:44:44.230 0:06.145 Opal Garbage:0x5adb7910 SIP Transaction id=z9hG4bK48a8ebb5-c1d5-de11-9049-001bfc5bfc70 destroyed. 2009/11/22 11:44:44.230 0:06.145 Opal Garbage:0x5adb7910 SIP Transaction id=z9hG4bK066fc6b5-c1d5-de11-9049-001bfc5bfc70 destroyed. 2009/11/22 11:44:44.230 0:06.145 Opal Garbage:0x5adb7910 SIP Transaction id=z9hG4bKbea8cab5-c1d5-de11-9049-001bfc5bfc70 destroyed. 2009/11/22 11:44:44.230 0:06.145 Opal Garbage:0x5adb7910 SIP Transaction id=z9hG4bKa6e1cbb6-c1d5-de11-9049-001bfc5bfc70 destroyed. 2009/11/22 11:44:45.005 0:06.921 Housekeeper:0x5ad35910 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bK229dcbb6-c1d5-de11-9049-001bfc5bfc70 2009/11/22 11:44:45.026 0:06.942 Housekeeper:0x5ad35910 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bKbce7cdb6-c1d5-de11-9049-001bfc5bfc70 2009/11/22 11:44:45.230 0:07.146 Opal Garbage:0x5adb7910 SIP Transaction id=z9hG4bK229dcbb6-c1d5-de11-9049-001bfc5bfc70 destroyed. 2009/11/22 11:44:45.230 0:07.146 Opal Garbage:0x5adb7910 SIP Transaction id=z9hG4bKbce7cdb6-c1d5-de11-9049-001bfc5bfc70 destroyed. 2009/11/22 11:44:54.748 0:16.663 Network In...0x5ad76910 IfaceMon Finished interface monitor thread.