Serviio

DLNA media server
https://forum.serviio.org/

Sony BDP-S590 can't play .MKV files (corrupt or unsupported)

https://forum.serviio.org/viewtopic.php?f=11&t=10320

Page 1 of 1

Sony BDP-S590 can't play .MKV files (corrupt or unsupported)

PostPosted: Wed May 01, 2013 2:47 pm
by dukilupp
I have downloaded a movie and can't play in Sony BDP-S590, please advise me, thanks so much.

I have also checked there: http://esupport.sony.com/US/perl/suppor ... nfo_id=959

My file is encoded with AVC, I don't know why it can't play.

  Code:
General
Unique ID                                : 192154076161364347389600328882598955456 (0x908F876766A67E5B9B13AFE0158BC9C0)
Complete name                            : F:\001.mkv
Format                                   : Matroska
Format version                           : Version 4 / Version 2
File size                                : 8.70 GiB
Duration                                 : 1h 15mn
Overall bit rate                         : 16.6 Mbps
Encoded date                             : UTC 2013-05-01 12:06:32
Writing application                      : mkvmerge v6.0.0 ('Coming Up For Air') built on Jan 20 2013 09:52:00
Writing library                          : libebml v1.3.0 + libmatroska v1.4.0

Video
ID                                       : 1
Format                                   : AVC
Format/Info                              : Advanced Video Codec
Format profile                           : High@L5.1
Format settings, CABAC                   : Yes
Format settings, ReFrames                : 2 frames
Codec ID                                 : V_MPEG4/ISO/AVC
Duration                                 : 1h 15mn
Bit rate                                 : 14.8 Mbps
Width                                    : 1 920 pixels
Height                                   : 1 080 pixels
Display aspect ratio                     : 16:9
Frame rate mode                          : Constant
Frame rate                               : 23.976 fps
Color space                              : YUV
Chroma subsampling                       : 4:2:0
Bit depth                                : 8 bits
Scan type                                : Progressive
Bits/(Pixel*Frame)                       : 0.297
Stream size                              : 7.74 GiB (89%)
Writing library                          : x264 core
Language                                 : English
Default                                  : Yes
Forced                                   : No
Color primaries                          : BT.709
Transfer characteristics                 : BT.709
Matrix coefficients                      : BT.709

Audio
ID                                       : 2
Format                                   : DTS
Format/Info                              : Digital Theater Systems
Mode                                     : 16
Format settings, Endianness              : Big
Codec ID                                 : A_DTS
Duration                                 : 1h 15mn
Bit rate mode                            : Constant
Bit rate                                 : 1 509 Kbps
Channel(s)                               : 6 channels
Channel positions                        : Front: L C R, Side: L R, LFE
Sampling rate                            : 48.0 KHz
Bit depth                                : 24 bits
Compression mode                         : Lossy
Stream size                              : 810 MiB (9%)
Language                                 : English
Default                                  : Yes
Forced                                   : No

Text #1
ID                                       : 3
Format                                   : UTF-8
Codec ID                                 : S_TEXT/UTF8
Codec ID/Info                            : UTF-8 Plain Text
Title                                    : English
Language                                 : English
Default                                  : Yes
Forced                                   : No

Text #2
ID                                       : 4
Format                                   : VobSub
Muxing mode                              : zlib
Codec ID                                 : S_VOBSUB
Codec ID/Info                            : The same subtitle format used on DVDs
Title                                    : English -SDH
Language                                 : English
Default                                  : No
Forced                                   : No

Text #3
ID                                       : 5
Format                                   : UTF-8
Codec ID                                 : S_TEXT/UTF8
Codec ID/Info                            : UTF-8 Plain Text
Title                                    : English - Forced
Language                                 : English
Default                                  : No
Forced                                   : No

Text #4
ID                                       : 6
Format                                   : VobSub
Muxing mode                              : zlib
Codec ID                                 : S_VOBSUB
Codec ID/Info                            : The same subtitle format used on DVDs
Language                                 : Russian
Default                                  : No
Forced                                   : No

Text #5
ID                                       : 7
Format                                   : VobSub
Muxing mode                              : zlib
Codec ID                                 : S_VOBSUB
Codec ID/Info                            : The same subtitle format used on DVDs
Language                                 : Ukrainian
Default                                  : No
Forced                                   : No

Text #6
ID                                       : 8
Format                                   : VobSub
Muxing mode                              : zlib
Codec ID                                 : S_VOBSUB
Codec ID/Info                            : The same subtitle format used on DVDs
Language                                 : Swedish
Default                                  : No
Forced                                   : No

Text #7
ID                                       : 9
Format                                   : VobSub
Muxing mode                              : zlib
Codec ID                                 : S_VOBSUB
Codec ID/Info                            : The same subtitle format used on DVDs
Language                                 : Danish
Default                                  : No
Forced                                   : No

Text #8
ID                                       : 10
Format                                   : VobSub
Muxing mode                              : zlib
Codec ID                                 : S_VOBSUB
Codec ID/Info                            : The same subtitle format used on DVDs
Language                                 : Finnish
Default                                  : No
Forced                                   : No

Text #9
ID                                       : 11
Format                                   : VobSub
Muxing mode                              : zlib
Codec ID                                 : S_VOBSUB
Codec ID/Info                            : The same subtitle format used on DVDs
Language                                 : Icelandic
Default                                  : No
Forced                                   : No

Text #10
ID                                       : 12
Format                                   : VobSub
Muxing mode                              : zlib
Codec ID                                 : S_VOBSUB
Codec ID/Info                            : The same subtitle format used on DVDs
Language                                 : Greek
Default                                  : No
Forced                                   : No

Text #11
ID                                       : 13
Format                                   : VobSub
Muxing mode                              : zlib
Codec ID                                 : S_VOBSUB
Codec ID/Info                            : The same subtitle format used on DVDs
Language                                 : Bulgarian
Default                                  : No
Forced                                   : No

Text #12
ID                                       : 14
Format                                   : VobSub
Muxing mode                              : zlib
Codec ID                                 : S_VOBSUB
Codec ID/Info                            : The same subtitle format used on DVDs
Language                                 : Latvian
Default                                  : No
Forced                                   : No

Text #13
ID                                       : 15
Format                                   : VobSub
Muxing mode                              : zlib
Codec ID                                 : S_VOBSUB
Codec ID/Info                            : The same subtitle format used on DVDs
Language                                 : Lithuanian
Default                                  : No
Forced                                   : No

Text #14
ID                                       : 16
Format                                   : VobSub
Muxing mode                              : zlib
Codec ID                                 : S_VOBSUB
Codec ID/Info                            : The same subtitle format used on DVDs
Language                                 : Estonian
Default                                  : No
Forced                                   : No

Text #15
ID                                       : 17
Format                                   : UTF-8
Codec ID                                 : S_TEXT/UTF8
Codec ID/Info                            : UTF-8 Plain Text
Language                                 : Dutch
Default                                  : No
Forced                                   : No

Text #16
ID                                       : 18
Format                                   : UTF-8
Codec ID                                 : S_TEXT/UTF8
Codec ID/Info                            : UTF-8 Plain Text
Language                                 : Romanian
Default                                  : No
Forced                                   : No

Text #17
ID                                       : 19
Format                                   : UTF-8
Codec ID                                 : S_TEXT/UTF8
Codec ID/Info                            : UTF-8 Plain Text
Default                                  : No
Forced                                   : No

Menu
00:00:00.000                             : en:00:00:00.000
00:08:02.315                             : en:00:08:02.315
00:11:49.167                             : en:00:11:49.167
00:15:18.751                             : en:00:15:18.751
00:20:33.482                             : en:00:20:33.482
00:23:04.258                             : en:00:23:04.258
00:29:39.444                             : en:00:29:39.444
00:37:23.992                             : en:00:37:23.992
00:42:47.982                             : en:00:42:47.982
00:50:22.269                             : en:00:50:22.269
01:01:04.661                             : en:01:01:04.661
01:14:05.107                             : en:01:14:05.107

Re: Sony BDP-S590 can't play .MKV files (corrupt or unsuppor

PostPosted: Wed May 01, 2013 5:09 pm
by slyguy42o
can you post your logs after trying to play the file?

Re: Sony BDP-S590 can't play .MKV files (corrupt or unsuppor

PostPosted: Thu May 02, 2013 10:07 am
by Illico
Your file should be transcoded to mpeg2video (h264 profile level >4.1).
Could you enable detailed logging and post log here ?

Re: Sony BDP-S590 can't play .MKV files (corrupt or unsuppor

PostPosted: Thu May 02, 2013 12:39 pm
by dukilupp
slyguy42o wrote:can you post your logs after trying to play the file?


Hi, I can run the .mkv file in computer.

When I play it in S590, it shows me that the file is corrupt or unsupported.

Re: Sony BDP-S590 can't play .MKV files (corrupt or unsuppor

PostPosted: Thu May 02, 2013 12:40 pm
by dukilupp
Illico wrote:Your file should be transcode to mpeg2video (h264 profile level >4.1).
Could you enable detailed logging and post log here ?


How to enable detailed logging? Any software to do so? Where can I find log4j.xml? Thanks for your help.

Re: Sony BDP-S590 can't play .MKV files (corrupt or unsuppor

PostPosted: Thu May 02, 2013 2:50 pm
by Illico
What is your OS (Windows/MAC/Linux)?

Re: Sony BDP-S590 can't play .MKV files (corrupt or unsuppor

PostPosted: Thu May 02, 2013 3:05 pm
by dukilupp
Illico wrote:What is your OS (Windows/MAC/Linux)?


Windows XP

Re: Sony BDP-S590 can't play .MKV files (corrupt or unsuppor

PostPosted: Thu May 02, 2013 4:52 pm
by Illico
So read carefully this thread : viewtopic.php?f=5&t=7
And look at C:\Program Files\Serviio\config\ path

Re: Sony BDP-S590 can't play .MKV files (corrupt or unsuppor

PostPosted: Fri May 03, 2013 6:39 am
by dukilupp
Illico wrote:So read carefully this thread : viewtopic.php?f=5&t=7
And look at C:\Program Files\Serviio\config\ path


Hi, I have downloaded Serviio...

I have read the thread.

So, you want me to start Serviio and start Windows Media Player Network Sharing Service?

I can play the .mkv in the computer.

But how to read the log?

Thanks so much!

Re: Sony BDP-S590 can't play .MKV files (corrupt or unsuppor

PostPosted: Fri May 03, 2013 7:24 am
by dukilupp
Hi, I have read http://wiki.serviio.org/doku.php?id=howto_win_ffmpeg

Am I correct?

  Code:
C:\Program Files\Serviio\lib>ffmpeg.exe -i "F:\001.mkv"
ffmpeg version 1.1.1 Copyright (c) 2000-2013 the FFmpeg developers
  built on Feb  5 2013 21:59:54 with gcc 4.7.2 (GCC)
  configuration: --enable-static --disable-shared --disable-ffplay --disable-ffs
erver --enable-memalign-hack --enable-libmp3lame --enable-libass --enable-librtm
p --enable-fontconfig --enable-libfreetype --enable-zlib --extra-libs='-lrtmp -l
polarssl -lws2_32 -lwinmm -lexpat -lfreetype -lfribidi -lz' --arch=x86 --enable-
runtime-cpudetect --enable-pthreads --target-os=mingw32 --cross-prefix=i686-w64-
mingw32- --pkg-config=pkg-config
  libavutil      52. 13.100 / 52. 13.100
  libavcodec     54. 86.100 / 54. 86.100
  libavformat    54. 59.106 / 54. 59.106
  libavdevice    54.  3.102 / 54.  3.102
  libavfilter     3. 32.100 /  3. 32.100
  libswscale      2.  1.103 /  2.  1.103
  libswresample   0. 17.102 /  0. 17.102
Input #0, matroska,webm, from 'F:\001.mkv':
  Metadata:
    creation_time   : 2013-05-01 12:06:32
  Duration: 01:15:00.37, start: 0.000000, bitrate: 16608 kb/s
    Chapter #0.0: start 0.000000, end 482.315000
    Metadata:
      title           : 00:00:00.000
    Chapter #0.1: start 482.315000, end 709.167000
    Metadata:
      title           : 00:08:02.315
    Chapter #0.2: start 709.167000, end 918.751000
    Metadata:
      title           : 00:11:49.167
    Chapter #0.3: start 918.751000, end 1233.482000
    Metadata:
      title           : 00:15:18.751
    Chapter #0.4: start 1233.482000, end 1384.258000
    Metadata:
      title           : 00:20:33.482
    Chapter #0.5: start 1384.258000, end 1779.444000
    Metadata:
      title           : 00:23:04.258
    Chapter #0.6: start 1779.444000, end 2243.992000
    Metadata:
      title           : 00:29:39.444
    Chapter #0.7: start 2243.992000, end 2567.982000
    Metadata:
      title           : 00:37:23.992
    Chapter #0.8: start 2567.982000, end 3022.269000
    Metadata:
      title           : 00:42:47.982
    Chapter #0.9: start 3022.269000, end 3664.661000
    Metadata:
      title           : 00:50:22.269
    Chapter #0.10: start 3664.661000, end 4445.107000
    Metadata:
      title           : 01:01:04.661
    Chapter #0.11: start 4445.107000, end 4500.374000
    Metadata:
      title           : 01:14:05.107
    Stream #0:0(eng): Video: h264 (High), yuv420p, 1920x1080, SAR 1:1 DAR 16:9,
23.98 fps, 23.98 tbr, 1k tbn, 47.95 tbc (default)
    Stream #0:1(eng): Audio: dts (DTS), 48000 Hz, 5.1(side), fltp, 1536 kb/s (de
fault)
    Stream #0:2(eng): Subtitle: subrip (default)
    Metadata:
      title           : English
    Stream #0:3(eng): Subtitle: dvd_subtitle
    Metadata:
      title           : English -SDH
    Stream #0:4(eng): Subtitle: subrip
    Metadata:
      title           : English - Forced
    Stream #0:5(rus): Subtitle: dvd_subtitle
    Stream #0:6(ukr): Subtitle: dvd_subtitle
    Stream #0:7(swe): Subtitle: dvd_subtitle
    Stream #0:8(dan): Subtitle: dvd_subtitle
    Stream #0:9(fin): Subtitle: dvd_subtitle
    Stream #0:10(ice): Subtitle: dvd_subtitle
    Stream #0:11(gre): Subtitle: dvd_subtitle
    Stream #0:12(bul): Subtitle: dvd_subtitle
    Stream #0:13(lav): Subtitle: dvd_subtitle
    Stream #0:14(lit): Subtitle: dvd_subtitle
    Stream #0:15(est): Subtitle: dvd_subtitle
    Stream #0:16(dut): Subtitle: subrip
    Stream #0:17(rum): Subtitle: subrip
    Stream #0:18: Subtitle: subrip
At least one output file must be specified

C:\Program Files\Serviio\lib>

Re: Sony BDP-S590 can't play .MKV files (corrupt or unsuppor

PostPosted: Fri May 03, 2013 7:40 am
by Illico
Post here : C:\Program Files\Serviio\log\serviio.log

Re: Sony BDP-S590 can't play .MKV files (corrupt or unsuppor

PostPosted: Fri May 03, 2013 8:09 am
by dukilupp
Illico wrote:Post here : C:\Program Files\Serviio\log\serviio.log


Please correct me if I have posted wrong code, thanks.

  Code:
2013-05-03 15:35:18,750 INFO  [LibraryAdditionsCheckerThread] Added file '001.mkv' (title: Cheena Thaana 001) to Library
2013-05-03 15:35:20,468 INFO  [LibraryAdditionsCheckerThread] Added file '002.mkv' (title: Niceland (Population. 1.000.002)) to Library
2013-05-03 15:37:40,968 INFO  [LibraryAdditionsCheckerThread] Finished looking for newly added files
2013-05-03 15:37:41,062 INFO  [LibraryUpdatesCheckerThread] Finished looking for updates to currently shared files
2013-05-03 15:37:41,171 INFO  [PlaylistMaintainerThread] Finished looking for playlist changes
2013-05-03 15:37:41,343 INFO  [LibraryAdditionsCheckerThread] Started looking for newly added files
2013-05-03 15:37:41,343 INFO  [LibraryUpdatesCheckerThread] Started looking for updates to currently shared files
2013-05-03 15:37:41,343 INFO  [PlaylistMaintainerThread] Started looking playlist changes
2013-05-03 15:39:51,515 WARN  [ProcessExecutor] Process cmd has a return code of 1! This is a possible error. Detailed output follows.
st:0 PTS: 105563940 DTS: 105563940 < 105565816 invalid, clipping
[mpegts @ 01C6A020] st:0 PTS: 105613890 DTS: 105613890 < 105617746 invalid, clipping
[mpegts @ 01C6A020] st:0 PTS: 105616725 DTS: 105616725 < 105617747 invalid, clipping
frame=28146 fps=114 q=-1.0 size= 2374724kB time=00:19:33.91 bitrate=16571.7kbits/s
av_interleaved_write_frame(): No space left on device
2013-05-03 15:39:54,546 ERROR [ResourceTransportRequestHandler] Error while processing resource, sending back 500 error. Message: FFmpeg execution failed
java.io.IOException: FFmpeg execution failed
   at org.serviio.delivery.resource.transcode.FileBasedTranscodingDeliveryStrategy.createInputStream(FileBasedTranscodingDeliveryStrategy.java:47)
   at org.serviio.delivery.resource.transcode.AbstractTranscodingDeliveryEngine.retrieveTranscodedResource(AbstractTranscodingDeliveryEngine.java:131)
   at org.serviio.delivery.resource.AbstractDeliveryEngine.deliver(AbstractDeliveryEngine.java:108)
   at org.serviio.delivery.MediaResourceRetrievalStrategy.retrieveResource(MediaResourceRetrievalStrategy.java:120)
   at org.serviio.delivery.GETMethodProcessor.retrieveResource(GETMethodProcessor.java:120)
   at org.serviio.delivery.GETMethodProcessor.buildDeliveryContainer(GETMethodProcessor.java:48)
   at org.serviio.delivery.AbstractMethodProcessor.handleRequest(AbstractMethodProcessor.java:100)
   at org.serviio.delivery.ResourceDeliveryProcessor.deliverContent(ResourceDeliveryProcessor.java:80)
   at org.serviio.upnp.webserver.ResourceTransportRequestHandler.handleRequest(ResourceTransportRequestHandler.java:85)
   at org.serviio.upnp.webserver.AbstractRequestHandler.handle(AbstractRequestHandler.java:61)
   at org.apache.http.protocol.HttpService.doService(HttpService.java:293)
   at org.serviio.upnp.webserver.ServiioHttpService.doService(ServiioHttpService.java:77)
   at org.apache.http.protocol.HttpService.handleRequest(HttpService.java:212)
   at org.serviio.upnp.webserver.WebServer$WorkerThread.run(WebServer.java:192)
