container-test-run-matrix-synapse
default.checks.aarch64-linux.matrix-synapse
· build #391
· raw
1additionally exposed symbols:2 machine,3 start_all, machines, driver, Machine, wait_for_signal4Starting machine567<<< NixOS Stage 2 >>>89booting system configuration /nix/store/4vfw9dblg7bh3a648455nk6rq50s2y2m-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/vpnizxs18sv6agrsqplsvaml9y3bfx0k-coreutils-9.11/bin/sleep 999999999 && echo ae87f3f2-58b1-46c8-800d-29bd07fa3b2c'29Running as unit: run-p481-i93758436.service; invocation ID: 05a0d562dd544db49bc752190dd3275b30To attach to container machine run on the same machine that runs the test:31 sudo nsenter --user --target $(\pgrep -f '^/bin/sh.*ae87f3f2-58b1-46c8-800d-29bd07fa3b2c') --mount --uts --ipc --net --pid --cgroup /bin/sh -c bash 3233To inject external network and continue test, run:34sudo /nix/store/1bhl5b9821ifsz1dbfm4wxbjbfywmsaq-python3-3.13.14/bin/python3.13 /nix/store/lf6lngb6dzima2m8nqbna4lc6bm1qw85-test-driver-0.0.1/lib/python3.13/site-packages/test_driver/inject_network.py ae87f3f2-58b1-46c8-800d-29bd07fa3b2c35+ 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 17 10:10:34 machine systemd[1]: Starting Synapse Matrix homeserver...51Aug 17 10:10:37 machine matrix-synapse-pre-start[476]: Generating signing key file /var/lib/matrix-synapse/homeserver.signing.key52Aug 17 10:10:40 machine synapse_homeserver[491]: This server is configured to use 'matrix.org' as its trusted key server via the53Aug 17 10:10:40 machine synapse_homeserver[491]: 'trusted_key_servers' config option. 'matrix.org' is a good choice for a key54Aug 17 10:10:40 machine synapse_homeserver[491]: server since it is long-lived, stable and trusted. However, some admins may55Aug 17 10:10:40 machine synapse_homeserver[491]: wish to use another server for this purpose.56Aug 17 10:10:40 machine synapse_homeserver[491]: To suppress this warning and continue using 'matrix.org', admins should set57Aug 17 10:10:40 machine synapse_homeserver[491]: 'suppress_key_server_warning' to 'true' in homeserver.yaml.58Aug 17 10:10:40 machine synapse_homeserver[491]: --------------------------------------------------------------------------------59Aug 17 10:10:40 machine synapse[491]: synapse.config.logger: [main] ***** STARTING SERVER *****60Aug 17 10:10:40 machine synapse[491]: synapse.config.logger: [main] Server /nix/store/wrbw2mlx5755960ihv9l77c8dhrrm3iv-matrix-synapse-1.158.0/bin/synapse_homeserver version 1.158.061Aug 17 10:10:40 machine synapse[491]: synapse.config.logger: [main] Copyright (c) 2023 New Vector, Inc62Aug 17 10:10:40 machine synapse[491]: synapse.config.logger: [main] Licensed under the AGPL 3.0 license. Website: https://github.com/element-hq/synapse63Aug 17 10:10:40 machine synapse[491]: synapse.config.logger: [main] Server hostname: clan.test64Aug 17 10:10:40 machine synapse[491]: synapse.config.logger: [main] Public Base URL: https://clan.test/65Aug 17 10:10:40 machine synapse[491]: synapse.config.logger: [main] Instance name: master66Aug 17 10:10:40 machine synapse[491]: synapse.config.logger: [main] Twisted reactor: EPollReactor67Aug 17 10:10:40 machine synapse[491]: synapse.app.homeserver: [main] Setting up server68Aug 17 10:10:40 machine synapse[491]: synapse.server: [main] Setting up.69Aug 17 10:10:40 machine synapse[491]: synapse.storage.databases: [main] [database config 'master']: Checking database server70Aug 17 10:10:40 machine synapse[491]: synapse.storage.databases: [main] [database config 'master']: Preparing for databases ['main', 'state']71Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] ['main', 'state']: Checking existing schema version72Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] ['main', 'state']: Initialising new database73Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v7374Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/01event_failed_pull_attempts.sql75Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/02add_pusher_enabled.sql76Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/02room_id_indexes_for_purging.sql77Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/03pusher_device_id.sql78Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/03users_approved_column.sql79Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/04partial_join_details.sql80Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/04pending_device_list_updates.sql81Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/05old_push_actions.sql.postgres82Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/06thread_notifications_thread_id_idx.sql83Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/08thread_receipts_non_null.sql.postgres84Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/09partial_joined_via_destination.sql85Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/09threads_table.sql86Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] Running 73/10_update_sqlite_fts4_tokenizer.py:run_create87Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/10login_tokens.sql88Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/11event_search_room_id_n_distinct.sql.postgres89Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/12refactor_device_list_outbound_pokes.sql90Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/13add_device_lists_index.sql91Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/20_un_partial_stated_room_stream.sql92Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/21_un_partial_stated_room_stream_seq.sql.postgres93Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/22_rebuild_user_dir_stats.sql94Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/22_un_partial_stated_event_stream.sql95Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/23_fix_thread_index.sql96Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/23_un_partial_stated_room_stream_seq.sql.postgres97Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/24_events_jump_to_date_index.sql98Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/25drop_presence.sql99Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v74100Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 74/01_user_directory_stale_remote_users.sql101Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 74/02_set_device_id_for_pushers_bg_update.sql102Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 74/03_membership_tables_event_stream_ordering.sql.postgres103Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 74/03_room_membership_index.sql104Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 74/04_delete_e2e_backup_keys_for_deactivated_users.sql105Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] Running 74/04_membership_tables_event_stream_ordering_triggers.py:run_create106Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 74/05_events_txn_id_device_id.sql107Aug 17 10:10:40 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 74/90COMMENTS_destinations.sql.postgres108Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v75109Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v76110Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 76/01_add_profiles_full_user_id_column.sql111Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 76/02_add_user_filters_full_user_id_column.sql112Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 76/03_per_user_experimental_features.sql113Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 76/04_add_room_forgetter.sql114Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v77115Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/01_add_profiles_not_valid_check.sql.postgres116Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/02_add_user_filters_not_valid_check.sql.postgres117Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 77/03bg_populate_full_user_id_profiles.sql118Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 77/04bg_populate_full_user_id_user_filters.sql119Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 77/05thread_notifications_backfill.sql120Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_actions.sql.postgres121Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_actions_staging.sql.postgres122Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_summary.sql.postgres123Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 77/14bg_indices_event_stream_ordering.sql124Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v78125Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Running 78/03event_extremities_constraints.py:run_create126Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Running 78/04_add_full_user_id_index_user_filters.py:run_create127Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v79128Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 79/03_read_write_locks_triggers.sql.postgres129Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Running 79/04_mitigate_stream_ordering_update_race.py:run_create130Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 79/05_read_write_locks_triggers.sql.postgres131Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v80132Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 80/01_users_alter_locked.sql133Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/02_read_write_locks_unlogged.sql.postgres134Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 80/02_scheduled_tasks.sql135Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/03_read_write_locks_triggers.sql.postgres136Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/04_read_write_locks_deadlock.sql.postgres137Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v81138Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v82139Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 82/02_scheduled_tasks_index.sql140Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 82/04_add_indices_for_purging_rooms.sql141Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 82/05gaps.sql142Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v83143Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 83/01_drop_old_tables.sql144Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 83/05_cross_signing_key_update_grant.sql145Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 83/06_event_push_summary_room.sql146Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v84147Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 84/01_auth_links_stats.sql.postgres148Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 84/02_auth_links_index.sql149Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 84/03_auth_links_analyze.sql.postgres150Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 84/04_access_token_index.sql151Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v85152Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 85/01_add_suspended.sql153Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 85/02_add_instance_names.sql154Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 85/03_new_sequences.sql.postgres155Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 85/04_cleanup_device_federation_outbox.sql156Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 85/05_add_instance_names_converted_pos.sql157Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 85/06_add_room_reports.sql158Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v86159Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 86/01_authenticate_media.sql160Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 86/02_receipts_event_id_index.sql161Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v87162Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 87/01_sliding_sync_memberships.sql163Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 87/02_per_connection_state.sql164Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 87/03_current_state_index.sql165Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v88166Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 88/01_add_delayed_events.sql167Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 88/01_custom_profile_fields.sql168Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 88/02_fix_sliding_sync_membership_snapshots_forgotten_column.sql169Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 88/03_add_otk_ts_added_index.sql170Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 88/04_current_state_delta_index.sql171Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 88/05_drop_old_otks.sql.postgres172Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 88/05_sliding_sync_room_config_index.sql173Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 88/06_events_received_ts_index.sql174Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v89175Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 89/01_sliding_sync_membership_snapshot_index.sql176Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 89/01_state_groups_deletion.sql177Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v90178Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 90/01_add_column_participant_room_memberships_table.sql179Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 90/02_delete_unreferenced_state_groups.sql180Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 90/03_remove_old_deletion_bg_update.sql181Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v91182Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 91/01_media_hash.sql183Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v92184Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/01_remove_trigger.sql.postgres185Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/02_remove_populate_participant_bg_update.sql186Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/04_ss_membership_snapshot_idx.sql187Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/04_thread_subscriptions.sql188Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/04_thread_subscriptions_seq.sql.postgres189Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/05_fixup_max_depth_cap.sql190Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/05_thread_subscriptions_comments.sql.postgres191Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/06_device_federation_inbox_index.sql192Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/06_threads_last_sent_stream_ordering_comments.sql.postgres193Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/07_add_user_reports.sql194Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/07_event_txn_id_device_id_txn_id2.sql195Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/08_room_ban_redactions.sql196Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/08_thread_subscriptions_seq_fixup.sql.postgres197Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/09_thread_subscriptions_update.sql198Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/09_thread_subscriptions_update.sql.postgres199Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v93200Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 93/01_add_delayed_events.sql201Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 93/01_sticky_events.sql202Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 93/01_sticky_events_seq.sql.postgres203Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 93/02_sliding_sync_members.sql204Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 93/03_sss_pos_last_used.sql205Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Running 93/04_make_delayed_event_content_text.py:run_create206Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v94207Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/01_redactions_recheck.sql208Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/02_redactions_recheck_bg_update.sql209Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/03_device_lists_room_timestamp.sql210Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/03_quarantined_media_tracking.sql211Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 94/03_quarantined_media_tracking_seq.sql.postgres212Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/03_state_dag_fwd_extrems.sql213Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/04_device_lists_changes_max_pruned.sql214Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/05_rerun_flag_existing_quarantined_media.sql215Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/06_sliding_sync_connections_last_used_ts_index.sql216Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/07_sliding_sync_lazy_members_position_index.sql217Aug 17 10:10:41 machine synapse[491]: synapse.storage.prepare_database: [main] Schema now up to date218Aug 17 10:10:41 machine synapse[491]: synapse.storage.databases: [main] [database config 'master']: Starting 'main' database219Aug 17 10:10:41 machine synapse[491]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules(id): 1220Aug 17 10:10:41 machine synapse[491]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules_enable(id): 1221Aug 17 10:10:41 machine synapse[491]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 month ago222Aug 17 10:10:41 machine synapse[491]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 month ago: it's 0223Aug 17 10:10:41 machine synapse[491]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 day ago224Aug 17 10:10:41 machine synapse[491]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 day ago: it's 0225Aug 17 10:10:41 machine synapse[491]: synapse.storage.util.id_generators: [main] Initialising stream generator for access_tokens(id): 1226Aug 17 10:10:41 machine synapse[491]: synapse.storage.util.id_generators: [main] Initialising stream generator for refresh_tokens(id): 1227Aug 17 10:10:41 machine synapse[491]: synapse.storage.util.id_generators: [main] Initialising stream generator for event_reports(id): 1228Aug 17 10:10:41 machine synapse[491]: synapse.storage.util.id_generators: [main] Initialising stream generator for room_reports(id): 1229Aug 17 10:10:41 machine synapse[491]: synapse.storage.util.id_generators: [main] Initialising stream generator for user_reports(id): 1230Aug 17 10:10:41 machine synapse[491]: synapse.storage.databases: [main] [database config 'master']: Starting 'state' database231Aug 17 10:10:41 machine synapse[491]: synapse.storage.databases: [main] [database config 'master']: prepared232Aug 17 10:10:41 machine synapse[491]: synapse.server: [main] Finished setting up.233Aug 17 10:10:41 machine synapse[491]: twisted: [main] Redirected stdout/stderr to logs234Aug 17 10:10:41 machine synapse[491]: synapse.app.homeserver: [main] Running235Aug 17 10:10:41 machine synapse[491]: synapse.app.homeserver: [main] Set file limit to: 524288236Aug 17 10:10:41 machine synapse[491]: synapse.push.pusher: [call_when_running] email enable notifs: False237Aug 17 10:10:41 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'profile'238Aug 17 10:10:41 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.device_list_update'239Aug 17 10:10:41 machine synapse[491]: synapse.util.caches.lrucache: [call_when_running] Expiring LRU caches after 1800 seconds240Aug 17 10:10:41 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.presence'241Aug 17 10:10:41 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.typing'242Aug 17 10:10:41 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'directory'243Aug 17 10:10:41 machine synapse[491]: synapse.handlers.deactivate_account: [user_parter_loop-0] Starting user parter244Aug 17 10:10:41 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.receipt'245Aug 17 10:10:41 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.signing_key_update'246Aug 17 10:10:41 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'org.matrix.signing_key_update'247Aug 17 10:10:41 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'client_keys'248Aug 17 10:10:41 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.direct_to_device'249Aug 17 10:10:41 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.health.HealthResource object at 0xffffee089ea0> to path b'/health'250Aug 17 10:10:41 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.EncodingResourceWrapper object at 0xffffee302f20> to path b'/_matrix/client'251Aug 17 10:10:41 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffee308c20> to path b'/.well-known'252Aug 17 10:10:41 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.http.server.JsonResource object at 0xffffee3030a0> to path b'/_synapse/admin'253Aug 17 10:10:41 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.pick_idp.PickIdpResource object at 0xffffee3090a0> to path b'/_synapse/client/pick_idp'254Aug 17 10:10:41 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffedcaf8a0> to path b'/_synapse/client/pick_username'255Aug 17 10:10:41 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.new_user_consent.NewUserConsentResource object at 0xffffee3096a0> to path b'/_synapse/client/new_user_consent'256Aug 17 10:10:41 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.sso_register.SsoRegisterResource object at 0xffffee309820> to path b'/_synapse/client/sso_register'257Aug 17 10:10:41 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.unsubscribe.UnsubscribeResource object at 0xffffee3099a0> to path b'/_synapse/client/unsubscribe'258Aug 17 10:10:41 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.media_upload_limit_exceeded.MediaUploadLimitExceededResource object at 0xffffee309b20> to path b'/_synapse/client/media_upload_limit_exceeded'259Aug 17 10:10:41 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching FilePath('/nix/store/wrbw2mlx5755960ihv9l77c8dhrrm3iv-matrix-synapse-1.158.0/lib/python3.13/site-packages/synapse/static') to path b'/_matrix/static'260Aug 17 10:10:41 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee309fa0> to path b'/_matrix/media/r0'261Aug 17 10:10:41 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee309fa0> to path b'/_matrix/media/v3'262Aug 17 10:10:41 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee309fa0> to path b'/_matrix/media/v1'263Aug 17 10:10:41 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.federation.transport.server.TransportLayerServer object at 0xffffee30aba0> to path b'/_matrix/federation'264Aug 17 10:10:41 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.key.v2.KeyResource object at 0xffffee30dd20> to path b'/_matrix/key'265Aug 17 10:10:41 machine synapse[491]: twisted: [call_when_running] SynapseSite starting on 8008266Aug 17 10:10:41 machine synapse[491]: synapse.app._base: [call_when_running] Synapse now listening on TCP port 8008267Aug 17 10:10:41 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).268Aug 17 10:10:41 machine synapse[491]: synapse.storage.background_updates: [background_updates-0] Starting background schema updates for database master269Aug 17 10:10:41 machine synapse[491]: synapse.handlers.deactivate_account: [user_parter_loop-0] User parter finished: stopping270Aug 17 10:10:41 machine synapse[491]: synapse.push.pusherpool: [start_pushers-0] Started pushers271Aug 17 10:10:41 machine bnzgj7n6gry2s8ppq7j2akw0vsk6df4m-matrix-synapse-create-users[520]: Connection to ::1 8008 port [tcp/http-alt] succeeded!272Aug 17 10:10:42 machine synapse[491]: 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]273Aug 17 10:10:42 machine synapse[491]: synapse.handlers.auth: [POST-1] Logging in user @admin:clan.test on device IOQBYTPOHB274Aug 17 10:10:42 machine synapse[491]: synapse.access.http.8008: [POST-1] ::1 - 8008 - {None} Processed request: 0.302sec/0.001sec ru=(0.256sec, 0.006sec) db=(0.005sec/0.035sec/12) 138B 200 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]275Aug 17 10:10:42 machine bnzgj7n6gry2s8ppq7j2akw0vsk6df4m-matrix-synapse-create-users[521]: Sending registration request...276Aug 17 10:10:42 machine bnzgj7n6gry2s8ppq7j2akw0vsk6df4m-matrix-synapse-create-users[521]: Success!277Aug 17 10:10:42 machine synapse[491]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'threads_backfill'278Aug 17 10:10:42 machine synapse[491]: synapse.storage.background_updates: [background_updates-0] Running background update 'threads_backfill'. Processed 0 items in 4ms. (total_rate=None/ms, current_rate=None/ms, total_updated=0, batch_size=100)279Aug 17 10:10:43 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]280Aug 17 10:10:43 machine synapse[491]: synapse.handlers.auth: [POST-3] Logging in user @someuser:clan.test on device BHSAPEDRBT281Aug 17 10:10:43 machine synapse[491]: synapse.access.http.8008: [POST-3] ::1 - 8008 - {None} Processed request: 0.308sec/0.001sec ru=(0.273sec, 0.002sec) db=(0.005sec/0.020sec/12) 145B 200 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]282Aug 17 10:10:43 machine bnzgj7n6gry2s8ppq7j2akw0vsk6df4m-matrix-synapse-create-users[528]: Sending registration request...283Aug 17 10:10:43 machine bnzgj7n6gry2s8ppq7j2akw0vsk6df4m-matrix-synapse-create-users[528]: Success!284Aug 17 10:10:43 machine systemd[1]: Started Synapse Matrix homeserver.285Aug 17 10:10:43 machine synapse[491]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'device_lists_changes_in_room_by_room_index'286Aug 17 10:10:43 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_room287Aug 17 10:10:43 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 6ms. (total_rate=0.16666666666666666/ms, current_rate=0.16666666666666666/ms, total_updated=1, batch_size=100)288Aug 17 10:10:43 machine synapse[491]: synapse.access.http.8008: [GET-4] ::1 - 8008 - {None} Processed request: 0.001sec/-0.000sec ru=(0.000sec, 0.000sec) db=(0.000sec/0.000sec/0) 10145B 200 "GET /_matrix/static/ HTTP/1.1" "curl/8.21.0" [0 dbevts]289Aug 17 10:10:43 machine systemd[1]: Stopping Synapse Matrix homeserver...290Aug 17 10:10:43 machine synapse[491]: twisted: [sentinel] Received SIGTERM, shutting down.291Aug 17 10:10:43 machine synapse[491]: synapse.storage.databases.main.lock: [LockStore._on_shutdown-0] Dropping held locks due to shutdown292Aug 17 10:10:43 machine synapse[491]: synapse.storage.databases.main.lock: [LockStore._on_shutdown-0] Dropped locks due to shutdown293Aug 17 10:10:43 machine synapse[491]: synapse.handlers.presence: [PresenceHandler._on_shutdown-0] Performing _on_shutdown. Persisting 0 unpersisted changes294Aug 17 10:10:43 machine synapse[491]: synapse.handlers.presence: [PresenceHandler._on_shutdown-0] Finished _on_shutdown295Aug 17 10:10:43 machine synapse[491]: synapse.app._base: [log_shutdown] Shutting down...296Aug 17 10:10:43 machine synapse[491]: twisted: [sentinel] (TCP Port 8008 Closed)297Aug 17 10:10:43 machine synapse[491]: twisted: [sentinel] Main loop terminated.298Aug 17 10:10:43 machine systemd[1]: matrix-synapse.service: Deactivated successfully.299Aug 17 10:10:43 machine systemd[1]: Stopped Synapse Matrix homeserver.300Aug 17 10:10:43 machine systemd[1]: matrix-synapse.service: Consumed 7.955s CPU time over 9.101s wall clock time.301Aug 17 10:10:43 machine systemd[1]: Starting Synapse Matrix homeserver...302Aug 17 10:10:49 machine synapse_homeserver[560]: This server is configured to use 'matrix.org' as its trusted key server via the303Aug 17 10:10:49 machine synapse_homeserver[560]: 'trusted_key_servers' config option. 'matrix.org' is a good choice for a key304Aug 17 10:10:49 machine synapse_homeserver[560]: server since it is long-lived, stable and trusted. However, some admins may305Aug 17 10:10:49 machine synapse_homeserver[560]: wish to use another server for this purpose.306Aug 17 10:10:49 machine synapse_homeserver[560]: To suppress this warning and continue using 'matrix.org', admins should set307Aug 17 10:10:49 machine synapse_homeserver[560]: 'suppress_key_server_warning' to 'true' in homeserver.yaml.308Aug 17 10:10:49 machine synapse_homeserver[560]: --------------------------------------------------------------------------------309Aug 17 10:10:49 machine synapse[560]: synapse.config.logger: [main] ***** STARTING SERVER *****310Aug 17 10:10:49 machine synapse[560]: synapse.config.logger: [main] Server /nix/store/wrbw2mlx5755960ihv9l77c8dhrrm3iv-matrix-synapse-1.158.0/bin/synapse_homeserver version 1.158.0311Aug 17 10:10:49 machine synapse[560]: synapse.config.logger: [main] Copyright (c) 2023 New Vector, Inc312Aug 17 10:10:49 machine synapse[560]: synapse.config.logger: [main] Licensed under the AGPL 3.0 license. Website: https://github.com/element-hq/synapse313Aug 17 10:10:49 machine synapse[560]: synapse.config.logger: [main] Server hostname: clan.test314Aug 17 10:10:49 machine synapse[560]: synapse.config.logger: [main] Public Base URL: https://clan.test/315Aug 17 10:10:49 machine synapse[560]: synapse.config.logger: [main] Instance name: master316Aug 17 10:10:49 machine synapse[560]: synapse.config.logger: [main] Twisted reactor: EPollReactor317Aug 17 10:10:49 machine synapse[560]: synapse.app.homeserver: [main] Setting up server318Aug 17 10:10:49 machine synapse[560]: synapse.server: [main] Setting up.319Aug 17 10:10:49 machine synapse[560]: synapse.storage.databases: [main] [database config 'master']: Checking database server320Aug 17 10:10:49 machine synapse[560]: synapse.storage.databases: [main] [database config 'master']: Preparing for databases ['main', 'state']321Aug 17 10:10:49 machine synapse[560]: synapse.storage.prepare_database: [main] ['main', 'state']: Checking existing schema version322Aug 17 10:10:49 machine synapse[560]: synapse.storage.prepare_database: [main] ['main', 'state']: Existing schema is 94 (+10 deltas)323Aug 17 10:10:49 machine synapse[560]: synapse.storage.databases.main: [main] Checking database for consistency with configuration...324Aug 17 10:10:49 machine synapse[560]: synapse.storage.prepare_database: [main] Applying schema deltas for v94325Aug 17 10:10:49 machine synapse[560]: synapse.storage.prepare_database: [main] Schema now up to date326Aug 17 10:10:49 machine synapse[560]: synapse.storage.databases: [main] [database config 'master']: Starting 'main' database327Aug 17 10:10:49 machine synapse[560]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules(id): 1328Aug 17 10:10:49 machine synapse[560]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules_enable(id): 1329Aug 17 10:10:49 machine synapse[560]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 month ago330Aug 17 10:10:49 machine synapse[560]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 month ago: it's 0331Aug 17 10:10:49 machine synapse[560]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 day ago332Aug 17 10:10:49 machine synapse[560]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 day ago: it's 0333Aug 17 10:10:49 machine synapse[560]: synapse.storage.util.id_generators: [main] Initialising stream generator for access_tokens(id): 3334Aug 17 10:10:49 machine synapse[560]: synapse.storage.util.id_generators: [main] Initialising stream generator for refresh_tokens(id): 1335Aug 17 10:10:49 machine synapse[560]: synapse.storage.util.id_generators: [main] Initialising stream generator for event_reports(id): 1336Aug 17 10:10:49 machine synapse[560]: synapse.storage.util.id_generators: [main] Initialising stream generator for room_reports(id): 1337Aug 17 10:10:49 machine synapse[560]: synapse.storage.util.id_generators: [main] Initialising stream generator for user_reports(id): 1338Aug 17 10:10:49 machine synapse[560]: synapse.storage.databases: [main] [database config 'master']: Starting 'state' database339Aug 17 10:10:49 machine synapse[560]: synapse.storage.databases: [main] [database config 'master']: prepared340Aug 17 10:10:49 machine synapse[560]: synapse.server: [main] Finished setting up.341Aug 17 10:10:49 machine synapse[560]: twisted: [main] Redirected stdout/stderr to logs342Aug 17 10:10:49 machine synapse[560]: synapse.app.homeserver: [main] Running343Aug 17 10:10:49 machine synapse[560]: synapse.app.homeserver: [main] Set file limit to: 524288344Aug 17 10:10:49 machine synapse[560]: synapse.push.pusher: [call_when_running] email enable notifs: False345Aug 17 10:10:49 machine synapse[560]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'profile'346Aug 17 10:10:49 machine synapse[560]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.device_list_update'347Aug 17 10:10:49 machine synapse[560]: synapse.util.caches.lrucache: [call_when_running] Expiring LRU caches after 1800 seconds348Aug 17 10:10:49 machine synapse[560]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.presence'349Aug 17 10:10:49 machine synapse[560]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.typing'350Aug 17 10:10:49 machine synapse[560]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'directory'351Aug 17 10:10:49 machine synapse[560]: synapse.handlers.deactivate_account: [user_parter_loop-0] Starting user parter352Aug 17 10:10:49 machine synapse[560]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.receipt'353Aug 17 10:10:49 machine synapse[560]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.signing_key_update'354Aug 17 10:10:49 machine synapse[560]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'org.matrix.signing_key_update'355Aug 17 10:10:49 machine synapse[560]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'client_keys'356Aug 17 10:10:49 machine synapse[560]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.direct_to_device'357Aug 17 10:10:49 machine synapse[560]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.health.HealthResource object at 0xffffee08a020> to path b'/health'358Aug 17 10:10:49 machine synapse[560]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.EncodingResourceWrapper object at 0xffffee2f30a0> to path b'/_matrix/client'359Aug 17 10:10:49 machine synapse[560]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffee2f8da0> to path b'/.well-known'360Aug 17 10:10:49 machine synapse[560]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.http.server.JsonResource object at 0xffffee2f3220> to path b'/_synapse/admin'361Aug 17 10:10:49 machine synapse[560]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.pick_idp.PickIdpResource object at 0xffffee2f9220> to path b'/_synapse/client/pick_idp'362Aug 17 10:10:49 machine synapse[560]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffedc9f760> to path b'/_synapse/client/pick_username'363Aug 17 10:10:49 machine synapse[560]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.new_user_consent.NewUserConsentResource object at 0xffffee2f9820> to path b'/_synapse/client/new_user_consent'364Aug 17 10:10:49 machine synapse[560]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.sso_register.SsoRegisterResource object at 0xffffee2f99a0> to path b'/_synapse/client/sso_register'365Aug 17 10:10:49 machine synapse[560]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.unsubscribe.UnsubscribeResource object at 0xffffee2f9b20> to path b'/_synapse/client/unsubscribe'366Aug 17 10:10:49 machine synapse[560]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.media_upload_limit_exceeded.MediaUploadLimitExceededResource object at 0xffffee2f9ca0> to path b'/_synapse/client/media_upload_limit_exceeded'367Aug 17 10:10:49 machine synapse[560]: synapse.util.httpresourcetree: [call_when_running] Attaching FilePath('/nix/store/wrbw2mlx5755960ihv9l77c8dhrrm3iv-matrix-synapse-1.158.0/lib/python3.13/site-packages/synapse/static') to path b'/_matrix/static'368Aug 17 10:10:49 machine synapse[560]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee2fa120> to path b'/_matrix/media/r0'369Aug 17 10:10:49 machine synapse[560]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee2fa120> to path b'/_matrix/media/v3'370Aug 17 10:10:49 machine synapse[560]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee2fa120> to path b'/_matrix/media/v1'371Aug 17 10:10:49 machine synapse[560]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.federation.transport.server.TransportLayerServer object at 0xffffee2fad20> to path b'/_matrix/federation'372Aug 17 10:10:49 machine synapse[560]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.key.v2.KeyResource object at 0xffffee2fdea0> to path b'/_matrix/key'373Aug 17 10:10:49 machine synapse[560]: twisted: [call_when_running] SynapseSite starting on 8008374Aug 17 10:10:49 machine synapse[560]: synapse.app._base: [call_when_running] Synapse now listening on TCP port 8008375Aug 17 10:10:49 machine synapse[560]: 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).376Aug 17 10:10:49 machine synapse[560]: synapse.storage.background_updates: [background_updates-0] Starting background schema updates for database master377Aug 17 10:10:49 machine synapse[560]: synapse.handlers.deactivate_account: [user_parter_loop-0] User parter finished: stopping378Aug 17 10:10:49 machine synapse[560]: synapse.push.pusherpool: [start_pushers-0] Started pushers379Aug 17 10:10:49 machine bnzgj7n6gry2s8ppq7j2akw0vsk6df4m-matrix-synapse-create-users[585]: Connection to ::1 8008 port [tcp/http-alt] succeeded!380Aug 17 10:10:50 machine synapse[560]: 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]381Aug 17 10:10:50 machine synapse[560]: synapse.http.server: [POST-1] <XForwardedForRequest at 0xffffe1a01ba0 method='POST' uri='/_synapse/admin/v1/register' clientproto='HTTP/1.1' site='8008'> SynapseError: 400 - User ID already taken.382Aug 17 10:10:50 machine synapse[560]: synapse.access.http.8008: [POST-1] ::1 - 8008 - {None} Processed request: 0.249sec/0.000sec ru=(0.242sec, 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]383Aug 17 10:10:50 machine bnzgj7n6gry2s8ppq7j2akw0vsk6df4m-matrix-synapse-create-users[586]: Sending registration request...384Aug 17 10:10:50 machine bnzgj7n6gry2s8ppq7j2akw0vsk6df4m-matrix-synapse-create-users[586]: User already exists. Skipping.385Aug 17 10:10:50 machine synapse[560]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'populate_stats_process_rooms'386Aug 17 10:10:50 machine synapse[560]: 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)387Aug 17 10:10:51 machine synapse[560]: 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]388Aug 17 10:10:51 machine synapse[560]: synapse.http.server: [POST-3] <XForwardedForRequest at 0xffffe1a02fa0 method='POST' uri='/_synapse/admin/v1/register' clientproto='HTTP/1.1' site='8008'> SynapseError: 400 - User ID already taken.389Aug 17 10:10:51 machine synapse[560]: 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]390Aug 17 10:10:51 machine bnzgj7n6gry2s8ppq7j2akw0vsk6df4m-matrix-synapse-create-users[592]: Sending registration request...391Aug 17 10:10:51 machine bnzgj7n6gry2s8ppq7j2akw0vsk6df4m-matrix-synapse-create-users[592]: User already exists. Skipping.392Aug 17 10:10:51 machine systemd[1]: Started Synapse Matrix homeserver.393+ systemctl --no-pager show matrix-synapse394+ nc -z -v ::1 8008395Connection to ::1 8008 port [tcp/http-alt] succeeded!396+ curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test'397