valhallasw added a subscriber: valhallasw. valhallasw added a comment.
With a clear apicache, the following requests are made:
1. 2015-03-28 11:33:26 threadedhttp.py, 215 in request: DEBUG ('https://en.wikipedia.org/w/api.php', 'POST', 'maxlag=5&format=json&meta=siteinfo%7Cuserinfo&action=query&siprop=namespaces%7Cnamespacealiases%7Cgeneral&uiprop=blockinfo%7Chasmsg', {'connection': 'keep-alive', 'Content-Type': 'application/x-www-form-urlencoded', 'user-agent': u'test1 (wikipedia:en; User:Valhallasw) Pywikibot/2.0b2 (g4338) httplib2/0.8 Python/2.7.6.final.0'}, 5, None) 2. 2015-03-28 11:33:26 threadedhttp.py, 215 in request: DEBUG ('https://en.wikipedia.org/w/api.php', 'POST', 'action=query&meta=userinfo&maxlag=5&uiprop=blockinfo%7Cgroups%7Chasmsg%7Crights&format=json', {'cookie': 'GeoIP=::::v4', 'connection': 'keep-alive', 'Content-Type': 'application/x-www-form-urlencoded', 'user-agent': u'test1 (wikipedia:en; User:Valhallasw) Pywikibot/2.0b2 (g4338) httplib2/0.8 Python/2.7.6.final.0'}, 5, None) 3. 2015-03-28 11:33:26 threadedhttp.py, 215 in request: DEBUG ('https://en.wikipedia.org/w/api.php', 'POST', 'action=query&meta=userinfo&maxlag=5&uiprop=blockinfo%7Cgroups%7Chasmsg%7Crights&format=json', {'cookie': 'GeoIP=::::v4', 'connection': 'keep-alive', 'Content-Type': 'application/x-www-form-urlencoded', 'user-agent': u'test1 (wikipedia:en; User:Valhallasw) Pywikibot/2.0b2 (g4338) httplib2/0.8 Python/2.7.6.final.0'}, 5, None) 4. 2015-03-28 11:33:26 threadedhttp.py, 215 in request: DEBUG ('https://en.wikipedia.org/w/api.php', 'POST', 'maxlag=5&format=json&meta=siteinfo%7Cuserinfo&action=query&siprop=general&uiprop=blockinfo%7Chasmsg', {'cookie': 'GeoIP=::::v4', 'connection': 'keep-alive', 'Content-Type': 'application/x-www-form-urlencoded', 'user-agent': u'test1 (wikipedia:en; User:Valhallasw) Pywikibot/2.0b2 (g4338) httplib2/0.8 Python/2.7.6.final.0'}, 5, None)
Only the first of those returns the server time -- I'm not sure why there's several requests for the blockinfo, though. **No further requests are made** (and I also cannot reproduce the 'first timestamp is different' issue). However, the following does appear in the log:
2015-03-28 11:33:26 api.py, 545 in _handle_warnings: WARNING API warning (query): Formatting of continuation data will be changing soon. To continue using the current formatting, use the 'rawcontinue' parameter. To begin using the new format, pass an empty string for 'continue' in the initial query. 2015-03-28 11:33:32 api.py, 545 in _handle_warnings: WARNING API warning (query): Formatting of continuation data will be changing soon. To continue using the current formatting, use the 'rawcontinue' parameter. To begin using the new format, pass an empty string for 'continue' in the initial query. 2015-03-28 11:33:39 api.py, 545 in _handle_warnings: WARNING API warning (query): Formatting of continuation data will be changing soon. To continue using the current formatting, use the 'rawcontinue' parameter. To begin using the new format, pass an empty string for 'continue' in the initial query.
which suggests the response of siteinfo is parsed every time. This suggests the issue is in the api cache. A simple confirmation if this is clearing apicache/* during the run, which results in a new date being shown.
TASK DETAIL https://phabricator.wikimedia.org/T94293
REPLY HANDLER ACTIONS Reply to comment or attach files, or !close, !claim, !unsubscribe or !assign <username>.
EMAIL PREFERENCES https://phabricator.wikimedia.org/settings/panel/emailpreferences/
To: XZise, valhallasw Cc: valhallasw, XZise, Aklapper, PhantomTech, Gryllida, jayvdb, scfc, pywikipedia-bugs