Container matrix-synapse failed to start

[Cortana787T]

When starting matrix-synapse on first setup it fails after 102.6s with the error Error dependency synapse failed to start
I don't know how I should write an issue but I can give more info on demand. It's a Debian VServer with unknown specs besides Debian Linux installed
It also doesn't work on Ubuntu
Also not on Arch

[wlphi]

Can you share the logs? Run docker compose logs matrix-synapse right after the failure and paste the output here.

The 102s timeout suggests Synapse starts but never opens port 8008. If you have a metrics.yaml in your synapse/data/ folder alongside homeserver.yaml, that's likely the culprit — see #31 for the explanation. If that's your situation, remove metrics.yaml or merge its contents into homeserver.yaml and try again.

[Cortana787T]

Can you share the logs? Run docker compose logs matrix-synapse right after the failure and paste the output here.

The 102s timeout suggests Synapse starts but never opens port 8008. If you have a metrics.yaml in your synapse/data/ folder alongside homeserver.yaml, that's likely the culprit — see #31 for the explanation. If that's your situation, remove metrics.yaml or merge its contents into homeserver.yaml and try again.

I got: no such service: matrix-synapse

[Cortana787T]

I retried and got:
root@matrix:~/ess-docker-compose# docker logs -f matrix-synapse
Starting synapse with args -m synapse.app.homeserver --config-path /data
This server is configured to use 'matrix.org' as its trusted key server via the
'trusted_key_servers' config option. 'matrix.org' is a good choice for a key
server since it is long-lived, stable and trusted. However, some admins may
wish to use another server for this purpose.

To suppress this warning and continue using 'matrix.org', admins should set
'suppress_key_server_warning' to 'true' in homeserver.yaml.

