Upload fails often in 2.7

misaochan commented 6 years ago

Report from user on googlegroups He says "Very often: Upload failed"

And has logs attached. Unfortunately I can't share them here without permission, but anyone who has signed the NDA that allows them to access our private group is welcome to check it there.

There are two issues here:

The logs don't provide any meaningful information. It is completely cluttered by system logs, etc. This has been the case for logs provided by everyone else as well. Is it at all possible for us to filter for our app's logs programmatically in the "send logs" feature?
The upload failing very often. I plan to ask him where he is uploading from (sd card, etc), and for screenshots if he can provide them. What other information do you guys think would be useful to solve the problem? @maskaravivek @psh @nicolas-raoul @neslihanturan

He isn't the only one with it - a recent Play Store review was from a user who encountered the same problem. So I would probably assign this issue a fairly high priority since this is our core functionality.

nicolas-raoul commented 6 years ago

I actually also started to get failed uploads recently, when the new version was pushed to beta. Might be a coincidence though. Failed uploads were images I shared from the Android stock gallery.

misaochan commented 6 years ago

@nicolas-raoul Ah, interesting. Can you reproduce this reliably? What steps did you use to share the image - was it by going to the gallery, selecting the image, tapping "Share" and then selecting the Commons app? Where were the images stored - SD card, device, google photos etc?

neslihanturan commented 6 years ago

This seems very interesting. I looked at commit histories of possibly related classes but couldn't recognized a big change. Implement direct uploads for Nearby Map #1060 seems like the biggest change but I don't think it break something on regular upload. What do you think @misaochan , is this possible?

Other than #1060 the reason can be a mistake made when merging or rebasing, or solving a leak.

misaochan commented 6 years ago

Nearby uploads did not require significant changes to regular uploads, I think it's very unlikely that the small changes made would have caused the failures. Also, the review re: failed uploads was made while production was still in 2.6 - if that user was in beta, I'd think he would have used beta feedback instead of quitting beta just so he could post a regular review.

I will try and look at what might be causing this when I work on the upload overhaul. It would be very helpful to know where the images were stored etc. though.

maskaravivek commented 6 years ago

@misaochan We could grep through his logs and check the relevant errors. Can you point me to the user(maybe on our team chat) whose logs needs to be checked?

tanvidadu commented 6 years ago

To filter out logcats https://stackoverflow.com/questions/17448912/using-grep-command-to-filter-logcat-in-android?utm_medium=organic&utm_source=google_rich_qa&utm_campaign=google_rich_qa this might help.

misaochan commented 6 years ago

Thanks for the suggestions, everyone! I'll create a separate issue for the logs. :)

I got back to the user asking a few questions, hopefully that will help us with the failed uploads.

@maskaravivek , I messaged you on Hangouts.

maskaravivek commented 6 years ago

@misaochan Yes the logs are very less and there's almost nothing useful in the logs. I just see this logline multiple times.

23:46:54.215 28766 28794 E WTF     : Result: org.mediawiki.api.ApiResult@88326d205-01

I remember seeing this log a few times in the app when logs upload fails for me.

ashishkumar468 commented 6 years ago

I tried to debug the issue [By uploading image from my google pixel emulator], it seems like there is an issue at the backend side, I am attaching the response xml which i am getting in the logs

<?xml version="1.0" encoding="UTF-8"?>
<api servedby="mw1278">
   <error code="abusefilter-disallowed" info="⧼abusefilter-warning-copyv2⧽" warning="&lt;p&gt;We could not determine whether this file is suitable for Wikimedia Commons. Please only upload photos that you took yourself with your camera, or see &lt;a href=&quot;/wiki/Special:MyLanguage/Commons:Licensing&quot; title=&quot;Special:MyLanguage/Commons:Licensing&quot;&gt;what else is acceptable&lt;/a&gt;. See the &lt;a href=&quot;/wiki/Special:MyLanguage/Commons:First_steps&quot; title=&quot;Special:MyLanguage/Commons:First steps&quot;&gt;guide&lt;/a&gt; to make sure the file is acceptable and learn how to upload it on Wikimedia Commons.&lt;/p&gt;&lt;p&gt;&lt;strong&gt;The content must be &lt;a href=&quot;/wiki/Special:MyLanguage/Commons:Licensing&quot; title=&quot;Special:MyLanguage/Commons:Licensing&quot;&gt;freely licensed&lt;/a&gt;. Don't copy files illegally (in violation of copyright) from the World Wide Web.&lt;/strong&gt;&lt;/p&gt;&#xA;&lt;p&gt;&lt;small&gt;If you believe that the file meets &lt;a href=&quot;/wiki/Commons:L&quot; class=&quot;mw-redirect&quot; title=&quot;Commons:L&quot;&gt;our licensing standards&lt;/a&gt;: Upload the file again or click &quot;Retry failed uploads&quot;/&quot;Submit modified file description&quot;.&lt;/small&gt;&lt;/p&gt;&#xA;" filekey="15py7xyvxdhw.4ztl85.6650319.jpg" sessionkey="15py7xyvxdhw.4ztl85.6650319.jpg">
      <message key="abusefilter-warning-copyv2">
         <params>
            <param>Small jpeg's uploaded by users with low editcount</param>
            <param>156</param>
         </params>
      </message>
      <abusefilter id="156" description="Small jpeg's uploaded by users with low editcount">
         <actions>
            <_v>throttle</_v>
            <_v>warn</_v>
         </actions>
      </abusefilter>
      <docref xml:space="preserve">See https://commons.wikimedia.org/w/api.php for API usage. Subscribe to the mediawiki-api-announce mailing list at &amp;lt;https://lists.wikimedia.org/mailman/listinfo/mediawiki-api-announce&amp;gt; for notice of API deprecations and breaking changes.</docref>
   </error>
</api>

As we can see from the xml, which says error code="abusefilter-disallowed, which hopefully states its a backend issue with some images.

maskaravivek commented 6 years ago

The error posted by @ashishkumar468 looks interesting. Am not sure if the user's upload failed with the same error. We are not logging server failure responses so there's no way to tell why it failed for the user.

misaochan commented 6 years ago

Just received permission from the user to repost his response to me. I think this will be very helpful:

Thema Workflow is Like this:

Make Photos by the way -> at Home (with working WLAN) Open google Photo -> senden Photo top snapseed -> Finish -> Share Button -> commons App -> Upload

After that the Progress bar moves bitte after some time "Upload failed"

After several tries i Made a logoff in the commons App. Then it worked. Perhaps the Automatik Login in commons App failed?

misaochan commented 6 years ago

It seems that the common denominator in both the above post's user and @nicolas-raoul 's reports is that they both used "Share" instead of uploading directly from the app. @nicolas-raoul do you recall if you "Share"d one or multiple photos when it happened to you?

nicolas-raoul commented 6 years ago

One by one. I almost never use the multiple photo upload feature.

On Mon, Jun 4, 2018 at 5:24 PM, Josephine Lim notifications@github.com wrote:

It seems that the common denominator in both the above post's user and @nicolas-raoul https://github.com/nicolas-raoul 's reports is that they both used "Share" instead of uploading directly from the app. @nicolas-raoul https://github.com/nicolas-raoul do you recall if you "Share"d one or multiple photos when it happened to you?

— You are receiving this because you were mentioned. Reply to this email directly, view it on GitHub https://github.com/commons-app/apps-android-commons/issues/1485#issuecomment-394273827, or mute the thread https://github.com/notifications/unsubscribe-auth/AAGFBhChA70qBb6ZBP3xAFHQ1z1YpCSpks5t5O7WgaJpZM4TvNi0 .

misaochan commented 6 years ago

Thanks @nicolas-raoul ! Do you also remember where your photo was stored (SD card or device)? Do you ever share from Google Photos?

misaochan commented 6 years ago

I have had no luck with reproducing this bug on v2.7.1 (Android 7.0), despite experimenting with different ways of uploading via Share.

I have tried:

Stock gallery > select image (from device storage) > Share > Commons
Stock gallery > select image (from SD card) > Share > Commons
Google Photos > select image > Share > Commons
Stock gallery > select multiple images > Share > Commons

All of the above uploads succeeded.

Anyone have any idea how we can reproduce this? Otherwise I think it will be very difficult to solve this, at least not until we get Send Logs working in a more efficient manner so that people can send us useful logs of the failed uploads. #1489

misaochan commented 6 years ago

I actually managed to reproduce this bug - by complete accident, while testing something else. The problem here is that this is the Play Store version 2.7.1 - I'm afraid to uninstall and build manually because then I might lose the reproduction.

But I can't seem to get any sort of useful logs in logcat from it. Logcat is running, but our app's process appears to be... hidden? Even when filtering for our package, I only get logs that aren't actually generated by our package. It isn't that it's too much spam, but I sorted through the spam and our own logs are virtually nonexistent.

Any idea what I'm doing wrong?

misaochan commented 6 years ago

Well, I can't reproduce this anymore. :/ Guess we have to hope that #1592 works.

neslihanturan commented 6 years ago

@misaochan this link can work to get rid of "no deugable process" alert: https://stackoverflow.com/questions/41033849/no-debuggable-processes-in-android-studio-when-connected-with-phone-which-runs-a

misaochan commented 6 years ago

I received a message on FB from a user who is experiencing this issue. She mentioned that the issue started a couple of months ago only, and about 99% of her uploads failed. She tried uninstalling and reinstalling the app, logging out and logging in again... none of that worked. So the issue may not be as simple as the auth token problem that we were assuming in #1592 . :/

She also says that while she normally uploads via "Share > Commons", her uploads from within the app itself fail as well.

Based on her and @nicolas-raoul 's comments, it seems like this would have been caused by one of the changes we made in 2.7.0 (which was released early May). The problem is that that release was a HUGE one and we don't know what the trigger could've been. It could have been related to the 2FA auth change, it could have been related to direct Nearby uploads, or it could even have been caused by one of the small changes to ShareActivity (like searching through other pictures for coordinates, etc).

Does anyone have any suggestions? I think that at the very least, along with the file system changes @neslihanturan is implementing, we need to implement a better feedback system for failed uploads. They should display to the user WHY they failed, in a manner that can be seen by the user, not just logs (which we cannot reliably obtain).

nicolas-raoul commented 6 years ago

We could get this FB user to do bisection, in order to find the exact commit that triggers the problem. If she can generate the APKs that would be the best, otherwise any volunteer to provide her with APKs every day or so until the commit is found? @sivaraam maybe?

sivaraam commented 6 years ago

I wouldn't mind doing the bisect (given a reasonable range to bisect, of course) if the FB user is ready to take APKs daily and try and test if it has issues or not. I think it would be a little tricky if there they couldn't consistently state a yes/no for "Whether the issue occurs to them in this APK?".

BTW, I've actually faced this issue once before (last week when I wasn't poking around for #1654, think). I wasn't able to upload any images at all for some time and I had to re-install the app to make the issue go away. I tried to see if I could reproduce it now and I did see this happen again. I think a slightly wavery network might be behind this, I'm not sure though. My flow was something like,

Share a photo from the Gallery using Commons and initiate the upload
The upload gets interrupted by a bad network (I intentionally terminated the network while testing today)
Then I cancel the upload in the app (If I remember correctly, even retrying the upload fails)
I try to upload the same image again and the upload fails
Then I cannot upload any image and I had to re-install the app to upload the images.

nicolas-raoul commented 6 years ago

Even a very rough bisect would be better than nothing, starting with the APK of the previously released version :-) That would rule out (or not) server-side changes.

misaochan commented 6 years ago

Thanks for volunteering, @sivaraam . :) Yes, I think the range would be between the 2.6.7 release and the 2.7.0 release.

I will talk to the user about this once she gets back to me on the previous question that I asked her re: 2FA. @sivaraam what is your preferred method of communication, can I link her to your on-wiki talk page?

nicolas-raoul commented 6 years ago

How about communicating via the public mailing list? So that everybody can benefit from the insight or offer ideas.

sivaraam commented 6 years ago

I don't think wiki talk pages would be suitable for sharing APKs. Public mailing list would be nice, as @nicolas-raoul suggests.

misaochan commented 6 years ago

Hmm, okay. So one of us would make a public mailing list post linking this issue and asking anyone who is experiencing problems to please download the APK and see if their problem is fixed with that APK? And I'll link the FB user to that post?

sivaraam commented 6 years ago

So one of us would make a public mailing list post linking this issue and asking anyone who is experiencing problems to please download the APK and see if their problem is fixed with that APK?

Yes, initially we would share an APK and based on their response we would be sharing further APKs (~10-11 APKs in total for the given range) which they would also have to test with and give feedback to help us narrow down the culprit. It might be time consuming and repetitive but it's the down-side of bisecting (unfortunately we can't use git bisect run).

Just to make sure, as this is a time consuming and repetitive process which might frustrate the user we should go ahead with this only if we have no other way to track down the issue. I think we currently are hopeless seeing the discussion in this thread.

BTW, which flavour of the apk (debug, prod) do you wish to provide to the users?

misaochan commented 6 years ago

Good point, it might indeed be frustrating to the user.

I talked to @neslihanturan who has found that the changes she is making to the file upload process at the moment have solved her own failed uploads problems. I think that once she and @maskaravivek have completed their bugfixes, we will go ahead with 2.8, and I will contact the users who have reported problems, and see if that fixes it for them. If it doesn't, then we will proceed with the bisection via public mailing list. How does that sound?

The APK flavour should be prodRelease IMO (we have prod/beta and debug/release, both types can be used in any permutation). You can sign the release APK with your own key.

sivaraam commented 6 years ago

If it doesn't, then we will proceed with the bisection via public mailing list. How does that sound?

Sounds nice. If the change magically does fix the failed upload issues, everyone's happy! :relaxed:

misaochan commented 6 years ago

I had a failure happen to me while testing the 2.8-release branch on prodDebug, API 24 emulator. I used the Gallery method to upload a photo from Google Photos. Associated logs:

07-17 18:41:40.103 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1713889 of 2516894
07-17 18:41:40.117 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1791713 of 2516894
07-17 18:41:40.131 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1869537 of 2516894
07-17 18:41:41.063 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1947361 of 2516894
07-17 18:41:42.023 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 2025185 of 2516894
07-17 18:41:42.039 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 2103009 of 2516894
07-17 18:41:43.463 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 2180833 of 2516894
07-17 18:41:43.479 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 2258657 of 2516894
07-17 18:41:44.423 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 2336481 of 2516894
07-17 18:41:44.761 6243-6290/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xb2785600: ver 2 0 (tinfo 0xb2783470)
07-17 18:41:44.772 6243-6290/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xb2785600: ver 2 0 (tinfo 0xb2783470)
07-17 18:41:44.779 6243-6290/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xb2785600: ver 2 0 (tinfo 0xb2783470)
07-17 18:41:44.789 6243-6290/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xb2785600: ver 2 0 (tinfo 0xb2783470)
07-17 18:41:44.795 6243-6290/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xb2785600: ver 2 0 (tinfo 0xb2783470)
07-17 18:41:44.806 6243-6290/fr.free.nrw.commons.debug D/OpenGLRenderer: endAllActiveAnimators on 0x96b09200 (RippleDrawable) with handle 0x96bc1780
07-17 18:41:45.083 1661-2349/system_process I/WindowManager: Destroying surface Surface(name=fr.free.nrw.commons.debug/fr.free.nrw.commons.upload.ShareActivity) called by com.android.server.wm.WindowStateAnimator.destroySurface:2016 com.android.server.wm.WindowStateAnimator.destroySurfaceLocked:882 com.android.server.wm.WindowState.removeLocked:1456 com.android.server.wm.WindowManagerService.removeWindowInnerLocked:2484 com.android.server.wm.WindowManagerService.removeWindowLocked:2442 com.android.server.wm.WindowManagerService.removeWindowLocked:2311 com.android.server.wm.WindowManagerService.removeWindow:2306 com.android.server.wm.Session.remove:202 
07-17 18:41:45.649 6243-6474/fr.free.nrw.commons.debug D/ModificationsSyncAdapte: Found 1 modifications to execute
07-17 18:41:46.348 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 2414305 of 2516894
07-17 18:41:46.363 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 2492129 of 2516894
07-17 18:41:46.379 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 2516894 of 2516894
07-17 18:41:49.707 6243-6243/fr.free.nrw.commons.debug W/View: requestLayout() improperly called by android.widget.TextView{9327d20 V.ED..... ......ID 12,36-203,73 #7f0f013e app:id/contributionTitle} during layout: running second layout pass
    requestLayout() improperly called by android.widget.TextView{35f36d9 V.ED..... ......ID 395,171-477,363 #7f0f013a app:id/contributionSequenceNumber} during layout: running second layout pass
07-17 18:41:56.689 6243-6299/fr.free.nrw.commons.debug E/WTF: Result: org.mediawiki.api.ApiResult@61fe214
07-17 18:41:56.694 6243-6299/fr.free.nrw.commons.debug E/ApacheHttpClientMediaWi: mustbeloggedin
07-17 18:41:56.694 6243-6299/fr.free.nrw.commons.debug D/UploadService: Response is fr.free.nrw.commons.mwapi.UploadResult@1ed8abd
    Contribution upload failed. Wikidata entity won't be edited
07-17 18:41:57.289 6243-6480/fr.free.nrw.commons.debug D/ModificationsSyncAdapte: Found 1 modifications to execute
07-17 18:41:57.706 6243-6243/fr.free.nrw.commons.debug W/View: requestLayout() improperly called by android.widget.TextView{56d15b9 V.ED..... ......ID 12,36-203,73 #7f0f013e app:id/contributionTitle} during layout: running second layout pass
    requestLayout() improperly called by android.widget.TextView{226cdfe V.ED..... ......ID 395,171-477,363 #7f0f013a app:id/contributionSequenceNumber} during layout: running second layout pass
    requestLayout() improperly called by android.widget.TextView{dbd495f V.ED..... ......ID 12,12-68,41 #7f0f013d app:id/contributionState} during layout: running second layout pass
07-17 18:42:00.623 6243-6243/fr.free.nrw.commons.debug D/MediaDetailFragment: MediaDetailFragment ready to display details
07-17 18:42:00.643 6243-6243/fr.free.nrw.commons.debug D/MediaDetailFragment: MediaDetailFragment ready to display details
07-17 18:42:00.644 6243-6243/fr.free.nrw.commons.debug D/MediaDetailFragment: MediaDetailFragment ready to display details
07-17 18:42:01.113 6243-6452/fr.free.nrw.commons.debug D/MediaDataExtractor: Nominated for deletion: false
07-17 18:42:01.589 6243-6357/fr.free.nrw.commons.debug D/MediaDataExtractor: Nominated for deletion: false
07-17 18:42:01.618 6243-6452/fr.free.nrw.commons.debug D/MediaDetailFragment: java.io.IOException: org.xml.sax.SAXParseException: Unexpected end of document
        at fr.free.nrw.commons.MediaDataExtractor.processWikiParseTree(MediaDataExtractor.java:104)
        at fr.free.nrw.commons.MediaDataExtractor.fetch(MediaDataExtractor.java:77)
        at fr.free.nrw.commons.media.MediaDetailFragment$3.doInBackground(MediaDetailFragment.java:252)
        at fr.free.nrw.commons.media.MediaDetailFragment$3.doInBackground(MediaDetailFragment.java:241)
        at android.os.AsyncTask$2.call(AsyncTask.java:304)
        at java.util.concurrent.FutureTask.run(FutureTask.java:237)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1133)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:607)
        at java.lang.Thread.run(Thread.java:761)
     Caused by: org.xml.sax.SAXParseException: Unexpected end of document
        at org.apache.harmony.xml.parsers.DocumentBuilderImpl.parse(DocumentBuilderImpl.java:125)
        at javax.xml.parsers.DocumentBuilder.parse(DocumentBuilder.java:107)
        at fr.free.nrw.commons.MediaDataExtractor.processWikiParseTree(MediaDataExtractor.java:100)
        at fr.free.nrw.commons.MediaDataExtractor.fetch(MediaDataExtractor.java:77) 
        at fr.free.nrw.commons.media.MediaDetailFragment$3.doInBackground(MediaDetailFragment.java:252) 
        at fr.free.nrw.commons.media.MediaDetailFragment$3.doInBackground(MediaDetailFragment.java:241) 
        at android.os.AsyncTask$2.call(AsyncTask.java:304) 
        at java.util.concurrent.FutureTask.run(FutureTask.java:237) 
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1133) 
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:607) 
        at java.lang.Thread.run(Thread.java:761) 
07-17 18:42:01.625 6243-6243/fr.free.nrw.commons.debug D/MediaDetailFragment: Failed to load photo details.
07-17 18:42:02.109 6243-6357/fr.free.nrw.commons.debug D/MediaDetailFragment: java.io.IOException: org.xml.sax.SAXParseException: Unexpected end of document
        at fr.free.nrw.commons.MediaDataExtractor.processWikiParseTree(MediaDataExtractor.java:104)
        at fr.free.nrw.commons.MediaDataExtractor.fetch(MediaDataExtractor.java:77)
        at fr.free.nrw.commons.media.MediaDetailFragment$3.doInBackground(MediaDetailFragment.java:252)
        at fr.free.nrw.commons.media.MediaDetailFragment$3.doInBackground(MediaDetailFragment.java:241)
        at android.os.AsyncTask$2.call(AsyncTask.java:304)
        at java.util.concurrent.FutureTask.run(FutureTask.java:237)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1133)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:607)
        at java.lang.Thread.run(Thread.java:761)
     Caused by: org.xml.sax.SAXParseException: Unexpected end of document
        at org.apache.harmony.xml.parsers.DocumentBuilderImpl.parse(DocumentBuilderImpl.java:125)
        at javax.xml.parsers.DocumentBuilder.parse(DocumentBuilder.java:107)
        at fr.free.nrw.commons.MediaDataExtractor.processWikiParseTree(MediaDataExtractor.java:100)
        at fr.free.nrw.commons.MediaDataExtractor.fetch(MediaDataExtractor.java:77) 
        at fr.free.nrw.commons.media.MediaDetailFragment$3.doInBackground(MediaDetailFragment.java:252) 
        at fr.free.nrw.commons.media.MediaDetailFragment$3.doInBackground(MediaDetailFragment.java:241) 
        at android.os.AsyncTask$2.call(AsyncTask.java:304) 
        at java.util.concurrent.FutureTask.run(FutureTask.java:237) 
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1133) 
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:607) 
        at java.lang.Thread.run(Thread.java:761) 
