DiscussionTools PHPUnit integration tests are failing causing CI failures on that extension and on the ReportIncident extension:
21:22:02 There were 7 failures: 21:22:02 21:22:02 1) MediaWiki\Extension\DiscussionTools\Tests\ThreadItemStoreTest::testInsertThreadItems with data set #0 ('cases/ThreadItemStore/1simple-example') 21:22:02 Failed asserting that two arrays are equal. 21:22:02 --- Expected 21:22:02 +++ Actual 21:22:02 @@ @@ 21:22:02 'discussiontools_items' => Array ( 21:22:02 0 => Array (...) 21:22:02 1 => Array (...) 21:22:02 - 2 => Array (...) 21:22:02 - 3 => Array (...) 21:22:02 ) 21:22:02 'discussiontools_item_pages' => Array ( 21:22:02 0 => Array (...) 21:22:02 1 => Array (...) 21:22:02 - 2 => Array (...) 21:22:02 - 3 => Array (...) 21:22:02 ) 21:22:02 'discussiontools_item_revisions' => Array ( 21:22:02 0 => Array (...) 21:22:02 1 => Array (...) 21:22:02 2 => Array ( 21:22:02 - 'itr_id' => '5' 21:22:02 - 'itr_itemid_id' => '3' 21:22:02 - 'itr_revision_id' => '2' 21:22:02 - 'itr_items_id' => '3' 21:22:02 - 'itr_parent_id' => '4' 21:22:02 - 'itr_transcludedfrom' => null 21:22:02 - 'itr_level' => '2' 21:22:02 - 'itr_headinglevel' => null 21:22:02 - ) 21:22:02 - 3 => Array ( 21:22:02 'itr_id' => '3' 21:22:02 'itr_itemid_id' => '1' 21:22:02 'itr_revision_id' => '3' 21:22:02 @@ @@ 21:22:02 'itr_level' => '0' 21:22:02 'itr_headinglevel' => '2' 21:22:02 ) 21:22:02 - 4 => Array (...) 21:22:02 - 5 => Array (...) 21:22:02 - 6 => Array (...) 21:22:02 + 3 => Array ( 21:22:02 + 'itr_id' => '4' 21:22:02 + 'itr_itemid_id' => '2' 21:22:02 + 'itr_revision_id' => '3' 21:22:02 + 'itr_items_id' => '2' 21:22:02 + 'itr_parent_id' => '3' 21:22:02 + 'itr_transcludedfrom' => null 21:22:02 + 'itr_level' => '1' 21:22:02 + 'itr_headinglevel' => null 21:22:02 + ) 21:22:02 ) 21:22:02 'discussiontools_item_ids' => Array ( 21:22:02 0 => Array (...) 21:22:02 1 => Array (...) 21:22:02 - 2 => Array (...) 21:22:02 - 3 => Array (...) 21:22:02 ) 21:22:02 ) 21:22:02 21:22:02 /workspace/src/extensions/DiscussionTools/tests/phpunit/ThreadItemStoreTest.php:88 21:22:02 phpvfscomposer:///workspace/src/vendor/phpunit/phpunit/phpunit:97 21:22:02 === Logs generated by test case 21:22:02 [objectcache] [debug] MainWANObjectCache using store {class} {"class":"HashBagOStuff"} 21:22:02 [localisation] [debug] LocalisationCache using store LCStoreNull [] 21:22:02 [CentralAuth] [debug] Loading state for global user {user} from DB {"user":"X"} 21:22:02 [CentralAuth] [debug] Loading attached wiki list for global user X from DB [] 21:22:02 [CentralAuth] [debug] Loading groups for global user {user} {"user":"X"} 21:22:02 [objectcache] [debug] fetchOrRegenerate(global:centralauth-user:02129bb861061d1a052c592e2dc6b383): miss, new value computed [] 21:22:02 [CentralAuth] [debug] Loading CentralAuthUser for user {user} from cache object {"user":"X"} 21:22:02 [CentralAuth] [debug] Loading state for global user {user} from DB {"user":"Y"} 21:22:02 [CentralAuth] [debug] Loading attached wiki list for global user Y from DB [] 21:22:02 [CentralAuth] [debug] Loading groups for global user {user} {"user":"Y"} 21:22:02 [objectcache] [debug] fetchOrRegenerate(global:centralauth-user:57cec4137b614c87cb4e24a3d003a3e0): miss, new value computed [] 21:22:02 [CentralAuth] [debug] Loading CentralAuthUser for user {user} from cache object {"user":"Y"} 21:22:02 [CentralAuth] [debug] Loading state for global user {user} from DB {"user":"Z"} 21:22:02 [CentralAuth] [debug] Loading attached wiki list for global user Z from DB [] 21:22:02 [CentralAuth] [debug] Loading groups for global user {user} {"user":"Z"} 21:22:02 [objectcache] [debug] fetchOrRegenerate(global:centralauth-user:21c2e59531c8710156d34a3c30ac81d5): miss, new value computed [] 21:22:02 [CentralAuth] [debug] Loading CentralAuthUser for user {user} from cache object {"user":"Z"} 21:22:02 [localisation] [debug] LocalisationCache::isExpired(en): cache missing, need to make one [] 21:22:02 [wfDebug] [debug] ParserFactory: using default preprocessor {"private":false} 21:22:02 [ContentHandler] [info] Registered handler for wikitext: WikitextContentHandler [] 21:22:02 [MessageCache] [debug] MessageCache using store {class} {"class":"HashBagOStuff"} 21:22:02 [objectcache] [debug] MainObjectStash using store {class} {"class":"HashBagOStuff"} 21:22:02 [ParserCache] [debug] Creating ParserCache instance for pcache [] 21:22:02 [objectcache] [warning] Rejected set() for {cachekey} due to pending writes. {"cachekey":"global:NameTableSqlStore:content_models:wikidb-unittest_","lag":0,"age":0.7451488971710205,"walltime":0.0002961158752441406} 21:22:02 [objectcache] [debug] fetchOrRegenerate(global:NameTableSqlStore:content_models:wikidb-unittest_): miss, new value computed [] 21:22:02 [objectcache] [warning] Rejected set() for {cachekey} due to pending writes. {"cachekey":"global:NameTableSqlStore:slot_roles:wikidb-unittest_","lag":0,"age":0.7460830211639404,"walltime":0.00023221969604492188} 21:22:02 [objectcache] [debug] fetchOrRegenerate(global:NameTableSqlStore:slot_roles:wikidb-unittest_): miss, new value computed [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.revision-create"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.revision-create' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.revision-create. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #62023 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #62023, processing time: 6.8902969360352E-5 [] 21:22:02 [OldRevisionImporter] [debug] ImportableOldRevisionImporter::import: running updates [] 21:22:02 [UserOptionsManager] [debug] Loading options from database {"user_id":1} 21:22:02 [ParserCache] [debug] Saved in parser cache {"name":"pcache","key":"wikidb-unittest_:pcache:idhash:1-0!canonical!en","cache_time":"20231016202129","rev_id":1} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:page-content-model:1): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started RefreshSecondaryDataUpdate #51529 [] 21:22:02 [wfDebug] [debug] BacklinkCache::queryLinks: got results from DB {"private":false} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:linktargetstore-id:1%3AThreadItemStore1): miss, new value computed [] 21:22:02 [objectcache] [warning] Rejected set() for {cachekey} due to pending writes. {"cachekey":"wikidb-unittest_:numbacklinks:ns1%3AThreadItemStore1:templatelinks","lag":0,"age":0.7603769302368164,"walltime":0.0006630420684814453} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:numbacklinks:ns1%3AThreadItemStore1:templatelinks): miss, new value computed [] 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:linktargetstore-id:1%3AThreadItemStore1): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [ParserCache] [debug] Creating ParserCache instance for parsoid-pcache [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [ParserCache] [debug] ParserOutput metadata cache miss {"name":"parsoid-pcache"} 21:22:02 [ParserCache] [debug] Creating ParserCache instance for parsoid [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [ParserCache] [debug] ParserOutput metadata cache miss {"name":"parsoid"} 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [ParserCache] [debug] ParserOutput metadata cache miss {"name":"parsoid-pcache"} 21:22:02 [objectcache] [debug] fetchOrRegenerate(global:SqlBlobStore-blob:wikidb-unittest_:tt%3A118): miss, new value computed [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for ban from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for crh from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for iu from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for ku from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for sh from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for shi from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for sr from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for tg from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for tly from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for uz from source [] 21:22:02 [ParserCache] [debug] Saved in parser cache {"name":"parsoid-pcache","key":"wikidb-unittest_:parsoid-pcache:idhash:1-0!useParsoid=1!en","cache_time":"20231016202129","rev_id":1} 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended RefreshSecondaryDataUpdate #51529, processing time: 0.03697395324707 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #80404 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.page-properties-change"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.page-properties-change' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.page-properties-change. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #80404, processing time: 0.00034022331237793 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #62216 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #62216, processing time: 3.3140182495117E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #50549 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #50549, processing time: 3.6001205444336E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SiteStatsUpdate #85417 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SiteStatsUpdate #85417, processing time: 0.00043702125549316 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SearchUpdate #51529 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SearchUpdate #51529, processing time: 0.00077199935913086 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started CdnCacheUpdate #77400 [] 21:22:02 [squid] [info] CdnCacheUpdate::purge: http://127.0.0.1:9413/wiki/Talk:ThreadItemStore1 http://127.0.0.1:9413/index.php?title=Talk:ThreadItemStore1&action=history http://127.0.0.1:9413/wiki/ThreadItemStore1 http://127.0.0.1:9413/index.php?title=ThreadItemStore1&action=history {"private":false} 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended CdnCacheUpdate #77400, processing time: 0.0001220703125 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.revision-create"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.revision-create' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.revision-create. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #54733 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #54733, processing time: 5.2928924560547E-5 [] 21:22:02 [OldRevisionImporter] [debug] ImportableOldRevisionImporter::import: running updates [] 21:22:02 [UserOptionsManager] [debug] Loading options from database {"user_id":2} 21:22:02 [ParserCache] [debug] Saved in parser cache {"name":"pcache","key":"wikidb-unittest_:pcache:idhash:1-0!canonical!en","cache_time":"20231016202129","rev_id":2} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:page-content-model:2): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:2): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started RefreshSecondaryDataUpdate #77001 [] 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:linktargetstore-id:1%3AThreadItemStore1): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:2): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended RefreshSecondaryDataUpdate #77001, processing time: 0.013572931289673 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #82593 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.page-properties-change"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.page-properties-change' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.page-properties-change. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #82593, processing time: 0.00031304359436035 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #81323 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #81323, processing time: 3.504753112793E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #78965 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #78965, processing time: 3.7193298339844E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SiteStatsUpdate #76887 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SiteStatsUpdate #76887, processing time: 0.0004119873046875 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SearchUpdate #77001 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SearchUpdate #77001, processing time: 0.00080299377441406 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started CdnCacheUpdate #77184 [] 21:22:02 [squid] [info] CdnCacheUpdate::purge: http://127.0.0.1:9413/wiki/Talk:ThreadItemStore1 http://127.0.0.1:9413/index.php?title=Talk:ThreadItemStore1&action=history {"private":false} 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended CdnCacheUpdate #77184, processing time: 9.1075897216797E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_WikiPage::onArticleEdit #76887 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_WikiPage::onArticleEdit #76887, processing time: 4.9114227294922E-5 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.revision-create"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.revision-create' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.revision-create. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #57529 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #57529, processing time: 6.3896179199219E-5 [] 21:22:02 [OldRevisionImporter] [debug] ImportableOldRevisionImporter::import: running updates [] 21:22:02 [UserOptionsManager] [debug] Loading options from database {"user_id":3} 21:22:02 [ParserCache] [debug] Saved in parser cache {"name":"pcache","key":"wikidb-unittest_:pcache:idhash:1-0!canonical!en","cache_time":"20231016202129","rev_id":3} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:page-content-model:3): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:3): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started RefreshSecondaryDataUpdate #77184 [] 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:linktargetstore-id:1%3AThreadItemStore1): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:3): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended RefreshSecondaryDataUpdate #77184, processing time: 0.012545824050903 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #76822 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.page-properties-change"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.page-properties-change' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.page-properties-change. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #76822, processing time: 0.00029087066650391 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #79398 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #79398, processing time: 3.3855438232422E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #79316 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #79316, processing time: 3.6001205444336E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SiteStatsUpdate #83599 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SiteStatsUpdate #83599, processing time: 0.0003819465637207 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SearchUpdate #77184 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SearchUpdate #77184, processing time: 0.00069212913513184 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started CdnCacheUpdate #62142 [] 21:22:02 [squid] [info] CdnCacheUpdate::purge: http://127.0.0.1:9413/wiki/Talk:ThreadItemStore1 http://127.0.0.1:9413/index.php?title=Talk:ThreadItemStore1&action=history {"private":false} 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended CdnCacheUpdate #62142, processing time: 0.00011205673217773 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_WikiPage::onArticleEdit #83599 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_WikiPage::onArticleEdit #83599, processing time: 4.7922134399414E-5 [] 21:22:02 === 21:22:02 21:22:02 2) MediaWiki\Extension\DiscussionTools\Tests\ThreadItemStoreTest::testInsertThreadItems with data set #1 ('cases/ThreadItemStore/2archiv...ection') 21:22:02 Failed asserting that two arrays are equal. 21:22:02 --- Expected 21:22:02 +++ Actual 21:22:02 @@ @@ 21:22:02 'itp_items_id' => '1' 21:22:02 'itp_page_id' => '1' 21:22:02 'itp_oldest_revision_id' => '1' 21:22:02 - 'itp_newest_revision_id' => '1' 21:22:02 + 'itp_newest_revision_id' => '2' 21:22:02 ) 21:22:02 1 => Array ( 21:22:02 'itp_id' => '2' 21:22:02 @@ @@ 21:22:02 'itp_items_id' => '2' 21:22:02 'itp_page_id' => '1' 21:22:02 'itp_oldest_revision_id' => '1' 21:22:02 - 'itp_newest_revision_id' => '1' 21:22:02 + 'itp_newest_revision_id' => '2' 21:22:02 ) 21:22:02 2 => Array (...) 21:22:02 3 => Array (...) 21:22:02 @@ @@ 21:22:02 2 => Array ( 21:22:02 'itr_id' => '3' 21:22:02 'itr_itemid_id' => '1' 21:22:02 - 'itr_revision_id' => '3' 21:22:02 + 'itr_revision_id' => '2' 21:22:02 'itr_items_id' => '1' 21:22:02 'itr_parent_id' => null 21:22:02 'itr_transcludedfrom' => null 21:22:02 @@ @@ 21:22:02 3 => Array ( 21:22:02 'itr_id' => '4' 21:22:02 'itr_itemid_id' => '2' 21:22:02 - 'itr_revision_id' => '3' 21:22:02 + 'itr_revision_id' => '2' 21:22:02 'itr_items_id' => '2' 21:22:02 'itr_parent_id' => '3' 21:22:02 'itr_transcludedfrom' => null 21:22:02 @@ @@ 21:22:02 'itr_level' => '1' 21:22:02 'itr_headinglevel' => null 21:22:02 ) 21:22:02 + 4 => Array (...) 21:22:02 + 5 => Array (...) 21:22:02 ) 21:22:02 'discussiontools_item_ids' => Array (...) 21:22:02 ) 21:22:02 21:22:02 /workspace/src/extensions/DiscussionTools/tests/phpunit/ThreadItemStoreTest.php:88 21:22:02 phpvfscomposer:///workspace/src/vendor/phpunit/phpunit/phpunit:97 21:22:02 === Logs generated by test case 21:22:02 [objectcache] [debug] MainWANObjectCache using store {class} {"class":"HashBagOStuff"} 21:22:02 [localisation] [debug] LocalisationCache using store LCStoreNull [] 21:22:02 [localisation] [debug] LocalisationCache::isExpired(en): cache missing, need to make one [] 21:22:02 [wfDebug] [debug] ParserFactory: using default preprocessor {"private":false} 21:22:02 [ContentHandler] [info] Registered handler for wikitext: WikitextContentHandler [] 21:22:02 [MessageCache] [debug] MessageCache using store {class} {"class":"HashBagOStuff"} 21:22:02 [objectcache] [debug] MainObjectStash using store {class} {"class":"HashBagOStuff"} 21:22:02 [ParserCache] [debug] Creating ParserCache instance for pcache [] 21:22:02 [objectcache] [warning] Rejected set() for {cachekey} due to pending writes. {"cachekey":"global:NameTableSqlStore:content_models:wikidb-unittest_","lag":0,"age":0.8761529922485352,"walltime":0.0002319812774658203} 21:22:02 [objectcache] [debug] fetchOrRegenerate(global:NameTableSqlStore:content_models:wikidb-unittest_): miss, new value computed [] 21:22:02 [objectcache] [warning] Rejected set() for {cachekey} due to pending writes. {"cachekey":"global:NameTableSqlStore:slot_roles:wikidb-unittest_","lag":0,"age":0.87717604637146,"walltime":0.00023889541625976562} 21:22:02 [objectcache] [debug] fetchOrRegenerate(global:NameTableSqlStore:slot_roles:wikidb-unittest_): miss, new value computed [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.revision-create"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.revision-create' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.revision-create. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #81395 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #81395, processing time: 6.413459777832E-5 [] 21:22:02 [OldRevisionImporter] [debug] ImportableOldRevisionImporter::import: running updates [] 21:22:02 [UserOptionsManager] [debug] Loading options from database {"user_id":1} 21:22:02 [ParserCache] [debug] Saved in parser cache {"name":"pcache","key":"wikidb-unittest_:pcache:idhash:1-0!canonical!en","cache_time":"20231016202129","rev_id":1} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:page-content-model:1): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started RefreshSecondaryDataUpdate #74917 [] 21:22:02 [wfDebug] [debug] BacklinkCache::queryLinks: got results from DB {"private":false} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:linktargetstore-id:1%3AThreadItemStore2): miss, new value computed [] 21:22:02 [objectcache] [warning] Rejected set() for {cachekey} due to pending writes. {"cachekey":"wikidb-unittest_:numbacklinks:ns1%3AThreadItemStore2:templatelinks","lag":0,"age":0.8917860984802246,"walltime":0.0006489753723144531} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:numbacklinks:ns1%3AThreadItemStore2:templatelinks): miss, new value computed [] 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:linktargetstore-id:1%3AThreadItemStore2): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [ParserCache] [debug] Creating ParserCache instance for parsoid-pcache [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [ParserCache] [debug] ParserOutput metadata cache miss {"name":"parsoid-pcache"} 21:22:02 [ParserCache] [debug] Creating ParserCache instance for parsoid [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [ParserCache] [debug] ParserOutput metadata cache miss {"name":"parsoid"} 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [ParserCache] [debug] ParserOutput metadata cache miss {"name":"parsoid-pcache"} 21:22:02 [objectcache] [debug] fetchOrRegenerate(global:SqlBlobStore-blob:wikidb-unittest_:tt%3A121): miss, new value computed [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for ban from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for crh from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for iu from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for ku from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for sh from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for shi from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for sr from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for tg from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for tly from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for uz from source [] 21:22:02 [ParserCache] [debug] Saved in parser cache {"name":"parsoid-pcache","key":"wikidb-unittest_:parsoid-pcache:idhash:1-0!useParsoid=1!en","cache_time":"20231016202129","rev_id":1} 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended RefreshSecondaryDataUpdate #74917, processing time: 0.098199844360352 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #83030 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.page-properties-change"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.page-properties-change' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.page-properties-change. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #83030, processing time: 0.00036501884460449 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #39214 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #39214, processing time: 3.4093856811523E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #74918 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #74918, processing time: 3.814697265625E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SiteStatsUpdate #82677 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SiteStatsUpdate #82677, processing time: 0.00044608116149902 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SearchUpdate #74917 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SearchUpdate #74917, processing time: 0.00078678131103516 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started CdnCacheUpdate #83083 [] 21:22:02 [squid] [info] CdnCacheUpdate::purge: http://127.0.0.1:9413/wiki/Talk:ThreadItemStore2 http://127.0.0.1:9413/index.php?title=Talk:ThreadItemStore2&action=history http://127.0.0.1:9413/wiki/ThreadItemStore2 http://127.0.0.1:9413/index.php?title=ThreadItemStore2&action=history {"private":false} 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended CdnCacheUpdate #83083, processing time: 9.7990036010742E-5 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.revision-create"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.revision-create' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.revision-create. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #83026 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #83026, processing time: 6.2942504882812E-5 [] 21:22:02 [OldRevisionImporter] [debug] ImportableOldRevisionImporter::import: running updates [] 21:22:02 [UserOptionsManager] [debug] Loading options from database {"user_id":3} 21:22:02 [ParserCache] [debug] Saved in parser cache {"name":"pcache","key":"wikidb-unittest_:pcache:idhash:1-0!canonical!en","cache_time":"20231016202130","rev_id":2} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:page-content-model:2): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:2): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started RefreshSecondaryDataUpdate #83007 [] 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:linktargetstore-id:1%3AThreadItemStore2): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:2): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended RefreshSecondaryDataUpdate #83007, processing time: 0.017160892486572 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #84520 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.page-properties-change"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.page-properties-change' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.page-properties-change. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #84520, processing time: 0.0003049373626709 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #85848 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #85848, processing time: 3.2901763916016E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #78378 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #78378, processing time: 3.6001205444336E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SiteStatsUpdate #83937 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SiteStatsUpdate #83937, processing time: 0.00062108039855957 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SearchUpdate #83007 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SearchUpdate #83007, processing time: 0.0010030269622803 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started CdnCacheUpdate #74175 [] 21:22:02 [squid] [info] CdnCacheUpdate::purge: http://127.0.0.1:9413/wiki/Talk:ThreadItemStore2 http://127.0.0.1:9413/index.php?title=Talk:ThreadItemStore2&action=history {"private":false} 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended CdnCacheUpdate #74175, processing time: 8.702278137207E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_WikiPage::onArticleEdit #83937 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_WikiPage::onArticleEdit #83937, processing time: 4.7206878662109E-5 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.revision-create"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.revision-create' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.revision-create. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #84714 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #84714, processing time: 5.6982040405273E-5 [] 21:22:02 [OldRevisionImporter] [debug] ImportableOldRevisionImporter::import: running updates [] 21:22:02 [ParserCache] [debug] Saved in parser cache {"name":"pcache","key":"wikidb-unittest_:pcache:idhash:2-0!canonical!en","cache_time":"20231016202130","rev_id":3} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:page-content-model:3): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:3): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started RefreshSecondaryDataUpdate #46064 [] 21:22:02 [wfDebug] [debug] BacklinkCache::queryLinks: got results from DB {"private":false} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:linktargetstore-id:1%3AThreadItemStore2/Archive): miss, new value computed [] 21:22:02 [objectcache] [warning] Rejected set() for {cachekey} due to pending writes. {"cachekey":"wikidb-unittest_:numbacklinks:ns1%3AThreadItemStore2\/Archive:templatelinks","lag":0,"age":0.0547940731048584,"walltime":0.0009899139404296875} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:numbacklinks:ns1%3AThreadItemStore2/Archive:templatelinks): miss, new value computed [] 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:linktargetstore-id:1%3AThreadItemStore2/Archive): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:3): process cache hit [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:3): process cache hit [] 21:22:02 [ParserCache] [debug] ParserOutput metadata cache miss {"name":"parsoid-pcache"} 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:3): process cache hit [] 21:22:02 [ParserCache] [debug] ParserOutput metadata cache miss {"name":"parsoid"} 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:3): process cache hit [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:3): process cache hit [] 21:22:02 [ParserCache] [debug] ParserOutput metadata cache miss {"name":"parsoid-pcache"} 21:22:02 [objectcache] [debug] fetchOrRegenerate(global:SqlBlobStore-blob:wikidb-unittest_:tt%3A123): miss, new value computed [] 21:22:02 [ParserCache] [debug] Saved in parser cache {"name":"parsoid-pcache","key":"wikidb-unittest_:parsoid-pcache:idhash:2-0!useParsoid=1!en","cache_time":"20231016202130","rev_id":3} 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:3): process cache hit [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:3): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended RefreshSecondaryDataUpdate #46064, processing time: 0.042388916015625 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #62963 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.page-properties-change"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.page-properties-change' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.page-properties-change. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #62963, processing time: 0.00053906440734863 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #75963 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #75963, processing time: 5.1975250244141E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #78629 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #78629, processing time: 5.5074691772461E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SiteStatsUpdate #84625 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SiteStatsUpdate #84625, processing time: 0.00061202049255371 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SearchUpdate #46064 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SearchUpdate #46064, processing time: 0.00088119506835938 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started CdnCacheUpdate #71437 [] 21:22:02 [squid] [info] CdnCacheUpdate::purge: http://127.0.0.1:9413/wiki/Talk:ThreadItemStore2/Archive http://127.0.0.1:9413/index.php?title=Talk:ThreadItemStore2/Archive&action=history http://127.0.0.1:9413/wiki/ThreadItemStore2/Archive http://127.0.0.1:9413/index.php?title=ThreadItemStore2/Archive&action=history {"private":false} 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended CdnCacheUpdate #71437, processing time: 0.00014615058898926 [] 21:22:02 === 21:22:02 21:22:02 3) MediaWiki\Extension\DiscussionTools\Tests\ThreadItemStoreTest::testInsertThreadItems with data set #3 ('cases/ThreadItemStore/4transc...ection') 21:22:02 Failed asserting that two arrays are equal. 21:22:02 --- Expected 21:22:02 +++ Actual 21:22:02 @@ @@ 21:22:02 1 => Array (...) 21:22:02 2 => Array ( 21:22:02 'itp_id' => '3' 21:22:02 - 'itp_items_id' => '3' 21:22:02 - 'itp_page_id' => '1' 21:22:02 - 'itp_oldest_revision_id' => '2' 21:22:02 - 'itp_newest_revision_id' => '2' 21:22:02 + 'itp_items_id' => '1' 21:22:02 + 'itp_page_id' => '2' 21:22:02 + 'itp_oldest_revision_id' => '3' 21:22:02 + 'itp_newest_revision_id' => '3' 21:22:02 ) 21:22:02 3 => Array ( 21:22:02 'itp_id' => '4' 21:22:02 - 'itp_items_id' => '1' 21:22:02 + 'itp_items_id' => '2' 21:22:02 'itp_page_id' => '2' 21:22:02 'itp_oldest_revision_id' => '3' 21:22:02 'itp_newest_revision_id' => '3' 21:22:02 @@ @@ 21:22:02 ) 21:22:02 4 => Array ( 21:22:02 'itp_id' => '5' 21:22:02 - 'itp_items_id' => '2' 21:22:02 + 'itp_items_id' => '3' 21:22:02 'itp_page_id' => '2' 21:22:02 'itp_oldest_revision_id' => '3' 21:22:02 'itp_newest_revision_id' => '3' 21:22:02 ) 21:22:02 - 5 => Array (...) 21:22:02 ) 21:22:02 'discussiontools_item_revisions' => Array ( 21:22:02 0 => Array (...) 21:22:02 @@ @@ 21:22:02 3 => Array (...) 21:22:02 4 => Array ( 21:22:02 'itr_id' => '5' 21:22:02 - 'itr_itemid_id' => '3' 21:22:02 - 'itr_revision_id' => '2' 21:22:02 - 'itr_items_id' => '3' 21:22:02 - 'itr_parent_id' => '4' 21:22:02 - 'itr_transcludedfrom' => null 21:22:02 - 'itr_level' => '2' 21:22:02 - 'itr_headinglevel' => null 21:22:02 - ) 21:22:02 - 5 => Array ( 21:22:02 - 'itr_id' => '6' 21:22:02 'itr_itemid_id' => '1' 21:22:02 'itr_revision_id' => '3' 21:22:02 'itr_items_id' => '1' 21:22:02 @@ @@ 21:22:02 'itr_level' => '0' 21:22:02 'itr_headinglevel' => '2' 21:22:02 ) 21:22:02 + 5 => Array ( 21:22:02 + 'itr_id' => '6' 21:22:02 + 'itr_itemid_id' => '2' 21:22:02 + 'itr_revision_id' => '3' 21:22:02 + 'itr_items_id' => '2' 21:22:02 + 'itr_parent_id' => '5' 21:22:02 + 'itr_transcludedfrom' => '1' 21:22:02 + 'itr_level' => '1' 21:22:02 + 'itr_headinglevel' => null 21:22:02 + ) 21:22:02 6 => Array ( 21:22:02 'itr_id' => '7' 21:22:02 - 'itr_itemid_id' => '2' 21:22:02 + 'itr_itemid_id' => '3' 21:22:02 'itr_revision_id' => '3' 21:22:02 - 'itr_items_id' => '2' 21:22:02 + 'itr_items_id' => '3' 21:22:02 'itr_parent_id' => '6' 21:22:02 'itr_transcludedfrom' => '1' 21:22:02 - 'itr_level' => '1' 21:22:02 + 'itr_level' => '2' 21:22:02 'itr_headinglevel' => null 21:22:02 ) 21:22:02 - 7 => Array (...) 21:22:02 ) 21:22:02 'discussiontools_item_ids' => Array (...) 21:22:02 ) 21:22:02 21:22:02 /workspace/src/extensions/DiscussionTools/tests/phpunit/ThreadItemStoreTest.php:88 21:22:02 phpvfscomposer:///workspace/src/vendor/phpunit/phpunit/phpunit:97 21:22:02 === Logs generated by test case 21:22:02 [objectcache] [debug] MainWANObjectCache using store {class} {"class":"HashBagOStuff"} 21:22:02 [localisation] [debug] LocalisationCache using store LCStoreNull [] 21:22:02 [localisation] [debug] LocalisationCache::isExpired(en): cache missing, need to make one [] 21:22:02 [wfDebug] [debug] ParserFactory: using default preprocessor {"private":false} 21:22:02 [ContentHandler] [info] Registered handler for wikitext: WikitextContentHandler [] 21:22:02 [MessageCache] [debug] MessageCache using store {class} {"class":"HashBagOStuff"} 21:22:02 [objectcache] [debug] MainObjectStash using store {class} {"class":"HashBagOStuff"} 21:22:02 [ParserCache] [debug] Creating ParserCache instance for pcache [] 21:22:02 [objectcache] [warning] Rejected set() for {cachekey} due to pending writes. {"cachekey":"global:NameTableSqlStore:content_models:wikidb-unittest_","lag":0,"age":0.22696805000305176,"walltime":0.00021886825561523438} 21:22:02 [objectcache] [debug] fetchOrRegenerate(global:NameTableSqlStore:content_models:wikidb-unittest_): miss, new value computed [] 21:22:02 [objectcache] [warning] Rejected set() for {cachekey} due to pending writes. {"cachekey":"global:NameTableSqlStore:slot_roles:wikidb-unittest_","lag":0,"age":0.22783493995666504,"walltime":0.00020503997802734375} 21:22:02 [objectcache] [debug] fetchOrRegenerate(global:NameTableSqlStore:slot_roles:wikidb-unittest_): miss, new value computed [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.revision-create"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.revision-create' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.revision-create. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #42832 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #42832, processing time: 8.8930130004883E-5 [] 21:22:02 [OldRevisionImporter] [debug] ImportableOldRevisionImporter::import: running updates [] 21:22:02 [UserOptionsManager] [debug] Loading options from database {"user_id":1} 21:22:02 [ParserCache] [debug] Saved in parser cache {"name":"pcache","key":"wikidb-unittest_:pcache:idhash:1-0!canonical!en","cache_time":"20231016202130","rev_id":1} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:page-content-model:1): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started RefreshSecondaryDataUpdate #83890 [] 21:22:02 [wfDebug] [debug] BacklinkCache::queryLinks: got results from DB {"private":false} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:linktargetstore-id:1%3AThreadItemStore4/b): miss, new value computed [] 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:numbacklinks:ns1%3AThreadItemStore4/b:templatelinks): miss, new value computed [] 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:linktargetstore-id:1%3AThreadItemStore4/b): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [ParserCache] [debug] Creating ParserCache instance for parsoid-pcache [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [ParserCache] [debug] ParserOutput metadata cache miss {"name":"parsoid-pcache"} 21:22:02 [ParserCache] [debug] Creating ParserCache instance for parsoid [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [ParserCache] [debug] ParserOutput metadata cache miss {"name":"parsoid"} 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [ParserCache] [debug] ParserOutput metadata cache miss {"name":"parsoid-pcache"} 21:22:02 [objectcache] [debug] fetchOrRegenerate(global:SqlBlobStore-blob:wikidb-unittest_:tt%3A126): miss, new value computed [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for ban from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for crh from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for iu from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for ku from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for sh from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for shi from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for sr from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for tg from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for tly from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for uz from source [] 21:22:02 [ParserCache] [debug] Saved in parser cache {"name":"parsoid-pcache","key":"wikidb-unittest_:parsoid-pcache:idhash:1-0!useParsoid=1!en","cache_time":"20231016202130","rev_id":1} 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended RefreshSecondaryDataUpdate #83890, processing time: 0.031677007675171 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #76094 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #76094, processing time: 8.4877014160156E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #74965 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #74965, processing time: 3.8862228393555E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SiteStatsUpdate #71645 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SiteStatsUpdate #71645, processing time: 0.00039410591125488 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SearchUpdate #83890 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SearchUpdate #83890, processing time: 0.00066399574279785 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started CdnCacheUpdate #80583 [] 21:22:02 [squid] [info] CdnCacheUpdate::purge: http://127.0.0.1:9413/wiki/Talk:ThreadItemStore4/b http://127.0.0.1:9413/index.php?title=Talk:ThreadItemStore4/b&action=history http://127.0.0.1:9413/wiki/ThreadItemStore4/b http://127.0.0.1:9413/index.php?title=ThreadItemStore4/b&action=history {"private":false} 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended CdnCacheUpdate #80583, processing time: 9.4890594482422E-5 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.revision-create"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.revision-create' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.revision-create. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #76643 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #76643, processing time: 4.2915344238281E-5 [] 21:22:02 [OldRevisionImporter] [debug] ImportableOldRevisionImporter::import: running updates [] 21:22:02 [UserOptionsManager] [debug] Loading options from database {"user_id":2} 21:22:02 [ParserCache] [debug] Saved in parser cache {"name":"pcache","key":"wikidb-unittest_:pcache:idhash:1-0!canonical!en","cache_time":"20231016202130","rev_id":2} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:page-content-model:2): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:2): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started RefreshSecondaryDataUpdate #42171 [] 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:linktargetstore-id:1%3AThreadItemStore4/b): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:2): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended RefreshSecondaryDataUpdate #42171, processing time: 0.011695146560669 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #85605 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.page-properties-change"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.page-properties-change' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.page-properties-change. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #85605, processing time: 0.00027680397033691 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #85603 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #85603, processing time: 5.9843063354492E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #85255 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #85255, processing time: 3.2901763916016E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SiteStatsUpdate #74573 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SiteStatsUpdate #74573, processing time: 0.00036406517028809 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SearchUpdate #42171 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SearchUpdate #42171, processing time: 0.00060009956359863 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started CdnCacheUpdate #85521 [] 21:22:02 [squid] [info] CdnCacheUpdate::purge: http://127.0.0.1:9413/wiki/Talk:ThreadItemStore4/b http://127.0.0.1:9413/index.php?title=Talk:ThreadItemStore4/b&action=history {"private":false} 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended CdnCacheUpdate #85521, processing time: 8.3208084106445E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_WikiPage::onArticleEdit #74573 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_WikiPage::onArticleEdit #74573, processing time: 4.2915344238281E-5 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.revision-create"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.revision-create' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.revision-create. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #85577 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #85577, processing time: 4.6968460083008E-5 [] 21:22:02 [OldRevisionImporter] [debug] ImportableOldRevisionImporter::import: running updates [] 21:22:02 [UserOptionsManager] [debug] Loading options from database {"user_id":3} 21:22:02 [objectcache] [debug] fetchOrRegenerate(global:revision-row-1.29:wikidb-unittest_:1:2): miss, new value computed [] 21:22:02 [objectcache] [debug] fetchOrRegenerate(global:SqlBlobStore-blob:wikidb-unittest_:tt%3A127): miss, new value computed [] 21:22:02 [ParserCache] [debug] Saved in parser cache {"name":"pcache","key":"wikidb-unittest_:pcache:idhash:2-0!canonical!en","cache_time":"20231016202130","rev_id":3} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:page-content-model:3): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:3): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started RefreshSecondaryDataUpdate #84729 [] 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:linktargetstore-id:Talk%3AThreadItemStore4/b): miss, new value computed [] 21:22:02 [wfDebug] [debug] BacklinkCache::queryLinks: got results from DB {"private":false} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:linktargetstore-id:1%3AThreadItemStore4): miss, new value computed [] 21:22:02 [objectcache] [warning] Rejected set() for {cachekey} due to pending writes. {"cachekey":"wikidb-unittest_:numbacklinks:ns1%3AThreadItemStore4:templatelinks","lag":0,"age":0.31272196769714355,"walltime":0.0006220340728759766} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:numbacklinks:ns1%3AThreadItemStore4:templatelinks): miss, new value computed [] 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:linktargetstore-id:1%3AThreadItemStore4): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:3): process cache hit [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:3): process cache hit [] 21:22:02 [ParserCache] [debug] ParserOutput metadata cache miss {"name":"parsoid-pcache"} 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:3): process cache hit [] 21:22:02 [ParserCache] [debug] ParserOutput metadata cache miss {"name":"parsoid"} 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:3): process cache hit [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:3): process cache hit [] 21:22:02 [ParserCache] [debug] ParserOutput metadata cache miss {"name":"parsoid-pcache"} 21:22:02 [objectcache] [debug] fetchOrRegenerate(global:SqlBlobStore-blob:wikidb-unittest_:tt%3A128): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(global:SqlBlobStore-blob:wikidb-unittest_:tt%3A127): process cache hit [] 21:22:02 [ParserCache] [debug] Saved in parser cache {"name":"parsoid-pcache","key":"wikidb-unittest_:parsoid-pcache:idhash:2-0!useParsoid=1!en","cache_time":"20231016202130","rev_id":3} 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:3): process cache hit [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:3): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended RefreshSecondaryDataUpdate #84729, processing time: 0.028931856155396 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #85502 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.page-properties-change"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.page-properties-change' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.page-properties-change. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #85502, processing time: 0.00027608871459961 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #82014 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #82014, processing time: 3.1948089599609E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #73274 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #73274, processing time: 3.504753112793E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SiteStatsUpdate #84407 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SiteStatsUpdate #84407, processing time: 0.00047612190246582 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SearchUpdate #84729 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SearchUpdate #84729, processing time: 0.00085902214050293 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started CdnCacheUpdate #79265 [] 21:22:02 [squid] [info] CdnCacheUpdate::purge: http://127.0.0.1:9413/wiki/Talk:ThreadItemStore4 http://127.0.0.1:9413/index.php?title=Talk:ThreadItemStore4&action=history http://127.0.0.1:9413/wiki/ThreadItemStore4 http://127.0.0.1:9413/index.php?title=ThreadItemStore4&action=history {"private":false} 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended CdnCacheUpdate #79265, processing time: 9.7990036010742E-5 [] 21:22:02 === 21:22:02 21:22:02 4) MediaWiki\Extension\DiscussionTools\Tests\ThreadItemStoreTest::testInsertThreadItems with data set #4 ('cases/ThreadItemStore/5change...tation') 21:22:02 Failed asserting that two arrays are equal. 21:22:02 --- Expected 21:22:02 +++ Actual 21:22:02 @@ @@ 21:22:02 'discussiontools_items' => Array ( 21:22:02 0 => Array (...) 21:22:02 1 => Array (...) 21:22:02 - 2 => Array (...) 21:22:02 - 3 => Array (...) 21:22:02 ) 21:22:02 'discussiontools_item_pages' => Array ( 21:22:02 0 => Array (...) 21:22:02 1 => Array (...) 21:22:02 - 2 => Array (...) 21:22:02 - 3 => Array (...) 21:22:02 ) 21:22:02 'discussiontools_item_revisions' => Array ( 21:22:02 0 => Array (...) 21:22:02 1 => Array (...) 21:22:02 2 => Array ( 21:22:02 - 'itr_id' => '5' 21:22:02 - 'itr_itemid_id' => '3' 21:22:02 - 'itr_revision_id' => '2' 21:22:02 - 'itr_items_id' => '3' 21:22:02 - 'itr_parent_id' => '4' 21:22:02 + 'itr_id' => '3' 21:22:02 + 'itr_itemid_id' => '1' 21:22:02 + 'itr_revision_id' => '4' 21:22:02 + 'itr_items_id' => '1' 21:22:02 + 'itr_parent_id' => null 21:22:02 'itr_transcludedfrom' => null 21:22:02 - 'itr_level' => '2' 21:22:02 - 'itr_headinglevel' => null 21:22:02 + 'itr_level' => '0' 21:22:02 + 'itr_headinglevel' => '2' 21:22:02 ) 21:22:02 3 => Array ( 21:22:02 - 'itr_id' => '7' 21:22:02 - 'itr_itemid_id' => '4' 21:22:02 - 'itr_revision_id' => '3' 21:22:02 - 'itr_items_id' => '4' 21:22:02 - 'itr_parent_id' => '6' 21:22:02 + 'itr_id' => '4' 21:22:02 + 'itr_itemid_id' => '2' 21:22:02 + 'itr_revision_id' => '4' 21:22:02 + 'itr_items_id' => '2' 21:22:02 + 'itr_parent_id' => '3' 21:22:02 'itr_transcludedfrom' => null 21:22:02 - 'itr_level' => '3' 21:22:02 + 'itr_level' => '1' 21:22:02 'itr_headinglevel' => null 21:22:02 ) 21:22:02 - 4 => Array (...) 21:22:02 - 5 => Array (...) 21:22:02 - 6 => Array (...) 21:22:02 - 7 => Array (...) 21:22:02 ) 21:22:02 'discussiontools_item_ids' => Array ( 21:22:02 0 => Array (...) 21:22:02 1 => Array (...) 21:22:02 - 2 => Array (...) 21:22:02 - 3 => Array (...) 21:22:02 - 4 => Array (...) 21:22:02 ) 21:22:02 ) 21:22:02 21:22:02 /workspace/src/extensions/DiscussionTools/tests/phpunit/ThreadItemStoreTest.php:88 21:22:02 phpvfscomposer:///workspace/src/vendor/phpunit/phpunit/phpunit:97 21:22:02 === Logs generated by test case 21:22:02 [objectcache] [debug] MainWANObjectCache using store {class} {"class":"HashBagOStuff"} 21:22:02 [localisation] [debug] LocalisationCache using store LCStoreNull [] 21:22:02 [localisation] [debug] LocalisationCache::isExpired(en): cache missing, need to make one [] 21:22:02 [wfDebug] [debug] ParserFactory: using default preprocessor {"private":false} 21:22:02 [ContentHandler] [info] Registered handler for wikitext: WikitextContentHandler [] 21:22:02 [MessageCache] [debug] MessageCache using store {class} {"class":"HashBagOStuff"} 21:22:02 [objectcache] [debug] MainObjectStash using store {class} {"class":"HashBagOStuff"} 21:22:02 [ParserCache] [debug] Creating ParserCache instance for pcache [] 21:22:02 [objectcache] [warning] Rejected set() for {cachekey} due to pending writes. {"cachekey":"global:NameTableSqlStore:content_models:wikidb-unittest_","lag":0,"age":0.371229887008667,"walltime":0.0002620220184326172} 21:22:02 [objectcache] [debug] fetchOrRegenerate(global:NameTableSqlStore:content_models:wikidb-unittest_): miss, new value computed [] 21:22:02 [objectcache] [warning] Rejected set() for {cachekey} due to pending writes. {"cachekey":"global:NameTableSqlStore:slot_roles:wikidb-unittest_","lag":0,"age":0.3722360134124756,"walltime":0.0002560615539550781} 21:22:02 [objectcache] [debug] fetchOrRegenerate(global:NameTableSqlStore:slot_roles:wikidb-unittest_): miss, new value computed [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.revision-create"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.revision-create' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.revision-create. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #82044 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #82044, processing time: 6.103515625E-5 [] 21:22:02 [OldRevisionImporter] [debug] ImportableOldRevisionImporter::import: running updates [] 21:22:02 [UserOptionsManager] [debug] Loading options from database {"user_id":1} 21:22:02 [ParserCache] [debug] Saved in parser cache {"name":"pcache","key":"wikidb-unittest_:pcache:idhash:1-0!canonical!en","cache_time":"20231016202130","rev_id":1} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:page-content-model:1): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started RefreshSecondaryDataUpdate #44136 [] 21:22:02 [wfDebug] [debug] BacklinkCache::queryLinks: got results from DB {"private":false} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:linktargetstore-id:1%3AThreadItemStore5): miss, new value computed [] 21:22:02 [objectcache] [warning] Rejected set() for {cachekey} due to pending writes. {"cachekey":"wikidb-unittest_:numbacklinks:ns1%3AThreadItemStore5:templatelinks","lag":0,"age":0.4512910842895508,"walltime":0.0006279945373535156} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:numbacklinks:ns1%3AThreadItemStore5:templatelinks): miss, new value computed [] 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:linktargetstore-id:1%3AThreadItemStore5): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [ParserCache] [debug] Creating ParserCache instance for parsoid-pcache [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [ParserCache] [debug] ParserOutput metadata cache miss {"name":"parsoid-pcache"} 21:22:02 [ParserCache] [debug] Creating ParserCache instance for parsoid [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [ParserCache] [debug] ParserOutput metadata cache miss {"name":"parsoid"} 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [ParserCache] [debug] ParserOutput metadata cache miss {"name":"parsoid-pcache"} 21:22:02 [objectcache] [debug] fetchOrRegenerate(global:SqlBlobStore-blob:wikidb-unittest_:tt%3A129): miss, new value computed [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for ban from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for crh from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for iu from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for ku from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for sh from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for shi from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for sr from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for tg from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for tly from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for uz from source [] 21:22:02 [ParserCache] [debug] Saved in parser cache {"name":"parsoid-pcache","key":"wikidb-unittest_:parsoid-pcache:idhash:1-0!useParsoid=1!en","cache_time":"20231016202130","rev_id":1} 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended RefreshSecondaryDataUpdate #44136, processing time: 0.033777952194214 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #81777 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.page-properties-change"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.page-properties-change' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.page-properties-change. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #81777, processing time: 0.00030088424682617 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #74106 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #74106, processing time: 0.00011706352233887 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #82967 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #82967, processing time: 4.1961669921875E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SiteStatsUpdate #80238 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SiteStatsUpdate #80238, processing time: 0.00050806999206543 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SearchUpdate #44136 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SearchUpdate #44136, processing time: 0.00081992149353027 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started CdnCacheUpdate #81926 [] 21:22:02 [squid] [info] CdnCacheUpdate::purge: http://127.0.0.1:9413/wiki/Talk:ThreadItemStore5 http://127.0.0.1:9413/index.php?title=Talk:ThreadItemStore5&action=history http://127.0.0.1:9413/wiki/ThreadItemStore5 http://127.0.0.1:9413/index.php?title=ThreadItemStore5&action=history {"private":false} 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended CdnCacheUpdate #81926, processing time: 9.3936920166016E-5 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.revision-create"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.revision-create' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.revision-create. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #81585 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #81585, processing time: 4.3869018554688E-5 [] 21:22:02 [OldRevisionImporter] [debug] ImportableOldRevisionImporter::import: running updates [] 21:22:02 [UserOptionsManager] [debug] Loading options from database {"user_id":2} 21:22:02 [ParserCache] [debug] Saved in parser cache {"name":"pcache","key":"wikidb-unittest_:pcache:idhash:1-0!canonical!en","cache_time":"20231016202130","rev_id":2} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:page-content-model:2): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:2): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started RefreshSecondaryDataUpdate #80291 [] 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:linktargetstore-id:1%3AThreadItemStore5): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:2): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended RefreshSecondaryDataUpdate #80291, processing time: 0.016330003738403 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #85541 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.page-properties-change"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.page-properties-change' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.page-properties-change. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #85541, processing time: 0.00026893615722656 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #81349 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #81349, processing time: 3.0040740966797E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #62751 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #62751, processing time: 3.504753112793E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SiteStatsUpdate #77144 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SiteStatsUpdate #77144, processing time: 0.0004878044128418 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SearchUpdate #80291 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SearchUpdate #80291, processing time: 0.00090694427490234 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started CdnCacheUpdate #81887 [] 21:22:02 [squid] [info] CdnCacheUpdate::purge: http://127.0.0.1:9413/wiki/Talk:ThreadItemStore5 http://127.0.0.1:9413/index.php?title=Talk:ThreadItemStore5&action=history {"private":false} 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended CdnCacheUpdate #81887, processing time: 9.0122222900391E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_WikiPage::onArticleEdit #77144 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_WikiPage::onArticleEdit #77144, processing time: 4.6014785766602E-5 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.revision-create"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.revision-create' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.revision-create. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #79966 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #79966, processing time: 4.4107437133789E-5 [] 21:22:02 [OldRevisionImporter] [debug] ImportableOldRevisionImporter::import: running updates [] 21:22:02 [UserOptionsManager] [debug] Loading options from database {"user_id":3} 21:22:02 [ParserCache] [debug] Saved in parser cache {"name":"pcache","key":"wikidb-unittest_:pcache:idhash:1-0!canonical!en","cache_time":"20231016202130","rev_id":3} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:page-content-model:3): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:3): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started RefreshSecondaryDataUpdate #81887 [] 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:linktargetstore-id:1%3AThreadItemStore5): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:3): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended RefreshSecondaryDataUpdate #81887, processing time: 0.014792919158936 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #76119 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.page-properties-change"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.page-properties-change' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.page-properties-change. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #76119, processing time: 0.00028109550476074 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #76752 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #76752, processing time: 3.3140182495117E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #84815 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #84815, processing time: 3.6001205444336E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SiteStatsUpdate #79327 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SiteStatsUpdate #79327, processing time: 0.00034785270690918 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SearchUpdate #81887 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SearchUpdate #81887, processing time: 0.0005950927734375 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started CdnCacheUpdate #73971 [] 21:22:02 [squid] [info] CdnCacheUpdate::purge: http://127.0.0.1:9413/wiki/Talk:ThreadItemStore5 http://127.0.0.1:9413/index.php?title=Talk:ThreadItemStore5&action=history {"private":false} 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended CdnCacheUpdate #73971, processing time: 8.8930130004883E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_WikiPage::onArticleEdit #79327 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_WikiPage::onArticleEdit #79327, processing time: 4.7922134399414E-5 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.revision-create"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.revision-create' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.revision-create. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #84836 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #84836, processing time: 5.0067901611328E-5 [] 21:22:02 [OldRevisionImporter] [debug] ImportableOldRevisionImporter::import: running updates [] 21:22:02 [ParserCache] [debug] Saved in parser cache {"name":"pcache","key":"wikidb-unittest_:pcache:idhash:1-0!canonical!en","cache_time":"20231016202130","rev_id":4} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:page-content-model:4): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:4): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started RefreshSecondaryDataUpdate #84815 [] 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:linktargetstore-id:1%3AThreadItemStore5): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:4): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended RefreshSecondaryDataUpdate #84815, processing time: 0.012615919113159 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #82728 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #82728, processing time: 6.6995620727539E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #73978 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #73978, processing time: 3.9100646972656E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SiteStatsUpdate #82721 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SiteStatsUpdate #82721, processing time: 0.00042414665222168 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SearchUpdate #84815 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SearchUpdate #84815, processing time: 0.00069785118103027 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started CdnCacheUpdate #82667 [] 21:22:02 [squid] [info] CdnCacheUpdate::purge: http://127.0.0.1:9413/wiki/Talk:ThreadItemStore5 http://127.0.0.1:9413/index.php?title=Talk:ThreadItemStore5&action=history {"private":false} 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended CdnCacheUpdate #82667, processing time: 8.702278137207E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_WikiPage::onArticleEdit #82721 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_WikiPage::onArticleEdit #82721, processing time: 7.7009201049805E-5 [] 21:22:02 === 21:22:02 21:22:02 5) MediaWiki\Extension\DiscussionTools\Tests\ThreadItemStoreTest::testInsertThreadItems with data set #5 ('cases/ThreadItemStore/6change...-level') 21:22:02 Failed asserting that two arrays are equal. 21:22:02 --- Expected 21:22:02 +++ Actual 21:22:02 @@ @@ 21:22:02 'discussiontools_items' => Array ( 21:22:02 0 => Array (...) 21:22:02 1 => Array (...) 21:22:02 - 2 => Array (...) 21:22:02 - 3 => Array (...) 21:22:02 ) 21:22:02 'discussiontools_item_pages' => Array ( 21:22:02 0 => Array (...) 21:22:02 1 => Array (...) 21:22:02 - 2 => Array (...) 21:22:02 - 3 => Array (...) 21:22:02 ) 21:22:02 'discussiontools_item_revisions' => Array ( 21:22:02 0 => Array (...) 21:22:02 1 => Array (...) 21:22:02 2 => Array ( 21:22:02 - 'itr_id' => '5' 21:22:02 - 'itr_itemid_id' => '3' 21:22:02 - 'itr_revision_id' => '2' 21:22:02 - 'itr_items_id' => '3' 21:22:02 + 'itr_id' => '3' 21:22:02 + 'itr_itemid_id' => '1' 21:22:02 + 'itr_revision_id' => '3' 21:22:02 + 'itr_items_id' => '1' 21:22:02 'itr_parent_id' => null 21:22:02 'itr_transcludedfrom' => null 21:22:02 'itr_level' => '0' 21:22:02 @@ @@ 21:22:02 'itr_headinglevel' => '2' 21:22:02 ) 21:22:02 3 => Array ( 21:22:02 - 'itr_id' => '6' 21:22:02 - 'itr_itemid_id' => '4' 21:22:02 - 'itr_revision_id' => '2' 21:22:02 - 'itr_items_id' => '4' 21:22:02 - 'itr_parent_id' => '5' 21:22:02 + 'itr_id' => '4' 21:22:02 + 'itr_itemid_id' => '2' 21:22:02 + 'itr_revision_id' => '3' 21:22:02 + 'itr_items_id' => '2' 21:22:02 + 'itr_parent_id' => '3' 21:22:02 'itr_transcludedfrom' => null 21:22:02 'itr_level' => '1' 21:22:02 'itr_headinglevel' => null 21:22:02 ) 21:22:02 - 4 => Array (...) 21:22:02 - 5 => Array (...) 21:22:02 - 6 => Array (...) 21:22:02 - 7 => Array (...) 21:22:02 ) 21:22:02 'discussiontools_item_ids' => Array ( 21:22:02 0 => Array (...) 21:22:02 1 => Array (...) 21:22:02 - 2 => Array (...) 21:22:02 - 3 => Array (...) 21:22:02 - 4 => Array (...) 21:22:02 ) 21:22:02 ) 21:22:02 21:22:02 /workspace/src/extensions/DiscussionTools/tests/phpunit/ThreadItemStoreTest.php:88 21:22:02 phpvfscomposer:///workspace/src/vendor/phpunit/phpunit/phpunit:97 21:22:02 === Logs generated by test case 21:22:02 [objectcache] [debug] MainWANObjectCache using store {class} {"class":"HashBagOStuff"} 21:22:02 [localisation] [debug] LocalisationCache using store LCStoreNull [] 21:22:02 [localisation] [debug] LocalisationCache::isExpired(en): cache missing, need to make one [] 21:22:02 [wfDebug] [debug] ParserFactory: using default preprocessor {"private":false} 21:22:02 [ContentHandler] [info] Registered handler for wikitext: WikitextContentHandler [] 21:22:02 [MessageCache] [debug] MessageCache using store {class} {"class":"HashBagOStuff"} 21:22:02 [objectcache] [debug] MainObjectStash using store {class} {"class":"HashBagOStuff"} 21:22:02 [ParserCache] [debug] Creating ParserCache instance for pcache [] 21:22:02 [objectcache] [warning] Rejected set() for {cachekey} due to pending writes. {"cachekey":"global:NameTableSqlStore:content_models:wikidb-unittest_","lag":0,"age":0.5971660614013672,"walltime":0.00028204917907714844} 21:22:02 [objectcache] [debug] fetchOrRegenerate(global:NameTableSqlStore:content_models:wikidb-unittest_): miss, new value computed [] 21:22:02 [objectcache] [warning] Rejected set() for {cachekey} due to pending writes. {"cachekey":"global:NameTableSqlStore:slot_roles:wikidb-unittest_","lag":0,"age":0.5983729362487793,"walltime":0.0002689361572265625} 21:22:02 [objectcache] [debug] fetchOrRegenerate(global:NameTableSqlStore:slot_roles:wikidb-unittest_): miss, new value computed [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.revision-create"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.revision-create' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.revision-create. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #73186 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #73186, processing time: 8.0108642578125E-5 [] 21:22:02 [OldRevisionImporter] [debug] ImportableOldRevisionImporter::import: running updates [] 21:22:02 [UserOptionsManager] [debug] Loading options from database {"user_id":1} 21:22:02 [ParserCache] [debug] Saved in parser cache {"name":"pcache","key":"wikidb-unittest_:pcache:idhash:1-0!canonical!en","cache_time":"20231016202130","rev_id":1} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:page-content-model:1): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started RefreshSecondaryDataUpdate #70563 [] 21:22:02 [wfDebug] [debug] BacklinkCache::queryLinks: got results from DB {"private":false} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:linktargetstore-id:1%3AThreadItemStore6): miss, new value computed [] 21:22:02 [objectcache] [warning] Rejected set() for {cachekey} due to pending writes. {"cachekey":"wikidb-unittest_:numbacklinks:ns1%3AThreadItemStore6:templatelinks","lag":0,"age":0.6142849922180176,"walltime":0.0009140968322753906} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:numbacklinks:ns1%3AThreadItemStore6:templatelinks): miss, new value computed [] 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:linktargetstore-id:1%3AThreadItemStore6): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [ParserCache] [debug] Creating ParserCache instance for parsoid-pcache [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [ParserCache] [debug] ParserOutput metadata cache miss {"name":"parsoid-pcache"} 21:22:02 [ParserCache] [debug] Creating ParserCache instance for parsoid [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [ParserCache] [debug] ParserOutput metadata cache miss {"name":"parsoid"} 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [ParserCache] [debug] ParserOutput metadata cache miss {"name":"parsoid-pcache"} 21:22:02 [objectcache] [debug] fetchOrRegenerate(global:SqlBlobStore-blob:wikidb-unittest_:tt%3A133): miss, new value computed [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for ban from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for crh from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for iu from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for ku from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for sh from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for shi from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for sr from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for tg from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for tly from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for uz from source [] 21:22:02 [ParserCache] [debug] Saved in parser cache {"name":"parsoid-pcache","key":"wikidb-unittest_:parsoid-pcache:idhash:1-0!useParsoid=1!en","cache_time":"20231016202130","rev_id":1} 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended RefreshSecondaryDataUpdate #70563, processing time: 0.044166088104248 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #40127 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.page-properties-change"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.page-properties-change' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.page-properties-change. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #40127, processing time: 0.00041007995605469 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #52258 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #52258, processing time: 3.6001205444336E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #84479 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #84479, processing time: 3.7908554077148E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SiteStatsUpdate #74886 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SiteStatsUpdate #74886, processing time: 0.00056600570678711 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SearchUpdate #70563 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SearchUpdate #70563, processing time: 0.00086688995361328 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started CdnCacheUpdate #62359 [] 21:22:02 [squid] [info] CdnCacheUpdate::purge: http://127.0.0.1:9413/wiki/Talk:ThreadItemStore6 http://127.0.0.1:9413/index.php?title=Talk:ThreadItemStore6&action=history http://127.0.0.1:9413/wiki/ThreadItemStore6 http://127.0.0.1:9413/index.php?title=ThreadItemStore6&action=history {"private":false} 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended CdnCacheUpdate #62359, processing time: 0.00010085105895996 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.revision-create"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.revision-create' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.revision-create. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #49878 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #49878, processing time: 4.887580871582E-5 [] 21:22:02 [OldRevisionImporter] [debug] ImportableOldRevisionImporter::import: running updates [] 21:22:02 [UserOptionsManager] [debug] Loading options from database {"user_id":2} 21:22:02 [ParserCache] [debug] Saved in parser cache {"name":"pcache","key":"wikidb-unittest_:pcache:idhash:1-0!canonical!en","cache_time":"20231016202130","rev_id":2} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:page-content-model:2): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:2): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started RefreshSecondaryDataUpdate #83151 [] 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:linktargetstore-id:1%3AThreadItemStore6): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:2): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended RefreshSecondaryDataUpdate #83151, processing time: 0.01882791519165 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #83755 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.page-properties-change"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.page-properties-change' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.page-properties-change. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #83755, processing time: 0.00031709671020508 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #83780 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #83780, processing time: 3.3855438232422E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #74497 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #74497, processing time: 3.6001205444336E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SiteStatsUpdate #84999 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SiteStatsUpdate #84999, processing time: 0.00054001808166504 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SearchUpdate #83151 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SearchUpdate #83151, processing time: 0.0011870861053467 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started CdnCacheUpdate #71545 [] 21:22:02 [squid] [info] CdnCacheUpdate::purge: http://127.0.0.1:9413/wiki/Talk:ThreadItemStore6 http://127.0.0.1:9413/index.php?title=Talk:ThreadItemStore6&action=history {"private":false} 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended CdnCacheUpdate #71545, processing time: 0.00011801719665527 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_WikiPage::onArticleEdit #84999 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_WikiPage::onArticleEdit #84999, processing time: 6.7949295043945E-5 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.revision-create"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.revision-create' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.revision-create. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #73323 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #73323, processing time: 6.8902969360352E-5 [] 21:22:02 [OldRevisionImporter] [debug] ImportableOldRevisionImporter::import: running updates [] 21:22:02 [UserOptionsManager] [debug] Loading options from database {"user_id":3} 21:22:02 [ParserCache] [debug] Saved in parser cache {"name":"pcache","key":"wikidb-unittest_:pcache:idhash:1-0!canonical!en","cache_time":"20231016202130","rev_id":3} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:page-content-model:3): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:3): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started RefreshSecondaryDataUpdate #71545 [] 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:linktargetstore-id:1%3AThreadItemStore6): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:3): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended RefreshSecondaryDataUpdate #71545, processing time: 0.016626119613647 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #74107 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #74107, processing time: 7.0095062255859E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #83612 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #83612, processing time: 3.6954879760742E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SiteStatsUpdate #79773 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SiteStatsUpdate #79773, processing time: 0.0015420913696289 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SearchUpdate #71545 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SearchUpdate #71545, processing time: 0.0010039806365967 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started CdnCacheUpdate #84230 [] 21:22:02 [squid] [info] CdnCacheUpdate::purge: http://127.0.0.1:9413/wiki/Talk:ThreadItemStore6 http://127.0.0.1:9413/index.php?title=Talk:ThreadItemStore6&action=history {"private":false} 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended CdnCacheUpdate #84230, processing time: 9.608268737793E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_WikiPage::onArticleEdit #79773 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_WikiPage::onArticleEdit #79773, processing time: 4.887580871582E-5 [] 21:22:02 === 21:22:02 21:22:02 6) MediaWiki\Extension\DiscussionTools\Tests\ThreadItemStoreTest::testInsertThreadItems with data set #6 ('cases/ThreadItemStore/7identi...estamp') 21:22:02 Failed asserting that two arrays are equal. 21:22:02 --- Expected 21:22:02 +++ Actual 21:22:02 @@ @@ 21:22:02 1 => Array (...) 21:22:02 2 => Array (...) 21:22:02 3 => Array (...) 21:22:02 - 4 => Array (...) 21:22:02 - 5 => Array (...) 21:22:02 ) 21:22:02 'discussiontools_item_pages' => Array ( 21:22:02 0 => Array (...) 21:22:02 @@ @@ 21:22:02 1 => Array (...) 21:22:02 2 => Array (...) 21:22:02 3 => Array (...) 21:22:02 - 4 => Array (...) 21:22:02 - 5 => Array (...) 21:22:02 ) 21:22:02 'discussiontools_item_revisions' => Array ( 21:22:02 0 => Array (...) 21:22:02 @@ @@ 21:22:02 2 => Array (...) 21:22:02 3 => Array (...) 21:22:02 4 => Array ( 21:22:02 - 'itr_id' => '7' 21:22:02 - 'itr_itemid_id' => '5' 21:22:02 - 'itr_revision_id' => '2' 21:22:02 - 'itr_items_id' => '5' 21:22:02 - 'itr_parent_id' => '6' 21:22:02 - 'itr_transcludedfrom' => null 21:22:02 - 'itr_level' => '2' 21:22:02 - 'itr_headinglevel' => null 21:22:02 - ) 21:22:02 - 5 => Array ( 21:22:02 'itr_id' => '5' 21:22:02 'itr_itemid_id' => '1' 21:22:02 'itr_revision_id' => '3' 21:22:02 @@ @@ 21:22:02 'itr_level' => '0' 21:22:02 'itr_headinglevel' => '2' 21:22:02 ) 21:22:02 - 6 => Array ( 21:22:02 + 5 => Array ( 21:22:02 'itr_id' => '6' 21:22:02 'itr_itemid_id' => '2' 21:22:02 'itr_revision_id' => '3' 21:22:02 @@ @@ 21:22:02 'itr_level' => '1' 21:22:02 'itr_headinglevel' => null 21:22:02 ) 21:22:02 - 7 => Array ( 21:22:02 - 'itr_id' => '8' 21:22:02 + 6 => Array ( 21:22:02 + 'itr_id' => '7' 21:22:02 'itr_itemid_id' => '3' 21:22:02 'itr_revision_id' => '3' 21:22:02 'itr_items_id' => '3' 21:22:02 @@ @@ 21:22:02 'itr_level' => '0' 21:22:02 'itr_headinglevel' => '2' 21:22:02 ) 21:22:02 - 8 => Array (...) 21:22:02 - 9 => Array (...) 21:22:02 - 10 => Array (...) 21:22:02 + 7 => Array ( 21:22:02 + 'itr_id' => '8' 21:22:02 + 'itr_itemid_id' => '4' 21:22:02 + 'itr_revision_id' => '3' 21:22:02 + 'itr_items_id' => '4' 21:22:02 + 'itr_parent_id' => '7' 21:22:02 + 'itr_transcludedfrom' => null 21:22:02 + 'itr_level' => '1' 21:22:02 + 'itr_headinglevel' => null 21:22:02 + ) 21:22:02 ) 21:22:02 'discussiontools_item_ids' => Array ( 21:22:02 0 => Array (...) 21:22:02 @@ @@ 21:22:02 1 => Array (...) 21:22:02 2 => Array (...) 21:22:02 3 => Array (...) 21:22:02 - 4 => Array (...) 21:22:02 - 5 => Array (...) 21:22:02 ) 21:22:02 ) 21:22:02 21:22:02 /workspace/src/extensions/DiscussionTools/tests/phpunit/ThreadItemStoreTest.php:88 21:22:02 phpvfscomposer:///workspace/src/vendor/phpunit/phpunit/phpunit:97 21:22:02 === Logs generated by test case 21:22:02 [objectcache] [debug] MainWANObjectCache using store {class} {"class":"HashBagOStuff"} 21:22:02 [localisation] [debug] LocalisationCache using store LCStoreNull [] 21:22:02 [localisation] [debug] LocalisationCache::isExpired(en): cache missing, need to make one [] 21:22:02 [wfDebug] [debug] ParserFactory: using default preprocessor {"private":false} 21:22:02 [ContentHandler] [info] Registered handler for wikitext: WikitextContentHandler [] 21:22:02 [MessageCache] [debug] MessageCache using store {class} {"class":"HashBagOStuff"} 21:22:02 [objectcache] [debug] MainObjectStash using store {class} {"class":"HashBagOStuff"} 21:22:02 [ParserCache] [debug] Creating ParserCache instance for pcache [] 21:22:02 [objectcache] [warning] Rejected set() for {cachekey} due to pending writes. {"cachekey":"global:NameTableSqlStore:content_models:wikidb-unittest_","lag":0,"age":0.7601680755615234,"walltime":0.00038886070251464844} 21:22:02 [objectcache] [debug] fetchOrRegenerate(global:NameTableSqlStore:content_models:wikidb-unittest_): miss, new value computed [] 21:22:02 [objectcache] [warning] Rejected set() for {cachekey} due to pending writes. {"cachekey":"global:NameTableSqlStore:slot_roles:wikidb-unittest_","lag":0,"age":0.7616438865661621,"walltime":0.0005319118499755859} 21:22:02 [objectcache] [debug] fetchOrRegenerate(global:NameTableSqlStore:slot_roles:wikidb-unittest_): miss, new value computed [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.revision-create"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.revision-create' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.revision-create. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #73785 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #73785, processing time: 7.2002410888672E-5 [] 21:22:02 [OldRevisionImporter] [debug] ImportableOldRevisionImporter::import: running updates [] 21:22:02 [UserOptionsManager] [debug] Loading options from database {"user_id":1} 21:22:02 [ParserCache] [debug] Saved in parser cache {"name":"pcache","key":"wikidb-unittest_:pcache:idhash:1-0!canonical!en","cache_time":"20231016202130","rev_id":1} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:page-content-model:1): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started RefreshSecondaryDataUpdate #83075 [] 21:22:02 [wfDebug] [debug] BacklinkCache::queryLinks: got results from DB {"private":false} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:linktargetstore-id:1%3AThreadItemStore7): miss, new value computed [] 21:22:02 [objectcache] [warning] Rejected set() for {cachekey} due to pending writes. {"cachekey":"wikidb-unittest_:numbacklinks:ns1%3AThreadItemStore7:templatelinks","lag":0,"age":0.7786738872528076,"walltime":0.0006940364837646484} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:numbacklinks:ns1%3AThreadItemStore7:templatelinks): miss, new value computed [] 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:linktargetstore-id:1%3AThreadItemStore7): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [ParserCache] [debug] Creating ParserCache instance for parsoid-pcache [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [ParserCache] [debug] ParserOutput metadata cache miss {"name":"parsoid-pcache"} 21:22:02 [ParserCache] [debug] Creating ParserCache instance for parsoid [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [ParserCache] [debug] ParserOutput metadata cache miss {"name":"parsoid"} 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [ParserCache] [debug] ParserOutput metadata cache miss {"name":"parsoid-pcache"} 21:22:02 [objectcache] [debug] fetchOrRegenerate(global:SqlBlobStore-blob:wikidb-unittest_:tt%3A136): miss, new value computed [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for ban from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for crh from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for iu from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for ku from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for sh from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for shi from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for sr from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for tg from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for tly from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for uz from source [] 21:22:02 [ParserCache] [debug] Saved in parser cache {"name":"parsoid-pcache","key":"wikidb-unittest_:parsoid-pcache:idhash:1-0!useParsoid=1!en","cache_time":"20231016202130","rev_id":1} 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended RefreshSecondaryDataUpdate #83075, processing time: 0.056710004806519 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #85973 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.page-properties-change"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.page-properties-change' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.page-properties-change. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #85973, processing time: 0.00033187866210938 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #57989 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #57989, processing time: 4.4107437133789E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #84255 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #84255, processing time: 4.0054321289062E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SiteStatsUpdate #74229 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SiteStatsUpdate #74229, processing time: 0.0004420280456543 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SearchUpdate #83075 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SearchUpdate #83075, processing time: 0.00066685676574707 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started CdnCacheUpdate #83084 [] 21:22:02 [squid] [info] CdnCacheUpdate::purge: http://127.0.0.1:9413/wiki/Talk:ThreadItemStore7 http://127.0.0.1:9413/index.php?title=Talk:ThreadItemStore7&action=history http://127.0.0.1:9413/wiki/ThreadItemStore7 http://127.0.0.1:9413/index.php?title=ThreadItemStore7&action=history {"private":false} 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended CdnCacheUpdate #83084, processing time: 0.00010299682617188 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.revision-create"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.revision-create' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.revision-create. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #84251 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #84251, processing time: 5.5074691772461E-5 [] 21:22:02 [OldRevisionImporter] [debug] ImportableOldRevisionImporter::import: running updates [] 21:22:02 [UserOptionsManager] [debug] Loading options from database {"user_id":2} 21:22:02 [ParserCache] [debug] Saved in parser cache {"name":"pcache","key":"wikidb-unittest_:pcache:idhash:1-0!canonical!en","cache_time":"20231016202130","rev_id":2} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:page-content-model:2): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:2): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started RefreshSecondaryDataUpdate #83058 [] 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:linktargetstore-id:1%3AThreadItemStore7): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:2): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended RefreshSecondaryDataUpdate #83058, processing time: 0.015071868896484 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #43430 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.page-properties-change"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.page-properties-change' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.page-properties-change. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #43430, processing time: 0.00032496452331543 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #80042 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #80042, processing time: 3.2901763916016E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #78690 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #78690, processing time: 4.0054321289062E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SiteStatsUpdate #84059 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SiteStatsUpdate #84059, processing time: 0.00033807754516602 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SearchUpdate #83058 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SearchUpdate #83058, processing time: 0.00062298774719238 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started CdnCacheUpdate #84395 [] 21:22:02 [squid] [info] CdnCacheUpdate::purge: http://127.0.0.1:9413/wiki/Talk:ThreadItemStore7 http://127.0.0.1:9413/index.php?title=Talk:ThreadItemStore7&action=history {"private":false} 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended CdnCacheUpdate #84395, processing time: 8.7976455688477E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_WikiPage::onArticleEdit #84059 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_WikiPage::onArticleEdit #84059, processing time: 4.7922134399414E-5 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.revision-create"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.revision-create' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.revision-create. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #78827 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #78827, processing time: 5.2928924560547E-5 [] 21:22:02 [OldRevisionImporter] [debug] ImportableOldRevisionImporter::import: running updates [] 21:22:02 [UserOptionsManager] [debug] Loading options from database {"user_id":3} 21:22:02 [ParserCache] [debug] Saved in parser cache {"name":"pcache","key":"wikidb-unittest_:pcache:idhash:1-0!canonical!en","cache_time":"20231016202130","rev_id":3} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:page-content-model:3): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:3): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started RefreshSecondaryDataUpdate #84395 [] 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:linktargetstore-id:1%3AThreadItemStore7): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:3): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended RefreshSecondaryDataUpdate #84395, processing time: 0.017591953277588 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #84245 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.page-properties-change"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.page-properties-change' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.page-properties-change. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #84245, processing time: 0.0003209114074707 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #82615 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #82615, processing time: 3.504753112793E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #84034 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #84034, processing time: 3.8862228393555E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SiteStatsUpdate #84031 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SiteStatsUpdate #84031, processing time: 0.00040984153747559 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SearchUpdate #84395 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SearchUpdate #84395, processing time: 0.00073504447937012 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started CdnCacheUpdate #82707 [] 21:22:02 [squid] [info] CdnCacheUpdate::purge: http://127.0.0.1:9413/wiki/Talk:ThreadItemStore7 http://127.0.0.1:9413/index.php?title=Talk:ThreadItemStore7&action=history {"private":false} 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended CdnCacheUpdate #82707, processing time: 8.9883804321289E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_WikiPage::onArticleEdit #84031 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_WikiPage::onArticleEdit #84031, processing time: 4.7922134399414E-5 [] 21:22:02 === 21:22:02 21:22:02 7) MediaWiki\Extension\DiscussionTools\Tests\ThreadItemStoreTest::testInsertThreadItems with data set #7 ('cases/ThreadItemStore/8indist...e-page') 21:22:02 Failed asserting that two arrays are equal. 21:22:02 --- Expected 21:22:02 +++ Actual 21:22:02 @@ @@ 21:22:02 'discussiontools_items' => Array ( 21:22:02 0 => Array (...) 21:22:02 1 => Array (...) 21:22:02 - 2 => Array (...) 21:22:02 ) 21:22:02 'discussiontools_item_pages' => Array ( 21:22:02 0 => Array (...) 21:22:02 1 => Array (...) 21:22:02 - 2 => Array (...) 21:22:02 ) 21:22:02 'discussiontools_item_revisions' => Array ( 21:22:02 0 => Array (...) 21:22:02 1 => Array (...) 21:22:02 2 => Array ( 21:22:02 - 'itr_id' => '5' 21:22:02 - 'itr_itemid_id' => '3' 21:22:02 - 'itr_revision_id' => '2' 21:22:02 - 'itr_items_id' => '3' 21:22:02 - 'itr_parent_id' => '4' 21:22:02 + 'itr_id' => '3' 21:22:02 + 'itr_itemid_id' => '1' 21:22:02 + 'itr_revision_id' => '6' 21:22:02 + 'itr_items_id' => '1' 21:22:02 + 'itr_parent_id' => null 21:22:02 'itr_transcludedfrom' => null 21:22:02 - 'itr_level' => '2' 21:22:02 - 'itr_headinglevel' => null 21:22:02 + 'itr_level' => '0' 21:22:02 + 'itr_headinglevel' => '2' 21:22:02 ) 21:22:02 3 => Array ( 21:22:02 - 'itr_id' => '7' 21:22:02 - 'itr_itemid_id' => '4' 21:22:02 - 'itr_revision_id' => '4' 21:22:02 - 'itr_items_id' => '3' 21:22:02 - 'itr_parent_id' => '6' 21:22:02 + 'itr_id' => '4' 21:22:02 + 'itr_itemid_id' => '2' 21:22:02 + 'itr_revision_id' => '6' 21:22:02 + 'itr_items_id' => '2' 21:22:02 + 'itr_parent_id' => '3' 21:22:02 'itr_transcludedfrom' => null 21:22:02 - 'itr_level' => '3' 21:22:02 + 'itr_level' => '1' 21:22:02 'itr_headinglevel' => null 21:22:02 ) 21:22:02 - 4 => Array (...) 21:22:02 - 5 => Array (...) 21:22:02 - 6 => Array (...) 21:22:02 - 7 => Array (...) 21:22:02 ) 21:22:02 'discussiontools_item_ids' => Array ( 21:22:02 0 => Array (...) 21:22:02 1 => Array (...) 21:22:02 - 2 => Array (...) 21:22:02 - 3 => Array (...) 21:22:02 - 4 => Array (...) 21:22:02 ) 21:22:02 ) 21:22:02 21:22:02 /workspace/src/extensions/DiscussionTools/tests/phpunit/ThreadItemStoreTest.php:88 21:22:02 phpvfscomposer:///workspace/src/vendor/phpunit/phpunit/phpunit:97 21:22:02 === Logs generated by test case 21:22:02 [objectcache] [debug] MainWANObjectCache using store {class} {"class":"HashBagOStuff"} 21:22:02 [localisation] [debug] LocalisationCache using store LCStoreNull [] 21:22:02 [localisation] [debug] LocalisationCache::isExpired(en): cache missing, need to make one [] 21:22:02 [wfDebug] [debug] ParserFactory: using default preprocessor {"private":false} 21:22:02 [ContentHandler] [info] Registered handler for wikitext: WikitextContentHandler [] 21:22:02 [MessageCache] [debug] MessageCache using store {class} {"class":"HashBagOStuff"} 21:22:02 [objectcache] [debug] MainObjectStash using store {class} {"class":"HashBagOStuff"} 21:22:02 [ParserCache] [debug] Creating ParserCache instance for pcache [] 21:22:02 [objectcache] [warning] Rejected set() for {cachekey} due to pending writes. {"cachekey":"global:NameTableSqlStore:content_models:wikidb-unittest_","lag":0,"age":0.9266750812530518,"walltime":0.0003039836883544922} 21:22:02 [objectcache] [debug] fetchOrRegenerate(global:NameTableSqlStore:content_models:wikidb-unittest_): miss, new value computed [] 21:22:02 [objectcache] [warning] Rejected set() for {cachekey} due to pending writes. {"cachekey":"global:NameTableSqlStore:slot_roles:wikidb-unittest_","lag":0,"age":0.9277620315551758,"walltime":0.0002808570861816406} 21:22:02 [objectcache] [debug] fetchOrRegenerate(global:NameTableSqlStore:slot_roles:wikidb-unittest_): miss, new value computed [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.revision-create"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.revision-create' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.revision-create. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #81270 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #81270, processing time: 6.7949295043945E-5 [] 21:22:02 [OldRevisionImporter] [debug] ImportableOldRevisionImporter::import: running updates [] 21:22:02 [UserOptionsManager] [debug] Loading options from database {"user_id":2} 21:22:02 [ParserCache] [debug] Saved in parser cache {"name":"pcache","key":"wikidb-unittest_:pcache:idhash:1-0!canonical!en","cache_time":"20231016202130","rev_id":1} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:page-content-model:1): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started RefreshSecondaryDataUpdate #82621 [] 21:22:02 [wfDebug] [debug] BacklinkCache::queryLinks: got results from DB {"private":false} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:linktargetstore-id:1%3AThreadItemStore8): miss, new value computed [] 21:22:02 [objectcache] [warning] Rejected set() for {cachekey} due to pending writes. {"cachekey":"wikidb-unittest_:numbacklinks:ns1%3AThreadItemStore8:templatelinks","lag":0,"age":0.945396900177002,"walltime":0.0009329319000244141} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:numbacklinks:ns1%3AThreadItemStore8:templatelinks): miss, new value computed [] 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:linktargetstore-id:1%3AThreadItemStore8): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [ParserCache] [debug] Creating ParserCache instance for parsoid-pcache [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [ParserCache] [debug] ParserOutput metadata cache miss {"name":"parsoid-pcache"} 21:22:02 [ParserCache] [debug] Creating ParserCache instance for parsoid [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [ParserCache] [debug] ParserOutput metadata cache miss {"name":"parsoid"} 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [ParserCache] [debug] ParserOutput metadata cache miss {"name":"parsoid-pcache"} 21:22:02 [objectcache] [debug] fetchOrRegenerate(global:SqlBlobStore-blob:wikidb-unittest_:tt%3A139): miss, new value computed [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for ban from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for crh from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for iu from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for ku from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for sh from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for shi from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for sr from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for tg from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for tly from source [] 21:22:02 [localisation] [debug] LocalisationCache::loadCoreData: got localisation for uz from source [] 21:22:02 [ParserCache] [debug] Saved in parser cache {"name":"parsoid-pcache","key":"wikidb-unittest_:parsoid-pcache:idhash:1-0!useParsoid=1!en","cache_time":"20231016202131","rev_id":1} 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:1): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended RefreshSecondaryDataUpdate #82621, processing time: 0.054660081863403 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #74367 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.page-properties-change"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.page-properties-change' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.page-properties-change. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #74367, processing time: 0.0003211498260498 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #65208 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #65208, processing time: 3.504753112793E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #84676 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #84676, processing time: 3.6954879760742E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SiteStatsUpdate #83584 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SiteStatsUpdate #83584, processing time: 0.00042319297790527 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SearchUpdate #82621 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SearchUpdate #82621, processing time: 0.00070714950561523 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started CdnCacheUpdate #71864 [] 21:22:02 [squid] [info] CdnCacheUpdate::purge: http://127.0.0.1:9413/wiki/Talk:ThreadItemStore8 http://127.0.0.1:9413/index.php?title=Talk:ThreadItemStore8&action=history http://127.0.0.1:9413/wiki/ThreadItemStore8 http://127.0.0.1:9413/index.php?title=ThreadItemStore8&action=history {"private":false} 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended CdnCacheUpdate #71864, processing time: 0.00010013580322266 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.revision-create"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.revision-create' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.revision-create. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #79100 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #79100, processing time: 5.5074691772461E-5 [] 21:22:02 [OldRevisionImporter] [debug] ImportableOldRevisionImporter::import: running updates [] 21:22:02 [UserOptionsManager] [debug] Loading options from database {"user_id":1} 21:22:02 [ParserCache] [debug] Saved in parser cache {"name":"pcache","key":"wikidb-unittest_:pcache:idhash:1-0!canonical!en","cache_time":"20231016202131","rev_id":2} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:page-content-model:2): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:2): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started RefreshSecondaryDataUpdate #81766 [] 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:linktargetstore-id:1%3AThreadItemStore8): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:2): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended RefreshSecondaryDataUpdate #81766, processing time: 0.093348026275635 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #78485 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.page-properties-change"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.page-properties-change' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.page-properties-change. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #78485, processing time: 0.00057601928710938 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #78171 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #78171, processing time: 7.5817108154297E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #85954 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #85954, processing time: 8.2015991210938E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SiteStatsUpdate #76382 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SiteStatsUpdate #76382, processing time: 0.00072813034057617 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SearchUpdate #81766 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SearchUpdate #81766, processing time: 0.00096702575683594 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started CdnCacheUpdate #62220 [] 21:22:02 [squid] [info] CdnCacheUpdate::purge: http://127.0.0.1:9413/wiki/Talk:ThreadItemStore8 http://127.0.0.1:9413/index.php?title=Talk:ThreadItemStore8&action=history {"private":false} 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended CdnCacheUpdate #62220, processing time: 0.00013899803161621 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_WikiPage::onArticleEdit #76382 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_WikiPage::onArticleEdit #76382, processing time: 7.6055526733398E-5 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.revision-create"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.revision-create' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.revision-create. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #77797 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #77797, processing time: 8.702278137207E-5 [] 21:22:02 [OldRevisionImporter] [debug] ImportableOldRevisionImporter::import: running updates [] 21:22:02 [ParserCache] [debug] Saved in parser cache {"name":"pcache","key":"wikidb-unittest_:pcache:idhash:1-0!canonical!en","cache_time":"20231016202131","rev_id":3} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:page-content-model:3): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:3): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started RefreshSecondaryDataUpdate #62220 [] 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:linktargetstore-id:1%3AThreadItemStore8): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:3): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended RefreshSecondaryDataUpdate #62220, processing time: 0.020296096801758 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #82849 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #82849, processing time: 0.00018215179443359 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #44126 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #44126, processing time: 0.00011777877807617 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SiteStatsUpdate #83046 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SiteStatsUpdate #83046, processing time: 0.001154899597168 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SearchUpdate #62220 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SearchUpdate #62220, processing time: 0.0017180442810059 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started CdnCacheUpdate #82902 [] 21:22:02 [squid] [info] CdnCacheUpdate::purge: http://127.0.0.1:9413/wiki/Talk:ThreadItemStore8 http://127.0.0.1:9413/index.php?title=Talk:ThreadItemStore8&action=history {"private":false} 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended CdnCacheUpdate #82902, processing time: 0.0001978874206543 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_WikiPage::onArticleEdit #83046 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_WikiPage::onArticleEdit #83046, processing time: 0.00012898445129395 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.revision-create"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.revision-create' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.revision-create. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #85754 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #85754, processing time: 0.00011610984802246 [] 21:22:02 [OldRevisionImporter] [debug] ImportableOldRevisionImporter::import: running updates [] 21:22:02 [ParserCache] [debug] Saved in parser cache {"name":"pcache","key":"wikidb-unittest_:pcache:idhash:1-0!canonical!en","cache_time":"20231016202131","rev_id":4} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:page-content-model:4): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:4): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started RefreshSecondaryDataUpdate #44126 [] 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:linktargetstore-id:1%3AThreadItemStore8): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:4): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended RefreshSecondaryDataUpdate #44126, processing time: 0.02333402633667 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #86028 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #86028, processing time: 0.00010108947753906 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #61333 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #61333, processing time: 5.8889389038086E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SiteStatsUpdate #80118 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SiteStatsUpdate #80118, processing time: 0.00059700012207031 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SearchUpdate #44126 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SearchUpdate #44126, processing time: 0.0010590553283691 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started CdnCacheUpdate #43161 [] 21:22:02 [squid] [info] CdnCacheUpdate::purge: http://127.0.0.1:9413/wiki/Talk:ThreadItemStore8 http://127.0.0.1:9413/index.php?title=Talk:ThreadItemStore8&action=history {"private":false} 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended CdnCacheUpdate #43161, processing time: 0.00015687942504883 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_WikiPage::onArticleEdit #80118 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_WikiPage::onArticleEdit #80118, processing time: 7.2002410888672E-5 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.revision-create"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.revision-create' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.revision-create. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #82462 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #82462, processing time: 0.00015616416931152 [] 21:22:02 [OldRevisionImporter] [debug] ImportableOldRevisionImporter::import: running updates [] 21:22:02 [UserOptionsManager] [debug] Loading options from database {"user_id":3} 21:22:02 [ParserCache] [debug] Saved in parser cache {"name":"pcache","key":"wikidb-unittest_:pcache:idhash:1-0!canonical!en","cache_time":"20231016202131","rev_id":5} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:page-content-model:5): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:5): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started RefreshSecondaryDataUpdate #61333 [] 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:linktargetstore-id:1%3AThreadItemStore8): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:5): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended RefreshSecondaryDataUpdate #61333, processing time: 0.026840925216675 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #83887 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #83887, processing time: 0.00011801719665527 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #75736 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #75736, processing time: 7.2956085205078E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SiteStatsUpdate #73642 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SiteStatsUpdate #73642, processing time: 0.00058412551879883 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SearchUpdate #61333 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SearchUpdate #61333, processing time: 0.0010800361633301 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started CdnCacheUpdate #74077 [] 21:22:02 [squid] [info] CdnCacheUpdate::purge: http://127.0.0.1:9413/wiki/Talk:ThreadItemStore8 http://127.0.0.1:9413/index.php?title=Talk:ThreadItemStore8&action=history {"private":false} 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended CdnCacheUpdate #74077, processing time: 0.0001530647277832 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_WikiPage::onArticleEdit #73642 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_WikiPage::onArticleEdit #73642, processing time: 0.00011014938354492 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.revision-create"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.revision-create' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.revision-create. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #45244 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks::sendRevisionCreateEvent #45244, processing time: 7.2002410888672E-5 [] 21:22:02 [OldRevisionImporter] [debug] ImportableOldRevisionImporter::import: running updates [] 21:22:02 [ParserCache] [debug] Saved in parser cache {"name":"pcache","key":"wikidb-unittest_:pcache:idhash:1-0!canonical!en","cache_time":"20231016202131","rev_id":6} 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:page-content-model:6): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:6): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started RefreshSecondaryDataUpdate #75736 [] 21:22:02 [objectcache] [debug] fetchOrRegenerate(wikidb-unittest_:linktargetstore-id:1%3AThreadItemStore8): miss, new value computed [] 21:22:02 [objectcache] [debug] getWithSetCallback(wikidb-unittest_:page-content-model:6): process cache hit [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended RefreshSecondaryDataUpdate #75736, processing time: 0.012572050094604 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #75103 [] 21:22:02 [EventStreamConfig] [debug] Selecting stream configs for target streams: {streams} {"streams":"mediawiki.page-properties-change"} 21:22:02 [EventStreamConfig] [warning] Stream 'mediawiki.page-properties-change' does not match any `stream` in stream config [] 21:22:02 [EventBus] [debug] Using non-producing EventBus instance for stream mediawiki.page-properties-change. This stream is either undeclared, or is explicitly disabled. [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended AutoCommitUpdate_MediaWiki\Deferred\LinksUpdate\LinksUpdate::doUpdate #75103, processing time: 0.00028896331787109 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #55637 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Extension\EventBus\EventBusHooks->onLinksUpdateComplete #55637, processing time: 3.504753112793E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #83554 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_MediaWiki\Storage\DerivedPageDataUpdater->doUpdates #83554, processing time: 3.3855438232422E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SiteStatsUpdate #40178 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SiteStatsUpdate #40178, processing time: 0.00036382675170898 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started SearchUpdate #75736 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended SearchUpdate #75736, processing time: 0.00060296058654785 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started CdnCacheUpdate #80432 [] 21:22:02 [squid] [info] CdnCacheUpdate::purge: http://127.0.0.1:9413/wiki/Talk:ThreadItemStore8 http://127.0.0.1:9413/index.php?title=Talk:ThreadItemStore8&action=history {"private":false} 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended CdnCacheUpdate #80432, processing time: 8.702278137207E-5 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: started MWCallableUpdate_WikiPage::onArticleEdit #40178 [] 21:22:02 [DeferredUpdates] [debug] DeferredUpdates::run: ended MWCallableUpdate_WikiPage::onArticleEdit #40178, processing time: 4.7922134399414E-5 [] 21:22:02 === 21:22:02 21:22:02 FAILURES! 21:22:02 Tests: 2969, Assertions: 27188, Failures: 7, Skipped: 54.
Links
Example DNM change on the DiscussionTools repo - https://gerrit.wikimedia.org/r/c/mediawiki/extensions/DiscussionTools/+/966266
Example failure on the ReportIncident extension - https://integration.wikimedia.org/ci/job/quibble-vendor-mysql-php74-noselenium-docker/131977/console