Skip to content

FAQ  •  Register  •  Login

problem with pause ff rew

Post a reply
<<

Al_V

Serviio newbie

Posts: 7

Joined: Fri Dec 17, 2010 3:59 am

Post Fri Dec 17, 2010 4:15 am

problem with pause ff rew

I have searched the forum and have not found any answers.
I have a toshiba 55ux600u, and found a toshiba regza profile that will play my .avi's
The pause button pauses the movie as it should, but the problem is I cannot resume play.
The time counter counts forward, but the movie does not play video nor audio.
Rewind and FF also move the time counter but nothing plays.

skip to end works, and skip to beginning returns to the beginning and starts over as it should.

I created a log file in which I pushed pause about 5 seconds into the video, and then pushed play.
The log file is too big to post, so I removed some of it.
The timer advanced without play and then I pushed stop.

I hope someone can help
THANKS!!

2010-12-16 22:54:56,765 DEBUG [ApplicationInstanceManager] Listening for application instances on socket 44331
2010-12-16 22:54:56,781 INFO [MediaServer] ------------------------------------------------------------------------
2010-12-16 22:54:56,781 INFO [MediaServer] Serviio DLNA media streaming server v 0.4.2 (rev. 683706a96104)
2010-12-16 22:54:56,781 INFO [MediaServer] Petr Nejedly 2009-2010
2010-12-16 22:54:56,781 INFO [MediaServer] http://www.serviio.org
2010-12-16 22:54:56,781 INFO [MediaServer]
2010-12-16 22:54:56,781 INFO [MediaServer] Java 1.6.0_18-Sun Microsystems Inc.
2010-12-16 22:54:56,781 INFO [MediaServer] OS Windows 7 amd64 6.1
2010-12-16 22:54:56,781 INFO [MediaServer] File encoding: windows-1252
2010-12-16 22:54:56,781 INFO [MediaServer] ------------------------------------------------------------------------
2010-12-16 22:54:57,062 INFO [RestletServer] Starting Restlet server exposed on localhost:23423
2010-12-16 22:54:57,265 INFO [DBSchemaUpdateExecutor] Checking if DB schema needs to be updated
2010-12-16 22:54:57,359 DEBUG [DBLogDAOImpl] Checking if script 'script-0.1.sql' has been run
2010-12-16 22:55:02,281 DEBUG [DBLogDAOImpl] Checking if script 'script-0.1.1.sql' has been run
2010-12-16 22:55:02,281 DEBUG [DBLogDAOImpl] Checking if script 'script-0.2.sql' has been run
2010-12-16 22:55:02,281 DEBUG [DBLogDAOImpl] Checking if script 'script-0.3.1.sql' has been run
2010-12-16 22:55:02,281 DEBUG [DBLogDAOImpl] Checking if script 'script-0.4.sql' has been run
2010-12-16 22:55:02,281 DEBUG [DBLogDAOImpl] Checking if script 'script-0.4.1.sql' has been run
2010-12-16 22:55:02,281 DEBUG [DBLogDAOImpl] Checking if script 'script-0.4.2.sql' has been run
2010-12-16 22:55:03,187 INFO [ProfilesDefinitionParser] Parsing Profiles definition
2010-12-16 22:55:03,218 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 1
2010-12-16 22:55:04,593 INFO [ProfilesDefinitionParser] Added profile 'Generic DLNA profile' (id=1)
2010-12-16 22:55:04,593 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 2
2010-12-16 22:55:04,937 INFO [ProfilesDefinitionParser] Added profile 'Samsung TV (B-series)' (id=2)
2010-12-16 22:55:04,953 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 3
2010-12-16 22:55:05,203 INFO [ProfilesDefinitionParser] Added profile 'XBox 360' (id=3)
2010-12-16 22:55:05,203 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 4
2010-12-16 22:55:05,265 INFO [ProfilesDefinitionParser] Added profile 'Playstation 3' (id=4)
2010-12-16 22:55:05,265 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 5
2010-12-16 22:55:05,359 INFO [ProfilesDefinitionParser] Added profile 'Samsung TV (A-series)' (id=5)
2010-12-16 22:55:05,359 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 6
2010-12-16 22:55:05,406 INFO [ProfilesDefinitionParser] Added profile 'DirecTV HD-DVR' (id=6)
2010-12-16 22:55:05,406 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 7
2010-12-16 22:55:05,500 INFO [ProfilesDefinitionParser] Added profile 'Samsung TV / player (C-series)' (id=7)
2010-12-16 22:55:05,500 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 8
2010-12-16 22:55:05,546 INFO [ProfilesDefinitionParser] Added profile 'LG BD player' (id=8)
2010-12-16 22:55:05,546 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 9
2010-12-16 22:55:05,859 INFO [ProfilesDefinitionParser] Added profile 'Sony Bravia TV' (id=9)
2010-12-16 22:55:05,859 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 10
2010-12-16 22:55:06,062 INFO [ProfilesDefinitionParser] Added profile 'Sony BD Player' (id=10)
2010-12-16 22:55:06,062 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 11
2010-12-16 22:55:06,187 INFO [ProfilesDefinitionParser] Added profile 'TOSHIBA REGZA' (id=11)
2010-12-16 22:55:06,218 DEBUG [FFMPEGWrapper] Invoking FFMPEG to check if it exists of path C:\Program Files (x86)\Serviio\bin\\..\lib/ffmpeg.exe
2010-12-16 22:55:06,250 DEBUG [ProcessExecutor] Starting C:\Program Files (x86)\Serviio\bin\\..\lib/ffmpeg.exe
2010-12-16 22:55:09,812 DEBUG [ServiioConsole] Initializing Serviio Console
2010-12-16 22:55:09,859 DEBUG [ApplicationInstanceManager] Listening for application instances on socket 44332
2010-12-16 22:55:09,859 INFO [ServiioConsole] Starting Serviio Console
2010-12-16 22:55:09,905 INFO [VideoDeliveryEngine] Cleaning transcode engine and its data
2010-12-16 22:55:10,020 DEBUG [ConfigEntryDAOImpl] Reading all ConfigEntries
2010-12-16 22:55:10,524 DEBUG [VideoDeliveryEngine] Deleting temporary transcoded files from: C:\Windows\TEMP\Serviio
2010-12-16 22:55:11,156 INFO [WebServer] WebServer starting on port 8895
2010-12-16 22:55:11,911 DEBUG [SwingHelper] Choosing java look and feel: com.sun.java.swing.plaf.windows.WindowsLookAndFeel
2010-12-16 22:55:11,926 INFO [Device] Created UPnP Device with UUID: 83403509-60a4-3ac0-bae1-94340baf2344, bound address: 192.168.1.2
2010-12-16 22:55:12,145 INFO [ServiioConsole] Connecting to Serviio REST on localhost:23423
2010-12-16 22:55:12,223 INFO [CompositeCacheManager] Creating cache manager from config file: /cache.ccf
2010-12-16 22:55:12,364 INFO [ThreadPoolManager] thread_pool.default PoolConfiguration = useBoundary = [true] boundarySize = [2000] maximumPoolSize = [150] minimumPoolSize = [4] keepAliveTime = [300000] whenBlockedPolicy = [RUN] startUpSize = [4]
2010-12-16 22:55:12,364 INFO [CompositeCacheConfigurator] Setting default auxiliaries to null
2010-12-16 22:55:12,364 INFO [CompositeCacheConfigurator] No special CompositeCacheAttributes class defined for key [jcs.default.cacheattributes], using default class.
2010-12-16 22:55:12,364 INFO [CompositeCacheConfigurator] setting defaultCompositeCacheAttributes to [ useLateral = true, useRemote = true, useDisk = true, maxObjs = 100, maxSpoolPerRun = -1, diskUsagePattern = 0 ]
2010-12-16 22:55:12,364 INFO [CompositeCacheConfigurator] No special ElementAttribute class defined for key [jcs.default.elementattributes], using default class.
2010-12-16 22:55:12,364 INFO [CompositeCacheConfigurator] setting defaultElementAttributes to [ IS_LATERAL = true, IS_SPOOL = true, IS_REMOTE = true, IS_ETERNAL = true, MaxLifeSeconds = -1, IdleTime = -1, CreateTime = 1292558112364, LastAccessTime = 1292558112364, getTimeToLiveSeconds() = -1, createTime = 1292558112364 ]
2010-12-16 22:55:12,379 INFO [CompositeCacheConfigurator] No special ElementAttribute class defined for key [jcs.region.local_resetafterplay.elementattributes], using default class.
2010-12-16 22:55:12,426 INFO [LRUMemoryCache] initialized LRUMemoryCache for local_resetafterplay
2010-12-16 22:55:12,426 INFO [CompositeCache] Constructed cache with name [local_resetafterplay] and cache attributes [ useLateral = true, useRemote = true, useDisk = true, maxObjs = 1000, maxSpoolPerRun = -1, diskUsagePattern = 0 ]
2010-12-16 22:55:12,426 INFO [CompositeCacheConfigurator] No special ElementAttribute class defined for key [jcs.region.local_default.elementattributes], using default class.
2010-12-16 22:55:12,426 INFO [LRUMemoryCache] initialized LRUMemoryCache for local_default
2010-12-16 22:55:12,426 INFO [CompositeCache] Constructed cache with name [local_default] and cache attributes [ useLateral = true, useRemote = true, useDisk = true, maxObjs = 1000, maxSpoolPerRun = -1, diskUsagePattern = 0 ]
2010-12-16 22:55:12,426 INFO [CompositeCacheConfigurator] Parsed regions [local_resetafterplay, local_default]
2010-12-16 22:55:12,426 INFO [CompositeCacheConfigurator] Finished configuration in 62 ms.
2010-12-16 22:55:12,426 DEBUG [DiscoveryManager] UPNP device 83403509-60a4-3ac0-bae1-94340baf2344 is available
2010-12-16 22:55:12,426 INFO [DiscoveryAdvertisementNotifier] Starting DiscoveryAdvertisementNotifier
2010-12-16 22:55:12,442 INFO [DiscoverySSDPMessageListener] Starting DiscoverySSDPMessageListener using interface 192.168.1.2
2010-12-16 22:55:12,509 INFO [EventSubscriptionExpirationChecker] Starting EventSubscriptionExpirationChecker
2010-12-16 22:55:12,510 INFO [EventDispatcher] Starting EventDispatcher
2010-12-16 22:55:12,545 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface 192.168.1.2
2010-12-16 22:55:12,609 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 83403509-60a4-3ac0-bae1-94340baf2344
2010-12-16 22:55:12,687 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:14:17
2010-12-16 22:55:12,791 DEBUG [WebServer] Incoming connection from /192.168.1.3:48279
2010-12-16 22:55:12,797 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 83403509-60a4-3ac0-bae1-94340baf2344 from UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2
2010-12-16 22:55:14,886 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'TOSHIBA REGZA'
2010-12-16 22:55:14,915 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.2:52369
2010-12-16 22:55:14,958 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2010-12-16 22:55:14,989 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2010-12-16 22:55:15,000 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2010-12-16 22:55:15,226 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2010-12-16 22:55:15,289 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2:
2010-12-16 22:55:15,473 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.2:52369
2010-12-16 22:55:16,848 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2010-12-16 22:55:16,859 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2:
2010-12-16 22:55:17,094 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2010-12-16 22:55:17,906 INFO [UpdateChecker] Checking if a new version is available
2010-12-16 22:55:17,917 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.2:52369
2010-12-16 22:55:18,474 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.2:52369
2010-12-16 22:55:18,625 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.3:32771
2010-12-16 22:55:18,627 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s)
2010-12-16 22:55:18,729 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.3:32771
2010-12-16 22:55:18,738 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s)
2010-12-16 22:55:19,289 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /192.168.1.2:52369
2010-12-16 22:55:19,291 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.2:52369
2010-12-16 22:55:19,929 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s)
2010-12-16 22:55:20,514 DEBUG [WebServer] Incoming connection from /192.168.1.2:49167
2010-12-16 22:55:20,515 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 83403509-60a4-3ac0-bae1-94340baf2344 from Microsoft-Windows/6.1 UPnP/1.0 Windows-Media-Player-DMS/12.0.7600.16385 DLNADOC/1.50
2010-12-16 22:55:20,518 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'TOSHIBA REGZA'
2010-12-16 22:55:20,732 DEBUG [WebServer] Incoming connection from /192.168.1.2:49168
2010-12-16 22:55:20,733 DEBUG [UPnPIconRequestHandler] UPnP icon request received for icon smallPNG
2010-12-16 22:55:21,063 DEBUG [RepositoryDAOImpl] Reading all Repositories
2010-12-16 22:55:22,291 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /192.168.1.2:52369
2010-12-16 22:55:22,292 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.2:52369
2010-12-16 22:55:22,342 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s)
2010-12-16 22:55:23,346 DEBUG [MetadataExtractorConfigDAOImpl] Reading a list of Extractor configuration for type AUDIO
2010-12-16 22:55:23,511 DEBUG [MetadataExtractorConfigDAOImpl] Reading a list of Extractor configuration for type VIDEO
2010-12-16 22:55:24,079 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.3:32771
2010-12-16 22:55:24,080 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s)
2010-12-16 22:55:24,180 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.3:32771
2010-12-16 22:55:24,181 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s)
2010-12-16 22:55:25,291 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /192.168.1.2:52369
2010-12-16 22:55:25,293 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.2:52369
2010-12-16 22:55:25,295 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s)
2010-12-16 22:55:25,566 DEBUG [RepositoryDAOImpl] Reading all Repositories
2010-12-16 22:55:32,462 INFO [LibraryAdditionsCheckerThread] Started looking for newly added files
2010-12-16 22:55:32,462 DEBUG [RepositoryDAOImpl] Reading all Repositories
2010-12-16 22:55:32,464 INFO [LibraryUpdatesCheckerThread] Started looking for updates to currently shared files
2010-12-16 22:55:32,464 DEBUG [RepositoryDAOImpl] Reading all Repositories
2010-12-16 22:55:32,466 DEBUG [LibraryUpdatesCheckerThread] Looking for removed/updated files in repository: C:\temp
2010-12-16 22:55:32,488 DEBUG [LibraryAdditionsCheckerThread] Looking for files to share in folder: C:\temp
2010-12-16 22:55:32,510 DEBUG [MediaItemDAOImpl] Reading MediaItems for Repository (id = 3)
2010-12-16 22:55:32,636 DEBUG [LibraryAdditionsCheckerThread] Found file 'The.Daily.Show.2010.10.28.avi', checking if it's already in the Library
2010-12-16 22:55:32,636 DEBUG [MediaItemDAOImpl] Checking if DB already contains media item The.Daily.Show.2010.10.28.avi
2010-12-16 22:55:33,544 DEBUG [MediaItemDAOImpl] Media item The.Daily.Show.2010.10.28.avi already exists in DB
2010-12-16 22:55:33,582 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.2:52369
2010-12-16 22:55:33,586 DEBUG [MediaItemDAOImpl] Getting file of media item 245
2010-12-16 22:55:33,876 DEBUG [MetadataExtractorConfigDAOImpl] Reading a list of Extractor configuration for type AUDIO
2010-12-16 22:55:33,879 DEBUG [MetadataExtractorConfigDAOImpl] Reading a list of Extractor configuration for type VIDEO
2010-12-16 22:55:33,884 DEBUG [MetadataExtractorConfigDAOImpl] Reading a list of Extractor configuration for type IMAGE
2010-12-16 22:55:33,887 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 245) and extractor EMBEDDED
2010-12-16 22:55:34,074 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 245) and extractor COVER_IMAGE_IN_FOLDER
2010-12-16 22:55:34,075 DEBUG [LibraryUpdatesCheckerThread] Updating dirty metadata
2010-12-16 22:55:34,075 DEBUG [MediaItemDAOImpl] Reading dirty MediaItems for Repository (id = 3)
2010-12-16 22:55:36,582 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.2:52369
2010-12-16 22:55:39,583 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.2:52369
2010-12-16 22:55:50,450 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.3:32771
2010-12-16 22:55:50,451 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s)
2010-12-16 22:55:50,552 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.3:32771
2010-12-16 22:55:50,553 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s)
2010-12-16 22:55:50,661 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.3:32771
2010-12-16 22:55:50,662 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s)
2010-12-16 22:55:50,763 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.3:32771
2010-12-16 22:55:50,764 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s)
2010-12-16 22:55:50,920 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.3:32771
2010-12-16 22:55:50,923 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s)
2010-12-16 22:55:51,023 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.3:32771
2010-12-16 22:55:51,024 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s)
2010-12-16 22:55:53,110 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.3:32771
2010-12-16 22:55:53,111 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s)
2010-12-16 22:55:53,212 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.3:32771
2010-12-16 22:55:53,214 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s)
2010-12-16 22:55:53,360 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.3:32771
2010-12-16 22:55:53,362 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s)
2010-12-16 22:55:53,462 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.3:32771
2010-12-16 22:55:53,463 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s)
2010-12-16 22:55:56,873 DEBUG [WebServer] Incoming connection from /192.168.1.3:51240
2010-12-16 22:55:56,874 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2:
2010-12-16 22:55:57,074 DEBUG [ContentDirectory] Browse() called for profile TOSHIBA REGZA with parameters: objectID = 0, browseFlag = BrowseDirectChildren, filter = upnp:class,dc:date,res,res@size, startIndex = 0, count = 120, sortCriteria =
2010-12-16 22:55:57,193 INFO [ContentDirectoryDefinitionParser] Parsing ContentDirectory definition
2010-12-16 22:55:57,201 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=0, title=Serviio Media Library, class=CONTAINER, cacheRegion=local_default
2010-12-16 22:55:57,261 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A, title=Audio, class=CONTAINER, cacheRegion=local_default
2010-12-16 22:55:57,299 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_A, title=Artists, class=CONTAINER, cacheRegion=local_default
2010-12-16 22:55:57,309 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListArtistsByNameCommand, containerClass=MUSIC_ARTIST, itemClass=null, idPrefix=ART, recursive=false, cacheRegion=local_default
2010-12-16 22:55:57,320 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
2010-12-16 22:55:57,334 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
2010-12-16 22:55:57,343 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_F, title=Folders, class=CONTAINER, cacheRegion=local_default
2010-12-16 22:55:57,354 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
2010-12-16 22:55:57,365 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_G, title=Genres, class=CONTAINER, cacheRegion=local_default
2010-12-16 22:55:57,375 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
2010-12-16 22:55:57,388 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
2010-12-16 22:55:57,398 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_T, title=Titles, class=CONTAINER, cacheRegion=local_default
2010-12-16 22:55:57,409 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
2010-12-16 22:55:57,426 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
2010-12-16 22:55:57,436 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_S, title=All songs, class=CONTAINER, cacheRegion=local_default
2010-12-16 22:55:57,448 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
2010-12-16 22:55:57,675 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_AS, title=Artist Songs, class=CONTAINER, cacheRegion=local_default
2010-12-16 22:55:57,686 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
2010-12-16 22:55:57,696 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
2010-12-16 22:55:57,710 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_ALB, title=All albums, class=CONTAINER, cacheRegion=local_default
2010-12-16 22:55:57,721 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
2010-12-16 22:55:57,731 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
2010-12-16 22:55:57,741 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=I, title=Image, class=CONTAINER, cacheRegion=local_default
2010-12-16 22:55:57,750 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=I_A, title=Albums, class=CONTAINER, cacheRegion=local_default
2010-12-16 22:55:57,759 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
2010-12-16 22:55:57,771 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
2010-12-16 22:55:57,780 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=I_F, title=Folders, class=CONTAINER, cacheRegion=local_default
2010-12-16 22:55:57,791 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
2010-12-16 22:55:57,800 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=I_Y, title=Year, class=CONTAINER, cacheRegion=local_default
2010-12-16 22:55:57,810 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
2010-12-16 22:55:57,822 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
2010-12-16 22:55:57,831 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=I_T, title=Timeline, class=CONTAINER, cacheRegion=local_default
2010-12-16 22:55:57,840 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
2010-12-16 22:55:57,852 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
2010-12-16 22:55:57,863 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
2010-12-16 22:55:57,871 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=I_AI, title=All images, class=CONTAINER, cacheRegion=local_default
2010-12-16 22:55:57,882 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
2010-12-16 22:55:57,891 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V, title=Video, class=CONTAINER, cacheRegion=local_default
2010-12-16 22:55:57,900 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_T, title=All titles, class=CONTAINER, cacheRegion=local_default
2010-12-16 22:55:57,913 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
2010-12-16 22:55:57,923 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
2010-12-16 22:55:57,934 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_M, title=Movies, class=CONTAINER, cacheRegion=local_default
2010-12-16 22:55:57,944 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
2010-12-16 22:55:57,953 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_S, title=Series, class=CONTAINER, cacheRegion=local_default
2010-12-16 22:55:57,966 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
2010-12-16 22:55:57,977 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
2010-12-16 22:55:57,987 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
2010-12-16 22:55:57,998 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_F, title=Folders, class=CONTAINER, cacheRegion=local_default
2010-12-16 22:55:58,008 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
2010-12-16 22:55:58,017 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_LV, title=Last viewed, class=CONTAINER, cacheRegion=local_resetafterplay
2010-12-16 22:55:58,029 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
2010-12-16 22:55:58,038 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_LA, title=Last added, class=CONTAINER, cacheRegion=local_default
2010-12-16 22:55:58,136 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
2010-12-16 22:55:58,145 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_G, title=Genres, class=CONTAINER, cacheRegion=local_default
2010-12-16 22:55:58,159 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
2010-12-16 22:55:58,170 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
2010-12-16 22:55:58,180 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_A, title=Actors, class=CONTAINER, cacheRegion=local_default
2010-12-16 22:55:58,191 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
2010-12-16 22:55:58,204 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
2010-12-16 22:55:58,214 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
2010-12-16 22:55:58,223 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_D, title=Directors, class=CONTAINER, cacheRegion=local_default
2010-12-16 22:55:58,236 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
2010-12-16 22:55:58,247 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
2010-12-16 22:55:58,259 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
2010-12-16 22:55:58,291 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_P, title=Producers, class=CONTAINER, cacheRegion=local_default
2010-12-16 22:55:58,300 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
2010-12-16 22:55:58,314 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
2010-12-16 22:55:58,326 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
2010-12-16 22:55:58,374 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2010-12-16 22:55:58,384 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2010-12-16 22:55:58,394 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2:
2010-12-16 22:55:58,600 DEBUG [ContentDirectory] Browse() called for profile TOSHIBA REGZA with parameters: objectID = 0, browseFlag = BrowseDirectChildren, filter = res,res@protocolInfo,res@size,dc:date,res@duration,res@dlna:ifoFileURI,@dlna:dlnaManaged, startIndex = 0, count = 3, sortCriteria =
2010-12-16 22:55:58,601 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2010-12-16 22:55:58,608 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2010-12-16 22:56:01,205 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2:
2010-12-16 22:56:01,404 DEBUG [ContentDirectory] Browse() called for profile TOSHIBA REGZA with parameters: objectID = V, browseFlag = BrowseDirectChildren, filter = upnp:class,dc:date,res,res@size, startIndex = 0, count = 120, sortCriteria =
2010-12-16 22:56:01,456 DEBUG [VideoDAOImpl] Retrieving number of video initials
2010-12-16 22:56:01,516 DEBUG [VideoDAOImpl] Retrieving number of videos of type 2
2010-12-16 22:56:01,545 DEBUG [SeriesDAOImpl] Retrieving number of series
2010-12-16 22:56:01,613 DEBUG [RepositoryDAOImpl] Retrieving number of repositories for VIDEO
2010-12-16 22:56:01,626 DEBUG [VideoDAOImpl] Retrieving number of 10 last viewed videos
2010-12-16 22:56:01,648 DEBUG [VideoDAOImpl] Retrieving number of 10 last added videos
2010-12-16 22:56:01,648 DEBUG [VideoDAOImpl] Retrieving number of videos of type 0
2010-12-16 22:56:01,688 DEBUG [GenreDAOImpl] Retrieving number of genres for VIDEO
2010-12-16 22:56:01,779 DEBUG [PersonDAOImpl] Retrieving number of person initials for role ACTOR
2010-12-16 22:56:02,186 DEBUG [PersonDAOImpl] Retrieving number of person initials for role DIRECTOR
2010-12-16 22:56:02,189 DEBUG [PersonDAOImpl] Retrieving number of person initials for role PRODUCER
2010-12-16 22:56:02,196 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2010-12-16 22:56:02,211 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2010-12-16 22:56:02,229 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2:
2010-12-16 22:56:02,466 DEBUG [ContentDirectory] Browse() called for profile TOSHIBA REGZA with parameters: objectID = V, browseFlag = BrowseDirectChildren, filter = res,res@protocolInfo,res@size,dc:date,res@duration,res@dlna:ifoFileURI,@dlna:dlnaManaged, startIndex = 0, count = 10, sortCriteria =
2010-12-16 22:56:02,466 DEBUG [VideoDAOImpl] Retrieving number of video initials
2010-12-16 22:56:02,468 DEBUG [VideoDAOImpl] Retrieving number of videos of type 2
2010-12-16 22:56:02,469 DEBUG [SeriesDAOImpl] Retrieving number of series
2010-12-16 22:56:02,470 DEBUG [RepositoryDAOImpl] Retrieving number of repositories for VIDEO
2010-12-16 22:56:02,471 DEBUG [VideoDAOImpl] Retrieving number of 10 last viewed videos
2010-12-16 22:56:02,473 DEBUG [VideoDAOImpl] Retrieving number of 10 last added videos
2010-12-16 22:56:02,473 DEBUG [VideoDAOImpl] Retrieving number of videos of type 0
2010-12-16 22:56:02,474 DEBUG [GenreDAOImpl] Retrieving number of genres for VIDEO
2010-12-16 22:56:02,476 DEBUG [PersonDAOImpl] Retrieving number of person initials for role ACTOR
2010-12-16 22:56:02,478 DEBUG [PersonDAOImpl] Retrieving number of person initials for role DIRECTOR
2010-12-16 22:56:02,480 DEBUG [PersonDAOImpl] Retrieving number of person initials for role PRODUCER
2010-12-16 22:56:02,481 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2010-12-16 22:56:02,510 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2010-12-16 22:56:05,410 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2:
2010-12-16 22:56:05,621 DEBUG [ContentDirectory] Browse() called for profile TOSHIBA REGZA with parameters: objectID = V_T, browseFlag = BrowseDirectChildren, filter = upnp:class,dc:date,res,res@size, startIndex = 0, count = 120, sortCriteria =
2010-12-16 22:56:05,624 DEBUG [VideoDAOImpl] Retrieving list of video initials (from=0, count=120)
2010-12-16 22:56:05,705 DEBUG [VideoDAOImpl] Retrieving number of videos with initial D
2010-12-16 22:56:05,732 DEBUG [VideoDAOImpl] Retrieving number of video initials
2010-12-16 22:56:05,734 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2010-12-16 22:56:05,740 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2010-12-16 22:56:05,747 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2:
2010-12-16 22:56:05,952 DEBUG [ContentDirectory] Browse() called for profile TOSHIBA REGZA with parameters: objectID = V_T, browseFlag = BrowseDirectChildren, filter = res,res@protocolInfo,res@size,dc:date,res@duration,res@dlna:ifoFileURI,@dlna:dlnaManaged, startIndex = 0, count = 1, sortCriteria =
2010-12-16 22:56:05,953 DEBUG [VideoDAOImpl] Retrieving list of video initials (from=0, count=1)
2010-12-16 22:56:05,970 DEBUG [VideoDAOImpl] Retrieving number of videos with initial D
2010-12-16 22:56:05,971 DEBUG [VideoDAOImpl] Retrieving number of video initials
2010-12-16 22:56:05,972 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2010-12-16 22:56:05,978 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2010-12-16 22:56:07,004 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2:
2010-12-16 22:56:07,204 DEBUG [ContentDirectory] Browse() called for profile TOSHIBA REGZA with parameters: objectID = V_T#INI_D, browseFlag = BrowseDirectChildren, filter = upnp:class,dc:date,res,res@size, startIndex = 0, count = 120, sortCriteria =
2010-12-16 22:56:07,204 DEBUG [VideoDAOImpl] Retrieving list of videos with initial D (from=0, count=120)
2010-12-16 22:56:07,286 DEBUG [GenreDAOImpl] Reading a Genre (id = 17)
2010-12-16 22:56:07,296 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 245)
2010-12-16 22:56:07,325 DEBUG [MediaItemDAOImpl] Getting file of media item 245
2010-12-16 22:56:07,331 DEBUG [VideoDAOImpl] Reading a Video (id = 245)
2010-12-16 22:56:07,353 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 245 and profile TOSHIBA REGZA
2010-12-16 22:56:07,356 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.10.28.avi: MPEG_TS_SD_EU_ISO
2010-12-16 22:56:07,356 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.10.28.avi: MPEG_TS_SD_NA_ISO
2010-12-16 22:56:07,357 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.10.28.avi: MPEG_TS_SD_KO_ISO
2010-12-16 22:56:07,360 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 50)
2010-12-16 22:56:07,586 DEBUG [VideoDAOImpl] Retrieving number of videos with initial D
2010-12-16 22:56:07,587 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2010-12-16 22:56:07,627 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2010-12-16 22:56:07,636 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2:
2010-12-16 22:56:07,846 DEBUG [ContentDirectory] Browse() called for profile TOSHIBA REGZA with parameters: objectID = V_T#INI_D, browseFlag = BrowseDirectChildren, filter = res,res@protocolInfo,res@size,dc:date,res@duration,res@dlna:ifoFileURI,@dlna:dlnaManaged, startIndex = 0, count = 1, sortCriteria =
2010-12-16 22:56:07,846 DEBUG [VideoDAOImpl] Retrieving list of videos with initial D (from=0, count=1)
2010-12-16 22:56:07,900 DEBUG [GenreDAOImpl] Reading a Genre (id = 17)
2010-12-16 22:56:07,902 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 245)
2010-12-16 22:56:07,903 DEBUG [MediaItemDAOImpl] Getting file of media item 245
2010-12-16 22:56:07,906 DEBUG [VideoDAOImpl] Reading a Video (id = 245)
2010-12-16 22:56:07,907 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 245 and profile TOSHIBA REGZA
2010-12-16 22:56:07,908 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.10.28.avi: MPEG_TS_SD_EU_ISO
2010-12-16 22:56:07,908 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.10.28.avi: MPEG_TS_SD_NA_ISO
2010-12-16 22:56:07,908 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.10.28.avi: MPEG_TS_SD_KO_ISO
2010-12-16 22:56:07,940 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 50)
2010-12-16 22:56:07,943 DEBUG [VideoDAOImpl] Retrieving number of videos with initial D
2010-12-16 22:56:07,944 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2010-12-16 22:56:07,953 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2010-12-16 22:56:09,318 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2:
2010-12-16 22:56:09,528 DEBUG [ContentDirectory] Browse() called for profile TOSHIBA REGZA with parameters: objectID = V_T#INI_D, browseFlag = BrowseDirectChildren, filter = res,res@protocolInfo,res@size,dc:date,res@duration,res@dlna:ifoFileURI,@dlna:dlnaManaged, startIndex = 0, count = 1, sortCriteria =
2010-12-16 22:56:09,529 DEBUG [LocalContentCacheDecorator] Found entry in the cache (local_default), returning it
2010-12-16 22:56:09,567 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2010-12-16 22:56:09,592 DEBUG [WebServer] Incoming connection from /192.168.1.3:51241
2010-12-16 22:56:09,785 DEBUG [ResourceTransportRequestHandler] GET /resource/245/MEDIA_ITEM/MPEG_TS_SD_EU_ISO HTTP/1.1, headers = [HOST: 192.168.1.2:8895, USER-AGENT: UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2, transferMode.dlna.org: Streaming, ]
2010-12-16 22:56:09,786 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.1.3, Profile=TOSHIBA REGZA'
2010-12-16 22:56:09,786 DEBUG [ResourceTransportRequestHandler] Request for resource 245 and type 'MEDIA_ITEM' received
2010-12-16 22:56:09,787 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 245)
2010-12-16 22:56:09,789 DEBUG [VideoDAOImpl] Reading a Video (id = 245)
2010-12-16 22:56:09,791 DEBUG [VideoDeliveryEngine] Delivering item '245' for client 'IPAddress=/192.168.1.3, Profile=TOSHIBA REGZA'
2010-12-16 22:56:09,791 DEBUG [VideoDeliveryEngine] Delivering file 'The.Daily.Show.2010.10.28.avi' using transcoding
2010-12-16 22:56:09,791 DEBUG [VideoDeliveryEngine] No suitable transcoding job exists yet, start one for client 'IPAddress=/192.168.1.3, Profile=TOSHIBA REGZA'
2010-12-16 22:56:09,793 DEBUG [MediaItemDAOImpl] Getting file of media item 245
2010-12-16 22:56:09,803 DEBUG [FFMPEGWrapper] Invoking FFmpeg to transcode file: C:\temp\The.Daily.Show.2010.10.28.avi
2010-12-16 22:56:09,804 DEBUG [ProcessExecutor] Starting C:\Program Files (x86)\Serviio\bin\\..\lib/ffmpeg.exe -threads 1 -i C:\temp\The.Daily.Show.2010.10.28.avi -y -vcodec mpeg2video -sameq -r 29.97 -g 15 -copyts -acodec ac3 -ab 192k -sn -f mpegts C:\Windows\TEMP\Serviio\transcoding-temp-245-MPEG2TS.stf
2010-12-16 22:56:11,355 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.10.28.avi: MPEG_TS_SD_EU_ISO
2010-12-16 22:56:11,355 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.10.28.avi: MPEG_TS_SD_NA_ISO
2010-12-16 22:56:11,356 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.10.28.avi: MPEG_TS_SD_KO_ISO
2010-12-16 22:56:11,356 DEBUG [VideoDeliveryEngine] Sending transcoding stream
2010-12-16 22:56:11,357 DEBUG [MediaItemDAOImpl] Marking MediaItem (id = 245) as read
2010-12-16 22:56:11,713 DEBUG [LocalContentCacheDecorator] Cleared cache (local_resetafterplay)
2010-12-16 22:56:11,716 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 9000000000
2010-12-16 22:56:11,717 DEBUG [ResourceTransportRequestHandler] Creating entity with chunked transfer
2010-12-16 22:56:11,717 DEBUG [ResourceTransportRequestHandler] Sending file back
2010-12-16 22:56:11,717 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [Content-Type: video/mpeg, Date: Fri, 17 Dec 2010 03:56:11 GMT, Server: Windows 7, UPnP/1.0 DLNADOC/1.50, Serviio/0.4.2, Cache-control: no-cache, transferMode.dlna.org: Streaming, ]
2010-12-16 22:56:20,201 DEBUG [WebServer] I/O error: Connection reset by peer: socket write error
2010-12-16 22:56:23,101 DEBUG [WebServer] Incoming connection from /192.168.1.3:51242
2010-12-16 22:56:23,319 DEBUG [ResourceTransportRequestHandler] GET /resource/245/MEDIA_ITEM/MPEG_TS_SD_EU_ISO HTTP/1.1, headers = [HOST: 192.168.1.2:8895, USER-AGENT: UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2, TimeSeekRange.dlna.org: npt=7.5-, transferMode.dlna.org: Streaming, ]
2010-12-16 22:56:23,319 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.1.3, Profile=TOSHIBA REGZA'
2010-12-16 22:56:23,319 DEBUG [ResourceTransportRequestHandler] Request for resource 245 and type 'MEDIA_ITEM' received
2010-12-16 22:56:23,320 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 245)
2010-12-16 22:56:23,322 DEBUG [VideoDAOImpl] Reading a Video (id = 245)
2010-12-16 22:56:23,323 DEBUG [VideoDeliveryEngine] Delivering item '245' for client 'IPAddress=/192.168.1.3, Profile=TOSHIBA REGZA'
2010-12-16 22:56:23,324 DEBUG [VideoDeliveryEngine] Delivering file 'The.Daily.Show.2010.10.28.avi' using transcoding
2010-12-16 22:56:23,324 DEBUG [VideoDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'IPAddress=/192.168.1.3, Profile=TOSHIBA REGZA'
2010-12-16 22:56:23,324 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.10.28.avi: MPEG_TS_SD_EU_ISO
2010-12-16 22:56:23,325 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.10.28.avi: MPEG_TS_SD_NA_ISO
2010-12-16 22:56:23,325 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.10.28.avi: MPEG_TS_SD_KO_ISO
2010-12-16 22:56:23,325 DEBUG [VideoDeliveryEngine] Sending transcoding stream
2010-12-16 22:56:23,325 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 1500526653
2010-12-16 22:56:23,325 DEBUG [ResourceTransportRequestHandler] Creating entity with chunked transfer
2010-12-16 22:56:23,326 DEBUG [ResourceTransportRequestHandler] Sending file back
2010-12-16 22:56:23,326 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [Content-Type: video/mpeg, TimeSeekRange.dlna.org: npt=7-1287/1287, Date: Fri, 17 Dec 2010 03:56:23 GMT, Server: Windows 7, UPnP/1.0 DLNADOC/1.50, Serviio/0.4.2, Cache-control: no-cache, transferMode.dlna.org: Streaming, ]
2010-12-16 22:56:28,307 DEBUG [WebServer] I/O error: Connection reset
2010-12-16 22:56:33,917 DEBUG [WebServer] I/O error: Connection reset by peer: socket write error
2010-12-16 22:56:44,361 INFO [ServiioConsole] Leaving Serviio Console
2010-12-16 22:56:44,392 DEBUG [ActionsServerResource] Action with name 'exitServiio' was requested
2010-12-16 22:56:44,560 INFO [LibraryAdditionsCheckerThread] Finished looking for newly added files
2010-12-16 22:56:46,102 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,102 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,102 DEBUG [WebServer] Socket closed


I removed about 1200 lines of socket closed!


2010-12-16 22:56:46,191 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,191 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,191 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,191 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,192 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,192 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,192 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,192 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,192 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,192 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,192 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,192 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,192 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,192 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,192 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,192 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,192 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,192 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,192 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,192 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,192 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,192 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,193 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,193 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,193 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,193 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,193 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,193 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,193 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,193 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,193 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,193 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,193 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,193 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,193 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,193 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,193 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,193 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,193 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,193 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,193 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,193 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,194 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,194 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,194 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,194 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,194 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,194 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,194 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,194 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,194 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,194 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,194 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,194 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,194 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,194 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,194 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,194 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,194 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,194 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,194 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,194 DEBUG [WebServer] Socket closed
2010-12-16 22:56:46,233 DEBUG [DiscoveryManager] UPNP device 83403509-60a4-3ac0-bae1-94340baf2344 is unavailable
2010-12-16 22:56:46,233 INFO [DiscoveryAdvertisementNotifier] Leaving DiscoveryAdvertisementNotifier
2010-12-16 22:56:46,234 INFO [EventDispatcher] Leaving EventDispatcher
2010-12-16 22:56:46,234 INFO [EventSubscriptionExpirationChecker] Leaving EventSubscriptionExpirationChecker, removing all event subscriptions
2010-12-16 22:56:46,234 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP byebye using interface 192.168.1.2
2010-12-16 22:56:46,234 INFO [DiscoverySSDPMessageListener] Leaving DiscoverySSDPMessageListener
2010-12-16 22:56:46,234 INFO [DatabaseManager] Shutting down database
2010-12-16 22:56:46,234 DEBUG [DBConnectionPool] Closed connection for pool Serviio DB Pool
2010-12-16 22:56:46,234 DEBUG [DBConnectionPool] Closed connection for pool Serviio DB Pool
2010-12-16 22:56:46,250 INFO [WebServer] WebServer shutting down
2010-12-16 22:56:46,593 DEBUG [DatabaseManager] DB shutdown returned: Derby system shutdown.
2010-12-16 22:56:46,593 INFO [VideoDeliveryEngine] Cleaning transcode engine and its data
2010-12-16 22:56:46,593 DEBUG [ProcessExecutor] Stopping external process: Thread[Thread-19,5,main]
2010-12-16 22:56:46,593 DEBUG [TranscodingJobListener] Transcoding finished; successful: false
2010-12-16 22:56:46,609 DEBUG [VideoDeliveryEngine] Deleting temporary transcoded files from: C:\Windows\TEMP\Serviio
2010-12-16 22:56:46,625 DEBUG [VideoDeliveryEngine] Deleted file C:\Windows\TEMP\Serviio\transcoding-temp-245-MPEG2TS.stf: true
<<

zip

User avatar

Serviio developer / Site Admin

Posts: 17212

Joined: Sat Oct 24, 2009 12:24 pm

Location: London, UK

Post Fri Dec 17, 2010 8:28 am

Re: problem with pause ff rew

From the log I can see the file started transcoding and was served, but that's it. Pause is normally handled by the TV (stop the current stream and don't ask for anu more data). I assume it's the TV's problem the counter goes on, pausing works ok on my Samsung.
<<

