EFTA00502472.pdf

From: File "Nolumes/BuildData/pulse- datathgents/599758/recipes/859788647/base/b/drb/googleclient/apps/webdrive_sync/osxklist/Google Drive.app/Contents/ftesources/_boot_.py", line 77, in <module> File "Nolumes/BuildData/pulse- datathgents/599758/recipes/859788647/base/b/drb/googleclient/apps/webdrive_sync/osxklist/Google Drive.app/Contents/ftesources/_boot_.py", line 62, in _run File "/ApplicationslGoogle Drive.app/Contents/Resourceslmain.py", line 12, in <module> File "osx/run_googledrive.pyo", line 31, in Main File "PyObjCTools/AppHelper.pyo", line 235, in runEventLoop File "osx/GSyncAppDelegate.pyo", line 297, in applicationWillTerminate_ File "osx/mac_sync_app_controller.pyo", line 252, in Exit File "common/ui_action_handler.pyo", line 450, in Quit File "common/sync_app.pyo", line 1643, in Quit 2015-12-16 16:32:56,925 -0500 INFO pid=11413 140735235863312:MainThread thread_manager.pyo:114 Stop was called 2015-12-16 16:32:56,926 -0500 INFO pid=11413 140735235863312:MainThread thread_manager.pyo:250 Asking <ConnectivityThread(ConnectivityThread, started 4583641088)> to stop. 2015-12-16 16:32:56,926 -0500 INFO pid=11413 140735235863312:MainThread thread_manager.pyo:250 Asking <Worker(Worker-0, started 4571021312)> to stop. 2015-12-16 16:32:56,927 -0500 WARNING pid=11413 140735235863312:MainThread sync_client_thread.pyo:237 Wake() called while thread was running, should be a N0P 2015-12-16 16:32:56,927 -0500 INFO pid=11413 140735235863312:MainThread thread_manager.pyo:250 Asking <FilewatcherMonitor(FilewatcherMonitor, started 4562608128)> to stop. 2015-12-16 16:32:57,015 -0500 INFO pid=11413 140735235863312:MainThread thread_manager.pyo:250 Asking <TelemetryStoreSizeMonitor(TelemetryStoreSizeMonitorThread, started 4566814720)> to stop. 2015-12-16 16:32:57,016 -0500 INFO pid=11413 4571021312:Worker-0 sync_client_thread.pyo:209 Worker-0 run loop has terminated. 2015-12-16 16:32:57,016 -0500 INFO pid=11413 4571021312:Worker-0 sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-16 16:32:57,016 -0500 INFO pid=11413 140735235863312:MainThread thread_manager.pyo:250 Asking <SchedulerThread(InternalScheduler, started 4587847680)> to stop. 2015-12-16 16:32:57,017 -0500 INFO pid=11413 4566814720:TelemetryStoreSizeMonitorThread sync_client_thread.pyo:209 TelemetryStoreSizeMonitorThread run loop has terminated. 2015-12-16 16:32:57,017 -0500 INFO pid=11413 140735235863312:MainThread thread_manager.pyo:250 Asking <LocalFSPoller(LocalFSPoller, started 4592054272)> to stop. 2015-12-16 16:32:57,018 -0500 INFO pid=11413 140735235863312:MainThread thread_manager.pyo:250 Asking <CloudWatcher(CloudWatcher, started 4596260864)> to stop. 2015-12-16 16:32:57,018 -0500 INFO pid=11413 4592054272:LocalFSPoller sync_client_thread.pyo:209 LocalFSPoller run loop has terminated. 2015-12-16 16:32:57,019 -0500 INFO pid=11413 140735235863312:MainThread thread_manager.pyo:250 Asking <PauseTelemetryThread(PauseTelemetryThread, started 4625707008)> to stop. 2015-12-16 16:32:57,019 -0500 INFO pid=11413 4596260864:CloudWatcher cloud_watcher.pyo:680 Stop detected 2015-12-16 16:32:57,019 -0500 INFO pid=11413 140735235863312:MainThread thread_manager.pyo:250 Asking <PushTelemetryThread(PushTelemetryThread, started 4604674048)> to stop. 2015-12-16 16:32:57,019 -0500 INFO pid=11413 4625707008:PauseTelemetryThread sync_client_thread.pyo:209 PauseTelemetryThread run loop has terminated. 2015-12-16 16:32:57,101 -0500 INFO pid=11413 4571021312:Worker-0 sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-16 16:32:57,129 -0500 WARNING pid=11413 4596260864:CloudWatcher cloud_watcher.pyo:726 Exiting CloudWatcher. PushRunLoop() 2015-12-16 16:32:57,129 -0500 INFO pid=11413 4625707008:PauseTelemetryThread pause_telemetry.pyo:127 Attempting final telemetry harvest. 2015-12-16 16:32:57,130 -0500 INFO pid=11413 140735235863312:MainThread EFTA00502472 thread_manager.pyo:250 Asking <TelemetryUploaderThread(TelemetryUploaderThread, started 460888''6401> to stop. 2015-12-16 16:32:57,130 -0500 INFO pid=11413 4604674048:PushTelemetryThread sync_client_thread.pyo:209 PushTelemetryThread run loop has terminated. 2015-12-16 16:32:57,130 -0500 INFO pid=11413 4596260864:CloudWatcher sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user default/snapshot.db 2015-12-16 16:32:57,131 -0500 INFO pid=11413 4625707008:PauseTelemetryThread pause_telemetry.pyo:190 Harvested pause statistics at t=1450301577.13: (‹PauseReason.API ERRORS: 'pause api err•>: _HarvestStat(paused=0.0, trial=0.0, occurrences=0), <i)auseReason.BLOaCINa_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=26.59132075900561, trial=0.0, occurrences=1), lrisauseReason.NO_LOCAL_DISK_SPACE: 'pause_no_space'>: _HarvestStat(paused=0.0, trial=0.0, occurrences=0)) 2015-12-16 16:32:57,131 -0500 INFO pid=11413 4571021312:Worker-0 utils.pyo:714 Execute cleanup callback 'persistence sqlite:37c43fb041f34ed8868e185ed0a0857b/Users/lgroff/Library/Application Support/Googie/Drive/user default/sync_config.db' 2015-12-16 16:32:57,131 -0500 INFO pid=11413 4604674048:PushTelemetryThread telemetry.pyo:207 Attempting final telemetry harvest. 2015-12-16 16:32:57,151 -0500 INFO pid=11413 140735235863312:MainThread thread manager.pyo:250 Asking <CrashReportUploaderThread(CrashReportUploaderThread, started 46130872321> to stop. 2015-12-16 16:32:57,151 -0500 INFO pid=11413 4571021312:Worker-0 utils.pyo:714 Execute cleanup callback 'persistence sqlite:25ee69d5eaa6494caaddc843b6ea0051/Users/lgroff/Library/Application Support/Googie/Drive/user default/snapshot.db' 2015-12-16 16:32:57,152 -0500 INFO pid=11413 4604674048:PushTelemetryThread telemetry.pyo:167 PushStates.ONLINE: 41507.25 => 26677.3 2015-12-16 16:32:57,152 -0500 INFO pid=11413 4608880640:TelemetryUploaderThread sync_client thread.pyo:209 TelemetryUploaderThread run loop has terminated. 2015-12-16 16:32:57,152 -0500 INFO pid=11413 140735235863312:MainThread thread manager.pyo:250 Asking <Batcher(Batcher, started 46172938241> to stop. 2015-12-16 16:32:57,153 -0500 INFO pid=11413 140735235863312:MainThread thread manager.pyo:250 Asking <FinderInjector(FinderInjector, started 45225574401> to stop. 2015-12-16 16:32:57,154 -0500 INFO pid=11413 4617293824:Batcher sync_client thread.pyo:270 Batcher run loop has terminated. 2015-12-16 16:32:57,154 -0500 INFO pid=11413 4596260864:CloudWatcher sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user default/sync_config.db 2015-12-16 16:32:57,154 -0500 INFO pid=11413 4617293824:Batcher sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user default/snapshot.db 2015-12-16 16:32:57,154 -0500 INFO pid=11413 140735235863312:MainThread thread_manager.pyo:250 Asking <FeatureSwitchPoller(FeatureSwitchPoller, started 45794344961> to stop. 2015-12-16 16:32:57,155 -0500 INFO pid=11413 4522557440:FinderInjector sync_client_thread.pyo:209 Finderlnjector run loop has terminated. 2015-12-16 16:32:57,155 -0500 INFO pid=11413 4596260864:CloudWatcher utils.pyo:714 Execute cleanup callback 'persistence sqlite:e9cb74a6ae6d45219142f6eb5b32b47b/Users/lgroff/Library/Application Support/Googie/Drive/user default/snapshot.db' 2015-12-16 16:32:57,155 -0500 INFO pid=11413 4617293824:Batcher utils.pyo:714 Execute cleanup callback 'persistence sqlite:77ef3ff655554a80b1c2c553247aa589/Users/lgroff/Library/Application Support/Googie/Drive/user default/sync_config.db' 2015-12-16 16:32:57,169 -0500 INFO pid=11413 4596260864:CloudWatcher utils.pyo:714 Execute cleanup callback 'DBConnectionManagerCleanupCallback' 2015-12-16 16:32:57,170 -0500 INFO pid=11413 140735235863312:MainThread thread_manager.pyo:250 Asking <Worker(Worker-2, started 46004674561> to stop. 2015-12-16 16:32:57,170 -0500 INFO pid=11413 4579434496:FeatureSwitchPoller sync_client_thread.pyo:209 FeatureSwitchPoller run loop has terminated. EFTA00502473 2015-12-16 16:32:57,170 -0500 INFO pid=11413 4617293824:Batcher sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-16 16:32:57,170 -0500 INFO pid=11413 4596260864:CloudWatcher db_connection.pyo:431 Closing ManagedConnection(id=10E6F85D0, thread=CloudWatcher-111F56000, path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph/dict_2.db, closed=False) 2015-12-16 16:32:57,170 -0500 WARNING pid=11413 140735235863312:MainThread sync_client_thread.pyo:237 Wake() called while thread was running, should be a N0P 2015-12-16 16:32:57,171 -0500 INFO pid=11413 4617293824:Batcher utils.pyo:714 Execute cleanup callback 'persistence_sqlite:38f45828776d462994b8a14780c65f33/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2015-12-16 16:32:57,171 -0500 INFO pid=11413 140735235863312:MainThread thread_manager.pyo:250 Asking <OverlayStatusCleaner(OverlayStatusCleaner, started 4629913600)> to stop. 2015-12-16 16:32:57,172 -0500 INFO pid=11413 4600467456:Worker-2 sync_client_thread.pyo:209 Worker-2 run loop has terminated. 2015-12-16 16:32:57,172 -0500 INFO pid=11413 4600467456:Worker-2 sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-16 16:32:57,173 -0500 INFO pid=11413 140735235863312:MainThread thread_manager.pyo:250 Asking <RawEventUpgrader(RawEventUpgrader, started 4621500416)> to stop. 2015-12-16 16:32:57,173 -0500 INFO pid=11413 4629913600:OverlayStatusCleaner sync_client_thread.pyo:209 OverlayStatusCleaner run loop has terminated. 2015-12-16 16:32:57,173 -0500 INFO pid=11413 4600467456:Worker-2 sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-16 16:32:57,174 -0500 INFO pid=11413 140735235863312:MainThread thread_manager.pyo:250 Asking <IpcServerThread(IpcServerThread, started 4552515584)> to stop. 2015-12-16 16:32:57,174 -0500 INFO pid=11413 4621500416:RawEventUpgrader sync_client_thread.pyo:209 RawEventUpgrader run loop has terminated. 2015-12-16 16:32:57,174 -0500 INFO pid=11413 4600467456:Worker-2 utils.pyo:714 Execute cleanup callback 'persistence_sqlite:842c5777fde94936adf6837b2fec6ff6/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2015-12-16 16:32:57,174 -0500 INFO pid=11413 140735235863312:MainThread thread_manager.pyo:250 Asking <LocalWatcher(LocalWatcher, started 4634120192)> to stop. 2015-12-16 16:32:57,175 -0500 INFO pid=11413 4600467456:Worker-2 utils.pyo:714 Execute cleanup callback 'persistence_sqlite:2ee20509496745739f96e299d7427bbf/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2015-12-16 16:32:57,175 -0500 INFO pid=11413 140735235863312:MainThread thread_manager.pyo:250 Asking <PushXmppClient(XmppClient, started 4575227904)> to stop. 2015-12-16 16:32:57,236 -0500 WARNING pid=11413 140735235863312:MainThread task.pyo:242 DriveXmppTask-0x10f84bc90 aborting: abort 2015-12-16 16:32:57,236 -0500 INFO pid=11413 140735235863312:MainThread task.pyo:254 Task <common.push.push_xmpp_client.DriveXmppTask object at 0x10f84bc90> complete. 2015-12-16 16:32:57,237 -0500 INFO pid=11413 140735235863312:MainThread xmpplib.pyo:143 Close called with 'why' reason = Client initiated close 2015-12-16 16:32:57,912 -0500 INFO pid=11413 4596260864:CloudWatcher utils.pyo:714 Execute cleanup callback 'persistence_sqlite:62bd5f0d074a4482b701f71b7ab3e5db/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2016-01-04 09:53:01,227 -0500 INFO pid=14964 140735235863312:MainThread logging_config.pyo:249 OS: Darwin/10.9.5 2016-01-04 09:53:01,392 -0500 INFO pid=14964 140735235863312:MainThread logging_config.pyo:252 Google Drive (build 1.26.0707.2863) 2016-01-04 09:53:01,392 -0500 INFO pid=14964 140735235863312:MainThread logging_config.pyo:253 SSL: OpenSSL 0.9.8zg 14 July 2015 2016-01-04 09:53:01,904 -0500 INFO pid=14964 140735235863312:MainThread GSyncAppDelegate.pyo:198 Application did finish launching. 2016-01-04 09:53:05,597 -0500 INFO pid=14964 140735235863312:MainThread service.pyo:132 Creating singleton service instance of '<class 'common.file_lock.FileLock'>' EFTA00502474 2016-01-04 09:53:05,598 -0500 INFO pid=14964 140735235863312:MainThread service.pyo:132 Creating singleton service instance of '<class 'common.file_lock.FileLock'>' 2016-01-04 09:53:05,599 -0500 INFO pid=14964 4523036672:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.service.user.User'>' 2016-01-04 09:53:05,599 -0500 INFO pid=14964 4523036672:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.roots.roots.RootManager'>' 2016-01-04 09:53:05,599 -0500 INFO pid=14964 4523036672:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.thread_manager.ThreadManager'> 1 2016-01-04 09:53:05,599 -0500 INFO pid=14964 4523036672:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.pause_manager.Service'>' 2016-01-04 09:53:05,600 -0500 INFO pid=14964 4523036672:RunAsync-_InitializeSyncAppAsync-1 sync_app.pyo:330 Getting TokenStorage... 2016-01-04 09:53:05,600 -0500 INFO pid=14964 4523036672:RunAsync-_InitializeSyncAppAsync-1 sync_app.pyo:332 Got token storage: <token_storage.TokenStorage object at 0x10931ded0> 2016-01-04 09:53:05,600 -0500 INFO pid=14964 4523036672:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.features.AppFeatures'>' 2016-01-04 09:53:05,600 -0500 INFO pid=14964 4523036672:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.cert.Manager'>' 2016-01-04 09:53:05,834 -0500 INFO pid=14964 4523036672:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.net.bandwidth.BandwidthThrottler'>' 2016-01-04 09:53:05,935 -0500 INFO pid=14964 4523036672:RunAsync-_InitializeSyncAppAsync-1 sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-04 09:53:05,936 -0500 INFO pid=14964 4523036672:RunAsync-_InitializeSyncAppAsync-1 sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 09:53:05,936 -0500 INFO pid=14964 4523036672:RunAsync-_InitializeSyncAppAsync-1 utils.pyo:737 AddCleanupCallback persistence_sqlite:130f92f03cb14979a6ea490e0daf0805/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-04 09:53:05,937 -0500 INFO pid=14964 4523036672:RunAsync-_InitializeSyncAppAsync-1 utils.pyo:737 AddCleanupCallback persistence_sqlite:2796efl5d41944fc9ca67c5772cf27cc/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 09:53:06,165 -0500 INFO pid=14964 4523036672:RunAsync-_InitializeSyncAppAsync-1 sync_app.pyo:506 BandwidthThrottler configured via sync_config 2016-01-04 09:53:06,165 -0500 INFO pid=14964 4523036672:RunAsync-_InitializeSyncAppAsync-1 bandwidth.pyo:59 BandwidthThrottler.SetRxBytesPerSecond 0 2016-01-04 09:53:06,166 -0500 INFO pid=14964 4523036672:RunAsync-_InitializeSyncAppAsync-1 bandwidth. pyo:68 BandwidthThrottler.SetTxBytesPerSecond 0 2016-01-04 09:53:06,166 -0500 INFO pid=14964 4523036672:RunAsync-_InitializeSyncAppAsync-1 sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 09:53:06,166 -0500 INFO pid=14964 4523036672:RunAsync-_InitializeSyncAppAsync-1 sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-04 09:53:06,205 -0500 INFO pid=14964 4523036672:RunAsync-_InitializeSyncAppAsync-1 utils.pyo:714 Execute cleanup callback 'persistence_sqlite:130f92f03cb14979a6ea490e0daf0805/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2016-01-04 09:53:06,225 -0500 INFO pid=14964 4523036672:RunAsync-_InitializeSyncAppAsync-1 utils.pyo:714 Execute cleanup callback 'persistence_sqlite:2796efl5d41944fc9ca67c5772cf27cc/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2016-01-04 09:53:06,226 -0500 INFO pid=14964 4523036672:FinderInjector sync_client_thread.pyo:175 Finderlnjector run loop has started. 2016-01-04 09:53:09,803 -0500 INFO pid=14964 140735235863312:MainThread image_resources.py:292 Loading image resources/images/ic_drawer_24.png 2016-01-04 09:53:10,356 -0500 INFO pid=14964 140735235863312:MainThread image_resources.py:292 Loading image resources/images/menu_drive-logo.png EFTA00502475 2016-01-04 09:53:10,510 -0500 INFO pid=14964 140735235863312:MainThread image_resources.py:292 Loading image resources/images/menu_google-logo-gray.png 2016-01-04 09:53:10,561 -0500 INFO pid=14964 140735235863312:MainThread image_resources.py:292 Loading image resources/images/ic_folder_mydrive_24.png 2016-01-04 09:53:10,609 -0500 INFO pid=14964 140735235863312:MainThread image_resources.py:292 Loading image resources/images/ic_web_24.png 2016-01-04 09:53:10,611 -0500 INFO pid=14964 140735235863312:MainThread image_resources.py:292 Loading image resources/images/ic_sync_problem_24.png 2016-01-04 09:53:10,685 -0500 INFO pid=14964 140735235863312:MainThread image_resources.py:292 Loading image resources/images/ic_done.png 2016-01-04 09:53:13,612 -0500 INFO pid=14964 4545896448:ThreadMonitor thread_manager.pyo:336 Waiting on exit condition. Wake up after 3600s. 2016-01-04 09:53:13,612 -0500 INFO pid=14964 4550103040:_LaunchSyncApp sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-04 09:53:13,614 -0500 INFO pid=14964 4550103040:_LaunchSyncApp sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 09:53:13,614 -0500 INFO pid=14964 4550103040:_LaunchSyncApp utils.pyo:737 AddCleanupCallback persistence_sqlite: f4371174dea8430d9a7955de2a2fc2c7/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-04 09:53:13,615 -0500 INFO pid=14964 4550103040:_LaunchSyncApp utils.pyo:737 AddCleanupCallback persistence_sqlite:5e9cc00alc9b4f578ce71227dd71d0d6/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 09:53:13,716 -0500 INFO pid=14964 4550103040:_LaunchSyncApp user.pyo:66 Initializing User instance with new credentials. None 2016-01-04 09:53:13,716 -0500 INFO pid=14964 4550103040:_LaunchSyncApp docs_client_factory.pyo:89 Creating a new DriveClient 2016-01-04 09:53:13,716 -0500 INFO pid=14964 4550103040:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.quota.Quotalnfo'>' 2016-01-04 09:53:13,717 -0500 INFO pid=14964 4550103040:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.token_bucket.CloudBucketManager'> 1 2016-01-04 09:53:13,718 -0500 INFO pid=14964 4550103040:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.drive_api.drive_resource.Manager'>. 2016-01-04 09:53:13,718 -0500 INFO pid=14964 4550103040:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.drive_api.http.Manager'>. 2016-01-04 09:53:13,718 -0500 INFO pid=14964 4550103040:_LaunchSyncApp drive_resource.pyo:130 Initialize apiclient Drive resource for current user token 2016-01-04 09:53:13,720 -0500 INFO pid=14964 140735235863312:MainThread sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-04 09:53:13,748 -0500 INFO pid=14964 140735235863312:MainThread sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 09:53:13,749 -0500 INFO pid=14964 140735235863312:MainThread sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-04 09:53:13,749 -0500 INFO pid=14964 140735235863312:MainThread sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 09:53:13,781 -0500 INFO pid=14964 4550103040:_LaunchSyncApp drive_resource.pyo: 178 Drive SDK baseUrl = https://www.googleapis.com/drive/v2internal/ 2016-01-04 09:53:13,787 -0500 INFO pid=14964 4550103040:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.drive_api.http.AccessTokenInMemoryCache'>. 2016-01-04 09:53:13,788 -0500 INFO pid=14964 4550103040:_LaunchSyncApp drive_request.pyo:285 Drive request: About(tags=Reason.SYNC_OTHER) - Read request - None 2016-01-04 09:53:13,790 -0500 INFO pid=14964 4550103040:_LaunchSyncApp discovery.pyo:762 URL being requested: GET https://www.googleapis.com/drive/v2internal/about? fields=rootFolderld%2CquotaBytesTotal%2CquotaBytesUsedAggregate%2CquotaBytesUsedInTrash%2Cquo taType%2Cuser%2FemailAddress&reason=304Salt=json 2016-01-04 09:53:13,807 -0500 INFO pid=14964 4550103040:_LaunchSyncApp client.pyo:515 Attempting refresh to obtain initial access_token EFTA00502476 2016-01-04 09:53:13,808 -0500 INFO pid=14964 4550103040:_LaunchSyncApp client.pyo:755 Refreshing access_token 2016-01-04 09:53:13,967 -0500 INFO pid=14964 4550103040:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.net.dapper.DapperService'>' 2016-01-04 09:53:14,016 -0500 INFO pid=14964 4550103040:_LaunchSyncApp http.pyo:248 Request user-agent: Python-httplib2/0.9 (gzip) 2016-01-04 09:53:14,016 -0500 INFO pid=14964 4550103040:_LaunchSyncApp sync_http_client.pyo:236 Opening direct connecton to accounts.google.com:443. 2016-01-04 09:53:14,161 -0500 INFO pid=14964 4550103040:_LaunchSyncApp http.pyo:267 Response: 151 bytes - application/json; charset=utf-8 (encoding:gzip) 2016-01-04 09:53:14,162 -0500 INFO pid=14964 4550103040:_LaunchSyncApp http.pyo:248 Request user-agent: googledrivesync-1.26.0707.2863 google-api-python-client/1.3.1 (gzip) (Darwin/10.9.5) 2016-01-04 09:53:14,162 -0500 INFO pid=14964 4550103040:_LaunchSyncApp sync_http_client.pyo:236 Opening direct connecton to www.googleapis.com:443. 2016-01-04 09:53:14,937 -0500 INFO pid=14964 4550103040:_LaunchSyncApp http.pyo:267 Response: 241 bytes - application/json; charset=UTF-8 (encoding:gzip) 2016-01-04 09:53:15,196 -0500 INFO pid=14964 4550103040:_LaunchSyncApp drive_request.pyo:292 Drive response: Request (None): About(tags=Reason.SYNC_OTHER) Response: About(root_id=OABNN8iEiWGvgUk9PVA, email_address=lesley.jee@gmail.com, quota_bytes_total=16106127360, quota_bytes_used_aggregate=15360076124, quota_bytes_used_in_trash=405074503, quota_type=LIMITED) 2016-01-04 09:53:15,210 -0500 INFO pid=14964 4550103040:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.drive_api.doc_id_provider.Store'>' 2016-01-04 09:53:15,210 -0500 INFO pid=14964 4550103040:_LaunchSyncApp user.pyo:66 Initializing User instance with new credentials. lesley.jee@gmail.com 2016-01-04 09:53:15,210 -0500 INFO pid=14964 4550103040:_LaunchSyncApp sync_app.pyo:1235 Configuring sync app from feature switches. 2016-01-04 09:53:15,210 -0500 INFO pid=14964 4550103040:_LaunchSyncApp features.pyo:112 Loads feature switches. 2016-01-04 09:53:15,210 -0500 INFO pid=14964 4550103040:_LaunchSyncApp feature_switch_manager.pyo:644 Calling server to get feature switches. 2016-01-04 09:53:15,210 -0500 INFO pid=14964 4550103040:_LaunchSyncApp drive_request.pyo:285 Drive request: ListFeatureSwitches(tags=Reason.SYNC_OTHER) - Read request - lesley.jee@gmail.com 2016-01-04 09:53:15,212 -0500 INFO pid=14964 4550103040: LaunchSyncApp discovery.pyo:762 URL being requested: GET https://www.googleapis.com/drive/v2internal/settings? fields=items%2Fkey%2Citems%2FValue&reason=304Snamespace=FEATURE_SWITCHSalt=json 2016-01-04 09:53:15,213 -0500 INFO pid=14964 4550103040:_LaunchSyncApp http.pyo:248 Request user-agent: googledrivesync-1.26.0707.2863 google-api-python-client/1.3.1 (gzip) (Darwin/10.9.5) 2016-01-04 09:53:15,276 -0500 INFO pid=14964 4550103040:_LaunchSyncApp http.pyo:267 Response: 1434 bytes - application/json; charset=UTF-8 (encoding:gzip) 2016-01-04 09:53:15,277 -0500 INFO pid=14964 4550103040:_LaunchSyncApp drive_request.pyo:292 Drive response: Request (lesley.jee@gmail.com): ListFeatureSwitches(tags=Reason.SYNC_OTHER) Response: (u'enable_impressions_library': u'false', u'selective_sub_folder_sync.: u'false', u'crash_throttle_percentage': u'99.7', u'enable_feedback': u'true', u'enable_usb_detection': u'false', u'max_page_size_selective_syncl: u'1000', u'enable_shared_move_confirmation': u'false', u'enable_disk_check_on_preferences': u'false', u'enable_recursive_size': u'false', u'enable_push': u'true', u'enable_sync_options_in_setup_flow': u'false', u'enable_multipause': u'true', u'log_backup_count': u'0', u'enable_arbitrary_sync': u'false', u'enable_disk_check_on_setup': u'false', u'maxbatch_upload_files': u'30', u'cloud_graph_disk_generation': u'2', u'use_drive_api': u'false', u'query_strategy': u'all', u'enable_multipleaccounts': u'false', u'perf_throttle_percentage': u'100', u'test.feature_switch.name': u") 2016-01-04 09:53:15,278 -0500 INFO pid=14964 4550103040:_LaunchSyncApp sync_app.pyo:1260 Feature Switches: FeatureSwitchSettings( accept_blob_download_gzip_encoding=True, EFTA00502477 backup_polling_interval_secs=7200, change_filters=['DRIVE_SYNC 1], cloud_graph_disk_generation=2, crash_log_size_limit=10000000, crash_throttle_percentage=99.7, disabled_cloud_graph_disk_app_versions=[], docs_list_page_size=1000, download_in_place=False, download_progress_bar=False, download_strategy=turl_template', download_url=thttps://googledrive.com/p/host/(doc_id]', enable_arbitrary_sync=False, enable_batch_upload=True, enable_change_filters=False, enable_context_menu=True, enable_dapper_trace=False, enable_disk_dict=False, enable_feedback=True, enable_f ile_sync_status=True, enable_impressions_library=False, enable_local_v2=False, enable_multipause=True, enable_multiple_accounts=False, enable_native_opener=True, enable_push=True, enable_recursive_size=False, enable_shared_moveconfirmation=False, enable_usb_detection=False, feedback_type='prod', ghost_all_files=False, help_url=lhttps://support.google.com/drive/M1=%s&p=desktop_homel, 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, new_document_ur1= 1 https://docs.google.com/document?usp=drive_syncl, new_presentation_ur1= 1https://docs.google.com/presentation?usp=drive_synci, new_spreadsheet_ur1='https://docs.google.com/spreadsheets?usp=drive_synci, numworkers=3, open_ur1='https://docs.google.com/open?id=(doc_id)', overlays_enabled_finder_versions=[.10.71, '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, guery_strategy=u'all', regular_polling_interval_secs=30, selective_sub_folder_sync=False, share_template_ur1='https://drive.google.com/sharing/share? 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='https://drive.google.com/syncclient_impressions', token_bucket_read_gps=10, token_bucketwrite_gps=3, use_drive_api=False,) 2016-01-04 09:53:15,303 -0500 INFO pid=14964 4550103040:_LaunchSyncApp logging_config.pyo:249 OS: Darwin/10.9.5 2016-01-04 09:53:15,312 -0500 INFO pid=14964 4550103040:_LaunchSyncApp logging_config.pyo:252 Google Drive (build 1.26.0707.2863) 2016-01-04 09:53:15,312 -0500 INFO pid=14964 4550103040:_LaunchSyncApp EFTA00502478 logging_config.pyo:253 SSL: OpenSSL 0.9.8zg 14 July 2015 2016-01-04 09:53:15,312 -0500 INFO pid=14964 4550103040:_LaunchSyncApp sync_app.pyo:1269 Config: Email: lesley.jee@gmail.com Sync root: /Users/lgroff/Google Drive Sync collections: set([]) Upgrade number: 22 App version: 1.26.0707.2863 Selective sync: False Cloud Graph generation: 2 Folder sync: [] Local app whitelist: set([]) Local app blacklist: set([]) Bandwidth RX Rate kpBs: 0 Bandwidth TX Rate kpBs: 0 Yosemite icons upgraded: False Win10 icons upgraded: False USB sync enabled: True Do sync default folder: True 2016-01-04 09:53:15,315 -0500 INFO pid=14964 4550103040:_LaunchSyncApp sync_app.pyo:1274 Switching cloud client based on new feature switches 2016-01-04 09:53:15,315 -0500 INFO pid=14964 4550103040:_LaunchSyncApp docs_client_factory.pyo:97 Creating a new Wapi DocsClient 2016-01-04 09:53:15,316 -0500 INFO pid=14964 4550103040:_LaunchSyncApp telemetry.pyo:228 SetPushClientVersion: 1 (3, 20130807) 2016-01-04 09:53:15,316 -0500 INFO pid=14964 4550103040:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.push.telemetry.PushTelemetry'>' 2016-01-04 09:53:15,316 -0500 INFO pid=14964 4550103040:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.telemetry.telemetry_manager.TelemetryManager'>' 2016-01-04 09:53:15,904 -0500 INFO pid=14964 4550103040:_LaunchSyncApp image_resources.py:268 Loading custom image resources/images/folder-mac.icns with function _CreateNSImage 2016-01-04 09:53:15,940 -0500 INFO pid=14964 4550103040:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.roots.root_wrangler.RootWrangler'>' 2016-01-04 09:53:15,940 -0500 INFO pid=14964 4550103040:_LaunchSyncApp root_wrangler.pyo:65 RootWrangler.RootObserverRegistered with roots [_MyDriveRoot(u'/Users/lgroff/Google Drive', 'root', <RootState.INITED: 2>)] 2016-01-04 09:53:15,941 -0500 INFO pid=14964 4550103040:_LaunchSyncApp root_wrangler.pyo:73 RootWrangler.RootsAdded with roots (ilyDriveRoot(u'/Users/lgroff/Google Drive', 'root', <RootState.INITED: 2>)] 2016-01-04 09:53:15,941 -0500 INFO pid=14964 4550103040:_LaunchSyncApp root_wrangler.pyo:79 RootWrangler got inited root _MyDriveRoot(u'/Users/lgroff/Google Drive', 'root', <RootState.INITED: 2>) 2016-01-04 09:53:15,942 Registering aggregation 2016-01-04 09:53:15,942 Registering aggregation 2016-01-04 09:53:15,942 Registering aggregation 2016-01-04 09:53:15,942 Registering aggregation 2016-01-04 09:53:15,942 Registering aggregation 2016-01-04 09:53:15,943 Registering aggregation 2016-01-04 09:53:15,943 Registering aggregation 2016-01-04 09:53:15,943 Registering aggregation 2016-01-04 09:53:16,705 filewatcher_ext initialized 2016-01-04 09:53:16,706 -0500 INFO pid=14964 4550103040:_LaunchSyncApp docs_client_factory.pyo:97 Creating a new Wapi DocsClient 2016-01-04 09:53:16,707 -0500 INFO pid=14964 4550103040:_LaunchSyncApp -0500 INFO rule type: -0500 INFO rule type: -0500 INFO rule type: -0500 INFO rule type: -0500 INFO rule type: -0500 INFO rule type: -0500 INFO rule type: -0500 INFO rule type: -0500 INFO pid=14964 4550103040:_LaunchSyncApp rule_factory.pyo:159 common.aggregator_rules.downloads.DownloadCreate pid=14964 4550103040:_LaunchSyncApp rule_factory.pyo:159 common.aggregator_rules.downloads.DownloadModify pid=14964 4550103040:_LaunchSyncApp rule_factory.pyo:159 common.aggregator_rules.uploads.UploadCreate pid=14964 4550103040:_LaunchSyncApp rule_factory.pyo:159 common.aggregator_rules.uploads.UploadDelete pid=14964 4550103040:_LaunchSyncApp rulefactory.pyo:159 common.aggregator_rules.uploads.UploadModify pid=14964 4550103040:_LaunchSyncApp rule_factory.pyo:159 common.aggregator_rules.uploads.UploadMove pid=14964 4550103040:_LaunchSyncApp rule_factory.pyo:159 common.aggregator_rules.uploads.UploadMoveFrom pid=14964 4550103040:_LaunchSyncApp rule_factory.pyo:159 common.aggregator_rules.uploads.UploadMoveTo pid=14964 4558016512:FilewatcherMonitor filewatcher.pyo:71 EFTA00502479 docs_client_factory.pyo:97 Creating a new Wapi DocsClient 2016-01-04 09:53:16,708 -0500 INFO pid=14964 4550103040:_LaunchSyncApp docs_client_factory.pyo:97 Creating a new Wapi DocsClient 2016-01-04 09:53:16,709 -0500 INFO pid=14964 4550103040:_LaunchSyncApp docs_client_factory.pyo:97 Creating a new Wapi DocsClient 2016-01-04 09:53:16,709 -0500 INFO pid=14964 4550103040:_LaunchSyncApp docs_client_factory.pyo:97 Creating a new Wapi DocsClient 2016-01-04 09:53:16,710 -0500 INFO pid=14964 4550103040:_LaunchSyncApp docs_client_factory.pyo:97 Creating a new Wapi DocsClient 2016-01-04 09:53:16,710 -0500 INFO pid=14964 4550103040:_LaunchSyncApp docs_client_factory.pyo:97 Creating a new Wapi DocsClient 2016-01-04 09:53:16,711 -0500 INFO pid=14964 4550103040:_LaunchSyncApp xmpplib_notifier.pyo:34 Configure storage: <common.push.tango.storage.SyncConfigStorage; proxy of <Swig Object of type 'invalidation::Storage *' at 0x10f6b89f0> > 2016-01-04 09:53:16,711 -0500 INFO pid=14964 4550103040:_LaunchSyncApp xmpplib_notifier.pyo:59 Creating client thread 2016-01-04 09:53:16,711 -0500 INFO pid=14964 4550103040:_LaunchSyncApp push_xmpp_client.pyo:223 Using talk server: talk.google.com:5222 2016-01-04 09:53:16,712 -0500 INFO pid=14964 4550103040:_LaunchSyncApp xmpplib_notifier.pyo:68 Creating notification bridge 2016-01-04 09:53:16,748 -0500 INFO pid=14964 4550103040:_LaunchSyncApp docs_client_factory.pyo:97 Creating a new Wapi DocsClient 2016-01-04 09:53:16,751 -0500 INFO pid=14964 4565504000:OverlayStatusCleaner sync_client_thread.pyo:175 OverlayStatusCleaner run loop has started. 2016-01-04 09:53:16,751 -0500 INFO pid=14964 4569710592:CrashReportUploaderThread crash report uploader thread.pyo:60 Starting CrashReportUploaderThread. 2016-01-04 09:53:16,752 -0500 INFO pid=14964 4582330368:Worker-0 sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-04 09:53:16,752 -0500 INFO pid=14964 4586536960:TelemetryUploaderThread sync_client_thread.pyo:175 TelemetryUploaderThread run loop has started. 2016-01-04 09:53:16,753 -0500 INFO pid=14964 4590743552:LocalFSPoller sync_client_thread.pyo:175 LocalFSPoller run loop has started. 2016-01-04 09:53:16,754 -0500 INFO pid=14964 4594950144:CloudWatcher sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user default/snapshot.db 2016-01-04 09:53:16,754 -0500 INFO pid=14964 4599156736:PushTelemetryThread sync_client_thread.pyo:175 PushTelemetryThread run loop has started. 2016-01-04 09:53:16,754 -0500 INFO pid=14964 4603363328:FeatureSwitchPoller sync_client_thread.pyo:175 FeatureSwitchPoller run loop has started. 2016-01-04 09:53:16,755 -0500 INFO pid=14964 4607569920:Worker-2 sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user default/snapshot.db 2016-01-04 09:53:16,755 -0500 INFO pid=14964 4611776512:PushNotificationKeepaliveThread sync_client_thread.pyo:175 PushNotificationKeepaliveThread run loop has started. 2016-01-04 09:53:16,755 -0500 INFO pid=14964 4603363328:FeatureSwitchPoller feature_switch_manager.pyo:644 Calling server to get feature switches. 2016-01-04 09:53:16,756 -0500 INFO pid=14964 4615983104:Batcher sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-04 09:53:16,756 -0500 INFO pid=14964 4624396288:XmppClient telemetry.pyo:153 Push state None => PushStates.CONNECTING 2016-01-04 09:53:16,756 -0500 INFO pid=14964 4620189696:RawEventUpgrader sync_client_thread.pyo:175 RawEventUpgrader run loop has started. 2016-01-04 09:53:16,756 -0500 INFO pid=14964 4628602880:TelemetryStoreSizeMonitorThread sync_client_thread.pyo:175 TelemetryStoreSizeMonitorThread run loop has started. 2016-01-04 09:53:16,757 -0500 INFO pid=14964 4637016064:LocalDiskSpaceChecker sync_client_thread.pyo:175 LocalDiskSpaceChecker run loop has started. 2016-01-04 09:53:16,757 -0500 INFO pid=14964 4641222656:PauseTelemetryThread sync_client_thread.pyo:175 PauseTelemetryThread run loop has started. 2016-01-04 09:53:16,757 -0500 INFO pid=14964 4645429248:LocalWatcher watcher.pyo:347 LocalWatcher Run invoked.Waiting for first pass of local scan from poller to start 2016-01-04 09:53:16,758 -0500 INFO pid=14964 4550103040:_LaunchSyncApp drive_extension_installer.pyo:174 Uninstalling extension nknebiagdodnminbdpflhpkgfpeijdbf 2016-01-04 09:53:16,758 -0500 INFO pid=14964 4649635840:Worker-1 sqlite.pyo:276 Open EFTA00502480 master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-04 09:53:16,774 -0500 INFO pid=14964 4603363328:FeatureSwitchPoller drive_resource.pyo:130 Initialize apiclient Drive resource for lesley.jee@gmail.com 2016-01-04 09:53:16,774 -0500 INFO pid=14964 4611776512:PushNotificationKeepaliveThread sync_http_client.pyo:236 Opening direct connecton to accounts.google.com:443. 2016-01-04 09:53:16,775 -0500 INFO pid=14964 4624396288:XmppClient connection_task.pyo:151 Recalculated LoginTask timeout: 16.000000 2016-01-04 09:53:16,776 -0500 INFO pid=14964 4628602880:TelemetryStoreSizeMonitorThread telemetry_store_monitor_thread.pyo: 44 Checking Telemetry store size. 2016-01-04 09:53:16,778 -0500 INFO pid=14964 4603363328:FeatureSwitchPoller drive_resource.pyo: 178 Drive SDK baseUrl = https://www.googleapis.com/drive/v2internal/ 2016-01-04 09:53:16,779 -0500 INFO pid=14964 4624396288:XmppClient telemetry.pyo:167 PushStates.CONNECTING: +0.0223779 => 0.0223779 2016-01-04 09:53:16,779 -0500 INFO pid=14964 4550103040:_LaunchSyncApp utils.pyo:714 Execute cleanup callback 'persistence_sqlite:5e9cc00alc9b4f578ce71227dd71d0d6/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2016-01-04 09:53:16,780 -0500 INFO pid=14964 4624396288:XmppClient xmpplib.pyo:80 Connecting to talk.google.com:5222 2016-01-04 09:53:16,780 -0500 INFO pid=14964 4550103040:_LaunchSyncApp sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 09:53:16,780 -0500 INFO pid=14964 4603363328:FeatureSwitchPoller drive_request.pyo:285 Drive request: ListFeatureSwitches(tags=Reason.SYNC_OTHER) - Read request - lesley.jee@gmail.com 2016-01-04 09:53:16,784 -0500 INFO pid=14964 4603363328:FeatureSwitchPoller discovery.pyo:762 URL being requested: GET https://www.googleapis.com/drive/v2internal/settings? fields=items%2Fkey%2Citems%2Fvalue&reason=304Snamespace=FEATURE_SWITCHSalt=json 2016-01-04 09:53:16,784 -0500 INFO pid=14964 4607569920:Worker-2 sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 09:53:16,784 -0500 INFO pid=14964 4594950144:CloudWatcher sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 09:53:16,785 -0500 INFO pid=14964 4582330368:Worker-0 sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 09:53:16,785 -0500 INFO pid=14964 4603363328:FeatureSwitchPoller client.pyo:515 Attempting refresh to obtain initial access_token 2016-01-04 09:53:16,785 -0500 INFO pid=14964 4615983104:Batcher sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 09:53:16,786 -0500 INFO pid=14964 4603363328:FeatureSwitchPoller client.pyo:735 Updated access_token read from Storage 2016-01-04 09:53:16,786 -0500 INFO pid=14964 4649635840:Worker-1 sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 09:53:16,787 -0500 INFO pid=14964 4603363328:FeatureSwitchPoller http.pyo:248 Request user-agent: googledrivesync-1.26.0707.2863 google-api-python-client/1.3.1 (gzip) (Darwin/10.9.5) 2016-01-04 09:53:16,787 -0500 INFO pid=14964 4603363328:FeatureSwitchPoller sync_http_client.pyo:236 Opening direct connecton to www.googleapis.com:443. 2016-01-04 09:53:16,787 -0500 INFO pid=14964 4550103040:_LaunchSyncApp utils.pyo:714 Execute cleanup callback 'persistence_sqlite:f4371174dea8430d9a7955de2a2fc2c7/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2016-01-04 09:53:16,787 -0500 INFO pid=14964 4550103040:_LaunchSyncApp sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-04 09:53:16,788 -0500 INFO pid=14964 4607569920:Worker-2 utils.pyo:737 AddCleanupCallback persistence_sqlite: 6a8482ff59e14571b9f7c3b163e7f7dd/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-04 09:53:16,788 -0500 INFO pid=14964 4607569920:Worker-2 utils.pyo:737 EFTA00502481 AddCleanupCallback persistence_sglite:48bbc7c021ca410088d1b976718f281e/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 09:53:16,789 -0500 INFO pid=14964 4607569920:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadChangeACL 2016-01-04 09:53:16,789 -0500 INFO pid=14964 4607569920:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadCreate 2016-01-04 09:53:16,789 -0500 INFO pid=14964 4607569920:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadDelete 2016-01-04 09:53:16,789 -0500 INFO pid=14964 4607569920:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadModify 2016-01-04 09:53:16,789 -0500 INFO pid=14964 4607569920:Worker-2 command_factory.pyo: 186 Registering worker command type: common.worker.downloads.DownloadMove 2016-01-04 09:53:16,789 -0500 INFO pid=14964 4594950144:CloudWatcher utils.pyo:737 AddCleanupCallback persistence_scilite:07fcf959d2f4481caef94f9eabdbd161/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-04 09:53:16,790 -0500 INFO pid=14964 4594950144:CloudWatcher utils.pyo:737 AddCleanupCallback persistence_sglite:0c8054162925439ea2f5692030b2343f/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 09:53:16,789 -0500 INFO pid=14964 4582330368:Worker-0 utils.pyo:737 AddCleanupCallback persistence_scilite:bdaecfd49a3d422f843deb9f5f0b6ee2/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-04 09:53:16,789 -0500 INFO pid=14964 4607569920:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadRename 2016-01-04 09:53:16,790 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_graph.pyo:115 Initialize persistent graph with generation 2 2016-01-04 09:53:16,790 -0500 INFO pid=14964 4649635840:Worker-1 utils.pyo:737 AddCleanupCallback persistence_scilite:ecf158a2449c4f60b0f1a421b1043c30/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-04 09:53:16,790 -0500 INFO pid=14964 4582330368:Worker-0 utils.pyo:737 AddCleanupCallback persistence_sglite:38034c89af884493af3c0f9lcc095545/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 09:53:16,790 -0500 INFO pid=14964 4615983104:Batcher utils.pyo:737 AddCleanupCallback persistence_sglite:335e83d28c064b868990682b7d512af9/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-04 09:53:16,792 -0500 INFO pid=14964 4607569920:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.uploads.BatchUploadCreate 2016-01-04 09:53:16,792 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_graph.pyo:1563 Current sync config generation: 2 2016-01-04 09:53:16,793 -0500 INFO pid=14964 4649635840:Worker-1 utils.pyo:737 AddCleanupCallback persistence_sglite:c07895b2f1d74042aef9f704ed698f6d/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 09:53:16,810 -0500 INFO pid=14964 4582330368:Worker-0 command_factory.pyo:186 Registering worker command type: common.worker.uploads.BatchUploadCreate 2016-01-04 09:53:16,810 -0500 INFO pid=14964 4615983104:Batcher utils.pyo:737 AddCleanupCallback persistence_scilite:e3bd4b8bbf70481bae9ee7ac2a7c9fcf/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 09:53:16,810 -0500 INFO pid=14964 4607569920:Worker-2 command_factory.pyo: 186 Registering worker command type: common.worker.uploads.UploadCreate 2016-01-04 09:53:16,810 -0500 INFO pid=14964 4649635840:Worker-1 EFTA00502482 command_factory.pyo:186 Registering worker command type: common.worker.internals.InternalCreate 2016-01-04 09:53:16,811 -0500 INFO pid=14964 4594950144:CloudWatcher Cleanup cloud graph directory '/Users/lgrof f/Library/Application Support/Google/Drive/user_default/cloud_graph' of files 'dict_*db* 1 'dict_2.db-shm', idict_2.db-wal'] 2016-01-04 09:53:16,811 -0500 INFO pid=14964 4624396288:XmppClient telemetry.pyo:167 PushStates.CONNECTING: +0.0324937 => 0.0548716 2016-01-04 09:53:16,811 -0500 INFO pid=14964 4582330368:Worker-0 command_factory.pyo: 186 Registering worker command type: common.worker.uploads.UploadCreate 2016-01-04 09:53:16,811 -0500 INFO pid=14964 4607569920:Worker-2 command_factory.pyo: 186 Registering worker command type: common.worker.uploads.UploadDelete -0500 INFO pid=14964 4649635840:Worker-1 Registering worker command type: . InternalModify -0500 INFO pid=14964 4624396288:XmppClient ONNECTING => PushStates.NEGOTIATING -0500 INFO pid=14964 4582330368:Worker-0 Registering worker command type: common.worker -0500 INFO pid=14964 4607569920:Worker-2 Registering worker command type: common.worker -0500 INFO pid=14964 4649635840:Worker-1 initialize -0500 INFO pid=14964 4582330368:Worker-0 Registering worker command type: common.worker -0500 INFO pid=14964 4607569920:Worker-2 Registering worker command type: common.worker -0500 INFO pid=14964 4649635840:Worker-1 175 Worker-1 run loop has started. -0500 INFO pid=14964 4582330368:Worker-0 Registering worker command type: common.worker -0500 INFO pid=14964 4607569920:Worker-2 Registering worker command type: common.worker 2016-01-04 09:53:16,845 -0500 INFO pid=14964 4582330368:Worker-0 command_factory.pyo:186 Registering worker command type: common.worker. 2016-01-04 09:53:16,845 -0500 INFO pid=14964 4607569920:Worker-2 Waiting for batcher to initialize 2016-01-04 09:53:16,855 -0500 INFO pid=14964 4582330368:Worker-0 Waiting for batcher to initialize 2016-01-04 09:53:16,855 -0500 INFO pid=14964 4607569920:Worker-2 sync_client_thread.pyo:175 Worker-2 run loop has started. 2016-01-04 09:53:16,855 -0500 INFO pid=14964 4582330368:Worker-0 sync_client_thread.pyo:175 Worker-0 run loop has started. 2016-01-04 09:53:16,855 -0500 INFO pid=14964 4594950144:CloudWatcher AddCleanupCal lback DBConnectionManagerCleanupCal lback 2016-01-04 09:53:16,856 -0500 INFO pid=14964 4594950144:CloudWatcher Created thread connection on database /Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph/dict_2.db 2016-01-04 09:53:16,856 -0500 INFO pid=14964 4594950144:CloudWatcher Reuse CloudDiskDict database: /Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph/dict_2.db 2016-01-04 09:53:16,889 -0500 INFO pid=14964 4624396288:XmppClient SSL peer=gmail.com 2016-01-04 09:53:16,948 -0500 INFO pid=14964 4624396288:XmppClient PushStates.NEGOTIATING: +0.137176 => 0.137176 2016-01-04 09:53:16,958 -0500 INFO pid=14964 4603363328:FeatureSwitchPoller http.pyo:267 Response: 1434 bytes - application/json; charset=UTF-8 (encoding:gzip) 2016-01-04 09:53:16,960 -0500 INFO pid=14964 4603363328:FeatureSwitchPoller drive_request.pyo:292 Drive response: Request (lesley.jee@gmail.com): ListFeatureSwitches(tags=Reason.SYNC_OTHER) Response: (u'enable_impressions_library': u'false', u'selective_sub_folder_syncl: u'false', u'crash_throttle_percentage': u'99.7', u'enable_feedback': u'true', u'enable_usb_detection': u'false', u'max_page_size_selective_sync': u'1000 1, u'enable_shared_move_confirmation': 2016-01-04 09:53:16,812 command_factory.pyo:186 common.worker. internals 2016-01-04 09:53:16,812 Push state PushStates.0 2016-01-04 09:53:16,812 command_factory.pyo:186 2016-01-04 09:53:16,812 command_factory.pyo:186 2016-01-04 09:53:16,813 Waiting for batcher to 2016-01-04 09:53:16,814 command_factory.pyo:186 2016-01-04 09:53:16,814 command_factory.pyo:186 2016-01-04 09:53:16,814 sync_client_thread.pyo: 2016-01-04 09:53:16,814 command_factory.pyo:186 2016-01-04 09:53:16,814 command_factory.pyo:186 cloud_graph.pyo: 1415 except ('dict_2.db', telemetry.pyo: 153 .uploads.UploadDelete .uploads.UploadModify workers.pyo:127 .uploads.UploadModify .uploads .UploaciMove .uploads .UploaciMove .uploads.UploadRename uploads.UploadRename workers.pyo:127 db_connection.pyo:200 workers.pyo:127 utils.pyo:737 cloud_graph.pyo:956 xmpplib.pyo:420 Start telemetry.pyo:167 EFTA00502483 u'false', u'enable_disk_check_on_preferences': u'false', u'enable_recursive_size': u'false', u'enablepush': u'true', u'enable_sync_options_in_setup_flow': u'false', u'enable_multipause': u'true', u'log_backup_count': u'0', u'enable_arbitrary_sync': u'false', u'enable_disk_check_on_setup': u'false', u'maxbatch_upload_files': u'30', u'cloud_graph_disk_generation': u'2', u'use_drive_api': u'false', u'query_strategy': u'all', u'enable_multipleaccounts': u'false', u'perf_throttle_percentage': u'100', u'test.feature_switch.name': u") 2016-01-04 09:53:16,973 -0500 INFO pid=14964 4611776512:PushNotificationKeepaliveThread sync_http_client.pyo:236 Opening direct connecton to clients3.google.com:443. 2016-01-04 09:53:17,046 -0500 INFO pid=14964 4624396288:XmppClient telemetry.pyo:167 PushStates.NEGOTIATING: +0.0977627 => 0.234938 2016-01-04 09:53:17,176 -0500 INFO pid=14964 4624396288:XmppClient task.pyo:254 Task <common.push.xmpp.login_task.LoginTask object at Ox10fb15890> complete. 2016-01-04 09:53:17,176 -0500 WARNING pid=14964 4624396288:XmppClient login_task.pyo:90 Clearing connection for LoginTask: <common.push.xmpp.login_task.LoginTask object at 0x10fb15890> 2016-01-04 09:53:17,176 -0500 INFO pid=14964 4624396288:XmppClient push_xmpp_client.pyo:313 Connected to talk.google.com:5222 2016-01-04 09:53:17,177 -0500 WARNING pid=14964 4653842432:RunAsync-_OnXmppConnect-4 notification_bridge.pyo:126 XMPP detected connect. 2016-01-04 09:53:17,177 -0500 INFO pid=14964 4653842432:RunAsync-_OnXmppConnect-4 xmpp_network_channel.pyo:169 Sending push channel subscription request 2016-01-04 09:53:17,178 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_graph.pyo:155 Initialize persistent graph with existing database /Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph/dict_2.db 2016-01-04 09:53:17,178 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_graph.pyo:216 Building gdoc docids cache. 2016-01-04 09:53:17,232 -0500 INFO pid=14964 4624396288:XmppClient xmpp_network_channel.pyo:187 Received subscription response from Push for XMPPBot 2016-01-04 09:53:17,232 -0500 INFO pid=14964 4653842432:RunAsync-_OnXmppConnect-4 notification_bridge.pyo:142 Starting TICL (client version 3.20130807)... 2016-01-04 09:53:17,232 -0500 INFO pid=14964 4653842432:RunAsync-_OnXmppConnect-4 xmpp_network_channel.pyo: 65 XmppNetworkChannel.InformNetworkStatus: CONNECTED 2016-01-04 09:53:17,282 -0500 INFO pid=14964 4632809472:ListenerScheduler invalidation_listener.pyo:185 InvalidationListener.ReissueRegistrations(prefix=, prefix_len=0) 2016-01-04 09:53:17,558 -0500 INFO pid=14964 4611776512:PushNotificationKeepaliveThread push_notification_keepalive_thread.pyo:49 Sent push keepalive message at: Mon Jan 4 09:53:17 2016 2016-01-04 09:53:18,089 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_graph.pyo:161 Reuse CloudDiskDict CloudDiskDict(max_change_stamp=76149, app_version=1.26.0707.2863, (}) 2016-01-04 09:53:18,090 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_watcher.pyo:831 Performing full snapshot diff. 2016-01-04 09:53:18,090 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_watcher.pyo:1026 Getting cloud resources via changelog feed from 76150 2016-01-04 09:53:18,090 -0500 INFO pid=14964 4594950144:CloudWatcher docs_client.pyo:743 Requesting Doc List: /feeds/default/private/changes?start-index=76150&showroot=true&include- shared=true&reason=startup 2016-01-04 09:53:18,091 -0500 INFO pid=14964 4594950144:CloudWatcher sync_http_client.pyo:236 Opening direct connecton to clients3.google.com:443. 2016-01-04 09:53:18,478 -0500 INFO pid=14964 4594950144:CloudWatcher docs_client.pyo:1174 Feed has 17 entries 2016-01-04 09:53:18,478 -0500 INFO pid=14964 4594950144:CloudWatcher docs_client.pyo:1181 End of feed, no next URI 2016-01-04 09:53:18,510 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_watcher.pyo:807 Got 17 cloud entries 2016-01-04 09:53:18,528 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_watcher.pyo:815 720 nodes in the cloud graph 2016-01-04 09:53:18,563 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_snapshot_diff_helper.pyo:84 Prepare for Pruning graph (total of 720 entries). sync gdocs=True, sync folders=None, excluded folders=frozenset(()) 2016-01-04 09:53:18,563 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_snapshot_diff_helper.pyo:85 Start Prune Graph (total of 720 entries) 2016-01-04 09:53:18,578 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_snapshot_diff_helper.pyo:175 Pruning detected 703 items to keep EFTA00502484 2016-01-04 09:53:18,579 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_snapshot_diff_helper.pyo:182 703 entries in graph after pruning 2016-01-04 09:53:18,579 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_snapshot_diff_helper.pyo:93 Compare to snaphost 2016-01-04 09:53:18,579 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_snapshot_diff_helper.pyo:762 Starting cloud graph vs snapshot diff with selective sync set: None 2016-01-04 09:53:18,579 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_snapshot_diff_helper.pyo:764 Prepopulating DOC_ID-to-inodes cache 2016-01-04 09:53:18,582 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_snapshot_diff_helper.pyo:768 First pass detecting moved entries 2016-01-04 09:53:18,717 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_snapshot_diff_helper.pyo:783 Second pass detecting creations and modification entries 2016-01-04 09:53:19,086 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_snapshot_diff_helper.pyo:810 Processed 702 entries in total. Number of events detected: (_FSChangeActionType(Action.MODIFY): 3, _FSChangeActionType(Action.CREATE): 81 2016-01-04 09:53:19,156 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_watcher.pyo:875 Done with full snapshot diff. 2016-01-04 09:53:19,156 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_watcher.pyo:934 CloudWatcher generated 11 events 2016-01-04 09:53:19,156 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_watcher.pyo:940 CloudWatcher generated FSChange(Direction.DOWNLOAD, Action.CREATE, name=Third Party Payment Form.pdf, route=[CloudEntry(doc_id=0BxNN8iEiWGvg0V2sZG9wUDRWT1E,filename=Third Party Payment Form.pdf,modified=1450455497,created=1450455501,acl_role=owner,doc_type=DocType.BLOB,removed= False,parent_doc_ids=set(['root']),child_doc_ids=set([]),size=8670,checksum=0ce073f22eaa6c84c 4110fOlab404749,change_stamp=76176,server_mod_time=Nonef is_zombie=False,shared=Falser recursiv e_size=None, resource_type=pdf,version=None)], rid=0BxNN8iEiWGvg0V2s2G9wUDRWT1E, parent_ino=529983, is_folder=False, unparents_shared=False, size=8670, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB) 2016-01-04 09:53:19,157 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_watcher.pyo:940 CloudWatcher generated FSChange(Direction.DOWNLOAD, Action.CREATE, name=image3.JPG, route= (CloudEntry(doc_id=OBxNN8iEiWGvgN2x2bmwzT1d6eXc,filename=image3.JPG,modified=1451168662,creat ed=1451168671,acl_role=owner,doc_type=DocType.BLOB,removed=Falser parent_doc_ids=setWroot'D ,child_doc_ids=set([11,size=164240,checksum=3ef6f4428a9a902cd2e5f784bb64bf0c,change_stamp=764 61,server_mod_time=None,is_zombie=False,shared=False,recursive_size=None,resource_type=file,v ersion=None)], rid=OBxNN8iEiWGvgN2x2bmwzTld6eXc, parent_ino=529983, is_folder=False, unparents_shared=False, size=164240, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB) 2016-01-04 09:53:19,157 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_watcher.pyo:940 CloudWatcher generated FSChange(Direction.DOWNLOAD, Action.CREATE, name=PassportAnnRodriquezl.JPG, route= (CloudEntry(doc_id=OBxNN8iEiWGvgcDd5SngzMGw4WWM,filename=PassportAnnRodriquezl.JPG,modified=1 451168725,created=1451168726,acl_role=owner,doc_type=DocType.BLOB,removed=False,parent_doc_id s=set([ 1 root']),child_doc_ids=set([11,size=164240,checksum=3ef6f4428a9a902cd2e5f784bb64bf0c,c hange_stamp=76466,server_mod_time=None,is_zombie=False,shared=False,recursive_size=None,resou rce_type=file,version=None)], rid=OBxNN8iEiWGvgcDd5SngzMGw4WWM, parent_ino=529983, is_folder=False, unparents_shared=False, size=164240, is_cancelled=False, ispriority=False, doc_type=DocType.BLOB) 2016-01-04 09:53:19,157 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_watcher.pyo:940 CloudWatcher generated FSChange(Direction.DOWNLOAD, Action.CREATE, name=PassportAnnRodriquez2.JPG, route= (CloudEntry(doc_id=06xNN8iEiWGvgWnM3VFd2T1YON3c,filename=PassportAnnRodriquez2.JPG,modified=1 451168746,created=1451168747,acl_role=owner,doc_type=DocType.BLOB,removed=False,parent_doc_id s=set([ 1 root']),child_doc_ids=set([1),size=158946,checksum=66f8ee9730f9f664dae0689165e76bdd,c hange_stamp=76469,server_mod_time=None,is_zombie=False,shared=False,recursive_size=None,resou rce_type=file,version=None)], rid=OBxNN8iEiWGvgWnM3VFd2T1Y0N3c, parent_ino=529983, is_folder=False, unparents_shared=False, size=158946, is_cancelled=False, ispriority=False, doc_type=DocType.BLOB) 2016-01-04 09:53:19,157 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_watcher.pyo:940 CloudWatcher generated FSChange(Direction.DOWNLOAD, Action.MODIFY, ino=15425400, name=2016 January.docx, rid=0BxNN8iEiWGvgU3pmX01CLUdjdUk, parent_ino=14390475, is_folder=False, unparents_shared=False, modified=1451864423, checksum=43b82585e3e489858710f66fc440d859, size=80510, is_cancelled=False, ispriority=False, doc_type=DocType.BLOB) 2016-01-04 09:53:19,157 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_watcher.pyo:940 CloudWatcher generated FSChange(Direction.DOWNLOAD, Action.CREATE, EFTA00502485 name=PassportAnnRodriquez3.JPG, route= (CloudEntry(doc_id=OBxNN8iEiWGvgSG1GW1N5RUtFRUO,filename=PassportAnnRodriquez3.JPG,modified=1 451168761,created=1451168761,acl_role=owner,doc_type=DocType.BLOB,removed=False,parent_doc_id s=set(['root']),child_doc_ids=set([]),size=164240,checksum=3ef6f4428a9a902cd2e5f784bb64bf0c,c hange_stamp=76472,server_mod_time=None,is_zombie=False,shared=False,recursive_size=None,resou rce_type=file,version=None)], rid=OBxNN8iEiWGvgSG1GW1N5RUtFRUO, parent_ino=529983, is_folder=False, unparents_shared=False, size=164240, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB) 2016-01-04 09:53:19,158 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_watcher.pyo:940 CloudWatcher generated FSChange(Direction.DOWNLOAD, Action.CREATE, name=imagel.JPG, route= (CloudEntry(doc_id=OBxNN8iEiWGvgbVB6b3BPWURTYkU,filename=imagel.JPG,modified=1451168662,creat ed=1451168671,acl_role=owner,doc_type=DocType.BLOB,removed=False,parent_doc_ids=set(['root']) ,child_doc_ids=set([]),size=158946,checksum=66f8ee9730f9f664dae0689165e76bdd,change_stamp=764 62,server_mod_time=None,is_zombie=False,shared=False,recursive_size=None,resource_type=file,v ersion=None)], rid=OBxNN8iEiWGvgbVB6b3BPWURTYkU, parent_ino=529983, is_folder=False, unparents_shared=False, size=158946, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB) 2016-01-04 09:53:19,158 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_watcher.pyo:940 CloudWatcher generated FSChange(Direction.DOWNLOAD, Action.MODIFY, ino=15425403, name=2015 December.docx, rid=OBxNN8iEiWGvgRF1wTG9EcDJ12Fk, parent_ino=14390475, is_folder=False, unparents_shared=False, modified=1451425817, checksum=d3b4256771c0a0698294d47a4 fd06542, size=90222, is_cancelled=False, ispriority=False, doc_type=DocType.BL0B) 2016-01-04 09:53:19,158 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_watcher.pyo:940 CloudWatcher generated FSChange(Direction.DOWNLOAD, Action.MODIFY, ino=14455808, name=Christmas Address List.docx, rid=0BxNN8iEiWGvgNE90NVB6cFhTeG8, parent_ino=14390475, is_folder=False, unparents_shared=False, modified=1450901913, checksum=fc4f77a002871e8e51081ae36f05aa2a, size=64651, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB) 2016-01-04 09:53:19,158 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_watcher.pyo:940 CloudWatcher generated FSChange(Direction.DOWNLOAD, Action.CREATE, name=2015JerryElfLetter.docx, route= [CloudEntry(doc_id=OBxNN8iEiWGvgdVExUWFUZDJKbDA,filename=2015JerryElfLetter.docx,modified=145 0982714,created=1450982719,acl_role=owner,doc_type=DocType.BLOB,removed=False,parent_doc_ids= set(['0BxNN8iEiWGvgRjcxNnBhRm5nV1IO1),child_doc_ids=set([]).size=92538,checksum=2ffcc8ffa236d f81469bbe979f6d87d5,change_stamp=76431,server_mod_time=Noneris_zombie=False,shared=False,recu rsive_size=None,resource_type=filer version=Nonej], rid=0BxNN8iEiWGvgdVExUWFUZDJKbDA, parent_ino=14390475, is_folder=False, unparents_shared=False, size=92538, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB) 2016-01-04 09:53:19,158 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_watcher.pyo:940 CloudWatcher generated FSChange(Direction.DOWNLOAD, Action.CREATE, name=image2.JPG, route= (CloudEntry(doc_id=OBxNN8iEiWGvgcj2KX3BxTmxpaGM,filename=image2.JPG,modified=1451168662,creat ed=1451168668,acl_role=owner,doc_type=DocType.BLOB,removed=False,parent_doc_ids=set(['root']) ,child_doc_ids=set([]),size=158809,checksum=d9724b214e14b9237bd88e3397a45a9a,change_stamp=764 57,server_mod_time=None,is_zombie=False,shared=False,recursive_size=None,resource_type=file,v ersion=None)], rid=OBxNN8iEiWGvgcj2KX3BxTmxpaGM, parent_ino=529983, is_folder=False, unparents_shared=False, size=158809, is_cancelled=False, ispriority=False, doc_type=DocType.BLOB) 2016-01-04 09:53:19,158 -0500 INFO pid=14964 4594950144:CloudWatcher aggregator.pyo:108 -- > Received event None FSChange(Direction.DOWNLOAD, Action.CREATE, name=PassportAnnRodriquez3.JPG, route= (CloudEntry(doc_id=011xNN8iEiWGvgSG1GW1N5RUtFRU0,filename=PassportAnnRodriquez3.JPG,modified=1 451168761,created=1451168761,acl_role=owner,doc_type=DocType.BLOB,removed=False,parent_doc_id s=set([ 1 root']),child_doc_ids=set([]),size=164240,checksum=3ef6f4428a9a902cd2e5f784bb64bf0c,c hange_stamp=76472,server_mod_time=None,is_zombie=False,shared=False,recursive_size=None,resou rce_type=file,version=None)], rid=OBxNN8iEiWGvgSG1GW1N5RUtFRUO, parent_ino=529983, is_folder=False, unparents_shared=False, size=164240, is_cancelled=False, ispriority=False, doc_type=DocType.BLOB) 2016-01-04 09:53:19,159 -0500 INFO pid=14964 4594950144:CloudWatcher aggregator.pyo:108 -- > Received event None FSChange(Direction.DOWNLOAD, Action.CREATE, name=PassportAnnRodriquez2. JPG, route= (CloudEntry(doc_id=011xNN8iEiWGvgWnM3VFd2T1Y0N3c,filename=PassportAnnRodriquez2.JPG,modified=1 451168746,created=1451168747,acl_role=owner,doc_type=DocType.BLOB,removed=False,parent_doc_id s=set([ 1 root']),child_doc_ids=set([]),size=158946,checksum=66f8ee9730f9f664dae0689165e76bdd,c hange_stamp=76469,server_mod_time=None,is_zombie=False,shared=False,recursive_size=None,resou rce_type=file,version=None)], rid=OBxNN8iEiWGvgWnM3VFd2T1Y0N3c, parent_ino=529983, EFTA00502486 is_folder=False, unparents_shared=False, size=158946, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB) 2016-01-04 09:53:19,159 -0500 INFO pid=14964 4594950144:CloudWatcher aggregator.pyo:108 -- > Received event None FSChange(Direction.DOWNLOAD, Action.CREATE, name=PassportAnnRodriquezl.JPG, route= (CloudEntry(doc_id=011xNN8iEiWGvgcDd5SngzMGw4WWM,filename=PassportAnnRodriquezl.JPG,modified=1 451168725,created=1451168726,acl_role=owner,doc_type=DocType.BLOB,removed=False,parent_doc_id s=set([ 1 root 1]),child_doc_ids=set([]),size=164240,checksum=3ef6f4428a9a902cd2e5f784bb64bfOc,c hange_stamp=76466,server_mod_time=None,is_zombie=False,shared=False,recursive_size=None,resou rce_type=file,version=None)], rid=OBxNN8iEiWGvgcDd5SngzMGw4WWM, parent_ino=529983, is_folder=False, unparents_shared=False, size=164240, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB) 2016-01-04 09:53:19,160 -0500 INFO pid=14964 4594950144:CloudWatcher aggregator.pyo:108 -- > Received event None FSChange(Direction.DOWNLOAD, Action.CREATE, name=image3.JPG, route= (CloudEntry(doc_id=0BxNN8iEiWGvgN2x2bmwzT1d6eXc,filename=image3.JPG,modified=1451168662,creat ed=1451168671,acl_role=owner,doc_type=DocType.BLOB,removed=False,parent_doc_ids=setWrootrn ,child_doc_ids=set([)),size=164240,checksum=3ef6f4428a9a902cd2e5f784bb64bfOc,change_stamp=764 61,server_mod_time=None,is_zombie=False,shared=False,recursive_size=None,resource_type=file,v ersion=None)], rid=0BxNN8iEiWGvgN2x2bmwzTld6eXc, parent_ino=529983, is_folder=False, unparents_shared=False, size=164240, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB) 2016-01-04 09:53:19,160 -0500 INFO pid=14964 4594950144:CloudWatcher aggregator.pyo:108 -- > Received event None FSChange(Direction.DOWNLOAD, Action.CREATE, name=imagel.JPG, route= (CloudEntry(doc_id=0BxNN8iEiWGvgbVB6b3BPWURTYkU,filename=imagel.JPG,modified=1451168662,creat ed=1451168671,acl_role=owner,doc_type=DocType.BLOB,removed=False,parent_doc_ids=set(['root 1]) ,child_doc_ids=set([]),size=158946,checksum=66f8ee9730f9f664dae0689165e76bdd,change_stamp=764 62,server_mod_time=None,is_zombie=False,shared=False,recursive_size=None,resource_type=file,v ersion=None)], rid=OBxNN8iEiWGvgbVB6b3BPWURTYkU, parent_ino=529983, is_folder=False, unparents_shared=False, size=158946, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB) 2016-01-04 09:53:19,160 -0500 INFO pid=14964 4594950144:CloudWatcher aggregator.pyo:108 -- > Received event None FSChange(Direction.DOWNLOAD, Action.CREATE, name=image2.JPG, route= (CloudEntry(doc_id=011xNN8iEiWGvgcj2KX3BxTmxpaGM,filename=image2.JPG,modified=1451168662,creat ed=1451168668,acl_role=owner,doc_type=DocType.BLOB,removed=False r parent_doc_ids=setWroot'D ,child_doc_ids=set([]),size=158809,checksum=d9724b214e14b9237bd88e3397a45a9a,change_stamp=764 57,server_mod_time=None,is_zombie=False,shared=False,recursive_size=None,resource_type=file,v ersion=None)], rid=011xNN8iEiWGvgcj2KX3BxTmxpaGM, parent_ino=529983, is_folder=False, unparents_shared=False, size=158809, is_cancelled=False, ispriority=False, doc_type=DocType.BLOB) 2016-01-04 09:53:19,160 -0500 INFO pid=14964 4594950144:CloudWatcher aggregator.pyo:108 -- > Received event None FSChange(Direction.DOWNLOAD, Action.CREATE, name=2015JerryElfLetter.docx, route= (CloudEntry(doc_id=08xNN8iEiWGvgdVExUWFU2DJKbDA,filename=2015JerryElfLetter.docx,modified=145 0982714,created=1450982719,acl_role=owner,doc_type=DocType.BLOB,removed=False,parent_doc_ids= set([ 10BxNN8iEiWGvgRjcxNnBhRm5nV1k.]),child_doc_ids=set([]).size=92538,checksum=2ffcc8ffa236d f81469bbe979f6d87d5,change_stamp=76431,server_mod_time=None,is_zombie=False,shared=False,recu rsive_size=None,resource_type=filer version=None)], rid=0BxNN8iEiWGvgdVExUWFUZDJKI3DA, parent_ino=14390475, is_folder=False, unparents_shared=False, size=92538, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB) 2016-01-04 09:53:19,161 -0500 INFO pid=14964 4594950144:CloudWatcher aggregator.pyo:108 -- > Received event None FSChange(Direction.DOWNLOAD, Action.CREATE, name=Third Party Payment Form.pdf, route=[CloudEntry(doc_id=0BxNN8iEiWGvg0VZs2G9wUDRWT1E,filename=Third Party Payment Form.pdf,modified=1450455497,created=1450455501,acl_role=owner,doc_type=DocType.BLOB,removed= False,parent_doc_ids=set((iroot']),child_doc_ids=set([]),size=8670,checksum=Oce073f22eaa6c84c 4110fOlab404749,change_stamp=76176,server_mod_time=Nonef is_zombie=False,shared=Falser recursiv e_size=None, resource_type=pdf,version=None)], rid=0BxNN8iEiWGvg0V2s2G9wUDRWT1E, parent_ino=529983, is_folder=False, unparents_shared=False, size=8670, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB) 2016-01-04 09:53:19,161 -0500 INFO pid=14964 4594950144:CloudWatcher aggregator.pyo:108 -- > Received event None FSChange(Direction.DOWNLOAD, Action.MODIFY, ino=15425400, name=2016 January.docx, rid=0BxNN8iEiWGvgU3pmX01CLVdjdVk, parent_ino=14390475, EFTA00502487 is_folder=False, unparents_shared=False, modified=1451864423, checksum=43b82585e3e489858710f66fc440d859, size=80510, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB) 2016-01-04 09:53:19,161 -0500 INFO pid=14964 4594950144:CloudWatcher downloads.pyo:126 ProcessDownloadModify (FSChange(Direction.DOWNLOAD, Action.MODIFY, ino=15425400, name=2016 January.docx, rid=0BxNN8iEiWGvgU3pmX01CLVdjdVk, parent_ino=14390475, is_folder=False, unparents_shared=False, modified=1451864423, checksum=43b82585e3e489858710f66fc440d859, size=80510, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB)) 2016-01-04 09:53:19,162 -0500 INFO pid=14964 4594950144:CloudWatcher aggregator.pyo:108 > Received event None FSChange(Direction.DOWNLOAD, Action.MODIFY, ino=15425403, name=2015 December.docx, rid=0115(NN8iEiWGvgRF1wTG9EcDJ12Fk, parent_ino=14390475, is_folder=False, unparents_shared=False, modified=1451425817, checksum=d3b4256771c0a0698294d47a4fd06542, size=90222, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB) 2016-01-04 09:53:19,162 -0500 INFO pid=14964 4594950144:CloudWatcher downloads.pyo:126 ProcessDownloadModify (FSChange(Direction.DOWNLOAD, Action.MODIFY, ino=15425403, name=2015 December.docx, rid=011xNN8iEiWGvgRF1wTG9EcDJ12Fk, parent_ino=14390475, is_folder=False, unparents_shared=False, modified=1451425817, checksum=d3b4256771c0a0698294d47a4 fd06542, size=90222, is_cancelled=False, ispriority=False, doc_type=DocType.BLOB)) 2016-01-04 09:53:19,162 -0500 INFO pid=14964 4594950144:CloudWatcher aggregator.pyo:108 > Received event None FSChange(Direction.DOWNLOAD, Action.MODIFY, ino=14455808, name=Christmas Address List.docx, rid=0BxNN8iEiWGvgNE9ONVB6cFhTeG8, parent_ino=14390475, is_folder=False, unparents_shared=False, modified=1450901913, checksum=fc4f77a002871e8e51081ae36f05aa2a, size=64651, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB) 2016-01-04 09:53:19,162 -0500 INFO pid=14964 4594950144:CloudWatcher downloads.pyo:126 ProcessDownloadModify (FSChange(Direction.DOWNLOAD, Action.MODIFY, ino=14455808, name=Christmas Address List.docx, rid=0BxNN8iEiWGvgNE9ONVB6cFhTeG8, parent_ino=14390475, is_folder=False, unparents_shared=False, modified=1450901913, checksum=fc4f77a002871e8e51081ae36f05aa2a, size=64651, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB)) 2016-01-04 09:53:19,295 -0500 INFO pid=14964 4649635840:Worker-1 workers.pyo:183 Worker starting on [ImmutableChange(Direction.DOWNLOAD, Action.CREATE, name=PassportAnnRodriquez3.JPG, route= (CloudEntry(doc_id=011xNN8iEiWGvgSG1GW1N5RUtFRUO,filename=PassportAnnRodriquez3.JPG,modified=1 451168761,created=1451168761,acl_role=owner,doc_type=DocType.BLOB,removed=False,parent_doc_id s=set([ 1 rootrn,child_doc_ids=set(I1),size=164240,checksum=3ef6f4428a9a902cd2e5f784bb64bf0c,c hange_stamp=76472,server_mod_time=None,is_zombie=False,shared=False,recursive_size=None,resou rce_type=file,version=None)], rid=0BxNN8iEiWGvgSG1GW1N5RUtFRUO, parent_ino=529983, is_folder=False, unparents_shared=False, size=164240, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB, hash=-6455883231874191130, _constructor_called=True)] 2016-01-04 09:53:19,297 -0500 INFO pid=14964 4607569920:Worker-2 workers.pyo:183 Worker starting on [ImmutableChange(Direction.DOWNLOAD, Action.CREATE, name=PassportAnnRodriquez2.JPG, route= (CloudEntry(doc_id=011xNN8iEiWGvgWnM3VFd2T1YON3c,filename=PassportAnnRodriquez2.JPG,modified=1 451168746,created=1451168747,acl_role=owner,doc_type=DocType.BLOB,removed=False,parent_doc_id s=set([ 1 root.]),child_doc_ids=set([]),size=158946,checksum=66f8ee9730f9f664dae0689165e76bdd,c hange_stamp=76469,server_mod_time=None,is_zombie=False,shared=False,recursive_size=None,resou rce_type=file,version=None)], rid=0BxNN8iEiWGvgWnM3VFd2T1YON3c, parent_ino=529983, is_folder=False, unparents_shared=False, size=158946, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB, hash=-5587393419791269130, _constructor_called=True)] 2016-01-04 09:53:19,299 -0500 INFO pid=14964 4582330368:Worker-0 workers.pyo:183 Worker starting on [ImmutableChange(Direction.DOWNLOAD, Action.CREATE, name=PassportAnnRodriquezl.JPG, route= [CloudEntry(doc_id=011xNN8iEiWGvgcDd5SngzMGw4WWM,filename=PassportAnnRodriquezl.JPG,modified=1 451168725,created=1451168726,acl_role=owner,doc_type=DocType.BLOB,removed=False,parent_doc_id s=set([ 1 root 1]),child_doc_ids=set([]),size=164240,checksum=3ef6f4428a9a902cd2e5f784bb64bf0c,c hange_stamp=76466,server_mod_time=None,is_zombie=False,shared=False,recursive_size=None,resou rce_type=file,version=None)], rid=OBxNN8iEiWGvgcDd5SngzMGw4WWM, parent_ino=529983, is_folder=False, unparents_shared=False, size=164240, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB, hash=-6587347613674440695, _constructor_called=True)] 2016-01-04 09:53:19,337 -0500 INFO pid=14964 4582330368:Worker-0 download.pyo:139 Downloading https://googledrive.com/p/host/OBxNN8iEiWGvgcDd5SngzMGw4WWM to /var/folders/n1/ypw25pmj06qfyyl_8xnt3kf4000Ogn/T/tmpgMg6b1 2016-01-04 09:53:19,337 -0500 INFO pid=14964 4649635840:Worker-1 download.pyo:139 EFTA00502488 Downloading https://googledrive.com/p/host/OWENN8iEiWGvgSG1GW1N5RUtFRUO to /var/folders/nl/ypw25pmj06gfyyl_8xnt3kf40000gn/T/tmpM2MNVp 2016-01-04 09:53:19,338 -0500 INFO pid=14964 4607569920:Worker-2 download.pyo:139 Downloading https://googledrive.com/p/host/0BxNN8iEiWGvgWnM3VFd2T1YON3c to /var/folders/n1/ypw25pmj06qfyyl_8xnt3kf40000gn/T/tmpNxYJKF 2016-01-04 09:53:19,340 -0500 INFO pid=14964 4649635840:Worker-1 sync_http_client.pyo:236 Opening direct connecton to googledrive.com:443. 2016-01-04 09:53:19,340 -0500 INFO pid=14964 4582330368:Worker-0 sync_http_client.pyo:236 Opening direct connecton to googledrive.com:443. 2016-01-04 09:53:19,340 -0500 INFO pid=14964 4607569920:Worker-2 sync_http_client.pyo:236 Opening direct connecton to googledrive.com:443. 2016-01-04 09:53:19,756 -0500 INFO pid=14964 4590743552:LocalFSPoller poller.pyo:126 Cloud scan complete. Preparing to start LocalFSPoller 2016-01-04 09:53:19,851 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_sqlite.pyo:143 Adding local entry inode=15554200, filename=None 2016-01-04 09:53:19,851 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_sqlite.pyo: 171 Adding cloud entry resource_id=file:OBxNN8iEiWGvgSG1GW1N5RUtFRUO, filename=None 2016-01-04 09:53:19,852 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_sqlite.pyo:253 Updating cloud entry doc_id=0124(NN8iEiWGvgSG1GW1N5RUtFRUO, filename=PassportAnnRodriquez3.JPG 2016-01-04 09:53:19,852 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_sqlite.pyo:230 Updating local entry inode=15554200, filename=PassportAnnRodriquez3.JPG, modified=1451168761, checksum=3ef6f4428a9a902cd2e5f784bb64bf0c, size=164240, is_folder=False 2016-01-04 09:53:19,852 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_sqlite.pyo:542 Adding local relation child_inode=15554200, parent_inode=529983 2016-01-04 09:53:19,853 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_sqlite.pyo:519 Adding cloud relation child_doc_id=OBxNN8iEiWGvgSG1GW1N5RUtFRUO, parent_doc_id=root 2016-01-04 09:53:19,853 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_sqlite.pyo:562 Adding Mapping inode=15554200, doc_id=011xNN8iEiWGvgSG1GW1N5RUtFRUO 2016-01-04 09:53:19,854 -0500 INFO pid=14964 4649635840:Worker-1 workers.pyo:194 Worker successfully completed (ImmutableChange(Direction.DOWNLOAD, Action.CREATE, name=PassportAnnRodriquez3.JPG, route= [CloudEntry(doc_id=06xNN8iEiWGvgSG1GW1N5RUtFRUO,filename=PassportAnnRodriquez3.JPG,modified=1 451168761,created=1451168761,acl_role=owner,doc_type=DocType.BLOB,removed=False,parent_doc_id s=set([ 1 root 1]),child_doc_ids=set([]),size=164240,checksum=3ef6f4428a9a902cd2e5f784bb64bf0c,c hange_stamp=76472,server_mod_time=None,is_zombie=False,shared=False,recursive_size=None,resou rce_type=file,version=None)], rid=OBxNN8iEiWGvgSG1GW1N5RUtFRUO, parent_ino=529983, is_folder=False, unparents_shared=False, size=164240, is_cancelled=False, isyriority=False, doc_type=DocType.BLOB, hash=-6455883231874191130, _constructor_called=True)] 2016-01-04 09:53:19,856 -0500 INFO pid=14964 4649635840:Worker-1 workers.pyo:183 Worker starting on [ImmutableChange(Direction.DOWNLOAD, Action.CREATE, name=image3.JPG, route= (CloudEntry(doc_id=OBxNN8iEiWGvgN2x2bmwzTld6eXc,filename=image3.JPG,modified=1451168662,creat ed=1451168671,acl_role=owner,doc_type=DocType.BLOB,removed=False,parent_doc_ids=set(['root']) ,child_doc_ids=set([]),size=164240,checksum=3ef6f4428a9a902cd2e5f784bb64bf0c,change_stamp=764 61,server_mod_time=None,is_zombie=False,shared=False,recursive_size=None,resource_type=file,v ersion=None)], rid=OBxNN8iEiWGvgN2x2bmwzTld6eXc, parent_ino=529983, is_folder=False, unparents_shared=False, size=164240, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB, hash=-2204559016602182442, _constructor_called=True)] 2016-01-04 09:53:19,858 -0500 INFO pid=14964 4649635840:Worker-1 download.pyo:139 Downloading https://googledrive.com/p/host/0BxNN8iEiWGvgN2x2bmwzT1d6eXc to /var/folders/nl/ypw25pmj06gfyyl_8xnt3kf40000gn/T/tmpLDvX3A 2016-01-04 09:53:19,859 -0500 INFO pid=14964 4649635840:Worker-1 sync_http_client.pyo:236 Opening direct connecton to googledrive.com:443. 2016-01-04 09:53:19,861 -0500 INFO pid=14964 4582330368:Worker-0 snapshot_sqlite.pyo:143 Adding local entry inode=15554201, filename=None 2016-01-04 09:53:19,861 -0500 INFO pid=14964 4582330368:Worker-0 snapshot_sqlite.pyo: 171 Adding cloud entry resource_id=file:OBxNN8iEiWGvgcDd5SngzMGw4WWM, filename=None 2016-01-04 09:53:19,861 -0500 INFO pid=14964 4582330368:Worker-0 snapshot_sqlite.pyo:253 Updating cloud entry doc_id=0BxNN8iEiWGvgcDd5SngzMGw4WWM, filename=PassportAnnRodriquezl.JPG EFTA00502489 2016-01-04 09:53:19,861 -0500 INFO pid=14964 4582330368:Worker-0 snapshot_sqlite.pyo:230 Updating local entry inode=15554201, filename=PassportAnnRodriquezl.JPG, modified=1451168725, checksum=3ef6f4428a9a902cd2e5f784bb64bf0c, size=164240, is_folder=False 2016-01-04 09:53:19,862 -0500 INFO pid=14964 4582330368:Worker-0 snapshot_sqlite.pyo: 542 Adding local relation child_inode=15554201, parent_inode=529983 2016-01-04 09:53:19,862 -0500 INFO pid=14964 4582330368:Worker-0 snapshot_sqlite.pyo:519 Adding cloud relation child_doc_id=OBxNN8iEiWGvgcDd5SngzMGw4WWM, parent_doc_id=root 2016-01-04 09:53:19,862 -0500 INFO pid=14964 4582330368:Worker-0 snapshot_sqlite.pyo:562 Adding Mapping inode=15554201, doc_id=0BxNN8iEiWGvgcDd5SngzMGw4WWM 2016-01-04 09:53:19,863 -0500 INFO pid=14964 4582330368:Worker-0 workers.pyo:194 Worker successfully completed (ImmutableChange(Direction.DOWNLOAD, Action.CREATE, name=PassportAnnRodriquezl.JPG, route= (CloudEntry(doc_id=OBxNN8iEiWGvgcDd5SngzMGw4WWM,filename=PassportAnnRodriquezl.JPG,modified=1 451168725,created=1451168726,acl_role=owner,doc_type=DocType.BLOB,removed=False,parent_doc_id s=set(('root']),child_doc_ids=set([]),size=164240,checksum=3ef6f4428a9a902cd2e5f784bb64bf0c,c hange_stamp=76466,server_mod_time=None,is_zombie=False,shared=False,recursive_size=None,resou rce_type=file,version=None)], rid=OBxNN8iEiWGvgcDd5SngzMGw4WWM, parent_ino=529983, is_folder=False, unparents_shared=False, size=164240, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB, hash=-6587347613674440695, _constructor_called=True)] 2016-01-04 09:53:19,864 -0500 INFO pid=14964 4582330368:Worker-0 workers.pyo:183 Worker starting on [ImmutableChange(Direction.DOWNLOAD, Action.CREATE, name=imagel.JPG, route= (CloudEntry(doc_id=OBxNN8iEiWGvgbVB6b3BPWURTYkU,filename=imagel.JPG,modified=1451168662,creat ed=1451168671,acl_role=owner,doc_type=DocType.BLOB,removed=False,parent_doc_ids=set(['root']) ,child_doc_ids=set([]),size=158946,checksum=66f8ee9730f9f664dae0689165e76bdd,change_stamp=764 62,server_mod_time=None,is_zombie=False,shared=False,recursive_size=None,resource_type=file,v ersion=None)], rid=OBxNN8iEiWGvgbVB6b3BPWURTYkU, parent_ino=529983, is_folder=False, unparents_shared=False, size=158946, is_cancelled=False, ispriority=False, doc_type=DocType.BLOB, hash=1206714204386382479, _constructor_called=True)] 2016-01-04 09:53:19,865 -0500 INFO pid=14964 4582330368:Worker-0 download.pyo:139 Downloading https://googledrive.com/p/host/0WENN8iEiWGvgb‘Na6b3BPWURTYkU to /var/folders/nl/ypw25pmj06gfyyl_8xnt3kf40000gn/T/tmpqXvpwm 2016-01-04 09:53:19,866 -0500 INFO pid=14964 4582330368:Worker-0 sync_http_client.pyo:236 Opening direct connecton to googledrive.com:443. 2016-01-04 09:53:19,879 -0500 INFO pid=14964 4607569920:Worker-2 snapshot_sqlite.pyo:143 Adding local entry inode=15554202, filename=None 2016-01-04 09:53:19,880 -0500 INFO pid=14964 4607569920:Worker-2 snapshot_sqlite.pyo: 171 Adding cloud entry resource_id=file:0BxNN8iEiWGvgWnM3VFd2T1Y0N3c, filename=None 2016-01-04 09:53:19,880 -0500 INFO pid=14964 4607569920:Worker-2 snapshot_sqlite.pyo:253 Updating cloud entry doc_id=0RxNN8iEiWGvgWnM3VFd2T1Y0N3c, filename=PassportAnnRodriquez2.JPG 2016-01-04 09:53:19,880 -0500 INFO pid=14964 4607569920:Worker-2 snapshot_sqlite.pyo:230 Updating local entry inode=15554202, filename=PassportAnnRodriquez2.JPG, modified=1451168746, checksum=66f8ee9730f9f664dae0689165e76bdd, size=158946, is_folder=False 2016-01-04 09:53:19,880 -0500 INFO pid=14964 4607569920:Worker-2 snapshot_sqlite.pyo:542 Adding local relation child_inode=15554202, parent_inode=529983 2016-01-04 09:53:19,880 -0500 INFO pid=14964 4607569920:Worker-2 snapshot_sqlite.pyo:519 Adding cloud relation child_doc_id=OBxNN8iEiWGvgWnM3VFd2T1Y0N3c, parent_doc_id=root 2016-01-04 09:53:19,881 -0500 INFO pid=14964 4607569920:Worker-2 snapshot_sqlite.pyo:562 Adding Mapping inode=15554202, doc_id=0BxNN8iEiWGvgWnM3VFd2T1Y0N3c 2016-01-04 09:53:19,881 -0500 INFO pid=14964 4607569920:Worker-2 workers.pyo:194 Worker successfully completed (ImmutableChange(Direction.DOWNLOAD, Action.CREATE, name=PassportAnnRodriquez2.JPG, route= (CloudEntry(doc_id=011xNN8iEiWGvgWnM3VFd2T1Y0N3c,filename=PassportAnnRodriquez2.JPG,modified=1 451168746,created=1451168747,acl_role=owner,doc_type=DocType.BLOB,removed=False,parent_doc_id s=set([ 1 rootrn,child_doc_ids=set([]),size=158946,checksum=66f8ee9730f9f664dae0689165e76bdd,c hange_stamp=76469,server_mod_time=None,is_zombie=False,shared=False,recursive_size=None,resou rce_type=file,version=None)], rid=OBxNN8iEiWGvgWnM3VFd2T1Y0N3c, parent_ino=529983, is_folder=False, unparents_shared=False, size=158946, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB, hash=-5587393419791269130, _constructor_called=True)] EFTA00502490 2016-01-04 09:53:19,883 -0500 INFO pid=14964 4607569920:Worker-2 workers.pyo:183 Worker starting on [ImmutableChange(Direction.DOWNLOAD, Action.CREATE, name=image2.JPG, route= (CloudEntry(doc_id=0BxNN8iEiWGvgcj2KX3BxTmxpaGM,filename=image2.JPG,modified=1451168662,creat ed=1451168668,acl_role=owner,doc_type=DocType.BLOB,removed=False,parent_doc_ids=set(['root']) ,child_doc_ids=set([]),size=158809,checksum=d9724b214e14b9237bd88e3397a45a9a,change_stamp=764 57,server_mod_time=None,is_zombie=False,shared=False,recursive_size=None,resource_type=file,v ersion=None)], rid=0BxNN8iEiWGvgcj2KX3BxTmxpaGM, parent_ino=529983, is_folder=False, unparents_shared=False, size=158809, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB, hash=-5437124921948004300, _constructor_called=True)] 2016-01-04 09:53:19,884 -0500 INFO pid=14964 4607569920:Worker-2 download.pyo:139 Downloading https://googledrive.com/p/host/0WENN8iEiWGvgcj2KX3BxTmxpaGM to /var/folders/n1/ypw25pmj06qfyyl_8xnt3kf40000gn/T/tmpntC19 2016-01-04 09:53:19,885 -0500 INFO pid=14964 4607569920:Worker-2 sync_http_client.pyo:236 Opening direct connecton to googledrive.com:443. 2016-01-04 09:53:20,179 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_sqlite.pyo:143 Adding local entry inode=15554203, filename=None 2016-01-04 09:53:20,179 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_sqlite.pyo: 171 Adding cloud entry resource_id=file:0B5ENN8iEiWGvgN2x2bmwzT1d6eXc, filename=None 2016-01-04 09:53:20,180 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_sqlite.pyo:253 Updating cloud entry doc_id=0BxNN8iEiWGvgN2x2bmwzT1d6eXc, filename=image3.JPG 2016-01-04 09:53:20, snapshot_sqlite.pyo: modified=1451168662, 2016-01-04 09:53:20, snapshot_sqlite.pyo: 2016-01-04 09:53:20,180 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_sqlite.pyo:519 Adding cloud relation child_doc_id=0BxNN8iEiWGvgN2x2bmwzTld6eXc, parent_doc_id=root 2016-01-04 09:53:20,180 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_sqlite.pyo:562 Adding Mapping inode=15554203, doc_id=0BxNN8iEiWGvgN2x2bmwzT1d6eXc 2016-01-04 09:53:20,181 -0500 INFO pid=14964 4649635840:Worker-1 workers.pyo:194 Worker successfully completed (ImmutableChange(Direction.DOWNLOAD, Action.CREATE, name=image3.JPG, route= (CloudEntry(doc_id=0BxNN8iEiWGvgN2x2bmwzT1d6eXc,filename=image3.JPG,modified=1451168662,creat ed=1451168671,acl_role=owner,doc_type=DocType.BLOB,removed=Falser parent_doc_ids=set(['rootrn ,child_doc_ids=set([]),size=164240,checksum=3ef6f4428a9a902cd2e5f784bb64bf0c,change_stamp=764 61,server_mod_time=None,is_zombie=False,shared=False,recursive_size=None,resource_type=file,v ersion=None)], rid=0BxNN8iEiWGvgN2x2bmwzTld6eXc, parent_ino=529983, is_folder=False, unparents_shared=False, size=164240, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB, hash=-2204559016602182442, _constructor_called=True)] 2016-01-04 09:53:20,184 -0500 INFO pid=14964 4649635840:Worker-1 workers.pyo:183 Worker starting on [ImmutableChange(Direction.DOWNLOAD, Action.CREATE, name=Third Party Payment Form.pdf, route=[CloudEntry(doc_id=0BxNN8iEiWGvgOV2s249wUDRWT1E,filename=Third Party Payment Form.pdf,modified=1450455497,created=1450455501,acl_role=owner,doc_type=DocType.BLOB,removed= False,parent_doc_ids=set(['root']),child_doc_ids=set([]),size=8670,checksum=0ce073f22eaa6c84c 4110f0lab404749,change_stamp=76176,server_mod_time=Nonef is_zombie=False,shared=Falser recursiv e_size=None, resource_type=pdf,version=None)], rid=0BxNN8iEiWGvgOVZsZG9wUDRWT1E, parent_ino=529983, is_folder=False, unparents_shared=False, size=8670, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB, hash=-4131613288298216687, _constructor_called=True)] 2016-01-04 09:53:20,185 -0500 INFO pid=14964 4649635840:Worker-1 download.pyo:139 Downloading https://googledrive.com/p/host/0laxNN8iEiWGvgOV2s249wUDRWT1E to /var/folders/n1/ypw25pmj06qfyyl_8xnt3kf40000gn/T/tmpg8DaNR 2016-01-04 09:53:20,186 -0500 INFO pid=14964 4649635840:Worker-1 sync_http_client.pyo:236 Opening direct connecton to googledrive.com:443. 2016-01-04 09:53:20,200 -0500 INFO pid=14964 4582330368:Worker-0 snapshot_sqlite.pyo:143 Adding local entry inode=15554204, filename=None 2016-01-04 09:53:20,200 -0500 INFO pid=14964 4582330368:Worker-0 snapshot_sqlite.pyo: 171 Adding cloud entry resource_id=file:0B5ENN8iEiWGvgbVB6b3BPWURTYkU, filename=None 2016-01-04 09:53:20,200 -0500 INFO pid=14964 4582330368:Worker-0 180 -0500 INFO pid=14964 4649635840:Worker-1 230 Updating local entry inode=15554203, filename=image3.JPG, checksum=3ef6f4428a9a902cd2e5f784bb64bf0c, size=164240, is_folder=False 180 -0500 INFO pid=14964 4649635840:Worker-1 542 Adding local relation child_inode=15554203, parent_inode=529983 EFTA00502491 snapshot_scilite.pyo:253 Updating cloud entry doc_id=0124cNN8iEiWGvgbVE6b3BPWURTYkU, filename=imagel.JPG 2016-01-04 09:53:20,201 -0500 INFO pid=14964 4582330368:Worker-0 snapshot_scilite.pyo:230 Updating local entry inode=15554204, filename=imagel.JPG, modified=1451168662, checksum=66f8ee9730f9f664dae0689165e76bdd, size=158946, is_folder=False 2016-01-04 09:53:20,201 -0500 INFO pid=14964 4582330368:Worker-0 snapshot_scilite.pyo:542 Adding local relation child_inode=15554204, parent_inode=529983 2016-01-04 09:53:20,201 -0500 INFO pid=14964 4582330368:Worker-0 snapshot_scilite.pyo:519 Adding cloud relation child_doc_id=OBxNN8iEiWGvgbVB6b3BPWURTYkU, parent_doc_id=root 2016-01-04 09:53:20,201 -0500 INFO pid=14964 4582330368:Worker-0 snapshot_scilite.pyo:562 Adding Mapping inode=15554204, doc_id=0BxNN8iEiWGvgbVB6b3BPWURTYkU 2016-01-04 09:53:20,202 -0500 INFO pid=14964 4582330368:Worker-0 workers.pyo:194 Worker successfully completed (ImmutableChange(Direction.DOWNLOAD, Action.CREATE, name=imagel.JPG, route= (CloudEntry(doc_id=0ftNN8iEiWGvgbVB6b3BPWURTYkU,filename=imagel.JPG,modified=1451168662,creat ed=1451168671,acl_role=owner,doc_type=DocType.BLOB,removed=False,parent_doc_ids=set([ 1 root']) ,child_doc_ids=set([]),size=158946,checksum=66f8ee9730f9f664dae0689165e76bdd,change_stamp=764 62,server_mod_time=None,is_zombie=False r shared=False,recursive_size=None,resource_type=fileor ersion=None)], rid=OBxNN8iEiWGvgbVB6b3BPWURTYkU, parent_ino=529983, is_folder=False, unparents_shared=False, size=158946, is_cancelled=False, ispriority=False, doc_type=DocType.BLOB, hash=1206714204386382479, _constructor_called=True)] 2016-01-04 09:53:20,290 -0500 INFO pid=14964 4607569920:Worker-2 snapshot_scilite.pyo:143 Adding local entry inode=15554205, filename=None 2016-01-04 09:53:20,291 -0500 INFO pid=14964 4607569920:Worker-2 snapshot_scilite.pyo:171 Adding cloud entry resource_id=file:OB,ENN8iEiWGvgcjEKX3ExTmxpaGM, filename=None 2016-01-04 09:53:20,291 -0500 INFO pid=14964 4607569920:Worker-2 snapshot_scilite.pyo:253 Updating cloud entry doc_id=01IWNN8iEiWGvgcjEKX3ExTmxpaGM, filename=image2.JPG 2016-01-04 09:53:20,291 -0500 INFO pid=14964 4607569920:Worker-2 snapshot_scilite.pyo:230 Updating local entry inode=15554205, filename=image2.JPG, modified=1451168662, checksum=d9724b214e14b9237bd88e3397a45a9a, size=158809, is_folder=False 2016-01-04 09:53:20,291 -0500 INFO pid=14964 4607569920:Worker-2 snapshot_scilite.pyo:542 Adding local relation child_inode=15554205, parent_inode=529983 2016-01-04 09:53:20,291 -0500 INFO pid=14964 4607569920:Worker-2 snapshot_scilite.pyo:519 Adding cloud relation child_doc_id=0BxNN8iEiWGvgcjEKX3ExTmxpaGM, parent_doc_id=root 2016-01-04 09:53:20,291 -0500 INFO pid=14964 4607569920:Worker-2 snapshot_scilite.pyo:562 Adding Mapping inode=15554205, doc_id=0BxNN8iEiWGvgcjEKX3ExTmxpaGM 2016-01-04 09:53:20,292 -0500 INFO pid=14964 4607569920:Worker-2 workers.pyo:194 Worker successfully completed (ImmutableChange(Direction.DOWNLOAD, Action.CREATE, name=image2.JPG, route= (CloudEntry(doc_id=06xNN8iEiWGvgcjEKX3RxTmxpaGM,filename=image2.JPG,modified=1451168662,creat ed=1451168668,acl_role=owner,doc_type=DocType.BLOB,removed=Falser parent_doc_ids=set(['root']) ,child_doc_ids=set([]),size=158809,checksum=d9724b214e14b9237bd88e3397a45a9a,change_stamp=764 57,server_mod_time=None,is_zombie=False,shared=False,recursive_size=None,resource_type=file,v ersion=None)], rid=0BxNN8iEiWGvgcjEKX3ExTmxpaGM, parent_ino=529983, is_folder=False, unparents_shared=False, size=158809, is_cancelled=False, ispriority=False, doc_type=DocType.BLOB, hash=-5437124921948004300, _constructor_called=True)] 2016-01-04 09:53:20,450 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_scilite.pyo:143 Adding local entry inode=15554206, filename=None 2016-01-04 09:53:20,450 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_scilite.pyo:171 Adding cloud entry resource_id=pdf:OMNN8iEiWGvg0)/2s2G9wUDRWT1E, filename=None 2016-01-04 09:53:20,450 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_scilite.pyo:253 Updating cloud entry doc_id=0BxNN8iEiWGvg0VZsZG9wUDRWT1E, filename=Third Party Payment Form.pdf 2016-01-04 09:53:20,450 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_scilite.pyo:230 Updating local entry inode=15554206, filename=Third Party Payment Form.pdf, modified=1450455497, checksum=Oce073f22eaa6c84c4110fOlab404749, size=8670, is_folder=False 2016-01-04 09:53:20,451 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_scilite.pyo:542 Adding local relation child_inode=15554206, parent_inode=529983 2016-01-04 09:53:20,451 -0500 INFO pid=14964 4649635840:Worker-1 EFTA00502492 snapshot_sqlite.pyo:519 Adding cloud relation child_doc_id=0BxNN8iEiWGvg0VZsZG9wUDRWT1E, parent_doc_id=root 2016-01-04 09:53:20,451 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_sqlite.pyo:562 Adding Mapping inode=15554206, doc_id=0BxNN8iEiWGvg0VZsZG9wUDRWT1E 2016-01-04 09:53:20,452 -0500 INFO pid=14964 4649635840:Worker-1 workers.pyo:194 Worker successfully completed (ImmutableChange(Direction.DOWNLOAD, Action.CREATE, name=Third Party Payment Form.pdf, route=(CloudEntry(doc_id=08xNN8iEiWGvg0VZsZG9wUDRWT1E,filename=Third Party Payment Form.pdf,modified=1450455497,created=1450455501,acl_role=owner,doc_type=DocType.BLOB,removed= False,parent_doc_ids=set([iroot']),child_doc_ids=set([]),size=8670,checksum=Oce073f22eaa6c84c 4110fOlab404749,change_stamp=76176,server_mod_time=Nonef is_zombie=False,shared=Falser recursiv e_size=None, resource_type=pdf,version=None)], rid=0BxNN8iEiWGvg0VZsZG9wUDRWT1E, parent_ino=529983, is_folder=False, unparents_shared=False, size=8670, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB, hash=-4131613288298216687, _constructor_called=True)[ 2016-01-04 09:53:20,453 -0500 INFO pid=14964 4649635840:Worker-1 workers.pyo:183 Worker starting on [ImmutableChange(Direction.DOWNLOAD, Action.CREATE, name=2015JerryElfLetter.docx, route= (CloudEntry(doc_id=08xNN8iEiWGvgdVExUWFUZDJKbDA,filename=2015JerryElfLetter.docx,modified=145 0982714,created=1450982719,acl_role=owner,doc_type=DocType.BLOB,removed=False,parent_doc_ids= set(['0BxNN8iEiWGvgRjcxNnBhRm5nV1k.]),child_doc_ids=set(Il)fsize=92538,checksum=2ffcc8ffa236d f81469bbe979f6d87d5,change_stamp=76431,server_mod_time=None,is_zombie=False,shared=False,recu rsive_size=None,resource_type=filer version=None)), rid=0BxNN8iEiWGvgdVExUWFUZDJKlaDA, parent_ino=14390475, is_folder=False, unparents_shared=False, size=92538, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB, hash=-3311494791203674541, _constructor_called=True)] 2016-01-04 09:53:20,454 -0500 INFO pid=14964 4649635840:Worker-1 download.pyo:139 Downloading https://googledrive.com/p/host/OBxNN8iEiWGvgdVExUWFUZDJKbDA to /var/folders/n1/ypw25pmj06qfyyl_8xnt3kf4000Ogn/T/tmp62m7Vm 2016-01-04 09:53:20,455 -0500 INFO pid=14964 4649635840:Worker-1 sync_http_client.pyo:236 Opening direct connecton to googledrive.com:443. 2016-01-04 09:53:20,727 -0500 INFO pid=14964 4573917184:InternalScheduler sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 09:53:20,836 -0500 INFO pid=14964 4573917184:InternalScheduler sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 09:53:20,837 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_sqlite.pyo:143 Adding local entry inode=15554207, filename=None 2016-01-04 09:53:20,838 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_sqlite.pyo: 171 Adding cloud entry resource_id=file:OBxNN8iEiWGvgdVExUWFUZDJKIDDA, filename=None 2016-01-04 09:53:20,838 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_sqlite.pyo:253 Updating cloud entry doc_id=0115(NN8iEiWGvgdVExUWFUZDJKbDA, filename=2015JerryElfLetter.docx 2016-01-04 09:53:20,838 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_sqlite.pyo:230 Updating local entry inode=15554207, filename=2015JerryElfLetter.docx, modified=1450982714, checksum=2ffcc8ffa236df81469bbe979f6d87d5, size=92538, is_folder=False 2016-01-04 09:53:20,838 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_sqlite.pyo: 542 Adding local relation child_inode=15554207, parent_inode=14390475 2016-01-04 09:53:20,838 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_sqlite.pyo:519 Adding cloud relation child_doc_id=08xNN8iEiWGvgdVExUWFUZDJKbDA, parent_doc_id=08xNN8iEiWGvgRjcxNnlahRm5nVlk 2016-01-04 09:53:20,838 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_sqlite.pyo:562 Adding Mapping inode=15554207, doc_id=0B5(NN8iEiWGvgdVExUWFUZDJKbDA 2016-01-04 09:53:20,844 -0500 INFO pid=14964 4649635840:Worker-1 workers.pyo:194 Worker successfully completed (ImmutableChange(Direction.DOWNLOAD, Action.CREATE, name=2015JerryElfLetter.docx, route= (CloudEntry(doc_id=08xNN8iEiWGvgdVExUWFUZDJKbDA,filename=2015JerryElfLetter.docx,modified=145 0982714,created=1450982719,acl_role=owner,doc_type=DocType.BLOB,removed=False,parent_doc_ids= set([ 10BxNN8iEiWGvgRjcxNnBhRm5nV110]),child_doc_ids=set((])fsize=92538,checksum=2ffcc8ffa236d f81469bbe979f6d87d5,change_stamp=76431,server_mod_time=None,is_zombie=False,shared=False,recu rsive_size=None,resource_type=filer version=None)), rid=0BxNN8iEiWGvgdVExUWFUZDJKlaDA, parent_ino=14390475, is_folder=False, unparents_shared=False, size=92538, is_cancelled=False, EFTA00502493 is_priority=False, doc_type=DocType.BLOB, hash=-3311494791203674541, _constructor_called=True)] 2016-01-04 09:53:20,847 -0500 INFO pid=14964 4649635840:Worker-1 workers.pyo:183 Worker starting on [ImmutableChange(Direction.DOWNLOAD, Action.MODIFY, ino=15425400, name=2016 January.docx, rid=0BxNN8iEiWGvgU3pmX01CLUdjdUk, parent_ino=14390475, is_folder=False, unparents_shared=False, modified=1451864423, checksum=43b82585e3e489858710f66fc440d859, size=80510, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB, hash=5747291435105101430, _constructor_called=True)] 2016-01-04 09:53:20,848 -0500 INFO pid=14964 4649635840:Worker-1 download.pyo:139 Downloading https://googledrive.com/p/host/0BxNN8iEiWGvgU3pmX01CLUdjdUk to /var/folders/n1/ypw25pmj06qfyyl_8xnt3kf4000Ogn/T/tmpIGtCCD 2016-01-04 09:53:20,849 -0500 INFO pid=14964 4649635840:Worker-1 sync_http_client.pyo:236 Opening direct connecton to googledrive.com:443. 2016-01-04 09:53:21,250 -0500 INFO pid=14964 4649635840:Worker-1 commands.pyo:58 Updating inode in snapshot. old=15425400, new=15554209 2016-01-04 09:53:21,251 -0500 INFO pid=14964 4649635840:Worker-1 commands.pyo:61 Entry associated with new inode prior to update None: 2016-01-04 09:53:21,251 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_sqlite.pyo:552 Removing local relation child_inode=15425400, parent_inode=14390475 2016-01-04 09:53:21,251 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_sqlite.pyo: 572 Removing Mapping inode=15425400, doc_id=065(NN8iEiWGvgU3pmX01CLUdjdUk 2016-01-04 09:53:21,251 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_sqlite.pyo:154 Removing local entry inode=15425400 2016-01-04 09:53:21,251 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_sqlite.pyo:143 Adding local entry inode=15554209, filename=None 2016-01-04 09:53:21,252 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_sqlite.pyo: 542 Adding local relation child_inode=15554209, parent_inode=14390475 2016-01-04 09:53:21,252 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_sqlite.pyo:230 Updating local entry inode=15554209, filename=2016 January.docx, modified=1450290920, checksum=e2c1b64cdea2b671d394fba2e4185b67, size=46905, is_folder=False 2016-01-04 09:53:21,252 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_sqlite.pyo:562 Adding Mapping inode=15554209, doc_id=0BxNN8iEiWGvgU3pmX01CLUdjdVk 2016-01-04 09:53:21,252 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_sqlite.pyo:230 Updating local entry inode=15554209, modified=1451864423, checksum=43b82585e3e489858710f66fc440d859, size=80510 2016-01-04 09:53:21,252 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_sqlite.pyo:253 Updating cloud entry doc_id=0BxNN8iEiWGvgU3pmX01CLUdjdUk, filename=None 2016-01-04 09:53:21,253 -0500 INFO pid=14964 4649635840:Worker-1 workers.pyo:194 Worker successfully completed (ImmutableChange(Direction.DOWNLOAD, Action.MODIFY, ino=15425400, name=2016 January.docx, rid=0BxNN8iEiWGvgU3pmX01CLUdjdVk, parent_ino=14390475, is_folder=False, unparents_shared=False, modified=1451864423, checksum=43b82585e3e489858710f66fc440d859, size=80510, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB, hash=5747291435105101430, _constructor_called=True)] 2016-01-04 09:53:21,255 -0500 INFO pid=14964 4649635840:Worker-1 workers.pyo:183 Worker starting on [ImmutableChange(Direction.DOWNLOAD, Action.MODIFY, ino=15425403, name=2015 December.docx, rid=065(NN8iEiWGvgRF1wTG9EcDJ12Fk, parent_ino=14390475, is_folder=False, unparents_shared=False, modified=1451425817, checksum=d3b4256771c0a0698294d47a4fd06542, size=90222, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB, hash=-3310601379741757886, _constructor_called=True)] 2016-01-04 09:53:21,256 -0500 INFO pid=14964 4649635840:Worker-1 download.pyo:139 Downloading https://googledrive.com/p/host/OWENN8iEiWGvgRF1wTG9EcDJ1EFk to /var/folders/n1/ypw25pmj06qfyyl_8xnt3kf4000Ogn/T/tmpUajoen 2016-01-04 09:53:21,257 -0500 INFO pid=14964 4649635840:Worker-1 sync_http_client.pyo:236 Opening direct connecton to googledrive.com:443. 2016-01-04 09:53:21,305 -0500 INFO pid=14964 4582330368:Worker-0 workers.pyo:183 Worker starting on [ImmutableChange(Direction.DOWNLOAD, Action.MODIFY, ino=14455808, name=Christmas Address List.docx, rid=0BxNN8iEiWGvgNE9ONVB6cFhTeG8, parent_ino=14390475, is_folder=False, unparents_shared=False, modified=1450901913, checksum=fc4f77a002871e8e51081ae36f05aa2a, size=64651, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB, hash=601754238340406930, _constructor_called=True)] 2016-01-04 09:53:21,307 -0500 INFO pid=14964 4582330368:Worker-0 download.pyo:139 Downloading https://googledrive.com/p/host/OWENN8iEiWGvgNE9ONVB6cFhTeG8 to /var/folders/n1/ypw25pmj06qfyyl_8xnt3kf4000Ogn/T/tmpIwoSNL 2016-01-04 09:53:21,308 -0500 INFO pid=14964 4582330368:Worker-0 EFTA00502494 sync_http_client.pyo:236 Opening direct connecton to googledrive.com:443. 2016-01-04 09:53:21,320 -0500 INFO pid=14964 4632809472:ListenerScheduler invalidation_listener.pyo:125 InvalidationListener.InformRegistrationStatus(1014:CHANGELOG, REGISTERED) 2016-01-04 09:53:21,320 -0500 WARNING pid=14964 4632809472:ListenerScheduler notification_bridge.pyo:202 Invalidation status callback called with connected=True 2016-01-04 09:53:21,320 -0500 INFO pid=14964 4632809472:ListenerScheduler telemetry.pyo:167 PushStates.NEGOTIATING: 44.27413 => 4.50906 2016-01-04 09:53:21,320 -0500 INFO pid=14964 4632809472:ListenerScheduler telemetry.pyo:153 Push state PushStates.NEGOTIATING => PushStates.ONLINE 2016-01-04 09:53:21,320 -0500 INFO pid=14964 4632809472:ListenerScheduler pause_manager.pyo:130 Removing pause reason OFFLINE. Paused: False 2016-01-04 09:53:21,320 -0500 ERROR pid=14964 4632809472:ListenerScheduler pause_manager.pyo:438 Tried to get last event time for PauseReason.OFFLINE, but no last event time recorded. 2016-01-04 09:53:21,562 -0500 INFO pid=14964 4632809472:ListenerScheduler invalidation_listener.pyo:82 InvalidationListener.Invalidation(1014:CHANGELOG, wapi_version=76761) 2016-01-04 09:53:21,562 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_watcher.pyo:716 Change ignored. observed: 76761, retrieved: 76761 2016-01-04 09:53:21,743 -0500 INFO pid=14964 4582330368:Worker-0 commands.pyo:58 Updating inode in snapshot. old=14455808, new=15554212 2016-01-04 09:53:21,743 -0500 INFO pid=14964 4582330368:Worker-0 associated with new inode prior to update None: 2016-01-04 09:53:21,743 -0500 INFO pid=14964 4582330368:Worker-0 snapshot_scilite.pyo:552 Removing local relation child_inode=14455808, parent_inode=14390475 2016-01-04 09:53:21,744 -0500 INFO pid=14964 4649635840:Worker-1 Updating inode in snapshot. old=15425403, new=15554211 2016-01-04 09:53:21,744 -0500 INFO pid=14964 4649635840:Worker-1 associated with new 2016-01-04 09:53:21, snapshot_scilite.pyo: 2016-01-04 09:53:21, snapshot_scilite.pyo: 2016-01-04 09:53:21,744 -0500 INFO pid=14964 4582330368:Worker-0 snapshot_scilite.pyo:154 Removing local entry inode=14455808 2016-01-04 09:53:21,745 -0500 INFO pid=14964 4582330368:Worker-0 snapshot_scilite.pyo:143 Adding local entry inode=15554212, filename=None 2016-01-04 09:53:21,745 -0500 INFO pid=14964 4582330368:Worker-0 snapshot_scilite.pyo:542 Adding local relation child_inode=15554212, parent_inode=14390475 2016-01-04 09:53:21,745 -0500 INFO pid=14964 4582330368:Worker-0 snapshot_scilite.pyo:230 Updating local entry inode=15554212, filename=Christmas Address List.docx, modified=1450283597, checksum=27be98b59a9fbbcaab5ed2ca243c18c3, size=64506, is_folder=False 2016-01-04 09:53:21,745 -0500 INFO pid=14964 4582330368:Worker-0 snapshot_scilite.pyo:562 Adding Mapping inode=15554212, doc_id=0BxNN8iEiWGvgNE9ONVB6cFhTeG8 2016-01-04 09:53:21,745 -0500 INFO pid=14964 4582330368:Worker-0 snapshot_scilite.pyo:230 Updating local entry inode=15554212, modified=1450901913, checksum=fc4f77a002871e8e51081ae36f05aa2a, size=64651 2016-01-04 09:53:21,746 -0500 INFO pid=14964 4582330368:Worker-0 snapshot_scilite.pyo:253 Updating cloud entry doc_id=0BxNN8iEiWGvgNE9ONVB6cFhTeG8, filename=None 2016-01-04 09:53:21,757 -0500 INFO pid=14964 4582330368:Worker-0 workers.pyo:194 Worker successfully completed (ImmutableChange(Direction.DOWNLOAD, Action.MODIFY, ino=14455808, name=Christmas Address List.docx, rid=0BxNN8iEiWGvgNE9ONVB6cFhTeG8, parent_ino=14390475, is_folder=False, unparents_shared=False, modified=1450901913, checksum=fc4f77a002871e8e51081ae36f05aa2a, size=64651, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB, hash=601754238340406930, _constructor_called=True)] 2016-01-04 09:53:22,745 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_scilite.pyo:572 Removing Mapping inode=15425403, doc_id=06xNN8iEiWGvgRF1wTG9EcDJ1ZFk 2016-01-04 09:53:22,745 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_scilite.pyo:154 Removing local entry inode=15425403 2016-01-04 09:53:22,745 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_scilite.pyo:143 Adding local entry inode=15554211, filename=None 2016-01-04 09:53:22,746 -0500 INFO pid=14964 4649635840:Worker-1 commands.pyo:61 Entry commands.pyo:58 commands.pyo:61 Entry inode prior to update None: 744 -0500 INFO pid=14964 4582330368:Worker-0 572 Removing Mapping inode=14455808, doc_id=OBxNN8iEiWGvgNE90NVB6cFhTeG8 744 -0500 INFO pid=14964 4649635840:Worker-1 552 Removing local relation child_inode=15425403, parent_inode=14390475 EFTA00502495 snapshot_scilite.pyo:542 Adding local relation child_inode=15554211, parent_inode=14390475 2016-01-04 09:53:22,746 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_scilite.pyo:230 Updating local entry inode=15554211, filename=2015 December.docx, modified=1450301229, checksum=1d6e329726f5c82a55361d261743b60a, size=57602, is_folder=False 2016-01-04 09:53:22,746 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_scilite.pyo:562 Adding Mapping inode=15554211, doc_id=0BxNN8iEiWGvgRF1wTG9EcDJ1ZFk 2016-01-04 09:53:22,746 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_scilite.pyo:230 Updating local entry inode=15554211, modified=1451425817, checksum=d3b4256771c0a0698294d47a4fd06542, size=90222 2016-01-04 09:53:22,746 -0500 INFO pid=14964 4649635840:Worker-1 snapshot_scilite.pyo:253 Updating cloud entry doc_id=0BxNN8iEiWGvgRF1wTG9EcDJ1ZFk, filename=None 2016-01-04 09:53:22,755 -0500 INFO pid=14964 4649635840:Worker-1 workers.pyo:194 Worker successfully completed (ImmutableChange(Direction.DOWNLOAD, Action.MODIFY, ino=15425403, name=2015 December.docx, rid=0BxNN8iEiWGvgRF1wTG9EcDJ1ZFk, parent_ino=14390475, is_folder=False, unparents_shared=False, modified=1451425817, checksum=d3b4256771c0a0698294d47a4fd06542, size=90222, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB, hash=-3310601379741757886, _constructor_called=True)] 2016-01-04 09:53:23,306 -0500 INFO pid=14964 4615983104:Batcher batcher.pyo:239 Current batch is completed. Batcher snooze. 2016-01-04 09:53:23,306 -0500 INFO pid=14964 4615983104:Batcher sync_status.pyo:133 Reset sync status 2016-01-04 09:53:23,307 -0500 INFO pid=14964 4615983104:Batcher batcher.pyo:855 Batcher Stats = file_count = Counter (1 COUNT KEY Cdirection=_DownloadDirectionType (Direction. DOWNLOAD), action=_FSChangeActionType(Action.CREATE), batch=False, successful=True): 8, _COUNT KEY(direction=_DownloadDirectionType(Direction. DOWNLOAD), action=_FSChangeActionType(Action.MODIFY), batch=False, successful=True): 3)), byte_count = Counter (1 COUNT KEY (direction= DownloadDirectionType (Direction. DOWNLOAD), action=_FSChangeActionType(Action.CREATE), batch=False, successful=True): _COUNT KEY(direction=_DownloadDirectionType(Direction. DOWNLOAD), action=_FSChangeActionType(Action.MODIFY), batch=False, successful=True): 235383)), batch_operation_count = Counter(), process_seconds = Counter (f_COUNT_KEY (direction= DownloadDirectionType (Direction. DOWNLOAD), action= FSChangeActionType(Action.CREATE), batch=False, successful=True): 3.4366226196289062, _COUNT KEY(direction=_DownloadDirectionType(Direction. DOWNLOAD), action= FSChangeActionType(Action.MODIFY), batch=False, successful=True): 2.356796979904175)), duration seconds = 4 (start_time = 1451919199, end_time = 1451919203) 2016-01-04 09:53:23,307 -0500 INFO pid=14964 4590743552:LocalFSPoller poller.pyo:137 LocalFSPoller first pass started. Waiting for localWatcher to start watching. 2016-01-04 09:53:23,308 -0500 INFO pid=14964 4645429248:LocalWatcher watcher.pyo:266 RootsAdded with roots [_MyDriveRoot(u'/Users/lgroff/Google Drive', 'root', <RootState.INITED: 2>)] 2016-01-04 09:53:23,308 -0500 INFO pid=14964 4645429248:LocalWatcher watcher.pyo:271 watcher adding new root to graph: _MyDriveRoot(u'/Users/lgroff/Google Drive', 'root', <RootState.INITED: 2>) 2016-01-04 09:53:23,308 -0500 INFO pid=14964 4645429248:LocalWatcher watcher.pyo:252 Scheduling roots: [_MyDriveRoot(u'/Users/lgroff/Google Drive', 'root', <RootState.INITED: 2>)] 2016-01-04 09:53:23,308 -0500 INFO pid=14964 4645429248:LocalWatcher watcher.pyo:305 Scheduling root: _MyDriveRoot(u'/Users/lgroff/Google Drive', 'root', <RootState.INITED: 2>) 2016-01-04 09:53:23,309 -0500 INFO pid=14964 4645429248:LocalWatcher watcher.pyo:368 LocalWatcher is up. 2016-01-04 09:53:23,309 -0500 INFO pid=14964 4590743552:LocalFSPoller poller.pyo:146 Finished waiting for LocalWatcher. 2016-01-04 09:53:23,309 -0500 INFO pid=14964 4590743552:LocalFSPoller poller.pyo:149 Starting a local disk scan. 2016-01-04 09:53:23,310 -0500 INFO pid=14964 4590743552:LocalFSPoller diff_helper.pyo:86 Walking: [u'/Users/lgroff/Google Drive'] 2016-01-04 09:53:23,429 -0500 INFO pid=14964 4590743552:LocalFSPoller poller.pyo:166 Number of found changes: 0 2016-01-04 09:53:23,430 -0500 INFO pid=14964 4590743552:LocalFSPoller poller.pyo:177 Finished local disk scan. 2016-01-04 09:55:35,495 -0500 INFO pid=14964 4558016512:FilewatcherMonitor watcher.pyo:550 PathsCallback called with: [ul/Users/lgroff/Google Drive/'] 1070629, EFTA00502496 2016-01-04 09:55:35,505 -0500 INFO pid=14964 4558016512:FilewatcherMonitor diff_helper.pyo:86 Scanning: [u'/Users/lgraff/Google Drive.] 2016-01-04 09:55:40,837 -0500 INFO pid=14964 4558016512:FilewatcherMonitor watcher.pyo:550 PathsCallback called with: [u'/Users/lgroff/Google Drive/'] 2016-01-04 09:55:40,837 -0500 INFO pid=14964 4558016512:FilewatcherMonitor diff_helper.pyo:86 Scanning: (u'/Users/lgroff/Google Drive.] 2016-01-04 09:55:53,528 -0500 INFO pid=14964 4558016512:FilewatcherMonitor watcher.pyo:550 PathsCallback called with: [u'/Users/lgroff/Google Drive/My Documents (1)/1 ] 2016-01-04 09:55:53,544 -0500 INFO pid=14964 4558016512:FilewatcherMonitor diff_helper.pyo:86 Scanning: [u'/Users/lgroff/Google Drive/My Documents (1)'] 2016-01-04 09:56:01,565 -0500 INFO pid=14964 4558016512:FilewatcherMonitor watcher.pyo:550 PathsCallback called with: [u'/Users/lgroff/Google Drive/My Documents (1)/1 ] 2016-01-04 09:56:01,586 -0500 INFO pid=14964 4558016512:FilewatcherMonitor diff_helper.pyo:86 Scanning: [u'/Users/lgroff/Google Drive/My Documents (1)'] 2016-01-04 09:56:02,970 -0500 INFO pid=14964 4558016512:FilewatcherMonitor watcher.pyo:550 PathsCallback called with: [u'/Users/lgroff/Google Drive/My Documents (1)/.] 2016-01-04 09:56:02,970 -0500 INFO pid=14964 4558016512:FilewatcherMonitor diff_helper.pyo:86 Scanning: [u'/Users/lgroff/Google Drive/My Documents (1)'] 2016-01-04 10:08:16,773 -0500 INFO pid=14964 4628602880:TelemetryStoreSizeMonitorThread telemetry_store_monitor_thread.pyo: 44 Checking Telemetry store size. 2016-01-04 10:23:16,820 -0500 INFO pid=14964 4628602880:TelemetryStoreSizeMonitorThread telemetry_store_monitor_thread.pyo: 44 Checking Telemetry store size. 2016-01-04 10:38:16,838 -0500 INFO pid=14964 4628602880:TelemetryStoreSizeMonitorThread telemetry_store_monitor_thread.pyo: 44 Checking Telemetry store size. 2016-01-04 10:42:18,922 -0500 INFO pid=14964 4558016512:FilewatcherMonitor watcher.pyo:550 PathsCallback called with: [u'/Users/lgroff/Google Drive/', u'/Users/lgroff/Google Drive/My Documents (1)/1 ] 2016-01-04 10:42:18,950 -0500 INFO pid=14964 4558016512:FilewatcherMonitor diff_helper.pyo:86 Scanning: [u'/Users/lgraff/Google Drive', u'/Users/lgroff/Google Drive/My Documents Cl)'] 2016-01-04 10:42:19,171 -0500 INFO pid=14964 4558016512:FilewatcherMonitor diff_helper.pyo:133 Found 1 changes: [[FSChange(Direction.UPLOAD, Action.MODIFY, ino=15554209, path=u'/Users/lgroff/Google Drive/My Documents (1)', name=u'2016 January.docx', parent_ino=14390475, new_ino=15554209, is_folder=False, unparents_shared=False, modified=1451922137, checksum=3bb492add684fe4e094cc9felcdc6bf5, size=87667, generator_module=l, is_cancelled=False, is_priority=False)]] 2016-01-04 10:42:19,171 -0500 INFO pid=14964 4558016512:FilewatcherMonitor aggregator.pyo:108 > Received event ImmutableRawEvent(op=MODIFY, path=/Users/lgroff/Google Drive/My Documents (1)/2016 January.docx, time=1451922139.17, is_dir=False, ino=15554209, size=87667, oldpath=None, new_ino=15554209, mtime=1451922137, parent_ino=14390475, affects_gdoc=None, is_cancelled=RawEventIsCancelledFlag.FALSE, old_parent_ino=None, _hash_ code=-7973531745848086861) FSChange(Direction.UPL0AD, Action.MODIFY, ino=15554209, path=u./Users/lgraff/Google Drive/My Documents (1)', name=u'2016 January.docx', parent_ino=14390475, new_ino=15554209, is_folder=False, unparents_shared=False, modified=1451922137, checksum=3bb492add684fe4e094cc9felcdc6bf5, size=87667, generator_module=l, is_cancelled=False, is_priority=False) 2016-01-04 10:42:19,199 -0500 INFO pid=14964 4558016512:FilewatcherMonitor change_buffer.pyo:1042 Adding change and event to change buffer: FSChange(Direction.UPLOAD, Action.MODIFY, ino=15554209, path=u'/Users/lgroff/Google Drive/My Documents (1)', name=u'2016 January.docx', parent_ino=14390475, new_ino=15554209, is_folder=False, unparents_shared=False, modified=1451922137, checksum=3bb492add684fe4e094cc9felcdc6bf5, size=87667, generator_module=l, is_cancelled=False, ispriority=False) ImmutableRawEvent(op=MODIFY, path=/Users/lgroff/Google Drive/My Documents (1)/2016 January.docx, time=1451922139.17, is_dir=False, ino=15554209, size=87667, old_path=None, new_ino=15554209, mtime=1451922137, parent_ino=14390475, affects_gdoc=None, is_cancelled=RawEventIsCancelledFlag.FALSE, old_parent_ino=None, _hash_code=-7973531745848086861) 2016-01-04 10:42:21,680 -0500 INFO pid=14964 4607569920:Worker-2 workers.pyo:183 Worker starting on [ImmutableChange(Direction.UPLOAD, Action.MODIFY, ino=15554209, path=u 1/Users/lgraff/Google Drive/My Documents (1)', name=u'2016 January.docx', parent_ino=14390475, new_ino=15554209, is_folder=False, unparents_shared=False, modified=1451922137, checksum=3bb492add684fe4e094cc9felcdc6bf5, size=87667, generator_module=l, is_cancelled=False, is_priority=False, hash=-3365462239003201581, _constructor_called=True)] 2016-01-04 10:42:21,736 -0500 INFO pid=14964 4607569920:Worker-2 proxy_manager.pyo:354 Removing stale proxy entry from cache EFTA00502497 2016-01-04 10:42:21,738 -0500 INFO pid=14964 4607569920:Worker-2 sync_http_client.pyo:236 Opening direct connecton to clients3.google.com: 443. 2016-01-04 10:42:23,128 -0500 INFO pid=14964 4607569920:Worker-2 docs_client.pyo:2167 Updating: 2016 January.docx (87667 bytes). DocsEntry: <ns0:entry xmlns:ns0="http://www.w3.org/2005/Atom" xmlns:nsl="http://schemas.google.com/g/2005" xmlns:ns2="http://schemas.google.com/docs/2007" xmlns:ns3="http://www.w3.org/2007/app" nsl:etag="&quot;AxZQShZXQit7ImBl&quot;"><nsl:quotaBytesUsed>80510</nsl:quotaBytesUsed> <ns0:category label="hidden" scheme="http://schemas.google.com/g/2005/1abels" term="http://schemas.google.com/g/2005/labels#hidden" /><ns0:category label="has-path-to- root" scheme="http://schemas.google.com/docs/2007" term="http://schemas.google.com/docs/2007#has-path-to-root" /><ns0:category label="application/vnd.openxmlformats-officedocument.wordprocessingml.document" scheme="http://schemas.google.com/g/2005#kind" term="http://schemas.google.com/docs/2007#file" /><ns0:category label="modified-by-me" scheme="http://schemas.google.com/g/2005/1abels" term="http://schemas.google.com/g/2005/1abels#modified-by-me" /><ns0:category label="viewed" scheme="http://schemas.google.com/g/2005/1abels" term="http://schemas.google.com/g/2005/1abels#viewed" /><ns0:category label="purgeable" scheme="http://schemas.google.com/g/2005/1abels" term="http://schemas.google.com/g/2005/1abels#purgeable" I> <ns0:id>https://clients3.google.com/feeds/id/file%3A0BxNN8iEiWGvgU3pmX01CLVdjdVk</ns0:id> <ns2:writersCanlnvite value="true" /><ns0:author><ns0:name>lesley.jee</ns0:name> <ns0:email>lesley.jee@gmail.com</ns0:email></ns0:author><ns0:content src="https://doc-Os-bc- docs.googleusercontent.com/docs/securesc/rsu4vkf416fjahebskrbestclo213ea4g/mdq73uveb5v2amoktvn khturev85egg4/1451916000000/07946446228801835265/07946446228801835265/0BxNN8iEiWGvgU3pmX01CLV djdVk?e=download&amp;gd=true" type="application/vnd.openxmlformats- officedocument.wordprocessingml.document" /><ns0:updated>2016-01-04T15:42:17</ns0:updated> <ns0:published>2015-12-05T14:07:55.2442</ns0:published><nsl:lastViewed>2016-01- 04T13:43:35.8222</nsl:lastViewed><nsl:lastModifiedBy><ns0:name>lesley.jee</ns0:name> <ns0:email>lesley.jee@gmail.com</ns0:email></nsl:lastModifiedBy><ns0:title>2016 January.docx</ns0:title><nsl:resourceId>file:0BxNN8iEiWGvgU3pmX01CLVdjdVk</nsl:resourceId> <ns0:link href="https://clients3.google.com/feeds/default/private/full/folder%3A0BxNN8iEiWGvgRjcxNnBhRm 5nVlk" rel="http://schemas.google.com/docs/2007#parent" type="application/atom+xml" I> <ns0:link href="https://drive.google.com/file/d/0WENN8iEiWGvgU3pmX01CLVdjdVkiview? usp=docslist_api" rel="alternate" type="text/html" /><ns0:link href="https://ssl.gstatic.com/docs/doclist/images/icon_10_word_list.png" rel="http://schemas.google.com/docs/2007#icon" type="image/png" /><ns0:link href="https://clients3.google.com/feeds/upload/create- session/default/private/full/file%3A0B5ENN8iEiWGvgU3pmX01CLVdjdVk?new- revision=true&amp;override-modified-timestamp=true&amp;force-modified-timestamp=true" rel="http://schemas.google.com/g/2005#resumable-edit-media" type="application/atom+xml" I> <ns0:link href="https://clients3.google.com/feeds/upload/file/default/private/full/file%3A0BxNN8iEiWGvg U3pmX01CLVdjdVk" rel="http://schemas.google.com/docs/2007#alt-edit-media" type="application/atom+xml" /><ns0:link href="https://1h4.googleusercontent.com/KKXNFyl4vjloGYVjo5IXpd2nI7bYZJIuegg2pluKfKssxg4I96grYJ iSiVxj3vSw0hlCy6w=s220" rel="http://schemas.google.com/docs/2007/thumbnail" type="image/png" /><ns0:link href="https://drive.google.com/sharing/share? subapp=10&amp;id=0BxNN8iEiWGvgU3pmX01CLVdjdVkfiamp;shareProtocolVersion=2&amp;foreignService=g oogledrivesync&amp;command=settings&amp;shareUiType=default&amp;authuser=0&amp;client=desktop &amp;hl=en" rel="http://schemas.google.com/docs/2007#share" type="text/html" /><ns0:link href="https://clients3.google.com/feeds/default/private/full/file%3A0BxNN8iEiWGvgU3pmX01CLVdj dVk" rel="self" type="application/atom+xml" /><ns0:link href="https://clients3.google.com/feeds/default/private/full/file%3A0BxNN8iEiWGvgU3pmX01CLVdj dVk?new-revision=true&amp;override-modified-timestamp=true&amp;force-modified-timestamp=true" rel="edit" type="application/atom+xml" /><ns0:link href="https://clients3.google.com/feeds/default/media/file%3A0BxNN8iEiWGvgU3pmX01CLVdjdVk" rel="edit-media" type="application/vnd.openxmlformats- officedocument.wordprocessingml.document" /><nsl:feedLink href="https://clients3.google.com/feeds/default/private/full/file%3A0BxNN8iEiWGvgU3pmX01CLVdj dVk/acl" rel="http://schemas.google.com/ac1/2007#accessControlList" /><nsl:feedLink href="https://clients3.google.com/feeds/default/private/full/file%3A0BxNN8iEiWGvgU3pmX01CLVdj dVk/revisions" rel="http://schemas.google.com/docs/2007/revisions" /><ns3:edited>2016-01- 04T15:02:50.1412.</ns3:edited><ns2:isShareable value="true" /><ns2:isShareableByMe EFTA00502498 value="true" /><ns2:modifiedByMeDate>2016-01-03T23:40:23.0002.</ns2:modifiedByMeDate> <ns2:md5Checksum>43b82585e3e489858710f66fc440d859</ns2:md5Checksum><ns2:filename>2016 January.docx</ns2:filename><ns2:suggestedFilename>2016 January.docx</ns2:suggestedFilename> <ns2:size>80510</ns2:size></ns0:entry> 2016-01-04 10:42:23,135 -0500 INFO pid=14964 4607569920:Worker-2 sync_http_client.pyo:236 Opening direct connecton to upload.drive.google.com:443. 2016-01-04 10:42:25,054 -0500 INFO pid=14964 4632809472:ListenerScheduler invalidation_listener.pyo:82 InvalidationListener.Invalidation(1014:CHANGELOG, wapi_version=76767) 2016-01-04 10:42:25,056 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_watcher.pyo:711 Getting changes; observed: 76767, retrieved: 76761 2016-01-04 10:42:25,239 -0500 INFO pid=14964 4632809472:ListenerScheduler invalidation_listener.pyo:82 InvalidationListener.Invalidation(1014:CHANGELOG, wapi_version=76768) 2016-01-04 10:42:25,246 -0500 INFO pid=14964 4607569920:Worker-2 snapshot_sqlite.pyo:253 Updating cloud entry doc_id=0BxNN8iEiWGvgU3pmX01CLVdjdVk, filename=None 2016-01-04 10:42:25,247 -0500 INFO pid=14964 4632809472:ListenerScheduler invalidation_listener.pyo:82 InvalidationListener.Invalidation(1014:CHANGELOG, wapi_version=76769) 2016-01-04 10:42:25,248 -0500 INFO pid=14964 4607569920:Worker-2 snapshot_sqlite.pyo:230 Updating local entry inode=15554209, modified=1451922137, checksum=3bb492add684fe4e094cc9felcdc6bf5, size=87667 2016-01-04 10:42:25,288 -0500 INFO pid=14964 4607569920:Worker-2 workers.pyo:194 Worker successfully completed (ImmutableChange(Direction.UPL0AD, Action.MODIFY, ino=15554209, path=u'/Users/lgroff/Google Drive/My Documents (1)', name=u'2016 January.docx', parent_ino=14390475, new_ino=15554209, is_folder=False, unparents_shared=False, modified=1451922137, checksum=3bb492add684fe4e094cc9felcdc6bf5, size=87667, generator_module=l, is_cancelled=False, is_priority=False, hash=-3365462239003201581, _constructor_called=True)) 2016-01-04 10:42:25,678 -0500 INFO pid=14964 4615983104:Batcher batcher.pyo:239 Current batch is completed. Batcher snooze. 2016-01-04 10:42:25,678 -0500 INFO pid=14964 4615983104:Batcher sync_status.pyo:133 Reset sync status 2016-01-04 10:42:25,687 -0500 INFO pid=14964 4615983104:Batcher batcher.pyo:855 Batcher Stats = file_count = Counter (f_COUNT_KEY Cdirection=_UploadDirectionType (Direction. UPLOAD), action=_FSChangeActionType(Action.MODIFY), batch=False, successful=True): 1)), byte_count = Counter (f_COUNT_KEY (direction=_UploadDirectionType (Direction. UPLOAD), action=_FSChangeActionType(Action.MODIFY), batch=False, successful=True): 87667)), batch_operation_count = Counter(), process_seconds = Counter (f_COUNT_KEY (direction=_UploadDirectionType (Direction. UPLOAD), action=_FSChangeActionType(Action.MODIFY), batch=False, successful=True): 3.5822410583496094)), duration seconds = 4 (start_time = 1451922141, end_time = 1451922145) 2016-01-04 10:42:25,697 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_watcher.pyo:1026 Getting cloud resources via changelog feed from 76762 2016-01-04 10:42:25,698 -0500 INFO pid=14964 4594950144:CloudWatcher docs_client.pyo:743 Requesting Doc List: /feeds/default/private/changes?start-index=76762&showroot=true&include- shared=true&reason=change 2016-01-04 10:42:25,698 -0500 INFO pid=14964 4594950144:CloudWatcher sync_http_client.pyo:236 Opening direct connecton to clients3.google.com:443. 2016-01-04 10:42:25,916 -0500 INFO pid=14964 4594950144:CloudWatcher docs_client.pyo:1174 Feed has 1 entries 2016-01-04 10:42:25,916 -0500 INFO pid=14964 4594950144:CloudWatcher docs_client.pyo:1181 End of feed, no next URI 2016-01-04 10:42:25,916 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_watcher.pyo:1645 Built recent change dict: l'0BxNN8iEiWGvgU3pmX01CLVdjdVIO: (WorkerModifyCloudEvent(modify_time=1451922137, size=87667, checksum=3bb492add684fe4e094cc9felcdc6bf5, doc_id=0BxNN8iEiWGvgU3pmX01CLVdjdVk, server_mod_time=1451922144, version=None, _hash_code=3779561205475719420)]) 2016-01-04 10:42:25,948 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_watcher.pyo: 1368 GenerateCreateForDescendents() entry CloudEntry(doc_id=0BxNN8iEiWGvgU3pmX01CLVdjdVk,filename=2016 January.docx,modified=1451922137,created=1449324475,acl_role=owner,doc_type=DocType.BLOB,remo ved=Falserparent_doc_ids=set(['0B5ENN8iEiWGvgRjcxNnBhRm5nV1IO)),child_doc_ids=set([)).size=876 EFTA00502499 67,checksum=3bb492add684fe4e094cc9felcdc6bf5,change_stamp=76769,servermod time=1451922144, is zombie=False,shared=False,recursive size=None,resource_type=file,version=Rone) parents_added set([]) 2016-01-04 10:42:25,967 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_watcher.pyo:198 HandleSyncConfigSettingsChange generated events=set(()) 2016-01-04 10:42:30,969 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_watcher.pyo:716 Change ignored. observed: 76769, retrieved: 76769 2016-01-04 10:53:13,534 -0500 INFO pid=14964 4545896448:ThreadMonitor thread_manager.pyo:339 Waking up. 2016-01-04 10:53:13,552 -0500 INFO pid=14964 4545896448:ThreadMonitor thread_manager.pyo:336 Waiting on exit condition. Wake up after 3600s. 2016-01-04 10:53:16,676 -0500 INFO pid=14964 4599156736:PushTelemetryThread telemetry.pyo:167 PushStates.ONLINE: 43595.44 => 3595.44 2016-01-04 10:53:16,676 -0500 INFO pid=14964 4599156736:PushTelemetryThread telemetry.pyo:112 Push telemetry harvest triggered, but only 3599.99917167s elapsed. Skipping. 2016-01-04 10:53:16,698 -0500 INFO pid=14964 4641222656:PauseTelemetryThread pause_telemetry.pyo:185 Collected 0.999983690845h of telemetry at t=1451922796.7, skipping. 2016-01-04 10:53:16,896 -0500 INFO pid=14964 4628602880:TelemetryStoreSizeMonitorThread telemetry_store_monitor_thread.pyo: 44 Checking Telemetry store size. 2016-01-04 10:56:29,074 -0500 INFO pid=14964 4632809472:ListenerScheduler invalidation_listener.pyo:82 InvalidationListener.Invalidation(1014:CHANGELOG, wapi_version=76770) 2016-01-04 10:56:29,094 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_watcher.pyo:711 Getting changes; observed: 76770, retrieved: 76769 2016-01-04 10:56:29,095 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_watcher.pyo:1026 Getting cloud resources via changelog feed from 76770 2016-01-04 10:56:29,095 -0500 INFO pid=14964 4594950144:CloudWatcher docs_client.pyo:743 Requesting Doc List: /feeds/default/private/changes?start-index=76770&showroot=true&include- shared=true&reason=change 2016-01-04 10:56:29,167 -0500 INFO pid=14964 4594950144:CloudWatcher proxy_manager.pyo:354 Removing stale proxy entry from cache 2016-01-04 10:56:29,167 -0500 INFO pid=14964 4594950144:CloudWatcher sync_http_client.pyo:236 Opening direct connecton to accounts.google.com:443. 2016-01-04 10:56:29,391 -0500 INFO pid=14964 4594950144:CloudWatcher proxy_manager.pyo:354 Removing stale proxy entry from cache 2016-01-04 10:56:29,392 -0500 INFO pid=14964 4594950144:CloudWatcher sync_http_client.pyo:236 Opening direct connecton to clients3.google.com:443. 2016-01-04 10:56:30,073 -0500 INFO pid=14964 4594950144:CloudWatcher docs_client.pyo:1174 Feed has 1 entries 2016-01-04 10:56:30,073 -0500 INFO pid=14964 4594950144:CloudWatcher docs_client.pyo:1181 End of feed, no next URI 2016-01-04 10:56:30,075 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_watcher.pyo: 1368 GenerateCreateForDescendents() entry CloudEntry(doc_id=0BxNN8iEiWGvgU3pmX01CLVdjdVk,filename=2016 January.docx,modified=1451922137,created=1449324475,acl_role=owner,doc_type=DocType.HLOB,remo ved=Falser parent_doc_ids=set(['0R5ENN8iEiWGvgRjcxNnBhRm5nV1k.)),child_doc_ids=set((]).size=876 67,checksum=3bb492add684fe4e094cc9felcdc6bf5,change_stamp=76770,servermod_time=1451922987,is zombie=False,shared=False,recursive_size=None,resource_type=file,version=None) parents_added zwt((l) 2016-01-04 10:56:30,103 -0500 INFO pid=14964 4594950144:CloudWatcher clo HandleSyncConfigSettingsChange generated events=set(()) 2016-01-04 11:03:57,399 -0500 INFO pid=14964 4558016512:FilewatcherMonitor PathsCallback called with: [u'/Users/lgroff/Google Drive/'] 2016-01-04 11:03:57,797 -0500 INFO pid=14964 4558016512:FilewatcherMonitor Scanning: (u'/Users/lgroff/Google Drive') 2016-01-04 11:04:01,315 -0500 INFO pid=14964 4558016512:FilewatcherMonitor PathsCallback called with: [u'/Users/lgroff/Google Drive/'] 2016-01-04 11:04:01,315 -0500 INFO pid=14964 4558016512:FilewatcherMonitor Scanning: (u'/Users/lgroff/Google Drive'] 2016-01-04 11:04:07,498 -0500 INFO pid=14964 4558016512:FilewatcherMonitor PathsCallback called with: [u'/Users/lgroff/Google Drive/'] 2016-01-04 11:04:07,606 -0500 INFO pid=14964 4558016512:FilewatcherMonitor Scanning: (u'/Users/lgroff/Google Drive'] 2016-01-04 11:04:11,351 -0500 INFO pid=14964 4558016512:FilewatcherMonitor PathsCallback called with: [u'/Users/lgroff/Google Drive/'] ud_watcher.pyo:198 watcher.pyo:550 diff_helper.pyo:86 watcher.pyo:550 diff_helper.pyo:86 watcher.pyo:550 diff_helper.pyo:86 watcher.pyo:550 EFTA00502500 2016-01-04 11:04:11,351 -0500 INFO pid=14964 4558016512:FilewatcherMonitor diff_helper.pyo:86 Scanning: (u'/Users/lgroff/Google Drive') 2016-01-04 11:04:20,035 -0500 INFO pid=14964 4558016512:FilewatcherMonitor watcher.pyo:550 PathsCallback called with: (u'/Users/lgroff/Google Drivel'] 2016-01-04 11:04:20,084 -0500 INFO pid=14964 4558016512:FilewatcherMonitor diff_helper.pyo:86 Scanning: (u'/Users/lgroff/Google Drive') 2016-01-04 11:04:25,037 -0500 INFO pid=14964 4558016512:FilewatcherMonitor watcher.pyo:550 PathsCallback called with: (u'/Users/lgroff/Google Drivel'] 2016-01-04 11:04:25,037 -0500 INFO pid=14964 4558016512:FilewatcherMonitor diff_helper.pyo:86 Scanning: (u'/Users/lgroff/Google Drive') 2016-01-04 11:08:16,890 -0500 INFO pid=14964 4628602880:TelemetryStoreSizeMonitorThread telemetry_store_monitor_thread.pyo: 44 Checking Telemetry store size. 2016-01-04 11:23:17,995 -0500 INFO pid=14964 4628602880:TelemetryStoreSizeMonitorThread telemetry_store_monitor_thread.pyo: 44 Checking Telemetry store size. 2016-01-04 11:38:18,176 -0500 INFO pid=14964 4628602880:TelemetryStoreSizeMonitorThread telemetry_store_monitor_thread.pyo: 44 Checking Telemetry store size. 2016-01-04 11:53:13,602 -0500 INFO pid=14964 4545896448:ThreadMonitor thread_manager.pyo:339 Waking up. 2016-01-04 11:53:15,902 -0500 INFO pid=14964 4545896448:ThreadMonitor thread_manager.pyo:336 Waiting on exit condition. Wake up after 3600s. 2016-01-04 11:53:16,839 -0500 INFO pid=14964 4599156736:PushTelemetryThread telemetry.pyo:167 PushStates.ONLINE: 43600.1 => 7195.54 2016-01-04 11:53:17,102 -0500 INFO pid=14964 4641222656:PauseTelemetryThread pause_telemetry.pyo:185 Collected 2.00009591222h of telemetry at t=1451926397.1, skipping. 2016-01-04 11:53:17,281 -0500 INFO pid=14964 4599156736:PushTelemetryThread telemetry.pyo:112 Push telemetry harvest triggered, but only 7200.10396695s elapsed. Skipping. 2016-01-04 11:53:18,437 -0500 INFO pid=14964 4628602880:TelemetryStoreSizeMonitorThread telemetry_store_monitor_thread.pyo: 44 Checking Telemetry store size. 2016-01-04 12:05:41,807 -0500 INFO pid=14964 4558016512:FilewatcherMonitor watcher.pyo:550 PathsCallback called with: (u'/Users/lgroff/Google Drive/My Documents (1)/', u'/Users/lgroff/Google Drive/') 2016-01-04 12:05:42,819 -0500 INFO pid=14964 4558016512:FilewatcherMonitor diff_helper.pyo:86 Scanning: (u'/Users/lgroff/Google Drive/My Documents (1)', u'/Users/lgroff/Google Drive'] 2016-01-04 12:05:48,806 -0500 INFO pid=14964 4558016512:FilewatcherMonitor diff_helper.pyo:133 Found 1 changes: ((FSChange(Direction.UPLOAD, Action.MODIFY, ino=15554209, path=u'/Users/lgroff/Google Drive/My Documents (1)', name=u'2016 January.docx', parent_ino=14390475, new_ino=15554209, is_folder=False, unparents_shared=False, modified=1451927139, checksum=4639ab3c1249973b135fa493afde0b78, size=85773, generator_module=1, is_cancelled=False, is_priority=False))) 2016-01-04 12:05:48,938 -0500 INFO pid=14964 4558016512:FilewatcherMonitor aggregator.pyo:108 > Received event ImmutableRawEvent(op=MODIFY, path=/Users/lgroff/Google Drive/My Documents (1)/2016 January.docx, time=1451927148.94, is_dir=False, ino=15554209, size=85773, old_path=None, new_ino=15554209, mtime=1451927139, parent_ino=14390475, affects_gdoc=None, is_cancelled=RawEventIsCancelledFlag.FALSE, old_parent_ino=None, _hash_ code=3715985397219739992) FSChange(Direction.UPLOAD, Action.MODIFY, ino=15554209, path=u'/Users/lgroff/Google Drive/My Documents (1)', name=u'2016 January.docx', parent_ino=14390475, new_ino=15554209, is_folder=False, unparents_shared=False, modified=1451927139, checksum=4639ab3c1249973b135fa493afde0b78, size=85773, generator_module=1, is_cancelled=False, is_priority=False) 2016-01-04 12:05:49,018 -0500 INFO pid=14964 4558016512:FilewatcherMonitor change_buffer.pyo:1042 Adding change and event to change buffer: FSChange(Direction.UPLOAD, Action.MODIFY, ino=15554209, path=u'/Users/lgroff/Google Drive/My Documents (1)', name=u'2016 January.docx', parent_ino=14390475, new_ino=15554209, is_folder=False, unparents_shared=False, modified=1451927139, checksum=4639ab3c1249973b135fa493afde0b78, size=85773, generator_module=1, is_cancelled=False, is_priority=False) ImmutableRawEvent(op=MODIFY, path=/Users/lgroff/Google Drive/My Documents (1)/2016 January.docx, time=1451927148.94, is_dir=False, ino=15554209, size=85773, old_path=None, new_ino=15554209, mtime=1451927139, parent_ino=14390475, affects_gdoc=None, is_cancelled=RawEventIsCancelledFlag.FALSE, old_parent_ino=None, _hash_code=3715985397219739992) 2016-01-04 12:05:49,020 -0500 INFO pid=14964 4558016512:FilewatcherMonitor watcher.pyo:550 PathsCallback called with: (u'/Users/lgroff/Google Drive/My Documents (1)/', u'/Users/lgroff/Google Drive/1) 2016-01-04 12:05:49,020 -0500 INFO pid=14964 4558016512:FilewatcherMonitor diff_helper.pyo:86 Scanning: (u'/Users/lgroff/Google Drive/My Documents (1)', u'/Users/lgroff/Google Drive'] EFTA00502501 2016-01-04 12:05:52,085 -0500 INFO pid=14964 4582330368:Worker-0 workers.pyo:183 Worker starting on [ImmutableChange(Direction.UPLOAD, Action.MODIFY, ino=15554209, path=u'/Users/lgroff/Google Drive/My Documents (1)', name=u'2016 January.docx', parent_ino=14390475, new_ino=15554209, is_folder=False, unparents_shared=False, modified=1451927139, checksum=4639ab3c1249973b135fa493afde0b78, size=85773, generator_module=1, is_cancelled=False, is_priority=False, hash=503851787230248274, _constructor_called=True)] 2016-01-04 12:05:52,452 -0500 INFO pid=14964 4582330368:Worker-0 proxy_manager.pyo:354 Removing stale proxy entry from cache 2016-01-04 12:05:52,455 -0500 INFO pid=14964 4582330368:Worker-0 sync_http_client.pyo:236 Opening direct connecton to accounts.google.com:443. 2016-01-04 12:05:54,106 -0500 INFO pid=14964 4582330368:Worker-0 proxy_manager.pyo:354 Removing stale proxy entry from cache 2016-01-04 12:05:54,106 -0500 INFO pid=14964 4582330368:Worker-0 sync_http_client.pyo:236 Opening direct connecton to clients3.google.com:443. 2016-01-04 12:05:54,686 -0500 INFO pid=14964 4582330368:Worker-0 docs client.pyo:2167 Updating: 2016 January.docx (85773 bytes). DocsEntry: <ns0:entry xmlns:ns0="http://www.w3.org/2005/Atom" xmlns:nsl="http://schemas.google.com/g/2005" xmlns:ns2="http://schemas.google.com/docs/2007" xmlns:ns3="http://www.w3.org/2007/app" nsl:etag="&quot;AxZWRxdeHyt7ImBl&quot;"><nsl:quotaBytesUsed>87667</nsl:quotaBytesUsed> <ns0:category label="hidden" scheme="http://schemas.google.com/g/2005/labels" term="http://schemas.google.com/g/2005/labels#hidden" /><ns0:category label="has-path-to- root" scheme="http://schemas.google.com/docs/2007" term="http://schemas.google.com/docs/2007#has-path-to-root" /><ns0:category label="application/vnd.openxmlformats-officedocument.wordprocessingml.document" scheme="http://schemas.google.com/g/2005#kind" term="http://schemas.google.com/docs/2007#file" /><ns0:category label="modified-by-me" scheme="http://schemas.google.com/g/2005/1abels" term="http://schemas.google.com/g/2005/labels#modified-by-me" /><ns0:category label="viewed" scheme="http://schemas.google.com/g/2005/1abels" term="http://schemas.google.com/g/2005/labels#viewed" /><ns0:category label="purgeable" scheme="http://schemas.google.com/g/2005/1abels" term="http://schemas.google.com/g/2005/1abels#purgeable" /> <ns0:id>https://clients3.google.com/feeds/id/file%3A0BxNN8iEiWGvgU3pmX01CLVdjdVk</ns0:id> <ns2:writersCanlnvite value="true" /><ns0:author><ns0:name>lesley.jee</ns0:name> <ns0:email>lesley.jee@gmail.com</ns0:email></ns0:author><ns0:content src="https://doc-Os-bc- docs.googleusercontent.com/docs/securesc/rsu4vkf416fjahebskrbestclo213ea4g/ls6dkb4pc4pu211nngi ghh1s15m112h6/1451923200000/07946446228801835265/07946446228801835265/0B5ENN8iEiWGvgU3pmX01CLV djdVk?e=download&amp;gd=true" type="application/vnd.openxmlformats- officedocument.wordprocessingml.document" /><ns0:updated>2016-01-04T17:05:39</ns0:updated> <ns0:published>2015-12-05T14:07:55.2442</ns0:published><nsl:lastViewed>2016-01- 04T15:56:27.7382.</nsl:lastViewed><nsl:lastModifiedBy><ns0:name>lesley.jee</ns0:name> <ns0:email>lesley.jee@gmail.com</ns0:email></nsl:lastModifiedBy><ns0:title>2016 January.docx</ns0:title><nsl:resourceId>file:0B5ENN8iEiWGvgU3pmX01CLVdjdVk</nsl:resourceId> <ns0:link href="https://clients3.google.com/feeds/default/private/full/folder%3A0BxNN8iEiWGvgRjcxNnEhRm 5nVlk" rel="http://schemas.google.com/docs/2007#parent" type="application/atom+xml" /> <ns0:link href="https://drive.google.com/file/d/0WENN8iEiWGvgU3pmX01CLVdjdVkiview? usp=docslist_api" rel="alternate" type="text/html" /><ns0:link href="https://ssl.gstatic.com/docs/doclist/images/icon_10_word_list.png" rel="http://schemas.google.com/docs/2007#icon" type="image/png" /><ns0:link href="https://clients3.google.com/feeds/upload/create- session/default/private/full/file%3A0B5ENN8iEiWGvgU3pmX01CLVdjdVk?new- revision=true&amp;override-modified-timestamp=true&amp;force-modified-timestamp=true" rel="http://schemas.google.com/g/2005#resumable-edit-media" type="application/atom+xml" /> <ns0:link href="https://clients3.google.com/feeds/upload/file/default/private/full/file%3A0BxNN8iEiWGvg U3pmX01CLVdjdVk" rel="http://schemas.google.com/docs/2007#alt-edit-media" type="application/atom+xml" /><ns0:link href="https://1h4.googleusercontent.com/F5Gm92H9Z9gYYBCMwf5346_29V6rUiWKlwxG219X2v8A92cKzUDn Rntl-Scz0zI24EGUQ=s220" rel="http://schemas.google.com/docs/2007/thumbnail" type="image/png" /><ns0:link href="https://drive.google.com/sharing/share? subapp=10&amp;id=08xNN8iEiWGvgU3pmX01CLVdjdVkfiamp;shareProtocolVersion=2&amp;foreignService=g oogledrivesync&amp;command=settings&amp;shareUiType=default&amp;authuser=0&amp;client=desktop &amp;hl=en" rel="http://schemas.google.com/docs/2007#share" type="text/html" /><ns0:link EFTA00502502 href="https://clients3.google.com/feeds/default/private/full/file%3A0BxNN8iEiWGvgU3pmX01CLVdj dVk" rel="self" type="application/atom+xml" /><ns0:link href="https://clients3.google.com/feeds/default/private/full/file%3A0BxNN8iEiWGvgU3pmX01CLVdj dVk?new-revision=true&amp;override-modified-timestamp=true&amp;force-modified-timestamp=true" rel="edit" type="application/atom+xml" /><ns0:link href="https://clients3.google.com/feeds/default/media/file%3A0BxNN8iEiWGvgU3pmX01CLVdjdVk" rel="edit-media" type="application/vnd.openxmlformats- officedocument.wordprocessingml.document" /><nsl:feedLink href="https://clients3.google.com/feeds/default/private/full/file%3A0BxNN8iEiWGvgU3pmX01CLVdj dVk/acl" rel="http://schemas.google.com/ac1/2007.accessControlList" /><nsl:feedLink href="https://clients3.google.com/feeds/default/private/full/file%3A0BxNN8iEiWGvgU3pmX01CLVdj dVk/revisions" rel="http://schemas.google.com/docs/2007/revisions" /><ns3:edited>2016-01- 04T15:56:27.7972</ns3:edited><ns2:isShareable value="true" /><ns2:isShareableByMe value="true" /><ns2:modifiedByMeDate>2016-01-04T15:42:17.0002</ns2:modifiedByMeDate> <ns2:md5Checksum>3bb492add684fe4e094cc9felcdc6bf5</ns2:md5Checksum><ns2:filename>2016 January.docx</ns2:filename><ns2:suggestedFilename>2016 January.docx</ns2:suggestedFilename> <ns2:size>87667</ns2:size></ns0:entry> 2016-01-04 12:05:54,691 -0500 INFO pid=14964 4582330368:Worker-0 proxy_manager.pyo:354 Removing stale proxy entry from cache 2016-01-04 12:05:54,692 -0500 INFO pid=14964 4582330368:Worker-0 sync_http_client.pyo:236 Opening direct connecton to upload.drive.google.com:443. 2016-01-04 12:05:56,095 -0500 INFO pid=14964 4632809472:ListenerScheduler invalidation_listener.pyo:82 InvalidationListener.Invalidation(1014:CHANGELOG, wapi_version=76775) 2016-01-04 12:05:56,098 -0500 INFO pid=14964 4582330368:Worker-0 snapshot_sqlite.pyo:253 Updating cloud entry doc_id=0BxNN8iEiWGvgU3pmX01CLVdjdVk, filename=None 2016-01-04 12:05:56,212 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_watcher.pyo:711 Getting changes; observed: 76775, retrieved: 76770 2016-01-04 12:05:56,214 -0500 INFO pid=14964 4582330368:Worker-0 snapshot_sqlite.pyo:230 Updating local entry inode=15554209, modified=1451927139, checksum=4639ab3c1249973b135fa493afde0b78, size=85773 2016-01-04 12:05:56,382 -0500 INFO pid=14964 4582330368:Worker-0 workers.pyo:194 Worker successfully completed (ImmutableChange(Direction.UPLOAD, Action.MODIFY, ino=15554209, path=u'/Users/lgroff/Google Drive/My Documents (1)', name=u'2016 January.docx', parent_ino=14390475, new_ino=15554209, is_folder=False, unparents_shared=False, modified=1451927139, checksum=4639ab3c1249973b135fa493afde0b78, size=85773, generator_module=1, is_cancelled=False, is_priority=False, hash=503851787230248274, _constructor_called=True)) 2016-01-04 12:05:56,634 -0500 INFO pid=14964 4632809472:ListenerScheduler invalidation_listener.pyo:82 InvalidationListener.Invalidation(1014:CHANGELOG, wapi_version=76776) 2016-01-04 12:05:56,762 -0500 INFO pid=14964 4615983104:Batcher batcher.pyo:239 Current batch is completed. Batcher snooze. 2016-01-04 12:05:56,762 -0500 INFO pid=14964 4615983104:Batcher sync_status.pyo:133 Reset sync status 2016-01-04 12:05:56,896 -0500 INFO pid=14964 4615983104:Batcher batcher.pyo:855 Batcher Stats = file_count = Counter(f_COUNT_KEY(direction=_UploadDirectionType(Direction.UPLOAD), action=_FSChangeActionType(Action.MODIFY), batch=False, successful=True): 1)), byte_count = Counter (f_COUNT_KEY Cdirection=_UploadDirectionType (Direction. UPLOAD), action=_FSChangeActionType(Action.MODIFY), batch=False, successful=True): 85773)), batch_operation_count = Counter(), process_seconds = Counter (f_COUNT_KEY Cdirection=_UploadDirectionType (Direction. UPLOAD), action=_FSChangeActionType(Action.MODIFY), batch=False, successful=True): 4.274615049362183)), duration seconds = 5 (start_time = 1451927151, end_time = 1451927156) 2016-01-04 12:05:56,897 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_watcher.pyo:1026 Getting cloud resources via changelog feed from 76771 2016-01-04 12:05:56,897 -0500 INFO pid=14964 4594950144:CloudWatcher docs_client.pyo:743 Requesting Doc List: /feeds/default/private/changes?start-index=76771&showroot=true&include- shared=true&reason=change 2016-01-04 12:05:56,898 -0500 INFO pid=14964 4594950144:CloudWatcher sync_http_client.pyo:236 Opening direct connecton to clients3.google.com:443. 2016-01-04 12:05:57,091 -0500 INFO pid=14964 4594950144:CloudWatcher docs_client.pyo:1174 Feed has 1 entries EFTA00502503 2016-01-04 12:05:57,152 -0500 INFO pid=14964 4594950144:CloudWatcher docs_client.pyo:1181 End of feed, no next URI 2016-01-04 12:05:57,153 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_watcher.pyo:1645 Built recent change dict: l'0lixbItaiEiWGvgU3pmX01CLVdjdVk': (WorkerModifyCloudEvent(modify_time=1451927139, size=85773, checksum=4639ab3c1249973b135fa493afde0b78, doc id=0BxNN8iEiWGvgU3pmX01CLVdjdVk, server mod_time=1451927155, version=None, hasii code=9217158699047651292)]) 2016-01-04 12:05:57,308 -0500 INFO pid=14964 4594950144:CloudWatcher cloud watcher.pyo:1368 GenerateCreateForDescendents() entry CloudEntry(doc_id=0BxNN8iEiWGvgU3pmX01CLVdjdVk,filename=2016 January.docx,modified=1451927139,created=1449324475,acl role=owner,doc_type=DocType.BLOB,remo ved=False,parent_doc ids=set(['0BxNN8iEiWGvgRjcxNnBhRm5RV1k 1]),child_doc_ids=set([]).size=857 73,checksum=4639ab3c1249973b135fa493afde0b78,change_stamp=76776,server mod time=1451927155,is zombie=False,shared=False,recursive_size=None,resource_type=file,version=Rone) parents_added set([]) 2016-01-04 12:05:57,452 -0500 INFO pid=14964 4594950144:CloudWatcher cloud_watcher.pyo:198 HandleSyncConfigSettingsChange generated events=set([]) 2016-01-04 12:09:36,538 -0500 INFO pid=240 140735232422672:MainThread logging_config.pyo:249 OS: Darwin/10.9.5 2016-01-04 12:09:36,568 -0500 INFO pid=240 140735232422672:MainThread logging_config.pyo:252 Google Drive (build 1.26.0707.2863) 2016-01-04 12:09:36,568 -0500 INFO pid=240 140735232422672:MainThread logging_config.pyo:253 SSL: OpenSSL 0.9.8zg 14 July 2015 2016-01-04 12:09:36,645 -0500 INFO pid=240 140735232422672:MainThread GSyncAppDelegate.pyo:198 Application did finish launching. 2016-01-04 12:09:36,665 -0500 INFO pid=240 140735232422672:MainThread service.pyo:132 Creating singleton service instance of '<class 'common.file lock.FileLocki> 1 2016-01-04 12:09:36,666 -0500 INFO pid=240 140735232422672:MainThread service.pyo:132 Creating singleton service instance of '<class 'common.file_lock.FileLock'>' 2016-01-04 12:09:36,677 -0500 INFO pid=240 4522594304:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.service.user.User'>' 2016-01-04 12:09:36,677 -0500 INFO pid=240 4522594304:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.roots.roots.RootManager'>. 2016-01-04 12:09:36,677 -0500 INFO pid=240 service.pyo:132 Creating singleton service 'common.thread_manager.ThreadManager'> 1 2016-01-04 12:09:36,678 -0500 INFO pid=240 service.pyo:132 Creating singleton service 'common.pause_manager.Service'>' 2016-01-04 12:09:36,678 -0500 INFO pid=240 sync_app.pyo:330 Getting TokenStorage... 2016-01-04 12:09:36,678 -0500 INFO pid=240 4522594304:RunAsync-_InitializeSyncAppAsync-1 sync_app.pyo:332 Got token storage: <token_storage.TokenStorage object at 0x109376ed0> 2016-01-04 12:09:36,678 -0500 INFO pid=240 4522594304:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.features.AppFeatures'>' 2016-01-04 12:09:36,679 -0500 INFO pid=240 4522594304:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.cert.Manager'>' 2016-01-04 12:09:36,757 -0500 INFO pid=240 4522594304:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.net.bandwidth.BandwidthThrottler'>' 2016-01-04 12:09:36,759 -0500 INFO pid=240 4522594304:RunAsync-_InitializeSyncAppAsync-1 sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-04 12:09:36,760 -0500 INFO pid=240 4522594304:RunAsync-_InitializeSyncAppAsync-1 sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 12:09:36,761 -0500 INFO pid=240 4522594304:RunAsync-_InitializeSyncAppAsync-1 utils.pyo:737 AddCleanupCallback persistence_sqlite:42a4c048e17442d7af5eb2800a4b5621/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-04 12:09:36,761 -0500 INFO pid=240 4522594304:RunAsync-_InitializeSyncAppAsync-1 utils.pyo:737 AddCleanupCallback persistence_sglite:ea05328f3e3a4671b185f4c39f6b64d0/Users/lgroff/Library/Application 4522594304:RunAsync-_InitializeSyncAppAsync-1 instance of '<class 4522594304:RunAsync-_InitializeSyncAppAsync-1 instance of '<class 4522594304:RunAsync-_InitializeSyncAppAsync-1 EFTA00502504 Support/Google/Drive/user_default/sync_config.db 2016-01-04 12:09:36,865 -0500 INFO pid=240 4522594304:RunAsync-_InitializeSyncAppAsync-1 sync_app.pyo:506 BandwidthThrottler configured via sync_config 2016-01-04 12:09:36,865 -0500 INFO pid=240 4522594304:RunAsync-_InitializeSyncAppAsync-1 bandwidth. pyo:59 BandwidthThrottler.SetRxBytesPerSecond 0 2016-01-04 12:09:36,866 -0500 INFO pid=240 4522594304:RunAsync-_InitializeSyncAppAsync-1 bandwidth. pyo:68 BandwidthThrottler.SetTxBytesPerSecond 0 2016-01-04 12:09:36,866 -0500 INFO pid=240 4522594304:RunAsync-_InitializeSyncAppAsync-1 sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 12:09:36,997 -0500 INFO pid=240 4522594304:RunAsync-_InitializeSyncAppAsync-1 sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-04 12:09:37,018 -0500 INFO pid=240 4522594304:RunAsync-_InitializeSyncAppAsync-1 utils.pyo:714 Execute cleanup callback 'persistence_sqlite:42a4c048e17442d7af5eb2800a4b5621/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2016-01-04 12:09:37,030 -0500 INFO pid=240 4522594304:RunAsync-_InitializeSyncAppAsync-1 utils.pyo:714 Execute cleanup callback 'persistence_sqlite:ea05328f3e3a4671b185f4c39f6b64d0/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2016-01-04 12:09:37,030 -0500 INFO pid=240 4531269632:Finderlnjector sync_client_thread.pyo:175 Finderlnjector run loop has started. 2016-01-04 12:09:37,052 -0500 INFO pid=240 140735232422672:MainThread image_resources.py:292 Loading image resources/images/ic_drawer_24.png 2016-01-04 12:09:37,054 -0500 INFO pid=240 140735232422672:MainThread image_resources.py:292 Loading image resources/images/menu_drive-logo.png 2016-01-04 12:09:37,056 -0500 INFO pid=240 140735232422672:MainThread image_resources.py:292 Loading image resources/images/menu_google-logo-gray.png 2016-01-04 12:09:37,061 -0500 INFO pid=240 140735232422672:MainThread image_resources.py:292 Loading image resources/images/ic_folder_mydrive_24.png 2016-01-04 12:09:37,063 -0500 INFO pid=240 140735232422672:MainThread image_resources.py:292 Loading image resources/images/ic_web_24.png 2016-01-04 12:09:37,066 -0500 INFO pid=240 140735232422672:MainThread image_resources.py:292 Loading image resources/images/ic_sync_problem_24.png 2016-01-04 12:09:37,079 -0500 INFO pid=240 140735232422672:MainThread image_resources.py:292 Loading image resources/images/ic_done.png 2016-01-04 12:09:37,345 -0500 INFO pid=240 4545372160:ThreadMonitor thread_manager.pyo:336 Waiting on exit condition. Wake up after 3600s. 2016-01-04 12:09:37,346 -0500 INFO pid=240 4549578752:_LaunchSyncApp sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user default/snapshot.db 2016-01-04 12:09:37,352 -0500 INFO pid=240 4549578752:_LaunchSyncApp sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 12:09:37,353 -0500 INFO pid=240 4549578752:_LaunchSyncApp utils.pyo:737 AddCleanupCallback persistence_sqlite:837ebe4bb27b4328becfllbad0fc8a06/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-04 12:09:37,353 -0500 INFO pid=240 4549578752:_LaunchSyncApp utils.pyo:737 AddCleanupCallback persistence_sqlite: f6cc3462873640d49401f2fdc5abb425/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 12:09:37,383 -0500 INFO pid=240 4549578752:_LaunchSyncApp user.pyo:66 Initializing User instance with new credentials. None 2016-01-04 12:09:37,384 -0500 INFO pid=240 4549578752:_LaunchSyncApp docs_client_factory.pyo:89 Creating a new DriveClient 2016-01-04 12:09:37,384 -0500 INFO pid=240 4549578752:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.quota.QuotaInfo•>' 2016-01-04 12:09:37,385 -0500 INFO pid=240 4549578752:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.token_bucket.CloudBucketManager'>' 2016-01-04 12:09:37,385 -0500 INFO pid=240 4549578752:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.drive_api.drive_resource.Manager'>' 2016-01-04 12:09:37,385 -0500 INFO pid=240 4549578752: LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common._drive_api.http.Manager'>. EFTA00502505 2016-01-04 12:09:37,385 -0500 INFO pid=240 4549578752:_LaunchSyncApp drive_resource.pyo:130 Initialize apiclient Drive resource for current user token 2016-01-04 12:09:37,416 -0500 INFO pid=240 4549578752:_LaunchSyncApp drive_resource.pyo:178 Drive SDK baseUrl = https://www.googleapis.com/drive/v2internal/ 2016-01-04 12:09:37,430 -0500 INFO pid=240 4549578752:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.drive_api.http.AccessTokenInMemoryCache'> 1 2016-01-04 12:09:37,430 -0500 INFO pid=240 4549578752:_LaunchSyncApp drive_request.pyo:285 Drive request: About(tags=Reason.SYNC_OTHER) - Read request - None 2016-01-04 12:09:37,433 -0500 INFO pid=240 4549578752:_LaunchSyncApp discovery.pyo:762 URL being requested: GET https://www.googleapis.com/drive/v2internal/about? fields=rootFolderId%2CquotaBytesTotal%2CquotaBytesUsedAggregate%2CquotaBytesUsedInTrash%2Cquo taType%2Cuser%2FemailAddress&reason=304Salt=json 2016-01-04 12:09:37,434 -0500 WARNING pid=240 4541165568:IpcServerThread server.pyo:145 Socket (/Users/lgroff/Library/Application Support/Google/Drive/GoogleDrivelpcPipe) already in use, deleting it. 2016-01-04 12:09:37,434 -0500 INFO pid=240 4549578752:_LaunchSyncApp client.pyo:515 Attempting refresh to obtain initial access_token 2016-01-04 12:09:37,435 -0500 INFO pid=240 4549578752:_LaunchSyncApp client.pyo:755 Refreshing access_token 2016-01-04 12:09:37,446 -0500 INFO pid=240 4549578752:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.net.dapper.DapperService'>' 2016-01-04 12:09:37,447 -0500 INFO pid=240 4549578752:_LaunchSyncApp http.pyo:248 Request user-agent: Python-httplib2/0.9 (gzip) 2016-01-04 12:09:37,447 -0500 INFO pid=240 4549578752:_LaunchSyncApp sync_http_client.pyo:236 Opening direct connecton to accounts.google.com:443. 2016-01-04 12:09:37,573 -0500 INFO pid=240 4549578752:_LaunchSyncApp http.pyo:267 Response: 151 bytes - application/json; charset=utf-8 (encoding:gzip) 2016-01-04 12:09:37,574 -0500 INFO pid=240 4549578752:_LaunchSyncApp http.pyo:248 Request user-agent: googledrivesync-1.26.0707.2863 google-api-python-client/1.3.1 (gzip) (Darwin/10.9.5) 2016-01-04 12:09:37,574 -0500 INFO pid=240 4549578752:_LaunchSyncApp sync_http_client.pyo:236 Opening direct connecton to www.googleapis.com:443. 2016-01-04 12:09:38,115 -0500 INFO pid=240 140735232422672:MainThread sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-04 12:09:38,127 -0500 INFO pid=240 140735232422672:MainThread sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 12:09:38,128 -0500 INFO pid=240 140735232422672:MainThread sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-04 12:09:38,128 -0500 INFO pid=240 140735232422672:MainThread sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 12:09:38,205 -0500 INFO pid=240 4549578752:_LaunchSyncApp http.pyo:267 Response: 241 bytes - application/json; charset=UTF-8 (encoding:gzip) 2016-01-04 12:09:38,767 -0500 INFO pid=240 4549578752:_LaunchSyncApp drive_request.pyo:292 Drive response: Request (None): About(tags=Reason.SYNC OTHER) Response: About(root_id=0ABNN8iEiWGvgUk9PVA, email_address=lesley.jee@gmail.com, quota_bytes_total=16106127360, quota_bytes_used_aggregate=15360081387, quota bytes used_in_trash=405074503, quota_type=LIMITED) 2016-01-04 12:09:38,768 -0500 INFO pid=240 4549578752:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.drive_api.doc_id_provider.Store'>' 2016-01-04 12:09:38,768 -0500 INFO pid=240 4549578752:_LaunchSyncApp user.pyo:66 Initializing User instance with new credentials. lesley.jee@gmail.com 2016-01-04 12:09:38,768 -0500 INFO pid=240 4549578752:_LaunchSyncApp sync_app.pyo:1235 Configuring sync app from feature switches. 2016-01-04 12:09:38,768 -0500 INFO pid=240 4549578752:_LaunchSyncApp features.pyo:112 Loads feature switches. 2016-01-04 12:09:38,768 -0500 INFO pid=240 4549578752:_LaunchSyncApp feature_switch_manager.pyo:644 Calling server to get feature switches. EFTA00502506 2016-01-04 12:09:38,769 -0500 INFO pid=240 4549578752:_LaunchSyncApp drive_request.pyo:285 Drive request: ListFeatureSwitches(tags=Reason.SYNC_OTRER) - Read request - lesley.jee@gmail.com 2016-01-04 12:09:38,771 -0500 INFO pid=240 4549578752:_LaunchSyncApp discovery.pyo:762 UAL being requested: GET https://www.googleapis.com/drive/v2internal/settings? fields=items%2Fkey%2Citems%2Fvalue&reason=304&namespace=FEATURE_SWITCHfialt=json 2016-01-04 12:09:38,771 -0500 INFO pid=240 4549578752:_LaunchSyncApp http.pyo:248 Request user-agent: googledrivesync-1.26.0707.2863 google-api-python-client/1.3.1 (gzip) (Darwin/10.9.5) 2016-01-04 12:09:38,831 -0500 INFO pid=240 4549578752:_LaunchSyncApp http.pyo:267 Response: 1434 bytes - application/json; charset=UTF-8 (encoding:gzip) 2016-01-04 12:09:38,866 -0500 INFO pid=240 4549578752:_LaunchSyncApp drive_request.pyo:292 Drive response: Request (lesley.jee@gmail.com): ListFeatureSwitches(tags=Reason.SYNC_OTHER) Response: (u'enable_impressions_library': u'false', u'selective_sub_folder_sync.: u'false', u'crash_throttlepercentage': u'99.7', u'enable_feedback': u'true', u'enable_usb_detection': u'false', u'max_page_size_selective_sync': u'1000', u'enable_shared_move_confirmation': u'false', u'enable_disk_check_on_preferences': u'false', u'enable_recursive_size': u'false', u'enable_push': u'true', u'enable_sync_options_in_setup_flow': u'false', u'enable_multipause': u'true', u'log_backup_count': u'0', u'enable_arbitrary_sync': u'false', u'enable_disk_check_on_setup': u'false', u'maxbatch_upload_files': u'30', u'cloud_graph_disk_generation': u'2', u'use_drive_api': u'false', u'query_strategy': u'all', u'enable_multipleaccounts': u'false', u'perf_throttle_percentage': u'100', u'test.feature_switch.name': u") 2016-01-04 12:09:38,868 -0500 INFO pid=240 4549578752:_LaunchSyncApp sync_app.pyo:1260 Feature Switches: FeatureSwitchSettings( accept_blob_download_gzip_encoding=True, backup_polling_interval_secs=7200, change_filters=('DRIVE_SYNC'), cloud_graph_disk_generation=2, crash_log_size_limit=10000000, crash_throttle_percentage=99.7, disabled_cloud_graph_disk_app_versions=(), docs_list_page_size=1000, download_in_place=False, download_progress_bar=False, download_strategy=turl_template', download_url='https://googledrive.com/p/host/(doc_id}', enable_arbitrary_sync=False, enable_batch_upload=True, enable_change_filters=False, enable_context_menu=True, enable_dapper_trace=False, enable_disk_dict=False, enable_feedback=True, enable_f ile_sync_status=True, enable_impressions_library=False, enable_local_v2=False, enable_multipause=True, enable_multiple_accounts=False, enable_native_opener=True, enable_push=True, enable_recursive_size=False, enableshared_moveconfirmation=False, enable_usb_detection=False, feedback_type='prod', ghost_all_files=False, help_ur1='https://support.google.com/drive/?h1=%s&p=desktop_homel, impressions_heartbeat_interval_secs=10800, impressions_upload_interval_secs=1800, log_backup_count=0, log_size_mb=256, EFTA00502507 logging_level='info', max_batch_upload_files=30, newdocument_url=thttps://docs.google.com/document?usp=drive_sync', new_presentationur1= 1https://docs.google.com/presentation?usp=drive_synci, new_spreadsheet_ur1='https://docs.google.com/spreadsheets?usp=drive_synci, numworkers=3, open_ur1='https://docs.google.com/open?id=(doc_id)', overlays_enabled_finder_versions=[ 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_clientversion=1, push_keepalive_interval=72000.0, query_strategy=u'all', 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,) 2016-01-04 12:09:39,088 -0500 INFO pid=240 4549578752:_LaunchSyncApp logging_config.pyo:249 OS: Darwin/10.9.5 2016-01-04 12:09:39,090 -0500 INFO pid=240 4549578752:_LaunchSyncApp logging_config.pyo:252 Google Drive (build 1.26.0707.2863) 2016-01-04 12:09:39,090 -0500 INFO pid=240 4549578752:_LaunchSyncApp logging_config.pyo:253 SSL: OpenSSL 0.9.8zg 14 July 2015 2016-01-04 12:09:39,090 -0500 INFO pid=240 4549578752:_LaunchSyncApp sync_app.pyo:1269 Config: Email: lesley.jee@gmail.com Sync root: /Users/lgroff/Google Drive Sync collections: set([]) Upgrade number: 22 App version: 1.26.0707.2863 Selective sync: False Cloud Graph generation: 2 Folder sync: [) Local app whitelist: set([]) Local app blacklist: set([]) Bandwidth RX Rate kpBs: 0 Bandwidth TX Rate kpBs: 0 Yosemite icons upgraded: False Win10 icons upgraded: False USE sync enabled: True Do sync default folder: True 2016-01-04 12:09:39,093 -0500 INFO pid=240 4549578752:_LaunchSyncApp sync_app.pyo:1274 Switching cloud client based on new feature switches 2016-01-04 12:09:39,093 -0500 INFO pid=240 4549578752:_LaunchSyncApp docs_client_factory.pyo:97 Creating a new Wapi DocsClient 2016-01-04 12:09:39,094 -0500 INFO pid=240 4549578752:_LaunchSyncApp telemetry.pyo:228 SetPushClientVersion: 1 (3, 20130807) 2016-01-04 12:09:39,094 -0500 INFO pid=240 4549578752:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.push.telemetry.PushTelemetry'>' 2016-01-04 12:09:39,094 -0500 INFO pid=240 4549578752:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.telemetry.telemetry_manager.TelemetryManager'>. 2016-01-04 12:09:39,547 -0500 INFO pid=240 4549578752:_LaunchSyncApp image_resources.py:268 Loading custom image resources/images/folder-mac.icns with function _CreateNSlmage 2016-01-04 12:09:39,549 -0500 INFO pid=240 4549578752:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.roots.root_wrangler.RootWrangler'>' 2016-01-04 12:09:39,549 -0500 INFO pid=240 4549578752:_LaunchSyncApp root_wrangler.pyo:65 EFTA00502508 RootWrangler.RootObserverRegistered with roots (_MyDriveRoot(u'/Users/lgroff/Google Drive', 'root', <RootState.INITED: 2>)] 2016-01-04 12:09:39,549 -0500 INFO pid=240 4549578752:_LaunchSyncApp root_wrangler.pyo:73 RootWrangler.RootsAdded with roots [_MyDriveRoot(u'/Users/lgroff/Google Drive', 'root', <RootState.INITED: 2>)] 2016-01-04 12:09:39,550 -0500 INFO pid=240 4549578752:_LaunchSyncApp root_wrangler.pyo:79 RootWrangler got inited root _MyDriveRoot(u'/Users/lgroff/Google Drive', 'root', INITED: 2>) 12:09:39,550 aggregation 12:09:39,550 aggregation 12:09:39,965 aggregation 12:09:39,966 aggregation 12:09:39,966 aggregation 12:09:39,966 aggregation 12:09:39,966 aggregation 12:09:39,966 aggregation 12:09:40,097 <RootState. 2016-01-04 Registering 2016-01-04 Registering 2016-01-04 Registering 2016-01-04 Registering 2016-01-04 Registering 2016-01-04 Registering 2016-01-04 Registering 2016-01-04 Registering 2016-01-04 filewatcher_ext initialized 2016-01-04 12:09:40,098 -0500 INFO pid=240 454 docs_client_factory.pyo:97 Creating a new Wapi 2016-01-04 12:09:40,099 -0500 INFO pid=240 454 docs_client_factory.pyo:97 Creating a new Wapi 2016-01-04 12:09:40,099 -0500 INFO pid=240 454 docs_client_factory.pyo:97 Creating a new Wapi 2016-01-04 12:09:40,100 -0500 INFO pid=240 454 docs_client_factory.pyo:97 Creating a new Wapi 2016-01-04 12:09:40,101 -0500 INFO pid=240 454 docs_client_factory.pyo:97 Creating a new Wapi 2016-01-04 12:09:40,101 -0500 INFO pid=240 454 docs_client_factory.pyo:97 Creating a new Wapi 2016-01-04 12:09:40,102 -0500 INFO pid=240 454 docs_client_factory.pyo:97 Creating a new Wapi 2016-01-04 12:09:40,103 -0500 INFO pid=240 454 Configure storage: <common.push.tango.storage. type 'invalidation::Storage *' at 0xl0f77ec30> 2016-01-04 12:09:40,103 -0500 INFO pid=240 454 Creating client thread 2016-01-04 12:09:40,103 -0500 INFO pid=240 4549578752:_LaunchSyncApp push_xmpp_client.pyo:223 Using talk server: talk.google.com:5222 2016-01-04 12:09:40,103 -0500 INFO pid=240 4549578752:_LaunchSyncApp xmpplib_notifier.pyo:68 Creating notification bridge 2016-01-04 12:09:40,105 -0500 INFO pid=240 4549578752:_LaunchSyncApp docs_client_factory.pyo:97 Creating a new Wapi DocsClient 2016-01-04 12:09:40,107 -0500 INFO pid=240 4566360064:LocalWatcher watcher.pyo:347 LocalWatcher Run invoked.Waiting for first pass of local scan from poller to start 2016-01-04 12:09:40,108 -0500 INFO pid=240 4574773248:RawEventUpgrader sync_client_thread.pyo:175 RawEventUpgrader run loop has started. 2016-01-04 12:09:40,108 -0500 INFO pid=240 4570566656:Worker-2 master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-04 12:09:40,109 -0500 INFO pid=240 4578979840:TelemetryUploaderThread sync_client_thread.pyo:175 TelemetryUploaderThread run loop has started. 2016-01-04 12:09:40,109 -0500 INFO pid=240 4587393024:LocalFSPoller sync_client_thread.pyo:175 LocalFSPoller run loop has started. 2016-01-04 12:09:40,110 -0500 INFO pid=240 4600012800:Worker-1 master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db -0500 INFO rule type: -0500 INFO rule type: -0500 INFO rule type: -0500 INFO rule type: -0500 INFO rule type: -0500 INFO rule type: -0500 INFO rule type: -0500 INFO rule type: -0500 INFO pid=240 4549578752:_LaunchSyncApp rule_factory.pyo:159 common.aggregator_rules.downloads.DownloadCreate pid=240 4549578752:_LaunchSyncApp rule_factory.pyo:159 common.aggregator_rules.downloads.DownloadModify pid=240 4549578752: LaunchSyncApp rule_factory.pyo:159 common.aggregator_rules.uploads.UploadCreate pid=240 4549578752:_LaunchSyncApp rule_factory.pyo:159 common.aggregator_rules.uploads.UploadDelete pid=240 4549578752:_LaunchSyncApp rule_factory.pyo:159 common.aggregator_rules.uploads.UploadModify pid=240 4549578752:_LaunchSyncApp rule_factory.pyo:159 common.aggregator_rules.uploads.UploadMove pid=240 4549578752: LaunchSyncApp rule_factory.pyo:159 common.aggregator_rules.uploads.UploadMoveFrom pid=240 4549578752: LaunchSyncApp rule_factory.pyo:159 common.aggregator_rules.uploads.UploadMoveTo pid=240 4562153472:FilewatcherMonitor filewatcher.pyo:71 9578752:_LaunchSyncApp DocsClient 9578752:_LaunchSyncApp DocsClient 9578752:_LaunchSyncApp DocsClient 9578752:_LaunchSyncApp DocsClient 9578752:_LaunchSyncApp DocsClient 9578752:_LaunchSyncApp DocsClient 9578752:_LaunchSyncApp DocsClient 9578752:_LaunchSyncApp xmpplib_notifier.pyo:34 SyncConfigStorage; proxy of <Swig Object of 9578752:_LaunchSyncApp xmpplib_notifier.pyo:59 sqlite.pyo:276 Open sqlite.pyo:276 Open EFTA00502509 2016-01-04 12:09:40,111 -0500 INFO pid=240 4604219392:PushNotificationKeepaliveThread sync_client_thread.pyo:175 PushNotificationKeepaliveThread run loop has started. 2016-01-04 12:09:40,111 -0500 INFO pid=240 4608425984:XmppClient telemetry.pyo:153 Push state None => PushStates.CONNECTING 2016-01-04 12:09:40,112 -0500 INFO pid=240 4612632576:LocalDiskSpaceChecker sync_client_thread.pyo:175 LocalDiskSpaceChecker run loop has started. 2016-01-04 12:09:40,113 -0500 INFO pid=240 4604219392:PushNotificationKeepaliveThread sync_http_client.pyo:236 Opening direct connecton to accounts.google.com:443. 2016-01-04 12:09:40,113 -0500 INFO pid=240 4616839168:CloudWatcher sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-04 12:09:40,113 -0500 INFO pid=240 4621045760:Batcher sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-04 12:09:40,113 -0500 INFO pid=240 4625252352:CrashReportUploaderThread crash_report_uploader_thread.pyo: 60 Starting CrashReportUploaderThread. 2016-01-04 12:09:40,114 -0500 INFO pid=240 4633665536:OverlayStatusCleaner sync_client_thread.pyo:175 OverlayStatusCleaner run loop has started. 2016-01-04 12:09:40,114 -0500 INFO pid=240 4629458944:Worker-0 sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-04 12:09:40,114 -0500 INFO pid=240 4637872128:FeatureSwitchPoller sync_client_thread.pyo:175 FeatureSwitchPoller run loop has started. 2016-01-04 12:09:40,114 -0500 INFO pid=240 4642078720:TelemetryStoreSizeMonitorThread sync_client_thread.pyo:175 TelemetryStoreSizeMonitorThread run loop has started. 2016-01-04 12:09:40,114 -0500 INFO pid=240 4646285312:PushTelemetryThread sync_client_thread.pyo:175 PushTelemetryThread run loop has started. 2016-01-04 12:09:40,115 -0500 INFO pid=240 4650491904:PauseTelemetryThread sync_client_thread.pyo:175 PauseTelemetryThread run loop has started. 2016-01-04 12:09:40,115 -0500 INFO pid=240 4549578752:_LaunchSyncApp drive_extension_installer.pyo:174 Uninstalling extension nknebiagdodnminbdpflhpkgfpeijdbf 2016-01-04 12:09:40,242 -0500 INFO pid=240 4608425984:XmppClient connection_task.pyo:151 Recalculated LoginTask timeout: 16.000000 2016-01-04 12:09:40,246 -0500 INFO pid=240 4637872128:FeatureSwitchPoller feature_switch_manager.pyo:644 Calling server to get feature switches. 2016-01-04 12:09:40,246 -0500 INFO pid=240 4642078720:TelemetryStoreSizeMonitorThread telemetry_store_monitor_thread.pyo: 44 Checking Telemetry store size. 2016-01-04 12:09:40,248 -0500 INFO pid=240 4608425984:XmppClient telemetry.pyo:167 PushStates.CONNECTING: +0.136805 => 0.136805 2016-01-04 12:09:40,248 -0500 INFO pid=240 4637872128:FeatureSwitchPoller drive_resource.pyo:130 Initialize apiclient Drive resource for lesley.jee@gmail.com 2016-01-04 12:09:40,249 -0500 INFO pid=240 4549578752:_LaunchSyncApp utils.pyo:714 Execute cleanup callback 'persistence_sqlite:837ebe4bb27b4328becfllbad0fc8a06/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2016-01-04 12:09:40,249 -0500 INFO pid=240 4608425984:XmppClient xmpplib.pyo:80 Connecting to talk.google.com:5222 2016-01-04 12:09:40,249 -0500 INFO pid=240 4637872128:FeatureSwitchPoller drive_resource.pyo: 178 Drive SDK baseUrl = https://www.googleapis.com/drive/v2internal/ 2016-01-04 12:09:40,249 -0500 INFO pid=240 4549578752:_LaunchSyncApp sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-04 12:09:40,250 -0500 INFO pid=240 4570566656:Worker-2 sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 12:09:40,253 -0500 INFO pid=240 4600012800:Worker-1 master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 12:09:40,255 -0500 INFO pid=240 4621045760:Batcher master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 12:09:40,255 -0500 INFO pid=240 4616839168:CloudWatcher master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 12:09:40,256 -0500 INFO pid=240 4629458944:Worker-0 sqlite.pyo:276 Open sqlite.pyo:276 Open sqlite.pyo:276 Open sqlite.pyo:276 Open EFTA00502510 master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 12:09:40,256 -0500 INFO pid=240 4549578752:_LaunchSyncApp utils.pyo:714 Execute cleanup callback 'persistence_sqlite:f6cc3462873640d49401f2fdc5abb425/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2016-01-04 12:09:40,287 -0500 INFO pid=240 4608425984:XmppClient telemetry.pyo:167 PushStates.CONNECTING: +0.0389774 => 0.175782 2016-01-04 12:09:40,297 -0500 INFO pid=240 4637872128:FeatureSwitchPoller drive_request.pyo:285 Drive request: ListFeatureSwitches(tags=Reason.SYNC_OTHER) - Read request - lesley.jee@gmail.com 2016-01-04 12:09:40,298 -0500 INFO pid=240 4549578752:_LaunchSyncApp sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 12:09:40,299 -0500 INFO pid=240 4608425984:XmppClient telemetry.pyo:153 Push state PushStates.CONNECTING => PushStates.NEGOTIATING 2016-01-04 12:09:40,302 -0500 INFO pid=240 4637872128:FeatureSwitchPoller discovery.pyo:762 URL being requested: GET https://www.googleapis.com/drive/v2internal/settings? fields=items%2Fkey%2Citems%2Fvalue&reason=304finamespace=FEATURE_SWITCHfialt=json 2016-01-04 12:09:40,304 -0500 INFO pid=240 4637872128:FeatureSwitchPoller client.pyo:515 Attempting refresh to obtain initial access_token 2016-01-04 12:09:40,304 -0500 INFO pid=240 4637872128:FeatureSwitchPoller client.pyo:735 Updated access_token read from Storage 2016-01-04 12:09:40,303 -0500 INFO pid=240 4570566656:Worker-2 utils.pyo:737 AddCleanupCallback persistence_sqlite:3abcd4d3632f44aa805152d0dfe49d63/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-04 12:09:40,303 -0500 INFO pid=240 4621045760:Batcher utils.pyo:737 AddCleanupCallback persistence_sqlite:b796d7328dc04601a75b8a639847b29c/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-04 12:09:40,303 -0500 INFO pid=240 4629458944:Worker-0 utils.pyo:737 AddCleanupCallback persistence_sqlite:eb81e592cde94f0la9aa341b2fc71046/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-04 12:09:40,303 -0500 INFO pid=240 4600012800:Worker-1 utils.pyo:737 AddCleanupCallback persistence_sqlite:6557e01081084a27ad660e9a25090081/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-04 12:09:40,304 -0500 INFO pid=240 4616839168:CloudWatcher utils.pyo:737 AddCleanupCallback persistence_sqlite:801e21f6e0b941db8799b6ea52c7a4fe/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-04 12:09:40,305 -0500 INFO pid=240 4637872128:FeatureSwitchPoller http.pyo:248 Request user-agent: googledrivesync-1.26.0707.2863 google-api-python-client/1.3.1 (gzip) (Darwin/10.9.5) 2016-01-04 12:09:40,306 -0500 INFO pid=240 4570566656:Worker-2 utils.pyo:737 AddCleanupCallback persistence_sqlite:cac39cc736314dbd88968d2747b2a5a2/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 12:09:40,306 -0500 INFO pid=240 4621045760:Batcher utils.pyo:737 AddCleanupCallback persistence_sqlite:0ecle02dc0f74d13b502d70e4c7fdd38/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 12:09:40,306 -0500 INFO pid=240 4629458944:Worker-0 utils.pyo:737 AddCleanupCallback persistence_sqlite:8217316549cc4b3887d21c9140b39260/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 12:09:40,306 -0500 INFO pid=240 4600012800:Worker-1 utils.pyo:737 AddCleanupCallback persistence_sqlite:d6b88a5f6b2846cfa34dc28bf51d829c/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-04 12:09:40,306 -0500 INFO pid=240 4616839168:CloudWatcher utils.pyo:737 AddCleanupCallback persistence_sqlite:0712d7d50d7e4c4d8b53b79c96e1e06f/Users/lgroff/Library/Application EFTA00502511 Support/Google/Drive/user_default/sync_config.db 2016-01-04 12:09:40,307 -0500 INFO pid=240 4637872128:FeatureSwitchPoller sync_http_client.pyo:236 Opening direct connecton to www.googleapis.com:443. 2016-01-04 12:09:40,307 -0500 INFO pid=240 4570566656:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadChangeACL 2016-01-04 12:09:40,308 -0500 INFO pid=240 4629458944:Worker-0 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadChangeACL 2016-01-04 12:09:40,308 -0500 INFO pid=240 4600012800:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadChangeACL 2016-01-04 12:09:40,378 -0500 INFO pid=240 4608425984:XmppClient xmpplib.pyo:420 Start SSL peer=gmail.com 2016-01-04 12:09:40,406 -0500 INFO pid=240 4604219392:PushNotificationKeepaliveThread sync_http_client.pyo:236 Opening direct connecton to clients3.google.com:443. 2016-01-04 12:09:40,420 -0500 INFO pid=240 4616839168:CloudWatcher cloud_graph.pyo:115 Initialize persistent graph with generation 2 2016-01-04 12:09:40,421 -0500 INFO pid=240 4570566656:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadCreate 2016-01-04 12:09:40,421 -0500 INFO pid=240 4629458944:Worker-0 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadCreate 2016-01-04 12:09:40,421 -0500 INFO pid=240 4600012800:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadCreate 2016-01-04 12:09:40,422 -0500 INFO pid=240 4616839168:CloudWatcher cloud_graph.pyo:1563 Current sync config generation: 2 2016-01-04 12:09:40,422 -0500 INFO pid=240 4570566656:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadDelete 2016-01-04 12:09:40,422 -0500 INFO pid=240 4629458944:Worker-0 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadDelete 2016-01-04 12:09:40,422 -0500 INFO pid=240 4600012800:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadDelete 2016-01-04 12:09:40,423 -0500 INFO pid=240 4570566656:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadModify 2016-01-04 12:09:40,423 -0500 INFO pid=240 4629458944:Worker-0 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadModify 2016-01-04 12:09:40,423 -0500 INFO pid=240 4600012800:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadModify 2016-01-04 12:09:40,423 -0500 INFO pid=240 4570566656:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadMove 2016-01-04 12:09:40,423 -0500 INFO pid=240 4629458944:Worker-0 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadMove 2016-01-04 12:09:40,423 -0500 INFO pid=240 4600012800:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadMove 2016-01-04 12:09:40,423 -0500 INFO pid=240 4570566656:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadRename 2016-01-04 12:09:40,423 -0500 INFO pid=240 4629458944:Worker-0 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadRename 2016-01-04 12:09:40,424 -0500 INFO pid=240 4600012800:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadRename 2016-01-04 12:09:40,425 -0500 INFO pid=240 4570566656:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.uploads.BatchUploadCreate 2016-01-04 12:09:40,425 -0500 INFO pid=240 4629458944:Worker-0 command_factory.pyo:186 Registering worker command type: common.worker.uploads.BatchUploadCreate 2016-01-04 12:09:40,426 -0500 INFO pid=240 4616839168:CloudWatcher cloud_graph.pyo:1415 Cleanup cloud graph directory '/Users/lgrof f/Library/Application Support/Google/Drive/user_default/cloud_graph' of files 'dict_*.db* 1 except ['dict_2.db 1, ldict_2.db-shml, 'dict_2.db-wal'] 2016-01-04 12:09:40,426 -0500 INFO pid=240 4600012800:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.uploads.BatchUploadCreate 2016-01-04 12:09:40,426 -0500 INFO pid=240 4570566656:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.uploads.UploadCreate 2016-01-04 12:09:40,483 -0500 INFO pid=240 4608425984:XmppClient PushStates.NEGOTIATING: 40.195906 => 0.195906 2016-01-04 12:09:40,484 -0500 INFO pid=240 4629458944:Worker-0 Registering worker command type: common.worker.uploads.UploadCreate 2016-01-04 12:09:40,485 -0500 INFO pid=240 4600012800:Worker-1 Registering worker command type: common.worker.uploads.UploadCreate telemetry.pyo: 167 command_factory.pyo: 186 command_factory.pyo:186 EFTA00502512 2016-01-04 12:09:40,485 -0500 INFO pid=240 4570566656:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.uploads.UploadDelete 2016-01-04 12:09:40,485 -0500 INFO pid=240 4629458944:Worker-0 command_factory.pyo:186 Registering worker command type: common.worker.uploads.UploadDelete 2016-01-04 12:09:40,485 -0500 INFO pid=240 4600012800:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.uploads.UploadDelete 2016-01-04 12:09:40,485 -0500 INFO pid=240 4570566656:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.uploads.UploadModify 2016-01-04 12:09:40,486 -0500 INFO pid=240 4629458944:Worker-0 command_factory.pyo:186 Registering worker command type: common.worker.uploads.UploadModify 2016-01-04 12:09:40,486 -0500 INFO pid=240 4600012800:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.uploads.UploadModify 2016-01-04 12:09:40,486 -0500 INFO pid=240 4570566656:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.uploads.UploadMove 2016-01-04 12:09:40,486 -0500 INFO pid=240 4629458944:Worker-0 command_factory.pyo:186 Registering worker command type: common.worker.uploads.UploadMove 2016-01-04 12:09:40,486 -0500 INFO pid=240 4600012800:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.uploads.UploadMove 2016-01-04 12:09:40,486 -0500 INFO pid=240 4570566656:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.uploads.UploadRename 2016-01-04 12:09:40,486 -0500 INFO pid=240 4629458944:Worker-0 command_factory.pyo:186 Registering worker command type: common.worker.uploads.UploadRename 2016-01-04 12:09:40,487 -0500 INFO pid=240 4600012800:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.uploads.UploadRename 2016-01-04 12:09:40,487 -0500 INFO pid=240 4570566656:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.internals.InternalCreate 2016-01-04 12:09:40,487 -0500 INFO pid=240 4629458944:Worker-0 command_factory.pyo:186 Registering worker command type: common.worker.internals.InternalCreate 2016-01-04 12:09:40,487 -0500 INFO pid=240 4600012800:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.internals.InternalCreate 2016-01-04 12:09:40,487 -0500 INFO pid=240 4570566656:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.internals.InternalModify 2016-01-04 12:09:40,487 -0500 INFO pid=240 4629458944:Worker-0 command_factory.pyo:186 Registering worker command type: common.worker.internals.InternalModify 2016-01-04 12:09:40,488 -0500 INFO pid=240 4600012800:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.internals.InternalModify 2016-01-04 12:09:40,488 -0500 INFO pid=240 4570566656:Worker-2 workers.pyo:127 Waiting for batcher to initialize 2016-01-04 12:09:40,488 -0500 INFO pid=240 4629458944:Worker-0 workers.pyo:127 Waiting for batcher to initialize 2016-01-04 12:09:40,488 -0500 INFO pid=240 4600012800:Worker-1 workers.pyo:127 Waiting for batcher to initialize 2016-01-04 12:09:40,557 -0500 INFO pid=240 4570566656:Worker-2 sync_client_thread.pyo:175 Worker-2 run loop has started. 2016-01-04 12:09:40,558 -0500 INFO pid=240 4629458944:Worker-0 sync_client_thread.pyo:175 Worker-0 run loop has started. 2016-01-04 12:09:40,558 -0500 INFO pid=240 4600012800:Worker-1 sync_client_thread.pyo:175 Worker-1 run loop has started. 2016-01-04 12:09:40,583 -0500 INFO pid=240 4637872128:FeatureSwitchPoller http.pyo:267 Response: 1434 bytes - application/json; charset=UTF-8 (encoding:gzip) 2016-01-04 12:09:40,586 -0500 INFO pid=240 4608425984:XmppClient telemetry.pyo:167 PushStates.NEGOTIATING: 40.10293 => 0.298836 2016-01-04 12:09:40,590 -0500 INFO pid=240 4604219392:PushNotificationKeepaliveThread push_notification_keepalive_thread.pyo:49 Sent push keepalive message at: Mon Jan 4 12:09:40 2016 2016-01-04 12:09:40,714 -0500 INFO pid=240 4608425984:XmppClient task.pyo:254 Task <common.push.xmpp.login_task.LoginTask object at 0x10f8436d0> complete. 2016-01-04 12:09:40,714 -0500 WARNING pid=240 4608425984:XmppClient login_task.pyo:90 Clearing connection for LoginTask: <common.push.xmpp.login_task.LoginTask object at 0xl0f8436d0> 2016-01-04 12:09:40,714 -0500 INFO pid=240 4608425984:XmppClient push_xmpp_client.pyo:313 Connected to talk.google.com:5222 2016-01-04 12:09:40,715 -0500 WARNING pid=240 4549578752:RunAsync-_OnXmppConnect-4 notification_bridge.pyo:126 XMPP detected connect. 2016-01-04 12:09:40,715 -0500 INFO pid=240 4549578752:RunAsync-_OnXmppConnect-4 EFTA00502513 xmpp_network_channel.pyo:169 Sending push channel subscription request 2016-01-04 12:09:40,753 -0500 INFO pid=240 4608425984:XmppClient xmpp_network_channel.pyo:187 Received subscription response from Push for XMPPBot 2016-01-04 12:09:40,754 -0500 INFO pid=240 4549578752:RunAsync-_OnXmppConnect-4 notification_bridge.pyo:142 Starting TICL (client version 3.20130807)... 2016-01-04 12:09:40,754 -0500 INFO pid=240 4549578752:RunAsync-_OnXmppConnect-4 xmpp_network_channel.pyo: 65 XmppNetworkChannel.InformNetworkStatus: CONNECTED 2016-01-04 12:09:40,755 -0500 INFO pid=240 4583186432:ListenerScheduler invalidation_listener.pyo:185 InvalidationListener.ReissueRegistrations(prefix=, prefix_len=0) 2016-01-04 12:09:40,896 -0500 INFO pid=240 4637872128:FeatureSwitchPoller drive_request.pyo:292 Drive response: Request (lesley.jee@gmail.com): ListFeatureSwitches(tags=Reason.SYNC_OTHER) Response: (u'enable_impressions_library': u'false., u'selective_sub_folder_sync.: u'false., u'crash_throttle_percentage': u'99.7', u'enable_feedback': u'true', u'enable_usb_detection': u'false', u'max_page_size_selective_sync': u'1000', u'enable_shared_move_confirmation': u'false', u'enable_disk_check_on_preferences': u'false., u'enable_recursive_size': u'false', u'enable_push': u'true', u'enable_sync_options_in_setup_flow': u'false', u'enable_multipause': u'true', u'log_backup_count': u'0', u'enable_arbitrary_sync': u'false', u'enable_disk_check_on_setup': u'false', u'maxbatch_upload_files': u'30', u'cloud_graph_disk_generation': u'2', u'use_drive_api': u'false', u'query_strategy': u'all', u'enable_multiple_accounts': u'false', u'perf_throttle_percentage': u'100', u'test.featureswitch.name': u") 2016-01-04 12:09:40,979 -0500 INFO pid=240 4616839168:CloudWatcher utils.pyo:737 AddCleanupCal lback DBConnectionManagerCleanupCal lback 2016-01-04 12:09:40,979 -0500 INFO pid=240 4616839168:CloudWatcher db_connection.pyo:200 Created thread connection on database /Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph/dict_2.db 2016-01-04 12:09:41,000 -0500 INFO pid=240 4616839168:CloudWatcher cloud_graph.pyo:956 Reuse CloudDiskDict database: /Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph/dict_2.db 2016-01-04 12:09:41,004 -0500 INFO pid=240 4616839168:CloudWatcher cloud_graph.pyo:155 Initialize persistent graph with existing database /Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph/dict_2.db 2016-01-04 12:09:41,004 -0500 INFO pid=240 4616839168:CloudWatcher cloud_graph.pyo:216 Building gdoc docids cache. 2016-01-04 12:09:41,056 -0500 INFO pid=240 4616839168:CloudWatcher cloud_graph.pyo:161 Reuse CloudDiskDict CloudDiskDict(max_change_stamp=76776, app_version=1.26.0707.2863, ()) 2016-01-04 12:09:41,056 -0500 INFO pid=240 4616839168:CloudWatcher cloud_watcher.pyo:831 Performing full snapshot diff. 2016-01-04 12:09:41,057 -0500 INFO pid=240 4616839168:CloudWatcher cloud_watcher.pyo:1026 Getting cloud resources via changelog feed from 76777 2016-01-04 12:09:41,057 -0500 INFO pid=240 4616839168:CloudWatcher docs_client.pyo:743 Requesting Doc List: /feeds/default/private/changes?start-index=76777&showroot=true&include- shared=true&reason=startup 2016-01-04 12:09:41,058 -0500 INFO pid=240 4616839168:CloudWatcher sync_http_client.pyo:236 Opening direct connecton to clients3.google.com:443. 2016-01-04 12:09:41,175 -0500 INFO pid=240 4616839168:CloudWatcher docs_client.pyo:1181 End of feed, no next URI 2016-01-04 12:09:41,175 -0500 INFO pid=240 4616839168:CloudWatcher cloud_watcher.pyo:807 Got 0 cloud entries 2016-01-04 12:09:41,175 -0500 INFO pid=240 4616839168:CloudWatcher cloud_watcher.pyo:815 720 nodes in the cloud graph 2016-01-04 12:09:41,220 -0500 INFO pid=240 4616839168:CloudWatcher cloud_snapshot_diff_helper.pyo:84 Prepare for Pruning graph (total of 720 entries). sync gdocs=True, sync folders=None, excluded folders=frozenset(()) 2016-01-04 12:09:41,220 -0500 INFO pid=240 4616839168:CloudWatcher cloud_snapshot_diff_helper.pyo:85 Start Prune Graph (total of 720 entries) 2016-01-04 12:09:41,236 -0500 INFO pid=240 4616839168:CloudWatcher cloud_snapshot_diff_helper.pyo:175 Pruning detected 703 items to keep 2016-01-04 12:09:41,237 -0500 INFO pid=240 4616839168:CloudWatcher cloud_snapshot_diff_helper.pyo:182 703 entries in graph after pruning 2016-01-04 12:09:41,237 -0500 INFO pid=240 4616839168:CloudWatcher EFTA00502514 cloud snapshot diff_helper.pyo:93 Compare to snaphost 2016-01-04 12:09:41,237 -0500 INFO pid=240 4616839168:CloudWatcher cloud_snapshot_diff_helper.pyo:762 Starting cloud graph vs snapshot set: None 2016-01-04 12:09:41, cloud snapshot cliff_ 2016-01-04 12:09:41, cloud snapshot cliff_ 2016-01-04 12:09:41, cloud_snapshot_diff_ 237 -0500 INFO helper.pyo:764 240 -0500 INFO helper.pyo:768 327 -0500 INFO helper.pyo:783 2016-01-04 12:09:41,740 -0500 INFO pid=240 4616839168:CloudWatcher cloud_snapshot_diff_helper.pyo:810 Processed 702 entries in total. Number of events detected: () 2016-01-04 12:09:41,813 -0500 INFO pid=240 4616839168:CloudWatcher cloud_watcher.pyo:875 Done with full snapshot diff. 2016-01-04 12:09:42,111 -0500 INFO pid=240 4587393024:LocalFSPoller poller.pyo:126 Cloud scan complete. Preparing to start LocalFSPoller 2016-01-04 12:09:42,111 -0500 INFO pid=240 4587393024:LocalFSPoller LocalFSPoller first pass started. Waiting for localWatcher to start 2016-01-04 12:09:42,112 -0500 INFO pid=240 4566360064:LocalWatcher RootsAdded with roots [_MyDriveRoot(u'/Users/lgroff/Google Drive', 2>)] 2016-01-04 12:09:42,112 -0500 INFO pid=240 4566360064:LocalWatcher adding new root to graph: _MyDriveRoot(u'/Users/lgroff/Google Drive INITED: 2>) 12:09:42,112 -0500 INFO pid=240 4566360064:LocalWatcher watcher.pyo:252 roots: [_MyDriveRoot(u 1/Users/lgroff/Google Drive', 'root', <RootState.INITED: diff with selective sync <RootState. 2016-01-04 Scheduling 2>)] 2016-01-04 12:09:42,112 -0500 INFO pid=240 4566360064:LocalWatcher Scheduling root: _MyDriveRoot(u'/Users/lgroff/Google Drive', 'root', 2016-01-04 12:09:42,132 -0500 INFO pid=240 4566360064:LocalWatcher LocalWatcher is up. 2016-01-04 12:09:42,133 -0500 waiting for LocalWatcher. 2016-01-04 12:09:42,133 -0500 a local disk scan. 2016-01-04 12:09:42,133 -0500 pid=240 4616839168:CloudWatcher Prepopulating D0C_ID-to-inodes cache pid=240 4616839168:CloudWatcher First pass detecting moved entries pid=240 4616839168:CloudWatcher Second pass detecting creations and modification entries poller.pyo:137 watching. watcher.pyo:266 'root', <RootState.INITED: watcher.pyo:271 watcher ', 'root', INFO pid=240 4587393024:LocalFSPoller INFO pid=240 4587393024:LocalFSPoller INFO pid=240 4587393024:LocalFSPoller Walking: [u'/Users/lgroff/Google Drive'] 2016-01-04 12:09:43,192 -0500 INFO pid=240 of found changes: 0 2016-01-04 12:09:43,192 -0500 local disk scan. 2016-01-04 12:09:43,356 -0500 invalidation_listener.pyo:125 REGISTERED) 2016-01-04 12:09:43,356 -0500 WARNING pid=240 4583186432:ListenerScheduler notification_bridge.pyo:202 Invalidation status callback called with connected=True 2016-01-04 12:09:43,356 -0500 INFO pid=240 4583186432:ListenerScheduler telemetry.pyo:167 PushStates.NEGOTIATING: 42.77066 => 3.06949 2016-01-04 12:09:43,356 -0500 INFO pid=240 4583186432:ListenerScheduler telemetry.pyo:153 Push state PushStates.NEGOTIATING => PushStates.ONLINE 2016-01-04 12:09:43,356 -0500 INFO pid=240 4583186432:ListenerScheduler pause_manager.pyo:130 Removing pause reason OFFLINE. Paused: False 2016-01-04 12:09:43,357 -0500 ERROR pid=240 4583186432:ListenerScheduler pause_manager.pyo:438 Tried to get last event time for PauseReason.OFFLINE, but no last event time recorded. 2016-01-04 12:10:11,814 -0500 INFO pid=240 4616839168:CloudWatcher cloud_watcher.pyo:698 Getting changes: elapsed_time: 30.001076545 2016-01-04 12:10:11,815 -0500 INFO pid=240 4616839168:CloudWatcher cloud_watcher.pyo:1026 Getting cloud resources via changelog feed from 76777 2016-01-04 12:10:11,815 -0500 INFO pid=240 4616839168:CloudWatcher docs_client.pyo:743 Requesting Doc List: /feeds/default/private/changes?start-index=76777&showroot=true&include- shared=true&reason=poll 2016-01-04 12:10:11,816 -0500 INFO pid=240 4616839168:CloudWatcher sync_http_client.pyo:236 Opening direct connecton to clients3.google.com:443. watcher.pyo:305 <RootState. INITED: 2>) watcher.pyo:368 4587393024:LocalFSPoller poller.pyo:146 Finished poller.pyo:149 Starting diff_helper.pyo:86 poller.pyo:166 Number INFO pid=240 4587393024:LocalFSPoller poller.pyo:177 Finished INFO pid=240 4583186432:ListenerScheduler InvalidationListener.InformRegistrationStatus(1014:CHANGELOG, EFTA00502515 2016-01-04 12:10:12,035 -0500 INFO pid=240 4616839168:CloudWatcher docs_client.pyo:1181 End of feed, no next URI 2016-01-04 12:10:12,035 -0500 INFO pid=240 4616839168:CloudWatcher cloud_watcher.pyo:198 HandleSyncConfigSettingsChange generated events=set([]) 2016-01-04 12:24:40,304 -0500 INFO pid=240 4642078720:TelemetryStoreSizeMonitorThread telemetry_store_monitor_thread.pyo: 44 Checking Telemetry store size. 2016-01-04 12:28:16,054 -0500 INFO pid=240 4562153472:FilewatcherMonitor watcher.pyo:550 PathsCallback called with: (u'/Users/lgroff/Google Drive/My Documents (1)/', u'/Users/lgroff/Google Drive/1] 2016-01-04 12:28:16,127 -0500 INFO pid=240 4562153472:FilewatcherMonitor diff_helper.pyo:86 Scanning: (u'/Users/lgroff/Google Drive/My Documents (1)', u'/Users/lgroff/Google Drive'] 2016-01-04 12:28:18,217 -0500 INFO pid=240 4562153472:FilewatcherMonitor diff_helper.pyo:133 Found 1 changes: ((FSChange(Direction.UPLOAD, Action.MODIFY, ino=15554209, path=u'/Users/lgroff/Google Drive/My Documents (1)', name=u'2016 January.docx', parent_ino=14390475, new_ino=15554209, is_folder=False, unparents_shared=False, modified=1451928494, checksum=5dbe3118569bc0aed350422c17c142e9, size=85788, generator_module=l, is_cancelled=False, is_priority=False)]] 2016-01-04 12:28:18,218 -0500 INFO pid=240 4562153472:FilewatcherMonitor aggregator.pyo:108 - > Received event ImmutableRawEvent(op=MODIFY, path=/Users/lgroff/Google Drive/My Documents (1)/2016 January.docx, time=1451928498.22, is_dir=False, ino=15554209, size=85788, oldpath=None, new_ino=15554209, mtime=1451928494, parent_ino=14390475, affects_gdoc=None, is_cancelled=RawEventIsCancelledFlag.FALSE, old_parent_ino=None, _hash_ code=-2725774786680448500) FSChange(Direction.UPL0AD, Action.MODIFY, ino=15554209, path=u'/Users/lgroff/Google Drive/My Documents (1)', name=u'2016 January.docx', parent_ino=14390475, new_ino=15554209, is_folder=False, unparents_shared=False, modified=1451928494, checksum=5dbe3118569bc0aed350422c17c142e9, size=85788, generator_module=l, is_cancelled=False, is_priority=False) 2016-01-04 12:28:18,514 -0500 INFO pid=240 4562153472:FilewatcherMonitor change_buffer.pyo:1042 Adding change and event to change buffer: FSChange(Direction.UPLOAD, Action.MODIFY, ino=15554209, path=u'iUsers/lgroff/Google Drive/My Documents (1)', name=u'2016 January.docx', parent_ino=14390475, new_ino=15554209, is_folder=False, unparents_shared=False, modified=1451928494, checksum=5dbe3118569bc0aed350422c17c142e9, size=85788, generator_module=1, is_cancelled=False, ispriority=False) ImmutableRawEvent(op=MODIFY, path=/Users/lgroff/Google Drive/My Documents (1)/2016 January.docx, time=1451928498.22, is_dir=False, ino=15554209, size=85788, old_path=None, new_ino=15554209, mtime=1451928494, parent_ino=14390475, affects_gdoc=None, is_cancelled=RawEventIsCancelledFlag.FALSE, old_parent_ino=None, _hash_code=-2725774786680448500) 2016-01-04 12:28:18,515 -0500 INFO pid=240 4562153472:FilewatcherMonitor watcher.pyo:550 PathsCallback called with: (u'/Users/lgroff/Google Drive/My Documents (1)/', u'/Users/lgroff/Google Drive/'] 2016-01-04 12:28:18,515 -0500 INFO pid=240 4562153472:FilewatcherMonitor diff_helper.pyo:86 Scanning: (u'/Users/lgroff/Google Drive/My Documents (1)', u'/Users/lgroff/Google Drive'] 2016-01-04 12:28:19,044 -0500 INFO pid=240 4562153472:FilewatcherMonitor watcher.pyo:550 PathsCallback called with: (u'/Users/lgroff/Google Drive/My Documents (1)/', u'/Users/lgroff/Google Drive/'] 2016-01-04 12:28:19,045 -0500 INFO pid=240 4562153472:FilewatcherMonitor diff_helper.pyo:86 Scanning: (u'/Users/lgroff/Google Drive/My Documents (1)', u'/Users/lgroff/Google Drive'] 2016-01-04 12:28:19,812 -0500 INFO pid=240 4562153472:FilewatcherMonitor watcher.pyo:550 PathsCallback called with: (u'/Users/lgroff/Google Drive/My Documents (1)/.] 2016-01-04 12:28:19,912 -0500 INFO pid=240 4562153472:FilewatcherMonitor diff_helper.pyo:86 Scanning: (u'/Users/lgroff/Google Drive/My Documents (1)'] 2016-01-04 12:28:23,627 -0500 INFO pid=240 4570566656:Worker-2 workers.pyo:183 Worker starting on (ImmutableChange(Direction.UPLOAD, Action.MODIFY, ino=15554209, path=u'/Users/lgroff/Google Drive/My Documents (1)', name=u'2016 January.docx', parent_ino=14390475, new_ino=15554209, is_folder=False, unparents_shared=False, modified=1451928494, checksum=5dbe3118569bc0aed350422c17c142e9, size=85788, generator_module=l, is_cancelled=False, is_priority=False, hash=5753114237788948750, _constructor_called=True)] 2016-01-04 12:28:24,075 -0500 INFO pid=240 4570566656:Worker-2 proxy_manager.pyo:354 Removing stale proxy entry from cache 2016-01-04 12:28:24,100 -0500 INFO pid=240 4570566656:Worker-2 sync_http_client.pyo:236 Opening direct connecton to clients3.google.com:443. 2016-01-04 12:28:25,820 -0500 INFO pid=240 4570566656:Worker-2 docs client.pyo:2167 Updating: 2016 January.docx (85788 bytes). DocsEntry: <ns0:entry EFTA00502516 xmlns:ns0="http://www.w3.org/2005/Atom" xmlns:nsl="http://schemas.google.com/g/2005" xmlns:ns2="http://schemas.google.com/docs/2007" xmlns:ns3="http://www.w3.org/2007/app" nsl:etag="&quot;AxZUWAtZRCt7ImBl&quot;"><nsl:quotaBytesUsed>85773</nsl:quotaBytesUsed> <ns0:category label="hidden" scheme="http://schemas.google.com/g/2005/labels" term="http://schemas.google.com/g/2005/labels#hidden" /><ns0:category label="has-path-to- root" scheme="http://schemas.google.com/docs/2007" term="http://schemas.google.com/docs/2007#has-path-to-root" /><ns0:category label="application/vnd.openxmlformats-officedocument.wordprocessingml.document" scheme="http://schemas.google.com/g/2005#kind" term="http://schemas.google.com/docs/2007#file" /><ns0:category label="modified-by-me" scheme="http://schemas.google.com/g/2005/1abels" term="http://schemas.google.com/g/2005/labels#modified-by-me" /><ns0:category label="viewed" scheme="http://schemas.google.com/g/2005/1abels" term="http://schemas.google.com/g/2005/1abels#viewed" /><ns0:category label="purgeable" scheme="http://schemas.google.com/g/2005/1abels" term="http://schemas.google.com/g/2005/1abels#purgeable" I> <ns0:id>https://clients3.google.com/feeds/id/file%3A0BxNN8iEiWGvgU3pmX01CLVdjdVk</ns0:id> <ns2:writersCanlnvite value="true" /><ns0:author><nsO:name>lesley.jee</ns0:name> <ns0:email>lesley.jee@gmail.com</ns0:email></ns0:author><ns0:content src="https://doc-Os-bc- docs.googleusercontent.com/docs/securesc/rsu4vkf416fjahebskrbestclo213ea4g/ls6dkb4pc4pu211nngi ghh1s15m112116/1451923200000/07946446228801835265/07946446228801835265/0BxNN8iEiWGvgU3pmX01CLV djdVI0e=download&amp;gd=true" type="application/vnd.openxmlformats- officedocument.wordprocessingml.document" /><nsO:updated>2016-01-04T17:28:14</nsO:updated> <ns0:published>2015-12-05T14:07:55.244Z</ns0:published><nsl:lastViewed>2016-01- 04T15:56:27.7382.</nsl:lastViewed><nsl:lastModifiedBy><ns0:name>lesley.jee</ns0:name> <ns0:email>lesley.jee@gmail.com</ns0:email></nsl:lastModifiedBy><ns0:title>2016 January.docx</ns0:title><nsl:resourceId>file:OBxNN8iEiWGvgU3pmX01CLVdjdVk</nsl:resourceId> <ns0:link href="https://clients3.google.com/feeds/default/private/full/folder%3A0BxNN8iEiWGvgRjcxNnBhRm 5nVlk" rel="http://schemas.google.com/docs/2007#parent" type="application/atom+xml" I> <ns0:link href="https://drive.google.com/file/d/OWENN8iEiWGvgU3pmX01CLVdjdVkiview? usp=docslist_api" rel="alternate" type="text/html" /><ns0:link href="https://ssl.gstatic.com/docs/doclist/images/icon_10_word_list.png" rel="http://schemas.google.com/docs/2007#icon" type="image/png" /><ns0:link href="https://clients3.google.com/feeds/upload/create- session/default/private/full/file%3A0B5ENN8iEiWGvgU3pmX0ICLVdjdVk?new- revision=true&amp;override-modified-timestamp=true&amp;force-modified-timestamp=true" rel="http://schemas.google.com/g/2005#resumable-edit-media" type="application/atom+xml" I> <ns0:link href="https://clients3.google.com/feeds/upload/file/default/private/full/file%3A0BxNN8iEiWGvg U3pmX01CLVdjdVk" rel="http://schemas.google.com/docs/2007#alt-edit-media" type="application/atom+xml" /><ns0:link href="https://1h3.googleusercontent.com/oVqfVCx1_9XStRkmISxpLT9yBWEL7s1Htoa0VETif3r9zr6Nlxhai kN1L1XJKbaX.flf7jw=s220" rel="http://schemas.google.com/docs/2007/thumbnail" type="image/png" /><ns0:link href="https://drive.google.com/sharing/share? subapp=10&amp;id=08xNN8iEiWGvgU3pmX01CLVdjdVkfiamp;shareProtocolVersion=2&amp;foreignService=g oogledrivesync&amp;command=settings&amp;shareUiType=default&amp;authuser=0&amp;client=desktop &amp;hl=en" rel="http://schemas.google.com/docs/2007#share" type="text/html" /><ns0:link href="https://clients3.google.com/feeds/default/private/full/file%3A0BxNN8iEiWGvgU3pmX01CLVdj dVk" rel="self" type="application/atom+xml" /><ns0:link href="https://clients3.google.com/feeds/default/private/full/file%3A0BxNN8iEiWGvgU3pmX01CLVdj dVk?new-revision=true&amp;override-modified-timestamp=true&amp;force-modified-timestamp=true" rel="edit" type="application/atom+xml" /><ns0:link href="https://clients3.google.com/feeds/default/media/file%3A0BxNN8iEiWGvgU3pmX0ICLVdjdVk" rel="edit-media" type="application/vnd.openxmlformats- officedocument.wordprocessingml.document" /><nsl:feedLink href="https://clients3.google.com/feeds/default/private/full/file%3A0BxNN8iEiWGvgU3pmX01CLVdj dVk/acl" rel="http://schemas.google.com/ac1/2007#accessControlList" /><nsl:feedLink href="https://clients3.google.com/feeds/default/private/full/file%3A0BxNN8iEiWGvgU3pmX01CLVdj dVk/revisions" rel="http://schemas.google.com/docs/2007/revisions" /><ns3:edited>2016-01- 04T17:05:55.6832</ns3:edited><ns2:isShareable value="true" /><ns2:isShareableByMe value="true" /><ns2:modifiedByMeDate>2016-01-04T17:05:39.0002.</ns2:modifiedByMeDate> <ns2:md5Checksum>4639ab3c1249973b135fa493afde0b78</ns2:md5Checksum><ns2:filename>2016 January.docx</ns2:filename><ns2:suggestedFilename>2016 January.docx</ns2:suggestedFilename> <ns2:size>85773</ns2:size></ns0:entry> EFTA00502517 2016-01-04 12:28:25,827 -0500 INFO pid=240 4570566656:Worker-2 sync_http_client.pyo:236 Opening direct connecton to upload.drive.google.com:443. 2016-01-04 12:28:27,920 -0500 INFO pid=240 4570566656:Worker-2 snapshot_sqlite.pyo:253 Updating cloud entry doc_id=0BxNN8iEiWGvgU3pm)(01CLVdjdVk, filename=None 2016-01-04 12:28:28,084 -0500 INFO pid=240 4570566656:Worker-2 snapshot_sqlite.pyo:230 Updating local entry inode=15554209, modified=1451928494, checksum=5dbe3118569bc0aed350422c17c142e9, size=85788 2016-01-04 12:28:28,104 -0500 INFO pid=240 4570566656:Worker-2 workers.pyo:194 Worker successfully completed [ImmutableChange(Direction.UPLOAD, Action.MODIFY, ino=15554209, path=u'/Users/lgroff/Google Drive/My Documents (1)', name=u'2016 January.docx', parent_ino=14390475, new_ino=15554209, is_folder=False, unparents_shared=False, modified=1451928494, checksum=5dbe3118569bc0aed350422c17c142e9, size=85788, generator_module=1, is_cancelled=False, is_priority=False, hash=5753114237788948750, _constructor_called=True)) 2016-01-04 12:28:28,215 -0500 INFO pid=240 4583186432:ListenerScheduler invalidation_listener.pyo:82 InvalidationListener.Invalidation(1014:CHANGELOG, wapi_version=76780) 2016-01-04 12:28:28,217 -0500 INFO pid=240 4616839168:CloudWatcher cloud_watcher.pyo:711 Getting changes; observed: 76780, retrieved: 76776 2016-01-04 12:28:28,275 -0500 INFO pid=240 4583186432:ListenerScheduler invalidation_listener.pyo:82 InvalidationListener.Invalidation(1014:CHANGELOG, wapi_version=76781) 2016-01-04 12:28:28,905 -0500 INFO pid=240 4621045760:Batcher batcher.pyo:239 Current batch is completed. Batcher snooze. 2016-01-04 12:28:28,905 -0500 INFO pid=240 4621045760:Batcher sync_status.pyo:133 Reset sync status 2016-01-04 12:28:28,963 -0500 INFO pid=240 4621045760:Batcher batcher.pyo:855 Batcher Stats = file_count = Counter(f_COUNT KEY(direction= UploadDirectionType(Direction.UPLOAD), action= FSChangeActionType(Action.MOBIFY), batch=False, successful=True): 1)), byte_count = Counterif COUNT KEY(direction=_UploadDirectionType(Direction.UPLOAD), action=_FSChangeActionType(Action.MODIFY), batch=False, successful=True): 85788)), batch_operation_count = Counter(), process seconds = Counter(f COUNT KEY(direction=_UploadDireciionType(Direction.UPLOAD), action= FChang;ActionType(Action.MODIFY), batch=False, successful=True): 4.476372957229614)), duration seconds = 7 (start time = 1451928501, end time = 1451928508) 2016-01-04 12:28:28,978 -0500 INFO pid=240 4616839168:CloudWatcher cloud_watcher.pyo:1026 Getting cloud resources via changelog feed from 76777 2016-01-04 12:28:28,978 -0500 INFO pid=240 4616839168:CloudWatcher docs_client.pyo:743 Requesting Doc List: /feeds/default/private/changes?start-index=76777&showroot=true&include- shared=true&reason=change 2016-01-04 12:28:28,979 -0500 INFO pid=240 4616839168:CloudWatcher sync_http_client.pyo:236 Opening direct connecton to clients3.google.com:443. 2016-01-04 12:28:29,153 -0500 INFO pid=240 4616839168:CloudWatcher docs_client.pyo:1174 Feed has 1 entries 2016-01-04 12:28:29,153 -0500 INFO pid=240 4616839168:CloudWatcher docs_client.pyo:1181 End of feed, no next URI 2016-01-04 12:28:29,153 -0500 INFO pid=240 4616839168:CloudWatcher cloud_watcher.pyo:1645 Built recent change dict: ( 1 0BxNN8iEiWGvgU3pm)(01CLVdjdVk': (WorkerModifyCloudEvent(modify_time=1451928494, size=85788, checksum=5dbe3118569bc0aed350422c17c142e9, doc id=0BxNN8iEiWGvgU3pmX01CLVdjdVk, server mod_time=1451928507, version=None, hash_code=-6670949485967542750))} 2016-01-04 12:28:29,207 -0500 INFO pid=240 4616839168:CloudWatcher cloud_watcher.pyo:1368 GenerateCreateForDescendents() entry CloudEntry(doc_id=0BxNN8iEiWGvgU3pm)(01CLVdjdVk,filename=2016 January.docx,modified=1451928494,created=1449324475,acl role=owner,doc_type=DocType.BLOB,remo ved=False,parent doc ids=set(['OBxNN8iEiWGvgRjcxNnIMIRm5TIV1k.)),child_doc_ids=set([]).size=857 88,checksum=5dbe5118S69bcOaed350422c17c142e9,change_stamp=76782,servermod time=1451928507,is zombie=False,shared=False,recursive_size=None,resource_type=file,version=Rone) parents_added set([)) 2016-01-04 12:28:29,271 -0500 INFO pid=240 4616839168:CloudWatcher cloud_watcher.pyo:198 HandleSyncConfigSettingsChange generated events=set([]) 2016-01-04 12:28:34,273 -0500 INFO pid=240 4616839168:CloudWatcher cloud_watcher.pyo:716 Change ignored. observed: 76781, retrieved: 76782 2016-01-04 12:28:38,242 -0500 INFO pid=240 4583186432:ListenerScheduler invalidation_listener.pyo:82 InvalidationListener.Invalidation(1014:CHANGELOG, EFTA00502518 wapi_version=76782) 2016-01-04 12:28:38,242 -0500 INFO pid=240 4616839168:CloudWatcher cloud_watcher.pyo:716 Change ignored. observed: 76782, retrieved: 76782 2016-01-04 12:39:40,474 -0500 INFO pid=240 4642078720:TelemetryStoreSizeMonitorThread telemetry_store_monitor_thread.pyo: 44 Checking Telemetry store size. 2016-01-04 12:54:40,956 -0500 INFO pid=240 4642078720:TelemetryStoreSizeMonitorThread telemetry_store_monitor_thread.pyo: 44 Checking Telemetry store size. 2016-01-04 13:09:07,794 -0500 INFO pid=240 4562153472:FilewatcherMonitor watcher.pyo:550 PathsCallback called with: (u'/Users/lgroff/Google Drive/My Documents (1)/1 ] 2016-01-04 13:09:07,806 -0500 INFO pid=240 4562153472:FilewatcherMonitor diff_helper.pyo:86 Scanning: (u'/Users/lgroff/Google Drive/My Documents (1)'] 2016-01-04 13:09:12,760 -0500 INFO pid=240 140735232422672:MainThread sync_app.pyo:1643 Quit called from: File "/Volumes/BuildData/pulse- data/agents/599758/recipes/859788647/base/b/drb/googleclient/apps/webdrive_sync/osx/dist/Goog le Drive.app/Contents/Resources/ boot .py", line 77, in <module> File "/Volumes/BuildData/pulse- data/agents/599758/recipes/859788647/base/b/drb/googleclient/apps/webdrive_sync/osx/dist/Goog le Drive.app/Contents/Resources/ boot .py", line 62, in _run File "/Applications/Google Drive.app/Contents/Resources/main.py", line 12, in <module> File "osx/run_googledrive.pyo", line 31, in Main File "Py0bjCTools/Appllelper.pyo", line 235, in runEventLoop File "osx/GSyncAppDelegate.pyo", line 297, in applicationWillTerminate_ File "osx/mac_sync_app_controller.pyo", line 252, in Exit File "common/ui_action_handler.pyo", line 450, in Quit File "common/sync_app.pyo", line 1643, in Quit EFTA00502519