TurnBasedMultiplayer.LoadMatchesResult empty after deploying new APK

[mkalksma]

Hi,

I have a topic at stackoverflow (https://stackoverflow.com/questions/29805131/turnbased-matches-disappear-after-loading-for-the-first-time). The issue is that if I create a match the match is returned by TurnBasedMultiplayer.LoadMatchesResult (status code OK).

Now I deploy a new version of the app (either through Android Studio or an update of my alpha app from the Play Store). Result: No matches are returned in the LoadMatchesResult (status code still OK). If I delete the cache from Play Services on the device and reopen the app all matches are returned again. If the matches are not returned in the LoadMatchesResult they are also not shown in the default UI (getInboxIntent()).

I release the LoadMatchesResult once I'm done with the data by calling release(). This happens both on a Nexus 5 and emulators.

[claywilkinson]

Hi @mkalksma - can you capture a log file of the matches not loading? That would help a lot in figuring out what is going on.

[mkalksma]

Ofcourse here are two log files. The first one where matches were returned (cleared cache of Google PLay Services) and the second with no matches after a deployment from Android Studio

05-04 19:56:39.916 740-1280/? I/ActivityManager﹕ START u0 {act=android.intent.action.MAIN cat=[android.intent.category.LAUNCHER] flg=0x10200000 cmp=matsjoe.com.surroundings/.LaunchActivity bnds=[548,794][778,1050]} from uid 10087 on display 0 05-04 19:56:39.922 740-1280/? V/WindowManager﹕ addAppToken: AppWindowToken{39928e40 token=Token{81e85c3 ActivityRecord{29376d72 u0 matsjoe.com.surroundings/.LaunchActivity t2409}}} to stack=1 task=2409 at 0 05-04 19:56:39.927 740-819/? V/WindowManager﹕ Adding window Window{15e8ea3b u0 Starting matsjoe.com.surroundings} at 14 of 20 (after Window{1e26486d u0 com.whatsapp/com.whatsapp.Conversation}) 05-04 19:56:39.982 19249-19249/matsjoe.com.surroundings W/PopupManager﹕ You have not specified a View to use as content view for popups. Falling back to the Activity content view which may not work properly in future versions of the API. Use setViewForPopups() to set your content view. 05-04 19:56:40.022 19249-19265/matsjoe.com.surroundings I/art﹕ Background sticky concurrent mark sweep GC freed 373(15KB) AllocSpace objects, 0(0B) LOS objects, 0% free, 96MB/96MB, paused 5.694ms total 12.388ms 05-04 19:56:40.104 20062-20062/? D/ChimeraSrvcProxy﹕ Creating service proxy ComponentInfo{com.google.android.gms/com.google.android.gms.games.chimera.GamesAndroidServiceProxy} 05-04 19:56:40.104 20062-20062/? D/ChimeraSrvcProxy﹕ Proxying container service ComponentInfo{com.google.android.gms/com.google.android.gms.games.chimera.GamesAndroidServiceProxy} to Chimera service impl com.google.android.gms.games.service.GamesAndroidService 05-04 19:56:40.104 20062-20062/? D/ChimeraCfgMgr﹕ Loading module com.google.android.gms.games from APK com.google.android.gms 05-04 19:56:40.108 740-6316/? V/WindowManager﹕ Adding window Window{37f89e21 u0 matsjoe.com.surroundings/matsjoe.com.surroundings.LaunchActivity} at 14 of 21 (before Window{15e8ea3b u0 Starting matsjoe.com.surroundings}) 05-04 19:56:40.169 20062-20062/? D/ChimeraSrvcProxy﹕ Creating service proxy ComponentInfo{com.google.android.gms/com.google.android.gms.games.chimera.GamesAsyncServiceProxy} 05-04 19:56:40.169 20062-20062/? D/ChimeraSrvcProxy﹕ Proxying container service ComponentInfo{com.google.android.gms/com.google.android.gms.games.chimera.GamesAsyncServiceProxy} to Chimera service impl com.google.android.gms.games.service.GamesAsyncService 05-04 19:56:40.169 20062-20062/? D/ChimeraCfgMgr﹕ Loading module com.google.android.gms.games from APK com.google.android.gms 05-04 19:56:40.177 13654-13654/? V/GLSActivity﹕ AuthDelegateWrapperCreated with selected intent: Intent { cmp=com.google.android.gms/.auth.DefaultAuthDelegateService } 05-04 19:56:40.219 13654-13860/? I/GLSUser﹕ [GLSUser] getTokenFromCache: [account: ELLIDED:-29926, callingPkg: matsjoe.com.surroundings, service: oauth2:https://www.googleapis.com/auth/plus.login https://www.googleapis.com/auth/games] 05-04 19:56:40.220 20062-20229/? V/AuthAccountOperation﹕ offline access is not requested; requesting access token 05-04 19:56:40.223 13654-13860/? I/Auth.Core﹕ [TokenCache] Missing snowballing token: no granted scopes set. 05-04 19:56:40.242 13654-13861/? I/GLSUser﹕ [GLSUser] getTokenFromCache: [account: ELLIDED:-29926, callingPkg: matsjoe.com.surroundings, service: oauth2:https://www.googleapis.com/auth/games https://www.googleapis.com/auth/plus.login] 05-04 19:56:40.244 13654-13861/? I/Auth.Core﹕ [TokenCache] Missing snowballing token: no granted scopes set. 05-04 19:56:40.251 20062-20229/? V/AuthAccountOperation﹕ access token request successful 05-04 19:56:40.263 740-819/? I/ActivityManager﹕ Displayed matsjoe.com.surroundings/.LaunchActivity: +313ms 05-04 19:56:40.298 13654-13859/? I/GLSUser﹕ [GLSUser] getTokenFromCache: [account: ELLIDED:-29926, callingPkg: matsjoe.com.surroundings, service: oauth2:https://www.googleapis.com/auth/games https://www.googleapis.com/auth/plus.login] 05-04 19:56:40.299 13654-13859/? I/Auth.Core﹕ [TokenCache] Missing snowballing token: no granted scopes set. 05-04 19:56:41.794 740-1581/? I/ActivityManager﹕ START u0 {cmp=matsjoe.com.surroundings/.GameListActivity} from uid 10176 on display 0 05-04 19:56:41.797 740-1581/? V/WindowManager﹕ addAppToken: AppWindowToken{37fd5994 token=Token{389ad9e7 ActivityRecord{3185aba6 u0 matsjoe.com.surroundings/.GameListActivity t2409}}} to stack=1 task=2409 at 1 05-04 19:56:41.814 19249-19249/matsjoe.com.surroundings W/PopupManager﹕ You have not specified a View to use as content view for popups. Falling back to the Activity content view which may not work properly in future versions of the API. Use setViewForPopups() to set your content view. 05-04 19:56:41.833 19249-19249/matsjoe.com.surroundings I/Ads﹕ Starting ad request. 05-04 19:56:41.848 740-6316/? V/WindowManager﹕ Adding window Window{2f207b00 u0 matsjoe.com.surroundings/matsjoe.com.surroundings.GameListActivity} at 15 of 22 (after Window{37f89e21 u0 matsjoe.com.surroundings/matsjoe.com.surroundings.LaunchActivity}) 05-04 19:56:41.860 20062-20062/? D/ChimeraSrvcProxy﹕ Creating service proxy ComponentInfo{com.google.android.gms/com.google.android.gms.games.chimera.GamesAsyncServiceProxy} 05-04 19:56:41.860 20062-20062/? D/ChimeraSrvcProxy﹕ Proxying container service ComponentInfo{com.google.android.gms/com.google.android.gms.games.chimera.GamesAsyncServiceProxy} to Chimera service impl com.google.android.gms.games.service.GamesAsyncService 05-04 19:56:41.860 20062-20062/? D/ChimeraCfgMgr﹕ Loading module com.google.android.gms.games from APK com.google.android.gms 05-04 19:56:41.874 13654-13654/? V/GLSActivity﹕ AuthDelegateWrapperCreated with selected intent: Intent { cmp=com.google.android.gms/.auth.DefaultAuthDelegateService } 05-04 19:56:41.904 20062-20262/? V/AuthAccountOperation﹕ offline access is not requested; requesting access token 05-04 19:56:41.906 13654-13787/? I/GLSUser﹕ [GLSUser] getTokenFromCache: [account: ELLIDED:-29926, callingPkg: matsjoe.com.surroundings, service: oauth2:https://www.googleapis.com/auth/plus.login https://www.googleapis.com/auth/games] 05-04 19:56:41.914 13654-13787/? I/Auth.Core﹕ [TokenCache] Missing snowballing token: no granted scopes set. 05-04 19:56:41.966 13654-13858/? I/GLSUser﹕ [GLSUser] getTokenFromCache: [account: ELLIDED:-29926, callingPkg: matsjoe.com.surroundings, service: oauth2:https://www.googleapis.com/auth/games https://www.googleapis.com/auth/plus.login] 05-04 19:56:41.968 13654-13858/? I/Auth.Core﹕ [TokenCache] Missing snowballing token: no granted scopes set. 05-04 19:56:41.978 20062-20262/? V/AuthAccountOperation﹕ access token request successful 05-04 19:56:42.016 740-819/? I/ActivityManager﹕ Displayed matsjoe.com.surroundings/.GameListActivity: +215ms 05-04 19:56:42.063 13654-13787/? I/GLSUser﹕ [GLSUser] getTokenFromCache: [account: ELLIDED:-29926, callingPkg: matsjoe.com.surroundings, service: oauth2:https://www.googleapis.com/auth/games https://www.googleapis.com/auth/plus.login] 05-04 19:56:42.066 13654-13787/? I/Auth.Core﹕ [TokenCache] Missing snowballing token: no granted scopes set. 05-04 19:56:42.092 13654-13861/? I/GLSUser﹕ [GLSUser] getTokenFromCache: [account: ELLIDED:-29926, callingPkg: com.google.android.gms, service: oauth2:https://www.googleapis.com/auth/games.firstparty] 05-04 19:56:42.094 13654-13861/? I/Auth.Core﹕ [TokenCache] Missing snowballing token: no granted scopes set. 05-04 19:56:42.097 20062-29281/? I/Ads﹕ CsiReporterFactory: CSI is not enabled. No CSI reporter created. 05-04 19:56:42.114 20062-20062/? W/art﹕ Attempt to remove local handle scope entry from IRT, ignoring 05-04 19:56:42.115 20062-20062/? W/AwContents﹕ onDetachedFromWindow called when already detached. Ignoring 05-04 19:56:42.119 20062-20062/? W/art﹕ Attempt to remove local handle scope entry from IRT, ignoring 05-04 19:56:42.119 20062-20062/? W/art﹕ Attempt to remove local handle scope entry from IRT, ignoring 05-04 19:56:42.137 20062-20062/? W/BindingManager﹕ Cannot call determinedVisibility() - never saw a connection for the pid: 20062 05-04 19:56:42.151 20062-20062/? I/chromium﹕ [INFO:CONSOLE(0)] "Document was loaded from Application Cache with manifest https://googleads.g.doubleclick.net/mads/static/mad/sdk/native/sdk-core-v40.appcache", source: https://googleads.g.doubleclick.net/mads/static/mad/sdk/native/sdk-core-v40.html (0) 05-04 19:56:42.151 20062-20062/? I/chromium﹕ [INFO:CONSOLE(0)] "Application Cache Checking event", source: https://googleads.g.doubleclick.net/mads/static/mad/sdk/native/sdk-core-v40.html (0) 05-04 19:56:42.197 740-812/? E/LocSvc_eng﹕ E/int loc_eng_inject_location(loc_eng_data_s_type&, double, double, float): log_eng state error: instance not initialized 05-04 19:56:42.197 740-812/? E/LocSvc_eng﹕ E/int loc_eng_inject_location(loc_eng_data_s_type&, double, double, float): log_eng state error: instance not initialized 05-04 19:56:42.204 13718-13852/? D/LocationFilter﹕ Forcing stable location. Original location:Location[fused 53,221490,6,527133 acc=10 et=+1d7h26m3s916ms] because connected WiFi network is the same, bssid=80:3c:08:01:6b:f0 05-04 19:56:42.207 740-1137/? D/WifiService﹕ releaseWifiLockLocked: WifiLock{NlpWifiLock type=2 binder=android.os.BinderProxy@13d839e0} 05-04 19:56:42.284 20062-20062/? I/chromium﹕ [INFO:CONSOLE(0)] "Application Cache NoUpdate event", source: https://googleads.g.doubleclick.net/mads/static/mad/sdk/native/sdk-core-v40.html (0) 05-04 19:56:42.478 740-1243/? I/art﹕ Explicit concurrent mark sweep GC freed 105659(4MB) AllocSpace objects, 26(630KB) LOS objects, 26% free, 43MB/59MB, paused 1.439ms total 128.091ms 05-04 19:56:42.488 20062-20062/? W/BindingManager﹕ Cannot call determinedVisibility() - never saw a connection for the pid: 20062 05-04 19:56:42.498 20062-20062/? W/ScreenOrientationListener﹕ Removing an inexistent observer! 05-04 19:56:42.666 19249-19249/matsjoe.com.surroundings W/art﹕ Attempt to remove local handle scope entry from IRT, ignoring 05-04 19:56:42.668 19249-19249/matsjoe.com.surroundings W/AwContents﹕ onDetachedFromWindow called when already detached. Ignoring 05-04 19:56:42.679 19249-19249/matsjoe.com.surroundings W/art﹕ Attempt to remove local handle scope entry from IRT, ignoring 05-04 19:56:42.679 19249-19249/matsjoe.com.surroundings W/art﹕ Attempt to remove local handle scope entry from IRT, ignoring

Not working:

05-04 20:05:24.753 740-1244/? I/ActivityManager﹕ START u0 {act=android.intent.action.MAIN cat=[android.intent.category.LAUNCHER] flg=0x10200000 cmp=matsjoe.com.surroundings/.LaunchActivity bnds=[548,794][778,1050]} from uid 10087 on display 0 05-04 20:05:24.753 740-1244/? V/WindowManager﹕ addAppToken: AppWindowToken{3b3e7c15 token=Token{2932e1cc ActivityRecord{73cffff u0 matsjoe.com.surroundings/.LaunchActivity t2412}}} to stack=1 task=2412 at 0 05-04 20:05:24.780 740-819/? V/WindowManager﹕ Adding window Window{376318cd u0 Starting matsjoe.com.surroundings} at 4 of 10 (after Window{21c94b80 u0 com.tul.aviate/com.tul.aviator.ui.TabbedHomeActivity}) 05-04 20:05:24.798 27979-27979/? I/art﹕ Late-enabling -Xcheck:jni 05-04 20:05:24.799 740-7185/? I/ActivityManager﹕ Start proc 27979:matsjoe.com.surroundings/u0a176 for activity matsjoe.com.surroundings/.LaunchActivity 05-04 20:05:24.837 27979-27991/? I/art﹕ Debugger is no longer active 05-04 20:05:25.091 27979-27979/? W/PopupManager﹕ You have not specified a View to use as content view for popups. Falling back to the Activity content view which may not work properly in future versions of the API. Use setViewForPopups() to set your content view. 05-04 20:05:25.244 27979-27979/? W/ResourcesManager﹕ Asset path '/system/framework/com.android.media.remotedisplay.jar' does not exist or contains no resources. 05-04 20:05:25.245 27979-27979/? W/ResourcesManager﹕ Asset path '/system/framework/com.android.location.provider.jar' does not exist or contains no resources. 05-04 20:05:25.353 27979-27996/? I/art﹕ Background sticky concurrent mark sweep GC freed 3732(343KB) AllocSpace objects, 2(32KB) LOS objects, 0% free, 27MB/27MB, paused 5.319ms total 73.005ms 05-04 20:05:25.372 27979-27979/? W/ResourcesManager﹕ Asset path '/system/framework/com.android.media.remotedisplay.jar' does not exist or contains no resources. 05-04 20:05:25.372 27979-27979/? W/ResourcesManager﹕ Asset path '/system/framework/com.android.location.provider.jar' does not exist or contains no resources. 05-04 20:05:25.456 20062-20062/? D/ChimeraSrvcProxy﹕ Creating service proxy ComponentInfo{com.google.android.gms/com.google.android.gms.games.chimera.GamesAndroidServiceProxy} 05-04 20:05:25.456 20062-20062/? D/ChimeraSrvcProxy﹕ Proxying container service ComponentInfo{com.google.android.gms/com.google.android.gms.games.chimera.GamesAndroidServiceProxy} to Chimera service impl com.google.android.gms.games.service.GamesAndroidService 05-04 20:05:25.456 20062-20062/? D/ChimeraCfgMgr﹕ Loading module com.google.android.gms.games from APK com.google.android.gms 05-04 20:05:25.467 27979-28014/? D/OpenGLRenderer﹕ Use EGL_SWAP_BEHAVIOR_PRESERVED: true 05-04 20:05:25.475 27979-27979/? D/Atlas﹕ Validating map... 05-04 20:05:25.480 740-1581/? V/WindowManager﹕ Adding window Window{371b362c u0 matsjoe.com.surroundings/matsjoe.com.surroundings.LaunchActivity} at 4 of 11 (before Window{376318cd u0 Starting matsjoe.com.surroundings}) 05-04 20:05:25.535 27979-28014/? I/Adreno-EGL﹕ : QUALCOMM Build: 01/14/15, ab0075f, Id3510ff6dc 05-04 20:05:25.537 27979-28014/? I/OpenGLRenderer﹕ Initialized EGL, version 1.4 05-04 20:05:25.595 27979-28014/? D/OpenGLRenderer﹕ Enabling debug mode 0 05-04 20:05:25.639 13654-13654/? V/GLSActivity﹕ AuthDelegateWrapperCreated with selected intent: Intent { cmp=com.google.android.gms/.auth.DefaultAuthDelegateService } 05-04 20:05:25.640 20062-20062/? D/ChimeraSrvcProxy﹕ Creating service proxy ComponentInfo{com.google.android.gms/com.google.android.gms.games.chimera.GamesAsyncServiceProxy} 05-04 20:05:25.640 20062-20062/? D/ChimeraSrvcProxy﹕ Proxying container service ComponentInfo{com.google.android.gms/com.google.android.gms.games.chimera.GamesAsyncServiceProxy} to Chimera service impl com.google.android.gms.games.service.GamesAsyncService 05-04 20:05:25.640 20062-20062/? D/ChimeraCfgMgr﹕ Loading module com.google.android.gms.games from APK com.google.android.gms 05-04 20:05:25.663 13654-13670/? I/GLSUser﹕ [GLSUser] getTokenFromCache: [account: ELLIDED:-29926, callingPkg: matsjoe.com.surroundings, service: oauth2:https://www.googleapis.com/auth/plus.login https://www.googleapis.com/auth/games] 05-04 20:05:25.666 13654-13670/? I/Auth.Core﹕ [TokenCache] Missing snowballing token: no granted scopes set. 05-04 20:05:25.717 20062-28019/? V/AuthAccountOperation﹕ offline access is not requested; requesting access token 05-04 20:05:25.738 13654-13857/? I/GLSUser﹕ [GLSUser] getTokenFromCache: [account: ELLIDED:-29926, callingPkg: matsjoe.com.surroundings, service: oauth2:https://www.googleapis.com/auth/games https://www.googleapis.com/auth/plus.login] 05-04 20:05:25.741 13654-13857/? I/Auth.Core﹕ [TokenCache] Missing snowballing token: no granted scopes set. 05-04 20:05:25.748 20062-28019/? V/AuthAccountOperation﹕ access token request successful 05-04 20:05:25.781 740-819/? I/ActivityManager﹕ Displayed matsjoe.com.surroundings/.LaunchActivity: +1s18ms 05-04 20:05:25.849 13654-13764/? I/GLSUser﹕ [GLSUser] getTokenFromCache: [account: ELLIDED:-29926, callingPkg: matsjoe.com.surroundings, service: oauth2:https://www.googleapis.com/auth/games https://www.googleapis.com/auth/plus.login] 05-04 20:05:25.852 13654-13764/? I/Auth.Core﹕ [TokenCache] Missing snowballing token: no granted scopes set. 05-04 20:05:25.863 740-761/? D/WifiService﹕ acquireWifiLockLocked: WifiLock{NlpWifiLock type=2 binder=android.os.BinderProxy@29053a8c} 05-04 20:05:25.868 740-859/? E/WifiStateMachine﹕ WifiStateMachine CMD_START_SCAN source 10007 txSuccessRate=0,00 rxSuccessRate=0,00 targetRoamBSSID=80:3c:08:01:6b:f0 RSSI=-51 05-04 20:05:25.883 740-859/? E/WifiStateMachine﹕ WifiStateMachine CMD_START_SCAN source 10007 txSuccessRate=0,00 rxSuccessRate=0,00 targetRoamBSSID=80:3c:08:01:6b:f0 RSSI=-51 05-04 20:05:25.944 27979-28014/matsjoe.com.surroundings V/RenderScript﹕ Application requested CPU execution 05-04 20:05:25.982 27979-28014/matsjoe.com.surroundings V/RenderScript﹕ 0xaec0f400 Launching thread(s), CPUs 4 05-04 20:05:26.050 740-813/? I/ActivityManager﹕ Start proc 28046:com.facebook.katana/u0a74 for service com.facebook.katana/com.facebook.push.mqtt.service.MqttPushService 05-04 20:05:26.166 28046-28046/? W/ResourcesManager﹕ Asset path '/system/framework/com.google.android.maps.jar' does not exist or contains no resources. 05-04 20:05:26.269 28046-28046/? D/CatchMeIfYouCan﹕ Process () ca: '/data/data/com.facebook.katana/app_cmiyc/A1657CCD543F12E66E587EE52ED9D802E22AAC4701' 05-04 20:05:26.269 28046-28046/? D/CatchMeIfYouCan﹕ Process () fc: '/data/data/com.facebook.katana/app_cmiyc/A1657CCD543F12E66E587EE52ED9D802E22AAC4702' 05-04 20:05:26.270 28046-28046/? D/CatchMeIfYouCan﹕ Process () cp: '('default', 3, 120000, silent [true], restart [false], logout [false])' 05-04 20:05:26.277 28046-28046/? D/ACRA﹕ ACRA is enabled for com.facebook.katana, intializing... 05-04 20:05:26.281 28046-28046/? D/ACRA﹕ Looking for error files in /data/data/com.facebook.katana/app_acra-reports 05-04 20:05:26.282 28046-28046/? D/ACRA﹕ Looking for error files in /data/data/com.facebook.katana/app_minidumps 05-04 20:05:26.301 28046-28046/? I/DexLibLoader﹕ not using cross-dex optimization: ART in use 05-04 20:05:26.301 28046-28046/? V/DexLibLoader﹕ about to lock: /data/data/com.facebook.katana/mdex_lock 05-04 20:05:26.303 28046-28046/? V/DexLibLoader﹕ locking /data/data/com.facebook.katana/mdex_lock took 1 ms 05-04 20:05:26.304 28046-28046/? V/DexLibLoader﹕ Secondary program dex metadata: classes2.dex 4c64862ac4257be8c9f5f9a4596158421991d03d secondary.dex01.Canary 05-04 20:05:26.304 28046-28046/? V/DexLibLoader﹕ Secondary program dex metadata: classes3.dex f0a0b938cf37404fe0713d6dd77dbd7cf40ec6b3 secondary.dex02.Canary 05-04 20:05:26.304 28046-28046/? V/DexLibLoader﹕ Secondary program dex metadata: classes4.dex e2ed91197eacc226426f8bb19f4ee42588526e0d secondary.dex03.Canary 05-04 20:05:26.304 28046-28046/? V/DexLibLoader﹕ Secondary program dex metadata: classes5.dex dee89263a37bea79ad3e635c98b939dce81edab1 secondary.dex04.Canary 05-04 20:05:26.304 28046-28046/? V/DexLibLoader﹕ Secondary program dex metadata: classes6.dex eeaf60af4737eca2ea297a320b896016076d5c1f secondary.dex05.Canary 05-04 20:05:26.304 28046-28046/? V/DexLibLoader﹕ Secondary program dex metadata: classes7.dex ace9a8079d43f164d245ebd381f96777182bd1e3 secondary.dex06.Canary 05-04 20:05:26.304 28046-28046/? V/DexLibLoader﹕ Secondary program dex metadata: classes8.dex e0ae4c5b7f4e0c47f1082323ee694baac9cfbbda secondary.dex07.Canary 05-04 20:05:26.305 28046-28046/? V/DexLibLoader﹕ Secondary program dex metadata: classes9.dex fc100bbe2752679d4652d86f55a13cf7eac9af5a secondary.dex08.Canary 05-04 20:05:26.306 28046-28046/? D/DexLibLoader﹕ directory "/data/data/com.facebook.katana/app_secondary_program_dex" is valid 05-04 20:05:26.308 28046-28046/? D/DexLibLoader﹕ directory "/data/data/com.facebook.katana/app_secondary_program_dex_opt" is valid 05-04 20:05:26.316 28046-28046/? D/DexLibLoader﹕ loading pre-built fallback odex files 05-04 20:05:26.316 28046-28046/? V/MultiDexClassLoader﹕ installing MultiDexClassLoader before application classloader 05-04 20:05:26.318 28046-28046/? D/DexLibLoader﹕ released odex store lock 05-04 20:05:26.318 28046-28046/? D/DexLibLoader﹕ DexLibLoader.loadAll took 17 ms 05-04 20:05:26.319 28046-28046/? D/MemoryReductionHack﹕ Marked as initialized entry corresponding to path /data/app/com.facebook.katana-2/base.apk 05-04 20:05:26.380 740-4465/? I/ActivityManager﹕ START u0 {cmp=matsjoe.com.surroundings/.GameListActivity} from uid 10176 on display 0 05-04 20:05:26.382 740-4465/? V/WindowManager﹕ addAppToken: AppWindowToken{236aa2b6 token=Token{226cea51 ActivityRecord{2f117378 u0 matsjoe.com.surroundings/.GameListActivity t2412}}} to stack=1 task=2412 at 1 05-04 20:05:26.399 27979-27979/matsjoe.com.surroundings W/PopupManager﹕ You have not specified a View to use as content view for popups. Falling back to the Activity content view which may not work properly in future versions of the API. Use setViewForPopups() to set your content view. 05-04 20:05:26.578 28046-28046/? I/Dextr﹕ LogQueue params desc: IIJIIIJJ 05-04 20:05:26.588 27979-27979/matsjoe.com.surroundings I/WebViewFactory﹕ Loading com.google.android.webview version 42.0.2311.137 (code 2311137) 05-04 20:05:26.666 27979-27979/matsjoe.com.surroundings I/LibraryLoader﹕ Time to load native libraries: 28 ms (timestamps 9091-9119) 05-04 20:05:26.666 27979-27979/matsjoe.com.surroundings I/LibraryLoader﹕ Expected native library version number "",actual native library version number "" 05-04 20:05:26.692 27979-27979/matsjoe.com.surroundings V/WebViewChromiumFactoryProvider﹕ Binding Chromium to main looper Looper (main, tid 1) {1e76fcd2} 05-04 20:05:26.693 27979-27979/matsjoe.com.surroundings I/LibraryLoader﹕ Expected native library version number "",actual native library version number "" 05-04 20:05:26.694 27979-27979/matsjoe.com.surroundings I/chromium﹕ [INFO:library_loader_hooks.cc(112)] Chromium logging enabled: level = 0, default verbosity = 0 05-04 20:05:26.702 27979-27979/matsjoe.com.surroundings I/BrowserStartupController﹕ Initializing chromium process, singleProcess=true 05-04 20:05:26.707 27979-27979/matsjoe.com.surroundings W/art﹕ Attempt to remove local handle scope entry from IRT, ignoring 05-04 20:05:26.708 27979-27979/matsjoe.com.surroundings E/SysUtils﹕ ApplicationContext is null in ApplicationStatus 05-04 20:05:26.740 27979-28089/matsjoe.com.surroundings W/AudioManagerAndroid﹕ Requires BLUETOOTH permission 05-04 20:05:26.758 27979-27979/matsjoe.com.surroundings W/chromium﹕ [WARNING:resource_bundle.cc(286)] locale_file_path.empty() 05-04 20:05:26.759 27979-27979/matsjoe.com.surroundings I/chromium﹕ [INFO:aw_browser_main_parts.cc(63)] Load from apk succesful, fd=61 off=6847012 len=3614 05-04 20:05:26.763 27979-27979/matsjoe.com.surroundings I/chromium﹕ [INFO:aw_browser_main_parts.cc(76)] Loading webviewchromium.pak from, fd:62 off:7953036 len:1161174 05-04 20:05:26.790 28081-28081/? I/dex2oat﹕ /system/bin/dex2oat --runtime-arg -classpath --runtime-arg --instruction-set=arm --instruction-set-features=div --runtime-arg -Xrelocate --boot-image=/system/framework/boot.art --dex-file=/data/data/matsjoe.com.surroundings/cache/ads-991743382.jar --oat-fd=48 --oat-location=/data/data/matsjoe.com.surroundings/cache/ads-991743382.dex --runtime-arg -Xms64m --runtime-arg -Xmx512m 05-04 20:05:26.835 27979-27979/matsjoe.com.surroundings I/Ads﹕ CsiReporterFactory: CSI is not enabled. No CSI reporter created. 05-04 20:05:26.842 27979-27979/matsjoe.com.surroundings I/Ads﹕ Starting ad request. 05-04 20:05:26.859 740-1278/? V/WindowManager﹕ Adding window Window{212c30a7 u0 matsjoe.com.surroundings/matsjoe.com.surroundings.GameListActivity} at 5 of 12 (after Window{371b362c u0 matsjoe.com.surroundings/matsjoe.com.surroundings.LaunchActivity}) 05-04 20:05:26.870 20062-20062/? D/ChimeraSrvcProxy﹕ Creating service proxy ComponentInfo{com.google.android.gms/com.google.android.gms.games.chimera.GamesAsyncServiceProxy} 05-04 20:05:26.870 20062-20062/? D/ChimeraSrvcProxy﹕ Proxying container service ComponentInfo{com.google.android.gms/com.google.android.gms.games.chimera.GamesAsyncServiceProxy} to Chimera service impl com.google.android.gms.games.service.GamesAsyncService 05-04 20:05:26.870 20062-20062/? D/ChimeraCfgMgr﹕ Loading module com.google.android.gms.games from APK com.google.android.gms 05-04 20:05:26.874 13654-13654/? V/GLSActivity﹕ AuthDelegateWrapperCreated with selected intent: Intent { cmp=com.google.android.gms/.auth.DefaultAuthDelegateService } 05-04 20:05:26.890 13654-13861/? I/GLSUser﹕ [GLSUser] getTokenFromCache: [account: ELLIDED:-29926, callingPkg: matsjoe.com.surroundings, service: oauth2:https://www.googleapis.com/auth/plus.login https://www.googleapis.com/auth/games] 05-04 20:05:26.894 13654-13861/? I/Auth.Core﹕ [TokenCache] Missing snowballing token: no granted scopes set. 05-04 20:05:26.896 27979-27979/matsjoe.com.surroundings D/matches﹕ --------LOAD MATCHES------ 05-04 20:05:26.930 20062-28111/? V/AuthAccountOperation﹕ offline access is not requested; requesting access token 05-04 20:05:26.948 13654-13860/? I/GLSUser﹕ [GLSUser] getTokenFromCache: [account: ELLIDED:-29926, callingPkg: matsjoe.com.surroundings, service: oauth2:https://www.googleapis.com/auth/games https://www.googleapis.com/auth/plus.login] 05-04 20:05:26.951 13654-13860/? I/Auth.Core﹕ [TokenCache] Missing snowballing token: no granted scopes set. 05-04 20:05:26.957 20062-28111/? V/AuthAccountOperation﹕ access token request successful 05-04 20:05:27.115 28081-28081/? W/dex2oat﹕ Compilation of void com.google.android.ads.zxxz.a.() took 141.927ms 05-04 20:05:27.115 28081-28124/? W/dex2oat﹕ Compilation of void com.google.android.ads.zxxz.c.() took 141.652ms 05-04 20:05:27.116 28081-28122/? W/dex2oat﹕ Compilation of void com.google.android.ads.zxxz.b.() took 141.671ms 05-04 20:05:27.117 28081-28123/? W/dex2oat﹕ Compilation of void com.google.android.ads.zxxz.d.() took 140.870ms 05-04 20:05:27.144 28081-28081/? I/dex2oat﹕ dex2oat took 347.872ms (threads: 4) arena alloc=178KB java alloc=10KB native alloc=1220KB free=6MB 05-04 20:05:27.147 13654-13861/? I/GLSUser﹕ [GLSUser] getTokenFromCache: [account: ELLIDED:-29926, callingPkg: matsjoe.com.surroundings, service: oauth2:https://www.googleapis.com/auth/games https://www.googleapis.com/auth/plus.login] 05-04 20:05:27.149 13654-13861/? I/Auth.Core﹕ [TokenCache] Missing snowballing token: no granted scopes set. 05-04 20:05:27.166 27979-27996/matsjoe.com.surroundings I/art﹕ Background sticky concurrent mark sweep GC freed 13704(919KB) AllocSpace objects, 8(124KB) LOS objects, 0% free, 57MB/57MB, paused 1.450ms total 178.981ms 05-04 20:05:27.375 28046-28046/? W/StaticBindingVerifier﹕ Verify 05-04 20:05:27.440 27979-27979/matsjoe.com.surroundings I/Choreographer﹕ Skipped 32 frames! The application may be doing too much work on its main thread. 05-04 20:05:27.518 740-819/? I/ActivityManager﹕ Displayed matsjoe.com.surroundings/.GameListActivity: +1s132ms 05-04 20:05:27.546 13654-13857/? I/GLSUser﹕ [GLSUser] getTokenFromCache: [account: ELLIDED:-29926, callingPkg: com.google.android.gms, service: oauth2:https://www.googleapis.com/auth/games.firstparty] 05-04 20:05:27.555 13654-13857/? I/Auth.Core﹕ [TokenCache] Missing snowballing token: no granted scopes set. 05-04 20:05:27.644 740-860/? D/WifiService﹕ New client listening to asynchronous messages 05-04 20:05:28.049 28046-28060/? W/art﹕ Suspending all threads took: 14.506ms 05-04 20:05:28.334 740-6316/? I/art﹕ Explicit concurrent mark sweep GC freed 32149(1486KB) AllocSpace objects, 3(176KB) LOS objects, 29% free, 38MB/54MB, paused 1.675ms total 75.417ms 05-04 20:05:28.368 27979-27979/matsjoe.com.surroundings D/matches﹕ --------LOAD MATCHES RESPONSE------ 05-04 20:05:28.655 28046-28046/? W/fb4a(:):AbstractMqttPushService﹕ Attempt to start service that is already started 05-04 20:05:29.324 13718-13852/? D/LocationFilter﹕ Forcing stable location. Original location:Location[fused 53,221458,6,527084 acc=49 et=+1d7h34m51s43ms] because connected WiFi network is the same, bssid=80:3c:08:01:6b:f0 05-04 20:05:29.328 740-812/? E/LocSvc_eng﹕ E/int loc_eng_inject_location(loc_eng_data_s_type&, double, double, float): log_eng state error: instance not initialized 05-04 20:05:29.328 740-812/? E/LocSvc_eng﹕ E/int loc_eng_inject_location(loc_eng_data_s_type&, double, double, float): log_eng state error: instance not initialized 05-04 20:05:29.339 740-1278/? D/WifiService﹕ releaseWifiLockLocked: WifiLock{NlpWifiLock type=2 binder=android.os.BinderProxy@29053a8c} 05-04 20:05:29.596 20062-29281/? I/Ads﹕ CsiReporterFactory: CSI is not enabled. No CSI reporter created. 05-04 20:05:29.633 20062-20062/? W/art﹕ Attempt to remove local handle scope entry from IRT, ignoring 05-04 20:05:29.635 20062-20062/? W/AwContents﹕ onDetachedFromWindow called when already detached. Ignoring 05-04 20:05:29.650 20062-20062/? W/art﹕ Attempt to remove local handle scope entry from IRT, ignoring 05-04 20:05:29.650 20062-20062/? W/art﹕ Attempt to remove local handle scope entry from IRT, ignoring 05-04 20:05:29.680 20062-20062/? W/BindingManager﹕ Cannot call determinedVisibility() - never saw a connection for the pid: 20062 05-04 20:05:29.705 20062-20062/? I/chromium﹕ [INFO:CONSOLE(0)] "Document was loaded from Application Cache with manifest https://googleads.g.doubleclick.net/mads/static/mad/sdk/native/sdk-core-v40.appcache", source: https://googleads.g.doubleclick.net/mads/static/mad/sdk/native/sdk-core-v40.html (0) 05-04 20:05:29.705 20062-20062/? I/chromium﹕ [INFO:CONSOLE(0)] "Application Cache Checking event", source: https://googleads.g.doubleclick.net/mads/static/mad/sdk/native/sdk-core-v40.html (0) 05-04 20:05:29.796 20062-20062/? I/chromium﹕ [INFO:CONSOLE(0)] "Application Cache NoUpdate event", source: https://googleads.g.doubleclick.net/mads/static/mad/sdk/native/sdk-core-v40.html (0) 05-04 20:05:29.999 20062-20062/? W/BindingManager﹕ Cannot call determinedVisibility() - never saw a connection for the pid: 20062 05-04 20:05:30.003 20062-20062/? W/ScreenOrientationListener﹕ Removing an inexistent observer! 05-04 20:05:30.068 27979-28097/matsjoe.com.surroundings W/chromium﹕ [WARNING:data_reduction_proxy_config.cc(150)] SPDY proxy OFF at startup 05-04 20:05:30.075 27979-27979/matsjoe.com.surroundings W/art﹕ Attempt to remove local handle scope entry from IRT, ignoring 05-04 20:05:30.082 27979-27979/matsjoe.com.surroundings W/AwContents﹕ onDetachedFromWindow called when already detached. Ignoring 05-04 20:05:30.091 27979-27979/matsjoe.com.surroundings W/art﹕ Attempt to remove local handle scope entry from IRT, ignoring 05-04 20:05:30.091 27979-27979/matsjoe.com.surroundings W/art﹕ Attempt to remove local handle scope entry from IRT, ignoring 05-04 20:05:30.246 27979-27979/matsjoe.com.surroundings W/BindingManager﹕ Cannot call determinedVisibility() - never saw a connection for the pid: 27979 05-04 20:05:30.699 27979-27979/matsjoe.com.surroundings I/Ads﹕ Scheduling ad refresh 60000 milliseconds from now. 05-04 20:05:30.707 27979-27979/matsjoe.com.surroundings W/art﹕ Attempt to remove local handle scope entry from IRT, ignoring 05-04 20:05:30.709 27979-27979/matsjoe.com.surroundings W/AwContents﹕ onDetachedFromWindow called when already detached. Ignoring 05-04 20:05:30.722 27979-27979/matsjoe.com.surroundings W/art﹕ Attempt to remove local handle scope entry from IRT, ignoring 05-04 20:05:30.722 27979-27979/matsjoe.com.surroundings W/art﹕ Attempt to remove local handle scope entry from IRT, ignoring 05-04 20:05:30.731 27979-27979/matsjoe.com.surroundings I/Ads﹕ Ad finished loading. 05-04 20:05:30.808 27979-27979/matsjoe.com.surroundings W/BindingManager﹕ Cannot call determinedVisibility() - never saw a connection for the pid: 27979 05-04 20:05:30.821 740-813/? W/ProcessCpuTracker﹕ Skipping unknown process pid 28255

Or: http://pastebin.com/PdhMHZBk for working log and http://pastebin.com/6GJuhPxa for not working.

Hope it can help. (Tip:in the non working log search for 'matches' added log message before calling the loadmatches function and one in the response method

[claywilkinson]

Thanks! I'll take a look.

[mkalksma]

Meanwhile I tried to test it by creating a new game in the developer dashboard. And also disabled all the close/release functions on the data returned. This didn't help either.

[TroelsN]

I have the exact same problem. If I deploy a new version of my APK from eclipse all matches usually disappear. The same happens to my two beta testers when they update the app from the Play Store.

I also once had all matches disappear from my device while my app was running: the callback from Games.TurnBasedMultiplayer.takeTurn reported status code 1 (STATUS_INTERNAL_ERROR) and no match data was returned. After that all matches were gone.

[jorgelamb]

We have same issue: updating the app (our beta users updating to a beta version in this case) loses all the matches. We are afraid to release the new version to prod with this issue and have all users complain.

While developing, as we have the app from play store + the test app from Android Studio (different package name) it doesn't lose the matches. Maybe it detects that at least one other app is linked to the game?

Our workaround for the close group of beta-testers is to ask them to move in all the games before updating. This way, once the other players in the game move, they are returned again by the api. This is not viable when updating Prod version of our game.

[trowe67]

I have the exact same problem. If I deploy a new version of my APK from eclipse all matches usually disappear. Very annoying when I am trying to test something that only happens sometimes in games. This has only been happening for the last few weeks too.

[TroelsN]

Exact same problem exists with googles own example app called Skeleton TBMP. When I deploy a new APK version from eclipse all matches disappear.

Just to make sure not to have missed anything I installed a new development environment and created the example app from scratch. Went through all the required steps stated in the documentation for "Configuring Google Play Games Services" and "Setting Up Google Play Games Services". Downloaded "Google Play Services SDK" and "Google Play game services - Android Samples".

This is a serious issue that prevents us from releasing our app.

[justinhessdev]

I also have that same issue using Skeleton TBMP. Whenever I make an update and relaunch my game, I lose all my saved games. This started happening to me about 3 weeks ago.

[TroelsN]

Any news on this?

[claywilkinson]

We've been able to reproduce it - Stay tuned for updates.

[TroelsN]

That is good news!

[justinhessdev]

All my saved games are back! I have yet to make an update to my apk and run the app again to check if the saved games are still there...But this seems very promising! Thanks :)

[justinhessdev]

Update: Unfortunately my saved games disappear when I update my app and launch it through my test devices. Please fix.

[TroelsN]

No fix yet? - Am I tuned in to the right channel? :-)

[trowe67]

Any update on this? It's a real pain trying to debug, when you can't keep the game state after you make a coding change!

[jorgelamb]

We are still waiting for a solution before releasing a new version of our game, as it is a big issue if the users lost their games.

For development it works fine for us, though. We have two different package ids granted (ie. com.example.app and com.example.app.debug) so we can always have the current version on play store and our debug version. Maybe having the two different apps with the same games is a workaround that works, you can give it a try.

[claywilkinson]

There is a fix in-flight, it should be rolling out shortly ( < month most likely). I can't really say precisely when right now, but as I know more, I'll pass it along.

[jorgelamb]

We got a new version of Play Games, is the fix there? Or what should we wait to know it's resolved?

[claywilkinson]

I believe the new version has the fix in it.

[claywilkinson]

Closing this issue, I believe the fix has been rolled out. Please open another issue if you are still having the problem. Thanks!