Al_V

Serviio newbie

Posts: 7

Joined: Fri Dec 17, 2010 3:59 am

Post Fri Dec 17, 2010 11:51 am

Re: problem with pause ff rew

Thanks for the quick reply.
Pause works ok, and the time counter stops.
The problem is after pausing, I cannot resume playing.
After pausing, I push play, and the counter resumes, but the video and sound remain paused.

Are there any configuration settings to modify any of these controls?

Thanks again!
<<

zip

User avatar

Serviio developer / Site Admin

Posts: 17212

Joined: Sat Oct 24, 2009 12:24 pm

Location: London, UK

Post Fri Dec 17, 2010 12:50 pm

Re: problem with pause ff rew

Ok, got you. Maybe I see something in the log and will look into it: https://bitbucket.org/xnejp03/serviio/i ... ng-failing

Not much you can do now, but will try to solve it for 0.5
<<

Al_V

Serviio newbie

Posts: 7

Joined: Fri Dec 17, 2010 3:59 am

Post Fri Dec 17, 2010 1:06 pm

Re: problem with pause ff rew

Excellent! Thank you!
<<

Al_V

Serviio newbie

Posts: 7

Joined: Fri Dec 17, 2010 3:59 am

Post Sat Jan 15, 2011 11:30 pm

Re: problem with pause ff rew

