search

microg / GmsCore

Free implementation of Play Services
https://microg.org
Apache License 2.0
8.66k stars 1.73k forks source link

Gmail sync #86

Open nagalun opened 8 years ago

nagalun commented 8 years ago

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 commented 8 years ago

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

sequielo commented 7 years ago

This issue continues to be here. Some more feedback:

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

sequielo commented 7 years ago

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

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

HardLight commented 7 years ago

@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 commented 7 years ago

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

HardLight commented 7 years ago

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