EFTA00502202.pdf

From: File "Nolumes/BuildData/pulse- datathgents/563586/recipes/843291236/base/b/drb/googleclient/apps/webdrive_sync/osxklist/Google Drive.app/Contents/Resources/_boot_.py", line 43, in <module> File "Nolumes/BuildData/pulse- datathgents/563586/recipes/843291236/base/b/drb/googleclient/apps/webdrive_sync/osxklist/Google Drive.app/Contents/Resources/_boot_.py", line 38, in _run File "/Applications/Google Drive.app/Contents/Resourceslmain.py", line 12, in <module> File "osx/run_googledrive.pyo", line 31, in Main File "build/bdist.macosx-10.7-intel/egg/PyObjCTools/AppHelper.py", line 235, in runEventLoop File "osx/GSyncAppDelegate.pyo", line 297, in applicationWillTerminate_ File "osx/mac_sync_app_controller.pyo", line 251, in Exit File "common/ui_action_handler.pyo", line 444, in Quit File "common/sync_app.pyo", line 1576, in Quit 2015-11-05 16:58:07,101 -0500 INFO pid=8052 140735117783824:MainThread thread_manager.pyo:114 Stop was called 2015-11-05 16:58:07,101 -0500 INFO pid=8052 140735117783824:MainThread thread_manager.pyo:250 Asking <Batcher(Batcher, started 45579878401> to stop. 2015-11-05 16:58:07,101 -0500 INFO pid=8052 140735117783824:MainThread thread_manager.pyo:250 Asking <OverlayStatusCleaner(OverlayStatusCleaner, started 45621944321> to stop. 2015-11-05 16:58:07,101 -0500 INFO pid=8052 4557987840:Batcher sync_client_thread.pyo:270 Batcher run loop has terminated. 2015-11-05 16:58:07,102 -0500 INFO pid=8052 4557987840:Batcher sqlite.pyo:378 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-11-05 16:58:07,102 -0500 INFO pid=8052 140735117783824:MainThread thread_manager.pyo:250 Asking <FilewatcherMonitor(FilewatcherMonitor, started 4553519104)> to stop. 2015-11-05 16:58:07,103 -0500 INFO pid=8052 4562194432:OverlayStatusCleaner sync_client_thread.pyo:209 OverlayStatusCleaner run loop has terminated. 2015-11-05 16:58:07,115 -0500 INFO pid=8052 140735117783824:MainThread thread_manager.pyo:250 Asking <IpcServerThread(IpcServerThread, started 4537495552)> to stop. 2015-11-05 16:58:07,116 -0500 INFO pid=8052 140735117783824:MainThread thread_manager.pyo:250 Asking <LocalWatcher(LocalWatcher, started 45706076161> to stop. 2015-11-05 16:58:07,116 -0500 INFO pid=8052 140735117783824:MainThread thread_manager.pyo:250 Asking <TelemetryUploaderThread(TelemetryUploaderThread, started 45916405761> to stop. 2015-11-05 16:58:07,117 -0500 INFO pid=8052 140735117783824:MainThread thread_manager.pyo:250 Asking <SchedulerThread(InternalScheduler, started 4574814208)> to stop. 2015-11-05 16:58:07,117 -0500 INFO pid=8052 4591640576:TelemetryUploaderThread sync_client_thread.pyo:209 TelemetryUploaderThread run loop has terminated. 2015-11-05 16:58:07,117 -0500 INFO pid=8052 140735117783824:MainThread thread_manager.pyo:250 Asking <TelemetryStoreSizeMonitor(TelemetryStoreSizeMonitorThread, started 4579020800)> to stop. 2015-11-05 16:58:07,118 -0500 INFO pid=8052 140735117783824:MainThread thread_manager.pyo:250 Asking <Worker(Worker-0, started 45832273921> to stop. 2015-11-05 16:58:07,118 -0500 INFO pid=8052 4579020800:TelemetryStoreSizeMonitorThread sync_client_thread.pyo:209 TelemetryStoreSizeMonitorThread run loop has terminated. 2015-11-05 16:58:07,118 -0500 WARNING pid=8052 140735117783824:MainThread sync_client_thread.pyo:237 Wake() called while thread was running, should be a N0P 2015-11-05 16:58:07,118 -0500 INFO pid=8052 140735117783824:MainThread thread_manager.pyo:250 Asking <PushTelemetryThread(PushTelemetryThread, started 45874339841> to stop. 2015-11-05 16:58:07,119 -0500 INFO pid=8052 4583227392:Worker-0 sync_client_thread.pyo:209 Worker-0 run loop has terminated. 2015-11-05 16:58:07,119 -0500 INFO pid=8052 4583227392:Worker-0 sqlite.pyo:378 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-11-05 16:58:07,120 -0500 INFO pid=8052 140735117783824:MainThread thread_manager.pyo:250 Asking <FeatureSwitchPoller(FeatureSwitchPoller, started 46337064961> to stop. EFTA00502202 2015-11-05 16:58:07,120 -0500 INFO pid=8052 4587433984:PushTelemetryThread sync_client_thread.pyo:209 PushTelemetryThread run loop has terminated. 2015-11-05 16:58:07,121 -0500 INFO pid=8052 4587433984:PushTelemetryThread telemetry.pyo:205 Attempting final telemetry harvest. 2015-11-05 16:58:07,121 -0500 INFO pid=8052 4633706496:FeatureSwitchPoller sync_client_thread.pyo:209 FeatureSwitchPoller run loop has terminated. 2015-11-05 16:58:07,121 -0500 INFO pid=8052 140735117783824:MainThread thread_manager.pyo:250 Asking <PushNotificationKeepaliveThread(PushNotificationKeepaliveThread, started 4600053760)> to stop. 2015-11-05 16:58:07,125 -0500 WARNING pid=8052 4537495552:IpcServerThread utils.pyo:755 interrupt_condition is set in Sleeper.Wait, waiting 0.2 seconds 2015-11-05 16:58:07,136 -0500 INFO pid=8052 4587433984:PushTelemetryThread telemetry.pyo:165 PushStates.ONLINE: 42536.57 => 27706.8 2015-11-05 16:58:07,137 -0500 INFO pid=8052 140735117783824:MainThread thread_manager.pyo:250 Asking <RawEventUpgrader(RawEventUpgrader, started 4566401024)> to stop. 2015-11-05 16:58:07,137 -0500 INFO pid=8052 4600053760:PushNotificationKeepaliveThread sync_client_thread.pyo:209 PushNotificationKeepaliveThread run loop has terminated. 2015-11-05 16:58:07,138 -0500 INFO pid=8052 140735117783824:MainThread thread_manager.pyo:250 Asking <CloudWatcher(CloudWatcher, started 4604260352)> to stop. 2015-11-05 16:58:07,138 -0500 INFO pid=8052 4566401024:RawEventUpgrader sync_client_thread.pyo:209 RawEventUpgrader run loop has terminated. 2015-11-05 16:58:07,139 -0500 INFO pid=8052 140735117783824:MainThread thread_manager.pyo:250 Asking <SchedulerThread(ListenerScheduler, started 4625293312)> to stop. 2015-11-05 16:58:07,139 -0500 INFO pid=8052 4604260352:CloudWatcher cloud_watcher.pyo:664 Stop detected 2015-11-05 16:58:07,139 -0500 INFO pid=8052 140735117783824:MainThread thread_manager.pyo:250 Asking <Worker(Worker-2, started 4612673536)> to stop. 2015-11-05 16:58:07,140 -0500 WARNING pid=8052 4604260352:CloudWatcher cloud_watcher.pyo:710 Exiting CloudWatcher. PushRunLoop() 2015-11-05 16:58:07,140 -0500 WARNING pid=8052 140735117783824:MainThread sync_client_thread.pyo:237 Wake() called while thread was running, should be a N0P 2015-11-05 16:58:07,140 -0500 INFO pid=8052 4604260352:CloudWatcher sqlite.pyo:378 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-11-05 16:58:07,140 -0500 INFO pid=8052 140735117783824:MainThread thread_manager.pyo:250 Asking <PushXmppelient(Xmppelient, started 4616880128)> to stop. 2015-11-05 16:58:07,141 -0500 WARNING pid=8052 140735117783824:MainThread task.pyo:242 DriveXmppTask-0xl0f275c50 aborting: abort 2015-11-05 16:58:07,141 -0500 INFO pid=8052 140735117783824:MainThread task.pyo:254 Task <common.push.push_xmpp_client.DriveXmppTask object at 0x10f275c50> complete. 2015-11-05 16:58:07,141 -0500 INFO pid=8052 4612673536:Worker-2 sync_client_thread.pyo:209 Worker-2 run loop has terminated. 2015-11-05 16:58:07,142 -0500 INFO pid=8052 140735117783824:MainThread Close called with 'why' reason = Client initiated close 2015-11-05 16:58:07,142 -0500 INFO pid=8052 4612673536:Worker-2 connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-11-05 16:58:07,353 -0500 INFO pid=8052 4557987840:Batcher cleanup callback 'persistence_sqlite:adld159d950c486080d426d4ef77a2f8/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2015-11-05 16:58:07,353 -0500 INFO pid=8052 4583227392:Worker-0 connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-11-05 16:58:07,353 -0500 INFO pid=8052 4612673536:Worker-2 connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-11-05 16:58:07,353 -0500 INFO pid=8052 4557987840:Batcher cleanup callback 'persistence_sqlite:4ble6cf5470b484db4f7d0a93cf477f1/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2015-11-05 16:58:07,354 -0500 INFO pid=8052 4604260352:CloudWatcher sqlite.pyo:378 Close xmpplib.pyo: 143 sqlite.pyo:378 Close utils.pyo:703 Execute sqlite.pyo:378 Close sqlite.pyo:378 Close utils.pyo:703 Execute EFTA00502203 connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-11-05 16:58:07,354 -0500 INFO pid=8052 4557987840:Batcher connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-11-05 16:58:07,354 -0500 INFO pid=8052 4583227392:Worker-0 cleanup callback 'persistence_sqlite:7328b7a763a04771afca4f317e86fb96/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2015-11-05 16:58:07,354 -0500 INFO pid=8052 4612673536:Worker-2 utils.pyo:703 Execute cleanup callback 'persistence_sqlite:63ced7983f2f44a8beaa9f40albeb47c/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2015-11-05 16:58:07,475 -0500 INFO pid=8052 4583227392:Worker-0 utils.pyo:703 Execute cleanup callback 'persistence_sqlite:beaa9247ff3540d1901fd72a805e76f4/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2015-11-05 16:58:07,475 -0500 INFO pid=8052 4604260352:CloudWatcher utils.pyo:703 Execute cleanup callback 'persistence_sqlite:8b8141a501f649bf89e1d1597d2bc3a5/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2015-11-05 16:58:07,475 -0500 INFO pid=8052 4612673536:Worker-2 utils.pyo:703 Execute cleanup callback 'persistence_sqlite:426c1cf8f23348c0a0c99c3eb9ec37e5/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2015-11-05 16:58:07,476 -0500 INFO pid=8052 4604260352:CloudWatcher utils.pyo:703 Execute cleanup callback 'persistence_sqlite:e7534ed9006f489281f340780d8c6741/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2015-11-05 16:58:07,476 -0500 INFO pid=8052 4604260352:CloudWatcher utils.pyo:703 Execute cleanup callback 'DBConnectionManagerCleanupCallback' 2015-11-05 16:58:07,476 -0500 INFO pid=8052 4604260352:CloudWatcher db_connection.pyo:434 Closing ManagedConnection(id=10F0D0BD0, thread=CloudWatcher-1126F7000, path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph/dict_2.db, closed=False) 2015-11-05 16:58:09,499 -0500 INFO pid=8052 140735117783824:MainThread task.pyo:254 Task <common.push.push_xmpp_client.DriveXmppTask object at 0x10f275c50> complete. 2015-11-05 16:58:09,499 -0500 INFO pid=8052 140735117783824:MainThread xmpplib.pyo:143 Close called with 'why' reason = Client initiated close 2015-11-05 16:58:09,500 -0500 INFO pid=8052 140735117783824:MainThread thread_manager.pyo:250 Asking <LocalFSPoller(LocalFSPoller, started 4621086720)> to stop. 2015-11-05 16:58:09,500 -0500 INFO pid=8052 4616880128:XmppClient push_xmpp_client.pyo:135 Exiting run loop 2015-11-05 16:58:09,501 -0500 INFO pid=8052 4621086720:LocalFSPoller sync_client_thread.pyo:209 LocalFSPoller run loop has terminated. 2015-11-05 16:58:09,501 -0500 INFO pid=8052 140735117783824:MainThread thread_manager.pyo:250 Asking <LocalDiskSpaceChecker(LocalDiskSpaceChecker, started 4608466944)> to stop. 2015-11-05 16:58:09,501 -0500 INFO pid=8052 4608466944:LocalDiskSpaceChecker sync_client_thread.pyo:209 LocalDiskSpaceChecker run loop has terminated. 2015-11-05 16:58:09,502 -0500 INFO pid=8052 140735117783824:MainThread thread_manager.pyo:250 Asking <CrashReportUploaderThread(CrashReportUploaderThread, started 4629499904)> to stop. 2015-11-05 16:58:09,502 -0500 INFO pid=8052 140735117783824:MainThread thread_manager.pyo:250 Asking <PauseTelemetryThread(PauseTelemetryThread, started 4595847168)> to stop. 2015-11-05 16:58:09,502 -0500 INFO pid=8052 140735117783824:MainThread thread_manager.pyo:250 Asking <ConnectivityThread(ConnectivityThread, started 4637913088)> to stop. 2015-11-05 16:58:09,503 -0500 INFO pid=8052 4595847168:PauseTelemetryThread sync_client_thread.pyo:209 PauseTelemetryThread run loop has terminated. 2015-11-05 16:58:09,503 -0500 INFO pid=8052 4595847168:PauseTelemetryThread pause_telemetry.pyo:127 Attempting final telemetry harvest. 2015-11-05 16:58:09,503 -0500 INFO pid=8052 140735117783824:MainThread thread_manager.pyo:250 Asking <FinderInjector(FinderInjector, started 4523966464)> to stop. sqlite.pyo:378 Close utils.pyo:703 Execute EFTA00502204 2015-11-05 16:58:09,505 -0500 INFO pid=8052 4595847168:PauseTelemetryThread pause_telemetry.pyo:189 Harvested pause statistics at t=1446760689.5: (‹PauseReason.API ERRORS: 'pause api err'>: _HarvestStat(paused=0.0, trial=0.0, occurrences=0), aauseReason.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.368652919016313, trial=0.0, occurrences=1), lrisauseReason.NO_LOCAL_DISK_SPACE: 'pause_no_space'>: _HarvestStat(paused=0.0, trial=0.0, occurrences=0)) 2015-11-05 16:58:09,520 -0500 INFO pid=8052 140735117783824:MainThread thread manager.pyo:250 Asking <Worker(Worker-1, started 4642119680)> to stop. 2015-11-05 16:58:09,521 -0500 INFO pid=8052 4523966464:FinderInjector sync_client thread.pyo:209 Finderlnjector run loop has terminated. 2015-11-05 16:58:09,521 -0500 WARNING pid=8052 140735117783824:MainThread sync_client thread.pyo:237 Wake() called while thread was running, should be a N0P 2015-11-05 16:58:09,521 -0500 INFO pid=8052 140735117783824:MainThread thread manager.pyo:261 Stop completed successfully. 2015-11-05 16:58:09,522 -0500 INFO pid=8052 4642119680:Worker-1 sync_client thread.pyo:209 Worker-1 run loop has terminated. 2015-11-05 16:58:09,522 -0500 INFO pid=8052 4541702144:ThreadMonitor thread_manager.pyo:339 Waking up. 2015-11-05 16:58:09,522 -0500 INFO pid=8052 4642119680:Worker-1 sqlite.pyo:378 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user default/snapshot.db 2015-11-05 16:58:09,838 -0500 INFO pid=8052 4642119680:Worker-1 sqlite.pyo:378 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user default/sync_config.db 2015-11-05 16:58:10,062 -0500 INFO pid=8052 4642119680:Worker-1 utils.pyo:703 Execute cleanup callback 'persistence sqlite:d0e24ced35064673810df9447acaa0b3/Users/lgroff/Library/Application Support/Googie/Drive/user default/sync_config.db' 2015-11-05 16:58:10,062 -0500 INFO pid=8052 4642119680:Worker-1 utils.pyo:703 Execute cleanup callback 'persistence sqlite:cfec5870c23c44ff9b07f13d48ellb54/Users/lgroff/Library/Application Support/Google/Drive/user default/snapshot.db' 2015-11-05 16:58:10,095 -0500 INFO pid=8052 140735117783824:MainThread sync_app.pyo:1604 Repr caching stats: <class 'common.delivery.ImmutableChange'>: max hits: 3 mean hits: 3.0 max miss: 0 mean miss: 0.0 <class 'common.worker.worker_event.WorkerModifyCloudEvent'>: max hits: 1 mean hits: 1.0 max miss: 0 mean miss: 0.0 <class 'common. raw event. ImmutableRawEvent'>: max hits: 11 mean hits: 6.6 max miss: 0 mean miss: 0.0 <class 'common.worker.worker_event.WorkerCreateCloudEvent'>: max hits: 1 mean hits: 1.0 max miss: 0 mean miss: 0.0 2015-11-17 15:10:56,940 -0500 INFO pid=10530 140735117783824:MainThread logging_config.pyo:249 OS: Darwin/10.9.5 2015-11-17 15:10:57,000 -0500 INFO pid=10530 140735117783824 logging_config.pyo:252 Google Drive (build 1.25.0523.2491) 2015-11-17 15:10:57,000 -0500 INFO pid=10530 140735117783824 logging_config.pyo:253 SSL: OpenSSL 0.9.8zg 14 July 2015 2015-11-17 15:10:57,017 -0500 INFO pid=10530 140735117783824 GSyncAppDelegate.pyo:198 Application did finish launching. :MainThread :MainThread :MainThread EFTA00502205 2015-11-17 15:10:58,967 -0500 INFO pid=10530 140735117783824:MainThread service.pyo:132 Creating singleton service instance of '<class 'common.file_lock.FileLock'>' 2015-11-17 15:10:58,968 -0500 INFO pid=10530 140735117783824:MainThread service.pyo:132 Creating singleton service instance of '<class 'common.file_lock.FileLock'>' 2015-11-17 15:10:58,968 -0500 INFO pid=10530 4519219200:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.service.user.User'>' 2015-11-17 15:10:58,969 -0500 INFO pid=10530 4519219200:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common. roots. roots. RootManager'>' 2015-11-17 15:10:58,969 -0500 INFO pid=10530 4519219200:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.thread_manager.ThreadManager'> 1 2015-11-17 15:10:58,969 -0500 INFO pid=10530 4519219200:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.pause_manager.Service'>' 2015-11-17 15:10:58,970 -0500 INFO pid=10530 4519219200:RunAsync-_InitializeSyncAppAsync-1 sync_app.pyo:308 Getting TokenStorage... 2015-11-17 15:10:58,970 -0500 INFO pid=10530 4519219200:RunAsync-_InitializeSyncAppAsync-1 sync_app.pyo:310 Got token storage: <token_storage.TokenStorage object at 0x109206e60> 2015-11-17 15:10:58,970 -0500 INFO pid=10530 4519219200:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.features.AppFeatures'>' 2015-11-17 15:10:58,970 -0500 INFO pid=10530 4519219200:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.cert.Manager'>' 2015-11-17 15:10:59,321 -0500 INFO pid=10530 4519219200:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.net.bandwidth.BandwidthThrottler'>' 2015-11-17 15:10:59,487 -0500 INFO pid=10530 4519219200:RunAsync-_InitializeSyncAppAsync-1 sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-11-17 15:10:59,488 -0500 INFO pid=10530 4519219200:RunAsync-_InitializeSyncAppAsync-1 sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-11-17 15:10:59,488 -0500 INFO pid=10530 4519219200:RunAsync-_InitializeSyncAppAsync-1 utils.pyo:726 ADDING persistence_sqlite:f30bc0d67d0844acbb2d9a2dle041c3e/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-11-17 15:10:59,489 -0500 INFO pid=10530 4519219200:RunAsync-_InitializeSyncAppAsync-1 utils.pyo:726 ADDING persistence_sqlite:2031c7ba69194db49ec3eeld772a3b93/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-11-17 15:11:00,154 -0500 INFO pid=10530 4519219200:RunAsync-_InitializeSyncAppAsync-1 sync_app.pyo:483 BandwidthThrottler configured via sync_config 2015-11-17 15:11:00,155 -0500 INFO pid=10530 4519219200:RunAsync-_InitializeSyncAppAsync-1 bandwidth. pyo:59 BandwidthThrottler.SetRxBytesPerSecond 0 2015-11-17 15:11:00,155 -0500 INFO pid=10530 4519219200:RunAsync-_InitializeSyncAppAsync-1 bandwidth. pyo:68 BandwidthThrottler.SetTxBytesPerSecond 0 2015-11-17 15:11:00,155 -0500 INFO pid=10530 4519219200:RunAsync-_InitializeSyncAppAsync-1 sqlite.pyo:378 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-11-17 15:11:00,155 -0500 INFO pid=10530 4519219200:RunAsync-_InitializeSyncAppAsync-1 sqlite.pyo:378 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-11-17 15:11:00,289 -0500 INFO pid=10530 4519219200:RunAsync-_InitializeSyncAppAsync-1 utils.pyo:703 Execute cleanup callback 'persistence_sqlite:2031c7ba69194db49ec3eeld772a3b93/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2015-11-17 15:11:00,289 -0500 INFO pid=10530 4519219200:RunAsync-_InitializeSyncAppAsync-1 utils.pyo:703 Execute cleanup callback 'persistence_sqlite:f30bc0d67d0844acbb2d9a2dle041c3e/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2015-11-17 15:11:00,291 -0500 INFO pid=10530 4525658112:Finderlnjector sync_client_thread.pyo:175 Finderlnjector run loop has started. 2015-11-17 15:11:01,837 -0500 INFO pid=10530 140735117783824:MainThread image_resources.py:293 Loading image resources/images/ic_drawer_24.png EFTA00502206 2015-11-17 15:11:02,020 -0500 INFO pid=10530 140735117783824:MainThread image_resources.py:293 Loading image resources/images/menu_drive-logo.png 2015-11-17 15:11:02,136 -0500 INFO pid=10530 140735117783824:MainThread image_resources.py:293 Loading image resources/images/menu_google-logo-gray.png 2015-11-17 15:11:02,381 -0500 INFO pid=10530 140735117783824:MainThread image_resources.py:293 Loading image resources/images/ic_folder_mydrive_24.png 2015-11-17 15:11:02,534 -0500 INFO pid=10530 140735117783824:MainThread image_resources.py:293 Loading image resources/images/ic_web_24.png 2015-11-17 15:11:02,536 -0500 INFO pid=10530 140735117783824:MainThread image_resources.py:293 Loading image resources/images/menu_backups.png 2015-11-17 15:11:02,537 -0500 INFO pid=10530 140735117783824:MainThread image_resources.py:293 Loading image resources/images/ic_syncproblem_24.png 2015-11-17 15:11:02,635 -0500 INFO pid=10530 140735117783824:MainThread image_resources.py:293 Loading image resources/images/ic_done_24.png 2015-11-17 15:11:03,252 -0500 INFO pid=10530 4543799296:ThreadMonitor thread_manager.pyo:336 Waiting on exit condition. Wake up after 3600s. 2015-11-17 15:11:03,253 -0500 INFO pid=10530 4548005888:_LaunchSyncApp sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-11-17 15:11:03,254 -0500 INFO pid=10530 4548005888:_LaunchSyncApp sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-11-17 15:11:03,254 -0500 INFO pid=10530 4548005888:_LaunchSyncApp utils.pyo:726 ADDING persistence_sqlite: 3160e8a635c74b128df6ecbb59414680/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-11-17 15:11:03,255 -0500 INFO pid=10530 4548005888:_LaunchSyncApp utils.pyo:726 ADDING persistence_sqlite:7e84bd38143944769aff701f0f414675/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-11-17 15:11:03,279 -0500 INFO pid=10530 4548005888:_LaunchSyncApp user.pyo:66 Initializing User instance with new credentials. None 2015-11-17 15:11:03,279 -0500 INFO pid=10530 4548005888:_LaunchSyncApp docs_client_factory.pyo:103 Creating a new DriveClient 2015-11-17 15:11:03,280 -0500 INFO pid=10530 4548005888:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.token_bucket.CloudBucketManager'>' 2015-11-17 15:11:03,281 -0500 INFO pid=10530 4548005888:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.drive_api.drive_resource.Manager'>. 2015-11-17 15:11:03,281 -0500 INFO pid=10530 4548005888:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.drive_api.http.Manager'>. 2015-11-17 15:11:03,281 -0500 INFO pid=10530 4548005888:_LaunchSyncApp drive_resource.pyo:130 Initialize apiclient Drive resource for current user token 2015-11-17 15:11:03,367 -0500 INFO pid=10530 4548005888:_LaunchSyncApp drive_resource.pyo: 178 Drive SDK baseUrl = https://www.googleapis.com/drive/v2internal/ 2015-11-17 15:11:03,369 -0500 INFO pid=10530 4548005888:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.drive_api.http.AccessTokenInMemoryCache'> 1 2015-11-17 15:11:03,369 -0500 INFO pid=10530 4548005888:_LaunchSyncApp drive_request.pyo:220 Drive request: About(tags=Reason.SYNC_OTHER) - Read request - None 2015-11-17 15:11:03,372 -0500 INFO pid=10530 4548005888: LaunchSyncApp discovery.pyo:762 URL being requested: GET https://www.googleapis.com/drive/v2internal/about? fields=rootFolderld%2CquotaBytesTota1%2CquotaBytesUsedAggregate%2CquotaBytesUsedInTrash%2Cquo taType%2Cuser%2FemailAddress&reason=304Salt=json 2015-11-17 15:11:03,396 -0500 INFO pid=10530 4548005888:_LaunchSyncApp client.pyo:515 Attempting refresh to obtain initial access_token 2015-11-17 15:11:03,397 -0500 INFO pid=10530 4548005888:_LaunchSyncApp client.pyo:755 Refreshing access_token 2015-11-17 15:11:03,400 -0500 INFO pid=10530 4548005888:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.net.dapper.DapperService'>' 2015-11-17 15:11:03,400 -0500 INFO pid=10530 4548005888:_LaunchSyncApp http.pyo:249 Request user-agent: Python-httplib2/0.9 (gzip) 2015-11-17 15:11:03,401 -0500 INFO pid=10530 4548005888:_LaunchSyncApp sync_http_client.pyo:233 Opening direct connecton to accounts.google.com:443. 2015-11-17 15:11:03,710 -0500 INFO pid=10530 140735117783824:MainThread sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-11-17 15:11:03,711 -0500 INFO pid=10530 140735117783824:MainThread sqlite.pyo:276 EFTA00502207 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-11-17 15:11:03,711 -0500 INFO pid=10530 140735117783824:MainThread sqlite.pyo:378 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-11-17 15:11:03,712 -0500 INFO pid=10530 140735117783824:MainThread sqlite.pyo:378 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-11-17 15:11:03,950 -0500 INFO pid=10530 4548005888:_LaunchSyncApp http.pyo:268 Response: 151 bytes - application/json; charset=utf-8 (encoding:gzip) 2015-11-17 15:11:03,951 -0500 INFO pid=10530 4548005888:_LaunchSyncApp http.pyo:249 Request user-agent: googledrivesync-1.25.0523.2491 google-api-python-client/1.3.1 (gzip) (Darwin/10.9.5) 2015-11-17 15:11:03,951 -0500 INFO pid=10530 4548005888:_LaunchSyncApp sync_http_client.pyo:233 Opening direct connecton to www.googleapis.com:443. 2015-11-17 15:11:04,783 -0500 INFO pid=10530 4548005888:_LaunchSyncApp http.pyo:268 Response: 241 bytes - application/json; charset=UTF-8 (encoding:gzip) 2015-11-17 15:11:04,807 -0500 INFO pid=10530 4548005888:_LaunchSyncApp drive_request.pyo:225 Drive response: Request (None): About(tags=Reason.SYNC_OTHER) Response: About(root_id=0ABNN8iEiWGvgUk9OVA, email_address=lesley.jee@gmail.com, quota_bytes_total=16106127360, quota_bytes_used_aggregate=14901590941, quota_bytes_used_in_trash=405074503, quota_type=LIMITED) 2015-11-17 15:11:04,808 -0500 INFO pid=10530 4548005888:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.drive_api.doc_id_provider.Store'>' 2015-11-17 15:11:04,808 -0500 INFO pid=10530 4548005888:_LaunchSyncApp user.pyo:66 Initializing User instance with new credentials. lesley.jee@gmail.com 2015-11-17 15:11:04,808 -0500 INFO pid=10530 4548005888:_LaunchSyncApp features.pyo:131 Loads feature switches. 2015-11-17 15:11:04,808 -0500 INFO pid=10530 4548005888:_LaunchSyncApp features.pyo:165 Loading feature switches with server. 2015-11-17 15:11:04,808 -0500 INFO pid=10530 4548005888:_LaunchSyncApp feature_switch_manager.pyo:633 Calling server to get feature switches. 2015-11-17 15:11:04,809 -0500 INFO pid=10530 4548005888:_LaunchSyncApp drive_request.pyo:220 Drive request: ListFeatureSwitches(tags=Reason.SYNC_OTHER) - Read request - lesley.jee@gmail.com 2015-11-17 15:11:04,811 -0500 INFO pid=10530 4548005888: LaunchSyncApp discovery.pyo:762 URL being requested: GET https://www.googleapis.com/drive/v2internal/settings? fields=items%2Fkey%2Citems%2Fvalue&reason=304&namespace=FEATURE_SWITCHSalt=json 2015-11-17 15:11:04,812 -0500 INFO pid=10530 4548005888:_LaunchSyncApp http.pyo:249 Request user-agent: googledrivesync-1.25.0523.2491 google-api-python-client/1.3.1 (gzip) (Darwin/10.9.5) 2015-11-17 15:11:04,887 -0500 INFO pid=10530 4548005888:_LaunchSyncApp http.pyo:268 Response: 1017 bytes - application/json; charset=UTF-8 (encoding:gzip) 2015-11-17 15:11:04,904 -0500 INFO pid=10530 4548005888:_LaunchSyncApp drive_request.pyo:225 Drive response: Request (lesley.jee@gmail.com): ListFeatureSwitches(tags=Reason.SYNC_OTHER) Response: (u'enable_impressions_library': u'false., u'enable_multiple_accounts': u'false', u'enable_push': u'true', u'enable_shared_move_confirmation': u'false', u'log_backup_count': u'0', u'crash_throttle_percentage': u'99.7', u'perf_throttle_percentage': u'100 1, u'enable_feedback': u'true', u'enable_multipause': u'false', u'enable_usb_detection': u'false', u'max_batch_upload_files': u'30', u'cloud_graph_disk_generation': u'2', u'test.feature_switch.name': u", u'use_drive_api.: u'false., u'query_strategy': u'all', u'selective_sub_folder_sync': 2015-11-17 15:11:04,905 -0500 INFO pid=10530 4548005888:_LaunchSyncApp sync_app.pyo:1209 Feature Switches: FeatureSwitchSettings( accept_blob_download_gzip_encoding=True, backup_polling_interval_secs=7200, change_filters=('DRIVE_SYNC 1), cloud_graph_disk_generation=2, EFTA00502208 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_file_sync_status=True, enable_impressions_library=False, enable_multipause=False, enable_multiple_accounts=False, enable_native_opener=True, enable_push=True, 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 url='https://docs.google.com/document?usp=drive_sync', new_presentationur1='https://docs.google.com/presentation?usp=drive_synci, new_spreadsheet_ur1=Ihttps://docs.google.com/spreadsheets?usp=drive_synci, numworkers=3, only_rooted_items_in_cloud_graph=False, open_ur1='https://docs.google.com/open?id=fdoc_id)', overlays_enabled_finder_versions=['10.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, regular_polling_interval_secs=30, selective_sub_folder_sync=False, share_template_url=lhttps://drive.google.com/sharing/share? subapp=10&shareProtocolVersion=2&theme=2Scommand=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', tokenbucket_read_qps=10, token_bucketwrite_qps=3, use_drive_api=False,) 2015-11-17 15:11:04,977 -0500 INFO pid=10530 4548005888:_LaunchSyncApp logging_config.pyo:249 OS: Darwin/10.9.5 2015-11-17 15:11:04,995 -0500 INFO pid=10530 4548005888:_LaunchSyncApp logging_config.pyo:252 Google Drive (build 1.25.0523.2491) 2015-11-17 15:11:04,995 -0500 INFO pid=10530 4548005888:_LaunchSyncApp logging_config.pyo:253 SSL: OpenSSL 0.9.8zg 14 July 2015 2015-11-17 15:11:04,995 -0500 INFO pid=10530 4548005888:_LaunchSyncApp sync_app.pyo:1218 Config: Email: lesley.jee@gmail.com Sync root: /Users/lgroff/Google Drive Sync collections: set([]) EFTA00502209 Upgrade number: 22 App version: 1.25.0523.2491 Selective sync: False Cloud Graph generation: 2 Folder sync: (3 Local app whitelist: set((l) Local app blacklist: set((l) 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 2015-11-17 15:11:04,999 -0500 INFO pid=10530 4548005888:_LaunchSyncApp sync_app.pyo:1223 Switching cloud client based on new feature switches 2015-11-17 15:11:04,999 -0500 INFO pid=10530 4548005888:_LaunchSyncApp docs_client_factory.pyo:112 Creating a new Wapi DocsClient 2015-11-17 15:11:05,000 -0500 INFO pid=10530 4548005888:_LaunchSyncApp telemetry.pyo:222 SetPushClientVersion: 1 (3, 20130807) 2015-11-17 15:11:05,000 -0500 INFO pid=10530 4548005888:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.push.telemetry.PushTelemetry'>' 2015-11-17 15:11:05,000 -0500 INFO pid=10530 4548005888:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.telemetry.telemetry_manager.TelemetryManager'>' 2015-11-17 15:11:05,579 -0500 INFO pid=10530 4548005888:_LaunchSyncApp image_resources.py:269 Loading custom image resources/images/folder-mac.icns with function _CreateNSImage 2015-11-17 15:11:05,580 -0500 INFO pid=10530 4548005888:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.arbitrary sync.ArbitrarySynci> 1 2015-11-17 15:11:05,581 -0500 INFO pid=10530 4548005888:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.downloads.DownloadCreate 2015-11-17 15:11:05,581 -0500 INFO pid=10530 4548005888:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.downloads.DownloadModify 2015-11-17 15:11:05,581 -0500 INFO pid=10530 4548005888:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadCreate 2015-11-17 15:11:05,581 -0500 INFO pid=10530 4548005888:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadDelete 2015-11-17 15:11:05,581 -0500 INFO pid=10530 4548005888:_LaunchSyncApp rulefactory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadModify 2015-11-17 15:11:05,582 -0500 INFO pid=10530 4548005888:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadMove 2015-11-17 15:11:05,582 -0500 INFO pid=10530 4548005888:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadMoveFrom 2015-11-17 15:11:05,582 -0500 INFO pid=10530 4548005888:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadMoveTo 2015-11-17 15:11:08,745 -0500 INFO pid=10530 4560752640:FilewatcherMonitor filewatcher.pyo:71 filewatcher_ext initialized 2015-11-17 15:11:08,746 -0500 INFO pid=10530 4548005888:_LaunchSyncApp docs_client_factory.pyo:112 Creating a new Wapi DocsClient 2015-11-17 15:11:08,748 -0500 INFO pid=10530 4548005888:_LaunchSyncApp docs_client_factory.pyo:112 Creating a new Wapi DocsClient 2015-11-17 15:11:08,748 -0500 INFO pid=10530 4548005888:_LaunchSyncApp docs_client_factory.pyo:112 Creating a new Wapi DocsClient 2015-11-17 15:11:08,749 -0500 INFO pid=10530 4548005888:_LaunchSyncApp docs_client_factory.pyo:112 Creating a new Wapi DocsClient 2015-11-17 15:11:08,750 -0500 INFO pid=10530 4548005888:_LaunchSyncApp docs_client_factory.pyo:112 Creating a new Wapi DocsClient 2015-11-17 15:11:08,751 -0500 INFO pid=10530 4548005888:_LaunchSyncApp docs_client_factory.pyo:112 Creating a new Wapi DocsClient 2015-11-17 15:11:08,751 -0500 INFO pid=10530 4548005888:_LaunchSyncApp docs_client_factory.pyo:112 Creating a new Wapi DocsClient 2015-11-17 15:11:08,790 -0500 INFO pid=10530 4548005888:_LaunchSyncApp xmpplib_notifier.pyo:34 Configure storage: <common.push.tango.storage.SyncConfigStorage; proxy of <Swig Object of type 'invalidation::Storage *' at 0xl0f5ee7b0> > 2015-11-17 15:11:08,790 -0500 INFO pid=10530 4548005888:_LaunchSyncApp EFTA00502210 xmpplib_notifier.pyo:59 Creating client thread 2015-11-17 15:11:08,790 -0500 INFO pid=10530 4548005888:_LaunchSyncApp push_xmpp_client.pyo:223 Using talk server: talk.google.com:5222 2015-11-17 15:11:08,791 -0500 INFO pid=10530 4548005888:_LaunchSyncApp xmpplib_notifier.pyo:68 Creating notification bridge 2015-11-17 15:11:08,919 -0500 INFO pid=10530 4548005888:_LaunchSyncApp docs_client_factory.pyo:112 Creating a new Wapi DocsClient 2015-11-17 15:11:08,922 -0500 INFO pid=10530 4564959232:Worker-1 sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-11-17 15:11:08,923 -0500 INFO pid=10530 4569165824:PushNotificationKeepaliveThread sync_client_thread.pyo:175 PushNotificationKeepaliveThread run loop has started. 2015-11-17 15:11:08,924 -0500 INFO pid=10530 4573372416:XmppClient telemetry.pyo:151 Push state None => PushStates.CONNECTING 2015-11-17 15:11:08,925 -0500 INFO pid=10530 4581785600:LocalWatcher watcher.pyo:312 LocalWatcher Run invoked.Waiting for first pass of local scan from poller to start 2015-11-17 15:11:08,925 -0500 INFO pid=10530 4573372416:XmppClient connection_task.pyo:151 Recalculated LoginTask timeout: 16.000000 2015-11-17 15:11:08,926 -0500 INFO pid=10530 4569165824:PushNotificationKeepaliveThread sync_http_client.pyo:233 Opening direct connecton to accounts.google.com:443. 2015-11-17 15:11:08,926 -0500 INFO pid=10530 4573372416:XmppClient telemetry.pyo:165 PushStates.CONNECTING: +0.00293341 => 0.00293341 2015-11-17 15:11:08,927 -0500 INFO pid=10530 4585992192:FeatureSwitchPoller sync_client_thread.pyo:175 FeatureSwitchPoller run loop has started. 2015-11-17 15:11:08,927 -0500 INFO pid=10530 4564959232:Worker-1 sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-11-17 15:11:08,927 -0500 INFO pid=10530 4573372416:XmppClient xmpplib.pyo:80 Connecting to talk.google.com: 5222 2015-11-17 15:11:08,927 -0500 INFO pid=10530 4590198784:TelemetryUploaderThread sync_client_thread.pyo:175 TelemetryUploaderThread run loop has started. 2015-11-17 15:11:08,928 -0500 INFO pid=10530 4585992192:FeatureSwitchPoller features.pyo:156 Current cloud client is not using the same service than what we used initially to fetch feature switches. Using a one-off client with the same service. 2015-11-17 15:11:08,928 -0500 INFO pid=10530 4594405376:TelemetryStoreSizeMonitorThread sync_client_thread.pyo:175 TelemetryStoreSizeMonitorThread run loop has started. 2015-11-17 15:11:08,928 -0500 INFO pid=10530 4598611968:Worker-0 sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-11-17 15:11:08,929 -0500 INFO pid=10530 4602818560:PushTelemetryThread sync_client_thread.pyo:175 PushTelemetryThread run loop has started. 2015-11-17 15:11:08,929 -0500 INFO pid=10530 4607025152:PauseTelemetryThread sync_client_thread.pyo:175 PauseTelemetryThread run loop has started. 2015-11-17 15:11:08,930 -0500 INFO pid=10530 4585992192:FeatureSwitchPoller features.pyo:180 Create a default cloud client factory. 2015-11-17 15:11:08,930 -0500 INFO pid=10530 4611231744:LocalFSPoller sync_client_thread.pyo:175 LocalFSPoller run loop has started. 2015-11-17 15:11:08,930 -0500 INFO pid=10530 4615438336:Batcher sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-11-17 15:11:08,931 -0500 INFO pid=10530 4594405376:TelemetryStoreSizeMonitorThread telemetry_store_monitor_thread.pyo: 44 Checking Telemetry store size. 2015-11-17 15:11:08,931 -0500 INFO pid=10530 4564959232:Worker-1 utils.pyo:726 ADDING persistence_sqlite:8e3837ebde57471a870bfba8215b73de/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-11-17 15:11:08,931 -0500 INFO pid=10530 4623851520:CrashReportUploaderThread crash_report_uploader_thread.pyo:60 Starting CrashReportUploaderThread. 2015-11-17 15:11:08,932 -0500 INFO pid=10530 4628058112:CloudWatcher sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-11-17 15:11:08,932 -0500 INFO pid=10530 4619644928:Worker-2 sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-11-17 15:11:08,932 -0500 INFO pid=10530 4632264704:LocalDiskSpaceChecker sync_client_thread.pyo:175 LocalDiskSpaceChecker run loop has started. EFTA00502211 2015-11-17 15:11:08,933 -0500 INFO pid=10530 4598611968:Worker-0 sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-11-17 15:11:08,933 -0500 INFO pid=10530 4640677888:RawEventUpgrader sync_client_thread.pyo:175 RawEventUpgrader run loop has started. 2015-11-17 15:11:08,934 -0500 INFO pid=10530 4644884480:OverlayStatusCleaner sync_client_thread.pyo:175 OverlayStatusCleaner run loop has started. 2015-11-17 15:11:08,935 -0500 INFO pid=10530 4548005888:_LaunchSyncApp drive_extension_installer.pyo:174 Uninstalling extension nknebiagdodnminbdpflhpkgfpeijdbf 2015-11-17 15:11:08,936 -0500 INFO pid=10530 4585992192:FeatureSwitchPoller features.pyo:182 Set credentials on User service. 2015-11-17 15:11:08,936 -0500 INFO pid=10530 4564959232:Worker-1 utils.pyo:726 ADDING persistence_sqlite:7336557573fc4beealab0c9aa811b1f5/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-11-17 15:11:08,939 -0500 INFO pid=10530 4585992192:FeatureSwitchPoller user.pyo:66 Initializing User instance with new credentials. None 2015-11-17 15:11:08,940 -0500 INFO pid=10530 4564959232:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadChangeACL 2015-11-17 15:11:08,940 -0500 INFO pid=10530 4585992192:FeatureSwitchPoller docs_client_factory.pyo:103 Creating a new DriveClient 2015-11-17 15:11:08,940 -0500 INFO pid=10530 4615438336:Batcher sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-11-17 15:11:08,940 -0500 INFO pid=10530 4564959232:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadCreate 2015-11-17 15:11:08,941 -0500 INFO pid=10530 4548005888:_LaunchSyncApp utils.pyo:703 Execute cleanup callback 'persistence_sqlite: 3160e8a635c74b128df6ecbb59414680/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2015-11-17 15:11:08,941 -0500 INFO pid=10530 4628058112:CloudWatcher sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-11-17 15:11:08,941 -0500 INFO pid=10530 4585992192:FeatureSwitchPoller features.pyo:165 Loading feature switches with server. 2015-11-17 15:11:08,941 -0500 INFO pid=10530 4619644928:Worker-2 sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-11-17 15:11:08,942 -0500 INFO pid=10530 4598611968:Worker-0 utils.pyo:726 ADDING persistence_sqlite:a7d12f2b3bae4de0896f1c96dd4fb5eb/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-11-17 15:11:08,942 -0500 INFO pid=10530 4564959232:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadDelete 2015-11-17 15:11:08,942 -0500 INFO pid=10530 4548005888:_LaunchSyncApp sqlite.pyo:378 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-11-17 15:11:08,942 -0500 INFO pid=10530 4615438336:Batcher utils.pyo:726 ADDING persistence_sqlite:60bb2309e3f5459685927bdeacl7bleb/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-11-17 15:11:08,943 -0500 INFO pid=10530 4585992192:FeatureSwitchPoller feature_switch_manager.pyo:633 Calling server to get feature switches. 2015-11-17 15:11:08,943 -0500 INFO pid=10530 4598611968:Worker-0 utils.pyo:726 ADDING persistence_sqlite:040693e9a6744e0f92f504ae431776f6/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-11-17 15:11:08,943 -0500 INFO pid=10530 4628058112:CloudWatcher utils.pyo:726 ADDING persistence_sqlite:f263477d7a494ffeb643642dd9b24e70/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-11-17 15:11:08,943 -0500 INFO pid=10530 4564959232:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadModify 2015-11-17 15:11:08,944 -0500 INFO pid=10530 4615438336:Batcher utils.pyo:726 ADDING persistence_sqlite: 9f7f452fce4b4390972278ab9ee99bf5/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db EFTA00502212 2015-11-17 15:11:08,944 -0500 INFO pid=10530 4619644928:Worker-2 utils.pyo:726 ADDING persistence_sqlite:e06aac7da53041bcaa0508e1c42d86aa/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-11-17 15:11:08,946 -0500 INFO pid=10530 4598611968:Worker-0 command_factory.pyo:186 Registering worker command type: common.worker.uploads.BatchUploadCreate 2015-11-17 15:11:08,946 -0500 INFO pid=10530 4585992192:FeatureSwitchPoller drive_resource.pyo:130 Initialize apiclient Drive resource for current user token 2015-11-17 15:11:08,946 -0500 INFO pid=10530 4628058112:CloudWatcher utils.pyo:726 ADDING persistence_sqlite:71bbl7b59fce45b5a92a054eec088446/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-11-17 15:11:08,946 -0500 INFO pid=10530 4564959232:Worker-1 command_factory.pyo: 186 Registering worker command type: common.worker.downloads.DownloadMove 2015-11-17 15:11:08,947 -0500 INFO pid=10530 4548005888:_LaunchSyncApp utils.pyo:703 Execute cleanup callback 'persistence_sqlite:7e84bd38143944769aff701f0f414675/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2015-11-17 15:11:08,947 -0500 INFO pid=10530 4619644928:Worker-2 utils.pyo:726 ADDING persistence_sqlite:f916a20dd24f45ec9eb501bc284997d1/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_configS 2015-11-17 15:11:08,947 -0500 INFO pid=10530 4598611968:Worker-0 command_factory.pyo: 186 Registering worker command type: common.worker.uploads.UploadCreate 2015-11-17 15:11:08,947 -0500 INFO pid=10530 4585992192:FeatureSwitchPoller drive_resource.pyo: 178 Drive SDK baseUrl = https://www.googleapis.com/drive/v2internal/ 2015-11-17 15:11:08,947 -0500 INFO pid=10530 4628058112:CloudWatcher cloud_graph.pyo:120 Initialize persistent graph with generation 2 2015-11-17 15:11:08,947 -0500 INFO pid=10530 4564959232:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadRename 2015-11-17 15:11:08,948 -0500 INFO pid=10530 4548005888:_LaunchSyncApp sqlite.pyo:378 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-11-17 15:11:08,948 -0500 INFO pid=10530 4619644928:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.internals.InternalCreate 2015-11-17 15:11:08,948 -0500 INFO pid=10530 4598611968:Worker-0 command_factory.pyo: 186 Registering worker command type: common.worker.uploads.UploadDelete 2015-11-17 15:11:08,948 -0500 INFO pid=10530 4628058112:CloudWatcher cloud_graph.pyo:1626 Current sync config generation: 2 2015-11-17 15:11:08,949 -0500 INFO pid=10530 4564959232:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.internals.InternalCreate 2015-11-17 15:11:08,950 -0500 INFO pid=10530 4585992192:FeatureSwitchPoller drive_request.pyo:220 Drive request: ListFeatureSwitches(tags=Reason.SYNC_OTHER) - Read request - None 2015-11-17 15:11:08,950 -0500 INFO pid=10530 4619644928:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.internals.InternalModify 2015-11-17 15:11:08,950 -0500 INFO pid=10530 4598611968:Worker-0 command_factory.pyo: 186 Registering worker command type: common.worker.uploads.UploadModify 2015-11-17 15:11:08,951 -0500 INFO pid=10530 4628058112:CloudWatcher cloud_graph.pyo:1477 Cleanup cloud graph directory '/Users/lgrof f/Library/Application Support/Google/Drive/user_default/cloud_graph' of files 'dict_*.db" except ['dict_2.db 1, ldict_2.db-shml, 'dict_2.db-wal'] 2015-11-17 15:11:08,951 -0500 INFO pid=10530 4564959232:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.internals.InternalModify 2015-11-17 15:11:08,952 -0500 INFO pid=10530 4619644928:Worker-2 workers.pyo:124 Waiting for batcher to initialize 2015-11-17 15:11:08,954 -0500 INFO pid=10530 4585992192:FeatureSwitchPoller discovery.pyo:762 URL being requested: GET https://www.googleapis.com/drive/v2internal/settings? fields=items%2Fkey%2Citems%2FValue&reason=304&namespace=FEATURE_SWITCH&alt=json 2015-11-17 15:11:08,954 -0500 INFO pid=10530 4598611968:Worker-0 command_factory.pyo: 186 Registering worker command type: common.worker.uploads.UploadMove 2015-11-17 15:11:08,955 -0500 INFO pid=10530 4564959232:Worker-1 workers.pyo:124 EFTA00502213 Waiting for batcher to initialize 2015-11-17 15:11:08,955 -0500 INFO pid=10530 4619644928:Worker-2 sync_client_thread.pyo:175 Worker-2 run loop has started. 2015-11-17 15:11:08,957 -0500 INFO pid=10530 4598611968:Worker-0 command_factory.pyo:186 Registering worker command type: common.worker.uploads.UploadRename 2015-11-17 15:11:08,957 -0500 INFO pid=10530 4585992192:FeatureSwitchPoller client.pyo:515 Attempting refresh to obtain initial access_token 2015-11-17 15:11:08,957 -0500 INFO pid=10530 4564959232:Worker-1 sync_client_thread.pyo:175 Worker-1 run loop has started. 2015-11-17 15:11:08,958 -0500 INFO pid=10530 4628058112:CloudWatcher utils.pyo:726 ADDING DBConnectionManagerCleanupCallback 2015-11-17 15:11:08,958 -0500 INFO pid=10530 4598611968:Worker-0 workers.pyo:124 Waiting for batcher to initialize 2015-11-17 15:11:08,958 -0500 INFO pid=10530 4585992192:FeatureSwitchPoller client.pyo:735 Updated access_token read from Storage 2015-11-17 15:11:08,959 -0500 INFO pid=10530 4628058112:CloudWatcher db_connection.pyo:200 Created thread connection on database /Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph/dict_2.db 2015-11-17 15:11:08,959 -0500 INFO pid=10530 4598611968:Worker-0 sync_client_thread.pyo:175 Worker-0 run loop has started. 2015-11-17 15:11:08,959 -0500 INFO pid=10530 4585992192:FeatureSwitchPoller http.pyo:249 Request user-agent: googledrivesync-1.25.0523.2491 google-api-python-client/1.3.1 (gzip) (Darwin/10.9.5) 2015-11-17 15:11:08,960 -0500 INFO pid=10530 4585992192:FeatureSwitchPoller sync_http_client.pyo:233 Opening direct connecton to www.googleapis.com:443. 2015-11-17 15:11:08,960 -0500 INFO pid=10530 4628058112:CloudWatcher cloud_graph.pyo:1001 Reuse CloudDiskDict database: /Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph/dict_2.db 2015-11-17 15:11:08,973 -0500 INFO pid=10530 4573372416:XmppClient telemetry.pyo:165 PushStates.CONNECTING: +0.0466342 => 0.0495676 2015-11-17 15:11:08,973 -0500 INFO pid=10530 4573372416:XmppClient telemetry.pyo:151 Push state PushStates.CONNECTING => PushStates.NEGOTIATING 2015-11-17 15:11:09,066 -0500 INFO pid=10530 4573372416:XmppClient xmpplib.pyo:397 Start SSL peer=gmail.com 2015-11-17 15:11:09,159 -0500 INFO pid=10530 4569165824:PushNotificationKeepaliveThread sync_http_client.pyo:233 Opening direct connecton to clients3.google.com:443. 2015-11-17 15:11:09,166 -0500 INFO pid=10530 4573372416:XmppClient telemetry.pyo:165 PushStates.NEGOTIATING: +0.193032 => 0.193032 2015-11-17 15:11:09,182 -0500 INFO pid=10530 4585992192:FeatureSwitchPoller http.pyo:268 Response: 1017 bytes - application/json; charset=UTF-8 (encoding:gzip) 2015-11-17 15:11:09,199 -0500 INFO pid=10530 4585992192:FeatureSwitchPoller drive_request.pyo:225 Drive response: Request (None): ListFeatureSwitches(tags=Reason.SYNC_OTHER) Response: (u'enable_impressions_library': u'false', u'enable_multiple_accounts': u'false', u'enable_push': u'true', u'enable_shared_move_confirmation': u'false', u'log_backup_count': u'0', u'crash_throttle_percentage': u'99.7', u'perf_throttle_percentage': u'enable_feedback': u'true', u'enable_multipause': u'false', u'enable_usb_detection': u'false', u'max_batch_upload_files': u'30', u'cloud_graph_disk_generation': u'2', u'test.feature_switch.name': u", u'use_drive_apil: u'false., u'query_strategy': wall', u'selective_sub_folder_synci: u'false') 2015-11-17 15:11:09,268 -0500 INFO pid=10530 4573372416:XmppClient telemetry.pyo:165 PushStates.NEGOTIATING: +0.102183 => 0.295215 2015-11-17 15:11:09,348 -0500 INFO pid=10530 4628058112:CloudWatcher cloud_graph.pyo:161 Initialize persistent graph with existing database /Users/lgrof f/Library/Application Support/Google/Drive/user_default/cloud_graph/dict_2.db 2015-11-17 15:11:09,349 -0500 INFO pid=10530 4628058112:CloudWatcher cloud_graph.pyo:256 Building gdoc docids cache. 2015-11-17 15:11:09,430 -0500 INFO pid=10530 4573372416:XmppClient task.pyo:254 Task <common.push.xmpp.login_task.LoginTask object at 0xl0f5ade50> complete. 2015-11-17 15:11:09,431 -0500 WARNING pid=10530 4573372416:XmppClient login_task.pyo:90 Clearing connection for LoginTask: <common.push.xmpp.login_task.LoginTask object at 0xl0f5ade50> 2015-11-17 15:11:09,431 -0500 INFO pid=10530 4573372416:XmppClient EFTA00502214 push_xmpp_client.pyo:313 Connected to talk.google.com:5222 2015-11-17 15:11:09,432 -0500 WARNING pid=10530 4548005888:RunAsync-_OnXmppConnect-4 notification_bridge.pyo:126 XMPP detected connect. 2015-11-17 15:11:09,432 -0500 INFO pid=10530 4548005888:RunAsync-_OnXmppConnect-4 xmpp_network_channel.pyo:169 Sending push channel subscription request 2015-11-17 15:11:09,479 -0500 INFO pid=10530 4573372416:XmppClient xmpp_network_channel.pyo:187 Received subscription response from Push for XMPPBot 2015-11-17 15:11:09,479 -0500 INFO pid=10530 4548005888:RunAsync-_OnXmppConnect-4 notification_bridge.pyo:142 Starting TICL (client version 3.20130807)... 2015-11-17 15:11:09,506 -0500 INFO pid=10530 4548005888:RunAsync-_OnXmppConnect-4 xmpp_network_channel.pyo: 65 XmppNetworkChannel.InformNetworkStatus: CONNECTED 2015-11-17 15:11:09,638 -0500 INFO pid=10530 4636471296:ListenerScheduler invalidation_listener.pyo:185 InvalidationListener.ReissueRegistrations(prefix=, prefix_len=0) 2015-11-17 15:11:09,878 -0500 INFO pid=10530 4569165824:PushNotificationKeepaliveThread push_notification_keepalive_thread.pyo:49 Sent push keepalive message at: Tue Nov 17 15:11:09 2015 2015-11-17 15:11:10,374 -0500 INFO pid=10530 4628058112:CloudWatcher cloud_graph.pyo:171 Reuse CloudDiskDict CloudDiskDict(max_change_stamp=73452, app_version=1.25.0523.2491, only_rooted_items=False, ()) 2015-11-17 15:11:10,374 -0500 INFO pid=10530 4628058112:CloudWatcher cloud_watcher.pyo:828 Performing full snapshot diff. 2015-11-17 15:11:10,375 -0500 INFO pid=10530 4628058112:CloudWatcher cloud_watcher.pyo:1026 Getting cloud resources via changelog feed from 73453 2015-11-17 15:11:10,375 -0500 INFO pid=10530 4628058112:CloudWatcher docs_client.pyo:743 Requesting Doc List: ifeeds/default/private/changes?start-index=73453&showroot=true&include- shared=true&reason=startup 2015-11-17 15:11:10,376 -0500 INFO pid=10530 4628058112:CloudWatcher sync_http_client.pyo:233 Opening direct connecton to clients3.google.com:443. 2015-11-17 15:11:10,707 -0500 INFO pid=10530 4628058112:CloudWatcher docs_client.pyo:1137 Feed has 8 entries 2015-11-17 15:11:10,707 -0500 INFO pid=10530 4628058112:CloudWatcher docs_client.pyo:1144 End of feed, no next URI 2015-11-17 15:11:10,712 -0500 INFO pid=10530 4628058112:CloudWatcher cloud_watcher.pyo:794 Got 8 cloud entries 2015-11-17 15:11:10,714 -0500 INFO pid=10530 4628058112:CloudWatcher cloud_watcher.pyo:812 647 nodes in the cloud graph 2015-11-17 15:11:10,744 -0500 INFO pid=10530 4628058112:CloudWatcher cloud_snapshot_diff_helper.pyo:84 Prepare for Pruning graph (total of 647 entries). sync gdocs=True, sync folders=None, excluded folders=frozenset(()) 2015-11-17 15:11:10,744 -0500 INFO pid=10530 4628058112:CloudWatcher cloud_snapshot_diff_helper.pyo:85 Start Prune Graph (total of 647 entries) 2015-11-17 15:11:10,754 -0500 INFO pid=10530 4628058112:CloudWatcher cloud_snapshot_diff_helper.pyo:175 Pruning detected 631 items to keep 2015-11-17 15:11:10,754 -0500 INFO pid=10530 4628058112:CloudWatcher cloud_snapshot_diff_helper.pyo:182 631 entries in graph after pruning 2015-11-17 15:11:10,754 -0500 INFO pid=10530 4628058112:CloudWatcher cloud_snapshot_diff_helper.pyo:93 Compare to snapshot 2015-11-17 15:11:10,755 -0500 INFO pid=10530 4628058112:CloudWatcher cloud_snapshot_diff_helper.pyo:762 Starting cloud graph vs snapshot diff with selective sync set: None 2015-11-17 15:11:10,755 -0500 INFO pid=10530 4628058112:CloudWatcher cloud_snapshot_diff_helper.pyo:764 Prepopulating DOC_ID-to-inodes cache 2015-11-17 15:11:10,758 -0500 INFO pid=10530 4628058112:CloudWatcher cloud_snapshot_diff_helper.pyo:768 First pass detecting moved entries 2015-11-17 15:11:10,991 -0500 INFO pid=10530 4628058112:CloudWatcher cloud_snapshot_diff_helper.pyo:783 Second pass detecting creations and modification entries 2015-11-17 15:11:11,302 -0500 INFO pid=10530 4628058112:CloudWatcher cloud_snapshot_diff_helper.pyo:810 Processed 630 entries in total. Number of events detected: (_FSChangeActionType(Action.MODIFY): 1, _FSChangeActionType(Action.CREATE): 1} 2015-11-17 15:11:11,372 -0500 INFO pid=10530 4628058112:CloudWatcher cloud_watcher.pyo:872 Done with full snapshot diff. 2015-11-17 15:11:11,372 -0500 INFO pid=10530 4628058112:CloudWatcher cloud_watcher.pyo:931 CloudWatcher generated 2 events 2015-11-17 15:11:11,372 -0500 INFO pid=10530 4628058112:CloudWatcher cloud_watcher.pyo:937 EFTA00502215 CloudWatcher generated FSChange(Direction.DOWNLOAD, Action.MODIFY, ino=15146407, name=2015 November2.docx, rid=0BxNN8iEiWGvgb2c3V1htMG11YU0, parent_ino=14390475, is_folder=False, modified=1447788761, checksum=5951bb512688f875cce9b02f1708224f, size=62576, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB) 2015-11-17 15:11:11,372 -0500 INFO pid=10530 4628058112:CloudWatcher cloud_watcher.pyo:937 CloudWatcher generated FSChange(Direction.DOWNLOAD, Action.CREATE, name=2015 December.docx, route=[CloudEntry(doc_id=OBxNN8iEiWGvgRF1wTG9ECDJ12Fk,filename=2015 December.docx,modified=1447784653,created=1447774983,acl_role=owner,doc_type=DocType.BLOB,rem oved=False,parent_doc_ids=set(('0BxNN8iEiWGvgRjcxNnlahRm5nVlk']),child_doc_ids=set([]),size=44 804,checksum=97689a3bf1c3c2151caldeb2c39e5eaa,change_stamp=74418,server_mod_time=Nonef is_zomb ie=False,shared=False,resource_type=file,version=None)), rid=0BxNN8iEiWGvgRF1wTG9EcDJ12Fk, parent_ino=14390475, is_folder=False, size=44804, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB) 2015-11-17 15:11:11,372 -0500 INFO pid=10530 4628058112:CloudWatcher aggregator.pyo:108 -- > Received event None FSChange(Direction.DOWNLOAD, Action.CREATE, name=2015 December.docx, route=[CloudEntry(doc_id=OBxNN8iEiWGvgRF1wTG9ECDJ12Fk,filename=2015 December.docx,modified=1447784653,created=1447774983,acl_role=owner,doc_type=DocType.BLOB,rem oved=False,parent_doc_ids=set(('OBxNNBiEiWGvgRjcxNnBhRmSnVlk']),child_doc_ids=set([]),size=44 804,checksum=97689a3bf1c3c2151caldeb2c39e5eaa,change_stamp=74418,server_mod_time=Nonef is_zomb ie=False,shared=False,resource_type=file,version=None)), rid=0BxNN8iEiWGvgRF1wTG9EcDJ12Fk, parent_ino=14390475, is_folder=False, size=44804, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB) 2015-11-17 15:11:11,373 -0500 INFO pid=10530 4628058112:CloudWatcher aggregator.pyo:108 -- > Received event None FSChange(Direction.DOWNLOAD, Action.MODIFY, ino=15146407, name=2015 November2.docx, rid=0BxNN8iEiWGvgb2c3V1htMG11YU0, parent_ino=14390475, is_folder=False, modified=1447788761, checksum=5951bb512688f875cce9b02f1708224f, size=62576, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB) 2015-11-17 15:11:11,373 -0500 INFO pid=10530 4628058112:CloudWatcher downloads.pyo:126 ProcessDownloadModify (FSChange(Direction.DOWNLOAD, Action.MODIFY, ino=15146407, name=2015 November2.docx, rid=0BxNN8iEiWGvgb2c3V1htMG11YU0, parent_ino=14390475, is_folder=False, modified=1447788761, checksum=5951bb512688f875cce9b02f1708224f, size=62576, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB)) 2015-11-17 15:11:11,445 -0500 INFO pid=10530 4619644928:Worker-2 workers.pyo:180 Worker starting on [ImmutableChange(Direction.DOWNLOAD, Action.CREATE, name=2015 December.docx, route=[CloudEntry(doc_id=OBxNN8iEiWGvgRF1wTG9ECDJ12Fk,filename=2015 December.docx,modified=1447784653,created=1447774983,acl_role=owner,doc_type=DocType.BLOB,rem oved=False,parent_doc_ids=set(('OBxNNBiEiWGvgRjcxNnBhRmSnVlk']),child_doc_ids=set([]),size=44 804,checksum=97689a3bf1c3c2151caldeb2c39e5eaa,change_stamp=74418,server_mod_time=Nonef is_zomb ie=False,shared=False,resource_type=file,version=None)), rid=0BxNN8iEiWGvgRF1wTG9EcDJ12Fk, parent_ino=14390475, is_folder=False, size=44804, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB, hash=-3825572888725288937, _constructor_called=True)) 2015-11-17 15:11:11,448 -0500 INFO pid=10530 4619644928:Worker-2 download.pyo:139 Downloading https://googledrive.com/p/host/0BxNN8iEiWGvgRF1wTG9EcDJ12Fk to /var/folders/n1/ypw25pmj06qfyyl_8xnt3kf40000gn/T/tmpw7k2.3U 2015-11-17 15:11:11,449 -0500 INFO pid=10530 4619644928:Worker-2 sync_http_client.pyo:233 Opening direct connecton to googledrive.com:443. 2015-11-17 15:11:11,450 -0500 INFO pid=10530 4564959232:Worker-1 workers.pyo:180 Worker starting on [ImmutableChange(Direction.DOWNLOAD, Action.MODIFY, ino=15146407, name=2015 November2.docx, rid=0BxNN8iEiWGvgb2c3V1htMG11YU0, parent_ino=14390475, is_folder=False, modified=1447788761, checksum=5951bb512688f875cce9b02f1708224f, size=62576, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB, hash=6514198082592140197, _constructor_called=True)] 2015-11-17 15:11:11,452 -0500 INFO pid=10530 4564959232:Worker-1 download.pyo:139 Downloading https://googledrive.com/p/host/0BxNN8iEiWGvgb2c3V1htMG11YU0 to /var/folders/n1/ypw25pmj06qfyyl_8xnt3kf40000gn/T/tmpE0rsOg 2015-11-17 15:11:11,453 -0500 INFO pid=10530 4564959232:Worker-1 sync_http_client.pyo:233 Opening direct connecton to googledrive.com:443. 2015-11-17 15:11:11,936 -0500 INFO pid=10530 4611231744:LocalFSPoller poller.pyo:126 Cloud scan complete. Preparing to start LocalFSPoller 2015-11-17 15:11:11,973 -0500 INFO pid=10530 4619644928:Worker-2 snapshot_scilite.pyo:143 Adding local entry inode=15154198, filename=None 2015-11-17 15:11:11,974 -0500 INFO pid=10530 4619644928:Worker-2 snapshot_scilite.pyo:171 Adding cloud entry resource_id=file:0BxNN8iEiWGvgRF1wTG9EcDJ12,Fk, filename=None 2015-11-17 15:11:11,974 -0500 INFO pid=10530 4619644928:Worker-2 EFTA00502216 snapshot_sqlite.pyo:253 Updating cloud entry doc_id=0BxNN8iEiWGvgRF1wTG9EcDJ1ZFk, filename=2015 December.docx 2015-11-17 15:11:12,049 -0500 INFO pid=10530 4564959232:Worker-1 commands.pyo:58 Updating inode in snapshot. old=15146407, new=15154199 2015-11-17 15:11:12,049 -0500 INFO pid=10530 4564959232:Worker-1 commands.pyo:61 Entry associated with new inode prior to update None: 2015-11-17 15:11:12,049 -0500 INFO pid=10530 4564959232:Worker-1 snapshot_sqlite.pyo: 552 Removing local relation child_inode=15146407, parent_inode=14390475 2015-11-17 15:11:12,081 -0500 INFO pid=10530 4619644928:Worker-2 snapshot_sqlite.pyo:230 Updating local entry inode=15154198, filename=2015 December.docx, modified=1447784653, checksum=97689a3bf1c3c2151caldeb2c39e5eaa, size=44804, is_folder=False 2015-11-17 15:11:12,081 -0500 INFO pid=10530 4619644928:Worker-2 snapshot_sqlite.pyo: 542 Adding local relation child_inode=15154198, parent_inode=14390475 2015-11-17 15:11:12,081 -0500 INFO pid=10530 4619644928:Worker-2 snapshot_sqlite.pyo:519 Adding cloud relation child_doc_id=011xNN8iEiWGvgRF1wTG9EcDJ12Fk, parent_doc_id=OBxNN8iEiWGvgRj cxNnBhRm5nVlk 2015-11-17 15:11:12,081 -0500 INFO pid=10530 4619644928:Worker-2 snapshot_sqlite.pyo:562 Adding Mapping inode=15154198, doc_id=0BxNN8iEiWGvgRF1wTG9EcDJ12Fk 2015-11-17 15:11:12,113 -0500 INFO pid=10530 4619644928:Worker-2 workers.pyo:191 Worker successfully completed (ImmutableChange(Direction.DOWNLOAD, Action.CREATE, name=2015 December.docx, route=(CloudEntry(doc_id=0BxNN8iEiWGvgRFIwTG9EcDJ12,Fk,filename=2015 December.docx,modified=1447784653,created=1447774983,acl_role=owner,doc_type=DocType.BLOB,rem oved=False,parent_doc_ids=set(('OBxNN8iEiWGvgRjcxNnBhRm5nV1101),child_doc_ids=set([]),size=44 804,checksum=97689a3bf1c3c2151caldeb2c39e5eaa,change_stamp=74418,server_mod_time=Nonef is_zomb ie=False,shared=False,resource_type=file,version=None)], rid=0BxNN8iEiWGvgRF1wTG9EcDJIZFk, parent_ino=14390475, is_folder=False, size=44804, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB, hash=-3825572888725288937, _constructor_called=True)] 2015-11-17 15:11:12,893 -0500 INFO pid=10530 4577579008:InternalScheduler sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-11-17 15:11:12,910 -0500 INFO pid=10530 4577579008:InternalScheduler sqlite.pyo:378 Close connection. path=/Users/Igroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-11-17 15:11:13,051 -0500 INFO pid=10530 4564959232:Worker-1 snapshot_sqlite.pyo: 572 Removing Mapping inode=15146407, doc_id=085(NN8iEiWGvgb2c3V1htMG11YUO 2015-11-17 15:11:13,051 -0500 INFO pid=10530 4564959232:Worker-1 snapshot_sqlite.pyo:154 Removing local entry inode=15146407 2015-11-17 15:11:13,051 -0500 INFO pid=10530 4564959232:Worker-1 snapshot_sqlite.pyo:143 Adding local entry inode=15154199, filename=None 2015-11-17 15:11:13,051 -0500 INFO pid=10530 4564959232:Worker-1 snapshot_sqlite.pyo: 542 Adding local relation child_inode=15154199, parent_inode=14390475 2015-11-17 15:11:13,052 -0500 INFO pid=10530 4564959232:Worker-1 snapshot_sqlite.pyo:230 Updating local entry inode=15154199, filename=2015 November2.docx, modified=1446758915, checksum=c146cd5c86f952aa9a61b3lffObfe458, size=93527, is_folder=False 2015-11-17 15:11:13,052 -0500 INFO pid=10530 4564959232:Worker-1 snapshot_sqlite.pyo:562 Adding Mapping inode=15154199, doc_id=0BxNN8iEiWGvgb2c3V1htMG11YUO 2015-11-17 15:11:13,052 -0500 INFO pid=10530 4564959232:Worker-1 snapshot_sqlite.pyo:230 Updating local entry inode=15154199, modified=1447788761, checksum=5951bb512688f875cce9b02f1708224f, size=62576 2015-11-17 15:11:13,052 -0500 INFO pid=10530 4564959232:Worker-1 snapshot_sqlite.pyo:253 Updating cloud entry doc_id=0BxNN8iEiWGvgb2c3V1htMG11YUO, filename=None 2015-11-17 15:11:13,074 -0500 INFO pid=10530 4564959232:Worker-1 workers.pyo:191 Worker successfully completed (ImmutableChange(Direction.DOWNLOAD, Action.MODIFY, ino=15146407, name=2015 November2.docx, rid=085(NN8iEiWGvgb2c3V1htMG11YUO, parent_ino=14390475, is_folder=False, modified=1447788761, checksum=5951bb512688f875cce9b02f1708224f, size=62576, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB, hash=6514198082592140197, _constructor_called=True)] 2015-11-17 15:11:13,447 -0500 INFO pid=10530 4615438336:Batcher batcher.pyo:240 Current batch is completed. Batcher snooze. 2015-11-17 15:11:13,447 -0500 INFO pid=10530 4615438336:Batcher sync_status.pyo:133 Reset sync status 2015-11-17 15:11:13,447 -0500 INFO pid=10530 4615438336:Batcher batcher.pyo:856 Batcher Stats = file_count = Counter ( { COUNT KEY Cdirection=_DownloadDirectionType (Direction. DOWNLOAD), EFTA00502217 action=_FSChangeActionType(Action.CREATE), batch=False, successful=True) _COUNT KEY(direction=_DownloadDirectionType(Direction. DOWNLOAD), action=_FSChangeActionType(Action.MODIFY), batch=False, successful=True) Counter (1 COUNT KEY Cdirection=_DownloadDirectionType (Direction. DOWNLOAD) action=_FSChangeActionType(Action.MODIFY), batch=False, successful=True) _COUNT KEY(direction=_DownloadDirectionType(Direction. DOWNLOAD), action=_FSChangeActionType(Action.CREATE), batch=False, successful=True) batch_operation_count = Counter(), process_seconds = Counter (1 COUNT KEY Cdirection=_DownloadDirectionType (Direction. DOWNLOAD) action=_FSChangeActionType(Action.MODIFY), batch=False, successful=True) _COUNT KEY(direction=_DownloadDirectionType(Direction. DOWNLOAD), action=_FSChangeActionType(Action.CREATE), batch=False, successful=True): 0.6667990684509277)), duration seconds = 2 (start_time = 1447791071, end_time = 1447791073) 2015-11-17 15:11:13,448 -0500 INFO pid=10530 4611231744:LocalFSPoller poller.pyo:137 LocalFSPoller first pass started. Waiting for localWatcher to start watching. 2015-11-17 15:11:13,448 -0500 INFO pid=10530 4581785600:LocalWatcher watcher.pyo:240 RootsAdded with roots [_MyDriveRoot(u'/Users/lgroff/Google Drive', 'root')] 2015-11-17 15:11:13,449 -0500 INFO pid=10530 4581785600:LocalWatcher watcher.pyo:243 Scheduling roots: [_MyDriveRoot(u'/Users/lgroff/Google Drive', 'root') 2015-11-17 15:11:13,449 -0500 INFO pid=10530 4581785600:LocalWatcher watcher.pyo:270 Scheduling root: _MyDriveRoot(u'/Users/lgroff/Google Drive', 'root') 2015-11-17 15:11:13,449 -0500 INFO pid=10530 4581785600:LocalWatcher watcher.pyo:333 LocalWatcher is up. 2015-11-17 15:11:13,450 -0500 INFO pid=10530 4611231744:LocalFSPoller poller.pyo:146 Finished waiting for LocalWatcher. 2015-11-17 15:11:13,450 -0500 INFO pid=10530 4611231744:LocalFSPoller poller.pyo:149 Starting a local disk scan. 2015-11-17 15:11:13,450 -0500 INFO pid=10530 4611231744:LocalFSPoller diff_helper.pyo:86 Walking: [u'/Users/lgroff/Google Drive') 2015-11-17 15:11:13,498 -0500 INFO pid=10530 4636471296:ListenerScheduler invalidation_listener.pyo:125 InvalidationListener.InformRegistrationStatus(1014:CHANGELOG, REGISTERED) 2015-11-17 15:11:13,498 -0500 WARNING pid=10530 4636471296:ListenerScheduler notification_bridge.pyo:202 Invalidation status callback called with connected=True 2015-11-17 15:11:13,499 -0500 INFO pid=10530 4636471296:ListenerScheduler telemetry.pyo:165 PushStates.NEGOTIATING: 44.2308 => 4.52601 2015-11-17 15:11:13,499 -0500 INFO pid=10530 4636471296:ListenerScheduler telemetry.pyo:151 Push state PushStates.NEGOTIATING => PushStates.ONLINE 2015-11-17 15:11:13,500 -0500 INFO pid=10530 4636471296:ListenerScheduler pause_manager.pyo:130 Removing pause reason OFFLINE. Paused: False 2015-11-17 15:11:13,500 -0500 ERROR pid=10530 4636471296:ListenerScheduler pause_manager.pyo:438 Tried to get last event time for PauseReason.OFFLINE, but no last event time recorded. 2015-11-17 15:11:13,500 -0500 INFO pid=10530 4636471296:ListenerScheduler invalidation_listener.pyo:125 InvalidationListener.InformRegistrationStatus(1014:CHANGELOG, REGISTERED) 2015-11-17 15:11:13,500 -0500 WARNING pid=10530 4636471296:ListenerScheduler notification_bridge.pyo:202 Invalidation status callback called with connected=True 2015-11-17 15:11:13,500 -0500 INFO pid=10530 4636471296:ListenerScheduler telemetry.pyo:165 PushStates.ONLINE: +0.00129614 => 0.00129614 2015-11-17 15:11:13,500 -0500 INFO pid=10530 4636471296:ListenerScheduler pause_manager.pyo:130 Removing pause reason OFFLINE. Paused: False 2015-11-17 15:11:13,501 -0500 ERROR pid=10530 4636471296:ListenerScheduler pause_manager.pyo:438 Tried to get last event time for PauseReason.OFFLINE, but no last event time recorded. 2015-11-17 15:11:13,829 -0500 INFO pid=10530 4611231744:LocalFSPoller poller.pyo:166 Number of found changes: 0 2015-11-17 15:11:13,830 -0500 INFO pid=10530 4611231744:LocalFSPoller poller.pyo:177 Finished local disk scan. 2015-11-17 15:11:13,900 -0500 INFO pid=10530 4636471296:ListenerScheduler invalidation_listener.pyo:82 InvalidationListener.Invalidation(1014:CHANGELOG, wapi_version=74417) 2015-11-17 15:11:13,902 -0500 INFO pid=10530 4628058112:CloudWatcher cloud_watcher.pyo:700 Change ignored. observed: 74417, retrieved: 74418 2015-11-17 15:12:46,051 -0500 INFO pid=10530 140735117783824:MainThread : 1, : 1)), byte_count = • 62576, : 44804)), • 1.6232900619506836, EFTA00502218 image_resources.py:293 Loading image resources/images/menu_share.png 2015-11-17 15:12:46,108 -0500 INFO pid=10530 140735117783824:MainThread image_resources.py:293 Loading image resources/images/menu_sync-paused.png 2015-11-17 15:12:46,110 -0500 INFO pid=10530 140735117783824:MainThread image_resources.py:293 Loading image resources/images/menu_error.png 2015-11-17 15:17:52,296 -0500 INFO pid=10530 140735117783824:MainThread sync_app.pyo:1576 Quit called from: File "/Volumes/BuildData/pulse- data/agents/563586/recipes/843291236/base/b/drb/googleclient/apps/webdrive_sync/osx/dist/Goog le Drive.app/Contents/Resources/ boot .py", line 43, in <module> File "/Volumes/BuildData/pulse- data/agents/563586/recipes/843291236/base/b/drb/googleclient/apps/webdrive_sync/osx/dist/Goog le Drive.app/Contents/Resources/ boot .py", line 38, 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 "build/bdist.macosx-10.7-intel/egg/PyObjCTools/AppHelper.py", line 235, in runEventLoop File "osx/GSyncAppDelegate.pyo", line 297, in applicationWillTerminate_ File "osx/mac_sync_app_controller.pyo", line 251, in Exit File "common/ui_action_handler.pyo", line 444, in Quit File "common/sync_app.pyo", line 1576, in Quit EFTA00502219