Login
[x]
Log in using an account from:
Fedora Account System
Red Hat Associate
Red Hat Customer
Or login using a Red Hat Bugzilla account
Forgot Password
Login:
Hide Forgot
Create an Account
Red Hat Bugzilla – Attachment 662556 Details for
Bug 885769
ekiga continues to send OPTIONS for disabled accounts
[?]
New
Simple Search
Advanced Search
My Links
Browse
Requests
Reports
Current State
Search
Tabular reports
Graphical reports
Duplicates
Other Reports
User Changes
Plotly Reports
Bug Status
Bug Severity
Non-Defaults
|
Product Dashboard
Help
Page Help!
Bug Writing Guidelines
What's new
Browser Support Policy
5.0.4.rh83 Release notes
FAQ
Guides index
User guide
Web Services
Contact
Legal
This site requires JavaScript to be enabled to function correctly, please enable it.
Yet another debug output of sending options while disabled
ekiga-sleepwalk.out (text/plain), 175.70 KB, created by
Stuart D Gathman
on 2012-12-12 18:18:52 UTC
(
hide
)
Description:
Yet another debug output of sending options while disabled
Filename:
MIME Type:
Creator:
Stuart D Gathman
Created:
2012-12-12 18:18:52 UTC
Size:
175.70 KB
patch
obsolete
> on Unix Linux (3.6.9-2.fc17.i686-i686) with PTLib (v2.10.9 (svn:28574)) at 2012/12/12 13:14:11.811 >2012/12/12 13:14:11.820 0:00.008 PTLib Maximum per-process file handles is 1024 >2012/12/12 13:14:11.820 0:00.009 PLUGIN Enumerating plugin directory /usr/lib/ptlib-2.10.9/ >2012/12/12 13:14:11.820 0:00.009 PLUGIN Enumerating plugin directory /usr/lib/ptlib-2.10.9/devices/ >2012/12/12 13:14:11.821 0:00.009 PLUGIN Enumerating plugin directory /usr/lib/ptlib-2.10.9/devices/videoinput/ >2012/12/12 13:14:11.821 0:00.009 UDLL opening /usr/lib/ptlib-2.10.9/devices/videoinput/v4l2_pwplugin.so >2012/12/12 13:14:11.829 0:00.017 PLUGIN Enumerating plugin directory /usr/lib/ptlib-2.10.9/devices/sound/ >2012/12/12 13:14:11.829 0:00.018 UDLL opening /usr/lib/ptlib-2.10.9/devices/sound/pulse_pwplugin.so >2012/12/12 13:14:11.855 0:00.044 UDLL opening /usr/lib/ptlib-2.10.9/devices/sound/alsa_pwplugin.so >2012/12/12 13:14:11.864 0:00.052 PLUGIN Enumerating plugin directory /usr/lib/opal-3.10.9/ >2012/12/12 13:14:11.864 0:00.053 PLUGIN Enumerating plugin directory /usr/lib/opal-3.10.9/codecs/ >2012/12/12 13:14:11.864 0:00.053 PLUGIN Enumerating plugin directory /usr/lib/opal-3.10.9/codecs/video/ >2012/12/12 13:14:11.865 0:00.053 UDLL opening /usr/lib/opal-3.10.9/codecs/video/h261_vic_ptplugin.so >2012/12/12 13:14:11.866 0:00.054 UDLL opening /usr/lib/opal-3.10.9/codecs/video/theora_ptplugin.so >2012/12/12 13:14:11.868 0:00.056 PLUGIN Enumerating plugin directory /usr/lib/opal-3.10.9/codecs/audio/ >2012/12/12 13:14:11.868 0:00.056 UDLL opening /usr/lib/opal-3.10.9/codecs/audio/g7221_ptplugin.so >2012/12/12 13:14:11.869 0:00.057 UDLL opening /usr/lib/opal-3.10.9/codecs/audio/g726_ptplugin.so >2012/12/12 13:14:11.869 0:00.058 UDLL opening /usr/lib/opal-3.10.9/codecs/audio/iLBC_ptplugin.so >2012/12/12 13:14:11.871 0:00.059 UDLL opening /usr/lib/opal-3.10.9/codecs/audio/ima_adpcm_ptplugin.so >2012/12/12 13:14:11.871 0:00.059 UDLL opening /usr/lib/opal-3.10.9/codecs/audio/g722_ptplugin.so >2012/12/12 13:14:11.871 0:00.060 UDLL opening /usr/lib/opal-3.10.9/codecs/audio/speex_ptplugin.so >2012/12/12 13:14:11.873 0:00.061 UDLL opening /usr/lib/opal-3.10.9/codecs/audio/g7222_ptplugin.so >2012/12/12 13:14:11.880 0:00.069 UDLL opening /usr/lib/opal-3.10.9/codecs/audio/gsmamrcodec_ptplugin.so >2012/12/12 13:14:11.881 0:00.070 UDLL opening /usr/lib/opal-3.10.9/codecs/audio/lpc10_ptplugin.so >2012/12/12 13:14:11.882 0:00.070 UDLL opening /usr/lib/opal-3.10.9/codecs/audio/gsm0610_ptplugin.so >2012/12/12 13:14:11.883 0:00.071 OpalPlugin Using default handler for plugin codec h261_vic_ptplugin >2012/12/12 13:14:11.884 0:00.072 OpalPlugin Creating new media format H.261 >2012/12/12 13:14:11.886 0:00.074 OpalPlugin Creating H.261 plugin capability >2012/12/12 13:14:11.886 0:00.075 OpalPlugin Creating H.261 plugin capability >2012/12/12 13:14:11.887 0:00.075 OpalPlugin Using default handler for plugin codec theora_ptplugin >2012/12/12 13:14:11.887 0:00.075 Plugin Started logging. >2012/12/12 13:14:11.887 0:00.075 OpalPlugin Creating new media format theora >2012/12/12 13:14:11.888 0:00.076 OpalPlugin Using default handler for plugin codec g7221_ptplugin >2012/12/12 13:14:11.888 0:00.076 OpalPlugin Creating new media format G.722.1-24k >2012/12/12 13:14:11.889 0:00.077 OpalPlugin Creating new media format G.722.1-32k >2012/12/12 13:14:11.889 0:00.078 OpalPlugin Using default handler for plugin codec g726_ptplugin >2012/12/12 13:14:11.889 0:00.078 OpalPlugin Creating new media format G.726-40k >2012/12/12 13:14:11.890 0:00.078 OpalPlugin Creating new media format G.726-32k >2012/12/12 13:14:11.890 0:00.079 OpalPlugin Creating new media format G.726-24k >2012/12/12 13:14:11.891 0:00.079 OpalPlugin Creating new media format G.726-16k >2012/12/12 13:14:11.892 0:00.080 OpalPlugin Using default handler for plugin codec iLBC_ptplugin >2012/12/12 13:14:11.892 0:00.080 OpalPlugin Creating new media format iLBC >2012/12/12 13:14:11.893 0:00.081 OpalPlugin Creating new media format iLBC-13k3 >2012/12/12 13:14:11.896 0:00.084 OpalPlugin Creating new media format iLBC-15k2 >2012/12/12 13:14:11.897 0:00.085 OpalPlugin Using default handler for plugin codec ima_adpcm_ptplugin >2012/12/12 13:14:11.897 0:00.085 OpalPlugin Creating new media format MS-IMA-ADPCM >2012/12/12 13:14:11.897 0:00.086 OpalPlugin Using default handler for plugin codec g722_ptplugin >2012/12/12 13:14:11.898 0:00.086 OpalPlugin Creating new media format G.722-64k >2012/12/12 13:14:11.898 0:00.086 OpalPlugin Using default handler for plugin codec speex_ptplugin >2012/12/12 13:14:11.898 0:00.087 OpalPlugin Creating new media format SpeexIETFNarrow-5.95k >2012/12/12 13:14:11.899 0:00.087 OpalPlugin Creating new media format SpeexIETFNarrow-8k >2012/12/12 13:14:11.901 0:00.089 OpalPlugin Creating new media format SpeexIETFNarrow-11k >2012/12/12 13:14:11.902 0:00.090 OpalPlugin Creating new media format SpeexIETFNarrow-15k >2012/12/12 13:14:11.903 0:00.092 OpalPlugin Creating new media format SpeexIETFNarrow-18.2k >2012/12/12 13:14:11.904 0:00.093 OpalPlugin Creating new media format SpeexIETFNarrow-24.6k >2012/12/12 13:14:11.905 0:00.094 OpalPlugin Creating new media format SpeexIETFWide-20.6k >2012/12/12 13:14:11.907 0:00.095 OpalPlugin Creating new media format SpeexWNarrow-8k >2012/12/12 13:14:11.908 0:00.096 OpalPlugin Creating new media format SpeexWide-20.6k >2012/12/12 13:14:11.909 0:00.098 OpalPlugin Creating new media format SpeexNB >2012/12/12 13:14:11.910 0:00.099 OpalPlugin Creating new media format SpeexWB >2012/12/12 13:14:11.912 0:00.100 OpalPlugin Using default handler for plugin codec g7222_ptplugin >2012/12/12 13:14:11.912 0:00.100 OpalPlugin Creating new media format G.722.2 >2012/12/12 13:14:11.913 0:00.101 OpalPlugin Using default handler for plugin codec gsmamrcodec_ptplugin >2012/12/12 13:14:11.913 0:00.102 OpalPlugin Creating new media format GSM-AMR >2012/12/12 13:14:11.914 0:00.102 OpalPlugin Using default handler for plugin codec lpc10_ptplugin >2012/12/12 13:14:11.914 0:00.103 OpalPlugin Creating new media format LPC-10 >2012/12/12 13:14:11.915 0:00.103 OpalPlugin Using default handler for plugin codec gsm0610_ptplugin >2012/12/12 13:14:11.915 0:00.103 OpalPlugin Creating new media format GSM-06.10 >2012/12/12 13:14:11.915 0:00.104 OpalPlugin Creating new media format MS-GSM >2012/12/12 13:14:11.920 0:00.109 PWLib File handle high water mark set: 10 Thread unblock pipe >2012/12/12 13:14:11.921 0:00.109 PTLib No permission to set priority level 4 >2012/12/12 13:14:11.921 0:00.109 PTLib Thread high water mark set: 2 >2012/12/12 13:14:11.921 0:00.109 PWLib File handle high water mark set: 12 Thread unblock pipe >2012/12/12 13:14:11.921 0:00.110 PTLib No permission to set priority level 4 >2012/12/12 13:14:11.921 0:00.110 PTLib Thread high water mark set: 3 >2012/12/12 13:14:11.925 0:00.114 PWLib File handle high water mark set: 17 Thread unblock pipe >2012/12/12 13:14:11.930 0:00.118 PTLib No permission to set priority level 4 >2012/12/12 13:14:11.930 0:00.118 PTLib Thread high water mark set: 4 >2012/12/12 13:14:11.930 0:00.119 HalManager_dbus Initialising HAL Manager >2012/12/12 13:14:11.937 0:00.125 HalManager_dbus Populating device list >2012/12/12 13:14:11.939 0:00.127 HalManager_dbus Populating full device list failed - The name org.freedesktop.Hal was not provided by any .service files >2012/12/12 13:14:11.939 0:00.128 HalManager_dbus Populating interface list >2012/12/12 13:14:11.941 0:00.129 HalManager_dbus Populating full interface list failed - Method "getDevices" with signature "" on interface "org.freedesktop.NetworkManager" doesn't exist > >2012/12/12 13:14:11.942 0:00.130 PV4L2Plugin Detecting V4L2 devices >2012/12/12 13:14:11.942 0:00.131 PV4L2Plugin detected device metadata at /sys/class/video4linux/ >2012/12/12 13:14:12.041 0:00.229 PWLib File handle high water mark set: 23 Thread unblock pipe >2012/12/12 13:14:12.041 0:00.229 PTLib Thread high water mark set: 5 >2012/12/12 13:14:12.041 0:00.229 OpalMan Created manager. >2012/12/12 13:14:12.041 0:00.230 PV4L2Plugin Detecting V4L2 devices >2012/12/12 13:14:12.041 0:00.230 PV4L2Plugin detected device metadata at /sys/class/video4linux/ >2012/12/12 13:14:12.059 0:00.248 OpalMan Attached endpoint with prefix pc >2012/12/12 13:14:12.060 0:00.248 OpalEP Created endpoint: pc >2012/12/12 13:14:12.060 0:00.248 LocalEP Created endpoint. >2012/12/12 13:14:12.072 0:00.261 PCSS Created PC sound system endpoint. >Players: >alsa_output.usb-0d8c_Generic_USB_Audio_Device-00-Device.analog-stereo >alsa_output.pci-0000_00_1f.5.analog-stereo >Null Audio >Default >PulseAudio >EKIGA >*.wav >Generic USB Audio Device >Intel ICH5 >Intel ICH5 (1) >Recorders: >Null Audio >Default >PulseAudio >alsa_input.usb-0d8c_Generic_USB_Audio_Device-00-Device.analog-mono >alsa_input.usb-_USB2.0_1.3MP_UVC_Camera-02-Camera.analog-mono >alsa_input.pci-0000_00_1f.5.analog-stereo >USB2.0 1.3MP UVC Camera >EKIGA >*.wav >Generic USB Audio Device >Intel ICH5 >Intel ICH5 (1) >Intel ICH5 (2) >Intel ICH5 (3) > >2012/12/12 13:14:12.077 0:00.265 OPAL SetMediaFormatOrder() >2012/12/12 13:14:12.077 0:00.265 OPAL SetMediaFormatMask() >2012/12/12 13:14:12.078 0:00.267 OpalMan Attached endpoint with prefix sip >2012/12/12 13:14:12.078 0:00.267 OpalEP Created endpoint: sip >2012/12/12 13:14:12.079 0:00.267 PWLib File handle high water mark set: 24 PUDPSocket >2012/12/12 13:14:12.079 0:00.267 IfaceMon Initial interface list: >127.0.0.1 <00-00-00-00-00-00> (lo) >192.168.9.34 <00-0C-F1-D9-97-B4> (em1) > >2012/12/12 13:14:12.080 0:00.268 PTLIB Opened NetLink socket >2012/12/12 13:14:12.080 0:00.268 PWLib File handle high water mark set: 28 Thread unblock pipe >2012/12/12 13:14:12.080 0:00.269 PTLib Thread high water mark set: 6 >2012/12/12 13:14:12.080 0:00.269 PWLib File handle high water mark set: 30 Thread unblock pipe >2012/12/12 13:14:12.080 0:00.269 PTLib No permission to set priority level 4 >2012/12/12 13:14:12.081 0:00.269 PTLib Thread high water mark set: 7 >2012/12/12 13:14:12.081 0:00.269 OpalMan Attached endpoint with prefix sips >2012/12/12 13:14:12.081 0:00.269 SIP Created endpoint. >2012/12/12 13:14:12.082 0:00.270 PWLib File handle high water mark set: 31 PUDPSocket >2012/12/12 13:14:12.082 0:00.270 MonSock Created socket bundle for all interfaces. >2012/12/12 13:14:12.082 0:00.270 PWLib File handle high water mark set: 32 PUDPSocket >2012/12/12 13:14:12.082 0:00.270 MonSock Created bundled UDP socket 192.168.9.34:5060 >2012/12/12 13:14:12.082 0:00.270 PWLib File handle high water mark set: 34 Thread unblock pipe >2012/12/12 13:14:12.082 0:00.271 PTLib Thread high water mark set: 8 >2012/12/12 13:14:12.082 0:00.271 PTLib No permission to set priority level 4 >2012/12/12 13:14:12.082 0:00.271 OpalMan Added route "sip:.*=pc:*" >2012/12/12 13:14:12.083 0:00.271 OpalMan Added route "pc:.*=sip:<da>" >2012/12/12 13:14:12.091 0:00.279 OpalMan Attached endpoint with prefix h323 >2012/12/12 13:14:12.094 0:00.283 OpalEP Created endpoint: h323 >2012/12/12 13:14:12.095 0:00.283 OpalMan Attached endpoint with prefix h323s >2012/12/12 13:14:12.095 0:00.283 H323 Created endpoint. >2012/12/12 13:14:12.095 0:00.283 PWLib File handle high water mark set: 35 PTCPSocket >2012/12/12 13:14:12.095 0:00.283 PWLib File handle high water mark set: 37 Thread unblock pipe >2012/12/12 13:14:12.095 0:00.284 Network In...0xb26beb40 IfaceMon Started interface monitor thread. >2012/12/12 13:14:12.095 0:00.284 Opal Liste...0xb263cb40 Listen Started listening thread on udp$*:5060 >2012/12/12 13:14:12.096 0:00.284 PTLib Thread high water mark set: 9 >2012/12/12 13:14:12.096 0:00.284 OpalMan Added route "h323:.*=pc:<db>" >2012/12/12 13:14:12.096 0:00.284 OpalMan Added route "pc:.*=h323:<da>" >2012/12/12 13:14:12.097 0:00.286 Opal Liste...0xb25fbb40 Listen Started listening thread on tcp$*:1720 >2012/12/12 13:14:12.097 0:00.286 Opal Liste...0xb25fbb40 Listen Waiting on socket accept on tcp$*:1720 >2012/12/12 13:14:12.098 0:00.287 PWLib File handle high water mark set: 38 PUDPSocket >2012/12/12 13:14:12.099 0:00.287 OpalMan Added presentity for SIP_Presentity >2012/12/12 13:14:12.099 0:00.287 Created presentity for sip:1264941142@prod.voipdnsservers.com >2012/12/12 13:14:12.099 0:00.288 OpalMan Added presentity for SIP_Presentity >2012/12/12 13:14:12.099 0:00.288 Created presentity for sip:469269@sip.diamondcard.us >2012/12/12 13:14:12.100 0:00.288 OpalMan Added presentity for SIP_Presentity >2012/12/12 13:14:12.100 0:00.288 Created presentity for sip:SDGathman@ekiga.net >2012/12/12 13:14:12.130 0:00.318 MediaFormat Removing codecs SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,theora,H.261,PCM-16S-48kHz,PCM-16S-32kHz,PCM-16S-16kHz,PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,UserInput/RFC2833,NamedSignalEvent,YUV420P,MSRP,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling >2012/12/12 13:14:12.131 0:00.319 OPAL SetMediaFormatMask(G.722-64k,G.722.1-24k,G.722.1-32k,G.722.2,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,iLBC,iLBC-13k3,iLBC-15k2,RFC4175_YCbCr-4:2:0,T.140,MSRP,SIP-IM,NamedSignalEvent) >2012/12/12 13:14:12.131 0:00.320 OPAL SetMediaFormatOrder(SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,theora,H.261,PCM-16S-48kHz,PCM-16S-32kHz,PCM-16S-16kHz,PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,UserInput/RFC2833,NamedSignalEvent,YUV420P,MSRP,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling) >2012/12/12 13:14:12.151 0:00.339 MediaFormat Removing codecs SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,theora,H.261,PCM-16S-48kHz,PCM-16S-32kHz,PCM-16S-16kHz,PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,UserInput/RFC2833,NamedSignalEvent,YUV420P,MSRP,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling >2012/12/12 13:14:12.152 0:00.341 OPAL SetMediaFormatMask(G.722-64k,G.722.1-24k,G.722.1-32k,G.722.2,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,iLBC,iLBC-13k3,iLBC-15k2,RFC4175_YCbCr-4:2:0,T.140,MSRP,SIP-IM,NamedSignalEvent) >2012/12/12 13:14:12.153 0:00.341 OPAL SetMediaFormatOrder(SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,theora,H.261,PCM-16S-48kHz,PCM-16S-32kHz,PCM-16S-16kHz,PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,UserInput/RFC2833,NamedSignalEvent,YUV420P,MSRP,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling) >2012/12/12 13:14:12.157 0:00.345 OpalConfBridge maximum_video_tx_bitrate set to 64kb/s >2012/12/12 13:14:12.183 0:00.372 Ekiga Nat binding delay set to 10 >2012/12/12 13:14:12.184 0:00.373 Ekiga Sent its own presence (publish) for sip:1264941142@prod.voipdnsservers.com: available, note I'm available using Ekiga >2012/12/12 13:14:12.184 0:00.373 Ekiga Sent its own presence (publish) for sip:469269@sip.diamondcard.us: available, note I'm available using Ekiga >2012/12/12 13:14:12.184 0:00.373 Ekiga Sent its own presence (publish) for sip:SDGathman@ekiga.net: available, note I'm available using Ekiga >2012/12/12 13:14:12.210 0:00.398 PWLib File handle high water mark set: 39 Thread unblock pipe >2012/12/12 13:14:12.210 0:00.399 Ekiga Started STUN detector >2012/12/12 13:14:12.210 0:00.399 PTLib Thread high water mark set: 10 >2012/12/12 13:14:12.211 0:00.399 StunDetector:0xb25bab40 PWLib File handle high water mark set: 40 PUDPSocket >2012/12/12 13:14:12.316 0:00.505 StunDetector:0xb25bab40 PWLib File handle high water mark set: 45 PUDPSocket >2012/12/12 13:14:12.342 0:00.531 AudioOutputCore Detected Device: PulseAudio (PTLIB/Pulse) >2012/12/12 13:14:12.343 0:00.531 AudioOutputCore Detected Device: alsa_output.usb-0d8c_Generic_USB_Audio_Device-00-Device.analog-stereo (PTLIB/Pulse) >2012/12/12 13:14:12.343 0:00.531 AudioOutputCore Detected Device: alsa_output.pci-0000_00_1f.5.analog-stereo (PTLIB/Pulse) >2012/12/12 13:14:12.343 0:00.531 AudioOutputCore Detected Device: Default (PTLIB/ALSA) >2012/12/12 13:14:12.343 0:00.531 AudioOutputCore Detected Device: Generic USB Audio Device (PTLIB/ALSA) >2012/12/12 13:14:12.343 0:00.531 AudioOutputCore Detected Device: Intel ICH5 (PTLIB/ALSA) >2012/12/12 13:14:12.343 0:00.531 AudioOutputCore Detected Device: Intel ICH5 (1) (PTLIB/ALSA) >2012/12/12 13:14:12.492 0:00.681 AudioOutputCore Detected Device: PulseAudio (PTLIB/Pulse) >2012/12/12 13:14:12.493 0:00.681 AudioOutputCore Detected Device: alsa_output.usb-0d8c_Generic_USB_Audio_Device-00-Device.analog-stereo (PTLIB/Pulse) >2012/12/12 13:14:12.493 0:00.681 AudioOutputCore Detected Device: alsa_output.pci-0000_00_1f.5.analog-stereo (PTLIB/Pulse) >2012/12/12 13:14:12.493 0:00.681 AudioOutputCore Detected Device: Default (PTLIB/ALSA) >2012/12/12 13:14:12.493 0:00.681 AudioOutputCore Detected Device: Generic USB Audio Device (PTLIB/ALSA) >2012/12/12 13:14:12.493 0:00.681 AudioOutputCore Detected Device: Intel ICH5 (PTLIB/ALSA) >2012/12/12 13:14:12.493 0:00.682 AudioOutputCore Detected Device: Intel ICH5 (1) (PTLIB/ALSA) >2012/12/12 13:14:12.526 0:00.715 AudioInputCore Detected Device: Null Audio (PTLIB/NullAudio) >2012/12/12 13:14:12.527 0:00.715 AudioInputCore Detected Device: PulseAudio (PTLIB/Pulse) >2012/12/12 13:14:12.527 0:00.715 AudioInputCore Detected Device: alsa_input.usb-0d8c_Generic_USB_Audio_Device-00-Device.analog-mono (PTLIB/Pulse) >2012/12/12 13:14:12.527 0:00.715 AudioInputCore Detected Device: alsa_input.usb-_USB2.0_1.3MP_UVC_Camera-02-Camera.analog-mono (PTLIB/Pulse) >2012/12/12 13:14:12.527 0:00.715 AudioInputCore Detected Device: alsa_input.pci-0000_00_1f.5.analog-stereo (PTLIB/Pulse) >2012/12/12 13:14:12.527 0:00.715 AudioInputCore Detected Device: Default (PTLIB/ALSA) >2012/12/12 13:14:12.527 0:00.715 AudioInputCore Detected Device: USB2.0 1.3MP UVC Camera (PTLIB/ALSA) >2012/12/12 13:14:12.527 0:00.715 AudioInputCore Detected Device: Generic USB Audio Device (PTLIB/ALSA) >2012/12/12 13:14:12.527 0:00.715 AudioInputCore Detected Device: Intel ICH5 (PTLIB/ALSA) >2012/12/12 13:14:12.527 0:00.715 AudioInputCore Detected Device: Intel ICH5 (1) (PTLIB/ALSA) >2012/12/12 13:14:12.527 0:00.716 AudioInputCore Detected Device: Intel ICH5 (2) (PTLIB/ALSA) >2012/12/12 13:14:12.527 0:00.716 AudioInputCore Detected Device: Intel ICH5 (3) (PTLIB/ALSA) >2012/12/12 13:14:12.553 0:00.741 PV4L2Plugin Detecting V4L2 devices >2012/12/12 13:14:12.553 0:00.742 PV4L2Plugin detected device metadata at /sys/class/video4linux/ >2012/12/12 13:14:12.572 0:00.761 VidInputCore Detected Device: Moving Logo (Moving Logo/Moving Logo) >2012/12/12 13:14:12.573 0:00.761 VidInputCore Detected Device: USB2.0 1.3MP UVC Camera (PTLIB/V4L2) >2012/12/12 13:14:12.857 0:01.045 VideoOutputCoreConfBridge Updating video view >2012/12/12 13:14:12.859 0:01.047 VideoOutputCoreConfBridge Updating zoom >2012/12/12 13:14:12.859 0:01.048 VideoOutputCoreConfBridge Updating Video Settings >2012/12/12 13:14:12.862 0:01.051 VideoOutputCoreConfBridge Updating Video Settings >2012/12/12 13:14:12.863 0:01.051 VideoOutputCoreConfBridge Updating Video Settings >2012/12/12 13:14:12.863 0:01.051 VideoOutputCoreConfBridge Updating Video Settings >2012/12/12 13:14:12.863 0:01.051 VidInputCoreConfBridge Updating preview size and fps >2012/12/12 13:14:12.863 0:01.051 VidInputCore Setting new preview config: 176x144/30 >2012/12/12 13:14:12.866 0:01.055 VidInputCoreConfBridge Updating preview size and fps >2012/12/12 13:14:12.866 0:01.055 VidInputCore Setting new preview config: 176x144/30 >2012/12/12 13:14:12.870 0:01.059 PV4L2Plugin Detecting V4L2 devices >2012/12/12 13:14:12.871 0:01.059 PV4L2Plugin detected device metadata at /sys/class/video4linux/ >2012/12/12 13:14:12.887 0:01.076 VidInputCore Detected Device: Moving Logo (Moving Logo/Moving Logo) >2012/12/12 13:14:12.888 0:01.076 VidInputCore Detected Device: USB2.0 1.3MP UVC Camera (PTLIB/V4L2) >2012/12/12 13:14:12.888 0:01.076 VidInputCoreConfBridge Updating device >2012/12/12 13:14:12.888 0:01.076 VidInputCore Setting device: USB2.0 1.3MP UVC Camera (PTLIB/V4L2) >2012/12/12 13:14:12.888 0:01.076 GMVideoInputManager_ptlib Setting Device USB2.0 1.3MP UVC Camera (PTLIB/V4L2) >2012/12/12 13:14:12.888 0:01.077 PV4L2Plugin Detecting V4L2 devices >2012/12/12 13:14:12.888 0:01.077 PV4L2Plugin detected device metadata at /sys/class/video4linux/ >2012/12/12 13:14:12.905 0:01.094 VidInputCore Detected Device: Moving Logo (Moving Logo/Moving Logo) >2012/12/12 13:14:12.906 0:01.094 VidInputCore Detected Device: USB2.0 1.3MP UVC Camera (PTLIB/V4L2) >2012/12/12 13:14:12.906 0:01.094 VidInputCoreConfBridge Updating device >2012/12/12 13:14:12.906 0:01.094 VidInputCore Setting device: USB2.0 1.3MP UVC Camera (PTLIB/V4L2) >2012/12/12 13:14:12.906 0:01.094 GMVideoInputManager_ptlib Setting Device USB2.0 1.3MP UVC Camera (PTLIB/V4L2) >2012/12/12 13:14:12.906 0:01.095 PV4L2Plugin Detecting V4L2 devices >2012/12/12 13:14:12.906 0:01.095 PV4L2Plugin detected device metadata at /sys/class/video4linux/ >2012/12/12 13:14:12.923 0:01.112 VidInputCore Detected Device: Moving Logo (Moving Logo/Moving Logo) >2012/12/12 13:14:12.924 0:01.112 VidInputCore Detected Device: USB2.0 1.3MP UVC Camera (PTLIB/V4L2) >2012/12/12 13:14:12.924 0:01.112 VidInputCoreConfBridge Updating device >2012/12/12 13:14:12.924 0:01.112 VidInputCore Setting device: USB2.0 1.3MP UVC Camera (PTLIB/V4L2) >2012/12/12 13:14:12.924 0:01.112 GMVideoInputManager_ptlib Setting Device USB2.0 1.3MP UVC Camera (PTLIB/V4L2) >2012/12/12 13:14:12.925 0:01.114 VidInputCoreConfBridge Updating image >2012/12/12 13:14:12.929 0:01.117 AudioOutputCore Detected Device: PulseAudio (PTLIB/Pulse) >2012/12/12 13:14:12.929 0:01.117 AudioOutputCore Detected Device: alsa_output.usb-0d8c_Generic_USB_Audio_Device-00-Device.analog-stereo (PTLIB/Pulse) >2012/12/12 13:14:12.929 0:01.117 AudioOutputCore Detected Device: alsa_output.pci-0000_00_1f.5.analog-stereo (PTLIB/Pulse) >2012/12/12 13:14:12.929 0:01.117 AudioOutputCore Detected Device: Default (PTLIB/ALSA) >2012/12/12 13:14:12.929 0:01.117 AudioOutputCore Detected Device: Generic USB Audio Device (PTLIB/ALSA) >2012/12/12 13:14:12.929 0:01.117 AudioOutputCore Detected Device: Intel ICH5 (PTLIB/ALSA) >2012/12/12 13:14:12.929 0:01.117 AudioOutputCore Detected Device: Intel ICH5 (1) (PTLIB/ALSA) >2012/12/12 13:14:12.929 0:01.118 AudioOutputCoreConfBridge Set device to Pulse/alsa_output.pci-0000_00_1f.5.analog-stereo >2012/12/12 13:14:12.929 0:01.118 AudioOutputCore Setting device[0]: alsa_output.pci-0000_00_1f.5.analog-stereo (PTLIB/Pulse) >2012/12/12 13:14:12.929 0:01.118 GMAudioOutputManager_ptlib Setting Device[0] alsa_output.pci-0000_00_1f.5.analog-stereo (PTLIB/Pulse) >2012/12/12 13:14:12.929 0:01.118 AudioOutputCoreConfBridge Updating device >2012/12/12 13:14:12.930 0:01.118 AudioOutputCore Setting device[1]: alsa_output.usb-0d8c_Generic_USB_Audio_Device-00-Device.analog-stereo (PTLIB/Pulse) >2012/12/12 13:14:12.930 0:01.118 GMAudioOutputManager_ptlib Setting Device[1] alsa_output.usb-0d8c_Generic_USB_Audio_Device-00-Device.analog-stereo (PTLIB/Pulse) >2012/12/12 13:14:12.943 0:01.131 AudioInputCore Detected Device: Null Audio (PTLIB/NullAudio) >2012/12/12 13:14:12.943 0:01.132 AudioInputCore Detected Device: PulseAudio (PTLIB/Pulse) >2012/12/12 13:14:12.943 0:01.132 AudioInputCore Detected Device: alsa_input.usb-0d8c_Generic_USB_Audio_Device-00-Device.analog-mono (PTLIB/Pulse) >2012/12/12 13:14:12.943 0:01.132 AudioInputCore Detected Device: alsa_input.usb-_USB2.0_1.3MP_UVC_Camera-02-Camera.analog-mono (PTLIB/Pulse) >2012/12/12 13:14:12.944 0:01.132 AudioInputCore Detected Device: alsa_input.pci-0000_00_1f.5.analog-stereo (PTLIB/Pulse) >2012/12/12 13:14:12.944 0:01.132 AudioInputCore Detected Device: Default (PTLIB/ALSA) >2012/12/12 13:14:12.944 0:01.132 AudioInputCore Detected Device: USB2.0 1.3MP UVC Camera (PTLIB/ALSA) >2012/12/12 13:14:12.944 0:01.132 AudioInputCore Detected Device: Generic USB Audio Device (PTLIB/ALSA) >2012/12/12 13:14:12.944 0:01.132 AudioInputCore Detected Device: Intel ICH5 (PTLIB/ALSA) >2012/12/12 13:14:12.944 0:01.132 AudioInputCore Detected Device: Intel ICH5 (1) (PTLIB/ALSA) >2012/12/12 13:14:12.944 0:01.132 AudioInputCore Detected Device: Intel ICH5 (2) (PTLIB/ALSA) >2012/12/12 13:14:12.944 0:01.132 AudioInputCore Detected Device: Intel ICH5 (3) (PTLIB/ALSA) >2012/12/12 13:14:12.944 0:01.132 AudioInputCore Setting device: alsa_input.pci-0000_00_1f.5.analog-stereo (PTLIB/Pulse) >2012/12/12 13:14:12.944 0:01.132 GMAudioInputManager_ptlib Setting Device alsa_input.pci-0000_00_1f.5.analog-stereo (PTLIB/Pulse) >2012/12/12 13:14:12.944 0:01.132 AudioInputCore Set device to Pulse/alsa_input.pci-0000_00_1f.5.analog-stereo >2012/12/12 13:14:13.098 0:01.286 Ekiga version 4.0.0 >2012/12/12 13:14:13.098 0:01.287 PTLIB version 2.10.9 >2012/12/12 13:14:13.098 0:01.287 OPAL version 3.10.9 >2012/12/12 13:14:13.098 0:01.287 Accelerated rendering support enabled >2012/12/12 13:14:13.098 0:01.287 DBUS support enabled >2012/12/12 13:14:13.099 0:01.287 GConf support enabled >2012/12/12 13:14:17.220 0:05.409 StunDetector:0xb25bab40 PWLib File handle low water mark set: 40 PUDPSocket >2012/12/12 13:14:17.321 0:05.510 StunDetector:0xb25bab40 OPAL STUN server "stun.ekiga.net" replies Port Restricted NAT, external IP 24.248.44.159 >2012/12/12 13:14:17.322 0:05.510 StunDetector:0xb25bab40 PTLib Thread ended: name="StunDetector:0xb25bab40", real=5.111, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%) >2012/12/12 13:14:17.523 0:05.711 Listen Stopping listening thread on udp$*:5060 >2012/12/12 13:14:17.523 0:05.711 MonSock Closed UDP socket 0x8e09040 >2012/12/12 13:14:17.523 0:05.711 Opal Liste...0xb263cb40 Listen UDP read error. >2012/12/12 13:14:17.523 0:05.712 Opal Liste...0xb263cb40 PWLib File handle low water mark set: 32 PTextFile >2012/12/12 13:14:17.523 0:05.712 Opal Liste...0xb263cb40 PTLib Thread ended: name="Opal Listener:0xb263cb40", real=5.427, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%) >2012/12/12 13:14:17.544 0:05.732 MonSock Deleting UDP socket 0x8e09040 >2012/12/12 13:14:17.544 0:05.732 Housekeeper:0xb267db40 Ekiga Stopped STUN detector >2012/12/12 13:14:17.545 0:05.733 PWLib File handle low water mark set: 31 PUDPSocket >2012/12/12 13:14:17.545 0:05.733 MonSock Created socket bundle for all interfaces. >2012/12/12 13:14:17.642 0:05.831 MonSock Created bundled UDP socket via STUN, internal=192.168.9.34:5060, external=24.248.44.159:1024 >2012/12/12 13:14:17.642 0:05.831 PTLib No permission to set priority level 4 >2012/12/12 13:14:17.643 0:05.831 Listen Stopping listening thread on tcp$*:1720 >2012/12/12 13:14:17.643 0:05.831 Opal Liste...0xb25fbb40 PTLib Thread ended: name="Opal Listener:0xb25fbb40", real=5.546, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%) >2012/12/12 13:14:17.644 0:05.832 Opal Liste...0xb263cb40 Listen Started listening thread on udp$*:5060 >2012/12/12 13:14:17.644 0:05.833 Opal Liste...0xb25fbb40 Listen Started listening thread on tcp$*:1720 >2012/12/12 13:14:17.644 0:05.833 Opal Liste...0xb25fbb40 Listen Waiting on socket accept on tcp$*:1720 >2012/12/12 13:15:40.312 1:28.500 subscriber:0xabd03b40 OpalPres 'sip:1264941142@prod.voipdnsservers.com' opening. >2012/12/12 13:15:40.312 1:28.501 subscriber:0xabd03b40 SIPPres Using sip:1264941142@prod.voipdnsservers.com as presence server for sip:1264941142@prod.voipdnsservers.com >2012/12/12 13:15:40.313 1:28.501 subscriber:0xabd03b40 PWLib File handle high water mark set: 47 Thread unblock pipe >2012/12/12 13:15:40.313 1:28.501 subscriber:0xabd03b40 PTLib Thread high water mark set: 11 >2012/12/12 13:15:40.313 1:28.501 subscriber:0xabd03b40 OpalPres Creating presentity command 'SIP_Presentity21SIPWatcherInfoCommand' >2012/12/12 13:15:40.313 1:28.501 subscriber:0xabd03b40 SIP Start REGISTER > aor=1264941142@prod.voipdnsservers.com > remote=prod.voipdnsservers.com > local= > contact= > proxy= > authID=1264941142 > realm= > expire=3600 > restore=30 > minRetry=default > maxRetry=default >compatibility=FullyCompliant >2012/12/12 13:15:40.314 1:28.502 subscriber:0xabd03b40 SIP Constructed REGISTER handler for sip:1264941142@prod.voipdnsservers.com >2012/12/12 13:15:40.314 1:28.503 subscriber:0xabd03b40 SIP Executing state change to Subscribing for REGISTER handler, target=sip:1264941142@prod.voipdnsservers.com, id=a026a09a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:40.314 1:28.503 subscriber:0xabd03b40 SIP Changing REGISTER handler from Unavailable to Subscribing, target=sip:1264941142@prod.voipdnsservers.com, id=a026a09a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:40.315 1:28.503 subscriber:0xabd03b40 DNS SRV Lookup "_sip._udp.prod.voipdnsservers.com" >2012/12/12 13:15:40.315 1:28.503 PThreadObj:0xabcc2b40 OpalPres Command thread started >2012/12/12 13:15:40.315 1:28.504 PThreadObj:0xabcc2b40 SIPPres 'sip:1264941142@prod.voipdnsservers.com' sending subscribe for own presence.watcherinfo >2012/12/12 13:15:40.315 1:28.504 PThreadObj:0xabcc2b40 SIP Start SUBSCRIBE > eventPackage=presence.winfo > aor=sip:1264941142@prod.voipdnsservers.com > remote=sip:1264941142@prod.voipdnsservers.com;transport=tcp > local=sip:1264941142@prod.voipdnsservers.com > contact= > proxy= > authID=1264941142 > realm= > expire=300 > restore=30 > minRetry=default > maxRetry=default >2012/12/12 13:15:40.317 1:28.505 PThreadObj:0xabcc2b40 SIP Constructed SUBSCRIBE handler for sip:1264941142@prod.voipdnsservers.com;OPAL-local-id=sip:1264941142%40prod.voipdnsservers.com >2012/12/12 13:15:40.317 1:28.505 PThreadObj:0xabcc2b40 SIP Executing state change to Subscribing for SUBSCRIBE handler, target=sip:1264941142@prod.voipdnsservers.com;OPAL-local-id=sip:1264941142%40prod.voipdnsservers.com, id=ee91a09a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:40.317 1:28.505 PThreadObj:0xabcc2b40 SIP Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:1264941142@prod.voipdnsservers.com;OPAL-local-id=sip:1264941142%40prod.voipdnsservers.com, id=ee91a09a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:40.317 1:28.505 PThreadObj:0xabcc2b40 DNS SRV Lookup "_sip._tcp.prod.voipdnsservers.com" >2012/12/12 13:15:40.334 1:28.522 PThreadObj:0xabcc2b40 SIP Attempting SRV record entry 0: 208.73.144.71:5060 >2012/12/12 13:15:40.336 1:28.524 PThreadObj:0xabcc2b40 SIP No compatible listener to create transport for tcp$208.73.144.71:5060 >2012/12/12 13:15:40.336 1:28.524 PThreadObj:0xabcc2b40 SIP Changing SUBSCRIBE handler from Subscribing to Unavailable, target=sip:1264941142@prod.voipdnsservers.com;OPAL-local-id=sip:1264941142%40prod.voipdnsservers.com, id=ee91a09a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:40.336 1:28.524 PThreadObj:0xabcc2b40 SIP Retrying SUBSCRIBE after 30 seconds. >2012/12/12 13:15:40.336 1:28.524 subscriber:0xabd03b40 SIP Attempting SRV record entry 0: 208.73.144.71:5060 >2012/12/12 13:15:40.336 1:28.525 subscriber:0xabd03b40 OpalUDP Binding to interface: 0.0.0.0:5060 >2012/12/12 13:15:40.337 1:28.525 subscriber:0xabd03b40 PWLib File handle high water mark set: 48 PUDPSocket >2012/12/12 13:15:40.336 1:28.525 subscriber:0xabd03b40 SIP Created transport udp$208.73.144.71:5060<if=udp$*:5060> >2012/12/12 13:15:40.337 1:28.525 subscriber:0xabd03b40 OpalUDP Started connect to 208.73.144.71:5060 >2012/12/12 13:15:40.337 1:28.525 subscriber:0xabd03b40 OpalUDP Writing to interface 0 - "192.168.9.34%em1" >2012/12/12 13:15:40.434 1:28.623 subscriber:0xabd03b40 OpalMan Listener interfaces: associated transport=udp$24.248.44.159:1024 > udp$24.248.44.159:1024,udp$24.248.44.159:5060,udp$192.168.9.34:5060 >2012/12/12 13:15:40.436 1:28.624 subscriber:0xabd03b40 SIP Transaction created. >2012/12/12 13:15:40.438 1:28.626 subscriber:0xabd03b40 DNS SRV Lookup "_sip._udp.prod.voipdnsservers.com" >2012/12/12 13:15:40.438 1:28.627 subscriber:0xabd03b40 SIP Attempting SRV record entry 0: 208.73.144.71:5060 >2012/12/12 13:15:40.438 1:28.627 subscriber:0xabd03b40 SIP Transaction remote address is udp$208.73.144.71:5060 >2012/12/12 13:15:40.439 1:28.627 subscriber:0xabd03b40 SIP Sending PDU (656 bytes) to: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >REGISTER sip:prod.voipdnsservers.com SIP/2.0 >CSeq: 1 REGISTER >Via: SIP/2.0/UDP 24.248.44.159:1024;branch=z9hG4bK54fbb29a-f542-e211-8519-000cf1d997b4;rport >User-Agent: Ekiga/4.0.0 >From: <sip:1264941142@prod.voipdnsservers.com>;tag=4432a09a-f542-e211-8519-000cf1d997b4 >Call-ID: a026a09a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:1264941142@prod.voipdnsservers.com> >Contact: <sip:1264941142@24.248.44.159:1024>;q=1, <sip:1264941142@24.248.44.159:5060>;q=0.667, <sip:1264941142@192.168.9.34:5060>;q=0.334 >Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK >Expires: 3600 >Content-Length: 0 >Max-Forwards: 70 > > >2012/12/12 13:15:40.439 1:28.628 subscriber:0xabd03b40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:15:40.440 1:28.628 subscriber:0xabd03b40 SIP Transaction timers set: retry=0.500, completion=6.000 >2012/12/12 13:15:40.440 1:28.628 subscriber:0xabd03b40 PTLib Thread ended: name="subscriber:0xabd03b40", real=0.128, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%) >2012/12/12 13:15:40.503 1:28.691 Opal Liste...0xb263cb40 OpalUDP Binding to interface: 192.168.9.34:5060 >2012/12/12 13:15:40.503 1:28.691 Opal Liste...0xb263cb40 SIP Waiting for PDU on udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:15:40.503 1:28.692 Opal Liste...0xb263cb40 SIP PDU received: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >SIP/2.0 401 Unauthorized >CSeq: 1 REGISTER >Via: SIP/2.0/UDP 24.248.44.159:1024;received=24.248.44.159;branch=z9hG4bK54fbb29a-f542-e211-8519-000cf1d997b4;rport=1024 >From: <sip:1264941142@prod.voipdnsservers.com>;tag=4432a09a-f542-e211-8519-000cf1d997b4 >Call-ID: a026a09a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:1264941142@prod.voipdnsservers.com>;tag=578983519-1355336140473 >Content-Length: 0 >WWW-Authenticate: DIGEST qop="auth",nonce="BroadWorksXhams5709TrhqyoeBW",realm="nextiva.com",algorithm=MD5 > > >2012/12/12 13:15:40.504 1:28.692 Opal Liste...0xb263cb40 SIP Queueing PDU "1 REGISTER <401>", transaction=z9hG4bK54fbb29a-f542-e211-8519-000cf1d997b4, token=a026a09a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:40.504 1:28.693 Opal Liste...0xb263cb40 PWLib File handle high water mark set: 49 Thread unblock pipe >2012/12/12 13:15:40.505 1:28.693 Opal Liste...0xb263cb40 PTLib No permission to set priority level 3 >2012/12/12 13:15:40.505 1:28.693 Opal Liste...0xb263cb40 PTLib Thread high water mark set: 12 >2012/12/12 13:15:40.505 1:28.693 Opal Liste...0xb263cb40 Opal Transport clean up on termination >2012/12/12 13:15:40.505 1:28.693 Opal Liste...0xb263cb40 Opal Transport Close >2012/12/12 13:15:40.505 1:28.693 Opal Liste...0xb263cb40 PWLib File handle high water mark set: 50 PUDPSocket >2012/12/12 13:15:40.505 1:28.693 Opal Liste...0xb263cb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:15:40.506 1:28.694 Pool:0xabc81b40 SIP Handling PDU "1 REGISTER <401>" for transaction=z9hG4bK54fbb29a-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:40.506 1:28.694 Pool:0xabc81b40 SIP REGISTER transaction id=z9hG4bK54fbb29a-f542-e211-8519-000cf1d997b4 completing. >2012/12/12 13:15:40.506 1:28.694 Pool:0xabc81b40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:15:40.506 1:28.694 Pool:0xabc81b40 SIP Received Authentication Required response for z9hG4bK54fbb29a-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:40.506 1:28.694 Pool:0xabc81b40 HTTP Authentication contains qop-options auth >2012/12/12 13:15:40.506 1:28.695 Pool:0xabc81b40 SIP Creating authentication credentials of user "1264941142" for realm "nextiva.com" >2012/12/12 13:15:40.506 1:28.695 Pool:0xabc81b40 SIP Auth realm set to nextiva.com >2012/12/12 13:15:40.507 1:28.695 Pool:0xabc81b40 PWLib File handle low water mark set: 39 PUDPSocket >2012/12/12 13:15:40.508 1:28.697 Pool:0xabc81b40 OpalMan Listener interfaces: associated transport=udp$24.248.44.159:1024 > udp$24.248.44.159:1024,udp$24.248.44.159:5060,udp$192.168.9.34:5060 >2012/12/12 13:15:40.510 1:28.698 Pool:0xabc81b40 SIP Transaction created. >2012/12/12 13:15:40.512 1:28.700 Pool:0xabc81b40 HTTP Adding authentication information >2012/12/12 13:15:40.512 1:28.700 Pool:0xabc81b40 DNS SRV Lookup "_sip._udp.prod.voipdnsservers.com" >2012/12/12 13:15:40.512 1:28.700 Pool:0xabc81b40 SIP Attempting SRV record entry 0: 208.73.144.71:5060 >2012/12/12 13:15:40.512 1:28.701 Pool:0xabc81b40 SIP Transaction remote address is udp$208.73.144.71:5060 >2012/12/12 13:15:40.512 1:28.701 Pool:0xabc81b40 SIP Sending PDU (925 bytes) to: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >REGISTER sip:prod.voipdnsservers.com SIP/2.0 >CSeq: 2 REGISTER >Via: SIP/2.0/UDP 24.248.44.159:1024;branch=z9hG4bK0837be9a-f542-e211-8519-000cf1d997b4;rport >User-Agent: Ekiga/4.0.0 >Authorization: Digest username="1264941142", realm="nextiva.com", nonce="BroadWorksXhams5709TrhqyoeBW", uri="sip:prod.voipdnsservers.com", algorithm=MD5, response="aa6d8e778584695be8328079de0e8e2d", cnonce="f87ebd9a-f542-e211-8519-000cf1d997b4", nc=00000001, qop=auth >From: <sip:1264941142@prod.voipdnsservers.com>;tag=4432a09a-f542-e211-8519-000cf1d997b4 >Call-ID: a026a09a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:1264941142@prod.voipdnsservers.com> >Contact: <sip:1264941142@24.248.44.159:1024>;q=1, <sip:1264941142@24.248.44.159:5060>;q=0.667, <sip:1264941142@192.168.9.34:5060>;q=0.334 >Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK >Expires: 3600 >Content-Length: 0 >Max-Forwards: 70 > > >2012/12/12 13:15:40.513 1:28.701 Pool:0xabc81b40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:15:40.513 1:28.702 Pool:0xabc81b40 SIP Transaction timers set: retry=0.500, completion=6.000 >2012/12/12 13:15:40.514 1:28.702 Pool:0xabc81b40 SIP REGISTER transaction id=z9hG4bK54fbb29a-f542-e211-8519-000cf1d997b4 completed. >2012/12/12 13:15:40.514 1:28.702 Pool:0xabc81b40 SIP Handled PDU "1 REGISTER <401>" >2012/12/12 13:15:40.577 1:28.766 Opal Liste...0xb263cb40 OpalUDP Binding to interface: 192.168.9.34:5060 >2012/12/12 13:15:40.578 1:28.766 Opal Liste...0xb263cb40 SIP Waiting for PDU on udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:15:40.578 1:28.767 Opal Liste...0xb263cb40 SIP PDU received: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >SIP/2.0 200 OK >CSeq: 2 REGISTER >Via: SIP/2.0/UDP 24.248.44.159:1024;received=24.248.44.159;branch=z9hG4bK0837be9a-f542-e211-8519-000cf1d997b4;rport=1024 >From: <sip:1264941142@prod.voipdnsservers.com>;tag=4432a09a-f542-e211-8519-000cf1d997b4 >Call-ID: a026a09a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:1264941142@prod.voipdnsservers.com>;tag=135630620-1355336140545 >Contact: <sip:1264941142@24.248.44.159:1024>;expires=3600;q=1 >Allow-Events: call-info,line-seize,dialog,message-summary,as-feature-event,x-broadworks-hoteling,x-broadworks-call-center-status >Content-Length: 0 > > >2012/12/12 13:15:40.579 1:28.767 Opal Liste...0xb263cb40 SIP Queueing PDU "2 REGISTER <200>", transaction=z9hG4bK0837be9a-f542-e211-8519-000cf1d997b4, token=a026a09a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:40.579 1:28.770 Opal Liste...0xb263cb40 Opal Transport clean up on termination >2012/12/12 13:15:40.581 1:28.770 Opal Liste...0xb263cb40 Opal Transport Close >2012/12/12 13:15:40.582 1:28.770 Opal Liste...0xb263cb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:15:40.582 1:28.770 Pool:0xabc81b40 SIP Handling PDU "2 REGISTER <200>" for transaction=z9hG4bK0837be9a-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:40.582 1:28.771 Pool:0xabc81b40 SIP REGISTER transaction id=z9hG4bK0837be9a-f542-e211-8519-000cf1d997b4 completing. >2012/12/12 13:15:40.582 1:28.771 Pool:0xabc81b40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:15:40.582 1:28.771 Pool:0xabc81b40 SIP No User-Agent or Server fields, Product Info unknown. >2012/12/12 13:15:40.583 1:28.772 Pool:0xabc81b40 SIP Expiry time for REGISTER set to 3600 seconds. >2012/12/12 13:15:40.583 1:28.772 Pool:0xabc81b40 SIP Changing REGISTER handler from Subscribing to Subscribed, target=sip:1264941142@prod.voipdnsservers.com, id=a026a09a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:40.586 1:28.775 Pool:0xabc81b40 SIP REGISTER transaction id=z9hG4bK0837be9a-f542-e211-8519-000cf1d997b4 completed. >2012/12/12 13:15:40.587 1:28.775 Pool:0xabc81b40 SIP Handled PDU "2 REGISTER <200>" >2012/12/12 13:15:40.592 1:28.780 SIP Start SUBSCRIBE > eventPackage=message-summary > aor=sip:1264941142@prod.voipdnsservers.com > remote= > local= > contact= > proxy= > authID= > realm= > expire=3600 > restore=30 > minRetry=default > maxRetry=default >2012/12/12 13:15:40.593 1:28.781 SIP Constructed SUBSCRIBE handler for sip:1264941142@prod.voipdnsservers.com >2012/12/12 13:15:40.593 1:28.781 SIP Executing state change to Subscribing for SUBSCRIBE handler, target=sip:1264941142@prod.voipdnsservers.com, id=d4aeca9a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:40.593 1:28.781 SIP Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:1264941142@prod.voipdnsservers.com, id=d4aeca9a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:40.593 1:28.781 DNS SRV Lookup "_sip._udp.prod.voipdnsservers.com" >2012/12/12 13:15:40.593 1:28.782 SIP Attempting SRV record entry 0: 208.73.144.71:5060 >2012/12/12 13:15:40.594 1:28.782 OpalUDP Binding to interface: 0.0.0.0:5060 >2012/12/12 13:15:40.594 1:28.782 SIP Created transport udp$208.73.144.71:5060<if=udp$*:5060> >2012/12/12 13:15:40.594 1:28.782 OpalUDP Started connect to 208.73.144.71:5060 >2012/12/12 13:15:40.594 1:28.783 OpalUDP Writing to interface 0 - "192.168.9.34%em1" >2012/12/12 13:15:40.595 1:28.783 SIP Transaction created. >2012/12/12 13:15:40.599 1:28.788 DNS SRV Lookup "_sip._udp.prod.voipdnsservers.com" >2012/12/12 13:15:40.600 1:28.788 SIP Attempting SRV record entry 0: 208.73.144.71:5060 >2012/12/12 13:15:40.600 1:28.788 SIP Transaction remote address is udp$208.73.144.71:5060 >2012/12/12 13:15:40.600 1:28.788 SIP Sending PDU (644 bytes) to: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >SUBSCRIBE sip:1264941142@prod.voipdnsservers.com SIP/2.0 >CSeq: 1 SUBSCRIBE >Via: SIP/2.0/UDP 24.248.44.159:1024;branch=z9hG4bK0c2bcb9a-f542-e211-8519-000cf1d997b4;rport >User-Agent: Ekiga/4.0.0 >From: <sip:1264941142@prod.voipdnsservers.com>;tag=c800cb9a-f542-e211-8519-000cf1d997b4 >Call-ID: d4aeca9a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:1264941142@prod.voipdnsservers.com> >Accept: application/simple-message-summary >Contact: <sip:1264941142@24.248.44.159:1024> >Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK >Expires: 3600 >Event: message-summary >Content-Length: 0 >Max-Forwards: 70 > > >2012/12/12 13:15:40.605 1:28.794 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:15:40.606 1:28.794 SIP Transaction timers set: retry=0.500, completion=6.000 >2012/12/12 13:15:40.606 1:28.794 OpalPres Creating presentity command 'SIP_Presentity27OpalSetLocalPresenceCommand' >2012/12/12 13:15:40.606 1:28.794 PThreadObj:0xabcc2b40 SIPPres 'sip:1264941142@prod.voipdnsservers.com' sending own presence Available/I'm available using Ekiga >2012/12/12 13:15:40.606 1:28.795 PThreadObj:0xabcc2b40 SIPPres PIDF entity derived from AOR as pres:1264941142@prod.voipdnsservers.com >2012/12/12 13:15:40.607 1:28.795 PThreadObj:0xabcc2b40 SIP Start PUBLISH > eventPackage=presence > aor=sip:1264941142@prod.voipdnsservers.com > remote=sip:1264941142@prod.voipdnsservers.com > local= > contact= > proxy= > authID= > realm= > expire=300 > restore=30 > minRetry=default > maxRetry=default >2012/12/12 13:15:40.607 1:28.796 PThreadObj:0xabcc2b40 SIP Constructed PUBLISH handler for sip:1264941142@prod.voipdnsservers.com >2012/12/12 13:15:40.607 1:28.796 PThreadObj:0xabcc2b40 SIP Executing state change to Subscribing for PUBLISH handler, target=sip:1264941142@prod.voipdnsservers.com, id=38e4cc9a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:40.607 1:28.796 PThreadObj:0xabcc2b40 SIP Changing PUBLISH handler from Unavailable to Subscribing, target=sip:1264941142@prod.voipdnsservers.com, id=38e4cc9a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:40.607 1:28.796 PThreadObj:0xabcc2b40 DNS SRV Lookup "_sip._udp.prod.voipdnsservers.com" >2012/12/12 13:15:40.608 1:28.796 PThreadObj:0xabcc2b40 SIP Attempting SRV record entry 0: 208.73.144.71:5060 >2012/12/12 13:15:40.608 1:28.796 PThreadObj:0xabcc2b40 OpalUDP Binding to interface: 0.0.0.0:5060 >2012/12/12 13:15:40.608 1:28.796 PThreadObj:0xabcc2b40 SIP Created transport udp$208.73.144.71:5060<if=udp$*:5060> >2012/12/12 13:15:40.610 1:28.798 PThreadObj:0xabcc2b40 OpalUDP Started connect to 208.73.144.71:5060 >2012/12/12 13:15:40.610 1:28.798 PThreadObj:0xabcc2b40 OpalUDP Writing to interface 0 - "192.168.9.34%em1" >2012/12/12 13:15:40.610 1:28.798 PThreadObj:0xabcc2b40 SIP Transaction created. >2012/12/12 13:15:40.615 1:28.804 OpalPres Creating presentity command 'SIP_Presentity27OpalSetLocalPresenceCommand' >2012/12/12 13:15:40.615 1:28.804 Ekiga Sent its own presence (publish) for sip:1264941142@prod.voipdnsservers.com: available, note I'm available using Ekiga >2012/12/12 13:15:40.616 1:28.804 Ekiga Sent its own presence (publish) for sip:469269@sip.diamondcard.us: available, note I'm available using Ekiga >2012/12/12 13:15:40.616 1:28.804 Ekiga Sent its own presence (publish) for sip:SDGathman@ekiga.net: available, note I'm available using Ekiga >2012/12/12 13:15:40.622 1:28.810 PThreadObj:0xabcc2b40 DNS SRV Lookup "_sip._udp.prod.voipdnsservers.com" >2012/12/12 13:15:40.622 1:28.810 PThreadObj:0xabcc2b40 SIP Attempting SRV record entry 0: 208.73.144.71:5060 >2012/12/12 13:15:40.622 1:28.810 PThreadObj:0xabcc2b40 SIP Transaction remote address is udp$208.73.144.71:5060 >2012/12/12 13:15:40.622 1:28.811 PThreadObj:0xabcc2b40 SIP Sending PDU (974 bytes) to: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >PUBLISH sip:1264941142@prod.voipdnsservers.com SIP/2.0 >CSeq: 3 PUBLISH >Via: SIP/2.0/UDP 24.248.44.159:1024;branch=z9hG4bK8c7bcd9a-f542-e211-8519-000cf1d997b4;rport >User-Agent: Ekiga/4.0.0 >From: <sip:1264941142@prod.voipdnsservers.com> >Call-ID: 38e4cc9a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:1264941142@prod.voipdnsservers.com> >Expires: 300 >Event: presence >Content-Length: 520 >Content-Type: application/pidf+xml >Max-Forwards: 70 > ><?xml version="1.0" encoding="UTF-8"?> ><presence xmlns="urn:ietf:params:xml:ns:pidf" xmlns:dm="urn:ietf:params:xml:ns:pidf:data-model" xmlns:rpid="urn:ietf:params:xml:ns:pidf:rpid" entity="pres:1264941142@prod.voipdnsservers.com"> > <tuple id="T8D811BD2"> > <status> > <basic>open</basic> > </status> > <contact priority="1">sip:1264941142@prod.voipdnsservers.com</contact> > <note>I'm available using Ekiga</note> > <timestamp>2012-12-12T13:15:40-05:00</timestamp> > </tuple> ></presence> > >2012/12/12 13:15:40.623 1:28.811 PThreadObj:0xabcc2b40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:15:40.626 1:28.815 PThreadObj:0xabcc2b40 SIP Transaction timers set: retry=0.500, completion=6.000 >2012/12/12 13:15:40.628 1:28.816 PThreadObj:0xabcc2b40 SIPPres 'sip:1264941142@prod.voipdnsservers.com' sending own presence Available/I'm available using Ekiga >2012/12/12 13:15:40.628 1:28.817 PThreadObj:0xabcc2b40 SIPPres PIDF entity derived from AOR as pres:1264941142@prod.voipdnsservers.com >2012/12/12 13:15:40.628 1:28.817 PThreadObj:0xabcc2b40 SIP Start PUBLISH > eventPackage=presence > aor=sip:1264941142@prod.voipdnsservers.com > remote=sip:1264941142@prod.voipdnsservers.com > local= > contact= > proxy= > authID= > realm= > expire=300 > restore=30 > minRetry=default > maxRetry=default >2012/12/12 13:15:40.629 1:28.817 PThreadObj:0xabcc2b40 SIP Queueing state change to Subscribing for PUBLISH handler while in Subscribing state, target=sip:1264941142@prod.voipdnsservers.com, id=38e4cc9a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:40.664 1:28.852 Opal Liste...0xb263cb40 OpalUDP Binding to interface: 192.168.9.34:5060 >2012/12/12 13:15:40.664 1:28.852 Opal Liste...0xb263cb40 SIP Waiting for PDU on udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:15:40.665 1:28.853 Opal Liste...0xb263cb40 SIP PDU received: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >SIP/2.0 401 Unauthorized >CSeq: 1 SUBSCRIBE >Via: SIP/2.0/UDP 24.248.44.159:1024;received=24.248.44.159;branch=z9hG4bK0c2bcb9a-f542-e211-8519-000cf1d997b4;rport=1024 >From: <sip:1264941142@prod.voipdnsservers.com>;tag=c800cb9a-f542-e211-8519-000cf1d997b4 >Call-ID: d4aeca9a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:1264941142@prod.voipdnsservers.com>;tag=2089560849-1355336140634 >Content-Length: 0 >WWW-Authenticate: DIGEST qop="auth",nonce="BroadWorksXhams574qTfjypyiBW",realm="nextiva.com",algorithm=MD5 > > >2012/12/12 13:15:40.665 1:28.854 Opal Liste...0xb263cb40 SIP Queueing PDU "1 SUBSCRIBE <401>", transaction=z9hG4bK0c2bcb9a-f542-e211-8519-000cf1d997b4, token=d4aeca9a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:40.666 1:28.854 Pool:0xabc81b40 SIP Handling PDU "1 SUBSCRIBE <401>" for transaction=z9hG4bK0c2bcb9a-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:40.666 1:28.854 Pool:0xabc81b40 SIP SUBSCRIBE transaction id=z9hG4bK0c2bcb9a-f542-e211-8519-000cf1d997b4 completing. >2012/12/12 13:15:40.666 1:28.854 Pool:0xabc81b40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:15:40.666 1:28.854 Pool:0xabc81b40 SIP Received Authentication Required response for z9hG4bK0c2bcb9a-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:40.666 1:28.854 Pool:0xabc81b40 HTTP Authentication contains qop-options auth >2012/12/12 13:15:40.666 1:28.854 Pool:0xabc81b40 SIP Located existing credentials for ID "1264941142" at realm "nextiva.com" >2012/12/12 13:15:40.666 1:28.854 Pool:0xabc81b40 SIP Using auth info for realm "nextiva.com" >2012/12/12 13:15:40.666 1:28.855 Pool:0xabc81b40 SIP Creating authentication credentials of user "1264941142" for realm "nextiva.com" >2012/12/12 13:15:40.666 1:28.855 Pool:0xabc81b40 SIP Auth realm set to nextiva.com >2012/12/12 13:15:40.666 1:28.855 Pool:0xabc81b40 Duplicate handler for authId="1264941142", realm="nextiva.com" >2012/12/12 13:15:40.666 1:28.855 Pool:0xabc81b40 Duplicate handler for AOR user="1264941142", realm="nextiva.com" >2012/12/12 13:15:40.667 1:28.855 Pool:0xabc81b40 SIP Transaction created. >2012/12/12 13:15:40.672 1:28.861 Opal Liste...0xb263cb40 Opal Transport clean up on termination >2012/12/12 13:15:40.672 1:28.861 Opal Liste...0xb263cb40 Opal Transport Close >2012/12/12 13:15:40.672 1:28.861 Opal Liste...0xb263cb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:15:40.674 1:28.862 Pool:0xabc81b40 HTTP Adding authentication information >2012/12/12 13:15:40.674 1:28.863 Pool:0xabc81b40 DNS SRV Lookup "_sip._udp.prod.voipdnsservers.com" >2012/12/12 13:15:40.686 1:28.874 Opal Liste...0xb263cb40 OpalUDP Binding to interface: 192.168.9.34:5060 >2012/12/12 13:15:40.686 1:28.875 Opal Liste...0xb263cb40 SIP Waiting for PDU on udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:15:40.687 1:28.875 Opal Liste...0xb263cb40 SIP PDU received: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >SIP/2.0 501 Not implemented >CSeq: 3 PUBLISH >Via: SIP/2.0/UDP 24.248.44.159:1024;received=24.248.44.159;branch=z9hG4bK8c7bcd9a-f542-e211-8519-000cf1d997b4;rport=1024 >From: <sip:1264941142@prod.voipdnsservers.com> >Call-ID: 38e4cc9a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:1264941142@prod.voipdnsservers.com>;tag=1218335469-1355336140655 >Allow: ACK,BYE,CANCEL,INFO,INVITE,MESSAGE,OPTIONS,PRACK,REFER,REGISTER,SUBSCRIBE,NOTIFY,UPDATE >Content-Length: 0 > > >2012/12/12 13:15:40.688 1:28.876 Opal Liste...0xb263cb40 SIP Queueing PDU "3 PUBLISH <501>", transaction=z9hG4bK8c7bcd9a-f542-e211-8519-000cf1d997b4, token=38e4cc9a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:40.688 1:28.876 Opal Liste...0xb263cb40 PTLib No permission to set priority level 3 >2012/12/12 13:15:40.688 1:28.877 Opal Liste...0xb263cb40 Opal Transport clean up on termination >2012/12/12 13:15:40.688 1:28.877 Opal Liste...0xb263cb40 Opal Transport Close >2012/12/12 13:15:40.688 1:28.877 Opal Liste...0xb263cb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:15:40.689 1:28.877 Pool:0xabc81b40 SIP Attempting SRV record entry 0: 208.73.144.71:5060 >2012/12/12 13:15:40.689 1:28.877 Pool:0xabc81b40 SIP Transaction remote address is udp$208.73.144.71:5060 >2012/12/12 13:15:40.689 1:28.878 Pool:0xabc81b40 SIP Sending PDU (924 bytes) to: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >SUBSCRIBE sip:1264941142@prod.voipdnsservers.com SIP/2.0 >CSeq: 2 SUBSCRIBE >Via: SIP/2.0/UDP 24.248.44.159:1024;branch=z9hG4bK16cad69a-f542-e211-8519-000cf1d997b4;rport >User-Agent: Ekiga/4.0.0 >Authorization: Digest username="1264941142", realm="nextiva.com", nonce="BroadWorksXhams574qTfjypyiBW", uri="sip:1264941142@prod.voipdnsservers.com", algorithm=MD5, response="bbdd7fa6904414e632a81db83d267426", cnonce="a0e1d59a-f542-e211-8519-000cf1d997b4", nc=00000001, qop=auth >From: <sip:1264941142@prod.voipdnsservers.com>;tag=c800cb9a-f542-e211-8519-000cf1d997b4 >Call-ID: d4aeca9a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:1264941142@prod.voipdnsservers.com> >Accept: application/simple-message-summary >Contact: <sip:1264941142@24.248.44.159:1024> >Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK >Expires: 3600 >Event: message-summary >Content-Length: 0 >Max-Forwards: 70 > > >2012/12/12 13:15:40.690 1:28.879 Pool:0xabc40b40 SIP Handling PDU "3 PUBLISH <501>" for transaction=z9hG4bK8c7bcd9a-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:40.690 1:28.879 Pool:0xabc40b40 SIP PUBLISH transaction id=z9hG4bK8c7bcd9a-f542-e211-8519-000cf1d997b4 completing. >2012/12/12 13:15:40.690 1:28.879 Pool:0xabc40b40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:15:40.691 1:28.879 Pool:0xabc40b40 SIP Not retrying PUBLISH due to error response 501 Not Implemented >2012/12/12 13:15:40.691 1:28.879 Pool:0xabc40b40 SIP Changing PUBLISH handler from Subscribing to Unsubscribed, target=sip:1264941142@prod.voipdnsservers.com, id=38e4cc9a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:40.691 1:28.879 Pool:0xabc40b40 SIP Changing PUBLISH handler from Unsubscribed to Subscribing, target=sip:1264941142@prod.voipdnsservers.com, id=38e4cc9a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:40.691 1:28.879 Pool:0xabc40b40 SIP Transaction created. >2012/12/12 13:15:40.691 1:28.880 Pool:0xabc81b40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:15:40.692 1:28.880 Pool:0xabc81b40 SIP Transaction timers set: retry=0.500, completion=6.000 >2012/12/12 13:15:40.692 1:28.880 Pool:0xabc81b40 SIP SUBSCRIBE transaction id=z9hG4bK0c2bcb9a-f542-e211-8519-000cf1d997b4 completed. >2012/12/12 13:15:40.692 1:28.880 Pool:0xabc81b40 SIP Handled PDU "1 SUBSCRIBE <401>" >2012/12/12 13:15:40.693 1:28.881 Pool:0xabc40b40 DNS SRV Lookup "_sip._udp.prod.voipdnsservers.com" >2012/12/12 13:15:40.693 1:28.882 Pool:0xabc40b40 SIP Attempting SRV record entry 0: 208.73.144.71:5060 >2012/12/12 13:15:40.693 1:28.882 Pool:0xabc40b40 SIP Transaction remote address is udp$208.73.144.71:5060 >2012/12/12 13:15:40.694 1:28.882 Pool:0xabc40b40 SIP Sending PDU (974 bytes) to: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >PUBLISH sip:1264941142@prod.voipdnsservers.com SIP/2.0 >CSeq: 4 PUBLISH >Via: SIP/2.0/UDP 24.248.44.159:1024;branch=z9hG4bKaaf1d99a-f542-e211-8519-000cf1d997b4;rport >User-Agent: Ekiga/4.0.0 >From: <sip:1264941142@prod.voipdnsservers.com> >Call-ID: 38e4cc9a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:1264941142@prod.voipdnsservers.com> >Expires: 300 >Event: presence >Content-Length: 520 >Content-Type: application/pidf+xml >Max-Forwards: 70 > ><?xml version="1.0" encoding="UTF-8"?> ><presence xmlns="urn:ietf:params:xml:ns:pidf" xmlns:dm="urn:ietf:params:xml:ns:pidf:data-model" xmlns:rpid="urn:ietf:params:xml:ns:pidf:rpid" entity="pres:1264941142@prod.voipdnsservers.com"> > <tuple id="T8D811BD2"> > <status> > <basic>open</basic> > </status> > <contact priority="1">sip:1264941142@prod.voipdnsservers.com</contact> > <note>I'm available using Ekiga</note> > <timestamp>2012-12-12T13:15:40-05:00</timestamp> > </tuple> ></presence> > >2012/12/12 13:15:40.694 1:28.883 Pool:0xabc40b40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:15:40.695 1:28.883 Pool:0xabc40b40 SIP Transaction timers set: retry=0.500, completion=6.000 >2012/12/12 13:15:40.695 1:28.883 Pool:0xabc40b40 SIP PUBLISH transaction id=z9hG4bK8c7bcd9a-f542-e211-8519-000cf1d997b4 completed. >2012/12/12 13:15:40.695 1:28.883 Pool:0xabc40b40 SIP Handled PDU "3 PUBLISH <501>" >2012/12/12 13:15:40.752 1:28.940 Opal Liste...0xb263cb40 OpalUDP Binding to interface: 192.168.9.34:5060 >2012/12/12 13:15:40.752 1:28.940 Opal Liste...0xb263cb40 SIP Waiting for PDU on udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:15:40.753 1:28.941 Opal Liste...0xb263cb40 SIP PDU received: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >SIP/2.0 200 OK >CSeq: 2 SUBSCRIBE >Via: SIP/2.0/UDP 24.248.44.159:1024;received=24.248.44.159;branch=z9hG4bK16cad69a-f542-e211-8519-000cf1d997b4;rport=1024 >From: <sip:1264941142@prod.voipdnsservers.com>;tag=c800cb9a-f542-e211-8519-000cf1d997b4 >Call-ID: d4aeca9a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:1264941142@prod.voipdnsservers.com>;tag=807005338-1355336140722 >Contact: <sip:1264941142@208.73.144.71:5060;transport=udp> >Expires: 3600 >Content-Length: 0 > > >2012/12/12 13:15:40.753 1:28.942 Opal Liste...0xb263cb40 SIP Queueing PDU "2 SUBSCRIBE <200>", transaction=z9hG4bK16cad69a-f542-e211-8519-000cf1d997b4, token=d4aeca9a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:40.753 1:28.942 Pool:0xabc81b40 SIP Handling PDU "2 SUBSCRIBE <200>" for transaction=z9hG4bK16cad69a-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:40.754 1:28.942 Pool:0xabc81b40 SIP SUBSCRIBE transaction id=z9hG4bK16cad69a-f542-e211-8519-000cf1d997b4 completing. >2012/12/12 13:15:40.754 1:28.942 Pool:0xabc81b40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:15:40.754 1:28.942 Pool:0xabc81b40 SIP Expiry time for SUBSCRIBE set to 3600 seconds. >2012/12/12 13:15:40.754 1:28.942 Pool:0xabc81b40 SIP Route set is >2012/12/12 13:15:40.754 1:28.942 Pool:0xabc81b40 SIP Set Request URI to sip:1264941142@208.73.144.71:5060;transport=udp >2012/12/12 13:15:40.754 1:28.943 Pool:0xabc81b40 SIP Updating dialog tag from "" to "807005338-1355336140722" >2012/12/12 13:15:40.755 1:28.943 Pool:0xabc81b40 SIP No User-Agent or Server fields, Product Info unknown. >2012/12/12 13:15:40.755 1:28.943 Pool:0xabc81b40 SIP Changing SUBSCRIBE handler from Subscribing to Subscribed, target=sip:1264941142@prod.voipdnsservers.com, id=d4aeca9a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:40.755 1:28.943 Pool:0xabc81b40 SIP SUBSCRIBE transaction id=z9hG4bK16cad69a-f542-e211-8519-000cf1d997b4 completed. >2012/12/12 13:15:40.755 1:28.943 Pool:0xabc81b40 SIP Handled PDU "2 SUBSCRIBE <200>" >2012/12/12 13:15:40.755 1:28.943 Opal Liste...0xb263cb40 Opal Transport clean up on termination >2012/12/12 13:15:40.755 1:28.943 Opal Liste...0xb263cb40 Opal Transport Close >2012/12/12 13:15:40.755 1:28.944 Opal Liste...0xb263cb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:15:40.756 1:28.944 Opal Liste...0xb263cb40 OpalUDP Binding to interface: 192.168.9.34:5060 >2012/12/12 13:15:40.756 1:28.944 Opal Liste...0xb263cb40 SIP Waiting for PDU on udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:15:40.760 1:28.948 Opal Liste...0xb263cb40 SIP PDU received: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >NOTIFY sip:1264941142@24.248.44.159:1024 SIP/2.0 >CSeq: 273958836 NOTIFY >Via: SIP/2.0/UDP 208.73.144.71:5060;branch=z9hG4bKeqscqa2010r1js0di1v1.1 >From: <sip:1264941142@208.73.144.71>;tag=807005338-1355336140722 >Call-ID: d4aeca9a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:1264941142@24.248.44.159>;tag=c800cb9a-f542-e211-8519-000cf1d997b4 >Contact: <sip:1264941142@208.73.144.71:5060;transport=udp> >Subscription-State: active;expires=3599 >Event: message-summary >Content-Type: application/simple-message-summary >Content-Length: 42 >Max-Forwards: 9 > >Messages-Waiting: no >voice-message: 0/0 > >2012/12/12 13:15:40.761 1:28.949 Opal Liste...0xb263cb40 SIP Received NOTIFY message-summary >2012/12/12 13:15:40.761 1:28.949 Opal Liste...0xb263cb40 SIP Found a SUBSCRIBE corresponding to the NOTIFY message-summary >2012/12/12 13:15:40.761 1:28.949 Opal Liste...0xb263cb40 SIP No User-Agent or Server fields, Product Info unknown. >2012/12/12 13:15:40.761 1:28.949 Opal Liste...0xb263cb40 SIP Dialog initial sequence number 273958836 >2012/12/12 13:15:40.761 1:28.950 Opal Liste...0xb263cb40 SIP Subscription is Subscribed >2012/12/12 13:15:40.761 1:28.950 Opal Liste...0xb263cb40 SIP Calling package NOTIFY handler for AOR "sip:1264941142@prod.voipdnsservers.com" >2012/12/12 13:15:40.778 1:28.966 Opal Liste...0xb263cb40 SIP Sending PDU (384 bytes) to: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >SIP/2.0 200 OK >CSeq: 273958836 NOTIFY >Via: SIP/2.0/UDP 208.73.144.71:5060;branch=z9hG4bKeqscqa2010r1js0di1v1.1 >From: <sip:1264941142@208.73.144.71>;tag=807005338-1355336140722 >Call-ID: d4aeca9a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:1264941142@24.248.44.159>;tag=c800cb9a-f542-e211-8519-000cf1d997b4 >Contact: <sip:1264941142@24.248.44.159:1024> >Content-Length: 0 > > >2012/12/12 13:15:40.790 1:28.979 Opal Liste...0xb263cb40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:15:40.790 1:28.979 Opal Liste...0xb263cb40 Opal Transport clean up on termination >2012/12/12 13:15:40.791 1:28.979 Opal Liste...0xb263cb40 Opal Transport Close >2012/12/12 13:15:40.791 1:28.979 Opal Liste...0xb263cb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:15:40.791 1:28.984 Opal Liste...0xb263cb40 OpalUDP Binding to interface: 192.168.9.34:5060 >2012/12/12 13:15:40.796 1:28.984 Opal Liste...0xb263cb40 SIP Waiting for PDU on udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:15:40.796 1:28.984 Opal Liste...0xb263cb40 SIP PDU received: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >SIP/2.0 501 Not implemented >CSeq: 4 PUBLISH >Via: SIP/2.0/UDP 24.248.44.159:1024;received=24.248.44.159;branch=z9hG4bKaaf1d99a-f542-e211-8519-000cf1d997b4;rport=1024 >From: <sip:1264941142@prod.voipdnsservers.com> >Call-ID: 38e4cc9a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:1264941142@prod.voipdnsservers.com>;tag=2006636960-1355336140727 >Allow: ACK,BYE,CANCEL,INFO,INVITE,MESSAGE,OPTIONS,PRACK,REFER,REGISTER,SUBSCRIBE,NOTIFY,UPDATE >Content-Length: 0 > > >2012/12/12 13:15:40.797 1:28.985 Opal Liste...0xb263cb40 SIP Queueing PDU "4 PUBLISH <501>", transaction=z9hG4bKaaf1d99a-f542-e211-8519-000cf1d997b4, token=38e4cc9a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:40.805 1:28.993 Pool:0xabc81b40 SIP Handling PDU "4 PUBLISH <501>" for transaction=z9hG4bKaaf1d99a-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:40.806 1:28.994 Pool:0xabc81b40 SIP PUBLISH transaction id=z9hG4bKaaf1d99a-f542-e211-8519-000cf1d997b4 completing. >2012/12/12 13:15:40.806 1:28.994 Pool:0xabc81b40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:15:40.806 1:28.994 Pool:0xabc81b40 SIP Not retrying PUBLISH due to error response 501 Not Implemented >2012/12/12 13:15:40.806 1:28.994 Pool:0xabc81b40 SIP Changing PUBLISH handler from Subscribing to Unsubscribed, target=sip:1264941142@prod.voipdnsservers.com, id=38e4cc9a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:40.806 1:28.994 Pool:0xabc81b40 SIP PUBLISH transaction id=z9hG4bKaaf1d99a-f542-e211-8519-000cf1d997b4 completed. >2012/12/12 13:15:40.806 1:28.994 Pool:0xabc81b40 SIP Handled PDU "4 PUBLISH <501>" >2012/12/12 13:15:40.806 1:28.995 Opal Liste...0xb263cb40 Opal Transport clean up on termination >2012/12/12 13:15:40.806 1:28.995 Opal Liste...0xb263cb40 Opal Transport Close >2012/12/12 13:15:40.806 1:28.995 Opal Liste...0xb263cb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:15:41.060 1:29.248 Opal Garbage:0xb26ffb40 Opal Transport clean up on termination >2012/12/12 13:15:41.060 1:29.249 Opal Garbage:0xb26ffb40 Opal Transport Close >2012/12/12 13:15:41.060 1:29.249 Opal Garbage:0xb26ffb40 Opal Transport clean up on termination >2012/12/12 13:15:41.060 1:29.249 Opal Garbage:0xb26ffb40 Opal Transport Close >2012/12/12 13:15:41.060 1:29.249 Opal Garbage:0xb26ffb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:15:41.061 1:29.249 Opal Garbage:0xb26ffb40 SIP Destroyed PUBLISH handler for sip:1264941142@prod.voipdnsservers.com >2012/12/12 13:15:41.514 1:29.702 Housekeeper:0xb267db40 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bK54fbb29a-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:41.514 1:29.702 Housekeeper:0xb267db40 SIP Completed state for transaction id=z9hG4bK54fbb29a-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:41.587 1:29.775 Housekeeper:0xb267db40 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bK0837be9a-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:41.587 1:29.776 Housekeeper:0xb267db40 SIP Completed state for transaction id=z9hG4bK0837be9a-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:41.692 1:29.881 Housekeeper:0xb267db40 SIP Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK0c2bcb9a-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:41.693 1:29.881 Housekeeper:0xb267db40 SIP Completed state for transaction id=z9hG4bK0c2bcb9a-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:41.718 1:29.906 Housekeeper:0xb267db40 SIP Set state Terminated_Success for PUBLISH transaction id=z9hG4bK8c7bcd9a-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:41.718 1:29.906 Housekeeper:0xb267db40 SIP Completed state for transaction id=z9hG4bK8c7bcd9a-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:41.755 1:29.944 Housekeeper:0xb267db40 SIP Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK16cad69a-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:41.755 1:29.944 Housekeeper:0xb267db40 SIP Completed state for transaction id=z9hG4bK16cad69a-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:41.807 1:29.995 Housekeeper:0xb267db40 SIP Set state Terminated_Success for PUBLISH transaction id=z9hG4bKaaf1d99a-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:41.807 1:29.995 Housekeeper:0xb267db40 SIP Completed state for transaction id=z9hG4bKaaf1d99a-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:42.062 1:30.250 Opal Garbage:0xb26ffb40 SIP Transaction id=z9hG4bK8c7bcd9a-f542-e211-8519-000cf1d997b4 destroyed. >2012/12/12 13:15:42.062 1:30.250 Opal Garbage:0xb26ffb40 SIP Transaction id=z9hG4bK54fbb29a-f542-e211-8519-000cf1d997b4 destroyed. >2012/12/12 13:15:42.062 1:30.250 Opal Garbage:0xb26ffb40 SIP Transaction id=z9hG4bK0837be9a-f542-e211-8519-000cf1d997b4 destroyed. >2012/12/12 13:15:42.062 1:30.251 Opal Garbage:0xb26ffb40 SIP Transaction id=z9hG4bK0c2bcb9a-f542-e211-8519-000cf1d997b4 destroyed. >2012/12/12 13:15:42.063 1:30.251 Opal Garbage:0xb26ffb40 SIP Transaction id=z9hG4bK16cad69a-f542-e211-8519-000cf1d997b4 destroyed. >2012/12/12 13:15:42.063 1:30.251 Opal Garbage:0xb26ffb40 SIP Transaction id=z9hG4bKaaf1d99a-f542-e211-8519-000cf1d997b4 destroyed. >2012/12/12 13:15:42.186 1:30.374 Housekeeper:0xb267db40 SIP Constructed OPTIONS handler for sip:stuart@208.73.144.71 >2012/12/12 13:15:42.186 1:30.374 Housekeeper:0xb267db40 SIP Changing OPTIONS handler from Unavailable to Subscribed, target=sip:stuart@208.73.144.71, id=f2c9bd9b-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:42.186 1:30.375 Housekeeper:0xb267db40 SIP Executing state change to Subscribing for OPTIONS handler, target=sip:stuart@208.73.144.71, id=f2c9bd9b-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:42.186 1:30.375 Housekeeper:0xb267db40 SIP Changing OPTIONS handler from Subscribed to Subscribing, target=sip:stuart@208.73.144.71, id=f2c9bd9b-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:42.187 1:30.375 Housekeeper:0xb267db40 OpalUDP Binding to interface: 0.0.0.0:5060 >2012/12/12 13:15:42.187 1:30.375 Housekeeper:0xb267db40 SIP Created transport udp$208.73.144.71:5060<if=udp$*:5060> >2012/12/12 13:15:42.187 1:30.375 Housekeeper:0xb267db40 OpalUDP Started connect to 208.73.144.71:5060 >2012/12/12 13:15:42.187 1:30.376 Housekeeper:0xb267db40 OpalUDP Writing to interface 0 - "192.168.9.34%em1" >2012/12/12 13:15:42.187 1:30.376 Housekeeper:0xb267db40 SIP Transaction created. >2012/12/12 13:15:42.188 1:30.376 Housekeeper:0xb267db40 OpalMan Listener interfaces: associated transport=None > udp$192.168.9.34:5060 >2012/12/12 13:15:42.286 1:30.474 Housekeeper:0xb267db40 SIP Generated default local URI: sip:stuart@24.248.44.159 >2012/12/12 13:15:42.288 1:30.476 Housekeeper:0xb267db40 SIP Transaction remote address is udp$208.73.144.71:5060 >2012/12/12 13:15:42.288 1:30.476 Housekeeper:0xb267db40 SIP Sending PDU (601 bytes) to: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >OPTIONS sip:stuart@208.73.144.71 SIP/2.0 >CSeq: 5 OPTIONS >Via: SIP/2.0/UDP 24.248.44.159:1024;branch=z9hG4bKa435cd9b-f542-e211-8519-000cf1d997b4;rport >User-Agent: Ekiga/4.0.0 >From: "Stuart D Gathman" <sip:stuart@24.248.44.159>;tag=9611cd9b-f542-e211-8519-000cf1d997b4 >Call-ID: f2c9bd9b-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:stuart@208.73.144.71> >Accept: application/sdp, application/media_control+xml, application/dtmf, application/dtmf-relay >Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK >Expires: 3600 >Content-Length: 0 >Max-Forwards: 70 > > >2012/12/12 13:15:42.288 1:30.477 Housekeeper:0xb267db40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:15:42.289 1:30.477 Housekeeper:0xb267db40 SIP Transaction timers set: retry=0.500, completion=6.000 >2012/12/12 13:15:42.289 1:30.478 Housekeeper:0xb267db40 SIP Constructed OPTIONS handler for sip:stuart@208.73.144.71 >2012/12/12 13:15:42.290 1:30.478 Housekeeper:0xb267db40 SIP Changing OPTIONS handler from Unavailable to Subscribed, target=sip:stuart@208.73.144.71, id=a895cd9b-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:42.290 1:30.478 Housekeeper:0xb267db40 SIP Executing state change to Subscribing for OPTIONS handler, target=sip:stuart@208.73.144.71, id=a895cd9b-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:42.290 1:30.478 Housekeeper:0xb267db40 SIP Changing OPTIONS handler from Subscribed to Subscribing, target=sip:stuart@208.73.144.71, id=a895cd9b-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:42.290 1:30.478 Housekeeper:0xb267db40 OpalUDP Binding to interface: 0.0.0.0:5060 >2012/12/12 13:15:42.290 1:30.478 Housekeeper:0xb267db40 SIP Created transport udp$208.73.144.71:5060<if=udp$*:5060> >2012/12/12 13:15:42.290 1:30.479 Housekeeper:0xb267db40 OpalUDP Started connect to 208.73.144.71:5060 >2012/12/12 13:15:42.291 1:30.479 Housekeeper:0xb267db40 OpalUDP Writing to interface 0 - "192.168.9.34%em1" >2012/12/12 13:15:42.291 1:30.479 Housekeeper:0xb267db40 SIP Transaction created. >2012/12/12 13:15:42.292 1:30.480 Housekeeper:0xb267db40 OpalMan Listener interfaces: associated transport=None > udp$192.168.9.34:5060 >2012/12/12 13:15:42.293 1:30.481 Housekeeper:0xb267db40 SIP Generated default local URI: sip:stuart@24.248.44.159 >2012/12/12 13:15:42.294 1:30.482 Housekeeper:0xb267db40 SIP Transaction remote address is udp$208.73.144.71:5060 >2012/12/12 13:15:42.294 1:30.483 Housekeeper:0xb267db40 SIP Sending PDU (601 bytes) to: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >OPTIONS sip:stuart@208.73.144.71 SIP/2.0 >CSeq: 6 OPTIONS >Via: SIP/2.0/UDP 24.248.44.159:1024;branch=z9hG4bKfc3bce9b-f542-e211-8519-000cf1d997b4;rport >User-Agent: Ekiga/4.0.0 >From: "Stuart D Gathman" <sip:stuart@24.248.44.159>;tag=a01ace9b-f542-e211-8519-000cf1d997b4 >Call-ID: a895cd9b-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:stuart@208.73.144.71> >Accept: application/sdp, application/media_control+xml, application/dtmf, application/dtmf-relay >Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK >Expires: 3600 >Content-Length: 0 >Max-Forwards: 70 > > >2012/12/12 13:15:42.295 1:30.484 Housekeeper:0xb267db40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:15:42.295 1:30.484 Housekeeper:0xb267db40 SIP Transaction timers set: retry=0.500, completion=6.000 >2012/12/12 13:15:42.350 1:30.539 Opal Liste...0xb263cb40 OpalUDP Binding to interface: 192.168.9.34:5060 >2012/12/12 13:15:42.351 1:30.539 Opal Liste...0xb263cb40 SIP Waiting for PDU on udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:15:42.351 1:30.540 Opal Liste...0xb263cb40 SIP No Content-Length present from udp$208.73.144.71:5060<if=udp$24.248.44.159:1024>, reading till end of datagram/stream. >2012/12/12 13:15:42.352 1:30.540 Opal Liste...0xb263cb40 SIP PDU received: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >SIP/2.0 403 Forbidden >CSeq: 5 OPTIONS >Via: SIP/2.0/UDP 24.248.44.159:1024;received=24.248.44.159;branch=z9hG4bKa435cd9b-f542-e211-8519-000cf1d997b4;rport=1024 >From: "Stuart D Gathman" <sip:stuart@24.248.44.159>;tag=9611cd9b-f542-e211-8519-000cf1d997b4 >Call-ID: f2c9bd9b-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:stuart@208.73.144.71>;tag=aprqngfrt-4s3n2230000a0 > > >2012/12/12 13:15:42.358 1:30.546 Opal Liste...0xb263cb40 SIP Queueing PDU "5 OPTIONS <403>", transaction=z9hG4bKa435cd9b-f542-e211-8519-000cf1d997b4, token=f2c9bd9b-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:42.358 1:30.546 Pool:0xabc81b40 SIP Handling PDU "5 OPTIONS <403>" for transaction=z9hG4bKa435cd9b-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:42.358 1:30.546 Pool:0xabc81b40 SIP OPTIONS transaction id=z9hG4bKa435cd9b-f542-e211-8519-000cf1d997b4 completing. >2012/12/12 13:15:42.358 1:30.546 Pool:0xabc81b40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:15:42.358 1:30.546 Pool:0xabc81b40 SIP Completed OPTIONS command to sip:stuart@208.73.144.71, status=403 Forbidden >2012/12/12 13:15:42.358 1:30.546 Pool:0xabc81b40 SIP Not retrying OPTIONS due to error response 403 Forbidden >2012/12/12 13:15:42.358 1:30.546 Pool:0xabc81b40 SIP Changing OPTIONS handler from Subscribing to Unsubscribed, target=sip:stuart@208.73.144.71, id=f2c9bd9b-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:42.358 1:30.547 Pool:0xabc81b40 SIP OPTIONS transaction id=z9hG4bKa435cd9b-f542-e211-8519-000cf1d997b4 completed. >2012/12/12 13:15:42.358 1:30.547 Pool:0xabc81b40 SIP Handled PDU "5 OPTIONS <403>" >2012/12/12 13:15:42.358 1:30.547 Opal Liste...0xb263cb40 Opal Transport clean up on termination >2012/12/12 13:15:42.358 1:30.547 Opal Liste...0xb263cb40 Opal Transport Close >2012/12/12 13:15:42.359 1:30.547 Opal Liste...0xb263cb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:15:42.359 1:30.548 Opal Liste...0xb263cb40 OpalUDP Binding to interface: 192.168.9.34:5060 >2012/12/12 13:15:42.359 1:30.548 Opal Liste...0xb263cb40 SIP Waiting for PDU on udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:15:42.360 1:30.548 Opal Liste...0xb263cb40 SIP No Content-Length present from udp$208.73.144.71:5060<if=udp$24.248.44.159:1024>, reading till end of datagram/stream. >2012/12/12 13:15:42.360 1:30.549 Opal Liste...0xb263cb40 SIP PDU received: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >SIP/2.0 403 Forbidden >CSeq: 6 OPTIONS >Via: SIP/2.0/UDP 24.248.44.159:1024;received=24.248.44.159;branch=z9hG4bKfc3bce9b-f542-e211-8519-000cf1d997b4;rport=1024 >From: "Stuart D Gathman" <sip:stuart@24.248.44.159>;tag=a01ace9b-f542-e211-8519-000cf1d997b4 >Call-ID: a895cd9b-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:stuart@208.73.144.71>;tag=aprqngfrt-rb336030000c0 > > >2012/12/12 13:15:42.361 1:30.549 Opal Liste...0xb263cb40 SIP Queueing PDU "6 OPTIONS <403>", transaction=z9hG4bKfc3bce9b-f542-e211-8519-000cf1d997b4, token=a895cd9b-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:42.361 1:30.550 Pool:0xabc81b40 SIP Handling PDU "6 OPTIONS <403>" for transaction=z9hG4bKfc3bce9b-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:42.361 1:30.550 Pool:0xabc81b40 SIP OPTIONS transaction id=z9hG4bKfc3bce9b-f542-e211-8519-000cf1d997b4 completing. >2012/12/12 13:15:42.361 1:30.550 Pool:0xabc81b40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:15:42.362 1:30.550 Pool:0xabc81b40 SIP Completed OPTIONS command to sip:stuart@208.73.144.71, status=403 Forbidden >2012/12/12 13:15:42.362 1:30.550 Pool:0xabc81b40 SIP Not retrying OPTIONS due to error response 403 Forbidden >2012/12/12 13:15:42.362 1:30.550 Pool:0xabc81b40 SIP Changing OPTIONS handler from Subscribing to Unsubscribed, target=sip:stuart@208.73.144.71, id=a895cd9b-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:42.362 1:30.550 Pool:0xabc81b40 SIP OPTIONS transaction id=z9hG4bKfc3bce9b-f542-e211-8519-000cf1d997b4 completed. >2012/12/12 13:15:42.362 1:30.550 Pool:0xabc81b40 SIP Handled PDU "6 OPTIONS <403>" >2012/12/12 13:15:42.362 1:30.550 Opal Liste...0xb263cb40 Opal Transport clean up on termination >2012/12/12 13:15:42.362 1:30.550 Opal Liste...0xb263cb40 Opal Transport Close >2012/12/12 13:15:42.362 1:30.550 Opal Liste...0xb263cb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:15:43.063 1:31.252 Opal Garbage:0xb26ffb40 Opal Transport clean up on termination >2012/12/12 13:15:43.064 1:31.252 Opal Garbage:0xb26ffb40 Opal Transport Close >2012/12/12 13:15:43.064 1:31.252 Opal Garbage:0xb26ffb40 Opal Transport clean up on termination >2012/12/12 13:15:43.064 1:31.252 Opal Garbage:0xb26ffb40 Opal Transport Close >2012/12/12 13:15:43.064 1:31.252 Opal Garbage:0xb26ffb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:15:43.064 1:31.253 Opal Garbage:0xb26ffb40 SIP Destroyed OPTIONS handler for sip:stuart@208.73.144.71 >2012/12/12 13:15:43.065 1:31.253 Opal Garbage:0xb26ffb40 Opal Transport clean up on termination >2012/12/12 13:15:43.065 1:31.253 Opal Garbage:0xb26ffb40 Opal Transport Close >2012/12/12 13:15:43.065 1:31.253 Opal Garbage:0xb26ffb40 Opal Transport clean up on termination >2012/12/12 13:15:43.065 1:31.253 Opal Garbage:0xb26ffb40 Opal Transport Close >2012/12/12 13:15:43.065 1:31.253 Opal Garbage:0xb26ffb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:15:43.065 1:31.254 Opal Garbage:0xb26ffb40 SIP Destroyed OPTIONS handler for sip:stuart@208.73.144.71 >2012/12/12 13:15:43.359 1:31.547 Housekeeper:0xb267db40 SIP Set state Terminated_Success for OPTIONS transaction id=z9hG4bKa435cd9b-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:43.359 1:31.547 Housekeeper:0xb267db40 SIP Completed state for transaction id=z9hG4bKa435cd9b-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:43.384 1:31.573 Housekeeper:0xb267db40 SIP Set state Terminated_Success for OPTIONS transaction id=z9hG4bKfc3bce9b-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:43.384 1:31.573 Housekeeper:0xb267db40 SIP Completed state for transaction id=z9hG4bKfc3bce9b-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:44.066 1:32.254 Opal Garbage:0xb26ffb40 SIP Transaction id=z9hG4bKfc3bce9b-f542-e211-8519-000cf1d997b4 destroyed. >2012/12/12 13:15:44.066 1:32.255 Opal Garbage:0xb26ffb40 SIP Transaction id=z9hG4bKa435cd9b-f542-e211-8519-000cf1d997b4 destroyed. >2012/12/12 13:15:52.185 1:40.374 Housekeeper:0xb267db40 SIP Constructed OPTIONS handler for sip:stuart@208.73.144.71 >2012/12/12 13:15:52.185 1:40.374 Housekeeper:0xb267db40 SIP Changing OPTIONS handler from Unavailable to Subscribed, target=sip:stuart@208.73.144.71, id=0e8fb3a1-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:52.185 1:40.374 Housekeeper:0xb267db40 SIP Executing state change to Subscribing for OPTIONS handler, target=sip:stuart@208.73.144.71, id=0e8fb3a1-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:52.186 1:40.374 Housekeeper:0xb267db40 SIP Changing OPTIONS handler from Subscribed to Subscribing, target=sip:stuart@208.73.144.71, id=0e8fb3a1-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:52.186 1:40.374 Housekeeper:0xb267db40 OpalUDP Binding to interface: 0.0.0.0:5060 >2012/12/12 13:15:52.186 1:40.374 Housekeeper:0xb267db40 SIP Created transport udp$208.73.144.71:5060<if=udp$*:5060> >2012/12/12 13:15:52.186 1:40.375 Housekeeper:0xb267db40 OpalUDP Started connect to 208.73.144.71:5060 >2012/12/12 13:15:52.187 1:40.375 Housekeeper:0xb267db40 OpalUDP Writing to interface 0 - "192.168.9.34%em1" >2012/12/12 13:15:52.187 1:40.375 Housekeeper:0xb267db40 SIP Transaction created. >2012/12/12 13:15:52.187 1:40.376 Housekeeper:0xb267db40 OpalMan Listener interfaces: associated transport=None > udp$192.168.9.34:5060 >2012/12/12 13:15:52.284 1:40.472 Housekeeper:0xb267db40 SIP Generated default local URI: sip:stuart@24.248.44.159 >2012/12/12 13:15:52.286 1:40.474 Housekeeper:0xb267db40 SIP Transaction remote address is udp$208.73.144.71:5060 >2012/12/12 13:15:52.286 1:40.474 Housekeeper:0xb267db40 SIP Sending PDU (601 bytes) to: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >OPTIONS sip:stuart@208.73.144.71 SIP/2.0 >CSeq: 7 OPTIONS >Via: SIP/2.0/UDP 24.248.44.159:1024;branch=z9hG4bK40d0c2a1-f542-e211-8519-000cf1d997b4;rport >User-Agent: Ekiga/4.0.0 >From: "Stuart D Gathman" <sip:stuart@24.248.44.159>;tag=3aaec2a1-f542-e211-8519-000cf1d997b4 >Call-ID: 0e8fb3a1-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:stuart@208.73.144.71> >Accept: application/sdp, application/media_control+xml, application/dtmf, application/dtmf-relay >Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK >Expires: 3600 >Content-Length: 0 >Max-Forwards: 70 > > >2012/12/12 13:15:52.287 1:40.475 Housekeeper:0xb267db40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:15:52.287 1:40.475 Housekeeper:0xb267db40 SIP Transaction timers set: retry=0.500, completion=6.000 >2012/12/12 13:15:52.288 1:40.476 Housekeeper:0xb267db40 SIP Constructed OPTIONS handler for sip:stuart@208.73.144.71 >2012/12/12 13:15:52.288 1:40.476 Housekeeper:0xb267db40 SIP Changing OPTIONS handler from Unavailable to Subscribed, target=sip:stuart@208.73.144.71, id=c22fc3a1-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:52.288 1:40.476 Housekeeper:0xb267db40 SIP Executing state change to Subscribing for OPTIONS handler, target=sip:stuart@208.73.144.71, id=c22fc3a1-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:52.288 1:40.476 Housekeeper:0xb267db40 SIP Changing OPTIONS handler from Subscribed to Subscribing, target=sip:stuart@208.73.144.71, id=c22fc3a1-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:52.288 1:40.476 Housekeeper:0xb267db40 OpalUDP Binding to interface: 0.0.0.0:5060 >2012/12/12 13:15:52.288 1:40.477 Housekeeper:0xb267db40 SIP Created transport udp$208.73.144.71:5060<if=udp$*:5060> >2012/12/12 13:15:52.289 1:40.477 Housekeeper:0xb267db40 OpalUDP Started connect to 208.73.144.71:5060 >2012/12/12 13:15:52.289 1:40.477 Housekeeper:0xb267db40 OpalUDP Writing to interface 0 - "192.168.9.34%em1" >2012/12/12 13:15:52.289 1:40.477 Housekeeper:0xb267db40 SIP Transaction created. >2012/12/12 13:15:52.290 1:40.478 Housekeeper:0xb267db40 OpalMan Listener interfaces: associated transport=None > udp$192.168.9.34:5060 >2012/12/12 13:15:52.295 1:40.483 Housekeeper:0xb267db40 SIP Generated default local URI: sip:stuart@24.248.44.159 >2012/12/12 13:15:52.300 1:40.488 Housekeeper:0xb267db40 SIP Transaction remote address is udp$208.73.144.71:5060 >2012/12/12 13:15:52.300 1:40.488 Housekeeper:0xb267db40 SIP Sending PDU (601 bytes) to: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >OPTIONS sip:stuart@208.73.144.71 SIP/2.0 >CSeq: 8 OPTIONS >Via: SIP/2.0/UDP 24.248.44.159:1024;branch=z9hG4bK3877c4a1-f542-e211-8519-000cf1d997b4;rport >User-Agent: Ekiga/4.0.0 >From: "Stuart D Gathman" <sip:stuart@24.248.44.159>;tag=7053c4a1-f542-e211-8519-000cf1d997b4 >Call-ID: c22fc3a1-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:stuart@208.73.144.71> >Accept: application/sdp, application/media_control+xml, application/dtmf, application/dtmf-relay >Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK >Expires: 3600 >Content-Length: 0 >Max-Forwards: 70 > > >2012/12/12 13:15:52.301 1:40.489 Housekeeper:0xb267db40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:15:52.301 1:40.489 Housekeeper:0xb267db40 SIP Transaction timers set: retry=0.500, completion=6.000 >2012/12/12 13:15:52.346 1:40.534 Opal Liste...0xb263cb40 OpalUDP Binding to interface: 192.168.9.34:5060 >2012/12/12 13:15:52.346 1:40.534 Opal Liste...0xb263cb40 SIP Waiting for PDU on udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:15:52.347 1:40.535 Opal Liste...0xb263cb40 SIP No Content-Length present from udp$208.73.144.71:5060<if=udp$24.248.44.159:1024>, reading till end of datagram/stream. >2012/12/12 13:15:52.347 1:40.535 Opal Liste...0xb263cb40 SIP PDU received: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >SIP/2.0 403 Forbidden >CSeq: 7 OPTIONS >Via: SIP/2.0/UDP 24.248.44.159:1024;received=24.248.44.159;branch=z9hG4bK40d0c2a1-f542-e211-8519-000cf1d997b4;rport=1024 >From: "Stuart D Gathman" <sip:stuart@24.248.44.159>;tag=3aaec2a1-f542-e211-8519-000cf1d997b4 >Call-ID: 0e8fb3a1-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:stuart@208.73.144.71>;tag=aprqngfrt-pj113j30000e0 > > >2012/12/12 13:15:52.348 1:40.536 Opal Liste...0xb263cb40 SIP Queueing PDU "7 OPTIONS <403>", transaction=z9hG4bK40d0c2a1-f542-e211-8519-000cf1d997b4, token=0e8fb3a1-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:52.348 1:40.536 Pool:0xabc81b40 SIP Handling PDU "7 OPTIONS <403>" for transaction=z9hG4bK40d0c2a1-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:52.348 1:40.536 Pool:0xabc81b40 SIP OPTIONS transaction id=z9hG4bK40d0c2a1-f542-e211-8519-000cf1d997b4 completing. >2012/12/12 13:15:52.348 1:40.536 Pool:0xabc81b40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:15:52.348 1:40.537 Pool:0xabc81b40 SIP Completed OPTIONS command to sip:stuart@208.73.144.71, status=403 Forbidden >2012/12/12 13:15:52.348 1:40.537 Pool:0xabc81b40 SIP Not retrying OPTIONS due to error response 403 Forbidden >2012/12/12 13:15:52.348 1:40.537 Pool:0xabc81b40 SIP Changing OPTIONS handler from Subscribing to Unsubscribed, target=sip:stuart@208.73.144.71, id=0e8fb3a1-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:52.348 1:40.537 Pool:0xabc81b40 SIP OPTIONS transaction id=z9hG4bK40d0c2a1-f542-e211-8519-000cf1d997b4 completed. >2012/12/12 13:15:52.349 1:40.537 Pool:0xabc81b40 SIP Handled PDU "7 OPTIONS <403>" >2012/12/12 13:15:52.349 1:40.537 Opal Liste...0xb263cb40 Opal Transport clean up on termination >2012/12/12 13:15:52.349 1:40.537 Opal Liste...0xb263cb40 Opal Transport Close >2012/12/12 13:15:52.349 1:40.537 Opal Liste...0xb263cb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:15:52.360 1:40.548 Opal Liste...0xb263cb40 OpalUDP Binding to interface: 192.168.9.34:5060 >2012/12/12 13:15:52.361 1:40.549 Opal Liste...0xb263cb40 SIP Waiting for PDU on udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:15:52.361 1:40.550 Opal Liste...0xb263cb40 SIP No Content-Length present from udp$208.73.144.71:5060<if=udp$24.248.44.159:1024>, reading till end of datagram/stream. >2012/12/12 13:15:52.362 1:40.550 Opal Liste...0xb263cb40 SIP PDU received: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >SIP/2.0 403 Forbidden >CSeq: 8 OPTIONS >Via: SIP/2.0/UDP 24.248.44.159:1024;received=24.248.44.159;branch=z9hG4bK3877c4a1-f542-e211-8519-000cf1d997b4;rport=1024 >From: "Stuart D Gathman" <sip:stuart@24.248.44.159>;tag=7053c4a1-f542-e211-8519-000cf1d997b4 >Call-ID: c22fc3a1-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:stuart@208.73.144.71>;tag=aprqngfrt-a21nsm30000g0 > > >2012/12/12 13:15:52.362 1:40.551 Opal Liste...0xb263cb40 SIP Queueing PDU "8 OPTIONS <403>", transaction=z9hG4bK3877c4a1-f542-e211-8519-000cf1d997b4, token=c22fc3a1-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:52.362 1:40.551 Pool:0xabc81b40 SIP Handling PDU "8 OPTIONS <403>" for transaction=z9hG4bK3877c4a1-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:52.362 1:40.551 Pool:0xabc81b40 SIP OPTIONS transaction id=z9hG4bK3877c4a1-f542-e211-8519-000cf1d997b4 completing. >2012/12/12 13:15:52.362 1:40.551 Pool:0xabc81b40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:15:52.363 1:40.551 Pool:0xabc81b40 SIP Completed OPTIONS command to sip:stuart@208.73.144.71, status=403 Forbidden >2012/12/12 13:15:52.363 1:40.551 Pool:0xabc81b40 SIP Not retrying OPTIONS due to error response 403 Forbidden >2012/12/12 13:15:52.363 1:40.551 Pool:0xabc81b40 SIP Changing OPTIONS handler from Subscribing to Unsubscribed, target=sip:stuart@208.73.144.71, id=c22fc3a1-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:52.363 1:40.551 Pool:0xabc81b40 SIP OPTIONS transaction id=z9hG4bK3877c4a1-f542-e211-8519-000cf1d997b4 completed. >2012/12/12 13:15:52.363 1:40.551 Pool:0xabc81b40 SIP Handled PDU "8 OPTIONS <403>" >2012/12/12 13:15:52.363 1:40.551 Opal Liste...0xb263cb40 Opal Transport clean up on termination >2012/12/12 13:15:52.363 1:40.551 Opal Liste...0xb263cb40 Opal Transport Close >2012/12/12 13:15:52.363 1:40.552 Opal Liste...0xb263cb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:15:53.068 1:41.256 Opal Garbage:0xb26ffb40 Opal Transport clean up on termination >2012/12/12 13:15:53.068 1:41.257 Opal Garbage:0xb26ffb40 Opal Transport Close >2012/12/12 13:15:53.068 1:41.257 Opal Garbage:0xb26ffb40 Opal Transport clean up on termination >2012/12/12 13:15:53.068 1:41.257 Opal Garbage:0xb26ffb40 Opal Transport Close >2012/12/12 13:15:53.068 1:41.257 Opal Garbage:0xb26ffb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:15:53.069 1:41.257 Opal Garbage:0xb26ffb40 SIP Destroyed OPTIONS handler for sip:stuart@208.73.144.71 >2012/12/12 13:15:53.069 1:41.258 Opal Garbage:0xb26ffb40 Opal Transport clean up on termination >2012/12/12 13:15:53.069 1:41.258 Opal Garbage:0xb26ffb40 Opal Transport Close >2012/12/12 13:15:53.069 1:41.258 Opal Garbage:0xb26ffb40 Opal Transport clean up on termination >2012/12/12 13:15:53.070 1:41.258 Opal Garbage:0xb26ffb40 Opal Transport Close >2012/12/12 13:15:53.070 1:41.258 Opal Garbage:0xb26ffb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:15:53.070 1:41.258 Opal Garbage:0xb26ffb40 SIP Destroyed OPTIONS handler for sip:stuart@208.73.144.71 >2012/12/12 13:15:53.348 1:41.537 Housekeeper:0xb267db40 SIP Set state Terminated_Success for OPTIONS transaction id=z9hG4bK40d0c2a1-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:53.349 1:41.537 Housekeeper:0xb267db40 SIP Completed state for transaction id=z9hG4bK40d0c2a1-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:53.374 1:41.562 Housekeeper:0xb267db40 SIP Set state Terminated_Success for OPTIONS transaction id=z9hG4bK3877c4a1-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:53.374 1:41.562 Housekeeper:0xb267db40 SIP Completed state for transaction id=z9hG4bK3877c4a1-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:54.071 1:42.259 Opal Garbage:0xb26ffb40 SIP Transaction id=z9hG4bK40d0c2a1-f542-e211-8519-000cf1d997b4 destroyed. >2012/12/12 13:15:54.071 1:42.259 Opal Garbage:0xb26ffb40 SIP Transaction id=z9hG4bK3877c4a1-f542-e211-8519-000cf1d997b4 destroyed. >2012/12/12 13:15:57.229 1:45.418 PWLib File handle high water mark set: 51 Thread unblock pipe >2012/12/12 13:15:57.229 1:45.418 PTLib Thread high water mark set: 13 >2012/12/12 13:15:57.234 1:45.422 subscriber:0xabbffb40 SIP Executing state change to Unsubscribing for REGISTER handler, target=sip:1264941142@prod.voipdnsservers.com, id=a026a09a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:57.234 1:45.422 subscriber:0xabbffb40 SIP Changing REGISTER handler from Subscribed to Unsubscribing, target=sip:1264941142@prod.voipdnsservers.com, id=a026a09a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:57.234 1:45.422 subscriber:0xabbffb40 SIP Transaction created. >2012/12/12 13:15:57.234 1:45.423 subscriber:0xabbffb40 PWLib File handle high water mark set: 52 PUDPSocket >2012/12/12 13:15:57.236 1:45.424 subscriber:0xabbffb40 HTTP Adding authentication information >2012/12/12 13:15:57.236 1:45.424 subscriber:0xabbffb40 DNS SRV Lookup "_sip._udp.prod.voipdnsservers.com" >2012/12/12 13:15:57.236 1:45.425 subscriber:0xabbffb40 SIP Attempting SRV record entry 0: 208.73.144.71:5060 >2012/12/12 13:15:57.236 1:45.425 subscriber:0xabbffb40 SIP Transaction remote address is udp$208.73.144.71:5060 >2012/12/12 13:15:57.237 1:45.425 subscriber:0xabbffb40 SIP Sending PDU (833 bytes) to: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >REGISTER sip:prod.voipdnsservers.com SIP/2.0 >CSeq: 9 REGISTER >Via: SIP/2.0/UDP 24.248.44.159:1024;branch=z9hG4bKdc1fb6a4-f542-e211-8519-000cf1d997b4;rport >User-Agent: Ekiga/4.0.0 >Authorization: Digest username="1264941142", realm="nextiva.com", nonce="BroadWorksXhams5709TrhqyoeBW", uri="sip:prod.voipdnsservers.com", algorithm=MD5, response="90d6cb4719aba22a08bb18cebad02a4f", cnonce="f87ebd9a-f542-e211-8519-000cf1d997b4", nc=00000002, qop=auth >From: <sip:1264941142@prod.voipdnsservers.com>;tag=4432a09a-f542-e211-8519-000cf1d997b4 >Call-ID: a026a09a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:1264941142@prod.voipdnsservers.com> >Contact: <sip:1264941142@24.248.44.159:1024>;q=1 >Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK >Expires: 0 >Content-Length: 0 >Max-Forwards: 70 > > >2012/12/12 13:15:57.242 1:45.430 subscriber:0xabbffb40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:15:57.242 1:45.430 subscriber:0xabbffb40 SIP Transaction timers set: retry=0.500, completion=6.000 >2012/12/12 13:15:57.242 1:45.430 subscriber:0xabbffb40 OpalPres 'sip:1264941142@prod.voipdnsservers.com' closing. >2012/12/12 13:15:57.242 1:45.430 subscriber:0xabbffb40 OpalPres Stopping command thread PThreadObj:0xabcc2b40 >2012/12/12 13:15:57.242 1:45.431 PThreadObj:0xabcc2b40 OpalPres Command thread ended >2012/12/12 13:15:57.242 1:45.431 PThreadObj:0xabcc2b40 PTLib Thread ended: name="PThreadObj:0xabcc2b40", real=16.928, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%) >2012/12/12 13:15:57.243 1:45.431 subscriber:0xabbffb40 SIPPres 'sip:1264941142@prod.voipdnsservers.com' sending own presence No Presence/ >2012/12/12 13:15:57.243 1:45.431 subscriber:0xabbffb40 SIPPres PIDF entity derived from AOR as pres:1264941142@prod.voipdnsservers.com >2012/12/12 13:15:57.243 1:45.431 subscriber:0xabbffb40 SIP Start PUBLISH > eventPackage=presence > aor=sip:1264941142@prod.voipdnsservers.com > remote=sip:1264941142@prod.voipdnsservers.com > local= > contact= > proxy= > authID= > realm= > expire=300 > restore=30 > minRetry=default > maxRetry=default >2012/12/12 13:15:57.245 1:45.433 subscriber:0xabbffb40 SIP Constructed PUBLISH handler for sip:1264941142@prod.voipdnsservers.com >2012/12/12 13:15:57.249 1:45.438 subscriber:0xabbffb40 SIP Executing state change to Subscribing for PUBLISH handler, target=sip:1264941142@prod.voipdnsservers.com, id=2c97b7a4-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:57.249 1:45.438 subscriber:0xabbffb40 SIP Changing PUBLISH handler from Unavailable to Subscribing, target=sip:1264941142@prod.voipdnsservers.com, id=2c97b7a4-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:57.250 1:45.438 subscriber:0xabbffb40 DNS SRV Lookup "_sip._udp.prod.voipdnsservers.com" >2012/12/12 13:15:57.250 1:45.438 subscriber:0xabbffb40 SIP Attempting SRV record entry 0: 208.73.144.71:5060 >2012/12/12 13:15:57.250 1:45.438 subscriber:0xabbffb40 OpalUDP Binding to interface: 0.0.0.0:5060 >2012/12/12 13:15:57.250 1:45.439 subscriber:0xabbffb40 PWLib File handle low water mark set: 46 PUDPSocket >2012/12/12 13:15:57.250 1:45.439 subscriber:0xabbffb40 SIP Created transport udp$208.73.144.71:5060<if=udp$*:5060> >2012/12/12 13:15:57.251 1:45.439 subscriber:0xabbffb40 OpalUDP Started connect to 208.73.144.71:5060 >2012/12/12 13:15:57.251 1:45.439 subscriber:0xabbffb40 OpalUDP Writing to interface 0 - "192.168.9.34%em1" >2012/12/12 13:15:57.251 1:45.439 subscriber:0xabbffb40 SIP Transaction created. >2012/12/12 13:15:57.253 1:45.441 subscriber:0xabbffb40 DNS SRV Lookup "_sip._udp.prod.voipdnsservers.com" >2012/12/12 13:15:57.256 1:45.445 subscriber:0xabbffb40 SIP Attempting SRV record entry 0: 208.73.144.71:5060 >2012/12/12 13:15:57.257 1:45.445 subscriber:0xabbffb40 SIP Transaction remote address is udp$208.73.144.71:5060 >2012/12/12 13:15:57.257 1:45.445 subscriber:0xabbffb40 SIP Sending PDU (928 bytes) to: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >PUBLISH sip:1264941142@prod.voipdnsservers.com SIP/2.0 >CSeq: 10 PUBLISH >Via: SIP/2.0/UDP 24.248.44.159:1024;branch=z9hG4bKbcbfb8a4-f542-e211-8519-000cf1d997b4;rport >User-Agent: Ekiga/4.0.0 >From: <sip:1264941142@prod.voipdnsservers.com> >Call-ID: 2c97b7a4-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:1264941142@prod.voipdnsservers.com> >Expires: 300 >Event: presence >Content-Length: 473 >Content-Type: application/pidf+xml >Max-Forwards: 70 > ><?xml version="1.0" encoding="UTF-8"?> ><presence xmlns="urn:ietf:params:xml:ns:pidf" xmlns:dm="urn:ietf:params:xml:ns:pidf:data-model" xmlns:rpid="urn:ietf:params:xml:ns:pidf:rpid" entity="pres:1264941142@prod.voipdnsservers.com"> > <tuple id="T8D811BD2"> > <status> > <basic>closed</basic> > </status> > <contact priority="1">sip:1264941142@prod.voipdnsservers.com</contact> > <timestamp>2012-12-12T13:15:57-05:00</timestamp> > </tuple> ></presence> > >2012/12/12 13:15:57.262 1:45.451 subscriber:0xabbffb40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:15:57.263 1:45.451 subscriber:0xabbffb40 SIP Transaction timers set: retry=0.500, completion=6.000 >2012/12/12 13:15:57.263 1:45.451 subscriber:0xabbffb40 SIPPres 'sip:1264941142@prod.voipdnsservers.com' sending final unsubscribe for own presence watcher >2012/12/12 13:15:57.263 1:45.452 subscriber:0xabbffb40 SIP Expiry time for SUBSCRIBE set to 300 seconds. >2012/12/12 13:15:57.264 1:45.452 subscriber:0xabbffb40 SIP Changing SUBSCRIBE handler from Unavailable to Unsubscribed, target=sip:1264941142@prod.voipdnsservers.com;OPAL-local-id=sip:1264941142%40prod.voipdnsservers.com, id=ee91a09a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:57.264 1:45.452 subscriber:0xabbffb40 SIP Start PUBLISH > eventPackage=presence > aor=sip:1264941142@prod.voipdnsservers.com > remote= > local= > contact= > proxy= > authID= > realm= > expire=0 > restore=30 > minRetry=default > maxRetry=default >2012/12/12 13:15:57.264 1:45.452 subscriber:0xabbffb40 SIP Queueing state change to Subscribing for PUBLISH handler while in Subscribing state, target=sip:1264941142@prod.voipdnsservers.com, id=2c97b7a4-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:57.264 1:45.453 subscriber:0xabbffb40 SIPPres 'sip:1264941142@prod.voipdnsservers.com' awaiting unsubscriptions to complete. >2012/12/12 13:15:57.264 1:45.453 subscriber:0xabbffb40 SIPPres 'sip:1264941142@prod.voipdnsservers.com' closed. >2012/12/12 13:15:57.265 1:45.453 subscriber:0xabbffb40 PTLib Thread ended: name="subscriber:0xabbffb40", real=0.034, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%) >2012/12/12 13:15:57.305 1:45.494 Opal Liste...0xb263cb40 OpalUDP Binding to interface: 192.168.9.34:5060 >2012/12/12 13:15:57.306 1:45.494 Opal Liste...0xb263cb40 SIP Waiting for PDU on udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:15:57.306 1:45.495 Opal Liste...0xb263cb40 SIP PDU received: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >SIP/2.0 200 OK >CSeq: 9 REGISTER >Via: SIP/2.0/UDP 24.248.44.159:1024;received=24.248.44.159;branch=z9hG4bKdc1fb6a4-f542-e211-8519-000cf1d997b4;rport=1024 >From: <sip:1264941142@prod.voipdnsservers.com>;tag=4432a09a-f542-e211-8519-000cf1d997b4 >Call-ID: a026a09a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:1264941142@prod.voipdnsservers.com>;tag=1267653806-1355336157276 >Allow-Events: call-info,line-seize,dialog,message-summary,as-feature-event,x-broadworks-hoteling,x-broadworks-call-center-status >Content-Length: 0 > > >2012/12/12 13:15:57.307 1:45.495 Opal Liste...0xb263cb40 SIP Queueing PDU "9 REGISTER <200>", transaction=z9hG4bKdc1fb6a4-f542-e211-8519-000cf1d997b4, token=a026a09a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:57.307 1:45.496 Pool:0xabc81b40 SIP Handling PDU "9 REGISTER <200>" for transaction=z9hG4bKdc1fb6a4-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:57.307 1:45.496 Pool:0xabc81b40 SIP REGISTER transaction id=z9hG4bKdc1fb6a4-f542-e211-8519-000cf1d997b4 completing. >2012/12/12 13:15:57.307 1:45.496 Pool:0xabc81b40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:15:57.308 1:45.496 Pool:0xabc81b40 SIP Changing REGISTER handler from Unsubscribing to Unsubscribed, target=sip:1264941142@prod.voipdnsservers.com, id=a026a09a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:57.308 1:45.496 Pool:0xabc81b40 SIP REGISTER transaction id=z9hG4bKdc1fb6a4-f542-e211-8519-000cf1d997b4 completed. >2012/12/12 13:15:57.308 1:45.496 Pool:0xabc81b40 SIP Handled PDU "9 REGISTER <200>" >2012/12/12 13:15:57.308 1:45.496 Opal Liste...0xb263cb40 Opal Transport clean up on termination >2012/12/12 13:15:57.308 1:45.496 Opal Liste...0xb263cb40 Opal Transport Close >2012/12/12 13:15:57.308 1:45.496 Opal Liste...0xb263cb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:15:57.317 1:45.505 Opal Liste...0xb263cb40 OpalUDP Binding to interface: 192.168.9.34:5060 >2012/12/12 13:15:57.318 1:45.506 Opal Liste...0xb263cb40 SIP Waiting for PDU on udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:15:57.318 1:45.507 Opal Liste...0xb263cb40 SIP No Content-Length present from udp$208.73.144.71:5060<if=udp$24.248.44.159:1024>, reading till end of datagram/stream. >2012/12/12 13:15:57.319 1:45.507 Opal Liste...0xb263cb40 SIP PDU received: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >SIP/2.0 403 Forbidden >CSeq: 10 PUBLISH >Via: SIP/2.0/UDP 24.248.44.159:1024;received=24.248.44.159;branch=z9hG4bKbcbfb8a4-f542-e211-8519-000cf1d997b4;rport=1024 >From: <sip:1264941142@prod.voipdnsservers.com> >Call-ID: 2c97b7a4-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:1264941142@prod.voipdnsservers.com>;tag=aprqngfrt-8hn4ur00000k0 > > >2012/12/12 13:15:57.319 1:45.507 Opal Liste...0xb263cb40 SIP Queueing PDU "10 PUBLISH <403>", transaction=z9hG4bKbcbfb8a4-f542-e211-8519-000cf1d997b4, token=2c97b7a4-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:57.319 1:45.508 Pool:0xabc81b40 SIP Handling PDU "10 PUBLISH <403>" for transaction=z9hG4bKbcbfb8a4-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:57.319 1:45.508 Pool:0xabc81b40 SIP PUBLISH transaction id=z9hG4bKbcbfb8a4-f542-e211-8519-000cf1d997b4 completing. >2012/12/12 13:15:57.319 1:45.508 Pool:0xabc81b40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:15:57.320 1:45.508 Pool:0xabc81b40 SIP Not retrying PUBLISH due to error response 403 Forbidden >2012/12/12 13:15:57.320 1:45.508 Pool:0xabc81b40 SIP Changing PUBLISH handler from Subscribing to Unsubscribed, target=sip:1264941142@prod.voipdnsservers.com, id=2c97b7a4-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:57.320 1:45.508 Pool:0xabc81b40 SIP Changing PUBLISH handler from Unsubscribed to Subscribing, target=sip:1264941142@prod.voipdnsservers.com, id=2c97b7a4-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:57.320 1:45.508 Pool:0xabc81b40 SIP Transaction created. >2012/12/12 13:15:57.321 1:45.509 Pool:0xabc81b40 DNS SRV Lookup "_sip._udp.prod.voipdnsservers.com" >2012/12/12 13:15:57.321 1:45.510 Pool:0xabc81b40 SIP Attempting SRV record entry 0: 208.73.144.71:5060 >2012/12/12 13:15:57.322 1:45.510 Pool:0xabc81b40 SIP Transaction remote address is udp$208.73.144.71:5060 >2012/12/12 13:15:57.322 1:45.510 Pool:0xabc81b40 SIP Sending PDU (417 bytes) to: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >PUBLISH sip:1264941142@prod.voipdnsservers.com SIP/2.0 >CSeq: 11 PUBLISH >Via: SIP/2.0/UDP 24.248.44.159:1024;branch=z9hG4bKf431c3a4-f542-e211-8519-000cf1d997b4;rport >User-Agent: Ekiga/4.0.0 >From: <sip:1264941142@prod.voipdnsservers.com> >Call-ID: 2c97b7a4-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:1264941142@prod.voipdnsservers.com> >Expires: 300 >Event: presence >Content-Length: 0 >Max-Forwards: 70 > > >2012/12/12 13:15:57.323 1:45.511 Opal Liste...0xb263cb40 Opal Transport clean up on termination >2012/12/12 13:15:57.323 1:45.511 Opal Liste...0xb263cb40 Opal Transport Close >2012/12/12 13:15:57.326 1:45.515 Pool:0xabc81b40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:15:57.323 1:45.511 Opal Liste...0xb263cb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:15:57.327 1:45.515 Pool:0xabc81b40 SIP Transaction timers set: retry=0.500, completion=6.000 >2012/12/12 13:15:57.327 1:45.515 Pool:0xabc81b40 SIP PUBLISH transaction id=z9hG4bKbcbfb8a4-f542-e211-8519-000cf1d997b4 completed. >2012/12/12 13:15:57.332 1:45.521 Pool:0xabc81b40 SIP Handled PDU "10 PUBLISH <403>" >2012/12/12 13:15:57.381 1:45.569 Opal Liste...0xb263cb40 OpalUDP Binding to interface: 192.168.9.34:5060 >2012/12/12 13:15:57.382 1:45.570 Opal Liste...0xb263cb40 SIP Waiting for PDU on udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:15:57.382 1:45.571 Opal Liste...0xb263cb40 SIP No Content-Length present from udp$208.73.144.71:5060<if=udp$24.248.44.159:1024>, reading till end of datagram/stream. >2012/12/12 13:15:57.383 1:45.571 Opal Liste...0xb263cb40 SIP PDU received: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >SIP/2.0 403 Forbidden >CSeq: 11 PUBLISH >Via: SIP/2.0/UDP 24.248.44.159:1024;received=24.248.44.159;branch=z9hG4bKf431c3a4-f542-e211-8519-000cf1d997b4;rport=1024 >From: <sip:1264941142@prod.voipdnsservers.com> >Call-ID: 2c97b7a4-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:1264941142@prod.voipdnsservers.com>;tag=aprqngfrt-8hn4ur00000m0 > > >2012/12/12 13:15:57.383 1:45.571 Opal Liste...0xb263cb40 SIP Queueing PDU "11 PUBLISH <403>", transaction=z9hG4bKf431c3a4-f542-e211-8519-000cf1d997b4, token=2c97b7a4-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:57.383 1:45.572 Pool:0xabc81b40 SIP Handling PDU "11 PUBLISH <403>" for transaction=z9hG4bKf431c3a4-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:57.383 1:45.572 Pool:0xabc81b40 SIP PUBLISH transaction id=z9hG4bKf431c3a4-f542-e211-8519-000cf1d997b4 completing. >2012/12/12 13:15:57.383 1:45.572 Pool:0xabc81b40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:15:57.383 1:45.572 Pool:0xabc81b40 SIP Not retrying PUBLISH due to error response 403 Forbidden >2012/12/12 13:15:57.384 1:45.572 Pool:0xabc81b40 SIP Changing PUBLISH handler from Subscribing to Unsubscribed, target=sip:1264941142@prod.voipdnsservers.com, id=2c97b7a4-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:15:57.384 1:45.572 Pool:0xabc81b40 SIP PUBLISH transaction id=z9hG4bKf431c3a4-f542-e211-8519-000cf1d997b4 completed. >2012/12/12 13:15:57.384 1:45.572 Pool:0xabc81b40 SIP Handled PDU "11 PUBLISH <403>" >2012/12/12 13:15:57.384 1:45.572 Opal Liste...0xb263cb40 Opal Transport clean up on termination >2012/12/12 13:15:57.384 1:45.572 Opal Liste...0xb263cb40 Opal Transport Close >2012/12/12 13:15:57.384 1:45.572 Opal Liste...0xb263cb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:15:58.073 1:46.261 Opal Garbage:0xb26ffb40 Opal Transport clean up on termination >2012/12/12 13:15:58.073 1:46.261 Opal Garbage:0xb26ffb40 Opal Transport Close >2012/12/12 13:15:58.073 1:46.261 Opal Garbage:0xb26ffb40 Opal Transport clean up on termination >2012/12/12 13:15:58.073 1:46.261 Opal Garbage:0xb26ffb40 Opal Transport Close >2012/12/12 13:15:58.073 1:46.261 Opal Garbage:0xb26ffb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:15:58.074 1:46.262 Opal Garbage:0xb26ffb40 SIP Destroyed REGISTER handler for sip:1264941142@prod.voipdnsservers.com >2012/12/12 13:15:58.074 1:46.262 Opal Garbage:0xb26ffb40 SIP Destroyed SUBSCRIBE handler for sip:1264941142@prod.voipdnsservers.com;OPAL-local-id=sip:1264941142%40prod.voipdnsservers.com >2012/12/12 13:15:58.074 1:46.263 Opal Garbage:0xb26ffb40 Opal Transport clean up on termination >2012/12/12 13:15:58.074 1:46.263 Opal Garbage:0xb26ffb40 Opal Transport Close >2012/12/12 13:15:58.074 1:46.263 Opal Garbage:0xb26ffb40 Opal Transport clean up on termination >2012/12/12 13:15:58.074 1:46.263 Opal Garbage:0xb26ffb40 Opal Transport Close >2012/12/12 13:15:58.075 1:46.263 Opal Garbage:0xb26ffb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:15:58.075 1:46.263 Opal Garbage:0xb26ffb40 SIP Destroyed PUBLISH handler for sip:1264941142@prod.voipdnsservers.com >2012/12/12 13:15:58.312 1:46.501 Housekeeper:0xb267db40 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bKdc1fb6a4-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:58.312 1:46.501 Housekeeper:0xb267db40 SIP Completed state for transaction id=z9hG4bKdc1fb6a4-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:58.338 1:46.526 Housekeeper:0xb267db40 SIP Set state Terminated_Success for PUBLISH transaction id=z9hG4bKbcbfb8a4-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:58.338 1:46.526 Housekeeper:0xb267db40 SIP Completed state for transaction id=z9hG4bKbcbfb8a4-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:58.384 1:46.572 Housekeeper:0xb267db40 SIP Set state Terminated_Success for PUBLISH transaction id=z9hG4bKf431c3a4-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:58.384 1:46.573 Housekeeper:0xb267db40 SIP Completed state for transaction id=z9hG4bKf431c3a4-f542-e211-8519-000cf1d997b4 >2012/12/12 13:15:59.076 1:47.264 Opal Garbage:0xb26ffb40 SIP Transaction id=z9hG4bKf431c3a4-f542-e211-8519-000cf1d997b4 destroyed. >2012/12/12 13:15:59.076 1:47.264 Opal Garbage:0xb26ffb40 SIP Transaction id=z9hG4bKdc1fb6a4-f542-e211-8519-000cf1d997b4 destroyed. >2012/12/12 13:15:59.076 1:47.264 Opal Garbage:0xb26ffb40 SIP Transaction id=z9hG4bKbcbfb8a4-f542-e211-8519-000cf1d997b4 destroyed. >2012/12/12 13:16:02.186 1:50.374 Housekeeper:0xb267db40 SIP Constructed OPTIONS handler for sip:stuart@208.73.144.71 >2012/12/12 13:16:02.186 1:50.375 Housekeeper:0xb267db40 SIP Changing OPTIONS handler from Unavailable to Subscribed, target=sip:stuart@208.73.144.71, id=fc90a9a7-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:16:02.186 1:50.375 Housekeeper:0xb267db40 SIP Executing state change to Subscribing for OPTIONS handler, target=sip:stuart@208.73.144.71, id=fc90a9a7-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:16:02.186 1:50.375 Housekeeper:0xb267db40 SIP Changing OPTIONS handler from Subscribed to Subscribing, target=sip:stuart@208.73.144.71, id=fc90a9a7-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:16:02.187 1:50.375 Housekeeper:0xb267db40 OpalUDP Binding to interface: 0.0.0.0:5060 >2012/12/12 13:16:02.187 1:50.375 Housekeeper:0xb267db40 SIP Created transport udp$208.73.144.71:5060<if=udp$*:5060> >2012/12/12 13:16:02.187 1:50.375 Housekeeper:0xb267db40 OpalUDP Started connect to 208.73.144.71:5060 >2012/12/12 13:16:02.187 1:50.376 Housekeeper:0xb267db40 OpalUDP Writing to interface 0 - "192.168.9.34%em1" >2012/12/12 13:16:02.188 1:50.376 Housekeeper:0xb267db40 SIP Transaction created. >2012/12/12 13:16:02.188 1:50.377 Housekeeper:0xb267db40 OpalMan Listener interfaces: associated transport=None > udp$192.168.9.34:5060 >2012/12/12 13:16:02.284 1:50.473 Housekeeper:0xb267db40 SIP Generated default local URI: sip:stuart@24.248.44.159 >2012/12/12 13:16:02.286 1:50.475 Housekeeper:0xb267db40 SIP Transaction remote address is udp$208.73.144.71:5060 >2012/12/12 13:16:02.287 1:50.475 Housekeeper:0xb267db40 SIP Sending PDU (602 bytes) to: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >OPTIONS sip:stuart@208.73.144.71 SIP/2.0 >CSeq: 12 OPTIONS >Via: SIP/2.0/UDP 24.248.44.159:1024;branch=z9hG4bK58d0b8a7-f542-e211-8519-000cf1d997b4;rport >User-Agent: Ekiga/4.0.0 >From: "Stuart D Gathman" <sip:stuart@24.248.44.159>;tag=5696b8a7-f542-e211-8519-000cf1d997b4 >Call-ID: fc90a9a7-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:stuart@208.73.144.71> >Accept: application/sdp, application/media_control+xml, application/dtmf, application/dtmf-relay >Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK >Expires: 3600 >Content-Length: 0 >Max-Forwards: 70 > > >2012/12/12 13:16:02.288 1:50.476 Housekeeper:0xb267db40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:16:02.288 1:50.476 Housekeeper:0xb267db40 SIP Transaction timers set: retry=0.500, completion=6.000 >2012/12/12 13:16:02.346 1:50.535 Opal Liste...0xb263cb40 OpalUDP Binding to interface: 192.168.9.34:5060 >2012/12/12 13:16:02.347 1:50.535 Opal Liste...0xb263cb40 SIP Waiting for PDU on udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:16:02.347 1:50.536 Opal Liste...0xb263cb40 SIP No Content-Length present from udp$208.73.144.71:5060<if=udp$24.248.44.159:1024>, reading till end of datagram/stream. >2012/12/12 13:16:02.348 1:50.536 Opal Liste...0xb263cb40 SIP PDU received: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >SIP/2.0 403 Forbidden >CSeq: 12 OPTIONS >Via: SIP/2.0/UDP 24.248.44.159:1024;received=24.248.44.159;branch=z9hG4bK58d0b8a7-f542-e211-8519-000cf1d997b4;rport=1024 >From: "Stuart D Gathman" <sip:stuart@24.248.44.159>;tag=5696b8a7-f542-e211-8519-000cf1d997b4 >Call-ID: fc90a9a7-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:stuart@208.73.144.71>;tag=aprqngfrt-412ngp30000o0 > > >2012/12/12 13:16:02.348 1:50.536 Opal Liste...0xb263cb40 SIP Queueing PDU "12 OPTIONS <403>", transaction=z9hG4bK58d0b8a7-f542-e211-8519-000cf1d997b4, token=fc90a9a7-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:16:02.348 1:50.537 Pool:0xabc81b40 SIP Handling PDU "12 OPTIONS <403>" for transaction=z9hG4bK58d0b8a7-f542-e211-8519-000cf1d997b4 >2012/12/12 13:16:02.349 1:50.537 Pool:0xabc81b40 SIP OPTIONS transaction id=z9hG4bK58d0b8a7-f542-e211-8519-000cf1d997b4 completing. >2012/12/12 13:16:02.349 1:50.537 Pool:0xabc81b40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:16:02.349 1:50.537 Pool:0xabc81b40 SIP Completed OPTIONS command to sip:stuart@208.73.144.71, status=403 Forbidden >2012/12/12 13:16:02.349 1:50.537 Pool:0xabc81b40 SIP Not retrying OPTIONS due to error response 403 Forbidden >2012/12/12 13:16:02.349 1:50.537 Pool:0xabc81b40 SIP Changing OPTIONS handler from Subscribing to Unsubscribed, target=sip:stuart@208.73.144.71, id=fc90a9a7-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:16:02.349 1:50.537 Pool:0xabc81b40 SIP OPTIONS transaction id=z9hG4bK58d0b8a7-f542-e211-8519-000cf1d997b4 completed. >2012/12/12 13:16:02.349 1:50.537 Pool:0xabc81b40 SIP Handled PDU "12 OPTIONS <403>" >2012/12/12 13:16:02.349 1:50.537 Opal Liste...0xb263cb40 Opal Transport clean up on termination >2012/12/12 13:16:02.349 1:50.538 Opal Liste...0xb263cb40 Opal Transport Close >2012/12/12 13:16:02.349 1:50.538 Opal Liste...0xb263cb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:16:03.077 1:51.265 Opal Garbage:0xb26ffb40 Opal Transport clean up on termination >2012/12/12 13:16:03.077 1:51.265 Opal Garbage:0xb26ffb40 Opal Transport Close >2012/12/12 13:16:03.077 1:51.265 Opal Garbage:0xb26ffb40 Opal Transport clean up on termination >2012/12/12 13:16:03.077 1:51.266 Opal Garbage:0xb26ffb40 Opal Transport Close >2012/12/12 13:16:03.077 1:51.266 Opal Garbage:0xb26ffb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:16:03.078 1:51.266 Opal Garbage:0xb26ffb40 SIP Destroyed OPTIONS handler for sip:stuart@208.73.144.71 >2012/12/12 13:16:03.351 1:51.540 Housekeeper:0xb267db40 SIP Set state Terminated_Success for OPTIONS transaction id=z9hG4bK58d0b8a7-f542-e211-8519-000cf1d997b4 >2012/12/12 13:16:03.352 1:51.540 Housekeeper:0xb267db40 SIP Completed state for transaction id=z9hG4bK58d0b8a7-f542-e211-8519-000cf1d997b4 >2012/12/12 13:16:04.088 1:52.277 Opal Garbage:0xb26ffb40 SIP Transaction id=z9hG4bK58d0b8a7-f542-e211-8519-000cf1d997b4 destroyed. >2012/12/12 13:16:12.187 2:00.375 Housekeeper:0xb267db40 SIP Constructed OPTIONS handler for sip:stuart@208.73.144.71 >2012/12/12 13:16:12.187 2:00.376 Housekeeper:0xb267db40 SIP Changing OPTIONS handler from Unavailable to Subscribed, target=sip:stuart@208.73.144.71, id=1a9a9fad-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:16:12.187 2:00.376 Housekeeper:0xb267db40 SIP Executing state change to Subscribing for OPTIONS handler, target=sip:stuart@208.73.144.71, id=1a9a9fad-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:16:12.187 2:00.376 Housekeeper:0xb267db40 SIP Changing OPTIONS handler from Subscribed to Subscribing, target=sip:stuart@208.73.144.71, id=1a9a9fad-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:16:12.188 2:00.376 Housekeeper:0xb267db40 OpalUDP Binding to interface: 0.0.0.0:5060 >2012/12/12 13:16:12.188 2:00.376 Housekeeper:0xb267db40 SIP Created transport udp$208.73.144.71:5060<if=udp$*:5060> >2012/12/12 13:16:12.188 2:00.376 Housekeeper:0xb267db40 OpalUDP Started connect to 208.73.144.71:5060 >2012/12/12 13:16:12.188 2:00.377 Housekeeper:0xb267db40 OpalUDP Writing to interface 0 - "192.168.9.34%em1" >2012/12/12 13:16:12.189 2:00.377 Housekeeper:0xb267db40 SIP Transaction created. >2012/12/12 13:16:12.189 2:00.378 Housekeeper:0xb267db40 OpalMan Listener interfaces: associated transport=None > udp$192.168.9.34:5060 >2012/12/12 13:16:12.288 2:00.476 Housekeeper:0xb267db40 SIP Generated default local URI: sip:stuart@24.248.44.159 >2012/12/12 13:16:12.290 2:00.478 Housekeeper:0xb267db40 SIP Transaction remote address is udp$208.73.144.71:5060 >2012/12/12 13:16:12.290 2:00.478 Housekeeper:0xb267db40 SIP Sending PDU (602 bytes) to: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >OPTIONS sip:stuart@208.73.144.71 SIP/2.0 >CSeq: 13 OPTIONS >Via: SIP/2.0/UDP 24.248.44.159:1024;branch=z9hG4bK8632afad-f542-e211-8519-000cf1d997b4;rport >User-Agent: Ekiga/4.0.0 >From: "Stuart D Gathman" <sip:stuart@24.248.44.159>;tag=aa0eafad-f542-e211-8519-000cf1d997b4 >Call-ID: 1a9a9fad-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:stuart@208.73.144.71> >Accept: application/sdp, application/media_control+xml, application/dtmf, application/dtmf-relay >Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK >Expires: 3600 >Content-Length: 0 >Max-Forwards: 70 > > >2012/12/12 13:16:12.291 2:00.479 Housekeeper:0xb267db40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:16:12.291 2:00.479 Housekeeper:0xb267db40 SIP Transaction timers set: retry=0.500, completion=6.000 >2012/12/12 13:16:12.349 2:00.538 Opal Liste...0xb263cb40 OpalUDP Binding to interface: 192.168.9.34:5060 >2012/12/12 13:16:12.350 2:00.538 Opal Liste...0xb263cb40 SIP Waiting for PDU on udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:16:12.350 2:00.539 Opal Liste...0xb263cb40 SIP No Content-Length present from udp$208.73.144.71:5060<if=udp$24.248.44.159:1024>, reading till end of datagram/stream. >2012/12/12 13:16:12.351 2:00.539 Opal Liste...0xb263cb40 SIP PDU received: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >SIP/2.0 403 Forbidden >CSeq: 13 OPTIONS >Via: SIP/2.0/UDP 24.248.44.159:1024;received=24.248.44.159;branch=z9hG4bK8632afad-f542-e211-8519-000cf1d997b4;rport=1024 >From: "Stuart D Gathman" <sip:stuart@24.248.44.159>;tag=aa0eafad-f542-e211-8519-000cf1d997b4 >Call-ID: 1a9a9fad-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:stuart@208.73.144.71>;tag=aprqngfrt-ruc5p620000q0 > > >2012/12/12 13:16:12.351 2:00.539 Opal Liste...0xb263cb40 SIP Queueing PDU "13 OPTIONS <403>", transaction=z9hG4bK8632afad-f542-e211-8519-000cf1d997b4, token=1a9a9fad-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:16:12.352 2:00.540 Pool:0xabc81b40 SIP Handling PDU "13 OPTIONS <403>" for transaction=z9hG4bK8632afad-f542-e211-8519-000cf1d997b4 >2012/12/12 13:16:12.352 2:00.540 Pool:0xabc81b40 SIP OPTIONS transaction id=z9hG4bK8632afad-f542-e211-8519-000cf1d997b4 completing. >2012/12/12 13:16:12.352 2:00.540 Pool:0xabc81b40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:16:12.352 2:00.540 Pool:0xabc81b40 SIP Completed OPTIONS command to sip:stuart@208.73.144.71, status=403 Forbidden >2012/12/12 13:16:12.352 2:00.540 Pool:0xabc81b40 SIP Not retrying OPTIONS due to error response 403 Forbidden >2012/12/12 13:16:12.352 2:00.540 Pool:0xabc81b40 SIP Changing OPTIONS handler from Subscribing to Unsubscribed, target=sip:stuart@208.73.144.71, id=1a9a9fad-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:16:12.352 2:00.540 Pool:0xabc81b40 SIP OPTIONS transaction id=z9hG4bK8632afad-f542-e211-8519-000cf1d997b4 completed. >2012/12/12 13:16:12.352 2:00.540 Pool:0xabc81b40 SIP Handled PDU "13 OPTIONS <403>" >2012/12/12 13:16:12.352 2:00.541 Opal Liste...0xb263cb40 Opal Transport clean up on termination >2012/12/12 13:16:12.352 2:00.541 Opal Liste...0xb263cb40 Opal Transport Close >2012/12/12 13:16:12.352 2:00.541 Opal Liste...0xb263cb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:16:13.091 2:01.280 Opal Garbage:0xb26ffb40 Opal Transport clean up on termination >2012/12/12 13:16:13.091 2:01.280 Opal Garbage:0xb26ffb40 Opal Transport Close >2012/12/12 13:16:13.091 2:01.280 Opal Garbage:0xb26ffb40 Opal Transport clean up on termination >2012/12/12 13:16:13.092 2:01.280 Opal Garbage:0xb26ffb40 Opal Transport Close >2012/12/12 13:16:13.092 2:01.280 Opal Garbage:0xb26ffb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:16:13.092 2:01.280 Opal Garbage:0xb26ffb40 SIP Destroyed OPTIONS handler for sip:stuart@208.73.144.71 >2012/12/12 13:16:13.352 2:01.540 Housekeeper:0xb267db40 SIP Set state Terminated_Success for OPTIONS transaction id=z9hG4bK8632afad-f542-e211-8519-000cf1d997b4 >2012/12/12 13:16:13.352 2:01.540 Housekeeper:0xb267db40 SIP Completed state for transaction id=z9hG4bK8632afad-f542-e211-8519-000cf1d997b4 >2012/12/12 13:16:14.093 2:02.281 Opal Garbage:0xb26ffb40 SIP Transaction id=z9hG4bK8632afad-f542-e211-8519-000cf1d997b4 destroyed. >2012/12/12 13:16:22.187 2:10.376 Housekeeper:0xb267db40 SIP Constructed OPTIONS handler for sip:stuart@208.73.144.71 >2012/12/12 13:16:22.187 2:10.376 Housekeeper:0xb267db40 SIP Changing OPTIONS handler from Unavailable to Subscribed, target=sip:stuart@208.73.144.71, id=8a7e95b3-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:16:22.187 2:10.376 Housekeeper:0xb267db40 SIP Executing state change to Subscribing for OPTIONS handler, target=sip:stuart@208.73.144.71, id=8a7e95b3-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:16:22.187 2:10.376 Housekeeper:0xb267db40 SIP Changing OPTIONS handler from Subscribed to Subscribing, target=sip:stuart@208.73.144.71, id=8a7e95b3-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:16:22.188 2:10.376 Housekeeper:0xb267db40 OpalUDP Binding to interface: 0.0.0.0:5060 >2012/12/12 13:16:22.188 2:10.376 Housekeeper:0xb267db40 SIP Created transport udp$208.73.144.71:5060<if=udp$*:5060> >2012/12/12 13:16:22.188 2:10.377 Housekeeper:0xb267db40 OpalUDP Started connect to 208.73.144.71:5060 >2012/12/12 13:16:22.189 2:10.377 Housekeeper:0xb267db40 OpalUDP Writing to interface 0 - "192.168.9.34%em1" >2012/12/12 13:16:22.189 2:10.377 Housekeeper:0xb267db40 SIP Transaction created. >2012/12/12 13:16:22.189 2:10.378 Housekeeper:0xb267db40 OpalMan Listener interfaces: associated transport=None > udp$192.168.9.34:5060 >2012/12/12 13:16:22.286 2:10.474 Housekeeper:0xb267db40 SIP Generated default local URI: sip:stuart@24.248.44.159 >2012/12/12 13:16:22.288 2:10.476 Housekeeper:0xb267db40 SIP Transaction remote address is udp$208.73.144.71:5060 >2012/12/12 13:16:22.288 2:10.476 Housekeeper:0xb267db40 SIP Sending PDU (602 bytes) to: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >OPTIONS sip:stuart@208.73.144.71 SIP/2.0 >CSeq: 14 OPTIONS >Via: SIP/2.0/UDP 24.248.44.159:1024;branch=z9hG4bK0ec3a4b3-f542-e211-8519-000cf1d997b4;rport >User-Agent: Ekiga/4.0.0 >From: "Stuart D Gathman" <sip:stuart@24.248.44.159>;tag=d29fa4b3-f542-e211-8519-000cf1d997b4 >Call-ID: 8a7e95b3-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:stuart@208.73.144.71> >Accept: application/sdp, application/media_control+xml, application/dtmf, application/dtmf-relay >Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK >Expires: 3600 >Content-Length: 0 >Max-Forwards: 70 > > >2012/12/12 13:16:22.289 2:10.477 Housekeeper:0xb267db40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:16:22.289 2:10.478 Housekeeper:0xb267db40 SIP Transaction timers set: retry=0.499, completion=5.999 >2012/12/12 13:16:22.348 2:10.536 Opal Liste...0xb263cb40 OpalUDP Binding to interface: 192.168.9.34:5060 >2012/12/12 13:16:22.348 2:10.536 Opal Liste...0xb263cb40 SIP Waiting for PDU on udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:16:22.348 2:10.537 Opal Liste...0xb263cb40 SIP No Content-Length present from udp$208.73.144.71:5060<if=udp$24.248.44.159:1024>, reading till end of datagram/stream. >2012/12/12 13:16:22.349 2:10.537 Opal Liste...0xb263cb40 SIP PDU received: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >SIP/2.0 403 Forbidden >CSeq: 14 OPTIONS >Via: SIP/2.0/UDP 24.248.44.159:1024;received=24.248.44.159;branch=z9hG4bK0ec3a4b3-f542-e211-8519-000cf1d997b4;rport=1024 >From: "Stuart D Gathman" <sip:stuart@24.248.44.159>;tag=d29fa4b3-f542-e211-8519-000cf1d997b4 >Call-ID: 8a7e95b3-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:stuart@208.73.144.71>;tag=aprqngfrt-dad33f20000s0 > > >2012/12/12 13:16:22.350 2:10.538 Opal Liste...0xb263cb40 SIP Queueing PDU "14 OPTIONS <403>", transaction=z9hG4bK0ec3a4b3-f542-e211-8519-000cf1d997b4, token=8a7e95b3-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:16:22.350 2:10.538 Pool:0xabc81b40 SIP Handling PDU "14 OPTIONS <403>" for transaction=z9hG4bK0ec3a4b3-f542-e211-8519-000cf1d997b4 >2012/12/12 13:16:22.350 2:10.539 Pool:0xabc81b40 SIP OPTIONS transaction id=z9hG4bK0ec3a4b3-f542-e211-8519-000cf1d997b4 completing. >2012/12/12 13:16:22.350 2:10.539 Pool:0xabc81b40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:16:22.350 2:10.539 Pool:0xabc81b40 SIP Completed OPTIONS command to sip:stuart@208.73.144.71, status=403 Forbidden >2012/12/12 13:16:22.350 2:10.539 Pool:0xabc81b40 SIP Not retrying OPTIONS due to error response 403 Forbidden >2012/12/12 13:16:22.351 2:10.539 Pool:0xabc81b40 SIP Changing OPTIONS handler from Subscribing to Unsubscribed, target=sip:stuart@208.73.144.71, id=8a7e95b3-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:16:22.351 2:10.539 Pool:0xabc81b40 SIP OPTIONS transaction id=z9hG4bK0ec3a4b3-f542-e211-8519-000cf1d997b4 completed. >2012/12/12 13:16:22.351 2:10.539 Pool:0xabc81b40 SIP Handled PDU "14 OPTIONS <403>" >2012/12/12 13:16:22.351 2:10.539 Opal Liste...0xb263cb40 Opal Transport clean up on termination >2012/12/12 13:16:22.351 2:10.539 Opal Liste...0xb263cb40 Opal Transport Close >2012/12/12 13:16:22.351 2:10.539 Opal Liste...0xb263cb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:16:23.095 2:11.283 Opal Garbage:0xb26ffb40 Opal Transport clean up on termination >2012/12/12 13:16:23.095 2:11.283 Opal Garbage:0xb26ffb40 Opal Transport Close >2012/12/12 13:16:23.095 2:11.283 Opal Garbage:0xb26ffb40 Opal Transport clean up on termination >2012/12/12 13:16:23.095 2:11.283 Opal Garbage:0xb26ffb40 Opal Transport Close >2012/12/12 13:16:23.095 2:11.283 Opal Garbage:0xb26ffb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:16:23.096 2:11.284 Opal Garbage:0xb26ffb40 SIP Destroyed OPTIONS handler for sip:stuart@208.73.144.71 >2012/12/12 13:16:23.351 2:11.539 Housekeeper:0xb267db40 SIP Set state Terminated_Success for OPTIONS transaction id=z9hG4bK0ec3a4b3-f542-e211-8519-000cf1d997b4 >2012/12/12 13:16:23.351 2:11.540 Housekeeper:0xb267db40 SIP Completed state for transaction id=z9hG4bK0ec3a4b3-f542-e211-8519-000cf1d997b4 >2012/12/12 13:16:24.096 2:12.284 Opal Garbage:0xb26ffb40 SIP Transaction id=z9hG4bK0ec3a4b3-f542-e211-8519-000cf1d997b4 destroyed. >2012/12/12 13:16:32.187 2:20.376 Housekeeper:0xb267db40 SIP Constructed OPTIONS handler for sip:stuart@208.73.144.71 >2012/12/12 13:16:32.188 2:20.376 Housekeeper:0xb267db40 SIP Changing OPTIONS handler from Unavailable to Subscribed, target=sip:stuart@208.73.144.71, id=346a8bb9-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:16:32.188 2:20.376 Housekeeper:0xb267db40 SIP Executing state change to Subscribing for OPTIONS handler, target=sip:stuart@208.73.144.71, id=346a8bb9-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:16:32.188 2:20.376 Housekeeper:0xb267db40 SIP Changing OPTIONS handler from Subscribed to Subscribing, target=sip:stuart@208.73.144.71, id=346a8bb9-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:16:32.188 2:20.376 Housekeeper:0xb267db40 OpalUDP Binding to interface: 0.0.0.0:5060 >2012/12/12 13:16:32.188 2:20.376 Housekeeper:0xb267db40 SIP Created transport udp$208.73.144.71:5060<if=udp$*:5060> >2012/12/12 13:16:32.189 2:20.377 Housekeeper:0xb267db40 OpalUDP Started connect to 208.73.144.71:5060 >2012/12/12 13:16:32.189 2:20.377 Housekeeper:0xb267db40 OpalUDP Writing to interface 0 - "192.168.9.34%em1" >2012/12/12 13:16:32.189 2:20.377 Housekeeper:0xb267db40 SIP Transaction created. >2012/12/12 13:16:32.190 2:20.378 Housekeeper:0xb267db40 OpalMan Listener interfaces: associated transport=None > udp$192.168.9.34:5060 >2012/12/12 13:16:32.288 2:20.476 Housekeeper:0xb267db40 SIP Generated default local URI: sip:stuart@24.248.44.159 >2012/12/12 13:16:32.289 2:20.478 Housekeeper:0xb267db40 SIP Transaction remote address is udp$208.73.144.71:5060 >2012/12/12 13:16:32.290 2:20.478 Housekeeper:0xb267db40 SIP Sending PDU (602 bytes) to: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >OPTIONS sip:stuart@208.73.144.71 SIP/2.0 >CSeq: 15 OPTIONS >Via: SIP/2.0/UDP 24.248.44.159:1024;branch=z9hG4bKe2e39ab9-f542-e211-8519-000cf1d997b4;rport >User-Agent: Ekiga/4.0.0 >From: "Stuart D Gathman" <sip:stuart@24.248.44.159>;tag=4cc09ab9-f542-e211-8519-000cf1d997b4 >Call-ID: 346a8bb9-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:stuart@208.73.144.71> >Accept: application/sdp, application/media_control+xml, application/dtmf, application/dtmf-relay >Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK >Expires: 3600 >Content-Length: 0 >Max-Forwards: 70 > > >2012/12/12 13:16:32.290 2:20.479 Housekeeper:0xb267db40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:16:32.290 2:20.479 Housekeeper:0xb267db40 SIP Transaction timers set: retry=0.500, completion=6.000 >2012/12/12 13:16:32.349 2:20.538 Opal Liste...0xb263cb40 OpalUDP Binding to interface: 192.168.9.34:5060 >2012/12/12 13:16:32.350 2:20.538 Opal Liste...0xb263cb40 SIP Waiting for PDU on udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:16:32.350 2:20.538 Opal Liste...0xb263cb40 SIP No Content-Length present from udp$208.73.144.71:5060<if=udp$24.248.44.159:1024>, reading till end of datagram/stream. >2012/12/12 13:16:32.351 2:20.539 Opal Liste...0xb263cb40 SIP PDU received: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >SIP/2.0 403 Forbidden >CSeq: 15 OPTIONS >Via: SIP/2.0/UDP 24.248.44.159:1024;received=24.248.44.159;branch=z9hG4bKe2e39ab9-f542-e211-8519-000cf1d997b4;rport=1024 >From: "Stuart D Gathman" <sip:stuart@24.248.44.159>;tag=4cc09ab9-f542-e211-8519-000cf1d997b4 >Call-ID: 346a8bb9-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:stuart@208.73.144.71>;tag=aprqngfrt-d5n20130000u0 > > >2012/12/12 13:16:32.351 2:20.540 Opal Liste...0xb263cb40 SIP Queueing PDU "15 OPTIONS <403>", transaction=z9hG4bKe2e39ab9-f542-e211-8519-000cf1d997b4, token=346a8bb9-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:16:32.351 2:20.540 Pool:0xabc81b40 SIP Handling PDU "15 OPTIONS <403>" for transaction=z9hG4bKe2e39ab9-f542-e211-8519-000cf1d997b4 >2012/12/12 13:16:32.351 2:20.540 Pool:0xabc81b40 SIP OPTIONS transaction id=z9hG4bKe2e39ab9-f542-e211-8519-000cf1d997b4 completing. >2012/12/12 13:16:32.352 2:20.540 Pool:0xabc81b40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:16:32.352 2:20.540 Pool:0xabc81b40 SIP Completed OPTIONS command to sip:stuart@208.73.144.71, status=403 Forbidden >2012/12/12 13:16:32.352 2:20.540 Pool:0xabc81b40 SIP Not retrying OPTIONS due to error response 403 Forbidden >2012/12/12 13:16:32.352 2:20.540 Pool:0xabc81b40 SIP Changing OPTIONS handler from Subscribing to Unsubscribed, target=sip:stuart@208.73.144.71, id=346a8bb9-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:16:32.352 2:20.540 Pool:0xabc81b40 SIP OPTIONS transaction id=z9hG4bKe2e39ab9-f542-e211-8519-000cf1d997b4 completed. >2012/12/12 13:16:32.352 2:20.540 Pool:0xabc81b40 SIP Handled PDU "15 OPTIONS <403>" >2012/12/12 13:16:32.352 2:20.540 Opal Liste...0xb263cb40 Opal Transport clean up on termination >2012/12/12 13:16:32.352 2:20.540 Opal Liste...0xb263cb40 Opal Transport Close >2012/12/12 13:16:32.352 2:20.541 Opal Liste...0xb263cb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:16:33.098 2:21.286 Opal Garbage:0xb26ffb40 Opal Transport clean up on termination >2012/12/12 13:16:33.098 2:21.286 Opal Garbage:0xb26ffb40 Opal Transport Close >2012/12/12 13:16:33.098 2:21.286 Opal Garbage:0xb26ffb40 Opal Transport clean up on termination >2012/12/12 13:16:33.098 2:21.286 Opal Garbage:0xb26ffb40 Opal Transport Close >2012/12/12 13:16:33.098 2:21.286 Opal Garbage:0xb26ffb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:16:33.099 2:21.287 Opal Garbage:0xb26ffb40 SIP Destroyed OPTIONS handler for sip:stuart@208.73.144.71 >2012/12/12 13:16:33.352 2:21.540 Housekeeper:0xb267db40 SIP Set state Terminated_Success for OPTIONS transaction id=z9hG4bKe2e39ab9-f542-e211-8519-000cf1d997b4 >2012/12/12 13:16:33.352 2:21.541 Housekeeper:0xb267db40 SIP Completed state for transaction id=z9hG4bKe2e39ab9-f542-e211-8519-000cf1d997b4 >2012/12/12 13:16:34.099 2:22.288 Opal Garbage:0xb26ffb40 SIP Transaction id=z9hG4bKe2e39ab9-f542-e211-8519-000cf1d997b4 destroyed. >2012/12/12 13:16:42.188 2:30.376 Housekeeper:0xb267db40 SIP Constructed OPTIONS handler for sip:stuart@208.73.144.71 >2012/12/12 13:16:42.188 2:30.376 Housekeeper:0xb267db40 SIP Changing OPTIONS handler from Unavailable to Subscribed, target=sip:stuart@208.73.144.71, id=1c5981bf-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:16:42.188 2:30.377 Housekeeper:0xb267db40 SIP Executing state change to Subscribing for OPTIONS handler, target=sip:stuart@208.73.144.71, id=1c5981bf-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:16:42.188 2:30.377 Housekeeper:0xb267db40 SIP Changing OPTIONS handler from Subscribed to Subscribing, target=sip:stuart@208.73.144.71, id=1c5981bf-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:16:42.189 2:30.377 Housekeeper:0xb267db40 OpalUDP Binding to interface: 0.0.0.0:5060 >2012/12/12 13:16:42.189 2:30.377 Housekeeper:0xb267db40 SIP Created transport udp$208.73.144.71:5060<if=udp$*:5060> >2012/12/12 13:16:42.189 2:30.377 Housekeeper:0xb267db40 OpalUDP Started connect to 208.73.144.71:5060 >2012/12/12 13:16:42.189 2:30.377 Housekeeper:0xb267db40 OpalUDP Writing to interface 0 - "192.168.9.34%em1" >2012/12/12 13:16:42.189 2:30.378 Housekeeper:0xb267db40 SIP Transaction created. >2012/12/12 13:16:42.190 2:30.378 Housekeeper:0xb267db40 OpalMan Listener interfaces: associated transport=None > udp$192.168.9.34:5060 >2012/12/12 13:16:42.289 2:30.477 Housekeeper:0xb267db40 SIP Generated default local URI: sip:stuart@24.248.44.159 >2012/12/12 13:16:42.291 2:30.479 Housekeeper:0xb267db40 SIP Transaction remote address is udp$208.73.144.71:5060 >2012/12/12 13:16:42.291 2:30.479 Housekeeper:0xb267db40 SIP Sending PDU (602 bytes) to: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >OPTIONS sip:stuart@208.73.144.71 SIP/2.0 >CSeq: 16 OPTIONS >Via: SIP/2.0/UDP 24.248.44.159:1024;branch=z9hG4bKc8fc90bf-f542-e211-8519-000cf1d997b4;rport >User-Agent: Ekiga/4.0.0 >From: "Stuart D Gathman" <sip:stuart@24.248.44.159>;tag=50d990bf-f542-e211-8519-000cf1d997b4 >Call-ID: 1c5981bf-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:stuart@208.73.144.71> >Accept: application/sdp, application/media_control+xml, application/dtmf, application/dtmf-relay >Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK >Expires: 3600 >Content-Length: 0 >Max-Forwards: 70 > > >2012/12/12 13:16:42.292 2:30.480 Housekeeper:0xb267db40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:16:42.292 2:30.480 Housekeeper:0xb267db40 SIP Transaction timers set: retry=0.500, completion=6.000 >2012/12/12 13:16:42.351 2:30.540 Opal Liste...0xb263cb40 OpalUDP Binding to interface: 192.168.9.34:5060 >2012/12/12 13:16:42.352 2:30.540 Opal Liste...0xb263cb40 SIP Waiting for PDU on udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:16:42.353 2:30.541 Opal Liste...0xb263cb40 SIP No Content-Length present from udp$208.73.144.71:5060<if=udp$24.248.44.159:1024>, reading till end of datagram/stream. >2012/12/12 13:16:42.353 2:30.542 Opal Liste...0xb263cb40 SIP PDU received: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >SIP/2.0 403 Forbidden >CSeq: 16 OPTIONS >Via: SIP/2.0/UDP 24.248.44.159:1024;received=24.248.44.159;branch=z9hG4bKc8fc90bf-f542-e211-8519-000cf1d997b4;rport=1024 >From: "Stuart D Gathman" <sip:stuart@24.248.44.159>;tag=50d990bf-f542-e211-8519-000cf1d997b4 >Call-ID: 1c5981bf-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:stuart@208.73.144.71>;tag=aprqngfrt-e2naj03000001 > > >2012/12/12 13:16:42.354 2:30.542 Opal Liste...0xb263cb40 SIP Queueing PDU "16 OPTIONS <403>", transaction=z9hG4bKc8fc90bf-f542-e211-8519-000cf1d997b4, token=1c5981bf-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:16:42.354 2:30.542 Pool:0xabc81b40 SIP Handling PDU "16 OPTIONS <403>" for transaction=z9hG4bKc8fc90bf-f542-e211-8519-000cf1d997b4 >2012/12/12 13:16:42.354 2:30.542 Pool:0xabc81b40 SIP OPTIONS transaction id=z9hG4bKc8fc90bf-f542-e211-8519-000cf1d997b4 completing. >2012/12/12 13:16:42.354 2:30.543 Pool:0xabc81b40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:16:42.354 2:30.543 Pool:0xabc81b40 SIP Completed OPTIONS command to sip:stuart@208.73.144.71, status=403 Forbidden >2012/12/12 13:16:42.354 2:30.543 Pool:0xabc81b40 SIP Not retrying OPTIONS due to error response 403 Forbidden >2012/12/12 13:16:42.354 2:30.543 Pool:0xabc81b40 SIP Changing OPTIONS handler from Subscribing to Unsubscribed, target=sip:stuart@208.73.144.71, id=1c5981bf-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:16:42.354 2:30.543 Pool:0xabc81b40 SIP OPTIONS transaction id=z9hG4bKc8fc90bf-f542-e211-8519-000cf1d997b4 completed. >2012/12/12 13:16:42.355 2:30.543 Pool:0xabc81b40 SIP Handled PDU "16 OPTIONS <403>" >2012/12/12 13:16:42.355 2:30.543 Opal Liste...0xb263cb40 Opal Transport clean up on termination >2012/12/12 13:16:42.355 2:30.543 Opal Liste...0xb263cb40 Opal Transport Close >2012/12/12 13:16:42.355 2:30.543 Opal Liste...0xb263cb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:16:43.101 2:31.289 Opal Garbage:0xb26ffb40 Opal Transport clean up on termination >2012/12/12 13:16:43.101 2:31.290 Opal Garbage:0xb26ffb40 Opal Transport Close >2012/12/12 13:16:43.101 2:31.290 Opal Garbage:0xb26ffb40 Opal Transport clean up on termination >2012/12/12 13:16:43.101 2:31.290 Opal Garbage:0xb26ffb40 Opal Transport Close >2012/12/12 13:16:43.101 2:31.290 Opal Garbage:0xb26ffb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:16:43.102 2:31.290 Opal Garbage:0xb26ffb40 SIP Destroyed OPTIONS handler for sip:stuart@208.73.144.71 >2012/12/12 13:16:43.354 2:31.543 Housekeeper:0xb267db40 SIP Set state Terminated_Success for OPTIONS transaction id=z9hG4bKc8fc90bf-f542-e211-8519-000cf1d997b4 >2012/12/12 13:16:43.355 2:31.543 Housekeeper:0xb267db40 SIP Completed state for transaction id=z9hG4bKc8fc90bf-f542-e211-8519-000cf1d997b4 >2012/12/12 13:16:44.103 2:32.291 Opal Garbage:0xb26ffb40 SIP Transaction id=z9hG4bKc8fc90bf-f542-e211-8519-000cf1d997b4 destroyed. >2012/12/12 13:16:52.188 2:40.376 Housekeeper:0xb267db40 SIP Constructed OPTIONS handler for sip:stuart@208.73.144.71 >2012/12/12 13:16:52.188 2:40.376 Housekeeper:0xb267db40 SIP Changing OPTIONS handler from Unavailable to Subscribed, target=sip:stuart@208.73.144.71, id=c04077c5-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:16:52.189 2:40.377 Housekeeper:0xb267db40 SIP Executing state change to Subscribing for OPTIONS handler, target=sip:stuart@208.73.144.71, id=c04077c5-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:16:52.189 2:40.377 Housekeeper:0xb267db40 SIP Changing OPTIONS handler from Subscribed to Subscribing, target=sip:stuart@208.73.144.71, id=c04077c5-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:16:52.189 2:40.378 Housekeeper:0xb267db40 OpalUDP Binding to interface: 0.0.0.0:5060 >2012/12/12 13:16:52.190 2:40.378 Housekeeper:0xb267db40 SIP Created transport udp$208.73.144.71:5060<if=udp$*:5060> >2012/12/12 13:16:52.190 2:40.378 Housekeeper:0xb267db40 OpalUDP Started connect to 208.73.144.71:5060 >2012/12/12 13:16:52.190 2:40.378 Housekeeper:0xb267db40 OpalUDP Writing to interface 0 - "192.168.9.34%em1" >2012/12/12 13:16:52.190 2:40.379 Housekeeper:0xb267db40 SIP Transaction created. >2012/12/12 13:16:52.191 2:40.379 Housekeeper:0xb267db40 OpalMan Listener interfaces: associated transport=None > udp$192.168.9.34:5060 >2012/12/12 13:16:52.289 2:40.478 Housekeeper:0xb267db40 SIP Generated default local URI: sip:stuart@24.248.44.159 >2012/12/12 13:16:52.291 2:40.479 Housekeeper:0xb267db40 SIP Transaction remote address is udp$208.73.144.71:5060 >2012/12/12 13:16:52.292 2:40.480 Housekeeper:0xb267db40 SIP Sending PDU (602 bytes) to: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >OPTIONS sip:stuart@208.73.144.71 SIP/2.0 >CSeq: 17 OPTIONS >Via: SIP/2.0/UDP 24.248.44.159:1024;branch=z9hG4bK8eef86c5-f542-e211-8519-000cf1d997b4;rport >User-Agent: Ekiga/4.0.0 >From: "Stuart D Gathman" <sip:stuart@24.248.44.159>;tag=feca86c5-f542-e211-8519-000cf1d997b4 >Call-ID: c04077c5-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:stuart@208.73.144.71> >Accept: application/sdp, application/media_control+xml, application/dtmf, application/dtmf-relay >Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK >Expires: 3600 >Content-Length: 0 >Max-Forwards: 70 > > >2012/12/12 13:16:52.292 2:40.481 Housekeeper:0xb267db40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:16:52.292 2:40.481 Housekeeper:0xb267db40 SIP Transaction timers set: retry=0.500, completion=6.000 >2012/12/12 13:16:52.351 2:40.540 Opal Liste...0xb263cb40 OpalUDP Binding to interface: 192.168.9.34:5060 >2012/12/12 13:16:52.352 2:40.540 Opal Liste...0xb263cb40 SIP Waiting for PDU on udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:16:52.352 2:40.541 Opal Liste...0xb263cb40 SIP No Content-Length present from udp$208.73.144.71:5060<if=udp$24.248.44.159:1024>, reading till end of datagram/stream. >2012/12/12 13:16:52.353 2:40.541 Opal Liste...0xb263cb40 SIP PDU received: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >SIP/2.0 403 Forbidden >CSeq: 17 OPTIONS >Via: SIP/2.0/UDP 24.248.44.159:1024;received=24.248.44.159;branch=z9hG4bK8eef86c5-f542-e211-8519-000cf1d997b4;rport=1024 >From: "Stuart D Gathman" <sip:stuart@24.248.44.159>;tag=feca86c5-f542-e211-8519-000cf1d997b4 >Call-ID: c04077c5-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:stuart@208.73.144.71>;tag=aprqngfrt-f23vvo3000021 > > >2012/12/12 13:16:52.354 2:40.542 Opal Liste...0xb263cb40 SIP Queueing PDU "17 OPTIONS <403>", transaction=z9hG4bK8eef86c5-f542-e211-8519-000cf1d997b4, token=c04077c5-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:16:52.354 2:40.542 Pool:0xabc81b40 SIP Handling PDU "17 OPTIONS <403>" for transaction=z9hG4bK8eef86c5-f542-e211-8519-000cf1d997b4 >2012/12/12 13:16:52.354 2:40.542 Pool:0xabc81b40 SIP OPTIONS transaction id=z9hG4bK8eef86c5-f542-e211-8519-000cf1d997b4 completing. >2012/12/12 13:16:52.354 2:40.542 Pool:0xabc81b40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:16:52.354 2:40.543 Pool:0xabc81b40 SIP Completed OPTIONS command to sip:stuart@208.73.144.71, status=403 Forbidden >2012/12/12 13:16:52.354 2:40.543 Pool:0xabc81b40 SIP Not retrying OPTIONS due to error response 403 Forbidden >2012/12/12 13:16:52.354 2:40.543 Pool:0xabc81b40 SIP Changing OPTIONS handler from Subscribing to Unsubscribed, target=sip:stuart@208.73.144.71, id=c04077c5-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:16:52.354 2:40.543 Pool:0xabc81b40 SIP OPTIONS transaction id=z9hG4bK8eef86c5-f542-e211-8519-000cf1d997b4 completed. >2012/12/12 13:16:52.354 2:40.543 Pool:0xabc81b40 SIP Handled PDU "17 OPTIONS <403>" >2012/12/12 13:16:52.355 2:40.543 Opal Liste...0xb263cb40 Opal Transport clean up on termination >2012/12/12 13:16:52.355 2:40.543 Opal Liste...0xb263cb40 Opal Transport Close >2012/12/12 13:16:52.355 2:40.543 Opal Liste...0xb263cb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:16:53.104 2:41.293 Opal Garbage:0xb26ffb40 Opal Transport clean up on termination >2012/12/12 13:16:53.104 2:41.293 Opal Garbage:0xb26ffb40 Opal Transport Close >2012/12/12 13:16:53.105 2:41.293 Opal Garbage:0xb26ffb40 Opal Transport clean up on termination >2012/12/12 13:16:53.105 2:41.293 Opal Garbage:0xb26ffb40 Opal Transport Close >2012/12/12 13:16:53.105 2:41.293 Opal Garbage:0xb26ffb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:16:53.105 2:41.293 Opal Garbage:0xb26ffb40 SIP Destroyed OPTIONS handler for sip:stuart@208.73.144.71 >2012/12/12 13:16:53.355 2:41.543 Housekeeper:0xb267db40 SIP Set state Terminated_Success for OPTIONS transaction id=z9hG4bK8eef86c5-f542-e211-8519-000cf1d997b4 >2012/12/12 13:16:53.355 2:41.543 Housekeeper:0xb267db40 SIP Completed state for transaction id=z9hG4bK8eef86c5-f542-e211-8519-000cf1d997b4 >2012/12/12 13:16:54.106 2:42.294 Opal Garbage:0xb26ffb40 SIP Transaction id=z9hG4bK8eef86c5-f542-e211-8519-000cf1d997b4 destroyed. >2012/12/12 13:17:02.190 2:50.378 Housekeeper:0xb267db40 SIP Constructed OPTIONS handler for sip:stuart@208.73.144.71 >2012/12/12 13:17:02.190 2:50.378 Housekeeper:0xb267db40 SIP Changing OPTIONS handler from Unavailable to Subscribed, target=sip:stuart@208.73.144.71, id=34626dcb-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:17:02.190 2:50.379 Housekeeper:0xb267db40 SIP Executing state change to Subscribing for OPTIONS handler, target=sip:stuart@208.73.144.71, id=34626dcb-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:17:02.190 2:50.379 Housekeeper:0xb267db40 SIP Changing OPTIONS handler from Subscribed to Subscribing, target=sip:stuart@208.73.144.71, id=34626dcb-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:17:02.191 2:50.379 Housekeeper:0xb267db40 OpalUDP Binding to interface: 0.0.0.0:5060 >2012/12/12 13:17:02.191 2:50.379 Housekeeper:0xb267db40 SIP Created transport udp$208.73.144.71:5060<if=udp$*:5060> >2012/12/12 13:17:02.191 2:50.379 Housekeeper:0xb267db40 OpalUDP Started connect to 208.73.144.71:5060 >2012/12/12 13:17:02.191 2:50.380 Housekeeper:0xb267db40 OpalUDP Writing to interface 0 - "192.168.9.34%em1" >2012/12/12 13:17:02.191 2:50.380 Housekeeper:0xb267db40 SIP Transaction created. >2012/12/12 13:17:02.192 2:50.380 Housekeeper:0xb267db40 OpalMan Listener interfaces: associated transport=None > udp$192.168.9.34:5060 >2012/12/12 13:17:02.289 2:50.477 Housekeeper:0xb267db40 SIP Generated default local URI: sip:stuart@24.248.44.159 >2012/12/12 13:17:02.291 2:50.479 Housekeeper:0xb267db40 SIP Transaction remote address is udp$208.73.144.71:5060 >2012/12/12 13:17:02.291 2:50.479 Housekeeper:0xb267db40 SIP Sending PDU (602 bytes) to: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >OPTIONS sip:stuart@208.73.144.71 SIP/2.0 >CSeq: 18 OPTIONS >Via: SIP/2.0/UDP 24.248.44.159:1024;branch=z9hG4bK8cb97ccb-f542-e211-8519-000cf1d997b4;rport >User-Agent: Ekiga/4.0.0 >From: "Stuart D Gathman" <sip:stuart@24.248.44.159>;tag=b6947ccb-f542-e211-8519-000cf1d997b4 >Call-ID: 34626dcb-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:stuart@208.73.144.71> >Accept: application/sdp, application/media_control+xml, application/dtmf, application/dtmf-relay >Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK >Expires: 3600 >Content-Length: 0 >Max-Forwards: 70 > > >2012/12/12 13:17:02.292 2:50.480 Housekeeper:0xb267db40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:17:02.292 2:50.480 Housekeeper:0xb267db40 SIP Transaction timers set: retry=0.500, completion=6.000 >2012/12/12 13:17:02.350 2:50.538 Opal Liste...0xb263cb40 OpalUDP Binding to interface: 192.168.9.34:5060 >2012/12/12 13:17:02.351 2:50.539 Opal Liste...0xb263cb40 SIP Waiting for PDU on udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:17:02.351 2:50.540 Opal Liste...0xb263cb40 SIP No Content-Length present from udp$208.73.144.71:5060<if=udp$24.248.44.159:1024>, reading till end of datagram/stream. >2012/12/12 13:17:02.352 2:50.540 Opal Liste...0xb263cb40 SIP PDU received: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >SIP/2.0 403 Forbidden >CSeq: 18 OPTIONS >Via: SIP/2.0/UDP 24.248.44.159:1024;received=24.248.44.159;branch=z9hG4bK8cb97ccb-f542-e211-8519-000cf1d997b4;rport=1024 >From: "Stuart D Gathman" <sip:stuart@24.248.44.159>;tag=b6947ccb-f542-e211-8519-000cf1d997b4 >Call-ID: 34626dcb-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:stuart@208.73.144.71>;tag=aprqngfrt-sb21jb2000041 > > >2012/12/12 13:17:02.352 2:50.540 Opal Liste...0xb263cb40 SIP Queueing PDU "18 OPTIONS <403>", transaction=z9hG4bK8cb97ccb-f542-e211-8519-000cf1d997b4, token=34626dcb-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:17:02.352 2:50.541 Pool:0xabc81b40 SIP Handling PDU "18 OPTIONS <403>" for transaction=z9hG4bK8cb97ccb-f542-e211-8519-000cf1d997b4 >2012/12/12 13:17:02.352 2:50.541 Pool:0xabc81b40 SIP OPTIONS transaction id=z9hG4bK8cb97ccb-f542-e211-8519-000cf1d997b4 completing. >2012/12/12 13:17:02.352 2:50.541 Pool:0xabc81b40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:17:02.353 2:50.541 Pool:0xabc81b40 SIP Completed OPTIONS command to sip:stuart@208.73.144.71, status=403 Forbidden >2012/12/12 13:17:02.353 2:50.541 Pool:0xabc81b40 SIP Not retrying OPTIONS due to error response 403 Forbidden >2012/12/12 13:17:02.353 2:50.541 Pool:0xabc81b40 SIP Changing OPTIONS handler from Subscribing to Unsubscribed, target=sip:stuart@208.73.144.71, id=34626dcb-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:17:02.353 2:50.541 Pool:0xabc81b40 SIP OPTIONS transaction id=z9hG4bK8cb97ccb-f542-e211-8519-000cf1d997b4 completed. >2012/12/12 13:17:02.353 2:50.541 Pool:0xabc81b40 SIP Handled PDU "18 OPTIONS <403>" >2012/12/12 13:17:02.353 2:50.541 Opal Liste...0xb263cb40 Opal Transport clean up on termination >2012/12/12 13:17:02.353 2:50.541 Opal Liste...0xb263cb40 Opal Transport Close >2012/12/12 13:17:02.353 2:50.541 Opal Liste...0xb263cb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:17:03.108 2:51.296 Opal Garbage:0xb26ffb40 Opal Transport clean up on termination >2012/12/12 13:17:03.108 2:51.296 Opal Garbage:0xb26ffb40 Opal Transport Close >2012/12/12 13:17:03.108 2:51.296 Opal Garbage:0xb26ffb40 Opal Transport clean up on termination >2012/12/12 13:17:03.108 2:51.296 Opal Garbage:0xb26ffb40 Opal Transport Close >2012/12/12 13:17:03.108 2:51.296 Opal Garbage:0xb26ffb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:17:03.108 2:51.297 Opal Garbage:0xb26ffb40 SIP Destroyed OPTIONS handler for sip:stuart@208.73.144.71 >2012/12/12 13:17:03.353 2:51.541 Housekeeper:0xb267db40 SIP Set state Terminated_Success for OPTIONS transaction id=z9hG4bK8cb97ccb-f542-e211-8519-000cf1d997b4 >2012/12/12 13:17:03.354 2:51.542 Housekeeper:0xb267db40 SIP Completed state for transaction id=z9hG4bK8cb97ccb-f542-e211-8519-000cf1d997b4 >2012/12/12 13:17:03.354 2:51.542 OpalMan Clearing all calls and waiting, primary thread. >2012/12/12 13:17:04.109 2:52.297 Opal Garbage:0xb26ffb40 SIP Transaction id=z9hG4bK8cb97ccb-f542-e211-8519-000cf1d997b4 destroyed. >2012/12/12 13:17:04.109 2:52.298 OpalMan All calls cleared. >2012/12/12 13:17:04.109 2:52.298 OpalMan Shutting down endpoints. >2012/12/12 13:17:04.110 2:52.298 OpalMan Clearing all calls and waiting, primary thread. >2012/12/12 13:17:05.110 2:53.298 OpalMan All calls cleared. >2012/12/12 13:17:05.110 2:53.298 OpalEP pc endpoint shutting down. >2012/12/12 13:17:05.110 2:53.298 SIP Shutting down. >2012/12/12 13:17:05.110 2:53.299 SIP Changing SUBSCRIBE handler from Subscribed to Unsubscribing, target=sip:1264941142@prod.voipdnsservers.com, id=d4aeca9a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:17:05.111 2:53.299 SIP Transaction created. >2012/12/12 13:17:05.113 2:53.302 HTTP Adding authentication information >2012/12/12 13:17:05.114 2:53.302 SIP No SRV lookup as has explicit port number. >2012/12/12 13:17:05.114 2:53.302 SIP Transaction remote address is udp$208.73.144.71:5060 >2012/12/12 13:17:05.114 2:53.303 SIP Sending PDU (953 bytes) to: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >SUBSCRIBE sip:1264941142@208.73.144.71:5060;transport=udp SIP/2.0 >CSeq: 3 SUBSCRIBE >Via: SIP/2.0/UDP 24.248.44.159:1024;branch=z9hG4bK84432bcd-f542-e211-8519-000cf1d997b4;rport >User-Agent: Ekiga/4.0.0 >Authorization: Digest username="1264941142", realm="nextiva.com", nonce="BroadWorksXhams574qTfjypyiBW", uri="sip:1264941142@208.73.144.71:5060", algorithm=MD5, response="e7606a582a1d6d598bb128076e57b7cf", cnonce="a0e1d59a-f542-e211-8519-000cf1d997b4", nc=00000002, qop=auth >From: <sip:1264941142@prod.voipdnsservers.com>;tag=c800cb9a-f542-e211-8519-000cf1d997b4 >Call-ID: d4aeca9a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:1264941142@prod.voipdnsservers.com>;tag=807005338-1355336140722 >Accept: application/simple-message-summary >Contact: <sip:1264941142@24.248.44.159:1024> >Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK >Expires: 0 >Event: message-summary >Content-Length: 0 >Max-Forwards: 70 > > >2012/12/12 13:17:05.115 2:53.304 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:17:05.116 2:53.304 SIP Transaction timers set: retry=0.500, completion=6.000 >2012/12/12 13:17:05.177 2:53.365 Opal Liste...0xb263cb40 OpalUDP Binding to interface: 192.168.9.34:5060 >2012/12/12 13:17:05.177 2:53.365 Opal Liste...0xb263cb40 SIP Waiting for PDU on udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:17:05.178 2:53.366 Opal Liste...0xb263cb40 SIP PDU received: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >SIP/2.0 200 OK >CSeq: 3 SUBSCRIBE >Via: SIP/2.0/UDP 24.248.44.159:1024;received=24.248.44.159;branch=z9hG4bK84432bcd-f542-e211-8519-000cf1d997b4;rport=1024 >From: <sip:1264941142@prod.voipdnsservers.com>;tag=c800cb9a-f542-e211-8519-000cf1d997b4 >Call-ID: d4aeca9a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:1264941142@prod.voipdnsservers.com>;tag=807005338-1355336140722 >Contact: <sip:1264941142@208.73.144.71:5060;transport=udp> >Expires: 0 >Content-Length: 0 > > >2012/12/12 13:17:05.178 2:53.367 Opal Liste...0xb263cb40 SIP Queueing PDU "3 SUBSCRIBE <200>", transaction=z9hG4bK84432bcd-f542-e211-8519-000cf1d997b4, token=d4aeca9a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >2012/12/12 13:17:05.179 2:53.367 Pool:0xabc81b40 SIP Handling PDU "3 SUBSCRIBE <200>" for transaction=z9hG4bK84432bcd-f542-e211-8519-000cf1d997b4 >2012/12/12 13:17:05.179 2:53.367 Pool:0xabc81b40 SIP SUBSCRIBE transaction id=z9hG4bK84432bcd-f542-e211-8519-000cf1d997b4 completing. >2012/12/12 13:17:05.179 2:53.367 Pool:0xabc81b40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:17:05.179 2:53.367 Pool:0xabc81b40 SIP Expiry time for SUBSCRIBE set to 0 seconds. >2012/12/12 13:17:05.179 2:53.367 Pool:0xabc81b40 SIP Route set is >2012/12/12 13:17:05.179 2:53.368 Pool:0xabc81b40 SIP Set Request URI to sip:1264941142@208.73.144.71:5060;transport=udp >2012/12/12 13:17:05.180 2:53.368 Pool:0xabc81b40 SIP SUBSCRIBE transaction id=z9hG4bK84432bcd-f542-e211-8519-000cf1d997b4 completed. >2012/12/12 13:17:05.180 2:53.368 Pool:0xabc81b40 SIP Handled PDU "3 SUBSCRIBE <200>" >2012/12/12 13:17:05.180 2:53.368 Opal Liste...0xb263cb40 Opal Transport clean up on termination >2012/12/12 13:17:05.180 2:53.368 Opal Liste...0xb263cb40 Opal Transport Close >2012/12/12 13:17:05.180 2:53.368 Opal Liste...0xb263cb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:17:05.181 2:53.369 Opal Liste...0xb263cb40 OpalUDP Binding to interface: 192.168.9.34:5060 >2012/12/12 13:17:05.181 2:53.369 Opal Liste...0xb263cb40 SIP Waiting for PDU on udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:17:05.182 2:53.370 Opal Liste...0xb263cb40 SIP PDU received: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >NOTIFY sip:1264941142@24.248.44.159:1024 SIP/2.0 >CSeq: 274043261 NOTIFY >Via: SIP/2.0/UDP 208.73.144.71:5060;branch=z9hG4bKeqscqa2010r1js0di1v1skg8l2pu1.1 >From: <sip:1264941142@208.73.144.71>;tag=807005338-1355336140722 >Call-ID: d4aeca9a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:1264941142@24.248.44.159>;tag=c800cb9a-f542-e211-8519-000cf1d997b4 >Contact: <sip:1264941142@208.73.144.71:5060;transport=udp> >Subscription-State: terminated >Event: message-summary >Content-Type: application/simple-message-summary >Content-Length: 42 >Max-Forwards: 9 > >Messages-Waiting: no >voice-message: 0/0 > >2012/12/12 13:17:05.183 2:53.371 Opal Liste...0xb263cb40 SIP Sending PDU (364 bytes) to: rem=udp$208.73.144.71:5060,local=udp$24.248.44.159:1024,if=192.168.9.34%em1 >SIP/2.0 503 Service Unavailable >CSeq: 274043261 NOTIFY >Via: SIP/2.0/UDP 208.73.144.71:5060;branch=z9hG4bKeqscqa2010r1js0di1v1skg8l2pu1.1 >From: <sip:1264941142@208.73.144.71>;tag=807005338-1355336140722 >Call-ID: d4aeca9a-f542-e211-8519-000cf1d997b4@sdg.bmsi.com >To: <sip:1264941142@24.248.44.159>;tag=c800cb9a-f542-e211-8519-000cf1d997b4 >Content-Length: 0 > > >2012/12/12 13:17:05.183 2:53.372 Opal Liste...0xb263cb40 OpalUDP Setting interface to 192.168.9.34%em1 >2012/12/12 13:17:05.184 2:53.372 Opal Liste...0xb263cb40 Opal Transport clean up on termination >2012/12/12 13:17:05.184 2:53.372 Opal Liste...0xb263cb40 Opal Transport Close >2012/12/12 13:17:05.184 2:53.372 Opal Liste...0xb263cb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:17:06.153 2:54.341 Opal Garbage:0xb26ffb40 Opal Transport clean up on termination >2012/12/12 13:17:06.153 2:54.342 Opal Garbage:0xb26ffb40 Opal Transport Close >2012/12/12 13:17:06.153 2:54.342 Opal Garbage:0xb26ffb40 Opal Transport clean up on termination >2012/12/12 13:17:06.153 2:54.342 Opal Garbage:0xb26ffb40 Opal Transport Close >2012/12/12 13:17:06.154 2:54.342 Opal Garbage:0xb26ffb40 Opal Deleted transport udp$208.73.144.71:5060<if=udp$24.248.44.159:1024> >2012/12/12 13:17:06.154 2:54.342 Opal Garbage:0xb26ffb40 SIP Destroyed SUBSCRIBE handler for sip:1264941142@prod.voipdnsservers.com >2012/12/12 13:17:06.179 2:54.368 Housekeeper:0xb267db40 SIP Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK84432bcd-f542-e211-8519-000cf1d997b4 >2012/12/12 13:17:06.180 2:54.368 Housekeeper:0xb267db40 SIP Completed state for transaction id=z9hG4bK84432bcd-f542-e211-8519-000cf1d997b4 >2012/12/12 13:17:06.253 2:54.441 OpalEP sip endpoint shutting down. >2012/12/12 13:17:06.253 2:54.441 Listen Stopping listening thread on udp$*:5060 >2012/12/12 13:17:06.254 2:54.442 MonSock Closed UDP socket 0x8e08b70 >2012/12/12 13:17:06.254 2:54.442 Opal Liste...0xb263cb40 Listen UDP read error. >2012/12/12 13:17:06.254 2:54.442 Opal Liste...0xb263cb40 PWLib File handle low water mark set: 32 PTextFile >2012/12/12 13:17:06.254 2:54.443 Opal Liste...0xb263cb40 PTLib Thread ended: name="Opal Listener:0xb263cb40", real=168.610, kernel=0.020 (0%), user=0.030 (0%), both=0.050 (0%) >2012/12/12 13:17:06.274 2:54.462 MonSock Deleting UDP socket 0x8e08b70 >2012/12/12 13:17:06.275 2:54.463 OpalEP h323 endpoint shutting down. >2012/12/12 13:17:06.275 2:54.463 Listen Stopping listening thread on tcp$*:1720 >2012/12/12 13:17:06.275 2:54.463 Opal Liste...0xb25fbb40 PWLib File handle low water mark set: 31 PTextFile >2012/12/12 13:17:06.275 2:54.464 Opal Liste...0xb25fbb40 PTLib Thread ended: name="Opal Listener:0xb25fbb40", real=168.631, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%) >2012/12/12 13:17:06.276 2:54.464 PCSS Deleted PC sound system endpoint. >2012/12/12 13:17:06.276 2:54.464 LocalEP Deleted endpoint. >2012/12/12 13:17:06.276 2:54.464 OpalEP pc endpoint destroyed. >2012/12/12 13:17:06.277 2:54.465 Pool:0xabc81b40 PTLib Thread ended: name="Pool:0xabc81b40", real=85.770, kernel=0.000 (0%), user=0.020 (0%), both=0.020 (0%) >2012/12/12 13:17:06.277 2:54.465 ThreadPool Destroying pool thread >2012/12/12 13:17:06.277 2:54.466 Pool:0xabc40b40 PTLib Thread ended: name="Pool:0xabc40b40", real=85.587, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%) >2012/12/12 13:17:06.278 2:54.466 ThreadPool Destroying pool thread >2012/12/12 13:17:06.278 2:54.466 SIP Transaction id=z9hG4bK84432bcd-f542-e211-8519-000cf1d997b4 destroyed. >2012/12/12 13:17:06.278 2:54.467 OpalEP sip endpoint destroyed. >2012/12/12 13:17:06.278 2:54.467 OpalEP h323 endpoint destroyed. >2012/12/12 13:17:06.279 2:54.467 OpalMan Shutting down endpoints. >2012/12/12 13:17:06.279 2:54.467 OpalMan Clearing all calls and waiting, primary thread. >2012/12/12 13:17:07.155 2:55.343 OpalMan All calls cleared. >2012/12/12 13:17:07.155 2:55.343 Opal Garbage:0xb26ffb40 PTLib Thread ended: name="Opal Garbage:0xb26ffb40", real=175.111, kernel=0.010 (0%), user=0.030 (0%), both=0.040 (0%) >2012/12/12 13:17:07.165 2:55.353 IfaceMon Awaiting thread termination >2012/12/12 13:17:07.165 2:55.353 Network In...0xb26beb40 IfaceMon Finished interface monitor thread. >2012/12/12 13:17:07.165 2:55.354 Network In...0xb26beb40 PWLib File handle low water mark set: 22 PTextFile >2012/12/12 13:17:07.165 2:55.354 Network In...0xb26beb40 PTLib Thread ended: name="Network Interface Monitor:0xb26beb40", real=175.070, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%) >2012/12/12 13:17:07.166 2:55.354 OpalMan Deleted manager. >2012/12/12 13:17:07.167 2:55.355 AudioEvent...0xb28b4b40 PTLib Thread ended: name="AudioEventScheduler:0xb28b4b40", real=175.245, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%) >2012/12/12 13:17:07.167 2:55.356 VideoPrevi...0xb28f5b40 PWLib File handle low water mark set: 11 PTextFile >2012/12/12 13:17:07.168 2:55.356 VideoPrevi...0xb28f5b40 PTLib Thread ended: name="VideoPreviewManager:0xb28f5b40", real=175.247, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%) >2012/12/12 13:17:07.168 2:55.356 GMVideoOut...0xb2873b40 PTLib Thread ended: name="GMVideoOutputManager:0xb2873b40", real=175.238, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%) >2012/12/12 13:17:07.173 2:55.362 OpalPlugin Using default handler for plugin codec h261_vic_ptplugin >2012/12/12 13:17:07.174 2:55.362 OpalPlugin Using default handler for plugin codec theora_ptplugin >2012/12/12 13:17:07.174 2:55.362 OpalPlugin Using default handler for plugin codec g7221_ptplugin >2012/12/12 13:17:07.174 2:55.362 OpalPlugin Using default handler for plugin codec g726_ptplugin >2012/12/12 13:17:07.174 2:55.363 OpalPlugin Using default handler for plugin codec iLBC_ptplugin >2012/12/12 13:17:07.174 2:55.363 OpalPlugin Using default handler for plugin codec ima_adpcm_ptplugin >2012/12/12 13:17:07.174 2:55.363 OpalPlugin Using default handler for plugin codec g722_ptplugin >2012/12/12 13:17:07.176 2:55.364 OpalPlugin Using default handler for plugin codec speex_ptplugin >2012/12/12 13:17:07.177 2:55.365 OpalPlugin Using default handler for plugin codec g7222_ptplugin >2012/12/12 13:17:07.177 2:55.365 OpalPlugin Using default handler for plugin codec gsmamrcodec_ptplugin >2012/12/12 13:17:07.177 2:55.365 OpalPlugin Using default handler for plugin codec lpc10_ptplugin >2012/12/12 13:17:07.177 2:55.365 OpalPlugin Using default handler for plugin codec gsm0610_ptplugin >2012/12/12 13:17:07.177 2:55.366 UDLL Closing /usr/lib/opal-3.10.9/codecs/audio/gsm0610_ptplugin.so >2012/12/12 13:17:07.177 2:55.366 UDLL Closing /usr/lib/opal-3.10.9/codecs/audio/lpc10_ptplugin.so >2012/12/12 13:17:07.178 2:55.366 UDLL Closing /usr/lib/opal-3.10.9/codecs/audio/gsmamrcodec_ptplugin.so >2012/12/12 13:17:07.178 2:55.366 UDLL Closing /usr/lib/opal-3.10.9/codecs/audio/g7222_ptplugin.so >2012/12/12 13:17:07.178 2:55.366 UDLL Closing /usr/lib/opal-3.10.9/codecs/audio/speex_ptplugin.so >2012/12/12 13:17:07.178 2:55.366 UDLL Closing /usr/lib/opal-3.10.9/codecs/audio/g722_ptplugin.so >2012/12/12 13:17:07.178 2:55.366 UDLL Closing /usr/lib/opal-3.10.9/codecs/audio/ima_adpcm_ptplugin.so >2012/12/12 13:17:07.178 2:55.367 UDLL Closing /usr/lib/opal-3.10.9/codecs/audio/iLBC_ptplugin.so >2012/12/12 13:17:07.178 2:55.367 UDLL Closing /usr/lib/opal-3.10.9/codecs/audio/g726_ptplugin.so >2012/12/12 13:17:07.178 2:55.367 UDLL Closing /usr/lib/opal-3.10.9/codecs/audio/g7221_ptplugin.so >2012/12/12 13:17:07.179 2:55.367 UDLL Closing /usr/lib/opal-3.10.9/codecs/video/theora_ptplugin.so >2012/12/12 13:17:07.179 2:55.367 UDLL Closing /usr/lib/opal-3.10.9/codecs/video/h261_vic_ptplugin.so >2012/12/12 13:17:07.179 2:55.367 UDLL Closing /usr/lib/ptlib-2.10.9/devices/sound/alsa_pwplugin.so >2012/12/12 13:17:07.179 2:55.367 UDLL Closing /usr/lib/ptlib-2.10.9/devices/sound/pulse_pwplugin.so >2012/12/12 13:17:07.179 2:55.367 UDLL Closing /usr/lib/ptlib-2.10.9/devices/videoinput/v4l2_pwplugin.so >2012/12/12 13:17:08.292 2:56.480 Housekeeper:0xb267db40 PWLib File handle low water mark set: 9 PTextFile >2012/12/12 13:17:08.292 2:56.480 Housekeeper:0xb267db40 PTLib Thread ended: name="Housekeeper:0xb267db40", real=176.203, kernel=0.030 (0%), user=0.050 (0%), both=0.080 (0%)
<b>You cannot view the attachment while viewing its details because your browser does not support IFRAMEs. <a href="attachment.cgi?id=662556">View the attachment on a separate page</a>.</b>
View Attachment As Raw
Actions:
View
Attachments on
bug 885769
:
660920
|
660921
|
660966
|
662555
| 662556 |
662557
|
680361