MediaBrower cannot replay LiveStreams

Mon Aug 20, 2012 2:13 pm by jhb50

EDIT: See the viewtopic.php?f=5&t=7138#p51658 post in this thread for the summary of this bug.

When playing a live stream with mediabrowser set to orig quality, regardless of the original stream, the first play works correctly but the second play cannot find the original transcoded file, for example with either of these streams

Code:: http://127.0.0.1:8902 Input #0, mpegts, from 'http://127.0.0.1:8902': Duration: N/A, start: 36800.171189, bitrate: 96 kb/s Program 1 Stream #0:0[0x56](eng): Audio: mp2 ([3][0][0][0] / 0x0003), 48000 Hz, mono, s16, 96 kb/s Stream #0:1[0x57]: Video: h264 (Constrained Baseline) ([27][0][0][0] / 0x001 B), yuv420p, 646x396 [SAR 128:117 DAR 20672:11583], 62 fps, 25 tbr, 90k tbn, 50 tbc or http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d Input #0, mpegts, from 'http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e206 69fbdfb31493d': Duration: N/A, start: 27534.794789, bitrate: 15096 kb/s Program 256 Metadata: service_name : ?Eurosport Radug service_provider: ?GTSS Stream #0:0[0x80]: Video: mpeg2video (Main) ([2][0][0][0] / 0x0002), yuv420p , 704x576 [SAR 16:11 DAR 16:9], 15000 kb/s, 31.62 fps, 25 tbr, 90k tbn, 50 tbc Stream #0:1[0x81]: Audio: mp2 ([4][0][0][0] / 0x0004), 48000 Hz, mono, s16, 96 kb/s

The first play works fine but on the second play mediabrowser issues the following error message that the transcoded stream cannot be found.

Code:: Error: 200, Stream not found, NetStream.Play.StreamNotFound, clip: '[Clip] 'http://localhost:23424/cds/resource/1000000000800001/MEDIA_ITEM/FLV-0/ORIGINAL,flv_player?authToken=c3e6c87377fa434f8467c784d75da3db'' at org.flowplayer.view::Launcher/doHandleError() at org.flowplayer.view::Launcher/onClipError() at EventListener/notify() at org.flowplayer.model::EventDispatcher/_dispatchEvent() at org.flowplayer.model::EventDispatcher/http://flowplayer.org/core/internal::doDispatchErrorEvent() at org.flowplayer.model::EventDispatcher/http://flowplayer.org/core/internal::doDispatchEvent() at org.flowplayer.model::Clip/onClipEvent() at EventListener/notify() at org.flowplayer.model::EventDispatcher/_dispatchEvent() at org.flowplayer.model::EventDispatcher/http://flowplayer.org/core/internal::doDispatchErrorEvent() at org.flowplayer.model::ClipEventDispatcher/dispatchError() at org.flowplayer.controller::NetStreamControllingStreamProvider/_onNetStatus()

The log shows the first play transcoded the file and that subsequent playbacks used the existing transcode without errors. There is no indication that Mediabrowser was unable to find the previously transcoded file.