2013-05-03 15:39:54,562 ERROR [ResourceTransportRequestHandler] Error while processing resource, sending back 500 error. Message: FFmpeg execution failed
java.io.IOException: FFmpeg execution failed
   at org.serviio.delivery.resource.transcode.FileBasedTranscodingDeliveryStrategy.createInputStream(FileBasedTranscodingDeliveryStrategy.java:47)
   at org.serviio.delivery.resource.transcode.AbstractTranscodingDeliveryEngine.retrieveTranscodedResource(AbstractTranscodingDeliveryEngine.java:131)
   at org.serviio.delivery.resource.AbstractDeliveryEngine.deliver(AbstractDeliveryEngine.java:108)
   at org.serviio.delivery.MediaResourceRetrievalStrategy.retrieveResource(MediaResourceRetrievalStrategy.java:120)
   at org.serviio.delivery.GETMethodProcessor.retrieveResource(GETMethodProcessor.java:120)
   at org.serviio.delivery.GETMethodProcessor.buildDeliveryContainer(GETMethodProcessor.java:48)
   at org.serviio.delivery.AbstractMethodProcessor.handleRequest(AbstractMethodProcessor.java:100)
   at org.serviio.delivery.ResourceDeliveryProcessor.deliverContent(ResourceDeliveryProcessor.java:80)
   at org.serviio.upnp.webserver.ResourceTransportRequestHandler.handleRequest(ResourceTransportRequestHandler.java:85)
   at org.serviio.upnp.webserver.AbstractRequestHandler.handle(AbstractRequestHandler.java:61)
   at org.apache.http.protocol.HttpService.doService(HttpService.java:293)
   at org.serviio.upnp.webserver.ServiioHttpService.doService(ServiioHttpService.java:77)
   at org.apache.http.protocol.HttpService.handleRequest(HttpService.java:212)
   at org.serviio.upnp.webserver.WebServer$WorkerThread.run(WebServer.java:192)
2013-05-03 15:41:06,031 INFO  [LibraryManager] Forcing metadata update for 'VIDEO' media files
2013-05-03 15:41:14,484 INFO  [LibraryAdditionsCheckerThread] Finished looking for newly added files
2013-05-03 15:41:14,578 INFO  [LibraryUpdatesCheckerThread] Finished looking for updates to currently shared files
2013-05-03 15:41:14,687 INFO  [PlaylistMaintainerThread] Finished looking for playlist changes
2013-05-03 15:41:14,875 INFO  [LibraryAdditionsCheckerThread] Started looking for newly added files

Re: Sony BDP-S590 can't play .MKV files (corrupt or unsuppor

PostPosted: Sat May 04, 2013 1:29 pm
by dukilupp
I have used this method to get the log: viewtopic.php?f=5&t=7

  Code:
