Sun Feb 27, 2011 9:50 am by mike.clayton999
Hi Guys,
Here is the
detailed log from
Server Startup (I have removed the Library Meida Check)
- Code:
2011-02-27 08:58:34,384 DEBUG [ApplicationInstanceManager] Listening for application instances on socket 44331
2011-02-27 08:58:34,400 INFO [MediaServer] ------------------------------------------------------------------------
2011-02-27 08:58:34,400 INFO [MediaServer] Serviio DLNA media streaming server v 0.5 (rev. 975aa458693c)
2011-02-27 08:58:34,400 INFO [MediaServer] Petr Nejedly 2009-2011
2011-02-27 08:58:34,400 INFO [MediaServer] http://www.serviio.org
2011-02-27 08:58:34,400 INFO [MediaServer]
2011-02-27 08:58:34,400 INFO [MediaServer] Java 1.6.0_15-Sun Microsystems Inc.
2011-02-27 08:58:34,400 INFO [MediaServer] OS Windows XP x86 5.1
2011-02-27 08:58:34,400 INFO [MediaServer] File encoding: windows-1252
2011-02-27 08:58:34,400 INFO [MediaServer] ------------------------------------------------------------------------
2011-02-27 08:58:34,603 INFO [RestletServer] Starting Restlet server exposed on localhost:23423
2011-02-27 08:58:34,681 INFO [DBSchemaUpdateExecutor] Checking if DB schema needs to be updated
2011-02-27 08:58:34,696 DEBUG [DBLogDAOImpl] Checking if script 'script-0.1.sql' has been run
2011-02-27 08:58:35,118 DEBUG [ConfigEntryDAOImpl] Reading all ConfigEntries
2011-02-27 08:58:36,602 INFO [WebServer] Socket buffer set to 65535 bytes
2011-02-27 08:58:36,602 INFO [Device] Created UPnP Device with UUID: 82389917-f0f6-3db1-8111-6fff694a3834, bound address: 10.1.1.7
2011-02-27 08:58:36,649 INFO [CompositeCacheManager] Creating cache manager from config file: /cache.ccf
2011-02-27 08:58:36,665 INFO [ThreadPoolManager] thread_pool.default PoolConfiguration = useBoundary = [true] boundarySize = [2000] maximumPoolSize = [150] minimumPoolSize = [4] keepAliveTime = [300000] whenBlockedPolicy = [RUN] startUpSize = [4]
2011-02-27 08:58:36,665 INFO [CompositeCacheConfigurator] Setting default auxiliaries to null
2011-02-27 08:58:36,665 INFO [CompositeCacheConfigurator] No special CompositeCacheAttributes class defined for key [jcs.default.cacheattributes], using default class.
2011-02-27 08:58:36,665 INFO [CompositeCacheConfigurator] setting defaultCompositeCacheAttributes to [ useLateral = true, useRemote = true, useDisk = true, maxObjs = 100, maxSpoolPerRun = -1, diskUsagePattern = 0 ]
2011-02-27 08:58:36,665 INFO [CompositeCacheConfigurator] No special ElementAttribute class defined for key [jcs.default.elementattributes], using default class.
2011-02-27 08:58:36,665 INFO [CompositeCacheConfigurator] setting defaultElementAttributes to [ IS_LATERAL = true, IS_SPOOL = true, IS_REMOTE = true, IS_ETERNAL = true, MaxLifeSeconds = -1, IdleTime = -1, CreateTime = 1298797116665, LastAccessTime = 1298797116665, getTimeToLiveSeconds() = -1, createTime = 1298797116665 ]
2011-02-27 08:58:36,681 INFO [CompositeCacheConfigurator] No special ElementAttribute class defined for key [jcs.region.local_resetafterplay.elementattributes], using default class.
2011-02-27 08:58:36,712 INFO [LRUMemoryCache] initialized LRUMemoryCache for local_resetafterplay
2011-02-27 08:58:36,712 INFO [CompositeCache] Constructed cache with name [local_resetafterplay] and cache attributes [ useLateral = true, useRemote = true, useDisk = true, maxObjs = 1000, maxSpoolPerRun = -1, diskUsagePattern = 0 ]
2011-02-27 08:58:36,712 INFO [CompositeCacheConfigurator] No special ElementAttribute class defined for key [jcs.region.local_default.elementattributes], using default class.
2011-02-27 08:58:36,712 INFO [LRUMemoryCache] initialized LRUMemoryCache for local_default
2011-02-27 08:58:36,712 INFO [CompositeCache] Constructed cache with name [local_default] and cache attributes [ useLateral = true, useRemote = true, useDisk = true, maxObjs = 1000, maxSpoolPerRun = -1, diskUsagePattern = 0 ]
2011-02-27 08:58:36,712 INFO [CompositeCacheConfigurator] Parsed regions [local_resetafterplay, local_default]
2011-02-27 08:58:36,712 INFO [CompositeCacheConfigurator] Finished configuration in 47 ms.
2011-02-27 08:58:36,790 DEBUG [DBLogDAOImpl] Checking if script 'script-0.1.1.sql' has been run
2011-02-27 08:58:36,806 DEBUG [DBLogDAOImpl] Checking if script 'script-0.2.sql' has been run
2011-02-27 08:58:36,806 DEBUG [DBLogDAOImpl] Checking if script 'script-0.3.1.sql' has been run
2011-02-27 08:58:36,806 DEBUG [DBLogDAOImpl] Checking if script 'script-0.4.sql' has been run
2011-02-27 08:58:36,806 DEBUG [DBLogDAOImpl] Checking if script 'script-0.4.1.sql' has been run
2011-02-27 08:58:36,806 DEBUG [DBLogDAOImpl] Checking if script 'script-0.4.2.sql' has been run
2011-02-27 08:58:36,806 DEBUG [DBLogDAOImpl] Checking if script 'script-0.5.sql' has been run
2011-02-27 08:58:37,305 INFO [ProfilesDefinitionParser] Parsing Profiles definition
2011-02-27 08:58:37,368 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 1
2011-02-27 08:58:38,352 INFO [ProfilesDefinitionParser] Added profile 'Generic DLNA profile' (id=1)
2011-02-27 08:58:38,352 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 2
2011-02-27 08:58:38,665 INFO [ProfilesDefinitionParser] Added profile 'Samsung TV (B-series)' (id=2)
2011-02-27 08:58:38,665 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 3
2011-02-27 08:58:38,805 INFO [ProfilesDefinitionParser] Added profile 'XBox 360' (id=3)
2011-02-27 08:58:38,946 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 4
2011-02-27 08:58:39,024 INFO [ProfilesDefinitionParser] Added profile 'Playstation 3' (id=4)
2011-02-27 08:58:39,024 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 5
2011-02-27 08:58:39,243 INFO [ProfilesDefinitionParser] Added profile 'Samsung TV (A-series)' (id=5)
2011-02-27 08:58:39,243 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 6
2011-02-27 08:58:39,321 INFO [ProfilesDefinitionParser] Added profile 'DirecTV HD-DVR' (id=6)
2011-02-27 08:58:39,321 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 7
2011-02-27 08:58:39,555 INFO [ProfilesDefinitionParser] Added profile 'Samsung TV / player (C-series)' (id=7)
2011-02-27 08:58:39,555 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 8
2011-02-27 08:58:39,633 INFO [ProfilesDefinitionParser] Added profile 'LG BD player' (id=8)
2011-02-27 08:58:39,649 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 9
2011-02-27 08:58:40,149 INFO [ProfilesDefinitionParser] Added profile 'Sony Bravia TV' (id=9)
2011-02-27 08:58:40,149 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 10
2011-02-27 08:58:41,055 INFO [ProfilesDefinitionParser] Added profile 'Sony BD Player (EU)' (id=10)
2011-02-27 08:58:41,055 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 11
2011-02-27 08:58:42,289 INFO [ProfilesDefinitionParser] Added profile 'Sony BD Player (US)' (id=11)
2011-02-27 08:58:42,289 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 12
2011-02-27 08:58:42,414 INFO [ProfilesDefinitionParser] Added profile 'Panasonic Viera' (id=12)
2011-02-27 08:58:42,414 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 13
2011-02-27 08:58:42,664 INFO [ProfilesDefinitionParser] Added profile 'Toshiba Rezga' (id=13)
2011-02-27 08:58:42,664 DEBUG [FFMPEGWrapper] Invoking FFMPEG to check if it exists of path C:\Program Files\Serviio_DLNA\bin\\..\lib/ffmpeg.exe
2011-02-27 08:58:42,664 DEBUG [ProcessExecutor] Starting C:\Program Files\Serviio_DLNA\bin\\..\lib/ffmpeg.exe
2011-02-27 08:58:42,821 INFO [VideoDeliveryEngine] Cleaning transcode engine and its data
2011-02-27 08:58:42,930 INFO [WebServer] WebServer starting on port 8895
2011-02-27 08:58:42,945 DEBUG [DiscoveryManager] UPNP device 82389917-f0f6-3db1-8111-6fff694a3834 (10.1.1.7) is available
2011-02-27 08:58:42,945 INFO [RendererSearchSender] Searching for Renderer devices
2011-02-27 08:58:42,945 INFO [DiscoveryAdvertisementNotifier] Starting DiscoveryAdvertisementNotifier
2011-02-27 08:58:42,945 INFO [RendererExpirationChecker] Starting RendererExpirationChecker
2011-02-27 08:58:42,961 INFO [EventDispatcher] Starting EventDispatcher
2011-02-27 08:58:42,961 INFO [EventSubscriptionExpirationChecker] Starting EventSubscriptionExpirationChecker
2011-02-27 08:58:43,024 INFO [DiscoverySSDPMessageListener] Starting DiscoverySSDPMessageListener using interface eth2 (VIA Rhine II Fast Ethernet Adapter - Virtual Machine Network Services Driver) and address 10.1.1.7, timeout = 0
2011-02-27 08:58:43,727 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth2 (VIA Rhine II Fast Ethernet Adapter - Virtual Machine Network Services Driver) and address 10.1.1.7, timeout = 0
2011-02-27 08:58:43,758 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 82389917-f0f6-3db1-8111-6fff694a3834
2011-02-27 08:58:43,789 DEBUG [RendererSearchSender] Multicasting SSDP M-SEARCH using interface eth1 (VMware Virtual Ethernet Adapter for VMnet1) and address 192.168.0.1, timeout = 0
2011-02-27 08:58:43,789 DEBUG [RendererSearchSender] Sending 1 'm-search' messages
2011-02-27 08:58:43,789 DEBUG [RendererSearchSender] Multicasting SSDP M-SEARCH using interface eth0 (VMware Virtual Ethernet Adapter for VMnet8) and address 192.168.189.1, timeout = 0
2011-02-27 08:58:43,789 DEBUG [RendererSearchSender] Sending 1 'm-search' messages
2011-02-27 08:58:43,805 DEBUG [RendererSearchSender] Multicasting SSDP M-SEARCH using interface eth2 (VIA Rhine II Fast Ethernet Adapter - Virtual Machine Network Services Driver) and address 10.1.1.7, timeout = 0
2011-02-27 08:58:43,805 DEBUG [RendererSearchSender] Sending 1 'm-search' messages
2011-02-27 08:58:43,805 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /10.1.1.7:2238
2011-02-27 08:58:43,805 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /10.1.1.7:2238
2011-02-27 08:58:43,805 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /10.1.1.7:2238
2011-02-27 08:58:45,570 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth1 (VMware Virtual Ethernet Adapter for VMnet1) and address 192.168.0.1, timeout = 0
2011-02-27 08:58:45,570 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 82389917-f0f6-3db1-8111-6fff694a3834
2011-02-27 08:58:47,383 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth0 (VMware Virtual Ethernet Adapter for VMnet8) and address 192.168.189.1, timeout = 0
2011-02-27 08:58:47,383 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 82389917-f0f6-3db1-8111-6fff694a3834
2011-02-27 08:58:49,195 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:10
2011-02-27 08:58:49,804 DEBUG [RendererSearchSender] Finished searching for Renderer devices
2011-02-27 08:58:59,272 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth2 (VIA Rhine II Fast Ethernet Adapter - Virtual Machine Network Services Driver) and address 10.1.1.7, timeout = 0
2011-02-27 08:58:59,272 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 82389917-f0f6-3db1-8111-6fff694a3834
2011-02-27 08:59:01,084 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth1 (VMware Virtual Ethernet Adapter for VMnet1) and address 192.168.0.1, timeout = 0
2011-02-27 08:59:01,084 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 82389917-f0f6-3db1-8111-6fff694a3834
2011-02-27 08:59:02,896 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth0 (VMware Virtual Ethernet Adapter for VMnet8) and address 192.168.189.1, timeout = 0
2011-02-27 08:59:02,896 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 82389917-f0f6-3db1-8111-6fff694a3834
2011-02-27 08:59:04,709 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:10
2011-02-27 08:59:09,802 INFO [LibraryAdditionsCheckerThread] Started looking for newly added files
2011-02-27 08:59:09,818 INFO [LibraryUpdatesCheckerThread] Started looking for updates to currently shared files
2011-02-27 08:59:09,818 DEBUG [RepositoryDAOImpl] Reading all Repositories
2011-02-27 08:59:09,833 DEBUG [RepositoryDAOImpl] Reading all Repositories
2011-02-27 08:59:09,974 DEBUG [LibraryAdditionsCheckerThread] Looking for files to share in folder: X:\web\Film\Films
2011-02-27 08:59:09,974 DEBUG [LibraryUpdatesCheckerThread] Looking for removed/updated files in repository: X:\web\Film\Films
2011-02-27 08:59:09,974 DEBUG [MediaItemDAOImpl] Reading MediaItems for Repository (id = 1)
2011-02-27 08:59:10,271 DEBUG [LibraryAdditionsCheckerThread] Found file '16 Blocks.avi', checking if it's already in the Library
2011-02-27 08:59:10,271 DEBUG [MediaItemDAOImpl] Checking if DB already contains media item 16 Blocks.avi
2011-02-27 08:59:12,661 DEBUG [MediaItemDAOImpl] Getting file of media item 1
2011-02-27 08:59:12,802 DEBUG [MediaItemDAOImpl] Media item 16 Blocks.avi already exists in DB
2011-02-27 08:59:12,864 DEBUG [MetadataExtractorConfigDAOImpl] Reading a list of Extractor configuration for type AUDIO
2011-02-27 08:59:12,864 DEBUG [LibraryAdditionsCheckerThread] Found file '2 Fast 2 Furious.avi', checking if it's already in the Library
2011-02-27 08:59:12,880 DEBUG [MediaItemDAOImpl] Checking if DB already contains media item 2 Fast 2 Furious.avi
2011-02-27 08:59:13,036 DEBUG [MetadataExtractorConfigDAOImpl] Reading a list of Extractor configuration for type VIDEO
2011-02-27 08:59:13,036 DEBUG [MetadataExtractorConfigDAOImpl] Reading a list of Extractor configuration for type IMAGE
2011-02-27 08:59:13,052 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 1) and extractor EMBEDDED
2011-02-27 08:59:13,130 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 1) and extractor COVER_IMAGE_IN_FOLDER
2011-02-27 08:59:13,239 DEBUG [MediaItemDAOImpl] Getting file of media item 2
2011-02-27 08:59:13,255 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 2) and extractor EMBEDDED
2011-02-27 08:59:13,270 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 2) and extractor COVER_IMAGE_IN_FOLDER
2011-02-27 08:59:13,270 DEBUG [MediaItemDAOImpl] Media item 2 Fast 2 Furious.avi already exists in DB
2011-02-27 08:59:13,286 DEBUG [LibraryAdditionsCheckerThread] Found file '2001 A Space Odyssey.avi', checking if it's already in the Library
2011-02-27 08:59:13,286 DEBUG [MediaItemDAOImpl] Checking if DB already contains media item 2001 A Space Odyssey.avi
2011-02-27 08:59:13,364 DEBUG [MediaItemDAOImpl] Getting file of media item 3
2011-02-27 08:59:13,380 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 3) and extractor EMBEDDED
2011-02-27 08:59:13,411 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 3) and extractor COVER_IMAGE_IN_FOLDER
2011-02-27 08:59:13,473 DEBUG [MediaItemDAOImpl] Media item 2001 A Space Odyssey.avi already exists in DB[
etc... etc... etc....
2011-02-27 09:16:22,033 DEBUG [MediaItemDAOImpl] Checking if DB already contains media item Zack and Miri Make a Porno (2008).avi
2011-02-27 09:16:22,252 DEBUG [MediaItemDAOImpl] Media item Zack and Miri Make a Porno (2008).avi already exists in DB
2011-02-27 09:16:22,283 DEBUG [LibraryAdditionsCheckerThread] Found file 'Zohan.avi', checking if it's already in the Library
2011-02-27 09:16:22,283 DEBUG [MediaItemDAOImpl] Checking if DB already contains media item Zohan.avi
2011-02-27 09:16:22,440 DEBUG [MediaItemDAOImpl] Media item Zohan.avi already exists in DB
2011-02-27 09:16:22,455 DEBUG [LibraryAdditionsCheckerThread] Found file 'Zoolander.avi', checking if it's already in the Library
2011-02-27 09:16:22,455 DEBUG [MediaItemDAOImpl] Checking if DB already contains media item Zoolander.avi
2011-02-27 09:16:22,627 DEBUG [MediaItemDAOImpl] Media item Zoolander.avi already exists in DB
2011-02-27 09:16:22,627 DEBUG [RepositoryDAOImpl] Marking Repository 1 as scanned with current timestamp
Log while requesting a film via AllShare
- Code:
2011-02-27 09:32:57,222 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /10.1.1.133:19550
2011-02-27 09:32:57,222 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /10.1.1.133:19550
2011-02-27 09:32:59,222 DEBUG [WebServer] Incoming connection from /10.1.1.133:34027
2011-02-27 09:32:59,222 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 82389917-f0f6-3db1-8111-6fff694a3834 from 10.1.1.133 (headers = [user-agent: SEC_HHP_Galaxy S/1.0,Host: 10.1.1.7:8895,Connection: Keep-Alive])
2011-02-27 09:32:59,222 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 10.1.1.133
2011-02-27 09:32:59,222 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 10.1.1.133
2011-02-27 09:32:59,237 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Generic DLNA profile'
2011-02-27 09:33:00,175 DEBUG [WebServer] Incoming connection from /10.1.1.133:37464
2011-02-27 09:33:00,378 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 10.1.1.133:
2011-02-27 09:33:00,378 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 10.1.1.133
2011-02-27 09:33:00,378 DEBUG [ContentDirectory] Browse() called for profile Generic DLNA profile with parameters: objectID = 0, browseFlag = BrowseDirectChildren, filter = *, startIndex = 0, count = 1, sortCriteria =
2011-02-27 09:33:00,378 DEBUG [LocalContentCacheDecorator] Found entry in the cache (local_default), returning it
2011-02-27 09:33:00,393 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-02-27 09:33:00,550 DEBUG [WebServer] Incoming connection from /10.1.1.133:39052
2011-02-27 09:33:00,675 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 10.1.1.133:
2011-02-27 09:33:00,675 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 10.1.1.133
2011-02-27 09:33:00,690 DEBUG [ContentDirectory] Browse() called for profile Generic DLNA profile with parameters: objectID = 0, browseFlag = BrowseDirectChildren, filter = *, startIndex = 0, count = 6, sortCriteria =
2011-02-27 09:33:00,690 DEBUG [LocalContentCacheDecorator] Found entry in the cache (local_default), returning it
2011-02-27 09:33:00,690 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-02-27 09:33:02,034 DEBUG [WebServer] Incoming connection from /10.1.1.133:57366
2011-02-27 09:33:02,300 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 10.1.1.133:
2011-02-27 09:33:02,300 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 10.1.1.133
2011-02-27 09:33:02,300 DEBUG [ContentDirectory] Browse() called for profile Generic DLNA profile with parameters: objectID = V, browseFlag = BrowseDirectChildren, filter = *, startIndex = 0, count = 6, sortCriteria =
2011-02-27 09:33:02,300 DEBUG [LocalContentCacheDecorator] Found entry in the cache (local_default), returning it
2011-02-27 09:33:02,300 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-02-27 09:33:02,487 DEBUG [WebServer] Incoming connection from /10.1.1.133:42315
2011-02-27 09:33:02,690 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 10.1.1.133:
2011-02-27 09:33:02,690 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 10.1.1.133
2011-02-27 09:33:02,690 DEBUG [ContentDirectory] Browse() called for profile Generic DLNA profile with parameters: objectID = V, browseFlag = BrowseDirectChildren, filter = *, startIndex = 6, count = 6, sortCriteria =
2011-02-27 09:33:02,690 DEBUG [LocalContentCacheDecorator] Found entry in the cache (local_default), returning it
2011-02-27 09:33:02,706 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-02-27 09:33:03,659 DEBUG [WebServer] Incoming connection from /10.1.1.133:58837
2011-02-27 09:33:03,909 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 10.1.1.133:
2011-02-27 09:33:03,909 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 10.1.1.133
2011-02-27 09:33:03,924 DEBUG [ContentDirectory] Browse() called for profile Generic DLNA profile with parameters: objectID = V_M, browseFlag = BrowseDirectChildren, filter = *, startIndex = 0, count = 6, sortCriteria =
2011-02-27 09:33:03,924 DEBUG [LocalContentCacheDecorator] Found entry in the cache (local_default), returning it
2011-02-27 09:33:03,924 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-02-27 09:33:04,143 DEBUG [WebServer] Incoming connection from /10.1.1.133:49448
2011-02-27 09:33:04,424 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 10.1.1.133:
2011-02-27 09:33:04,424 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 10.1.1.133
2011-02-27 09:33:04,440 DEBUG [ContentDirectory] Browse() called for profile Generic DLNA profile with parameters: objectID = V_M, browseFlag = BrowseDirectChildren, filter = *, startIndex = 6, count = 6, sortCriteria =
2011-02-27 09:33:04,440 DEBUG [LocalContentCacheDecorator] Found entry in the cache (local_default), returning it
2011-02-27 09:33:04,440 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-02-27 09:33:04,784 DEBUG [WebServer] Incoming connection from /10.1.1.133:37729
2011-02-27 09:33:04,909 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 10.1.1.133:
2011-02-27 09:33:04,909 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 10.1.1.133
2011-02-27 09:33:04,909 DEBUG [ContentDirectory] Browse() called for profile Generic DLNA profile with parameters: objectID = V_M, browseFlag = BrowseDirectChildren, filter = *, startIndex = 12, count = 6, sortCriteria =
2011-02-27 09:33:04,909 DEBUG [LocalContentCacheDecorator] Found entry in the cache (local_default), returning it
2011-02-27 09:33:04,924 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-02-27 09:33:05,065 DEBUG [WebServer] Incoming connection from /10.1.1.133:45906
2011-02-27 09:33:05,205 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 10.1.1.133:
2011-02-27 09:33:05,205 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 10.1.1.133
2011-02-27 09:33:05,205 DEBUG [ContentDirectory] Browse() called for profile Generic DLNA profile with parameters: objectID = V_M, browseFlag = BrowseDirectChildren, filter = *, startIndex = 18, count = 6, sortCriteria =
2011-02-27 09:33:05,205 DEBUG [LocalContentCacheDecorator] Found entry in the cache (local_default), returning it
2011-02-27 09:33:05,221 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-02-27 09:33:05,362 DEBUG [WebServer] Incoming connection from /10.1.1.133:43557
2011-02-27 09:33:05,659 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 10.1.1.133:
2011-02-27 09:33:05,659 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 10.1.1.133
2011-02-27 09:33:05,659 DEBUG [ContentDirectory] Browse() called for profile Generic DLNA profile with parameters: objectID = V_M, browseFlag = BrowseDirectChildren, filter = *, startIndex = 24, count = 6, sortCriteria =
2011-02-27 09:33:05,659 DEBUG [LocalContentCacheDecorator] Found entry in the cache (local_default), returning it
2011-02-27 09:33:05,674 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-02-27 09:33:05,893 DEBUG [WebServer] Incoming connection from /10.1.1.133:53891
2011-02-27 09:33:06,096 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 10.1.1.133:
2011-02-27 09:33:06,096 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 10.1.1.133
2011-02-27 09:33:06,377 DEBUG [ContentDirectory] Browse() called for profile Generic DLNA profile with parameters: objectID = V_M, browseFlag = BrowseDirectChildren, filter = *, startIndex = 30, count = 6, sortCriteria =
2011-02-27 09:33:06,377 DEBUG [LocalContentCacheDecorator] Found entry in the cache (local_default), returning it
2011-02-27 09:33:06,377 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-02-27 09:33:06,408 DEBUG [WebServer] Incoming connection from /10.1.1.133:35324
2011-02-27 09:33:06,408 DEBUG [ResourceTransportRequestHandler] GET http://10.1.1.7:8895/resource/2801/MEDIA_ITEM/AVI HTTP/1.1, headers = [Accept: */*,Host: 10.1.1.7:8895,Range: bytes=0-5,x-wap-profile: ,User-Agent: Samsung Player User-Agent,Connection: Keep-Alive]]
2011-02-27 09:33:06,408 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 10.1.1.133
2011-02-27 09:33:06,408 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/10.1.1.133, Profile=Generic DLNA profile'
2011-02-27 09:33:06,408 ERROR [ResourceTransportRequestHandler] Error while processing resource, sending back 500 error. Message: For input string: ""
java.lang.NumberFormatException: For input string: ""
at java.lang.NumberFormatException.forInputString(Unknown Source)
at java.lang.Long.parseLong(Unknown Source)
at java.lang.Long.<init>(Unknown Source)
at org.serviio.upnp.webserver.ResourceTransportRequestHandler.handleRequest(ResourceTransportRequestHandler.java:109)
at org.serviio.upnp.webserver.AbstractRequestHandler.handle(AbstractRequestHandler.java:52)
at org.apache.http.protocol.HttpService.doService(HttpService.java:293)
at org.serviio.upnp.webserver.ServiioHttpService.doService(ServiioHttpService.java:58)
at org.apache.http.protocol.HttpService.handleRequest(HttpService.java:212)
at org.serviio.upnp.webserver.WebServer$WorkerThread.run(WebServer.java:187)
2011-02-27 09:33:06,424 DEBUG [WebServer] Incoming connection from /10.1.1.133:38919
2011-02-27 09:33:06,424 DEBUG [ResourceTransportRequestHandler] GET http://10.1.1.7:8895/resource/2801/MEDIA_ITEM/AVI HTTP/1.1, headers = [Accept: */*,Host: 10.1.1.7:8895,Range: bytes=0-,x-wap-profile: ,User-Agent: Samsung Player User-Agent,Connection: Keep-Alive]]
2011-02-27 09:33:06,424 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 10.1.1.133
2011-02-27 09:33:06,424 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/10.1.1.133, Profile=Generic DLNA profile'
2011-02-27 09:33:06,424 ERROR [ResourceTransportRequestHandler] Error while processing resource, sending back 500 error. Message: For input string: ""
java.lang.NumberFormatException: For input string: ""
at java.lang.NumberFormatException.forInputString(Unknown Source)
at java.lang.Long.parseLong(Unknown Source)
at java.lang.Long.<init>(Unknown Source)
at org.serviio.upnp.webserver.ResourceTransportRequestHandler.handleRequest(ResourceTransportRequestHandler.java:109)
at org.serviio.upnp.webserver.AbstractRequestHandler.handle(AbstractRequestHandler.java:52)
at org.apache.http.protocol.HttpService.doService(HttpService.java:293)
at org.serviio.upnp.webserver.ServiioHttpService.doService(ServiioHttpService.java:58)
at org.apache.http.protocol.HttpService.handleRequest(HttpService.java:212)
at org.serviio.upnp.webserver.WebServer$WorkerThread.run(WebServer.java:187)
2011-02-27 09:33:06,518 DEBUG [WebServer] Incoming connection from /10.1.1.133:57896
2011-02-27 09:33:06,518 DEBUG [ResourceTransportRequestHandler] GET http://10.1.1.7:8895/resource/2801/MEDIA_ITEM/AVI HTTP/1.1, headers = [Accept: */*,Host: 10.1.1.7:8895,Range: bytes=0-5,x-wap-profile: ,User-Agent: Samsung Player User-Agent,Connection: Keep-Alive]]
2011-02-27 09:33:06,518 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 10.1.1.133
2011-02-27 09:33:06,518 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/10.1.1.133, Profile=Generic DLNA profile'
2011-02-27 09:33:06,518 ERROR [ResourceTransportRequestHandler] Error while processing resource, sending back 500 error. Message: For input string: ""
java.lang.NumberFormatException: For input string: ""
at java.lang.NumberFormatException.forInputString(Unknown Source)
at java.lang.Long.parseLong(Unknown Source)
at java.lang.Long.<init>(Unknown Source)
at org.serviio.upnp.webserver.ResourceTransportRequestHandler.handleRequest(ResourceTransportRequestHandler.java:109)
at org.serviio.upnp.webserver.AbstractRequestHandler.handle(AbstractRequestHandler.java:52)
at org.apache.http.protocol.HttpService.doService(HttpService.java:293)
at org.serviio.upnp.webserver.ServiioHttpService.doService(ServiioHttpService.java:58)
at org.apache.http.protocol.HttpService.handleRequest(HttpService.java:212)
at org.serviio.upnp.webserver.WebServer$WorkerThread.run(WebServer.java:187)
2011-02-27 09:33:07,174 DEBUG [ResourceTransportRequestHandler] HEAD /resource/2801/MEDIA_ITEM/AVI HTTP/1.1, headers = [getcaptioninfo.sec: 1,getcontentfeatures.dlna.org: 1,User-Agent: Dalvik/1.2.0 (Linux; U; Android 2.2.1; GT-I9000 Build/FROYO),Host: 10.1.1.7:8895,Connection: Keep-Alive]]
2011-02-27 09:33:07,174 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 10.1.1.133
2011-02-27 09:33:07,174 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/10.1.1.133, Profile=Generic DLNA profile'
2011-02-27 09:33:07,174 DEBUG [ResourceTransportRequestHandler] Request for resource 2801 and type 'MEDIA_ITEM' received
2011-02-27 09:33:07,174 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 2801)
2011-02-27 09:33:07,174 DEBUG [VideoDAOImpl] Reading a Video (id = 2801)
2011-02-27 09:33:07,174 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 2801, format AVI and profile Generic DLNA profile
2011-02-27 09:33:07,174 DEBUG [ResourceTransportRequestHandler] Sending HEAD response back
2011-02-27 09:33:07,174 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/x-msvideo,Content-Length: 1302314496,Date: Sun, 27 Feb 2011 09:33:07 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/0.5,Cache-control: no-cache,contentFeatures.dlna.org: DLNA.ORG_PN=AVI;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01500000000000000000000000000000,transferMode.dlna.org: Streaming]]
Log while requesting a film via EyeCon
- Code:
2011-02-27 09:43:06,124 DEBUG [RendererManager] Retrieve device description from http://10.1.1.133:8888/description.xml
2011-02-27 09:43:06,218 DEBUG [RendererSearchSender] Multicasting SSDP M-SEARCH using interface eth1 (VMware Virtual Ethernet Adapter for VMnet1) and address 192.168.0.1, timeout = 0
2011-02-27 09:43:06,218 DEBUG [RendererSearchSender] Sending 1 'm-search' messages
2011-02-27 09:43:06,234 DEBUG [RendererSearchSender] Multicasting SSDP M-SEARCH using interface eth2 (VIA Rhine II Fast Ethernet Adapter - Virtual Machine Network Services Driver) and address 10.1.1.7, timeout = 0
2011-02-27 09:43:06,234 DEBUG [RendererSearchSender] Sending 1 'm-search' messages
2011-02-27 09:43:06,234 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /10.1.1.7:2439
2011-02-27 09:43:06,234 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /10.1.1.7:2439
2011-02-27 09:43:06,234 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /10.1.1.7:2439
2011-02-27 09:43:06,234 DEBUG [RendererSearchSender] Multicasting SSDP M-SEARCH using interface eth0 (VMware Virtual Ethernet Adapter for VMnet8) and address 192.168.189.1, timeout = 0
2011-02-27 09:43:06,234 DEBUG [RendererSearchSender] Sending 1 'm-search' messages
2011-02-27 09:43:06,249 WARN [RendererManager] Error while retrieving renderer description: Cannot parse device description
2011-02-27 09:43:06,249 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth2 (VIA Rhine II Fast Ethernet Adapter - Virtual Machine Network Services Driver) and address 10.1.1.7, timeout = 0
2011-02-27 09:43:06,249 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 82389917-f0f6-3db1-8111-6fff694a3834
2011-02-27 09:43:06,312 DEBUG [RendererSearchSender] Received search response: location: http://10.1.1.133:8888/description.xml, st: urn:schemas-upnp-org:device:MediaRenderer:1
2011-02-27 09:43:06,312 DEBUG [RendererSearchSender] Received a valid M-SEARCH response from Renderer b4:07:f9:d0:8c:c3 from address /10.1.1.133:42760
2011-02-27 09:43:06,312 DEBUG [RendererSearchSender] Received search response: location: http://10.1.1.133:8888/description.xml, st: urn:schemas-upnp-org:device:MediaRenderer:1
2011-02-27 09:43:06,312 DEBUG [RendererSearchSender] Received a valid M-SEARCH response from Renderer b4:07:f9:d0:8c:c3 from address /10.1.1.133:42760
2011-02-27 09:43:06,312 DEBUG [RendererManager] Retrieve device description from http://10.1.1.133:8888/description.xml
2011-02-27 09:43:06,406 DEBUG [RendererSearchSender] Received search response: location: http://10.1.1.133:8888/description.xml, st: urn:schemas-upnp-org:device:MediaRenderer:1
2011-02-27 09:43:06,406 DEBUG [RendererSearchSender] Received a valid M-SEARCH response from Renderer b4:07:f9:d0:8c:c3 from address /10.1.1.133:42760
2011-02-27 09:43:06,453 WARN [RendererManager] Error while retrieving renderer description: Cannot parse device description
2011-02-27 09:43:06,453 DEBUG [RendererManager] Retrieve device description from http://10.1.1.133:8888/description.xml
2011-02-27 09:43:06,609 WARN [RendererManager] Error while retrieving renderer description: Cannot parse device description
2011-02-27 09:43:06,624 DEBUG [RendererManager] Retrieve device description from http://10.1.1.133:8888/description.xml
2011-02-27 09:43:06,656 DEBUG [WebServer] Incoming connection from /10.1.1.133:48375
2011-02-27 09:43:06,656 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 82389917-f0f6-3db1-8111-6fff694a3834 from 10.1.1.133 (headers = [host: 10.1.1.7,User-Agent: Dalvik/1.2.0 (Linux; U; Android 2.2.1; GT-I9000 Build/FROYO),Connection: Keep-Alive])
2011-02-27 09:43:06,656 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 10.1.1.133
2011-02-27 09:43:06,656 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 10.1.1.133
2011-02-27 09:43:06,656 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Generic DLNA profile'
2011-02-27 09:43:06,671 WARN [RendererManager] Error while retrieving renderer description: Cannot parse device description
2011-02-27 09:43:06,671 DEBUG [RendererManager] Retrieve device description from http://10.1.1.133:8888/description.xml
2011-02-27 09:43:06,796 WARN [RendererManager] Error while retrieving renderer description: Cannot parse device description
2011-02-27 09:43:06,828 DEBUG [WebServer] Incoming connection from /10.1.1.133:39352
2011-02-27 09:43:06,828 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2011-02-27 09:43:06,828 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2011-02-27 09:43:06,952 DEBUG [WebServer] Incoming connection from /10.1.1.133:55957
2011-02-27 09:43:07,046 DEBUG [ServiceEventSubscriptionRequestHandler] ServiceEvent subscription request received for service ConnectionManager
2011-02-27 09:43:07,046 DEBUG [ServiceEventSubscriptionRequestHandler] Event subscription registered (uuid=ff89b1ba-a2bb-4177-9ad6-566f3e269da6) for service urn:upnp-org:serviceId:ConnectionManager reporting to http://10.1.1.133:8058/evetSub
2011-02-27 09:43:07,077 DEBUG [WebServer] Incoming connection from /10.1.1.133:55404
2011-02-27 09:43:07,077 DEBUG [EventDispatcher] Sending event notification #0 for subscription ff89b1ba-a2bb-4177-9ad6-566f3e269da6 to endpoint http://10.1.1.133:8058/evetSub
2011-02-27 09:43:07,077 DEBUG [RequestExecutor] Sending HTTP request to 10.1.1.133:8058
2011-02-27 09:43:07,140 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2011-02-27 09:43:07,156 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2011-02-27 09:43:07,156 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSearchCapabilities"' from 10.1.1.133:
2011-02-27 09:43:07,156 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 10.1.1.133
2011-02-27 09:43:07,156 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-02-27 09:43:07,202 DEBUG [WebServer] Incoming connection from /10.1.1.133:48448
2011-02-27 09:43:07,249 DEBUG [ServiceEventSubscriptionRequestHandler] ServiceEvent subscription request received for service ContentDirectory
2011-02-27 09:43:07,249 DEBUG [ServiceEventSubscriptionRequestHandler] Event subscription registered (uuid=2e4d0f4d-0577-4023-a50a-852186fbbd3f) for service urn:upnp-org:serviceId:ContentDirectory reporting to http://10.1.1.133:8058/evetSub
2011-02-27 09:43:07,343 DEBUG [EventDispatcher] Event notification sent and received successfully
2011-02-27 09:43:07,343 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from 10.1.1.133:
2011-02-27 09:43:07,343 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 10.1.1.133
2011-02-27 09:43:07,359 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-02-27 09:43:07,843 DEBUG [EventDispatcher] Sending event notification #0 for subscription 2e4d0f4d-0577-4023-a50a-852186fbbd3f to endpoint http://10.1.1.133:8058/evetSub
2011-02-27 09:43:07,843 DEBUG [RequestExecutor] Sending HTTP request to 10.1.1.133:8058
2011-02-27 09:43:08,046 DEBUG [EventDispatcher] Event notification sent and received successfully
2011-02-27 09:43:08,093 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth1 (VMware Virtual Ethernet Adapter for VMnet1) and address 192.168.0.1, timeout = 0
2011-02-27 09:43:08,093 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 82389917-f0f6-3db1-8111-6fff694a3834
2011-02-27 09:43:09,452 DEBUG [RendererSearchSender] Received search response: location: http://10.1.1.133:8888/description.xml, st: urn:schemas-upnp-org:device:MediaRenderer:1
2011-02-27 09:43:09,452 DEBUG [RendererSearchSender] Received a valid M-SEARCH response from Renderer b4:07:f9:d0:8c:c3 from address /10.1.1.133:49900
2011-02-27 09:43:09,452 DEBUG [RendererSearchSender] Received search response: location: http://10.1.1.133:8888/description.xml, st: urn:schemas-upnp-org:device:MediaRenderer:1
2011-02-27 09:43:09,452 DEBUG [RendererSearchSender] Received a valid M-SEARCH response from Renderer b4:07:f9:d0:8c:c3 from address /10.1.1.133:49900
2011-02-27 09:43:09,452 DEBUG [RendererManager] Retrieve device description from http://10.1.1.133:8888/description.xml
2011-02-27 09:43:09,452 DEBUG [RendererSearchSender] Received search response: location: http://10.1.1.133:8888/description.xml, st: urn:schemas-upnp-org:device:MediaRenderer:1
2011-02-27 09:43:09,452 DEBUG [RendererSearchSender] Received a valid M-SEARCH response from Renderer b4:07:f9:d0:8c:c3 from address /10.1.1.133:49900
2011-02-27 09:43:09,530 WARN [RendererManager] Error while retrieving renderer description: Cannot parse device description
2011-02-27 09:43:09,530 DEBUG [RendererManager] Retrieve device description from http://10.1.1.133:8888/description.xml
2011-02-27 09:43:09,577 WARN [RendererManager] Error while retrieving renderer description: Cannot parse device description
2011-02-27 09:43:09,577 DEBUG [RendererManager] Retrieve device description from http://10.1.1.133:8888/description.xml
2011-02-27 09:43:09,593 WARN [RendererManager] Error while retrieving renderer description: Cannot parse device description
2011-02-27 09:43:09,905 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth0 (VMware Virtual Ethernet Adapter for VMnet8) and address 192.168.189.1, timeout = 0
2011-02-27 09:43:09,905 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 82389917-f0f6-3db1-8111-6fff694a3834
2011-02-27 09:43:11,718 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:10
2011-02-27 09:43:12,233 DEBUG [RendererSearchSender] Finished searching for Renderer devices
2011-02-27 09:43:12,671 DEBUG [WebServer] Incoming connection from /10.1.1.133:41109
2011-02-27 09:43:12,921 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 10.1.1.133:
2011-02-27 09:43:12,921 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 10.1.1.133
2011-02-27 09:43:12,936 DEBUG [ContentDirectory] Browse() called for profile Generic DLNA profile with parameters: objectID = 0, browseFlag = BrowseDirectChildren, filter = *, startIndex = 0, count = 16, sortCriteria =
2011-02-27 09:43:12,936 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2011-02-27 09:43:12,936 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-02-27 09:43:14,545 DEBUG [WebServer] Incoming connection from /10.1.1.133:51880
2011-02-27 09:43:14,889 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 10.1.1.133:
2011-02-27 09:43:14,889 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 10.1.1.133
2011-02-27 09:43:14,889 DEBUG [ContentDirectory] Browse() called for profile Generic DLNA profile with parameters: objectID = V, browseFlag = BrowseDirectChildren, filter = *, startIndex = 0, count = 16, sortCriteria =
2011-02-27 09:43:14,889 DEBUG [VideoDAOImpl] Retrieving number of video initials
2011-02-27 09:43:14,920 DEBUG [VideoDAOImpl] Retrieving number of videos of type 2
2011-02-27 09:43:14,967 DEBUG [SeriesDAOImpl] Retrieving number of series
2011-02-27 09:43:14,967 DEBUG [RepositoryDAOImpl] Retrieving number of repositories for VIDEO
2011-02-27 09:43:14,967 DEBUG [VideoDAOImpl] Retrieving number of 10 last viewed videos
2011-02-27 09:43:15,014 DEBUG [VideoDAOImpl] Retrieving number of 10 last added videos
2011-02-27 09:43:15,014 DEBUG [VideoDAOImpl] Retrieving number of videos of type 0
2011-02-27 09:43:15,045 DEBUG [GenreDAOImpl] Retrieving number of genres for VIDEO
2011-02-27 09:43:15,108 DEBUG [PersonDAOImpl] Retrieving number of person initials for role ACTOR
2011-02-27 09:43:17,795 DEBUG [PersonDAOImpl] Retrieving number of person initials for role DIRECTOR
2011-02-27 09:43:17,858 DEBUG [PersonDAOImpl] Retrieving number of person initials for role PRODUCER
2011-02-27 09:43:17,951 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2011-02-27 09:43:17,951 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-02-27 09:43:21,779 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth2 (VIA Rhine II Fast Ethernet Adapter - Virtual Machine Network Services Driver) and address 10.1.1.7, timeout = 0
2011-02-27 09:43:21,779 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 82389917-f0f6-3db1-8111-6fff694a3834
2011-02-27 09:43:22,326 DEBUG [WebServer] Incoming connection from /10.1.1.133:55743
2011-02-27 09:43:22,435 DEBUG [ServiceEventSubscriptionRequestHandler] ServiceEvent renewal request received for service ConnectionManager and subscription uuid:ff89b1ba-a2bb-4177-9ad6-566f3e269da6
2011-02-27 09:43:22,435 DEBUG [ServiceEventSubscriptionRequestHandler] Event subscription renewed for service urn:upnp-org:serviceId:ConnectionManager and subscription ff89b1ba-a2bb-4177-9ad6-566f3e269da6
2011-02-27 09:43:22,701 DEBUG [WebServer] Incoming connection from /10.1.1.133:60922
2011-02-27 09:43:22,841 DEBUG [ServiceEventSubscriptionRequestHandler] ServiceEvent renewal request received for service ContentDirectory and subscription uuid:2e4d0f4d-0577-4023-a50a-852186fbbd3f
2011-02-27 09:43:22,841 DEBUG [ServiceEventSubscriptionRequestHandler] Event subscription renewed for service urn:upnp-org:serviceId:ContentDirectory and subscription 2e4d0f4d-0577-4023-a50a-852186fbbd3f
2011-02-27 09:43:23,591 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth1 (VMware Virtual Ethernet Adapter for VMnet1) and address 192.168.0.1, timeout = 0
2011-02-27 09:43:23,591 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 82389917-f0f6-3db1-8111-6fff694a3834
2011-02-27 09:43:24,201 DEBUG [WebServer] Incoming connection from /10.1.1.133:32966
2011-02-27 09:43:24,341 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 10.1.1.133:
2011-02-27 09:43:24,341 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 10.1.1.133
2011-02-27 09:43:24,341 DEBUG [ContentDirectory] Browse() called for profile Generic DLNA profile with parameters: objectID = V_LV, browseFlag = BrowseDirectChildren, filter = *, startIndex = 0, count = 16, sortCriteria =
2011-02-27 09:43:24,357 DEBUG [VideoDAOImpl] Retrieving list of 10 last viewed videos (from=0, count=16)
2011-02-27 09:43:24,451 DEBUG [SeriesDAOImpl] Reading a Series (id = 7)
2011-02-27 09:43:24,451 DEBUG [GenreDAOImpl] Reading a Genre (id = 2)
2011-02-27 09:43:24,451 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 430)
2011-02-27 09:43:24,451 DEBUG [MediaItemDAOImpl] Getting file of media item 430
2011-02-27 09:43:24,466 DEBUG [VideoDAOImpl] Reading a Video (id = 430)
2011-02-27 09:43:24,466 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 430 and profile Generic DLNA profile
2011-02-27 09:43:24,466 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 18522)
2011-02-27 09:43:24,466 DEBUG [SeriesDAOImpl] Reading a Series (id = 7)
2011-02-27 09:43:24,466 DEBUG [GenreDAOImpl] Reading a Genre (id = 2)
2011-02-27 09:43:24,466 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 428)
2011-02-27 09:43:24,466 DEBUG [MediaItemDAOImpl] Getting file of media item 428
2011-02-27 09:43:24,466 DEBUG [VideoDAOImpl] Reading a Video (id = 428)
2011-02-27 09:43:24,466 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 428 and profile Generic DLNA profile
2011-02-27 09:43:24,466 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 18520)
2011-02-27 09:43:24,466 DEBUG [SeriesDAOImpl] Reading a Series (id = 47)
2011-02-27 09:43:24,466 DEBUG [GenreDAOImpl] Reading a Genre (id = 2)
2011-02-27 09:43:24,466 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 15446)
2011-02-27 09:43:24,466 DEBUG [MediaItemDAOImpl] Getting file of media item 15446
2011-02-27 09:43:24,466 DEBUG [VideoDAOImpl] Reading a Video (id = 15446)
2011-02-27 09:43:24,466 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 15446 and profile Generic DLNA profile
2011-02-27 09:43:24,466 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 21254)
2011-02-27 09:43:24,482 DEBUG [SeriesDAOImpl] Reading a Series (id = 8)
2011-02-27 09:43:24,482 DEBUG [GenreDAOImpl] Reading a Genre (id = 2)
2011-02-27 09:43:24,482 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 459)
2011-02-27 09:43:24,482 DEBUG [MediaItemDAOImpl] Getting file of media item 459
2011-02-27 09:43:24,482 DEBUG [VideoDAOImpl] Reading a Video (id = 459)
2011-02-27 09:43:24,482 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 459 and profile Generic DLNA profile
2011-02-27 09:43:24,482 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 18551)
2011-02-27 09:43:24,497 DEBUG [SeriesDAOImpl] Reading a Series (id = 47)
2011-02-27 09:43:24,497 DEBUG [GenreDAOImpl] Reading a Genre (id = 2)
2011-02-27 09:43:24,497 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 15388)
2011-02-27 09:43:24,497 DEBUG [MediaItemDAOImpl] Getting file of media item 15388
2011-02-27 09:43:24,497 DEBUG [VideoDAOImpl] Reading a Video (id = 15388)
2011-02-27 09:43:24,497 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 15388 and profile Generic DLNA profile
2011-02-27 09:43:24,497 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 21196)
2011-02-27 09:43:24,513 DEBUG [SeriesDAOImpl] Reading a Series (id = 47)
2011-02-27 09:43:24,513 DEBUG [GenreDAOImpl] Reading a Genre (id = 2)
2011-02-27 09:43:24,513 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 15443)
2011-02-27 09:43:24,513 DEBUG [MediaItemDAOImpl] Getting file of media item 15443
2011-02-27 09:43:24,513 DEBUG [VideoDAOImpl] Reading a Video (id = 15443)
2011-02-27 09:43:24,513 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 15443 and profile Generic DLNA profile
2011-02-27 09:43:24,513 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 21251)
2011-02-27 09:43:24,513 DEBUG [SeriesDAOImpl] Reading a Series (id = 47)
2011-02-27 09:43:24,529 DEBUG [GenreDAOImpl] Reading a Genre (id = 2)
2011-02-27 09:43:24,529 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 15384)
2011-02-27 09:43:24,529 DEBUG [MediaItemDAOImpl] Getting file of media item 15384
2011-02-27 09:43:24,529 DEBUG [VideoDAOImpl] Reading a Video (id = 15384)
2011-02-27 09:43:24,529 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 15384 and profile Generic DLNA profile
2011-02-27 09:43:24,529 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 21192)
2011-02-27 09:43:24,529 DEBUG [GenreDAOImpl] Reading a Genre (id = 4)
2011-02-27 09:43:24,529 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 15321)
2011-02-27 09:43:24,529 DEBUG [MediaItemDAOImpl] Getting file of media item 15321
2011-02-27 09:43:24,544 DEBUG [VideoDAOImpl] Reading a Video (id = 15321)
2011-02-27 09:43:24,544 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 15321 and profile Generic DLNA profile
2011-02-27 09:43:24,544 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 21130)
2011-02-27 09:43:24,544 DEBUG [GenreDAOImpl] Reading a Genre (id = 4)
2011-02-27 09:43:24,544 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 15325)
2011-02-27 09:43:24,544 DEBUG [MediaItemDAOImpl] Getting file of media item 15325
2011-02-27 09:43:24,544 DEBUG [VideoDAOImpl] Reading a Video (id = 15325)
2011-02-27 09:43:24,544 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 15325 and profile Generic DLNA profile
2011-02-27 09:43:24,544 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 21135)
2011-02-27 09:43:24,544 DEBUG [GenreDAOImpl] Reading a Genre (id = 1)
2011-02-27 09:43:24,544 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 9)
2011-02-27 09:43:24,544 DEBUG [MediaItemDAOImpl] Getting file of media item 9
2011-02-27 09:43:24,544 DEBUG [VideoDAOImpl] Reading a Video (id = 9)
2011-02-27 09:43:24,544 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 9 and profile Generic DLNA profile
2011-02-27 09:43:24,544 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 18102)
2011-02-27 09:43:24,544 DEBUG [VideoDAOImpl] Retrieving number of 10 last viewed videos
2011-02-27 09:43:24,576 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_resetafterplay), returning it
2011-02-27 09:43:24,591 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-02-27 09:43:25,419 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth0 (VMware Virtual Ethernet Adapter for VMnet8) and address 192.168.189.1, timeout = 0
2011-02-27 09:43:25,419 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 82389917-f0f6-3db1-8111-6fff694a3834
2011-02-27 09:43:27,232 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:10
2011-02-27 09:43:30,981 DEBUG [RendererManager] Retrieve device description from http://10.1.1.133:8888/description.xml
2011-02-27 09:43:31,075 WARN [RendererManager] Error while retrieving renderer description: Cannot parse device description
2011-02-27 09:43:31,653 DEBUG [RendererManager] Retrieve device description from http://10.1.1.133:8888/description.xml
2011-02-27 09:43:31,762 WARN [RendererManager] Error while retrieving renderer description: Cannot parse device description
2011-02-27 09:43:32,184 DEBUG [RendererManager] Retrieve device description from http://10.1.1.133:8888/description.xml
2011-02-27 09:43:32,247 WARN [RendererManager] Error while retrieving renderer description: Cannot parse device description
2011-02-27 09:43:37,340 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth2 (VIA Rhine II Fast Ethernet Adapter - Virtual Machine Network Services Driver) and address 10.1.1.7, timeout = 0
2011-02-27 09:43:37,340 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 82389917-f0f6-3db1-8111-6fff694a3834
2011-02-27 09:43:39,152 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth1 (VMware Virtual Ethernet Adapter for VMnet1) and address 192.168.0.1, timeout = 0
2011-02-27 09:43:39,152 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 82389917-f0f6-3db1-8111-6fff694a3834
Hope this helps.
Mike