Code:: 2012-08-20 09:24:32,301 DEBUG [CDSRetrieveMediaServerResource] Request for content delivery accepted 2012-08-20 09:24:32,301 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-20 09:24:32,301 DEBUG [ResourceDeliveryProcessor] Request for resource 1000000000800001 and type 'MEDIA_ITEM' received 2012-08-20 09:24:32,301 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1000000000800001 (online) 2012-08-20 09:24:32,302 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-20 09:24:32,303 DEBUG [VideoDeliveryEngine] Delivering item '1000000000800001' for client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-20 09:24:32,303 DEBUG [VideoDeliveryEngine] Delivering file 'http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d' using transcoding 2012-08-20 09:24:32,303 DEBUG [AbstractTranscodingDeliveryEngine] No suitable transcoding job exists yet, start one for client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-20 09:24:32,304 DEBUG [FFMPEGWrapper] Invoking FFmpeg to transcode video file: http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d 2012-08-20 09:24:32,304 DEBUG [ProcessExecutor] Starting C:\Program Files\Serviio\bin\\..\lib/ffmpeg.exe -i http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d -y -copyts -c:v flv -sameq -vf scale=1024:576,setsar=1 -r 24000/1001 -g 15 -c:a libmp3lame -b:a 96k -ar 44100 -ac 1 -map 0:0 -map 0:1 -sn -f flv pipe: 2012-08-20 09:24:41,305 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d: FLV 2012-08-20 09:24:41,305 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d: FLV 2012-08-20 09:24:41,305 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d: FLV 2012-08-20 09:24:41,305 DEBUG [ResourceDeliveryProcessor] Stream entity has length: 50000000000 2012-08-20 09:24:41,306 DEBUG [ResourceDeliveryProcessor] Sending file back 2012-08-20 09:24:55,203 DEBUG [CDSRetrieveMediaServerResource] Request for content delivery accepted 2012-08-20 09:24:55,203 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-20 09:24:55,204 DEBUG [ResourceDeliveryProcessor] Request for resource 1000000000800001 and type 'MEDIA_ITEM' received 2012-08-20 09:24:55,204 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1000000000800001 (online) 2012-08-20 09:24:55,204 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-20 09:24:55,204 DEBUG [VideoDeliveryEngine] Delivering item '1000000000800001' for client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-20 09:24:55,205 DEBUG [VideoDeliveryEngine] Delivering file 'http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d' using transcoding 2012-08-20 09:24:55,205 DEBUG [AbstractTranscodingDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-20 09:24:55,205 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d: FLV 2012-08-20 09:24:55,205 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d: FLV 2012-08-20 09:24:55,205 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d: FLV 2012-08-20 09:24:55,205 DEBUG [ResourceDeliveryProcessor] Stream entity has length: 50000000000 2012-08-20 09:24:55,205 DEBUG [ResourceDeliveryProcessor] Sending file back 2012-08-20 09:24:55,303 DEBUG [CDSRetrieveMediaServerResource] Request for content delivery accepted 2012-08-20 09:24:55,303 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-20 09:24:55,304 DEBUG [ResourceDeliveryProcessor] Request for resource 1000000000800001 and type 'MEDIA_ITEM' received 2012-08-20 09:24:55,304 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1000000000800001 (online) 2012-08-20 09:24:55,304 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-20 09:24:55,304 DEBUG [VideoDeliveryEngine] Delivering item '1000000000800001' for client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-20 09:24:55,305 DEBUG [VideoDeliveryEngine] Delivering file 'http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d' using transcoding 2012-08-20 09:24:55,305 DEBUG [AbstractTranscodingDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-20 09:24:56,305 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d: FLV 2012-08-20 09:24:56,305 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d: FLV 2012-08-20 09:24:56,305 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d: FLV 2012-08-20 09:24:56,305 DEBUG [ResourceDeliveryProcessor] Stream entity has length: 50000000000 2012-08-20 09:24:56,305 DEBUG [ResourceDeliveryProcessor] Sending file back 2012-08-20 09:24:56,404 DEBUG [CDSRetrieveMediaServerResource] Request for content delivery accepted 2012-08-20 09:24:56,404 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-20 09:24:56,404 DEBUG [ResourceDeliveryProcessor] Request for resource 1000000000800001 and type 'MEDIA_ITEM' received 2012-08-20 09:24:56,404 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1000000000800001 (online) 2012-08-20 09:24:56,404 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-20 09:24:56,405 DEBUG [VideoDeliveryEngine] Delivering item '1000000000800001' for client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-20 09:24:56,405 DEBUG [VideoDeliveryEngine] Delivering file 'http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d' using transcoding 2012-08-20 09:24:56,405 DEBUG [AbstractTranscodingDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-20 09:24:56,405 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d: FLV 2012-08-20 09:24:56,405 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d: FLV 2012-08-20 09:24:56,406 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d: FLV 2012-08-20 09:24:56,406 DEBUG [ResourceDeliveryProcessor] Stream entity has length: 50000000000 2012-08-20 09:24:56,406 DEBUG [ResourceDeliveryProcessor] Sending file back

I discovered this in attempting to play SOPCAST's http:\\127.0.0.1:8902 but it also happens with other http: streams as documented above. However it does not happen with other streams like rtmp,rtsp,or mmsh. It is similar to the problem we had during the beta where a repeat playback would not work until another file was played back.

Additional tests:
Set mediabrowser to play medium quality by default.
Repetitive playbacks in medium work fine, but the file is transcoded each time, unlike original which unsuccessfully tries to reuse previous transcoded file.
Switching to Original in the player just hangs on spinning wheel, and nothing shows in the log except the kill of the previous playback, then
Switching to low resolution on the player works fine.

Code:: 2012-08-20 10:59:05,200 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-20 10:59:05,201 DEBUG [ResourceDeliveryProcessor] Request for resource 1000000000800001 and type 'MEDIA_ITEM' received 2012-08-20 10:59:05,201 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1000000000800001 (online) 2012-08-20 10:59:05,201 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-20 10:59:05,203 DEBUG [VideoDeliveryEngine] Delivering item '1000000000800001' for client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-20 10:59:05,203 DEBUG [VideoDeliveryEngine] Delivering file 'http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d' using transcoding 2012-08-20 10:59:05,204 DEBUG [AbstractTranscodingDeliveryEngine] No suitable transcoding job exists yet, start one for client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-20 10:59:05,205 DEBUG [FFMPEGWrapper] Invoking FFmpeg to transcode video file: http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d 2012-08-20 10:59:05,205 DEBUG [ProcessExecutor] Starting C:\Program Files\Serviio\bin\\..\lib/ffmpeg.exe -i http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d -y -copyts -c:v flv -b:v 1000k -maxrate:v 1000k -bufsize:v 1000k -vf scale=854:480,setsar=1 -r 24000/1001 -g 15 -c:a libmp3lame -b:a 96k -ar 44100 -ac 1 -map 0:0 -map 0:1 -sn -f flv pipe: 2012-08-20 10:59:16,205 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d: FLV 2012-08-20 10:59:16,205 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d: FLV 2012-08-20 10:59:16,205 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d: FLV 2012-08-20 10:59:16,205 DEBUG [ResourceDeliveryProcessor] Stream entity has length: 50000000000 2012-08-20 10:59:16,205 DEBUG [ResourceDeliveryProcessor] Sending file back 2012-08-20 11:01:01,312 DEBUG [CDSRetrieveMediaServerResource] Request for content delivery accepted 2012-08-20 11:01:01,312 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-20 11:01:01,313 DEBUG [ResourceDeliveryProcessor] Request for resource 1000000000800001 and type 'MEDIA_ITEM' received 2012-08-20 11:01:01,313 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1000000000800001 (online) 2012-08-20 11:01:01,313 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-20 11:01:01,314 DEBUG [VideoDeliveryEngine] Delivering item '1000000000800001' for client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-20 11:01:01,315 DEBUG [VideoDeliveryEngine] Delivering file 'http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d' using transcoding 2012-08-20 11:01:01,315 DEBUG [AbstractTranscodingDeliveryEngine] Stopping previous transcoding job of file 'transcoding-temp-1000000000800001-flv_player-MEDIUM.stf' 2012-08-20 11:01:01,315 DEBUG [ProcessExecutor] Stopping external process: Thread[Thread-121,5,main] 2012-08-20 11:01:01,316 DEBUG [ProcessUtils] Killing the Windows process: 17228 2012-08-20 11:01:01,335 DEBUG [TranscodingJobListener] Transcoding finished; successful: true 2012-08-20 11:01:01,336 DEBUG [AbstractTranscodingDeliveryEngine] No suitable transcoding job exists yet, start one for client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-20 11:01:01,337 DEBUG [FFMPEGWrapper] Invoking FFmpeg to transcode video file: http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d 2012-08-20 11:01:01,338 DEBUG [ProcessExecutor] Starting C:\Program Files\Serviio\bin\\..\lib/ffmpeg.exe -i http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d -y -copyts -c:v flv -b:v 1000k -maxrate:v 1000k -bufsize:v 1000k -vf scale=854:480,setsar=1 -r 24000/1001 -g 15 -c:a libmp3lame -b:a 96k -ar 44100 -ac 1 -map 0:0 -map 0:1 -sn -f flv pipe: 2012-08-20 11:01:12,839 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d: FLV 2012-08-20 11:01:12,839 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d: FLV 2012-08-20 11:01:12,839 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d: FLV 2012-08-20 11:01:12,839 DEBUG [ResourceDeliveryProcessor] Stream entity has length: 50000000000 2012-08-20 11:01:12,839 DEBUG [ResourceDeliveryProcessor] Sending file back 2012-08-20 11:01:13,002 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target upnp:rootdevice from address /192.168.1.10:57538 2012-08-20 11:01:13,002 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:01:13,003 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.10:57538 2012-08-20 11:01:16,344 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net4 (Realtek RTL8191SE Wireless LAN 802.11n PCI-E NIC) and address 192.168.1.10, timeout = 0 2012-08-20 11:01:16,345 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 2012-08-20 11:01:16,876 DEBUG [NonClosingPipedInputStream] Closing piped input stream and closing related feeder process 2012-08-20 11:01:17,615 WARN [PipedOutputBytesReader] Error reading bytes stream from external process: Stream closed 2012-08-20 11:01:18,145 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:55 2012-08-20 11:01:24,300 DEBUG [FeedUpdaterThread] Checking for new and expired online resources 2012-08-20 11:01:24,300 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories 2012-08-20 11:01:24,305 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 50) 2012-08-20 11:01:24,314 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 76) 2012-08-20 11:01:24,315 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 77) 2012-08-20 11:01:24,315 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-20 11:02:14,000 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net4 (Realtek RTL8191SE Wireless LAN 802.11n PCI-E NIC) and address 192.168.1.10, timeout = 0 2012-08-20 11:02:14,000 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 2012-08-20 11:02:15,801 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:55 2012-08-20 11:02:19,115 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target upnp:rootdevice from address /192.168.1.10:57538 2012-08-20 11:02:19,115 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:02:19,116 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.10:57538 2012-08-20 11:02:24,317 DEBUG [FeedUpdaterThread] Checking for new and expired online resources 2012-08-20 11:02:24,317 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories 2012-08-20 11:02:24,324 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 50) 2012-08-20 11:02:24,341 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 76) 2012-08-20 11:02:24,342 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 77) 2012-08-20 11:02:24,342 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-20 11:03:08,068 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /192.168.1.10:63950 2012-08-20 11:03:08,068 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:03:08,069 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.10:63950 2012-08-20 11:03:08,069 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:03:08,069 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.10:63950 2012-08-20 11:03:09,393 DEBUG [WebServer] Incoming connection from /192.168.1.10:50685 2012-08-20 11:03:09,393 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:03:09,394 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 from 192.168.1.10 (headers = [Cache-Control: no-cache,Connection: Close,Pragma: no-cache,Accept: text/xml, application/xml,Host: 192.168.1.10:8895,User-Agent: Microsoft-Windows/6.1 UPnP/1.0 Windows-Media-Player-DMS/12.0.7600.16385 DLNADOC/1.50]) 2012-08-20 11:03:09,394 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:03:09,394 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Cache-Control: no-cache,Connection: Close,Pragma: no-cache,Accept: text/xml, application/xml,Host: 192.168.1.10:8895,User-Agent: Microsoft-Windows/6.1 UPnP/1.0 Windows-Media-Player-DMS/12.0.7600.16385 DLNADOC/1.50] 2012-08-20 11:03:09,395 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:03:09,404 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Generic DLNA profile' 2012-08-20 11:03:09,434 DEBUG [WebServer] Incoming connection from /192.168.1.10:50686 2012-08-20 11:03:09,434 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:03:09,435 DEBUG [UPnPIconRequestHandler] UPnP icon request received for icon smallPNG 2012-08-20 11:03:11,067 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /192.168.1.10:63950 2012-08-20 11:03:11,067 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:03:11,068 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.10:63950 2012-08-20 11:03:11,068 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:03:11,069 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.10:63950 2012-08-20 11:03:11,690 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net4 (Realtek RTL8191SE Wireless LAN 802.11n PCI-E NIC) and address 192.168.1.10, timeout = 0 2012-08-20 11:03:11,691 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 2012-08-20 11:03:13,491 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:41 2012-08-20 11:03:14,067 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /192.168.1.10:63950 2012-08-20 11:03:14,067 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:03:14,068 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.1.10:63950 2012-08-20 11:03:14,068 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:03:14,069 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.10:63950 2012-08-20 11:03:24,344 DEBUG [FeedUpdaterThread] Checking for new and expired online resources 2012-08-20 11:03:24,344 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories 2012-08-20 11:03:24,346 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 50) 2012-08-20 11:03:24,365 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 76) 2012-08-20 11:03:24,365 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 77) 2012-08-20 11:03:24,366 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-20 11:03:25,227 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target upnp:rootdevice from address /192.168.1.10:57538 2012-08-20 11:03:25,227 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:03:25,228 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.10:57538 2012-08-20 11:03:55,171 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net4 (Realtek RTL8191SE Wireless LAN 802.11n PCI-E NIC) and address 192.168.1.10, timeout = 0 2012-08-20 11:03:55,172 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 2012-08-20 11:03:56,972 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:57 2012-08-20 11:04:03,220 DEBUG [PlaylistDAOImpl] Reading all Playlists 2012-08-20 11:04:03,228 DEBUG [PlaylistMaintainerThread] Playlist ALL MUSIC FOR WIIMC+ has unresolved items, checking if they are in the library now 2012-08-20 11:04:03,228 DEBUG [PlaylistParser] Parsing playlist 'D:\My Music\- Playlists\ALL MUSIC FOR WIIMC+.m3u' 2012-08-20 11:04:03,228 DEBUG [PlaylistParser] Reading playlist from a local file 2012-08-20 11:04:03,230 DEBUG [PlaylistParser] Found a suitable playlist parser strategy: M3UParserStrategy 2012-08-20 11:04:03,401 DEBUG [PlaylistDAOImpl] Reading items for playlist 9 2012-08-20 11:04:03,424 DEBUG [PlaylistMaintainerThread] Found playlist item that has not been added yet: D:\My Music\Freddy Fender - Forever Gold (2000)\Freddy Fender - Forever Gold 01 - Wasted Days And Wasted Nights.mp3 2012-08-20 11:04:03,424 DEBUG [MediaItemDAOImpl] Looking up a media item for file path: D:\My Music\Freddy Fender - Forever Gold (2000)\Freddy Fender - Forever Gold 01 - Wasted Days And Wasted Nights.mp3, ignore case: true 2012-08-20 11:04:03,425 DEBUG [PlaylistMaintainerThread] Item 'D:\My Music\Freddy Fender - Forever Gold (2000)\Freddy Fender - Forever Gold 01 - Wasted Days And Wasted Nights.mp3' cannot be resolved to an entity in the Serviio library, will try again later 2012-08-20 11:04:17,069 DEBUG [WebServer] I/O error: Connection reset 2012-08-20 11:04:31,337 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target upnp:rootdevice from address /192.168.1.10:57538 2012-08-20 11:04:31,338 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:04:31,338 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.10:57538 2012-08-20 11:04:35,668 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:63950 2012-08-20 11:04:35,668 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:04:38,668 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:63950 2012-08-20 11:04:38,668 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:04:41,668 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:63950 2012-08-20 11:04:41,668 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:04:44,684 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:63950 2012-08-20 11:04:44,684 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:04:47,684 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:63950 2012-08-20 11:04:47,684 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:04:50,684 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:63950 2012-08-20 11:04:50,684 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:04:54,992 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net4 (Realtek RTL8191SE Wireless LAN 802.11n PCI-E NIC) and address 192.168.1.10, timeout = 0 2012-08-20 11:04:54,993 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 2012-08-20 11:04:56,793 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:36 2012-08-20 11:05:24,385 DEBUG [FeedUpdaterThread] Checking for new and expired online resources 2012-08-20 11:05:24,385 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories 2012-08-20 11:05:24,387 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 50) 2012-08-20 11:05:24,396 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 76) 2012-08-20 11:05:24,397 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 77) 2012-08-20 11:05:24,397 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-20 11:05:33,289 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net4 (Realtek RTL8191SE Wireless LAN 802.11n PCI-E NIC) and address 192.168.1.10, timeout = 0 2012-08-20 11:05:33,290 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 2012-08-20 11:05:34,671 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:63950 2012-08-20 11:05:34,671 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:05:35,090 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:50 2012-08-20 11:05:37,448 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target upnp:rootdevice from address /192.168.1.10:57538 2012-08-20 11:05:37,448 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:05:37,449 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.10:57538 2012-08-20 11:05:37,671 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:63950 2012-08-20 11:05:37,671 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:05:40,671 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:63950 2012-08-20 11:05:40,671 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:05:43,689 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:63950 2012-08-20 11:05:43,689 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:05:46,686 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:63950 2012-08-20 11:05:46,686 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:05:49,686 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:63950 2012-08-20 11:05:49,686 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:06:24,399 DEBUG [FeedUpdaterThread] Checking for new and expired online resources 2012-08-20 11:06:24,399 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories 2012-08-20 11:06:24,401 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 50) 2012-08-20 11:06:24,410 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 76) 2012-08-20 11:06:24,411 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 77) 2012-08-20 11:06:24,411 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-20 11:06:25,411 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net4 (Realtek RTL8191SE Wireless LAN 802.11n PCI-E NIC) and address 192.168.1.10, timeout = 0 2012-08-20 11:06:25,411 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 2012-08-20 11:06:27,212 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:54 2012-08-20 11:06:43,561 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target upnp:rootdevice from address /192.168.1.10:57538 2012-08-20 11:06:43,561 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:06:43,562 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.10:57538 2012-08-20 11:07:21,701 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net4 (Realtek RTL8191SE Wireless LAN 802.11n PCI-E NIC) and address 192.168.1.10, timeout = 0 2012-08-20 11:07:21,702 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 2012-08-20 11:07:23,502 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:58 2012-08-20 11:07:24,412 DEBUG [FeedUpdaterThread] Checking for new and expired online resources 2012-08-20 11:07:24,412 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories 2012-08-20 11:07:24,414 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 50) 2012-08-20 11:07:24,423 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 76) 2012-08-20 11:07:24,424 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 77) 2012-08-20 11:07:24,424 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-20 11:07:49,674 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target upnp:rootdevice from address /192.168.1.10:57538 2012-08-20 11:07:49,674 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:07:49,675 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.10:57538 2012-08-20 11:08:22,445 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net4 (Realtek RTL8191SE Wireless LAN 802.11n PCI-E NIC) and address 192.168.1.10, timeout = 0 2012-08-20 11:08:22,446 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 2012-08-20 11:08:24,246 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:41 2012-08-20 11:08:24,426 DEBUG [FeedUpdaterThread] Checking for new and expired online resources 2012-08-20 11:08:24,426 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories 2012-08-20 11:08:24,430 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 50) 2012-08-20 11:08:24,446 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 76) 2012-08-20 11:08:24,447 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 77) 2012-08-20 11:08:24,448 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-20 11:08:55,791 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target upnp:rootdevice from address /192.168.1.10:57538 2012-08-20 11:08:55,792 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:08:55,792 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.10:57538 2012-08-20 11:09:02,742 DEBUG [NonClosingPipedInputStream] Closing piped input stream and closing related feeder process 2012-08-20 11:09:02,742 DEBUG [ProcessExecutor] Stopping external process: Thread[Thread-125,5,main] 2012-08-20 11:09:02,742 DEBUG [ProcessUtils] Killing the Windows process: 11024 2012-08-20 11:09:02,764 DEBUG [TranscodingJobListener] Transcoding finished; successful: true 2012-08-20 11:09:03,077 WARN [PipedOutputBytesReader] Error reading bytes stream from external process: Stream closed 2012-08-20 11:09:03,457 DEBUG [PlaylistDAOImpl] Reading all Playlists 2012-08-20 11:09:03,468 DEBUG [PlaylistMaintainerThread] Playlist ALL MUSIC FOR WIIMC+ has unresolved items, checking if they are in the library now 2012-08-20 11:09:03,469 DEBUG [PlaylistParser] Parsing playlist 'D:\My Music\- Playlists\ALL MUSIC FOR WIIMC+.m3u' 2012-08-20 11:09:03,469 DEBUG [PlaylistParser] Reading playlist from a local file 2012-08-20 11:09:03,471 DEBUG [PlaylistParser] Found a suitable playlist parser strategy: M3UParserStrategy 2012-08-20 11:09:03,503 DEBUG [PlaylistDAOImpl] Reading items for playlist 9 2012-08-20 11:09:03,520 DEBUG [PlaylistMaintainerThread] Found playlist item that has not been added yet: D:\My Music\Freddy Fender - Forever Gold (2000)\Freddy Fender - Forever Gold 01 - Wasted Days And Wasted Nights.mp3 2012-08-20 11:09:03,521 DEBUG [MediaItemDAOImpl] Looking up a media item for file path: D:\My Music\Freddy Fender - Forever Gold (2000)\Freddy Fender - Forever Gold 01 - Wasted Days And Wasted Nights.mp3, ignore case: true 2012-08-20 11:09:03,521 DEBUG [PlaylistMaintainerThread] Item 'D:\My Music\Freddy Fender - Forever Gold (2000)\Freddy Fender - Forever Gold 01 - Wasted Days And Wasted Nights.mp3' cannot be resolved to an entity in the Serviio library, will try again later 2012-08-20 11:09:05,948 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net4 (Realtek RTL8191SE Wireless LAN 802.11n PCI-E NIC) and address 192.168.1.10, timeout = 0 2012-08-20 11:09:05,949 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 2012-08-20 11:09:07,749 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:41 2012-08-20 11:09:24,449 DEBUG [FeedUpdaterThread] Checking for new and expired online resources 2012-08-20 11:09:24,449 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories 2012-08-20 11:09:24,453 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 50) 2012-08-20 11:09:24,468 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 76) 2012-08-20 11:09:24,469 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 77) 2012-08-20 11:09:24,470 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-20 11:09:49,925 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net4 (Realtek RTL8191SE Wireless LAN 802.11n PCI-E NIC) and address 192.168.1.10, timeout = 0 2012-08-20 11:09:49,926 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 2012-08-20 11:09:51,726 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:57 2012-08-20 11:10:01,910 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target upnp:rootdevice from address /192.168.1.10:57538 2012-08-20 11:10:01,911 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:10:01,911 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.10:57538 2012-08-20 11:10:24,472 DEBUG [FeedUpdaterThread] Checking for new and expired online resources 2012-08-20 11:10:24,472 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories 2012-08-20 11:10:24,476 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 50) 2012-08-20 11:10:24,492 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 76) 2012-08-20 11:10:24,492 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 77) 2012-08-20 11:10:24,493 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-20 11:10:46,827 DEBUG [CDSRetrieveMediaServerResource] Request for content delivery accepted 2012-08-20 11:10:46,827 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-20 11:10:46,828 DEBUG [ResourceDeliveryProcessor] Request for resource 1000000000800001 and type 'MEDIA_ITEM' received 2012-08-20 11:10:46,828 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1000000000800001 (online) 2012-08-20 11:10:46,828 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-20 11:10:46,829 DEBUG [VideoDeliveryEngine] Delivering item '1000000000800001' for client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-20 11:10:46,830 DEBUG [VideoDeliveryEngine] Delivering file 'http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d' using transcoding 2012-08-20 11:10:46,830 DEBUG [AbstractTranscodingDeliveryEngine] No suitable transcoding job exists yet, start one for client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-20 11:10:46,831 DEBUG [FFMPEGWrapper] Invoking FFmpeg to transcode video file: http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d 2012-08-20 11:10:46,831 DEBUG [ProcessExecutor] Starting C:\Program Files\Serviio\bin\\..\lib/ffmpeg.exe -i http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d -y -copyts -c:v flv -b:v 1000k -maxrate:v 1000k -bufsize:v 1000k -vf scale=854:480,setsar=1 -r 24000/1001 -g 15 -c:a libmp3lame -b:a 96k -ar 44100 -ac 1 -map 0:0 -map 0:1 -sn -f flv pipe: 2012-08-20 11:10:49,889 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net4 (Realtek RTL8191SE Wireless LAN 802.11n PCI-E NIC) and address 192.168.1.10, timeout = 0 2012-08-20 11:10:49,890 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 2012-08-20 11:10:51,690 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:45 2012-08-20 11:10:57,831 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d: FLV 2012-08-20 11:10:57,831 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d: FLV 2012-08-20 11:10:57,831 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d: FLV 2012-08-20 11:10:57,831 DEBUG [ResourceDeliveryProcessor] Stream entity has length: 50000000000 2012-08-20 11:10:57,831 DEBUG [ResourceDeliveryProcessor] Sending file back 2012-08-20 11:11:08,025 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target upnp:rootdevice from address /192.168.1.10:57538 2012-08-20 11:11:08,025 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:11:08,026 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.10:57538 2012-08-20 11:11:24,493 DEBUG [FeedUpdaterThread] Checking for new and expired online resources 2012-08-20 11:11:24,493 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories 2012-08-20 11:11:24,497 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 50) 2012-08-20 11:11:24,513 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 76) 2012-08-20 11:11:24,514 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 77) 2012-08-20 11:11:24,515 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-20 11:11:37,021 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net4 (Realtek RTL8191SE Wireless LAN 802.11n PCI-E NIC) and address 192.168.1.10, timeout = 0 2012-08-20 11:11:37,022 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 2012-08-20 11:11:38,822 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:38 2012-08-20 11:11:42,680 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:63950 2012-08-20 11:11:42,680 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:11:45,679 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:63950 2012-08-20 11:11:45,679 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:11:48,679 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:63950 2012-08-20 11:11:48,679 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:11:51,695 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:63950 2012-08-20 11:11:51,695 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:11:54,695 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:63950 2012-08-20 11:11:54,695 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:11:57,695 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:63950 2012-08-20 11:11:57,695 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:12:14,145 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target upnp:rootdevice from address /192.168.1.10:57538 2012-08-20 11:12:14,145 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:12:14,146 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.10:57538 2012-08-20 11:12:17,233 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net4 (Realtek RTL8191SE Wireless LAN 802.11n PCI-E NIC) and address 192.168.1.10, timeout = 0 2012-08-20 11:12:17,234 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 2012-08-20 11:12:19,034 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:40 2012-08-20 11:12:24,515 DEBUG [FeedUpdaterThread] Checking for new and expired online resources 2012-08-20 11:12:24,515 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories 2012-08-20 11:12:24,519 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 50) 2012-08-20 11:12:24,535 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 76) 2012-08-20 11:12:24,536 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 77) 2012-08-20 11:12:24,536 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-20 11:12:42,705 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:63950 2012-08-20 11:12:42,705 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:12:45,705 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:63950 2012-08-20 11:12:45,705 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:12:48,705 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:63950 2012-08-20 11:12:48,705 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:12:51,731 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:63950 2012-08-20 11:12:51,731 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:12:54,731 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:63950 2012-08-20 11:12:54,731 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:12:57,731 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:63950 2012-08-20 11:12:57,731 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:13:00,020 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net4 (Realtek RTL8191SE Wireless LAN 802.11n PCI-E NIC) and address 192.168.1.10, timeout = 0 2012-08-20 11:13:00,021 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 2012-08-20 11:13:01,822 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:54 2012-08-20 11:13:20,271 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target upnp:rootdevice from address /192.168.1.10:57538 2012-08-20 11:13:20,271 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:13:20,272 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.10:57538 2012-08-20 11:13:22,804 DEBUG [NonClosingPipedInputStream] Closing piped input stream and closing related feeder process 2012-08-20 11:13:22,804 DEBUG [ProcessExecutor] Stopping external process: Thread[Thread-131,5,main] 2012-08-20 11:13:22,804 DEBUG [ProcessUtils] Killing the Windows process: 10220 2012-08-20 11:13:22,828 DEBUG [TranscodingJobListener] Transcoding finished; successful: true 2012-08-20 11:13:22,985 WARN [PipedOutputBytesReader] Error reading bytes stream from external process: Stream closed 2012-08-20 11:13:24,538 DEBUG [FeedUpdaterThread] Checking for new and expired online resources 2012-08-20 11:13:24,538 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories 2012-08-20 11:13:24,542 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 50) 2012-08-20 11:13:24,563 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 76) 2012-08-20 11:13:24,564 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 77) 2012-08-20 11:13:24,565 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-20 11:13:56,709 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net4 (Realtek RTL8191SE Wireless LAN 802.11n PCI-E NIC) and address 192.168.1.10, timeout = 0 2012-08-20 11:13:56,710 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 2012-08-20 11:13:58,510 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:38 2012-08-20 11:14:03,537 DEBUG [PlaylistDAOImpl] Reading all Playlists 2012-08-20 11:14:03,549 DEBUG [PlaylistMaintainerThread] Playlist ALL MUSIC FOR WIIMC+ has unresolved items, checking if they are in the library now 2012-08-20 11:14:03,549 DEBUG [PlaylistParser] Parsing playlist 'D:\My Music\- Playlists\ALL MUSIC FOR WIIMC+.m3u' 2012-08-20 11:14:03,549 DEBUG [PlaylistParser] Reading playlist from a local file 2012-08-20 11:14:03,552 DEBUG [PlaylistParser] Found a suitable playlist parser strategy: M3UParserStrategy 2012-08-20 11:14:03,731 DEBUG [PlaylistDAOImpl] Reading items for playlist 9 2012-08-20 11:14:03,744 DEBUG [PlaylistMaintainerThread] Found playlist item that has not been added yet: D:\My Music\Freddy Fender - Forever Gold (2000)\Freddy Fender - Forever Gold 01 - Wasted Days And Wasted Nights.mp3 2012-08-20 11:14:03,744 DEBUG [MediaItemDAOImpl] Looking up a media item for file path: D:\My Music\Freddy Fender - Forever Gold (2000)\Freddy Fender - Forever Gold 01 - Wasted Days And Wasted Nights.mp3, ignore case: true 2012-08-20 11:14:03,744 DEBUG [PlaylistMaintainerThread] Item 'D:\My Music\Freddy Fender - Forever Gold (2000)\Freddy Fender - Forever Gold 01 - Wasted Days And Wasted Nights.mp3' cannot be resolved to an entity in the Serviio library, will try again later 2012-08-20 11:14:24,565 DEBUG [FeedUpdaterThread] Checking for new and expired online resources 2012-08-20 11:14:24,565 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories 2012-08-20 11:14:24,568 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 50) 2012-08-20 11:14:24,583 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 76) 2012-08-20 11:14:24,584 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 77) 2012-08-20 11:14:24,585 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-20 11:14:26,392 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target upnp:rootdevice from address /192.168.1.10:57538 2012-08-20 11:14:26,392 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-20 11:14:26,393 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.10:57538 2012-08-20 11:14:37,319 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net4 (Realtek RTL8191SE Wireless LAN 802.11n PCI-E NIC) and address 192.168.1.10, timeout = 0 2012-08-20 11:14:37,319 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 2012-08-20 11:14:39,119 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:47 2012-08-20 11:14:56,322 DEBUG [CDSRetrieveMediaServerResource] Request for content delivery accepted 2012-08-20 11:14:56,326 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-20 11:14:56,326 DEBUG [ResourceDeliveryProcessor] Request for resource 1000000000800001 and type 'MEDIA_ITEM' received 2012-08-20 11:14:56,326 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1000000000800001 (online) 2012-08-20 11:14:56,327 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-20 11:14:56,328 DEBUG [VideoDeliveryEngine] Delivering item '1000000000800001' for client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-20 11:14:56,328 DEBUG [VideoDeliveryEngine] Delivering file 'http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d' using transcoding 2012-08-20 11:14:56,329 DEBUG [AbstractTranscodingDeliveryEngine] No suitable transcoding job exists yet, start one for client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-20 11:14:56,329 DEBUG [FFMPEGWrapper] Invoking FFmpeg to transcode video file: http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d 2012-08-20 11:14:56,330 DEBUG [ProcessExecutor] Starting C:\Program Files\Serviio\bin\\..\lib/ffmpeg.exe -i http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d -y -copyts -c:v flv -b:v 500k -maxrate:v 500k -bufsize:v 500k -vf scale=356:200,setsar=1 -r 24000/1001 -g 15 -c:a libmp3lame -b:a 96k -ar 44100 -ac 1 -map 0:0 -map 0:1 -sn -f flv pipe: 2012-08-20 11:15:04,331 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d: FLV 2012-08-20 11:15:04,331 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d: FLV 2012-08-20 11:15:04,331 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file http://194.28.91.43:8080/esp_russian-8b3f33d1a1e1ca1e20669fbdfb31493d: FLV 2012-08-20 11:15:04,331 DEBUG [ResourceDeliveryProcessor] Stream entity has length: 50000000000 2012-08-20 11:15:04,331 DEBUG [ResourceDeliveryProcessor] Sending file back 2012-08-20 11:15:04,622 DEBUG [CDSRetrieveMediaServerResource]

