container-test-run-matrix-synapse
checks.aarch64-linux.matrix-synapse
· build #1029
· raw
1build01: building pj4pz3m2hmss4kigdzc3z3lg2vdfbndj-container-test-run-matrix-synapse.drv2additionally exposed symbols:3 machine,4 start_all, machines, driver, Machine, wait_for_signal5Starting machine678<<< NixOS Stage 2 >>>910booting system configuration /nix/store/8k7wgvi5h1b58hj1h65rll698wjc273v-nixos-system-machine-test11running activation script...12setting up /etc...132: host0@if3: <BROADCAST,MULTICAST> mtu 1500 qdisc noop state DOWN mode DEFAULT group default qlen 100014 link/ether 0e:74:26:22:81:c2 brd ff:ff:ff:ff:ff:ff link-netnsid 015setting up age secrets...16starting systemd...17systemd 260.5 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)18Detected virtualization systemd-nspawn.19Detected architecture arm64.20Detected first boot.21Initializing machine ID from container UUID.22Applying preset policy.23Populated /etc with preset unit settings.24Queued start job for default target Multi-User System.25262728<<< Welcome to NixOS test (aarch64) - console >>>29+ systemd-run /bin/sh -c '/nix/store/3l7ygqcc6v0rbi9d4j1m6phi6sz86j98-coreutils-9.11/bin/sleep 999999999 && echo 92d5c481-f619-49ef-8148-5f06d71d25a6'30Running as unit: run-p478-i1920008.service; invocation ID: e1ab6ccd94fe45b899cb889707099f3931To attach to container machine run on the same machine that runs the test:32 sudo nsenter --user --target $(\pgrep -f '^/bin/sh.*92d5c481-f619-49ef-8148-5f06d71d25a6') --mount --uts --ipc --net --pid --cgroup /bin/sh -c bash 3334To inject external network and continue test, run:35sudo /nix/store/q1khrhbn506imjxx7kiqcbihycawa6kl-python3-3.13.15/bin/python3.13 /nix/store/6kasz8qw4mw4szwg29sxyra6pgbwz4zq-test-driver-0.0.1/lib/python3.13/site-packages/test_driver/inject_network.py 92d5c481-f619-49ef-8148-5f06d71d25a636+ 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.01 seconds)48+ systemctl restart matrix-synapse49+ journalctl -u matrix-synapse --no-pager50Oct 19 05:18:30 machine systemd[1]: Starting Synapse Matrix homeserver...51Oct 19 05:18:33 machine matrix-synapse-pre-start[473]: Generating signing key file /var/lib/matrix-synapse/homeserver.signing.key52Oct 19 05:18:36 machine synapse_homeserver[488]: This server is configured to use 'matrix.org' as its trusted key server via the53Oct 19 05:18:36 machine synapse_homeserver[488]: 'trusted_key_servers' config option. 'matrix.org' is a good choice for a key54Oct 19 05:18:36 machine synapse_homeserver[488]: server since it is long-lived, stable and trusted. However, some admins may55Oct 19 05:18:36 machine synapse_homeserver[488]: wish to use another server for this purpose.56Oct 19 05:18:36 machine synapse_homeserver[488]: To suppress this warning and continue using 'matrix.org', admins should set57Oct 19 05:18:36 machine synapse_homeserver[488]: 'suppress_key_server_warning' to 'true' in homeserver.yaml.58Oct 19 05:18:36 machine synapse_homeserver[488]: --------------------------------------------------------------------------------59Oct 19 05:18:36 machine synapse[488]: synapse.config.logger: [main] ***** STARTING SERVER *****60Oct 19 05:18:36 machine synapse[488]: synapse.config.logger: [main] Server /nix/store/928l52anrm0pvcn6k9haijzhbygjrjs5-matrix-synapse-1.162.0/bin/synapse_homeserver version 1.162.061Oct 19 05:18:36 machine synapse[488]: synapse.config.logger: [main] Copyright (c) 2023 New Vector, Inc62Oct 19 05:18:36 machine synapse[488]: synapse.config.logger: [main] Licensed under the AGPL 3.0 license. Website: https://github.com/element-hq/synapse63Oct 19 05:18:36 machine synapse[488]: synapse.config.logger: [main] Server hostname: clan.test64Oct 19 05:18:36 machine synapse[488]: synapse.config.logger: [main] Public Base URL: https://clan.test/65Oct 19 05:18:36 machine synapse[488]: synapse.config.logger: [main] Instance name: master66Oct 19 05:18:36 machine synapse[488]: synapse.config.logger: [main] Twisted reactor: EPollReactor67Oct 19 05:18:36 machine synapse[488]: synapse.app.homeserver: [main] Setting up server68Oct 19 05:18:36 machine synapse[488]: synapse.server: [main] Setting up.69Oct 19 05:18:36 machine synapse[488]: synapse.storage.databases: [main] [database config 'master']: Checking database server70Oct 19 05:18:36 machine synapse[488]: synapse.storage.databases: [main] [database config 'master']: Preparing for databases ['main', 'state']71Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] ['main', 'state']: Checking existing schema version72Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] ['main', 'state']: Initialising new database73Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v7374Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/01event_failed_pull_attempts.sql75Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/02add_pusher_enabled.sql76Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/02room_id_indexes_for_purging.sql77Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/03pusher_device_id.sql78Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/03users_approved_column.sql79Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/04partial_join_details.sql80Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/04pending_device_list_updates.sql81Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/05old_push_actions.sql.postgres82Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/06thread_notifications_thread_id_idx.sql83Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/08thread_receipts_non_null.sql.postgres84Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/09partial_joined_via_destination.sql85Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/09threads_table.sql86Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Running 73/10_update_sqlite_fts4_tokenizer.py:run_create87Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/10login_tokens.sql88Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/11event_search_room_id_n_distinct.sql.postgres89Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/12refactor_device_list_outbound_pokes.sql90Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/13add_device_lists_index.sql91Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/20_un_partial_stated_room_stream.sql92Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/21_un_partial_stated_room_stream_seq.sql.postgres93Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/22_rebuild_user_dir_stats.sql94Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/22_un_partial_stated_event_stream.sql95Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/23_fix_thread_index.sql96Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/23_un_partial_stated_room_stream_seq.sql.postgres97Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/24_events_jump_to_date_index.sql98Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/25drop_presence.sql99Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v74100Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 74/01_user_directory_stale_remote_users.sql101Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 74/02_set_device_id_for_pushers_bg_update.sql102Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 74/03_membership_tables_event_stream_ordering.sql.postgres103Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 74/03_room_membership_index.sql104Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 74/04_delete_e2e_backup_keys_for_deactivated_users.sql105Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Running 74/04_membership_tables_event_stream_ordering_triggers.py:run_create106Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 74/05_events_txn_id_device_id.sql107Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 74/90COMMENTS_destinations.sql.postgres108Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v75109Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v76110Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 76/01_add_profiles_full_user_id_column.sql111Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 76/02_add_user_filters_full_user_id_column.sql112Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 76/03_per_user_experimental_features.sql113Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 76/04_add_room_forgetter.sql114Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v77115Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/01_add_profiles_not_valid_check.sql.postgres116Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/02_add_user_filters_not_valid_check.sql.postgres117Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 77/03bg_populate_full_user_id_profiles.sql118Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 77/04bg_populate_full_user_id_user_filters.sql119Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 77/05thread_notifications_backfill.sql120Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_actions.sql.postgres121Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_actions_staging.sql.postgres122Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_summary.sql.postgres123Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 77/14bg_indices_event_stream_ordering.sql124Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v78125Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Running 78/03event_extremities_constraints.py:run_create126Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Running 78/04_add_full_user_id_index_user_filters.py:run_create127Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v79128Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 79/03_read_write_locks_triggers.sql.postgres129Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Running 79/04_mitigate_stream_ordering_update_race.py:run_create130Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 79/05_read_write_locks_triggers.sql.postgres131Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v80132Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 80/01_users_alter_locked.sql133Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/02_read_write_locks_unlogged.sql.postgres134Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 80/02_scheduled_tasks.sql135Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/03_read_write_locks_triggers.sql.postgres136Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/04_read_write_locks_deadlock.sql.postgres137Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v81138Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v82139Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 82/02_scheduled_tasks_index.sql140Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 82/04_add_indices_for_purging_rooms.sql141Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 82/05gaps.sql142Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v83143Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 83/01_drop_old_tables.sql144Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 83/05_cross_signing_key_update_grant.sql145Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 83/06_event_push_summary_room.sql146Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v84147Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 84/01_auth_links_stats.sql.postgres148Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 84/02_auth_links_index.sql149Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 84/03_auth_links_analyze.sql.postgres150Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 84/04_access_token_index.sql151Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v85152Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 85/01_add_suspended.sql153Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 85/02_add_instance_names.sql154Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 85/03_new_sequences.sql.postgres155Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 85/04_cleanup_device_federation_outbox.sql156Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 85/05_add_instance_names_converted_pos.sql157Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 85/06_add_room_reports.sql158Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v86159Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 86/01_authenticate_media.sql160Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 86/02_receipts_event_id_index.sql161Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v87162Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 87/01_sliding_sync_memberships.sql163Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 87/02_per_connection_state.sql164Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 87/03_current_state_index.sql165Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v88166Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 88/01_add_delayed_events.sql167Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 88/01_custom_profile_fields.sql168Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 88/02_fix_sliding_sync_membership_snapshots_forgotten_column.sql169Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 88/03_add_otk_ts_added_index.sql170Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 88/04_current_state_delta_index.sql171Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 88/05_drop_old_otks.sql.postgres172Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 88/05_sliding_sync_room_config_index.sql173Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 88/06_events_received_ts_index.sql174Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v89175Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 89/01_sliding_sync_membership_snapshot_index.sql176Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 89/01_state_groups_deletion.sql177Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v90178Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 90/01_add_column_participant_room_memberships_table.sql179Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 90/02_delete_unreferenced_state_groups.sql180Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 90/03_remove_old_deletion_bg_update.sql181Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v91182Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 91/01_media_hash.sql183Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v92184Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/01_remove_trigger.sql.postgres185Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 92/02_remove_populate_participant_bg_update.sql186Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 92/04_ss_membership_snapshot_idx.sql187Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 92/04_thread_subscriptions.sql188Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/04_thread_subscriptions_seq.sql.postgres189Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 92/05_fixup_max_depth_cap.sql190Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/05_thread_subscriptions_comments.sql.postgres191Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 92/06_device_federation_inbox_index.sql192Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/06_threads_last_sent_stream_ordering_comments.sql.postgres193Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 92/07_add_user_reports.sql194Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 92/07_event_txn_id_device_id_txn_id2.sql195Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 92/08_room_ban_redactions.sql196Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/08_thread_subscriptions_seq_fixup.sql.postgres197Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 92/09_thread_subscriptions_update.sql198Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/09_thread_subscriptions_update.sql.postgres199Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v93200Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 93/01_add_delayed_events.sql201Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 93/01_sticky_events.sql202Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 93/01_sticky_events_seq.sql.postgres203Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 93/02_sliding_sync_members.sql204Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 93/03_sss_pos_last_used.sql205Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Running 93/04_make_delayed_event_content_text.py:run_create206Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v94207Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 94/01_redactions_recheck.sql208Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 94/01_state_groups_persisting_inserted_ts.sql.postgres209Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 94/02_redactions_recheck_bg_update.sql210Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 94/03_device_lists_room_timestamp.sql211Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 94/03_quarantined_media_tracking.sql212Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 94/03_quarantined_media_tracking_seq.sql.postgres213Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 94/03_state_dag_fwd_extrems.sql214Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 94/04_device_lists_changes_max_pruned.sql215Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 94/05_rerun_flag_existing_quarantined_media.sql216Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 94/06_sliding_sync_connections_last_used_ts_index.sql217Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 94/07_profile_updates.sql218Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 94/07_profile_updates_seq.sql.postgres219Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 94/07_sliding_sync_lazy_members_position_index.sql220Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 94/08_device_lists_changes_in_room_unconverted_idx.sql221Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 94/09_e2e_cross_signing_signatures_index.sql222Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 94/10_e2e_cross_signing_signatures_add_key_id_to_index.sql223Oct 19 05:18:36 machine synapse[488]: synapse.storage.prepare_database: [main] Schema now up to date224Oct 19 05:18:36 machine synapse[488]: synapse.storage.databases: [main] [database config 'master']: Starting 'main' database225Oct 19 05:18:36 machine synapse[488]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules(id): 1226Oct 19 05:18:36 machine synapse[488]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules_enable(id): 1227Oct 19 05:18:36 machine synapse[488]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 month ago228Oct 19 05:18:36 machine synapse[488]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 month ago: it's 0229Oct 19 05:18:36 machine synapse[488]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 day ago230Oct 19 05:18:36 machine synapse[488]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 day ago: it's 0231Oct 19 05:18:36 machine synapse[488]: synapse.storage.util.id_generators: [main] Initialising stream generator for access_tokens(id): 1232Oct 19 05:18:36 machine synapse[488]: synapse.storage.util.id_generators: [main] Initialising stream generator for refresh_tokens(id): 1233Oct 19 05:18:36 machine synapse[488]: synapse.storage.util.id_generators: [main] Initialising stream generator for event_reports(id): 1234Oct 19 05:18:36 machine synapse[488]: synapse.storage.util.id_generators: [main] Initialising stream generator for room_reports(id): 1235Oct 19 05:18:36 machine synapse[488]: synapse.storage.util.id_generators: [main] Initialising stream generator for user_reports(id): 1236Oct 19 05:18:36 machine synapse[488]: synapse.storage.databases: [main] [database config 'master']: Starting 'state' database237Oct 19 05:18:36 machine synapse[488]: synapse.storage.databases: [main] [database config 'master']: prepared238Oct 19 05:18:36 machine synapse[488]: synapse.server: [main] Finished setting up.239Oct 19 05:18:36 machine synapse[488]: twisted: [main] Redirected stdout/stderr to logs240Oct 19 05:18:36 machine synapse[488]: synapse.app.homeserver: [main] Running241Oct 19 05:18:36 machine synapse[488]: synapse.app.homeserver: [main] Set file limit to: 524288242Oct 19 05:18:36 machine synapse[488]: synapse.push.pusher: [call_when_running] email enable notifs: False243Oct 19 05:18:36 machine synapse[488]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'profile'244Oct 19 05:18:36 machine synapse[488]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.device_list_update'245Oct 19 05:18:36 machine synapse[488]: synapse.util.caches.lrucache: [call_when_running] Expiring LRU caches after 1800 seconds246Oct 19 05:18:36 machine synapse[488]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.presence'247Oct 19 05:18:36 machine synapse[488]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.typing'248Oct 19 05:18:36 machine synapse[488]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'directory'249Oct 19 05:18:36 machine synapse[488]: synapse.handlers.deactivate_account: [user_parter_loop-0] Starting user parter250Oct 19 05:18:36 machine synapse[488]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.receipt'251Oct 19 05:18:36 machine synapse[488]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.signing_key_update'252Oct 19 05:18:36 machine synapse[488]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'org.matrix.signing_key_update'253Oct 19 05:18:36 machine synapse[488]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'client_keys'254Oct 19 05:18:36 machine synapse[488]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.direct_to_device'255Oct 19 05:18:36 machine synapse[488]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.health.HealthResource object at 0xffffee09b820> to path b'/health'256Oct 19 05:18:36 machine synapse[488]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.EncodingResourceWrapper object at 0xffffee3d60a0> to path b'/_matrix/client'257Oct 19 05:18:36 machine synapse[488]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffee3dbda0> to path b'/.well-known'258Oct 19 05:18:36 machine synapse[488]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.http.server.JsonResource object at 0xffffee3d6220> to path b'/_synapse/admin'259Oct 19 05:18:36 machine synapse[488]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.pick_idp.PickIdpResource object at 0xffffee3dc220> to path b'/_synapse/client/pick_idp'260Oct 19 05:18:36 machine synapse[488]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffe3e57320> to path b'/_synapse/client/pick_username'261Oct 19 05:18:36 machine synapse[488]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.new_user_consent.NewUserConsentResource object at 0xffffee3dc820> to path b'/_synapse/client/new_user_consent'262Oct 19 05:18:36 machine synapse[488]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.sso_register.SsoRegisterResource object at 0xffffee3dc9a0> to path b'/_synapse/client/sso_register'263Oct 19 05:18:36 machine synapse[488]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.unsubscribe.UnsubscribeResource object at 0xffffee3dcb20> to path b'/_synapse/client/unsubscribe'264Oct 19 05:18:36 machine synapse[488]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.media_upload_limit_exceeded.MediaUploadLimitExceededResource object at 0xffffee3dcca0> to path b'/_synapse/client/media_upload_limit_exceeded'265Oct 19 05:18:36 machine synapse[488]: synapse.util.httpresourcetree: [call_when_running] Attaching FilePath('/nix/store/928l52anrm0pvcn6k9haijzhbygjrjs5-matrix-synapse-1.162.0/lib/python3.13/site-packages/synapse/static') to path b'/_matrix/static'266Oct 19 05:18:36 machine synapse[488]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee3dd120> to path b'/_matrix/media/r0'267Oct 19 05:18:36 machine synapse[488]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee3dd120> to path b'/_matrix/media/v3'268Oct 19 05:18:36 machine synapse[488]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee3dd120> to path b'/_matrix/media/v1'269Oct 19 05:18:36 machine synapse[488]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.federation.transport.server.TransportLayerServer object at 0xffffee3ddd20> to path b'/_matrix/federation'270Oct 19 05:18:36 machine synapse[488]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.key.v2.KeyResource object at 0xffffee3e0ea0> to path b'/_matrix/key'271Oct 19 05:18:36 machine synapse[488]: twisted: [call_when_running] SynapseSite starting on 8008272Oct 19 05:18:36 machine synapse[488]: synapse.app._base: [call_when_running] Synapse now listening on TCP port 8008273Oct 19 05:18:36 machine synapse[488]: 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).274Oct 19 05:18:36 machine synapse[488]: synapse.storage.background_updates: [background_updates-0] Starting background schema updates for database master275Oct 19 05:18:36 machine synapse[488]: synapse.handlers.deactivate_account: [user_parter_loop-0] User parter finished: stopping276Oct 19 05:18:36 machine synapse[488]: synapse.push.pusherpool: [start_pushers-0] Started pushers277Oct 19 05:18:37 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[516]: Connection to ::1 8008 port [tcp/http-alt] succeeded!278Oct 19 05:18:37 machine synapse[488]: synapse.access.http.8008: [GET-0] ::1 - 8008 - {None} Processed request: 0.002sec/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]279Oct 19 05:18:37 machine synapse[488]: synapse.handlers.auth: [POST-1] Logging in user @admin:clan.test on device NNEWMAZUHN280Oct 19 05:18:37 machine synapse[488]: synapse.access.http.8008: [POST-1] ::1 - 8008 - {None} Processed request: 0.295sec/0.001sec ru=(0.253sec, 0.004sec) db=(0.004sec/0.028sec/12) 138B 200 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]281Oct 19 05:18:37 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[517]: Sending registration request...282Oct 19 05:18:37 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[517]: Success!283Oct 19 05:18:37 machine synapse[488]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'threads_backfill'284Oct 19 05:18:37 machine synapse[488]: 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)285Oct 19 05:18:38 machine synapse[488]: 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]286Oct 19 05:18:38 machine synapse[488]: synapse.handlers.auth: [POST-3] Logging in user @someuser:clan.test on device HNAUOHXNKU287Oct 19 05:18:38 machine synapse[488]: synapse.access.http.8008: [POST-3] ::1 - 8008 - {None} Processed request: 0.272sec/0.000sec ru=(0.249sec, 0.002sec) db=(0.003sec/0.011sec/12) 145B 200 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]288Oct 19 05:18:38 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[524]: Sending registration request...289Oct 19 05:18:38 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[524]: Success!290Oct 19 05:18:38 machine systemd[1]: Started Synapse Matrix homeserver.291Oct 19 05:18:38 machine synapse[488]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'device_lists_changes_in_room_by_room_index'292Oct 19 05:18:38 machine synapse[488]: synapse.storage.background_updates: [background_updates-0] Adding index device_lists_changes_in_room_by_room_idx to device_lists_changes_in_room293Oct 19 05:18:38 machine synapse[488]: synapse.storage.background_updates: [background_updates-0] Running background update 'device_lists_changes_in_room_by_room_index'. Processed 1 items in 3ms. (total_rate=0.3333333333333333/ms, current_rate=0.3333333333333333/ms, total_updated=1, batch_size=100)294Oct 19 05:18:39 machine synapse[488]: synapse.access.http.8008: [GET-4] ::1 - 8008 - {None} Processed request: 0.000sec/-0.000sec ru=(0.000sec, 0.000sec) db=(0.000sec/0.000sec/0) 10145B 200 "GET /_matrix/static/ HTTP/1.1" "curl/8.22.0" [0 dbevts]295Oct 19 05:18:39 machine synapse[488]: twisted: [sentinel] Received SIGTERM, shutting down.296Oct 19 05:18:39 machine systemd[1]: Stopping Synapse Matrix homeserver...297Oct 19 05:18:39 machine synapse[488]: synapse.storage.databases.main.lock: [LockStore._on_shutdown-0] Dropping held locks due to shutdown298Oct 19 05:18:39 machine synapse[488]: synapse.storage.databases.main.lock: [LockStore._on_shutdown-0] Dropped locks due to shutdown299Oct 19 05:18:39 machine synapse[488]: synapse.handlers.presence: [PresenceHandler._on_shutdown-0] Performing _on_shutdown. Persisting 0 unpersisted changes300Oct 19 05:18:39 machine synapse[488]: synapse.handlers.presence: [PresenceHandler._on_shutdown-0] Finished _on_shutdown301Oct 19 05:18:39 machine synapse[488]: synapse.app._base: [log_shutdown] Shutting down...302Oct 19 05:18:39 machine synapse[488]: twisted: [sentinel] (TCP Port 8008 Closed)303Oct 19 05:18:39 machine synapse[488]: twisted: [sentinel] Main loop terminated.304Oct 19 05:18:39 machine systemd[1]: matrix-synapse.service: Deactivated successfully.305Oct 19 05:18:39 machine systemd[1]: Stopped Synapse Matrix homeserver.306Oct 19 05:18:39 machine systemd[1]: matrix-synapse.service: Consumed 7.581s CPU time over 8.596s wall clock time.307Oct 19 05:18:39 machine systemd[1]: Starting Synapse Matrix homeserver...308Oct 19 05:18:45 machine synapse_homeserver[556]: This server is configured to use 'matrix.org' as its trusted key server via the309Oct 19 05:18:45 machine synapse_homeserver[556]: 'trusted_key_servers' config option. 'matrix.org' is a good choice for a key310Oct 19 05:18:45 machine synapse_homeserver[556]: server since it is long-lived, stable and trusted. However, some admins may311Oct 19 05:18:45 machine synapse_homeserver[556]: wish to use another server for this purpose.312Oct 19 05:18:45 machine synapse_homeserver[556]: To suppress this warning and continue using 'matrix.org', admins should set313Oct 19 05:18:45 machine synapse_homeserver[556]: 'suppress_key_server_warning' to 'true' in homeserver.yaml.314Oct 19 05:18:45 machine synapse_homeserver[556]: --------------------------------------------------------------------------------315Oct 19 05:18:45 machine synapse[556]: synapse.config.logger: [main] ***** STARTING SERVER *****316Oct 19 05:18:45 machine synapse[556]: synapse.config.logger: [main] Server /nix/store/928l52anrm0pvcn6k9haijzhbygjrjs5-matrix-synapse-1.162.0/bin/synapse_homeserver version 1.162.0317Oct 19 05:18:45 machine synapse[556]: synapse.config.logger: [main] Copyright (c) 2023 New Vector, Inc318Oct 19 05:18:45 machine synapse[556]: synapse.config.logger: [main] Licensed under the AGPL 3.0 license. Website: https://github.com/element-hq/synapse319Oct 19 05:18:45 machine synapse[556]: synapse.config.logger: [main] Server hostname: clan.test320Oct 19 05:18:45 machine synapse[556]: synapse.config.logger: [main] Public Base URL: https://clan.test/321Oct 19 05:18:45 machine synapse[556]: synapse.config.logger: [main] Instance name: master322Oct 19 05:18:45 machine synapse[556]: synapse.config.logger: [main] Twisted reactor: EPollReactor323Oct 19 05:18:45 machine synapse[556]: synapse.app.homeserver: [main] Setting up server324Oct 19 05:18:45 machine synapse[556]: synapse.server: [main] Setting up.325Oct 19 05:18:45 machine synapse[556]: synapse.storage.databases: [main] [database config 'master']: Checking database server326Oct 19 05:18:45 machine synapse[556]: synapse.storage.databases: [main] [database config 'master']: Preparing for databases ['main', 'state']327Oct 19 05:18:45 machine synapse[556]: synapse.storage.prepare_database: [main] ['main', 'state']: Checking existing schema version328Oct 19 05:18:45 machine synapse[556]: synapse.storage.prepare_database: [main] ['main', 'state']: Existing schema is 94 (+16 deltas)329Oct 19 05:18:45 machine synapse[556]: synapse.storage.databases.main: [main] Checking database for consistency with configuration...330Oct 19 05:18:45 machine synapse[556]: synapse.storage.prepare_database: [main] Applying schema deltas for v94331Oct 19 05:18:45 machine synapse[556]: synapse.storage.prepare_database: [main] Schema now up to date332Oct 19 05:18:45 machine synapse[556]: synapse.storage.databases: [main] [database config 'master']: Starting 'main' database333Oct 19 05:18:45 machine synapse[556]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules(id): 1334Oct 19 05:18:45 machine synapse[556]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules_enable(id): 1335Oct 19 05:18:45 machine synapse[556]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 month ago336Oct 19 05:18:45 machine synapse[556]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 month ago: it's 0337Oct 19 05:18:45 machine synapse[556]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 day ago338Oct 19 05:18:45 machine synapse[556]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 day ago: it's 0339Oct 19 05:18:45 machine synapse[556]: synapse.storage.util.id_generators: [main] Initialising stream generator for access_tokens(id): 3340Oct 19 05:18:45 machine synapse[556]: synapse.storage.util.id_generators: [main] Initialising stream generator for refresh_tokens(id): 1341Oct 19 05:18:45 machine synapse[556]: synapse.storage.util.id_generators: [main] Initialising stream generator for event_reports(id): 1342Oct 19 05:18:45 machine synapse[556]: synapse.storage.util.id_generators: [main] Initialising stream generator for room_reports(id): 1343Oct 19 05:18:45 machine synapse[556]: synapse.storage.util.id_generators: [main] Initialising stream generator for user_reports(id): 1344Oct 19 05:18:45 machine synapse[556]: synapse.storage.databases: [main] [database config 'master']: Starting 'state' database345Oct 19 05:18:45 machine synapse[556]: synapse.storage.databases: [main] [database config 'master']: prepared346Oct 19 05:18:45 machine synapse[556]: synapse.server: [main] Finished setting up.347Oct 19 05:18:45 machine synapse[556]: twisted: [main] Redirected stdout/stderr to logs348Oct 19 05:18:45 machine synapse[556]: synapse.app.homeserver: [main] Running349Oct 19 05:18:45 machine synapse[556]: synapse.app.homeserver: [main] Set file limit to: 524288350Oct 19 05:18:45 machine synapse[556]: synapse.push.pusher: [call_when_running] email enable notifs: False351Oct 19 05:18:45 machine synapse[556]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'profile'352Oct 19 05:18:45 machine synapse[556]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.device_list_update'353Oct 19 05:18:45 machine synapse[556]: synapse.util.caches.lrucache: [call_when_running] Expiring LRU caches after 1800 seconds354Oct 19 05:18:45 machine synapse[556]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.presence'355Oct 19 05:18:45 machine synapse[556]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.typing'356Oct 19 05:18:45 machine synapse[556]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'directory'357Oct 19 05:18:45 machine synapse[556]: synapse.handlers.deactivate_account: [user_parter_loop-0] Starting user parter358Oct 19 05:18:45 machine synapse[556]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.receipt'359Oct 19 05:18:45 machine synapse[556]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.signing_key_update'360Oct 19 05:18:45 machine synapse[556]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'org.matrix.signing_key_update'361Oct 19 05:18:45 machine synapse[556]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'client_keys'362Oct 19 05:18:45 machine synapse[556]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.direct_to_device'363Oct 19 05:18:45 machine synapse[556]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.health.HealthResource object at 0xffffee08b9a0> to path b'/health'364Oct 19 05:18:45 machine synapse[556]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.EncodingResourceWrapper object at 0xffffee3b6220> to path b'/_matrix/client'365Oct 19 05:18:45 machine synapse[556]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffee3bbf20> to path b'/.well-known'366Oct 19 05:18:45 machine synapse[556]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.http.server.JsonResource object at 0xffffee3b63a0> to path b'/_synapse/admin'367Oct 19 05:18:45 machine synapse[556]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.pick_idp.PickIdpResource object at 0xffffee3bc3a0> to path b'/_synapse/client/pick_idp'368Oct 19 05:18:45 machine synapse[556]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffe3e371e0> to path b'/_synapse/client/pick_username'369Oct 19 05:18:45 machine synapse[556]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.new_user_consent.NewUserConsentResource object at 0xffffee3bc9a0> to path b'/_synapse/client/new_user_consent'370Oct 19 05:18:45 machine synapse[556]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.sso_register.SsoRegisterResource object at 0xffffee3bcb20> to path b'/_synapse/client/sso_register'371Oct 19 05:18:45 machine synapse[556]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.unsubscribe.UnsubscribeResource object at 0xffffee3bcca0> to path b'/_synapse/client/unsubscribe'372Oct 19 05:18:45 machine synapse[556]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.media_upload_limit_exceeded.MediaUploadLimitExceededResource object at 0xffffee3bce20> to path b'/_synapse/client/media_upload_limit_exceeded'373Oct 19 05:18:45 machine synapse[556]: synapse.util.httpresourcetree: [call_when_running] Attaching FilePath('/nix/store/928l52anrm0pvcn6k9haijzhbygjrjs5-matrix-synapse-1.162.0/lib/python3.13/site-packages/synapse/static') to path b'/_matrix/static'374Oct 19 05:18:45 machine synapse[556]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee3bd2a0> to path b'/_matrix/media/r0'375Oct 19 05:18:45 machine synapse[556]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee3bd2a0> to path b'/_matrix/media/v3'376Oct 19 05:18:45 machine synapse[556]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee3bd2a0> to path b'/_matrix/media/v1'377Oct 19 05:18:45 machine synapse[556]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.federation.transport.server.TransportLayerServer object at 0xffffee3bdea0> to path b'/_matrix/federation'378Oct 19 05:18:45 machine synapse[556]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.key.v2.KeyResource object at 0xffffee3c1020> to path b'/_matrix/key'379Oct 19 05:18:45 machine synapse[556]: twisted: [call_when_running] SynapseSite starting on 8008380Oct 19 05:18:45 machine synapse[556]: synapse.app._base: [call_when_running] Synapse now listening on TCP port 8008381Oct 19 05:18:45 machine synapse[556]: 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).382Oct 19 05:18:45 machine synapse[556]: synapse.storage.background_updates: [background_updates-0] Starting background schema updates for database master383Oct 19 05:18:45 machine synapse[556]: synapse.handlers.deactivate_account: [user_parter_loop-0] User parter finished: stopping384Oct 19 05:18:45 machine synapse[556]: synapse.push.pusherpool: [start_pushers-0] Started pushers385Oct 19 05:18:45 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[581]: Connection to ::1 8008 port [tcp/http-alt] succeeded!386Oct 19 05:18:46 machine synapse[556]: synapse.access.http.8008: [GET-0] ::1 - 8008 - {None} Processed request: 0.001sec/0.001sec ru=(0.001sec, 0.001sec) db=(0.000sec/0.000sec/0) 140B 200 "GET /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]387Oct 19 05:18:46 machine synapse[556]: synapse.http.server: [POST-1] <XForwardedForRequest at 0xffffe3e32d20 method='POST' uri='/_synapse/admin/v1/register' clientproto='HTTP/1.1' site='8008'> SynapseError: 400 - User ID already taken.388Oct 19 05:18:46 machine synapse[556]: synapse.access.http.8008: [POST-1] ::1 - 8008 - {None} Processed request: 0.249sec/0.000sec ru=(0.244sec, 0.000sec) db=(0.000sec/0.002sec/1) 60B 400 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]389Oct 19 05:18:46 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[582]: Sending registration request...390Oct 19 05:18:46 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[582]: User already exists. Skipping.391Oct 19 05:18:46 machine synapse[556]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'populate_stats_process_rooms'392Oct 19 05:18:46 machine synapse[556]: 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)393Oct 19 05:18:46 machine synapse[556]: 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]394Oct 19 05:18:47 machine synapse[556]: synapse.http.server: [POST-3] <XForwardedForRequest at 0xffffe3e34ee0 method='POST' uri='/_synapse/admin/v1/register' clientproto='HTTP/1.1' site='8008'> SynapseError: 400 - User ID already taken.395Oct 19 05:18:47 machine synapse[556]: synapse.access.http.8008: [POST-3] ::1 - 8008 - {None} Processed request: 0.263sec/0.000sec ru=(0.259sec, 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]396Oct 19 05:18:47 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[588]: Sending registration request...397Oct 19 05:18:47 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[588]: User already exists. Skipping.398Oct 19 05:18:47 machine systemd[1]: Started Synapse Matrix homeserver.399+ systemctl --no-pager show matrix-synapse400+ nc -z -v ::1 8008401Connection to ::1 8008 port [tcp/http-alt] succeeded!402+ curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test'403