Page MenuHomePhabricator

Expectation (readQueryTime <= 5) by MediaWiki\Api\ApiMain::setRequestExpectations not met (actual: {actualSeconds}) in trx #{trxId}:{query}
Closed, ResolvedPublicPRODUCTION ERROR

Description

Error
  • mwversion: 1.45.0-wmf.8
  • timestamp: 2025-07-07T18:47:25.340Z
  • phpversion: 8.1.32
  • reqId: d4b46abe-feae-4685-bd84-efafd2315cf1
  • Find reqId in Logstash
normalized_message
Expectation (readQueryTime <= 5) by MediaWiki\Api\ApiMain::setRequestExpectations not met (actual: {actualSeconds}) in trx #{trxId}:
{query}
FrameLocationCall
from/srv/mediawiki/php-1.45.0-wmf.8/includes/libs/rdbms/TransactionProfiler.php(558)
#0/srv/mediawiki/php-1.45.0-wmf.8/includes/libs/rdbms/TransactionProfiler.php(363)Wikimedia\Rdbms\TransactionProfiler->reportExpectationViolated(string, Wikimedia\Rdbms\GeneralizedSql, float, string, string)
#1/srv/mediawiki/php-1.45.0-wmf.8/includes/libs/rdbms/database/TransactionManager.php(574)Wikimedia\Rdbms\TransactionProfiler->recordQueryCompletion(Wikimedia\Rdbms\GeneralizedSql, float, bool, int, string, string)
#2/srv/mediawiki/php-1.45.0-wmf.8/includes/libs/rdbms/database/Database.php(858)Wikimedia\Rdbms\TransactionManager->recordQueryCompletion(Wikimedia\Rdbms\GeneralizedSql, float, bool, int, string)
#3/srv/mediawiki/php-1.45.0-wmf.8/includes/libs/rdbms/database/Database.php(711)Wikimedia\Rdbms\Database->attemptQuery(Wikimedia\Rdbms\Query, string, bool)
#4/srv/mediawiki/php-1.45.0-wmf.8/includes/libs/rdbms/database/Database.php(638)Wikimedia\Rdbms\Database->executeQuery(Wikimedia\Rdbms\Query, string, int)
#5/srv/mediawiki/php-1.45.0-wmf.8/includes/libs/rdbms/database/Database.php(1367)Wikimedia\Rdbms\Database->query(Wikimedia\Rdbms\Query, string)
#6/srv/mediawiki/php-1.45.0-wmf.8/includes/libs/rdbms/database/DBConnRef.php(127)Wikimedia\Rdbms\Database->select(array, array, array, string, array, array)
#7/srv/mediawiki/php-1.45.0-wmf.8/includes/libs/rdbms/database/DBConnRef.php(351)Wikimedia\Rdbms\DBConnRef->__call(string, array)
#8/srv/mediawiki/php-1.45.0-wmf.8/includes/libs/rdbms/querybuilder/SelectQueryBuilder.php(762)Wikimedia\Rdbms\DBConnRef->select(array, array, array, string, array, array)
#9/srv/mediawiki/php-1.45.0-wmf.8/extensions/intersection/includes/Hooks.php(707)Wikimedia\Rdbms\SelectQueryBuilder->fetchResultSet()
#10/srv/mediawiki/php-1.45.0-wmf.8/includes/poolcounter/PoolCounterWorkViaCallback.php(81)MediaWiki\Extension\DynamicPageList\Hooks::MediaWiki\Extension\DynamicPageList\{closure}()
#11/srv/mediawiki/php-1.45.0-wmf.8/includes/poolcounter/PoolCounterWork.php(173)MediaWiki\PoolCounter\PoolCounterWorkViaCallback->doWork()
#12/srv/mediawiki/php-1.45.0-wmf.8/extensions/intersection/includes/Hooks.php(743)MediaWiki\PoolCounter\PoolCounterWork->execute()
#13/srv/mediawiki/php-1.45.0-wmf.8/includes/libs/objectcache/WANObjectCache.php(1842)MediaWiki\Extension\DynamicPageList\Hooks::MediaWiki\Extension\DynamicPageList\{closure}(bool, int, array, null, array)
#14/srv/mediawiki/php-1.45.0-wmf.8/includes/libs/objectcache/WANObjectCache.php(1646)Wikimedia\ObjectCache\WANObjectCache->fetchOrRegenerate(string, int, Closure, array, array)
#15/srv/mediawiki/php-1.45.0-wmf.8/extensions/intersection/includes/Hooks.php(756)Wikimedia\ObjectCache\WANObjectCache->getWithSetCallback(string, int, Closure, array)
#16/srv/mediawiki/php-1.45.0-wmf.8/extensions/intersection/includes/Hooks.php(544)MediaWiki\Extension\DynamicPageList\Hooks::processQuery(string, Wikimedia\Rdbms\DBConnRef, Wikimedia\Rdbms\SelectQueryBuilder)
#17/srv/mediawiki/php-1.45.0-wmf.8/includes/parser/Parser.php(4091)MediaWiki\Extension\DynamicPageList\Hooks::renderDynamicPageList(string, array, MediaWiki\Parser\Parser, MediaWiki\Parser\PPTemplateFrame_Hash)
#18/srv/mediawiki/php-1.45.0-wmf.8/includes/parser/PPFrame_Hash.php(362)MediaWiki\Parser\Parser->extensionSubstitution(array, MediaWiki\Parser\PPTemplateFrame_Hash, bool)
#19/srv/mediawiki/php-1.45.0-wmf.8/includes/parser/PPTemplateFrame_Hash.php(180)MediaWiki\Parser\PPFrame_Hash->expand(MediaWiki\Parser\PPNode_Hash_Tree, int)
#20/srv/mediawiki/php-1.45.0-wmf.8/includes/parser/PPTemplateFrame_Hash.php(192)MediaWiki\Parser\PPTemplateFrame_Hash->getNamedArgument(string)
#21/srv/mediawiki/php-1.45.0-wmf.8/includes/parser/Parser.php(3994)MediaWiki\Parser\PPTemplateFrame_Hash->getArgument(string)
#22/srv/mediawiki/php-1.45.0-wmf.8/includes/parser/PPFrame_Hash.php(297)MediaWiki\Parser\Parser->argSubstitution(array, MediaWiki\Parser\PPTemplateFrame_Hash)
#23/srv/mediawiki/php-1.45.0-wmf.8/includes/parser/Parser.php(3360)MediaWiki\Parser\PPFrame_Hash->expand(MediaWiki\Parser\PPNode_Hash_Tree)
#24/srv/mediawiki/php-1.45.0-wmf.8/includes/parser/PPFrame_Hash.php(280)MediaWiki\Parser\Parser->braceSubstitution(array, MediaWiki\Parser\PPTemplateFrame_Hash)
#25/srv/mediawiki/php-1.45.0-wmf.8/includes/parser/PPTemplateFrame_Hash.php(100)MediaWiki\Parser\PPFrame_Hash->expand(MediaWiki\Parser\PPNode_Hash_Tree, int)
#26/srv/mediawiki/php-1.45.0-wmf.8/includes/parser/Parser.php(3357)MediaWiki\Parser\PPTemplateFrame_Hash->cachedExpand(string, MediaWiki\Parser\PPNode_Hash_Tree)
#27/srv/mediawiki/php-1.45.0-wmf.8/includes/parser/PPFrame_Hash.php(280)MediaWiki\Parser\Parser->braceSubstitution(array, MediaWiki\Parser\PPFrame_Hash)
#28/srv/mediawiki/php-1.45.0-wmf.8/includes/parser/Parser.php(2982)MediaWiki\Parser\PPFrame_Hash->expand(MediaWiki\Parser\PPNode_Hash_Tree, int)
#29/srv/mediawiki/php-1.45.0-wmf.8/includes/parser/Parser.php(1618)MediaWiki\Parser\Parser->replaceVariables(string)
#30/srv/mediawiki/php-1.45.0-wmf.8/includes/parser/Parser.php(705)MediaWiki\Parser\Parser->internalParse(string)
#31/srv/mediawiki/php-1.45.0-wmf.8/includes/content/WikitextContentHandler.php(382)MediaWiki\Parser\Parser->parse(string, MediaWiki\Title\Title, MediaWiki\Parser\ParserOptions, bool, bool, int)
#32/srv/mediawiki/php-1.45.0-wmf.8/includes/content/ContentHandler.php(1695)MediaWiki\Content\WikitextContentHandler->fillParserOutput(MediaWiki\Content\WikitextContent, MediaWiki\Content\Renderer\ContentParseParams, MediaWiki\Parser\ParserOutput)
#33/srv/mediawiki/php-1.45.0-wmf.8/includes/content/Renderer/ContentRenderer.php(75)MediaWiki\Content\ContentHandler->getParserOutput(MediaWiki\Content\WikitextContent, MediaWiki\Content\Renderer\ContentParseParams)
#34/srv/mediawiki/php-1.45.0-wmf.8/includes/Revision/RenderedRevision.php(261)MediaWiki\Content\Renderer\ContentRenderer->getParserOutput(MediaWiki\Content\WikitextContent, MediaWiki\Page\PageIdentityValue, MediaWiki\Revision\RevisionStoreRecord, MediaWiki\Parser\ParserOptions, array)
#35/srv/mediawiki/php-1.45.0-wmf.8/includes/Revision/RenderedRevision.php(233)MediaWiki\Revision\RenderedRevision->getSlotParserOutputUncached(MediaWiki\Content\WikitextContent, array)
#36/srv/mediawiki/php-1.45.0-wmf.8/includes/Revision/RevisionRenderer.php(238)MediaWiki\Revision\RenderedRevision->getSlotParserOutput(string, array)
#37/srv/mediawiki/php-1.45.0-wmf.8/includes/Revision/RevisionRenderer.php(171)MediaWiki\Revision\RevisionRenderer->combineSlotOutput(MediaWiki\Revision\RenderedRevision, MediaWiki\Parser\ParserOptions, array)
#38/srv/mediawiki/php-1.45.0-wmf.8/includes/Revision/RenderedRevision.php(196)MediaWiki\Revision\RevisionRenderer->MediaWiki\Revision\{closure}(MediaWiki\Revision\RenderedRevision, array)
#39/srv/mediawiki/php-1.45.0-wmf.8/includes/page/ParserOutputAccess.php(591)MediaWiki\Revision\RenderedRevision->getRevisionParserOutput()
#40/srv/mediawiki/php-1.45.0-wmf.8/includes/page/ParserOutputAccess.php(505)MediaWiki\Page\ParserOutputAccess->renderRevision(MediaWiki\Page\WikiPage, MediaWiki\Parser\ParserOptions, MediaWiki\Revision\RevisionStoreRecord, array, null)
#41/srv/mediawiki/php-1.45.0-wmf.8/includes/content/ContentHandler.php(1465)MediaWiki\Page\ParserOutputAccess->getParserOutput(MediaWiki\Page\WikiPage, MediaWiki\Parser\ParserOptions, MediaWiki\Revision\RevisionStoreRecord, array)
#42/srv/mediawiki/php-1.45.0-wmf.8/extensions/CirrusSearch/includes/BuildDocument/ParserOutputPageProperties.php(99)MediaWiki\Content\ContentHandler->getParserOutputForIndexing(MediaWiki\Page\WikiPage, null, MediaWiki\Revision\RevisionStoreRecord)
#43/srv/mediawiki/php-1.45.0-wmf.8/includes/libs/objectcache/WANObjectCache.php(1842)CirrusSearch\BuildDocument\ParserOutputPageProperties->CirrusSearch\BuildDocument\{closure}(bool, int, array, null, array)
#44/srv/mediawiki/php-1.45.0-wmf.8/includes/libs/objectcache/WANObjectCache.php(1646)Wikimedia\ObjectCache\WANObjectCache->fetchOrRegenerate(string, int, Closure, array, array)
#45/srv/mediawiki/php-1.45.0-wmf.8/extensions/CirrusSearch/includes/BuildDocument/ParserOutputPageProperties.php(113)Wikimedia\ObjectCache\WANObjectCache->getWithSetCallback(string, int, Closure)
#46/srv/mediawiki/php-1.45.0-wmf.8/extensions/CirrusSearch/includes/BuildDocument/ParserOutputPageProperties.php(49)CirrusSearch\BuildDocument\ParserOutputPageProperties->finalizeReal(Elastica\Document, MediaWiki\Page\WikiPage, CirrusSearch\CirrusSearch, MediaWiki\Revision\RevisionStoreRecord)
#47/srv/mediawiki/php-1.45.0-wmf.8/extensions/CirrusSearch/includes/BuildDocument/BuildDocument.php(215)CirrusSearch\BuildDocument\ParserOutputPageProperties->finalize(Elastica\Document, MediaWiki\Title\Title, MediaWiki\Revision\RevisionStoreRecord)
#48/srv/mediawiki/php-1.45.0-wmf.8/extensions/CirrusSearch/includes/Api/QueryBuildDocument.php(128)CirrusSearch\BuildDocument\BuildDocument->finalize(Elastica\Document, bool, MediaWiki\Revision\RevisionStoreRecord)
#49/srv/mediawiki/php-1.45.0-wmf.8/includes/api/ApiQuery.php(745)CirrusSearch\Api\QueryBuildDocument->execute()
#50/srv/mediawiki/php-1.45.0-wmf.8/includes/api/ApiMain.php(2016)MediaWiki\Api\ApiQuery->execute()
#51/srv/mediawiki/php-1.45.0-wmf.8/includes/api/ApiMain.php(954)MediaWiki\Api\ApiMain->executeAction()
#52/srv/mediawiki/php-1.45.0-wmf.8/includes/api/ApiMain.php(925)MediaWiki\Api\ApiMain->executeActionWithErrorHandling()
#53/srv/mediawiki/php-1.45.0-wmf.8/includes/api/ApiEntryPoint.php(152)MediaWiki\Api\ApiMain->execute()
#54/srv/mediawiki/php-1.45.0-wmf.8/includes/MediaWikiEntryPoint.php(198)MediaWiki\Api\ApiEntryPoint->execute()
#55/srv/mediawiki/php-1.45.0-wmf.8/api.php(44)MediaWiki\MediaWikiEntryPoint->run()
#56/srv/mediawiki/w/api.php(3)require(string)
#57{main}
Impact
Notes

