Redukce zpoždění při použití ffmpeg: -loglevel
Při použití v řetězci zpracování stream používáme ffmpeg, dochází k jisté prodlevě při zahájení sledování daného kanálu. To je pochopitelné. Stejně jako je pochopitelné, že se každý snažíme toto zpoždění omezit na nezbytné minimum. Poprvé jsme na fóru toto téma otevřeli ve vlákně o příjmu internetových radií Tvheadend a internetová rádia, kde k tomu účinně přispěli @mobilemanic a @marhyz - viz jejich příspěvky na 1. stránce, které doporučuji si přečíst! Já jsem se teď k tomu vrátil a začal s těmi doporučeními laborovat. Při té příležitosti jsem se zajímal také o parametr -loglevel.
Obecně má člověk tendenci, pokud chce něco zrychlit, tak omezit počet parametrů pod mylným dojmem "méně parametrů - méně funkcí". V případě -loglevel je to ale přesně naopak, tento parametr totiž rozsah funkce ffmpeg redukuje. Vyjmutím parametru probíhá logování na default úrovni, což mimo jiné znamená, že se na výstupu, který je v tomto případě směrován na Tvheadend, před vlastním streamem objeví i známá sada pro tuto chvíli zcela nepodstatných informací. To Tvheadend určitě nějaký čas zabere.
Jednoduše řečeno, oproti původní představě, že parametr -loglevel fatal je lepší vynechat platí, že je minimálně lepší ho tam ponechat. Níže uvádím ukázku logu Tvheadend bez tohoto parametru a s tímto parametrem:
Log Tvheadend bez použití parametru -loglevel fatal - spuštění a zastavení jednoho programu:
a to samé s použitím -loglevel fatal:
Kdo chce jít ještě dál a může úroveň logování omezit ještě více, např. -loglevel panic, či dokonce až na úroveň nejnižší, tedy -loglevel quiet, ale to se samozřejmě v běžném provozu neprojeví. Úroveň fatal je tedy v tomto případě zcela na místě.
Někdo možná namítne, že jde o pouhé ms, v tomto případě je to 233 ms (ono to samozřejmě nemusí nutně znamenat, že těch 233 ms trvá právě jen zpracování výpisu ffmpeg), ale jedna ms k druhé a může to už začít být zajímavé.
A poznámka na závěr. To není samozřejmě všechno. O dalších redukcích se píše v příspěvcích o kterých píší výše (viz -probesize nebo -analyzeduration). Zkusím provést nějaké pokusy a měření případně se tady o tom také rozepíšu.
Při použití v řetězci zpracování stream používáme ffmpeg, dochází k jisté prodlevě při zahájení sledování daného kanálu. To je pochopitelné. Stejně jako je pochopitelné, že se každý snažíme toto zpoždění omezit na nezbytné minimum. Poprvé jsme na fóru toto téma otevřeli ve vlákně o příjmu internetových radií Tvheadend a internetová rádia, kde k tomu účinně přispěli @mobilemanic a @marhyz - viz jejich příspěvky na 1. stránce, které doporučuji si přečíst! Já jsem se teď k tomu vrátil a začal s těmi doporučeními laborovat. Při té příležitosti jsem se zajímal také o parametr -loglevel.
Obecně má člověk tendenci, pokud chce něco zrychlit, tak omezit počet parametrů pod mylným dojmem "méně parametrů - méně funkcí". V případě -loglevel je to ale přesně naopak, tento parametr totiž rozsah funkce ffmpeg redukuje. Vyjmutím parametru probíhá logování na default úrovni, což mimo jiné znamená, že se na výstupu, který je v tomto případě směrován na Tvheadend, před vlastním streamem objeví i známá sada pro tuto chvíli zcela nepodstatných informací. To Tvheadend určitě nějaký čas zabere.
Jednoduše řečeno, oproti původní představě, že parametr -loglevel fatal je lepší vynechat platí, že je minimálně lepší ho tam ponechat. Níže uvádím ukázku logu Tvheadend bez tohoto parametru a s tímto parametrem:
Log Tvheadend bez použití parametru -loglevel fatal - spuštění a zastavení jednoho programu:
Kód:
2018-04-21 11:11:44.100 mpegts: sledovanitv.cz.generic.m3u8 - National Geographic in Sledovanitv.cz - tuning on IPTV
2018-04-21 11:11:44.104 subscription: 00B3: "127.0.0.1 [ | Kodi Media Center ]" subscribing on channel "National Geographic", weight: 150, adapter: "IPTV", network: "Sledovanitv.cz", mux: "sledovanitv.cz.generic.m3u8 - National Geographic", provider: "moderntv.eu", service: "Service01", profile="htsp", hostname="127.0.0.1", username="127.0.0.1", client="Kodi Media Center"
2018-04-21 11:11:44.105 spawn: Executing "/storage/.kodi/addons/service.playlist/provider/sledovanitv.cz/streamer.sh"
2018-04-21 11:11:44.137 spawn: ffmpeg version 8.2.4-7-g84fe69f Copyright (c) 2000-2017 the FFmpeg developers
2018-04-21 11:11:44.137 spawn: built with gcc 6.2.0 (GCC)
2018-04-21 11:11:44.137 spawn: configuration: --prefix=/usr --cpu=x86-64 --arch=x86_64 --enable-cross-compile --cross-prefix=/home/chewitt/LibreELEC.82-images/build.LibreELEC-Generic.x86_64-8.2.5/toolchain/bin/x86_64-libreelec-linux-gnu- --sysroot=/home/chewitt/LibreELEC.82-images/build.LibreELEC-Generic.x86_64-8.2.5/toolchain/x86_64-libreelec-linux-gnu/sysroot --sysinclude=/home/chewitt/LibreELEC.82-images/build.LibreELEC-Generic.x86_64-8.2.5/toolchain/x86_64-libreelec-linux-gnu/sysroot/usr/include --target-os=linux --nm=/home/chewitt/LibreELEC.82-images/build.LibreELEC-Generic.x86_64-8.2.5/toolchain/bin/x86_64-libreelec-linux-gnu-nm --ar=/home/chewitt/LibreELEC.82-images/build.LibreELEC-Generic.x86_64-8.2.5/toolchain/bin/x86_64-libreelec-linux-gnu-ar --as=/home/chewitt/LibreELEC.82-images/build.LibreELEC-Generic.x86_64-8.2.5/toolchain/bin/x86_64-libreelec-linux-gnu-gcc --cc=/home/chewitt/LibreELEC.82-images/build.LibreELEC-Generic.x86_64-8.2.5/toolchain/bin/x86_64-libreelec-linux-gnu-gcc --ld=/home/chewitt/LibreELEC.82-images/b
2018-04-21 11:11:44.137 spawn: libavutil 55. 28.100 / 55. 28.100
2018-04-21 11:11:44.137 spawn: libavcodec 57. 48.101 / 57. 48.101
2018-04-21 11:11:44.137 spawn: libavformat 57. 41.100 / 57. 41.100
2018-04-21 11:11:44.137 spawn: libavdevice 57. 0.101 / 57. 0.101
2018-04-21 11:11:44.137 spawn: libavfilter 6. 47.100 / 6. 47.100
2018-04-21 11:11:44.137 spawn: libswscale 4. 1.100 / 4. 1.100
2018-04-21 11:11:44.137 spawn: libswresample 2. 1.100 / 2. 1.100
2018-04-21 11:11:44.137 spawn: libpostproc 54. 0.100 / 54. 0.100
2018-04-21 11:11:44.358 spawn: Input #0, mpegts, from 'http://sledovanitv.cz/vlc/channel/NGC.vlc?token=XXX':
2018-04-21 11:11:44.358 spawn: Duration: N/A, start: 77161.339333, bitrate: N/A
2018-04-21 11:11:44.358 spawn: Program 1
2018-04-21 11:11:44.358 spawn: Metadata:
2018-04-21 11:11:44.358 spawn: service_name : Service01
2018-04-21 11:11:44.358 spawn: service_provider: moderntv.eu
2018-04-21 11:11:44.358 spawn: Stream #0:0[0x100]: Video: h264 (Main) ([27][0][0][0] / 0x001B), yuv420p, 720x576 [SAR 64:45 DAR 16:9], 25 fps, 25 tbr, 90k tbn, 50 tbc
2018-04-21 11:11:44.360 spawn: Stream #0:1[0x101](cze): Audio: aac (LC) ([15][0][0][0] / 0x000F), 48000 Hz, stereo, fltp, 131 kb/s
2018-04-21 11:11:44.360 spawn: Stream #0:2[0x102](eng): Audio: aac (LC) ([15][0][0][0] / 0x000F), 48000 Hz, stereo, fltp, 119 kb/s
2018-04-21 11:11:44.360 spawn: [mpegts @ 0x143f360] Using AVStream.codec to pass codec parameters to muxers is deprecated, use AVStream.codecpar instead.
2018-04-21 11:11:44.360 spawn: Last message repeated 1 times
2018-04-21 11:11:44.360 spawn: Output #0, mpegts, to 'pipe:1':
2018-04-21 11:11:44.360 spawn: Metadata:
2018-04-21 11:11:44.360 spawn: encoder : Lavf57.41.100
2018-04-21 11:11:44.360 spawn: Stream #0:0: Video: h264 ([27][0][0][0] / 0x001B), yuv420p, 720x576 [SAR 64:45 DAR 16:9], q=2-31, 25 fps, 25 tbr, 90k tbn, 90k tbc
2018-04-21 11:11:44.360 spawn: Stream #0:1(cze): Audio: aac (LC) ([15][0][0][0] / 0x000F), 48000 Hz, stereo, 131 kb/s
2018-04-21 11:11:44.360 spawn: Stream mapping:
2018-04-21 11:11:44.360 spawn: Stream #0:0 -> #0:0 (copy)
2018-04-21 11:11:44.360 spawn: Stream #0:1 -> #0:1 (copy)
2018-04-21 11:11:44.360 spawn: Press [q] to stop, [?] for help
2018-04-21 11:11:44.868 spawn: frame= 14 fps=0.0 q=-1.0 size= 92kB time=00:00:00.54 bitrate=1388.1kbits/s speed=1.06x
2018-04-21 11:11:45.376 spawn: frame= 26 fps= 26 q=-1.0 size= 128kB time=00:00:01.02 bitrate=1023.7kbits/s speed=1.01x
2018-04-21 11:11:45.884 spawn: frame= 39 fps= 26 q=-1.0 size= 189kB time=00:00:01.54 bitrate=1002.6kbits/s speed=1.01x
2018-04-21 11:11:46.392 spawn: frame= 47 fps= 23 q=-1.0 size= 221kB time=00:00:02.06 bitrate= 875.8kbits/s speed=1.02x
2018-04-21 11:11:46.899 spawn: frame= 59 fps= 23 q=-1.0 size= 264kB time=00:00:02.58 bitrate= 837.8kbits/s speed=1.02x
2018-04-21 11:11:47.408 spawn: frame= 75 fps= 25 q=-1.0 size= 319kB time=00:00:03.07 bitrate= 850.9kbits/s speed=1.01x
2018-04-21 11:11:47.916 spawn: frame= 86 fps= 24 q=-1.0 size= 370kB time=00:00:03.58 bitrate= 844.7kbits/s speed=1.01x
2018-04-21 11:11:48.122 subscription: 00B3: "127.0.0.1 [ | Kodi Media Center ]" unsubscribing from "National Geographic", hostname="127.0.0.1", username="127.0.0.1", client="Kodi Media Center"a to samé s použitím -loglevel fatal:
Kód:
2018-04-21 11:19:37.568 mpegts: sledovanitv.cz.generic.m3u8 - National Geographic in Sledovanitv.cz - tuning on IPTV
2018-04-21 11:19:37.576 spawn: Executing "/storage/.kodi/addons/service.playlist/provider/sledovanitv.cz/streamer.sh"
2018-04-21 11:19:37.576 subscription: 00B4: "127.0.0.1 [ | Kodi Media Center ]" subscribing on channel "National Geographic", weight: 150, adapter: "IPTV", network: "Sledovanitv.cz", mux: "sledovanitv.cz.generic.m3u8 - National Geographic", provider: "FFmpeg", service: "Service01", profile="htsp", hostname="127.0.0.1", username="127.0.0.1", client="Kodi Media Center"
2018-04-21 11:19:53.618 subscription: 00B4: "127.0.0.1 [ | Kodi Media Center ]" unsubscribing from "National Geographic", hostname="127.0.0.1", username="127.0.0.1", client="Kodi Media Center"Kdo chce jít ještě dál a může úroveň logování omezit ještě více, např. -loglevel panic, či dokonce až na úroveň nejnižší, tedy -loglevel quiet, ale to se samozřejmě v běžném provozu neprojeví. Úroveň fatal je tedy v tomto případě zcela na místě.
Někdo možná namítne, že jde o pouhé ms, v tomto případě je to 233 ms (ono to samozřejmě nemusí nutně znamenat, že těch 233 ms trvá právě jen zpracování výpisu ffmpeg), ale jedna ms k druhé a může to už začít být zajímavé.
A poznámka na závěr. To není samozřejmě všechno. O dalších redukcích se píše v příspěvcích o kterých píší výše (viz -probesize nebo -analyzeduration). Zkusím provést nějaké pokusy a měření případně se tady o tom také rozepíšu.