2009/08/03 15:12:25.567 0:00.260 Version 3.2.5 by on Unix Linux (2.6.29.6-213.fc11.x86_64-x86_64) with PTLib (v2.6.4) at 2009/8/3 15:12:25.567 2009/08/03 15:12:25.568 0:00.261 Ekiga git revision: unknown 2009/08/03 15:12:25.573 0:00.266 Ekiga registered on D-Bus: org.ekiga.Ekiga 2009/08/03 15:12:25.575 0:00.268 PWLib File handle high water mark set: 17 Thread unblock pipe 2009/08/03 15:12:25.576 0:00.269 PTLib Thread high water mark set: 2 2009/08/03 15:12:25.576 0:00.270 PWLib File handle high water mark set: 19 Thread unblock pipe 2009/08/03 15:12:25.577 0:00.271 PTLib Thread high water mark set: 3 2009/08/03 15:12:25.578 0:00.271 PWLib File handle high water mark set: 21 Thread unblock pipe 2009/08/03 15:12:25.580 0:00.273 PWLib File handle high water mark set: 23 Thread unblock pipe 2009/08/03 15:12:25.585 0:00.279 PTLib Thread high water mark set: 4 2009/08/03 15:12:25.586 0:00.280 HalManager_dbus Initialising HAL Manager 2009/08/03 15:12:25.598 0:00.292 HalManager_dbus Populating device list 2009/08/03 15:12:25.638 0:00.331 HalManager_dbus Detected V4L capabilities on /dev/video1 name: Monitor Integrated Webcam 2009/08/03 15:12:25.638 0:00.331 HalManager_dbus Detected V4L2 capabilities on /dev/video1 name: Monitor Integrated Webcam 2009/08/03 15:12:25.655 0:00.349 HalManager_dbus Detected V4L capabilities on /dev/video0 name: Laptop Integrated Webcam 2009/08/03 15:12:25.656 0:00.349 HalManager_dbus Detected V4L2 capabilities on /dev/video0 name: Laptop Integrated Webcam 2009/08/03 15:12:25.753 0:00.447 HalManager_dbus Populated device list with 24 devices 2009/08/03 15:12:25.754 0:00.447 HalManager_dbus Populating interface list 2009/08/03 15:12:25.754 0:00.448 HalManager_dbus Populating full interface list failed - Method "getDevices" with signature "" on interface "org.freedesktop.NetworkManager" doesn't exist 2009/08/03 15:12:25.755 0:00.448 Detecting V4L2 devices 2009/08/03 15:12:25.755 0:00.448 PV4L2Plugin detected device metadata at /sys/class/video4linux/ 2009/08/03 15:12:25.807 0:00.501 PWLib File handle high water mark set: 28 Thread unblock pipe 2009/08/03 15:12:25.808 0:00.501 PTLib Thread high water mark set: 5 2009/08/03 15:12:25.808 0:00.501 OpalMan Created manager. 2009/08/03 15:12:25.808 0:00.501 OpalMan Registered endpoint with prefix pc 2009/08/03 15:12:25.808 0:00.501 OpalEP Created endpoint: pc 2009/08/03 15:12:25.813 0:00.506 PCSS Created PC sound system endpoint. Players: Default HDA Intel HDA Intel (1) HDA Intel (2) EKIGA *.wav Recorders: Default Monitor Integrated Webcam HDA Intel EKIGA *.wav 2009/08/03 15:12:25.813 0:00.507 OPAL SetMediaFormatOrder() 2009/08/03 15:12:25.813 0:00.507 OPAL SetMediaFormatMask() 2009/08/03 15:12:25.814 0:00.507 OpalMan Registered endpoint with prefix sip 2009/08/03 15:12:25.814 0:00.508 OpalEP Created endpoint: sip 2009/08/03 15:12:25.815 0:00.508 PWLib File handle high water mark set: 29 PUDPSocket 2009/08/03 15:12:25.815 0:00.508 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.10.176 [20:02:83:bf:18:89:00:00:02:15:c5:ff:fe:7e:01:79] <00-15-C5-7E-01-79> (eth0) 10.3.225.205 <00-00-00-00-00-00> (tun0) 2009/08/03 15:12:25.815 0:00.508 PWLib File handle high water mark set: 30 Thread unblock pipe 2009/08/03 15:12:25.815 0:00.508 PTLib Thread high water mark set: 6 2009/08/03 15:12:25.815 0:00.509 PThreadObj:0xe8d85910 IfaceMon Started interface monitor thread. 2009/08/03 15:12:25.815 0:00.509 PThreadObj:0xe8d85910 PWLib File handle high water mark set: 31 PUDPSocket 2009/08/03 15:12:25.816 0:00.509 PWLib File handle high water mark set: 32 Thread unblock pipe 2009/08/03 15:12:25.816 0:00.509 PTLib Thread high water mark set: 7 2009/08/03 15:12:25.816 0:00.509 OpalMan Registered endpoint with prefix sips 2009/08/03 15:12:25.816 0:00.509 SIP Created endpoint. 2009/08/03 15:12:25.816 0:00.510 PWLib File handle high water mark set: 33 PUDPSocket 2009/08/03 15:12:25.816 0:00.510 MonSock Created socket bundle for all interfaces. 2009/08/03 15:12:25.817 0:00.510 PWLib File handle high water mark set: 34 PUDPSocket 2009/08/03 15:12:25.817 0:00.510 MonSock Created bundled UDP socket 192.168.10.176:5060 2009/08/03 15:12:25.817 0:00.511 PWLib File handle high water mark set: 35 PUDPSocket 2009/08/03 15:12:25.817 0:00.511 MonSock Created bundled UDP socket 10.3.225.205:5060 2009/08/03 15:12:25.817 0:00.511 PWLib File handle high water mark set: 37 Thread unblock pipe 2009/08/03 15:12:25.818 0:00.511 PTLib Thread high water mark set: 8 2009/08/03 15:12:25.818 0:00.511 Opal Liste...0xd5d7d910 Listen Started listening thread on udp$*:5060 2009/08/03 15:12:25.821 0:00.514 OpalMan Added route "sip:.*=pc:*" 2009/08/03 15:12:25.821 0:00.514 OpalMan Added route "pc:.*=sip:" 2009/08/03 15:12:25.821 0:00.515 OpalMan Registered endpoint with prefix h323 2009/08/03 15:12:25.822 0:00.515 OpalEP Created endpoint: h323 2009/08/03 15:12:25.822 0:00.515 OpalMan Registered endpoint with prefix h323s 2009/08/03 15:12:25.822 0:00.515 H323 Created endpoint. 2009/08/03 15:12:25.822 0:00.515 PWLib File handle high water mark set: 38 PTCPSocket 2009/08/03 15:12:25.822 0:00.515 PWLib File handle high water mark set: 40 Thread unblock pipe 2009/08/03 15:12:25.822 0:00.515 PTLib Thread high water mark set: 9 2009/08/03 15:12:25.822 0:00.516 Opal Liste...0xd5d3c910 Listen Started listening thread on tcp$*:1720 2009/08/03 15:12:25.822 0:00.516 Opal Liste...0xd5d3c910 Listen Waiting on socket accept on tcp$*:1720 2009/08/03 15:12:25.822 0:00.516 OpalMan Added route "h323:.*=pc:" 2009/08/03 15:12:25.823 0:00.516 OpalMan Added route "pc:.*=h323:" 2009/08/03 15:12:25.831 0:00.525 MediaFormat Removing codecs SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,GSM-06.10,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k 2009/08/03 15:12:25.832 0:00.525 OPAL SetMediaFormatMask(PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,G.722-64k,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,YUV420P,H.261,H.261-CIF,H.261-QCIF,RFC4175_YCbCr-4:2:0,theora,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz) 2009/08/03 15:12:25.832 0:00.525 OPAL SetMediaFormatOrder(SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,GSM-06.10,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k) 2009/08/03 15:12:25.839 0:00.532 MediaFormat Removing codecs SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,GSM-06.10,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k 2009/08/03 15:12:25.839 0:00.532 OPAL SetMediaFormatMask(PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,G.722-64k,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,YUV420P,H.261,H.261-CIF,H.261-QCIF,RFC4175_YCbCr-4:2:0,theora,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz) 2009/08/03 15:12:25.839 0:00.532 OPAL SetMediaFormatOrder(SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,GSM-06.10,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k) 2009/08/03 15:12:25.851 0:00.545 PWLib File handle high water mark set: 42 Thread unblock pipe 2009/08/03 15:12:25.852 0:00.545 PTLib Thread high water mark set: 10 2009/08/03 15:12:25.853 0:00.547 StunDetector:0xd5cfb910 PWLib File handle high water mark set: 43 PUDPSocket 2009/08/03 15:12:25.961 0:00.654 StunDetector:0xd5cfb910 PWLib File handle high water mark set: 44 PUDPSocket 2009/08/03 15:12:26.005 0:00.698 VideoOutputCoreConfBridge Updating video view 2009/08/03 15:12:26.006 0:00.699 VideoOutputCoreConfBridge Updating zoom 2009/08/03 15:12:26.006 0:00.700 VideoOutputCoreConfBridge Updating Video Settings 2009/08/03 15:12:26.007 0:00.701 VideoOutputCoreConfBridge Updating Video Settings 2009/08/03 15:12:26.007 0:00.701 VideoOutputCoreConfBridge Updating Video Settings 2009/08/03 15:12:26.008 0:00.701 VideoOutputCoreConfBridge Updating Video Settings 2009/08/03 15:12:26.008 0:00.701 VidInputCoreConfBridge Updating preview size and fps 2009/08/03 15:12:26.008 0:00.701 VidInputCore Setting new preview config: 320x240/30 2009/08/03 15:12:26.011 0:00.704 VidInputCoreConfBridge Updating preview size and fps 2009/08/03 15:12:26.011 0:00.704 VidInputCore Setting new preview config: 320x240/30 2009/08/03 15:12:26.014 0:00.707 VidInputCoreConfBridge Updating device 2009/08/03 15:12:26.014 0:00.708 VidInputCore Setting device: Monitor Integrated Webcam (PTLIB/V4L2) 2009/08/03 15:12:26.014 0:00.708 GMVideoInputManager_ptlib Setting Device Monitor Integrated Webcam (PTLIB/V4L2) 2009/08/03 15:12:26.014 0:00.708 VidInputCoreConfBridge Updating device 2009/08/03 15:12:26.015 0:00.708 VidInputCore Setting device: Monitor Integrated Webcam (PTLIB/V4L2) 2009/08/03 15:12:26.015 0:00.708 GMVideoInputManager_ptlib Setting Device Monitor Integrated Webcam (PTLIB/V4L2) 2009/08/03 15:12:26.015 0:00.708 VidInputCoreConfBridge Updating device 2009/08/03 15:12:26.015 0:00.708 VidInputCore Setting device: Monitor Integrated Webcam (PTLIB/V4L2) 2009/08/03 15:12:26.015 0:00.708 GMVideoInputManager_ptlib Setting Device Monitor Integrated Webcam (PTLIB/V4L2) 2009/08/03 15:12:26.015 0:00.709 VidInputCoreConfBridge Updating image 2009/08/03 15:12:26.015 0:00.709 VidInputCoreConfBridge Updating preview 2009/08/03 15:12:26.016 0:00.709 VidInputCore Stopping Preview 2009/08/03 15:12:26.016 0:00.709 AudioOutputCoreConfBridge Updating device 2009/08/03 15:12:26.016 0:00.709 AudioOutputCore Setting device[0]: HDA Intel (2) (PTLIB/ALSA) 2009/08/03 15:12:26.016 0:00.709 GMAudioOutputManager_ptlib Setting Device[0] HDA Intel (2) (PTLIB/ALSA) 2009/08/03 15:12:26.016 0:00.710 AudioOutputCoreConfBridge Updating device 2009/08/03 15:12:26.016 0:00.710 AudioOutputCore Setting device[1]: HDA Intel (2) (PTLIB/ALSA) 2009/08/03 15:12:26.018 0:00.711 AudioInputCoreConfBridge Updating device 2009/08/03 15:12:26.018 0:00.712 AudioInputCore Setting device: HDA Intel (PTLIB/ALSA) 2009/08/03 15:12:26.018 0:00.712 GMAudioInputManager_ptlib Setting Device HDA Intel (PTLIB/ALSA) 2009/08/03 15:12:26.068 0:00.761 StunDetector:0xd5cfb910 PWLib File handle high water mark set: 57 PUDPSocket 2009/08/03 15:12:26.237 0:00.931 Ekiga version 3.2.5 2009/08/03 15:12:26.237 0:00.931 OPAL version 3.6.4 2009/08/03 15:12:26.237 0:00.931 PTLIB version 2.6.4 2009/08/03 15:12:26.238 0:00.931 GNOME support disabled 2009/08/03 15:12:26.238 0:00.931 Accelerated rendering support enabled 2009/08/03 15:12:26.238 0:00.931 DBUS support enabled 2009/08/03 15:12:26.238 0:00.931 GConf support enabled 2009/08/03 15:12:26.238 0:00.931 ESound support disabled 2009/08/03 15:12:28.471 0:03.164 StunDetector:0xd5cfb910 PWLib File handle low water mark set: 43 PUDPSocket 2009/08/03 15:12:28.577 0:03.271 StunDetector:0xd5cfb910 OPAL STUN server "stun.corp.redhat.com" replies Symmetric Firewall, external IP 10.3.225.205 2009/08/03 15:12:28.577 0:03.271 StunDetector:0xd5cfb910 PTLib Destroyed thread 0x1e9d380 StunDetector:0xd5cfb910(id = 7ff9d5cfb910) 2009/08/03 15:12:29.449 0:04.142 Listen Stopping listening thread on udp$*:5060 2009/08/03 15:12:29.450 0:04.143 MonSock Closed UDP socket 0x20049b0 2009/08/03 15:12:29.450 0:04.143 Opal Liste...0xd5d7d910 Listen UDP read error. 2009/08/03 15:12:29.470 0:04.163 MonSock Deleting UDP socket 0x20049b0 2009/08/03 15:12:29.470 0:04.164 MonSock Closed UDP socket 0x2004320 2009/08/03 15:12:29.470 0:04.164 MonSock Deleting UDP socket 0x2004320 2009/08/03 15:12:29.471 0:04.164 PTLib Destroyed thread 0x20046d0 Opal Listener:0xd5d7d910(id = 7ff9d5d7d910) 2009/08/03 15:12:29.471 0:04.164 PWLib File handle low water mark set: 33 PUDPSocket 2009/08/03 15:12:29.471 0:04.164 MonSock Created socket bundle for all interfaces. 2009/08/03 15:12:29.471 0:04.165 MonSock Created bundled UDP socket 192.168.10.176:5060 2009/08/03 15:12:29.472 0:04.165 MonSock Created bundled UDP socket 10.3.225.205:5060 2009/08/03 15:12:29.472 0:04.165 Opal Liste...0xd5d7d910 Listen Started listening thread on udp$*:5060 2009/08/03 15:12:29.472 0:04.165 Listen Stopping listening thread on tcp$*:1720 2009/08/03 15:12:29.473 0:04.166 PTLib Destroyed thread 0x20085e0 Opal Listener:0xd5d3c910(id = 7ff9d5d3c910) 2009/08/03 15:12:29.473 0:04.167 Opal Liste...0xd5d3c910 Listen Started listening thread on tcp$*:1720 2009/08/03 15:12:29.473 0:04.167 Opal Liste...0xd5d3c910 Listen Waiting on socket accept on tcp$*:1720 2009/08/03 15:12:29.474 0:04.167 subscriber:0xd5cfb910 SIP Start REGISTER aor=8122425@ccm-sub2.ipt.phx.redhat.com registrar=ccm-sub2.ipt.phx.redhat.com contact= authID=22425 realm= expire=3600 restore=30 minRetry=0.000 maxRetry=0.000 2009/08/03 15:12:29.476 0:04.169 subscriber:0xd5cfb910 SIP Changing REGISTER handler from Unavailable to Subscribing, target=sip:8122425@ccm-sub2.ipt.phx.redhat.com, id=c6b51667-e87e-de11-9f67-0015c57e0179@luminos.j2sol 2009/08/03 15:12:29.581 0:04.275 subscriber:0xd5cfb910 OpalUDP Binding to interface: 0.0.0.0:5060 2009/08/03 15:12:29.582 0:04.275 subscriber:0xd5cfb910 SIP Created transport udp$10.8.8.32:5060 2009/08/03 15:12:29.583 0:04.276 subscriber:0xd5cfb910 OpalUDP Started connect to 10.8.8.32:5060 2009/08/03 15:12:29.584 0:04.277 subscriber:0xd5cfb910 OpalUDP Writing to interface 0 - "192.168.10.176%eth0" 2009/08/03 15:12:29.586 0:04.279 subscriber:0xd5cfb910 OpalMan Listener interfaces: associated transport=udp$192.168.10.176:5060 udp$192.168.10.176:5060,udp$10.3.225.205:5060 2009/08/03 15:12:29.587 0:04.281 subscriber:0xd5cfb910 SIP Transaction created. 2009/08/03 15:12:29.590 0:04.283 subscriber:0xd5cfb910 SIP No SRV lookup as has explicit port number. 2009/08/03 15:12:29.590 0:04.284 subscriber:0xd5cfb910 SIP Transaction remote address is udp$ccm-sub2.ipt.phx.redhat.com:5060 2009/08/03 15:12:29.592 0:04.285 subscriber:0xd5cfb910 SIP Sending PDU (648 bytes) to: rem=udp$10.8.8.32:5060,local=udp$192.168.10.176:5060,if=192.168.10.176%eth0 REGISTER sip:ccm-sub2.ipt.phx.redhat.com SIP/2.0 Route: CSeq: 1 REGISTER Via: SIP/2.0/UDP 192.168.10.176:5060;branch=z9hG4bK16452867-e87e-de11-9f67-0015c57e0179;rport User-Agent: Ekiga/3.2.5 From: ;tag=90e11667-e87e-de11-9f67-0015c57e0179 Call-ID: c6b51667-e87e-de11-9f67-0015c57e0179@luminos.j2sol 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/08/03 15:12:29.593 0:04.286 subscriber:0xd5cfb910 OpalUDP Setting interface to 192.168.10.176%eth0 2009/08/03 15:12:29.594 0:04.287 subscriber:0xd5cfb910 SIP Transaction timers set: retry=0.500, completion=6.000 2009/08/03 15:12:29.595 0:04.288 subscriber:0xd5cfb910 OpalUDP Writing to interface 1 - "10.3.225.205%tun0" 2009/08/03 15:12:29.597 0:04.290 subscriber:0xd5cfb910 OpalMan Listener interfaces: associated transport=udp$10.3.225.205:5060 udp$10.3.225.205:5060,udp$192.168.10.176:5060 2009/08/03 15:12:29.598 0:04.291 subscriber:0xd5cfb910 SIP Transaction created. 2009/08/03 15:12:29.602 0:04.295 subscriber:0xd5cfb910 SIP No SRV lookup as has explicit port number. 2009/08/03 15:12:29.602 0:04.296 subscriber:0xd5cfb910 SIP Transaction remote address is udp$ccm-sub2.ipt.phx.redhat.com:5060 2009/08/03 15:12:29.603 0:04.297 subscriber:0xd5cfb910 SIP Sending PDU (646 bytes) to: rem=udp$10.8.8.32:5060,local=udp$10.3.225.205:5060,if=10.3.225.205%tun0 REGISTER sip:ccm-sub2.ipt.phx.redhat.com SIP/2.0 Route: CSeq: 1 REGISTER Via: SIP/2.0/UDP 10.3.225.205:5060;branch=z9hG4bK42212a67-e87e-de11-9f67-0015c57e0179;rport User-Agent: Ekiga/3.2.5 From: ;tag=90e11667-e87e-de11-9f67-0015c57e0179 Call-ID: c6b51667-e87e-de11-9f67-0015c57e0179@luminos.j2sol 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/08/03 15:12:29.604 0:04.298 subscriber:0xd5cfb910 OpalUDP Setting interface to 10.3.225.205%tun0 2009/08/03 15:12:29.605 0:04.298 subscriber:0xd5cfb910 SIP Transaction timers set: retry=0.500, completion=6.000 2009/08/03 15:12:29.614 0:04.307 subscriber:0xd5cfb910 PTLib Destroyed thread 0x1fc2820 subscriber:0xd5cfb910(id = 7ff9d5cfb910) 2009/08/03 15:12:29.660 0:04.354 Opal Liste...0xd5d7d910 OpalUDP Binding to interface: 10.3.225.205:5060 2009/08/03 15:12:29.660 0:04.354 Opal Liste...0xd5d7d910 SIP Waiting for PDU on udp$10.8.8.32:5060 2009/08/03 15:12:29.661 0:04.354 Opal Liste...0xd5d7d910 SIP PDU received: rem=udp$10.8.8.32:5060,local=udp$10.3.225.205:5060,if=10.3.225.205%tun0 SIP/2.0 100 Trying Date: Mon, 03 Aug 2009 22:12:31 GMT CSeq: 1 REGISTER Via: SIP/2.0/UDP 10.3.225.205:5060;branch=z9hG4bK42212a67-e87e-de11-9f67-0015c57e0179;rport From: ;tag=90e11667-e87e-de11-9f67-0015c57e0179 Call-ID: c6b51667-e87e-de11-9f67-0015c57e0179@luminos.j2sol To: Content-Length: 0 2009/08/03 15:12:29.661 0:04.354 Opal Liste...0xd5d7d910 SIP Queueing PDU "1 REGISTER <100>", transaction=z9hG4bK42212a67-e87e-de11-9f67-0015c57e0179, token=c6b51667-e87e-de11-9f67-0015c57e0179@luminos.j2sol 2009/08/03 15:12:29.661 0:04.354 Opal Liste...0xd5d7d910 Opal Transport clean up on termination 2009/08/03 15:12:29.661 0:04.354 Opal Liste...0xd5d7d910 Opal Transport Close 2009/08/03 15:12:29.661 0:04.354 Opal Liste...0xd5d7d910 Opal Deleted transport udp$10.8.8.32:5060 2009/08/03 15:12:29.661 0:04.354 Pool:0xd5cfb910 SIP Handling PDU "1 REGISTER <100>" for transaction=z9hG4bK42212a67-e87e-de11-9f67-0015c57e0179 2009/08/03 15:12:29.661 0:04.355 Pool:0xd5cfb910 SIP REGISTER transaction id=z9hG4bK42212a67-e87e-de11-9f67-0015c57e0179 proceeding. 2009/08/03 15:12:29.662 0:04.355 Opal Liste...0xd5d7d910 OpalUDP Binding to interface: 10.3.225.205:5060 2009/08/03 15:12:29.662 0:04.355 Pool:0xd5cfb910 SIP Handled PDU "1 REGISTER <100>" 2009/08/03 15:12:29.662 0:04.355 Opal Liste...0xd5d7d910 SIP Waiting for PDU on udp$10.8.8.32:5060 2009/08/03 15:12:29.662 0:04.355 Opal Liste...0xd5d7d910 SIP PDU received: rem=udp$10.8.8.32:5060,local=udp$10.3.225.205:5060,if=10.3.225.205%tun0 SIP/2.0 401 Unauthorized Date: Mon, 03 Aug 2009 22:12:31 GMT CSeq: 1 REGISTER Via: SIP/2.0/UDP 10.3.225.205:5060;branch=z9hG4bK42212a67-e87e-de11-9f67-0015c57e0179;rport From: ;tag=90e11667-e87e-de11-9f67-0015c57e0179 Call-ID: c6b51667-e87e-de11-9f67-0015c57e0179@luminos.j2sol To: ;tag=1002167201 Content-Length: 0 WWW-Authenticate: Digest realm="ccmsipline", nonce="6s2OZaTRwPOqBjRh5pmm4OXUfwRRDuXO", algorithm=MD5 2009/08/03 15:12:29.662 0:04.355 Opal Liste...0xd5d7d910 SIP Queueing PDU "1 REGISTER <401>", transaction=z9hG4bK42212a67-e87e-de11-9f67-0015c57e0179, token=c6b51667-e87e-de11-9f67-0015c57e0179@luminos.j2sol 2009/08/03 15:12:29.662 0:04.356 Opal Liste...0xd5d7d910 Opal Transport clean up on termination 2009/08/03 15:12:29.662 0:04.356 Opal Liste...0xd5d7d910 Opal Transport Close 2009/08/03 15:12:29.662 0:04.356 Pool:0xd5cfb910 SIP Handling PDU "1 REGISTER <401>" for transaction=z9hG4bK42212a67-e87e-de11-9f67-0015c57e0179 2009/08/03 15:12:29.662 0:04.356 Opal Liste...0xd5d7d910 Opal Deleted transport udp$10.8.8.32:5060 2009/08/03 15:12:29.662 0:04.356 Pool:0xd5cfb910 SIP REGISTER transaction id=z9hG4bK42212a67-e87e-de11-9f67-0015c57e0179 completed. 2009/08/03 15:12:29.662 0:04.356 Pool:0xd5cfb910 SIP Received Authentication Required response 2009/08/03 15:12:29.663 0:04.356 Pool:0xd5cfb910 SIP Found auth info for realm ccmsipline 2009/08/03 15:12:29.663 0:04.356 Pool:0xd5cfb910 SIP REGISTER transaction id=z9hG4bK16452867-e87e-de11-9f67-0015c57e0179 aborted. 2009/08/03 15:12:29.663 0:04.356 Pool:0xd5cfb910 SIP Set state Terminated_Aborted for REGISTER transaction id=z9hG4bK16452867-e87e-de11-9f67-0015c57e0179 2009/08/03 15:12:29.663 0:04.356 Pool:0xd5cfb910 OpalUDP Setting interface to 10.3.225.205%tun0 2009/08/03 15:12:29.663 0:04.356 Pool:0xd5cfb910 SIP Changing REGISTER handler from Unavailable to Subscribing, target=sip:8122425@ccm-sub2.ipt.phx.redhat.com, id=c6b51667-e87e-de11-9f67-0015c57e0179@luminos.j2sol 2009/08/03 15:12:29.664 0:04.357 Pool:0xd5cfb910 OpalMan Listener interfaces: associated transport=udp$10.3.225.205:5060 udp$10.3.225.205:5060,udp$192.168.10.176:5060 2009/08/03 15:12:29.664 0:04.358 Pool:0xd5cfb910 SIP Transaction created. 2009/08/03 15:12:29.665 0:04.358 Pool:0xd5cfb910 SIP Adding authentication information 2009/08/03 15:12:29.665 0:04.359 Pool:0xd5cfb910 SIP No SRV lookup as has explicit port number. 2009/08/03 15:12:29.665 0:04.359 Pool:0xd5cfb910 SIP Transaction remote address is udp$ccm-sub2.ipt.phx.redhat.com:5060 2009/08/03 15:12:29.666 0:04.359 Pool:0xd5cfb910 SIP Sending PDU (847 bytes) to: rem=udp$10.8.8.32:5060,local=udp$10.3.225.205:5060,if=10.3.225.205%tun0 REGISTER sip:ccm-sub2.ipt.phx.redhat.com SIP/2.0 Route: CSeq: 2 REGISTER Via: SIP/2.0/UDP 10.3.225.205:5060;branch=z9hG4bK68ce3367-e87e-de11-9f67-0015c57e0179;rport User-Agent: Ekiga/3.2.5 Authorization: Digest username="22425", realm="ccmsipline", nonce="6s2OZaTRwPOqBjRh5pmm4OXUfwRRDuXO", uri="sip:ccm-sub2.ipt.phx.redhat.com", algorithm=MD5, response="dc50e5108ebec6e00db33aa8a3ffeb88" From: ;tag=90e11667-e87e-de11-9f67-0015c57e0179 Call-ID: c6b51667-e87e-de11-9f67-0015c57e0179@luminos.j2sol 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/08/03 15:12:29.666 0:04.359 Pool:0xd5cfb910 OpalUDP Setting interface to 10.3.225.205%tun0 2009/08/03 15:12:29.666 0:04.359 Pool:0xd5cfb910 SIP Transaction timers set: retry=0.500, completion=6.000 2009/08/03 15:12:29.666 0:04.359 Pool:0xd5cfb910 SIP Handled PDU "1 REGISTER <401>" 2009/08/03 15:12:29.721 0:04.415 Opal Liste...0xd5d7d910 OpalUDP Binding to interface: 10.3.225.205:5060 2009/08/03 15:12:29.721 0:04.415 Opal Liste...0xd5d7d910 SIP Waiting for PDU on udp$10.8.8.32:5060 2009/08/03 15:12:29.722 0:04.415 Opal Liste...0xd5d7d910 SIP PDU received: rem=udp$10.8.8.32:5060,local=udp$10.3.225.205:5060,if=10.3.225.205%tun0 SIP/2.0 100 Trying Date: Mon, 03 Aug 2009 22:12:32 GMT CSeq: 2 REGISTER Via: SIP/2.0/UDP 10.3.225.205:5060;branch=z9hG4bK68ce3367-e87e-de11-9f67-0015c57e0179;rport From: ;tag=90e11667-e87e-de11-9f67-0015c57e0179 Call-ID: c6b51667-e87e-de11-9f67-0015c57e0179@luminos.j2sol To: ;tag=1002167201 Content-Length: 0 2009/08/03 15:12:29.723 0:04.416 Opal Liste...0xd5d7d910 SIP Queueing PDU "2 REGISTER <100>", transaction=z9hG4bK68ce3367-e87e-de11-9f67-0015c57e0179, token=c6b51667-e87e-de11-9f67-0015c57e0179@luminos.j2sol 2009/08/03 15:12:29.723 0:04.416 Opal Liste...0xd5d7d910 Opal Transport clean up on termination 2009/08/03 15:12:29.723 0:04.416 Opal Liste...0xd5d7d910 Opal Transport Close 2009/08/03 15:12:29.723 0:04.416 Opal Liste...0xd5d7d910 Opal Deleted transport udp$10.8.8.32:5060 2009/08/03 15:12:29.723 0:04.416 Pool:0xd5cfb910 SIP Handling PDU "2 REGISTER <100>" for transaction=z9hG4bK68ce3367-e87e-de11-9f67-0015c57e0179 2009/08/03 15:12:29.724 0:04.417 Opal Liste...0xd5d7d910 OpalUDP Binding to interface: 10.3.225.205:5060 2009/08/03 15:12:29.724 0:04.417 Pool:0xd5cfb910 SIP REGISTER transaction id=z9hG4bK68ce3367-e87e-de11-9f67-0015c57e0179 proceeding. 2009/08/03 15:12:29.724 0:04.417 Opal Liste...0xd5d7d910 SIP Waiting for PDU on udp$10.8.8.32:5060 2009/08/03 15:12:29.724 0:04.418 Pool:0xd5cfb910 SIP Handled PDU "2 REGISTER <100>" 2009/08/03 15:12:29.725 0:04.418 Opal Liste...0xd5d7d910 SIP PDU received: rem=udp$10.8.8.32:5060,local=udp$10.3.225.205:5060,if=10.3.225.205%tun0 SIP/2.0 200 OK Date: Mon, 03 Aug 2009 22:12:32 GMT CSeq: 2 REGISTER Via: SIP/2.0/UDP 10.3.225.205:5060;branch=z9hG4bK68ce3367-e87e-de11-9f67-0015c57e0179;rport From: ;tag=90e11667-e87e-de11-9f67-0015c57e0179 Call-ID: c6b51667-e87e-de11-9f67-0015c57e0179@luminos.j2sol To: ;tag=1002167201 Contact: ;q=1, ;q=0.500 Expires: 240 Content-Length: 0 2009/08/03 15:12:29.725 0:04.419 Opal Liste...0xd5d7d910 SIP Queueing PDU "2 REGISTER <200>", transaction=z9hG4bK68ce3367-e87e-de11-9f67-0015c57e0179, token=c6b51667-e87e-de11-9f67-0015c57e0179@luminos.j2sol 2009/08/03 15:12:29.725 0:04.419 Opal Liste...0xd5d7d910 Opal Transport clean up on termination 2009/08/03 15:12:29.725 0:04.419 Opal Liste...0xd5d7d910 Opal Transport Close 2009/08/03 15:12:29.726 0:04.419 Pool:0xd5cfb910 SIP Handling PDU "2 REGISTER <200>" for transaction=z9hG4bK68ce3367-e87e-de11-9f67-0015c57e0179 2009/08/03 15:12:29.726 0:04.419 Opal Liste...0xd5d7d910 Opal Deleted transport udp$10.8.8.32:5060 2009/08/03 15:12:29.726 0:04.419 Pool:0xd5cfb910 SIP REGISTER transaction id=z9hG4bK68ce3367-e87e-de11-9f67-0015c57e0179 completed. 2009/08/03 15:12:29.726 0:04.420 Pool:0xd5cfb910 SIP No User-Agent or Server fields, Product Info unknown. 2009/08/03 15:12:29.726 0:04.420 Pool:0xd5cfb910 SIP Changing REGISTER handler from Subscribing to Subscribed, target=sip:8122425@ccm-sub2.ipt.phx.redhat.com, id=c6b51667-e87e-de11-9f67-0015c57e0179@luminos.j2sol 2009/08/03 15:12:29.727 0:04.420 Pool:0xd5cfb910 OpalUDP Setting interface to 10.3.225.205%tun0 2009/08/03 15:12:29.728 0:04.421 Pool:0xd5cfb910 SIP Expiry time for REGISTER set to 240 seconds. 2009/08/03 15:12:29.728 0:04.422 Pool:0xd5cfb910 SIP Expiry time for REGISTER set to 240 seconds. 2009/08/03 15:12:29.729 0:04.422 Pool:0xd5cfb910 SIP No User-Agent or Server fields, Product Info unknown. 2009/08/03 15:12:29.730 0:04.424 Pool:0xd5cfb910 SIP Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:8122425@ccm-sub2.ipt.phx.redhat.com, id=1ec83d67-e87e-de11-9f67-0015c57e0179@luminos.j2sol 2009/08/03 15:12:29.731 0:04.424 Pool:0xd5cfb910 OpalUDP Binding to interface: 0.0.0.0:5060 2009/08/03 15:12:29.731 0:04.425 Pool:0xd5cfb910 SIP Created transport udp$10.8.8.32:5060 2009/08/03 15:12:29.732 0:04.425 Pool:0xd5cfb910 OpalUDP Started connect to 10.8.8.32:5060 2009/08/03 15:12:29.734 0:04.428 Pool:0xd5cfb910 OpalUDP Writing to interface 0 - "192.168.10.176%eth0" 2009/08/03 15:12:29.735 0:04.429 Pool:0xd5cfb910 SIP Transaction created. 2009/08/03 15:12:29.739 0:04.432 Pool:0xd5cfb910 SIP No SRV lookup as has explicit port number. 2009/08/03 15:12:29.739 0:04.433 Pool:0xd5cfb910 SIP Transaction remote address is udp$ccm-sub2.ipt.phx.redhat.com:5060 2009/08/03 15:12:29.740 0:04.434 Pool:0xd5cfb910 SIP Sending PDU (702 bytes) to: rem=udp$10.8.8.32:5060,local=udp$192.168.10.176:5060,if=192.168.10.176%eth0 SUBSCRIBE sip:8122425@ccm-sub2.ipt.phx.redhat.com SIP/2.0 Route: CSeq: 1 SUBSCRIBE Via: SIP/2.0/UDP 192.168.10.176:5060;branch=z9hG4bK9ac33e67-e87e-de11-9f67-0015c57e0179;rport User-Agent: Ekiga/3.2.5 From: "Jesse Keating" ;tag=aa813e67-e87e-de11-9f67-0015c57e0179 Call-ID: 1ec83d67-e87e-de11-9f67-0015c57e0179@luminos.j2sol 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/08/03 15:12:29.741 0:04.435 Pool:0xd5cfb910 OpalUDP Setting interface to 192.168.10.176%eth0 2009/08/03 15:12:29.742 0:04.435 Pool:0xd5cfb910 SIP Transaction timers set: retry=0.500, completion=6.000 2009/08/03 15:12:29.743 0:04.436 Pool:0xd5cfb910 OpalUDP Writing to interface 1 - "10.3.225.205%tun0" 2009/08/03 15:12:29.743 0:04.437 Pool:0xd5cfb910 SIP Transaction created. 2009/08/03 15:12:29.746 0:04.440 Pool:0xd5cfb910 SIP No SRV lookup as has explicit port number. 2009/08/03 15:12:29.748 0:04.441 Pool:0xd5cfb910 SIP Transaction remote address is udp$ccm-sub2.ipt.phx.redhat.com:5060 2009/08/03 15:12:29.751 0:04.445 Pool:0xd5cfb910 SIP Sending PDU (698 bytes) to: rem=udp$10.8.8.32:5060,local=udp$10.3.225.205:5060,if=10.3.225.205%tun0 SUBSCRIBE sip:8122425@ccm-sub2.ipt.phx.redhat.com SIP/2.0 Route: CSeq: 2 SUBSCRIBE Via: SIP/2.0/UDP 10.3.225.205:5060;branch=z9hG4bKd4004067-e87e-de11-9f67-0015c57e0179;rport User-Agent: Ekiga/3.2.5 From: "Jesse Keating" ;tag=aa813e67-e87e-de11-9f67-0015c57e0179 Call-ID: 1ec83d67-e87e-de11-9f67-0015c57e0179@luminos.j2sol 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/08/03 15:12:29.752 0:04.446 Pool:0xd5cfb910 OpalUDP Setting interface to 10.3.225.205%tun0 2009/08/03 15:12:29.753 0:04.446 Pool:0xd5cfb910 SIP Transaction timers set: retry=0.500, completion=6.000 2009/08/03 15:12:29.754 0:04.447 Pool:0xd5cfb910 SIP Handled PDU "2 REGISTER <200>" 2009/08/03 15:12:29.776 0:04.470 SIP Changing PUBLISH handler from Unavailable to Subscribing, target=sip:8122425@ccm-sub2.ipt.phx.redhat.com, id=ccc24467-e87e-de11-9f67-0015c57e0179@luminos.j2sol 2009/08/03 15:12:29.776 0:04.470 OpalUDP Binding to interface: 0.0.0.0:5060 2009/08/03 15:12:29.776 0:04.470 SIP Created transport udp$10.8.8.32:5060 2009/08/03 15:12:29.777 0:04.470 OpalUDP Started connect to 10.8.8.32:5060 2009/08/03 15:12:29.777 0:04.470 OpalUDP Writing to interface 0 - "192.168.10.176%eth0" 2009/08/03 15:12:29.777 0:04.470 SIP Expiry time for PUBLISH set to 500 seconds. 2009/08/03 15:12:29.777 0:04.470 SIP Transaction created. 2009/08/03 15:12:29.778 0:04.471 DNS SRV Lookup ccm-sub2.ipt.phx.redhat.com service _sip._udp 2009/08/03 15:12:29.812 0:04.506 Opal Liste...0xd5d7d910 OpalUDP Binding to interface: 10.3.225.205:5060 2009/08/03 15:12:29.813 0:04.506 Opal Liste...0xd5d7d910 SIP Waiting for PDU on udp$10.8.8.32:5060 2009/08/03 15:12:29.813 0:04.507 Opal Liste...0xd5d7d910 SIP PDU received: rem=udp$10.8.8.32:5060,local=udp$10.3.225.205:5060,if=10.3.225.205%tun0 SIP/2.0 503 Service Unavailable Date: Mon, 03 Aug 2009 22:12:32 GMT CSeq: 2 SUBSCRIBE Via: SIP/2.0/UDP 10.3.225.205:5060;branch=z9hG4bKd4004067-e87e-de11-9f67-0015c57e0179;rport From: "Jesse Keating" ;tag=aa813e67-e87e-de11-9f67-0015c57e0179 Retry-After: 60 Call-ID: 1ec83d67-e87e-de11-9f67-0015c57e0179@luminos.j2sol To: ;tag=1979787152 Warning: 399 "Routing failed: ccbid=4965914 socket=10.3.225.205:5060" Content-Length: 0 2009/08/03 15:12:29.814 0:04.507 Opal Liste...0xd5d7d910 SIP Queueing PDU "2 SUBSCRIBE <503>", transaction=z9hG4bKd4004067-e87e-de11-9f67-0015c57e0179, token=1ec83d67-e87e-de11-9f67-0015c57e0179@luminos.j2sol 2009/08/03 15:12:29.814 0:04.507 Opal Liste...0xd5d7d910 Opal Transport clean up on termination 2009/08/03 15:12:29.814 0:04.507 Opal Liste...0xd5d7d910 Opal Transport Close 2009/08/03 15:12:29.814 0:04.508 Pool:0xd5cfb910 SIP Handling PDU "2 SUBSCRIBE <503>" for transaction=z9hG4bKd4004067-e87e-de11-9f67-0015c57e0179 2009/08/03 15:12:29.814 0:04.507 Opal Liste...0xd5d7d910 Opal Deleted transport udp$10.8.8.32:5060 2009/08/03 15:12:29.814 0:04.508 Pool:0xd5cfb910 SIP SUBSCRIBE transaction id=z9hG4bKd4004067-e87e-de11-9f67-0015c57e0179 completed. 2009/08/03 15:12:29.815 0:04.508 Pool:0xd5cfb910 SIP Not retrying SUBSCRIBE due to error response 503 Service Unavailable 2009/08/03 15:12:29.815 0:04.508 Pool:0xd5cfb910 SIP Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:8122425@ccm-sub2.ipt.phx.redhat.com, id=1ec83d67-e87e-de11-9f67-0015c57e0179@luminos.j2sol 2009/08/03 15:12:29.815 0:04.509 Pool:0xd5cfb910 SIP SUBSCRIBE transaction id=z9hG4bK9ac33e67-e87e-de11-9f67-0015c57e0179 aborted. 2009/08/03 15:12:29.815 0:04.509 Pool:0xd5cfb910 SIP Set state Terminated_Aborted for SUBSCRIBE transaction id=z9hG4bK9ac33e67-e87e-de11-9f67-0015c57e0179 2009/08/03 15:12:29.816 0:04.509 Pool:0xd5cfb910 SIP Not retrying SUBSCRIBE due to error response 487 Request Terminated 2009/08/03 15:12:29.816 0:04.509 Pool:0xd5cfb910 SIP Changing SUBSCRIBE handler from Unsubscribed to Unsubscribed, target=sip:8122425@ccm-sub2.ipt.phx.redhat.com, id=1ec83d67-e87e-de11-9f67-0015c57e0179@luminos.j2sol 2009/08/03 15:12:29.816 0:04.509 Pool:0xd5cfb910 SIP SUBSCRIBE transaction id=z9hG4bKd4004067-e87e-de11-9f67-0015c57e0179 aborted. 2009/08/03 15:12:29.816 0:04.509 Pool:0xd5cfb910 SIP Handled PDU "2 SUBSCRIBE <503>" 2009/08/03 15:12:29.937 0:04.630 SIP No SRV record found. 2009/08/03 15:12:29.937 0:04.630 SIP Transaction remote address is udp$ccm-sub2.ipt.phx.redhat.com:5060 2009/08/03 15:12:29.938 0:04.631 SIP Sending PDU (928 bytes) to: rem=udp$10.8.8.32:5060,local=udp$192.168.10.176:5060,if=192.168.10.176%eth0 PUBLISH sip:8122425@ccm-sub2.ipt.phx.redhat.com SIP/2.0 CSeq: 3 PUBLISH Via: SIP/2.0/UDP 192.168.10.176:5060;branch=z9hG4bKe4fa4467-e87e-de11-9f67-0015c57e0179;rport User-Agent: Ekiga/3.2.5 From: ;tag=0ae94467-e87e-de11-9f67-0015c57e0179 Call-ID: ccc24467-e87e-de11-9f67-0015c57e0179@luminos.j2sol To: Contact: Expires: 500 Event: presence Content-Type: application/pidf+xml Content-Length: 389 Max-Forwards: 70 online - I'm online using Ekiga open sip:8122425@ccm-sub2.ipt.phx.redhat.com 2009/08/03 15:12:29.938 0:04.631 OpalUDP Setting interface to 192.168.10.176%eth0 2009/08/03 15:12:29.938 0:04.632 SIP Transaction timers set: retry=0.500, completion=6.000 2009/08/03 15:12:29.938 0:04.632 OpalUDP Writing to interface 1 - "10.3.225.205%tun0" 2009/08/03 15:12:29.938 0:04.632 Opal Garbage:0xe8dc6910 SIP Transaction id=z9hG4bK16452867-e87e-de11-9f67-0015c57e0179 destroyed. 2009/08/03 15:12:29.939 0:04.632 Opal Garbage:0xe8dc6910 SIP SUBSCRIBE transaction id=z9hG4bKd4004067-e87e-de11-9f67-0015c57e0179 aborted. 2009/08/03 15:12:29.939 0:04.632 Opal Garbage:0xe8dc6910 SIP Deleting SIPSubscribeHandler sip:8122425@ccm-sub2.ipt.phx.redhat.com 2009/08/03 15:12:29.939 0:04.632 Opal Garbage:0xe8dc6910 Opal Transport clean up on termination 2009/08/03 15:12:29.939 0:04.632 Opal Garbage:0xe8dc6910 Opal Transport Close 2009/08/03 15:12:29.939 0:04.633 Opal Garbage:0xe8dc6910 Opal Transport clean up on termination 2009/08/03 15:12:29.939 0:04.633 Opal Garbage:0xe8dc6910 Opal Transport Close 2009/08/03 15:12:29.939 0:04.633 Opal Garbage:0xe8dc6910 Opal Deleted transport udp$10.8.8.32:5060 2009/08/03 15:12:29.940 0:04.633 Opal Garbage:0xe8dc6910 SIP Deleted handler. 2009/08/03 15:12:29.940 0:04.633 SIP Expiry time for PUBLISH set to 500 seconds. 2009/08/03 15:12:29.940 0:04.633 SIP Transaction created. 2009/08/03 15:12:29.943 0:04.636 DNS SRV Lookup ccm-sub2.ipt.phx.redhat.com service _sip._udp 2009/08/03 15:12:30.103 0:04.796 SIP No SRV record found. 2009/08/03 15:12:30.103 0:04.796 SIP Transaction remote address is udp$ccm-sub2.ipt.phx.redhat.com:5060 2009/08/03 15:12:30.103 0:04.797 SIP Sending PDU (924 bytes) to: rem=udp$10.8.8.32:5060,local=udp$10.3.225.205:5060,if=10.3.225.205%tun0 PUBLISH sip:8122425@ccm-sub2.ipt.phx.redhat.com SIP/2.0 CSeq: 4 PUBLISH Via: SIP/2.0/UDP 10.3.225.205:5060;branch=z9hG4bKcc035e67-e87e-de11-9f67-0015c57e0179;rport User-Agent: Ekiga/3.2.5 From: ;tag=a0d55d67-e87e-de11-9f67-0015c57e0179 Call-ID: ccc24467-e87e-de11-9f67-0015c57e0179@luminos.j2sol To: Contact: Expires: 500 Event: presence Content-Type: application/pidf+xml Content-Length: 389 Max-Forwards: 70 online - I'm online using Ekiga open sip:8122425@ccm-sub2.ipt.phx.redhat.com 2009/08/03 15:12:30.104 0:04.797 OpalUDP Setting interface to 10.3.225.205%tun0 2009/08/03 15:12:30.104 0:04.797 SIP Transaction timers set: retry=0.500, completion=6.000 2009/08/03 15:12:30.161 0:04.855 Opal Liste...0xd5d7d910 OpalUDP Binding to interface: 10.3.225.205:5060 2009/08/03 15:12:30.162 0:04.855 Opal Liste...0xd5d7d910 SIP Waiting for PDU on udp$10.8.8.32:5060 2009/08/03 15:12:30.162 0:04.856 Opal Liste...0xd5d7d910 SIP PDU received: rem=udp$10.8.8.32:5060,local=udp$10.3.225.205:5060,if=10.3.225.205%tun0 SIP/2.0 500 Internal Server Error Date: Mon, 03 Aug 2009 22:12:32 GMT CSeq: 4 PUBLISH Via: SIP/2.0/UDP 10.3.225.205:5060;branch=z9hG4bKcc035e67-e87e-de11-9f67-0015c57e0179;rport From: ;tag=a0d55d67-e87e-de11-9f67-0015c57e0179 Call-ID: ccc24467-e87e-de11-9f67-0015c57e0179@luminos.j2sol To: ;tag=711892687 Content-Length: 0 2009/08/03 15:12:30.163 0:04.856 Opal Liste...0xd5d7d910 SIP Queueing PDU "4 PUBLISH <500>", transaction=z9hG4bKcc035e67-e87e-de11-9f67-0015c57e0179, token=ccc24467-e87e-de11-9f67-0015c57e0179@luminos.j2sol 2009/08/03 15:12:30.163 0:04.856 Opal Liste...0xd5d7d910 Opal Transport clean up on termination 2009/08/03 15:12:30.163 0:04.856 Opal Liste...0xd5d7d910 Opal Transport Close 2009/08/03 15:12:30.163 0:04.856 Pool:0xd5cfb910 SIP Handling PDU "4 PUBLISH <500>" for transaction=z9hG4bKcc035e67-e87e-de11-9f67-0015c57e0179 2009/08/03 15:12:30.163 0:04.857 Pool:0xd5cfb910 SIP PUBLISH transaction id=z9hG4bKcc035e67-e87e-de11-9f67-0015c57e0179 completed. 2009/08/03 15:12:30.163 0:04.857 Pool:0xd5cfb910 SIP Not retrying PUBLISH due to error response 500 Internal Server Error 2009/08/03 15:12:30.163 0:04.857 Pool:0xd5cfb910 SIP Changing PUBLISH handler from Subscribing to Unsubscribed, target=sip:8122425@ccm-sub2.ipt.phx.redhat.com, id=ccc24467-e87e-de11-9f67-0015c57e0179@luminos.j2sol 2009/08/03 15:12:30.163 0:04.857 Opal Liste...0xd5d7d910 Opal Deleted transport udp$10.8.8.32:5060 2009/08/03 15:12:30.163 0:04.857 Pool:0xd5cfb910 SIP PUBLISH transaction id=z9hG4bKe4fa4467-e87e-de11-9f67-0015c57e0179 aborted. 2009/08/03 15:12:30.164 0:04.857 Pool:0xd5cfb910 SIP Set state Terminated_Aborted for PUBLISH transaction id=z9hG4bKe4fa4467-e87e-de11-9f67-0015c57e0179 2009/08/03 15:12:30.164 0:04.857 Pool:0xd5cfb910 SIP Not retrying PUBLISH due to error response 487 Request Terminated 2009/08/03 15:12:30.164 0:04.857 Pool:0xd5cfb910 SIP Changing PUBLISH handler from Unsubscribed to Unsubscribed, target=sip:8122425@ccm-sub2.ipt.phx.redhat.com, id=ccc24467-e87e-de11-9f67-0015c57e0179@luminos.j2sol 2009/08/03 15:12:30.164 0:04.858 Pool:0xd5cfb910 SIP PUBLISH transaction id=z9hG4bKcc035e67-e87e-de11-9f67-0015c57e0179 aborted. 2009/08/03 15:12:30.164 0:04.858 Pool:0xd5cfb910 SIP Handled PDU "4 PUBLISH <500>" 2009/08/03 15:12:30.666 0:05.359 Housekeeper:0xe8d44910 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bK42212a67-e87e-de11-9f67-0015c57e0179 2009/08/03 15:12:30.754 0:05.448 Housekeeper:0xe8d44910 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bK68ce3367-e87e-de11-9f67-0015c57e0179 2009/08/03 15:12:30.816 0:05.509 Housekeeper:0xe8d44910 SIP Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bKd4004067-e87e-de11-9f67-0015c57e0179 2009/08/03 15:12:30.940 0:05.634 Opal Garbage:0xe8dc6910 SIP Transaction id=z9hG4bK9ac33e67-e87e-de11-9f67-0015c57e0179 destroyed. 2009/08/03 15:12:30.941 0:05.634 Opal Garbage:0xe8dc6910 SIP Transaction id=z9hG4bK68ce3367-e87e-de11-9f67-0015c57e0179 destroyed. 2009/08/03 15:12:30.941 0:05.634 Opal Garbage:0xe8dc6910 SIP Transaction id=z9hG4bK42212a67-e87e-de11-9f67-0015c57e0179 destroyed. 2009/08/03 15:12:30.941 0:05.635 Opal Garbage:0xe8dc6910 SIP Transaction id=z9hG4bKd4004067-e87e-de11-9f67-0015c57e0179 destroyed. 2009/08/03 15:12:30.941 0:05.635 Opal Garbage:0xe8dc6910 SIP Transaction id=z9hG4bKe4fa4467-e87e-de11-9f67-0015c57e0179 destroyed. 2009/08/03 15:12:30.942 0:05.635 Opal Garbage:0xe8dc6910 SIP PUBLISH transaction id=z9hG4bKcc035e67-e87e-de11-9f67-0015c57e0179 aborted. 2009/08/03 15:12:30.942 0:05.635 Opal Garbage:0xe8dc6910 SIP Deleting SIPPublishHandler sip:8122425@ccm-sub2.ipt.phx.redhat.com 2009/08/03 15:12:30.942 0:05.635 Opal Garbage:0xe8dc6910 Opal Transport clean up on termination 2009/08/03 15:12:30.942 0:05.635 Opal Garbage:0xe8dc6910 Opal Transport Close 2009/08/03 15:12:30.942 0:05.635 Opal Garbage:0xe8dc6910 Opal Transport clean up on termination 2009/08/03 15:12:30.942 0:05.635 Opal Garbage:0xe8dc6910 Opal Transport Close 2009/08/03 15:12:30.942 0:05.636 Opal Garbage:0xe8dc6910 Opal Deleted transport udp$10.8.8.32:5060 2009/08/03 15:12:30.943 0:05.636 Opal Garbage:0xe8dc6910 SIP Deleted handler. 2009/08/03 15:12:31.165 0:05.858 Housekeeper:0xe8d44910 SIP Set state Terminated_Success for PUBLISH transaction id=z9hG4bKcc035e67-e87e-de11-9f67-0015c57e0179 2009/08/03 15:12:31.943 0:06.636 Opal Garbage:0xe8dc6910 SIP Transaction id=z9hG4bKcc035e67-e87e-de11-9f67-0015c57e0179 destroyed. 2009/08/03 15:12:37.519 0:12.212 OpalMan Set up call from pc:* to sip:912537779069@ccm-sub2.ipt.phx.redhat.com 2009/08/03 15:12:37.519 0:12.212 Call Created Call[b70cd002d1] 2009/08/03 15:12:37.519 0:12.213 OpalMan Set up connection to "pc:*" 2009/08/03 15:12:37.520 0:12.213 OpalCon Created connection Call[b70cd002d1]-EP[j9afce06c2] 2009/08/03 15:12:37.520 0:12.213 Silence Parameters set: mode=NoSilenceDetection, threshold=0, silencedb=3200, signaldb=80, period=4800 2009/08/03 15:12:37.520 0:12.213 Silence Handler created 2009/08/03 15:12:37.520 0:12.213 Echo Canceler Handler created 2009/08/03 15:12:37.520 0:12.213 PCSS Created PC sound system connection: token="j9afce06c2" player="EKIGA" recorder="EKIGA" 2009/08/03 15:12:37.520 0:12.213 OpalCon SetPhase from UninitialisedPhase to SetUpPhase for Call[b70cd002d1]-EP[j9afce06c2] 2009/08/03 15:12:37.520 0:12.213 OpalMan OnIncoming connection Call[b70cd002d1]-EP[j9afce06c2] 2009/08/03 15:12:37.520 0:12.214 OpalCon Applying string options: 2009/08/03 15:12:37.520 0:12.214 Call GetOtherPartyConnection Call[b70cd002d1]-EP[j9afce06c2] 2009/08/03 15:12:37.520 0:12.214 OpalMan Searching for route "pc:jkeating sip:912537779069@ccm-sub2.ipt.phx.redhat.com" 2009/08/03 15:12:37.524 0:12.217 OpalMan Set up connection to "sip:912537779069@ccm-sub2.ipt.phx.redhat.com" 2009/08/03 15:12:37.525 0:12.218 OpalCon Created connection Call[b70cd002d1]-EP[be1ee36b-e87e-de11-9f67-0015c57e0179] 2009/08/03 15:12:37.525 0:12.219 RFC2833 Handler created 2009/08/03 15:12:37.526 0:12.219 RFC2833 Handler created 2009/08/03 15:12:37.527 0:12.220 SIP Created connection. 2009/08/03 15:12:37.527 0:12.221 PCSS Outgoing call routed to sip:912537779069@ccm-sub2.ipt.phx.redhat.com for Call[b70cd002d1]-EP[j9afce06c2] 2009/08/03 15:12:37.532 0:12.226 PTLib Thread high water mark set: 11 2009/08/03 15:12:37.533 0:12.226 OpalMan SetUpCall succeeded, call=Call[b70cd002d1] 2009/08/03 15:12:37.533 0:12.227 CallSetup:0xa6232910 Call OnSetUp Call[b70cd002d1]-EP[j9afce06c2] 2009/08/03 15:12:37.535 0:12.228 CallSetup:0xa6232910 SIP SetUpConnection: sip:912537779069@ccm-sub2.ipt.phx.redhat.com 2009/08/03 15:12:37.535 0:12.229 CallSetup:0xa6232910 OpalCon SetPhase from UninitialisedPhase to SetUpPhase for Call[b70cd002d1]-EP[be1ee36b-e87e-de11-9f67-0015c57e0179] 2009/08/03 15:12:37.536 0:12.229 CallSetup:0xa6232910 OpalCon Applying string options: 2009/08/03 15:12:37.536 0:12.229 CallSetup:0xa6232910 SIP Found registrar on domain ccm-sub2.ipt.phx.redhat.com, using interface 10.3.225.205%tun0 2009/08/03 15:12:37.537 0:12.230 CallSetup:0xa6232910 PWLib File handle high water mark set: 58 PUDPSocket 2009/08/03 15:12:37.538 0:12.232 CallSetup:0xa6232910 OpalUDP Binding to interface: 10.3.225.205:5060 2009/08/03 15:12:37.539 0:12.232 CallSetup:0xa6232910 SIP Created transport udp$10.8.8.32:5060 2009/08/03 15:12:37.540 0:12.233 CallSetup:0xa6232910 OpalUDP Started connect to 10.8.8.32:5060 2009/08/03 15:12:37.540 0:12.234 CallSetup:0xa6232910 SIP Updating dialog tag from "" to "be1ee36b-e87e-de11-9f67-0015c57e0179" 2009/08/03 15:12:37.568 0:12.261 CallSetup:0xa6232910 SIP INVITE transaction id=z9hG4bKcab6e96b-e87e-de11-9f67-0015c57e0179 created. 2009/08/03 15:12:37.569 0:12.262 CallSetup:0xa6232910 SIP Creating INVITE request 2009/08/03 15:12:37.572 0:12.265 CallSetup:0xa6232910 MediaFormat Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,G.722-64k,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,YUV420P,H.261,H.261-CIF,H.261-QCIF,RFC4175_YCbCr-4:2:0,theora,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz 2009/08/03 15:12:37.573 0:12.266 CallSetup:0xa6232910 MediaFormat Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,G.722-64k,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,YUV420P,H.261,H.261-CIF,H.261-QCIF,RFC4175_YCbCr-4:2:0,theora,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz 2009/08/03 15:12:37.573 0:12.266 CallSetup:0xa6232910 Call GetMediaFormats for Call[b70cd002d1]-EP[be1ee36b-e87e-de11-9f67-0015c57e0179] SpeexIETFWide-20.6k SpeexWB SpeexWide-20.6k G.711-uLaw-64k G.711-ALaw-64k GSM-06.10 SpeexIETFNarrow-8k SpeexNB SpeexWNarrow-8k 2009/08/03 15:12:37.573 0:12.266 CallSetup:0xa6232910 SIP Offering media type audio in SDP with formats SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,GSM-06.10,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k 2009/08/03 15:12:37.573 0:12.266 CallSetup:0xa6232910 Call IsMediaBypassPossible Call[b70cd002d1]-EP[be1ee36b-e87e-de11-9f67-0015c57e0179] session 1 2009/08/03 15:12:37.573 0:12.267 CallSetup:0xa6232910 OpalMan IsMediaBypassPossible: session 1 2009/08/03 15:12:37.573 0:12.267 CallSetup:0xa6232910 OpalCon IsMediaBypassPossible: default returns false 2009/08/03 15:12:37.573 0:12.267 CallSetup:0xa6232910 RTP Cannot find media session 1 2009/08/03 15:12:37.573 0:12.267 CallSetup:0xa6232910 RTP Cannot find RTP session 1 2009/08/03 15:12:37.574 0:12.267 CallSetup:0xa6232910 RTP_UDP Session 1, created with NAT flag set to 0 2009/08/03 15:12:37.574 0:12.267 CallSetup:0xa6232910 PWLib File handle high water mark set: 59 PUDPSocket 2009/08/03 15:12:37.574 0:12.268 CallSetup:0xa6232910 PWLib File handle low water mark set: 58 PUDPSocket 2009/08/03 15:12:37.574 0:12.268 CallSetup:0xa6232910 RTP_UDP Session 1 created: 10.3.225.205:5062-5063 ssrc=3098408973 2009/08/03 15:12:37.574 0:12.268 CallSetup:0xa6232910 RTP Creating new session RTP_UDP 2009/08/03 15:12:37.575 0:12.268 CallSetup:0xa6232910 RTP Found existing media session 1 2009/08/03 15:12:37.577 0:12.270 CallSetup:0xa6232910 SDP SDP not including SpeexIETFWide-20.6k as it is not a SIP transportable format 2009/08/03 15:12:37.577 0:12.270 CallSetup:0xa6232910 SDP SDP not including SpeexWide-20.6k as it is not a SIP transportable format 2009/08/03 15:12:37.577 0:12.271 CallSetup:0xa6232910 SDP SDP not including SpeexIETFNarrow-8k as it is not a SIP transportable format 2009/08/03 15:12:37.578 0:12.271 CallSetup:0xa6232910 SDP SDP not including SpeexWNarrow-8k as it is not a SIP transportable format 2009/08/03 15:12:37.578 0:12.271 CallSetup:0xa6232910 SIP Using default RTP payload [pt=101] for UserInput/RFC2833 2009/08/03 15:12:37.578 0:12.271 CallSetup:0xa6232910 SIP Using default RTP payload [pt=120] for NamedSignalEvent 2009/08/03 15:12:37.586 0:12.279 CallSetup:0xa6232910 MediaFormat Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,G.722-64k,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,YUV420P,H.261,H.261-CIF,H.261-QCIF,RFC4175_YCbCr-4:2:0,theora,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz 2009/08/03 15:12:37.587 0:12.281 CallSetup:0xa6232910 MediaFormat Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,G.722-64k,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,YUV420P,H.261,H.261-CIF,H.261-QCIF,RFC4175_YCbCr-4:2:0,theora,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz 2009/08/03 15:12:37.588 0:12.282 CallSetup:0xa6232910 Call GetMediaFormats for Call[b70cd002d1]-EP[be1ee36b-e87e-de11-9f67-0015c57e0179] SpeexIETFWide-20.6k SpeexWB SpeexWide-20.6k G.711-uLaw-64k G.711-ALaw-64k GSM-06.10 SpeexIETFNarrow-8k SpeexNB SpeexWNarrow-8k 2009/08/03 15:12:37.588 0:12.282 CallSetup:0xa6232910 SIP No media formats of type video, not adding SDP 2009/08/03 15:12:37.589 0:12.283 CallSetup:0xa6232910 SIP No SRV lookup as has explicit port number. 2009/08/03 15:12:37.589 0:12.283 CallSetup:0xa6232910 SIP Transaction remote address is udp$ccm-sub2.ipt.phx.redhat.com:5060 2009/08/03 15:12:37.592 0:12.285 CallSetup:0xa6232910 SIP Sending PDU (1148 bytes) to: rem=udp$10.8.8.32:5060,local=udp$10.3.225.205:5060,if=10.3.225.205%tun0 INVITE sip:912537779069@ccm-sub2.ipt.phx.redhat.com SIP/2.0 Route: Date: Mon, 03 Aug 2009 22:12:37 GMT CSeq: 1 INVITE Via: SIP/2.0/UDP 10.3.225.205:5060;branch=z9hG4bKcab6e96b-e87e-de11-9f67-0015c57e0179;rport User-Agent: Ekiga/3.2.5 From: "Jesse Keating" ;tag=be1ee36b-e87e-de11-9f67-0015c57e0179 Call-ID: 7058e36b-e87e-de11-9f67-0015c57e0179@luminos.j2sol To: Contact: Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING Content-Type: application/sdp Content-Length: 458 Max-Forwards: 70 v=0 o=- 1249337557 1 IN IP4 10.3.225.205 s=Opal SIP Session c=IN IP4 10.3.225.205 t=0 0 m=audio 5062 RTP/AVP 113 0 8 3 112 101 120 c=IN IP4 10.3.225.205 a=sendrecv a=rtpmap:113 Speex/16000/1 a=fmtp:113 sr=16000,mode=any a=rtpmap:0 PCMU/8000/1 a=rtpmap:8 PCMA/8000/1 a=rtpmap:3 gsm/8000/1 a=rtpmap:112 Speex/8000/1 a=fmtp:112 sr=8000,mode=any a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16,32,36 a=rtpmap:120 NSE/8000 a=fmtp:120 192-193 2009/08/03 15:12:37.593 0:12.286 CallSetup:0xa6232910 OpalUDP Setting interface to 10.3.225.205%tun0 2009/08/03 15:12:37.593 0:12.287 CallSetup:0xa6232910 SIP Transaction timers set: retry=0.500, completion=1:00.000 2009/08/03 15:12:37.594 0:12.287 CallSetup:0xa6232910 OpalCon OnSetUpConnectionCall[b70cd002d1]-EP[be1ee36b-e87e-de11-9f67-0015c57e0179] 2009/08/03 15:12:37.594 0:12.287 CallSetup:0xa6232910 OpalEP OnSetUpConnection Call[b70cd002d1]-EP[be1ee36b-e87e-de11-9f67-0015c57e0179] 2009/08/03 15:12:37.594 0:12.288 CallSetup:0xa6232910 PTLib Destroyed thread 0x1fd0730 CallSetup:0xa6232910(id = 7ff9a6232910) 2009/08/03 15:12:37.764 0:12.458 Opal Liste...0xd5d7d910 OpalUDP Binding to interface: 10.3.225.205:5060 2009/08/03 15:12:37.765 0:12.458 Opal Liste...0xd5d7d910 SIP Waiting for PDU on udp$10.8.8.32:5060 2009/08/03 15:12:37.765 0:12.459 Opal Liste...0xd5d7d910 SIP PDU received: rem=udp$10.8.8.32:5060,local=udp$10.3.225.205:5060,if=10.3.225.205%tun0 SIP/2.0 100 Trying Date: Mon, 03 Aug 2009 22:12:40 GMT CSeq: 1 INVITE Via: SIP/2.0/UDP 10.3.225.205:5060;branch=z9hG4bKcab6e96b-e87e-de11-9f67-0015c57e0179;rport From: "Jesse Keating" ;tag=be1ee36b-e87e-de11-9f67-0015c57e0179 Call-ID: 7058e36b-e87e-de11-9f67-0015c57e0179@luminos.j2sol To: Allow-Events: presence Content-Length: 0 2009/08/03 15:12:37.766 0:12.459 Opal Liste...0xd5d7d910 SIP Queueing PDU "1 INVITE <100>", transaction=z9hG4bKcab6e96b-e87e-de11-9f67-0015c57e0179, token=be1ee36b-e87e-de11-9f67-0015c57e0179 2009/08/03 15:12:37.766 0:12.459 Opal Liste...0xd5d7d910 PWLib File handle low water mark set: 44 Thread unblock pipe 2009/08/03 15:12:37.766 0:12.459 Opal Liste...0xd5d7d910 Opal Transport clean up on termination 2009/08/03 15:12:37.766 0:12.459 Opal Liste...0xd5d7d910 Opal Transport Close 2009/08/03 15:12:37.766 0:12.460 Opal Liste...0xd5d7d910 Opal Deleted transport udp$10.8.8.32:5060 2009/08/03 15:12:37.767 0:12.460 Opal Liste...0xd5d7d910 OpalUDP Binding to interface: 10.3.225.205:5060 2009/08/03 15:12:37.767 0:12.460 Opal Liste...0xd5d7d910 SIP Waiting for PDU on udp$10.8.8.32:5060 2009/08/03 15:12:37.768 0:12.461 Opal Liste...0xd5d7d910 SIP PDU received: rem=udp$10.8.8.32:5060,local=udp$10.3.225.205:5060,if=10.3.225.205%tun0 SIP/2.0 503 Service Unavailable Date: Mon, 03 Aug 2009 22:12:40 GMT CSeq: 1 INVITE Via: SIP/2.0/UDP 10.3.225.205:5060;branch=z9hG4bKcab6e96b-e87e-de11-9f67-0015c57e0179;rport From: "Jesse Keating" ;tag=be1ee36b-e87e-de11-9f67-0015c57e0179 Call-ID: 7058e36b-e87e-de11-9f67-0015c57e0179@luminos.j2sol To: ;tag=1195529277 Reason: Q.850;cause=63 Warning: 399 "Routing failed: ccbid=4965973 socket=10.3.225.205:5060" Allow-Events: presence Content-Length: 0 2009/08/03 15:12:37.768 0:12.462 Opal Liste...0xd5d7d910 SIP Queueing PDU "1 INVITE <503>", transaction=z9hG4bKcab6e96b-e87e-de11-9f67-0015c57e0179, token=be1ee36b-e87e-de11-9f67-0015c57e0179 2009/08/03 15:12:37.768 0:12.462 Opal Liste...0xd5d7d910 ThreadPool Allocated worker thread by group Id be1ee36b-e87e-de11-9f67-0015c57e0179 2009/08/03 15:12:37.768 0:12.462 Opal Liste...0xd5d7d910 Opal Transport clean up on termination 2009/08/03 15:12:37.768 0:12.462 Opal Liste...0xd5d7d910 Opal Transport Close 2009/08/03 15:12:37.769 0:12.462 Opal Liste...0xd5d7d910 Opal Deleted transport udp$10.8.8.32:5060 2009/08/03 15:12:37.766 0:12.460 Pool:0xa6232910 SIP Handling PDU "1 INVITE <100>" for transaction=z9hG4bKcab6e96b-e87e-de11-9f67-0015c57e0179 2009/08/03 15:12:37.770 0:12.464 Pool:0xa6232910 SIP No User-Agent or Server fields, Product Info unknown. 2009/08/03 15:12:37.770 0:12.464 Pool:0xa6232910 SIP INVITE transaction id=z9hG4bKcab6e96b-e87e-de11-9f67-0015c57e0179 proceeding. 2009/08/03 15:12:37.771 0:12.464 Pool:0xa6232910 SIP Received Trying response 2009/08/03 15:12:37.771 0:12.465 Pool:0xa6232910 OpalCon SetPhase from SetUpPhase to ProceedingPhase for Call[b70cd002d1]-EP[be1ee36b-e87e-de11-9f67-0015c57e0179] 2009/08/03 15:12:37.771 0:12.465 Pool:0xa6232910 OpalMan OnProceeding Call[b70cd002d1]-EP[be1ee36b-e87e-de11-9f67-0015c57e0179] 2009/08/03 15:12:37.772 0:12.465 Pool:0xa6232910 Call OnProceeding Call[b70cd002d1]-EP[be1ee36b-e87e-de11-9f67-0015c57e0179] 2009/08/03 15:12:37.772 0:12.465 Pool:0xa6232910 SIP Handled PDU "1 INVITE <100>" 2009/08/03 15:12:37.772 0:12.465 Pool:0xa6232910 SIP Handling PDU "1 INVITE <503>" for transaction=z9hG4bKcab6e96b-e87e-de11-9f67-0015c57e0179 2009/08/03 15:12:37.774 0:12.467 Pool:0xa6232910 SIP Sending PDU (487 bytes) to: rem=udp$10.8.8.32:5060,local=udp$10.3.225.205:5060,if=10.3.225.205%tun0 ACK sip:912537779069@ccm-sub2.ipt.phx.redhat.com SIP/2.0 Route: CSeq: 1 ACK Via: SIP/2.0/UDP 10.3.225.205:5060;branch=z9hG4bKcab6e96b-e87e-de11-9f67-0015c57e0179;rport From: "Jesse Keating" ;tag=be1ee36b-e87e-de11-9f67-0015c57e0179 Call-ID: 7058e36b-e87e-de11-9f67-0015c57e0179@luminos.j2sol To: ;tag=1195529277 Content-Length: 0 Max-Forwards: 70 2009/08/03 15:12:37.775 0:12.468 Pool:0xa6232910 OpalUDP Setting interface to 10.3.225.205%tun0 2009/08/03 15:12:37.775 0:12.468 Pool:0xa6232910 SIP INVITE transaction id=z9hG4bKcab6e96b-e87e-de11-9f67-0015c57e0179 completed. 2009/08/03 15:12:37.775 0:12.469 Pool:0xa6232910 OpalCon SetPhase from ProceedingPhase to ReleasingPhase for Call[b70cd002d1]-EP[be1ee36b-e87e-de11-9f67-0015c57e0179] 2009/08/03 15:12:37.775 0:12.469 Pool:0xa6232910 OpalCon Releasing Call[b70cd002d1]-EP[be1ee36b-e87e-de11-9f67-0015c57e0179] 2009/08/03 15:12:37.776 0:12.469 Pool:0xa6232910 OpalCon Call end reason for Call[b70cd002d1]-EP[be1ee36b-e87e-de11-9f67-0015c57e0179] set to EndedByQ931Cause 2009/08/03 15:12:37.776 0:12.469 Pool:0xa6232910 PWLib File handle high water mark set: 61 Thread unblock pipe 2009/08/03 15:12:37.776 0:12.469 Pool:0xa6232910 PTLib Thread high water mark set: 12 2009/08/03 15:12:37.776 0:12.470 OnRelease:0xa61f1910 SIP OnReleased: Call[b70cd002d1]-EP[be1ee36b-e87e-de11-9f67-0015c57e0179], phase = ReleasingPhase 2009/08/03 15:12:37.776 0:12.470 OnRelease:0xa61f1910 OpalCon SetPhase from ReleasingPhase to ReleasingPhase for Call[b70cd002d1]-EP[be1ee36b-e87e-de11-9f67-0015c57e0179] 2009/08/03 15:12:37.777 0:12.470 OnRelease:0xa61f1910 OpalCon Media streams closed. 2009/08/03 15:12:37.777 0:12.471 OnRelease:0xa61f1910 SIP Awaiting forked INVITE transaction completion, id=z9hG4bKcab6e96b-e87e-de11-9f67-0015c57e0179 2009/08/03 15:12:37.777 0:12.471 OnRelease:0xa61f1910 OpalCon SetPhase from ReleasingPhase to ReleasedPhase for Call[b70cd002d1]-EP[be1ee36b-e87e-de11-9f67-0015c57e0179] 2009/08/03 15:12:37.778 0:12.471 OnRelease:0xa61f1910 OpalCon OnReleased Call[b70cd002d1]-EP[be1ee36b-e87e-de11-9f67-0015c57e0179] 2009/08/03 15:12:37.778 0:12.471 OnRelease:0xa61f1910 OpalEP OnReleased Call[b70cd002d1]-EP[be1ee36b-e87e-de11-9f67-0015c57e0179] 2009/08/03 15:12:37.778 0:12.471 OnRelease:0xa61f1910 OpalMan OnReleased Call[b70cd002d1]-EP[be1ee36b-e87e-de11-9f67-0015c57e0179] 2009/08/03 15:12:37.779 0:12.472 OnRelease:0xa61f1910 Call OnReleased Call[b70cd002d1]-EP[be1ee36b-e87e-de11-9f67-0015c57e0179] 2009/08/03 15:12:37.779 0:12.472 OnRelease:0xa61f1910 OpalCon SetPhase from SetUpPhase to ReleasingPhase for Call[b70cd002d1]-EP[j9afce06c2] 2009/08/03 15:12:37.779 0:12.472 OnRelease:0xa61f1910 OpalCon Releasing Call[b70cd002d1]-EP[j9afce06c2] 2009/08/03 15:12:37.779 0:12.473 OnRelease:0xa61f1910 OpalCon Call end reason for Call[b70cd002d1]-EP[j9afce06c2] set to EndedByQ931Cause 2009/08/03 15:12:37.779 0:12.473 OnRelease:0xa61f1910 OpalCon OnReleased Call[b70cd002d1]-EP[j9afce06c2] 2009/08/03 15:12:37.780 0:12.473 OnRelease:0xa61f1910 OpalEP OnReleased Call[b70cd002d1]-EP[j9afce06c2] 2009/08/03 15:12:37.780 0:12.473 OnRelease:0xa61f1910 OpalMan OnReleased Call[b70cd002d1]-EP[j9afce06c2] 2009/08/03 15:12:37.780 0:12.473 OnRelease:0xa61f1910 Call OnReleased Call[b70cd002d1]-EP[j9afce06c2] 2009/08/03 15:12:37.780 0:12.474 OnRelease:0xa61f1910 OpalCon Media streams closed. 2009/08/03 15:12:37.780 0:12.474 OnRelease:0xa61f1910 OpalCon Media streams closed. 2009/08/03 15:12:37.781 0:12.474 OnRelease:0xa61f1910 OpalCon OnRelease thread completed for Call[b70cd002d1]-EP[be1ee36b-e87e-de11-9f67-0015c57e0179] 2009/08/03 15:12:37.781 0:12.474 OnRelease:0xa61f1910 PTLib Destroyed thread 0x7ff9a004db10 OnRelease:0xa61f1910(id = 7ff9a61f1910) 2009/08/03 15:12:37.781 0:12.475 Pool:0xa6232910 SIP Handled PDU "1 INVITE <503>" (ekiga:23111): Gdk-CRITICAL **: gdk_x11_atom_to_xatom_for_display: assertion `atom != GDK_NONE' failed 2009/08/03 15:12:37.894 0:12.587 AEScheduler Removing Event incoming_call_sound from queue 2009/08/03 15:12:37.894 0:12.587 AEScheduler Removing Event ring_tone_sound from queue 2009/08/03 15:12:37.894 0:12.587 OpalMan OnClearedCall Call[b70cd002d1] from "pc:jkeating" to "sip:912537779069@ccm-sub2.ipt.phx.redhat.com" 2009/08/03 15:12:37.894 0:12.587 OPAL Attempting to close recording on call b70cd002d1 when not recording. 2009/08/03 15:12:37.945 0:12.638 Opal Garbage:0xe8dc6910 PCSS Deleted PC sound system connection. 2009/08/03 15:12:37.945 0:12.638 Opal Garbage:0xe8dc6910 OpalCon Connection Call[b70cd002d1]-EP[j9afce06c2] destroyed. 2009/08/03 15:12:38.782 0:13.476 Housekeeper:0xe8d44910 SIP Set state Terminated_Success for INVITE transaction id=z9hG4bKcab6e96b-e87e-de11-9f67-0015c57e0179 2009/08/03 15:12:38.945 0:13.639 Opal Garbage:0xe8dc6910 RTP Closing session 1 2009/08/03 15:12:38.945 0:13.639 Opal Garbage:0xe8dc6910 RTP Deleting session 1 2009/08/03 15:12:38.945 0:13.639 Opal Garbage:0xe8dc6910 RTP_UDP Session 1, Shutting down read. 2009/08/03 15:12:38.946 0:13.639 Opal Garbage:0xe8dc6910 RTP_UDP Session 1, Shutting down write. 2009/08/03 15:12:38.946 0:13.639 Opal Garbage:0xe8dc6910 SIP Transaction id=z9hG4bKcab6e96b-e87e-de11-9f67-0015c57e0179 destroyed. 2009/08/03 15:12:38.946 0:13.640 Opal Garbage:0xe8dc6910 Opal Transport clean up on termination 2009/08/03 15:12:38.947 0:13.640 Opal Garbage:0xe8dc6910 Opal Transport Close 2009/08/03 15:12:38.947 0:13.640 Opal Garbage:0xe8dc6910 Opal Transport clean up on termination 2009/08/03 15:12:38.947 0:13.640 Opal Garbage:0xe8dc6910 Opal Transport Close 2009/08/03 15:12:38.947 0:13.640 Opal Garbage:0xe8dc6910 Opal Deleted transport udp$10.8.8.32:5060 2009/08/03 15:12:38.947 0:13.640 Opal Garbage:0xe8dc6910 SIP Deleted connection. 2009/08/03 15:12:38.949 0:13.642 Opal Garbage:0xe8dc6910 OpalCon Connection Call[b70cd002d1]-EP[be1ee36b-e87e-de11-9f67-0015c57e0179] destroyed. 2009/08/03 15:12:39.964 0:14.657 Call Call[b70cd002d1] destroyed. 2009/08/03 15:12:40.816 0:15.510 Network In...0xe8d85910 PWLib File handle low water mark set: 58 PUDPSocket 2009/08/03 15:12:44.038 0:18.731 PTLib Destroyed thread 0x1f52260 AudioEventScheduler:0xe8e48910(id = 7ff9e8e48910) 2009/08/03 15:12:44.039 0:18.732 PTLib Destroyed thread 0x1f52888 PreviewManager(id = 0) 2009/08/03 15:12:44.128 0:18.821 PTLib Destroyed thread 0x1f53b30 GMVideoOutputManager:0xe8e07910(id = 7ff9e8e07910) 2009/08/03 15:12:44.128 0:18.821 PTLib Destroyed thread 0x1f51950 VideoPreviewManager:0xe8e89910(id = 7ff9e8e89910) 2009/08/03 15:12:44.136 0:18.830 Network In...0xe8d85910 IfaceMon Finished interface monitor thread. 2009/08/03 15:12:44.146 0:18.840 WaitForTermination on 140711330076944 finished 2009/08/03 15:12:44.147 0:18.840 PTLib Destroyed thread 0x20019d0 Network Interface Monitor:0xe8d85910(id = 7ff9e8d85910) 2009/08/03 15:12:44.157 0:18.851 WaitForTermination on 140711329810704 finished 2009/08/03 15:12:44.158 0:18.851 PTLib Destroyed thread 0x2001860 Housekeeper:0xe8d44910(id = 7ff9e8d44910)