| Anomie added a comment. |
The one thing that looks odd to me is this request:
Start request POST /jenkins-mwext-mw-selenium-node-composer-jessie-178/index.php/Special:UserLogin HTTP HEADERS: HOST: localhost:9412 CONNECTION: keep-alive CONTENT-LENGTH: 198 CACHE-CONTROL: max-age=0 ORIGIN: http://localhost:9412 UPGRADE-INSECURE-REQUESTS: 1 USER-AGENT: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/57.0.2987.98 Safari/537.36 CONTENT-TYPE: application/x-www-form-urlencoded ACCEPT: text/html,application/xhtml+xml,application/xml;q=0.9,image/webp,*/*;q=0.8 REFERER: http://localhost:9412/jenkins-mwext-mw-selenium-node-composer-jessie-178/index.php?title=Special:UserLogin ACCEPT-ENCODING: gzip, deflate, br ACCEPT-LANGUAGE: en-US,en;q=0.8 COOKIE: jenkins_u0_mw_session=aat4e7f32ah724timnuqcp59v65mgfsv ... [authentication] Primary login with MediaWiki\Auth\LocalPasswordPrimaryAuthenticationProvider succeeded [authentication] Login for WikiAdmin succeeded [CryptRand] 0 bytes of randomness leftover in the buffer. [session] SessionBackend "f8g9d0teu1t9k3t62kksknhlkmdpgvdo" metadata dirty due to ID reset (formerly "aat4e7f32ah724timnuqcp59v65mgfsv") [session] SessionBackend "f8g9d0teu1t9k3t62kksknhlkmdpgvdo" data dirty due to dirty(): MediaWiki\Auth\AuthManager->continueAuthentication/MediaWiki\Auth\AuthManager->setSessionDataForUser/MediaWiki\Session\Session->resetAllTokens/MediaWiki\Session\Session->remove/MediaWiki\Session\SessionBackend->dirty [session] SessionBackend "f8g9d0teu1t9k3t62kksknhlkmdpgvdo" metadata dirty due to user change [session] SessionBackend "f8g9d0teu1t9k3t62kksknhlkmdpgvdo" data dirty due to dirty(): MediaWiki\Auth\AuthManager->beginAuthentication/MediaWiki\Auth\AuthManager->continueAuthentication/MediaWiki\Auth\AuthManager->setSessionDataForUser/MediaWiki\Session\Session->set/MediaWiki\Session\SessionBackend->dirty [session] SessionBackend "f8g9d0teu1t9k3t62kksknhlkmdpgvdo" data dirty due to dirty(): MediaWiki\Auth\AuthManager->beginAuthentication/MediaWiki\Auth\AuthManager->continueAuthentication/MediaWiki\Auth\AuthManager->setSessionDataForUser/MediaWiki\Session\Session->set/MediaWiki\Session\SessionBackend->dirty [session] SessionBackend "f8g9d0teu1t9k3t62kksknhlkmdpgvdo" save: dataDirty=1 metaDirty=1 forcePersist=0 [cookie] setcookie: "jenkins_u0_mw_session", "f8g9d0teu1t9k3t62kksknhlkmdpgvdo", "0", "/", "", "", "1" [cookie] setcookie: "jenkins_u0_mwUserID", "1", "1526714909", "/", "", "", "1" [cookie] setcookie: "jenkins_u0_mwUserName", "WikiAdmin", "1526714909", "/", "", "", "1" [cookie] already deleted setcookie: "jenkins_u0_mwToken", "", "1492586909", "/", "", "", "1" [cookie] already deleted setcookie: "forceHTTPS", "", "1492586909", "/", "", "", "1" ... MediaWiki::preOutputCommit: primary transaction round committed MediaWiki::preOutputCommit: pre-send deferred updates completed [DBReplication] Wikimedia\Rdbms\ChronologyProtector::shutdownLB: DB '127.0.0.1:3306' touched
MediaWiki::preOutputCommit: LBFactory shutdown completed
[cookie] setcookie: "UseDC", "master", "1524122919", "/", "", "", "1"
[cookie] setcookie: "UseCDNCache", "false", "1524122919", "/", "", "", "1"
OutputPage::sendCacheControl: private caching; **
[runJobs] refreshLinksPrioritized 0.6544586852278953-öäü-♠♣♥♦ rootJobTimestamp=20180419072820 useRecursiveLinksUpdate=1 triggeringUser={"userId":0,"userName":"127.0.0.1"} triggeringRevisionId=4 causeAction=edit-page causeAgent=127.0.0.1 requestId=11cd8aefe6a35966b4539bf5 (id=15,timestamp=20180419072820) STARTING
Title::getRestrictionTypes: applicable restrictions to [[0.6544586852278953-öäü-♠♣♥♦]] are {edit,move}
[ContentHandler] Created handler for wikitext: WikitextContentHandler
[caches] parser: SqlBagOStuff
Parser cache options found.
[session] Session "aat4e7f32ah724timnuqcp59v65mgfsv" requested without UserID cookie
[session] SessionBackend "aat4e7f32ah724timnuqcp59v65mgfsv" is unsaved, marking dirty in constructor
[CryptRand] 0 bytes of randomness leftover in the buffer.
[session] SessionBackend "eovsojkij5dg8fsg2o2iej46u7392jse" metadata dirty due to ID reset (formerly "aat4e7f32ah724timnuqcp59v65mgfsv")
[session] SessionBackend "eovsojkij5dg8fsg2o2iej46u7392jse" save: dataDirty=1 metaDirty=1 forcePersist=0
[cookie] setcookie: "jenkins_u0_mw_session", "eovsojkij5dg8fsg2o2iej46u7392jse", "0", "/", "", "", "1"
[cookie] setcookie: "jenkins_u0_mwUserID", "", "1492586909", "/", "", "", "1"
[cookie] already deleted setcookie: "jenkins_u0_mwToken", "", "1492586909", "/", "", "", "1"
[cookie] already deleted setcookie: "forceHTTPS", "", "1492586909", "/", "", "", "1"
ParserOutput cache found.
[DBQuery] RefreshLinksJob::runForTitle: committing on behalf of RefreshLinksJob::run.
BacklinkCache::queryLinks: got results from DB
[DBQuery] RefreshLinksJob::runForTitle: committing on behalf of RefreshLinksJob::run.
[runJobs] refreshLinksPrioritized 0.6544586852278953-öäü-♠♣♥♦ rootJobTimestamp=20180419072820 useRecursiveLinksUpdate=1 triggeringUser={"userId":0,"userName":"127.0.0.1"} triggeringRevisionId=4 causeAction=edit-page causeAgent=127.0.0.1 requestId=11cd8aefe6a35966b4539bf5 (id=15,timestamp=20180419072820) t=146 good
Request ended normallyIt looks like the login changed the session, then post-send (except not really post because it apparently was able to re-set the cookies) it ran a job that wound up trying to load the pre-login session and stomped on the post-login session's cookies. In this case, that probably was ParserCache→ParserOptions→User::loadFromSession() trying to get the session.
Normally the "global" WebRequest (from $wgRequest or RequestContext) should have already had the updated SessionId cached, so something somewhere must have replaced it. I don't know what might have done so.
TASK DETAIL
EMAIL PREFERENCES
To: zeljkofilipin, Anomie
Cc: Anomie, hashar, Jakob_WMDE, Addshore, Pablo-WMDE, WMDE-leszek, Aklapper, zeljkofilipin, Lahi, Gq86, Cinemantique, GoranSMilovanovic, QZanden, LawExplorer, Liudvikas, Luke081515, thcipriani, Wikidata-bugs, aude, Darkdadaah, Mbch331, Jay8g, greg
Cc: Anomie, hashar, Jakob_WMDE, Addshore, Pablo-WMDE, WMDE-leszek, Aklapper, zeljkofilipin, Lahi, Gq86, Cinemantique, GoranSMilovanovic, QZanden, LawExplorer, Liudvikas, Luke081515, thcipriani, Wikidata-bugs, aude, Darkdadaah, Mbch331, Jay8g, greg
_______________________________________________ Wikidata-bugs mailing list [email protected] https://lists.wikimedia.org/mailman/listinfo/wikidata-bugs
