Automatic Library Refresh issue

After I first start serviio it won't begin automatically scanning for library changes/additions.
I have my libraries set for automatic refreshing and updating.

I've left it for hours, days, weeks even and it never picks up new or changed files.

Log into the console and Force Refresh, the scanner runs and the changes pop right up.

After that the automatic process seems to keep running on it's own. It isn't however predictable. Sometimes file changes/additions are picked up nearly instantly, other times they take quite a few minutes, even up to an hour while I've been watching it closely. The refresh period is set to 5 minutes.

[zip]

it sleeps for 5 minutes between the scans, so if the (initial) scan takes 2 hours, it then sleeps for 5 minutes and then checks for updates.

That kinda explains the random periods between updates after starting. How come I need to Force it to start?

[zip]

No idea, it should work. Maybe there is a file in there that is blocking the library indexing to proceed / finish? Check the (detailed) log

Turned on detailed logging and restarted the server. After several days not a single thing in the log tagged with Library. Shut down the server and then there are a bunch of logs about library actions, but even then, nothing that seems to indicate it was hung up.

At shutdown time, these are the first library log items:

  Code:

2014-08-01 00:28:54,379 INFO  [LibraryAdditionsCheckerThread] Started looking for newly added files
2014-08-01 00:28:54,557 INFO  [LibraryUpdatesCheckerThread] Started looking for updates to currently shared files

[zip]

you should see some DEBUG statements. if not, you didn't turn on detailed logging correctly

I did follow those instructions and see plenty of DEBUG messages, but none that appear to apply to library operations. Is there some specific string I should look for?

[zip]

You'd see a lot of lines regarding to files being removed. So at the point where those begin, somewhere above it'll say the folder it's scanning now. It'll probably say 'removing because it doesn't exist' .. something along these lines

Nope, I don't see anything in the logs like that after startup. Continue to see it start the library scanner after I initiate a shutdown though and then I see items like that in the log. Here's the log from startup on. After this there just seems to be an endless stream of information as the DLNA clients check-in with the server.

  Code:

