Whoa boy rust is a problem child

.gitignore

@@ -1,2 +1,3 @@
 **/*.sw*
 **/*.db
+**/target

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"

secert-hitler/Cargo.toml

@@ -0,0 +1,10 @@
+[package]
+name = "secert-hitler"
+version = "0.1.0"
+authors = ["bel <squeaky2x3@blapointe.com>"]
+edition = "2018"
+
+# See more keys and their definitions at https://doc.rust-lang.org/cargo/reference/manifest.html
+
+[dependencies]
+rand = "*"

secert-hitler/src/config.rs

@@ -0,0 +1 @@
+pub static PORT:&str = ":8080";

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);
+   }
+}

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);
+   }
+}

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<Event>,
+}
+
+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<Event> {
+      let mut unseen: Vec<Event> = 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::<String>()
+}
+
+#[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");
+   }
+}

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<MockRoom>,
+}
+
+impl MockRooms {
+   pub fn new() -> MockRooms {
+      MockRooms {
+         rooms: vec![],
+      }
+   }
+}
+
+impl Rooms for MockRooms {
+   fn create(&mut self) -> Box<dyn Room> {
+      let room = MockRoom::create();
+      let _room = room.room();
+      self.rooms.push(room);
+      Box::new(_room)
+   }
+
+   fn join(&self, room_id: String) -> Result<Box<dyn Room>, &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");
+   }
+}

secert-hitler/src/controller/state/room.rs

@@ -0,0 +1,14 @@
+use super::event;
+
+pub trait Room {
+   fn sync(&mut self) -> Vec<event::Event>;
+   fn send(&mut self, message: String) -> Result<&str, &str>;
+   fn room_id(&self) -> String;
+}
+
+#[cfg(test)]
+mod tests {
+   #[test]
+   fn room() {
+   }
+}

secert-hitler/src/controller/state/rooms.rs

@@ -0,0 +1,13 @@
+use super::room::Room;
+
+pub trait Rooms {
+   fn create(&mut self) -> Box<dyn Room>;
+   fn join(&self, room_id: String) -> Result<Box<dyn Room>, &str>;
+}
+
+#[cfg(test)]
+mod tests {
+   #[test]
+   fn rooms() {
+   }
+}

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);
+}

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"  \

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 <synapse.rest.ClientRestResource object at 0x7f697f4d0910> to path b'/_matrix/client/api/v1'
+2020-05-02 07:19:21,813 - synapse.util.httpresourcetree - 46 - INFO -  - Attaching <synapse.rest.ClientRestResource object at 0x7f697f4d0910> to path b'/_matrix/client/r0'
+2020-05-02 07:19:21,813 - synapse.util.httpresourcetree - 46 - INFO -  - Attaching <synapse.rest.ClientRestResource object at 0x7f697f4d0910> to path b'/_matrix/client/unstable'
+2020-05-02 07:19:21,813 - synapse.util.httpresourcetree - 46 - INFO -  - Attaching <synapse.rest.ClientRestResource object at 0x7f697f4d0910> to path b'/_matrix/client/v2_alpha'
+2020-05-02 07:19:21,813 - synapse.util.httpresourcetree - 46 - INFO -  - Attaching <synapse.rest.ClientRestResource object at 0x7f697f4d0910> to path b'/_matrix/client/versions'
+2020-05-02 07:19:21,813 - synapse.util.httpresourcetree - 46 - INFO -  - Attaching <synapse.rest.well_known.WellKnownResource object at 0x7f697ec63910> to path b'/.well-known/matrix/client'
+2020-05-02 07:19:21,813 - synapse.util.httpresourcetree - 46 - INFO -  - Attaching <synapse.rest.admin.AdminRestResource object at 0x7f697ec63ad0> 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 <synapse.rest.media.v1.media_repository.MediaRepositoryResource object at 0x7f697ec56a10> to path b'/_matrix/media/r0'
+2020-05-02 07:19:21,814 - synapse.util.httpresourcetree - 46 - INFO -  - Attaching <synapse.rest.media.v1.media_repository.MediaRepositoryResource object at 0x7f697ec56a10> to path b'/_matrix/media/v1'
+2020-05-02 07:19:21,814 - synapse.util.httpresourcetree - 46 - INFO -  - Attaching <synapse.federation.transport.server.TransportLayerServer object at 0x7f697ec6b690> to path b'/_matrix/federation'
+2020-05-02 07:19:21,814 - synapse.util.httpresourcetree - 46 - INFO -  - Attaching <synapse.rest.key.v2.KeyApiV2Resource object at 0x7f697ec0b6d0> 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 <synapse.rest.ClientRestResource object at 0x7f9df3212690> to path b'/_matrix/client/api/v1'
+2020-05-02 07:21:12,366 - synapse.util.httpresourcetree - 46 - INFO -  - Attaching <synapse.rest.ClientRestResource object at 0x7f9df3212690> to path b'/_matrix/client/r0'
+2020-05-02 07:21:12,366 - synapse.util.httpresourcetree - 46 - INFO -  - Attaching <synapse.rest.ClientRestResource object at 0x7f9df3212690> to path b'/_matrix/client/unstable'
+2020-05-02 07:21:12,366 - synapse.util.httpresourcetree - 46 - INFO -  - Attaching <synapse.rest.ClientRestResource object at 0x7f9df3212690> to path b'/_matrix/client/v2_alpha'
+2020-05-02 07:21:12,367 - synapse.util.httpresourcetree - 46 - INFO -  - Attaching <synapse.rest.ClientRestResource object at 0x7f9df3212690> to path b'/_matrix/client/versions'
+2020-05-02 07:21:12,367 - synapse.util.httpresourcetree - 46 - INFO -  - Attaching <synapse.rest.well_known.WellKnownResource object at 0x7f9df29b3710> to path b'/.well-known/matrix/client'
+2020-05-02 07:21:12,367 - synapse.util.httpresourcetree - 46 - INFO -  - Attaching <synapse.rest.admin.AdminRestResource object at 0x7f9df2985350> 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 <synapse.rest.media.v1.media_repository.MediaRepositoryResource object at 0x7f9df298af90> to path b'/_matrix/media/r0'
+2020-05-02 07:21:12,367 - synapse.util.httpresourcetree - 46 - INFO -  - Attaching <synapse.rest.media.v1.media_repository.MediaRepositoryResource object at 0x7f9df298af90> to path b'/_matrix/media/v1'
+2020-05-02 07:21:12,367 - synapse.util.httpresourcetree - 46 - INFO -  - Attaching <synapse.federation.transport.server.TransportLayerServer object at 0x7f9df293b7d0> to path b'/_matrix/federation'
+2020-05-02 07:21:12,368 - synapse.util.httpresourcetree - 46 - INFO -  - Attaching <synapse.rest.key.v2.KeyApiV2Resource object at 0x7f9df29a1910> 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

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

testdata/matrix-sandbox/synapse-test.key

@@ -0,0 +1 @@
+ed25519 a_RcOc CohEFmFuSDY+kHBbY//SMIUZp+hIuyOMEphT2NLEsDc