Event Timeline

Change #1167311 had a related patch set uploaded (by Zabe; author: Zabe):

[mediawiki/extensions/intersection@master] Fix categorylinks read new code for excluding categories

https://gerrit.wikimedia.org/r/1167311

Change #1167311 merged by jenkins-bot:

[mediawiki/extensions/intersection@master] Fix categorylinks read new code for excluding categories

https://gerrit.wikimedia.org/r/1167311

Change #1167569 had a related patch set uploaded (by Zabe; author: Zabe):

[mediawiki/extensions/intersection@wmf/1.45.0-wmf.8] Fix categorylinks read new code for excluding categories

https://gerrit.wikimedia.org/r/1167569

Change #1167570 had a related patch set uploaded (by Zabe; author: Zabe):

[mediawiki/extensions/intersection@wmf/1.45.0-wmf.9] Fix categorylinks read new code for excluding categories

https://gerrit.wikimedia.org/r/1167570

Wargo triaged this task as Unbreak Now! priority.Wed, Jul 9, 12:58 PM
Wargo subscribed.

Resulted in pt wikinews main page error.

Change #1167570 merged by jenkins-bot:

[mediawiki/extensions/intersection@wmf/1.45.0-wmf.9] Fix categorylinks read new code for excluding categories

https://gerrit.wikimedia.org/r/1167570

