Error displayed when do a cache log #6682

viperbruno · 2017-08-15T00:32:41Z

Detailed steps causing the problem:

Open a geocache
Log as "found it"
When you are submitting it display an error saying "login failed"
Reload geocache information, you see that the log was submitted successfully

It begin to appear, after new Geocaching.com layout.

Expected behavior after performing these steps:

Version of c:geo used:

Is the problem reproducible for you?

Yes/No

System information:

Attach system information here if available (see c:geo menu -> About c:geo -> Swipe right to System)
Keep the apostrophe at beginning and end to have it properly formatted

Other comments and remarks:

e.g. Reference to other issues, projects, sources, etc.

The text was updated successfully, but these errors were encountered:

cnlson · 2017-08-15T03:31:15Z

i have the same issue, worked yesterday, does not work today.
I did confirm that cgeo has the correct login and is authorized.

Version of c:geo used: 2017.07.24

Is the problem reproducible for you? Yes, occurs everytime. Interestingly, when I kept trying to submit, after the first found log, it changed to a dnf log for each of the 8 or so times I tried after, before i checked and saw what happened.

Yes/No

System information:
--- System information ---
Device: SM-N910V (trltevzw, Verizon)
Android version: 6.0.1
Android build: MMB29M.N910VVRU2CPF3
c:geo version: 2017.07.24
Google Play services: enabled - 11.3.02 (430-161239932)
Low power mode: inactive
Compass capabilities: yes
Rotation vector sensor: present
Orientation sensor: present
Magnetometer & Accelerometer sensor: present
Direction sensor used: rotation vector
Hide own/found: false
Map strategy: detailed
HW acceleration: enabled (default state)
System language: en_US
System date format: M/d/yy
Debug mode active: no
System internal c:geo dir: /data/user/0/cgeo.geocaching (3.3 GB free) internal
User storage c:geo dir: /storage/emulated/0/cgeo (3.3 GB free) external non-removable
Geocache data: /storage/9C33-6BBD/Android/data/cgeo.geocaching/files/GeocacheData (52.9 GB free) external removable
Database: /storage/emulated/0/Android/data/cgeo.geocaching/files/databases/data (2.4 MB) on user storage
Fine location permission: granted
Write external storage permission: granted
Geocaching sites enabled:
geocaching.com: Logged in (Login OK) / PREMIUM
Geocaching.com date format: MM/dd/yyyy
Installed c:geo plugins: none
--- End of system information ---

Bananeweizen · 2017-08-15T07:20:22Z

the geocaching.com server was off during that time, therefore this might be connected to just the server not available. https://twitter.com/GoGeocaching/status/897251827212746754

Can someone recheck this now?

cnlson · 2017-08-15T07:26:16Z

I can confirm it is still occurring

Lineflyer · 2017-08-15T07:50:33Z

Confirmed with latest nightly!

Error message in c:geo as shown above but logging is OK on the website.

Lineflyer · 2017-08-15T07:56:56Z

Here is a debug log (when debug logging is active, c:geo is also crashing while trying to post the log).
Sorry, I did not have time to clean up the log:

08-15 09:55:32.349  5063 24578 I ActivityManager: START u0 {act=null typ=null flg=0x0 cmp=ComponentInfo{cgeo.geocaching/cgeo.geocaching.log.LogCacheActivity}} from uid 10351 on display 0
08-15 09:55:32.355  5063 24578 I MultiWindowManagerService: setTaskDimensions: Task=TaskRecord{81d8ae7d0 #9997 A=cgeo.geocaching U=0 StackId=1 sz=3} minWidth=-1 minHeight=-1 maxWidth=-1 maxHeight=-1
08-15 09:55:32.356  5063 24578 D ActivityManager: moveToFront() : reason=startedActivity setFocusedActivity isAttached=true TaskRecord{81d8ae7d0 #9997 A=cgeo.geocaching U=0 StackId=1 sz=4}
08-15 09:55:32.357  5063 24578 D ActivityManager: resumeTopActivityInnerLocked() : #1 prevTask=TaskRecord{81d8ae7d0 #9997 A=cgeo.geocaching U=0 StackId=1 sz=4} next=ActivityRecord{69015a6d0 u0 cgeo.geocaching/.log.LogCacheActivity t9997} mFocusedStack=ActivityStack{992170ad0 stackId=1, 25 tasks}
08-15 09:55:32.379  5063  8581 D ActivityManager: resumeTopActivityInnerLocked() : #1 prevTask=TaskRecord{81d8ae7d0 #9997 A=cgeo.geocaching U=0 StackId=1 sz=4} next=ActivityRecord{69015a6d0 u0 cgeo.geocaching/.log.LogCacheActivity t9997} mFocusedStack=ActivityStack{992170ad0 stackId=1, 25 tasks}
08-15 09:55:32.385  5063  5063 D GameManagerService: NotifyRunnable. pkg: cgeo.geocaching, type: 4, isMinimized: false, isTunableApp: false
08-15 09:55:32.402  5063  5401 D SecContentProvider: called from cgeo.geocaching
08-15 09:55:32.451 14984 15111 D cgeo    : [network--10] GeokretyConnector.loadInventory: userid=0
08-15 09:55:32.452 14984 15047 D cgeo    : [OkHttp] GET https://geokrety.org/export2.php?inventory=1&secid=QIWqAUTrsUeNX2bCOgzVzYtNazAzVVTldgd42QCKkNUheNRkMwDpukkaSu9IaEBX7oip132Q5RMBjJfjPD7RaLlDx71sOSc70Icch6FvNQG3UWxl2I8kExGT8AVDZNJ5
08-15 09:55:32.459 14984 14984 D InputMethodManager: ISS - flag : 0Pid : 14984 view : cgeo.geocaching
08-15 09:55:32.461 14984 15045 D cgeo    : [OkHttp] GET https://www.geocaching.com/seek/log.aspx?ID=2511222
08-15 09:55:32.485  5063 24577 V WindowManager: Relayout Window{2da337ed0 u0 cgeo.geocaching/cgeo.geocaching.log.LogCacheActivity}: viewVisibility=0 req=1439x2475 WM.LayoutParams{(0,0)(fillxfill) sim=#110 ty=1 fl=#1810100 wanim=0x10303ea needsMenuKey=2 naviIconColor=0}
08-15 09:55:32.554  5063  6278 D WindowManager: finishDrawingWindow: Window{2da337ed0 u0 cgeo.geocaching/cgeo.geocaching.log.LogCacheActivity} mDrawState=DRAW_PENDING
08-15 09:55:32.556 14984 14984 V InputMethodManager: Starting input: tba=android.view.inputmethod.EditorInfo@d699d98 nm : cgeo.geocaching ic=com.android.internal.widget.EditableInputConnection@f8c99f1
08-15 09:55:32.560  5063  5165 I ActivityManager: Displayed cgeo.geocaching/.log.LogCacheActivity: +180ms
08-15 09:55:32.562 13887 13887 I SamsungIME: [IMI] onStartInput - caller packageName : cgeo.geocaching
08-15 09:55:32.578  5063 14109 D WindowManager: finishDrawingWindow: Window{2da337ed0 u0 cgeo.geocaching/cgeo.geocaching.log.LogCacheActivity} mDrawState=HAS_DRAWN
08-15 09:55:32.584  5063 14113 V WindowManager: Relayout Window{442b37d0 u0 cgeo.geocaching/cgeo.geocaching.CacheDetailActivity}: viewVisibility=8 req=1440x2560 WM.LayoutParams{(0,0)(fillxfill) sim=#10 ty=1 fl=#1810100 wanim=0x10303ea needsMenuKey=2 naviIconColor=0}
08-15 09:55:32.638  5063  5165 I WindowManager: Destroying surface Surface(name=cgeo.geocaching/cgeo.geocaching.CacheDetailActivity) called by com.android.server.wm.WindowStateAnimator.destroySurface:2838 com.android.server.wm.WindowStateAnimator.destroySurfaceLocked:1069 com.android.server.wm.WindowState.destroyOrSaveSurface:2534 com.android.server.wm.AppWindowToken.destroySurfaces:412 com.android.server.wm.AppWindowToken.destroySurfaces:376 com.android.server.wm.WindowStateAnimator.finishExit:654 com.android.server.wm.WindowStateAnimator.stepAnimationLocked:564 com.android.server.wm.WindowAnimator.updateWindowsLocked:439 
08-15 09:55:32.781  5063  6714 V WindowManager: Relayout Window{2da337ed0 u0 cgeo.geocaching/cgeo.geocaching.log.LogCacheActivity}: viewVisibility=0 req=1440x2560 WM.LayoutParams{(0,0)(fillxfill) sim=#110 ty=1 fl=#1810100 wanim=0x10303ea needsMenuKey=2 naviIconColor=0}
08-15 09:55:32.911 14984 15045 D cgeo    : [OkHttp] 200 (450 ms) GET https://www.geocaching.com/seek/log.aspx?ID=2511222 (http/1.1)
08-15 09:55:32.966  5063  5165 I WindowManager: Destroying surface Surface(name=cgeo.geocaching/cgeo.geocaching.log.LogCacheActivity_dim) called by com.android.server.wm.WindowStateAnimator.finishExit:587 com.android.server.wm.AppWindowAnimator.stepAnimationLocked:493 com.android.server.wm.WindowAnimator.updateAppWindowsLocked:227 com.android.server.wm.WindowAnimator.animateLocked:919 com.android.server.wm.WindowAnimator.-wrap0:-1 com.android.server.wm.WindowAnimator$1.doFrame:180 android.view.Choreographer$CallbackRecord.run:925 android.view.Choreographer.doCallbacks:702 
08-15 09:55:33.014  5063  5708 V WindowManager: Relayout Window{442b37d0 u0 cgeo.geocaching/cgeo.geocaching.CacheDetailActivity}: viewVisibility=8 req=1440x2560 WM.LayoutParams{(0,0)(fillxfill) sim=#20 ty=1 fl=#1810100 wanim=0x10303ea needsMenuKey=2 naviIconColor=0}
08-15 09:55:33.014  5063  5708 I WindowManager: Destroying surface Surface(name=cgeo.geocaching/cgeo.geocaching.CacheDetailActivity) called by com.android.server.wm.WindowStateAnimator.destroySurface:2838 com.android.server.wm.WindowStateAnimator.destroySurfaceLocked:1069 com.android.server.wm.WindowState.destroyOrSaveSurface:2534 com.android.server.wm.WindowManagerService.tryStartExitingAnimation:3504 com.android.server.wm.WindowManagerService.relayoutWindow:3360 com.android.server.wm.Session.relayoutEx:244 android.view.IWindowSession$Stub.onTransact:407 com.android.server.wm.Session.onTransact:151 
08-15 09:55:34.879 14984 15004 D cgeo    : [looper callbacks] LocationProvider: stopping location requests
08-15 09:55:35.447  6202 15136 D FusedRequestManager_FLP: manageRemoveGmsRequest, remove high_power request from cgeo.geocaching through GooglePlayService
08-15 09:55:35.799 14984 15047 D cgeo    : [OkHttp] 200 (3346 ms) GET https://geokrety.org/export2.php?inventory=1&secid=QIWqAUTrsUeNX2bCOgzVzYtNazAzVVTldgd42QCKkNUheNRkMwDpukkaSu9IaEBX7oip132Q5RMBjJfjPD7RaLlDx71sOSc70Icch6FvNQG3UWxl2I8kExGT8AVDZNJ5 (http/1.1)
08-15 09:55:39.203 14984 15114 I cgeo    : [AsyncTask #3] Trying to post log for cache #2511222 - action: NOTE; date: 2017.8.15, log: test; trackables: 11
08-15 09:55:39.226 14984 15047 D cgeo    : [OkHttp] POST https://www.geocaching.com/seek/log.aspx?ID=2511222
08-15 09:55:43.824 14984 15047 D cgeo    : [OkHttp] 200 (4598 ms) POST https://www.geocaching.com/seek/log.aspx?ID=2511222 (http/1.1)
08-15 09:55:44.418 14984 15047 D cgeo    : [OkHttp] GET https://www.geocaching.com/account/login
08-15 09:55:45.405 14984 15047 D cgeo    : [OkHttp] 200 (987 ms) GET https://www.geocaching.com/account/login (http/1.1) (=> https://www.geocaching.com/play/search)
08-15 09:55:46.349 14984 15114 I cgeo    : [AsyncTask #3] Already logged in Geocaching.com as Lineflyer (PREMIUM)
08-15 09:55:46.428 14984 15114 I cgeo    : [AsyncTask #3] Geocaching.com language already set to English
08-15 09:55:46.431 14984 15047 D cgeo    : [OkHttp] GET https://www.geocaching.com/account/settings/homelocation
08-15 09:55:46.431 14984 15045 D cgeo    : [OkHttp] GET https://www.geocaching.com/account/settings/membership
08-15 09:55:46.432 14984 15036 D cgeo    : [OkHttp] GET https://www.geocaching.com/account/settings/preferences
08-15 09:55:46.773 14984 15045 D cgeo    : [OkHttp] 200 (341 ms) GET https://www.geocaching.com/account/settings/membership (http/1.1)
08-15 09:55:46.990 14984 15045 D cgeo    : [OkHttp] Setting member status to PREMIUM
08-15 09:55:47.154 14984 15047 D cgeo    : [OkHttp] 200 (723 ms) GET https://www.geocaching.com/account/settings/homelocation (http/1.1)
08-15 09:55:47.583 14984 15036 D cgeo    : [OkHttp] 200 (1151 ms) GET https://www.geocaching.com/account/settings/preferences (http/1.1)
08-15 09:55:47.970 14984 15114 D cgeo    : [AsyncTask #3] Setting GC custom date to d.MM.yyyy
08-15 09:55:47.976 14984 15036 D cgeo    : [OkHttp] POST https://www.geocaching.com/seek/log.aspx?ID=2511222
08-15 09:55:52.186 14984 15036 D cgeo    : [OkHttp] 200 (4209 ms) POST https://www.geocaching.com/seek/log.aspx?ID=2511222 (http/1.1)
08-15 09:55:52.790 14984 15114 E cgeo    : [AsyncTask #3] GCParser.postLog: Cannot log in geocaching
08-15 09:55:52.793 14984 15114 E cgeo    : [AsyncTask #3] GCLoggingManager.postLog
08-15 09:55:52.793 14984 15114 E cgeo    : java.lang.RuntimeException: Aborting on Log.e()
08-15 09:55:52.793 14984 15114 E cgeo    : 	at cgeo.geocaching.utils.Log.e(Log.java:98)
08-15 09:55:52.793 14984 15114 E cgeo    : 	at cgeo.geocaching.connector.gc.GCParser.postLog(GCParser.java:1168)
08-15 09:55:52.793 14984 15114 E cgeo    : 	at cgeo.geocaching.connector.gc.GCLoggingManager.postLog(GCLoggingManager.java:105)
08-15 09:55:52.793 14984 15114 E cgeo    : 	at cgeo.geocaching.log.LogCacheActivity$Poster.doInBackgroundInternal(LogCacheActivity.java:550)
08-15 09:55:52.793 14984 15114 E cgeo    : 	at cgeo.geocaching.log.LogCacheActivity$Poster.doInBackgroundInternal(LogCacheActivity.java:536)
08-15 09:55:52.793 14984 15114 E cgeo    : 	at cgeo.geocaching.utils.AbstractAsyncTaskWithProgress.doInBackground(AbstractAsyncTaskWithProgress.java:132)
08-15 09:55:52.793 14984 15114 E cgeo    : 	at android.os.AsyncTask$2.call(AsyncTask.java:304)
08-15 09:55:52.793 14984 15114 E cgeo    : 	at java.util.concurrent.FutureTask.run(FutureTask.java:237)
08-15 09:55:52.793 14984 15114 E cgeo    : 	at android.os.AsyncTask$SerialExecutor$1.run(AsyncTask.java:243)
08-15 09:55:52.793 14984 15114 E cgeo    : 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1133)
08-15 09:55:52.793 14984 15114 E cgeo    : 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:607)
08-15 09:55:52.793 14984 15114 E cgeo    : 	at java.lang.Thread.run(Thread.java:762)
08-15 09:55:52.795 14984 15114 E cgeo    : [AsyncTask #3] LogCacheActivity.Poster.doInBackgroundInternal
08-15 09:55:52.795 14984 15114 E cgeo    : java.lang.RuntimeException: Aborting on Log.e()
08-15 09:55:52.795 14984 15114 E cgeo    : 	at cgeo.geocaching.utils.Log.e(Log.java:98)
08-15 09:55:52.795 14984 15114 E cgeo    : 	at cgeo.geocaching.connector.gc.GCParser.postLog(GCParser.java:1168)
08-15 09:55:52.795 14984 15114 E cgeo    : 	at cgeo.geocaching.connector.gc.GCLoggingManager.postLog(GCLoggingManager.java:105)
08-15 09:55:52.795 14984 15114 E cgeo    : 	at cgeo.geocaching.log.LogCacheActivity$Poster.doInBackgroundInternal(LogCacheActivity.java:550)
08-15 09:55:52.795 14984 15114 E cgeo    : 	at cgeo.geocaching.log.LogCacheActivity$Poster.doInBackgroundInternal(LogCacheActivity.java:536)
08-15 09:55:52.795 14984 15114 E cgeo    : 	at cgeo.geocaching.utils.AbstractAsyncTaskWithProgress.doInBackground(AbstractAsyncTaskWithProgress.java:132)
08-15 09:55:52.795 14984 15114 E cgeo    : 	at android.os.AsyncTask$2.call(AsyncTask.java:304)
08-15 09:55:52.795 14984 15114 E cgeo    : 	at java.util.concurrent.FutureTask.run(FutureTask.java:237)
08-15 09:55:52.795 14984 15114 E cgeo    : 	at android.os.AsyncTask$SerialExecutor$1.run(AsyncTask.java:243)
08-15 09:55:52.795 14984 15114 E cgeo    : 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1133)
08-15 09:55:52.795 14984 15114 E cgeo    : 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:607)
08-15 09:55:52.795 14984 15114 E cgeo    : 	at java.lang.Thread.run(Thread.java:762)
08-15 09:55:53.094 14984 15114 W cgeo    : [AsyncTask #3] UncaughtException
08-15 09:55:53.094 14984 15114 W cgeo    : java.lang.RuntimeException: An error occurred while executing doInBackground()
08-15 09:55:53.094 14984 15114 W cgeo    : 	at android.os.AsyncTask$3.done(AsyncTask.java:318)
08-15 09:55:53.094 14984 15114 W cgeo    : 	at java.util.concurrent.FutureTask.finishCompletion(FutureTask.java:354)
08-15 09:55:53.094 14984 15114 W cgeo    : 	at java.util.concurrent.FutureTask.setException(FutureTask.java:223)
08-15 09:55:53.094 14984 15114 W cgeo    : 	at java.util.concurrent.FutureTask.run(FutureTask.java:242)
08-15 09:55:53.094 14984 15114 W cgeo    : 	at android.os.AsyncTask$SerialExecutor$1.run(AsyncTask.java:243)
08-15 09:55:53.094 14984 15114 W cgeo    : 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1133)
08-15 09:55:53.094 14984 15114 W cgeo    : 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:607)
08-15 09:55:53.094 14984 15114 W cgeo    : 	at java.lang.Thread.run(Thread.java:762)
08-15 09:55:53.094 14984 15114 W cgeo    : Caused by: java.lang.RuntimeException: Aborting on Log.e()
08-15 09:55:53.094 14984 15114 W cgeo    : 	at cgeo.geocaching.utils.Log.e(Log.java:98)
08-15 09:55:53.094 14984 15114 W cgeo    : 	at cgeo.geocaching.connector.gc.GCParser.postLog(GCParser.java:1168)
08-15 09:55:53.094 14984 15114 W cgeo    : 	at cgeo.geocaching.connector.gc.GCLoggingManager.postLog(GCLoggingManager.java:105)
08-15 09:55:53.094 14984 15114 W cgeo    : 	at cgeo.geocaching.log.LogCacheActivity$Poster.doInBackgroundInternal(LogCacheActivity.java:550)
08-15 09:55:53.094 14984 15114 W cgeo    : 	at cgeo.geocaching.log.LogCacheActivity$Poster.doInBackgroundInternal(LogCacheActivity.java:536)
08-15 09:55:53.094 14984 15114 W cgeo    : 	at cgeo.geocaching.utils.AbstractAsyncTaskWithProgress.doInBackground(AbstractAsyncTaskWithProgress.java:132)
08-15 09:55:53.094 14984 15114 W cgeo    : 	at android.os.AsyncTask$2.call(AsyncTask.java:304)
08-15 09:55:53.094 14984 15114 W cgeo    : 	at java.util.concurrent.FutureTask.run(FutureTask.java:237)
08-15 09:55:53.094 14984 15114 W cgeo    : 	... 4 more
08-15 09:55:53.094 14984 15114 E AndroidRuntime: Process: cgeo.geocaching, PID: 14984
08-15 09:55:53.094 14984 15114 E AndroidRuntime: 	at cgeo.geocaching.utils.Log.e(Log.java:98)
08-15 09:55:53.094 14984 15114 E AndroidRuntime: 	at cgeo.geocaching.connector.gc.GCParser.postLog(GCParser.java:1168)
08-15 09:55:53.094 14984 15114 E AndroidRuntime: 	at cgeo.geocaching.connector.gc.GCLoggingManager.postLog(GCLoggingManager.java:105)
08-15 09:55:53.094 14984 15114 E AndroidRuntime: 	at cgeo.geocaching.log.LogCacheActivity$Poster.doInBackgroundInternal(LogCacheActivity.java:550)
08-15 09:55:53.094 14984 15114 E AndroidRuntime: 	at cgeo.geocaching.log.LogCacheActivity$Poster.doInBackgroundInternal(LogCacheActivity.java:536)
08-15 09:55:53.094 14984 15114 E AndroidRuntime: 	at cgeo.geocaching.utils.AbstractAsyncTaskWithProgress.doInBackground(AbstractAsyncTaskWithProgress.java:132)
08-15 09:55:53.099  5063 11392 W ActivityManager:   Force finishing activity cgeo.geocaching/.log.LogCacheActivity
08-15 09:55:53.101  5063 11392 D ActivityManager: moveToFront() : reason=finishActivity adjustFocus setFocusedActivity isAttached=true TaskRecord{81d8ae7d0 #9997 A=cgeo.geocaching U=0 StackId=1 sz=4}
08-15 09:55:53.126  5063  6715 D ActivityManager: resumeTopActivityInnerLocked() : #1 prevTask=TaskRecord{81d8ae7d0 #9997 A=cgeo.geocaching U=0 StackId=1 sz=4} next=ActivityRecord{55d133fd0 u0 cgeo.geocaching/.CacheDetailActivity t9997} mFocusedStack=ActivityStack{992170ad0 stackId=1, 25 tasks}
08-15 09:55:53.136  5063  5063 D GameManagerService: NotifyRunnable. pkg: cgeo.geocaching, type: 4, isMinimized: false, isTunableApp: false
08-15 09:55:53.143  5063  6279 V WindowManager: Relayout Window{442b37d0 u0 cgeo.geocaching/cgeo.geocaching.CacheDetailActivity}: viewVisibility=4 req=1440x2560 WM.LayoutParams{(0,0)(fillxfill) sim=#20 ty=1 fl=#1810100 wanim=0x10303ea needsMenuKey=2 naviIconColor=0}
08-15 09:55:53.165  5063  5133 V WindowManager: Relayout Window{d8df26dd0 u0 Application Error: cgeo.geocaching}: viewVisibility=0 req=1352x508 WM.LayoutParams{(0,0)(wrapxwrap) gr=#11 sim=#120 ty=2003 fl=#1820002 pfl=0x110 fmt=-3 wanim=0x103038b surfaceInsets=Rect(7, 7 - 7, 7) needsMenuKey=2 naviIconColor=0}
08-15 09:55:53.166 14984 14984 D cgeo    : [main] LocationProvider: requesting most precise locations
08-15 09:55:53.174 14984 14984 D cgeo    : [main] Loading GC352Y3 Die alte Schule from DB
08-15 09:55:53.213  6202  6202 D FusedRequestManager_FLP: manageLocationRequest, new gps (HIGH_POWER) request from cgeo.geocaching interval 2000 through GooglePlayService, HWFLP = 0
08-15 09:55:53.213  6202  6202 D FusedRequestManager_FLP: cgeo.geocaching/gps/10351_GPS/2000/is already existed in AllRequestList so return
08-15 09:55:53.270  5063  5133 D WindowManager: finishDrawingWindow: Window{d8df26dd0 u0 Application Error: cgeo.geocaching} mDrawState=DRAW_PENDING
08-15 09:55:53.301  5063  5133 D WindowManager: finishDrawingWindow: Window{d8df26dd0 u0 Application Error: cgeo.geocaching} mDrawState=HAS_DRAWN
08-15 09:55:53.599  5063  6666 V WindowManager: Relayout Window{442b37d0 u0 cgeo.geocaching/cgeo.geocaching.CacheDetailActivity}: viewVisibility=0 req=1440x2560 WM.LayoutParams{(0,0)(fillxfill) sim=#10 ty=1 fl=#1810100 wanim=0x10303ea needsMenuKey=2 naviIconColor=0}
08-15 09:55:53.709  5063 24582 D WindowManager: finishDrawingWindow: Window{442b37d0 u0 cgeo.geocaching/cgeo.geocaching.CacheDetailActivity} mDrawState=DRAW_PENDING
08-15 09:55:53.752  5063  6716 D WindowManager: finishDrawingWindow: Window{442b37d0 u0 cgeo.geocaching/cgeo.geocaching.CacheDetailActivity} mDrawState=HAS_DRAWN
08-15 09:55:53.772  5063  6435 V WindowManager: Relayout Window{2da337ed0 u0 cgeo.geocaching/cgeo.geocaching.log.LogCacheActivity}: viewVisibility=8 req=1440x2560 WM.LayoutParams{(0,0)(fillxfill) sim=#110 ty=1 fl=#1810100 wanim=0x10303ea needsMenuKey=2 naviIconColor=0}
08-15 09:55:54.186  5063 14097 I WindowManager: Destroying surface Surface(name=cgeo.geocaching/cgeo.geocaching.log.LogCacheActivity) called by com.android.server.wm.WindowStateAnimator.destroySurface:2838 com.android.server.wm.WindowStateAnimator.destroySurfaceLocked:1069 com.android.server.wm.WindowState.removeLocked:1776 com.android.server.wm.WindowManagerService.removeWindowInnerLocked:2876 com.android.server.wm.WindowManagerService.removeWindowLocked:2826 com.android.server.wm.WindowManagerService.removeWindowLocked:2641 com.android.server.wm.WindowManagerService.removeWindow:2636 com.android.server.wm.Session.remove:208 
08-15 09:55:54.208 14984 14984 E WindowManager: android.view.WindowLeaked: Activity cgeo.geocaching.log.LogCacheActivity has leaked window DecorView@3c7f8[Sende Log…] that was originally added here
08-15 09:55:54.208 14984 14984 E WindowManager: 	at cgeo.geocaching.activity.Progress.show(Progress.java:45)
08-15 09:55:54.208 14984 14984 E WindowManager: 	at cgeo.geocaching.utils.AbstractAsyncTaskWithProgress.onPreExecute(AbstractAsyncTaskWithProgress.java:50)
08-15 09:55:54.208 14984 14984 E WindowManager: 	at cgeo.geocaching.log.LogCacheActivity.sendLogInternal(LogCacheActivity.java:851)
08-15 09:55:54.208 14984 14984 E WindowManager: 	at cgeo.geocaching.log.LogCacheActivity.sendLogAndConfirm(LogCacheActivity.java:846)
08-15 09:55:54.208 14984 14984 E WindowManager: 	at cgeo.geocaching.log.LogCacheActivity.onOptionsItemSelected(LogCacheActivity.java:801)
08-15 09:55:54.226  5063 24579 I MultiWindowManagerService: setTaskDimensions: Task=TaskRecord{81d8ae7d0 #9997 A=cgeo.geocaching U=0 StackId=1 sz=3} minWidth=-1 minHeight=-1 maxWidth=-1 maxHeight=-1
08-15 09:55:54.456  5063 11392 W ActivityManager:   Force finishing activity cgeo.geocaching/.CacheDetailActivity
08-15 09:55:54.456  5063 11392 D ActivityManager: moveToFront() : reason=resetFocusedActivityIfNeeded setFocusedActivity isAttached=true TaskRecord{81d8ae7d0 #9997 A=cgeo.geocaching U=0 StackId=1 sz=3}
08-15 09:55:54.463  5063 11392 I WindowManager: Destroying surface Surface(name=cgeo.geocaching/cgeo.geocaching.CacheDetailActivity) called by com.android.server.wm.WindowStateAnimator.destroySurface:2838 com.android.server.wm.WindowStateAnimator.destroySurfaceLocked:1069 com.android.server.wm.WindowState.removeLocked:1776 com.android.server.wm.WindowManagerService.removeWindowInnerLocked:2876 com.android.server.wm.WindowManagerService.removeWindowLocked:2826 com.android.server.wm.WindowManagerService.removeWindowLocked:2641 com.android.server.wm.AppWindowToken.removeAllWindows:596 com.android.server.wm.AppWindowToken.removeAppFromTaskLocked:358 
08-15 09:55:54.469  5063 11392 I MultiWindowManagerService: setTaskDimensions: Task=TaskRecord{81d8ae7d0 #9997 A=cgeo.geocaching U=0 StackId=1 sz=2} minWidth=-1 minHeight=-1 maxWidth=-1 maxHeight=-1
08-15 09:55:54.470  5063 11392 D ActivityManager: resumeTopActivityInnerLocked() : #1 prevTask=null next=ActivityRecord{f9bc4b0d0 u0 cgeo.geocaching/.CacheListActivity t9997} mFocusedStack=ActivityStack{992170ad0 stackId=1, 25 tasks}
08-15 09:55:54.476  5063  5063 D GameManagerService: NotifyRunnable. pkg: cgeo.geocaching, type: 4, isMinimized: false, isTunableApp: false
08-15 09:55:54.481  5063 11392 W ActivityManager:   Force finishing activity cgeo.geocaching/.CacheListActivity
08-15 09:55:54.482  5063 11392 D ActivityManager: moveToFront() : reason=resetFocusedActivityIfNeeded setFocusedActivity isAttached=true TaskRecord{81d8ae7d0 #9997 A=cgeo.geocaching U=0 StackId=1 sz=2}
08-15 09:55:54.483  5063 11392 I MultiWindowManagerService: setTaskDimensions: Task=TaskRecord{81d8ae7d0 #9997 A=cgeo.geocaching U=0 StackId=1 sz=1} minWidth=-1 minHeight=-1 maxWidth=-1 maxHeight=-1
08-15 09:55:54.483  5063 11392 D ActivityManager: resumeTopActivityInnerLocked() : #1 prevTask=null next=ActivityRecord{e23690bd0 u0 cgeo.geocaching/.MainActivity t9997} mFocusedStack=ActivityStack{992170ad0 stackId=1, 25 tasks}
08-15 09:55:54.490  5063  5063 D GameManagerService: NotifyRunnable. pkg: cgeo.geocaching, type: 4, isMinimized: false, isTunableApp: false
08-15 09:55:54.496  5063 11392 W ActivityManager:   Force finishing activity cgeo.geocaching/.MainActivity
08-15 09:55:54.531  5063 11392 E ActivityManager: Found activity ActivityRecord{e23690bd0 u0 cgeo.geocaching/.MainActivity t9997 f} in proc activity list using null instead of expected ProcessRecord{4d6ab32 14984:cgeo.geocaching/u0a351}
08-15 09:55:54.547  5063 11392 I ActivityManager: Killing 14984:cgeo.geocaching/u0a351 (adj 199): crash
08-15 09:55:54.654  5063  6279 I WindowManager: WIN DEATH: Window{4e8ad31d0 u0 cgeo.geocaching/cgeo.geocaching.MainActivity}
08-15 09:55:54.657  5063  6714 I WindowManager: WIN DEATH: Window{7c9f4e5d0 u0 cgeo.geocaching/cgeo.geocaching.CacheListActivity}
08-15 09:55:54.814  5063  5165 I WindowManager: Destroying surface Surface(name=Application Error: cgeo.geocaching) called by com.android.server.wm.WindowStateAnimator.destroySurface:2838 com.android.server.wm.WindowStateAnimator.destroySurfaceLocked:1069 com.android.server.wm.WindowState.destroyOrSaveSurface:2534 com.android.server.wm.WindowSurfacePlacer.performSurfacePlacementInner:499 com.android.server.wm.WindowSurfacePlacer.performSurfacePlacementLoop:274 com.android.server.wm.WindowSurfacePlacer.performSurfacePlacement:222 com.android.server.wm.WindowManagerService$H.handleMessage:9166 android.os.Handler.dispatchMessage:102 
08-15 09:55:55.142  6202 15149 D FusedRequestManager_FLP: manageRemoveGmsRequest, remove high_power request from cgeo.geocaching through GooglePlayService
---- 15.08.2017 09:55:55 ----

Removed blank lines -- SammysHP

Lineflyer · 2017-08-15T08:05:11Z

Posted problem info on social networks.

Lineflyer · 2017-08-15T08:08:51Z

Notification in c:geo posted.

Lineflyer · 2017-08-15T08:24:41Z

On the website I see indeed some layout changes which might affect our login check or something else during the logging procedure. The old logging page however is still available, so it is likely not related to #6519.

I guess some content has changed which makes us fail to detect, that the user is logged in, which then triggers the normal login procedure (which seems to work normal so far).

Lineflyer · 2017-08-15T10:37:06Z

@Bananeweizen Are you able to look into this problem? If the problem does not vanish in the next hours (which would mean it is probably not caused by a website problem) we might need to get a fixed version out as soon as possible :(

cgeo/cgeo#6682

Lineflyer · 2017-08-15T12:48:43Z

As the problem is still there, I added a FAQ entry and warning on homepage and modified the notification in c:geo accordingly (linked to FAQ).

Lineflyer · 2017-08-15T14:52:57Z

Additional info from user via FB:
Problem also appears for TB logs.

Lineflyer · 2017-08-15T19:52:39Z

Right now we discovered that the webpage is back to the old design/HTML.
So also the current release 2017.07.24 is tested and working again.

As according to @kumy his change in #6685 should be compatible with both styles, I tested the scenario of new code (RC version from release branch) with old design/HTML:

Cache logging OK for BM and PM
TB logging OK for BM and PM
Home coordinate retrieval OK for BM and PM

Let us keep the change on release branch for now and wait and see what happens next.

If the site keeps rolled back the next hours I will remove or modify the notification, etc.

kumy · 2017-08-15T19:54:31Z

For the record, the changed part was a new class added SignedInProfileLink:

Here is what we get during this day...

<a class="SignedInProfileLink li-user-info" href="/my/default.aspx" title="View Your Profile">
<span class="user-avatar">
<img id="ctl00_uxLoginStatus_hlHeaderAvatar" src="https://img.geocaching.com/avatar/d0c6a8c9-2b7f-4d56-b8e7-a16508e33733.png" />
</span>
<span class="user-name">kumy</span>
<span class="cache-count">353 Finds</span>
</a>

Lineflyer · 2017-08-16T08:01:11Z

Status today 9am UTC:
The page still seems to be on the previous style. Logging works normal with 2017.07.24 version.
Lets wait another day and then revert the changes on release branch.

Lineflyer · 2017-08-16T08:02:00Z

Notification has been updated (..."might sporadically fail...") and I did another posting on FB "currently no more problem"

Lineflyer · 2017-08-16T20:30:55Z

Just tested and it sill looks normal.
I will cancel the notification in c:geo for now.

kumy · 2017-08-19T16:58:43Z

Fix from #6685 needs to be ported to master. Current builds of master fail with:

04:44.781  
04:44.781  cgeo.geocaching.CgeoApplicationTest > testSearchByViewportNotLoggedIn[emulator(AVD) - 6.0] �[31mFAILED �[0m
04:44.916  	java.lang.AssertionError:
04:44.916  	Expecting:
05:28.516  
05:28.516  cgeo.geocaching.connector.gc.GCLoginTest > testRetrieveHomeLocation[emulator(AVD) - 6.0] �[31mFAILED �[0m
05:28.516  	org.junit.ComparisonFailure: expected:<[tru]e> but was:<[fals]e>
05:28.516  	at cgeo.geocaching.connector.gc.GCLoginTest.testRetrieveHomeLocation(GCLoginTest.java:29)
05:29.785  
05:29.785  cgeo.geocaching.connector.gc.GCLoginTest > testValidHomeLocation[emulator(AVD) - 6.0] �[31mFAILED �[0m
05:29.785  	cgeo.geocaching.location.Geopoint$ParseException: Cannot parse coordinates
05:29.785  	at cgeo.geocaching.location.GeopointParser.parse(GeopointParser.java:568)
07:53.463  :main:connectedBasicDebugAndroidTest FAILED

http://ci.cgeo.org/job/cgeo%20continuous%20integration/1687/console

Lineflyer · 2017-08-19T17:13:42Z

But last days I repeatedly tested and could not see this (logging) issue using current master/nightly.

kumy · 2017-08-19T17:56:50Z

I was rather talking about the "could not find home coordinates"

…

On August 19, 2017 7:13:43 PM GMT+02:00, Lars ***@***.***> wrote: But last days I repeatedly tested and could not see this (logging) issue using current master/nightly. -- You are receiving this because you were mentioned. Reply to this email directly or view it on GitHub: #6682 (comment)

-- Sent from my Android device with K-9 Mail. Please excuse my brevity.

pstorch · 2017-08-19T20:24:53Z

@kumy are you talking about the commit ebc9c42 only or also 78fd083?

kumy · 2017-08-19T21:27:01Z

@pstorch exactly. Both commits.

…

On August 19, 2017 10:24:54 PM GMT+02:00, Peter Storch ***@***.***> wrote: @kumy are you talking about the commit ebc9c42 only or also 78fd083? -- You are receiving this because you were mentioned. Reply to this email directly or view it on GitHub: #6682 (comment)

-- Sent from my Android device with K-9 Mail. Please excuse my brevity.

pstorch · 2017-08-20T05:42:55Z

We could cherry pick only those two, but would then always cherry pick from release to master. I think we should revert the one unwanted commit to be able to regularly merge from release to master. What do you think?

kumy · 2017-08-20T09:21:29Z

Ok for me to revert the third then merge.

…

On August 20, 2017 7:42:55 AM GMT+02:00, Peter Storch ***@***.***> wrote: We could cherry pick only those two, but would then always cherry pick from release to master. I think we should revert the one unwanted commit to be able to regularly merge from release to master. What do you think? -- You are receiving this because you were mentioned. Reply to this email directly or view it on GitHub: #6682 (comment)

-- Sent from my Android device with K-9 Mail. Please excuse my brevity.

kumy · 2017-08-20T09:32:37Z

I've reverted 9ea91e3 and merged release into master.

This reverts commit 9ea91e3.

Lineflyer · 2017-08-20T18:41:11Z

@kumy So 9ea91e3 is the commit we would need in case the aboe mentioned problems arises again, right?

kumy · 2017-08-20T18:42:54Z

Exact.

alan666notb · 2017-08-22T07:56:58Z

There are reports on the "Geocaching UK" FB group that this problem has re-appeared.

alan666notb · 2017-08-22T08:50:31Z

I can confirm the problem is back - just reproduced it myself.

kumy · 2017-08-22T09:07:38Z

@Lineflyer should we re-enable 9ea91e3, maybe in master and release a NB/Beta?

@alan666notb may you please send us html code for the login status from the top bar when you are on the login page?

Mine is:

<ul class="profile-panel detailed">
        <li class="messagecenterheaderwidget li-messages">
            <span></span>
            <a href="/account/messagecenter" title="Messages">
              <svg class="icon icon-svg-fill white" width="24px" height="24px">
                  <use xmlns:xlink="http://www.w3.org/1999/xlink" xlink:href="/account/Content/ui-icons/sprites/global.svg#icon-message-center"></use>
              </svg>
            </a>
        </li>
        <li class="li-user">
            <a href="/my/default.aspx" title="Your profile" class="li-user-info" accesskey="p">
                <span class="user-avatar">
                    <img src="https://img.geocaching.com/avatar/d0c6a8c9-2b7f-4d56-b8e7-a16508e33733.png" alt="Your profile">
                </span>
                <span class="user-name">kumy</span>
                <span class="cache-count">353 Finds</span>
            </a>
  [...]
</ul>

alan666notb · 2017-08-22T09:31:01Z

Is this any use?
(I copy/pasted it from 'view source' in Chrome)


<ul class="profile-panel detailed">
        <li class="messagecenterheaderwidget li-messages">
            <span></span>
            <a href="/account/messagecenter" title="Message Center">
              <svg class="icon icon-svg-fill white" width="24px" height="24px">
                  <use xmlns:xlink="http://www.w3.org/1999/xlink" xlink:href="/account/Content/ui-icons/sprites/global.svg#icon-message-center"></use>
              </svg>
            </a>
        </li>
        <li class="li-user">
            <a href="/my/default.aspx" title="View Your Profile" class="li-user-info" accesskey="p">
                <span class="user-avatar">
                    <img src="https://img.geocaching.com/avatar/942a865d-c09f-45a4-90a3-29eacf049c82.jpg" alt="Your profile photo" />
                </span>
                <span class="user-name">alan666notb</span>
                <span class="cache-count">6,224 Finds</span>
            </a>

kumy · 2017-08-22T09:51:53Z

@alan666notb Thanks.
@Lineflyer I hit the issue too. May you please enable the cgeo alert message please.

I can't build anything until tonight. We should verify 9ea91e3, but I think it should work.

kumy · 2017-08-22T12:22:55Z

@Lineflyer I have re-played notification job to enable the warning again.

Lineflyer · 2017-08-22T15:20:13Z

Confirmed, the problem is there again also for me (and from support mail).

Might also be related to #6702

kumy · 2017-08-22T15:21:06Z

+1 for the relation with #6702

Lineflyer · 2017-08-22T15:42:29Z

@kumy Feel free to provide a fix proposal on release branch as soon as you have time for it.
Later tonight I can check it again and we may decide if we release a fixed version.

GS has changed the top bar code, let's adapt our page parsing. Moved to using Jsoup instead of old style regexp pattern

kumy · 2017-08-22T17:29:17Z

For the records, here is how the old page looked like:


<div id="ctl00_uxLoginStatus_divSignedIn">
    <ul class="logged-in-user">
        <li class="li-user">
            <a class="SignedInProfileLink" href="/my/default.aspx" title="View Your Profile">
                <span class="avatar">
                    <img id="ctl00_uxLoginStatus_hlHeaderAvatar" src="https://img.geocaching.com/avatar/89468432-87cd-4f75-9426-9a1b8f520a71.jpg" />
                </span>
                <span class="li-user-info">
                    <span>storc</span>
                    <span class="cache-count">2,814 Finds</span>
                </span>
            </a>

GS has changed the top bar code, let's adapt our page parsing. Moved to using Jsoup instead of old style regexp pattern

Lineflyer · 2017-08-22T20:48:36Z

Beta version with fix published.
New release will follow next days/tomorrow.

Notification modified to be shown for all versions except RC and nightly.

GS has changed the top bar code, let's adapt our page parsing. Moved to using Jsoup instead of old style regexp pattern

Bananeweizen closed this as completed Aug 15, 2017

Bananeweizen reopened this Aug 15, 2017

Lineflyer added Bug Issues classified as a bug Prio - High A significant malfunction of a feature/function. High user impact. labels Aug 15, 2017

Lineflyer added a commit to cgeo/cgeo.github.io that referenced this issue Aug 15, 2017

FAQ: Add current issue

356f16d

cgeo/cgeo#6682

kumy added a commit that referenced this issue Aug 15, 2017

Fix #6682: Failed to detect loggin status

846bf53

kumy added a commit that referenced this issue Aug 20, 2017

Revert "Fix #6682: Failed to detect loggin status"

4cced8e

This reverts commit 9ea91e3.

kumy closed this as completed in 9ea91e3 Aug 20, 2017

kumy reopened this Aug 20, 2017

kumy added a commit that referenced this issue Aug 22, 2017

Fix #6682, #6702: Parse username using Jsoup

4882ed9

GS has changed the top bar code, let's adapt our page parsing. Moved to using Jsoup instead of old style regexp pattern

kumy added a commit that referenced this issue Aug 22, 2017

Fix #6682, #6702: Parse username using Jsoup

9138707

GS has changed the top bar code, let's adapt our page parsing. Moved to using Jsoup instead of old style regexp pattern

kumy added a commit that referenced this issue Aug 22, 2017

Fix #6682, #6702: Parse username using Jsoup

98fa588

GS has changed the top bar code, let's adapt our page parsing. Moved to using Jsoup instead of old style regexp pattern

kumy closed this as completed in 1e506cc Aug 22, 2017

Lineflyer mentioned this issue Aug 23, 2017

Next bugfix release #6707

Closed

Lineflyer added this to the Release branch milestone Aug 23, 2017

This was referenced Aug 23, 2017

Error although log posted successfully #6644

Closed

Fix legacy version #6712

Closed

pstorch pushed a commit that referenced this issue Oct 9, 2017

Fix #6682, #6702: Parse username using Jsoup

81720bc

GS has changed the top bar code, let's adapt our page parsing. Moved to using Jsoup instead of old style regexp pattern

Error displayed when do a cache log #6682

Error displayed when do a cache log #6682

Comments

viperbruno commented Aug 15, 2017

Detailed steps causing the problem:

Expected behavior after performing these steps:

Version of c:geo used:

Is the problem reproducible for you?

System information:

Other comments and remarks:

cnlson commented Aug 15, 2017

Bananeweizen commented Aug 15, 2017 • edited Loading

cnlson commented Aug 15, 2017 • edited Loading

Lineflyer commented Aug 15, 2017 • edited Loading

Lineflyer commented Aug 15, 2017 • edited by SammysHP Loading

Lineflyer commented Aug 15, 2017

Lineflyer commented Aug 15, 2017

Lineflyer commented Aug 15, 2017

Lineflyer commented Aug 15, 2017

Lineflyer commented Aug 15, 2017

Lineflyer commented Aug 15, 2017

Lineflyer commented Aug 15, 2017

kumy commented Aug 15, 2017

Lineflyer commented Aug 16, 2017

Lineflyer commented Aug 16, 2017

Lineflyer commented Aug 16, 2017

kumy commented Aug 19, 2017

Lineflyer commented Aug 19, 2017

kumy commented Aug 19, 2017 via email

pstorch commented Aug 19, 2017

kumy commented Aug 19, 2017 via email

pstorch commented Aug 20, 2017

kumy commented Aug 20, 2017 via email

kumy commented Aug 20, 2017

Lineflyer commented Aug 20, 2017

kumy commented Aug 20, 2017

alan666notb commented Aug 22, 2017

alan666notb commented Aug 22, 2017

kumy commented Aug 22, 2017

alan666notb commented Aug 22, 2017 • edited Loading

kumy commented Aug 22, 2017

kumy commented Aug 22, 2017

Lineflyer commented Aug 22, 2017

kumy commented Aug 22, 2017

Lineflyer commented Aug 22, 2017

kumy commented Aug 22, 2017

Lineflyer commented Aug 22, 2017

Bananeweizen commented Aug 15, 2017 •

edited

Loading

cnlson commented Aug 15, 2017 •

edited

Loading

Lineflyer commented Aug 15, 2017 •

edited

Loading

Lineflyer commented Aug 15, 2017 •

edited by SammysHP

Loading

alan666notb commented Aug 22, 2017 •

edited

Loading