Mon Aug 20, 2012 10:24 pm by jhb50

I've now tested back to 1.0 beta2 and the same problem exists on all releases. http streams will play once in mediabrowser and then will not play again because the previous transcoded file cannot be found.

I've also tested this using Mediabrowser on my Android, which has a different version of flash than my PC, and the problem is the same. This rules out flash as the cause of the problem.

I have also repetitively played the streams via DLNA on my TV and it is able to replay.

Wed Aug 22, 2012 2:58 am by jhb50

Damn!!

Just realized the problem.

How can a livestream use a previously transcoded one...its live!!

There's the bug!!

Serviio is not forcing a new transcode when an http stream is replayed!

Its trying to use a previous one which of course does not exist!

Wed Aug 22, 2012 5:50 am by grolschie

Is this where you see: "DEBUG [AbstractTranscodingDeliveryEngine] No suitable transcoding job exists yet, start one for client" ?

Thu Aug 23, 2012 3:24 am by jhb50

grolschie wrote:Is this where you see: "DEBUG [AbstractTranscodingDeliveryEngine] No suitable transcoding job exists yet, start one for client" ?

I don't understand your question. That line appears whenever a file or stream is transcoded. Nothing to do with this problem.

Thu Aug 23, 2012 3:39 am by jhb50

Zip here is a summary of info regarding this Mediaplayer bug.

