From: File "Nolumes/BuildData/pulse-

From: File "Nolumes/BuildData/pulse- datathgents/599942/recipes/941592543/base/b/drb/googleclient/apps/webdrive_sync/osiddist/Google Drive.app/Contents/ftesources/_boot_.py", line 77, in <module> File "Nolumes/BuildData/pulse- datathgents/599942/recipes/941592543/base/b/drb/googleclient/apps/webdrive_sync/osiddist/Google Drive.app/Contents/ftesources/_boot_.py", line 62, in _run File "/Applications/Google Drive.app/Contents/Resourceslmain.py", line 12, in <module> File "osx/run_googledrive.pyo", line 31, in Main File "PyObjCTools/AppHelper.pyo", line 235, in runEventLoop File "osic/GSyncAppDelegate.pyo", line 296, in applicationWillTerminate_ File "osx/mac_sync_app_controller.pyo", line 263, in Exit File "common/ui_action_handler.pyo", line 444, in Quit File "common/sync_app.pyo", line 1742, in Quit 2016-03-31 11:00:04,014 -0400 INFO pid=1052 140735119745024:MainThread thread_manager.pyo:116 Stop was called 2016-03-31 11:00:04,014 -0400 INFO pid=1052 140735119745024:MainThread thread_manager.pyo:253 Asking <PushXmppClient(XmppClient, started 123145328779264)> with tid 123145328779264 to stop. 2016-03-31 11:00:04,014 -0400 WARNING pid=1052 140735119745024:MainThread task.pyo:242 DriveXmppTask-0xIlle779d0 aborting: abort 2016-03-31 11:00:04,015 -0400 INFO pid=1052 140735119745024:MainThread task.pyo:254 Task <common.push.push_xmpp_client.DriveXmppTask object at 0x111e779d0> complete. 2016-03-31 11:00:04,015 -0400 INFO pid=1052 140735119745024:MainThread xmpplib.pyo:143 Close called with 'why' reason = Client initiated close 2016-03-31 11:06:49,996 -0400 INFO pid=1478 140735119745024:MainThread logging_config.pyo:257 OS: Darwin/10.11.3 2016-03-31 11:06:49,997 -0400 INFO pid=1478 140735119745024:MainThread logging_config.pyo:260 Google Drive (build 1.28.1549.1322) 2016-03-31 11:06:49,997 -0400 INFO pid=1478 140735119745024:MainThread logging_config.pyo:261 SSL: OpenSSL 0.9.82g 14 July 2015 2016-03-31 11:06:50,115 -0400 INFO pid=1478 140735119745024:MainThread GSyncAppDelegate.pyo:198 Application did finish launching. 2016-03-31 11:06:50,137 -0400 INFO pid=1478 140735119745024:MainThread service.pyo:132 Creating singleton service instance of '<class 'common.file_lock.FileLocki> 1 2016-03-31 11:06:50,138 -0400 INFO pid=1478 140735119745024:MainThread service.pyo:132 Creating singleton service instance of '<class 'common.file_lock.FileLocki> 1 2016-03-31 11:06:50,149 -0400 INFO pid=1478 123145309270016:RunAsync-_InitializeSyncAppAsync- 1 service.pyo:132 Creating singleton service instance of '<class 'common.service.user.User'>' 2016-03-31 11:06:50,149 -0400 INFO pid=1478 123145309270016:RunAsync-_InitializeSyncAppAsync- 1 service.pyo:132 Creating singleton service instance of '<class 'common.roots.roots.RootManager'>' 2016-03-31 11:06:50,149 -0400 INFO pid=1478 123145309270016:RunAsync-_InitializeSyncAppAsync- 1 service.pyo:132 Creating singleton service instance of '<class 'common. thread_manager. ThreadManager'>' 2016-03-31 11:06:50,150 -0400 INFO pid=1478 123145309270016:RunAsync-_InitializeSyncAppAsync- 1 service.pyo:132 Creating singleton service instance of '<class Icommon.pause_manager.Service'>' 2016-03-31 11:06:50,150 -0400 INFO pid=1478 123145309270016:RunAsync-_InitializeSyncAppAsync- 1 sync_app.pyo:344 Getting TokenStorage... 2016-03-31 11:06:50,150 -0400 INFO pid=1478 123145309270016:RunAsync-_InitializeSyncAppAsync- 1 sync_app.pyo:346 Got token storage: <platforms.token_storage.TokenStorage object at 0xl0f823310> 2016-03-31 11:06:50,151 -0400 INFO pid=1478 123145309270016:RunAsync-_InitializeSyncAppAsync- 1 service.pyo:132 Creating singleton service instance of '<class 'common.features.AppFeatures'> 1 2016-03-31 11:06:50,151 -0400 INFO pid=1478 123145309270016:RunAsync-_InitializeSyncAppAsync- 1 service.pyo:132 Creating singleton service instance of '<class 'common.cert.Manager'>' 2016-03-31 11:06:50,159 -0400 INFO pid=1478 123145309270016:RunAsync-_InitializeSyncAppAsync- 1 service.pyo:132 Creating singleton service instance of '<class 'common.net.bandwidth.BandwidthThrottler'>' 2016-03-31 11:06:50,161 -0400 INFO pid=1478 123145309270016:RunAsync-_InitializeSyncAppAsync- 1 service.pyo:132 Creating singleton service instance of '<class EFTA00503628 'common.persistence.persistence.Factory'>' 2016-03-31 11:06:50,161 -0400 INFO pid=1478 123145309270016:RunAsync-_InitializeSyncAppAsync- 1 persistence.pyo:39 Initialize factory with policy PlatformPolicy(google_drive_config_directory_path=u'/Users/lgroff/Library/Application Support/Google/Drive', main_sync_root=None, system_download_cache_folder=None, process_id=None) 2016-03-31 11:06:50,162 -0400 INFO pid=1478 123145309270016:RunAsync-_InitializeSyncAppAsync- 1 service.pyo:132 Creating singleton service instance of '<class 'common.persistence.sync_config_sqlite.SyncConfigSOLitel> 1 2016-03-31 11:06:50,162 -0400 INFO pid=1478 123145309270016:RunAsync-_InitializeSyncAppAsync- 1 sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-03-31 11:06:50,163 -0400 INFO pid=1478 123145309270016:RunAsync-_InitializeSyncAppAsync- 1 sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-03-31 11:06:50,164 -0400 INFO pid=1478 123145309270016:RunAsync-_InitializeSyncAppAsync- 1 threads.pyo:130 AddCleanupCallback persistence_sqlite: 94c246d666854d8e9efcf9a5008b8b28/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-03-31 11:06:50,164 -0400 INFO pid=1478 123145309270016:RunAsync-_InitializeSyncAppAsync- 1 threads.pyo: 130 AddCleanupCallback persistence_sqlite:4629cb6f83ae45e4b8d23a1e60b8983b/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-03-31 11:06:50,168 -0400 INFO pid=1478 123145309270016:RunAsync-_InitializeSyncAppAsync- 1 sync_app.pyo:522 BandwidthThrottler configured via sync_config 2016-03-31 11:06:50,168 -0400 INFO pid=1478 123145309270016:RunAsync-_InitializeSyncAppAsync- 1 bandwidth.pyo:59 BandwidthThrottler.SetRxBytesPerSecond 0 2016-03-31 11:06:50,169 -0400 INFO pid=1478 123145309270016:RunAsync-_InitializeSyncAppAsync- 1 bandwidth.pyo:68 BandwidthThrottler.SetTxBytesPerSecond 0 2016-03-31 11:06:50,169 -0400 INFO pid=1478 123145309270016:RunAsync-_InitializeSyncAppAsync- 1 sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-03-31 11:06:50,169 -0400 INFO pid=1478 123145309270016:RunAsync-_InitializeSyncAppAsync- 1 sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-03-31 11:06:50,189 -0400 INFO pid=1478 123145309270016:RunAsync-_InitializeSyncAppAsync- 1 threads.pyo:109 Execute cleanup callback 'persistence_sqlite: 94c246d666854d8e9efcf9a5008b8b28/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2016-03-31 11:06:50,190 -0400 INFO pid=1478 123145309270016:RunAsync-_InitializeSyncAppAsync- 1 threads.pyo:109 Execute cleanup callback 'persistence_sqlite:4629cb6f83ae45e4b8d23ale60b8983b/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2016-03-31 11:06:50,228 -0400 INFO pid=1478 140735119745024:MainThread image_resources.py:228 Loading image images/ic_drawer_24.png 2016-03-31 11:06:50,229 -0400 INFO pid=1478 140735119745024:MainThread image_resources.py:228 Loading image images/menu_drive-logo.png 2016-03-31 11:06:50,230 -0400 INFO pid=1478 140735119745024:MainThread image_resources.py:228 Loading image images/menu_google-logo-gray.png 2016-03-31 11:06:50,233 -0400 INFO pid=1478 140735119745024:MainThread image_resources.py:228 Loading image images/ic_folder_mydrive_24.png 2016-03-31 11:06:50,235 -0400 INFO pid=1478 140735119745024:MainThread image_resources.py:228 Loading image images/ic_web_24.png 2016-03-31 11:06:50,237 -0400 INFO pid=1478 140735119745024:MainThread image_resources.py:228 Loading image images/ic_sync_problem_24.png 2016-03-31 11:06:50,259 -0400 INFO pid=1478 140735119745024:MainThread image_resources.py:228 Loading image images/ic_done.png 2016-03-31 11:06:50,310 -0400 INFO pid=1478 123145315622912:ThreadMonitor thread_manager.pyo:339 Waiting on exit condition. Wake up after 3600s. 2016-03-31 11:06:50,311 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-03-31 11:06:50,312 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db EFTA00503629 2016-03-31 11:06:50,312 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp threads.pyo:130 AddCleanupCallback persistence_sqlite:0126a736d0e444beabb308dd2c757581/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-03-31 11:06:50,313 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp threads.pyo:130 AddCleanupCallback persistence_sqlite:76552d75358840ddad784fd728c72f4b/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-03-31 11:06:50,347 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp user.pyo:68 Initializing User instance with new credentials. None 2016-03-31 11:06:50,348 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp docs_client_factory.pyo:89 Creating a new DriveClient 2016-03-31 11:06:50,348 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.quota.Quotalnfo'>' 2016-03-31 11:06:50,349 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.token_bucket.CloudRucketManager'> 1 2016-03-31 11:06:50,350 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.drive_api.drive_resource.Manager'>' 2016-03-31 11:06:50,350 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.drive_api.http.Manager'>' 2016-03-31 11:06:50,350 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp drive_resource.pyo:134 Initialize apiclient Drive resource for current user token 2016-03-31 11:06:50,351 -0400 ERROR pid=1478 123145311416320:IpcServerThread ipc_socket_base.pyo:247 Failed to bind to socket /Users/lgroff/Library/Group Containers/google_drive/tmpmjMjQF (attempt 1 of 3) Traceback (most recent call last): File "common/ipc/ipc_socket_base.pyo", line 242, in Bind File "common/ipc/ipc_socket_base.pyo", line 176, in Bind File "socket.pyo", line 228, in meth error: (Errno 48] Address already in use 2016-03-31 11:06:50,352 -0400 ERROR pid=1478 123145311416320:IpcServerThread ipc_socket_base.pyo:247 Failed to bind to socket /Users/lgroff/Library/Group Containers/google_drive/tmpmjMjQF (attempt 2 of 3) Traceback (most recent call last): File "common/ipc/ipc_socket_base.pyo", line 242, in Bind File "common/ipc/ipc_socket_base.pyo", line 176, in Bind File "socket.pyo", line 228, in meth error: (Errno 48] Address already in use 2016-03-31 11:06:50,363 -0400 INFO pid=1478 123145319829504: LaunchSyncApp drive_resource.pyo:195 Drive SDK baseUrl = 2016-03-31 11:06:50,364 -0400 ERROR pid=1478 123145311416320:IpcServerThread ipc_socket_base.pyo:247 Failed to bind to socket /Users/lgroff/Library/Group Containers/google_drive/tmpmjMjQF (attempt 3 of 3) Traceback (most recent call last): File "common/ipc/ipc_socket_base.pyo", line 242, in Bind File "common/ipc/ipc_socket_base.pyo", line 176, in Bind File "socket.pyo", line 228, in meth error: (Errno 48] Address already in use 2016-03-31 11:06:50,364 -0400 WARNING pid=1478 123145311416320:IpcServerThread server.pyo:145 Socket (/Users/lgroff/Library/Application Support/Google/Drive/GoogleDrivelpcPipe) already in use, deleting it. 2016-03-31 11:06:50,368 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.drive_api.http.AccessTokenlnMemoryCache'>' 2016-03-31 11:06:50,368 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp drive_request.pyo:290 Drive request: About(tags=Reason.SYNC_OTHER) - Read request - None 2016-03-31 11:06:50,371 -0400 INFO pid=1478 123145319829504: LaunchSyncApp discovery.pyo:762 URL being requested: GET fields=rootFolderld%2CquotaBytesTotal%2CquotaBytesUsedAggregate%2CquotaBytesUsedInTrash%2Cquo taType%2Cuser%2FemailAddress&reason=304Salt=json 2016-03-31 11:06:50,372 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp client.pyo:515 Attempting refresh to obtain initial access_token 2016-03-31 11:06:50,372 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp client.pyo:755 Refreshing access_token 2016-03-31 11:06:50,375 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp service.pyo:132 EFTA00503630 Creating singleton service instance of '<class 'common.net.dapper.DapperService'>' 2016-03-31 11:06:50,375 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp http.pyo:256 Request user-agent: Python-httplib2/0.9.2 (gzip) 2016-03-31 11:06:50,376 -0400 INFO pid=1478 123145319829504: LaunchSyncApp sync_http_client.pyo:238 Opening direct connection to 2016-03-31 11:06:50,539 -0400 INFO pid=1478 123145319829504: LaunchSyncApp http.pyo:275 Response: 152 bytes - application/json; charset=utf-8 (encoding:gzip) 2016-03-31 11:06:50,540 -0400 INFO pid=1478 123145319829504: LaunchSyncApp http.pyo:256 Request user-agent: googledrivesync-1.28.1549.1322 google-api-python-client/1.3.1 (gzip) (Darwin/10.11.3) 2016-03-31 11:06:50,541 -0400 INFO pid=1478 123145319829504: LaunchSyncApp sync_http_client.pyo:238 Opening direct connection to 2016-03-31 11:06:51,175 -0400 INFO pid=1478 123145319829504: LaunchSyncApp http.pyo:275 Response: 242 bytes - application/json; charset=UTF-8 (encoding:gzip) 2016-03-31 11:06:51,176 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp drive_request.pyo:297 Drive response: Request (None): About(tags=Reason.SYNC_OTHER) Response: About(root_id=0ABNN8iEiWGvgUk9PVA, quota_bytes_total=107374182400, quota_bytes_used_aggregate=16752656566, quota_bytes_used_in_trash=410286319, quota_type=LIMITED) 2016-03-31 11:06:51,177 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.drive_api.doc_id_provider.Store'>' 2016-03-31 11:06:51,177 -0400 INFO pid=1478 123145319829504: LaunchSyncApp user.pyo:68 Initializing User instance with new credentials. 2016-03-31 11:06:51,177 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp sync_app.pyo:1322 Configuring sync app from feature switches. 2016-03-31 11:06:51,177 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp features.pyo:112 Loads feature switches. 2016-03-31 11:06:51,177 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp feature_switch_manager.pyo:672 Calling server to get feature switches. 2016-03-31 11:06:51,178 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp drive_request.pyo:290 Drive request: ListFeatureSwitches(tags=Reason.SYNC_OTHER) - Read request - 2016-03-31 11:06:51,179 -0400 INFO pid=1478 123145319829504: LaunchSyncApp discovery.pyo:762 URL being requested: GET fields=items%2Fkey%2Citems%2Fvalue&reason=304Snamespace=FEATURE_SWITCHSalt=json 2016-03-31 11:06:51,179 -0400 INFO pid=1478 123145319829504: LaunchSyncApp http.pyo:256 Request user-agent: googledrivesync-1.28.1549.1322 google-api-python-client/1.3.1 (gzip) (Darwin/10.11.3) 2016-03-31 11:06:51,234 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp http.pyo:275 Response: 1546 bytes - application/json; charset=UTF-8 (encoding:gzip) 2016-03-31 11:06:51,236 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp drive_request.pyo:297 Drive response: Request ( ): ListFeatureSwitches(tags=Reason.SYNC_OTHER) Response: (u'cloud_graph_disk_generation': u'2', u'crash_throttle_percentage': u'0', u'enable_arbitrary_sync': u'false', u'enable_change_filters': u'false', u'enable_disk_check_on_preferences': u'true', u'enable_disk_check_on_setup': u'true', u'enable_feedback': u'true', u'enable_impressions_library': u'true', u'enable_local_v2 1: u'false', u'enable_multipause': u'true', u'enable_multiple_accounts': u'false', u'enable_push': u'true', u'enable_recursivesize': u'true', u'enable_shared_move_confirmation': u'true', u'enable_sync_options_in_setup_flow': u'true', u'enable_usb_detection': u'false', u'log_backup_count': u'0', EFTA00503631 u'max_batch_upload_files': u'30', u'max_page_size_selective_sync': u'1000', u'perf_throttle_percentage': u'100', u'query_strategy': u'all', u'selective_sub_folder_sync': u'false', uttest.featureswitch.name': u", u'use_drive_api': u'true') 2016-03-31 11:06:51,238 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp sync_app.pyo:1346 Feature Switches: FeatureSwitchSettings( accept_blob_download_gzip_encoding=True, backup_polling_interval_secs=7200, change_filters=f 1 DRIVE_SYNC 1], cloud_graph_disk_generation=2, crash_log_size_limit=10000000, crash_throttle_percentage=0.0, disabled_cloud_graph_disk_app_versions=]], docs_list_page_size=1000, download_in_place=False, download_progress_bar=False, download_strategy='url template', download_ur1= 1 doc_id)', enable_arbitrary_sync=False, enable_batch_upload=True, enable_change_filters=False, enable_context_menu=True, enable_dapper_trace=False, enable_disk_check_on_preferences=True, enable_disk_check_on_setup=True, enable_feedback=True, enable_f ile_sync_status=True, enable_impressions_library=True, enable_local_v2=False, enable_multipause=True, enable_multiple_accounts=False, enable_native_opener=True, enable_no_default_folder=False, enable_push=True, enable_recursive_si ze=True, enable_shared_move_confirmation=True, enable_sync_options_in_setup_flow=True, enable_usb_detection=False, feedback_type='prod', ghost_all files=False, help_ur1=1 desktop_home', impressions_heartbeat_interval_secs=10800, impressions_upload_interval_secs=1800, log_backup_count=0, log_size_mb=256, logging_level='info', max_batch_upload_files=30, max_page_size_changes=1000, max_page_size_cloud_graph=1000, max_page_size_selective sync=1000, new_document_ur1= 1 new_presentation_ur1=' new_spreadsheet_ur1=' numworkers=3, open_ur1=1 (doc_id)', overlays_enabled_finder_versions=[.10.7', '10.7.1', '10.7.2', '10.7.3', '10.7.5', '10.8', '10.8.1', '10.8.2', '10.8.3', '10.9', '10.9.1', '10.9.2', '10.9.3', '10.9.4'], perf_throttle_percentage=100.0, push_clientversion=1, push_keepalive_interval=72000.0, query_strategy=u'all', EFTA00503632 regular_polling_interval_secs=30, selective_sub_folder sync=False, share_template_ur1=' subapp=10&shareProtocolVersion=2&theme=2&command=settings&shareUiType=default&authuser=0&clie nt=desktop', show_confirmation_dialog_on_delete=True, telemetry_enabled=True, telemetry_upload interval secs=1800, telemetry_ur1=' tokenbucket_read_qps=10, token_bucket_write_qps=3, use_drive_api=True, user_confirmation_delay=2.0,) 2016-03-31 11:06:51,239 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp logging_config.pyo:257 OS: Darwin/10.11.3 2016-03-31 11:06:51,239 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp logging_config.pyo:260 Google Drive (build 1.28.1549.1322) 2016-03-31 11:06:51,239 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp logging_config.pyo:261 SSL: OpenSSL 0.9.8zg 14 July 2015 2016-03-31 11:06:51,239 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp sync_app.pyo:1355 Config: Email: Sync root: /Users/lgroff/Google Drive Sync collections: set([]) Upgrade number: 23 App version: 1.28.1549.1322 Selective sync: False Cloud Graph generation: 2 Folder sync: [] Local app whitelist: set([]) Local app blacklist: set([]) Bandwidth RX Rate kpBs: 0 Bandwidth TX Rate kpBs: 0 Yosemite icons upgraded: True Win10 icons upgraded: False USB sync enabled: True Do sync default folder: True 2016-03-31 11:06:51,243 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp telemetry.pyo:228 SetPushClientVersion: 1 (3, 20130807) 2016-03-31 11:06:51,243 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.push.telemetry.PushTelemetry'>' 2016-03-31 11:06:51,243 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common. telemetry. impression_logger_manager. ImpressionLoggerManager' >1 2016-03-31 11:06:51,321 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.managers.LockManager'>' 2016-03-31 11:06:51,322 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.downloads.DownloadCreate 2016-03-31 11:06:51,322 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.downloads.DownloadModify 2016-03-31 11:06:51,322 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadCreate 2016-03-31 11:06:51,322 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadDelete 2016-03-31 11:06:51,322 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadModify 2016-03-31 11:06:51,322 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadMove 2016-03-31 11:06:51,323 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp EFTA00503633 rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadMoveFrom 2016-03-31 11:06:51,323 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadMoveTo 2016-03-31 11:06:51,323 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.roots.root_wrangler.RootWrangler'>. 2016-03-31 11:06:51,324 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.machine_folder.Manager'>. 2016-03-31 11:06:51,324 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.secure_user_storage.Manager'>. 2016-03-31 11:06:51,504 -0400 INFO pid=1478 123145325109248:FilewatcherMonitor filewatcher.pyo:71 filewatcher_ext initialized 2016-03-31 11:06:51,505 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp docs_client_factory.pyo:89 Creating a new DriveClient 2016-03-31 11:06:51,505 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp docs_client_factory.pyo:89 Creating a new DriveClient 2016-03-31 11:06:51,506 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp docs_client_factory.pyo:89 Creating a new DriveClient 2016-03-31 11:06:51,506 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp docs_client_factory.pyo:89 Creating a new DriveClient 2016-03-31 11:06:51,507 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp docs_client_factory.pyo:89 Creating a new DriveClient 2016-03-31 11:06:51,507 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp docs_client_factory.pyo:89 Creating a new DriveClient 2016-03-31 11:06:51,507 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp docs_client_factory.pyo:89 Creating a new DriveClient 2016-03-31 11:06:51,507 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp xmpplib_notifier.pyo:34 Configure storage: <common.push.tango.storage.SyncConfigStorage; proxy of <Swig Object of type 'invalidation::Storage *' at 0x111e13f60> > 2016-03-31 11:06:51,508 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp xmpplib_notifier.pyo:59 Creating client thread 2016-03-31 11:06:51,508 -0400 INFO pid=1478 123145319829504: LaunchSyncApp push_xmpp_client.pyo:223 Using talk server: 2016-03-31 11:06:51,508 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp xmpplib_notifier.pyo:68 Creating notification bridge 2016-03-31 11:06:51,510 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp docs_client_factory.pyo:89 Creating a new DriveClient 2016-03-31 11:06:51,514 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp image_resources.py:279 Loading custom image images/folder-mac-yosemite.icns with function _CreateNSImage 2016-03-31 11:06:51,515 -0400 INFO pid=1478 123145329315840:LocalFSPoller sync_client_thread.pyo:178 LocalFSPoller run loop has started. 2016-03-31 11:06:51,516 -0400 INFO pid=1478 123145333522432:LocalDiskSpaceChecker sync_client_thread.pyo:178 LocalDiskSpaceChecker run loop has started. 2016-03-31 11:06:51,516 -0400 INFO pid=1478 123145337729024:TelemetryStoreSizeMonitorThread sync_client_thread.pyo:178 TelemetryStoreSizeMonitorThread run loop has started. 2016-03-31 11:06:51,517 -0400 INFO pid=1478 123145337729024:TelemetryStoreSizeMonitorThread telemetry_store_monitor_thread.pyo:44 Checking Telemetry store size. 2016-03-31 11:06:51,517 -0400 INFO pid=1478 123145341935616:PauseTelemetryThread sync_client_thread.pyo:178 PauseTelemetryThread run loop has started. 2016-03-31 11:06:51,519 -0400 INFO pid=1478 123145354555392:XmppClient telemetry.pyo:153 Push state None => PushStates.CONNECTING 2016-03-31 11:06:51,520 -0400 INFO pid=1478 123145354555392:XmppClient connection_task.pyo:151 Recalculated LoginTask timeout: 16.000000 2016-03-31 11:06:51,520 -0400 INFO pid=1478 123145354555392:XmppClient telemetry.pyo:167 PushStates.CONNECTING: +0.00139403 => 0.00139403 2016-03-31 11:06:51,520 -0400 INFO pid=1478 123145354555392:XmppClient xmpplib.pyo:80 Connecting to 2016-03-31 11:06:51,521 -0400 INFO pid=1478 123145358761984:RawEventUpgrader sync_client_thread.pyo:178 RawEventUpgrader run loop has started. 2016-03-31 11:06:51,522 -0400 INFO pid=1478 123145362968576:CloudWatcher sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-03-31 11:06:51,522 -0400 INFO pid=1478 123145362968576:CloudWatcher sqlite.pyo:276 EFTA00503634 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-03-31 11:06:51,523 -0400 INFO pid=1478 123145362968576:CloudWatcher threads.pyo:130 AddCleanupCallback persistence_sqlite:06b61d18b87c4eac80f15c40b0474a18/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-03-31 11:06:51,523 -0400 INFO pid=1478 123145362968576:CloudWatcher threads.pyo:130 AddCleanupCallback persistence_sqlite:d2fe7021cc7f43dab3e990fe5f74f94c/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-03-31 11:06:51,523 -0400 INFO pid=1478 123145362968576:CloudWatcher cloud_graph.pyo:102 Initialize persistent graph with generation 2 2016-03-31 11:06:51,524 -0400 INFO pid=1478 123145362968576:CloudWatcher cloud_graph.pyo:926 Current sync config generation: 2 2016-03-31 11:06:51,524 -0400 INFO pid=1478 123145362968576:CloudWatcher cloud_disk_dict.pyo:618 Cleanup cloud graph directory '/Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph' of files 'dict_*.db" except ['dict_2.db 1, ldict2.db-shml, 'dict_2.db-wal'] 2016-03-31 11:06:51,525 -0400 INFO pid=1478 123145367175168:Worker-0 sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-03-31 11:06:51,527 -0400 INFO pid=1478 123145362968576:CloudWatcher cloud_disk_dict.pyo:104 Reuse CloudDiskDict database: /Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph/dict_2.db 2016-03-31 11:06:51,527 -0400 INFO pid=1478 123145371381760:SyncClientImpressionsThread sync_client_thread.pyo:178 SyncClientImpressionsThread run loop has started. 2016-03-31 11:06:51,528 -0400 INFO pid=1478 123145375588352:Worker-2 sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-03-31 11:06:51,530 -0400 INFO pid=1478 123145362968576:CloudWatcher threads.pyo:130 AddCleanupcal lback DBConnectionManagerCleanupCal lback 2016-03-31 11:06:51,530 -0400 INFO pid=1478 123145362968576:CloudWatcher db_connection.pyo:202 Created thread connection on database /Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph/dict_2.db 2016-03-31 11:06:51,531 -0400 INFO pid=1478 123145362968576:CloudWatcher cloud_graph.pyo:142 Initialize persistent graph with existing database /Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph/dict_2.db 2016-03-31 11:06:51,531 -0400 INFO pid=1478 123145362968576:CloudWatcher cloud_graph.pyo:203 Building gdoc docids cache. 2016-03-31 11:06:51,532 -0400 INFO pid=1478 123145375588352:Worker-2 sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-03-31 11:06:51,532 -0400 INFO pid=1478 123145375588352:Worker-2 threads.pyo:130 AddCleanupCallback persistence_sqlite:f23dcef9lfdc463b882a3d3ab22350a7/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-03-31 11:06:51,533 -0400 INFO pid=1478 123145375588352:Worker-2 threads.pyo:130 AddCleanupCallback persistence_sqlite:3df23b82fdbf4c4c810a39ab51c6448c/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-03-31 11:06:51,533 -0400 INFO pid=1478 123145375588352:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadChangeACL 2016-03-31 11:06:51,533 -0400 INFO pid=1478 123145375588352:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadCreate 2016-03-31 11:06:51,533 -0400 INFO pid=1478 123145375588352:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadDelete 2016-03-31 11:06:51,533 -0400 INFO pid=1478 123145375588352:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadModify 2016-03-31 11:06:51,533 -0400 INFO pid=1478 123145375588352:Worker-2 command_factory.pyo: 186 Registering worker command type: common.worker.downloads.DownloadMove 2016-03-31 11:06:51,533 -0400 INFO pid=1478 123145375588352:Worker-2 EFTA00503635 command_factory.pyo:186 common.worker.downloads 2016-03-31 11:06:51,534 command_factory.pyo:186 common.worker.uploads.B 2016-03-31 11:06:51,535 command_factory.pyo:186 2016-03-31 11:06:51,535 command_factory.pyo:186 2016-03-31 11:06:51,535 command_factory.pyo:186 2016-03-31 11:06:51,535 command_factory.pyo:186 2016-03-31 11:06:51,535 command_factory.pyo:186 2016-03-31 11:06:51,535 Registering worker command type: . DownloadRename -0400 INFO pid=1478 123145375588352:Worker-2 Registering worker command type: atchUploadCreate -0400 INFO pid=1478 123145375588352:Worker-2 Registering worker command type: common.worker.uploads -0400 INFO pid=1478 123145375588352:Worker-2 Registering worker command type: common.worker.uploads -0400 INFO pid=1478 123145375588352:Worker-2 Registering worker command type: common.worker.uploads -0400 INFO pid=1478 123145375588352:Worker-2 Registering worker command type: common.worker.uploads.UploaciMove -0400 INFO pid=1478 123145375588352:Worker-2 Registering worker command type: common.worker.uploads.UploadRename -0400 INFO pid=1478 123145375588352:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.internals.InternalCreate 2016-03-31 11:06:51,535 -0400 INFO pid=1478 123145375588352:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.internals.InternalModify 2016-03-31 11:06:51,535 -0400 INFO pid=1478 123145375588352:Worker-2 workers.pyo:128 Waiting for batcher to initialize 2016-03-31 11:06:51,536 -0400 INFO pid=1478 123145384001536:CrashReportUploaderThread crash_report_uploader_thread.pyo:60 Starting CrashReportUploaderThread. 2016-03-31 11:06:51,537 -0400 INFO pid=1478 123145367175168:Worker-0 sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-03-31 11:06:51,538 -0400 INFO pid=1478 123145367175168:Worker-0 threads.pyo:130 AddCleanupCallback persistence_sqlite:5a23fb06175849a4b9a4dd8189df2529/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-03-31 11:06:51,538 -0400 INFO pid=1478 123145367175168:Worker-0 threads.pyo:130 AddCleanupCallback persistence_sqlite:632d2f2cd2364cf088939f564b9lbcf0/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-03-31 11:06:51,538 -0400 INFO pid=1478 123145367175168:Worker-0 workers.pyo:128 Waiting for batcher to initialize 2016-03-31 11:06:51,540 -0400 INFO pid=1478 123145388208128:RootWrangler root_wrangler.pyo: 92 RootWrangler.RootObserverRegistered with roots (24yDriveRoot(u'/Usersielgroff/Google Drive', LocalID(inode=435288), 'root', <RootState.INITED: 2>)] 2016-03-31 11:06:51,540 -0400 INFO pid=1478 123145388208128:RootWrangler root_wrangler.pyo: 100 RootWrangler.RootsAdded with roots (_MyDriveRoot(u'/Users/lgroff/Google Drive', LocalID(inode=435288), 'root', <RootState.INITED: 2>)] 2016-03-31 11:06:51,540 -0400 INFO pid=1478 123145388208128:RootWrangler sync_client_thread.pyo:178 RootWrangler run loop has started. 2016-03-31 11:06:51,540 -0400 INFO pid=1478 123145388208128:RootWrangler root_wrangler.pyo:82 RootWrangler got inited root _MyDriveRoot(u'/Users/lgroff/Google Drive', LocalID(inode=435288), 'root', <RootState.INITED: 2>) 2016-03-31 11:06:51,554 -0400 INFO pid=1478 123145392414720:OverlayStatusCleaner sync_client_thread.pyo:178 OverlayStatusCleaner run loop has started. 2016-03-31 11:06:51,556 -0400 INFO pid=1478 123145354555392:XmppClient telemetry.pyo:167 PushStates.CONNECTING: +0.0360485 => 0.0374425 2016-03-31 11:06:51,556 -0400 INFO pid=1478 123145354555392:XmppClient Push state PushStates.CONNECTING => PushStates.NEGOTIATING 2016-03-31 11:06:51,601 -0400 INFO pid=1478 123145400827904:Worker-1 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-03-31 11:06:51,602 -0400 INFO pid=1478 123145400827904:Worker-1 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-03-31 11:06:51,604 -0400 INFO pid=1478 123145405034496:Batcher Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db .UploadCreate .UploadDelete .UploadModify telemetry.pyo: 153 sqlite.pyo:276 sqlite.pyo:276 sqlite.pyo:276 EFTA00503636 2016-03-31 11:06:51,605 -0400 INFO pid=1478 123145405034496:Batcher sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-03-31 11:06:51,606 -0400 INFO pid=1478 123145400827904:Worker-1 threads.pyo:130 AddCleanupCallback persistence_sqlite:c5071877d5bb4c67a450e5663ead8846/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-03-31 11:06:51,608 -0400 INFO pid=1478 123145409241088:ConfirmationHandler sync_client_thread.pyo:178 ConfirmationHandler run loop has started. 2016-03-31 11:06:51,610 -0400 INFO pid=1478 123145400827904:Worker-1 threads.pyo:130 AddCleanupCallback persistence_sqlite:4b512592afbb40ae9886f3b8dfc0cbla/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-03-31 11:06:51,614 -0400 INFO pid=1478 123145400827904:Worker-1 workers.pyo:128 Waiting for batcher to initialize 2016-03-31 11:06:51,611 -0400 INFO pid=1478 123145413447680:PushTelemetryThread sync_client_thread.pyo:178 PushTelemetryThread run loop has started. 2016-03-31 11:06:51,614 -0400 INFO pid=1478 123145405034496:Batcher threads.pyo:130 AddCleanupCallback persistence_sqlite:d2lbc282ebdc43349ebacaf224f90f83/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-03-31 11:06:51,615 -0400 INFO pid=1478 123145405034496:Batcher threads.pyo:130 AddCleanupCallback persistence_sqlite:e1b954520406407faca05a894044f887/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-03-31 11:06:51,613 -0400 INFO pid=1478 123145417654272:LocalWatcher watcher.pyo:342 LocalWatcher Run invoked.Waiting for first pass of local scan from poller to start 2016-03-31 11:06:51,618 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp doc_id_provider.pyo:226 Pre-fetching triggered by caller. Threshold 900. 0 available doc IDs in store. Requesting more doc IDs... 2016-03-31 11:06:51,618 -0400 INFO pid=1478 123145319829504: LaunchSyncApp doc_id_provider.pyo:235 Waking up fetcher thread to get more doc IDs. Currently running. 2016-03-31 11:06:51,619 -0400 INFO pid=1478 123145319829504:_LaunchSyncApp drive_extension_installer.pyo:175 Uninstalling extension nknebiagdodnminbdpflhpkgfpeijdbf 2016-03-31 11:06:51,617 -0400 INFO pid=1478 123145367175168:Worker-0 sync_client_thread.pyo:178 Worker-0 run loop has started. 2016-03-31 11:06:51,618 -0400 INFO pid=1478 123145400827904:Worker-1 sync_client_thread.pyo:178 Worker-1 run loop has started. 2016-03-31 11:06:51,618 -0400 INFO pid=1478 123145426067456:PushNotificationKeepaliveThread sync_client_thread.pyo:178 PushNotificationKeepaliveThread run loop has started. 2016-03-31 11:06:51,621 -0400 INFO pid=1478 123145426067456:PushNotificationKeepaliveThread push_notification_keepalive_thread.pyo:49 Sent push keepalive message at: Thu Mar 31 11:06:51 2016 2016-03-31 11:06:51,617 -0400 INFO pid=1478 123145375588352:Worker-2 sync_client_thread.pyo:178 Worker-2 run loop has started. 2016-03-31 11:06:51,617 -0400 INFO pid=1478 123145362968576:CloudWatcher cloud_graph.pyo:148 Reuse CloudDiskDict CloudDiskDict(max_change_stamp=80243, app_version=1.28.1549.1322, 1)) 2016-03-31 11:06:51,622 -0400 INFO pid=1478 123145362968576:CloudWatcher cloud_watcher.pyo:801 Performing full snapshot diff. 2016-03-31 11:06:51,623 -0400 INFO pid=1478 123145362968576:CloudWatcher cloud_watcher.pyo:996 Getting cloud resources via changelog feed from 80244 2016-03-31 11:06:51,621 -0400 ERROR pid=1478 123145319829504:_LaunchSyncApp chrome_utils.pyo:79 Deleting file /Users/lgroff/Library/Application Support/Google/Chrome/External Extensions/nknebiagdodnminbdpflhpkgfpeijdbf.json failed because: lErrno 2] No such file or directory: '/Users/lgroff/Library/Application Support/Google/Chrome/External Extensions/nknebiagdodnminbdpflhpkgfpeijdbf.json' 2016-03-31 11:06:51,614 -0400 INFO pid=1478 123145421860864:FeatureSwitchPoller sync_client_thread.pyo:178 FeatureSwitchPoller run loop has started. 2016-03-31 11:06:51,625 -0400 INFO pid=1478 123145421860864:FeatureSwitchPoller feature_switch_manager.pyo:672 Calling server to get feature switches. 2016-03-31 11:06:51,625 -0400 INFO pid=1478 123145421860864:FeatureSwitchPoller drive_resource.pyo:134 Initialize apiclient Drive resource for 2016-03-31 11:06:51,625 -0400 INFO pid=1478 123145421860864:FeatureSwitchPoller EFTA00503637 drive_resource.pyo:195 Drive SDK baseUrl = 2016-03-31 11:06:51,624 -0400 INFO pid=1478 123145362968576:CloudWatcher drive_batch.pyo:187 Drive - - BatchRequest with 2 inner requests: ListChanges(start_change_id=80244L, max_results_per_page=1000, tags=Reason. SYNC_INITIAL, include_deleted=True, initial_max_results_per_page=1000) About(tags=Reason.SYNC_INITIAL)) 2016-03-31 11:06:51,627 -0400 INFO pid=1478 123145362968576:CloudWatcher drive_resource.pyo:134 Initialize apiclient Drive resource for 2016-03-31 11:06:51,627 -0400 INFO pid=1478 123145362968576:CloudWatcher drive_resource.pyo:195 Drive SDK baseUrl = 2016-03-31 11:06:51,626 -0400 INFO pid=1478 123145421860864:FeatureSwitchPoller drive_request.pyo:290 Drive request: ListFeatureSwitches(tags=Reason.SYNC_OTHER) - Read request - 2016-03-31 11:06:51,627 -0400 ERROR pid=1478 123145319829504:_LaunchSyncApp fatal_exceptions.pyo:55 Ignoring non-fatal error: OSError: [Errno 2] No such file or directory: '/Users/lgroff/Library/Application Support/Google/Chrome', Traceback: File "common/exception_utils.pyo", line 72, in SuppressExceptions File "common/sync_app.pyo", line 1477, in _PostSetup File "common/drive_extension_installer.pyo", line 142, in InstallExtension File "common/drive_extension_installer.pyo", line 106, in CheckExtensionlnstalled File "osx/fileutils.pyo", line 120, in ListDir EFTA00503638