EFTA00503846.pdf

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/ftesources/main.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-04-06 16:58:12,421 -0400 INFO pid=416 140735179177984:MainThread thread_manager.pyo:116 Stop was called 2016-04-06 16:58:12,421 -0400 INFO pid=416 140735179177984:MainThread thread_manager.pyo:253 Asking <FeatureSwitchPoller(FeatureSwitchPoller, started 123145329315840)> with tid 123145329315840 to stop. 2016-04-06 16:58:12,421 -0400 INFO pid=416 140735179177984:MainThread thread_manager.pyo:253 Asking <IpcServerThread(IpcServerThread, started 123145311416320)> with tid 123145311416320 to stop. 2016-04-06 16:58:12,421 -0400 INFO pid=416 123145329315840:FeatureSwitchPoller sync_client_thread.pyo:212 FeatureSwitchPoller run loop has terminated. 2016-04-06 16:58:12,421 -0400 INFO pid=416 140735179177984:MainThread thread_manager.pyo:253 Asking <CrashReportUploaderThread(CrashReportUploaderThread, started 123145367175168)> with tid 123145367175168 to stop. 2016-04-06 16:58:12,421 -0400 INFO pid=416 123145329315840:FeatureSwitchPoller sync_client_thread.pyo:83 ThreadExit: thread FeatureSwitchPoller exiting normalish 2016-04-06 16:58:12,422 -0400 INFO pid=416 123145367175168:CrashReportUploaderThread sync_client_thread.pyo: 83 ThreadExit: thread CrashReportUploaderThread exiting normalish 2016-04-06 16:58:12,422 -0400 INFO pid=416 140735179177984:MainThread thread_manager.pyo:253 Asking <PushNotificationKeepaliveThread(PushNotificationKeepaliveThread, started 123145337729024)> with tid 123145337729024 to stop. 2016-04-06 16:58:12,423 -0400 INFO pid=416 140735179177984:MainThread thread_manager.pyo:253 Asking <LocalWatcher(LocalWatcher, started 123145341935616)> with tid 123145341935616 to stop. 2016-04-06 16:58:12,423 -0400 INFO pid=416 123145337729024:PushNotificationKeepaliveThread sync_client_thread.pyo:212 PushNotificationKeepaliveThread run loop has terminated. 2016-04-06 16:58:12,423 -0400 INFO pid=416 123145337729024:PushNotificationKeepaliveThread sync_client_thread.pyo: 83 ThreadExit: thread PushNotificationKeepaliveThread exiting normalish 2016-04-06 16:58:12,424 -0400 INFO pid=416 123145325109248:FilewatcherMonitor sync_client_thread.pyo:83 ThreadExit: thread FilewatcherMonitor exiting normalish 2016-04-06 16:58:12,424 -0400 INFO pid=416 123145341935616:LocalWatcher sync_client_thread.pyo:83 ThreadExit: thread LocalWatcher exiting normalish 2016-04-06 16:58:12,424 -0400 INFO pid=416 140735179177984:MainThread thread manager.pyo:253 Asking <SyncClientWaitingThreadWithCallback(DocIdsFetcherThread- , started 123145346142208)> with tid 123145346142208 to stop. 2016-04-06 16:58:12,425 -0400 INFO pid=416 140735179177984:MainThread thread_manager.pyo:253 Asking <LocalFSPoller(LocalFSPoller, started 123145350348800)> with tid 123145350348800 to stop. 2016-04-06 16:58:12,425 -0400 INFO pid=416 123145346142208:DocIdsFetcherThread- sync_client_thread.pyo:272 run loop has terminated. 2016-04-06 16:58:12,425 -0400 INFO pid=416 123145346142208:DocIdsFetcherThread- sync_client_thread.pyo:83 ThreadExit: thread DocldsFetcherThread- exiting normalish 2016-04-06 16:58:12,425 -0400 INFO pid=416 140735179177984:MainThread thread_manager.pyo:253 Asking <TelemetryStoreSizeMonitor(TelemetryStoreSizeMonitorThread, started 123145354555392)> with tid 123145354555392 to stop. 2016-04-06 16:58:12,426 -0400 INFO pid=416 123145350348800:LocalFSPoller EFTA00503846 sync_client_thread.pyo:212 LocalFSPoller run loop has terminated. 2016-04-06 16:58:12,426 -0400 INFO pid=416 140735179177984:MainThread thread_manager.pyo:253 Asking <CloudWatcher(CloudWatcher, started 123145375588352)> with tid 123145375588352 to stop. 2016-04-06 16:58:12,426 -0400 INFO pid=416 123145350348800:LocalFSPoller sync_client_thread.pyo:83 ThreadExit: thread LocalFSPoller exiting normalish 2016-04-06 16:58:12,426 -0400 INFO pid=416 123145354555392:TelemetryStoreSizeMonitorThread sync_client_thread.pyo:212 TelemetryStoreSizeMonitorThread run loop has terminated. 2016-04-06 16:58:12,426 -0400 INFO pid=416 140735179177984:MainThread thread_manager.pyo:253 Asking <ConnectivityThread(ConnectivityThread, started 123145358761984)> with tid 123145358761984 to stop. 2016-04-06 16:58:12,427 -0400 INFO pid=416 123145375588352:CloudWatcher cloud_watcher.pyo:650 Stop detected 2016-04-06 16:58:12,427 -0400 INFO pid=416 123145354555392:TelemetryStoreSizeMonitorThread sync_client_thread.pyo: 83 ThreadExit: thread TelemetryStoreSizeMonitorThread exiting normalish 2016-04-06 16:58:12,427 -0400 INFO pid=416 140735179177984:MainThread thread_manager.pyo:253 Asking <PushXmppClient(XmppClient, started 123145400827904)> with tid 123145400827904 to stop. 2016-04-06 16:58:12,427 -0400 WARNING pid=416 123145375588352:CloudWatcher cloud_watcher.pyo: 696 Exiting CloudWatcher. PushRunLoop() 2016-04-06 16:58:12,427 -0400 INFO pid=416 123145358761984:ConnectivityThread sync_client_thread.pyo:83 ThreadExit: thread ConnectivityThread exiting normalish 2016-04-06 16:58:12,427 -0400 WARNING pid=416 140735179177984:MainThread task.pyo:242 DriveXmppTask-0x111f8eb10 aborting: abort 2016-04-06 16:58:12,428 -0400 INFO pid=416 123145375588352:CloudWatcher sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-04-06 16:58:12,428 -0400 INFO pid=416 140735179177984:MainThread task.pyo:254 Task <common.push.push_xmpp_client.DriveXmppTask object at 0x111f8eb10> complete. 2016-04-06 16:58:12,428 -0400 INFO pid=416 140735179177984:MainThread xmpplib.pyo:143 Close called with 'why' reason = Client initiated close 2016-04-06 16:58:12,429 -0400 INFO pid=416 123145375588352:CloudWatcher sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-06 16:58:12,429 -0400 INFO pid=416 123145375588352:CloudWatcher sync_client_thread.pyo:83 ThreadExit: thread CloudWatcher exiting normalish 2016-04-06 16:58:12,429 -0400 INFO pid=416 123145375588352:CloudWatcher threads.pyo:109 Execute cleanup callback 'persistence_sqlite:b90d9470df76465ca8e3497ef68a9feb/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2016-04-06 16:58:12,429 -0400 INFO pid=416 123145375588352:CloudWatcher threads.pyo:109 Execute cleanup callback 'DBConnectionManagerCleanupCallback' 2016-04-06 16:58:12,430 -0400 INFO pid=416 123145375588352:CloudWatcher db_connection.pyo:433 Closing ManagedConnection(id=112428850, thread=CloudWatcher- 7000045E2000, path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph/dict_2.db, closed=False) 2016-04-06 16:58:12,443 -0400 INFO pid=416 123145375588352:CloudWatcher threads.pyo:109 Execute cleanup callback 'persistence_sqlite:fbc050db59664827aaffddd77bc8a368/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2016-04-20 15:21:44,068 -0400 INFO pid=516 140735190016000:MainThread logging_config.pyo:257 OS: Darwin/10.11.3 2016-04-20 15:21:44,080 -0400 INFO pid=516 140735190016000:MainThread logging_config.pyo:260 Google Drive (build 1.28.1549.1322) 2016-04-20 15:21:44,080 -0400 INFO pid=516 140735190016000:MainThread logging_config.pyo:261 SSL: OpenSSL 0.9.8zg 14 July 2015 2016-04-20 15:21:44,165 -0400 INFO pid=516 140735190016000:MainThread GSyncAppDelegate.pyo:198 Application did finish launching. 2016-04-20 15:21:44,200 -0400 INFO pid=516 140735190016000:MainThread service.pyo:132 Creating singleton service instance of '<class 'common.file_lock.FileLock'>' 2016-04-20 15:21:44,200 -0400 INFO pid=516 140735190016000:MainThread service.pyo:132 Creating singleton service instance of '<class 'common.file_lock.FileLock'>' 2016-04-20 15:21:44,201 -0400 INFO pid=516 123145309270016:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.service.user.User'>' EFTA00503847 2016-04-20 15:21:44,202 -0400 INFO pid=516 service.pyo:132 Creating singleton service 'common. roots. roots. RootManager'>' 2016-04-20 15:21:44,202 -0400 INFO pid=516 service.pyo:132 Creating singleton service 'common.thread_manager.ThreadManager'> 1 2016-04-20 15:21:44,202 -0400 INFO pid=516 service.pyo:132 Creating singleton service 'common.pause_manager.Service'>' 2016-04-20 15:21:44,203 -0400 INFO pid=516 123145309270016:RunAsync-_InitializeSyncAppAsync-1 sync_app.pyo:344 Getting TokenStorage... 2016-04-20 15:21:44,203 -0400 INFO pid=516 123145309270016:RunAsync-_InitializeSyncAppAsync-1 sync_app.pyo:346 Got token storage: <platforms.token_storage.TokenStorage object at 0xl0f721650> 2016-04-20 15:21:44,203 -0400 INFO pid=516 123145309270016:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.features.AppFeatures'>' 2016-04-20 15:21:44,203 -0400 INFO pid=516 service.pyo:132 Creating singleton service 2016-04-20 15:21:44,213 -0400 INFO pid=516 service.pyo:132 Creating singleton service 'common.net.bandwidth.BandwidthThrottler'>' 2016-04-20 15:21:44,216 -0400 INFO pid=516 123145309270016:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.persistence.persistence.Factory'>' 2016-04-20 15:21:44,216 -0400 INFO pid=516 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-04-20 15:21:44,216 -0400 INFO pid=516 123145309270016:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.persistence.sync_config_sglite.SyncConfigSQLite'>' 2016-04-20 15:21:44,217 -0400 INFO pid=516 123145309270016:RunAsync-_InitializeSyncAppAsync-1 sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-04-20 15:21:44,218 -0400 INFO pid=516 123145309270016:RunAsync-_InitializeSyncAppAsync-1 sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-20 15:21:44,218 -0400 INFO pid=516 123145309270016:RunAsync-_InitializeSyncAppAsync-1 threads.pyo: 130 AddeleanupCallback persistence_sqlite:fbf0a85202d6422499cf858c88c5ee49/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-04-20 15:21:44,219 -0400 INFO pid=516 123145309270016:RunAsync-_InitializeSyncAppAsync-1 threads.pyo: 130 AddeleanupCallback persistence_sqlite:d6239bcfale84ac386e1c0dfe621e452/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-20 15:21:44,224 -0400 INFO pid=516 123145309270016:RunAsync-_InitializeSyncAppAsync-1 sync_app.pyo:522 BandwidthThrottler configured via sync_config 2016-04-20 15:21:44,224 -0400 INFO pid=516 123145309270016:RunAsync-_InitializeSyncAppAsync-1 bandwidth.pyo: 59 BandwidthThrottler.SetRxBytesPerSecond 0 2016-04-20 15:21:44,224 -0400 INFO pid=516 123145309270016:RunAsync-_InitializeSyncAppAsync-1 bandwidth.pyo: 68 BandwidthThrottler.SetTxBytesPerSecond 0 2016-04-20 15:21:44,224 -0400 INFO pid=516 123145309270016:RunAsync-_InitializeSyncAppAsync-1 sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-20 15:21:44,225 -0400 INFO pid=516 123145309270016:RunAsync-_InitializeSyncAppAsync-1 sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-04-20 15:21:44,226 -0400 INFO pid=516 123145309270016:RunAsync-_InitializeSyncAppAsync-1 threads.pyo:109 Execute cleanup callback 'persistence_sqlite:fbf0a85202d6422499cf858c88c5ee49/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2016-04-20 15:21:44,226 -0400 INFO pid=516 123145309270016:RunAsync-_InitializeSyncAppAsync-1 threads.pyo:109 Execute cleanup callback 123145309270016:RunAsync-_InitializeSyncAppAsync-1 instance of '<class 123145309270016:RunAsync-_InitializeSyncAppAsync-1 instance of '<class 123145309270016:RunAsync-_InitializeSyncAppAsync-1 instance of '<class 123145309270016:RunAsync-_InitializeSyncAppAsync-1 instance of '<class 'common.cert.Manager'>' 123145309270016:RunAsync-_InitializeSyncAppAsync-1 instance of '<class EFTA00503848 'persistence_sqlite:d6239bcfale84ac386e1c0dfe621e452/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2016-04-20 15:21:44,292 -0400 INFO pid=516 140735190016000:MainThread image_resources.py:228 Loading image images/ic_drawer_24.png 2016-04-20 15:21:44,294 -0400 INFO pid=516 140735190016000:MainThread image_resources.py:228 Loading image images/menu_drive-logo.png 2016-04-20 15:21:44,295 -0400 INFO pid=516 140735190016000:MainThread image_resources.py:228 Loading image images/menu_google-logo-gray.png 2016-04-20 15:21:44,298 -0400 INFO pid=516 140735190016000:MainThread image_resources.py:228 Loading image images/ic_folder_mydrive_24.png 2016-04-20 15:21:44,300 -0400 INFO pid=516 140735190016000:MainThread image_resources.py:228 Loading image images/ic_web_24.png 2016-04-20 15:21:44,302 -0400 INFO pid=516 140735190016000:MainThread image_resources.py:228 Loading image images/ic_sync_problem_24.png 2016-04-20 15:21:44,326 -0400 INFO pid=516 140735190016000:MainThread image_resources.py:228 Loading image images/ic_done.png 2016-04-20 15:21:44,414 -0400 INFO pid=516 123145315622912:ThreadMonitor thread_manager.pyo:339 Waiting on exit condition. Wake up after 3600s. 2016-04-20 15:21:44,415 -0400 INFO pid=516 123145319829504:_LaunchSyncApp sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-04-20 15:21:44,416 -0400 INFO pid=516 123145319829504:_LaunchSyncApp Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-20 15:21:44,417 -0400 INFO pid=516 123145319829504:_LaunchSyncApp AddCleanupCallback persistence_sqlite:65993f36d4e240ca9e638b58c637b4e5/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-04-20 15:21:44,417 -0400 INFO pid=516 123145319829504:_LaunchSyncApp threads.pyo:130 AddCleanupCallback persistence_sqlite:7e18ca7883d04797a3ble8445865a011/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-20 15:21:44,459 -0400 INFO pid=516 123145319829504:_LaunchSyncApp user.pyo:68 Initializing User instance with new credentials. None 2016-04-20 15:21:44,460 -0400 INFO pid=516 123145319829504:_LaunchSyncApp docs_client_factory.pyo:89 Creating a new DriveClient 2016-04-20 15:21:44,460 -0400 INFO pid=516 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.quota.Quotalnfo'>' 2016-04-20 15:21:44,461 -0400 INFO pid=516 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.token_bucket.CloudBucketManager'>' 2016-04-20 15:21:44,461 -0400 INFO pid=516 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.drive_api.drive_resource.Manager'>. 2016-04-20 15:21:44,462 -0400 INFO pid=516 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.drive_api.http.Manager'>. 2016-04-20 15:21:44,462 -0400 INFO pid=516 123145319829504:_LaunchSyncApp drive_resource.pyo:134 Initialize apiclient Drive resource for current user token 2016-04-20 15:21:44,463 -0400 ERROR pid=516 123145311416320:IpcServerThread ipc_socket_base.pyo:247 Failed to bind to socket /Users/lgroff/Library/Group Containers/google_drive/tmpdj8jDW (attempt 1 of 3) Traceback (most recent call last): File "common/ipc/ipc_socket_base.pyou, line 242, in Bind File "common/ipc/ipc_socket_base.pyou, line 176, in Bind File "socket.pyo", line 228, in meth error: (Errno 48] Address already in use 2016-04-20 15:21:44,474 -0400 INFO pid=516 123145319829504: LaunchSyncApp drive_resource.pyo:195 Drive SDK baseUrl = 2016-04-20 15:21:44,476 -0400 ERROR pid=516 123145311416320:IpcServerThread ipc_socket_base.pyo:247 Failed to bind to socket /Users/lgrof£/Library/Group Containers/google_drive/tmpdj8jDW (attempt 2 of 3) Traceback (most recent call last): File "common/ipc/ipc_socket_base.pyou, line 242, in Bind File "common/ipc/ipc_socket_base.pyou, line 176, in Bind File "socket.pyo", line 228, in meth error: (Errno 48] Address already in use 2016-04-20 15:21:44,476 -0400 ERROR pid=516 123145311416320:IpcServerThread sqlite.pyo:276 threads.pyo:130 EFTA00503849 ipc_socket_base.pyo:247 Failed to bind to socket /Users/lgroff/Library/Group Containers/google_drive/tmpdj8jDW (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-04-20 15:21:44,477 -0400 WARNING pid=516 123145311416320:IpcServerThread server.pyo:145 Socket (/Users/lgroff/Library/Application Support/Google/Drive/GoogleDrivelpcPipe) already in use, deleting it. 2016-04-20 15:21:44,477 -0400 INFO pid=516 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.drive_api.http.AccessTokenlnMemoryCache'>' 2016-04-20 15:21:44,478 -0400 INFO pid=516 123145319829504:_LaunchSyncApp drive_request.pyo:290 Drive request: About(tags=Reason.SYNC_OTHER) - Read request - None 2016-04-20 15:21:44,481 -0400 INFO pid=516 123145319829504: LaunchSyncApp discovery.pyo:762 URL being requested: GET fields=rootFolderld%2CquotaBytesTotal%2CquotaBytesUsedAggregate%2CquotaBytesUsedlnTrash%2Cquo taType%2Cuser%2FemailAddress&reason=304salt=json 2016-04-20 15:21:44,482 -0400 INFO pid=516 123145319829504:_LaunchSyncApp client.pyo:515 Attempting refresh to obtain initial access_token 2016-04-20 15:21:44,482 -0400 INFO pid=516 123145319829504:_LaunchSyncApp client.pyo:755 Refreshing access_token 2016-04-20 15:21:44,486 -0400 INFO pid=516 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.net.dapper.DapperService'>' 2016-04-20 15:21:44,486 -0400 INFO pid=516 123145319829504:_LaunchSyncApp http.pyo:256 Request user-agent: Python-httplib2/0.9.2 (gzip) 2016-04-20 15:21:44,486 -0400 INFO pid=516 123145319829504: LaunchSyncApp sync_http_client.pyo:238 Opening direct connection to 2016-04-20 15:21:44,636 -0400 INFO pid=516 123145319829504:_LaunchSyncApp http.pyo:275 Response: 152 bytes - application/json; charset=utf-8 (encoding:gzip) 2016-04-20 15:21:44,637 -0400 INFO pid=516 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-04-20 15:21:44,637 -0400 INFO pid=516 123145319829504: LaunchSyncApp sync_http_client.pyo:238 Opening direct connection to 2016-04-20 15:21:45,354 -0400 INFO pid=516 140735190016000:MainThread sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-04-20 15:21:45,354 -0400 INFO pid=516 140735190016000:MainThread sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-20 15:21:45,355 -0400 INFO pid=516 140735190016000:MainThread sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-04-20 15:21:45,355 -0400 INFO pid=516 140735190016000:MainThread sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-20 15:21:45,525 -0400 INFO pid=516 123145319829504:_LaunchSyncApp http.pyo:275 Response: 242 bytes - application/json; charset=UTF-8 (encoding:gzip) 2016-04-20 15:21:45,526 -0400 INFO pid=516 123145319829504:_LaunchSyncApp drive_request.pyo:297 Drive response: Request (None): About(tags=Reason.SYNC_OTNER) Response: About(root_id=0ABNN8iEiWGvgUk9PVA, quota_bytes_total=107374182400, quota_bytes_used_aggregate=16776221607, quota_bytes_used_in_trash=410609783, quota_type=LIMITED) 2016-04-20 15:21:45,527 -0400 INFO pid=516 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.drive_api.doc_id_provider.Store'>' 2016-04-20 15:21:45,527 -0400 INFO pid=516 123145319829504: LaunchSyncApp user.pyo:68 Initializing User instance with new credentials. 2016-04-20 15:21:45,527 -0400 INFO pid=516 123145319829504:_LaunchSyncApp sync_app.pyo:1322 Configuring sync app from feature switches. 2016-04-20 15:21:45,527 -0400 INFO pid=516 123145319829504:_LaunchSyncApp features.pyo:112 EFTA00503850 Loads feature switches. 2016-04-20 15:21:45,527 -0400 INFO pid=516 123145319829504:_LaunchSyncApp feature_switch_manager.pyo:672 Calling server to get feature switches. 2016-04-20 15:21:45,527 -0400 INFO pid=516 123145319829504:_LaunchSyncApp drive_request.pyo:290 Drive request: ListFeatureSwitches(tags=Reason.SYNC_OTHER) - Read request - 2016-04-20 15:21:45,530 -0400 INFO pid=516 123145319829504: LaunchSyncApp discovery.pyo:762 URL being requested: GET fields=items%2Fkey%2Citems%2Fvalue&reason=304&namespace=FEATURE_SWITCHfialt=json 2016-04-20 15:21:45,530 -0400 INFO pid=516 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-04-20 15:21:45,593 -0400 INFO pid=516 123145319829504:_LaunchSyncApp http.pyo:275 Response: 1548 bytes - application/json; charset=UTF-8 (encoding:gzip) 2016-04-20 15:21:45,595 -0400 INFO pid=516 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'false', 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', 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'root', u'selective_sub_folder_sync': u'false', uttest.featureswitch.name': u", u'use_drive_api': u'true') 2016-04-20 15:21:45,597 -0400 INFO pid=516 123145319829504:_LaunchSyncApp sync_app.pyo:1346 Feature Switches: FeatureSwitchSettings( accept_blob_download_gzip_encoding=True, backup_polling_interval_secs=7200, change_filters=['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=turl 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=False, enable_disk_check_on_setup=True, EFTA00503851 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= 1 new_spreadsheet_ur1= 1 numworkers=3, open_ur1=' (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'root', 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=' token_bucket_read_qps=10, token_bucketwrite_qps=3, use_drive_api=True, user_confirmation_delay=2.0,) 2016-04-20 15:21:45,599 -0400 INFO pid=516 123145319829504:_LaunchSyncApp logging_config.pyo:257 OS: Darwin/10.11.3 2016-04-20 15:21:45,599 -0400 INFO pid=516 123145319829504:_LaunchSyncApp logging_config.pyo:260 Google Drive (build 1.28.1549.1322) 2016-04-20 15:21:45,599 -0400 INFO pid=516 123145319829504:_LaunchSyncApp logging_config.pyo:261 SSL: OpenSSL 0.9.8zg 14 July 2015 2016-04-20 15:21:45,599 -0400 INFO pid=516 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: [] EFTA00503852 Local app whitelist: set([]) Local app blacklist: set([]) Bandwidth RX Rate kpBs: 0 Bandwidth TX Rate kpBs: 0 Yosemite icons upgraded: True Winl0 icons upgraded: False USB sync enabled: True Do sync default folder: True 2016-04-20 15:21:45,601 -0400 INFO pid=516 123145319829504:_LaunchSyncApp telemetry.pyo:228 SetPushClientVersion: 1 (3, 20130807) 2016-04-20 15:21:45,601 -0400 INFO pid=516 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.push.telemetry.PushTelemetry'> 1 2016-04-20 15:21:45,601 -0400 INFO pid=516 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.telemetry.impression_logger_manager.ImpressionLoggerManager'>. 2016-04-20 15:21:46,013 -0400 INFO pid=516 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.managers.LockManager'> 1 2016-04-20 15:21:46,014 -0400 INFO pid=516 123145319829504:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.downloads.DownloadCreate 2016-04-20 15:21:46,014 -0400 INFO pid=516 123145319829504:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.downloads.DownloadModify 2016-04-20 15:21:46,014 -0400 INFO pid=516 123145319829504:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadCreate 2016-04-20 15:21:46,014 -0400 INFO pid=516 123145319829504:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadDelete 2016-04-20 15:21:46,014 -0400 INFO pid=516 123145319829504:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadModify 2016-04-20 15:21:46,014 -0400 INFO pid=516 123145319829504:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadMove 2016-04-20 15:21:46,014 -0400 INFO pid=516 123145319829504:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadMoveFrom 2016-04-20 15:21:46,014 -0400 INFO pid=516 123145319829504:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadMoveTo 2016-04-20 15:21:46,015 -0400 INFO pid=516 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.roots.root_wrangler.RootWrangler'>. 2016-04-20 15:21:46,016 -0400 INFO pid=516 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.machine_folder.Manager'>. 2016-04-20 15:21:46,016 -0400 INFO pid=516 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.secure_user_storage.Manager'>. 2016-04-20 15:21:46,354 -0400 INFO pid=516 123145324036096:FilewatcherMonitor filewatcher.pyo:71 filewatcher_ext initialized 2016-04-20 15:21:46,357 -0400 INFO pid=516 123145319829504:_LaunchSyncApp docs_client_factory.pyo:89 Creating a new DriveClient 2016-04-20 15:21:46,358 -0400 INFO pid=516 123145319829504:_LaunchSyncApp docs_client_factory.pyo:89 Creating a new DriveClient 2016-04-20 15:21:46,358 -0400 INFO pid=516 123145319829504:_LaunchSyncApp docs_client_factory.pyo:89 Creating a new DriveClient 2016-04-20 15:21:46,359 -0400 INFO pid=516 123145319829504:_LaunchSyncApp docs_client_factory.pyo:89 Creating a new DriveClient 2016-04-20 15:21:46,359 -0400 INFO pid=516 123145319829504:_LaunchSyncApp docs_client_factory.pyo:89 Creating a new DriveClient 2016-04-20 15:21:46,360 -0400 INFO pid=516 123145319829504:_LaunchSyncApp docs_client_factory.pyo:89 Creating a new DriveClient 2016-04-20 15:21:46,360 -0400 INFO pid=516 123145319829504:_LaunchSyncApp docs_client_factory.pyo:89 Creating a new DriveClient 2016-04-20 15:21:46,361 -0400 INFO pid=516 123145319829504:_LaunchSyncApp xmpplib_notifier.pyo:34 Configure storage: <common.push.tango.storage.SyncConfigStorage; EFTA00503853 proxy of <Swig Object of type 'invalidation::Storage *' at 0x111efb600> > 2016-04-20 15:21:46,362 -0400 INFO pid=516 123145319829504:_LaunchSyncApp xmpplib_notifier.pyo:59 Creating client thread 2016-04-20 15:21:46,362 -0400 INFO pid=516 123145319829504: LaunchSyncApp push_xmpp_client.pyo:223 Using talk server: 2016-04-20 15:21:46,362 -0400 INFO pid=516 123145319829504:_LaunchSyncApp xmpplib_notifier.pyo:68 Creating notification bridge 2016-04-20 15:21:46,368 -0400 INFO pid=516 123145319829504:_LaunchSyncApp docs_client_factory.pyo:89 Creating a new DriveClient 2016-04-20 15:21:46,371 -0400 INFO pid=516 123145319829504:_LaunchSyncApp image_resources.py:279 Loading custom image images/folder-mac-yosemite.icns with function _CreateNSImage 2016-04-20 15:21:46,373 -0400 INFO pid=516 123145329852416:LocalFSPoller sync_client_thread.pyo:178 LocalFSPoller run loop has started. 2016-04-20 15:21:46,375 -0400 INFO pid=516 123145334059008:XmppClient Push state None => PushStates.CONNECTING 2016-04-20 15:21:46,375 -0400 INFO pid=516 123145334059008:XmppClient connection_task.pyo:151 Recalculated LoginTask timeout: 16.000000 2016-04-20 15:21:46,376 -0400 INFO pid=516 123145334059008:XmppClient PushStates.CONNECTING: +0.00125265 => 0.00125265 2016-04-20 15:21:46,376 -0400 INFO pid=516 123145334059008:XmppClient Connecting to 2016-04-20 15:21:46,376 -0400 INFO pid=516 123145342472192:Worker-0 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-04-20 15:21:46,377 -0400 INFO pid=516 123145342472192:Worker-0 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-20 15:21:46,378 -0400 INFO pid=516 123145342472192:Worker-0 AddCleanupCallback persistence_sglite:7c7dded60bc3434b86352bc2a2bfaf40/Users/lgroff/Library/Application Support/Google/Drive/user default/snapshot.db 2016-04-20 15:21:46,378 -0400 INFO pid=516 123145342472192:Worker-0 threads.pyo:130 AddCleanupCallback persistence_scilite:8b1933616de44a05b4acd6ca5dd55c20/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-20 15:21:46,378 -0400 INFO pid=516 123145342472192:Worker-0 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadChangeACL 2016-04-20 15:21:46,379 -0400 INFO pid=516 123145342472192:Worker-0 command_factory.pyo:186 Registering worker command type: DownloadCreate -0400 INFO pid=516 Registering worker DownloadDelete -0400 INFO pid=516 Registering worker DownloadModify -0400 INFO pid=516 Registering worker -0400 INFO pid=516 Registering worker DownloadRename -0400 INFO pid=516 Registering worker common.worker.uploads.BatchUploadCreate 2016-04-20 15:21:46,380 command_factory.pyo:186 2016-04-20 15:21:46,380 command_factory.pyo:186 2016-04-20 15:21:46,380 command_factory.pyo:186 2016-04-20 15:21:46,381 command_factory.pyo:186 2016-04-20 15:21:46,381 common.worker.downloads. 2016-04-20 15:21:46,379 command_factory.pyo:186 common.worker.downloads. 2016-04-20 15:21:46,379 command_factory.pyo:186 common.worker.downloads. 2016-04-20 15:21:46,379 command_factory.pyo:186 2016-04-20 15:21:46,379 command_factory.pyo: 186 common.worker.downloads. 2016-04-20 15:21:46,380 command_factory.pyo:186 -0400 INFO pid=516 Registering worker -0400 INFO pid=516 Registering worker -0400 INFO pid=516 Registering worker -0400 INFO pid=516 Registering worker -0400 INFO pid=516 123145342472192:Worker-0 command type: 123145342472192:Worker-0 command type: telemetry.pyo:153 telemetry.pyo: 167 xmpplib.pyo:80 sqlite.pyo:276 sqlite.pyo:276 threads.pyo:130 123145342472192:Worker-0 command type: common.worker.downloads.DownloadMove 123145342472192:Worker-0 command type: 123145342472192:Worker-0 command type: 123145342472192:Worker-0 command type: common.worker.uploads.UploadCreate 123145342472192:Worker-0 command type: common.worker.uploads.UploadDelete 123145342472192:Worker-0 command type: common.worker.uploads.UploadModify 123145342472192:Worker-0 command type: common.worker.uploads.UploaciMove 123145342472192:Worker-0 EFTA00503854 command_factory.pyo:186 2016-04-20 15:21:46,381 command_factory.pyo:186 common.worker.internals 2016-04-20 15:21:46,381 command_factory.pyo:186 common.worker.internals 2016-04-20 15:21:46,382 Registering worker -0400 INFO pid=516 Registering worker . InternalCreate -0400 INFO pid=516 Registering worker . InternalModify -0400 INFO pid=516 Waiting for batcher to initialize 2016-04-20 15:21:46,381 -0400 INFO pid=516 123145346678784:CloudWatcher Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-04-20 15:21:46,384 -0400 INFO pid=516 123145346678784:CloudWatcher Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-20 15:21:46,385 -0400 INFO pid=516 123145346678784:CloudWatcher AddCleanupCallback persistence_sqlite:2d19729eaea648248db20c21e11208c3/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-04-20 15:21:46,385 -0400 INFO pid=516 123145346678784:CloudWatcher threads.pyo:130 AddCleanupCallback persistence_sqlite:1d3c8a2b364244dab2bfcbc66ff05590/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-20 15:21:46,385 -0400 INFO pid=516 123145346678784:CloudWatcher cloud_graph.pyo:102 Initialize persistent graph with generation 2 2016-04-20 15:21:46,386 -0400 INFO pid=516 123145346678784:CloudWatcher cloud_graph.pyo:926 Current sync config generation: 2 2016-04-20 15:21:46,386 -0400 INFO pid=516 123145346678784:CloudWatcher cloud_disk_dict.pyo:618 Cleanup cloud graph directory '/Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph' of files 'clict_*.db" except ( 1dict_2.db 1, ldict_2.db-shml, idict_2.db-wal'] 2016-04-20 15:21:46,387 -0400 INFO pid=516 123145346678784:CloudWatcher cloud_disk_dict.pyo: 104 Reuse CloudDiskDict database: /Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph/dict_2.db 2016-04-20 15:21:46,388 -0400 INFO pid=516 123145346678784:CloudWatcher threads.pyo:130 AddCleanupCallback DBConnectionManagerCleanupCal lback 2016-04-20 15:21:46,388 -0400 INFO pid=516 123145346678784: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-04-20 15:21:46,389 -0400 INFO pid=516 123145350885376:PauseTelemetryThread sync_client_thread.pyo:178 PauseTelemetryThread run loop has started. 2016-04-20 15:21:46,395 -0400 INFO pid=516 123145355091968:CrashReportUploaderThread crash_report_uploader_thread.pyo: 60 Starting CrashReportUploaderThread. 2016-04-20 15:21:46,398 -0400 INFO pid=516 123145359298560:SyncClientImpressionsThread sync_client_thread.pyo:178 SyncClientImpressionsThread run loop has started. 2016-04-20 15:21:46,399 -0400 INFO pid=516 123145363505152:Worker-2 sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-04-20 15:21:46,400 -0400 INFO pid=516 123145363505152:Worker-2 sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-20 15:21:46,400 -0400 INFO pid=516 123145363505152:Worker-2 threads.pyo:130 AddCleanupCallback persistence_sqlite:5476f152113648dda985a144a06d4078/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-04-20 15:21:46,401 -0400 INFO pid=516 123145363505152:Worker-2 threads.pyo:130 AddCleanupCallback persistence_sqlite:6bc7c3519d52432ca3801cec89b36120/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-20 15:21:46,401 -0400 INFO pid=516 123145363505152:Worker-2 workers.pyo:128 Waiting for batcher to initialize 2016-04-20 15:21:46,402 -0400 INFO pid=516 123145367711744:RootWrangler root_wrangler.pyo: 92 RootWrangler.RootObserverRegistered with roots (_MyDriveRoot(u'/Users/lgroff/Google Drive', LocalID(inode=435288), 'root', <RootState.INITED: 2>)] command type: common.worker.uploads.UploadRename 123145342472192:Worker-0 command type: 123145342472192:Worker-0 command type: 123145342472192:Worker-0 workers.pyo:128 sqlite.pyo:276 sqlite.pyo:276 threads.pyo:130 EFTA00503855 2016-04-20 15:21:46,403 -0400 INFO pid=516 123145367711744:RootWrangler root_wrangler.pyo: 100 RootWrangler.RootsAdded with roots ]_MyDriveRoot(u'/Users/lgroff/Google Drive', LocallD(inode=435288), 'root', <RootState.INITED: 2>)] 2016-04-20 15:21:46,403 -0400 INFO pid=516 123145367711744:RootWrangler sync_client_thread.pyo:178 RootWrangler run loop has started. 2016-04-20 15:21:46,403 -0400 INFO pid=516 123145367711744:RootWrangler root_wrangler.pyo:82 RootWrangler got inited root _MyDriveRoot(u'iUsers/lgroff/Google Drive', LocalID(inode=435288), 'root', <RootState.INITED: 2>) 2016-04-20 15:21:46,404 -0400 INFO pid=516 123145371918336:OverlayStatusCleaner sync_client_thread.pyo:178 OverlayStatusCleaner run loop has started. 2016-04-20 15:21:46,405 -0400 INFO pid=516 123145376124928:FeatureSwitchPoller sync_client_thread.pyo:178 FeatureSwitchPoller run loop has started. 2016-04-20 15:21:46,405 -0400 INFO pid=516 123145376124928:FeatureSwitchPoller feature_switch_manager.pyo:672 Calling server to get feature switches. 2016-04-20 15:21:46,405 -0400 INFO pid=516 123145376124928:FeatureSwitchPoller drive_resource.pyo:134 Initialize apiclient Drive resource for 2016-04-20 15:21:46,405 -0400 INFO pid=516 123145376124928:FeatureSwitchPoller drive_resource.pyo:195 Drive SDK baseUrl = 2016-04-20 15:21:46,406 -0400 INFO pid=516 123145376124928:FeatureSwitchPoller drive_request.pyo:290 Drive request: ListFeatureSwitches(tags=Reason.SYNC_OTHER) - Read request - 2016-04-20 15:21:46,410 -0400 INFO pid=516 123145376124928:FeatureSwitchPoller discovery.pyo:762 UAL being requested: GET fields=items%2Fkey%2Citems%2FValue&reason=304&namespace=FEATURE SWITCH&alt=json 2016-04-20 15:21:46,411 -0400 INFO pid=516 123145346678784:Cloaatcher 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-04-20 15:21:46,412 -0400 INFO pid=516 123145346678784:51oudWatcher cloud_graph.pyo:203 Building gdoc docids cache. 2016-04-20 15:21:46,412 -0400 INFO pid=516 123145380331520:ConfirmationHandler sync_client_thread.pyo:178 ConfirmationHandler run loop has started. 2016-04-20 15:21:46,413 -0400 INFO pid=516 123145376124928:FeatureSwitchPoller client.pyo:515 Attempting refresh to obtain initial access token 2016-04-20 15:21:46,413 -0400 INFO pid=516 123145376124928:FeatureSwitchPoller client.pyo:735 Updated access token read from Storage 2016-04-20 15:21:46,413 -0400 INFO pid=516 123145376124928:FeatureSwitchPoller http.pyo:256 Request user-agent: googledrivesync-1.28.1549.1322 google-api-python-client/1.3.1 (gzip) (Darwin/10.11.3) 2016-04-20 15:21:46,413 -0400 INFO pid=516 123145376124928:FeatureSwitchPoller sync_http_client.pyo:238 Opening direct connection to 2016-04-20 15:21:46,415 -0400 INFO pid=516 123145388744704:PushTelemetryThread sync_client_thread.pyo:178 PushTelemetryThread run loop has started. 2016-04-20 15:21:46,418 -0400 INFO pid=516 123145392951296:RawEventUpgrader sync_client_thread.pyo:178 RawEventUpgrader run loop has started. 2016-04-20 15:21:46,418 -0400 INFO pid=516 123145334059008:XmppClient telemetry.pyo:167 PushStates.CONNECTING: +0.0425356 => 0.0437883 2016-04-20 15:21:46,419 -0400 INFO pid=516 123145334059008:XmppClient Push state PushStates.CONNECTING => PushStates.NEGOTIATING 2016-04-20 15:21:46,419 -0400 INFO pid=516 123145397157888:Batcher Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user default/snapshot.db 2016-04-20 15:21:46,420 -0400 INFO pid=516 123145397157888:Batcher Open master connection to /Users/lgroff/Library/Application Support/Google/Driveiuser default/sync_config.db 2016-04-20 15:21:46,421 -0400 INFO pid=516 123145397157888:Batcher AddCleanupCallback persistence_sqlite:5c0cd60da7604387bb9c68b7aad77c93/Users/lgroff/Library/Application Support/Google/Drive/user default/snapshot.db 2016-04-20 15:21:46,421 -0400 INFO pid=516 123145397157888:Batcher Addeleanupeallback persistence_sqlite:468967fd9f004fc3adbd9c81f7223972/Users/lgroff/Library/Application Support/Google/Drive/user default/sync_config.db 2016-04-20 15:21:46,421 -0400 INFO pid=516 123145342472192:Worker-0 sync_client_thread.pyo:178 Worker-0 run loop has started. telemetry.pyo:153 sqlite.pyo:276 sqlite.pyo:276 threads.pyo:130 threads.pyo:130 EFTA00503856 2016-04-20 15:21:46,425 -0400 INFO pid=516 123145401364480:Worker-1 sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-04-20 15:21:46,426 -0400 INFO pid=516 123145401364480:Worker-1 sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-20 15:21:46,426 -0400 INFO pid=516 123145363505152:Worker-2 sync_client_thread.pyo:178 Worker-2 run loop has started. 2016-04-20 15:21:46,432 -0400 INFO pid=516 123145401364480:Worker-1 threads.pyo:130 AddCleanupCallback persistence_sqlite:3f6bff344aed47e8abed66f8084ca38b/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-04-20 15:21:46,432 -0400 INFO pid=516 123145401364480:Worker-1 threads.pyo:130 AddCleanupCallback persistence_sqlite:3b8365f3cffb4a8794756a76c4ca0062/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-20 15:21:46,432 -0400 INFO pid=516 123145401364480:Worker-1 workers.pyo:128 Waiting for batcher to initialize 2016-04-20 15:21:46,432 -0400 INFO pid=516 123145401364480:Worker-1 sync_client_thread.pyo:178 Worker-1 run loop has started. 2016-04-20 15:21:46,433 -0400 INFO pid=516 123145405571072:PushNotificationKeepaliveThread sync_client_thread.pyo:178 PushNotificationKeepaliveThread run loop has started. 2016-04-20 15:21:46,433 -0400 INFO pid=516 123145405571072:PushNotificationKeepaliveThread push_notification_keepalive_thread.pyo:49 Sent push keepalive message at: Wed Apr 20 15:21:46 2016 2016-04-20 15:21:46,438 -0400 INFO pid=516 123145413984256:LocalDiskSpaceChecker sync_client_thread.pyo:178 LocalDiskSpaceChecker run loop has started. 2016-04-20 15:21:46,443 -0400 INFO pid=516 123145418190848:TelemetryStoreSizeMonitorThread sync_client_thread.pyo:178 TelemetryStoreSizeMonitorThread run loop has started. 2016-04-20 15:21:46,444 -0400 INFO pid=516 123145418190848:TelemetryStoreSizeMonitorThread telemetry_store_monitor_thread.pyo: 44 Checking Telemetry store size. 2016-04-20 15:21:46,480 -0400 INFO pid=516 123145426604032:LocalWatcher watcher.pyo:342 LocalWatcher Run invoked.Waiting for first pass of local scan from poller to start 2016-04-20 15:21:46,481 -0400 INFO pid=516 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-04-20 15:21:46,481 -0400 INFO pid=516 123145319829504: LaunchSyncApp doc_id_provider.pyo:235 Waking up fetcher thread to get more doc IDs. Currently running. 2016-04-20 15:21:46,481 -0400 INFO pid=516 123145319829504:_LaunchSyncApp drive_extension_installer.pyo:175 Uninstalling extension nknebiagdodnminbdpflhpkgfpeijdbf 2016-04-20 15:21:46,485 -0400 ERROR pid=516 123145319829504:_LaunchSyncApp chrome_utils.pyo:79 Deleting file /Users/lgroff/Library/Application Support/Google/Chrome/External Extensions/nknebiagdodnminbdpflhpkgfpeijdbf.json failed because: [Errno 2] No such file or directory: '/Users/lgroff/Library/Application Support/Google/Chrome/External Extensions/nknebiagdodnminbdpflhpkgfpeijdbf.json' 2016-04-20 15:21:46,486 -0400 ERROR pid=516 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 EFTA00503857