2014-08-07 23:15:16,365 DEBUG [ApplicationInstanceManager] Listening for application instances on socket 44331
2014-08-07 23:15:16,434 INFO  [MediaServer] ------------------------------------------------------------------------
2014-08-07 23:15:16,435 INFO  [MediaServer] Serviio DLNA media streaming server v 1.4.1.2 (rev. 3753eff81a2f+)
2014-08-07 23:15:16,435 INFO  [MediaServer] Petr Nejedly 2009-2014
2014-08-07 23:15:16,435 INFO  [MediaServer] http://www.serviio.org
2014-08-07 23:15:16,436 INFO  [MediaServer]
2014-08-07 23:15:16,436 INFO  [MediaServer] Java 1.6.0_32-Sun Microsystems Inc.
2014-08-07 23:15:16,436 INFO  [MediaServer] OS FreeBSD amd64 10.0-RELEASE-p7
2014-08-07 23:15:16,437 INFO  [MediaServer] File encoding: ISO-8859-1
2014-08-07 23:15:16,439 INFO  [MediaServer] Headless mode enabled: true
2014-08-07 23:15:16,440 INFO  [MediaServer] User: dlna
2014-08-07 23:15:16,440 INFO  [MediaServer] User home dir: /usr/home/dlna
2014-08-07 23:15:16,440 INFO  [MediaServer] Temp dir: /tmp/
2014-08-07 23:15:16,440 INFO  [MediaServer] ------------------------------------------------------------------------
2014-08-07 23:15:17,332 INFO  [WebServer] Socket buffer set to 65535 bytes
2014-08-07 23:15:17,341 INFO  [RestletServer] Starting Restlet server (/rest) exposed on port 23423
2014-08-07 23:15:17,623 INFO  [RestletServer] Starting Restlet server (/cds) exposed on port 23424
2014-08-07 23:15:17,626 INFO  [RestletServer] Starting Restlet server (/mediabrowser) exposed on port 23424
2014-08-07 23:15:17,764 INFO  [DBSchemaUpdateExecutor] Checking if DB schema needs to be updated
2014-08-07 23:15:17,818 DEBUG [DBLogDAOImpl] Checking if script 'script-0.1.sql' has been run
2014-08-07 23:15:17,829 INFO  [DatabaseManager] Using DERBY database language
2014-08-07 23:15:23,443 DEBUG [DBLogDAOImpl] Checking if script 'script-0.1.1.sql' has been run
2014-08-07 23:15:23,448 DEBUG [DBLogDAOImpl] Checking if script 'script-0.2.sql' has been run
2014-08-07 23:15:23,453 DEBUG [DBLogDAOImpl] Checking if script 'script-0.3.1.sql' has been run
2014-08-07 23:15:23,457 DEBUG [DBLogDAOImpl] Checking if script 'script-0.4.sql' has been run
2014-08-07 23:15:23,463 DEBUG [DBLogDAOImpl] Checking if script 'script-0.4.1.sql' has been run
2014-08-07 23:15:23,469 DEBUG [DBLogDAOImpl] Checking if script 'script-0.4.2.sql' has been run
2014-08-07 23:15:23,487 DEBUG [DBLogDAOImpl] Checking if script 'script-0.5.sql' has been run
2014-08-07 23:15:23,496 DEBUG [DBLogDAOImpl] Checking if script 'script-0.5.1.sql' has been run
2014-08-07 23:15:23,511 DEBUG [DBLogDAOImpl] Checking if script 'script-0.6.sql' has been run
2014-08-07 23:15:23,517 DEBUG [DBLogDAOImpl] Checking if script 'script-0.6.1.sql' has been run
2014-08-07 23:15:23,531 DEBUG [DBLogDAOImpl] Checking if script 'script-0.6.2.sql' has been run
2014-08-07 23:15:23,540 DEBUG [DBLogDAOImpl] Checking if script 'script-1.0.sql' has been run
2014-08-07 23:15:23,555 DEBUG [DBLogDAOImpl] Checking if script 'script-1.1.sql' has been run
2014-08-07 23:15:23,561 DEBUG [DBLogDAOImpl] Checking if script 'script-1.2.sql' has been run
2014-08-07 23:15:23,579 DEBUG [DBLogDAOImpl] Checking if script 'script-1.2.1.sql' has been run
2014-08-07 23:15:23,596 DEBUG [DBLogDAOImpl] Checking if script 'script-1.3.sql' has been run
2014-08-07 23:15:23,601 DEBUG [DBLogDAOImpl] Checking if script 'script-1.4.sql' has been run
2014-08-07 23:15:23,897 DEBUG [LicensingManager] Updating license
2014-08-07 23:15:23,916 DEBUG [ConfigEntryDAOImpl] Reading all ConfigEntries
2014-08-07 23:15:24,004 DEBUG [LicenseValidator] Validating license file (343 bytes)
2014-08-07 23:15:24,626 INFO  [MediaServer] ------------------------------------------------------------------------
2014-08-07 23:15:24,626 INFO  [MediaServer] License: FREE
2014-08-07 23:15:24,626 INFO  [MediaServer] ------------------------------------------------------------------------
2014-08-07 23:15:24,641 DEBUG [LocalAddressResolverStrategy] Resolving host IP address automatically
2014-08-07 23:15:24,665 INFO  [Device] Created UPnP Device with UUID: 81dd8464-2e05-39ea-9498-65c27cb4f652, bound address: 10.10.10.11
2014-08-07 23:15:29,093 INFO  [ProfilesDefinitionParser] Parsing Profiles definition
2014-08-07 23:15:29,109 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 1
2014-08-07 23:15:29,680 INFO  [ProfilesDefinitionParser] Added profile 'Generic DLNA profile' (id=1)
2014-08-07 23:15:29,681 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 2
2014-08-07 23:15:29,944 INFO  [ProfilesDefinitionParser] Added profile 'Samsung TV (B-series)' (id=2)
2014-08-07 23:15:29,944 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 3
2014-08-07 23:15:29,966 INFO  [ProfilesDefinitionParser] Added profile 'Xbox 360' (id=3)
2014-08-07 23:15:29,967 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 4
2014-08-07 23:15:29,976 INFO  [ProfilesDefinitionParser] Added profile 'Playstation 3' (id=4)
2014-08-07 23:15:29,977 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 5
2014-08-07 23:15:29,983 INFO  [ProfilesDefinitionParser] Added profile 'Samsung TV (A-series)' (id=5)
2014-08-07 23:15:29,984 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 6
2014-08-07 23:15:30,001 INFO  [ProfilesDefinitionParser] Added profile 'DirecTV HD-DVR' (id=6)
2014-08-07 23:15:30,002 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile sam_ef
2014-08-07 23:15:30,020 INFO  [ProfilesDefinitionParser] Added profile 'Samsung TV / player (E/F-series)' (id=sam_ef)
2014-08-07 23:15:30,021 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 7
2014-08-07 23:15:30,029 INFO  [ProfilesDefinitionParser] Added profile 'Samsung TV / player (C/D-series)' (id=7)
2014-08-07 23:15:30,030 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 8
2014-08-07 23:15:30,036 INFO  [ProfilesDefinitionParser] Added profile 'LG TV / player' (id=8)
2014-08-07 23:15:30,036 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile sony2012
2014-08-07 23:15:30,066 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2012)' (id=sony2012)
2014-08-07 23:15:30,067 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile sony2013
2014-08-07 23:15:30,091 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2013)' (id=sony2013)
2014-08-07 23:15:30,092 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile bdp2013
2014-08-07 23:15:30,105 INFO  [ProfilesDefinitionParser] Added profile 'Sony BDP-Sx100 (2013)' (id=bdp2013)
2014-08-07 23:15:30,106 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile bdp2012
2014-08-07 23:15:30,114 INFO  [ProfilesDefinitionParser] Added profile 'Sony BDP-Sx90 (2012)' (id=bdp2012)
2014-08-07 23:15:30,115 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile bdp2010
2014-08-07 23:15:30,143 INFO  [ProfilesDefinitionParser] Added profile 'Sony BDP-Sx70/SMP-N100 (2010)' (id=bdp2010)
2014-08-07 23:15:30,144 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile bdp2010us
2014-08-07 23:15:30,149 INFO  [ProfilesDefinitionParser] Added profile 'Sony BDP-Sx70/SMP-N100 (2010) - US' (id=bdp2010us)
2014-08-07 23:15:30,151 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile bdp2011
2014-08-07 23:15:30,157 INFO  [ProfilesDefinitionParser] Added profile 'Sony BDP-Sx80/SMP-N200 (2011)' (id=bdp2011)
2014-08-07 23:15:30,159 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 13
2014-08-07 23:15:30,166 INFO  [ProfilesDefinitionParser] Added profile 'Toshiba REGZA' (id=13)
2014-08-07 23:15:30,167 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile tosh2012
2014-08-07 23:15:30,178 INFO  [ProfilesDefinitionParser] Added profile 'Toshiba REGZA 2012-' (id=tosh2012)
2014-08-07 23:15:30,179 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 14
2014-08-07 23:15:30,195 INFO  [ProfilesDefinitionParser] Added profile 'WDTV Live' (id=14)
2014-08-07 23:15:30,195 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 15
2014-08-07 23:15:30,200 INFO  [ProfilesDefinitionParser] Added profile 'LaCie LaCinema Media Player' (id=15)
2014-08-07 23:15:30,201 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 16
2014-08-07 23:15:30,206 INFO  [ProfilesDefinitionParser] Added profile 'Manta WIFI Centre' (id=16)
2014-08-07 23:15:30,207 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 17
2014-08-07 23:15:30,213 INFO  [ProfilesDefinitionParser] Added profile 'Pure Flow' (id=17)
2014-08-07 23:15:30,217 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 18
2014-08-07 23:15:30,224 INFO  [ProfilesDefinitionParser] Added profile 'Sharp Aquos' (id=18)
2014-08-07 23:15:30,225 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile sony2011
2014-08-07 23:15:30,230 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2011)' (id=sony2011)
2014-08-07 23:15:30,231 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile sony2010
2014-08-07 23:15:30,239 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2010)' (id=sony2010)
2014-08-07 23:15:30,240 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile plps2010
2014-08-07 23:15:30,267 INFO  [ProfilesDefinitionParser] Added profile 'Philips (2010-)' (id=plps2010)
2014-08-07 23:15:30,268 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 20
2014-08-07 23:15:30,273 INFO  [ProfilesDefinitionParser] Added profile 'Philips (2009)' (id=20)
2014-08-07 23:15:30,274 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile sony2009
2014-08-07 23:15:30,292 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2009)' (id=sony2009)
2014-08-07 23:15:30,293 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 22
2014-08-07 23:15:30,301 INFO  [ProfilesDefinitionParser] Added profile 'Denon AVR' (id=22)
2014-08-07 23:15:30,301 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile viera2013
2014-08-07 23:15:30,311 INFO  [ProfilesDefinitionParser] Added profile 'Panasonic Viera (2013)' (id=viera2013)
2014-08-07 23:15:30,312 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile viera2011a
2014-08-07 23:15:30,318 INFO  [ProfilesDefinitionParser] Added profile 'Panasonic Viera G/GT/DT/UT/VT (2011/2012)' (id=viera2011a)
2014-08-07 23:15:30,326 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile viera2011
2014-08-07 23:15:30,332 INFO  [ProfilesDefinitionParser] Added profile 'Panasonic Viera E/S/ST/VT (2011)' (id=viera2011)
2014-08-07 23:15:30,333 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile viera2010
2014-08-07 23:15:30,350 INFO  [ProfilesDefinitionParser] Added profile 'Panasonic Viera (2010)' (id=viera2010)
2014-08-07 23:15:30,351 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile sony2012us
2014-08-07 23:15:30,356 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2012) - US' (id=sony2012us)
2014-08-07 23:15:30,363 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile sony2011us
2014-08-07 23:15:30,368 INFO  [ProfilesDefinitionParser] Added profile 'Sony Bravia TV (2011) - US' (id=sony2011us)
2014-08-07 23:15:30,369 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile SonySTR
2014-08-07 23:15:30,376 INFO  [ProfilesDefinitionParser] Added profile 'Sony AVReceiver STR' (id=SonySTR)
2014-08-07 23:15:30,376 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile SonyCMT
2014-08-07 23:15:30,384 INFO  [ProfilesDefinitionParser] Added profile 'Sony Audio CMT' (id=SonyCMT)
2014-08-07 23:15:30,385 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile viziotv
2014-08-07 23:15:30,390 INFO  [ProfilesDefinitionParser] Added profile 'Vizio TV' (id=viziotv)
2014-08-07 23:15:30,391 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile win8metro
2014-08-07 23:15:30,407 INFO  [ProfilesDefinitionParser] Added profile 'Windows 8/RT' (id=win8metro)
2014-08-07 23:15:30,408 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile panasonicBDT220
2014-08-07 23:15:30,426 INFO  [ProfilesDefinitionParser] Added profile 'Panasonic BD player' (id=panasonicBDT220)
2014-08-07 23:15:30,426 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile philips_streamium
2014-08-07 23:15:30,430 INFO  [ProfilesDefinitionParser] Added profile 'Philips NP Streamium' (id=philips_streamium)
2014-08-07 23:15:30,430 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile roku
2014-08-07 23:15:30,435 INFO  [ProfilesDefinitionParser] Added profile 'Chaneru' (id=roku)
2014-08-07 23:15:30,436 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile RokuMPApp
2014-08-07 23:15:30,461 INFO  [ProfilesDefinitionParser] Added profile 'Roku Media Player' (id=RokuMPApp)
2014-08-07 23:15:30,462 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile YamBD
2014-08-07 23:15:30,465 INFO  [ProfilesDefinitionParser] Added profile 'Yamaha BD-A1020/BD-A1010' (id=YamBD)
2014-08-07 23:15:30,466 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile TNDC
2014-08-07 23:15:30,472 INFO  [ProfilesDefinitionParser] Added profile 'Telenet Yelo TV' (id=TNDC)
2014-08-07 23:15:30,472 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile xbox_one
2014-08-07 23:15:30,478 INFO  [ProfilesDefinitionParser] Added profile 'Xbox One' (id=xbox_one)
2014-08-07 23:15:30,874 INFO  [ProfilesDefinitionParser] Parsing Profiles definition
2014-08-07 23:15:30,875 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile flv_player
2014-08-07 23:15:30,883 INFO  [ProfilesDefinitionParser] Added profile 'Flash player' (id=flv_player)
2014-08-07 23:15:30,883 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile ios
2014-08-07 23:15:30,891 INFO  [ProfilesDefinitionParser] Added profile 'iOS' (id=ios)
2014-08-07 23:15:30,891 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile serviigo_standard
2014-08-07 23:15:30,897 INFO  [ProfilesDefinitionParser] Added profile 'ServiiGo (Standard)' (id=serviigo_standard)
2014-08-07 23:15:30,898 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile serviigo
2014-08-07 23:15:30,903 INFO  [ProfilesDefinitionParser] Added profile 'ServiiGo (Legacy Devices)' (id=serviigo)
2014-08-07 23:15:30,903 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile serviigo_hls
2014-08-07 23:15:30,909 INFO  [ProfilesDefinitionParser] Added profile 'ServiiGo (HLS)' (id=serviigo_hls)
2014-08-07 23:15:30,910 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile serviigo_gtv
2014-08-07 23:15:30,915 INFO  [ProfilesDefinitionParser] Added profile 'ServiiGo (Google TV)' (id=serviigo_gtv)
2014-08-07 23:15:30,916 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile wp8
2014-08-07 23:15:30,924 INFO  [ProfilesDefinitionParser] Added profile 'Windows Phone 8' (id=wp8)
2014-08-07 23:15:30,924 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile chromecast
2014-08-07 23:15:30,933 INFO  [ProfilesDefinitionParser] Added profile 'Chromecast' (id=chromecast)
2014-08-07 23:15:31,407 INFO  [PluginCompilerThread] Looking for plugins at /var/db/serviio/home/plugins
2014-08-07 23:15:31,409 WARN  [PluginCompilerThread] Plugins folder '/var/db/serviio/home/plugins' does not exist. No plugins will be compiled.
2014-08-07 23:15:31,427 INFO  [UpdateChecker] Checking if a new version is available
2014-08-07 23:15:31,473 DEBUG [FFMPEGWrapper] Invoking FFMPEG to check if it exists of path /usr/local/bin/ffmpeg
2014-08-07 23:15:31,491 DEBUG [ProcessExecutor] Starting /usr/local/bin/ffmpeg
2014-08-07 23:15:31,751 INFO  [FFMPEGWrapper] Found FFmpeg: ffmpeg version 2.2.4 Copyright (c) 2000-2014 the FFmpeg developers
2014-08-07 23:15:31,767 DEBUG [DCRawWrapper] Invoking DCRAW to check if it exists of path dcraw
2014-08-07 23:15:31,769 DEBUG [ProcessExecutor] Starting dcraw
2014-08-07 23:15:31,861 ERROR [ProcessExecutor] Fatal error in process starting: Cannot run program "dcraw": java.io.IOException: error=2, No such file or directory
java.io.IOException: Cannot run program "dcraw": java.io.IOException: error=2, No such file or directory
   at java.lang.ProcessBuilder.start(ProcessBuilder.java:494)
   at org.serviio.external.ProcessExecutor.run(ProcessExecutor.java:135)
