Performance with displaying articles ttrss 1.10?

Support requests, bug reports, etc. go here. Dedicated servers / VDS hosting only
ralfonat
Bear Rating Trainee
Bear Rating Trainee
Posts: 21
Joined: 20 Mar 2013, 13:53

Performance with displaying articles ttrss 1.10?

Postby ralfonat » 08 Oct 2013, 17:12

Heya,

I upgraded from 1.9 to 1.10 and have since been having some abnormal article viewing problems. When I load up tinytinyrss and click on the first article it takes about 5 seconds till the article preview pane opens and the article is displayed. I enabled console and saw this output

Code: Select all

PluginHost::run 9 index.php:592
6553 : CS[0] -> BG : cmd=rebuildcontext onloadwff.js:78
loading article: 84540 index.php:385
cache check result: true index.php:385
additional ids: 85593,79950,79949,80551,75441,85559 index.php:387
PluginHost::run 3 index.php:592
?op=article&method=view&id=84540&cids=85593,79950,79949,80551,75441,85559 index.php:388
>>>>> Lag of 5 seconds is here <<<<<
article_callback2 84540


So I am wondering if this is a coincidence and its just my webserver / mysql misbehaving or if there was a change introduced that makes that callback a lot slower?

Anyways. Is there anything that would help me find the culprit? Profiling the code etc?

Many thanks in advance.

User avatar
fox
^ me reading your posts ^
Posts: 6318
Joined: 27 Aug 2005, 22:53
Location: Saint-Petersburg, Russia
Contact:

Re: Performance with displaying articles ttrss 1.10?

Postby fox » 08 Oct 2013, 19:49

You can use chrome profiler for better results but going by your log it lags when your server is processing the request to get articles, which would indicate that it's not a javascript issue at least.

You can probably add some debugging to view method but I'm not sure what could lag for 5 seconds in there, I don't think there were any major changes in that area between 1.9 and 1.10.

e: disable plugins, check mysql load, etc.

ralfonat
Bear Rating Trainee
Bear Rating Trainee
Posts: 21
Joined: 20 Mar 2013, 13:53

Re: Performance with displaying articles ttrss 1.10?

Postby ralfonat » 09 Oct 2013, 12:24

Hello fox,

thanks for your reply. I am kind of noobish in everything web related so please bear with me. As you and I have guessed it is not the client side/javascript code.

I concentrated on the backend.php call to view(). I hacked up the code a bit and put some debug/trace code in there.

Warning, wall of text:

Code: Select all

