EFTA00503868.pdf

From: File "Nolumes/BuildData/pulse- datathgents/599850/recipes/961180334/base/b/drb/googleclient/apps/webdrive_sync/osx/dist/Google Drive.app/Contents/ftesources/_boot_.py", line 77, in <module> File "Nolumes/BuildData/pulse- datathgents/599850/recipes/961180334/base/b/drb/googleclient/apps/webdrive_sync/osx/dist/Google Drive.app/Contents/ftesources/_boot_.py", line 62, in _run File "/Applications/Google 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 296, in applicationWillTerminate_ File "osx/mac_sync_app_controller.pyo", line 266, in Exit File "common/ui_action_handler.pyo", line 444, in Quit File "common/sync_app.pyo", line 1753, in Quit 2016-04-20 15:32:54,835 -0400 INFO pid=631 140735190016000:MainThread thread_manager.pyo:116 Stop was called 2016-04-20 15:32:54,835 -0400 INFO pid=631 140735190016000:MainThread thread_manager.pyo:253 Asking <LocalFSPoller(LocalFSPoller, started 123145328242688)> with tid 123145328242688 to stop. 2016-04-20 15:32:54,835 -0400 INFO pid=631 140735190016000:MainThread thread_manager.pyo:253 Asking <SchedulerThread(ListenerScheduler, started 123145332449280)> with tid 123145332449280 to stop. 2016-04-20 15:32:54,836 -0400 INFO pid=631 123145328242688:LocalFSPoller sync_client_thread.pyo:212 LocalFSPoller run loop has terminated. 2016-04-20 15:32:54,836 -0400 INFO pid=631 140735190016000:MainThread thread_manager.pyo:253 Asking <FilewatcherMonitor(FilewatcherMonitor, started 123145309270016)> with tid 123145309270016 to stop. 2016-04-20 15:32:54,836 -0400 INFO pid=631 123145332449280:ListenerScheduler sync_client_thread.pyo:83 ThreadExit: thread ListenerScheduler exiting normalish 2016-04-20 15:32:54,836 -0400 INFO pid=631 123145328242688:LocalFSPoller sync_client_thread.pyo:83 ThreadExit: thread LocalFSPoller exiting normalish 2016-04-20 15:32:54,836 -0400 INFO pid=631 140735190016000:MainThread thread_manager.pyo:253 Asking <RootWrangler(RootWrangler, started 123145336655872)> with tid 123145336655872 to stop. 2016-04-20 15:32:54,836 -0400 INFO pid=631 123145309270016:FilewatcherMonitor sync_client_thread.pyo:83 ThreadExit: thread FilewatcherMonitor exiting normalish 2016-04-20 15:32:54,837 -0400 INFO pid=631 140735190016000:MainThread thread_manager.pyo:253 Asking <Batcher(Batcher, started 123145340862464)> with tid 123145340862464 to stop. 2016-04-20 15:32:54,838 -0400 INFO pid=631 140735190016000:MainThread thread_manager.pyo:253 Asking <RawEventUpgrader(RawEventUpgrader, started 123145345069056)> with tid 123145345069056 to stop. 2016-04-20 15:32:54,837 -0400 INFO pid=631 123145336655872:RootWrangler sync_client_thread.pyo:212 RootWrangler run loop has terminated. 2016-04-20 15:32:54,838 -0400 INFO pid=631 123145340862464:Batcher sync_client_thread.pyo:272 Batcher run loop has terminated. 2016-04-20 15:32:54,838 -0400 INFO pid=631 123145336655872:RootWrangler root_wrangler.pyo: 124 RootWrangler.Root0bserverUnregistered 2016-04-20 15:32:54,838 -0400 INFO pid=631 140735190016000:MainThread thread manager.pyo:253 Asking <SyncClientWaitingThreadWithCallback(DocIdsFetcherThread- , started 123145349275648)> with tid 123145349275648 to stop. 2016-04-20 15:32:54,838 -0400 INFO pid=631 123145345069056:RawEventUpgrader sync_client_thread.pyo:212 RawEventUpgrader run loop has terminated. 2016-04-20 15:32:54,838 -0400 INFO pid=631 123145340862464:Batcher sqlite.pyo:366 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-04-20 15:32:54,839 -0400 INFO pid=631 123145336655872:RootWrangler sync_client_thread.pyo:83 ThreadExit: thread RootWrangler exiting normalish 2016-04-20 15:32:54,839 -0400 INFO pid=631 123145345069056:RawEventUpgrader sync_client_thread.pyo:83 ThreadExit: thread RawEventUpgrader exiting normalish 2016-04-20 15:32:54,839 -0400 INFO pid=631 123145349275648:DocIdsFetcherThread- sync_client_thread.pyo:272 run loop has terminated. EFTA00503868 2016-04-20 15:32:54,839 -0400 INFO pid=631 140735190016000:MainThread thread_manager.pyo:253 Asking <LocalWatcher(LocalWatcher, started 123145353482240)> with tid 123145353482240 to stop. 2016-04-20 15:32:54,839 -0400 INFO pid=631 123145340862464:Batcher sync_client_thread.pyo:83 ThreadExit: thread Batcher exiting normalish 2016-04-20 15:32:54,839 -0400 INFO pid=631 123145349275648:DocIdsFetcherThread- sync_client_thread.pyo:83 ThreadExit: thread DocldsFetcherThread- exiting normalish 2016-04-20 15:32:54,840 -0400 INFO pid=631 123145340862464:Batcher threads.pyo:109 Execute cleanup callback 'persistence_sqlite:3ccbld0b026f44ef9leb06b98e675a21/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2016-04-20 15:32:54,840 -0400 INFO pid=631 140735190016000:MainThread thread_manager.pyo:253 Asking <Worker(Worker-0, started 123145357688832)> with tid 123145357688832 to stop. 2016-04-20 15:32:54,840 -0400 INFO pid=631 123145353482240:LocalWatcher sync_client_thread.pyo:83 ThreadExit: thread LocalWatcher exiting normalish 2016-04-20 15:32:54,840 -0400 INFO pid=631 123145340862464:Batcher sqlite.pyo:366 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-20 15:32:54,841 -0400 INFO pid=631 140735190016000:MainThread thread_manager.pyo:253 Asking <RunFunctionThread(_LaunchSyncApp, stopped 123145324036096)> with tid 123145324036096 to stop. 2016-04-20 15:32:54,841 -0400 INFO pid=631 123145357688832:Worker-0 sync_client_thread.pyo:212 Worker-0 run loop has terminated. 2016-04-20 15:32:54,842 -0400 INFO pid=631 140735190016000:MainThread thread_manager.pyo:253 Asking <CloudWatcher(CloudWatcher, started 123145361895424)> with tid 123145361895424 to stop. 2016-04-20 15:32:54,842 -0400 INFO pid=631 123145357688832:Worker-0 sqlite.pyo:366 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-04-20 15:32:54,842 -0400 INFO pid=631 123145340862464:Batcher threads.pyo:109 Execute cleanup callback 'persistence_sqlite: 8d1d468801874c098dcb24490b7dbe26/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2016-04-20 15:32:54,842 -0400 INFO pid=631 140735190016000:MainThread thread_manager.pyo:253 Asking <Worker(Worker-2, started 123145366102016)> with tid 123145366102016 to stop. 2016-04-20 15:32:54,842 -0400 INFO pid=631 123145361895424:CloudWatcher cloud_watcher.pyo: 1076 Stop detected 2016-04-20 15:32:54,843 -0400 INFO pid=631 123145357688832:Worker-0 sqlite.pyo:366 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-20 15:32:54,843 -0400 WARNING pid=631 123145361895424:CloudWatcher cloud_watcher.pyo: 1122 Exiting CloudWatcher.PushRunLoop() 2016-04-20 15:32:54,843 -0400 INFO pid=631 140735190016000:MainThread thread_manager.pyo:253 Asking <CrashReportUploaderThread(CrashReportUploaderThread, started 123145370308608)> with tid 123145370308608 to stop. 2016-04-20 15:32:54,843 -0400 INFO pid=631 123145357688832:Worker-0 sync_client_thread.pyo:83 ThreadExit: thread Worker-0 exiting normalish 2016-04-20 15:32:54,843 -0400 INFO pid=631 123145361895424:CloudWatcher sqlite.pyo:366 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-04-20 15:32:54,844 -0400 INFO pid=631 123145357688832:Worker-0 threads.pyo:109 Execute cleanup callback 'persistence_sqlite:68dca50e21b74d99bdb9ldb1192247fb/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2016-04-20 15:32:54,844 -0400 INFO pid=631 123145370308608:CrashReportUploaderThread sync_client_thread.pyo: 83 ThreadExit: thread CrashReportUploaderThread exiting normalish 2016-04-20 15:32:54,844 -0400 INFO pid=631 123145366102016:Worker-2 sync_client_thread.pyo:212 Worker-2 run loop has terminated. 2016-04-20 15:32:54,844 -0400 INFO pid=631 140735190016000:MainThread thread_manager.pyo:253 Asking <SchedulerThread(InternalScheduler, started 123145374515200)> with tid 123145374515200 to stop. 2016-04-20 15:32:54,844 -0400 INFO pid=631 123145357688832:Worker-0 threads.pyo:109 EFTA00503869 Execute cleanup callback 'persistence sqlite:9eae69e3f9bf4292b2e318d55fb1c8d0/Users/lgroff/Library/Application Support/Googie/Drive/user default/sync_config.db' 2016-04-20 15:32:54,844 -0400 INFO pid=631 123145366102016:Worker-2 sqlite.pyo:366 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user default/snapshot.db 2016-04-20 15:32:54,845 -0400 INFO pid=631 140735190016000:MainThread thread manager.pyo:253 Asking <PauseTelemetryThread(PauseTelemetryThread, started 123145378721792)> with tid 123145378721792 to stop. 2016-04-20 15:32:54,845 -0400 INFO pid=631 123145374515200:InternalScheduler sync_client thread.pyo:83 ThreadExit: thread InternalScheduler exiting normalish 2016-04-20 15:32:54,845 -0400 INFO pid=631 123145366102016:Worker-2 sqlite.pyo:366 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user default/sync_config.db 2016-04-20 15:32:54,846 -0400 INFO pid=631 140735190016000:MainThread thread manager.pyo:253 Asking <FeatureSwitchPoller(FeatureSwitchPoller, started 123145382928384)> with tid 123145382928384 to stop. 2016-04-20 15:32:54,846 -0400 INFO pid=631 123145378721792:PauseTelemetryThread sync_client thread.pyo:212 PauseTelemetryThread run loop has terminated. 2016-04-20 15:32:54,846 -0400 INFO pid=631 123145378721792:PauseTelemetryThread pause telemetry.pyo:127 Attempting final telemetry harvest. 2016-04-20 15:32:54,847 -0400 INFO pid=631 140735190016000:MainThread thread manager.pyo:253 Asking <SyncClientImpressionsThread(SyncClientImpressionsThread, started 123145387134976)> with tid 123145387134976 to stop. 2016-04-20 15:32:54,846 -0400 INFO pid=631 123145378721792:PauseTelemetryThread pause_telemetry.pyo:190 Harvested pause statistics at t=1461180774.85: (‹PauseReason.API ERRORS: 'pause api err'>: _HarvestStat(paused=0.0, trial=0.0, occurrences=0), nauseReason.BLOEKINa_ERRORS: 'pause blocking_err'>: _HarvestStat(paused=0.0, trial=0.0, occurrences=0), <PauseReason.NETWORK_ERROILS: 'pause_net err'>: HarvestStat(paused=0.0, trial=0.0, occurrences=0), <PauseReason.USER: 'pause_user'>: HarvestStat(paused=0.0, trial=0.0, occurrences=0), <PauseReason.OFFLINE: 'pause offline'>: HarvestStat(paused=0.0, trial=0.0, occurrences=0), <PauseReason.NO LOCAL_ DISK_ ST'ACE: 'pause no space'>: _HarvestStat(paused=0.0, trial=0.0, occurrences=i)) 2016-04-20 15:32:54,847 -0400 INFO pid=631 123145382928384:FeatureSwitchPoller sync_client thread.pyo:212 FeatureSwitchPoller run loop has terminated. 2016-04-20 15:32:54,847 -0400 INFO pid=631 123145378721792:PauseTelemetryThread sync_client thread.pyo:83 ThreadExit: thread PauseTelemetryThread exiting normalish 2016-04-20 15:32:54,847 -0400 INFO pid=631 123145382928384:FeatureSwitchPoller sync_client thread.pyo:83 ThreadExit: thread FeatureSwitchPoller exiting normalish 2016-04-20 15:32:54,847 -0400 INFO pid=631 123145361895424:CloudWatcher sqlite.pyo:366 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user default/sync_config.db 2016-04-20 15:32:54,847 -0400 INFO pid=631 123145366102016:Worker-2 sync_client thread.pyo:83 ThreadExit: thread Worker-2 exiting normalish 2016-04-20 15:32:54,848 -0400 INFO pid=631 140735190016000:MainThread thread manager.pyo:253 Asking <GroupingConfirmationThread(ConfirmationHandler, started 123145391341568)> with tid 123145391341568 to stop. 2016-04-20 15:32:54,848 -0400 INFO pid=631 123145387134976:SyncClientImpressionsThread sync client thread.pyo:212 SyncClientImpressionsThread run loop has terminated. 2016-04-20 15:32:54,848 -0400 INFO pid=631 123145366102016:Worker-2 threads.pyo:109 Execute cleanup callback 'persistence sqlite:7d2530e2ce44455d8f46c9be953c2lae/Users/lgroff/Library/Application Support/Googie/Drive/user default/snapshot.db' 2016-04-20 15:32:54,849 -0400 INFO pid=631 123145387134976:SyncClientImpressionsThread sync_client thread.pyo:83 ThreadExit: thread SyncClientImpressionsThread exiting normalish 2016-04-20 15:32:54,849 -0400 INFO pid=631 123145361895424:CloudWatcher sync_client thread.pyo:83 ThreadExit: thread CloudWatcher exiting normalish 2016-04-20 15:32:54,849 -0400 INFO pid=631 123145366102016:Worker-2 threads.pyo:109 Execute cleanup callback 'persistence sqlite:81e93c9c9f5640bba28c60e43d2b74e7/Users/lgroff/Library/Application Support/Googie/Drive/user default/sync_config.db' 2016-04-20 15:32:54,849 -0400 INFO pid=631 123145361895424:CloudWatcher threads.pyo:109 Execute cleanup callback 'persistence sqlite:a4bcb9ed74fc49378cf7df630e67fal6/Users/lgroff/Library/Application Support/Googie/Drive/user_default/snapshot.db' EFTA00503870 2016-04-20 15:32:54,849 -0400 INFO pid=631 140735190016000:MainThread thread_manager.pyo:253 Asking <PushNotificationKeepaliveThread(PushNotificationKeepaliveThread, started 123145395548160)> with tid 123145395548160 to stop. 2016-04-20 15:32:54,849 -0400 INFO pid=631 123145391341568:ConfirmationHandler sync_client_thread.pyo:212 ConfirmationHandler run loop has terminated. 2016-04-20 15:32:54,850 -0400 INFO pid=631 123145361895424:CloudWatcher threads.pyo:109 Execute cleanup callback 'persistence_sqlite:9e8519dfald5447daee2ce8e2e70b008/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2016-04-20 15:32:54,850 -0400 INFO pid=631 123145391341568:ConfirmationHandler sync_client_thread.pyo:83 ThreadExit: thread ConfirmationHandler exiting normalish 2016-04-20 15:32:54,850 -0400 INFO pid=631 140735190016000:MainThread thread_manager.pyo:253 Asking <LocalDiskSpaceChecker(LocalDiskSpaceChecker, started 123145399754752)> with tid 123145399754752 to stop. 2016-04-20 15:32:54,850 -0400 INFO pid=631 123145395548160:PushNotificationKeepaliveThread sync_client_thread.pyo:212 PushNotificationKeepaliveThread run loop has terminated. 2016-04-20 15:32:54,850 -0400 INFO pid=631 123145361895424:CloudWatcher threads.pyo:109 Execute cleanup callback 'DBConnectionManagerCleanupCallback' 2016-04-20 15:32:54,851 -0400 INFO pid=631 123145395548160:PushNotificationKeepaliveThread sync_client_thread.pyo: 83 ThreadExit: thread PushNotificationKeepaliveThread exiting normalish 2016-04-20 15:32:54,851 -0400 INFO pid=631 140735190016000:MainThread thread_manager.pyo:253 Asking <OverlayStatusCleaner(OverlayStatusCleaner, started 123145403961344)> with tid 123145403961344 to stop. 2016-04-20 15:32:54,851 -0400 INFO pid=631 123145399754752:LocalDiskSpaceChecker sync_client_thread.pyo:212 LocalDiskSpaceChecker run loop has terminated. 2016-04-20 15:32:54,851 -0400 INFO pid=631 123145361895424:CloudWatcher db_connection.pyo:433 Closing ManagedConnection(id=11152C850, thread=CloudWatcher- 7000038D3000, path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph/dict_2.db, closed=False) 2016-04-20 15:32:54,852 -0400 INFO pid=631 123145399754752:LocalDiskSpaceChecker sync_client_thread.pyo:83 ThreadExit: thread LocalDiskSpaceChecker exiting normalish 2016-04-20 15:32:54,852 -0400 INFO pid=631 123145403961344:OverlayStatusCleaner sync_client_thread.pyo:212 OverlayStatusCleaner run loop has terminated. 2016-04-20 15:32:54,852 -0400 INFO pid=631 140735190016000:MainThread thread_manager.pyo:253 Asking <TelemetryStoreSizeMonitor(TelemetryStoreSizeMonitorThread, started 123145408167936)> with tid 123145408167936 to stop. 2016-04-20 15:32:54,852 -0400 INFO pid=631 123145403961344:OverlayStatusCleaner sync_client_thread.pyo:83 ThreadExit: thread OverlayStatusCleaner exiting normalish 2016-04-20 15:32:54,853 -0400 INFO pid=631 140735190016000:MainThread thread_manager.pyo:253 Asking <IpcServerThread(IpcServerThread, started 123145315622912)> with tid 123145315622912 to stop. 2016-04-20 15:32:54,853 -0400 INFO pid=631 123145408167936:TelemetryStoreSizeMonitorThread sync_client_thread.pyo:212 TelemetryStoreSizeMonitorThread run loop has terminated. 2016-04-20 15:32:54,853 -0400 INFO pid=631 140735190016000:MainThread thread_manager.pyo:253 Asking <ConnectivityThread(ConnectivityThread, started 123145412374528)> with tid 123145412374528 to stop. 2016-04-20 15:32:54,853 -0400 INFO pid=631 123145408167936:TelemetryStoreSizeMonitorThread sync_client_thread.pyo: 83 ThreadExit: thread TelemetryStoreSizeMonitorThread exiting normalish 2016-04-20 15:32:54,853 -0400 INFO pid=631 123145412374528:ConnectivityThread sync_client_thread.pyo:83 ThreadExit: thread ConnectivityThread exiting normalish 2016-04-20 15:32:54,854 -0400 INFO pid=631 140735190016000:MainThread thread_manager.pyo:253 Asking <PushTelemetryThread(PushTelemetryThread, started 123145416581120)> with tid 123145416581120 to stop. 2016-04-20 15:32:54,854 -0400 INFO pid=631 140735190016000:MainThread thread_manager.pyo:253 Asking <Worker(Worker-1, started 123145420787712)> with tid 123145420787712 to stop. 2016-04-20 15:32:54,854 -0400 INFO pid=631 140735190016000:MainThread thread_manager.pyo:253 Asking <PushXmppClient(XmppClient, started 123145424994304)> with tid 123145424994304 to stop. 2016-04-20 15:32:54,854 -0400 INFO pid=631 123145416581120:PushTelemetryThread sync_client_thread.pyo:212 PushTelemetryThread run loop has terminated. 2016-04-20 15:32:54,854 -0400 INFO pid=631 123145420787712:Worker-1 EFTA00503871 sync_client_thread.pyo:212 Worker-1 run loop has terminated. 2016-04-20 15:32:54,854 -0400 WARNING pid=631 140735190016000:MainThread task.pyo:242 DriveEmppTask-0x11206cb50 aborting: abort 2016-04-20 15:32:54,855 -0400 INFO pid=631 123145416581120:PushTelemetryThread telemetry.pyo:207 Attempting final telemetry harvest. 2016-04-20 15:32:54,855 -0400 INFO pid=631 123145420787712:Worker-1 sqlite.pyo:366 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-04-20 15:32:54,855 -0400 INFO pid=631 140735190016000:MainThread task.pyo:254 Task <common.push.push_xmpp_client.DriveXmppTask object at 0x11206cb50> complete. 2016-04-20 15:32:54,855 -0400 INFO pid=631 123145416581120:PushTelemetryThread telemetry.pyo:167 PushStates.0NLINE: +600.104 => 600.104 2016-04-20 15:32:54,855 -0400 INFO pid=631 140735190016000:MainThread xmpplib.pyo:143 Close called with 'why' reason = Client initiated close 2016-04-20 15:32:54,856 -0400 INFO pid=631 123145420787712:Worker-1 sqlite.pyo:366 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-20 15:32:54,856 -0400 INFO pid=631 123145416581120:PushTelemetryThread sync_client_thread.pyo:83 ThreadExit: thread PushTelemetryThread exiting normalish 2016-04-20 15:32:54,860 -0400 INFO pid=631 123145420787712:Worker-1 sync_client_thread.pyo:83 ThreadExit: thread Worker-1 exiting normalish 2016-04-20 15:32:54,860 -0400 INFO pid=631 123145420787712:Worker-1 threads.pyo:109 Execute cleanup callback 'persistence_sqlite:51061b7636744ael8e71d8504543de22/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2016-04-20 15:32:54,860 -0400 INFO pid=631 123145420787712:Worker-1 threads.pyo:109 Execute cleanup callback 'persistence_sqlite:891elfec8ble4d91a75c61f5f99680a9/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2016-04-25 09:04:34,426 -0400 INFO pid=4243 140735190016000:MainThread logging_config.pyo:257 OS: Darwin/10.11.3 2016-04-25 09:04:34,427 -0400 INFO pid=4243 140735190016000:MainThread logging_config.pyo:260 Google Drive (build 1.29.1861.9751) 2016-04-25 09:04:34,427 -0400 INFO pid=4243 140735190016000:MainThread logging_config.pyo:261 SSL: OpenSSL 0.9.8zg 14 July 2015 2016-04-25 09:04:34,544 -0400 INFO pid=4243 140735190016000:MainThread GSyncAppDelegate.pyo:198 Application did finish launching. 2016-04-25 09:04:34,868 -0400 INFO pid=4243 140735190016000:MainThread service.pyo:132 Creating singleton service instance of '<class 'common.file_lock.FileLocki> 1 2016-04-25 09:04:34,869 -0400 INFO pid=4243 140735190016000:MainThread service.pyo:132 Creating singleton service instance of '<class 'common.file_lock.FileLocki> 1 2016-04-25 09:04:34,870 -0400 INFO pid=4243 123145308733440:RunAsync-_InitializeSyncAppAsync- 1 service.pyo:132 Creating singleton service instance of '<class 'common.service.user.User'>' 2016-04-25 09:04:34,871 -0400 INFO pid=4243 123145308733440:RunAsync-_InitializeSyncAppAsync- 1 service.pyo:132 Creating singleton service instance of '<class 'common.roots.roots.RootManager'>' 2016-04-25 09:04:34,871 -0400 INFO pid=4243 123145308733440:RunAsync-_InitializeSyncAppAsync- 1 service.pyo:132 Creating singleton service instance of '<class 'common. thread_manager. ThreadManager'>' 2016-04-25 09:04:34,871 -0400 INFO pid=4243 123145308733440:RunAsync-_InitializeSyncAppAsync- 1 service.pyo:132 Creating singleton service instance of '<class 'common.pause_manager.Service'>' 2016-04-25 09:04:34,871 -0400 INFO pid=4243 123145308733440:RunAsync-_InitializeSyncAppAsync- 1 sync_app.pyo:347 Getting TokenStorage... 2016-04-25 09:04:34,871 -0400 INFO pid=4243 123145308733440:RunAsync-_InitializeSyncAppAsync- 1 sync_app.pyo:349 Got token storage: <platforms.token_storage.TokenStorage object at 0x10f8ec610> 2016-04-25 09:04:34,872 -0400 INFO pid=4243 123145308733440:RunAsync-_InitializeSyncAppAsync- 1 service.pyo:132 Creating singleton service instance of '<class 'common.features.AppFeatures'>' 2016-04-25 09:04:34,872 -0400 INFO pid=4243 123145308733440:RunAsync-_InitializeSyncAppAsync- 1 service.pyo:132 Creating singleton service instance of '<class 'common.cert.Manager'>' 2016-04-25 09:04:34,885 -0400 INFO pid=4243 123145308733440:RunAsync-_InitializeSyncAppAsync- 1 service.pyo:132 Creating singleton service instance of '<class 'common.net.bandwidth.BandwidthThrottler'>1 EFTA00503872 2016-04-25 09:04:34,888 -0400 INFO pid=4243 123145308733440:RunAsync-_InitializeSyncAppAsync- 1 service.pyo:132 Creating singleton service instance of '<class 'common.persistence.persistence.Factory'>' 2016-04-25 09:04:34,888 -0400 INFO pid=4243 123145308733440:RunAsync-_InitializeSyncAppAsync- 1 persistence.pyo:39 Initialize factory with policy PlatformPolicy(google_drive_config_directory_path=u'/Users/lgroff/Library/Application Support/Google/Drive', main_sync_root=None, system_download_cache_folder=None, process_id=None) 2016-04-25 09:04:34,888 -0400 INFO pid=4243 123145308733440:RunAsync-_InitializeSyncAppAsync- 1 service.pyo:132 Creating singleton service instance of '<class 'common.persistence.sync_config_sqlite.SyncConfignLite'> 1 2016-04-25 09:04:34,889 -0400 INFO pid=4243 123145308733440:RunAsync-_InitializeSyncAppAsync- 1 sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-04-25 09:04:34,890 -0400 INFO pid=4243 123145308733440:RunAsync-_InitializeSyncAppAsync- 1 sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-25 09:04:34,890 -0400 INFO pid=4243 123145308733440:RunAsync-_InitializeSyncAppAsync- 1 threads.pyo: 130 AddCleanupCallback persistence_sqlite:6720bb6db70b4df6acf60a2dd4744845/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-04-25 09:04:34,891 -0400 INFO pid=4243 123145308733440:RunAsync-_InitializeSyncAppAsync- 1 threads.pyo: 130 AddCleanupCallback persistence_sqlite:11f46160c3be49a9970aa51065022cdc/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-25 09:04:34,896 -0400 INFO pid=4243 123145308733440:RunAsync-_InitializeSyncAppAsync- 1 sync_app.pyo:525 BandwidthThrottler configured via sync_config 2016-04-25 09:04:34,896 -0400 INFO pid=4243 123145308733440:RunAsync-_InitializeSyncAppAsync- 1 bandwidth.pyo:59 BandwidthThrottler.SetRxBytesPerSecond 0 2016-04-25 09:04:34,897 -0400 INFO pid=4243 123145308733440:RunAsync-_InitializeSyncAppAsync- 1 bandwidth.pyo:68 BandwidthThrottler.SetTxBytesPerSecond 0 2016-04-25 09:04:34,897 -0400 INFO pid=4243 123145308733440:RunAsync-_InitializeSyncAppAsync- 1 sqlite.pyo:366 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-25 09:04:34,897 -0400 INFO pid=4243 123145308733440:RunAsync-_InitializeSyncAppAsync- 1 sqlite.pyo:366 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-04-25 09:04:34,899 -0400 INFO pid=4243 123145308733440:RunAsync-_InitializeSyncAppAsync- 1 threads.pyo:109 Execute cleanup callback 'persistence_sqlite:11f46160c3be49a9970aa51065022cdc/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2016-04-25 09:04:34,899 -0400 INFO pid=4243 123145308733440:RunAsync-_InitializeSyncAppAsync- 1 threads.pyo:109 Execute cleanup callback 'persistence_sqlite:6720bb6db70b4df6acf60a2dd4744845/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2016-04-25 09:04:34,971 -0400 INFO pid=4243 140735190016000:MainThread image_resources.py:253 Loading image images/ic_drawer_24.png 2016-04-25 09:04:34,975 -0400 INFO pid=4243 140735190016000:MainThread image_resources.py:253 Loading image images/menu_drive-logo.png 2016-04-25 09:04:34,977 -0400 INFO pid=4243 140735190016000:MainThread image_resources.py:253 Loading image images/menu_google-logo-gray.png 2016-04-25 09:04:34,981 -0400 INFO pid=4243 140735190016000:MainThread image_resources.py:253 Loading image images/ic_folder_mydrive_24.png 2016-04-25 09:04:34,984 -0400 INFO pid=4243 140735190016000:MainThread image_resources.py:253 Loading image images/ic_web_24.png 2016-04-25 09:04:34,986 -0400 INFO pid=4243 140735190016000:MainThread image_resources.py:253 Loading image images/ic_sync_problem_24.png 2016-04-25 09:04:34,997 -0400 INFO pid=4243 140735190016000:MainThread image_resources.py:253 Loading image images/ic_done.png 2016-04-25 09:04:35,068 -0400 INFO pid=4243 123145315622912:ThreadMonitor thread_manager.pyo:339 Waiting on exit condition. Wake up after 3600s. 2016-04-25 09:04:35,069 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-04-25 09:04:35,071 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp sqlite.pyo:276 EFTA00503873 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-25 09:04:35,072 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp threads.pyo:130 AddCleanupCallback persistence_sqlite:3c40bc23cf2543cb884726a2a8f0f131/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-04-25 09:04:35,072 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp threads.pyo:130 AddCleanupCallback persistence_sqlite:4e9503f83db44d6d803b744023b6b236/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-25 09:04:35,102 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp user.pyo:68 Initializing User instance with new credentials. None 2016-04-25 09:04:35,103 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp docs_client_factory.pyo:89 Creating a new DriveClient 2016-04-25 09:04:35,103 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.quota.Quotalnfo'>' 2016-04-25 09:04:35,104 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.token_bucket.CloudRucketManager'> 1 2016-04-25 09:04:35,105 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.drive_api.drive_resource.Manager'>' 2016-04-25 09:04:35,105 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.drive_api.http.Manager'>. 2016-04-25 09:04:35,105 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp drive_resource.pyo:149 Initialize apiclient Drive resource for current user token 2016-04-25 09:04:35,106 -0400 ERROR pid=4243 123145324036096:IpcServerThread ipc_socket_base.pyo:247 Failed to bind to socket /Users/lgroff/Library/Group Containers/google_drive/tmpf0Xerd (attempt 1 of 3) Traceback (most recent call last): File "common/ipc/ipc_socket_base.pyo", line 242, in Bind File "common/ipc/ipc_socket_base.pyo", line 176, in Bind File "socket.pyo", line 228, in meth error: [Erma 48] Address already in use 2016-04-25 09:04:35,106 -0400 ERROR pid=4243 123145324036096:IpcServerThread ipc_socket_base.pyo:247 Failed to bind to socket /Users/lgroff/Library/Group Containers/google_drive/tmpf0Xerd (attempt 2 of 3) Traceback (most recent call last): File "common/ipc/ipc_socket_base.pyo", line 242, in Bind File "common/ipc/ipc_socket_base.pyo", line 176, in Bind File "socket.pyo", line 228, in meth error: [Erma 48] Address already in use 2016-04-25 09:04:35,106 -0400 ERROR pid=4243 123145324036096:IpcServerThread ipc_socket_base.pyo:247 Failed to bind to socket /Users/lgroff/Library/Group Containers/google_drive/tmpf0Xerd (attempt 3 of 3) Traceback (most recent call last): File "common/ipc/ipc_socket_base.pyo", line 242, in Bind File "common/ipc/ipc_socket_base.pyo", line 176, in Bind File "socket.pyo", line 228, in meth error: [Erma 48] Address already in use 2016-04-25 09:04:35,107 -0400 WARNING pid=4243 123145324036096:IpcServerThread server.pyo:145 Socket (/Users/lgroff/Library/Application Support/Google/Drive/GoogleDrivelpcPipe) already in use, deleting it. 2016-04-25 09:04:35,116 -0400 INFO pid=4243 123145319829504: LaunchSyncApp drive_resource.pyo:210 Drive SDK baseUrl = 2016-04-25 09:04:35,118 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.drive_api.http.AccessTokenInMemoryCache'>. 2016-04-25 09:04:35,119 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp drive_request.pyo:297 Drive request: About (tags=Reason.SYNC_OTHER(SyncType.UNKNOWN_SYNC_TYPE) ) - Read request - None 2016-04-25 09:04:35,121 -0400 INFO pid=4243 123145319829504: LaunchSyncApp discovery.pyo:762 URL being requested: GET fields=rootFolderld%2CquotaBytesTotal%2CquotaBytesUsedAggregate%2CquotaBytesUsedInTrash%2Cquo taType%2Cuser%2FemailAddress&reason=304Salt=json 2016-04-25 09:04:35,122 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp client.pyo:515 Attempting refresh to obtain initial access_token EFTA00503874 2016-04-25 09:04:35,123 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp client.pyo:755 Refreshing access token 2016-04-25 09:04:35,125 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.net.dapper.DapperService'>' 2016-04-25 09:04:35,126 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp http.pyo:257 Request user-agent: Python-httplib2/0.9.2 (gzip) 2016-04-25 09:04:35,126 -0400 INFO pid=4243 123145319829504: LaunchSyncApp sync_http_client.pyo:238 Opening direct connection to 2016-04-25 09:04:35,288 -0400 INFO pid=4243 123145319829504: LaunchSyncApp http.pyo:276 Response: 152 bytes - application/json; charset=utf-8 (encoding:gzip) 2016-04-25 09:04:35,289 -0400 INFO pid=4243 123145319829504: LaunchSyncApp http.pyo:257 Request user-agent: googledrivesync-1.29.1861.9751 google-api-python-client/1.3.1 (gzip) (Darwin/10.11.3) 2016-04-25 09:04:35,289 -0400 INFO pid=4243 123145319829504: LaunchSyncApp sync_http_client.pyo:238 Opening direct connection to 2016-04-25 09:04:36,012 -0400 INFO pid=4243 123145319829504: LaunchSyncApp http.pyo:276 Response: 242 bytes - application/json; charset=UTF-8 (encoding:gzip) 2016-04-25 09:04:36,013 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp drive_request.pyo:304 Drive response: Request (None): About(tags=Reason.SYNC_OTHER(SyncType.UNKNOWN_SYNC_TYPE)) Response: About(root_id=0ABNN8iEiWGvgUk9PVA, quota bytes_total=107374182400, quota_bytes used aggregate=16861871350, quota—bytes used_in_trash=410609783, quota iype=IIMITED) 2016-04-25 59:04:36,013 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.drive_api.doc_id_provider.Store'>' 2016-04-25 09:04:36,014 -0400 INFO pid=4243 123145319829504: LaunchSyncApp user.pyo:68 Initializing User instance with new credentials. 2016-04-25 09:04:36,014 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp sync_app.pyo:1333 Configuring sync app from feature switches. 2016-04-25 09:04:36,014 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp features.pyo:112 Loads feature switches. 2016-04-25 09:04:36,014 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp feature_switch manager.pyo:682 Calling server to get feature switches. 2016-04-25 09:04:36,014 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp drive request.pyo:297 Drive request: ListF;atureSwitches(tags=Reason.SYNC_OTHER(SyncType.UNKNOWN_SYNC_TYPE)) - Read request - 2016-04-25 09:04:36,016 -0400 INFO pid=4243 123145319829504: LaunchSyncApp discovery.pyo:762 URL being requested: GET fields=items%2Fkey%2Citems%2FValue&reason=304&namespace=FEATURE_SWITCHfialt=json 2016-04-25 09:04:36,016 -0400 INFO pid=4243 123145319829504: LaunchSyncApp http.pyo:257 Request user-agent: googledrivesync-1.29.1861.9751 google-api-python-client/1.3.1 (gzip) (Darwin/10.11.3) 2016-04-25 09:04:36,028 -0400 INFO pid=4243 140735190016000:MainThread sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-04-25 09:04:36,029 -0400 INFO pid=4243 140735190016000:MainThread Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-25 09:04:36,029 -0400 INFO pid=4243 140735190016000:MainThread Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-04-25 09:04:36,029 -0400 INFO pid=4243 140735190016000:MainThread Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-25 09:04:36,073 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp Response: 1609 bytes - application/json; charset=UTF-8 (encoding:gzip) 2016-04-25 09:04:36,074 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp drive_request.pyo:304 Drive response: Request ( ): ListFeatureSwitches(tags=Reason.SYNC_OTHER(SyncType.UNKNOWN_SYNC_TYPE)) Response: (u'cloud_graph_disk_generation': u'2', sqlite.pyo:276 sqlite.pyo:366 sqlite.pyo:366 http.pyo:276 EFTA00503875 u'crash_throttle_percentage': u'0', u'enable_arbitrary_sync': u'false', u'enable_change_filters': u'false', u'enable_disk_check_on_preferences': u'true', u'enable_disk_check_on_setup': u'true', u'enable_feedback': u'true', u'enable_impressions_library': u'true', u'enable_local_v2': u'false', u'enable_multipause': u'true', u'enable_multiple_accounts': u'false', u'enable_push': u'true', u'enable_recursivesize': u'true', u'enable_shared_move_confirmation': u'true', u'enable_sync_options_in_setup_flow': u'true', u'enable_usb_detection': u'false', u'log_backup_count': u'0', u'max_batch_upload_files': u'30', u'max_page_size_changes.: u'1000', u'max_page_size_selective_sync': u'4000', u'perf_throttle_percentage': u'100', u'query_strategy': u'root', u'selective_sub_folder_sync': u'true', u'test.featureswitch.name': u", u'use_drive_api': u'true') 2016-04-25 09:04:36,075 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp sync_app.pyo:1357 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=0.0, disabled_cloud_graph_disk_app_versions=)), docs_list_page_size=1000, download_in_place=False, download_progress_bar=False, download_strategy='url template', download_ur1= 1 doc_id)', enable_arbitrary_sync=False, enable_batch_upload=True, enable_change_filters=False, enable_context_menu=True, enable_dapper_trace=False, enable_disk_check_on_preferences=True, enable_disk_check_on_setup=True, enable_feedback=True, enable_f ile_sync_status=True, enable_impressions_library=True, enable_local_v2=False, enable_multipause=True, enable_multiple_accounts=False, enable_native_opener=True, enable_no_default_folder=False, enable_push=True, enable_recursive_si ze=True, enable_shared_move_confirmation=True, enable_sync_options_in_setup_flow=True, enable_usb_detection=False, feedback_type='prod', ghost_all files=False, help_ur1=1 desktop_home', impressions_heartbeat_interval_secs=10800, impressions_upload_interval_secs=1800, log_backup_count=0, EFTA00503876 log_size_mb=256, logging_level='info', max_batch_upload_files=30, max_page_size_changes=1000, max_page_size_cloud_graph=1000, max_page_size_cloud_graph_fallback=1000, max_page_size_selective_sync=4000, max_page_size_selective sync fallback=1000, new_document_ur1= 1 new_presentation_ur1=' new_spreadsheet_ur1= 1 numworkers=3, open_ur1=' (doc_id)', overlays_enabled_finder_versions=[.10.7', '10.7.1', '10.7.2', '10.7.3', '10.7.5', '10.8', '10.8.1', '10.8.2', '10.8.3', '10.9', '10.9.1', '10.9.2', '10.9.3', '10.9.4'], perf_throttle_percentage=100.0, push_clientversion=1, push_keepalive_interval=72000.0, query_strategy=u'root', regular_polling_interval_secs=30, selective_sub_folder sync=True, share_template_ur1=' subapp=10&shareProtocolVersion=2&theme=2&command=settings&shareUiType=default&authuser=0&clie nt=desktop', show_confirmation_dialog_on_delete=True, telemetry_enabled=True, telemetry_upload interval secs=1800, telemetry_ur1=' token_bucket_read_qps=10, token_bucketwrite_qps=3, use_drive_api=True, user_confirmation_delay=2.0,) 2016-04-25 09:04:36,076 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp logging_config.pyo:257 OS: Darwin/10.11.3 2016-04-25 09:04:36,076 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp logging_config.pyo:260 Google Drive (build 1.29.1861.9751) 2016-04-25 09:04:36,076 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp logging_config.pyo:261 SSL: OpenSSL 0.9.8zg 14 July 2015 2016-04-25 09:04:36,076 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp sync_app.pyo:1366 Config: Email: Sync root: /Users/lgroff/Google Drive Sync collections: set((]) Upgrade number: 24 App version: 1.29.1861.9751 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: True Win10 icons upgraded: False USB sync enabled: True Do sync default folder: True 2016-04-25 09:04:36,078 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp telemetry.pyo:228 SetPushClientVersion: 1 (3, 20130807) 2016-04-25 09:04:36,078 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.push.telemetry.PushTelemetry'>' 2016-04-25 09:04:36,079 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common. telemetry. impression_logger_manager. ImpressionLoggerManager '>' 2016-04-25 09:04:36,146 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.managers.LockManager'>' EFTA00503877 2016-04-25 09:04:36,147 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator rules.downloads.DownloadCreate 2016-04-25 09:04:36,147 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator rules.downloads.DownloadModify 2016-04-25 09:04:36,147 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator rules.uploads.UploadCreate 2016-04-25 09:04:36,147 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator rules.uploads.UploadDelete 2016-04-25 09:04:36,147 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator rules.uploads.UploadModify 2016-04-25 09:04:36,147 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator rules.uploads.UploadMove 2016-04-25 09:04:36,147 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator rules.uploads.UploadMoveFrom 2016-04-25 09:04:36,147 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator rules.uploads.UploadMoveTo 2016-04-25 09:04:36,148 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.roots.root_wrangler.RootWrangler'>. 2016-04-25 09:04:36,148 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.machine_folder.Manager'>' 2016-04-25 09:04:36,148 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.secure_user_storage.Manager'>. 2016-04-25 09:04:36,340 -0400 INFO pid=4243 123145311416320:FilewatcherMonitor filewatcher.pyo:71 filewatcher_ext initialized 2016-04-25 09:04:36,341 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp docs_client_factory.pyo:89 Creating a new DriveClient 2016-04-25 09:04:36,341 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp docs_client_factory.pyo:89 Creating a new DriveClient 2016-04-25 09:04:36,342 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp docs_client_factory.pyo:89 Creating a new DriveClient 2016-04-25 09:04:36,342 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp docs_client_factory.pyo:89 Creating a new DriveClient 2016-04-25 09:04:36,343 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp docs_client_factory.pyo:89 Creating a new DriveClient 2016-04-25 09:04:36,343 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp docs_client_factory.pyo:89 Creating a new DriveClient 2016-04-25 09:04:36,343 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp docs_client_factory.pyo:89 Creating a new DriveClient 2016-04-25 09:04:36,344 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp xmpplib_notifier.pyo:34 Configure storage: <common.push.tango.storage.SyncConfigStorage; proxy of <Swig Object of type 'invalidation::Storage *' at 0x1120cdfc0> > 2016-04-25 09:04:36,344 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp xmpplib_notifier.pyo:59 Creating client thread 2016-04-25 09:04:36,344 -0400 INFO pid=4243 123145319829504: LaunchSyncApp push_xmpp_client.pyo:223 Using talk server: 2016-04-25 09:04:36,345 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp xmpplib_notifier.pyo:68 Creating notification bridge 2016-04-25 09:04:36,348 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp docs_client_factory.pyo:89 Creating a new DriveClient 2016-04-25 09:04:36,351 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp image_resources.py:304 Loading custom image images/folder-mac-yosemite.icns with function CreateNSImage 2016-04-25 09:04:36,352 -0400 INFO pid=4243 123145328242688:CrashReportUploaderThread crash report uploader thread.pyo:60 Starting CrashReportUploaderThread. 2016-04-25 09:04:36,352 -0400 INFO pid=4243 123145332449280:TelemetryStoreSizeMonitorThread sync_client_thread.pyo:178 TelemetryStoreSizeMonitorThread run loop has started. 2016-04-25 09:04:36,353 -0400 INFO pid=4243 123145332449280:TelemetryStoreSizeMonitorThread EFTA00503878 telemetry_store_monitor_thread.pyo:44 Checking Telemetry store size. 2016-04-25 09:04:36,353 -0400 INFO pid=4243 123145336655872:PushTelemetryThread sync_client_thread.pyo:178 PushTelemetryThread run loop has started. 2016-04-25 09:04:36,353 -0400 INFO pid=4243 123145340862464:LocalDiskSpaceChecker sync_client_thread.pyo:178 LocalDiskSpaceChecker run loop has started. 2016-04-25 09:04:36,354 -0400 INFO pid=4243 123145345069056:Worker-0 sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-04-25 09:04:36,355 -0400 INFO pid=4243 123145353482240:PauseTelemetryThread sync_client_thread.pyo:178 PauseTelemetryThread run loop has started. 2016-04-25 09:04:36,355 -0400 INFO pid=4243 123145357688832:FeatureSwitchPoller sync_client_thread.pyo:178 FeatureSwitchPoller run loop has started. 2016-04-25 09:04:36,356 -0400 INFO pid=4243 123145357688832:FeatureSwitchPoller feature_switch_manager.pyo:682 Calling server to get feature switches. 2016-04-25 09:04:36,356 -0400 INFO pid=4243 123145345069056:Worker-0 sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-25 09:04:36,357 -0400 INFO pid=4243 123145361895424:RawEventUpgrader sync_client_thread.pyo:178 RawEventUpgrader run loop has started. 2016-04-25 09:04:36,357 -0400 INFO pid=4243 123145357688832:FeatureSwitchPoller drive_resource.pyo:149 Initialize apiclient Drive resource for 2016-04-25 09:04:36,358 -0400 INFO pid=4243 123145370308608:PushNotificationKeepaliveThread sync_client_thread.pyo:178 PushNotificationKeepaliveThread run loop has started. 2016-04-25 09:04:36,358 -0400 INFO pid=4243 123145366102016:Worker-2 sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-04-25 09:04:36,358 -0400 INFO pid=4243 123145357688832:FeatureSwitchPoller drive_resource.pyo:210 Drive SDK baseUrl = 2016-04-25 09:04:36,359 -0400 INFO pid=4243 123145374515200:CloudWatcher sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-04-25 09:04:36,359 -0400 INFO pid=4243 123145370308608:PushNotificationKeepaliveThread push_notification_keepalive_thread.pyo:49 Sent push keepalive message at: Mon Apr 25 09:04:36 2016 2016-04-25 09:04:36,360 -0400 INFO pid=4243 123145345069056:Worker-0 threads.pyo:130 AddCleanupCallback persistence_sqlite:a33df9f289224e2d9aldbcd1b6ce5b53/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-04-25 09:04:36,360 -0400 INFO pid=4243 123145378721792:XmppClient telemetry.pyo:153 Push state None => PushStates.CONNECTING 2016-04-25 09:04:36,361 -0400 INFO pid=4243 123145366102016:Worker-2 sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-25 09:04:36,361 -0400 INFO pid=4243 123145345069056:Worker-0 threads.pyo:130 AddCleanupCallback persistence_sqlite:af6cec52a05e439calda200ef761ff95/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-25 09:04:36,362 -0400 INFO pid=4243 123145382928384:Batcher sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-04-25 09:04:36,362 -0400 INFO pid=4243 123145357688832:FeatureSwitchPoller drive_request.pyo:297 Drive request: ListFeatureSwitches(tags=Reason.SYNC_OTHER(SyncType.UNKNOWN_SYNC_TYPE)) - Read request - 2016-04-25 09:04:36,363 -0400 INFO pid=4243 123145387134976:SyncClientImpressionsThread sync_client_thread.pyo:178 SyncClientImpressionsThread run loop has started. 2016-04-25 09:04:36,363 -0400 INFO pid=4243 123145378721792:XmppClient connection_task.pyo:151 Recalculated LoginTask timeout: 16.000000 2016-04-25 09:04:36,364 -0400 INFO pid=4243 123145374515200:CloudWatcher sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-25 09:04:36,368 -0400 INFO pid=4243 123145366102016:Worker-2 threads.pyo:130 AddCleanupCallback persistence_sqlite:042b145e6c4b45ec93ff3fd3c7bfle53/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db EFTA00503879 2016-04-25 09:04:36,370 -0400 INFO pid=4243 123145357688832:FeatureSwitchPoller discovery.pyo:762 UAL being requested: GET fields=items%2Fkey%2Citems%2FValue&reason=304finamespace=FEATURE_SWITCH&alt=json 2016-04-25 09:04:36,371 -0400 INFO pid=4243 123145345069056:Worker-0 command_factory.pyo:175 Registered worker command types: common.worker.downloads.DownloadChangeACL for Direction.DOWNLOAD Action.CHANGE_ACL common.worker.downloads.DownloadCreate for Direction.DOWNLOAD Action.CREATE common.worker.downloads.DownloadDelete for Direction.DOWNLOAD Action.DELETE common.worker.downloads.DownloadModify for Direction.DOWNLOAD Action.MODIFY common.worker.downloads.DownloadMove for Direction.DOWNLOAD Action.MOVE common.worker.downloads.DownloadRename for Direction.DOWNLOAD Action.RENAME common.worker.internals.InternalCreate for Direction.INTERNAL Action.CREATE common.worker.internals.InternalModify for Direction.INTERNAL Action.MODIFY common.worker.undo.UndoCloudDelete for Direction. DOWNLOAD Action. DELETE undo common.worker.undo.UndoLocalDelete for Direction.UPLOAD Action. DELETE undo common.worker.undo.UndoLocalMove for Direction.UPLOAD Action.MOVE undo common.worker.uploads.BatchUploadCreate for Direction.UPLOAD Action.CREATE is_batch common.worker.uploads.UploadCreate for Direction.UPLOAD Action.CREATE common.worker.uploads.UploadDelete for Direction.UPLOAD Action.DELETE common.worker.uploads.UploadModify for Direction.UPLOAD Action.MODIFY common.worker.uploads.UploadMove for Direction.UPLOAD Action.MOVE common.worker.uploads.UploadRename for Direction.UPLOAD Action.RENAME 2016-04-25 09:04:36,372 -0400 INFO pid=4243 123145399754752:OverlayStatusCleaner sync_client_thread.pyo:178 OverlayStatusCleaner run loop has started. 2016-04-25 09:04:36,372 -0400 INFO pid=4243 123145378721792:XmppClient telemetry.pyo:167 PushStates.CONNECTING: +0.0117377 => 0.0117377 2016-04-25 09:04:36,373 -0400 INFO pid=4243 123145366102016:Worker-2 threads.pyo:130 AddCleanupCallback persistence_sqlite: 93cf22fec2c746b2b46faa15853bd6fa/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-25 09:04:36,372 -0400 INFO pid=4243 123145403961344:RootWrangler root_wrangler.pyo: 120 RootWrangler.Root0bserverRegistered with roots (_MyDriveRoot(u'/Users/lgroff/Google Drive', LocalID(inode=435288), 'root', <RootState.INITED: 2>)] 2016-04-25 09:04:36,373 -0400 INFO pid=4243 123145408167936:ConfirmationHandler sync_client_thread.pyo:178 ConfirmationHandler run loop has started. 2016-04-25 09:04:36,374 -0400 INFO pid=4243 123145412374528:LocalFSPoller sync_client_thread.pyo:178 LocalFSPoller run loop has started. 2016-04-25 09:04:36,374 -0400 INFO pid=4243 123145345069056:Worker-0 workers.pyo:128 Waiting for batcher to initialize 2016-04-25 09:04:36,374 -0400 INFO pid=4243 123145357688832:FeatureSwitchPoller client.pyo:515 Attempting refresh to obtain initial access token 2016-04-25 09:04:36,375 -0400 INFO pid=4243 123145382928384:Batcher sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-25 09:04:36,375 -0400 INFO pid=4243 123145374515200:CloudWatcher threads.pyo:130 AddCleanupCallback persistence_sqlite:e8a0827c08524033935f69cf8296908a/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-04-25 09:04:36,375 -0400 INFO pid=4243 123145416581120:LocalWatcher watcher.pyo:342 LocalWatcher Run invoked.Waiting for first pass of local scan from poller to start 2016-04-25 09:04:36,375 -0400 INFO pid=4243 123145378721792:XmppClient xmpplib.pyo:80 Connecting to 2016-04-25 09:04:36,375 -0400 INFO pid=4243 123145366102016:Worker-2 workers.pyo:128 Waiting for batcher to initialize 2016-04-25 09:04:36,376 -0400 INFO pid=4243 123145403961344:RootWrangler root_wrangler.pyo: 128 RootWrangler.RootsAdded with roots (_MyDriveRoot(u'/Users/lgroff/Google Drive', LocalID(inode=435288), 'root', <RootState.INITED: 2>)] 2016-04-25 09:04:36,376 -0400 INFO pid=4243 123145424994304:Worker-1 sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-04-25 09:04:36,377 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp doc_id_provider.pyo:226 Pre-fetching triggered by caller. Threshold 900. 0 available doc IDs EFTA00503880 in store. Requesting more doc IDs... 2016-04-25 09:04:36,377 -0400 INFO pid=4243 123145357688832:FeatureSwitchPoller client.pyo:735 Updated access_token read from Storage 2016-04-25 09:04:36,377 -0400 INFO pid=4243 123145374515200:CloudWatcher threads.pyo:130 AddCleanupCallback persistence_sqlite:8da4c32d53bb411196a016a42edac5e9/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-25 09:04:36,378 -0400 INFO pid=4243 123145403961344:RootWrangler sync_client_thread.pyo:178 RootWrangler run loop has started. 2016-04-25 09:04:36,378 -0400 INFO pid=4243 123145382928384:Batcher threads.pyo:130 AddCleanupCallback persistence_sqlite:800849d6f26041cea600759b2372c504/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-04-25 09:04:36,379 -0400 INFO pid=4243 123145319829504: LaunchSyncApp doc_id_provider.pyo:235 Waking up fetcher thread to get more doc IDs. Currently running. 2016-04-25 09:04:36,379 -0400 INFO pid=4243 123145374515200:CloudWatcher cloud_graph.pyo:162 Initialize persistent graph with generation 2 2016-04-25 09:04:36,379 -0400 INFO pid=4243 123145357688832:FeatureSwitchPoller http.pyo:257 Request user-agent: googledrivesync-1.29.1861.9751 google-api-python-client/1.3.1 (gzip) (Darwin/10.11.3) 2016-04-25 09:04:36,380 -0400 INFO pid=4243 123145403961344:RootWrangler root_wrangler.pyo: 107 RootWrangler got inited root _MyDriveRoot(u'/Users/lgroff/Google Drive', LocalID(inode=435288), 'root', <RootState.INITED: 2>) 2016-04-25 09:04:36,380 -0400 INFO pid=4243 123145424994304:Worker-1 sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-25 09:04:36,380 -0400 INFO pid=4243 123145382928384:Batcher threads.pyo:130 AddCleanupCallback persistence_sqlite:96d6a443b73842f2b50e285e39b4938e/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-25 09:04:36,381 -0400 INFO pid=4243 123145319829504:_LaunchSyncApp drive_extension_installer.pyo:175 Uninstalling extension nknebiagdodnminbdpflhpkgfpeijdbf 2016-04-25 09:04:36,381 -0400 INFO pid=4243 123145374515200:CloudWatcher cloud_graph.pyo:1374 Current sync config generation: 2 2016-04-25 09:04:36,381 -0400 INFO pid=4243 123145357688832:FeatureSwitchPoller sync_http_client.pyo:238 Opening direct connection to 2016-04-25 09:04:36,382 -0400 INFO pid=4243 123145366102016:Worker-2 sync_client_thread.pyo:178 Worker-2 run loop has started. 2016-04-25 09:04:36,382 -0400 INFO pid=4243 123145345069056:Worker-0 sync_client_thread.pyo:178 Worker-0 run loop has started. 2016-04-25 09:04:36,383 -0400 INFO pid=4243 123145374515200:CloudWatcher cloud_disk_dict.pyo:618 Cleanup cloud graph directory '/Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph' of files 'dict_*.db* 1 except ['dict_2.db 1, 'dict_2.db-wal'] 2016-04-25 09:04:36,383 -0400 INFO pid=4243 123145424994304:Worker-1 threads.pyo:130 AddCleanupCallback persistence_sqlite:096944ca2f1344d999487def0987dcac/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-04-25 09:04:36,384 -0400 INFO pid=4243 123145424994304:Worker-1 threads.pyo:130 AddCleanupCallback persistence_sqlite:ff76758186814978a5a6490c3a71d5dd/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-04-25 09:04:36,384 -0400 ERROR pid=4243 123145319829504:_LaunchSyncApp chrome_utils.pyo:79 Deleting file /Users/lgroff/Library/Application Support/Google/Chrome/External Extensions/nknebiagdodnminbdpflhpkgfpeijdbf.json failed because: lErrno 2] No such file or directory: '/Users/lgroff/Library/Application Support/Google/Chrome/External Extensions/nknebiagdodnminbdpflhpkgfpeijdbf.json' 2016-04-25 09:04:36,385 -0400 INFO pid=4243 123145374515200:CloudWatcher cloud_disk_dict.pyo: 104 Reuse CloudDiskDict database: /Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph/dict_2.db 2016-04-25 09:04:36,384 -0400 INFO pid=4243 123145424994304:Worker-1 workers.pyo:128 Waiting for batcher to initialize 2016-04-25 09:04:36,386 -0400 INFO pid=4243 123145424994304:Worker-1 sync_client_thread.pyo:178 Worker-1 run loop has started. EFTA00503881 2016-04-25 09:04:36,386 -0400 ERROR pid=4243 123145319829504:_LaunchSyncApp fatal_exceptions.pyo:55 Ignoring non-fatal error: OSError: [Errno 2] No such file or directory: VUsers/lgroff/Library/Application Support/Google/Chrome', Traceback: File "common/exception_utils.pyo", line 72, in SuppressExceptions File ncommon/sync_app.pyon, line 1488, in _PostSetup File ncommon/drive_extension_installer.pyo", line 142, in InstallExtension File ncommon/drive_extension_installer.pyo", line 106, in CheckExtensionlnstalled File "osx/fileutils.pyo", line 124, in ListDir EFTA00503882