container-test-run-matrix-synapse
checks.aarch64-linux.matrix-synapse
· build #682
· raw
1additionally exposed symbols:2 machine,3 start_all, machines, driver, Machine, wait_for_signal4Starting machine567<<< NixOS Stage 2 >>>89booting system configuration /nix/store/d5cfzdn9ivd60fm8dm8zdlfai2f566n1-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/wjm08iz4sdw5qgmx58dimv8ch7gbyyaj-coreutils-9.11/bin/sleep 999999999 && echo 7147a6ab-2391-4450-8f63-29cd468a7b45'29Running as unit: run-p420-i13586047.service; invocation ID: fe383534eef04b5bae933226b18195f330To attach to container machine run on the same machine that runs the test:31 sudo nsenter --user --target $(\pgrep -f '^/bin/sh.*7147a6ab-2391-4450-8f63-29cd468a7b45') --mount --uts --ipc --net --pid --cgroup /bin/sh -c bash 3233To inject external network and continue test, run:34sudo /nix/store/akbq19lqha5w5h9hcchn309znqm94pq0-python3-3.13.15/bin/python3.13 /nix/store/qrxpb4s36qq6d4f5n5c5d78f1y3lvyhy-test-driver-0.0.1/lib/python3.13/site-packages/test_driver/inject_network.py 7147a6ab-2391-4450-8f63-29cd468a7b4535+ systemctl --no-pager show matrix-synapse36+ systemctl list-jobs --full37+ systemctl --no-pager show matrix-synapse38+ systemctl list-jobs --full39+ systemctl --no-pager show matrix-synapse40+ systemctl --no-pager show matrix-synapse41+ systemctl --no-pager show matrix-synapse42+ systemctl --no-pager show matrix-synapse43+ systemctl --no-pager show matrix-synapse44+ systemctl --no-pager show matrix-synapse45+ systemctl --no-pager show matrix-synapse46+ systemctl --no-pager show matrix-synapse47+ systemctl --no-pager show matrix-synapse48+ nc -z -v ::1 800849Connection to ::1 8008 port [tcp/http-alt] succeeded!50machine: waiting for success: curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test'51+ curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test'52(finished: waiting for success: curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test', in 0.02 seconds)53+ systemctl restart matrix-synapse54+ journalctl -u matrix-synapse --no-pager55Sep 17 00:16:38 machine systemd[1]: Starting Synapse Matrix homeserver...56Sep 17 00:16:40 machine matrix-synapse-pre-start[485]: Generating signing key file /var/lib/matrix-synapse/homeserver.signing.key57Sep 17 00:16:43 machine synapse_homeserver[498]: This server is configured to use 'matrix.org' as its trusted key server via the58Sep 17 00:16:43 machine synapse_homeserver[498]: 'trusted_key_servers' config option. 'matrix.org' is a good choice for a key59Sep 17 00:16:43 machine synapse_homeserver[498]: server since it is long-lived, stable and trusted. However, some admins may60Sep 17 00:16:43 machine synapse_homeserver[498]: wish to use another server for this purpose.61Sep 17 00:16:43 machine synapse_homeserver[498]: To suppress this warning and continue using 'matrix.org', admins should set62Sep 17 00:16:43 machine synapse_homeserver[498]: 'suppress_key_server_warning' to 'true' in homeserver.yaml.63Sep 17 00:16:43 machine synapse_homeserver[498]: --------------------------------------------------------------------------------64Sep 17 00:16:43 machine synapse[498]: synapse.config.logger: [main] ***** STARTING SERVER *****65Sep 17 00:16:43 machine synapse[498]: synapse.config.logger: [main] Server /nix/store/dc3p08cmvzgsnfpwr26r4f1z2skd4j7s-matrix-synapse-1.160.0/bin/synapse_homeserver version 1.160.066Sep 17 00:16:43 machine synapse[498]: synapse.config.logger: [main] Copyright (c) 2023 New Vector, Inc67Sep 17 00:16:43 machine synapse[498]: synapse.config.logger: [main] Licensed under the AGPL 3.0 license. Website: https://github.com/element-hq/synapse68Sep 17 00:16:43 machine synapse[498]: synapse.config.logger: [main] Server hostname: clan.test69Sep 17 00:16:43 machine synapse[498]: synapse.config.logger: [main] Public Base URL: https://clan.test/70Sep 17 00:16:43 machine synapse[498]: synapse.config.logger: [main] Instance name: master71Sep 17 00:16:43 machine synapse[498]: synapse.config.logger: [main] Twisted reactor: EPollReactor72Sep 17 00:16:43 machine synapse[498]: synapse.app.homeserver: [main] Setting up server73Sep 17 00:16:43 machine synapse[498]: synapse.server: [main] Setting up.74Sep 17 00:16:43 machine synapse[498]: synapse.storage.databases: [main] [database config 'master']: Checking database server75Sep 17 00:16:43 machine synapse[498]: synapse.storage.databases: [main] [database config 'master']: Preparing for databases ['main', 'state']76Sep 17 00:16:43 machine synapse[498]: synapse.storage.prepare_database: [main] ['main', 'state']: Checking existing schema version77Sep 17 00:16:43 machine synapse[498]: synapse.storage.prepare_database: [main] ['main', 'state']: Initialising new database78Sep 17 00:16:43 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema deltas for v7379Sep 17 00:16:43 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 73/01event_failed_pull_attempts.sql80Sep 17 00:16:43 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 73/02add_pusher_enabled.sql81Sep 17 00:16:43 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 73/02room_id_indexes_for_purging.sql82Sep 17 00:16:43 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 73/03pusher_device_id.sql83Sep 17 00:16:43 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 73/03users_approved_column.sql84Sep 17 00:16:43 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 73/04partial_join_details.sql85Sep 17 00:16:43 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 73/04pending_device_list_updates.sql86Sep 17 00:16:43 machine synapse[498]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/05old_push_actions.sql.postgres87Sep 17 00:16:43 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 73/06thread_notifications_thread_id_idx.sql88Sep 17 00:16:43 machine synapse[498]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/08thread_receipts_non_null.sql.postgres89Sep 17 00:16:43 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 73/09partial_joined_via_destination.sql90Sep 17 00:16:43 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 73/09threads_table.sql91Sep 17 00:16:43 machine synapse[498]: synapse.storage.prepare_database: [main] Running 73/10_update_sqlite_fts4_tokenizer.py:run_create92Sep 17 00:16:43 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 73/10login_tokens.sql93Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/11event_search_room_id_n_distinct.sql.postgres94Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 73/12refactor_device_list_outbound_pokes.sql95Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 73/13add_device_lists_index.sql96Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 73/20_un_partial_stated_room_stream.sql97Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/21_un_partial_stated_room_stream_seq.sql.postgres98Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 73/22_rebuild_user_dir_stats.sql99Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 73/22_un_partial_stated_event_stream.sql100Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 73/23_fix_thread_index.sql101Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/23_un_partial_stated_room_stream_seq.sql.postgres102Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 73/24_events_jump_to_date_index.sql103Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 73/25drop_presence.sql104Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema deltas for v74105Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 74/01_user_directory_stale_remote_users.sql106Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 74/02_set_device_id_for_pushers_bg_update.sql107Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying engine-specific schema 74/03_membership_tables_event_stream_ordering.sql.postgres108Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 74/03_room_membership_index.sql109Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 74/04_delete_e2e_backup_keys_for_deactivated_users.sql110Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Running 74/04_membership_tables_event_stream_ordering_triggers.py:run_create111Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 74/05_events_txn_id_device_id.sql112Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying engine-specific schema 74/90COMMENTS_destinations.sql.postgres113Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema deltas for v75114Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema deltas for v76115Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 76/01_add_profiles_full_user_id_column.sql116Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 76/02_add_user_filters_full_user_id_column.sql117Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 76/03_per_user_experimental_features.sql118Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 76/04_add_room_forgetter.sql119Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema deltas for v77120Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/01_add_profiles_not_valid_check.sql.postgres121Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/02_add_user_filters_not_valid_check.sql.postgres122Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 77/03bg_populate_full_user_id_profiles.sql123Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 77/04bg_populate_full_user_id_user_filters.sql124Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 77/05thread_notifications_backfill.sql125Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_actions.sql.postgres126Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_actions_staging.sql.postgres127Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_summary.sql.postgres128Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 77/14bg_indices_event_stream_ordering.sql129Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema deltas for v78130Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Running 78/03event_extremities_constraints.py:run_create131Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Running 78/04_add_full_user_id_index_user_filters.py:run_create132Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema deltas for v79133Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying engine-specific schema 79/03_read_write_locks_triggers.sql.postgres134Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Running 79/04_mitigate_stream_ordering_update_race.py:run_create135Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying engine-specific schema 79/05_read_write_locks_triggers.sql.postgres136Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema deltas for v80137Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 80/01_users_alter_locked.sql138Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/02_read_write_locks_unlogged.sql.postgres139Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 80/02_scheduled_tasks.sql140Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/03_read_write_locks_triggers.sql.postgres141Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/04_read_write_locks_deadlock.sql.postgres142Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema deltas for v81143Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema deltas for v82144Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 82/02_scheduled_tasks_index.sql145Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 82/04_add_indices_for_purging_rooms.sql146Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 82/05gaps.sql147Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema deltas for v83148Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 83/01_drop_old_tables.sql149Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 83/05_cross_signing_key_update_grant.sql150Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 83/06_event_push_summary_room.sql151Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema deltas for v84152Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying engine-specific schema 84/01_auth_links_stats.sql.postgres153Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 84/02_auth_links_index.sql154Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying engine-specific schema 84/03_auth_links_analyze.sql.postgres155Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 84/04_access_token_index.sql156Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema deltas for v85157Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 85/01_add_suspended.sql158Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 85/02_add_instance_names.sql159Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying engine-specific schema 85/03_new_sequences.sql.postgres160Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 85/04_cleanup_device_federation_outbox.sql161Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 85/05_add_instance_names_converted_pos.sql162Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 85/06_add_room_reports.sql163Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema deltas for v86164Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 86/01_authenticate_media.sql165Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 86/02_receipts_event_id_index.sql166Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema deltas for v87167Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 87/01_sliding_sync_memberships.sql168Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 87/02_per_connection_state.sql169Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 87/03_current_state_index.sql170Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema deltas for v88171Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 88/01_add_delayed_events.sql172Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 88/01_custom_profile_fields.sql173Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 88/02_fix_sliding_sync_membership_snapshots_forgotten_column.sql174Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 88/03_add_otk_ts_added_index.sql175Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 88/04_current_state_delta_index.sql176Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying engine-specific schema 88/05_drop_old_otks.sql.postgres177Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 88/05_sliding_sync_room_config_index.sql178Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 88/06_events_received_ts_index.sql179Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema deltas for v89180Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 89/01_sliding_sync_membership_snapshot_index.sql181Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 89/01_state_groups_deletion.sql182Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema deltas for v90183Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 90/01_add_column_participant_room_memberships_table.sql184Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 90/02_delete_unreferenced_state_groups.sql185Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 90/03_remove_old_deletion_bg_update.sql186Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema deltas for v91187Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 91/01_media_hash.sql188Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema deltas for v92189Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/01_remove_trigger.sql.postgres190Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 92/02_remove_populate_participant_bg_update.sql191Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 92/04_ss_membership_snapshot_idx.sql192Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 92/04_thread_subscriptions.sql193Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/04_thread_subscriptions_seq.sql.postgres194Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 92/05_fixup_max_depth_cap.sql195Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/05_thread_subscriptions_comments.sql.postgres196Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 92/06_device_federation_inbox_index.sql197Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/06_threads_last_sent_stream_ordering_comments.sql.postgres198Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 92/07_add_user_reports.sql199Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 92/07_event_txn_id_device_id_txn_id2.sql200Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 92/08_room_ban_redactions.sql201Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/08_thread_subscriptions_seq_fixup.sql.postgres202Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 92/09_thread_subscriptions_update.sql203Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/09_thread_subscriptions_update.sql.postgres204Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema deltas for v93205Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 93/01_add_delayed_events.sql206Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 93/01_sticky_events.sql207Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying engine-specific schema 93/01_sticky_events_seq.sql.postgres208Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 93/02_sliding_sync_members.sql209Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 93/03_sss_pos_last_used.sql210Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Running 93/04_make_delayed_event_content_text.py:run_create211Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema deltas for v94212Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 94/01_redactions_recheck.sql213Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 94/02_redactions_recheck_bg_update.sql214Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 94/03_device_lists_room_timestamp.sql215Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 94/03_quarantined_media_tracking.sql216Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying engine-specific schema 94/03_quarantined_media_tracking_seq.sql.postgres217Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 94/03_state_dag_fwd_extrems.sql218Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 94/04_device_lists_changes_max_pruned.sql219Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 94/05_rerun_flag_existing_quarantined_media.sql220Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 94/06_sliding_sync_connections_last_used_ts_index.sql221Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 94/07_profile_updates.sql222Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying engine-specific schema 94/07_profile_updates_seq.sql.postgres223Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 94/07_sliding_sync_lazy_members_position_index.sql224Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Applying schema 94/08_device_lists_changes_in_room_unconverted_idx.sql225Sep 17 00:16:44 machine synapse[498]: synapse.storage.prepare_database: [main] Schema now up to date226Sep 17 00:16:44 machine synapse[498]: synapse.storage.databases: [main] [database config 'master']: Starting 'main' database227Sep 17 00:16:44 machine synapse[498]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules(id): 1228Sep 17 00:16:44 machine synapse[498]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules_enable(id): 1229Sep 17 00:16:44 machine synapse[498]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 month ago230Sep 17 00:16:44 machine synapse[498]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 month ago: it's 0231Sep 17 00:16:44 machine synapse[498]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 day ago232Sep 17 00:16:44 machine synapse[498]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 day ago: it's 0233Sep 17 00:16:44 machine synapse[498]: synapse.storage.util.id_generators: [main] Initialising stream generator for access_tokens(id): 1234Sep 17 00:16:44 machine synapse[498]: synapse.storage.util.id_generators: [main] Initialising stream generator for refresh_tokens(id): 1235Sep 17 00:16:44 machine synapse[498]: synapse.storage.util.id_generators: [main] Initialising stream generator for event_reports(id): 1236Sep 17 00:16:44 machine synapse[498]: synapse.storage.util.id_generators: [main] Initialising stream generator for room_reports(id): 1237Sep 17 00:16:44 machine synapse[498]: synapse.storage.util.id_generators: [main] Initialising stream generator for user_reports(id): 1238Sep 17 00:16:44 machine synapse[498]: synapse.storage.databases: [main] [database config 'master']: Starting 'state' database239Sep 17 00:16:44 machine synapse[498]: synapse.storage.databases: [main] [database config 'master']: prepared240Sep 17 00:16:44 machine synapse[498]: synapse.server: [main] Finished setting up.241Sep 17 00:16:44 machine synapse[498]: twisted: [main] Redirected stdout/stderr to logs242Sep 17 00:16:44 machine synapse[498]: synapse.app.homeserver: [main] Running243Sep 17 00:16:44 machine synapse[498]: synapse.app.homeserver: [main] Set file limit to: 524288244Sep 17 00:16:44 machine synapse[498]: synapse.push.pusher: [call_when_running] email enable notifs: False245Sep 17 00:16:44 machine synapse[498]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'profile'246Sep 17 00:16:44 machine synapse[498]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.device_list_update'247Sep 17 00:16:44 machine synapse[498]: synapse.util.caches.lrucache: [call_when_running] Expiring LRU caches after 1800 seconds248Sep 17 00:16:44 machine synapse[498]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.presence'249Sep 17 00:16:44 machine synapse[498]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.typing'250Sep 17 00:16:44 machine synapse[498]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'directory'251Sep 17 00:16:44 machine synapse[498]: synapse.handlers.deactivate_account: [user_parter_loop-0] Starting user parter252Sep 17 00:16:44 machine synapse[498]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.receipt'253Sep 17 00:16:44 machine synapse[498]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.signing_key_update'254Sep 17 00:16:44 machine synapse[498]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'org.matrix.signing_key_update'255Sep 17 00:16:44 machine synapse[498]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'client_keys'256Sep 17 00:16:44 machine synapse[498]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.direct_to_device'257Sep 17 00:16:44 machine synapse[498]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.health.HealthResource object at 0xffffedea95a0> to path b'/health'258Sep 17 00:16:44 machine synapse[498]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.EncodingResourceWrapper object at 0xffffee133ca0> to path b'/_matrix/client'259Sep 17 00:16:44 machine synapse[498]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffee1399a0> to path b'/.well-known'260Sep 17 00:16:44 machine synapse[498]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.http.server.JsonResource object at 0xffffee133e20> to path b'/_synapse/admin'261Sep 17 00:16:44 machine synapse[498]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.pick_idp.PickIdpResource object at 0xffffee139e20> to path b'/_synapse/client/pick_idp'262Sep 17 00:16:44 machine synapse[498]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffe28343a0> to path b'/_synapse/client/pick_username'263Sep 17 00:16:44 machine synapse[498]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.new_user_consent.NewUserConsentResource object at 0xffffee13a420> to path b'/_synapse/client/new_user_consent'264Sep 17 00:16:44 machine synapse[498]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.sso_register.SsoRegisterResource object at 0xffffee13a5a0> to path b'/_synapse/client/sso_register'265Sep 17 00:16:44 machine synapse[498]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.unsubscribe.UnsubscribeResource object at 0xffffee13a720> to path b'/_synapse/client/unsubscribe'266Sep 17 00:16:44 machine synapse[498]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.media_upload_limit_exceeded.MediaUploadLimitExceededResource object at 0xffffee13a8a0> to path b'/_synapse/client/media_upload_limit_exceeded'267Sep 17 00:16:44 machine synapse[498]: synapse.util.httpresourcetree: [call_when_running] Attaching FilePath('/nix/store/dc3p08cmvzgsnfpwr26r4f1z2skd4j7s-matrix-synapse-1.160.0/lib/python3.13/site-packages/synapse/static') to path b'/_matrix/static'268Sep 17 00:16:44 machine synapse[498]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee13ad20> to path b'/_matrix/media/r0'269Sep 17 00:16:44 machine synapse[498]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee13ad20> to path b'/_matrix/media/v3'270Sep 17 00:16:44 machine synapse[498]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee13ad20> to path b'/_matrix/media/v1'271Sep 17 00:16:44 machine synapse[498]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.federation.transport.server.TransportLayerServer object at 0xffffee13b920> to path b'/_matrix/federation'272Sep 17 00:16:44 machine synapse[498]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.key.v2.KeyResource object at 0xffffee13eaa0> to path b'/_matrix/key'273Sep 17 00:16:44 machine synapse[498]: twisted: [call_when_running] SynapseSite starting on 8008274Sep 17 00:16:44 machine synapse[498]: synapse.app._base: [call_when_running] Synapse now listening on TCP port 8008275Sep 17 00:16:44 machine synapse[498]: 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).276Sep 17 00:16:44 machine synapse[498]: synapse.storage.background_updates: [background_updates-0] Starting background schema updates for database master277Sep 17 00:16:44 machine synapse[498]: synapse.handlers.deactivate_account: [user_parter_loop-0] User parter finished: stopping278Sep 17 00:16:44 machine synapse[498]: synapse.push.pusherpool: [start_pushers-0] Started pushers279Sep 17 00:16:44 machine 4s8l47vbpf1f8d5bs90rbh78zxhjwx0h-matrix-synapse-create-users[526]: Connection to ::1 8008 port [tcp/http-alt] succeeded!280Sep 17 00:16:45 machine synapse[498]: 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]281Sep 17 00:16:45 machine synapse[498]: synapse.handlers.auth: [POST-1] Logging in user @admin:clan.test on device OLUOMDKLFE282Sep 17 00:16:45 machine synapse[498]: synapse.access.http.8008: [POST-1] ::1 - 8008 - {None} Processed request: 0.301sec/0.001sec ru=(0.255sec, 0.008sec) db=(0.005sec/0.031sec/12) 138B 200 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]283Sep 17 00:16:45 machine 4s8l47vbpf1f8d5bs90rbh78zxhjwx0h-matrix-synapse-create-users[527]: Sending registration request...284Sep 17 00:16:45 machine 4s8l47vbpf1f8d5bs90rbh78zxhjwx0h-matrix-synapse-create-users[527]: Success!285Sep 17 00:16:45 machine synapse[498]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'threads_backfill'286Sep 17 00:16:45 machine synapse[498]: 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)287Sep 17 00:16:45 machine synapse[498]: 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]288Sep 17 00:16:46 machine synapse[498]: synapse.handlers.auth: [POST-3] Logging in user @someuser:clan.test on device LUNWCFPVAE289Sep 17 00:16:46 machine synapse[498]: synapse.access.http.8008: [POST-3] ::1 - 8008 - {None} Processed request: 0.300sec/0.001sec ru=(0.264sec, 0.006sec) db=(0.005sec/0.018sec/12) 145B 200 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]290Sep 17 00:16:46 machine 4s8l47vbpf1f8d5bs90rbh78zxhjwx0h-matrix-synapse-create-users[534]: Sending registration request...291Sep 17 00:16:46 machine 4s8l47vbpf1f8d5bs90rbh78zxhjwx0h-matrix-synapse-create-users[534]: Success!292Sep 17 00:16:46 machine systemd[1]: Started Synapse Matrix homeserver.293Sep 17 00:16:46 machine synapse[498]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'device_lists_changes_in_room_by_room_index'294Sep 17 00:16:46 machine synapse[498]: synapse.storage.background_updates: [background_updates-0] Adding index device_lists_changes_in_room_by_room_idx to device_lists_changes_in_room295Sep 17 00:16:46 machine synapse[498]: 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)296Sep 17 00:16:47 machine synapse[498]: 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]297Sep 17 00:16:47 machine systemd[1]: Stopping Synapse Matrix homeserver...298Sep 17 00:16:47 machine synapse[498]: twisted: [sentinel] Received SIGTERM, shutting down.299Sep 17 00:16:47 machine synapse[498]: synapse.storage.databases.main.lock: [LockStore._on_shutdown-0] Dropping held locks due to shutdown300Sep 17 00:16:47 machine synapse[498]: synapse.storage.databases.main.lock: [LockStore._on_shutdown-0] Dropped locks due to shutdown301Sep 17 00:16:47 machine synapse[498]: synapse.handlers.presence: [PresenceHandler._on_shutdown-0] Performing _on_shutdown. Persisting 0 unpersisted changes302Sep 17 00:16:47 machine synapse[498]: synapse.handlers.presence: [PresenceHandler._on_shutdown-0] Finished _on_shutdown303Sep 17 00:16:47 machine synapse[498]: synapse.app._base: [log_shutdown] Shutting down...304Sep 17 00:16:47 machine synapse[498]: twisted: [sentinel] (TCP Port 8008 Closed)305Sep 17 00:16:47 machine synapse[498]: twisted: [sentinel] Main loop terminated.306Sep 17 00:16:47 machine systemd[1]: matrix-synapse.service: Deactivated successfully.307Sep 17 00:16:47 machine systemd[1]: Stopped Synapse Matrix homeserver.308Sep 17 00:16:47 machine systemd[1]: matrix-synapse.service: Consumed 7.798s CPU time over 9.057s wall clock time.309Sep 17 00:16:47 machine systemd[1]: Starting Synapse Matrix homeserver...310Sep 17 00:16:52 machine synapse_homeserver[566]: This server is configured to use 'matrix.org' as its trusted key server via the311Sep 17 00:16:52 machine synapse_homeserver[566]: 'trusted_key_servers' config option. 'matrix.org' is a good choice for a key312Sep 17 00:16:52 machine synapse_homeserver[566]: server since it is long-lived, stable and trusted. However, some admins may313Sep 17 00:16:52 machine synapse_homeserver[566]: wish to use another server for this purpose.314Sep 17 00:16:52 machine synapse_homeserver[566]: To suppress this warning and continue using 'matrix.org', admins should set315Sep 17 00:16:52 machine synapse_homeserver[566]: 'suppress_key_server_warning' to 'true' in homeserver.yaml.316Sep 17 00:16:52 machine synapse_homeserver[566]: --------------------------------------------------------------------------------317Sep 17 00:16:52 machine synapse[566]: synapse.config.logger: [main] ***** STARTING SERVER *****318Sep 17 00:16:52 machine synapse[566]: synapse.config.logger: [main] Server /nix/store/dc3p08cmvzgsnfpwr26r4f1z2skd4j7s-matrix-synapse-1.160.0/bin/synapse_homeserver version 1.160.0319Sep 17 00:16:52 machine synapse[566]: synapse.config.logger: [main] Copyright (c) 2023 New Vector, Inc320Sep 17 00:16:52 machine synapse[566]: synapse.config.logger: [main] Licensed under the AGPL 3.0 license. Website: https://github.com/element-hq/synapse321Sep 17 00:16:52 machine synapse[566]: synapse.config.logger: [main] Server hostname: clan.test322Sep 17 00:16:52 machine synapse[566]: synapse.config.logger: [main] Public Base URL: https://clan.test/323Sep 17 00:16:52 machine synapse[566]: synapse.config.logger: [main] Instance name: master324Sep 17 00:16:52 machine synapse[566]: synapse.config.logger: [main] Twisted reactor: EPollReactor325Sep 17 00:16:52 machine synapse[566]: synapse.app.homeserver: [main] Setting up server326Sep 17 00:16:52 machine synapse[566]: synapse.server: [main] Setting up.327Sep 17 00:16:52 machine synapse[566]: synapse.storage.databases: [main] [database config 'master']: Checking database server328Sep 17 00:16:52 machine synapse[566]: synapse.storage.databases: [main] [database config 'master']: Preparing for databases ['main', 'state']329Sep 17 00:16:52 machine synapse[566]: synapse.storage.prepare_database: [main] ['main', 'state']: Checking existing schema version330Sep 17 00:16:52 machine synapse[566]: synapse.storage.prepare_database: [main] ['main', 'state']: Existing schema is 94 (+13 deltas)331Sep 17 00:16:52 machine synapse[566]: synapse.storage.databases.main: [main] Checking database for consistency with configuration...332Sep 17 00:16:52 machine synapse[566]: synapse.storage.prepare_database: [main] Applying schema deltas for v94333Sep 17 00:16:52 machine synapse[566]: synapse.storage.prepare_database: [main] Schema now up to date334Sep 17 00:16:52 machine synapse[566]: synapse.storage.databases: [main] [database config 'master']: Starting 'main' database335Sep 17 00:16:52 machine synapse[566]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules(id): 1336Sep 17 00:16:52 machine synapse[566]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules_enable(id): 1337Sep 17 00:16:52 machine synapse[566]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 month ago338Sep 17 00:16:52 machine synapse[566]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 month ago: it's 0339Sep 17 00:16:52 machine synapse[566]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 day ago340Sep 17 00:16:52 machine synapse[566]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 day ago: it's 0341Sep 17 00:16:52 machine synapse[566]: synapse.storage.util.id_generators: [main] Initialising stream generator for access_tokens(id): 3342Sep 17 00:16:52 machine synapse[566]: synapse.storage.util.id_generators: [main] Initialising stream generator for refresh_tokens(id): 1343Sep 17 00:16:52 machine synapse[566]: synapse.storage.util.id_generators: [main] Initialising stream generator for event_reports(id): 1344Sep 17 00:16:52 machine synapse[566]: synapse.storage.util.id_generators: [main] Initialising stream generator for room_reports(id): 1345Sep 17 00:16:52 machine synapse[566]: synapse.storage.util.id_generators: [main] Initialising stream generator for user_reports(id): 1346Sep 17 00:16:52 machine synapse[566]: synapse.storage.databases: [main] [database config 'master']: Starting 'state' database347Sep 17 00:16:52 machine synapse[566]: synapse.storage.databases: [main] [database config 'master']: prepared348Sep 17 00:16:52 machine synapse[566]: synapse.server: [main] Finished setting up.349Sep 17 00:16:52 machine synapse[566]: twisted: [main] Redirected stdout/stderr to logs350Sep 17 00:16:52 machine synapse[566]: synapse.app.homeserver: [main] Running351Sep 17 00:16:52 machine synapse[566]: synapse.app.homeserver: [main] Set file limit to: 524288352Sep 17 00:16:52 machine synapse[566]: synapse.push.pusher: [call_when_running] email enable notifs: False353Sep 17 00:16:52 machine synapse[566]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'profile'354Sep 17 00:16:52 machine synapse[566]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.device_list_update'355Sep 17 00:16:52 machine synapse[566]: synapse.util.caches.lrucache: [call_when_running] Expiring LRU caches after 1800 seconds356Sep 17 00:16:52 machine synapse[566]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.presence'357Sep 17 00:16:52 machine synapse[566]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.typing'358Sep 17 00:16:52 machine synapse[566]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'directory'359Sep 17 00:16:52 machine synapse[566]: synapse.handlers.deactivate_account: [user_parter_loop-0] Starting user parter360Sep 17 00:16:52 machine synapse[566]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.receipt'361Sep 17 00:16:52 machine synapse[566]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.signing_key_update'362Sep 17 00:16:52 machine synapse[566]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'org.matrix.signing_key_update'363Sep 17 00:16:52 machine synapse[566]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'client_keys'364Sep 17 00:16:52 machine synapse[566]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.direct_to_device'365Sep 17 00:16:52 machine synapse[566]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.health.HealthResource object at 0xffffee099720> to path b'/health'366Sep 17 00:16:52 machine synapse[566]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.EncodingResourceWrapper object at 0xffffee333e20> to path b'/_matrix/client'367Sep 17 00:16:52 machine synapse[566]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffee339b20> to path b'/.well-known'368Sep 17 00:16:52 machine synapse[566]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.http.server.JsonResource object at 0xffffee333fa0> to path b'/_synapse/admin'369Sep 17 00:16:52 machine synapse[566]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.pick_idp.PickIdpResource object at 0xffffee339fa0> to path b'/_synapse/client/pick_idp'370Sep 17 00:16:52 machine synapse[566]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffe2a24260> to path b'/_synapse/client/pick_username'371Sep 17 00:16:52 machine synapse[566]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.new_user_consent.NewUserConsentResource object at 0xffffee33a5a0> to path b'/_synapse/client/new_user_consent'372Sep 17 00:16:52 machine synapse[566]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.sso_register.SsoRegisterResource object at 0xffffee33a720> to path b'/_synapse/client/sso_register'373Sep 17 00:16:52 machine synapse[566]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.unsubscribe.UnsubscribeResource object at 0xffffee33a8a0> to path b'/_synapse/client/unsubscribe'374Sep 17 00:16:52 machine synapse[566]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.media_upload_limit_exceeded.MediaUploadLimitExceededResource object at 0xffffee33aa20> to path b'/_synapse/client/media_upload_limit_exceeded'375Sep 17 00:16:52 machine synapse[566]: synapse.util.httpresourcetree: [call_when_running] Attaching FilePath('/nix/store/dc3p08cmvzgsnfpwr26r4f1z2skd4j7s-matrix-synapse-1.160.0/lib/python3.13/site-packages/synapse/static') to path b'/_matrix/static'376Sep 17 00:16:52 machine synapse[566]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee33aea0> to path b'/_matrix/media/r0'377Sep 17 00:16:52 machine synapse[566]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee33aea0> to path b'/_matrix/media/v3'378Sep 17 00:16:52 machine synapse[566]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee33aea0> to path b'/_matrix/media/v1'379Sep 17 00:16:52 machine synapse[566]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.federation.transport.server.TransportLayerServer object at 0xffffee33baa0> to path b'/_matrix/federation'380Sep 17 00:16:52 machine synapse[566]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.key.v2.KeyResource object at 0xffffee33ec20> to path b'/_matrix/key'381Sep 17 00:16:52 machine synapse[566]: twisted: [call_when_running] SynapseSite starting on 8008382Sep 17 00:16:52 machine synapse[566]: synapse.app._base: [call_when_running] Synapse now listening on TCP port 8008383Sep 17 00:16:52 machine synapse[566]: synapse.app._base: [call_when_running] garbage collector: Freezing all allocated objects in the hopes that (almost) everything currently allocated are things that will be used by the homeserver for the rest of time. Doing so means less work each GC (hopefully).384Sep 17 00:16:52 machine synapse[566]: synapse.storage.background_updates: [background_updates-0] Starting background schema updates for database master385Sep 17 00:16:52 machine synapse[566]: synapse.handlers.deactivate_account: [user_parter_loop-0] User parter finished: stopping386Sep 17 00:16:52 machine synapse[566]: synapse.push.pusherpool: [start_pushers-0] Started pushers387Sep 17 00:16:53 machine 4s8l47vbpf1f8d5bs90rbh78zxhjwx0h-matrix-synapse-create-users[591]: Connection to ::1 8008 port [tcp/http-alt] succeeded!388Sep 17 00:16:53 machine synapse[566]: synapse.access.http.8008: [GET-0] ::1 - 8008 - {None} Processed request: 0.001sec/0.001sec ru=(0.002sec, 0.000sec) db=(0.000sec/0.000sec/0) 140B 200 "GET /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]389Sep 17 00:16:53 machine synapse[566]: synapse.http.server: [POST-1] <XForwardedForRequest at 0xffffe2a26e20 method='POST' uri='/_synapse/admin/v1/register' clientproto='HTTP/1.1' site='8008'> SynapseError: 400 - User ID already taken.390Sep 17 00:16:53 machine synapse[566]: synapse.access.http.8008: [POST-1] ::1 - 8008 - {None} Processed request: 0.251sec/0.001sec ru=(0.244sec, 0.001sec) db=(0.001sec/0.001sec/1) 60B 400 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]391Sep 17 00:16:53 machine 4s8l47vbpf1f8d5bs90rbh78zxhjwx0h-matrix-synapse-create-users[592]: Sending registration request...392Sep 17 00:16:53 machine 4s8l47vbpf1f8d5bs90rbh78zxhjwx0h-matrix-synapse-create-users[592]: User already exists. Skipping.393Sep 17 00:16:53 machine synapse[566]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'populate_stats_process_rooms'394Sep 17 00:16:53 machine synapse[566]: synapse.storage.background_updates: [background_updates-0] Running background update 'populate_stats_process_rooms'. Processed 1 items in 5ms. (total_rate=0.2/ms, current_rate=0.2/ms, total_updated=1, batch_size=100)395Sep 17 00:16:54 machine synapse[566]: 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]396Sep 17 00:16:54 machine synapse[566]: synapse.http.server: [POST-3] <XForwardedForRequest at 0xffffedc76a20 method='POST' uri='/_synapse/admin/v1/register' clientproto='HTTP/1.1' site='8008'> SynapseError: 400 - User ID already taken.397Sep 17 00:16:54 machine synapse[566]: synapse.access.http.8008: [POST-3] ::1 - 8008 - {None} Processed request: 0.235sec/0.000sec ru=(0.229sec, 0.000sec) db=(0.000sec/0.001sec/1) 60B 400 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]398Sep 17 00:16:54 machine 4s8l47vbpf1f8d5bs90rbh78zxhjwx0h-matrix-synapse-create-users[598]: Sending registration request...399Sep 17 00:16:54 machine 4s8l47vbpf1f8d5bs90rbh78zxhjwx0h-matrix-synapse-create-users[598]: User already exists. Skipping.400Sep 17 00:16:54 machine systemd[1]: Started Synapse Matrix homeserver.401+ systemctl --no-pager show matrix-synapse402+ nc -z -v ::1 8008403Connection to ::1 8008 port [tcp/http-alt] succeeded!404+ curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test'405