2013-05-04 21:37:26,906 DEBUG [ApplicationInstanceManager] Listening for application instances on socket 44331
2013-05-04 21:37:26,937 INFO  [MediaServer] ------------------------------------------------------------------------
2013-05-04 21:37:26,937 INFO  [MediaServer] Serviio DLNA media streaming server v 1.2.1 (rev. a2aa56064460+)
2013-05-04 21:37:26,937 INFO  [MediaServer] Petr Nejedly 2009-2013
2013-05-04 21:37:26,937 INFO  [MediaServer] http://www.serviio.org
2013-05-04 21:37:26,937 INFO  [MediaServer]
2013-05-04 21:37:26,937 INFO  [MediaServer] Java 1.7.0_15-Oracle Corporation
2013-05-04 21:37:26,937 INFO  [MediaServer] OS Windows XP x86 5.1
2013-05-04 21:37:26,937 INFO  [MediaServer] File encoding: x-windows-950
2013-05-04 21:37:26,937 INFO  [MediaServer] Headless mode enabled: true
2013-05-04 21:37:26,937 INFO  [MediaServer] User: SYSTEM
2013-05-04 21:37:26,937 INFO  [MediaServer] User home dir: C:\
2013-05-04 21:37:26,937 INFO  [MediaServer] Temp dir: C:\WINDOWS\TEMP\
2013-05-04 21:37:26,937 INFO  [MediaServer] ------------------------------------------------------------------------
2013-05-04 21:37:27,093 INFO  [WebServer] Socket buffer set to 65535 bytes
2013-05-04 21:37:27,093 INFO  [RestletServer] Starting Restlet server (/rest) exposed on port 23423
2013-05-04 21:37:27,156 INFO  [RestletServer] Starting Restlet server (/cds) exposed on port 23424
2013-05-04 21:37:27,156 INFO  [RestletServer] Starting Restlet server (/mediabrowser) exposed on port 23424
2013-05-04 21:37:27,171 INFO  [DBSchemaUpdateExecutor] Checking if DB schema needs to be updated
2013-05-04 21:37:27,187 DEBUG [DBLogDAOImpl] Checking if script 'script-0.1.sql' has been run
2013-05-04 21:37:28,468 DEBUG [DBLogDAOImpl] Checking if script 'script-0.1.1.sql' has been run
2013-05-04 21:37:28,468 DEBUG [DBLogDAOImpl] Checking if script 'script-0.2.sql' has been run
2013-05-04 21:37:28,468 DEBUG [DBLogDAOImpl] Checking if script 'script-0.3.1.sql' has been run
2013-05-04 21:37:28,468 DEBUG [DBLogDAOImpl] Checking if script 'script-0.4.sql' has been run
2013-05-04 21:37:28,468 DEBUG [DBLogDAOImpl] Checking if script 'script-0.4.1.sql' has been run
2013-05-04 21:37:28,468 DEBUG [DBLogDAOImpl] Checking if script 'script-0.4.2.sql' has been run
2013-05-04 21:37:28,468 DEBUG [DBLogDAOImpl] Checking if script 'script-0.5.sql' has been run
2013-05-04 21:37:28,468 DEBUG [DBLogDAOImpl] Checking if script 'script-0.5.1.sql' has been run
2013-05-04 21:37:28,468 DEBUG [DBLogDAOImpl] Checking if script 'script-0.6.sql' has been run
2013-05-04 21:37:28,468 DEBUG [DBLogDAOImpl] Checking if script 'script-0.6.1.sql' has been run
2013-05-04 21:37:28,468 DEBUG [DBLogDAOImpl] Checking if script 'script-0.6.2.sql' has been run
2013-05-04 21:37:28,468 DEBUG [DBLogDAOImpl] Checking if script 'script-1.0.sql' has been run
2013-05-04 21:37:28,484 DEBUG [DBLogDAOImpl] Checking if script 'script-1.1.sql' has been run
2013-05-04 21:37:28,484 DEBUG [DBLogDAOImpl] Checking if script 'script-1.2.sql' has been run
2013-05-04 21:37:28,484 DEBUG [DBLogDAOImpl] Checking if script 'script-1.2.1.sql' has been run
2013-05-04 21:37:28,500 DEBUG [ConfigEntryDAOImpl] Reading all ConfigEntries
2013-05-04 21:37:28,515 DEBUG [LicenseValidator] Validating license file (352 bytes)
2013-05-04 21:37:29,000 INFO  [MediaServer] ------------------------------------------------------------------------
2013-05-04 21:37:29,000 INFO  [MediaServer] License: PRO (EVALUATION, id: 11111111-1111-1111-1111-111111111113)
2013-05-04 21:37:29,000 INFO  [MediaServer] ------------------------------------------------------------------------
2013-05-04 21:37:29,000 DEBUG [LocalAddressResolverStrategy] Resolving host IP address automatically
2013-05-04 21:37:29,234 INFO  [Device] Created UPnP Device with UUID: 057e814f-d039-3103-87b8-24793c5a6859, bound address: 192.168.1.121
2013-05-04 21:37:29,656 INFO  [ProfilesDefinitionParser] Parsing Profiles definition
2013-05-04 21:37:29,656 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 1
2013-05-04 21:37:29,734 INFO  [ProfilesDefinitionParser] Added profile 'Generic DLNA profile' (id=1)
2013-05-04 21:37:29,734 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 2
2013-05-04 21:37:29,734 INFO  [ProfilesDefinitionParser] Added profile 'Samsung TV (B-series)' (id=2)
2013-05-04 21:37:29,734 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 3
2013-05-04 21:37:29,734 INFO  [ProfilesDefinitionParser] Added profile 'Xbox 360' (id=3)
2013-05-04 21:37:29,734 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 4
2013-05-04 21:37:29,734 INFO  [ProfilesDefinitionParser] Added profile 'Playstation 3' (id=4)
2013-05-04 21:37:29,734 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 5
2013-05-04 21:37:29,750 INFO  [ProfilesDefinitionParser] Added profile 'Samsung TV (A-series)' (id=5)
2013-05-04 21:37:29,750 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 6
2013-05-04 21:37:29,765 INFO  [ProfilesDefinitionParser] Added profile 'DirecTV HD-DVR' (id=6)
2013-05-04 21:37:29,765 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 7
2013-05-04 21:37:29,765 INFO  [ProfilesDefinitionParser] Added profile 'Samsung TV / player (C/D/E-series)' (id=7)
2013-05-04 21:37:29,765 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 8
2013-05-04 21:37:29,781 INFO  [ProfilesDefinitionParser] Added profile 'LG TV / player' (id=8)
2013-05-04 21:37:29,781 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile sony2012
2013-05-04 21:37:29,781 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2012)' (id=sony2012)
2013-05-04 21:37:29,781 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile bdp2012
2013-05-04 21:37:29,781 INFO  [ProfilesDefinitionParser] Added profile 'Sony BDP-Sx90 (2012)' (id=bdp2012)
2013-05-04 21:37:29,781 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile bdp2010
2013-05-04 21:37:29,781 INFO  [ProfilesDefinitionParser] Added profile 'Sony BDP-Sx70/SMP-N100 (2010)' (id=bdp2010)
2013-05-04 21:37:29,781 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile bdp2010us
2013-05-04 21:37:29,781 INFO  [ProfilesDefinitionParser] Added profile 'Sony BDP-Sx70/SMP-N100 (2010) - US' (id=bdp2010us)
2013-05-04 21:37:29,781 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile bdp2011
2013-05-04 21:37:29,781 INFO  [ProfilesDefinitionParser] Added profile 'Sony BDP-Sx80/SMP-N200 (2011)' (id=bdp2011)
2013-05-04 21:37:29,781 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 13
2013-05-04 21:37:29,781 INFO  [ProfilesDefinitionParser] Added profile 'Toshiba REGZA' (id=13)
2013-05-04 21:37:29,781 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 14
2013-05-04 21:37:29,781 INFO  [ProfilesDefinitionParser] Added profile 'WDTV Live' (id=14)
2013-05-04 21:37:29,781 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 15
2013-05-04 21:37:29,781 INFO  [ProfilesDefinitionParser] Added profile 'LaCie LaCinema Media Player' (id=15)
2013-05-04 21:37:29,781 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 16
2013-05-04 21:37:29,781 INFO  [ProfilesDefinitionParser] Added profile 'Manta WIFI Centre' (id=16)
2013-05-04 21:37:29,781 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 17
2013-05-04 21:37:29,781 INFO  [ProfilesDefinitionParser] Added profile 'Pure Flow' (id=17)
2013-05-04 21:37:29,781 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 18
2013-05-04 21:37:29,781 INFO  [ProfilesDefinitionParser] Added profile 'Sharp Aquos' (id=18)
2013-05-04 21:37:29,781 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile sony2011
2013-05-04 21:37:29,781 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2011)' (id=sony2011)
2013-05-04 21:37:29,781 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile sony2010
2013-05-04 21:37:29,781 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2010)' (id=sony2010)
2013-05-04 21:37:29,781 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile plps2010
2013-05-04 21:37:29,781 INFO  [ProfilesDefinitionParser] Added profile 'Philips (2010-)' (id=plps2010)
2013-05-04 21:37:29,781 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 20
2013-05-04 21:37:29,781 INFO  [ProfilesDefinitionParser] Added profile 'Philips (2009)' (id=20)
2013-05-04 21:37:29,781 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile sony2009
2013-05-04 21:37:29,781 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2009)' (id=sony2009)
2013-05-04 21:37:29,781 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 22
2013-05-04 21:37:29,781 INFO  [ProfilesDefinitionParser] Added profile 'Denon AVR' (id=22)
2013-05-04 21:37:29,781 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile viera2011a
2013-05-04 21:37:29,781 INFO  [ProfilesDefinitionParser] Added profile 'Panasonic Viera G/GT/DT/UT/VT (2011/2012)' (id=viera2011a)
2013-05-04 21:37:29,781 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile viera2011
2013-05-04 21:37:29,781 INFO  [ProfilesDefinitionParser] Added profile 'Panasonic Viera E/S/ST/VT (2011)' (id=viera2011)
2013-05-04 21:37:29,781 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile viera2010
2013-05-04 21:37:29,781 INFO  [ProfilesDefinitionParser] Added profile 'Panasonic Viera (2010)' (id=viera2010)
2013-05-04 21:37:29,781 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile sony2012us
2013-05-04 21:37:29,781 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2012) - US' (id=sony2012us)
2013-05-04 21:37:29,781 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile sony2011us
2013-05-04 21:37:29,781 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2011) - US' (id=sony2011us)
2013-05-04 21:37:29,781 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile SonySTR
2013-05-04 21:37:29,781 INFO  [ProfilesDefinitionParser] Added profile 'Sony AVReceiver STR' (id=SonySTR)
2013-05-04 21:37:29,781 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile SonyCMT
2013-05-04 21:37:29,781 INFO  [ProfilesDefinitionParser] Added profile 'Sony Audio CMT' (id=SonyCMT)
2013-05-04 21:37:29,781 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile viziotv
2013-05-04 21:37:29,781 INFO  [ProfilesDefinitionParser] Added profile 'Vizio TV' (id=viziotv)
2013-05-04 21:37:29,781 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile win8metro
2013-05-04 21:37:29,781 INFO  [ProfilesDefinitionParser] Added profile 'Windows 8/RT' (id=win8metro)
2013-05-04 21:37:29,812 INFO  [ProfilesDefinitionParser] Parsing Profiles definition
2013-05-04 21:37:29,812 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile flv_player
2013-05-04 21:37:29,812 INFO  [ProfilesDefinitionParser] Added profile 'Flash player' (id=flv_player)
2013-05-04 21:37:29,812 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile serviigo
2013-05-04 21:37:29,812 INFO  [ProfilesDefinitionParser] Added profile 'ServiiGo' (id=serviigo)
2013-05-04 21:37:29,812 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile wp8
2013-05-04 21:37:29,812 INFO  [ProfilesDefinitionParser] Added profile 'Windows Phone 8' (id=wp8)
2013-05-04 21:37:29,843 INFO  [PluginCompilerThread] Looking for plugins at C:\Program Files\Serviio\bin\..\plugins
2013-05-04 21:37:29,843 INFO  [PluginCompilerThread] Started looking for plugins
2013-05-04 21:37:29,843 INFO  [UpdateChecker] Checking if a new version is available
2013-05-04 21:37:29,859 DEBUG [FFMPEGWrapper] Invoking FFMPEG to check if it exists of path C:\Program Files\Serviio\bin\\..\lib/ffmpeg.exe
2013-05-04 21:37:29,859 DEBUG [ProcessExecutor] Starting C:\Program Files\Serviio\bin\\..\lib/ffmpeg.exe
2013-05-04 21:37:30,281 DEBUG [DCRawWrapper] Invoking DCRAW to check if it exists of path C:\Program Files\Serviio\bin\\..\lib/dcraw.exe
2013-05-04 21:37:30,281 DEBUG [ProcessExecutor] Starting C:\Program Files\Serviio\bin\\..\lib/dcraw.exe
2013-05-04 21:37:30,406 INFO  [AbstractTranscodingDeliveryEngine] Cleaning transcode engine and its data
2013-05-04 21:37:30,406 DEBUG [AbstractTranscodingDeliveryEngine] Deleting temporary transcoded files from: C:\WINDOWS\TEMP\Serviio
2013-05-04 21:37:30,437 INFO  [WebServer] WebServer starting on port 8895
2013-05-04 21:37:30,453 DEBUG [DiscoveryManager] UPNP device 057e814f-d039-3103-87b8-24793c5a6859 (192.168.1.121) is available
2013-05-04 21:37:30,453 INFO  [EventSubscriptionExpirationChecker] Starting EventSubscriptionExpirationChecker
2013-05-04 21:37:30,453 INFO  [DiscoveryAdvertisementNotifier] Starting DiscoveryAdvertisementNotifier
2013-05-04 21:37:30,453 INFO  [EventDispatcher] Starting EventDispatcher
2013-05-04 21:37:30,453 INFO  [DiscoverySSDPMessageListener] Starting DiscoverySSDPMessageListener using interface eth0 (Atheros AR8131 PCI-E Gigabit Ethernet Controller - Packet Scheduler Miniport) and address 192.168.1.121, timeout = 0
2013-05-04 21:37:30,453 INFO  [RendererExpirationChecker] Starting RendererExpirationChecker
2013-05-04 21:37:30,453 INFO  [RendererSearchSender] Searching for Renderer devices
2013-05-04 21:37:30,453 DEBUG [RendererSearchSender$RendererSearchWorker] Multicasting SSDP M-SEARCH using interface eth0 (Atheros AR8131 PCI-E Gigabit Ethernet Controller - Packet Scheduler Miniport) and address 192.168.1.121, timeout = 0
2013-05-04 21:37:30,484 DEBUG [RendererSearchSender$RendererSearchWorker] Sending 1 'm-search' messages
2013-05-04 21:37:30,484 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /192.168.1.121:2147s
2013-05-04 21:37:30,484 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /192.168.1.121:2147s
2013-05-04 21:37:30,484 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /192.168.1.121:2147s
2013-05-04 21:37:30,531 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth0 (Atheros AR8131 PCI-E Gigabit Ethernet Controller - Packet Scheduler Miniport) and address 192.168.1.121, timeout = 0
2013-05-04 21:37:30,531 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 057e814f-d039-3103-87b8-24793c5a6859
2013-05-04 21:37:31,312 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:48597: GET /deviceDescription/057e814f-d039-3103-87b8-24793c5a6859 HTTP/1.1, headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:37:29 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";]]
2013-05-04 21:37:31,312 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:31,328 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 057e814f-d039-3103-87b8-24793c5a6859 from 192.168.1.143 (headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:37:29 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";])
2013-05-04 21:37:31,328 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:31,328 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:37:29 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";]
2013-05-04 21:37:31,328 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:31,515 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Generic DLNA profile'
2013-05-04 21:37:31,546 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:48598: GET /serviceDescription/ConnectionManager HTTP/1.1, headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:37:29 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";]]
2013-05-04 21:37:31,546 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:31,546 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2013-05-04 21:37:31,546 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2013-05-04 21:37:31,578 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:48599: GET /serviceDescription/ContentDirectory HTTP/1.1, headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:37:29 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";]]
2013-05-04 21:37:31,578 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:31,578 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2013-05-04 21:37:31,593 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2013-05-04 21:37:31,640 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:48600: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";,Host: 192.168.1.121:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 280,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"]]
2013-05-04 21:37:31,640 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:31,640 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from 192.168.1.143
2013-05-04 21:37:31,640 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:31,921 DEBUG [RendererSearchSender$RendererSearchWorker] Received search response: location: http://192.168.1.143:52323/dmr.xml, st: urn:schemas-upnp-org:device:MediaRenderer:1
2013-05-04 21:37:31,921 DEBUG [RendererSearchSender$RendererSearchWorker] Received a valid M-SEARCH response from Renderer 00000000-0000-1010-8000-106f3f796feb from address /192.168.1.143:58620
2013-05-04 21:37:31,937 DEBUG [RendererManager] Adding renderer Renderer [uuid=00000000-0000-1010-8000-106f3f796feb, ipAddress=192.168.1.143, name=KDL-32HX75A, profileId=1, manuallyAdded=false, forcedProfile=false, enabled=true, accessGroupId=1] to list of active renderers
2013-05-04 21:37:31,953 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-05-04 21:37:31,968 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:48601: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";,Host: 192.168.1.121:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 273,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"]]
2013-05-04 21:37:31,968 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:31,968 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"' from 192.168.1.143
2013-05-04 21:37:31,968 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:32,125 DEBUG [ConnectionManager] Sending protocol info using profile 'Generic DLNA profile'
2013-05-04 21:37:32,140 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-05-04 21:37:32,234 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:48602: GET /deviceDescription/057e814f-d039-3103-87b8-24793c5a6859 HTTP/1.1, headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:37:30 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";]]
2013-05-04 21:37:32,234 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:32,234 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 057e814f-d039-3103-87b8-24793c5a6859 from 192.168.1.143 (headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:37:30 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";])
2013-05-04 21:37:32,234 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:32,234 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:37:30 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";]
2013-05-04 21:37:32,234 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:32,234 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Generic DLNA profile'
2013-05-04 21:37:32,250 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:48603: GET /serviceDescription/ConnectionManager HTTP/1.1, headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:37:30 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";]]
2013-05-04 21:37:32,250 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:32,250 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2013-05-04 21:37:32,250 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2013-05-04 21:37:32,296 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:48604: GET /icon/largePNG HTTP/1.1, headers = [transferMode.dlna.org: Interactive,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";,Host: 192.168.1.121:8895]]
2013-05-04 21:37:32,296 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:32,296 DEBUG [UPnPIconRequestHandler] UPnP icon request received for icon largePNG
2013-05-04 21:37:32,296 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:48605: GET /serviceDescription/ContentDirectory HTTP/1.1, headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:37:30 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";]]
2013-05-04 21:37:32,296 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:32,296 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2013-05-04 21:37:32,296 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2013-05-04 21:37:32,343 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:08 (advertisement duration is 1800 sec.)
2013-05-04 21:37:32,359 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:48606: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";,Host: 192.168.1.121:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 280,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"]]
2013-05-04 21:37:32,359 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:32,359 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from 192.168.1.143
2013-05-04 21:37:32,359 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:32,531 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-05-04 21:37:32,546 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:48607: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";,Host: 192.168.1.121:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 273,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"]]
2013-05-04 21:37:32,546 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:32,546 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"' from 192.168.1.143
2013-05-04 21:37:32,546 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:32,734 DEBUG [ConnectionManager] Sending protocol info using profile 'Generic DLNA profile'
2013-05-04 21:37:32,734 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-05-04 21:37:33,437 DEBUG [RendererSearchSender$RendererSearchWorker] Received search response: location: http://192.168.1.143:52323/dmr.xml, st: urn:schemas-upnp-org:device:MediaRenderer:1
2013-05-04 21:37:33,437 DEBUG [RendererSearchSender$RendererSearchWorker] Received a valid M-SEARCH response from Renderer 00000000-0000-1010-8000-106f3f796feb from address /192.168.1.143:58620
2013-05-04 21:37:33,812 DEBUG [RendererSearchSender$RendererSearchWorker] Received search response: location: http://192.168.1.143:52323/dmr.xml, st: urn:schemas-upnp-org:device:MediaRenderer:1
2013-05-04 21:37:33,812 DEBUG [RendererSearchSender$RendererSearchWorker] Received a valid M-SEARCH response from Renderer 00000000-0000-1010-8000-106f3f796feb from address /192.168.1.143:58620
2013-05-04 21:37:36,484 DEBUG [RendererSearchSender] Finished searching for Renderer devices
2013-05-04 21:37:40,531 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth0 (Atheros AR8131 PCI-E Gigabit Ethernet Controller - Packet Scheduler Miniport) and address 192.168.1.121, timeout = 0
2013-05-04 21:37:40,531 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 057e814f-d039-3103-87b8-24793c5a6859
2013-05-04 21:37:41,218 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:48608: GET /deviceDescription/057e814f-d039-3103-87b8-24793c5a6859 HTTP/1.1, headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:37:39 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";]]
2013-05-04 21:37:41,218 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:41,218 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 057e814f-d039-3103-87b8-24793c5a6859 from 192.168.1.143 (headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:37:39 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";])
2013-05-04 21:37:41,218 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:41,218 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:37:39 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";]
2013-05-04 21:37:41,218 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:41,234 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Generic DLNA profile'
2013-05-04 21:37:41,250 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:48609: GET /serviceDescription/ConnectionManager HTTP/1.1, headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:37:39 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";]]
2013-05-04 21:37:41,250 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:41,250 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2013-05-04 21:37:41,250 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2013-05-04 21:37:41,265 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:48610: GET /serviceDescription/ContentDirectory HTTP/1.1, headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:37:39 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";]]
2013-05-04 21:37:41,265 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:41,265 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2013-05-04 21:37:41,265 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2013-05-04 21:37:41,296 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:48611: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";,Host: 192.168.1.121:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 280,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"]]
2013-05-04 21:37:41,296 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:41,312 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from 192.168.1.143
2013-05-04 21:37:41,312 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:41,484 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-05-04 21:37:41,500 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:48612: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";,Host: 192.168.1.121:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 273,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"]]
2013-05-04 21:37:41,500 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:41,500 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"' from 192.168.1.143
2013-05-04 21:37:41,500 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:41,687 DEBUG [ConnectionManager] Sending protocol info using profile 'Generic DLNA profile'
2013-05-04 21:37:41,687 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-05-04 21:37:41,765 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:48613: GET /deviceDescription/057e814f-d039-3103-87b8-24793c5a6859 HTTP/1.1, headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:37:39 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";]]
2013-05-04 21:37:41,765 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:41,765 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 057e814f-d039-3103-87b8-24793c5a6859 from 192.168.1.143 (headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:37:39 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";])
2013-05-04 21:37:41,765 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:41,765 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:37:39 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";]
2013-05-04 21:37:41,765 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:41,765 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Generic DLNA profile'
2013-05-04 21:37:41,828 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:48614: GET /serviceDescription/ConnectionManager HTTP/1.1, headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:37:39 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";]]
2013-05-04 21:37:41,828 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:41,828 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2013-05-04 21:37:41,828 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2013-05-04 21:37:41,843 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:48615: GET /serviceDescription/ContentDirectory HTTP/1.1, headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:37:39 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";]]
2013-05-04 21:37:41,843 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:41,843 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2013-05-04 21:37:41,843 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2013-05-04 21:37:41,890 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:48616: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";,Host: 192.168.1.121:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 280,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"]]
2013-05-04 21:37:41,890 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:41,890 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from 192.168.1.143
2013-05-04 21:37:41,890 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:42,093 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-05-04 21:37:42,093 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:48617: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";,Host: 192.168.1.121:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 273,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"]]
2013-05-04 21:37:42,093 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:42,093 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"' from 192.168.1.143
2013-05-04 21:37:42,093 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:42,281 DEBUG [ConnectionManager] Sending protocol info using profile 'Generic DLNA profile'
2013-05-04 21:37:42,296 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-05-04 21:37:42,343 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:08 (advertisement duration is 1800 sec.)
2013-05-04 21:37:50,531 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth0 (Atheros AR8131 PCI-E Gigabit Ethernet Controller - Packet Scheduler Miniport) and address 192.168.1.121, timeout = 0
2013-05-04 21:37:50,531 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 057e814f-d039-3103-87b8-24793c5a6859
2013-05-04 21:37:51,156 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:48618: GET /deviceDescription/057e814f-d039-3103-87b8-24793c5a6859 HTTP/1.1, headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:37:49 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";]]
2013-05-04 21:37:51,156 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:51,156 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 057e814f-d039-3103-87b8-24793c5a6859 from 192.168.1.143 (headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:37:49 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";])
2013-05-04 21:37:51,156 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:51,171 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:37:49 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";]
2013-05-04 21:37:51,171 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:51,171 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Generic DLNA profile'
2013-05-04 21:37:51,187 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:48619: GET /serviceDescription/ConnectionManager HTTP/1.1, headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:37:49 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";]]
2013-05-04 21:37:51,187 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:51,187 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2013-05-04 21:37:51,187 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2013-05-04 21:37:51,218 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:48620: GET /serviceDescription/ContentDirectory HTTP/1.1, headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:37:49 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";]]
2013-05-04 21:37:51,218 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:51,218 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2013-05-04 21:37:51,218 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2013-05-04 21:37:51,250 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:48621: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";,Host: 192.168.1.121:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 280,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"]]
2013-05-04 21:37:51,250 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:51,265 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from 192.168.1.143
2013-05-04 21:37:51,265 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:51,437 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-05-04 21:37:51,468 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:48622: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";,Host: 192.168.1.121:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 273,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"]]
2013-05-04 21:37:51,468 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:51,468 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"' from 192.168.1.143
2013-05-04 21:37:51,468 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:51,640 DEBUG [ConnectionManager] Sending protocol info using profile 'Generic DLNA profile'
2013-05-04 21:37:51,640 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-05-04 21:37:51,718 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:48623: GET /deviceDescription/057e814f-d039-3103-87b8-24793c5a6859 HTTP/1.1, headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:37:49 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";]]
2013-05-04 21:37:51,718 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:51,734 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 057e814f-d039-3103-87b8-24793c5a6859 from 192.168.1.143 (headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:37:49 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";])
2013-05-04 21:37:51,734 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:51,734 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:37:49 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";]
2013-05-04 21:37:51,734 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:51,734 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Generic DLNA profile'
2013-05-04 21:37:51,750 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:48624: GET /serviceDescription/ConnectionManager HTTP/1.1, headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:37:49 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";]]
2013-05-04 21:37:51,750 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:51,750 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2013-05-04 21:37:51,750 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2013-05-04 21:37:51,765 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:48625: GET /serviceDescription/ContentDirectory HTTP/1.1, headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:37:49 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";]]
2013-05-04 21:37:51,765 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:51,765 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2013-05-04 21:37:51,765 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2013-05-04 21:37:51,796 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:48626: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";,Host: 192.168.1.121:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 280,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"]]
2013-05-04 21:37:51,796 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:51,796 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from 192.168.1.143
2013-05-04 21:37:51,796 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:51,953 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-05-04 21:37:51,953 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:48627: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";,Host: 192.168.1.121:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 273,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"]]
2013-05-04 21:37:51,953 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:51,953 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"' from 192.168.1.143
2013-05-04 21:37:51,953 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:37:52,140 DEBUG [ConnectionManager] Sending protocol info using profile 'Generic DLNA profile'
2013-05-04 21:37:52,156 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-05-04 21:37:52,343 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:03:51 (advertisement duration is 1800 sec.)
2013-05-04 21:37:56,484 INFO  [FeedUpdaterThread] Started looking for information about online resources
2013-05-04 21:37:56,484 DEBUG [FeedUpdaterThread] Checking for new and expired online resources
2013-05-04 21:37:56,484 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2013-05-04 21:37:56,484 INFO  [LibraryAdditionsCheckerThread] Started looking for newly added files
2013-05-04 21:37:56,484 DEBUG [RepositoryDAOImpl] Reading all Repositories
2013-05-04 21:37:56,500 INFO  [LibraryUpdatesCheckerThread] Started looking for updates to currently shared files
2013-05-04 21:37:56,500 DEBUG [RepositoryDAOImpl] Reading all Repositories
2013-05-04 21:37:56,500 INFO  [PlaylistMaintainerThread] Started looking playlist changes
2013-05-04 21:37:56,500 DEBUG [PlaylistDAOImpl] Reading all Playlists
2013-05-04 21:38:08,609 DEBUG [ServiioConsole] Initializing Serviio Console
2013-05-04 21:38:08,625 DEBUG [ApplicationInstanceManager] Listening for application instances on socket 44332
2013-05-04 21:38:08,625 INFO  [ServiioConsole] Starting Serviio Console
2013-05-04 21:38:08,718 DEBUG [SwingHelper] Choosing java look and feel: com.sun.java.swing.plaf.windows.WindowsLookAndFeel
2013-05-04 21:38:08,765 INFO  [ServiioConsole] Connecting to Serviio REST on localhost:23423
2013-05-04 21:38:09,640 DEBUG [ServiioConsole] Retrieving console settings
2013-05-04 21:38:09,671 DEBUG [ServiioConsole] Setting console locale to be: en
2013-05-04 21:38:09,687 INFO  [Messages] Loaded console message bundle for locale: en
2013-05-04 21:38:09,859 DEBUG [ServiioFrameView] Initializing main frame
2013-05-04 21:38:10,062 DEBUG [ServiioFrameView] System tray is supported
2013-05-04 21:38:10,375 DEBUG [AccessGroupDAOImpl] Reading all AccessGroups
2013-05-04 21:38:10,968 DEBUG [RepositoryDAOImpl] Reading all Repositories
2013-05-04 21:38:10,968 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2013-05-04 21:38:11,578 DEBUG [RendererDAOImpl] Retrieving list of all stored renderers
2013-05-04 21:38:11,781 DEBUG [AccessGroupDAOImpl] Reading all AccessGroups
2013-05-04 21:38:12,390 DEBUG [MetadataExtractorConfigDAOImpl] Reading a list of Extractor configuration for type AUDIO
2013-05-04 21:38:12,515 DEBUG [MetadataExtractorConfigDAOImpl] Reading a list of Extractor configuration for type VIDEO
2013-05-04 21:38:13,093 INFO  [ContentDirectoryDefinitionParser] Parsing ContentDirectory definition
2013-05-04 21:38:13,093 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=0, titleKey=pc5dczc_media_library, class=CONTAINER, cacheRegion=no_cache, browsable = null, editable = null
2013-05-04 21:38:13,125 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A, titleKey=audio, class=CONTAINER, cacheRegion=no_cache, browsable = null, editable = true
2013-05-04 21:38:13,140 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_A, titleKey=artists, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2013-05-04 21:38:13,140 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListArtistInitialsCommand, containerClass=CONTAINER, itemClass=null, idPrefix=INI, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,156 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListArtistsForInitialCommand, containerClass=MUSIC_ARTIST, itemClass=null, idPrefix=ART, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,156 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListAlbumsForArtistCommand, containerClass=MUSIC_ALBUM, itemClass=null, idPrefix=A, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,171 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListSongsForArtistAndAlbumCommand, containerClass=null, itemClass=MUSIC_TRACK, idPrefix=S, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,171 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=AL_A, titleKey=album_artists, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2013-05-04 21:38:13,187 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListAlbumArtistInitialsCommand, containerClass=CONTAINER, itemClass=null, idPrefix=INI, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,187 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListAlbumArtistsForInitialCommand, containerClass=MUSIC_ARTIST, itemClass=null, idPrefix=ART, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,203 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListAlbumsForAlbumArtistCommand, containerClass=MUSIC_ALBUM, itemClass=null, idPrefix=A, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,203 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListSongsForAlbumCommand, containerClass=null, itemClass=MUSIC_TRACK, idPrefix=S, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,218 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_F, titleKey=folders, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2013-05-04 21:38:13,218 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListAudioFoldersByNameCommand, containerClass=STORAGE_FOLDER, itemClass=MUSIC_TRACK, idPrefix=FOL, recursive=true, cacheRegion=local_default
2013-05-04 21:38:13,234 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_G, titleKey=genres, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2013-05-04 21:38:13,234 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListAudioGenresByNameCommand, containerClass=MUSIC_GENRE, itemClass=null, idPrefix=GEN, recursive=false, cacheRegion=local_default

Re: Sony BDP-S590 can't play .MKV files (corrupt or unsuppor

PostPosted: Sat May 04, 2013 1:53 pm
by dukilupp
Continue...

  Code:
