EFTA00502378.pdf

From: File "Nolumes/BuildData/pulse- datathgents/563586/recipes/843291236/base/b/drb/googleclient/apps/webdrive_sync/osxklist/Google Drive.app/Contents/Resources/_boot_.py", line 43, in <module> File "Nolumes/BuildData/pulse- datathgents/563586/recipes/843291236/base/b/drb/googleclient/apps/webdrive_sync/osxklist/Google Drive.app/Contents/Resources/_boot_.py", line 38, in _run File "/Applications/Google Drive.app/Contents/Resources/main.py", line 12, in <module> File "osx/run_googledrive.pyo", line 31, in Main File "build/bdist.macosx-10.7-intel/egg/PyObjCTools/AppHelper.py", line 235, in runEventLoop File "osx/GSyncAppDelegate.pyo", line 297, in applicationWillTerminate_ File "osxlmac_sync_app_controller.pyo", line 251, in Exit File "common/ui_action_handler.pyo", line 444, in Quit File "common/sync_app.pyo", line 1576, in Quit 2015-12-02 16:02:57,539 -0500 INFO pid=245 140735235863312:MainThread thread_manager.pyo:114 Stop was called 2015-12-02 16:02:57,539 -0500 INFO pid=245 140735235863312:MainThread thread_manager.pyo:250 Asking <PushNotificationKeepaliveThread(PushNotificationKeepaliveThread, started 45623869441> to stop. 2015-12-02 16:02:57,540 -0500 INFO pid=245 140735235863312:MainThread thread_manager.pyo:250 Asking <FilewatcherMonitor(FilewatcherMonitor, started 4558180352)> to stop. 2015-12-02 16:02:57,540 -0500 INFO pid=245 4562386944:PushNotificationKeepaliveThread sync_client_thread.pyo:209 PushNotificationKeepaliveThread run loop has terminated. 2015-12-02 16:02:57,549 -0500 INFO pid=245 140735235863312:MainThread thread_manager.pyo:250 Asking <SchedulerThread(ListenerScheduler, started 45665935361> to stop. 2015-12-02 16:02:57,550 -0500 INFO pid=245 140735235863312:MainThread thread_manager.pyo:250 Asking <RawEventUpgrader(RawEventUpgrader, started 45708001281> to stop. 2015-12-02 16:02:57,551 -0500 INFO pid=245 4570800128:RawEventUpgrader sync_client_thread.pyo:209 RawEventUpgrader run loop has terminated. 2015-12-02 16:02:57,551 -0500 INFO pid=245 140735235863312:MainThread thread_manager.pyo:250 Asking <ConnectivityThread(ConnectivityThread, started 4635758592)> to stop. 2015-12-02 16:02:57,555 -0500 INFO pid=245 140735235863312:MainThread thread_manager.pyo:250 Asking <CrashReportUploaderThread(CrashReportUploaderThread, started 4583419904)> to stop. 2015-12-02 16:02:57,555 -0500 INFO pid=245 140735235863312:MainThread thread_manager.pyo:250 Asking <PushTelemetryThread(PushTelemetryThread, started 4587626496)> to stop. 2015-12-02 16:02:57,556 -0500 INFO pid=245 140735235863312:MainThread thread_manager.pyo:250 Asking <LocalDiskSpaceChecker(LocalDiskSpaceChecker, started 4591833088)> to stop. 2015-12-02 16:02:57,556 -0500 INFO pid=245 4587626496:PushTelemetryThread sync_client_thread.pyo:209 PushTelemetryThread run loop has terminated. 2015-12-02 16:02:57,556 -0500 INFO pid=245 4587626496:PushTelemetryThread telemetry.pyo:205 Attempting final telemetry harvest. 2015-12-02 16:02:57,556 -0500 INFO pid=245 4587626496:PushTelemetryThread telemetry.pyo:165 PushStates.ONLINE: +718.844 => 11515.8 2015-12-02 16:02:57,557 -0500 INFO pid=245 140735235863312:MainThread thread_manager.pyo:250 Asking <IpcServerThread(IpcServerThread, started 4535980032)> to stop. 2015-12-02 16:02:57,557 -0500 INFO pid=245 140735235863312:MainThread thread_manager.pyo:250 Asking <Worker(Worker-1, started 46002462721> to stop. 2015-12-02 16:02:57,558 -0500 INFO pid=245 4591833088:LocalDiskSpaceChecker sync_client_thread.pyo:209 LocalDiskSpaceChecker run loop has terminated. 2015-12-02 16:02:57,558 -0500 WARNING pid=245 140735235863312:MainThread sync_client_thread.pyo:237 Wake() called while thread was running, should be a N0P 2015-12-02 16:02:57,558 -0500 INFO pid=245 140735235863312:MainThread thread_manager.pyo:250 Asking <CloudWatcher(CloudWatcher, started 46483783681> to stop. 2015-12-02 16:02:57,684 -0500 INFO pid=245 4600246272:Worker-1 sync_client_thread.pyo:209 Worker-1 run loop has terminated. EFTA00502378 2015-12-02 16:02:57,685 -0500 INFO pid=245 4600246272:Worker-1 sqlite.pyo:378 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-02 16:02:57,754 -0500 INFO pid=245 140735235863312:MainThread thread_manager.pyo:250 Asking <PushXmppClient(XmppClient, started 4575006720)> to stop. 2015-12-02 16:02:57,755 -0500 INFO pid=245 4648378368:CloudWatcher cloud_watcher.pyo:664 Stop detected 2015-12-02 16:02:57,755 -0500 WARNING pid=245 140735235863312:MainThread task.pyo:242 DriveXmppTask-0x10f39ce50 aborting: abort 2015-12-02 16:02:57,755 -0500 WARNING pid=245 4648378368:CloudWatcher cloud_watcher.pyo:710 Exiting CloudWatcher.PushRunLoop() 2015-12-02 16:02:57,755 -0500 INFO pid=245 140735235863312:MainThread task.pyo:254 Task <common.push.push_xmpp_client.DriveXmppTask object at 0x10f39ce50> complete. 2015-12-02 16:02:57,756 -0500 INFO pid=245 4648378368:CloudWatcher sqlite.pyo:378 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-02 16:02:57,756 -0500 INFO pid=245 140735235863312:MainThread xmpplib.pyo:143 Close called with 'why' reason = Client initiated close 2015-12-02 16:02:57,772 -0500 INFO pid=245 4600246272:Worker-1 sqlite.pyo:378 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-02 16:02:57,773 -0500 INFO pid=245 4600246272:Worker-1 utils.pyo:703 Execute cleanup callback 'persistence_sqlite:4ffe00e4bc134ce6837e3443faeeb7d0/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2015-12-02 16:02:57,773 -0500 INFO pid=245 4600246272:Worker-1 utils.pyo:703 Execute cleanup callback 'persistence_sqlite:bc9706e0lbc04f13ad304c78c4eb7533/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2015-12-02 16:02:57,773 -0500 INFO pid=245 4648378368:CloudWatcher sqlite.pyo:378 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-02 16:02:57,774 -0500 INFO pid=245 4648378368:CloudWatcher utils.pyo:703 Execute cleanup callback 'persistence_sqlite:884df6cafbd54dcaa621f87616655774/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2015-12-02 16:02:57,774 -0500 INFO pid=245 4648378368:CloudWatcher utils.pyo:703 Execute cleanup callback 'persistence_sqlite:56715af5a7a74df2a9387ddll1c8378a/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2015-12-02 16:02:57,774 -0500 INFO pid=245 4648378368:CloudWatcher utils.pyo:703 Execute cleanup callback 'DBConnectionManagerCleanupCallback' 2015-12-02 16:02:57,774 -0500 INFO pid=245 4648378368:CloudWatcher db_connection.pyo:434 Closing ManagedConnection(id=10EF5D690, thread=CloudWatcher-11510A000, path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph/dict_2.db, closed=False) 2015-12-02 16:02:57,850 -0500 WARNING pid=245 4535980032:IpcServerThread utils.pyo:755 interrupt_condition is set in Sleeper.Wait, waiting 0.2 seconds 2015-12-03 09:12:18,391 -0500 INFO pid=903 140735235863312:MainThread logging_config.pyo:249 OS: Darwin/10.9.5 2015-12-03 09:12:18,425 -0500 INFO pid=903 140735235863312:MainThread logging_config.pyo:252 Google Drive (build 1.25.0523.2491) 2015-12-03 09:12:18,425 -0500 INFO pid=903 140735235863312:MainThread logging_config.pyo:253 SSL: OpenSSL 0.9.8zg 14 July 2015 2015-12-03 09:12:18,598 -0500 INFO pid=903 140735235863312:MainThread GSyncAppDelegate.pyo:198 Application did finish launching. 2015-12-03 09:12:20,033 -0500 INFO pid=903 140735235863312:MainThread service.pyo:132 Creating singleton service instance of '<class 'common.file_lock.FileLock'>• 2015-12-03 09:12:20,034 -0500 INFO pid=903 140735235863312:MainThread service.pyo:132 Creating singleton service instance of '<class 'common.file_lock.FileLock'>' 2015-12-03 09:12:20,035 -0500 INFO pid=903 4528852992:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.service.user.User'>' 2015-12-03 09:12:20,036 -0500 INFO pid=903 4528852992:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.roots.roots.RootManager'>' EFTA00502379 2015-12-03 09:12:20,036 -0500 INFO pid=903 4528852992:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.thread_manager.ThreadManager'> 1 2015-12-03 09:12:20,036 -0500 INFO pid=903 4528852992:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.pause_manager.Service'>' 2015-12-03 09:12:20,036 -0500 INFO pid=903 4528852992:RunAsync-_InitializeSyncAppAsync-1 sync_app.pyo:308 Getting TokenStorage... 2015-12-03 09:12:20,037 -0500 INFO pid=903 4528852992:RunAsync-_InitializeSyncAppAsync-1 sync_app.pyo:310 Got token storage: <token_storage.TokenStorage object at 0x10901ae60> 2015-12-03 09:12:20,037 -0500 INFO pid=903 4528852992:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.features.AppFeatures'>' 2015-12-03 09:12:20,037 -0500 INFO pid=903 4528852992:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.cert.Manager'>' 2015-12-03 09:12:20,150 -0500 INFO pid=903 4528852992:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.net.bandwidth.BandwidthThrottler'> 1 2015-12-03 09:12:20,218 -0500 INFO pid=903 4528852992:RunAsync-_InitializeSyncAppAsync-1 sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-03 09:12:20,250 -0500 INFO pid=903 4528852992:RunAsync-_InitializeSyncAppAsync-1 sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-03 09:12:20,278 -0500 INFO pid=903 4528852992:RunAsync-_InitializeSyncAppAsync-1 utils.pyo:726 ADDING persistence_sqlite:ae7e291359b14d939cf86fd89a884a14/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-03 09:12:20,278 -0500 INFO pid=903 4528852992:RunAsync-_InitializeSyncAppAsync-1 utils.pyo:726 ADDING persistence_sqlite:811ce7e892614f85b4654542f3685736/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-03 09:12:20,536 -0500 INFO pid=903 4528852992:RunAsync-_InitializeSyncAppAsync-1 sync_app.pyo:483 BandwidthThrottler configured via sync_config 2015-12-03 09:12:20,536 -0500 INFO pid=903 4528852992:RunAsync-_InitializeSyncAppAsync-1 bandwidth.pyo:59 BandwidthThrottler.SetRxBytesPerSecond 0 2015-12-03 09:12:20,536 -0500 INFO pid=903 4528852992:RunAsync-_InitializeSyncAppAsync-1 bandwidth.pyo:68 BandwidthThrottler.SetTxBytesPerSecond 0 2015-12-03 09:12:20,536 -0500 INFO pid=903 4528852992:RunAsync-_InitializeSyncAppAsync-1 sqlite.pyo:378 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-03 09:12:20,537 -0500 INFO pid=903 4528852992:RunAsync-_InitializeSyncAppAsync-1 sqlite.pyo:378 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-03 09:12:20,801 -0500 INFO pid=903 4528852992:RunAsync-_InitializeSyncAppAsync-1 utils.pyo:703 Execute cleanup callback 'persistence_sqlite:811ce7e892614f85b4654542f3685736/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2015-12-03 09:12:20,803 -0500 INFO pid=903 4537266176:Finderlnjector sync_client_thread.pyo:175 Finderlnjector run loop has started. 2015-12-03 09:12:20,889 -0500 INFO pid=903 4528852992:RunAsync-_InitializeSyncAppAsync-1 utils.pyo:703 Execute cleanup callback 'persistence_sqlite:ae7e291359b14d939cf86fd89a884a14/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2015-12-03 09:12:21,331 -0500 INFO pid=903 140735235863312:MainThread image_resources.py:293 Loading image resources/images/ic_drawer_24.png 2015-12-03 09:12:21,668 -0500 INFO pid=903 140735235863312:MainThread image_resources.py:293 Loading image resources/images/menu_drive-logo.png 2015-12-03 09:12:21,674 -0500 INFO pid=903 140735235863312:MainThread image_resources.py:293 Loading image resources/images/menu_google-logo-gray.png 2015-12-03 09:12:21,772 -0500 INFO pid=903 140735235863312:MainThread image_resources.py:293 Loading image resources/images/ic_folder_mydrive_24.png 2015-12-03 09:12:21,802 -0500 INFO pid=903 140735235863312:MainThread image_resources.py:293 Loading image resources/images/ic_web_24.png 2015-12-03 09:12:21,804 -0500 INFO pid=903 140735235863312:MainThread EFTA00502380 image_resources.py:293 Loading image resources/images/menu_backups.png 2015-12-03 09:12:21,805 -0500 INFO pid=903 140735235863312:MainThread image_resources.py:293 Loading image resources/images/ic_sync_problem_24.png 2015-12-03 09:12:21,860 -0500 INFO pid=903 140735235863312:MainThread image_resources.py:293 Loading image resources/images/ic_done_24.png 2015-12-03 09:12:22,714 -0500 INFO pid=903 4550447104:ThreadMonitor thread_manager.pyo:336 Waiting on exit condition. Wake up after 3600s. 2015-12-03 09:12:22,714 -0500 INFO pid=903 4554653696:_LaunchSyncApp sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-03 09:12:22,834 -0500 INFO pid=903 4554653696:_LaunchSyncApp sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-03 09:12:22,916 -0500 INFO pid=903 140735235863312:MainThread sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-03 09:12:22,958 -0500 INFO pid=903 4554653696:_LaunchSyncApp utils.pyo:726 ADDING persistence_sqlite:6152b32300384408aeedf0e8fal6b6b6/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-03 09:12:22,958 -0500 INFO pid=903 140735235863312:MainThread sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-03 09:12:22,959 -0500 INFO pid=903 4554653696:_LaunchSyncApp utils.pyo:726 ADDING persistence_sqlite:c0de81c0lb0146458117bb4a7f1019d4/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-03 09:12:23,813 -0500 INFO pid=903 140735235863312:MainThread sqlite.pyo:378 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-03 09:12:23,813 -0500 INFO pid=903 4554653696:_LaunchSyncApp user.pyo:66 Initializing User instance with new credentials. None 2015-12-03 09:12:23,813 -0500 INFO pid=903 140735235863312:MainThread sqlite.pyo:378 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-03 09:12:23,814 -0500 INFO pid=903 4554653696:_LaunchSyncApp docs_client_factory.pyo:103 Creating a new DriveClient 2015-12-03 09:12:23,816 -0500 INFO pid=903 4554653696:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.token_bucket.CloudBucketManager'>' 2015-12-03 09:12:23,816 -0500 INFO pid=903 4554653696:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.drive_api.drive_resource.Manager'>. 2015-12-03 09:12:23,817 -0500 INFO pid=903 4554653696: LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common._drive_api.http.Manager'>. 2015-12-03 09:12:23,817 -0500 INFO pid=903 4554653696:_LaunchSyncApp drive_resource.pyo:130 Initialize apiclient Drive resource for current user token 2015-12-03 09:12:23,937 -0500 INFO pid=903 4554653696:_LaunchSyncApp drive_resource.pyo:178 Drive SDK baseUrl = https://www.googleapis.com/drive/v2internal/ 2015-12-03 09:12:23,939 -0500 INFO pid=903 4554653696:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.drive_api.http.AccessTokenInMemoryCache'>. 2015-12-03 09:12:23,940 -0500 INFO pid=903 4554653696:_LaunchSyncApp drive_request.pyo:220 Drive request: About(tags=Reason.SYNC_OTHER) - Read request - None 2015-12-03 09:12:23,942 -0500 INFO pid=903 4554653696:_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-03 09:12:24,226 -0500 INFO pid=903 4554653696:_LaunchSyncApp client.pyo:515 Attempting refresh to obtain initial access_token 2015-12-03 09:12:24,226 -0500 INFO pid=903 4554653696:_LaunchSyncApp client.pyo:755 Refreshing access_token 2015-12-03 09:12:24,467 -0500 INFO pid=903 4554653696: LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common._net.dapper.DapperService'>' 2015-12-03 09:12:24,566 -0500 INFO pid=903 4554653696:_LaunchSyncApp http.pyo:249 Request user-agent: Python-httplib2/0.9 (gzip) 2015-12-03 09:12:24,566 -0500 INFO pid=903 4554653696:_LaunchSyncApp sync_http_client.pyo:233 Opening direct connecton to accounts.google.com:443. 2015-12-03 09:12:25,359 -0500 INFO pid=903 4554653696:_LaunchSyncApp http.pyo:268 Response: EFTA00502381 151 bytes - application/json; charset=utf-8 (encoding:gzip) 2015-12-03 09:12:25,360 -0500 INFO pid=903 4554653696:_LaunchSyncApp http.pyo:249 Request user-agent: googledrivesync-1.25.0523.2491 google-api-python-client/1.3.1 (gzip) (Darwin/10.9.5) 2015-12-03 09:12:25,360 -0500 INFO pid=903 4554653696:_LaunchSyncApp sync_http_client.pyo:233 Opening direct connecton to www.googleapis.com:443. 2015-12-03 09:12:26,189 -0500 INFO pid=903 4554653696:_LaunchSyncApp http.pyo:268 Response: 241 bytes - application/json; charset=UTF-8 (encoding:gzip) 2015-12-03 09:12:26,213 -0500 INFO pid=903 4554653696:_LaunchSyncApp drive_request.pyo:225 Drive response: Request (None): About(tags=Reason.SYNC_OTHER) Response: About(root_id=OABNN8iEiWGvgUk9PVA, [email protected], quota_bytes_total=16106127360, quota_bytes_used_aggregate=15139681003, quota_bytes_used_in_trash=405074503, quota_type=LIMITED) 2015-12-03 09:12:26,213 -0500 INFO pid=903 4554653696:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.drive_api.doc_id_provider.Store'>' 2015-12-03 09:12:26,213 -0500 INFO pid=903 4554653696:_LaunchSyncApp user.pyo:66 Initializing User instance with new credentials. [email protected] 2015-12-03 09:12:26,214 -0500 INFO pid=903 4554653696:_LaunchSyncApp features.pyo:131 Loads feature switches. 2015-12-03 09:12:26,214 -0500 INFO pid=903 4554653696:_LaunchSyncApp features.pyo:165 Loading feature switches with server. 2015-12-03 09:12:26,214 -0500 INFO pid=903 4554653696:_LaunchSyncApp feature_switch_manager.pyo:633 Calling server to get feature switches. 2015-12-03 09:12:26,214 -0500 INFO pid=903 4554653696:_LaunchSyncApp drive_request.pyo:220 Drive request: ListFeatureSwitches(tags=Reason.SYNC_OTHER) - Read request - [email protected] 2015-12-03 09:12:26,216 -0500 INFO pid=903 4554653696:_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-03 09:12:26,217 -0500 INFO pid=903 4554653696:_LaunchSyncApp http.pyo:249 Request user-agent: googledrivesync-1.25.0523.2491 google-api-python-client/1.3.1 (gzip) (Darwin/10.9.5) 2015-12-03 09:12:26,280 -0500 INFO pid=903 4554653696:_LaunchSyncApp http.pyo:268 Response: 1017 bytes - application/json; charset=UTF-8 (encoding:gzip) 2015-12-03 09:12:26,281 -0500 INFO pid=903 4554653696:_LaunchSyncApp drive_request.pyo:225 Drive response: Request ([email protected]): ListFeatureSwitches(tags=Reason.SYNC_OTHER) Response: (u'enable_impressions_library': u'false', u'enable_multiple_accounts': u'false', u'enable_push': u'true', u'enable_shared_move_confirmation': u'false', u'log_backup_count': u'0', u'crash_throttle_percentage': u'99.7', u'perf_throttle_percentage': u'100 1 , u'enable_feedback': u'true', u'enable_multipause': u'false', u'enable_usb_detection': u'false', u'max_batch_upload_files': u'30', u'cloud_graph_disk_generation': u'2', u'test.feature_switch.name': u", u'use_drive_api': u'false', u'query_strategy': u'all', u'selective_sub_folder_sync': u'false') 2015-12-03 09:12:26,282 -0500 INFO pid=903 4554653696:_LaunchSyncApp sync_app.pyo:1209 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, EFTA00502382 enable_batch_upload=True, enable_change_filters=False, enable_context_menu=True, enable_dapper_trace=False, enable_disk_dict=False, enable_feedback=True, enable_file_sync_status=True, enable_impressions_library=False, enable_multipause=False, enable_multiple_accounts=False, enable_native_opener=True, enable_push=True, enable_usb_detection=False, feedback_type='prod', ghost_all_files=False, help_url=lhttps://support.google.com/drive/M1=%s&p=desktop_homel, impressions_heartbeat_interval_secs=10800, impressions_upload_interval_secs=1800, log_backup_count=0, log_size_mb=256, logging_level='info', max_batch_upload_files=30, new_document_ur1= 1 https://docs.google.com/document?usp=drive_syncl, new presentation ur1='https://docs.google.com/presentation?usp=drive_sync', new_spreadsheet_ur1='https://docs.google.com/spreadsheets?usp=drive_synci, numworkers=3, only_rooted_items_in_cloud_graph=False, open_ur1='https://docs.google.com/open?id=fdoc_id)', overlays_enabled_finder_versions=['10.7', '10.7.1', '10.7.2', '10.7.3', '10.7.5', '10.8', '10.8.1', '10.8.2', '10.8.3', '10.9', '10.9.1', '10.9.2', '10.9.3', '10.9.4'], perf_throttle_percentage=100.0, push_clientversion=1, push_keepalive_interval=72000.0, regular_polling_interval_secs=30, selective_sub_folder_sync=False, share_template_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', tokenbucket_read_qps=10, token_bucketwrite_qps=3, use_drive_api=False,) 2015-12-03 09:12:26,284 -0500 INFO pid=903 4554653696:_LaunchSyncApp logging_config.pyo:249 OS: Darwin/10.9.5 2015-12-03 09:12:26,290 -0500 INFO pid=903 4554653696:_LaunchSyncApp logging_config.pyo:252 Google Drive (build 1.25.0523.2491) 2015-12-03 09:12:26,291 -0500 INFO pid=903 4554653696:_LaunchSyncApp logging_config.pyo:253 SSL: OpenSSL 0.9.8zg 14 July 2015 2015-12-03 09:12:26,291 -0500 INFO pid=903 4554653696:_LaunchSyncApp sync_app.pyo:1218 Config: Email: [email protected] Sync root: /Users/lgroff/Google Drive Sync collections: set([]) Upgrade number: 22 App version: 1.25.0523.2491 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 EFTA00502383 Yosemite icons upgraded: False Win10 icons upgraded: False USB sync enabled: True Do sync default folder: True 2015-12-03 09:12:26,293 -0500 INFO pid=903 4554653696:_LaunchSyncApp sync_app.pyo:1223 Switching cloud client based on new feature switches 2015-12-03 09:12:26,294 -0500 INFO pid=903 4554653696: LaunchSyncApp docs_client_factory.pyo:112 Creating a new Wapi DocsClient 2015-12-03 09:12:26,295 -0500 INFO pid=903 4554653696:_LaunchSyncApp telemetry.pyo:222 SetPushClientVersion: 1 (3, 20130807) 2015-12-03 09:12:26,295 -0500 INFO pid=903 4554653696:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.push.telemetry.PushTelemetry'>' 2015-12-03 09:12:26,295 -0500 INFO pid=903 4554653696:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.telemetry.telemetry_manager.TelemetryManager'> 1 2015-12-03 09:12:26,763 -0500 INFO pid=903 4554653696:_LaunchSyncApp image_resources.py:269 Loading custom image resources/images/folder-mac.icns with function _CreateNSlmage 2015-12-03 09:12:26,765 -0500 INFO pid=903 4554653696:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.arbitrary_sync.ArbitrarySync'>' 2015-12-03 09:12:26,766 -0500 INFO pid=903 4554653696:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.downloads.DownloadCreate 2015-12-03 09:12:26,766 -0500 INFO pid=903 4554653696:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.downloads.DownloadModify 2015-12-03 09:12:26,766 -0500 INFO pid=903 4554653696:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadCreate 2015-12-03 09:12:26,766 -0500 INFO pid=903 4554653696:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadDelete 2015-12-03 09:12:26,766 -0500 INFO pid=903 4554653696:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadModify 2015-12-03 09:12:26,766 -0500 INFO pid=903 4554653696:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadMove 2015-12-03 09:12:26,766 -0500 INFO pid=903 4554653696:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadMoveFrom 2015-12-03 09:12:26,766 -0500 INFO pid=903 4554653696:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator rules.uploads.UploadMoveTo 2015-12-03 09:12:27,600 -0500 INFO pid=903 4567732224:FilewatcherMonitor filewatcher.pyo:71 filewatcher ext initialized 2015-12-03 09:12:27,600 -0500 INFO pid=903 4554653696: LaunchSyncApp docs_client_factory.pyo:112 Creating a new Wapi DocsClient 2015-12-03 09:12:27,602 -0500 INFO pid=903 4554653696: LaunchSyncApp docs_client_factory.pyo:112 Creating a new Wapi DocsClient 2015-12-03 09:12:27,603 -0500 INFO pid=903 4554653696: LaunchSyncApp docs_client_factory.pyo:112 Creating a new Wapi DocsClient 2015-12-03 09:12:27,603 -0500 INFO pid=903 4554653696: LaunchSyncApp docs_client_factory.pyo:112 Creating a new Wapi DocsClient 2015-12-03 09:12:27,604 -0500 INFO pid=903 4554653696: LaunchSyncApp docs client factory.pyo:112 Creating a new Wapi DocsClient 2015-12-03 09:12:27,605 -0500 INFO pid=903 4554653696:_LaunchSyncApp docs_client_factory.pyo:112 Creating a new Wapi DocsClient 2015-12-03 09:12:27,605 -0500 INFO pid=903 4554653696:_LaunchSyncApp docs_client_factory.pyo:112 Creating a new Wapi DocsClient 2015-12-03 09:12:27,622 -0500 INFO pid=903 4554653696:_LaunchSyncApp xmpplib_notifier.pyo:34 Configure storage: <common.push.tango.storage.SyncConfigStorage; proxy of <Swig Object of type 'invalidation::Storage *' at 0xl0fc99c90> > 2015-12-03 09:12:27,622 -0500 INFO pid=903 4554653696:_LaunchSyncApp xmpplib_notifier.pyo:59 Creating client thread 2015-12-03 09:12:27,622 -0500 INFO pid=903 4554653696:_LaunchSyncApp push_xmpp_client.pyo:223 Using talk server: talk.google.com:5222 2015-12-03 09:12:27,622 -0500 INFO pid=903 4554653696:_LaunchSyncApp xmpplib_notifier.pyo:68 Creating notification bridge 2015-12-03 09:12:27,680 -0500 INFO pid=903 4554653696:_LaunchSyncApp docs_client_factory.pyo:112 Creating a new Wapi DocsClient 2015-12-03 09:12:27,875 -0500 INFO pid=903 4576145408:LocalDiskSpaceChecker sync_client_thread.pyo:175 LocalDiskSpaceChecker run loop has started. 2015-12-03 09:12:27,875 -0500 INFO pid=903 4580352000:Batcher sqlite.pyo:276 Open EFTA00502384 master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-03 09:12:27,876 -0500 INFO pid=903 4588765184:OverlayStatusCleaner sync_client_thread.pyo:175 OverlayStatusCleaner run loop has started. 2015-12-03 09:12:27,876 -0500 INFO pid=903 4592971776:CrashReportUploaderThread crash_report_uploader_thread.pyo:60 Starting CrashReportUploaderThread. 2015-12-03 09:12:27,877 -0500 INFO pid=903 4597178368:RawEventUpgrader sync_client_thread.pyo:175 RawEventUpgrader run loop has started. 2015-12-03 09:12:27,877 -0500 INFO pid=903 4601384960:LocalWatcher watcher.pyo:312 LocalWatcher Run invoked.Waiting for first pass of local scan from poller to start 2015-12-03 09:12:27,878 -0500 INFO pid=903 4580352000:Batcher sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-03 09:12:27,878 -0500 INFO pid=903 4605591552:PushNotificationKeepaliveThread sync_client_thread.pyo:175 PushNotificationKeepaliveThread run loop has started. 2015-12-03 09:12:27,879 -0500 INFO pid=903 4609798144:Worker-1 sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-03 09:12:27,880 -0500 INFO pid=903 4614004736:XmppClient telemetry.pyo:151 Push state None => PushStates.CONNECTING 2015-12-03 09:12:27,880 -0500 INFO pid=903 4618211328:LocalFSPoller sync_client_thread.pyo:175 LocalFSPoller run loop has started. 2015-12-03 09:12:27,881 -0500 INFO pid=903 4614004736:XmppClient connection_task.pyo:151 Recalculated LoginTask timeout: 16.000000 2015-12-03 09:12:27,882 -0500 INFO pid=903 4622417920:FeatureSwitchPoller sync_client_thread.pyo:175 FeatureSwitchPoller run loop has started. 2015-12-03 09:12:27,882 -0500 INFO pid=903 4605591552:PushNotificationKeepaliveThread sync_http_client.pyo:233 Opening direct connecton to accounts.google.com:443. 2015-12-03 09:12:27,882 -0500 INFO pid=903 4626624512:TelemetryUploaderThread sync_client_thread.pyo:175 TelemetryUploaderThread run loop has started. 2015-12-03 09:12:27,882 -0500 INFO pid=903 4609798144:Worker-1 sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-03 09:12:27,883 -0500 INFO pid=903 4580352000:Batcher utils.pyo:726 ADDING persistence_sqlite:d9a7b6cbe22049158e86ee4fea484fle/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-03 09:12:27,883 -0500 INFO pid=903 4630831104:TelemetryStoreSizeMonitorThread sync_client_thread.pyo:175 TelemetryStoreSizeMonitorThread run loop has started. 2015-12-03 09:12:27,883 -0500 INFO pid=903 4635037696:Worker-0 sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-03 09:12:27,883 -0500 INFO pid=903 4639244288:PushTelemetryThread sync_client_thread.pyo:175 PushTelemetryThread run loop has started. 2015-12-03 09:12:27,884 -0500 INFO pid=903 4614004736:XmppClient telemetry.pyo:165 PushStates.CONNECTING: +0.00365756 => 0.00365756 2015-12-03 09:12:27,884 -0500 INFO pid=903 4622417920:FeatureSwitchPoller features.pyo:156 Current cloud client is not using the same service than what we used initially to fetch feature switches. Using a one-off client with the same service. 2015-12-03 09:12:27,884 -0500 INFO pid=903 4643450880:PauseTelemetryThread sync_client_thread.pyo:175 PauseTelemetryThread run loop has started. 2015-12-03 09:12:27,885 -0500 INFO pid=903 4647657472:CloudWatcher sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-03 09:12:27,885 -0500 INFO pid=903 4651864064:Worker-2 sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-03 09:12:27,886 -0500 INFO pid=903 4554653696:_LaunchSyncApp drive_extension_installer.pyo:174 Uninstalling extension nknebiagdodnminbdpflhpkgfpeijdbf 2015-12-03 09:12:27,887 -0500 INFO pid=903 4580352000:Batcher utils.pyo:726 ADDING persistence_sqlite:f9f56c67a9674d6c967471f7c105c42c/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-03 09:12:27,887 -0500 INFO pid=903 4630831104:TelemetryStoreSizeMonitorThread telemetry_store_monitor_thread.pyo:44 Checking Telemetry store size. 2015-12-03 09:12:27,887 -0500 INFO pid=903 4614004736:XmppClient xmpplib.pyo:80 Connecting to talk.google.com:5222 EFTA00502385 2015-12-03 09:12:27,888 -0500 INFO pid=903 4609798144:Worker-1 utils.pyo:726 ADDING persistence_sqlite:019bd4a9412c4bb2b452597b40fd36ee/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-03 09:12:27,888 -0500 INFO pid=903 4622417920:FeatureSwitchPoller features.pyo:180 Create a default cloud client factory. 2015-12-03 09:12:27,890 -0500 INFO pid=903 4635037696:Worker-0 sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-03 09:12:27,890 -0500 INFO pid=903 4609798144:Worker-1 utils.pyo:726 ADDING persistence_sqlite:40a2fc526a024e4f9eed7d3783d53226/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-03 09:12:27,891 -0500 INFO pid=903 4554653696:_LaunchSyncApp utils.pyo:703 Execute cleanup callback 'persistence_sqlite:6152b32300384408aeedf0e8fal6b6b6/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2015-12-03 09:12:27,891 -0500 INFO pid=903 4622417920:FeatureSwitchPoller features.pyo:182 Set credentials on User service. 2015-12-03 09:12:27,892 -0500 INFO pid=903 4609798144:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadChangeACL 2015-12-03 09:12:27,892 -0500 INFO pid=903 4554653696:_LaunchSyncApp sqlite.pyo:378 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-03 09:12:27,892 -0500 INFO pid=903 4622417920:FeatureSwitchPoller user.pyo:66 Initializing User instance with new credentials. None 2015-12-03 09:12:27,892 -0500 INFO pid=903 4609798144:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadCreate 2015-12-03 09:12:27,892 -0500 INFO pid=903 4622417920:FeatureSwitchPoller docs_client_factory.pyo:103 Creating a new DriveClient 2015-12-03 09:12:27,893 -0500 INFO pid=903 4609798144:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadDelete 2015-12-03 09:12:27,893 -0500 INFO pid=903 4622417920:FeatureSwitchPoller features.pyo:165 Loading feature switches with server. 2015-12-03 09:12:27,893 -0500 INFO pid=903 4609798144:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadModify 2015-12-03 09:12:27,893 -0500 INFO pid=903 4622417920:FeatureSwitchPoller feature_switch_manager.pyo:633 Calling server to get feature switches. 2015-12-03 09:12:27,893 -0500 INFO pid=903 4609798144:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadMove 2015-12-03 09:12:27,894 -0500 INFO pid=903 4622417920:FeatureSwitchPoller drive_resource.pyo:130 Initialize apiclient Drive resource for current user token 2015-12-03 09:12:27,894 -0500 INFO pid=903 4609798144:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadRename 2015-12-03 09:12:27,894 -0500 INFO pid=903 4622417920:FeatureSwitchPoller drive_resource.pyo:178 Drive SDK baseUrl = https://www.googleapis.com/drive/v2internal/ 2015-12-03 09:12:27,894 -0500 INFO pid=903 4635037696:Worker-0 utils.pyo:726 ADDING persistence_sqlite:de5b8a74b24045a4b3cdcfee2c76ace0/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-03 09:12:27,896 -0500 INFO pid=903 4609798144:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.uploads.BatchUploadCreate 2015-12-03 09:12:27,896 -0500 INFO pid=903 4635037696:Worker-0 utils.pyo:726 ADDING persistence_sqlite:d6137eacba73434cab9389d086ce218b/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-03 09:12:27,897 -0500 INFO pid=903 4609798144:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.uploads.UploadCreate 2015-12-03 09:12:27,897 -0500 INFO pid=903 4635037696:Worker-0 command_factory.pyo:186 Registering worker command type: common.worker.internals.InternalCreate 2015-12-03 09:12:27,897 -0500 INFO pid=903 4554653696:_LaunchSyncApp utils.pyo:703 Execute cleanup callback 'persistence_sqlite:c0de81c0lb0146458117bb4a7f1019d4/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2015-12-03 09:12:27,897 -0500 INFO pid=903 4651864064:Worker-2 sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-03 09:12:27,898 -0500 INFO pid=903 4609798144:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.uploads.UploadDelete EFTA00502386 2015-12-03 09:12:27,898 -0500 INFO pid=903 4622417920:FeatureSwitchPoller drive_request.pyo:220 Drive request: ListFeatureSwitches(tags=Reason.SYNC_OTHER) - Read request - None 2015-12-03 09:12:27,899 -0500 INFO pid=903 4635037696:Worker-0 command_factory.pyo:186 Registering worker command type: common.worker.internals.InternalModify 2015-12-03 09:12:27,899 -0500 INFO pid=903 4647657472:CloudWatcher sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-03 09:12:27,899 -0500 INFO pid=903 4554653696:_LaunchSyncApp sqlite.pyo:378 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-03 09:12:27,899 -0500 INFO pid=903 4609798144:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.uploads.UploadModify 2015-12-03 09:12:27,902 -0500 INFO pid=903 4622417920:FeatureSwitchPoller discovery.pyo:762 URL being requested: GET https://www.googleapis.com/drive/v2internal/settings? fields=items%2Fkey%2Citems%2FValue&reason=304finamespace=FEATURE_SWITCH&alt=json 2015-12-03 09:12:27,902 -0500 INFO pid=903 4635037696:Worker-0 workers.pyo:124 Waiting for batcher to initialize 2015-12-03 09:12:27,902 -0500 INFO pid=903 4651864064:Worker-2 utils.pyo:726 ADDING persistence_sqlite:493fd05fdcb94096b83d8b70f3b08575/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-03 09:12:27,903 -0500 INFO pid=903 4609798144:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.uploads.UploadMove 2015-12-03 09:12:27,903 -0500 INFO pid=903 4635037696:Worker-0 sync_client_thread.pyo:175 Worker-0 run loop has started. 2015-12-03 09:12:27,903 -0500 INFO pid=903 4651864064:Worker-2 utils.pyo:726 ADDING persistence_sqlite:dd4770e7fb474ce8998983a6badd9500/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-03 09:12:27,904 -0500 INFO pid=903 4622417920:FeatureSwitchPoller client.pyo:515 Attempting refresh to obtain initial access_token 2015-12-03 09:12:27,904 -0500 INFO pid=903 4609798144:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.uploads.UploadRename 2015-12-03 09:12:27,904 -0500 INFO pid=903 4647657472:CloudWatcher utils.pyo:726 ADDING persistence_sqlite:b738fc7c37704319878c56151cbd8133/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-12-03 09:12:27,905 -0500 INFO pid=903 4651864064:Worker-2 workers.pyo:124 Waiting for batcher to initialize 2015-12-03 09:12:27,905 -0500 INFO pid=903 4622417920:FeatureSwitchPoller client.pyo:735 Updated access_token read from Storage 2015-12-03 09:12:27,905 -0500 INFO pid=903 4609798144:Worker-1 workers.pyo:124 Waiting for batcher to initialize 2015-12-03 09:12:27,905 -0500 INFO pid=903 4647657472:CloudWatcher utils.pyo:726 ADDING persistence_sqlite:e7lfbd2bdeb14088bbl0cddab30f552d/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-12-03 09:12:27,905 -0500 INFO pid=903 4651864064:Worker-2 sync_client_thread.pyo:175 Worker-2 run loop has started. 2015-12-03 09:12:27,906 -0500 INFO pid=903 4622417920:FeatureSwitchPoller http.pyo:249 Request user-agent: googledrivesync-1.25.0523.2491 google-api-python-client/1.3.1 (gzip) (Darwin/10.9.5) 2015-12-03 09:12:27,906 -0500 INFO pid=903 4609798144:Worker-1 sync_client_thread.pyo:175 Worker-1 run loop has started. 2015-12-03 09:12:27,906 -0500 INFO pid=903 4647657472:CloudWatcher cloud_graph.pyo:120 Initialize persistent graph with generation 2 2015-12-03 09:12:27,907 -0500 INFO pid=903 4622417920:FeatureSwitchPoller sync_http_client.pyo:233 Opening direct connecton to www.googleapis.com:443. 2015-12-03 09:12:27,907 -0500 INFO pid=903 4647657472:CloudWatcher cloud_graph.pyo:1626 Current sync config generation: 2 2015-12-03 09:12:27,907 -0500 INFO pid=903 4647657472:CloudWatcher cloud_graph.pyo:1477 Cleanup cloud graph directory '/Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph' of files 'dict_*.db" except ( 1 dict_2.db 1 , Idict2.db-shm., 2015-12-03 09:12:27,919 -0500 INFO pid=903 4614004736:XmppClient telemetry.pyo:165 PushStates.CONNECTING: +0.0348965 => 0.0385541 2015-12-03 09:12:27,919 -0500 INFO pid=903 4614004736:XmppClient telemetry.pyo:151 Push state PushStates.CONNECTING => PushStates.NEGOTIATING EFTA00502387 2015-12-03 09:12:27,929 -0500 INFO pid=903 4647657472:CloudWatcher utils.pyo:726 ADDING DBConnectionManagerCleanupCallback 2015-12-03 09:12:27,930 -0500 INFO pid=903 4647657472: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-03 09:12:27,930 -0500 INFO pid=903 4647657472:CloudWatcher cloud_graph.pyo:1001 Reuse CloudDiskDict database: /Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph/dict_2.db 2015-12-03 09:12:28,013 -0500 INFO pid=903 4614004736:XmppClient xmpplib.pyo:397 Start SSL peer=gmail.com 2015-12-03 09:12:28,048 -0500 INFO pid=903 4605591552:PushNotificationKeepaliveThread sync_http_client.pyo:233 Opening direct connecton to clients3.google.com:443. 2015-12-03 09:12:28,063 -0500 INFO pid=903 4622417920:FeatureSwitchPoller http.pyo:268 Response: 1017 bytes - application/json; charset=UTF-8 (encoding:gzip) 2015-12-03 09:12:28,067 -0500 INFO pid=903 4614004736:XmppClient telemetry.pyo:165 PushStates.NEGOTIATING: 40.148083 => 0.148083 2015-12-03 09:12:28,171 -0500 INFO pid=903 4614004736:XmppClient telemetry.pyo:165 PushStates.NEGOTIATING: 40.104598 => 0.25268 2015-12-03 09:12:28,278 -0500 INFO pid=903 4647657472:CloudWatcher cloud_graph.pyo:161 Initialize persistent graph with existing database /Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph/dict_2.db 2015-12-03 09:12:28,279 -0500 INFO pid=903 4647657472:CloudWatcher cloud_graph.pyo:256 Building gdoc docids cache. 2015-12-03 09:12:28,305 -0500 INFO pid=903 4614004736:XmppClient task.pyo:254 Task <common.push.xmpp.login_task.LoginTask object at 0x10fc64590> complete. 2015-12-03 09:12:28,305 -0500 WARNING pid=903 4614004736:XmppClient login_task.pyo:90 Clearing connection for LoginTask: <common.push.xmpp.login_task.LoginTask object at 0x10fc64590> 2015-12-03 09:12:28,306 -0500 INFO pid=903 4614004736:XmppClient push_xmpp_client.pyo:313 Connected to talk.google.com:5222 2015-12-03 09:12:28,306 -0500 WARNING pid=903 4554653696:RunAsync-_OnXmppConnect-4 notification_bridge.pyo:126 XMPP detected connect. 2015-12-03 09:12:28,306 -0500 INFO pid=903 4554653696:RunAsync-_OnXmppConnect-4 xmpp_network_channel.pyo:169 Sending push channel subscription request 2015-12-03 09:12:28,757 -0500 INFO pid=903 4605591552:PushNotificationKeepaliveThread push_notification_keepalive_thread.pyo:49 Sent push keepalive message at: Thu Dec 3 09:12:28 2015 2015-12-03 09:12:28,797 -0500 INFO pid=903 4622417920:FeatureSwitchPoller drive_request.pyo:225 Drive response: Request (None): ListFeatureSwitches(tags=Reason.SYNC_OTHER) Response: (u'enable_impressions_library': u'false., u'enable_multiple_accounts': u'false', u'enable_push': u'true', u'enable_shared_move_confirmation': u'false', u'log_backup_count': u'0', u'crash_throttle_percentage': u'99.7', u'perf_throttle_percentage': u'100 1 , u'enable_feedback': u'true', u'enable_multipause': u'false', u'enable_usb_detection': u'false', u'max_batch_upload_files': u'30', u'cloud_graph_disk_generation': u'2', u'test.feature_switch.name': u", u'use_drive_api.: u'false., u'query_strategy': u'all', u'selective_sub_folder_synci: u'false') 2015-12-03 09:12:28,840 -0500 INFO pid=903 4614004736:XmppClient xmpp_network_channel.pyo:187 Received subscription response from Push for XMPPBot 2015-12-03 09:12:28,840 -0500 INFO pid=903 4554653696:RunAsync-_OnXmppConnect-4 notification_bridge.pyo:142 Starting TICL (client version 3.20130807)... 2015-12-03 09:12:29,012 -0500 INFO pid=903 4554653696:RunAsync-_OnXmppConnect-4 xmpp_network_channel.pyo:65 XmppNetworkChannel.InformNetworkStatus: CONNECTED 2015-12-03 09:12:29,045 -0500 INFO pid=903 4584558592:ListenerScheduler invalidation_listener.pyo:185 InvalidationListener.ReissueRegistrations(prefix=, prefix_len=0) 2015-12-03 09:12:29,329 -0500 INFO pid=903 4647657472:CloudWatcher cloud_graph.pyo:171 Reuse CloudDiskDict CloudDiskDict(max_change_stamp=75537, app_version=1.25.0523.2491, only_rooted_items=False, ()) 2015-12-03 09:12:29,433 -0500 INFO pid=903 4647657472:CloudWatcher cloud_watcher.pyo:828 Performing full snapshot diff. 2015-12-03 09:12:29,433 -0500 INFO pid=903 4647657472:CloudWatcher cloud_watcher.pyo:1026 Getting cloud resources via changelog feed from 75538 EFTA00502388 2015-12-03 09:12:29,433 -0500 INFO pid=903 4647657472:CloudWatcher docs_client.pyo:743 Requesting Doc List: /feeds/default/private/changes?start-index=75538&showroot=true&include- shared=true&reason=startup 2015-12-03 09:12:29,434 -0500 INFO pid=903 4647657472:CloudWatcher sync_http_client.pyo:233 Opening direct connecton to clients3.google.com:443. 2015-12-03 09:12:29,774 -0500 INFO pid=903 4647657472:CloudWatcher docs_client.pyo:1137 Feed has 2 entries 2015-12-03 09:12:29,775 -0500 INFO pid=903 4647657472:CloudWatcher docs_client.pyo:1144 End of feed, no next URI 2015-12-03 09:12:29,778 -0500 INFO pid=903 4647657472:CloudWatcher cloud_watcher.pyo:794 Got 2 cloud entries 2015-12-03 09:12:29,854 -0500 INFO pid=903 4647657472:CloudWatcher cloud_watcher.pyo:812 710 nodes in the cloud graph 2015-12-03 09:12:29,889 -0500 INFO pid=903 4647657472: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-03 09:12:29,889 -0500 INFO pid=903 4647657472:CloudWatcher cloud_snapshot_diff_helper.pyo:85 Start Prune Graph (total of 710 entries) 2015-12-03 09:12:29,902 -0500 INFO pid=903 4647657472:CloudWatcher cloud snapshot diff_helper.pyo:175 Pruning detected 694 items to keep 2015-12-03 09:12:29,902 -0500 INFO pid=903 4647657472:CloudWatcher cloud snapshot diff_helper.pyo:182 694 entries in graph after pruning 2015-12-03 09:12:29,902 -0500 INFO pid=903 4647657472:CloudWatcher cloud snapshot diff_helper.pyo:93 Compare to snaphost 2015-12-03 09:12:29,902 -0500 INFO pid=903 4647657472:CloudWatcher cloud_snapshot_diff_helper.pyo:762 Starting cloud graph vs snapshot diff with selective sync set: None 2015-12-03 09:12:29,902 -0500 INFO pid=903 4647657472:CloudWatcher cloud snapshot cliff_helper.pyo:764 Prepopulating D0C_ID-to-inodes cache 2015-12-03 09:12:29,905 -0500 INFO pid=903 4647657472:CloudWatcher cloud snapshot cliff_helper.pyo:768 First pass detecting moved entries 2015-12-03 09:12:30,039 -0500 INFO pid=903 4647657472:CloudWatcher cloud_snapshot_cliff_helper.pyo:783 Second pass detecting creations and modification entries 2015-12-03 09:12:30,431 -0500 INFO pid=903 4647657472:CloudWatcher cloud_snapshot_diff_helper.pyo:810 Processed 693 entries in total. Number of events detected: (_FSChangeActionType(Action.MODIFY): 1) 2015-12-03 09:12:30,505 -0500 INFO pid=903 4647657472:CloudWatcher cloud_watcher.py