Hi Zip,
well, as stated in the beta forum, beta 5 didn't fix my issue, so I created this new log file.
I browsed by folders to the video, started it, paused it, FF and tried to resume playing, then I pushed pause and stop, and exited.
Thanks for all your hard work!
  Code:
2011-01-15 18:42:15,921 DEBUG [ApplicationInstanceManager] Listening for application instances on socket 44331
2011-01-15 18:42:15,921 INFO  [MediaServer] ------------------------------------------------------------------------
2011-01-15 18:42:15,921 INFO  [MediaServer] Serviio DLNA media streaming server v 0.5-SNAPSHOT (rev. e350e435b877+)
2011-01-15 18:42:15,921 INFO  [MediaServer] Petr Nejedly 2009-2011
2011-01-15 18:42:15,921 INFO  [MediaServer] http://www.serviio.org
2011-01-15 18:42:15,921 INFO  [MediaServer]
2011-01-15 18:42:15,921 INFO  [MediaServer] Java 1.6.0_23-Sun Microsystems Inc.
2011-01-15 18:42:15,921 INFO  [MediaServer] OS Windows XP x86 5.1
2011-01-15 18:42:15,921 INFO  [MediaServer] File encoding: windows-1252
2011-01-15 18:42:15,921 INFO  [MediaServer] ------------------------------------------------------------------------
2011-01-15 18:42:16,000 INFO  [RestletServer] Starting Restlet server exposed on localhost:23423
2011-01-15 18:42:16,031 INFO  [DBSchemaUpdateExecutor] Checking if DB schema needs to be updated
2011-01-15 18:42:16,031 DEBUG [DBLogDAOImpl] Checking if script 'script-0.1.sql' has been run
2011-01-15 18:42:16,625 DEBUG [DBLogDAOImpl] Checking if script 'script-0.1.1.sql' has been run
2011-01-15 18:42:16,625 DEBUG [DBLogDAOImpl] Checking if script 'script-0.2.sql' has been run
2011-01-15 18:42:16,625 DEBUG [DBLogDAOImpl] Checking if script 'script-0.3.1.sql' has been run
2011-01-15 18:42:16,625 DEBUG [DBLogDAOImpl] Checking if script 'script-0.4.sql' has been run
2011-01-15 18:42:16,625 DEBUG [DBLogDAOImpl] Checking if script 'script-0.4.1.sql' has been run
2011-01-15 18:42:16,625 DEBUG [DBLogDAOImpl] Checking if script 'script-0.4.2.sql' has been run
2011-01-15 18:42:16,625 DEBUG [DBLogDAOImpl] Checking if script 'script-0.5.sql' has been run
2011-01-15 18:42:16,765 INFO  [ProfilesDefinitionParser] Parsing Profiles definition
2011-01-15 18:42:16,781 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 1
2011-01-15 18:42:16,937 INFO  [ProfilesDefinitionParser] Added profile 'Generic DLNA profile' (id=1)
2011-01-15 18:42:16,937 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 2
2011-01-15 18:42:16,984 INFO  [ProfilesDefinitionParser] Added profile 'Samsung TV (B-series)' (id=2)
2011-01-15 18:42:16,984 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 3
2011-01-15 18:42:17,015 INFO  [ProfilesDefinitionParser] Added profile 'XBox 360' (id=3)
2011-01-15 18:42:17,015 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 4
2011-01-15 18:42:17,031 INFO  [ProfilesDefinitionParser] Added profile 'Playstation 3' (id=4)
2011-01-15 18:42:17,031 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 5
2011-01-15 18:42:17,062 INFO  [ProfilesDefinitionParser] Added profile 'Samsung TV (A-series)' (id=5)
2011-01-15 18:42:17,062 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 6
2011-01-15 18:42:17,078 INFO  [ProfilesDefinitionParser] Added profile 'DirecTV HD-DVR' (id=6)
2011-01-15 18:42:17,078 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 7
2011-01-15 18:42:17,109 INFO  [ProfilesDefinitionParser] Added profile 'Samsung TV / player (C-series)' (id=7)
2011-01-15 18:42:17,109 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 8
2011-01-15 18:42:17,125 INFO  [ProfilesDefinitionParser] Added profile 'LG BD player' (id=8)
2011-01-15 18:42:17,125 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 9
2011-01-15 18:42:17,171 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV' (id=9)
2011-01-15 18:42:17,171 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 10
2011-01-15 18:42:17,343 INFO  [ProfilesDefinitionParser] Added profile 'Sony BD Player (EU)' (id=10)
2011-01-15 18:42:17,343 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 11
2011-01-15 18:42:17,546 INFO  [ProfilesDefinitionParser] Added profile 'Sony BD Player (US)' (id=11)
2011-01-15 18:42:17,546 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 12
2011-01-15 18:42:17,578 INFO  [ProfilesDefinitionParser] Added profile 'Panasonic Viera' (id=12)
2011-01-15 18:42:17,578 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 13
2011-01-15 18:42:17,609 INFO  [ProfilesDefinitionParser] Added profile 'Toshiba Rezga' (id=13)
2011-01-15 18:42:17,609 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 14
2011-01-15 18:42:17,671 INFO  [ProfilesDefinitionParser] Added profile 'Toshiba 55ux600u' (id=14)
2011-01-15 18:42:17,671 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 15
2011-01-15 18:42:17,718 INFO  [ProfilesDefinitionParser] Added profile 'TOSHIBA 55' (id=15)
2011-01-15 18:42:17,718 DEBUG [FFMPEGWrapper] Invoking FFMPEG to check if it exists of path C:\Program Files\Serviio\bin\\..\lib/ffmpeg.exe
2011-01-15 18:42:17,718 DEBUG [ProcessExecutor] Starting C:\Program Files\Serviio\bin\\..\lib/ffmpeg.exe
2011-01-15 18:42:17,765 INFO  [VideoDeliveryEngine] Cleaning transcode engine and its data
2011-01-15 18:42:17,765 DEBUG [ConfigEntryDAOImpl] Reading all ConfigEntries
2011-01-15 18:42:17,781 DEBUG [VideoDeliveryEngine] Deleting temporary transcoded files from: C:\WINDOWS\TEMP\Serviio
2011-01-15 18:42:17,781 INFO  [WebServer] Socket buffer set to 65535 bytes
2011-01-15 18:42:17,796 INFO  [WebServer] WebServer starting on port 8895
2011-01-15 18:42:17,796 INFO  [RendererSearchSender] Searching for Renderer devices
2011-01-15 18:42:17,953 INFO  [Device] Created UPnP Device with UUID: 223a9375-eb61-3269-abcf-3ad9f2e63b1f, bound address: 192.168.1.6
2011-01-15 18:42:17,968 INFO  [CompositeCacheManager] Creating cache manager from config file: /cache.ccf
2011-01-15 18:42:17,968 INFO  [ThreadPoolManager] thread_pool.default PoolConfiguration = useBoundary = [true] boundarySize = [2000] maximumPoolSize = [150] minimumPoolSize = [4] keepAliveTime = [300000] whenBlockedPolicy = [RUN] startUpSize = [4]
2011-01-15 18:42:17,968 INFO  [CompositeCacheConfigurator] Setting default auxiliaries to null
2011-01-15 18:42:17,968 INFO  [CompositeCacheConfigurator] No special CompositeCacheAttributes class defined for key [jcs.default.cacheattributes], using default class.
2011-01-15 18:42:17,968 INFO  [CompositeCacheConfigurator] setting defaultCompositeCacheAttributes to [ useLateral = true, useRemote = true, useDisk = true, maxObjs = 100, maxSpoolPerRun = -1, diskUsagePattern = 0 ]
2011-01-15 18:42:17,968 INFO  [CompositeCacheConfigurator] No special ElementAttribute class defined for key [jcs.default.elementattributes], using default class.
2011-01-15 18:42:17,968 INFO  [CompositeCacheConfigurator] setting defaultElementAttributes to [ IS_LATERAL = true, IS_SPOOL = true, IS_REMOTE = true, IS_ETERNAL = true, MaxLifeSeconds = -1, IdleTime = -1, CreateTime = 1295134937968, LastAccessTime = 1295134937968, getTimeToLiveSeconds() = -1, createTime = 1295134937968 ]
2011-01-15 18:42:17,968 INFO  [CompositeCacheConfigurator] No special ElementAttribute class defined for key [jcs.region.local_resetafterplay.elementattributes], using default class.
2011-01-15 18:42:17,984 INFO  [LRUMemoryCache] initialized LRUMemoryCache for local_resetafterplay
2011-01-15 18:42:17,984 INFO  [CompositeCache] Constructed cache with name [local_resetafterplay] and cache attributes [ useLateral = true, useRemote = true, useDisk = true, maxObjs = 1000, maxSpoolPerRun = -1, diskUsagePattern = 0 ]
2011-01-15 18:42:17,984 INFO  [CompositeCacheConfigurator] No special ElementAttribute class defined for key [jcs.region.local_default.elementattributes], using default class.
2011-01-15 18:42:17,984 INFO  [LRUMemoryCache] initialized LRUMemoryCache for local_default
2011-01-15 18:42:17,984 INFO  [CompositeCache] Constructed cache with name [local_default] and cache attributes [ useLateral = true, useRemote = true, useDisk = true, maxObjs = 1000, maxSpoolPerRun = -1, diskUsagePattern = 0 ]
2011-01-15 18:42:17,984 INFO  [CompositeCacheConfigurator] Parsed regions [local_resetafterplay, local_default]
2011-01-15 18:42:17,984 INFO  [CompositeCacheConfigurator] Finished configuration in 16 ms.
2011-01-15 18:42:17,984 DEBUG [RendererSearchSender] Multicasting SSDP M-SEARCH using interface eth0 (Intel(R) 82567LM Gigabit Network Connection - Packet Scheduler Miniport) and address 192.168.1.6, timeout = 0
2011-01-15 18:42:18,000 DEBUG [RendererSearchSender] Sending 1 'm-search' messages
2011-01-15 18:42:23,031 DEBUG [ServiioConsole] Initializing Serviio Console
2011-01-15 18:42:23,046 DEBUG [ApplicationInstanceManager] Listening for application instances on socket 44332
2011-01-15 18:42:23,046 INFO  [ServiioConsole] Starting Serviio Console
2011-01-15 18:42:23,062 DEBUG [SwingHelper] Choosing java look and feel: com.sun.java.swing.plaf.windows.WindowsLookAndFeel
2011-01-15 18:42:23,078 INFO  [ServiioConsole] Connecting to Serviio REST on localhost:23423
2011-01-15 18:42:23,812 INFO  [Messages] Loaded console message bundle for locale: en
2011-01-15 18:42:24,000 DEBUG [RendererSearchSender] Finished searching for Renderer devices
2011-01-15 18:42:24,000 DEBUG [DiscoveryManager] UPNP device 223a9375-eb61-3269-abcf-3ad9f2e63b1f is available
2011-01-15 18:42:24,000 INFO  [DiscoveryAdvertisementNotifier] Starting DiscoveryAdvertisementNotifier
2011-01-15 18:42:24,000 INFO  [EventSubscriptionExpirationChecker] Starting EventSubscriptionExpirationChecker
2011-01-15 18:42:24,000 INFO  [RendererExpirationChecker] Starting RendererExpirationChecker
2011-01-15 18:42:24,000 INFO  [EventDispatcher] Starting EventDispatcher
2011-01-15 18:42:24,000 INFO  [DiscoverySSDPMessageListener] Starting DiscoverySSDPMessageListener using interface eth0 (Intel(R) 82567LM Gigabit Network Connection - Packet Scheduler Miniport) and address 192.168.1.6, timeout = 0
2011-01-15 18:42:24,031 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth0 (Intel(R) 82567LM Gigabit Network Connection - Packet Scheduler Miniport) and address 192.168.1.6, timeout = 0
2011-01-15 18:42:24,031 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 223a9375-eb61-3269-abcf-3ad9f2e63b1f
2011-01-15 18:42:24,671 DEBUG [WebServer] Incoming connection from /192.168.1.5:51724
2011-01-15 18:42:24,687 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 223a9375-eb61-3269-abcf-3ad9f2e63b1f from 192.168.1.5 (headers = [HOST: 192.168.1.6:8895,USER-AGENT: UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2])
2011-01-15 18:42:24,687 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-15 18:42:24,718 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-15 18:42:24,890 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Toshiba Rezga'
2011-01-15 18:42:24,890 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2011-01-15 18:42:24,906 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2011-01-15 18:42:24,906 DEBUG [ServiioConsole] Retrieving preferred console language
2011-01-15 18:42:24,921 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2011-01-15 18:42:24,921 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2011-01-15 18:42:24,953 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from 192.168.1.5:
2011-01-15 18:42:24,953 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-15 18:42:25,125 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-01-15 18:42:25,125 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from 192.168.1.5:
2011-01-15 18:42:25,125 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-15 18:42:25,296 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-01-15 18:42:25,421 DEBUG [ServiioConsole] Setting console locale to be: en
2011-01-15 18:42:25,421 INFO  [Messages] Loaded console message bundle for locale: en
2011-01-15 18:42:25,531 INFO  [UpdateChecker] Checking if a new version is available
2011-01-15 18:42:25,843 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:10
2011-01-15 18:42:26,031 DEBUG [ServiioFrameView] Initializing main frame
2011-01-15 18:42:26,031 DEBUG [ServiioFrameView] System tray is supported
2011-01-15 18:42:26,218 DEBUG [RepositoryDAOImpl] Reading all Repositories
2011-01-15 18:42:26,781 DEBUG [RendererDAOImpl] Retrieving list of all stored renderers
2011-01-15 18:42:26,812 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.5:32771
2011-01-15 18:42:26,812 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.5:32771
2011-01-15 18:42:26,921 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.5:32771
2011-01-15 18:42:26,921 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.5:32771
2011-01-15 18:42:28,875 DEBUG [MetadataExtractorConfigDAOImpl] Reading a list of Extractor configuration for type AUDIO
2011-01-15 18:42:28,921 DEBUG [MetadataExtractorConfigDAOImpl] Reading a list of Extractor configuration for type VIDEO
2011-01-15 18:42:30,984 INFO  [ContentDirectoryDefinitionParser] Parsing ContentDirectory definition
2011-01-15 18:42:30,984 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=0, titleKey=serviio_media_library, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = null
2011-01-15 18:42:31,031 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A, titleKey=audio, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2011-01-15 18:42:31,031 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_A, titleKey=artists, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2011-01-15 18:42:31,046 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

