New issue
Advanced search Search tips
Note: Color blocks (like or ) mean that a user may not be available. Tooltip shows the reason.

Issue 818675 link

Starred by 2 users

Issue metadata

Status: WontFix
Owner:
Closed: Mar 2018
Cc:
Components:
EstimatedDays: ----
NextAction: ----
OS: Chrome
Pri: 2
Type: Bug



Sign in to add a comment

Sync failing

Project Member Reported by reillyg@chromium.org, Mar 5 2018

Issue description

Chrome Version: 65.0.3325.107
OS: Chrome OS Platform 10323.46.0

What steps will reproduce the problem?
(1) Add bookmark.

What is the expected result?
Bookmark is synced to other instances of Chrome signed in this account.

What happens instead?
Sync fails.

From chrome://sync-internals I see that the sync server is returning SYNC_SERVER_ERROR to the GetUpdate request.

I have two profiles on this machine and both of them are in this state.

Status dump:

Mon Mar 05 2018 07:06:54 GMT-0800 (PST)
======
Status
======
{
  "actionable_error": [
    {
      "is_valid": false,
      "stat_name": "Error Type",
      "stat_value": "Uninitialized"
    },
    {
      "is_valid": false,
      "stat_name": "Action",
      "stat_value": "Uninitialized"
    },
    {
      "is_valid": false,
      "stat_name": "URL",
      "stat_value": "Uninitialized"
    },
    {
      "is_valid": false,
      "stat_name": "Error Description",
      "stat_value": "Uninitialized"
    }
  ],
  "actionable_error_detected": false,
  "details": [
    {
      "data": [
        {
          "is_valid": true,
          "stat_name": "Summary",
          "stat_value": "Sync service initialized"
        }
      ],
      "is_sensitive": false,
      "title": "Summary"
    },
    {
      "data": [
        {
          "is_valid": true,
          "stat_name": "Client Version",
          "stat_value": "Google Chrome Chrome OS 65.0.3325.107 (0) beta"
        },
        {
          "is_valid": true,
          "stat_name": "Server URL",
          "stat_value": "https://clients4.google.com/chrome-sync"
        }
      ],
      "is_sensitive": false,
      "title": "Version Info"
    },
    {
      "data": [
        {
          "is_valid": true,
          "stat_name": "Requested Token",
          "stat_value": "Monday, March 5, 2018 at 4:50:32 AM"
        },
        {
          "is_valid": true,
          "stat_name": "Received Token",
          "stat_value": "Monday, March 5, 2018 at 4:50:32 AM"
        },
        {
          "is_valid": true,
          "stat_name": "Token Request Status",
          "stat_value": "OK"
        },
        {
          "is_valid": true,
          "stat_name": "Next Token Request",
          "stat_value": "not scheduled"
        }
      ],
      "is_sensitive": false,
      "title": "Credentials"
    },
    {
      "data": [
        {
          "is_valid": true,
          "stat_name": "Server Connection",
          "stat_value": "server error since Monday, March 5, 2018 at 4:50:32 AM"
        },
        {
          "is_valid": true,
          "stat_name": "Last Synced",
          "stat_value": "2 mins ago"
        },
        {
          "is_valid": true,
          "stat_name": "Sync First-Time Setup Complete",
          "stat_value": true
        },
        {
          "is_valid": true,
          "stat_name": "Sync Backend Initialization",
          "stat_value": "Started"
        },
        {
          "is_valid": true,
          "stat_name": "Syncing",
          "stat_value": false
        },
        {
          "is_valid": true,
          "stat_name": "Local Sync Backend Enabled",
          "stat_value": false
        },
        {
          "is_valid": false,
          "stat_name": "Local Backend Path",
          "stat_value": "Uninitialized"
        }
      ],
      "is_sensitive": false,
      "title": "Local State"
    },
    {
      "data": [
        {
          "is_valid": true,
          "stat_name": "Throttled or Backoff",
          "stat_value": true
        },
        {
          "is_valid": true,
          "stat_name": "Retry Time",
          "stat_value": "Monday, March 5, 2018 at 7:10:38 AM"
        },
        {
          "is_valid": true,
          "stat_name": "Notifications Enabled",
          "stat_value": true
        }
      ],
      "is_sensitive": false,
      "title": "Network"
    },
    {
      "data": [
        {
          "is_valid": true,
          "stat_name": "Explicit Passphrase",
          "stat_value": false
        },
        {
          "is_valid": true,
          "stat_name": "Passphrase Required",
          "stat_value": false
        },
        {
          "is_valid": true,
          "stat_name": "Cryptographer Ready",
          "stat_value": true
        },
        {
          "is_valid": true,
          "stat_name": "Cryptographer Has Pending Keys",
          "stat_value": false
        },
        {
          "is_valid": true,
          "stat_name": "Encrypted Types",
          "stat_value": "Passwords, WiFi Credentials"
        },
        {
          "is_valid": true,
          "stat_name": "Has Keystore Key",
          "stat_value": true
        },
        {
          "is_valid": true,
          "stat_name": "Keystore Migration Time",
          "stat_value": "Monday, June 9, 2014 at 8:36:13 AM"
        },
        {
          "is_valid": true,
          "stat_name": "Passphrase Type",
          "stat_value": "PassphraseType::KEYSTORE_PASSPHRASE"
        },
        {
          "is_valid": true,
          "stat_name": "Passphrase Time",
          "stat_value": "No Passphrase Time"
        }
      ],
      "is_sensitive": false,
      "title": "Encryption"
    },
    {
      "data": [
        {
          "is_valid": true,
          "stat_name": "Sync Source",
          "stat_value": "NOTIFICATION"
        },
        {
          "is_valid": true,
          "stat_name": "GetKey Step Result",
          "stat_value": "UNSET"
        },
        {
          "is_valid": true,
          "stat_name": "Download Step Result",
          "stat_value": "SYNC_SERVER_ERROR"
        },
        {
          "is_valid": true,
          "stat_name": "Commit Step Result",
          "stat_value": "UNSET"
        }
      ],
      "is_sensitive": false,
      "title": "Status from Last Completed Session"
    },
    {
      "data": [
        {
          "is_valid": true,
          "stat_name": "Notifications Received",
          "stat_value": 136
        },
        {
          "is_valid": true,
          "stat_name": "Updates Downloaded",
          "stat_value": 0
        },
        {
          "is_valid": true,
          "stat_name": "Tombstone Updates",
          "stat_value": 0
        },
        {
          "is_valid": true,
          "stat_name": "Reflected Updates",
          "stat_value": 0
        },
        {
          "is_valid": true,
          "stat_name": "Successful Commits",
          "stat_value": 0
        },
        {
          "is_valid": true,
          "stat_name": "Conflicts Resolved: Client Wins",
          "stat_value": 0
        },
        {
          "is_valid": true,
          "stat_name": "Conflicts Resolved: Server Wins",
          "stat_value": 0
        }
      ],
      "is_sensitive": false,
      "title": "Running Totals"
    },
    {
      "data": [
        {
          "is_valid": true,
          "stat_name": "Encryption Conflicts",
          "stat_value": 0
        },
        {
          "is_valid": true,
          "stat_name": "Hierarchy Conflicts",
          "stat_value": 0
        },
        {
          "is_valid": true,
          "stat_name": "Server Conflicts",
          "stat_value": 0
        },
        {
          "is_valid": true,
          "stat_name": "Committed Items",
          "stat_value": 0
        }
      ],
      "is_sensitive": false,
      "title": "Transient Counters (this cycle)"
    },
    {
      "data": [
        {
          "is_valid": true,
          "stat_name": "Updates Downloaded",
          "stat_value": 0
        },
        {
          "is_valid": true,
          "stat_name": "Committed Count",
          "stat_value": 0
        },
        {
          "is_valid": true,
          "stat_name": "Entries",
          "stat_value": 3836
        }
      ],
      "is_sensitive": false,
      "title": "Transient Counters (last cycle of last completed session)"
    },
    {
      "data": [
        {
          "is_valid": true,
          "stat_name": "Server Invalidations",
          "stat_value": 0
        },
        {
          "is_valid": true,
          "stat_name": "Local Changes",
          "stat_value": 0
        },
        {
          "is_valid": true,
          "stat_name": "Local Refreshes",
          "stat_value": 0
        }
      ],
      "is_sensitive": false,
      "title": "Nudge Source Counters"
    }
  ],
  "type_status": [
    {
      "group_type": "Group Type",
      "message": "Message",
      "name": "Model Type",
      "num_entries": "Total Entries",
      "num_live": "Live Entries",
      "state": "State",
      "status": "header"
    },
    {
      "group_type": "Group UI",
      "message": "Backed off",
      "name": "Bookmarks",
      "state": "Running",
      "status": "warning"
    },
    {
      "group_type": "Group UI",
      "message": "Backed off",
      "name": "Preferences",
      "state": "Running",
      "status": "warning"
    },
    {
      "group_type": "Group Password",
      "message": "Backed off",
      "name": "Passwords",
      "state": "Running",
      "status": "warning"
    },
    {
      "group_type": "Group DB",
      "message": "Backed off",
      "name": "Autofill Profiles",
      "state": "Running",
      "status": "warning"
    },
    {
      "group_type": "Group Non Blocking",
      "message": "Backed off",
      "name": "Autofill",
      "state": "Running",
      "status": "warning"
    },
    {
      "group_type": "Group DB",
      "message": "Backed off",
      "name": "Autofill Wallet",
      "state": "Running",
      "status": "warning"
    },
    {
      "group_type": "Group DB",
      "message": "Backed off",
      "name": "Autofill Wallet Metadata",
      "state": "Running",
      "status": "warning",
      "num_entries": 3,
      "num_live": 3
    },
    {
      "group_type": "Group UI",
      "message": "Backed off",
      "name": "Themes",
      "state": "Running",
      "status": "warning",
      "num_entries": 2,
      "num_live": 2
    },
    {
      "group_type": "Group History",
      "message": "Backed off",
      "name": "Typed URLs",
      "state": "Running",
      "status": "warning",
      "num_entries": 2533,
      "num_live": 2533
    },
    {
      "group_type": "Group UI",
      "message": "Backed off",
      "name": "Extensions",
      "state": "Running",
      "status": "warning",
      "num_entries": 12,
      "num_live": 12
    },
    {
      "group_type": "Group UI",
      "message": "Backed off",
      "name": "Search Engines",
      "state": "Running",
      "status": "warning",
      "num_entries": 128,
      "num_live": 127
    },
    {
      "group_type": "Group UI",
      "message": "Backed off",
      "name": "Sessions",
      "state": "Running",
      "status": "warning",
      "num_entries": 362,
      "num_live": 362
    },
    {
      "group_type": "Group UI",
      "message": "Backed off",
      "name": "Apps",
      "state": "Running",
      "status": "warning",
      "num_entries": 32,
      "num_live": 32
    },
    {
      "group_type": "Group File",
      "message": "Backed off",
      "name": "App settings",
      "state": "Running",
      "status": "warning",
      "num_entries": 25,
      "num_live": 25
    },
    {
      "group_type": "Group File",
      "message": "Backed off",
      "name": "Extension settings",
      "state": "Running",
      "status": "warning",
      "num_entries": 7,
      "num_live": 7
    },
    {
      "group_type": "Group UI",
      "message": "Backed off",
      "name": "History Delete Directives",
      "state": "Running",
      "status": "warning",
      "num_entries": 1,
      "num_live": 1
    },
    {
      "group_type": "Group UI",
      "message": "Backed off",
      "name": "Dictionary",
      "state": "Running",
      "status": "warning",
      "num_entries": 13,
      "num_live": 13
    },
    {
      "group_type": "Group UI",
      "message": "Backed off",
      "name": "Favicon Images",
      "state": "Running",
      "status": "warning",
      "num_entries": 226,
      "num_live": 200
    },
    {
      "group_type": "Group UI",
      "message": "Backed off",
      "name": "Favicon Tracking",
      "state": "Running",
      "status": "warning",
      "num_entries": 227,
      "num_live": 201
    },
    {
      "group_type": "Group Non Blocking",
      "message": "Backed off",
      "name": "Device Info",
      "state": "Running",
      "status": "warning",
      "num_entries": 9,
      "num_live": 9
    },
    {
      "group_type": "Group UI",
      "message": "Backed off",
      "name": "Priority Preferences",
      "state": "Running",
      "status": "warning",
      "num_entries": 14,
      "num_live": 14
    },
    {
      "group_type": "Group Passive",
      "message": "Datatype not ready at config time.",
      "name": "Managed User Settings",
      "state": "Not Running",
      "status": "disabled",
      "num_entries": 0,
      "num_live": 0
    },
    {
      "group_type": "Group UI",
      "message": "Backed off",
      "name": "Managed Users",
      "state": "Running",
      "status": "warning",
      "num_entries": 1,
      "num_live": 1
    },
    {
      "group_type": "Group UI",
      "message": "Backed off",
      "name": "Managed User Shared Settings",
      "state": "Running",
      "status": "warning",
      "num_entries": 1,
      "num_live": 1
    },
    {
      "group_type": "Group UI",
      "message": "Backed off",
      "name": "App List",
      "state": "Running",
      "status": "warning",
      "num_entries": 78,
      "num_live": 78
    },
    {
      "group_type": "Group Passive",
      "message": "Datatype not ready at config time.",
      "name": "Managed User Whitelists",
      "state": "Not Running",
      "status": "disabled",
      "num_entries": 0,
      "num_live": 0
    },
    {
      "group_type": "Group Passive",
      "message": "Datatype not ready at config time.",
      "name": "Arc Package",
      "state": "Not Running",
      "status": "disabled",
      "num_entries": 0,
      "num_live": 0
    },
    {
      "group_type": "Group Non Blocking",
      "message": "Backed off",
      "name": "Printers",
      "state": "Running",
      "status": "warning",
      "num_entries": 0,
      "num_live": 0
    },
    {
      "group_type": "Group Non Blocking",
      "message": "Backed off",
      "name": "User Events",
      "state": "Running",
      "status": "warning",
      "num_entries": 370,
      "num_live": 370
    },
    {
      "group_type": "Group Passive",
      "message": "Backed off",
      "name": "Tabs",
      "state": "Running",
      "status": "warning",
      "num_entries": 0,
      "num_live": 0
    }
  ],
  "unrecoverable_error_detected": false
}

 
Labels: Sync-Triaged
Owner: mastiz@chromium.org
Status: Assigned (was: Untriaged)
Thanks for reporting!

