Message ID | 20160530141950.GA4268@gmail.com |
---|---|
State | New |
Headers |
Received: from localhost ([127.0.0.1] helo=www.linuxtv.org) by www.linuxtv.org with esmtp (Exim 4.84_2) (envelope-from <vdr-bounces@linuxtv.org>) id 1b7O30-0001U3-R0; Mon, 30 May 2016 14:20:02 +0000 Received: from mail.tu-berlin.de ([130.149.7.33]) by www.linuxtv.org with esmtp (Exim 4.84_2) (envelope-from <glenvt18@gmail.com>) id 1b7O2x-0001Tt-U2 for vdr@linuxtv.org; Mon, 30 May 2016 14:20:00 +0000 X-tubIT-Incoming-IP: 209.85.215.66 Received: from mail-lf0-f66.google.com ([209.85.215.66]) by mail.tu-berlin.de (exim-4.84_2/mailfrontend-7) with esmtps [TLSv1.2:AES128-GCM-SHA256:128] for <vdr@linuxtv.org> id 1b7O2w-0004OB-15; Mon, 30 May 2016 16:19:59 +0200 Received: by mail-lf0-f66.google.com with SMTP id w16so11845499lfd.0 for <vdr@linuxtv.org>; Mon, 30 May 2016 07:19:57 -0700 (PDT) DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=gmail.com; s=20120113; h=date:from:to:subject:message-id:references:mime-version :content-disposition:in-reply-to:user-agent; bh=vqyGJoHHJu9RG48S47ExOWrnFaGENpZg9II4E9iFR/4=; b=pnpyXz5q5nQwo7KsZk5kbJXgIj8DWA8qoqKIouz1FEqJJau1K/bT5gYZcVN448mFeE 6dxvW+GAAIznH2jzSYWtjO95/lQ+LPgqKR0pPgJZnbaiPCIS+IHvPeMiMkHXDeIsMRWc KeS4sPExtjzHBCpIuzmd+VOvoP7QQMzLq1bIYxBZ0MagkywWM/gy4UNo2d7qDB3PCQ+5 znuS1pSyB59qXSLmNR3NujFgXADHuiff2cN2iDG6d/YPrnJ30kaw0htkZU87fw+qm5bJ 6BfIE/xjIOgFuRtpWUlS7C4RzP7lbyDGu+CHo8+IAUDSL751udvGuitc7g4txAM1dc9z BlDg== X-Google-DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=1e100.net; s=20130820; h=x-gm-message-state:date:from:to:subject:message-id:references :mime-version:content-disposition:in-reply-to:user-agent; bh=vqyGJoHHJu9RG48S47ExOWrnFaGENpZg9II4E9iFR/4=; b=M3gO/HWNDCjd4CaoGuqExgHB9lW0pGVl/BAX3yebrehdHOj0aWxLI36jxAcOZJa5Nb C5y2QcOs37SEXGM76X8GEw6Fo9EFRhNN7fkjW1C6tBjhRrg+PXpxgkFh5uuQgMqQb2rn nh8p7sutM7XcL1/fER+xzM1jIdXe09+3Profzg7nM14L+Dvllxsy8PuoJiQayyjiHn00 RkfYaQrtJgNyHCIG5DudPq+OGgPtbRq5nxZ/c/w3fOCqUW2V4QC8a7jCnreXobIiDOR7 Sp1geLSFzo9rkX+/gEAHjprVo4Z+IO6wZucsLoQHeMc4Gt4MuCf8ZXn+cb0HZZoe9S9W KhYQ== X-Gm-Message-State: ALyK8tKiQCI16BGe/ZGOxSdfz/JKDqT78424RVNzM5I6CDy1G6qMeMF7r0NPL8yp+uhU2A== X-Received: by 10.25.168.148 with SMTP id r142mr7043735lfe.18.1464617997284; Mon, 30 May 2016 07:19:57 -0700 (PDT) Received: from gmail.com ([185.135.149.183]) by smtp.gmail.com with ESMTPSA id l72sm4566238lfi.37.2016.05.30.07.19.56 (version=TLSv1/SSLv3 cipher=OTHER); Mon, 30 May 2016 07:19:56 -0700 (PDT) Date: Mon, 30 May 2016 17:19:50 +0300 From: glenvt18 <glenvt18@gmail.com> To: VDR Mailing List <vdr@linuxtv.org> Message-ID: <20160530141950.GA4268@gmail.com> References: <CAJXjwjQNNAEZm5VHscPTj9c=7DsqB8jT0x=fRn-Fc65BUP+eJA@mail.gmail.com> <7e6b1245-7b3a-c84d-f5ec-382154431646@keynet-technology.com> <CAJXjwjSm5iSK_qebOdNpW-nR4UFtc_h0uF5Ai6ze1xjrHvY+AQ@mail.gmail.com> <6dc87d78-0c31-c151-b042-c4cbd583f471@keynet-technology.com> <20160527135108.GA1418@home> <2f0378bf-0b3d-01a3-51ae-85c4c41d31a0@keynet-technology.com> MIME-Version: 1.0 Content-Disposition: inline In-Reply-To: <2f0378bf-0b3d-01a3-51ae-85c4c41d31a0@keynet-technology.com> User-Agent: Mutt/1.5.21 (2010-09-15) X-PMX-Version: 6.0.0.2142326, Antispam-Engine: 2.7.2.2107409, Antispam-Data: 2016.5.30.140916 X-PMX-Spam: Gauge=X, Probability=10%, Report=' TO_IN_SUBJECT 0.5, FROM_NAME_ONE_WORD 0.05, HTML_00_01 0.05, HTML_00_10 0.05, BODY_SIZE_3000_3999 0, BODY_SIZE_5000_LESS 0, BODY_SIZE_7000_LESS 0, DKIM_SIGNATURE 0, IN_REP_TO 0, LEGITIMATE_NEGATE 0, MSG_THREAD 0, NO_URI_HTTPS 0, REFERENCES 0, WEBMAIL_SOURCE 0, __ANY_URI 0, __BOUNCE_CHALLENGE_SUBJ 0, __BOUNCE_NDR_SUBJ_EXEMPT 0, __CD 0, __CP_URI_IN_BODY 0, __CT 0, __CT_TEXT_PLAIN 0, __DATE_TZ_RU 0, __DQ_NEG_HEUR 0, __DQ_NEG_IP 0, __FORWARDED_MSG 0, __FRAUD_WEBMAIL 0, __FRAUD_WEBMAIL_FROM 0, __FROM_GMAIL 0, __HAS_FROM 0, __HAS_MSGID 0, __IN_REP_TO 0, __MAL_TELEKOM_URI 0, __MIME_TEXT_ONLY 0, __MIME_VERSION 0, __MULTIPLE_URI_TEXT 0, __PHISH_SPEAR_STRUCTURE_1 0, __RDNS_GMAIL 0, __REFERENCES 0, __SANE_MSGID 0, __STOCK_PHRASE_7 0, __SUBJ_ALPHA_END 0, __SUBJ_ALPHA_NEGATE 0, __TO_IN_SUBJECT2 0, __TO_MALFORMED_2 0, __URI_IN_BODY 0, __URI_NS , __URI_WITH_PATH 0, __USER_AGENT 0, __YOUTUBE_RCVD 0' X-LSpam-Score: 0.8 (/) X-LSpam-Report: No, score=0.8 required=5.0 tests=FREEMAIL_ENVFROM_END_DIGIT=0.25, FREEMAIL_FROM=0.001, RCVD_IN_DNSWL_LOW=-0.7, RCVD_IN_MSPIKE_H3=-0.01, RCVD_IN_MSPIKE_WL=-0.01, RDNS_NONE=1.274, T_DKIM_INVALID=0.01 autolearn=no autolearn_force=no Subject: Re: [vdr] [PATCH] Device power saving feature X-BeenThere: vdr@linuxtv.org X-Mailman-Version: 2.1.18 Precedence: list List-Id: VDR Mailing List <vdr.linuxtv.org> List-Unsubscribe: <http://www.linuxtv.org/cgi-bin/mailman/options/vdr>, <mailto:vdr-request@linuxtv.org?subject=unsubscribe> List-Archive: <http://www.linuxtv.org/pipermail/vdr/> List-Post: <mailto:vdr@linuxtv.org> List-Help: <mailto:vdr-request@linuxtv.org?subject=help> List-Subscribe: <http://www.linuxtv.org/cgi-bin/mailman/listinfo/vdr>, <mailto:vdr-request@linuxtv.org?subject=subscribe> Reply-To: VDR Mailing List <vdr@linuxtv.org> Content-Type: text/plain; charset="utf-8" Content-Transfer-Encoding: base64 Errors-To: vdr-bounces@linuxtv.org Sender: "vdr" <vdr-bounces@linuxtv.org> |
Commit Message
glenvt18
May 30, 2016, 2:19 p.m. UTC
Could you attach (or, better, upload to pastebin.com) the whole log file. Are you running vdr with --log=3? I need to see 'idle timer' debug messages and some other debug stuff. Could you also apply this change -- Thanks for testing. Best, Sergey Chernyavskiy. On Mon, May 30, 2016 at 09:59:46AM +0100, Richard F wrote: > Thanks - I built it and and have had it running for about 24 hours: > > A couple of observations: > I'm seeing this about every 30 - 60 mins as expected, but the timeout > seems to be short - not the PowerdownTimeoutM (I assume minutes?) set at > 15 in the config file > > ... > > I haven't had a chance to take the server down to insert the power > monitor yet, but will do so at convenient time in the next few days > > Thanks > Richard > > > On 27/05/2016 14:51, glenvt18 wrote: > > Yes, sure. The patch against VDR-2.2.0: > > http://pastebin.com/D4VQd1rG > > > > While testing set VDR log level to 3 (--log=3) and use this helper > > script to monitor your frontends: > > http://pastebin.com/uW4NW0rZ > > > > Don't forget to enable power saving in the Setup->LNB menu. > > > > If you don't have sudo (you're not on Debian or Ubuntu), remove it and > > run the script as root. It would be great if you could measure the > > power consumption of you tuners (at least the USB one). > > > > Best, > > Sergey Chernyavskiy. > > > > On Fri, May 27, 2016 at 09:59:06AM +0100, Richard F wrote: > >> I'd like to give it a go on DVB & DVBT-2 tuners (1 PCI, 1 USB) > >> Would you be kind enough to post a version for VDR2.20 or advise changes > >> necessary? > >> > >> Thanks > >> Richard > >> > >> > >> On 26/05/2016 18:44, glenvt18 wrote: > >>> For about a month now. Tested with 3 different DVB-S/S2 tuners with > >>> VDR 2.3.1 and VDR 2.2.0 (requires minor changes to the patch) - rock > >>> solid. > >>> I don't have VPS, so I can't test it. The same is about DVB-T/C > >>> tuners. After all, if something doesn't work this feature can be > >>> disabled (and it is disabled by default), > >>> > >> > >> _______________________________________________ > >> vdr mailing list > >> vdr@linuxtv.org > >> http://www.linuxtv.org/cgi-bin/mailman/listinfo/vdr > > > > > _______________________________________________ > vdr mailing list > vdr@linuxtv.org > http://www.linuxtv.org/cgi-bin/mailman/listinfo/vdr
Comments
OK Sergey, I've posted a log with your patch applied + log level 3 - about 3 hours worth There was a recording 16:15 to 16:45, other than that idle I can run it for longer if you need it, though it's creating a fair bit of log. I seem to get a lot of erroneous EPGsearch results at level 3, not sure why. http://pastebin.com/8DbYQdTX Let me know what it tells you Thanks Richard On 30/05/2016 15:19, glenvt18 wrote: > Could you attach (or, better, upload to pastebin.com) the whole log > file. Are you running vdr with --log=3? I need to see 'idle timer' debug > messages and some other debug stuff. Could you also apply this change > > diff --git a/eitscan.c b/eitscan.c > index 3899e00..b1c8c13 100644 > --- a/eitscan.c > +++ b/eitscan.c > @@ -159,7 +159,7 @@ void cEITScanner::Process(void) > Skins.Message(mtInfo, tr("Starting EPG scan")); > } > } > - //dsyslog("EIT scan: device %d source %-8s tp %5d", Device->DeviceNumber() + 1, *cSource::ToString(Channel->Source()), Channel->Transponder()); > + dsyslog("EIT scan: device %d source %-8s tp %5d", Device->DeviceNumber() + 1, *cSource::ToString(Channel->Source()), Channel->Transponder()); > if (lastActivity == 0) > // forced scan - set idle timer for each channel switch; > // this prevents powering down while scanning a transponder > -- > > Thanks for testing. > > Best, > Sergey Chernyavskiy.
From what I can see, everything works as expected. You can safely lessen the power-down timeout to 3 minutes. This will be enough for EPG scan as you have at most 8 transponders/multiplexes which gives 8 * 1/3 = 2m40s - see 'EIT scan ...' messages. As for those small intervals of activity you mentioned, it happens because some 'preparation' work is done before a timer starts. VDR tries to switch to the timer's transponder and gather EPG data. For a VPS timer this begins within VPS margin and an hour before the timer start time. For a non-VPS timer it begins one minute before the timer start time. I've added 30 seconds to the occupied timeout (see my cDevice::SetOccupied() comment) to avoid frequent on/off switching. So, you're likely to see 38-second (or, sometimes, a bit more) periods of activity. If you still worry about it, keep you server idle for 2 hours before a nearest VPS timer starts and paste the log. I've removed 'sudo' from the monitor script: http://pastebin.com/8JDffAXF Run it as user 'vdr' for some time. It will draw you a nice 'map' of your devices' activity. If you notice channel switch failures, let me know. Do your tuners get warm when active? Thanks again for testing. Best, Sergey Chernyavskiy. On Tue, May 31, 2016 at 08:43:01PM +0100, Richard F wrote: > OK Sergey, I've posted a log with your patch applied + log level 3 - > about 3 hours worth There was a recording 16:15 to 16:45, other than > that idle I can run it for longer if you need it, though it's creating > a fair bit of log. I seem to get a lot of erroneous EPGsearch results > at level 3, not sure why. http://pastebin.com/8DbYQdTX > > Let me know what it tells you Thanks Richard > > > On 30/05/2016 15:19, glenvt18 wrote: > > Could you attach (or, better, upload to pastebin.com) the whole log > > file. Are you running vdr with --log=3? I need to see 'idle timer' > > debug messages and some other debug stuff. Could you also apply this > > change > > > > diff --git a/eitscan.c b/eitscan.c index 3899e00..b1c8c13 100644 --- > > a/eitscan.c +++ b/eitscan.c @@ -159,7 +159,7 @@ void > > cEITScanner::Process(void) Skins.Message(mtInfo, tr("Starting EPG > > scan")); } } - //dsyslog("EIT > > scan: device %d source %-8s tp %5d", Device->DeviceNumber() + 1, > > *cSource::ToString(Channel->Source()), Channel->Transponder()); + > > dsyslog("EIT scan: device %d source %-8s tp %5d", > > Device->DeviceNumber() + 1, *cSource::ToString(Channel->Source()), > > Channel->Transponder()); if (lastActivity == 0) // forced scan - set > > idle timer for each channel switch; // this prevents powering down > > while scanning a transponder -- > > > > Thanks for testing. > > > > Best, Sergey Chernyavskiy. > > > > _______________________________________________ vdr mailing list > vdr@linuxtv.org http://www.linuxtv.org/cgi-bin/mailman/listinfo/vdr
On 1/06/2016 03:49, glenvt18 wrote: > >From what I can see, everything works as expected. You can safely lessen > the power-down timeout to 3 minutes. This will be enough for EPG scan as > you have at most 8 transponders/multiplexes which gives 8 * 1/3 = 2m40s > - see 'EIT scan ...' messages. I reduced it to 5 mins to give it some margin > As for those small intervals of activity you mentioned, it happens > because some 'preparation' work is done before a timer starts. VDR > tries to switch to the timer's transponder and gather EPG data. For a > VPS timer this begins within VPS margin and an hour before the timer > start time. For a non-VPS timer it begins one minute before the timer > start time. I've added 30 seconds to the occupied timeout (see my > cDevice::SetOccupied() comment) to avoid frequent on/off switching. So, > you're likely to see 38-second (or, sometimes, a bit more) periods of > activity. If you still worry about it, keep you server idle for 2 hours > before a nearest VPS timer starts and paste the log. OK, I'll keep an eye on it. No adverse effects yet - good work, thanks. I'm doing some other testing and stuff atm so want to keep the server up - I'll possibly take it down at the weekend, make some power measurements and post them > I've removed 'sudo' from the monitor script: > http://pastebin.com/8JDffAXF > > Run it as user 'vdr' for some time. It will draw you a nice 'map' of > your devices' activity. > > If you notice channel switch failures, let me know. > > Do your tuners get warm when active? Yes, the TV tuners always did run warm, and I recall that when VDR is stopped the power is several watts lower (not because of VDR/CPU load itself according to stats, it's when the tuners go active - hence my interest in your patch). As the server also runs the home automation/lights/heating/security, CCTV, mail server, and file storage/Owncloud it's not an option to shut it down when not recording, and I have t choose when to take it down ! Richard > > Thanks again for testing. > > Best, > Sergey Chernyavskiy. > > On Tue, May 31, 2016 at 08:43:01PM +0100, Richard F wrote: >> OK Sergey, I've posted a log with your patch applied + log level 3 - >> about 3 hours worth There was a recording 16:15 to 16:45, other than >> that idle I can run it for longer if you need it, though it's creating >> a fair bit of log. I seem to get a lot of erroneous EPGsearch results >> at level 3, not sure why. http://pastebin.com/8DbYQdTX >> >> Let me know what it tells you Thanks Richard >> >> >> On 30/05/2016 15:19, glenvt18 wrote: >>> Could you attach (or, better, upload to pastebin.com) the whole log >>> file. Are you running vdr with --log=3? I need to see 'idle timer' >>> debug messages and some other debug stuff. Could you also apply this >>> change >>> >>> diff --git a/eitscan.c b/eitscan.c index 3899e00..b1c8c13 100644 --- >>> a/eitscan.c +++ b/eitscan.c @@ -159,7 +159,7 @@ void >>> cEITScanner::Process(void) Skins.Message(mtInfo, tr("Starting EPG >>> scan")); } } - //dsyslog("EIT >>> scan: device %d source %-8s tp %5d", Device->DeviceNumber() + 1, >>> *cSource::ToString(Channel->Source()), Channel->Transponder()); + >>> dsyslog("EIT scan: device %d source %-8s tp %5d", >>> Device->DeviceNumber() + 1, *cSource::ToString(Channel->Source()), >>> Channel->Transponder()); if (lastActivity == 0) // forced scan - set >>> idle timer for each channel switch; // this prevents powering down >>> while scanning a transponder -- >>> >>> Thanks for testing. >>> >>> Best, Sergey Chernyavskiy. >> >> >> _______________________________________________ vdr mailing list >> vdr@linuxtv.org http://www.linuxtv.org/cgi-bin/mailman/listinfo/vdr >
> As the server also runs the home automation/lights/heating/security, > CCTV, mail server, and file storage/Owncloud ... That's the main purpose of this feature. You don't have to switch off your tuners physically, close your connections or put your server into sleep (and ARM devices usually don't have a sleep mode at all). You can stream your recordings, watch movies and do other stuff with your server and, at the same time, keep your tuners cold. > OK, I'll keep an eye on it. No adverse effects yet - good work, thanks. I've modified the monitor script: http://pastebin.com/DR4gUdZR Now it prints timestamps and it's output can be searched and grep-ed. Run it with '|tee' for several days. Best, Sergey Chernyavskiy. On Thu, Jun 02, 2016 at 03:35:49PM +0100, Richard F wrote: > On 1/06/2016 03:49, glenvt18 wrote: > > >From what I can see, everything works as expected. You can safely lessen > > the power-down timeout to 3 minutes. This will be enough for EPG scan as > > you have at most 8 transponders/multiplexes which gives 8 * 1/3 = 2m40s > > - see 'EIT scan ...' messages. > I reduced it to 5 mins to give it some margin > > As for those small intervals of activity you mentioned, it happens > > because some 'preparation' work is done before a timer starts. VDR > > tries to switch to the timer's transponder and gather EPG data. For a > > VPS timer this begins within VPS margin and an hour before the timer > > start time. For a non-VPS timer it begins one minute before the timer > > start time. I've added 30 seconds to the occupied timeout (see my > > cDevice::SetOccupied() comment) to avoid frequent on/off switching. So, > > you're likely to see 38-second (or, sometimes, a bit more) periods of > > activity. If you still worry about it, keep you server idle for 2 hours > > before a nearest VPS timer starts and paste the log. > OK, I'll keep an eye on it. No adverse effects yet - good work, thanks. > > I'm doing some other testing and stuff atm so want to keep the server up - > I'll possibly take it down at the weekend, make some power measurements > and post them > > I've removed 'sudo' from the monitor script: > > http://pastebin.com/8JDffAXF > > > > Run it as user 'vdr' for some time. It will draw you a nice 'map' of > > your devices' activity. > > > > If you notice channel switch failures, let me know. > > > > Do your tuners get warm when active? > Yes, the TV tuners always did run warm, and I recall that when VDR is > stopped the power is several watts lower (not because of VDR/CPU load > itself according to stats, it's when the tuners go active - hence my > interest in your patch). > > As the server also runs the home automation/lights/heating/security, > CCTV, mail server, and file storage/Owncloud it's not an option to shut > it down when not recording, and I have t choose when to take it down ! > > Richard > > > > Thanks again for testing. > > > > Best, > > Sergey Chernyavskiy. > > > > On Tue, May 31, 2016 at 08:43:01PM +0100, Richard F wrote: > >> OK Sergey, I've posted a log with your patch applied + log level 3 - > >> about 3 hours worth There was a recording 16:15 to 16:45, other than > >> that idle I can run it for longer if you need it, though it's creating > >> a fair bit of log. I seem to get a lot of erroneous EPGsearch results > >> at level 3, not sure why. http://pastebin.com/8DbYQdTX > >> > >> Let me know what it tells you Thanks Richard > >> > >> > >> On 30/05/2016 15:19, glenvt18 wrote: > >>> Could you attach (or, better, upload to pastebin.com) the whole log > >>> file. Are you running vdr with --log=3? I need to see 'idle timer' > >>> debug messages and some other debug stuff. Could you also apply this > >>> change > >>> > >>> diff --git a/eitscan.c b/eitscan.c index 3899e00..b1c8c13 100644 --- > >>> a/eitscan.c +++ b/eitscan.c @@ -159,7 +159,7 @@ void > >>> cEITScanner::Process(void) Skins.Message(mtInfo, tr("Starting EPG > >>> scan")); } } - //dsyslog("EIT > >>> scan: device %d source %-8s tp %5d", Device->DeviceNumber() + 1, > >>> *cSource::ToString(Channel->Source()), Channel->Transponder()); + > >>> dsyslog("EIT scan: device %d source %-8s tp %5d", > >>> Device->DeviceNumber() + 1, *cSource::ToString(Channel->Source()), > >>> Channel->Transponder()); if (lastActivity == 0) // forced scan - set > >>> idle timer for each channel switch; // this prevents powering down > >>> while scanning a transponder -- > >>> > >>> Thanks for testing. > >>> > >>> Best, Sergey Chernyavskiy. > >> > >> > >> _______________________________________________ vdr mailing list > >> vdr@linuxtv.org http://www.linuxtv.org/cgi-bin/mailman/listinfo/vdr > > > > > _______________________________________________ > vdr mailing list > vdr@linuxtv.org > http://www.linuxtv.org/cgi-bin/mailman/listinfo/vdr
Sergey, I took down the server at the weekend to install a newly built kernel and insert the power monitor. 47W - All services running at idle, tuner power saving disabled 44W - All services running at idle, tuner power saving enabled As tuners are powered down most of the time, that's a handy 6% average saving. The hardware is an HP 8300CMT with high efficiency PSU, G2130 Ivy bridge CPU and a 3 x 2T RAID + KMC4400R for CCTV video capture, the tuners are PCTV Nanostick 290e for HD/SD (USB2) and an old Leadtek DTV1000 T (PCI) for SD - both of which are super-reliable BTW. Obviously other tuners will vary, and if the PSU isn't the efficient 90% one like this, the savings will be marginally more. Richard On 2/06/2016 17:15, glenvt18 wrote: >> As the server also runs the home automation/lights/heating/security, >> CCTV, mail server, and file storage/Owncloud ... > That's the main purpose of this feature. You don't have to switch off > your tuners physically, close your connections or put your server into > sleep (and ARM devices usually don't have a sleep mode at all). You can > stream your recordings, watch movies and do other stuff with your server > and, at the same time, keep your tuners cold. > >> OK, I'll keep an eye on it. No adverse effects yet - good work, thanks. > I've modified the monitor script: > http://pastebin.com/DR4gUdZR > > Now it prints timestamps and it's output can be searched and grep-ed. > Run it with '|tee' for several days. > > Best, > Sergey Chernyavskiy. >
Hi Richard, Unlike DVB-S/S2 tuners your DVB-T/T2 ones don't have to supply power to a LNB (which draws at least 2W). So, your 3W drop can be considered as "normal". For your USB stick even 1W saving can be helpful. Thanks again for testing. Sergey. On Mon, Jun 06, 2016 at 11:21:24AM +0100, Richard F wrote: > Sergey, > > I took down the server at the weekend to install a newly built kernel > and insert the power monitor. > > 47W - All services running at idle, tuner power saving disabled > 44W - All services running at idle, tuner power saving enabled > > As tuners are powered down most of the time, that's a handy 6% average > saving. > > The hardware is an HP 8300CMT with high efficiency PSU, G2130 Ivy bridge > CPU and a 3 x 2T RAID + KMC4400R for CCTV video capture, the tuners are > PCTV Nanostick 290e for HD/SD (USB2) and an old Leadtek DTV1000 T (PCI) > for SD - both of which are super-reliable BTW. > > Obviously other tuners will vary, and if the PSU isn't the efficient 90% > one like this, the savings will be marginally more. > > Richard > > > On 2/06/2016 17:15, glenvt18 wrote: > >> As the server also runs the home automation/lights/heating/security, > >> CCTV, mail server, and file storage/Owncloud ... > > That's the main purpose of this feature. You don't have to switch off > > your tuners physically, close your connections or put your server into > > sleep (and ARM devices usually don't have a sleep mode at all). You can > > stream your recordings, watch movies and do other stuff with your server > > and, at the same time, keep your tuners cold. > > > >> OK, I'll keep an eye on it. No adverse effects yet - good work, thanks. > > I've modified the monitor script: > > http://pastebin.com/DR4gUdZR > > > > Now it prints timestamps and it's output can be searched and grep-ed. > > Run it with '|tee' for several days. > > > > Best, > > Sergey Chernyavskiy. > > > > > _______________________________________________ > vdr mailing list > vdr@linuxtv.org > http://www.linuxtv.org/cgi-bin/mailman/listinfo/vdr
I'm happy with just a modest power saving. Put in context, on my system, 3Wh ~ 25 mins SD content transcoding to H264/AAC = 30-40% disk space reduction with no noticeable loss of quality. So ~70Wh saving / day pays for ongoing SD recording compression and multi-TB historical library compression in about 3 months. I free up a 1TB or so of RAID :-) Thanks Richard On 7/06/2016 14:45, glenvt18 wrote: > Hi Richard, > > Unlike DVB-S/S2 tuners your DVB-T/T2 ones don't have to supply power to > a LNB (which draws at least 2W). So, your 3W drop can be considered as > "normal". For your USB stick even 1W saving can be helpful. > > Thanks again for testing. > > Sergey.
That's cool:) On Wed, Jun 08, 2016 at 05:23:43PM +0100, Richard F wrote: > I'm happy with just a modest power saving. > > Put in context, on my system, 3Wh ~ 25 mins SD content transcoding to > H264/AAC = 30-40% disk space reduction with no noticeable loss of > quality. So ~70Wh saving / day pays for ongoing SD recording > compression and multi-TB historical library compression in about 3 > months. I free up a 1TB or so of RAID :-) > > Thanks > Richard > > > On 7/06/2016 14:45, glenvt18 wrote: > > Hi Richard, > > > > Unlike DVB-S/S2 tuners your DVB-T/T2 ones don't have to supply power to > > a LNB (which draws at least 2W). So, your 3W drop can be considered as > > "normal". For your USB stick even 1W saving can be helpful. > > > > Thanks again for testing. > > > > Sergey. > > > _______________________________________________ > vdr mailing list > vdr@linuxtv.org > http://www.linuxtv.org/cgi-bin/mailman/listinfo/vdr
Hi, I've tried this as well now. I have two Cine S2 dual-tuner cards (an older 5.5 running on ngene driver and a 6.5 running with the ddbridge driver) attached to a cascadable multiswitch. The VDR (2.2) runs as a headless server with the vnsi plugin to allow to connect Kodi clients to the VDR. With all adapters active, I see a power consumption of about 50W. When your power-saving patch kicks in, this is reduced by about 7W to 43W. So far this seems to work fine. Thanks for this patch! +1 for including this into vanilla VDR. bye, Tobias
Hi Sergey, My VDR setup started having a bit of a fit last night, and has been doing this for the last 8 hours (no changes my end) Before I restart VDR, any tests I can do to diagnose? It seems to have started after a live stream (Kodi/VNSI) + a recording stopped - here's the VDR-related log from where the connection started to where the problem seemed to start It may be that the VNSI plugin is keeping the receiver active ? (the VNSI-Error: cxSocket::read error occurs 2 or 3 times a day - not unusual, nor is any other log entry) Jun 24 01:12:59 ha-server vdr: [8690] loading /etc/vdr/plugins/vnsiserver/allowed_hosts.conf Jun 24 01:12:59 ha-server vdr: [8690] VNSI: Client with ID 75 connected: 10.0.0.90:52606 Jun 24 01:12:59 ha-server vdr: [15789] VNSI: Welcome client 'XBMC Media Center' with protocol version '8' Jun 24 01:12:59 ha-server vdr: [15789] VNSI: LiveStreamer::Close - close Jun 24 01:12:59 ha-server vdr: [15789] VNSI: close video input ... Jun 24 01:12:59 ha-server vdr: [15789] VNSI: Successfully found following device: 0x91cef0 (1) for receiving Jun 24 01:12:59 ha-server vdr: [15789] dvb tuner: power-up - opening frontend 0/0 Jun 24 01:12:59 ha-server vdr: [15789] VNSI: activate live receiver: 1, pmt change: 1 Jun 24 01:12:59 ha-server vdr: [15789] VNSI: Successfully switched to channel 1 - BBC ONE Lon Jun 24 01:12:59 ha-server vdr: [15789] VNSI: Started streaming of channel BBC ONE Lon (timeout 5 seconds) Jun 24 01:12:59 ha-server vdr: [15793] VNSI: Created stream for pid=101 and type=7 Jun 24 01:12:59 ha-server vdr: [15793] VNSI: Created stream for pid=102 and type=2 Jun 24 01:12:59 ha-server vdr: [15793] VNSI: Created stream for pid=106 and type=2 Jun 24 01:12:59 ha-server vdr: [15793] VNSI: Created stream for pid=105 and type=9 Jun 24 01:13:00 ha-server vdr: [8686] channel 3 (BBC TWO) event Fri 24.06.2016 01:05-02:05 (VPS: 24.06. 01:05) 'The Big C & Me' status 4 Jun 24 01:13:05 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 Jun 24 01:13:10 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 Jun 24 01:13:46 ha-server vdr: [15789] VNSI-Error: cxSocket::read(fd=18): read() error at 0/4 Jun 24 01:13:46 ha-server vdr: [15793] VNSI: exit streamer thread Jun 24 01:13:46 ha-server vdr: [15789] VNSI: LiveStreamer::Close - close Jun 24 01:13:46 ha-server vdr: [15789] VNSI: close video input ... Jun 24 01:13:46 ha-server vdr: [15789] VNSI: activate live receiver: 0, pmt change: 0 Jun 24 01:13:46 ha-server vdr: [15789] VNSI: close video input ... Jun 24 01:13:46 ha-server vdr: [8692] VNSI: Client with ID 75 seems to be disconnected, removing from client list Jun 24 01:13:49 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 Jun 24 01:13:51 ha-server vdr: [8690] loading /etc/vdr/plugins/vnsiserver/allowed_hosts.conf Jun 24 01:13:51 ha-server vdr: [8690] VNSI: Client with ID 76 connected: 10.0.0.90:52610 Jun 24 01:13:51 ha-server vdr: [15824] VNSI: Welcome client 'XBMC Media Center' with protocol version '8' Jun 24 01:13:51 ha-server vdr: [15824] VNSI: LiveStreamer::Close - close Jun 24 01:13:51 ha-server vdr: [15824] VNSI: close video input ... Jun 24 01:13:51 ha-server vdr: [15824] VNSI: Successfully found following device: 0x91cef0 (1) for receiving Jun 24 01:13:51 ha-server vdr: [15824] VNSI: activate live receiver: 1, pmt change: 1 Jun 24 01:13:51 ha-server vdr: [15824] VNSI: Successfully switched to channel 1 - BBC ONE Lon Jun 24 01:13:51 ha-server vdr: [15824] VNSI: Started streaming of channel BBC ONE Lon (timeout 5 seconds) Jun 24 01:13:52 ha-server vdr: [15826] VNSI: Created stream for pid=101 and type=7 Jun 24 01:13:52 ha-server vdr: [15826] VNSI: Created stream for pid=102 and type=2 Jun 24 01:13:52 ha-server vdr: [15826] VNSI: Created stream for pid=106 and type=2 Jun 24 01:13:52 ha-server vdr: [15826] VNSI: Created stream for pid=105 and type=9 Jun 24 01:13:54 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 Jun 24 01:13:59 ha-server vdr: [7056] VNSI-Error: cxSocket::read(fd=6): read() error at 0/4 Jun 24 01:13:59 ha-server vdr: [8692] VNSI: Client with ID 72 seems to be disconnected, removing from client list Jun 24 01:14:33 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 Jun 24 01:14:38 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 Jun 24 01:15:17 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 Jun 24 01:15:22 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 Jun 24 01:16:01 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 Jun 24 01:16:06 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 Jun 24 01:16:43 ha-server vdr: [15824] VNSI-Error: cxSocket::read(fd=8): read() error at 0/4 Jun 24 01:16:43 ha-server vdr: [15826] VNSI: exit streamer thread Jun 24 01:16:43 ha-server vdr: [15824] VNSI: LiveStreamer::Close - close Jun 24 01:16:43 ha-server vdr: [15824] VNSI: close video input ... Jun 24 01:16:43 ha-server vdr: [15824] VNSI: activate live receiver: 0, pmt change: 0 Jun 24 01:16:43 ha-server vdr: [15824] VNSI: close video input ... Jun 24 01:16:43 ha-server vdr: [8692] VNSI: Client with ID 76 seems to be disconnected, removing from client list Jun 24 01:16:45 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 Jun 24 01:16:50 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 Jun 24 01:16:56 ha-server vdr: [8690] loading /etc/vdr/plugins/vnsiserver/allowed_hosts.conf Jun 24 01:16:56 ha-server vdr: [8690] VNSI: Client with ID 77 connected: 10.0.0.90:52614 Jun 24 01:16:56 ha-server vdr: [16255] VNSI: Welcome client 'XBMC Media Center' with protocol version '8' Jun 24 01:16:56 ha-server vdr: [16255] VNSI: LiveStreamer::Close - close Jun 24 01:16:56 ha-server vdr: [16255] VNSI: close video input ... Jun 24 01:16:56 ha-server vdr: [16255] VNSI: Successfully found following device: 0x91cef0 (1) for receiving Jun 24 01:16:56 ha-server vdr: [16255] VNSI: activate live receiver: 1, pmt change: 1 Jun 24 01:16:56 ha-server vdr: [16255] VNSI: Successfully switched to channel 20 - ITV Jun 24 01:16:56 ha-server vdr: [16255] VNSI: Started streaming of channel ITV (timeout 5 seconds) Jun 24 01:16:57 ha-server vdr: [16257] VNSI: Created stream for pid=1701 and type=7 Jun 24 01:16:57 ha-server vdr: [16257] VNSI: Created stream for pid=1702 and type=2 Jun 24 01:16:57 ha-server vdr: [16257] VNSI: Created stream for pid=1703 and type=2 Jun 24 01:16:57 ha-server vdr: [16257] VNSI: Created stream for pid=1731 and type=9 Jun 24 01:17:29 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 Jun 24 01:35:42 ha-server vdr: [8686] channel 45 (Film4) event Fri 24.06.2016 01:35-03:50 (VPS: 24.06. 01:35) 'Greenberg' status 4 Jun 24 01:35:45 ha-server vdr: [8679] timer 1 (45 1635-1850 VPS '-Films~The Spy Who Came In from the Cold~2016.06.23-16:35-Thu') set to no event Jun 24 01:36:11 ha-server vdr: [8679] deleting timer 1 (45 1635-1850 VPS '-Films~The Spy Who Came In from the Cold~2016.06.23-16:35-Thu') Jun 24 01:36:11 ha-server vdr: [8692] VNSI: Timers state changed (71) Jun 24 01:36:11 ha-server vdr: [8692] VNSI: Requesting clients to reload timers Jun 24 02:15:32 ha-server vdr: [16255] VNSI-Error: cxSocket::read(fd=23): read() error at 0/4 Jun 24 02:15:33 ha-server vdr: [16257] VNSI: exit streamer thread Jun 24 02:15:33 ha-server vdr: [16255] VNSI: LiveStreamer::Close - close Jun 24 02:15:33 ha-server vdr: [16255] VNSI: close video input ... Jun 24 02:15:33 ha-server vdr: [16255] VNSI: activate live receiver: 0, pmt change: 0 Jun 24 02:15:33 ha-server vdr: [16255] VNSI: close video input ... Jun 24 02:15:33 ha-server vdr: [8692] VNSI: Client with ID 77 seems to be disconnected, removing from client list Jun 24 02:15:40 ha-server vdr: [15117] VNSI: cxSocket::read(fd=17): eof, connection closed Jun 24 02:15:40 ha-server vdr: [8692] VNSI: Client with ID 74 seems to be disconnected, removing from client list Jun 24 02:20:34 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 0/0 Jun 24 02:20:38 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 Jun 24 02:21:17 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 Jun 24 02:21:22 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 Jun 24 02:22:01 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 Jun 24 02:22:06 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 ..... Jun 25 09:50:17 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 Jun 25 09:50:22 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 Jun 25 09:51:01 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 Jun 25 09:51:06 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 Jun 25 09:51:45 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 Jun 25 09:51:50 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 Jun 25 09:52:29 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 Thanks Richard On 7/06/2016 14:45, glenvt18 wrote: > Hi Richard, > > Unlike DVB-S/S2 tuners your DVB-T/T2 ones don't have to supply power to > a LNB (which draws at least 2W). So, your 3W drop can be considered as > "normal". For your USB stick even 1W saving can be helpful. > > Thanks again for testing. > > Sergey. > > On Mon, Jun 06, 2016 at 11:21:24AM +0100, Richard F wrote:
Hi Richard, As there are no debug messages in your log I tried to reconstruct some of them (power-related) - see my comments. Note that an extra second is added to all timeouts due to the 'greater than' comparison. It was done on purpose to add some save margin. Power-saving looks to work OK. But. I don't see any 'timer ... start/stop' or 'record ...' messages in your log. So, I have some questions: 1) Did you fail to record 'The Big C & Me' at 01:05 and 'Greenberg' at 01:35? 2) Did you cancel 'Greenberg'? 3) Was your streaming interrupted? 4) What is your vnsi streaming priority? 5) What is your recording priority? 6) Are the channels you recorded and the channels you streamed on the same transponder? I'm asking this because I see device 1 being idle all that time. 7) What happens with your VDR when a vnsi streaming is interrupted by a higher priority recording on the same device but on another transponder? 8) What timers (their dates) did you have from Jun 24 00:00 to Jun 24 04:00? 9) What is your Vps Margin setting? > Jun 24 01:12:59 ha-server vdr: [8690] loading /etc/vdr/plugins/vnsiserver/allowed_hosts.conf > Jun 24 01:12:59 ha-server vdr: [8690] VNSI: Client with ID 75 connected: 10.0.0.90:52606 > Jun 24 01:12:59 ha-server vdr: [15789] VNSI: Welcome client 'XBMC Media Center' with protocol version '8' > Jun 24 01:12:59 ha-server vdr: [15789] VNSI: LiveStreamer::Close - close > Jun 24 01:12:59 ha-server vdr: [15789] VNSI: close video input ... > Jun 24 01:12:59 ha-server vdr: [15789] VNSI: Successfully found following device: 0x91cef0 (1) for receiving > Jun 24 01:12:59 ha-server vdr: [15789] dvb tuner: power-up - opening frontend 0/0 streaming started on device 0, wake up device 0 > Jun 24 01:12:59 ha-server vdr: [15789] VNSI: activate live receiver: 1, pmt change: 1 > Jun 24 01:12:59 ha-server vdr: [15789] VNSI: Successfully switched to channel 1 - BBC ONE Lon > Jun 24 01:12:59 ha-server vdr: [15789] VNSI: Started streaming of channel BBC ONE Lon (timeout 5 seconds) > Jun 24 01:12:59 ha-server vdr: [15793] VNSI: Created stream for pid=101 and type=7 > Jun 24 01:12:59 ha-server vdr: [15793] VNSI: Created stream for pid=102 and type=2 > Jun 24 01:12:59 ha-server vdr: [15793] VNSI: Created stream for pid=106 and type=2 > Jun 24 01:12:59 ha-server vdr: [15793] VNSI: Created stream for pid=105 and type=9 > Jun 24 01:13:00 ha-server vdr: [8686] channel 3 (BBC TWO) event Fri 24.06.2016 01:05-02:05 (VPS: 24.06. 01:05) 'The Big C & Me' status 4 > Jun 24 01:13:05 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 > Jun 24 01:13:10 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 tune to a transponder on device 1 to refresh EPG before a (VPS) timer starts, call cDevice::SetOccupied(), set device 1 idle timer to 38 sec > Jun 24 01:13:46 ha-server vdr: [15789] VNSI-Error: cxSocket::read(fd=18): read() error at 0/4 > Jun 24 01:13:46 ha-server vdr: [15793] VNSI: exit streamer thread > Jun 24 01:13:46 ha-server vdr: [15789] VNSI: LiveStreamer::Close - close > Jun 24 01:13:46 ha-server vdr: [15789] VNSI: close video input ... > Jun 24 01:13:46 ha-server vdr: [15789] VNSI: activate live receiver: 0, pmt change: 0 > Jun 24 01:13:46 ha-server vdr: [15789] VNSI: close video input ... > Jun 24 01:13:46 ha-server vdr: [8692] VNSI: Client with ID 75 seems to be disconnected, removing from client list > Jun 24 01:13:49 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 device 1 has been idle for 38 sec - power it down > Jun 24 01:13:51 ha-server vdr: [8690] loading /etc/vdr/plugins/vnsiserver/allowed_hosts.conf > Jun 24 01:13:51 ha-server vdr: [8690] VNSI: Client with ID 76 connected: 10.0.0.90:52610 > Jun 24 01:13:51 ha-server vdr: [15824] VNSI: Welcome client 'XBMC Media Center' with protocol version '8' > Jun 24 01:13:51 ha-server vdr: [15824] VNSI: LiveStreamer::Close - close > Jun 24 01:13:51 ha-server vdr: [15824] VNSI: close video input ... > Jun 24 01:13:51 ha-server vdr: [15824] VNSI: Successfully found following device: 0x91cef0 (1) for receiving > Jun 24 01:13:51 ha-server vdr: [15824] VNSI: activate live receiver: 1, pmt change: 1 > Jun 24 01:13:51 ha-server vdr: [15824] VNSI: Successfully switched to channel 1 - BBC ONE Lon > Jun 24 01:13:51 ha-server vdr: [15824] VNSI: Started streaming of channel BBC ONE Lon (timeout 5 seconds) > Jun 24 01:13:52 ha-server vdr: [15826] VNSI: Created stream for pid=101 and type=7 > Jun 24 01:13:52 ha-server vdr: [15826] VNSI: Created stream for pid=102 and type=2 > Jun 24 01:13:52 ha-server vdr: [15826] VNSI: Created stream for pid=106 and type=2 > Jun 24 01:13:52 ha-server vdr: [15826] VNSI: Created stream for pid=105 and type=9 > Jun 24 01:13:54 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 tune to a transponder on device 1 to refresh EPG before a (VPS) timer starts, call cDevice::SetOccupied(), set device 1 idle timer to 38 sec > Jun 24 01:13:59 ha-server vdr: [7056] VNSI-Error: cxSocket::read(fd=6): read() error at 0/4 > Jun 24 01:13:59 ha-server vdr: [8692] VNSI: Client with ID 72 seems to be disconnected, removing from client list > Jun 24 01:14:33 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 device 1 has been idle for 38 sec - power it down > Jun 24 01:14:38 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 tune to a transponder on device 1 to refresh EPG before a (VPS) timer starts, call cDevice::SetOccupied(), set device 1 idle timer to 38 sec > Jun 24 01:15:17 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 device 1 has been idle for 38 sec - power it down > Jun 24 01:15:22 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 tune to a transponder on device 1 to refresh EPG before a (VPS) timer starts, call cDevice::SetOccupied(), set device 1 idle timer to 38 sec > Jun 24 01:16:01 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 device 1 has been idle for 38 sec - power it down > Jun 24 01:16:06 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 tune to a transponder on device 1 to refresh EPG before a (VPS) timer starts, call cDevice::SetOccupied(), set device 1 idle timer to 38 sec > Jun 24 01:16:43 ha-server vdr: [15824] VNSI-Error: cxSocket::read(fd=8): read() error at 0/4 > Jun 24 01:16:43 ha-server vdr: [15826] VNSI: exit streamer thread > Jun 24 01:16:43 ha-server vdr: [15824] VNSI: LiveStreamer::Close - close > Jun 24 01:16:43 ha-server vdr: [15824] VNSI: close video input ... > Jun 24 01:16:43 ha-server vdr: [15824] VNSI: activate live receiver: 0, pmt change: 0 > Jun 24 01:16:43 ha-server vdr: [15824] VNSI: close video input ... > Jun 24 01:16:43 ha-server vdr: [8692] VNSI: Client with ID 76 seems to be disconnected, removing from client list > Jun 24 01:16:45 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 device 1 has been idle for 38 sec - power it down > Jun 24 01:16:50 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 tune to a transponder on device 1 to refresh EPG before a (VPS) timer starts, call cDevice::SetOccupied(), set device 1 idle timer to 38 sec > Jun 24 01:16:56 ha-server vdr: [8690] loading /etc/vdr/plugins/vnsiserver/allowed_hosts.conf > Jun 24 01:16:56 ha-server vdr: [8690] VNSI: Client with ID 77 connected: 10.0.0.90:52614 > Jun 24 01:16:56 ha-server vdr: [16255] VNSI: Welcome client 'XBMC Media Center' with protocol version '8' > Jun 24 01:16:56 ha-server vdr: [16255] VNSI: LiveStreamer::Close - close > Jun 24 01:16:56 ha-server vdr: [16255] VNSI: close video input ... > Jun 24 01:16:56 ha-server vdr: [16255] VNSI: Successfully found following device: 0x91cef0 (1) for receiving > Jun 24 01:16:56 ha-server vdr: [16255] VNSI: activate live receiver: 1, pmt change: 1 > Jun 24 01:16:56 ha-server vdr: [16255] VNSI: Successfully switched to channel 20 - ITV > Jun 24 01:16:56 ha-server vdr: [16255] VNSI: Started streaming of channel ITV (timeout 5 seconds) > Jun 24 01:16:57 ha-server vdr: [16257] VNSI: Created stream for pid=1701 and type=7 > Jun 24 01:16:57 ha-server vdr: [16257] VNSI: Created stream for pid=1702 and type=2 > Jun 24 01:16:57 ha-server vdr: [16257] VNSI: Created stream for pid=1703 and type=2 > Jun 24 01:16:57 ha-server vdr: [16257] VNSI: Created stream for pid=1731 and type=9 > Jun 24 01:17:29 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 device 1 has been idle for 38 sec - power it down > Jun 24 01:35:42 ha-server vdr: [8686] channel 45 (Film4) event Fri 24.06.2016 01:35-03:50 (VPS: 24.06. 01:35) 'Greenberg' status 4 > Jun 24 01:35:45 ha-server vdr: [8679] timer 1 (45 1635-1850 VPS '-Films~The Spy Who Came In from the Cold~2016.06.23-16:35-Thu') set to no event > Jun 24 01:36:11 ha-server vdr: [8679] deleting timer 1 (45 1635-1850 VPS '-Films~The Spy Who Came In from the Cold~2016.06.23-16:35-Thu') > Jun 24 01:36:11 ha-server vdr: [8692] VNSI: Timers state changed (71) > Jun 24 01:36:11 ha-server vdr: [8692] VNSI: Requesting clients to reload timers > Jun 24 02:15:32 ha-server vdr: [16255] VNSI-Error: cxSocket::read(fd=23): read() error at 0/4 > Jun 24 02:15:33 ha-server vdr: [16257] VNSI: exit streamer thread > Jun 24 02:15:33 ha-server vdr: [16255] VNSI: LiveStreamer::Close - close streaming on device 0 has ended, set device 0 idle timer to 5 min (your setting) > Jun 24 02:15:33 ha-server vdr: [16255] VNSI: close video input ... > Jun 24 02:15:33 ha-server vdr: [16255] VNSI: activate live receiver: 0, pmt change: 0 > Jun 24 02:15:33 ha-server vdr: [16255] VNSI: close video input ... > Jun 24 02:15:33 ha-server vdr: [8692] VNSI: Client with ID 77 seems to be disconnected, removing from client list > Jun 24 02:15:40 ha-server vdr: [15117] VNSI: cxSocket::read(fd=17): eof, connection closed > Jun 24 02:15:40 ha-server vdr: [8692] VNSI: Client with ID 74 seems to be disconnected, removing from client list > Jun 24 02:20:34 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 0/0 device 0 has been idle for 300 sec - power it down > Jun 24 02:20:38 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 tune to a transponder on device 1 to refresh EPG before a (VPS) timer starts, call cDevice::SetOccupied(), set device 1 idle timer to 38 sec > Jun 24 02:21:17 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 device 1 has been idle for 38 sec - power it down > Jun 24 02:21:22 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 tune to a transponder on device 1 to refresh EPG before a (VPS) timer starts, call cDevice::SetOccupied(), set device 1 idle timer to 38 sec > Jun 24 02:22:01 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 device 1 has been idle for 38 sec - power it down > Jun 24 02:22:06 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 tune to a transponder on device 1 to refresh EPG before a (VPS) timer starts, call cDevice::SetOccupied(), set device 1 idle timer to 38 sec > ..... > > Jun 25 09:50:17 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 > Jun 25 09:50:22 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 tune to a transponder on device 1 to refresh EPG before a (VPS) timer starts, call cDevice::SetOccupied(), set device 1 idle timer to 38 sec > Jun 25 09:51:01 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 device 1 has been idle for 38 sec - power it down > Jun 25 09:51:06 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 tune to a transponder on device 1 to refresh EPG before a (VPS) timer starts, call cDevice::SetOccupied(), set device 1 idle timer to 38 sec > Jun 25 09:51:45 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 device 1 has been idle for 38 sec - power it down > Jun 25 09:51:50 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 tune to a transponder on device 1 to refresh EPG before a (VPS) timer starts, call cDevice::SetOccupied(), set device 1 idle timer to 38 sec > Jun 25 09:52:29 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 device 1 has been idle for 38 sec - power it down
On 28/06/2016 03:14, glenvt18 wrote: > Hi Richard, > > As there are no debug messages in your log I tried to reconstruct some > of them (power-related) - see my comments. Note that an extra second is > added to all timeouts due to the 'greater than' comparison. It was done > on purpose to add some save margin. Power-saving looks to work OK. But. > I don't see any 'timer ... start/stop' or 'record ...' messages in your > log. So, I have some questions: Yes, I turned off debug as it's been running awhile. > 1) Did you fail to record 'The Big C & Me' at 01:05 and 'Greenberg' at > 01:35? No, that was just a (standard) notification from VDR that the running status of a program had changed > 2) Did you cancel 'Greenberg'? No, VDR was idle before I started up Kodi/VNSI at around 01:00. Actually looking back through the log some more, it seems that Kodi had one of it's regular hiccup startup problems here. Basically it starts, then crashes, then starts, often 2 or 3 times. This is a known issue - the Kodi devs say they've changed the startup mechanism in newer versions, but these aren't stable enough for regular use for various other reasons. > 3) Was your streaming interrupted? > 4) What is your vnsi streaming priority? Not sure I have a VNSI priority, at least there isn't a setup variable for it. > 5) What is your recording priority? Generally 50 or 99. But in this case it wasn't recording, just streaming. > 6) Are the channels you recorded and the channels you streamed on the same > transponder? I'm asking this because I see device 1 being idle all that > time. As above, there was only 1 > 7) What happens with your VDR when a vnsi streaming is interrupted by a > higher priority recording on the same device but on another transponder? Not tested. > 8) What timers (their dates) did you have from Jun 24 00:00 to Jun 24 > 04:00? None > 9) What is your Vps Margin setting? 600 On reflection I think this is really a VNSI problem, possibly a side-effect of locking up the VNSI process. I have had a similar situation in the past (before adding your patch) where VNSI server plugin locks up after a client hiccup start, then any client trying to connect keeps trying to attach and times out. This time the client didn't time out (as I recall), but it was late, so I left it and went to bed...! I'll monitor for any other effects. thanks Richard >> Jun 24 01:12:59 ha-server vdr: [8690] loading /etc/vdr/plugins/vnsiserver/allowed_hosts.conf >> Jun 24 01:12:59 ha-server vdr: [8690] VNSI: Client with ID 75 connected: 10.0.0.90:52606 >> Jun 24 01:12:59 ha-server vdr: [15789] VNSI: Welcome client 'XBMC Media Center' with protocol version '8' >> Jun 24 01:12:59 ha-server vdr: [15789] VNSI: LiveStreamer::Close - close >> Jun 24 01:12:59 ha-server vdr: [15789] VNSI: close video input ... >> Jun 24 01:12:59 ha-server vdr: [15789] VNSI: Successfully found following device: 0x91cef0 (1) for receiving >> Jun 24 01:12:59 ha-server vdr: [15789] dvb tuner: power-up - opening frontend 0/0 > streaming started on device 0, wake up device 0 >> Jun 24 01:12:59 ha-server vdr: [15789] VNSI: activate live receiver: 1, pmt change: 1 >> Jun 24 01:12:59 ha-server vdr: [15789] VNSI: Successfully switched to channel 1 - BBC ONE Lon >> Jun 24 01:12:59 ha-server vdr: [15789] VNSI: Started streaming of channel BBC ONE Lon (timeout 5 seconds) >> Jun 24 01:12:59 ha-server vdr: [15793] VNSI: Created stream for pid=101 and type=7 >> Jun 24 01:12:59 ha-server vdr: [15793] VNSI: Created stream for pid=102 and type=2 >> Jun 24 01:12:59 ha-server vdr: [15793] VNSI: Created stream for pid=106 and type=2 >> Jun 24 01:12:59 ha-server vdr: [15793] VNSI: Created stream for pid=105 and type=9 >> Jun 24 01:13:00 ha-server vdr: [8686] channel 3 (BBC TWO) event Fri 24.06.2016 01:05-02:05 (VPS: 24.06. 01:05) 'The Big C & Me' status 4 >> Jun 24 01:13:05 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 >> Jun 24 01:13:10 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 > tune to a transponder on device 1 to refresh EPG before a (VPS) timer starts, call cDevice::SetOccupied(), set device 1 idle timer to 38 sec >> Jun 24 01:13:46 ha-server vdr: [15789] VNSI-Error: cxSocket::read(fd=18): read() error at 0/4 >> Jun 24 01:13:46 ha-server vdr: [15793] VNSI: exit streamer thread >> Jun 24 01:13:46 ha-server vdr: [15789] VNSI: LiveStreamer::Close - close >> Jun 24 01:13:46 ha-server vdr: [15789] VNSI: close video input ... >> Jun 24 01:13:46 ha-server vdr: [15789] VNSI: activate live receiver: 0, pmt change: 0 >> Jun 24 01:13:46 ha-server vdr: [15789] VNSI: close video input ... >> Jun 24 01:13:46 ha-server vdr: [8692] VNSI: Client with ID 75 seems to be disconnected, removing from client list >> Jun 24 01:13:49 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 > device 1 has been idle for 38 sec - power it down >> Jun 24 01:13:51 ha-server vdr: [8690] loading /etc/vdr/plugins/vnsiserver/allowed_hosts.conf >> Jun 24 01:13:51 ha-server vdr: [8690] VNSI: Client with ID 76 connected: 10.0.0.90:52610 >> Jun 24 01:13:51 ha-server vdr: [15824] VNSI: Welcome client 'XBMC Media Center' with protocol version '8' >> Jun 24 01:13:51 ha-server vdr: [15824] VNSI: LiveStreamer::Close - close >> Jun 24 01:13:51 ha-server vdr: [15824] VNSI: close video input ... >> Jun 24 01:13:51 ha-server vdr: [15824] VNSI: Successfully found following device: 0x91cef0 (1) for receiving >> Jun 24 01:13:51 ha-server vdr: [15824] VNSI: activate live receiver: 1, pmt change: 1 >> Jun 24 01:13:51 ha-server vdr: [15824] VNSI: Successfully switched to channel 1 - BBC ONE Lon >> Jun 24 01:13:51 ha-server vdr: [15824] VNSI: Started streaming of channel BBC ONE Lon (timeout 5 seconds) >> Jun 24 01:13:52 ha-server vdr: [15826] VNSI: Created stream for pid=101 and type=7 >> Jun 24 01:13:52 ha-server vdr: [15826] VNSI: Created stream for pid=102 and type=2 >> Jun 24 01:13:52 ha-server vdr: [15826] VNSI: Created stream for pid=106 and type=2 >> Jun 24 01:13:52 ha-server vdr: [15826] VNSI: Created stream for pid=105 and type=9 >> Jun 24 01:13:54 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 > tune to a transponder on device 1 to refresh EPG before a (VPS) timer starts, call cDevice::SetOccupied(), set device 1 idle timer to 38 sec >> Jun 24 01:13:59 ha-server vdr: [7056] VNSI-Error: cxSocket::read(fd=6): read() error at 0/4 >> Jun 24 01:13:59 ha-server vdr: [8692] VNSI: Client with ID 72 seems to be disconnected, removing from client list >> Jun 24 01:14:33 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 > device 1 has been idle for 38 sec - power it down >> Jun 24 01:14:38 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 > tune to a transponder on device 1 to refresh EPG before a (VPS) timer starts, call cDevice::SetOccupied(), set device 1 idle timer to 38 sec >> Jun 24 01:15:17 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 > device 1 has been idle for 38 sec - power it down >> Jun 24 01:15:22 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 > tune to a transponder on device 1 to refresh EPG before a (VPS) timer starts, call cDevice::SetOccupied(), set device 1 idle timer to 38 sec >> Jun 24 01:16:01 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 > device 1 has been idle for 38 sec - power it down >> Jun 24 01:16:06 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 > tune to a transponder on device 1 to refresh EPG before a (VPS) timer starts, call cDevice::SetOccupied(), set device 1 idle timer to 38 sec >> Jun 24 01:16:43 ha-server vdr: [15824] VNSI-Error: cxSocket::read(fd=8): read() error at 0/4 >> Jun 24 01:16:43 ha-server vdr: [15826] VNSI: exit streamer thread >> Jun 24 01:16:43 ha-server vdr: [15824] VNSI: LiveStreamer::Close - close >> Jun 24 01:16:43 ha-server vdr: [15824] VNSI: close video input ... >> Jun 24 01:16:43 ha-server vdr: [15824] VNSI: activate live receiver: 0, pmt change: 0 >> Jun 24 01:16:43 ha-server vdr: [15824] VNSI: close video input ... >> Jun 24 01:16:43 ha-server vdr: [8692] VNSI: Client with ID 76 seems to be disconnected, removing from client list >> Jun 24 01:16:45 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 > device 1 has been idle for 38 sec - power it down >> Jun 24 01:16:50 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 > tune to a transponder on device 1 to refresh EPG before a (VPS) timer starts, call cDevice::SetOccupied(), set device 1 idle timer to 38 sec >> Jun 24 01:16:56 ha-server vdr: [8690] loading /etc/vdr/plugins/vnsiserver/allowed_hosts.conf >> Jun 24 01:16:56 ha-server vdr: [8690] VNSI: Client with ID 77 connected: 10.0.0.90:52614 >> Jun 24 01:16:56 ha-server vdr: [16255] VNSI: Welcome client 'XBMC Media Center' with protocol version '8' >> Jun 24 01:16:56 ha-server vdr: [16255] VNSI: LiveStreamer::Close - close >> Jun 24 01:16:56 ha-server vdr: [16255] VNSI: close video input ... >> Jun 24 01:16:56 ha-server vdr: [16255] VNSI: Successfully found following device: 0x91cef0 (1) for receiving >> Jun 24 01:16:56 ha-server vdr: [16255] VNSI: activate live receiver: 1, pmt change: 1 >> Jun 24 01:16:56 ha-server vdr: [16255] VNSI: Successfully switched to channel 20 - ITV >> Jun 24 01:16:56 ha-server vdr: [16255] VNSI: Started streaming of channel ITV (timeout 5 seconds) >> Jun 24 01:16:57 ha-server vdr: [16257] VNSI: Created stream for pid=1701 and type=7 >> Jun 24 01:16:57 ha-server vdr: [16257] VNSI: Created stream for pid=1702 and type=2 >> Jun 24 01:16:57 ha-server vdr: [16257] VNSI: Created stream for pid=1703 and type=2 >> Jun 24 01:16:57 ha-server vdr: [16257] VNSI: Created stream for pid=1731 and type=9 >> Jun 24 01:17:29 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 > device 1 has been idle for 38 sec - power it down >> Jun 24 01:35:42 ha-server vdr: [8686] channel 45 (Film4) event Fri 24.06.2016 01:35-03:50 (VPS: 24.06. 01:35) 'Greenberg' status 4 >> Jun 24 01:35:45 ha-server vdr: [8679] timer 1 (45 1635-1850 VPS '-Films~The Spy Who Came In from the Cold~2016.06.23-16:35-Thu') set to no event >> Jun 24 01:36:11 ha-server vdr: [8679] deleting timer 1 (45 1635-1850 VPS '-Films~The Spy Who Came In from the Cold~2016.06.23-16:35-Thu') >> Jun 24 01:36:11 ha-server vdr: [8692] VNSI: Timers state changed (71) >> Jun 24 01:36:11 ha-server vdr: [8692] VNSI: Requesting clients to reload timers >> Jun 24 02:15:32 ha-server vdr: [16255] VNSI-Error: cxSocket::read(fd=23): read() error at 0/4 >> Jun 24 02:15:33 ha-server vdr: [16257] VNSI: exit streamer thread >> Jun 24 02:15:33 ha-server vdr: [16255] VNSI: LiveStreamer::Close - close > streaming on device 0 has ended, set device 0 idle timer to 5 min (your setting) >> Jun 24 02:15:33 ha-server vdr: [16255] VNSI: close video input ... >> Jun 24 02:15:33 ha-server vdr: [16255] VNSI: activate live receiver: 0, pmt change: 0 >> Jun 24 02:15:33 ha-server vdr: [16255] VNSI: close video input ... >> Jun 24 02:15:33 ha-server vdr: [8692] VNSI: Client with ID 77 seems to be disconnected, removing from client list >> Jun 24 02:15:40 ha-server vdr: [15117] VNSI: cxSocket::read(fd=17): eof, connection closed >> Jun 24 02:15:40 ha-server vdr: [8692] VNSI: Client with ID 74 seems to be disconnected, removing from client list >> Jun 24 02:20:34 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 0/0 > device 0 has been idle for 300 sec - power it down >> Jun 24 02:20:38 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 > tune to a transponder on device 1 to refresh EPG before a (VPS) timer starts, call cDevice::SetOccupied(), set device 1 idle timer to 38 sec >> Jun 24 02:21:17 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 > device 1 has been idle for 38 sec - power it down >> Jun 24 02:21:22 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 > tune to a transponder on device 1 to refresh EPG before a (VPS) timer starts, call cDevice::SetOccupied(), set device 1 idle timer to 38 sec >> Jun 24 02:22:01 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 > device 1 has been idle for 38 sec - power it down >> Jun 24 02:22:06 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 > tune to a transponder on device 1 to refresh EPG before a (VPS) timer starts, call cDevice::SetOccupied(), set device 1 idle timer to 38 sec >> ..... >> >> Jun 25 09:50:17 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 >> Jun 25 09:50:22 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 > tune to a transponder on device 1 to refresh EPG before a (VPS) timer starts, call cDevice::SetOccupied(), set device 1 idle timer to 38 sec >> Jun 25 09:51:01 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 > device 1 has been idle for 38 sec - power it down >> Jun 25 09:51:06 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 > tune to a transponder on device 1 to refresh EPG before a (VPS) timer starts, call cDevice::SetOccupied(), set device 1 idle timer to 38 sec >> Jun 25 09:51:45 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 > device 1 has been idle for 38 sec - power it down >> Jun 25 09:51:50 ha-server vdr: [8679] dvb tuner: power-up - opening frontend 1/0 > tune to a transponder on device 1 to refresh EPG before a (VPS) timer starts, call cDevice::SetOccupied(), set device 1 idle timer to 38 sec >> Jun 25 09:52:29 ha-server vdr: [8679] dvb tuner: power-down - closing frontend 1/0 > device 1 has been idle for 38 sec - power it down >
> No, that was just a (standard) notification from VDR that the running > status of a program had changed Sorry. I was a bit sleepy that night:) It was a false alarm. > Not sure I have a VNSI priority, at least there isn't a setup variable > for it. It's under Settings->Add-ons->My add-ons->PVR clients-> VDR VNSI Client->Configure->General->Priority. I have it set to 0. > > 8) What timers (their dates) did you have from Jun 24 00:00 to Jun 24 > > 04:00? > None > > 9) What is your Vps Margin setting? > 600 In this case your VDR might have been trying to update EPG for the timers scheduled later, probably, next night. Anyway, it's all about VDR's internal logic for updating timers' EPG events and has nothing to do with power saving. Every time a VDR decides to update EPG for a channel with a timer scheduled the device's frontend must be opened prior to zapping and kept open for a while (38 sec as you see in the log). If you have power-related issues you're likely to see ioctl() errors in the log and emergency exits after a recording starts (VDR will be restarted in this case). > I'll monitor for any other effects. Let me know.
diff --git a/eitscan.c b/eitscan.c index 3899e00..b1c8c13 100644 --- a/eitscan.c +++ b/eitscan.c @@ -159,7 +159,7 @@ void cEITScanner::Process(void) Skins.Message(mtInfo, tr("Starting EPG scan")); } } - //dsyslog("EIT scan: device %d source %-8s tp %5d", Device->DeviceNumber() + 1, *cSource::ToString(Channel->Source()), Channel->Transponder()); + dsyslog("EIT scan: device %d source %-8s tp %5d", Device->DeviceNumber() + 1, *cSource::ToString(Channel->Source()), Channel->Transponder()); if (lastActivity == 0) // forced scan - set idle timer for each channel switch; // this prevents powering down while scanning a transponder