container-test-run-matrix-synapse
checks.aarch64-linux.matrix-synapse
· build #517
· raw
1additionally exposed symbols:2 machine,3 start_all, machines, driver, Machine, wait_for_signal4Starting machine567<<< NixOS Stage 2 >>>89booting system configuration /nix/store/nwh5as59682ank1pmxay1k54lc88ym81-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 ff5ce3ed-0682-41ee-b169-8fe8b1cea1a6'29Running as unit: run-p423-i110395096.service; invocation ID: 32248fc955654a8b937cb0e30a30bc8130To attach to container machine run on the same machine that runs the test:31 sudo nsenter --user --target $(\pgrep -f '^/bin/sh.*ff5ce3ed-0682-41ee-b169-8fe8b1cea1a6') --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/f4nvkcws5x7zcpbn7zsscrdviq2jxrpi-test-driver-0.0.1/lib/python3.13/site-packages/test_driver/inject_network.py ff5ce3ed-0682-41ee-b169-8fe8b1cea1a635+ systemctl --no-pager show matrix-synapse36+ systemctl list-jobs --full37+ 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+ systemctl --no-pager show matrix-synapse45+ systemctl --no-pager show matrix-synapse46+ systemctl --no-pager show matrix-synapse47+ systemctl --no-pager show matrix-synapse48+ nc -z -v ::1 800849Connection to ::1 8008 port [tcp/http-alt] succeeded!50machine: waiting for success: curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test'51+ curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test'52(finished: waiting for success: curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test', in 0.02 seconds)53+ systemctl restart matrix-synapse54+ journalctl -u matrix-synapse --no-pager55Aug 28 15:09:12 machine systemd[1]: Starting Synapse Matrix homeserver...56Aug 28 15:09:16 machine matrix-synapse-pre-start[483]: Generating signing key file /var/lib/matrix-synapse/homeserver.signing.key57Aug 28 15:09:19 machine synapse_homeserver[497]: This server is configured to use 'matrix.org' as its trusted key server via the58Aug 28 15:09:19 machine synapse_homeserver[497]: 'trusted_key_servers' config option. 'matrix.org' is a good choice for a key59Aug 28 15:09:19 machine synapse_homeserver[497]: server since it is long-lived, stable and trusted. However, some admins may60Aug 28 15:09:19 machine synapse_homeserver[497]: wish to use another server for this purpose.61Aug 28 15:09:19 machine synapse_homeserver[497]: To suppress this warning and continue using 'matrix.org', admins should set62Aug 28 15:09:19 machine synapse_homeserver[497]: 'suppress_key_server_warning' to 'true' in homeserver.yaml.63Aug 28 15:09:19 machine synapse_homeserver[497]: --------------------------------------------------------------------------------64Aug 28 15:09:20 machine synapse[497]: synapse.config.logger: [main] ***** STARTING SERVER *****65Aug 28 15:09:20 machine synapse[497]: synapse.config.logger: [main] Server /nix/store/1s8lqnc1k176xsjap48vxjq430ckjqnm-matrix-synapse-1.159.0/bin/synapse_homeserver version 1.159.066Aug 28 15:09:20 machine synapse[497]: synapse.config.logger: [main] Copyright (c) 2023 New Vector, Inc67Aug 28 15:09:20 machine synapse[497]: synapse.config.logger: [main] Licensed under the AGPL 3.0 license. Website: https://github.com/element-hq/synapse68Aug 28 15:09:20 machine synapse[497]: synapse.config.logger: [main] Server hostname: clan.test69Aug 28 15:09:20 machine synapse[497]: synapse.config.logger: [main] Public Base URL: https://clan.test/70Aug 28 15:09:20 machine synapse[497]: synapse.config.logger: [main] Instance name: master71Aug 28 15:09:20 machine synapse[497]: synapse.config.logger: [main] Twisted reactor: EPollReactor72Aug 28 15:09:20 machine synapse[497]: synapse.app.homeserver: [main] Setting up server73Aug 28 15:09:20 machine synapse[497]: synapse.server: [main] Setting up.74Aug 28 15:09:20 machine synapse[497]: synapse.storage.databases: [main] [database config 'master']: Checking database server75Aug 28 15:09:20 machine synapse[497]: synapse.storage.databases: [main] [database config 'master']: Preparing for databases ['main', 'state']76Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] ['main', 'state']: Checking existing schema version77Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] ['main', 'state']: Initialising new database78Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema deltas for v7379Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 73/01event_failed_pull_attempts.sql80Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 73/02add_pusher_enabled.sql81Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 73/02room_id_indexes_for_purging.sql82Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 73/03pusher_device_id.sql83Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 73/03users_approved_column.sql84Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 73/04partial_join_details.sql85Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 73/04pending_device_list_updates.sql86Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/05old_push_actions.sql.postgres87Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 73/06thread_notifications_thread_id_idx.sql88Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/08thread_receipts_non_null.sql.postgres89Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 73/09partial_joined_via_destination.sql90Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 73/09threads_table.sql91Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Running 73/10_update_sqlite_fts4_tokenizer.py:run_create92Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 73/10login_tokens.sql93Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/11event_search_room_id_n_distinct.sql.postgres94Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 73/12refactor_device_list_outbound_pokes.sql95Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 73/13add_device_lists_index.sql96Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 73/20_un_partial_stated_room_stream.sql97Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/21_un_partial_stated_room_stream_seq.sql.postgres98Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 73/22_rebuild_user_dir_stats.sql99Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 73/22_un_partial_stated_event_stream.sql100Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 73/23_fix_thread_index.sql101Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/23_un_partial_stated_room_stream_seq.sql.postgres102Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 73/24_events_jump_to_date_index.sql103Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 73/25drop_presence.sql104Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema deltas for v74105Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 74/01_user_directory_stale_remote_users.sql106Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 74/02_set_device_id_for_pushers_bg_update.sql107Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying engine-specific schema 74/03_membership_tables_event_stream_ordering.sql.postgres108Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 74/03_room_membership_index.sql109Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 74/04_delete_e2e_backup_keys_for_deactivated_users.sql110Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Running 74/04_membership_tables_event_stream_ordering_triggers.py:run_create111Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 74/05_events_txn_id_device_id.sql112Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying engine-specific schema 74/90COMMENTS_destinations.sql.postgres113Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema deltas for v75114Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema deltas for v76115Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 76/01_add_profiles_full_user_id_column.sql116Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 76/02_add_user_filters_full_user_id_column.sql117Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 76/03_per_user_experimental_features.sql118Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 76/04_add_room_forgetter.sql119Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema deltas for v77120Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/01_add_profiles_not_valid_check.sql.postgres121Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/02_add_user_filters_not_valid_check.sql.postgres122Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 77/03bg_populate_full_user_id_profiles.sql123Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 77/04bg_populate_full_user_id_user_filters.sql124Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 77/05thread_notifications_backfill.sql125Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_actions.sql.postgres126Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_actions_staging.sql.postgres127Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_summary.sql.postgres128Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 77/14bg_indices_event_stream_ordering.sql129Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema deltas for v78130Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Running 78/03event_extremities_constraints.py:run_create131Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Running 78/04_add_full_user_id_index_user_filters.py:run_create132Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema deltas for v79133Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying engine-specific schema 79/03_read_write_locks_triggers.sql.postgres134Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Running 79/04_mitigate_stream_ordering_update_race.py:run_create135Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying engine-specific schema 79/05_read_write_locks_triggers.sql.postgres136Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema deltas for v80137Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 80/01_users_alter_locked.sql138Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/02_read_write_locks_unlogged.sql.postgres139Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 80/02_scheduled_tasks.sql140Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/03_read_write_locks_triggers.sql.postgres141Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/04_read_write_locks_deadlock.sql.postgres142Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema deltas for v81143Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema deltas for v82144Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 82/02_scheduled_tasks_index.sql145Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 82/04_add_indices_for_purging_rooms.sql146Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 82/05gaps.sql147Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema deltas for v83148Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 83/01_drop_old_tables.sql149Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 83/05_cross_signing_key_update_grant.sql150Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 83/06_event_push_summary_room.sql151Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema deltas for v84152Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying engine-specific schema 84/01_auth_links_stats.sql.postgres153Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 84/02_auth_links_index.sql154Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying engine-specific schema 84/03_auth_links_analyze.sql.postgres155Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 84/04_access_token_index.sql156Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema deltas for v85157Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 85/01_add_suspended.sql158Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 85/02_add_instance_names.sql159Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying engine-specific schema 85/03_new_sequences.sql.postgres160Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 85/04_cleanup_device_federation_outbox.sql161Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 85/05_add_instance_names_converted_pos.sql162Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 85/06_add_room_reports.sql163Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema deltas for v86164Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 86/01_authenticate_media.sql165Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 86/02_receipts_event_id_index.sql166Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema deltas for v87167Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 87/01_sliding_sync_memberships.sql168Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 87/02_per_connection_state.sql169Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 87/03_current_state_index.sql170Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema deltas for v88171Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 88/01_add_delayed_events.sql172Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 88/01_custom_profile_fields.sql173Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 88/02_fix_sliding_sync_membership_snapshots_forgotten_column.sql174Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 88/03_add_otk_ts_added_index.sql175Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 88/04_current_state_delta_index.sql176Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying engine-specific schema 88/05_drop_old_otks.sql.postgres177Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 88/05_sliding_sync_room_config_index.sql178Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 88/06_events_received_ts_index.sql179Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema deltas for v89180Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 89/01_sliding_sync_membership_snapshot_index.sql181Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 89/01_state_groups_deletion.sql182Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema deltas for v90183Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 90/01_add_column_participant_room_memberships_table.sql184Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 90/02_delete_unreferenced_state_groups.sql185Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 90/03_remove_old_deletion_bg_update.sql186Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema deltas for v91187Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 91/01_media_hash.sql188Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema deltas for v92189Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/01_remove_trigger.sql.postgres190Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 92/02_remove_populate_participant_bg_update.sql191Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 92/04_ss_membership_snapshot_idx.sql192Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 92/04_thread_subscriptions.sql193Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/04_thread_subscriptions_seq.sql.postgres194Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 92/05_fixup_max_depth_cap.sql195Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/05_thread_subscriptions_comments.sql.postgres196Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 92/06_device_federation_inbox_index.sql197Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/06_threads_last_sent_stream_ordering_comments.sql.postgres198Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 92/07_add_user_reports.sql199Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 92/07_event_txn_id_device_id_txn_id2.sql200Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 92/08_room_ban_redactions.sql201Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/08_thread_subscriptions_seq_fixup.sql.postgres202Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 92/09_thread_subscriptions_update.sql203Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/09_thread_subscriptions_update.sql.postgres204Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema deltas for v93205Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 93/01_add_delayed_events.sql206Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 93/01_sticky_events.sql207Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying engine-specific schema 93/01_sticky_events_seq.sql.postgres208Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 93/02_sliding_sync_members.sql209Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 93/03_sss_pos_last_used.sql210Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Running 93/04_make_delayed_event_content_text.py:run_create211Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema deltas for v94212Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 94/01_redactions_recheck.sql213Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 94/02_redactions_recheck_bg_update.sql214Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 94/03_device_lists_room_timestamp.sql215Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 94/03_quarantined_media_tracking.sql216Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying engine-specific schema 94/03_quarantined_media_tracking_seq.sql.postgres217Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 94/03_state_dag_fwd_extrems.sql218Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 94/04_device_lists_changes_max_pruned.sql219Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 94/05_rerun_flag_existing_quarantined_media.sql220Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 94/06_sliding_sync_connections_last_used_ts_index.sql221Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 94/07_profile_updates.sql222Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying engine-specific schema 94/07_profile_updates_seq.sql.postgres223Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Applying schema 94/07_sliding_sync_lazy_members_position_index.sql224Aug 28 15:09:20 machine synapse[497]: synapse.storage.prepare_database: [main] Schema now up to date225Aug 28 15:09:20 machine synapse[497]: synapse.storage.databases: [main] [database config 'master']: Starting 'main' database226Aug 28 15:09:20 machine synapse[497]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules(id): 1227Aug 28 15:09:20 machine synapse[497]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules_enable(id): 1228Aug 28 15:09:20 machine synapse[497]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 month ago229Aug 28 15:09:20 machine synapse[497]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 month ago: it's 0230Aug 28 15:09:20 machine synapse[497]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 day ago231Aug 28 15:09:20 machine synapse[497]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 day ago: it's 0232Aug 28 15:09:20 machine synapse[497]: synapse.storage.util.id_generators: [main] Initialising stream generator for access_tokens(id): 1233Aug 28 15:09:20 machine synapse[497]: synapse.storage.util.id_generators: [main] Initialising stream generator for refresh_tokens(id): 1234Aug 28 15:09:20 machine synapse[497]: synapse.storage.util.id_generators: [main] Initialising stream generator for event_reports(id): 1235Aug 28 15:09:20 machine synapse[497]: synapse.storage.util.id_generators: [main] Initialising stream generator for room_reports(id): 1236Aug 28 15:09:20 machine synapse[497]: synapse.storage.util.id_generators: [main] Initialising stream generator for user_reports(id): 1237Aug 28 15:09:20 machine synapse[497]: synapse.storage.databases: [main] [database config 'master']: Starting 'state' database238Aug 28 15:09:20 machine synapse[497]: synapse.storage.databases: [main] [database config 'master']: prepared239Aug 28 15:09:20 machine synapse[497]: synapse.server: [main] Finished setting up.240Aug 28 15:09:20 machine synapse[497]: twisted: [main] Redirected stdout/stderr to logs241Aug 28 15:09:20 machine synapse[497]: synapse.app.homeserver: [main] Running242Aug 28 15:09:20 machine synapse[497]: synapse.app.homeserver: [main] Set file limit to: 524288243Aug 28 15:09:20 machine synapse[497]: synapse.push.pusher: [call_when_running] email enable notifs: False244Aug 28 15:09:20 machine synapse[497]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'profile'245Aug 28 15:09:20 machine synapse[497]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.device_list_update'246Aug 28 15:09:20 machine synapse[497]: synapse.util.caches.lrucache: [call_when_running] Expiring LRU caches after 1800 seconds247Aug 28 15:09:20 machine synapse[497]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.presence'248Aug 28 15:09:20 machine synapse[497]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.typing'249Aug 28 15:09:20 machine synapse[497]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'directory'250Aug 28 15:09:20 machine synapse[497]: synapse.handlers.deactivate_account: [user_parter_loop-0] Starting user parter251Aug 28 15:09:20 machine synapse[497]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.receipt'252Aug 28 15:09:20 machine synapse[497]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.signing_key_update'253Aug 28 15:09:20 machine synapse[497]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'org.matrix.signing_key_update'254Aug 28 15:09:20 machine synapse[497]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'client_keys'255Aug 28 15:09:20 machine synapse[497]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.direct_to_device'256Aug 28 15:09:20 machine synapse[497]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.health.HealthResource object at 0xffffee079120> to path b'/health'257Aug 28 15:09:20 machine synapse[497]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.EncodingResourceWrapper object at 0xffffee133820> to path b'/_matrix/client'258Aug 28 15:09:20 machine synapse[497]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffee139520> to path b'/.well-known'259Aug 28 15:09:20 machine synapse[497]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.http.server.JsonResource object at 0xffffee1339a0> to path b'/_synapse/admin'260Aug 28 15:09:20 machine synapse[497]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.pick_idp.PickIdpResource object at 0xffffee1399a0> to path b'/_synapse/client/pick_idp'261Aug 28 15:09:20 machine synapse[497]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffe1831f60> to path b'/_synapse/client/pick_username'262Aug 28 15:09:20 machine synapse[497]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.new_user_consent.NewUserConsentResource object at 0xffffee139fa0> to path b'/_synapse/client/new_user_consent'263Aug 28 15:09:20 machine synapse[497]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.sso_register.SsoRegisterResource object at 0xffffee13a120> to path b'/_synapse/client/sso_register'264Aug 28 15:09:20 machine synapse[497]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.unsubscribe.UnsubscribeResource object at 0xffffee13a2a0> to path b'/_synapse/client/unsubscribe'265Aug 28 15:09:20 machine synapse[497]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.media_upload_limit_exceeded.MediaUploadLimitExceededResource object at 0xffffee13a420> to path b'/_synapse/client/media_upload_limit_exceeded'266Aug 28 15:09:20 machine synapse[497]: synapse.util.httpresourcetree: [call_when_running] Attaching FilePath('/nix/store/1s8lqnc1k176xsjap48vxjq430ckjqnm-matrix-synapse-1.159.0/lib/python3.13/site-packages/synapse/static') to path b'/_matrix/static'267Aug 28 15:09:20 machine synapse[497]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee13a8a0> to path b'/_matrix/media/r0'268Aug 28 15:09:20 machine synapse[497]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee13a8a0> to path b'/_matrix/media/v3'269Aug 28 15:09:20 machine synapse[497]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee13a8a0> to path b'/_matrix/media/v1'270Aug 28 15:09:20 machine synapse[497]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.federation.transport.server.TransportLayerServer object at 0xffffee13b4a0> to path b'/_matrix/federation'271Aug 28 15:09:20 machine synapse[497]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.key.v2.KeyResource object at 0xffffee13e620> to path b'/_matrix/key'272Aug 28 15:09:20 machine synapse[497]: twisted: [call_when_running] SynapseSite starting on 8008273Aug 28 15:09:20 machine synapse[497]: synapse.app._base: [call_when_running] Synapse now listening on TCP port 8008274Aug 28 15:09:20 machine synapse[497]: 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).275Aug 28 15:09:20 machine synapse[497]: synapse.storage.background_updates: [background_updates-0] Starting background schema updates for database master276Aug 28 15:09:20 machine synapse[497]: synapse.handlers.deactivate_account: [user_parter_loop-0] User parter finished: stopping277Aug 28 15:09:20 machine synapse[497]: synapse.push.pusherpool: [start_pushers-0] Started pushers278Aug 28 15:09:20 machine wilq5k4l3zkskj1vfpl6p6ld63xg52f7-matrix-synapse-create-users[526]: Connection to ::1 8008 port [tcp/http-alt] succeeded!279Aug 28 15:09:21 machine synapse[497]: 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]280Aug 28 15:09:21 machine synapse[497]: synapse.handlers.auth: [POST-1] Logging in user @admin:clan.test on device LTLKQPGYSK281Aug 28 15:09:21 machine synapse[497]: synapse.access.http.8008: [POST-1] ::1 - 8008 - {None} Processed request: 0.299sec/0.001sec ru=(0.258sec, 0.000sec) db=(0.005sec/0.027sec/12) 138B 200 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]282Aug 28 15:09:21 machine wilq5k4l3zkskj1vfpl6p6ld63xg52f7-matrix-synapse-create-users[527]: Sending registration request...283Aug 28 15:09:21 machine wilq5k4l3zkskj1vfpl6p6ld63xg52f7-matrix-synapse-create-users[527]: Success!284Aug 28 15:09:21 machine synapse[497]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'threads_backfill'285Aug 28 15:09:21 machine synapse[497]: synapse.storage.background_updates: [background_updates-0] Running background update 'threads_backfill'. Processed 0 items in 6ms. (total_rate=None/ms, current_rate=None/ms, total_updated=0, batch_size=100)286Aug 28 15:09:22 machine synapse[497]: synapse.access.http.8008: [GET-2] ::1 - 8008 - {None} Processed request: 0.000sec/0.001sec ru=(0.000sec, 0.000sec) db=(0.000sec/0.000sec/0) 140B 200 "GET /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]287Aug 28 15:09:22 machine synapse[497]: synapse.handlers.auth: [POST-3] Logging in user @someuser:clan.test on device KNUJKSTKKD288Aug 28 15:09:22 machine synapse[497]: synapse.access.http.8008: [POST-3] ::1 - 8008 - {None} Processed request: 0.285sec/0.000sec ru=(0.258sec, 0.000sec) db=(0.004sec/0.017sec/12) 145B 200 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]289Aug 28 15:09:22 machine wilq5k4l3zkskj1vfpl6p6ld63xg52f7-matrix-synapse-create-users[534]: Sending registration request...290Aug 28 15:09:22 machine wilq5k4l3zkskj1vfpl6p6ld63xg52f7-matrix-synapse-create-users[534]: Success!291Aug 28 15:09:22 machine systemd[1]: Started Synapse Matrix homeserver.292Aug 28 15:09:22 machine synapse[497]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'device_lists_changes_in_room_by_room_index'293Aug 28 15:09:22 machine synapse[497]: synapse.storage.background_updates: [background_updates-0] Adding index device_lists_changes_in_room_by_room_idx to device_lists_changes_in_room294Aug 28 15:09:22 machine synapse[497]: synapse.storage.background_updates: [background_updates-0] Running background update 'device_lists_changes_in_room_by_room_index'. Processed 1 items in 9ms. (total_rate=0.1111111111111111/ms, current_rate=0.11111111111111112/ms, total_updated=1, batch_size=100)295Aug 28 15:09:23 machine synapse[497]: 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]296Aug 28 15:09:23 machine synapse[497]: twisted: [sentinel] Received SIGTERM, shutting down.297Aug 28 15:09:23 machine systemd[1]: Stopping Synapse Matrix homeserver...298Aug 28 15:09:23 machine synapse[497]: synapse.storage.databases.main.lock: [LockStore._on_shutdown-0] Dropping held locks due to shutdown299Aug 28 15:09:23 machine synapse[497]: synapse.storage.databases.main.lock: [LockStore._on_shutdown-0] Dropped locks due to shutdown300Aug 28 15:09:23 machine synapse[497]: synapse.handlers.presence: [PresenceHandler._on_shutdown-0] Performing _on_shutdown. Persisting 0 unpersisted changes301Aug 28 15:09:23 machine synapse[497]: synapse.handlers.presence: [PresenceHandler._on_shutdown-0] Finished _on_shutdown302Aug 28 15:09:23 machine synapse[497]: synapse.app._base: [log_shutdown] Shutting down...303Aug 28 15:09:23 machine synapse[497]: twisted: [sentinel] (TCP Port 8008 Closed)304Aug 28 15:09:23 machine synapse[497]: twisted: [sentinel] Main loop terminated.305Aug 28 15:09:23 machine systemd[1]: matrix-synapse.service: Deactivated successfully.306Aug 28 15:09:23 machine systemd[1]: Stopped Synapse Matrix homeserver.307Aug 28 15:09:23 machine systemd[1]: matrix-synapse.service: Consumed 8.956s CPU time over 11.006s wall clock time.308Aug 28 15:09:23 machine systemd[1]: Starting Synapse Matrix homeserver...309Aug 28 15:09:29 machine synapse_homeserver[566]: This server is configured to use 'matrix.org' as its trusted key server via the310Aug 28 15:09:29 machine synapse_homeserver[566]: 'trusted_key_servers' config option. 'matrix.org' is a good choice for a key311Aug 28 15:09:29 machine synapse_homeserver[566]: server since it is long-lived, stable and trusted. However, some admins may312Aug 28 15:09:29 machine synapse_homeserver[566]: wish to use another server for this purpose.313Aug 28 15:09:29 machine synapse_homeserver[566]: To suppress this warning and continue using 'matrix.org', admins should set314Aug 28 15:09:29 machine synapse_homeserver[566]: 'suppress_key_server_warning' to 'true' in homeserver.yaml.315Aug 28 15:09:29 machine synapse_homeserver[566]: --------------------------------------------------------------------------------316Aug 28 15:09:29 machine synapse[566]: synapse.config.logger: [main] ***** STARTING SERVER *****317Aug 28 15:09:29 machine synapse[566]: synapse.config.logger: [main] Server /nix/store/1s8lqnc1k176xsjap48vxjq430ckjqnm-matrix-synapse-1.159.0/bin/synapse_homeserver version 1.159.0318Aug 28 15:09:29 machine synapse[566]: synapse.config.logger: [main] Copyright (c) 2023 New Vector, Inc319Aug 28 15:09:29 machine synapse[566]: synapse.config.logger: [main] Licensed under the AGPL 3.0 license. Website: https://github.com/element-hq/synapse320Aug 28 15:09:29 machine synapse[566]: synapse.config.logger: [main] Server hostname: clan.test321Aug 28 15:09:29 machine synapse[566]: synapse.config.logger: [main] Public Base URL: https://clan.test/322Aug 28 15:09:29 machine synapse[566]: synapse.config.logger: [main] Instance name: master323Aug 28 15:09:29 machine synapse[566]: synapse.config.logger: [main] Twisted reactor: EPollReactor324Aug 28 15:09:29 machine synapse[566]: synapse.app.homeserver: [main] Setting up server325Aug 28 15:09:29 machine synapse[566]: synapse.server: [main] Setting up.326Aug 28 15:09:29 machine synapse[566]: synapse.storage.databases: [main] [database config 'master']: Checking database server327Aug 28 15:09:29 machine synapse[566]: synapse.storage.databases: [main] [database config 'master']: Preparing for databases ['main', 'state']328Aug 28 15:09:29 machine synapse[566]: synapse.storage.prepare_database: [main] ['main', 'state']: Checking existing schema version329Aug 28 15:09:29 machine synapse[566]: synapse.storage.prepare_database: [main] ['main', 'state']: Existing schema is 94 (+12 deltas)330Aug 28 15:09:29 machine synapse[566]: synapse.storage.databases.main: [main] Checking database for consistency with configuration...331Aug 28 15:09:29 machine synapse[566]: synapse.storage.prepare_database: [main] Applying schema deltas for v94332Aug 28 15:09:29 machine synapse[566]: synapse.storage.prepare_database: [main] Schema now up to date333Aug 28 15:09:29 machine synapse[566]: synapse.storage.databases: [main] [database config 'master']: Starting 'main' database334Aug 28 15:09:29 machine synapse[566]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules(id): 1335Aug 28 15:09:29 machine synapse[566]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules_enable(id): 1336Aug 28 15:09:29 machine synapse[566]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 month ago337Aug 28 15:09:29 machine synapse[566]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 month ago: it's 0338Aug 28 15:09:29 machine synapse[566]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 day ago339Aug 28 15:09:29 machine synapse[566]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 day ago: it's 0340Aug 28 15:09:29 machine synapse[566]: synapse.storage.util.id_generators: [main] Initialising stream generator for access_tokens(id): 3341Aug 28 15:09:29 machine synapse[566]: synapse.storage.util.id_generators: [main] Initialising stream generator for refresh_tokens(id): 1342Aug 28 15:09:29 machine synapse[566]: synapse.storage.util.id_generators: [main] Initialising stream generator for event_reports(id): 1343Aug 28 15:09:29 machine synapse[566]: synapse.storage.util.id_generators: [main] Initialising stream generator for room_reports(id): 1344Aug 28 15:09:29 machine synapse[566]: synapse.storage.util.id_generators: [main] Initialising stream generator for user_reports(id): 1345Aug 28 15:09:29 machine synapse[566]: synapse.storage.databases: [main] [database config 'master']: Starting 'state' database346Aug 28 15:09:29 machine synapse[566]: synapse.storage.databases: [main] [database config 'master']: prepared347Aug 28 15:09:29 machine synapse[566]: synapse.server: [main] Finished setting up.348Aug 28 15:09:29 machine synapse[566]: twisted: [main] Redirected stdout/stderr to logs349Aug 28 15:09:29 machine synapse[566]: synapse.app.homeserver: [main] Running350Aug 28 15:09:29 machine synapse[566]: synapse.app.homeserver: [main] Set file limit to: 524288351Aug 28 15:09:29 machine synapse[566]: synapse.push.pusher: [call_when_running] email enable notifs: False352Aug 28 15:09:29 machine synapse[566]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'profile'353Aug 28 15:09:29 machine synapse[566]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.device_list_update'354Aug 28 15:09:29 machine synapse[566]: synapse.util.caches.lrucache: [call_when_running] Expiring LRU caches after 1800 seconds355Aug 28 15:09:29 machine synapse[566]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.presence'356Aug 28 15:09:29 machine synapse[566]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.typing'357Aug 28 15:09:29 machine synapse[566]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'directory'358Aug 28 15:09:29 machine synapse[566]: synapse.handlers.deactivate_account: [user_parter_loop-0] Starting user parter359Aug 28 15:09:29 machine synapse[566]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.receipt'360Aug 28 15:09:29 machine synapse[566]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.signing_key_update'361Aug 28 15:09:29 machine synapse[566]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'org.matrix.signing_key_update'362Aug 28 15:09:29 machine synapse[566]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'client_keys'363Aug 28 15:09:29 machine synapse[566]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.direct_to_device'364Aug 28 15:09:29 machine synapse[566]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.health.HealthResource object at 0xffffee0292a0> to path b'/health'365Aug 28 15:09:29 machine synapse[566]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.EncodingResourceWrapper object at 0xffffee2e39a0> to path b'/_matrix/client'366Aug 28 15:09:29 machine synapse[566]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffee2e96a0> to path b'/.well-known'367Aug 28 15:09:29 machine synapse[566]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.http.server.JsonResource object at 0xffffee2e3b20> to path b'/_synapse/admin'368Aug 28 15:09:29 machine synapse[566]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.pick_idp.PickIdpResource object at 0xffffee2e9b20> to path b'/_synapse/client/pick_idp'369Aug 28 15:09:29 machine synapse[566]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffe1a51e20> to path b'/_synapse/client/pick_username'370Aug 28 15:09:29 machine synapse[566]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.new_user_consent.NewUserConsentResource object at 0xffffee2ea120> to path b'/_synapse/client/new_user_consent'371Aug 28 15:09:29 machine synapse[566]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.sso_register.SsoRegisterResource object at 0xffffee2ea2a0> to path b'/_synapse/client/sso_register'372Aug 28 15:09:29 machine synapse[566]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.unsubscribe.UnsubscribeResource object at 0xffffee2ea420> to path b'/_synapse/client/unsubscribe'373Aug 28 15:09:29 machine synapse[566]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.media_upload_limit_exceeded.MediaUploadLimitExceededResource object at 0xffffee2ea5a0> to path b'/_synapse/client/media_upload_limit_exceeded'374Aug 28 15:09:29 machine synapse[566]: synapse.util.httpresourcetree: [call_when_running] Attaching FilePath('/nix/store/1s8lqnc1k176xsjap48vxjq430ckjqnm-matrix-synapse-1.159.0/lib/python3.13/site-packages/synapse/static') to path b'/_matrix/static'375Aug 28 15:09:29 machine synapse[566]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee2eaa20> to path b'/_matrix/media/r0'376Aug 28 15:09:29 machine synapse[566]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee2eaa20> to path b'/_matrix/media/v3'377Aug 28 15:09:29 machine synapse[566]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee2eaa20> to path b'/_matrix/media/v1'378Aug 28 15:09:29 machine synapse[566]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.federation.transport.server.TransportLayerServer object at 0xffffee2eb620> to path b'/_matrix/federation'379Aug 28 15:09:29 machine synapse[566]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.key.v2.KeyResource object at 0xffffee2ee7a0> to path b'/_matrix/key'380Aug 28 15:09:29 machine synapse[566]: twisted: [call_when_running] SynapseSite starting on 8008381Aug 28 15:09:29 machine synapse[566]: synapse.app._base: [call_when_running] Synapse now listening on TCP port 8008382Aug 28 15:09:29 machine synapse[566]: 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).383Aug 28 15:09:29 machine synapse[566]: synapse.storage.background_updates: [background_updates-0] Starting background schema updates for database master384Aug 28 15:09:29 machine synapse[566]: synapse.handlers.deactivate_account: [user_parter_loop-0] User parter finished: stopping385Aug 28 15:09:29 machine synapse[566]: synapse.push.pusherpool: [start_pushers-0] Started pushers386Aug 28 15:09:30 machine wilq5k4l3zkskj1vfpl6p6ld63xg52f7-matrix-synapse-create-users[591]: Connection to ::1 8008 port [tcp/http-alt] succeeded!387Aug 28 15:09:30 machine synapse[566]: synapse.access.http.8008: [GET-0] ::1 - 8008 - {None} Processed request: 0.001sec/0.001sec ru=(0.002sec, 0.000sec) db=(0.000sec/0.000sec/0) 140B 200 "GET /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]388Aug 28 15:09:30 machine synapse[566]: synapse.http.server: [POST-1] <XForwardedForRequest at 0xffffe1a543a0 method='POST' uri='/_synapse/admin/v1/register' clientproto='HTTP/1.1' site='8008'> SynapseError: 400 - User ID already taken.389Aug 28 15:09:30 machine synapse[566]: synapse.access.http.8008: [POST-1] ::1 - 8008 - {None} Processed request: 0.247sec/0.000sec ru=(0.245sec, 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 28 15:09:30 machine wilq5k4l3zkskj1vfpl6p6ld63xg52f7-matrix-synapse-create-users[592]: Sending registration request...391Aug 28 15:09:30 machine wilq5k4l3zkskj1vfpl6p6ld63xg52f7-matrix-synapse-create-users[592]: User already exists. Skipping.392Aug 28 15:09:30 machine synapse[566]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'populate_stats_process_rooms'393Aug 28 15:09:30 machine synapse[566]: 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)394Aug 28 15:09:31 machine synapse[566]: 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]395Aug 28 15:09:31 machine synapse[566]: synapse.http.server: [POST-3] <XForwardedForRequest at 0xffffedb93960 method='POST' uri='/_synapse/admin/v1/register' clientproto='HTTP/1.1' site='8008'> SynapseError: 400 - User ID already taken.396Aug 28 15:09:31 machine synapse[566]: 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]397Aug 28 15:09:31 machine wilq5k4l3zkskj1vfpl6p6ld63xg52f7-matrix-synapse-create-users[598]: Sending registration request...398Aug 28 15:09:31 machine wilq5k4l3zkskj1vfpl6p6ld63xg52f7-matrix-synapse-create-users[598]: User already exists. Skipping.399Aug 28 15:09:31 machine systemd[1]: Started Synapse Matrix homeserver.400+ systemctl --no-pager show matrix-synapse401+ nc -z -v ::1 8008402Connection to ::1 8008 port [tcp/http-alt] succeeded!403+ curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test'404