container-test-run-matrix-synapse
checks.aarch64-linux.matrix-synapse
· build #631
· raw
1additionally exposed symbols:2 machine,3 start_all, machines, driver, Machine, wait_for_signal4Starting machine567<<< NixOS Stage 2 >>>89booting system configuration /nix/store/mm8k88hb63s9xj5pzzf1clgmwca79zz6-nixos-system-machine-test10running activation script...11setting up /etc...122: host0@if3: <BROADCAST,MULTICAST> mtu 1500 qdisc noop state DOWN mode DEFAULT group default qlen 100013 link/ether 0e:74:26:22:81:c2 brd ff:ff:ff:ff:ff:ff link-netnsid 014setting up age secrets...15starting systemd...16systemd 260.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)17Detected virtualization systemd-nspawn.18Detected architecture arm64.19Detected first boot.20Initializing machine ID from container UUID.21Applying preset policy.22Populated /etc with preset unit settings.23Queued start job for default target Multi-User System.24252627<<< Welcome to NixOS test (aarch64) - console >>>28+ systemd-run /bin/sh -c '/nix/store/wjm08iz4sdw5qgmx58dimv8ch7gbyyaj-coreutils-9.11/bin/sleep 999999999 && echo b633fda0-f332-4e78-a393-56afaf146d76'29Running as unit: run-p481-i8653921.service; invocation ID: fac15168a94a4158a4ae78a28016815830To attach to container machine run on the same machine that runs the test:31 sudo nsenter --user --target $(\pgrep -f '^/bin/sh.*b633fda0-f332-4e78-a393-56afaf146d76') --mount --uts --ipc --net --pid --cgroup /bin/sh -c bash 3233To inject external network and continue test, run:34sudo /nix/store/akbq19lqha5w5h9hcchn309znqm94pq0-python3-3.13.15/bin/python3.13 /nix/store/qrxpb4s36qq6d4f5n5c5d78f1y3lvyhy-test-driver-0.0.1/lib/python3.13/site-packages/test_driver/inject_network.py b633fda0-f332-4e78-a393-56afaf146d7635+ systemctl --no-pager show matrix-synapse36+ systemctl --no-pager show matrix-synapse37+ systemctl --no-pager show matrix-synapse38+ systemctl --no-pager show matrix-synapse39+ systemctl --no-pager show matrix-synapse40+ systemctl --no-pager show matrix-synapse41+ systemctl --no-pager show matrix-synapse42+ nc -z -v ::1 800843Connection to ::1 8008 port [tcp/http-alt] succeeded!44machine: waiting for success: curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test'45+ curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test'46(finished: waiting for success: curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test', in 0.02 seconds)47+ systemctl restart matrix-synapse48+ journalctl -u matrix-synapse --no-pager49Sep 11 05:14:26 machine systemd[1]: Starting Synapse Matrix homeserver...50Sep 11 05:14:29 machine matrix-synapse-pre-start[476]: Generating signing key file /var/lib/matrix-synapse/homeserver.signing.key51Sep 11 05:14:31 machine synapse_homeserver[491]: This server is configured to use 'matrix.org' as its trusted key server via the52Sep 11 05:14:31 machine synapse_homeserver[491]: 'trusted_key_servers' config option. 'matrix.org' is a good choice for a key53Sep 11 05:14:31 machine synapse_homeserver[491]: server since it is long-lived, stable and trusted. However, some admins may54Sep 11 05:14:31 machine synapse_homeserver[491]: wish to use another server for this purpose.55Sep 11 05:14:31 machine synapse_homeserver[491]: To suppress this warning and continue using 'matrix.org', admins should set56Sep 11 05:14:31 machine synapse_homeserver[491]: 'suppress_key_server_warning' to 'true' in homeserver.yaml.57Sep 11 05:14:31 machine synapse_homeserver[491]: --------------------------------------------------------------------------------58Sep 11 05:14:32 machine synapse[491]: synapse.config.logger: [main] ***** STARTING SERVER *****59Sep 11 05:14:32 machine synapse[491]: synapse.config.logger: [main] Server /nix/store/dc3p08cmvzgsnfpwr26r4f1z2skd4j7s-matrix-synapse-1.160.0/bin/synapse_homeserver version 1.160.060Sep 11 05:14:32 machine synapse[491]: synapse.config.logger: [main] Copyright (c) 2023 New Vector, Inc61Sep 11 05:14:32 machine synapse[491]: synapse.config.logger: [main] Licensed under the AGPL 3.0 license. Website: https://github.com/element-hq/synapse62Sep 11 05:14:32 machine synapse[491]: synapse.config.logger: [main] Server hostname: clan.test63Sep 11 05:14:32 machine synapse[491]: synapse.config.logger: [main] Public Base URL: https://clan.test/64Sep 11 05:14:32 machine synapse[491]: synapse.config.logger: [main] Instance name: master65Sep 11 05:14:32 machine synapse[491]: synapse.config.logger: [main] Twisted reactor: EPollReactor66Sep 11 05:14:32 machine synapse[491]: synapse.app.homeserver: [main] Setting up server67Sep 11 05:14:32 machine synapse[491]: synapse.server: [main] Setting up.68Sep 11 05:14:32 machine synapse[491]: synapse.storage.databases: [main] [database config 'master']: Checking database server69Sep 11 05:14:32 machine synapse[491]: synapse.storage.databases: [main] [database config 'master']: Preparing for databases ['main', 'state']70Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] ['main', 'state']: Checking existing schema version71Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] ['main', 'state']: Initialising new database72Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v7373Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/01event_failed_pull_attempts.sql74Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/02add_pusher_enabled.sql75Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/02room_id_indexes_for_purging.sql76Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/03pusher_device_id.sql77Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/03users_approved_column.sql78Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/04partial_join_details.sql79Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/04pending_device_list_updates.sql80Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/05old_push_actions.sql.postgres81Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/06thread_notifications_thread_id_idx.sql82Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/08thread_receipts_non_null.sql.postgres83Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/09partial_joined_via_destination.sql84Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/09threads_table.sql85Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Running 73/10_update_sqlite_fts4_tokenizer.py:run_create86Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/10login_tokens.sql87Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/11event_search_room_id_n_distinct.sql.postgres88Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/12refactor_device_list_outbound_pokes.sql89Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/13add_device_lists_index.sql90Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/20_un_partial_stated_room_stream.sql91Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/21_un_partial_stated_room_stream_seq.sql.postgres92Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/22_rebuild_user_dir_stats.sql93Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/22_un_partial_stated_event_stream.sql94Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/23_fix_thread_index.sql95Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/23_un_partial_stated_room_stream_seq.sql.postgres96Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/24_events_jump_to_date_index.sql97Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/25drop_presence.sql98Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v7499Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 74/01_user_directory_stale_remote_users.sql100Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 74/02_set_device_id_for_pushers_bg_update.sql101Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 74/03_membership_tables_event_stream_ordering.sql.postgres102Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 74/03_room_membership_index.sql103Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 74/04_delete_e2e_backup_keys_for_deactivated_users.sql104Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Running 74/04_membership_tables_event_stream_ordering_triggers.py:run_create105Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 74/05_events_txn_id_device_id.sql106Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 74/90COMMENTS_destinations.sql.postgres107Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v75108Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v76109Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 76/01_add_profiles_full_user_id_column.sql110Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 76/02_add_user_filters_full_user_id_column.sql111Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 76/03_per_user_experimental_features.sql112Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 76/04_add_room_forgetter.sql113Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v77114Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/01_add_profiles_not_valid_check.sql.postgres115Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/02_add_user_filters_not_valid_check.sql.postgres116Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 77/03bg_populate_full_user_id_profiles.sql117Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 77/04bg_populate_full_user_id_user_filters.sql118Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 77/05thread_notifications_backfill.sql119Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_actions.sql.postgres120Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_actions_staging.sql.postgres121Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_summary.sql.postgres122Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 77/14bg_indices_event_stream_ordering.sql123Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v78124Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Running 78/03event_extremities_constraints.py:run_create125Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Running 78/04_add_full_user_id_index_user_filters.py:run_create126Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v79127Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 79/03_read_write_locks_triggers.sql.postgres128Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Running 79/04_mitigate_stream_ordering_update_race.py:run_create129Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 79/05_read_write_locks_triggers.sql.postgres130Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v80131Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 80/01_users_alter_locked.sql132Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/02_read_write_locks_unlogged.sql.postgres133Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 80/02_scheduled_tasks.sql134Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/03_read_write_locks_triggers.sql.postgres135Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/04_read_write_locks_deadlock.sql.postgres136Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v81137Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v82138Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 82/02_scheduled_tasks_index.sql139Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 82/04_add_indices_for_purging_rooms.sql140Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 82/05gaps.sql141Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v83142Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 83/01_drop_old_tables.sql143Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 83/05_cross_signing_key_update_grant.sql144Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 83/06_event_push_summary_room.sql145Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v84146Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 84/01_auth_links_stats.sql.postgres147Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 84/02_auth_links_index.sql148Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 84/03_auth_links_analyze.sql.postgres149Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 84/04_access_token_index.sql150Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v85151Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 85/01_add_suspended.sql152Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 85/02_add_instance_names.sql153Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 85/03_new_sequences.sql.postgres154Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 85/04_cleanup_device_federation_outbox.sql155Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 85/05_add_instance_names_converted_pos.sql156Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 85/06_add_room_reports.sql157Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v86158Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 86/01_authenticate_media.sql159Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 86/02_receipts_event_id_index.sql160Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v87161Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 87/01_sliding_sync_memberships.sql162Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 87/02_per_connection_state.sql163Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 87/03_current_state_index.sql164Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v88165Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 88/01_add_delayed_events.sql166Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 88/01_custom_profile_fields.sql167Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 88/02_fix_sliding_sync_membership_snapshots_forgotten_column.sql168Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 88/03_add_otk_ts_added_index.sql169Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 88/04_current_state_delta_index.sql170Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 88/05_drop_old_otks.sql.postgres171Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 88/05_sliding_sync_room_config_index.sql172Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 88/06_events_received_ts_index.sql173Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v89174Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 89/01_sliding_sync_membership_snapshot_index.sql175Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 89/01_state_groups_deletion.sql176Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v90177Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 90/01_add_column_participant_room_memberships_table.sql178Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 90/02_delete_unreferenced_state_groups.sql179Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 90/03_remove_old_deletion_bg_update.sql180Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v91181Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 91/01_media_hash.sql182Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v92183Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/01_remove_trigger.sql.postgres184Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/02_remove_populate_participant_bg_update.sql185Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/04_ss_membership_snapshot_idx.sql186Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/04_thread_subscriptions.sql187Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/04_thread_subscriptions_seq.sql.postgres188Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/05_fixup_max_depth_cap.sql189Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/05_thread_subscriptions_comments.sql.postgres190Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/06_device_federation_inbox_index.sql191Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/06_threads_last_sent_stream_ordering_comments.sql.postgres192Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/07_add_user_reports.sql193Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/07_event_txn_id_device_id_txn_id2.sql194Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/08_room_ban_redactions.sql195Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/08_thread_subscriptions_seq_fixup.sql.postgres196Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/09_thread_subscriptions_update.sql197Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/09_thread_subscriptions_update.sql.postgres198Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v93199Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 93/01_add_delayed_events.sql200Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 93/01_sticky_events.sql201Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 93/01_sticky_events_seq.sql.postgres202Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 93/02_sliding_sync_members.sql203Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 93/03_sss_pos_last_used.sql204Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Running 93/04_make_delayed_event_content_text.py:run_create205Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v94206Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/01_redactions_recheck.sql207Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/02_redactions_recheck_bg_update.sql208Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/03_device_lists_room_timestamp.sql209Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/03_quarantined_media_tracking.sql210Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 94/03_quarantined_media_tracking_seq.sql.postgres211Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/03_state_dag_fwd_extrems.sql212Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/04_device_lists_changes_max_pruned.sql213Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/05_rerun_flag_existing_quarantined_media.sql214Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/06_sliding_sync_connections_last_used_ts_index.sql215Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/07_profile_updates.sql216Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 94/07_profile_updates_seq.sql.postgres217Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/07_sliding_sync_lazy_members_position_index.sql218Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/08_device_lists_changes_in_room_unconverted_idx.sql219Sep 11 05:14:32 machine synapse[491]: synapse.storage.prepare_database: [main] Schema now up to date220Sep 11 05:14:32 machine synapse[491]: synapse.storage.databases: [main] [database config 'master']: Starting 'main' database221Sep 11 05:14:32 machine synapse[491]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules(id): 1222Sep 11 05:14:32 machine synapse[491]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules_enable(id): 1223Sep 11 05:14:32 machine synapse[491]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 month ago224Sep 11 05:14:32 machine synapse[491]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 month ago: it's 0225Sep 11 05:14:32 machine synapse[491]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 day ago226Sep 11 05:14:32 machine synapse[491]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 day ago: it's 0227Sep 11 05:14:32 machine synapse[491]: synapse.storage.util.id_generators: [main] Initialising stream generator for access_tokens(id): 1228Sep 11 05:14:32 machine synapse[491]: synapse.storage.util.id_generators: [main] Initialising stream generator for refresh_tokens(id): 1229Sep 11 05:14:32 machine synapse[491]: synapse.storage.util.id_generators: [main] Initialising stream generator for event_reports(id): 1230Sep 11 05:14:32 machine synapse[491]: synapse.storage.util.id_generators: [main] Initialising stream generator for room_reports(id): 1231Sep 11 05:14:32 machine synapse[491]: synapse.storage.util.id_generators: [main] Initialising stream generator for user_reports(id): 1232Sep 11 05:14:32 machine synapse[491]: synapse.storage.databases: [main] [database config 'master']: Starting 'state' database233Sep 11 05:14:32 machine synapse[491]: synapse.storage.databases: [main] [database config 'master']: prepared234Sep 11 05:14:32 machine synapse[491]: synapse.server: [main] Finished setting up.235Sep 11 05:14:32 machine synapse[491]: twisted: [main] Redirected stdout/stderr to logs236Sep 11 05:14:32 machine synapse[491]: synapse.app.homeserver: [main] Running237Sep 11 05:14:32 machine synapse[491]: synapse.app.homeserver: [main] Set file limit to: 524288238Sep 11 05:14:32 machine synapse[491]: synapse.push.pusher: [call_when_running] email enable notifs: False239Sep 11 05:14:32 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'profile'240Sep 11 05:14:32 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.device_list_update'241Sep 11 05:14:32 machine synapse[491]: synapse.util.caches.lrucache: [call_when_running] Expiring LRU caches after 1800 seconds242Sep 11 05:14:32 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.presence'243Sep 11 05:14:32 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.typing'244Sep 11 05:14:32 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'directory'245Sep 11 05:14:32 machine synapse[491]: synapse.handlers.deactivate_account: [user_parter_loop-0] Starting user parter246Sep 11 05:14:32 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.receipt'247Sep 11 05:14:32 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.signing_key_update'248Sep 11 05:14:32 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'org.matrix.signing_key_update'249Sep 11 05:14:32 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'client_keys'250Sep 11 05:14:32 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.direct_to_device'251Sep 11 05:14:32 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.health.HealthResource object at 0xffffee0595a0> to path b'/health'252Sep 11 05:14:32 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.EncodingResourceWrapper object at 0xffffee313ca0> to path b'/_matrix/client'253Sep 11 05:14:32 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffee3199a0> to path b'/.well-known'254Sep 11 05:14:32 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.http.server.JsonResource object at 0xffffee313e20> to path b'/_synapse/admin'255Sep 11 05:14:32 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.pick_idp.PickIdpResource object at 0xffffee319e20> to path b'/_synapse/client/pick_idp'256Sep 11 05:14:32 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffe1a043a0> to path b'/_synapse/client/pick_username'257Sep 11 05:14:32 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.new_user_consent.NewUserConsentResource object at 0xffffee31a420> to path b'/_synapse/client/new_user_consent'258Sep 11 05:14:32 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.sso_register.SsoRegisterResource object at 0xffffee31a5a0> to path b'/_synapse/client/sso_register'259Sep 11 05:14:32 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.unsubscribe.UnsubscribeResource object at 0xffffee31a720> to path b'/_synapse/client/unsubscribe'260Sep 11 05:14:32 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.media_upload_limit_exceeded.MediaUploadLimitExceededResource object at 0xffffee31a8a0> to path b'/_synapse/client/media_upload_limit_exceeded'261Sep 11 05:14:32 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching FilePath('/nix/store/dc3p08cmvzgsnfpwr26r4f1z2skd4j7s-matrix-synapse-1.160.0/lib/python3.13/site-packages/synapse/static') to path b'/_matrix/static'262Sep 11 05:14:32 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee31ad20> to path b'/_matrix/media/r0'263Sep 11 05:14:32 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee31ad20> to path b'/_matrix/media/v3'264Sep 11 05:14:32 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee31ad20> to path b'/_matrix/media/v1'265Sep 11 05:14:32 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.federation.transport.server.TransportLayerServer object at 0xffffee31b920> to path b'/_matrix/federation'266Sep 11 05:14:32 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.key.v2.KeyResource object at 0xffffee31eaa0> to path b'/_matrix/key'267Sep 11 05:14:32 machine synapse[491]: twisted: [call_when_running] SynapseSite starting on 8008268Sep 11 05:14:32 machine synapse[491]: synapse.app._base: [call_when_running] Synapse now listening on TCP port 8008269Sep 11 05:14:32 machine synapse[491]: synapse.app._base: [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).270Sep 11 05:14:32 machine synapse[491]: synapse.storage.background_updates: [background_updates-0] Starting background schema updates for database master271Sep 11 05:14:32 machine synapse[491]: synapse.handlers.deactivate_account: [user_parter_loop-0] User parter finished: stopping272Sep 11 05:14:32 machine synapse[491]: synapse.push.pusherpool: [start_pushers-0] Started pushers273Sep 11 05:14:32 machine 4s8l47vbpf1f8d5bs90rbh78zxhjwx0h-matrix-synapse-create-users[520]: Connection to ::1 8008 port [tcp/http-alt] succeeded!274Sep 11 05:14:33 machine synapse[491]: synapse.access.http.8008: [GET-0] ::1 - 8008 - {None} Processed request: 0.001sec/0.001sec ru=(0.003sec, 0.000sec) db=(0.000sec/0.000sec/0) 140B 200 "GET /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]275Sep 11 05:14:33 machine synapse[491]: synapse.handlers.auth: [POST-1] Logging in user @admin:clan.test on device VNJRBOJFVV276Sep 11 05:14:33 machine synapse[491]: synapse.access.http.8008: [POST-1] ::1 - 8008 - {None} Processed request: 0.304sec/0.001sec ru=(0.262sec, 0.005sec) db=(0.005sec/0.033sec/12) 138B 200 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]277Sep 11 05:14:33 machine 4s8l47vbpf1f8d5bs90rbh78zxhjwx0h-matrix-synapse-create-users[521]: Sending registration request...278Sep 11 05:14:33 machine 4s8l47vbpf1f8d5bs90rbh78zxhjwx0h-matrix-synapse-create-users[521]: Success!279Sep 11 05:14:33 machine synapse[491]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'threads_backfill'280Sep 11 05:14:33 machine synapse[491]: synapse.storage.background_updates: [background_updates-0] Running background update 'threads_backfill'. Processed 0 items in 5ms. (total_rate=None/ms, current_rate=None/ms, total_updated=0, batch_size=100)281Sep 11 05:14:34 machine synapse[491]: synapse.access.http.8008: [GET-2] ::1 - 8008 - {None} Processed request: 0.000sec/0.001sec ru=(0.000sec, 0.000sec) db=(0.000sec/0.000sec/0) 140B 200 "GET /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]282Sep 11 05:14:34 machine synapse[491]: synapse.handlers.auth: [POST-3] Logging in user @someuser:clan.test on device EICIDBTLTD283Sep 11 05:14:34 machine synapse[491]: synapse.access.http.8008: [POST-3] ::1 - 8008 - {None} Processed request: 0.288sec/0.001sec ru=(0.255sec, 0.002sec) db=(0.004sec/0.019sec/12) 145B 200 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]284Sep 11 05:14:34 machine 4s8l47vbpf1f8d5bs90rbh78zxhjwx0h-matrix-synapse-create-users[528]: Sending registration request...285Sep 11 05:14:34 machine 4s8l47vbpf1f8d5bs90rbh78zxhjwx0h-matrix-synapse-create-users[528]: Success!286Sep 11 05:14:34 machine systemd[1]: Started Synapse Matrix homeserver.287Sep 11 05:14:34 machine synapse[491]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'device_lists_changes_in_room_by_room_index'288Sep 11 05:14:34 machine synapse[491]: synapse.storage.background_updates: [background_updates-0] Adding index device_lists_changes_in_room_by_room_idx to device_lists_changes_in_room289Sep 11 05:14:34 machine synapse[491]: synapse.storage.background_updates: [background_updates-0] Running background update 'device_lists_changes_in_room_by_room_index'. Processed 1 items in 8ms. (total_rate=0.125/ms, current_rate=0.125/ms, total_updated=1, batch_size=100)290Sep 11 05:14:34 machine synapse[491]: synapse.access.http.8008: [GET-4] ::1 - 8008 - {None} Processed request: 0.001sec/-0.000sec ru=(0.001sec, 0.000sec) db=(0.000sec/0.000sec/0) 10145B 200 "GET /_matrix/static/ HTTP/1.1" "curl/8.21.0" [0 dbevts]291Sep 11 05:14:34 machine systemd[1]: Stopping Synapse Matrix homeserver...292Sep 11 05:14:34 machine synapse[491]: twisted: [sentinel] Received SIGTERM, shutting down.293Sep 11 05:14:34 machine synapse[491]: synapse.storage.databases.main.lock: [LockStore._on_shutdown-0] Dropping held locks due to shutdown294Sep 11 05:14:34 machine synapse[491]: synapse.storage.databases.main.lock: [LockStore._on_shutdown-0] Dropped locks due to shutdown295Sep 11 05:14:34 machine synapse[491]: synapse.handlers.presence: [PresenceHandler._on_shutdown-0] Performing _on_shutdown. Persisting 0 unpersisted changes296Sep 11 05:14:34 machine synapse[491]: synapse.handlers.presence: [PresenceHandler._on_shutdown-0] Finished _on_shutdown297Sep 11 05:14:34 machine synapse[491]: synapse.app._base: [log_shutdown] Shutting down...298Sep 11 05:14:34 machine synapse[491]: twisted: [sentinel] (TCP Port 8008 Closed)299Sep 11 05:14:34 machine synapse[491]: twisted: [sentinel] Main loop terminated.300Sep 11 05:14:34 machine systemd[1]: matrix-synapse.service: Deactivated successfully.301Sep 11 05:14:34 machine systemd[1]: Stopped Synapse Matrix homeserver.302Sep 11 05:14:34 machine systemd[1]: matrix-synapse.service: Consumed 7.601s CPU time over 8.385s wall clock time.303Sep 11 05:14:34 machine systemd[1]: Starting Synapse Matrix homeserver...304Sep 11 05:14:40 machine synapse_homeserver[559]: This server is configured to use 'matrix.org' as its trusted key server via the305Sep 11 05:14:40 machine synapse_homeserver[559]: 'trusted_key_servers' config option. 'matrix.org' is a good choice for a key306Sep 11 05:14:40 machine synapse_homeserver[559]: server since it is long-lived, stable and trusted. However, some admins may307Sep 11 05:14:40 machine synapse_homeserver[559]: wish to use another server for this purpose.308Sep 11 05:14:40 machine synapse_homeserver[559]: To suppress this warning and continue using 'matrix.org', admins should set309Sep 11 05:14:40 machine synapse_homeserver[559]: 'suppress_key_server_warning' to 'true' in homeserver.yaml.310Sep 11 05:14:40 machine synapse_homeserver[559]: --------------------------------------------------------------------------------311Sep 11 05:14:40 machine synapse[559]: synapse.config.logger: [main] ***** STARTING SERVER *****312Sep 11 05:14:40 machine synapse[559]: synapse.config.logger: [main] Server /nix/store/dc3p08cmvzgsnfpwr26r4f1z2skd4j7s-matrix-synapse-1.160.0/bin/synapse_homeserver version 1.160.0313Sep 11 05:14:40 machine synapse[559]: synapse.config.logger: [main] Copyright (c) 2023 New Vector, Inc314Sep 11 05:14:40 machine synapse[559]: synapse.config.logger: [main] Licensed under the AGPL 3.0 license. Website: https://github.com/element-hq/synapse315Sep 11 05:14:40 machine synapse[559]: synapse.config.logger: [main] Server hostname: clan.test316Sep 11 05:14:40 machine synapse[559]: synapse.config.logger: [main] Public Base URL: https://clan.test/317Sep 11 05:14:40 machine synapse[559]: synapse.config.logger: [main] Instance name: master318Sep 11 05:14:40 machine synapse[559]: synapse.config.logger: [main] Twisted reactor: EPollReactor319Sep 11 05:14:40 machine synapse[559]: synapse.app.homeserver: [main] Setting up server320Sep 11 05:14:40 machine synapse[559]: synapse.server: [main] Setting up.321Sep 11 05:14:40 machine synapse[559]: synapse.storage.databases: [main] [database config 'master']: Checking database server322Sep 11 05:14:40 machine synapse[559]: synapse.storage.databases: [main] [database config 'master']: Preparing for databases ['main', 'state']323Sep 11 05:14:40 machine synapse[559]: synapse.storage.prepare_database: [main] ['main', 'state']: Checking existing schema version324Sep 11 05:14:40 machine synapse[559]: synapse.storage.prepare_database: [main] ['main', 'state']: Existing schema is 94 (+13 deltas)325Sep 11 05:14:40 machine synapse[559]: synapse.storage.databases.main: [main] Checking database for consistency with configuration...326Sep 11 05:14:40 machine synapse[559]: synapse.storage.prepare_database: [main] Applying schema deltas for v94327Sep 11 05:14:40 machine synapse[559]: synapse.storage.prepare_database: [main] Schema now up to date328Sep 11 05:14:40 machine synapse[559]: synapse.storage.databases: [main] [database config 'master']: Starting 'main' database329Sep 11 05:14:40 machine synapse[559]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules(id): 1330Sep 11 05:14:40 machine synapse[559]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules_enable(id): 1331Sep 11 05:14:40 machine synapse[559]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 month ago332Sep 11 05:14:40 machine synapse[559]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 month ago: it's 0333Sep 11 05:14:40 machine synapse[559]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 day ago334Sep 11 05:14:40 machine synapse[559]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 day ago: it's 0335Sep 11 05:14:40 machine synapse[559]: synapse.storage.util.id_generators: [main] Initialising stream generator for access_tokens(id): 3336Sep 11 05:14:40 machine synapse[559]: synapse.storage.util.id_generators: [main] Initialising stream generator for refresh_tokens(id): 1337Sep 11 05:14:40 machine synapse[559]: synapse.storage.util.id_generators: [main] Initialising stream generator for event_reports(id): 1338Sep 11 05:14:40 machine synapse[559]: synapse.storage.util.id_generators: [main] Initialising stream generator for room_reports(id): 1339Sep 11 05:14:40 machine synapse[559]: synapse.storage.util.id_generators: [main] Initialising stream generator for user_reports(id): 1340Sep 11 05:14:40 machine synapse[559]: synapse.storage.databases: [main] [database config 'master']: Starting 'state' database341Sep 11 05:14:40 machine synapse[559]: synapse.storage.databases: [main] [database config 'master']: prepared342Sep 11 05:14:40 machine synapse[559]: synapse.server: [main] Finished setting up.343Sep 11 05:14:40 machine synapse[559]: twisted: [main] Redirected stdout/stderr to logs344Sep 11 05:14:40 machine synapse[559]: synapse.app.homeserver: [main] Running345Sep 11 05:14:40 machine synapse[559]: synapse.app.homeserver: [main] Set file limit to: 524288346Sep 11 05:14:40 machine synapse[559]: synapse.push.pusher: [call_when_running] email enable notifs: False347Sep 11 05:14:40 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'profile'348Sep 11 05:14:40 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.device_list_update'349Sep 11 05:14:40 machine synapse[559]: synapse.util.caches.lrucache: [call_when_running] Expiring LRU caches after 1800 seconds350Sep 11 05:14:40 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.presence'351Sep 11 05:14:40 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.typing'352Sep 11 05:14:40 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'directory'353Sep 11 05:14:40 machine synapse[559]: synapse.handlers.deactivate_account: [user_parter_loop-0] Starting user parter354Sep 11 05:14:40 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.receipt'355Sep 11 05:14:40 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.signing_key_update'356Sep 11 05:14:40 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'org.matrix.signing_key_update'357Sep 11 05:14:40 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'client_keys'358Sep 11 05:14:40 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.direct_to_device'359Sep 11 05:14:40 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.health.HealthResource object at 0xffffee029720> to path b'/health'360Sep 11 05:14:40 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.EncodingResourceWrapper object at 0xffffee333e20> to path b'/_matrix/client'361Sep 11 05:14:40 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffee339b20> to path b'/.well-known'362Sep 11 05:14:40 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.http.server.JsonResource object at 0xffffee333fa0> to path b'/_synapse/admin'363Sep 11 05:14:40 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.pick_idp.PickIdpResource object at 0xffffee339fa0> to path b'/_synapse/client/pick_idp'364Sep 11 05:14:40 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffe1a04260> to path b'/_synapse/client/pick_username'365Sep 11 05:14:40 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.new_user_consent.NewUserConsentResource object at 0xffffee33a5a0> to path b'/_synapse/client/new_user_consent'366Sep 11 05:14:40 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.sso_register.SsoRegisterResource object at 0xffffee33a720> to path b'/_synapse/client/sso_register'367Sep 11 05:14:40 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.unsubscribe.UnsubscribeResource object at 0xffffee33a8a0> to path b'/_synapse/client/unsubscribe'368Sep 11 05:14:40 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.media_upload_limit_exceeded.MediaUploadLimitExceededResource object at 0xffffee33aa20> to path b'/_synapse/client/media_upload_limit_exceeded'369Sep 11 05:14:40 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching FilePath('/nix/store/dc3p08cmvzgsnfpwr26r4f1z2skd4j7s-matrix-synapse-1.160.0/lib/python3.13/site-packages/synapse/static') to path b'/_matrix/static'370Sep 11 05:14:40 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee33aea0> to path b'/_matrix/media/r0'371Sep 11 05:14:40 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee33aea0> to path b'/_matrix/media/v3'372Sep 11 05:14:40 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee33aea0> to path b'/_matrix/media/v1'373Sep 11 05:14:40 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.federation.transport.server.TransportLayerServer object at 0xffffee33baa0> to path b'/_matrix/federation'374Sep 11 05:14:40 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.key.v2.KeyResource object at 0xffffee33ec20> to path b'/_matrix/key'375Sep 11 05:14:40 machine synapse[559]: twisted: [call_when_running] SynapseSite starting on 8008376Sep 11 05:14:40 machine synapse[559]: synapse.app._base: [call_when_running] Synapse now listening on TCP port 8008377Sep 11 05:14:40 machine synapse[559]: synapse.app._base: [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).378Sep 11 05:14:40 machine synapse[559]: synapse.storage.background_updates: [background_updates-0] Starting background schema updates for database master379Sep 11 05:14:40 machine synapse[559]: synapse.handlers.deactivate_account: [user_parter_loop-0] User parter finished: stopping380Sep 11 05:14:40 machine synapse[559]: synapse.push.pusherpool: [start_pushers-0] Started pushers381Sep 11 05:14:41 machine 4s8l47vbpf1f8d5bs90rbh78zxhjwx0h-matrix-synapse-create-users[584]: Connection to ::1 8008 port [tcp/http-alt] succeeded!382Sep 11 05:14:41 machine synapse[559]: synapse.access.http.8008: [GET-0] ::1 - 8008 - {None} Processed request: 0.001sec/0.001sec ru=(0.001sec, 0.000sec) db=(0.000sec/0.000sec/0) 140B 200 "GET /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]383Sep 11 05:14:41 machine synapse[559]: synapse.http.server: [POST-1] <XForwardedForRequest at 0xffffe1a06ba0 method='POST' uri='/_synapse/admin/v1/register' clientproto='HTTP/1.1' site='8008'> SynapseError: 400 - User ID already taken.384Sep 11 05:14:41 machine synapse[559]: synapse.access.http.8008: [POST-1] ::1 - 8008 - {None} Processed request: 0.238sec/0.000sec ru=(0.231sec, 0.001sec) db=(0.000sec/0.002sec/1) 60B 400 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]385Sep 11 05:14:41 machine 4s8l47vbpf1f8d5bs90rbh78zxhjwx0h-matrix-synapse-create-users[585]: Sending registration request...386Sep 11 05:14:41 machine 4s8l47vbpf1f8d5bs90rbh78zxhjwx0h-matrix-synapse-create-users[585]: User already exists. Skipping.387Sep 11 05:14:41 machine synapse[559]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'populate_stats_process_rooms'388Sep 11 05:14:41 machine synapse[559]: synapse.storage.background_updates: [background_updates-0] Running background update 'populate_stats_process_rooms'. Processed 1 items in 16ms. (total_rate=0.0625/ms, current_rate=0.0625/ms, total_updated=1, batch_size=100)389Sep 11 05:14:42 machine synapse[559]: synapse.access.http.8008: [GET-2] ::1 - 8008 - {None} Processed request: 0.000sec/0.000sec ru=(0.000sec, 0.000sec) db=(0.000sec/0.000sec/0) 140B 200 "GET /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]390Sep 11 05:14:42 machine synapse[559]: synapse.http.server: [POST-3] <XForwardedForRequest at 0xffffe1a07320 method='POST' uri='/_synapse/admin/v1/register' clientproto='HTTP/1.1' site='8008'> SynapseError: 400 - User ID already taken.391Sep 11 05:14:42 machine synapse[559]: synapse.access.http.8008: [POST-3] ::1 - 8008 - {None} Processed request: 0.239sec/0.000sec ru=(0.235sec, 0.000sec) db=(0.000sec/0.001sec/1) 60B 400 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]392Sep 11 05:14:42 machine 4s8l47vbpf1f8d5bs90rbh78zxhjwx0h-matrix-synapse-create-users[591]: Sending registration request...393Sep 11 05:14:42 machine 4s8l47vbpf1f8d5bs90rbh78zxhjwx0h-matrix-synapse-create-users[591]: User already exists. Skipping.394Sep 11 05:14:42 machine systemd[1]: Started Synapse Matrix homeserver.395+ systemctl --no-pager show matrix-synapse396+ nc -z -v ::1 8008397Connection to ::1 8008 port [tcp/http-alt] succeeded!398+ curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test'399