on Unix Linux (3.11.0-18-generic-x86_64) with PTLib (v2.10.10 (svn:29149)) at 2014/4/2 12:46:52.275 2014/04/02 12:46:52.278 0:00.003 PTLib Maximum per-process file handles is 1024 2014/04/02 12:46:52.279 0:00.003 PLUGIN Enumerating plugin directory /usr/lib/ptlib-2.10.10/ 2014/04/02 12:46:52.279 0:00.004 PLUGIN Enumerating plugin directory /usr/lib/ptlib-2.10.10/devices/ 2014/04/02 12:46:52.279 0:00.004 PLUGIN Enumerating plugin directory /usr/lib/ptlib-2.10.10/devices/videoinput/ 2014/04/02 12:46:52.280 0:00.004 UDLL opening /usr/lib/ptlib-2.10.10/devices/videoinput/v4l2_pwplugin.so 2014/04/02 12:46:52.284 0:00.009 PLUGIN Enumerating plugin directory /usr/lib/ptlib-2.10.10/devices/sound/ 2014/04/02 12:46:52.285 0:00.009 UDLL opening /usr/lib/ptlib-2.10.10/devices/sound/oss_pwplugin.so 2014/04/02 12:46:52.287 0:00.012 UDLL opening /usr/lib/ptlib-2.10.10/devices/sound/alsa_pwplugin.so 2014/04/02 12:46:52.290 0:00.015 UDLL opening /usr/lib/ptlib-2.10.10/devices/sound/pulse_pwplugin.so 2014/04/02 12:46:52.299 0:00.024 PLUGIN Enumerating plugin directory /usr/lib/opal-3.10.10/ 2014/04/02 12:46:52.299 0:00.024 PLUGIN Enumerating plugin directory /usr/lib/opal-3.10.10/fax/ 2014/04/02 12:46:52.300 0:00.024 UDLL opening /usr/lib/opal-3.10.10/fax/spandsp_ptplugin.so 2014/04/02 12:46:52.307 0:00.031 PLUGIN Enumerating plugin directory /usr/lib/opal-3.10.10/codecs/ 2014/04/02 12:46:52.307 0:00.031 PLUGIN Enumerating plugin directory /usr/lib/opal-3.10.10/codecs/video/ 2014/04/02 12:46:52.307 0:00.032 UDLL opening /usr/lib/opal-3.10.10/codecs/video/theora_ptplugin.so 2014/04/02 12:46:52.309 0:00.034 UDLL opening /usr/lib/opal-3.10.10/codecs/video/h261_vic_ptplugin.so 2014/04/02 12:46:52.311 0:00.035 UDLL opening /usr/lib/opal-3.10.10/codecs/video/mpeg4_ffmpeg_ptplugin.so 2014/04/02 12:46:52.312 0:00.037 UDLL opening /usr/lib/opal-3.10.10/codecs/video/h264_x264_ptplugin.so 2014/04/02 12:46:52.315 0:00.040 UDLL opening /usr/lib/opal-3.10.10/codecs/video/h263_ffmpeg_ptplugin.so 2014/04/02 12:46:52.319 0:00.043 PLUGIN Enumerating plugin directory /usr/lib/opal-3.10.10/codecs/audio/ 2014/04/02 12:46:52.319 0:00.043 UDLL opening /usr/lib/opal-3.10.10/codecs/audio/ima_adpcm_ptplugin.so 2014/04/02 12:46:52.319 0:00.044 UDLL opening /usr/lib/opal-3.10.10/codecs/audio/g7221_ptplugin.so 2014/04/02 12:46:52.320 0:00.044 UDLL opening /usr/lib/opal-3.10.10/codecs/audio/lpc10_ptplugin.so 2014/04/02 12:46:52.320 0:00.044 UDLL opening /usr/lib/opal-3.10.10/codecs/audio/silk_ptplugin.so 2014/04/02 12:46:52.321 0:00.045 UDLL opening /usr/lib/opal-3.10.10/codecs/audio/g722_ptplugin.so 2014/04/02 12:46:52.321 0:00.045 UDLL opening /usr/lib/opal-3.10.10/codecs/audio/gsmamrcodec_ptplugin.so 2014/04/02 12:46:52.321 0:00.045 UDLL opening /usr/lib/opal-3.10.10/codecs/audio/g7222_ptplugin.so 2014/04/02 12:46:52.321 0:00.046 UDLL opening /usr/lib/opal-3.10.10/codecs/audio/speex_ptplugin.so 2014/04/02 12:46:52.322 0:00.046 UDLL opening /usr/lib/opal-3.10.10/codecs/audio/iLBC_ptplugin.so 2014/04/02 12:46:52.322 0:00.047 UDLL opening /usr/lib/opal-3.10.10/codecs/audio/g726_ptplugin.so 2014/04/02 12:46:52.322 0:00.047 UDLL opening /usr/lib/opal-3.10.10/codecs/audio/gsm0610_ptplugin.so 2014/04/02 12:46:52.323 0:00.047 OpalPlugin Using default handler for plugin codec spandsp_ptplugin 2014/04/02 12:46:52.323 0:00.048 OpalPlugin Creating new media format T.38 2014/04/02 12:46:52.323 0:00.048 OpalPlugin Creating new media format TIFF-File 2014/04/02 12:46:52.324 0:00.048 OpalPlugin Using default handler for plugin codec theora_ptplugin 2014/04/02 12:46:52.324 0:00.048 Plugin Started logging. 2014/04/02 12:46:52.324 0:00.048 OpalPlugin Creating new media format theora 2014/04/02 12:46:52.324 0:00.049 OpalPlugin Using default handler for plugin codec h261_vic_ptplugin 2014/04/02 12:46:52.324 0:00.049 OpalPlugin Creating new media format H.261 2014/04/02 12:46:52.324 0:00.049 OpalPlugin Creating H.261 plugin capability 2014/04/02 12:46:52.325 0:00.049 OpalPlugin Creating H.261 plugin capability 2014/04/02 12:46:52.326 0:00.051 OpalPlugin Using default handler for plugin codec mpeg4_ffmpeg_ptplugin 2014/04/02 12:46:52.327 0:00.051 Plugin Started logging. 2014/04/02 12:46:52.327 0:00.051 OpalPlugin Creating new media format MPEG4 2014/04/02 12:46:52.327 0:00.051 OpalPlugin Not adding H.323 capability for plugin codec MPEG4 as this has been specifically disabled 2014/04/02 12:46:52.327 0:00.052 OpalPlugin Using default handler for plugin codec h264_x264_ptplugin 2014/04/02 12:46:52.327 0:00.052 Plugin Started logging. 2014/04/02 12:46:52.327 0:00.052 OpalPlugin Creating new media format H.264 2014/04/02 12:46:52.327 0:00.052 Plugin Started logging. 2014/04/02 12:46:52.328 0:00.052 Plugin Started logging. 2014/04/02 12:46:52.328 0:00.052 OpalPlugin Creating new media format H.264-1 2014/04/02 12:46:52.328 0:00.052 Plugin Started logging. 2014/04/02 12:46:52.328 0:00.052 Plugin Started logging. 2014/04/02 12:46:52.328 0:00.053 OpalPlugin Creating new media format H.264-0 2014/04/02 12:46:52.328 0:00.053 Plugin Started logging. 2014/04/02 12:46:52.328 0:00.053 OpalPlugin Using default handler for plugin codec h263_ffmpeg_ptplugin 2014/04/02 12:46:52.328 0:00.053 Plugin Started logging. 2014/04/02 12:46:52.328 0:00.053 OpalPlugin Creating new media format H.263plus 2014/04/02 12:46:52.329 0:00.053 OpalPlugin Creating H.263 plugin capability 2014/04/02 12:46:52.329 0:00.054 OpalPlugin Creating H.263 plugin capability 2014/04/02 12:46:52.329 0:00.054 Plugin Started logging. 2014/04/02 12:46:52.329 0:00.054 OpalPlugin Creating new media format H.263 2014/04/02 12:46:52.329 0:00.054 OpalPlugin Creating H.263 plugin capability 2014/04/02 12:46:52.329 0:00.054 OpalPlugin Creating H.263 plugin capability 2014/04/02 12:46:52.329 0:00.054 OpalPlugin Using default handler for plugin codec ima_adpcm_ptplugin 2014/04/02 12:46:52.329 0:00.054 OpalPlugin Creating new media format MS-IMA-ADPCM 2014/04/02 12:46:52.330 0:00.054 OpalPlugin Using default handler for plugin codec g7221_ptplugin 2014/04/02 12:46:52.330 0:00.054 OpalPlugin Creating new media format G.722.1-24k 2014/04/02 12:46:52.330 0:00.055 OpalPlugin Creating new media format G.722.1-32k 2014/04/02 12:46:52.331 0:00.055 OpalPlugin Using default handler for plugin codec lpc10_ptplugin 2014/04/02 12:46:52.331 0:00.055 OpalPlugin Creating new media format LPC-10 2014/04/02 12:46:52.331 0:00.056 OpalPlugin Using default handler for plugin codec silk_ptplugin 2014/04/02 12:46:52.331 0:00.056 Plugin Started logging. 2014/04/02 12:46:52.331 0:00.056 OpalPlugin Creating new media format SILK-8 2014/04/02 12:46:52.331 0:00.056 Plugin Started logging. 2014/04/02 12:46:52.331 0:00.056 Plugin Started logging. 2014/04/02 12:46:52.331 0:00.056 OpalPlugin Creating new media format SILK-16 2014/04/02 12:46:52.332 0:00.056 Plugin Started logging. 2014/04/02 12:46:52.332 0:00.056 OpalPlugin Using default handler for plugin codec g722_ptplugin 2014/04/02 12:46:52.332 0:00.056 OpalPlugin Creating new media format G.722-64k 2014/04/02 12:46:52.332 0:00.056 OpalPlugin Using default handler for plugin codec gsmamrcodec_ptplugin 2014/04/02 12:46:52.332 0:00.057 OpalPlugin Creating new media format GSM-AMR 2014/04/02 12:46:52.332 0:00.057 OpalPlugin Using default handler for plugin codec g7222_ptplugin 2014/04/02 12:46:52.332 0:00.057 OpalPlugin Creating new media format G.722.2 2014/04/02 12:46:52.333 0:00.057 OpalPlugin Using default handler for plugin codec speex_ptplugin 2014/04/02 12:46:52.333 0:00.057 OpalPlugin Creating new media format SpeexIETFNarrow-5.95k 2014/04/02 12:46:52.333 0:00.058 OpalPlugin Creating new media format SpeexIETFNarrow-8k 2014/04/02 12:46:52.334 0:00.058 OpalPlugin Creating new media format SpeexIETFNarrow-11k 2014/04/02 12:46:52.334 0:00.059 OpalPlugin Creating new media format SpeexIETFNarrow-15k 2014/04/02 12:46:52.335 0:00.059 OpalPlugin Creating new media format SpeexIETFNarrow-18.2k 2014/04/02 12:46:52.335 0:00.060 OpalPlugin Creating new media format SpeexIETFNarrow-24.6k 2014/04/02 12:46:52.336 0:00.061 OpalPlugin Creating new media format SpeexIETFWide-20.6k 2014/04/02 12:46:52.337 0:00.061 OpalPlugin Creating new media format SpeexWNarrow-8k 2014/04/02 12:46:52.337 0:00.062 OpalPlugin Creating new media format SpeexWide-20.6k 2014/04/02 12:46:52.338 0:00.062 OpalPlugin Creating new media format SpeexNB 2014/04/02 12:46:52.338 0:00.063 OpalPlugin Creating new media format SpeexWB 2014/04/02 12:46:52.339 0:00.064 OpalPlugin Using default handler for plugin codec iLBC_ptplugin 2014/04/02 12:46:52.339 0:00.064 OpalPlugin Creating new media format iLBC 2014/04/02 12:46:52.340 0:00.065 OpalPlugin Creating new media format iLBC-13k3 2014/04/02 12:46:52.341 0:00.065 OpalPlugin Creating new media format iLBC-15k2 2014/04/02 12:46:52.341 0:00.066 OpalPlugin Using default handler for plugin codec g726_ptplugin 2014/04/02 12:46:52.342 0:00.066 OpalPlugin Creating new media format G.726-40k 2014/04/02 12:46:52.342 0:00.066 OpalPlugin Creating new media format G.726-32k 2014/04/02 12:46:52.342 0:00.067 OpalPlugin Creating new media format G.726-24k 2014/04/02 12:46:52.342 0:00.067 OpalPlugin Creating new media format G.726-16k 2014/04/02 12:46:52.343 0:00.067 OpalPlugin Using default handler for plugin codec gsm0610_ptplugin 2014/04/02 12:46:52.343 0:00.067 OpalPlugin Creating new media format GSM-06.10 2014/04/02 12:46:52.343 0:00.068 OpalPlugin Creating new media format MS-GSM 2014/04/02 12:46:52.345 0:00.070 PWLib File handle high water mark set: 10 Thread unblock pipe 2014/04/02 12:46:52.345 0:00.070 PWLib File handle high water mark set: 12 Thread unblock pipe 2014/04/02 12:46:52.345 0:00.070 PTLib No permission to set priority level 4 2014/04/02 12:46:52.345 0:00.070 PTLib Thread high water mark set: 2 2014/04/02 12:46:52.345 0:00.070 PTLib No permission to set priority level 4 2014/04/02 12:46:52.345 0:00.070 PTLib Thread high water mark set: 3 2014/04/02 12:46:52.345 0:00.070 PWLib File handle high water mark set: 17 Thread unblock pipe 2014/04/02 12:46:52.345 0:00.070 PTLib No permission to set priority level 4 2014/04/02 12:46:52.345 0:00.070 PTLib Thread high water mark set: 4 2014/04/02 12:46:52.348 0:00.072 PWLib File handle high water mark set: 19 Thread unblock pipe 2014/04/02 12:46:52.351 0:00.076 PTLib No permission to set priority level 4 2014/04/02 12:46:52.351 0:00.076 PTLib Thread high water mark set: 5 2014/04/02 12:46:52.352 0:00.077 HalManager_dbus Initialising HAL Manager 2014/04/02 12:46:52.354 0:00.079 HalManager_dbus Populating device list 2014/04/02 12:46:52.355 0:00.080 HalManager_dbus Populating full device list failed - The name org.freedesktop.Hal was not provided by any .service files 2014/04/02 12:46:52.355 0:00.080 HalManager_dbus Populating interface list 2014/04/02 12:46:52.356 0:00.081 HalManager_dbus Populating full interface list failed - Method "getDevices" with signature "" on interface "org.freedesktop.NetworkManager" doesn't exist 2014/04/02 12:46:52.358 0:00.083 PV4L2Plugin Detecting V4L2 devices 2014/04/02 12:46:52.359 0:00.083 PV4L2Plugin detected device metadata at /sys/class/video4linux/ 2014/04/02 12:46:52.426 0:00.151 PWLib File handle high water mark set: 25 Thread unblock pipe 2014/04/02 12:46:52.426 0:00.151 PTLib Thread high water mark set: 6 2014/04/02 12:46:52.426 0:00.151 OpalMan Created manager. 2014/04/02 12:46:52.426 0:00.151 PV4L2Plugin Detecting V4L2 devices 2014/04/02 12:46:52.426 0:00.151 PV4L2Plugin detected device metadata at /sys/class/video4linux/ 2014/04/02 12:46:52.435 0:00.159 OpalMan Attached endpoint with prefix pc 2014/04/02 12:46:52.435 0:00.159 OpalEP Created endpoint: pc 2014/04/02 12:46:52.435 0:00.159 LocalEP Created endpoint. 2014/04/02 12:46:52.453 0:00.177 PCSS Created PC sound system endpoint. Players: alsa_output.pci-0000_01_00.1.hdmi-stereo alsa_output.pci-0000_00_1b.0.analog-stereo Null Audio Default PulseAudio HDA Intel PCH [92HD90BXX Analog] EKIGA HDA NVidia [HDMI 0] HDA NVidia [HDMI 1] HDA NVidia [HDMI 2] *.wav Recorders: Null Audio Default PulseAudio alsa_input.pci-0000_00_1b.0.analog-stereo HDA Intel PCH [92HD90BXX Analog] EKIGA *.wav 2014/04/02 12:46:52.460 0:00.185 OPAL SetMediaFormatOrder() 2014/04/02 12:46:52.460 0:00.185 OPAL SetMediaFormatMask() 2014/04/02 12:46:52.461 0:00.185 OpalMan Attached endpoint with prefix sip 2014/04/02 12:46:52.461 0:00.185 OpalEP Created endpoint: sip 2014/04/02 12:46:52.461 0:00.186 PWLib File handle high water mark set: 26 PUDPSocket 2014/04/02 12:46:52.461 0:00.186 IfaceMon Initial interface list: 127.0.0.1 <00-00-00-00-00-00> (lo) 147.173.3.245 <5C-26-0A-60-25-8D> (eth0) 2014/04/02 12:46:52.461 0:00.186 PTLIB Opened NetLink socket 2014/04/02 12:46:52.461 0:00.186 PWLib File handle high water mark set: 30 Thread unblock pipe 2014/04/02 12:46:52.461 0:00.186 PTLib Thread high water mark set: 7 2014/04/02 12:46:52.461 0:00.186 Network In...51dbdae700 IfaceMon Started interface monitor thread. 2014/04/02 12:46:52.462 0:00.186 OpalMan Attached endpoint with prefix sips 2014/04/02 12:46:52.462 0:00.186 SIP Created endpoint. 2014/04/02 12:46:52.462 0:00.186 PWLib File handle high water mark set: 31 PUDPSocket 2014/04/02 12:46:52.462 0:00.186 MonSock Created socket bundle for all interfaces. 2014/04/02 12:46:52.462 0:00.186 PWLib File handle high water mark set: 32 PUDPSocket 2014/04/02 12:46:52.462 0:00.187 MonSock Created bundled UDP socket 147.173.3.245:5060 2014/04/02 12:46:52.462 0:00.187 PWLib File handle high water mark set: 34 Thread unblock pipe 2014/04/02 12:46:52.462 0:00.187 PTLib Thread high water mark set: 8 2014/04/02 12:46:52.462 0:00.187 PTLib No permission to set priority level 4 2014/04/02 12:46:52.462 0:00.187 Opal Liste...51c40f0700 Listen Started listening thread on udp$*:5060 2014/04/02 12:46:52.462 0:00.187 OpalMan Added route "sip:.*=pc:*" 2014/04/02 12:46:52.462 0:00.187 OpalMan Added route "pc:.*=sip:" 2014/04/02 12:46:52.463 0:00.187 OpalMan Attached endpoint with prefix h323 2014/04/02 12:46:52.463 0:00.187 OpalEP Created endpoint: h323 2014/04/02 12:46:52.463 0:00.187 OpalMan Attached endpoint with prefix h323s 2014/04/02 12:46:52.463 0:00.187 H323 Created endpoint. 2014/04/02 12:46:52.463 0:00.188 PWLib File handle high water mark set: 35 PTCPSocket 2014/04/02 12:46:52.463 0:00.188 PWLib File handle high water mark set: 37 Thread unblock pipe 2014/04/02 12:46:52.463 0:00.188 PTLib Thread high water mark set: 9 2014/04/02 12:46:52.463 0:00.188 OpalMan Added route "h323:.*=pc:*" 2014/04/02 12:46:52.463 0:00.188 Opal Liste...51c40af700 Listen Started listening thread on tcp$*:1720 2014/04/02 12:46:52.463 0:00.188 OpalMan Added route "pc:.*=h323:" 2014/04/02 12:46:52.463 0:00.188 Opal Liste...51c40af700 Listen Waiting on socket accept on tcp$*:1720 2014/04/02 12:46:52.464 0:00.188 PWLib File handle high water mark set: 38 PUDPSocket 2014/04/02 12:46:52.464 0:00.188 OpalMan Added presentity for SIP_Presentity 2014/04/02 12:46:52.464 0:00.189 Created presentity for sip:umfodx xivo grenoble cnrs fr 2014/04/02 12:46:52.479 0:00.204 MediaFormat Removing codecs G.722-64k,G.722.1-24k,G.722.1-32k,G.722.2,SILK-16,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-ALaw-64k,G.711-uLaw-64k,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,MS-GSM,SILK-8,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,iLBC,H.261,H.263,H.263plus,H.264,H.264-0,H.264-1,MPEG4,theora,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 2014/04/02 12:46:52.480 0:00.205 OPAL SetMediaFormatMask(GSM-AMR,LPC-10,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,T.38,TIFF-File,iLBC-13k3,iLBC-15k2,RFC4175_YCbCr-4:2:0,T.140,MSRP,SIP-IM,NamedSignalEvent) 2014/04/02 12:46:52.480 0:00.205 OPAL SetMediaFormatOrder(G.722-64k,G.722.1-24k,G.722.1-32k,G.722.2,SILK-16,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-ALaw-64k,G.711-uLaw-64k,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,MS-GSM,SILK-8,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,iLBC,H.261,H.263,H.263plus,H.264,H.264-0,H.264-1,MPEG4,theora,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) 2014/04/02 12:46:52.493 0:00.218 MediaFormat Removing codecs G.722-64k,G.722.1-24k,G.722.1-32k,G.722.2,SILK-16,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-ALaw-64k,G.711-uLaw-64k,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,MS-GSM,SILK-8,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,iLBC,H.261,H.263,H.263plus,H.264,H.264-0,H.264-1,MPEG4,theora,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 2014/04/02 12:46:52.494 0:00.219 OPAL SetMediaFormatMask(GSM-AMR,LPC-10,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,T.38,TIFF-File,iLBC-13k3,iLBC-15k2,RFC4175_YCbCr-4:2:0,T.140,MSRP,SIP-IM,NamedSignalEvent) 2014/04/02 12:46:52.494 0:00.219 OPAL SetMediaFormatOrder(G.722-64k,G.722.1-24k,G.722.1-32k,G.722.2,SILK-16,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-ALaw-64k,G.711-uLaw-64k,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,MS-GSM,SILK-8,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,iLBC,H.261,H.263,H.263plus,H.264,H.264-0,H.264-1,MPEG4,theora,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) 2014/04/02 12:46:52.496 0:00.220 OpalConfBridge maximum_video_tx_bitrate set to 1024kb/s 2014/04/02 12:46:52.509 0:00.234 Ekiga Nat binding delay set to 10 2014/04/02 12:46:52.510 0:00.234 Ekiga Sent its own presence (publish) for sip:umfodx xivo grenoble cnrs fr: available, note 2014/04/02 12:46:52.515 0:00.239 PWLib File handle high water mark set: 39 Thread unblock pipe 2014/04/02 12:46:52.515 0:00.239 PTLib Thread high water mark set: 10 2014/04/02 12:46:52.515 0:00.240 subscriber...51c406e700 OpalPres 'sip:umfodx xivo grenoble cnrs fr' opening. 2014/04/02 12:46:52.515 0:00.240 subscriber...51c406e700 SIPPres Using sip:umfodx xivo grenoble cnrs fr as presence server for sip:umfodx xivo grenoble cnrs fr 2014/04/02 12:46:52.515 0:00.240 subscriber...51c406e700 PWLib File handle high water mark set: 41 Thread unblock pipe 2014/04/02 12:46:52.515 0:00.240 subscriber...51c406e700 PTLib Thread high water mark set: 11 2014/04/02 12:46:52.515 0:00.240 subscriber...51c406e700 OpalPres Creating presentity command 'SIP_Presentity21SIPWatcherInfoCommand' 2014/04/02 12:46:52.515 0:00.240 PThreadObj...51bc25a700 OpalPres Command thread started 2014/04/02 12:46:52.515 0:00.240 PThreadObj...51bc25a700 SIPPres 'sip:umfodx xivo grenoble cnrs fr' sending subscribe for own presence.watcherinfo 2014/04/02 12:46:52.515 0:00.240 subscriber...51c406e700 SIP Start REGISTER aor=umfodx xivo grenoble cnrs fr remote=xivo.grenoble.cnrs.fr local= contact= proxy= authID=umfodx realm= expire=3600 restore=30 minRetry=default maxRetry=default compatibility=FullyCompliant 2014/04/02 12:46:52.516 0:00.240 PThreadObj...51bc25a700 SIP Start SUBSCRIBE eventPackage=presence.winfo aor=sip:umfodx xivo grenoble cnrs fr remote=sip:umfodx xivo grenoble cnrs fr;transport=tcp local=sip:umfodx xivo grenoble cnrs fr contact= proxy= authID=umfodx realm= expire=300 restore=30 minRetry=default maxRetry=default 2014/04/02 12:46:52.516 0:00.241 subscriber...51c406e700 SIP Constructed REGISTER handler for sip:umfodx xivo grenoble cnrs fr 2014/04/02 12:46:52.516 0:00.241 subscriber...51c406e700 SIP Executing state change to Subscribing for REGISTER handler, target=sip:umfodx xivo grenoble cnrs fr, id=864109c5-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:46:52.516 0:00.241 subscriber...51c406e700 SIP Changing REGISTER handler from Unavailable to Subscribing, target=sip:umfodx xivo grenoble cnrs fr, id=864109c5-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:46:52.516 0:00.241 subscriber...51c406e700 DNS SRV Lookup "_sip._udp.xivo.grenoble.cnrs.fr" 2014/04/02 12:46:52.516 0:00.241 PThreadObj...51bc25a700 SIP Constructed SUBSCRIBE handler for sip:umfodx xivo grenoble cnrs fr;OPAL-local-id=sip:umfodx%40xivo.grenoble.cnrs.fr 2014/04/02 12:46:52.516 0:00.241 PThreadObj...51bc25a700 SIP Executing state change to Subscribing for SUBSCRIBE handler, target=sip:umfodx xivo grenoble cnrs fr;OPAL-local-id=sip:umfodx%40xivo.grenoble.cnrs.fr, id=e25309c5-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:46:52.517 0:00.241 PThreadObj...51bc25a700 SIP Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:umfodx xivo grenoble cnrs fr;OPAL-local-id=sip:umfodx%40xivo.grenoble.cnrs.fr, id=e25309c5-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:46:52.517 0:00.241 PThreadObj...51bc25a700 DNS SRV Lookup "_sip._tcp.xivo.grenoble.cnrs.fr" 2014/04/02 12:46:52.518 0:00.243 subscriber...51c406e700 DNS Query failed: error=-1 2014/04/02 12:46:52.518 0:00.243 subscriber...51c406e700 SIP No SRV record found. 2014/04/02 12:46:52.518 0:00.243 subscriber...51c406e700 OpalUDP Binding to interface: 0.0.0.0:5060 2014/04/02 12:46:52.519 0:00.244 PThreadObj...51bc25a700 DNS Query failed: error=-1 2014/04/02 12:46:52.519 0:00.244 PThreadObj...51bc25a700 SIP No SRV record found. 2014/04/02 12:46:52.519 0:00.244 subscriber...51c406e700 PWLib File handle high water mark set: 42 PUDPSocket 2014/04/02 12:46:52.520 0:00.244 PThreadObj...51bc25a700 SIP No compatible listener to create transport for tcp$xivo.grenoble.cnrs.fr:5060 2014/04/02 12:46:52.519 0:00.244 subscriber...51c406e700 SIP Created transport udp$147.173.1.45:5060 2014/04/02 12:46:52.520 0:00.244 PThreadObj...51bc25a700 SIP Changing SUBSCRIBE handler from Subscribing to Unavailable, target=sip:umfodx xivo grenoble cnrs fr;OPAL-local-id=sip:umfodx%40xivo.grenoble.cnrs.fr, id=e25309c5-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:46:52.520 0:00.244 subscriber...51c406e700 OpalUDP Started connect to 147.173.1.45:5060 2014/04/02 12:46:52.520 0:00.244 PThreadObj...51bc25a700 SIP Retrying SUBSCRIBE after 30 seconds. 2014/04/02 12:46:52.520 0:00.244 subscriber...51c406e700 OpalUDP Writing to interface 0 - "147.173.3.245%eth0" 2014/04/02 12:46:52.520 0:00.245 subscriber...51c406e700 PWLib File handle high water mark set: 44 PUDPSocket 2014/04/02 12:46:52.520 0:00.245 subscriber...51c406e700 PWLib File handle low water mark set: 42 PUDPSocket 2014/04/02 12:46:52.520 0:00.245 subscriber...51c406e700 OpalMan Listener interfaces: associated transport=udp$147.173.3.245:5060 udp$147.173.3.245:5060 2014/04/02 12:46:52.521 0:00.245 subscriber...51c406e700 SIP Transaction created. 2014/04/02 12:46:52.521 0:00.246 subscriber...51c406e700 SIP Transaction remembering interface "147.173.3.245%eth0" 2014/04/02 12:46:52.521 0:00.246 subscriber...51c406e700 DNS SRV Lookup "_sip._udp.xivo.grenoble.cnrs.fr" 2014/04/02 12:46:52.521 0:00.246 subscriber...51c406e700 SIP No SRV record found. 2014/04/02 12:46:52.521 0:00.246 subscriber...51c406e700 SIP Transaction remote address is udp$xivo.grenoble.cnrs.fr:5060 2014/04/02 12:46:52.521 0:00.246 subscriber...51c406e700 SIP Sending PDU (547 bytes) to: rem=udp$147.173.1.45:5060,local=udp$147.173.3.245:5060,if=147.173.3.245%eth0 REGISTER sip:xivo.grenoble.cnrs.fr SIP/2.0 CSeq: 1 REGISTER Via: SIP/2.0/UDP 147.173.3.245:5060;branch=z9hG4bK22ff09c5-c1b8-e311-88f5-5c260a60258d;rport User-Agent: Ekiga/4.0.1 From: ;tag=6a4909c5-c1b8-e311-88f5-5c260a60258d Call-ID: 864109c5-c1b8-e311-88f5-5c260a60258d laptop-dom To: Contact: ;q=1 Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK Expires: 3600 Content-Length: 0 Max-Forwards: 70 2014/04/02 12:46:52.521 0:00.246 subscriber...51c406e700 OpalUDP Setting interface to 147.173.3.245%eth0 2014/04/02 12:46:52.522 0:00.246 subscriber...51c406e700 SIP Transaction timers set: retry=0.500, completion=6.000 2014/04/02 12:46:52.522 0:00.246 subscriber...51c406e700 PTLib Thread ended: name="subscriber:0x7f51c406e700", real=0.006, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%) 2014/04/02 12:46:52.523 0:00.247 Opal Liste...51c40f0700 OpalUDP Binding to interface: 147.173.3.245:5060 2014/04/02 12:46:52.523 0:00.247 Opal Liste...51c40f0700 SIP Waiting for PDU on udp$147.173.1.45:5060 2014/04/02 12:46:52.523 0:00.247 Opal Liste...51c40f0700 SIP PDU received: rem=udp$147.173.1.45:5060,local=udp$147.173.3.245:5060,if=147.173.3.245%eth0 SIP/2.0 401 Unauthorized CSeq: 1 REGISTER Via: SIP/2.0/UDP 147.173.3.245:5060;branch=z9hG4bK22ff09c5-c1b8-e311-88f5-5c260a60258d;received=147.173.3.245;rport=5060 Server: XiVO PBX From: ;tag=6a4909c5-c1b8-e311-88f5-5c260a60258d Call-ID: 864109c5-c1b8-e311-88f5-5c260a60258d laptop-dom Supported: replaces, timer To: ;tag=as392975cb Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Content-Length: 0 WWW-Authenticate: Digest algorithm=MD5, realm="xivo", nonce="0cbcaff9" 2014/04/02 12:46:52.523 0:00.248 Opal Liste...51c40f0700 SIP Queueing PDU "1 REGISTER <401>", transaction=z9hG4bK22ff09c5-c1b8-e311-88f5-5c260a60258d, token=864109c5-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:46:52.523 0:00.248 Opal Liste...51c40f0700 PTLib No permission to set priority level 3 2014/04/02 12:46:52.523 0:00.248 Opal Liste...51c40f0700 PTLib Thread high water mark set: 12 2014/04/02 12:46:52.524 0:00.248 Opal Liste...51c40f0700 Opal Transport clean up on termination 2014/04/02 12:46:52.524 0:00.248 Opal Liste...51c40f0700 Opal Transport Close 2014/04/02 12:46:52.524 0:00.248 Pool:0x7f51979bc700 SIP Handling PDU "1 REGISTER <401>" for transaction=z9hG4bK22ff09c5-c1b8-e311-88f5-5c260a60258d 2014/04/02 12:46:52.524 0:00.248 Pool:0x7f51979bc700 SIP REGISTER transaction id=z9hG4bK22ff09c5-c1b8-e311-88f5-5c260a60258d completing. 2014/04/02 12:46:52.524 0:00.248 Opal Liste...51c40f0700 Opal Deleted transport udp$147.173.1.45:5060 2014/04/02 12:46:52.524 0:00.248 Pool:0x7f51979bc700 SIP Finalising handlers interface "147.173.3.245%eth0" 2014/04/02 12:46:52.524 0:00.248 Pool:0x7f51979bc700 OpalUDP Setting interface to 147.173.3.245%eth0 2014/04/02 12:46:52.524 0:00.248 Pool:0x7f51979bc700 SIP Received Authentication Required response for z9hG4bK22ff09c5-c1b8-e311-88f5-5c260a60258d 2014/04/02 12:46:52.524 0:00.248 Pool:0x7f51979bc700 SIP Creating authentication credentials of user "umfodx" for realm "xivo" 2014/04/02 12:46:52.524 0:00.248 Pool:0x7f51979bc700 SIP Auth realm set to xivo 2014/04/02 12:46:52.524 0:00.249 Pool:0x7f51979bc700 PWLib File handle low water mark set: 38 PUDPSocket 2014/04/02 12:46:52.524 0:00.249 Pool:0x7f51979bc700 OpalMan Listener interfaces: associated transport=udp$147.173.3.245:5060 udp$147.173.3.245:5060 2014/04/02 12:46:52.525 0:00.249 Pool:0x7f51979bc700 SIP Transaction created. 2014/04/02 12:46:52.525 0:00.249 Pool:0x7f51979bc700 HTTP Adding authentication information 2014/04/02 12:46:52.525 0:00.249 Pool:0x7f51979bc700 SIP Transaction remembering interface "147.173.3.245%eth0" 2014/04/02 12:46:52.525 0:00.250 Pool:0x7f51979bc700 DNS SRV Lookup "_sip._udp.xivo.grenoble.cnrs.fr" 2014/04/02 12:46:52.525 0:00.250 Pool:0x7f51979bc700 SIP No SRV record found. 2014/04/02 12:46:52.525 0:00.250 Pool:0x7f51979bc700 SIP Transaction remote address is udp$xivo.grenoble.cnrs.fr:5060 2014/04/02 12:46:52.525 0:00.250 Pool:0x7f51979bc700 SIP Sending PDU (713 bytes) to: rem=udp$147.173.1.45:5060,local=udp$147.173.3.245:5060,if=147.173.3.245%eth0 REGISTER sip:xivo.grenoble.cnrs.fr SIP/2.0 CSeq: 2 REGISTER Via: SIP/2.0/UDP 147.173.3.245:5060;branch=z9hG4bK8e970ac5-c1b8-e311-88f5-5c260a60258d;rport User-Agent: Ekiga/4.0.1 Authorization: Digest username="umfodx", realm="xivo", nonce="0cbcaff9", uri="sip:xivo.grenoble.cnrs.fr", algorithm=MD5, response="64df171f930359595684b3dc7926bee6" From: ;tag=6a4909c5-c1b8-e311-88f5-5c260a60258d Call-ID: 864109c5-c1b8-e311-88f5-5c260a60258d laptop-dom To: Contact: ;q=1 Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK Expires: 3600 Content-Length: 0 Max-Forwards: 70 2014/04/02 12:46:52.525 0:00.250 Pool:0x7f51979bc700 OpalUDP Setting interface to 147.173.3.245%eth0 2014/04/02 12:46:52.525 0:00.250 Pool:0x7f51979bc700 SIP Transaction timers set: retry=0.500, completion=6.000 2014/04/02 12:46:52.525 0:00.250 Pool:0x7f51979bc700 SIP REGISTER transaction id=z9hG4bK22ff09c5-c1b8-e311-88f5-5c260a60258d completed. 2014/04/02 12:46:52.525 0:00.250 Pool:0x7f51979bc700 SIP Handled PDU "1 REGISTER <401>" 2014/04/02 12:46:52.528 0:00.253 Opal Liste...51c40f0700 OpalUDP Binding to interface: 147.173.3.245:5060 2014/04/02 12:46:52.528 0:00.253 Opal Liste...51c40f0700 SIP Waiting for PDU on udp$147.173.1.45:5060 2014/04/02 12:46:52.529 0:00.253 Opal Liste...51c40f0700 SIP PDU received: rem=udp$147.173.1.45:5060,local=udp$147.173.3.245:5060,if=147.173.3.245%eth0 SIP/2.0 200 OK Date: Wed, 02 Apr 2014 10:46:52 GMT CSeq: 2 REGISTER Via: SIP/2.0/UDP 147.173.3.245:5060;branch=z9hG4bK8e970ac5-c1b8-e311-88f5-5c260a60258d;received=147.173.3.245;rport=5060 Server: XiVO PBX From: ;tag=6a4909c5-c1b8-e311-88f5-5c260a60258d Call-ID: 864109c5-c1b8-e311-88f5-5c260a60258d laptop-dom Supported: replaces, timer To: ;tag=as392975cb Contact: ;expires=3600 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Expires: 3600 Content-Length: 0 2014/04/02 12:46:52.529 0:00.253 Opal Liste...51c40f0700 SIP Queueing PDU "2 REGISTER <200>", transaction=z9hG4bK8e970ac5-c1b8-e311-88f5-5c260a60258d, token=864109c5-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:46:52.529 0:00.253 Opal Liste...51c40f0700 Opal Transport clean up on termination 2014/04/02 12:46:52.529 0:00.253 Pool:0x7f51979bc700 SIP Handling PDU "2 REGISTER <200>" for transaction=z9hG4bK8e970ac5-c1b8-e311-88f5-5c260a60258d 2014/04/02 12:46:52.529 0:00.254 Opal Liste...51c40f0700 Opal Transport Close 2014/04/02 12:46:52.529 0:00.254 Pool:0x7f51979bc700 SIP REGISTER transaction id=z9hG4bK8e970ac5-c1b8-e311-88f5-5c260a60258d completing. 2014/04/02 12:46:52.529 0:00.254 Pool:0x7f51979bc700 SIP Finalising handlers interface "147.173.3.245%eth0" 2014/04/02 12:46:52.529 0:00.254 Pool:0x7f51979bc700 OpalUDP Setting interface to 147.173.3.245%eth0 2014/04/02 12:46:52.529 0:00.254 Pool:0x7f51979bc700 SIP Product Info: name="XiVO", version="", vendor="", comments="PBX" 2014/04/02 12:46:52.529 0:00.254 Opal Liste...51c40f0700 Opal Deleted transport udp$147.173.1.45:5060 2014/04/02 12:46:52.529 0:00.254 Opal Liste...51c40f0700 OpalUDP Binding to interface: 147.173.3.245:5060 2014/04/02 12:46:52.529 0:00.254 Pool:0x7f51979bc700 SIP Expiry time for REGISTER set to 3600 seconds. 2014/04/02 12:46:52.529 0:00.254 Pool:0x7f51979bc700 SIP Changing REGISTER handler from Subscribing to Subscribed, target=sip:umfodx xivo grenoble cnrs fr, id=864109c5-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:46:52.529 0:00.254 Opal Liste...51c40f0700 SIP Waiting for PDU on udp$147.173.1.45:5060 2014/04/02 12:46:52.529 0:00.254 Pool:0x7f51979bc700 SIP REGISTER transaction id=z9hG4bK8e970ac5-c1b8-e311-88f5-5c260a60258d completed. 2014/04/02 12:46:52.530 0:00.254 Pool:0x7f51979bc700 SIP Handled PDU "2 REGISTER <200>" 2014/04/02 12:46:52.530 0:00.254 Opal Liste...51c40f0700 SIP PDU received: rem=udp$147.173.1.45:5060,local=udp$147.173.3.245:5060,if=147.173.3.245%eth0 NOTIFY sip:umfodx 147 173 3 245:5060 SIP/2.0 CSeq: 102 NOTIFY Via: SIP/2.0/UDP 147.173.1.45:5060;branch=z9hG4bK716ff420 User-Agent: XiVO PBX From: "xivo" ;tag=as6702bb51 Call-ID: 0d614aa00e67097e13c872d274672c44 147 173 1 45:5060 To: Contact: Event: message-summary Content-Type: application/simple-message-summary Content-Length: 87 Max-Forwards: 70 Messages-Waiting: no Message-Account: sip:*98 147 173 1 45 Voice-Message: 0/0 (0/0) 2014/04/02 12:46:52.530 0:00.255 Opal Liste...51c40f0700 SIP Received NOTIFY message-summary 2014/04/02 12:46:52.530 0:00.255 Opal Liste...51c40f0700 SIP Work around Asterisk bug in message-summary event package. 2014/04/02 12:46:52.530 0:00.255 Opal Liste...51c40f0700 SIP Could not find a SUBSCRIBE corresponding to the NOTIFY message-summary 2014/04/02 12:46:52.531 0:00.256 Opal Liste...51c40f0700 SIP Sending PDU (335 bytes) to: rem=udp$147.173.1.45:5060,local=udp$147.173.3.245:5060,if=147.173.3.245%eth0 SIP/2.0 481 Call Leg/Transaction Does Not Exist CSeq: 102 NOTIFY Via: SIP/2.0/UDP 147.173.1.45:5060;branch=z9hG4bK716ff420 From: "xivo" ;tag=as6702bb51 Call-ID: 0d614aa00e67097e13c872d274672c44 147 173 1 45:5060 To: Contact: Content-Length: 0 2014/04/02 12:46:52.531 0:00.256 Opal Liste...51c40f0700 OpalUDP Setting interface to 147.173.3.245%eth0 2014/04/02 12:46:52.532 0:00.256 Opal Liste...51c40f0700 Opal Transport clean up on termination 2014/04/02 12:46:52.532 0:00.256 Opal Liste...51c40f0700 Opal Transport Close 2014/04/02 12:46:52.532 0:00.256 Opal Liste...51c40f0700 Opal Deleted transport udp$147.173.1.45:5060 2014/04/02 12:46:52.682 0:00.406 AudioOutputCore Detected Device: Default (PTLIB/ALSA) 2014/04/02 12:46:52.682 0:00.406 AudioOutputCore Detected Device: HDA Intel PCH [92HD90BXX Analog] (PTLIB/ALSA) 2014/04/02 12:46:52.682 0:00.406 AudioOutputCore Detected Device: HDA NVidia [HDMI 0] (PTLIB/ALSA) 2014/04/02 12:46:52.682 0:00.406 AudioOutputCore Detected Device: HDA NVidia [HDMI 1] (PTLIB/ALSA) 2014/04/02 12:46:52.682 0:00.406 AudioOutputCore Detected Device: HDA NVidia [HDMI 2] (PTLIB/ALSA) 2014/04/02 12:46:52.682 0:00.406 AudioOutputCore Detected Device: PulseAudio (PTLIB/Pulse) 2014/04/02 12:46:52.682 0:00.406 AudioOutputCore Detected Device: alsa_output.pci-0000_01_00.1.hdmi-stereo (PTLIB/Pulse) 2014/04/02 12:46:52.682 0:00.406 AudioOutputCore Detected Device: alsa_output.pci-0000_00_1b.0.analog-stereo (PTLIB/Pulse) 2014/04/02 12:46:52.782 0:00.506 AudioOutputCore Detected Device: Default (PTLIB/ALSA) 2014/04/02 12:46:52.782 0:00.506 AudioOutputCore Detected Device: HDA Intel PCH [92HD90BXX Analog] (PTLIB/ALSA) 2014/04/02 12:46:52.782 0:00.506 AudioOutputCore Detected Device: HDA NVidia [HDMI 0] (PTLIB/ALSA) 2014/04/02 12:46:52.782 0:00.507 AudioOutputCore Detected Device: HDA NVidia [HDMI 1] (PTLIB/ALSA) 2014/04/02 12:46:52.782 0:00.507 AudioOutputCore Detected Device: HDA NVidia [HDMI 2] (PTLIB/ALSA) 2014/04/02 12:46:52.782 0:00.507 AudioOutputCore Detected Device: PulseAudio (PTLIB/Pulse) 2014/04/02 12:46:52.782 0:00.507 AudioOutputCore Detected Device: alsa_output.pci-0000_01_00.1.hdmi-stereo (PTLIB/Pulse) 2014/04/02 12:46:52.782 0:00.507 AudioOutputCore Detected Device: alsa_output.pci-0000_00_1b.0.analog-stereo (PTLIB/Pulse) 2014/04/02 12:46:52.792 0:00.517 AudioInputCore Detected Device: Default (PTLIB/ALSA) 2014/04/02 12:46:52.793 0:00.517 AudioInputCore Detected Device: HDA Intel PCH [92HD90BXX Analog] (PTLIB/ALSA) 2014/04/02 12:46:52.793 0:00.517 AudioInputCore Detected Device: PulseAudio (PTLIB/Pulse) 2014/04/02 12:46:52.793 0:00.517 AudioInputCore Detected Device: alsa_input.pci-0000_00_1b.0.analog-stereo (PTLIB/Pulse) 2014/04/02 12:46:52.797 0:00.521 PV4L2Plugin Detecting V4L2 devices 2014/04/02 12:46:52.797 0:00.521 PV4L2Plugin detected device metadata at /sys/class/video4linux/ 2014/04/02 12:46:52.805 0:00.530 VidInputCore Detected Device: Moving Logo (Moving Logo/Moving Logo) 2014/04/02 12:46:52.805 0:00.530 VidInputCore Detected Device: Laptop_Integrated_Webcam_FHD (PTLIB/V4L2) 2014/04/02 12:46:53.526 0:01.251 Housekeepe...51dbef3700 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bK22ff09c5-c1b8-e311-88f5-5c260a60258d 2014/04/02 12:46:53.526 0:01.251 Housekeepe...51dbef3700 SIP Completed state for transaction id=z9hG4bK22ff09c5-c1b8-e311-88f5-5c260a60258d 2014/04/02 12:46:53.552 0:01.276 Housekeepe...51dbef3700 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bK8e970ac5-c1b8-e311-88f5-5c260a60258d 2014/04/02 12:46:53.552 0:01.276 Housekeepe...51dbef3700 SIP Completed state for transaction id=z9hG4bK8e970ac5-c1b8-e311-88f5-5c260a60258d 2014/04/02 12:46:54.427 0:02.152 Opal Garba...51dbdef700 SIP Transaction id=z9hG4bK8e970ac5-c1b8-e311-88f5-5c260a60258d destroyed. 2014/04/02 12:46:54.428 0:02.152 Opal Garba...51dbdef700 SIP Transaction id=z9hG4bK22ff09c5-c1b8-e311-88f5-5c260a60258d destroyed. 2014/04/02 12:47:02.510 0:10.235 Housekeepe...51dbef3700 PWLib File handle high water mark set: 49 PUDPSocket 2014/04/02 12:47:17.873 0:25.597 VideoOutputCoreConfBridge Updating video view 2014/04/02 12:47:17.874 0:25.598 VideoOutputCoreConfBridge Updating zoom 2014/04/02 12:47:17.874 0:25.599 VideoOutputCoreConfBridge Updating Video Settings 2014/04/02 12:47:17.877 0:25.602 VideoOutputCoreConfBridge Updating Video Settings 2014/04/02 12:47:17.877 0:25.602 VideoOutputCoreConfBridge Updating Video Settings 2014/04/02 12:47:17.877 0:25.602 VideoOutputCoreConfBridge Updating Video Settings 2014/04/02 12:47:17.878 0:25.602 VidInputCoreConfBridge Updating preview size and fps 2014/04/02 12:47:17.878 0:25.602 VidInputCore Setting new preview config: 320x240/30 2014/04/02 12:47:17.887 0:25.612 VidInputCoreConfBridge Updating preview size and fps 2014/04/02 12:47:17.888 0:25.612 VidInputCore Setting new preview config: 320x240/30 2014/04/02 12:47:17.893 0:25.617 PV4L2Plugin Detecting V4L2 devices 2014/04/02 12:47:17.893 0:25.617 PV4L2Plugin detected device metadata at /sys/class/video4linux/ 2014/04/02 12:47:17.956 0:25.680 VidInputCore Detected Device: Moving Logo (Moving Logo/Moving Logo) 2014/04/02 12:47:17.956 0:25.680 VidInputCore Detected Device: Laptop_Integrated_Webcam_FHD (PTLIB/V4L2) 2014/04/02 12:47:17.956 0:25.680 VidInputCoreConfBridge Updating device 2014/04/02 12:47:17.956 0:25.680 VidInputCore Setting device: Laptop_Integrated_Webcam_FHD (PTLIB/V4L2) 2014/04/02 12:47:17.956 0:25.681 GMVideoInputManager_ptlib Setting Device Laptop_Integrated_Webcam_FHD (PTLIB/V4L2) 2014/04/02 12:47:17.956 0:25.681 PV4L2Plugin Detecting V4L2 devices 2014/04/02 12:47:17.956 0:25.681 PV4L2Plugin detected device metadata at /sys/class/video4linux/ 2014/04/02 12:47:17.975 0:25.700 VidInputCore Detected Device: Moving Logo (Moving Logo/Moving Logo) 2014/04/02 12:47:17.975 0:25.700 VidInputCore Detected Device: Laptop_Integrated_Webcam_FHD (PTLIB/V4L2) 2014/04/02 12:47:17.975 0:25.700 VidInputCoreConfBridge Updating device 2014/04/02 12:47:17.975 0:25.700 VidInputCore Setting device: Laptop_Integrated_Webcam_FHD (PTLIB/V4L2) 2014/04/02 12:47:17.976 0:25.700 GMVideoInputManager_ptlib Setting Device Laptop_Integrated_Webcam_FHD (PTLIB/V4L2) 2014/04/02 12:47:17.976 0:25.700 PV4L2Plugin Detecting V4L2 devices 2014/04/02 12:47:17.976 0:25.700 PV4L2Plugin detected device metadata at /sys/class/video4linux/ 2014/04/02 12:47:17.992 0:25.717 VidInputCore Detected Device: Moving Logo (Moving Logo/Moving Logo) 2014/04/02 12:47:17.992 0:25.717 VidInputCore Detected Device: Laptop_Integrated_Webcam_FHD (PTLIB/V4L2) 2014/04/02 12:47:17.992 0:25.717 VidInputCoreConfBridge Updating device 2014/04/02 12:47:17.992 0:25.717 VidInputCore Setting device: Laptop_Integrated_Webcam_FHD (PTLIB/V4L2) 2014/04/02 12:47:17.992 0:25.717 GMVideoInputManager_ptlib Setting Device Laptop_Integrated_Webcam_FHD (PTLIB/V4L2) 2014/04/02 12:47:17.993 0:25.718 VidInputCoreConfBridge Updating image 2014/04/02 12:47:17.997 0:25.722 AudioOutputCore Detected Device: Default (PTLIB/ALSA) 2014/04/02 12:47:17.997 0:25.722 AudioOutputCore Detected Device: HDA Intel PCH [92HD90BXX Analog] (PTLIB/ALSA) 2014/04/02 12:47:17.997 0:25.722 AudioOutputCore Detected Device: HDA NVidia [HDMI 0] (PTLIB/ALSA) 2014/04/02 12:47:17.998 0:25.722 AudioOutputCore Detected Device: HDA NVidia [HDMI 1] (PTLIB/ALSA) 2014/04/02 12:47:17.998 0:25.722 AudioOutputCore Detected Device: HDA NVidia [HDMI 2] (PTLIB/ALSA) 2014/04/02 12:47:17.998 0:25.722 AudioOutputCore Detected Device: PulseAudio (PTLIB/Pulse) 2014/04/02 12:47:17.998 0:25.722 AudioOutputCore Detected Device: alsa_output.pci-0000_01_00.1.hdmi-stereo (PTLIB/Pulse) 2014/04/02 12:47:17.998 0:25.722 AudioOutputCore Detected Device: alsa_output.pci-0000_00_1b.0.analog-stereo (PTLIB/Pulse) 2014/04/02 12:47:17.998 0:25.722 AudioOutputCoreConfBridge Set device to ALSA/Default 2014/04/02 12:47:17.998 0:25.722 AudioOutputCore Setting device[0]: Default (PTLIB/ALSA) 2014/04/02 12:47:17.998 0:25.722 GMAudioOutputManager_ptlib Setting Device[0] Default (PTLIB/ALSA) 2014/04/02 12:47:17.998 0:25.722 AudioOutputCoreConfBridge Updating device 2014/04/02 12:47:17.998 0:25.722 AudioOutputCore Setting device[1]: Default (PTLIB/ALSA) 2014/04/02 12:47:18.024 0:25.749 AudioInputCore Detected Device: Default (PTLIB/ALSA) 2014/04/02 12:47:18.024 0:25.749 AudioInputCore Detected Device: HDA Intel PCH [92HD90BXX Analog] (PTLIB/ALSA) 2014/04/02 12:47:18.024 0:25.749 AudioInputCore Detected Device: PulseAudio (PTLIB/Pulse) 2014/04/02 12:47:18.025 0:25.749 AudioInputCore Detected Device: alsa_input.pci-0000_00_1b.0.analog-stereo (PTLIB/Pulse) 2014/04/02 12:47:18.025 0:25.749 AudioInputCore Setting device: Default (PTLIB/ALSA) 2014/04/02 12:47:18.025 0:25.749 GMAudioInputManager_ptlib Setting Device Default (PTLIB/ALSA) 2014/04/02 12:47:18.025 0:25.749 AudioInputCore Set device to ALSA/Default 2014/04/02 12:47:18.051 0:25.775 Ekiga version 4.0.1 2014/04/02 12:47:18.051 0:25.775 PTLIB version 2.10.10 2014/04/02 12:47:18.051 0:25.775 OPAL version 3.10.10 2014/04/02 12:47:18.051 0:25.775 Accelerated rendering support enabled 2014/04/02 12:47:18.051 0:25.775 DBUS support enabled 2014/04/02 12:47:18.051 0:25.775 GConf support enabled 2014/04/02 12:47:18.089 0:25.813 OpalPres Creating presentity command 'SIP_Presentity27OpalSetLocalPresenceCommand' 2014/04/02 12:47:18.089 0:25.813 PThreadObj...51bc25a700 SIPPres 'sip:umfodx xivo grenoble cnrs fr' sending own presence Available/ 2014/04/02 12:47:18.089 0:25.813 SIP Start SUBSCRIBE eventPackage=message-summary aor=sip:umfodx xivo grenoble cnrs fr remote= local= contact= proxy= authID= realm= expire=3600 restore=30 minRetry=default maxRetry=default 2014/04/02 12:47:18.089 0:25.813 PThreadObj...51bc25a700 SIPPres PIDF entity derived from AOR as pres:umfodx xivo grenoble cnrs fr 2014/04/02 12:47:18.089 0:25.813 PThreadObj...51bc25a700 SIP Start PUBLISH eventPackage=presence aor=sip:umfodx xivo grenoble cnrs fr remote=sip:umfodx xivo grenoble cnrs fr local= contact= proxy= authID= realm= expire=300 restore=30 minRetry=default maxRetry=default 2014/04/02 12:47:18.089 0:25.814 PThreadObj...51bc25a700 SIP Constructed PUBLISH handler for sip:umfodx xivo grenoble cnrs fr 2014/04/02 12:47:18.089 0:25.814 SIP Constructed SUBSCRIBE handler for sip:umfodx xivo grenoble cnrs fr 2014/04/02 12:47:18.089 0:25.814 PThreadObj...51bc25a700 SIP Executing state change to Subscribing for PUBLISH handler, target=sip:umfodx xivo grenoble cnrs fr, id=4c6a47d4-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:47:18.089 0:25.814 PThreadObj...51bc25a700 SIP Changing PUBLISH handler from Unavailable to Subscribing, target=sip:umfodx xivo grenoble cnrs fr, id=4c6a47d4-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:47:18.089 0:25.814 PThreadObj...51bc25a700 DNS SRV Lookup "_sip._udp.xivo.grenoble.cnrs.fr" 2014/04/02 12:47:18.089 0:25.814 PThreadObj...51bc25a700 SIP No SRV record found. 2014/04/02 12:47:18.089 0:25.814 SIP Executing state change to Subscribing for SUBSCRIBE handler, target=sip:umfodx xivo grenoble cnrs fr, id=706e47d4-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:47:18.089 0:25.814 PThreadObj...51bc25a700 OpalUDP Binding to interface: 0.0.0.0:5060 2014/04/02 12:47:18.089 0:25.814 SIP Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:umfodx xivo grenoble cnrs fr, id=706e47d4-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:47:18.090 0:25.814 DNS SRV Lookup "_sip._udp.xivo.grenoble.cnrs.fr" 2014/04/02 12:47:18.089 0:25.814 PThreadObj...51bc25a700 SIP Created transport udp$147.173.1.45:5060 2014/04/02 12:47:18.090 0:25.814 SIP No SRV record found. 2014/04/02 12:47:18.090 0:25.814 PThreadObj...51bc25a700 OpalUDP Started connect to 147.173.1.45:5060 2014/04/02 12:47:18.090 0:25.814 PThreadObj...51bc25a700 OpalUDP Writing to interface 0 - "147.173.3.245%eth0" 2014/04/02 12:47:18.090 0:25.814 PThreadObj...51bc25a700 SIP Transaction created. 2014/04/02 12:47:18.090 0:25.814 OpalUDP Binding to interface: 0.0.0.0:5060 2014/04/02 12:47:18.090 0:25.814 SIP Created transport udp$147.173.1.45:5060 2014/04/02 12:47:18.090 0:25.814 OpalUDP Started connect to 147.173.1.45:5060 2014/04/02 12:47:18.090 0:25.814 PThreadObj...51bc25a700 SIP Transaction remembering interface "147.173.3.245%eth0" 2014/04/02 12:47:18.090 0:25.814 PThreadObj...51bc25a700 DNS SRV Lookup "_sip._udp.xivo.grenoble.cnrs.fr" 2014/04/02 12:47:18.090 0:25.815 PThreadObj...51bc25a700 SIP No SRV record found. 2014/04/02 12:47:18.090 0:25.815 PThreadObj...51bc25a700 SIP Transaction remote address is udp$xivo.grenoble.cnrs.fr:5060 2014/04/02 12:47:18.090 0:25.815 OpalUDP Writing to interface 0 - "147.173.3.245%eth0" 2014/04/02 12:47:18.090 0:25.815 PThreadObj...51bc25a700 SIP Sending PDU (893 bytes) to: rem=udp$147.173.1.45:5060,local=udp$147.173.3.245:5060,if=147.173.3.245%eth0 PUBLISH sip:umfodx xivo grenoble cnrs fr SIP/2.0 CSeq: 3 PUBLISH Via: SIP/2.0/UDP 147.173.3.245:5060;branch=z9hG4bKfe8547d4-c1b8-e311-88f5-5c260a60258d;rport User-Agent: Ekiga/4.0.1 From: Call-ID: 4c6a47d4-c1b8-e311-88f5-5c260a60258d laptop-dom To: Expires: 300 Event: presence Content-Length: 459 Content-Type: application/pidf+xml Max-Forwards: 70 open sip:umfodx xivo grenoble cnrs fr 2014-04-02T12:47:18+02:00 2014/04/02 12:47:18.090 0:25.815 PThreadObj...51bc25a700 OpalUDP Setting interface to 147.173.3.245%eth0 2014/04/02 12:47:18.090 0:25.815 PThreadObj...51bc25a700 SIP Transaction timers set: retry=0.500, completion=6.000 2014/04/02 12:47:18.090 0:25.815 SIP Transaction created. 2014/04/02 12:47:18.091 0:25.816 Opal Liste...51c40f0700 OpalUDP Binding to interface: 147.173.3.245:5060 2014/04/02 12:47:18.091 0:25.816 SIP Transaction remembering interface "147.173.3.245%eth0" 2014/04/02 12:47:18.091 0:25.816 DNS SRV Lookup "_sip._udp.xivo.grenoble.cnrs.fr" 2014/04/02 12:47:18.091 0:25.816 Opal Liste...51c40f0700 SIP Waiting for PDU on udp$147.173.1.45:5060 2014/04/02 12:47:18.091 0:25.816 SIP No SRV record found. 2014/04/02 12:47:18.091 0:25.816 SIP Transaction remote address is udp$xivo.grenoble.cnrs.fr:5060 2014/04/02 12:47:18.091 0:25.816 Opal Liste...51c40f0700 SIP PDU received: rem=udp$147.173.1.45:5060,local=udp$147.173.3.245:5060,if=147.173.3.245%eth0 SIP/2.0 489 Bad Event CSeq: 3 PUBLISH Via: SIP/2.0/UDP 147.173.3.245:5060;branch=z9hG4bKfe8547d4-c1b8-e311-88f5-5c260a60258d;received=147.173.3.245;rport=5060 Server: XiVO PBX From: Call-ID: 4c6a47d4-c1b8-e311-88f5-5c260a60258d laptop-dom Supported: replaces, timer To: ;tag=as13b41984 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Content-Length: 0 2014/04/02 12:47:18.092 0:25.816 Opal Liste...51c40f0700 SIP Queueing PDU "3 PUBLISH <489>", transaction=z9hG4bKfe8547d4-c1b8-e311-88f5-5c260a60258d, token=4c6a47d4-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:47:18.092 0:25.816 Opal Liste...51c40f0700 Opal Transport clean up on termination 2014/04/02 12:47:18.092 0:25.816 Opal Liste...51c40f0700 Opal Transport Close 2014/04/02 12:47:18.092 0:25.816 Pool:0x7f51979bc700 SIP Handling PDU "3 PUBLISH <489>" for transaction=z9hG4bKfe8547d4-c1b8-e311-88f5-5c260a60258d 2014/04/02 12:47:18.092 0:25.816 Opal Liste...51c40f0700 PWLib File handle high water mark set: 50 PUDPSocket 2014/04/02 12:47:18.092 0:25.816 Pool:0x7f51979bc700 SIP PUBLISH transaction id=z9hG4bKfe8547d4-c1b8-e311-88f5-5c260a60258d completing. 2014/04/02 12:47:18.092 0:25.816 Opal Liste...51c40f0700 Opal Deleted transport udp$147.173.1.45:5060 2014/04/02 12:47:18.092 0:25.816 Pool:0x7f51979bc700 SIP Not retrying PUBLISH due to error response 489 Bad Event 2014/04/02 12:47:18.092 0:25.816 Pool:0x7f51979bc700 SIP Changing PUBLISH handler from Subscribing to Unsubscribed, target=sip:umfodx xivo grenoble cnrs fr, id=4c6a47d4-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:47:18.092 0:25.816 Pool:0x7f51979bc700 SIP PUBLISH transaction id=z9hG4bKfe8547d4-c1b8-e311-88f5-5c260a60258d completed. 2014/04/02 12:47:18.092 0:25.816 SIP Sending PDU (615 bytes) to: rem=udp$147.173.1.45:5060,local=udp$147.173.3.245:5060,if=147.173.3.245%eth0 SUBSCRIBE sip:umfodx xivo grenoble cnrs fr SIP/2.0 CSeq: 1 SUBSCRIBE Via: SIP/2.0/UDP 147.173.3.245:5060;branch=z9hG4bK44a447d4-c1b8-e311-88f5-5c260a60258d;rport User-Agent: Ekiga/4.0.1 From: ;tag=7a9b47d4-c1b8-e311-88f5-5c260a60258d Call-ID: 706e47d4-c1b8-e311-88f5-5c260a60258d laptop-dom To: Accept: application/simple-message-summary Contact: Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK Expires: 3600 Event: message-summary Content-Length: 0 Max-Forwards: 70 2014/04/02 12:47:18.092 0:25.816 Pool:0x7f51979bc700 SIP Handled PDU "3 PUBLISH <489>" 2014/04/02 12:47:18.092 0:25.816 OpalUDP Setting interface to 147.173.3.245%eth0 2014/04/02 12:47:18.092 0:25.816 SIP Transaction timers set: retry=0.500, completion=6.000 2014/04/02 12:47:18.092 0:25.817 OpalPres Creating presentity command 'SIP_Presentity27OpalSetLocalPresenceCommand' 2014/04/02 12:47:18.092 0:25.817 PThreadObj...51bc25a700 SIPPres 'sip:umfodx xivo grenoble cnrs fr' sending own presence Available/ 2014/04/02 12:47:18.092 0:25.817 Ekiga Sent its own presence (publish) for sip:umfodx xivo grenoble cnrs fr: available, note 2014/04/02 12:47:18.092 0:25.817 PThreadObj...51bc25a700 SIPPres PIDF entity derived from AOR as pres:umfodx xivo grenoble cnrs fr 2014/04/02 12:47:18.092 0:25.817 PThreadObj...51bc25a700 SIP Start PUBLISH eventPackage=presence aor=sip:umfodx xivo grenoble cnrs fr remote=sip:umfodx xivo grenoble cnrs fr local= contact= proxy= authID= realm= expire=300 restore=30 minRetry=default maxRetry=default 2014/04/02 12:47:18.092 0:25.817 PThreadObj...51bc25a700 SIP Handler SIPPublishHandler unsubscribed, awaiting shutdown. 2014/04/02 12:47:18.092 0:25.817 PThreadObj...51bc25a700 SIP Constructed PUBLISH handler for sip:umfodx xivo grenoble cnrs fr 2014/04/02 12:47:18.092 0:25.817 PThreadObj...51bc25a700 SIP Executing state change to Subscribing for PUBLISH handler, target=sip:umfodx xivo grenoble cnrs fr, id=82ed47d4-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:47:18.093 0:25.817 PThreadObj...51bc25a700 SIP Changing PUBLISH handler from Unavailable to Subscribing, target=sip:umfodx xivo grenoble cnrs fr, id=82ed47d4-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:47:18.093 0:25.817 PThreadObj...51bc25a700 DNS SRV Lookup "_sip._udp.xivo.grenoble.cnrs.fr" 2014/04/02 12:47:18.093 0:25.817 PThreadObj...51bc25a700 SIP No SRV record found. 2014/04/02 12:47:18.093 0:25.817 Opal Liste...51c40f0700 OpalUDP Binding to interface: 147.173.3.245:5060 2014/04/02 12:47:18.093 0:25.817 PThreadObj...51bc25a700 OpalUDP Binding to interface: 0.0.0.0:5060 2014/04/02 12:47:18.093 0:25.817 Opal Liste...51c40f0700 PWLib File handle low water mark set: 49 PUDPSocket 2014/04/02 12:47:18.093 0:25.817 Opal Liste...51c40f0700 SIP Waiting for PDU on udp$147.173.1.45:5060 2014/04/02 12:47:18.093 0:25.817 PThreadObj...51bc25a700 SIP Created transport udp$147.173.1.45:5060 2014/04/02 12:47:18.093 0:25.817 PThreadObj...51bc25a700 OpalUDP Started connect to 147.173.1.45:5060 2014/04/02 12:47:18.093 0:25.817 PThreadObj...51bc25a700 OpalUDP Writing to interface 0 - "147.173.3.245%eth0" 2014/04/02 12:47:18.093 0:25.817 PThreadObj...51bc25a700 SIP Transaction created. 2014/04/02 12:47:18.093 0:25.817 Opal Liste...51c40f0700 SIP PDU received: rem=udp$147.173.1.45:5060,local=udp$147.173.3.245:5060,if=147.173.3.245%eth0 SIP/2.0 401 Unauthorized CSeq: 1 SUBSCRIBE Via: SIP/2.0/UDP 147.173.3.245:5060;branch=z9hG4bK44a447d4-c1b8-e311-88f5-5c260a60258d;received=147.173.3.245;rport=5060 Server: XiVO PBX From: ;tag=7a9b47d4-c1b8-e311-88f5-5c260a60258d Call-ID: 706e47d4-c1b8-e311-88f5-5c260a60258d laptop-dom Supported: replaces, timer To: ;tag=as4aea1e99 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Content-Length: 0 WWW-Authenticate: Digest algorithm=MD5, realm="xivo", nonce="74787887" 2014/04/02 12:47:18.093 0:25.818 Opal Liste...51c40f0700 SIP Queueing PDU "1 SUBSCRIBE <401>", transaction=z9hG4bK44a447d4-c1b8-e311-88f5-5c260a60258d, token=706e47d4-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:47:18.093 0:25.818 Opal Liste...51c40f0700 Opal Transport clean up on termination 2014/04/02 12:47:18.093 0:25.818 Opal Liste...51c40f0700 Opal Transport Close 2014/04/02 12:47:18.093 0:25.818 Pool:0x7f51979bc700 SIP Handling PDU "1 SUBSCRIBE <401>" for transaction=z9hG4bK44a447d4-c1b8-e311-88f5-5c260a60258d 2014/04/02 12:47:18.093 0:25.818 Pool:0x7f51979bc700 SIP SUBSCRIBE transaction id=z9hG4bK44a447d4-c1b8-e311-88f5-5c260a60258d completing. 2014/04/02 12:47:18.093 0:25.818 Opal Liste...51c40f0700 Opal Deleted transport udp$147.173.1.45:5060 2014/04/02 12:47:18.093 0:25.818 Pool:0x7f51979bc700 SIP Finalising handlers interface "147.173.3.245%eth0" 2014/04/02 12:47:18.093 0:25.818 Pool:0x7f51979bc700 OpalUDP Setting interface to 147.173.3.245%eth0 2014/04/02 12:47:18.093 0:25.818 Pool:0x7f51979bc700 SIP Received Authentication Required response for z9hG4bK44a447d4-c1b8-e311-88f5-5c260a60258d 2014/04/02 12:47:18.093 0:25.818 Pool:0x7f51979bc700 SIP Located existing credentials for ID "umfodx" at realm "xivo" 2014/04/02 12:47:18.093 0:25.818 PThreadObj...51bc25a700 SIP Transaction remembering interface "147.173.3.245%eth0" 2014/04/02 12:47:18.093 0:25.818 Pool:0x7f51979bc700 SIP Using auth info for realm "xivo" 2014/04/02 12:47:18.093 0:25.818 PThreadObj...51bc25a700 DNS SRV Lookup "_sip._udp.xivo.grenoble.cnrs.fr" 2014/04/02 12:47:18.093 0:25.818 Pool:0x7f51979bc700 SIP Creating authentication credentials of user "umfodx" for realm "xivo" 2014/04/02 12:47:18.093 0:25.818 Pool:0x7f51979bc700 SIP Auth realm set to xivo 2014/04/02 12:47:18.093 0:25.818 PThreadObj...51bc25a700 SIP No SRV record found. 2014/04/02 12:47:18.093 0:25.818 Pool:0x7f51979bc700 Duplicate handler for authId="umfodx", realm="xivo" 2014/04/02 12:47:18.093 0:25.818 PThreadObj...51bc25a700 SIP Transaction remote address is udp$xivo.grenoble.cnrs.fr:5060 2014/04/02 12:47:18.093 0:25.818 Pool:0x7f51979bc700 Duplicate handler for AOR user="umfodx", realm="xivo" 2014/04/02 12:47:18.094 0:25.818 PThreadObj...51bc25a700 SIP Sending PDU (893 bytes) to: rem=udp$147.173.1.45:5060,local=udp$147.173.3.245:5060,if=147.173.3.245%eth0 PUBLISH sip:umfodx xivo grenoble cnrs fr SIP/2.0 CSeq: 4 PUBLISH Via: SIP/2.0/UDP 147.173.3.245:5060;branch=z9hG4bK100548d4-c1b8-e311-88f5-5c260a60258d;rport User-Agent: Ekiga/4.0.1 From: Call-ID: 82ed47d4-c1b8-e311-88f5-5c260a60258d laptop-dom To: Expires: 300 Event: presence Content-Length: 459 Content-Type: application/pidf+xml Max-Forwards: 70 open sip:umfodx xivo grenoble cnrs fr 2014-04-02T12:47:18+02:00 2014/04/02 12:47:18.094 0:25.818 Pool:0x7f51979bc700 SIP Transaction created. 2014/04/02 12:47:18.094 0:25.818 PThreadObj...51bc25a700 OpalUDP Setting interface to 147.173.3.245%eth0 2014/04/02 12:47:18.094 0:25.818 PThreadObj...51bc25a700 SIP Transaction timers set: retry=0.500, completion=6.000 2014/04/02 12:47:18.094 0:25.819 Pool:0x7f51979bc700 HTTP Adding authentication information 2014/04/02 12:47:18.094 0:25.819 Pool:0x7f51979bc700 SIP Transaction remembering interface "147.173.3.245%eth0" 2014/04/02 12:47:18.094 0:25.819 Pool:0x7f51979bc700 DNS SRV Lookup "_sip._udp.xivo.grenoble.cnrs.fr" 2014/04/02 12:47:18.094 0:25.819 Pool:0x7f51979bc700 SIP No SRV record found. 2014/04/02 12:47:18.094 0:25.819 Pool:0x7f51979bc700 SIP Transaction remote address is udp$xivo.grenoble.cnrs.fr:5060 2014/04/02 12:47:18.095 0:25.819 Opal Liste...51c40f0700 OpalUDP Binding to interface: 147.173.3.245:5060 2014/04/02 12:47:18.095 0:25.819 Pool:0x7f51979bc700 SIP Sending PDU (788 bytes) to: rem=udp$147.173.1.45:5060,local=udp$147.173.3.245:5060,if=147.173.3.245%eth0 SUBSCRIBE sip:umfodx xivo grenoble cnrs fr SIP/2.0 CSeq: 2 SUBSCRIBE Via: SIP/2.0/UDP 147.173.3.245:5060;branch=z9hG4bK342248d4-c1b8-e311-88f5-5c260a60258d;rport User-Agent: Ekiga/4.0.1 Authorization: Digest username="umfodx", realm="xivo", nonce="74787887", uri="sip:umfodx xivo grenoble cnrs fr", algorithm=MD5, response="b3e708c0a9693132214f80dc72e2c522" From: ;tag=7a9b47d4-c1b8-e311-88f5-5c260a60258d Call-ID: 706e47d4-c1b8-e311-88f5-5c260a60258d laptop-dom To: Accept: application/simple-message-summary Contact: Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK Expires: 3600 Event: message-summary Content-Length: 0 Max-Forwards: 70 2014/04/02 12:47:18.095 0:25.819 Opal Liste...51c40f0700 SIP Waiting for PDU on udp$147.173.1.45:5060 2014/04/02 12:47:18.095 0:25.819 Pool:0x7f51979bc700 OpalUDP Setting interface to 147.173.3.245%eth0 2014/04/02 12:47:18.095 0:25.819 Pool:0x7f51979bc700 SIP Transaction timers set: retry=0.500, completion=6.000 2014/04/02 12:47:18.095 0:25.819 Pool:0x7f51979bc700 SIP SUBSCRIBE transaction id=z9hG4bK44a447d4-c1b8-e311-88f5-5c260a60258d completed. 2014/04/02 12:47:18.095 0:25.819 Pool:0x7f51979bc700 SIP Handled PDU "1 SUBSCRIBE <401>" 2014/04/02 12:47:18.095 0:25.819 Opal Liste...51c40f0700 SIP PDU received: rem=udp$147.173.1.45:5060,local=udp$147.173.3.245:5060,if=147.173.3.245%eth0 SIP/2.0 489 Bad Event CSeq: 4 PUBLISH Via: SIP/2.0/UDP 147.173.3.245:5060;branch=z9hG4bK100548d4-c1b8-e311-88f5-5c260a60258d;received=147.173.3.245;rport=5060 Server: XiVO PBX From: Call-ID: 82ed47d4-c1b8-e311-88f5-5c260a60258d laptop-dom Supported: replaces, timer To: ;tag=as245ebe3e Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Content-Length: 0 2014/04/02 12:47:18.095 0:25.819 Opal Liste...51c40f0700 SIP Queueing PDU "4 PUBLISH <489>", transaction=z9hG4bK100548d4-c1b8-e311-88f5-5c260a60258d, token=82ed47d4-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:47:18.095 0:25.819 Opal Liste...51c40f0700 Opal Transport clean up on termination 2014/04/02 12:47:18.095 0:25.820 Opal Liste...51c40f0700 Opal Transport Close 2014/04/02 12:47:18.095 0:25.820 Pool:0x7f51979bc700 SIP Handling PDU "4 PUBLISH <489>" for transaction=z9hG4bK100548d4-c1b8-e311-88f5-5c260a60258d 2014/04/02 12:47:18.095 0:25.820 Pool:0x7f51979bc700 SIP PUBLISH transaction id=z9hG4bK100548d4-c1b8-e311-88f5-5c260a60258d completing. 2014/04/02 12:47:18.095 0:25.820 Opal Liste...51c40f0700 Opal Deleted transport udp$147.173.1.45:5060 2014/04/02 12:47:18.095 0:25.820 Pool:0x7f51979bc700 SIP Not retrying PUBLISH due to error response 489 Bad Event 2014/04/02 12:47:18.095 0:25.820 Pool:0x7f51979bc700 SIP Changing PUBLISH handler from Subscribing to Unsubscribed, target=sip:umfodx xivo grenoble cnrs fr, id=82ed47d4-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:47:18.095 0:25.820 Pool:0x7f51979bc700 SIP PUBLISH transaction id=z9hG4bK100548d4-c1b8-e311-88f5-5c260a60258d completed. 2014/04/02 12:47:18.095 0:25.820 Pool:0x7f51979bc700 SIP Handled PDU "4 PUBLISH <489>" 2014/04/02 12:47:18.095 0:25.820 Opal Liste...51c40f0700 OpalUDP Binding to interface: 147.173.3.245:5060 2014/04/02 12:47:18.095 0:25.820 Opal Liste...51c40f0700 SIP Waiting for PDU on udp$147.173.1.45:5060 2014/04/02 12:47:18.096 0:25.820 Opal Liste...51c40f0700 SIP PDU received: rem=udp$147.173.1.45:5060,local=udp$147.173.3.245:5060,if=147.173.3.245%eth0 SIP/2.0 200 OK CSeq: 2 SUBSCRIBE Via: SIP/2.0/UDP 147.173.3.245:5060;branch=z9hG4bK342248d4-c1b8-e311-88f5-5c260a60258d;received=147.173.3.245;rport=5060 Server: XiVO PBX From: ;tag=7a9b47d4-c1b8-e311-88f5-5c260a60258d Call-ID: 706e47d4-c1b8-e311-88f5-5c260a60258d laptop-dom Supported: replaces, timer To: ;tag=as4aea1e99 Contact: ;expires=3600 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Expires: 3600 Content-Length: 0 2014/04/02 12:47:18.096 0:25.820 Opal Liste...51c40f0700 SIP Queueing PDU "2 SUBSCRIBE <200>", transaction=z9hG4bK342248d4-c1b8-e311-88f5-5c260a60258d, token=706e47d4-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:47:18.096 0:25.820 Opal Liste...51c40f0700 Opal Transport clean up on termination 2014/04/02 12:47:18.096 0:25.820 Opal Liste...51c40f0700 Opal Transport Close 2014/04/02 12:47:18.096 0:25.820 Pool:0x7f51979bc700 SIP Handling PDU "2 SUBSCRIBE <200>" for transaction=z9hG4bK342248d4-c1b8-e311-88f5-5c260a60258d 2014/04/02 12:47:18.096 0:25.820 Pool:0x7f51979bc700 SIP SUBSCRIBE transaction id=z9hG4bK342248d4-c1b8-e311-88f5-5c260a60258d completing. 2014/04/02 12:47:18.096 0:25.820 Opal Liste...51c40f0700 Opal Deleted transport udp$147.173.1.45:5060 2014/04/02 12:47:18.096 0:25.820 Pool:0x7f51979bc700 SIP Finalising handlers interface "147.173.3.245%eth0" 2014/04/02 12:47:18.096 0:25.821 Pool:0x7f51979bc700 OpalUDP Setting interface to 147.173.3.245%eth0 2014/04/02 12:47:18.096 0:25.821 Opal Liste...51c40f0700 OpalUDP Binding to interface: 147.173.3.245:5060 2014/04/02 12:47:18.096 0:25.821 Pool:0x7f51979bc700 SIP Expiry time for SUBSCRIBE set to 3600 seconds. 2014/04/02 12:47:18.096 0:25.821 Pool:0x7f51979bc700 SIP Route set is 2014/04/02 12:47:18.096 0:25.821 Opal Liste...51c40f0700 SIP Waiting for PDU on udp$147.173.1.45:5060 2014/04/02 12:47:18.096 0:25.821 Pool:0x7f51979bc700 SIP Set Request URI to sip:umfodx 147 173 1 45:5060 2014/04/02 12:47:18.096 0:25.821 Pool:0x7f51979bc700 SIP Updating dialog tag from "" to "as4aea1e99" 2014/04/02 12:47:18.096 0:25.821 Opal Liste...51c40f0700 SIP PDU received: rem=udp$147.173.1.45:5060,local=udp$147.173.3.245:5060,if=147.173.3.245%eth0 NOTIFY sip:umfodx 147 173 3 245 SIP/2.0 Route: CSeq: 102 NOTIFY Via: SIP/2.0/UDP 147.173.1.45:5060;branch=z9hG4bK53298815;rport User-Agent: XiVO PBX From: "xivo" ;tag=as4aea1e99 Call-ID: 706e47d4-c1b8-e311-88f5-5c260a60258d laptop-dom To: ;tag=7a9b47d4-c1b8-e311-88f5-5c260a60258d Contact: Subscription-State: active Event: message-summary Content-Type: application/simple-message-summary Content-Length: 87 Max-Forwards: 70 Messages-Waiting: no Message-Account: sip:*98 147 173 1 45 Voice-Message: 0/0 (0/0) 2014/04/02 12:47:18.097 0:25.821 Opal Liste...51c40f0700 SIP Received NOTIFY message-summary 2014/04/02 12:47:18.097 0:25.821 Pool:0x7f51979bc700 SIP Product Info: name="XiVO", version="", vendor="", comments="PBX" 2014/04/02 12:47:18.097 0:25.821 Pool:0x7f51979bc700 SIP Changing SUBSCRIBE handler from Subscribing to Subscribed, target=sip:umfodx xivo grenoble cnrs fr, id=706e47d4-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:47:18.097 0:25.821 Opal Liste...51c40f0700 SIP Found a SUBSCRIBE corresponding to the NOTIFY message-summary 2014/04/02 12:47:18.097 0:25.821 Pool:0x7f51979bc700 SIP SUBSCRIBE transaction id=z9hG4bK342248d4-c1b8-e311-88f5-5c260a60258d completed. 2014/04/02 12:47:18.097 0:25.821 Pool:0x7f51979bc700 SIP Handled PDU "2 SUBSCRIBE <200>" 2014/04/02 12:47:18.097 0:25.821 Opal Liste...51c40f0700 SIP Product Info: name="XiVO", version="", vendor="", comments="PBX" 2014/04/02 12:47:18.097 0:25.821 Opal Liste...51c40f0700 SIP Dialog initial sequence number 102 2014/04/02 12:47:18.097 0:25.821 Opal Liste...51c40f0700 SIP Subscription is Subscribed 2014/04/02 12:47:18.097 0:25.821 Opal Liste...51c40f0700 SIP Calling package NOTIFY handler for AOR "sip:umfodx xivo grenoble cnrs fr" 2014/04/02 12:47:18.098 0:25.822 Opal Liste...51c40f0700 SIP Sending PDU (368 bytes) to: rem=udp$147.173.1.45:5060,local=udp$147.173.3.245:5060,if=147.173.3.245%eth0 SIP/2.0 200 OK CSeq: 102 NOTIFY Via: SIP/2.0/UDP 147.173.1.45:5060;branch=z9hG4bK53298815;rport=5060;received=147.173.1.45 From: "xivo" ;tag=as4aea1e99 Call-ID: 706e47d4-c1b8-e311-88f5-5c260a60258d laptop-dom To: ;tag=7a9b47d4-c1b8-e311-88f5-5c260a60258d Contact: Content-Length: 0 2014/04/02 12:47:18.098 0:25.822 Opal Liste...51c40f0700 OpalUDP Setting interface to 147.173.3.245%eth0 2014/04/02 12:47:18.098 0:25.822 Opal Liste...51c40f0700 Opal Transport clean up on termination 2014/04/02 12:47:18.098 0:25.822 Opal Liste...51c40f0700 Opal Transport Close 2014/04/02 12:47:18.098 0:25.822 Opal Liste...51c40f0700 Opal Deleted transport udp$147.173.1.45:5060 2014/04/02 12:47:18.435 0:26.160 Opal Garba...51dbdef700 Opal Transport clean up on termination 2014/04/02 12:47:18.435 0:26.160 Opal Garba...51dbdef700 Opal Transport Close 2014/04/02 12:47:18.435 0:26.160 Opal Garba...51dbdef700 Opal Transport clean up on termination 2014/04/02 12:47:18.435 0:26.160 Opal Garba...51dbdef700 Opal Transport Close 2014/04/02 12:47:18.435 0:26.160 Opal Garba...51dbdef700 Opal Deleted transport udp$147.173.1.45:5060 2014/04/02 12:47:18.436 0:26.161 Opal Garba...51dbdef700 SIP Destroyed PUBLISH handler for sip:umfodx xivo grenoble cnrs fr 2014/04/02 12:47:18.437 0:26.161 Opal Garba...51dbdef700 Opal Transport clean up on termination 2014/04/02 12:47:18.437 0:26.161 Opal Garba...51dbdef700 Opal Transport Close 2014/04/02 12:47:18.437 0:26.161 Opal Garba...51dbdef700 Opal Transport clean up on termination 2014/04/02 12:47:18.437 0:26.161 Opal Garba...51dbdef700 Opal Transport Close 2014/04/02 12:47:18.437 0:26.162 Opal Garba...51dbdef700 Opal Deleted transport udp$147.173.1.45:5060 2014/04/02 12:47:18.437 0:26.162 Opal Garba...51dbdef700 SIP Destroyed PUBLISH handler for sip:umfodx xivo grenoble cnrs fr 2014/04/02 12:47:19.091 0:26.816 Housekeepe...51dbef3700 SIP Set state Terminated_Success for PUBLISH transaction id=z9hG4bKfe8547d4-c1b8-e311-88f5-5c260a60258d 2014/04/02 12:47:19.092 0:26.816 Housekeepe...51dbef3700 SIP Completed state for transaction id=z9hG4bKfe8547d4-c1b8-e311-88f5-5c260a60258d 2014/04/02 12:47:19.117 0:26.842 Housekeepe...51dbef3700 SIP Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK44a447d4-c1b8-e311-88f5-5c260a60258d 2014/04/02 12:47:19.117 0:26.842 Housekeepe...51dbef3700 SIP Completed state for transaction id=z9hG4bK44a447d4-c1b8-e311-88f5-5c260a60258d 2014/04/02 12:47:19.117 0:26.842 Housekeepe...51dbef3700 SIP Set state Terminated_Success for PUBLISH transaction id=z9hG4bK100548d4-c1b8-e311-88f5-5c260a60258d 2014/04/02 12:47:19.117 0:26.842 Housekeepe...51dbef3700 SIP Completed state for transaction id=z9hG4bK100548d4-c1b8-e311-88f5-5c260a60258d 2014/04/02 12:47:19.117 0:26.842 Housekeepe...51dbef3700 SIP Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK342248d4-c1b8-e311-88f5-5c260a60258d 2014/04/02 12:47:19.117 0:26.842 Housekeepe...51dbef3700 SIP Completed state for transaction id=z9hG4bK342248d4-c1b8-e311-88f5-5c260a60258d 2014/04/02 12:47:19.439 0:27.163 Opal Garba...51dbdef700 SIP Transaction id=z9hG4bK44a447d4-c1b8-e311-88f5-5c260a60258d destroyed. 2014/04/02 12:47:19.439 0:27.164 Opal Garba...51dbdef700 SIP Transaction id=z9hG4bK342248d4-c1b8-e311-88f5-5c260a60258d destroyed. 2014/04/02 12:47:19.440 0:27.165 Opal Garba...51dbdef700 SIP Transaction id=z9hG4bK100548d4-c1b8-e311-88f5-5c260a60258d destroyed. 2014/04/02 12:47:19.441 0:27.165 Opal Garba...51dbdef700 SIP Transaction id=z9hG4bKfe8547d4-c1b8-e311-88f5-5c260a60258d destroyed. 2014/04/02 12:47:22.536 0:30.261 Housekeepe...51dbef3700 SIP Starting SUBSCRIBE for offline retry 2014/04/02 12:47:22.537 0:30.261 Housekeepe...51dbef3700 SIP Changing SUBSCRIBE handler from Unavailable to Restoring, target=sip:umfodx xivo grenoble cnrs fr;OPAL-local-id=sip:umfodx%40xivo.grenoble.cnrs.fr, id=e25309c5-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:47:22.537 0:30.261 Housekeepe...51dbef3700 DNS SRV Lookup "_sip._tcp.xivo.grenoble.cnrs.fr" 2014/04/02 12:47:22.540 0:30.264 Housekeepe...51dbef3700 DNS Query failed: error=-1 2014/04/02 12:47:22.540 0:30.264 Housekeepe...51dbef3700 SIP No SRV record found. 2014/04/02 12:47:22.540 0:30.264 Housekeepe...51dbef3700 SIP No compatible listener to create transport for tcp$xivo.grenoble.cnrs.fr:5060 2014/04/02 12:47:22.540 0:30.265 Housekeepe...51dbef3700 SIP Changing SUBSCRIBE handler from Restoring to Unavailable, target=sip:umfodx xivo grenoble cnrs fr;OPAL-local-id=sip:umfodx%40xivo.grenoble.cnrs.fr, id=e25309c5-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:47:22.540 0:30.265 Housekeepe...51dbef3700 SIP Retrying SUBSCRIBE after 30 seconds. 2014/04/02 12:47:52.562 1:00.287 Housekeepe...51dbef3700 SIP Starting SUBSCRIBE for offline retry 2014/04/02 12:47:52.563 1:00.287 Housekeepe...51dbef3700 SIP Changing SUBSCRIBE handler from Unavailable to Restoring, target=sip:umfodx xivo grenoble cnrs fr;OPAL-local-id=sip:umfodx%40xivo.grenoble.cnrs.fr, id=e25309c5-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:47:52.563 1:00.287 Housekeepe...51dbef3700 DNS SRV Lookup "_sip._tcp.xivo.grenoble.cnrs.fr" 2014/04/02 12:47:52.566 1:00.290 Housekeepe...51dbef3700 DNS Query failed: error=-1 2014/04/02 12:47:52.566 1:00.290 Housekeepe...51dbef3700 SIP No SRV record found. 2014/04/02 12:47:52.566 1:00.291 Housekeepe...51dbef3700 SIP No compatible listener to create transport for tcp$xivo.grenoble.cnrs.fr:5060 2014/04/02 12:47:52.566 1:00.291 Housekeepe...51dbef3700 SIP Changing SUBSCRIBE handler from Restoring to Unavailable, target=sip:umfodx xivo grenoble cnrs fr;OPAL-local-id=sip:umfodx%40xivo.grenoble.cnrs.fr, id=e25309c5-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:47:52.566 1:00.291 Housekeepe...51dbef3700 SIP Retrying SUBSCRIBE after 30 seconds. 2014/04/02 12:48:22.589 1:30.313 Housekeepe...51dbef3700 SIP Starting SUBSCRIBE for offline retry 2014/04/02 12:48:22.589 1:30.314 Housekeepe...51dbef3700 SIP Changing SUBSCRIBE handler from Unavailable to Restoring, target=sip:umfodx xivo grenoble cnrs fr;OPAL-local-id=sip:umfodx%40xivo.grenoble.cnrs.fr, id=e25309c5-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:48:22.590 1:30.314 Housekeepe...51dbef3700 DNS SRV Lookup "_sip._tcp.xivo.grenoble.cnrs.fr" 2014/04/02 12:48:22.592 1:30.317 Housekeepe...51dbef3700 DNS Query failed: error=-1 2014/04/02 12:48:22.593 1:30.317 Housekeepe...51dbef3700 SIP No SRV record found. 2014/04/02 12:48:22.593 1:30.317 Housekeepe...51dbef3700 SIP No compatible listener to create transport for tcp$xivo.grenoble.cnrs.fr:5060 2014/04/02 12:48:22.593 1:30.317 Housekeepe...51dbef3700 SIP Changing SUBSCRIBE handler from Restoring to Unavailable, target=sip:umfodx xivo grenoble cnrs fr;OPAL-local-id=sip:umfodx%40xivo.grenoble.cnrs.fr, id=e25309c5-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:48:22.593 1:30.318 Housekeepe...51dbef3700 SIP Retrying SUBSCRIBE after 30 seconds. 2014/04/02 12:48:52.615 2:00.339 Housekeepe...51dbef3700 SIP Starting SUBSCRIBE for offline retry 2014/04/02 12:48:52.615 2:00.340 Housekeepe...51dbef3700 SIP Changing SUBSCRIBE handler from Unavailable to Restoring, target=sip:umfodx xivo grenoble cnrs fr;OPAL-local-id=sip:umfodx%40xivo.grenoble.cnrs.fr, id=e25309c5-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:48:52.616 2:00.340 Housekeepe...51dbef3700 DNS SRV Lookup "_sip._tcp.xivo.grenoble.cnrs.fr" 2014/04/02 12:48:52.618 2:00.343 Housekeepe...51dbef3700 DNS Query failed: error=-1 2014/04/02 12:48:52.618 2:00.343 Housekeepe...51dbef3700 SIP No SRV record found. 2014/04/02 12:48:52.619 2:00.343 Housekeepe...51dbef3700 SIP No compatible listener to create transport for tcp$xivo.grenoble.cnrs.fr:5060 2014/04/02 12:48:52.619 2:00.343 Housekeepe...51dbef3700 SIP Changing SUBSCRIBE handler from Restoring to Unavailable, target=sip:umfodx xivo grenoble cnrs fr;OPAL-local-id=sip:umfodx%40xivo.grenoble.cnrs.fr, id=e25309c5-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:48:52.619 2:00.343 Housekeepe...51dbef3700 SIP Retrying SUBSCRIBE after 30 seconds. 2014/04/02 12:48:57.557 2:05.281 OpalMan Clearing all calls and waiting, primary thread. 2014/04/02 12:48:58.468 2:06.192 OpalMan All calls cleared. 2014/04/02 12:48:58.468 2:06.192 OpalMan Shutting down endpoints. 2014/04/02 12:48:58.468 2:06.193 OpalMan Clearing all calls and waiting, primary thread. 2014/04/02 12:48:59.468 2:07.193 OpalMan All calls cleared. 2014/04/02 12:48:59.468 2:07.193 OpalPres 'sip:umfodx xivo grenoble cnrs fr' closing. 2014/04/02 12:48:59.468 2:07.193 OpalPres Stopping command thread PThreadObj:0x7f51bc25a700 2014/04/02 12:48:59.468 2:07.193 PThreadObj...51bc25a700 OpalPres Command thread ended 2014/04/02 12:48:59.469 2:07.193 PThreadObj...51bc25a700 PWLib File handle low water mark set: 44 PTextFile 2014/04/02 12:48:59.469 2:07.193 PThreadObj...51bc25a700 PTLib Thread ended: name="PThreadObj:0x7f51bc25a700", real=126.953, kernel=0.010 (0%), user=0.000 (0%), both=0.010 (0%) 2014/04/02 12:48:59.479 2:07.203 SIPPres 'sip:umfodx xivo grenoble cnrs fr' sending own presence No Presence/ 2014/04/02 12:48:59.479 2:07.203 SIPPres PIDF entity derived from AOR as pres:umfodx xivo grenoble cnrs fr 2014/04/02 12:48:59.479 2:07.204 SIP Start PUBLISH eventPackage=presence aor=sip:umfodx xivo grenoble cnrs fr remote=sip:umfodx xivo grenoble cnrs fr local= contact= proxy= authID= realm= expire=300 restore=30 minRetry=default maxRetry=default 2014/04/02 12:48:59.481 2:07.205 SIP Constructed PUBLISH handler for sip:umfodx xivo grenoble cnrs fr 2014/04/02 12:48:59.481 2:07.205 SIP Executing state change to Subscribing for PUBLISH handler, target=sip:umfodx xivo grenoble cnrs fr, id=4485b610-c2b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:48:59.481 2:07.205 SIP Changing PUBLISH handler from Unavailable to Subscribing, target=sip:umfodx xivo grenoble cnrs fr, id=4485b610-c2b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:48:59.481 2:07.206 DNS SRV Lookup "_sip._udp.xivo.grenoble.cnrs.fr" 2014/04/02 12:48:59.484 2:07.208 DNS Query failed: error=-1 2014/04/02 12:48:59.484 2:07.208 SIP No SRV record found. 2014/04/02 12:48:59.484 2:07.209 OpalUDP Binding to interface: 0.0.0.0:5060 2014/04/02 12:48:59.484 2:07.209 PWLib File handle low water mark set: 40 PUDPSocket 2014/04/02 12:48:59.484 2:07.209 SIP Created transport udp$147.173.1.45:5060 2014/04/02 12:48:59.484 2:07.209 OpalUDP Started connect to 147.173.1.45:5060 2014/04/02 12:48:59.485 2:07.209 OpalUDP Writing to interface 0 - "147.173.3.245%eth0" 2014/04/02 12:48:59.485 2:07.209 SIP Transaction created. 2014/04/02 12:48:59.486 2:07.210 SIP Transaction remembering interface "147.173.3.245%eth0" 2014/04/02 12:48:59.486 2:07.210 DNS SRV Lookup "_sip._udp.xivo.grenoble.cnrs.fr" 2014/04/02 12:48:59.486 2:07.210 SIP No SRV record found. 2014/04/02 12:48:59.486 2:07.210 SIP Transaction remote address is udp$xivo.grenoble.cnrs.fr:5060 2014/04/02 12:48:59.486 2:07.211 SIP Sending PDU (895 bytes) to: rem=udp$147.173.1.45:5060,local=udp$147.173.3.245:5060,if=147.173.3.245%eth0 PUBLISH sip:umfodx xivo grenoble cnrs fr SIP/2.0 CSeq: 5 PUBLISH Via: SIP/2.0/UDP 147.173.3.245:5060;branch=z9hG4bKf03cb710-c2b8-e311-88f5-5c260a60258d;rport User-Agent: Ekiga/4.0.1 From: Call-ID: 4485b610-c2b8-e311-88f5-5c260a60258d laptop-dom To: Expires: 300 Event: presence Content-Length: 461 Content-Type: application/pidf+xml Max-Forwards: 70 closed sip:umfodx xivo grenoble cnrs fr 2014-04-02T12:48:59+02:00 2014/04/02 12:48:59.487 2:07.211 OpalUDP Setting interface to 147.173.3.245%eth0 2014/04/02 12:48:59.487 2:07.212 SIP Transaction timers set: retry=0.499, completion=5.999 2014/04/02 12:48:59.487 2:07.212 SIPPres 'sip:umfodx xivo grenoble cnrs fr' sending final unsubscribe for own presence watcher 2014/04/02 12:48:59.488 2:07.212 SIP Expiry time for SUBSCRIBE set to 300 seconds. 2014/04/02 12:48:59.488 2:07.212 SIP Changing SUBSCRIBE handler from Unavailable to Unsubscribed, target=sip:umfodx xivo grenoble cnrs fr;OPAL-local-id=sip:umfodx%40xivo.grenoble.cnrs.fr, id=e25309c5-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:48:59.488 2:07.212 SIP Start PUBLISH eventPackage=presence aor=sip:umfodx xivo grenoble cnrs fr remote= local= contact= proxy= authID= realm= expire=0 restore=30 minRetry=default maxRetry=default 2014/04/02 12:48:59.488 2:07.213 Opal Liste...51c40f0700 OpalUDP Binding to interface: 147.173.3.245:5060 2014/04/02 12:48:59.488 2:07.213 SIP Queueing state change to Subscribing for PUBLISH handler while in Subscribing state, target=sip:umfodx xivo grenoble cnrs fr, id=4485b610-c2b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:48:59.488 2:07.213 SIPPres 'sip:umfodx xivo grenoble cnrs fr' awaiting unsubscriptions to complete. 2014/04/02 12:48:59.489 2:07.213 SIPPres 'sip:umfodx xivo grenoble cnrs fr' closed. 2014/04/02 12:48:59.488 2:07.213 Opal Liste...51c40f0700 SIP Waiting for PDU on udp$147.173.1.45:5060 2014/04/02 12:48:59.489 2:07.213 OpalEP pc endpoint shutting down. 2014/04/02 12:48:59.489 2:07.213 SIP Shutting down. 2014/04/02 12:48:59.489 2:07.214 SIP Changing REGISTER handler from Subscribed to Unsubscribing, target=sip:umfodx xivo grenoble cnrs fr, id=864109c5-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:48:59.489 2:07.214 SIP Transaction created. 2014/04/02 12:48:59.489 2:07.214 Opal Liste...51c40f0700 SIP PDU received: rem=udp$147.173.1.45:5060,local=udp$147.173.3.245:5060,if=147.173.3.245%eth0 SIP/2.0 489 Bad Event CSeq: 5 PUBLISH Via: SIP/2.0/UDP 147.173.3.245:5060;branch=z9hG4bKf03cb710-c2b8-e311-88f5-5c260a60258d;received=147.173.3.245;rport=5060 Server: XiVO PBX From: Call-ID: 4485b610-c2b8-e311-88f5-5c260a60258d laptop-dom Supported: replaces, timer To: ;tag=as429ef55a Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Content-Length: 0 2014/04/02 12:48:59.490 2:07.214 Opal Liste...51c40f0700 SIP Queueing PDU "5 PUBLISH <489>", transaction=z9hG4bKf03cb710-c2b8-e311-88f5-5c260a60258d, token=4485b610-c2b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:48:59.490 2:07.214 Opal Liste...51c40f0700 Opal Transport clean up on termination 2014/04/02 12:48:59.490 2:07.214 Opal Liste...51c40f0700 Opal Transport Close 2014/04/02 12:48:59.490 2:07.214 Pool:0x7f51979bc700 SIP Handling PDU "5 PUBLISH <489>" for transaction=z9hG4bKf03cb710-c2b8-e311-88f5-5c260a60258d 2014/04/02 12:48:59.490 2:07.215 Opal Liste...51c40f0700 Opal Deleted transport udp$147.173.1.45:5060 2014/04/02 12:48:59.490 2:07.215 Pool:0x7f51979bc700 SIP PUBLISH transaction id=z9hG4bKf03cb710-c2b8-e311-88f5-5c260a60258d completing. 2014/04/02 12:48:59.490 2:07.215 Pool:0x7f51979bc700 SIP Not retrying PUBLISH due to error response 489 Bad Event 2014/04/02 12:48:59.491 2:07.215 HTTP Adding authentication information 2014/04/02 12:48:59.490 2:07.215 Pool:0x7f51979bc700 SIP Changing PUBLISH handler from Subscribing to Unsubscribed, target=sip:umfodx xivo grenoble cnrs fr, id=4485b610-c2b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:48:59.491 2:07.215 SIP Transaction remembering interface "147.173.3.245%eth0" 2014/04/02 12:48:59.491 2:07.215 Pool:0x7f51979bc700 SIP Changing PUBLISH handler from Unsubscribed to Subscribing, target=sip:umfodx xivo grenoble cnrs fr, id=4485b610-c2b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:48:59.491 2:07.215 DNS SRV Lookup "_sip._udp.xivo.grenoble.cnrs.fr" 2014/04/02 12:48:59.491 2:07.216 SIP No SRV record found. 2014/04/02 12:48:59.491 2:07.216 SIP Transaction remote address is udp$xivo.grenoble.cnrs.fr:5060 2014/04/02 12:48:59.491 2:07.216 Pool:0x7f51979bc700 OpalUDP Writing to interface 0 - "147.173.3.245%eth0" 2014/04/02 12:48:59.491 2:07.216 Pool:0x7f51979bc700 SIP Transaction created. 2014/04/02 12:48:59.492 2:07.216 SIP Sending PDU (706 bytes) to: rem=udp$147.173.1.45:5060,local=udp$147.173.3.245:5060,if=147.173.3.245%eth0 REGISTER sip:xivo.grenoble.cnrs.fr SIP/2.0 CSeq: 6 REGISTER Via: SIP/2.0/UDP 147.173.3.245:5060;branch=z9hG4bKdcebb710-c2b8-e311-88f5-5c260a60258d;rport User-Agent: Ekiga/4.0.1 Authorization: Digest username="umfodx", realm="xivo", nonce="0cbcaff9", uri="sip:xivo.grenoble.cnrs.fr", algorithm=MD5, response="64df171f930359595684b3dc7926bee6" From: ;tag=6a4909c5-c1b8-e311-88f5-5c260a60258d Call-ID: 864109c5-c1b8-e311-88f5-5c260a60258d laptop-dom To: Contact: Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK Expires: 0 Content-Length: 0 Max-Forwards: 70 2014/04/02 12:48:59.492 2:07.217 OpalUDP Setting interface to 147.173.3.245%eth0 2014/04/02 12:48:59.492 2:07.217 SIP Transaction timers set: retry=0.500, completion=6.000 2014/04/02 12:48:59.493 2:07.217 SIP Changing SUBSCRIBE handler from Subscribed to Unsubscribing, target=sip:umfodx xivo grenoble cnrs fr, id=706e47d4-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:48:59.493 2:07.218 SIP Transaction created. 2014/04/02 12:48:59.493 2:07.218 Opal Liste...51c40f0700 OpalUDP Binding to interface: 147.173.3.245:5060 2014/04/02 12:48:59.493 2:07.218 Pool:0x7f51979bc700 SIP Transaction remembering interface "147.173.3.245%eth0" 2014/04/02 12:48:59.494 2:07.218 Pool:0x7f51979bc700 DNS SRV Lookup "_sip._udp.xivo.grenoble.cnrs.fr" 2014/04/02 12:48:59.493 2:07.218 Opal Liste...51c40f0700 SIP Waiting for PDU on udp$147.173.1.45:5060 2014/04/02 12:48:59.494 2:07.218 Pool:0x7f51979bc700 SIP No SRV record found. 2014/04/02 12:48:59.494 2:07.219 Pool:0x7f51979bc700 SIP Transaction remote address is udp$xivo.grenoble.cnrs.fr:5060 2014/04/02 12:48:59.494 2:07.219 Opal Liste...51c40f0700 SIP PDU received: rem=udp$147.173.1.45:5060,local=udp$147.173.3.245:5060,if=147.173.3.245%eth0 SIP/2.0 401 Unauthorized CSeq: 6 REGISTER Via: SIP/2.0/UDP 147.173.3.245:5060;branch=z9hG4bKdcebb710-c2b8-e311-88f5-5c260a60258d;received=147.173.3.245;rport=5060 Server: XiVO PBX From: ;tag=6a4909c5-c1b8-e311-88f5-5c260a60258d Call-ID: 864109c5-c1b8-e311-88f5-5c260a60258d laptop-dom Supported: replaces, timer To: ;tag=as34fa6bec Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Content-Length: 0 WWW-Authenticate: Digest algorithm=MD5, realm="xivo", nonce="20843bd0" 2014/04/02 12:48:59.495 2:07.219 Opal Liste...51c40f0700 SIP Queueing PDU "6 REGISTER <401>", transaction=z9hG4bKdcebb710-c2b8-e311-88f5-5c260a60258d, token=864109c5-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:48:59.495 2:07.219 Opal Liste...51c40f0700 PTLib No permission to set priority level 3 2014/04/02 12:48:59.495 2:07.219 Pool:0x7f51979bc700 SIP Sending PDU (396 bytes) to: rem=udp$147.173.1.45:5060,local=udp$147.173.3.245:5060,if=147.173.3.245%eth0 PUBLISH sip:umfodx xivo grenoble cnrs fr SIP/2.0 CSeq: 7 PUBLISH Via: SIP/2.0/UDP 147.173.3.245:5060;branch=z9hG4bK7c55b810-c2b8-e311-88f5-5c260a60258d;rport User-Agent: Ekiga/4.0.1 From: Call-ID: 4485b610-c2b8-e311-88f5-5c260a60258d laptop-dom To: Expires: 300 Event: presence Content-Length: 0 Max-Forwards: 70 2014/04/02 12:48:59.495 2:07.220 Opal Liste...51c40f0700 Opal Transport clean up on termination 2014/04/02 12:48:59.495 2:07.220 Opal Liste...51c40f0700 Opal Transport Close 2014/04/02 12:48:59.495 2:07.220 Pool:0x7f51979bc700 OpalUDP Setting interface to 147.173.3.245%eth0 2014/04/02 12:48:59.495 2:07.220 HTTP Adding authentication information 2014/04/02 12:48:59.495 2:07.220 SIP Transaction remembering interface "147.173.3.245%eth0" 2014/04/02 12:48:59.495 2:07.220 Pool:0x7f51bc25a700 SIP Handling PDU "6 REGISTER <401>" for transaction=z9hG4bKdcebb710-c2b8-e311-88f5-5c260a60258d 2014/04/02 12:48:59.495 2:07.220 Opal Liste...51c40f0700 Opal Deleted transport udp$147.173.1.45:5060 2014/04/02 12:48:59.495 2:07.220 Pool:0x7f51979bc700 SIP Transaction timers set: retry=0.500, completion=6.000 2014/04/02 12:48:59.495 2:07.220 Pool:0x7f51bc25a700 SIP REGISTER transaction id=z9hG4bKdcebb710-c2b8-e311-88f5-5c260a60258d completing. 2014/04/02 12:48:59.496 2:07.220 Pool:0x7f51bc25a700 SIP Finalising handlers interface "147.173.3.245%eth0" 2014/04/02 12:48:59.496 2:07.220 Pool:0x7f51bc25a700 OpalUDP Setting interface to 147.173.3.245%eth0 2014/04/02 12:48:59.496 2:07.220 SIP No SRV lookup as has explicit port number. 2014/04/02 12:48:59.496 2:07.220 Pool:0x7f51bc25a700 SIP Received Authentication Required response for z9hG4bKdcebb710-c2b8-e311-88f5-5c260a60258d 2014/04/02 12:48:59.496 2:07.220 Pool:0x7f51979bc700 SIP PUBLISH transaction id=z9hG4bKf03cb710-c2b8-e311-88f5-5c260a60258d completed. 2014/04/02 12:48:59.496 2:07.220 SIP Transaction remote address is udp$147.173.1.45:5060 2014/04/02 12:48:59.496 2:07.220 Pool:0x7f51bc25a700 SIP Updating authentication credentials of user "umfodx" for realm "xivo" 2014/04/02 12:48:59.496 2:07.220 Pool:0x7f51979bc700 SIP Handled PDU "5 PUBLISH <489>" 2014/04/02 12:48:59.496 2:07.220 Pool:0x7f51bc25a700 SIP Transaction created. 2014/04/02 12:48:59.496 2:07.220 SIP Sending PDU (792 bytes) to: rem=udp$147.173.1.45:5060,local=udp$147.173.3.245:5060,if=147.173.3.245%eth0 SUBSCRIBE sip:umfodx 147 173 1 45:5060 SIP/2.0 CSeq: 3 SUBSCRIBE Via: SIP/2.0/UDP 147.173.3.245:5060;branch=z9hG4bK707ab810-c2b8-e311-88f5-5c260a60258d;rport User-Agent: Ekiga/4.0.1 Authorization: Digest username="umfodx", realm="xivo", nonce="74787887", uri="sip:umfodx 147 173 1 45:5060", algorithm=MD5, response="d0ef2fa067460a3d3ab33d151289cd0f" From: ;tag=7a9b47d4-c1b8-e311-88f5-5c260a60258d Call-ID: 706e47d4-c1b8-e311-88f5-5c260a60258d laptop-dom To: ;tag=as4aea1e99 Accept: application/simple-message-summary Contact: Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK Expires: 0 Event: message-summary Content-Length: 0 Max-Forwards: 70 2014/04/02 12:48:59.496 2:07.221 Opal Liste...51c40f0700 OpalUDP Binding to interface: 147.173.3.245:5060 2014/04/02 12:48:59.496 2:07.221 OpalUDP Setting interface to 147.173.3.245%eth0 2014/04/02 12:48:59.496 2:07.221 SIP Transaction timers set: retry=0.500, completion=6.000 2014/04/02 12:48:59.496 2:07.221 SIP Attempting to abort PUBLISH transaction id=z9hG4bK7c55b810-c2b8-e311-88f5-5c260a60258d 2014/04/02 12:48:59.496 2:07.221 Opal Liste...51c40f0700 SIP Waiting for PDU on udp$147.173.1.45:5060 2014/04/02 12:48:59.496 2:07.221 SIP Set state Terminated_Aborted for PUBLISH transaction id=z9hG4bK7c55b810-c2b8-e311-88f5-5c260a60258d 2014/04/02 12:48:59.496 2:07.221 SIP Not retrying PUBLISH due to error response 487 Request Terminated 2014/04/02 12:48:59.496 2:07.221 SIP Changing PUBLISH handler from Subscribing to Unsubscribed, target=sip:umfodx xivo grenoble cnrs fr, id=4485b610-c2b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:48:59.496 2:07.221 Pool:0x7f51bc25a700 HTTP Adding authentication information 2014/04/02 12:48:59.496 2:07.221 SIP Completed state for transaction id=z9hG4bK7c55b810-c2b8-e311-88f5-5c260a60258d 2014/04/02 12:48:59.497 2:07.221 Pool:0x7f51bc25a700 SIP Transaction remembering interface "147.173.3.245%eth0" 2014/04/02 12:48:59.496 2:07.221 Opal Liste...51c40f0700 SIP PDU received: rem=udp$147.173.1.45:5060,local=udp$147.173.3.245:5060,if=147.173.3.245%eth0 SIP/2.0 489 Bad Event CSeq: 7 PUBLISH Via: SIP/2.0/UDP 147.173.3.245:5060;branch=z9hG4bK7c55b810-c2b8-e311-88f5-5c260a60258d;received=147.173.3.245;rport=5060 Server: XiVO PBX From: Call-ID: 4485b610-c2b8-e311-88f5-5c260a60258d laptop-dom Supported: replaces, timer To: ;tag=as0d826ef2 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Content-Length: 0 2014/04/02 12:48:59.497 2:07.221 Pool:0x7f51bc25a700 DNS SRV Lookup "_sip._udp.xivo.grenoble.cnrs.fr" 2014/04/02 12:48:59.497 2:07.221 Pool:0x7f51bc25a700 SIP No SRV record found. 2014/04/02 12:48:59.497 2:07.221 Opal Liste...51c40f0700 SIP Queueing PDU "7 PUBLISH <489>", transaction=z9hG4bK7c55b810-c2b8-e311-88f5-5c260a60258d, token=z9hG4bK7c55b810-c2b8-e311-88f5-5c260a60258d 2014/04/02 12:48:59.497 2:07.221 Opal Liste...51c40f0700 Opal Transport clean up on termination 2014/04/02 12:48:59.497 2:07.221 Opal Liste...51c40f0700 Opal Transport Close 2014/04/02 12:48:59.497 2:07.221 Pool:0x7f51bc25a700 SIP Transaction remote address is udp$xivo.grenoble.cnrs.fr:5060 2014/04/02 12:48:59.497 2:07.221 Opal Liste...51c40f0700 Opal Deleted transport udp$147.173.1.45:5060 2014/04/02 12:48:59.497 2:07.221 Opal Liste...51c40f0700 OpalUDP Binding to interface: 147.173.3.245:5060 2014/04/02 12:48:59.497 2:07.222 Opal Liste...51c40f0700 SIP Waiting for PDU on udp$147.173.1.45:5060 2014/04/02 12:48:59.497 2:07.222 Pool:0x7f51bc25a700 SIP Sending PDU (706 bytes) to: rem=udp$147.173.1.45:5060,local=udp$147.173.3.245:5060,if=147.173.3.245%eth0 REGISTER sip:xivo.grenoble.cnrs.fr SIP/2.0 CSeq: 8 REGISTER Via: SIP/2.0/UDP 147.173.3.245:5060;branch=z9hG4bK18e1b810-c2b8-e311-88f5-5c260a60258d;rport User-Agent: Ekiga/4.0.1 Authorization: Digest username="umfodx", realm="xivo", nonce="20843bd0", uri="sip:xivo.grenoble.cnrs.fr", algorithm=MD5, response="12c0b365c90860a065b1f05cbbe48cca" From: ;tag=6a4909c5-c1b8-e311-88f5-5c260a60258d Call-ID: 864109c5-c1b8-e311-88f5-5c260a60258d laptop-dom To: Contact: Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK Expires: 0 Content-Length: 0 Max-Forwards: 70 2014/04/02 12:48:59.497 2:07.222 Pool:0x7f51bc25a700 OpalUDP Setting interface to 147.173.3.245%eth0 2014/04/02 12:48:59.497 2:07.222 Opal Liste...51c40f0700 SIP PDU received: rem=udp$147.173.1.45:5060,local=udp$147.173.3.245:5060,if=147.173.3.245%eth0 SIP/2.0 401 Unauthorized CSeq: 3 SUBSCRIBE Via: SIP/2.0/UDP 147.173.3.245:5060;branch=z9hG4bK707ab810-c2b8-e311-88f5-5c260a60258d;received=147.173.3.245;rport=5060 Server: XiVO PBX From: ;tag=7a9b47d4-c1b8-e311-88f5-5c260a60258d Call-ID: 706e47d4-c1b8-e311-88f5-5c260a60258d laptop-dom Supported: replaces, timer To: ;tag=as4aea1e99 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Content-Length: 0 WWW-Authenticate: Digest algorithm=MD5, realm="xivo", nonce="4b2aa20f", stale=true 2014/04/02 12:48:59.497 2:07.222 Pool:0x7f51bc25a700 SIP Transaction timers set: retry=0.500, completion=6.000 2014/04/02 12:48:59.497 2:07.222 Opal Liste...51c40f0700 SIP Queueing PDU "3 SUBSCRIBE <401>", transaction=z9hG4bK707ab810-c2b8-e311-88f5-5c260a60258d, token=706e47d4-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:48:59.497 2:07.222 Opal Liste...51c40f0700 PTLib No permission to set priority level 3 2014/04/02 12:48:59.497 2:07.222 Pool:0x7f51bc25a700 SIP REGISTER transaction id=z9hG4bKdcebb710-c2b8-e311-88f5-5c260a60258d completed. 2014/04/02 12:48:59.497 2:07.222 Opal Liste...51c40f0700 Opal Transport clean up on termination 2014/04/02 12:48:59.497 2:07.222 Opal Liste...51c40f0700 Opal Transport Close 2014/04/02 12:48:59.497 2:07.222 Pool:0x7f51bc25a700 SIP Handled PDU "6 REGISTER <401>" 2014/04/02 12:48:59.497 2:07.222 Pool:0x7f51800e9700 SIP Handling PDU "3 SUBSCRIBE <401>" for transaction=z9hG4bK707ab810-c2b8-e311-88f5-5c260a60258d 2014/04/02 12:48:59.497 2:07.222 Pool:0x7f51979bc700 SIP Handling PDU "7 PUBLISH <489>" for transaction=z9hG4bK7c55b810-c2b8-e311-88f5-5c260a60258d 2014/04/02 12:48:59.498 2:07.222 Pool:0x7f51979bc700 SIP Ignoring duplicate response to PUBLISH transaction id=z9hG4bK7c55b810-c2b8-e311-88f5-5c260a60258d 2014/04/02 12:48:59.498 2:07.222 Pool:0x7f51800e9700 SIP SUBSCRIBE transaction id=z9hG4bK707ab810-c2b8-e311-88f5-5c260a60258d completing. 2014/04/02 12:48:59.498 2:07.222 Pool:0x7f51979bc700 SIP Handled PDU "7 PUBLISH <489>" 2014/04/02 12:48:59.498 2:07.222 Opal Liste...51c40f0700 Opal Deleted transport udp$147.173.1.45:5060 2014/04/02 12:48:59.498 2:07.222 Pool:0x7f51800e9700 SIP Finalising handlers interface "147.173.3.245%eth0" 2014/04/02 12:48:59.498 2:07.222 Pool:0x7f51800e9700 OpalUDP Setting interface to 147.173.3.245%eth0 2014/04/02 12:48:59.498 2:07.222 Pool:0x7f51800e9700 SIP Received Authentication Required response for z9hG4bK707ab810-c2b8-e311-88f5-5c260a60258d 2014/04/02 12:48:59.498 2:07.222 Pool:0x7f51800e9700 HTTP Authentication contains stale flag "true" 2014/04/02 12:48:59.498 2:07.222 Pool:0x7f51800e9700 SIP Located existing credentials for ID "umfodx" at realm "xivo" 2014/04/02 12:48:59.498 2:07.222 Pool:0x7f51800e9700 SIP Using auth info for realm "xivo" 2014/04/02 12:48:59.498 2:07.222 Pool:0x7f51800e9700 SIP Updating authentication credentials of user "umfodx" for realm "xivo" 2014/04/02 12:48:59.498 2:07.222 Pool:0x7f51800e9700 SIP Transaction created. 2014/04/02 12:48:59.498 2:07.223 Opal Liste...51c40f0700 OpalUDP Binding to interface: 147.173.3.245:5060 2014/04/02 12:48:59.498 2:07.223 Pool:0x7f51800e9700 HTTP Adding authentication information 2014/04/02 12:48:59.499 2:07.223 Pool:0x7f51800e9700 SIP Transaction remembering interface "147.173.3.245%eth0" 2014/04/02 12:48:59.499 2:07.223 Pool:0x7f51800e9700 SIP No SRV lookup as has explicit port number. 2014/04/02 12:48:59.498 2:07.223 Opal Liste...51c40f0700 SIP Waiting for PDU on udp$147.173.1.45:5060 2014/04/02 12:48:59.499 2:07.223 Pool:0x7f51800e9700 SIP Transaction remote address is udp$147.173.1.45:5060 2014/04/02 12:48:59.499 2:07.223 Pool:0x7f51800e9700 SIP Sending PDU (792 bytes) to: rem=udp$147.173.1.45:5060,local=udp$147.173.3.245:5060,if=147.173.3.245%eth0 SUBSCRIBE sip:umfodx 147 173 1 45:5060 SIP/2.0 CSeq: 4 SUBSCRIBE Via: SIP/2.0/UDP 147.173.3.245:5060;branch=z9hG4bK0c2eb910-c2b8-e311-88f5-5c260a60258d;rport User-Agent: Ekiga/4.0.1 Authorization: Digest username="umfodx", realm="xivo", nonce="4b2aa20f", uri="sip:umfodx 147 173 1 45:5060", algorithm=MD5, response="26986e44aa63f5a2615692f60b069242" From: ;tag=7a9b47d4-c1b8-e311-88f5-5c260a60258d Call-ID: 706e47d4-c1b8-e311-88f5-5c260a60258d laptop-dom To: ;tag=as4aea1e99 Accept: application/simple-message-summary Contact: Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK Expires: 0 Event: message-summary Content-Length: 0 Max-Forwards: 70 2014/04/02 12:48:59.499 2:07.223 Pool:0x7f51800e9700 OpalUDP Setting interface to 147.173.3.245%eth0 2014/04/02 12:48:59.499 2:07.223 Opal Liste...51c40f0700 SIP PDU received: rem=udp$147.173.1.45:5060,local=udp$147.173.3.245:5060,if=147.173.3.245%eth0 SIP/2.0 200 OK Date: Wed, 02 Apr 2014 10:48:59 GMT CSeq: 8 REGISTER Via: SIP/2.0/UDP 147.173.3.245:5060;branch=z9hG4bK18e1b810-c2b8-e311-88f5-5c260a60258d;received=147.173.3.245;rport=5060 Server: XiVO PBX From: ;tag=6a4909c5-c1b8-e311-88f5-5c260a60258d Call-ID: 864109c5-c1b8-e311-88f5-5c260a60258d laptop-dom Supported: replaces, timer To: ;tag=as34fa6bec Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Expires: 0 Content-Length: 0 2014/04/02 12:48:59.499 2:07.223 Pool:0x7f51800e9700 SIP Transaction timers set: retry=0.500, completion=6.000 2014/04/02 12:48:59.499 2:07.223 Pool:0x7f51800e9700 SIP SUBSCRIBE transaction id=z9hG4bK707ab810-c2b8-e311-88f5-5c260a60258d completed. 2014/04/02 12:48:59.499 2:07.223 Pool:0x7f51800e9700 SIP Handled PDU "3 SUBSCRIBE <401>" 2014/04/02 12:48:59.499 2:07.224 Opal Liste...51c40f0700 SIP Queueing PDU "8 REGISTER <200>", transaction=z9hG4bK18e1b810-c2b8-e311-88f5-5c260a60258d, token=864109c5-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:48:59.499 2:07.224 Opal Liste...51c40f0700 Opal Transport clean up on termination 2014/04/02 12:48:59.499 2:07.224 Pool:0x7f51979bc700 SIP Handling PDU "8 REGISTER <200>" for transaction=z9hG4bK18e1b810-c2b8-e311-88f5-5c260a60258d 2014/04/02 12:48:59.499 2:07.224 Opal Liste...51c40f0700 Opal Transport Close 2014/04/02 12:48:59.499 2:07.224 Pool:0x7f51979bc700 SIP REGISTER transaction id=z9hG4bK18e1b810-c2b8-e311-88f5-5c260a60258d completing. 2014/04/02 12:48:59.499 2:07.224 Pool:0x7f51979bc700 SIP Finalising handlers interface "147.173.3.245%eth0" 2014/04/02 12:48:59.499 2:07.224 Pool:0x7f51979bc700 OpalUDP Setting interface to 147.173.3.245%eth0 2014/04/02 12:48:59.499 2:07.224 Pool:0x7f51979bc700 SIP Changing REGISTER handler from Unsubscribing to Unsubscribed, target=sip:umfodx xivo grenoble cnrs fr, id=864109c5-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:48:59.499 2:07.224 Pool:0x7f51979bc700 SIP REGISTER transaction id=z9hG4bK18e1b810-c2b8-e311-88f5-5c260a60258d completed. 2014/04/02 12:48:59.499 2:07.224 Opal Liste...51c40f0700 Opal Deleted transport udp$147.173.1.45:5060 2014/04/02 12:48:59.499 2:07.224 Pool:0x7f51979bc700 SIP Handled PDU "8 REGISTER <200>" 2014/04/02 12:48:59.500 2:07.224 Opal Liste...51c40f0700 OpalUDP Binding to interface: 147.173.3.245:5060 2014/04/02 12:48:59.500 2:07.224 Opal Liste...51c40f0700 SIP Waiting for PDU on udp$147.173.1.45:5060 2014/04/02 12:48:59.500 2:07.224 Opal Liste...51c40f0700 SIP PDU received: rem=udp$147.173.1.45:5060,local=udp$147.173.3.245:5060,if=147.173.3.245%eth0 SIP/2.0 200 OK CSeq: 4 SUBSCRIBE Via: SIP/2.0/UDP 147.173.3.245:5060;branch=z9hG4bK0c2eb910-c2b8-e311-88f5-5c260a60258d;received=147.173.3.245;rport=5060 Server: XiVO PBX From: ;tag=7a9b47d4-c1b8-e311-88f5-5c260a60258d Call-ID: 706e47d4-c1b8-e311-88f5-5c260a60258d laptop-dom Supported: replaces, timer To: ;tag=as4aea1e99 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Expires: 0 Content-Length: 0 2014/04/02 12:48:59.500 2:07.225 Opal Liste...51c40f0700 SIP Queueing PDU "4 SUBSCRIBE <200>", transaction=z9hG4bK0c2eb910-c2b8-e311-88f5-5c260a60258d, token=706e47d4-c1b8-e311-88f5-5c260a60258d laptop-dom 2014/04/02 12:48:59.500 2:07.225 Opal Liste...51c40f0700 Opal Transport clean up on termination 2014/04/02 12:48:59.500 2:07.225 Pool:0x7f51979bc700 SIP Handling PDU "4 SUBSCRIBE <200>" for transaction=z9hG4bK0c2eb910-c2b8-e311-88f5-5c260a60258d 2014/04/02 12:48:59.500 2:07.225 Opal Liste...51c40f0700 Opal Transport Close 2014/04/02 12:48:59.500 2:07.225 Pool:0x7f51979bc700 SIP SUBSCRIBE transaction id=z9hG4bK0c2eb910-c2b8-e311-88f5-5c260a60258d completing. 2014/04/02 12:48:59.500 2:07.225 Pool:0x7f51979bc700 SIP Finalising handlers interface "147.173.3.245%eth0" 2014/04/02 12:48:59.500 2:07.225 Pool:0x7f51979bc700 OpalUDP Setting interface to 147.173.3.245%eth0 2014/04/02 12:48:59.500 2:07.225 Pool:0x7f51979bc700 SIP Expiry time for SUBSCRIBE set to 0 seconds. 2014/04/02 12:48:59.500 2:07.225 Pool:0x7f51979bc700 SIP Route set is 2014/04/02 12:48:59.500 2:07.225 Opal Liste...51c40f0700 Opal Deleted transport udp$147.173.1.45:5060 2014/04/02 12:48:59.500 2:07.225 Pool:0x7f51979bc700 SIP SUBSCRIBE transaction id=z9hG4bK0c2eb910-c2b8-e311-88f5-5c260a60258d completed. 2014/04/02 12:48:59.500 2:07.225 Pool:0x7f51979bc700 SIP Handled PDU "4 SUBSCRIBE <200>" 2014/04/02 12:49:00.469 2:08.193 Opal Garba...51dbdef700 SIP Transaction id=z9hG4bK7c55b810-c2b8-e311-88f5-5c260a60258d destroyed. 2014/04/02 12:49:00.469 2:08.194 Opal Garba...51dbdef700 SIP Destroyed SUBSCRIBE handler for sip:umfodx xivo grenoble cnrs fr;OPAL-local-id=sip:umfodx%40xivo.grenoble.cnrs.fr 2014/04/02 12:49:00.470 2:08.195 Opal Garba...51dbdef700 Opal Transport clean up on termination 2014/04/02 12:49:00.470 2:08.195 Opal Garba...51dbdef700 Opal Transport Close 2014/04/02 12:49:00.470 2:08.195 Opal Garba...51dbdef700 Opal Transport clean up on termination 2014/04/02 12:49:00.470 2:08.195 Opal Garba...51dbdef700 Opal Transport Close 2014/04/02 12:49:00.470 2:08.195 Opal Garba...51dbdef700 Opal Deleted transport udp$147.173.1.45:5060 2014/04/02 12:49:00.471 2:08.195 Opal Garba...51dbdef700 SIP Destroyed PUBLISH handler for sip:umfodx xivo grenoble cnrs fr 2014/04/02 12:49:00.471 2:08.196 Opal Garba...51dbdef700 Opal Transport clean up on termination 2014/04/02 12:49:00.471 2:08.196 Opal Garba...51dbdef700 Opal Transport Close 2014/04/02 12:49:00.472 2:08.196 Opal Garba...51dbdef700 Opal Transport clean up on termination 2014/04/02 12:49:00.472 2:08.196 Opal Garba...51dbdef700 Opal Transport Close 2014/04/02 12:49:00.472 2:08.196 Opal Garba...51dbdef700 Opal Deleted transport udp$147.173.1.45:5060 2014/04/02 12:49:00.472 2:08.197 Opal Garba...51dbdef700 SIP Destroyed REGISTER handler for sip:umfodx xivo grenoble cnrs fr 2014/04/02 12:49:00.472 2:08.197 Opal Garba...51dbdef700 Opal Transport clean up on termination 2014/04/02 12:49:00.472 2:08.197 Opal Garba...51dbdef700 Opal Transport Close 2014/04/02 12:49:00.473 2:08.197 Opal Garba...51dbdef700 Opal Transport clean up on termination 2014/04/02 12:49:00.473 2:08.197 Opal Garba...51dbdef700 Opal Transport Close 2014/04/02 12:49:00.473 2:08.197 Opal Garba...51dbdef700 Opal Deleted transport udp$147.173.1.45:5060 2014/04/02 12:49:00.473 2:08.197 Opal Garba...51dbdef700 SIP Destroyed SUBSCRIBE handler for sip:umfodx xivo grenoble cnrs fr 2014/04/02 12:49:00.498 2:08.223 Housekeepe...51dbef3700 SIP Set state Terminated_Success for PUBLISH transaction id=z9hG4bKf03cb710-c2b8-e311-88f5-5c260a60258d 2014/04/02 12:49:00.498 2:08.223 Housekeepe...51dbef3700 SIP Completed state for transaction id=z9hG4bKf03cb710-c2b8-e311-88f5-5c260a60258d 2014/04/02 12:49:00.498 2:08.223 Housekeepe...51dbef3700 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bKdcebb710-c2b8-e311-88f5-5c260a60258d 2014/04/02 12:49:00.499 2:08.223 Housekeepe...51dbef3700 SIP Completed state for transaction id=z9hG4bKdcebb710-c2b8-e311-88f5-5c260a60258d 2014/04/02 12:49:00.499 2:08.223 Housekeepe...51dbef3700 SIP Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK707ab810-c2b8-e311-88f5-5c260a60258d 2014/04/02 12:49:00.499 2:08.223 Housekeepe...51dbef3700 SIP Completed state for transaction id=z9hG4bK707ab810-c2b8-e311-88f5-5c260a60258d 2014/04/02 12:49:00.524 2:08.249 Housekeepe...51dbef3700 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bK18e1b810-c2b8-e311-88f5-5c260a60258d 2014/04/02 12:49:00.524 2:08.249 Housekeepe...51dbef3700 SIP Completed state for transaction id=z9hG4bK18e1b810-c2b8-e311-88f5-5c260a60258d 2014/04/02 12:49:00.524 2:08.249 Housekeepe...51dbef3700 SIP Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK0c2eb910-c2b8-e311-88f5-5c260a60258d 2014/04/02 12:49:00.524 2:08.249 Housekeepe...51dbef3700 SIP Completed state for transaction id=z9hG4bK0c2eb910-c2b8-e311-88f5-5c260a60258d 2014/04/02 12:49:00.600 2:08.325 OpalEP sip endpoint shutting down. 2014/04/02 12:49:00.600 2:08.325 Listen Stopping listening thread on udp$*:5060 2014/04/02 12:49:00.600 2:08.325 MonSock Closed UDP socket 0x23defd0 2014/04/02 12:49:00.601 2:08.325 Opal Liste...51c40f0700 Listen UDP read error. 2014/04/02 12:49:00.601 2:08.325 Opal Liste...51c40f0700 PWLib File handle low water mark set: 32 PTextFile 2014/04/02 12:49:00.601 2:08.326 Opal Liste...51c40f0700 PTLib Thread ended: name="Opal Listener:0x7f51c40f0700", real=128.138, kernel=0.000 (0%), user=0.010 (0%), both=0.010 (0%) 2014/04/02 12:49:00.621 2:08.345 MonSock Deleting UDP socket 0x23defd0 2014/04/02 12:49:00.621 2:08.346 OpalEP h323 endpoint shutting down. 2014/04/02 12:49:00.621 2:08.346 Listen Stopping listening thread on tcp$*:1720 2014/04/02 12:49:00.622 2:08.346 Opal Liste...51c40af700 PWLib File handle low water mark set: 31 PTextFile 2014/04/02 12:49:00.622 2:08.346 Opal Liste...51c40af700 PTLib Thread ended: name="Opal Listener:0x7f51c40af700", real=128.158, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%) 2014/04/02 12:49:00.632 2:08.356 PCSS Deleted PC sound system endpoint. 2014/04/02 12:49:00.632 2:08.356 LocalEP Deleted endpoint. 2014/04/02 12:49:00.632 2:08.356 OpalEP pc endpoint destroyed. 2014/04/02 12:49:00.632 2:08.357 IfaceMon Awaiting thread termination 2014/04/02 12:49:00.632 2:08.357 Network In...51dbdae700 IfaceMon Finished interface monitor thread. 2014/04/02 12:49:00.633 2:08.357 Network In...51dbdae700 PTLib Thread ended: name="Network Interface Monitor:0x7f51dbdae700", real=128.171, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%) 2014/04/02 12:49:00.643 2:08.367 Pool:0x7f51979bc700 PWLib File handle low water mark set: 26 PTextFile 2014/04/02 12:49:00.643 2:08.368 Pool:0x7f51979bc700 PTLib Thread ended: name="Pool:0x7f51979bc700", real=128.119, kernel=0.000 (0%), user=0.010 (0%), both=0.010 (0%) 2014/04/02 12:49:00.653 2:08.377 ThreadPool Destroying pool thread 2014/04/02 12:49:00.653 2:08.378 Pool:0x7f51bc25a700 PTLib Thread ended: name="Pool:0x7f51bc25a700", real=1.158, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%) 2014/04/02 12:49:00.663 2:08.388 ThreadPool Destroying pool thread 2014/04/02 12:49:00.664 2:08.389 Pool:0x7f51800e9700 PTLib Thread ended: name="Pool:0x7f51800e9700", real=1.166, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%) 2014/04/02 12:49:00.674 2:08.398 ThreadPool Destroying pool thread 2014/04/02 12:49:00.675 2:08.399 SIP Transaction id=z9hG4bK707ab810-c2b8-e311-88f5-5c260a60258d destroyed. 2014/04/02 12:49:00.675 2:08.400 SIP Transaction id=z9hG4bK0c2eb910-c2b8-e311-88f5-5c260a60258d destroyed. 2014/04/02 12:49:00.676 2:08.400 SIP Transaction id=z9hG4bK18e1b810-c2b8-e311-88f5-5c260a60258d destroyed. 2014/04/02 12:49:00.676 2:08.401 SIP Transaction id=z9hG4bKf03cb710-c2b8-e311-88f5-5c260a60258d destroyed. 2014/04/02 12:49:00.677 2:08.401 SIP Transaction id=z9hG4bKdcebb710-c2b8-e311-88f5-5c260a60258d destroyed. 2014/04/02 12:49:00.677 2:08.402 OpalEP sip endpoint destroyed. 2014/04/02 12:49:00.677 2:08.402 OpalEP h323 endpoint destroyed. 2014/04/02 12:49:00.678 2:08.402 OpalMan Shutting down endpoints. 2014/04/02 12:49:00.678 2:08.402 OpalMan Clearing all calls and waiting, primary thread. 2014/04/02 12:49:01.474 2:09.198 OpalMan All calls cleared. 2014/04/02 12:49:01.474 2:09.199 Opal Garba...51dbdef700 PTLib Thread ended: name="Opal Garbage:0x7f51dbdef700", real=129.047, kernel=0.000 (0%), user=0.020 (0%), both=0.020 (0%) 2014/04/02 12:49:01.484 2:09.209 OpalMan Deleted manager. 2014/04/02 12:49:01.486 2:09.210 AudioEvent...51dbe71700 PWLib File handle low water mark set: 24 PTextFile 2014/04/02 12:49:01.486 2:09.211 VideoPrevi...51dbeb2700 PTLib Thread ended: name="VideoPreviewManager:0x7f51dbeb2700", real=129.140, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%) 2014/04/02 12:49:01.486 2:09.211 AudioEvent...51dbe71700 PTLib Thread ended: name="AudioEventScheduler:0x7f51dbe71700", real=129.140, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%) 2014/04/02 12:49:01.489 2:09.213 OpalPlugin Using default handler for plugin codec spandsp_ptplugin 2014/04/02 12:49:01.489 2:09.213 OpalPlugin Using default handler for plugin codec theora_ptplugin 2014/04/02 12:49:01.489 2:09.214 OpalPlugin Using default handler for plugin codec h261_vic_ptplugin 2014/04/02 12:49:01.489 2:09.214 OpalPlugin Using default handler for plugin codec mpeg4_ffmpeg_ptplugin 2014/04/02 12:49:01.489 2:09.214 OpalPlugin Using default handler for plugin codec h264_x264_ptplugin 2014/04/02 12:49:01.489 2:09.214 OpalPlugin Using default handler for plugin codec h263_ffmpeg_ptplugin 2014/04/02 12:49:01.490 2:09.214 OpalPlugin Using default handler for plugin codec ima_adpcm_ptplugin 2014/04/02 12:49:01.490 2:09.214 OpalPlugin Using default handler for plugin codec g7221_ptplugin 2014/04/02 12:49:01.490 2:09.214 OpalPlugin Using default handler for plugin codec lpc10_ptplugin 2014/04/02 12:49:01.490 2:09.214 OpalPlugin Using default handler for plugin codec silk_ptplugin 2014/04/02 12:49:01.490 2:09.215 OpalPlugin Using default handler for plugin codec g722_ptplugin 2014/04/02 12:49:01.490 2:09.215 OpalPlugin Using default handler for plugin codec gsmamrcodec_ptplugin 2014/04/02 12:49:01.490 2:09.215 OpalPlugin Using default handler for plugin codec g7222_ptplugin 2014/04/02 12:49:01.491 2:09.216 OpalPlugin Using default handler for plugin codec speex_ptplugin 2014/04/02 12:49:01.491 2:09.216 OpalPlugin Using default handler for plugin codec iLBC_ptplugin 2014/04/02 12:49:01.491 2:09.216 OpalPlugin Using default handler for plugin codec g726_ptplugin 2014/04/02 12:49:01.492 2:09.216 OpalPlugin Using default handler for plugin codec gsm0610_ptplugin 2014/04/02 12:49:01.492 2:09.216 UDLL Closing /usr/lib/opal-3.10.10/codecs/audio/gsm0610_ptplugin.so 2014/04/02 12:49:01.492 2:09.216 UDLL Closing /usr/lib/opal-3.10.10/codecs/audio/g726_ptplugin.so 2014/04/02 12:49:01.492 2:09.217 UDLL Closing /usr/lib/opal-3.10.10/codecs/audio/iLBC_ptplugin.so 2014/04/02 12:49:01.492 2:09.217 UDLL Closing /usr/lib/opal-3.10.10/codecs/audio/speex_ptplugin.so 2014/04/02 12:49:01.492 2:09.217 UDLL Closing /usr/lib/opal-3.10.10/codecs/audio/g7222_ptplugin.so 2014/04/02 12:49:01.492 2:09.217 UDLL Closing /usr/lib/opal-3.10.10/codecs/audio/gsmamrcodec_ptplugin.so 2014/04/02 12:49:01.493 2:09.217 UDLL Closing /usr/lib/opal-3.10.10/codecs/audio/g722_ptplugin.so 2014/04/02 12:49:01.493 2:09.217 UDLL Closing /usr/lib/opal-3.10.10/codecs/audio/silk_ptplugin.so 2014/04/02 12:49:01.493 2:09.217 UDLL Closing /usr/lib/opal-3.10.10/codecs/audio/lpc10_ptplugin.so 2014/04/02 12:49:01.493 2:09.218 UDLL Closing /usr/lib/opal-3.10.10/codecs/audio/g7221_ptplugin.so 2014/04/02 12:49:01.493 2:09.218 UDLL Closing /usr/lib/opal-3.10.10/codecs/audio/ima_adpcm_ptplugin.so 2014/04/02 12:49:01.493 2:09.218 UDLL Closing /usr/lib/opal-3.10.10/codecs/video/h263_ffmpeg_ptplugin.so 2014/04/02 12:49:01.493 2:09.218 UDLL Closing /usr/lib/opal-3.10.10/codecs/video/h264_x264_ptplugin.so 2014/04/02 12:49:01.494 2:09.218 UDLL Closing /usr/lib/opal-3.10.10/codecs/video/mpeg4_ffmpeg_ptplugin.so 2014/04/02 12:49:01.494 2:09.219 UDLL Closing /usr/lib/opal-3.10.10/codecs/video/h261_vic_ptplugin.so 2014/04/02 12:49:01.494 2:09.219 UDLL Closing /usr/lib/opal-3.10.10/codecs/video/theora_ptplugin.so 2014/04/02 12:49:01.494 2:09.219 UDLL Closing /usr/lib/opal-3.10.10/fax/spandsp_ptplugin.so 2014/04/02 12:49:01.494 2:09.219 UDLL Closing /usr/lib/ptlib-2.10.10/devices/sound/pulse_pwplugin.so 2014/04/02 12:49:01.495 2:09.219 UDLL Closing /usr/lib/ptlib-2.10.10/devices/sound/alsa_pwplugin.so 2014/04/02 12:49:01.495 2:09.219 UDLL Closing /usr/lib/ptlib-2.10.10/devices/sound/oss_pwplugin.so 2014/04/02 12:49:01.495 2:09.219 UDLL Closing /usr/lib/ptlib-2.10.10/devices/videoinput/v4l2_pwplugin.so 2014/04/02 12:49:02.523 2:10.247 GMVideoOut...51dbe30700 PWLib File handle low water mark set: 13 PTextFile 2014/04/02 12:49:02.523 2:10.247 GMVideoOut...51dbe30700 PTLib Thread ended: name="GMVideoOutputManager:0x7f51dbe30700", real=130.171, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%) 2014/04/02 12:49:02.540 2:10.264 Housekeepe...51dbef3700 PWLib File handle low water mark set: 9 PTextFile 2014/04/02 12:49:02.540 2:10.264 Housekeepe...51dbef3700 PTLib Thread ended: name="Housekeeper:0x7f51dbef3700", real=130.194, kernel=0.000 (0%), user=0.020 (0%), both=0.020 (0%)