07-17 18:42:02.115 6243-6452/fr.free.nrw.commons.debug D/MediaDataExtractor: Nominated for deletion: false
07-17 18:42:02.125 6243-6243/fr.free.nrw.commons.debug D/MediaDetailFragment: Failed to load photo details.
07-17 18:42:02.623 6243-6452/fr.free.nrw.commons.debug D/MediaDetailFragment: java.io.IOException: org.xml.sax.SAXParseException: Unexpected end of document
        at fr.free.nrw.commons.MediaDataExtractor.processWikiParseTree(MediaDataExtractor.java:104)
        at fr.free.nrw.commons.MediaDataExtractor.fetch(MediaDataExtractor.java:77)
        at fr.free.nrw.commons.media.MediaDetailFragment$3.doInBackground(MediaDetailFragment.java:252)
        at fr.free.nrw.commons.media.MediaDetailFragment$3.doInBackground(MediaDetailFragment.java:241)
        at android.os.AsyncTask$2.call(AsyncTask.java:304)
        at java.util.concurrent.FutureTask.run(FutureTask.java:237)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1133)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:607)
        at java.lang.Thread.run(Thread.java:761)
     Caused by: org.xml.sax.SAXParseException: Unexpected end of document
        at org.apache.harmony.xml.parsers.DocumentBuilderImpl.parse(DocumentBuilderImpl.java:125)
        at javax.xml.parsers.DocumentBuilder.parse(DocumentBuilder.java:107)
        at fr.free.nrw.commons.MediaDataExtractor.processWikiParseTree(MediaDataExtractor.java:100)
        at fr.free.nrw.commons.MediaDataExtractor.fetch(MediaDataExtractor.java:77) 
        at fr.free.nrw.commons.media.MediaDetailFragment$3.doInBackground(MediaDetailFragment.java:252) 
        at fr.free.nrw.commons.media.MediaDetailFragment$3.doInBackground(MediaDetailFragment.java:241) 
        at android.os.AsyncTask$2.call(AsyncTask.java:304) 
        at java.util.concurrent.FutureTask.run(FutureTask.java:237) 
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1133) 
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:607) 
        at java.lang.Thread.run(Thread.java:761) 
07-17 18:42:02.624 6243-6243/fr.free.nrw.commons.debug D/MediaDetailFragment: Failed to load photo details.

misaochan commented 6 years ago

Logging out did not fix the issue, my next upload (via the same method) also failed:

07-17 18:50:11.336 6243-6299/fr.free.nrw.commons.debug D/UploadService: Successfully revalidated token!
07-17 18:50:11.843 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 46815 of 1537162
07-17 18:50:11.868 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 95967 of 1537162
07-17 18:50:11.881 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 145119 of 1537162
07-17 18:50:11.898 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 194271 of 1537162
07-17 18:50:11.910 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 243423 of 1537162
07-17 18:50:11.927 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 292575 of 1537162
07-17 18:50:11.951 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 341727 of 1537162
07-17 18:50:11.972 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 390879 of 1537162
07-17 18:50:12.127 6243-6603/fr.free.nrw.commons.debug D/CategorizationFragment: Previous year: 2017
    Previous year: 2017
07-17 18:50:12.608 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 440031 of 1537162
07-17 18:50:12.621 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 489183 of 1537162
07-17 18:50:12.634 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 538335 of 1537162
07-17 18:50:12.654 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 587487 of 1537162
07-17 18:50:12.673 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 636639 of 1537162
07-17 18:50:12.687 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 685791 of 1537162
07-17 18:50:13.359 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 734943 of 1537162
07-17 18:50:13.376 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 784095 of 1537162
07-17 18:50:13.391 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 833247 of 1537162
07-17 18:50:13.415 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 882399 of 1537162
07-17 18:50:13.434 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 931551 of 1537162
07-17 18:50:13.455 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 980703 of 1537162
07-17 18:50:13.470 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1029855 of 1537162
07-17 18:50:17.691 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1079007 of 1537162
07-17 18:50:17.705 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1128159 of 1537162
07-17 18:50:17.721 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1177311 of 1537162
07-17 18:50:19.372 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1226463 of 1537162
07-17 18:50:19.385 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1275615 of 1537162
07-17 18:50:20.572 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1324767 of 1537162
07-17 18:50:20.581 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1373919 of 1537162
07-17 18:50:20.598 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1423071 of 1537162
07-17 18:50:21.533 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1472223 of 1537162
07-17 18:50:21.546 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1521375 of 1537162
07-17 18:50:21.571 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1537162 of 1537162
07-17 18:50:24.490 6243-6290/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xb2785600: ver 2 0 (tinfo 0xb2783470)
07-17 18:50:24.504 6243-6290/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xb2785600: ver 2 0 (tinfo 0xb2783470)
07-17 18:50:24.512 6243-6290/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xb2785600: ver 2 0 (tinfo 0xb2783470)
07-17 18:50:24.521 6243-6290/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xb2785600: ver 2 0 (tinfo 0xb2783470)
07-17 18:50:24.532 6243-6290/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xb2785600: ver 2 0 (tinfo 0xb2783470)
07-17 18:50:24.543 6243-6290/fr.free.nrw.commons.debug D/OpenGLRenderer: endAllActiveAnimators on 0x95103700 (RippleDrawable) with handle 0x93794780
07-17 18:50:25.022 6243-7257/fr.free.nrw.commons.debug D/ModificationsSyncAdapte: Found 1 modifications to execute
07-17 18:50:31.280 6243-6299/fr.free.nrw.commons.debug E/WTF: Result: org.mediawiki.api.ApiResult@fe2b6e7
07-17 18:50:31.282 6243-6299/fr.free.nrw.commons.debug E/ApacheHttpClientMediaWi: mustbeloggedin
07-17 18:50:31.282 6243-6299/fr.free.nrw.commons.debug D/UploadService: Response is fr.free.nrw.commons.mwapi.UploadResult@4ec7294
    Contribution upload failed. Wikidata entity won't be edited

misaochan commented 6 years ago

3rd test, via Gallery > selecting a photo stored on external storage (not Google Photos):

07-17 18:52:19.148 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 108297 of 118932
07-17 18:52:19.164 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 112393 of 118932
07-17 18:52:19.186 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 116489 of 118932
07-17 18:52:19.206 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 118932 of 118932
07-17 18:52:19.325 6243-6290/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xb2785600: ver 2 0 (tinfo 0xb2783470)
07-17 18:52:19.337 6243-6290/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xb2785600: ver 2 0 (tinfo 0xb2783470)
07-17 18:52:19.348 6243-6290/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xb2785600: ver 2 0 (tinfo 0xb2783470)
07-17 18:52:19.356 6243-6290/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xb2785600: ver 2 0 (tinfo 0xb2783470)
07-17 18:52:20.224 6243-6290/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xb2785600: ver 2 0 (tinfo 0xb2783470)
07-17 18:52:20.247 6243-6290/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xb2785600: ver 2 0 (tinfo 0xb2783470)
07-17 18:52:20.252 1661-1957/system_process I/WindowManager: Destroying surface Surface(name=fr.free.nrw.commons.debug/fr.free.nrw.commons.upload.ShareActivity) called by com.android.server.wm.WindowStateAnimator.destroySurface:2016 com.android.server.wm.WindowStateAnimator.destroySurfaceLocked:882 com.android.server.wm.WindowState.removeLocked:1456 com.android.server.wm.WindowManagerService.removeWindowInnerLocked:2484 com.android.server.wm.WindowManagerService.removeWindowLocked:2442 com.android.server.wm.WindowManagerService.removeWindowLocked:2311 com.android.server.wm.WindowManagerService.removeWindow:2306 com.android.server.wm.Session.remove:202 
07-17 18:52:20.301 6243-6290/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xb2785600: ver 2 0 (tinfo 0xb2783470)
07-17 18:52:20.457 6243-6299/fr.free.nrw.commons.debug E/WTF: Result: org.mediawiki.api.ApiResult@53f561f
07-17 18:52:20.460 6243-6299/fr.free.nrw.commons.debug E/ApacheHttpClientMediaWi: mustbeloggedin
07-17 18:52:20.460 6243-6299/fr.free.nrw.commons.debug D/UploadService: Response is fr.free.nrw.commons.mwapi.UploadResult@32f4d6c
    Contribution upload failed. Wikidata entity won't be edited
