Feeds Still Update but Keep seeing "Lock: update.lock" Each Time Cron Job Runs


#1

Describe the problem you’re having:
Every time my cron job runs, I receive an email saying [13:00:04/1893] Lock: update.lock
[13:00:04/1893] Scheduled 67 feeds to update… along with a list of all my feeds below. Feeds are still successfully updated but it keeps emailing me that message.

If possible include steps to reproduce the problem:

Happens every hour because that’s how often my cron is scheduled to run.

tt-rss version (including git commit id):

17.12 – commit ID is b037ffd628b62a68c9aceb4726d7e8d58b47b89c

Platform (i.e. Linux distro, PHP, PostgreSQL, etc) versions:

Shared hosting on DreamHost, PHP 5.6, MySQL 5 Database

Please provide any additional information below:

Here is the full log I receive after cron jobs run, it’s strange that the feeds continue to update but this comes through still:

[03:00:04/3497] Lock: update.lock
[03:00:04/3497] Scheduled 67 feeds to update...
[03:00:04/3497] Base feed: http://tt-rss.org/forum/rss.php
[03:00:04/3497]  => 2018-05-26 02:00:07, 1 1
[03:00:06/3497]     2.3716 (sec)
[03:00:06/3497] Base feed: http://adsense.blogspot.com/rss.xml
[03:00:06/3497]  => 2018-05-26 02:00:09, 7 2
[03:00:07/3497]     0.8338 (sec)
[03:00:07/3497] Base feed: http://feeds.feedburner.com/blogspot/tRaA
[03:00:07/3497]  => 2018-05-26 02:00:09, 8 2
[03:00:08/3497]     0.7515 (sec)
[03:00:08/3497] Base feed: http://feeds.feedburner.com/GoogleNewsBlog?format=xml
[03:00:08/3497]  => 2018-05-26 02:00:10, 9 2
[03:00:08/3497]     0.2644 (sec)
[03:00:08/3497] Base feed: http://googlewebmastercentral.blogspot.com/feeds/posts/default
[03:00:08/3497]  => 2018-05-26 02:00:10, 10 2
[03:00:09/3497]     0.8541 (sec)
[03:00:09/3497] Base feed: http://feeds.feedburner.com/OfficialGmailBlog?format=xml
[03:00:09/3497]  => 2018-05-26 02:00:14, 11 2
[03:00:09/3497]     0.0200 (sec)
[03:00:09/3497] Base feed: http://adwords.blogspot.com/atom.xml
[03:00:09/3497]  => 2018-05-26 02:00:15, 14 2
[03:00:10/3497]     0.8720 (sec)
[03:00:10/3497] Base feed: http://googleblog.blogspot.com/feeds/posts/default
[03:00:10/3497]  => 2018-05-26 02:00:15, 15 2
[03:00:11/3497]     1.1903 (sec)
[03:00:11/3497] Base feed: http://customerexperiencematrix.blogspot.com/feeds/posts/default
[03:00:11/3497]  => 2018-05-26 02:00:16, 128 2
[03:00:11/3497]     0.4026 (sec)
[03:00:11/3497] Base feed: https://aws.amazon.com/blogs/ai/feed/
[03:00:11/3497]  => 2018-05-26 02:00:16, 129 2
[03:00:12/3497]     0.4049 (sec)
[03:00:12/3497] Base feed: http://www.techmeme.com/feed.xml
[03:00:12/3497]  => 2018-05-26 02:00:16, 132 2
[03:00:13/3497]     1.7439 (sec)
[03:00:13/3497] Base feed: http://blogs.forrester.com/taxonomy/term/8/all/feed
[03:00:13/3497]  => 2018-05-26 02:00:17, 133 2
[03:00:14/3497]     0.5065 (sec)
[03:00:14/3497] Base feed: https://blogs.adobe.com/digitalmarketing/feed/
[03:00:14/3497]  => 2018-05-26 02:00:19, 127 2
[03:00:15/3497]     1.4634 (sec)
[03:00:15/3497] Base feed: https://enterprisechess.com/feed/
[03:00:15/3497]  => 2018-05-26 02:00:19, 134 2
[03:00:16/3497]     0.6816 (sec)
[03:00:16/3497] Base feed: https://www.marketingaiinstitute.com/blog/rss.xml
[03:00:16/3497]  => 2018-05-26 02:00:20, 142 2
[03:00:16/3497]     0.3566 (sec)
[03:00:16/3497] Base feed: https://waitbutwhy.com/feed
[03:00:16/3497]  => 2018-05-26 02:00:20, 143 2
[03:00:17/3497]     0.2539 (sec)
[03:00:17/3497] Base feed: https://eapj.org/feed/
[03:00:17/3497]  => 2018-05-26 02:00:22, 135 2
[03:00:19/3497]     1.9170 (sec)
[03:00:19/3497] Base feed: https://aitrends.com/feed/
[03:00:19/3497]  => 2018-05-26 02:00:23, 138 2
[03:00:20/3497]     1.0207 (sec)
[03:00:20/3497] Base feed: https://blog.algorithmia.com/feed/
[03:00:20/3497]  => 2018-05-26 02:00:24, 139 2
[03:00:20/3497]     0.4377 (sec)
[03:00:20/3497] Base feed: http://www.techdirt.com/techdirt_rss.xml
[03:00:20/3497]  => 2018-05-26 02:00:26, 16 2
[03:00:22/3497]     1.6408 (sec)
[03:00:22/3497] Base feed: http://feeds.feedburner.com/ProbloggerHelpingBloggersEarnMoney
[03:00:22/3497]  => 2018-05-26 02:00:26, 28 2
[03:00:22/3497]     0.4748 (sec)
[03:00:22/3497] Base feed: http://www.alistapart.com/rss.xml
[03:00:22/3497]  => 2018-05-26 02:00:26, 30 2
[03:00:22/3497]     0.1909 (sec)
[03:00:22/3497] Base feed: http://www.lifehacker.com/index.xml
[03:00:22/3497]  => 2018-05-26 02:00:30, 31 2
[03:00:24/3497]     1.2302 (sec)
[03:00:24/3497] Base feed: http://masl.cis.gvsu.edu/feed/
[03:00:24/3497]  => 2018-05-26 02:00:45, 130 2
[03:00:39/3497]     15.0692 (sec)
[03:00:39/3497] Base feed: http://feeds.feedburner.com/Techcrunch
[03:00:39/3497]  => 2018-05-26 02:00:49, 32 2
[03:00:40/3497]     1.3106 (sec)
[03:00:40/3497] Base feed: http://twocents.lifehacker.com/rss
[03:00:40/3497]  => 2018-05-26 02:00:50, 54 2
[03:00:40/3497]     0.4755 (sec)
[03:00:40/3497] Base feed: http://whitecoatinvestor.com/feed/
[03:00:40/3497]  => 2018-05-26 02:00:52, 55 2
[03:00:42/3497]     1.5301 (sec)
[03:00:42/3497] Base feed: http://feeds.feedburner.com/thesimpledollar
[03:00:42/3497]  => 2018-05-26 02:00:52, 56 2
[03:00:42/3497]     0.2496 (sec)
[03:00:42/3497] Base feed: http://www.vanguardblog.com/feed
[03:00:42/3497]  => 2018-05-26 02:00:53, 59 2
[03:00:42/3497]     0.1622 (sec)
[03:00:42/3497] Base feed: http://feeds.feedburner.com/Mymoneyblog
[03:00:42/3497]  => 2018-05-26 02:00:53, 60 2
[03:00:43/3497]     0.2873 (sec)
[03:00:43/3497] Base feed: http://feeds.feedburner.com/Monevatorcom
[03:00:43/3497]  => 2018-05-26 02:00:53, 61 2
[03:00:43/3497]     0.1271 (sec)
[03:00:43/3497] Base feed: http://feeds.feedburner.com/Cashmoneylifecom
[03:00:43/3497]  => 2018-05-26 02:00:54, 62 2
[03:00:43/3497]     0.0265 (sec)
[03:00:43/3497] Base feed: http://www.economist.com/rss/finance_and_economics_rss.xml
[03:00:43/3497]  => 2018-05-26 02:00:55, 64 2
[03:00:43/3497]     0.4082 (sec)
[03:00:43/3497] Base feed: http://www.iwillteachyoutoberich.com/feed/
[03:00:43/3497]  => 2018-05-26 02:00:55, 65 2
[03:00:44/3497]     0.3135 (sec)
[03:00:44/3497] Base feed: http://feeds.feedburner.com/MrMoneyMustache
[03:00:44/3497]  => 2018-05-26 02:00:55, 67 2
[03:00:44/3497]     0.5269 (sec)
[03:00:44/3497] Base feed: http://www.wired.com/news_drop/netcenter/netcenter.rdf
[03:00:44/3497]  => 2018-05-26 02:00:57, 89 2
[03:00:45/3497]     0.8042 (sec)
[03:00:45/3497] Base feed: http://feeds.feedblitz.com/neuromarketing
[03:00:45/3497]  => 2018-05-26 02:00:57, 90 2
[03:00:45/3497]     0.5175 (sec)
[03:00:45/3497] Base feed: https://blog.google/products/maps/rss/
[03:00:45/3497]  => 2018-05-26 02:00:58, 91 2
[03:00:46/3497]     0.5378 (sec)
[03:00:46/3497] Base feed: https://www.technologyreview.com/topnews.rss
[03:00:46/3497]  => 2018-05-26 02:00:59, 94 2
[03:00:47/3497]     0.7526 (sec)
[03:00:47/3497] Base feed: https://blog.google/products/docs/rss/
[03:00:47/3497]  => 2018-05-26 02:01:01, 95 2
[03:00:47/3497]     0.5421 (sec)
[03:00:47/3497] Base feed: http://feeds.feedburner.com/blogspot/gJZg
[03:00:47/3497]  => 2018-05-26 02:01:01, 96 2
[03:00:48/3497]     0.3449 (sec)
[03:00:48/3497] Base feed: http://feeds.feedburner.com/GoogleDriveBlog
[03:00:48/3497]  => 2018-05-26 02:01:01, 97 2
[03:00:53/3497]     5.6437 (sec)
[03:00:53/3497] Base feed: http://feeds.feedburner.com/google/think
[03:00:53/3497]  => 2018-05-26 02:01:01, 98 2
[03:00:53/3497]     0.0206 (sec)
[03:00:53/3497] Base feed: https://chrome.googleblog.com/feeds/posts/default
[03:00:53/3497]  => 2018-05-26 02:01:02, 99 2
[03:00:55/3497]     1.9437 (sec)
[03:00:55/3497] Base feed: http://feeds.obliviousinvestor.com/TheObliviousInvestor
[03:00:55/3497]  => 2018-05-26 02:01:02, 100 2
[03:00:55/3497]     0.2834 (sec)
[03:00:55/3497] Base feed: http://feeds.feedburner.com/TheIncidentalEconomist
[03:00:55/3497]  => 2018-05-26 02:01:02, 101 2
[03:00:56/3497]     0.3789 (sec)
[03:00:56/3497] Base feed: http://feeds.feedburner.com/TheFinanceBuff
[03:00:56/3497]  => 2018-05-26 02:01:02, 102 2
[03:00:56/3497]     0.1314 (sec)
[03:00:56/3497] Base feed: http://feeds.feedburner.com/CostcoInsider
[03:00:56/3497]  => 2018-05-26 02:01:02, 103 2
[03:00:56/3497]     0.0219 (sec)
[03:00:56/3497] Base feed: http://www.martechadvisor.com/feed/
[03:00:56/3497]  => 2018-05-26 02:01:03, 104 2
[03:00:57/3497]     0.9053 (sec)
[03:00:57/3497] Base feed: https://www.google.com/alerts/feeds/04895652994563455050/15090456364134228425
[03:00:57/3497]  => 2018-05-26 02:01:03, 105 2
[03:00:57/3497]     0.1459 (sec)
[03:00:57/3497] Base feed: http://chiefmartec.com/feed/
[03:00:57/3497]  => 2018-05-26 02:01:05, 106 2
[03:00:57/3497]     0.1562 (sec)
[03:00:57/3497] Base feed: http://www.iftf.org/rss-feed/
[03:00:57/3497]  => 2018-05-26 02:01:05, 107 2
[03:00:58/3497]     0.5171 (sec)
[03:00:58/3497] Base feed: https://rework.withgoogle.com/blog/feed/
[03:00:58/3497]  => 2018-05-26 02:01:05, 140 2
[03:00:58/3497]     0.3786 (sec)
[03:00:58/3497] Base feed: https://martechtoday.com/main-news-feed
[03:00:58/3497]  => 2018-05-26 02:01:06, 109 2
[03:00:59/3497]     0.7720 (sec)
[03:00:59/3497] Base feed: http://feeds.feedburner.com/ClPlBl
[03:00:59/3497]  => 2018-05-26 02:01:07, 110 2
[03:00:59/3497]     0.0197 (sec)
[03:00:59/3497] Base feed: http://feeds2.feedburner.com/michaelhyatt
[03:00:59/3497]  => 2018-05-26 02:01:07, 111 2
[03:00:59/3497]     0.0927 (sec)
[03:00:59/3497] Base feed: https://eblingroup.com/blog/feed/
[03:00:59/3497]  => 2018-05-26 02:01:07, 112 2
[03:01:00/3497]     0.5235 (sec)
[03:01:00/3497] Base feed: http://jeremiekubicek.com/feed/
[03:01:00/3497]  => 2018-05-26 02:01:08, 115 2
[03:01:00/3497]     0.1864 (sec)
[03:01:00/3497] Base feed: https://leadershipfreak.blog/feed/
[03:01:00/3497]  => 2018-05-26 02:01:08, 117 2
[03:01:00/3497]     0.1463 (sec)
[03:01:00/3497] Base feed: http://www.danpink.com/feed/
[03:01:00/3497]  => 2018-05-26 02:01:09, 118 2
[03:01:01/3497]     0.7328 (sec)
[03:01:01/3497] Base feed: http://feeds.hbr.org/harvardbusiness
[03:01:01/3497]  => 2018-05-26 02:01:09, 119 2
[03:01:01/3497]     0.2426 (sec)
[03:01:01/3497] Base feed: http://blogs.opentext.com/tag/edi/feed/
[03:01:01/3497]  => 2018-05-26 02:01:10, 121 2
[03:01:01/3497]     0.2637 (sec)
[03:01:01/3497] Base feed: http://blogs.informatica.com/tag/edi/feed/
[03:01:01/3497]  => 2018-05-26 02:01:11, 122 2