I deleted some lines here because of maximum post size

2011-01-15 18:42:31,296 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
2011-01-15 18:42:31,296 INFO  [BrowsingCategoriesMessages] Loaded browsing categories message bundle for locale: en
2011-01-15 18:42:32,890 DEBUG [RepositoryDAOImpl] Reading all Repositories
2011-01-15 18:42:35,890 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth0 (Intel(R) 82567LM Gigabit Network Connection - Packet Scheduler Miniport) and address 192.168.1.6, timeout = 0
2011-01-15 18:42:35,890 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 223a9375-eb61-3269-abcf-3ad9f2e63b1f
2011-01-15 18:42:37,703 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:10
2011-01-15 18:42:44,000 INFO  [LibraryAdditionsCheckerThread] Started looking for newly added files
2011-01-15 18:42:44,000 DEBUG [RepositoryDAOImpl] Reading all Repositories
2011-01-15 18:42:44,000 INFO  [LibraryUpdatesCheckerThread] Started looking for updates to currently shared files
2011-01-15 18:42:44,000 DEBUG [RepositoryDAOImpl] Reading all Repositories
2011-01-15 18:42:44,015 DEBUG [LibraryAdditionsCheckerThread] Looking for files to share in folder: C:\Documents and Settings\superman\My Documents\My Videos
2011-01-15 18:42:44,015 DEBUG [LibraryUpdatesCheckerThread] Looking for removed/updated files in repository: C:\Documents and Settings\superman\My Documents\My Videos
2011-01-15 18:42:44,015 DEBUG [MediaItemDAOImpl] Reading MediaItems for Repository (id = 2)
2011-01-15 18:42:44,031 DEBUG [LibraryAdditionsCheckerThread] Found file 'The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi', checking if it's already in the Library
2011-01-15 18:42:44,031 DEBUG [MediaItemDAOImpl] Checking if DB already contains media item The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi
2011-01-15 18:42:44,187 DEBUG [MediaItemDAOImpl] Media item The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi already exists in DB
2011-01-15 18:42:44,187 DEBUG [RepositoryDAOImpl] Marking Repository 2 as scanned with current timestamp
2011-01-15 18:42:44,281 DEBUG [MediaItemDAOImpl] Getting file of media item 33
2011-01-15 18:42:44,296 DEBUG [MetadataExtractorConfigDAOImpl] Reading a list of Extractor configuration for type AUDIO
2011-01-15 18:42:44,296 DEBUG [MetadataExtractorConfigDAOImpl] Reading a list of Extractor configuration for type VIDEO
2011-01-15 18:42:44,296 DEBUG [MetadataExtractorConfigDAOImpl] Reading a list of Extractor configuration for type IMAGE
2011-01-15 18:42:44,296 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 33) and extractor EMBEDDED
2011-01-15 18:42:44,312 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 33) and extractor COVER_IMAGE_IN_FOLDER
2011-01-15 18:42:44,312 DEBUG [MediaItemDAOImpl] Reading dirty MediaItems for Repository (id = 2)
2011-01-15 18:42:44,328 DEBUG [RepositoryDAOImpl] Marking Repository 2 as scanned with current timestamp
2011-01-15 18:42:47,734 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth0 (Intel(R) 82567LM Gigabit Network Connection - Packet Scheduler Miniport) and address 192.168.1.6, timeout = 0
2011-01-15 18:42:47,734 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 223a9375-eb61-3269-abcf-3ad9f2e63b1f
2011-01-15 18:42:49,546 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:12:25
2011-01-15 18:43:48,515 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.5:32771
2011-01-15 18:43:48,515 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.5:32771
2011-01-15 18:43:48,609 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.5:32771
2011-01-15 18:43:48,609 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.5:32771
2011-01-15 18:43:48,718 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.5:32771
2011-01-15 18:43:48,718 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.5:32771
2011-01-15 18:43:48,812 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.5:32771
2011-01-15 18:43:48,812 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.5:32771
2011-01-15 18:43:49,031 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.5:32771
2011-01-15 18:43:49,031 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.5:32771
2011-01-15 18:43:49,125 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.5:32771
2011-01-15 18:43:49,140 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.5:32771
2011-01-15 18:43:50,796 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.5:32771
2011-01-15 18:43:50,796 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.5:32771
2011-01-15 18:43:50,890 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.5:32771
2011-01-15 18:43:50,890 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.5:32771
2011-01-15 18:43:51,093 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.5:32771
2011-01-15 18:43:51,093 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.5:32771
2011-01-15 18:43:51,187 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.5:32771
2011-01-15 18:43:51,187 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.5:32771
2011-01-15 18:43:53,609 DEBUG [WebServer] Incoming connection from /192.168.1.5:51725
2011-01-15 18:43:53,609 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.1.5:
2011-01-15 18:43:53,609 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-15 18:43:53,734 DEBUG [ContentDirectory] Browse() called for profile Toshiba Rezga with parameters: objectID = 0, browseFlag = BrowseDirectChildren, filter = upnp:class,dc:date,res,res@size, startIndex = 0, count = 120, sortCriteria =
2011-01-15 18:43:53,765 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2011-01-15 18:43:53,781 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-01-15 18:43:53,796 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.1.5:
2011-01-15 18:43:53,796 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-15 18:43:53,921 DEBUG [ContentDirectory] Browse() called for profile Toshiba Rezga with parameters: objectID = 0, browseFlag = BrowseDirectChildren, filter = res,res@protocolInfo,res@size,dc:date,res@duration,res@dlna:ifoFileURI,@dlna:dlnaManaged, startIndex = 0, count = 3, sortCriteria =
2011-01-15 18:43:53,921 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2011-01-15 18:43:53,937 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-01-15 18:43:56,875 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.1.5:
2011-01-15 18:43:56,875 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-15 18:43:57,046 DEBUG [ContentDirectory] Browse() called for profile Toshiba Rezga with parameters: objectID = V, browseFlag = BrowseDirectChildren, filter = upnp:class,dc:date,res,res@size, startIndex = 0, count = 120, sortCriteria =
2011-01-15 18:43:57,062 DEBUG [VideoDAOImpl] Retrieving number of video initials
2011-01-15 18:43:57,093 DEBUG [VideoDAOImpl] Retrieving number of videos of type 2
2011-01-15 18:43:57,109 DEBUG [SeriesDAOImpl] Retrieving number of series
2011-01-15 18:43:57,140 DEBUG [RepositoryDAOImpl] Retrieving number of repositories for VIDEO
2011-01-15 18:43:57,156 DEBUG [VideoDAOImpl] Retrieving number of 10 last viewed videos
2011-01-15 18:43:57,171 DEBUG [VideoDAOImpl] Retrieving number of 10 last added videos
2011-01-15 18:43:57,171 DEBUG [VideoDAOImpl] Retrieving number of videos of type 0
2011-01-15 18:43:57,187 DEBUG [GenreDAOImpl] Retrieving number of genres for VIDEO
2011-01-15 18:43:57,203 DEBUG [PersonDAOImpl] Retrieving number of person initials for role ACTOR
2011-01-15 18:43:57,218 DEBUG [PersonDAOImpl] Retrieving number of person initials for role DIRECTOR
2011-01-15 18:43:57,218 DEBUG [PersonDAOImpl] Retrieving number of person initials for role PRODUCER
2011-01-15 18:43:57,218 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2011-01-15 18:43:57,218 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-01-15 18:43:57,234 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.1.5:
2011-01-15 18:43:57,234 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-15 18:43:57,437 DEBUG [ContentDirectory] Browse() called for profile Toshiba Rezga with parameters: objectID = V, browseFlag = BrowseDirectChildren, filter = res,res@protocolInfo,res@size,dc:date,res@duration,res@dlna:ifoFileURI,@dlna:dlnaManaged, startIndex = 0, count = 10, sortCriteria =
2011-01-15 18:43:57,437 DEBUG [VideoDAOImpl] Retrieving number of video initials
2011-01-15 18:43:57,437 DEBUG [VideoDAOImpl] Retrieving number of videos of type 2
2011-01-15 18:43:57,437 DEBUG [SeriesDAOImpl] Retrieving number of series
2011-01-15 18:43:57,437 DEBUG [RepositoryDAOImpl] Retrieving number of repositories for VIDEO
2011-01-15 18:43:57,437 DEBUG [VideoDAOImpl] Retrieving number of 10 last viewed videos
2011-01-15 18:43:57,437 DEBUG [VideoDAOImpl] Retrieving number of 10 last added videos
2011-01-15 18:43:57,437 DEBUG [VideoDAOImpl] Retrieving number of videos of type 0
2011-01-15 18:43:57,437 DEBUG [GenreDAOImpl] Retrieving number of genres for VIDEO
2011-01-15 18:43:57,437 DEBUG [PersonDAOImpl] Retrieving number of person initials for role ACTOR
2011-01-15 18:43:57,437 DEBUG [PersonDAOImpl] Retrieving number of person initials for role DIRECTOR
2011-01-15 18:43:57,437 DEBUG [PersonDAOImpl] Retrieving number of person initials for role PRODUCER
2011-01-15 18:43:57,437 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2011-01-15 18:43:57,453 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-01-15 18:44:00,375 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.1.5:
2011-01-15 18:44:00,375 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-15 18:44:00,562 DEBUG [ContentDirectory] Browse() called for profile Toshiba Rezga with parameters: objectID = V_F, browseFlag = BrowseDirectChildren, filter = upnp:class,dc:date,res,res@size, startIndex = 0, count = 120, sortCriteria =
2011-01-15 18:44:00,562 DEBUG [RepositoryDAOImpl] Retrieving list of Repositories for VIDEO (from=0, count=120)
2011-01-15 18:44:00,593 DEBUG [FolderDAOImpl] Getting number of VIDEO sub-folders and media items in folder 'root'
2011-01-15 18:44:00,640 DEBUG [RepositoryDAOImpl] Retrieving number of repositories for VIDEO
2011-01-15 18:44:00,640 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2011-01-15 18:44:00,656 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-01-15 18:44:00,656 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.1.5:
2011-01-15 18:44:00,656 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-15 18:44:00,859 DEBUG [ContentDirectory] Browse() called for profile Toshiba Rezga with parameters: objectID = V_F, browseFlag = BrowseDirectChildren, filter = res,res@protocolInfo,res@size,dc:date,res@duration,res@dlna:ifoFileURI,@dlna:dlnaManaged, startIndex = 0, count = 1, sortCriteria =
2011-01-15 18:44:00,859 DEBUG [RepositoryDAOImpl] Retrieving list of Repositories for VIDEO (from=0, count=1)
2011-01-15 18:44:00,875 DEBUG [FolderDAOImpl] Getting number of VIDEO sub-folders and media items in folder 'root'
2011-01-15 18:44:00,890 DEBUG [RepositoryDAOImpl] Retrieving number of repositories for VIDEO
2011-01-15 18:44:00,890 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2011-01-15 18:44:00,890 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-01-15 18:44:01,968 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.1.5:
2011-01-15 18:44:01,968 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-15 18:44:02,171 DEBUG [ContentDirectory] Browse() called for profile Toshiba Rezga with parameters: objectID = V_F#FOL_R2, browseFlag = BrowseDirectChildren, filter = upnp:class,dc:date,res,res@size, startIndex = 0, count = 120, sortCriteria =
2011-01-15 18:44:02,171 DEBUG [FolderDAOImpl] Getting number of sub-Folders in folder null
2011-01-15 18:44:02,203 DEBUG [VideoDAOImpl] Retrieving list of videos for folder 13 (from=0, count=120)
2011-01-15 18:44:02,234 DEBUG [GenreDAOImpl] Reading a Genre (id = 6)
2011-01-15 18:44:02,250 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 33)
2011-01-15 18:44:02,265 DEBUG [MediaItemDAOImpl] Getting file of media item 33
2011-01-15 18:44:02,281 DEBUG [VideoDAOImpl] Reading a Video (id = 33)
2011-01-15 18:44:02,296 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 33 and profile Toshiba Rezga
2011-01-15 18:44:02,296 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi: MPEG_TS_SD_EU_ISO
2011-01-15 18:44:02,296 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi: MPEG_TS_SD_NA_ISO
2011-01-15 18:44:02,296 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi: MPEG_TS_SD_KO_ISO
2011-01-15 18:44:02,296 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 33)
2011-01-15 18:44:02,375 DEBUG [FolderDAOImpl] Getting number of VIDEO sub-folders and media items in folder 'root'
2011-01-15 18:44:02,375 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2011-01-15 18:44:02,375 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-01-15 18:44:02,375 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.1.5:
2011-01-15 18:44:02,375 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-15 18:44:02,562 DEBUG [ContentDirectory] Browse() called for profile Toshiba Rezga with parameters: objectID = V_F#FOL_R2, browseFlag = BrowseDirectChildren, filter = res,res@protocolInfo,res@size,dc:date,res@duration,res@dlna:ifoFileURI,@dlna:dlnaManaged, startIndex = 0, count = 1, sortCriteria =
2011-01-15 18:44:02,562 DEBUG [FolderDAOImpl] Getting number of sub-Folders in folder null
2011-01-15 18:44:02,578 DEBUG [VideoDAOImpl] Retrieving list of videos for folder 13 (from=0, count=1)
2011-01-15 18:44:02,578 DEBUG [GenreDAOImpl] Reading a Genre (id = 6)
2011-01-15 18:44:02,578 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 33)
2011-01-15 18:44:02,578 DEBUG [MediaItemDAOImpl] Getting file of media item 33
2011-01-15 18:44:02,578 DEBUG [VideoDAOImpl] Reading a Video (id = 33)
2011-01-15 18:44:02,578 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 33 and profile Toshiba Rezga
2011-01-15 18:44:02,578 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi: MPEG_TS_SD_EU_ISO
2011-01-15 18:44:02,578 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi: MPEG_TS_SD_NA_ISO
2011-01-15 18:44:02,578 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi: MPEG_TS_SD_KO_ISO
2011-01-15 18:44:02,578 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 33)
2011-01-15 18:44:02,578 DEBUG [FolderDAOImpl] Getting number of VIDEO sub-folders and media items in folder 'root'
2011-01-15 18:44:02,578 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2011-01-15 18:44:02,578 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-01-15 18:44:04,750 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.1.5:
2011-01-15 18:44:04,750 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-15 18:44:04,890 DEBUG [ContentDirectory] Browse() called for profile Toshiba Rezga with parameters: objectID = V_F#FOL_R2, browseFlag = BrowseDirectChildren, filter = res,res@protocolInfo,res@size,dc:date,res@duration,res@dlna:ifoFileURI,@dlna:dlnaManaged, startIndex = 0, count = 1, sortCriteria =
2011-01-15 18:44:04,890 DEBUG [LocalContentCacheDecorator] Found entry in the cache (local_default), returning it
2011-01-15 18:44:04,890 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-01-15 18:44:04,906 DEBUG [WebServer] Incoming connection from /192.168.1.5:51726
2011-01-15 18:44:05,078 DEBUG [ResourceTransportRequestHandler] GET /resource/33/MEDIA_ITEM/MPEG_TS_SD_EU_ISO HTTP/1.1, headers = [HOST: 192.168.1.6:8895,USER-AGENT: UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2,transferMode.dlna.org: Streaming]]
2011-01-15 18:44:05,078 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-15 18:44:05,093 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-15 18:44:05,093 DEBUG [ResourceTransportRequestHandler] Request for resource 33 and type 'MEDIA_ITEM' received
2011-01-15 18:44:05,093 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 33)
2011-01-15 18:44:05,093 DEBUG [VideoDAOImpl] Reading a Video (id = 33)
2011-01-15 18:44:05,093 DEBUG [VideoDeliveryEngine] Delivering item '33' for client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-15 18:44:05,093 DEBUG [VideoDeliveryEngine] Delivering file 'The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi' using transcoding
2011-01-15 18:44:05,093 DEBUG [VideoDeliveryEngine] No suitable transcoding job exists yet, start one for client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-15 18:44:05,093 DEBUG [MediaItemDAOImpl] Getting file of media item 33
2011-01-15 18:44:05,093 DEBUG [FFMPEGWrapper] Invoking FFmpeg to transcode file: C:\Documents and Settings\superman\My Documents\My Videos\The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi
2011-01-15 18:44:05,093 DEBUG [ProcessExecutor] Starting C:\Program Files\Serviio\bin\\..\lib/ffmpeg.exe -threads 1 -i C:\Documents and Settings\superman\My Documents\My Videos\The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi -y -vcodec mpeg2video -sameq -r 29.97 -g 15 -copyts -acodec ac3 -ab 128k -ac 2 -map 0:0 -map 0:1 -sn -f mpegts C:\WINDOWS\TEMP\Serviio\transcoding-temp-33-MPEG2TS.stf
2011-01-15 18:44:05,593 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi: MPEG_TS_SD_EU_ISO
2011-01-15 18:44:05,593 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi: MPEG_TS_SD_NA_ISO
2011-01-15 18:44:05,593 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi: MPEG_TS_SD_KO_ISO
2011-01-15 18:44:05,593 DEBUG [VideoDeliveryEngine] Sending transcoding stream
2011-01-15 18:44:05,593 DEBUG [MediaItemDAOImpl] Marking MediaItem (id = 33) as read
2011-01-15 18:44:05,609 DEBUG [LocalContentCacheDecorator] Cleared cache (local_resetafterplay)
2011-01-15 18:44:05,609 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 9000000000
2011-01-15 18:44:05,609 DEBUG [ResourceTransportRequestHandler] Creating entity with chunked transfer
2011-01-15 18:44:05,609 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-01-15 18:44:05,609 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/mpeg,Date: Sat, 15 Jan 2011 23:44:05 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/0.5-SNAPSHOT,Cache-control: no-cache,transferMode.dlna.org: Streaming]]
2011-01-15 18:44:06,421 DEBUG [WebServer] I/O error: Software caused connection abort: socket write error
2011-01-15 18:44:06,421 DEBUG [WebServer] Incoming connection from /192.168.1.5:51727
2011-01-15 18:44:06,593 DEBUG [ResourceTransportRequestHandler] GET /resource/33/MEDIA_ITEM/MPEG_TS_SD_EU_ISO HTTP/1.1, headers = [HOST: 192.168.1.6:8895,USER-AGENT: UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2,transferMode.dlna.org: Streaming]]
2011-01-15 18:44:06,593 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-15 18:44:06,593 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-15 18:44:06,593 DEBUG [ResourceTransportRequestHandler] Request for resource 33 and type 'MEDIA_ITEM' received
2011-01-15 18:44:06,593 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 33)
2011-01-15 18:44:06,593 DEBUG [VideoDAOImpl] Reading a Video (id = 33)
2011-01-15 18:44:06,593 DEBUG [VideoDeliveryEngine] Delivering item '33' for client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-15 18:44:06,593 DEBUG [VideoDeliveryEngine] Delivering file 'The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi' using transcoding
2011-01-15 18:44:06,593 DEBUG [VideoDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-15 18:44:06,593 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi: MPEG_TS_SD_EU_ISO
2011-01-15 18:44:06,593 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi: MPEG_TS_SD_NA_ISO
2011-01-15 18:44:06,593 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi: MPEG_TS_SD_KO_ISO
2011-01-15 18:44:06,593 DEBUG [VideoDeliveryEngine] Sending transcoding stream
2011-01-15 18:44:06,593 DEBUG [MediaItemDAOImpl] Marking MediaItem (id = 33) as read
2011-01-15 18:44:06,593 DEBUG [LocalContentCacheDecorator] Cleared cache (local_resetafterplay)
2011-01-15 18:44:06,593 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 9000000000
2011-01-15 18:44:06,593 DEBUG [ResourceTransportRequestHandler] Creating entity with chunked transfer
2011-01-15 18:44:06,593 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-01-15 18:44:06,593 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/mpeg,Date: Sat, 15 Jan 2011 23:44:06 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/0.5-SNAPSHOT,Cache-control: no-cache,transferMode.dlna.org: Streaming]]
2011-01-15 18:44:18,062 DEBUG [WebServer] I/O error: Software caused connection abort: socket write error
2011-01-15 18:44:20,453 DEBUG [WebServer] Incoming connection from /192.168.1.5:51728
2011-01-15 18:44:20,578 DEBUG [ResourceTransportRequestHandler] GET /resource/33/MEDIA_ITEM/MPEG_TS_SD_EU_ISO HTTP/1.1, headers = [HOST: 192.168.1.6:8895,USER-AGENT: UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2,TimeSeekRange.dlna.org: npt=13.7-14.7,transferMode.dlna.org: Streaming]]
2011-01-15 18:44:20,578 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-15 18:44:20,578 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-15 18:44:20,578 DEBUG [ResourceTransportRequestHandler] Request for resource 33 and type 'MEDIA_ITEM' received
2011-01-15 18:44:20,578 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 33)
2011-01-15 18:44:20,578 DEBUG [VideoDAOImpl] Reading a Video (id = 33)
2011-01-15 18:44:20,578 DEBUG [VideoDeliveryEngine] Delivering item '33' for client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-15 18:44:20,578 DEBUG [VideoDeliveryEngine] Delivering file 'The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi' using transcoding
2011-01-15 18:44:20,578 DEBUG [VideoDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-15 18:44:20,578 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi: MPEG_TS_SD_EU_ISO
2011-01-15 18:44:20,578 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi: MPEG_TS_SD_NA_ISO
2011-01-15 18:44:20,578 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi: MPEG_TS_SD_KO_ISO
2011-01-15 18:44:20,578 DEBUG [VideoDeliveryEngine] Sending transcoding stream
2011-01-15 18:44:20,578 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 7450588
2011-01-15 18:44:20,578 DEBUG [ResourceTransportRequestHandler] Creating entity with chunked transfer
2011-01-15 18:44:20,578 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-01-15 18:44:20,578 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/mpeg,TimeSeekRange.dlna.org: npt=13.7-14.7/1288,Date: Sat, 15 Jan 2011 23:44:20 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/0.5-SNAPSHOT,Cache-control: no-cache,transferMode.dlna.org: Streaming]]
2011-01-15 18:44:22,890 DEBUG [ResourceTransportRequestHandler] GET /resource/33/MEDIA_ITEM/MPEG_TS_SD_EU_ISO HTTP/1.1, headers = [HOST: 192.168.1.6:8895,USER-AGENT: UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2,TimeSeekRange.dlna.org: npt=16.1-17.1,transferMode.dlna.org: Streaming]]
2011-01-15 18:44:22,890 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-15 18:44:22,890 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-15 18:44:22,890 DEBUG [ResourceTransportRequestHandler] Request for resource 33 and type 'MEDIA_ITEM' received
2011-01-15 18:44:22,890 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 33)
2011-01-15 18:44:22,890 DEBUG [VideoDAOImpl] Reading a Video (id = 33)
2011-01-15 18:44:22,890 DEBUG [VideoDeliveryEngine] Delivering item '33' for client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-15 18:44:22,890 DEBUG [VideoDeliveryEngine] Delivering file 'The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi' using transcoding
2011-01-15 18:44:22,890 DEBUG [VideoDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-15 18:44:22,890 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi: MPEG_TS_SD_EU_ISO
2011-01-15 18:44:22,890 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi: MPEG_TS_SD_NA_ISO
2011-01-15 18:44:22,890 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi: MPEG_TS_SD_KO_ISO
2011-01-15 18:44:22,890 DEBUG [VideoDeliveryEngine] Sending transcoding stream
2011-01-15 18:44:22,890 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 8533790
2011-01-15 18:44:22,890 DEBUG [ResourceTransportRequestHandler] Creating entity with chunked transfer
2011-01-15 18:44:22,890 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-01-15 18:44:22,890 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/mpeg,TimeSeekRange.dlna.org: npt=16.1-17.1/1288,Date: Sat, 15 Jan 2011 23:44:22 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/0.5-SNAPSHOT,Cache-control: no-cache,transferMode.dlna.org: Streaming]]
2011-01-15 18:44:25,093 DEBUG [ResourceTransportRequestHandler] GET /resource/33/MEDIA_ITEM/MPEG_TS_SD_EU_ISO HTTP/1.1, headers = [HOST: 192.168.1.6:8895,USER-AGENT: UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2,TimeSeekRange.dlna.org: npt=28.3-29.3,transferMode.dlna.org: Streaming]]
2011-01-15 18:44:25,093 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-15 18:44:25,093 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-15 18:44:25,093 DEBUG [ResourceTransportRequestHandler] Request for resource 33 and type 'MEDIA_ITEM' received
2011-01-15 18:44:25,093 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 33)
2011-01-15 18:44:25,093 DEBUG [VideoDAOImpl] Reading a Video (id = 33)
2011-01-15 18:44:25,093 DEBUG [VideoDeliveryEngine] Delivering item '33' for client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-15 18:44:25,093 DEBUG [VideoDeliveryEngine] Delivering file 'The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi' using transcoding
2011-01-15 18:44:25,093 DEBUG [VideoDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-15 18:44:25,093 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi: MPEG_TS_SD_EU_ISO
2011-01-15 18:44:25,093 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi: MPEG_TS_SD_NA_ISO
2011-01-15 18:44:25,093 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi: MPEG_TS_SD_KO_ISO
2011-01-15 18:44:25,093 DEBUG [VideoDeliveryEngine] Sending transcoding stream
2011-01-15 18:44:25,109 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 14169091
2011-01-15 18:44:25,109 DEBUG [ResourceTransportRequestHandler] Creating entity with chunked transfer
2011-01-15 18:44:25,109 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-01-15 18:44:25,109 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/mpeg,TimeSeekRange.dlna.org: npt=28.3-29.3/1288,Date: Sat, 15 Jan 2011 23:44:25 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/0.5-SNAPSHOT,Cache-control: no-cache,transferMode.dlna.org: Streaming]]
2011-01-15 18:44:27,421 DEBUG [WebServer] Incoming connection from /192.168.1.5:51729
2011-01-15 18:44:27,609 DEBUG [ResourceTransportRequestHandler] GET /resource/33/MEDIA_ITEM/MPEG_TS_SD_EU_ISO HTTP/1.1, headers = [HOST: 192.168.1.6:8895,USER-AGENT: UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2,TimeSeekRange.dlna.org: npt=43.3-44.3,transferMode.dlna.org: Streaming]]
2011-01-15 18:44:27,609 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-15 18:44:27,609 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-15 18:44:27,609 DEBUG [ResourceTransportRequestHandler] Request for resource 33 and type 'MEDIA_ITEM' received
2011-01-15 18:44:27,609 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 33)
2011-01-15 18:44:27,609 DEBUG [VideoDAOImpl] Reading a Video (id = 33)
2011-01-15 18:44:27,609 DEBUG [VideoDeliveryEngine] Delivering item '33' for client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-15 18:44:27,609 DEBUG [VideoDeliveryEngine] Delivering file 'The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi' using transcoding
2011-01-15 18:44:27,609 DEBUG [VideoDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-15 18:44:27,609 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi: MPEG_TS_SD_EU_ISO
2011-01-15 18:44:27,609 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi: MPEG_TS_SD_NA_ISO
2011-01-15 18:44:27,609 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi: MPEG_TS_SD_KO_ISO
2011-01-15 18:44:27,609 DEBUG [VideoDeliveryEngine] Sending transcoding stream
2011-01-15 18:44:27,625 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 20476438
2011-01-15 18:44:27,625 DEBUG [ResourceTransportRequestHandler] Creating entity with chunked transfer
2011-01-15 18:44:27,625 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-01-15 18:44:27,625 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/mpeg,TimeSeekRange.dlna.org: npt=43.3-44.3/1288,Date: Sat, 15 Jan 2011 23:44:27 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/0.5-SNAPSHOT,Cache-control: no-cache,transferMode.dlna.org: Streaming]]
2011-01-15 18:44:29,453 DEBUG [WebServer] I/O error: Software caused connection abort: socket write error
2011-01-15 18:44:29,453 DEBUG [WebServer] Incoming connection from /192.168.1.5:51730
2011-01-15 18:44:29,625 DEBUG [ResourceTransportRequestHandler] GET /resource/33/MEDIA_ITEM/MPEG_TS_SD_EU_ISO HTTP/1.1, headers = [HOST: 192.168.1.6:8895,USER-AGENT: UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2,TimeSeekRange.dlna.org: npt=27.5-,transferMode.dlna.org: Streaming]]
2011-01-15 18:44:29,625 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-15 18:44:29,625 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-15 18:44:29,625 DEBUG [ResourceTransportRequestHandler] Request for resource 33 and type 'MEDIA_ITEM' received
2011-01-15 18:44:29,625 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 33)
2011-01-15 18:44:29,625 DEBUG [VideoDAOImpl] Reading a Video (id = 33)
2011-01-15 18:44:29,625 DEBUG [VideoDeliveryEngine] Delivering item '33' for client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-15 18:44:29,625 DEBUG [VideoDeliveryEngine] Delivering file 'The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi' using transcoding
2011-01-15 18:44:29,625 DEBUG [VideoDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-15 18:44:29,625 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi: MPEG_TS_SD_EU_ISO
2011-01-15 18:44:29,625 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi: MPEG_TS_SD_NA_ISO
2011-01-15 18:44:29,625 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Daily.Show.2010.12.13.Gordon.Brown.HDTV.XviD-FQM.[VTV].avi: MPEG_TS_SD_KO_ISO
2011-01-15 18:44:29,625 DEBUG [VideoDeliveryEngine] Sending transcoding stream
2011-01-15 18:44:29,625 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 583597026
2011-01-15 18:44:29,625 DEBUG [ResourceTransportRequestHandler] Creating entity with chunked transfer
2011-01-15 18:44:29,625 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-01-15 18:44:29,625 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/mpeg,TimeSeekRange.dlna.org: npt=27.5-1288.0/1288,Date: Sat, 15 Jan 2011 23:44:29 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/0.5-SNAPSHOT,Cache-control: no-cache,transferMode.dlna.org: Streaming]]
2011-01-15 18:44:45,953 DEBUG [WebServer] I/O error: Software caused connection abort: socket write error
2011-01-15 18:45:11,843 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.5:32771
2011-01-15 18:45:11,843 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.5:32771
2011-01-15 18:45:11,937 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.5:32771
2011-01-15 18:45:11,937 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.5:32771
<<