07-17 18:52:20.862 1661-1692/system_process I/WindowManager: Destroying surface Surface(name=fr.free.nrw.commons.debug/fr.free.nrw.commons.upload.ShareActivity) called by com.android.server.wm.WindowStateAnimator.destroySurface:2016 com.android.server.wm.WindowStateAnimator.destroySurfaceLocked:882 com.android.server.wm.WindowState.removeLocked:1456 com.android.server.wm.WindowManagerService.removeWindowInnerLocked:2484 com.android.server.wm.WindowManagerService.removeWindowLocked:2442 com.android.server.wm.WindowManagerService.removeWindowLocked:2311 com.android.server.wm.WindowManagerService.removeWindow:2306 com.android.server.wm.Session.remove:202 
07-17 18:52:21.037 6243-7343/fr.free.nrw.commons.debug D/ModificationsSyncAdapte: Found 1 modifications to execute
07-17 18:52:21.374 6243-6243/fr.free.nrw.commons.debug W/View: requestLayout() improperly called by android.widget.TextView{b411e96 V.ED..... ......ID 12,41-359,78 #7f0f013e app:id/contributionTitle} during layout: running second layout pass
    requestLayout() improperly called by android.widget.TextView{fea17 V.ED..... ......ID 395,171-477,363 #7f0f013a app:id/contributionSequenceNumber} during layout: running second layout pass
    requestLayout() improperly called by android.widget.TextView{4e3de04 V.ED..... ......ID 12,12-68,41 #7f0f013d app:id/contributionState} during layout: running second layout pass
07-17 18:52:23.390 6243-6243/fr.free.nrw.commons.debug W/View: requestLayout() improperly called by android.widget.TextView{f7cd034 V.ED..... ......ID 12,41-359,78 #7f0f013e app:id/contributionTitle} during layout: running second layout pass
    requestLayout() improperly called by android.widget.TextView{bccae5d V.ED..... ......ID 395,171-477,363 #7f0f013a app:id/contributionSequenceNumber} during layout: running second layout pass
07-17 18:52:23.391 6243-6243/fr.free.nrw.commons.debug W/View: requestLayout() improperly called by android.widget.TextView{c34c3d2 V.ED..... ......ID 12,12-68,41 #7f0f013d app:id/contributionState} during layout: running second layout pass
07-17 18:52:23.840 6243-6243/fr.free.nrw.commons.debug W/View: requestLayout() improperly called by android.widget.TextView{f7cd034 V.ED..... ......ID 12,41-359,78 #7f0f013e app:id/contributionTitle} during layout: running second layout pass
    requestLayout() improperly called by android.widget.TextView{bccae5d V.ED..... ......ID 395,171-477,363 #7f0f013a app:id/contributionSequenceNumber} during layout: running second layout pass
    requestLayout() improperly called by android.widget.TextView{c34c3d2 V.ED..... ......ID 12,12-68,41 #7f0f013d app:id/contributionState} during layout: running second layout pass
07-17 18:52:26.270 6243-6243/fr.free.nrw.commons.debug D/MediaDetailFragment: MediaDetailFragment ready to display details
07-17 18:52:26.288 6243-6243/fr.free.nrw.commons.debug D/MediaDetailFragment: MediaDetailFragment ready to display details
    MediaDetailFragment ready to display details
07-17 18:52:26.781 6243-7329/fr.free.nrw.commons.debug D/MediaDataExtractor: Nominated for deletion: false
07-17 18:52:27.300 6243-7326/fr.free.nrw.commons.debug D/MediaDataExtractor: Nominated for deletion: false
07-17 18:52:27.315 6243-7329/fr.free.nrw.commons.debug D/MediaDetailFragment: java.io.IOException: org.xml.sax.SAXParseException: Unexpected end of document
        at fr.free.nrw.commons.MediaDataExtractor.processWikiParseTree(MediaDataExtractor.java:104)
        at fr.free.nrw.commons.MediaDataExtractor.fetch(MediaDataExtractor.java:77)
        at fr.free.nrw.commons.media.MediaDetailFragment$3.doInBackground(MediaDetailFragment.java:252)
        at fr.free.nrw.commons.media.MediaDetailFragment$3.doInBackground(MediaDetailFragment.java:241)
        at android.os.AsyncTask$2.call(AsyncTask.java:304)
        at java.util.concurrent.FutureTask.run(FutureTask.java:237)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1133)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:607)
        at java.lang.Thread.run(Thread.java:761)
     Caused by: org.xml.sax.SAXParseException: Unexpected end of document
        at org.apache.harmony.xml.parsers.DocumentBuilderImpl.parse(DocumentBuilderImpl.java:125)
        at javax.xml.parsers.DocumentBuilder.parse(DocumentBuilder.java:107)
        at fr.free.nrw.commons.MediaDataExtractor.processWikiParseTree(MediaDataExtractor.java:100)
        at fr.free.nrw.commons.MediaDataExtractor.fetch(MediaDataExtractor.java:77) 
        at fr.free.nrw.commons.media.MediaDetailFragment$3.doInBackground(MediaDetailFragment.java:252) 
        at fr.free.nrw.commons.media.MediaDetailFragment$3.doInBackground(MediaDetailFragment.java:241) 
        at android.os.AsyncTask$2.call(AsyncTask.java:304) 
        at java.util.concurrent.FutureTask.run(FutureTask.java:237) 
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1133) 
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:607) 
        at java.lang.Thread.run(Thread.java:761) 
07-17 18:52:27.316 6243-6243/fr.free.nrw.commons.debug D/MediaDetailFragment: Failed to load photo details.
07-17 18:52:27.828 6243-7326/fr.free.nrw.commons.debug D/MediaDetailFragment: java.io.IOException: org.xml.sax.SAXParseException: Unexpected end of document
        at fr.free.nrw.commons.MediaDataExtractor.processWikiParseTree(MediaDataExtractor.java:104)
        at fr.free.nrw.commons.MediaDataExtractor.fetch(MediaDataExtractor.java:77)
        at fr.free.nrw.commons.media.MediaDetailFragment$3.doInBackground(MediaDetailFragment.java:252)
        at fr.free.nrw.commons.media.MediaDetailFragment$3.doInBackground(MediaDetailFragment.java:241)
        at android.os.AsyncTask$2.call(AsyncTask.java:304)
        at java.util.concurrent.FutureTask.run(FutureTask.java:237)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1133)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:607)
        at java.lang.Thread.run(Thread.java:761)
     Caused by: org.xml.sax.SAXParseException: Unexpected end of document
        at org.apache.harmony.xml.parsers.DocumentBuilderImpl.parse(DocumentBuilderImpl.java:125)
        at javax.xml.parsers.DocumentBuilder.parse(DocumentBuilder.java:107)
        at fr.free.nrw.commons.MediaDataExtractor.processWikiParseTree(MediaDataExtractor.java:100)
        at fr.free.nrw.commons.MediaDataExtractor.fetch(MediaDataExtractor.java:77) 
        at fr.free.nrw.commons.media.MediaDetailFragment$3.doInBackground(MediaDetailFragment.java:252) 
        at fr.free.nrw.commons.media.MediaDetailFragment$3.doInBackground(MediaDetailFragment.java:241) 
        at android.os.AsyncTask$2.call(AsyncTask.java:304) 
        at java.util.concurrent.FutureTask.run(FutureTask.java:237) 
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1133) 
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:607) 
        at java.lang.Thread.run(Thread.java:761) 
07-17 18:52:27.829 6243-6243/fr.free.nrw.commons.debug D/MediaDetailFragment: Failed to load photo details.
07-17 18:52:27.835 6243-7329/fr.free.nrw.commons.debug D/MediaDataExtractor: Nominated for deletion: false
07-17 18:52:28.363 6243-7329/fr.free.nrw.commons.debug D/MediaDataExtractor: MediaDataExtractor searching for license

misaochan commented 6 years ago

I think the MediaDataExtractor exception is unrelated - it happened when I tap the failed image to investigate further, and that class appears to be only for displaying media details. Here are the complete logs for the 4th failed upload, without tapping the failed image.

07-17 19:20:24.358 6243-6243/fr.free.nrw.commons.debug D/ContributionController: startGalleryPick() called with pickImageIntent

    --------- beginning of system
07-17 19:20:24.389 6404-7784/com.android.documentsui W/DocumentsActivity: Failed to restore stack for package: fr.free.nrw.commons.debug because of error: java.io.FileNotFoundException: Failed to find matching root for null
07-17 19:20:24.560 6243-6290/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xb2785600: ver 2 0 (tinfo 0xb2783470)
07-17 19:20:24.564 6243-6290/fr.free.nrw.commons.debug D/OpenGLRenderer: endAllActiveAnimators on 0x97c2df80 (RippleDrawable) with handle 0x93794960
07-17 19:20:24.941 1661-2308/system_process I/WindowManager: Destroying surface Surface(name=fr.free.nrw.commons.debug/fr.free.nrw.commons.contributions.ContributionsActivity) called by com.android.server.wm.WindowStateAnimator.destroySurface:2016 com.android.server.wm.WindowStateAnimator.destroySurfaceLocked:882 com.android.server.wm.WindowState.destroyOrSaveSurface:2100 com.android.server.wm.WindowManagerService.tryStartExitingAnimation:3024 com.android.server.wm.WindowManagerService.relayoutWindow:2904 com.android.server.wm.Session.relayout:224 android.view.IWindowSession$Stub.onTransact:286 com.android.server.wm.Session.onTransact:145 
07-17 19:20:45.896 6243-6243/fr.free.nrw.commons.debug D/ContributionsListFragme: OnActivityResult() parameters: Req code: 1 Result code: -1 Data: Intent { dat=content://com.google.android.apps.photos.contentprovider/0/1/mediakey:/AF1QipNc23xpG2bHvWg2VpxXBABHdooiSCK3R18vSV4W/ORIGINAL/NONE/1722298755 flg=0x1 clip={text/uri-list U:content://com.google.android.apps.photos.contentprovider/0/1/mediakey%3A%2FAF1QipNc23xpG2bHvWg2VpxXBABHdooiSCK3R18vSV4W/ORIGINAL/NONE/1722298755} }
07-17 19:20:45.897 6243-6243/fr.free.nrw.commons.debug D/ContributionController: Is direct upload false and the Wikidata entity ID is null
    handleImagePicked() called with onActivityResult()
07-17 19:20:45.903 6243-6243/fr.free.nrw.commons.debug I/ContributionController: Image selected
07-17 19:20:45.903 1661-2308/system_process I/ActivityManager: START u0 {act=android.intent.action.SEND typ=image/jpeg flg=0x1 cmp=fr.free.nrw.commons.debug/fr.free.nrw.commons.upload.ShareActivity clip={image/jpeg U:content://com.google.android.apps.photos.contentprovider/0/1/mediakey%3A%2FAF1QipNc23xpG2bHvWg2VpxXBABHdooiSCK3R18vSV4W/ORIGINAL/NONE/1722298755} (has extras)} from uid 10084 on display 0
07-17 19:20:45.932 6243-6290/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xb2785600: ver 2 0 (tinfo 0xb2783470)
07-17 19:20:45.965 6243-6243/fr.free.nrw.commons.debug I/AppCompatViewInflater: app:theme is now deprecated. Please move to using android:theme instead.
07-17 19:20:45.974 6243-6243/fr.free.nrw.commons.debug D/ShareActivity: This was initiated by a direct upload from Nearby
07-17 19:20:45.975 6243-6243/fr.free.nrw.commons.debug D/ShareActivity: Received wikiDataEntityId from contribution controller null
07-17 19:20:45.976 6243-6243/fr.free.nrw.commons.debug D/ShareActivity: Uri: content://com.google.android.apps.photos.contentprovider/0/1/mediakey%3A%2FAF1QipNc23xpG2bHvWg2VpxXBABHdooiSCK3R18vSV4W/ORIGINAL/NONE/1722298755
07-17 19:20:45.978 6243-6243/fr.free.nrw.commons.debug D/ShareActivity: Ext storage dir: /storage/emulated/0
07-17 19:20:45.994 6243-6243/fr.free.nrw.commons.debug I/FileUtils: File SHA1: 73c29356278dc48ce759051f011606de7c316205
07-17 19:20:45.995 6243-6243/fr.free.nrw.commons.debug D/ShareActivity: Input stream created from content://com.google.android.apps.photos.contentprovider/0/1/mediakey%3A%2FAF1QipNc23xpG2bHvWg2VpxXBABHdooiSCK3R18vSV4W/ORIGINAL/NONE/1722298755
    File SHA1 is: 73c29356278dc48ce759051f011606de7c316205
07-17 19:20:45.995 6243-6243/fr.free.nrw.commons.debug D/FileProcessor: Calling GPSExtractor
07-17 19:20:46.000 6243-6243/fr.free.nrw.commons.debug I/ExifInterface_JNI: Raw image not detected
07-17 19:20:46.020 6243-6243/fr.free.nrw.commons.debug D/FileUtils: Filepath (copied): /storage/emulated/0/CommonsApp/1531819246012.jpg
07-17 19:20:46.020 6243-6243/fr.free.nrw.commons.debug D/FileProcessor: Filepath: /storage/emulated/0/CommonsApp/1531819246012.jpg
    filePath/storage/emulated/0/CommonsApp/1531819246012.jpg
07-17 19:20:46.021 6243-6243/fr.free.nrw.commons.debug D/FileProcessor: folderTime Number:5
    fild date:1531819246000 time of creation1531819246000
07-17 19:20:46.021 6243-6243/fr.free.nrw.commons.debug W/System.err: java.io.FileNotFoundException: No content provider: /storage/emulated/0/CommonsApp/1531819246012.jpg
        at android.content.ContentResolver.openTypedAssetFileDescriptor(ContentResolver.java:1131)
        at android.content.ContentResolver.openAssetFileDescriptor(ContentResolver.java:982)
        at android.content.ContentResolver.openFileDescriptor(ContentResolver.java:835)
07-17 19:20:46.023 6243-6243/fr.free.nrw.commons.debug W/System.err:     at android.content.ContentResolver.openFileDescriptor(ContentResolver.java:789)
        at fr.free.nrw.commons.upload.FileProcessor.findOtherImages(FileProcessor.java:157)
07-17 19:20:46.024 6243-6243/fr.free.nrw.commons.debug W/System.err:     at fr.free.nrw.commons.upload.FileProcessor.processFileCoordinates(FileProcessor.java:122)
        at fr.free.nrw.commons.upload.ShareActivity.onCreate(ShareActivity.java:288)
        at android.app.Activity.performCreate(Activity.java:6662)
        at android.app.Instrumentation.callActivityOnCreate(Instrumentation.java:1118)
        at android.app.ActivityThread.performLaunchActivity(ActivityThread.java:2599)
07-17 19:20:46.025 6243-6243/fr.free.nrw.commons.debug W/System.err:     at android.app.ActivityThread.handleLaunchActivity(ActivityThread.java:2707)
        at android.app.ActivityThread.-wrap12(ActivityThread.java)
        at android.app.ActivityThread$H.handleMessage(ActivityThread.java:1460)
07-17 19:20:46.026 6243-6243/fr.free.nrw.commons.debug W/System.err:     at android.os.Handler.dispatchMessage(Handler.java:102)
        at android.os.Looper.loop(Looper.java:154)
07-17 19:20:46.027 6243-6243/fr.free.nrw.commons.debug W/System.err:     at android.app.ActivityThread.main(ActivityThread.java:6077)
        at java.lang.reflect.Method.invoke(Native Method)
        at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
        at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
07-17 19:20:46.034 6243-6243/fr.free.nrw.commons.debug I/TextInputLayout: EditText added is not a TextInputEditText. Please switch to using that class instead.
07-17 19:20:46.035 6243-6243/fr.free.nrw.commons.debug I/TextInputLayout: EditText added is not a TextInputEditText. Please switch to using that class instead.
07-17 19:20:46.040 6243-6243/fr.free.nrw.commons.debug D/SingleUploadFragment: Image title: Android sculptures, image desc: group of sculptures, image categories: Android sculptures

    CC BY-SA 3.0
    Position: 2 Attribution-ShareAlike 3.0
07-17 19:20:46.133 6243-6290/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xb2785600: ver 2 0 (tinfo 0xb2783470)
07-17 19:20:46.449 1661-1682/system_process I/ActivityManager: Displayed fr.free.nrw.commons.debug/fr.free.nrw.commons.upload.ShareActivity: +523ms
07-17 19:20:46.476 1661-2344/system_process I/WindowManager: Destroying surface Surface(name=fr.free.nrw.commons.debug/fr.free.nrw.commons.contributions.ContributionsActivity) called by com.android.server.wm.WindowStateAnimator.destroySurface:2016 com.android.server.wm.WindowStateAnimator.destroySurfaceLocked:882 com.android.server.wm.WindowState.destroyOrSaveSurface:2100 com.android.server.wm.WindowManagerService.tryStartExitingAnimation:3024 com.android.server.wm.WindowManagerService.relayoutWindow:2904 com.android.server.wm.Session.relayout:224 android.view.IWindowSession$Stub.onTransact:286 com.android.server.wm.Session.onTransact:145 
07-17 19:20:46.544 6243-7804/fr.free.nrw.commons.debug D/ExistingFileAsync: File already exists in Commons: false
07-17 19:20:46.562 6243-6243/fr.free.nrw.commons.debug D/ShareActivity: content://com.google.android.apps.photos.contentprovider/0/1/mediakey%3A%2FAF1QipNc23xpG2bHvWg2VpxXBABHdooiSCK3R18vSV4W/ORIGINAL/NONE/1722298755 duplicate check: NO_DUPLICATE
07-17 19:20:46.587 6243-6243/fr.free.nrw.commons.debug D/FileUtils: Filepath (copied): /storage/emulated/0/CommonsApp/1531819246577.jpg
07-17 19:20:46.588 6243-7805/fr.free.nrw.commons.debug D/DetectUnwantedPicturesA: FilePath: /storage/emulated/0/CommonsApp/1531819246577.jpg
07-17 19:20:47.547 6243-7805/fr.free.nrw.commons.debug D/ImageUtils: dark rectangles count = 2, total rectangles count = 25
07-17 19:20:58.602 6243-6290/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xb2785600: ver 2 0 (tinfo 0xb2783470)
07-17 19:20:58.613 6243-6290/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xb2785600: ver 2 0 (tinfo 0xb2783470)
07-17 19:20:58.696 6243-6290/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xb2785600: ver 2 0 (tinfo 0xb2783470)
07-17 19:20:58.704 6243-6290/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xb2785600: ver 2 0 (tinfo 0xb2783470)
07-17 19:21:03.506 6243-6243/fr.free.nrw.commons.debug W/IInputConnectionWrapper: finishComposingText on inactive InputConnection
    finishComposingText on inactive InputConnection
    finishComposingText on inactive InputConnection
07-17 19:21:03.536 6243-6290/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xb2785600: ver 2 0 (tinfo 0xb2783470)
07-17 19:21:03.549 6243-6290/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xb2785600: ver 2 0 (tinfo 0xb2783470)
07-17 19:21:03.660 6243-6290/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xb2785600: ver 2 0 (tinfo 0xb2783470)
07-17 19:21:03.666 6243-6290/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xb2785600: ver 2 0 (tinfo 0xb2783470)
07-17 19:21:06.709 6243-6243/fr.free.nrw.commons.debug D/FileProcessor: Calling GPSExtractor
07-17 19:21:06.718 6243-6243/fr.free.nrw.commons.debug I/ExifInterface_JNI: Raw image not detected
07-17 19:21:06.721 6243-6243/fr.free.nrw.commons.debug D/CacheController: Categories being cached: [Patios in California, Glass facades in the United States, Google, Yorkshire terrier, Googleplex, Mountain View, California]
07-17 19:21:06.721 6243-6243/fr.free.nrw.commons.debug D/ShareActivity: Cache the categories found
07-17 19:21:06.721 6243-6243/fr.free.nrw.commons.debug D/UploadController: Wikidata entity ID received from Share activity is null
07-17 19:21:06.732 6243-7810/fr.free.nrw.commons.debug D/UploadController: MimeType is: image/jpeg
07-17 19:21:06.733 6243-7810/fr.free.nrw.commons.debug D/UploadController: local uri   content://com.google.android.apps.photos.contentprovider/0/1/mediakey%3A%2FAF1QipNc23xpG2bHvWg2VpxXBABHdooiSCK3R18vSV4W/ORIGINAL/NONE/1722298755
07-17 19:21:06.755 6243-6290/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xb2785600: ver 2 0 (tinfo 0xb2783470)
07-17 19:21:06.764 6243-6290/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xb2785600: ver 2 0 (tinfo 0xb2783470)
07-17 19:21:06.765 6243-6243/fr.free.nrw.commons.debug D/UploadService: Upload service queue has contribution with wiki data entity id as null
07-17 19:21:06.787 6243-6299/fr.free.nrw.commons.debug D/UploadService: Before execution!
07-17 19:21:06.799 6243-6299/fr.free.nrw.commons.debug D/UploadService: making sure of uniqueness of name: City circle tram.jpg
07-17 19:21:06.831 6243-6243/fr.free.nrw.commons.debug W/IInputConnectionWrapper: finishComposingText on inactive InputConnection
07-17 19:21:06.833 6243-6243/fr.free.nrw.commons.debug W/IInputConnectionWrapper: finishComposingText on inactive InputConnection
    finishComposingText on inactive InputConnection
07-17 19:21:07.319 6243-6243/fr.free.nrw.commons.debug D/CategorizationFragment: Direct category found: 'Android sculptures    '
    DirectCat does not equal emptyString. Direct Cat list has [Android sculptures    ]
    Image has direct Cat
07-17 19:21:07.344 6243-7812/fr.free.nrw.commons.debug D/CategorizationFragment: Previous year: 2017
07-17 19:21:07.345 6243-7812/fr.free.nrw.commons.debug D/CategorizationFragment: Previous year: 2017
    Previous year: 2017
    Previous year: 2017
07-17 19:21:07.346 6243-7812/fr.free.nrw.commons.debug D/CategorizationFragment: Previous year: 2017
    Previous year: 2017
07-17 19:21:07.347 6243-7812/fr.free.nrw.commons.debug D/CategorizationFragment: Previous year: 2017
07-17 19:21:07.829 6243-6299/fr.free.nrw.commons.debug D/UploadService: Successfully revalidated token!
07-17 19:21:08.389 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 71389 of 2325941
07-17 19:21:08.408 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 145117 of 2325941
07-17 19:21:08.428 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 218845 of 2325941
07-17 19:21:08.449 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 292573 of 2325941
07-17 19:21:08.477 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 366301 of 2325941
07-17 19:21:08.733 6243-7812/fr.free.nrw.commons.debug D/CategorizationFragment: Previous year: 2017
07-17 19:21:08.734 6243-7812/fr.free.nrw.commons.debug D/CategorizationFragment: Previous year: 2017
    Previous year: 2017
07-17 19:21:08.735 6243-7812/fr.free.nrw.commons.debug D/CategorizationFragment: Previous year: 2017
    Previous year: 2017
07-17 19:21:09.154 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 440029 of 2325941
07-17 19:21:09.170 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 513757 of 2325941
07-17 19:21:09.415 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 587485 of 2325941
07-17 19:21:09.433 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 661213 of 2325941
07-17 19:21:09.932 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 734941 of 2325941
07-17 19:21:09.955 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 808669 of 2325941
07-17 19:21:09.979 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 882397 of 2325941
07-17 19:21:10.003 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 956125 of 2325941
07-17 19:21:10.022 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1029853 of 2325941
07-17 19:21:10.892 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1103581 of 2325941
07-17 19:21:12.824 6243-6290/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xb2785600: ver 2 0 (tinfo 0xb2783470)
07-17 19:21:12.844 6243-6290/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xb2785600: ver 2 0 (tinfo 0xb2783470)
07-17 19:21:12.853 6243-6290/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xb2785600: ver 2 0 (tinfo 0xb2783470)
07-17 19:21:12.878 6243-6290/fr.free.nrw.commons.debug D/OpenGLRenderer: endAllActiveAnimators on 0x97c32c80 (RippleDrawable) with handle 0x937943d0
07-17 19:21:13.144 1661-1673/system_process I/WindowManager: Destroying surface Surface(name=fr.free.nrw.commons.debug/fr.free.nrw.commons.upload.ShareActivity) called by com.android.server.wm.WindowStateAnimator.destroySurface:2016 com.android.server.wm.WindowStateAnimator.destroySurfaceLocked:882 com.android.server.wm.WindowState.removeLocked:1456 com.android.server.wm.WindowManagerService.removeWindowInnerLocked:2484 com.android.server.wm.WindowManagerService.removeWindowLocked:2442 com.android.server.wm.WindowManagerService.removeWindowLocked:2311 com.android.server.wm.WindowManagerService.removeWindow:2306 com.android.server.wm.Session.remove:202 
07-17 19:21:13.825 6243-7814/fr.free.nrw.commons.debug D/ModificationsSyncAdapte: Found 2 modifications to execute
07-17 19:21:15.943 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1177309 of 2325941
07-17 19:21:15.960 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1251037 of 2325941
07-17 19:21:17.382 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1324765 of 2325941
07-17 19:21:17.413 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1398493 of 2325941
07-17 19:21:18.149 6243-6271/fr.free.nrw.commons.debug I/art: Starting a blocking GC Explicit
07-17 19:21:18.166 6243-6271/fr.free.nrw.commons.debug I/art: Explicit concurrent mark sweep GC freed 25967(2MB) AllocSpace objects, 26(9MB) LOS objects, 7% free, 48MB/52MB, paused 723us total 15.310ms
07-17 19:21:18.584 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1472221 of 2325941
07-17 19:21:19.544 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1545949 of 2325941
07-17 19:21:19.556 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1619677 of 2325941
07-17 19:21:20.503 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1693405 of 2325941
07-17 19:21:20.520 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1767133 of 2325941
07-17 19:21:21.703 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1840861 of 2325941
07-17 19:21:21.713 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1914589 of 2325941
07-17 19:21:22.664 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1988317 of 2325941
07-17 19:21:23.885 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 2062045 of 2325941
07-17 19:21:23.898 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 2135773 of 2325941
07-17 19:21:24.842 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 2209501 of 2325941
07-17 19:21:24.854 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 2283229 of 2325941
07-17 19:21:26.285 6243-6299/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 2325941 of 2325941
07-17 19:21:34.331 6243-6299/fr.free.nrw.commons.debug E/WTF: Result: org.mediawiki.api.ApiResult@fb93a4d
07-17 19:21:34.333 6243-6299/fr.free.nrw.commons.debug E/ApacheHttpClientMediaWi: mustbeloggedin
07-17 19:21:34.334 6243-6299/fr.free.nrw.commons.debug D/UploadService: Response is fr.free.nrw.commons.mwapi.UploadResult@62f7902
    Contribution upload failed. Wikidata entity won't be edited
07-17 19:21:34.937 6243-7817/fr.free.nrw.commons.debug D/ModificationsSyncAdapte: Found 2 modifications to execute

I guess the two key lines are:

07-17 19:21:34.331 6243-6299/fr.free.nrw.commons.debug E/WTF: Result: org.mediawiki.api.ApiResult@fb93a4d
07-17 19:21:34.333 6243-6299/fr.free.nrw.commons.debug E/ApacheHttpClientMediaWi: mustbeloggedin
07-17 19:21:34.334 6243-6299/fr.free.nrw.commons.debug D/UploadService: Response is fr.free.nrw.commons.mwapi.UploadResult@62f7902

Which unfortunately doesn't appear to be much help. It does point towards the cause being related to user authentication though (despite the fact that a manual login doesn't solve the problem). Perhaps the changes to auth token that we did for 2FA might be the cause, since we only started getting these complaints after that release. @maskaravivek , what do you think?

misaochan commented 6 years ago

I commented out the call to findOtherImages to ensure that the error below is not related to the failure. It isn't - image upload still fails without that method call.

07-17 19:20:46.021 6243-6243/fr.free.nrw.commons.debug W/System.err: java.io.FileNotFoundException: No content provider: /storage/emulated/0/CommonsApp/1531819246012.jpg
        at android.content.ContentResolver.openTypedAssetFileDescriptor(ContentResolver.java:1131)
        at android.content.ContentResolver.openAssetFileDescriptor(ContentResolver.java:982)
        at android.content.ContentResolver.openFileDescriptor(ContentResolver.java:835)
07-17 19:20:46.023 6243-6243/fr.free.nrw.commons.debug W/System.err:     at android.content.ContentResolver.openFileDescriptor(ContentResolver.java:789)
        at fr.free.nrw.commons.upload.FileProcessor.findOtherImages(FileProcessor.java:157)

misaochan commented 6 years ago

And finally, as suggested by @neslihanturan , here are logs for a successful upload from a 2nd emulator (with the same API, settings, etc). We can compare both sets of logs to see the difference.

07-17 19:56:47.370 5433-5433/fr.free.nrw.commons.debug D/ContributionsListFragme: OnActivityResult() parameters: Req code: 1 Result code: -1 Data: Intent { dat=content://com.google.android.apps.photos.contentprovider/0/1/mediakey:/AF1QipPOQhJe6cwLu07cczQa4yfLRh3uMF_nzclK4fb3/ORIGINAL/NONE/647990119 flg=0x1 clip={text/uri-list U:content://com.google.android.apps.photos.contentprovider/0/1/mediakey%3A%2FAF1QipPOQhJe6cwLu07cczQa4yfLRh3uMF_nzclK4fb3/ORIGINAL/NONE/647990119} }
07-17 19:56:47.371 5433-5433/fr.free.nrw.commons.debug D/ContributionController: Is direct upload false and the Wikidata entity ID is null
    handleImagePicked() called with onActivityResult()
07-17 19:56:47.392 5433-5433/fr.free.nrw.commons.debug I/ContributionController: Image selected
07-17 19:56:47.393 2291-3501/system_process I/ActivityManager: START u0 {act=android.intent.action.SEND typ=image/jpeg flg=0x1 cmp=fr.free.nrw.commons.debug/fr.free.nrw.commons.upload.ShareActivity clip={image/jpeg U:content://com.google.android.apps.photos.contentprovider/0/1/mediakey%3A%2FAF1QipPOQhJe6cwLu07cczQa4yfLRh3uMF_nzclK4fb3/ORIGINAL/NONE/647990119} (has extras)} from uid 10080 on display 0
07-17 19:56:47.516 5433-5433/fr.free.nrw.commons.debug I/AppCompatViewInflater: app:theme is now deprecated. Please move to using android:theme instead.
07-17 19:56:47.563 5433-5433/fr.free.nrw.commons.debug D/ShareActivity: This was initiated by a direct upload from Nearby
07-17 19:56:47.566 5433-5433/fr.free.nrw.commons.debug D/ShareActivity: Received wikiDataEntityId from contribution controller null
07-17 19:56:47.571 5433-5433/fr.free.nrw.commons.debug D/ShareActivity: Uri: content://com.google.android.apps.photos.contentprovider/0/1/mediakey%3A%2FAF1QipPOQhJe6cwLu07cczQa4yfLRh3uMF_nzclK4fb3/ORIGINAL/NONE/647990119
07-17 19:56:47.573 5433-5433/fr.free.nrw.commons.debug D/ShareActivity: Ext storage dir: /storage/emulated/0
07-17 19:56:47.606 5433-5433/fr.free.nrw.commons.debug I/FileUtils: File SHA1: 765aba1913b63d92a53e729e95edb8e649372d69
07-17 19:56:47.609 5433-5433/fr.free.nrw.commons.debug D/ShareActivity: Input stream created from content://com.google.android.apps.photos.contentprovider/0/1/mediakey%3A%2FAF1QipPOQhJe6cwLu07cczQa4yfLRh3uMF_nzclK4fb3/ORIGINAL/NONE/647990119
07-17 19:56:47.610 5433-5433/fr.free.nrw.commons.debug D/ShareActivity: File SHA1 is: 765aba1913b63d92a53e729e95edb8e649372d69
07-17 19:56:47.621 5433-5433/fr.free.nrw.commons.debug D/FileProcessor: Calling GPSExtractor
07-17 19:56:47.642 5433-5433/fr.free.nrw.commons.debug I/ExifInterface_JNI: Raw image not detected
07-17 19:56:47.720 5433-5433/fr.free.nrw.commons.debug D/FileUtils: Filepath (copied): /storage/emulated/0/CommonsApp/1531821407676.jpg
07-17 19:56:47.721 5433-5433/fr.free.nrw.commons.debug D/FileProcessor: Filepath: /storage/emulated/0/CommonsApp/1531821407676.jpg
    filePath/storage/emulated/0/CommonsApp/1531821407676.jpg
    folderTime Number:1
07-17 19:56:47.722 5433-5433/fr.free.nrw.commons.debug D/FileProcessor: fild date:1531821407000 time of creation1531821407000
07-17 19:56:47.723 5433-5433/fr.free.nrw.commons.debug W/System.err: java.io.FileNotFoundException: No content provider: /storage/emulated/0/CommonsApp/1531821407676.jpg
        at android.content.ContentResolver.openTypedAssetFileDescriptor(ContentResolver.java:1131)
        at android.content.ContentResolver.openAssetFileDescriptor(ContentResolver.java:982)
        at android.content.ContentResolver.openFileDescriptor(ContentResolver.java:835)
        at android.content.ContentResolver.openFileDescriptor(ContentResolver.java:789)
        at fr.free.nrw.commons.upload.FileProcessor.findOtherImages(FileProcessor.java:157)
        at fr.free.nrw.commons.upload.FileProcessor.processFileCoordinates(FileProcessor.java:122)
        at fr.free.nrw.commons.upload.ShareActivity.onCreate(ShareActivity.java:288)
        at android.app.Activity.performCreate(Activity.java:6662)
        at android.app.Instrumentation.callActivityOnCreate(Instrumentation.java:1118)
        at android.app.ActivityThread.performLaunchActivity(ActivityThread.java:2599)
        at android.app.ActivityThread.handleLaunchActivity(ActivityThread.java:2707)
        at android.app.ActivityThread.-wrap12(ActivityThread.java)
        at android.app.ActivityThread$H.handleMessage(ActivityThread.java:1460)
        at android.os.Handler.dispatchMessage(Handler.java:102)
        at android.os.Looper.loop(Looper.java:154)
        at android.app.ActivityThread.main(ActivityThread.java:6077)
        at java.lang.reflect.Method.invoke(Native Method)
        at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
        at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
07-17 19:56:47.741 5433-5433/fr.free.nrw.commons.debug I/TextInputLayout: EditText added is not a TextInputEditText. Please switch to using that class instead.
07-17 19:56:47.742 5433-5433/fr.free.nrw.commons.debug I/TextInputLayout: EditText added is not a TextInputEditText. Please switch to using that class instead.
07-17 19:56:47.750 5433-5433/fr.free.nrw.commons.debug D/SingleUploadFragment: Image title: , image desc: , image categories: 
07-17 19:56:47.752 5433-5433/fr.free.nrw.commons.debug D/SingleUploadFragment: CC BY-SA 3.0
07-17 19:56:47.753 5433-5433/fr.free.nrw.commons.debug D/SingleUploadFragment: Position: 2 Attribution-ShareAlike 3.0
07-17 19:56:47.759 5433-5458/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0x8f4a0020: ver 2 0 (tinfo 0x8f496a70)
07-17 19:56:47.910 5433-5458/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0x8f4a0020: ver 2 0 (tinfo 0x8f496a70)
07-17 19:56:47.915 5433-5438/fr.free.nrw.commons.debug I/art: Do full code cache collection, code=118KB, data=113KB
07-17 19:56:47.917 5433-5438/fr.free.nrw.commons.debug I/art: After code cache collection, code=100KB, data=82KB
07-17 19:56:48.043 2291-2346/system_process I/ActivityManager: Displayed fr.free.nrw.commons.debug/fr.free.nrw.commons.upload.ShareActivity: +621ms
07-17 19:56:48.066 2291-3549/system_process I/WindowManager: Destroying surface Surface(name=fr.free.nrw.commons.debug/fr.free.nrw.commons.contributions.ContributionsActivity) called by com.android.server.wm.WindowStateAnimator.destroySurface:2016 com.android.server.wm.WindowStateAnimator.destroySurfaceLocked:882 com.android.server.wm.WindowState.destroyOrSaveSurface:2100 com.android.server.wm.WindowManagerService.tryStartExitingAnimation:3024 com.android.server.wm.WindowManagerService.relayoutWindow:2904 com.android.server.wm.Session.relayout:224 android.view.IWindowSession$Stub.onTransact:286 com.android.server.wm.Session.onTransact:145 
07-17 19:56:48.085 5433-5444/fr.free.nrw.commons.debug I/art: Background partial concurrent mark sweep GC freed 3327(167KB) AllocSpace objects, 2(40KB) LOS objects, 18% free, 17MB/21MB, paused 5.851ms total 84.779ms
07-17 19:56:49.364 5433-7382/fr.free.nrw.commons.debug D/ExistingFileAsync: File already exists in Commons: false
07-17 19:56:49.365 5433-5433/fr.free.nrw.commons.debug D/ShareActivity: content://com.google.android.apps.photos.contentprovider/0/1/mediakey%3A%2FAF1QipPOQhJe6cwLu07cczQa4yfLRh3uMF_nzclK4fb3/ORIGINAL/NONE/647990119 duplicate check: NO_DUPLICATE
07-17 19:56:49.392 5433-5433/fr.free.nrw.commons.debug D/FileUtils: Filepath (copied): /storage/emulated/0/CommonsApp/1531821409384.jpg
07-17 19:56:49.394 5433-7391/fr.free.nrw.commons.debug D/DetectUnwantedPicturesA: FilePath: /storage/emulated/0/CommonsApp/1531821409384.jpg
07-17 19:56:49.643 5433-5438/fr.free.nrw.commons.debug I/art: Do partial code cache collection, code=117KB, data=96KB
07-17 19:56:49.645 5433-5438/fr.free.nrw.commons.debug I/art: After code cache collection, code=116KB, data=95KB
    Increasing code cache capacity to 512KB
07-17 19:56:50.648 5433-7391/fr.free.nrw.commons.debug D/ImageUtils: dark rectangles count = 3, total rectangles count = 25
07-17 19:57:05.500 5433-5433/fr.free.nrw.commons.debug D/FileProcessor: Calling GPSExtractor
07-17 19:57:05.515 5433-5433/fr.free.nrw.commons.debug I/ExifInterface_JNI: Raw image not detected
07-17 19:57:05.520 5433-5433/fr.free.nrw.commons.debug D/CacheController: No categories found, so no categories cached
07-17 19:57:05.520 5433-5433/fr.free.nrw.commons.debug D/ShareActivity: Cache the categories found
07-17 19:57:05.521 5433-5433/fr.free.nrw.commons.debug D/UploadController: Wikidata entity ID received from Share activity is null
07-17 19:57:05.540 5433-7397/fr.free.nrw.commons.debug D/UploadController: MimeType is: image/jpeg
    local uri   content://com.google.android.apps.photos.contentprovider/0/1/mediakey%3A%2FAF1QipPOQhJe6cwLu07cczQa4yfLRh3uMF_nzclK4fb3/ORIGINAL/NONE/647990119
07-17 19:57:05.571 5433-5458/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0x8f4a0020: ver 2 0 (tinfo 0x8f496a70)
07-17 19:57:05.587 5433-5458/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0x8f4a0020: ver 2 0 (tinfo 0x8f496a70)
07-17 19:57:05.591 5433-5433/fr.free.nrw.commons.debug D/UploadService: Upload service queue has contribution with wiki data entity id as null
07-17 19:57:05.621 5433-5582/fr.free.nrw.commons.debug D/UploadService: Before execution!
07-17 19:57:05.675 5433-5582/fr.free.nrw.commons.debug D/UploadService: making sure of uniqueness of name: deGraves street melbourne.jpg
07-17 19:57:05.740 5433-5433/fr.free.nrw.commons.debug W/IInputConnectionWrapper: finishComposingText on inactive InputConnection
07-17 19:57:05.741 5433-5433/fr.free.nrw.commons.debug W/IInputConnectionWrapper: finishComposingText on inactive InputConnection
07-17 19:57:05.746 5433-5433/fr.free.nrw.commons.debug W/IInputConnectionWrapper: finishComposingText on inactive InputConnection
07-17 19:57:06.198 5433-5433/fr.free.nrw.commons.debug D/CategorizationFragment: Direct category found: ''
07-17 19:57:06.199 5433-5433/fr.free.nrw.commons.debug D/CategorizationFragment: Image has no direct Cat
07-17 19:57:07.932 5433-5582/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 67359 of 2150904
07-17 19:57:07.975 5433-5582/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 132895 of 2150904
07-17 19:57:08.008 5433-5582/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 198431 of 2150904
07-17 19:57:08.039 5433-5582/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 263967 of 2150904
07-17 19:57:08.068 5433-5582/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 329503 of 2150904
07-17 19:57:08.110 5433-5582/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 395039 of 2150904
07-17 19:57:08.591 5433-7401/fr.free.nrw.commons.debug D/CategorizationFragment: Previous year: 2017
    Previous year: 2017
07-17 19:57:08.732 5433-5582/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 460575 of 2150904
07-17 19:57:08.763 5433-5582/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 526111 of 2150904
07-17 19:57:09.033 5433-5582/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 591647 of 2150904
07-17 19:57:09.080 5433-5582/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 657183 of 2150904
07-17 19:57:09.108 5433-5582/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 722719 of 2150904
07-17 19:57:09.649 5433-5582/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 788255 of 2150904
07-17 19:57:09.676 5433-5582/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 853791 of 2150904
07-17 19:57:09.700 5433-5582/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 919327 of 2150904
07-17 19:57:09.730 5433-5582/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 984863 of 2150904
07-17 19:57:09.749 5433-5582/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1050399 of 2150904
07-17 19:57:10.446 5433-5458/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0x8f4a0020: ver 2 0 (tinfo 0x8f496a70)
07-17 19:57:10.465 5433-5458/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0x8f4a0020: ver 2 0 (tinfo 0x8f496a70)
07-17 19:57:10.505 5433-5458/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0x8f4a0020: ver 2 0 (tinfo 0x8f496a70)
07-17 19:57:10.546 5433-5458/fr.free.nrw.commons.debug D/OpenGLRenderer: endAllActiveAnimators on 0x8c86e880 (RippleDrawable) with handle 0x8e637b60
07-17 19:57:10.825 2291-3494/system_process I/WindowManager: Destroying surface Surface(name=fr.free.nrw.commons.debug/fr.free.nrw.commons.upload.ShareActivity) called by com.android.server.wm.WindowStateAnimator.destroySurface:2016 com.android.server.wm.WindowStateAnimator.destroySurfaceLocked:882 com.android.server.wm.WindowState.removeLocked:1456 com.android.server.wm.WindowManagerService.removeWindowInnerLocked:2484 com.android.server.wm.WindowManagerService.removeWindowLocked:2442 com.android.server.wm.WindowManagerService.removeWindowLocked:2311 com.android.server.wm.WindowManagerService.removeWindow:2306 com.android.server.wm.Session.remove:202 
07-17 19:57:12.703 5433-7403/fr.free.nrw.commons.debug D/ModificationsSyncAdapte: Found 1 modifications to execute
07-17 19:57:14.272 5433-5582/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1115935 of 2150904
07-17 19:57:14.294 5433-5582/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1181471 of 2150904
07-17 19:57:14.323 5433-5582/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1247007 of 2150904
07-17 19:57:14.353 5433-5582/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1312543 of 2150904
07-17 19:57:14.790 5433-5582/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1378079 of 2150904
07-17 19:57:14.827 5433-5582/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1443615 of 2150904
07-17 19:57:15.192 5433-5444/fr.free.nrw.commons.debug I/art: Background partial concurrent mark sweep GC freed 30506(2MB) AllocSpace objects, 26(7MB) LOS objects, 18% free, 18MB/22MB, paused 5.397ms total 48.144ms
07-17 19:57:15.564 5433-5582/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1509151 of 2150904
07-17 19:57:15.585 5433-5582/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1574687 of 2150904
07-17 19:57:17.776 5433-5582/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1640223 of 2150904
07-17 19:57:17.794 5433-5582/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1705759 of 2150904
07-17 19:57:21.410 5433-5582/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1771295 of 2150904
07-17 19:57:21.431 5433-5582/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1836831 of 2150904
07-17 19:57:22.876 5433-5582/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1902367 of 2150904
07-17 19:57:22.903 5433-5582/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1967903 of 2150904
07-17 19:57:23.870 5433-5582/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 2033439 of 2150904
07-17 19:57:23.890 5433-5582/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 2098975 of 2150904
07-17 19:57:24.880 5433-5582/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 2150904 of 2150904
07-17 19:57:38.690 5433-5582/fr.free.nrw.commons.debug E/WTF: Result: org.mediawiki.api.ApiResult@5fcaf6
07-17 19:57:38.708 5433-5582/fr.free.nrw.commons.debug D/UploadService: Response is fr.free.nrw.commons.mwapi.UploadResult@8e48ef7
07-17 19:57:38.709 5433-5582/fr.free.nrw.commons.debug D/UploadService: Contribution upload success. Initiating Wikidata edit for entity id null
07-17 19:57:38.709 5433-5582/fr.free.nrw.commons.debug D/WikidataEditService: Skipping creation of claim as Wikidata entity ID is null
07-17 19:57:39.350 5433-7407/fr.free.nrw.commons.debug D/ModificationsSyncAdapte: Found 1 modifications to execute
07-17 19:57:39.923 5433-7407/fr.free.nrw.commons.debug D/ModificationsSyncAdapte: Page content is == {{int:filedesc}} ==
    {{Information
    |description=
    |source={{own}}
    |author=[[User:Misaochan2|Misaochan2]]
    |date={{According to EXIF data|2018-07-15}}
    }}
    == {{int:license-header}} ==
    {{self|cc-by-sa-3.0}}

    {{Uploaded from Mobile|platform=Android|version=2.7.2-debug-2.8-release-fork~1d851fc0}}
    {{Uncategorized|year=2018|month=July|day=17}}
07-17 19:57:40.958 5433-7407/fr.free.nrw.commons.debug D/ModificationsSyncAdapte: Response is Success

nicolas-raoul commented 6 years ago

In order to help investigation on this issue, I created a special version of the app that outputs all HTTP requests and responses to logcat: https://github.com/nicolas-raoul/apps-android-commons/commit/65afb0d74d07f1c2fb2b61720eff46c7c7e360f7

As you can see it is a simple build.gradle change so feel free to try it by simply modifying your file. If you need to see more HTTP details please create an issue or send a pull request to https://github.com/nicolas-raoul/java-http-fluent thanks!

This could prove useful in the future too, as it allows to quickly find out whether the app or the server is at fault, and check for request problems / unexpected responses. If outputting all of this information has too big of an impact on performance, we could set Gradle to only use that version of httpfluent when generating debug versions.

misaochan commented 6 years ago

Logs with @nicolas-raoul 's modification below. I (and @maskaravivek ) think it would actually be nice to have this output in the debug build variant of our app, although I would probably prefer to have it in verbose so we can filter it (instead of the current debug log level).

07-19 02:42:04.851 6251-6362/fr.free.nrw.commons.debug D/ContributionsSyncAdapte: Last modified at 2018-07-16T11:44:58Z
    0 results!
07-19 02:42:04.855 6251-6383/fr.free.nrw.commons.debug I/System.out: HTTP Request: GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=multiple+test+-+2&iiprop=url&action=query&format=xml&format=xml
07-19 02:42:04.856 6251-6362/fr.free.nrw.commons.debug D/ContributionsSyncAdapte: Oh hai, everyone! Look, a kitty!
07-19 02:42:04.982 6251-6339/fr.free.nrw.commons.debug I/System.out: HTTP Response: 200 Server=nginx/1.13.6 Date=Wed, 18 Jul 2018 16:42:05 GMT Content-Type=text/html Transfer-Encoding=chunked Connection=keep-alive Access-Control-Allow-Origin=* X-Clacks-Overhead=GNU Terry Pratchett Content-Security-Policy-Report-Only=default-src 'self' 'unsafe-eval' 'unsafe-inline' blob: data: filesystem: mediastream: *.wikibooks.org *.wikidata.org wikimedia.org *.wikimedia.org *.wikinews.org *.wikipedia.org *.wikiquote.org *.wikisource.org *.wikiversity.org *.wikivoyage.org *.wiktionary.org *.wmflabs.org wikimediafoundation.org *.mediawiki.org wss://tools.wmflabs.org; report-uri https://tools.wmflabs.org/csp-report/collect;
07-19 02:42:05.329 6251-6383/fr.free.nrw.commons.debug I/System.out: HTTP Response: 200 Date=Wed, 18 Jul 2018 16:42:05 GMT Content-Type=text/xml; charset=utf-8 Content-Length=219 Connection=keep-alive Server=mw1315.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=40306 t=1531932125409110 X-Varnish=626277445, 844345369, 939692821, 864900144 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1054 pass, cp2013 pass, cp5011 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=18-Jul-2018;https=1 X-Client-IP=censored
07-19 02:42:05.332 6251-6383/fr.free.nrw.commons.debug I/System.out: HTTP Request: GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=multiple+test+-+3&iiprop=url&action=query&format=xml&format=xml
07-19 02:42:05.805 6251-6383/fr.free.nrw.commons.debug I/System.out: HTTP Response: 200 Date=Wed, 18 Jul 2018 16:42:06 GMT Content-Type=text/xml; charset=utf-8 Content-Length=219 Connection=keep-alive Server=mw1287.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=28761 t=1531932125884936 X-Varnish=1052853590, 225816206, 495158387, 861534779 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1052 pass, cp2023 pass, cp5009 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=18-Jul-2018;https=1 X-Client-IP=censored
07-19 02:42:08.814 6251-6286/fr.free.nrw.commons.debug I/art: Starting a blocking GC Explicit
07-19 02:42:08.826 6251-6286/fr.free.nrw.commons.debug I/art: Explicit concurrent mark sweep GC freed 6224(426KB) AllocSpace objects, 3(60KB) LOS objects, 39% free, 4MB/7MB, paused 719us total 11.171ms
07-19 02:43:01.405 6251-6256/fr.free.nrw.commons.debug I/art: Do partial code cache collection, code=27KB, data=30KB
07-19 02:43:01.406 6251-6256/fr.free.nrw.commons.debug I/art: After code cache collection, code=19KB, data=27KB
    Increasing code cache capacity to 128KB
07-19 02:43:03.023 1661-2122/system_process I/ActivityManager: START u0 {flg=0x20000 cmp=fr.free.nrw.commons.debug/fr.free.nrw.commons.AboutActivity} from uid 10084 on display 0
07-19 02:43:03.062 6251-6251/fr.free.nrw.commons.debug I/AppCompatViewInflater: app:theme is now deprecated. Please move to using android:theme instead.
07-19 02:43:03.137 6251-6350/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xa3e8aa80: ver 2 0 (tinfo 0xa3ea7280)
07-19 02:43:03.155 1661-1682/system_process I/ActivityManager: Displayed fr.free.nrw.commons.debug/fr.free.nrw.commons.AboutActivity: +104ms
07-19 02:43:03.161 6251-6350/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xa3e8aa80: ver 2 0 (tinfo 0xa3ea7280)
07-19 02:43:03.167 6251-6350/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xa3e8aa80: ver 2 0 (tinfo 0xa3ea7280)
07-19 02:43:03.540 1661-2770/system_process I/WindowManager: Destroying surface Surface(name=fr.free.nrw.commons.debug/fr.free.nrw.commons.contributions.ContributionsActivity) called by com.android.server.wm.WindowStateAnimator.destroySurface:2016 com.android.server.wm.WindowStateAnimator.destroySurfaceLocked:882 com.android.server.wm.WindowState.destroyOrSaveSurface:2100 com.android.server.wm.WindowManagerService.tryStartExitingAnimation:3024 com.android.server.wm.WindowManagerService.relayoutWindow:2904 com.android.server.wm.Session.relayout:224 android.view.IWindowSession$Stub.onTransact:286 com.android.server.wm.Session.onTransact:145 
07-19 02:43:05.873 6251-6256/fr.free.nrw.commons.debug I/art: Do partial code cache collection, code=57KB, data=61KB
    After code cache collection, code=47KB, data=57KB
    Increasing code cache capacity to 256KB
07-19 02:43:06.621 1661-1672/system_process I/ActivityManager: START u0 {flg=0x24000000 cmp=fr.free.nrw.commons.debug/fr.free.nrw.commons.contributions.ContributionsActivity} from uid 10084 on display 0
07-19 02:43:06.721 6251-6350/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xa3e8aa80: ver 2 0 (tinfo 0xa3ea7280)
07-19 02:43:06.746 6251-6350/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xa3e8aa80: ver 2 0 (tinfo 0xa3ea7280)
07-19 02:43:06.750 6251-6350/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xa3e8aa80: ver 2 0 (tinfo 0xa3ea7280)
07-19 02:43:07.073 1661-2349/system_process I/WindowManager: Destroying surface Surface(name=fr.free.nrw.commons.debug/fr.free.nrw.commons.AboutActivity) called by com.android.server.wm.WindowStateAnimator.destroySurface:2016 com.android.server.wm.WindowStateAnimator.destroySurfaceLocked:882 com.android.server.wm.WindowState.removeLocked:1456 com.android.server.wm.WindowManagerService.removeWindowInnerLocked:2484 com.android.server.wm.WindowManagerService.removeWindowLocked:2442 com.android.server.wm.WindowManagerService.removeWindowLocked:2311 com.android.server.wm.WindowManagerService.removeWindow:2306 com.android.server.wm.Session.remove:202 
07-19 02:43:07.637 6251-6251/fr.free.nrw.commons.debug D/ContributionController: startGalleryPick() called with pickImageIntent
07-19 02:43:07.727 6251-6350/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xa3e8aa80: ver 2 0 (tinfo 0xa3ea7280)
07-19 02:43:07.729 6251-6350/fr.free.nrw.commons.debug D/OpenGLRenderer: endAllActiveAnimators on 0x96952800 (RippleDrawable) with handle 0x95dd5850
07-19 02:43:07.742 6418-6434/com.android.documentsui W/DocumentsActivity: Failed to restore stack for package: fr.free.nrw.commons.debug because of error: java.io.FileNotFoundException: Failed to find matching root for null
07-19 02:43:08.102 1661-1673/system_process I/WindowManager: Destroying surface Surface(name=fr.free.nrw.commons.debug/fr.free.nrw.commons.contributions.ContributionsActivity) called by com.android.server.wm.WindowStateAnimator.destroySurface:2016 com.android.server.wm.WindowStateAnimator.destroySurfaceLocked:882 com.android.server.wm.WindowState.destroyOrSaveSurface:2100 com.android.server.wm.WindowManagerService.tryStartExitingAnimation:3024 com.android.server.wm.WindowManagerService.relayoutWindow:2904 com.android.server.wm.Session.relayout:224 android.view.IWindowSession$Stub.onTransact:286 com.android.server.wm.Session.onTransact:145 
07-19 02:43:12.082 6251-6286/fr.free.nrw.commons.debug I/art: Starting a blocking GC Explicit
07-19 02:43:12.098 6251-6286/fr.free.nrw.commons.debug I/art: Explicit concurrent mark sweep GC freed 7519(392KB) AllocSpace objects, 8(160KB) LOS objects, 24% free, 4MB/5MB, paused 1.113ms total 14.459ms
07-19 02:43:22.272 6251-6251/fr.free.nrw.commons.debug D/ContributionsListFragme: OnActivityResult() parameters: Req code: 1 Result code: -1 Data: Intent { dat=content://com.google.android.apps.photos.contentprovider/0/1/mediakey:/AF1QipPrDnls_djkbY3AYJBqZ_DF1A4DPipq9_gWPMUs/ORIGINAL/NONE/1707416755 flg=0x1 clip={text/uri-list U:content://com.google.android.apps.photos.contentprovider/0/1/mediakey%3A%2FAF1QipPrDnls_djkbY3AYJBqZ_DF1A4DPipq9_gWPMUs/ORIGINAL/NONE/1707416755} }
07-19 02:43:22.272 6251-6251/fr.free.nrw.commons.debug D/ContributionController: Is direct upload false and the Wikidata entity ID is null
    handleImagePicked() called with onActivityResult()
07-19 02:43:22.277 6251-6251/fr.free.nrw.commons.debug I/ContributionController: Image selected
07-19 02:43:22.278 1661-2349/system_process I/ActivityManager: START u0 {act=android.intent.action.SEND typ=image/jpeg flg=0x1 cmp=fr.free.nrw.commons.debug/fr.free.nrw.commons.upload.ShareActivity clip={image/jpeg U:content://com.google.android.apps.photos.contentprovider/0/1/mediakey%3A%2FAF1QipPrDnls_djkbY3AYJBqZ_DF1A4DPipq9_gWPMUs/ORIGINAL/NONE/1707416755} (has extras)} from uid 10084 on display 0
07-19 02:43:22.315 6251-6251/fr.free.nrw.commons.debug I/AppCompatViewInflater: app:theme is now deprecated. Please move to using android:theme instead.
07-19 02:43:22.332 6251-6251/fr.free.nrw.commons.debug D/ShareActivity: This was initiated by a direct upload from Nearby
    Received wikiDataEntityId from contribution controller null
07-19 02:43:22.336 6251-6251/fr.free.nrw.commons.debug D/ShareActivity: Uri: content://com.google.android.apps.photos.contentprovider/0/1/mediakey%3A%2FAF1QipPrDnls_djkbY3AYJBqZ_DF1A4DPipq9_gWPMUs/ORIGINAL/NONE/1707416755
07-19 02:43:22.337 6251-6251/fr.free.nrw.commons.debug D/ShareActivity: Ext storage dir: /storage/emulated/0
07-19 02:43:22.352 6251-6251/fr.free.nrw.commons.debug I/FileUtils: File SHA1: c2d6d1262f7efe08a8eb97780795b81e3c9d57a6
07-19 02:43:22.354 6251-6251/fr.free.nrw.commons.debug D/ShareActivity: Input stream created from content://com.google.android.apps.photos.contentprovider/0/1/mediakey%3A%2FAF1QipPrDnls_djkbY3AYJBqZ_DF1A4DPipq9_gWPMUs/ORIGINAL/NONE/1707416755
    File SHA1 is: c2d6d1262f7efe08a8eb97780795b81e3c9d57a6
07-19 02:43:22.355 6251-6251/fr.free.nrw.commons.debug D/FileProcessor: Calling GPSExtractor
07-19 02:43:22.356 6251-6462/fr.free.nrw.commons.debug I/System.out: HTTP Request: GET https://commons.wikimedia.org/w/api.php?aisha1=c2d6d1262f7efe08a8eb97780795b81e3c9d57a6&list=allimages&action=query&format=xml&format=xml
07-19 02:43:22.361 6251-6251/fr.free.nrw.commons.debug I/ExifInterface_JNI: Raw image not detected
07-19 02:43:22.391 6251-6251/fr.free.nrw.commons.debug D/FileUtils: Filepath (copied): /storage/emulated/0/CommonsApp/1531932202373.jpg
07-19 02:43:22.392 6251-6251/fr.free.nrw.commons.debug D/FileProcessor: Filepath: /storage/emulated/0/CommonsApp/1531932202373.jpg
    filePath/storage/emulated/0/CommonsApp/1531932202373.jpg
    folderTime Number:1
    fild date:1531932202000 time of creation1531932202000
07-19 02:43:22.392 6251-6251/fr.free.nrw.commons.debug W/System.err: java.io.FileNotFoundException: No content provider: /storage/emulated/0/CommonsApp/1531932202373.jpg
07-19 02:43:22.393 6251-6251/fr.free.nrw.commons.debug W/System.err:     at android.content.ContentResolver.openTypedAssetFileDescriptor(ContentResolver.java:1131)
        at android.content.ContentResolver.openAssetFileDescriptor(ContentResolver.java:982)
        at android.content.ContentResolver.openFileDescriptor(ContentResolver.java:835)
        at android.content.ContentResolver.openFileDescriptor(ContentResolver.java:789)
        at fr.free.nrw.commons.upload.FileProcessor.findOtherImages(FileProcessor.java:157)
        at fr.free.nrw.commons.upload.FileProcessor.processFileCoordinates(FileProcessor.java:122)
        at fr.free.nrw.commons.upload.ShareActivity.onCreate(ShareActivity.java:288)
        at android.app.Activity.performCreate(Activity.java:6662)
        at android.app.Instrumentation.callActivityOnCreate(Instrumentation.java:1118)
        at android.app.ActivityThread.performLaunchActivity(ActivityThread.java:2599)
        at android.app.ActivityThread.handleLaunchActivity(ActivityThread.java:2707)
        at android.app.ActivityThread.-wrap12(ActivityThread.java)
        at android.app.ActivityThread$H.handleMessage(ActivityThread.java:1460)
        at android.os.Handler.dispatchMessage(Handler.java:102)
        at android.os.Looper.loop(Looper.java:154)
        at android.app.ActivityThread.main(ActivityThread.java:6077)
        at java.lang.reflect.Method.invoke(Native Method)
        at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
        at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
07-19 02:43:22.400 6251-6251/fr.free.nrw.commons.debug I/TextInputLayout: EditText added is not a TextInputEditText. Please switch to using that class instead.
    EditText added is not a TextInputEditText. Please switch to using that class instead.
07-19 02:43:22.407 6251-6251/fr.free.nrw.commons.debug D/SingleUploadFragment: Image title: Android sculptures, image desc: group of sculptures, image categories: Android sculptures

    CC BY 4.0
07-19 02:43:22.408 6251-6251/fr.free.nrw.commons.debug D/SingleUploadFragment: Position: 3 Attribution 4.0
07-19 02:43:22.415 6251-6350/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xa3e8aa80: ver 2 0 (tinfo 0xa3ea7280)
07-19 02:43:22.532 6251-6350/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xa3e8aa80: ver 2 0 (tinfo 0xa3ea7280)
07-19 02:43:22.604 6251-6350/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xa3e8aa80: ver 2 0 (tinfo 0xa3ea7280)
07-19 02:43:22.606 1661-1682/system_process I/ActivityManager: Displayed fr.free.nrw.commons.debug/fr.free.nrw.commons.upload.ShareActivity: +323ms
07-19 02:43:22.621 6251-6350/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xa3e8aa80: ver 2 0 (tinfo 0xa3ea7280)
07-19 02:43:22.649 1661-2308/system_process I/WindowManager: Destroying surface Surface(name=fr.free.nrw.commons.debug/fr.free.nrw.commons.contributions.ContributionsActivity) called by com.android.server.wm.WindowStateAnimator.destroySurface:2016 com.android.server.wm.WindowStateAnimator.destroySurfaceLocked:882 com.android.server.wm.WindowState.destroyOrSaveSurface:2100 com.android.server.wm.WindowManagerService.tryStartExitingAnimation:3024 com.android.server.wm.WindowManagerService.relayoutWindow:2904 com.android.server.wm.Session.relayout:224 android.view.IWindowSession$Stub.onTransact:286 com.android.server.wm.Session.onTransact:145 
07-19 02:43:23.263 6251-6462/fr.free.nrw.commons.debug I/System.out: HTTP Response: 200 Date=Wed, 18 Jul 2018 16:43:23 GMT Content-Type=text/xml; charset=utf-8 Content-Length=77 Connection=keep-alive Server=mw1339.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Set-Cookie=commonswikiSession=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; secure; httponly Set-Cookie=commonswikiUserID=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; secure; httponly Set-Cookie=centralauth_User=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; domain=commons.wikimedia.org; secure; httponly Set-Cookie=centralauth_Token=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; domain=commons.wikimedia.org; secure; httponly Set-Cookie=centralauth_Session=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; domain=commons.wikimedia.org; secure; httponly Backend-Timing=D=31230 t=1531932203354748 X-Varnish=442277983, 842029060, 1067981205, 867639818 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1066 pass, cp2013 pass, cp5010 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload Set-Cookie=WMF-Last-Access=18-Jul-2018;Path=/;HttpOnly;secure;Expires=Sun, 19 Aug 2018 12:00:00 GMT X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=16-Jul-2018;https=1 X-Client-IP=censored
07-19 02:43:23.265 6251-6462/fr.free.nrw.commons.debug D/ExistingFileAsync: File already exists in Commons: false
07-19 02:43:23.266 6251-6251/fr.free.nrw.commons.debug D/ShareActivity: content://com.google.android.apps.photos.contentprovider/0/1/mediakey%3A%2FAF1QipPrDnls_djkbY3AYJBqZ_DF1A4DPipq9_gWPMUs/ORIGINAL/NONE/1707416755 duplicate check: NO_DUPLICATE
07-19 02:43:23.414 6251-6251/fr.free.nrw.commons.debug D/FileUtils: Filepath (copied): /storage/emulated/0/CommonsApp/1531932203277.jpg
07-19 02:43:23.414 6251-6466/fr.free.nrw.commons.debug D/DetectUnwantedPicturesA: FilePath: /storage/emulated/0/CommonsApp/1531932203277.jpg
07-19 02:43:23.523 6251-6350/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xa3e8aa80: ver 2 0 (tinfo 0xa3ea7280)
07-19 02:43:23.531 6251-6350/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xa3e8aa80: ver 2 0 (tinfo 0xa3ea7280)
07-19 02:43:23.586 6251-6350/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xa3e8aa80: ver 2 0 (tinfo 0xa3ea7280)
07-19 02:43:23.593 6251-6350/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xa3e8aa80: ver 2 0 (tinfo 0xa3ea7280)
07-19 02:43:24.221 6251-6466/fr.free.nrw.commons.debug D/ImageUtils: dark rectangles count = 8, total rectangles count = 25
07-19 02:43:25.805 6251-6256/fr.free.nrw.commons.debug I/art: Do full code cache collection, code=124KB, data=116KB
    After code cache collection, code=115KB, data=93KB
07-19 02:43:29.675 6251-6251/fr.free.nrw.commons.debug W/IInputConnectionWrapper: finishComposingText on inactive InputConnection
    finishComposingText on inactive InputConnection
07-19 02:43:29.676 6251-6251/fr.free.nrw.commons.debug W/IInputConnectionWrapper: finishComposingText on inactive InputConnection
07-19 02:43:29.723 6251-6350/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xa3e8aa80: ver 2 0 (tinfo 0xa3ea7280)
07-19 02:43:29.741 6251-6350/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xa3e8aa80: ver 2 0 (tinfo 0xa3ea7280)
07-19 02:43:31.288 6251-6251/fr.free.nrw.commons.debug D/FileProcessor: Calling GPSExtractor
07-19 02:43:31.296 6251-6251/fr.free.nrw.commons.debug I/ExifInterface_JNI: Raw image not detected
07-19 02:43:31.303 6251-6251/fr.free.nrw.commons.debug D/CacheController: No categories found, so no categories cached
07-19 02:43:31.304 6251-6251/fr.free.nrw.commons.debug D/ShareActivity: Cache the categories found
07-19 02:43:31.304 6251-6251/fr.free.nrw.commons.debug D/UploadController: Wikidata entity ID received from Share activity is null
07-19 02:43:31.315 6251-6475/fr.free.nrw.commons.debug D/UploadController: MimeType is: image/jpeg
07-19 02:43:31.316 6251-6475/fr.free.nrw.commons.debug D/UploadController: local uri   content://com.google.android.apps.photos.contentprovider/0/1/mediakey%3A%2FAF1QipPrDnls_djkbY3AYJBqZ_DF1A4DPipq9_gWPMUs/ORIGINAL/NONE/1707416755
07-19 02:43:31.347 6251-6350/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xa3e8aa80: ver 2 0 (tinfo 0xa3ea7280)
07-19 02:43:31.355 6251-6350/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xa3e8aa80: ver 2 0 (tinfo 0xa3ea7280)
07-19 02:43:31.362 6251-6251/fr.free.nrw.commons.debug D/UploadService: Upload service queue has contribution with wiki data entity id as null
07-19 02:43:31.392 6251-6364/fr.free.nrw.commons.debug D/UploadService: Before execution!
07-19 02:43:31.404 6251-6364/fr.free.nrw.commons.debug D/UploadService: making sure of uniqueness of name: deGraves street Melbourne.jpg
07-19 02:43:31.404 6251-6364/fr.free.nrw.commons.debug I/System.out: HTTP Request: GET https://commons.wikimedia.org/w/api.php?prop=imageinfo&titles=File%3AdeGraves+street+Melbourne.jpg&action=query&format=xml
07-19 02:43:31.429 6251-6251/fr.free.nrw.commons.debug W/IInputConnectionWrapper: finishComposingText on inactive InputConnection
07-19 02:43:31.430 6251-6251/fr.free.nrw.commons.debug W/IInputConnectionWrapper: finishComposingText on inactive InputConnection
    finishComposingText on inactive InputConnection
07-19 02:43:31.846 6251-6364/fr.free.nrw.commons.debug I/System.out: HTTP Response: 200 Date=Wed, 18 Jul 2018 16:43:32 GMT Content-Type=text/xml; charset=utf-8 Content-Length=289 Connection=keep-alive Server=mw1276.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=44995 t=1531932211926001 X-Varnish=425189932, 244125535, 939699577, 864742067 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1066 pass, cp2016 pass, cp5011 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=18-Jul-2018;https=1 X-Client-IP=censored
07-19 02:43:31.849 6251-6364/fr.free.nrw.commons.debug I/System.out: HTTP Request: GET https://commons.wikimedia.org/w/api.php?meta=userinfo&action=query&format=xml
07-19 02:43:31.918 6251-6251/fr.free.nrw.commons.debug D/CategorizationFragment: Direct category found: 'Android sculptures    '
    DirectCat does not equal emptyString. Direct Cat list has [Android sculptures    ]
07-19 02:43:31.919 6251-6251/fr.free.nrw.commons.debug D/CategorizationFragment: Image has direct Cat
07-19 02:43:31.939 6251-6339/fr.free.nrw.commons.debug D/CategorizationFragment: Previous year: 2017
07-19 02:43:31.939 6251-6339/fr.free.nrw.commons.debug I/System.out: HTTP Request: GET https://commons.wikimedia.org/w/api.php?srlimit=25&list=search&action=query&srnamespace=14&format=xml&srwhat=text&srsearch=deGraves+street+Melbourne&format=xml
07-19 02:43:32.260 6251-6364/fr.free.nrw.commons.debug I/System.out: HTTP Response: 200 Date=Wed, 18 Jul 2018 16:43:32 GMT Content-Type=text/xml; charset=utf-8 Content-Length=113 Connection=keep-alive Server=mw1342.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=25592 t=1531932212357401 X-Varnish=703703442, 837087338, 33381271, 868554195 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1067 pass, cp2013 pass, cp5012 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=18-Jul-2018;https=1 X-Client-IP=censored
07-19 02:43:32.279 6251-6364/fr.free.nrw.commons.debug D/UploadService: Successfully revalidated token!
07-19 02:43:32.280 6251-6364/fr.free.nrw.commons.debug I/System.out: HTTP Request: GET https://commons.wikimedia.org/w/api.php?type=edit&action=tokens&format=xml
07-19 02:43:32.713 6251-6364/fr.free.nrw.commons.debug I/System.out: HTTP Response: 200 Date=Wed, 18 Jul 2018 16:43:32 GMT Content-Type=text/xml; charset=utf-8 Content-Length=510 Connection=keep-alive Server=mw1316.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Set-Cookie=commonswikiSession=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; secure; httponly Set-Cookie=commonswikiUserID=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; secure; httponly Set-Cookie=centralauth_User=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; domain=commons.wikimedia.org; secure; httponly Set-Cookie=centralauth_Token=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; domain=commons.wikimedia.org; secure; httponly Set-Cookie=centralauth_Session=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; domain=commons.wikimedia.org; secure; httponly Backend-Timing=D=34020 t=1531932212799915 X-Varnish=706709262, 842361009, 605044462, 864167048 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1067 pass, cp2013 pass, cp5008 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload Set-Cookie=WMF-Last-Access=18-Jul-2018;Path=/;HttpOnly;secure;Expires=Sun, 19 Aug 2018 12:00:00 GMT X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=16-Jul-2018;https=1 X-Client-IP=censored
07-19 02:43:32.718 6251-6364/fr.free.nrw.commons.debug I/System.out: HTTP Request: POST https://commons.wikimedia.org/w/api.php
07-19 02:43:32.737 6251-6364/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 59098 of 1868078
07-19 02:43:32.754 6251-6364/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 116442 of 1868078
07-19 02:43:32.777 6251-6364/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 173786 of 1868078
07-19 02:43:32.799 6251-6364/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 231130 of 1868078
07-19 02:43:32.817 6251-6364/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 288474 of 1868078
07-19 02:43:32.837 6251-6364/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 345818 of 1868078
07-19 02:43:32.854 6251-6364/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 403162 of 1868078
07-19 02:43:32.937 6251-6339/fr.free.nrw.commons.debug I/System.out: HTTP Response: 200 Date=Wed, 18 Jul 2018 16:43:33 GMT Content-Type=text/xml; charset=utf-8 Content-Length=1153 Connection=keep-alive Server=mw1282.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=123344 t=1531932212934168 X-Varnish=148837809, 845627916, 607198848, 868754053 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1055 pass, cp2013 pass, cp5008 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=18-Jul-2018;https=1 X-Client-IP=censored
07-19 02:43:32.942 6251-6339/fr.free.nrw.commons.debug D/CategorizationFragment: Previous year: 2017
    Previous year: 2017
    Previous year: 2017
07-19 02:43:32.943 6251-6339/fr.free.nrw.commons.debug D/CategorizationFragment: Previous year: 2017
07-19 02:43:33.479 6251-6364/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 460506 of 1868078
07-19 02:43:33.494 6251-6364/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 517850 of 1868078
07-19 02:43:33.511 6251-6364/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 575194 of 1868078
07-19 02:43:33.525 6251-6364/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 632538 of 1868078
07-19 02:43:33.541 6251-6364/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 689882 of 1868078
07-19 02:43:34.216 6251-6364/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 747226 of 1868078
07-19 02:43:34.228 6251-6364/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 804570 of 1868078
07-19 02:43:34.239 6251-6364/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 861914 of 1868078
07-19 02:43:34.253 6251-6364/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 919258 of 1868078
07-19 02:43:34.264 6251-6364/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 976602 of 1868078
07-19 02:43:34.278 6251-6364/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1033946 of 1868078
07-19 02:43:35.845 6251-6483/fr.free.nrw.commons.debug I/System.out: HTTP Request: GET https://commons.wikimedia.org/w/api.php?type=edit&action=tokens&format=xml
07-19 02:43:35.846 6251-6462/fr.free.nrw.commons.debug I/System.out: HTTP Request: GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=deGraves+street+Melbourne&iiprop=url&action=query&format=xml&format=xml
07-19 02:43:35.848 6251-6462/fr.free.nrw.commons.debug I/System.out: HTTP Request: GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=deGraves+street+Melbourne&iiprop=url&action=query&format=xml&format=xml
07-19 02:43:35.849 6251-6462/fr.free.nrw.commons.debug I/System.out: HTTP Request: GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=deGraves+street+Melbourne&iiprop=url&action=query&format=xml&format=xml
07-19 02:43:35.850 6251-6462/fr.free.nrw.commons.debug I/System.out: HTTP Request: GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=deGraves+street+Melbourne&iiprop=url&action=query&format=xml&format=xml
07-19 02:43:35.851 6251-6462/fr.free.nrw.commons.debug I/System.out: HTTP Request: GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=deGraves+street+Melbourne&iiprop=url&action=query&format=xml&format=xml
07-19 02:43:35.856 6251-6350/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xa3e8aa80: ver 2 0 (tinfo 0xa3ea7280)
07-19 02:43:35.870 6251-6350/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xa3e8aa80: ver 2 0 (tinfo 0xa3ea7280)
07-19 02:43:35.877 6251-6350/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xa3e8aa80: ver 2 0 (tinfo 0xa3ea7280)
07-19 02:43:35.888 6251-6350/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xa3e8aa80: ver 2 0 (tinfo 0xa3ea7280)
07-19 02:43:35.893 6251-6350/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xa3e8aa80: ver 2 0 (tinfo 0xa3ea7280)
07-19 02:43:35.911 6251-6350/fr.free.nrw.commons.debug D/OpenGLRenderer: endAllActiveAnimators on 0x974db480 (RippleDrawable) with handle 0x97bc62a0
07-19 02:43:36.173 1661-6263/system_process I/WindowManager: Destroying surface Surface(name=fr.free.nrw.commons.debug/fr.free.nrw.commons.upload.ShareActivity) called by com.android.server.wm.WindowStateAnimator.destroySurface:2016 com.android.server.wm.WindowStateAnimator.destroySurfaceLocked:882 com.android.server.wm.WindowState.removeLocked:1456 com.android.server.wm.WindowManagerService.removeWindowInnerLocked:2484 com.android.server.wm.WindowManagerService.removeWindowLocked:2442 com.android.server.wm.WindowManagerService.removeWindowLocked:2311 com.android.server.wm.WindowManagerService.removeWindow:2306 com.android.server.wm.Session.remove:202 
07-19 02:43:37.826 6251-6483/fr.free.nrw.commons.debug I/System.out: HTTP Response: 200 Date=Wed, 18 Jul 2018 16:43:38 GMT Content-Type=text/xml; charset=utf-8 Content-Length=510 Connection=keep-alive Server=mw1317.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Set-Cookie=commonswikiSession=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; secure; httponly Set-Cookie=commonswikiUserID=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; secure; httponly Set-Cookie=centralauth_User=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; domain=commons.wikimedia.org; secure; httponly Set-Cookie=centralauth_Token=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; domain=commons.wikimedia.org; secure; httponly Set-Cookie=centralauth_Session=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; domain=commons.wikimedia.org; secure; httponly Backend-Timing=D=30385 t=1531932217876014 X-Varnish=698890274, 845343914, 489324404, 866336162 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1067 pass, cp2013 pass, cp5009 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload Set-Cookie=WMF-Last-Access=18-Jul-2018;Path=/;HttpOnly;secure;Expires=Sun, 19 Aug 2018 12:00:00 GMT X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=16-Jul-2018;https=1 X-Client-IP=censored
07-19 02:43:37.829 6251-6483/fr.free.nrw.commons.debug D/ModificationsSyncAdapte: Found 1 modifications to execute
07-19 02:43:38.280 6251-6462/fr.free.nrw.commons.debug I/System.out: HTTP Response: 200 Date=Wed, 18 Jul 2018 16:43:38 GMT Content-Type=text/xml; charset=utf-8 Content-Length=243 Connection=keep-alive Server=mw1229.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=26098 t=1531932218376670 X-Varnish=441984902, 844059278, 491150987, 867120900 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1066 pass, cp2013 pass, cp5009 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=18-Jul-2018;https=1 X-Client-IP=censored
07-19 02:43:38.284 6251-6462/fr.free.nrw.commons.debug I/System.out: HTTP Request: GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=multiple+test+-+2&iiprop=url&action=query&format=xml&format=xml
07-19 02:43:38.542 6251-6364/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1091290 of 1868078
07-19 02:43:38.558 6251-6364/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1148634 of 1868078
07-19 02:43:38.574 6251-6364/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1205978 of 1868078
07-19 02:43:38.753 6251-6462/fr.free.nrw.commons.debug I/System.out: HTTP Response: 200 Date=Wed, 18 Jul 2018 16:43:38 GMT Content-Type=text/xml; charset=utf-8 Content-Length=219 Connection=keep-alive Server=mw1229.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=31912 t=1531932218839934 X-Varnish=624970006, 845343920, 553029640, 868538807 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1054 pass, cp2013 pass, cp5007 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=18-Jul-2018;https=1 X-Client-IP=censored
07-19 02:43:38.757 6251-6462/fr.free.nrw.commons.debug I/System.out: HTTP Request: GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=deGraves+street+Melbourne&iiprop=url&action=query&format=xml&format=xml
07-19 02:43:38.837 6251-6256/fr.free.nrw.commons.debug I/art: Do partial code cache collection, code=122KB, data=112KB
    After code cache collection, code=114KB, data=106KB
07-19 02:43:38.838 6251-6256/fr.free.nrw.commons.debug I/art: Increasing code cache capacity to 512KB
07-19 02:43:39.220 6251-6462/fr.free.nrw.commons.debug I/System.out: HTTP Response: 200 Date=Wed, 18 Jul 2018 16:43:39 GMT Content-Type=text/xml; charset=utf-8 Content-Length=243 Connection=keep-alive Server=mw1283.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=30248 t=1531932219312972 X-Varnish=445286666, 846313795, 1066022483, 861890308 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1066 pass, cp2013 pass, cp5010 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=18-Jul-2018;https=1 X-Client-IP=censored
07-19 02:43:39.223 6251-6462/fr.free.nrw.commons.debug I/System.out: HTTP Request: GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=multiple+test+-+2&iiprop=url&action=query&format=xml&format=xml
07-19 02:43:39.687 6251-6462/fr.free.nrw.commons.debug I/System.out: HTTP Response: 200 Date=Wed, 18 Jul 2018 16:43:39 GMT Content-Type=text/xml; charset=utf-8 Content-Length=219 Connection=keep-alive Server=mw1280.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=37307 t=1531932219769984 X-Varnish=635930447, 844650909, 32437568, 862254295 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1054 pass, cp2013 pass, cp5012 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=18-Jul-2018;https=1 X-Client-IP=censored
07-19 02:43:40.699 6251-6364/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1263322 of 1868078
07-19 02:43:40.713 6251-6364/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1320666 of 1868078
07-19 02:43:40.738 6251-6466/fr.free.nrw.commons.debug I/System.out: HTTP Request: GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=deGraves+street+Melbourne&iiprop=url&action=query&format=xml&format=xml
07-19 02:43:41.183 6251-6286/fr.free.nrw.commons.debug I/art: Starting a blocking GC Explicit
07-19 02:43:41.201 6251-6286/fr.free.nrw.commons.debug I/art: Explicit concurrent mark sweep GC freed 28479(2MB) AllocSpace objects, 25(7MB) LOS objects, 19% free, 16MB/20MB, paused 1.376ms total 15.082ms
07-19 02:43:41.230 6251-6466/fr.free.nrw.commons.debug I/System.out: HTTP Response: 200 Date=Wed, 18 Jul 2018 16:43:41 GMT Content-Type=text/xml; charset=utf-8 Transfer-Encoding=chunked Connection=keep-alive Server=mw1348.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=36339 t=1531932221311620 X-Varnish=435502583, 848533479, 938276059, 868734719 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1066 pass, cp2013 pass, cp5011 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=18-Jul-2018;https=1 X-Client-IP=censored
07-19 02:43:41.234 6251-6466/fr.free.nrw.commons.debug I/System.out: HTTP Request: GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=deGraves+street+Melbourne&iiprop=url&action=query&format=xml&format=xml
07-19 02:43:41.659 6251-6364/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1378010 of 1868078
07-19 02:43:41.671 6251-6364/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1435354 of 1868078
07-19 02:43:41.686 6251-6364/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1492698 of 1868078
07-19 02:43:41.732 6251-6466/fr.free.nrw.commons.debug I/System.out: HTTP Response: 200 Date=Wed, 18 Jul 2018 16:43:41 GMT Content-Type=text/xml; charset=utf-8 Transfer-Encoding=chunked Connection=keep-alive Server=mw1347.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=26036 t=1531932221832642 X-Varnish=427094922, 351049197, 493759392, 865512594 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1066 pass, cp2023 pass, cp5009 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=18-Jul-2018;https=1 X-Client-IP=censored
07-19 02:43:41.737 6251-6466/fr.free.nrw.commons.debug I/System.out: HTTP Request: GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=deGraves+street+Melbourne&iiprop=url&action=query&format=xml&format=xml
07-19 02:43:42.255 6251-6466/fr.free.nrw.commons.debug I/System.out: HTTP Response: 200 Date=Wed, 18 Jul 2018 16:43:42 GMT Content-Type=text/xml; charset=utf-8 Content-Length=243 Connection=keep-alive Server=mw1235.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=34692 t=1531932222343421 X-Varnish=435176882, 176189646, 1066523628, 862254320 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1066 pass, cp2001 pass, cp5010 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=18-Jul-2018;https=1 X-Client-IP=censored
07-19 02:43:42.258 6251-6466/fr.free.nrw.commons.debug I/System.out: HTTP Request: GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=multiple+test+-+2&iiprop=url&action=query&format=xml&format=xml
07-19 02:43:42.619 6251-6364/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1550042 of 1868078
07-19 02:43:42.626 6251-6364/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1607386 of 1868078
07-19 02:43:42.798 6251-6466/fr.free.nrw.commons.debug I/System.out: HTTP Response: 200 Date=Wed, 18 Jul 2018 16:43:43 GMT Content-Type=text/xml; charset=utf-8 Transfer-Encoding=chunked Connection=keep-alive Server=mw1280.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=37264 t=1531932222881149 X-Varnish=633344920, 843960377, 35631536, 865163685 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1054 pass, cp2013 pass, cp5012 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=18-Jul-2018;https=1 X-Client-IP=censored
07-19 02:43:42.801 6251-6466/fr.free.nrw.commons.debug I/System.out: HTTP Request: GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=deGraves+street+Melbourne&iiprop=url&action=query&format=xml&format=xml
07-19 02:43:43.386 6251-6466/fr.free.nrw.commons.debug I/System.out: HTTP Response: 200 Date=Wed, 18 Jul 2018 16:43:43 GMT Content-Type=text/xml; charset=utf-8 Transfer-Encoding=chunked Connection=keep-alive Server=mw1312.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=36852 t=1531932223471114 X-Varnish=440181347, 633060896, 488697020, 869532123 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1066 pass, cp2019 pass, cp5009 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=18-Jul-2018;https=1 X-Client-IP=censored
07-19 02:43:43.388 6251-6466/fr.free.nrw.commons.debug I/System.out: HTTP Request: GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=multiple+test+-+2&iiprop=url&action=query&format=xml&format=xml
07-19 02:43:44.227 6251-6466/fr.free.nrw.commons.debug I/System.out: HTTP Response: 200 Date=Wed, 18 Jul 2018 16:43:44 GMT Content-Type=text/xml; charset=utf-8 Content-Length=219 Connection=keep-alive Server=mw1346.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=33825 t=1531932224316018 X-Varnish=633118121, 849772813, 1065467617, 868196963 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1054 pass, cp2013 pass, cp5010 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=18-Jul-2018;https=1 X-Client-IP=censored
07-19 02:43:45.266 6251-6364/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1664730 of 1868078
07-19 02:43:45.281 6251-6364/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1722074 of 1868078
07-19 02:43:45.295 6251-6364/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1779418 of 1868078
07-19 02:43:45.309 6251-6364/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1836762 of 1868078
07-19 02:43:45.331 6251-6364/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1868078 of 1868078
07-19 02:43:45.376 6251-6462/fr.free.nrw.commons.debug I/System.out: HTTP Request: GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=deGraves+street+Melbourne&iiprop=url&action=query&format=xml&format=xml
07-19 02:43:45.827 6251-6462/fr.free.nrw.commons.debug I/System.out: HTTP Response: 200 Date=Wed, 18 Jul 2018 16:43:46 GMT Content-Type=text/xml; charset=utf-8 Content-Length=243 Connection=keep-alive Server=mw1288.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=26644 t=1531932225921197 X-Varnish=446268079, 845603691, 36740126, 865583797 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1066 pass, cp2013 pass, cp5012 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=18-Jul-2018;https=1 X-Client-IP=censored
07-19 02:43:45.830 6251-6462/fr.free.nrw.commons.debug I/System.out: HTTP Request: GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=multiple+test+-+2&iiprop=url&action=query&format=xml&format=xml
07-19 02:43:46.319 6251-6462/fr.free.nrw.commons.debug I/System.out: HTTP Response: 200 Date=Wed, 18 Jul 2018 16:43:46 GMT Content-Type=text/xml; charset=utf-8 Content-Length=219 Connection=keep-alive Server=mw1344.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=37512 t=1531932226401107 X-Varnish=634527641, 848205474, 605175317, 863389988 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1054 pass, cp2013 pass, cp5008 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=18-Jul-2018;https=1 X-Client-IP=censored
07-19 02:43:54.739 6251-6364/fr.free.nrw.commons.debug I/System.out: HTTP Response: 200 Date=Wed, 18 Jul 2018 16:43:54 GMT Content-Type=text/xml; charset=utf-8 Transfer-Encoding=chunked Connection=keep-alive Server=mw1228.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev MediaWiki-API-Error=mustbeloggedin P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=1385700 t=1531932233481221 X-Varnish=692285303, 177464124, 32952079, 868985581 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1067 pass, cp2001 pass, cp5012 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=WMF-Last-Access=18-Jul-2018;https=1 X-Client-IP=censored
07-19 02:43:54.775 6251-6364/fr.free.nrw.commons.debug E/WTF: Result: org.mediawiki.api.ApiResult@63e985a
07-19 02:43:54.777 6251-6364/fr.free.nrw.commons.debug E/ApacheHttpClientMediaWi: mustbeloggedin
07-19 02:43:54.778 6251-6364/fr.free.nrw.commons.debug D/UploadService: Response is fr.free.nrw.commons.mwapi.UploadResult@aae7a8b
    Contribution upload failed. Wikidata entity won't be edited
07-19 02:43:54.840 6251-6466/fr.free.nrw.commons.debug I/System.out: HTTP Request: GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=multiple+test+-+2&iiprop=url&action=query&format=xml&format=xml
07-19 02:43:54.857 6251-6488/fr.free.nrw.commons.debug I/System.out: HTTP Request: GET https://commons.wikimedia.org/w/api.php?type=edit&action=tokens&format=xml
07-19 02:43:55.313 6251-6488/fr.free.nrw.commons.debug I/System.out: HTTP Response: 200 Date=Wed, 18 Jul 2018 16:43:55 GMT Content-Type=text/xml; charset=utf-8 Transfer-Encoding=chunked Connection=keep-alive Server=mw1341.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Set-Cookie=commonswikiSession=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; secure; httponly Set-Cookie=commonswikiUserID=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; secure; httponly Set-Cookie=centralauth_User=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; domain=commons.wikimedia.org; secure; httponly Set-Cookie=centralauth_Token=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; domain=commons.wikimedia.org; secure; httponly Set-Cookie=centralauth_Session=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; domain=commons.wikimedia.org; secure; httponly Backend-Timing=D=31813 t=1531932235402081 X-Varnish=700729972, 538679360, 605797253, 866595907 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1067 pass, cp2010 pass, cp5008 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload Set-Cookie=WMF-Last-Access=18-Jul-2018;Path=/;HttpOnly;secure;Expires=Sun, 19 Aug 2018 12:00:00 GMT X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=16-Jul-2018;https=1 X-Client-IP=censored

misaochan commented 6 years ago

@maskaravivek , the last GET response looks interesting:

Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Set-Cookie=commonswikiSession=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; secure; httponly Set-Cookie=commonswikiUserID=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; secure; httponly Set-Cookie=centralauth_User=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; domain=commons.wikimedia.org; secure; httponly Set-Cookie=centralauth_Token=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; domain=commons.wikimedia.org; secure; httponly Set-Cookie=centralauth_Session=deleted; expires=Thu,

nicolas-raoul commented 6 years ago

Something caught my attention:

02:43:40.738 HTTP Request: GET 
02:43:41.230 HTTP Response: 200 
02:43:41.234 HTTP Request: GET
02:43:41.732 HTTP Response: 200 
02:43:41.737 HTTP Request: GET 
02:43:42.255 HTTP Response: 200 
02:43:42.258 HTTP Request: GET 
02:43:42.798 HTTP Response: 200 
02:43:42.801 HTTP Request: GET
02:43:43.386 HTTP Response: 200 
02:43:43.388 HTTP Request: GET 
02:43:44.227 HTTP Response: 200 
02:43:45.376 HTTP Request: GET
02:43:45.827 HTTP Response: 200
02:43:45.830 HTTP Request: GET
02:43:46.319 HTTP Response: 200
02:43:54.739 HTTP Response: 200
02:43:54.840 HTTP Request: GET 
02:43:54.857 HTTP Request: GET
02:43:55.313 HTTP Response: 200

The response at 02:43:54.739 seems out of place. It probably answers go a request that was made long before. To know more, I added request identifiers. Please replace the commit number by 6f022b0 in your build.gradle as seen at https://github.com/nicolas-raoul/apps-android-commons/commit/7f8c44db06ee4be62f44d23f55e28c9206a54873 and post the log again (making sure to include lines early enough to show the requests whose response are near the error), thanks!

misaochan commented 6 years ago

Logs with the new commit number:

07-19 20:39:31.260 8206-8206/fr.free.nrw.commons.debug D/ContributionsListFragme: OnActivityResult() parameters: Req code: 1 Result code: -1 Data: Intent { dat=content://com.google.android.apps.photos.contentprovider/0/1/mediakey:/AF1QipMY1qBCGgwyQwOYVZGeVhZDY8XCcQqxyScEOGpl/ORIGINAL/NONE/1671903561 flg=0x1 clip={text/uri-list U:content://com.google.android.apps.photos.contentprovider/0/1/mediakey%3A%2FAF1QipMY1qBCGgwyQwOYVZGeVhZDY8XCcQqxyScEOGpl/ORIGINAL/NONE/1671903561} }
07-19 20:39:31.260 8206-8206/fr.free.nrw.commons.debug D/ContributionController: Is direct upload false and the Wikidata entity ID is null
    handleImagePicked() called with onActivityResult()
07-19 20:39:31.267 8206-8206/fr.free.nrw.commons.debug I/ContributionController: Image selected
07-19 20:39:31.267 1661-2308/system_process I/ActivityManager: START u0 {act=android.intent.action.SEND typ=image/jpeg flg=0x1 cmp=fr.free.nrw.commons.debug/fr.free.nrw.commons.upload.ShareActivity clip={image/jpeg U:content://com.google.android.apps.photos.contentprovider/0/1/mediakey%3A%2FAF1QipMY1qBCGgwyQwOYVZGeVhZDY8XCcQqxyScEOGpl/ORIGINAL/NONE/1671903561} (has extras)} from uid 10084 on display 0
07-19 20:39:31.332 8206-8206/fr.free.nrw.commons.debug I/AppCompatViewInflater: app:theme is now deprecated. Please move to using android:theme instead.
07-19 20:39:31.350 8206-8206/fr.free.nrw.commons.debug D/ShareActivity: This was initiated by a direct upload from Nearby
07-19 20:39:31.351 8206-8206/fr.free.nrw.commons.debug D/ShareActivity: Received wikiDataEntityId from contribution controller null
07-19 20:39:31.352 8206-8206/fr.free.nrw.commons.debug D/ShareActivity: Uri: content://com.google.android.apps.photos.contentprovider/0/1/mediakey%3A%2FAF1QipMY1qBCGgwyQwOYVZGeVhZDY8XCcQqxyScEOGpl/ORIGINAL/NONE/1671903561
07-19 20:39:31.353 8206-8206/fr.free.nrw.commons.debug D/ShareActivity: Ext storage dir: /storage/emulated/0
07-19 20:39:31.374 8206-8206/fr.free.nrw.commons.debug I/FileUtils: File SHA1: 9807f4778dd8831a8f6e9f4253e49e7342dd4a0c
07-19 20:39:31.375 8206-8206/fr.free.nrw.commons.debug D/ShareActivity: Input stream created from content://com.google.android.apps.photos.contentprovider/0/1/mediakey%3A%2FAF1QipMY1qBCGgwyQwOYVZGeVhZDY8XCcQqxyScEOGpl/ORIGINAL/NONE/1671903561
    File SHA1 is: 9807f4778dd8831a8f6e9f4253e49e7342dd4a0c
07-19 20:39:31.376 8206-8206/fr.free.nrw.commons.debug D/FileProcessor: Calling GPSExtractor
07-19 20:39:31.377 8206-8353/fr.free.nrw.commons.debug I/System.out: HTTP Request (5): GET https://commons.wikimedia.org/w/api.php?aisha1=9807f4778dd8831a8f6e9f4253e49e7342dd4a0c&list=allimages&action=query&format=xml&format=xml
07-19 20:39:31.385 8206-8206/fr.free.nrw.commons.debug I/ExifInterface_JNI: Raw image not detected
07-19 20:39:31.445 8206-8206/fr.free.nrw.commons.debug D/FileUtils: Filepath (copied): /storage/emulated/0/CommonsApp/1531996771398.jpg
07-19 20:39:31.445 8206-8206/fr.free.nrw.commons.debug D/FileProcessor: Filepath: /storage/emulated/0/CommonsApp/1531996771398.jpg
    filePath/storage/emulated/0/CommonsApp/1531996771398.jpg
07-19 20:39:31.446 8206-8206/fr.free.nrw.commons.debug D/FileProcessor: folderTime Number:3
    fild date:1531996771000 time of creation1531996771000
07-19 20:39:31.446 8206-8206/fr.free.nrw.commons.debug W/System.err: java.io.FileNotFoundException: No content provider: /storage/emulated/0/CommonsApp/1531996771398.jpg
        at android.content.ContentResolver.openTypedAssetFileDescriptor(ContentResolver.java:1131)
        at android.content.ContentResolver.openAssetFileDescriptor(ContentResolver.java:982)
07-19 20:39:31.447 8206-8206/fr.free.nrw.commons.debug W/System.err:     at android.content.ContentResolver.openFileDescriptor(ContentResolver.java:835)
        at android.content.ContentResolver.openFileDescriptor(ContentResolver.java:789)
        at fr.free.nrw.commons.upload.FileProcessor.findOtherImages(FileProcessor.java:157)
        at fr.free.nrw.commons.upload.FileProcessor.processFileCoordinates(FileProcessor.java:122)
        at fr.free.nrw.commons.upload.ShareActivity.onCreate(ShareActivity.java:288)
        at android.app.Activity.performCreate(Activity.java:6662)
        at android.app.Instrumentation.callActivityOnCreate(Instrumentation.java:1118)
        at android.app.ActivityThread.performLaunchActivity(ActivityThread.java:2599)
        at android.app.ActivityThread.handleLaunchActivity(ActivityThread.java:2707)
        at android.app.ActivityThread.-wrap12(ActivityThread.java)
        at android.app.ActivityThread$H.handleMessage(ActivityThread.java:1460)
        at android.os.Handler.dispatchMessage(Handler.java:102)
        at android.os.Looper.loop(Looper.java:154)
        at android.app.ActivityThread.main(ActivityThread.java:6077)
        at java.lang.reflect.Method.invoke(Native Method)
        at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:866)
        at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:756)
07-19 20:39:31.453 8206-8206/fr.free.nrw.commons.debug I/TextInputLayout: EditText added is not a TextInputEditText. Please switch to using that class instead.
07-19 20:39:31.454 8206-8206/fr.free.nrw.commons.debug I/TextInputLayout: EditText added is not a TextInputEditText. Please switch to using that class instead.
07-19 20:39:31.465 8206-8206/fr.free.nrw.commons.debug D/SingleUploadFragment: Image title: , image desc: , image categories: 
    CC BY-SA 3.0
    Position: 2 Attribution-ShareAlike 3.0
07-19 20:39:31.472 8206-8258/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xa3e92500: ver 2 0 (tinfo 0xa3ea7da0)
07-19 20:39:31.567 8206-8211/fr.free.nrw.commons.debug I/art: Do partial code cache collection, code=60KB, data=60KB
07-19 20:39:31.568 8206-8211/fr.free.nrw.commons.debug I/art: After code cache collection, code=60KB, data=60KB
    Increasing code cache capacity to 256KB
07-19 20:39:31.583 8206-8258/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xa3e92500: ver 2 0 (tinfo 0xa3ea7da0)
07-19 20:39:31.642 1661-1682/system_process I/ActivityManager: Displayed fr.free.nrw.commons.debug/fr.free.nrw.commons.upload.ShareActivity: +364ms
07-19 20:39:31.872 1661-2181/system_process I/WindowManager: Destroying surface Surface(name=fr.free.nrw.commons.debug/fr.free.nrw.commons.contributions.ContributionsActivity) called by com.android.server.wm.WindowStateAnimator.destroySurface:2016 com.android.server.wm.WindowStateAnimator.destroySurfaceLocked:882 com.android.server.wm.WindowState.destroyOrSaveSurface:2100 com.android.server.wm.WindowManagerService.tryStartExitingAnimation:3024 com.android.server.wm.WindowManagerService.relayoutWindow:2904 com.android.server.wm.Session.relayout:224 android.view.IWindowSession$Stub.onTransact:286 com.android.server.wm.Session.onTransact:145 
07-19 20:39:31.923 8206-8353/fr.free.nrw.commons.debug I/System.out: HTTP Response (5): 200 Date=Thu, 19 Jul 2018 10:39:32 GMT Content-Type=text/xml; charset=utf-8 Content-Length=77 Connection=keep-alive Server=mw1343.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Set-Cookie=commonswikiSession=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; secure; httponly Set-Cookie=commonswikiUserID=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; secure; httponly Set-Cookie=centralauth_User=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; domain=commons.wikimedia.org; secure; httponly Set-Cookie=centralauth_Token=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; domain=commons.wikimedia.org; secure; httponly Set-Cookie=centralauth_Session=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; domain=commons.wikimedia.org; secure; httponly Backend-Timing=D=29928 t=1531996771991985 X-Varnish=332245679, 695059971, 41779983, 92019405 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1065 pass, cp2016 pass, cp5012 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload Set-Cookie=WMF-Last-Access=19-Jul-2018;Path=/;HttpOnly;secure;Expires=Mon, 20 Aug 2018 00:00:00 GMT X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=16-Jul-2018;https=1 X-Client-IP=censored
07-19 20:39:31.926 8206-8353/fr.free.nrw.commons.debug D/ExistingFileAsync: File already exists in Commons: false
07-19 20:39:31.941 8206-8206/fr.free.nrw.commons.debug D/ShareActivity: content://com.google.android.apps.photos.contentprovider/0/1/mediakey%3A%2FAF1QipMY1qBCGgwyQwOYVZGeVhZDY8XCcQqxyScEOGpl/ORIGINAL/NONE/1671903561 duplicate check: NO_DUPLICATE
07-19 20:39:31.963 8206-8206/fr.free.nrw.commons.debug D/FileUtils: Filepath (copied): /storage/emulated/0/CommonsApp/1531996771955.jpg
07-19 20:39:31.964 8206-8354/fr.free.nrw.commons.debug D/DetectUnwantedPicturesA: FilePath: /storage/emulated/0/CommonsApp/1531996771955.jpg
07-19 20:39:32.850 8206-8354/fr.free.nrw.commons.debug D/ImageUtils: dark rectangles count = 0, total rectangles count = 25
07-19 20:39:37.709 8206-8206/fr.free.nrw.commons.debug D/FileProcessor: Calling GPSExtractor
07-19 20:39:37.718 8206-8206/fr.free.nrw.commons.debug I/ExifInterface_JNI: Raw image not detected
07-19 20:39:37.721 8206-8206/fr.free.nrw.commons.debug D/CacheController: No categories found, so no categories cached
07-19 20:39:37.722 8206-8206/fr.free.nrw.commons.debug D/ShareActivity: Cache the categories found
07-19 20:39:37.723 8206-8206/fr.free.nrw.commons.debug D/UploadController: Wikidata entity ID received from Share activity is null
07-19 20:39:37.730 8206-8358/fr.free.nrw.commons.debug D/UploadController: MimeType is: image/jpeg
    local uri   content://com.google.android.apps.photos.contentprovider/0/1/mediakey%3A%2FAF1QipMY1qBCGgwyQwOYVZGeVhZDY8XCcQqxyScEOGpl/ORIGINAL/NONE/1671903561
07-19 20:39:37.765 8206-8206/fr.free.nrw.commons.debug D/UploadService: Upload service queue has contribution with wiki data entity id as null
07-19 20:39:37.783 8206-8258/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xa3e92500: ver 2 0 (tinfo 0xa3ea7da0)
07-19 20:39:37.785 8206-8264/fr.free.nrw.commons.debug D/UploadService: Before execution!
07-19 20:39:37.792 8206-8258/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xa3e92500: ver 2 0 (tinfo 0xa3ea7da0)
07-19 20:39:37.798 8206-8264/fr.free.nrw.commons.debug D/UploadService: making sure of uniqueness of name: City circle tram melbourne.jpg
07-19 20:39:37.798 8206-8264/fr.free.nrw.commons.debug I/System.out: HTTP Request (6): GET https://commons.wikimedia.org/w/api.php?prop=imageinfo&titles=File%3ACity+circle+tram+melbourne.jpg&action=query&format=xml
07-19 20:39:37.844 8206-8206/fr.free.nrw.commons.debug W/IInputConnectionWrapper: finishComposingText on inactive InputConnection
    finishComposingText on inactive InputConnection
    finishComposingText on inactive InputConnection
07-19 20:39:38.306 8206-8264/fr.free.nrw.commons.debug I/System.out: HTTP Response (6): 200 Date=Thu, 19 Jul 2018 10:39:38 GMT Content-Type=text/xml; charset=utf-8 Content-Length=178 Connection=keep-alive Server=mw1347.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=45043 t=1531996778366822 X-Varnish=790759018, 238031476, 31911906, 92671029 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1068 pass, cp2013 pass, cp5012 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=19-Jul-2018;https=1 X-Client-IP=censored
07-19 20:39:38.308 8206-8264/fr.free.nrw.commons.debug I/System.out: HTTP Request (7): GET https://commons.wikimedia.org/w/api.php?meta=userinfo&action=query&format=xml
07-19 20:39:38.320 8206-8206/fr.free.nrw.commons.debug D/CategorizationFragment: Direct category found: ''
    Image has no direct Cat
07-19 20:39:38.342 8206-8255/fr.free.nrw.commons.debug I/System.out: HTTP Request (8): GET https://commons.wikimedia.org/w/api.php?srlimit=25&list=search&action=query&srnamespace=14&format=xml&srwhat=text&srsearch=City+circle+tram+melbourne&format=xml
07-19 20:39:38.793 8206-8264/fr.free.nrw.commons.debug I/System.out: HTTP Response (7): 200 Date=Thu, 19 Jul 2018 10:39:39 GMT Content-Type=text/xml; charset=utf-8 Content-Length=113 Connection=keep-alive Server=mw1315.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=27321 t=1531996778874563 X-Varnish=530606218, 208778844, 26381385, 93194414 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1067 pass, cp2013 pass, cp5012 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=19-Jul-2018;https=1 X-Client-IP=censored
07-19 20:39:38.808 8206-8264/fr.free.nrw.commons.debug D/UploadService: Successfully revalidated token!
07-19 20:39:38.809 8206-8264/fr.free.nrw.commons.debug I/System.out: HTTP Request (9): GET https://commons.wikimedia.org/w/api.php?type=edit&action=tokens&format=xml
07-19 20:39:38.984 8206-8255/fr.free.nrw.commons.debug I/System.out: HTTP Response (8): 200 Date=Thu, 19 Jul 2018 10:39:39 GMT Content-Type=text/xml; charset=utf-8 Content-Length=934 Connection=keep-alive Server=mw1317.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=172798 t=1531996778918961 X-Varnish=766736509, 1001658092, 40250068, 92737352 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1068 pass, cp2010 pass, cp5012 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=19-Jul-2018;https=1 X-Client-IP=censored
07-19 20:39:38.991 8206-8255/fr.free.nrw.commons.debug D/CategorizationFragment: Previous year: 2017
07-19 20:39:38.995 8206-8255/fr.free.nrw.commons.debug D/CategorizationFragment: Previous year: 2017
07-19 20:39:39.330 8206-8264/fr.free.nrw.commons.debug I/System.out: HTTP Response (9): 200 Date=Thu, 19 Jul 2018 10:39:39 GMT Content-Type=text/xml; charset=utf-8 Content-Length=510 Connection=keep-alive Server=mw1282.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Set-Cookie=commonswikiSession=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; secure; httponly Set-Cookie=commonswikiUserID=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; secure; httponly Set-Cookie=centralauth_User=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; domain=commons.wikimedia.org; secure; httponly Set-Cookie=centralauth_Token=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; domain=commons.wikimedia.org; secure; httponly Set-Cookie=centralauth_Session=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; domain=commons.wikimedia.org; secure; httponly Backend-Timing=D=45326 t=1531996779389244 X-Varnish=543004075, 238322955, 631360176, 93552783 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1067 pass, cp2013 pass, cp5008 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload Set-Cookie=WMF-Last-Access=19-Jul-2018;Path=/;HttpOnly;secure;Expires=Mon, 20 Aug 2018 00:00:00 GMT X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=16-Jul-2018;https=1 X-Client-IP=censored
07-19 20:39:39.334 8206-8264/fr.free.nrw.commons.debug I/System.out: HTTP Request (10): POST https://commons.wikimedia.org/w/api.php
07-19 20:39:39.351 8206-8264/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 67310 of 2211252
07-19 20:39:39.363 8206-8264/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 136942 of 2211252
07-19 20:39:39.378 8206-8264/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 206574 of 2211252
07-19 20:39:39.390 8206-8264/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 276206 of 2211252
07-19 20:39:39.409 8206-8264/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 345838 of 2211252
07-19 20:39:40.088 8206-8264/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 415470 of 2211252
07-19 20:39:40.108 8206-8264/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 485102 of 2211252
07-19 20:39:40.341 8206-8264/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 554734 of 2211252
07-19 20:39:40.353 8206-8264/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 624366 of 2211252
07-19 20:39:40.369 8206-8264/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 693998 of 2211252
07-19 20:39:41.083 8206-8264/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 763630 of 2211252
07-19 20:39:41.099 8206-8258/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xa3e92500: ver 2 0 (tinfo 0xa3ea7da0)
07-19 20:39:41.104 8206-8264/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 833262 of 2211252
07-19 20:39:41.104 8206-8362/fr.free.nrw.commons.debug I/System.out: HTTP Request (11): GET https://commons.wikimedia.org/w/api.php?type=edit&action=tokens&format=xml
07-19 20:39:41.122 8206-8264/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 902894 of 2211252
07-19 20:39:41.132 8206-8258/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xa3e92500: ver 2 0 (tinfo 0xa3ea7da0)
07-19 20:39:41.133 8206-8264/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 972526 of 2211252
07-19 20:39:41.145 8206-8353/fr.free.nrw.commons.debug I/System.out: HTTP Request (12): GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=City+circle+tram+melbourne&iiprop=url&action=query&format=xml&format=xml
07-19 20:39:41.157 8206-8264/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1042158 of 2211252
07-19 20:39:41.165 8206-8258/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xa3e92500: ver 2 0 (tinfo 0xa3ea7da0)
07-19 20:39:41.181 8206-8258/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xa3e92500: ver 2 0 (tinfo 0xa3ea7da0)
07-19 20:39:41.189 8206-8258/fr.free.nrw.commons.debug D/EGL_emulation: eglMakeCurrent: 0xa3e92500: ver 2 0 (tinfo 0xa3ea7da0)
07-19 20:39:41.207 8206-8258/fr.free.nrw.commons.debug D/OpenGLRenderer: endAllActiveAnimators on 0x95459500 (RippleDrawable) with handle 0x96f296f0
07-19 20:39:41.573 1661-2771/system_process I/WindowManager: Destroying surface Surface(name=fr.free.nrw.commons.debug/fr.free.nrw.commons.upload.ShareActivity) called by com.android.server.wm.WindowStateAnimator.destroySurface:2016 com.android.server.wm.WindowStateAnimator.destroySurfaceLocked:882 com.android.server.wm.WindowState.removeLocked:1456 com.android.server.wm.WindowManagerService.removeWindowInnerLocked:2484 com.android.server.wm.WindowManagerService.removeWindowLocked:2442 com.android.server.wm.WindowManagerService.removeWindowLocked:2311 com.android.server.wm.WindowManagerService.removeWindow:2306 com.android.server.wm.Session.remove:202 
07-19 20:39:43.832 8206-8362/fr.free.nrw.commons.debug I/System.out: HTTP Response (11): 200 Date=Thu, 19 Jul 2018 10:39:44 GMT Content-Type=text/xml; charset=utf-8 Transfer-Encoding=chunked Connection=keep-alive Server=mw1223.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Set-Cookie=commonswikiSession=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; secure; httponly Set-Cookie=commonswikiUserID=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; secure; httponly Set-Cookie=centralauth_User=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; domain=commons.wikimedia.org; secure; httponly Set-Cookie=centralauth_Token=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; domain=commons.wikimedia.org; secure; httponly Set-Cookie=centralauth_Session=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; domain=commons.wikimedia.org; secure; httponly Backend-Timing=D=29180 t=1531996783911537 X-Varnish=533358476, 239174201, 888076149, 93520407 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1067 pass, cp2013 pass, cp5010 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload Set-Cookie=WMF-Last-Access=19-Jul-2018;Path=/;HttpOnly;secure;Expires=Mon, 20 Aug 2018 00:00:00 GMT X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=16-Jul-2018;https=1 X-Client-IP=censored
07-19 20:39:43.835 8206-8362/fr.free.nrw.commons.debug D/ModificationsSyncAdapte: Found 1 modifications to execute
07-19 20:39:44.504 8206-8353/fr.free.nrw.commons.debug I/System.out: HTTP Response (12): 200 Date=Thu, 19 Jul 2018 10:39:44 GMT Content-Type=text/xml; charset=utf-8 Content-Length=150 Connection=keep-alive Server=mw1279.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=33603 t=1531996784574310 X-Varnish=425009977, 693040117, 37957380, 1065516529 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1054 pass, cp2016 pass, cp5012 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=19-Jul-2018;https=1 X-Client-IP=censored
07-19 20:39:44.508 8206-8353/fr.free.nrw.commons.debug I/System.out: HTTP Request (13): GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=City+circle+tram+melbourne&iiprop=url&action=query&format=xml&format=xml
07-19 20:39:45.072 8206-8353/fr.free.nrw.commons.debug I/System.out: HTTP Response (13): 200 Date=Thu, 19 Jul 2018 10:39:45 GMT Content-Type=text/xml; charset=utf-8 Content-Length=150 Connection=keep-alive Server=mw1348.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=40398 t=1531996785139712 X-Varnish=422328334, 697190407, 320231444, 83814918 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1054 pass, cp2016 pass, cp5009 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=19-Jul-2018;https=1 X-Client-IP=censored
07-19 20:39:45.408 8206-8264/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1111790 of 2211252
07-19 20:39:45.421 8206-8264/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1181422 of 2211252
07-19 20:39:45.441 8206-8264/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1251054 of 2211252
07-19 20:39:45.445 8206-8354/fr.free.nrw.commons.debug I/System.out: HTTP Request (14): GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=City+circle+tram+melbourne&iiprop=url&action=query&format=xml&format=xml
07-19 20:39:45.885 8206-8264/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1320686 of 2211252
07-19 20:39:45.902 8206-8264/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1390318 of 2211252
07-19 20:39:45.914 8206-8264/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1459950 of 2211252
07-19 20:39:46.009 8206-8354/fr.free.nrw.commons.debug I/System.out: HTTP Response (14): 200 Date=Thu, 19 Jul 2018 10:39:46 GMT Content-Type=text/xml; charset=utf-8 Content-Length=150 Connection=keep-alive Server=mw1314.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=35075 t=1531996786086988 X-Varnish=428084233, 697779329, 764794117, 90549928 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1054 pass, cp2016 pass, cp5011 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=19-Jul-2018;https=1 X-Client-IP=censored
07-19 20:39:46.013 8206-8354/fr.free.nrw.commons.debug I/System.out: HTTP Request (15): GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=City+circle+tram+melbourne&iiprop=url&action=query&format=xml&format=xml
07-19 20:39:46.014 8206-8211/fr.free.nrw.commons.debug I/art: Do full code cache collection, code=117KB, data=112KB
    After code cache collection, code=106KB, data=83KB
07-19 20:39:46.582 8206-8230/fr.free.nrw.commons.debug I/art: Starting a blocking GC Explicit
07-19 20:39:46.595 8206-8230/fr.free.nrw.commons.debug I/art: Explicit concurrent mark sweep GC freed 22348(1854KB) AllocSpace objects, 18(9MB) LOS objects, 16% free, 20MB/24MB, paused 674us total 12.463ms
07-19 20:39:46.604 8206-8264/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1529582 of 2211252
07-19 20:39:47.319 8206-8354/fr.free.nrw.commons.debug I/System.out: HTTP Response (15): 200 Date=Thu, 19 Jul 2018 10:39:47 GMT Content-Type=text/xml; charset=utf-8 Content-Length=150 Connection=keep-alive Server=mw1280.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=26266 t=1531996787405485 X-Varnish=423446554, 807834238, 319739479, 92019927 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1054 pass, cp2023 pass, cp5009 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=19-Jul-2018;https=1 X-Client-IP=censored
07-19 20:39:47.322 8206-8354/fr.free.nrw.commons.debug I/System.out: HTTP Request (16): GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=City+circle+tram+melbourne&iiprop=url&action=query&format=xml&format=xml
07-19 20:39:49.417 8206-8354/fr.free.nrw.commons.debug I/System.out: HTTP Response (16): 200 Date=Thu, 19 Jul 2018 10:39:49 GMT Content-Type=text/xml; charset=utf-8 Transfer-Encoding=chunked Connection=keep-alive Server=mw1289.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=34739 t=1531996789501628 X-Varnish=419939725, 801763377, 319739579, 93487873 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1054 pass, cp2023 pass, cp5009 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=19-Jul-2018;https=1 X-Client-IP=censored
07-19 20:39:49.420 8206-8354/fr.free.nrw.commons.debug I/System.out: HTTP Request (17): GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=City+circle+tram+melbourne&iiprop=url&action=query&format=xml&format=xml
07-19 20:39:49.480 8206-8264/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1599214 of 2211252
07-19 20:39:49.496 8206-8264/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1668846 of 2211252
07-19 20:39:49.940 8206-8354/fr.free.nrw.commons.debug I/System.out: HTTP Response (17): 200 Date=Thu, 19 Jul 2018 10:39:50 GMT Content-Type=text/xml; charset=utf-8 Content-Length=150 Connection=keep-alive Server=mw1280.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=37073 t=1531996790021310 X-Varnish=428639915, 697355302, 639043256, 86654124 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1054 pass, cp2016 pass, cp5008 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=19-Jul-2018;https=1 X-Client-IP=censored
07-19 20:39:49.943 8206-8354/fr.free.nrw.commons.debug I/System.out: HTTP Request (18): GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=City+circle+tram+melbourne&iiprop=url&action=query&format=xml&format=xml
07-19 20:39:50.470 8206-8354/fr.free.nrw.commons.debug I/System.out: HTTP Response (18): 200 Date=Thu, 19 Jul 2018 10:39:50 GMT Content-Type=text/xml; charset=utf-8 Content-Length=150 Connection=keep-alive Server=mw1235.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=25392 t=1531996790549102 X-Varnish=422110683, 232811275, 764794384, 87569646 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1054 pass, cp2013 pass, cp5011 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=19-Jul-2018;https=1 X-Client-IP=censored
07-19 20:39:52.857 8206-8264/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1738478 of 2211252
07-19 20:39:52.871 8206-8264/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1808110 of 2211252
07-19 20:39:52.910 8206-8353/fr.free.nrw.commons.debug I/System.out: HTTP Request (19): GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=City+circle+tram+melbourne&iiprop=url&action=query&format=xml&format=xml
07-19 20:39:53.432 8206-8353/fr.free.nrw.commons.debug I/System.out: HTTP Response (19): 200 Date=Thu, 19 Jul 2018 10:39:53 GMT Content-Type=text/xml; charset=utf-8 Content-Length=150 Connection=keep-alive Server=mw1317.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=28172 t=1531996793512064 X-Varnish=410648344, 999865562, 636007906, 92934595 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1054 pass, cp2010 pass, cp5008 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=19-Jul-2018;https=1 X-Client-IP=censored
07-19 20:39:54.292 8206-8264/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1877742 of 2211252
07-19 20:39:54.325 8206-8354/fr.free.nrw.commons.debug I/System.out: HTTP Request (20): GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=City+circle+tram+melbourne&iiprop=url&action=query&format=xml&format=xml
07-19 20:39:54.860 8206-8354/fr.free.nrw.commons.debug I/System.out: HTTP Response (20): 200 Date=Thu, 19 Jul 2018 10:39:55 GMT Content-Type=text/xml; charset=utf-8 Content-Length=150 Connection=keep-alive Server=mw1347.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=29711 t=1531996794934681 X-Varnish=420436966, 699181737, 888096512, 92052358 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1054 pass, cp2016 pass, cp5010 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=19-Jul-2018;https=1 X-Client-IP=censored
07-19 20:39:54.863 8206-8354/fr.free.nrw.commons.debug I/System.out: HTTP Request (21): GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=City+circle+tram+melbourne&iiprop=url&action=query&format=xml&format=xml
07-19 20:39:55.417 8206-8354/fr.free.nrw.commons.debug I/System.out: HTTP Response (21): 200 Date=Thu, 19 Jul 2018 10:39:55 GMT Content-Type=text/xml; charset=utf-8 Transfer-Encoding=chunked Connection=keep-alive Server=mw1282.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=26062 t=1531996795499744 X-Varnish=425730636, 807617199, 636462789, 93227114 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1054 pass, cp2023 pass, cp5008 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=19-Jul-2018;https=1 X-Client-IP=censored
07-19 20:39:55.490 8206-8264/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 1947374 of 2211252
07-19 20:39:55.503 8206-8264/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 2017006 of 2211252
07-19 20:39:55.540 8206-8353/fr.free.nrw.commons.debug I/System.out: HTTP Request (22): GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=City+circle+tram+melbourne&iiprop=url&action=query&format=xml&format=xml
07-19 20:39:56.092 8206-8353/fr.free.nrw.commons.debug I/System.out: HTTP Response (22): 200 Date=Thu, 19 Jul 2018 10:39:56 GMT Content-Type=text/xml; charset=utf-8 Content-Length=150 Connection=keep-alive Server=mw1288.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=50760 t=1531996796150014 X-Varnish=413891828, 697978223, 380802908, 90580242 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1054 pass, cp2016 pass, cp5007 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=19-Jul-2018;https=1 X-Client-IP=censored
07-19 20:39:56.097 8206-8353/fr.free.nrw.commons.debug I/System.out: HTTP Request (23): GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=City+circle+tram+melbourne&iiprop=url&action=query&format=xml&format=xml
07-19 20:39:56.452 8206-8264/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 2086638 of 2211252
07-19 20:39:56.463 8206-8264/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 2156270 of 2211252
07-19 20:39:56.639 8206-8353/fr.free.nrw.commons.debug I/System.out: HTTP Response (23): 200 Date=Thu, 19 Jul 2018 10:39:56 GMT Content-Type=text/xml; charset=utf-8 Transfer-Encoding=chunked Connection=keep-alive Server=mw1316.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=27847 t=1531996796722056 X-Varnish=425500881, 998685353, 895201435, 89348906 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1054 pass, cp2010 pass, cp5010 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=19-Jul-2018;https=1 X-Client-IP=censored
07-19 20:39:56.644 8206-8353/fr.free.nrw.commons.debug I/System.out: HTTP Request (24): GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=City+circle+tram+melbourne&iiprop=url&action=query&format=xml&format=xml
07-19 20:39:57.220 8206-8353/fr.free.nrw.commons.debug I/System.out: HTTP Response (24): 200 Date=Thu, 19 Jul 2018 10:39:57 GMT Content-Type=text/xml; charset=utf-8 Transfer-Encoding=chunked Connection=keep-alive Server=mw1312.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=43282 t=1531996797285031 X-Varnish=422983832, 235976422, 892817518, 88782342 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1054 pass, cp2013 pass, cp5010 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=19-Jul-2018;https=1 X-Client-IP=censored
07-19 20:39:57.413 8206-8264/fr.free.nrw.commons.debug D/UploadService$Notificat: Uploaded 2211252 of 2211252
07-19 20:39:57.441 8206-8354/fr.free.nrw.commons.debug I/System.out: HTTP Request (25): GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=City+circle+tram+melbourne&iiprop=url&action=query&format=xml&format=xml
07-19 20:39:58.029 8206-8354/fr.free.nrw.commons.debug I/System.out: HTTP Response (25): 200 Date=Thu, 19 Jul 2018 10:39:58 GMT Content-Type=text/xml; charset=utf-8 Content-Length=150 Connection=keep-alive Server=mw1280.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=33983 t=1531996798102563 X-Varnish=423214431, 807214214, 764529291, 76005905 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1054 pass, cp2023 pass, cp5011 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=19-Jul-2018;https=1 X-Client-IP=censored
07-19 20:39:58.031 8206-8354/fr.free.nrw.commons.debug I/System.out: HTTP Request (26): GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=City+circle+tram+melbourne&iiprop=url&action=query&format=xml&format=xml
07-19 20:39:58.575 8206-8354/fr.free.nrw.commons.debug I/System.out: HTTP Response (26): 200 Date=Thu, 19 Jul 2018 10:39:58 GMT Content-Type=text/xml; charset=utf-8 Transfer-Encoding=chunked Connection=keep-alive Server=mw1341.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=37444 t=1531996798652084 X-Varnish=416867134, 86976070, 891968316, 91468574 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1054 pass, cp2019 pass, cp5010 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=19-Jul-2018;https=1 X-Client-IP=censored
07-19 20:40:08.058 8206-8264/fr.free.nrw.commons.debug I/System.out: HTTP Response (10): 200 Date=Thu, 19 Jul 2018 10:40:08 GMT Content-Type=text/xml; charset=utf-8 Transfer-Encoding=chunked Connection=keep-alive Server=mw1290.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev MediaWiki-API-Error=mustbeloggedin P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=1420121 t=1531996806743182 X-Varnish=533185267, 237574482, 29548055, 83815502 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1067 pass, cp2013 pass, cp5012 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=WMF-Last-Access=19-Jul-2018;https=1 X-Client-IP=censored
07-19 20:40:08.100 8206-8264/fr.free.nrw.commons.debug E/WTF: Result: org.mediawiki.api.ApiResult@fa09ea
07-19 20:40:08.102 8206-8264/fr.free.nrw.commons.debug E/ApacheHttpClientMediaWi: mustbeloggedin
07-19 20:40:08.102 8206-8264/fr.free.nrw.commons.debug D/UploadService: Response is fr.free.nrw.commons.mwapi.UploadResult@e77b4db
    Contribution upload failed. Wikidata entity won't be edited
07-19 20:40:08.162 8206-8368/fr.free.nrw.commons.debug I/System.out: HTTP Request (27): GET https://commons.wikimedia.org/w/api.php?type=edit&action=tokens&format=xml
07-19 20:40:08.176 8206-8353/fr.free.nrw.commons.debug I/System.out: HTTP Request (28): GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=City+circle+tram+melbourne&iiprop=url&action=query&format=xml&format=xml
07-19 20:40:08.686 8206-8368/fr.free.nrw.commons.debug I/System.out: HTTP Response (27): 200 Date=Thu, 19 Jul 2018 10:40:08 GMT Content-Type=text/xml; charset=utf-8 Content-Length=510 Connection=keep-alive Server=mw1339.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Set-Cookie=commonswikiSession=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; secure; httponly Set-Cookie=commonswikiUserID=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; secure; httponly Set-Cookie=centralauth_User=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; domain=commons.wikimedia.org; secure; httponly Set-Cookie=centralauth_Token=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; domain=commons.wikimedia.org; secure; httponly Set-Cookie=centralauth_Session=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; domain=commons.wikimedia.org; secure; httponly Backend-Timing=D=29461 t=1531996808768973 X-Varnish=532149927, 237441545, 324995749, 87938652 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1067 pass, cp2013 pass, cp5009 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload Set-Cookie=WMF-Last-Access=19-Jul-2018;Path=/;HttpOnly;secure;Expires=Mon, 20 Aug 2018 00:00:00 GMT X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=16-Jul-2018;https=1 X-Client-IP=censored
07-19 20:40:08.689 8206-8368/fr.free.nrw.commons.debug D/ModificationsSyncAdapte: Found 1 modifications to execute
07-19 20:40:08.726 8206-8353/fr.free.nrw.commons.debug I/System.out: HTTP Response (28): 200 Date=Thu, 19 Jul 2018 10:40:08 GMT Content-Type=text/xml; charset=utf-8 Content-Length=150 Connection=keep-alive Server=mw1313.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Set-Cookie=commonswikiSession=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; secure; httponly Set-Cookie=commonswikiUserID=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; secure; httponly Set-Cookie=centralauth_User=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; domain=commons.wikimedia.org; secure; httponly Set-Cookie=centralauth_Token=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; domain=commons.wikimedia.org; secure; httponly Set-Cookie=centralauth_Session=deleted; expires=Thu, 01-Jan-1970 00:00:01 GMT; Max-Age=0; path=/; domain=commons.wikimedia.org; secure; httponly Backend-Timing=D=37540 t=1531996808790605 X-Varnish=417355105, 695133436, 20019718, 91042042 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1054 pass, cp2016 pass, cp5012 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload Set-Cookie=WMF-Last-Access=19-Jul-2018;Path=/;HttpOnly;secure;Expires=Mon, 20 Aug 2018 00:00:00 GMT X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=16-Jul-2018;https=1 X-Client-IP=censored
07-19 20:40:08.729 8206-8353/fr.free.nrw.commons.debug I/System.out: HTTP Request (29): GET https://commons.wikimedia.org/w/api.php?iiurlwidth=640&prop=imageinfo&titles=City+circle+tram+melbourne&iiprop=url&action=query&format=xml&format=xml
07-19 20:40:09.252 8206-8353/fr.free.nrw.commons.debug I/System.out: HTTP Response (29): 200 Date=Thu, 19 Jul 2018 10:40:09 GMT Content-Type=text/xml; charset=utf-8 Transfer-Encoding=chunked Connection=keep-alive Server=mw1230.eqiad.wmnet X-Powered-By=HHVM/3.18.6-dev P3P=CP="This is not a P3P policy! See https://commons.wikimedia.org/wiki/Special:CentralAutoLogin/P3P for more info." Cache-control=private, must-revalidate, max-age=0 Vary=Accept-Encoding,Treat-as-Untrusted,X-Forwarded-Proto,Cookie,Authorization Content-Disposition=inline; filename=api-result.xml X-Content-Type-Options=nosniff X-Frame-Options=DENY Backend-Timing=D=30661 t=1531996809332907 X-Varnish=414054915, 697425131, 634470493, 93391893 Via=1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1), 1.1 varnish (Varnish/5.1) Accept-Ranges=bytes Age=0 X-Cache=cp1054 pass, cp2016 pass, cp5008 pass, cp5007 pass X-Cache-Status=pass Strict-Transport-Security=max-age=106384710; includeSubDomains; preload X-Analytics=ns=-1;special=Badtitle;WMF-Last-Access=19-Jul-2018;https=1 X-Client-IP=censored

nicolas-raoul commented 6 years ago

Request (5): GET
Response (5): 200
Request (6): GET
Response (6): 200
Request (7): GET
Request (8): GET
Response (7): 200
Request (9): GET
Response (8): 200
Response (9): 200
Request (10): POST
Request (11): GET
Request (12): GET
Response (11): 200
Response (12): 200
Request (13): GET
Response (13): 200
Request (14): GET
Response (14): 200
Request (15): GET
Response (15): 200
Request (16): GET
Response (16): 200
Request (17): GET
Response (17): 200
Request (18): GET
Response (18): 200
Request (19): GET
Response (19): 200
Request (20): GET
Response (20): 200
Request (21): GET
Response (21): 200
Request (22): GET
Response (22): 200
Request (23): GET
Response (23): 200
Request (24): GET
Response (24): 200
Request (25): GET
Response (25): 200
Request (26): GET
Response (26): 200
Response (10): 200
Request (27): GET
Request (28): GET
Response (27): 200
Response (28): 200
Request (29): GET
Response (29): 200

All requests have their response. The response just before WTF/mustbeloggedin is the response to request 10, the POST.

maskaravivek commented 6 years ago

@nicolas-raoul The request body and headers are not being logged for POST requests by the logging library. Would it be possible for you to add these logs to the library?

nicolas-raoul commented 6 years ago

@maskaravivek The strange thing is that I have log the headers, and they appear when I run tests on Ubuntu, but then the headers do not show up on Android. Do you really want the body of the requests? GET requests do not have a body, and for the POST request it is presumably just the image. I will try adding that tomorrow. https://github.com/nicolas-raoul/java-http-fluent/blob/master/src/main/java/in/yuvi/http/fluent/Http.java#L476

misaochan commented 6 years ago

@nicolas-raoul @maskaravivek Should I re-test with the latest commit?

nicolas-raoul commented 6 years ago

Sorry I had a look yesterday but adding the body is not easy as the code that code that is in yet another library :-/

maskaravivek commented 6 years ago

The headers are the most important thing as per my discussion with Tgr. He asked for the request body as it wasn't visible in the logs but the issue is likely to be in just how the headers are being sent.

@nicolas-raoul Have you already added the headers? Does it show up in logcat?

nicolas-raoul commented 6 years ago

@maskaravivek In the first version already I had implemented showing headers, and they show up when I test on Linux/Eclipse, but quite incredibly they do not show up when running on Android. Maybe the underlying implementation of some low-level networking Java classes is different.

maskaravivek commented 6 years ago

In that case, we should consider other options to debug the HTTP traffic from the app. I came across a few tools that let you do so.

I have personally used Fiddler in the past and have found it to be very easy to use. @misaochan Could you try out Fiddler or any other HTTP traffic debugger to reproduce the scenario.

nicolas-raoul commented 6 years ago

@maskaravivek Good ideas! I wonder whether any Commons server (maybe the beta one?) is accessible via HTTP (not HTTPS) that would simplify things.

maskaravivek commented 6 years ago

Fiddler seems to support debugging of HTTPS traffic as well. Moreover, the second link that I mentioned also supports HTTPS traffic debugging after installing a custom SSL certificate. :)

commons-app / apps-android-commons

Upload fails often in 2.7 #1485