I got through "test run #3" tonight. I'll have to get better at turning off both library updates and, it appears, metadata processing/extraction before I'll do a fourth run. I'm seeing a large number of warnings mostly it seems about having non-dates in year headers, etc, I think, that deserve some investigation later. However, I think I culled the pertinent messages from the log (run #3 and run 2) and await feedback before trying again.
The chosen test movie, it became apparent, is (inadvertant) in a directory that is set for metadata extraction but is one of a few NOT expected to have its own metadata in an online resource. I'd certainly think this would not affect anything. And I am sure, I will have to check, the problem happens for movies metadat'd and with it. I will make a note in passing that I saw some really strange things over a week ago with playing serviio files on the WDTVLive in regards to "playing" metadata instead of the video (they went away) and I have no other feedback on that.
My suspicion is it has something to due with metadata management between Serviio and WD.
Serviio was started. Transcoding was off. WDTVLive was turned off/on again. Basically, vereything I saidin my original post was rerun. The chosen test movie was successfully played on the XBMC renderer or played off other servers to WDTVLive successfully and the only way it was played successfully remotely off of Serviio server was 1) if it was the first media sent to WDTVLive (sometimes) and 2) off Serviio Go by way of BEAM (twonky).
From RUN 3 Serviio log ...
2013-05-05 22:42:43,311 INFO [WebServer] WebServer starting on port 8895
2013-05-05 22:42:43,311 INFO [EventSubscriptionExpirationChecker] Starting EventSubscriptionExpirationChecker
2013-05-05 22:42:43,311 INFO [EventDispatcher] Starting EventDispatcher
2013-05-05 22:42:43,311 INFO [DiscoveryAdvertisementNotifier] Starting DiscoveryAdvertisementNotifier
2013-05-05 22:42:43,384 INFO [DiscoverySSDPMessageListener] Starting DiscoverySSDPMessageListener using interface eth3 (Broadcom NetLink (TM) Gigabit Ethernet) and address 192.168.1.40, timeout = 0
2013-05-05 22:42:43,385 INFO [RendererSearchSender] Searching for Renderer devices
2013-05-05 22:42:43,481 INFO [RendererManager] Stored a new renderer: uuid='3f6005c4-57ef-bd80-9ff9-ac521ec3add2', name = 'XBMC Media Center', ipAddress='192.168.1.40', profile = '1'
2013-05-05 22:42:44,401 INFO [RendererManager] Stored a new renderer: uuid='48e25832-71c7-8395-7e99-5c15c4ff0b06', name = 'foobar2000 Media Renderer', ipAddress='192.168.1.40', profile = '1'
2013-05-05 22:42:45,401 INFO [RendererManager] Stored a new renderer: uuid='3f6005c4-57ef-bd80-9ff9-ac521ec3add2', name = 'XBMC Media Center', ipAddress='192.168.1.40', profile = '1'
2013-05-05 22:42:45,416 INFO [RendererManager] Stored a new renderer: uuid='48e25832-71c7-8395-7e99-5c15c4ff0b06', name = 'foobar2000 Media Renderer', ipAddress='192.168.1.40', profile = '1'
2013-05-05 22:42:45,429 INFO [RendererManager] Stored a new renderer: uuid='3f6005c4-57ef-bd80-9ff9-ac521ec3add2', name = 'XBMC Media Center', ipAddress='192.168.1.40', profile = '1'
MY NOTE HERE IT LOOKS LIKE SERVIIO HAPPENED TO GO BY THE "TEST MOVIE" Santana Diving - Burma Dive Safari.mp4. ("Flash avi was not used at all)
2013-05-05 23:02:14,826 WARN [OnlineVideoSourcesMetadataExtractor] Online metadata search returned no results for file Santana Diving - Burma Dive Safari (Santana 03-2004 - 320 x 240).mp4 [VideoDescription [type=FILM, names=[santana diving burma dive safari (santana 03, burma dive safari], year=2004, season=null, episode=null]]
2013-05-05 23:02:15,392 INFO [LibraryUpdatesCheckerThread] Updated 'Santana Diving - Burma Dive Safari (Santana 03-2004 - 320 x 240)' (\\MYBOOKLIVE\Media\_Movies_SD\Burma Dive Safari (2004 archived)\Santana Diving - Burma Dive Safari (Santana 03-2004 - 320 x 240).mp4) in Library
2013-05-05 23:02:16,604 WARN [OnlineVideoSourcesMetadataExtractor] Online metadata search returned no results for file Santana Diving - Burma Dive Safari.mp4 [VideoDescription [type=FILM, names=[santana diving burma dive safari, burma dive safari], year=2004, season=null, episode=null]]
2013-05-05 23:02:16,702 INFO [LibraryUpdatesCheckerThread] Updated 'Santana Diving - Burma Dive Safari' (\\MYBOOKLIVE\Media\_Movies_SD\Burma Dive Safari (2004 archived)\Santana Diving - Burma Dive Safari.mp4) in Library
2013-05-05 23:02:19,152 WARN [LibraryManager] Extractor ONLINE_VIDEO_SOURCES failed to connect to metadata source for file \\MYBOOKLIVE\Media\_Videos_TV\Flash.Gordon 7.5 Gb\Flash.Gordon.S01E19.DSR.XviD.avi, will try again later: Error connecting to thetvdb.com:
http://thetvdb.com/api/235C8CA4529142E9 ... /19/en.xml2013-05-05 23:02:19,260 INFO [LibraryUpdatesCheckerThread] Updated 'Flash.Gordon.S01E19.DSR.XviD' (\\MYBOOKLIVE\Media\_Videos_TV\Flash.Gordon 7.5 Gb\Flash.Gordon.S01E19.DSR.XviD.avi) in Library
2013-05-05 23:02:21,588 WARN [LibraryManager] Extractor ONLINE_VIDEO_SOURCES failed to connect to metadata source for file \\MYBOOKLIVE\Media\_Videos_TV\Flash.Gordon 7.5 Gb\Flash.Gordon.S01E17.DSR.XviD.avi, will try again later: Error connecting to thetvdb.com:
http://thetvdb.com/api/235C8CA4529142E9 ... /17/en.xml2013-05-05 23:02:21,642 INFO [LibraryUpdatesCheckerThread] Updated 'Flash.Gordon.S01E17.DSR.XviD' (\\MYBOOKLIVE\Media\_Videos_TV\Flash.Gordon 7.5 Gb\Flash.Gordon.S01E17.DSR.XviD.avi) in Library
2013-05-05 23:02:23,566 WARN [LibraryManager] Extractor ONLINE_VIDEO_SOURCES failed to connect to metadata source for file \\MYBOOKLIVE\Media\_Videos_TV\Flash.Gordon 7.5 Gb\Flash.Gordon.S01E03.DSR.XviD.avi, will try again later: Error connecting to thetvdb.com:
http://thetvdb.com/api/235C8CA4529142E9 ... 1/3/en.xml2013-05-05 23:02:23,626 INFO [LibraryUpdatesCheckerThread] Updated 'Flash.Gordon.S01E03.DSR.XviD' (\\MYBOOKLIVE\Media\_Videos_TV\Flash.Gordon 7.5 Gb\Flash.Gordon.S01E03.DSR.XviD.avi) in Library
2013-05-05 23:02:25,698 WARN [LibraryManager] Extractor ONLINE_VIDEO_SOURCES failed to connect to metadata source for file \\MYBOOKLIVE\Media\_Videos_TV\Flash.Gordon 7.5 Gb\Flash.Gordon.S01E02.DSR.XviD.avi, will try again later: Error connecting to thetvdb.com:
http://thetvdb.com/api/235C8CA4529142E9 ... 1/2/en.xml2013-05-05 23:02:25,772 INFO [LibraryUpdatesCheckerThread] Updated 'Flash.Gordon.S01E02.DSR.XviD' (\\MYBOOKLIVE\Media\_Videos_TV\Flash.Gordon 7.5 Gb\Flash.Gordon.S01E02.DSR.XviD.avi) in Library
2013-05-05 23:02:27,613 WARN [LibraryManager] Extractor ONLINE_VIDEO_SOURCES failed to connect to metadata source for file \\MYBOOKLIVE\Media\_Videos_TV\Flash.Gordon 7.5 Gb\Flash.Gordon.S01E04.DSR.XviD.avi, will try again later: Error connecting to thetvdb.com:
http://thetvdb.com/api/235C8CA4529142E9 ... 1/4/en.xml2013-05-05 23:02:27,667 INFO [LibraryUpdatesCheckerThread] Updated 'Flash.Gordon.S01E04.DSR.XviD' (\\MYBOOKLIVE\Media\_Videos_TV\Flash.Gordon 7.5 Gb\Flash.Gordon.S01E04.DSR.XviD.avi) in Library
2013-05-05 23:02:29,453 WARN [LibraryManager] Extractor ONLINE_VIDEO_SOURCES failed to connect to metadata source for file \\MYBOOKLIVE\Media\_Videos_TV\Flash.Gordon 7.5 Gb\Flash.Gordon.S01E15.DSR.XviD.avi, will try again later: Error connecting to thetvdb.com:
http://thetvdb.com/api/235C8CA4529142E9 ... /15/en.xml2013-05-05 23:02:29,580 INFO [LibraryUpdatesCheckerThread] Updated 'Flash.Gordon.S01E15.DSR.XviD' (\\MYBOOKLIVE\Media\_Videos_TV\Flash.Gordon 7.5 Gb\Flash.Gordon.S01E15.DSR.XviD.avi) in Library
2013-05-05 23:02:32,691 WARN [LibraryManager] Extractor ONLINE_VIDEO_SOURCES failed to connect to metadata source for file \\MYBOOKLIVE\Media\_Videos_TV\Flash.Gordon 7.5 Gb\Flash.Gordon.S01E21.DSR.XviD.avi, will try again later: Error connecting to thetvdb.com:
http://thetvdb.com/api/235C8CA4529142E9 ... /21/en.xml2013-05-05 23:02:32,746 INFO [LibraryUpdatesCheckerThread] Updated 'Flash.Gordon.S01E21.DSR.XviD' (\\MYBOOKLIVE\Media\_Videos_TV\Flash.Gordon 7.5 Gb\Flash.Gordon.S01E21.DSR.XviD.avi) in Library
2013-05-05 23:02:34,144 WARN [LibraryAdditionsCheckerThread] Cannot add file 06 - billy's got a gun.mp3 because of an unexpected error. Message: For input string: "Augu"
2013-05-05 23:05:59,802 WARN [ResourceDeliveryProcessor] Invalid request, sending back 400 error
org.serviio.upnp.protocol.http.transport.InvalidResourceRequestException: Invalid incoming request: /resource/149858/MEDIA_ITEM/MPEG4_P2_MP4_ASP_AAC-0
at org.serviio.upnp.protocol.http.transport.RequestedResourceDescriptor.<init>(RequestedResourceDescriptor.java:73)
at org.serviio.upnp.protocol.http.transport.AbstractProtocolHandler.getRequestedResourceDescription(AbstractProtocolHandler.java:35)
at org.serviio.delivery.ResourceDeliveryProcessor.deliverContent(ResourceDeliveryProcessor.java:65)
at org.serviio.upnp.webserver.ResourceTransportRequestHandler.handleRequest(ResourceTransportRequestHandler.java:85)
at org.serviio.upnp.webserver.AbstractRequestHandler.handle(AbstractRequestHandler.java:61)
at org.apache.http.protocol.HttpService.doService(HttpService.java:293)
at org.serviio.upnp.webserver.ServiioHttpService.doService(ServiioHttpService.java:77)
at org.apache.http.protocol.HttpService.handleRequest(HttpService.java:212)
at org.serviio.upnp.webserver.WebServer$WorkerThread.run(WebServer.java:192)
Caused by: java.lang.ArrayIndexOutOfBoundsException: 3
at org.serviio.upnp.protocol.http.transport.RequestedResourceDescriptor.<init>(RequestedResourceDescriptor.java:70)
... 8 more
2013-05-05 23:06:43,388 INFO [DiscoveryAdvertisementNotifier] Leaving DiscoveryAdvertisementNotifier
2013-05-05 23:06:43,388 INFO [DiscoverySSDPMessageListener] Leaving DiscoverySSDPMessageListener
2013-05-05 23:06:43,388 INFO [WebServer] WebServer shutting down
2013-05-05 23:06:43,389 INFO [EventDispatcher] Leaving EventDispatcher
2013-05-05 23:06:43,388 INFO [EventSubscriptionExpirationChecker] Leaving EventSubscriptionExpirationChecker, removing all event subscriptions
From Run 2 Serviio Log
org.serviio.upnp.protocol.http.transport.InvalidResourceRequestException: Invalid incoming request: /resource/149858/MEDIA_ITEM/MPEG4_P2_MP4_ASP_AAC-0
at org.serviio.upnp.protocol.http.transport.RequestedResourceDescriptor.<init>(RequestedResourceDescriptor.java:73)
at org.serviio.upnp.protocol.http.transport.AbstractProtocolHandler.getRequestedResourceDescription(AbstractProtocolHandler.java:35)
at org.serviio.delivery.ResourceDeliveryProcessor.deliverContent(ResourceDeliveryProcessor.java:65)
at org.serviio.upnp.webserver.ResourceTransportRequestHandler.handleRequest(ResourceTransportRequestHandler.java:85)
at org.serviio.upnp.webserver.AbstractRequestHandler.handle(AbstractRequestHandler.java:61)
at org.apache.http.protocol.HttpService.doService(HttpService.java:293)
at org.serviio.upnp.webserver.ServiioHttpService.doService(ServiioHttpService.java:77)
at org.apache.http.protocol.HttpService.handleRequest(HttpService.java:212)
at org.serviio.upnp.webserver.WebServer$WorkerThread.run(WebServer.java:192)
Caused by: java.lang.ArrayIndexOutOfBoundsException: 3
at org.serviio.upnp.protocol.http.transport.RequestedResourceDescriptor.<init>(RequestedResourceDescriptor.java:70)
... 8 more