Have you experienced 
(truncated message above)

How long have you been experiencing this issue? I assume it does not get fixed after you restart Chrome?
I can try restarting Chrome again when I get home. I believe this issue has persisted across a number of reboots as I only recently noticed that bookmarks I created about 2 weeks ago had not been syncing.
I restarted Chrome again last night and am getting the same errors this morning and no data has been synced to other devices that do not show these errors.
Owner: sfiera@chromium.org
sfiera@ (please reassign back): This seems to be a case sync itself has no auth error but GetUpdate fails with SYNC_SERVER_ERROR. Does this ring a bell? Do we have server-side metrics for these errors?


Owner: mastiz@chromium.org
I’m not aware of any ongoing issues implicated by this. SYNC_SERVER_ERROR doesn’t tell me much about what the underlying server-side error is (and it may be deliberate that the server doesn’t provide debugging information).

I’d be curious in the content of the bookmark’s sync node from chrome://sync-internals, and whether other changes can still sync (e.g. changing title of an old bookmark, or a preference), but I’m not really sure how to get started investigating server-side.
Please tell me how to collect the necessary data.

Comment 8 by mastiz@chromium.org, Mar 12 2018

reillyg@, I'm afraid we don't have many ideas, so I'll need to start with broad questions.

Could you please answer the following:
1. Is this a custom chrome build, test google account, or enterprise google account?
2. Have you ever enabled a custom sync passphrase for this account or profile?
3. Can you repro in different networks (e.g. GoogleGuest)?
4. Can you repro the same in stable channel?
5. (Optional) can you repro the same using a newly-created profile and test account?

Comment 9 by mastiz@chromium.org, Mar 12 2018

Labels: Needs-Feedback
Status: WontFix (was: Assigned)
1. No, no, yes but this issue also reproduces on my personal account which is signed into another profile on the machine.
2. No.
3. The device is at home.
4/5. I need to wipe this machine for other reasons so I will write this off as a fluke unless it reoccurs.
Thanks, please reopen if you encounter this issue again.

Sign in to add a comment