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