EFTA00502249.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-04-20 16:49:19,510 -0400 INFO pid=1882 -1603214936:MainThread thread_manager.pyo:77 Stop was called 2015-04-20 16:49:19,510 -0400 WARNING pid=1882 -1603214936:MainThread task.pyo:245 DriveXmppTask-0xb6faa08 aborting: abort 2015-04-20 16:49:19,510 -0400 INFO pid=1882 -1603214936:MainThread task.pyo:257 Task <common.push.push_xmpp_client.DriveXmppTask instance at 0xb6faa08> complete. 2015-04-20 16:49:19,535 -0400 INFO pid=1882 -1603214936:MainThread xmpplib.pyo:114 Close called with 'why' reason = Client initiated close 2015-04-21 09:02:50,978 -0400 INFO pid=3672 -1603214936:MainThread logging_config.pyo:249 OS: Darwin/10.9.5 2015-04-21 09:02:50,995 -0400 INFO pid=3672 -1603214936:MainThread logging_config.pyo:252 Google Drive (build 1.20.8672.3137) 2015-04-21 09:02:51,029 -0400 INFO pid=3672 -1603214936:MainThread logging_config.pyo:253 SSL: OpenSSL 0.9.82c 15 Oct 2014 2015-04-21 09:02:51,048 -0400 INFO pid=3672 -1603214936:MainThread GSyncAppDelegate.pyo:216 Application did finish launching. 2015-04-21 09:02:51,617 -0400 INFO pid=3672 -1335775232:Thread-1 service.pyo:71 Creating user scope service instance of '<class 'common.service.user.User'>' 2015-04-21 09:02:51,617 -0400 INFO pid=3672 -1335775232:Thread-1 service.pyo:71 Creating user scope service instance of '<class 'common.local.roots.RootManager'>' 2015-04-21 09:02:51,617 -0400 INFO pid=3672 -1335775232:Thread-1 service.pyo:71 Creating user scope service instance of '<class 'common.pause_manager.Service'>' 2015-04-21 09:02:51,617 -0400 INFO pid=3672 -1335775232:Thread-1 sync_app.pyo:301 Getting TokenStorage... 2015-04-21 09:02:51,617 -0400 INFO pid=3672 -1335775232:Thread-1 sync_app.pyo:303 Got token storage: <token_storage.TokenStorage object at 0x7fd4360> 2015-04-21 09:02:51,618 -0400 INFO pid=3672 -1335775232:Thread-1 service.pyo:71 Creating user scope service instance of '<class 'common.features.AppFeatures'>' 2015-04-21 09:02:51,619 -0400 INFO pid=3672 -1335775232:Thread-1 service.pyo:71 Creating user scope service instance of '<class 'common.cert.Manager'>' 2015-04-21 09:02:51,619 -0400 INFO pid=3672 -1335775232:Thread-1 service.pyo:156 Creating singleton service instance of '<class 'common.sync_http_client.BandwidthThrottler'> 1 2015-04-21 09:02:51,620 -0400 INFO pid=3672 -1335775232:Thread-1 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-21 09:02:51,621 -0400 INFO pid=3672 -1335775232:Thread-1 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-21 09:02:51,622 -0400 INFO pid=3672 -1335775232:Thread-1 utils.pyo:663 ADDING persistence_sqlite:d3fc9e8b6fbf41b38ea7240f05df3d12/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-21 09:02:51,622 -0400 INFO pid=3672 -1335775232:Thread-1 utils.pyo:663 ADDING persistence_sqlite:7293c27aff8c4bd8a25beald808d398d/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-21 09:02:51,734 -0400 INFO pid=3672 -1335775232:Thread-1 sync_app.pyo:477 BandwidthThrottler configured via sync_config 2015-04-21 09:02:51,734 -0400 INFO pid=3672 -1335775232:Thread-1 sync_http_client.pyo:91 BandwidthThrottler.SetRxBytesPerSecond 0 2015-04-21 09:02:51,734 -0400 INFO pid=3672 -1335775232:Thread-1 EFTA00502249 sync_http_client.pyo:100 BandwidthThrottler.SetTxBytesPerSecond 0 2015-04-21 09:02:51,734 -0400 INFO pid=3672 -1335775232:Thread-1 sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-21 09:02:51,734 -0400 INFO pid=3672 -1335775232:Thread-1 sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-21 09:02:51,735 -0400 INFO pid=3672 -1335775232:Thread-1 utils.pyo:640 Execute cleanup callback 'persistence_sqlite:d3fc9e8b6fbf41b38ea7240f05df3d12/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2015-04-21 09:02:51,736 -0400 INFO pid=3672 -1335775232:Thread-1 utils.pyo:640 Execute cleanup callback 'persistence_sqlite:7293c27aff8c4bd8a25beald808d398d/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2015-04-21 09:02:51,742 -0400 INFO pid=3672 -1331572736:Thread-2 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-21 09:02:51,743 -0400 INFO pid=3672 -1331572736:Thread-2 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-21 09:02:51,743 -0400 INFO pid=3672 -1331572736:Thread-2 utils.pyo:663 ADDING persistence_sqlite:77e75ce5fa3c4a61918c31cdb3715b7f/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-21 09:02:51,744 -0400 INFO pid=3672 -1331572736:Thread-2 utils.pyo:663 ADDING persistence_sqlite:f9f0b6d6a99b4147b613a02cfce2976e/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-21 09:02:51,744 -0400 INFO pid=3672 -1331572736:Thread-2 feature_switch_manager.pyo:479 Successfully read persisted feature switches. 2015-04-21 09:02:51,970 -0400 INFO pid=3672 -1603214936:MainThread image_resources.py:246 Loading image resources/images/ic_drawer_24.png 2015-04-21 09:02:52,027 -0400 INFO pid=3672 -1603214936:MainThread image_resources.py:246 Loading image resources/images/menu_drive-logo.png 2015-04-21 09:02:52,055 -0400 INFO pid=3672 -1603214936:MainThread image_resources.py:246 Loading image resources/images/menu_google-logo-gray.png 2015-04-21 09:02:52,062 -0400 INFO pid=3672 -1603214936:MainThread image_resources.py:246 Loading image resources/images/ic_folder_mydrive_24.png 2015-04-21 09:02:52,064 -0400 INFO pid=3672 -1603214936:MainThread image_resources.py:246 Loading image resources/images/ic_web_24.png 2015-04-21 09:02:52,067 -0400 INFO pid=3672 -1603214936:MainThread image_resources.py:246 Loading image resources/images/menu_backups.png 2015-04-21 09:02:52,070 -0400 INFO pid=3672 -1603214936:MainThread image_resources.py:246 Loading image resources/images/ic_sync_problem_24.png 2015-04-21 09:02:52,091 -0400 INFO pid=3672 -1603214936:MainThread image_resources.py:246 Loading image resources/images/ic_done_24.png 2015-04-21 09:02:52,187 -0400 INFO pid=3672 -1603214936:MainThread service.pyo:209 Creating paramed singleton service instance of '<class 'common.file_lock.FileLock'>' 2015-04-21 09:02:52,187 -0400 INFO pid=3672 -1603214936:MainThread service.pyo:209 Creating paramed singleton service instance of '<class 'common.file_lock.FileLock'>' 2015-04-21 09:02:52,229 -0400 INFO pid=3672 -1323167744:ThreadMonitor thread_manager.pyo:259 Waiting on exit condition. Wake up after 3600s. 2015-04-21 09:02:52,230 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-21 09:02:52,231 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-21 09:02:52,232 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp utils.pyo:663 ADDING persistence_sqlite:5353f30beadb4518a60926b4laaec5d5/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-21 09:02:52,232 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp utils.pyo:663 ADDING persistence_sqlite:1038f9ec8483449b92a464bac7f29be6/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-21 09:02:52,257 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp user.pyo:64 Initializing User instance with new credentials. None EFTA00502250 2015-04-21 09:02:52,257 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp docs_client_factory.pyo:99 Creating a new Wapi DocsClient 2015-04-21 09:02:52,259 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp service.pyo:71 Creating user scope service instance of '<class 'common.token_bucket.CloudBucketManager'>' 2015-04-21 09:02:52,284 -0400 ERROR pid=3672 -1327370240:IpcServerThread ipc_socket_base.pyo:249 Failed to bind to socket /private/var/folders/n1/ypw25pmj06qfyyl_8xntakf40000gn/T/tmpKYAnC5 (attempt 1 of 3) Traceback (most recent call last): File "common/ipc/ipc_socket_base.pyou, line 242, in Bind File "common/ipc/ipc_socket_base.pyo", line 176, in Bind File "socket.pyo", line 224, in meth error: (Errno 48] Address already in use 2015-04-21 09:02:52,284 -0400 ERROR pid=3672 -1327370240:IpcServerThread ipc_socket_base.pyo:249 Failed to bind to socket /private/var/folders/n1/ypw25pmj06qfyyl_8xntakf40000gn/T/tmpKYAnC5 (attempt 2 of 3) Traceback (most recent call last): File ucommon/ipc/ipc_socket_base.pyo", line 242, in Bind File "common/ipc/ipc_socket_base.pyo", line 176, in Bind File "socket.pyo", line 224, in meth error: (Errno 48] Address already in use 2015-04-21 09:02:52,285 -0400 WARNING pid=3672 -1327370240:IpcServerThread server.pyo:144 Socket (/Users/lgroff/Library/Application Support/Google/Drive/GoogleDrivelpcPipe) already in use, deleting it. 2015-04-21 09:02:52,344 -0400 INFO pid=3672 -1331572736:Thread-2 finder_injector.pyo:426 Local Google Drive path not yet available, skip loading overlays 2015-04-21 09:02:52,346 -0400 INFO pid=3672 -1314762752:Finderlnjector sync_client_thread.pyo:177 Finderlnjector run loop has started. 2015-04-21 09:02:52,346 -0400 INFO pid=3672 -1331572736:Thread-2 utils.pyo:640 Execute cleanup callback 'persistence_sqlite:77e75ce5fa3c4a61918c31cdb3715b7f/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2015-04-21 09:02:52,347 -0400 INFO pid=3672 -1331572736:Thread-2 sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-21 09:02:52,347 -0400 INFO pid=3672 -1331572736:Thread-2 utils.pyo:640 Execute cleanup callback 'persistence_sqlite:f9f0b6d6a99b4147b613a02cfce2976e/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2015-04-21 09:02:52,347 -0400 INFO pid=3672 -1331572736:Thread-2 sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-21 09:03:01,297 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp user.pyo:64 Initializing User instance with new credentials. lesley.jee@gmail.com 2015-04-21 09:03:01,297 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp features.pyo:109 Loads feature switches. 2015-04-21 09:03:01,297 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp features.pyo:138 Loading feature switches with server. 2015-04-21 09:03:01,297 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp feature_switch_manager.pyo:551 Calling server to get feature switches. 2015-04-21 09:03:01,298 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp feature_switch_manager.pyo:471 Successfully received feature switches from server. 2015-04-21 09:03:01,299 -0400 INFO pid=3672 -1318965248:_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=p1.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, EFTA00502251 download_strategy=turl_template', download_url=thttps://googledrive.com/p/host/(doc_id)', 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=iprod., ghost_all_files=False, help_ur1='https://support.google.com/drive/M1=%s&p=desktop_homel, log_backup_count=0, log_size_mb=256, logging_level='info', max_batch_upload_files=30, newdocument_ur1= 1 https://docs.google.com/document?usp=drive_syncl, new presentation ur1='https://docs.google.com/presentation?usp=drive_sync', new_spreadsheet_ur1=Ilittps://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.6.8 1, '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_url=lhttps://drive.google.com/sharing/share? subapp=10&shareProtocolVersion=2&theme=2&command=settings&shareUiType=default&authuser=0&clie nt=desktop', show_confirmation_dialog_on_delete=True, telemetry_enabled=True, telemetry_upload_interval_secs=1800, telemetry_ur1='https://drive.google.com/syncclient_impressions', token_bucket_read_qps=10, token_bucket_write_qps=3,) 2015-04-21 09:03:01,317 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp logging_config.pyo:249 OS: Darwin/10.9.5 2015-04-21 09:03:01,329 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp logging_config.pyo:252 Google Drive (build 1.20.8672.3137) 2015-04-21 09:03:01,329 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp logging_config.pyo:253 SSL: OpenSSL 0.9.8zc 15 Oct 2014 2015-04-21 09:03:01,329 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp sync_app.pyo:1162 Config: Email: lesley.jee@gmail.com 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-04-21 09:03:01,332 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp service.pyo:111 Creating user scope service subclass of '<class 'common.push.notifier.Notifier'>' 2015-04-21 09:03:01,332 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp service.pyo:113 Actual service created: <common.push.notifier.PushXMPPNotifier object at 0x8c89950> 2015-04-21 09:03:02,185 -0400 INFO pid=3672 -1603214936:MainThread service.pyo:71 EFTA00502252 Creating user scope service instance of '<class 'common.auto_backup.AutoRackup'>' 2015-04-21 09:03:02,186 -0400 INFO pid=3672 -1603214936:MainThread sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-21 09:03:02,186 -0400 INFO pid=3672 -1603214936:MainThread sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-21 09:03:02,187 -0400 INFO pid=3672 -1603214936:MainThread sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-21 09:03:02,187 -0400 INFO pid=3672 -1603214936:MainThread sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-21 09:03:02,187 -0400 INFO pid=3672 -1603214936:MainThread service.pyo:71 Creating user scope service instance of '<class 'common.telemetry.telemetry_manager.TelemetryManager'>' 2015-04-21 09:03:06,014 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp image_resources.py:222 Loading custom image resources/images/folder-mac.icns with function _CreateNSImage 2015-04-21 09:03:07,189 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.downloads.DownloadCreate 2015-04-21 09:03:07,189 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.downloads.DownloadModify 2015-04-21 09:03:07,190 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadCreate 2015-04-21 09:03:07,190 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadDelete 2015-04-21 09:03:07,190 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp rulefactory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadModify 2015-04-21 09:03:07,190 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadMove 2015-04-21 09:03:07,190 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadMoveFrom 2015-04-21 09:03:07,190 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator rules.uploads.UploadMoveTo 2015-04-21 09:03:07,192 -0400 INFO pid=3672 -1335242752:FilewatcherMonitor filewatcher.pyo:68 filewatcher_ext initialized 2015-04-21 09:03:07,192 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp docs_client_factory.pyo:99 Creating a new Wapi DocsClient 2015-04-21 09:03:07,193 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp docs_client_factory.pyo:99 Creating a new Wapi DocsClient 2015-04-21 09:03:07,194 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp docs_client_factory.pyo:99 Creating a new Wapi DocsClient 2015-04-21 09:03:07,194 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp docs_client_factory.pyo:99 Creating a new Wapi DocsClient 2015-04-21 09:03:07,195 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp docs_client_factory.pyo:99 Creating a new Wapi DocsClient 2015-04-21 09:03:07,195 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp docs_client_factory.pyo:99 Creating a new Wapi DocsClient 2015-04-21 09:03:07,195 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp docs_client_factory.pyo:99 Creating a new Wapi DocsClient 2015-04-21 09:03:07,196 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp service.pyo:280 Remove instance for service '<class 'common.push.notifier.Notifier'>' 2015-04-21 09:03:07,196 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp service.pyo:lll Creating user scope service subclass of '<class 'common.push.notifier.Notifier'>' 2015-04-21 09:03:07,196 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp service.pyo:113 Actual service created: <common.push.notifier.PushXMPPNotifier object at 0x8c89950> 2015-04-21 09:03:07,196 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp notifier.pyo:167 Configure storage: <common.sync_config_sqlite.SyncConfigSQLite object at 0x8c89670> 2015-04-21 09:03:07,257 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp notifier.pyo:213 Creating client thread 2015-04-21 09:03:07,257 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp push_xmpp_client.pyo:225 Using talk server: talk.google.com:5222 2015-04-21 09:03:07,258 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp notifier.pyo:222 Creating notification bridge EFTA00502253 2015-04-21 09:03:07,342 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp docs_client_factory.pyo:99 Creating a new Wapi DocsClient 2015-04-21 09:03:07,344 -0400 INFO pid=3672 -1310560256:FeatureSwitchPoller sync_client_thread.pyo:177 FeatureSwitchPoller run loop has started. 2015-04-21 09:03:07,345 -0400 INFO pid=3672 -1310560256:FeatureSwitchPoller features.pyo:138 Loading feature switches with server. 2015-04-21 09:03:07,345 -0400 INFO pid=3672 -1310560256:FeatureSwitchPoller feature_switch_manager.pyo:551 Calling server to get feature switches. 2015-04-21 09:03:07,345 -0400 INFO pid=3672 -1306357760:OverlayStatusCleaner sync_client_thread.pyo:177 OverlayStatusCleaner run loop has started. 2015-04-21 09:03:07,347 -0400 INFO pid=3672 -1310560256:FeatureSwitchPoller sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-21 09:03:07,347 -0400 INFO pid=3672 -1302155264:LocalFSPoller sync_client_thread.pyo:177 LocalFSPoller run loop has started. 2015-04-21 09:03:07,348 -0400 INFO pid=3672 -1297952768:KmppClient connection_task.pyo:157 Recalculated LoginTask timeout: 16.000000 2015-04-21 09:03:07,348 -0400 INFO pid=3672 -1293750272:PushNotificationKeepaliveThread sync_client_thread.pyo:177 PushNotificationKeepaliveThread run loop has started. 2015-04-21 09:03:07,349 -0400 INFO pid=3672 -1289547776:Worker-1 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-21 09:03:07,351 -0400 INFO pid=3672 -1281142784:LocalDiskSpaceChecker sync_client_thread.pyo:177 LocalDiskSpaceChecker run loop has started. 2015-04-21 09:03:07,351 -0400 INFO pid=3672 -1285345280:LocalWatcher watcher.pyo:317 LocalWatcher Run invoked.Waiting for first pass of local scan from poller to start 2015-04-21 09:03:07,351 -0400 INFO pid=3672 -1310560256:FeatureSwitchPoller sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user default/sync_config.db 2015-04-21 09:03:07,353 -0400 INFO pid=3672 -1272737792:CloudWatcher sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-21 09:03:07,353 -0400 INFO pid=3672 -1268535296:RawEventUpgrader sync_client_thread.pyo:177 RawEventUpgrader run loop has started. 2015-04-21 09:03:07,355 -0400 INFO pid=3672 -1264332800:CrashReportUploaderThread crash report uploader thread.pyo:58 Starting CrashReportUploaderThread. 2015-04-21 09:03:07,356 -0400 INFO pid=3672 -1255927808:TelemetryStoreSizeMonitorThread sync_client_thread.pyo:177 TelemetryStoreSizeMonitorThread run loop has started. 2015-04-21 09:03:07,356 -0400 INFO pid=3672 -1260130304:Batcher sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-21 09:03:07,357 -0400 INFO pid=3672 -1289547776:Worker-1 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-21 09:03:07,357 -0400 INFO pid=3672 -1255927808:TelemetryStoreSizeMonitorThread telemetry_store monitor_thread.pyo:47 Checking Telemetry store size. 2015-04-21 09:03:07,358 -0400 INFO pid=3672 -1243320320:Worker-2 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user default/snapshot.db 2015-04-21 09:03:07,359 -0400 INFO pid=3672 -1239117824:Worker-0 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-21 09:03:07,359 -0400 INFO pid=3672 -1310560256:FeatureSwitchPoller utils.pyo:663 ADDING persistence_sqlite:8301be52f4f2484cb8d3d224e3ecl4c6/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-21 09:03:07,360 -0400 INFO pid=3672 -1234915328:TelemetryUploaderThread sync_client_thread.pyo:177 TelemetryUploaderThread run loop has started. 2015-04-21 09:03:07,360 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp drive_extension_installer.pyo:174 Uninstalling extension nknebiagdodnminbdpflhpkgfpeijdbf 2015-04-21 09:03:07,360 -0400 INFO pid=3672 -1310560256:FeatureSwitchPoller utils.pyo:663 ADDING persistence_sqlite:42f20ba91a144331a2223a4ddae96df0/Users/lgroff/Library/Application Support/Google/Drive/user default/sync_config.db 2015-04-21 09:03:07,361 -0400 INFO pid=3672 -1310560256:FeatureSwitchPoller feature_switch_manager.pyo:471 Successfully received feature switches from server. 2015-04-21 09:03:07,614 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp utils.pyo:640 Execute EFTA00502254 cleanup callback 'persistence_sqlite:5353f30beadb4518a60926b4laaec5d5/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2015-04-21 09:03:07,616 -0400 INFO pid=3672 -1289547776:Worker-1 utils.pyo:663 ADDING persistence_sqlite:3b80aff68a974065af8df9025c21a0d7/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-21 09:03:07,616 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-21 09:03:07,616 -0400 INFO pid=3672 -1272737792:CloudWatcher sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-21 09:03:07,617 -0400 INFO pid=3672 -1260130304:Batcher sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-21 09:03:07,617 -0400 INFO pid=3672 -1289547776:Worker-1 utils.pyo:663 ADDING persistence_sqlite:f3ba1929f94f415a97325ca7d8bb7b46/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-21 09:03:07,617 -0400 INFO pid=3672 -1243320320:Worker-2 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-21 09:03:07,618 -0400 INFO pid=3672 -1239117824:Worker-0 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-21 09:03:07,618 -0400 INFO pid=3672 -1289547776:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.downloads.DownloadChangeACL 2015-04-21 09:03:07,619 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp utils.pyo:640 Execute cleanup callback 'persistence_sqlite:1038f9ec8483449b92a464bac7f29be6/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2015-04-21 09:03:07,664 -0400 INFO pid=3672 -1289547776:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.downloads.DownloadCreate 2015-04-21 09:03:07,664 -0400 INFO pid=3672 -1318965248:_LaunchSyncApp sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-21 09:03:07,665 -0400 INFO pid=3672 -1260130304:Batcher utils.pyo:663 ADDING persistence_sqlite:2871c2c3d0744480a5d597f09cebdbf6/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-21 09:03:07,665 -0400 INFO pid=3672 -1272737792:CloudWatcher utils.pyo:663 ADDING persistence_sqlite:7d64a4a3955d45369bf6f787bb14843b/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-21 09:03:07,665 -0400 INFO pid=3672 -1289547776:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.downloads.DownloadDelete 2015-04-21 09:03:07,666 -0400 INFO pid=3672 -1239117824:Worker-0 utils.pyo:663 ADDING persistence_sqlite:dc04b5lcbe2c43db8a840a3a26a66080/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-21 09:03:07,666 -0400 INFO pid=3672 -1260130304:Batcher utils.pyo:663 ADDING persistence_sqlite:96e6684e71344c2c81010c9e3bbcd280/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-21 09:03:07,666 -0400 INFO pid=3672 -1272737792:CloudWatcher utils.pyo:663 ADDING persistence_sqlite:3e3c1161c8ca4bcbblff6a69861d5e9b/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-21 09:03:07,667 -0400 INFO pid=3672 -1243320320:Worker-2 utils.pyo:663 ADDING persistence_sqlite:f6ac779db41942648054ef6a560bf0le/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-21 09:03:07,667 -0400 INFO pid=3672 -1289547776:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.downloads.DownloadModify 2015-04-21 09:03:07,668 -0400 INFO pid=3672 -1239117824:Worker-0 utils.pyo:663 ADDING persistence_sqlite:deb6adffb9464e5389755068fbfd39c1/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-21 09:03:07,668 -0400 INFO pid=3672 -1272737792:CloudWatcher cloud_graph.pyo:118 EFTA00502255 Initialize persistent graph with generation 2 2015-04-21 09:03:07,668 -0400 INFO pid=3672 -1243320320:Worker-2 utils.pyo:663 ADDING persistence_sglite:243227d193344fdd85b9c24ea4f64206/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-21 09:03:07,668 -0400 INFO pid=3672 -1289547776:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.downloads.DownloadMove 2015-04-21 09:03:07,670 -0400 INFO pid=3672 -1239117824:Worker-0 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BackupCreate 2015-04-21 09:03:07,670 -0400 INFO pid=3672 -1272737792:CloudWatcher cloud_graph.pyo:1507 Current sync config generation: 2 2015-04-21 09:03:07,671 -0400 INFO pid=3672 -1243320320:Worker-2 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BackupCreate 2015-04-21 09:03:07,671 -0400 INFO pid=3672 -1289547776:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.downloads.DownloadRename 2015-04-21 09:03:07,671 -0400 INFO pid=3672 -1239117824:Worker-0 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BackupDelete 2015-04-21 09:03:07,671 -0400 INFO pid=3672 -1243320320:Worker-2 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BackupDelete 2015-04-21 09:03:07,672 -0400 INFO pid=3672 -1272737792:CloudWatcher cloud_graph.pyo:1364 Cleanup cloud graph directory '/Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph' of files 'dict_*.db" except ['dict_2.db 1 , ldict_2.db-shm., 'dict_2.db-wal'] 2015-04-21 09:03:07,685 -0400 INFO pid=3672 -1289547776:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.internals.InternalCreate 2015-04-21 09:03:07,685 -0400 INFO pid=3672 -1239117824:Worker-0 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BackupModify 2015-04-21 09:03:07,686 -0400 INFO pid=3672 -1243320320:Worker-2 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BackupModify 2015-04-21 09:03:07,686 -0400 INFO pid=3672 -1289547776:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.internals.InternalModify 2015-04-21 09:03:07,686 -0400 INFO pid=3672 -1239117824:Worker-0 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BackupMove 2015-04-21 09:03:07,686 -0400 INFO pid=3672 -1243320320:Worker-2 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BackupMove 2015-04-21 09:03:07,686 -0400 INFO pid=3672 -1289547776:Worker-1 workers.pyo:132 Waiting for batcher to initialize 2015-04-21 09:03:07,686 -0400 INFO pid=3672 -1239117824:Worker-0 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BackupRename 2015-04-21 09:03:07,687 -0400 INFO pid=3672 -1243320320:Worker-2 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BackupRename 2015-04-21 09:03:07,687 -0400 INFO pid=3672 -1289547776:Worker-1 sync_client_thread.pyo:177 Worker-1 run loop has started 2015-04-21 09:03:07,687 -0400 INFO pid=3672 -1239117824:Worker-0 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BatchUploadCreate 2015-04-21 09:03:07,687 -0400 INFO pid=3672 -1243320320:Worker-2 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BatchUploadCreate 2015-04-21 09:03:07,687 -0400 INFO pid=3672 -1239117824:Worker-0 command_factory.pyo:190 Registering worker command type: common.worker.uploads.UploadCreate 2015-04-21 09:03:07,687 -0400 INFO pid=3672 -1243320320:Worker-2 command_factory.pyo:190 Registering worker command type: common.worker.uploads.UploadCreate 2015-04-21 09:03:07,688 -0400 INFO pid=3672 -1239117824:Worker-0 command_factory.pyo:190 Registering worker command type: common.worker.uploads.UploadDelete 2015-04-21 09:03:07,688 -0400 INFO pid=3672 -1243320320:Worker-2 command_factory.pyo:190 Registering worker command type: common.worker.uploads.UploadDelete 2015-04-21 09:03:07,688 -0400 INFO pid=3672 -1239117824:Worker-0 command_factory.pyo:190 Registering worker command type: common.worker.uploads.UploadModify 2015-04-21 09:03:07,688 -0400 INFO pid=3672 -1243320320:Worker-2 command_factory.pyo:190 Registering worker command type: common.worker.uploads.UploadModify 2015-04-21 09:03:07,688 -0400 INFO pid=3672 -1239117824:Worker-0 command_factory.pyo:190 Registering worker command type: common.worker.uploads.UploaciMove EFTA00502256 2015-04-21 09:03:07,688 -0400 INFO pid=3672 -1243320320:Worker-2 command_factory.pyo:190 Registering worker command type: common.worker.uploads.UploaciMove 2015-04-21 09:03:07,688 -0400 INFO pid=3672 -1239117824:Worker-0 command_factory.pyo:190 Registering worker command type: common.worker.uploads.UploadRename 2015-04-21 09:03:07,689 -0400 INFO pid=3672 -1243320320:Worker-2 command_factory.pyo:190 Registering worker command type: common.worker.uploads.UploadRename 2015-04-21 09:03:07,689 -0400 INFO pid=3672 -1239117824:Worker-0 workers.pyo:132 Waiting for batcher to initialize 2015-04-21 09:03:07,707 -0400 INFO pid=3672 -1297952768:XmppClient xmpplib.pyo:354 Start SSL peer=64.233.171.125:5222 2015-04-21 09:03:07,723 -0400 INFO pid=3672 -1243320320:Worker-2 workers.pyo:132 Waiting for batcher to initialize 2015-04-21 09:03:07,724 -0400 INFO pid=3672 -1239117824:Worker-0 sync_client_thread.pyo:177 Worker-0 run loop has started. 2015-04-21 09:03:07,724 -0400 INFO pid=3672 -1230712832:IpcConnectionThread-/var/folders/n1/ypw25pmj06qfyyl_8xnt3kf40000gn/T/tmptnCJrd-1 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-21 09:03:07,724 -0400 INFO pid=3672 -1243320320:Worker-2 sync_client_thread.pyo:177 Worker-2 run loop has started. 2015-04-21 09:03:07,726 -0400 INFO pid=3672 -1230712832:IpcConnectionThread-/var/folders/n1/ypw25pmj06qfyyl_8xnt3kf40000gn/T/tmptnCJrd-1 sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-21 09:03:07,726 -0400 INFO pid=3672 -1230712832:IpcConnectionThread-/var/folders/n1/ypw25pmj06qfyyl_8xnt3kf40000gn/T/tmptnCJrd-1 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-21 09:03:07,727 -0400 INFO pid=3672 -1230712832:IpcConnectionThread-/var/folders/n1/ypw25pmj06qfyyl_8xnt3kf40000gn/T/tmptnCJrd-1 sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-21 09:03:07,727 -0400 ERROR pid=3672 -1230712832:IpcConnectionThread-/var/folders/n1/ypw25pmj06qfyyl_8xnt3kf40000gn/T/tmptnCJrd-1 server.pyo:191 Got an Ipc client socket exception. Hanging up. Traceback (most recent call last): File "common/ipc/server.pyo", line 186, in Run File "common/ipc/router.pyo", line 398, in ProcessRequest File "common/ipc/router.pyo", line 430, in _SendResponse File "common/ipc/router.pyo", line 242, in SendBytes File "common/ipc/ipc_socket_base.pyo", line 200, in SendAll File "socket.pyo", line 224, in meth error: (Errno 32] Broken pipe 2015-04-21 09:03:07,727 -0400 INFO pid=3672 -1230712832:IpcConnectionThread-/var/folders/n1/ypw25pmj06qfyyl_8xnt3kf40000gn/T/tmptnCJrd-1 server.pyo:198 Closing Ipc socket connection with client. 2015-04-21 09:03:07,952 -0400 INFO pid=3672 -1272737792:CloudWatcher utils.pyo:663 ADDING DBConnectionManagerCleanupCallback 2015-04-21 09:03:07,952 -0400 INFO pid=3672 -1272737792: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-04-21 09:03:08,039 -0400 INFO pid=3672 -1272737792:CloudWatcher cloud_graph.pyo:888 Reuse CloudDiskDict database: /Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph/dict_2.db 2015-04-21 09:03:08,124 -0400 INFO pid=3672 -1272737792: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-04-21 09:03:08,124 -0400 INFO pid=3672 -1272737792:CloudWatcher cloud_graph.pyo:256 Building gdoc docids cache. 2015-04-21 09:03:08,264 -0400 INFO pid=3672 -1318965248:IpcConnectionThread-/var/folders/n1/ypw25pmj06qfyyl_8xnt3kf40000gn/T/tmptnCJrd-2 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-21 09:03:08,265 -0400 INFO pid=3672 -1318965248:IpcConnectionThread-/var/folders/n1/ypw25pmj06qfyyl_8xnt3kf40000gn/T/tmptnCJrd-2 EFTA00502257 sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-21 09:03:08,266 -0400 INFO pid=3672 -1318965248:IpcConnectionThread-/var/folders/n1/ypw25pmj06qfyyl_8xnt3kf40000gn/T/tmptnCJrd-2 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-21 09:03:08,266 -0400 INFO pid=3672 -1318965248:IpcConnectionThread-/var/folders/n1/ypw25pmj06qfyyl_8xnt3kf40000gn/T/tmptnCJrd-2 sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-21 09:03:08,268 -0400 INFO pid=3672 -1318965248:IpcConnectionThread-/var/folders/n1/ypw25pmj06qfyyl_8xnt3kf40000gn/T/tmptnCJrd-2 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-21 09:03:08,268 -0400 INFO pid=3672 -1318965248:IpcConnectionThread-/var/folders/n1/ypw25pmj06qfyyl_8xnt3kf40000gn/T/tmptnCJrd-2 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-21 09:03:08,269 -0400 INFO pid=3672 -1318965248:IpcConnectionThread-/var/folders/n1/ypw25pmj06qfyyl_8xnt3kf40000gn/T/tmptnCJrd-2 utils.pyo:663 ADDING persistence_sqlite:beddb61032b04844ad384139a19274d2/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-21 09:03:08,269 -0400 INFO pid=3672 -1318965248:IpcConnectionThread-/var/folders/n1/ypw25pmj06qfyyl_8xnt3kf40000gn/T/tmptnCJrd-2 utils.pyo:663 ADDING persistence_sqlite:63057af8cbd64ea89c1d8adbdf9f277c/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-21 09:03:08,299 -0400 INFO pid=3672 -1297952768:XmppClient xmpplib.pyo:358 Finish SSL 2015-04-21 09:03:08,535 -0400 INFO pid=3672 -1297952768:XmppClient task.pyo:257 Task <common.push.xmpp.login_task.LoginTask instance at 0xb8eb918> complete. 2015-04-21 09:03:08,536 -0400 WARNING pid=3672 -1297952768:XmppClient login_task.pyo:117 Clearing connection for LoginTask: <common.push.xmpp.login_task.LoginTask instance at 0xb8eb918> 2015-04-21 09:03:08,536 -0400 INFO pid=3672 -1297952768:XmppClient push_xmpp_client.pyo:304 Connected to talk.google.com:5222 2015-04-21 09:03:08,537 -0400 WARNING pid=3672 -1230712832:Thread-4 notification_bridge.pyo:121 XMPP detected connect. 2015-04-21 09:03:08,537 -0400 INFO pid=3672 -1230712832:Thread-4 xmpp_network_channel.pyo:148 Sending push channel subscription request 2015-04-21 09:03:08,584 -0400 INFO pid=3672 -1297952768:XmppClient xmpp_network_channel.pyo:166 Received subscription response from Push for XMPPBot 2015-04-21 09:03:08,584 -0400 INFO pid=3672 -1230712832:Thread-4 notification_bridge.pyo:137 Starting TICL (client version 3.20130807)... 2015-04-21 09:03:08,585 -0400 INFO pid=3672 -1230712832:Thread-4 xmpp_network_channel.pyo:64 XmppNetworkChannel.InformNetworkStatus: CONNECTED 2015-04-21 09:03:08,678 -0400 INFO pid=3672 -1251725312:ListenerScheduler invalidation_listener.pyo:185 InvalidationListener.ReissueRegistrations(prefix=, prefix_len=0) 2015-04-21 09:03:09,161 -0400 INFO pid=3672 -1272737792:CloudWatcher cloud_graph.pyo:171 Reuse CloudDiskDict CloudDiskDict(max_change_stamp=59977, app_version=1.20.8672.3137, only_rooted_items=False, ()) 2015-04-21 09:03:09,162 -0400 INFO pid=3672 -1272737792:CloudWatcher cloud_watcher.pyo:797 Performing full snapshot diff. 2015-04-21 09:03:09,162 -0400 INFO pid=3672 -1272737792:CloudWatcher cloud_watcher.pyo:986 Getting cloud resources via changelog feed from 59978 2015-04-21 09:03:09,162 -0400 INFO pid=3672 -1272737792:CloudWatcher docs_client.pyo:741 Requesting Doc List: /feeds/default/private/changes?start-index=59978&showroot=true&include- shared=true&reason=startup 2015-04-21 09:03:11,439 -0400 INFO pid=3672 -1251725312:ListenerScheduler invalidation_listener.pyo:82 InvalidationListener.Invalidation(1014:CHANGELOG, wapi_version=60028) 2015-04-21 09:03:11,468 -0400 INFO pid=3672 -1251725312:ListenerScheduler invalidation_listener.pyo:125 InvalidationListener.InformRegistrationStatus(1014:CHANGELOG, EFTA00502258 REGISTERED) 2015-04-21 09:03:11,468 -0400 WARNING pid=3672 -1251725312:ListenerScheduler notification_bridge.pyo:192 Invalidation status callback called with connected=True 2015-04-21 09:03:11,468 -0400 INFO pid=3672 -1251725312:ListenerScheduler pause_manager.pyo:171 Removing pause reasons (OFFLINE) from (). Paused: False 2015-04-21 09:03:12,256 -0400 INFO pid=3672 -1293750272:PushNotificationKeepaliveThread push_notification_keepalive_thread.pyo:49 Sent push keepalive message at: Tue Apr 21 09:03:12 2015 2015-04-21 09:03:13,569 -0400 INFO pid=3672 -1272737792:CloudWatcher docs_client.pyo:1131 Feed has 1 entries 2015-04-21 09:03:13,569 -0400 INFO pid=3672 -1272737792:CloudWatcher docs_client.pyo:1138 End of feed, no next URI 2015-04-21 09:03:13,572 -0400 INFO pid=3672 -1272737792:CloudWatcher cloud_watcher.pyo:766 Got 1 cloud entries 2015-04-21 09:03:13,874 -0400 INFO pid=3672 -1272737792:CloudWatcher cloud_watcher.pyo:783 582 nodes in the cloud graph 2015-04-21 09:03:13,905 -0400 INFO pid=3672 -1272737792:CloudWatcher cloud_snapshot_diff_helper.pyo:84 Prepare for Pruning graph (total of 582 entries). sync gdocs=True, sync folders=None, excluded folders=frozenset(()) 2015-04-21 09:03:13,905 -0400 INFO pid=3672 -1272737792:CloudWatcher cloud_snapshot_diff_helper.pyo:85 Start Prune Graph (total of 582 entries) 2015-04-21 09:03:13,911 -0400 INFO pid=3672 -1272737792:CloudWatcher cloud_snapshot_diff_helper.pyo:168 Pruning detected 577 items to keep 2015-04-21 09:03:13,912 -0400 INFO pid=3672 -1272737792:CloudWatcher cloud_snapshot_diff_helper.pyo:175 577 entries in graph after pruning 2015-04-21 09:03:13,912 -0400 INFO pid=3672 -1272737792:CloudWatcher cloud_snapshot_diff_helper.pyo:93 Compare to snaphost 2015-04-21 09:03:13,912 -0400 INFO pid=3672 -1272737792:CloudWatcher cloud_snapshot_diff_helper.pyo:751 Starting cloud graph vs snapshot diff with selective sync set: None 2015-04-21 09:03:13,913 -0400 INFO pid=3672 -1272737792:CloudWatcher cloud_snapshot_diff_helper.pyo:753 Prepopulating DOC_ID-to-inodes cache 2015-04-21 09:03:13,915 -0400 INFO pid=3672 -1272737792:CloudWatcher cloud_snapshot_diff_helper.pyo:757 First pass detecting moved entries 2015-04-21 09:03:14,085 -0400 INFO pid=3672 -1272737792:CloudWatcher cloud_snapshot_diff_helper.pyo:772 Second pass detecting creations and modification entries 2015-04-21 09:03:14,353 -0400 INFO pid=3672 -1272737792:CloudWatcher cloud_snapshot_diff_helper.pyo:799 Processed 576 entries in total. Number of events detected: (_FSChangeActionType(Action.MODIFY): 1) 2015-04-21 09:03:14,403 -0400 INFO pid=3672 -1272737792:CloudWatcher cloud_watcher.pyo:840 Done with full snapshot diff. 2015-04-21 09:03:14,404 -0400 INFO pid=3672 -1272737792:CloudWatcher cloud_watcher.pyo:896 CloudWatcher generated 1 events 2015-04-21 09:03:14,404 -0400 INFO pid=3672 -1272737792:CloudWatcher cloud_watcher.pyo:902 CloudWatcher generated FSChange(Direction.DOWNLOAD, Action.MODIFY, ino=13540355, name=2015 April.docx, rid=0BxNN8iEiWGvgaXhaVzNUdGt6ckU, parent_ino=529993, is_folder=False, modified=1429607791, checksum=d85d31a29190896fb488dff005429e15, size=109767, backup=Backup.N0_BACKUP_CONTENT, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB) 2015-04-21 09:03:14,404 -0400 INFO pid=3672 -1272737792:CloudWatcher aggregator.pyo:114 -- > Received event None FSChange(Direction.DOWNLOAD, Action.MODIFY, ino=13540355, name=2015 April.docx, rid=0BxNN8iEiWGvgaXhaVzNUdGt6ckU, parent_ino=529993, is_folder=False, modified=1429607791, checksum=d85d31a29190896fb488dff005429e15, size=109767, backup=Backup.N0_BACKUP_CONTENT, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB) 2015-04-21 09:03:14,404 -0400 INFO pid=3672 -1272737792:CloudWatcher downloads.pyo:127 ProcessDownloadModify (FSChange(Direction.DOWNLOAD, Action.MODIFY, ino=13540355, name=2015 April.docx, rid=0124ENN8iEiWGvgaXhaVzNUdGt6ckU, parent_ino=529993, is_folder=False, modified=1429607791, checksum=d85d31a29190896fb488dff005429e15, size=109767, backup=Backup.N0_BACKUP_CONTENT, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB)) 2015-04-21 09:03:14,405 -0400 INFO pid=3672 -1272737792:CloudWatcher change_buffer.pyo:1028 Adding change to change buffer: FSChange(Direction.D0WNLOAD, Action.MODIFY, ino=13540355, name=2015 April.docx, rid=0BxNN8iEiWGvgaXhaVzNUdGt6ckU, parent_ino=529993, is_folder=False, modified=1429607791, EFTA00502259 checksum=d85d31a29190896fb488dff005429e15, size=109767, backup=Backup.NO_BACKUP_CONTENT, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB, hash=2085891238) 2015-04-21 09:03:14,498 -0400 INFO pid=3672 -1272737792:CloudWatcher cloud_watcher.pyo:671 Change ignored. observed: 60028, retrieved: 60028 2015-04-21 09:03:14,907 -0400 INFO pid=3672 -1260130304:Batcher batcher.pyo:240 Ready changes are pending. 2015-04-21 09:03:14,910 -0400 INFO pid=3672 -1289547776:Worker-1 workers.pyo:188 Worker starting on [ImmutableChange(Direction.DOWNLOAD, Action.MODIFY, ino=13540355, name=2015 April.docx, rid=0BxNN8iEiWGvgaXhaVzNUdGt6ckU, parent_ino=529993, is_folder=False, modified=1429607791, checksum=d85d31a29190896fb488dff005429e15, size=109767, backup=Backup.N0_BACKUP_CONTENT, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB, hash=2085891238, _constructor_called=True)] 2015-04-21 09:03:14,929 -0400 INFO pid=3672 -1289547776:Worker-1 download.pyo:112 Downloading https://googledrive.com/p/host/0WENN8iEiWGvgaXhaVzNUdGt6ckU to /var/folders/nl/ypw25pmj06gfyyl_8xnt3kf40000gn/T/tmpucDar4 2015-04-21 09:03:15,404 -0400 INFO pid=3672 -1302155264:LocalFSPoller poller.pyo:156 Cloud scan complete. Preparing to start LocalFSPoller 2015-04-21 09:03:15,908 -0400 INFO pid=3672 -1260130304:Batcher batcher.pyo:257 There are no blacklisted requests. 2015-04-21 09:03:15,919 -0400 INFO pid=3672 -1318965248:IpcConnectionThread-/var/folders/n1/ypw25pmj06gfyyl_8xnt3kf40000gn/T/tmptneJrd-2 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-21 09:03:15,920 -0400 INFO pid=3672 -1318965248:IpcConnectionThread-/var/folders/n1/ypw25pmj06gfyyl_8xnt3kf40000gn/T/tmptneJrd-2 sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-21 09:03:15,921 -0400 INFO pid=3672 -1318965248:IpcConnectionThread-/var/folders/n1/ypw25pmj06gfyyl_8xnt3kf40000gn/T/tmptneJrd-2 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-21 09:03:15,921 -0400 INFO pid=3672 -1318965248:IpcConnectionThread-/var/folders/n1/ypw25pmj06gfyyl_8xnt3kf40000gn/T/tmptneJrd-2 sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Applicatio