diff --git a/.gitignore b/.gitignore index efd4a88..d8dcf48 100644 --- a/.gitignore +++ b/.gitignore @@ -1,3 +1,4 @@ **/*.sw* **/*.db **/target +**/*.log diff --git a/secert-hitler/src/controller/state/room.rs b/secert-hitler/src/controller/state/room.rs index 8d7b354..9102d5f 100644 --- a/secert-hitler/src/controller/state/room.rs +++ b/secert-hitler/src/controller/state/room.rs @@ -8,7 +8,15 @@ pub trait Room { #[cfg(test)] mod tests { + use super::Room; + use super::super::mockroom::MockRoom; + #[test] - fn room() { + fn mockroom() { + fn gen() -> impl Room { + let r = MockRoom::create(); + r + } + gen(); } } diff --git a/secert-hitler/src/controller/state/rooms.rs b/secert-hitler/src/controller/state/rooms.rs index f417088..77a89ba 100644 --- a/secert-hitler/src/controller/state/rooms.rs +++ b/secert-hitler/src/controller/state/rooms.rs @@ -7,7 +7,15 @@ pub trait Rooms { #[cfg(test)] mod tests { + use super::Rooms; + use super::super::mockrooms::MockRooms; + #[test] - fn rooms() { + fn mockrooms() { + fn gen() -> impl Rooms { + let r = MockRooms::new(); + r + } + gen(); } } diff --git a/testdata/matrix-sandbox/homeserver.log b/testdata/matrix-sandbox/homeserver.log deleted file mode 100644 index 08e891c..0000000 --- a/testdata/matrix-sandbox/homeserver.log +++ /dev/null @@ -1,580 +0,0 @@ -2020-05-02 07:19:16,961 - twisted - 192 - INFO - None - Redirected stdout/stderr to logs -2020-05-02 07:19:16,961 - root - 257 - WARNING - None - ***** STARTING SERVER ***** -2020-05-02 07:19:16,961 - root - 258 - WARNING - None - Server /nix/store/8xrn90w8184gwac56bwm8dgxb2dl0qql-matrix-synapse-1.12.1/bin/homeserver version 1.12.1 -2020-05-02 07:19:16,961 - root - 259 - INFO - None - Server hostname: 192.168.0.86 -2020-05-02 07:19:16,961 - synapse.app.homeserver - 346 - INFO - None - Setting up server -2020-05-02 07:19:16,961 - synapse.server - 240 - INFO - None - Setting up. -2020-05-02 07:19:16,962 - synapse.storage.data_stores - 48 - INFO - None - Preparing database 'master'... -2020-05-02 07:19:21,541 - synapse.storage.prepare_database - 320 - INFO - None - Upgrading schema to v55 -2020-05-02 07:19:21,542 - synapse.storage.prepare_database - 400 - INFO - None - Applying schema 55/access_token_expiry.sql -2020-05-02 07:19:21,548 - synapse.storage.prepare_database - 400 - INFO - None - Applying schema 55/track_threepid_validations.sql -2020-05-02 07:19:21,551 - synapse.storage.prepare_database - 400 - INFO - None - Applying schema 55/users_alter_deactivated.sql -2020-05-02 07:19:21,556 - synapse.storage.prepare_database - 320 - INFO - None - Upgrading schema to v56 -2020-05-02 07:19:21,559 - synapse.storage.prepare_database - 400 - INFO - None - Applying schema 56/add_spans_to_device_lists.sql -2020-05-02 07:19:21,564 - synapse.storage.prepare_database - 400 - INFO - None - Applying schema 56/current_state_events_membership.sql -2020-05-02 07:19:21,570 - synapse.storage.prepare_database - 400 - INFO - None - Applying schema 56/current_state_events_membership_mk2.sql -2020-05-02 07:19:21,571 - synapse.storage.prepare_database - 400 - INFO - None - Applying schema 56/delete_keys_from_deleted_backups.sql -2020-05-02 07:19:21,572 - synapse.storage.prepare_database - 400 - INFO - None - Applying schema 56/destinations_failure_ts.sql -2020-05-02 07:19:21,577 - synapse.storage.prepare_database - 400 - INFO - None - Applying schema 56/device_stream_id_insert.sql -2020-05-02 07:19:21,578 - synapse.storage.prepare_database - 400 - INFO - None - Applying schema 56/devices_last_seen.sql -2020-05-02 07:19:21,584 - synapse.storage.prepare_database - 400 - INFO - None - Applying schema 56/drop_unused_event_tables.sql -2020-05-02 07:19:21,585 - synapse.storage.prepare_database - 400 - INFO - None - Applying schema 56/event_expiry.sql -2020-05-02 07:19:21,586 - synapse.storage.prepare_database - 400 - INFO - None - Applying schema 56/event_labels.sql -2020-05-02 07:19:21,587 - synapse.storage.prepare_database - 400 - INFO - None - Applying schema 56/event_labels_background_update.sql -2020-05-02 07:19:21,587 - synapse.storage.prepare_database - 400 - INFO - None - Applying schema 56/fix_room_keys_index.sql -2020-05-02 07:19:21,588 - synapse.storage.prepare_database - 400 - INFO - None - Applying schema 56/hidden_devices.sql -2020-05-02 07:19:21,589 - synapse.storage.prepare_database - 404 - INFO - None - Applying engine-specific schema 56/hidden_devices_fix.sql.sqlite -2020-05-02 07:19:21,596 - synapse.storage.prepare_database - 400 - INFO - None - Applying schema 56/nuke_empty_communities_from_db.sql -2020-05-02 07:19:21,597 - synapse.storage.prepare_database - 400 - INFO - None - Applying schema 56/public_room_list_idx.sql -2020-05-02 07:19:21,597 - synapse.storage.prepare_database - 400 - INFO - None - Applying schema 56/redaction_censor.sql -2020-05-02 07:19:21,598 - synapse.storage.prepare_database - 400 - INFO - None - Applying schema 56/redaction_censor2.sql -2020-05-02 07:19:21,600 - synapse.storage.prepare_database - 400 - INFO - None - Applying schema 56/redaction_censor4.sql -2020-05-02 07:19:21,600 - synapse.storage.prepare_database - 400 - INFO - None - Applying schema 56/remove_tombstoned_rooms_from_directory.sql -2020-05-02 07:19:21,600 - synapse.storage.prepare_database - 400 - INFO - None - Applying schema 56/room_key_etag.sql -2020-05-02 07:19:21,602 - synapse.storage.prepare_database - 400 - INFO - None - Applying schema 56/room_membership_idx.sql -2020-05-02 07:19:21,603 - synapse.storage.prepare_database - 400 - INFO - None - Applying schema 56/room_retention.sql -2020-05-02 07:19:21,603 - synapse.storage.prepare_database - 400 - INFO - None - Applying schema 56/signing_keys.sql -2020-05-02 07:19:21,604 - synapse.storage.prepare_database - 400 - INFO - None - Applying schema 56/signing_keys_nonunique_signatures.sql -2020-05-02 07:19:21,604 - synapse.storage.prepare_database - 400 - INFO - None - Applying schema 56/state_group_room_idx.sql -2020-05-02 07:19:21,605 - synapse.storage.prepare_database - 400 - INFO - None - Applying schema 56/stats_separated.sql -2020-05-02 07:19:21,607 - synapse.storage.prepare_database - 389 - INFO - None - Running script 56/unique_user_filter_index.py -2020-05-02 07:19:21,704 - synapse.storage.prepare_database - 400 - INFO - None - Applying schema 56/user_external_ids.sql -2020-05-02 07:19:21,706 - synapse.storage.prepare_database - 400 - INFO - None - Applying schema 56/users_in_public_rooms_idx.sql -2020-05-02 07:19:21,707 - synapse.storage.prepare_database - 320 - INFO - None - Upgrading schema to v57 -2020-05-02 07:19:21,708 - synapse.storage.prepare_database - 400 - INFO - None - Applying schema 57/delete_old_current_state_events.sql -2020-05-02 07:19:21,709 - synapse.storage.prepare_database - 400 - INFO - None - Applying schema 57/device_list_remote_cache_stale.sql -2020-05-02 07:19:21,711 - synapse.storage.prepare_database - 389 - INFO - None - Running script 57/local_current_membership.py -2020-05-02 07:19:21,713 - synapse.storage.prepare_database - 400 - INFO - None - Applying schema 57/rooms_version_column.sql -2020-05-02 07:19:21,719 - synapse.storage.prepare_database - 404 - INFO - None - Applying engine-specific schema 57/rooms_version_column_2.sql.sqlite -2020-05-02 07:19:21,720 - synapse.storage.prepare_database - 404 - INFO - None - Applying engine-specific schema 57/rooms_version_column_3.sql.sqlite -2020-05-02 07:19:21,744 - synapse.storage.data_stores - 58 - INFO - None - Starting 'main' data store -2020-05-02 07:19:21,750 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - None - Searching for stream ordering 1 month ago -2020-05-02 07:19:21,750 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - None - Found stream ordering 1 month ago: it's 0 -2020-05-02 07:19:21,750 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - None - Searching for stream ordering 1 day ago -2020-05-02 07:19:21,750 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - None - Found stream ordering 1 day ago: it's 0 -2020-05-02 07:19:21,754 - synapse.storage.data_stores - 68 - INFO - None - Starting 'state' data store -2020-05-02 07:19:21,755 - synapse.storage.data_stores - 81 - INFO - None - Database 'master' prepared -2020-05-02 07:19:21,756 - synapse.server - 243 - INFO - None - Finished setting up. -2020-05-02 07:19:21,779 - synapse.app.homeserver - 110 - INFO - - Running -2020-05-02 07:19:21,779 - synapse.app.homeserver - 30 - INFO - - Set file limit to: 4096 -2020-05-02 07:19:21,781 - synapse.federation.federation_server - 766 - INFO - - Registering federation query handler for 'profile' -2020-05-02 07:19:21,782 - synapse.push.pusher - 42 - INFO - - email enable notifs: False -2020-05-02 07:19:21,785 - synapse.handlers.auth - 89 - INFO - - Extra password_providers: [] -2020-05-02 07:19:21,786 - synapse.push.mailer - 686 - INFO - - loading email templates ['sso_redirect_confirm.html'] from '/nix/store/8xrn90w8184gwac56bwm8dgxb2dl0qql-matrix-synapse-1.12.1/lib/python3.7/site-packages/synapse/res/templates' -2020-05-02 07:19:21,788 - synapse.federation.federation_server - 748 - INFO - - Registering federation EDU handler for 'm.device_list_update' -2020-05-02 07:19:21,788 - synapse.federation.federation_server - 766 - INFO - - Registering federation query handler for 'directory' -2020-05-02 07:19:21,798 - synapse.federation.federation_server - 748 - INFO - - Registering federation EDU handler for 'm.presence' -2020-05-02 07:19:21,798 - synapse.federation.federation_server - 748 - INFO - - Registering federation EDU handler for 'm.typing' -2020-05-02 07:19:21,800 - synapse.handlers.deactivate_account - 186 - INFO - user_parter_loop-0 - Starting user parter -2020-05-02 07:19:21,801 - synapse.federation.federation_server - 748 - INFO - - Registering federation EDU handler for 'm.receipt' -2020-05-02 07:19:21,802 - synapse.federation.federation_server - 748 - INFO - - Registering federation EDU handler for 'org.matrix.signing_key_update' -2020-05-02 07:19:21,802 - synapse.federation.federation_server - 766 - INFO - - Registering federation query handler for 'client_keys' -2020-05-02 07:19:21,802 - synapse.federation.federation_server - 748 - INFO - - Registering federation EDU handler for 'm.direct_to_device' -2020-05-02 07:19:21,812 - synapse.util.httpresourcetree - 46 - INFO - - Attaching to path b'/_matrix/client/api/v1' -2020-05-02 07:19:21,813 - synapse.util.httpresourcetree - 46 - INFO - - Attaching to path b'/_matrix/client/r0' -2020-05-02 07:19:21,813 - synapse.util.httpresourcetree - 46 - INFO - - Attaching to path b'/_matrix/client/unstable' -2020-05-02 07:19:21,813 - synapse.util.httpresourcetree - 46 - INFO - - Attaching to path b'/_matrix/client/v2_alpha' -2020-05-02 07:19:21,813 - synapse.util.httpresourcetree - 46 - INFO - - Attaching to path b'/_matrix/client/versions' -2020-05-02 07:19:21,813 - synapse.util.httpresourcetree - 46 - INFO - - Attaching to path b'/.well-known/matrix/client' -2020-05-02 07:19:21,813 - synapse.util.httpresourcetree - 46 - INFO - - Attaching to path b'/_synapse/admin' -2020-05-02 07:19:21,814 - synapse.util.httpresourcetree - 46 - INFO - - Attaching FilePath('/nix/store/8xrn90w8184gwac56bwm8dgxb2dl0qql-matrix-synapse-1.12.1/lib/python3.7/site-packages/synapse/static') to path b'/_matrix/static' -2020-05-02 07:19:21,814 - synapse.util.httpresourcetree - 46 - INFO - - Attaching to path b'/_matrix/media/r0' -2020-05-02 07:19:21,814 - synapse.util.httpresourcetree - 46 - INFO - - Attaching to path b'/_matrix/media/v1' -2020-05-02 07:19:21,814 - synapse.util.httpresourcetree - 46 - INFO - - Attaching to path b'/_matrix/federation' -2020-05-02 07:19:21,814 - synapse.util.httpresourcetree - 46 - INFO - - Attaching to path b'/_matrix/key/v2' -2020-05-02 07:19:21,814 - twisted - 192 - INFO - - SynapseSite starting on 39487 -2020-05-02 07:19:21,814 - synapse.app.homeserver - 163 - INFO - - Synapse now listening on TCP port 39487 -2020-05-02 07:19:21,840 - synapse.storage.background_updates - 102 - INFO - background_updates-0 - Starting background schema updates -2020-05-02 07:19:21,840 - synapse.handlers.deactivate_account - 196 - INFO - user_parter_loop-0 - User parter finished: stopping -2020-05-02 07:19:21,841 - synapse.push.pusherpool - 268 - INFO - start_pushers-0 - Started pushers -2020-05-02 07:19:22,843 - synapse.storage.background_updates - 211 - INFO - background_updates-0 - Starting update batch on background update 'users_set_deactivated_flag' -2020-05-02 07:19:22,861 - synapse.storage.background_updates - 253 - INFO - background_updates-0 - Running background update 'users_set_deactivated_flag'. Processed 0 items in 14ms. (total_rate=0/ms, current_rate=0/ms, total_updated=0, batch_size=100) -2020-05-02 07:19:23,862 - synapse.storage.background_updates - 211 - INFO - background_updates-0 - Starting update batch on background update 'current_state_events_membership' -2020-05-02 07:19:23,879 - synapse.storage.background_updates - 253 - INFO - background_updates-0 - Running background update 'current_state_events_membership'. Processed 0 items in 14ms. (total_rate=0/ms, current_rate=0/ms, total_updated=0, batch_size=100) -2020-05-02 07:19:24,880 - synapse.storage.background_updates - 211 - INFO - background_updates-0 - Starting update batch on background update 'devices_last_seen' -2020-05-02 07:19:24,898 - synapse.storage.background_updates - 253 - INFO - background_updates-0 - Running background update 'devices_last_seen'. Processed 0 items in 15ms. (total_rate=0/ms, current_rate=0/ms, total_updated=0, batch_size=100) -2020-05-02 07:19:25,899 - synapse.storage.background_updates - 211 - INFO - background_updates-0 - Starting update batch on background update 'event_store_labels' -2020-05-02 07:19:25,927 - synapse.storage.background_updates - 253 - INFO - background_updates-0 - Running background update 'event_store_labels'. Processed 0 items in 26ms. (total_rate=0/ms, current_rate=0/ms, total_updated=0, batch_size=100) -2020-05-02 07:19:26,929 - synapse.storage.background_updates - 211 - INFO - background_updates-0 - Starting update batch on background update 'redactions_received_ts' -2020-05-02 07:19:26,945 - synapse.storage.background_updates - 253 - INFO - background_updates-0 - Running background update 'redactions_received_ts'. Processed 0 items in 14ms. (total_rate=0/ms, current_rate=0/ms, total_updated=0, batch_size=100) -2020-05-02 07:19:27,947 - synapse.storage.background_updates - 211 - INFO - background_updates-0 - Starting update batch on background update 'redactions_have_censored_ts_idx' -2020-05-02 07:19:27,949 - synapse.storage.background_updates - 396 - INFO - background_updates-0 - Adding index redactions_have_censored_ts to redactions -2020-05-02 07:19:27,980 - synapse.storage.background_updates - 253 - INFO - background_updates-0 - Running background update 'redactions_have_censored_ts_idx'. Processed 1 items in 31ms. (total_rate=0/ms, current_rate=0/ms, total_updated=0, batch_size=100) -2020-05-02 07:19:28,982 - synapse.storage.background_updates - 211 - INFO - background_updates-0 - Starting update batch on background update 'remove_tombstoned_rooms_from_directory' -2020-05-02 07:19:28,999 - synapse.storage.background_updates - 253 - INFO - background_updates-0 - Running background update 'remove_tombstoned_rooms_from_directory'. Processed 0 items in 15ms. (total_rate=0/ms, current_rate=0/ms, total_updated=0, batch_size=100) -2020-05-02 07:19:30,000 - synapse.storage.background_updates - 211 - INFO - background_updates-0 - Starting update batch on background update 'room_membership_forgotten_idx' -2020-05-02 07:19:30,003 - synapse.storage.background_updates - 396 - INFO - background_updates-0 - Adding index room_memberships_user_room_forgotten to room_memberships -2020-05-02 07:19:30,034 - synapse.storage.background_updates - 253 - INFO - background_updates-0 - Running background update 'room_membership_forgotten_idx'. Processed 1 items in 32ms. (total_rate=0/ms, current_rate=0/ms, total_updated=0, batch_size=100) -2020-05-02 07:19:31,035 - synapse.storage.background_updates - 211 - INFO - background_updates-0 - Starting update batch on background update 'insert_room_retention' -2020-05-02 07:19:31,052 - synapse.storage.background_updates - 253 - INFO - background_updates-0 - Running background update 'insert_room_retention'. Processed 100 items in 14ms. (total_rate=0/ms, current_rate=0/ms, total_updated=0, batch_size=100) -2020-05-02 07:19:32,054 - synapse.storage.background_updates - 211 - INFO - background_updates-0 - Starting update batch on background update 'state_groups_room_id_idx' -2020-05-02 07:19:32,057 - synapse.storage.background_updates - 396 - INFO - background_updates-0 - Adding index state_groups_room_id_idx to state_groups -2020-05-02 07:19:32,088 - synapse.storage.background_updates - 253 - INFO - background_updates-0 - Running background update 'state_groups_room_id_idx'. Processed 1 items in 31ms. (total_rate=0/ms, current_rate=0/ms, total_updated=0, batch_size=100) -2020-05-02 07:19:33,090 - synapse.storage.background_updates - 211 - INFO - background_updates-0 - Starting update batch on background update 'populate_stats_process_rooms' -2020-05-02 07:19:33,107 - synapse.storage.background_updates - 253 - INFO - background_updates-0 - Running background update 'populate_stats_process_rooms'. Processed 1 items in 14ms. (total_rate=0/ms, current_rate=0/ms, total_updated=0, batch_size=100) -2020-05-02 07:19:34,108 - synapse.storage.background_updates - 211 - INFO - background_updates-0 - Starting update batch on background update 'add_rooms_room_version_column' -2020-05-02 07:19:34,126 - synapse.storage.background_updates - 253 - INFO - background_updates-0 - Running background update 'add_rooms_room_version_column'. Processed 100 items in 15ms. (total_rate=0/ms, current_rate=0/ms, total_updated=0, batch_size=100) -2020-05-02 07:19:35,129 - synapse.storage.background_updates - 211 - INFO - background_updates-0 - Starting update batch on background update 'populate_stats_process_users' -2020-05-02 07:19:35,145 - synapse.storage.background_updates - 253 - INFO - background_updates-0 - Running background update 'populate_stats_process_users'. Processed 1 items in 14ms. (total_rate=0/ms, current_rate=0/ms, total_updated=0, batch_size=100) -2020-05-02 07:19:36,146 - synapse.storage.background_updates - 211 - INFO - background_updates-0 - Starting update batch on background update 'delete_old_current_state_events' -2020-05-02 07:19:36,164 - synapse.storage.background_updates - 253 - INFO - background_updates-0 - Running background update 'delete_old_current_state_events'. Processed 100 items in 15ms. (total_rate=0/ms, current_rate=0/ms, total_updated=0, batch_size=100) -2020-05-02 07:19:37,167 - synapse.storage.background_updates - 116 - INFO - background_updates-0 - No more background updates to do. Unscheduling background update task. -2020-05-02 07:21:12,311 - twisted - 192 - INFO - None - Redirected stdout/stderr to logs -2020-05-02 07:21:12,311 - root - 257 - WARNING - None - ***** STARTING SERVER ***** -2020-05-02 07:21:12,311 - root - 258 - WARNING - None - Server /nix/store/8xrn90w8184gwac56bwm8dgxb2dl0qql-matrix-synapse-1.12.1/bin/homeserver version 1.12.1 -2020-05-02 07:21:12,311 - root - 259 - INFO - None - Server hostname: 192.168.0.86 -2020-05-02 07:21:12,311 - synapse.app.homeserver - 346 - INFO - None - Setting up server -2020-05-02 07:21:12,311 - synapse.server - 240 - INFO - None - Setting up. -2020-05-02 07:21:12,312 - synapse.storage.data_stores - 48 - INFO - None - Preparing database 'master'... -2020-05-02 07:21:12,314 - synapse.storage.prepare_database - 320 - INFO - None - Upgrading schema to v57 -2020-05-02 07:21:12,316 - synapse.storage.data_stores - 58 - INFO - None - Starting 'main' data store -2020-05-02 07:21:12,318 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - None - Searching for stream ordering 1 month ago -2020-05-02 07:21:12,318 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - None - Found stream ordering 1 month ago: it's 0 -2020-05-02 07:21:12,318 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - None - Searching for stream ordering 1 day ago -2020-05-02 07:21:12,318 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - None - Found stream ordering 1 day ago: it's 0 -2020-05-02 07:21:12,320 - synapse.storage.data_stores - 68 - INFO - None - Starting 'state' data store -2020-05-02 07:21:12,320 - synapse.storage.data_stores - 81 - INFO - None - Database 'master' prepared -2020-05-02 07:21:12,320 - synapse.server - 243 - INFO - None - Finished setting up. -2020-05-02 07:21:12,335 - synapse.app.homeserver - 110 - INFO - - Running -2020-05-02 07:21:12,335 - synapse.app.homeserver - 30 - INFO - - Set file limit to: 4096 -2020-05-02 07:21:12,337 - synapse.federation.federation_server - 766 - INFO - - Registering federation query handler for 'profile' -2020-05-02 07:21:12,338 - synapse.push.pusher - 42 - INFO - - email enable notifs: False -2020-05-02 07:21:12,341 - synapse.handlers.auth - 89 - INFO - - Extra password_providers: [] -2020-05-02 07:21:12,341 - synapse.push.mailer - 686 - INFO - - loading email templates ['sso_redirect_confirm.html'] from '/nix/store/8xrn90w8184gwac56bwm8dgxb2dl0qql-matrix-synapse-1.12.1/lib/python3.7/site-packages/synapse/res/templates' -2020-05-02 07:21:12,344 - synapse.federation.federation_server - 748 - INFO - - Registering federation EDU handler for 'm.device_list_update' -2020-05-02 07:21:12,344 - synapse.federation.federation_server - 766 - INFO - - Registering federation query handler for 'directory' -2020-05-02 07:21:12,352 - synapse.federation.federation_server - 748 - INFO - - Registering federation EDU handler for 'm.presence' -2020-05-02 07:21:12,352 - synapse.federation.federation_server - 748 - INFO - - Registering federation EDU handler for 'm.typing' -2020-05-02 07:21:12,354 - synapse.handlers.deactivate_account - 186 - INFO - user_parter_loop-0 - Starting user parter -2020-05-02 07:21:12,355 - synapse.federation.federation_server - 748 - INFO - - Registering federation EDU handler for 'm.receipt' -2020-05-02 07:21:12,355 - synapse.federation.federation_server - 748 - INFO - - Registering federation EDU handler for 'org.matrix.signing_key_update' -2020-05-02 07:21:12,355 - synapse.federation.federation_server - 766 - INFO - - Registering federation query handler for 'client_keys' -2020-05-02 07:21:12,356 - synapse.federation.federation_server - 748 - INFO - - Registering federation EDU handler for 'm.direct_to_device' -2020-05-02 07:21:12,366 - synapse.util.httpresourcetree - 46 - INFO - - Attaching to path b'/_matrix/client/api/v1' -2020-05-02 07:21:12,366 - synapse.util.httpresourcetree - 46 - INFO - - Attaching to path b'/_matrix/client/r0' -2020-05-02 07:21:12,366 - synapse.util.httpresourcetree - 46 - INFO - - Attaching to path b'/_matrix/client/unstable' -2020-05-02 07:21:12,366 - synapse.util.httpresourcetree - 46 - INFO - - Attaching to path b'/_matrix/client/v2_alpha' -2020-05-02 07:21:12,367 - synapse.util.httpresourcetree - 46 - INFO - - Attaching to path b'/_matrix/client/versions' -2020-05-02 07:21:12,367 - synapse.util.httpresourcetree - 46 - INFO - - Attaching to path b'/.well-known/matrix/client' -2020-05-02 07:21:12,367 - synapse.util.httpresourcetree - 46 - INFO - - Attaching to path b'/_synapse/admin' -2020-05-02 07:21:12,367 - synapse.util.httpresourcetree - 46 - INFO - - Attaching FilePath('/nix/store/8xrn90w8184gwac56bwm8dgxb2dl0qql-matrix-synapse-1.12.1/lib/python3.7/site-packages/synapse/static') to path b'/_matrix/static' -2020-05-02 07:21:12,367 - synapse.util.httpresourcetree - 46 - INFO - - Attaching to path b'/_matrix/media/r0' -2020-05-02 07:21:12,367 - synapse.util.httpresourcetree - 46 - INFO - - Attaching to path b'/_matrix/media/v1' -2020-05-02 07:21:12,367 - synapse.util.httpresourcetree - 46 - INFO - - Attaching to path b'/_matrix/federation' -2020-05-02 07:21:12,368 - synapse.util.httpresourcetree - 46 - INFO - - Attaching to path b'/_matrix/key/v2' -2020-05-02 07:21:12,368 - twisted - 192 - INFO - - SynapseSite starting on 39487 -2020-05-02 07:21:12,368 - synapse.app.homeserver - 163 - INFO - - Synapse now listening on TCP port 39487 -2020-05-02 07:21:12,393 - synapse.storage.background_updates - 102 - INFO - background_updates-0 - Starting background schema updates -2020-05-02 07:21:12,393 - synapse.handlers.deactivate_account - 196 - INFO - user_parter_loop-0 - User parter finished: stopping -2020-05-02 07:21:12,394 - synapse.push.pusherpool - 268 - INFO - start_pushers-0 - Started pushers -2020-05-02 07:21:13,396 - synapse.storage.background_updates - 116 - INFO - background_updates-0 - No more background updates to do. Unscheduling background update task. -2020-05-02 07:22:59,726 - synapse.handlers.auth - 323 - INFO - POST-0 - Auth completed with creds: {'m.login.dummy': True}. Client dict has keys: ['username', 'password'] -2020-05-02 07:22:59,794 - synapse.handlers.auth - 526 - INFO - POST-0 - Logging in user @a1e9066e-d062-4af6-9ee5-e8c069e344ba:192.168.0.86 on device FNJPXRYPIB -2020-05-02 07:22:59,817 - synapse.access.http.39487 - 302 - INFO - POST-0 - ::1 - 39487 - {None} Processed request: 0.093sec/0.001sec (0.019sec, 0.000sec) (0.007sec/0.060sec/11) 472B 200 "POST /_matrix/client/r0/register HTTP/1.1" "curl/7.58.0" [0 dbevts] -2020-05-02 07:23:00,005 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 07:23:00,538 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 07:23:00,715 - synapse.access.http.39487 - 302 - INFO - POST-1 - ::1 - 39487 - {@a1e9066e-d062-4af6-9ee5-e8c069e344ba:192.168.0.86} Processed request: 0.835sec/0.000sec (0.091sec, 0.011sec) (0.341sec/0.147sec/40) 126B 200 "POST /_matrix/client/r0/createRoom HTTP/1.1" "curl/7.58.0" [0 dbevts] -2020-05-02 07:23:00,797 - synapse.access.http.39487 - 302 - INFO - POST-2 - ::1 - 39487 - {@a1e9066e-d062-4af6-9ee5-e8c069e344ba:192.168.0.86} Processed request: 0.062sec/0.001sec (0.003sec, 0.000sec) (0.040sec/0.013sec/6) 54B 200 "POST /_matrix/client/r0/join/!qXGPZxkFUrZujySLCj:192.168.0.86 HTTP/1.1" "curl/7.58.0" [0 dbevts] -2020-05-02 07:23:00,879 - synapse.access.http.39487 - 302 - INFO - PUT-3 - ::1 - 39487 - {@a1e9066e-d062-4af6-9ee5-e8c069e344ba:192.168.0.86} Processed request: 0.048sec/0.001sec (0.009sec, 0.000sec) (0.002sec/0.001sec/2) 67B 200 "PUT /_matrix/client/r0/rooms/!qXGPZxkFUrZujySLCj:192.168.0.86/send/m.room.message/6432825e-924a-4af8-a8ad-00a2323515e4 HTTP/1.1" "curl/7.58.0" [0 dbevts] -2020-05-02 07:23:01,026 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 07:23:01,035 - synapse.access.http.39487 - 302 - INFO - GET-4 - ::1 - 39487 - {@a1e9066e-d062-4af6-9ee5-e8c069e344ba:192.168.0.86} Processed request: 0.122sec/0.000sec (0.022sec, 0.000sec) (0.045sec/0.030sec/19) 21309B 200 "GET /_matrix/client/r0/sync HTTP/1.1" "curl/7.58.0" [0 dbevts] -2020-05-02 07:23:01,077 - synapse.access.http.39487 - 302 - INFO - PUT-5 - ::1 - 39487 - {@a1e9066e-d062-4af6-9ee5-e8c069e344ba:192.168.0.86} Processed request: 0.023sec/0.001sec (0.000sec, 0.000sec) (0.001sec/0.000sec/2) 67B 200 "PUT /_matrix/client/r0/rooms/!qXGPZxkFUrZujySLCj:192.168.0.86/send/m.room.message/c3e7de47-3353-493f-87b3-36ee93c925a8 HTTP/1.1" "curl/7.58.0" [0 dbevts] -2020-05-02 07:23:01,127 - synapse.access.http.39487 - 302 - INFO - GET-6 - ::1 - 39487 - {@a1e9066e-d062-4af6-9ee5-e8c069e344ba:192.168.0.86} Processed request: 0.035sec/0.001sec (0.008sec, 0.000sec) (0.022sec/0.001sec/1) 1788B 200 "GET /_matrix/client/r0/sync?since=s8_3_0_1_1_1_1_2_1 HTTP/1.1" "curl/7.58.0" [0 dbevts] -2020-05-02 07:23:12,354 - synapse.handlers.presence - 256 - INFO - persist_presence_changes-0 - Persisting 1 unpersisted presence updates -2020-05-02 07:26:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-0 - Calling _generate_user_daily_visits -2020-05-02 07:26:42,323 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 07:31:12,325 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-0 - Searching for stream ordering 1 month ago -2020-05-02 07:31:12,327 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-0 - Found stream ordering 1 month ago: it's 2 -2020-05-02 07:31:12,327 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-0 - Searching for stream ordering 1 day ago -2020-05-02 07:31:12,328 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-0 - Found stream ordering 1 day ago: it's 2 -2020-05-02 07:31:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-1 - Calling _generate_user_daily_visits -2020-05-02 07:32:42,322 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 07:36:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-2 - Calling _generate_user_daily_visits -2020-05-02 07:38:27,322 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 07:41:12,320 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-1 - Searching for stream ordering 1 month ago -2020-05-02 07:41:12,320 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-1 - Found stream ordering 1 month ago: it's 2 -2020-05-02 07:41:12,320 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-1 - Searching for stream ordering 1 day ago -2020-05-02 07:41:12,321 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-1 - Found stream ordering 1 day ago: it's 2 -2020-05-02 07:41:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-3 - Calling _generate_user_daily_visits -2020-05-02 07:44:27,323 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 07:46:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-4 - Calling _generate_user_daily_visits -2020-05-02 07:50:12,360 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 07:51:12,321 - synapse.storage.data_stores.main.event_push_actions - 834 - INFO - rotate_notifs-0 - Rotating notifications -2020-05-02 07:51:12,328 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-2 - Searching for stream ordering 1 month ago -2020-05-02 07:51:12,330 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-2 - Found stream ordering 1 month ago: it's 2 -2020-05-02 07:51:12,330 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-2 - Searching for stream ordering 1 day ago -2020-05-02 07:51:12,331 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-2 - Found stream ordering 1 day ago: it's 2 -2020-05-02 07:51:12,333 - synapse.storage.data_stores.main.event_push_actions - 878 - INFO - rotate_notifs-0 - Rotating notifications up to: 2 -2020-05-02 07:51:12,334 - synapse.storage.data_stores.main.event_push_actions - 913 - INFO - rotate_notifs-0 - Rotating notifications, handling 0 rows -2020-05-02 07:51:12,336 - synapse.storage.data_stores.main.event_push_actions - 947 - INFO - rotate_notifs-0 - Rotating notifications, deleted 0 push actions -2020-05-02 07:51:12,355 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-5 - Calling _generate_user_daily_visits -2020-05-02 07:55:47,322 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 07:56:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-6 - Calling _generate_user_daily_visits -2020-05-02 08:01:12,323 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-3 - Searching for stream ordering 1 month ago -2020-05-02 08:01:12,325 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-3 - Found stream ordering 1 month ago: it's 2 -2020-05-02 08:01:12,326 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-3 - Searching for stream ordering 1 day ago -2020-05-02 08:01:12,326 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-3 - Found stream ordering 1 day ago: it's 2 -2020-05-02 08:01:12,335 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-7 - Calling _generate_user_daily_visits -2020-05-02 08:01:22,322 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 08:06:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-8 - Calling _generate_user_daily_visits -2020-05-02 08:07:12,362 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 08:07:12,370 - synapse.metrics - 464 - INFO - - Collecting gc 2 -2020-05-02 08:11:12,325 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-4 - Searching for stream ordering 1 month ago -2020-05-02 08:11:12,327 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-4 - Found stream ordering 1 month ago: it's 2 -2020-05-02 08:11:12,327 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-4 - Searching for stream ordering 1 day ago -2020-05-02 08:11:12,328 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-4 - Found stream ordering 1 day ago: it's 2 -2020-05-02 08:11:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-9 - Calling _generate_user_daily_visits -2020-05-02 08:12:02,320 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 08:16:12,335 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-10 - Calling _generate_user_daily_visits -2020-05-02 08:17:52,323 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 08:21:12,320 - synapse.storage.data_stores.main.event_push_actions - 834 - INFO - rotate_notifs-1 - Rotating notifications -2020-05-02 08:21:12,324 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-5 - Searching for stream ordering 1 month ago -2020-05-02 08:21:12,325 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-5 - Found stream ordering 1 month ago: it's 2 -2020-05-02 08:21:12,325 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-5 - Searching for stream ordering 1 day ago -2020-05-02 08:21:12,325 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-5 - Found stream ordering 1 day ago: it's 2 -2020-05-02 08:21:12,326 - synapse.storage.data_stores.main.event_push_actions - 878 - INFO - rotate_notifs-1 - Rotating notifications up to: 2 -2020-05-02 08:21:12,327 - synapse.storage.data_stores.main.event_push_actions - 913 - INFO - rotate_notifs-1 - Rotating notifications, handling 0 rows -2020-05-02 08:21:12,327 - synapse.storage.data_stores.main.event_push_actions - 947 - INFO - rotate_notifs-1 - Rotating notifications, deleted 0 push actions -2020-05-02 08:21:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-11 - Calling _generate_user_daily_visits -2020-05-02 08:23:27,322 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 08:26:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-12 - Calling _generate_user_daily_visits -2020-05-02 08:29:22,322 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 08:31:12,325 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-6 - Searching for stream ordering 1 month ago -2020-05-02 08:31:12,327 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-6 - Found stream ordering 1 month ago: it's 2 -2020-05-02 08:31:12,335 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-6 - Searching for stream ordering 1 day ago -2020-05-02 08:31:12,337 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-6 - Found stream ordering 1 day ago: it's 2 -2020-05-02 08:31:12,341 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-13 - Calling _generate_user_daily_visits -2020-05-02 08:35:17,323 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 08:36:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-14 - Calling _generate_user_daily_visits -2020-05-02 08:41:07,322 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 08:41:12,324 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-7 - Searching for stream ordering 1 month ago -2020-05-02 08:41:12,326 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-7 - Found stream ordering 1 month ago: it's 2 -2020-05-02 08:41:12,326 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-7 - Searching for stream ordering 1 day ago -2020-05-02 08:41:12,327 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-7 - Found stream ordering 1 day ago: it's 2 -2020-05-02 08:41:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-15 - Calling _generate_user_daily_visits -2020-05-02 08:46:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-16 - Calling _generate_user_daily_visits -2020-05-02 08:46:52,323 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 08:51:12,321 - synapse.storage.data_stores.main.event_push_actions - 834 - INFO - rotate_notifs-2 - Rotating notifications -2020-05-02 08:51:12,327 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-8 - Searching for stream ordering 1 month ago -2020-05-02 08:51:12,329 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-8 - Found stream ordering 1 month ago: it's 2 -2020-05-02 08:51:12,329 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-8 - Searching for stream ordering 1 day ago -2020-05-02 08:51:12,330 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-8 - Found stream ordering 1 day ago: it's 2 -2020-05-02 08:51:12,332 - synapse.storage.data_stores.main.event_push_actions - 878 - INFO - rotate_notifs-2 - Rotating notifications up to: 2 -2020-05-02 08:51:12,333 - synapse.storage.data_stores.main.event_push_actions - 913 - INFO - rotate_notifs-2 - Rotating notifications, handling 0 rows -2020-05-02 08:51:12,334 - synapse.storage.data_stores.main.event_push_actions - 947 - INFO - rotate_notifs-2 - Rotating notifications, deleted 0 push actions -2020-05-02 08:51:12,341 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-17 - Calling _generate_user_daily_visits -2020-05-02 08:52:42,322 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 08:56:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-18 - Calling _generate_user_daily_visits -2020-05-02 08:58:37,320 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 09:01:12,321 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-9 - Searching for stream ordering 1 month ago -2020-05-02 09:01:12,322 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-9 - Found stream ordering 1 month ago: it's 2 -2020-05-02 09:01:12,322 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-9 - Searching for stream ordering 1 day ago -2020-05-02 09:01:12,322 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-9 - Found stream ordering 1 day ago: it's 2 -2020-05-02 09:01:12,335 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-19 - Calling _generate_user_daily_visits -2020-05-02 09:04:17,323 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 09:06:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-20 - Calling _generate_user_daily_visits -2020-05-02 09:10:12,362 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 09:10:12,370 - synapse.metrics - 464 - INFO - - Collecting gc 2 -2020-05-02 09:11:12,320 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-10 - Searching for stream ordering 1 month ago -2020-05-02 09:11:12,321 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-10 - Found stream ordering 1 month ago: it's 2 -2020-05-02 09:11:12,321 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-10 - Searching for stream ordering 1 day ago -2020-05-02 09:11:12,321 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-10 - Found stream ordering 1 day ago: it's 2 -2020-05-02 09:11:12,335 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-21 - Calling _generate_user_daily_visits -2020-05-02 09:15:07,323 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 09:16:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-22 - Calling _generate_user_daily_visits -2020-05-02 09:20:52,322 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 09:21:12,321 - synapse.storage.data_stores.main.event_push_actions - 834 - INFO - rotate_notifs-3 - Rotating notifications -2020-05-02 09:21:12,324 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-11 - Searching for stream ordering 1 month ago -2020-05-02 09:21:12,325 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-11 - Found stream ordering 1 month ago: it's 2 -2020-05-02 09:21:12,325 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-11 - Searching for stream ordering 1 day ago -2020-05-02 09:21:12,325 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-11 - Found stream ordering 1 day ago: it's 2 -2020-05-02 09:21:12,325 - synapse.storage.data_stores.main.event_push_actions - 878 - INFO - rotate_notifs-3 - Rotating notifications up to: 2 -2020-05-02 09:21:12,326 - synapse.storage.data_stores.main.event_push_actions - 913 - INFO - rotate_notifs-3 - Rotating notifications, handling 0 rows -2020-05-02 09:21:12,326 - synapse.storage.data_stores.main.event_push_actions - 947 - INFO - rotate_notifs-3 - Rotating notifications, deleted 0 push actions -2020-05-02 09:21:12,338 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-23 - Calling _generate_user_daily_visits -2020-05-02 09:26:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-24 - Calling _generate_user_daily_visits -2020-05-02 09:26:12,362 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 09:31:12,325 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-12 - Searching for stream ordering 1 month ago -2020-05-02 09:31:12,326 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-12 - Found stream ordering 1 month ago: it's 2 -2020-05-02 09:31:12,327 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-12 - Searching for stream ordering 1 day ago -2020-05-02 09:31:12,328 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-12 - Found stream ordering 1 day ago: it's 2 -2020-05-02 09:31:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-25 - Calling _generate_user_daily_visits -2020-05-02 09:32:12,322 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 09:36:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-26 - Calling _generate_user_daily_visits -2020-05-02 09:38:07,323 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 09:41:12,320 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-13 - Searching for stream ordering 1 month ago -2020-05-02 09:41:12,321 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-13 - Found stream ordering 1 month ago: it's 2 -2020-05-02 09:41:12,321 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-13 - Searching for stream ordering 1 day ago -2020-05-02 09:41:12,321 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-13 - Found stream ordering 1 day ago: it's 2 -2020-05-02 09:41:12,335 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-27 - Calling _generate_user_daily_visits -2020-05-02 09:43:57,323 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 09:46:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-28 - Calling _generate_user_daily_visits -2020-05-02 09:49:42,322 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 09:51:12,320 - synapse.storage.data_stores.main.event_push_actions - 834 - INFO - rotate_notifs-4 - Rotating notifications -2020-05-02 09:51:12,326 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-14 - Searching for stream ordering 1 month ago -2020-05-02 09:51:12,328 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-14 - Found stream ordering 1 month ago: it's 2 -2020-05-02 09:51:12,328 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-14 - Searching for stream ordering 1 day ago -2020-05-02 09:51:12,329 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-14 - Found stream ordering 1 day ago: it's 2 -2020-05-02 09:51:12,331 - synapse.storage.data_stores.main.event_push_actions - 878 - INFO - rotate_notifs-4 - Rotating notifications up to: 2 -2020-05-02 09:51:12,332 - synapse.storage.data_stores.main.event_push_actions - 913 - INFO - rotate_notifs-4 - Rotating notifications, handling 0 rows -2020-05-02 09:51:12,333 - synapse.storage.data_stores.main.event_push_actions - 947 - INFO - rotate_notifs-4 - Rotating notifications, deleted 0 push actions -2020-05-02 09:51:12,341 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-29 - Calling _generate_user_daily_visits -2020-05-02 09:55:27,323 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 09:56:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-30 - Calling _generate_user_daily_visits -2020-05-02 10:01:12,322 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 10:01:12,326 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-15 - Searching for stream ordering 1 month ago -2020-05-02 10:01:12,328 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-15 - Found stream ordering 1 month ago: it's 2 -2020-05-02 10:01:12,328 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-15 - Searching for stream ordering 1 day ago -2020-05-02 10:01:12,329 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-15 - Found stream ordering 1 day ago: it's 2 -2020-05-02 10:01:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-31 - Calling _generate_user_daily_visits -2020-05-02 10:06:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-32 - Calling _generate_user_daily_visits -2020-05-02 10:06:57,322 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 10:11:12,325 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-16 - Searching for stream ordering 1 month ago -2020-05-02 10:11:12,327 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-16 - Found stream ordering 1 month ago: it's 2 -2020-05-02 10:11:12,327 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-16 - Searching for stream ordering 1 day ago -2020-05-02 10:11:12,328 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-16 - Found stream ordering 1 day ago: it's 2 -2020-05-02 10:11:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-33 - Calling _generate_user_daily_visits -2020-05-02 10:12:52,319 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 10:12:52,354 - synapse.metrics - 464 - INFO - - Collecting gc 2 -2020-05-02 10:16:12,335 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-34 - Calling _generate_user_daily_visits -2020-05-02 10:17:37,322 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 10:21:12,322 - synapse.storage.data_stores.main.event_push_actions - 834 - INFO - rotate_notifs-5 - Rotating notifications -2020-05-02 10:21:12,325 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-17 - Searching for stream ordering 1 month ago -2020-05-02 10:21:12,326 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-17 - Found stream ordering 1 month ago: it's 2 -2020-05-02 10:21:12,326 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-17 - Searching for stream ordering 1 day ago -2020-05-02 10:21:12,326 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-17 - Found stream ordering 1 day ago: it's 2 -2020-05-02 10:21:12,326 - synapse.storage.data_stores.main.event_push_actions - 878 - INFO - rotate_notifs-5 - Rotating notifications up to: 2 -2020-05-02 10:21:12,327 - synapse.storage.data_stores.main.event_push_actions - 913 - INFO - rotate_notifs-5 - Rotating notifications, handling 0 rows -2020-05-02 10:21:12,327 - synapse.storage.data_stores.main.event_push_actions - 947 - INFO - rotate_notifs-5 - Rotating notifications, deleted 0 push actions -2020-05-02 10:21:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-35 - Calling _generate_user_daily_visits -2020-05-02 10:23:12,321 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 10:26:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-36 - Calling _generate_user_daily_visits -2020-05-02 10:29:07,322 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 10:31:12,324 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-18 - Searching for stream ordering 1 month ago -2020-05-02 10:31:12,326 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-18 - Found stream ordering 1 month ago: it's 2 -2020-05-02 10:31:12,327 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-18 - Searching for stream ordering 1 day ago -2020-05-02 10:31:12,327 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-18 - Found stream ordering 1 day ago: it's 2 -2020-05-02 10:31:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-37 - Calling _generate_user_daily_visits -2020-05-02 10:35:02,323 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 10:36:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-38 - Calling _generate_user_daily_visits -2020-05-02 10:40:47,323 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 10:41:12,323 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-19 - Searching for stream ordering 1 month ago -2020-05-02 10:41:12,325 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-19 - Found stream ordering 1 month ago: it's 2 -2020-05-02 10:41:12,326 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-19 - Searching for stream ordering 1 day ago -2020-05-02 10:41:12,326 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-19 - Found stream ordering 1 day ago: it's 2 -2020-05-02 10:41:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-39 - Calling _generate_user_daily_visits -2020-05-02 10:46:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-40 - Calling _generate_user_daily_visits -2020-05-02 10:46:37,323 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 10:51:12,321 - synapse.storage.data_stores.main.event_push_actions - 834 - INFO - rotate_notifs-6 - Rotating notifications -2020-05-02 10:51:12,325 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-20 - Searching for stream ordering 1 month ago -2020-05-02 10:51:12,325 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-20 - Found stream ordering 1 month ago: it's 2 -2020-05-02 10:51:12,326 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-20 - Searching for stream ordering 1 day ago -2020-05-02 10:51:12,326 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-20 - Found stream ordering 1 day ago: it's 2 -2020-05-02 10:51:12,326 - synapse.storage.data_stores.main.event_push_actions - 878 - INFO - rotate_notifs-6 - Rotating notifications up to: 2 -2020-05-02 10:51:12,326 - synapse.storage.data_stores.main.event_push_actions - 913 - INFO - rotate_notifs-6 - Rotating notifications, handling 0 rows -2020-05-02 10:51:12,327 - synapse.storage.data_stores.main.event_push_actions - 947 - INFO - rotate_notifs-6 - Rotating notifications, deleted 0 push actions -2020-05-02 10:51:12,335 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-41 - Calling _generate_user_daily_visits -2020-05-02 10:52:22,322 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 10:56:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-42 - Calling _generate_user_daily_visits -2020-05-02 10:58:17,322 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 11:01:12,326 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-21 - Searching for stream ordering 1 month ago -2020-05-02 11:01:12,328 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-21 - Found stream ordering 1 month ago: it's 2 -2020-05-02 11:01:12,336 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-21 - Searching for stream ordering 1 day ago -2020-05-02 11:01:12,337 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-21 - Found stream ordering 1 day ago: it's 2 -2020-05-02 11:01:12,342 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-43 - Calling _generate_user_daily_visits -2020-05-02 11:04:12,361 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 11:06:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-44 - Calling _generate_user_daily_visits -2020-05-02 11:10:02,324 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 11:11:12,321 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-22 - Searching for stream ordering 1 month ago -2020-05-02 11:11:12,321 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-22 - Found stream ordering 1 month ago: it's 2 -2020-05-02 11:11:12,321 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-22 - Searching for stream ordering 1 day ago -2020-05-02 11:11:12,322 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-22 - Found stream ordering 1 day ago: it's 2 -2020-05-02 11:11:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-45 - Calling _generate_user_daily_visits -2020-05-02 11:15:57,323 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 11:15:57,326 - synapse.metrics - 464 - INFO - - Collecting gc 2 -2020-05-02 11:16:12,337 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-46 - Calling _generate_user_daily_visits -2020-05-02 11:20:47,323 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 11:21:12,321 - synapse.storage.data_stores.main.event_push_actions - 834 - INFO - rotate_notifs-7 - Rotating notifications -2020-05-02 11:21:12,328 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-23 - Searching for stream ordering 1 month ago -2020-05-02 11:21:12,330 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-23 - Found stream ordering 1 month ago: it's 2 -2020-05-02 11:21:12,331 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-23 - Searching for stream ordering 1 day ago -2020-05-02 11:21:12,332 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-23 - Found stream ordering 1 day ago: it's 2 -2020-05-02 11:21:12,334 - synapse.storage.data_stores.main.event_push_actions - 878 - INFO - rotate_notifs-7 - Rotating notifications up to: 2 -2020-05-02 11:21:12,337 - synapse.storage.data_stores.main.event_push_actions - 913 - INFO - rotate_notifs-7 - Rotating notifications, handling 0 rows -2020-05-02 11:21:12,340 - synapse.storage.data_stores.main.event_push_actions - 947 - INFO - rotate_notifs-7 - Rotating notifications, deleted 0 push actions -2020-05-02 11:21:12,352 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-47 - Calling _generate_user_daily_visits -2020-05-02 11:26:12,322 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 11:26:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-48 - Calling _generate_user_daily_visits -2020-05-02 11:31:12,324 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-24 - Searching for stream ordering 1 month ago -2020-05-02 11:31:12,326 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-24 - Found stream ordering 1 month ago: it's 2 -2020-05-02 11:31:12,326 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-24 - Searching for stream ordering 1 day ago -2020-05-02 11:31:12,327 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-24 - Found stream ordering 1 day ago: it's 2 -2020-05-02 11:31:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-49 - Calling _generate_user_daily_visits -2020-05-02 11:31:52,323 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 11:36:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-50 - Calling _generate_user_daily_visits -2020-05-02 11:37:47,322 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 11:41:12,325 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-25 - Searching for stream ordering 1 month ago -2020-05-02 11:41:12,327 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-25 - Found stream ordering 1 month ago: it's 2 -2020-05-02 11:41:12,328 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-25 - Searching for stream ordering 1 day ago -2020-05-02 11:41:12,328 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-25 - Found stream ordering 1 day ago: it's 2 -2020-05-02 11:41:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-51 - Calling _generate_user_daily_visits -2020-05-02 11:43:47,322 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 11:46:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-52 - Calling _generate_user_daily_visits -2020-05-02 11:49:42,320 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 11:51:12,319 - synapse.storage.data_stores.main.event_push_actions - 834 - INFO - rotate_notifs-8 - Rotating notifications -2020-05-02 11:51:12,320 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-26 - Searching for stream ordering 1 month ago -2020-05-02 11:51:12,320 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-26 - Found stream ordering 1 month ago: it's 2 -2020-05-02 11:51:12,320 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-26 - Searching for stream ordering 1 day ago -2020-05-02 11:51:12,320 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-26 - Found stream ordering 1 day ago: it's 2 -2020-05-02 11:51:12,321 - synapse.storage.data_stores.main.event_push_actions - 878 - INFO - rotate_notifs-8 - Rotating notifications up to: 2 -2020-05-02 11:51:12,321 - synapse.storage.data_stores.main.event_push_actions - 913 - INFO - rotate_notifs-8 - Rotating notifications, handling 0 rows -2020-05-02 11:51:12,321 - synapse.storage.data_stores.main.event_push_actions - 947 - INFO - rotate_notifs-8 - Rotating notifications, deleted 0 push actions -2020-05-02 11:51:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-53 - Calling _generate_user_daily_visits -2020-05-02 11:55:22,323 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 11:56:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-54 - Calling _generate_user_daily_visits -2020-05-02 12:01:12,323 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 12:01:12,325 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-27 - Searching for stream ordering 1 month ago -2020-05-02 12:01:12,325 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-27 - Found stream ordering 1 month ago: it's 2 -2020-05-02 12:01:12,325 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-27 - Searching for stream ordering 1 day ago -2020-05-02 12:01:12,325 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-27 - Found stream ordering 1 day ago: it's 2 -2020-05-02 12:01:12,335 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-55 - Calling _generate_user_daily_visits -2020-05-02 12:06:12,337 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-56 - Calling _generate_user_daily_visits -2020-05-02 12:07:02,322 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 12:11:12,324 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-28 - Searching for stream ordering 1 month ago -2020-05-02 12:11:12,326 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-28 - Found stream ordering 1 month ago: it's 2 -2020-05-02 12:11:12,326 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-28 - Searching for stream ordering 1 day ago -2020-05-02 12:11:12,327 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-28 - Found stream ordering 1 day ago: it's 2 -2020-05-02 12:11:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-57 - Calling _generate_user_daily_visits -2020-05-02 12:12:57,323 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 12:16:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-58 - Calling _generate_user_daily_visits -2020-05-02 12:18:57,322 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 12:18:57,324 - synapse.metrics - 464 - INFO - - Collecting gc 2 -2020-05-02 12:21:12,322 - synapse.storage.data_stores.main.event_push_actions - 834 - INFO - rotate_notifs-9 - Rotating notifications -2020-05-02 12:21:12,330 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-29 - Searching for stream ordering 1 month ago -2020-05-02 12:21:12,332 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-29 - Found stream ordering 1 month ago: it's 2 -2020-05-02 12:21:12,332 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-29 - Searching for stream ordering 1 day ago -2020-05-02 12:21:12,333 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-29 - Found stream ordering 1 day ago: it's 2 -2020-05-02 12:21:12,337 - synapse.storage.data_stores.main.event_push_actions - 878 - INFO - rotate_notifs-9 - Rotating notifications up to: 2 -2020-05-02 12:21:12,339 - synapse.storage.data_stores.main.event_push_actions - 913 - INFO - rotate_notifs-9 - Rotating notifications, handling 0 rows -2020-05-02 12:21:12,341 - synapse.storage.data_stores.main.event_push_actions - 947 - INFO - rotate_notifs-9 - Rotating notifications, deleted 0 push actions -2020-05-02 12:21:12,350 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-59 - Calling _generate_user_daily_visits -2020-05-02 12:23:22,323 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 12:26:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-60 - Calling _generate_user_daily_visits -2020-05-02 12:29:17,322 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 12:31:12,320 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-30 - Searching for stream ordering 1 month ago -2020-05-02 12:31:12,321 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-30 - Found stream ordering 1 month ago: it's 2 -2020-05-02 12:31:12,321 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-30 - Searching for stream ordering 1 day ago -2020-05-02 12:31:12,321 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-30 - Found stream ordering 1 day ago: it's 2 -2020-05-02 12:31:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-61 - Calling _generate_user_daily_visits -2020-05-02 12:35:12,361 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 12:36:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-62 - Calling _generate_user_daily_visits -2020-05-02 12:41:12,324 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 12:41:12,326 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-31 - Searching for stream ordering 1 month ago -2020-05-02 12:41:12,328 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-31 - Found stream ordering 1 month ago: it's 2 -2020-05-02 12:41:12,329 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-31 - Searching for stream ordering 1 day ago -2020-05-02 12:41:12,329 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-31 - Found stream ordering 1 day ago: it's 2 -2020-05-02 12:41:12,335 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-63 - Calling _generate_user_daily_visits -2020-05-02 12:46:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-64 - Calling _generate_user_daily_visits -2020-05-02 12:46:52,322 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 12:51:12,322 - synapse.storage.data_stores.main.event_push_actions - 834 - INFO - rotate_notifs-10 - Rotating notifications -2020-05-02 12:51:12,325 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-32 - Searching for stream ordering 1 month ago -2020-05-02 12:51:12,325 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-32 - Found stream ordering 1 month ago: it's 2 -2020-05-02 12:51:12,325 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-32 - Searching for stream ordering 1 day ago -2020-05-02 12:51:12,326 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-32 - Found stream ordering 1 day ago: it's 2 -2020-05-02 12:51:12,326 - synapse.storage.data_stores.main.event_push_actions - 878 - INFO - rotate_notifs-10 - Rotating notifications up to: 2 -2020-05-02 12:51:12,326 - synapse.storage.data_stores.main.event_push_actions - 913 - INFO - rotate_notifs-10 - Rotating notifications, handling 0 rows -2020-05-02 12:51:12,326 - synapse.storage.data_stores.main.event_push_actions - 947 - INFO - rotate_notifs-10 - Rotating notifications, deleted 0 push actions -2020-05-02 12:51:12,335 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-65 - Calling _generate_user_daily_visits -2020-05-02 12:52:37,323 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 12:56:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-66 - Calling _generate_user_daily_visits -2020-05-02 12:58:32,322 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 13:01:12,324 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-33 - Searching for stream ordering 1 month ago -2020-05-02 13:01:12,326 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-33 - Found stream ordering 1 month ago: it's 2 -2020-05-02 13:01:12,326 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-33 - Searching for stream ordering 1 day ago -2020-05-02 13:01:12,327 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-33 - Found stream ordering 1 day ago: it's 2 -2020-05-02 13:01:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-67 - Calling _generate_user_daily_visits -2020-05-02 13:04:27,323 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 13:06:12,337 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-68 - Calling _generate_user_daily_visits -2020-05-02 13:10:22,323 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 13:11:12,324 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-34 - Searching for stream ordering 1 month ago -2020-05-02 13:11:12,326 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-34 - Found stream ordering 1 month ago: it's 2 -2020-05-02 13:11:12,326 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-34 - Searching for stream ordering 1 day ago -2020-05-02 13:11:12,327 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-34 - Found stream ordering 1 day ago: it's 2 -2020-05-02 13:11:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-69 - Calling _generate_user_daily_visits -2020-05-02 13:16:12,324 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 13:16:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-70 - Calling _generate_user_daily_visits -2020-05-02 13:21:12,321 - synapse.storage.data_stores.main.event_push_actions - 834 - INFO - rotate_notifs-11 - Rotating notifications -2020-05-02 13:21:12,325 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-35 - Searching for stream ordering 1 month ago -2020-05-02 13:21:12,325 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-35 - Found stream ordering 1 month ago: it's 2 -2020-05-02 13:21:12,325 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-35 - Searching for stream ordering 1 day ago -2020-05-02 13:21:12,325 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-35 - Found stream ordering 1 day ago: it's 2 -2020-05-02 13:21:12,326 - synapse.storage.data_stores.main.event_push_actions - 878 - INFO - rotate_notifs-11 - Rotating notifications up to: 2 -2020-05-02 13:21:12,326 - synapse.storage.data_stores.main.event_push_actions - 913 - INFO - rotate_notifs-11 - Rotating notifications, handling 0 rows -2020-05-02 13:21:12,326 - synapse.storage.data_stores.main.event_push_actions - 947 - INFO - rotate_notifs-11 - Rotating notifications, deleted 0 push actions -2020-05-02 13:21:12,335 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-71 - Calling _generate_user_daily_visits -2020-05-02 13:21:32,322 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 13:21:32,325 - synapse.metrics - 464 - INFO - - Collecting gc 2 -2020-05-02 13:26:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-72 - Calling _generate_user_daily_visits -2020-05-02 13:26:12,339 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 13:31:12,324 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-36 - Searching for stream ordering 1 month ago -2020-05-02 13:31:12,326 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-36 - Found stream ordering 1 month ago: it's 2 -2020-05-02 13:31:12,327 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-36 - Searching for stream ordering 1 day ago -2020-05-02 13:31:12,328 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-36 - Found stream ordering 1 day ago: it's 2 -2020-05-02 13:31:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-73 - Calling _generate_user_daily_visits -2020-05-02 13:31:52,322 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 13:36:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-74 - Calling _generate_user_daily_visits -2020-05-02 13:37:42,323 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 13:41:12,324 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-37 - Searching for stream ordering 1 month ago -2020-05-02 13:41:12,326 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-37 - Found stream ordering 1 month ago: it's 2 -2020-05-02 13:41:12,326 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-37 - Searching for stream ordering 1 day ago -2020-05-02 13:41:12,327 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-37 - Found stream ordering 1 day ago: it's 2 -2020-05-02 13:41:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-75 - Calling _generate_user_daily_visits -2020-05-02 13:43:37,322 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 13:46:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-76 - Calling _generate_user_daily_visits -2020-05-02 13:49:32,322 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 13:51:12,321 - synapse.storage.data_stores.main.event_push_actions - 834 - INFO - rotate_notifs-12 - Rotating notifications -2020-05-02 13:51:12,327 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-38 - Searching for stream ordering 1 month ago -2020-05-02 13:51:12,329 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-38 - Found stream ordering 1 month ago: it's 2 -2020-05-02 13:51:12,329 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-38 - Searching for stream ordering 1 day ago -2020-05-02 13:51:12,330 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-38 - Found stream ordering 1 day ago: it's 2 -2020-05-02 13:51:12,332 - synapse.storage.data_stores.main.event_push_actions - 878 - INFO - rotate_notifs-12 - Rotating notifications up to: 2 -2020-05-02 13:51:12,333 - synapse.storage.data_stores.main.event_push_actions - 913 - INFO - rotate_notifs-12 - Rotating notifications, handling 0 rows -2020-05-02 13:51:12,334 - synapse.storage.data_stores.main.event_push_actions - 947 - INFO - rotate_notifs-12 - Rotating notifications, deleted 0 push actions -2020-05-02 13:51:12,341 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-77 - Calling _generate_user_daily_visits -2020-05-02 13:55:22,321 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 13:56:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-78 - Calling _generate_user_daily_visits -2020-05-02 14:01:12,320 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 14:01:12,320 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-39 - Searching for stream ordering 1 month ago -2020-05-02 14:01:12,321 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-39 - Found stream ordering 1 month ago: it's 2 -2020-05-02 14:01:12,321 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-39 - Searching for stream ordering 1 day ago -2020-05-02 14:01:12,321 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-39 - Found stream ordering 1 day ago: it's 2 -2020-05-02 14:01:12,335 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-79 - Calling _generate_user_daily_visits -2020-05-02 14:06:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-80 - Calling _generate_user_daily_visits -2020-05-02 14:06:52,323 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 14:11:12,325 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-40 - Searching for stream ordering 1 month ago -2020-05-02 14:11:12,327 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-40 - Found stream ordering 1 month ago: it's 2 -2020-05-02 14:11:12,327 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-40 - Searching for stream ordering 1 day ago -2020-05-02 14:11:12,328 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-40 - Found stream ordering 1 day ago: it's 2 -2020-05-02 14:11:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-81 - Calling _generate_user_daily_visits -2020-05-02 14:12:42,319 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 14:16:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-82 - Calling _generate_user_daily_visits -2020-05-02 14:18:37,323 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 14:21:12,322 - synapse.storage.data_stores.main.event_push_actions - 834 - INFO - rotate_notifs-13 - Rotating notifications -2020-05-02 14:21:12,329 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-41 - Searching for stream ordering 1 month ago -2020-05-02 14:21:12,331 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-41 - Found stream ordering 1 month ago: it's 2 -2020-05-02 14:21:12,331 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-41 - Searching for stream ordering 1 day ago -2020-05-02 14:21:12,332 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-41 - Found stream ordering 1 day ago: it's 2 -2020-05-02 14:21:12,334 - synapse.storage.data_stores.main.event_push_actions - 878 - INFO - rotate_notifs-13 - Rotating notifications up to: 2 -2020-05-02 14:21:12,336 - synapse.storage.data_stores.main.event_push_actions - 913 - INFO - rotate_notifs-13 - Rotating notifications, handling 0 rows -2020-05-02 14:21:12,337 - synapse.storage.data_stores.main.event_push_actions - 947 - INFO - rotate_notifs-13 - Rotating notifications, deleted 0 push actions -2020-05-02 14:21:12,351 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-83 - Calling _generate_user_daily_visits -2020-05-02 14:24:02,323 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 14:24:02,326 - synapse.metrics - 464 - INFO - - Collecting gc 2 -2020-05-02 14:26:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-84 - Calling _generate_user_daily_visits -2020-05-02 14:28:42,321 - synapse.metrics - 464 - INFO - - Collecting gc 1 -2020-05-02 14:31:12,323 - synapse.storage.data_stores.main.event_push_actions - 499 - INFO - event_push_action_stream_orderings-42 - Searching for stream ordering 1 month ago -2020-05-02 14:31:12,326 - synapse.storage.data_stores.main.event_push_actions - 504 - INFO - event_push_action_stream_orderings-42 - Found stream ordering 1 month ago: it's 2 -2020-05-02 14:31:12,327 - synapse.storage.data_stores.main.event_push_actions - 506 - INFO - event_push_action_stream_orderings-42 - Searching for stream ordering 1 day ago -2020-05-02 14:31:12,327 - synapse.storage.data_stores.main.event_push_actions - 511 - INFO - event_push_action_stream_orderings-42 - Found stream ordering 1 day ago: it's 2 -2020-05-02 14:31:12,336 - synapse.storage.data_stores.main - 425 - INFO - generate_user_daily_visits-85 - Calling _generate_user_daily_visits -2020-05-02 14:34:22,322 - synapse.metrics - 464 - INFO - - Collecting gc 1