2013-05-04 21:38:13,250 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListSongsForGenreCommand, containerClass=null, itemClass=MUSIC_TRACK, idPrefix=S, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,250 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_C, titleKey=composers, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2013-05-04 21:38:13,250 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListComposerInitialsCommand, containerClass=CONTAINER, itemClass=null, idPrefix=INI, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,265 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListComposersForInitialCommand, containerClass=MUSIC_ARTIST, itemClass=null, idPrefix=COM, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,265 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListAlbumsForComposerCommand, containerClass=MUSIC_ALBUM, itemClass=null, idPrefix=A, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,281 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListSongsForComposerAndAlbumCommand, containerClass=null, itemClass=MUSIC_TRACK, idPrefix=S, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,281 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_T, titleKey=titles, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2013-05-04 21:38:13,296 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListSongInitialsCommand, containerClass=CONTAINER, itemClass=null, idPrefix=INI, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,296 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListSongsForInitialCommand, containerClass=null, itemClass=MUSIC_TRACK, idPrefix=S, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,312 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_R, titleKey=random, class=CONTAINER, cacheRegion=no_cache, browsable = null, editable = true
2013-05-04 21:38:13,312 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListRandomSongsCommand, containerClass=null, itemClass=MUSIC_TRACK, idPrefix=S, recursive=false, cacheRegion=no_cache
2013-05-04 21:38:13,328 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_PL, titleKey=playlists, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2013-05-04 21:38:13,328 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListAudioPlaylistsCommand, containerClass=PLAYLIST_CONTAINER, itemClass=null, idPrefix=PL, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,328 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListSongsForPlaylistCommand, containerClass=null, itemClass=MUSIC_TRACK, idPrefix=S, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,343 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_OF, titleKey=online, class=CONTAINER, cacheRegion=no_cache, browsable = null, editable = true
2013-05-04 21:38:13,343 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListAudioOnlineContentCommand, containerClass=STORAGE_FOLDER, itemClass=MUSIC_TRACK, idPrefix=FOL, recursive=true, cacheRegion=no_cache
2013-05-04 21:38:13,359 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_S, titleKey=All songs, class=CONTAINER, cacheRegion=local_default, browsable = false, editable = null
2013-05-04 21:38:13,359 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListAllSongsCommand, containerClass=null, itemClass=MUSIC_TRACK, idPrefix=S, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,375 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_AS, titleKey=Artist Songs, class=CONTAINER, cacheRegion=local_default, browsable = false, editable = null
2013-05-04 21:38:13,375 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListArtistsByNameCommand, containerClass=MUSIC_ARTIST, itemClass=null, idPrefix=ART_S, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,390 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListSongsForArtistCommand, containerClass=null, itemClass=MUSIC_TRACK, idPrefix=S, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,390 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_ALB, titleKey=All albums, class=CONTAINER, cacheRegion=local_default, browsable = false, editable = null
2013-05-04 21:38:13,390 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListAllAlbumsCommand, containerClass=MUSIC_ALBUM, itemClass=null, idPrefix=ALB, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,406 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListSongsForAlbumCommand, containerClass=null, itemClass=MUSIC_TRACK, idPrefix=S, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,406 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=I, titleKey=image, class=CONTAINER, cacheRegion=no_cache, browsable = null, editable = true
2013-05-04 21:38:13,421 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=I_A, titleKey=albums, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2013-05-04 21:38:13,421 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.image.ListFlatImageFoldersByNameCommand, containerClass=CONTAINER, itemClass=null, idPrefix=ALB, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,437 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.image.ListImagesForFolderCommand, containerClass=null, itemClass=PHOTO, idPrefix=I, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,437 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=I_F, titleKey=folders, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2013-05-04 21:38:13,453 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.image.ListImageFoldersByNameCommand, containerClass=STORAGE_FOLDER, itemClass=PHOTO, idPrefix=FOL, recursive=true, cacheRegion=local_default
2013-05-04 21:38:13,453 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=I_Y, titleKey=year, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2013-05-04 21:38:13,468 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.image.ListImagesCreationYearsCommand, containerClass=CONTAINER, itemClass=null, idPrefix=YEAR, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,468 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.image.ListImagesForCreationYearCommand, containerClass=null, itemClass=PHOTO, idPrefix=I, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,468 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=I_T, titleKey=month, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2013-05-04 21:38:13,484 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.image.ListImagesCreationYearsCommand, containerClass=CONTAINER, itemClass=null, idPrefix=YEAR, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,484 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.image.ListImagesCreationMonthsCommand, containerClass=CONTAINER, itemClass=null, idPrefix=MON, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,500 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.image.ListImagesForCreationMonthAndYearCommand, containerClass=null, itemClass=PHOTO, idPrefix=I, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,500 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=I_PL, titleKey=playlists, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2013-05-04 21:38:13,515 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.image.ListImagePlaylistsCommand, containerClass=PLAYLIST_CONTAINER, itemClass=null, idPrefix=PL, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,515 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.image.ListImagesForPlaylistCommand, containerClass=null, itemClass=PHOTO, idPrefix=S, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,531 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=I_OF, titleKey=online, class=CONTAINER, cacheRegion=no_cache, browsable = null, editable = true
2013-05-04 21:38:13,531 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.image.ListImageOnlineContentCommand, containerClass=STORAGE_FOLDER, itemClass=PHOTO, idPrefix=FOL, recursive=true, cacheRegion=no_cache
2013-05-04 21:38:13,546 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=I_AI, titleKey=All images, class=CONTAINER, cacheRegion=local_default, browsable = false, editable = null
2013-05-04 21:38:13,546 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.image.ListAllImagesCommand, containerClass=null, itemClass=PHOTO, idPrefix=I, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,562 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V, titleKey=video, class=CONTAINER, cacheRegion=no_cache, browsable = null, editable = true
2013-05-04 21:38:13,562 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_T, titleKey=titles, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2013-05-04 21:38:13,562 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListVideoInitialsCommand, containerClass=CONTAINER, itemClass=null, idPrefix=INI, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,578 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListVideosForInitialCommand, containerClass=null, itemClass=VIDEO_ITEM, idPrefix=V, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,593 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_M, titleKey=movies, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2013-05-04 21:38:13,593 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListMovieVideosByNameCommand, containerClass=null, itemClass=VIDEO_ITEM, idPrefix=V, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,593 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_S, titleKey=series, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2013-05-04 21:38:13,609 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListSeriesByNameCommand, containerClass=CONTAINER, itemClass=null, idPrefix=SER, recursive=false, cacheRegion=local_resetafterplay
2013-05-04 21:38:13,609 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListSeasonsForSeriesCommand, containerClass=CONTAINER, itemClass=null, idPrefix=SSN, recursive=false, cacheRegion=local_resetafterplay
2013-05-04 21:38:13,625 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListEpisodesForSeriesSeasonCommand, containerClass=null, itemClass=VIDEO_ITEM, idPrefix=V, recursive=false, cacheRegion=local_resetafterplay
2013-05-04 21:38:13,625 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_F, titleKey=folders, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2013-05-04 21:38:13,640 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListVideoFoldersByNameCommand, containerClass=STORAGE_FOLDER, itemClass=VIDEO_ITEM, idPrefix=FOL, recursive=true, cacheRegion=local_default
2013-05-04 21:38:13,640 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_LV, titleKey=last_viewed, class=CONTAINER, cacheRegion=local_resetafterplay, browsable = null, editable = true
2013-05-04 21:38:13,656 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListLastViewedVideosCommand, containerClass=null, itemClass=VIDEO_ITEM, idPrefix=V, recursive=false, cacheRegion=local_resetafterplay
2013-05-04 21:38:13,656 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_LA, titleKey=last_added, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2013-05-04 21:38:13,671 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListLastAddedVideosCommand, containerClass=null, itemClass=VIDEO_ITEM, idPrefix=V, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,671 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_G, titleKey=genres, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2013-05-04 21:38:13,687 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListVideoGenresByNameCommand, containerClass=CONTAINER, itemClass=null, idPrefix=GEN, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,703 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListVideosForGenreCommand, containerClass=null, itemClass=VIDEO_ITEM, idPrefix=V, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,703 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_A, titleKey=actors, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2013-05-04 21:38:13,718 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListActorInitialsCommand, containerClass=CONTAINER, itemClass=null, idPrefix=INI, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,718 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListActorsForInitialCommand, containerClass=PERSON, itemClass=null, idPrefix=P, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,734 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListVideosForActorCommand, containerClass=null, itemClass=VIDEO_ITEM, idPrefix=V, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,734 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_D, titleKey=directors, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2013-05-04 21:38:13,750 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListDirectorInitialsCommand, containerClass=CONTAINER, itemClass=null, idPrefix=INI, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,750 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListDirectorsForInitialCommand, containerClass=PERSON, itemClass=null, idPrefix=P, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,765 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListVideosForDirectorCommand, containerClass=null, itemClass=VIDEO_ITEM, idPrefix=V, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,765 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_P, titleKey=producers, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2013-05-04 21:38:13,765 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListProducerInitialsCommand, containerClass=CONTAINER, itemClass=null, idPrefix=INI, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,781 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListProducersForInitialCommand, containerClass=PERSON, itemClass=null, idPrefix=P, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,781 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListVideosForProducerCommand, containerClass=null, itemClass=VIDEO_ITEM, idPrefix=V, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,796 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_PL, titleKey=playlists, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2013-05-04 21:38:13,796 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListVideoPlaylistsCommand, containerClass=PLAYLIST_CONTAINER, itemClass=null, idPrefix=PL, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,812 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListVideosForPlaylistCommand, containerClass=null, itemClass=VIDEO_ITEM, idPrefix=S, recursive=false, cacheRegion=local_default
2013-05-04 21:38:13,812 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_OF, titleKey=online, class=CONTAINER, cacheRegion=no_cache, browsable = null, editable = true
2013-05-04 21:38:13,828 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListVideoOnlineContentCommand, containerClass=STORAGE_FOLDER, itemClass=VIDEO_ITEM, idPrefix=FOL, recursive=true, cacheRegion=no_cache
2013-05-04 21:38:13,843 INFO  [BrowsingCategoriesMessages] Loaded browsing categories message bundle for locale: en
2013-05-04 21:38:15,187 DEBUG [RepositoryDAOImpl] Reading all Repositories
2013-05-04 21:38:15,187 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2013-05-04 21:38:15,203 DEBUG [ServiioConsole] Reading application data
2013-05-04 21:38:15,203 DEBUG [RepositoryView$LibraryStatusCheckerThread] Starting library status checking thread
2013-05-04 21:38:22,796 DEBUG [DiscoverySSDPMessageListener] Received a valid NOTIFY (ssdp:alive) message from Renderer 00000000-0000-1010-8000-106f3f796feb from address /192.168.1.143:52323
2013-05-04 21:38:23,046 DEBUG [DiscoverySSDPMessageListener] Received a valid NOTIFY (ssdp:alive) message from Renderer 00000000-0000-1010-8000-106f3f796feb from address /192.168.1.143:52323
2013-05-04 21:38:32,906 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2013-05-04 21:38:32,921 DEBUG [ConfigEntryDAOImpl] Reading a ConfigEntry (name = search_hidden_files)
2013-05-04 21:38:32,921 DEBUG [FeedUpdaterThread] Checking for new and expired online resources
2013-05-04 21:38:32,921 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2013-05-04 21:38:32,921 DEBUG [ConfigEntryDAOImpl] Updating ConfigEntry (name = search_hidden_files, value = false)
2013-05-04 21:38:33,203 DEBUG [ConfigEntryDAOImpl] Reading a ConfigEntry (name = search_updated_files)
2013-05-04 21:38:33,203 DEBUG [ConfigEntryDAOImpl] Updating ConfigEntry (name = search_updated_files, value = true)
2013-05-04 21:38:33,203 DEBUG [ConfigEntryDAOImpl] Reading a ConfigEntry (name = repository_automatic_check)
2013-05-04 21:38:33,203 DEBUG [ConfigEntryDAOImpl] Updating ConfigEntry (name = repository_automatic_check, value = true)
2013-05-04 21:38:33,203 DEBUG [ConfigEntryDAOImpl] Reading a ConfigEntry (name = repository_automatic_check_interval)
2013-05-04 21:38:33,203 DEBUG [ConfigEntryDAOImpl] Updating ConfigEntry (name = repository_automatic_check_interval, value = 5)
2013-05-04 21:38:33,234 DEBUG [ConfigEntryDAOImpl] Reading a ConfigEntry (name = online_feed_max_num_items)
2013-05-04 21:38:33,234 DEBUG [ConfigEntryDAOImpl] Updating ConfigEntry (name = online_feed_max_num_items, value = 20)
2013-05-04 21:38:33,234 DEBUG [ConfigEntryDAOImpl] Reading a ConfigEntry (name = online_feed_expiry_interval)
2013-05-04 21:38:33,234 DEBUG [ConfigEntryDAOImpl] Updating ConfigEntry (name = online_feed_expiry_interval, value = 24)
2013-05-04 21:38:33,234 INFO  [LibraryAdditionsCheckerThread] Finished looking for newly added files
2013-05-04 21:38:33,343 INFO  [LibraryUpdatesCheckerThread] Finished looking for updates to currently shared files
2013-05-04 21:38:33,437 INFO  [PlaylistMaintainerThread] Finished looking for playlist changes
2013-05-04 21:38:33,531 DEBUG [RepositoryServerResource] Updating repository with values: Repository [id=null, folder=F:\, keepScanningForUpdates=true, lastScanned=null, supportedFileTypes=[AUDIO, VIDEO, IMAGE], supportsOnlineMetadata=true]
2013-05-04 21:38:33,531 DEBUG [RepositoryDAOImpl] Reading all Repositories
2013-05-04 21:38:33,531 DEBUG [RepositoryService] Found 0 repositories to be removed
2013-05-04 21:38:33,531 DEBUG [RepositoryDAOImpl] Creating a new Repository (folder = F:\)
2013-05-04 21:38:33,593 DEBUG [RepositoryDAOImpl] Adding Access Groups to the new Repository
2013-05-04 21:38:33,796 INFO  [LibraryUpdatesCheckerThread] Started looking for updates to currently shared files
2013-05-04 21:38:33,796 INFO  [LibraryAdditionsCheckerThread] Started looking for newly added files
2013-05-04 21:38:33,796 DEBUG [RepositoryDAOImpl] Reading all Repositories
2013-05-04 21:38:33,796 DEBUG [RepositoryDAOImpl] Reading all Repositories
2013-05-04 21:38:33,796 INFO  [PlaylistMaintainerThread] Started looking playlist changes
2013-05-04 21:38:33,796 DEBUG [PlaylistDAOImpl] Reading all Playlists
2013-05-04 21:38:33,796 DEBUG [LibraryUpdatesCheckerThread] Looking for removed playlist files in repository: F:\
2013-05-04 21:38:33,796 DEBUG [PlaylistDAOImpl] Reading Playlists for Repository (id = 11)
2013-05-04 21:38:33,796 DEBUG [LibraryAdditionsCheckerThread] Looking for files to share in folder: F:\
2013-05-04 21:38:33,796 DEBUG [LibraryAdditionsCheckerThread] Found file 'test.mkv', checking if it's already in the Library
2013-05-04 21:38:33,812 DEBUG [LibraryUpdatesCheckerThread] Looking for removed/updated files in repository: F:\
2013-05-04 21:38:33,828 DEBUG [MediaItemDAOImpl] Reading MediaItems for Repository (id = 11)
2013-05-04 21:38:33,828 DEBUG [MediaItemDAOImpl] Checking if DB already contains media item test.mkv
2013-05-04 21:38:33,828 DEBUG [MediaItemDAOImpl] Looking up a media item for file path: F:\test.mkv, ignore case: true
2013-05-04 21:38:33,906 DEBUG [RepositoryDAOImpl] Reading all Repositories
2013-05-04 21:38:33,921 DEBUG [AccessGroupDAOImpl] Reading all AccessGroups for Repository (id = 11)
2013-05-04 21:38:34,312 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2013-05-04 21:38:34,312 DEBUG [MediaItemDAOImpl] Reading dirty MediaItems for Repository (id = 11)
2013-05-04 21:38:34,312 DEBUG [MediaItemDAOImpl] Media item test.mkv doesn't exist in DB yet
2013-05-04 21:38:34,328 DEBUG [LibraryAdditionsCheckerThread] File not in Library, will add it
2013-05-04 21:38:34,328 DEBUG [RepositoryDAOImpl] Marking Repository 11 as scanned with current timestamp
2013-05-04 21:38:34,390 DEBUG [MetadataExtractorConfigDAOImpl] Reading a list of Extractor configuration for type AUDIO
2013-05-04 21:38:34,390 DEBUG [MetadataExtractorConfigDAOImpl] Reading a list of Extractor configuration for type VIDEO
2013-05-04 21:38:34,390 DEBUG [MetadataExtractorConfigDAOImpl] Reading a list of Extractor configuration for type IMAGE
2013-05-04 21:38:34,406 DEBUG [VideoExtractionStrategy] Extracting metadata of video file: F:\test.mkv
2013-05-04 21:38:34,406 DEBUG [RepositoryDAOImpl] Reading all Repositories
2013-05-04 21:38:34,406 DEBUG [AccessGroupDAOImpl] Reading all AccessGroups for Repository (id = 11)
2013-05-04 21:38:34,406 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2013-05-04 21:38:34,421 DEBUG [FFMPEGWrapper] Invoking FFMPEG to retrieve media information for file: F:\test.mkv
2013-05-04 21:38:34,421 DEBUG [ProcessExecutor] Starting C:\Program Files\Serviio\bin\\..\lib/ffmpeg.exe -i F:\test.mkv
2013-05-04 21:38:34,843 DEBUG [FFmpegMetadataRetriever] Retrieving H264 profile/level for file 'F:\test.mkv'
2013-05-04 21:38:34,843 DEBUG [FFMPEGWrapper] Invoking FFMPEG to retrieve H264 header for file: F:\test.mkv
2013-05-04 21:38:34,843 DEBUG [ProcessExecutor] Starting C:\Program Files\Serviio\bin\\..\lib/ffmpeg.exe -i F:\test.mkv -frames:v 1 -c:v copy -f h264 -bsf:v h264_mp4toannexb -an pipe:
2013-05-04 21:38:35,000 DEBUG [FFmpegMetadataRetriever] File 'F:\test.mkv' has H264 profile HIGH, levels [{H=5.1, RF=3.1}] and 2 ref frames
2013-05-04 21:38:35,000 DEBUG [FFMPEGWrapper] Invoking FFMPEG to retrieve thumbnail for file: F:\test.mkv
2013-05-04 21:38:35,000 DEBUG [ProcessExecutor] Starting C:\Program Files\Serviio\bin\\..\lib/ffmpeg.exe -ss 120 -i F:\test.mkv -an -frames:v 1 -f image2 pipe:
2013-05-04 21:38:39,437 DEBUG [LibraryManager] Metadata found via extractor EMBEDDED: VideoMetadata [title=test, filePath=F:\test.mkv, fileSize=9342843022, audioBitrate=1536, audioCodec=DTS, audioStreamIndex=1, bitrate=16608, channels=6, container=MATROSKA, contentType=UNKNOWN, duration=4500, episodeNumber=null, fps=23.976, frequency=48000, h264Levels={H=5.1, RF=3.1}, h264Profile=HIGH, ftyp=null, height=1080, seasonNumber=null, seriesName=null, timestampType=null, videoBitrate=null, videoCodec=H264, videoFourCC=null, videoStreamIndex=0, width=1920, embeddedSubtitles=[2/SRT/eng/true, 4/SRT/eng/false, 16/SRT/dut/false, 17/SRT/rum/false, 18/SRT//false]]
2013-05-04 21:38:39,453 DEBUG [TheMovieDBSourceAdaptor] Searching for movie 'test'  (language: en)
2013-05-04 21:38:40,734 DEBUG [TheMovieDBSourceAdaptor] Found 20 matches
2013-05-04 21:38:40,828 DEBUG [TheMovieDBSourceAdaptor] Found a suitable movie match, id = 336
2013-05-04 21:38:40,828 DEBUG [TheMovieDBSourceAdaptor] Retrieving details of movie (movieId = 336, language = en)
2013-05-04 21:38:42,953 DEBUG [TheMovieDBSourceAdaptor] Retrieved poster: http://d3gtl9l2a4fn1j.cloudfront.net/t/p/w185/563TvkCAfJTK1vhIziZSQX67UZF.jpg
2013-05-04 21:38:42,953 DEBUG [LibraryManager] Metadata found via extractor ONLINE_VIDEO_SOURCES: VideoMetadata [title=Duck, You Sucker, filePath=null, fileSize=0, audioBitrate=null, audioCodec=null, audioStreamIndex=null, bitrate=null, channels=null, container=null, contentType=MOVIE, duration=null, episodeNumber=null, fps=null, frequency=null, h264Levels={}, h264Profile=null, ftyp=null, height=null, seasonNumber=null, seriesName=null, timestampType=null, videoBitrate=null, videoCodec=null, videoFourCC=null, videoStreamIndex=null, width=null, embeddedSubtitles=[]]
2013-05-04 21:38:42,953 DEBUG [VideoService] Adding video into database: Duck, You Sucker
2013-05-04 21:38:43,093 DEBUG [GenreDAOImpl] Reading a Genre (name = Action)
2013-05-04 21:38:43,140 DEBUG [GenreService] Genre Action not found, creating a new one
2013-05-04 21:38:43,140 DEBUG [GenreDAOImpl] Creating a new Genre (name = Action)
2013-05-04 21:38:43,156 DEBUG [CoverImageService] Resizing and storing cover art image
2013-05-04 21:38:43,156 DEBUG [ImageUtils] Starting image resize, size = 29615 bytes
2013-05-04 21:38:43,343 DEBUG [ImageUtils] Returning resized image, size = 6785 bytes
2013-05-04 21:38:43,343 DEBUG [CoverImageService] Image successfully resized
2013-05-04 21:38:43,343 DEBUG [CoverImageDAOImpl] Creating a new ImageCover (length = 6785)
2013-05-04 21:38:43,515 DEBUG [VideoDAOImpl] Creating a new Video (title = Duck, You Sucker)
2013-05-04 21:38:43,796 DEBUG [PersonDAOImpl] Adding a Person Sergio Leone to media item 1400 as DIRECTOR
2013-05-04 21:38:43,796 DEBUG [PersonDAOImpl] Reading a Person (name = Sergio Leone)
2013-05-04 21:38:43,921 DEBUG [PersonDAOImpl] Creating a new Person (name = Sergio Leone)
2013-05-04 21:38:43,937 DEBUG [PersonDAOImpl] Checking if person 345 has a role DIRECTOR for media item 1400
2013-05-04 21:38:44,421 DEBUG [PersonDAOImpl] Adding a Person Fulvio Morsella to media item 1400 as PRODUCER
2013-05-04 21:38:44,421 DEBUG [PersonDAOImpl] Reading a Person (name = Fulvio Morsella)
2013-05-04 21:38:44,421 DEBUG [PersonDAOImpl] Creating a new Person (name = Fulvio Morsella)
2013-05-04 21:38:44,421 DEBUG [PersonDAOImpl] Checking if person 346 has a role PRODUCER for media item 1400
2013-05-04 21:38:44,421 DEBUG [PersonDAOImpl] Adding a Person James Coburn to media item 1400 as ACTOR
2013-05-04 21:38:44,421 DEBUG [PersonDAOImpl] Reading a Person (name = James Coburn)
2013-05-04 21:38:44,437 DEBUG [PersonDAOImpl] Creating a new Person (name = James Coburn)
2013-05-04 21:38:44,437 DEBUG [PersonDAOImpl] Checking if person 347 has a role ACTOR for media item 1400
2013-05-04 21:38:44,453 DEBUG [PersonDAOImpl] Adding a Person Rod Steiger to media item 1400 as ACTOR
2013-05-04 21:38:44,453 DEBUG [PersonDAOImpl] Reading a Person (name = Rod Steiger)
2013-05-04 21:38:44,453 DEBUG [PersonDAOImpl] Creating a new Person (name = Rod Steiger)
2013-05-04 21:38:44,453 DEBUG [PersonDAOImpl] Checking if person 348 has a role ACTOR for media item 1400
2013-05-04 21:38:44,453 DEBUG [PersonDAOImpl] Adding a Person Maria Monti to media item 1400 as ACTOR
2013-05-04 21:38:44,453 DEBUG [PersonDAOImpl] Reading a Person (name = Maria Monti)
2013-05-04 21:38:44,453 DEBUG [PersonDAOImpl] Creating a new Person (name = Maria Monti)
2013-05-04 21:38:44,453 DEBUG [PersonDAOImpl] Checking if person 349 has a role ACTOR for media item 1400
2013-05-04 21:38:44,468 DEBUG [PersonDAOImpl] Adding a Person Rik Battaglia to media item 1400 as ACTOR
2013-05-04 21:38:44,468 DEBUG [PersonDAOImpl] Reading a Person (name = Rik Battaglia)
2013-05-04 21:38:44,468 DEBUG [PersonDAOImpl] Creating a new Person (name = Rik Battaglia)
2013-05-04 21:38:44,468 DEBUG [PersonDAOImpl] Checking if person 350 has a role ACTOR for media item 1400
2013-05-04 21:38:44,468 DEBUG [PersonDAOImpl] Adding a Person Franco Graziosi to media item 1400 as ACTOR
2013-05-04 21:38:44,468 DEBUG [PersonDAOImpl] Reading a Person (name = Franco Graziosi)
2013-05-04 21:38:44,468 DEBUG [PersonDAOImpl] Creating a new Person (name = Franco Graziosi)
2013-05-04 21:38:44,468 DEBUG [PersonDAOImpl] Checking if person 351 has a role ACTOR for media item 1400
2013-05-04 21:38:44,468 DEBUG [PersonDAOImpl] Adding a Person Romolo Valli to media item 1400 as ACTOR
2013-05-04 21:38:44,468 DEBUG [PersonDAOImpl] Reading a Person (name = Romolo Valli)
2013-05-04 21:38:44,468 DEBUG [PersonDAOImpl] Creating a new Person (name = Romolo Valli)
2013-05-04 21:38:44,468 DEBUG [PersonDAOImpl] Checking if person 352 has a role ACTOR for media item 1400
2013-05-04 21:38:44,484 DEBUG [PersonDAOImpl] Adding a Person Antoine Saint-John to media item 1400 as ACTOR
2013-05-04 21:38:44,484 DEBUG [PersonDAOImpl] Reading a Person (name = Antoine Saint-John)
2013-05-04 21:38:44,484 DEBUG [PersonDAOImpl] Creating a new Person (name = Antoine Saint-John)
2013-05-04 21:38:44,484 DEBUG [PersonDAOImpl] Checking if person 353 has a role ACTOR for media item 1400
2013-05-04 21:38:44,484 DEBUG [PersonDAOImpl] Adding a Person Vivienne Chandler to media item 1400 as ACTOR
2013-05-04 21:38:44,484 DEBUG [PersonDAOImpl] Reading a Person (name = Vivienne Chandler)
2013-05-04 21:38:44,484 DEBUG [PersonDAOImpl] Creating a new Person (name = Vivienne Chandler)
2013-05-04 21:38:44,484 DEBUG [PersonDAOImpl] Checking if person 354 has a role ACTOR for media item 1400
2013-05-04 21:38:44,484 DEBUG [PersonDAOImpl] Adding a Person David Warbeck to media item 1400 as ACTOR
2013-05-04 21:38:44,484 DEBUG [PersonDAOImpl] Reading a Person (name = David Warbeck)
2013-05-04 21:38:44,484 DEBUG [PersonDAOImpl] Creating a new Person (name = David Warbeck)
2013-05-04 21:38:44,484 DEBUG [PersonDAOImpl] Checking if person 355 has a role ACTOR for media item 1400
2013-05-04 21:38:44,484 DEBUG [PersonDAOImpl] Adding a Person Giulio Battiferri to media item 1400 as ACTOR
2013-05-04 21:38:44,484 DEBUG [PersonDAOImpl] Reading a Person (name = Giulio Battiferri)
2013-05-04 21:38:44,500 DEBUG [PersonDAOImpl] Creating a new Person (name = Giulio Battiferri)
2013-05-04 21:38:44,500 DEBUG [PersonDAOImpl] Checking if person 356 has a role ACTOR for media item 1400
2013-05-04 21:38:44,500 DEBUG [MetadataDescriptorDAOImpl] Creating a new MetadataDescriptor (type = ONLINE_VIDEO_SOURCES, mediaItemId = 1400)
2013-05-04 21:38:44,781 DEBUG [MetadataDescriptorDAOImpl] Creating a new MetadataDescriptor (type = EMBEDDED, mediaItemId = 1400)
2013-05-04 21:38:44,781 INFO  [LibraryAdditionsCheckerThread] Added file 'test.mkv' (title: Duck, You Sucker) to Library
2013-05-04 21:38:44,781 DEBUG [LibraryAdditionsCheckerThread] Found file 'test2.mkv', checking if it's already in the Library
2013-05-04 21:38:44,796 DEBUG [MediaItemDAOImpl] Checking if DB already contains media item test2.mkv
2013-05-04 21:38:44,796 DEBUG [MediaItemDAOImpl] Looking up a media item for file path: F:\test2.mkv, ignore case: true
2013-05-04 21:38:44,796 DEBUG [MediaItemDAOImpl] Media item test2.mkv doesn't exist in DB yet
2013-05-04 21:38:44,796 DEBUG [LibraryAdditionsCheckerThread] File not in Library, will add it
2013-05-04 21:38:44,796 DEBUG [VideoExtractionStrategy] Extracting metadata of video file: F:\test2.mkv
2013-05-04 21:38:44,796 DEBUG [FFMPEGWrapper] Invoking FFMPEG to retrieve media information for file: F:\test2.mkv
2013-05-04 21:38:44,796 DEBUG [ProcessExecutor] Starting C:\Program Files\Serviio\bin\\..\lib/ffmpeg.exe -i F:\test2.mkv
2013-05-04 21:38:44,937 DEBUG [FFmpegMetadataRetriever] Retrieving H264 profile/level for file 'F:\test2.mkv'
2013-05-04 21:38:44,937 DEBUG [FFMPEGWrapper] Invoking FFMPEG to retrieve H264 header for file: F:\test2.mkv
2013-05-04 21:38:44,937 DEBUG [ProcessExecutor] Starting C:\Program Files\Serviio\bin\\..\lib/ffmpeg.exe -i F:\test2.mkv -frames:v 1 -c:v copy -f h264 -bsf:v h264_mp4toannexb -an pipe:
2013-05-04 21:38:45,078 DEBUG [FFmpegMetadataRetriever] File 'F:\test2.mkv' has H264 profile HIGH, levels [{H=5.1, RF=3.1}] and 2 ref frames
2013-05-04 21:38:45,078 DEBUG [FFMPEGWrapper] Invoking FFMPEG to retrieve thumbnail for file: F:\test2.mkv
2013-05-04 21:38:45,078 DEBUG [ProcessExecutor] Starting C:\Program Files\Serviio\bin\\..\lib/ffmpeg.exe -ss 120 -i F:\test2.mkv -an -frames:v 1 -f image2 pipe:
2013-05-04 21:38:45,734 DEBUG [LibraryManager] Metadata found via extractor EMBEDDED: VideoMetadata [title=test2, filePath=F:\test2.mkv, fileSize=8209530285, audioBitrate=1536, audioCodec=DTS, audioStreamIndex=1, bitrate=16119, channels=6, container=MATROSKA, contentType=UNKNOWN, duration=4074, episodeNumber=null, fps=23.976, frequency=48000, h264Levels={H=5.1, RF=3.1}, h264Profile=HIGH, ftyp=null, height=1080, seasonNumber=null, seriesName=null, timestampType=null, videoBitrate=null, videoCodec=H264, videoFourCC=null, videoStreamIndex=0, width=1920, embeddedSubtitles=[2/SRT/eng/true, 4/SRT/eng/false, 16/SRT/dut/false, 17/SRT/rum/false, 18/SRT//false]]
2013-05-04 21:38:45,734 DEBUG [TheMovieDBSourceAdaptor] Searching for movie 'test2'  (language: en)
2013-05-04 21:38:46,171 DEBUG [TheMovieDBSourceAdaptor] Found 0 matches
2013-05-04 21:38:46,171 DEBUG [TheMovieDBSourceAdaptor] No movie with the name has been found
2013-05-04 21:38:46,171 WARN  [OnlineVideoSourcesMetadataExtractor] Online metadata search returned no results for file test2.mkv [VideoDescription [type=FILM, names=[test2, null], year=null, season=null, episode=null]]
2013-05-04 21:38:46,171 DEBUG [VideoService] Adding video into database: test2
2013-05-04 21:38:46,171 DEBUG [GenreDAOImpl] Reading a Genre (name = Unknown)
2013-05-04 21:38:46,171 DEBUG [GenreService] Genre Unknown not found, creating a new one
2013-05-04 21:38:46,171 DEBUG [GenreDAOImpl] Creating a new Genre (name = Unknown)
2013-05-04 21:38:46,187 DEBUG [CoverImageService] Resizing and storing cover art image
2013-05-04 21:38:46,187 DEBUG [ImageUtils] Starting image resize, size = 114456 bytes
2013-05-04 21:38:46,359 DEBUG [ImageUtils] Returning resized image, size = 4746 bytes
2013-05-04 21:38:46,359 DEBUG [CoverImageService] Image successfully resized
2013-05-04 21:38:46,359 DEBUG [CoverImageDAOImpl] Creating a new ImageCover (length = 4746)
2013-05-04 21:38:46,359 DEBUG [VideoDAOImpl] Creating a new Video (title = test2)
2013-05-04 21:38:46,359 DEBUG [PersonDAOImpl] Adding a Person Unknown to media item 1401 as DIRECTOR
2013-05-04 21:38:46,359 DEBUG [PersonDAOImpl] Reading a Person (name = Unknown)
2013-05-04 21:38:46,359 DEBUG [PersonDAOImpl] Creating a new Person (name = Unknown)
2013-05-04 21:38:46,359 DEBUG [PersonDAOImpl] Checking if person 357 has a role DIRECTOR for media item 1401
2013-05-04 21:38:46,375 DEBUG [PersonDAOImpl] Adding a Person Unknown to media item 1401 as PRODUCER
2013-05-04 21:38:46,375 DEBUG [PersonDAOImpl] Reading a Person (name = Unknown)
2013-05-04 21:38:46,375 DEBUG [PersonDAOImpl] Checking if person 357 has a role PRODUCER for media item 1401
2013-05-04 21:38:46,375 DEBUG [PersonDAOImpl] Adding a Person Unknown to media item 1401 as ACTOR
2013-05-04 21:38:46,375 DEBUG [PersonDAOImpl] Reading a Person (name = Unknown)
2013-05-04 21:38:46,375 DEBUG [PersonDAOImpl] Checking if person 357 has a role ACTOR for media item 1401
2013-05-04 21:38:46,375 DEBUG [MetadataDescriptorDAOImpl] Creating a new MetadataDescriptor (type = EMBEDDED, mediaItemId = 1401)
2013-05-04 21:38:46,375 INFO  [LibraryAdditionsCheckerThread] Added file 'test2.mkv' (title: test2) to Library
2013-05-04 21:38:46,375 DEBUG [RepositoryDAOImpl] Marking Repository 11 as scanned with current timestamp
2013-05-04 21:38:46,484 DEBUG [AbstractCDSLibraryIndexingListener] Library updated, notifying CDS
2013-05-04 21:38:46,484 DEBUG [LocalContentCacheDecorator] Cleared cache (local_resetafterplay)
2013-05-04 21:38:46,484 DEBUG [LocalContentCacheDecorator] Cleared cache (local_default)
2013-05-04 21:39:24,718 DEBUG [RendererDAOImpl] Retrieving list of all stored renderers
2013-05-04 21:39:26,828 DEBUG [RendererDAOImpl] Retrieving list of all stored renderers
2013-05-04 21:39:28,750 DEBUG [RendererDAOImpl] Retrieving list of all stored renderers
2013-05-04 21:39:32,921 DEBUG [FeedUpdaterThread] Checking for new and expired online resources
2013-05-04 21:39:32,921 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2013-05-04 21:39:44,062 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.39:54196s
2013-05-04 21:39:44,062 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.39:54196
2013-05-04 21:39:44,234 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.39:54196s
2013-05-04 21:39:44,234 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.39:54196
2013-05-04 21:39:44,437 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.39:54196s
2013-05-04 21:39:44,437 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.39:54196
2013-05-04 21:39:44,687 DEBUG [ServiioHttpService] Incoming request from /192.168.1.39:43101: GET /deviceDescription/057e814f-d039-3103-87b8-24793c5a6859 HTTP/1.1, headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:39:40 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";]]
2013-05-04 21:39:44,687 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:39:44,687 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 057e814f-d039-3103-87b8-24793c5a6859 from 192.168.1.39 (headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:39:40 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";])
2013-05-04 21:39:44,687 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:39:44,687 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:39:40 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";]
2013-05-04 21:39:44,687 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:39:44,687 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Sony BDP-Sx70/SMP-N100 (2010)'
2013-05-04 21:39:44,718 DEBUG [ServiioHttpService] Incoming request from /192.168.1.39:43103: GET /serviceDescription/ConnectionManager HTTP/1.1, headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:39:40 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";]]
2013-05-04 21:39:44,718 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:39:44,718 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2013-05-04 21:39:44,734 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2013-05-04 21:39:44,734 DEBUG [ServiioHttpService] Incoming request from /192.168.1.39:43104: GET /serviceDescription/ContentDirectory HTTP/1.1, headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:39:40 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";]]
2013-05-04 21:39:44,734 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:39:44,734 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2013-05-04 21:39:44,750 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2013-05-04 21:39:44,781 DEBUG [ServiioHttpService] Incoming request from /192.168.1.39:43105: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";,Host: 192.168.1.121:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 280,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"]]
2013-05-04 21:39:44,781 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:39:44,781 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from 192.168.1.39
2013-05-04 21:39:44,781 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:39:44,906 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-05-04 21:39:44,906 DEBUG [ServiioHttpService] Incoming request from /192.168.1.39:43106: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";,Host: 192.168.1.121:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 273,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"]]
2013-05-04 21:39:44,906 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:39:44,906 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"' from 192.168.1.39
2013-05-04 21:39:44,906 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:39:45,093 DEBUG [ConnectionManager] Sending protocol info using profile 'Sony BDP-Sx70/SMP-N100 (2010)'
2013-05-04 21:39:45,109 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-05-04 21:39:45,156 DEBUG [ServiioHttpService] Incoming request from /192.168.1.39:43107: GET /deviceDescription/057e814f-d039-3103-87b8-24793c5a6859 HTTP/1.1, headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:39:40 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";]]
2013-05-04 21:39:45,156 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:39:45,156 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 057e814f-d039-3103-87b8-24793c5a6859 from 192.168.1.39 (headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:39:40 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";])
2013-05-04 21:39:45,156 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:39:45,156 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:39:40 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";]
2013-05-04 21:39:45,156 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:39:45,156 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Sony BDP-Sx70/SMP-N100 (2010)'
2013-05-04 21:39:45,187 DEBUG [ServiioHttpService] Incoming request from /192.168.1.39:43108: GET /serviceDescription/ConnectionManager HTTP/1.1, headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:39:40 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";]]
2013-05-04 21:39:45,187 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:39:45,187 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2013-05-04 21:39:45,187 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2013-05-04 21:39:45,187 DEBUG [ServiioHttpService] Incoming request from /192.168.1.39:43109: GET /serviceDescription/ContentDirectory HTTP/1.1, headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:39:40 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";]]
2013-05-04 21:39:45,187 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:39:45,187 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2013-05-04 21:39:45,187 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2013-05-04 21:39:45,234 DEBUG [ServiioHttpService] Incoming request from /192.168.1.39:43110: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";,Host: 192.168.1.121:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 280,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"]]
2013-05-04 21:39:45,234 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:39:45,234 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from 192.168.1.39
2013-05-04 21:39:45,234 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:39:45,359 DEBUG [ServiioHttpService] Incoming request from /192.168.1.39:43111: GET /icon/smallPNG HTTP/1.1, headers = [transferMode.dlna.org: Interactive,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";,Host: 192.168.1.121:8895]]
2013-05-04 21:39:45,359 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:39:45,359 DEBUG [UPnPIconRequestHandler] UPnP icon request received for icon smallPNG
2013-05-04 21:39:45,406 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-05-04 21:39:45,406 DEBUG [ServiioHttpService] Incoming request from /192.168.1.39:43112: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";,Host: 192.168.1.121:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 273,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"]]
2013-05-04 21:39:45,406 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:39:45,406 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"' from 192.168.1.39
2013-05-04 21:39:45,406 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:39:45,609 DEBUG [ConnectionManager] Sending protocol info using profile 'Sony BDP-Sx70/SMP-N100 (2010)'
2013-05-04 21:39:45,609 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-05-04 21:39:48,859 DEBUG [DiscoverySSDPMessageListener] Received a valid NOTIFY (ssdp:byebye) message from Renderer 00000000-0000-1010-8000-3c077199cf8e from address /192.168.1.39:52323
2013-05-04 21:39:49,078 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /192.168.1.39:54196s
2013-05-04 21:39:49,171 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /192.168.1.39:54196s
2013-05-04 21:39:49,234 DEBUG [DiscoverySSDPMessageListener] Received a valid NOTIFY (ssdp:byebye) message from Renderer 00000000-0000-1010-8000-3c077199cf8e from address /192.168.1.39:52323
2013-05-04 21:39:49,281 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /192.168.1.39:54196s
2013-05-04 21:39:49,671 DEBUG [DiscoverySSDPMessageListener] Received a valid NOTIFY (ssdp:alive) message from Renderer 00000000-0000-1010-8000-3c077199cf8e from address /192.168.1.39:52323