zip

User avatar

Serviio developer / Site Admin

Posts: 17212

Joined: Sat Oct 24, 2009 12:24 pm

Location: London, UK

Post Sun Jan 16, 2011 12:54 am

Re: problem with pause ff rew

This is a transcoded file. Could you try the same on a file that plays natively?
<<

Al_V

Serviio newbie

Posts: 7

Joined: Fri Dec 17, 2010 3:59 am

Post Sun Jan 16, 2011 2:47 pm

Re: problem with pause ff rew

OK,
I found a file that plays natively.
In this log, I did the same, browsed by folder, play, pause, ff, play, stop, exit, all controls work perfectly.
Thanks again for working to resolve this.

  Code:
2011-01-16 09:38:17,281 DEBUG [ApplicationInstanceManager] Listening for application instances on socket 44331
2011-01-16 09:38:17,281 INFO  [MediaServer] ------------------------------------------------------------------------
2011-01-16 09:38:17,281 INFO  [MediaServer] Serviio DLNA media streaming server v 0.5-SNAPSHOT (rev. e350e435b877+)
2011-01-16 09:38:17,281 INFO  [MediaServer] Petr Nejedly 2009-2011
2011-01-16 09:38:17,281 INFO  [MediaServer] http://www.serviio.org
2011-01-16 09:38:17,281 INFO  [MediaServer]
2011-01-16 09:38:17,281 INFO  [MediaServer] Java 1.6.0_23-Sun Microsystems Inc.
2011-01-16 09:38:17,281 INFO  [MediaServer] OS Windows XP x86 5.1
2011-01-16 09:38:17,281 INFO  [MediaServer] File encoding: windows-1252
2011-01-16 09:38:17,281 INFO  [MediaServer] ------------------------------------------------------------------------
2011-01-16 09:38:17,359 INFO  [RestletServer] Starting Restlet server exposed on localhost:23423
2011-01-16 09:38:17,390 INFO  [DBSchemaUpdateExecutor] Checking if DB schema needs to be updated
2011-01-16 09:38:17,390 DEBUG [DBLogDAOImpl] Checking if script 'script-0.1.sql' has been run
2011-01-16 09:38:17,984 DEBUG [DBLogDAOImpl] Checking if script 'script-0.1.1.sql' has been run
2011-01-16 09:38:17,984 DEBUG [DBLogDAOImpl] Checking if script 'script-0.2.sql' has been run
2011-01-16 09:38:17,984 DEBUG [DBLogDAOImpl] Checking if script 'script-0.3.1.sql' has been run
2011-01-16 09:38:17,984 DEBUG [DBLogDAOImpl] Checking if script 'script-0.4.sql' has been run
2011-01-16 09:38:17,984 DEBUG [DBLogDAOImpl] Checking if script 'script-0.4.1.sql' has been run
2011-01-16 09:38:17,984 DEBUG [DBLogDAOImpl] Checking if script 'script-0.4.2.sql' has been run
2011-01-16 09:38:17,984 DEBUG [DBLogDAOImpl] Checking if script 'script-0.5.sql' has been run
2011-01-16 09:38:18,125 INFO  [ProfilesDefinitionParser] Parsing Profiles definition
2011-01-16 09:38:18,140 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 1
2011-01-16 09:38:18,312 INFO  [ProfilesDefinitionParser] Added profile 'Generic DLNA profile' (id=1)
2011-01-16 09:38:18,312 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 2
2011-01-16 09:38:18,343 INFO  [ProfilesDefinitionParser] Added profile 'Samsung TV (B-series)' (id=2)
2011-01-16 09:38:18,359 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 3
2011-01-16 09:38:18,390 INFO  [ProfilesDefinitionParser] Added profile 'XBox 360' (id=3)
2011-01-16 09:38:18,390 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 4
2011-01-16 09:38:18,406 INFO  [ProfilesDefinitionParser] Added profile 'Playstation 3' (id=4)
2011-01-16 09:38:18,406 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 5
2011-01-16 09:38:18,421 INFO  [ProfilesDefinitionParser] Added profile 'Samsung TV (A-series)' (id=5)
2011-01-16 09:38:18,437 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 6
2011-01-16 09:38:18,453 INFO  [ProfilesDefinitionParser] Added profile 'DirecTV HD-DVR' (id=6)
2011-01-16 09:38:18,453 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 7
2011-01-16 09:38:18,484 INFO  [ProfilesDefinitionParser] Added profile 'Samsung TV / player (C-series)' (id=7)
2011-01-16 09:38:18,484 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 8
2011-01-16 09:38:18,500 INFO  [ProfilesDefinitionParser] Added profile 'LG BD player' (id=8)
2011-01-16 09:38:18,500 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 9
2011-01-16 09:38:18,546 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV' (id=9)
2011-01-16 09:38:18,546 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 10
2011-01-16 09:38:18,718 INFO  [ProfilesDefinitionParser] Added profile 'Sony BD Player (EU)' (id=10)
2011-01-16 09:38:18,718 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 11
2011-01-16 09:38:18,921 INFO  [ProfilesDefinitionParser] Added profile 'Sony BD Player (US)' (id=11)
2011-01-16 09:38:18,921 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 12
2011-01-16 09:38:18,968 INFO  [ProfilesDefinitionParser] Added profile 'Panasonic Viera' (id=12)
2011-01-16 09:38:18,968 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 13
2011-01-16 09:38:19,000 INFO  [ProfilesDefinitionParser] Added profile 'Toshiba Rezga' (id=13)
2011-01-16 09:38:19,000 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 14
2011-01-16 09:38:19,062 INFO  [ProfilesDefinitionParser] Added profile 'Toshiba 55ux600u' (id=14)
2011-01-16 09:38:19,062 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 15
2011-01-16 09:38:19,109 INFO  [ProfilesDefinitionParser] Added profile 'TOSHIBA 55' (id=15)
2011-01-16 09:38:19,109 DEBUG [FFMPEGWrapper] Invoking FFMPEG to check if it exists of path C:\Program Files\Serviio\bin\\..\lib/ffmpeg.exe
2011-01-16 09:38:19,109 DEBUG [ProcessExecutor] Starting C:\Program Files\Serviio\bin\\..\lib/ffmpeg.exe
2011-01-16 09:38:19,156 INFO  [VideoDeliveryEngine] Cleaning transcode engine and its data
2011-01-16 09:38:19,156 DEBUG [ConfigEntryDAOImpl] Reading all ConfigEntries
2011-01-16 09:38:19,156 DEBUG [VideoDeliveryEngine] Deleting temporary transcoded files from: C:\WINDOWS\TEMP\Serviio
2011-01-16 09:38:19,156 INFO  [WebServer] Socket buffer set to 65535 bytes
2011-01-16 09:38:19,187 INFO  [WebServer] WebServer starting on port 8895
2011-01-16 09:38:19,187 INFO  [RendererSearchSender] Searching for Renderer devices
2011-01-16 09:38:19,343 INFO  [Device] Created UPnP Device with UUID: 223a9375-eb61-3269-abcf-3ad9f2e63b1f, bound address: 192.168.1.6
2011-01-16 09:38:19,343 INFO  [CompositeCacheManager] Creating cache manager from config file: /cache.ccf
2011-01-16 09:38:19,359 INFO  [ThreadPoolManager] thread_pool.default PoolConfiguration = useBoundary = [true] boundarySize = [2000] maximumPoolSize = [150] minimumPoolSize = [4] keepAliveTime = [300000] whenBlockedPolicy = [RUN] startUpSize = [4]
2011-01-16 09:38:19,359 INFO  [CompositeCacheConfigurator] Setting default auxiliaries to null
2011-01-16 09:38:19,359 INFO  [CompositeCacheConfigurator] No special CompositeCacheAttributes class defined for key [jcs.default.cacheattributes], using default class.
2011-01-16 09:38:19,359 INFO  [CompositeCacheConfigurator] setting defaultCompositeCacheAttributes to [ useLateral = true, useRemote = true, useDisk = true, maxObjs = 100, maxSpoolPerRun = -1, diskUsagePattern = 0 ]
2011-01-16 09:38:19,359 INFO  [CompositeCacheConfigurator] No special ElementAttribute class defined for key [jcs.default.elementattributes], using default class.
2011-01-16 09:38:19,359 INFO  [CompositeCacheConfigurator] setting defaultElementAttributes to [ IS_LATERAL = true, IS_SPOOL = true, IS_REMOTE = true, IS_ETERNAL = true, MaxLifeSeconds = -1, IdleTime = -1, CreateTime = 1295188699359, LastAccessTime = 1295188699359, getTimeToLiveSeconds() = -1, createTime = 1295188699359 ]
2011-01-16 09:38:19,359 INFO  [CompositeCacheConfigurator] No special ElementAttribute class defined for key [jcs.region.local_resetafterplay.elementattributes], using default class.
2011-01-16 09:38:19,375 INFO  [LRUMemoryCache] initialized LRUMemoryCache for local_resetafterplay
2011-01-16 09:38:19,375 INFO  [CompositeCache] Constructed cache with name [local_resetafterplay] and cache attributes [ useLateral = true, useRemote = true, useDisk = true, maxObjs = 1000, maxSpoolPerRun = -1, diskUsagePattern = 0 ]
2011-01-16 09:38:19,375 INFO  [CompositeCacheConfigurator] No special ElementAttribute class defined for key [jcs.region.local_default.elementattributes], using default class.

