EFTA00504436.pdf

From: File "Nolumes/BuildData/pulse- datathgents/wpyt4.hot/recipes/670045187/base/b/drb/googleclient/apps/webdrive_syndosx/dist/Googl e Drive.app/Contents/Resources/_boot_.py", line 43, in <module> File "Nolumes/BuildData/pulse- datathgents/wpyt4.hot/recipes/670045187/base/b/drb/googleclientJapps/webdrive_sync/osx/dist/Googl e 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 "PyObjCTools/AppHelper.pyo", line 235, in runEventLoop File "osx/GSyncAppDelegate.pyo", line 315, in applicationWillTerminate_ File "osx/mac_sync_app_controller.pyo", line 302, in Exit File "common/ui_action_handler.pyo", line 457, in Quit File "common/sync_app.pyo", line 1487, in Quit 2015-05-07 16:23:07,479 -0400 INFO pid=259 -1590500952:MainThread thread manager.pyo:77 Stop was called 2015-05-07 16:23:07,480 -0400 INFO pid=259 -1310560256:TelemetryStoreSizeMonitorThread sync_client_thread.pyo:203 TelemetryStoreSizeMonitorThread run loop has terminated. 2015-05-07 16:23:07,480 -0400 WARNING pid=259 -1590500952:MainThread task.pyo:245 DriveEmppTask-0xb867d50 aborting: abort 2015-05-07 16:23:07,480 -0400 INFO pid=259 -1314762752:FeatureSwitchPoller sync_client_thread.pyo:203 FeatureSwitchPoller run loop has terminated. 2015-05-07 16:23:07,480 -0400 INFO pid=259 -1590500952:MainThread task.pyo:257 Task <common.push.push_xmpp_client.DriveXmppTask instance at 0xb867d50> complete. 2015-05-07 16:23:07,481 -0400 INFO pid=259 -1314762752:FeatureSwitchPoller utils.pyo:640 Execute cleanup callback 'persistence_sqlite:00886c4f379442eba3f0aa68107fc5dc/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2015-05-07 16:23:07,481 -0400 INFO pid=259 -1590500952:MainThread xmpplib.pyo:114 Close called with 'why' reason = Client initiated close 2015-05-07 16:23:07,481 -0400 INFO pid=259 -1314762752:FeatureSwitchPoller sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-05-07 16:23:07,482 -0400 INFO pid=259 -1314762752:FeatureSwitchPoller utils.pyo:640 Execute cleanup callback 'persistence_sqlite:c1720696227a42c8adb7ad1b8021c7dc/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2015-05-07 16:23:07,482 -0400 INFO pid=259 -1314762752:FeatureSwitchPoller sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-05-08 09:05:28,782 -0400 INFO pid=360 -1590500952:MainThread logging_config.pyo:249 OS: Darwin/10.9.5 2015-05-08 09:05:28,820 -0400 INFO pid=360 -1590500952:MainThread logging_config.pyo:252 Google Drive (build 1.20.8672.3137) 2015-05-08 09:05:28,821 -0400 INFO pid=360 -1590500952:MainThread logging_config.pyo:253 SSL: OpenSSL 0.9.8zd 8 Jan 2015 2015-05-08 09:05:28,861 -0400 INFO pid=360 -1590500952:MainThread GSyncAppDelegate.pyo:216 Application did finish launching. 2015-05-08 09:05:29,863 -0400 INFO pid=360 -1336307712:Thread-1 service.pyo:71 Creating user scope service instance of '<class 'common.service.user.User'>' 2015-05-08 09:05:29,863 -0400 INFO pid=360 -1336307712:Thread-1 service.pyo:71 Creating user scope service instance of '<class 'common.local.roots.RootManager'>' 2015-05-08 09:05:29,863 -0400 INFO pid=360 -1336307712:Thread-1 service.pyo:71 Creating user scope service instance of '<class 'common.pause_manager.Service'>' 2015-05-08 09:05:29,864 -0400 INFO pid=360 -1336307712:Thread-1 sync_app.pyo:301 Getting TokenStorage... 2015-05-08 09:05:29,864 -0400 INFO pid=360 -1336307712:Thread-1 sync_app.pyo:303 Got token storage: <token_storage.TokenStorage object at 0x7fd4360> 2015-05-08 09:05:29,864 -0400 INFO pid=360 -1336307712:Thread-1 service.pyo:71 Creating user scope service instance of '<class 'common.features.AppFeatures'>' 2015-05-08 09:05:29,865 -0400 INFO pid=360 -1336307712:Thread-1 service.pyo:71 Creating user scope service instance of '<class 'common.cert.Manager'>' 2015-05-08 09:05:29,869 -0400 INFO pid=360 -1336307712:Thread-1 service.pyo:156 EFTA00504436 Creating singleton service instance of '<class 'common.sync_http_client.BandwidthThrottler'> 1 2015-05-08 09:05:29,870 -0400 INFO pid=360 -1336307712:Thread-1 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-05-08 09:05:29,872 -0400 INFO pid=360 -1336307712:Thread-1 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-05-08 09:05:29,875 -0400 INFO pid=360 -1336307712:Thread-1 utils.pyo:663 ADDING persistence_sqlite:7b139d6b1779424897a8lfe005842a01/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-05-08 09:05:29,875 -0400 INFO pid=360 -1336307712:Thread-1 utils.pyo:663 ADDING persistence_sqlite:af098094ee7d4527814fe6740cc79326/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-05-08 09:05:30,149 -0400 INFO pid=360 -1336307712:Thread-1 sync_app.pyo:477 BandwidthThrottler configured via sync_config 2015-05-08 09:05:30,149 -0400 INFO pid=360 -1336307712:Thread-1 sync_http_client.pyo:91 BandwidthThrottler.SetRxBytesPerSecond 0 2015-05-08 09:05:30,149 -0400 INFO pid=360 -1336307712:Thread-1 sync_http_client.pyo:100 BandwidthThrottler.SetTxBytesPerSecond 0 2015-05-08 09:05:30,149 -0400 INFO pid=360 -1336307712:Thread-1 sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-05-08 09:05:30,152 -0400 INFO pid=360 -1336307712:Thread-1 sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-05-08 09:05:30,255 -0400 INFO pid=360 -1336307712:Thread-1 utils.pyo:640 Execute cleanup callback 'persistence_sqlite:7b139d6b1779424897a8lfe005842a01/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2015-05-08 09:05:30,255 -0400 INFO pid=360 -1336307712:Thread-1 utils.pyo:640 Execute cleanup callback 'persistence_sqlite:af098094ee7d4527814fe6740cc79326/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2015-05-08 09:05:30,266 -0400 INFO pid=360 -1336307712:Finderlnjector sync_client_thread.pyo:177 Finderlnjector run loop has started. 2015-05-08 09:05:30,478 -0400 INFO pid=360 -1590500952:MainThread image_resources.py:246 Loading image resources/images/ic_drawer_24.png 2015-05-08 09:05:30,638 -0400 INFO pid=360 -1590500952:MainThread image_resources.py:246 Loading image resources/images/menu_drive-logo.png 2015-05-08 09:05:30,647 -0400 INFO pid=360 -1590500952:MainThread image_resources.py:246 Loading image resources/images/menu_google-logo-gray.png 2015-05-08 09:05:30,650 -0400 INFO pid=360 -1590500952:MainThread image_resources.py:246 Loading image resources/images/ic_folder_mydrive_24.png 2015-05-08 09:05:30,681 -0400 INFO pid=360 -1590500952:MainThread image_resources.py:246 Loading image resources/images/ic_web_24.png 2015-05-08 09:05:30,715 -0400 INFO pid=360 -1590500952:MainThread image_resources.py:246 Loading image resources/images/menu_backups.png 2015-05-08 09:05:30,727 -0400 INFO pid=360 -1590500952:MainThread image_resources.py:246 Loading image resources/images/ic_sync_problem_24.png 2015-05-08 09:05:30,744 -0400 INFO pid=360 -1590500952:MainThread image_resources.py:246 Loading image resources/images/ic_done_24.png 2015-05-08 09:05:30,772 -0400 INFO pid=360 -1590500952:MainThread service.pyo:209 Creating paramed singleton service instance of '<class 'common.file_lock.FileLock'>' 2015-05-08 09:05:30,790 -0400 INFO pid=360 -1590500952:MainThread service.pyo:209 Creating paramed singleton service instance of '<class 'common.file_lock.FileLock'>' 2015-05-08 09:05:31,934 -0400 INFO pid=360 -1327370240:ThreadMonitor thread_manager.pyo:259 Waiting on exit condition. Wake up after 3600s. 2015-05-08 09:05:31,935 -0400 INFO pid=360 -1323167744:_LaunchSyncApp sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-05-08 09:05:31,939 -0400 INFO pid=360 -1590500952:MainThread sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-05-08 09:05:31,959 -0400 INFO pid=360 -1590500952:MainThread sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application EFTA00504437 Support/Google/Drive/user_default/sync_config.db 2015-05-08 09:05:31,959 -0400 INFO pid=360 -1323167744:_LaunchSyncApp sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-05-08 09:05:31,965 -0400 INFO pid=360 -1590500952:MainThread sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-05-08 09:05:31,965 -0400 INFO pid=360 -1323167744:_LaunchSyncApp utils.pyo:663 ADDING persistence_sqlite:217b90f2f46a4794b3d39c938fc03ba9/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-05-08 09:05:31,966 -0400 INFO pid=360 -1323167744:_LaunchSyncApp utils.pyo:663 ADDING persistence_sqlite:c86769c407fe4136b4f90c70c91529ac/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-05-08 09:05:32,209 -0400 INFO pid=360 -1590500952:MainThread sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-05-08 09:05:32,210 -0400 INFO pid=360 -1323167744:_LaunchSyncApp user.pyo:64 Initializing User instance with new credentials. None 2015-05-08 09:05:32,210 -0400 INFO pid=360 -1590500952:MainThread feature_switch_manager.pyo:479 Successfully read persisted feature switches. 2015-05-08 09:05:32,210 -0400 INFO pid=360 -1323167744:_LaunchSyncApp docs_client_factory.pyo:99 Creating a new Wapi DocsClient 2015-05-08 09:05:32,218 -0400 INFO pid=360 -1323167744:_LaunchSyncApp service.pyo:71 Creating user scope service instance of '<class 'common.token_bucket.CloudBucketManager'>. 2015-05-08 09:05:32,280 -0400 WARNING pid=360 -1331572736:IpcServerThread server.pyo:144 Socket (/Users/lgroff/Library/Application Support/Google/Drive/GoogleDrivelpcPipel already in use, deleting it. 2015-05-08 09:05:41,939 -0400 INFO pid=360 -1323167744: LaunchSyncApp user.pyo:64 Initializing User instance with new credentials. 2015-05-08 09:05:41,939 -0400 INFO pid=360 -1323167744:_LaunchSyncApp features.pyo:109 Loads feature switches. 2015-05-08 09:05:41,939 -0400 INFO pid=360 -1323167744:_LaunchSyncApp features.pyo:138 Loading feature switches with server. 2015-05-08 09:05:41,940 -0400 INFO pid=360 -1323167744:_LaunchSyncApp feature_switch_manager.pyo:551 Calling server to get feature switches. 2015-05-08 09:05:41,940 -0400 INFO pid=360 -1323167744:_LaunchSyncApp feature_switch_manager.pyo:471 Successfully received feature switches from server. 2015-05-08 09:05:41,941 -0400 INFO pid=360 -1323167744:_LaunchSyncApp sync_app.pyo:1153 Feature Switches: FeatureSwitchSettings( accept_blob_download_gzip_encoding=True, auto_backup=False, backup_polling_interval_secs=7200, cloud_graph_disk_generation=2, crash_log_size_limit=10000000, crash_throttle_percentage=99.7, disabled_cloud_graph_disk_app_versions=['1.8.4288.4175', '1.8.4340.2022', '1.10.4711.1662', '1.10.4657.9104', '1.10.4658.6881'], docs_list_page_size=1000, download_in_place=False, download_progress_bar=False, download_strategy=turl_template', download_ur1= 1 https://googledrive.com/p/host/(doc_idP, enable_batch_upload=True, enable_context_menu=True, enable_dapper_trace=False, enable_disk_dict=False, enable_feedback=False, enable_file_sync_status=True, enable_multiple_accounts=False, enable_native_opener=True, enable_new_menu=True, enable_push=True, enable_snapshot_reconstruct=True, feedback_type='prod', EFTA00504438 ghost_all_files=False, help_url=ihttps://support.google.com/drive/M1=%s&p=desktop_home., log_backup_count=0, log_size_mb=256, logging_level='info', max_batch_upload_files=30, newdocument_url=thttps://docs.google.com/document?usp=drive_sync', new_presentationur1= 1 https://docs.google.com/presentation?usp=drive_synct, new_spreadsheet_ur1='https://docs.google.com/spreadsheets?usp=drive_synct, numworkers=3, only_rooted_items_in_cloud_graph=False, open_ur1='https://docs.google.com/open?id=(doc_id)', overlays_enabled_finder_versions=['10.6.8', '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_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', token_bucket read gps=10, tokenbucket—writ;_qps=3,) 2015-05-08 09:05:42,000 -0400 INFO pid=360 -1323167744:_LaunchSyncApp logging_config.pyo:249 OS: Darwin/10.9.5 2015-05-08 09:05:42,015 -0400 INFO pid=360 -1323167744:_LaunchSyncApp logging_config.pyo:252 Google Drive (build 1.20.8672.3137) 2015-05-08 09:05:42,015 -0400 INFO pid=360 -1323167744:_LaunchSyncApp logging_config.pyo:253 SSL: OpenSSL 0.9.8zd 8 Jan 2015 2015-05-08 09:05:42,015 -0400 INFO pid=360 -1323167744:_LaunchSyncApp sync_app.pyo:1162 Config: Email: Sync root: /Users/lgroff/Google Drive Sync collections: set([]) Upgrade number: 20 App version: 1.20.8672.3137 Selective sync: False Cloud Graph generation: 2 Auto-Backup activated: False Folder sync: [] Local app whitelist: set([]) Local app blacklist: set([]) 2015-05-08 09:05:42,089 -0400 INFO pid=360 -1323167744:_LaunchSyncApp service.pyo:lll Creating user scope service subclass of '<class 'common.push.notifier.Notifier'>' 2015-05-08 09:05:42,089 -0400 INFO pid=360 -1323167744:_LaunchSyncApp service.pyo:113 Actual service created: <common.push.notifier.PushXMPPNotifier object at 0x8c88950> 2015-05-08 09:05:43,141 -0400 INFO pid=360 -1590500952:MainThread service.pyo:71 Creating user scope service instance of '<class 'common.auto_backup.AutoBackup'>' 2015-05-08 09:05:43,142 -0400 INFO pid=360 -1590500952:MainThread sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-05-08 09:05:43,157 -0400 INFO pid=360 -1590500952:MainThread sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-05-08 09:05:43,158 -0400 INFO pid=360 -1590500952:MainThread sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-05-08 09:05:43,158 -0400 INFO pid=360 -1590500952:MainThread sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-05-08 09:05:43,159 -0400 INFO pid=360 -1590500952:MainThread service.pyo:71 EFTA00504439 Creating user scope service instance of '<class 'common.telemetry.telemetry_manager.TelemetryManager'> 1 2015-05-08 09:05:46,982 -0400 INFO pid=360 -1323167744:_LaunchSyncApp image_resources.py:222 Loading custom image resources/images/folder-mac.icns with function _CreateNSlmage 2015-05-08 09:05:47,396 -0400 INFO pid=360 -1323167744:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.downloads.DownloadCreate 2015-05-08 09:05:47,396 -0400 INFO pid=360 -1323167744:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.downloads.DownloadModify 2015-05-08 09:05:47,396 -0400 INFO pid=360 -1323167744:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadCreate 2015-05-08 09:05:47,396 -0400 INFO pid=360 -1323167744:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadDelete 2015-05-08 09:05:47,396 -0400 INFO pid=360 -1323167744:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadModify 2015-05-08 09:05:47,396 -0400 INFO pid=360 -1323167744:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadMove 2015-05-08 09:05:47,396 -0400 INFO pid=360 -1323167744:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadMoveFrom 2015-05-08 09:05:47,396 -0400 INFO pid=360 -1323167744:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadMoveTo 2015-05-08 09:05:47,398 -0400 INFO pid=360 -1318965248:FilewatcherMonitor filewatcher.pyo:68 filewatcher ext initialized 2015-05-08 09:05:47,398 -0400 INFO pid=360 -1323167744:_LaunchSyncApp docs_client_factory.pyo:99 Creating a new Wapi DocsClient 2015-05-08 09:05:47,399 -0400 INFO pid=360 -1323167744:_LaunchSyncApp docs_client_factory.pyo:99 Creating a new Wapi DocsClient 2015-05-08 09:05:47,400 -0400 INFO pid=360 -1323167744:_LaunchSyncApp docs_client_factory.pyo:99 Creating a new Wapi DocsClient 2015-05-08 09:05:47,400 -0400 INFO pid=360 -1323167744:_LaunchSyncApp docs_client_factory.pyo:99 Creating a new Wapi DocsClient 2015-05-08 09:05:47,401 -0400 INFO pid=360 -1323167744:_LaunchSyncApp docs_client_factory.pyo:99 Creating a new Wapi DocsClient 2015-05-08 09:05:47,401 -0400 INFO pid=360 -1323167744:_LaunchSyncApp docs_client_factory.pyo:99 Creating a new Wapi DocsClient 2015-05-08 09:05:47,401 -0400 INFO pid=360 -1323167744:_LaunchSyncApp docs_client_factory.pyo:99 Creating a new Wapi DocsClient 2015-05-08 09:05:47,412 -0400 INFO pid=360 -1323167744:_LaunchSyncApp service.pyo:280 Remove instance for service '<class 'common.push.notifier.Notifier'>' 2015-05-08 09:05:47,412 -0400 INFO pid=360 -1323167744:_LaunchSyncApp service.pyo:111 Creating user scope service subclass of '<class 'common.push.notifier.Notifier'>' 2015-05-08 09:05:47,412 -0400 INFO pid=360 -1323167744:_LaunchSyncApp service.pyo:113 Actual service created: <common.push.notifier.PushXMPPNotifier object at 0x8c88950> 2015-05-08 09:05:47,412 -0400 INFO pid=360 -1323167744:_LaunchSyncApp notifier.pyo:167 Configure storage: <common.sync_config_scilite.SyncConfigSOLite object at 0x8c88b70> 2015-05-08 09:05:47,425 -0400 INFO pid=360 -1323167744:_LaunchSyncApp notifier.pyo:213 Creating client thread 2015-05-08 09:05:47,426 -0400 INFO pid=360 -1323167744:_LaunchSyncApp push_xmpp_client.pyo:225 Using talk server: talk.google.com:5222 2015-05-08 09:05:47,426 -0400 INFO pid=360 -1323167744:_LaunchSyncApp notifier.pyo:222 Creating notification bridge 2015-05-08 09:05:47,485 -0400 INFO pid=360 -1323167744:_LaunchSyncApp docs_client_factory.pyo:99 Creating a new Wapi DocsClient 2015-05-08 09:05:47,487 -0400 INFO pid=360 -1314762752:FeatureSwitchPoller sync_client_thread.pyo:177 FeatureSwitchPoller run loop has started. 2015-05-08 09:05:47,487 -0400 INFO pid=360 -1310560256:TelemetryStoreSizeMonitorThread sync_client_thread.pyo:177 TelemetryStoreSizeMonitorThread run loop has started. 2015-05-08 09:05:47,487 -0400 INFO pid=360 -1314762752:FeatureSwitchPoller features.pyo:138 Loading feature switches with server. 2015-05-08 09:05:47,488 -0400 INFO pid=360 -1306357760:OverlayStatusCleaner sync_client_thread.pyo:177 OverlayStatusCleaner run loop has started. 2015-05-08 09:05:47,488 -0400 INFO pid=360 -1302155264:XmppClient connection_task.pyo:157 Recalculated LoginTask timeout: 16.000000 2015-05-08 09:05:47,489 -0400 INFO pid=360 -1310560256:TelemetryStoreSizeMonitorThread telemetry_store monitor_thread.pyo:47 Checking Telemetry store size. 2015-05-08 09:05:47,489 -0400 INFO pid=360 -1314762752:FeatureSwitchPoller EFTA00504440 feature_switch_manager.pyo:551 Calling server to get feature switches. 2015-05-08 09:05:47,489 -0400 INFO pid=360 -1297952768:PushNotificationKeepaliveThread sync_client_thread.pyo:177 PushNotificationKeepaliveThread run loop has started. 2015-05-08 09:05:47,490 -0400 INFO pid=360 -1293750272:Worker-1 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-05-08 09:05:47,491 -0400 INFO pid=360 -1289547776:LocalWatcher watcher.pyo:317 LocalWatcher Run invoked.Waiting for first pass of local scan from poller to start 2015-05-08 09:05:47,491 -0400 INFO pid=360 -1285345280:LocalDiskSpaceChecker sync_client_thread.pyo:177 LocalDiskSpaceChecker run loop has started. 2015-05-08 09:05:47,492 -0400 INFO pid=360 -1314762752:FeatureSwitchPoller sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-05-08 09:05:47,493 -0400 INFO pid=360 -1276940288:CloudWatcher sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-05-08 09:05:47,493 -0400 INFO pid=360 -1272737792:LocalFSPoller sync_client_thread.pyo:177 LocalFSPoller run loop has started. 2015-05-08 09:05:47,495 -0400 INFO pid=360 -1268535296:CrashReportUploaderThread crash_report_uploader_thread.pyo:58 Starting CrashReportUploaderThread. 2015-05-08 09:05:47,498 -0400 INFO pid=360 -1260130304:Batcher sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-05-08 09:05:47,499 -0400 INFO pid=360 -1293750272:Worker-1 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-05-08 09:05:47,499 -0400 INFO pid=360 -1251725312:Worker-2 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-05-08 09:05:47,500 -0400 INFO pid=360 -1247522816:RawEventUpgrader sync_client_thread.pyo:177 RawEventUpgrader run loop has started. 2015-05-08 09:05:47,500 -0400 INFO pid=360 -1243320320:Worker-0 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-05-08 09:05:47,500 -0400 INFO pid=360 -1239117824:TelemetryUploaderThread sync_client_thread.pyo:177 TelemetryUploaderThread run loop has started. 2015-05-08 09:05:47,501 -0400 INFO pid=360 -1323167744:_LaunchSyncApp drive_extension_installer.pyo:174 Uninstalling extension nknebiagdodnminbdpflhpkgfpeijdbf 2015-05-08 09:05:47,504 -0400 INFO pid=360 -1323167744:_LaunchSyncApp utils.pyo:640 Execute cleanup callback 'persistence_sqlite:217b90f2f46a4794b3d39c938fc03ba9/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2015-05-08 09:05:47,505 -0400 INFO pid=360 -1323167744:_LaunchSyncApp sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-05-08 09:05:47,507 -0400 INFO pid=360 -1293750272:Worker-1 utils.pyo:663 ADDING persistence_sqlite:84870a0d1lba4alaa2be807498278527/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-05-08 09:05:47,508 -0400 INFO pid=360 -1293750272:Worker-1 utils.pyo:663 ADDING persistence_sqlite:276e662531e443308123e0de3ba70bb0/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-05-08 09:05:47,508 -0400 INFO pid=360 -1293750272:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.downloads.DownloadChangeACL 2015-05-08 09:05:47,508 -0400 INFO pid=360 -1293750272:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.downloads.DownloadCreate 2015-05-08 09:05:47,508 -0400 INFO pid=360 -1293750272:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.downloads.DownloadDelete 2015-05-08 09:05:47,508 -0400 INFO pid=360 -1293750272:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.downloads.DownloadModify 2015-05-08 09:05:47,509 -0400 INFO pid=360 -1293750272:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.downloads.DownloadMove EFTA00504441 2015-05-08 09:05:47,509 -0400 INFO pid=360 -1293750272:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.downloads.DownloadRename 2015-05-08 09:05:47,510 -0400 INFO pid=360 -1293750272:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BackupCreate 2015-05-08 09:05:47,510 -0400 INFO pid=360 -1293750272:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BackupDelete 2015-05-08 09:05:47,510 -0400 INFO pid=360 -1293750272:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BackupModify 2015-05-08 09:05:47,511 -0400 INFO pid=360 -1293750272:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BackupMove 2015-05-08 09:05:47,511 -0400 INFO pid=360 -1293750272:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BackupRename 2015-05-08 09:05:47,511 -0400 INFO pid=360 -1293750272:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BatchUploadCreate 2015-05-08 09:05:47,511 -0400 INFO pid=360 -1293750272:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.uploads.UploadCreate 2015-05-08 09:05:47,511 -0400 INFO pid=360 -1293750272:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.uploads.UploadDelete 2015-05-08 09:05:47,511 -0400 INFO pid=360 -1293750272:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.uploads.UploadModify 2015-05-08 09:05:47,511 -0400 INFO pid=360 -1293750272:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.uploads.UploadMove 2015-05-08 09:05:47,512 -0400 INFO pid=360 -1276940288:CloudWatcher sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-05-08 09:05:47,512 -0400 INFO pid=360 -1314762752:FeatureSwitchPoller sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-05-08 09:05:47,513 -0400 INFO pid=360 -1323167744:_LaunchSyncApp utils.pyo:640 Execute cleanup callback 'persistence_sqlite:c86769c407fe4136b4f90c70c91529ac/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2015-05-08 09:05:47,514 -0400 INFO pid=360 -1260130304:Batcher sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-05-08 09:05:47,514 -0400 INFO pid=360 -1251725312:Worker-2 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-05-08 09:05:47,515 -0400 INFO pid=360 -1243320320:Worker-0 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-05-08 09:05:47,525 -0400 INFO pid=360 -1293750272:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.uploads.UploadRename 2015-05-08 09:05:47,526 -0400 INFO pid=360 -1323167744:_LaunchSyncApp sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-05-08 09:05:47,527 -0400 INFO pid=360 -1293750272:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.internals.InternalCreate 2015-05-08 09:05:47,527 -0400 INFO pid=360 -1293750272:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.internals.InternalModify 2015-05-08 09:05:47,527 -0400 INFO pid=360 -1293750272:Worker-1 workers.pyo:132 Waiting for batcher to initialize 2015-05-08 09:05:47,533 -0400 INFO pid=360 -1276940288:CloudWatcher utils.pyo:663 ADDING persistence_sqlite:9b2885039c124214bced4b760d676578/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-05-08 09:05:47,534 -0400 INFO pid=360 -1314762752:FeatureSwitchPoller utils.pyo:663 ADDING persistence_sqlite:94176b4a127b4d6c9d95cd0922ff52fd/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-05-08 09:05:47,534 -0400 INFO pid=360 -1276940288:CloudWatcher utils.pyo:663 ADDING persistence_sqlite:3feaead263934d7e85dc87b66f5adc2f/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db EFTA00504442 2015-05-08 09:05:47,534 -0400 INFO pid=360 -1314762752:FeatureSwitchPoller utils.pyo:663 ADDING persistence_scilite:244650acb7d542e8862056a3dde94f74/Users/Igroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-05-08 09:05:47,534 -0400 INFO pid=360 -1276940288:CloudWatcher cloud_graph.pyo:118 Initialize persistent graph with generation 2 2015-05-08 09:05:47,534 -0400 INFO pid=360 -1314762752:FeatureSwitchPoller feature_switch_manager.pyo:471 Successfully received feature switches from server. 2015-05-08 09:05:47,535 -0400 INFO pid=360 -1276940288:CloudWatcher cloud_graph.pyo:1507 Current sync config generation: 2 2015-05-08 09:05:47,540 -0400 INFO pid=360 -1276940288:CloudWatcher cloud_graph.pyo:1364 Cleanup cloud graph directory '/Users/Igroff/Library/Application Support/Google/Drive/user_default/cloud_graph' of files 'dict_*.db" except ['dict_2.db 1 , 'dict 2.db-shml, 'dict 2.db-wal'] 2015-05-08 09:05:47,541 -0400 INFO pid=360 -1260130304:Batcher utils.pyo:663 ADDING persistence_sglite:37030f05a06541f7b28e1536eed9ca8a/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-05-08 09:05:47,541 -0400 INFO pid=360 -1260130304:Batcher utils.pyo:663 ADDING persistence_scilite:a5ad330270b64a9b900db6eb4e46bf9b/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-05-08 09:05:47,542 -0400 INFO pid=360 -1251725312:Worker-2 utils.pyo:663 ADDING persistence_sglite:55087fd5a10a4e14822792fd55497e6f/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-05-08 09:05:47,542 -0400 INFO pid=360 -1243320320:Worker-0 utils.pyo:663 ADDING persistence_scilite:dfc40aa422ff4c2fa0708dcac257d7a6/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-05-08 09:05:47,549 -0400 INFO pid=360 -1251725312:Worker-2 utils.pyo:663 ADDING persistence_sglite:445738489fa24al0adadl8cc765d3cc9/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-05-08 09:05:47,549 -0400 INFO pid=360 -1293750272:Worker-1 sync_client_thread.pyo:177 Worker-1 run loop has started. 2015-05-08 09:05:47,550 -0400 INFO pid=360 -1243320320:Worker-0 utils.pyo:663 ADDING persistence_sglite:78a943054ec04603afdlf31f6515e737/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-05-08 09:05:47,550 -0400 INFO pid=360 -1251725312:Worker-2 workers.pyo:132 Waiting for batcher to initialize 2015-05-08 09:05:47,550 -0400 INFO pid=360 -1243320320:Worker-0 workers.pyo:132 Waiting for batcher to initialize 2015-05-08 09:05:47,551 -0400 INFO pid=360 -1251725312:Worker-2 sync_client_thread.pyo:177 Worker-2 run loop has started. 2015-05-08 09:05:47,551 -0400 INFO pid=360 -1243320320:Worker-0 sync_client_thread.pyo:177 Worker-0 run loop has started. 2015-05-08 09:05:47,576 -0400 INFO pid=360 -1302155264:XmppClient xmpplib.pyo:354 Start SSL peer 2015-05-08 09:05:47,717 -0400 INFO pid=360 -1276940288:CloudWatcher utils.pyo:663 ADDING DBConnectionManagerCleanupCallback 2015-05-08 09:05:47,717 -0400 INFO pid=360 -1276940288:CloudWatcher db_connection.pyo:207 Created thread connection on database /Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph/dict_2.db 2015-05-08 09:05:47,748 -0400 INFO pid=360 -1276940288:CloudWatcher cloud_graph.pyo:888 Reuse CloudDiskDict database: /Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph/dict_2.db 2015-05-08 09:05:47,875 -0400 INFO pid=360 -1276940288: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-05-08 09:05:47,875 -0400 INFO pid=360 -1276940288:CloudWatcher cloud_graph.pyo:256 Building gdoc docids cache. 2015-05-08 09:05:48,497 -0400 INFO pid=360 -1302155264:XmppClient xmpplib.pyo:358 Finish SSL 2015-05-08 09:05:48,662 -0400 INFO pid=360 -1302155264:XmppClient task.pyo:257 Task <common.push.xmpp.login_task.LoginTask instance at 0xb875be8> complete. 2015-05-08 09:05:48,662 -0400 WARNING pid=360 -1302155264:XmppClient login_task.pyo:117 Clearing connection for LoginTask: <common.push.xmpp.login_task.LoginTask instance at 0xb875be8> 2015-05-08 09:05:48,662 -0400 INFO pid=360 -1302155264:XmppClient push_xmpp_client.pyo:304 Connected to talk.google.com:5222 EFTA00504443 2015-05-08 09:05:48,663 -0400 WARNING pid=360 -1323167744:Thread-4 notification_bridge.pyo:121 XMPP detected connect. 2015-05-08 09:05:48,663 -0400 INFO pid=360 -1323167744:Thread-4 xmpp_network_channel.pyo:148 Sending push channel subscription request 2015-05-08 09:05:48,689 -0400 INFO pid=360 -1302155264:XmppClient xmpp_network_channel.pyo:166 Received subscription response from Push for XMPPBot 2015-05-08 09:05:48,690 -0400 INFO pid=360 -1323167744:Thread-4 notification_bridge.pyo:137 Starting TICL (client version 3.20130807)... 2015-05-08 09:05:48,690 -0400 INFO pid=360 -1323167744:Thread-4 xmpp_network_channel.pyo:64 XmppNetworkChannel.InformNetworkStatus: CONNECTED 2015-05-08 09:05:48,773 -0400 INFO pid=360 -1264332800:ListenerScheduler invalidation_listener.pyo:185 InvalidationListener.ReissueRegistrations(prefix=, prefix_len=0) 2015-05-08 09:05:48,930 -0400 INFO pid=360 -1276940288:CloudWatcher cloud_graph.pyo:171 Reuse CloudDiskDict CloudDiskDict(max_change_stamp=61358, app_version=1.20.8672.3137, only_rooted_items=False, ()) 2015-05-08 09:05:48,931 -0400 INFO pid=360 -1276940288:CloudWatcher cloud_watcher.pyo:797 Performing full snapshot diff. 2015-05-08 09:05:48,931 -0400 INFO pid=360 -1276940288:CloudWatcher cloud_watcher.pyo:986 Getting cloud resources via changelog feed from 61359 2015-05-08 09:05:48,931 -0400 INFO pid=360 -1276940288:CloudWatcher docs_client.pyo:741 Requesting Doc List: iffeeds/default/private/changes?start-index=61359&showroot=true&include- shared=true&reason=startup 2015-05-08 09:05:51,538 -0400 INFO pid=360 -1264332800:ListenerScheduler invalidation_listener.pyo:125 InvalidationListener.InformRegistrationStatus(1014:CHANGELOG, REGISTERED) 2015-05-08 09:05:51,538 -0400 WARNING pid=360 -1264332800:ListenerScheduler notification_bridge.pyo:192 Invalidation status callback called with connected=True 2015-05-08 09:05:51,538 -0400 INFO pid=360 -1264332800:ListenerScheduler pause_manager.pyo:171 Removing pause reasons (OFFLINE) from (). Paused: False 2015-05-08 09:05:51,554 -0400 INFO pid=360 -1264332800:ListenerScheduler invalidation_listener.pyo:82 InvalidationListener.Invalidation(1014:CHANGELOG, wapi_version=61371) 2015-05-08 09:05:52,068 -0400 INFO pid=360 -1297952768:PushNotificationKeepaliveThread push_notification_keepalive_thread.pyo:49 Sent push keepalive message at: Fri May 8 09:05:52 2015 2015-05-08 09:05:53,563 -0400 INFO pid=360 -1276940288:CloudWatcher docs_client.pyo:1131 Feed has 1 entries 2015-05-08 09:05:53,563 -0400 INFO pid=360 -1276940288:CloudWatcher docs_client.pyo:1138 End of feed, no next URI 2015-05-08 09:05:53,567 -0400 INFO pid=360 -1276940288:CloudWatcher cloud_watcher.pyo:766 Got 1 cloud entries 2015-05-08 09:05:53,568 -0400 INFO pid=360 -1276940288:CloudWatcher cloud_watcher.pyo:783 588 nodes in the cloud graph 2015-05-08 09:05:53,606 -0400 INFO pid=360 -1276940288:CloudWatcher cloud_snapshot_diff_helper.pyo:84 Prepare for Pruning graph (total of 588 entries). sync gdocs=True, sync folders=None, excluded folders=frozenset(()) 2015-05-08 09:05:53,607 -0400 INFO pid=360 -1276940288:CloudWatcher cloud snapshot diff_helper.pyo:85 Start Prune Graph (total of 588 entries) 2015-05-08 09:05:53,615 -0400 INFO pid=360 -1276940288:CloudWatcher cloud snapshot diff_helper.pyo:168 Pruning detected 582 items to keep 2015-05-08 09:05:53,615 -0400 INFO pid=360 -1276940288:CloudWatcher cloud snapshot diff_helper.pyo:175 582 entries in graph after pruning 2015-05-08 09:05:53,615 -0400 INFO pid=360 -1276940288:CloudWatcher cloud snapshot diff_helper.pyo:93 Compare to snaphost 2015-05-08 09:05:53,616 -0400 INFO pid=360 -1276940288:CloudWatcher cloud_snapshot_diff_helper.pyo:751 Starting cloud graph vs snapshot diff with selective sync set: None 2015-05-08 09:05:53,616 -0400 INFO pid=360 -1276940288:CloudWatcher cloud snapshot cliff_helper.pyo:753 Prepopulating DOC ID-to-inodes cache 2015-05-08 09:05:53,676 -0400 INFO pid=360 -1276940218:CloudWatcher cloud snapshot cliff_helper.pyo:757 First pass detecting moved entries 2015-05-08 09:05:53,890 -0400 INFO pid=360 -1276940288:CloudWatcher cloud snapshot diff helper.pyo:772 Second pass detecting creations and modification entries 2015-05-08 09:05:54,171 -0400 INFO pid=360 -1276940288:CloudWatcher EFTA00504444 cloud_snapshot_diff_helper.pyo:799 Processed 581 entries in total. Number of events detected: (_FSChangeActionType(Action.MODIFY): 1) 2015-05-08 09:05:54,215 -0400 INFO pid=360 -1276940288:CloudWatcher cloud_watcher.pyo:840 Done with full snapshot diff. 2015-05-08 09:05:54,215 -0400 INFO pid=360 -1276940288:CloudWatcher cloud_watcher.pyo:896 CloudWatcher generated 1 events 2015-05-08 09:05:54,215 -0400 INFO pid=360 -1276940288:CloudWatcher cloud_watcher.pyo:902 CloudWatcher generated FSChange(Direction.DOWNLOAD, Action.MODIFY, ino=13676561, name=2015 May.docx, rid=011xNN8iEiWGvgWEFSZylucFMOLUk, parent_ino=529993, is_folder=False, modified=1431076872, checksum=5a2ae3e29b4002cbd418e201ae969e10, size=89396, backup=Backup.NO_BACKUP_CONTENT, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB) 2015-05-08 09:05:54,276 -0400 INFO pid=360 -1276940288:CloudWatcher aggregator.pyo:114 --- > Received event None FSChange(Direction.DOWNLOAD, Action.MODIFY, ino=13676561, name=2015 May.docx, rid=0BxNN8iEiWGvgWEFSZylucFMOLUk, parent_ino=529993, is_folder=False, modified=1431076872, checksum=5a2ae3e29b4002cbd418e201ae969e10, size=89396, backup=Backup.NO_BACKUP_CONTENT, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB) 2015-05-08 09:05:54,276 -0400 INFO pid=360 -1276940288:CloudWatcher downloads.pyo:127 ProcessDownloadModify (FSChange(Direction.DOWNLOAD, Action.MODIFY, ino=13676561, name=2015 May.docx, rid=011xNN8iEiWGvgWEFSZylucFMOLUk, parent_ino=529993, is_folder=False, modified=1431076872, checksum=5a2ae3e29b4002cbd418e201ae969e10, size=89396, backup=Backup.NO_BACKUP_CONTENT, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB)) 2015-05-08 09:05:54,276 -0400 INFO pid=360 -1276940288:CloudWatcher change_buffer.pyo:1028 Adding change to change buffer: FSChange(Direction.DOWNLOAD, Action.MODIFY, ino=13676561, name=2015 May.docx, rid=011xNN8iEiWGvgWEFSZylucFMOLUk, parent_ino=529993, is_folder=False, modified=1431076872, checksum=5a2ae3e29b4002cbd418e201ae969e10, size=89396, backup=Backup.NO_BACKUP_CONTENT, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB, hash=1449038168) 2015-05-08 09:05:54,277 -0400 INFO pid=360 -1276940288:CloudWatcher cloud_watcher.pyo:671 Change ignored. observed: 61371, retrieved: 61371 2015-05-08 09:05:54,503 -0400 INFO pid=360 -1272737792:LocalFSPoller poller.pyo:156 Cloud scan complete. Preparing to start LocalFSPoller 2015-05-08 09:05:54,503 -0400 INFO pid=360 -1272737792:LocalFSPoller poller.pyo:168 LocalFSPoller first pass started. Waiting for localWatcher to start watching. 2015-05-08 09:05:54,504 -0400 INFO pid=360 -1289547776:LocalWatcher watcher.pyo:240 RootObserverRegistered with roots [_MyDriveRoot(u'/Users/lgroff/Google Drive')] 2015-05-08 09:05:54,504 -0400 INFO pid=360 -1289547776:LocalWatcher watcher.pyo:275 Scheduling root: _MyDriveRoot(u'/Users/lgroff/Google Drive') 2015-05-08 09:05:54,504 -0400 INFO pid=360 -1289547776:LocalWatcher watcher.pyo:338 LocalWatcher is up. 2015-05-08 09:05:54,504 -0400 INFO pid=360 -1272737792:LocalFSPoller poller.pyo:177 Finished waiting for LocalWatcher. 2015-05-08 09:05:54,505 -0400 INFO pid=360 -1272737792:LocalFSPoller poller.pyo:180 Starting a local disk scan. 2015-05-08 09:05:54,505 -0400 INFO pid=360 -1272737792:LocalFSPoller diff_helper.pyo:87 Walking: [u'/Users/lgroff/Google Drive'] 2015-05-08 09:05:55,109 -0400 INFO pid=360 -1272737792:LocalFSPoller poller.pyo:197 Number of found changes: 0 2015-05-08 09:05:55,111 -0400 INFO pid=360 -1272737792:LocalFSPoller poller.pyo:209 Finished local disk scan. 2015-05-08 09:05:55,162 -0400 INFO pid=360 -1260130304:Batcher batcher.pyo:240 Ready changes are pending. 2015-05-08 09:05:55,165 -0400 INFO pid=360 -1293750272:Worker-1 workers.pyo:188 Worker starting on (ImmutableChange(Direction.DOWNLOAD, Action.MODIFY, ino=13676561, name=2015 May.docx, rid=011xNN8iEiWGvgWEFSZylucFMOLUk, parent_ino=529993, is_folder=False, modified=1431076872, checksum=5a2ae3e29b4002cbd418e201ae969e10, size=89396, backup=Backup.NO_BACKUP_CONTENT, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB, hash=1449038168, _constructor_called=True)) 2015-05-08 09:05:55,168 -0400 INFO pid=360 -1293750272:Worker-1 download.pyo:112 Downloading https://googledrive.com/p/host/OBxNN8iEiWGvgWEFSZylucFMOLUk to /var/folders/nl/ypw25pmj06gfyyl_8xnt3kf40000gn/T/tmpYjWCvD 2015-05-08 09:05:56,163 -0400 INFO pid=360 -1260130304:Batcher batcher.pyo:257 There are no blacklisted requests. EFTA00504445 2015-05-08 09:05:59,874 -0400 INFO pid=360 -1293750272:Worker-1 fileutils.pyo:208 Opening /var/folders/n1/ypw25pmj06cifyyl_8xntakf40000gn/T/tmpYjWCvD 2015-05-08 09:05:59,886 -0400 INFO pid=360 -1293750272:Worker-1 commands.pyo:56 Updating inode in snapshot. old=13676561, new=13786628 2015-05-08 09:05:59,887 -0400 INFO pid=360 -1293750272:Worker-1 commands.pyo:59 Entry associated with new inode prior to update None: 2015-05-08 09:05:59,887 -0400 INFO pid=360 -1293750272:Worker-1 snapshot_sglite.pyo:508 Removing local relation child_inode=13676561, parent_inode=529993 2015-05-08 09:05:59,887 -0400 INFO pid=360 -1293750272:Worker-1 snapshot_sglite.pyo:528 Removing Mapping inode=13676561, doc_id=0BxNN8iEiWGvgWEFSZylucFMOLUk 2015-05-08 09:05:59,887 -0400 INFO pid=360 -1293750272:Worker-1 snapshot_sglite.pyo:154 Removing local entry inode=13676561 2015-05-08 09:05:59,887 -0400 INFO pid=360 -1293750272:Worker-1 snapshot_sglite.pyo:143 Adding local entry inode=13786628, filename=None 2015-05-08 09:05:59,888 -0400 INFO pid=360 -1293750272:Worker-1 snapshot_sglite.pyo:498 Adding local relation child_inode=13786628, parent_inode=529993 2015-05-08 09:05:59,888 -0400 INFO pid=360 -1293750272:Worker-1 snapshot_sglite.pyo:225 Updating local entry inode=13786628, filename=2015 May.docx, modified=1431029936, checksum=20d8dedfcf487bfc79956ccc5d7914de, size=56727 2015-05-08 09:05:59,888 -0400 INFO pid=360 -1293750272:Worker-1 snapshot_sglite.pyo:518 Adding Mapping inode=13786628, doc_id=0BxNN8iEiWGvgWEFSZylucFMOLUk 2015-05-08 09:05:59,888 -0400 INFO pid=360 -1293750272:Worker-1 snapshot_sglite.pyo:225 Updating local entry inode=13786628, modified=1431076872, checksum=5a2ae3e29b4002cbd418e201ae969e10, size=89396 2015-05-08 09:05:59,888 -0400 INFO pid=360 -1293750272:Worker-1 snapshot_sglite.pyo:248 Updating cloud entry doc_id=0BxNN8iEiWGvgWEFSZylucFMOLUk, filename=None 2015-05-08 09:05:59,889 -0400 INFO pid=360 -1293750272:Worker-1 workers.pyo:199 Worker successfully completed (ImmutableChange(Direction.DOWNLOAD, Action.MODIFY, ino=13676561, name=2015 May.docx, rid=08xNN8iEiWGvgWEFSZylucFMOLUk, parent_ino=529993, is_folder=False, modified=1431076872, checksum=5a2ae3e29b4002cbd418e201ae969e10, size=89396, backup=Backup.NO_BACKUP_CONTENT, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB, hash=1449038168, _constructor_called=True)] 2015-05-08 09:05:59,889 -0400 INFO pid=360 -1293750272:Worker-1 change_buffer.pyo:276 Removing entry from change buffer: ChangeBufferEntry(state=IN_PROGRESS, fschange=ImmutableChange(Direction.DOWNLOAD, Action.MODIFY, ino=13676561, name=2015 May.docx, rid=0BxNN8iEiWGvgWEFSZylucFMOLUk, parent_ino=529993, is_folder=False, modified=1431076872, checksum=5a2ae3e29b4002cbd418e201ae969e10, size=89396, backup=Backup.NO_BACKUP_CONTENT, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB, hash=1449038168, _constructor_called=True), seguence_number=0) 2015-05-08 09:05:59,896 -0400 INFO pid=360 -1318965248:FilewatcherMonitor watcher.pyo:520 PathsCallback called with: (u'/Users/lgroff/Google Drive/My Documents/.] 2015-05-08 09:05:59,897 -0400 INFO pid=360 -1318965248:FilewatcherMonitor diff_helper.pyo:87 Scanning: (u'/Users/lgroff/Google Drive/My Documents'] 2015-05-08 09:06:00,168 -0400 INFO pid=360 -1260130304:Batcher batcher.pyo:232 Current batch is completed. Batcher snooze. 2015-05-08 09:06:00,169 -0400 INFO pid=360 -1260130304:Batcher sync_status.pyo:133 Reset sync status 2015-05-08 09:06:00,169 -0400 INFO pid=360 -1260130304:Batcher batcher.pyo:846 Batcher Stats = file_count = Counter( { COUNT KEY Cdirection=_DownloadDirectionType(Direction.DOWNLOAD), action=_FSChangeActionType(Action.MODIFY), batch=False, successful=True): 1)), byte_count = Counter( { COUNT KEY Cdirection=_DownloadDirectionType(Direction.DOWNLOAD), action=_FSChangeActionType(Action.MODIFY), batch=False, successful=True): 89396)), batch_operation_count = Counter(), process_seconds = Counter( { COUNT KEY Cdirection=_DownloadDirectionType(Direction.DOWNLOAD), action=_FSChangeActionType(Action.MODIFY), batch=False, successful=True): 4.723232984542847)), duration seconds = 5 (start_time = 1431090355, end_time = 1431090360) 2015-05-08 09:06:03,748 -0400 INFO pid=360 -1318965248:FilewatcherMonitor watcher.pyo:520 PathsCallback called with: (u'/Users/lgroff/Google Drive/My Documents/ I ] 2015-05-08 09:06:03,749 -0400 INFO pid=360 -1318965248:FilewatcherMonitor diff_helper.pyo:87 Scanning: (u'/Users/lgroff/Google Drive/My Documents'] 2015-05-08 09:20:47,498 -0400 INFO pid=360 -1310560256:TelemetryStoreSizeMonitorThread telemetry_store_monitor_thread.pyo:47 Checking Telemetry store size. 2015-05-08 09:35:47,534 -0400 INFO pid=360 -1310560256:TelemetryStoreSizeMonitorThread EFTA00504446 telemetry_store_monitor_thread.pyo:47 Checking Telemetry store size. 2015-05-08 09:41:36,728 -0400 INFO pid=360 -1318965248:FilewatcherMonitor watcher.pyo:520 PathsCallback called with: (u'/Users/lgroff/Google Drive/My Documents/', u'/Users/lgroff/Google Drive/') 2015-05-08 09:41:36,823 -0400 INFO pid=360 -1318965248:FilewatcherMonitor diff_helper.pyo:87 Scanning: (u'/Users/lgroff/Google Drive/My Documents', u'/Users/lgroff/Google Drive') 2015-05-08 09:41:37,446 -0400 INFO pid=360 -1318965248:FilewatcherMonitor fileutils.pyo:208 Opening /Users/lgroff/Google Drive/My Documents/2015 May.docx 2015-05-08 09:41:37,447 -0400 INFO pid=360 -1318965248:FilewatcherMonitor diff_helper.pyo:134 Found 1 changes: [(FSChange(Direction.UPLOAD, Action.MODIFY, ino=13786628, path=u'/Users/lgroff/Google Drive/My Documents', name=u'2015 May.docx', parent_ino=529993, new_ino=13786628, modified=1431092495.0, checksum=26e47bdcd19e14bbdlcfc2c42d3bccd6, size=89511, backup=Backup.NO_BACKUP_CONTENT, generator_module=1, is_cancelled=False, is_priority=False))) 2015-05-08 09:41:37,447 -0400 INFO pid=360 -1318965248:FilewatcherMonitor aggregator.pyo:114 > Received event ImmutableRawEvent(op=MODIFY, path=/Users/lgroff/Google Drive/My Documents/2015 May.docx, time=1431092497.45, is_dir=False, ino=13786628, size=89511, old_path=None, new_ino=13786628, mtime=1431092495.0, parent_ino=529993, affects_gdoc=None, is_cancelled=RawEventIsCancelledFlag.FALSE, old_parent_ino=None, backup=Backup.NO_BACKUP_CONTENT, _hash_code=600693540) FSChange(Direction.UPLOAD, Action.MODIFY, ino=13786628, path=u'/Users/lgroff/Google Drive/My Documents', name=u'2015 Ma