container-test-run-matrix-synapse
default.checks.aarch64-linux.matrix-synapse
· build #363
· raw
1additionally exposed symbols:2 machine,3 start_all, machines, driver, Machine, wait_for_signal4Starting machine567<<< NixOS Stage 2 >>>89booting system configuration /nix/store/z27ixavw5mc90zh6imdz8djqyln5s9rv-nixos-system-machine-test10running activation script...11setting up /etc...122: host0@if3: <BROADCAST,MULTICAST> mtu 1500 qdisc noop state DOWN mode DEFAULT group default qlen 100013 link/ether 0e:74:26:22:81:c2 brd ff:ff:ff:ff:ff:ff link-netnsid 014setting up age secrets...15starting systemd...16systemd 260.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)17Detected virtualization systemd-nspawn.18Detected architecture arm64.19Detected first boot.20Initializing machine ID from container UUID.21Applying preset policy.22Populated /etc with preset unit settings.23Queued start job for default target Multi-User System.24252627<<< Welcome to NixOS test (aarch64) - console >>>28+ systemd-run /bin/sh -c '/nix/store/vpnizxs18sv6agrsqplsvaml9y3bfx0k-coreutils-9.11/bin/sleep 999999999 && echo e08e7839-7b82-4940-9010-eea6e1fb296b'29Running as unit: run-p481-i89490473.service; invocation ID: b7b48db260f44452887a929d7aa34f0230To attach to container machine run on the same machine that runs the test:31 sudo nsenter --user --target $(\pgrep -f '^/bin/sh.*e08e7839-7b82-4940-9010-eea6e1fb296b') --mount --uts --ipc --net --pid --cgroup /bin/sh -c bash 3233To inject external network and continue test, run:34sudo /nix/store/1bhl5b9821ifsz1dbfm4wxbjbfywmsaq-python3-3.13.14/bin/python3.13 /nix/store/lf6lngb6dzima2m8nqbna4lc6bm1qw85-test-driver-0.0.1/lib/python3.13/site-packages/test_driver/inject_network.py e08e7839-7b82-4940-9010-eea6e1fb296b35+ 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+ nc -z -v ::1 800843Connection to ::1 8008 port [tcp/http-alt] succeeded!44machine: waiting for success: curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test'45+ curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test'46(finished: waiting for success: curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test', in 0.02 seconds)47+ systemctl restart matrix-synapse48+ journalctl -u matrix-synapse --no-pager49Aug 15 00:08:44 machine systemd[1]: Starting Synapse Matrix homeserver...50Aug 15 00:08:46 machine matrix-synapse-pre-start[476]: Generating signing key file /var/lib/matrix-synapse/homeserver.signing.key51Aug 15 00:08:49 machine synapse_homeserver[491]: This server is configured to use 'matrix.org' as its trusted key server via the52Aug 15 00:08:49 machine synapse_homeserver[491]: 'trusted_key_servers' config option. 'matrix.org' is a good choice for a key53Aug 15 00:08:49 machine synapse_homeserver[491]: server since it is long-lived, stable and trusted. However, some admins may54Aug 15 00:08:49 machine synapse_homeserver[491]: wish to use another server for this purpose.55Aug 15 00:08:49 machine synapse_homeserver[491]: To suppress this warning and continue using 'matrix.org', admins should set56Aug 15 00:08:49 machine synapse_homeserver[491]: 'suppress_key_server_warning' to 'true' in homeserver.yaml.57Aug 15 00:08:49 machine synapse_homeserver[491]: --------------------------------------------------------------------------------58Aug 15 00:08:49 machine synapse[491]: synapse.config.logger: [main] ***** STARTING SERVER *****59Aug 15 00:08:49 machine synapse[491]: synapse.config.logger: [main] Server /nix/store/wrbw2mlx5755960ihv9l77c8dhrrm3iv-matrix-synapse-1.158.0/bin/synapse_homeserver version 1.158.060Aug 15 00:08:49 machine synapse[491]: synapse.config.logger: [main] Copyright (c) 2023 New Vector, Inc61Aug 15 00:08:49 machine synapse[491]: synapse.config.logger: [main] Licensed under the AGPL 3.0 license. Website: https://github.com/element-hq/synapse62Aug 15 00:08:49 machine synapse[491]: synapse.config.logger: [main] Server hostname: clan.test63Aug 15 00:08:49 machine synapse[491]: synapse.config.logger: [main] Public Base URL: https://clan.test/64Aug 15 00:08:49 machine synapse[491]: synapse.config.logger: [main] Instance name: master65Aug 15 00:08:49 machine synapse[491]: synapse.config.logger: [main] Twisted reactor: EPollReactor66Aug 15 00:08:49 machine synapse[491]: synapse.app.homeserver: [main] Setting up server67Aug 15 00:08:49 machine synapse[491]: synapse.server: [main] Setting up.68Aug 15 00:08:49 machine synapse[491]: synapse.storage.databases: [main] [database config 'master']: Checking database server69Aug 15 00:08:49 machine synapse[491]: synapse.storage.databases: [main] [database config 'master']: Preparing for databases ['main', 'state']70Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] ['main', 'state']: Checking existing schema version71Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] ['main', 'state']: Initialising new database72Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v7373Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/01event_failed_pull_attempts.sql74Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/02add_pusher_enabled.sql75Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/02room_id_indexes_for_purging.sql76Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/03pusher_device_id.sql77Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/03users_approved_column.sql78Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/04partial_join_details.sql79Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/04pending_device_list_updates.sql80Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/05old_push_actions.sql.postgres81Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/06thread_notifications_thread_id_idx.sql82Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/08thread_receipts_non_null.sql.postgres83Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/09partial_joined_via_destination.sql84Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/09threads_table.sql85Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Running 73/10_update_sqlite_fts4_tokenizer.py:run_create86Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/10login_tokens.sql87Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/11event_search_room_id_n_distinct.sql.postgres88Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/12refactor_device_list_outbound_pokes.sql89Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/13add_device_lists_index.sql90Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/20_un_partial_stated_room_stream.sql91Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/21_un_partial_stated_room_stream_seq.sql.postgres92Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/22_rebuild_user_dir_stats.sql93Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/22_un_partial_stated_event_stream.sql94Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/23_fix_thread_index.sql95Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/23_un_partial_stated_room_stream_seq.sql.postgres96Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/24_events_jump_to_date_index.sql97Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/25drop_presence.sql98Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v7499Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 74/01_user_directory_stale_remote_users.sql100Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 74/02_set_device_id_for_pushers_bg_update.sql101Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 74/03_membership_tables_event_stream_ordering.sql.postgres102Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 74/03_room_membership_index.sql103Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 74/04_delete_e2e_backup_keys_for_deactivated_users.sql104Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Running 74/04_membership_tables_event_stream_ordering_triggers.py:run_create105Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 74/05_events_txn_id_device_id.sql106Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 74/90COMMENTS_destinations.sql.postgres107Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v75108Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v76109Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 76/01_add_profiles_full_user_id_column.sql110Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 76/02_add_user_filters_full_user_id_column.sql111Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 76/03_per_user_experimental_features.sql112Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 76/04_add_room_forgetter.sql113Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v77114Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/01_add_profiles_not_valid_check.sql.postgres115Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/02_add_user_filters_not_valid_check.sql.postgres116Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 77/03bg_populate_full_user_id_profiles.sql117Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 77/04bg_populate_full_user_id_user_filters.sql118Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 77/05thread_notifications_backfill.sql119Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_actions.sql.postgres120Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_actions_staging.sql.postgres121Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_summary.sql.postgres122Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 77/14bg_indices_event_stream_ordering.sql123Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v78124Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Running 78/03event_extremities_constraints.py:run_create125Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Running 78/04_add_full_user_id_index_user_filters.py:run_create126Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v79127Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 79/03_read_write_locks_triggers.sql.postgres128Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Running 79/04_mitigate_stream_ordering_update_race.py:run_create129Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 79/05_read_write_locks_triggers.sql.postgres130Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v80131Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 80/01_users_alter_locked.sql132Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/02_read_write_locks_unlogged.sql.postgres133Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 80/02_scheduled_tasks.sql134Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/03_read_write_locks_triggers.sql.postgres135Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/04_read_write_locks_deadlock.sql.postgres136Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v81137Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v82138Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 82/02_scheduled_tasks_index.sql139Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 82/04_add_indices_for_purging_rooms.sql140Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 82/05gaps.sql141Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v83142Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 83/01_drop_old_tables.sql143Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 83/05_cross_signing_key_update_grant.sql144Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 83/06_event_push_summary_room.sql145Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v84146Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 84/01_auth_links_stats.sql.postgres147Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 84/02_auth_links_index.sql148Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 84/03_auth_links_analyze.sql.postgres149Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 84/04_access_token_index.sql150Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v85151Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 85/01_add_suspended.sql152Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 85/02_add_instance_names.sql153Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 85/03_new_sequences.sql.postgres154Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 85/04_cleanup_device_federation_outbox.sql155Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 85/05_add_instance_names_converted_pos.sql156Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 85/06_add_room_reports.sql157Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v86158Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 86/01_authenticate_media.sql159Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 86/02_receipts_event_id_index.sql160Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v87161Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 87/01_sliding_sync_memberships.sql162Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 87/02_per_connection_state.sql163Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 87/03_current_state_index.sql164Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v88165Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 88/01_add_delayed_events.sql166Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 88/01_custom_profile_fields.sql167Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 88/02_fix_sliding_sync_membership_snapshots_forgotten_column.sql168Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 88/03_add_otk_ts_added_index.sql169Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 88/04_current_state_delta_index.sql170Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 88/05_drop_old_otks.sql.postgres171Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 88/05_sliding_sync_room_config_index.sql172Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 88/06_events_received_ts_index.sql173Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v89174Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 89/01_sliding_sync_membership_snapshot_index.sql175Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 89/01_state_groups_deletion.sql176Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v90177Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 90/01_add_column_participant_room_memberships_table.sql178Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 90/02_delete_unreferenced_state_groups.sql179Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 90/03_remove_old_deletion_bg_update.sql180Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v91181Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 91/01_media_hash.sql182Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v92183Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/01_remove_trigger.sql.postgres184Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/02_remove_populate_participant_bg_update.sql185Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/04_ss_membership_snapshot_idx.sql186Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/04_thread_subscriptions.sql187Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/04_thread_subscriptions_seq.sql.postgres188Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/05_fixup_max_depth_cap.sql189Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/05_thread_subscriptions_comments.sql.postgres190Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/06_device_federation_inbox_index.sql191Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/06_threads_last_sent_stream_ordering_comments.sql.postgres192Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/07_add_user_reports.sql193Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/07_event_txn_id_device_id_txn_id2.sql194Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/08_room_ban_redactions.sql195Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/08_thread_subscriptions_seq_fixup.sql.postgres196Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/09_thread_subscriptions_update.sql197Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/09_thread_subscriptions_update.sql.postgres198Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v93199Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 93/01_add_delayed_events.sql200Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 93/01_sticky_events.sql201Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 93/01_sticky_events_seq.sql.postgres202Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 93/02_sliding_sync_members.sql203Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 93/03_sss_pos_last_used.sql204Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Running 93/04_make_delayed_event_content_text.py:run_create205Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v94206Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/01_redactions_recheck.sql207Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/02_redactions_recheck_bg_update.sql208Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/03_device_lists_room_timestamp.sql209Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/03_quarantined_media_tracking.sql210Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 94/03_quarantined_media_tracking_seq.sql.postgres211Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/03_state_dag_fwd_extrems.sql212Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/04_device_lists_changes_max_pruned.sql213Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/05_rerun_flag_existing_quarantined_media.sql214Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/06_sliding_sync_connections_last_used_ts_index.sql215Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/07_sliding_sync_lazy_members_position_index.sql216Aug 15 00:08:49 machine synapse[491]: synapse.storage.prepare_database: [main] Schema now up to date217Aug 15 00:08:49 machine synapse[491]: synapse.storage.databases: [main] [database config 'master']: Starting 'main' database218Aug 15 00:08:49 machine synapse[491]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules(id): 1219Aug 15 00:08:49 machine synapse[491]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules_enable(id): 1220Aug 15 00:08:49 machine synapse[491]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 month ago221Aug 15 00:08:49 machine synapse[491]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 month ago: it's 0222Aug 15 00:08:49 machine synapse[491]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 day ago223Aug 15 00:08:49 machine synapse[491]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 day ago: it's 0224Aug 15 00:08:49 machine synapse[491]: synapse.storage.util.id_generators: [main] Initialising stream generator for access_tokens(id): 1225Aug 15 00:08:49 machine synapse[491]: synapse.storage.util.id_generators: [main] Initialising stream generator for refresh_tokens(id): 1226Aug 15 00:08:49 machine synapse[491]: synapse.storage.util.id_generators: [main] Initialising stream generator for event_reports(id): 1227Aug 15 00:08:49 machine synapse[491]: synapse.storage.util.id_generators: [main] Initialising stream generator for room_reports(id): 1228Aug 15 00:08:49 machine synapse[491]: synapse.storage.util.id_generators: [main] Initialising stream generator for user_reports(id): 1229Aug 15 00:08:49 machine synapse[491]: synapse.storage.databases: [main] [database config 'master']: Starting 'state' database230Aug 15 00:08:49 machine synapse[491]: synapse.storage.databases: [main] [database config 'master']: prepared231Aug 15 00:08:49 machine synapse[491]: synapse.server: [main] Finished setting up.232Aug 15 00:08:49 machine synapse[491]: twisted: [main] Redirected stdout/stderr to logs233Aug 15 00:08:49 machine synapse[491]: synapse.app.homeserver: [main] Running234Aug 15 00:08:49 machine synapse[491]: synapse.app.homeserver: [main] Set file limit to: 524288235Aug 15 00:08:50 machine synapse[491]: synapse.push.pusher: [call_when_running] email enable notifs: False236Aug 15 00:08:50 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'profile'237Aug 15 00:08:50 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.device_list_update'238Aug 15 00:08:50 machine synapse[491]: synapse.util.caches.lrucache: [call_when_running] Expiring LRU caches after 1800 seconds239Aug 15 00:08:50 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.presence'240Aug 15 00:08:50 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.typing'241Aug 15 00:08:50 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'directory'242Aug 15 00:08:50 machine synapse[491]: synapse.handlers.deactivate_account: [user_parter_loop-0] Starting user parter243Aug 15 00:08:50 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.receipt'244Aug 15 00:08:50 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.signing_key_update'245Aug 15 00:08:50 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'org.matrix.signing_key_update'246Aug 15 00:08:50 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'client_keys'247Aug 15 00:08:50 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.direct_to_device'248Aug 15 00:08:50 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.health.HealthResource object at 0xffffee039ea0> to path b'/health'249Aug 15 00:08:50 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.EncodingResourceWrapper object at 0xffffee2d2f20> to path b'/_matrix/client'250Aug 15 00:08:50 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffee2d8c20> to path b'/.well-known'251Aug 15 00:08:50 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.http.server.JsonResource object at 0xffffee2d30a0> to path b'/_synapse/admin'252Aug 15 00:08:50 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.pick_idp.PickIdpResource object at 0xffffee2d90a0> to path b'/_synapse/client/pick_idp'253Aug 15 00:08:50 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffedcaf8a0> to path b'/_synapse/client/pick_username'254Aug 15 00:08:50 machine synapse[491]: 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'255Aug 15 00:08:50 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.sso_register.SsoRegisterResource object at 0xffffee2d9820> to path b'/_synapse/client/sso_register'256Aug 15 00:08:50 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.unsubscribe.UnsubscribeResource object at 0xffffee2d99a0> to path b'/_synapse/client/unsubscribe'257Aug 15 00:08:50 machine synapse[491]: 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'258Aug 15 00:08:50 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching FilePath('/nix/store/wrbw2mlx5755960ihv9l77c8dhrrm3iv-matrix-synapse-1.158.0/lib/python3.13/site-packages/synapse/static') to path b'/_matrix/static'259Aug 15 00:08:50 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee2d9fa0> to path b'/_matrix/media/r0'260Aug 15 00:08:50 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee2d9fa0> to path b'/_matrix/media/v3'261Aug 15 00:08:50 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee2d9fa0> to path b'/_matrix/media/v1'262Aug 15 00:08:50 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.federation.transport.server.TransportLayerServer object at 0xffffee2daba0> to path b'/_matrix/federation'263Aug 15 00:08:50 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.key.v2.KeyResource object at 0xffffee2ddd20> to path b'/_matrix/key'264Aug 15 00:08:50 machine synapse[491]: twisted: [call_when_running] SynapseSite starting on 8008265Aug 15 00:08:50 machine synapse[491]: synapse.app._base: [call_when_running] Synapse now listening on TCP port 8008266Aug 15 00:08:50 machine synapse[491]: synapse.app._base: [call_when_running] garbage collector: Freezing all allocated objects in the hopes that (almost) everything currently allocated are things that will be used by the homeserver for the rest of time. Doing so means less work each GC (hopefully).267Aug 15 00:08:50 machine synapse[491]: synapse.storage.background_updates: [background_updates-0] Starting background schema updates for database master268Aug 15 00:08:50 machine synapse[491]: synapse.handlers.deactivate_account: [user_parter_loop-0] User parter finished: stopping269Aug 15 00:08:50 machine synapse[491]: synapse.push.pusherpool: [start_pushers-0] Started pushers270Aug 15 00:08:50 machine bnzgj7n6gry2s8ppq7j2akw0vsk6df4m-matrix-synapse-create-users[519]: Connection to ::1 8008 port [tcp/http-alt] succeeded!271Aug 15 00:08:50 machine synapse[491]: synapse.access.http.8008: [GET-0] ::1 - 8008 - {None} Processed request: 0.001sec/0.001sec ru=(0.001sec, 0.000sec) db=(0.000sec/0.000sec/0) 140B 200 "GET /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]272Aug 15 00:08:51 machine synapse[491]: synapse.handlers.auth: [POST-1] Logging in user @admin:clan.test on device LLIPCBEHEC273Aug 15 00:08:51 machine synapse[491]: synapse.access.http.8008: [POST-1] ::1 - 8008 - {None} Processed request: 0.293sec/0.001sec ru=(0.255sec, 0.004sec) db=(0.004sec/0.026sec/12) 138B 200 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]274Aug 15 00:08:51 machine bnzgj7n6gry2s8ppq7j2akw0vsk6df4m-matrix-synapse-create-users[520]: Sending registration request...275Aug 15 00:08:51 machine bnzgj7n6gry2s8ppq7j2akw0vsk6df4m-matrix-synapse-create-users[520]: Success!276Aug 15 00:08:51 machine synapse[491]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'threads_backfill'277Aug 15 00:08:51 machine synapse[491]: synapse.storage.background_updates: [background_updates-0] Running background update 'threads_backfill'. Processed 0 items in 5ms. (total_rate=None/ms, current_rate=None/ms, total_updated=0, batch_size=100)278Aug 15 00:08:51 machine synapse[491]: synapse.access.http.8008: [GET-2] ::1 - 8008 - {None} Processed request: 0.000sec/0.001sec ru=(0.000sec, 0.000sec) db=(0.000sec/0.000sec/0) 140B 200 "GET /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]279Aug 15 00:08:51 machine synapse[491]: synapse.handlers.auth: [POST-3] Logging in user @someuser:clan.test on device FFZFVRODCV280Aug 15 00:08:51 machine synapse[491]: synapse.access.http.8008: [POST-3] ::1 - 8008 - {None} Processed request: 0.300sec/0.001sec ru=(0.266sec, 0.001sec) db=(0.004sec/0.018sec/12) 145B 200 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]281Aug 15 00:08:51 machine bnzgj7n6gry2s8ppq7j2akw0vsk6df4m-matrix-synapse-create-users[527]: Sending registration request...282Aug 15 00:08:51 machine bnzgj7n6gry2s8ppq7j2akw0vsk6df4m-matrix-synapse-create-users[527]: Success!283Aug 15 00:08:51 machine systemd[1]: Started Synapse Matrix homeserver.284Aug 15 00:08:52 machine synapse[491]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'device_lists_changes_in_room_by_room_index'285Aug 15 00:08:52 machine synapse[491]: synapse.storage.background_updates: [background_updates-0] Adding index device_lists_changes_in_room_by_room_idx to device_lists_changes_in_room286Aug 15 00:08:52 machine synapse[491]: synapse.storage.background_updates: [background_updates-0] Running background update 'device_lists_changes_in_room_by_room_index'. Processed 1 items in 7ms. (total_rate=0.14285714285714285/ms, current_rate=0.14285714285714285/ms, total_updated=1, batch_size=100)287Aug 15 00:08:52 machine synapse[491]: synapse.access.http.8008: [GET-4] ::1 - 8008 - {None} Processed request: 0.001sec/-0.000sec ru=(0.000sec, 0.000sec) db=(0.000sec/0.000sec/0) 10145B 200 "GET /_matrix/static/ HTTP/1.1" "curl/8.21.0" [0 dbevts]288Aug 15 00:08:52 machine synapse[491]: twisted: [sentinel] Received SIGTERM, shutting down.289Aug 15 00:08:52 machine systemd[1]: Stopping Synapse Matrix homeserver...290Aug 15 00:08:52 machine synapse[491]: synapse.storage.databases.main.lock: [LockStore._on_shutdown-0] Dropping held locks due to shutdown291Aug 15 00:08:52 machine synapse[491]: synapse.storage.databases.main.lock: [LockStore._on_shutdown-0] Dropped locks due to shutdown292Aug 15 00:08:52 machine synapse[491]: synapse.handlers.presence: [PresenceHandler._on_shutdown-0] Performing _on_shutdown. Persisting 0 unpersisted changes293Aug 15 00:08:52 machine synapse[491]: synapse.handlers.presence: [PresenceHandler._on_shutdown-0] Finished _on_shutdown294Aug 15 00:08:52 machine synapse[491]: synapse.app._base: [log_shutdown] Shutting down...295Aug 15 00:08:52 machine synapse[491]: twisted: [sentinel] (TCP Port 8008 Closed)296Aug 15 00:08:52 machine synapse[491]: twisted: [sentinel] Main loop terminated.297Aug 15 00:08:52 machine systemd[1]: matrix-synapse.service: Deactivated successfully.298Aug 15 00:08:52 machine systemd[1]: Stopped Synapse Matrix homeserver.299Aug 15 00:08:52 machine systemd[1]: matrix-synapse.service: Consumed 7.522s CPU time over 8.662s wall clock time.300Aug 15 00:08:52 machine systemd[1]: Starting Synapse Matrix homeserver...301Aug 15 00:08:57 machine synapse_homeserver[559]: This server is configured to use 'matrix.org' as its trusted key server via the302Aug 15 00:08:57 machine synapse_homeserver[559]: 'trusted_key_servers' config option. 'matrix.org' is a good choice for a key303Aug 15 00:08:57 machine synapse_homeserver[559]: server since it is long-lived, stable and trusted. However, some admins may304Aug 15 00:08:57 machine synapse_homeserver[559]: wish to use another server for this purpose.305Aug 15 00:08:57 machine synapse_homeserver[559]: To suppress this warning and continue using 'matrix.org', admins should set306Aug 15 00:08:57 machine synapse_homeserver[559]: 'suppress_key_server_warning' to 'true' in homeserver.yaml.307Aug 15 00:08:57 machine synapse_homeserver[559]: --------------------------------------------------------------------------------308Aug 15 00:08:57 machine synapse[559]: synapse.config.logger: [main] ***** STARTING SERVER *****309Aug 15 00:08:57 machine synapse[559]: synapse.config.logger: [main] Server /nix/store/wrbw2mlx5755960ihv9l77c8dhrrm3iv-matrix-synapse-1.158.0/bin/synapse_homeserver version 1.158.0310Aug 15 00:08:57 machine synapse[559]: synapse.config.logger: [main] Copyright (c) 2023 New Vector, Inc311Aug 15 00:08:57 machine synapse[559]: synapse.config.logger: [main] Licensed under the AGPL 3.0 license. Website: https://github.com/element-hq/synapse312Aug 15 00:08:57 machine synapse[559]: synapse.config.logger: [main] Server hostname: clan.test313Aug 15 00:08:57 machine synapse[559]: synapse.config.logger: [main] Public Base URL: https://clan.test/314Aug 15 00:08:57 machine synapse[559]: synapse.config.logger: [main] Instance name: master315Aug 15 00:08:57 machine synapse[559]: synapse.config.logger: [main] Twisted reactor: EPollReactor316Aug 15 00:08:57 machine synapse[559]: synapse.app.homeserver: [main] Setting up server317Aug 15 00:08:57 machine synapse[559]: synapse.server: [main] Setting up.318Aug 15 00:08:57 machine synapse[559]: synapse.storage.databases: [main] [database config 'master']: Checking database server319Aug 15 00:08:57 machine synapse[559]: synapse.storage.databases: [main] [database config 'master']: Preparing for databases ['main', 'state']320Aug 15 00:08:57 machine synapse[559]: synapse.storage.prepare_database: [main] ['main', 'state']: Checking existing schema version321Aug 15 00:08:57 machine synapse[559]: synapse.storage.prepare_database: [main] ['main', 'state']: Existing schema is 94 (+10 deltas)322Aug 15 00:08:57 machine synapse[559]: synapse.storage.databases.main: [main] Checking database for consistency with configuration...323Aug 15 00:08:57 machine synapse[559]: synapse.storage.prepare_database: [main] Applying schema deltas for v94324Aug 15 00:08:57 machine synapse[559]: synapse.storage.prepare_database: [main] Schema now up to date325Aug 15 00:08:57 machine synapse[559]: synapse.storage.databases: [main] [database config 'master']: Starting 'main' database326Aug 15 00:08:57 machine synapse[559]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules(id): 1327Aug 15 00:08:57 machine synapse[559]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules_enable(id): 1328Aug 15 00:08:57 machine synapse[559]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 month ago329Aug 15 00:08:57 machine synapse[559]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 month ago: it's 0330Aug 15 00:08:57 machine synapse[559]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 day ago331Aug 15 00:08:57 machine synapse[559]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 day ago: it's 0332Aug 15 00:08:57 machine synapse[559]: synapse.storage.util.id_generators: [main] Initialising stream generator for access_tokens(id): 3333Aug 15 00:08:57 machine synapse[559]: synapse.storage.util.id_generators: [main] Initialising stream generator for refresh_tokens(id): 1334Aug 15 00:08:57 machine synapse[559]: synapse.storage.util.id_generators: [main] Initialising stream generator for event_reports(id): 1335Aug 15 00:08:57 machine synapse[559]: synapse.storage.util.id_generators: [main] Initialising stream generator for room_reports(id): 1336Aug 15 00:08:57 machine synapse[559]: synapse.storage.util.id_generators: [main] Initialising stream generator for user_reports(id): 1337Aug 15 00:08:57 machine synapse[559]: synapse.storage.databases: [main] [database config 'master']: Starting 'state' database338Aug 15 00:08:58 machine synapse[559]: synapse.storage.databases: [main] [database config 'master']: prepared339Aug 15 00:08:58 machine synapse[559]: synapse.server: [main] Finished setting up.340Aug 15 00:08:58 machine synapse[559]: twisted: [main] Redirected stdout/stderr to logs341Aug 15 00:08:58 machine synapse[559]: synapse.app.homeserver: [main] Running342Aug 15 00:08:58 machine synapse[559]: synapse.app.homeserver: [main] Set file limit to: 524288343Aug 15 00:08:58 machine synapse[559]: synapse.push.pusher: [call_when_running] email enable notifs: False344Aug 15 00:08:58 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'profile'345Aug 15 00:08:58 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.device_list_update'346Aug 15 00:08:58 machine synapse[559]: synapse.util.caches.lrucache: [call_when_running] Expiring LRU caches after 1800 seconds347Aug 15 00:08:58 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.presence'348Aug 15 00:08:58 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.typing'349Aug 15 00:08:58 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'directory'350Aug 15 00:08:58 machine synapse[559]: synapse.handlers.deactivate_account: [user_parter_loop-0] Starting user parter351Aug 15 00:08:58 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.receipt'352Aug 15 00:08:58 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.signing_key_update'353Aug 15 00:08:58 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'org.matrix.signing_key_update'354Aug 15 00:08:58 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'client_keys'355Aug 15 00:08:58 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.direct_to_device'356Aug 15 00:08:58 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.health.HealthResource object at 0xffffee08a020> to path b'/health'357Aug 15 00:08:58 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.EncodingResourceWrapper object at 0xffffee2e30a0> to path b'/_matrix/client'358Aug 15 00:08:58 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffee2e8da0> to path b'/.well-known'359Aug 15 00:08:58 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.http.server.JsonResource object at 0xffffee2e3220> to path b'/_synapse/admin'360Aug 15 00:08:58 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.pick_idp.PickIdpResource object at 0xffffee2e9220> to path b'/_synapse/client/pick_idp'361Aug 15 00:08:58 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffedcaf760> to path b'/_synapse/client/pick_username'362Aug 15 00:08:58 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.new_user_consent.NewUserConsentResource object at 0xffffee2e9820> to path b'/_synapse/client/new_user_consent'363Aug 15 00:08:58 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.sso_register.SsoRegisterResource object at 0xffffee2e99a0> to path b'/_synapse/client/sso_register'364Aug 15 00:08:58 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.unsubscribe.UnsubscribeResource object at 0xffffee2e9b20> to path b'/_synapse/client/unsubscribe'365Aug 15 00:08:58 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.media_upload_limit_exceeded.MediaUploadLimitExceededResource object at 0xffffee2e9ca0> to path b'/_synapse/client/media_upload_limit_exceeded'366Aug 15 00:08:58 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching FilePath('/nix/store/wrbw2mlx5755960ihv9l77c8dhrrm3iv-matrix-synapse-1.158.0/lib/python3.13/site-packages/synapse/static') to path b'/_matrix/static'367Aug 15 00:08:58 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee2ea120> to path b'/_matrix/media/r0'368Aug 15 00:08:58 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee2ea120> to path b'/_matrix/media/v3'369Aug 15 00:08:58 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee2ea120> to path b'/_matrix/media/v1'370Aug 15 00:08:58 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.federation.transport.server.TransportLayerServer object at 0xffffee2ead20> to path b'/_matrix/federation'371Aug 15 00:08:58 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.key.v2.KeyResource object at 0xffffee2edea0> to path b'/_matrix/key'372Aug 15 00:08:58 machine synapse[559]: twisted: [call_when_running] SynapseSite starting on 8008373Aug 15 00:08:58 machine synapse[559]: synapse.app._base: [call_when_running] Synapse now listening on TCP port 8008374Aug 15 00:08:58 machine synapse[559]: synapse.app._base: [call_when_running] garbage collector: Freezing all allocated objects in the hopes that (almost) everything currently allocated are things that will be used by the homeserver for the rest of time. Doing so means less work each GC (hopefully).375Aug 15 00:08:58 machine synapse[559]: synapse.storage.background_updates: [background_updates-0] Starting background schema updates for database master376Aug 15 00:08:58 machine synapse[559]: synapse.handlers.deactivate_account: [user_parter_loop-0] User parter finished: stopping377Aug 15 00:08:58 machine synapse[559]: synapse.push.pusherpool: [start_pushers-0] Started pushers378Aug 15 00:08:58 machine bnzgj7n6gry2s8ppq7j2akw0vsk6df4m-matrix-synapse-create-users[584]: Connection to ::1 8008 port [tcp/http-alt] succeeded!379Aug 15 00:08:58 machine synapse[559]: 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]380Aug 15 00:08:58 machine synapse[559]: synapse.http.server: [POST-1] <XForwardedForRequest at 0xffffedcaa760 method='POST' uri='/_synapse/admin/v1/register' clientproto='HTTP/1.1' site='8008'> SynapseError: 400 - User ID already taken.381Aug 15 00:08:58 machine synapse[559]: synapse.access.http.8008: [POST-1] ::1 - 8008 - {None} Processed request: 0.249sec/0.000sec ru=(0.246sec, 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]382Aug 15 00:08:58 machine bnzgj7n6gry2s8ppq7j2akw0vsk6df4m-matrix-synapse-create-users[585]: Sending registration request...383Aug 15 00:08:58 machine bnzgj7n6gry2s8ppq7j2akw0vsk6df4m-matrix-synapse-create-users[585]: User already exists. Skipping.384Aug 15 00:08:59 machine synapse[559]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'populate_stats_process_rooms'385Aug 15 00:08:59 machine synapse[559]: synapse.storage.background_updates: [background_updates-0] Running background update 'populate_stats_process_rooms'. Processed 1 items in 4ms. (total_rate=0.25/ms, current_rate=0.25/ms, total_updated=1, batch_size=100)386Aug 15 00:08:59 machine synapse[559]: 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]387Aug 15 00:08:59 machine synapse[559]: synapse.http.server: [POST-3] <XForwardedForRequest at 0xffffedca63e0 method='POST' uri='/_synapse/admin/v1/register' clientproto='HTTP/1.1' site='8008'> SynapseError: 400 - User ID already taken.388Aug 15 00:08:59 machine synapse[559]: synapse.access.http.8008: [POST-3] ::1 - 8008 - {None} Processed request: 0.258sec/0.000sec ru=(0.251sec, 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]389Aug 15 00:08:59 machine bnzgj7n6gry2s8ppq7j2akw0vsk6df4m-matrix-synapse-create-users[591]: Sending registration request...390Aug 15 00:08:59 machine bnzgj7n6gry2s8ppq7j2akw0vsk6df4m-matrix-synapse-create-users[591]: User already exists. Skipping.391Aug 15 00:08:59 machine systemd[1]: Started Synapse Matrix homeserver.392+ systemctl --no-pager show matrix-synapse393+ nc -z -v ::1 8008394Connection to ::1 8008 port [tcp/http-alt] succeeded!395+ curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test'396