I removed some lines here to not exceed the post limit

2011-01-16 09:38:32,734 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
2011-01-16 09:38:32,734 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.5:32771
2011-01-16 09:38:32,734 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.5:32771
2011-01-16 09:38:32,734 INFO  [BrowsingCategoriesMessages] Loaded browsing categories message bundle for locale: en
2011-01-16 09:38:34,171 DEBUG [RepositoryDAOImpl] Reading all Repositories
2011-01-16 09:38:35,437 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.5:32771
2011-01-16 09:38:35,437 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.5:32771
2011-01-16 09:38:35,546 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.5:32771
2011-01-16 09:38:35,546 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.5:32771
2011-01-16 09:38:35,687 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.5:32771
2011-01-16 09:38:35,687 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.5:32771
2011-01-16 09:38:35,796 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.5:32771
2011-01-16 09:38:35,796 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.5:32771
2011-01-16 09:38:37,343 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth0 (Intel(R) 82567LM Gigabit Network Connection - Packet Scheduler Miniport) and address 192.168.1.6, timeout = 0
2011-01-16 09:38:37,343 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 223a9375-eb61-3269-abcf-3ad9f2e63b1f
2011-01-16 09:38:38,437 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.1.5:
2011-01-16 09:38:38,437 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-16 09:38:38,578 DEBUG [ContentDirectory] Browse() called for profile Toshiba Rezga with parameters: objectID = 0, browseFlag = BrowseDirectChildren, filter = upnp:class,dc:date,res,res@size, startIndex = 0, count = 120, sortCriteria =
2011-01-16 09:38:38,656 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2011-01-16 09:38:38,656 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-01-16 09:38:38,671 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.1.5:
2011-01-16 09:38:38,671 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-16 09:38:38,781 DEBUG [ContentDirectory] Browse() called for profile Toshiba Rezga with parameters: objectID = 0, browseFlag = BrowseDirectChildren, filter = res,res@protocolInfo,res@size,dc:date,res@duration,res@dlna:ifoFileURI,@dlna:dlnaManaged, startIndex = 0, count = 3, sortCriteria =
2011-01-16 09:38:38,781 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2011-01-16 09:38:38,781 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-01-16 09:38:39,156 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:10
2011-01-16 09:38:41,937 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.1.5:
2011-01-16 09:38:41,937 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-16 09:38:42,109 DEBUG [ContentDirectory] Browse() called for profile Toshiba Rezga with parameters: objectID = V, browseFlag = BrowseDirectChildren, filter = upnp:class,dc:date,res,res@size, startIndex = 0, count = 120, sortCriteria =
2011-01-16 09:38:42,125 DEBUG [VideoDAOImpl] Retrieving number of video initials
2011-01-16 09:38:42,187 DEBUG [VideoDAOImpl] Retrieving number of videos of type 2
2011-01-16 09:38:42,234 DEBUG [SeriesDAOImpl] Retrieving number of series
2011-01-16 09:38:42,265 DEBUG [RepositoryDAOImpl] Retrieving number of repositories for VIDEO
2011-01-16 09:38:42,281 DEBUG [VideoDAOImpl] Retrieving number of 10 last viewed videos
2011-01-16 09:38:42,281 DEBUG [VideoDAOImpl] Retrieving number of 10 last added videos
2011-01-16 09:38:42,281 DEBUG [VideoDAOImpl] Retrieving number of videos of type 0
2011-01-16 09:38:42,296 DEBUG [GenreDAOImpl] Retrieving number of genres for VIDEO
2011-01-16 09:38:42,312 DEBUG [PersonDAOImpl] Retrieving number of person initials for role ACTOR
2011-01-16 09:38:42,328 DEBUG [PersonDAOImpl] Retrieving number of person initials for role DIRECTOR
2011-01-16 09:38:42,328 DEBUG [PersonDAOImpl] Retrieving number of person initials for role PRODUCER
2011-01-16 09:38:42,328 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2011-01-16 09:38:42,343 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-01-16 09:38:42,359 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.1.5:
2011-01-16 09:38:42,359 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-16 09:38:42,500 DEBUG [ContentDirectory] Browse() called for profile Toshiba Rezga with parameters: objectID = V, browseFlag = BrowseDirectChildren, filter = res,res@protocolInfo,res@size,dc:date,res@duration,res@dlna:ifoFileURI,@dlna:dlnaManaged, startIndex = 0, count = 10, sortCriteria =
2011-01-16 09:38:42,500 DEBUG [VideoDAOImpl] Retrieving number of video initials
2011-01-16 09:38:42,500 DEBUG [VideoDAOImpl] Retrieving number of videos of type 2
2011-01-16 09:38:42,500 DEBUG [SeriesDAOImpl] Retrieving number of series
2011-01-16 09:38:42,500 DEBUG [RepositoryDAOImpl] Retrieving number of repositories for VIDEO
2011-01-16 09:38:42,500 DEBUG [VideoDAOImpl] Retrieving number of 10 last viewed videos
2011-01-16 09:38:42,515 DEBUG [VideoDAOImpl] Retrieving number of 10 last added videos
2011-01-16 09:38:42,515 DEBUG [VideoDAOImpl] Retrieving number of videos of type 0
2011-01-16 09:38:42,515 DEBUG [GenreDAOImpl] Retrieving number of genres for VIDEO
2011-01-16 09:38:42,515 DEBUG [PersonDAOImpl] Retrieving number of person initials for role ACTOR
2011-01-16 09:38:42,515 DEBUG [PersonDAOImpl] Retrieving number of person initials for role DIRECTOR
2011-01-16 09:38:42,515 DEBUG [PersonDAOImpl] Retrieving number of person initials for role PRODUCER
2011-01-16 09:38:42,515 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2011-01-16 09:38:42,515 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-01-16 09:38:45,046 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.1.5:
2011-01-16 09:38:45,046 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-16 09:38:45,218 DEBUG [ContentDirectory] Browse() called for profile Toshiba Rezga with parameters: objectID = V_F, browseFlag = BrowseDirectChildren, filter = upnp:class,dc:date,res,res@size, startIndex = 0, count = 120, sortCriteria =
2011-01-16 09:38:45,218 DEBUG [RepositoryDAOImpl] Retrieving list of Repositories for VIDEO (from=0, count=120)
2011-01-16 09:38:45,250 DEBUG [FolderDAOImpl] Getting number of VIDEO sub-folders and media items in folder 'root'
2011-01-16 09:38:45,343 DEBUG [RepositoryDAOImpl] Retrieving number of repositories for VIDEO
2011-01-16 09:38:45,343 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2011-01-16 09:38:45,343 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-01-16 09:38:45,359 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.1.5:
2011-01-16 09:38:45,359 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-16 09:38:45,406 INFO  [LibraryAdditionsCheckerThread] Started looking for newly added files
2011-01-16 09:38:45,406 DEBUG [RepositoryDAOImpl] Reading all Repositories
2011-01-16 09:38:45,406 INFO  [LibraryUpdatesCheckerThread] Started looking for updates to currently shared files
2011-01-16 09:38:45,406 DEBUG [RepositoryDAOImpl] Reading all Repositories
2011-01-16 09:38:45,406 DEBUG [LibraryAdditionsCheckerThread] Looking for files to share in folder: C:\Documents and Settings\superman\My Documents\My Videos
2011-01-16 09:38:45,406 DEBUG [LibraryUpdatesCheckerThread] Looking for removed/updated files in repository: C:\Documents and Settings\superman\My Documents\My Videos
2011-01-16 09:38:45,406 DEBUG [MediaItemDAOImpl] Reading MediaItems for Repository (id = 2)
2011-01-16 09:38:45,421 DEBUG [LibraryAdditionsCheckerThread] Found file 'insects.mpg', checking if it's already in the Library
2011-01-16 09:38:45,421 DEBUG [MediaItemDAOImpl] Checking if DB already contains media item insects.mpg
2011-01-16 09:38:45,437 DEBUG [MediaItemDAOImpl] Media item insects.mpg already exists in DB
2011-01-16 09:38:45,437 DEBUG [RepositoryDAOImpl] Marking Repository 2 as scanned with current timestamp
2011-01-16 09:38:45,515 DEBUG [MediaItemDAOImpl] Getting file of media item 35
2011-01-16 09:38:45,515 DEBUG [ContentDirectory] Browse() called for profile Toshiba Rezga with parameters: objectID = V_F, browseFlag = BrowseDirectChildren, filter = res,res@protocolInfo,res@size,dc:date,res@duration,res@dlna:ifoFileURI,@dlna:dlnaManaged, startIndex = 0, count = 1, sortCriteria =
2011-01-16 09:38:45,515 DEBUG [RepositoryDAOImpl] Retrieving list of Repositories for VIDEO (from=0, count=1)
2011-01-16 09:38:45,531 DEBUG [FolderDAOImpl] Getting number of VIDEO sub-folders and media items in folder 'root'
2011-01-16 09:38:45,531 DEBUG [RepositoryDAOImpl] Retrieving number of repositories for VIDEO
2011-01-16 09:38:45,531 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2011-01-16 09:38:45,531 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-01-16 09:38:45,531 DEBUG [MetadataExtractorConfigDAOImpl] Reading a list of Extractor configuration for type AUDIO
2011-01-16 09:38:45,531 DEBUG [MetadataExtractorConfigDAOImpl] Reading a list of Extractor configuration for type VIDEO
2011-01-16 09:38:45,546 DEBUG [MetadataExtractorConfigDAOImpl] Reading a list of Extractor configuration for type IMAGE
2011-01-16 09:38:45,546 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 35) and extractor EMBEDDED
2011-01-16 09:38:45,546 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 35) and extractor COVER_IMAGE_IN_FOLDER
2011-01-16 09:38:45,546 DEBUG [MediaItemDAOImpl] Reading dirty MediaItems for Repository (id = 2)
2011-01-16 09:38:45,562 DEBUG [RepositoryDAOImpl] Marking Repository 2 as scanned with current timestamp
2011-01-16 09:38:46,546 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.1.5:
2011-01-16 09:38:46,546 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-16 09:38:46,734 DEBUG [ContentDirectory] Browse() called for profile Toshiba Rezga with parameters: objectID = V_F#FOL_R2, browseFlag = BrowseDirectChildren, filter = upnp:class,dc:date,res,res@size, startIndex = 0, count = 120, sortCriteria =
2011-01-16 09:38:46,734 DEBUG [FolderDAOImpl] Getting number of sub-Folders in folder null
2011-01-16 09:38:46,765 DEBUG [VideoDAOImpl] Retrieving list of videos for folder 14 (from=0, count=120)
2011-01-16 09:38:46,796 DEBUG [GenreDAOImpl] Reading a Genre (id = 8)
2011-01-16 09:38:46,812 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 35)
2011-01-16 09:38:46,828 DEBUG [MediaItemDAOImpl] Getting file of media item 35
2011-01-16 09:38:46,828 DEBUG [VideoDAOImpl] Reading a Video (id = 35)
2011-01-16 09:38:46,828 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 35 and profile Toshiba Rezga
2011-01-16 09:38:46,828 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 35)
2011-01-16 09:38:46,843 DEBUG [FolderDAOImpl] Getting number of VIDEO sub-folders and media items in folder 'root'
2011-01-16 09:38:46,843 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2011-01-16 09:38:46,843 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-01-16 09:38:46,859 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.1.5:
2011-01-16 09:38:46,859 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-16 09:38:47,031 DEBUG [ContentDirectory] Browse() called for profile Toshiba Rezga with parameters: objectID = V_F#FOL_R2, browseFlag = BrowseDirectChildren, filter = res,res@protocolInfo,res@size,dc:date,res@duration,res@dlna:ifoFileURI,@dlna:dlnaManaged, startIndex = 0, count = 1, sortCriteria =
2011-01-16 09:38:47,031 DEBUG [FolderDAOImpl] Getting number of sub-Folders in folder null
2011-01-16 09:38:47,031 DEBUG [VideoDAOImpl] Retrieving list of videos for folder 14 (from=0, count=1)
2011-01-16 09:38:47,031 DEBUG [GenreDAOImpl] Reading a Genre (id = 8)
2011-01-16 09:38:47,031 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 35)
2011-01-16 09:38:47,031 DEBUG [MediaItemDAOImpl] Getting file of media item 35
2011-01-16 09:38:47,031 DEBUG [VideoDAOImpl] Reading a Video (id = 35)
2011-01-16 09:38:47,031 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 35 and profile Toshiba Rezga
2011-01-16 09:38:47,031 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 35)
2011-01-16 09:38:47,031 DEBUG [FolderDAOImpl] Getting number of VIDEO sub-folders and media items in folder 'root'
2011-01-16 09:38:47,046 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2011-01-16 09:38:47,046 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-01-16 09:38:48,437 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.1.5:
2011-01-16 09:38:48,453 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-16 09:38:48,640 DEBUG [ContentDirectory] Browse() called for profile Toshiba Rezga with parameters: objectID = V_F#FOL_R2, browseFlag = BrowseDirectChildren, filter = res,res@protocolInfo,res@size,dc:date,res@duration,res@dlna:ifoFileURI,@dlna:dlnaManaged, startIndex = 0, count = 1, sortCriteria =
2011-01-16 09:38:48,640 DEBUG [LocalContentCacheDecorator] Found entry in the cache (local_default), returning it
2011-01-16 09:38:48,656 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-01-16 09:38:48,671 DEBUG [WebServer] Incoming connection from /192.168.1.5:58795
2011-01-16 09:38:48,828 DEBUG [ResourceTransportRequestHandler] GET /resource/35/MEDIA_ITEM/MPEG_PS_PAL HTTP/1.1, headers = [HOST: 192.168.1.6:8895,USER-AGENT: UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2,Range: bytes=0-1155071,transferMode.dlna.org: Streaming]]
2011-01-16 09:38:48,843 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-16 09:38:48,843 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:38:48,843 DEBUG [ResourceTransportRequestHandler] Request for resource 35 and type 'MEDIA_ITEM' received
2011-01-16 09:38:48,843 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 35)
2011-01-16 09:38:48,843 DEBUG [VideoDAOImpl] Reading a Video (id = 35)
2011-01-16 09:38:48,843 DEBUG [VideoDeliveryEngine] Delivering item '35' for client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:38:48,843 DEBUG [VideoDeliveryEngine] Delivering file 'insects.mpg' in native format
2011-01-16 09:38:48,843 DEBUG [MediaItemDAOImpl] Getting file of media item 35
2011-01-16 09:38:48,843 DEBUG [MediaItemDAOImpl] Marking MediaItem (id = 35) as read
2011-01-16 09:38:48,875 DEBUG [LocalContentCacheDecorator] Cleared cache (local_resetafterplay)
2011-01-16 09:38:48,875 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 1155072
2011-01-16 09:38:48,875 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-01-16 09:38:48,875 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 206 Partial Content, headers = [[Content-Type: video/mpeg,Content-Range: bytes 0-1155071/291245793,Content-Length: 1155072,Date: Sun, 16 Jan 2011 14:38:48 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/0.5-SNAPSHOT,Cache-control: no-cache,transferMode.dlna.org: Streaming]]
2011-01-16 09:38:49,187 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth0 (Intel(R) 82567LM Gigabit Network Connection - Packet Scheduler Miniport) and address 192.168.1.6, timeout = 0
2011-01-16 09:38:49,187 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 223a9375-eb61-3269-abcf-3ad9f2e63b1f
2011-01-16 09:38:49,234 DEBUG [ResourceTransportRequestHandler] GET /resource/35/MEDIA_ITEM/MPEG_PS_PAL HTTP/1.1, headers = [HOST: 192.168.1.6:8895,USER-AGENT: UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2,transferMode.dlna.org: Streaming]]
2011-01-16 09:38:49,234 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-16 09:38:49,234 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:38:49,234 DEBUG [ResourceTransportRequestHandler] Request for resource 35 and type 'MEDIA_ITEM' received
2011-01-16 09:38:49,234 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 35)
2011-01-16 09:38:49,234 DEBUG [VideoDAOImpl] Reading a Video (id = 35)
2011-01-16 09:38:49,234 DEBUG [VideoDeliveryEngine] Delivering item '35' for client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:38:49,234 DEBUG [VideoDeliveryEngine] Delivering file 'insects.mpg' in native format
2011-01-16 09:38:49,234 DEBUG [MediaItemDAOImpl] Getting file of media item 35
2011-01-16 09:38:49,234 DEBUG [MediaItemDAOImpl] Marking MediaItem (id = 35) as read
2011-01-16 09:38:49,234 DEBUG [LocalContentCacheDecorator] Cleared cache (local_resetafterplay)
2011-01-16 09:38:49,234 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 291245793
2011-01-16 09:38:49,234 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-01-16 09:38:49,234 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/mpeg,Content-Length: 291245793,Date: Sun, 16 Jan 2011 14:38:49 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/0.5-SNAPSHOT,Cache-control: no-cache,transferMode.dlna.org: Streaming]]
2011-01-16 09:38:51,000 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:10:08
2011-01-16 09:38:53,531 DEBUG [WebServer] I/O error: Software caused connection abort: socket write error
2011-01-16 09:38:55,734 DEBUG [WebServer] Incoming connection from /192.168.1.5:58796
2011-01-16 09:38:55,875 DEBUG [ResourceTransportRequestHandler] GET /resource/35/MEDIA_ITEM/MPEG_PS_PAL HTTP/1.1, headers = [HOST: 192.168.1.6:8895,USER-AGENT: UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2,Range: bytes=4372480-4870143,transferMode.dlna.org: Streaming]]
2011-01-16 09:38:55,875 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-16 09:38:55,875 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:38:55,875 DEBUG [ResourceTransportRequestHandler] Request for resource 35 and type 'MEDIA_ITEM' received
2011-01-16 09:38:55,875 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 35)
2011-01-16 09:38:55,875 DEBUG [VideoDAOImpl] Reading a Video (id = 35)
2011-01-16 09:38:55,875 DEBUG [VideoDeliveryEngine] Delivering item '35' for client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:38:55,875 DEBUG [VideoDeliveryEngine] Delivering file 'insects.mpg' in native format
2011-01-16 09:38:55,875 DEBUG [MediaItemDAOImpl] Getting file of media item 35
2011-01-16 09:38:55,890 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 497664
2011-01-16 09:38:55,890 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-01-16 09:38:55,890 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 206 Partial Content, headers = [[Content-Type: video/mpeg,Content-Range: bytes 4372480-4870143/291245793,Content-Length: 497664,Date: Sun, 16 Jan 2011 14:38:55 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/0.5-SNAPSHOT,Cache-control: no-cache,transferMode.dlna.org: Streaming]]
2011-01-16 09:38:56,484 DEBUG [ResourceTransportRequestHandler] GET /resource/35/MEDIA_ITEM/MPEG_PS_PAL HTTP/1.1, headers = [HOST: 192.168.1.6:8895,USER-AGENT: UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2,Range: bytes=5543936-6041599,transferMode.dlna.org: Streaming]]
2011-01-16 09:38:56,484 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-16 09:38:56,484 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:38:56,484 DEBUG [ResourceTransportRequestHandler] Request for resource 35 and type 'MEDIA_ITEM' received
2011-01-16 09:38:56,484 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 35)
2011-01-16 09:38:56,484 DEBUG [VideoDAOImpl] Reading a Video (id = 35)
2011-01-16 09:38:56,484 DEBUG [VideoDeliveryEngine] Delivering item '35' for client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:38:56,484 DEBUG [VideoDeliveryEngine] Delivering file 'insects.mpg' in native format
2011-01-16 09:38:56,484 DEBUG [MediaItemDAOImpl] Getting file of media item 35
2011-01-16 09:38:56,484 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 497664
2011-01-16 09:38:56,484 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-01-16 09:38:56,484 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 206 Partial Content, headers = [[Content-Type: video/mpeg,Content-Range: bytes 5543936-6041599/291245793,Content-Length: 497664,Date: Sun, 16 Jan 2011 14:38:56 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/0.5-SNAPSHOT,Cache-control: no-cache,transferMode.dlna.org: Streaming]]
2011-01-16 09:38:56,875 DEBUG [ResourceTransportRequestHandler] GET /resource/35/MEDIA_ITEM/MPEG_PS_PAL HTTP/1.1, headers = [HOST: 192.168.1.6:8895,USER-AGENT: UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2,Range: bytes=6735872-7233535,transferMode.dlna.org: Streaming]]
2011-01-16 09:38:56,890 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-16 09:38:56,890 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:38:56,890 DEBUG [ResourceTransportRequestHandler] Request for resource 35 and type 'MEDIA_ITEM' received
2011-01-16 09:38:56,890 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 35)
2011-01-16 09:38:56,890 DEBUG [VideoDAOImpl] Reading a Video (id = 35)
2011-01-16 09:38:56,890 DEBUG [VideoDeliveryEngine] Delivering item '35' for client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:38:56,890 DEBUG [VideoDeliveryEngine] Delivering file 'insects.mpg' in native format
2011-01-16 09:38:56,890 DEBUG [MediaItemDAOImpl] Getting file of media item 35
2011-01-16 09:38:56,890 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 497664
2011-01-16 09:38:56,890 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-01-16 09:38:56,890 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 206 Partial Content, headers = [[Content-Type: video/mpeg,Content-Range: bytes 6735872-7233535/291245793,Content-Length: 497664,Date: Sun, 16 Jan 2011 14:38:56 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/0.5-SNAPSHOT,Cache-control: no-cache,transferMode.dlna.org: Streaming]]
2011-01-16 09:38:57,281 DEBUG [ResourceTransportRequestHandler] GET /resource/35/MEDIA_ITEM/MPEG_PS_PAL HTTP/1.1, headers = [HOST: 192.168.1.6:8895,USER-AGENT: UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2,Range: bytes=7925760-8423423,transferMode.dlna.org: Streaming]]
2011-01-16 09:38:57,281 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-16 09:38:57,281 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:38:57,281 DEBUG [ResourceTransportRequestHandler] Request for resource 35 and type 'MEDIA_ITEM' received
2011-01-16 09:38:57,281 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 35)
2011-01-16 09:38:57,281 DEBUG [VideoDAOImpl] Reading a Video (id = 35)
2011-01-16 09:38:57,296 DEBUG [VideoDeliveryEngine] Delivering item '35' for client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:38:57,296 DEBUG [VideoDeliveryEngine] Delivering file 'insects.mpg' in native format
2011-01-16 09:38:57,296 DEBUG [MediaItemDAOImpl] Getting file of media item 35
2011-01-16 09:38:57,296 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 497664
2011-01-16 09:38:57,296 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-01-16 09:38:57,296 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 206 Partial Content, headers = [[Content-Type: video/mpeg,Content-Range: bytes 7925760-8423423/291245793,Content-Length: 497664,Date: Sun, 16 Jan 2011 14:38:57 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/0.5-SNAPSHOT,Cache-control: no-cache,transferMode.dlna.org: Streaming]]
2011-01-16 09:38:57,687 DEBUG [ResourceTransportRequestHandler] GET /resource/35/MEDIA_ITEM/MPEG_PS_PAL HTTP/1.1, headers = [HOST: 192.168.1.6:8895,USER-AGENT: UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2,Range: bytes=9119744-9617407,transferMode.dlna.org: Streaming]]
2011-01-16 09:38:57,687 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-16 09:38:57,687 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:38:57,687 DEBUG [ResourceTransportRequestHandler] Request for resource 35 and type 'MEDIA_ITEM' received
2011-01-16 09:38:57,687 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 35)
2011-01-16 09:38:57,687 DEBUG [VideoDAOImpl] Reading a Video (id = 35)
2011-01-16 09:38:57,687 DEBUG [VideoDeliveryEngine] Delivering item '35' for client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:38:57,687 DEBUG [VideoDeliveryEngine] Delivering file 'insects.mpg' in native format
2011-01-16 09:38:57,687 DEBUG [MediaItemDAOImpl] Getting file of media item 35
2011-01-16 09:38:57,687 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 497664
2011-01-16 09:38:57,703 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-01-16 09:38:57,703 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 206 Partial Content, headers = [[Content-Type: video/mpeg,Content-Range: bytes 9119744-9617407/291245793,Content-Length: 497664,Date: Sun, 16 Jan 2011 14:38:57 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/0.5-SNAPSHOT,Cache-control: no-cache,transferMode.dlna.org: Streaming]]
2011-01-16 09:38:58,093 DEBUG [ResourceTransportRequestHandler] GET /resource/35/MEDIA_ITEM/MPEG_PS_PAL HTTP/1.1, headers = [HOST: 192.168.1.6:8895,USER-AGENT: UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2,Range: bytes=10307584-10805247,transferMode.dlna.org: Streaming]]
2011-01-16 09:38:58,093 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-16 09:38:58,093 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:38:58,093 DEBUG [ResourceTransportRequestHandler] Request for resource 35 and type 'MEDIA_ITEM' received
2011-01-16 09:38:58,093 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 35)
2011-01-16 09:38:58,093 DEBUG [VideoDAOImpl] Reading a Video (id = 35)
2011-01-16 09:38:58,093 DEBUG [VideoDeliveryEngine] Delivering item '35' for client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:38:58,093 DEBUG [VideoDeliveryEngine] Delivering file 'insects.mpg' in native format
2011-01-16 09:38:58,093 DEBUG [MediaItemDAOImpl] Getting file of media item 35
2011-01-16 09:38:58,093 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 497664
2011-01-16 09:38:58,093 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-01-16 09:38:58,093 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 206 Partial Content, headers = [[Content-Type: video/mpeg,Content-Range: bytes 10307584-10805247/291245793,Content-Length: 497664,Date: Sun, 16 Jan 2011 14:38:58 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/0.5-SNAPSHOT,Cache-control: no-cache,transferMode.dlna.org: Streaming]]
2011-01-16 09:38:58,484 DEBUG [ResourceTransportRequestHandler] GET /resource/35/MEDIA_ITEM/MPEG_PS_PAL HTTP/1.1, headers = [HOST: 192.168.1.6:8895,USER-AGENT: UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2,Range: bytes=11501568-11999231,transferMode.dlna.org: Streaming]]
2011-01-16 09:38:58,500 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-16 09:38:58,500 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:38:58,500 DEBUG [ResourceTransportRequestHandler] Request for resource 35 and type 'MEDIA_ITEM' received
2011-01-16 09:38:58,500 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 35)
2011-01-16 09:38:58,500 DEBUG [VideoDAOImpl] Reading a Video (id = 35)
2011-01-16 09:38:58,500 DEBUG [VideoDeliveryEngine] Delivering item '35' for client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:38:58,500 DEBUG [VideoDeliveryEngine] Delivering file 'insects.mpg' in native format
2011-01-16 09:38:58,500 DEBUG [MediaItemDAOImpl] Getting file of media item 35
2011-01-16 09:38:58,500 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 497664
2011-01-16 09:38:58,500 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-01-16 09:38:58,500 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 206 Partial Content, headers = [[Content-Type: video/mpeg,Content-Range: bytes 11501568-11999231/291245793,Content-Length: 497664,Date: Sun, 16 Jan 2011 14:38:58 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/0.5-SNAPSHOT,Cache-control: no-cache,transferMode.dlna.org: Streaming]]
2011-01-16 09:38:58,890 DEBUG [ResourceTransportRequestHandler] GET /resource/35/MEDIA_ITEM/MPEG_PS_PAL HTTP/1.1, headers = [HOST: 192.168.1.6:8895,USER-AGENT: UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2,Range: bytes=12689408-13187071,transferMode.dlna.org: Streaming]]
2011-01-16 09:38:58,890 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-16 09:38:58,890 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:38:58,890 DEBUG [ResourceTransportRequestHandler] Request for resource 35 and type 'MEDIA_ITEM' received
2011-01-16 09:38:58,890 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 35)
2011-01-16 09:38:58,890 DEBUG [VideoDAOImpl] Reading a Video (id = 35)
2011-01-16 09:38:58,906 DEBUG [VideoDeliveryEngine] Delivering item '35' for client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:38:58,906 DEBUG [VideoDeliveryEngine] Delivering file 'insects.mpg' in native format
2011-01-16 09:38:58,906 DEBUG [MediaItemDAOImpl] Getting file of media item 35
2011-01-16 09:38:58,906 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 497664
2011-01-16 09:38:58,906 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-01-16 09:38:58,906 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 206 Partial Content, headers = [[Content-Type: video/mpeg,Content-Range: bytes 12689408-13187071/291245793,Content-Length: 497664,Date: Sun, 16 Jan 2011 14:38:58 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/0.5-SNAPSHOT,Cache-control: no-cache,transferMode.dlna.org: Streaming]]
2011-01-16 09:38:59,296 DEBUG [ResourceTransportRequestHandler] GET /resource/35/MEDIA_ITEM/MPEG_PS_PAL HTTP/1.1, headers = [HOST: 192.168.1.6:8895,USER-AGENT: UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2,Range: bytes=13877248-14374911,transferMode.dlna.org: Streaming]]
2011-01-16 09:38:59,296 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-16 09:38:59,296 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:38:59,296 DEBUG [ResourceTransportRequestHandler] Request for resource 35 and type 'MEDIA_ITEM' received
2011-01-16 09:38:59,296 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 35)
2011-01-16 09:38:59,296 DEBUG [VideoDAOImpl] Reading a Video (id = 35)
2011-01-16 09:38:59,296 DEBUG [VideoDeliveryEngine] Delivering item '35' for client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:38:59,296 DEBUG [VideoDeliveryEngine] Delivering file 'insects.mpg' in native format
2011-01-16 09:38:59,296 DEBUG [MediaItemDAOImpl] Getting file of media item 35
2011-01-16 09:38:59,312 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 497664
2011-01-16 09:38:59,312 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-01-16 09:38:59,312 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 206 Partial Content, headers = [[Content-Type: video/mpeg,Content-Range: bytes 13877248-14374911/291245793,Content-Length: 497664,Date: Sun, 16 Jan 2011 14:38:59 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/0.5-SNAPSHOT,Cache-control: no-cache,transferMode.dlna.org: Streaming]]
2011-01-16 09:38:59,703 DEBUG [ResourceTransportRequestHandler] GET /resource/35/MEDIA_ITEM/MPEG_PS_PAL HTTP/1.1, headers = [HOST: 192.168.1.6:8895,USER-AGENT: UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2,Range: bytes=15069184-15566847,transferMode.dlna.org: Streaming]]
2011-01-16 09:38:59,703 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-16 09:38:59,703 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:38:59,703 DEBUG [ResourceTransportRequestHandler] Request for resource 35 and type 'MEDIA_ITEM' received
2011-01-16 09:38:59,703 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 35)
2011-01-16 09:38:59,703 DEBUG [VideoDAOImpl] Reading a Video (id = 35)
2011-01-16 09:38:59,703 DEBUG [VideoDeliveryEngine] Delivering item '35' for client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:38:59,703 DEBUG [VideoDeliveryEngine] Delivering file 'insects.mpg' in native format
2011-01-16 09:38:59,703 DEBUG [MediaItemDAOImpl] Getting file of media item 35
2011-01-16 09:38:59,703 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 497664
2011-01-16 09:38:59,703 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-01-16 09:38:59,703 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 206 Partial Content, headers = [[Content-Type: video/mpeg,Content-Range: bytes 15069184-15566847/291245793,Content-Length: 497664,Date: Sun, 16 Jan 2011 14:38:59 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/0.5-SNAPSHOT,Cache-control: no-cache,transferMode.dlna.org: Streaming]]
2011-01-16 09:39:00,109 DEBUG [ResourceTransportRequestHandler] GET /resource/35/MEDIA_ITEM/MPEG_PS_PAL HTTP/1.1, headers = [HOST: 192.168.1.6:8895,USER-AGENT: UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2,Range: bytes=16261120-16758783,transferMode.dlna.org: Streaming]]
2011-01-16 09:39:00,109 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-16 09:39:00,109 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:39:00,109 DEBUG [ResourceTransportRequestHandler] Request for resource 35 and type 'MEDIA_ITEM' received
2011-01-16 09:39:00,109 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 35)
2011-01-16 09:39:00,109 DEBUG [VideoDAOImpl] Reading a Video (id = 35)
2011-01-16 09:39:00,109 DEBUG [VideoDeliveryEngine] Delivering item '35' for client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:39:00,109 DEBUG [VideoDeliveryEngine] Delivering file 'insects.mpg' in native format
2011-01-16 09:39:00,109 DEBUG [MediaItemDAOImpl] Getting file of media item 35
2011-01-16 09:39:00,109 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 497664
2011-01-16 09:39:00,109 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-01-16 09:39:00,109 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 206 Partial Content, headers = [[Content-Type: video/mpeg,Content-Range: bytes 16261120-16758783/291245793,Content-Length: 497664,Date: Sun, 16 Jan 2011 14:39:00 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/0.5-SNAPSHOT,Cache-control: no-cache,transferMode.dlna.org: Streaming]]
2011-01-16 09:39:00,500 DEBUG [ResourceTransportRequestHandler] GET /resource/35/MEDIA_ITEM/MPEG_PS_PAL HTTP/1.1, headers = [HOST: 192.168.1.6:8895,USER-AGENT: UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2,Range: bytes=17463296-17960959,transferMode.dlna.org: Streaming]]
2011-01-16 09:39:00,500 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-16 09:39:00,500 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:39:00,500 DEBUG [ResourceTransportRequestHandler] Request for resource 35 and type 'MEDIA_ITEM' received
2011-01-16 09:39:00,500 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 35)
2011-01-16 09:39:00,500 DEBUG [VideoDAOImpl] Reading a Video (id = 35)
2011-01-16 09:39:00,515 DEBUG [VideoDeliveryEngine] Delivering item '35' for client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:39:00,515 DEBUG [VideoDeliveryEngine] Delivering file 'insects.mpg' in native format
2011-01-16 09:39:00,515 DEBUG [MediaItemDAOImpl] Getting file of media item 35
2011-01-16 09:39:00,515 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 497664
2011-01-16 09:39:00,515 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-01-16 09:39:00,515 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 206 Partial Content, headers = [[Content-Type: video/mpeg,Content-Range: bytes 17463296-17960959/291245793,Content-Length: 497664,Date: Sun, 16 Jan 2011 14:39:00 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/0.5-SNAPSHOT,Cache-control: no-cache,transferMode.dlna.org: Streaming]]
2011-01-16 09:39:00,812 DEBUG [ResourceTransportRequestHandler] GET /resource/35/MEDIA_ITEM/MPEG_PS_PAL HTTP/1.1, headers = [HOST: 192.168.1.6:8895,USER-AGENT: UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2,Range: bytes=18642944-19140607,transferMode.dlna.org: Streaming]]
2011-01-16 09:39:00,812 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-16 09:39:00,812 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:39:00,812 DEBUG [ResourceTransportRequestHandler] Request for resource 35 and type 'MEDIA_ITEM' received
2011-01-16 09:39:00,812 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 35)
2011-01-16 09:39:00,812 DEBUG [VideoDAOImpl] Reading a Video (id = 35)
2011-01-16 09:39:00,812 DEBUG [VideoDeliveryEngine] Delivering item '35' for client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:39:00,812 DEBUG [VideoDeliveryEngine] Delivering file 'insects.mpg' in native format
2011-01-16 09:39:00,812 DEBUG [MediaItemDAOImpl] Getting file of media item 35
2011-01-16 09:39:00,812 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 497664
2011-01-16 09:39:00,812 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-01-16 09:39:00,812 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 206 Partial Content, headers = [[Content-Type: video/mpeg,Content-Range: bytes 18642944-19140607/291245793,Content-Length: 497664,Date: Sun, 16 Jan 2011 14:39:00 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/0.5-SNAPSHOT,Cache-control: no-cache,transferMode.dlna.org: Streaming]]
2011-01-16 09:39:01,203 DEBUG [ResourceTransportRequestHandler] GET /resource/35/MEDIA_ITEM/MPEG_PS_PAL HTTP/1.1, headers = [HOST: 192.168.1.6:8895,USER-AGENT: UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2,Range: bytes=19832832-20330495,transferMode.dlna.org: Streaming]]
2011-01-16 09:39:01,203 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-16 09:39:01,203 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:39:01,203 DEBUG [ResourceTransportRequestHandler] Request for resource 35 and type 'MEDIA_ITEM' received
2011-01-16 09:39:01,203 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 35)
2011-01-16 09:39:01,203 DEBUG [VideoDAOImpl] Reading a Video (id = 35)
2011-01-16 09:39:01,218 DEBUG [VideoDeliveryEngine] Delivering item '35' for client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:39:01,218 DEBUG [VideoDeliveryEngine] Delivering file 'insects.mpg' in native format
2011-01-16 09:39:01,218 DEBUG [MediaItemDAOImpl] Getting file of media item 35
2011-01-16 09:39:01,218 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 497664
2011-01-16 09:39:01,218 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-01-16 09:39:01,218 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 206 Partial Content, headers = [[Content-Type: video/mpeg,Content-Range: bytes 19832832-20330495/291245793,Content-Length: 497664,Date: Sun, 16 Jan 2011 14:39:01 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/0.5-SNAPSHOT,Cache-control: no-cache,transferMode.dlna.org: Streaming]]
2011-01-16 09:39:01,609 DEBUG [ResourceTransportRequestHandler] GET /resource/35/MEDIA_ITEM/MPEG_PS_PAL HTTP/1.1, headers = [HOST: 192.168.1.6:8895,USER-AGENT: UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2,Range: bytes=21026816-21524479,transferMode.dlna.org: Streaming]]
2011-01-16 09:39:01,609 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-16 09:39:01,609 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:39:01,609 DEBUG [ResourceTransportRequestHandler] Request for resource 35 and type 'MEDIA_ITEM' received
2011-01-16 09:39:01,609 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 35)
2011-01-16 09:39:01,609 DEBUG [VideoDAOImpl] Reading a Video (id = 35)
2011-01-16 09:39:01,609 DEBUG [VideoDeliveryEngine] Delivering item '35' for client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:39:01,609 DEBUG [VideoDeliveryEngine] Delivering file 'insects.mpg' in native format
2011-01-16 09:39:01,609 DEBUG [MediaItemDAOImpl] Getting file of media item 35
2011-01-16 09:39:01,625 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 497664
2011-01-16 09:39:01,625 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-01-16 09:39:01,625 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 206 Partial Content, headers = [[Content-Type: video/mpeg,Content-Range: bytes 21026816-21524479/291245793,Content-Length: 497664,Date: Sun, 16 Jan 2011 14:39:01 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/0.5-SNAPSHOT,Cache-control: no-cache,transferMode.dlna.org: Streaming]]
2011-01-16 09:39:02,015 DEBUG [ResourceTransportRequestHandler] GET /resource/35/MEDIA_ITEM/MPEG_PS_PAL HTTP/1.1, headers = [HOST: 192.168.1.6:8895,USER-AGENT: UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2,Range: bytes=22214656-22712319,transferMode.dlna.org: Streaming]]
2011-01-16 09:39:02,015 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-16 09:39:02,015 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:39:02,015 DEBUG [ResourceTransportRequestHandler] Request for resource 35 and type 'MEDIA_ITEM' received
2011-01-16 09:39:02,015 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 35)
2011-01-16 09:39:02,015 DEBUG [VideoDAOImpl] Reading a Video (id = 35)
2011-01-16 09:39:02,015 DEBUG [VideoDeliveryEngine] Delivering item '35' for client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:39:02,015 DEBUG [VideoDeliveryEngine] Delivering file 'insects.mpg' in native format
2011-01-16 09:39:02,015 DEBUG [MediaItemDAOImpl] Getting file of media item 35
2011-01-16 09:39:02,015 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 497664
2011-01-16 09:39:02,015 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-01-16 09:39:02,015 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 206 Partial Content, headers = [[Content-Type: video/mpeg,Content-Range: bytes 22214656-22712319/291245793,Content-Length: 497664,Date: Sun, 16 Jan 2011 14:39:02 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/0.5-SNAPSHOT,Cache-control: no-cache,transferMode.dlna.org: Streaming]]
2011-01-16 09:39:02,234 DEBUG [WebServer] Incoming connection from /192.168.1.5:58797
2011-01-16 09:39:02,421 DEBUG [ResourceTransportRequestHandler] GET /resource/35/MEDIA_ITEM/MPEG_PS_PAL HTTP/1.1, headers = [HOST: 192.168.1.6:8895,USER-AGENT: UPnP/1.0 DLNADOC/1.50 Intel_SDK_for_UPnP_devices/1.2,Range: bytes=21028864-,transferMode.dlna.org: Streaming]]
2011-01-16 09:39:02,421 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.5
2011-01-16 09:39:02,421 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:39:02,421 DEBUG [ResourceTransportRequestHandler] Request for resource 35 and type 'MEDIA_ITEM' received
2011-01-16 09:39:02,421 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 35)
2011-01-16 09:39:02,421 DEBUG [VideoDAOImpl] Reading a Video (id = 35)
2011-01-16 09:39:02,421 DEBUG [VideoDeliveryEngine] Delivering item '35' for client 'IPAddress=/192.168.1.5, Profile=Toshiba Rezga'
2011-01-16 09:39:02,421 DEBUG [VideoDeliveryEngine] Delivering file 'insects.mpg' in native format
2011-01-16 09:39:02,421 DEBUG [MediaItemDAOImpl] Getting file of media item 35
2011-01-16 09:39:02,421 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 270216929
2011-01-16 09:39:02,421 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-01-16 09:39:02,421 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 206 Partial Content, headers = [[Content-Type: video/mpeg,Content-Range: bytes 21028864-291245792/291245793,Content-Length: 270216929,Date: Sun, 16 Jan 2011 14:39:02 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/0.5-SNAPSHOT,Cache-control: no-cache,transferMode.dlna.org: Streaming]]
2011-01-16 09:39:09,437 DEBUG [WebServer] I/O error: Software caused connection abort: socket write error
2011-01-16 09:39:37,921 INFO  [LibraryAdditionsCheckerThread] Finished looking for newly added files
2011-01-16 09:39:38,125 DEBUG [DiscoveryManager] UPNP device 223a9375-eb61-3269-abcf-3ad9f2e63b1f is unavailable
2011-01-16 09:39:38,125 INFO  [DiscoveryAdvertisementNotifier] Leaving DiscoveryAdvertisementNotifier
2011-01-16 09:39:38,125 INFO  [EventSubscriptionExpirationChecker] Leaving EventSubscriptionExpirationChecker, removing all event subscriptions
2011-01-16 09:39:38,125 DEBUG [WebServer] Socket closed
2011-01-16 09:39:38,125 INFO  [WebServer] WebServer shutting down
2011-01-16 09:39:38,125 INFO  [EventDispatcher] Leaving EventDispatcher
2011-01-16 09:39:38,140 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP byebye using interface eth0 (Intel(R) 82567LM Gigabit Network Connection - Packet Scheduler Miniport) and address YOAKAM.aircraft.kfabsc.com, timeout = 0
2011-01-16 09:39:38,140 INFO  [DatabaseManager] Shutting down database
2011-01-16 09:39:38,140 DEBUG [DBConnectionPool] Closed connection for pool Serviio DB Pool
2011-01-16 09:39:38,140 DEBUG [MultiCastUtils] Problem stopping multicast socket
java.net.SocketException: error setting options
   at java.net.PlainDatagramSocketImpl.leave(Native Method)
   at java.net.PlainDatagramSocketImpl.leave(Unknown Source)
   at java.net.MulticastSocket.leaveGroup(Unknown Source)
   at org.serviio.util.MultiCastUtils.stopMultiCastSocket(MultiCastUtils.java:66)
   at org.serviio.upnp.discovery.DiscoverySSDPMessageListener.run(DiscoverySSDPMessageListener.java:107)
   at java.lang.Thread.run(Unknown Source)
