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

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

Wed May 01, 2013 5:09 pm by slyguy42o

can you post your logs after trying to play the file?

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 ?

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.

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.

Thu May 02, 2013 2:50 pm by Illico

What is your OS (Windows/MAC/Linux)?

Thu May 02, 2013 3:05 pm by dukilupp

Illico wrote:What is your OS (Windows/MAC/Linux)?

Windows XP

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

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!

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>

Fri May 03, 2013 7:40 am by Illico

Post here : C:\Program Files\Serviio\log\serviio.log

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

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

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

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.)

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

Who is online