Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

Gmail sync #86

Open
nagalun opened this issue Feb 1, 2016 · 6 comments
Open

Gmail sync #86

nagalun opened this issue Feb 1, 2016 · 6 comments

Comments

@nagalun
Copy link
Contributor

nagalun commented Feb 1, 2016 •
edited by mar-v-in
Loading

When gmail sync is enabled under:
Settings > Accounts > Google > (account) > Gmail: ON,
it does not wait between syncs, here is a logcat:
(Is there another email client that uses GCM like gmail?)

02-01 11:27:13.863 11596 16471 W Gmail   : Sync started for account: account:1200572429
02-01 11:27:13.864 11596 16471 I Gmail   : notifyAccountChanged
02-01 11:27:13.867 11596 11620 I Gmail   : getAccountsCursor
02-01 11:27:13.871  1472 11676 D GmsAuthenticator: hasFeatures: Account {name=my-account@gmail.com, type=com.google}, [service_mail]
02-01 11:27:13.880 11596 16471 I Gmail   : MainSyncRequestProto: lowestBkwdConvoId: 0, highestHandledServerOp: 126568, normalSync: true
02-01 11:27:13.906 11596 16471 D ProviderInstallerImpl: yep, i should do something with Security here...
02-01 11:27:13.910  1472 12075 D GmsAuthenticator: getAuthToken: Account {name=my-account@gmail.com, type=com.google}, mail, Bundle[{androidPackageName=com.google.android.gm, callerPid=11596, callerUid=10139, notifyOnAuthFailure=true}]
02-01 11:27:13.912  1472 12075 D GmsAuthManager: token present, but expired
02-01 11:27:13.919  1472 12075 D GmsHttpFormClient: -- Request --
02-01 11:27:13.919  1472 12075 D GmsHttpFormClient: accountType=HOSTED_OR_GOOGLE&androidId=my-important-stuff
02-01 11:27:15.403  1472 12075 D GmsHttpFormClient: -- Response --
02-01 11:27:15.403  1472 12075 D GmsHttpFormClient: SID=my-important-stuff
02-01 11:27:15.403  1472 12075 D GmsHttpFormClient: LSID=my-important-stuff
02-01 11:27:15.403  1472 12075 D GmsHttpFormClient: Auth=my-important-stuff
02-01 11:27:15.403  1472 12075 D GmsHttpFormClient: issueAdvice=auto
02-01 11:27:15.403  1472 12075 D GmsHttpFormClient: services=mail,android,cl,talk,friendview,lso,youtube,chromiumsync,multilogin,lh2,urlshortener,omaha,writely,wise,oz,mymaps,googleme,esmobile
02-01 11:27:15.403  1472 12075 D GmsHttpFormClient: firstName=my-name
02-01 11:27:15.403  1472 12075 D GmsHttpFormClient: lastName=.
02-01 11:27:15.498  1472 12075 D GmsAuthenticator: getAuthToken: my-important-token
02-01 11:27:16.128  1472 12075 D GmsAuthenticator: hasFeatures: Account {name=my-account@gmail.com, type=com.google}, [service_mail, service_gmailrenameeligible]
02-01 11:27:16.273 11596 16471 I Gmail   : MainSyncRequestProto: lowestBkwdConvoId: 0, highestHandledServerOp: 126670, normalSync: true
02-01 11:27:16.279 11596 16471 D ProviderInstallerImpl: yep, i should do something with Security here...
02-01 11:27:16.285  1472 11676 D GmsAuthenticator: getAuthToken: Account {name=my-account@gmail.com, type=com.google}, mail, Bundle[{androidPackageName=com.google.android.gm, callerPid=11596, callerUid=10139, notifyOnAuthFailure=true}]
02-01 11:27:16.287  1472 11676 D GmsAuthManager: token present, but expired
02-01 11:27:16.294  1472 11676 D GmsHttpFormClient: -- Request --
02-01 11:27:16.294  1472 11676 D GmsHttpFormClient: accountType=HOSTED_OR_GOOGLE&androidId=my-important-stuff
02-01 11:27:16.560  1472 11676 D GmsHttpFormClient: -- Response --
02-01 11:27:16.560  1472 11676 D GmsHttpFormClient: SID=my-important-stuff
02-01 11:27:16.560  1472 11676 D GmsHttpFormClient: LSID=my-important-stuff
02-01 11:27:16.560  1472 11676 D GmsHttpFormClient: Auth=my-important-stuff
02-01 11:27:16.560  1472 11676 D GmsHttpFormClient: issueAdvice=auto
02-01 11:27:16.560  1472 11676 D GmsHttpFormClient: services=mail,android,cl,talk,friendview,lso,youtube,chromiumsync,multilogin,lh2,urlshortener,omaha,writely,wise,oz,mymaps,googleme,esmobile
02-01 11:27:16.560  1472 11676 D GmsHttpFormClient: firstName=my-name
02-01 11:27:16.560  1472 11676 D GmsHttpFormClient: lastName=.
02-01 11:27:16.667  1472 11676 D GmsAuthenticator: getAuthToken: my-important-token
02-01 11:27:17.027  1472 11676 D GmsAuthenticator: hasFeatures: Account {name=my-account@gmail.com, type=com.google}, [service_mail, service_gmailrenameeligible]
02-01 11:27:17.166 11596 16471 I Gmail   : MainSyncRequestProto: lowestBkwdConvoId: 0, highestHandledServerOp: 126670, normalSync: true
02-01 11:27:17.167 11596 16471 I Gmail   : lowestBackward conversation id 0
02-01 11:27:17.189  1472  1472 D GmsIcingIndexSvc: onBind: Intent { act=com.google.android.gms.icing.INDEX_SERVICE pkg=com.google.android.gms }
02-01 11:27:17.194  1472 12075 D GmsIcingIndexSvc: bound by: GetServiceRequest{serviceId=SEARCH_QUERIES, gmsVersion=7327000, packageName='com.google.android.gm', extras=Bundle[{}]}
02-01 11:27:17.197  1472  1483 D GmsIcingIndexSvc: bound by: GetServiceRequest{serviceId=SEARCH_CORPORA, gmsVersion=7327000, packageName='com.google.android.gm', extras=Bundle[{}]}
02-01 11:27:17.200  1472 11676 D GmsIcingCorporaImpl: getCorpusStatus: GetCorpusStatusRequest{versionCode=1, packageName='com.google.android.gm', corpus='messages/com.google/my-account%40gmail.com'}
02-01 11:27:17.204 11596 16471 E Gmail   : Account account:1200572429 is not registered for search
02-01 11:27:17.240 11596 16471 I Gmail   : Sending provider changed intent: Intent { act=android.intent.action.PROVIDER_CHANGED dat=content://gmail-ls/unread/^i (has extras) }
02-01 11:27:17.250 11596 16503 I NotifUtils: Validating Notification, mapSize: 1 getAttention: true ignoreUnobtrusive: true
02-01 11:27:17.260 11596 16471 I Gmail   : notifyAccountChanged
02-01 11:27:17.262 11596 16503 I NotifUtils: Showing notification with unreadCount of 7 and unseenCount of 2
02-01 11:27:17.266 11596 11613 I Gmail   : getAccountsCursor
02-01 11:27:17.271  1472  1485 D GmsAuthenticator: hasFeatures: Account {name=my-account@gmail.com, type=com.google}, [service_mail]
02-01 11:27:17.277 11596 16471 W Gmail   : Sync complete for account: account:1200572429
02-01 11:27:17.367  1472  1472 D GmsPeopleSvc: onBind: Intent { act=com.google.android.gms.people.service.START pkg=com.google.android.gms }
02-01 11:27:17.371 11596 16509 W Gmail   : Sync started for account: account:1200572429
02-01 11:27:17.372 11596 16509 I Gmail   : notifyAccountChanged
02-01 11:27:17.374  1472 11676 D GmsPeopleSvc: bound by: GetServiceRequest{serviceId=PEOPLE, gmsVersion=7327000, packageName='com.google.android.gm', extras=Bundle[{support_new_image_callback=true, social_client_application_id=135, real_client_package_name=com.google.android.gm}]}
02-01 11:27:17.375 11596 11638 I Gmail   : getAccountsCursor
02-01 11:27:17.380  1472 11676 D GmsAuthenticator: hasFeatures: Account {name=my-account@gmail.com, type=com.google}, [service_mail]
02-01 11:27:17.381  1472 12075 D GmsPeopleSvcImpl: loadAutocompleteList: my-account@gmail.com, null, false, com.google, no-reply@google.com, 1, 0, 10, true
02-01 11:27:17.392 11596 16509 I Gmail   : MainSyncRequestProto: lowestBkwdConvoId: 0, highestHandledServerOp: 126670, normalSync: true
02-01 11:27:17.411 11596 16509 D ProviderInstallerImpl: yep, i should do something with Security here...
02-01 11:27:17.415  1472 12076 D GmsAuthenticator: getAuthToken: Account {name=my-account@gmail.com, type=com.google}, mail, Bundle[{androidPackageName=com.google.android.gm, callerPid=11596, callerUid=10139, notifyOnAuthFailure=true}]
02-01 11:27:17.417  1472 12076 D GmsAuthManager: token present, but expired
... lots of spam ...
02-01 11:27:27.553 11596 11628 I Gmail   : master sync=true, engine sync=false

@jamorford
Copy link
Contributor

@nagalun K9-Mail does IMAP IDLE (leaves connection open to receive messages, similar to push) and the official CyanogenMod Email client does push AFAIK.

@bilbonotry bilbonotry mentioned this issue Apr 14, 2016
Closed
@sequielo
Copy link

sequielo commented Dec 23, 2016 •
edited
Loading

This issue continues to be here. Some more feedback:

  • Android c4.4.4
  • Gmail version v4.9 (127612)
  • MicroG GmsCore v0.2.4-30 (latest)

Here is a logcat:

I/Timeline( 1639): Timeline: Activity_windows_visible id: ActivityRecord{4285f958 u0 com.google.android.gm/.ui.MailActivityGmail t223} time:235453175
I/Gmail   ( 6497): Contacts Client connected:
I/Gmail   ( 6497): Sync is off with reason 2
I/Gmail   ( 6497): Sync is off with reason 2
I/Gmail   ( 6497): Sync is off with reason 2
I/ActivityManager( 1639): START u0 {act=android.intent.action.EDIT dat=setting://gmail/?account=account1@domain.com flg=0x80000 cmp=com.google.android.gm/.Gmail2PreferenceActivity} from pid 6497
D/GmsAuthenticator( 6478): hasFeatures: Account {name=account2@dmain.com, type=com.google}, [service_mail]
D/GmsAuthenticator( 6478): hasFeatures: Account {name=account1@domain.com, type=com.google}, [service_mail]
I/ActivityManager( 1639): Displayed com.google.android.gm/.Gmail2PreferenceActivity: +321ms
I/Timeline( 1639): Timeline: Activity_windows_visible id: ActivityRecord{42cdb5d8 u0 com.google.android.gm/.Gmail2PreferenceActivity t223} time:235459866
I/Timeline( 6497): Timeline: Activity_launch_request id:com.google.android.gm time:235460837
I/ActivityManager( 1639): START u0 {act=android.intent.action.MAIN cmp=com.google.android.gm/.preference.GmailPreferenceActivity (has extras)} from pid 6497
D/GmsServicesProvider( 6478): query caller=com.google.android.gm name=gmail_privacy_policy_url value=null
I/ActivityManager( 1639): Displayed com.google.android.gm/.preference.GmailPreferenceActivity: +389ms
I/Timeline( 1639): Timeline: Activity_windows_visible id: ActivityRecord{4228a298 u0 com.google.android.gm/.preference.GmailPreferenceActivity t223} time:235461293
D/GmsAuthenticator( 6478): hasFeatures: Account {name=account2@dmain.com, type=com.google}, [service_mail]
D/GmsAuthenticator( 6478): hasFeatures: Account {name=account1@domain.com, type=com.google}, [service_mail]
D/GmsAuthenticator( 6478): hasFeatures: Account {name=account2@dmain.com, type=com.google}, [service_mail]
D/GmsAuthenticator( 6478): hasFeatures: Account {name=account1@domain.com, type=com.google}, [service_mail]
D/GmsGcmMcsSvc( 6478): Heartbeat initiated, reason: Intent { act=org.microg.gms.gcm.mcs.HEARTBEAT flg=0x4 cmp=com.google.android.gms/org.microg.gms.gcm.McsService (has extras) }
D/GmsAuthenticator( 6478): hasFeatures: Account {name=account2@dmain.com, type=com.google}, [service_mail]
D/GmsAuthenticator( 6478): hasFeatures: Account {name=account1@domain.com, type=com.google}, [service_mail]
D/GmsGcmMcsSvc( 6478): Scheduling heartbeat in 900 seconds...
D/GmsGcmMcsOutput( 6478): Outgoing message: HeartbeatPing{last_stream_id_received=2}
I/Timeline( 1639): Timeline: Activity_windows_visible id: ActivityRecord{42cdb5d8 u0 com.google.android.gm/.Gmail2PreferenceActivity t223} time:235761720
D/GmsGcmMcsInput( 6478): Incoming message: HeartbeatAck{last_stream_id_received=2}
I/Timeline( 6497): Timeline: Activity_launch_request id:com.google.android.gm time:235764666
I/ActivityManager( 1639): START u0 {act=android.intent.action.MAIN cmp=com.google.android.gm/.preference.GmailPreferenceActivity (has extras)} from pid 6497
I/ActivityManager( 1639): Displayed com.google.android.gm/.preference.GmailPreferenceActivity: +419ms
I/Timeline( 1639): Timeline: Activity_windows_visible id: ActivityRecord{42feeb20 u0 com.google.android.gm/.preference.GmailPreferenceActivity t223} time:235765157
I/Gmail   ( 6497): MainSyncRequestProto: lowestBkwdConvoId: 0, highestHandledServerOp: 245174, normalSync: true
D/GmsAuthenticator( 6478): getAuthToken: Account {name=account1@domain.com, type=com.google}, mail, Bundle[{androidPackageName=com.google.android.gm, callerPid=6497, callerUid=10162, notifyOnAuthFailure=true}]
D/GmsAuthManager( 6478): token present, but expired
D/GmsHttpFormClient( 6478): -- Request --
D/GmsHttpFormClient( 6478): accountType=HOSTED_OR_GOOGLE&androidId=35b999f2d118e334&app=com.google.android.gm&client_sig=38918a453d07199354f8b19af05ec6562ced5788&callerPkg=com.google.android.gms&callerSig=38918a453d07199354f8b19af05ec6562ced5788&device_country=ZZ&Email=EMAILADDRESS&Token=oauth2rt_1%2FGhakgFenz36230dzBlIxK1hCP8MxwcKWaSCbf-5hVVs&source=android&service=mail&operatorCountry=ZZ&lang=zz_ZZ&is_called_from_account_manager=1&_opt_is_called_from_account_manager=1&sdk_version=19&has_permission=1&google_play_services_version=10084000
D/GmsHttpFormClient( 6478): -- Response --
D/GmsHttpFormClient( 6478): SID=IQRGP3_tIMUMRakbj_Nm8jR5ESvfYDCoOZphVQ-7xfrJclJay9Dlk0LL9KQg6oEBj2csIg.
D/GmsHttpFormClient( 6478): LSID=IQRGP4ElX6zpLLNeCNXg5GmNzB8Oz3oF65mpWjhXuWlchXrxi6B8jSDApegpST_aTRZ2hg.
D/GmsHttpFormClient( 6478): Auth=IQRGP_cTh6igNnE4gjPJ0RbB4BFoeYtyO6KA6FGUgA7p_lM9V99NST75MngFEBwopENYCg.
D/GmsHttpFormClient( 6478): issueAdvice=auto
D/GmsHttpFormClient( 6478): services=ah,apps,wise,CPanel,sprose,mail,devconsole,sitemaps,mobilesync,mydevices,groups2,talk,cl,writely,jotspot,contacts,multilogin,omaha,friendview,android,googleme,lh2,analytics,gtrans,HOSTED
D/GmsHttpFormClient( 6478): firstName=Ex
D/GmsHttpFormClient( 6478): lastName=Cu
D/GmsAuthenticator( 6478): getAuthToken: IQRGP_cTh6igNnE4gjPJ0RbB4BFoeYtyO6KA6FGUgA7p_lM9V99NST75MngFEBwopENYCg.
I/Gmail   ( 6497): MainSyncRequestProto: lowestBkwdConvoId: 0, highestHandledServerOp: 245174, normalSync: true
I/Gmail   ( 6497): lowestBackward conversation id 0
I/Gmail   ( 6497): MainSyncRequestProto: lowestBkwdConvoId: 0, highestHandledServerOp: 245174, normalSync: true
D/GmsAuthenticator( 6478): getAuthToken: Account {name=account1@domain.com, type=com.google}, mail, Bundle[{androidPackageName=com.google.android.gm, callerPid=6497, callerUid=10162, notifyOnAuthFailure=true}]
D/GmsAuthManager( 6478): token present, but expired
D/GmsHttpFormClient( 6478): -- Request --
D/GmsHttpFormClient( 6478): accountType=HOSTED_OR_GOOGLE&androidId=35b999f2d118e334&app=com.google.android.gm&client_sig=38918a453d07199354f8b19af05ec6562ced5788&callerPkg=com.google.android.gms&callerSig=38918a453d07199354f8b19af05ec6562ced5788&device_country=ZZ&Email=EMAILADDRESS&Token=oauth2rt_1%2FGhakgFenz36230dzBlIxK1hCP8MxwcKWaSCbf-5hVVs&source=android&service=mail&operatorCountry=ZZ&lang=zz_ZZ&is_called_from_account_manager=1&_opt_is_called_from_account_manager=1&sdk_version=19&has_permission=1&google_play_services_version=10084000
D/GmsHttpFormClient( 6478): -- Response --
D/GmsHttpFormClient( 6478): SID=IQRGP5kjtrH6nzxe-SvodcC665X5IJtt7FT14CLIwIWfTq-d16RjRqYsZMvOGoAiDwe_Og.
D/GmsHttpFormClient( 6478): LSID=IQRGP7JZpdQ1LJOS-m29BG5Y3g78SVcYprnV4_wS5J8TiV1HxuF07RzrYfI0lG4-e4DYuw.
D/GmsHttpFormClient( 6478): Auth=IQRGP8UhMkaQ8FoaWLLk128watBrtAWNifQhuUSNw7WPiiKqtieWpvjM5I9QDZLS68aOVA.
D/GmsHttpFormClient( 6478): issueAdvice=auto
D/GmsHttpFormClient( 6478): services=ah,apps,wise,CPanel,sprose,mail,devconsole,sitemaps,mobilesync,mydevices,groups2,talk,cl,writely,jotspot,contacts,multilogin,omaha,friendview,android,googleme,lh2,analytics,gtrans,HOSTED
D/GmsHttpFormClient( 6478): firstName=Ex
D/GmsHttpFormClient( 6478): lastName=Cu
D/GmsAuthenticator( 6478): getAuthToken: IQRGP8UhMkaQ8FoaWLLk128watBrtAWNifQhuUSNw7WPiiKqtieWpvjM5I9QDZLS68aOVA.
I/Gmail   ( 6497): MainSyncRequestProto: lowestBkwdConvoId: 0, highestHandledServerOp: 245174, normalSync: true
I/Gmail   ( 6497): lowestBackward conversation id 0
I/Gmail   ( 6497): MainSyncRequestProto: lowestBkwdConvoId: 0, highestHandledServerOp: 245174, normalSync: true
D/GmsAuthenticator( 6478): getAuthToken: Account {name=account1@domain.com, type=com.google}, mail, Bundle[{androidPackageName=com.google.android.gm, callerPid=6497, callerUid=10162, notifyOnAuthFailure=true}]
D/GmsAuthManager( 6478): token present, but expired
D/GmsHttpFormClient( 6478): -- Request --
D/GmsHttpFormClient( 6478): accountType=HOSTED_OR_GOOGLE&androidId=35b999f2d118e334&app=com.google.android.gm&client_sig=38918a453d07199354f8b19af05ec6562ced5788&callerPkg=com.google.android.gms&callerSig=38918a453d07199354f8b19af05ec6562ced5788&device_country=ZZ&Email=EMAILADDRESS&Token=oauth2rt_1%2FGhakgFenz36230dzBlIxK1hCP8MxwcKWaSCbf-5hVVs&source=android&service=mail&operatorCountry=ZZ&lang=zz_ZZ&is_called_from_account_manager=1&_opt_is_called_from_account_manager=1&sdk_version=19&has_permission=1&google_play_services_version=10084000
D/GmsHttpFormClient( 6478): -- Response --
D/GmsHttpFormClient( 6478): SID=IQRGP1tzBkTYmQ20GxhC4HvzO082EJJ5MZISiVBNUyGJ5GP32-zm0nkiMcEWuXxZtUp7GQ.
D/GmsHttpFormClient( 6478): LSID=IQRGP4R1vLQ1JBTNbCC_W4d5khM_GIf7hjNf29e0rSONslSuET_z41xJkRBMzkIAKfjauw.
D/GmsHttpFormClient( 6478): Auth=IQRGP3jphsnMl2aI0ijvIcHS6YaS_ss4gtMQAHd--7pV0-KSWWEEclCzA9IomhqxVcWlSg.
D/GmsHttpFormClient( 6478): issueAdvice=auto
D/GmsHttpFormClient( 6478): services=ah,apps,wise,CPanel,sprose,mail,devconsole,sitemaps,mobilesync,mydevices,groups2,talk,cl,writely,jotspot,contacts,multilogin,omaha,friendview,android,googleme,lh2,analytics,gtrans,HOSTED
D/GmsHttpFormClient( 6478): firstName=Ex
D/GmsHttpFormClient( 6478): lastName=Cu
D/GmsAuthenticator( 6478): getAuthToken: IQRGP3jphsnMl2aI0ijvIcHS6YaS_ss4gtMQAHd--7pV0-KSWWEEclCzA9IomhqxVcWlSg.
I/Gmail   ( 6497): MainSyncRequestProto: lowestBkwdConvoId: 0, highestHandledServerOp: 245174, normalSync: true
I/Gmail   ( 6497): lowestBackward conversation id 0
I/Gmail   ( 6497): MainSyncRequestProto: lowestBkwdConvoId: 0, highestHandledServerOp: 245174, normalSync: true
D/GmsAuthenticator( 6478): getAuthToken: Account {name=account1@domain.com, type=com.google}, mail, Bundle[{androidPackageName=com.google.android.gm, callerPid=6497, callerUid=10162, notifyOnAuthFailure=true}]
D/GmsAuthManager( 6478): token present, but expired
D/GmsHttpFormClient( 6478): -- Request --
D/GmsHttpFormClient( 6478): accountType=HOSTED_OR_GOOGLE&androidId=35b999f2d118e334&app=com.google.android.gm&client_sig=38918a453d07199354f8b19af05ec6562ced5788&callerPkg=com.google.android.gms&callerSig=38918a453d07199354f8b19af05ec6562ced5788&device_country=ZZ&Email=EMAILADDRESS&Token=oauth2rt_1%2FGhakgFenz36230dzBlIxK1hCP8MxwcKWaSCbf-5hVVs&source=android&service=mail&operatorCountry=ZZ&lang=zz_ZZ&is_called_from_account_manager=1&_opt_is_called_from_account_manager=1&sdk_version=19&has_permission=1&google_play_services_version=10084000
D/GmsHttpFormClient( 6478): -- Response --
D/GmsHttpFormClient( 6478): SID=IQRGPyrkoFk8jRMpiFxtLUxxUYjEdNj9es8iK34nPo8US1gRm4WHmO0kTa7J1Cp_WU8bag.
D/GmsHttpFormClient( 6478): LSID=IQRGP2Z6WJnaz9AjdZNP-WaIYVh4ghjsQtWbdOGhvveKzXPn-3QNYAhaxK6U_flvioFUHg.
D/GmsHttpFormClient( 6478): Auth=IQRGP_ng7bb2saaSZqqsvdk02gnDoiAwHoN95BcMEqCbzHtHmpXWAC3W2h97YBWydiI3FA.
D/GmsHttpFormClient( 6478): issueAdvice=auto
D/GmsHttpFormClient( 6478): services=ah,apps,wise,CPanel,sprose,mail,devconsole,sitemaps,mobilesync,mydevices,groups2,talk,cl,writely,jotspot,contacts,multilogin,omaha,friendview,android,googleme,lh2,analytics,gtrans,HOSTED

This line is really is suspicious:
D/GmsAuthManager( 6478): token present, but expired

mar-v-in added a commit that referenced this issue Dec 23, 2016
@mar-v-in
Various fixes
05a8b44 
related to #86, #164, #242
@sequielo
Copy link

Thank for the fast update @mar-v-in, the "token present, but expired" message disappeared from logs.

Nevertheless, the original issue (it doesn't wait between syncs) still occurs:
Here is a logcat:

12-26 16:14:59.213 W/ContextImpl( 1639): Calling a method in the system process without a qualified user: android.app.ContextImpl.sendBroadcast:1187 com.android.server.content.SyncStorageEngine.setMasterSyncAutomatically:894 com.android.server.content.ContentService.setMasterSyncAutomatically:631 android.content.IContentService$Stub.onTransact:292 com.android.server.content.ContentService.onTransact:140 
12-26 16:14:59.952 W/Gmail   ( 3916): Sync started for account: account:-136509172
12-26 16:14:59.960 I/Gmail   ( 3916): notifyAccountChanged
12-26 16:14:59.960 I/Gmail   ( 3916): getAccountsCursor
12-26 16:14:59.968 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_1, type=com.google}, [service_mail]
12-26 16:14:59.975 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_2, type=com.google}, [service_mail]
12-26 16:14:59.975 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_3, type=com.google}, [service_mail]
12-26 16:15:00.061 I/Gmail   ( 3916): master sync=true, engine sync=false
12-26 16:15:00.061 I/Gmail   ( 3916): MainSyncRequestProto: lowestBkwdConvoId: 0, highestHandledServerOp: 830670, normalSync: true
12-26 16:15:00.085 I/Gmail   ( 3916): master sync=true, engine sync=false
12-26 16:15:00.124 I/Xposed  ( 1639): FakeGApps: returning fake signature to com.google.android.gm
12-26 16:15:00.124 I/Xposed  ( 1639): FakeGApps: returning fake signature to com.google.android.gm
12-26 16:15:00.163 I/Xposed  ( 1639): FakeGApps: returning fake signature to com.google.android.gm
12-26 16:15:00.171 D/ProviderInstallerImpl( 3916): yep, i should do something with Security here...
12-26 16:15:00.179 D/GmsAuthenticator( 1964): getAuthToken: Account {name=ACCOUNT_1, type=com.google}, mail, Bundle[{androidPackageName=com.google.android.gm, callerPid=3916, callerUid=10163, notifyOnAuthFailure=true}]
12-26 16:15:00.179 D/GmsAuthManager( 1964): peekAuthToken: com.google.android.gm:38918a453d07199354f8b19af05ec6562ced5788:mail
12-26 16:15:00.179 D/GmsAuthenticator( 1964): getAuthToken: JATVtV1PFFzrlgTmJLAeUtGWQE2hi_52xZ1A_wdJBVDkkbrcdUemqaX0tKmvmn2neMktXA.
12-26 16:15:00.733 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_1, type=com.google}, [service_mail, service_gmailrenameeligible]
12-26 16:15:00.741 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_2, type=com.google}, [service_mail, service_gmailrenameeligible]
12-26 16:15:00.749 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_3, type=com.google}, [service_mail, service_gmailrenameeligible]
12-26 16:15:00.960 I/Gmail   ( 3916): MainSyncRequestProto: lowestBkwdConvoId: 0, highestHandledServerOp: 830676, normalSync: true
12-26 16:15:00.968 I/Gmail   ( 3916): lowestBackward conversation id 0
12-26 16:15:01.233 I/Gmail   ( 3916): notifyAccountChanged
12-26 16:15:01.233 I/Gmail   ( 3916): getAccountsCursor
12-26 16:15:01.233 W/Gmail   ( 3916): Sync complete for account: account:-136509172
12-26 16:15:01.233 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_1, type=com.google}, [service_mail]
12-26 16:15:01.241 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_2, type=com.google}, [service_mail]
12-26 16:15:01.241 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_3, type=com.google}, [service_mail]
12-26 16:15:01.319 I/Gmail   ( 3916): master sync=true, engine sync=false
12-26 16:15:01.483 I/Gmail   ( 3916): master sync=true, engine sync=false
12-26 16:15:02.491 I/Xposed  ( 1639): FakeGApps: returning fake signature to com.google.android.gm
12-26 16:15:02.491 I/Xposed  ( 1639): FakeGApps: returning fake signature to com.google.android.gm
12-26 16:15:02.514 I/Xposed  ( 1639): FakeGApps: returning fake signature to com.google.android.gm
12-26 16:15:02.530 D/ProviderInstallerImpl( 3916): yep, i should do something with Security here...
12-26 16:15:02.538 I/Xposed  ( 1639): FakeGApps: returning fake signature to com.google.android.gm
12-26 16:15:02.538 I/Xposed  ( 1639): FakeGApps: returning fake signature to com.google.android.gm
12-26 16:15:02.546 D/ProviderInstallerImpl( 3916): yep, i should do something with Security here...
12-26 16:15:05.436 V/PanelView( 1830): animationTick called with dtms=0; nothing to do (h=782.38 v=-429.95602)
12-26 16:15:05.436 V/PanelView( 1830): animationTick called with dtms=-7; nothing to do (h=782.38 v=-429.95602)
12-26 16:15:06.132 W/InputMethodManagerService( 1639): Window already focused, ignoring focus gain of: com.android.internal.view.IInputMethodClient$Stub$Proxy@4296ac70 attribute=android.view.inputmethod.EditorInfo@429561c8, token = android.os.BinderProxy@42a80f80
12-26 16:15:07.296 W/Gmail   ( 3916): Sync started for account: account:-136509172
12-26 16:15:07.296 I/Gmail   ( 3916): notifyAccountChanged
12-26 16:15:07.296 I/Gmail   ( 3916): getAccountsCursor
12-26 16:15:07.327 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_1, type=com.google}, [service_mail]
12-26 16:15:07.327 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_2, type=com.google}, [service_mail]
12-26 16:15:07.335 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_3, type=com.google}, [service_mail]
12-26 16:15:07.366 I/Gmail   ( 3916): master sync=true, engine sync=false
12-26 16:15:07.374 I/Gmail   ( 3916): MainSyncRequestProto: lowestBkwdConvoId: 0, highestHandledServerOp: 830676, normalSync: true
12-26 16:15:07.382 I/Gmail   ( 3916): master sync=true, engine sync=false
12-26 16:15:07.632 I/Xposed  ( 1639): FakeGApps: returning fake signature to com.google.android.gm
12-26 16:15:07.632 I/Xposed  ( 1639): FakeGApps: returning fake signature to com.google.android.gm
12-26 16:15:07.639 D/ProviderInstallerImpl( 3916): yep, i should do something with Security here...
12-26 16:15:07.647 D/GmsAuthenticator( 1964): getAuthToken: Account {name=ACCOUNT_1, type=com.google}, mail, Bundle[{androidPackageName=com.google.android.gm, callerPid=3916, callerUid=10163, notifyOnAuthFailure=true}]
12-26 16:15:07.655 D/GmsAuthManager( 1964): peekAuthToken: com.google.android.gm:38918a453d07199354f8b19af05ec6562ced5788:mail
12-26 16:15:07.655 D/GmsAuthenticator( 1964): getAuthToken: JATVtV1PFFzrlgTmJLAeUtGWQE2hi_52xZ1A_wdJBVDkkbrcdUemqaX0tKmvmn2neMktXA.
12-26 16:15:07.897 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_1, type=com.google}, [service_mail, service_gmailrenameeligible]
12-26 16:15:07.897 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_2, type=com.google}, [service_mail, service_gmailrenameeligible]
12-26 16:15:07.897 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_3, type=com.google}, [service_mail, service_gmailrenameeligible]
12-26 16:15:08.139 I/Gmail   ( 3916): MainSyncRequestProto: lowestBkwdConvoId: 0, highestHandledServerOp: 830676, normalSync: true
12-26 16:15:08.139 I/Gmail   ( 3916): lowestBackward conversation id 0
12-26 16:15:08.257 I/Gmail   ( 3916): notifyAccountChanged
12-26 16:15:08.264 I/Gmail   ( 3916): getAccountsCursor
12-26 16:15:08.264 W/Gmail   ( 3916): Sync complete for account: account:-136509172
12-26 16:15:08.264 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_1, type=com.google}, [service_mail]
12-26 16:15:08.264 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_2, type=com.google}, [service_mail]
12-26 16:15:08.272 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_3, type=com.google}, [service_mail]
12-26 16:15:08.311 I/Gmail   ( 3916): master sync=true, engine sync=false
12-26 16:15:08.468 I/Gmail   ( 3916): master sync=true, engine sync=false
12-26 16:15:08.608 W/Gmail   ( 3916): Sync started for account: account:-136509172
12-26 16:15:08.608 I/Gmail   ( 3916): notifyAccountChanged
12-26 16:15:08.632 I/Gmail   ( 3916): getAccountsCursor
12-26 16:15:08.632 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_1, type=com.google}, [service_mail]
12-26 16:15:08.639 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_2, type=com.google}, [service_mail]
12-26 16:15:08.639 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_3, type=com.google}, [service_mail]
12-26 16:15:08.663 I/Gmail   ( 3916): master sync=true, engine sync=false
12-26 16:15:08.671 I/Gmail   ( 3916): MainSyncRequestProto: lowestBkwdConvoId: 0, highestHandledServerOp: 830676, normalSync: true
12-26 16:15:08.686 I/Gmail   ( 3916): master sync=true, engine sync=false
12-26 16:15:08.725 I/Xposed  ( 1639): FakeGApps: returning fake signature to com.google.android.gm
12-26 16:15:08.725 I/Xposed  ( 1639): FakeGApps: returning fake signature to com.google.android.gm
12-26 16:15:08.741 I/Xposed  ( 1639): FakeGApps: returning fake signature to com.google.android.gm
12-26 16:15:08.749 D/ProviderInstallerImpl( 3916): yep, i should do something with Security here...
12-26 16:15:08.960 D/GmsAuthenticator( 1964): getAuthToken: Account {name=ACCOUNT_1, type=com.google}, mail, Bundle[{androidPackageName=com.google.android.gm, callerPid=3916, callerUid=10163, notifyOnAuthFailure=true}]
12-26 16:15:08.960 D/GmsAuthManager( 1964): peekAuthToken: com.google.android.gm:38918a453d07199354f8b19af05ec6562ced5788:mail
12-26 16:15:08.968 D/GmsAuthenticator( 1964): getAuthToken: JATVtV1PFFzrlgTmJLAeUtGWQE2hi_52xZ1A_wdJBVDkkbrcdUemqaX0tKmvmn2neMktXA.
12-26 16:15:09.593 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_1, type=com.google}, [service_mail, service_gmailrenameeligible]
12-26 16:15:09.600 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_2, type=com.google}, [service_mail, service_gmailrenameeligible]
12-26 16:15:09.616 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_3, type=com.google}, [service_mail, service_gmailrenameeligible]
12-26 16:15:09.788 I/Gmail   ( 3916): MainSyncRequestProto: lowestBkwdConvoId: 0, highestHandledServerOp: 830676, normalSync: true
12-26 16:15:09.788 I/Gmail   ( 3916): lowestBackward conversation id 0
12-26 16:15:09.929 I/Gmail   ( 3916): notifyAccountChanged
12-26 16:15:09.929 I/Gmail   ( 3916): getAccountsCursor
12-26 16:15:09.929 W/Gmail   ( 3916): Sync complete for account: account:-136509172
12-26 16:15:09.936 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_1, type=com.google}, [service_mail]
12-26 16:15:09.936 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_2, type=com.google}, [service_mail]
12-26 16:15:09.944 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_3, type=com.google}, [service_mail]
12-26 16:15:10.132 I/Gmail   ( 3916): master sync=true, engine sync=false
12-26 16:15:10.218 I/Gmail   ( 3916): master sync=true, engine sync=false
12-26 16:15:10.249 W/Gmail   ( 3916): Sync started for account: account:-136509172
12-26 16:15:10.249 I/Gmail   ( 3916): notifyAccountChanged
12-26 16:15:10.272 I/Gmail   ( 3916): getAccountsCursor
12-26 16:15:10.272 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_1, type=com.google}, [service_mail]
12-26 16:15:10.280 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_2, type=com.google}, [service_mail]
12-26 16:15:10.280 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_3, type=com.google}, [service_mail]
12-26 16:15:10.304 I/Gmail   ( 3916): MainSyncRequestProto: lowestBkwdConvoId: 0, highestHandledServerOp: 830676, normalSync: true
12-26 16:15:10.319 I/Gmail   ( 3916): master sync=true, engine sync=false
12-26 16:15:10.335 I/Gmail   ( 3916): master sync=true, engine sync=false
12-26 16:15:10.366 I/Xposed  ( 1639): FakeGApps: returning fake signature to com.google.android.gm
12-26 16:15:10.374 I/Xposed  ( 1639): FakeGApps: returning fake signature to com.google.android.gm
12-26 16:15:10.382 I/Xposed  ( 1639): FakeGApps: returning fake signature to com.google.android.gm
12-26 16:15:10.397 D/ProviderInstallerImpl( 3916): yep, i should do something with Security here...
12-26 16:15:10.397 D/GmsAuthenticator( 1964): getAuthToken: Account {name=ACCOUNT_1, type=com.google}, mail, Bundle[{androidPackageName=com.google.android.gm, callerPid=3916, callerUid=10163, notifyOnAuthFailure=true}]
12-26 16:15:10.405 D/GmsAuthManager( 1964): peekAuthToken: com.google.android.gm:38918a453d07199354f8b19af05ec6562ced5788:mail
12-26 16:15:10.405 D/GmsAuthenticator( 1964): getAuthToken: JATVtV1PFFzrlgTmJLAeUtGWQE2hi_52xZ1A_wdJBVDkkbrcdUemqaX0tKmvmn2neMktXA.
12-26 16:15:10.647 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_1, type=com.google}, [service_mail, service_gmailrenameeligible]
12-26 16:15:10.655 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_2, type=com.google}, [service_mail, service_gmailrenameeligible]
12-26 16:15:10.655 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_3, type=com.google}, [service_mail, service_gmailrenameeligible]
12-26 16:15:10.819 V/PanelView( 1830): animationTick called with dtms=0; nothing to do (h=247.49683 v=2130.3552)
12-26 16:15:10.819 V/PanelView( 1830): animationTick called with dtms=-9; nothing to do (h=247.49683 v=2130.3552)
12-26 16:15:11.249 I/Gmail   ( 3916): MainSyncRequestProto: lowestBkwdConvoId: 0, highestHandledServerOp: 830676, normalSync: true
12-26 16:15:11.249 I/Gmail   ( 3916): lowestBackward conversation id 0
12-26 16:15:11.358 I/Gmail   ( 3916): notifyAccountChanged
12-26 16:15:11.366 I/Gmail   ( 3916): getAccountsCursor
12-26 16:15:11.366 W/Gmail   ( 3916): Sync complete for account: account:-136509172
12-26 16:15:11.366 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_1, type=com.google}, [service_mail]
12-26 16:15:11.366 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_2, type=com.google}, [service_mail]
12-26 16:15:11.374 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_3, type=com.google}, [service_mail]
12-26 16:15:11.468 W/ContextImpl( 1639): Calling a method in the system process without a qualified user: android.app.ContextImpl.sendBroadcast:1187 com.android.server.content.SyncStorageEngine.setMasterSyncAutomatically:894 com.android.server.content.ContentService.setMasterSyncAutomatically:631 android.content.IContentService$Stub.onTransact:292 com.android.server.content.ContentService.onTransact:140 
12-26 16:15:11.593 I/Gmail   ( 3916): master sync=false, engine sync=false
12-26 16:15:11.788 I/Gmail   ( 3916): master sync=false, engine sync=false
12-26 16:15:11.796 W/Gmail   ( 3916): Sync started for account: account:-136509172
12-26 16:15:11.827 I/Gmail   ( 3916): notifyAccountChanged
12-26 16:15:11.835 I/Gmail   ( 3916): getAccountsCursor
12-26 16:15:11.843 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_1, type=com.google}, [service_mail]
12-26 16:15:11.843 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_2, type=com.google}, [service_mail]
12-26 16:15:11.843 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_3, type=com.google}, [service_mail]
12-26 16:15:11.858 I/Gmail   ( 3916): master sync=false, engine sync=true
12-26 16:15:11.874 I/Gmail   ( 3916): MainSyncRequestProto: lowestBkwdConvoId: 0, highestHandledServerOp: 830676, normalSync: true
12-26 16:15:11.874 I/Gmail   ( 3916): master sync=false, engine sync=false
12-26 16:15:11.889 I/Gmail   ( 3916): master sync=false, engine sync=false
12-26 16:15:11.897 I/Xposed  ( 1639): FakeGApps: returning fake signature to com.google.android.gm
12-26 16:15:11.913 I/Xposed  ( 1639): FakeGApps: returning fake signature to com.google.android.gm
12-26 16:15:11.944 I/Xposed  ( 1639): FakeGApps: returning fake signature to com.google.android.gm
12-26 16:15:11.952 D/ProviderInstallerImpl( 3916): yep, i should do something with Security here...
12-26 16:15:11.952 D/GmsAuthenticator( 1964): getAuthToken: Account {name=ACCOUNT_1, type=com.google}, mail, Bundle[{androidPackageName=com.google.android.gm, callerPid=3916, callerUid=10163, notifyOnAuthFailure=true}]
12-26 16:15:11.960 D/GmsAuthManager( 1964): peekAuthToken: com.google.android.gm:38918a453d07199354f8b19af05ec6562ced5788:mail
12-26 16:15:11.960 D/GmsAuthenticator( 1964): getAuthToken: JATVtV1PFFzrlgTmJLAeUtGWQE2hi_52xZ1A_wdJBVDkkbrcdUemqaX0tKmvmn2neMktXA.
12-26 16:15:12.202 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_1, type=com.google}, [service_mail, service_gmailrenameeligible]
12-26 16:15:12.210 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_2, type=com.google}, [service_mail, service_gmailrenameeligible]
12-26 16:15:12.210 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_3, type=com.google}, [service_mail, service_gmailrenameeligible]
12-26 16:15:12.405 I/Gmail   ( 3916): MainSyncRequestProto: lowestBkwdConvoId: 0, highestHandledServerOp: 830676, normalSync: true
12-26 16:15:12.405 I/Gmail   ( 3916): lowestBackward conversation id 0
12-26 16:15:12.475 I/Gmail   ( 3916): notifyAccountChanged
12-26 16:15:12.475 I/Gmail   ( 3916): getAccountsCursor
12-26 16:15:12.475 W/Gmail   ( 3916): Sync complete for account: account:-136509172
12-26 16:15:12.475 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_1, type=com.google}, [service_mail]
12-26 16:15:12.483 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_2, type=com.google}, [service_mail]
12-26 16:15:12.483 D/GmsAuthenticator( 1964): hasFeatures: Account {name=ACCOUNT_3, type=com.google}, [service_mail]
12-26 16:15:12.499 I/Gmail   ( 3916): master sync=false, engine sync=true
12-26 16:15:12.530 I/Gmail   ( 3916): master sync=false, engine sync=false
12-26 16:15:12.538 I/Gmail   ( 3916): master sync=false, engine sync=false
  • Android v4.4.4
  • Gmail version v5.0.1
  • MicroG GmsCore v0.2.4-39

If you need more details, please let me know where to look and kindly help you.
Thank you again!

@HardLight
Copy link

HardLight commented Mar 25, 2017 •
edited
Loading

@mar-v-in Problem still persists. Gmail sync loops and that cause serious battery drain. Only workaround is to turn off sync manually.
@sequielo, @nagalun have you figured out better solution?

Also it seems that issue occurs only then using multiple accounts (>2 probably).

OS: CM12.1
MicroG: v0.2.4-81
Gmail: tried versions between 6.11.27 and 7.3.13.

@ale5000-git
Copy link
Member

Have you tried with the version 0.2.4-103 of microG?

@HardLight
Copy link

@ale5000-git
Problem still exists on version 0.2.4-105.

This was referenced Oct 7, 2018
Closed
Merged
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
5 participants
@sequielo @HardLight @jamorford @nagalun @ale5000-git