Change #1167569 merged by jenkins-bot:

[mediawiki/extensions/intersection@wmf/1.45.0-wmf.8] Fix categorylinks read new code for excluding categories

https://gerrit.wikimedia.org/r/1167569

Mentioned in SAL (#wikimedia-operations) [2025-07-09T13:52:37Z] <zabe@deploy1003> Started scap sync-world: Backport for [[gerrit:1167574|ApiQueryCategoryMembers: Try stop forcing index in read new code (T399037)]], [[gerrit:1167573|ApiQueryCategoryMembers: Try stop forcing index in read new code (T399037)]], [[gerrit:1167570|Fix categorylinks read new code for excluding categories (T398861 T398939)]], [[gerrit:1167569|Fix categorylinks read new code for excluding categories (T39886

Mentioned in SAL (#wikimedia-operations) [2025-07-09T13:54:47Z] <zabe@deploy1003> zabe: Backport for [[gerrit:1167574|ApiQueryCategoryMembers: Try stop forcing index in read new code (T399037)]], [[gerrit:1167573|ApiQueryCategoryMembers: Try stop forcing index in read new code (T399037)]], [[gerrit:1167570|Fix categorylinks read new code for excluding categories (T398861 T398939)]], [[gerrit:1167569|Fix categorylinks read new code for excluding categories (T398861 T398939)]] synced

Mentioned in SAL (#wikimedia-operations) [2025-07-09T14:01:20Z] <zabe@deploy1003> Finished scap sync-world: Backport for [[gerrit:1167574|ApiQueryCategoryMembers: Try stop forcing index in read new code (T399037)]], [[gerrit:1167573|ApiQueryCategoryMembers: Try stop forcing index in read new code (T399037)]], [[gerrit:1167570|Fix categorylinks read new code for excluding categories (T398861 T398939)]], [[gerrit:1167569|Fix categorylinks read new code for excluding categories (T3988

Zabe lowered the priority of this task from Unbreak Now! to Needs Triage.EditedWed, Jul 9, 2:06 PM

Resulted in pt wikinews main page error.

This task is about the query being slow. T398939 is the task considering the bug, so that should rather have been UBN.

Will leave this open until I am sure the new query is no longer to slow.

Zabe claimed this task.