2026-07-04 11:29:18,612 - synapse.config.logger - 377 - WARNING - main - ***** STARTING SERVER *****
2026-07-04 11:29:18,613 - synapse.config.logger - 378 - WARNING - main - Server /usr/local/lib/python3.13/site-packages/synapse/app/homeserver.py version 1.155.0
2026-07-04 11:29:18,613 - synapse.config.logger - 383 - WARNING - main - Copyright (c) 2023 New Vector, Inc
2026-07-04 11:29:18,613 - synapse.config.logger - 384 - WARNING - main - Licensed under the AGPL 3.0 license. Website: https://github.com/element-hq/synapse
2026-07-04 11:29:18,613 - synapse.config.logger - 387 - INFO - main - Server hostname: cortanamc.de
2026-07-04 11:29:18,614 - synapse.config.logger - 388 - INFO - main - Public Base URL: https://cortanamc.de/
2026-07-04 11:29:18,614 - synapse.config.logger - 389 - INFO - main - Instance name: master
2026-07-04 11:29:18,614 - synapse.config.logger - 390 - INFO - main - Twisted reactor: EPollReactor
2026-07-04 11:29:18,614 - synapse.app.homeserver - 417 - INFO - main - Setting up server
2026-07-04 11:29:18,614 - synapse.server - 635 - INFO - main - Setting up.
2026-07-04 11:29:18,651 - synapse.storage.databases - 92 - INFO - main - [database config 'master']: Checking database server
2026-07-04 11:29:18,655 - synapse.storage.databases - 95 - INFO - main - [database config 'master']: Preparing for databases ['main', 'state']
2026-07-04 11:29:18,655 - synapse.storage.prepare_database - 132 - INFO - main - ['main', 'state']: Checking existing schema version
2026-07-04 11:29:18,775 - synapse.storage.prepare_database - 162 - INFO - main - ['main', 'state']: Initialising new database
2026-07-04 11:29:25,371 - synapse.storage.prepare_database - 430 - INFO - main - Applying schema deltas for v73
2026-07-04 11:29:25,374 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 73/01event_failed_pull_attempts.sql
2026-07-04 11:29:25,412 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 73/02add_pusher_enabled.sql
2026-07-04 11:29:25,419 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 73/02room_id_indexes_for_purging.sql
2026-07-04 11:29:25,461 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 73/03pusher_device_id.sql
2026-07-04 11:29:25,472 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 73/03users_approved_column.sql
2026-07-04 11:29:25,479 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 73/04partial_join_details.sql
2026-07-04 11:29:25,493 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 73/04pending_device_list_updates.sql
2026-07-04 11:29:25,548 - synapse.storage.prepare_database - 546 - INFO - main - Applying engine-specific schema 73/05old_push_actions.sql.postgres
2026-07-04 11:29:25,561 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 73/06thread_notifications_thread_id_idx.sql
2026-07-04 11:29:25,570 - synapse.storage.prepare_database - 546 - INFO - main - Applying engine-specific schema 73/08thread_receipts_non_null.sql.postgres
2026-07-04 11:29:25,579 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 73/09partial_joined_via_destination.sql
2026-07-04 11:29:25,585 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 73/09threads_table.sql
2026-07-04 11:29:25,633 - synapse.storage.prepare_database - 521 - INFO - main - Running 73/10_update_sqlite_fts4_tokenizer.py:run_create
2026-07-04 11:29:25,633 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 73/10login_tokens.sql
2026-07-04 11:29:25,706 - synapse.storage.prepare_database - 546 - INFO - main - Applying engine-specific schema 73/11event_search_room_id_n_distinct.sql.postgres
2026-07-04 11:29:25,713 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 73/12refactor_device_list_outbound_pokes.sql
2026-07-04 11:29:25,765 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 73/13add_device_lists_index.sql
2026-07-04 11:29:25,774 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 73/20_un_partial_stated_room_stream.sql
2026-07-04 11:29:25,829 - synapse.storage.prepare_database - 546 - INFO - main - Applying engine-specific schema 73/21_un_partial_stated_room_stream_seq.sql.postgres
2026-07-04 11:29:25,838 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 73/22_rebuild_user_dir_stats.sql
2026-07-04 11:29:25,845 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 73/22_un_partial_stated_event_stream.sql
2026-07-04 11:29:25,918 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 73/23_fix_thread_index.sql
2026-07-04 11:29:25,927 - synapse.storage.prepare_database - 546 - INFO - main - Applying engine-specific schema 73/23_un_partial_stated_room_stream_seq.sql.postgres
2026-07-04 11:29:25,936 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 73/24_events_jump_to_date_index.sql
2026-07-04 11:29:25,942 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 73/25drop_presence.sql
2026-07-04 11:29:25,952 - synapse.storage.prepare_database - 430 - INFO - main - Applying schema deltas for v74
2026-07-04 11:29:25,954 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 74/01_user_directory_stale_remote_users.sql
2026-07-04 11:29:26,034 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 74/02_set_device_id_for_pushers_bg_update.sql
2026-07-04 11:29:26,041 - synapse.storage.prepare_database - 546 - INFO - main - Applying engine-specific schema 74/03_membership_tables_event_stream_ordering.sql.postgres
2026-07-04 11:29:26,054 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 74/03_room_membership_index.sql
2026-07-04 11:29:26,059 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 74/04_delete_e2e_backup_keys_for_deactivated_users.sql
2026-07-04 11:29:26,066 - synapse.storage.prepare_database - 521 - INFO - main - Running 74/04_membership_tables_event_stream_ordering_triggers.py:run_create
2026-07-04 11:29:26,072 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 74/05_events_txn_id_device_id.sql
2026-07-04 11:29:26,150 - synapse.storage.prepare_database - 546 - INFO - main - Applying engine-specific schema 74/90COMMENTS_destinations.sql.postgres
2026-07-04 11:29:26,157 - synapse.storage.prepare_database - 430 - INFO - main - Applying schema deltas for v75
2026-07-04 11:29:26,158 - synapse.storage.prepare_database - 430 - INFO - main - Applying schema deltas for v76
2026-07-04 11:29:26,159 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 76/01_add_profiles_full_user_id_column.sql
2026-07-04 11:29:26,164 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 76/02_add_user_filters_full_user_id_column.sql
2026-07-04 11:29:26,171 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 76/03_per_user_experimental_features.sql
2026-07-04 11:29:26,215 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 76/04_add_room_forgetter.sql
2026-07-04 11:29:26,243 - synapse.storage.prepare_database - 430 - INFO - main - Applying schema deltas for v77
2026-07-04 11:29:26,245 - synapse.storage.prepare_database - 546 - INFO - main - Applying engine-specific schema 77/01_add_profiles_not_valid_check.sql.postgres
2026-07-04 11:29:26,252 - synapse.storage.prepare_database - 546 - INFO - main - Applying engine-specific schema 77/02_add_user_filters_not_valid_check.sql.postgres
2026-07-04 11:29:26,258 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 77/03bg_populate_full_user_id_profiles.sql
2026-07-04 11:29:26,264 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 77/04bg_populate_full_user_id_user_filters.sql
2026-07-04 11:29:26,272 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 77/05thread_notifications_backfill.sql
2026-07-04 11:29:26,285 - synapse.storage.prepare_database - 546 - INFO - main - Applying engine-specific schema 77/06thread_notifications_not_null_event_push_actions.sql.postgres
2026-07-04 11:29:26,293 - synapse.storage.prepare_database - 546 - INFO - main - Applying engine-specific schema 77/06thread_notifications_not_null_event_push_actions_staging.sql.postgres
2026-07-04 11:29:26,299 - synapse.storage.prepare_database - 546 - INFO - main - Applying engine-specific schema 77/06thread_notifications_not_null_event_push_summary.sql.postgres
2026-07-04 11:29:26,304 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 77/14bg_indices_event_stream_ordering.sql
2026-07-04 11:29:26,308 - synapse.storage.prepare_database - 430 - INFO - main - Applying schema deltas for v78
2026-07-04 11:29:26,314 - synapse.storage.prepare_database - 521 - INFO - main - Running 78/03event_extremities_constraints.py:run_create
2026-07-04 11:29:26,317 - synapse.storage.prepare_database - 521 - INFO - main - Running 78/04_add_full_user_id_index_user_filters.py:run_create
2026-07-04 11:29:26,317 - synapse.storage.prepare_database - 430 - INFO - main - Applying schema deltas for v79
2026-07-04 11:29:26,318 - synapse.storage.prepare_database - 546 - INFO - main - Applying engine-specific schema 79/03_read_write_locks_triggers.sql.postgres
2026-07-04 11:29:26,438 - synapse.storage.prepare_database - 521 - INFO - main - Running 79/04_mitigate_stream_ordering_update_race.py:run_create
2026-07-04 11:29:26,440 - synapse.storage.prepare_database - 546 - INFO - main - Applying engine-specific schema 79/05_read_write_locks_triggers.sql.postgres
2026-07-04 11:29:26,450 - synapse.storage.prepare_database - 430 - INFO - main - Applying schema deltas for v80
2026-07-04 11:29:26,451 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 80/01_users_alter_locked.sql
2026-07-04 11:29:26,458 - synapse.storage.prepare_database - 546 - INFO - main - Applying engine-specific schema 80/02_read_write_locks_unlogged.sql.postgres
2026-07-04 11:29:26,541 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 80/02_scheduled_tasks.sql
2026-07-04 11:29:26,605 - synapse.storage.prepare_database - 546 - INFO - main - Applying engine-specific schema 80/03_read_write_locks_triggers.sql.postgres
2026-07-04 11:29:26,614 - synapse.storage.prepare_database - 546 - INFO - main - Applying engine-specific schema 80/04_read_write_locks_deadlock.sql.postgres
2026-07-04 11:29:26,620 - synapse.storage.prepare_database - 430 - INFO - main - Applying schema deltas for v81
2026-07-04 11:29:26,621 - synapse.storage.prepare_database - 430 - INFO - main - Applying schema deltas for v82
2026-07-04 11:29:26,622 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 82/02_scheduled_tasks_index.sql
2026-07-04 11:29:26,642 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 82/04_add_indices_for_purging_rooms.sql
2026-07-04 11:29:26,648 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 82/05gaps.sql
2026-07-04 11:29:26,688 - synapse.storage.prepare_database - 430 - INFO - main - Applying schema deltas for v83
2026-07-04 11:29:26,690 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 83/01_drop_old_tables.sql
2026-07-04 11:29:26,724 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 83/05_cross_signing_key_update_grant.sql
2026-07-04 11:29:26,734 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 83/06_event_push_summary_room.sql
2026-07-04 11:29:26,740 - synapse.storage.prepare_database - 430 - INFO - main - Applying schema deltas for v84
2026-07-04 11:29:26,741 - synapse.storage.prepare_database - 546 - INFO - main - Applying engine-specific schema 84/01_auth_links_stats.sql.postgres
2026-07-04 11:29:26,750 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 84/02_auth_links_index.sql
2026-07-04 11:29:26,755 - synapse.storage.prepare_database - 546 - INFO - main - Applying engine-specific schema 84/03_auth_links_analyze.sql.postgres
2026-07-04 11:29:26,762 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 84/04_access_token_index.sql
2026-07-04 11:29:26,766 - synapse.storage.prepare_database - 430 - INFO - main - Applying schema deltas for v85
2026-07-04 11:29:26,768 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 85/01_add_suspended.sql
2026-07-04 11:29:26,775 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 85/02_add_instance_names.sql
2026-07-04 11:29:26,786 - synapse.storage.prepare_database - 546 - INFO - main - Applying engine-specific schema 85/03_new_sequences.sql.postgres
2026-07-04 11:29:26,809 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 85/04_cleanup_device_federation_outbox.sql
2026-07-04 11:29:26,814 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 85/05_add_instance_names_converted_pos.sql
2026-07-04 11:29:26,819 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 85/06_add_room_reports.sql
2026-07-04 11:29:26,859 - synapse.storage.prepare_database - 430 - INFO - main - Applying schema deltas for v86
2026-07-04 11:29:26,861 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 86/01_authenticate_media.sql
2026-07-04 11:29:26,872 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 86/02_receipts_event_id_index.sql
2026-07-04 11:29:26,880 - synapse.storage.prepare_database - 430 - INFO - main - Applying schema deltas for v87
2026-07-04 11:29:26,882 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 87/01_sliding_sync_memberships.sql
2026-07-04 11:29:27,055 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 87/02_per_connection_state.sql
2026-07-04 11:29:27,299 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 87/03_current_state_index.sql
2026-07-04 11:29:27,304 - synapse.storage.prepare_database - 430 - INFO - main - Applying schema deltas for v88
2026-07-04 11:29:27,306 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 88/01_add_delayed_events.sql
2026-07-04 11:29:27,410 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 88/01_custom_profile_fields.sql
2026-07-04 11:29:27,417 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 88/02_fix_sliding_sync_membership_snapshots_forgotten_column.sql
2026-07-04 11:29:27,422 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 88/03_add_otk_ts_added_index.sql
2026-07-04 11:29:27,428 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 88/04_current_state_delta_index.sql
2026-07-04 11:29:27,432 - synapse.storage.prepare_database - 546 - INFO - main - Applying engine-specific schema 88/05_drop_old_otks.sql.postgres
2026-07-04 11:29:27,439 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 88/05_sliding_sync_room_config_index.sql
2026-07-04 11:29:27,444 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 88/06_events_received_ts_index.sql
2026-07-04 11:29:27,449 - synapse.storage.prepare_database - 430 - INFO - main - Applying schema deltas for v89
2026-07-04 11:29:27,450 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 89/01_sliding_sync_membership_snapshot_index.sql
2026-07-04 11:29:27,454 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 89/01_state_groups_deletion.sql
2026-07-04 11:29:27,554 - synapse.storage.prepare_database - 430 - INFO - main - Applying schema deltas for v90
2026-07-04 11:29:27,556 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 90/01_add_column_participant_room_memberships_table.sql
2026-07-04 11:29:27,565 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 90/02_delete_unreferenced_state_groups.sql
2026-07-04 11:29:27,572 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 90/03_remove_old_deletion_bg_update.sql
2026-07-04 11:29:27,577 - synapse.storage.prepare_database - 430 - INFO - main - Applying schema deltas for v91
2026-07-04 11:29:27,579 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 91/01_media_hash.sql
2026-07-04 11:29:27,585 - synapse.storage.prepare_database - 430 - INFO - main - Applying schema deltas for v92
2026-07-04 11:29:27,586 - synapse.storage.prepare_database - 546 - INFO - main - Applying engine-specific schema 92/01_remove_trigger.sql.postgres
2026-07-04 11:29:27,593 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 92/02_remove_populate_participant_bg_update.sql
2026-07-04 11:29:27,598 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 92/04_ss_membership_snapshot_idx.sql
2026-07-04 11:29:27,603 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 92/04_thread_subscriptions.sql
2026-07-04 11:29:27,724 - synapse.storage.prepare_database - 546 - INFO - main - Applying engine-specific schema 92/04_thread_subscriptions_seq.sql.postgres
2026-07-04 11:29:27,731 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 92/05_fixup_max_depth_cap.sql
2026-07-04 11:29:27,738 - synapse.storage.prepare_database - 546 - INFO - main - Applying engine-specific schema 92/05_thread_subscriptions_comments.sql.postgres
2026-07-04 11:29:27,744 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 92/06_device_federation_inbox_index.sql
2026-07-04 11:29:27,750 - synapse.storage.prepare_database - 546 - INFO - main - Applying engine-specific schema 92/06_threads_last_sent_stream_ordering_comments.sql.postgres
2026-07-04 11:29:27,759 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 92/07_add_user_reports.sql
2026-07-04 11:29:27,831 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 92/07_event_txn_id_device_id_txn_id2.sql
2026-07-04 11:29:27,838 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 92/08_room_ban_redactions.sql
2026-07-04 11:29:27,879 - synapse.storage.prepare_database - 546 - INFO - main - Applying engine-specific schema 92/08_thread_subscriptions_seq_fixup.sql.postgres
2026-07-04 11:29:27,887 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 92/09_thread_subscriptions_update.sql
2026-07-04 11:29:27,894 - synapse.storage.prepare_database - 546 - INFO - main - Applying engine-specific schema 92/09_thread_subscriptions_update.sql.postgres
2026-07-04 11:29:27,899 - synapse.storage.prepare_database - 430 - INFO - main - Applying schema deltas for v93
2026-07-04 11:29:27,900 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 93/01_add_delayed_events.sql
2026-07-04 11:29:27,906 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 93/01_sticky_events.sql
2026-07-04 11:29:27,986 - synapse.storage.prepare_database - 546 - INFO - main - Applying engine-specific schema 93/01_sticky_events_seq.sql.postgres
2026-07-04 11:29:27,993 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 93/02_sliding_sync_members.sql
2026-07-04 11:29:28,051 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 93/03_sss_pos_last_used.sql
2026-07-04 11:29:28,057 - synapse.storage.prepare_database - 521 - INFO - main - Running 93/04_make_delayed_event_content_text.py:run_create
2026-07-04 11:29:28,137 - synapse.storage.prepare_database - 430 - INFO - main - Applying schema deltas for v94
2026-07-04 11:29:28,138 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 94/01_redactions_recheck.sql
2026-07-04 11:29:28,148 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 94/02_redactions_recheck_bg_update.sql
2026-07-04 11:29:28,159 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 94/03_device_lists_room_timestamp.sql
2026-07-04 11:29:28,165 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 94/03_quarantined_media_tracking.sql
2026-07-04 11:29:28,205 - synapse.storage.prepare_database - 546 - INFO - main - Applying engine-specific schema 94/03_quarantined_media_tracking_seq.sql.postgres
2026-07-04 11:29:28,211 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 94/03_state_dag_fwd_extrems.sql
2026-07-04 11:29:28,309 - synapse.storage.prepare_database - 538 - INFO - main - Applying schema 94/04_device_lists_changes_max_pruned.sql
2026-07-04 11:29:28,330 - synapse.storage.prepare_database - 565 - INFO - main - Schema now up to date
2026-07-04 11:29:28,338 - synapse.storage.databases - 110 - INFO - main - [database config 'master']: Starting 'main' database
2026-07-04 11:29:28,341 - synapse.config.appservice - 162 - INFO - main - (/appservices/doublepuppet.yaml) Explicitly empty 'url' provided. This application service will not receive events or queries.
2026-07-04 11:29:28,341 - synapse.config.appservice - 96 - INFO - main - Loaded application service: ApplicationService: {'token': '', 'url': None, 'hs_token': '', 'sender': @doublepuppet:cortanamc.de, 'server_name': 'cortanamc.de', 'clock': <synapse.util.clock.Clock object at 0x7f77de8b2e90>, 'namespaces': {'users': [Namespace(exclusive=False, regex=re.compile('@.:cortanamc.de'))], 'aliases': [], 'rooms': []}, 'id': 'doublepuppet', 'ip_range_whitelist': None, 'supports_ephemeral': False, 'msc3202_transaction_extensions': False, 'msc4190_device_management': False, 'protocols': set(), 'rate_limited': False}
2026-07-04 11:29:28,346 - synapse.config.appservice - 96 - INFO - main - Loaded application service: ApplicationService: {'token': '', 'url': 'http://hookshot:9993', 'hs_token': '', 'sender': @hookshot:cortanamc.de, 'server_name': 'cortanamc.de', 'clock': <synapse.util.clock.Clock object at 0x7f77de602ad0>, 'namespaces': {'users': [Namespace(exclusive=True, regex=re.compile('@webhooks.:cortanamc.de'))], 'aliases': [], 'rooms': []}, 'id': 'hookshot', 'ip_range_whitelist': None, 'supports_ephemeral': True, 'msc3202_transaction_extensions': False, 'msc4190_device_management': False, 'protocols': set(), 'rate_limited': False}
2026-07-04 11:29:28,404 - synapse.storage.util.id_generators - 92 - INFO - main - Initialising stream generator for push_rules(id): 1
2026-07-04 11:29:28,406 - synapse.storage.util.id_generators - 92 - INFO - main - Initialising stream generator for push_rules_enable(id): 1
2026-07-04 11:29:28,426 - synapse.storage.databases.main.event_push_actions - 1238 - INFO - main - Searching for stream ordering 1 month ago
2026-07-04 11:29:28,427 - synapse.storage.databases.main.event_push_actions - 1242 - INFO - main - Found stream ordering 1 month ago: it's 0
2026-07-04 11:29:28,427 - synapse.storage.databases.main.event_push_actions - 1245 - INFO - main - Searching for stream ordering 1 day ago
2026-07-04 11:29:28,428 - synapse.storage.databases.main.event_push_actions - 1249 - INFO - main - Found stream ordering 1 day ago: it's 0
2026-07-04 11:29:28,437 - synapse.storage.util.id_generators - 92 - INFO - main - Initialising stream generator for access_tokens(id): 1
2026-07-04 11:29:28,439 - synapse.storage.util.id_generators - 92 - INFO - main - Initialising stream generator for refresh_tokens(id): 1
2026-07-04 11:29:28,447 - synapse.storage.util.id_generators - 92 - INFO - main - Initialising stream generator for event_reports(id): 1
2026-07-04 11:29:28,448 - synapse.storage.util.id_generators - 92 - INFO - main - Initialising stream generator for room_reports(id): 1
2026-07-04 11:29:28,449 - synapse.storage.util.id_generators - 92 - INFO - main - Initialising stream generator for user_reports(id): 1
2026-07-04 11:29:28,467 - synapse.storage.databases - 127 - INFO - main - [database config 'master']: Starting 'state' database
2026-07-04 11:29:28,471 - synapse.storage.databases - 143 - INFO - main - [database config 'master']: prepared
2026-07-04 11:29:28,471 - synapse.server - 638 - INFO - main - Finished setting up.
2026-07-04 11:29:28,472 - twisted - 273 - INFO - main - Redirected stdout/stderr to logs
2026-07-04 11:29:28,472 - synapse.app.homeserver - 213 - INFO - main - Running
2026-07-04 11:29:28,481 - synapse.app.homeserver - 36 - INFO - main - Set file limit to: 524288
2026-07-04 11:29:28,531 - synapse.push.pusher - 45 - INFO - call_when_running - email enable notifs: False
2026-07-04 11:29:28,556 - synapse.federation.federation_server - 1507 - INFO - call_when_running - Registering federation query handler for 'profile'
2026-07-04 11:29:28,560 - synapse.federation.federation_server - 1487 - INFO - call_when_running - Registering federation EDU handler for 'm.device_list_update'
2026-07-04 11:29:28,561 - synapse.util.caches.lrucache - 241 - INFO - call_when_running - Expiring LRU caches after 1800 seconds
2026-07-04 11:29:28,562 - synapse.app._base - 343 - INFO - call_when_running - Starting metrics listener on 0.0.0.0:9000
2026-07-04 11:29:28,573 - synapse.federation.federation_server - 1487 - INFO - call_when_running - Registering federation EDU handler for 'm.presence'
2026-07-04 11:29:28,573 - synapse.federation.federation_server - 1487 - INFO - call_when_running - Registering federation EDU handler for 'm.typing'
2026-07-04 11:29:28,575 - synapse.federation.federation_server - 1507 - INFO - call_when_running - Registering federation query handler for 'directory'
2026-07-04 11:29:28,576 - synapse.handlers.deactivate_account - 281 - INFO - user_parter_loop-0 - Starting user parter
2026-07-04 11:29:28,598 - synapse.federation.federation_server - 1487 - INFO - call_when_running - Registering federation EDU handler for 'm.signing_key_update'
2026-07-04 11:29:28,598 - synapse.federation.federation_server - 1487 - INFO - call_when_running - Registering federation EDU handler for 'org.matrix.signing_key_update'
2026-07-04 11:29:28,598 - synapse.federation.federation_server - 1507 - INFO - call_when_running - Registering federation query handler for 'client_keys'
2026-07-04 11:29:28,622 - synapse.app._base - 777 - INFO - call_when_running - garbage collector: Freezing all allocated objects in the hopes that (almost) everything currently allocated are things that will be used by the homeserver for the rest of time. Doing so means less work each GC (hopefully).
2026-07-04 11:29:28,779 - synapse.storage.background_updates - 416 - INFO - background_updates-0 - Starting background schema updates for database master
2026-07-04 11:29:28,797 - synapse.handlers.deactivate_account - 291 - INFO - user_parter_loop-0 - User parter finished: stopping
2026-07-04 11:29:28,811 - synapse.push.pusherpool - 382 - INFO - start_pushers-0 - Started pushers
2026-07-04 11:29:29,827 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'threads_backfill'
2026-07-04 11:29:29,844 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'threads_backfill'. Processed 0 items in 13ms. (total_rate=None/ms, current_rate=None/ms, total_updated=0, batch_size=100)
2026-07-04 11:29:30,853 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'device_lists_changes_in_room_by_room_index'
2026-07-04 11:29:30,856 - synapse.storage.background_updates - 876 - INFO - background_updates-0 - Adding index device_lists_changes_in_room_by_room_idx to device_lists_changes_in_room
2026-07-04 11:29:30,906 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'device_lists_changes_in_room_by_room_index'. Processed 1 items in 50ms. (total_rate=0.02/ms, current_rate=0.02/ms, total_updated=1, batch_size=100)
2026-07-04 11:29:31,914 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'populate_stats_process_rooms'
2026-07-04 11:29:31,930 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'populate_stats_process_rooms'. Processed 1 items in 12ms. (total_rate=0.08333333333333333/ms, current_rate=0.08333333333333333/ms, total_updated=1, batch_size=100)
2026-07-04 11:29:32,936 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'populate_stats_process_users'
2026-07-04 11:29:32,950 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'populate_stats_process_users'. Processed 1 items in 11ms. (total_rate=0.09090909090909091/ms, current_rate=0.09090909090909091/ms, total_updated=1, batch_size=100)
2026-07-04 11:29:32,954 - synapse.storage.background_updates - 552 - INFO - background_updates-0 - Not starting on bg update populate_user_directory_cleanup until populate_user_directory_process_users is done
2026-07-04 11:29:33,956 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'populate_user_directory_createtables'
2026-07-04 11:29:34,143 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'populate_user_directory_createtables'. Processed 1 items in 185ms. (total_rate=0.005405405405405406/ms, current_rate=0.005405405405405406/ms, total_updated=1, batch_size=100)
2026-07-04 11:29:34,146 - synapse.storage.background_updates - 552 - INFO - background_updates-0 - Not starting on bg update populate_user_directory_cleanup until populate_user_directory_process_users is done
2026-07-04 11:29:35,148 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'populate_user_directory_process_rooms'
2026-07-04 11:29:35,451 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'populate_user_directory_process_rooms'. Processed 1 items in 300ms. (total_rate=0.0033333333333333335/ms, current_rate=0.0033333333333333335/ms, total_updated=1, batch_size=100)
2026-07-04 11:29:35,456 - synapse.storage.background_updates - 552 - INFO - background_updates-0 - Not starting on bg update populate_user_directory_cleanup until populate_user_directory_process_users is done
2026-07-04 11:29:36,457 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'populate_user_directory_process_users'
2026-07-04 11:29:36,469 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'populate_user_directory_process_users'. Processed 1 items in 9ms. (total_rate=0.1111111111111111/ms, current_rate=0.11111111111111112/ms, total_updated=1, batch_size=100)
2026-07-04 11:29:37,473 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'populate_user_directory_cleanup'
2026-07-04 11:29:37,509 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'populate_user_directory_cleanup'. Processed 1 items in 33ms. (total_rate=0.030303030303030304/ms, current_rate=0.030303030303030304/ms, total_updated=1, batch_size=100)
2026-07-04 11:29:38,511 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'events_jump_to_date_index'
2026-07-04 11:29:38,514 - synapse.storage.background_updates - 876 - INFO - background_updates-0 - Adding index events_jump_to_date_idx to events
2026-07-04 11:29:38,559 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'events_jump_to_date_index'. Processed 1 items in 45ms. (total_rate=0.022222222222222223/ms, current_rate=0.022222222222222223/ms, total_updated=1, batch_size=100)
2026-07-04 11:29:39,566 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'set_device_id_for_pushers'
2026-07-04 11:29:39,580 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'set_device_id_for_pushers'. Processed 0 items in 12ms. (total_rate=None/ms, current_rate=None/ms, total_updated=0, batch_size=100)
2026-07-04 11:29:40,585 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'room_membership_user_room_index'
2026-07-04 11:29:40,587 - synapse.storage.background_updates - 876 - INFO - background_updates-0 - Adding index room_membership_user_room_idx to room_memberships
2026-07-04 11:29:40,631 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'room_membership_user_room_index'. Processed 1 items in 44ms. (total_rate=0.022727272727272728/ms, current_rate=0.022727272727272728/ms, total_updated=1, batch_size=100)
2026-07-04 11:29:41,635 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'delete_e2e_backup_keys_for_deactivated_users'
2026-07-04 11:29:41,647 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'delete_e2e_backup_keys_for_deactivated_users'. Processed 0 items in 10ms. (total_rate=None/ms, current_rate=None/ms, total_updated=0, batch_size=100)
2026-07-04 11:29:42,650 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'profiles_full_user_id_key_idx'
2026-07-04 11:29:42,652 - synapse.storage.background_updates - 876 - INFO - background_updates-0 - Adding index profiles_full_user_id_key to profiles
2026-07-04 11:29:42,699 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'profiles_full_user_id_key_idx'. Processed 1 items in 47ms. (total_rate=0.02127659574468085/ms, current_rate=0.02127659574468085/ms, total_updated=1, batch_size=100)
2026-07-04 11:29:43,702 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'full_users_filters_unique_idx'
2026-07-04 11:29:43,704 - synapse.storage.background_updates - 876 - INFO - background_updates-0 - Adding index full_users_unique_idx to user_filters
2026-07-04 11:29:43,761 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'full_users_filters_unique_idx'. Processed 1 items in 57ms. (total_rate=0.017543859649122806/ms, current_rate=0.017543859649122806/ms, total_updated=1, batch_size=100)
2026-07-04 11:29:44,764 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'populate_full_user_id_profiles'
2026-07-04 11:29:44,785 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'populate_full_user_id_profiles'. Processed 1 items in 19ms. (total_rate=0.05263157894736842/ms, current_rate=0.05263157894736842/ms, total_updated=1, batch_size=100)
2026-07-04 11:29:45,788 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'populate_full_user_id_user_filters'
2026-07-04 11:29:45,809 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'populate_full_user_id_user_filters'. Processed 1 items in 19ms. (total_rate=0.05263157894736842/ms, current_rate=0.05263157894736842/ms, total_updated=1, batch_size=100)
2026-07-04 11:29:46,813 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'event_push_actions_staging_thread_id'
2026-07-04 11:29:46,815 - synapse.storage.background_updates - 752 - INFO - background_updates-0 - Validating constraint event_push_actions_staging_thread_id to event_push_actions_staging
2026-07-04 11:29:46,827 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'event_push_actions_staging_thread_id'. Processed 1 items in 12ms. (total_rate=0.08333333333333333/ms, current_rate=0.08333333333333333/ms, total_updated=1, batch_size=100)
2026-07-04 11:29:47,830 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'event_push_actions_thread_id'
2026-07-04 11:29:47,832 - synapse.storage.background_updates - 752 - INFO - background_updates-0 - Validating constraint event_push_actions_thread_id to event_push_actions
2026-07-04 11:29:47,844 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'event_push_actions_thread_id'. Processed 1 items in 12ms. (total_rate=0.08333333333333333/ms, current_rate=0.08333333333333333/ms, total_updated=1, batch_size=100)
2026-07-04 11:29:47,846 - synapse.storage.background_updates - 552 - INFO - background_updates-0 - Not starting on bg update event_push_drop_null_thread_id_indexes until event_push_summary_thread_id is done
2026-07-04 11:29:48,847 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'event_push_summary_thread_id'
2026-07-04 11:29:48,850 - synapse.storage.background_updates - 752 - INFO - background_updates-0 - Validating constraint event_push_summary_thread_id to event_push_summary
2026-07-04 11:29:48,860 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'event_push_summary_thread_id'. Processed 1 items in 10ms. (total_rate=0.1/ms, current_rate=0.1/ms, total_updated=1, batch_size=100)
2026-07-04 11:29:49,864 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'event_push_drop_null_thread_id_indexes'
2026-07-04 11:29:49,873 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'event_push_drop_null_thread_id_indexes'. Processed 0 items in 7ms. (total_rate=None/ms, current_rate=None/ms, total_updated=0, batch_size=100)
2026-07-04 11:29:50,876 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'current_state_events_stream_ordering_idx'
2026-07-04 11:29:50,878 - synapse.storage.background_updates - 876 - INFO - background_updates-0 - Adding index current_state_events_stream_ordering_idx to current_state_events
2026-07-04 11:29:50,924 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'current_state_events_stream_ordering_idx'. Processed 1 items in 46ms. (total_rate=0.021739130434782608/ms, current_rate=0.021739130434782608/ms, total_updated=1, batch_size=100)
2026-07-04 11:29:51,927 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'local_current_membership_stream_ordering_idx'
2026-07-04 11:29:51,930 - synapse.storage.background_updates - 876 - INFO - background_updates-0 - Adding index local_current_membership_stream_ordering_idx to local_current_membership
2026-07-04 11:29:51,969 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'local_current_membership_stream_ordering_idx'. Processed 1 items in 39ms. (total_rate=0.02564102564102564/ms, current_rate=0.02564102564102564/ms, total_updated=1, batch_size=100)
2026-07-04 11:29:52,979 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'room_memberships_stream_ordering_idx'
2026-07-04 11:29:52,991 - synapse.storage.background_updates - 876 - INFO - background_updates-0 - Adding index room_memberships_stream_ordering_idx to room_memberships
2026-07-04 11:29:53,030 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'room_memberships_stream_ordering_idx'. Processed 1 items in 39ms. (total_rate=0.02564102564102564/ms, current_rate=0.02564102564102564/ms, total_updated=1, batch_size=100)
2026-07-04 11:29:54,033 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'event_forward_extremities_event_id_foreign_key_constraint_update'
2026-07-04 11:29:54,054 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'event_forward_extremities_event_id_foreign_key_constraint_update'. Processed 100 items in 18ms. (total_rate=5.555555555555555/ms, current_rate=5.555555555555555/ms, total_updated=100, batch_size=100)
2026-07-04 11:29:55,058 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'e2e_room_keys_index_room_id'
2026-07-04 11:29:55,060 - synapse.storage.background_updates - 876 - INFO - background_updates-0 - Adding index e2e_room_keys_room_id to e2e_room_keys
2026-07-04 11:29:55,096 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'e2e_room_keys_index_room_id'. Processed 1 items in 36ms. (total_rate=0.027777777777777776/ms, current_rate=0.02777777777777778/ms, total_updated=1, batch_size=100)
2026-07-04 11:29:56,100 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'room_account_data_index_room_id'
2026-07-04 11:29:56,102 - synapse.storage.background_updates - 876 - INFO - background_updates-0 - Adding index room_account_data_room_id to room_account_data
2026-07-04 11:29:56,139 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'room_account_data_index_room_id'. Processed 1 items in 38ms. (total_rate=0.02631578947368421/ms, current_rate=0.02631578947368421/ms, total_updated=1, batch_size=100)
2026-07-04 11:29:57,143 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'event_push_summary_index_room_id'
2026-07-04 11:29:57,145 - synapse.storage.background_updates - 876 - INFO - background_updates-0 - Adding index event_push_summary_index_room_id to event_push_summary
2026-07-04 11:29:57,178 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'event_push_summary_index_room_id'. Processed 1 items in 33ms. (total_rate=0.030303030303030304/ms, current_rate=0.030303030303030304/ms, total_updated=1, batch_size=100)
2026-07-04 11:29:58,181 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'event_auth_chain_links_origin_index'
2026-07-04 11:29:58,183 - synapse.storage.background_updates - 876 - INFO - background_updates-0 - Adding index event_auth_chain_links_origin_index to event_auth_chain_links
2026-07-04 11:29:58,220 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'event_auth_chain_links_origin_index'. Processed 1 items in 37ms. (total_rate=0.02702702702702703/ms, current_rate=0.02702702702702703/ms, total_updated=1, batch_size=100)
2026-07-04 11:29:58,442 - synapse.storage.databases.main.event_push_actions - 1392 - INFO - rotate_notifs-0 - Rotating notifications
2026-07-04 11:29:58,448 - synapse.storage.databases.main.event_push_actions - 1595 - INFO - rotate_notifs-0 - Rotating notifications up to: 1
2026-07-04 11:29:58,454 - synapse.storage.databases.main.event_push_actions - 1681 - INFO - rotate_notifs-0 - Rotating notifications, handling 0 rows
2026-07-04 11:29:58,466 - synapse.storage.databases.main.event_push_actions - 1766 - INFO - rotate_notifs-0 - Rotating notifications, deleted 0 push actions
2026-07-04 11:29:58,562 - synapse.util.caches.lrucache - 220 - INFO - LruCache._expire_old_entries-0 - Dropped 0 items from caches
2026-07-04 11:29:59,223 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'access_tokens_refresh_token_id_idx'
2026-07-04 11:29:59,226 - synapse.storage.background_updates - 876 - INFO - background_updates-0 - Adding index access_tokens_refresh_token_id_idx to access_tokens
2026-07-04 11:29:59,257 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'access_tokens_refresh_token_id_idx'. Processed 1 items in 31ms. (total_rate=0.03225806451612903/ms, current_rate=0.03225806451612903/ms, total_updated=1, batch_size=100)
2026-07-04 11:30:00,261 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'cleanup_device_federation_outbox'
2026-07-04 11:30:00,273 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'cleanup_device_federation_outbox'. Processed 100 items in 10ms. (total_rate=10.0/ms, current_rate=10.0/ms, total_updated=100, batch_size=100)
2026-07-04 11:30:01,277 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'receipts_room_id_event_id_index'
2026-07-04 11:30:01,279 - synapse.storage.background_updates - 876 - INFO - background_updates-0 - Adding index receipts_linearized_event_id to receipts_linearized
2026-07-04 11:30:01,321 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'receipts_room_id_event_id_index'. Processed 1 items in 42ms. (total_rate=0.023809523809523808/ms, current_rate=0.023809523809523808/ms, total_updated=1, batch_size=100)
2026-07-04 11:30:02,324 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'current_state_events_members_room_index'
2026-07-04 11:30:02,328 - synapse.storage.background_updates - 876 - INFO - background_updates-0 - Adding index current_state_events_members_room_index to current_state_events
2026-07-04 11:30:02,379 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'current_state_events_members_room_index'. Processed 1 items in 51ms. (total_rate=0.0196078431372549/ms, current_rate=0.0196078431372549/ms, total_updated=1, batch_size=100)
2026-07-04 11:30:02,382 - synapse.storage.background_updates - 552 - INFO - background_updates-0 - Not starting on bg update sliding_sync_joined_rooms_bg_update until sliding_sync_prefill_joined_rooms_to_recalculate_table_bg_update is done
2026-07-04 11:30:03,384 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'sliding_sync_membership_snapshots_bg_update'
2026-07-04 11:30:03,414 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'sliding_sync_membership_snapshots_bg_update'. Processed 0 items in 28ms. (total_rate=None/ms, current_rate=None/ms, total_updated=0, batch_size=100)
2026-07-04 11:30:04,415 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'sliding_sync_membership_snapshots_bg_update'
2026-07-04 11:30:04,437 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'sliding_sync_membership_snapshots_bg_update'. Processed 0 items in 19ms. (total_rate=None/ms, current_rate=None/ms, total_updated=0, batch_size=100)
2026-07-04 11:30:04,439 - synapse.storage.background_updates - 552 - INFO - background_updates-0 - Not starting on bg update sliding_sync_joined_rooms_bg_update until sliding_sync_prefill_joined_rooms_to_recalculate_table_bg_update is done
2026-07-04 11:30:05,440 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'sliding_sync_prefill_joined_rooms_to_recalculate_table_bg_update'
2026-07-04 11:30:05,456 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'sliding_sync_prefill_joined_rooms_to_recalculate_table_bg_update'. Processed 0 items in 14ms. (total_rate=None/ms, current_rate=None/ms, total_updated=0, batch_size=100)
2026-07-04 11:30:06,459 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'sliding_sync_joined_rooms_bg_update'
2026-07-04 11:30:06,471 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'sliding_sync_joined_rooms_bg_update'. Processed 0 items in 9ms. (total_rate=None/ms, current_rate=None/ms, total_updated=0, batch_size=100)
2026-07-04 11:30:07,474 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'sliding_sync_membership_snapshots_fix_forgotten_column_bg_update'
2026-07-04 11:30:07,490 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'sliding_sync_membership_snapshots_fix_forgotten_column_bg_update'. Processed 0 items in 14ms. (total_rate=None/ms, current_rate=None/ms, total_updated=0, batch_size=100)
2026-07-04 11:30:08,494 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'add_otk_ts_added_index'
2026-07-04 11:30:08,497 - synapse.storage.background_updates - 876 - INFO - background_updates-0 - Adding index e2e_one_time_keys_json_user_id_device_id_algorithm_ts_added_idx to e2e_one_time_keys_json
2026-07-04 11:30:08,552 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'add_otk_ts_added_index'. Processed 1 items in 56ms. (total_rate=0.017857142857142856/ms, current_rate=0.017857142857142856/ms, total_updated=1, batch_size=100)
2026-07-04 11:30:09,555 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'current_state_delta_stream_room_index'
2026-07-04 11:30:09,557 - synapse.storage.background_updates - 876 - INFO - background_updates-0 - Adding index current_state_delta_stream_room_idx to current_state_delta_stream
2026-07-04 11:30:09,599 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'current_state_delta_stream_room_index'. Processed 1 items in 42ms. (total_rate=0.023809523809523808/ms, current_rate=0.023809523809523808/ms, total_updated=1, batch_size=100)
2026-07-04 11:30:10,602 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'sliding_sync_connection_room_configs_required_state_id_idx'
2026-07-04 11:30:10,605 - synapse.storage.background_updates - 876 - INFO - background_updates-0 - Adding index sliding_sync_connection_room_configs_required_state_id_idx to sliding_sync_connection_room_configs
2026-07-04 11:30:10,641 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'sliding_sync_connection_room_configs_required_state_id_idx'. Processed 1 items in 37ms. (total_rate=0.02702702702702703/ms, current_rate=0.02702702702702703/ms, total_updated=1, batch_size=100)
2026-07-04 11:30:11,643 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'events_received_ts_index'
2026-07-04 11:30:11,646 - synapse.storage.background_updates - 876 - INFO - background_updates-0 - Adding index received_ts_idx to events
2026-07-04 11:30:11,690 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'events_received_ts_index'. Processed 1 items in 44ms. (total_rate=0.022727272727272728/ms, current_rate=0.022727272727272728/ms, total_updated=1, batch_size=100)
2026-07-04 11:30:12,693 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'local_media_repository_sha256_idx'
2026-07-04 11:30:12,695 - synapse.storage.background_updates - 876 - INFO - background_updates-0 - Adding index local_media_repository_sha256 to local_media_repository
2026-07-04 11:30:12,734 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'local_media_repository_sha256_idx'. Processed 1 items in 39ms. (total_rate=0.02564102564102564/ms, current_rate=0.02564102564102564/ms, total_updated=1, batch_size=100)
2026-07-04 11:30:13,737 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'remote_media_cache_sha256_idx'
2026-07-04 11:30:13,740 - synapse.storage.background_updates - 876 - INFO - background_updates-0 - Adding index remote_media_cache_sha256 to remote_media_cache
2026-07-04 11:30:13,786 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'remote_media_cache_sha256_idx'. Processed 1 items in 46ms. (total_rate=0.021739130434782608/ms, current_rate=0.021739130434782608/ms, total_updated=1, batch_size=100)
2026-07-04 11:30:14,789 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'sliding_sync_membership_snapshots_membership_event_id_idx'
2026-07-04 11:30:14,791 - synapse.storage.background_updates - 876 - INFO - background_updates-0 - Adding index sliding_sync_membership_snapshots_membership_event_id_idx to sliding_sync_membership_snapshots
2026-07-04 11:30:14,824 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'sliding_sync_membership_snapshots_membership_event_id_idx'. Processed 1 items in 33ms. (total_rate=0.030303030303030304/ms, current_rate=0.030303030303030304/ms, total_updated=1, batch_size=100)
2026-07-04 11:30:15,826 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'mark_unreferenced_state_groups_for_deletion_bg_update'
2026-07-04 11:30:15,837 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'mark_unreferenced_state_groups_for_deletion_bg_update'. Processed 100 items in 8ms. (total_rate=12.5/ms, current_rate=12.5/ms, total_updated=100, batch_size=100)
2026-07-04 11:30:16,841 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'sliding_sync_membership_snapshots_user_id_stream_ordering'
2026-07-04 11:30:16,843 - synapse.storage.background_updates - 876 - INFO - background_updates-0 - Adding index sliding_sync_membership_snapshots_user_id_stream_ordering to sliding_sync_membership_snapshots
2026-07-04 11:30:16,896 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'sliding_sync_membership_snapshots_user_id_stream_ordering'. Processed 1 items in 53ms. (total_rate=0.018867924528301886/ms, current_rate=0.018867924528301886/ms, total_updated=1, batch_size=100)
2026-07-04 11:30:17,899 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'fixup_max_depth_cap'
2026-07-04 11:30:17,912 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'fixup_max_depth_cap'. Processed 0 items in 11ms. (total_rate=None/ms, current_rate=None/ms, total_updated=0, batch_size=100)
2026-07-04 11:30:18,915 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'device_federation_inbox_received_ts_index'
2026-07-04 11:30:18,917 - synapse.storage.background_updates - 876 - INFO - background_updates-0 - Adding index device_federation_inbox_received_ts_index to device_federation_inbox
2026-07-04 11:30:18,956 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'device_federation_inbox_received_ts_index'. Processed 1 items in 39ms. (total_rate=0.02564102564102564/ms, current_rate=0.02564102564102564/ms, total_updated=1, batch_size=100)
2026-07-04 11:30:19,959 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'event_txn_id_device_id_txn_id2'
2026-07-04 11:30:19,961 - synapse.storage.background_updates - 876 - INFO - background_updates-0 - Adding index event_txn_id_device_id_txn_id2 to event_txn_id_device_id
2026-07-04 11:30:20,015 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'event_txn_id_device_id_txn_id2'. Processed 1 items in 54ms. (total_rate=0.018518518518518517/ms, current_rate=0.018518518518518517/ms, total_updated=1, batch_size=100)
2026-07-04 11:30:21,017 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'delayed_events_idx'
2026-07-04 11:30:21,020 - synapse.storage.background_updates - 876 - INFO - background_updates-0 - Adding index delayed_events_idx to delayed_events
2026-07-04 11:30:21,072 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'delayed_events_idx'. Processed 1 items in 52ms. (total_rate=0.019230769230769232/ms, current_rate=0.019230769230769232/ms, total_updated=1, batch_size=100)
2026-07-04 11:30:22,076 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'flag_existing_quarantined_media'
2026-07-04 11:30:22,078 - synapse.storage.databases.main.room - 321 - INFO - background_updates-0 - Flagging existing quarantined media with local offset and remote offset /
2026-07-04 11:30:22,098 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'flag_existing_quarantined_media'. Processed 0 items in 19ms. (total_rate=None/ms, current_rate=None/ms, total_updated=0, batch_size=100)
2026-07-04 11:30:23,101 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'redactions_recheck'
2026-07-04 11:30:23,114 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'redactions_recheck'. Processed 0 items in 11ms. (total_rate=None/ms, current_rate=None/ms, total_updated=0, batch_size=100)
2026-07-04 11:30:24,118 - synapse.storage.background_updates - 584 - INFO - background_updates-0 - Starting update batch on background update 'device_lists_changes_in_room_inserted_ts_idx'
2026-07-04 11:30:24,121 - synapse.storage.background_updates - 876 - INFO - background_updates-0 - Adding index device_lists_changes_in_room_inserted_ts_idx to device_lists_changes_in_room
2026-07-04 11:30:24,164 - synapse.storage.background_updates - 627 - INFO - background_updates-0 - Running background update 'device_lists_changes_in_room_inserted_ts_idx'. Processed 1 items in 43ms. (total_rate=0.023255813953488372/ms, current_rate=0.023255813953488375/ms, total_updated=1, batch_size=100)
2026-07-04 11:30:24,166 - synapse.storage.background_updates - 434 - INFO - background_updates-0 - No more background updates to do. Unscheduling background update task.
2026-07-04 11:30:28,441 - synapse.storage.databases.main.event_push_actions - 1392 - INFO - rotate_notifs-1 - Rotating notifications
2026-07-04 11:30:28,447 - synapse.storage.databases.main.event_push_actions - 1595 - INFO - rotate_notifs-1 - Rotating notifications up to: 1
2026-07-04 11:30:28,453 - synapse.storage.databases.main.event_push_actions - 1681 - INFO - rotate_notifs-1 - Rotating notifications, handling 0 rows
2026-07-04 11:30:28,470 - synapse.storage.databases.main.event_push_actions - 1766 - INFO - rotate_notifs-1 - Rotating notifications, deleted 0 push actions
2026-07-04 11:30:28,562 - synapse.util.caches.lrucache - 220 - INFO - LruCache._expire_old_entries-1 - Dropped 0 items from caches
2026-07-04 11:30:28,586 - synapse.util.task_scheduler - 422 - INFO - task-delete_old_otks-0-delete_old_otks_task - Task complete: 0.013sec (0.001sec, 0.000sec) (0.003sec/0.008sec/2) [0 dbevts] None, None
2026-07-04 11:30:58,444 - synapse.storage.databases.main.event_push_actions - 1392 - INFO - rotate_notifs-2 - Rotating notifications
2026-07-04 11:30:58,451 - synapse.storage.databases.main.event_push_actions - 1595 - INFO - rotate_notifs-2 - Rotating notifications up to: 1
2026-07-04 11:30:58,457 - synapse.storage.databases.main.event_push_actions - 1681 - INFO - rotate_notifs-2 - Rotating notifications, handling 0 rows
2026-07-04 11:30:58,470 - synapse.storage.databases.main.event_push_actions - 1766 - INFO - rotate_notifs-2 - Rotating notifications, deleted 0 push actions
2026-07-04 11:30:58,562 - synapse.util.caches.lrucache - 220 - INFO - LruCache._expire_old_entries-2 - Dropped 0 items from caches
2026-07-04 11:31:28,445 - synapse.storage.databases.main.event_push_actions - 1392 - INFO - rotate_notifs-3 - Rotating notifications
2026-07-04 11:31:28,448 - synapse.storage.databases.main.event_push_actions - 1595 - INFO - rotate_notifs-3 - Rotating notifications up to: 1
2026-07-04 11:31:28,453 - synapse.storage.databases.main.event_push_actions - 1681 - INFO - rotate_notifs-3 - Rotating notifications, handling 0 rows
2026-07-04 11:31:28,469 - synapse.storage.databases.main.event_push_actions - 1766 - INFO - rotate_notifs-3 - Rotating notifications, deleted 0 push actions
2026-07-04 11:31:28,562 - synapse.util.caches.lrucache - 220 - INFO - LruCache._expire_old_entries-3 - Dropped 0 items from caches