Online streams transcode each time they are played with Mediaplayer. (obviously because they cant use an old transcode of a live stream) Http: streams however think there is a previous livestream transcode available for use the second time they are played and so the playback fails in Mediabrowser because the previous transcode cannot be found.

This bug has particular impact on Desktop streaming and SOPCAST streaming. Both of which use http://192.168.1.nn:pppp or http://127.0.0.1:pppp because once they are streamed, they cannot be streamed again. The bug is repeatable on my PC as well as my Android which rules out Flash as the problem because the Android Flash is different than the PC Version. The problem does not occur with DLNA streaming to the TV.

In attempting to find a workaround however I have discovered that http:/localhost:pppp does not have this problem/bug and seems to start a new transcode each time.

Let me know if I can provide more info to fix this problem.

Thu Aug 23, 2012 5:58 am by grolschie

jhb50 wrote:
grolschie wrote:Is this where you see: "DEBUG [AbstractTranscodingDeliveryEngine] No suitable transcoding job exists yet, start one for client" ?

I don't understand your question. That line appears whenever a file or stream is transcoded. Nothing to do with this problem.

Thanks. Got it. Sorry.

Mon Aug 27, 2012 10:29 pm by zip

the reuse message is there if you for example start a video, then stop it, then start it again. It works like this for DLNA clients.

Could this be related to the SOPCAST dilemma you posted about earlier?

Mon Aug 27, 2012 11:50 pm by jhb50

Could this be related to the SOPCAST dilemma you posted about earlier?

I don't think so because it works fine with the DLNA client (TV and Bubbleupnp) and I can pause and resume playback on those. It but only fails when pausing and restaring or requesting a replay on the Mediabrowser. As the log shows in those cases the previous transcode gets lost and cannot be found. Quite specific. This happened before in the beta for all videos but now its just for http's.

Wed Aug 29, 2012 6:53 pm by jhb50

OK I have found the problem.

When the http file is requested to play by mb, Serviio begins a transcode.

Code:: 2012-08-29 11:23:58,167 DEBUG [CDSRetrieveMediaServerResource] Request for content delivery accepted 2012-08-29 11:23:58,167 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-29 11:23:58,168 DEBUG [ResourceDeliveryProcessor] Request for resource 1000000000820001 and type 'MEDIA_ITEM' received 2012-08-29 11:23:58,168 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1000000000820001 (online) 2012-08-29 11:23:58,168 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 82) 2012-08-29 11:23:58,169 DEBUG [VideoDeliveryEngine] Delivering item '1000000000820001' for client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-29 11:23:58,169 DEBUG [VideoDeliveryEngine] Delivering file 'http://77.51.249.16:8103' using transcoding 2012-08-29 11:23:58,170 DEBUG [AbstractTranscodingDeliveryEngine] No suitable transcoding job exists yet, start one for client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-29 11:23:58,170 DEBUG [FFMPEGWrapper] Invoking FFmpeg to transcode video file: http://77.51.249.16:8103 2012-08-29 11:23:58,170 DEBUG [ProcessExecutor] Starting C:\Program Files\Serviio\bin\\..\lib/ffmpeg.exe -i http://77.51.249.16:8103 -y -copyts -c:v copy -c:a libmp3lame -b:a 96k -ar 44100 -ac 1 -map 0:1 -map 0:0 -sn -f flv pipe: 2012-08-29 11:24:02,670 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file http://77.51.249.16:8103: FLV 2012-08-29 11:24:02,670 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file http://77.51.249.16:8103: FLV 2012-08-29 11:24:02,670 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file http://77.51.249.16:8103: FLV 2012-08-29 11:24:02,670 DEBUG [ResourceDeliveryProcessor] Stream entity has length: 50000000000 2012-08-29 11:24:02,670 DEBUG [ResourceDeliveryProcessor] Sending file back

In my case 55 seconds later, Serviio issues the following messages, and regardless of what they say the livestream continues to play.

Code:: 2012-08-29 11:24:53,299 DEBUG [NonClosingPipedInputStream] Closing piped input stream and closing related feeder process 2012-08-29 11:24:53,299 DEBUG [ProcessExecutor] Stopping external process: Thread[Thread-1104,5,main] 2012-08-29 11:24:53,299 DEBUG [ProcessUtils] Killing the Windows process: 7304 2012-08-29 11:24:53,318 DEBUG [TranscodingJobListener] Transcoding finished; successful: true 2012-08-29 11:24:53,672 WARN [PipedOutputBytesReader] Error reading bytes stream from external process: Stream closed

