From cd841f38eb0f4b588e0b3ef8b1665b50c999fe29 Mon Sep 17 00:00:00 2001 From: bel Date: Sat, 2 May 2020 14:02:00 -0600 Subject: [PATCH] Whoa boy rust is a problem child --- .gitignore | 1 + secert-hitler/Cargo.lock | 84 +++ secert-hitler/Cargo.toml | 10 + secert-hitler/src/config.rs | 1 + .../src/controller/gamemaster/gamemaster.rs | 23 + secert-hitler/src/controller/mod.rs | 0 secert-hitler/src/controller/state/event.rs | 19 + .../src/controller/state/mockroom.rs | 134 +++++ .../src/controller/state/mockrooms.rs | 89 +++ secert-hitler/src/controller/state/room.rs | 14 + secert-hitler/src/controller/state/rooms.rs | 13 + secert-hitler/src/main.rs | 11 + secert-hitler/src/model/mod.rs | 0 secert-hitler/src/view/mod.rs | 0 testdata/matrix-sandbox/build.sh | 19 +- testdata/matrix-sandbox/homeserver.log | 551 ++++++++++++++++++ testdata/matrix-sandbox/synapse-test-log.yaml | 43 ++ testdata/matrix-sandbox/synapse-test.key | 1 + 18 files changed, 1005 insertions(+), 8 deletions(-) create mode 100644 secert-hitler/Cargo.lock create mode 100644 secert-hitler/Cargo.toml create mode 100644 secert-hitler/src/config.rs create mode 100644 secert-hitler/src/controller/gamemaster/gamemaster.rs create mode 100644 secert-hitler/src/controller/mod.rs create mode 100644 secert-hitler/src/controller/state/event.rs create mode 100644 secert-hitler/src/controller/state/mockroom.rs create mode 100644 secert-hitler/src/controller/state/mockrooms.rs create mode 100644 secert-hitler/src/controller/state/room.rs create mode 100644 secert-hitler/src/controller/state/rooms.rs create mode 100644 secert-hitler/src/main.rs create mode 100644 secert-hitler/src/model/mod.rs create mode 100644 secert-hitler/src/view/mod.rs create mode 100644 testdata/matrix-sandbox/homeserver.log create mode 100644 testdata/matrix-sandbox/synapse-test-log.yaml create mode 100644 testdata/matrix-sandbox/synapse-test.key diff --git a/.gitignore b/.gitignore index d20ab8d..efd4a88 100644 --- a/.gitignore +++ b/.gitignore @@ -1,2 +1,3 @@ **/*.sw* **/*.db +**/target diff --git a/secert-hitler/Cargo.lock b/secert-hitler/Cargo.lock new file mode 100644 index 0000000..440f6fb --- /dev/null +++ b/secert-hitler/Cargo.lock @@ -0,0 +1,84 @@ +# This file is automatically @generated by Cargo. +# It is not intended for manual editing. +[[package]] +name = "cfg-if" +version = "0.1.10" +source = "registry+https://github.com/rust-lang/crates.io-index" +checksum = "4785bdd1c96b2a846b2bd7cc02e86b6b3dbf14e7e53446c4f54c92a361040822" + +[[package]] +name = "getrandom" +version = "0.1.14" +source = "registry+https://github.com/rust-lang/crates.io-index" +checksum = "7abc8dd8451921606d809ba32e95b6111925cd2906060d2dcc29c070220503eb" +dependencies = [ + "cfg-if", + "libc", + "wasi", +] + +[[package]] +name = "libc" +version = "0.2.69" +source = "registry+https://github.com/rust-lang/crates.io-index" +checksum = "99e85c08494b21a9054e7fe1374a732aeadaff3980b6990b94bfd3a70f690005" + +[[package]] +name = "ppv-lite86" +version = "0.2.6" +source = "registry+https://github.com/rust-lang/crates.io-index" +checksum = "74490b50b9fbe561ac330df47c08f3f33073d2d00c150f719147d7c54522fa1b" + +[[package]] +name = "rand" +version = "0.7.3" +source = "registry+https://github.com/rust-lang/crates.io-index" +checksum = "6a6b1679d49b24bbfe0c803429aa1874472f50d9b363131f0e89fc356b544d03" +dependencies = [ + "getrandom", + "libc", + "rand_chacha", + "rand_core", + "rand_hc", +] + +[[package]] +name = "rand_chacha" +version = "0.2.2" +source = "registry+https://github.com/rust-lang/crates.io-index" +checksum = "f4c8ed856279c9737206bf725bf36935d8666ead7aa69b52be55af369d193402" +dependencies = [ + "ppv-lite86", + "rand_core", +] + +[[package]] +name = "rand_core" +version = "0.5.1" +source = "registry+https://github.com/rust-lang/crates.io-index" +checksum = "90bde5296fc891b0cef12a6d03ddccc162ce7b2aff54160af9338f8d40df6d19" +dependencies = [ + "getrandom", +] + +[[package]] +name = "rand_hc" +version = "0.2.0" +source = "registry+https://github.com/rust-lang/crates.io-index" +checksum = "ca3129af7b92a17112d59ad498c6f81eaf463253766b90396d39ea7a39d6613c" +dependencies = [ + "rand_core", +] + +[[package]] +name = "secert-hitler" +version = "0.1.0" +dependencies = [ + "rand", +] + +[[package]] +name = "wasi" +version = "0.9.0+wasi-snapshot-preview1" +source = "registry+https://github.com/rust-lang/crates.io-index" +checksum = "cccddf32554fecc6acb585f82a32a72e28b48f8c4c1883ddfeeeaa96f7d8e519" diff --git a/secert-hitler/Cargo.toml b/secert-hitler/Cargo.toml new file mode 100644 index 0000000..de858f8 --- /dev/null +++ b/secert-hitler/Cargo.toml @@ -0,0 +1,10 @@ +[package] +name = "secert-hitler" +version = "0.1.0" +authors = ["bel "] +edition = "2018" + +# See more keys and their definitions at https://doc.rust-lang.org/cargo/reference/manifest.html + +[dependencies] +rand = "*" diff --git a/secert-hitler/src/config.rs b/secert-hitler/src/config.rs new file mode 100644 index 0000000..e993cd0 --- /dev/null +++ b/secert-hitler/src/config.rs @@ -0,0 +1 @@ +pub static PORT:&str = ":8080"; diff --git a/secert-hitler/src/controller/gamemaster/gamemaster.rs b/secert-hitler/src/controller/gamemaster/gamemaster.rs new file mode 100644 index 0000000..d3ad63e --- /dev/null +++ b/secert-hitler/src/controller/gamemaster/gamemaster.rs @@ -0,0 +1,23 @@ +#[derive(Debug)] +pub struct GameMaster { + room_id: String, +} + +impl GameMaster { + fn new() -> GameMaster { + GameMaster{ + room_id: "hello".to_string(), + } + } +} + +#[cfg(test)] +mod tests { + use super::*; + + #[test] + fn new() { + let gm = GameMaster::new(); + println!("made: {:?}", gm); + } +} diff --git a/secert-hitler/src/controller/mod.rs b/secert-hitler/src/controller/mod.rs new file mode 100644 index 0000000..e69de29 diff --git a/secert-hitler/src/controller/state/event.rs b/secert-hitler/src/controller/state/event.rs new file mode 100644 index 0000000..b8f5492 --- /dev/null +++ b/secert-hitler/src/controller/state/event.rs @@ -0,0 +1,19 @@ +#[derive(Clone, Debug)] +pub struct Event { + pub sender: String, + pub body: String, + pub next: String, +} + +#[cfg(test)] +mod tests { + #[test] + fn event() { + let e = super::Event{ + sender: "sender".to_string(), + body: "body".to_string(), + next: "next".to_string(), + }; + println!("{:?}", e); + } +} diff --git a/secert-hitler/src/controller/state/mockroom.rs b/secert-hitler/src/controller/state/mockroom.rs new file mode 100644 index 0000000..36d01ee --- /dev/null +++ b/secert-hitler/src/controller/state/mockroom.rs @@ -0,0 +1,134 @@ +use super::room::Room; +use super::event::Event; + +use rand::{self, Rng}; +use rand::distributions::Alphanumeric; + +#[derive(Clone, Debug)] +pub struct MockRoom { + last: String, + room_id: String, + events: Vec, +} + +impl MockRoom { + pub fn create() -> MockRoom { + MockRoom::join(rands()) + } + + pub fn join(room_id: String) -> MockRoom { + MockRoom { + last: "".to_string(), + room_id: room_id, + events: vec![], + } + } + + pub fn room(&self) -> impl Room { + self.clone() + } +} + +impl Room for MockRoom { + fn sync(&mut self) -> Vec { + let mut unseen: Vec = vec![]; + let mut last = self.last.clone(); + for e in &self.events { + if e.next == self.last { + unseen.clear(); + } else { + unseen.push(e.clone()); + last = e.next.clone(); + } + } + self.last = last; + return unseen; + } + + fn send(&mut self, message: String) -> Result<&str, &str> { + let e = Event{ + sender: rands(), + next: rands(), + body: message, + }; + self.events.push(e); + Ok("ok") + } + + fn room_id(&self) -> String { + self.room_id.clone() + } +} + +pub fn rands() -> String { + rand::thread_rng() + .sample_iter(&Alphanumeric) + .take(10) + .collect::() +} + +#[cfg(test)] +mod tests { + use super::Room; + use super::MockRoom; + use super::Event; + use super::rands; + + fn _dummy() -> MockRoom { + let mut r = MockRoom::create(); + r.last = "1".to_string(); + r.events = vec![]; + for i in 0..5 { + r.events.push(Event{ + sender: i.to_string(), + next: i.to_string(), + body: i.to_string(), + }); + } + r + } + + #[test] + fn randstest() { + let a = rands(); + let b = rands(); + assert!(a != b, "a == {} == b == {}", a, b); + } + + #[test] + fn create() { + let mut r: MockRoom = MockRoom::create(); + println!("{:?}", r.sync()); + } + + #[test] + fn join() { + let rid = "a".to_string(); + let r: MockRoom = MockRoom::join(rid.to_string()); + assert!(r.room_id == rid); + } + + #[test] + fn sync() { + let mut r = _dummy(); + let events = r.sync(); + assert!(events.len() == 3, "want {}, got {}: {:?}", 3, events.len(), events); + assert!(events[0].sender == "2"); + assert!(events[0].body == "2"); + assert!(events[0].next == "2"); + assert!(r.last == "4", "want last==4, got {}", r.last); + } + + #[test] + fn send() { + let mut r = _dummy(); + let message = "message".to_string(); + r.sync(); + assert!(r.send(message.clone()).ok().unwrap() == "ok"); + assert!(r.last == "4"); + let events = r.sync(); + assert!(events.len() == 1); + assert!(events[0].body == message, "want {}, got {}: {:?}", message, events[0].body, events); + assert!(r.last != "4"); + } +} diff --git a/secert-hitler/src/controller/state/mockrooms.rs b/secert-hitler/src/controller/state/mockrooms.rs new file mode 100644 index 0000000..963aa5f --- /dev/null +++ b/secert-hitler/src/controller/state/mockrooms.rs @@ -0,0 +1,89 @@ +use super::rooms::Rooms; +use super::room::Room; +use super::mockroom::MockRoom; + +// #[derive(Clone, Debug)] +pub struct MockRooms { + rooms: Vec, +} + +impl MockRooms { + pub fn new() -> MockRooms { + MockRooms { + rooms: vec![], + } + } +} + +impl Rooms for MockRooms { + fn create(&mut self) -> Box { + let room = MockRoom::create(); + let _room = room.room(); + self.rooms.push(room); + Box::new(_room) + } + + fn join(&self, room_id: String) -> Result, &str> { + for r in &self.rooms { + if r.room_id() == room_id { + return Ok(Box::new(r.room())); + } + } + Err("not found") + } +} + +#[cfg(test)] +mod tests { + use super::MockRooms; + use super::MockRoom; + use super::Rooms; + + fn _dummy() -> MockRooms { + let mut mrs = MockRooms::new(); + for i in 0..5 { + let random = MockRoom::create(); + mrs.rooms.push(random); + let joined = MockRoom::join(i.to_string()); + mrs.rooms.push(joined); + } + assert!(mrs.rooms.len() == 10); + mrs + } + + #[test] + fn mockrooms() { + let mrs = MockRooms::new(); + assert!(mrs.rooms.len() == 0); + } + + #[test] + fn create() { + let mut mrs = _dummy(); + let was = mrs.rooms.len(); + let _ = mrs.create(); + let is = mrs.rooms.len(); + assert!(was+1 == is, "was {} rooms, want {} rooms, got {} rooms", was, was+1, is); + } + + #[test] + fn join_404() { + let mrs = _dummy(); + let was = mrs.rooms.len(); + let r = mrs.join("does not exist".to_string()); + let is = mrs.rooms.len(); + assert!(was == is, "was {} rooms, want {} rooms, got {} rooms", was, was+1, is); + assert!(!r.is_ok()); + } + + #[test] + fn join_found() { + let mrs = _dummy(); + let was = mrs.rooms.len(); + let r = mrs.join("0".to_string()); + let is = mrs.rooms.len(); + assert!(was == is, "was {} rooms, want {} rooms, got {} rooms", was, was+1, is); + assert!(r.is_ok()); + assert!(r.ok().unwrap().room_id() == "0"); + } +} diff --git a/secert-hitler/src/controller/state/room.rs b/secert-hitler/src/controller/state/room.rs new file mode 100644 index 0000000..8d7b354 --- /dev/null +++ b/secert-hitler/src/controller/state/room.rs @@ -0,0 +1,14 @@ +use super::event; + +pub trait Room { + fn sync(&mut self) -> Vec; + fn send(&mut self, message: String) -> Result<&str, &str>; + fn room_id(&self) -> String; +} + +#[cfg(test)] +mod tests { + #[test] + fn room() { + } +} diff --git a/secert-hitler/src/controller/state/rooms.rs b/secert-hitler/src/controller/state/rooms.rs new file mode 100644 index 0000000..f417088 --- /dev/null +++ b/secert-hitler/src/controller/state/rooms.rs @@ -0,0 +1,13 @@ +use super::room::Room; + +pub trait Rooms { + fn create(&mut self) -> Box; + fn join(&self, room_id: String) -> Result, &str>; +} + +#[cfg(test)] +mod tests { + #[test] + fn rooms() { + } +} diff --git a/secert-hitler/src/main.rs b/secert-hitler/src/main.rs new file mode 100644 index 0000000..5f819e9 --- /dev/null +++ b/secert-hitler/src/main.rs @@ -0,0 +1,11 @@ +mod config; +#[path = "./controller/gamemaster/gamemaster.rs"] mod gamemaster; +#[path = "./controller/state/event.rs"] mod event; +#[path = "./controller/state/room.rs"] mod room; +#[path = "./controller/state/rooms.rs"] mod rooms; +#[path = "./controller/state/mockroom.rs"] mod mockroom; +#[path = "./controller/state/mockrooms.rs"] mod mockrooms; + +fn main() { + println!("{}", config::PORT); +} diff --git a/secert-hitler/src/model/mod.rs b/secert-hitler/src/model/mod.rs new file mode 100644 index 0000000..e69de29 diff --git a/secert-hitler/src/view/mod.rs b/secert-hitler/src/view/mod.rs new file mode 100644 index 0000000..e69de29 diff --git a/testdata/matrix-sandbox/build.sh b/testdata/matrix-sandbox/build.sh index bea59f2..dc4e997 100644 --- a/testdata/matrix-sandbox/build.sh +++ b/testdata/matrix-sandbox/build.sh @@ -1,7 +1,10 @@ #! /bin/bash if ! ps aux | grep -v grep | grep homeserver; then - homeserver -c config.yaml & + cp synapse-test.key /tmp/ + cp synapse-test-log.yaml /tmp/ + homeserver -c config.yaml &> /tmp/synapse-test.log & + disown fi # pick a username @@ -9,7 +12,7 @@ username="$(cat /proc/sys/kernel/random/uuid)" # register out="$( - curl -sS http://192.168.0.86:39487/_matrix/client/r0/register \ + curl -sS http://localhost:39487/_matrix/client/r0/register \ -X POST \ -H 'content-type: application/json' \ -d "$( @@ -31,7 +34,7 @@ home_server="$(echo "$out" | jq -r .home_server)" room="$(cat /proc/sys/kernel/random/uuid)" out="$( - curl -sS http://192.168.0.86:39487/_matrix/client/r0/createRoom\ + curl -sS http://localhost:39487/_matrix/client/r0/createRoom\ -X POST \ -H 'content-type: application/json' \ -H 'Authorization: Bearer '"$access_token" \ @@ -46,7 +49,7 @@ room_id="$(echo "$out" | jq -r .room_id)" # join a room by name # curl -XPOST -d '{}' "https://localhost:8448/_matrix/client/r0/join/%21asfLdzLnOdGRkdPZWu:localhost?access_token=YOUR_ACCESS_TOKEN" -curl -sS "http://192.168.0.86:39487/_matrix/client/r0/join/$room_id" \ +curl -sS "http://localhost:39487/_matrix/client/r0/join/$room_id" \ -X POST \ -H 'Authorization: Bearer '"$access_token" \ -d '{}' @@ -58,7 +61,7 @@ txid="$(cat /proc/sys/kernel/random/uuid)" message="hello wurl" out="$( - curl -sS "http://192.168.0.86:39487/_matrix/client/r0/rooms/$room_id/send/m.room.message/$txid" \ + curl -sS "http://localhost:39487/_matrix/client/r0/rooms/$room_id/send/m.room.message/$txid" \ -X PUT \ -H 'content-type: application/json' \ -H 'Authorization: Bearer '"$access_token" \ @@ -74,7 +77,7 @@ printf "%s\n" "$out" # get recent messages # curl --globoff -XGET 'https://localhost:8448/_matrix/client/r0/sync?filter={"room":{"timeline":{"limit":1}}}&access_token=YOUR_ACCESS_TOKEN' out="$( - curl -sS "http://192.168.0.86:39487/_matrix/client/r0/sync" \ + curl -sS "http://localhost:39487/_matrix/client/r0/sync" \ -X GET \ -H 'content-type: application/json' \ -H 'Authorization: Bearer '"$access_token" \ @@ -89,7 +92,7 @@ txid="$(cat /proc/sys/kernel/random/uuid)" message="hello wurl2" out="$( - curl -sS "http://192.168.0.86:39487/_matrix/client/r0/rooms/$room_id/send/m.room.message/$txid" \ + curl -sS "http://localhost:39487/_matrix/client/r0/rooms/$room_id/send/m.room.message/$txid" \ -X PUT \ -H 'content-type: application/json' \ -H 'Authorization: Bearer '"$access_token" \ @@ -104,7 +107,7 @@ printf "%s\n" "$out" # get second recent messages # curl --globoff -XGET 'https://localhost:8448/_matrix/client/r0/sync?filter={"room":{"timeline":{"limit":1}}}&access_token=YOUR_ACCESS_TOKEN' -curl -sS "http://192.168.0.86:39487/_matrix/client/r0/sync?since=$since" \ +curl -sS "http://localhost:39487/_matrix/client/r0/sync?since=$since" \ -X GET \ -H 'content-type: application/json' \ -H 'Authorization: Bearer '"$access_token" \ diff --git a/testdata/matrix-sandbox/homeserver.log b/testdata/matrix-sandbox/homeserver.log new file mode 100644 index 0000000..829897a --- /dev/null +++ b/testdata/matrix-sandbox/homeserver.log @@ -0,0 +1,551 @@ +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 diff --git a/testdata/matrix-sandbox/synapse-test-log.yaml b/testdata/matrix-sandbox/synapse-test-log.yaml new file mode 100644 index 0000000..91633e4 --- /dev/null +++ b/testdata/matrix-sandbox/synapse-test-log.yaml @@ -0,0 +1,43 @@ +# Log configuration for Synapse. +# +# This is a YAML file containing a standard Python logging configuration +# dictionary. See [1] for details on the valid settings. +# +# [1]: https://docs.python.org/3.7/library/logging.config.html#configuration-dictionary-schema + +version: 1 + +formatters: + precise: + format: '%(asctime)s - %(name)s - %(lineno)d - %(levelname)s - %(request)s - %(message)s' + +filters: + context: + (): synapse.logging.context.LoggingContextFilter + request: "" + +handlers: + file: + class: logging.handlers.RotatingFileHandler + formatter: precise + filename: /home/bel/Go/src/local/containers/secret-hitler-via-matrix/testdata/matrix-sandbox/homeserver.log + maxBytes: 104857600 + backupCount: 10 + filters: [context] + encoding: utf8 + console: + class: logging.StreamHandler + formatter: precise + filters: [context] + +loggers: + synapse.storage.SQL: + # beware: increasing this to DEBUG will make synapse log sensitive + # information such as access tokens. + level: INFO + +root: + level: INFO + handlers: [file, console] + +disable_existing_loggers: false diff --git a/testdata/matrix-sandbox/synapse-test.key b/testdata/matrix-sandbox/synapse-test.key new file mode 100644 index 0000000..72bb5ae --- /dev/null +++ b/testdata/matrix-sandbox/synapse-test.key @@ -0,0 +1 @@ +ed25519 a_RcOc CohEFmFuSDY+kHBbY//SMIUZp+hIuyOMEphT2NLEsDc