2018/08/18 21:00:27 >>> LOG START
2018/08/18 21:00:27 [main:3] Liquidsoap 1.3.3
2018/08/18 21:00:27 [main:3] Using: bytes=[distributed with OCaml 4.02 or above] pcre=7.3.4 dtools=0.3.4 duppy=0.7.0 duppy.syntax=0.7.0 cry=0.6.1 mm=0.3.1 xmlplaylist=0.1.4 lastfm=0.3.1 ogg=0.5.2 vorbis=0.7.0 opus=0.1.2 speex=0.2.1 mad=0.4.5 flac=0.1.3 flac.ogg=0.1.3 dynlink=[distributed with Ocaml] lame=0.3.3 shine=0.2.1 gstreamer=0.2.3 fdkaac=0.2.1 theora=0.3.1 gavl=0.1.6 ffmpeg=0.1.2 bjack=0.1.5 alsa=0.2.3 ao=0.2.1 samplerate=0.1.4 taglib=0.3.3 ssl=0.5.5 magic=0.7.3 camomile=1.0.1 inotify=2.3 yojson=1.4.1 faad=0.3.3 soundtouch=0.1.8 portaudio=0.2.1 pulseaudio=0.1.3 ladspa=0.1.5 dssi=0.1.2 sdl=0.9.1 lo=0.1.1
2018/08/18 21:00:27 [gstreamer.loader:3] Loaded GStreamer 1.14.2 0
2018/08/18 21:00:27 [frame:3] Using 44100Hz audio, 25Hz video, 44100Hz master.
2018/08/18 21:00:27 [frame:3] Frame size must be a multiple of 1764 ticks = 1764 audio samples = 1 video samples.
2018/08/18 21:00:27 [frame:3] Targetting 'frame.duration': 0.04s = 1764 audio samples = 1764 ticks.
2018/08/18 21:00:27 [frame:3] Frames last 0.04s = 1764 audio samples = 1 video samples = 1764 ticks.
2018/08/18 21:00:27 [video.converter:4] Using preferred video converter: gavl.
2018/08/18 21:00:27 [audio.converter:4] Using preferred samplerate converter: libsamplerate.
2018/08/18 21:00:27 [threads:3] Created thread "generic queue #1".
2018/08/18 21:00:27 [threads:3] Created thread "generic queue #2".
2018/08/18 21:00:27 [threads:3] Created thread "non-blocking queue #1".
2018/08/18 21:00:27 [threads:3] Created thread "non-blocking queue #2".
2018/08/18 21:00:27 [clock:4] Currently 1 clocks allocated.
2018/08/18 21:00:27 [clock.wallclock_main:4] Starting 1 sources...
2018/08/18 21:00:27 [source:4] Source output.sdl_7569 gets up.
2018/08/18 21:00:27 [source:4] Source mksafe gets up.
2018/08/18 21:00:27 [source:4] Source playlist_7563 gets up.
2018/08/18 21:00:27 [filmlista(dot)txt:3] Loading playlist...
2018/08/18 21:00:27 [filmlista(dot)txt:3] No mime type specified, trying autodetection.
2018/08/18 21:00:27 [playlist parser:4] Trying application/rss+xml parser
2018/08/18 21:00:27 [playlist.xml:5] Parsing failed: xml error: expected root element
2018/08/18 21:00:27 [playlist parser:4] Trying application/xspf+xml parser
2018/08/18 21:00:27 [playlist.xml:5] Parsing failed: xml error: expected root element
2018/08/18 21:00:27 [playlist parser:4] Trying application/smil+xml parser
2018/08/18 21:00:27 [playlist.xml:5] Parsing failed: xml error: expected root element
2018/08/18 21:00:27 [playlist parser:4] Trying application/smil parser
2018/08/18 21:00:27 [playlist.xml:5] Parsing failed: xml error: expected root element
2018/08/18 21:00:27 [playlist parser:4] Trying application/xml parser
2018/08/18 21:00:27 [playlist.xml:5] Parsing failed: xml error: expected root element
2018/08/18 21:00:27 [playlist parser:4] Trying text/xml parser
2018/08/18 21:00:27 [playlist.xml:5] Parsing failed: xml error: expected root element
2018/08/18 21:00:27 [playlist parser:4] Trying audio/x-ms-asx parser
2018/08/18 21:00:27 [playlist.xml:5] Parsing failed: xml error: expected root element
2018/08/18 21:00:27 [playlist parser:4] Trying video/x-ms-asf parser
2018/08/18 21:00:27 [playlist.xml:5] Parsing failed: xml error: expected root element
2018/08/18 21:00:27 [playlist parser:4] Trying application/x-cue parser
2018/08/18 21:00:27 [playlist parser:4] Trying audio/x-scpls parser
2018/08/18 21:00:27 [playlist parser:4] Trying application/x-mpegURL parser
2018/08/18 21:00:27 [filmlista(dot)txt:3] Playlist treated as format application/x-mpegURL
2018/08/18 21:00:27 [filmlista(dot)txt:3] Successfully loaded a playlist of 1 tracks.
2018/08/18 21:00:27 [filmlista(dot)txt:4] Content kind is {audio=0;video=1;midi=0}.
2018/08/18 21:00:27 [filmlista(dot)txt:4] Activations changed: static=[], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:27 [source:4] Source safe_blank gets up.
2018/08/18 21:00:27 [safe_blank:4] Content kind is {audio=0;video=1;midi=0}.
2018/08/18 21:00:27 [safe_blank:4] Activations changed: static=[], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:27 [mksafe:4] Activations changed: static=[sdl:sdl], dynamic=[].
2018/08/18 21:00:27 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:27 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:27 [sdl:4] Activations changed: static=[sdl], dynamic=[].
2018/08/18 21:00:27 [sdl:4] Enabling caching mode: active source.
2018/08/18 21:00:27 [sdl:4] Initialized SDL video surface with 32bpp.
2018/08/18 21:00:27 [threads:3] Created thread "wallclock_main" (1 total).
2018/08/18 21:00:27 [clock:4] Main phase starts.
2018/08/18 21:00:27 [decoder:4] Trying method "META" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:27 [decoder:4] Trying method "WAV" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:27 [decoder:4] Trying method "AIFF" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:27 [decoder:4] Trying method "MIDI" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:27 [decoder:4] Trying method "IMAGE" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:27 [decoder:4] Trying method "PPM" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:27 [clock.wallclock_main:3] Streaming loop starts, synchronized with wallclock.
2018/08/18 21:00:27 [mksafe:3] Switch to safe_blank.
2018/08/18 21:00:27 [safe_blank:4] Activations changed: static=[mksafe:sdl:sdl], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:27 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:27 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:27 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:27 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:27 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:27 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:27 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:27 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:27 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:27 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:27 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:27 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:27 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:27 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:28 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:28 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:28 [decoder:4] Decoder "PPM" failed on "/home/per/tmp/test/TV.mp4": Image.RGBA32.Invalid_format("Not a PPM file.")!
2018/08/18 21:00:28 [decoder:4] Trying method "SDL/IMAGE" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:28 [decoder:4] Decoder "SDL/IMAGE" failed on "/home/per/tmp/test/TV.mp4": Sdlloader.SDLloader_exception("Unsupported image format")!
2018/08/18 21:00:28 [decoder:3] Unable to decode "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:28 [decoder:4] Trying method "FLAC" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:28 [decoder.flac:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:28 [decoder.flac:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:28 [decoder:4] Trying method "AAC" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:28 [decoder.aac:4] Libfaad recognizes "/home/per/tmp/test/TV.mp4" as AAC (44100Hz,2 channels).
2018/08/18 21:00:28 [decoder:4] Trying method "MP4" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:28 [decoder:4] Decoder "MP4" failed on "/home/per/tmp/test/TV.mp4": Faad.Failed!
2018/08/18 21:00:28 [decoder:4] Trying method "OGG" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:28 [decoder.ogg:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:28 [decoder.ogg:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:28 [decoder:4] Trying method "MAD" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:28 [decoder:4] Trying method "GSTREAMER" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:28 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:28 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:28 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:28 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:28 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:28 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:28 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:28 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:28 [decoder.gstreamer:5] File /home/per/tmp/test/TV.mp4 has audio.
2018/08/18 21:00:28 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:28 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:28 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:28 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:28 [decoder.gstreamer:5] File /home/per/tmp/test/TV.mp4 has video.
2018/08/18 21:00:28 [decoder:3] Method "GSTREAMER" accepted "/home/per/tmp/test/TV.mp4".
2018/08/18 21:00:28 [metadata.flac:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:28 [metadata.flac:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:28 [decoder.ogg:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:28 [decoder.ogg:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:28 [decoder.taglib:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:28 [decoder.taglib:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:28 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:28 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:28 [filmlista(dot)txt:4] Remaining: 0.0s, queued: 0.0s, adding: 30.0s (RID 1)
2018/08/18 21:00:28 [filmlista(dot)txt:4] Remaining: 0.0s, queued: 30.0s, taking: 30.0s
2018/08/18 21:00:28 [decoder.gstreamer:4] Using GStreamer 1.14.2.
2018/08/18 21:00:28 [decoder.gstreamer:5] Decode A/V: false/true.
2018/08/18 21:00:28 [decoder.gstreamer:5] Gstreamer pipeline: appsrc name=src ! tee name=t t. ! queue ! decodebin ! videoconvert ! videoscale add-borders=true ! videorate ! appsink name="video_sink" drop=false sync=false max-buffers=10 caps="video/x-raw,format=RGBA,width=320,height=240,framerate=25/1,pixel-aspect-ratio=1/1".
2018/08/18 21:00:28 [filmlista(dot)txt:3] Prepared "/home/per/tmp/test/TV.mp4" (RID 1).
2018/08/18 21:00:28 [mksafe:3] Switch to filmlista(dot)txt with transition.
2018/08/18 21:00:28 [safe_blank:4] Activations changed: static=[], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:28 [filmlista(dot)txt:4] Activations changed: static=[mksafe:sdl:sdl], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:28 [decoder:4] Decoding "/home/per/tmp/test/TV.mp4" ended: Gstreamer.Failure.
2018/08/18 21:00:28 [filmlista(dot)txt:3] Finished with "/home/per/tmp/test/TV.mp4".
2018/08/18 21:00:28 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:28 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:28 [mksafe:3] Switch to safe_blank with forgetful transition.
2018/08/18 21:00:28 [filmlista(dot)txt:4] Activations changed: static=[], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:28 [safe_blank:4] Activations changed: static=[mksafe:sdl:sdl], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:28 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:28 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:28 [decoder:4] Trying method "META" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:28 [decoder:4] Trying method "WAV" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:28 [decoder:4] Trying method "AIFF" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:28 [decoder:4] Trying method "MIDI" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:28 [decoder:4] Trying method "IMAGE" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:28 [decoder:4] Trying method "PPM" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:28 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:28 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:28 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:28 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:28 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:28 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:28 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:28 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:28 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:28 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:28 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:28 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:28 [decoder:4] Decoder "PPM" failed on "/home/per/tmp/test/TV.mp4": Image.RGBA32.Invalid_format("Not a PPM file.")!
2018/08/18 21:00:28 [decoder:4] Trying method "SDL/IMAGE" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:28 [decoder:4] Decoder "SDL/IMAGE" failed on "/home/per/tmp/test/TV.mp4": Sdlloader.SDLloader_exception("Unsupported image format")!
2018/08/18 21:00:28 [decoder:3] Unable to decode "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:28 [decoder:4] Trying method "FLAC" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:28 [decoder.flac:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:28 [decoder.flac:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:28 [decoder:4] Trying method "AAC" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:28 [decoder.aac:4] Libfaad recognizes "/home/per/tmp/test/TV.mp4" as AAC (44100Hz,2 channels).
2018/08/18 21:00:28 [decoder:4] Trying method "MP4" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:28 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:28 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:28 [decoder:4] Decoder "MP4" failed on "/home/per/tmp/test/TV.mp4": Faad.Failed!
2018/08/18 21:00:28 [decoder:4] Trying method "OGG" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:28 [decoder.ogg:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:28 [decoder.ogg:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:28 [decoder:4] Trying method "MAD" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:28 [decoder:4] Trying method "GSTREAMER" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:28 [decoder.gstreamer:5] File /home/per/tmp/test/TV.mp4 has audio.
2018/08/18 21:00:28 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:28 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:28 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:28 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:28 [decoder.gstreamer:5] File /home/per/tmp/test/TV.mp4 has video.
2018/08/18 21:00:28 [decoder:3] Method "GSTREAMER" accepted "/home/per/tmp/test/TV.mp4".
2018/08/18 21:00:28 [metadata.flac:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:28 [metadata.flac:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:28 [decoder.ogg:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:28 [decoder.ogg:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:28 [decoder.taglib:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:28 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:28 [decoder.taglib:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:28 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:28 [filmlista(dot)txt:4] Remaining: 0.0s, queued: 0.0s, adding: 30.0s (RID 2)
2018/08/18 21:00:28 [filmlista(dot)txt:4] Remaining: 0.0s, queued: 30.0s, taking: 30.0s
2018/08/18 21:00:28 [decoder.gstreamer:4] Using GStreamer 1.14.2.
2018/08/18 21:00:28 [decoder.gstreamer:5] Decode A/V: false/true.
2018/08/18 21:00:28 [decoder.gstreamer:5] Gstreamer pipeline: appsrc name=src ! tee name=t t. ! queue ! decodebin ! videoconvert ! videoscale add-borders=true ! videorate ! appsink name="video_sink" drop=false sync=false max-buffers=10 caps="video/x-raw,format=RGBA,width=320,height=240,framerate=25/1,pixel-aspect-ratio=1/1".
2018/08/18 21:00:28 [filmlista(dot)txt:3] Prepared "/home/per/tmp/test/TV.mp4" (RID 2).
2018/08/18 21:00:28 [mksafe:3] Switch to filmlista(dot)txt with transition.
2018/08/18 21:00:28 [safe_blank:4] Activations changed: static=[], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:28 [filmlista(dot)txt:4] Activations changed: static=[mksafe:sdl:sdl], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:28 [decoder:4] Decoding "/home/per/tmp/test/TV.mp4" ended: Gstreamer.Failure.
2018/08/18 21:00:28 [filmlista(dot)txt:3] Finished with "/home/per/tmp/test/TV.mp4".
2018/08/18 21:00:28 [decoder.gstreamer:5] End of stream.
2018/08/18 21:00:28 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:28 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:28 [mksafe:3] Switch to safe_blank with forgetful transition.
2018/08/18 21:00:28 [filmlista(dot)txt:4] Activations changed: static=[], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:28 [source:4] Source replay_metadata_7597 gets up.
2018/08/18 21:00:28 [decoder:4] Trying method "META" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:28 [replay_metadata_7597:4] Content kind is {audio=0;video=1;midi=0}.
2018/08/18 21:00:28 [decoder:4] Trying method "WAV" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:28 [safe_blank:4] Activations changed: static=[replay_metadata_7597:mksafe:sdl:sdl], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:28 [decoder:4] Trying method "AIFF" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:28 [replay_metadata_7597:4] Activations changed: static=[mksafe:sdl:sdl], dynamic=[].
2018/08/18 21:00:28 [decoder:4] Trying method "MIDI" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:28 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:28 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:28 [decoder:4] Trying method "IMAGE" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:28 [decoder:4] Trying method "PPM" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:28 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:28 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:28 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:28 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:28 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:28 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:29 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:29 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:29 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:29 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:29 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:29 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:29 [decoder:4] Decoder "PPM" failed on "/home/per/tmp/test/TV.mp4": Image.RGBA32.Invalid_format("Not a PPM file.")!
2018/08/18 21:00:29 [decoder:4] Trying method "SDL/IMAGE" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:29 [decoder:4] Decoder "SDL/IMAGE" failed on "/home/per/tmp/test/TV.mp4": Sdlloader.SDLloader_exception("Unsupported image format")!
2018/08/18 21:00:29 [decoder:3] Unable to decode "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:29 [decoder:4] Trying method "FLAC" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:29 [decoder.flac:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:29 [decoder.flac:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:29 [decoder:4] Trying method "AAC" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:29 [decoder.aac:4] Libfaad recognizes "/home/per/tmp/test/TV.mp4" as AAC (44100Hz,2 channels).
2018/08/18 21:00:29 [decoder:4] Trying method "MP4" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:29 [decoder:4] Decoder "MP4" failed on "/home/per/tmp/test/TV.mp4": Faad.Failed!
2018/08/18 21:00:29 [decoder:4] Trying method "OGG" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:29 [decoder.ogg:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:29 [decoder.ogg:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:29 [decoder:4] Trying method "MAD" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:29 [decoder:4] Trying method "GSTREAMER" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:29 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:29 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:29 [decoder.gstreamer:5] File /home/per/tmp/test/TV.mp4 has audio.
2018/08/18 21:00:29 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:29 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:29 [decoder.gstreamer:5] File /home/per/tmp/test/TV.mp4 has video.
2018/08/18 21:00:29 [decoder:3] Method "GSTREAMER" accepted "/home/per/tmp/test/TV.mp4".
2018/08/18 21:00:29 [metadata.flac:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:29 [metadata.flac:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:29 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:29 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:29 [decoder.ogg:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:29 [decoder.ogg:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:29 [decoder.taglib:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:29 [decoder.taglib:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:29 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:29 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:29 [filmlista(dot)txt:4] Remaining: 0.0s, queued: 0.0s, adding: 30.0s (RID 3)
2018/08/18 21:00:29 [filmlista(dot)txt:4] Remaining: 0.0s, queued: 30.0s, taking: 30.0s
2018/08/18 21:00:29 [decoder.gstreamer:4] Using GStreamer 1.14.2.
2018/08/18 21:00:29 [decoder.gstreamer:5] Decode A/V: false/true.
2018/08/18 21:00:29 [decoder.gstreamer:5] Gstreamer pipeline: appsrc name=src ! tee name=t t. ! queue ! decodebin ! videoconvert ! videoscale add-borders=true ! videorate ! appsink name="video_sink" drop=false sync=false max-buffers=10 caps="video/x-raw,format=RGBA,width=320,height=240,framerate=25/1,pixel-aspect-ratio=1/1".
2018/08/18 21:00:29 [filmlista(dot)txt:3] Prepared "/home/per/tmp/test/TV.mp4" (RID 3).
2018/08/18 21:00:29 [mksafe:3] Switch to filmlista(dot)txt with transition.
2018/08/18 21:00:29 [replay_metadata_7597:4] Activations changed: static=[], dynamic=[].
2018/08/18 21:00:29 [source:4] Source replay_metadata_7597 gets down.
2018/08/18 21:00:29 [safe_blank:4] Activations changed: static=[], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:29 [filmlista(dot)txt:4] Activations changed: static=[mksafe:sdl:sdl], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:29 [decoder:4] Decoding "/home/per/tmp/test/TV.mp4" ended: Gstreamer.Failure.
2018/08/18 21:00:29 [filmlista(dot)txt:3] Finished with "/home/per/tmp/test/TV.mp4".
2018/08/18 21:00:29 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:29 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:29 [mksafe:3] Switch to safe_blank with forgetful transition.
2018/08/18 21:00:29 [filmlista(dot)txt:4] Activations changed: static=[], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:29 [source:4] Source replay_metadata_7601 gets up.
2018/08/18 21:00:29 [replay_metadata_7601:4] Content kind is {audio=0;video=1;midi=0}.
2018/08/18 21:00:29 [safe_blank:4] Activations changed: static=[replay_metadata_7601:mksafe:sdl:sdl], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:29 [decoder:4] Trying method "META" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:29 [decoder:4] Trying method "WAV" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:29 [decoder:4] Trying method "AIFF" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:29 [decoder:4] Trying method "MIDI" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:29 [decoder:4] Trying method "IMAGE" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:29 [replay_metadata_7601:4] Activations changed: static=[mksafe:sdl:sdl], dynamic=[].
2018/08/18 21:00:29 [decoder:4] Trying method "PPM" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:29 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:29 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:29 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:29 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:29 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:29 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:29 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:29 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:29 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:29 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:29 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:29 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:29 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:29 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:29 [decoder:4] Decoder "PPM" failed on "/home/per/tmp/test/TV.mp4": Image.RGBA32.Invalid_format("Not a PPM file.")!
2018/08/18 21:00:29 [decoder:4] Trying method "SDL/IMAGE" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:29 [decoder:4] Decoder "SDL/IMAGE" failed on "/home/per/tmp/test/TV.mp4": Sdlloader.SDLloader_exception("Unsupported image format")!
2018/08/18 21:00:29 [decoder:3] Unable to decode "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:29 [decoder:4] Trying method "FLAC" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:29 [decoder.flac:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:29 [decoder.flac:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:29 [decoder:4] Trying method "AAC" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:29 [decoder.aac:4] Libfaad recognizes "/home/per/tmp/test/TV.mp4" as AAC (44100Hz,2 channels).
2018/08/18 21:00:29 [decoder:4] Trying method "MP4" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:29 [decoder:4] Decoder "MP4" failed on "/home/per/tmp/test/TV.mp4": Faad.Failed!
2018/08/18 21:00:29 [decoder:4] Trying method "OGG" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:29 [decoder.ogg:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:29 [decoder.ogg:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:29 [decoder:4] Trying method "MAD" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:29 [decoder:4] Trying method "GSTREAMER" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:29 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:29 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:29 [decoder.gstreamer:5] File /home/per/tmp/test/TV.mp4 has audio.
2018/08/18 21:00:29 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:29 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:29 [decoder.gstreamer:5] File /home/per/tmp/test/TV.mp4 has video.
2018/08/18 21:00:29 [decoder:3] Method "GSTREAMER" accepted "/home/per/tmp/test/TV.mp4".
2018/08/18 21:00:29 [metadata.flac:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:29 [metadata.flac:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:29 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:29 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:29 [decoder.ogg:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:29 [decoder.ogg:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:29 [decoder.taglib:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:29 [decoder.taglib:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:29 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:29 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:29 [filmlista(dot)txt:4] Remaining: 0.0s, queued: 0.0s, adding: 30.0s (RID 4)
2018/08/18 21:00:29 [filmlista(dot)txt:4] Remaining: 0.0s, queued: 30.0s, taking: 30.0s
2018/08/18 21:00:29 [decoder.gstreamer:4] Using GStreamer 1.14.2.
2018/08/18 21:00:29 [decoder.gstreamer:5] Decode A/V: false/true.
2018/08/18 21:00:29 [decoder.gstreamer:5] Gstreamer pipeline: appsrc name=src ! tee name=t t. ! queue ! decodebin ! videoconvert ! videoscale add-borders=true ! videorate ! appsink name="video_sink" drop=false sync=false max-buffers=10 caps="video/x-raw,format=RGBA,width=320,height=240,framerate=25/1,pixel-aspect-ratio=1/1".
2018/08/18 21:00:29 [filmlista(dot)txt:3] Prepared "/home/per/tmp/test/TV.mp4" (RID 4).
2018/08/18 21:00:29 [mksafe:3] Switch to filmlista(dot)txt with transition.
2018/08/18 21:00:29 [replay_metadata_7601:4] Activations changed: static=[], dynamic=[].
2018/08/18 21:00:29 [source:4] Source replay_metadata_7601 gets down.
2018/08/18 21:00:29 [safe_blank:4] Activations changed: static=[], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:29 [filmlista(dot)txt:4] Activations changed: static=[mksafe:sdl:sdl], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:29 [decoder:4] Decoding "/home/per/tmp/test/TV.mp4" ended: Gstreamer.Failure.
2018/08/18 21:00:29 [filmlista(dot)txt:3] Finished with "/home/per/tmp/test/TV.mp4".
2018/08/18 21:00:29 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:29 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:29 [mksafe:3] Switch to safe_blank with forgetful transition.
2018/08/18 21:00:29 [filmlista(dot)txt:4] Activations changed: static=[], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:29 [source:4] Source replay_metadata_7605 gets up.
2018/08/18 21:00:29 [replay_metadata_7605:4] Content kind is {audio=0;video=1;midi=0}.
2018/08/18 21:00:29 [safe_blank:4] Activations changed: static=[replay_metadata_7605:mksafe:sdl:sdl], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:29 [replay_metadata_7605:4] Activations changed: static=[mksafe:sdl:sdl], dynamic=[].
2018/08/18 21:00:29 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:29 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:29 [decoder:4] Trying method "META" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:29 [decoder:4] Trying method "WAV" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:29 [decoder:4] Trying method "AIFF" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:29 [decoder:4] Trying method "MIDI" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:29 [decoder:4] Trying method "IMAGE" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:29 [decoder:4] Trying method "PPM" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:29 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:29 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:29 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:29 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:29 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:29 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:29 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:29 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:30 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:30 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:30 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:30 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:30 [decoder:4] Decoder "PPM" failed on "/home/per/tmp/test/TV.mp4": Image.RGBA32.Invalid_format("Not a PPM file.")!
2018/08/18 21:00:30 [decoder:4] Trying method "SDL/IMAGE" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:30 [decoder:4] Decoder "SDL/IMAGE" failed on "/home/per/tmp/test/TV.mp4": Sdlloader.SDLloader_exception("Unsupported image format")!
2018/08/18 21:00:30 [decoder:3] Unable to decode "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:30 [decoder:4] Trying method "FLAC" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:30 [decoder.flac:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:30 [decoder.flac:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:30 [decoder:4] Trying method "AAC" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:30 [decoder.aac:4] Libfaad recognizes "/home/per/tmp/test/TV.mp4" as AAC (44100Hz,2 channels).
2018/08/18 21:00:30 [decoder:4] Trying method "MP4" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:30 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:30 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:30 [decoder:4] Decoder "MP4" failed on "/home/per/tmp/test/TV.mp4": Faad.Failed!
2018/08/18 21:00:30 [decoder:4] Trying method "OGG" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:30 [decoder.ogg:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:30 [decoder.ogg:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:30 [decoder:4] Trying method "MAD" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:30 [decoder:4] Trying method "GSTREAMER" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:30 [decoder.gstreamer:5] File /home/per/tmp/test/TV.mp4 has audio.
2018/08/18 21:00:30 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:30 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:30 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:30 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:30 [decoder.gstreamer:5] File /home/per/tmp/test/TV.mp4 has video.
2018/08/18 21:00:30 [decoder:3] Method "GSTREAMER" accepted "/home/per/tmp/test/TV.mp4".
2018/08/18 21:00:30 [metadata.flac:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:30 [metadata.flac:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:30 [decoder.ogg:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:30 [decoder.ogg:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:30 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:30 [decoder.taglib:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:30 [decoder.taglib:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:30 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:30 [filmlista(dot)txt:4] Remaining: 0.0s, queued: 0.0s, adding: 30.0s (RID 5)
2018/08/18 21:00:30 [filmlista(dot)txt:4] Remaining: 0.0s, queued: 30.0s, taking: 30.0s
2018/08/18 21:00:30 [decoder.gstreamer:4] Using GStreamer 1.14.2.
2018/08/18 21:00:30 [decoder.gstreamer:5] Decode A/V: false/true.
2018/08/18 21:00:30 [decoder.gstreamer:5] Gstreamer pipeline: appsrc name=src ! tee name=t t. ! queue ! decodebin ! videoconvert ! videoscale add-borders=true ! videorate ! appsink name="video_sink" drop=false sync=false max-buffers=10 caps="video/x-raw,format=RGBA,width=320,height=240,framerate=25/1,pixel-aspect-ratio=1/1".
2018/08/18 21:00:30 [filmlista(dot)txt:3] Prepared "/home/per/tmp/test/TV.mp4" (RID 5).
2018/08/18 21:00:30 [mksafe:3] Switch to filmlista(dot)txt with transition.
2018/08/18 21:00:30 [replay_metadata_7605:4] Activations changed: static=[], dynamic=[].
2018/08/18 21:00:30 [source:4] Source replay_metadata_7605 gets down.
2018/08/18 21:00:30 [safe_blank:4] Activations changed: static=[], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:30 [filmlista(dot)txt:4] Activations changed: static=[mksafe:sdl:sdl], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:30 [decoder:4] Decoding "/home/per/tmp/test/TV.mp4" ended: Gstreamer.Failure.
2018/08/18 21:00:30 [filmlista(dot)txt:3] Finished with "/home/per/tmp/test/TV.mp4".
2018/08/18 21:00:30 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:30 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:30 [mksafe:3] Switch to safe_blank with forgetful transition.
2018/08/18 21:00:30 [filmlista(dot)txt:4] Activations changed: static=[], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:30 [source:4] Source replay_metadata_7609 gets up.
2018/08/18 21:00:30 [replay_metadata_7609:4] Content kind is {audio=0;video=1;midi=0}.
2018/08/18 21:00:30 [safe_blank:4] Activations changed: static=[replay_metadata_7609:mksafe:sdl:sdl], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:30 [replay_metadata_7609:4] Activations changed: static=[mksafe:sdl:sdl], dynamic=[].
2018/08/18 21:00:30 [decoder:4] Trying method "META" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:30 [decoder.gstreamer:5] End of stream.
2018/08/18 21:00:30 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:30 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:30 [decoder:4] Trying method "WAV" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:30 [decoder:4] Trying method "AIFF" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:30 [decoder:4] Trying method "MIDI" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:30 [decoder:4] Trying method "IMAGE" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:30 [decoder:4] Trying method "PPM" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:30 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:30 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:30 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:30 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:30 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:30 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:30 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:30 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:30 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:30 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:30 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:30 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:30 [decoder:4] Decoder "PPM" failed on "/home/per/tmp/test/TV.mp4": Image.RGBA32.Invalid_format("Not a PPM file.")!
2018/08/18 21:00:30 [decoder:4] Trying method "SDL/IMAGE" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:30 [decoder:4] Decoder "SDL/IMAGE" failed on "/home/per/tmp/test/TV.mp4": Sdlloader.SDLloader_exception("Unsupported image format")!
2018/08/18 21:00:30 [decoder:3] Unable to decode "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:30 [decoder:4] Trying method "FLAC" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:30 [decoder.flac:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:30 [decoder.flac:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:30 [decoder:4] Trying method "AAC" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:30 [decoder.aac:4] Libfaad recognizes "/home/per/tmp/test/TV.mp4" as AAC (44100Hz,2 channels).
2018/08/18 21:00:30 [decoder:4] Trying method "MP4" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:30 [decoder:4] Decoder "MP4" failed on "/home/per/tmp/test/TV.mp4": Faad.Failed!
2018/08/18 21:00:30 [decoder:4] Trying method "OGG" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:30 [decoder.ogg:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:30 [decoder.ogg:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:30 [decoder:4] Trying method "MAD" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:30 [decoder:4] Trying method "GSTREAMER" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:30 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:30 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:30 [decoder.gstreamer:5] File /home/per/tmp/test/TV.mp4 has audio.
2018/08/18 21:00:30 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:30 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:30 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:30 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:30 [decoder.gstreamer:5] File /home/per/tmp/test/TV.mp4 has video.
2018/08/18 21:00:30 [decoder:3] Method "GSTREAMER" accepted "/home/per/tmp/test/TV.mp4".
2018/08/18 21:00:30 [metadata.flac:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:30 [metadata.flac:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:30 [decoder.ogg:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:30 [decoder.ogg:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:30 [decoder.taglib:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:30 [decoder.taglib:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:30 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:30 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:30 [filmlista(dot)txt:4] Remaining: 0.0s, queued: 0.0s, adding: 30.0s (RID 6)
2018/08/18 21:00:30 [filmlista(dot)txt:4] Remaining: 0.0s, queued: 30.0s, taking: 30.0s
2018/08/18 21:00:30 [decoder.gstreamer:4] Using GStreamer 1.14.2.
2018/08/18 21:00:30 [decoder.gstreamer:5] Decode A/V: false/true.
2018/08/18 21:00:30 [decoder.gstreamer:5] Gstreamer pipeline: appsrc name=src ! tee name=t t. ! queue ! decodebin ! videoconvert ! videoscale add-borders=true ! videorate ! appsink name="video_sink" drop=false sync=false max-buffers=10 caps="video/x-raw,format=RGBA,width=320,height=240,framerate=25/1,pixel-aspect-ratio=1/1".
2018/08/18 21:00:30 [filmlista(dot)txt:3] Prepared "/home/per/tmp/test/TV.mp4" (RID 6).
2018/08/18 21:00:30 [mksafe:3] Switch to filmlista(dot)txt with transition.
2018/08/18 21:00:30 [replay_metadata_7609:4] Activations changed: static=[], dynamic=[].
2018/08/18 21:00:30 [source:4] Source replay_metadata_7609 gets down.
2018/08/18 21:00:30 [safe_blank:4] Activations changed: static=[], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:30 [filmlista(dot)txt:4] Activations changed: static=[mksafe:sdl:sdl], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:30 [decoder:4] Decoding "/home/per/tmp/test/TV.mp4" ended: Gstreamer.Failure.
2018/08/18 21:00:30 [filmlista(dot)txt:3] Finished with "/home/per/tmp/test/TV.mp4".
2018/08/18 21:00:30 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:30 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:30 [mksafe:3] Switch to safe_blank with forgetful transition.
2018/08/18 21:00:30 [filmlista(dot)txt:4] Activations changed: static=[], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:30 [source:4] Source replay_metadata_7613 gets up.
2018/08/18 21:00:30 [replay_metadata_7613:4] Content kind is {audio=0;video=1;midi=0}.
2018/08/18 21:00:30 [decoder:4] Trying method "META" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:30 [decoder:4] Trying method "WAV" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:30 [decoder:4] Trying method "AIFF" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:30 [decoder:4] Trying method "MIDI" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:30 [decoder:4] Trying method "IMAGE" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:30 [decoder:4] Trying method "PPM" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:30 [safe_blank:4] Activations changed: static=[replay_metadata_7613:mksafe:sdl:sdl], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:30 [replay_metadata_7613:4] Activations changed: static=[mksafe:sdl:sdl], dynamic=[].
2018/08/18 21:00:30 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:30 [source:4] Garbage collected empty_7604.
2018/08/18 21:00:30 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:30 [source:4] Garbage collected replay_metadata_7601.
2018/08/18 21:00:30 [source:4] Garbage collected empty_7600.
2018/08/18 21:00:30 [source:4] Garbage collected replay_metadata_7597.
2018/08/18 21:00:30 [source:4] Garbage collected empty_7596.
2018/08/18 21:00:30 [source:4] Garbage collected empty_7595.
2018/08/18 21:00:30 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:30 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:30 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:30 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:30 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:30 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:30 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:30 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:30 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:30 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:31 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:31 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:31 [decoder:4] Decoder "PPM" failed on "/home/per/tmp/test/TV.mp4": Image.RGBA32.Invalid_format("Not a PPM file.")!
2018/08/18 21:00:31 [decoder:4] Trying method "SDL/IMAGE" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:31 [decoder:4] Decoder "SDL/IMAGE" failed on "/home/per/tmp/test/TV.mp4": Sdlloader.SDLloader_exception("Unsupported image format")!
2018/08/18 21:00:31 [decoder:3] Unable to decode "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:31 [decoder:4] Trying method "FLAC" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:31 [decoder.flac:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:31 [decoder.flac:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:31 [decoder:4] Trying method "AAC" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:31 [decoder.aac:4] Libfaad recognizes "/home/per/tmp/test/TV.mp4" as AAC (44100Hz,2 channels).
2018/08/18 21:00:31 [decoder:4] Trying method "MP4" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:31 [decoder:4] Decoder "MP4" failed on "/home/per/tmp/test/TV.mp4": Faad.Failed!
2018/08/18 21:00:31 [decoder:4] Trying method "OGG" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:31 [decoder.ogg:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:31 [decoder.ogg:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:31 [decoder:4] Trying method "MAD" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:31 [decoder:4] Trying method "GSTREAMER" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:31 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:31 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:31 [decoder.gstreamer:5] File /home/per/tmp/test/TV.mp4 has audio.
2018/08/18 21:00:31 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:31 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:31 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:31 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:31 [decoder.gstreamer:5] File /home/per/tmp/test/TV.mp4 has video.
2018/08/18 21:00:31 [decoder:3] Method "GSTREAMER" accepted "/home/per/tmp/test/TV.mp4".
2018/08/18 21:00:31 [metadata.flac:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:31 [metadata.flac:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:31 [decoder.ogg:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:31 [decoder.ogg:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:31 [decoder.taglib:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:31 [decoder.taglib:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:31 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:31 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:31 [filmlista(dot)txt:4] Remaining: 0.0s, queued: 0.0s, adding: 30.0s (RID 7)
2018/08/18 21:00:31 [filmlista(dot)txt:4] Remaining: 0.0s, queued: 30.0s, taking: 30.0s
2018/08/18 21:00:31 [decoder.gstreamer:4] Using GStreamer 1.14.2.
2018/08/18 21:00:31 [decoder.gstreamer:5] Decode A/V: false/true.
2018/08/18 21:00:31 [decoder.gstreamer:5] Gstreamer pipeline: appsrc name=src ! tee name=t t. ! queue ! decodebin ! videoconvert ! videoscale add-borders=true ! videorate ! appsink name="video_sink" drop=false sync=false max-buffers=10 caps="video/x-raw,format=RGBA,width=320,height=240,framerate=25/1,pixel-aspect-ratio=1/1".
2018/08/18 21:00:31 [filmlista(dot)txt:3] Prepared "/home/per/tmp/test/TV.mp4" (RID 7).
2018/08/18 21:00:31 [mksafe:3] Switch to filmlista(dot)txt with transition.
2018/08/18 21:00:31 [replay_metadata_7613:4] Activations changed: static=[], dynamic=[].
2018/08/18 21:00:31 [source:4] Source replay_metadata_7613 gets down.
2018/08/18 21:00:31 [safe_blank:4] Activations changed: static=[], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:31 [filmlista(dot)txt:4] Activations changed: static=[mksafe:sdl:sdl], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:31 [decoder:4] Decoding "/home/per/tmp/test/TV.mp4" ended: Gstreamer.Failure.
2018/08/18 21:00:31 [filmlista(dot)txt:3] Finished with "/home/per/tmp/test/TV.mp4".
2018/08/18 21:00:31 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:31 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:31 [mksafe:3] Switch to safe_blank with forgetful transition.
2018/08/18 21:00:31 [filmlista(dot)txt:4] Activations changed: static=[], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:31 [source:4] Source replay_metadata_7617 gets up.
2018/08/18 21:00:31 [replay_metadata_7617:4] Content kind is {audio=0;video=1;midi=0}.
2018/08/18 21:00:31 [safe_blank:4] Activations changed: static=[replay_metadata_7617:mksafe:sdl:sdl], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:31 [replay_metadata_7617:4] Activations changed: static=[mksafe:sdl:sdl], dynamic=[].
2018/08/18 21:00:31 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:31 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:31 [decoder:4] Trying method "META" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:31 [decoder:4] Trying method "WAV" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:31 [decoder:4] Trying method "AIFF" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:31 [decoder:4] Trying method "MIDI" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:31 [decoder:4] Trying method "IMAGE" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:31 [decoder:4] Trying method "PPM" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:31 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:31 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:31 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:31 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:31 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:31 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:31 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:31 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:31 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:31 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:31 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:31 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:31 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:31 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:31 [decoder:4] Decoder "PPM" failed on "/home/per/tmp/test/TV.mp4": Image.RGBA32.Invalid_format("Not a PPM file.")!
2018/08/18 21:00:31 [decoder:4] Trying method "SDL/IMAGE" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:31 [decoder:4] Decoder "SDL/IMAGE" failed on "/home/per/tmp/test/TV.mp4": Sdlloader.SDLloader_exception("Unsupported image format")!
2018/08/18 21:00:31 [decoder:3] Unable to decode "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:31 [decoder:4] Trying method "FLAC" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:31 [decoder.flac:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:31 [decoder.flac:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:31 [decoder:4] Trying method "AAC" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:31 [decoder.aac:4] Libfaad recognizes "/home/per/tmp/test/TV.mp4" as AAC (44100Hz,2 channels).
2018/08/18 21:00:31 [decoder:4] Trying method "MP4" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:31 [decoder:4] Decoder "MP4" failed on "/home/per/tmp/test/TV.mp4": Faad.Failed!
2018/08/18 21:00:31 [decoder:4] Trying method "OGG" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:31 [decoder.ogg:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:31 [decoder.ogg:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:31 [decoder:4] Trying method "MAD" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:31 [decoder:4] Trying method "GSTREAMER" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:31 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:31 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:31 [decoder.gstreamer:5] File /home/per/tmp/test/TV.mp4 has audio.
2018/08/18 21:00:31 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:31 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:31 [decoder.gstreamer:5] File /home/per/tmp/test/TV.mp4 has video.
2018/08/18 21:00:31 [decoder:3] Method "GSTREAMER" accepted "/home/per/tmp/test/TV.mp4".
2018/08/18 21:00:31 [metadata.flac:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:31 [metadata.flac:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:31 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:31 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:31 [decoder.ogg:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:31 [decoder.ogg:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:31 [decoder.taglib:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:31 [decoder.taglib:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:31 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:31 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:31 [filmlista(dot)txt:4] Remaining: 0.0s, queued: 0.0s, adding: 30.0s (RID 8)
2018/08/18 21:00:31 [filmlista(dot)txt:4] Remaining: 0.0s, queued: 30.0s, taking: 30.0s
2018/08/18 21:00:31 [decoder.gstreamer:4] Using GStreamer 1.14.2.
2018/08/18 21:00:31 [decoder.gstreamer:5] Decode A/V: false/true.
2018/08/18 21:00:31 [decoder.gstreamer:5] Gstreamer pipeline: appsrc name=src ! tee name=t t. ! queue ! decodebin ! videoconvert ! videoscale add-borders=true ! videorate ! appsink name="video_sink" drop=false sync=false max-buffers=10 caps="video/x-raw,format=RGBA,width=320,height=240,framerate=25/1,pixel-aspect-ratio=1/1".
2018/08/18 21:00:31 [filmlista(dot)txt:3] Prepared "/home/per/tmp/test/TV.mp4" (RID 8).
2018/08/18 21:00:31 [mksafe:3] Switch to filmlista(dot)txt with transition.
2018/08/18 21:00:31 [replay_metadata_7617:4] Activations changed: static=[], dynamic=[].
2018/08/18 21:00:31 [source:4] Source replay_metadata_7617 gets down.
2018/08/18 21:00:31 [safe_blank:4] Activations changed: static=[], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:31 [filmlista(dot)txt:4] Activations changed: static=[mksafe:sdl:sdl], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:31 [decoder:4] Decoding "/home/per/tmp/test/TV.mp4" ended: Gstreamer.Failure.
2018/08/18 21:00:31 [filmlista(dot)txt:3] Finished with "/home/per/tmp/test/TV.mp4".
2018/08/18 21:00:31 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:31 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:31 [mksafe:3] Switch to safe_blank with forgetful transition.
2018/08/18 21:00:31 [filmlista(dot)txt:4] Activations changed: static=[], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:31 [source:4] Source replay_metadata_7621 gets up.
2018/08/18 21:00:31 [replay_metadata_7621:4] Content kind is {audio=0;video=1;midi=0}.
2018/08/18 21:00:31 [safe_blank:4] Activations changed: static=[replay_metadata_7621:mksafe:sdl:sdl], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:31 [replay_metadata_7621:4] Activations changed: static=[mksafe:sdl:sdl], dynamic=[].
2018/08/18 21:00:31 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:31 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:31 [decoder:4] Trying method "META" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:31 [decoder:4] Trying method "WAV" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:31 [decoder:4] Trying method "AIFF" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:31 [decoder:4] Trying method "MIDI" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:31 [decoder:4] Trying method "IMAGE" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:31 [decoder:4] Trying method "PPM" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:31 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:31 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:31 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:31 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:31 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:31 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:31 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:31 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:31 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:31 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:32 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:32 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:32 [decoder:4] Decoder "PPM" failed on "/home/per/tmp/test/TV.mp4": Image.RGBA32.Invalid_format("Not a PPM file.")!
2018/08/18 21:00:32 [decoder:4] Trying method "SDL/IMAGE" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:32 [decoder:4] Decoder "SDL/IMAGE" failed on "/home/per/tmp/test/TV.mp4": Sdlloader.SDLloader_exception("Unsupported image format")!
2018/08/18 21:00:32 [decoder:3] Unable to decode "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:32 [decoder:4] Trying method "FLAC" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:32 [decoder.flac:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:32 [decoder.flac:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:32 [decoder:4] Trying method "AAC" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:32 [decoder.aac:4] Libfaad recognizes "/home/per/tmp/test/TV.mp4" as AAC (44100Hz,2 channels).
2018/08/18 21:00:32 [decoder:4] Trying method "MP4" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:32 [decoder:4] Decoder "MP4" failed on "/home/per/tmp/test/TV.mp4": Faad.Failed!
2018/08/18 21:00:32 [decoder:4] Trying method "OGG" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:32 [decoder.ogg:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:32 [decoder.ogg:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:32 [decoder:4] Trying method "MAD" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:32 [decoder:4] Trying method "GSTREAMER" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:32 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:32 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:32 [decoder.gstreamer:5] File /home/per/tmp/test/TV.mp4 has audio.
2018/08/18 21:00:32 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:32 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:32 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:32 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:32 [decoder.gstreamer:5] File /home/per/tmp/test/TV.mp4 has video.
2018/08/18 21:00:32 [decoder:3] Method "GSTREAMER" accepted "/home/per/tmp/test/TV.mp4".
2018/08/18 21:00:32 [metadata.flac:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:32 [metadata.flac:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:32 [decoder.ogg:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:32 [decoder.ogg:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:32 [decoder.taglib:4] Invalid MIME type for "/home/per/tmp/test/TV.mp4": video/mp4!
2018/08/18 21:00:32 [decoder.taglib:4] Invalid file extension for "/home/per/tmp/test/TV.mp4"!
2018/08/18 21:00:32 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:32 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:32 [filmlista(dot)txt:4] Remaining: 0.0s, queued: 0.0s, adding: 30.0s (RID 9)
2018/08/18 21:00:32 [filmlista(dot)txt:4] Remaining: 0.0s, queued: 30.0s, taking: 30.0s
2018/08/18 21:00:32 [decoder.gstreamer:4] Using GStreamer 1.14.2.
2018/08/18 21:00:32 [decoder.gstreamer:5] Decode A/V: false/true.
2018/08/18 21:00:32 [decoder.gstreamer:5] Gstreamer pipeline: appsrc name=src ! tee name=t t. ! queue ! decodebin ! videoconvert ! videoscale add-borders=true ! videorate ! appsink name="video_sink" drop=false sync=false max-buffers=10 caps="video/x-raw,format=RGBA,width=320,height=240,framerate=25/1,pixel-aspect-ratio=1/1".
2018/08/18 21:00:32 [filmlista(dot)txt:3] Prepared "/home/per/tmp/test/TV.mp4" (RID 9).
2018/08/18 21:00:32 [mksafe:3] Switch to filmlista(dot)txt with transition.
2018/08/18 21:00:32 [replay_metadata_7621:4] Activations changed: static=[], dynamic=[].
2018/08/18 21:00:32 [source:4] Source replay_metadata_7621 gets down.
2018/08/18 21:00:32 [safe_blank:4] Activations changed: static=[], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:32 [filmlista(dot)txt:4] Activations changed: static=[mksafe:sdl:sdl], dynamic=[mksafe:sdl:sdl].
2018/08/18 21:00:32 [decoder:4] Decoding "/home/per/tmp/test/TV.mp4" ended: Gstreamer.Failure.
2018/08/18 21:00:32 [filmlista(dot)txt:3] Finished with "/home/per/tmp/test/TV.mp4".
2018/08/18 21:00:32 [filmlista(dot)txt:5] Queue is empty!
2018/08/18 21:00:32 [filmlista(dot)txt:5] Failed to prepare track: no file.
2018/08/18 21:00:32 [mksafe:3] Switch to safe_blank with forgetful transition.
2018/08/18 21:00:32 [decoder:4] Trying method "META" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:32 [decoder:4] Trying method "WAV" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:32 [decoder:4] Trying method "AIFF" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:32 [decoder:4] Trying method "MIDI" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:32 [decoder:4] Trying method "IMAGE" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:32 [decoder:4] Trying method "PPM" for "/home/per/tmp/test/TV.mp4"...
2018/08/18 21:00:32 [filmlista(dot)txt:4] Activations changed: static=[], dynamic=[mksafe:sdl:sdl].