Heyho, nachdem ich lange den PS3 Media Server verwendet habe, um von meinem Windows Rechner auf meine XBOX 360 zu streamen, der zwar meist gut funktioniert hat, aber dann ab und zu doch wieder nich so richtig und ich es so langsam satt hatte, mich damit zu beschäftigen, hab ich nach Alternativen gesucht und Plex gefunden. Nach der Einrichtung hab ich mich erstmal gefreut, da ich tatsächlch das Abspielen konnte, was der PS3 Media Server nicht hinbekommen hatte. Aber nachdem ich dann andere Videos ausprobiert hatte, merkte ich, dass er bei vielen einfach so die Wiedergabe abbricht.
Nungut, da das funktionierende Video nicht HD war und das abgebrochene 720p, könnte man jetzt meinen, dass es an der Auflösung liegt, also das z.B. das Netzwerk oder die CPU nicht damit klar kommt...ABER ein anderes 720p Video konnte er wiederum fast komplett abspielen kann, während das andere bereits nach wenigen Sekunden abbrach.
Ebenfalls scheint es auch nicht am Container zu liegen, da das abgebrochene Video interessanterweise ein mp4 file war und das größtenteils laufende video ein mkv file.
Man würde ja normalerweise eher vermuten, dass das mkv file probleme machen würde
An Audio Codec Problemen, wie man sie oft mit AC-3 oder FLAC hat liegt es denke ich auch nicht, da sowohl das abbrechende, als auch das laufende Video AAC Sound hatten.
Es liegt auch nicht an den Untertitelnspuren, da das laufende Video auch Untertitel hatte, die auch korrekt angezeigt wurden und das abbrechende Video keine Untertitel.
Die XBOX ist zwar über W-LAN mit dem Netzerk verbunden, aber ich habe es auch verkabelt probiert, mit dem selben Ergebnis.
Ich habe auch schon die verschiedenen Transcoder Einstellungen durch (Geschwindigkeit, Qualität, CPU auslasten), genauso wie die Streming einstellungen (720p 3Mbp/s, 480p 1.5Mbp/s), wobei diese ja sowieso nur für den Zugriff über das Web Interface zu sein scheinen.
Ich habe mir nun also mal den Server log angeguckt, den ich unten mal anfüge.
Was mir persönlich schonmal auffällt sind zwei Dinge.
1. Die Zeile * videoResolution => 1920x1080
Verstehe ich jetzt nicht, da das Video eine Auflösung von 1280x720 hat.
Ist damit vielleicht die Auflösung gemeint, in die das Video umgewandelt wird?
Fände ich sehr merkwürdig.
2. Das wichtigere, da das vermutlich schon die Ursache für das Abbrechen ist:
[Transcoder] [wmv2 @ 03583960] rc buffer underflow
[Transcoder] [wmv2 @ 03583960] max bitrate possibly too small or try trellis with large lmax or increase qmax
Ich habe im Zusammenhang mit Plex danach gegoogelt, jedoch nichts dazu gefunden, was auch der Grund dafür ist, dass ich mich nun ans Forum wende.
Nungut, ein Buffer Underflow erklärt ja im Grunde schon, wieso die Wiedergabe abgebrochen, die Frage ist ja nur noch, was ihn verursacht hat.
Anscheinend kommt der Transcoder ja nicht hinterher, was ich nicht verstehe, da er wiederum das andere Video fast komplett abspielen konnte.
Ein 3GHz 4 Kern Prozessor und 8GB RAM sollten ja auch absolut ausreichend sein, bei 1080p könnte ichs noch verstehen, aber 720p is locker machbar.
Die zweite zeile verrät mir ja schon fast eine Lösung des problems, das Problem hier ist nur, dass ich die Transcode Parameter ja nicht ohne weiteres verändern kann, weder über die einstellungen noch über registry einträge, ich weiß nicht ob und wie diese überhaupt verändern werden können, beim PS3 Media Server konnte man diese beim MEncoder ja noch ersichtlich nach belieben verändern, fände es nicht sehr günstig, wenn man das hier nicht tun kann.
Nungut, mehr gibts erstmal nich zu sagen, vor dem Log schreibe ich noch kurz die Mediainfo der beiden erwähnten videos auf.
Vielen Dank schonmal
Nach wenigen Sekunden abbrechendes Video:
Format : MPEG-4 Format_Profile : Base Media CodecID : isom FileSize/String : 285 MiB Duration/String : 24mn 11s OverallBitRate_Mode/String : Variable OverallBitRate/String : 1 646 Kbps Encoded_Date : UTC 2012-12-02 20:20:06 Tagged_Date : UTC 2012-12-02 20:20:06Video
ID/String : 1
Format : AVC
Format/Info : Advanced Video Codec
Format_Profile : High@L5.0
Format_Settings_CABAC/String : Yes
Format_Settings_RefFrames/String : 16 frames
CodecID : avc1
CodecID/Info : Advanced Video Coding
Duration/String : 24mn 10s
BitRate/String : 1 515 Kbps
BitRate_Maximum/String : 8 580 Kbps
Width/String : 1 280 pixels
Height/String : 720 pixels
DisplayAspectRatio/String : 16:9
FrameRate_Mode/String : Constant
FrameRate/String : 23.976 fps
ColorSpace : YUV
ChromaSubsampling : 4:2:0
BitDepth/String : 8 bits
ScanType/String : Progressive
Bits-(Pixel*Frame) : 0.069
StreamSize/String : 262 MiB (92%)
Encoded_Library/String : x264 core 129 r2230 1cffe9f
Encoded_Library_Settings : cabac=1 / ref=16 / deblock=1:0:0 / analyse=0x3:0x133 / me=umh / subme=10 / psy=1 / psy_rd=1.00:0.00 / mixed_ref=1 / me_range=24 / chroma_me=1 / trellis=2 / 8x8dct=1 / cqm=0 / deadzone=21,11 / fast_pskip=1 / chroma_qp_offset=-2 / threads=12 / lookahead_threads=2 / sliced_threads=0 / nr=0 / decimate=1 / interlaced=0 / bluray_compat=0 / constrained_intra=0 / bframes=8 / b_pyramid=2 / b_adapt=2 / b_bias=0 / direct=3 / weightb=1 / open_gop=0 / weightp=2 / keyint=240 / keyint_min=23 / scenecut=40 / intra_refresh=0 / rc_lookahead=60 / rc=crf / mbtree=1 / crf=18.0 / qcomp=0.60 / qpmin=10 / qpmax=51 / qpstep=4 / ip_ratio=1.40 / aq=1:1.00
Encoded_Date : UTC 2012-12-02 20:20:06
Tagged_Date : UTC 2012-12-02 20:20:08Audio
ID/String : 2
Format : AAC
Format/Info : Advanced Audio Codec
Format_Profile : HE-AAC / LC
CodecID : 40
Duration/String : 24mn 11s
BitRate_Mode/String : Variable
BitRate/String : 128 Kbps
BitRate_Maximum/String : 156 Kbps
Channel(s)/String : 2 channels
ChannelPositions : Front: L R
SamplingRate/String : 44.1 KHz / 22.05 KHz
Compression_Mode/String : Lossy
StreamSize/String : 22.1 MiB (8%)
Encoded_Date : UTC 2012-12-02 20:20:08
Tagged_Date : UTC 2012-12-02 20:20:08
Fast komplett durchlaufendes Video:
Format : Matroska
Format version : Version 2
File size : 506 MiB
Duration : 24mn 11s
Overall bit rate : 2 922 Kbps
Encoded date : UTC 2012-05-27 22:06:52
Writing application : mkvmerge v5.5.0 ('Healer') built on Apr 6 2012 21:43:24
Writing library : libebml v1.2.3 + libmatroska v1.3.0
Attachements : Marker SD.ttf / HONYA-JI_Regular.ttf / Kautiva_Type-K_Regular.ttf / Agent _C.ttf / AnkeSans.ttf / CAC_Moose.ttf / fennrg__.ttf / HANDAB__.TTF / Josschrift_Bold.ttf / lastninja.ttf / mcgarg__.ttf / Timeless.ttf / !PaulMaul.ttf / Dupree.ttf / Miu.ttf / mona.ttf / ninifont-regular.otf / Qlassik_TB.ttf / SchulVokalDotless.ttf / STRIPPE.TTF / VAGRoundedStd-Light.otf / Andrew.ttf / CHUBBY.TTF / Kozuka_Gothic_Pro_M_Regular.otf
Video
ID : 1
Format : AVC
Format/Info : Advanced Video Codec
Format profile : High@L4.0
Format settings, CABAC : Yes
Format settings, ReFrames : 8 frames
Muxing mode : Header stripping
Codec ID : V_MPEG4/ISO/AVC
Duration : 24mn 11s
Nominal bit rate : 2 500 Kbps
Width : 1 280 pixels
Height : 720 pixels
Display aspect ratio : 16:9
Frame rate mode : Constant
Frame rate : 23.976 fps
Color space : YUV
Chroma subsampling : 4:2:0
Bit depth : 8 bits
Scan type : Progressive
Bits/(Pixel*Frame) : 0.113
Title : K-On! - E08.Empfang der neuen Schüler! [720p][Yousei]
Writing library : x264 core 75 r1251M e553a4c
Encoding settings : cabac=1 / ref=8 / deblock=1:-2:-2 / analyse=0x3:0x133 / me=umh / subme=9 / psy=1 / psy_rd=0.6:0.0 / mixed_ref=1 / me_range=32 / chroma_me=1 / trellis=2 / 8x8dct=1 / cqm=0 / deadzone=21,11 / chroma_qp_offset=-2 / threads=2 / nr=0 / decimate=0 / mbaff=0 / bframes=8 / b_pyramid=1 / b_adapt=2 / b_bias=0 / direct=3 / wpredb=1 / keyint=250 / keyint_min=25 / scenecut=40 / rc=2pass / mbtree=0 / bitrate=2500 / ratetol=1.0 / qcomp=0.60 / qpmin=9 / qpmax=28 / qpstep=4 / cplxblur=20.0 / qblur=0.5 / ip_ratio=1.40 / pb_ratio=1.30 / aq=1:1.00
Default : Yes
Forced : No
Audio #1
ID : 2
Format : AAC
Format/Info : Advanced Audio Codec
Format profile : LC
Codec ID : A_AAC
Duration : 24mn 11s
Channel(s) : 2 channels
Channel positions : Front: L R
Sampling rate : 48.0 KHz
Compression mode : Lossy
Title : Audio (AAC LC) [Frostii]
Language : Japanese
Default : No
Forced : No
Audio #2
ID : 3
Format : AC-3
Format/Info : Audio Coding 3
Mode extension : CM (complete main)
Format settings, Endianness : Big
Muxing mode : Header stripping
Codec ID : A_AC3
Duration : 24mn 11s
Bit rate mode : Constant
Bit rate : 192 Kbps
Channel(s) : 2 channels
Channel positions : Front: L R
Sampling rate : 48.0 KHz
Bit depth : 16 bits
Compression mode : Lossy
Delay relative to video : 8ms
Stream size : 33.2 MiB (7%)
Title : Audio (AC3) [gerdhanse]
Language : German
Default : Yes
Forced : No
Text #1
ID : 4
Format : ASS
Codec ID : S_TEXT/ASS
Codec ID/Info : Advanced Sub Station Alpha
Compression mode : Lossless
Title : Subtitles (ASS) [Frostii]
Language : English
Default : No
Forced : No
Text #2
ID : 5
Format : ASS
Codec ID : S_TEXT/ASS
Codec ID/Info : Advanced Sub Station Alpha
Compression mode : Lossless
Title : German OP/ED/Titles (ASS) [gerdhanse]
Language : German
Default : Yes
Forced : No
Text #3
ID : 6
Format : ASS
Codec ID : S_TEXT/ASS
Codec ID/Info : Advanced Sub Station Alpha
Compression mode : Lossless
Title : German Subtitles (ASS) [Akiba]
Language : German
Default : No
Forced : No
Text #4
ID : 7
Format : VobSub
Muxing mode : zlib
Codec ID : S_VOBSUB
Codec ID/Info : The same subtitle format used on DVDs
Title : German OP/ED/Titles (SUP) [gerdhanse]
Language : German
Default : No
Forced : No
Text #5
ID : 8
Format : VobSub
Muxing mode : zlib
Codec ID : S_VOBSUB
Codec ID/Info : The same subtitle format used on DVDs
Title : German Subtitles (SUP) [gerdhanse]
Language : German
Default : No
Forced : No
Menu
00:00:00.000 : en:Prolog
00:01:23.000 : en:OP
00:02:52.798 : en:Intro
00:05:07.641 : en:Part 1
00:11:31.816 : en:Part 2
00:22:09.787 : en:ED
00:23:39.793 : en:Preview
Server Log, ab dem Abspielen des abbrechenden Videos:
Mar 18, 2015 17:55:21:773 [4588] DEBUG - Request: [127.0.0.1:64800] GET /library/sections (2 live) Mar 18, 2015 17:55:21:777 [4588] DEBUG - It took 0.000000 sec to serialize a list with 1 elements. Mar 18, 2015 17:55:24:111 [4588] DEBUG - Request: [127.0.0.1:64832] GET /library/sections/4 (2 live) Mar 18, 2015 17:55:24:113 [4588] DEBUG - It took 0.000000 sec to serialize a list with 19 elements. Mar 18, 2015 17:55:26:355 [6200] DEBUG - Request: [127.0.0.1:64835] GET /library/sections/4/folder (2 live) Mar 18, 2015 17:55:26:368 [6200] DEBUG - It took 8.000000 ms to retrieve 211 items. Mar 18, 2015 17:55:26:401 [6200] DEBUG - It took 0.002000 sec to serialize a list with 211 elements. Mar 18, 2015 17:55:35:626 [4588] DEBUG - Request: [127.0.0.1:64838] GET /library/sections/4/folder?parent=631 (2 live) Mar 18, 2015 17:55:35:626 [4588] DEBUG - * parent => 631 Mar 18, 2015 17:55:35:686 [4588] DEBUG - It took 53.000000 ms to retrieve 12 items. Mar 18, 2015 17:55:35:690 [4588] DEBUG - It took 0.003000 sec to serialize a list with 13 elements. Mar 18, 2015 17:55:35:699 [6200] DEBUG - Request: [127.0.0.1:64839] GET /library/metadata/5710,5711,5712,5713,5714,5715,5716,5717,5718,5719,5720,5721 (2 live) Mar 18, 2015 17:55:35:730 [6200] DEBUG - It took 0.009000 sec to serialize a list with 12 elements. Mar 18, 2015 17:55:42:113 [4588] DEBUG - Request: [127.0.0.1:64874] GET /library/metadata/5710 (3 live) Mar 18, 2015 17:55:42:134 [4588] DEBUG - It took 0.002000 sec to serialize a list with 1 elements. Mar 18, 2015 17:55:42:142 [6200] DEBUG - Request: [127.0.0.1:64877] GET /video/:/transcode/universal/start?audioBoost=100&directPlay=0&directStream=1&path=%2Flibrary%2Fmetadata%2F5710&session=98dedad677369a63a25ced27d1b47a90735b23b5&subtitleSize=100&videoQuality=74&videoResolution=1920x1080 (2 live) Mar 18, 2015 17:55:42:142 [6200] DEBUG - * audioBoost => 100 Mar 18, 2015 17:55:42:143 [6200] DEBUG - * directPlay => 0 Mar 18, 2015 17:55:42:143 [6200] DEBUG - * directStream => 1 Mar 18, 2015 17:55:42:143 [6200] DEBUG - * path => /library/metadata/5710 Mar 18, 2015 17:55:42:143 [6200] DEBUG - * session => 98dedad677369a63a25ced27d1b47a90735b23b5 Mar 18, 2015 17:55:42:144 [6200] DEBUG - * subtitleSize => 100 Mar 18, 2015 17:55:42:144 [6200] DEBUG - * videoQuality => 74 Mar 18, 2015 17:55:42:144 [6200] DEBUG - * videoResolution => 1920x1080 Mar 18, 2015 17:55:42:149 [6200] DEBUG - Using profile Xbox 360 Mar 18, 2015 17:55:42:150 [6200] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/5710 Mar 18, 2015 17:55:42:171 [6200] DEBUG - It took 0.002000 sec to serialize a list with 1 elements. Mar 18, 2015 17:55:42:172 [6200] DEBUG - MDE: analyzing media 0 Mar 18, 2015 17:55:42:173 [6200] DEBUG - MDE: analyzing video part 6106: mp4/h264/aac/ Mar 18, 2015 17:55:42:173 [6200] DEBUG - MDE: selected video transcode profile: http - asf/wmv2/wmav2/ Mar 18, 2015 17:55:42:173 [6200] DEBUG - MDE: selected media 0 Mar 18, 2015 17:55:42:174 [6200] DEBUG - [Universal] Using local file path instead of URL: G:\Girls und Panzer\01.mp4 Mar 18, 2015 17:55:42:174 [6200] DEBUG - [Universal] Transcoding to http/asf/wmv2/wmav2 Mar 18, 2015 17:55:42:175 [6200] DEBUG - Cleaning directory for session 98dedad677369a63a25ced27d1b47a90735b23b5 () Mar 18, 2015 17:55:42:175 [6200] DEBUG - Starting a transcode session 98dedad677369a63a25ced27d1b47a90735b23b5 at offset -1.0 (stopped=1) Mar 18, 2015 17:55:42:178 [6200] DEBUG - Obtained video bitrate of 1515KBps from media analysis. Mar 18, 2015 17:55:42:179 [6200] DEBUG - Scaled up video bitrate to 2464Kbps based on 150% fudge factor. Mar 18, 2015 17:55:42:180 [6200] DEBUG - Job running: D:\Programme\Plex\Plex Media Server\PlexNewTranscoder.exe -i "G:\Girls und Panzer\01.mp4" "-y" "-nostats" -f "asf" -loglevel "quiet" -loglevel_plex "error" -map_metadata "-1" -progressurl "http://127.0.0.1:32400/video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress" -vcodec "wmv2" -qscale:v "9" -pix_fmt "yuv420p" -maxrate "2464k" -bufsize "4928k" -x264opts ":subme=0:me_range=4:rc_lookahead=10:me=dia:no_chroma_me:8x8dct=0:partitions=none" "-sn" -filter_complex "[0:#0x01]scale=w=iw:h=ih:force_original_aspect_ratio=decrease[n0];[n0]copy[out]" -map "[out]" -acodec "wmav2" -ac "2" -map "0:#0x02" -ab "128k" "-" Mar 18, 2015 17:55:42:409 [4588] INFO - [Transcoder] Input #0, mov,mp4,m4a,3gp,3g2,mj2, from 'G:\Girls und Panzer\01.mp4': Mar 18, 2015 17:55:42:412 [4268] INFO - [Transcoder] Stream #0:0(und): Video: h264 (High) (avc1 / 0x31637661), yuv420p, 1280x720 [SAR 1:1 DAR 16:9], 1515 kb/s, 23.98 fps, 23.98 tbr, 96k tbn, 47.95 tbc (default) Mar 18, 2015 17:55:42:415 [4588] INFO - [Transcoder] Stream #0:1(und): Audio: aac (mp4a / 0x6134706D), 44100 Hz, stereo, fltp, 127 kb/s (default) Mar 18, 2015 17:55:42:420 [6200] DEBUG - [FFMPEG] Duration: 1451 Mar 18, 2015 17:55:42:420 [6200] DEBUG - Read line, and done: 1 Mar 18, 2015 17:55:42:420 [6200] DEBUG - Started session successfully: 98dedad677369a63a25ced27d1b47a90735b23b5 Mar 18, 2015 17:55:42:420 [5008] DEBUG - Started universal transcode output thread Mar 18, 2015 17:55:42:424 [5356] DEBUG - [TranscodeOutputStream] Input processing thread started at offset 0 for -1 bytes. Mar 18, 2015 17:55:42:426 [4268] INFO - [Transcoder] Output #0, asf, to 'pipe:': Mar 18, 2015 17:55:42:429 [4588] INFO - [Transcoder] Stream #0:0: Video: wmv2 (WMV2 / 0x32564D57), yuv420p, 1280x720 [SAR 1:1 DAR 16:9], q=2-31, 200 kb/s, 23.98 fps, 1k tbn, 23.98 tbc (default) Mar 18, 2015 17:55:42:431 [4268] INFO - [Transcoder] Stream #0:1: Audio: wmav2 (a[1][0][0] / 0x0161), 44100 Hz, stereo, fltp, 128 kb/s (default) Mar 18, 2015 17:55:42:433 [6200] DEBUG - Request: [127.0.0.1:64892] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?width=1280&height=720 (4 live) Mar 18, 2015 17:55:42:433 [6200] DEBUG - * width => 1280 Mar 18, 2015 17:55:42:433 [6200] DEBUG - * height => 720 Mar 18, 2015 17:55:42:434 [6200] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:55:42:957 [4268] DEBUG - Request: [127.0.0.1:64896] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=0.1&size=234409&speed=4.2&remaining=341 (4 live) Mar 18, 2015 17:55:42:957 [4268] DEBUG - * progress => 0.1 Mar 18, 2015 17:55:42:957 [4268] DEBUG - * size => 234409 Mar 18, 2015 17:55:42:957 [4268] DEBUG - * speed => 4.2 Mar 18, 2015 17:55:42:957 [4268] DEBUG - * remaining => 341 Mar 18, 2015 17:55:42:957 [4268] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:55:43:461 [6200] DEBUG - Request: [127.0.0.1:64899] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=0.3&size=621609&speed=4.0&remaining=349 (4 live) Mar 18, 2015 17:55:43:461 [6200] DEBUG - * progress => 0.3 Mar 18, 2015 17:55:43:461 [6200] DEBUG - * size => 621609 Mar 18, 2015 17:55:43:461 [6200] DEBUG - * speed => 4.0 Mar 18, 2015 17:55:43:461 [6200] DEBUG - * remaining => 349 Mar 18, 2015 17:55:43:462 [6200] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:55:43:964 [4588] DEBUG - Request: [127.0.0.1:64900] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=0.4&size=887209&speed=4.1&remaining=352 (4 live) Mar 18, 2015 17:55:43:965 [4588] DEBUG - * progress => 0.4 Mar 18, 2015 17:55:43:965 [4588] DEBUG - * size => 887209 Mar 18, 2015 17:55:43:965 [4588] DEBUG - * speed => 4.1 Mar 18, 2015 17:55:43:965 [4588] DEBUG - * remaining => 352 Mar 18, 2015 17:55:43:965 [4588] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:55:44:470 [6200] DEBUG - Request: [127.0.0.1:64901] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=0.6&size=1312809&speed=4.0&remaining=352 (4 live) Mar 18, 2015 17:55:44:470 [6200] DEBUG - * progress => 0.6 Mar 18, 2015 17:55:44:470 [6200] DEBUG - * size => 1312809 Mar 18, 2015 17:55:44:470 [6200] DEBUG - * speed => 4.0 Mar 18, 2015 17:55:44:470 [6200] DEBUG - * remaining => 352 Mar 18, 2015 17:55:44:471 [6200] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:55:44:977 [4588] DEBUG - Request: [127.0.0.1:64910] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=0.7&size=1661609&speed=4.1&remaining=353 (4 live) Mar 18, 2015 17:55:44:977 [4588] DEBUG - * progress => 0.7 Mar 18, 2015 17:55:44:978 [4588] DEBUG - * size => 1661609 Mar 18, 2015 17:55:44:978 [4588] DEBUG - * speed => 4.1 Mar 18, 2015 17:55:44:978 [4588] DEBUG - * remaining => 353 Mar 18, 2015 17:55:44:978 [4588] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:55:45:484 [6200] DEBUG - Request: [127.0.0.1:64911] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=0.8&size=2036009&speed=4.0&remaining=353 (4 live) Mar 18, 2015 17:55:45:484 [6200] DEBUG - * progress => 0.8 Mar 18, 2015 17:55:45:484 [6200] DEBUG - * size => 2036009 Mar 18, 2015 17:55:45:484 [6200] DEBUG - * speed => 4.0 Mar 18, 2015 17:55:45:484 [6200] DEBUG - * remaining => 353 Mar 18, 2015 17:55:45:485 [6200] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:55:45:991 [4268] DEBUG - Request: [127.0.0.1:64912] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=1.0&size=2384809&speed=4.1&remaining=352 (4 live) Mar 18, 2015 17:55:45:991 [4268] DEBUG - * progress => 1.0 Mar 18, 2015 17:55:45:992 [4268] DEBUG - * size => 2384809 Mar 18, 2015 17:55:45:992 [4268] DEBUG - * speed => 4.1 Mar 18, 2015 17:55:45:992 [4268] DEBUG - * remaining => 352 Mar 18, 2015 17:55:45:992 [4268] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:55:46:499 [6200] DEBUG - Request: [127.0.0.1:64913] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=1.1&size=2765609&speed=4.0&remaining=352 (4 live) Mar 18, 2015 17:55:46:499 [6200] DEBUG - * progress => 1.1 Mar 18, 2015 17:55:46:499 [6200] DEBUG - * size => 2765609 Mar 18, 2015 17:55:46:499 [6200] DEBUG - * speed => 4.0 Mar 18, 2015 17:55:46:499 [6200] DEBUG - * remaining => 352 Mar 18, 2015 17:55:46:500 [6200] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:55:46:970 [4588] DEBUG - Request: [127.0.0.1:64914] GET /servers (4 live) Mar 18, 2015 17:55:46:970 [4588] DEBUG - It took 0.000000 sec to serialize a list with 1 elements. Mar 18, 2015 17:55:46:975 [6200] DEBUG - Request: [192.168.2.77:64915] GET /library/sections (5 live) Mar 18, 2015 17:55:46:977 [6200] DEBUG - It took 0.000000 sec to serialize a list with 1 elements. Mar 18, 2015 17:55:46:980 [4268] DEBUG - Request: [192.168.2.77:64916] GET /channels/all (4 live) Mar 18, 2015 17:55:46:981 [4268] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:55:47:010 [4588] DEBUG - Request: [127.0.0.1:64917] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=1.2&size=3130409&speed=4.1&remaining=353 (4 live) Mar 18, 2015 17:55:47:010 [4588] DEBUG - * progress => 1.2 Mar 18, 2015 17:55:47:010 [4588] DEBUG - * size => 3130409 Mar 18, 2015 17:55:47:010 [4588] DEBUG - * speed => 4.1 Mar 18, 2015 17:55:47:010 [4588] DEBUG - * remaining => 353 Mar 18, 2015 17:55:47:010 [4588] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:55:47:516 [6200] DEBUG - Request: [127.0.0.1:64918] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=1.4&size=3562409&speed=4.0&remaining=356 (4 live) Mar 18, 2015 17:55:47:516 [6200] DEBUG - * progress => 1.4 Mar 18, 2015 17:55:47:516 [6200] DEBUG - * size => 3562409 Mar 18, 2015 17:55:47:516 [6200] DEBUG - * speed => 4.0 Mar 18, 2015 17:55:47:516 [6200] DEBUG - * remaining => 356 Mar 18, 2015 17:55:47:517 [6200] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:55:48:020 [4588] DEBUG - Request: [127.0.0.1:64919] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=1.5&size=3911209&speed=4.1&remaining=353 (4 live) Mar 18, 2015 17:55:48:020 [4588] DEBUG - * progress => 1.5 Mar 18, 2015 17:55:48:020 [4588] DEBUG - * size => 3911209 Mar 18, 2015 17:55:48:020 [4588] DEBUG - * speed => 4.1 Mar 18, 2015 17:55:48:020 [4588] DEBUG - * remaining => 353 Mar 18, 2015 17:55:48:021 [4588] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:55:48:527 [4268] DEBUG - Request: [127.0.0.1:64920] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=1.7&size=4276009&speed=4.0&remaining=349 (4 live) Mar 18, 2015 17:55:48:527 [4268] DEBUG - * progress => 1.7 Mar 18, 2015 17:55:48:527 [4268] DEBUG - * size => 4276009 Mar 18, 2015 17:55:48:527 [4268] DEBUG - * speed => 4.0 Mar 18, 2015 17:55:48:527 [4268] DEBUG - * remaining => 349 Mar 18, 2015 17:55:48:527 [4268] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:55:49:031 [4588] DEBUG - Request: [127.0.0.1:64921] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=1.8&size=4612009&speed=4.1&remaining=352 (4 live) Mar 18, 2015 17:55:49:031 [4588] DEBUG - * progress => 1.8 Mar 18, 2015 17:55:49:031 [4588] DEBUG - * size => 4612009 Mar 18, 2015 17:55:49:031 [4588] DEBUG - * speed => 4.1 Mar 18, 2015 17:55:49:031 [4588] DEBUG - * remaining => 352 Mar 18, 2015 17:55:49:032 [4588] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:55:49:523 [6200] DEBUG - Request: [127.0.0.1:64922] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=1.9&size=4935209&speed=3.9&remaining=356 (4 live) Mar 18, 2015 17:55:49:523 [6200] DEBUG - * progress => 1.9 Mar 18, 2015 17:55:49:523 [6200] DEBUG - * size => 4935209 Mar 18, 2015 17:55:49:523 [6200] DEBUG - * speed => 3.9 Mar 18, 2015 17:55:49:523 [6200] DEBUG - * remaining => 356 Mar 18, 2015 17:55:49:523 [6200] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:55:50:040 [4588] DEBUG - Request: [127.0.0.1:64923] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=2.1&size=5312809&speed=4.1&remaining=354 (4 live) Mar 18, 2015 17:55:50:040 [4588] DEBUG - * progress => 2.1 Mar 18, 2015 17:55:50:040 [4588] DEBUG - * size => 5312809 Mar 18, 2015 17:55:50:040 [4588] DEBUG - * speed => 4.1 Mar 18, 2015 17:55:50:040 [4588] DEBUG - * remaining => 354 Mar 18, 2015 17:55:50:041 [4588] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:55:50:542 [4268] DEBUG - Request: [127.0.0.1:64924] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=2.2&size=5776809&speed=3.8&remaining=358 (5 live) Mar 18, 2015 17:55:50:542 [4268] DEBUG - * progress => 2.2 Mar 18, 2015 17:55:50:542 [4268] DEBUG - * size => 5776809 Mar 18, 2015 17:55:50:542 [4268] DEBUG - * speed => 3.8 Mar 18, 2015 17:55:50:542 [4268] DEBUG - * remaining => 358 Mar 18, 2015 17:55:50:543 [4268] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:55:51:044 [4588] DEBUG - Request: [127.0.0.1:64925] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=2.3&size=6336809&speed=3.5&remaining=388 (4 live) Mar 18, 2015 17:55:51:044 [4588] DEBUG - * progress => 2.3 Mar 18, 2015 17:55:51:044 [4588] DEBUG - * size => 6336809 Mar 18, 2015 17:55:51:044 [4588] DEBUG - * speed => 3.5 Mar 18, 2015 17:55:51:044 [4588] DEBUG - * remaining => 388 Mar 18, 2015 17:55:51:045 [4588] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:55:51:547 [4268] DEBUG - Request: [127.0.0.1:64926] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=2.5&size=6839209&speed=3.6&remaining=396 (4 live) Mar 18, 2015 17:55:51:547 [4268] DEBUG - * progress => 2.5 Mar 18, 2015 17:55:51:547 [4268] DEBUG - * size => 6839209 Mar 18, 2015 17:55:51:547 [4268] DEBUG - * speed => 3.6 Mar 18, 2015 17:55:51:548 [4268] DEBUG - * remaining => 396 Mar 18, 2015 17:55:51:548 [4268] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:55:52:045 [6200] DEBUG - Request: [127.0.0.1:64927] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=2.6&size=7216809&speed=4.0&remaining=368 (4 live) Mar 18, 2015 17:55:52:045 [6200] DEBUG - * progress => 2.6 Mar 18, 2015 17:55:52:045 [6200] DEBUG - * size => 7216809 Mar 18, 2015 17:55:52:045 [6200] DEBUG - * speed => 4.0 Mar 18, 2015 17:55:52:045 [6200] DEBUG - * remaining => 368 Mar 18, 2015 17:55:52:046 [6200] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:55:52:561 [4268] DEBUG - Request: [127.0.0.1:64928] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=2.8&size=7639209&speed=4.0&remaining=348 (4 live) Mar 18, 2015 17:55:52:561 [4268] DEBUG - * progress => 2.8 Mar 18, 2015 17:55:52:561 [4268] DEBUG - * size => 7639209 Mar 18, 2015 17:55:52:561 [4268] DEBUG - * speed => 4.0 Mar 18, 2015 17:55:52:561 [4268] DEBUG - * remaining => 348 Mar 18, 2015 17:55:52:561 [4268] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:55:53:065 [6200] DEBUG - Request: [127.0.0.1:64929] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=2.9&size=8176809&speed=3.6&remaining=368 (4 live) Mar 18, 2015 17:55:53:065 [6200] DEBUG - * progress => 2.9 Mar 18, 2015 17:55:53:065 [6200] DEBUG - * size => 8176809 Mar 18, 2015 17:55:53:065 [6200] DEBUG - * speed => 3.6 Mar 18, 2015 17:55:53:065 [6200] DEBUG - * remaining => 368 Mar 18, 2015 17:55:53:066 [6200] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:55:53:569 [4268] DEBUG - Request: [127.0.0.1:64930] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=3.0&size=8608809&speed=3.7&remaining=383 (4 live) Mar 18, 2015 17:55:53:569 [4268] DEBUG - * progress => 3.0 Mar 18, 2015 17:55:53:570 [4268] DEBUG - * size => 8608809 Mar 18, 2015 17:55:53:570 [4268] DEBUG - * speed => 3.7 Mar 18, 2015 17:55:53:570 [4268] DEBUG - * remaining => 383 Mar 18, 2015 17:55:53:570 [4268] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:55:54:081 [6200] DEBUG - Request: [127.0.0.1:64931] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=3.1&size=9114409&speed=3.4&remaining=392 (4 live) Mar 18, 2015 17:55:54:081 [6200] DEBUG - * progress => 3.1 Mar 18, 2015 17:55:54:081 [6200] DEBUG - * size => 9114409 Mar 18, 2015 17:55:54:081 [6200] DEBUG - * speed => 3.4 Mar 18, 2015 17:55:54:081 [6200] DEBUG - * remaining => 392 Mar 18, 2015 17:55:54:082 [6200] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:55:54:569 [4268] DEBUG - Request: [127.0.0.1:64932] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=3.2&size=9607209&speed=3.3&remaining=414 (4 live) Mar 18, 2015 17:55:54:569 [4268] DEBUG - * progress => 3.2 Mar 18, 2015 17:55:54:569 [4268] DEBUG - * size => 9607209 Mar 18, 2015 17:55:54:569 [4268] DEBUG - * speed => 3.3 Mar 18, 2015 17:55:54:570 [4268] DEBUG - * remaining => 414 Mar 18, 2015 17:55:54:570 [4268] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:55:55:080 [6200] DEBUG - Request: [127.0.0.1:64933] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=3.4&size=10359209&speed=2.9&remaining=451 (4 live) Mar 18, 2015 17:55:55:080 [6200] DEBUG - * progress => 3.4 Mar 18, 2015 17:55:55:081 [6200] DEBUG - * size => 10359209 Mar 18, 2015 17:55:55:081 [6200] DEBUG - * speed => 2.9 Mar 18, 2015 17:55:55:081 [6200] DEBUG - * remaining => 451 Mar 18, 2015 17:55:55:081 [6200] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:55:55:966 [4268] DEBUG - Request: [127.0.0.1:64934] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=3.4&size=10816809&speed=1.0&remaining=921 (4 live) Mar 18, 2015 17:55:55:966 [4268] DEBUG - * progress => 3.4 Mar 18, 2015 17:55:55:966 [4268] DEBUG - * size => 10816809 Mar 18, 2015 17:55:55:966 [4268] DEBUG - * speed => 1.0 Mar 18, 2015 17:55:55:966 [4268] DEBUG - * remaining => 921 Mar 18, 2015 17:55:55:967 [4268] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:55:56:442 [4588] DEBUG - Request: [127.0.0.1:64935] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=3.4&size=10871209&speed=0.2&remaining=4650 (4 live) Mar 18, 2015 17:55:56:442 [4588] DEBUG - * progress => 3.4 Mar 18, 2015 17:55:56:442 [4588] DEBUG - * size => 10871209 Mar 18, 2015 17:55:56:442 [4588] DEBUG - * speed => 0.2 Mar 18, 2015 17:55:56:442 [4588] DEBUG - * remaining => 4650 Mar 18, 2015 17:55:56:443 [4588] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:55:56:914 [6200] DEBUG - Request: [127.0.0.1:64936] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=3.4&size=10906409&speed=0.2&remaining=8377 (4 live) Mar 18, 2015 17:55:56:914 [6200] DEBUG - * progress => 3.4 Mar 18, 2015 17:55:56:914 [6200] DEBUG - * size => 10906409 Mar 18, 2015 17:55:56:914 [6200] DEBUG - * speed => 0.2 Mar 18, 2015 17:55:56:914 [6200] DEBUG - * remaining => 8377 Mar 18, 2015 17:55:56:915 [6200] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:55:57:894 [4268] DEBUG - Request: [127.0.0.1:64953] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=3.4&size=10967209&speed=0.2&remaining=8097 (4 live) Mar 18, 2015 17:55:57:894 [4268] DEBUG - * progress => 3.4 Mar 18, 2015 17:55:57:894 [4268] DEBUG - * size => 10967209 Mar 18, 2015 17:55:57:894 [4268] DEBUG - * speed => 0.2 Mar 18, 2015 17:55:57:895 [4268] DEBUG - * remaining => 8097 Mar 18, 2015 17:55:57:895 [4268] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:55:59:026 [6200] ERROR - [Transcoder] [wmv2 @ 03583960] rc buffer underflow Mar 18, 2015 17:55:59:029 [4588] ERROR - [Transcoder] [wmv2 @ 03583960] max bitrate possibly too small or try trellis with large lmax or increase qmax Mar 18, 2015 17:55:59:031 [6200] DEBUG - Request: [127.0.0.1:64956] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=3.4&size=11031209&speed=0.1&remaining=8387 (4 live) Mar 18, 2015 17:55:59:031 [6200] DEBUG - * progress => 3.4 Mar 18, 2015 17:55:59:032 [6200] DEBUG - * size => 11031209 Mar 18, 2015 17:55:59:032 [6200] DEBUG - * speed => 0.1 Mar 18, 2015 17:55:59:032 [6200] DEBUG - * remaining => 8387 Mar 18, 2015 17:55:59:032 [6200] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:55:59:580 [4268] DEBUG - Request: [127.0.0.1:64973] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=3.4&size=11056809&speed=0.1&remaining=9380 (4 live) Mar 18, 2015 17:55:59:580 [4268] DEBUG - * progress => 3.4 Mar 18, 2015 17:55:59:580 [4268] DEBUG - * size => 11056809 Mar 18, 2015 17:55:59:580 [4268] DEBUG - * speed => 0.1 Mar 18, 2015 17:55:59:581 [4268] DEBUG - * remaining => 9380 Mar 18, 2015 17:55:59:581 [4268] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:56:00:118 [6200] DEBUG - Request: [127.0.0.1:64977] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=3.4&size=11085609&speed=0.2&remaining=9187 (5 live) Mar 18, 2015 17:56:00:118 [6200] DEBUG - * progress => 3.4 Mar 18, 2015 17:56:00:119 [6200] DEBUG - * size => 11085609 Mar 18, 2015 17:56:00:119 [6200] DEBUG - * speed => 0.2 Mar 18, 2015 17:56:00:119 [6200] DEBUG - * remaining => 9187 Mar 18, 2015 17:56:00:119 [6200] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:56:00:667 [4588] DEBUG - Request: [127.0.0.1:64978] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=3.4&size=11111209&speed=0.2&remaining=9128 (4 live) Mar 18, 2015 17:56:00:668 [4588] DEBUG - * progress => 3.4 Mar 18, 2015 17:56:00:668 [4588] DEBUG - * size => 11111209 Mar 18, 2015 17:56:00:668 [4588] DEBUG - * speed => 0.2 Mar 18, 2015 17:56:00:668 [4588] DEBUG - * remaining => 9128 Mar 18, 2015 17:56:00:668 [4588] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:56:01:206 [4268] DEBUG - Request: [127.0.0.1:64979] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=3.4&size=11136809&speed=0.2&remaining=9128 (4 live) Mar 18, 2015 17:56:01:206 [4268] DEBUG - * progress => 3.4 Mar 18, 2015 17:56:01:206 [4268] DEBUG - * size => 11136809 Mar 18, 2015 17:56:01:206 [4268] DEBUG - * speed => 0.2 Mar 18, 2015 17:56:01:206 [4268] DEBUG - * remaining => 9128 Mar 18, 2015 17:56:01:207 [4268] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:56:01:737 [6200] DEBUG - Request: [127.0.0.1:64980] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=3.4&size=11165609&speed=0.2&remaining=8993 (4 live) Mar 18, 2015 17:56:01:737 [6200] DEBUG - * progress => 3.4 Mar 18, 2015 17:56:01:737 [6200] DEBUG - * size => 11165609 Mar 18, 2015 17:56:01:737 [6200] DEBUG - * speed => 0.2 Mar 18, 2015 17:56:01:737 [6200] DEBUG - * remaining => 8993 Mar 18, 2015 17:56:01:738 [6200] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:56:02:086 [4268] ERROR - [Transcoder] [wmv2 @ 03583960] rc buffer underflow Mar 18, 2015 17:56:02:089 [6200] ERROR - [Transcoder] [wmv2 @ 03583960] max bitrate possibly too small or try trellis with large lmax or increase qmax Mar 18, 2015 17:56:02:485 [4268] ERROR - [Transcoder] [wmv2 @ 03583960] rc buffer underflow Mar 18, 2015 17:56:02:488 [4588] ERROR - [Transcoder] [wmv2 @ 03583960] max bitrate possibly too small or try trellis with large lmax or increase qmax Mar 18, 2015 17:56:02:490 [6200] DEBUG - Request: [127.0.0.1:64985] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=3.4&size=11210409&speed=0.1&remaining=10777 (4 live) Mar 18, 2015 17:56:02:490 [6200] DEBUG - * progress => 3.4 Mar 18, 2015 17:56:02:490 [6200] DEBUG - * size => 11210409 Mar 18, 2015 17:56:02:490 [6200] DEBUG - * speed => 0.1 Mar 18, 2015 17:56:02:490 [6200] DEBUG - * remaining => 10777 Mar 18, 2015 17:56:02:491 [6200] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:56:03:040 [4588] DEBUG - Request: [127.0.0.1:64986] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=3.4&size=11236009&speed=0.2&remaining=10891 (5 live) Mar 18, 2015 17:56:03:040 [4588] DEBUG - * progress => 3.4 Mar 18, 2015 17:56:03:040 [4588] DEBUG - * size => 11236009 Mar 18, 2015 17:56:03:041 [4588] DEBUG - * speed => 0.2 Mar 18, 2015 17:56:03:041 [4588] DEBUG - * remaining => 10891 Mar 18, 2015 17:56:03:041 [4588] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:56:03:188 [4268] DEBUG - Request: [127.0.0.1:64987] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=3.4&size=11242409&speed=0.1&remaining=13898 (4 live) Mar 18, 2015 17:56:03:188 [4268] DEBUG - * progress => 3.4 Mar 18, 2015 17:56:03:188 [4268] DEBUG - * size => 11242409 Mar 18, 2015 17:56:03:188 [4268] DEBUG - * speed => 0.1 Mar 18, 2015 17:56:03:188 [4268] DEBUG - * remaining => 13898 Mar 18, 2015 17:56:03:189 [4268] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:56:04:126 [4588] DEBUG - Request: [127.0.0.1:64988] PUT /video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress?progress=3.4&size=11290409&speed=0.2&remaining=12330 (4 live) Mar 18, 2015 17:56:04:126 [4588] DEBUG - * progress => 3.4 Mar 18, 2015 17:56:04:126 [4588] DEBUG - * size => 11290409 Mar 18, 2015 17:56:04:126 [4588] DEBUG - * speed => 0.2 Mar 18, 2015 17:56:04:126 [4588] DEBUG - * remaining => 12330 Mar 18, 2015 17:56:04:127 [4588] DEBUG - It took 0.000000 sec to serialize a list with 0 elements. Mar 18, 2015 17:56:04:555 [6200] DEBUG - Request: [127.0.0.1:64993] GET /video/:/transcode/universal/start?audioBoost=100&directPlay=0&directStream=1&offset=9&path=%2Flibrary%2Fmetadata%2F5710&seekOffset=8392489&session=98dedad677369a63a25ced27d1b47a90735b23b5&subtitleSize=100&videoQuality=74&videoResolution=1920x1080 (4 live) Mar 18, 2015 17:56:04:555 [6200] DEBUG - * audioBoost => 100 Mar 18, 2015 17:56:04:555 [6200] DEBUG - * directPlay => 0 Mar 18, 2015 17:56:04:555 [6200] DEBUG - * directStream => 1 Mar 18, 2015 17:56:04:555 [6200] DEBUG - * offset => 9 Mar 18, 2015 17:56:04:555 [6200] DEBUG - * path => /library/metadata/5710 Mar 18, 2015 17:56:04:555 [6200] DEBUG - * seekOffset => 8392489 Mar 18, 2015 17:56:04:555 [6200] DEBUG - * session => 98dedad677369a63a25ced27d1b47a90735b23b5 Mar 18, 2015 17:56:04:555 [6200] DEBUG - * subtitleSize => 100 Mar 18, 2015 17:56:04:555 [6200] DEBUG - * videoQuality => 74 Mar 18, 2015 17:56:04:555 [6200] DEBUG - * videoResolution => 1920x1080 Mar 18, 2015 17:56:04:556 [6200] DEBUG - Using profile Xbox 360 Mar 18, 2015 17:56:04:556 [6200] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/5710 Mar 18, 2015 17:56:04:562 [6200] DEBUG - It took 0.001000 sec to serialize a list with 1 elements. Mar 18, 2015 17:56:04:562 [6200] DEBUG - MDE: analyzing media 0 Mar 18, 2015 17:56:04:562 [6200] DEBUG - MDE: analyzing video part 6106: mp4/h264/aac/ Mar 18, 2015 17:56:04:562 [6200] DEBUG - MDE: selected video transcode profile: http - asf/wmv2/wmav2/ Mar 18, 2015 17:56:04:562 [6200] DEBUG - MDE: selected media 0 Mar 18, 2015 17:56:04:563 [6200] DEBUG - [Universal] Using local file path instead of URL: G:\Girls und Panzer\01.mp4 Mar 18, 2015 17:56:04:563 [6200] DEBUG - [Universal] Transcoding to http/asf/wmv2/wmav2 Mar 18, 2015 17:56:04:563 [6200] DEBUG - Destroying existing session, reference count = 7 Mar 18, 2015 17:56:04:563 [6200] DEBUG - Cleaning directory for session 98dedad677369a63a25ced27d1b47a90735b23b5 () Mar 18, 2015 17:56:04:563 [6200] DEBUG - Killing job. Mar 18, 2015 17:56:04:576 [5008] ERROR - PeekNamedPipe failed: 0x6d Mar 18, 2015 17:56:04:577 [5008] DEBUG - Universal transcode session finished reading output. Mar 18, 2015 17:56:04:578 [5008] DEBUG - Finished universal transcode output thread Mar 18, 2015 17:56:04:578 [5008] DEBUG - Notifying stream that data is complete. Mar 18, 2015 17:56:04:578 [6200] DEBUG - Job was already killed, not killing again. Mar 18, 2015 17:56:04:578 [6200] DEBUG - Stopping transcode session 98dedad677369a63a25ced27d1b47a90735b23b5 Mar 18, 2015 17:56:04:578 [6200] DEBUG - Cleaning directory for session 98dedad677369a63a25ced27d1b47a90735b23b5 (D: mp\plex-transcode-98dedad677369a63a25ced27d1b47a90735b23b5-5484b7e9-7a89-4494-9258-53f1c568c849) Mar 18, 2015 17:56:04:582 [6200] DEBUG - Starting a transcode session 98dedad677369a63a25ced27d1b47a90735b23b5 at offset -1.0 (stopped=1) Mar 18, 2015 17:56:04:584 [6200] DEBUG - Obtained video bitrate of 1515KBps from media analysis. Mar 18, 2015 17:56:04:584 [6200] DEBUG - Scaled up video bitrate to 2464Kbps based on 150% fudge factor. Mar 18, 2015 17:56:04:584 [6200] DEBUG - Job running: D:\Programme\Plex\Plex Media Server\PlexNewTranscoder.exe -ss "9" -seek_offset "8392489" -i "G:\Girls und Panzer\01.mp4" "-y" "-nostats" -f "asf" -loglevel "quiet" -loglevel_plex "error" -map_metadata "-1" -progressurl "http://127.0.0.1:32400/video/:/transcode/session/98dedad677369a63a25ced27d1b47a90735b23b5/progress" -vcodec "wmv2" -qscale:v "9" -pix_fmt "yuv420p" -maxrate "2464k" -bufsize "4928k" -x264opts ":subme=0:me_range=4:rc_lookahead=10:me=dia:no_chroma_me:8x8dct=0:partitions=none" "-sn" -filter_complex "[0:#0x01]scale=w=iw:h=ih:force_original_aspect_ratio=decrease[n0];[n0]copy[out]" -map "[out]" -acodec "wmav2" -ac "2" -map "0:#0x02" -ab "128k" "-" Mar 18, 2015 17:56:04:596 [6208] DEBUG - [TranscodeOutputStream] Timed out waiting for data Mar 18, 2015 17:56:04:596 [6208] DEBUG - Removed transcode output stream 041D07A8, active count 1 => 0 Mar 18, 2015 17:56:04:669 [4588] ERROR - [Transcoder] Unrecognized option 'seek_offset'. Mar 18, 2015 17:56:04:672 [4268] ERROR - [Transcoder] Error splitting the argument list: Mar 18, 2015 17:56:04:675 [4588] ERROR - [Transcoder] Option not found Mar 18, 2015 17:56:04:677 [4268] ERROR - [Transcoder] Mar 18, 2015 17:56:04:704 [6200] ERROR - PeekNamedPipe failed: 0x6d Mar 18, 2015 17:56:04:704 [6200] ERROR - Failed to start session successfully. Mar 18, 2015 17:56:04:704 [6200] DEBUG - Cleaning directory for session 98dedad677369a63a25ced27d1b47a90735b23b5 (D: mp\plex-transcode-98dedad677369a63a25ced27d1b47a90735b23b5-be9317f1-771d-40ab-b80e-44cea8226954) Mar 18, 2015 17:56:04:706 [4588] DEBUG - Request: [127.0.0.1:64998] GET /:/timeline?containerKey=%2Flibrary%2Fmetadata%2F5710&duration=1451107&guid=com%2Eplexapp%2Eagents%2Enone%3A%2F%2F6ddee75cd0c01eb1700812269317f8bacdf1e839%3Flang%3Dxn&key=%2Flibrary%2Fmetadata%2F5710&ratingKey=5710&report=1&state=playing&time=1451000&X-Plex-Device=Xbox%20360&X-Plex-Device-Name=Xbox%20360&X-Plex-Product=DLNA (4 live) Mar 18, 2015 17:56:04:707 [4588] DEBUG - * containerKey => /library/metadata/5710 Mar 18, 2015 17:56:04:707 [4588] DEBUG - * duration => 1451107 Mar 18, 2015 17:56:04:707 [4588] DEBUG - * guid => com.plexapp.agents.none://6ddee75cd0c01eb1700812269317f8bacdf1e839?lang=xn Mar 18, 2015 17:56:04:707 [4588] DEBUG - * key => /library/metadata/5710 Mar 18, 2015 17:56:04:707 [4588] DEBUG - * ratingKey => 5710 Mar 18, 2015 17:56:04:707 [4588] DEBUG - * report => 1 Mar 18, 2015 17:56:04:707 [4588] DEBUG - * state => playing Mar 18, 2015 17:56:04:707 [4588] DEBUG - * time => 1451000 Mar 18, 2015 17:56:04:707 [4588] DEBUG - * X-Plex-Device => Xbox 360 Mar 18, 2015 17:56:04:707 [4588] DEBUG - * X-Plex-Device-Name => Xbox 360 Mar 18, 2015 17:56:04:707 [4588] DEBUG - * X-Plex-Product => DLNA Mar 18, 2015 17:56:04:708 [4588] DEBUG - Client [98dedad677369a63a25ced27d1b47a90735b23b5] reporting timeline state playing, progress of 1451000/1451107ms for guid=com.plexapp.agents.none://6ddee75cd0c01eb1700812269317f8bacdf1e839?lang=xn, ratingKey=5710 url=, key=/library/metadata/5710, containerKey=/library/metadata/5710, metadataId=5710 Mar 18, 2015 17:56:04:792 [4588] DEBUG - Library item 5710 '01' got played by account 1! Mar 18, 2015 17:56:04:792 [4588] DEBUG - [Now] Device is (Xbox 360). Mar 18, 2015 17:56:04:792 [4588] DEBUG - [Now] Updated play state for /library/metadata/5710. Mar 18, 2015 17:56:04:792 [3780] DEBUG - Sync: library section 4 changed: ItemsViewed Mar 18, 2015 17:56:04:792 [7092] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/5710 Mar 18, 2015 17:56:04:798 [7092] DEBUG - It took 0.001000 sec to serialize a list with 1 elements. Mar 18, 2015 17:56:04:798 [7092] DEBUG - Caching document http://127.0.0.1:32400/library/metadata/5710 Mar 18, 2015 17:56:04:798 [7092] DEBUG - [Now] Added metadata for /library/metadata/5710. Mar 18, 2015 17:56:04:804 [4268] DEBUG - Request: [127.0.0.1:64999] GET /:/timeline?containerKey=%2Flibrary%2Fmetadata%2F5710&duration=1451107&guid=com%2Eplexapp%2Eagents%2Enone%3A%2F%2F6ddee75cd0c01eb1700812269317f8bacdf1e839%3Flang%3Dxn&key=%2Flibrary%2Fmetadata%2F5710&ratingKey=5710&report=1&state=stopped&time=1451000&X-Plex-Device=Xbox%20360&X-Plex-Device-Name=Xbox%20360&X-Plex-Product=DLNA (5 live) Mar 18, 2015 17:56:04:804 [4268] DEBUG - * containerKey => /library/metadata/5710 Mar 18, 2015 17:56:04:804 [4268] DEBUG - * duration => 1451107 Mar 18, 2015 17:56:04:804 [4268] DEBUG - * guid => com.plexapp.agents.none://6ddee75cd0c01eb1700812269317f8bacdf1e839?lang=xn Mar 18, 2015 17:56:04:804 [4268] DEBUG - * key => /library/metadata/5710 Mar 18, 2015 17:56:04:804 [4268] DEBUG - * ratingKey => 5710 Mar 18, 2015 17:56:04:804 [4268] DEBUG - * report => 1 Mar 18, 2015 17:56:04:804 [4268] DEBUG - * state => stopped Mar 18, 2015 17:56:04:804 [4268] DEBUG - * time => 1451000 Mar 18, 2015 17:56:04:804 [4268] DEBUG - * X-Plex-Device => Xbox 360 Mar 18, 2015 17:56:04:805 [4268] DEBUG - * X-Plex-Device-Name => Xbox 360 Mar 18, 2015 17:56:04:805 [4268] DEBUG - * X-Plex-Product => DLNA Mar 18, 2015 17:56:04:805 [4268] DEBUG - Client [98dedad677369a63a25ced27d1b47a90735b23b5] reporting timeline state stopped, progress of 1451000/1451107ms for guid=com.plexapp.agents.none://6ddee75cd0c01eb1700812269317f8bacdf1e839?lang=xn, ratingKey=5710 url=, key=/library/metadata/5710, containerKey=/library/metadata/5710, metadataId=5710 Mar 18, 2015 17:56:04:805 [4268] DEBUG - [Now] Device is (Xbox 360). Mar 18, 2015 17:56:04:805 [4268] DEBUG - [Now] Updated play state for /library/metadata/5710. Mar 18, 2015 17:56:04:868 [5356] ERROR - Throttle: timed out trying to read chunk 2 Mar 18, 2015 17:56:05:746 [5356] DEBUG - [TranscodeOutputStream] Input processing thread exited after writing 8392489 bytes, m_closed=1, m_endOfFileReached=0, session->isStopped()=1 Mar 18, 2015 17:56:05:746 [5356] DEBUG - Cleaning directory for session 98dedad677369a63a25ced27d1b47a90735b23b5 (D: mp\plex-transcode-98dedad677369a63a25ced27d1b47a90735b23b5-5484b7e9-7a89-4494-9258-53f1c568c849) Mar 18, 2015 17:56:12:425 [4588] DEBUG - Request: [127.0.0.1:65006] GET /video/:/transcode/universal/ping?session=98dedad677369a63a25ced27d1b47a90735b23b5 (4 live) Mar 18, 2015 17:56:12:425 [4588] DEBUG - * session => 98dedad677369a63a25ced27d1b47a90735b23b5 Mar 18, 2015 17:56:12:427 [4588] WARN - Got a transcode session ping without a session GUID (or with an invalid one). Mar 18, 2015 17:56:22:253 [5908] DEBUG - Network change. Mar 18, 2015 17:56:22:253 [5908] DEBUG - NetworkInterface: Notified of network changed (force=0) Mar 18, 2015 17:56:22:294 [5908] DEBUG - Network change notification but nothing changed. Mar 18, 2015 17:56:22:294 [5908] DEBUG - Network change. Mar 18, 2015 17:56:22:294 [5908] DEBUG - NetworkInterface: Notified of network changed (force=0) Mar 18, 2015 17:56:22:315 [5908] DEBUG - Network change notification but nothing changed. Mar 18, 2015 17:56:35:751 [6200] DEBUG - Request: [127.0.0.1:65045] GET /video/:/transcode/universal/stop?session=98dedad677369a63a25ced27d1b47a90735b23b5 (2 live) Mar 18, 2015 17:56:35:751 [6200] DEBUG - * session => 98dedad677369a63a25ced27d1b47a90735b23b5 Mar 18, 2015 17:56:35:753 [6528] WARN - Got a request to stop a transcode session without a session GUID (or with an invalid one).