[Date: 09-10-2013 08:14:38:0.05278400/24046] view(
[Date: 09-10-2013 08:14:38:0.05296200/24046] array_push(
[Date: 09-10-2013 08:14:38:0.05304800/24046] format_article(
[Date: 09-10-2013 08:14:38:0.05345900/24046] 1 query ttrss_user_entries
[Date: 09-10-2013 08:14:38:0.05356100/24046] 2 update ttrss_user_entries
[Date: 09-10-2013 08:14:38:0.05422200/24046] 3 query ttrss_entries,ttrss_user_entries
[Date: 09-10-2013 08:14:38:0.13372100/24046] format_article)
[Date: 09-10-2013 08:14:38:0.13387200/24046] array_push)
[Date: 09-10-2013 08:14:38:0.13397800/24046] catchupArticleById(
[Date: 09-10-2013 08:14:38:0.17419300/24046] cabi UPDATE
[Date: 09-10-2013 08:14:38:0.17433500/24046] getArticleFeed before SELECT
[Date: 09-10-2013 08:14:38:0.17483900/24046] getArticleFeed after SELECT
[Date: 09-10-2013 08:14:38:0.17500800/24046] getArticleFeed
[Date: 09-10-2013 08:14:38:0.17513200/24046] ccache_update(
[Date: 09-10-2013 08:14:38:0.17556600/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:38:0.17626600/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:38:0.17681900/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:38:0.17744800/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:38:0.21615200/24046] ccache_update(
[Date: 09-10-2013 08:14:38:0.21662400/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:38:0.21690100/24046] c2 after select ttrss_feeds
[Date: 09-10-2013 08:14:38:0.21704700/24046] ccache_update(
[Date: 09-10-2013 08:14:38:0.21753000/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:38:0.21839500/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:38:0.21894800/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:38:0.21944300/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:38:0.24951400/24046] c6 after everything
[Date: 09-10-2013 08:14:38:0.24967100/24046] ccache_update(
[Date: 09-10-2013 08:14:38:0.25010200/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:38:0.25076400/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:38:0.25130800/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:38:0.25187700/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:38:0.27530200/24046] c6 after everything
[Date: 09-10-2013 08:14:38:0.27545600/24046] ccache_update(
[Date: 09-10-2013 08:14:38:0.27586800/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:38:0.27660800/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:38:0.27705900/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:38:0.27755300/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:38:0.30046500/24046] c6 after everything
[Date: 09-10-2013 08:14:38:0.30062000/24046] ccache_update(
[Date: 09-10-2013 08:14:38:0.30101800/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:38:0.30178400/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:38:0.30223000/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:38:0.30275900/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:38:0.32559700/24046] c6 after everything
[Date: 09-10-2013 08:14:38:0.32575200/24046] ccache_update(
[Date: 09-10-2013 08:14:38:0.32619400/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:38:0.32694400/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:38:0.32748000/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:38:0.32807900/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:38:0.35910400/24046] c6 after everything
[Date: 09-10-2013 08:14:38:0.35926000/24046] ccache_update(
[Date: 09-10-2013 08:14:38:0.35965800/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:38:0.36028900/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:38:0.36072100/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:38:0.36124100/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:38:0.38422400/24046] c6 after everything
[Date: 09-10-2013 08:14:38:0.38439000/24046] ccache_update(
[Date: 09-10-2013 08:14:38:0.38479900/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:38:0.38557200/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:38:0.38600300/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:38:0.38646200/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:38:0.40933600/24046] c6 after everything
[Date: 09-10-2013 08:14:38:0.40949200/24046] ccache_update(
[Date: 09-10-2013 08:14:38:0.40989400/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:38:0.41054000/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:38:0.41096900/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:38:0.41142900/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:38:0.43445000/24046] c6 after everything
[Date: 09-10-2013 08:14:38:0.43460600/24046] ccache_update(
[Date: 09-10-2013 08:14:38:0.43500800/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:38:0.43557600/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:38:0.43600300/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:38:0.43647500/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:38:0.45956300/24046] c6 after everything
[Date: 09-10-2013 08:14:38:0.45972000/24046] ccache_update(
[Date: 09-10-2013 08:14:38:0.46011700/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:38:0.46065700/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:38:0.46114200/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:38:0.46160300/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:38:0.48469800/24046] c6 after everything
[Date: 09-10-2013 08:14:38:0.48485400/24046] ccache_update(
[Date: 09-10-2013 08:14:38:0.48526000/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:38:0.48593100/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:38:0.48636600/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:38:0.48682300/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:38:0.50978200/24046] c6 after everything
[Date: 09-10-2013 08:14:38:0.50993800/24046] ccache_update(
[Date: 09-10-2013 08:14:38:0.51037500/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:38:0.51099800/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:38:0.51147700/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:38:0.51180000/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:38:0.53494700/24046] c6 after everything
[Date: 09-10-2013 08:14:38:0.53510300/24046] ccache_update(
[Date: 09-10-2013 08:14:38:0.53555300/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:38:0.53626800/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:38:0.53671400/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:38:0.53716400/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:38:0.56005400/24046] c6 after everything
[Date: 09-10-2013 08:14:38:0.56016600/24046] ccache_update(
[Date: 09-10-2013 08:14:38:0.56051400/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:38:0.56089900/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:38:0.56127400/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:38:0.56160400/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:38:0.59355000/24046] c6 after everything
[Date: 09-10-2013 08:14:38:0.59366700/24046] ccache_update(
[Date: 09-10-2013 08:14:38:0.59402100/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:38:0.59437500/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:38:0.59475300/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:38:0.59504000/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:38:0.61866600/24046] c6 after everything
[Date: 09-10-2013 08:14:38:0.61878200/24046] ccache_update(
[Date: 09-10-2013 08:14:38:0.61920300/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:38:0.61955700/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:38:0.61976900/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:38:0.62003800/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:38:0.64382400/24046] c6 after everything
[Date: 09-10-2013 08:14:38:0.64395500/24046] ccache_update(
[Date: 09-10-2013 08:14:38:0.64425400/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:38:0.64485300/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:38:0.64514000/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:38:0.64547400/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:38:0.67726300/24046] c6 after everything
[Date: 09-10-2013 08:14:38:0.67739500/24046] ccache_update(
[Date: 09-10-2013 08:14:38:0.67774400/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:38:0.67837700/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:38:0.67878800/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:38:0.67915400/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:38:0.71905300/24046] c6 after everything
[Date: 09-10-2013 08:14:38:0.71922100/24046] ccache_update(
[Date: 09-10-2013 08:14:38:0.71946000/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:38:0.71983100/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:38:0.72004200/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:38:0.72029600/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:38:0.75255600/24046] c6 after everything
[Date: 09-10-2013 08:14:38:0.75277400/24046] ccache_update(
[Date: 09-10-2013 08:14:38:0.75327000/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:38:0.75388100/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:38:0.75431900/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:38:0.75478600/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:38:0.79470900/24046] c6 after everything
[Date: 09-10-2013 08:14:38:0.79492900/24046] ccache_update(
[Date: 09-10-2013 08:14:38:0.79549300/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:38:0.79647500/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:38:0.79687700/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:38:0.79722900/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:38:0.87811600/24046] c6 after everything
[Date: 09-10-2013 08:14:38:0.87834000/24046] ccache_update(
[Date: 09-10-2013 08:14:38:0.87892900/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:38:0.88028400/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:38:0.88085500/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:38:0.88152600/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:38:0.91160600/24046] c6 after everything
[Date: 09-10-2013 08:14:38:0.91181300/24046] ccache_update(
[Date: 09-10-2013 08:14:38:0.91225700/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:38:0.91312400/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:38:0.91369200/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:38:0.91424800/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:38:0.94502800/24046] c6 after everything
[Date: 09-10-2013 08:14:38:0.94524200/24046] ccache_update(
[Date: 09-10-2013 08:14:38:0.94580700/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:38:0.94704100/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:38:0.94777000/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:38:0.94849700/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:38:0.97848400/24046] c6 after everything
[Date: 09-10-2013 08:14:38:0.97869500/24046] ccache_update(
[Date: 09-10-2013 08:14:38:0.97924800/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:38:0.98009900/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:38:0.98063800/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:38:0.98122400/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:39:0.01190000/24046] c6 after everything
[Date: 09-10-2013 08:14:39:0.01211000/24046] ccache_update(
[Date: 09-10-2013 08:14:39:0.01259900/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:39:0.01361500/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:39:0.01419600/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:39:0.01473500/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:39:0.04544200/24046] c6 after everything
[Date: 09-10-2013 08:14:39:0.04565800/24046] ccache_update(
[Date: 09-10-2013 08:14:39:0.04616800/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:39:0.04676900/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:39:0.04724000/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:39:0.04787600/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:39:0.07887900/24046] c6 after everything
[Date: 09-10-2013 08:14:39:0.07909800/24046] ccache_update(
[Date: 09-10-2013 08:14:39:0.07967100/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:39:0.08037200/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:39:0.08077700/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:39:0.08130800/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:39:0.11227000/24046] c6 after everything
[Date: 09-10-2013 08:14:39:0.11242300/24046] ccache_update(
[Date: 09-10-2013 08:14:39:0.11282600/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:39:0.11353600/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:39:0.11395500/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:39:0.11443000/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:39:0.14567100/24046] c6 after everything
[Date: 09-10-2013 08:14:39:0.14574300/24046] ccache_update(
[Date: 09-10-2013 08:14:39:0.14593200/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:39:0.14620000/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:39:0.14638400/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:39:0.14660700/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:39:0.17913300/24046] c6 after everything
[Date: 09-10-2013 08:14:39:0.17923700/24046] ccache_update(
[Date: 09-10-2013 08:14:39:0.17950700/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:39:0.17982900/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:39:0.18002000/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:39:0.18031400/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:39:0.21264900/24046] c6 after everything
[Date: 09-10-2013 08:14:39:0.21274700/24046] ccache_update(
[Date: 09-10-2013 08:14:39:0.21297600/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:39:0.21325900/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:39:0.21346200/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:39:0.21371900/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:39:0.24632100/24046] c6 after everything
[Date: 09-10-2013 08:14:39:0.24641600/24046] ccache_update(
[Date: 09-10-2013 08:14:39:0.24663200/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:39:0.24697400/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:39:0.24713700/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:39:0.24729600/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:39:0.28832200/24046] c6 after everything
[Date: 09-10-2013 08:14:39:0.28842300/24046] ccache_update(
[Date: 09-10-2013 08:14:39:0.28863200/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:39:0.28899800/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:39:0.28922100/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:39:0.28943200/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:39:0.33016900/24046] c6 after everything
[Date: 09-10-2013 08:14:39:0.33025800/24046] ccache_update(
[Date: 09-10-2013 08:14:39:0.33046900/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:39:0.33074000/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:39:0.33090300/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:39:0.33105600/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:39:0.36365900/24046] c6 after everything
[Date: 09-10-2013 08:14:39:0.36376700/24046] ccache_update(
[Date: 09-10-2013 08:14:39:0.36397700/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:39:0.36425900/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:39:0.36449500/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:39:0.36470800/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:39:0.39730800/24046] c6 after everything
[Date: 09-10-2013 08:14:39:0.39741100/24046] ccache_update(
[Date: 09-10-2013 08:14:39:0.39768200/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:39:0.39868200/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:39:0.39901100/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:39:0.39928800/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:39:0.43076400/24046] c6 after everything
[Date: 09-10-2013 08:14:39:0.43087600/24046] ccache_update(
[Date: 09-10-2013 08:14:39:0.43111900/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:39:0.43142700/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:39:0.43168800/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:39:0.43193200/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:39:0.46451500/24046] c6 after everything
[Date: 09-10-2013 08:14:39:0.46465300/24046] ccache_update(
[Date: 09-10-2013 08:14:39:0.46498100/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:39:0.46543500/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:39:0.46572000/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:39:0.46605200/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:39:0.49793900/24046] c6 after everything
[Date: 09-10-2013 08:14:39:0.49805100/24046] ccache_update(
[Date: 09-10-2013 08:14:39:0.49845300/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:39:0.49917600/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:39:0.49939300/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:39:0.49968700/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:39:0.53145200/24046] c6 after everything
[Date: 09-10-2013 08:14:39:0.53158800/24046] ccache_update(
[Date: 09-10-2013 08:14:39:0.53187500/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:39:0.53233900/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:39:0.53277400/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:39:0.53321300/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:39:0.56499700/24046] c6 after everything
[Date: 09-10-2013 08:14:39:0.56521400/24046] ccache_update(
[Date: 09-10-2013 08:14:39:0.56573000/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:39:0.56658700/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:39:0.56729100/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:39:0.56798000/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:39:0.59857300/24046] c6 after everything
[Date: 09-10-2013 08:14:39:0.59876800/24046] ccache_update(
[Date: 09-10-2013 08:14:39:0.59928800/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:39:0.59982500/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:39:0.60043800/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:39:0.60094400/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:39:0.63198600/24046] c6 after everything
[Date: 09-10-2013 08:14:39:0.63213800/24046] ccache_update(
[Date: 09-10-2013 08:14:39:0.63253300/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:39:0.63303900/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:39:0.63354300/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:39:0.63402200/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:39:0.66545400/24046] c6 after everything
[Date: 09-10-2013 08:14:39:0.66560600/24046] ccache_update(
[Date: 09-10-2013 08:14:39:0.66597600/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:39:0.66682600/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:39:0.66737600/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:39:0.66784700/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:39:0.69892800/24046] c6 after everything
[Date: 09-10-2013 08:14:39:0.69908000/24046] ccache_update(
[Date: 09-10-2013 08:14:39:0.69945300/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:39:0.69997900/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:39:0.70057400/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:39:0.70105200/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:39:0.73239900/24046] c6 after everything
[Date: 09-10-2013 08:14:39:0.73256600/24046] ccache_update(
[Date: 09-10-2013 08:14:39:0.73295100/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:39:0.73351200/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:39:0.73403600/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:39:0.73452300/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:39:0.76590800/24046] c6 after everything
[Date: 09-10-2013 08:14:39:0.76608200/24046] ccache_update(
[Date: 09-10-2013 08:14:39:0.76652800/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:39:0.76707600/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:39:0.76758600/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:39:0.76814700/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:39:0.79932300/24046] c6 after everything
[Date: 09-10-2013 08:14:39:0.79941200/24046] ccache_update(
[Date: 09-10-2013 08:14:39:0.79963400/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:39:0.80003700/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:39:0.80029300/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:39:0.80044500/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:39:0.83286800/24046] c6 after everything
[Date: 09-10-2013 08:14:39:0.83296100/24046] ccache_update(
[Date: 09-10-2013 08:14:39:0.83315900/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:39:0.83381800/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:39:0.83399300/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:39:0.83424400/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:39:0.85800600/24046] c6 after everything
[Date: 09-10-2013 08:14:39:0.85816000/24046] ccache_update(
[Date: 09-10-2013 08:14:39:0.85854900/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:39:0.85923200/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:39:0.85965100/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:39:0.86006100/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:39:0.88312100/24046] c6 after everything
[Date: 09-10-2013 08:14:39:0.88327300/24046] ccache_update(
[Date: 09-10-2013 08:14:39:0.88366300/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:39:0.88423500/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:39:0.88470300/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:39:0.88511100/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:39:0.90830000/24046] c6 after everything
[Date: 09-10-2013 08:14:39:0.90856700/24046] ccache_update(
[Date: 09-10-2013 08:14:39:0.90922300/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:39:0.90964900/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:39:0.90987500/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:39:0.91023500/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:39:0.94169600/24046] c6 after everything
[Date: 09-10-2013 08:14:39:0.94182800/24046] ccache_update(
[Date: 09-10-2013 08:14:39:0.94225000/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:39:0.94268600/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:39:0.94292900/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:39:0.94331600/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:39:0.96680300/24046] c6 after everything
[Date: 09-10-2013 08:14:39:0.96693000/24046] ccache_update(
[Date: 09-10-2013 08:14:39:0.96723700/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:39:0.96773600/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:39:0.96807900/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:39:0.96832700/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:39:0.99271000/24046] c6 after everything
[Date: 09-10-2013 08:14:39:0.99296000/24046] ccache_update(
[Date: 09-10-2013 08:14:39:0.99330400/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:39:0.99391600/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:39:0.99434000/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:39:0.99460600/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:40:0.03452300/24046] c6 after everything
[Date: 09-10-2013 08:14:40:0.03471200/24046] ccache_update(
[Date: 09-10-2013 08:14:40:0.03521500/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:40:0.03582100/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:40:0.03631100/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:40:0.03667400/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:40:0.07631600/24046] c6 after everything
[Date: 09-10-2013 08:14:40:0.07649500/24046] ccache_update(
[Date: 09-10-2013 08:14:40:0.07691900/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:40:0.07760800/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:40:0.07803700/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:40:0.07847100/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:40:0.11810900/24046] c6 after everything
[Date: 09-10-2013 08:14:40:0.11827400/24046] ccache_update(
[Date: 09-10-2013 08:14:40:0.11867500/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:40:0.11928600/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:40:0.11971000/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:40:0.12006800/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:40:0.15991100/24046] c6 after everything
[Date: 09-10-2013 08:14:40:0.16007700/24046] ccache_update(
[Date: 09-10-2013 08:14:40:0.16049500/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:40:0.16139100/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:40:0.16189200/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:40:0.16235100/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:40:0.20186600/24046] c6 after everything
[Date: 09-10-2013 08:14:40:0.20202400/24046] ccache_update(
[Date: 09-10-2013 08:14:40:0.20244700/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:40:0.20322300/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:40:0.20363800/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:40:0.20409600/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:40:0.24370200/24046] c6 after everything
[Date: 09-10-2013 08:14:40:0.24386500/24046] ccache_update(
[Date: 09-10-2013 08:14:40:0.24427200/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:40:0.24479600/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:40:0.24523900/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:40:0.24559800/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:40:0.27734900/24046] c6 after everything
[Date: 09-10-2013 08:14:40:0.27745900/24046] ccache_update(
[Date: 09-10-2013 08:14:40:0.27802800/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:40:0.27859400/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:40:0.27888700/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:40:0.27915200/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:40:0.31918700/24046] c6 after everything
[Date: 09-10-2013 08:14:40:0.31937200/24046] ccache_update(
[Date: 09-10-2013 08:14:40:0.31984500/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:40:0.32040200/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:40:0.32088600/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:40:0.32129400/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:40:0.35267900/24046] c6 after everything
[Date: 09-10-2013 08:14:40:0.35284700/24046] ccache_update(
[Date: 09-10-2013 08:14:40:0.35327600/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:40:0.35388600/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:40:0.35431300/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:40:0.35476300/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:40:0.38610900/24046] c6 after everything
[Date: 09-10-2013 08:14:40:0.38633300/24046] ccache_update(
[Date: 09-10-2013 08:14:40:0.38691500/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:40:0.38771200/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:40:0.38841300/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:40:0.38897500/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:40:0.41961000/24046] c6 after everything
[Date: 09-10-2013 08:14:40:0.41980300/24046] ccache_update(
[Date: 09-10-2013 08:14:40:0.42030800/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:40:0.42141300/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:40:0.42188600/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:40:0.42235200/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:40:0.45303100/24046] c6 after everything
[Date: 09-10-2013 08:14:40:0.45318300/24046] ccache_update(
[Date: 09-10-2013 08:14:40:0.45356900/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:40:0.45400500/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:40:0.45450800/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:40:0.45498300/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:40:0.48647000/24046] c6 after everything
[Date: 09-10-2013 08:14:40:0.48668800/24046] ccache_update(
[Date: 09-10-2013 08:14:40:0.48721600/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:40:0.48800800/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:40:0.48857600/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:40:0.48912900/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:40:0.51986600/24046] c6 after everything
[Date: 09-10-2013 08:14:40:0.51998500/24046] ccache_update(
[Date: 09-10-2013 08:14:40:0.52027600/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:40:0.52110300/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:40:0.52142100/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:40:0.52167500/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:40:0.56177800/24046] c6 after everything
[Date: 09-10-2013 08:14:40:0.56201000/24046] ccache_update(
[Date: 09-10-2013 08:14:40:0.56255900/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:40:0.56344900/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:40:0.56404900/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:40:0.56464200/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:40:0.59526100/24046] c6 after everything
[Date: 09-10-2013 08:14:40:0.59551000/24046] ccache_update(
[Date: 09-10-2013 08:14:40:0.59623900/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:40:0.59716100/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:40:0.59769100/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:40:0.59816100/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:40:0.67064500/24046] c6 after everything
[Date: 09-10-2013 08:14:40:0.67091500/24046] ccache_update(
[Date: 09-10-2013 08:14:40:0.67186800/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:40:0.67286300/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:40:0.67340200/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:40:0.67385300/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:40:0.70405200/24046] c6 after everything
[Date: 09-10-2013 08:14:40:0.70416100/24046] ccache_update(
[Date: 09-10-2013 08:14:40:0.70441900/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:40:0.70484600/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:40:0.70506800/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:40:0.70526200/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:40:0.74584600/24046] c6 after everything
[Date: 09-10-2013 08:14:40:0.74602200/24046] ccache_update(
[Date: 09-10-2013 08:14:40:0.74634500/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:40:0.74666600/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:40:0.74698400/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:40:0.74725000/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:40:0.77924400/24046] c6 after everything
[Date: 09-10-2013 08:14:40:0.77934000/24046] ccache_update(
[Date: 09-10-2013 08:14:40:0.77982800/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:40:0.78033400/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:40:0.78055500/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:40:0.78077200/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:40:0.81267000/24046] c6 after everything
[Date: 09-10-2013 08:14:40:0.81279300/24046] ccache_update(
[Date: 09-10-2013 08:14:40:0.81314800/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:40:0.81353400/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:40:0.81382900/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:40:0.81404600/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:40:0.84620200/24046] c6 after everything
[Date: 09-10-2013 08:14:40:0.84631700/24046] ccache_update(
[Date: 09-10-2013 08:14:40:0.84662300/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:40:0.84700000/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:40:0.84722900/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:40:0.84756200/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:40:0.92170000/24046] c6 after everything
[Date: 09-10-2013 08:14:40:0.92181400/24046] ccache_update(
[Date: 09-10-2013 08:14:40:0.92213900/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:40:0.92240500/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:40:0.92264300/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:40:0.92288100/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:40:0.99735200/24046] c6 after everything
[Date: 09-10-2013 08:14:40:0.99746700/24046] ccache_update(
[Date: 09-10-2013 08:14:40:0.99804900/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:40:0.99837800/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:40:0.99852900/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:40:0.99884900/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:41:0.06481900/24046] c6 after everything
[Date: 09-10-2013 08:14:41:0.06494400/24046] ccache_update(
[Date: 09-10-2013 08:14:41:0.06544000/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:41:0.06622600/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:41:0.06676700/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:41:0.06740000/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:41:0.09830700/24046] c6 after everything
[Date: 09-10-2013 08:14:41:0.09848100/24046] ccache_update(
[Date: 09-10-2013 08:14:41:0.09892400/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:41:0.09979500/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:41:0.10033400/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:41:0.10087700/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:41:0.13184700/24046] c6 after everything
[Date: 09-10-2013 08:14:41:0.13204900/24046] ccache_update(
[Date: 09-10-2013 08:14:41:0.13260200/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:41:0.13342600/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:41:0.13397000/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:41:0.13471100/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:41:0.16527200/24046] c6 after everything
[Date: 09-10-2013 08:14:41:0.16542600/24046] ccache_update(
[Date: 09-10-2013 08:14:41:0.16585800/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:41:0.16645400/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:41:0.16706100/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:41:0.16760100/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:41:0.21540600/24046] c6 after everything
[Date: 09-10-2013 08:14:41:0.21558300/24046] ccache_update(
[Date: 09-10-2013 08:14:41:0.21602300/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:41:0.21660000/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:41:0.21712800/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:41:0.21763000/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:41:0.24883500/24046] c6 after everything
[Date: 09-10-2013 08:14:41:0.24895800/24046] ccache_update(
[Date: 09-10-2013 08:14:41:0.24935500/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:41:0.24985900/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:41:0.25016900/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:41:0.25065900/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:41:0.28245600/24046] c6 after everything
[Date: 09-10-2013 08:14:41:0.28262900/24046] ccache_update(
[Date: 09-10-2013 08:14:41:0.28304300/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:41:0.28404900/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:41:0.28445900/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:41:0.28488400/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:41:0.35782800/24046] c6 after everything
[Date: 09-10-2013 08:14:41:0.35801500/24046] ccache_update(
[Date: 09-10-2013 08:14:41:0.35851300/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:41:0.35918600/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:41:0.35959800/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:41:0.35995900/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:41:0.39128100/24046] c6 after everything
[Date: 09-10-2013 08:14:41:0.39143100/24046] ccache_update(
[Date: 09-10-2013 08:14:41:0.39183000/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:41:0.39264600/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:41:0.39304900/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:41:0.39340300/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:41:0.42479400/24046] c6 after everything
[Date: 09-10-2013 08:14:41:0.42494600/24046] ccache_update(
[Date: 09-10-2013 08:14:41:0.42532900/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:41:0.42585300/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:41:0.42626400/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:41:0.42661000/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:41:0.44993100/24046] c6 after everything
[Date: 09-10-2013 08:14:41:0.45010100/24046] ccache_update(
[Date: 09-10-2013 08:14:41:0.45054400/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:41:0.45129000/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:41:0.45146900/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:41:0.45167200/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:41:0.47506100/24046] c6 after everything
[Date: 09-10-2013 08:14:41:0.47525100/24046] ccache_update(
[Date: 09-10-2013 08:14:41:0.47570800/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:41:0.47627600/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:41:0.47673000/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:41:0.47719600/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:41:0.50016200/24046] c6 after everything
[Date: 09-10-2013 08:14:41:0.50031900/24046] ccache_update(
[Date: 09-10-2013 08:14:41:0.50070400/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:41:0.50126200/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:41:0.50169300/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:41:0.50217100/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:41:0.53895000/24046] c6 after everything
[Date: 09-10-2013 08:14:41:0.53919900/24046] ccache_update(
[Date: 09-10-2013 08:14:41:0.53967500/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:41:0.54033600/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:41:0.54083900/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:41:0.54117300/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:41:0.56736700/24046] c6 after everything
[Date: 09-10-2013 08:14:41:0.56760600/24046] ccache_update(
[Date: 09-10-2013 08:14:41:0.56805300/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:41:0.56829000/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:41:0.56867000/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:41:0.56919500/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:41:0.61827300/24046] c6 after everything
[Date: 09-10-2013 08:14:41:0.61845100/24046] ccache_update(
[Date: 09-10-2013 08:14:41:0.61884100/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:41:0.61969500/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:41:0.62011100/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:41:0.62059400/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:41:0.68512600/24046] c6 after everything
[Date: 09-10-2013 08:14:41:0.68521700/24046] ccache_update(
[Date: 09-10-2013 08:14:41:0.68549000/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:41:0.68578600/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:41:0.68596600/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:41:0.68626500/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:41:0.71028000/24046] c6 after everything
[Date: 09-10-2013 08:14:41:0.71044700/24046] ccache_update(
[Date: 09-10-2013 08:14:41:0.71089600/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:41:0.71171500/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:41:0.71212100/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:41:0.71256100/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:41:0.73552100/24046] c6 after everything
[Date: 09-10-2013 08:14:41:0.73577800/24046] ccache_update(
[Date: 09-10-2013 08:14:41:0.73632500/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:41:0.73730800/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:41:0.73785900/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:41:0.73834900/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:41:0.76896700/24046] c6 after everything
[Date: 09-10-2013 08:14:41:0.76911800/24046] ccache_update(
[Date: 09-10-2013 08:14:41:0.76972000/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:41:0.77011400/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:41:0.77037500/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:41:0.77057900/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:41:0.83594200/24046] c6 after everything
[Date: 09-10-2013 08:14:41:0.83607800/24046] ccache_update(
[Date: 09-10-2013 08:14:41:0.83640700/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:41:0.83790800/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:41:0.83827300/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:41:0.83856200/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:41:0.86943000/24046] c6 after everything
[Date: 09-10-2013 08:14:41:0.86954000/24046] ccache_update(
[Date: 09-10-2013 08:14:41:0.86983800/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:41:0.87020900/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:41:0.87055700/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:41:0.87084400/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:41:0.90296900/24046] c6 after everything
[Date: 09-10-2013 08:14:41:0.90315000/24046] ccache_update(
[Date: 09-10-2013 08:14:41:0.90366500/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:41:0.90665900/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:41:0.90725600/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:41:0.90768300/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:41:0.93642800/24046] c6 after everything
[Date: 09-10-2013 08:14:41:0.93661100/24046] ccache_update(
[Date: 09-10-2013 08:14:41:0.93706600/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:41:0.93783900/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:41:0.93846700/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:41:0.93887700/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:41:0.97008400/24046] c6 after everything
[Date: 09-10-2013 08:14:41:0.97018300/24046] ccache_update(
[Date: 09-10-2013 08:14:41:0.97053900/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:41:0.97097700/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:41:0.97117500/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:41:0.97135200/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:42:0.01199200/24046] c6 after everything
[Date: 09-10-2013 08:14:42:0.01216300/24046] ccache_update(
[Date: 09-10-2013 08:14:42:0.01280100/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:42:0.01353900/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:42:0.01407800/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:42:0.01451200/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:42:0.07905000/24046] c6 after everything
[Date: 09-10-2013 08:14:42:0.07924600/24046] ccache_update(
[Date: 09-10-2013 08:14:42:0.07978800/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:42:0.08048800/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:42:0.08110800/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:42:0.08153200/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:42:0.11242400/24046] c6 after everything
[Date: 09-10-2013 08:14:42:0.11254500/24046] ccache_update(
[Date: 09-10-2013 08:14:42:0.11281300/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:42:0.11314400/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:42:0.11344000/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:42:0.11375200/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:42:0.14596400/24046] c6 after everything
[Date: 09-10-2013 08:14:42:0.14615500/24046] ccache_update(
[Date: 09-10-2013 08:14:42:0.14670500/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:42:0.14747900/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:42:0.14805500/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:42:0.14859100/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:42:0.21293600/24046] c6 after everything
[Date: 09-10-2013 08:14:42:0.21311500/24046] ccache_update(
[Date: 09-10-2013 08:14:42:0.21358200/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:42:0.21420400/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:42:0.21474400/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:42:0.21526100/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:42:0.27984600/24046] c6 after everything
[Date: 09-10-2013 08:14:42:0.28002400/24046] ccache_update(
[Date: 09-10-2013 08:14:42:0.28051700/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:42:0.28124100/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:42:0.28179100/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:42:0.28237300/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:42:0.31328500/24046] c6 after everything
[Date: 09-10-2013 08:14:42:0.31344600/24046] ccache_update(
[Date: 09-10-2013 08:14:42:0.31389700/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:42:0.31452000/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:42:0.31506600/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:42:0.31560000/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:42:0.34676600/24046] c6 after everything
[Date: 09-10-2013 08:14:42:0.34693100/24046] ccache_update(
[Date: 09-10-2013 08:14:42:0.34736600/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:42:0.34793000/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:42:0.34834200/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:42:0.34887100/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:42:0.38027100/24046] c6 after everything
[Date: 09-10-2013 08:14:42:0.38049600/24046] ccache_update(
[Date: 09-10-2013 08:14:42:0.38100800/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:42:0.38130800/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:42:0.38157000/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:42:0.38184300/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:42:0.41377300/24046] c6 after everything
[Date: 09-10-2013 08:14:42:0.41393400/24046] ccache_update(
[Date: 09-10-2013 08:14:42:0.41438300/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:42:0.41502100/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:42:0.41549300/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:42:0.41594700/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:42:0.44723400/24046] c6 after everything
[Date: 09-10-2013 08:14:42:0.44738900/24046] ccache_update(
[Date: 09-10-2013 08:14:42:0.44779300/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:42:0.44830000/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:42:0.44880500/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:42:0.44928600/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:42:0.48069200/24046] c6 after everything
[Date: 09-10-2013 08:14:42:0.48091600/24046] ccache_update(
[Date: 09-10-2013 08:14:42:0.48122400/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:42:0.48147700/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:42:0.48172700/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:42:0.48198300/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:42:0.51417200/24046] c6 after everything
[Date: 09-10-2013 08:14:42:0.51436000/24046] ccache_update(
[Date: 09-10-2013 08:14:42:0.51491300/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:42:0.51553000/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:42:0.51603700/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:42:0.51653300/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:42:0.54761100/24046] c6 after everything
[Date: 09-10-2013 08:14:42:0.54776700/24046] ccache_update(
[Date: 09-10-2013 08:14:42:0.54818000/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:42:0.54871900/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:42:0.54914100/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:42:0.54957100/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:42:0.58942000/24046] c6 after everything
[Date: 09-10-2013 08:14:42:0.58955600/24046] ccache_update(
[Date: 09-10-2013 08:14:42:0.58999500/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:42:0.59059500/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:42:0.59117500/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:42:0.59166400/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:42:0.62292700/24046] c6 after everything
[Date: 09-10-2013 08:14:42:0.62307900/24046] ccache_update(
[Date: 09-10-2013 08:14:42:0.62354000/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:42:0.62406800/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:42:0.62465900/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:42:0.62519900/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:42:0.66477100/24046] c6 after everything
[Date: 09-10-2013 08:14:42:0.66495400/24046] ccache_update(
[Date: 09-10-2013 08:14:42:0.66548200/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:42:0.66705500/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:42:0.66751500/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:42:0.66801800/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:42:0.69823300/24046] c6 after everything
[Date: 09-10-2013 08:14:42:0.69841400/24046] ccache_update(
[Date: 09-10-2013 08:14:42:0.69920100/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:42:0.69988400/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:42:0.70039000/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:42:0.70086000/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:42:0.76540700/24046] c6 after everything
[Date: 09-10-2013 08:14:42:0.76557300/24046] ccache_update(
[Date: 09-10-2013 08:14:42:0.76618900/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:42:0.76670900/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:42:0.76714400/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:42:0.76744800/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:42:0.83238200/24046] c6 after everything
[Date: 09-10-2013 08:14:42:0.83257300/24046] ccache_update(
[Date: 09-10-2013 08:14:42:0.83310600/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:42:0.83382000/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:42:0.83436600/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:42:0.83492900/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:42:0.87420000/24046] c6 after everything
[Date: 09-10-2013 08:14:42:0.87430800/24046] ccache_update(
[Date: 09-10-2013 08:14:42:0.87466500/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:42:0.87501400/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:42:0.87536200/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:42:0.87565600/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:42:0.91600000/24046] c6 after everything
[Date: 09-10-2013 08:14:42:0.91613800/24046] ccache_update(
[Date: 09-10-2013 08:14:42:0.91642700/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:42:0.91676900/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:42:0.91700200/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:42:0.91721100/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:42:0.94984400/24046] c6 after everything
[Date: 09-10-2013 08:14:42:0.95007200/24046] ccache_update(
[Date: 09-10-2013 08:14:42:0.95060100/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:42:0.95102900/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:42:0.95148700/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:42:0.95190700/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:42:0.99994700/24046] c6 after everything
[Date: 09-10-2013 08:14:43:0.00011500/24046] ccache_update(
[Date: 09-10-2013 08:14:43:0.00058800/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:43:0.00113600/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:43:0.00168800/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:43:0.00213000/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:43:0.02502400/24046] c6 after everything
[Date: 09-10-2013 08:14:43:0.02513400/24046] ccache_update(
[Date: 09-10-2013 08:14:43:0.02536500/24046] c1 after ccache_find
[Date: 09-10-2013 08:14:43:0.02565300/24046] c3a after getFeedArticles
[Date: 09-10-2013 08:14:43:0.02589800/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:43:0.02621700/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:43:0.15047100/24046] c6 after everything
[Date: 09-10-2013 08:14:43:0.15173700/24046] c3 after select ttrss_counters_cache
[Date: 09-10-2013 08:14:43:0.15226000/24046] c4 after SELECT $table
[Date: 09-10-2013 08:14:43:0.15303000/24046] c5 after INSERT/UPDATE
[Date: 09-10-2013 08:14:43:0.19249100/24046] c6 after everything
[Date: 09-10-2013 08:14:43:0.19269400/24046] c6 after everything
[Date: 09-10-2013 08:14:43:0.19287400/24046] ccache_update
[Date: 09-10-2013 08:14:43:0.19304200/24046] catchupArticleById)
[Date: 09-10-2013 08:14:43:0.19320300/24046] array_push2(
[Date: 09-10-2013 08:14:43:0.19336200/24046] format_article(
[Date: 09-10-2013 08:14:43:0.19382400/24046] 1 query ttrss_user_entries
[Date: 09-10-2013 08:14:43:0.19402000/24046] 2 update ttrss_user_entries
[Date: 09-10-2013 08:14:43:0.19475800/24046] 3 query ttrss_entries,ttrss_user_entries
[Date: 09-10-2013 08:14:43:0.21829100/24046] format_article)
[Date: 09-10-2013 08:14:43:0.21846800/24046] array_push2)
[Date: 09-10-2013 08:14:43:0.21857900/24046] array_push2(
[Date: 09-10-2013 08:14:43:0.21868100/24046] format_article(
[Date: 09-10-2013 08:14:43:0.21908200/24046] 1 query ttrss_user_entries
[Date: 09-10-2013 08:14:43:0.21923400/24046] 2 update ttrss_user_entries
[Date: 09-10-2013 08:14:43:0.21995900/24046] 3 query ttrss_entries,ttrss_user_entries
[Date: 09-10-2013 08:14:43:0.24360800/24046] format_article)
[Date: 09-10-2013 08:14:43:0.24375500/24046] array_push2)
[Date: 09-10-2013 08:14:43:0.24386000/24046] array_push2(
[Date: 09-10-2013 08:14:43:0.24395900/24046] format_article(
[Date: 09-10-2013 08:14:43:0.24435000/24046] 1 query ttrss_user_entries
[Date: 09-10-2013 08:14:43:0.24450200/24046] 2 update ttrss_user_entries
[Date: 09-10-2013 08:14:43:0.24517300/24046] 3 query ttrss_entries,ttrss_user_entries
[Date: 09-10-2013 08:14:43:0.30305400/24046] format_article)
[Date: 09-10-2013 08:14:43:0.30324700/24046] array_push2)
[Date: 09-10-2013 08:14:43:0.30340800/24046] array_push2(
[Date: 09-10-2013 08:14:43:0.30356100/24046] format_article(
[Date: 09-10-2013 08:14:43:0.30399200/24046] 1 query ttrss_user_entries
[Date: 09-10-2013 08:14:43:0.30417800/24046] 2 update ttrss_user_entries
[Date: 09-10-2013 08:14:43:0.30487700/24046] 3 query ttrss_entries,ttrss_user_entries
[Date: 09-10-2013 08:14:43:0.33663300/24046] format_article)
[Date: 09-10-2013 08:14:43:0.33677800/24046] array_push2)
[Date: 09-10-2013 08:14:43:0.33688200/24046] array_push2(
[Date: 09-10-2013 08:14:43:0.33698000/24046] format_article(
[Date: 09-10-2013 08:14:43:0.33738000/24046] 1 query ttrss_user_entries
[Date: 09-10-2013 08:14:43:0.33753200/24046] 2 update ttrss_user_entries
[Date: 09-10-2013 08:14:43:0.33819100/24046] 3 query ttrss_entries,ttrss_user_entries
[Date: 09-10-2013 08:14:43:0.37024900/24046] format_article)
[Date: 09-10-2013 08:14:43:0.37039600/24046] array_push2)


I tried to make the log points kind of self explanatory. What I found in the end is that the call(s) to ccache_update() are the culprit for the pretty constant 5 second delay. TTRSS seems to fire a ton of SQL statements here which all don't take longer than a few ms themselves, but since its so many it adds up to 5 secs.

Since I have no comparison I am wondering now:

a) I have a really bad case of data in my feeds that doesnt really work well with caching (yes i do have some hacked up/custom generated rss feeds from website monitoring)
b) my database/webserver is performing really bad
c) any sort of bug/performance hit was introduced in 1.10

Is there anything that I can do/check further?

should i post my changed .php files for you to check out your speeds for comparison?

BTW: my procedure was: reload tinytinyrss via F5 (chrome) and click on the first article in fresh articles.

User avatar
fox
^ me reading your posts ^
Posts: 6318
Joined: 27 Aug 2005, 22:53
Location: Saint-Petersburg, Russia
Contact:

Re: Performance with displaying articles ttrss 1.10?

Postby fox » 09 Oct 2013, 12:37

FWIW ccache implementation was not altered in any way between 1.9 and 1.10 so it shouldn't be upgrade related.

>should i post my changed .php files for you to check out your speeds for comparison?

Sure, preferably just make a pull request on github and I'll post the results here.

e: you can also try combined mode

ralfonat
Bear Rating Trainee
Bear Rating Trainee
Posts: 21
Joined: 20 Mar 2013, 13:53

Re: Performance with displaying articles ttrss 1.10?

Postby ralfonat » 09 Oct 2013, 15:57

Just learned how to use git to make that pull request.

https://github.com/gothfox/Tiny-Tiny-RSS/pull/313

I hope i only shot my knee 3 times during this.

Let me know if this works for you

User avatar
fox
^ me reading your posts ^
Posts: 6318
Joined: 27 Aug 2005, 22:53
Location: Saint-Petersburg, Russia
Contact:

Re: Performance with displaying articles ttrss 1.10?

Postby fox » 09 Oct 2013, 16:24

Clicked on a few articles.
Attachments
px.log
(12.93 KiB) Downloaded 52 times

ralfonat
Bear Rating Trainee
Bear Rating Trainee
Posts: 21
Joined: 20 Mar 2013, 13:53

Re: Performance with displaying articles ttrss 1.10?

Postby ralfonat » 09 Oct 2013, 16:30

Yes, interestingly enough in combined mode theres 0 delay

However that doesnt really seem usable to me UI wise

ralfonat
Bear Rating Trainee
Bear Rating Trainee
Posts: 21
Joined: 20 Mar 2013, 13:53

Re: Performance with displaying articles ttrss 1.10?

Postby ralfonat » 09 Oct 2013, 16:33

Hm interesting,

Did you do the same as I posted? Reload (F5) tinytinyrss window and click on one article?

Because from one view() call I got 133 calls to ccache_update() (see above)

and you get 5?!

User avatar
fox
^ me reading your posts ^
Posts: 6318
Joined: 27 Aug 2005, 22:53
Location: Saint-Petersburg, Russia
Contact:

Re: Performance with displaying articles ttrss 1.10?

Postby fox » 09 Oct 2013, 16:46

F5, clicked on a few articles in fresh feed.
Attachments
px.log
(33.04 KiB) Downloaded 46 times

User avatar
fox
^ me reading your posts ^
Posts: 6318
Joined: 27 Aug 2005, 22:53
Location: Saint-Petersburg, Russia
Contact:

Re: Performance with displaying articles ttrss 1.10?

Postby fox » 09 Oct 2013, 16:49

I think it goes like this. You click on an article, it gets marked as read.

Counter cache is updated for the feed the article belongs to. It causes the counter for the parent category to be recalculated. Which causes counters for all child feeds of that category to be recalculated. If your mysql is kinda slow and you have a lot of feeds in this category, I guess this could stack up.

e: It worked like this I think ever since the cache was introduced a few years ago, though, it's not something new.

e2: also if the categories are nested, I guess those could also start recalculating, but I'm not sure how that works exactly atm.

e3: also same basic thing is done in combined mode, the only difference being the counter calculation is not linked to actually displaying an article to you and is done in background.

User avatar
fox
^ me reading your posts ^
Posts: 6318
Joined: 27 Aug 2005, 22:53
Location: Saint-Petersburg, Russia
Contact:

Re: Performance with displaying articles ttrss 1.10?

Postby fox » 09 Oct 2013, 16:59

https://github.com/gothfox/Tiny-Tiny-RS ... 7b7c9692ef

Try this. Not sure if it breaks any counter stuff, but it probably shouldn't.

ralfonat
Bear Rating Trainee
Bear Rating Trainee
Posts: 21
Joined: 20 Mar 2013, 13:53

Re: Performance with displaying articles ttrss 1.10?

Postby ralfonat » 09 Oct 2013, 18:12

Ah, OK. I get the picture.

I have roughly 150 subscriptions in that category. Is that much?

ralfonat
Bear Rating Trainee
Bear Rating Trainee
Posts: 21
Joined: 20 Mar 2013, 13:53

Re: Performance with displaying articles ttrss 1.10?

Postby ralfonat » 09 Oct 2013, 18:13

fox wrote:https://github.com/gothfox/Tiny-Tiny-RSS/commit/b66be03a4865b50efeddfce00cc45b7b7c9692ef

Try this. Not sure if it breaks any counter stuff, but it probably shouldn't.


I'll try this in a few.

ralfonat
Bear Rating Trainee
Bear Rating Trainee
Posts: 21
Joined: 20 Mar 2013, 13:53

Re: Performance with displaying articles ttrss 1.10?

Postby ralfonat » 09 Oct 2013, 18:45

Side question:

In my feeds i have <img> tags and I noticed that in combined mode the images seem to be preloaded in the background? At least when i quickly browsed through a number of items it felt like none had to be requested.

If that's the case I guess its just the fundamental difference to the normal mode and won't happen in normal mode?

User avatar
fox
^ me reading your posts ^
Posts: 6318
Joined: 27 Aug 2005, 22:53
Location: Saint-Petersburg, Russia
Contact:

Re: Performance with displaying articles ttrss 1.10?

Postby fox » 09 Oct 2013, 18:49

tt-rss does not do any preloading, it outputs html content for your browser to download. Your problem with ccache has nothing to do with it.

e: it may appear this way for some technical reasons involving the difference in layout in cdm and 3 panel modes (the article is already there, etc)

In fact the articles in combined mode are encoded in the buffer until you scroll to them to save on unneeded "preloading".


Return to “Support”

Who is online

Users browsing this forum: No registered users and 4 guests