container-test-run-matrix-synapse
checks.aarch64-linux.matrix-synapse
· build #432
· raw
1additionally exposed symbols:2 machine,3 start_all, machines, driver, Machine, wait_for_signal4Starting machine567<<< NixOS Stage 2 >>>89booting system configuration /nix/store/lscz2n2ji683mdgdmr062l7yfkis10dm-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 af751c2d-a1f1-4f16-9350-eefa5b7151da'29Running as unit: run-p481-i98317451.service; invocation ID: 11e8f8d2b3fc41f286d2c267bca169dd30To attach to container machine run on the same machine that runs the test:31 sudo nsenter --user --target $(\pgrep -f '^/bin/sh.*af751c2d-a1f1-4f16-9350-eefa5b7151da') --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 af751c2d-a1f1-4f16-9350-eefa5b7151da35+ 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+ systemctl --no-pager show matrix-synapse44+ nc -z -v ::1 800845Connection to ::1 8008 port [tcp/http-alt] succeeded!46machine: waiting for success: curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test'47+ curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test'48(finished: waiting for success: curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test', in 0.02 seconds)49+ systemctl restart matrix-synapse50+ journalctl -u matrix-synapse --no-pager51Aug 19 15:11:33 machine systemd[1]: Starting Synapse Matrix homeserver...52Aug 19 15:11:36 machine matrix-synapse-pre-start[476]: Generating signing key file /var/lib/matrix-synapse/homeserver.signing.key53Aug 19 15:11:39 machine synapse_homeserver[492]: This server is configured to use 'matrix.org' as its trusted key server via the54Aug 19 15:11:39 machine synapse_homeserver[492]: 'trusted_key_servers' config option. 'matrix.org' is a good choice for a key55Aug 19 15:11:39 machine synapse_homeserver[492]: server since it is long-lived, stable and trusted. However, some admins may56Aug 19 15:11:39 machine synapse_homeserver[492]: wish to use another server for this purpose.57Aug 19 15:11:39 machine synapse_homeserver[492]: To suppress this warning and continue using 'matrix.org', admins should set58Aug 19 15:11:39 machine synapse_homeserver[492]: 'suppress_key_server_warning' to 'true' in homeserver.yaml.59Aug 19 15:11:39 machine synapse_homeserver[492]: --------------------------------------------------------------------------------60Aug 19 15:11:39 machine synapse[492]: synapse.config.logger: [main] ***** STARTING SERVER *****61Aug 19 15:11:39 machine synapse[492]: synapse.config.logger: [main] Server /nix/store/lb04knrjrz1zzn0cwdpx2k735s0j8adc-matrix-synapse-1.158.0/bin/synapse_homeserver version 1.158.062Aug 19 15:11:39 machine synapse[492]: synapse.config.logger: [main] Copyright (c) 2023 New Vector, Inc63Aug 19 15:11:39 machine synapse[492]: synapse.config.logger: [main] Licensed under the AGPL 3.0 license. Website: https://github.com/element-hq/synapse64Aug 19 15:11:39 machine synapse[492]: synapse.config.logger: [main] Server hostname: clan.test65Aug 19 15:11:39 machine synapse[492]: synapse.config.logger: [main] Public Base URL: https://clan.test/66Aug 19 15:11:39 machine synapse[492]: synapse.config.logger: [main] Instance name: master67Aug 19 15:11:39 machine synapse[492]: synapse.config.logger: [main] Twisted reactor: EPollReactor68Aug 19 15:11:39 machine synapse[492]: synapse.app.homeserver: [main] Setting up server69Aug 19 15:11:39 machine synapse[492]: synapse.server: [main] Setting up.70Aug 19 15:11:39 machine synapse[492]: synapse.storage.databases: [main] [database config 'master']: Checking database server71Aug 19 15:11:39 machine synapse[492]: synapse.storage.databases: [main] [database config 'master']: Preparing for databases ['main', 'state']72Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] ['main', 'state']: Checking existing schema version73Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] ['main', 'state']: Initialising new database74Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v7375Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/01event_failed_pull_attempts.sql76Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/02add_pusher_enabled.sql77Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/02room_id_indexes_for_purging.sql78Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/03pusher_device_id.sql79Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/03users_approved_column.sql80Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/04partial_join_details.sql81Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/04pending_device_list_updates.sql82Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/05old_push_actions.sql.postgres83Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/06thread_notifications_thread_id_idx.sql84Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/08thread_receipts_non_null.sql.postgres85Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/09partial_joined_via_destination.sql86Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/09threads_table.sql87Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Running 73/10_update_sqlite_fts4_tokenizer.py:run_create88Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/10login_tokens.sql89Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/11event_search_room_id_n_distinct.sql.postgres90Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/12refactor_device_list_outbound_pokes.sql91Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/13add_device_lists_index.sql92Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/20_un_partial_stated_room_stream.sql93Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/21_un_partial_stated_room_stream_seq.sql.postgres94Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/22_rebuild_user_dir_stats.sql95Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/22_un_partial_stated_event_stream.sql96Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/23_fix_thread_index.sql97Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/23_un_partial_stated_room_stream_seq.sql.postgres98Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/24_events_jump_to_date_index.sql99Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/25drop_presence.sql100Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v74101Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 74/01_user_directory_stale_remote_users.sql102Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 74/02_set_device_id_for_pushers_bg_update.sql103Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 74/03_membership_tables_event_stream_ordering.sql.postgres104Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 74/03_room_membership_index.sql105Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 74/04_delete_e2e_backup_keys_for_deactivated_users.sql106Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Running 74/04_membership_tables_event_stream_ordering_triggers.py:run_create107Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 74/05_events_txn_id_device_id.sql108Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 74/90COMMENTS_destinations.sql.postgres109Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v75110Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v76111Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 76/01_add_profiles_full_user_id_column.sql112Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 76/02_add_user_filters_full_user_id_column.sql113Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 76/03_per_user_experimental_features.sql114Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 76/04_add_room_forgetter.sql115Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v77116Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/01_add_profiles_not_valid_check.sql.postgres117Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/02_add_user_filters_not_valid_check.sql.postgres118Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 77/03bg_populate_full_user_id_profiles.sql119Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 77/04bg_populate_full_user_id_user_filters.sql120Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 77/05thread_notifications_backfill.sql121Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_actions.sql.postgres122Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_actions_staging.sql.postgres123Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_summary.sql.postgres124Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 77/14bg_indices_event_stream_ordering.sql125Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v78126Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Running 78/03event_extremities_constraints.py:run_create127Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Running 78/04_add_full_user_id_index_user_filters.py:run_create128Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v79129Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 79/03_read_write_locks_triggers.sql.postgres130Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Running 79/04_mitigate_stream_ordering_update_race.py:run_create131Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 79/05_read_write_locks_triggers.sql.postgres132Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v80133Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 80/01_users_alter_locked.sql134Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/02_read_write_locks_unlogged.sql.postgres135Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 80/02_scheduled_tasks.sql136Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/03_read_write_locks_triggers.sql.postgres137Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/04_read_write_locks_deadlock.sql.postgres138Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v81139Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v82140Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 82/02_scheduled_tasks_index.sql141Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 82/04_add_indices_for_purging_rooms.sql142Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 82/05gaps.sql143Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v83144Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 83/01_drop_old_tables.sql145Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 83/05_cross_signing_key_update_grant.sql146Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 83/06_event_push_summary_room.sql147Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v84148Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 84/01_auth_links_stats.sql.postgres149Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 84/02_auth_links_index.sql150Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 84/03_auth_links_analyze.sql.postgres151Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 84/04_access_token_index.sql152Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v85153Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 85/01_add_suspended.sql154Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 85/02_add_instance_names.sql155Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 85/03_new_sequences.sql.postgres156Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 85/04_cleanup_device_federation_outbox.sql157Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 85/05_add_instance_names_converted_pos.sql158Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 85/06_add_room_reports.sql159Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v86160Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 86/01_authenticate_media.sql161Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 86/02_receipts_event_id_index.sql162Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v87163Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 87/01_sliding_sync_memberships.sql164Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 87/02_per_connection_state.sql165Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 87/03_current_state_index.sql166Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v88167Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 88/01_add_delayed_events.sql168Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 88/01_custom_profile_fields.sql169Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 88/02_fix_sliding_sync_membership_snapshots_forgotten_column.sql170Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 88/03_add_otk_ts_added_index.sql171Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 88/04_current_state_delta_index.sql172Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 88/05_drop_old_otks.sql.postgres173Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 88/05_sliding_sync_room_config_index.sql174Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 88/06_events_received_ts_index.sql175Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v89176Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 89/01_sliding_sync_membership_snapshot_index.sql177Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 89/01_state_groups_deletion.sql178Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v90179Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 90/01_add_column_participant_room_memberships_table.sql180Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 90/02_delete_unreferenced_state_groups.sql181Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 90/03_remove_old_deletion_bg_update.sql182Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v91183Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 91/01_media_hash.sql184Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v92185Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/01_remove_trigger.sql.postgres186Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 92/02_remove_populate_participant_bg_update.sql187Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 92/04_ss_membership_snapshot_idx.sql188Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 92/04_thread_subscriptions.sql189Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/04_thread_subscriptions_seq.sql.postgres190Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 92/05_fixup_max_depth_cap.sql191Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/05_thread_subscriptions_comments.sql.postgres192Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 92/06_device_federation_inbox_index.sql193Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/06_threads_last_sent_stream_ordering_comments.sql.postgres194Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 92/07_add_user_reports.sql195Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 92/07_event_txn_id_device_id_txn_id2.sql196Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 92/08_room_ban_redactions.sql197Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/08_thread_subscriptions_seq_fixup.sql.postgres198Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 92/09_thread_subscriptions_update.sql199Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/09_thread_subscriptions_update.sql.postgres200Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v93201Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 93/01_add_delayed_events.sql202Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 93/01_sticky_events.sql203Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 93/01_sticky_events_seq.sql.postgres204Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 93/02_sliding_sync_members.sql205Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 93/03_sss_pos_last_used.sql206Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Running 93/04_make_delayed_event_content_text.py:run_create207Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v94208Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 94/01_redactions_recheck.sql209Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 94/02_redactions_recheck_bg_update.sql210Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 94/03_device_lists_room_timestamp.sql211Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 94/03_quarantined_media_tracking.sql212Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 94/03_quarantined_media_tracking_seq.sql.postgres213Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 94/03_state_dag_fwd_extrems.sql214Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 94/04_device_lists_changes_max_pruned.sql215Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 94/05_rerun_flag_existing_quarantined_media.sql216Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 94/06_sliding_sync_connections_last_used_ts_index.sql217Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 94/07_sliding_sync_lazy_members_position_index.sql218Aug 19 15:11:39 machine synapse[492]: synapse.storage.prepare_database: [main] Schema now up to date219Aug 19 15:11:39 machine synapse[492]: synapse.storage.databases: [main] [database config 'master']: Starting 'main' database220Aug 19 15:11:39 machine synapse[492]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules(id): 1221Aug 19 15:11:39 machine synapse[492]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules_enable(id): 1222Aug 19 15:11:39 machine synapse[492]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 month ago223Aug 19 15:11:39 machine synapse[492]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 month ago: it's 0224Aug 19 15:11:39 machine synapse[492]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 day ago225Aug 19 15:11:39 machine synapse[492]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 day ago: it's 0226Aug 19 15:11:40 machine synapse[492]: synapse.storage.util.id_generators: [main] Initialising stream generator for access_tokens(id): 1227Aug 19 15:11:40 machine synapse[492]: synapse.storage.util.id_generators: [main] Initialising stream generator for refresh_tokens(id): 1228Aug 19 15:11:40 machine synapse[492]: synapse.storage.util.id_generators: [main] Initialising stream generator for event_reports(id): 1229Aug 19 15:11:40 machine synapse[492]: synapse.storage.util.id_generators: [main] Initialising stream generator for room_reports(id): 1230Aug 19 15:11:40 machine synapse[492]: synapse.storage.util.id_generators: [main] Initialising stream generator for user_reports(id): 1231Aug 19 15:11:40 machine synapse[492]: synapse.storage.databases: [main] [database config 'master']: Starting 'state' database232Aug 19 15:11:40 machine synapse[492]: synapse.storage.databases: [main] [database config 'master']: prepared233Aug 19 15:11:40 machine synapse[492]: synapse.server: [main] Finished setting up.234Aug 19 15:11:40 machine synapse[492]: twisted: [main] Redirected stdout/stderr to logs235Aug 19 15:11:40 machine synapse[492]: synapse.app.homeserver: [main] Running236Aug 19 15:11:40 machine synapse[492]: synapse.app.homeserver: [main] Set file limit to: 524288237Aug 19 15:11:40 machine synapse[492]: synapse.push.pusher: [call_when_running] email enable notifs: False238Aug 19 15:11:40 machine synapse[492]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'profile'239Aug 19 15:11:40 machine synapse[492]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.device_list_update'240Aug 19 15:11:40 machine synapse[492]: synapse.util.caches.lrucache: [call_when_running] Expiring LRU caches after 1800 seconds241Aug 19 15:11:40 machine synapse[492]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.presence'242Aug 19 15:11:40 machine synapse[492]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.typing'243Aug 19 15:11:40 machine synapse[492]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'directory'244Aug 19 15:11:40 machine synapse[492]: synapse.handlers.deactivate_account: [user_parter_loop-0] Starting user parter245Aug 19 15:11:40 machine synapse[492]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.receipt'246Aug 19 15:11:40 machine synapse[492]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.signing_key_update'247Aug 19 15:11:40 machine synapse[492]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'org.matrix.signing_key_update'248Aug 19 15:11:40 machine synapse[492]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'client_keys'249Aug 19 15:11:40 machine synapse[492]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.direct_to_device'250Aug 19 15:11:40 machine synapse[492]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.health.HealthResource object at 0xffffee009ea0> to path b'/health'251Aug 19 15:11:40 machine synapse[492]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.EncodingResourceWrapper object at 0xffffee2d2f20> to path b'/_matrix/client'252Aug 19 15:11:40 machine synapse[492]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffee2d8c20> to path b'/.well-known'253Aug 19 15:11:40 machine synapse[492]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.http.server.JsonResource object at 0xffffee2d30a0> to path b'/_synapse/admin'254Aug 19 15:11:40 machine synapse[492]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.pick_idp.PickIdpResource object at 0xffffee2d90a0> to path b'/_synapse/client/pick_idp'255Aug 19 15:11:40 machine synapse[492]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffedcaf8a0> to path b'/_synapse/client/pick_username'256Aug 19 15:11:40 machine synapse[492]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.new_user_consent.NewUserConsentResource object at 0xffffee2d96a0> to path b'/_synapse/client/new_user_consent'257Aug 19 15:11:40 machine synapse[492]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.sso_register.SsoRegisterResource object at 0xffffee2d9820> to path b'/_synapse/client/sso_register'258Aug 19 15:11:40 machine synapse[492]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.unsubscribe.UnsubscribeResource object at 0xffffee2d99a0> to path b'/_synapse/client/unsubscribe'259Aug 19 15:11:40 machine synapse[492]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.media_upload_limit_exceeded.MediaUploadLimitExceededResource object at 0xffffee2d9b20> to path b'/_synapse/client/media_upload_limit_exceeded'260Aug 19 15:11:40 machine synapse[492]: 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'261Aug 19 15:11:40 machine synapse[492]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee2d9fa0> to path b'/_matrix/media/r0'262Aug 19 15:11:40 machine synapse[492]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee2d9fa0> to path b'/_matrix/media/v3'263Aug 19 15:11:40 machine synapse[492]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee2d9fa0> to path b'/_matrix/media/v1'264Aug 19 15:11:40 machine synapse[492]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.federation.transport.server.TransportLayerServer object at 0xffffee2daba0> to path b'/_matrix/federation'265Aug 19 15:11:40 machine synapse[492]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.key.v2.KeyResource object at 0xffffee2ddd20> to path b'/_matrix/key'266Aug 19 15:11:40 machine synapse[492]: twisted: [call_when_running] SynapseSite starting on 8008267Aug 19 15:11:40 machine synapse[492]: synapse.app._base: [call_when_running] Synapse now listening on TCP port 8008268Aug 19 15:11:40 machine synapse[492]: 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).269Aug 19 15:11:40 machine synapse[492]: synapse.storage.background_updates: [background_updates-0] Starting background schema updates for database master270Aug 19 15:11:40 machine synapse[492]: synapse.handlers.deactivate_account: [user_parter_loop-0] User parter finished: stopping271Aug 19 15:11:40 machine synapse[492]: synapse.push.pusherpool: [start_pushers-0] Started pushers272Aug 19 15:11:40 machine wilq5k4l3zkskj1vfpl6p6ld63xg52f7-matrix-synapse-create-users[521]: Connection to ::1 8008 port [tcp/http-alt] succeeded!273Aug 19 15:11:40 machine synapse[492]: synapse.access.http.8008: [GET-0] ::1 - 8008 - {None} Processed request: 0.001sec/0.001sec ru=(0.002sec, 0.001sec) db=(0.000sec/0.000sec/0) 140B 200 "GET /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]274Aug 19 15:11:41 machine synapse[492]: synapse.handlers.auth: [POST-1] Logging in user @admin:clan.test on device BUJFSQKKNH275Aug 19 15:11:41 machine synapse[492]: synapse.access.http.8008: [POST-1] ::1 - 8008 - {None} Processed request: 0.304sec/0.001sec ru=(0.266sec, 0.000sec) db=(0.005sec/0.033sec/12) 138B 200 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]276Aug 19 15:11:41 machine wilq5k4l3zkskj1vfpl6p6ld63xg52f7-matrix-synapse-create-users[522]: Sending registration request...277Aug 19 15:11:41 machine wilq5k4l3zkskj1vfpl6p6ld63xg52f7-matrix-synapse-create-users[522]: Success!278Aug 19 15:11:41 machine synapse[492]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'threads_backfill'279Aug 19 15:11:41 machine synapse[492]: 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)280Aug 19 15:11:41 machine synapse[492]: 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]281Aug 19 15:11:42 machine synapse[492]: synapse.handlers.auth: [POST-3] Logging in user @someuser:clan.test on device IKWSCLXMUR282Aug 19 15:11:42 machine synapse[492]: synapse.access.http.8008: [POST-3] ::1 - 8008 - {None} Processed request: 0.288sec/0.001sec ru=(0.256sec, 0.000sec) db=(0.005sec/0.017sec/12) 145B 200 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]283Aug 19 15:11:42 machine wilq5k4l3zkskj1vfpl6p6ld63xg52f7-matrix-synapse-create-users[529]: Sending registration request...284Aug 19 15:11:42 machine wilq5k4l3zkskj1vfpl6p6ld63xg52f7-matrix-synapse-create-users[529]: Success!285Aug 19 15:11:42 machine systemd[1]: Started Synapse Matrix homeserver.286Aug 19 15:11:42 machine synapse[492]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'device_lists_changes_in_room_by_room_index'287Aug 19 15:11:42 machine synapse[492]: synapse.storage.background_updates: [background_updates-0] Adding index device_lists_changes_in_room_by_room_idx to device_lists_changes_in_room288Aug 19 15:11:42 machine synapse[492]: synapse.storage.background_updates: [background_updates-0] Running background update 'device_lists_changes_in_room_by_room_index'. Processed 1 items in 7ms. (total_rate=0.14285714285714285/ms, current_rate=0.14285714285714285/ms, total_updated=1, batch_size=100)289Aug 19 15:11:43 machine synapse[492]: 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]290Aug 19 15:11:43 machine systemd[1]: Stopping Synapse Matrix homeserver...291Aug 19 15:11:43 machine synapse[492]: twisted: [sentinel] Received SIGTERM, shutting down.292Aug 19 15:11:43 machine synapse[492]: synapse.storage.databases.main.lock: [LockStore._on_shutdown-0] Dropping held locks due to shutdown293Aug 19 15:11:43 machine synapse[492]: synapse.storage.databases.main.lock: [LockStore._on_shutdown-0] Dropped locks due to shutdown294Aug 19 15:11:43 machine synapse[492]: synapse.handlers.presence: [PresenceHandler._on_shutdown-0] Performing _on_shutdown. Persisting 0 unpersisted changes295Aug 19 15:11:43 machine synapse[492]: synapse.handlers.presence: [PresenceHandler._on_shutdown-0] Finished _on_shutdown296Aug 19 15:11:43 machine synapse[492]: synapse.app._base: [log_shutdown] Shutting down...297Aug 19 15:11:43 machine synapse[492]: twisted: [sentinel] (TCP Port 8008 Closed)298Aug 19 15:11:43 machine synapse[492]: twisted: [sentinel] Main loop terminated.299Aug 19 15:11:43 machine systemd[1]: matrix-synapse.service: Deactivated successfully.300Aug 19 15:11:43 machine systemd[1]: Stopped Synapse Matrix homeserver.301Aug 19 15:11:43 machine systemd[1]: matrix-synapse.service: Consumed 8.301s CPU time over 10.069s wall clock time.302Aug 19 15:11:43 machine systemd[1]: Starting Synapse Matrix homeserver...303Aug 19 15:11:48 machine synapse_homeserver[561]: This server is configured to use 'matrix.org' as its trusted key server via the304Aug 19 15:11:48 machine synapse_homeserver[561]: 'trusted_key_servers' config option. 'matrix.org' is a good choice for a key305Aug 19 15:11:48 machine synapse_homeserver[561]: server since it is long-lived, stable and trusted. However, some admins may306Aug 19 15:11:48 machine synapse_homeserver[561]: wish to use another server for this purpose.307Aug 19 15:11:48 machine synapse_homeserver[561]: To suppress this warning and continue using 'matrix.org', admins should set308Aug 19 15:11:48 machine synapse_homeserver[561]: 'suppress_key_server_warning' to 'true' in homeserver.yaml.309Aug 19 15:11:48 machine synapse_homeserver[561]: --------------------------------------------------------------------------------310Aug 19 15:11:48 machine synapse[561]: synapse.config.logger: [main] ***** STARTING SERVER *****311Aug 19 15:11:48 machine synapse[561]: synapse.config.logger: [main] Server /nix/store/lb04knrjrz1zzn0cwdpx2k735s0j8adc-matrix-synapse-1.158.0/bin/synapse_homeserver version 1.158.0312Aug 19 15:11:48 machine synapse[561]: synapse.config.logger: [main] Copyright (c) 2023 New Vector, Inc313Aug 19 15:11:48 machine synapse[561]: synapse.config.logger: [main] Licensed under the AGPL 3.0 license. Website: https://github.com/element-hq/synapse314Aug 19 15:11:48 machine synapse[561]: synapse.config.logger: [main] Server hostname: clan.test315Aug 19 15:11:48 machine synapse[561]: synapse.config.logger: [main] Public Base URL: https://clan.test/316Aug 19 15:11:48 machine synapse[561]: synapse.config.logger: [main] Instance name: master317Aug 19 15:11:48 machine synapse[561]: synapse.config.logger: [main] Twisted reactor: EPollReactor318Aug 19 15:11:48 machine synapse[561]: synapse.app.homeserver: [main] Setting up server319Aug 19 15:11:48 machine synapse[561]: synapse.server: [main] Setting up.320Aug 19 15:11:48 machine synapse[561]: synapse.storage.databases: [main] [database config 'master']: Checking database server321Aug 19 15:11:48 machine synapse[561]: synapse.storage.databases: [main] [database config 'master']: Preparing for databases ['main', 'state']322Aug 19 15:11:48 machine synapse[561]: synapse.storage.prepare_database: [main] ['main', 'state']: Checking existing schema version323Aug 19 15:11:48 machine synapse[561]: synapse.storage.prepare_database: [main] ['main', 'state']: Existing schema is 94 (+10 deltas)324Aug 19 15:11:48 machine synapse[561]: synapse.storage.databases.main: [main] Checking database for consistency with configuration...325Aug 19 15:11:48 machine synapse[561]: synapse.storage.prepare_database: [main] Applying schema deltas for v94326Aug 19 15:11:48 machine synapse[561]: synapse.storage.prepare_database: [main] Schema now up to date327Aug 19 15:11:48 machine synapse[561]: synapse.storage.databases: [main] [database config 'master']: Starting 'main' database328Aug 19 15:11:48 machine synapse[561]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules(id): 1329Aug 19 15:11:48 machine synapse[561]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules_enable(id): 1330Aug 19 15:11:48 machine synapse[561]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 month ago331Aug 19 15:11:48 machine synapse[561]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 month ago: it's 0332Aug 19 15:11:48 machine synapse[561]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 day ago333Aug 19 15:11:48 machine synapse[561]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 day ago: it's 0334Aug 19 15:11:48 machine synapse[561]: synapse.storage.util.id_generators: [main] Initialising stream generator for access_tokens(id): 3335Aug 19 15:11:48 machine synapse[561]: synapse.storage.util.id_generators: [main] Initialising stream generator for refresh_tokens(id): 1336Aug 19 15:11:48 machine synapse[561]: synapse.storage.util.id_generators: [main] Initialising stream generator for event_reports(id): 1337Aug 19 15:11:48 machine synapse[561]: synapse.storage.util.id_generators: [main] Initialising stream generator for room_reports(id): 1338Aug 19 15:11:48 machine synapse[561]: synapse.storage.util.id_generators: [main] Initialising stream generator for user_reports(id): 1339Aug 19 15:11:48 machine synapse[561]: synapse.storage.databases: [main] [database config 'master']: Starting 'state' database340Aug 19 15:11:48 machine synapse[561]: synapse.storage.databases: [main] [database config 'master']: prepared341Aug 19 15:11:48 machine synapse[561]: synapse.server: [main] Finished setting up.342Aug 19 15:11:48 machine synapse[561]: twisted: [main] Redirected stdout/stderr to logs343Aug 19 15:11:48 machine synapse[561]: synapse.app.homeserver: [main] Running344Aug 19 15:11:48 machine synapse[561]: synapse.app.homeserver: [main] Set file limit to: 524288345Aug 19 15:11:48 machine synapse[561]: synapse.push.pusher: [call_when_running] email enable notifs: False346Aug 19 15:11:48 machine synapse[561]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'profile'347Aug 19 15:11:48 machine synapse[561]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.device_list_update'348Aug 19 15:11:48 machine synapse[561]: synapse.util.caches.lrucache: [call_when_running] Expiring LRU caches after 1800 seconds349Aug 19 15:11:48 machine synapse[561]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.presence'350Aug 19 15:11:48 machine synapse[561]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.typing'351Aug 19 15:11:48 machine synapse[561]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'directory'352Aug 19 15:11:48 machine synapse[561]: synapse.handlers.deactivate_account: [user_parter_loop-0] Starting user parter353Aug 19 15:11:48 machine synapse[561]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.receipt'354Aug 19 15:11:48 machine synapse[561]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.signing_key_update'355Aug 19 15:11:48 machine synapse[561]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'org.matrix.signing_key_update'356Aug 19 15:11:48 machine synapse[561]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'client_keys'357Aug 19 15:11:48 machine synapse[561]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.direct_to_device'358Aug 19 15:11:48 machine synapse[561]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.health.HealthResource object at 0xffffee08a020> to path b'/health'359Aug 19 15:11:48 machine synapse[561]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.EncodingResourceWrapper object at 0xffffee2f30a0> to path b'/_matrix/client'360Aug 19 15:11:48 machine synapse[561]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffee2f8da0> to path b'/.well-known'361Aug 19 15:11:48 machine synapse[561]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.http.server.JsonResource object at 0xffffee2f3220> to path b'/_synapse/admin'362Aug 19 15:11:48 machine synapse[561]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.pick_idp.PickIdpResource object at 0xffffee2f9220> to path b'/_synapse/client/pick_idp'363Aug 19 15:11:48 machine synapse[561]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffedcaf760> to path b'/_synapse/client/pick_username'364Aug 19 15:11:48 machine synapse[561]: 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'365Aug 19 15:11:48 machine synapse[561]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.sso_register.SsoRegisterResource object at 0xffffee2f99a0> to path b'/_synapse/client/sso_register'366Aug 19 15:11:48 machine synapse[561]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.unsubscribe.UnsubscribeResource object at 0xffffee2f9b20> to path b'/_synapse/client/unsubscribe'367Aug 19 15:11:48 machine synapse[561]: 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'368Aug 19 15:11:48 machine synapse[561]: 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'369Aug 19 15:11:48 machine synapse[561]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee2fa120> to path b'/_matrix/media/r0'370Aug 19 15:11:48 machine synapse[561]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee2fa120> to path b'/_matrix/media/v3'371Aug 19 15:11:48 machine synapse[561]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee2fa120> to path b'/_matrix/media/v1'372Aug 19 15:11:48 machine synapse[561]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.federation.transport.server.TransportLayerServer object at 0xffffee2fad20> to path b'/_matrix/federation'373Aug 19 15:11:48 machine synapse[561]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.key.v2.KeyResource object at 0xffffee2fdea0> to path b'/_matrix/key'374Aug 19 15:11:48 machine synapse[561]: twisted: [call_when_running] SynapseSite starting on 8008375Aug 19 15:11:48 machine synapse[561]: synapse.app._base: [call_when_running] Synapse now listening on TCP port 8008376Aug 19 15:11:48 machine synapse[561]: 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).377Aug 19 15:11:48 machine synapse[561]: synapse.storage.background_updates: [background_updates-0] Starting background schema updates for database master378Aug 19 15:11:48 machine synapse[561]: synapse.handlers.deactivate_account: [user_parter_loop-0] User parter finished: stopping379Aug 19 15:11:48 machine synapse[561]: synapse.push.pusherpool: [start_pushers-0] Started pushers380Aug 19 15:11:48 machine wilq5k4l3zkskj1vfpl6p6ld63xg52f7-matrix-synapse-create-users[586]: Connection to ::1 8008 port [tcp/http-alt] succeeded!381Aug 19 15:11:49 machine synapse[561]: synapse.access.http.8008: [GET-0] ::1 - 8008 - {None} Processed request: 0.001sec/0.001sec ru=(0.002sec, 0.001sec) db=(0.000sec/0.000sec/0) 140B 200 "GET /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]382Aug 19 15:11:49 machine synapse[561]: synapse.http.server: [POST-1] <XForwardedForRequest at 0xffffe1652960 method='POST' uri='/_synapse/admin/v1/register' clientproto='HTTP/1.1' site='8008'> SynapseError: 400 - User ID already taken.383Aug 19 15:11:49 machine synapse[561]: synapse.access.http.8008: [POST-1] ::1 - 8008 - {None} Processed request: 0.250sec/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]384Aug 19 15:11:49 machine wilq5k4l3zkskj1vfpl6p6ld63xg52f7-matrix-synapse-create-users[587]: Sending registration request...385Aug 19 15:11:49 machine wilq5k4l3zkskj1vfpl6p6ld63xg52f7-matrix-synapse-create-users[587]: User already exists. Skipping.386Aug 19 15:11:49 machine synapse[561]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'populate_stats_process_rooms'387Aug 19 15:11:49 machine synapse[561]: 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)388Aug 19 15:11:50 machine synapse[561]: 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]389Aug 19 15:11:50 machine synapse[561]: synapse.http.server: [POST-3] <XForwardedForRequest at 0xffffe1652e60 method='POST' uri='/_synapse/admin/v1/register' clientproto='HTTP/1.1' site='8008'> SynapseError: 400 - User ID already taken.390Aug 19 15:11:50 machine synapse[561]: synapse.access.http.8008: [POST-3] ::1 - 8008 - {None} Processed request: 0.235sec/0.000sec ru=(0.229sec, 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]391Aug 19 15:11:50 machine wilq5k4l3zkskj1vfpl6p6ld63xg52f7-matrix-synapse-create-users[593]: Sending registration request...392Aug 19 15:11:50 machine wilq5k4l3zkskj1vfpl6p6ld63xg52f7-matrix-synapse-create-users[593]: User already exists. Skipping.393Aug 19 15:11:50 machine systemd[1]: Started Synapse Matrix homeserver.394+ systemctl --no-pager show matrix-synapse395+ nc -z -v ::1 8008396Connection to ::1 8008 port [tcp/http-alt] succeeded!397+ curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test'398