container-test-run-matrix-synapse
checks.aarch64-linux.matrix-synapse
· build #482
· raw
1additionally exposed symbols:2 machine,3 start_all, machines, driver, Machine, wait_for_signal4Starting machine567<<< NixOS Stage 2 >>>89booting system configuration /nix/store/32x52v6sfccdmq4y78kpw1r04v0j70g3-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/hlc4nqfx0l06f6p7z7lmd77qcwszi7wc-coreutils-9.11/bin/sleep 999999999 && echo a647b90c-4b34-4ba9-88db-69d1003df9c4'29Running as unit: run-p479-i104845513.service; invocation ID: afbb807824704a3db64887d6a08fdd2530To attach to container machine run on the same machine that runs the test:31 sudo nsenter --user --target $(\pgrep -f '^/bin/sh.*a647b90c-4b34-4ba9-88db-69d1003df9c4') --mount --uts --ipc --net --pid --cgroup /bin/sh -c bash 3233To inject external network and continue test, run:34sudo /nix/store/d6kq2phq0zy5invmkfgg8h35zxaf13s9-python3-3.13.15/bin/python3.13 /nix/store/2y51rn5wgsfsam71nsisca336nva2cm8-test-driver-0.0.1/lib/python3.13/site-packages/test_driver/inject_network.py a647b90c-4b34-4ba9-88db-69d1003df9c435+ 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+ systemctl --no-pager show matrix-synapse43+ nc -z -v ::1 800844Connection to ::1 8008 port [tcp/http-alt] succeeded!45machine: waiting for success: curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test'46+ curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test'47(finished: waiting for success: curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test', in 0.02 seconds)48+ systemctl restart matrix-synapse49+ journalctl -u matrix-synapse --no-pager50Aug 25 00:11:22 machine systemd[1]: Starting Synapse Matrix homeserver...51Aug 25 00:11:25 machine matrix-synapse-pre-start[474]: Generating signing key file /var/lib/matrix-synapse/homeserver.signing.key52Aug 25 00:11:28 machine synapse_homeserver[489]: This server is configured to use 'matrix.org' as its trusted key server via the53Aug 25 00:11:28 machine synapse_homeserver[489]: 'trusted_key_servers' config option. 'matrix.org' is a good choice for a key54Aug 25 00:11:28 machine synapse_homeserver[489]: server since it is long-lived, stable and trusted. However, some admins may55Aug 25 00:11:28 machine synapse_homeserver[489]: wish to use another server for this purpose.56Aug 25 00:11:28 machine synapse_homeserver[489]: To suppress this warning and continue using 'matrix.org', admins should set57Aug 25 00:11:28 machine synapse_homeserver[489]: 'suppress_key_server_warning' to 'true' in homeserver.yaml.58Aug 25 00:11:28 machine synapse_homeserver[489]: --------------------------------------------------------------------------------59Aug 25 00:11:28 machine synapse[489]: synapse.config.logger: [main] ***** STARTING SERVER *****60Aug 25 00:11:28 machine synapse[489]: synapse.config.logger: [main] Server /nix/store/1s8lqnc1k176xsjap48vxjq430ckjqnm-matrix-synapse-1.159.0/bin/synapse_homeserver version 1.159.061Aug 25 00:11:28 machine synapse[489]: synapse.config.logger: [main] Copyright (c) 2023 New Vector, Inc62Aug 25 00:11:28 machine synapse[489]: synapse.config.logger: [main] Licensed under the AGPL 3.0 license. Website: https://github.com/element-hq/synapse63Aug 25 00:11:28 machine synapse[489]: synapse.config.logger: [main] Server hostname: clan.test64Aug 25 00:11:28 machine synapse[489]: synapse.config.logger: [main] Public Base URL: https://clan.test/65Aug 25 00:11:28 machine synapse[489]: synapse.config.logger: [main] Instance name: master66Aug 25 00:11:28 machine synapse[489]: synapse.config.logger: [main] Twisted reactor: EPollReactor67Aug 25 00:11:28 machine synapse[489]: synapse.app.homeserver: [main] Setting up server68Aug 25 00:11:28 machine synapse[489]: synapse.server: [main] Setting up.69Aug 25 00:11:28 machine synapse[489]: synapse.storage.databases: [main] [database config 'master']: Checking database server70Aug 25 00:11:28 machine synapse[489]: synapse.storage.databases: [main] [database config 'master']: Preparing for databases ['main', 'state']71Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] ['main', 'state']: Checking existing schema version72Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] ['main', 'state']: Initialising new database73Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema deltas for v7374Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 73/01event_failed_pull_attempts.sql75Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 73/02add_pusher_enabled.sql76Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 73/02room_id_indexes_for_purging.sql77Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 73/03pusher_device_id.sql78Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 73/03users_approved_column.sql79Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 73/04partial_join_details.sql80Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 73/04pending_device_list_updates.sql81Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/05old_push_actions.sql.postgres82Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 73/06thread_notifications_thread_id_idx.sql83Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/08thread_receipts_non_null.sql.postgres84Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 73/09partial_joined_via_destination.sql85Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 73/09threads_table.sql86Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Running 73/10_update_sqlite_fts4_tokenizer.py:run_create87Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 73/10login_tokens.sql88Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/11event_search_room_id_n_distinct.sql.postgres89Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 73/12refactor_device_list_outbound_pokes.sql90Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 73/13add_device_lists_index.sql91Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 73/20_un_partial_stated_room_stream.sql92Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/21_un_partial_stated_room_stream_seq.sql.postgres93Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 73/22_rebuild_user_dir_stats.sql94Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 73/22_un_partial_stated_event_stream.sql95Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 73/23_fix_thread_index.sql96Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/23_un_partial_stated_room_stream_seq.sql.postgres97Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 73/24_events_jump_to_date_index.sql98Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 73/25drop_presence.sql99Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema deltas for v74100Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 74/01_user_directory_stale_remote_users.sql101Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 74/02_set_device_id_for_pushers_bg_update.sql102Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying engine-specific schema 74/03_membership_tables_event_stream_ordering.sql.postgres103Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 74/03_room_membership_index.sql104Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 74/04_delete_e2e_backup_keys_for_deactivated_users.sql105Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Running 74/04_membership_tables_event_stream_ordering_triggers.py:run_create106Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 74/05_events_txn_id_device_id.sql107Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying engine-specific schema 74/90COMMENTS_destinations.sql.postgres108Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema deltas for v75109Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema deltas for v76110Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 76/01_add_profiles_full_user_id_column.sql111Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 76/02_add_user_filters_full_user_id_column.sql112Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 76/03_per_user_experimental_features.sql113Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 76/04_add_room_forgetter.sql114Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema deltas for v77115Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/01_add_profiles_not_valid_check.sql.postgres116Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/02_add_user_filters_not_valid_check.sql.postgres117Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 77/03bg_populate_full_user_id_profiles.sql118Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 77/04bg_populate_full_user_id_user_filters.sql119Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 77/05thread_notifications_backfill.sql120Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_actions.sql.postgres121Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_actions_staging.sql.postgres122Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_summary.sql.postgres123Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 77/14bg_indices_event_stream_ordering.sql124Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema deltas for v78125Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Running 78/03event_extremities_constraints.py:run_create126Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Running 78/04_add_full_user_id_index_user_filters.py:run_create127Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema deltas for v79128Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying engine-specific schema 79/03_read_write_locks_triggers.sql.postgres129Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Running 79/04_mitigate_stream_ordering_update_race.py:run_create130Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying engine-specific schema 79/05_read_write_locks_triggers.sql.postgres131Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema deltas for v80132Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 80/01_users_alter_locked.sql133Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/02_read_write_locks_unlogged.sql.postgres134Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 80/02_scheduled_tasks.sql135Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/03_read_write_locks_triggers.sql.postgres136Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/04_read_write_locks_deadlock.sql.postgres137Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema deltas for v81138Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema deltas for v82139Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 82/02_scheduled_tasks_index.sql140Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 82/04_add_indices_for_purging_rooms.sql141Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 82/05gaps.sql142Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema deltas for v83143Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 83/01_drop_old_tables.sql144Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 83/05_cross_signing_key_update_grant.sql145Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 83/06_event_push_summary_room.sql146Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema deltas for v84147Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying engine-specific schema 84/01_auth_links_stats.sql.postgres148Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 84/02_auth_links_index.sql149Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying engine-specific schema 84/03_auth_links_analyze.sql.postgres150Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 84/04_access_token_index.sql151Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema deltas for v85152Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 85/01_add_suspended.sql153Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 85/02_add_instance_names.sql154Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying engine-specific schema 85/03_new_sequences.sql.postgres155Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 85/04_cleanup_device_federation_outbox.sql156Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 85/05_add_instance_names_converted_pos.sql157Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 85/06_add_room_reports.sql158Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema deltas for v86159Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 86/01_authenticate_media.sql160Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 86/02_receipts_event_id_index.sql161Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema deltas for v87162Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 87/01_sliding_sync_memberships.sql163Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 87/02_per_connection_state.sql164Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 87/03_current_state_index.sql165Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema deltas for v88166Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 88/01_add_delayed_events.sql167Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 88/01_custom_profile_fields.sql168Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 88/02_fix_sliding_sync_membership_snapshots_forgotten_column.sql169Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 88/03_add_otk_ts_added_index.sql170Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 88/04_current_state_delta_index.sql171Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying engine-specific schema 88/05_drop_old_otks.sql.postgres172Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 88/05_sliding_sync_room_config_index.sql173Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 88/06_events_received_ts_index.sql174Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema deltas for v89175Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 89/01_sliding_sync_membership_snapshot_index.sql176Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 89/01_state_groups_deletion.sql177Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema deltas for v90178Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 90/01_add_column_participant_room_memberships_table.sql179Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 90/02_delete_unreferenced_state_groups.sql180Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 90/03_remove_old_deletion_bg_update.sql181Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema deltas for v91182Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 91/01_media_hash.sql183Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema deltas for v92184Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/01_remove_trigger.sql.postgres185Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 92/02_remove_populate_participant_bg_update.sql186Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 92/04_ss_membership_snapshot_idx.sql187Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 92/04_thread_subscriptions.sql188Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/04_thread_subscriptions_seq.sql.postgres189Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 92/05_fixup_max_depth_cap.sql190Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/05_thread_subscriptions_comments.sql.postgres191Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 92/06_device_federation_inbox_index.sql192Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/06_threads_last_sent_stream_ordering_comments.sql.postgres193Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 92/07_add_user_reports.sql194Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 92/07_event_txn_id_device_id_txn_id2.sql195Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 92/08_room_ban_redactions.sql196Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/08_thread_subscriptions_seq_fixup.sql.postgres197Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 92/09_thread_subscriptions_update.sql198Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/09_thread_subscriptions_update.sql.postgres199Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema deltas for v93200Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 93/01_add_delayed_events.sql201Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 93/01_sticky_events.sql202Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying engine-specific schema 93/01_sticky_events_seq.sql.postgres203Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 93/02_sliding_sync_members.sql204Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 93/03_sss_pos_last_used.sql205Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Running 93/04_make_delayed_event_content_text.py:run_create206Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema deltas for v94207Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 94/01_redactions_recheck.sql208Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 94/02_redactions_recheck_bg_update.sql209Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 94/03_device_lists_room_timestamp.sql210Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 94/03_quarantined_media_tracking.sql211Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying engine-specific schema 94/03_quarantined_media_tracking_seq.sql.postgres212Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 94/03_state_dag_fwd_extrems.sql213Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 94/04_device_lists_changes_max_pruned.sql214Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 94/05_rerun_flag_existing_quarantined_media.sql215Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 94/06_sliding_sync_connections_last_used_ts_index.sql216Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 94/07_profile_updates.sql217Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying engine-specific schema 94/07_profile_updates_seq.sql.postgres218Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Applying schema 94/07_sliding_sync_lazy_members_position_index.sql219Aug 25 00:11:28 machine synapse[489]: synapse.storage.prepare_database: [main] Schema now up to date220Aug 25 00:11:28 machine synapse[489]: synapse.storage.databases: [main] [database config 'master']: Starting 'main' database221Aug 25 00:11:28 machine synapse[489]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules(id): 1222Aug 25 00:11:28 machine synapse[489]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules_enable(id): 1223Aug 25 00:11:28 machine synapse[489]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 month ago224Aug 25 00:11:28 machine synapse[489]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 month ago: it's 0225Aug 25 00:11:28 machine synapse[489]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 day ago226Aug 25 00:11:28 machine synapse[489]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 day ago: it's 0227Aug 25 00:11:28 machine synapse[489]: synapse.storage.util.id_generators: [main] Initialising stream generator for access_tokens(id): 1228Aug 25 00:11:28 machine synapse[489]: synapse.storage.util.id_generators: [main] Initialising stream generator for refresh_tokens(id): 1229Aug 25 00:11:28 machine synapse[489]: synapse.storage.util.id_generators: [main] Initialising stream generator for event_reports(id): 1230Aug 25 00:11:28 machine synapse[489]: synapse.storage.util.id_generators: [main] Initialising stream generator for room_reports(id): 1231Aug 25 00:11:28 machine synapse[489]: synapse.storage.util.id_generators: [main] Initialising stream generator for user_reports(id): 1232Aug 25 00:11:28 machine synapse[489]: synapse.storage.databases: [main] [database config 'master']: Starting 'state' database233Aug 25 00:11:28 machine synapse[489]: synapse.storage.databases: [main] [database config 'master']: prepared234Aug 25 00:11:28 machine synapse[489]: synapse.server: [main] Finished setting up.235Aug 25 00:11:28 machine synapse[489]: twisted: [main] Redirected stdout/stderr to logs236Aug 25 00:11:28 machine synapse[489]: synapse.app.homeserver: [main] Running237Aug 25 00:11:28 machine synapse[489]: synapse.app.homeserver: [main] Set file limit to: 524288238Aug 25 00:11:28 machine synapse[489]: synapse.push.pusher: [call_when_running] email enable notifs: False239Aug 25 00:11:28 machine synapse[489]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'profile'240Aug 25 00:11:28 machine synapse[489]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.device_list_update'241Aug 25 00:11:28 machine synapse[489]: synapse.util.caches.lrucache: [call_when_running] Expiring LRU caches after 1800 seconds242Aug 25 00:11:28 machine synapse[489]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.presence'243Aug 25 00:11:28 machine synapse[489]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.typing'244Aug 25 00:11:28 machine synapse[489]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'directory'245Aug 25 00:11:28 machine synapse[489]: synapse.handlers.deactivate_account: [user_parter_loop-0] Starting user parter246Aug 25 00:11:28 machine synapse[489]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.receipt'247Aug 25 00:11:28 machine synapse[489]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.signing_key_update'248Aug 25 00:11:28 machine synapse[489]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'org.matrix.signing_key_update'249Aug 25 00:11:28 machine synapse[489]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'client_keys'250Aug 25 00:11:28 machine synapse[489]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.direct_to_device'251Aug 25 00:11:28 machine synapse[489]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.health.HealthResource object at 0xffffee099120> to path b'/health'252Aug 25 00:11:28 machine synapse[489]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.EncodingResourceWrapper object at 0xffffee303820> to path b'/_matrix/client'253Aug 25 00:11:28 machine synapse[489]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffee309520> to path b'/.well-known'254Aug 25 00:11:28 machine synapse[489]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.http.server.JsonResource object at 0xffffee3039a0> to path b'/_synapse/admin'255Aug 25 00:11:28 machine synapse[489]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.pick_idp.PickIdpResource object at 0xffffee3099a0> to path b'/_synapse/client/pick_idp'256Aug 25 00:11:28 machine synapse[489]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffe1a81f60> to path b'/_synapse/client/pick_username'257Aug 25 00:11:28 machine synapse[489]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.new_user_consent.NewUserConsentResource object at 0xffffee309fa0> to path b'/_synapse/client/new_user_consent'258Aug 25 00:11:28 machine synapse[489]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.sso_register.SsoRegisterResource object at 0xffffee30a120> to path b'/_synapse/client/sso_register'259Aug 25 00:11:28 machine synapse[489]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.unsubscribe.UnsubscribeResource object at 0xffffee30a2a0> to path b'/_synapse/client/unsubscribe'260Aug 25 00:11:28 machine synapse[489]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.media_upload_limit_exceeded.MediaUploadLimitExceededResource object at 0xffffee30a420> to path b'/_synapse/client/media_upload_limit_exceeded'261Aug 25 00:11:28 machine synapse[489]: synapse.util.httpresourcetree: [call_when_running] Attaching FilePath('/nix/store/1s8lqnc1k176xsjap48vxjq430ckjqnm-matrix-synapse-1.159.0/lib/python3.13/site-packages/synapse/static') to path b'/_matrix/static'262Aug 25 00:11:28 machine synapse[489]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee30a8a0> to path b'/_matrix/media/r0'263Aug 25 00:11:28 machine synapse[489]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee30a8a0> to path b'/_matrix/media/v3'264Aug 25 00:11:28 machine synapse[489]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee30a8a0> to path b'/_matrix/media/v1'265Aug 25 00:11:28 machine synapse[489]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.federation.transport.server.TransportLayerServer object at 0xffffee30b4a0> to path b'/_matrix/federation'266Aug 25 00:11:28 machine synapse[489]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.key.v2.KeyResource object at 0xffffee30e620> to path b'/_matrix/key'267Aug 25 00:11:28 machine synapse[489]: twisted: [call_when_running] SynapseSite starting on 8008268Aug 25 00:11:28 machine synapse[489]: synapse.app._base: [call_when_running] Synapse now listening on TCP port 8008269Aug 25 00:11:28 machine synapse[489]: 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).270Aug 25 00:11:29 machine synapse[489]: synapse.storage.background_updates: [background_updates-0] Starting background schema updates for database master271Aug 25 00:11:29 machine synapse[489]: synapse.handlers.deactivate_account: [user_parter_loop-0] User parter finished: stopping272Aug 25 00:11:29 machine synapse[489]: synapse.push.pusherpool: [start_pushers-0] Started pushers273Aug 25 00:11:29 machine wilq5k4l3zkskj1vfpl6p6ld63xg52f7-matrix-synapse-create-users[518]: Connection to ::1 8008 port [tcp/http-alt] succeeded!274Aug 25 00:11:29 machine synapse[489]: 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]275Aug 25 00:11:29 machine synapse[489]: synapse.handlers.auth: [POST-1] Logging in user @admin:clan.test on device TVHRIRMOBC276Aug 25 00:11:29 machine synapse[489]: synapse.access.http.8008: [POST-1] ::1 - 8008 - {None} Processed request: 0.295sec/0.000sec ru=(0.258sec, 0.005sec) db=(0.005sec/0.028sec/12) 138B 200 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]277Aug 25 00:11:29 machine wilq5k4l3zkskj1vfpl6p6ld63xg52f7-matrix-synapse-create-users[519]: Sending registration request...278Aug 25 00:11:29 machine wilq5k4l3zkskj1vfpl6p6ld63xg52f7-matrix-synapse-create-users[519]: Success!279Aug 25 00:11:30 machine synapse[489]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'threads_backfill'280Aug 25 00:11:30 machine synapse[489]: 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)281Aug 25 00:11:30 machine synapse[489]: 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]282Aug 25 00:11:30 machine synapse[489]: synapse.handlers.auth: [POST-3] Logging in user @someuser:clan.test on device PNNECDREGG283Aug 25 00:11:30 machine synapse[489]: synapse.access.http.8008: [POST-3] ::1 - 8008 - {None} Processed request: 0.261sec/0.000sec ru=(0.234sec, 0.003sec) db=(0.003sec/0.014sec/12) 145B 200 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]284Aug 25 00:11:30 machine wilq5k4l3zkskj1vfpl6p6ld63xg52f7-matrix-synapse-create-users[526]: Sending registration request...285Aug 25 00:11:30 machine wilq5k4l3zkskj1vfpl6p6ld63xg52f7-matrix-synapse-create-users[526]: Success!286Aug 25 00:11:30 machine systemd[1]: Started Synapse Matrix homeserver.287Aug 25 00:11:31 machine synapse[489]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'device_lists_changes_in_room_by_room_index'288Aug 25 00:11:31 machine synapse[489]: synapse.storage.background_updates: [background_updates-0] Adding index device_lists_changes_in_room_by_room_idx to device_lists_changes_in_room289Aug 25 00:11:31 machine synapse[489]: synapse.storage.background_updates: [background_updates-0] Running background update 'device_lists_changes_in_room_by_room_index'. Processed 1 items in 6ms. (total_rate=0.16666666666666666/ms, current_rate=0.16666666666666666/ms, total_updated=1, batch_size=100)290Aug 25 00:11:31 machine synapse[489]: synapse.access.http.8008: [GET-4] ::1 - 8008 - {None} Processed request: 0.001sec/-0.000sec ru=(0.002sec, 0.000sec) db=(0.000sec/0.000sec/0) 10145B 200 "GET /_matrix/static/ HTTP/1.1" "curl/8.21.0" [0 dbevts]291Aug 25 00:11:31 machine synapse[489]: twisted: [sentinel] Received SIGTERM, shutting down.292Aug 25 00:11:31 machine systemd[1]: Stopping Synapse Matrix homeserver...293Aug 25 00:11:31 machine synapse[489]: synapse.storage.databases.main.lock: [LockStore._on_shutdown-0] Dropping held locks due to shutdown294Aug 25 00:11:31 machine synapse[489]: synapse.storage.databases.main.lock: [LockStore._on_shutdown-0] Dropped locks due to shutdown295Aug 25 00:11:31 machine synapse[489]: synapse.handlers.presence: [PresenceHandler._on_shutdown-0] Performing _on_shutdown. Persisting 0 unpersisted changes296Aug 25 00:11:31 machine synapse[489]: synapse.handlers.presence: [PresenceHandler._on_shutdown-0] Finished _on_shutdown297Aug 25 00:11:31 machine synapse[489]: synapse.app._base: [log_shutdown] Shutting down...298Aug 25 00:11:31 machine synapse[489]: twisted: [sentinel] (TCP Port 8008 Closed)299Aug 25 00:11:31 machine synapse[489]: twisted: [sentinel] Main loop terminated.300Aug 25 00:11:31 machine systemd[1]: matrix-synapse.service: Deactivated successfully.301Aug 25 00:11:31 machine systemd[1]: Stopped Synapse Matrix homeserver.302Aug 25 00:11:31 machine systemd[1]: matrix-synapse.service: Consumed 7.597s CPU time over 8.853s wall clock time.303Aug 25 00:11:31 machine systemd[1]: Starting Synapse Matrix homeserver...304Aug 25 00:11:37 machine synapse_homeserver[558]: This server is configured to use 'matrix.org' as its trusted key server via the305Aug 25 00:11:37 machine synapse_homeserver[558]: 'trusted_key_servers' config option. 'matrix.org' is a good choice for a key306Aug 25 00:11:37 machine synapse_homeserver[558]: server since it is long-lived, stable and trusted. However, some admins may307Aug 25 00:11:37 machine synapse_homeserver[558]: wish to use another server for this purpose.308Aug 25 00:11:37 machine synapse_homeserver[558]: To suppress this warning and continue using 'matrix.org', admins should set309Aug 25 00:11:37 machine synapse_homeserver[558]: 'suppress_key_server_warning' to 'true' in homeserver.yaml.310Aug 25 00:11:37 machine synapse_homeserver[558]: --------------------------------------------------------------------------------311Aug 25 00:11:37 machine synapse[558]: synapse.config.logger: [main] ***** STARTING SERVER *****312Aug 25 00:11:37 machine synapse[558]: synapse.config.logger: [main] Server /nix/store/1s8lqnc1k176xsjap48vxjq430ckjqnm-matrix-synapse-1.159.0/bin/synapse_homeserver version 1.159.0313Aug 25 00:11:37 machine synapse[558]: synapse.config.logger: [main] Copyright (c) 2023 New Vector, Inc314Aug 25 00:11:37 machine synapse[558]: synapse.config.logger: [main] Licensed under the AGPL 3.0 license. Website: https://github.com/element-hq/synapse315Aug 25 00:11:37 machine synapse[558]: synapse.config.logger: [main] Server hostname: clan.test316Aug 25 00:11:37 machine synapse[558]: synapse.config.logger: [main] Public Base URL: https://clan.test/317Aug 25 00:11:37 machine synapse[558]: synapse.config.logger: [main] Instance name: master318Aug 25 00:11:37 machine synapse[558]: synapse.config.logger: [main] Twisted reactor: EPollReactor319Aug 25 00:11:37 machine synapse[558]: synapse.app.homeserver: [main] Setting up server320Aug 25 00:11:37 machine synapse[558]: synapse.server: [main] Setting up.321Aug 25 00:11:37 machine synapse[558]: synapse.storage.databases: [main] [database config 'master']: Checking database server322Aug 25 00:11:37 machine synapse[558]: synapse.storage.databases: [main] [database config 'master']: Preparing for databases ['main', 'state']323Aug 25 00:11:37 machine synapse[558]: synapse.storage.prepare_database: [main] ['main', 'state']: Checking existing schema version324Aug 25 00:11:37 machine synapse[558]: synapse.storage.prepare_database: [main] ['main', 'state']: Existing schema is 94 (+12 deltas)325Aug 25 00:11:37 machine synapse[558]: synapse.storage.databases.main: [main] Checking database for consistency with configuration...326Aug 25 00:11:37 machine synapse[558]: synapse.storage.prepare_database: [main] Applying schema deltas for v94327Aug 25 00:11:37 machine synapse[558]: synapse.storage.prepare_database: [main] Schema now up to date328Aug 25 00:11:37 machine synapse[558]: synapse.storage.databases: [main] [database config 'master']: Starting 'main' database329Aug 25 00:11:37 machine synapse[558]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules(id): 1330Aug 25 00:11:37 machine synapse[558]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules_enable(id): 1331Aug 25 00:11:37 machine synapse[558]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 month ago332Aug 25 00:11:37 machine synapse[558]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 month ago: it's 0333Aug 25 00:11:37 machine synapse[558]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 day ago334Aug 25 00:11:37 machine synapse[558]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 day ago: it's 0335Aug 25 00:11:37 machine synapse[558]: synapse.storage.util.id_generators: [main] Initialising stream generator for access_tokens(id): 3336Aug 25 00:11:37 machine synapse[558]: synapse.storage.util.id_generators: [main] Initialising stream generator for refresh_tokens(id): 1337Aug 25 00:11:37 machine synapse[558]: synapse.storage.util.id_generators: [main] Initialising stream generator for event_reports(id): 1338Aug 25 00:11:37 machine synapse[558]: synapse.storage.util.id_generators: [main] Initialising stream generator for room_reports(id): 1339Aug 25 00:11:37 machine synapse[558]: synapse.storage.util.id_generators: [main] Initialising stream generator for user_reports(id): 1340Aug 25 00:11:37 machine synapse[558]: synapse.storage.databases: [main] [database config 'master']: Starting 'state' database341Aug 25 00:11:37 machine synapse[558]: synapse.storage.databases: [main] [database config 'master']: prepared342Aug 25 00:11:37 machine synapse[558]: synapse.server: [main] Finished setting up.343Aug 25 00:11:37 machine synapse[558]: twisted: [main] Redirected stdout/stderr to logs344Aug 25 00:11:37 machine synapse[558]: synapse.app.homeserver: [main] Running345Aug 25 00:11:37 machine synapse[558]: synapse.app.homeserver: [main] Set file limit to: 524288346Aug 25 00:11:37 machine synapse[558]: synapse.push.pusher: [call_when_running] email enable notifs: False347Aug 25 00:11:37 machine synapse[558]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'profile'348Aug 25 00:11:37 machine synapse[558]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.device_list_update'349Aug 25 00:11:37 machine synapse[558]: synapse.util.caches.lrucache: [call_when_running] Expiring LRU caches after 1800 seconds350Aug 25 00:11:37 machine synapse[558]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.presence'351Aug 25 00:11:37 machine synapse[558]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.typing'352Aug 25 00:11:37 machine synapse[558]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'directory'353Aug 25 00:11:37 machine synapse[558]: synapse.handlers.deactivate_account: [user_parter_loop-0] Starting user parter354Aug 25 00:11:37 machine synapse[558]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.receipt'355Aug 25 00:11:37 machine synapse[558]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.signing_key_update'356Aug 25 00:11:37 machine synapse[558]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'org.matrix.signing_key_update'357Aug 25 00:11:37 machine synapse[558]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'client_keys'358Aug 25 00:11:37 machine synapse[558]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.direct_to_device'359Aug 25 00:11:37 machine synapse[558]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.health.HealthResource object at 0xffffee0892a0> to path b'/health'360Aug 25 00:11:37 machine synapse[558]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.EncodingResourceWrapper object at 0xffffee3139a0> to path b'/_matrix/client'361Aug 25 00:11:37 machine synapse[558]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffee3196a0> to path b'/.well-known'362Aug 25 00:11:37 machine synapse[558]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.http.server.JsonResource object at 0xffffee313b20> to path b'/_synapse/admin'363Aug 25 00:11:37 machine synapse[558]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.pick_idp.PickIdpResource object at 0xffffee319b20> to path b'/_synapse/client/pick_idp'364Aug 25 00:11:37 machine synapse[558]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffe18a1e20> to path b'/_synapse/client/pick_username'365Aug 25 00:11:37 machine synapse[558]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.new_user_consent.NewUserConsentResource object at 0xffffee31a120> to path b'/_synapse/client/new_user_consent'366Aug 25 00:11:37 machine synapse[558]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.sso_register.SsoRegisterResource object at 0xffffee31a2a0> to path b'/_synapse/client/sso_register'367Aug 25 00:11:37 machine synapse[558]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.unsubscribe.UnsubscribeResource object at 0xffffee31a420> to path b'/_synapse/client/unsubscribe'368Aug 25 00:11:37 machine synapse[558]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.media_upload_limit_exceeded.MediaUploadLimitExceededResource object at 0xffffee31a5a0> to path b'/_synapse/client/media_upload_limit_exceeded'369Aug 25 00:11:37 machine synapse[558]: synapse.util.httpresourcetree: [call_when_running] Attaching FilePath('/nix/store/1s8lqnc1k176xsjap48vxjq430ckjqnm-matrix-synapse-1.159.0/lib/python3.13/site-packages/synapse/static') to path b'/_matrix/static'370Aug 25 00:11:37 machine synapse[558]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee31aa20> to path b'/_matrix/media/r0'371Aug 25 00:11:37 machine synapse[558]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee31aa20> to path b'/_matrix/media/v3'372Aug 25 00:11:37 machine synapse[558]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee31aa20> to path b'/_matrix/media/v1'373Aug 25 00:11:37 machine synapse[558]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.federation.transport.server.TransportLayerServer object at 0xffffee31b620> to path b'/_matrix/federation'374Aug 25 00:11:37 machine synapse[558]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.key.v2.KeyResource object at 0xffffee31e7a0> to path b'/_matrix/key'375Aug 25 00:11:37 machine synapse[558]: twisted: [call_when_running] SynapseSite starting on 8008376Aug 25 00:11:37 machine synapse[558]: synapse.app._base: [call_when_running] Synapse now listening on TCP port 8008377Aug 25 00:11:37 machine synapse[558]: 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).378Aug 25 00:11:37 machine synapse[558]: synapse.storage.background_updates: [background_updates-0] Starting background schema updates for database master379Aug 25 00:11:37 machine synapse[558]: synapse.handlers.deactivate_account: [user_parter_loop-0] User parter finished: stopping380Aug 25 00:11:37 machine synapse[558]: synapse.push.pusherpool: [start_pushers-0] Started pushers381Aug 25 00:11:37 machine wilq5k4l3zkskj1vfpl6p6ld63xg52f7-matrix-synapse-create-users[583]: Connection to ::1 8008 port [tcp/http-alt] succeeded!382Aug 25 00:11:38 machine synapse[558]: synapse.access.http.8008: [GET-0] ::1 - 8008 - {None} Processed request: 0.001sec/0.001sec ru=(0.002sec, 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]383Aug 25 00:11:38 machine synapse[558]: synapse.http.server: [POST-1] <XForwardedForRequest at 0xffffe18a4260 method='POST' uri='/_synapse/admin/v1/register' clientproto='HTTP/1.1' site='8008'> SynapseError: 400 - User ID already taken.384Aug 25 00:11:38 machine synapse[558]: synapse.access.http.8008: [POST-1] ::1 - 8008 - {None} Processed request: 0.248sec/0.001sec ru=(0.244sec, 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]385Aug 25 00:11:38 machine wilq5k4l3zkskj1vfpl6p6ld63xg52f7-matrix-synapse-create-users[584]: Sending registration request...386Aug 25 00:11:38 machine wilq5k4l3zkskj1vfpl6p6ld63xg52f7-matrix-synapse-create-users[584]: User already exists. Skipping.387Aug 25 00:11:38 machine synapse[558]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'populate_stats_process_rooms'388Aug 25 00:11:38 machine synapse[558]: synapse.storage.background_updates: [background_updates-0] Running background update 'populate_stats_process_rooms'. Processed 1 items in 4ms. (total_rate=0.25/ms, current_rate=0.25/ms, total_updated=1, batch_size=100)389Aug 25 00:11:38 machine synapse[558]: 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]390Aug 25 00:11:39 machine synapse[558]: synapse.http.server: [POST-3] <XForwardedForRequest at 0xffffe18a5660 method='POST' uri='/_synapse/admin/v1/register' clientproto='HTTP/1.1' site='8008'> SynapseError: 400 - User ID already taken.391Aug 25 00:11:39 machine synapse[558]: synapse.access.http.8008: [POST-3] ::1 - 8008 - {None} Processed request: 0.240sec/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]392Aug 25 00:11:39 machine wilq5k4l3zkskj1vfpl6p6ld63xg52f7-matrix-synapse-create-users[590]: Sending registration request...393Aug 25 00:11:39 machine wilq5k4l3zkskj1vfpl6p6ld63xg52f7-matrix-synapse-create-users[590]: User already exists. Skipping.394Aug 25 00:11:39 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