Caused by: java.io.IOException: java.io.IOException: error=2, No such file or directory
   at java.lang.UNIXProcess.<init>(UNIXProcess.java:164)
   at java.lang.ProcessImpl.start(ProcessImpl.java:81)
   at java.lang.ProcessBuilder.start(ProcessBuilder.java:476)
   ... 1 more
2014-08-07 23:15:31,871 WARN  [MediaServer] DCRAW not found. Support for raw image files will be missing.
2014-08-07 23:15:32,028 INFO  [AbstractTranscodingDeliveryEngine] Cleaning transcode engine and its data
2014-08-07 23:15:32,031 DEBUG [AbstractTranscodingDeliveryEngine] Deleting temporary transcoded files from: /bulk/tivo/tmp/Serviio
2014-08-07 23:15:32,235 DEBUG [LocalAddressResolverStrategy] Resolving host IP address automatically
2014-08-07 23:15:32,235 INFO  [WebServer] WebServer starting on port 8895
2014-08-07 23:15:32,239 INFO  [Device] Updated bound IP address of Device with UUID: 81dd8464-2e05-39ea-9498-65c27cb4f652, bound address: 10.10.10.11
2014-08-07 23:15:32,273 DEBUG [DiscoveryManager] UPNP device 81dd8464-2e05-39ea-9498-65c27cb4f652 (10.10.10.11) is available
2014-08-07 23:15:32,277 INFO  [EventSubscriptionExpirationChecker] Starting EventSubscriptionExpirationChecker
2014-08-07 23:15:32,278 INFO  [EventDispatcher] Starting EventDispatcher
2014-08-07 23:15:32,280 INFO  [DiscoveryAdvertisementNotifier] Starting DiscoveryAdvertisementNotifier
2014-08-07 23:15:32,292 INFO  [DiscoverySSDPMessageListener] Starting DiscoverySSDPMessageListener using interface re0 (re0) and address 10.10.10.11, timeout = 0
2014-08-07 23:15:32,307 INFO  [RendererExpirationChecker] Starting RendererExpirationChecker
2014-08-07 23:15:32,310 INFO  [RendererSearchSender] Searching for Renderer devices
2014-08-07 23:15:32,317 DEBUG [RendererSearchSender$RendererSearchWorker] Multicasting SSDP M-SEARCH using interface re0 (re0) and address 10.10.10.11, timeout = 0
2014-08-07 23:15:32,329 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface re0 (re0) and address 10.10.10.11, timeout = 0
2014-08-07 23:15:32,410 DEBUG [RendererSearchSender$RendererSearchWorker] Sending 1 'm-search' messages
2014-08-07 23:15:32,415 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 81dd8464-2e05-39ea-9498-65c27cb4f652
2014-08-07 23:15:32,474 DEBUG [RendererSearchSender$RendererSearchWorker] Received search response: location: http://10.10.10.34:55000/dmr/ddd.xml, st: urn:schemas-upnp-org:device:MediaRenderer:1
2014-08-07 23:15:32,477 DEBUG [RendererSearchSender$RendererSearchWorker] Received a valid M-SEARCH response from Renderer 4d454930-0100-1000-8001-8cc1216d5120 from address 10.10.10.34
2014-08-07 23:15:32,519 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /10.10.10.11:34187s
2014-08-07 23:15:32,535 DEBUG [RendererSearchSender$RendererSearchWorker] Received search response: location: http://10.10.10.34:55000/dmr/ddd.xml, st: urn:schemas-upnp-org:device:MediaRenderer:1
2014-08-07 23:15:32,573 DEBUG [RendererSearchSender$RendererSearchWorker] Received a valid M-SEARCH response from Renderer 4d454930-0100-1000-8001-8cc1216d5120 from address 10.10.10.34
2014-08-07 23:15:32,577 DEBUG [RendererSearchSender$RendererSearchWorker] Received search response: location: http://10.10.10.34:55000/dmr/ddd.xml, st: urn:schemas-upnp-org:device:MediaRenderer:1
2014-08-07 23:15:32,579 DEBUG [RendererSearchSender$RendererSearchWorker] Received a valid M-SEARCH response from Renderer 4d454930-0100-1000-8001-8cc1216d5120 from address 10.10.10.34
2014-08-07 23:15:32,613 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /10.10.10.11:34187s
2014-08-07 23:15:32,924 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /10.10.10.11:34187s
2014-08-07 23:15:32,933 DEBUG [ServiioHttpService] Incoming request from /10.10.10.34:64815: GET /deviceDescription/81dd8464-2e05-39ea-9498-65c27cb4f652 HTTP/1.1, headers = [User-Agent: Panasonic MIL DLNA CP UPnP/1.0 DLNADOC/1.50,Host: 10.10.10.11:8895,X-PANASONIC-Registration: VklFUkEgU1Q2MAAAAAAAAAAAAAAAAAAAAAA=,X-PANASONIC-DMP-Profile: ""]]
2014-08-07 23:15:32,937 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 10.10.10.34
2014-08-07 23:15:33,248 DEBUG [RendererManager] Adding renderer Renderer [uuid=4d454930-0100-1000-8001-8cc1216d5120, ipAddress=10.10.10.34, name=Panasonic VIErA, profileId=viera2013, manuallyAdded=false, forcedProfile=false, enabled=true, accessGroupId=1] to list of active renderers
2014-08-07 23:15:33,266 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 81dd8464-2e05-39ea-9498-65c27cb4f652 from 10.10.10.34 (headers = [User-Agent: Panasonic MIL DLNA CP UPnP/1.0 DLNADOC/1.50,Host: 10.10.10.11:8895,X-PANASONIC-Registration: VklFUkEgU1Q2MAAAAAAAAAAAAAAAAAAAAAA=,X-PANASONIC-DMP-Profile: ""])