[03:01:02/3497]     0.6421 (sec)
[03:01:02/3497] Base feed: http://blogs.informatica.com/feed/
[03:01:02/3497]  => 2018-05-26 02:01:11, 123 2
[03:01:02/3497]     0.0275 (sec)
[03:01:02/3497] Base feed: http://blog.startwithwhy.com/refocus/atom.xml
[03:01:02/3497]  => 2018-05-26 02:01:11, 124 2
[03:01:02/3497]     0.2136 (sec)
[03:01:02/3497] Base feed: https://www.cleo.com/feed/
[03:01:02/3497]  => 2018-05-26 02:01:12, 125 2
[03:01:03/3497]     1.0419 (sec)
[03:01:03/3497] Base feed: https://www.edigateway.com/en/feed/
[03:01:03/3497]  => 2018-05-26 02:01:13, 126 2
[03:01:19/3497]     15.5281 (sec)
[03:01:19/3497] Processed 67 feeds in 74.8940 (sec), 1.1178 (sec/feed avg)
[03:01:19/3497] Running housekeeping tasks for user 1...
[03:01:19/3497] Running housekeeping tasks for user 2...
[03:01:19/3497] Sending digests, batch of max 15 users, headline limit = 1000
[03:01:19/3497] All done.
[03:01:19/3497] cache/simplepie: removed 0 files.
[03:01:19/3497] cache/feeds: removed 0 files.
[03:01:19/3497] cache/images: removed 0 files.
[03:01:19/3497] cache/export: removed 0 files.
[03:01:19/3497] cache/upload: removed 0 files.
[03:01:19/3497] Removed 0 old lock files.
[03:01:19/3497] Removing old error log entries...
[03:01:19/3497] Feedbrowser updated, 37 feeds processed.
[03:01:24/3497] Purged 1 orphaned posts.
[03:01:24/3497] Removed 0 (feeds) 0 (cats) orphaned counter cache entries.

#2

this line tells you the name of the lockfile that is used by the update task. i’m not sure why you have decided that this is a problem of some kind or that it would prevent feeds from updating.

if you are confused by debugging output, i suggest you set updating to quiet.


#3

My impression was that nitwit was confused that his cron job was sending him email… but maybe I missed the point.


#4

This message never used to send and started a few weeks ago so that’s why I was asking – thanks for looking regardless.