Re: Sony BDP-S590 can't play .MKV files (corrupt or unsuppor

PostPosted: Sat May 04, 2013 1:54 pm
by dukilupp
Continue...

  Code:
2013-05-04 21:39:49,671 DEBUG [RendererManager] Adding renderer Renderer [uuid=00000000-0000-1010-8000-3c077199cf8e, ipAddress=192.168.1.39, name=Blu-ray Disc Player, profileId=bdp2010, manuallyAdded=false, forcedProfile=false, enabled=true, accessGroupId=1] to list of active renderers
2013-05-04 21:39:49,937 DEBUG [DiscoverySSDPMessageListener] Received a valid NOTIFY (ssdp:alive) message from Renderer 00000000-0000-1010-8000-3c077199cf8e from address /192.168.1.39:52323
2013-05-04 21:39:58,421 DEBUG [ServiioHttpService] Incoming request from /192.168.1.39:43155: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";,Host: 192.168.1.121:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 595,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#Browse"]]
2013-05-04 21:39:58,421 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:39:58,421 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.1.39
2013-05-04 21:39:58,421 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:39:58,578 DEBUG [ContentDirectory] Browse() called for renderer Blu-ray Disc Player (profile bdp2010) with parameters: objectID = 2, browseFlag = BrowseDirectChildren, filter = av:mediaClass,dc:title,dc:date,@childCount,upnp:class,res,res@resolution,res@size,res@protocolInfo,res@duration,res@bitrate,av:containerClass,res@dlna:ifoFileURI, startIndex = 0, count = 10, sortCriteria =
2013-05-04 21:39:58,578 DEBUG [AccessGroupDAOImpl] Reading a AccessGroup (id = 1)
2013-05-04 21:39:58,609 DEBUG [VideoDAOImpl] Retrieving number of video initials [No_Restriction]
2013-05-04 21:39:58,656 DEBUG [VideoDAOImpl] Retrieving number of videos of type 2 [No_Restriction]
2013-05-04 21:39:58,671 DEBUG [SeriesDAOImpl] Retrieving number of series
2013-05-04 21:39:58,687 DEBUG [RepositoryDAOImpl] Retrieving number of repositories for VIDEO [No_Restriction]
2013-05-04 21:39:58,687 DEBUG [VideoDAOImpl] Retrieving number of 10 last viewed videos [No_Restriction]
2013-05-04 21:39:58,703 DEBUG [VideoDAOImpl] Retrieving number of 10 last added videos
2013-05-04 21:39:58,703 DEBUG [VideoDAOImpl] Retrieving number of videos of type 0 [No_Restriction]
2013-05-04 21:39:58,718 DEBUG [GenreDAOImpl] Retrieving number of genres for VIDEO
2013-05-04 21:39:58,734 DEBUG [PersonDAOImpl] Retrieving number of person initials for role ACTOR
2013-05-04 21:39:58,765 DEBUG [PersonDAOImpl] Retrieving number of person initials for role DIRECTOR
2013-05-04 21:39:58,765 DEBUG [PersonDAOImpl] Retrieving number of person initials for role PRODUCER
2013-05-04 21:39:58,765 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-05-04 21:39:58,796 DEBUG [ServiioHttpService] Incoming request from /192.168.1.39:43156: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";,Host: 192.168.1.121:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 596,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#Browse"]]
2013-05-04 21:39:58,796 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:39:58,796 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.1.39
2013-05-04 21:39:58,796 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:39:58,984 DEBUG [ContentDirectory] Browse() called for renderer Blu-ray Disc Player (profile bdp2010) with parameters: objectID = 2, browseFlag = BrowseDirectChildren, filter = av:mediaClass,dc:title,dc:date,@childCount,upnp:class,res,res@resolution,res@size,res@protocolInfo,res@duration,res@bitrate,av:containerClass,res@dlna:ifoFileURI, startIndex = 10, count = 10, sortCriteria =
2013-05-04 21:39:58,984 DEBUG [AccessGroupDAOImpl] Reading a AccessGroup (id = 1)
2013-05-04 21:39:58,984 DEBUG [PlaylistDAOImpl] Retrieving number of playlists for VIDEO [No_Restriction]
2013-05-04 21:39:59,000 DEBUG [OnlineRepositoryDAOImpl] Retrieving list of [FEED, WEB_RESOURCE] OnlineRepositories for VIDEO [No_Restriction]
2013-05-04 21:39:59,062 DEBUG [OnlineRepositoryDAOImpl] Retrieving list of [LIVE_STREAM] OnlineRepositories for VIDEO [No_Restriction]
2013-05-04 21:39:59,078 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-05-04 21:40:01,453 DEBUG [ServiioHttpService] Incoming request from /192.168.1.39:43158: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";,Host: 192.168.1.121:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 597,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#Browse"]]
2013-05-04 21:40:01,453 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:40:01,453 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.1.39
2013-05-04 21:40:01,453 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:40:01,593 DEBUG [ContentDirectory] Browse() called for renderer Blu-ray Disc Player (profile bdp2010) with parameters: objectID = V_F, browseFlag = BrowseDirectChildren, filter = av:mediaClass,dc:title,dc:date,@childCount,upnp:class,res,res@resolution,res@size,res@protocolInfo,res@duration,res@bitrate,av:containerClass,res@dlna:ifoFileURI, startIndex = 0, count = 10, sortCriteria =
2013-05-04 21:40:01,593 DEBUG [AccessGroupDAOImpl] Reading a AccessGroup (id = 1)
2013-05-04 21:40:01,593 DEBUG [RepositoryDAOImpl] Retrieving list of Repositories for VIDEO (from=0, count=10) [No_Restriction]
2013-05-04 21:40:01,671 DEBUG [FolderDAOImpl] Getting number of VIDEO sub-folders and media items in folder 'root' (filter: ALL) [No_Restriction]
2013-05-04 21:40:01,703 DEBUG [RepositoryDAOImpl] Retrieving number of repositories for VIDEO [No_Restriction]
2013-05-04 21:40:01,703 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2013-05-04 21:40:01,703 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-05-04 21:40:02,921 DEBUG [ServiioHttpService] Incoming request from /192.168.1.39:43159: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";,Host: 192.168.1.121:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 605,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#Browse"]]
2013-05-04 21:40:02,921 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:40:02,921 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.1.39
2013-05-04 21:40:02,921 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:40:03,109 DEBUG [ContentDirectory] Browse() called for renderer Blu-ray Disc Player (profile bdp2010) with parameters: objectID = V_F^FOL_R11, browseFlag = BrowseDirectChildren, filter = av:mediaClass,dc:title,dc:date,@childCount,upnp:class,res,res@resolution,res@size,res@protocolInfo,res@duration,res@bitrate,av:containerClass,res@dlna:ifoFileURI, startIndex = 0, count = 10, sortCriteria =
2013-05-04 21:40:03,109 DEBUG [AccessGroupDAOImpl] Reading a AccessGroup (id = 1)
2013-05-04 21:40:03,109 DEBUG [FolderDAOImpl] Getting number of sub-Folders in folder null [No_Restriction]
2013-05-04 21:40:03,109 DEBUG [VideoDAOImpl] Retrieving list of videos for folder 76 (from=0, count=10) [No_Restriction]
2013-05-04 21:40:03,140 DEBUG [MediaItemDAOImpl] Getting file of media item 1400
2013-05-04 21:40:03,171 DEBUG [GenreDAOImpl] Reading a Genre (id = 17)
2013-05-04 21:40:03,171 DEBUG [VideoDAOImpl] Reading a Video (id = 1400)
2013-05-04 21:40:03,187 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 1400 and profile Sony BDP-Sx70/SMP-N100 (2010)
2013-05-04 21:40:03,187 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file test.mkv: AVC_TS_MP_HD_AC3_ISO
2013-05-04 21:40:03,187 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 1396)
2013-05-04 21:40:03,203 DEBUG [MediaItemDAOImpl] Getting file of media item 1400
2013-05-04 21:40:03,218 DEBUG [MediaItemDAOImpl] Getting file of media item 1401
2013-05-04 21:40:03,218 DEBUG [GenreDAOImpl] Reading a Genre (id = 18)
2013-05-04 21:40:03,218 DEBUG [VideoDAOImpl] Reading a Video (id = 1401)
2013-05-04 21:40:03,218 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 1401 and profile Sony BDP-Sx70/SMP-N100 (2010)
2013-05-04 21:40:03,218 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file test2.mkv: AVC_TS_MP_HD_AC3_ISO
2013-05-04 21:40:03,218 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 1397)
2013-05-04 21:40:03,218 DEBUG [MediaItemDAOImpl] Getting file of media item 1401
2013-05-04 21:40:03,218 DEBUG [FolderDAOImpl] Getting number of VIDEO sub-folders and media items in folder 'root' (filter: ALL) [No_Restriction]
2013-05-04 21:40:03,218 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2013-05-04 21:40:03,218 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-05-04 21:40:13,625 DEBUG [ServiioHttpService] Incoming request from /192.168.1.39:43160: HEAD /resource/1400/MEDIA_ITEM/AVC_TS_MP_HD_AC3_ISO-1/ORIGINAL HTTP/1.1, headers = [User-Agent: UPnP/1.0 DLNADOC/1.50,Host: 192.168.1.121:8895,Accept: */*,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player",X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0",getcontentFeatures.dlna.org: 1]]
2013-05-04 21:40:13,625 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:40:13,625 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:40:13,656 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'Identifier=192.168.1.39, Profile=Sony BDP-Sx70/SMP-N100 (2010)'
2013-05-04 21:40:13,656 DEBUG [ResourceDeliveryProcessor] Request for resource 1400 and type 'MEDIA_ITEM' received
2013-05-04 21:40:13,656 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1400 (local)
2013-05-04 21:40:13,656 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 1400)
2013-05-04 21:40:13,656 DEBUG [VideoDAOImpl] Reading a Video (id = 1400)
2013-05-04 21:40:13,656 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 1400, format AVC_TS_MP_HD_AC3_ISO and profile Sony BDP-Sx70/SMP-N100 (2010)
2013-05-04 21:40:13,656 DEBUG [AbstractTranscodingDeliveryEngine] Getting media info for transcoded version of file test.mkv
2013-05-04 21:40:13,656 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file test.mkv: AVC_TS_MP_HD_AC3_ISO
2013-05-04 21:40:13,671 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/vnd.dlna.mpeg-tts,Date: Sat, 04 May 2013 13:40:13 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/1.2.1,Cache-control: no-cache,contentFeatures.dlna.org: DLNA.ORG_PN=MPEG_TS_SD_NA;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=01500000000000000000000000000000,transferMode.dlna.org: Streaming,realTimeInfo.dlna.org: DLNA.ORG_TLAG=*]]
2013-05-04 21:40:13,703 DEBUG [ServiioHttpService] Incoming request from /192.168.1.39:43160: GET /resource/1400/MEDIA_ITEM/AVC_TS_MP_HD_AC3_ISO-1/ORIGINAL HTTP/1.1, headers = [User-Agent: UPnP/1.0 DLNADOC/1.50,Host: 192.168.1.121:8895,Accept: */*,TimeSeekRange.dlna.org: npt=0-,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player",X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0",getcontentFeatures.dlna.org: 1,Pragma: getIfoFileURI.dlna.org,transferMode.dlna.org: Streaming]]
2013-05-04 21:40:13,703 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:40:13,703 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:40:13,703 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'Identifier=192.168.1.39, Profile=Sony BDP-Sx70/SMP-N100 (2010)'
2013-05-04 21:40:13,703 DEBUG [ResourceDeliveryProcessor] Request for resource 1400 and type 'MEDIA_ITEM' received
2013-05-04 21:40:13,703 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1400 (local)
2013-05-04 21:40:13,703 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 1400)
2013-05-04 21:40:13,703 DEBUG [VideoDAOImpl] Reading a Video (id = 1400)
2013-05-04 21:40:13,703 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 1400, format AVC_TS_MP_HD_AC3_ISO and profile Sony BDP-Sx70/SMP-N100 (2010)
2013-05-04 21:40:13,703 DEBUG [AbstractTranscodingDeliveryEngine] Getting media info for transcoded version of file test.mkv
2013-05-04 21:40:13,703 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file test.mkv: AVC_TS_MP_HD_AC3_ISO
2013-05-04 21:40:13,703 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1400 (local)
2013-05-04 21:40:13,703 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 1400)
2013-05-04 21:40:13,703 DEBUG [VideoDAOImpl] Reading a Video (id = 1400)
2013-05-04 21:40:13,703 DEBUG [VideoDeliveryEngine] Delivering item '1400' for client 'Identifier=192.168.1.39, Profile=Sony BDP-Sx70/SMP-N100 (2010)'
2013-05-04 21:40:13,703 DEBUG [VideoDeliveryEngine] Delivering file 'test.mkv' using transcoding
2013-05-04 21:40:13,703 DEBUG [AbstractTranscodingDeliveryEngine] No suitable transcoding job exists yet, start one for client 'Identifier=192.168.1.39, Profile=Sony BDP-Sx70/SMP-N100 (2010)'
2013-05-04 21:40:13,703 DEBUG [MediaItemDAOImpl] Getting file of media item 1400
2013-05-04 21:40:13,703 DEBUG [FFMPEGWrapper] Invoking FFmpeg to transcode video file: F:\test.mkv
2013-05-04 21:40:13,703 DEBUG [ProcessExecutor] Starting C:\Program Files\Serviio\bin\\..\lib/ffmpeg.exe -fflags +genpts -threads auto -i F:\test.mkv -y -c:v copy -bsf:v h264_mp4toannexb -c:a ac3 -b:a 320k -ac 2 -map 0:0 -map 0:1 -sn -f mpegts C:\WINDOWS\TEMP\Serviio\transcoding-temp-1400-bdp2010-ORIGINAL.stf
2013-05-04 21:40:14,203 DEBUG [FileBasedTranscodingDeliveryStrategy] Sending transcoding stream
2013-05-04 21:40:14,203 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file test.mkv: AVC_TS_MP_HD_AC3_ISO
2013-05-04 21:40:14,203 DEBUG [MediaItemDAOImpl] Marking MediaItem (id = 1400) as read
2013-05-04 21:40:14,218 DEBUG [LocalContentCacheDecorator] Cleared cache (local_resetafterplay)
2013-05-04 21:40:14,218 DEBUG [GETMethodProcessor] Stream entity has length: 50000000000
2013-05-04 21:40:14,218 DEBUG [ResourceTransportRequestHandler] Creating entity with chunked transfer
2013-05-04 21:40:14,218 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/vnd.dlna.mpeg-tts,Date: Sat, 04 May 2013 13:40:14 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/1.2.1,Cache-control: no-cache,TimeSeekRange.dlna.org: npt=0-4500/4500,contentFeatures.dlna.org: DLNA.ORG_PN=MPEG_TS_SD_NA;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=01500000000000000000000000000000,transferMode.dlna.org: Streaming,realTimeInfo.dlna.org: DLNA.ORG_TLAG=*]]
2013-05-04 21:40:14,218 DEBUG [WebServer] I/O error: Connection reset by peer: socket write error
2013-05-04 21:40:14,234 DEBUG [ServiioHttpService] Incoming request from /192.168.1.39:43161: GET /resource/1400/MEDIA_ITEM/AVC_TS_MP_HD_AC3_ISO-1/ORIGINAL HTTP/1.1, headers = [User-Agent: UPnP/1.0 DLNADOC/1.50,Host: 192.168.1.121:8895,Accept: */*,TimeSeekRange.dlna.org: npt=0-,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player",X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0",getcontentFeatures.dlna.org: 1,Pragma: getIfoFileURI.dlna.org,transferMode.dlna.org: Streaming]]
2013-05-04 21:40:14,234 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:40:14,234 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:40:14,234 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'Identifier=192.168.1.39, Profile=Sony BDP-Sx70/SMP-N100 (2010)'
2013-05-04 21:40:14,234 DEBUG [ResourceDeliveryProcessor] Request for resource 1400 and type 'MEDIA_ITEM' received
2013-05-04 21:40:14,234 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1400 (local)
2013-05-04 21:40:14,234 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 1400)
2013-05-04 21:40:14,234 DEBUG [VideoDAOImpl] Reading a Video (id = 1400)
2013-05-04 21:40:14,234 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 1400, format AVC_TS_MP_HD_AC3_ISO and profile Sony BDP-Sx70/SMP-N100 (2010)
2013-05-04 21:40:14,234 DEBUG [AbstractTranscodingDeliveryEngine] Getting media info for transcoded version of file test.mkv
2013-05-04 21:40:14,234 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file test.mkv: AVC_TS_MP_HD_AC3_ISO
2013-05-04 21:40:14,234 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1400 (local)
2013-05-04 21:40:14,234 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 1400)
2013-05-04 21:40:14,234 DEBUG [VideoDAOImpl] Reading a Video (id = 1400)
2013-05-04 21:40:14,234 DEBUG [VideoDeliveryEngine] Delivering item '1400' for client 'Identifier=192.168.1.39, Profile=Sony BDP-Sx70/SMP-N100 (2010)'
2013-05-04 21:40:14,234 DEBUG [VideoDeliveryEngine] Delivering file 'test.mkv' using transcoding
2013-05-04 21:40:14,234 DEBUG [AbstractTranscodingDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'Identifier=192.168.1.39, Profile=Sony BDP-Sx70/SMP-N100 (2010)'
2013-05-04 21:40:14,234 DEBUG [FileBasedTranscodingDeliveryStrategy] Sending transcoding stream
2013-05-04 21:40:14,234 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file test.mkv: AVC_TS_MP_HD_AC3_ISO
2013-05-04 21:40:14,234 DEBUG [MediaItemDAOImpl] Marking MediaItem (id = 1400) as read
2013-05-04 21:40:14,234 DEBUG [LocalContentCacheDecorator] Cleared cache (local_resetafterplay)
2013-05-04 21:40:14,234 DEBUG [GETMethodProcessor] Stream entity has length: 50000000000
2013-05-04 21:40:14,234 DEBUG [ResourceTransportRequestHandler] Creating entity with chunked transfer
2013-05-04 21:40:14,234 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/vnd.dlna.mpeg-tts,Date: Sat, 04 May 2013 13:40:14 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/1.2.1,Cache-control: no-cache,TimeSeekRange.dlna.org: npt=0-4500/4500,contentFeatures.dlna.org: DLNA.ORG_PN=MPEG_TS_SD_NA;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=01500000000000000000000000000000,transferMode.dlna.org: Streaming,realTimeInfo.dlna.org: DLNA.ORG_TLAG=*]]
2013-05-04 21:40:14,484 DEBUG [WebServer] I/O error: Connection reset by peer: socket write error
2013-05-04 21:40:14,531 DEBUG [ServiioHttpService] Incoming request from /192.168.1.39:43162: GET /resource/1400/MEDIA_ITEM/AVC_TS_MP_HD_AC3_ISO-1/ORIGINAL HTTP/1.1, headers = [User-Agent: UPnP/1.0 DLNADOC/1.50,Host: 192.168.1.121:8895,Accept: */*,TimeSeekRange.dlna.org: npt=0-,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player",X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0",getcontentFeatures.dlna.org: 1,Pragma: getIfoFileURI.dlna.org,transferMode.dlna.org: Streaming]]
2013-05-04 21:40:14,531 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:40:14,531 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:40:14,531 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'Identifier=192.168.1.39, Profile=Sony BDP-Sx70/SMP-N100 (2010)'
2013-05-04 21:40:14,531 DEBUG [ResourceDeliveryProcessor] Request for resource 1400 and type 'MEDIA_ITEM' received
2013-05-04 21:40:14,531 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1400 (local)
2013-05-04 21:40:14,531 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 1400)
2013-05-04 21:40:14,531 DEBUG [VideoDAOImpl] Reading a Video (id = 1400)
2013-05-04 21:40:14,531 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 1400, format AVC_TS_MP_HD_AC3_ISO and profile Sony BDP-Sx70/SMP-N100 (2010)
2013-05-04 21:40:14,531 DEBUG [AbstractTranscodingDeliveryEngine] Getting media info for transcoded version of file test.mkv
2013-05-04 21:40:14,531 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file test.mkv: AVC_TS_MP_HD_AC3_ISO
2013-05-04 21:40:14,531 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1400 (local)
2013-05-04 21:40:14,531 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 1400)
2013-05-04 21:40:14,531 DEBUG [VideoDAOImpl] Reading a Video (id = 1400)
2013-05-04 21:40:14,531 DEBUG [VideoDeliveryEngine] Delivering item '1400' for client 'Identifier=192.168.1.39, Profile=Sony BDP-Sx70/SMP-N100 (2010)'
2013-05-04 21:40:14,531 DEBUG [VideoDeliveryEngine] Delivering file 'test.mkv' using transcoding
2013-05-04 21:40:14,531 DEBUG [AbstractTranscodingDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'Identifier=192.168.1.39, Profile=Sony BDP-Sx70/SMP-N100 (2010)'
2013-05-04 21:40:14,531 DEBUG [FileBasedTranscodingDeliveryStrategy] Sending transcoding stream
2013-05-04 21:40:14,531 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file test.mkv: AVC_TS_MP_HD_AC3_ISO
2013-05-04 21:40:14,531 DEBUG [MediaItemDAOImpl] Marking MediaItem (id = 1400) as read
2013-05-04 21:40:14,531 DEBUG [LocalContentCacheDecorator] Cleared cache (local_resetafterplay)
2013-05-04 21:40:14,531 DEBUG [GETMethodProcessor] Stream entity has length: 50000000000
2013-05-04 21:40:14,531 DEBUG [ResourceTransportRequestHandler] Creating entity with chunked transfer
2013-05-04 21:40:14,531 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/vnd.dlna.mpeg-tts,Date: Sat, 04 May 2013 13:40:14 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/1.2.1,Cache-control: no-cache,TimeSeekRange.dlna.org: npt=0-4500/4500,contentFeatures.dlna.org: DLNA.ORG_PN=MPEG_TS_SD_NA;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=01500000000000000000000000000000,transferMode.dlna.org: Streaming,realTimeInfo.dlna.org: DLNA.ORG_TLAG=*]]
2013-05-04 21:40:15,015 DEBUG [WebServer] I/O error: Connection reset by peer: socket write error
2013-05-04 21:40:15,046 DEBUG [ServiioHttpService] Incoming request from /192.168.1.39:43163: GET /resource/1400/MEDIA_ITEM/AVC_TS_MP_HD_AC3_ISO-1/ORIGINAL HTTP/1.1, headers = [User-Agent: UPnP/1.0 DLNADOC/1.50,Host: 192.168.1.121:8895,Accept: */*,TimeSeekRange.dlna.org: npt=0-,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player",X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0",getcontentFeatures.dlna.org: 1,Pragma: getIfoFileURI.dlna.org,transferMode.dlna.org: Streaming]]
2013-05-04 21:40:15,046 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:40:15,046 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:40:15,046 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'Identifier=192.168.1.39, Profile=Sony BDP-Sx70/SMP-N100 (2010)'
2013-05-04 21:40:15,046 DEBUG [ResourceDeliveryProcessor] Request for resource 1400 and type 'MEDIA_ITEM' received
2013-05-04 21:40:15,046 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1400 (local)
2013-05-04 21:40:15,046 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 1400)
2013-05-04 21:40:15,046 DEBUG [VideoDAOImpl] Reading a Video (id = 1400)
2013-05-04 21:40:15,046 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 1400, format AVC_TS_MP_HD_AC3_ISO and profile Sony BDP-Sx70/SMP-N100 (2010)
2013-05-04 21:40:15,046 DEBUG [AbstractTranscodingDeliveryEngine] Getting media info for transcoded version of file test.mkv
2013-05-04 21:40:15,046 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file test.mkv: AVC_TS_MP_HD_AC3_ISO
2013-05-04 21:40:15,046 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1400 (local)
2013-05-04 21:40:15,046 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 1400)
2013-05-04 21:40:15,046 DEBUG [VideoDAOImpl] Reading a Video (id = 1400)
2013-05-04 21:40:15,046 DEBUG [VideoDeliveryEngine] Delivering item '1400' for client 'Identifier=192.168.1.39, Profile=Sony BDP-Sx70/SMP-N100 (2010)'
2013-05-04 21:40:15,046 DEBUG [VideoDeliveryEngine] Delivering file 'test.mkv' using transcoding
2013-05-04 21:40:15,046 DEBUG [AbstractTranscodingDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'Identifier=192.168.1.39, Profile=Sony BDP-Sx70/SMP-N100 (2010)'
2013-05-04 21:40:15,046 DEBUG [FileBasedTranscodingDeliveryStrategy] Sending transcoding stream
2013-05-04 21:40:15,046 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file test.mkv: AVC_TS_MP_HD_AC3_ISO
2013-05-04 21:40:15,046 DEBUG [MediaItemDAOImpl] Marking MediaItem (id = 1400) as read
2013-05-04 21:40:15,062 DEBUG [LocalContentCacheDecorator] Cleared cache (local_resetafterplay)
2013-05-04 21:40:15,062 DEBUG [GETMethodProcessor] Stream entity has length: 50000000000
2013-05-04 21:40:15,062 DEBUG [ResourceTransportRequestHandler] Creating entity with chunked transfer
2013-05-04 21:40:15,062 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/vnd.dlna.mpeg-tts,Date: Sat, 04 May 2013 13:40:15 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/1.2.1,Cache-control: no-cache,TimeSeekRange.dlna.org: npt=0-4500/4500,contentFeatures.dlna.org: DLNA.ORG_PN=MPEG_TS_SD_NA;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=01500000000000000000000000000000,transferMode.dlna.org: Streaming,realTimeInfo.dlna.org: DLNA.ORG_TLAG=*]]
2013-05-04 21:40:15,562 DEBUG [WebServer] I/O error: Connection reset by peer: socket write error
2013-05-04 21:40:16,000 DEBUG [ServiioHttpService] Incoming request from /192.168.1.39:43164: GET /resource/1400/MEDIA_ITEM/AVC_TS_MP_HD_AC3_ISO-1/ORIGINAL HTTP/1.1, headers = [User-Agent: UPnP/1.0 DLNADOC/1.50,Host: 192.168.1.121:8895,Accept: */*,TimeSeekRange.dlna.org: npt=0-,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player",X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0",getcontentFeatures.dlna.org: 1,Pragma: getIfoFileURI.dlna.org,transferMode.dlna.org: Streaming]]
2013-05-04 21:40:16,000 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:40:16,000 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:40:16,000 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'Identifier=192.168.1.39, Profile=Sony BDP-Sx70/SMP-N100 (2010)'
2013-05-04 21:40:16,000 DEBUG [ResourceDeliveryProcessor] Request for resource 1400 and type 'MEDIA_ITEM' received
2013-05-04 21:40:16,000 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1400 (local)
2013-05-04 21:40:16,000 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 1400)
2013-05-04 21:40:16,000 DEBUG [VideoDAOImpl] Reading a Video (id = 1400)
2013-05-04 21:40:16,000 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 1400, format AVC_TS_MP_HD_AC3_ISO and profile Sony BDP-Sx70/SMP-N100 (2010)
2013-05-04 21:40:16,000 DEBUG [AbstractTranscodingDeliveryEngine] Getting media info for transcoded version of file test.mkv
2013-05-04 21:40:16,000 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file test.mkv: AVC_TS_MP_HD_AC3_ISO
2013-05-04 21:40:16,000 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1400 (local)
2013-05-04 21:40:16,000 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 1400)
2013-05-04 21:40:16,000 DEBUG [VideoDAOImpl] Reading a Video (id = 1400)
2013-05-04 21:40:16,000 DEBUG [VideoDeliveryEngine] Delivering item '1400' for client 'Identifier=192.168.1.39, Profile=Sony BDP-Sx70/SMP-N100 (2010)'
2013-05-04 21:40:16,000 DEBUG [VideoDeliveryEngine] Delivering file 'test.mkv' using transcoding
2013-05-04 21:40:16,000 DEBUG [AbstractTranscodingDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'Identifier=192.168.1.39, Profile=Sony BDP-Sx70/SMP-N100 (2010)'
2013-05-04 21:40:16,000 DEBUG [FileBasedTranscodingDeliveryStrategy] Sending transcoding stream
2013-05-04 21:40:16,000 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file test.mkv: AVC_TS_MP_HD_AC3_ISO
2013-05-04 21:40:16,000 DEBUG [MediaItemDAOImpl] Marking MediaItem (id = 1400) as read
2013-05-04 21:40:16,000 DEBUG [LocalContentCacheDecorator] Cleared cache (local_resetafterplay)
2013-05-04 21:40:16,000 DEBUG [GETMethodProcessor] Stream entity has length: 50000000000
2013-05-04 21:40:16,000 DEBUG [ResourceTransportRequestHandler] Creating entity with chunked transfer
2013-05-04 21:40:16,000 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/vnd.dlna.mpeg-tts,Date: Sat, 04 May 2013 13:40:16 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/1.2.1,Cache-control: no-cache,TimeSeekRange.dlna.org: npt=0-4500/4500,contentFeatures.dlna.org: DLNA.ORG_PN=MPEG_TS_SD_NA;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=01500000000000000000000000000000,transferMode.dlna.org: Streaming,realTimeInfo.dlna.org: DLNA.ORG_TLAG=*]]
2013-05-04 21:40:16,421 DEBUG [WebServer] I/O error: Connection reset by peer: socket write error
2013-05-04 21:40:16,593 DEBUG [ServiioHttpService] Incoming request from /192.168.1.39:43165: GET /resource/1400/MEDIA_ITEM/AVC_TS_MP_HD_AC3_ISO-1/ORIGINAL HTTP/1.1, headers = [User-Agent: UPnP/1.0 DLNADOC/1.50,Host: 192.168.1.121:8895,Accept: */*,TimeSeekRange.dlna.org: npt=0-,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player",X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0",getcontentFeatures.dlna.org: 1,Pragma: getIfoFileURI.dlna.org,transferMode.dlna.org: Streaming]]
2013-05-04 21:40:16,593 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:40:16,593 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:40:16,593 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'Identifier=192.168.1.39, Profile=Sony BDP-Sx70/SMP-N100 (2010)'
2013-05-04 21:40:16,593 DEBUG [ResourceDeliveryProcessor] Request for resource 1400 and type 'MEDIA_ITEM' received
2013-05-04 21:40:16,593 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1400 (local)
2013-05-04 21:40:16,593 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 1400)
2013-05-04 21:40:16,593 DEBUG [VideoDAOImpl] Reading a Video (id = 1400)
2013-05-04 21:40:16,593 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 1400, format AVC_TS_MP_HD_AC3_ISO and profile Sony BDP-Sx70/SMP-N100 (2010)
2013-05-04 21:40:16,593 DEBUG [AbstractTranscodingDeliveryEngine] Getting media info for transcoded version of file test.mkv
2013-05-04 21:40:16,593 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file test.mkv: AVC_TS_MP_HD_AC3_ISO
2013-05-04 21:40:16,593 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1400 (local)
2013-05-04 21:40:16,593 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 1400)
2013-05-04 21:40:16,593 DEBUG [VideoDAOImpl] Reading a Video (id = 1400)
2013-05-04 21:40:16,593 DEBUG [VideoDeliveryEngine] Delivering item '1400' for client 'Identifier=192.168.1.39, Profile=Sony BDP-Sx70/SMP-N100 (2010)'
2013-05-04 21:40:16,593 DEBUG [VideoDeliveryEngine] Delivering file 'test.mkv' using transcoding
2013-05-04 21:40:16,593 DEBUG [AbstractTranscodingDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'Identifier=192.168.1.39, Profile=Sony BDP-Sx70/SMP-N100 (2010)'
2013-05-04 21:40:16,593 DEBUG [FileBasedTranscodingDeliveryStrategy] Sending transcoding stream
2013-05-04 21:40:16,593 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file test.mkv: AVC_TS_MP_HD_AC3_ISO
2013-05-04 21:40:16,593 DEBUG [MediaItemDAOImpl] Marking MediaItem (id = 1400) as read
2013-05-04 21:40:16,593 DEBUG [LocalContentCacheDecorator] Cleared cache (local_resetafterplay)
2013-05-04 21:40:16,593 DEBUG [GETMethodProcessor] Stream entity has length: 50000000000
2013-05-04 21:40:16,593 DEBUG [ResourceTransportRequestHandler] Creating entity with chunked transfer
2013-05-04 21:40:16,593 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/vnd.dlna.mpeg-tts,Date: Sat, 04 May 2013 13:40:16 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/1.2.1,Cache-control: no-cache,TimeSeekRange.dlna.org: npt=0-4500/4500,contentFeatures.dlna.org: DLNA.ORG_PN=MPEG_TS_SD_NA;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=01500000000000000000000000000000,transferMode.dlna.org: Streaming,realTimeInfo.dlna.org: DLNA.ORG_TLAG=*]]
2013-05-04 21:40:17,218 DEBUG [WebServer] I/O error: Connection reset by peer: socket write error
2013-05-04 21:40:17,234 DEBUG [ServiioHttpService] Incoming request from /192.168.1.39:43166: HEAD /resource/1400/MEDIA_ITEM/AVC_TS_MP_HD_AC3_ISO-1/ORIGINAL HTTP/1.1, headers = [User-Agent: UPnP/1.0 DLNADOC/1.50,Host: 192.168.1.121:8895,Accept: */*,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player",X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0",getcontentFeatures.dlna.org: 1]]
2013-05-04 21:40:17,234 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:40:17,234 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:40:17,234 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'Identifier=192.168.1.39, Profile=Sony BDP-Sx70/SMP-N100 (2010)'
2013-05-04 21:40:17,234 DEBUG [ResourceDeliveryProcessor] Request for resource 1400 and type 'MEDIA_ITEM' received
2013-05-04 21:40:17,234 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1400 (local)
2013-05-04 21:40:17,234 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 1400)
2013-05-04 21:40:17,234 DEBUG [VideoDAOImpl] Reading a Video (id = 1400)
2013-05-04 21:40:17,234 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 1400, format AVC_TS_MP_HD_AC3_ISO and profile Sony BDP-Sx70/SMP-N100 (2010)
2013-05-04 21:40:17,234 DEBUG [AbstractTranscodingDeliveryEngine] Getting media info for transcoded version of file test.mkv
2013-05-04 21:40:17,234 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file test.mkv: AVC_TS_MP_HD_AC3_ISO
2013-05-04 21:40:17,234 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/vnd.dlna.mpeg-tts,Date: Sat, 04 May 2013 13:40:17 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/1.2.1,Cache-control: no-cache,contentFeatures.dlna.org: DLNA.ORG_PN=MPEG_TS_SD_NA;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=01500000000000000000000000000000,transferMode.dlna.org: Streaming,realTimeInfo.dlna.org: DLNA.ORG_TLAG=*]]
2013-05-04 21:40:17,234 DEBUG [ServiioHttpService] Incoming request from /192.168.1.39:43166: GET /resource/1400/MEDIA_ITEM/AVC_TS_MP_HD_AC3_ISO-1/ORIGINAL HTTP/1.1, headers = [User-Agent: UPnP/1.0 DLNADOC/1.50,Host: 192.168.1.121:8895,Accept: */*,TimeSeekRange.dlna.org: npt=0-,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player",X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0",getcontentFeatures.dlna.org: 1,Pragma: getIfoFileURI.dlna.org,transferMode.dlna.org: Streaming]]
2013-05-04 21:40:17,234 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:40:17,234 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:40:17,234 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'Identifier=192.168.1.39, Profile=Sony BDP-Sx70/SMP-N100 (2010)'
2013-05-04 21:40:17,234 DEBUG [ResourceDeliveryProcessor] Request for resource 1400 and type 'MEDIA_ITEM' received
2013-05-04 21:40:17,234 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1400 (local)
2013-05-04 21:40:17,234 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 1400)
2013-05-04 21:40:17,234 DEBUG [VideoDAOImpl] Reading a Video (id = 1400)
2013-05-04 21:40:17,234 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 1400, format AVC_TS_MP_HD_AC3_ISO and profile Sony BDP-Sx70/SMP-N100 (2010)
2013-05-04 21:40:17,234 DEBUG [AbstractTranscodingDeliveryEngine] Getting media info for transcoded version of file test.mkv
2013-05-04 21:40:17,234 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file test.mkv: AVC_TS_MP_HD_AC3_ISO
2013-05-04 21:40:17,234 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1400 (local)
2013-05-04 21:40:17,234 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 1400)
2013-05-04 21:40:17,234 DEBUG [VideoDAOImpl] Reading a Video (id = 1400)
2013-05-04 21:40:17,234 DEBUG [VideoDeliveryEngine] Delivering item '1400' for client 'Identifier=192.168.1.39, Profile=Sony BDP-Sx70/SMP-N100 (2010)'
2013-05-04 21:40:17,234 DEBUG [VideoDeliveryEngine] Delivering file 'test.mkv' using transcoding
2013-05-04 21:40:17,234 DEBUG [AbstractTranscodingDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'Identifier=192.168.1.39, Profile=Sony BDP-Sx70/SMP-N100 (2010)'
2013-05-04 21:40:17,234 DEBUG [FileBasedTranscodingDeliveryStrategy] Sending transcoding stream
2013-05-04 21:40:17,234 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file test.mkv: AVC_TS_MP_HD_AC3_ISO
2013-05-04 21:40:17,234 DEBUG [MediaItemDAOImpl] Marking MediaItem (id = 1400) as read
2013-05-04 21:40:17,250 DEBUG [LocalContentCacheDecorator] Cleared cache (local_resetafterplay)
2013-05-04 21:40:17,250 DEBUG [GETMethodProcessor] Stream entity has length: 50000000000
2013-05-04 21:40:17,250 DEBUG [ResourceTransportRequestHandler] Creating entity with chunked transfer
2013-05-04 21:40:17,250 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/vnd.dlna.mpeg-tts,Date: Sat, 04 May 2013 13:40:17 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/1.2.1,Cache-control: no-cache,TimeSeekRange.dlna.org: npt=0-4500/4500,contentFeatures.dlna.org: DLNA.ORG_PN=MPEG_TS_SD_NA;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=01500000000000000000000000000000,transferMode.dlna.org: Streaming,realTimeInfo.dlna.org: DLNA.ORG_TLAG=*]]
2013-05-04 21:40:17,578 DEBUG [WebServer] I/O error: Software caused connection abort: socket write error
2013-05-04 21:40:17,734 DEBUG [ServiioHttpService] Incoming request from /192.168.1.39:43167: GET /resource/1400/MEDIA_ITEM/AVC_TS_MP_HD_AC3_ISO-1/ORIGINAL HTTP/1.1, headers = [User-Agent: UPnP/1.0 DLNADOC/1.50,Host: 192.168.1.121:8895,Accept: */*,TimeSeekRange.dlna.org: npt=0-,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player",X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0",getcontentFeatures.dlna.org: 1,Pragma: getIfoFileURI.dlna.org,transferMode.dlna.org: Streaming]]
2013-05-04 21:40:17,734 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:40:17,734 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.39
2013-05-04 21:40:17,734 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'Identifier=192.168.1.39, Profile=Sony BDP-Sx70/SMP-N100 (2010)'
2013-05-04 21:40:17,734 DEBUG [ResourceDeliveryProcessor] Request for resource 1400 and type 'MEDIA_ITEM' received
2013-05-04 21:40:17,734 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1400 (local)
2013-05-04 21:40:17,734 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 1400)
2013-05-04 21:40:17,734 DEBUG [VideoDAOImpl] Reading a Video (id = 1400)
2013-05-04 21:40:17,734 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 1400, format AVC_TS_MP_HD_AC3_ISO and profile Sony BDP-Sx70/SMP-N100 (2010)
2013-05-04 21:40:17,734 DEBUG [AbstractTranscodingDeliveryEngine] Getting media info for transcoded version of file test.mkv
2013-05-04 21:40:17,734 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file test.mkv: AVC_TS_MP_HD_AC3_ISO
2013-05-04 21:40:17,734 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1400 (local)
2013-05-04 21:40:17,734 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 1400)
2013-05-04 21:40:17,734 DEBUG [VideoDAOImpl] Reading a Video (id = 1400)
2013-05-04 21:40:17,734 DEBUG [VideoDeliveryEngine] Delivering item '1400' for client 'Identifier=192.168.1.39, Profile=Sony BDP-Sx70/SMP-N100 (2010)'
2013-05-04 21:40:17,734 DEBUG [VideoDeliveryEngine] Delivering file 'test.mkv' using transcoding
2013-05-04 21:40:17,734 DEBUG [AbstractTranscodingDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'Identifier=192.168.1.39, Profile=Sony BDP-Sx70/SMP-N100 (2010)'
2013-05-04 21:40:17,734 DEBUG [FileBasedTranscodingDeliveryStrategy] Sending transcoding stream
2013-05-04 21:40:17,734 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file test.mkv: AVC_TS_MP_HD_AC3_ISO
2013-05-04 21:40:17,734 DEBUG [MediaItemDAOImpl] Marking MediaItem (id = 1400) as read
2013-05-04 21:40:17,734 DEBUG [LocalContentCacheDecorator] Cleared cache (local_resetafterplay)
2013-05-04 21:40:17,734 DEBUG [GETMethodProcessor] Stream entity has length: 50000000000
2013-05-04 21:40:17,734 DEBUG [ResourceTransportRequestHandler] Creating entity with chunked transfer
2013-05-04 21:40:17,734 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/vnd.dlna.mpeg-tts,Date: Sat, 04 May 2013 13:40:17 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/1.2.1,Cache-control: no-cache,TimeSeekRange.dlna.org: npt=0-4500/4500,contentFeatures.dlna.org: DLNA.ORG_PN=MPEG_TS_SD_NA;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=01500000000000000000000000000000,transferMode.dlna.org: Streaming,realTimeInfo.dlna.org: DLNA.ORG_TLAG=*]]
2013-05-04 21:40:32,921 DEBUG [FeedUpdaterThread] Checking for new and expired online resources
2013-05-04 21:40:32,921 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2013-05-04 21:40:59,156 DEBUG [DiscoverySSDPMessageListener] Received a valid NOTIFY (ssdp:byebye) message from Renderer 00000000-0000-1010-8000-106f3f796feb from address /192.168.1.143:52323
2013-05-04 21:40:59,156 DEBUG [RendererManager] Removing renderer 00000000-0000-1010-8000-106f3f796feb from list of active renderers
2013-05-04 21:40:59,390 DEBUG [DiscoverySSDPMessageListener] Received a valid NOTIFY (ssdp:byebye) message from Renderer 00000000-0000-1010-8000-106f3f796feb from address /192.168.1.143:52323
2013-05-04 21:40:59,531 DEBUG [DiscoverySSDPMessageListener] Received a valid NOTIFY (ssdp:byebye) message from Renderer 00000000-0000-1010-8000-106f3f796feb from address /192.168.1.143:52323
2013-05-04 21:40:59,765 DEBUG [DiscoverySSDPMessageListener] Received a valid NOTIFY (ssdp:byebye) message from Renderer 00000000-0000-1010-8000-106f3f796feb from address /192.168.1.143:52323
2013-05-04 21:41:03,750 DEBUG [DiscoverySSDPMessageListener] Received a valid NOTIFY (ssdp:byebye) message from Renderer 00000000-0000-1010-8000-106f3f796feb from address /192.168.1.143:52323
2013-05-04 21:41:04,062 DEBUG [DiscoverySSDPMessageListener] Received a valid NOTIFY (ssdp:byebye) message from Renderer 00000000-0000-1010-8000-106f3f796feb from address /192.168.1.143:52323
2013-05-04 21:41:05,187 DEBUG [DiscoverySSDPMessageListener] Received a valid NOTIFY (ssdp:byebye) message from Renderer 00000000-0000-1010-8000-106f3f796feb from address /192.168.1.143:52323
2013-05-04 21:41:05,421 DEBUG [DiscoverySSDPMessageListener] Received a valid NOTIFY (ssdp:byebye) message from Renderer 00000000-0000-1010-8000-106f3f796feb from address /192.168.1.143:52323
2013-05-04 21:41:05,625 DEBUG [DiscoverySSDPMessageListener] Received a valid NOTIFY (ssdp:alive) message from Renderer 00000000-0000-1010-8000-106f3f796feb from address /192.168.1.143:52323
2013-05-04 21:41:05,625 DEBUG [RendererManager] Adding renderer Renderer [uuid=00000000-0000-1010-8000-106f3f796feb, ipAddress=192.168.1.143, name=KDL-32HX75A, profileId=1, manuallyAdded=false, forcedProfile=false, enabled=true, accessGroupId=1] to list of active renderers
2013-05-04 21:41:05,859 DEBUG [DiscoverySSDPMessageListener] Received a valid NOTIFY (ssdp:alive) message from Renderer 00000000-0000-1010-8000-106f3f796feb from address /192.168.1.143:52323
2013-05-04 21:41:28,296 DEBUG [WebServer] I/O error: Connection reset by peer: socket write error
2013-05-04 21:41:32,921 DEBUG [FeedUpdaterThread] Checking for new and expired online resources
2013-05-04 21:41:32,921 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2013-05-04 21:41:44,000 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth0 (Atheros AR8131 PCI-E Gigabit Ethernet Controller - Packet Scheduler Miniport) and address 192.168.1.121, timeout = 0
2013-05-04 21:41:44,000 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 057e814f-d039-3103-87b8-24793c5a6859
2013-05-04 21:41:44,656 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:50043: GET /deviceDescription/057e814f-d039-3103-87b8-24793c5a6859 HTTP/1.1, headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:41:41 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";]]
2013-05-04 21:41:44,656 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:41:44,656 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 057e814f-d039-3103-87b8-24793c5a6859 from 192.168.1.143 (headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:41:41 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";])
2013-05-04 21:41:44,656 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:41:44,656 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:41:41 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";]
2013-05-04 21:41:44,656 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:41:44,656 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Generic DLNA profile'
2013-05-04 21:41:44,671 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:50044: GET /serviceDescription/ConnectionManager HTTP/1.1, headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:41:41 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";]]
2013-05-04 21:41:44,671 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:41:44,671 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2013-05-04 21:41:44,671 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2013-05-04 21:41:44,703 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:50045: GET /serviceDescription/ContentDirectory HTTP/1.1, headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:41:41 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";]]
2013-05-04 21:41:44,703 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:41:44,703 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2013-05-04 21:41:44,703 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2013-05-04 21:41:44,734 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:50046: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";,Host: 192.168.1.121:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 280,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"]]
2013-05-04 21:41:44,734 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:41:44,734 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from 192.168.1.143
2013-05-04 21:41:44,734 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:41:44,906 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-05-04 21:41:44,906 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:50047: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";,Host: 192.168.1.121:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 273,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"]]
2013-05-04 21:41:44,906 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:41:44,906 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"' from 192.168.1.143
2013-05-04 21:41:44,906 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:41:45,093 DEBUG [ConnectionManager] Sending protocol info using profile 'Generic DLNA profile'
2013-05-04 21:41:45,109 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-05-04 21:41:45,171 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:50048: GET /deviceDescription/057e814f-d039-3103-87b8-24793c5a6859 HTTP/1.1, headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:41:42 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";]]
2013-05-04 21:41:45,171 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:41:45,171 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 057e814f-d039-3103-87b8-24793c5a6859 from 192.168.1.143 (headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:41:42 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";])
2013-05-04 21:41:45,171 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:41:45,171 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:41:42 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";]
2013-05-04 21:41:45,187 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:41:45,187 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Generic DLNA profile'
2013-05-04 21:41:45,203 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:50049: GET /serviceDescription/ConnectionManager HTTP/1.1, headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:41:42 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";]]
2013-05-04 21:41:45,203 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:41:45,203 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2013-05-04 21:41:45,203 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2013-05-04 21:41:45,218 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:50050: GET /serviceDescription/ContentDirectory HTTP/1.1, headers = [Host: 192.168.1.121:8895,Date: Sat, 04 May 2013 13:41:42 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";]]
2013-05-04 21:41:45,218 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:41:45,218 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2013-05-04 21:41:45,218 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2013-05-04 21:41:45,250 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:50051: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";,Host: 192.168.1.121:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 280,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"]]
2013-05-04 21:41:45,250 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:41:45,250 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from 192.168.1.143
2013-05-04 21:41:45,250 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:41:45,406 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-05-04 21:41:45,421 DEBUG [ServiioHttpService] Incoming request from /192.168.1.143:50052: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32HX75A";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-32HX75A"; mv="1.7";,Host: 192.168.1.121:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 273,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"]]
2013-05-04 21:41:45,421 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:41:45,421 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"' from 192.168.1.143
2013-05-04 21:41:45,421 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.143
2013-05-04 21:41:45,609 DEBUG [ConnectionManager] Sending protocol info using profile 'Generic DLNA profile'
2013-05-04 21:41:45,609 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-05-04 21:41:45,812 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:04:04 (advertisement duration is 1800 sec.)
All times are UTC
Page 1 of 1
Powered by phpBB © 2000, 2002, 2005, 2007 phpBB Group
http://www.phpbb.com/