EFTA00502404.pdf

From: File "Nolumes/BuildData/pulse- datathgents/599758/recipes/859788647/base/b/drb/googleclient/apps/webdrive_sync/osiddist/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/osiddist/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 "osic/GSyncAppDelegate.pyo", line 297, in applicationWillTerminate_ File "osxlmac_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-03 17:00:26,124 -0500 INFO pid=1858 140735235863312:MainThread thread_manager.pyo:114 Stop was called 2015-12-03 17:00:26,124 -0500 INFO pid=1858 140735235863312:MainThread thread_manager.pyo:250 Asking <LocalWatcher(LocalWatcher, started 45769932801> to stop. 2015-12-03 17:00:26,157 -0500 INFO pid=1858 140735235863312:MainThread thread_manager.pyo:250 Asking <ConnectivityThread(ConnectivityThread, started 4610646016)> to stop. 2015-12-03 17:00:26,188 -0500 INFO pid=1858 140735235863312:MainThread thread_manager.pyo:250 Asking <FilewatcherMonitor(FilewatcherMonitor, stopped 4572786688)> to stop. 2015-12-03 17:00:26,188 -0500 INFO pid=1858 140735235863312:MainThread thread_manager.pyo:250 Asking <Worker(Worker-2, started 46611251201> to stop. 2015-12-03 17:00:26,188 -0500 WARNING pid=1858 140735235863312:MainThread sync_client_thread.pyo:237 Wake() called while thread was running, should be a N0P 2015-12-03 17:00:26,188 -0500 INFO pid=1858 140735235863312:MainThread thread_manager.pyo:250 Asking <RawEventUpgrader(RawEventUpgrader, started 4644298752)> to stop. 2015-12-03 17:00:26,190 -0500 INFO pid=1858 140735235863312:MainThread thread_manager.pyo:250 Asking <PushXmppClient(XmppClient, started 45938196481> to stop. 2015-12-03 17:00:26,190 -0500 WARNING pid=1858 140735235863312:MainThread task.pyo:242 DriveXmppTask-0x110126dd0 aborting: abort 2015-12-03 17:00:26,190 -0500 INFO pid=1858 4661125120:Worker-2 sync_client_thread.pyo:209 Worker-2 run loop has terminated. 2015-12-03 17:00:26,190 -0500 INFO pid=1858 4644298752:RawEventUpgrader sync_client_thread.pyo:209 RawEventUpgrader run loop has terminated. 2015-12-03 17:00:26,190 -0500 INFO pid=1858 140735235863312:MainThread task.pyo:254 Task <common.push.push_xmpp_client.DriveXmppTask object at 0x110126dd0> complete. 2015-12-03 17:00:26,190 -0500 INFO pid=1858 4661125120:Worker-2 sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-03 17:00:26,191 -0500 INFO pid=1858 140735235863312:MainThread xmpplib.pyo:143 Close called with 'why' reason = Client initiated close 2015-12-03 17:00:26,191 -0500 INFO pid=1858 4661125120:Worker-2 sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-03 17:00:26,192 -0500 INFO pid=1858 4661125120:Worker-2 utils.pyo:714 Execute cleanup callback 'persistence_sqlite:155c6600641842e0aal6bf6f24afc8ae/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2015-12-03 17:00:26,192 -0500 INFO pid=1858 4661125120:Worker-2 utils.pyo:714 Execute cleanup callback 'persistence_sqlite: 424c9743ece3495184fcb61c2b725e6c/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2015-12-03 17:00:28,378 -0500 INFO pid=1858 140735235863312:MainThread task.pyo:254 Task <common.push.push_xmpp_client.DriveXmppTask object at 0x110126dd0> complete. 2015-12-03 17:00:28,379 -0500 INFO pid=1858 140735235863312:MainThread xmpplib.pyo:143 Close called with 'why' reason = Client initiated close 2015-12-03 17:00:28,379 -0500 INFO pid=1858 140735235863312:MainThread thread_manager.pyo:250 Asking <FinderInjector(Finderlnjector, started 4532998144)> to stop. EFTA00502404 2015-12-03 17:00:28,379 -0500 INFO pid=1858 4593819648:XmppClient push_xmpp_client.pyo:135 Exiting run loop 2015-12-03 17:00:28,380 -0500 INFO pid=1858 4532998144:Finderlnjector sync_client_thread.pyo:209 Finderlnjector run loop has terminated. 2015-12-03 17:00:28,380 -0500 INFO pid=1858 140735235863312:MainThread thread_manager.pyo:250 Asking <OverlayStatusCleaner(OverlayStatusCleaner, started 4598026240)> to stop. 2015-12-03 17:00:28,381 -0500 INFO pid=1858 140735235863312:MainThread thread_manager.pyo:250 Asking <CloudWatcher(CloudWatcher, started 4602232832)> to stop. 2015-12-03 17:00:28,381 -0500 INFO pid=1858 4598026240:OverlayStatusCleaner sync_client_thread.pyo:209 OverlayStatusCleaner run loop has terminated. 2015-12-03 17:00:28,381 -0500 INFO pid=1858 140735235863312:MainThread thread_manager.pyo:250 Asking <SchedulerThread(InternalScheduler, started 4606439424)> to stop. 2015-12-03 17:00:28,381 -0500 INFO pid=1858 4602232832:CloudWatcher cloud_watcher.pyo:680 Stop detected 2015-12-03 17:00:28,381 -0500 thread_manager.pyo:250 Asking 2015-12-03 17:00:28,382 -0500 cloud_watcher.pyo:726 Exiting 2015-12-03 17:00:28,382 -0500 INFO pid=1858 140735235863312:MainThread <Worker(Worker-1, started 4585406464)> to stop. WARNING pid=1858 4602232832:CloudWatcher CloudWatcher.PushRunLoop() WARNING pid=1858 140735235863312:MainThread sync_client_thread.pyo:237 Wake() called while thread was running, should be a NOP 2015-12-03 17:00:28,382 -0500 INFO pid=1858 4602232832:CloudWatcher sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-03 17:00:28,382 -0500 INFO pid=1858 140735235863312:MainThread thread_manager.pyo:250 Asking <Worker(Worker-0, started 4614852608)> to stop. 2015-12-03 17:00:28,383 -0500 INFO pid=1858 4585406464:Worker-1 sync_client_thread.pyo:209 Worker-1 run loop has terminated. 2015-12-03 17:00:28,383 -0500 INFO pid=1858 4585406464:Worker-1 sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-03 17:00:28,383 -0500 WARNING pid=1858 140735235863312:MainThread sync_client_thread.pyo:237 Wake() called while thread was running, should be a N0P 2015-12-03 17:00:28,384 -0500 INFO pid=1858 140735235863312:MainThread thread_manager.pyo:250 Asking <LocalFSPoller(LocalFSPoller, started 4619059200)> to stop. 2015-12-03 17:00:28,384 -0500 INFO pid=1858 4585406464:Worker-1 sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-03 17:00:28,384 -0500 INFO pid=1858 4614852608:Worker-0 sync_client_thread.pyo:209 Worker-0 run loop has terminated. 2015-12-03 17:00:28,385 -0500 INFO pid=1858 4614852608:Worker-0 sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-03 17:00:28,385 -0500 INFO pid=1858 4585406464:Worker-1 cleanup callback 'persistence_sqlite:33cdc23fe2b54a0983af8f2c23b9bcee/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2015-12-03 17:00:28,385 -0500 INFO pid=1858 140735235863312:MainThread thread_manager.pyo:250 Asking <LocalDiskSpaceChecker(LocalDiskSpaceChecker, started 4656918528)> to stop. 2015-12-03 17:00:28,385 -0500 INFO pid=1858 4602232832:CloudWatcher connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-03 17:00:28,385 -0500 INFO pid=1858 4585406464:Worker-1 cleanup callback 'persistence_sqlite:fff39ffaafc9494aa74327e815b69e33/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2015-12-03 17:00:28,385 -0500 INFO pid=1858 4619059200:LocalFSPoller sync_client_thread.pyo:209 LocalFSPoller run loop has terminated. 2015-12-03 17:00:28,386 -0500 INFO pid=1858 4656918528:LocalDiskSpaceChecker sync_client_thread.pyo:209 LocalDiskSpaceChecker run loop has terminated. 2015-12-03 17:00:28,386 -0500 INFO pid=1858 140735235863312:MainThread thread_manager.pyo:250 Asking <TelemetryUploaderThread(TelemetryUploaderThread, started 4623265792)> to stop. utils.pyo:714 Execute sqlite.pyo:362 Close utils.pyo:714 Execute EFTA00502405 2015-12-03 17:00:28,387 -0500 INFO pid=1858 140735235863312:MainThread thread_manager.pyo:250 Asking <TelemetryStoreSizeMonitor(TelemetryStoreSizeMonitorThread, started 4627472384)> to stop. 2015-12-03 17:00:28,387 -0500 INFO pid=1858 4614852608:Worker-0 sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-03 17:00:28,388 -0500 INFO pid=1858 4602232832:CloudWatcher utils.pyo:714 Execute cleanup callback 'persistence_sqlite:8f017e8091db40clbe7f0077c6e540b2/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2015-12-03 17:00:28,388 -0500 INFO pid=1858 4623265792:TelemetryUploaderThread sync_client_thread.pyo:209 TelemetryUploaderThread run loop has terminated. 2015-12-03 17:00:28,388 -0500 INFO pid=1858 4602232832:CloudWatcher utils.pyo:714 Execute cleanup callback 'DBConnectionManagerCleanupCallback' 2015-12-03 17:00:28,388 -0500 INFO pid=1858 4614852608:Worker-0 utils.pyo:714 Execute cleanup callback 'persistence_sqlite:699967e438b44158b3c43b6adef0f2ff/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2015-12-03 17:00:28,388 -0500 INFO pid=1858 140735235863312:MainThread thread_manager.pyo:250 Asking <PushTelemetryThread(PushTelemetryThread, started 4631678976)> to stop. 2015-12-03 17:00:28,389 -0500 INFO pid=1858 4627472384:TelemetryStoreSizeMonitorThread sync_client_thread.pyo:209 TelemetryStoreSizeMonitorThread run loop has terminated. 2015-12-03 17:00:28,389 -0500 INFO pid=1858 4602232832:CloudWatcher db_connection.pyo:431 Closing ManagedConnection(id=10FF04310, thread=CloudWatcher-112508000, path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph/dict_2.db, closed=False) 2015-12-03 17:00:28,389 -0500 INFO pid=1858 4614852608:Worker-0 utils.pyo:714 Execute cleanup callback 'persistence_sqlite:ce48760dc35241c58ddd92f9006b240f/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2015-12-03 17:00:28,390 -0500 INFO pid=1858 140735235863312:MainThread thread_manager.pyo:250 Asking <FeatureSwitchPoller(FeatureSwitchPoller, started 4635885568)> to stop. 2015-12-03 17:00:28,390 -0500 INFO pid=1858 4631678976:PushTelemetryThread sync_client_thread.pyo:209 PushTelemetryThread run loop has terminated. 2015-12-03 17:00:28,391 -0500 INFO pid=1858 4631678976:PushTelemetryThread telemetry.pyo:207 Attempting final telemetry harvest. 2015-12-03 17:00:28,391 -0500 INFO pid=1858 140735235863312:MainThread thread_manager.pyo:250 Asking <PushNotificationKeepaliveThread(PushNotificationKeepaliveThread, started 4640092160)> to stop. 2015-12-03 17:00:28,391 -0500 INFO pid=1858 4635885568:FeatureSwitchPoller sync_client_thread.pyo:209 FeatureSwitchPoller run loop has terminated. 2015-12-03 17:00:28,392 -0500 INFO pid=1858 4631678976:PushTelemetryThread telemetry.pyo:167 PushStates.ONLINE: 43415.47 => 7012.29 2015-12-03 17:00:28,392 -0500 INFO pid=1858 140735235863312:MainThread thread_manager.pyo:250 Asking <Batcher(Batcher, started 4589613056)> to stop. 2015-12-03 17:00:28,393 -0500 INFO pid=1858 4640092160:PushNotificationKeepaliveThread sync_client_thread.pyo:209 PushNotificationKeepaliveThread run loop has terminated. 2015-12-03 17:00:28,393 -0500 INFO pid=1858 140735235863312:MainThread thread_manager.pyo:250 Asking <CrashReportUploaderThread(CrashReportUploaderThread, started 4652711936)> to stop. 2015-12-03 17:00:28,393 -0500 INFO pid=1858 4589613056:Batcher sync_client_thread.pyo:270 Batcher run loop has terminated. 2015-12-03 17:00:28,394 -0500 INFO pid=1858 140735235863312:MainThread thread_manager.pyo:250 Asking <IpcServerThread(IpcServerThread, started 4551110656)> to stop. 2015-12-03 17:00:28,394 -0500 INFO pid=1858 4589613056:Batcher sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-03 17:00:28,394 -0500 INFO pid=1858 140735235863312:MainThread thread_manager.pyo:250 Asking <SchedulerThread(ListenerScheduler, started 4581199872)> to stop. 2015-12-03 17:00:28,395 -0500 INFO pid=1858 140735235863312:MainThread thread_manager.pyo:250 Asking <PauseTelemetryThread(PauseTelemetryThread, started EFTA00502406 4648505344)> to stop. 2015-12-03 17:00:28,395 -0500 INFO pid=1858 140735235863312:MainThread thread manager.pyo:261 Stop completed successfully. 2015-12-03 17:00:28,395 -0500 INFO pid=1858 4648505344:PauseTelemetryThread sync_client thread.pyo:209 PauseTelemetryThread run loop has terminated. 2015-12-03 17:00:28,396 -0500 INFO pid=1858 4555317248:ThreadMonitor thread_manager.pyo:339 Waking up. 2015-12-03 17:00:28,396 -0500 INFO pid=1858 4648505344:PauseTelemetryThread pause telemetry.pyo:127 Attempting final telemetry harvest. 2015-12-03 17:00:28,396 -0500 INFO pid=1858 4648505344:PauseTelemetryThread pause_telemetry.pyo:190 Harvested pause statistics at t=1449180028.4: (‹PauseReason.API ERRORS: 'pause api err'>: _HarvestStat(paused=0.0, trial=0.0, occurrences=0), <5auseReason.BLOEKIN5_ERRORS: 'pause blocking_err'>: _HarvestStat(paused=0.0, trial=0.0, occurrences=0), <PauseReason.NETWORK_ERROTLS: 'pause_net err'>: HarvestStat(paused=0.0, trial=0.0, occurrences=0), <PauseReason.USER: 'pause_user'>: HarvestStat(paused=0.0, trial=0.0, occurrences=0), <PauseReason.OFFLINE: 'pause offline'>: HarvestStat(paused=0.0, trial=0.0, occurrences=0), <PauseReason.NO LOCAL_ DISK_ S5ACE: 'pause no space'>: _HarvestStat(paused=0.0, trial=0.0, occurrences=5)1 2015-12-03 17:00:28,494 -0500 INFO pid=1858 4589613056:Batcher utils.pyo:714 Execute cleanup callback 'persistence sqlite:3cd3e1a6fef84a8abeel4ee59ff02ff8/Users/lgroff/Library/Application Support/Googie/Drive/user default/snapshot.db' 2015-12-03 17:00:28,494 -0500 INFO pid=1858 4589613056:Batcher utils.pyo:714 Execute cleanup callback 'persistence sqlite:0ae8e4dc4eaf4f73abbffdb1836d9245/Users/lgroff/Library/Application Support/Googie/Drive/user default/sync_config.db' 2015-12-03 17:00:28,494 -0500 INFO pid=1858 4589613056:Batcher sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user default/sync_config.db 2015-12-03 17:00:28,838 -0500 WARNING pid=1858 4551110656:IpcServerThread utils.pyo:766 interrupt condition is set in Sleeper.Wait, waiting 0.2 seconds 2015-12-04 09:03:56,995 -0500 INFO pid=2404 140735235863312:MainThread logging_config.pyo:249 OS: Darwin/10.9.5 2015-12-04 09:03:56,995 -0500 INFO pid=2404 140735235863312:MainThread logging_config.pyo:252 Google Drive (build 1.26.0707.2863) 2015-12-04 09:03:56,995 -0500 INFO pid=2404 140735235863312:MainThread logging_config.pyo:253 SSL: OpenSSL 0.9.8zg 14 July 2015 2015-12-04 09:03:57,070 -0500 INFO pid=2404 140735235863312:MainThread GSyncAppDelegate.pyo:198 Application did finish launching. 2015-12-04 09:03:59,568 -0500 INFO pid=2404 140735235863312:MainThread service.pyo:132 Creating singleton service instance of '<class 'common.file lock.FileLocki> 1 2015-12-04 09:03:59,568 -0500 INFO pid=2404 140735235863312:MainThread service.pyo:132 Creating singleton service instance of '<class 'common.file lock.FileLocki> 1 2015-12-04 09:03:59,569 -0500 INFO pid=2404 4524359680:RunAiync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.service.user.User'>' 2015-12-04 09:03:59,569 -0500 INFO pid=2404 4524359680:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common. roots. roots. RootManager'>' 2015-12-04 09:03:59,569 -0500 INFO pid=2404 4524359680:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common. thread manager. ThreadManager'>' 2015-12-04 09:03:59,570 -0500 INFO pid=2404 4524359680:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.pause_manager.Service'>' 2015-12-04 09:03:59,570 -0500 INFO pid=2404 4524359680:RunAsync-_InitializeSyncAppAsync-1 sync_app.pyo:330 Getting TokenStorage... 2015-12-04 09:03:59,570 -0500 INFO pid=2404 4524359680:RunAsync-_InitializeSyncAppAsync-1 sync_app.pyo:332 Got token storage: <token storage.TokenStorage object at 0x1091f6ed0> 2015-12-04 09:03:59,570 -0500 INFO pid=2404 4524359680:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.features.AppFeatures'>' 2015-12-04 09:03:59,571 -0500 INFO pid=2404 4524359680:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.cert.Manager'>' 2015-12-04 09:03:59,621 -0500 INFO pid=2404 4524359680:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class EFTA00502407 'common.net.bandwidth.BandwidthThrottler'> 1 2015-12-04 09:03:59,663 -0500 INFO pid=2404 4524359680:RunAsync-_InitializeSyncAppAsync-1 sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-04 09:03:59,674 -0500 INFO pid=2404 4524359680:RunAsync-_InitializeSyncAppAsync-1 sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-04 09:03:59,675 -0500 INFO pid=2404 4524359680:RunAsync-_InitializeSyncAppAsync-1 utils.pyo:737 AddCleanupCallback persistence_sqlite:falcla5fb11b4ceb985ec5e221624ddf/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-04 09:03:59,675 -0500 INFO pid=2404 4524359680:RunAsync-_InitializeSyncAppAsync-1 utils.pyo:737 AddCleanupCallback persistence_sqlite:c3lce7b191e94fd2ba3cb25e27f080dc/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-04 09:03:59,801 -0500 INFO pid=2404 4524359680:RunAsync-_InitializeSyncAppAsync-1 sync_app.pyo:506 BandwidthThrottler configured via sync_config 2015-12-04 09:03:59,801 -0500 INFO pid=2404 4524359680:RunAsync-_InitializeSyncAppAsync-1 bandwidth.pyo: 59 BandwidthThrottler.SetRxBytesPerSecond 0 2015-12-04 09:03:59,801 -0500 INFO pid=2404 4524359680:RunAsync-_InitializeSyncAppAsync-1 bandwidth. pyo: 68 BandwidthThrottler.SetTxBytesPerSecond 0 2015-12-04 09:03:59,801 -0500 INFO pid=2404 4524359680:RunAsync-_InitializeSyncAppAsync-1 sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-04 09:03:59,801 -0500 INFO pid=2404 4524359680:RunAsync-_InitializeSyncAppAsync-1 sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-04 09:03:59,959 -0500 INFO pid=2404 4524359680:RunAsync-_InitializeSyncAppAsync-1 utils.pyo:714 Execute cleanup callback 'persistence_sqlite:c3lce7b191e94fd2ba3cb25e27f080dc/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2015-12-04 09:04:00,069 -0500 INFO pid=2404 4524359680:RunAsync-_InitializeSyncAppAsync-1 utils.pyo:714 Execute cleanup callback 'persistence_sqlite:falcla5fbllb4ceb985ec5e221624ddf/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2015-12-04 09:04:00,072 -0500 INFO pid=2404 4528828416:Finderlnjector sync_client_thread.pyo:175 Finderlnjector run loop has started. 2015-12-04 09:04:00,624 -0500 INFO pid=2404 140735235863312:MainThread image_resources.py:292 Loading image resources/images/ic_drawer_24.png 2015-12-04 09:04:00,891 -0500 INFO pid=2404 140735235863312:MainThread image_resources.py:292 Loading image resources/images/menu_drive-logo.png 2015-12-04 09:04:00,974 -0500 INFO pid=2404 140735235863312:MainThread image_resources.py:292 Loading image resources/images/menu_google-logo-gray.png 2015-12-04 09:04:01,125 -0500 INFO pid=2404 140735235863312:MainThread image_resources.py:292 Loading image resources/images/ic_folder_mydrive_24.png 2015-12-04 09:04:01,191 -0500 INFO pid=2404 140735235863312:MainThread image_resources.py:292 Loading image resources/images/ic_web_24.png 2015-12-04 09:04:01,195 -0500 INFO pid=2404 140735235863312:MainThread image_resources.py:292 Loading image resources/images/ic_sync_problem_24.png 2015-12-04 09:04:01,430 -0500 INFO pid=2404 140735235863312:MainThread image_resources.py:292 Loading image resources/images/ic_done.png 2015-12-04 09:04:03,610 -0500 INFO pid=2404 4545728512:ThreadMonitor thread_manager.pyo:336 Waiting on exit condition. Wake up after 3600s. 2015-12-04 09:04:03,610 -0500 INFO pid=2404 4549935104:_LaunchSyncApp sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-04 09:04:03,612 -0500 INFO pid=2404 4549935104:_LaunchSyncApp sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-04 09:04:03,612 -0500 INFO pid=2404 4549935104:_LaunchSyncApp utils.pyo:737 AddCleanupCallback persistence_sqlite:edd8f0cbe8e844b59a5c827a4543ab1e/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-04 09:04:03,612 -0500 INFO pid=2404 4549935104:_LaunchSyncApp utils.pyo:737 AddCleanupCallback EFTA00502408 persistence_sqlite:674dda2981814dacaadlff429422fb14/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-04 09:04:03,635 -0500 INFO pid=2404 4549935104:_LaunchSyncApp user.pyo:66 Initializing User instance with new credentials. None 2015-12-04 09:04:03,635 -0500 INFO pid=2404 4549935104:_LaunchSyncApp docs_client_factory.pyo:89 Creating a new DriveClient 2015-12-04 09:04:03,635 -0500 INFO pid=2404 4549935104:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.quota.Quotalnfo'>' 2015-12-04 09:04:03,637 -0500 INFO pid=2404 4549935104:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.token_bucket.CloudRucketManager'> 1 2015-12-04 09:04:03,637 -0500 INFO pid=2404 140735235863312:MainThread sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-04 09:04:03,638 -0500 INFO pid=2404 4549935104:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.drive_api.drive_resource.Manager'>' 2015-12-04 09:04:03,639 -0500 INFO pid=2404 4549935104:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.drive_api.http.Manager'>' 2015-12-04 09:04:03,639 -0500 INFO pid=2404 4549935104:_LaunchSyncApp drive_resource.pyo:130 Initialize apiclient Drive resource for current user token 2015-12-04 09:04:03,640 -0500 INFO pid=2404 140735235863312:MainThread sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-04 09:04:03,640 -0500 INFO pid=2404 140735235863312:MainThread sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-04 09:04:03,640 -0500 INFO pid=2404 140735235863312:MainThread sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-04 09:04:04,004 -0500 INFO pid=2404 4549935104:_LaunchSyncApp drive_resource.pyo:178 Drive SDK baseUrl = https://www.googleapis.com/drive/v2internal/ 2015-12-04 09:04:04,006 -0500 INFO pid=2404 4549935104:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.drive_api.http.AccessTokenInMemoryCache'>. 2015-12-04 09:04:04,006 -0500 INFO pid=2404 4549935104:_LaunchSyncApp drive_request.pyo:285 Drive request: About(tags=Reason.SYNC_OTHER) - Read request - None 2015-12-04 09:04:04,009 -0500 INFO pid=2404 4549935104:_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 2015-12-04 09:04:04,055 -0500 INFO pid=2404 4549935104:_LaunchSyncApp client.pyo:515 Attempting refresh to obtain initial access_token 2015-12-04 09:04:04,055 -0500 INFO pid=2404 4549935104:_LaunchSyncApp client.pyo:755 Refreshing access_token 2015-12-04 09:04:04,058 -0500 INFO pid=2404 4549935104:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.net.dapper.DapperService'>' 2015-12-04 09:04:04,059 -0500 INFO pid=2404 4549935104:_LaunchSyncApp http.pyo:248 Request user-agent: Python-httplib2/0.9 (gzip) 2015-12-04 09:04:04,059 -0500 INFO pid=2404 4549935104:_LaunchSyncApp sync_http_client.pyo:236 Opening direct connecton to accounts.google.com:443. 2015-12-04 09:04:04,213 -0500 INFO pid=2404 4549935104:_LaunchSyncApp http.pyo:267 Response: 151 bytes - application/json; charset=utf-8 (encoding:gzip) 2015-12-04 09:04:04,557 -0500 INFO pid=2404 4549935104:_LaunchSyncApp http.pyo:248 Request user-agent: googledrivesync-1.26.0707.2863 google-api-python-client/1.3.1 (gzip) (Darwin/10.9.5) 2015-12-04 09:04:04,557 -0500 INFO pid=2404 4549935104:_LaunchSyncApp sync_http_client.pyo:236 Opening direct connecton to www.googleapis.com:443. 2015-12-04 09:04:05,468 -0500 INFO pid=2404 4549935104:_LaunchSyncApp http.pyo:267 Response: 241 bytes - application/json; charset=UTF-8 (encoding:gzip) 2015-12-04 09:04:05,564 -0500 INFO pid=2404 4549935104:_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=15172369832, EFTA00502409 quota_bytes_used_in_trash=405074503, quota_type=LIMITED) 2015-12-04 09:04:05,564 -0500 INFO pid=2404 4549935104:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.drive_api.doc_id_provider.Store'>' 2015-12-04 09:04:05,565 -0500 INFO pid=2404 4549935104:_LaunchSyncApp user.pyo:66 Initializing User instance with new credentials. lesley.jee@gmail.com 2015-12-04 09:04:05,565 -0500 INFO pid=2404 4549935104:_LaunchSyncApp sync_app.pyo:1235 Configuring sync app from feature switches. 2015-12-04 09:04:05,565 -0500 INFO pid=2404 4549935104:_LaunchSyncApp features.pyo:112 Loads feature switches. 2015-12-04 09:04:05,565 -0500 INFO pid=2404 4549935104:_LaunchSyncApp feature_switch_manager.pyo:644 Calling server to get feature switches. 2015-12-04 09:04:05,565 -0500 INFO pid=2404 4549935104:_LaunchSyncApp drive_request.pyo:285 Drive request: ListFeatureSwitches(tags=Reason.SYNC_OTHER) - Read request - lesley.jee@gmail.com 2015-12-04 09:04:05,567 -0500 INFO pid=2404 4549935104:_LaunchSyncApp 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-12-04 09:04:05,567 -0500 INFO pid=2404 4549935104:_LaunchSyncApp http.pyo:248 Request user-agent: googledrivesync-1.26.0707.2863 google-api-python-client/1.3.1 (gzip) (Darwin/10.9.5) 2015-12-04 09:04:05,626 -0500 INFO pid=2404 4549935104:_LaunchSyncApp http.pyo:267 Response: 1080 bytes - application/json; charset=UTF-8 (encoding:gzip) 2015-12-04 09:04:05,629 -0500 INFO pid=2404 4549935104:_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'enable_multiple_accounts': u'false', u'enable_recursive_size': 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', u'enable_feedback': u'true', u'enable_multipause': u'true', 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': u'false') 2015-12-04 09:04:05,630 -0500 INFO pid=2404 4549935104:_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, EFTA00502410 enable_shared_move_confirmation=False, enable_usb_detection=False, feedback_type='procr, 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='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, open_url=ihttps://docs.google.com/open?id=fdoc_id)', overlays_enabled_finder_versions=[ 1 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, query_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_qps=10, token_bucketwrite_qps=3, use_drive_api=False,) 2015-12-04 09:04:05,631 -0500 INFO pid=2404 4549935104:_LaunchSyncApp logging_config.pyo:249 OS: Darwin/10.9.5 2015-12-04 09:04:05,666 -0500 INFO pid=2404 4549935104:_LaunchSyncApp logging_config.pyo:252 Google Drive (build 1.26.0707.2863) 2015-12-04 09:04:05,666 -0500 INFO pid=2404 4549935104:_LaunchSyncApp logging_config.pyo:253 SSL: OpenSSL 0.9.8zg 14 July 2015 2015-12-04 09:04:05,666 -0500 INFO pid=2404 4549935104:_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 2015-12-04 09:04:05,669 -0500 INFO pid=2404 4549935104:_LaunchSyncApp sync_app.pyo:1274 Switching cloud client based on new feature switches 2015-12-04 09:04:05,669 -0500 INFO pid=2404 4549935104:_LaunchSyncApp docs_client_factory.pyo:97 Creating a new Wapi DocsClient 2015-12-04 09:04:05,670 -0500 INFO pid=2404 4549935104:_LaunchSyncApp telemetry.pyo:228 SetPushClientVersion: 1 (3, 20130807) 2015-12-04 09:04:05,670 -0500 INFO pid=2404 4549935104:_LaunchSyncApp service.pyo:132 EFTA00502411 Creating singleton service instance of '<class 'common.push.telemetry.PushTelemetry'>' 2015-12-04 09:04:05,670 -0500 INFO pid=2404 4549935104:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.telemetry.telemetry_manager.TelemetryManager'> 1 2015-12-04 09:04:05,922 -0500 INFO pid=2404 4549935104:_LaunchSyncApp image_resources.py:268 Loading custom image resources/images/folder-mac.icns with function _CreateNSlmage 2015-12-04 09:04:05,923 -0500 INFO pid=2404 4549935104:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.roots.root_wrangler.RootWrangler'>. 2015-12-04 09:04:05,923 -0500 INFO pid=2404 4549935104:_LaunchSyncApp root_wrangler.pyo:65 RootWrangler.RootObserverRegistered with roots ]_MyDriveRoot(u'/Users/lgroff/Google Drive', 'root', <RootState.INITED: 2>)] 2015-12-04 09:04:05,924 -0500 INFO pid=2404 4549935104:_LaunchSyncApp root_wrangler.pyo:73 RootWrangler.RootsAdded with roots (_MyDriveRoot(u'/Users/lgroff/Google Drive', 'root', <RootState.INITED: 2>)] 2015-12-04 09:04:05,924 -0500 INFO pid=2404 4549935104:_LaunchSyncApp root_wrangler.pyo:79 RootWrangler got inited root _MyDriveRoot(u'/Users/lgroff/Google Drive', 'root', <RootState. 2015-12-04 Registering 2015-12-04 Registering 2015-12-04 Registering 2015-12-04 Registering 2015-12-04 Registering 2015-12-04 Registering 2015-12-04 Registering 2015-12-04 Registering 2015-12-04 filewatcher_ext initialized 2015-12-04 09:04:07,417 -0500 INFO pid=2404 4549935104:_LaunchSyncApp docs_client_factory.pyo:97 Creating a new Wapi DocsClient 2015-12-04 09:04:07,418 -0500 INFO pid=2404 4549935104:_LaunchSyncApp docs_client_factory.pyo:97 Creating a new Wapi DocsClient 2015-12-04 09:04:07,419 -0500 INFO pid=2404 4549935104:_LaunchSyncApp docs_client_factory.pyo:97 Creating a new Wapi DocsClient 2015-12-04 09:04:07,419 -0500 INFO pid=2404 4549935104:_LaunchSyncApp docs_client_factory.pyo:97 Creating a new Wapi DocsClient 2015-12-04 09:04:07,420 -0500 INFO pid=2404 4549935104:_LaunchSyncApp docs_client_factory.pyo:97 Creating a new Wapi DocsClient 2015-12-04 09:04:07,421 -0500 INFO pid=2404 4549935104:_LaunchSyncApp docs_client_factory.pyo:97 Creating a new Wapi DocsClient 2015-12-04 09:04:07,421 -0500 INFO pid=2404 4549935104:_LaunchSyncApp docs_client_factory.pyo:97 Creating a new Wapi DocsClient 2015-12-04 09:04:07,422 -0500 INFO pid=2404 4549935104:_LaunchSyncApp xmpplib_notifier.pyo:34 Configure storage: <common.push.tango.storage.SyncConfigStorage; proxy of <Swig Object of type 'invalidation::Storage *' at 0x10f8499c0> > 2015-12-04 09:04:07,422 -0500 INFO pid=2404 4549935104:_LaunchSyncApp xmpplib_notifier.pyo:59 Creating client thread 2015-12-04 09:04:07,422 -0500 INFO pid=2404 4549935104:_LaunchSyncApp push_xmpp_client.pyo:223 Using talk server: talk.google.com:5222 2015-12-04 09:04:07,423 -0500 INFO pid=2404 4549935104:_LaunchSyncApp xmpplib_notifier.pyo:68 Creating notification bridge 2015-12-04 09:04:07,521 -0500 INFO pid=2404 4549935104:_LaunchSyncApp docs_client_factory.pyo:97 Creating a new Wapi DocsClient 2015-12-04 09:04:07,523 -0500 INFO pid=2404 4571496448:OverlayStatusCleaner sync_client_thread.pyo:175 OverlayStatusCleaner run loop has started. 2015-12-04 09:04:07,524 -0500 INFO pid=2404 4575703040:XmppClient state None => PushStates.CONNECTING 2015-12-04 09:04:07,525 -0500 INFO pid=2404 4579909632:Worker-0 INITED: 2>) 09:04:05,924 aggregation 09:04:05,924 aggregation 09:04:05,924 aggregation 09:04:05,924 aggregation 09:04:05,924 aggregation 09:04:05,924 aggregation 09:04:05,924 aggregation 09:04:05,925 aggregation 09:04:07,416 -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=2404 4549935104:_LaunchSyncApp rule_factory.pyo:159 common.aggregator_rules.downloads.DownloadCreate pid=2404 4549935104:_LaunchSyncApp rule_factory.pyo:159 common.aggregator_rules.downloads.DownloadModify pid=2404 4549935104:_LaunchSyncApp rule_factory.pyo:159 common.aggregator_rules.uploads.UploadCreate pid=2404 4549935104:_LaunchSyncApp rule_factory.pyo:159 common.aggregator_rules.uploads.UploadDelete pid=2404 4549935104:_LaunchSyncApp rule_factory.pyo:159 common.aggregator_rules.uploads.UploadModify pid=2404 4549935104:_LaunchSyncApp rule_factory.pyo:159 common.aggregator_rules.uploads.UploadMove pid=2404 4549935104:_LaunchSyncApp rule_factory.pyo:159 common.aggregator_rules.uploads.UploadMoveFrom pid=2404 4549935104:_LaunchSyncApp rule_factory.pyo:159 common.aggregator_rules.uploads.UploadMoveTo pid=2404 4563083264:FilewatcherMonitor filewatcher.pyo:71 telemetry.pyo:153 Push sqlite.pyo:276 Open EFTA00502412 master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-04 09:04:07,525 -0500 INFO pid=2404 4584116224:CloudWatcher sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-04 09:04:07,525 -0500 INFO pid=2404 4588322816:RawEventUpgrader sync_client_thread.pyo:175 RawEventUpgrader run loop has started. 2015-12-04 09:04:07,526 -0500 INFO pid=2404 4575703040:XmppClient connection_task.pyo:151 Recalculated LoginTask timeout: 16.000000 2015-12-04 09:04:07,526 -0500 INFO pid=2404 4592529408:TelemetryStoreSizeMonitorThread sync_client_thread.pyo:175 TelemetryStoreSizeMonitorThread run loop has started. 2015-12-04 09:04:07,526 -0500 INFO pid=2404 4596736000:PushTelemetryThread sync_client_thread.pyo:175 PushTelemetryThread run loop has started. 2015-12-04 09:04:07,527 -0500 INFO pid=2404 4600942592:FeatureSwitchPoller sync_client_thread.pyo:175 FeatureSwitchPoller run loop has started. 2015-12-04 09:04:07,527 -0500 INFO pid=2404 4605149184:Batcher sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-04 09:04:07,528 -0500 INFO pid=2404 4609355776:CrashReportUploaderThread crash_report_uploader_thread.pyo: 60 Starting CrashReportUploaderThread. 2015-12-04 09:04:07,528 -0500 INFO pid=2404 4613562368:Worker-2 sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-04 09:04:07,529 -0500 INFO pid=2404 4575703040:XmppClient telemetry.pyo:167 PushStates.CONNECTING: +0.00449341 => 0.00449341 2015-12-04 09:04:07,529 -0500 INFO pid=2404 4592529408:TelemetryStoreSizeMonitorThread telemetry_store_monitor_thread.pyo: 44 Checking Telemetry store size. 2015-12-04 09:04:07,529 -0500 INFO pid=2404 4617768960:PauseTelemetryThread sync_client_thread.pyo:175 PauseTelemetryThread run loop has started. 2015-12-04 09:04:07,529 -0500 INFO pid=2404 4621975552:LocalFSPoller sync_client_thread.pyo:175 LocalFSPoller run loop has started. 2015-12-04 09:04:07,530 -0500 INFO pid=2404 4626182144:LocalDiskSpaceChecker sync_client_thread.pyo:175 LocalDiskSpaceChecker run loop has started. 2015-12-04 09:04:07,530 -0500 INFO pid=2404 4600942592:FeatureSwitchPoller feature_switch_manager.pyo:644 Calling server to get feature switches. 2015-12-04 09:04:07,530 -0500 INFO pid=2404 4630388736:TelemetryUploaderThread sync_client_thread.pyo:175 TelemetryUploaderThread run loop has started. 2015-12-04 09:04:07,531 -0500 INFO pid=2404 4634595328:LocalWatcher watcher.pyo:347 LocalWatcher Run invoked.Waiting for first pass of local scan from poller to start 2015-12-04 09:04:07,531 -0500 INFO pid=2404 4643008512:Worker-1 sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-04 09:04:07,531 -0500 INFO pid=2404 4647215104:PushNotificationKeepaliveThread sync_client_thread.pyo:175 PushNotificationKeepaliveThread run loop has started. 2015-12-04 09:04:07,532 -0500 INFO pid=2404 4575703040:XmppClient xmpplib.pyo:80 Connecting to talk.google.com: 5222 2015-12-04 09:04:07,533 -0500 INFO pid=2404 4549935104:_LaunchSyncApp drive_extension_installer.pyo:174 Uninstalling extension nknebiagdodnminbdpflhpkgfpeijdbf 2015-12-04 09:04:07,534 -0500 INFO pid=2404 4600942592:FeatureSwitchPoller drive_resource.pyo:130 Initialize apiclient Drive resource for lesley.jee@gmail.com 2015-12-04 09:04:07,537 -0500 INFO pid=2404 4647215104:PushNotificationKeepaliveThread sync_http_client.pyo:236 Opening direct connecton to accounts.google.com:443. 2015-12-04 09:04:07,537 -0500 INFO pid=2404 4549935104:_LaunchSyncApp utils.pyo:714 Execute cleanup callback 'persistence_sqlite:edd8f0cbe8e844b59a5c827a4543able/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2015-12-04 09:04:07,551 -0500 INFO pid=2404 4600942592:FeatureSwitchPoller drive_resource.pyo: 178 Drive SDK baseUrl = https://www.googleapis.com/drive/v2internal/ 2015-12-04 09:04:07,556 -0500 INFO pid=2404 4549935104:_LaunchSyncApp sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-04 09:04:07,561 -0500 INFO pid=2404 4600942592:FeatureSwitchPoller drive_request.pyo:285 Drive request: ListFeatureSwitches(tags=Reason.SYNC_OTHER) - Read request - lesley.jee@gmail.com 2015-12-04 09:04:07,565 -0500 INFO pid=2404 4600942592:FeatureSwitchPoller discovery.pyo:762 EFTA00502413 URL being requested: GET https://www.googleapis.com/drive/v2internal/settings? fields=items%2Fkey%2Citems%2Fvalue&reason=3048namespace=FEATURE_SWITCHfialt=json 2015-12-04 09:04:07,567 -0500 INFO pid=2404 4600942592:FeatureSwitchPoller client.pyo:515 Attempting refresh to obtain initial access_token 2015-12-04 09:04:07,567 -0500 INFO pid=2404 4600942592:FeatureSwitchPoller client.pyo:735 Updated access_token read from Storage 2015-12-04 09:04:07,568 -0500 INFO pid=2404 4600942592:FeatureSwitchPoller http.pyo:248 Request user-agent: googledrivesync-1.26.0707.2863 google-api-python-client/1.3.1 (gzip) (Darwin/10.9.5) 2015-12-04 09:04:07,568 -0500 INFO pid=2404 4600942592:FeatureSwitchPoller sync_http_client.pyo:236 Opening direct connecton to www.googleapis.com:443. 2015-12-04 09:04:07,570 -0500 INFO pid=2404 4613562368:Worker-2 sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-04 09:04:07,571 -0500 INFO pid=2404 4605149184:Batcher sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-04 09:04:07,571 -0500 INFO pid=2404 4584116224:CloudWatcher sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-04 09:04:07,572 -0500 INFO pid=2404 4643008512:Worker-1 sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-04 09:04:07,572 -0500 INFO pid=2404 4549935104:_LaunchSyncApp utils.pyo:714 Execute cleanup callback 'persistence_sqlite:674dda2981814dacaadlff429422fb14/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2015-12-04 09:04:07,572 -0500 INFO pid=2404 4579909632:Worker-0 sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-04 09:04:07,573 -0500 INFO pid=2404 4549935104:_LaunchSyncApp sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-04 09:04:07,575 -0500 INFO pid=2404 4613562368:Worker-2 utils.pyo:737 AddCleanupCallback persistence_sqlite:fd35ea9018bb4a37bd9efl7a712430b2/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-04 09:04:07,575 -0500 INFO pid=2404 4613562368:Worker-2 utils.pyo:737 AddCleanupCallback persistence_sqlite:f3b8c00f4eeb457890c2b1339db82583/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-04 09:04:07,576 -0500 INFO pid=2404 4613562368:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadChangeACL 2015-12-04 09:04:07,576 -0500 INFO pid=2404 4613562368:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadCreate 2015-12-04 09:04:07,576 -0500 INFO pid=2404 4613562368:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadDelete 2015-12-04 09:04:07,577 -0500 INFO pid=2404 4613562368:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadModify 2015-12-04 09:04:07,577 -0500 INFO pid=2404 4584116224:CloudWatcher utils.pyo:737 AddCleanupCallback persistence_sqlite:fb175d257e57464ba7aad5100083b8b5/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-04 09:04:07,578 -0500 INFO pid=2404 4579909632:Worker-0 utils.pyo:737 AddCleanupCallback persistence_sqlite: 93ab0f58bea64a6bbe5b90f3683ca4db/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-04 09:04:07,578 -0500 INFO pid=2404 4643008512:Worker-1 utils.pyo:737 AddCleanupCallback persistence_sqlite:3f6904f095704ee8928bf32c5a58270f/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db EFTA00502414 2015-12-04 09:04:07,578 -0500 INFO pid=2404 4605149184:Batcher utils.pyo:737 AddCleanupCallback persistence_sglite:06ee6bcf7b584f1b84f608178560577d/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-04 09:04:07,578 -0500 INFO pid=2404 4613562368:Worker-2 command factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadMove 2015-12-04 09:04:07,578 -0500 INFO pid=2404 4584116224:CloudWatcher utils.pyo:737 AddCleanupCallback persistence_sglite:945b68b8abd24c3lbb4735a5ae373466/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-04 09:04:07,578 -0500 INFO pid=2404 4579909632:Worker-0 utils.pyo:737 AddCleanupCallback persistence_sglite:653c7b6363c34316a872c0af3fda4e18/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-04 09:04:07,579 -0500 INFO pid=2404 4643008512:Worker-1 utils.pyo:737 AddCleanupCallback persistence_sglite:3818cb12f7c64162a0b4571676880db2/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-04 09:04:07,579 -0500 INFO pid=2404 4605149184:Batcher utils.pyo:737 AddCleanupCallback persistence_scilite:e6ff9dbllc534689846fdecl7fcb771f/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-04 09:04:07,579 -0500 INFO pid=2404 4613562368:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadRename 2015-12-04 09:04:07,579 -0500 INFO pid=2404 4584116224:CloudWatcher cloud_graph.pyo:115 Initialize persistent graph with generation 2 2015-12-04 09:04:07,581 -0500 INFO pid=2404 4579909632:Worker-0 command_factory.pyo:186 Registering worker command type: common.worker.uploads.BatchUploadCreate 2015-12-04 09:04:07,581 -0500 INFO pid=2404 4643008512:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.uploads.BatchUploadCreate 2015-12-04 09:04:07,582 -0500 INFO pid=2404 4613562368:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.uploads.BatchUploadCreate 2015-12-04 09:04:07,583 -0500 INFO pid=2404 4584116224:CloudWatcher Current sync config ge 2015-12-04 09:04:07,58 command_factory.pyo:18 2015-12-04 09:04:07,58 command_factory.pyo:18 2015-12-04 09:04:07,58 command_factory.pyo:18 2015-12-04 09:04:07,58 command_factory.pyo:18 2015-12-04 09:04:07,58 neration: 2 3 -0500 INFO pid=2404 4579909632:Worker-0 6 Registering worker command type: common.worker 3 -0500 INFO pid=2404 4643008512:Worker-1 6 Registering worker command type: common.worker 3 -0500 INFO pid=2404 4613562368:Worker-2 6 Registering worker command type: common.worker 3 -0500 INFO pid=2404 4579909632:Worker-0 6 Registering worker command type: common.worker 3 -0500 INFO pid=2404 4584116224:CloudWatcher 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, 'dict 2.db-shml, 'dict 2.db-wal'] 2015-12-04 09:04:07,584 -0500 INFO pid=2404 4643008512:Worker-1 command_factory.pyo:186 2015-12-04 09:04:07,584 command_factory.pyo:186 2015-12-04 09:04:07,584 command_factory.pyo:186 2015-12-04 09:04:07,585 command_factory.pyo:186 2015-12-04 09:04:07,585 command_factory.pyo:186 2015-12-04 09:04:07,585 command_factory.pyo:186 2015-12-04 09:04:07,586 command_factory.pyo:186 2015-12-04 09:04:07,588 cloud_graph.pyo: 1563 Registering worker command type: common -0500 INFO pid=2404 4613562368:Worker-2 Registering worker command type: common -0500 INFO pid=2404 4579909632:Worker-0 Registering worker command type: common -0500 INFO pid=2404 4643008512:Worker-1 Registering worker command type: common -0500 INFO pid=2404 4613562368:Worker-2 Registering worker command type: common -0500 INFO pid=2404 4579909632:Worker-0 Registering worker command type: common -0500 INFO pid=2404 4643008512:Worker-1 Registering worker command type: common -0500 INFO pid=2404 4643008512:Worker-1 .uploads.UploadCreate .uploads.UploadCreate .uploads.UploadCreate .uploads.UploadDelete cloud graph.pyo:1415 .worker.uploads.UploadDelete .worker.uploads.UploadDelete .worker.uploads.UploadModify .worker.uploads.UploadModify .worker.uploads.UploadModify .worker . uploads . UploadMove .worker.uploads.UploardMove EFTA00502415 command_factory.pyo:186 2015-12-04 09:04:07,586 command_factory.pyo:186 2015-12-04 09:04:07,587 command_factory.pyo:186 2015-12-04 09:04:07,588 command_factory.pyo:186 common.worker.internals. 2015-12-04 09:04:07,588 command_factory.pyo:186 2015-12-04 09:04:07,588 command_factory.pyo:186 common.worker.internals. 2015-12-04 09:04:07,588 command_factory.pyo:186 common.worker.internals. 2015-12-04 09:04:07,589 Registering worker command type: common -0500 INFO pid=2404 4613562368:Worker-2 Registering worker command type: common -0500 INFO pid=2404 4579909632:Worker-0 Registering worker command type: common -0500 INFO pid=2404 4643008512:Worker-1 Registering worker command type: InternalCreate -0500 INFO pid=2404 4613562368:Worker-2 Registering worker command type: common -0500 INFO pid=2404 4579909632:Worker-0 Registering worker command type: InternalCreate -0500 INFO pid=2404 4643008512:Worker-1 Registering worker command type: InternalModify -0500 INFO pid=2404 4613562368:Worker-2 Waiting for batcher to initialize 2015-12-04 09:04:07,589 -0500 INFO pid=2404 4579909632:Worker-0 command_factory.pyo:186 Registering worker command type: common.worker.internals.InternalModify 2015-12-04 09:04:07,589 -0500 INFO pid=2404 4643008512:Worker-1 Waiting for batcher to initialize 2015-12-04 09:04:07,589 -0500 INFO pid=2404 4613562368:Worker-2 sync_client_thread.pyo:175 Worker-2 run loop has started. 2015-12-04 09:04:07,589 -0500 INFO pid=2404 4579909632:Worker-0 Waiting for batcher to initialize 2015-12-04 09:04:07,589 -0500 INFO pid=2404 4643008512:Worker-1 sync_client_thread.pyo:175 Worker-1 run loop has started. 2015-12-04 09:04:07,590 -0500 INFO pid=2404 4579909632:Worker-0 sync_client_thread.pyo:175 Worker-0 run loop has started. 2015-12-04 09:04:07,596 -0500 INFO pid=2404 4575703040:XmppClient PushStates.CONNECTING: +0.0676827 => 0.0721761 2015-12-04 09:04:07,597 -0500 INFO pid=2404 4575703040:XmppClient state PushStates.CONNECTING => PushStates.NEGOTIATING 2015-12-04 09:04:07,636 -0500 INFO pid=2404 4584116224:CloudWatcher AddCleanupCallback DBConnectionManagerCleanupCallback 2015-12-04 09:04:07,636 -0500 INFO pid=2404 4584116224: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-12-04 09:04:07,637 -0500 INFO pid=2404 4584116224:CloudWatcher cloud_graph.pyo:956 Reuse CloudDiskDict database: /Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph/dict_2.db 2015-12-04 09:04:07,675 -0500 INFO pid=2404 4575703040:XmppClient xmpplib.pyo:420 Start SSL peer=gmail.com 2015-12-04 09:04:07,729 -0500 INFO pid=2404 4600942592:FeatureSwitchPoller http.pyo:267 Response: 1080 bytes - application/json; charset=UTF-8 (encoding:gzip) 2015-12-04 09:04:07,747 -0500 INFO pid=2404 4647215104:PushNotificationKeepaliveThread sync_http_client.pyo:236 Opening direct connecton to clients3.google.com:443. 2015-12-04 09:04:07,747 -0500 INFO pid=2404 4575703040:XmppClient telemetry.pyo:167 PushStates.NEGOTIATING: +0.151125 => 0.151125 2015-12-04 09:04:07,788 -0500 INFO pid=2404 4600942592: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'enable_multiple_accounts': u'false', u'enable_recursive_size': 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', u'enable_feedback': u'true', u'enable_multipause': u'true', 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': wall', u'selective_sub_folder_sync': u'false') 2015-12-04 09:04:07,841 -0500 INFO pid=2404 4575703040:XmppClient telemetry.pyo:167 .worker.uploads.UploadRename .worker.uploads.UploaciMove .worker.uploads.UploadRename .worker.uploads.UploadRename workers.pyo:127 workers.pyo:127 workers.pyo:127 telemetry.pyo: 167 telemetry.pyo:153 Push utils.pyo:737 EFTA00502416 PushStates.NEGOTIATING: +0.0932982 => 0.244424 2015-12-04 09:04:07,899 -0500 INFO pid=2404 4584116224: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 2015-12-04 09:04:07,900 -0500 INFO pid=2404 4584116224:CloudWatcher cloud_graph.pyo:216 Building gdoc docids cache. 2015-12-04 09:04:07,972 -0500 INFO pid=2404 4575703040:XmppClient task.pyo:254 Task <common.push.xmpp.login_task.LoginTask object at 0x10f80ac10> complete. 2015-12-04 09:04:07,972 -0500 WARNING pid=2404 4575703040:XmppClient login_task.pyo:90 Clearing connection for LoginTask: <common.push.xmpp.login_task.LoginTask object at 0x10f80ac10> 2015-12-04 09:04:07,972 -0500 INFO pid=2404 4575703040:XmppClient push_xmpp_client.pyo:313 Connected to talk.google.com:5222 2015-12-04 09:04:07,973 -0500 WARNING pid=2404 4549935104:RunAsync-_OnXmppConnect-4 notification_bridge.pyo:126 XMPP detected connect. 2015-12-04 09:04:07,973 -0500 INFO pid=2404 4549935104:RunAsync-_OnXmppConnect-4 xmpp_network_channel.pyo:169 Sending push channel subscription request 2015-12-04 09:04:08,015 -0500 INFO pid=2404 4575703040:XmppClient xmpp_network_channel.pyo:187 Received subscription response from Push for XMPPBot 2015-12-04 09:04:08,015 -0500 INFO pid=2404 4549935104:RunAsync-_OnXmppConnect-4 notification_bridge.pyo:142 Starting TICL (client version 3.20130807)... 2015-12-04 09:04:08,016 -0500 INFO pid=2404 4549935104:RunAsync-_OnXmppConnect-4 xmpp_network_channel.pyo: 65 XmppNetworkChannel.InformNetworkStatus: CONNECTED 2015-12-04 09:04:08,072 -0500 INFO pid=2404 4651421696:ListenerScheduler invalidation_listener.pyo:185 InvalidationListener.ReissueRegistrations(prefix=, prefix_len=0) 2015-12-04 09:04:08,491 -0500 INFO pid=2404 4647215104:PushNotificationKeepaliveThread push_notification_keepalive_thread.pyo:49 Sent push keepalive message at: Fri Dec 4 09:04:08 2015 2015-12-04 09:04:09,211 -0500 INFO pid=2404 4584116224:CloudWatcher cloud_graph.pyo:161 Reuse CloudDiskDict CloudDiskDict(max_change_stamp=75617, app_version=1.26.0707.2863, ()) 2015-12-04 09:04:09,211 -0500 INFO pid=2404 4584116224:CloudWatcher cloud_watcher.pyo:831 Performing full snapshot diff. 2015-12-04 09:04:09,211 -0500 INFO pid=2404 4584116224:CloudWatcher cloud_watcher.pyo:1026 Getting cloud resources via changelog feed from 75618 2015-12-04 09:04:09,212 -0500 INFO pid=2404 4584116224:CloudWatcher docs_client.pyo:743 Requesting Doc List: /feeds/default/private/changes?start-index=75618&showroot=true&include- shared=true&reason=startup 2015-12-04 09:04:09,212 -0500 INFO pid=2404 4584116224:CloudWatcher sync_http_client.pyo:236 Opening direct connecton to clients3.google.com:443. 2015-12-04 09:04:09,487 -0500 INFO pid=2404 4584116224:CloudWatcher docs_client.pyo:1174 Feed has 2 entries 2015-12-04 09:04:09,487 -0500 INFO pid=2404 4584116224:CloudWatcher docs_client.pyo:1181 End of feed, no next URI 2015-12-04 09:04:09,490 -0500 INFO pid=2404 4584116224:CloudWatcher cloud_watcher.pyo:807 Got 2 cloud entries 2015-12-04 09:04:09,610 -0500 INFO pid=2404 4584116224:CloudWatcher cloud_watcher.pyo:815 710 nodes in the cloud graph 2015-12-04 09:04:09,641 -0500 INFO pid=2404 4584116224:CloudWatcher cloud_snapshot_diff_helper.pyo:84 Prepare for Pruning graph (total of 710 entries). sync gdocs=True, sync folders=None, excluded folders=frozenset(()) 2015-12-04 09:04:09,641 -0500 INFO pid=2404 4584116224:CloudWatcher cloud_snapshot_diff_helper.pyo:85 Start Prune Graph (total of 710 entries) 2015-12-04 09:04:09,653 -0500 INFO pid=2404 4584116224:CloudWatcher cloud_snapshot_diff_helper.pyo:175 Pruning detected 694 items to keep 2015-12-04 09:04:09,655 -0500 INFO pid=2404 4584116224:CloudWatcher cloud_snapshot_diff_helper.pyo:182 694 entries in graph after pruning 2015-12-04 09:04:09,655 -0500 INFO pid=2404 4584116224:CloudWatcher cloud_snapshot_diff_helper.pyo:93 Compare to snaphost 2015-12-04 09:04:09,655 -0500 INFO pid=2404 4584116224:CloudWatcher cloud_snapshot_diff_helper.pyo:762 Starting cloud graph vs snapshot diff with selective sync set: None 2015-12-04 09:04:09,655 -0500 INFO pid=2404 4584116224:CloudWatcher cloud_snapshot_diff_helper.pyo:764 Prepopulating DOC_ID-to-inodes cache 2015-12-04 09:04:09,659 -0500 INFO pid=2404 4584116224:CloudWatcher EFTA00502417 cloud_snapshot_diff_helper.pyo:768 First pass detecting moved entries 2015-12-04 09:04:09,958 -0500 INFO pid=2404 4584116224:CloudWatcher cloud_snapshot_diff_helper.pyo:783 Second pass detecting creations and modification entries 2015-12-04 09:04:10,314 -0500 INFO pid=2404 4584116224:CloudWatcher cloud_snapshot_diff_helper.pyo:810 Processed 693 entries in total. Number of events detected: (} 2015-12-04 09:04:10,385 -0500 INFO pid=2404 4584116224:CloudWatcher cloud_watcher.pyo:875 Done with full snapshot diff. 2015-12-04 09:04:10,535 -0500 INFO pid=2404 4621975552:LocalFSPoller poller.pyo:126 Cloud scan complete. Preparing to start LocalFSPoller 2015-12-04 09:04:10,535 -0500 INFO pid=2404 4621975552:LocalFSPoller poller.pyo:137 LocalFSPoller first pass started. Waiting for localWatcher to start watching. 2015-12-04 09:04:10,535 -0500 INFO pid=2404 4634595328:LocalWatcher watcher.pyo:266 RootsAdded with roots [_MyDriveRootfu'/Users/lgroff/Google Drive', 'root', <RootState.INITED: 2>)] 2015-12-04 09:04:10,536 -0500 INFO pid=2404 4634595328:LocalWatcher watcher.pyo:271 watcher adding new root to graph: _MyDriveRoot(u'/Users/lgroff/Google Drive', 'root', <RootState.INITED: 2>) 2015-12-04 09:04:10,536 -0500 INFO pid=2404 4634595328:LocalWatcher Scheduling roots: [_MyDriveRoot(u'/Users/lgroff/Google Drive', 'root', 2>)] 2015-12-04 09:04:10,536 -0500 INFO pid=2404 4634595328:LocalWatcher Scheduling root: _MyDriveRootfu'/Users/lgroff/Google Drive', 'root', 2015-12-04 09:04:10,536 -0500 INFO pid=2404 4634595328:LocalWatcher LocalWatcher is up. 2015-12-04 09:04:10,537 -0500 INFO pid=2404 4621975552:LocalFSPoller Finished waiting for LocalWatcher. 2015-12-04 09:04:10,537 -0500 INFO pid=2404 4621975552:LocalFSPoller Starting a local disk scan. 2015-12-04 09:04:10,537 -0500 INFO pid=2404 4621975552:LocalFSPoller Walking: [u'/Users/lgroff/Google Drive'] 2015-12-04 09:04:10,717 -0500 INFO pid=2404 4651421696:ListenerScheduler invalidation_listener.pyo:82 InvalidationListener.Invalidation(1014:CHANGELOG, wapi_version=75632) 2015-12-04 09:04:10,718 -0500 INFO pid=2404 4584116224:CloudWatcher cloud_watcher.pyo:716 Change ignored. observed: 75632, retrieved: 75632 2015-12-04 09:04:10,718 -0500 INFO pid=2404 4651421696:ListenerScheduler invalidation_listener.pyo:125 InvalidationListener.InformRegistrationStatus(1014:CHANGELOG, REGISTERED) 2015-12-04 09:04:10,898 -0500 WARNING pid=2404 4651421696:ListenerScheduler notification_bridge.pyo:202 Invalidation status callback called with connected=True 2015-12-04 09:04:10,899 -0500 INFO pid=2404 4651421696:ListenerScheduler telemetry.pyo:167 PushStates.NEGOTIATING: 43.05828 => 3.3027 2015-12-04 09:04:10,899 -0500 INFO pid=2404 4651421696:ListenerScheduler telemetry.pyo:153 Push state PushStates.NEGOTIATING => PushStates.ONLINE 2015-12-04 09:04:10,899 -0500 INFO pid=2404 4651421696:ListenerScheduler pause_manager.pyo:130 Removing pause reason OFFLINE. Paused: False 2015-12-04 09:04:10,900 -0500 ERROR pid=2404 4651421696:ListenerScheduler pause_manager.pyo:438 Tried to get last event time for PauseReason.OFFLINE, but no last event time recorded. 2015-12-04 09:04:11,165 -0500 INFO pid=2404 4621975552:LocalFSPoller poller.pyo:166 Number of found changes: 0 2015-12-04 09:04:11,165 -0500 INFO pid=2404 4621975552:LocalFSPoller poller.pyo:177 Finished local disk scan. 2015-12-04 09:04:40,387 -0500 INFO pid=2404 4584116224:CloudWatcher cloud_watcher.pyo:698 Getting changes: elapsed_time: 30.001131058 2015-12-04 09:04:40,387 -0500 INFO pid=2404 4584116224:CloudWatcher cloud_watcher.pyo:1026 Getting cloud resources via changelog feed from 75633 2015-12-04 09:04:40,387 -0500 INFO pid=2404 4584116224:CloudWatcher docs_client.pyo:743 Requesting Doc List: /feeds/default/private/changes?start-index=75633&showroot=true&include- shared=true&reason=poll 2015-12-04 09:04:40,388 -0500 INFO pid=2404 4584116224:CloudWatcher sync_http_client.pyo:236 Opening direct connecton to clients3.google.com:443. 2015-12-04 09:04:40,513 -0500 INFO pid=2404 4584116224:CloudWatcher docs_client.pyo:1181 End of feed, no next URI watcher.pyo:252 <RootState.INITED: watcher.pyo:305 <RootState. INITED: 2>) watcher.pyo:368 poller.pyo:146 poller.pyo:149 diff_helper.pyo: 86 EFTA00502418 2015-12-04 09:04:40,513 -0500 INFO pid=2404 4584116224:CloudWatcher cloud_watcher.pyo:198 HandleSyncConfigSettingsChange generated events=set([]) 2015-12-04 09:09:42,642 -0500 INFO pid=2404 4563083264:FilewatcherMonitor watcher.pyo:550 PathsCallback called with: [u'/Users/lgroff/Google 2015-12-04 09:09:42,642 -0500 INFO pid=2404 4563083264:FilewatcherMonitor diff_helper.pyo:86 Scanning: (u'/Users/lgroff/Google Drive'] 2015-12-04 09:19:07,532 -0500 INFO pid=2404 4592529408:TelemetryStoreSizeMonitorThread telemetry_store_monitor_thread.pyo: 44 Checking Telemetry store size. 2015-12-04 09:34:07,527 -0500 INFO pid=2404 4592529408:TelemetryStoreSizeMonitorThread telemetry_store_monitor_thread.pyo: 44 Checking Telemetry store size. 2015-12-04 09:49:07,519 -0500 INFO pid=2404 4592529408:TelemetryStoreSizeMonitorThread telemetry_store_monitor_thread.pyo: 44 Checking Telemetry store size. 2015-12-04 10:04:03,532 -0500 INFO pid=2404 4545728512:ThreadMonitor thread_manager.pyo:339 Waking up. 2015-12-04 10:04:03,555 -0500 INFO pid=2404 4545728512:ThreadMonitor thread_manager.pyo:336 Waiting on exit condition. Wake up after 3600s. 2015-12-04 10:04:07,451 -0500 INFO pid=2404 4596736000:PushTelemetryThread telemetry.pyo:167 PushStates.ONLINE: +3596.63 => 3596.63 2015-12-04 10:04:07,452 -0500 INFO pid=2404 4596736000:PushTelemetryThread telemetry.pyo:112 Push telemetry harvest triggered, but only 3600.00658035s elapsed. Skipping. 2015-12-04 10:04:07,455 -0500 INFO pid=2404 4617768960:PauseTelemetryThread pause_telemetry.pyo:185 Collected 0.999979468584h of telemetry at t=1449241447.46, skipping. 2015-12-04 10:04:07,510 -0500 INFO pid=2404 4592529408:TelemetryStoreSizeMonitorThread telemetry_store_monitor_thread.pyo: 44 Checking Telemetry store size. 2015-12-04 10:06:24,957 -0500 INFO pid=2404 4575703040:XmppClient xmpplib.pyo:143 Close called with 'why' reason = Read 0 bytes from socket - Closing socket. 2015-12-04 10:06:25,072 -0500 WARNING pid=2404 4575703040:XmppClient xmpplib.pyo:277 Flush failed with <class 'socket.error'>: [Errno 32] Broken pipe 2015-12-04 10:06:25,076 -0500 ERROR pid=2404 4575703040:XmppClient fatal_exceptions.pyo:55 Ignoring non-fatal error: error: [Errno 22] Invalid argument, Traceback: File "common/utils.pyo", line 325, in LogErrors File "common/push/xmpp/xmpplib.pyo", line 151, in Close File "common/push/xmpp/xmpplib.pyo", line 198, in _PreCloseFlush File "contextlib.pyo", line 24, in exit File "common/push/xmpp/xmpplib.pyo", line 119, in ShrinkBuffer File "socket.pyo", line 228, in meth EFTA00502419