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