EFTA00502604.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-21 17:00:24,081 -0400 INFO pid=243 -1603214936:MainThread thread manager.pyo:77 Stop was called 2015-04-21 17:00:24,082 -0400 WARNING pid=243 -1603214936:MainThread task.pyo:245 DriveXmppTask-0xba1fa08 aborting: abort 2015-04-21 17:00:24,082 -0400 INFO pid=243 -1603214936:MainThread task.pyo:257 Task <common.push.push_xmpp_client.DriveXmppTask instance at 0xbalfa08> complete. 2015-04-21 17:00:24,082 -0400 INFO pid=243 -1306890240:TelemetryStoreSizeMonitorThread sync_client_thread.pyo:203 TelemetryStoreSizeMonitorThread run loop has terminated. 2015-04-21 17:00:24,083 -0400 INFO pid=243 -1603214936:MainThread xmpplib.pyo:114 Close called with 'why' reason = Client initiated close 2015-04-21 17:00:24,083 -0400 INFO pid=243 -1311092736:FeatureSwitchPoller sync_client_thread.pyo:203 FeatureSwitchPoller run loop has terminated. 2015-04-21 17:00:24,083 -0400 INFO pid=243 -1311092736:FeatureSwitchPoller utils.pyo:640 Execute cleanup callback 'persistence_sqlite:513557193e874c6ba7d1c02259fc4f71/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2015-04-21 17:00:24,083 -0400 INFO pid=243 -1311092736:FeatureSwitchPoller sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-21 17:00:24,084 -0400 INFO pid=243 -1311092736:FeatureSwitchPoller utils.pyo:640 Execute cleanup callback 'persistence_sqlite:6673b2fb35634ab482d46dbe6f426014/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2015-04-21 17:00:24,084 -0400 INFO pid=243 -1311092736:FeatureSwitchPoller sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-22 09:16:05,954 -0400 INFO pid=1383 -1603214936:MainThread logging_config.pyo:249 OS: Darwin/10.9.5 2015-04-22 09:16:06,078 -0400 INFO pid=1383 -1603214936:MainThread logging_config.pyo:252 Google Drive (build 1.20.8672.3137) 2015-04-22 09:16:06,078 -0400 INFO pid=1383 -1603214936:MainThread logging_config.pyo:253 SSL: OpenSSL 0.9.8zc 15 Oct 2014 2015-04-22 09:16:06,191 -0400 INFO pid=1383 -1603214936:MainThread GSyncAppDelegate.pyo:216 Application did finish launching. 2015-04-22 09:16:08,534 -0400 INFO pid=1383 -1336307712:Thread-1 service.pyo:71 Creating user scope service instance of '<class 'common.service.user.User'>' 2015-04-22 09:16:08,534 -0400 INFO pid=1383 -1336307712:Thread-1 service.pyo:71 Creating user scope service instance of '<class 'common.local.roots.RootManager'>' 2015-04-22 09:16:08,535 -0400 INFO pid=1383 -1336307712:Thread-1 service.pyo:71 Creating user scope service instance of '<class 'common.pause_manager.Service'>' 2015-04-22 09:16:08,535 -0400 INFO pid=1383 -1336307712:Thread-1 sync_app.pyo:301 Getting TokenStorage 2015-04-22 09:16:08,535 -0400 INFO pid=1383 -1336307712:Thread-1 sync_app.pyo:303 Got token storage: <token_storage.TokenStorage object at 0x80d4360> 2015-04-22 09:16:08,535 -0400 INFO pid=1383 -1336307712:Thread-1 service.pyo:71 Creating user scope service instance of '<class 'common.features.AppFeatures'>' 2015-04-22 09:16:08,536 -0400 INFO pid=1383 -1336307712:Thread-1 service.pyo:71 Creating user scope service instance of '<class 'common.cert.Manager'>' 2015-04-22 09:16:08,551 -0400 INFO pid=1383 -1336307712:Thread-1 service.pyo:156 EFTA00502604 Creating singleton service instance of '<class 'common.sync_http_client.BandwidthThrottler'> 1 2015-04-22 09:16:08,552 -0400 INFO pid=1383 -1336307712:Thread-1 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-22 09:16:08,553 -0400 INFO pid=1383 -1336307712:Thread-1 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-22 09:16:08,553 -0400 INFO pid=1383 -1336307712:Thread-1 utils.pyo:663 ADDING persistence_sqlite:38d8042073a2475a94f023c15209c3d8/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-22 09:16:08,554 -0400 INFO pid=1383 -1336307712:Thread-1 utils.pyo:663 ADDING persistence_sqlite:b2910eel9d9543a1b5fc064f842d584f/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-22 09:16:08,904 -0400 INFO pid=1383 -1336307712:Thread-1 sync_app.pyo:477 BandwidthThrottler configured via sync_config 2015-04-22 09:16:08,904 -0400 INFO pid=1383 -1336307712:Thread-1 sync_http_client.pyo:91 BandwidthThrottler.SetRxBytesPerSecond 0 2015-04-22 09:16:08,904 -0400 INFO pid=1383 -1336307712:Thread-1 sync_http_client.pyo:100 BandwidthThrottler.SetTxBytesPerSecond 0 2015-04-22 09:16:08,904 -0400 INFO pid=1383 -1336307712:Thread-1 sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-22 09:16:08,905 -0400 INFO pid=1383 -1336307712:Thread-1 sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-22 09:16:08,908 -0400 INFO pid=1383 -1336307712:Thread-1 utils.pyo:640 Execute cleanup callback 'persistence_sqlite:b2910eel9d9543a1b5fc064f842d584f/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2015-04-22 09:16:08,909 -0400 INFO pid=1383 -1336307712:Thread-1 utils.pyo:640 Execute cleanup callback 'persistence_sqlite:38d8042073a2475a94f023c15209c3d8/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2015-04-22 09:16:08,915 -0400 INFO pid=1383 -1332105216:Thread-2 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-22 09:16:08,916 -0400 INFO pid=1383 -1332105216:Thread-2 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-22 09:16:08,916 -0400 INFO pid=1383 -1332105216:Thread-2 utils.pyo:663 ADDING persistence_sqlite:aa7362d362bf426a86f4f40c9db93407/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-22 09:16:08,917 -0400 INFO pid=1383 -1332105216:Thread-2 utils.pyo:663 ADDING persistence_sqlite:9717ca34c21a40ef93b9e833ff3e72e8/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-22 09:16:08,917 -0400 INFO pid=1383 -1332105216:Thread-2 feature_switch_manager.pyo:479 Successfully read persisted feature switches. 2015-04-22 09:16:09,407 -0400 INFO pid=1383 -1603214936:MainThread image_resources.py:246 Loading image resources/images/ic_drawer_24.png 2015-04-22 09:16:09,594 -0400 INFO pid=1383 -1603214936:MainThread image_resources.py:246 Loading image resources/images/menu_drive-logo.png 2015-04-22 09:16:09,639 -0400 INFO pid=1383 -1603214936:MainThread image_resources.py:246 Loading image resources/images/menu_google-logo-gray.png 2015-04-22 09:16:09,642 -0400 INFO pid=1383 -1603214936:MainThread image_resources.py:246 Loading image resources/images/ic_folder_mydrive_24.png 2015-04-22 09:16:09,698 -0400 INFO pid=1383 -1603214936:MainThread image_resources.py:246 Loading image resources/images/ic_web_24.png 2015-04-22 09:16:09,909 -0400 INFO pid=1383 -1603214936:MainThread image_resources.py:246 Loading image resources/images/menu_backups.png 2015-04-22 09:16:09,979 -0400 INFO pid=1383 -1603214936:MainThread image_resources.py:246 Loading image resources/images/ic_sync_problem_24.png 2015-04-22 09:16:10,124 -0400 INFO pid=1383 -1603214936:MainThread image_resources.py:246 Loading image resources/images/ic_done_24.png 2015-04-22 09:16:10,281 -0400 INFO pid=1383 -1603214936:MainThread service.pyo:209 Creating paramed singleton service instance of '<class 'common.file_lock.FileLock'>' EFTA00502605 2015-04-22 09:16:10,282 -0400 INFO pid=1383 -1603214936:MainThread service.pyo:209 Creating paramed singleton service instance of '<class •common.file_lock.FileLockJ> 1 2015-04-22 09:16:10,594 -0400 INFO pid=1383 -1323700224:ThreadMonitor thread_manager.pyo:259 Waiting on exit condition. Wake up after 3600s. 2015-04-22 09:16:10,595 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-22 09:16:10,596 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-22 09:16:10,596 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp utils.pyo:663 ADDING persistence_sqlite:a68087e9ceel4bc1b0c9317412f52282/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-22 09:16:10,596 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp utils.pyo:663 ADDING persistence_sqlite:832ea2a33a7a497fbef599a4f736dfd0/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-22 09:16:10,694 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp user.pyo:64 Initializing User instance with new credentials. None 2015-04-22 09:16:10,695 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp docs_client_factory.pyo:99 Creating a new Wapi DocsClient 2015-04-22 09:16:10,710 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp service.pyo:71 Creating user scope service instance of '<class 'common.token_bucket.CloudBucketManager'>' 2015-04-22 09:16:10,722 -0400 ERROR pid=1383 -1327902720:IpcServerThread ipc_socket_base.pyo:249 Failed to bind to socket /private/var/folders/n1/ypw25pmj06qfyyl_8xntakf40000gn/T/tmpNI1TLP (attempt 1 of 3) Traceback (most recent call last): File "common/ipc/ipc_socket_base.pyo", line 242, in Bind File "common/ipc/ipc_socket_base.pyo", line 176, in Bind File "socket.pyo", line 224, in meth error: [Erma 48] Address already in use 2015-04-22 09:16:10,722 -0400 ERROR pid=1383 -1327902720:IpcServerThread ipc_socket_base.pyo:249 Failed to bind to socket /private/var/folders/n1/ypw25pmj06qfyyl_8xntakf40000gn/T/tmpNI1TLP (attempt 2 of 3) Traceback (most recent call last): File "common/ipc/ipc_socket_base.pyo", line 242, in Bind File "common/ipc/ipc_socket_base.pyo", line 176, in Bind File "socket.pyo", line 224, in meth error: [Erma 48] Address already in use 2015-04-22 09:16:10,748 -0400 WARNING pid=1383 -1327902720:IpcServerThread server.pyo:144 Socket (/Users/lgroff/Library/Application Support/Google/Drive/GoogleDrivelpcPipe) already in use, deleting it. 2015-04-22 09:16:11,211 -0400 INFO pid=1383 -1332105216:Thread-2 finder_injector.pyo:426 Local Google Drive path not yet available, skip loading overlays 2015-04-22 09:16:11,214 -0400 INFO pid=1383 -1315295232:Finderlnjector sync_client_thread.pyo:177 Finderlnjector run loop has started. 2015-04-22 09:16:11,215 -0400 INFO pid=1383 -1332105216:Thread-2 utils.pyo:640 Execute cleanup callback 'persistence_sqlite:9717ca34c21a40ef93b9e833ff3e72e8/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2015-04-22 09:16:11,215 -0400 INFO pid=1383 -1332105216:Thread-2 sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-22 09:16:11,215 -0400 INFO pid=1383 -1332105216:Thread-2 utils.pyo:640 Execute cleanup callback 'persistence_sqlite:aa7362d362bf426a86f4f40c9db93407/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2015-04-22 09:16:11,215 -0400 INFO pid=1383 -1332105216:Thread-2 sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-22 09:16:20,297 -0400 INFO pid=1383 -1319497728: LaunchSyncApp user.pyo:64 Initializing User instance with new credentials. 2015-04-22 09:16:20,298 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp features.pyo:109 Loads feature switches. 2015-04-22 09:16:20,298 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp features.pyo:138 Loading feature switches with server. EFTA00502606 2015-04-22 09:16:20,298 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp feature_switch_manager.pyo:551 Calling server to get feature switches. 2015-04-22 09:16:20,299 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp feature_switch_manager.pyo:471 Successfully received feature switches from server. 2015-04-22 09:16:20,299 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp sync_app.pyo:1153 Feature Switches: FeatureSwitchSettingsf 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 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='prod', ghost all files=False, help_ur1=' desktop_home', log_backup_count=0, log_size_mb=256, logging_level='info', max_batch_upload_files=30, new_document_ur1= 1 new_presentation_ur1=' new_spreadsheet_ur1= 1 numworkers=3, only_rooted items in cloud graph=False, open_ur1= 1 (doc_id)', overlays_enabled_finder_versions=[ 1 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=' subapp=10&shareProtocolVersion=2&theme=2&command=settings&shareUiType=default&authuser=0&clie nt=desktop', show_confirmation_dialog_on_delete=True, telemetry_enabled=True, telemetry_upload interval secs=1800, telemetry_ur1=' token_bucket_read_qps=10, token_bucket_write_qps=3,) 2015-04-22 09:16:20,300 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp logging_config.pyo:249 OS: Darwin/10.9.5 2015-04-22 09:16:20,488 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp logging_config.pyo:252 Google Drive (build 1.20.8672.3137) 2015-04-22 09:16:20,488 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp logging_config.pyo:253 SSL: OpenSSL 0.9.8zc 15 Oct 2014 EFTA00502607 2015-04-22 09:16:20,488 -0400 INFO pid=1383 -1319497728:_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-04-22 09:16:20,491 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp service.pyo:lll Creating user scope service subclass of '<class 'common.push.notifier.Notifier'>' 2015-04-22 09:16:20,491 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp service.pyo:113 Actual service created: <common.push.notifier.PushXMPPNotifier object at 0x8d89950> 2015-04-22 09:16:21,281 -0400 INFO pid=1383 -1603214936:MainThread service.pyo:71 Creating user scope service instance of '<class 'common.auto_backup.AutoBackup'>' 2015-04-22 09:16:21,281 -0400 INFO pid=1383 -1603214936:MainThread sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-22 09:16:21,282 -0400 INFO pid=1383 -1603214936:MainThread sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-22 09:16:21,282 -0400 INFO pid=1383 -1603214936:MainThread sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-22 09:16:21,283 -0400 INFO pid=1383 -1603214936:MainThread sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-22 09:16:21,283 -0400 INFO pid=1383 -1603214936:MainThread service.pyo:71 Creating user scope service instance of '<class 'common.telemetry.telemetry_manager.TelemetryManager'> 1 2015-04-22 09:16:25,204 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp image_resources.py:222 Loading custom image resources/images/folder-mac.icns with function _CreateNSImage 2015-04-22 09:16:25,843 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.downloads.DownloadCreate 2015-04-22 09:16:25,843 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.downloads.DownloadModify 2015-04-22 09:16:25,844 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadCreate 2015-04-22 09:16:25,844 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadDelete 2015-04-22 09:16:25,844 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp rulefactory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadModify 2015-04-22 09:16:25,844 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadMove 2015-04-22 09:16:25,844 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadMoveFrom 2015-04-22 09:16:25,844 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp rule_factory.pyo:159 Registering aggregation rule type: common.aggregator_rules.uploads.UploadMoveTo 2015-04-22 09:16:25,845 -0400 INFO pid=1383 -1335775232:FilewatcherMonitor filewatcher.pyo:68 filewatcher_ext initialized 2015-04-22 09:16:25,846 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp docs_client_factory.pyo:99 Creating a new Wapi DocsClient 2015-04-22 09:16:25,847 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp docs_client_factory.pyo:99 Creating a new Wapi DocsClient 2015-04-22 09:16:25,847 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp docs_client_factory.pyo:99 Creating a new Wapi DocsClient 2015-04-22 09:16:25,848 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp docs_client_factory.pyo:99 Creating a new Wapi DocsClient 2015-04-22 09:16:25,848 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp docs_client_factory.pyo:99 Creating a new Wapi DocsClient EFTA00502608 2015-04-22 09:16:25,849 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp docs_client_factory.pyo:99 Creating a new Wapi DocsClient 2015-04-22 09:16:25,849 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp docs_client_factory.pyo:99 Creating a new Wapi DocsClient 2015-04-22 09:16:25,850 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp service.pyo:280 Remove instance for service '<class 'common.push.notifier.Notifier'>' 2015-04-22 09:16:25,850 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp service.pyo:111 Creating user scope service subclass of '<class 'common.push.notifier.Notifier'>' 2015-04-22 09:16:25,850 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp service.pyo:113 Actual service created: <common.push.notifier.PushXMPPNotifier object at 0x8d89950> 2015-04-22 09:16:25,850 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp notifier.pyo:167 Configure storage: <common.sync_config_sqlite.SyncConfigSQLite object at 0x8d89670> 2015-04-22 09:16:25,905 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp notifier.pyo:213 Creating client thread 2015-04-22 09:16:25,905 -0400 INFO pid=1383 -1319497728: LaunchSyncApp push_xmpp_client.pyo:225 Using talk server: 2015-04-22 09:16:25,905 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp notifier.pyo:222 Creating notification bridge 2015-04-22 09:16:26,066 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp docs_client_factory.pyo:99 Creating a new Wapi DocsClient 2015-04-22 09:16:26,069 -0400 INFO pid=1383 -1311092736:TelemetryStoreSizeMonitorThread sync_client_thread.pyo:177 TelemetryStoreSizeMonitorThread run loop has started. 2015-04-22 09:16:26,070 -0400 INFO pid=1383 -1306890240:XmppClient connection_task.pyo:157 Recalculated LoginTask timeout: 16.000000 2015-04-22 09:16:26,070 -0400 INFO pid=1383 -1311092736:TelemetryStoreSizeMonitorThread telemetry_store monitor_thread.pyo:47 Checking Telemetry store size. 2015-04-22 09:16:26,070 -0400 INFO pid=1383 -1302687744:OverlayStatusCleaner sync_client_thread.pyo:177 OverlayStatusCleaner run loop has started. 2015-04-22 09:16:26,071 -0400 INFO pid=1383 -1298485248:PushNotificationKeepaliveThread sync_client_thread.pyo:177 PushNotificationKeepaliveThread run loop has started. 2015-04-22 09:16:26,072 -0400 INFO pid=1383 -1294282752:Worker-1 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-22 09:16:26,072 -0400 INFO pid=1383 -1290080256:LocalWatcher watcher.pyo:317 LocalWatcher Run invoked.Waiting for first pass of local scan from poller to start 2015-04-22 09:16:26,073 -0400 INFO pid=1383 -1285877760:LocalDiskSpaceChecker sync_client_thread.pyo:177 LocalDiskSpaceChecker run loop has started. 2015-04-22 09:16:26,074 -0400 INFO pid=1383 -1281675264:RawEventUpgrader sync_client_thread.pyo:177 RawEventUpgrader run loop has started. 2015-04-22 09:16:26,075 -0400 INFO pid=1383 -1277472768:CloudWatcher sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user default/snapshot.db 2015-04-22 09:16:26,075 -0400 INFO pid=1383 -1273270272:LocalFSPoller sync_client_thread.pyo:177 LocalFSPoller run loop has started. 2015-04-22 09:16:26,076 -0400 INFO pid=1383 -1264865280:CrashReportUploaderThread crash report uploader thread.pyo:58 Starting CrashReportUploaderThread. 2015-04-22 09:16:26,078 -0400 INFO pid=1383 -1256460288:Batcher sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-22 09:16:26,079 -0400 INFO pid=1383 -1252257792:Worker-2 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-22 09:16:26,079 -0400 INFO pid=1383 -1239650304:TelemetryUploaderThread sync_client_thread.pyo:177 TelemetryUploaderThread run loop has started. 2015-04-22 09:16:26,079 -0400 INFO pid=1383 -1235447808:FeatureSwitchPoller sync_client_thread.pyo:177 FeatureSwitchPoller run loop has started. 2015-04-22 09:16:26,079 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp drive_extension_installer.pyo:174 Uninstalling extension nknebiagdodnminbdpflhpkgfpeijdbf 2015-04-22 09:16:26,079 -0400 INFO pid=1383 -1243852800:Worker-0 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-22 09:16:26,093 -0400 INFO pid=1383 -1235447808:FeatureSwitchPoller features.pyo:138 Loading feature switches with server. 2015-04-22 09:16:26,251 -0400 INFO pid=1383 -1235447808:FeatureSwitchPoller feature_switch_manager.pyo:551 Calling server to get feature switches. EFTA00502609 2015-04-22 09:16:26,253 -0400 INFO pid=1383 -1235447808:FeatureSwitchPoller sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-22 09:16:26,253 -0400 INFO pid=1383 -1294282752:Worker-1 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-22 09:16:26,253 -0400 INFO pid=1383 -1252257792:Worker-2 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-22 09:16:26,254 -0400 INFO pid=1383 -1277472768:CloudWatcher sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-22 09:16:26,254 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp utils.pyo:640 Execute cleanup callback 'persistence_sqlite:a68087e9ceel4bc1b0c9317412f52282/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2015-04-22 09:16:26,254 -0400 INFO pid=1383 -1243852800:Worker-0 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-22 09:16:26,255 -0400 INFO pid=1383 -1256460288:Batcher sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-22 09:16:26,255 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-22 09:16:26,258 -0400 INFO pid=1383 -1294282752:Worker-1 utils.pyo:663 ADDING persistence_sqlite:0238cf8b33684f7ca1b3727e32577af0/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-22 09:16:26,258 -0400 INFO pid=1383 -1294282752:Worker-1 utils.pyo:663 ADDING persistence_sqlite:ae60267730384a3cb062be847e54517f/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-22 09:16:26,258 -0400 INFO pid=1383 -1294282752:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.downloads.DownloadChangeACL 2015-04-22 09:16:26,259 -0400 INFO pid=1383 -1294282752:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.downloads.DownloadCreate 2015-04-22 09:16:26,259 -0400 INFO pid=1383 -1294282752:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.downloads.DownloadDelete 2015-04-22 09:16:26,259 -0400 INFO pid=1383 -1294282752:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.downloads.DownloadModify 2015-04-22 09:16:26,259 -0400 INFO pid=1383 -1252257792:Worker-2 utils.pyo:663 ADDING persistence_sqlite:42996dc131f74b2b9f097bcc227aac8c/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-22 09:16:26,260 -0400 INFO pid=1383 -1277472768:CloudWatcher utils.pyo:663 ADDING persistence_sqlite:03bed0c9f0734289925abdf674eb8be4/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-22 09:16:26,260 -0400 INFO pid=1383 -1243852800:Worker-0 utils.pyo:663 ADDING persistence_sqlite:d171ebcdf2b849f2bf151blbc7df35ae/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-22 09:16:26,262 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp utils.pyo:640 Execute cleanup callback 'persistence_sqlite:832ea2a33a7a497fbef599a4f736dfd0/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2015-04-22 09:16:26,262 -0400 INFO pid=1383 -1235447808:FeatureSwitchPoller sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-22 09:16:26,267 -0400 INFO pid=1383 -1294282752:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.downloads.DownloadMove 2015-04-22 09:16:26,267 -0400 INFO pid=1383 -1256460288:Batcher utils.pyo:663 ADDING persistence_sqlite:d714b51c66da46258bc7382d270ed24a/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-22 09:16:26,268 -0400 INFO pid=1383 -1252257792:Worker-2 utils.pyo:663 ADDING EFTA00502610 persistence_sqlite:e6e59caed9c44c3bae146d223cf2944f/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-22 09:16:26,268 -0400 INFO pid=1383 -1277472768:CloudWatcher utils.pyo:663 ADDING persistence_sqlite:b35f2838e2e8451e97cf866718127532/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-22 09:16:26,268 -0400 INFO pid=1383 -1243852800:Worker-0 utils.pyo:663 ADDING persistence_sqlite:de6261e3fc4149c5afbe625ebeba5684/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-22 09:16:26,269 -0400 INFO pid=1383 -1231245312:IpcConnectionThread-/var/folders/n1/ypw25pmj06qfyyl_8xnt3kf40000gn/T/tmpe7sQrX-1 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-22 09:16:26,269 -0400 INFO pid=1383 -1319497728:_LaunchSyncApp sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-22 09:16:26,269 -0400 INFO pid=1383 -1294282752:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.downloads.DownloadRename 2015-04-22 09:16:26,269 -0400 INFO pid=1383 -1256460288:Batcher utils.pyo:663 ADDING persistence_sqlite:37cb2c06677c4ea898elbdc3900b1956/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-22 09:16:26,271 -0400 INFO pid=1383 -1252257792:Worker-2 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BackupCreate 2015-04-22 09:16:26,271 -0400 INFO pid=1383 -1277472768:CloudWatcher cloud_graph.pyo:118 Initialize persistent graph with generation 2 2015-04-22 09:16:26,272 -0400 INFO pid=1383 -1235447808:FeatureSwitchPoller utils.pyo:663 ADDING persistence_sqlite:06773a0f06044a87bfad67f1947b4223/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2015-04-22 09:16:26,272 -0400 INFO pid=1383 -1243852800:Worker-0 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BackupCreate 2015-04-22 09:16:26,273 -0400 INFO pid=1383 -1294282752:Worker-1 command factory.pyo:190 Registering worker command type: common.worker.uploads.BackupCreate 2015-04-22 09:16:26,273 -0400 INFO pid=1383 -1252257792:Worker-2 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BackupDelete 2015-04-22 09:16:26,274 -0400 INFO pid=1383 -1277472768:CloudWatcher cloud_graph.pyo:1507 Current sync config generation: 2 2015-04-22 09:16:26,274 -0400 INFO pid=1383 -1235447808:FeatureSwitchPoller utils.pyo:663 ADDING persistence_sqlite:85078a9805574897840186c2484dc5cd/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-22 09:16:26,274 -0400 INFO pid=1383 -1243852800:Worker-0 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BackupDelete 2015-04-22 09:16:26,274 -0400 INFO pid=1383 -1294282752:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BackupDelete 2015-04-22 09:16:26,275 -0400 INFO pid=1383 -1252257792:Worker-2 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BackupModify 2015-04-22 09:16:26,275 -0400 INFO pid=1383 -1231245312:IpcConnectionThread-/var/folders/nl/ypw25pmj06gfyyl_8xnt3kf40000gn/T/tmpe7sQrX-1 sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-22 09:16:26,275 -0400 INFO pid=1383 -1235447808:FeatureSwitchPoller feature_switch_manager.pyo:471 Successfully received feature switches from server. 2015-04-22 09:16:26,275 -0400 INFO pid=1383 -1243852800:Worker-0 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BackupModify 2015-04-22 09:16:26,275 -0400 INFO pid=1383 -1294282752:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BackupModify 2015-04-22 09:16:26,276 -0400 INFO pid=1383 -1277472768: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 , 'dict 2.db-shml, 'dict 2.db-wal'] 2015-04-22 09:16:26,276 -0400 INFO pid=1383 -1252257792:Worker-2 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BackupMove 2015-04-22 09:16:26,277 -0400 INFO pid=1383 -1243852800:Worker-0 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BackupMove 2015-04-22 09:16:26,277 -0400 INFO pid=1383 -1294282752:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BackupMove EFTA00502611 2015-04-22 09:16:26,278 -0400 INFO pid=1383 -1231245312:IpcConnectionThread-/var/folders/n1/ypw25pmj06qfyyl_8xnt3kf40000gn/T/tmpe7sQrX-1 sqlite.pyo:277 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-22 09:16:26,278 -0400 INFO pid=1383 -1252257792:Worker-2 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BackupRename 2015-04-22 09:16:26,278 -0400 INFO pid=1383 -1243852800:Worker-0 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BackupRename 2015-04-22 09:16:26,278 -0400 INFO pid=1383 -1294282752:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.uploads.BackupRename 2015-04-22 09:16:26,279 -0400 INFO pid=1383 -1252257792:Worker-2 command_factory.pyo:190 Registering worker command type common.worker.uploads.BatchUploadCreate 2015-04-22 09:16:26,279 -0400 INFO pid=1383 -1243852800:Worker-0 command_factory.pyo:190 Registering worker command type common.worker.uploads.BatchUploadCreate 2015-04-22 09:16:26,279 -0400 INFO pid=1383 -1294282752:Worker-1 command_factory.pyo:190 Registering worker command type common.worker.uploads.BatchUploadCreate 2015-04-22 09:16:26,279 -0400 INFO pid=1383 -1252257792:Worker-2 command_factory.pyo:190 Registering worker command type: common.worker.uploads.UploadCreate 2015-04-22 09:16:26,279 -0400 INFO pid=1383 -1243852800:Worker-0 command_factory.pyo:190 Registering worker command type: common.worker.uploads.UploadCreate 2015-04-22 09:16:26,280 -0400 INFO pid=1383 -1294282752:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.uploads.UploadCreate 2015-04-22 09:16:26,280 -0400 INFO pid=1383 -1252257792:Worker-2 command_factory.pyo:190 Registering worker command type: common.worker.uploads.UploadDelete 2015-04-22 09:16:26,280 -0400 INFO pid=1383 -1243852800:Worker-0 command_factory.pyo:190 Registering worker command type: common.worker.uploads.UploadDelete 2015-04-22 09:16:26,280 -0400 INFO pid=1383 -1294282752:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.uploads.UploadDelete 2015-04-22 09:16:26,281 -0400 INFO pid=1383 -1252257792:Worker-2 command_factory.pyo:190 Registering worker command type: common.worker.uploads.UploadModify 2015-04-22 09:16:26,281 -0400 INFO pid=1383 -1243852800:Worker-0 command_factory.pyo:190 Registering worker command type: common.worker.uploads.UploadModify 2015-04-22 09:16:26,281 -0400 INFO pid=1383 -1231245312:IpcConnectionThread-/var/folders/n1/ypw25pmj06qfyyl_8xnt3kf40000gn/T/tmpe7sQrX-1 sqlite.pyo:379 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2015-04-22 09:16:26,281 -0400 INFO pid=1383 -1294282752:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.uploads.UploadModify 2015-04-22 09:16:26,281 -0400 INFO pid=1383 -1252257792:Worker-2 command_factory.pyo:190 Registering worker command type: common.worker.uploads.UploadMove 2015-04-22 09:16:26,281 -0400 INFO pid=1383 -1243852800:Worker-0 command_factory.pyo:190 Registering worker command type: common.worker.uploads.UploadMove 2015-04-22 09:16:26,282 -0400 INFO pid=1383 -1294282752:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.uploads.UploadMove 2015-04-22 09:16:26,282 -0400 INFO pid=1383 -1252257792:Worker-2 command_factory.pyo:190 Registering worker command type: common.worker.uploads.UploadRename 2015-04-22 09:16:26,282 -0400 ERROR pid=1383 -1231245312:IpcConnectionThread-/var/folders/n1/ypw25pmj06qfyyl_8xnt3kf40000gn/T/tmpe7sQrX-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-22 09:16:26,282 -0400 INFO pid=1383 -1243852800:Worker-0 command_factory.pyo:190 Registering worker command type: common.worker.uploads.UploadRename 2015-04-22 09:16:26,282 -0400 INFO pid=1383 -1294282752:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.uploads.UploadRename 2015-04-22 09:16:26,283 -0400 INFO pid=1383 -1252257792:Worker-2 EFTA00502612 command_factory.pyo:190 Registering worker command type: common.worker.internals.InternalCreate 2015-04-22 09:16:26,283 -0400 INFO pid=1383 -1231245312:IpcConnectionThread-/var/folders/n1/ypw25pmj06qfyyl_8xnt3kf40000gn/T/tmpe7sQrX-1 server.pyo:198 Closing Ipc socket connection with client. 2015-04-22 09:16:26,283 -0400 INFO pid=1383 -1243852800:Worker-0 command_factory.pyo:190 Registering worker command type: common.worker.internals.InternalCreate 2015-04-22 09:16:26,283 -0400 INFO pid=1383 -1294282752:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.internals.InternalCreate 2015-04-22 09:16:26,283 -0400 INFO pid=1383 -1252257792:Worker-2 command_factory.pyo:190 Registering worker command type: common.worker.internals.InternalModify 2015-04-22 09:16:26,284 -0400 INFO pid=1383 -1243852800:Worker-0 command_factory.pyo:190 Registering worker command type: common.worker.internals.InternalModify 2015-04-22 09:16:26,284 -0400 INFO pid=1383 -1294282752:Worker-1 command_factory.pyo:190 Registering worker command type: common.worker.internals.InternalModify 2015-04-22 09:16:26,284 -0400 INFO pid=1383 -1252257792:Worker-2 workers.pyo:132 Waiting for batcher to initialize 2015-04-22 09:16:26,284 -0400 INFO pid=1383 -1243852800:Worker-0 workers.pyo:132 Waiting for batcher to initialize 2015-04-22 09:16:26,284 -0400 INFO pid=1383 -1294282752:Worker-1 workers.pyo:132 Waiting for batcher to initialize 2015-04-22 09:16:26,285 -0400 INFO pid=1383 -1252257792:Worker-2 sync_client_thread.pyo:177 Worker-2 run loop has started. 2015-04-22 09:16:26,285 -0400 INFO pid=1383 -1243852800:Worker-0 sync_client_thread.pyo:177 Worker-0 run loop has started. 2015-04-22 09:16:26,285 -0400 INFO pid=1383 -1294282752:Worker-1 sync_client_thread.pyo:177 Worker-1 run loop has started. 2015-04-22 09:16:26,342 -0400 INFO pid=1383 -1306890240:XmppClient xmpplib.pyo:354 Start SSL peer=173.194.208.125:5222 2015-04-22 09:16:26,362 -0400 INFO pid=1383 -1277472768:CloudWatcher utils.pyo:663 ADDING DBConnectionManagerCleanupCallback 2015-04-22 09:16:26,363 -0400 INFO pid=1383 -1277472768: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-22 09:16:26,388 -0400 INFO pid=1383 -1277472768:CloudWatcher cloud_graph.pyo:888 Reuse CloudDiskDict database: /Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph/dict_2.db 2015-04-22 09:16:26,564 -0400 INFO pid=1383 -1277472768: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-22 09:16:26,565 -0400 INFO pid=1383 -1277472768:CloudWatcher cloud_graph.pyo:256 Building gdoc docids cache. 2015-04-22 09:16:26,863 -0400 INFO pid=1383 -1306890240:XmppClient xmpplib.pyo:358 Finish SSL 2015-04-22 09:16:27,256 -0400 INFO pid=1383 -1306890240:XmppClient task.pyo:257 Task <common.push.xmpp.login_task.LoginTask instance at 0xb8aa6c0> complete. 2015-04-22 09:16:27,257 -0400 WARNING pid=1383 -1306890240:XmppClient login_task.pyo:117 Clearing connection for LoginTask: <common.push.xmpp.login_task.LoginTask instance at 0xb8aa6c0> 2015-04-22 09:16:27,257 -0400 INFO pid=1383 -1306890240:XmppClient push_xmpp_client.pyo:304 Connected to 2015-04-22 09:16:27,257 -0400 WARNING pid=1383 -1319497728:Thread-4 notification_bridge.pyo:121 XMPP detected connect. 2015-04-22 09:16:27,258 -0400 INFO pid=1383 -1319497728:Thread-4 xmpp_network_channel.pyo:148 Sending push channel subscription request 2015-04-22 09:16:27,305 -0400 INFO pid=1383 -1306890240:XmppClient xmpp_network_channel.pyo:166 Received subscription response from Push for XMPPBot 2015-04-22 09:16:27,305 -0400 INFO pid=1383 -1319497728:Thread-4 notification_bridge.pyo:137 Starting TICL (client version 3.20130807)... 2015-04-22 09:16:27,305 -0400 INFO pid=1383 -1319497728:Thread-4 EFTA00502613 xmpp_network_channel.pyo:64 XmppNetworkChannel.InformNetworkStatus: CONNECTED 2015-04-22 09:16:27,319 -0400 INFO pid=1383 -1260662784:ListenerScheduler invalidation_listener.pyo:185 InvalidationListener.ReissueRegistrations(prefix=, prefix_len=0) 2015-04-22 09:16:27,852 -0400 INFO pid=1383 -1277472768:CloudWatcher cloud_graph.pyo:171 Reuse CloudDiskDict CloudDiskDict(max_change_stamp=60088, app_version=1.20.8672.3137, only_rooted_items=False, ()) 2015-04-22 09:16:27,853 -0400 INFO pid=1383 -1277472768:CloudWatcher cloud_watcher.pyo:797 Performing full snapshot diff. 2015-04-22 09:16:27,853 -0400 INFO pid=1383 -1277472768:CloudWatcher cloud_watcher.pyo:986 Getting cloud resources via changelog feed from 60089 2015-04-22 09:16:27,853 -0400 INFO pid=1383 -1277472768:CloudWatcher docs_client.pyo:741 Requesting Doc List: /feeds/default/private/changes?start-index=60089&showroot=true&include- shared=true&reason=startup 2015-04-22 09:16:30,024 -0400 INFO pid=1383 -1260662784:ListenerScheduler invalidation_listener.pyo:125 InvalidationListener.InformRegistrationStatus(1014:CHANGELOG, REGISTERED) 2015-04-22 09:16:30,024 -0400 WARNING pid=1383 -1260662784:ListenerScheduler notification_bridge.pyo:192 Invalidation status callback called with connected=True 2015-04-22 09:16:30,032 -0400 INFO pid=1383 -1260662784:ListenerScheduler pause_manager.pyo:171 Removing pause reasons (OFFLINE) from (). Paused: False 2015-04-22 09:16:30,292 -0400 INFO pid=1383 -1260662784:ListenerScheduler invalidation_listener.pyo:82 InvalidationListener.Invalidation(1014:CHANGELOG, wapi_version=60127) 2015-04-22 09:16:30,655 -0400 INFO pid=1383 -1298485248:PushNotificationKeepaliveThread push_notification_keepalive_thread.pyo:49 Sent push keepalive message at: Wed Apr 22 09:16:30 2015 2015-04-22 09:16:32,393 -0400 INFO pid=1383 -1277472768:CloudWatcher docs_client.pyo:1131 Feed has 1 entries 2015-04-22 09:16:32,393 -0400 INFO pid=1383 -1277472768:CloudWatcher docs_client.pyo:1138 End of feed, no next URI 2015-04-22 09:16:32,396 -0400 INFO pid=1383 -1277472768:CloudWatcher cloud_watcher.pyo:766 Got 1 cloud entries 2015-04-22 09:16:32,527 -0400 INFO pid=1383 -1277472768:CloudWatcher cloud_watcher.pyo:783 583 nodes in the cloud graph 2015-04-22 09:16:32,561 -0400 INFO pid=1383 -1277472768:CloudWatcher cloud_snapshot_diff_helper.pyo:84 Prepare for Pruning graph (total of 583 entries). sync gdocs=True, sync folders=None, excluded folders=frozenset(()) 2015-04-22 09:16:32,561 -0400 INFO pid=1383 -1277472768:CloudWatcher cloud_snapshot_diff_helper.pyo:85 Start Prune Graph (total of 583 entries) 2015-04-22 09:16:32,567 -0400 INFO pid=1383 -1277472768:CloudWatcher cloud_snapshot_diff_helper.pyo:168 Pruning detected 578 items to keep 2015-04-22 09:16:32,567 -0400 INFO pid=1383 -1277472768:CloudWatcher cloud_snapshot_diff_helper.pyo:175 578 entries in graph after pruning 2015-04-22 09:16:32,567 -0400 INFO pid=1383 -1277472768:CloudWatcher cloud_snapshot_diff_helper.pyo:93 Compare to snaphost 2015-04-22 09:16:32,568 -0400 INFO pid=1383 -1277472768:CloudWatcher cloud_snapshot_diff_helper.pyo:751 Starting cloud graph vs snapshot diff with selective sync set: None 2015-04-22 09:16:32,568 -0400 INFO pid=1383 -1277472768:CloudWatcher cloud_snapshot_diff_helper.pyo:753 Prepopulating DOC_ID-to-inodes cache 2015-04-22 09:16:32,748 -0400 INFO pid=1383 -1277472768:CloudWatcher cloud_snapshot_diff_helper.pyo:757 First pass detecting moved entries 2015-04-22 09:16:32,820 -0400 INFO pid=1383 -1277472768:CloudWatcher cloud_snapshot_diff_helper.pyo:772 Second pass detecting creations and modification entries 2015-04-22 09:16:33,086 -0400 INFO pid=1383 -1277472768:CloudWatcher cloud_snapshot_diff_helper.pyo:799 Processed 577 entries in total. Number of events detected: (_FSChangeActionType(Action.MODIFY): 1) 2015-04-22 09:16:33,132 -0400 INFO pid=1383 -1277472768:CloudWatcher cloud_watcher.pyo:840 Done with full snapshot diff. 2015-04-22 09:16:33,132 -0400 INFO pid=1383 -1277472768:CloudWatcher cloud_watcher.pyo:896 CloudWatcher generated 1 events 2015-04-22 09:16:33,133 -0400 INFO pid=1383 -1277472768:CloudWatcher cloud_watcher.pyo:902 CloudWatcher generated FSChange(Direction.DOWNLOAD, Action.MODIFY, ino=13585796, name=2015 April.docx, rid=0B3ENN8iEiWGvgaXhaVzNUdGt6ckU, parent_ino=529993, is_folder=False, EFTA00502614 modified=1429694225, checksum=2f778e24aba4cd68e3b3bee19d058500, size=110191, backup=Backup.NO_BACKUP_CONTENT, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB) 2015-04-22 09:16:33,133 -0400 INFO pid=1383 -1277472768:CloudWatcher aggregator.pyo:114 -- > Received event None FSChange(Direction.DOWNLOAD, Action.MODIFY, ino=13585796, name=2015 April.docx, rid=0BxNN8iEiWGvgaXhaVzNUdGt6ckU, parent_ino=529993, is_folder=False, modified=1429694225, checksum=2f778e24aba4cd68e3b3bee19d058500, size=110191, backup=Backup.NO_BACKUP_CONTENT, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB) 2015-04-22 09:16:33,134 -0400 INFO pid=1383 -1277472768:CloudWatcher downloads.pyo:127 ProcessDownloadModify (FSChange(Direction.DOWNLOAD, Action.MODIFY, ino=13585796, name=2015 April.docx, rid=0BxNN8iEiWGvgaXhaVzNUdGt6ckU, parent_ino=529993, is_folder=False, modified=1429694225, checksum=2f778e24aba4cd68e3b3beel9d058500, size=110191, backup=Backup.NO_BACKUP_CONTENT, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB)) 2015-04-22 09:16:33,134 -0400 INFO pid=1383 -1277472768:CloudWatcher change_buffer.pyo:1028 Adding change to change buffer: FSChange(Direction.DOWNLOAD, Action.MODIFY, ino=13585796, name=2015 April.docx, rid=0BxNN8iEiWGvgaXhaVzNUdGt6ckU, parent_ino=529993, is_folder=False, modified=1429694225, checksum=2f778e24aba4cd68e3b3bee19d058500, size=110191, backup=Backup.NO BACKUP_CONTENT, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB, hash=-1139971975) 2015-04-22 09:16:33,135 -0400 INFO pid=1383 -1277472768:CloudWatcher cloud_watcher.pyo:671 Change ignored. observed: 60127, retrieved: 60127 2015-04-22 09:16:33,637 -0400 INFO pid=1383 -1256460288:Batcher batcher.pyo:240 Ready changes are pending. 2015-04-22 09:16:33,640 -0400 INFO pid=1383 -1252257792:Worker-2 workers.pyo:188 Worker starting on [ImmutableChange(Direction.DOWNLOAD, Action.MODIFY, ino=13585796, name=2015 April.docx, rid=0BxNN8iEiWGvgaXhaVzNUdGt6ckU, parent_ino=529993, is_folder=False, modified=1429694225, checksum=2f778e24aba4cd68e3b3bee19d058500, size=110191, backup=Backup.NO_BACKUP_CONTENT, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB, hash=-1139971975, _constructor_called=True)] 2015-04-22 09:16:33,642 -0400 INFO pid=1383 -1252257792:Worker-2 download.pyo:112 Downloading to /var/folders/n1/ypw25pm)06qfyyl_8xnt3kf40000gn/T/tmpwuSyRP 2015-04-22 09:16:34,082 -0400 INFO pid=1383 -1273270272:LocalFSPoller poller.pyo:156 Cloud scan complete. Preparing to start LocalFSPoller 201