2011-01-16 09:39:38,140 DEBUG [DBConnectionPool] Closed connection for pool Serviio DB Pool
2011-01-16 09:39:38,140 INFO  [DiscoverySSDPMessageListener] Leaving DiscoverySSDPMessageListener
2011-01-16 09:39:38,218 DEBUG [DatabaseManager] DB shutdown returned: Derby system shutdown.
2011-01-16 09:39:38,218 INFO  [VideoDeliveryEngine] Cleaning transcode engine and its data
2011-01-16 09:39:38,218 DEBUG [VideoDeliveryEngine] Deleting temporary transcoded files from: C:\WINDOWS\TEMP\Serviio

<<

zip

User avatar

Serviio developer / Site Admin

Posts: 17212

Joined: Sat Oct 24, 2009 12:24 pm

Location: London, UK

Post Sun Jan 16, 2011 6:15 pm

Re: problem with pause ff rew

OK, in this case it uses byte based seek. So it must be something about the time based seek implementation, either of the TV or Serviio... will look further, but after 0.5 is out
<<

Al_V

Serviio newbie

Posts: 7

Joined: Fri Dec 17, 2010 3:59 am

Post Tue Jan 18, 2011 4:25 pm

Re: problem with pause ff rew

But the TV always receives mpeg2 format right?
Either native or transcoded by serviio.
So won't the TV always use the same seek method?
Thanks, and please correct me if I'm missing something.
<<

