2017-02-09 21:12:25,583 DEBUG [VideoDAOImpl] Reading a Video (id = 20278) 2017-02-09 21:12:25,634 DEBUG [SearchManager] Committing search index 2017-02-09 21:12:25,683 DEBUG [GenreDAOImpl] Reading a Genre (id = 1) 2017-02-09 21:12:25,707 DEBUG [PersonDAOImpl] Retrieving list of grouped persons for MediaItem 20278 2017-02-09 21:12:25,914 DEBUG [VideoDAOImpl] Reading a Video (id = 20278) 2017-02-09 21:12:25,916 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 20278 and profile Flash player 2017-02-09 21:12:25,921 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file "Testvideo.mkv": FLV 2017-02-09 21:12:25,921 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file "Testvideo.mkv": FLV 2017-02-09 21:12:25,921 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file "Testvideo.mkv": FLV 2017-02-09 21:12:25,924 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 19782) 2017-02-09 21:12:26,047 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it 2017-02-09 21:12:26,306 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth1 (eth1) and address 1.1.1.1, timeout = 0 2017-02-09 21:12:26,308 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 696a562e-885e-3c06-9d5a-7c7e4a973d25 2017-02-09 21:12:27,777 DEBUG [CDSRetrieveMediaServerResource] GET http://serviio-srv:23424/cds/resource/20278/MEDIA_ITEM/FLV-0/ORIGINAL?profile=flv_player HTTP/1.1, headers = [host: serviio-srv:23424,user-agent: Mozilla/5.0 (X11; Linux x86_64; rv:45.0) Gecko/20100101 Firefox/45.0,accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8,accept-language: en-US,en;q=0.5,accept-encoding: gzip, deflate,referer: http://serviio-srv:23424/mediabrowser/assets/flowplayer-3.2.16.swf,cookie: DW68700bfd16c2027de7de74a5a8202a6f=c3Zlbg%3D%3D%7C1%7CQRTwzCKMgFUTB9SKAbOnVifSk2uLu%2FUMU3PEa8h7JGg%3D; DOKU_PREFS=list%23thumbs%23link%233%23align%232%23size%234; DokuWiki=tjusiv67af9r3c7vhs988ea867; SL_lng_from=auto; SL_lng_to=es; SLloc=false,connection: keep-alive] 2017-02-09 21:12:27,782 DEBUG [CDSRetrieveMediaServerResource] Creating client with id '1.1.1.10' and name 'Remote client' 2017-02-09 21:12:27,782 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'Identifier=1.1.1.10, Profile=Flash player, Name=Remote client' 2017-02-09 21:12:27,783 DEBUG [ResourceDeliveryProcessor] Request for resource 20278 and type 'MEDIA_ITEM' received 2017-02-09 21:12:27,784 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 20278 (local) 2017-02-09 21:12:27,784 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 20278) 2017-02-09 21:12:27,795 DEBUG [VideoDAOImpl] Reading a Video (id = 20278) 2017-02-09 21:12:27,797 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 20278, format FLV and profile Flash player 2017-02-09 21:12:27,804 DEBUG [AbstractAVTranscodingDeliveryEngine] Getting media info for transcoded version of file Testvideo.mkv 2017-02-09 21:12:27,804 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file "Testvideo.mkv": FLV 2017-02-09 21:12:27,804 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file "Testvideo.mkv": FLV 2017-02-09 21:12:27,804 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file "Testvideo.mkv": FLV 2017-02-09 21:12:27,806 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 20278 (local) 2017-02-09 21:12:27,806 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 20278) 2017-02-09 21:12:27,807 DEBUG [VideoDAOImpl] Reading a Video (id = 20278) 2017-02-09 21:12:27,808 DEBUG [VideoDeliveryEngine] Delivering item '20278' for client 'Identifier=1.1.1.10, Profile=Flash player, Name=Remote client' 2017-02-09 21:12:27,808 DEBUG [VideoDeliveryEngine] Delivering file 'Testvideo.mkv' using transcoding 2017-02-09 21:12:27,817 DEBUG [AbstractAVTranscodingDeliveryEngine] No suitable transcoding job exists yet, start one for client 'Identifier=1.1.1.10, Profile=Flash player, Name=Remote client' 2017-02-09 21:12:27,826 DEBUG [FFMPEGWrapper] Invoking FFmpeg to transcode video file: /multimedia/video/Testvideo.mkv 2017-02-09 21:12:27,827 DEBUG [ProcessExecutor] Starting /usr/local/bin/ffmpeg -fflags +genpts -threads 0 -i /multimedia/video/Testvideo.mkv -y -threads 0 -c:v copy -c:a:0 libmp3lame -b:a:0 192k -ar:a:0 44100 -ac:a:0 2 -map 0:0 -map 0:1 -sn -f flv /tmp/Serviio/transcoding-temp-c589ee5f1b7d1b87e8b6c6988b483df1.stf 2017-02-09 21:12:28,124 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:30 (advertisement duration is 300 sec.) 2017-02-09 21:12:28,127 DEBUG [MediaItemDAOImpl] Getting counts of media items by type 2017-02-09 21:12:28,328 DEBUG [FileBasedTranscodingDeliveryStrategy] Sending transcoding stream 2017-02-09 21:12:28,336 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file "Testvideo.mkv": FLV 2017-02-09 21:12:28,336 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file "Testvideo.mkv": FLV 2017-02-09 21:12:28,336 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file "Testvideo.mkv": FLV 2017-02-09 21:12:28,409 INFO [PlaybackProgressUpdater] Started playback progress updater 2017-02-09 21:12:28,441 INFO [PlaybackEventsManager] Playback of media item 20278 has started at 0% on Identifier=1.1.1.10, Profile=Flash player, Name=Remote client 2017-02-09 21:12:28,452 DEBUG [GETMethodProcessor] Entity will be consumed till the end 2017-02-09 21:12:28,452 DEBUG [GETMethodProcessor] Stream entity has length: -1 2017-02-09 21:12:33,122 DEBUG [MediaItemDAOImpl] Getting counts of media items by type 2017-02-09 21:12:38,127 DEBUG [MediaItemDAOImpl] Getting counts of media items by type 2017-02-09 21:12:43,126 DEBUG [MediaItemDAOImpl] Getting counts of media items by type 2017-02-09 21:12:48,139 DEBUG [MediaItemDAOImpl] Getting counts of media items by type 2017-02-09 21:12:50,243 DEBUG [FeedUpdaterWorker] Checking for new and expired online resources 2017-02-09 21:12:50,243 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories 2017-02-09 21:12:50,245 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 1) 2017-02-09 21:12:50,246 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 2) 2017-02-09 21:12:50,247 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 3) 2017-02-09 21:12:50,248 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 4) 2017-02-09 21:12:50,248 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 5) 2017-02-09 21:12:50,249 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 6) 2017-02-09 21:12:50,267 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 7) 2017-02-09 21:12:50,268 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 8) 2017-02-09 21:12:50,269 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 9) 2017-02-09 21:12:50,269 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 10) 2017-02-09 21:12:50,270 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 11) 2017-02-09 21:12:50,271 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 12) 2017-02-09 21:12:50,272 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 13) 2017-02-09 21:12:50,272 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 14) 2017-02-09 21:12:50,273 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 15) 2017-02-09 21:12:50,274 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 16) 2017-02-09 21:12:50,275 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 17) 2017-02-09 21:12:50,275 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 18) 2017-02-09 21:12:50,276 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 19) 2017-02-09 21:12:50,277 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 20) 2017-02-09 21:12:50,286 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 21) 2017-02-09 21:12:50,287 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 22) 2017-02-09 21:12:50,288 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 23) 2017-02-09 21:12:50,289 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 24) 2017-02-09 21:12:50,289 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 25) 2017-02-09 21:12:50,291 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 26) 2017-02-09 21:12:50,292 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 27) 2017-02-09 21:12:50,292 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 28) 2017-02-09 21:12:50,293 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 29) 2017-02-09 21:12:50,294 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 30) 2017-02-09 21:12:50,295 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 31) 2017-02-09 21:12:50,296 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 32) 2017-02-09 21:12:50,296 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 33) 2017-02-09 21:12:53,125 DEBUG [MediaItemDAOImpl] Getting counts of media items by type 2017-02-09 21:12:55,635 DEBUG [SearchManager] Committing search index 2017-02-09 21:12:58,126 DEBUG [MediaItemDAOImpl] Getting counts of media items by type 2017-02-09 21:12:58,400 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth1 (eth1) and address 1.1.1.1, timeout = 0 2017-02-09 21:12:58,400 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 696a562e-885e-3c06-9d5a-7c7e4a973d25 2017-02-09 21:13:00,092 DEBUG [ServiioHttpService] Incoming request from /1.1.1.3:4906: GET /deviceDescription/696a562e-885e-3c06-9d5a-7c7e4a973d25 HTTP/1.1, headers = [Host: 1.1.1.1:8895,Connection: close,User-Agent: Mozilla/4.0 (compatible),Accept: */*]] 2017-02-09 21:13:00,092 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 1.1.1.3 2017-02-09 21:13:00,093 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 696a562e-885e-3c06-9d5a-7c7e4a973d25 from 1.1.1.3 (headers = [Host: 1.1.1.1:8895,Connection: close,User-Agent: Mozilla/4.0 (compatible),Accept: */*]) 2017-02-09 21:13:00,093 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 1.1.1.3 2017-02-09 21:13:00,093 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Host: 1.1.1.1:8895,Connection: close,User-Agent: Mozilla/4.0 (compatible),Accept: */*] 2017-02-09 21:13:00,093 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 1.1.1.3 2017-02-09 21:13:00,103 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Generic DLNA profile' 2017-02-09 21:13:00,164 DEBUG [ServiioHttpService] Incoming request from /1.1.1.3:4907: SUBSCRIBE /serviceEventing/ContentDirectory HTTP/1.1, headers = [Host: 1.1.1.1:8895,TIMEOUT: Second-300,NT: upnp:event,CALLBACK: ,CONNECTION: close]] 2017-02-09 21:13:00,164 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 1.1.1.3 2017-02-09 21:13:00,164 DEBUG [ServiceEventSubscriptionRequestHandler] ServiceEvent subscription request received for service ContentDirectory 2017-02-09 21:13:00,165 DEBUG [ServiceEventSubscriptionRequestHandler] Event subscription registered (uuid=1fbc3de4-def5-46ca-9492-b8a86c528ad0) for service urn:upnp-org:serviceId:ContentDirectory with duration 300 reporting to http://1.1.1.3:6666/ 2017-02-09 21:13:00,210 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:38 (advertisement duration is 300 sec.) 2017-02-09 21:13:00,625 DEBUG [EventDispatcher] Sending event notification #3 for subscription 1cd09b96-32ac-4a33-b30c-be0408b337d9 to endpoint http://1.1.1.4:1900/ 2017-02-09 21:13:00,625 DEBUG [RequestExecutor] Sending HTTP request to 1.1.1.4:1900 2017-02-09 21:13:00,630 DEBUG [EventDispatcher] Event notification sent and received successfully 2017-02-09 21:13:00,631 DEBUG [EventDispatcher] Sending event notification #0 for subscription 1fbc3de4-def5-46ca-9492-b8a86c528ad0 to endpoint http://1.1.1.3:6666/ 2017-02-09 21:13:00,631 DEBUG [RequestExecutor] Sending HTTP request to 1.1.1.3:6666 2017-02-09 21:13:00,639 DEBUG [EventDispatcher] Event notification sent and received successfully 2017-02-09 21:13:03,125 DEBUG [MediaItemDAOImpl] Getting counts of media items by type 2017-02-09 21:13:05,096 DEBUG [ServiioHttpService] Incoming request from /1.1.1.3:4910: POST /serviceControl HTTP/1.1, headers = [Host: 1.1.1.1:8895,SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#GetSearchCapabilities",USER-AGENT: KnOS/3.2 bridgeCo-DMP/3.0 DLNADOC/1.50 INTEL_NMPR/2.0,CONNECTION: close,CONTENT-TYPE: text/xml; charset="utf-8",Content-Length: 291]] 2017-02-09 21:13:05,096 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 1.1.1.3 2017-02-09 21:13:05,097 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSearchCapabilities"' from 1.1.1.3 2017-02-09 21:13:05,097 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 1.1.1.3 2017-02-09 21:13:05,097 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 1.1.1.3 2017-02-09 21:13:05,107 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message 2017-02-09 21:13:05,128 DEBUG [ServiioHttpService] Incoming request from /1.1.1.3:4911: POST /serviceControl HTTP/1.1, headers = [Host: 1.1.1.1:8895,SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities",USER-AGENT: KnOS/3.2 bridgeCo-DMP/3.0 DLNADOC/1.50 INTEL_NMPR/2.0,CONNECTION: close,CONTENT-TYPE: text/xml; charset="utf-8",Content-Length: 289]] 2017-02-09 21:13:05,128 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 1.1.1.3 2017-02-09 21:13:05,128 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from 1.1.1.3 2017-02-09 21:13:05,129 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 1.1.1.3 2017-02-09 21:13:05,129 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 1.1.1.3 2017-02-09 21:13:05,152 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message 2017-02-09 21:13:08,129 DEBUG [MediaItemDAOImpl] Getting counts of media items by type 2017-02-09 21:13:13,125 DEBUG [MediaItemDAOImpl] Getting counts of media items by type 2017-02-09 21:13:18,125 DEBUG [MediaItemDAOImpl] Getting counts of media items by type 2017-02-09 21:13:23,126 DEBUG [MediaItemDAOImpl] Getting counts of media items by type 2017-02-09 21:13:25,636 DEBUG [SearchManager] Committing search index 2017-02-09 21:13:28,131 DEBUG [MediaItemDAOImpl] Getting counts of media items by type 2017-02-09 21:13:32,338 WARN [ProcessExecutor] Process /usr/local/bin/ffmpeg has a return code of 1! This is a possible error. Detailed output follows. frame=16216 fps=253 q=-1.0 size= 763118kB time=00:11:16.31 bitrate=9243.5kbits/s speed=10.6x av_interleaved_write_frame(): No space left on device Error writing trailer of /tmp/Serviio/transcoding-temp-c589ee5f1b7d1b87e8b6c6988b483df1.stf: No space left on deviceframe=16277 fps=253 q=-1.0 Lsize= 766200kB time=00:11:18.97 bitrate=9244.4kbits/s speed=10.6x video:749568kB audio:15914kB subtitle:0kB other streams:0kB global headers:0kB muxing overhead: 0.093777% Conversion failed! 2017-02-09 21:13:32,338 DEBUG [TranscodingJobListener] Transcoding finished; successful: false