src/sync/engine/syncer_proto_util.cc

   1 // Copyright (c) 2012 The Chromium Authors. All rights reserved.
   2 // Use of this source code is governed by a BSD-style license that can be
   3 // found in the LICENSE file.
   4
   5 #include "sync/engine/syncer_proto_util.h"
   6
   7 #include "base/format_macros.h"
   8 #include "base/strings/stringprintf.h"
   9 #include "google_apis/google_api_keys.h"
  10 #include "sync/engine/net/server_connection_manager.h"
  11 #include "sync/engine/syncer.h"
  12 #include "sync/engine/syncer_types.h"
  13 #include "sync/engine/traffic_logger.h"
  14 #include "sync/internal_api/public/base/model_type.h"
  15 #include "sync/protocol/sync_enums.pb.h"
  16 #include "sync/protocol/sync_protocol_error.h"
  17 #include "sync/sessions/sync_session.h"
  18 #include "sync/syncable/directory.h"
  19 #include "sync/syncable/entry.h"
  20 #include "sync/syncable/syncable-inl.h"
  21 #include "sync/syncable/syncable_proto_util.h"
  22 #include "sync/util/time.h"
  23
  24 using std::string;
  25 using std::stringstream;
  26 using sync_pb::ClientToServerMessage;
  27 using sync_pb::ClientToServerResponse;
  28
  29 namespace syncer {
  30
  31 using sessions::SyncSession;
  32 using syncable::BASE_VERSION;
  33 using syncable::CTIME;
  34 using syncable::ID;
  35 using syncable::IS_DEL;
  36 using syncable::IS_DIR;
  37 using syncable::IS_UNSYNCED;
  38 using syncable::MTIME;
  39 using syncable::PARENT_ID;
  40
  41 namespace {
  42
  43 // Time to backoff syncing after receiving a throttled response.
  44 const int kSyncDelayAfterThrottled = 2 * 60 * 60;  // 2 hours
  45
  46 void LogResponseProfilingData(const ClientToServerResponse& response) {
  47   if (response.has_profiling_data()) {
  48     stringstream response_trace;
  49     response_trace << "Server response trace:";
  50
  51     if (response.profiling_data().has_user_lookup_time()) {
  52       response_trace << " user lookup: "
  53                      << response.profiling_data().user_lookup_time() << "ms";
  54     }
  55
  56     if (response.profiling_data().has_meta_data_write_time()) {
  57       response_trace << " meta write: "
  58                      << response.profiling_data().meta_data_write_time()
  59                      << "ms";
  60     }
  61
  62     if (response.profiling_data().has_meta_data_read_time()) {
  63       response_trace << " meta read: "
  64                      << response.profiling_data().meta_data_read_time() << "ms";
  65     }
  66
  67     if (response.profiling_data().has_file_data_write_time()) {
  68       response_trace << " file write: "
  69                      << response.profiling_data().file_data_write_time()
  70                      << "ms";
  71     }
  72
  73     if (response.profiling_data().has_file_data_read_time()) {
  74       response_trace << " file read: "
  75                      << response.profiling_data().file_data_read_time() << "ms";
  76     }
  77
  78     if (response.profiling_data().has_total_request_time()) {
  79       response_trace << " total time: "
  80                      << response.profiling_data().total_request_time() << "ms";
  81     }
  82     DVLOG(1) << response_trace.str();
  83   }
  84 }
  85
  86 SyncerError ServerConnectionErrorAsSyncerError(
  87     const HttpResponse::ServerConnectionCode server_status) {
  88   switch (server_status) {
  89     case HttpResponse::CONNECTION_UNAVAILABLE:
  90       return NETWORK_CONNECTION_UNAVAILABLE;
  91     case HttpResponse::IO_ERROR:
  92       return NETWORK_IO_ERROR;
  93     case HttpResponse::SYNC_SERVER_ERROR:
  94       // FIXME what does this mean?
  95       return SYNC_SERVER_ERROR;
  96     case HttpResponse::SYNC_AUTH_ERROR:
  97       return SYNC_AUTH_ERROR;
  98     case HttpResponse::RETRY:
  99       return SERVER_RETURN_TRANSIENT_ERROR;
 100     case HttpResponse::SERVER_CONNECTION_OK:
 101     case HttpResponse::NONE:
 102     default:
 103       NOTREACHED();
 104       return UNSET;
 105   }
 106 }
 107
 108 SyncProtocolErrorType ConvertSyncProtocolErrorTypePBToLocalType(
 109     const sync_pb::SyncEnums::ErrorType& error_type) {
 110   switch (error_type) {
 111     case sync_pb::SyncEnums::SUCCESS:
 112       return SYNC_SUCCESS;
 113     case sync_pb::SyncEnums::NOT_MY_BIRTHDAY:
 114       return NOT_MY_BIRTHDAY;
 115     case sync_pb::SyncEnums::THROTTLED:
 116       return THROTTLED;
 117     case sync_pb::SyncEnums::CLEAR_PENDING:
 118       return CLEAR_PENDING;
 119     case sync_pb::SyncEnums::TRANSIENT_ERROR:
 120       return TRANSIENT_ERROR;
 121     case sync_pb::SyncEnums::MIGRATION_DONE:
 122       return MIGRATION_DONE;
 123     case sync_pb::SyncEnums::DISABLED_BY_ADMIN:
 124       return DISABLED_BY_ADMIN;
 125     case sync_pb::SyncEnums::USER_ROLLBACK:
 126       return USER_ROLLBACK;
 127     case sync_pb::SyncEnums::UNKNOWN:
 128       return UNKNOWN_ERROR;
 129     case sync_pb::SyncEnums::USER_NOT_ACTIVATED:
 130     case sync_pb::SyncEnums::AUTH_INVALID:
 131     case sync_pb::SyncEnums::ACCESS_DENIED:
 132       return INVALID_CREDENTIAL;
 133     default:
 134       NOTREACHED();
 135       return UNKNOWN_ERROR;
 136   }
 137 }
 138
 139 ClientAction ConvertClientActionPBToLocalClientAction(
 140     const sync_pb::SyncEnums::Action& action) {
 141   switch (action) {
 142     case sync_pb::SyncEnums::UPGRADE_CLIENT:
 143       return UPGRADE_CLIENT;
 144     case sync_pb::SyncEnums::CLEAR_USER_DATA_AND_RESYNC:
 145       return CLEAR_USER_DATA_AND_RESYNC;
 146     case sync_pb::SyncEnums::ENABLE_SYNC_ON_ACCOUNT:
 147       return ENABLE_SYNC_ON_ACCOUNT;
 148     case sync_pb::SyncEnums::STOP_AND_RESTART_SYNC:
 149       return STOP_AND_RESTART_SYNC;
 150     case sync_pb::SyncEnums::DISABLE_SYNC_ON_CLIENT:
 151       return DISABLE_SYNC_ON_CLIENT;
 152     case sync_pb::SyncEnums::UNKNOWN_ACTION:
 153       return UNKNOWN_ACTION;
 154     default:
 155       NOTREACHED();
 156       return UNKNOWN_ACTION;
 157   }
 158 }
 159
 160 }  // namespace
 161
 162 ModelTypeSet GetTypesToMigrate(const ClientToServerResponse& response) {
 163   ModelTypeSet to_migrate;
 164   for (int i = 0; i < response.migrated_data_type_id_size(); i++) {
 165     int field_number = response.migrated_data_type_id(i);
 166     ModelType model_type = GetModelTypeFromSpecificsFieldNumber(field_number);
 167     if (!IsRealDataType(model_type)) {
 168       DLOG(WARNING) << "Unknown field number " << field_number;
 169       continue;
 170     }
 171     to_migrate.Put(model_type);
 172   }
 173   return to_migrate;
 174 }
 175
 176 SyncProtocolError ConvertErrorPBToLocalType(
 177     const sync_pb::ClientToServerResponse_Error& error) {
 178   SyncProtocolError sync_protocol_error;
 179   sync_protocol_error.error_type = ConvertSyncProtocolErrorTypePBToLocalType(
 180       error.error_type());
 181   sync_protocol_error.error_description = error.error_description();
 182   sync_protocol_error.url = error.url();
 183   sync_protocol_error.action = ConvertClientActionPBToLocalClientAction(
 184       error.action());
 185
 186   if (error.error_data_type_ids_size() > 0) {
 187     // THROTTLED is currently the only error code that uses |error_data_types|.
 188     DCHECK_EQ(error.error_type(), sync_pb::SyncEnums::THROTTLED);
 189     for (int i = 0; i < error.error_data_type_ids_size(); ++i) {
 190       int field_number = error.error_data_type_ids(i);
 191       ModelType model_type =
 192           GetModelTypeFromSpecificsFieldNumber(field_number);
 193       if (!IsRealDataType(model_type)) {
 194         DLOG(WARNING) << "Unknown field number " << field_number;
 195         continue;
 196       }
 197       sync_protocol_error.error_data_types.Put(model_type);
 198     }
 199   }
 200
 201   return sync_protocol_error;
 202 }
 203
 204 // static
 205 bool SyncerProtoUtil::VerifyResponseBirthday(
 206     const ClientToServerResponse& response,
 207     syncable::Directory* dir) {
 208
 209   std::string local_birthday = dir->store_birthday();
 210
 211   if (local_birthday.empty()) {
 212     if (!response.has_store_birthday()) {
 213       LOG(WARNING) << "Expected a birthday on first sync.";
 214       return false;
 215     }
 216
 217     DVLOG(1) << "New store birthday: " << response.store_birthday();
 218     dir->set_store_birthday(response.store_birthday());
 219     return true;
 220   }
 221
 222   // Error situation, but we're not stuck.
 223   if (!response.has_store_birthday()) {
 224     LOG(WARNING) << "No birthday in server response?";
 225     return true;
 226   }
 227
 228   if (response.store_birthday() != local_birthday) {
 229     LOG(WARNING) << "Birthday changed, showing syncer stuck";
 230     return false;
 231   }
 232
 233   return true;
 234 }
 235
 236 // static
 237 bool SyncerProtoUtil::IsSyncDisabledByAdmin(
 238     const sync_pb::ClientToServerResponse& response) {
 239   return (response.has_error_code() &&
 240           response.error_code() == sync_pb::SyncEnums::DISABLED_BY_ADMIN);
 241 }
 242
 243 // static
 244 void SyncerProtoUtil::AddRequestBirthday(syncable::Directory* dir,
 245                                          ClientToServerMessage* msg) {
 246   if (!dir->store_birthday().empty())
 247     msg->set_store_birthday(dir->store_birthday());
 248 }
 249
 250 // static
 251 void SyncerProtoUtil::AddBagOfChips(syncable::Directory* dir,
 252                                     ClientToServerMessage* msg) {
 253   msg->mutable_bag_of_chips()->ParseFromString(dir->bag_of_chips());
 254 }
 255
 256 // static
 257 void SyncerProtoUtil::SetProtocolVersion(ClientToServerMessage* msg) {
 258   const int current_version =
 259       ClientToServerMessage::default_instance().protocol_version();
 260   msg->set_protocol_version(current_version);
 261 }
 262
 263 // static
 264 bool SyncerProtoUtil::PostAndProcessHeaders(ServerConnectionManager* scm,
 265                                             sessions::SyncSession* session,
 266                                             const ClientToServerMessage& msg,
 267                                             ClientToServerResponse* response) {
 268   ServerConnectionManager::PostBufferParams params;
 269   DCHECK(msg.has_protocol_version());
 270   DCHECK_EQ(msg.protocol_version(),
 271             ClientToServerMessage::default_instance().protocol_version());
 272   msg.SerializeToString(&params.buffer_in);
 273
 274   ScopedServerStatusWatcher server_status_watcher(scm, &params.response);
 275   // Fills in params.buffer_out and params.response.
 276   if (!scm->PostBufferWithCachedAuth(&params, &server_status_watcher)) {
 277     LOG(WARNING) << "Error posting from syncer:" << params.response;
 278     return false;
 279   }
 280
 281   if (response->ParseFromString(params.buffer_out)) {
 282     // TODO(tim): This is an egregious layering violation (bug 35060).
 283     switch (response->error_code()) {
 284       case sync_pb::SyncEnums::ACCESS_DENIED:
 285       case sync_pb::SyncEnums::AUTH_INVALID:
 286       case sync_pb::SyncEnums::USER_NOT_ACTIVATED:
 287         // Fires on ScopedServerStatusWatcher
 288         params.response.server_status = HttpResponse::SYNC_AUTH_ERROR;
 289         return false;
 290       default:
 291         return true;
 292     }
 293   }
 294
 295   return false;
 296 }
 297
 298 base::TimeDelta SyncerProtoUtil::GetThrottleDelay(
 299     const ClientToServerResponse& response) {
 300   base::TimeDelta throttle_delay =
 301       base::TimeDelta::FromSeconds(kSyncDelayAfterThrottled);
 302   if (response.has_client_command()) {
 303     const sync_pb::ClientCommand& command = response.client_command();
 304     if (command.has_throttle_delay_seconds()) {
 305       throttle_delay =
 306           base::TimeDelta::FromSeconds(command.throttle_delay_seconds());
 307     }
 308   }
 309   return throttle_delay;
 310 }
 311
 312 namespace {
 313
 314 // Helper function for an assertion in PostClientToServerMessage.
 315 bool IsVeryFirstGetUpdates(const ClientToServerMessage& message) {
 316   if (!message.has_get_updates())
 317     return false;
 318   DCHECK_LT(0, message.get_updates().from_progress_marker_size());
 319   for (int i = 0; i < message.get_updates().from_progress_marker_size(); ++i) {
 320     if (!message.get_updates().from_progress_marker(i).token().empty())
 321       return false;
 322   }
 323   return true;
 324 }
 325
 326 // TODO(lipalani) : Rename these function names as per the CR for issue 7740067.
 327 SyncProtocolError ConvertLegacyErrorCodeToNewError(
 328     const sync_pb::SyncEnums::ErrorType& error_type) {
 329   SyncProtocolError error;
 330   error.error_type = ConvertSyncProtocolErrorTypePBToLocalType(error_type);
 331   if (error_type == sync_pb::SyncEnums::CLEAR_PENDING ||
 332       error_type == sync_pb::SyncEnums::NOT_MY_BIRTHDAY) {
 333     error.action = DISABLE_SYNC_ON_CLIENT;
 334   } else if (error_type == sync_pb::SyncEnums::DISABLED_BY_ADMIN) {
 335     error.action = STOP_SYNC_FOR_DISABLED_ACCOUNT;
 336   } else if (error_type == sync_pb::SyncEnums::USER_ROLLBACK) {
 337     error.action = DISABLE_SYNC_AND_ROLLBACK;
 338   } // There is no other action we can compute for legacy server.
 339   return error;
 340 }
 341
 342 }  // namespace
 343
 344 // static
 345 SyncerError SyncerProtoUtil::PostClientToServerMessage(
 346     ClientToServerMessage* msg,
 347     ClientToServerResponse* response,
 348     SyncSession* session) {
 349   CHECK(response);
 350   DCHECK(!msg->get_updates().has_from_timestamp());  // Deprecated.
 351   DCHECK(!msg->get_updates().has_requested_types());  // Deprecated.
 352
 353   // Add must-have fields.
 354   SetProtocolVersion(msg);
 355   AddRequestBirthday(session->context()->directory(), msg);
 356   DCHECK(msg->has_store_birthday() || IsVeryFirstGetUpdates(*msg));
 357   AddBagOfChips(session->context()->directory(), msg);
 358   msg->set_api_key(google_apis::GetAPIKey());
 359   msg->mutable_client_status()->CopyFrom(session->context()->client_status());
 360   msg->set_invalidator_client_id(session->context()->invalidator_client_id());
 361
 362   syncable::Directory* dir = session->context()->directory();
 363
 364   LogClientToServerMessage(*msg);
 365   if (!PostAndProcessHeaders(session->context()->connection_manager(), session,
 366                              *msg, response)) {
 367     // There was an error establishing communication with the server.
 368     // We can not proceed beyond this point.
 369     const HttpResponse::ServerConnectionCode server_status =
 370         session->context()->connection_manager()->server_status();
 371
 372     DCHECK_NE(server_status, HttpResponse::NONE);
 373     DCHECK_NE(server_status, HttpResponse::SERVER_CONNECTION_OK);
 374
 375     return ServerConnectionErrorAsSyncerError(server_status);
 376   }
 377   LogClientToServerResponse(*response);
 378
 379   // Persist a bag of chips if it has been sent by the server.
 380   PersistBagOfChips(dir, *response);
 381
 382   SyncProtocolError sync_protocol_error;
 383
 384   // The DISABLED_BY_ADMIN error overrides other errors sent by the server.
 385   if (IsSyncDisabledByAdmin(*response)) {
 386     sync_protocol_error.error_type = DISABLED_BY_ADMIN;
 387     sync_protocol_error.action = STOP_SYNC_FOR_DISABLED_ACCOUNT;
 388   } else if (!VerifyResponseBirthday(*response, dir)) {
 389     // If sync isn't disabled, first check for a birthday mismatch error.
 390     sync_protocol_error.error_type = NOT_MY_BIRTHDAY;
 391     sync_protocol_error.action = DISABLE_SYNC_ON_CLIENT;
 392   } else if (response->has_error()) {
 393     // This is a new server. Just get the error from the protocol.
 394     sync_protocol_error = ConvertErrorPBToLocalType(response->error());
 395   } else {
 396     // Legacy server implementation. Compute the error based on |error_code|.
 397     sync_protocol_error = ConvertLegacyErrorCodeToNewError(
 398         response->error_code());
 399   }
 400
 401   // Inform the delegate of the error we got.
 402   session->delegate()->OnSyncProtocolError(sync_protocol_error);
 403
 404   // Update our state for any other commands we've received.
 405   if (response->has_client_command()) {
 406     const sync_pb::ClientCommand& command = response->client_command();
 407     if (command.has_max_commit_batch_size()) {
 408       session->context()->set_max_commit_batch_size(
 409           command.max_commit_batch_size());
 410     }
 411
 412     if (command.has_set_sync_long_poll_interval()) {
 413       session->delegate()->OnReceivedLongPollIntervalUpdate(
 414           base::TimeDelta::FromSeconds(command.set_sync_long_poll_interval()));
 415     }
 416
 417     if (command.has_set_sync_poll_interval()) {
 418       session->delegate()->OnReceivedShortPollIntervalUpdate(
 419           base::TimeDelta::FromSeconds(command.set_sync_poll_interval()));
 420     }
 421
 422     if (command.has_sessions_commit_delay_seconds()) {
 423       std::map<ModelType, base::TimeDelta> delay_map;
 424       delay_map[SESSIONS] =
 425           base::TimeDelta::FromSeconds(command.sessions_commit_delay_seconds());
 426       session->delegate()->OnReceivedCustomNudgeDelays(delay_map);
 427     }
 428
 429     if (command.has_client_invalidation_hint_buffer_size()) {
 430       session->delegate()->OnReceivedClientInvalidationHintBufferSize(
 431           command.client_invalidation_hint_buffer_size());
 432     }
 433
 434     if (command.has_gu_retry_delay_seconds()) {
 435       session->delegate()->OnReceivedGuRetryDelay(
 436           base::TimeDelta::FromSeconds(command.gu_retry_delay_seconds()));
 437     }
 438
 439     if (command.custom_nudge_delays_size() > 0) {
 440       // Note that because this happens after the sessions_commit_delay_seconds
 441       // handling, any SESSIONS value in this map will override the one in
 442       // sessions_commit_delay_seconds.
 443       std::map<ModelType, base::TimeDelta> delay_map;
 444       for (int i = 0; i < command.custom_nudge_delays_size(); ++i) {
 445         ModelType type = GetModelTypeFromSpecificsFieldNumber(
 446             command.custom_nudge_delays(i).datatype_id());
 447         if (ProtocolTypes().Has(type)) {
 448           delay_map[type] = base::TimeDelta::FromMilliseconds(
 449               command.custom_nudge_delays(i).delay_ms());
 450         }
 451       }
 452       session->delegate()->OnReceivedCustomNudgeDelays(delay_map);
 453     }
 454   }
 455
 456   // Now do any special handling for the error type and decide on the return
 457   // value.
 458   switch (sync_protocol_error.error_type) {
 459     case UNKNOWN_ERROR:
 460       LOG(WARNING) << "Sync protocol out-of-date. The server is using a more "
 461                    << "recent version.";
 462       return SERVER_RETURN_UNKNOWN_ERROR;
 463     case SYNC_SUCCESS:
 464       LogResponseProfilingData(*response);
 465       return SYNCER_OK;
 466     case THROTTLED:
 467       if (sync_protocol_error.error_data_types.Empty()) {
 468         DLOG(WARNING) << "Client fully throttled by syncer.";
 469         session->delegate()->OnThrottled(GetThrottleDelay(*response));
 470       } else {
 471         DLOG(WARNING) << "Some types throttled by syncer.";
 472         session->delegate()->OnTypesThrottled(
 473             sync_protocol_error.error_data_types,
 474             GetThrottleDelay(*response));
 475       }
 476       return SERVER_RETURN_THROTTLED;
 477     case TRANSIENT_ERROR:
 478       return SERVER_RETURN_TRANSIENT_ERROR;
 479     case MIGRATION_DONE:
 480       LOG_IF(ERROR, 0 >= response->migrated_data_type_id_size())
 481           << "MIGRATION_DONE but no types specified.";
 482       session->delegate()->OnReceivedMigrationRequest(
 483           GetTypesToMigrate(*response));
 484       return SERVER_RETURN_MIGRATION_DONE;
 485     case CLEAR_PENDING:
 486       return SERVER_RETURN_CLEAR_PENDING;
 487     case NOT_MY_BIRTHDAY:
 488       return SERVER_RETURN_NOT_MY_BIRTHDAY;
 489     case DISABLED_BY_ADMIN:
 490       return SERVER_RETURN_DISABLED_BY_ADMIN;
 491     case USER_ROLLBACK:
 492       return SERVER_RETURN_USER_ROLLBACK;
 493     default:
 494       NOTREACHED();
 495       return UNSET;
 496   }
 497 }
 498
 499 // static
 500 bool SyncerProtoUtil::ShouldMaintainPosition(
 501     const sync_pb::SyncEntity& sync_entity) {
 502   // Maintain positions for bookmarks that are not server-defined top-level
 503   // folders.
 504   return GetModelType(sync_entity) == BOOKMARKS
 505       && !(sync_entity.folder() &&
 506            !sync_entity.server_defined_unique_tag().empty());
 507 }
 508
 509 // static
 510 void SyncerProtoUtil::CopyProtoBytesIntoBlob(const std::string& proto_bytes,
 511                                              syncable::Blob* blob) {
 512   syncable::Blob proto_blob(proto_bytes.begin(), proto_bytes.end());
 513   blob->swap(proto_blob);
 514 }
 515
 516 // static
 517 bool SyncerProtoUtil::ProtoBytesEqualsBlob(const std::string& proto_bytes,
 518                                            const syncable::Blob& blob) {
 519   if (proto_bytes.size() != blob.size())
 520     return false;
 521   return std::equal(proto_bytes.begin(), proto_bytes.end(), blob.begin());
 522 }
 523
 524 // static
 525 void SyncerProtoUtil::CopyBlobIntoProtoBytes(const syncable::Blob& blob,
 526                                              std::string* proto_bytes) {
 527   std::string blob_string(blob.begin(), blob.end());
 528   proto_bytes->swap(blob_string);
 529 }
 530
 531 // static
 532 const std::string& SyncerProtoUtil::NameFromSyncEntity(
 533     const sync_pb::SyncEntity& entry) {
 534   if (entry.has_non_unique_name())
 535     return entry.non_unique_name();
 536   return entry.name();
 537 }
 538
 539 // static
 540 const std::string& SyncerProtoUtil::NameFromCommitEntryResponse(
 541     const sync_pb::CommitResponse_EntryResponse& entry) {
 542   if (entry.has_non_unique_name())
 543     return entry.non_unique_name();
 544   return entry.name();
 545 }
 546
 547 // static
 548 void SyncerProtoUtil::PersistBagOfChips(syncable::Directory* dir,
 549     const sync_pb::ClientToServerResponse& response) {
 550   if (!response.has_new_bag_of_chips())
 551     return;
 552   std::string bag_of_chips;
 553   if (response.new_bag_of_chips().SerializeToString(&bag_of_chips))
 554     dir->set_bag_of_chips(bag_of_chips);
 555 }
 556
 557 std::string SyncerProtoUtil::SyncEntityDebugString(
 558     const sync_pb::SyncEntity& entry) {
 559   const std::string& mtime_str =
 560       GetTimeDebugString(ProtoTimeToTime(entry.mtime()));
 561   const std::string& ctime_str =
 562       GetTimeDebugString(ProtoTimeToTime(entry.ctime()));
 563   return base::StringPrintf(
 564       "id: %s, parent_id: %s, "
 565       "version: %" PRId64"d, "
 566       "mtime: %" PRId64"d (%s), "
 567       "ctime: %" PRId64"d (%s), "
 568       "name: %s, sync_timestamp: %" PRId64"d, "
 569       "%s ",
 570       entry.id_string().c_str(),
 571       entry.parent_id_string().c_str(),
 572       entry.version(),
 573       entry.mtime(), mtime_str.c_str(),
 574       entry.ctime(), ctime_str.c_str(),
 575       entry.name().c_str(), entry.sync_timestamp(),
 576       entry.deleted() ? "deleted, ":"");
 577 }
 578
 579 namespace {
 580 std::string GetUpdatesResponseString(
 581     const sync_pb::GetUpdatesResponse& response) {
 582   std::string output;
 583   output.append("GetUpdatesResponse:\n");
 584   for (int i = 0; i < response.entries_size(); i++) {
 585     output.append(SyncerProtoUtil::SyncEntityDebugString(response.entries(i)));
 586     output.append("\n");
 587   }
 588   return output;
 589 }
 590 }  // namespace
 591
 592 std::string SyncerProtoUtil::ClientToServerResponseDebugString(
 593     const ClientToServerResponse& response) {
 594   // Add more handlers as needed.
 595   std::string output;
 596   if (response.has_get_updates())
 597     output.append(GetUpdatesResponseString(response.get_updates()));
 598   return output;
 599 }
 600
 601 }  // namespace syncer