Email authentication still broken - MediaWiki-l

12 Jun 2015


      Hi,
I am having the exact same problem as Stefan, in his email from yesterday,
"EmailAuthentication broken - token is NULL in user  table" (
https://lists.wikimedia.org/pipermail/mediawiki-l/2015-June/044454.html).
My wiki installation is public, here: http://jatswiki.org. Feel free to go
there to see this problem for yourself, if you want.
If you try to create a new account, everything seems to work fine, and the
email is sent. But, when you click on it, the page comes back with "Invalid
token". But, if you go to the preferences screen, and send another one,
then that one (usually) works.
...
Looking at the database, i notice that the user_email_token,
user_email_token_expires and user_email_authenticated columns of the
user table are NULL for the newly registered users.
I see the same thing, and I note that when I resend an email confirmation,
I see what I guess are the expected results::
* user_email_authenticated stays NULL
* user_email_token and user_email_token_expires get a good values
I enabled the $wgDebugToolbar, and below is a full dump of what I creating
the account, the page that says "A confirmation code was sent to your email
address".
Thanks for any help you can give on this!  If there's some better place to
ask for help with this problem, please let me know.
Debug log:
----
Start request POST
/index.php?title=Special:UserLogin&action=submitlogin&type=signup&returnto=Main+Page
HTTP HEADERS:
HOST: jatswiki.org
CONTENT-LENGTH: 212
CACHE-CONTROL: max-age=0
ACCEPT:
text/html,application/xhtml+xml,application/xml;q=0.9,image/webp,*/*;q=0.8
ORIGIN: http://jatswiki.org
USER-AGENT: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_9_5)
AppleWebKit/537.36 (KHTML, like Gecko) Chrome/43.0.2357.124 Safari/537.36
CONTENT-TYPE: application/x-www-form-urlencoded
REFERER:
http://jatswiki.org/index.php?title=Special:UserLogin&returnto=Main+Page...
ACCEPT-ENCODING: gzip, deflate
ACCEPT-LANGUAGE: en-US,en;q=0.8
COOKIE: jatswiki_session=rrrc5rfpcq9qbma83f0pp88ro0;
jatswikiUserName=Test4; jatswikiLoggedOut=1434124288
X-FORWARDED-FOR: ::ffff:130.14.11.131
VIA: 1.1 localhost.localdomain 820EFE1A
[caches] main: EmptyBagOStuff, message: SqlBagOStuff, parser: SqlBagOStuff
[caches] LocalisationCache: using store LCStoreDB
Fully initialised
Connected to database 0 at localhost
IP: 130.14.254.26
Connected to database 0 at localhost
[MessageCache] MessageCache::load: Loading en... got from global cache
Unstubbing $wgParser on call of $wgParser::firstCallInit from
MessageCache::getParser
Parser: using preprocessor: Preprocessor_DOM
Unstubbing $wgLang on call of $wgLang::_unstub from
ParserOptions::__construct
User::getBlockedStatus: checking...
[captcha] ConfirmEdit: passed; new account 'Test5'
[SpamBlacklist] Loading email regex...
[SpamBlacklist] no files specified
[TitleBlacklist-cache] Updated jatswiki:title_blacklist_entries with 0
entries.
MWCryptRand::realGenerate: Generating cryptographic random bytes for
User::setToken/MWCryptRand::generateHex/MWCryptRand::realGenerateHex/MWCryptRand::generate/MWCryptRand::realGenerate
MWCryptRand::realGenerate: openssl_random_pseudo_bytes generated 16 bytes
of strong randomness.
MWCryptRand::realGenerate: 0 bytes of randomness leftover in the buffer.
DatabaseBase::query: Writes done: INSERT IGNORE INTO `user`
(user_id,user_name,user_password,user_newpassword,user_newpass_time,user_email,user_email_authenticated,user_real_name,user_token,user_registration,user_editcount,user_touched)
VALUES (NULL,'X')
User: loading options for user 8 from database.
MWCryptRand::realGenerate: Generating cryptographic random bytes for
User::setToken/MWCryptRand::generateHex/MWCryptRand::realGenerateHex/MWCryptRand::generate/MWCryptRand::realGenerate
MWCryptRand::realGenerate: openssl_random_pseudo_bytes generated 16 bytes
of strong randomness.
MWCryptRand::realGenerate: 0 bytes of randomness leftover in the buffer.
MWCryptRand::realGenerate: Generating cryptographic random bytes for
User::setInternalPassword/PasswordFactory::newFromPlaintext/Pbkdf2Password::crypt/MWCryptRand::generate/MWCryptRand::realGenerate
MWCryptRand::realGenerate: openssl_random_pseudo_bytes generated 16 bytes
of strong randomness.
MWCryptRand::realGenerate: 0 bytes of randomness leftover in the buffer.
MWCryptRand::realGenerate: Generating cryptographic random bytes for
User::setToken/MWCryptRand::generateHex/MWCryptRand::realGenerateHex/MWCryptRand::generate/MWCryptRand::realGenerate
MWCryptRand::realGenerate: openssl_random_pseudo_bytes generated 16 bytes
of strong randomness.
MWCryptRand::realGenerate: 0 bytes of randomness leftover in the buffer.
MWCryptRand::realGenerate: Generating cryptographic random bytes for
User::confirmationToken/MWCryptRand::generateHex/MWCryptRand::realGenerateHex/MWCryptRand::generate/MWCryptRand::realGenerate
MWCryptRand::realGenerate: openssl_random_pseudo_bytes generated 16 bytes
of strong randomness.
MWCryptRand::realGenerate: 0 bytes of randomness leftover in the buffer.
[exception] [54dc23a2]
/index.php?title=Special:UserLogin&action=submitlogin&type=signup&returnto=Main+Page
MWException from line 3658 of /var/www/jatswiki.org/includes/User.php: CAS
update failed on user_touched for user ID '8'.
#0 /var/www/jatswiki.org/includes/User.php(4165): User->saveSettings()
#1 /var/www/jatswiki.org/includes/specials/SpecialUserlogin.php(409):
User->sendConfirmationMail()
#2 /var/www/jatswiki.org/includes/specials/SpecialUserlogin.php(314):
LoginForm->addNewAccount()
#3 /var/www/jatswiki.org/includes/specialpage/SpecialPage.php(384):
LoginForm->execute(NULL)
#4 /var/www/jatswiki.org/includes/specialpage/SpecialPageFactory.php(582):
SpecialPage->run(NULL)
#5 /var/www/jatswiki.org/includes/MediaWiki.php(267):
SpecialPageFactory::executePath(Object(Title), Object(RequestContext))
#6 /var/www/jatswiki.org/includes/MediaWiki.php(566):
MediaWiki->performRequest()
#7 /var/www/jatswiki.org/includes/MediaWiki.php(414): MediaWiki->main()
#8 /var/www/jatswiki.org/index.php(41): MediaWiki->run()
#9 {main}
[exception-json] {"id":"54dc23a2","type":"MWException","file":"/var/www/
jatswiki.org/includes/User.php","line":3658,"message":"CAS update failed on
user_touched for user ID
'8'.","url":"/index.php?title=Special:UserLogin&action=submitlogin&type=signup&returnto=Main+Page","backtrace":[{"file":"/var/www/
jatswiki.org/includes/User.php
","line":4165,"function":"saveSettings","class":"User","type":"->","args":[]},{"file":"/var/www/
jatswiki.org/includes/specials/SpecialUserlogin.php
","line":409,"function":"sendConfirmationMail","class":"User","type":"->","args":[]},{"file":"/var/www/
jatswiki.org/includes/specials/SpecialUserlogin.php
","line":314,"function":"addNewAccount","class":"LoginForm","type":"->","args":[]},{"file":"/var/www/
jatswiki.org/includes/specialpage/SpecialPage.php
","line":384,"function":"execute","class":"LoginForm","type":"->","args":["NULL"]},{"file":"/var/www/
jatswiki.org/includes/specialpage/SpecialPageFactory.php
","line":582,"function":"run","class":"SpecialPage","type":"->","args":["NULL"]},{"file":"/var/www/
jatswiki.org/includes/MediaWiki.php
","line":267,"function":"executePath","class":"SpecialPageFactory","type":"::","args":["Title","RequestContext"]},{"file":"/var/www/
jatswiki.org/includes/MediaWiki.php
","line":566,"function":"performRequest","class":"MediaWiki","type":"->","args":[]},{"file":"/var/www/
jatswiki.org/includes/MediaWiki.php
","line":414,"function":"main","class":"MediaWiki","type":"->","args":[]},{"file":"/var/www/
jatswiki.org/index.php
","line":41,"function":"run","class":"MediaWiki","type":"->","args":[]}]}
UserMailer::send: sending mail to Test5 voldrani@gmail.com
Sending mail via internal mail() function
[exception] [63993193]
/index.php?title=Special:UserLogin&action=submitlogin&type=signup&returnto=Main+Page
MWException from line 3658 of /var/www/jatswiki.org/includes/User.php: CAS
update failed on user_touched for user ID '8'.
#0 /var/www/jatswiki.org/includes/specials/SpecialUserlogin.php(418):
User->saveSettings()
#1 /var/www/jatswiki.org/includes/specials/SpecialUserlogin.php(314):
LoginForm->addNewAccount()
#2 /var/www/jatswiki.org/includes/specialpage/SpecialPage.php(384):
LoginForm->execute(NULL)
#3 /var/www/jatswiki.org/includes/specialpage/SpecialPageFactory.php(582):
SpecialPage->run(NULL)
#4 /var/www/jatswiki.org/includes/MediaWiki.php(267):
SpecialPageFactory::executePath(Object(Title), Object(RequestContext))
#5 /var/www/jatswiki.org/includes/MediaWiki.php(566):
MediaWiki->performRequest()
#6 /var/www/jatswiki.org/includes/MediaWiki.php(414): MediaWiki->main()
#7 /var/www/jatswiki.org/index.php(41): MediaWiki->run()
#8 {main}
[exception-json] {"id":"63993193","type":"MWException","file":"/var/www/
jatswiki.org/includes/User.php","line":3658,"message":"CAS update failed on
user_touched for user ID
'8'.","url":"/index.php?title=Special:UserLogin&action=submitlogin&type=signup&returnto=Main+Page","backtrace":[{"file":"/var/www/
jatswiki.org/includes/specials/SpecialUserlogin.php
","line":418,"function":"saveSettings","class":"User","type":"->","args":[]},{"file":"/var/www/
jatswiki.org/includes/specials/SpecialUserlogin.php
","line":314,"function":"addNewAccount","class":"LoginForm","type":"->","args":[]},{"file":"/var/www/
jatswiki.org/includes/specialpage/SpecialPage.php
","line":384,"function":"execute","class":"LoginForm","type":"->","args":["NULL"]},{"file":"/var/www/
jatswiki.org/includes/specialpage/SpecialPageFactory.php
","line":582,"function":"run","class":"SpecialPage","type":"->","args":["NULL"]},{"file":"/var/www/
jatswiki.org/includes/MediaWiki.php
","line":267,"function":"executePath","class":"SpecialPageFactory","type":"::","args":["Title","RequestContext"]},{"file":"/var/www/
jatswiki.org/includes/MediaWiki.php
","line":566,"function":"performRequest","class":"MediaWiki","type":"->","args":[]},{"file":"/var/www/
jatswiki.org/includes/MediaWiki.php
","line":414,"function":"main","class":"MediaWiki","type":"->","args":[]},{"file":"/var/www/
jatswiki.org/index.php
","line":41,"function":"run","class":"MediaWiki","type":"->","args":[]}]}
[cookie] setcookie: "jatswikiUserID", "8", "1449676320", "/", "", "", "1"
[cookie] setcookie: "jatswikiUserName", "Test5", "1449676320", "/", "", "",
"1"
[cookie] setcookie: "jatswikiToken", "", "1434037920", "/", "", "", "1"
DatabaseBase::query: Writes done: REPLACE INTO `objectcache`
(keyname,value,exptime) VALUES ('X')
[gitinfo] Computed cacheFile= for /var/www/jatswiki.org
[gitinfo] Cache incomplete for /var/www/jatswiki.org