EFTA00505205.pdf

From: File "Nolumes/BuildData/pulse-data/agents/wpyt3-vm- l.hogrecipes/718520193/base/b/drb/googleclient/apps/webdrive_sync/osx/dist/Google Drive.app/Contents/ftesources/_boot_.py", line 43, in <module> File "Nolumes/BuildData/pulse- datathgents/wpyt3-vm- I .hot/recipes/718520193/base/b/drb/googleclient/apps/webdrive_sync/osx/dist/Google Drive.app/Contents/ftesources/_boot_.py", line 38, 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 "build/bdist.macosx-10.7-intel/egg/PyObjCTools/AppHelper.py", line 235, in runEventLoop File "osx/GSyncAppDelegate.pyo", line 293, in applicationWillTerminate_ File "osx/mac_sync_app_controller.pyo", line 263, in Exit File "common/ui_action_handler.pyo", line 453, in Quit File "common/sync_app.pyo", line 1494, in Quit 2015-06-01 16:58:38,594 -0400 INFO pid=1756 140735186998032:MainThread thread_manager.pyo:78 Stop was called 2015-06-01 16:58:38,594 -0400 INFO pid=1756 140735186998032:MainThread thread_manager.pyo:214 Asking <IpcServerThread(IpcServerThread, started 4544487424)> to stop. 2015-06-01 16:58:38,595 -0400 INFO pid=1756 140735186998032:MainThread thread_manager.pyo:214 Asking <Worker(Worker-2, started 4558163968)> to stop. 2015-06-01 16:58:38,595 -0400 WARNING pid=1756 140735186998032:MainThread sync_client_thread.pyo:236 Wake() called while thread was running, should be a N0P 2015-06-01 16:58:38,595 -0400 INFO pid=1756 140735186998032:MainThread thread_manager.pyo:214 Asking <FeatureSwitchPoller(FeatureSwitchPoller, started 4562370560)> to stop. 2015-06-01 16:58:38,596 -0400 INFO pid=1756 140735186998032:MainThread thread_manager.pyo:214 Asking <CloudWatcher(CloudWatcher, started 4617056256)> to stop. 2015-06-01 16:58:38,596 -0400 INFO pid=1756 4562370560:FeatureSwitchPoller sync_client_thread.pyo:212 FeatureSwitchPoller run loop has terminated. 2015-06-01 16:58:38,596 -0400 INFO pid=1756 4558163968:Worker-2 sync_client_thread.pyo:212 Worker-2 run loop has terminated. 2015-06-01 16:58:38,597 -0400 INFO pid=1756 4617056256:CloudWatcher cloud_watcher.pyo:638 Stop detected 2015-06-01 16:58:38,597 -0400 INFO pid=1756 4562370560:FeatureSwitchPoller utils.pyo:678 Execute cleanup callback 'persistence_sqlite:f7a13b860f98424cb0f7ea7dbd4f953a/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2015-06-01 16:58:38,597 -0400 INFO pid=1756 140735186998032:MainThread thread_manager.pyo:214 Asking <LocalDiskSpaceChecker(LocalDiskSpaceChecker, started 4642295808)> to stop. 2015-06-01 16:58:38,597 -0400 INFO pid=1756 4558163968:Worker-2 sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-06-01 16:58:38,597 -0400 WARNING pid=1756 4617056256:CloudWatcher cloud_watcher.pyo:683 Exiting CloudWatcher.PushRunLoop() 2015-06-01 16:58:38,597 -0400 INFO pid=1756 4562370560:FeatureSwitchPoller sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-06-01 16:58:38,598 -0400 INFO pid=1756 140735186998032:MainThread thread_manager.pyo:214 Asking <PauseTelemetryThread(PauseTelemetryThread, started 4574990336)> to stop. 2015-06-01 16:58:38,598 -0400 INFO pid=1756 4617056256:CloudWatcher sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-06-01 16:58:38,598 -0400 INFO pid=1756 4642295808:LocalDiskSpaceChecker sync_client_thread.pyo:212 LocalDiskSpaceChecker run loop has terminated. 2015-06-01 16:58:38,598 -0400 INFO pid=1756 140735186998032:MainThread thread_manager.pyo:214 Asking <PushNotificationKeepaliveThread(PushNotificationKeepaliveThread, started 4579196928)> to stop. 2015-06-01 16:58:38,599 -0400 INFO pid=1756 4574990336:PauseTelemetryThread sync_client_thread.pyo:212 PauseTelemetryThread run loop has terminated. EFTA00505205 2015-06-01 16:58:38,599 -0400 INFO pid=1756 4574990336:PauseTelemetryThread pause telemetry.pyo:121 Attempting final telemetry harvest. 2015-06-01 16:58:38,600 -0400 INFO pid=1756 4574990336:PauseTelemetryThread pause_telemetry.pyo:157 Harvested pause statistics at t=1433192318.6: (<PauseReason.API ERRORS: 'pause api err'>: _HarvestStat(paused=0.0, trial=0.0, occurrences=0), aauseReason.BLOeKINa_ERRORS: 'pause blocking_err'>: _HarvestStat(paused=0.0, trial=0.0, occurrences=0), <PauseReason.NETWORK_ERROTLS: 'pause_net err'>: HarvestStat(paused=0.0, trial=0.0, occurrences=0), <PauseReason.USER: 'pause user'>: HarvestStat(paused=0.0, trial=0.0, occurrences=0), <PauseReason.OFFLINE: 'pause offline'>: HarvestStat(paused=0.0, trial=0.0, occurrences=0), <PauseReason.NO LOCAL_ DISK_ST'ACE: 'pause no space'>: _HarvestStat(paused=0.0, trial=0.0, occurrences=5)) 2015-06-01 16:58:38,600 -0400 INFO pid=1756 140735186998032:MainThread thread_manager.pyo:214 Asking <TelemetryStoreSizeMonitor(TelemetryStoreSizeMonitorThread, started 4583403520)> to stop. 2015-06-01 16:58:38,600 -0400 INFO pid=1756 4579196928:PushNotificationKeepaliveThread sync_client thread.pyo:212 PushNotificationKeepaliveThread run loop has terminated. 2015-06-01 16:58:38,641 -0400 INFO pid=1756 140735186998032:MainThread thread_manager.pyo:214 Asking <LocalWatcher(LocalWatcher, started 4604436480)> to stop. 2015-06-01 16:58:38,641 -0400 INFO pid=1756 4583403520:TelemetryStoreSizeMonitorThread sync_client thread.pyo:212 TelemetryStoreSizeMonitorThread run loop has terminated. 2015-06-01 16:58:38,642 -0400 INFO pid=1756 140735186998032:MainThread thread_manager.pyo:214 Asking <SchedulerThread(ListenerScheduler, started 4591816704)> to stop. 2015-06-01 16:58:38,643 -0400 INFO pid=1756 140735186998032:MainThread thread_manager.pyo:214 Asking <TelemetryUploaderThread(TelemetryUploaderThread, started 4596023296)> to stop. 2015-06-01 16:58:38,643 -0400 INFO pid=1756 140735186998032:MainThread thread_manager.pyo:214 Asking <RawEventUpgrader(RawEventUpgrader, started 4633882624)> to stop. 2015-06-01 16:58:38,643 -0400 INFO pid=1756 4596023296:TelemetryUploaderThread sync_client thread.pyo:212 TelemetryUploaderThread run loop has terminated. 2015-06-01 16:58:38,644 -0400 INFO pid=1756 4633882624:RawEventUpgrader sync_client thread.pyo:212 RawEventUpgrader run loop has terminated. 2015-06-01 16:58:38,644 -0400 INFO pid=1756 140735186998032:MainThread thread_manager.pyo:214 Asking <LocalFSPoller(LocalFSPoller, started 4608643072)> to stop. 2015-06-01 16:58:38,644 -0400 INFO pid=1756 140735186998032:MainThread thread_manager.pyo:214 Asking <Worker(Worker-1, started 4612849664)> to stop. 2015-06-01 16:58:38,644 -0400 INFO pid=1756 4608643072:LocalFSPoller sync_client thread.pyo:212 LocalFSPoller run loop has terminated. 2015-06-01 16:58:38,645 -0400 WARNING pid=1756 140735186998032:MainThread sync_client thread.pyo:236 Wake() called while thread was running, should be a N0P 2015-06-01 16:58:38,645 -0400 INFO pid=1756 140735186998032:MainThread thread_manager.pyo:214 Asking <PushXmppClient(XmppClient, started 4566577152)> to stop. 2015-06-01 16:58:38,645 -0400 WARNING pid=1756 140735186998032:MainThread task.pyo:245 DriveXmppTask-0x10ee33830 aborting: abort 2015-06-01 16:58:38,645 -0400 INFO pid=1756 140735186998032:MainThread task.pyo:257 Task <common.push.push xmpp_client.DriveXmppTask instance at 0x10ee33830> complete. 2015-06-01 16:58:38,646 -0400 INFO pid=1756 140735186998032:MainThread xmpplib.pyo:114 Close called with 'why' reason = Client initiated close 2015-06-01 16:58:38,646 -0400 INFO pid=1756 4612849664:Worker-1 sync_client thread.pyo:212 Worker-1 run loop has terminated. 2015-06-01 16:58:38,646 -0400 INFO pid=1756 4612849664:Worker-1 sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user default/snapshot.db 2015-06-01 16:58:38,754 -0400 INFO pid=1756 4562370560:FeatureSwitchPoller utils.pyo:678 Execute cleanup callback 'persistence sqlite:b7e72e29e5d5491c977845e9dfe136c3/Users/lgroff/Library/Application Support/Googie/Drive/user default/sync_config.db' 2015-06-01 16:58:38,754 -0400 INFO pid=1756 4562370560:FeatureSwitchPoller sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user default/sync_config.db 2015-06-01 16:58:38,755 -0400 INFO pid=1756 4558163968:Worker-2 sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user default/sync_config.db 2015-06-01 16:58:38,755 -0400 INFO pid=1756 4612849664:Worker-1 sqlite.pyo:379 Close EFTA00505206 connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-06-01 16:58:38,756 -0400 INFO pid=1756 4617056256:CloudWatcher sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-06-01 16:58:38,792 -0400 INFO pid=1756 4558163968:Worker-2 utils.pyo:678 Execute cleanup callback 'persistence_sqlite:32c5flealb174c6b800b2f66eef01734/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2015-06-01 16:58:38,792 -0400 INFO pid=1756 4558163968:Worker-2 utils.pyo:678 Execute cleanup callback 'persistence_sqlite:3bc59e89376a4f7cb370033e20e9ella/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2015-06-01 16:58:38,792 -0400 INFO pid=1756 4612849664:Worker-1 utils.pyo:678 Execute cleanup callback 'persistence_sqlite:96ea52d6a7194f9a8fc143f4cce2ceed/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2015-06-01 16:58:38,793 -0400 INFO pid=1756 4617056256:CloudWatcher utils.pyo:678 Execute cleanup callback 'persistence_sqlite:e4ae669c767a4663a430dcd1270f8507/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2015-06-01 16:58:38,793 -0400 INFO pid=1756 4612849664:Worker-1 utils.pyo:678 Execute cleanup callback 'persistence_sqlite:d0e8fa083a1e4ddfb6ce3bd93ea5e33b/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2015-06-01 16:58:38,793 -0400 INFO pid=1756 4617056256:CloudWatcher utils.pyo:678 Execute cleanup callback 'persistence_sqlite:cOadbl8de8b54768a9fblf58d7e8c17f/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2015-06-01 16:58:38,794 -0400 INFO pid=1756 4617056256:CloudWatcher utils.pyo:678 Execute cleanup callback 'DBConnectionManagerCleanupCallback' 2015-06-01 16:58:38,794 -0400 INFO pid=1756 4617056256:CloudWatcher db_connection.pyo:441 Closing ManagedConnection(id=10D30F1DO, thread=CloudWatcher-113322000, path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph/dict_2.db, closed=False) 2015-06-01 16:58:39,476 -0400 INFO pid=1756 140735186998032:MainThread task.pyo:257 Task <common.push.push_xmpp_client.DriveXmppTask instance at 0x10ee33830> complete. 2015-06-01 16:58:39,477 -0400 INFO pid=1756 140735186998032:MainThread xmpplib.pyo:114 Close called with 'why' reason = Client initiated close 2015-06-01 16:58:39,477 -0400 INFO pid=1756 140735186998032:MainThread thread_manager.pyo:214 Asking <CrashReportUploaderThread(CrashReportUploaderThread, started 4621262848)> to stop. 2015-06-01 16:58:39,477 -0400 INFO pid=1756 140735186998032:MainThread thread_manager.pyo:214 Asking <FilewatcherMonitor(FilewatcherMonitor, stopped 4552429568)> to stop. 2015-06-01 16:58:39,477 -0400 INFO pid=1756 4566577152:XmppClient push_xmpp_client.pyo:139 Exiting run loop 2015-06-01 16:58:39,477 -0400 INFO pid=1756 140735186998032:MainThread thread_manager.pyo:214 Asking <ConnectivityThread(ConnectivityThread, started 4629676032)> to stop. 2015-06-01 16:58:39,478 -0400 INFO pid=1756 140735186998032:MainThread thread_manager.pyo:214 Asking <Batcher(Batcher, started 4625469440)> to stop. 2015-06-01 16:58:39,478 -0400 INFO pid=1756 140735186998032:MainThread thread_manager.pyo:214 Asking <OverlayStatusCleaner(OverlayStatusCleaner, started 4638089216)> to stop. 2015-06-01 16:58:39,478 -0400 INFO pid=1756 4625469440:Batcher sync_client_thread.pyo:270 Batcher run loop has terminated. 2015-06-01 16:58:39,479 -0400 INFO pid=1756 4625469440:Batcher sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-06-01 16:58:39,479 -0400 INFO pid=1756 140735186998032:MainThread thread_manager.pyo:214 Asking <Worker(Worker-0, started 4570783744)> to stop. 2015-06-01 16:58:39,479 -0400 INFO pid=1756 4638089216:OverlayStatusCleaner sync_client_thread.pyo:212 OverlayStatusCleaner run loop has terminated. 2015-06-01 16:58:39,479 -0400 WARNING pid=1756 140735186998032:MainThread EFTA00505207 sync_client_thread.pyo:236 Wake() called while thread was running, should be a N0P 2015-06-01 16:58:39,480 -0400 INFO pid=1756 140735186998032:MainThread thread_manager.pyo:214 Asking <SchedulerThread(InternalScheduler, started 4600229888)> to stop. 2015-06-01 16:58:39,480 -0400 INFO pid=1756 140735186998032:MainThread thread_manager.pyo:214 Asking <PushTelemetryThread(PushTelemetryThread, started 4587610112)> to stop. 2015-06-01 16:58:39,480 -0400 INFO pid=1756 4625469440:Batcher utils.pyo:678 Execute cleanup callback 'persistence_sqlite:a645e08d6f5a4651a00013b5d0355253/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2015-06-01 16:58:39,481 -0400 INFO pid=1756 4570783744:Worker-0 sync_client_thread.pyo:212 Worker-0 run loop has terminated. 2015-06-01 16:58:39,481 -0400 INFO pid=1756 4625469440:Batcher utils.pyo:678 Execute cleanup callback 'persistence_sqlite:80f84a8faf7a4a3a922e35aca0bdlcf8/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2015-06-01 16:58:39,481 -0400 INFO pid=1756 4570783744:Worker-0 sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-06-01 16:58:39,481 -0400 INFO pid=1756 4587610112:PushTelemetryThread sync_client_thread.pyo:212 PushTelemetryThread run loop has terminated. 2015-06-01 16:58:39,481 -0400 INFO pid=1756 140735186998032:MainThread thread_manager.pyo:214 Asking <FinderInjector(FinderInjector, started 4520509440)> to stop. 2015-06-01 16:58:39,481 -0400 INFO pid=1756 4625469440:Batcher sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-06-01 16:58:39,482 -0400 INFO pid=1756 4587610112:PushTelemetryThread telemetry.pyo:205 Attempting final telemetry harvest. 2015-06-01 16:58:39,482 -0400 INFO pid=1756 4587610112:PushTelemetryThread telemetry.pyo:165 PushStates.ONLINE: +3006.34 => 13800.3 2015-06-01 16:58:39,483 -0400 INFO pid=1756 4536074240:ThreadMonitor thread_manager.pyo:287 Waking up. 2015-06-01 16:58:39,483 -0400 INFO pid=1756 4520509440:Finderlnjector sync_client_thread.pyo:212 Finderlnjector run loop has terminated. 2015-06-02 09:10:40,669 -0400 INFO pid=5663 140735186998032:MainThread logging_config.pyo:249 OS: Darwin/10.9.5 2015-06-02 09:10:40,669 -0400 INFO pid=5663 140735186998032:MainThread logging_config.pyo:252 Google Drive (build 1.21.9226.6034) 2015-06-02 09:10:40,669 -0400 INFO pid=5663 140735186998032:MainThread logging_config.pyo:253 SSL: OpenSSL 0.9.8zd 8 Jan 2015 2015-06-02 09:10:40,712 -0400 INFO pid=5663 140735186998032:MainThread GSyncAppDelegate.pyo:194 Application did finish launching. 2015-06-02 09:10:42,077 -0400 INFO pid=5663 140735186998032:MainThread service.pyo:209 Creating paramed singleton service instance of '<class 'common.file_lock.FileLock'>' 2015-06-02 09:10:42,078 -0400 INFO pid=5663 140735186998032:MainThread service.pyo:209 Creating paramed singleton service instance of '<class 'common.file_lock.FileLocki> 1 2015-06-02 09:10:42,079 -0400 INFO pid=5663 4515778560:RunAsync-_InitializeSyncAppAsync-1 service.pyo:71 Creating user scope service instance of '<class 'common.service.user.User'>' 2015-06-02 09:10:42,079 -0400 INFO pid=5663 4515778560:RunAsync-_InitializeSyncAppAsync-1 service.pyo:71 Creating user scope service instance of '<class 'common.roots.roots.RootManager'>' 2015-06-02 09:10:42,079 -0400 INFO pid=5663 4515778560:RunAsync-_InitializeSyncAppAsync-1 service.pyo:71 Creating user scope service instance of '<class 'common.pause_manager.Service'>' 2015-06-02 09:10:42,080 -0400 INFO pid=5663 4515778560:RunAsync-_InitializeSyncAppAsync-1 sync_app.pyo:302 Getting TokenStorage... 2015-06-02 09:10:42,080 -0400 INFO pid=5663 4515778560:RunAsync-_InitializeSyncAppAsync-1 sync_app.pyo:304 Got token storage: <token_storage.TokenStorage object at 0x10911c550> 2015-06-02 09:10:42,080 -0400 INFO pid=5663 4515778560:RunAsync-_InitializeSyncAppAsync-1 service.pyo:71 Creating user scope service instance of '<class 'common.features.AppFeatures'>' 2015-06-02 09:10:42,080 -0400 INFO pid=5663 4515778560:RunAsync-_InitializeSyncAppAsync-1 service.pyo:71 Creating user scope service instance of '<class 'common.cert.Manager'>' 2015-06-02 09:10:42,080 -0400 INFO pid=5663 4515778560:RunAsync-_InitializeSyncAppAsync-1 EFTA00505208 service.pyo:156 Creating singleton service instance of '<class 'common.net.bandwidth.BandwidthThrottler'> 1 2015-06-02 09:10:42,116 -0400 INFO pid=5663 4515778560:RunAsync-_InitializeSyncAppAsync-1 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-06-02 09:10:42,117 -0400 INFO pid=5663 4515778560:RunAsync-_InitializeSyncAppAsync-1 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-06-02 09:10:42,118 -0400 INFO pid=5663 4515778560:RunAsync-_InitializeSyncAppAsync-1 utils.pyo:701 ADDING persistence_sqlite:898f1a79afb54b28b5fa2cb369703b1d/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-06-02 09:10:42,118 -0400 INFO pid=5663 4515778560:RunAsync-_InitializeSyncAppAsync-1 utils.pyo:701 ADDING persistence_sqlite:314feb226a25429e8625617869db31b6/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-06-02 09:10:42,272 -0400 INFO pid=5663 4515778560:RunAsync-_InitializeSyncAppAsync-1 sync_app.pyo:476 BandwidthThrottler configured via sync_config 2015-06-02 09:10:42,272 -0400 INFO pid=5663 4515778560:RunAsync-_InitializeSyncAppAsync-1 bandwidth.pyo:59 BandwidthThrottler.SetRxBytesPerSecond 0 2015-06-02 09:10:42,272 -0400 INFO pid=5663 4515778560:RunAsync-_InitializeSyncAppAsync-1 bandwidth.pyo:68 BandwidthThrottler.SetTxBytesPerSecond 0 2015-06-02 09:10:42,272 -0400 INFO pid=5663 4515778560:RunAsync-_InitializeSyncAppAsync-1 sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-06-02 09:10:42,272 -0400 INFO pid=5663 4515778560:RunAsync-_InitializeSyncAppAsync-1 sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-06-02 09:10:42,283 -0400 INFO pid=5663 4515778560:RunAsync-_InitializeSyncAppAsync-1 utils.pyo:678 Execute cleanup callback 'persistence_sqlite:314feb226a25429e8625617869db31b6/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2015-06-02 09:10:42,283 -0400 INFO pid=5663 4515778560:RunAsync-_InitializeSyncAppAsync-1 utils.pyo:678 Execute cleanup callback 'persistence_sqlite:898fla79afb54b28b5fa2cb369703b1d/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2015-06-02 09:10:42,287 -0400 INFO pid=5663 4515778560:Finderlnjector sync_client_thread.pyo:177 Finderlnjector run loop has started. 2015-06-02 09:10:42,366 -0400 INFO pid=5663 140735186998032:MainThread image_resources.py:248 Loading image resources/images/ic_drawer_24.png 2015-06-02 09:10:42,408 -0400 INFO pid=5663 140735186998032:MainThread image_resources.py:248 Loading image resources/images/menu_drive-logo.png 2015-06-02 09:10:42,429 -0400 INFO pid=5663 140735186998032:MainThread image_resources.py:248 Loading image resources/images/menu_google-logo-gray.png 2015-06-02 09:10:42,450 -0400 INFO pid=5663 140735186998032:MainThread image_resources.py:248 Loading image resources/images/ic_folder_mydrive_24.png 2015-06-02 09:10:42,452 -0400 INFO pid=5663 140735186998032:MainThread image_resources.py:248 Loading image resources/images/ic_web_24.png 2015-06-02 09:10:42,465 -0400 INFO pid=5663 140735186998032:MainThread image_resources.py:248 Loading image resources/images/menu_backups.png 2015-06-02 09:10:42,466 -0400 INFO pid=5663 140735186998032:MainThread image_resources.py:248 Loading image resources/images/ic_sync_problem_24.png 2015-06-02 09:10:42,484 -0400 INFO pid=5663 140735186998032:MainThread image_resources.py:248 Loading image resources/images/ic_done_24.png 2015-06-02 09:10:43,538 -0400 INFO pid=5663 4537532416:ThreadMonitor thread_manager.pyo:284 Waiting on exit condition. Wake up after 3600s. 2015-06-02 09:10:43,539 -0400 INFO pid=5663 4541739008:_LaunchSyncApp sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-06-02 09:10:43,540 -0400 INFO pid=5663 4541739008:_LaunchSyncApp sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-06-02 09:10:43,541 -0400 INFO pid=5663 4541739008:_LaunchSyncApp utils.pyo:701 ADDING persistence_sqlite:e94f728872b64cf9ace7bbc77732albf/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db EFTA00505209 2015-06-02 09:10:43,541 -0400 INFO pid=5663 4541739008:_LaunchSyncApp utils.pyo:701 ADDING persistence_sqlite:5a4de2f536d241cb88a741fe7d4eb7af/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-06-02 09:10:43,711 -0400 INFO pid=5663 140735186998032:MainThread sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-06-02 09:10:43,712 -0400 INFO pid=5663 4541739008:_LaunchSyncApp user.pyo:66 Initializing User instance with new credentials. None 2015-06-02 09:10:43,712 -0400 INFO pid=5663 4541739008:_LaunchSyncApp docs_client_factory.pyo:108 Creating a new Wapi DocsClient 2015-06-02 09:10:43,713 -0400 INFO pid=5663 140735186998032:MainThread sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-06-02 09:10:43,714 -0400 INFO pid=5663 4541739008:_LaunchSyncApp service.pyo:71 Creating user scope service instance of '<class 'common.token_bucket.CloudBucketManager'>. 2015-06-02 09:10:43,817 -0400 INFO pid=5663 140735186998032:MainThread sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-06-02 09:10:43,818 -0400 INFO pid=5663 140735186998032:MainThread sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-06-02 09:10:43,818 -0400 INFO pid=5663 140735186998032:MainThread feature_switch_manager.pyo:479 Successfully read persisted feature switches. 2015-06-02 09:10:43,821 -0400 INFO pid=5663 4541739008:_LaunchSyncApp service.pyo:71 Creating user scope service instance of '<class 'common.net.dapper.DapperService'>' 2015-06-02 09:10:45,149 -0400 INFO pid=5663 4541739008: LaunchSyncApp user.pyo:66 Initializing User instance with new credentials. 2015-06-02 09:10:45,149 -0400 INFO pid=5663 4541739008:_LaunchSyncApp features.pyo:113 Loads feature switches. 2015-06-02 09:10:45,149 -0400 INFO pid=5663 4541739008:_LaunchSyncApp features.pyo:148 Loading feature switches with server. 2015-06-02 09:10:45,149 -0400 INFO pid=5663 4541739008:_LaunchSyncApp feature_switch_manager.pyo:551 Calling server to get feature switches. 2015-06-02 09:10:45,150 -0400 INFO pid=5663 4541739008:_LaunchSyncApp feature_switch_manager.pyo:471 Successfully received feature switches from server. 2015-06-02 09:10:45,151 -0400 INFO pid=5663 4541739008:_LaunchSyncApp sync_app.pyo:1161 Feature Switches: FeatureSwitchSettings( accept_blob_download_gzip_encoding=True, auto_backup=False, backup_polling_interval_secs=7200, cloud_graph_disk_generation=2, crash_log_size_limit=10000000, crash_throttle_percentage=99.7, disabled_cloud_graph_disk_app_versions=['1.8.4288.4175', '1.8.4340.2022', '1.10.4711.1662', '1.10.4657.9104', '1.10.4658.6881'], docs_list_page_size=1000, download_in_place=False, download_progress_bar=False, download_strategy=turl_template', download_ur1= 1 https://googledrive.com/p/host/(doc_idli f enable_batch_upload=True, enable_context_menu=True, enable_dapper_trace=False, enable_disk_dict=False, enable_feedback=False, enable_file_sync_status=True, enable_multiple_accounts=False, enable_native_opener=True, enable_push=True, enable_usb_detection=False, feedback_type='prod', ghost_all_files=False, help_ur1='https://support.google.com/drive/?h1=%s&p=desktop_home', log_backup_count=0, EFTA00505210 log_size_mb=256, logging_level='info', max_batch_upload_files=30, newdocument_ur1= 1 https://docs.google.com/document?usp=drive_syncl, new_presentationur1='https://docs.google.com/presentation?usp=drive_synci, new_spreadsheet_ur1=Ihttps://docs.google.com/spreadsheets?usp=drive_synci, numworkers=3, only_rooted_items_in_cloud_graph=False, open_ur1='https://docs.google.com/open?id=fdoc_id)', overlays_enabled_finder_versions=(.10.6.8 1, '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_keepalive_interval=72000.0, regular_polling_interval_secs=30, selective_sub_folder_sync=False, share_template_url=lhttps://drive.google.com/sharing/share? subapp=10&shareProtocolVersion=2&theme=2ficommand=settings&shareUiType=default&authuser=0&clie nt=desktop', show_confirmation_dialog_on_delete=True, telemetry_enabled=True, telemetry_upload_interval_secs=1800, telemetry_ur1='https://drive.google.com/syncclient_impressions', token_bucket_read_qps=10, token_bucketwrite_gps=3, use_drive_api=False,) 2015-06-02 09:10:45,151 -0400 INFO pid=5663 4541739008:_LaunchSyncApp logging_config.pyo:249 OS: Darwin/10.9.5 2015-06-02 09:10:45,152 -0400 INFO pid=5663 4541739008:_LaunchSyncApp logging_config.pyo:252 Google Drive (build 1.21.9226.6034) 2015-06-02 09:10:45,152 -0400 INFO pid=5663 4541739008:_LaunchSyncApp logging_config.pyo:253 SSL: OpenSSL 0.9.8zd 8 Jan 2015 2015-06-02 09:10:45,152 -0400 INFO pid=5663 4541739008:_LaunchSyncApp sync_app.pyo:1170 Config: Email: Sync root: /Users/lgroff/Google Drive Sync collections: set([]) Upgrade number: 20 App version: 1.21.9226.6034 Selective sync: False Cloud Graph generation: 2 Auto-Backup activated: False Folder sync: [] Local app whitelist: set(()) Local app blacklist: set(()) Bandwidth RX Rate kpBs: 0 Bandwidth TX Rate kpBs: 0 Yosemite icons upgraded: False USB sync enabled: True 2015-06-02 09:10:45,154 -0400 INFO pid=5663 4541739008:_LaunchSyncApp service.pyo:lll Creating user scope service subclass of '<class 'common.push.notifier.Notifier'>' 2015-06-02 09:10:45,154 -0400 INFO pid=5663 4541739008:_LaunchSyncApp telemetry.pyo:222 SetPushClientVersion: 1 (3, 20130807) 2015-06-02 09:10:45,154 -0400 INFO pid=5663 4541739008:_LaunchSyncApp service.pyo:71 Creating user scope service instance of '<class 'common.push.telemetry.PushTelemetry'>' 2015-06-02 09:10:45,154 -0400 INFO pid=5663 4541739008:_LaunchSyncApp service.pyo:71 Creating user scope service instance of '<class 'common.telemetry.telemetry_manager.TelemetryManager'>. 2015-06-02 09:10:45,155 -0400 INFO pid=5663 4541739008:_LaunchSyncApp service.pyo:113 Actual service created: <common.push.notifier.PushXMPPNotifier object at 0x10d2f6090> 2015-06-02 09:10:45,709 -0400 INFO pid=5663 4541739008:_LaunchSyncApp image_resources.py:224 Loading custom image resources/images/folder-mac.icns with function CreateNSlmage 2015-06-02 09:10:45,713 -0400 INFO pid=5663 4541739008:_LaunchSyncApp service.pyo:71 Creating user scope service instance of '<class 'common.auto_backup.AutoBackup'>' 2015-06-02 09:10:46,109 -0400 INFO pid=5663 4541739008:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.downloads.DownloadCreate EFTA00505211 2015-06-02 09:10:46,110 -0400 INFO pid=5663 4541739008:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.downloads.DownloadModify 2015-06-02 09:10:46,110 -0400 INFO pid=5663 4541739008:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadCreate 2015-06-02 09:10:46,110 -0400 INFO pid=5663 4541739008:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadDelete 2015-06-02 09:10:46,110 -0400 INFO pid=5663 4541739008:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadModify 2015-06-02 09:10:46,110 -0400 INFO pid=5663 4541739008:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadMove 2015-06-02 09:10:46,110 -0400 INFO pid=5663 4541739008:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadMoveFrom 2015-06-02 09:10:46,111 -0400 INFO pid=5663 4541739008:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadMoveTo 2015-06-02 09:10:46,119 -0400 INFO pid=5663 4559212544:FilewatcherMonitor filewatcher.pyo:66 filewatcher_ext initialized 2015-06-02 09:10:46,120 -0400 INFO pid=5663 4541739008:_LaunchSyncApp docs_client_factory.pyo:108 Creating a new Wapi DocsClient 2015-06-02 09:10:46,121 -0400 INFO pid=5663 4541739008:_LaunchSyncApp docs_client_factory.pyo:108 Creating a new Wapi DocsClient 2015-06-02 09:10:46,122 -0400 INFO pid=5663 4541739008:_LaunchSyncApp docs_client_factory.pyo:108 Creating a new Wapi DocsClient 2015-06-02 09:10:46,122 -0400 INFO pid=5663 4541739008:_LaunchSyncApp docs_client_factory.pyo:108 Creating a new Wapi DocsClient 2015-06-02 09:10:46,123 -0400 INFO pid=5663 4541739008:_LaunchSyncApp docs_client_factory.pyo:108 Creating a new Wapi DocsClient 2015-06-02 09:10:46,123 -0400 INFO pid=5663 4541739008:_LaunchSyncApp docs_client_factory.pyo:108 Creating a new Wapi DocsClient 2015-06-02 09:10:46,124 -0400 INFO pid=5663 4541739008:_LaunchSyncApp docs_client_factory.pyo:108 Creating a new Wapi DocsClient 2015-06-02 09:10:46,125 -0400 INFO pid=5663 4541739008:_LaunchSyncApp notifier.pyo:175 Configure storage: <common.sync_config_sqlite.SyncConfigSQLite object at 0xl0adebf90> 2015-06-02 09:10:46,125 -0400 INFO pid=5663 4541739008:_LaunchSyncApp notifier.pyo:224 Creating client thread 2015-06-02 09:10:46,125 -0400 INFO pid=5663 4541739008:_LaunchSyncApp push_xmpp_client.pyo:227 Using talk server: talk.google.com:5222 2015-06-02 09:10:46,125 -0400 INFO pid=5663 4541739008:_LaunchSyncApp notifier.pyo:233 Creating notification bridge 2015-06-02 09:10:46,173 -0400 INFO pid=5663 4541739008:_LaunchSyncApp docs_client_factory.pyo:108 Creating a new Wapi DocsClient 2015-06-02 09:10:46,177 -0400 INFO pid=5663 4566048768:CloudWatcher sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-06-02 09:10:46,178 -0400 INFO pid=5663 4566048768:CloudWatcher sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-06-02 09:10:46,179 -0400 INFO pid=5663 4566048768:CloudWatcher utils.pyo:701 ADDING persistence_sqlite:c2a0808021bc43ef9ef4756a4c5f939f/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-06-02 09:10:46,179 -0400 INFO pid=5663 4566048768:CloudWatcher utils.pyo:701 ADDING persistence_sqlite:12437a4301da4b5e90c9804f05d2f340/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-06-02 09:10:46,179 -0400 INFO pid=5663 4566048768:CloudWatcher cloud_graph.pyo:118 Initialize persistent graph with generation 2 2015-06-02 09:10:46,179 -0400 INFO pid=5663 4566048768:CloudWatcher cloud_graph.pyo:1507 Current sync config generation: 2 2015-06-02 09:10:46,180 -0400 INFO pid=5663 4566048768:CloudWatcher cloud_graph.pyo:1364 Cleanup cloud graph directory '/Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph' of files 'dict_*.db" except ['dict_2.db 1 , 'dict_2.db-shre, 'dict_2.db-wal'] 2015-06-02 09:10:46,180 -0400 INFO pid=5663 4574461952:LocalDiskSpaceChecker sync_client_thread.pyo:177 LocalDiskSpaceChecker run loop has started. 2015-06-02 09:10:46,181 -0400 INFO pid=5663 4578668544:XmppClient telemetry.pyo:151 Push state None => PushStates.CONNECTING 2015-06-02 09:10:46,182 -0400 INFO pid=5663 4578668544:XmppClient EFTA00505212 connection_task.pyo:157 Recalculated LoginTask timeout: 16.000000 2015-06-02 09:10:46,182 -0400 INFO pid=5663 4578668544:Kmppelient telemetry.pyo:165 PushStates.CONNECTING: +0.00120231 => 0.00120231 2015-06-02 09:10:46,183 -0400 INFO pid=5663 4587081728:PushNotificationKeepaliveThread sync_client_thread.pyo:177 PushNotificationKeepaliveThread run loop has started. 2015-06-02 09:10:46,183 -0400 INFO pid=5663 4582875136:Worker-0 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-06-02 09:10:46,184 -0400 INFO pid=5663 4591288320:LocalWatcher watcher.pyo:317 LocalWatcher Run invoked.Waiting for first pass of local scan from poller to start 2015-06-02 09:10:46,185 -0400 INFO pid=5663 4566048768:CloudWatcher utils.pyo:701 ADDING DBConnectionManagerCleanupCallback 2015-06-02 09:10:46,185 -0400 INFO pid=5663 4595494912:FeatureSwitchPoller sync_client_thread.pyo:177 FeatureSwitchPoller run loop has started. 2015-06-02 09:10:46,187 -0400 INFO pid=5663 4595494912:FeatureSwitchPoller features.pyo:148 Loading feature switches with server. 2015-06-02 09:10:46,186 -0400 INFO pid=5663 4599701504:PushTelemetryThread sync_client_thread.pyo:177 PushTelemetryThread run loop has started. 2015-06-02 09:10:46,186 -0400 INFO pid=5663 4566048768:CloudWatcher db_connection.pyo:207 Created thread connection on database /Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph/dict_2.db 2015-06-02 09:10:46,188 -0400 INFO pid=5663 4566048768:CloudWatcher cloud_graph.pyo:888 Reuse CloudDiskDict database: /Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph/dict_2.db 2015-06-02 09:10:46,188 -0400 INFO pid=5663 4612321280:TelemetryUploaderThread sync_client_thread.pyo:177 TelemetryUploaderThread run loop has started. 2015-06-02 09:10:46,187 -0400 INFO pid=5663 4582875136:Worker-0 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-06-02 09:10:46,189 -0400 INFO pid=5663 4582875136:Worker-0 utils.pyo:701 ADDING persistence_sqlite:2a56cdf931d74584b1f910a9baa6c233/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-06-02 09:10:46,187 -0400 INFO pid=5663 4595494912:FeatureSwitchPoller feature_switch_manager.pyo:551 Calling server to get feature switches. 2015-06-02 09:10:46,187 -0400 INFO pid=5663 4603908096:PauseTelemetryThread sync_client_thread.pyo:177 PauseTelemetryThread run loop has started. 2015-06-02 09:10:46,187 -0400 INFO pid=5663 4608114688:RawEventUpgrader sync_client_thread.pyo:177 RawEventUpgrader run loop has started. 2015-06-02 09:10:46,189 -0400 INFO pid=5663 4582875136:Worker-0 utils.pyo:701 ADDING persistence_sqlite:5ff470d737dd4c35b930d9eb40edea94/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-06-02 09:10:46,191 -0400 INFO pid=5663 4595494912:FeatureSwitchPoller sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-06-02 09:10:46,192 -0400 INFO pid=5663 4582875136:Worker-0 command_factory.pyo:190 Registering worker command type: common.worker.downloads.DownloadChangeACL 2015-06-02 09:10:46,192 -0400 INFO pid=5663 4620734464:Worker-2 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-06-02 09:10:46,192 -0400 INFO pid=5663 4624941056:CrashReportUploaderThread crash_report_uploader_thread.pyo:60 Starting CrashReportUploaderThread. 2015-06-02 09:10:46,193 -0400 INFO pid=5663 4582875136:Worker-0 command_factory.pyo:190 Registering worker command type: common.worker.downloads.DownloadCreate 2015-06-02 09:10:46,194 -0400 INFO pid=5663 4620734464:Worker-2 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-06-02 09:10:46,194 -0400 INFO pid=5663 4595494912:FeatureSwitchPoller sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-06-02 09:10:46,195 -0400 INFO pid=5663 4629147648:Batcher sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-06-02 09:10:46,195 -0400 INFO pid=5663 4633354240:OverlayStatusCleaner EFTA00505213 sync_client_thread.pyo:177 OverlayStatusCleaner run loop has started. 2015-06-02 09:10:46,195 -0400 INFO pid=5663 4582875136:Worker-0 command_factory.pyo:190 Registering worker command type: common.worker.downloads.DownloadDelete 2015-06-02 09:10:46,195 -0400 INFO pid=5663 4637560832:Worker-1 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-06-02 09:10:46,195 -0400 INFO pid=5663 4641767424:LocalFSPoller sync_client_thread.pyo:177 LocalFSPoller run loop has started. 2015-06-02 09:10:46,196 -0400 INFO pid=5663 4541739008:_LaunchSyncApp drive_extension_installer.pyo:174 Uninstalling extension nknebiagdodnminbdpflhpkgfpeijdbf 2015-06-02 09:10:46,196 -0400 INFO pid=5663 4620734464:Worker-2 utils.pyo:701 ADDING persistence_sqlite:1205568dd89048ea90ac516cf7b41173/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-06-02 09:10:46,196 -0400 INFO pid=5663 4650180608:TelemetryStoreSizeMonitorThread sync_client_thread.pyo:177 TelemetryStoreSizeMonitorThread run loop has started. 2015-06-02 09:10:46,197 -0400 INFO pid=5663 4582875136:Worker-0 command_factory.pyo:190 Registering worker command type: common.worker.downloads.DownloadModify 2015-06-02 09:10:46,197 -0400 INFO pid=5663 4595494912:FeatureSwitchPoller utils.pyo:701 ADDING persistence_sqlite:0b0f04498700412383c7a5f8bd59f5fa/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-06-02 09:10:46,198 -0400 INFO pid=5663 4620734464:Worker-2 utils.pyo:701 ADDING persistence_sqlite:311e90ba4e564b729cf5fe7bbf666efl/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-06-02 09:10:46,198 -0400 INFO pid=5663 4650180608:TelemetryStoreSizeMonitorThread telemetry_store_monitor_thread.pyo:47 Checking Telemetry store size. 2015-06-02 09:10:46,198 -0400 INFO pid=5663 4582875136:Worker-0 command_factory.pyo:190 Registering worker command type: common.worker.downloads.DownloadMove 2015-06-02 09:10:46,198 -0400 INFO pid=5663 4595494912:FeatureSwitchPoller utils.pyo:701 ADDING persistence_sqlite:c7d96c62dd57465e96b8dadb57688381/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-06-02 09:10:46,199 -0400 INFO pid=5663 4541739008:_LaunchSyncApp utils.pyo:678 Execute cleanup callback 'persistence_sqlite:5a4de2f536d241cb88a741fe7d4eb7af/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2015-06-02 09:10:46,200 -0400 INFO pid=5663 4620734464:Worker-2 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BackupCreate 2015-06-02 09:10:46,201 -0400 INFO pid=5663 4637560832:Worker-1 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-06-02 09:10:46,201 -0400 INFO pid=5663 4582875136:Worker-0 command_factory.pyo:190 Registering worker command type: common.worker.downloads.DownloadRename 2015-06-02 09:10:46,201 -0400 INFO pid=5663 4629147648:Batcher sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-06-02 09:10:46,201 -0400 INFO pid=5663 4595494912:FeatureSwitchPoller feature_switch_manager.pyo:471 Successfully received feature switches from server. 2015-06-02 09:10:46,201 -0400 INFO pid=5663 4541739008:_LaunchSyncApp sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-06-02 09:10:46,202 -0400 INFO pid=5663 4620734464:Worker-2 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BackupDelete 2015-06-02 09:10:46,203 -0400 INFO pid=5663 4620734464:Worker-2 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BackupModify 2015-06-02 09:10:46,202 -0400 INFO pid=5663 4582875136:Worker-0 command_factory.pyo:190 Registering worker command type: common.worker.internals.InternalCreate 2015-06-02 09:10:46,204 -0400 INFO pid=5663 4582875136:Worker-0 command_factory.pyo:190 Registering worker command type: common.worker.internals.InternalModify 2015-06-02 09:10:46,204 -0400 INFO pid=5663 4582875136:Worker-0 workers.pyo:128 Waiting for batcher to initialize 2015-06-02 09:10:46,204 -0400 INFO pid=5663 4637560832:Worker-1 utils.pyo:701 ADDING EFTA00505214 persistence_sqlite:lef03ddf6c38415967c0f7ed4375474c/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-06-02 09:10:46,204 -0400 INFO pid=5663 4620734464:Worker-2 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BackupMove 2015-06-02 09:10:46,204 -0400 INFO pid=5663 4637560832:Worker-1 utils.pyo:701 ADDING persistence_sqlite:7f672490ed19453a992620c11d49edca/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-06-02 09:10:46,204 -0400 INFO pid=5663 4620734464:Worker-2 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BackupRename 2015-06-02 09:10:46,205 -0400 INFO pid=5663 4620734464:Worker-2 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BatchUploadCreate 2015-06-02 09:10:46,205 -0400 INFO pid=5663 4541739008:_LaunchSyncApp utils.pyo:678 Execute cleanup callback 'persistence_sqlite:e94f728872b64cf9ace7bbc77732albf/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2015-06-02 09:10:46,205 -0400 INFO pid=5663 4637560832:Worker-1 workers.pyo:128 Waiting for batcher to initialize 2015-06-02 09:10:46,205 -0400 INFO pid=5663 4620734464:Worker-2 command_factory.pyo:190 Registering worker command type: common.worker.uploads.UploadCreate 2015-06-02 09:10:46,205 -0400 INFO pid=5663 4629147648:Batcher utils.pyo:701 ADDING persistence_sqlite:7d2d01e0b9464bc7989699e6c20a55ba/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-06-02 09:10:46,205 -0400 INFO pid=5663 4541739008:_LaunchSyncApp sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-06-02 09:10:46,206 -0400 INFO pid=5663 4620734464:Worker-2 command_factory.pyo:190 Registering worker command type: common.worker.uploads.UploadDelete 2015-06-02 09:10:46,206 -0400 INFO pid=5663 4629147648:Batcher utils.pyo:701 ADDING persistence_sqlite:0bb23893f2424a55be034c5e4789d9b4/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-06-02 09:10:46,206 -0400 INFO pid=5663 4620734464:Worker-2 command_factory.pyo:190 Registering worker command type: common.worker.uploads.UploadModify 2015-06-02 09:10:46,206 -0400 INFO pid=5663 4620734464:Worker-2 command_factory.pyo:190 Registering worker command type: common.worker.uploads.UploaciMove 2015-06-02 09:10:46,206 -0400 INFO pid=5663 4620734464:Worker-2 command_factory.pyo:190 Registering worker command type: common.worker.uploads.UploadRename 2015-06-02 09:10:46,207 -0400 INFO pid=5663 4620734464:Worker-2 workers.pyo:128 Waiting for batcher to initialize 2015-06-02 09:10:46,207 -0400 INFO pid=5663 4637560832:Worker-1 sync_client_thread.pyo:177 Worker-1 run loop has started. 2015-06-02 09:10:46,207 -0400 INFO pid=5663 4620734464:Worker-2 sync_client_thread.pyo:177 Worker-2 run loop has started. 2015-06-02 09:10:46,207 -0400 INFO pid=5663 4582875136:Worker-0 sync_client_thread.pyo:177 Worker-0 run loop has started. 2015-06-02 09:10:46,216 -0400 INFO pid=5663 4566048768:CloudWatcher cloud_graph.pyo:161 Initialize persistent graph with existing database /Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph/dict_2.db 2015-06-02 09:10:46,216 -0400 INFO pid=5663 4566048768:CloudWatcher cloud_graph.pyo:256 Building gdoc docids cache. 2015-06-02 09:10:46,221 -0400 INFO pid=5663 4578668544:XmppClient telemetry.pyo:165 PushStates.CONNECTING: +0.0386395 => 0.0398418 2015-06-02 09:10:46,221 -0400 INFO pid=5663 4578668544:XmppClient telemetry.pyo:151 Push state PushStates.CONNECTING => PushStates.NEGOTIATING 2015-06-02 09:10:46,253 -0400 INFO pid=5663 4578668544:XmppClient xmpplib.pyo:384 StanzaConnection read s where length=138 and ssl=None, s='<stream:stream from="gmail.com" id="4E9E098CF9E92B97" version="1.0" xmlns:stream="http://etherx.jabber.org/streams" xmlns="jabber:client">' 2015-06-02 09:10:46,253 -0400 INFO pid=5663 4578668544:XmppClient xmpplib.pyo:384 StanzaConnection read s where length=241 and ssl=None, s='<stream:features><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sas1"><mechanism>X-OAUTR2</mechanism><mechanism>X-GOOGLE- TOXEN</mechanism></mechanisms></stream:features>' 2015-06-02 09:10:46,283 -0400 INFO pid=5663 4578668544:XmppClient xmpplib.pyo:384 StanzaConnection read s where length=50 and ssl=None, s='<proceed EFTA00505215 xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>' 2015-06-02 09:10:46,283 -0400 INFO pid=5663 4578668544:XmppClient xmpplib.pyo:354 Start SSL peer=74.125.141.125:5222 2015-06-02 09:10:46,333 -0400 INFO pid=5663 4587081728:PushNotificationKeepaliveThread push_notification_keepalive_thread.pyo:49 Sent push keepalive message at: Tue Jun 2 09:10:46 2015 2015-06-02 09:10:46,403 -0400 INFO pid=5663 4566048768:CloudWatcher cloud_graph.pyo:171 Reuse CloudDiskDict CloudDiskDict(max_change_stamp=62839, app_version=1.21.9226.6034, only_rooted_items=False, ()) 2015-06-02 09:10:46,403 -0400 INFO pid=5663 4566048768:CloudWatcher cloud_watcher.pyo:797 Performing full snapshot diff. 2015-06-02 09:10:46,404 -0400 INFO pid=5663 4566048768:CloudWatcher cloud_watcher.pyo:987 Getting cloud resources via changelog feed from 62840 2015-06-02 09:10:46,404 -0400 INFO pid=5663 4566048768:CloudWatcher docs_client.pyo:723 Requesting Doc List: /feeds/default/private/changes?start-index=62840&showroot=true&include- shared=true&reason=startup 2015-06-02 09:10:46,454 -0400 INFO pid=5663 4578668544:XmppClient xmpplib.pyo:358 Finish SSL 2015-06-02 09:10:46,454 -0400 INFO pid=5663 4578668544:XmppClient telemetry.pyo:165 PushStates.NEGOTIATING: +0.233769 => 0.233769 2015-06-02 09:10:46,488 -0400 INFO pid=5663 4578668544:XmppClient xmpplib.pyo:384 StanzaConnection read s where length=138 and ss1=<ssl.SSLSocket object at 0x10a3e5e60>, s='<stream:stream from="gmail.com" id="737DE64EFB91B863" version="1.0" xmlns:stream="http://etherx.jabber.org/streams" xmlns="jabber:client">' 2015-06-02 09:10:46,489 -0400 INFO pid=5663 4578668544:XmppClient xmpplib.pyo:384 StanzaConnection read s where length=197 and ss1=<ssl.SSLSocket object at 0x10a3e5e60>, s=1 <stream:features><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sas1"><mechanism>X- OAUTH2</mechanism><mechanism>X-GOOGLE-TOKEN</mechanism><mechanism>PLAIN</mechanism> </mechanisms></stream:features>' 2015-06-02 09:10:46,529 -0400 INFO pid=5663 4578668544:XmppClient xmpplib.pyo:384 StanzaConnection read s where length=51 and ss1=<ssl.SSLSocket object at 0x10a3e5e60>, s='<success xmlns="urn:ietf:params:xml:ns:xmpp-sas1"/>. 2015-06-02 09:10:46,530 -0400 INFO pid=5663 4578668544:XmppClient telemetry.pyo:165 PushStates.NEGOTIATING: +0.075212 => 0.308981 2015-06-02 09:10:46,574 -0400 INFO pid=5663 4578668544:XmppClient xmpplib.pyo:384 Sta