zip

User avatar

Serviio developer / Site Admin

Posts: 17212

Joined: Sat Oct 24, 2009 12:24 pm

Location: London, UK

Post Tue Jan 18, 2011 6:13 pm

Re: problem with pause ff rew

Serviio only supports time base seek when it transcodes and byte seek for native files. It tells this to the device - so the device makes those requests.
<<

ninjavz

User avatar

Serviio newbie

Posts: 18

Joined: Mon Jan 10, 2011 3:39 am

Location: Peru

Post Sat Jan 29, 2011 2:44 pm

Re: problem with pause ff rew

FYI
I installed v0.5 and Pause, FF and REW are still not working.

Zip, I send you the protocol Info of the Toshiba 55ux600u via PM.
<<

rlocke

Serviio newbie

Posts: 3

Joined: Mon May 02, 2011 4:19 pm

Post Mon May 02, 2011 4:21 pm

Re: problem with pause ff rew

I'm seeing exactly the same problems streaming to my Toshiba Regza 47XL700. Pause, fast forward, rewind simply don't work.

Any ideas?
<<

rlocke

Serviio newbie

Posts: 3

Joined: Mon May 02, 2011 4:19 pm

Post Tue May 03, 2011 12:32 pm

Re: problem with pause ff rew

It seems this problem was solved in this thread:

viewtopic.php?f=6&t=1242

Just look for Octalus' config file, it solves the problem nicely!

Return to Toshiba

Who is online

Users browsing this forum: No registered users and 4 guests

Powered by phpBB © 2000, 2002, 2005, 2007 phpBB Group.
Designed by ST Software for PTF.