container-test-run-matrix-synapse
default.checks.aarch64-linux.matrix-synapse
· build #337
· raw
1additionally exposed symbols:2 machine,3 start_all, machines, driver, Machine, wait_for_signal4Starting machine5678<<< NixOS Stage 2 >>>910booting system configuration /nix/store/ca3dlnzcmxpcqikbwxqb82pmhhj77aqr-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 261.1 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/dqxknsb71lsgzfzcdmn6x7fzssqbdyyx-coreutils-9.11/bin/sleep 999999999 && echo ed0d4842-f8fb-4bb3-b315-0e1a163f42a2'30Running as unit: run-p485-i86835804.service; invocation ID: 618922d4ee244330be65b023397bdc0831To attach to container machine run on the same machine that runs the test:32 sudo nsenter --user --target $(\pgrep -f '^/bin/sh.*ed0d4842-f8fb-4bb3-b315-0e1a163f42a2') --mount --uts --ipc --net --pid --cgroup /bin/sh -c bash 3334To inject external network and continue test, run:35sudo /nix/store/3inr3nxzqarhb9rh1z7s2s20j8c0395h-python3-3.14.7/bin/python3.14 /nix/store/yrfr6y1wjgrgr3g63rypc096h0dyh5rx-test-driver-0.0.1/lib/python3.14/site-packages/test_driver/inject_network.py ed0d4842-f8fb-4bb3-b315-0e1a163f42a236+ 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+ systemctl --no-pager show matrix-synapse44+ nc -z -v ::1 800845Connection to ::1 8008 port [tcp/http-alt] succeeded!46machine: waiting for success: curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test'47+ curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test'48(finished: waiting for success: curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test', in 0.02 seconds)49+ systemctl restart matrix-synapse50+ journalctl -u matrix-synapse --no-pager51Aug 13 05:08:53 machine systemd[1]: Starting Synapse Matrix homeserver...52Aug 13 05:08:55 machine matrix-synapse-pre-start[480]: Generating signing key file /var/lib/matrix-synapse/homeserver.signing.key53Aug 13 05:08:58 machine synapse_homeserver[496]: This server is configured to use 'matrix.org' as its trusted key server via the54Aug 13 05:08:58 machine synapse_homeserver[496]: 'trusted_key_servers' config option. 'matrix.org' is a good choice for a key55Aug 13 05:08:58 machine synapse_homeserver[496]: server since it is long-lived, stable and trusted. However, some admins may56Aug 13 05:08:58 machine synapse_homeserver[496]: wish to use another server for this purpose.57Aug 13 05:08:58 machine synapse_homeserver[496]: To suppress this warning and continue using 'matrix.org', admins should set58Aug 13 05:08:58 machine synapse_homeserver[496]: 'suppress_key_server_warning' to 'true' in homeserver.yaml.59Aug 13 05:08:58 machine synapse_homeserver[496]: --------------------------------------------------------------------------------60Aug 13 05:08:58 machine synapse[496]: synapse.config.logger: [main] ***** STARTING SERVER *****61Aug 13 05:08:58 machine synapse[496]: synapse.config.logger: [main] Server /nix/store/ynmr7xm8lmd435rsfmwl7f0nq7lpdd3i-matrix-synapse-1.158.0/bin/synapse_homeserver version 1.158.062Aug 13 05:08:58 machine synapse[496]: synapse.config.logger: [main] Copyright (c) 2023 New Vector, Inc63Aug 13 05:08:58 machine synapse[496]: synapse.config.logger: [main] Licensed under the AGPL 3.0 license. Website: https://github.com/element-hq/synapse64Aug 13 05:08:58 machine synapse[496]: synapse.config.logger: [main] Server hostname: clan.test65Aug 13 05:08:58 machine synapse[496]: synapse.config.logger: [main] Public Base URL: https://clan.test/66Aug 13 05:08:58 machine synapse[496]: synapse.config.logger: [main] Instance name: master67Aug 13 05:08:58 machine synapse[496]: synapse.config.logger: [main] Twisted reactor: EPollReactor68Aug 13 05:08:58 machine synapse[496]: synapse.app.homeserver: [main] Setting up server69Aug 13 05:08:58 machine synapse[496]: synapse.server: [main] Setting up.70Aug 13 05:08:58 machine synapse[496]: synapse.storage.databases: [main] [database config 'master']: Checking database server71Aug 13 05:08:58 machine synapse[496]: synapse.storage.databases: [main] [database config 'master']: Preparing for databases ['main', 'state']72Aug 13 05:08:58 machine synapse[496]: synapse.storage.prepare_database: [main] ['main', 'state']: Checking existing schema version73Aug 13 05:08:58 machine synapse[496]: synapse.storage.prepare_database: [main] ['main', 'state']: Initialising new database74Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema deltas for v7375Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 73/01event_failed_pull_attempts.sql76Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 73/02add_pusher_enabled.sql77Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 73/02room_id_indexes_for_purging.sql78Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 73/03pusher_device_id.sql79Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 73/03users_approved_column.sql80Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 73/04partial_join_details.sql81Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 73/04pending_device_list_updates.sql82Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/05old_push_actions.sql.postgres83Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 73/06thread_notifications_thread_id_idx.sql84Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/08thread_receipts_non_null.sql.postgres85Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 73/09partial_joined_via_destination.sql86Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 73/09threads_table.sql87Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Running 73/10_update_sqlite_fts4_tokenizer.py:run_create88Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 73/10login_tokens.sql89Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/11event_search_room_id_n_distinct.sql.postgres90Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 73/12refactor_device_list_outbound_pokes.sql91Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 73/13add_device_lists_index.sql92Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 73/20_un_partial_stated_room_stream.sql93Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/21_un_partial_stated_room_stream_seq.sql.postgres94Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 73/22_rebuild_user_dir_stats.sql95Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 73/22_un_partial_stated_event_stream.sql96Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 73/23_fix_thread_index.sql97Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/23_un_partial_stated_room_stream_seq.sql.postgres98Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 73/24_events_jump_to_date_index.sql99Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 73/25drop_presence.sql100Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema deltas for v74101Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 74/01_user_directory_stale_remote_users.sql102Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 74/02_set_device_id_for_pushers_bg_update.sql103Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying engine-specific schema 74/03_membership_tables_event_stream_ordering.sql.postgres104Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 74/03_room_membership_index.sql105Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 74/04_delete_e2e_backup_keys_for_deactivated_users.sql106Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Running 74/04_membership_tables_event_stream_ordering_triggers.py:run_create107Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 74/05_events_txn_id_device_id.sql108Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying engine-specific schema 74/90COMMENTS_destinations.sql.postgres109Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema deltas for v75110Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema deltas for v76111Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 76/01_add_profiles_full_user_id_column.sql112Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 76/02_add_user_filters_full_user_id_column.sql113Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 76/03_per_user_experimental_features.sql114Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 76/04_add_room_forgetter.sql115Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema deltas for v77116Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/01_add_profiles_not_valid_check.sql.postgres117Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/02_add_user_filters_not_valid_check.sql.postgres118Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 77/03bg_populate_full_user_id_profiles.sql119Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 77/04bg_populate_full_user_id_user_filters.sql120Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 77/05thread_notifications_backfill.sql121Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_actions.sql.postgres122Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_actions_staging.sql.postgres123Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_summary.sql.postgres124Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 77/14bg_indices_event_stream_ordering.sql125Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema deltas for v78126Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Running 78/03event_extremities_constraints.py:run_create127Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Running 78/04_add_full_user_id_index_user_filters.py:run_create128Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema deltas for v79129Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying engine-specific schema 79/03_read_write_locks_triggers.sql.postgres130Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Running 79/04_mitigate_stream_ordering_update_race.py:run_create131Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying engine-specific schema 79/05_read_write_locks_triggers.sql.postgres132Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema deltas for v80133Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 80/01_users_alter_locked.sql134Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/02_read_write_locks_unlogged.sql.postgres135Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 80/02_scheduled_tasks.sql136Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/03_read_write_locks_triggers.sql.postgres137Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/04_read_write_locks_deadlock.sql.postgres138Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema deltas for v81139Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema deltas for v82140Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 82/02_scheduled_tasks_index.sql141Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 82/04_add_indices_for_purging_rooms.sql142Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 82/05gaps.sql143Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema deltas for v83144Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 83/01_drop_old_tables.sql145Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 83/05_cross_signing_key_update_grant.sql146Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 83/06_event_push_summary_room.sql147Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema deltas for v84148Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying engine-specific schema 84/01_auth_links_stats.sql.postgres149Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 84/02_auth_links_index.sql150Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying engine-specific schema 84/03_auth_links_analyze.sql.postgres151Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 84/04_access_token_index.sql152Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema deltas for v85153Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 85/01_add_suspended.sql154Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 85/02_add_instance_names.sql155Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying engine-specific schema 85/03_new_sequences.sql.postgres156Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 85/04_cleanup_device_federation_outbox.sql157Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 85/05_add_instance_names_converted_pos.sql158Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 85/06_add_room_reports.sql159Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema deltas for v86160Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 86/01_authenticate_media.sql161Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 86/02_receipts_event_id_index.sql162Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema deltas for v87163Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 87/01_sliding_sync_memberships.sql164Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 87/02_per_connection_state.sql165Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 87/03_current_state_index.sql166Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema deltas for v88167Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 88/01_add_delayed_events.sql168Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 88/01_custom_profile_fields.sql169Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 88/02_fix_sliding_sync_membership_snapshots_forgotten_column.sql170Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 88/03_add_otk_ts_added_index.sql171Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 88/04_current_state_delta_index.sql172Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying engine-specific schema 88/05_drop_old_otks.sql.postgres173Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 88/05_sliding_sync_room_config_index.sql174Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 88/06_events_received_ts_index.sql175Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema deltas for v89176Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 89/01_sliding_sync_membership_snapshot_index.sql177Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 89/01_state_groups_deletion.sql178Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema deltas for v90179Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 90/01_add_column_participant_room_memberships_table.sql180Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 90/02_delete_unreferenced_state_groups.sql181Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 90/03_remove_old_deletion_bg_update.sql182Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema deltas for v91183Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 91/01_media_hash.sql184Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema deltas for v92185Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/01_remove_trigger.sql.postgres186Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 92/02_remove_populate_participant_bg_update.sql187Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 92/04_ss_membership_snapshot_idx.sql188Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 92/04_thread_subscriptions.sql189Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/04_thread_subscriptions_seq.sql.postgres190Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 92/05_fixup_max_depth_cap.sql191Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/05_thread_subscriptions_comments.sql.postgres192Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 92/06_device_federation_inbox_index.sql193Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/06_threads_last_sent_stream_ordering_comments.sql.postgres194Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 92/07_add_user_reports.sql195Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 92/07_event_txn_id_device_id_txn_id2.sql196Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 92/08_room_ban_redactions.sql197Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/08_thread_subscriptions_seq_fixup.sql.postgres198Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 92/09_thread_subscriptions_update.sql199Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/09_thread_subscriptions_update.sql.postgres200Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema deltas for v93201Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 93/01_add_delayed_events.sql202Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 93/01_sticky_events.sql203Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying engine-specific schema 93/01_sticky_events_seq.sql.postgres204Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 93/02_sliding_sync_members.sql205Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 93/03_sss_pos_last_used.sql206Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Running 93/04_make_delayed_event_content_text.py:run_create207Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema deltas for v94208Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 94/01_redactions_recheck.sql209Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 94/02_redactions_recheck_bg_update.sql210Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 94/03_device_lists_room_timestamp.sql211Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 94/03_quarantined_media_tracking.sql212Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying engine-specific schema 94/03_quarantined_media_tracking_seq.sql.postgres213Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 94/03_state_dag_fwd_extrems.sql214Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 94/04_device_lists_changes_max_pruned.sql215Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 94/05_rerun_flag_existing_quarantined_media.sql216Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 94/06_sliding_sync_connections_last_used_ts_index.sql217Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Applying schema 94/07_sliding_sync_lazy_members_position_index.sql218Aug 13 05:08:59 machine synapse[496]: synapse.storage.prepare_database: [main] Schema now up to date219Aug 13 05:08:59 machine synapse[496]: synapse.storage.databases: [main] [database config 'master']: Starting 'main' database220Aug 13 05:08:59 machine synapse[496]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules(id): 1221Aug 13 05:08:59 machine synapse[496]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules_enable(id): 1222Aug 13 05:08:59 machine synapse[496]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 month ago223Aug 13 05:08:59 machine synapse[496]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 month ago: it's 0224Aug 13 05:08:59 machine synapse[496]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 day ago225Aug 13 05:08:59 machine synapse[496]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 day ago: it's 0226Aug 13 05:08:59 machine synapse[496]: synapse.storage.util.id_generators: [main] Initialising stream generator for access_tokens(id): 1227Aug 13 05:08:59 machine synapse[496]: synapse.storage.util.id_generators: [main] Initialising stream generator for refresh_tokens(id): 1228Aug 13 05:08:59 machine synapse[496]: synapse.storage.util.id_generators: [main] Initialising stream generator for event_reports(id): 1229Aug 13 05:08:59 machine synapse[496]: synapse.storage.util.id_generators: [main] Initialising stream generator for room_reports(id): 1230Aug 13 05:08:59 machine synapse[496]: synapse.storage.util.id_generators: [main] Initialising stream generator for user_reports(id): 1231Aug 13 05:08:59 machine synapse[496]: synapse.storage.databases: [main] [database config 'master']: Starting 'state' database232Aug 13 05:08:59 machine synapse[496]: synapse.storage.databases: [main] [database config 'master']: prepared233Aug 13 05:08:59 machine synapse[496]: synapse.server: [main] Finished setting up.234Aug 13 05:08:59 machine synapse[496]: twisted: [main] Redirected stdout/stderr to logs235Aug 13 05:08:59 machine synapse[496]: synapse.app.homeserver: [main] Running236Aug 13 05:08:59 machine synapse[496]: synapse.app.homeserver: [main] Set file limit to: 524288237Aug 13 05:08:59 machine synapse[496]: synapse.push.pusher: [call_when_running] email enable notifs: False238Aug 13 05:08:59 machine synapse[496]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'profile'239Aug 13 05:08:59 machine synapse[496]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.device_list_update'240Aug 13 05:08:59 machine synapse[496]: synapse.util.caches.lrucache: [call_when_running] Expiring LRU caches after 1800 seconds241Aug 13 05:08:59 machine synapse[496]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.presence'242Aug 13 05:08:59 machine synapse[496]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.typing'243Aug 13 05:08:59 machine synapse[496]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'directory'244Aug 13 05:08:59 machine synapse[496]: synapse.handlers.deactivate_account: [user_parter_loop-0] Starting user parter245Aug 13 05:08:59 machine synapse[496]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.receipt'246Aug 13 05:08:59 machine synapse[496]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.signing_key_update'247Aug 13 05:08:59 machine synapse[496]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'org.matrix.signing_key_update'248Aug 13 05:08:59 machine synapse[496]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'client_keys'249Aug 13 05:08:59 machine synapse[496]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.direct_to_device'250Aug 13 05:08:59 machine synapse[496]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.health.HealthResource object at 0xffffec5a4720> to path b'/health'251Aug 13 05:08:59 machine synapse[496]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.EncodingResourceWrapper object at 0xffffec5bc420> to path b'/_matrix/client'252Aug 13 05:08:59 machine synapse[496]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffec892120> to path b'/.well-known'253Aug 13 05:08:59 machine synapse[496]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.http.server.JsonResource object at 0xffffec5bc5a0> to path b'/_synapse/admin'254Aug 13 05:08:59 machine synapse[496]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.pick_idp.PickIdpResource object at 0xffffec8925a0> to path b'/_synapse/client/pick_idp'255Aug 13 05:08:59 machine synapse[496]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffec3d2160> to path b'/_synapse/client/pick_username'256Aug 13 05:08:59 machine synapse[496]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.new_user_consent.NewUserConsentResource object at 0xffffec892ba0> to path b'/_synapse/client/new_user_consent'257Aug 13 05:08:59 machine synapse[496]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.sso_register.SsoRegisterResource object at 0xffffec892d20> to path b'/_synapse/client/sso_register'258Aug 13 05:08:59 machine synapse[496]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.unsubscribe.UnsubscribeResource object at 0xffffec892ea0> to path b'/_synapse/client/unsubscribe'259Aug 13 05:08:59 machine synapse[496]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.media_upload_limit_exceeded.MediaUploadLimitExceededResource object at 0xffffec893020> to path b'/_synapse/client/media_upload_limit_exceeded'260Aug 13 05:08:59 machine synapse[496]: synapse.util.httpresourcetree: [call_when_running] Attaching FilePath('/nix/store/ynmr7xm8lmd435rsfmwl7f0nq7lpdd3i-matrix-synapse-1.158.0/lib/python3.14/site-packages/synapse/static') to path b'/_matrix/static'261Aug 13 05:08:59 machine synapse[496]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffec8934a0> to path b'/_matrix/media/r0'262Aug 13 05:08:59 machine synapse[496]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffec8934a0> to path b'/_matrix/media/v3'263Aug 13 05:08:59 machine synapse[496]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffec8934a0> to path b'/_matrix/media/v1'264Aug 13 05:08:59 machine synapse[496]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.federation.transport.server.TransportLayerServer object at 0xffffec8940a0> to path b'/_matrix/federation'265Aug 13 05:08:59 machine synapse[496]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.key.v2.KeyResource object at 0xffffec897220> to path b'/_matrix/key'266Aug 13 05:08:59 machine synapse[496]: twisted: [call_when_running] SynapseSite starting on 8008267Aug 13 05:08:59 machine synapse[496]: synapse.app._base: [call_when_running] Synapse now listening on TCP port 8008268Aug 13 05:08:59 machine synapse[496]: 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).269Aug 13 05:08:59 machine synapse[496]: synapse.storage.background_updates: [background_updates-0] Starting background schema updates for database master270Aug 13 05:08:59 machine synapse[496]: synapse.push.pusherpool: [start_pushers-0] Started pushers271Aug 13 05:08:59 machine synapse[496]: synapse.handlers.deactivate_account: [user_parter_loop-0] User parter finished: stopping272Aug 13 05:08:59 machine alkhdnwkv91nib462fjs1i9868l7nd2v-matrix-synapse-create-users[524]: Connection to ::1 8008 port [tcp/http-alt] succeeded!273Aug 13 05:09:00 machine synapse[496]: 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.34.2" [0 dbevts]274Aug 13 05:09:00 machine synapse[496]: synapse.handlers.auth: [POST-1] Logging in user @admin:clan.test on device SWUDPZCMJD275Aug 13 05:09:00 machine synapse[496]: synapse.access.http.8008: [POST-1] ::1 - 8008 - {None} Processed request: 0.271sec/0.001sec ru=(0.238sec, 0.005sec) db=(0.004sec/0.022sec/12) 138B 200 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.34.2" [0 dbevts]276Aug 13 05:09:00 machine alkhdnwkv91nib462fjs1i9868l7nd2v-matrix-synapse-create-users[525]: Sending registration request...277Aug 13 05:09:00 machine alkhdnwkv91nib462fjs1i9868l7nd2v-matrix-synapse-create-users[525]: Success!278Aug 13 05:09:00 machine synapse[496]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'threads_backfill'279Aug 13 05:09:00 machine synapse[496]: synapse.storage.background_updates: [background_updates-0] Running background update 'threads_backfill'. Processed 0 items in 3ms. (total_rate=None/ms, current_rate=None/ms, total_updated=0, batch_size=100)280Aug 13 05:09:01 machine synapse[496]: synapse.access.http.8008: [GET-2] ::1 - 8008 - {None} Processed request: 0.000sec/0.000sec ru=(0.000sec, 0.000sec) db=(0.000sec/0.000sec/0) 140B 200 "GET /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.34.2" [0 dbevts]281Aug 13 05:09:01 machine synapse[496]: synapse.handlers.auth: [POST-3] Logging in user @someuser:clan.test on device QNQVRQRGZM282Aug 13 05:09:01 machine synapse[496]: synapse.access.http.8008: [POST-3] ::1 - 8008 - {None} Processed request: 0.260sec/0.001sec ru=(0.236sec, 0.002sec) db=(0.004sec/0.012sec/12) 145B 200 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.34.2" [0 dbevts]283Aug 13 05:09:01 machine alkhdnwkv91nib462fjs1i9868l7nd2v-matrix-synapse-create-users[532]: Sending registration request...284Aug 13 05:09:01 machine alkhdnwkv91nib462fjs1i9868l7nd2v-matrix-synapse-create-users[532]: Success!285Aug 13 05:09:01 machine systemd[1]: Started Synapse Matrix homeserver.286Aug 13 05:09:01 machine synapse[496]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'device_lists_changes_in_room_by_room_index'287Aug 13 05:09:01 machine synapse[496]: synapse.storage.background_updates: [background_updates-0] Adding index device_lists_changes_in_room_by_room_idx to device_lists_changes_in_room288Aug 13 05:09:01 machine synapse[496]: synapse.storage.background_updates: [background_updates-0] Running background update 'device_lists_changes_in_room_by_room_index'. Processed 1 items in 5ms. (total_rate=0.2/ms, current_rate=0.2/ms, total_updated=1, batch_size=100)289Aug 13 05:09:02 machine synapse[496]: 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]290Aug 13 05:09:02 machine systemd[1]: Stopping Synapse Matrix homeserver...291Aug 13 05:09:02 machine synapse[496]: twisted: [sentinel] Received SIGTERM, shutting down.292Aug 13 05:09:02 machine synapse[496]: synapse.storage.databases.main.lock: [LockStore._on_shutdown-0] Dropping held locks due to shutdown293Aug 13 05:09:02 machine synapse[496]: synapse.storage.databases.main.lock: [LockStore._on_shutdown-0] Dropped locks due to shutdown294Aug 13 05:09:02 machine synapse[496]: synapse.handlers.presence: [PresenceHandler._on_shutdown-0] Performing _on_shutdown. Persisting 0 unpersisted changes295Aug 13 05:09:02 machine synapse[496]: synapse.handlers.presence: [PresenceHandler._on_shutdown-0] Finished _on_shutdown296Aug 13 05:09:02 machine synapse[496]: synapse.app._base: [log_shutdown] Shutting down...297Aug 13 05:09:02 machine synapse[496]: twisted: [sentinel] (TCP Port 8008 Closed)298Aug 13 05:09:02 machine synapse[496]: twisted: [sentinel] Main loop terminated.299Aug 13 05:09:02 machine systemd[1]: matrix-synapse.service: Deactivated successfully.300Aug 13 05:09:02 machine systemd[1]: Stopped Synapse Matrix homeserver.301Aug 13 05:09:02 machine systemd[1]: matrix-synapse.service: Consumed 7.868s CPU time over 9.299s wall clock time.302Aug 13 05:09:02 machine systemd[1]: Starting Synapse Matrix homeserver...303Aug 13 05:09:07 machine synapse_homeserver[564]: This server is configured to use 'matrix.org' as its trusted key server via the304Aug 13 05:09:07 machine synapse_homeserver[564]: 'trusted_key_servers' config option. 'matrix.org' is a good choice for a key305Aug 13 05:09:07 machine synapse_homeserver[564]: server since it is long-lived, stable and trusted. However, some admins may306Aug 13 05:09:07 machine synapse_homeserver[564]: wish to use another server for this purpose.307Aug 13 05:09:07 machine synapse_homeserver[564]: To suppress this warning and continue using 'matrix.org', admins should set308Aug 13 05:09:07 machine synapse_homeserver[564]: 'suppress_key_server_warning' to 'true' in homeserver.yaml.309Aug 13 05:09:07 machine synapse_homeserver[564]: --------------------------------------------------------------------------------310Aug 13 05:09:07 machine synapse[564]: synapse.config.logger: [main] ***** STARTING SERVER *****311Aug 13 05:09:07 machine synapse[564]: synapse.config.logger: [main] Server /nix/store/ynmr7xm8lmd435rsfmwl7f0nq7lpdd3i-matrix-synapse-1.158.0/bin/synapse_homeserver version 1.158.0312Aug 13 05:09:07 machine synapse[564]: synapse.config.logger: [main] Copyright (c) 2023 New Vector, Inc313Aug 13 05:09:07 machine synapse[564]: synapse.config.logger: [main] Licensed under the AGPL 3.0 license. Website: https://github.com/element-hq/synapse314Aug 13 05:09:07 machine synapse[564]: synapse.config.logger: [main] Server hostname: clan.test315Aug 13 05:09:07 machine synapse[564]: synapse.config.logger: [main] Public Base URL: https://clan.test/316Aug 13 05:09:07 machine synapse[564]: synapse.config.logger: [main] Instance name: master317Aug 13 05:09:07 machine synapse[564]: synapse.config.logger: [main] Twisted reactor: EPollReactor318Aug 13 05:09:07 machine synapse[564]: synapse.app.homeserver: [main] Setting up server319Aug 13 05:09:07 machine synapse[564]: synapse.server: [main] Setting up.320Aug 13 05:09:08 machine synapse[564]: synapse.storage.databases: [main] [database config 'master']: Checking database server321Aug 13 05:09:08 machine synapse[564]: synapse.storage.databases: [main] [database config 'master']: Preparing for databases ['main', 'state']322Aug 13 05:09:08 machine synapse[564]: synapse.storage.prepare_database: [main] ['main', 'state']: Checking existing schema version323Aug 13 05:09:08 machine synapse[564]: synapse.storage.prepare_database: [main] ['main', 'state']: Existing schema is 94 (+10 deltas)324Aug 13 05:09:08 machine synapse[564]: synapse.storage.databases.main: [main] Checking database for consistency with configuration...325Aug 13 05:09:08 machine synapse[564]: synapse.storage.prepare_database: [main] Applying schema deltas for v94326Aug 13 05:09:08 machine synapse[564]: synapse.storage.prepare_database: [main] Schema now up to date327Aug 13 05:09:08 machine synapse[564]: synapse.storage.databases: [main] [database config 'master']: Starting 'main' database328Aug 13 05:09:08 machine synapse[564]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules(id): 1329Aug 13 05:09:08 machine synapse[564]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules_enable(id): 1330Aug 13 05:09:08 machine synapse[564]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 month ago331Aug 13 05:09:08 machine synapse[564]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 month ago: it's 0332Aug 13 05:09:08 machine synapse[564]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 day ago333Aug 13 05:09:08 machine synapse[564]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 day ago: it's 0334Aug 13 05:09:08 machine synapse[564]: synapse.storage.util.id_generators: [main] Initialising stream generator for access_tokens(id): 3335Aug 13 05:09:08 machine synapse[564]: synapse.storage.util.id_generators: [main] Initialising stream generator for refresh_tokens(id): 1336Aug 13 05:09:08 machine synapse[564]: synapse.storage.util.id_generators: [main] Initialising stream generator for event_reports(id): 1337Aug 13 05:09:08 machine synapse[564]: synapse.storage.util.id_generators: [main] Initialising stream generator for room_reports(id): 1338Aug 13 05:09:08 machine synapse[564]: synapse.storage.util.id_generators: [main] Initialising stream generator for user_reports(id): 1339Aug 13 05:09:08 machine synapse[564]: synapse.storage.databases: [main] [database config 'master']: Starting 'state' database340Aug 13 05:09:08 machine synapse[564]: synapse.storage.databases: [main] [database config 'master']: prepared341Aug 13 05:09:08 machine synapse[564]: synapse.server: [main] Finished setting up.342Aug 13 05:09:08 machine synapse[564]: twisted: [main] Redirected stdout/stderr to logs343Aug 13 05:09:08 machine synapse[564]: synapse.app.homeserver: [main] Running344Aug 13 05:09:08 machine synapse[564]: synapse.app.homeserver: [main] Set file limit to: 524288345Aug 13 05:09:08 machine synapse[564]: synapse.push.pusher: [call_when_running] email enable notifs: False346Aug 13 05:09:08 machine synapse[564]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'profile'347Aug 13 05:09:08 machine synapse[564]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.device_list_update'348Aug 13 05:09:08 machine synapse[564]: synapse.util.caches.lrucache: [call_when_running] Expiring LRU caches after 1800 seconds349Aug 13 05:09:08 machine synapse[564]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.presence'350Aug 13 05:09:08 machine synapse[564]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.typing'351Aug 13 05:09:08 machine synapse[564]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'directory'352Aug 13 05:09:08 machine synapse[564]: synapse.handlers.deactivate_account: [user_parter_loop-0] Starting user parter353Aug 13 05:09:08 machine synapse[564]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.receipt'354Aug 13 05:09:08 machine synapse[564]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.signing_key_update'355Aug 13 05:09:08 machine synapse[564]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'org.matrix.signing_key_update'356Aug 13 05:09:08 machine synapse[564]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'client_keys'357Aug 13 05:09:08 machine synapse[564]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.direct_to_device'358Aug 13 05:09:08 machine synapse[564]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.health.HealthResource object at 0xffffec654ea0> to path b'/health'359Aug 13 05:09:08 machine synapse[564]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.EncodingResourceWrapper object at 0xffffec66cba0> to path b'/_matrix/client'360Aug 13 05:09:08 machine synapse[564]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffec8628a0> to path b'/.well-known'361Aug 13 05:09:08 machine synapse[564]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.http.server.JsonResource object at 0xffffec66cd20> to path b'/_synapse/admin'362Aug 13 05:09:08 machine synapse[564]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.pick_idp.PickIdpResource object at 0xffffec862d20> to path b'/_synapse/client/pick_idp'363Aug 13 05:09:08 machine synapse[564]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffec3d28e0> to path b'/_synapse/client/pick_username'364Aug 13 05:09:08 machine synapse[564]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.new_user_consent.NewUserConsentResource object at 0xffffec863320> to path b'/_synapse/client/new_user_consent'365Aug 13 05:09:08 machine synapse[564]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.sso_register.SsoRegisterResource object at 0xffffec8634a0> to path b'/_synapse/client/sso_register'366Aug 13 05:09:08 machine synapse[564]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.unsubscribe.UnsubscribeResource object at 0xffffec863620> to path b'/_synapse/client/unsubscribe'367Aug 13 05:09:08 machine synapse[564]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.media_upload_limit_exceeded.MediaUploadLimitExceededResource object at 0xffffec8637a0> to path b'/_synapse/client/media_upload_limit_exceeded'368Aug 13 05:09:08 machine synapse[564]: synapse.util.httpresourcetree: [call_when_running] Attaching FilePath('/nix/store/ynmr7xm8lmd435rsfmwl7f0nq7lpdd3i-matrix-synapse-1.158.0/lib/python3.14/site-packages/synapse/static') to path b'/_matrix/static'369Aug 13 05:09:08 machine synapse[564]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffec863c20> to path b'/_matrix/media/r0'370Aug 13 05:09:08 machine synapse[564]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffec863c20> to path b'/_matrix/media/v3'371Aug 13 05:09:08 machine synapse[564]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffec863c20> to path b'/_matrix/media/v1'372Aug 13 05:09:08 machine synapse[564]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.federation.transport.server.TransportLayerServer object at 0xffffec864820> to path b'/_matrix/federation'373Aug 13 05:09:08 machine synapse[564]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.key.v2.KeyResource object at 0xffffec8679a0> to path b'/_matrix/key'374Aug 13 05:09:08 machine synapse[564]: twisted: [call_when_running] SynapseSite starting on 8008375Aug 13 05:09:08 machine synapse[564]: synapse.app._base: [call_when_running] Synapse now listening on TCP port 8008376Aug 13 05:09:08 machine synapse[564]: 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).377Aug 13 05:09:08 machine synapse[564]: synapse.storage.background_updates: [background_updates-0] Starting background schema updates for database master378Aug 13 05:09:08 machine synapse[564]: synapse.handlers.deactivate_account: [user_parter_loop-0] User parter finished: stopping379Aug 13 05:09:08 machine synapse[564]: synapse.push.pusherpool: [start_pushers-0] Started pushers380Aug 13 05:09:08 machine alkhdnwkv91nib462fjs1i9868l7nd2v-matrix-synapse-create-users[589]: Connection to ::1 8008 port [tcp/http-alt] succeeded!381Aug 13 05:09:08 machine synapse[564]: synapse.access.http.8008: [GET-0] ::1 - 8008 - {None} Processed request: 0.001sec/0.001sec ru=(0.002sec, 0.001sec) db=(0.000sec/0.000sec/0) 140B 200 "GET /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.34.2" [0 dbevts]382Aug 13 05:09:09 machine synapse[564]: synapse.http.server: [POST-1] <XForwardedForRequest at 0xffffec3d46e0 method='POST' uri='/_synapse/admin/v1/register' clientproto='HTTP/1.1' site='8008'> SynapseError: 400 - User ID already taken.383Aug 13 05:09:09 machine synapse[564]: synapse.access.http.8008: [POST-1] ::1 - 8008 - {None} Processed request: 0.247sec/0.001sec ru=(0.241sec, 0.000sec) db=(0.000sec/0.001sec/1) 60B 400 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.34.2" [0 dbevts]384Aug 13 05:09:09 machine alkhdnwkv91nib462fjs1i9868l7nd2v-matrix-synapse-create-users[590]: Sending registration request...385Aug 13 05:09:09 machine alkhdnwkv91nib462fjs1i9868l7nd2v-matrix-synapse-create-users[590]: User already exists. Skipping.386Aug 13 05:09:09 machine synapse[564]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'populate_stats_process_rooms'387Aug 13 05:09:09 machine synapse[564]: 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)388Aug 13 05:09:09 machine synapse[564]: synapse.access.http.8008: [GET-2] ::1 - 8008 - {None} Processed request: 0.000sec/0.000sec ru=(0.000sec, 0.000sec) db=(0.000sec/0.000sec/0) 140B 200 "GET /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.34.2" [0 dbevts]389Aug 13 05:09:09 machine synapse[564]: synapse.http.server: [POST-3] <XForwardedForRequest at 0xffffec3a4760 method='POST' uri='/_synapse/admin/v1/register' clientproto='HTTP/1.1' site='8008'> SynapseError: 400 - User ID already taken.390Aug 13 05:09:09 machine synapse[564]: synapse.access.http.8008: [POST-3] ::1 - 8008 - {None} Processed request: 0.240sec/0.000sec ru=(0.234sec, 0.000sec) db=(0.000sec/0.001sec/1) 60B 400 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.34.2" [0 dbevts]391Aug 13 05:09:09 machine alkhdnwkv91nib462fjs1i9868l7nd2v-matrix-synapse-create-users[596]: Sending registration request...392Aug 13 05:09:09 machine alkhdnwkv91nib462fjs1i9868l7nd2v-matrix-synapse-create-users[596]: User already exists. Skipping.393Aug 13 05:09:09 machine systemd[1]: Started Synapse Matrix homeserver.394+ systemctl --no-pager show matrix-synapse395+ nc -z -v ::1 8008396Connection to ::1 8008 port [tcp/http-alt] succeeded!397+ curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test'398