EFTA00502526.pdf

From: File "Nolumes/BuildData/pulse- datathgents/599758/recipes/859788647/base/b/drb/googleclient/apps/webdrive_sync/osiddist/Google Drive.app/Contents/ftesources/_boot_.py", line 77, in <module> File "Nolumes/BuildData/pulse- datathgents/599758/recipes/859788647/base/b/drb/googleclient/apps/webdrive_sync/osiddist/Google Drive.app/Contents/ftesources/_boot_.py", line 62, in _run File "/Applications/Google Drive.app/Contents/Resourceslmain.py", line 12, in <module> File "osx/run_googledrive.pyo", line 31, in Main File "PyObjCTools/AppHelper.pyo", line 235, in runEventLoop File "osic/GSyncAppDelegate.pyo", line 297, in applicationWillTerminate_ File "osx/mac_sync_app_controller.pyo", line 252, in Exit File "common/ui_action_handler.pyo", line 450, in Quit File "common/sync_app.pyo", line 1643, in Quit 2016-01-04 16:58:21,566 -0500 INFO pid=243 140735233524496:MainThread thread_manager.pyo:114 Stop was called 2016-01-04 16:58:21,566 -0500 INFO pid=243 140735233524496:MainThread thread_manager.pyo:250 Asking <SchedulerThread(ListenerScheduler, started 45686292481> to stop. 2016-01-04 16:58:21,567 -0500 INFO pid=243 140735233524496:MainThread thread_manager.pyo:250 Asking <CrashReportUploaderThread(CrashReportUploaderThread, started 45728358401> to stop. 2016-01-04 16:58:21,567 -0500 INFO pid=243 140735233524496:MainThread thread_manager.pyo:250 Asking <PushXmppClient(XmppClient, started 45770424321> to stop. 2016-01-04 16:58:21,567 -0500 WARNING pid=243 140735233524496:MainThread task.pyo:242 DriveXmppTask-0xl0f5c9fd0 aborting: abort 2016-01-04 16:58:21,567 -0500 INFO pid=243 140735233524496:MainThread task.pyo:254 Task <common.push.push_xmpp_client.DriveXmppTask object at 0xl0f5c9fd0> complete. 2016-01-04 16:58:21,568 -0500 INFO pid=243 140735233524496:MainThread xmpplib.pyo:143 Close called with 'why' reason = Client initiated close 2016-01-05 09:11:26,289 -0500 INFO pid=1127 140735233524496:MainThread logging_config.pyo:249 OS: Darwin/10.9.5 2016-01-05 09:11:26,351 -0500 INFO pid=1127 140735233524496:MainThread logging_config.pyo:252 Google Drive (build 1.26.0707.2863) 2016-01-05 09:11:26,351 -0500 INFO pid=1127 140735233524496:MainThread logging_config.pyo:253 SSL: OpenSSL 0.9.8zg 14 July 2015 2016-01-05 09:11:26,518 -0500 INFO pid=1127 140735233524496:MainThread GSyncAppDelegate.pyo:198 Application did finish launching. 2016-01-05 09:11:28,038 -0500 INFO pid=1127 140735233524496:MainThread service.pyo:132 Creating singleton service instance of '<class 'common.file_lock.FileLocki> 1 2016-01-05 09:11:28,038 -0500 INFO pid=1127 140735233524496:MainThread service.pyo:132 Creating singleton service instance of '<class 'common.file_lock.FileLock'>' 2016-01-05 09:11:28,039 -0500 INFO pid=1127 4522819584:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.service.user.User'>' 2016-01-05 09:11:28,040 -0500 INFO pid=1127 4522819584:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.roots.roots.RootManager'>' 2016-01-05 09:11:28,040 -0500 INFO pid=1127 4522819584:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.thread_manager.ThreadManager'> 1 2016-01-05 09:11:28,040 -0500 INFO pid=1127 4522819584:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.pause_manager.Service'>' 2016-01-05 09:11:28,041 -0500 INFO pid=1127 4522819584:RunAsync-_InitializeSyncAppAsync-1 sync_app.pyo:330 Getting TokenStorage... 2016-01-05 09:11:28,041 -0500 INFO pid=1127 4522819584:RunAsync-_InitializeSyncAppAsync-1 sync_app.pyo:332 Got token storage: <token_storage.TokenStorage object at 0x1092leed0> 2016-01-05 09:11:28,041 -0500 INFO pid=1127 4522819584:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.features.AppFeatures'>' 2016-01-05 09:11:28,041 -0500 INFO pid=1127 4522819584:RunAsync-_InitializeSyncAppAsync-1 service.pyo:132 Creating singleton service instance of '<class 'common.cert.Manager'>' 2016-01-05 09:11:28,048 -0500 INFO pid=1127 4522819584:RunAsync-_InitializeSyncAppAsync-1 EFTA00502526 service.pyo:132 Creating singleton service instance of '<class 'common.net.bandwidth.BandwidthThrottler'> 1 2016-01-05 09:11:28,049 -0500 INFO pid=1127 4522819584:RunAsync-_InitializeSyncAppAsync-1 sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-05 09:11:28,050 -0500 INFO pid=1127 4522819584:RunAsync-_InitializeSyncAppAsync-1 sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-05 09:11:28,051 -0500 INFO pid=1127 4522819584:RunAsync-_InitializeSyncAppAsync-1 utils.pyo:737 AddCleanupCallback persistence_sqlite:9e9681fd6d7f4833a7b8aaf1c9da5df7/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-05 09:11:28,051 -0500 INFO pid=1127 4522819584:RunAsync-_InitializeSyncAppAsync-1 utils.pyo:737 AddCleanupCallback persistence_sqlite:2cfle0a356ac4b5f93cbb9461f808033/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-05 09:11:28,538 -0500 INFO pid=1127 4522819584:RunAsync-_InitializeSyncAppAsync-1 sync_app.pyo:506 BandwidthThrottler configured via sync_config 2016-01-05 09:11:28,538 -0500 INFO pid=1127 4522819584:RunAsync-_InitializeSyncAppAsync-1 bandwidth. pyo:59 BandwidthThrottler.SetRxBytesPerSecond 0 2016-01-05 09:11:28,538 -0500 INFO pid=1127 4522819584:RunAsync-_InitializeSyncAppAsync-1 bandwidth. pyo:68 BandwidthThrottler.SetTxBytesPerSecond 0 2016-01-05 09:11:28,538 -0500 INFO pid=1127 4522819584:RunAsync-_InitializeSyncAppAsync-1 sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-05 09:11:28,539 -0500 INFO pid=1127 4522819584:RunAsync-_InitializeSyncAppAsync-1 sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-05 09:11:28,691 -0500 INFO pid=1127 4522819584:RunAsync-_InitializeSyncAppAsync-1 utils.pyo:714 Execute cleanup callback 'persistence_sqlite:9e9681fd6d7f4833a7b8aaf1c9da5df7/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2016-01-05 09:11:28,752 -0500 INFO pid=1127 4522819584:RunAsync-_InitializeSyncAppAsync-1 utils.pyo:714 Execute cleanup callback 'persistence_sqlite:2cfle0a356ac4b5f93cbb9461f808033/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2016-01-05 09:11:28,754 -0500 INFO pid=1127 4533010432:Finderlnjector sync_client_thread.pyo:175 Finderlnjector run loop has started. 2016-01-05 09:11:29,391 -0500 INFO pid=1127 140735233524496:MainThread image_resources.py:292 Loading image resources/images/ic_drawer_24.png 2016-01-05 09:11:29,485 -0500 INFO pid=1127 140735233524496:MainThread image_resources.py:292 Loading image resources/images/menu_drive-logo.png 2016-01-05 09:11:29,523 -0500 INFO pid=1127 140735233524496:MainThread image_resources.py:292 Loading image resources/images/menu_google-logo-gray.png 2016-01-05 09:11:29,586 -0500 INFO pid=1127 140735233524496:MainThread image_resources.py:292 Loading image resources/images/ic_folder_mydrive_24.png 2016-01-05 09:11:29,629 -0500 INFO pid=1127 140735233524496:MainThread image_resources.py:292 Loading image resources/images/ic_web_24.png 2016-01-05 09:11:29,631 -0500 INFO pid=1127 140735233524496:MainThread image_resources.py:292 Loading image resources/images/ic_sync_problem_24.png 2016-01-05 09:11:29,666 -0500 INFO pid=1127 140735233524496:MainThread image_resources.py:292 Loading image resources/images/ic_done.png 2016-01-05 09:11:31,235 -0500 INFO pid=1127 4546850816:ThreadMonitor thread_manager.pyo:336 Waiting on exit condition. Wake up after 3600s. 2016-01-05 09:11:31,236 -0500 INFO pid=1127 4551057408:_LaunchSyncApp sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-05 09:11:31,237 -0500 INFO pid=1127 4551057408:_LaunchSyncApp sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-05 09:11:31,238 -0500 INFO pid=1127 4551057408:_LaunchSyncApp utils.pyo:737 AddCleanupCallback persistence_sqlite:f40ae2c72af54952830159e658243ed0/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-05 09:11:31,238 -0500 INFO pid=1127 4551057408:_LaunchSyncApp utils.pyo:737 EFTA00502527 AddCleanupCallback persistence_sqlite:c90455369fa94544810775516797a74e/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-05 09:11:31,263 -0500 INFO pid=1127 4551057408:_LaunchSyncApp user.pyo:66 Initializing User instance with new credentials. None 2016-01-05 09:11:31,263 -0500 INFO pid=1127 4551057408:_LaunchSyncApp docs_client_factory.pyo:89 Creating a new DriveClient 2016-01-05 09:11:31,263 -0500 INFO pid=1127 4551057408:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.quota.Quotalnfo'>' 2016-01-05 09:11:31,267 -0500 INFO pid=1127 4551057408:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.token_bucket.CloudRucketManager'> 1 2016-01-05 09:11:31,267 -0500 INFO pid=1127 140735233524496:MainThread sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-05 09:11:31,268 -0500 INFO pid=1127 4551057408:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.drive_api.drive_resource.Manager'>. 2016-01-05 09:11:31,268 -0500 INFO pid=1127 4551057408:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.drive_api.http.Manager'>. 2016-01-05 09:11:31,268 -0500 INFO pid=1127 4551057408:_LaunchSyncApp drive_resource.pyo:130 Initialize apiclient Drive resource for current user token 2016-01-05 09:11:31,269 -0500 INFO pid=1127 140735233524496:MainThread sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-05 09:11:31,269 -0500 INFO pid=1127 140735233524496:MainThread sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-05 09:11:31,270 -0500 INFO pid=1127 140735233524496:MainThread sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-05 09:11:31,433 -0500 ERROR pid=1127 4542644224:IpcServerThread ipc_socket_base.pyo:249 Failed to bind to socket /private/var/folders/n1/ypw25pmj06qfyyl_8xntakf40000gn/T/tmplk2XDa (attempt 1 of 3) Traceback (most recent call last): File "common/ipc/ipc_socket_base.pyo", line 242, in Bind File "common/ipc/ipc_socket_base.pyo", line 176, in Bind File "socket.pyo", line 228, in meth error: (Errno 48] Address already in use 2016-01-05 09:11:31,434 -0500 ERROR pid=1127 4542644224:IpcServerThread ipc_socket_base.pyo:249 Failed to bind to socket /private/var/folders/n1/ypw25pmj06qfyyl_8xntakf40000gn/T/tmplk2XDa (attempt 2 of 3) Traceback (most recent call last): File "common/ipc/ipc_socket_base.pyo", line 242, in Bind File "common/ipc/ipc_socket_base.pyo", line 176, in Bind File "socket.pyo", line 228, in meth error: (Errno 48] Address already in use 2016-01-05 09:11:31,434 -0500 WARNING pid=1127 4542644224:IpcServerThread server.pyo:145 Socket (/Users/lgroff/Library/Application Support/Google/Drive/GoogleDrivelpcPipe) already in use, deleting it. 2016-01-05 09:11:31,471 -0500 INFO pid=1127 4551057408:_LaunchSyncApp drive_resource.pyo:178 Drive SDK baseUrl = https://www.googleapis.com/drive/v2internal/ 2016-01-05 09:11:31,509 -0500 INFO pid=1127 4551057408:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.drive_api.http.AccessTokenlnMemoryCache'>' 2016-01-05 09:11:31,510 -0500 INFO pid=1127 4551057408:_LaunchSyncApp drive_request.pyo:285 Drive request: About(tags=Reason.SYNC_OTNER) - Read request - None 2016-01-05 09:11:31,513 -0500 INFO pid=1127 4551057408:_LaunchSyncApp discovery.pyo:762 URL being requested: GET https://www.googleapis.com/drive/v2internal/about? fields=rootFolderld%2CquotaBytesTotal%2CquotaBytesUsedAggregate%2CquotaBytesUsedInTrash%2Cquo taType%2Cuser%2FemailAddress&reason=304Salt=json 2016-01-05 09:11:31,530 -0500 INFO pid=1127 4551057408:_LaunchSyncApp client.pyo:515 Attempting refresh to obtain initial access_token 2016-01-05 09:11:31,530 -0500 INFO pid=1127 4551057408:_LaunchSyncApp client.pyo:755 Refreshing access_token 2016-01-05 09:11:31,550 -0500 INFO pid=1127 4551057408:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.net.dapper.DapperService'>' EFTA00502528 2016-01-05 09:11:31,550 -0500 INFO pid=1127 4551057408:_LaunchSyncApp http.pyo:248 Request user-agent: Python-httplib2/0.9 (gzip) 2016-01-05 09:11:31,550 -0500 INFO pid=1127 4551057408:_LaunchSyncApp sync_http_client.pyo:236 Opening direct connecton to accounts.google.com:443. 2016-01-05 09:11:31,711 -0500 INFO pid=1127 4551057408:_LaunchSyncApp http.pyo:267 Response: 151 bytes - application/json; charset=utf-8 (encoding:gzip) 2016-01-05 09:11:31,713 -0500 INFO pid=1127 4551057408:_LaunchSyncApp http.pyo:248 Request user-agent: googledrivesync-1.26.0707.2863 google-api-python-client/1.3.1 (gzip) (Darwin/10.9.5) 2016-01-05 09:11:31,713 -0500 INFO pid=1127 4551057408:_LaunchSyncApp sync_http_client.pyo:236 Opening direct connecton to www.googleapis.com:443. 2016-01-05 09:11:32,424 -0500 INFO pid=1127 4551057408:_LaunchSyncApp http.pyo:267 Response: 241 bytes - application/json; charset=UTF-8 (encoding:gzip) 2016-01-05 09:11:32,457 -0500 INFO pid=1127 4551057408:_LaunchSyncApp drive_request.pyo:292 Drive response: Request (None): About(tags=Reason.SYNC_OTNER) Response: About(root_id=OABNN8iEiWGvgUk9PVA, email_address=lesley.jee@gmail.com, quota_bytes_total=16106127360, quota_bytes_used_aggregate=15378202979, quota_bytes_used_in_trash=405074503, quota_type=LIMITED) 2016-01-05 09:11:32,457 -0500 INFO pid=1127 4551057408:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.drive_api.doc_id_provider.Store'>' 2016-01-05 09:11:32,457 -0500 INFO pid=1127 4551057408:_LaunchSyncApp user.pyo:66 Initializing User instance with new credentials. lesley.jee@gmail.com 2016-01-05 09:11:32,458 -0500 INFO pid=1127 4551057408:_LaunchSyncApp sync_app.pyo:1235 Configuring sync app from feature switches. 2016-01-05 09:11:32,458 -0500 INFO pid=1127 4551057408:_LaunchSyncApp features.pyo:112 Loads feature switches. 2016-01-05 09:11:32,458 -0500 INFO pid=1127 4551057408:_LaunchSyncApp feature_switch_manager.pyo:644 Calling server to get feature switches. 2016-01-05 09:11:32,458 -0500 INFO pid=1127 4551057408:_LaunchSyncApp drive_request.pyo:285 Drive request: ListFeatureSwitches(tags=Reason.SYNC_OTHER) - Read request - lesley.jee@gmail.com 2016-01-05 09:11:32,460 -0500 INFO pid=1127 4551057408:_LaunchSyncApp discovery.pyo:762 URL being requested: GET https://www.googleapis.com/drive/v2internal/settings? fields=items%2Fkey%2Citems%2Fvalue&reason=3048namespace=FEATURE_SWITCHfialt=json 2016-01-05 09:11:32,460 -0500 INFO pid=1127 4551057408:_LaunchSyncApp http.pyo:248 Request user-agent: googledrivesync-1.26.0707.2863 google-api-python-client/1.3.1 (gzip) (Darwin/10.9.5) 2016-01-05 09:11:32,516 -0500 INFO pid=1127 4551057408:_LaunchSyncApp http.pyo:267 Response: 1434 bytes - application/json; charset=UTF-8 (encoding:gzip) 2016-01-05 09:11:32,531 -0500 INFO pid=1127 4551057408:_LaunchSyncApp drive_request.pyo:292 Drive response: Request (lesley.jee@gmail.com): ListFeatureSwitches(tags=Reason.SYNC_OTHER) Response: (u'enable_impressions_library': u'false', u'selective_sub_folder_sync.: u'false., u'crash_throttleyercentage': u'99.7', u'enable_feedback': u'true', u'enable_usb_detection': u'false', u'max_page_size_selective_syncl: u'1000', u'enable_shared_move_confirmation': u'false', u'enable_disk_check_on_preferences': u'false., u'enable_recursive_size': u'false', u'enable_push': u'true', u'enable_sync_options_in_setup_flow': u'false', u'enable_multipause': u'true', u'log_backup_count': u'0', u'enable_arbitrary_sync': u'false', u'enable_disk_check_on_setup': u'false', u'maxbatch_upload_files': u'30', u'cloud_graph_disk_generation': u'2', u'use_drive_api': u'false', u'query_strategy': u'all', u'enable_multiple_accounts': u'false', u'perf_throttle_percentage': u'100 1, u'test.featureswitch.name': u") 2016-01-05 09:11:32,532 -0500 INFO pid=1127 4551057408:_LaunchSyncApp sync_app.pyo:1260 Feature Switches: FeatureSwitchSettings( accept_blob_download_gzip_encoding=True, backup_polling_interval_secs=7200, change_filters=('DRIVE_SYNC'), cloud_graph_disk_generation=2, crash_log_size_limit=10000000, EFTA00502529 crash_throttle_percentage=99.7, disabled_cloud_graph_disk_app_versions=]], docs_list_page_size=1000, download_in_place=False, download_progress_bar=False, download_strategy=turl_template', download_url='https://googledrive.com/p/host/(doc_id}', enable_arbitrary_sync=False, enable_batch_upload=True, enable_change_filters=False, enable_context_menu=True, enable_dapper_trace=False, enable_disk_dict=False, enable_feedback=True, enable_f ile_sync_status=True, enable_impressions_library=False, enable_local_v2=False, enable_multipause=True, enable_multiple_accounts=False, enable_native_opener=True, enable_push=True, enable_recursive_size=False, enable_shared_moveconfirmation=False, enable_usb_detection=False, feedback_type=lprod., ghost_all_files=False, help_url=lhttps://support.google.com/drive/M1=%s&p=desktop_homel, impressions_heartbeat_interval_secs=10800, impressions_upload_interval_secs=1800, log_backup_count=0, log_size_mb=256, logging_level='info', max_batch_upload_files=30, new document ur1='https://docs.google.com/document?usp=drive_sync', new_presentation_ur1='https://docs.google.com/presentation?usp=drive_synci, new_spreadsheet_ur1=Ihttps://docs.google.com/spreadsheets?usp=drive_synci, numworkers=3, open_ur1='https://docs.google.com/open?id=fdoc_id)', overlays_enabled_finder_versions=[.10.71, '10.7.1', '10.7.2', '10.7.3', '10.7.5', '10.8', '10.8.1', '10.8.2', '10.8.3', '10.9', '10.9.1', '10.9.2', '10.9.3', '10.9.4'], perf_throttle_percentage=100.0, push_clientversion=1, push_keepalive_interval=72000.0, query_strategy=u'all', regular_polling_interval_secs=30, selective_sub_folder_sync=False, share_template_ur1='https://drive.google.com/sharing/share? subapp=10&shareProtocolVersion=2&theme=2Scommand=settings&shareUiType=default&authuser=0&clie nt=desktop', show_confirmation_dialog_on_delete=True, telemetry_enabled=True, telemetry_upload_interval_secs=1800, telemetry_ur1='https://drive.google.com/syncclient_impressions', tokenbucket_read_qps=10, token_bucketwrite_qps=3, use_drive_api=False,) 2016-01-05 09:11:32,533 -0500 INFO pid=1127 4551057408:_LaunchSyncApp logging_config.pyo:249 OS: Darwin/10.9.5 2016-01-05 09:11:32,540 -0500 INFO pid=1127 4551057408:_LaunchSyncApp logging_config.pyo:252 Google Drive (build 1.26.0707.2863) 2016-01-05 09:11:32,540 -0500 INFO pid=1127 4551057408:_LaunchSyncApp logging_config.pyo:253 SSL: OpenSSL 0.9.8zg 14 July 2015 2016-01-05 09:11:32,540 -0500 INFO pid=1127 4551057408:_LaunchSyncApp sync_app.pyo:1269 Config: Email: lesley.jee@gmail.com EFTA00502530 Sync root: /Users/lgroff/Google Drive Sync collections: set([]) Upgrade number: 22 App version: 1.26.0707.2863 Selective sync: False Cloud Graph generation: 2 Folder sync: [] Local app whitelist: set([]) Local app blacklist: set([]) Bandwidth RX Rate kpBs: 0 Bandwidth TX Rate kpBs: 0 Yosemite icons upgraded: False Win10 icons upgraded: False USE sync enabled: True Do sync default folder: True 2016-01-05 09:11:32,542 -0500 INFO pid=1127 4551057408:_LaunchSyncApp Switching cloud client based on new feature switches 2016-01-05 09:11:32,542 -0500 INFO pid=1127 4551057408:_LaunchSyncApp docs_client_factory.pyo:97 Creating a new Wapi DocsClient 2016-01-05 09:11:32,543 -0500 INFO pid=1127 4551057408:_LaunchSyncApp SetPushClientVersion: 1 (3, 20130807) 2016-01-05 09:11:32,543 -0500 INFO pid=1127 4551057408:_LaunchSyncApp Creating singleton service instance of '<class 'common.push.telemetry. 2016-01-05 09:11:32,543 -0500 INFO pid=1127 4551057408:_LaunchSyncApp Creating singleton service instance of '<class 'common.telemetry.telemetry_manager.TelemetryManager'>' 2016-01-05 09:11:33,151 -0500 INFO pid=1127 4551057408:_LaunchSyncApp image_resources.py:268 Loading custom image resources/images/folder-mac.icns with function CreateNSlmage 2016-01-05 09:11:33,153 -0500 INFO pid=1127 4551057408:_LaunchSyncApp service.pyo:132 Creating singleton service instance of '<class 'common.roots.root_wrangler.RootWrangler'>' 2016-01-05 09:11:33,172 -0500 INFO pid=1127 4551057408:_LaunchSyncApp root_wrangler.pyo:65 RootWrangler.RootObserverRegistered with roots [_MyDriveRoot(u'/Users/lgroff/Google Drive', 'root', <RootState.INITED: 2>)] 2016-01-05 09:11:33,173 -0500 INFO pid=1127 4551057408:_LaunchSyncApp root_wrangler.pyo:73 RootWrangler.RootsAdded with roots (_MyDriveRoot(u'/Users/lgroff/Google Drive', 'root', <RootState.INITED: 2>)] 2016-01-05 09:11:33,173 -0500 INFO pid=1127 4551057408:_LaunchSyncApp root_wrangler.pyo:79 RootWrangler got inited root _MyDriveRoot(u'/Users/lgroff/Google Drive', 'root', INITED: 2>) 09:11:33,173 aggregation 09:11:33,173 aggregation 09:11:33,173 aggregation 09:11:33,173 aggregation 09:11:33,174 aggregation 09:11:33,174 aggregation 09:11:33,174 aggregation 09:11:33,174 aggregation 2016-01-05 09:11:33,741 -0500 INFO pid=1127 4563091456:FilewatcherMonitor filewatcher.pyo:71 filewatcher ext initialized 2016-01-05 09:11:33,742 -0500 INFO pid=1127 4551057408:_LaunchSyncApp docs_client_factory.pyo:97 Creating a new Wapi DocsClient 2016-01-05 09:11:33,743 -0500 INFO pid=1127 4551057408:_LaunchSyncApp docs_client_factory.pyo:97 Creating a new Wapi DocsClient 2016-01-05 09:11:33,744 -0500 INFO pid=1127 4551057408:_LaunchSyncApp docs_client_factory.pyo:97 Creating a new Wapi DocsClient 2016-01-05 09:11:33,744 -0500 INFO pid=1127 4551057408:_LaunchSyncApp docs_client_factory.pyo:97 Creating a new Wapi DocsClient <RootState. 2016-01-05 Registering 2016-01-05 Registering 2016-01-05 Registering 2016-01-05 Registering 2016-01-05 Registering 2016-01-05 Registering 2016-01-05 Registering 2016-01-05 Registering -0500 INFO rule type: -0500 INFO rule type: -0500 INFO rule type: -0500 INFO rule type: -0500 INFO rule type: -0500 INFO rule type: -0500 INFO rule type: -0500 INFO rule type: sync_app.pyo:1274 telemetry.pyo: 228 service.pyo: 132 PushTelemetry'>' service.pyo: 132 pid=1127 4551057408:_LaunchSyncApp rule_factory common.aggregator_rules.downloads.DownloadCreate pid=1127 4551057408:_LaunchSyncApp rule_factory common.aggregator_rules.downloads.DownloadModify pid=1127 4551057408:_LaunchSyncApp rule_factory common.aggregator_rules.uploads.UploadCreate pid=1127 4551057408:_LaunchSyncApp rule_factory common.aggregator_rules.uploads.UploadDelete pid=1127 4551057408:_LaunchSyncApp rule_factory common.aggregator_rules.uploads.UploadModify pid=1127 4551057408:_LaunchSyncApp rule_factory common.aggregator_rules.uploads.UploadMove pid=1127 4551057408:_LaunchSyncApp rule_factory common.aggregator_rules.uploads.UploadMoveFrom pid=1127 4551057408:_LaunchSyncApp rule_factory common. aggregator rules.uploads.UploadMoveTo _ .pyo:159 .pyo:159 .pyo:159 .pyo:159 .pyo:159 .pyo:159 .pyo:159 .pyo:159 EFTA00502531 2016-01-05 09:11:33,745 -0500 INFO pid=1127 4551057408:_LaunchSyncApp docs_client_factory.pyo:97 Creating a new Wapi DocsClient 2016-01-05 09:11:33,746 -0500 INFO pid=1127 4551057408:_LaunchSyncApp docs_client_factory.pyo:97 Creating a new Wapi DocsClient 2016-01-05 09:11:33,746 -0500 INFO pid=1127 4551057408:_LaunchSyncApp docs_client_factory.pyo:97 Creating a new Wapi DocsClient 2016-01-05 09:11:33,756 -0500 INFO pid=1127 4551057408:_LaunchSyncApp xmpplib_notifier.pyo:34 Configure storage: <common.push.tango.storage.SyncConfigStorage; proxy of <Swig Object of type 'invalidation::Storage *' at 0x10f922cc0> > 2016-01-05 09:11:33,756 -0500 INFO pid=1127 4551057408:_LaunchSyncApp xmpplib_notifier.pyo:59 Creating client thread 2016-01-05 09:11:33,756 -0500 INFO pid=1127 4551057408:_LaunchSyncApp push_xmpp_client.pyo:223 Using talk server: talk.google.com:5222 2016-01-05 09:11:33,756 -0500 INFO pid=1127 4551057408:_LaunchSyncApp xmpplib_notifier.pyo:68 Creating notification bridge 2016-01-05 09:11:33,890 -0500 INFO pid=1127 4551057408:_LaunchSyncApp docs_client_factory.pyo:97 Creating a new Wapi DocsClient 2016-01-05 09:11:33,893 -0500 INFO pid=1127 4567298048:PauseTelemetryThread sync_client_thread.pyo:175 PauseTelemetryThread run loop has started. 2016-01-05 09:11:33,893 -0500 INFO pid=1127 4575711232:CloudWatcher sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-05 09:11:33,894 -0500 INFO pid=1127 4571504640:Worker-2 sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-05 09:11:33,894 -0500 INFO pid=1127 4579917824:PushNotificationKeepaliveThread sync_client_thread.pyo:175 PushNotificationKeepaliveThread run loop has started. 2016-01-05 09:11:33,894 -0500 INFO pid=1127 4584124416:OverlayStatusCleaner sync_client_thread.pyo:175 OverlayStatusCleaner run loop has started. 2016-01-05 09:11:33,895 -0500 INFO pid=1127 4592537600:LocalFSPoller sync_client_thread.pyo:175 LocalFSPoller run loop has started. 2016-01-05 09:11:33,895 -0500 INFO pid=1127 4588331008:CrashReportUploaderThread crash_report_uploader_thread.pyo: 60 Starting CrashReportUploaderThread. 2016-01-05 09:11:33,896 -0500 INFO pid=1127 4600950784:LocalDiskSpaceChecker sync_client_thread.pyo:175 LocalDiskSpaceChecker run loop has started. 2016-01-05 09:11:33,897 -0500 INFO pid=1127 4579917824:PushNotificationKeepaliveThread sync_http_client.pyo:236 Opening direct connecton to accounts.google.com:443. 2016-01-05 09:11:33,898 -0500 INFO pid=1127 4609363968:RawEventUpgrader sync_client_thread.pyo:175 RawEventUpgrader run loop has started. 2016-01-05 09:11:33,898 -0500 INFO pid=1127 4605157376:Worker-1 sqlite.pyo:276 Open master connection to /Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-05 09:11:33,898 -0500 INFO pid=1127 4575711232:CloudWatcher sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-05 09:11:33,899 -0500 INFO pid=1127 4617777152:XmppClient state None => PushStates.CONNECTING 2016-01-05 09:11:33,900 -0500 INFO pid=1127 4621983744:Batcher sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-05 09:11:33,900 -0500 INFO pid=1127 4571504640:Worker-2 sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-05 09:11:33,901 -0500 INFO pid=1127 4626190336:TelemetryStoreSizeMonitorThread sync_client_thread.pyo:175 TelemetryStoreSizeMonitorThread run loop has started. 2016-01-05 09:11:33,901 -0500 INFO pid=1127 4634603520:Worker-0 sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-05 09:11:33,902 -0500 INFO pid=1127 4638810112:PushTelemetryThread sync_client_thread.pyo:175 PushTelemetryThread run loop has started. 2016-01-05 09:11:33,902 -0500 INFO pid=1127 4617777152:XmppClient connection_task.pyo:151 Recalculated LoginTask timeout: 16.000000 2016-01-05 09:11:33,903 -0500 INFO pid=1127 4643016704:LocalWatcher watcher.pyo:347 LocalWatcher Run invoked.Waiting for first pass of local scan from poller to start 2016-01-05 09:11:33,903 -0500 INFO pid=1127 4647223296:TelemetryUploaderThread telemetry.pyo: 153 Push EFTA00502532 sync_client_thread.pyo:175 TelemetryUploaderThread run loop has started. 2016-01-05 09:11:33,903 -0500 INFO pid=1127 4651429888:FeatureSwitchPoller sync_client_thread.pyo:175 FeatureSwitchPoller run loop has started. 2016-01-05 09:11:33,904 -0500 INFO pid=1127 4626190336:TelemetryStoreSizeMonitorThread telemetry_store_monitor_thread.pyo:44 Checking Telemetry store size. 2016-01-05 09:11:33,905 -0500 INFO pid=1127 4551057408:_LaunchSyncApp drive_extension_installer.pyo:174 Uninstalling extension nknebiagdodnminbdpflhpkgfpeijdbf 2016-01-05 09:11:33,905 -0500 INFO pid=1127 4575711232:CloudWatcher utils.pyo:737 AddCleanupCallback persistence_sqlite:a0157bdf8488435181a3b5bb45b13f17/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-05 09:11:33,905 -0500 INFO pid=1127 4605157376:Worker-1 sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-05 09:11:33,906 -0500 INFO pid=1127 4617777152:XmppClient telemetry.pyo:167 PushStates.CONNECTING: +0.00658555 => 0.00658555 2016-01-05 09:11:33,906 -0500 INFO pid=1127 4651429888:FeatureSwitchPoller feature_switch_manager.pyo:644 Calling server to get feature switches. 2016-01-05 09:11:33,907 -0500 INFO pid=1127 4575711232:CloudWatcher utils.pyo:737 AddCleanupCallback persistence_sqlite:27bcf7450c524dd5b45eald09ff47118/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-05 09:11:33,908 -0500 INFO pid=1127 4551057408:_LaunchSyncApp utils.pyo:714 Execute cleanup callback 'persistence_sqlite:f40ae2c72af54952830159e658243ed0/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db' 2016-01-05 09:11:33,908 -0500 INFO pid=1127 4571504640:Worker-2 utils.pyo:737 AddCleanupCallback persistence_sqlite:d9ed232924594d9e9d2ee000e2acfd77/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-05 09:11:33,909 -0500 INFO pid=1127 4634603520:Worker-0 sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-05 09:11:33,909 -0500 INFO pid=1127 4621983744:Batcher sqlite.pyo:276 Open master connection to /Users/lgrof f/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-05 09:11:33,909 -0500 INFO pid=1127 4617777152:XmppClient xmpplib.pyo:80 Connecting to talk.google.com:5222 2016-01-05 09:11:33,909 -0500 INFO pid=1127 4651429888:FeatureSwitchPoller drive_resource.pyo:130 Initialize apiclient Drive resource for lesley.jee@gmail.com 2016-01-05 09:11:33,909 -0500 INFO pid=1127 4605157376:Worker-1 utils.pyo:737 AddCleanupCallback persistence_sqlite:cbe430af8d264a86800b4823dad79ff3/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-05 09:11:33,910 -0500 INFO pid=1127 4575711232:CloudWatcher cloud_graph.pyo:115 Initialize persistent graph with generation 2 2016-01-05 09:11:33,910 -0500 INFO pid=1127 4551057408:_LaunchSyncApp sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-05 09:11:33,910 -0500 INFO pid=1127 4571504640:Worker-2 utils.pyo:737 AddCleanupCallback persistence_sqlite:f0dlade8e3494dc6a75669d253cbdae3/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-05 09:11:33,911 -0500 INFO pid=1127 4651429888:FeatureSwitchPoller drive_resource.pyo: 178 Drive SDK baseUrl = https://www.googleapis.com/drive/v2internal/ 2016-01-05 09:11:33,911 -0500 INFO pid=1127 4605157376:Worker-1 utils.pyo:737 AddCleanupCallback persistence_sqlite:d44536ccdb6e45caabc8a3ec9a42eee2/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-05 09:11:33,912 -0500 INFO pid=1127 4575711232:CloudWatcher cloud_graph.pyo:1563 Current sync config generation: 2 2016-01-05 09:11:33,912 -0500 INFO pid=1127 4621983744:Batcher utils.pyo:737 AddCleanupCallback persistence_sqlite:965da7da010f4e55ba677749f9b5a984/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db EFTA00502533 2016-01-05 09:11:33,912 -0500 INFO pid=1127 4634603520:Worker-0 utils.pyo:737 AddCleanupCallback persistence_sqlite:7f525224b40a474eafff2e69b3edb7c6/Users/lgroff/Library/Application Support/Google/Drive/user_default/snapshot.db 2016-01-05 09:11:33,912 -0500 INFO pid=1127 4571504640:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadChangeACL 2016-01-05 09:11:33,913 -0500 INFO pid=1127 4605157376:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadChangeACL 2016-01-05 09:11:33,914 -0500 INFO pid=1127 4651429888:FeatureSwitchPoller drive_request.pyo:285 Drive request: ListFeatureSwitches(tags=Reason.SYNC_OTHER) - Read request - lesley.jee@gmail.com 2016-01-05 09:11:33,914 -0500 INFO pid=1127 4551057408:_LaunchSyncApp utils.pyo:714 Execute cleanup callback 'persistence_sqlite: c90455369fa94544810775516797a74e/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db' 2016-01-05 09:11:33,914 -0500 INFO pid=1127 4621983744:Batcher utils.pyo:737 AddCleanupCallback persistence_sqlite:bd56e47ealf74b1f955ab1562d0eedcf/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-05 09:11:33,915 -0500 INFO pid=1127 4575711232:CloudWatcher cloud_graph.pyo:1415 Cleanup cloud graph directory '/Users/lgrof f/Library/Application Support/Google/Drive/user_default/cloud_graph' of files 'dict_*.db" except ['dict_2.db 1, ldict_2.db-shml, idict_2.db-wal'] 2016-01-05 09:11:33,915 -0500 INFO pid=1127 4634603520:Worker-0 utils.pyo:737 AddCleanupCallback persistence_sqlite:7b43b7e888044bc78d564ba6a296fd15/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-05 09:11:33,915 -0500 INFO pid=1127 4571504640:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadCreate 2016-01-05 09:11:33,915 -0500 INFO pid=1127 4605157376:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadCreate 2016-01-05 09:11:33,917 -0500 INFO pid=1127 4651429888:FeatureSwitchPoller discovery.pyo:762 URL being requested: GET https://www.googleapis.com/drive/v2internal/settings? fields=items%2Fkey%2Citems%2FValue&reason=304&namespace=FEATURE_SWITCH&alt=json 2016-01-05 09:11:33,918 -0500 INFO pid=1127 4551057408:_LaunchSyncApp sqlite.pyo:362 Close connection. path=/Users/lgroff/Library/Application Support/Google/Drive/user_default/sync_config.db 2016-01-05 09:11:33,920 -0500 INFO pid=1127 4634603520:Worker-0 command_factory.pyo:186 Registering worker command type: common.worker.uploads.BatchUploadCreate 2016-01-05 09:11:33,921 -0500 INFO pid=1127 4571504640:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadDelete 2016-01-05 09:11:33,921 -0500 INFO pid=1127 4605157376:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadDelete 2016-01-05 09:11:33,924 -0500 INFO pid=1127 4605157376:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadModify 2016-01-05 09:11:33,923 -0500 INFO pid=1127 4634603520:Worker-0 command_factory.pyo:186 Registering worker command type: common.worker.uploads.UploadCreate 2016-01-05 09:11:33,924 -0500 INFO pid=1127 4571504640:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadModify 2016-01-05 09:11:33,923 -0500 INFO pid=1127 4651429888:FeatureSwitchPoller client.pyo:515 Attempting refresh to obtain initial access token 2016-01-05 09:11:33,924 -0500 INFO pid=1127 4605157376:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadMove 2016-01-05 09:11:33,924 -0500 INFO pid=1127 4634603520:Worker-0 command_factory.pyo:186 Registering worker command type: common.worker.uploads.UploadDelete 2016-01-05 09:11:33,924 -0500 INFO pid=1127 4571504640:Worker-2 EFTA00502534 command_factory.pyo: 186 Registering worker command type: common.worker.downloads.DownloadMove 2016-01-05 09:11:33,925 -0500 INFO pid=1127 4651429888:FeatureSwitchPoller client.pyo:735 Updated access_token read from Storage 2016-01-05 09:11:33,925 -0500 INFO pid=1127 4605157376:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadRename 2016-01-05 09:11:33,925 -0500 INFO pid=1127 4634603520:Worker-0 command_factory.pyo: 186 Registering worker command type: common.worker.uploads.UploadModify 2016-01-05 09:11:33,925 -0500 INFO pid=1127 4571504640:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.downloads.DownloadRename 2016-01-05 09:11:33,926 -0500 INFO pid=1127 4651429888:FeatureSwitchPoller http.pyo:248 Request user-agent: googledrivesync-1.26.0707.2863 google-api-python-client/1.3.1 (gzip) (Darwin/10.9.5) 2016-01-05 09:11:33,926 -0500 INFO pid=1127 4605157376:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.internals.InternalCreate 2016-01-05 09:11:33,926 -0500 INFO pid=1127 4634603520:Worker-0 command_factory.pyo: 186 Registering worker command type: common.worker.uploads.UploadMove 2016-01-05 09:11:33,926 -0500 INFO pid=1127 4571504640:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.internals.InternalCreate 2016-01-05 09:11:33,926 -0500 INFO pid=1127 4651429888:FeatureSwitchPoller sync_http_client.pyo:236 Opening direct connecton to www.googleapis.com:443. 2016-01-05 09:11:33,927 -0500 INFO pid=1127 4605157376:Worker-1 command_factory.pyo:186 Registering worker command type: common.worker.internals.InternalModify 2016-01-05 09:11:33,927 -0500 INFO pid=1127 4634603520:Worker-0 command_factory.pyo: 186 Registering worker command type: common.worker.uploads.UploadRename 2016-01-05 09:11:33,927 -0500 INFO pid=1127 4571504640:Worker-2 command_factory.pyo:186 Registering worker command type: common.worker.internals.InternalModify 2016-01-05 09:11:33,927 -0500 INFO pid=1127 4605157376:Worker-1 workers.pyo:127 Waiting for batcher to initialize 2016-01-05 09:11:33,927 -0500 INFO pid=1127 Waiting for batcher to initialize 2016-01-05 09:11:33,927 -0500 INFO pid=1127 Waiting for batcher to initialize 2016-01-05 09:11:33,928 -0500 INFO pid=1127 sync_client_thread.pyo:175 Worker-1 run loop has started. 2016-01-05 09:11:33,928 -0500 INFO pid=1127 4634603520:Worker-0 sync_client_thread.pyo:175 Worker-0 run loop has started. 2016-01-05 09:11:33,928 -0500 INFO pid=1127 4571504640:Worker-2 sync_client_thread.pyo:175 Worker-2 run loop has started. 2016-01-05 09:11:33,991 -0500 INFO pid=1127 4575711232:CloudWatcher utils.pyo:737 AddCleanupCallback DBConnectionManagerCleanupCallback 2016-01-05 09:11:33,992 -0500 INFO pid=1127 4575711232:CloudWatcher db_connection.pyo:200 Created thread connection on database /Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph/dict_2.db 2016-01-05 09:11:33,992 -0500 INFO pid=1127 4575711232:CloudWatcher cloud_graph.pyo:956 Reuse CloudDiskDict database: /Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph/dict_2.db 2016-01-05 09:11:33,993 -0500 INFO pid=1127 4617777152:XmppClient PushStates.CONNECTING: +0.087666 => 0.0942515 2016-01-05 09:11:33,994 -0500 INFO pid=1127 4617777152:XmppClient state PushStates.CONNECTING => PushStates.NEGOTIATING 2016-01-05 09:11:34,074 -0500 INFO pid=1127 4617777152:XmppClient SSL peer=gmail.com 2016-01-05 09:11:34,120 -0500 INFO pid=1127 4651429888:FeatureSwitchPoller http.pyo:267 Response: 1434 bytes - application/json; charset=UTF-8 (encoding:gzip) 2016-01-05 09:11:34,127 -0500 INFO pid=1127 4575711232:CloudWatcher cloud_graph.pyo:155 Initialize persistent graph with existing database /Users/lgroff/Library/Application Support/Google/Drive/user_default/cloud_graph/dict_2.db 2016-01-05 09:11:34,127 -0500 INFO pid=1127 4575711232:CloudWatcher cloud_graph.pyo:216 Building gdoc docids cache. 4634603520:Worker-0 4571504640:Worker-2 4605157376:Worker-1 workers.pyo:127 workers.pyo:127 telemetry.pyo: 167 telemetry.pyo:153 Push xmpplib.pyo:420 Start EFTA00502535 2016-01-05 09:11:34,136 -0500 INFO pid=1127 4579917824:PushNotificationKeepaliveThread sync_http_client.pyo:236 Opening direct connecton to clients3.google.com:443. 2016-01-05 09:11:34,138 -0500 INFO pid=1127 4617777152:XmppClient telemetry.pyo:167 PushStates.NEGOTIATING: +0.144204 => 0.144204 2016-01-05 09:11:34,214 -0500 INFO pid=1127 4651429888:FeatureSwitchPoller drive_request.pyo:292 Drive response: Request (lesley.jee@gmail.com): ListFeatureSwitches(tags=Reason.SYNC_OTHER) Response: (u'enable_impressions_library': u'false', u'selective_sub_folder_syncl: u'false., u'crash_throttle_percentage': u'99.7', u'enable_feedback': u'true', u'enable_usb_detection': u'false', u'max_page_size_selective_sync': u'1000 1, u'enable_shared_move_confirmation': u'false', u'enable_disk_check_on_preferences': u'false', u'enable_recursive_size': u'false', u'enable_push': u'true', u'enable_sync_options_in_setup_flow': u'false', u'enable_multipause': u'true', u'log_backup_count': u'0', u'enable_arbitrary_sync': u'false', u'enable_disk_check_on_setup': u'false', u'maxbatch_upload_files': u'30', u'cloud_graph_disk_generation': u'2', u'use_drive_api': u'false', u'query_strategy': u'all', u'enable_multipleaccounts': u'false', u'perf_throttle_percentage': u'100', u'test.featureswitch.name': u") 2016-01-05 09:11:34,336 -0500 INFO pid=1127 4617777152:XmppClient telemetry.pyo:167 PushStates.NEGOTIATING: +0.198 => 0.342204 2016-01-05 09:11:34,402 -0500 INFO pid=1127 4575711232:CloudWatcher cloud_graph.pyo:161 Reuse CloudDiskDict CloudDiskDict(max_change_stamp=76828, app_version=1.26.0707.2863, ()) 2016-01-05 09:11:34,403 -0500 INFO pid=1127 4575711232:CloudWatcher cloud_watcher.pyo:831 Performing full snapshot diff. 2016-01-05 09:11:34,404 -0500 INFO pid=1127 4575711232:CloudWatcher cloud_watcher.pyo:1026 Getting cloud resources via changelog feed from 76829 2016-01-05 09:11:34,404 -0500 INFO pid=1127 4575711232:CloudWatcher docs_client.pyo:743 Requesting Doc List: iffeeds/default/private/changes?start-index=76829&showroot=true&include- shared=true&reason=startup 2016-01-05 09:11:34,405 -0500 INFO pid=1127 4575711232:CloudWatcher sync_http_client.pyo:236 Opening direct connecton to clients3.google.com:443. 2016-01-05 09:11:34,477 -0500 INFO pid=1127 4617777152:XmppClient task.pyo:254 Task <common.push.xmpp.login_task.LoginTask object at Oxl0f8eaf50> complete. 2016-01-05 09:11:34,477 -0500 WARNING pid=1127 4617777152:XmppClient login_task.pyo:90 Clearing connection for LoginTask: <common.push.xmpp.login_task.LoginTask object at 0x10f8eaf50> 2016-01-05 09:11:34,477 -0500 INFO pid=1127 4617777152:XmppClient push_xmpp_client.pyo:313 Connected to talk.google.com:5222 2016-01-05 09:11:34,478 -0500 WARNING pid=1127 4655636480:RunAsync-_OnXmppConnect-4 notification_bridge.pyo:126 XMPP detected connect. 2016-01-05 09:11:34,478 -0500 INFO pid=1127 4655636480:RunAsync-_OnXmppConnect-4 xmpp_network_channel.pyo:169 Sending push channel subscription request 2016-01-05 09:11:34,518 -0500 INFO pid=1127 4617777152:XmppClient xmpp_network_channel.pyo:187 Received subscription response from Push for XMPPBot 2016-01-05 09:11:34,519 -0500 INFO pid=1127 4655636480:RunAsync-_OnXmppConnect-4 notification_bridge.pyo:142 Starting TICL (client version 3.20130807)... 2016-01-05 09:11:34,519 -0500 INFO pid=1127 4655636480:RunAsync-_OnXmppConnect-4 xmpp_network_channel.pyo: 65 XmppNetworkChannel.InformNetworkStatus: CONNECTED 2016-01-05 09:11:34,537 -0500 INFO pid=1127 4596744192:ListenerScheduler invalidation_listener.pyo:185 InvalidationListener.ReissueRegistrations(prefix=, prefix_len=0) 2016-01-05 09:11:34,751 -0500 INFO pid=1127 4579917824:PushNotificationKeepaliveThread push_notification_keepalive_thread.pyo:49 Sent push keepalive message at: Tue Jan 5 09:11:34 2016 2016-01-05 09:11:34,798 -0500 INFO pid=1127 4575711232:CloudWatcher docs_client.pyo:1174 Feed has 1 entries 2016-01-05 09:11:34,798 -0500 INFO pid=1127 4575711232:CloudWatcher docs_client.pyo:1181 End of feed, no next URI 2016-01-05 09:11:34,801 -0500 INFO pid=1127 4575711232:CloudWatcher cloud_watcher.pyo:807 Got 1 cloud entries 2016-01-05 09:11:34,802 -0500 INFO pid=1127 4575711232:CloudWatcher cloud_watcher.pyo:815 720 nodes in the cloud graph 2016-01-05 09:11:34,839 -0500 INFO pid=1127 4575711232:CloudWatcher cloud_snapshot_diff_helper.pyo:84 Prepare for Pruning graph (total of 720 entries). sync EFTA00502536 gdocs=True, sync folders=None, excluded folders=frozenset([]) 2016-01-05 09:11:34,841 -0500 INFO pid=1127 4575711232:CloudWatcher cloud snapshot diff_helper.pyo:85 Start Prune Graph (total of 720 entries) 2016-01-05 09:11:34,855 -0500 INFO pid=1127 4575711232:CloudWatcher cloud snapshot diff_helper.pyo:175 Pruning detected 703 items to keep 2016-01-05 09:11:34,855 -0500 INFO pid=1127 4575711232:CloudWatcher cloud snapshot diff_helper.pyo:182 703 entries in graph after pruning 2016-01-05 09:11:34,856 -0500 INFO pid=1127 4575711232:CloudWatcher cloud snapshot diff_helper.pyo:93 Compare to snaphost 2016-01-05 09:11:34,856 -0500 INFO pid=1127 4575711232:CloudWatcher cloud_snapshot_diff_helper.pyo:762 Starting cloud graph vs snapshot set: None 2016-01-05 09:11:34,856 -0500 INFO cloud_snapshot_diff_helper.pyo:764 2016-01-05 09:11:34,858 -0500 INFO cloud_snapshot_diff_helper.pyo:768 2016-01-05 09:11:34,983 -0500 INFO cloud_snapshot_diff_helper.pyo:783 2016-01-05 09:11:35,343 -0500 INFO cloud_snapshot_diff_helper.pyo:810 (_FSChangeActionType(Action.MODIFY) 2016-01-05 09:11:35,412 -0500 INFO Done with full snapshot diff. 2016-01-05 09:11:35,413 -0500 INFO pid=1127 4575711232:CloudWatcher cloud_watcher.pyo:934 CloudWatcher generated 1 events 2016-01-05 09:11:35,413 -0500 INFO pid=1127 4575711232:CloudWatcher cloud_watcher.pyo:940 CloudWatcher generated FSChange(Direction.DOWNLOAD, Action.MODIFY, ino=15554209, name=2016 January.docx, rid=0BxNN8iEiWGvgU3pmX01CLVdjdVk, parent_ino=14390475, is_folder=False, unparents_shared=False, modified=1451988927, checksum=b9e93a750d33ddae82db936c2fd3aa16, size=93364, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB) 2016-01-05 09:11:35,413 -0500 INFO pid=1127 4575711232:CloudWatcher aggregator.pyo:108 --- > Received event None FSChange(Direction.DOWNLOAD, Action.MODIFY, ino=15554209, name=2016 January.docx, rid=0BxNN8iEiWGvgU3pmX01CLVdjdVk, parent_ino=14390475, is_folder=False, unparents_shared=False, modified=1451988927, checksum=b9e93a750d33ddae82db936c2fd3aa16, size=93364, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB) 2016-01-05 09:11:35,414 -0500 INFO pid=1127 4575711232:CloudWatcher downloads.pyo:126 ProcessDownloadModify (FSChange(Direction.DOWNLOAD, Action.MODIFY, ino=15554209, name=2016 January.docx, rid=0BxNN8iEiWGvgU3pmX01CLVdjdVk, parent_ino=14390475, is_folder=False, unparents_shared=False, modified=1451988927, checksum=b9e93a750d33ddae82db936c2fd3aa16, size=93364, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB)) 2016-01-05 09:11:35,898 -0500 INFO pid=1127 4592537600:LocalFSPoller poller.pyo:126 Cloud scan complete. Preparing to start LocalFSPoller 2016-01-05 09:11:35,899 -0500 INFO pid=1127 4592537600:LocalFSPoller poller.pyo:137 LocalFSPoller first pass started. Waiting for localWatcher to start watching. 2016-01-05 09:11:35,899 -0500 INFO pid=1127 4643016704:LocalWatcher watcher.pyo:266 RootsAdded with roots [_MyDriveRoot(u'/Users/lgroff/Google Drive', 'root', <RootState.INITED: 2>)] 2016-01-05 09:11:35,899 -0500 INFO pid=1127 4643016704:LocalWatcher watcher.pyo:271 watcher adding new root to graph: _MyDriveRoot(u'/Users/lgroff/Google Drive', 'root', INITED: 2>) 09:11:35,899 -0500 INFO pid=1127 4643016704:LocalWatcher watcher.pyo:252 roots: [_MyDriveRoot(u'/Users/lgroff/Google Drive', 'root', <RootState.INITED: <RootState. 2016-01-05 Scheduling 2>)] 2016-01-05 Scheduling diff with selective sync pid=1127 4575711232:CloudWatcher Prepopulating DOC_ID-to-inodes cache pid=1127 4575711232:CloudWatcher First pass detecting moved entries pid=1127 4575711232:CloudWatcher Second pass detecting creations and modification entries pid=1127 4575711232:CloudWatcher Processed 702 entries in total. Number of events detected: : 1) pid=1127 4575711232:CloudWatcher cloud_watcher.pyo:875 09:11:35,899 -0500 INFO pid=1127 4643016704:LocalWatcher watcher.pyo:305 root: _MyDriveRoot(u'/Users/lgroff/Google Drive', 'root', <RootState.INITED: 2>) 2016-01-05 09:11:35,900 -0500 INFO pid=1127 4643016704:LocalWatcher LocalWatcher is up. 2016-01-05 09:11:35,900 -0500 INFO pid=1127 4592537600:LocalFSPoller Finished waiting for LocalWatcher. 2016-01-05 09:11:35,900 -0500 INFO pid=1127 4592537600:LocalFSPoller Starting a local disk scan. 2016-01-05 09:11:35,900 -0500 INFO pid=1127 4592537600:LocalFSPoller Walking: [u'/Users/lgroff/Google Drive'] 2016-01-05 09:11:36,136 -0500 INFO pid=1127 4592537600:LocalFSPoller watcher.pyo:368 poller.pyo:146 poller.pyo:149 diff_helper.pyo: 86 poller.pyo:166 Number EFTA00502537 of found changes: 0 2016-01-05 09:11:36,136 -0500 INFO pid=1127 4592537600:LocalFSPoller poller.pyo:177 Finished local disk scan. 2016-01-05 09:11:36,201 -0500 INFO pid=1127 4605157376:Worker-1 workers.pyo:183 Worker starting on [ImmutableChange(Direction.DOWNLOAD, Action.MODIFY, ino=15554209, name=2016 January.docx, rid=0BxNN8iEiWGvgU3pmX01CLVdjdVk, parent_ino=14390475, is_folder=False, unparents_shared=False, modified=1451988927, checksum=b9e93a750d33ddae82db936c2fd3aa16, size=93364, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB, hash=-3516717842571895284, _constructor_called=True)] 2016-01-05 09:11:36,204 -0500 INFO pid=1127 4605157376:Worker-1 download.pyo:139 Downloading https://googledrive.com/p/host/OBxNN8iEiWGvgU3pmX01CLVdjdVk to /var/folders/nl/ypw25pmj06gfyyl_8xnt3kf40000gn/T/tmpSc6FCi 2016-01-05 09:11:36,205 -0500 INFO pid=1127 4605157376:Worker-1 sync_http_client.pyo:236 Opening direct connecton to googledrive.com:443. 2016-01-05 09:11:36,639 -0500 INFO pid=1127 4605157376:Worker-1 commands.pyo:58 Updating inode in snapshot. old=15554209, new=15595952 2016-01-05 09:11:36,639 -0500 INFO pid=1127 4605157376:Worker-1 commands.pyo:61 Entry associated with new inode prior to update None: 2016-01-05 09:11:36,639 -0500 INFO pid=1127 4605157376:Worker-1 snapshot_sglite.pyo:552 Removing local relation child_inode=15554209, parent_inode=14390475 2016-01-05 09:11:36,639 -0500 INFO pid=1127 4605157376:Worker-1 snapshot_sglite.pyo:572 Removing Mapping inode=15554209, doc_id=OBxNN8iEiWGvgU3pmX01CLVdjdVk 2016-01-05 09:11:36,639 -0500 INFO pid=1127 4605157376:Worker-1 snapshot_sglite.pyo:154 Removing local entry inode=15554209 2016-01-05 09:11:36,640 -0500 INFO pid=1127 4605157376:Worker-1 snapshot_sglite.pyo:143 Adding local entry inode=15595952, filename=None 2016-01-05 09:11:36,640 -0500 INFO pid=1127 4605157376:Worker-1 snapshot_sglite.pyo: 542 Adding local relation child_inode=15595952, parent_inode=14390475 2016-01-05 09:11:36,640 -0500 INFO pid=1127 4605157376:Worker-1 snapshot_sglite.pyo:230 Updating local entry inode=15595952, filename=2016 January.docx, modified=1451944547, checksum=51d6e9835eb4e56c7d07969b4e80f7b0, size=93693, is_folder=False 2016-01-05 09:11:36,640 -0500 INFO pid=1127 4605157376:Worker-1 snapshot_sglite.pyo:562 Adding Mapping inode=15595952, doc_id=0BxNN8iEiWGvgU3pm0(01CLVdjdVk 2016-01-05 09:11:36,641 -0500 INFO pid=1127 4605157376:Worker-1 snapshot_sglite.pyo:230 Updating local entry inode=15595952, modified=1451988927, checksum=b9e93a750d33ddae82db936c2fd3aa16, size=93364 2016-01-05 09:11:36,641 -0500 INFO pid=1127 4605157376:Worker-1 snapshot_sglite.pyo:253 Updating cloud entry doc_id=0BxNN8iEiWGvgU3pmX01CLVdjdVk, filename=None 2016-01-05 09:11:36,642 -0500 INFO pid=1127 4605157376:Worker-1 workers.pyo:194 Worker successfully completed [ImmutableChange(Direction.DOWNLOAD, Action.MODIFY, ino=15554209, name=2016 January.docx, rid=0BxNN8iEiWGvgU3pmX01CLVdjdVk, parent_ino=14390475, is_folder=False, unparents_shared=False, modified=1451988927, checksum=b9e93a750d33ddae82db936c2fd3aa16, size=93364, is_cancelled=False, is_priority=False, doc_type=DocType.BLOB, hash=-3516717842571895284, _constructor_called=True)] 2016-01-05 09:11:36,648 -0500 INFO pid=1127 4563091456:FilewatcherMonitor watcher.pyo:550 PathsCallback called with: [u'/Users/lgroff/Google Drive/My Documents (1)/1 ] 2016-01-05 09:11:36,649 -0500 INFO pid=1127 4563091456:FilewatcherMonitor diff_helper.pyo:86 Scanning: (u'/Users/lgroff/Google Drive/My Documents (1)'] 2016-01-05 09:11:37,201 -0500 INFO pid=1127 4621983744:Batcher batcher.pyo:239 Current batch is completed. Batcher snooze. 2016-01-05 09:11:37,201 -0500 INFO pid=1127 4621983744:Batcher sync_status.pyo:133 Reset sync status 2016-01-05 09:11:37,201 -0500 INFO pid=1127 4621983744:Batcher batcher.pyo:855 Batcher Stats = file_count = Counter ( { COUNT KEY Cdirection=_DownloadDirectionType (Direction. DOWNLOAD), action=_FSChangeActionType(Action.MODIFY), batch=False, successful=True): 1)), byte_count = Counter ( { COUNT KEY Cdirection=_DownloadDirectionType (Direction. DOWNLOAD), action=_FSChangeActionType(Action.MODIFY), batch=False, successful=True): 93364)), batch_operation_count = Counter(), process_seconds = Counter ( ( COUNT KEY Cdirection=_DownloadDirectionType (Direction. DOWNLOAD), action=_FSChangeActionType(Action.MODIFY), batch=False, successful=True): 0.4399738311767578)), duration seconds = 1 (start_time = 1452003096, end_time = 1452003097) 2016-01-05 09:11:37,302 -0500 INFO pid=1127 4596744192:ListenerScheduler invalidation_listener.pyo:82 InvalidationListener.Invalidation(1014:CHANGELOG, EFTA00502538 wapi_version=76853) 2016-01-05 09:11:37,303 -0500 INFO pid=1127 4575711232:CloudWatcher cloud_watcher.pyo:716 Change ignored. observed: 76853, retrieved: 76855 2016-01-05 09:11:37,313 -0500 INFO pid=1127 4596744192:ListenerScheduler invalidation_listener.pyo:125 InvalidationListener.InformRegistrationStatus(1014:CHANGELOG, REGISTERED) 2016-01-05 09:11:37,313 -0500 WARNING pid=1127 4596744192:ListenerScheduler notification_bridge.pyo:202 Invalidation status callback called with connected=True 2016-01-05 09:11:37,313 -0500 INFO pid=1127 4596744192:ListenerScheduler telemetry.pyo:167 PushStates.NEGOTIATING: 42.9777 => 3.31991 2016-01-05 09:11:37,313 -0500 INFO pid=1127 4596744192:ListenerScheduler telemetry.pyo:153 Push state PushStates.NEGOTIATING => PushStates.ONLINE 2016-01-05 09:11:37,313 -0500 INFO pid=1127 4596744192:ListenerScheduler pause_manager.pyo:130 Removing pause reason OFFLINE. Paused: False 2016-01-05 09:11:37,314 -0500 ERROR pid=1127 4596744192:ListenerScheduler pause_manager.pyo:438 Tried to get last event time for PauseReason.OFFLINE, but no last event time recorded. 2016-01-05 09:11:37,982 -0500 INFO pid=1127 4563091456:FilewatcherMonitor watcher.pyo:550 PathsCallback called with: [u./Users/lgroff/Google Drive/My Documents (1)/.] 2016-01-05 09:11:37,982 -0500 INFO pid=1127 4563091456:FilewatcherMonitor diff_helper.pyo:86 Scanning: [u'/Users/lgroff/Google Drive/My Documents (1)'] 2016-01-05 09:12:05,414 -0500 INFO pid=1127 4575711232:CloudWatcher cloud_watcher.pyo:698 Getting changes: elapsed_time: 30.001060295 2016-01-05 09:12:05,415 -0500 INFO pid=1127 4575711232:CloudWatcher cloud_watcher.pyo:1026 Getting cloud resources via changelog feed from 76856 2016-01-05 09:12:05,415 -0500 INFO pid=1127 4575711232:CloudWatcher docs_client.pyo:743 Requesting Doc List: /feeds/default/private/changes?start-index=76856&showroot=true&include- shared=true&reason=poll 2016-01-05 09:12:05,416 -0500 INFO pid=1127 4575711232:CloudWatcher sync_http_client.pyo:236 Opening direct connecton to clients3.google.com:443. 2016-01-05 09:12:05,566 -0500 INFO pid=1127 4575711232:CloudWatcher docs_client.pyo:1181 End of feed, no next URI 2016-01-05 09:12:05,566 -0500 INFO pid=1127 4575711232:CloudWatcher cloud_watcher.pyo:198 HandleSyncConfigSettingsChange generated events=set([]) 2016-01-05 09:23:12,998 -0500 INFO pid=1127 4563091456:FilewatcherMonitor watcher.pyo:550 PathsCallback called with: [u./Users/lgroff/Google Drive/'] 2016-01-05 09:23:12,998 -0500 INFO pid=1127 4563091456:FilewatcherMonitor diff_helper.pyo:86 Scanning: (u'/Users/lgroff/Google Drive') 2016-01-05 09:23:18,140 -0500 INFO pid=1127 4563091456:FilewatcherMonitor watcher.pyo:550 PathsCallback called with: [u./Users/lgroff/Google Drive/'] 2016-01-05 09:23:18,140 -0500 INFO pid=1127 4563091456:FilewatcherMonitor diff_helper.pyo:86 Scanning: (u'/Users/lgroff/Google Drive') 2016-01-05 09:23:23,142 -0500 INFO pid=1127 4563091456:FilewatcherMonitor watcher.pyo:550 PathsCallback called with: [u./Users/lgroff/Google Drive/'] 2016-01-05 09:23:23,143 -0500 INFO pid=1127 4563091456:FilewatcherMonitor diff_helper.pyo:86 Scanning: (u'/Users/lgroff/Google Drive') 2016-01-05 09:26:33,889 -0500 INFO pid=1127 4626190336:TelemetryStoreSizeMonitorThread telemetry_store_monitor_thread.pyo: 44 Checking Telemetry store size. 2016-01-05 09:41:33,870 -0500 INFO pid=1127 4626190336:TelemetryStoreSizeMonitorThread telemetry_store_monitor_thread.pyo:44 Checking Telemetry store size. 2016-01-05 09:56:33,851 -0500 INFO pid=1127 4626190336:TelemetryStoreSizeMonitorThread telemetry_store_monitor_thread.pyo:44 Checking Telemetry store size. 2016-01-05 10:11:03,376 -0500 INFO pid=1127 4596744192:ListenerScheduler invalidation_listener.pyo:82 InvalidationListener.Invalidation(1014:CHANGELOG, wapi_version=76857) 2016-01-05 10:11:03,376 -0500 INFO pid=1127 4575711232:CloudWatcher cloud_watcher.pyo:711 Getting changes; observed: 76857, retrieved: 76855 2016-01-05 10:11:03,377 -0500 INFO pid=1127 4575711232:CloudWatcher cloud_watcher.pyo:1026 Getting cloud resources via changelog feed from 76856 2016-01-05 10:11:03,377 -0500 INFO pid=1127 4575711232:CloudWatcher docs_client.pyo:743 Requesting Doc List: /feeds/default/private/changes?start-index=76856&showroot=true&include- shared=true&reason=change 2016-01-05 10:11:03,378 -0500 INFO pid=1127 4575711232:CloudWatcher proxy_manager.pyo:354 Removing stale proxy entry from cache 2016-01-05 10:11:03,379 -0500 INFO pid=1127 4575711232:CloudWatcher EFTA00502539 sync_http_client.pyo:236 Opening direct connecton to accounts.google. 2016-01-05 10:11:03,586 -0500 INFO pid=1127 4575711232:CloudWatcher Removing stale proxy entry from cache 2016-01-05 10:11:03,586 -0500 INFO pid=1127 4575711232:CloudWatcher sync_http_client.pyo:236 Opening direct connecton to clients3.google. 2016-01-05 10:11:04,209 -0500 INFO pid=1127 4575711232:CloudWatcher Feed has 1 entries 2016-01-05 10:11:04,209 -0500 INFO pid=1127 4575711232:CloudWatcher End of feed, no next URI 2016-01-05 10:11:04,211 -0500 INFO pid=1127 4575711232:CloudWatcher GenerateCreateForDescendents() entry CloudEntry(doc_id=OBxNN8iEiWGvgU3pmX01CLVdjdVk,filename=2016 January.docx,modified=1451988927,created=1449324475,acl role=owner,doc_type=DocType.BLOB,remo ved=False,parent doc ids=set(['OBxNN8iEiWGvgRjcxNnBhRm5711/11:.]),child_doc_ids=set([1].size=933 64,checksum=b9e93a755d33ddae82db936c2fd3aa16,change_stamp=76857,server mod time=1452006652,is zombie=False,shared=False,recursive_size=None,resource_type=file,versIon=Uone) parents_added set([]) 2016-01-05 10:11:04,311 -0500 INFO pid=1127 4575711232:CloudWatcher cloud_watcher.pyo:198 HandleSyncConfigSettingsChange generated events=set([]) 2016-01-05 10:11:31,156 -0500 INFO pid=1127 4546850816:ThreadMonitor thread_manager.pyo:339 Waking up. 2016-01-05 10:11:31,157 -0500 INFO pid=1127 4546850816:ThreadMonitor thread_manager.pyo:336 Waiting on exit condition. Wake up after 3600s. 2016-01-05 10:11:33,815 -0500 INFO pid=1127 4567298048:PauseTelemetryThread pause telemetry.pyo:185 Collected 0.99997838974h of telemetry at t=1452006693.82, skipping. 2016-01-05 10:11:33,826 -0500 INFO pid=1127 4638810112:PushTelemetryThread telemetry.pyo:167 PushStates.ONLINE: +3596.59 => 3596.59 2016-01-05 10:11:33,826 -0500 INFO pid=1127 4638810112:PushTelemetryThread telemetry.pyo:112 Push telemetry harvest triggered, but only 3600.00624465s elapsed. Skipping. 2016-01-05 10:11:33,833 -0500 INFO pid=1127 4626190336:TelemetryStoreSizeMonitorThread telemetry_store monitor_thread.pyo:44 Checking Telemetry store size. 2016-01-05 10:22:02,420 -0500 WARNING pid=1127 4617777152:XmppClient xmpplib.pyo:277 Flush failed with <class 'socket.error'>: [Errno 32] Broken pipe 2016-01-05 10:22:02,421 -0500 ERROR pid=1127 4617777152:XmppClient fatal exceptions.pyo:55 Ignoring non-fatal error: error: [Errno 22] Invalid argument, Traceback: File "common/push/xmpp/connection_task.pyo", line 143, in HandleKeepaliveTimer File "contextlib.pyo", line 24, in _exit_ File "common/push/xmpp/xmpplib.pyo", line 119, in ShrinkBuffer File "socket.pyo", line 228, in meth com:443. proxy_manager.pyo:354 com:443. docs_client.pyo: 1174 docs_client.pyo:1181 cloud_watcher.pyo: 1368 EFTA00502540