If a pause and restart, or a close and replay of that http stream is requested before those messages are issued, the mb will simply spin and in firefox the flash error message that file not found is displayed.

If I wait 55 seconds before requesting a restart or a replay everything works fine.

Do I need to raise a ticket?

EDIT:
Continuing to test. This is also happening with rtmp stream for nasatvhd from ustream. The closing of the piped stream messages are sporadic, sometimes they happen after 55 seconds, and other times they take a long time like 19 minutes and 28 seconds in the attached log. that shows, the original playback at 17:59.05,
then multiple requests to play, all of which said file not found, then the piped message at 18:18.33 which allowed a replay to succeed at 18:24
Seems the problem is a delay in closing this piped stream.

Code:: 2012-08-29 17:59:04,544 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-29 17:59:04,544 DEBUG [ResourceDeliveryProcessor] Request for resource 1000000000470001 and type 'MEDIA_ITEM' received 2012-08-29 17:59:04,544 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1000000000470001 (online) 2012-08-29 17:59:04,544 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 47) 2012-08-29 17:59:04,545 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 47) 2012-08-29 17:59:04,546 DEBUG [VideoDeliveryEngine] Delivering item '1000000000470001' for client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-29 17:59:04,546 DEBUG [VideoDeliveryEngine] Delivering file 'rtmp://ustream.fc.llnwd.net/ustream playpath=ustream_llnw_live_1_112081513 swfUrl=http://static-cdn1.ustream.tv/swf/live/viewer.rsl:96.swf swfVfy=1 live=1' using transcoding 2012-08-29 17:59:04,547 DEBUG [AbstractTranscodingDeliveryEngine] No suitable transcoding job exists yet, start one for client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-29 17:59:04,547 DEBUG [VideoDeliveryEngine] Extracting new URL for the expired feed item 2012-08-29 17:59:04,547 DEBUG [FeedItemUrlExtractor] ustream.tv: Starting extraction of url for item: NASA TV in HD 2012-08-29 17:59:05,488 DEBUG [FeedItemUrlExtractor] ustream.tv: Finished extraction of url: ContentURLContainer [fileType=VIDEO, contentUrl=rtmp://ustream.fc.llnwd.net/ustream playpath=ustream_llnw_live_1_112117914 swfUrl=http://static-cdn1.ustream.tv/swf/live/viewer.rsl:96.swf swfVfy=1 live=1, thumbnailUrl=http://static-cdn1.ustream.tv/i/channel/picture/6/5/4/0/6540154/6540154_nasatv_public_hr_1330361732,66x66,r:1.jpg, expiresImmediately=true, cacheKey=USTREAM_6540154, live=true, ] 2012-08-29 17:59:05,488 DEBUG [VideoDeliveryEngine] Successfully set new URL for the feed item 2012-08-29 17:59:05,488 DEBUG [FFMPEGWrapper] Invoking FFmpeg to transcode video file: rtmp://ustream.fc.llnwd.net/ustream playpath=ustream_llnw_live_1_112117914 swfUrl=http://static-cdn1.ustream.tv/swf/live/viewer.rsl:96.swf swfVfy=1 live=1 2012-08-29 17:59:05,489 DEBUG [ProcessExecutor] Starting C:\Program Files\Serviio\bin\\..\lib/ffmpeg.exe -i rtmp://ustream.fc.llnwd.net/ustream playpath=ustream_llnw_live_1_112117914 swfUrl=http://static-cdn1.ustream.tv/swf/live/viewer.rsl:96.swf swfVfy=1 live=1 -y -copyts -c:v copy -c:a libmp3lame -b:a 64k -ar 44100 -ac 2 -map 0:0 -map 0:1 -sn -f flv pipe: 2012-08-29 17:59:09,489 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file rtmp://ustream.fc.llnwd.net/ustream playpath=ustream_llnw_live_1_112117914 swfUrl=http://static-cdn1.ustream.tv/swf/live/viewer.rsl:96.swf swfVfy=1 live=1: FLV 2012-08-29 17:59:09,489 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file rtmp://ustream.fc.llnwd.net/ustream playpath=ustream_llnw_live_1_112117914 swfUrl=http://static-cdn1.ustream.tv/swf/live/viewer.rsl:96.swf swfVfy=1 live=1: FLV 2012-08-29 17:59:09,489 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file rtmp://ustream.fc.llnwd.net/ustream playpath=ustream_llnw_live_1_112117914 swfUrl=http://static-cdn1.ustream.tv/swf/live/viewer.rsl:96.swf swfVfy=1 live=1: FLV 2012-08-29 17:59:09,489 DEBUG [ResourceDeliveryProcessor] Stream entity has length: 50000000000 2012-08-29 17:59:09,489 DEBUG [ResourceDeliveryProcessor] Sending file back 2012-08-29 17:59:23,570 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target upnp:rootdevice from address /192.168.1.10:57538 2012-08-29 17:59:23,570 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 17:59:23,571 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.10:57538 2012-08-29 17:59:25,539 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net4 (Realtek RTL8191SE Wireless LAN 802.11n PCI-E NIC) and address 192.168.1.10, timeout = 0 2012-08-29 17:59:25,539 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 2012-08-29 17:59:27,340 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:56 2012-08-29 17:59:43,403 DEBUG [FeedUpdaterThread] Checking for new and expired online resources 2012-08-29 17:59:43,403 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories 2012-08-29 17:59:43,404 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 6) 2012-08-29 17:59:43,419 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 47) 2012-08-29 17:59:43,420 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 77) 2012-08-29 17:59:43,420 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-29 17:59:43,420 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 82) 2012-08-29 17:59:43,421 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 83) 2012-08-29 17:59:43,421 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 99) 2012-08-29 18:00:23,631 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net4 (Realtek RTL8191SE Wireless LAN 802.11n PCI-E NIC) and address 192.168.1.10, timeout = 0 2012-08-29 18:00:23,631 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 2012-08-29 18:00:23,699 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target upnp:rootdevice from address /192.168.1.10:57538 2012-08-29 18:00:23,699 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:00:23,700 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.10:57538 2012-08-29 18:00:25,431 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:56 2012-08-29 18:00:43,423 DEBUG [FeedUpdaterThread] Checking for new and expired online resources 2012-08-29 18:00:43,423 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories 2012-08-29 18:00:43,425 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 6) 2012-08-29 18:00:43,451 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 47) 2012-08-29 18:00:43,452 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 77) 2012-08-29 18:00:43,453 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-29 18:00:43,453 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 82) 2012-08-29 18:00:43,453 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 83) 2012-08-29 18:00:43,454 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 99) 2012-08-29 18:01:22,288 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net4 (Realtek RTL8191SE Wireless LAN 802.11n PCI-E NIC) and address 192.168.1.10, timeout = 0 2012-08-29 18:01:22,288 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 2012-08-29 18:01:23,835 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target upnp:rootdevice from address /192.168.1.10:57538 2012-08-29 18:01:23,835 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:01:23,836 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.10:57538 2012-08-29 18:01:24,088 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:37 2012-08-29 18:01:43,456 DEBUG [FeedUpdaterThread] Checking for new and expired online resources 2012-08-29 18:01:43,456 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories 2012-08-29 18:01:43,458 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 6) 2012-08-29 18:01:43,480 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 47) 2012-08-29 18:01:43,481 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 77) 2012-08-29 18:01:43,481 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-29 18:01:43,482 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 82) 2012-08-29 18:01:43,482 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 83) 2012-08-29 18:01:43,483 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 99) 2012-08-29 18:02:01,626 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net4 (Realtek RTL8191SE Wireless LAN 802.11n PCI-E NIC) and address 192.168.1.10, timeout = 0 2012-08-29 18:02:01,627 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 2012-08-29 18:02:03,428 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:42 2012-08-29 18:02:07,913 DEBUG [PlaylistDAOImpl] Reading all Playlists 2012-08-29 18:02:07,918 DEBUG [PlaylistMaintainerThread] Playlist ALL MUSIC FOR WIIMC+ has unresolved items, checking if they are in the library now 2012-08-29 18:02:07,920 DEBUG [PlaylistParser] Parsing playlist 'D:\My Music\- Playlists\ALL MUSIC FOR WIIMC+.m3u' 2012-08-29 18:02:07,920 DEBUG [PlaylistParser] Reading playlist from a local file 2012-08-29 18:02:07,922 DEBUG [PlaylistParser] Found a suitable playlist parser strategy: M3UParserStrategy 2012-08-29 18:02:07,956 DEBUG [PlaylistDAOImpl] Reading items for playlist 9 2012-08-29 18:02:07,964 DEBUG [PlaylistMaintainerThread] Found playlist item that has not been added yet: D:\My Music\Freddy Fender - Forever Gold (2000)\Freddy Fender - Forever Gold 01 - Wasted Days And Wasted Nights.mp3 2012-08-29 18:02:07,964 DEBUG [MediaItemDAOImpl] Looking up a media item for file path: D:\My Music\Freddy Fender - Forever Gold (2000)\Freddy Fender - Forever Gold 01 - Wasted Days And Wasted Nights.mp3, ignore case: true 2012-08-29 18:02:07,964 DEBUG [PlaylistMaintainerThread] Item 'D:\My Music\Freddy Fender - Forever Gold (2000)\Freddy Fender - Forever Gold 01 - Wasted Days And Wasted Nights.mp3' cannot be resolved to an entity in the Serviio library, will try again later 2012-08-29 18:02:23,961 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target upnp:rootdevice from address /192.168.1.10:57538 2012-08-29 18:02:23,962 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:02:23,963 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.10:57538 2012-08-29 18:02:43,485 DEBUG [FeedUpdaterThread] Checking for new and expired online resources 2012-08-29 18:02:43,485 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories 2012-08-29 18:02:43,487 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 6) 2012-08-29 18:02:43,509 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 47) 2012-08-29 18:02:43,509 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 77) 2012-08-29 18:02:43,510 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-29 18:02:43,510 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 82) 2012-08-29 18:02:43,511 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 83) 2012-08-29 18:02:43,511 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 99) 2012-08-29 18:02:46,568 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net4 (Realtek RTL8191SE Wireless LAN 802.11n PCI-E NIC) and address 192.168.1.10, timeout = 0 2012-08-29 18:02:46,569 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 2012-08-29 18:02:48,369 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:43 2012-08-29 18:03:24,085 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target upnp:rootdevice from address /192.168.1.10:57538 2012-08-29 18:03:24,085 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:03:24,086 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.10:57538 2012-08-29 18:03:31,754 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net4 (Realtek RTL8191SE Wireless LAN 802.11n PCI-E NIC) and address 192.168.1.10, timeout = 0 2012-08-29 18:03:31,754 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 2012-08-29 18:03:33,554 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:48 2012-08-29 18:03:43,513 DEBUG [FeedUpdaterThread] Checking for new and expired online resources 2012-08-29 18:03:43,513 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories 2012-08-29 18:03:43,515 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 6) 2012-08-29 18:03:43,540 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 47) 2012-08-29 18:03:43,541 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 77) 2012-08-29 18:03:43,542 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-29 18:03:43,543 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 82) 2012-08-29 18:03:43,544 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 83) 2012-08-29 18:03:43,544 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 99) 2012-08-29 18:03:47,175 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:60931 2012-08-29 18:03:47,175 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:03:50,175 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:60931 2012-08-29 18:03:50,175 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:03:53,175 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:60931 2012-08-29 18:03:53,175 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:03:56,198 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:60931 2012-08-29 18:03:56,198 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:03:59,198 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:60931 2012-08-29 18:03:59,198 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:04:02,198 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:60931 2012-08-29 18:04:02,198 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:04:21,852 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net4 (Realtek RTL8191SE Wireless LAN 802.11n PCI-E NIC) and address 192.168.1.10, timeout = 0 2012-08-29 18:04:21,852 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 2012-08-29 18:04:23,653 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:55 2012-08-29 18:04:24,227 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target upnp:rootdevice from address /192.168.1.10:57538 2012-08-29 18:04:24,227 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:04:24,228 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.10:57538 2012-08-29 18:04:43,547 DEBUG [FeedUpdaterThread] Checking for new and expired online resources 2012-08-29 18:04:43,547 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories 2012-08-29 18:04:43,548 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 6) 2012-08-29 18:04:43,563 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 47) 2012-08-29 18:04:43,564 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 77) 2012-08-29 18:04:43,564 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-29 18:04:43,564 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 82) 2012-08-29 18:04:43,565 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 83) 2012-08-29 18:04:43,565 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 99) 2012-08-29 18:04:49,180 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:60931 2012-08-29 18:04:49,180 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:04:52,180 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:60931 2012-08-29 18:04:52,180 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:04:52,499 DEBUG [CDSRetrieveMediaServerResource] Request for content delivery accepted 2012-08-29 18:04:52,499 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-29 18:04:52,499 DEBUG [ResourceDeliveryProcessor] Request for resource 1000000000470001 and type 'MEDIA_ITEM' received 2012-08-29 18:04:52,499 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1000000000470001 (online) 2012-08-29 18:04:52,499 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 47) 2012-08-29 18:04:52,500 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 47) 2012-08-29 18:04:52,501 DEBUG [VideoDeliveryEngine] Delivering item '1000000000470001' for client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-29 18:04:52,501 DEBUG [VideoDeliveryEngine] Delivering file 'rtmp://ustream.fc.llnwd.net/ustream playpath=ustream_llnw_live_1_112081513 swfUrl=http://static-cdn1.ustream.tv/swf/live/viewer.rsl:96.swf swfVfy=1 live=1' using transcoding 2012-08-29 18:04:52,502 DEBUG [AbstractTranscodingDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-29 18:04:52,502 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file rtmp://ustream.fc.llnwd.net/ustream playpath=ustream_llnw_live_1_112081513 swfUrl=http://static-cdn1.ustream.tv/swf/live/viewer.rsl:96.swf swfVfy=1 live=1: FLV 2012-08-29 18:04:52,502 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file rtmp://ustream.fc.llnwd.net/ustream playpath=ustream_llnw_live_1_112081513 swfUrl=http://static-cdn1.ustream.tv/swf/live/viewer.rsl:96.swf swfVfy=1 live=1: FLV 2012-08-29 18:04:52,502 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file rtmp://ustream.fc.llnwd.net/ustream playpath=ustream_llnw_live_1_112081513 swfUrl=http://static-cdn1.ustream.tv/swf/live/viewer.rsl:96.swf swfVfy=1 live=1: FLV 2012-08-29 18:04:52,502 DEBUG [ResourceDeliveryProcessor] Stream entity has length: 50000000000 2012-08-29 18:04:52,502 DEBUG [ResourceDeliveryProcessor] Sending file back 2012-08-29 18:04:52,899 DEBUG [CDSRetrieveMediaServerResource] Request for content delivery accepted 2012-08-29 18:04:52,899 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-29 18:04:52,899 DEBUG [ResourceDeliveryProcessor] Request for resource 1000000000470001 and type 'MEDIA_ITEM' received 2012-08-29 18:04:52,899 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1000000000470001 (online) 2012-08-29 18:04:52,899 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 47) 2012-08-29 18:04:52,900 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 47) 2012-08-29 18:04:52,901 DEBUG [VideoDeliveryEngine] Delivering item '1000000000470001' for client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-29 18:04:52,901 DEBUG [VideoDeliveryEngine] Delivering file 'rtmp://ustream.fc.llnwd.net/ustream playpath=ustream_llnw_live_1_112081513 swfUrl=http://static-cdn1.ustream.tv/swf/live/viewer.rsl:96.swf swfVfy=1 live=1' using transcoding 2012-08-29 18:04:52,902 DEBUG [AbstractTranscodingDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-29 18:04:53,902 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file rtmp://ustream.fc.llnwd.net/ustream playpath=ustream_llnw_live_1_112081513 swfUrl=http://static-cdn1.ustream.tv/swf/live/viewer.rsl:96.swf swfVfy=1 live=1: FLV 2012-08-29 18:04:53,902 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file rtmp://ustream.fc.llnwd.net/ustream playpath=ustream_llnw_live_1_112081513 swfUrl=http://static-cdn1.ustream.tv/swf/live/viewer.rsl:96.swf swfVfy=1 live=1: FLV 2012-08-29 18:04:53,902 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file rtmp://ustream.fc.llnwd.net/ustream playpath=ustream_llnw_live_1_112081513 swfUrl=http://static-cdn1.ustream.tv/swf/live/viewer.rsl:96.swf swfVfy=1 live=1: FLV 2012-08-29 18:04:53,902 DEBUG [ResourceDeliveryProcessor] Stream entity has length: 50000000000 2012-08-29 18:04:53,902 DEBUG [ResourceDeliveryProcessor] Sending file back 2012-08-29 18:04:54,000 DEBUG [CDSRetrieveMediaServerResource] Request for content delivery accepted 2012-08-29 18:04:54,000 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-29 18:04:54,000 DEBUG [ResourceDeliveryProcessor] Request for resource 1000000000470001 and type 'MEDIA_ITEM' received 2012-08-29 18:04:54,000 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1000000000470001 (online) 2012-08-29 18:04:54,001 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 47) 2012-08-29 18:04:54,001 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 47) 2012-08-29 18:04:54,002 DEBUG [VideoDeliveryEngine] Delivering item '1000000000470001' for client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-29 18:04:54,002 DEBUG [VideoDeliveryEngine] Delivering file 'rtmp://ustream.fc.llnwd.net/ustream playpath=ustream_llnw_live_1_112081513 swfUrl=http://static-cdn1.ustream.tv/swf/live/viewer.rsl:96.swf swfVfy=1 live=1' using transcoding 2012-08-29 18:04:54,002 DEBUG [AbstractTranscodingDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-29 18:04:54,002 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file rtmp://ustream.fc.llnwd.net/ustream playpath=ustream_llnw_live_1_112081513 swfUrl=http://static-cdn1.ustream.tv/swf/live/viewer.rsl:96.swf swfVfy=1 live=1: FLV 2012-08-29 18:04:54,002 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file rtmp://ustream.fc.llnwd.net/ustream playpath=ustream_llnw_live_1_112081513 swfUrl=http://static-cdn1.ustream.tv/swf/live/viewer.rsl:96.swf swfVfy=1 live=1: FLV 2012-08-29 18:04:54,002 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file rtmp://ustream.fc.llnwd.net/ustream playpath=ustream_llnw_live_1_112081513 swfUrl=http://static-cdn1.ustream.tv/swf/live/viewer.rsl:96.swf swfVfy=1 live=1: FLV 2012-08-29 18:04:54,002 DEBUG [ResourceDeliveryProcessor] Stream entity has length: 50000000000 2012-08-29 18:04:54,002 DEBUG [ResourceDeliveryProcessor] Sending file back 2012-08-29 18:04:55,180 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:60931 2012-08-29 18:04:55,180 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:04:58,195 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:60931 2012-08-29 18:04:58,195 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:05:01,195 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:60931 2012-08-29 18:05:01,195 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:05:04,195 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:60931 2012-08-29 18:05:04,195 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:05:19,570 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net4 (Realtek RTL8191SE Wireless LAN 802.11n PCI-E NIC) and address 192.168.1.10, timeout = 0 2012-08-29 18:05:19,570 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 2012-08-29 18:05:21,371 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:47 2012-08-29 18:05:24,348 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target upnp:rootdevice from address /192.168.1.10:57538 2012-08-29 18:05:24,349 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:05:24,349 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.10:57538 2012-08-29 18:05:43,567 DEBUG [FeedUpdaterThread] Checking for new and expired online resources 2012-08-29 18:05:43,567 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories 2012-08-29 18:05:43,569 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 6) 2012-08-29 18:05:43,594 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 47) 2012-08-29 18:05:43,595 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 77) 2012-08-29 18:05:43,596 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-29 18:05:43,596 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 82) 2012-08-29 18:05:43,597 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 83) 2012-08-29 18:05:43,598 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 99) 2012-08-29 18:06:09,311 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net4 (Realtek RTL8191SE Wireless LAN 802.11n PCI-E NIC) and address 192.168.1.10, timeout = 0 2012-08-29 18:06:09,311 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 2012-08-29 18:06:11,112 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:40 2012-08-29 18:06:24,484 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target upnp:rootdevice from address /192.168.1.10:57538 2012-08-29 18:06:24,484 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:06:24,485 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.10:57538 2012-08-29 18:06:43,600 DEBUG [FeedUpdaterThread] Checking for new and expired online resources 2012-08-29 18:06:43,600 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories 2012-08-29 18:06:43,602 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 6) 2012-08-29 18:06:43,632 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 47) 2012-08-29 18:06:43,633 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 77) 2012-08-29 18:06:43,634 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-29 18:06:43,635 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 82) 2012-08-29 18:06:43,635 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 83) 2012-08-29 18:06:43,636 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 99) 2012-08-29 18:06:51,799 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net4 (Realtek RTL8191SE Wireless LAN 802.11n PCI-E NIC) and address 192.168.1.10, timeout = 0 2012-08-29 18:06:51,800 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 2012-08-29 18:06:53,600 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:55 2012-08-29 18:07:07,995 DEBUG [PlaylistDAOImpl] Reading all Playlists 2012-08-29 18:07:08,000 DEBUG [PlaylistMaintainerThread] Playlist ALL MUSIC FOR WIIMC+ has unresolved items, checking if they are in the library now 2012-08-29 18:07:08,000 DEBUG [PlaylistParser] Parsing playlist 'D:\My Music\- Playlists\ALL MUSIC FOR WIIMC+.m3u' 2012-08-29 18:07:08,000 DEBUG [PlaylistParser] Reading playlist from a local file 2012-08-29 18:07:08,003 DEBUG [PlaylistParser] Found a suitable playlist parser strategy: M3UParserStrategy 2012-08-29 18:07:08,024 DEBUG [PlaylistDAOImpl] Reading items for playlist 9 2012-08-29 18:07:08,032 DEBUG [PlaylistMaintainerThread] Found playlist item that has not been added yet: D:\My Music\Freddy Fender - Forever Gold (2000)\Freddy Fender - Forever Gold 01 - Wasted Days And Wasted Nights.mp3 2012-08-29 18:07:08,032 DEBUG [MediaItemDAOImpl] Looking up a media item for file path: D:\My Music\Freddy Fender - Forever Gold (2000)\Freddy Fender - Forever Gold 01 - Wasted Days And Wasted Nights.mp3, ignore case: true 2012-08-29 18:07:08,033 DEBUG [PlaylistMaintainerThread] Item 'D:\My Music\Freddy Fender - Forever Gold (2000)\Freddy Fender - Forever Gold 01 - Wasted Days And Wasted Nights.mp3' cannot be resolved to an entity in the Serviio library, will try again later 2012-08-29 18:07:24,615 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target upnp:rootdevice from address /192.168.1.10:57538 2012-08-29 18:07:24,615 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:07:24,616 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.10:57538 2012-08-29 18:07:43,639 DEBUG [FeedUpdaterThread] Checking for new and expired online resources 2012-08-29 18:07:43,639 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories 2012-08-29 18:07:43,640 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 6) 2012-08-29 18:07:43,654 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 47) 2012-08-29 18:07:43,654 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 77) 2012-08-29 18:07:43,655 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-29 18:07:43,655 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 82) 2012-08-29 18:07:43,656 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 83) 2012-08-29 18:07:43,656 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 99) 2012-08-29 18:07:49,378 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net4 (Realtek RTL8191SE Wireless LAN 802.11n PCI-E NIC) and address 192.168.1.10, timeout = 0 2012-08-29 18:07:49,378 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 2012-08-29 18:07:51,178 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:45 2012-08-29 18:08:07,888 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target upnp:rootdevice from address /192.168.1.10:57538 2012-08-29 18:08:07,888 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:08:07,889 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.10:57538 2012-08-29 18:08:08,523 DEBUG [WebServer] Incoming connection from /192.168.1.10:51106 2012-08-29 18:08:08,524 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:08:08,525 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 from 192.168.1.10 (headers = [User-Agent: Azureus 4.2.0.2,Connection: close,Accept-Encoding: gzip,Host: 192.168.1.10:8895,Accept: text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2]) 2012-08-29 18:08:08,525 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:08:08,526 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [User-Agent: Azureus 4.2.0.2,Connection: close,Accept-Encoding: gzip,Host: 192.168.1.10:8895,Accept: text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2] 2012-08-29 18:08:08,526 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:08:08,530 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Generic DLNA profile' 2012-08-29 18:08:24,756 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target upnp:rootdevice from address /192.168.1.10:57538 2012-08-29 18:08:24,756 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:08:24,757 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.10:57538 2012-08-29 18:08:36,550 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net4 (Realtek RTL8191SE Wireless LAN 802.11n PCI-E NIC) and address 192.168.1.10, timeout = 0 2012-08-29 18:08:36,550 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 2012-08-29 18:08:38,351 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:36 2012-08-29 18:08:43,657 DEBUG [FeedUpdaterThread] Checking for new and expired online resources 2012-08-29 18:08:43,657 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories 2012-08-29 18:08:43,659 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 6) 2012-08-29 18:08:43,685 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 47) 2012-08-29 18:08:43,686 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 77) 2012-08-29 18:08:43,687 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-29 18:08:43,687 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 82) 2012-08-29 18:08:43,688 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 83) 2012-08-29 18:08:43,689 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 99) 2012-08-29 18:09:15,006 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net4 (Realtek RTL8191SE Wireless LAN 802.11n PCI-E NIC) and address 192.168.1.10, timeout = 0 2012-08-29 18:09:15,006 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 2012-08-29 18:09:16,806 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:52 2012-08-29 18:09:24,890 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target upnp:rootdevice from address /192.168.1.10:57538 2012-08-29 18:09:24,890 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:09:24,891 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.10:57538 2012-08-29 18:09:43,691 DEBUG [FeedUpdaterThread] Checking for new and expired online resources 2012-08-29 18:09:43,691 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories 2012-08-29 18:09:43,693 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 6) 2012-08-29 18:09:43,718 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 47) 2012-08-29 18:09:43,719 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 77) 2012-08-29 18:09:43,720 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-29 18:09:43,721 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 82) 2012-08-29 18:09:43,721 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 83) 2012-08-29 18:09:43,722 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 99) 2012-08-29 18:10:10,035 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net4 (Realtek RTL8191SE Wireless LAN 802.11n PCI-E NIC) and address 192.168.1.10, timeout = 0 2012-08-29 18:10:10,036 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 2012-08-29 18:10:11,836 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:41 2012-08-29 18:10:25,042 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target upnp:rootdevice from address /192.168.1.10:57538 2012-08-29 18:10:25,043 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:10:25,043 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.10:57538 2012-08-29 18:10:43,195 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:60931 2012-08-29 18:10:43,196 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:10:43,725 DEBUG [FeedUpdaterThread] Checking for new and expired online resources 2012-08-29 18:10:43,725 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories 2012-08-29 18:10:43,727 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 6) 2012-08-29 18:10:43,752 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 47) 2012-08-29 18:10:43,754 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 77) 2012-08-29 18:10:43,755 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-29 18:10:43,756 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 82) 2012-08-29 18:10:43,756 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 83) 2012-08-29 18:10:43,757 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 99) 2012-08-29 18:10:46,195 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:60931 2012-08-29 18:10:46,195 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:10:49,195 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:60931 2012-08-29 18:10:49,195 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:10:52,222 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:60931 2012-08-29 18:10:52,222 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:10:53,776 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net4 (Realtek RTL8191SE Wireless LAN 802.11n PCI-E NIC) and address 192.168.1.10, timeout = 0 2012-08-29 18:10:53,776 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 2012-08-29 18:10:55,222 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:60931 2012-08-29 18:10:55,222 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:10:55,576 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:58 2012-08-29 18:10:58,223 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:60931 2012-08-29 18:10:58,223 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:11:25,180 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target upnp:rootdevice from address /192.168.1.10:57538 2012-08-29 18:11:25,180 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:11:25,180 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.10:57538 2012-08-29 18:11:42,200 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:60931 2012-08-29 18:11:42,200 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:11:43,759 DEBUG [FeedUpdaterThread] Checking for new and expired online resources 2012-08-29 18:11:43,759 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories 2012-08-29 18:11:43,761 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 6) 2012-08-29 18:11:43,785 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 47) 2012-08-29 18:11:43,787 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 77) 2012-08-29 18:11:43,787 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-29 18:11:43,788 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 82) 2012-08-29 18:11:43,789 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 83) 2012-08-29 18:11:43,790 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 99) 2012-08-29 18:11:45,199 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:60931 2012-08-29 18:11:45,199 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:11:48,199 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:60931 2012-08-29 18:11:48,199 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:11:51,227 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:60931 2012-08-29 18:11:51,227 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:11:53,949 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net4 (Realtek RTL8191SE Wireless LAN 802.11n PCI-E NIC) and address 192.168.1.10, timeout = 0 2012-08-29 18:11:53,950 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 2012-08-29 18:11:54,227 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:60931 2012-08-29 18:11:54,227 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:11:55,750 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:40 2012-08-29 18:11:57,227 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.1.10:60931 2012-08-29 18:11:57,227 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:12:08,048 DEBUG [PlaylistDAOImpl] Reading all Playlists 2012-08-29 18:12:08,053 DEBUG [PlaylistMaintainerThread] Playlist ALL MUSIC FOR WIIMC+ has unresolved items, checking if they are in the library now 2012-08-29 18:12:08,054 DEBUG [PlaylistParser] Parsing playlist 'D:\My Music\- Playlists\ALL MUSIC FOR WIIMC+.m3u' 2012-08-29 18:12:08,054 DEBUG [PlaylistParser] Reading playlist from a local file 2012-08-29 18:12:08,057 DEBUG [PlaylistParser] Found a suitable playlist parser strategy: M3UParserStrategy 2012-08-29 18:12:08,250 DEBUG [PlaylistDAOImpl] Reading items for playlist 9 2012-08-29 18:12:08,258 DEBUG [PlaylistMaintainerThread] Found playlist item that has not been added yet: D:\My Music\Freddy Fender - Forever Gold (2000)\Freddy Fender - Forever Gold 01 - Wasted Days And Wasted Nights.mp3 2012-08-29 18:12:08,258 DEBUG [MediaItemDAOImpl] Looking up a media item for file path: D:\My Music\Freddy Fender - Forever Gold (2000)\Freddy Fender - Forever Gold 01 - Wasted Days And Wasted Nights.mp3, ignore case: true 2012-08-29 18:12:08,258 DEBUG [PlaylistMaintainerThread] Item 'D:\My Music\Freddy Fender - Forever Gold (2000)\Freddy Fender - Forever Gold 01 - Wasted Days And Wasted Nights.mp3' cannot be resolved to an entity in the Serviio library, will try again later 2012-08-29 18:12:25,328 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target upnp:rootdevice from address /192.168.1.10:57538 2012-08-29 18:12:25,329 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:12:25,329 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.10:57538 2012-08-29 18:12:36,607 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net4 (Realtek RTL8191SE Wireless LAN 802.11n PCI-E NIC) and address 192.168.1.10, timeout = 0 2012-08-29 18:12:36,607 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 2012-08-29 18:12:38,408 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:59 2012-08-29 18:12:43,793 DEBUG [FeedUpdaterThread] Checking for new and expired online resources 2012-08-29 18:12:43,793 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories 2012-08-29 18:12:43,795 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 6) 2012-08-29 18:12:43,819 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 47) 2012-08-29 18:12:43,820 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 77) 2012-08-29 18:12:43,821 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-29 18:12:43,822 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 82) 2012-08-29 18:12:43,823 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 83) 2012-08-29 18:12:43,823 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 99) 2012-08-29 18:13:25,515 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target upnp:rootdevice from address /192.168.1.10:57538 2012-08-29 18:13:25,516 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.10 2012-08-29 18:13:25,516 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.10:57538 2012-08-29 18:13:38,537 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net4 (Realtek RTL8191SE Wireless LAN 802.11n PCI-E NIC) and address 192.168.1.10, timeout = 0 2012-08-29 18:13:38,537 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 8dee68fe-0370-3fc8-a5f5-ae4a1fb10a65 2012-08-29 18:13:40,337 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:38 2012-08-29 18:13:43,826 DEBUG [FeedUpdaterThread] Checking for new and expired online resources 2012-08-29 18:13:43,826 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories 2012-08-29 18:13:43,827 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 6) 2012-08-29 18:13:43,841 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 47) 2012-08-29 18:13:43,842 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 77) 2012-08-29 18:13:43,842 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 80) 2012-08-29 18:13:43,843 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 82) 2012-08-29 18:13:43,843 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 83) 2012-08-29 18:13:43,844 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 99) 2012-08-29 18:13:54,747 DEBUG [CDSRetrieveMediaServerResource] Request for content delivery accepted 2012-08-29 18:13:54,747 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-29 18:13:54,747 DEBUG [ResourceDeliveryProcessor] Request for resource 1000000000470001 and type 'MEDIA_ITEM' received 2012-08-29 18:13:54,747 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1000000000470001 (online) 2012-08-29 18:13:54,747 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 47) 2012-08-29 18:13:54,748 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 47) 2012-08-29 18:13:54,749 DEBUG [VideoDeliveryEngine] Delivering item '1000000000470001' for client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-29 18:13:54,750 DEBUG [VideoDeliveryEngine] Delivering file 'rtmp://ustream.fc.llnwd.net/ustream playpath=ustream_llnw_live_1_112081513 swfUrl=http://static-cdn1.ustream.tv/swf/live/viewer.rsl:96.swf swfVfy=1 live=1' using transcoding 2012-08-29 18:13:54,750 DEBUG [AbstractTranscodingDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-29 18:13:54,750 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file rtmp://ustream.fc.llnwd.net/ustream playpath=ustream_llnw_live_1_112081513 swfUrl=http://static-cdn1.ustream.tv/swf/live/viewer.rsl:96.swf swfVfy=1 live=1: FLV 2012-08-29 18:13:54,750 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file rtmp://ustream.fc.llnwd.net/ustream playpath=ustream_llnw_live_1_112081513 swfUrl=http://static-cdn1.ustream.tv/swf/live/viewer.rsl:96.swf swfVfy=1 live=1: FLV 2012-08-29 18:13:54,750 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file rtmp://ustream.fc.llnwd.net/ustream playpath=ustream_llnw_live_1_112081513 swfUrl=http://static-cdn1.ustream.tv/swf/live/viewer.rsl:96.swf swfVfy=1 live=1: FLV 2012-08-29 18:13:54,750 DEBUG [ResourceDeliveryProcessor] Stream entity has length: 50000000000 2012-08-29 18:13:54,751 DEBUG [ResourceDeliveryProcessor] Sending file back 2012-08-29 18:13:55,146 DEBUG [CDSRetrieveMediaServerResource] Request for content delivery accepted 2012-08-29 18:13:55,146 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-29 18:13:55,147 DEBUG [ResourceDeliveryProcessor] Request for resource 1000000000470001 and type 'MEDIA_ITEM' received 2012-08-29 18:13:55,147 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1000000000470001 (online) 2012-08-29 18:13:55,147 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 47) 2012-08-29 18:13:55,147 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 47) 2012-08-29 18:13:55,148 DEBUG [VideoDeliveryEngine] Delivering item '1000000000470001' for client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-29 18:13:55,148 DEBUG [VideoDeliveryEngine] Delivering file 'rtmp://ustream.fc.llnwd.net/ustream playpath=ustream_llnw_live_1_112081513 swfUrl=http://static-cdn1.ustream.tv/swf/live/viewer.rsl:96.swf swfVfy=1 live=1' using transcoding 2012-08-29 18:13:55,148 DEBUG [AbstractTranscodingDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-29 18:13:56,148 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file rtmp://ustream.fc.llnwd.net/ustream playpath=ustream_llnw_live_1_112081513 swfUrl=http://static-cdn1.ustream.tv/swf/live/viewer.rsl:96.swf swfVfy=1 live=1: FLV 2012-08-29 18:13:56,148 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file rtmp://ustream.fc.llnwd.net/ustream playpath=ustream_llnw_live_1_112081513 swfUrl=http://static-cdn1.ustream.tv/swf/live/viewer.rsl:96.swf swfVfy=1 live=1: FLV 2012-08-29 18:13:56,148 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file rtmp://ustream.fc.llnwd.net/ustream playpath=ustream_llnw_live_1_112081513 swfUrl=http://static-cdn1.ustream.tv/swf/live/viewer.rsl:96.swf swfVfy=1 live=1: FLV 2012-08-29 18:13:56,148 DEBUG [ResourceDeliveryProcessor] Stream entity has length: 50000000000 2012-08-29 18:13:56,148 DEBUG [ResourceDeliveryProcessor] Sending file back 2012-08-29 18:13:56,233 DEBUG [CDSRetrieveMediaServerResource] Request for content delivery accepted 2012-08-29 18:13:56,233 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-29 18:13:56,234 DEBUG [ResourceDeliveryProcessor] Request for resource 1000000000470001 and type 'MEDIA_ITEM' received 2012-08-29 18:13:56,234 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1000000000470001 (online) 2012-08-29 18:13:56,234 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 47) 2012-08-29 18:13:56,235 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 47) 2012-08-29 18:13:56,235 DEBUG [VideoDeliveryEngine] Delivering item '1000000000470001' for client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-29 18:13:56,235 DEBUG [VideoDeliveryEngine] Delivering file 'rtmp://ustream.fc.llnwd.net/ustream playpath=ustream_llnw_live_1_112081513 swfUrl=http://static-cdn1.ustream.tv/swf/live/viewer.rsl:96.swf swfVfy=1 live=1' using transcoding 2012-08-29 18:13:56,236 DEBUG [AbstractTranscodingDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'IPAddress=/127.0.0.1, Profile=Flash player' 2012-08-29 18:13:56,236 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file rtmp://ustream.fc.llnwd.net/ustream playpath=ustream_llnw_live_1_112081513 swfUrl=http://static-cdn1.ustream.tv/swf/live/viewer.rsl:96.swf swfVfy=1 live=1: FLV 2012-08-29 18:13:56,236 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file rtmp://ustream.fc.llnwd.net/ustream playpath=ustream_llnw_live_1_112081513 swfUrl=http://static-cdn1.ustream.tv/swf/live/viewer.rsl:96.swf swfVfy=1 live=1: FLV 2012-08-29 18:13:56,236 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file rtmp://ustream.fc.llnwd.net/ustream playpath=ustream_llnw_live_1_112081513 swfUrl=http://static-cdn1.ustream.tv/swf/live/viewer.rsl:96.swf swfVfy=1 live=1: FLV 2012-08-29 18:13:56,236 DEBUG [ResourceDeliveryProcessor] Stream entity has length: 50000000000 2012-08-29 18:13:56,236 DEBUG [ResourceDeliveryProcessor] Sending file back 2012-08-29 18:18:33,002 DEBUG [NonClosingPipedInputStream] Closing piped input stream and closing related feeder process 2012-08-29 18:18:33,002 DEBUG [ProcessExecutor] Stopping external process: Thread[Thread-1190,5,main] 2012-08-29 18:18:33,002 DEBUG [ProcessUtils] Killing the Windows process: 8700 2012-08-29 18:18:33,030 DEBUG [TranscodingJobListener] Transcoding finished; successful: true

Fri Aug 31, 2012 4:33 pm by jhb50

More testing:

As the extract below shows, replay and pause- restart will only work after Serviio closes the piped input stream but the delays in doing this are excessive. However if I request a medium playback while playing an original, subsequent original plays and pause-restarts close their piped input streams within seconds.

Code:: Flashplayer - Original - livestreams 29.27 ffmpeg - plays 30.03 exists - cannot find 30.39 exists - cannot find 30.56 exists - cannot find 31.58 Closing piped input stream 2:32 32.29 ffmpeg - plays 33.55 Closing piped input stream 1:26 34.56 ffmpeg - plays 36.37 Closing piped input stream 1:41 37.30 ffmpeg - plays 44:27 Closing piped input stream 6:57 52.37 ffmpeg - plays & request medium 52.56 ffmpeg medium - plays 53.11 ffmpeg - plays & request medium 53.25 ffmpeg medium - plays 53.44 ffmpeg - plays 53:55 Closing piped input stream :11 54.02 ffmpeg - plays 54.07 Closing piped input stream :05 54.17 ffmpeg - plays 54.26 Closing piped input stream :09 54:32 ffmpeg - plays 54.36 Closing piped input stream :04 54:44 ffmpeg - plays 54.56 Closing piped input stream :12 55:02 ffmpeg - plays 55.13 Closing piped input stream :12 55.27 Closing piped input stream 55.41 Closing piped input stream 55.59 Closing piped input stream

This extract when medium default is used, shows no attempt to reuse the existing transcode, but rather it is closed and a new transcode started. Only one instance of pipe closing occurs.

And just to confirm, these results are from using mediabrowser under Android browser rather than Windows which rules out the browser or flash as the source of the problem.

Code:: Flashplayer - Medium - livestreams 55:45 ffmpeg medium - plays 56:10 Stopping previous transcoding job of file 56:10 ffmpeg medium - plays 56:10 Closing piped input stream 56:33 ffmpeg medium - plays 56.51 Stopping previous transcoding job of file 56:52 ffmpeg medium 57:01 Closing piped input stream 57:32 Closing piped input stream 57:48 Closing piped input stream

Thu Sep 06, 2012 8:21 pm by zip

is this happening with live streams only?

Thu Sep 06, 2012 9:49 pm by jhb50

zip wrote:is this happening with live streams only?

yes, live streams only (http & rtmp) with mediabrowser set to orig quality. Seems the problem is a delay in closing the piped transcoded stream.Want a ticket?

Sun Sep 09, 2012 1:51 pm by jhb50

Opened a ticket https://bitbucket.org/xnejp03/serviio/i ... y-or-pause

Sun Sep 09, 2012 11:12 pm by zip

thanks

MediaBrower cannot replay LiveStreams

MediaBrower cannot replay LiveStreams

Re: LiveStream Transcoded file gets lost

Re: LiveStream Transcoded file gets lost

Re: LiveStream Transcoded file gets lost

Re: LiveStream Transcoded file gets lost

Re: LiveStream Transcoded file gets lost

Re: LiveStream Transcoded file gets lost

Re: MediaBrower cannot replay http: LiveStreams

Re: MediaBrower cannot replay http: LiveStreams

Re: MediaBrower cannot replay LiveStreams

Re: MediaBrower cannot replay http: LiveStreams

Re: MediaBrower cannot replay http: LiveStreams

Re: MediaBrower cannot replay http: LiveStreams

Re: MediaBrower cannot replay LiveStreams

Re: MediaBrower cannot replay LiveStreams

Who is online