container-test-run-matrix-synapse
default.checks.aarch64-linux.matrix-synapse
· build #339
· raw
1additionally exposed symbols:2 machine,3 start_all, machines, driver, Machine, wait_for_signal4Starting machine567<<< NixOS Stage 2 >>>89booting system configuration /nix/store/92n3dcfybfzjaxy38gmmp2qaxc1rg01r-nixos-system-machine-test10running activation script...11setting up /etc...122: host0@if3: <BROADCAST,MULTICAST> mtu 1500 qdisc noop state DOWN mode DEFAULT group default qlen 100013 link/ether 0e:74:26:22:81:c2 brd ff:ff:ff:ff:ff:ff link-netnsid 014setting up age secrets...15starting systemd...16systemd 260.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)17Detected virtualization systemd-nspawn.18Detected architecture arm64.19Detected first boot.20Initializing machine ID from container UUID.21Applying preset policy.22Populated /etc with preset unit settings.23Queued start job for default target Multi-User System.24252627<<< Welcome to NixOS test (aarch64) - console >>>28+ systemd-run /bin/sh -c '/nix/store/vpnizxs18sv6agrsqplsvaml9y3bfx0k-coreutils-9.11/bin/sleep 999999999 && echo b6b39be0-ecab-446a-bd54-de5bc3396883'29Running as unit: run-p473-i87334639.service; invocation ID: 6b201f410aca4ae18f047bf1acd8eef130To attach to container machine run on the same machine that runs the test:31 sudo nsenter --user --target $(\pgrep -f '^/bin/sh.*b6b39be0-ecab-446a-bd54-de5bc3396883') --mount --uts --ipc --net --pid --cgroup /bin/sh -c bash 3233To inject external network and continue test, run:34sudo /nix/store/1bhl5b9821ifsz1dbfm4wxbjbfywmsaq-python3-3.13.14/bin/python3.13 /nix/store/lf6lngb6dzima2m8nqbna4lc6bm1qw85-test-driver-0.0.1/lib/python3.13/site-packages/test_driver/inject_network.py b6b39be0-ecab-446a-bd54-de5bc339688335+ systemctl --no-pager show matrix-synapse36+ systemctl --no-pager show matrix-synapse37+ systemctl --no-pager show matrix-synapse38+ systemctl --no-pager show matrix-synapse39+ systemctl --no-pager show matrix-synapse40+ systemctl --no-pager show matrix-synapse41+ systemctl --no-pager show matrix-synapse42+ systemctl --no-pager show matrix-synapse43+ nc -z -v ::1 800844Connection to ::1 8008 port [tcp/http-alt] succeeded!45machine: waiting for success: curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test'46+ curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test'47(finished: waiting for success: curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test', in 0.02 seconds)48+ systemctl restart matrix-synapse49+ journalctl -u matrix-synapse --no-pager50Aug 13 05:35:03 machine systemd[1]: Starting Synapse Matrix homeserver...51Aug 13 05:35:06 machine matrix-synapse-pre-start[472]: Generating signing key file /var/lib/matrix-synapse/homeserver.signing.key52Aug 13 05:35:09 machine synapse_homeserver[488]: This server is configured to use 'matrix.org' as its trusted key server via the53Aug 13 05:35:09 machine synapse_homeserver[488]: 'trusted_key_servers' config option. 'matrix.org' is a good choice for a key54Aug 13 05:35:09 machine synapse_homeserver[488]: server since it is long-lived, stable and trusted. However, some admins may55Aug 13 05:35:09 machine synapse_homeserver[488]: wish to use another server for this purpose.56Aug 13 05:35:09 machine synapse_homeserver[488]: To suppress this warning and continue using 'matrix.org', admins should set57Aug 13 05:35:09 machine synapse_homeserver[488]: 'suppress_key_server_warning' to 'true' in homeserver.yaml.58Aug 13 05:35:09 machine synapse_homeserver[488]: --------------------------------------------------------------------------------59Aug 13 05:35:09 machine synapse[488]: synapse.config.logger: [main] ***** STARTING SERVER *****60Aug 13 05:35:09 machine synapse[488]: synapse.config.logger: [main] Server /nix/store/wrbw2mlx5755960ihv9l77c8dhrrm3iv-matrix-synapse-1.158.0/bin/synapse_homeserver version 1.158.061Aug 13 05:35:09 machine synapse[488]: synapse.config.logger: [main] Copyright (c) 2023 New Vector, Inc62Aug 13 05:35:09 machine synapse[488]: synapse.config.logger: [main] Licensed under the AGPL 3.0 license. Website: https://github.com/element-hq/synapse63Aug 13 05:35:09 machine synapse[488]: synapse.config.logger: [main] Server hostname: clan.test64Aug 13 05:35:09 machine synapse[488]: synapse.config.logger: [main] Public Base URL: https://clan.test/65Aug 13 05:35:09 machine synapse[488]: synapse.config.logger: [main] Instance name: master66Aug 13 05:35:09 machine synapse[488]: synapse.config.logger: [main] Twisted reactor: EPollReactor67Aug 13 05:35:09 machine synapse[488]: synapse.app.homeserver: [main] Setting up server68Aug 13 05:35:09 machine synapse[488]: synapse.server: [main] Setting up.69Aug 13 05:35:09 machine synapse[488]: synapse.storage.databases: [main] [database config 'master']: Checking database server70Aug 13 05:35:09 machine synapse[488]: synapse.storage.databases: [main] [database config 'master']: Preparing for databases ['main', 'state']71Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] ['main', 'state']: Checking existing schema version72Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] ['main', 'state']: Initialising new database73Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v7374Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/01event_failed_pull_attempts.sql75Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/02add_pusher_enabled.sql76Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/02room_id_indexes_for_purging.sql77Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/03pusher_device_id.sql78Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/03users_approved_column.sql79Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/04partial_join_details.sql80Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/04pending_device_list_updates.sql81Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/05old_push_actions.sql.postgres82Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/06thread_notifications_thread_id_idx.sql83Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/08thread_receipts_non_null.sql.postgres84Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/09partial_joined_via_destination.sql85Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/09threads_table.sql86Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Running 73/10_update_sqlite_fts4_tokenizer.py:run_create87Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/10login_tokens.sql88Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/11event_search_room_id_n_distinct.sql.postgres89Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/12refactor_device_list_outbound_pokes.sql90Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/13add_device_lists_index.sql91Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/20_un_partial_stated_room_stream.sql92Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/21_un_partial_stated_room_stream_seq.sql.postgres93Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/22_rebuild_user_dir_stats.sql94Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/22_un_partial_stated_event_stream.sql95Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/23_fix_thread_index.sql96Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/23_un_partial_stated_room_stream_seq.sql.postgres97Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/24_events_jump_to_date_index.sql98Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 73/25drop_presence.sql99Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v74100Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 74/01_user_directory_stale_remote_users.sql101Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 74/02_set_device_id_for_pushers_bg_update.sql102Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 74/03_membership_tables_event_stream_ordering.sql.postgres103Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 74/03_room_membership_index.sql104Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 74/04_delete_e2e_backup_keys_for_deactivated_users.sql105Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Running 74/04_membership_tables_event_stream_ordering_triggers.py:run_create106Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 74/05_events_txn_id_device_id.sql107Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 74/90COMMENTS_destinations.sql.postgres108Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v75109Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v76110Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 76/01_add_profiles_full_user_id_column.sql111Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 76/02_add_user_filters_full_user_id_column.sql112Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 76/03_per_user_experimental_features.sql113Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 76/04_add_room_forgetter.sql114Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v77115Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/01_add_profiles_not_valid_check.sql.postgres116Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/02_add_user_filters_not_valid_check.sql.postgres117Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 77/03bg_populate_full_user_id_profiles.sql118Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 77/04bg_populate_full_user_id_user_filters.sql119Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 77/05thread_notifications_backfill.sql120Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_actions.sql.postgres121Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_actions_staging.sql.postgres122Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_summary.sql.postgres123Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 77/14bg_indices_event_stream_ordering.sql124Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v78125Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Running 78/03event_extremities_constraints.py:run_create126Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Running 78/04_add_full_user_id_index_user_filters.py:run_create127Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v79128Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 79/03_read_write_locks_triggers.sql.postgres129Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Running 79/04_mitigate_stream_ordering_update_race.py:run_create130Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 79/05_read_write_locks_triggers.sql.postgres131Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v80132Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 80/01_users_alter_locked.sql133Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/02_read_write_locks_unlogged.sql.postgres134Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 80/02_scheduled_tasks.sql135Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/03_read_write_locks_triggers.sql.postgres136Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/04_read_write_locks_deadlock.sql.postgres137Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v81138Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v82139Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 82/02_scheduled_tasks_index.sql140Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 82/04_add_indices_for_purging_rooms.sql141Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 82/05gaps.sql142Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v83143Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 83/01_drop_old_tables.sql144Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 83/05_cross_signing_key_update_grant.sql145Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 83/06_event_push_summary_room.sql146Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v84147Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 84/01_auth_links_stats.sql.postgres148Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 84/02_auth_links_index.sql149Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 84/03_auth_links_analyze.sql.postgres150Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 84/04_access_token_index.sql151Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v85152Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 85/01_add_suspended.sql153Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 85/02_add_instance_names.sql154Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 85/03_new_sequences.sql.postgres155Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 85/04_cleanup_device_federation_outbox.sql156Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 85/05_add_instance_names_converted_pos.sql157Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 85/06_add_room_reports.sql158Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v86159Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 86/01_authenticate_media.sql160Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 86/02_receipts_event_id_index.sql161Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v87162Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 87/01_sliding_sync_memberships.sql163Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 87/02_per_connection_state.sql164Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 87/03_current_state_index.sql165Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v88166Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 88/01_add_delayed_events.sql167Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 88/01_custom_profile_fields.sql168Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 88/02_fix_sliding_sync_membership_snapshots_forgotten_column.sql169Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 88/03_add_otk_ts_added_index.sql170Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 88/04_current_state_delta_index.sql171Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 88/05_drop_old_otks.sql.postgres172Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 88/05_sliding_sync_room_config_index.sql173Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 88/06_events_received_ts_index.sql174Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v89175Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 89/01_sliding_sync_membership_snapshot_index.sql176Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 89/01_state_groups_deletion.sql177Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v90178Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 90/01_add_column_participant_room_memberships_table.sql179Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 90/02_delete_unreferenced_state_groups.sql180Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 90/03_remove_old_deletion_bg_update.sql181Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v91182Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 91/01_media_hash.sql183Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v92184Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/01_remove_trigger.sql.postgres185Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 92/02_remove_populate_participant_bg_update.sql186Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 92/04_ss_membership_snapshot_idx.sql187Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 92/04_thread_subscriptions.sql188Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/04_thread_subscriptions_seq.sql.postgres189Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 92/05_fixup_max_depth_cap.sql190Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/05_thread_subscriptions_comments.sql.postgres191Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 92/06_device_federation_inbox_index.sql192Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/06_threads_last_sent_stream_ordering_comments.sql.postgres193Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 92/07_add_user_reports.sql194Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 92/07_event_txn_id_device_id_txn_id2.sql195Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 92/08_room_ban_redactions.sql196Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/08_thread_subscriptions_seq_fixup.sql.postgres197Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 92/09_thread_subscriptions_update.sql198Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/09_thread_subscriptions_update.sql.postgres199Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v93200Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 93/01_add_delayed_events.sql201Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 93/01_sticky_events.sql202Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 93/01_sticky_events_seq.sql.postgres203Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 93/02_sliding_sync_members.sql204Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 93/03_sss_pos_last_used.sql205Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Running 93/04_make_delayed_event_content_text.py:run_create206Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema deltas for v94207Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 94/01_redactions_recheck.sql208Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 94/02_redactions_recheck_bg_update.sql209Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 94/03_device_lists_room_timestamp.sql210Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 94/03_quarantined_media_tracking.sql211Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying engine-specific schema 94/03_quarantined_media_tracking_seq.sql.postgres212Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 94/03_state_dag_fwd_extrems.sql213Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 94/04_device_lists_changes_max_pruned.sql214Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 94/05_rerun_flag_existing_quarantined_media.sql215Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 94/06_sliding_sync_connections_last_used_ts_index.sql216Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Applying schema 94/07_sliding_sync_lazy_members_position_index.sql217Aug 13 05:35:09 machine synapse[488]: synapse.storage.prepare_database: [main] Schema now up to date218Aug 13 05:35:09 machine synapse[488]: synapse.storage.databases: [main] [database config 'master']: Starting 'main' database219Aug 13 05:35:09 machine synapse[488]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules(id): 1220Aug 13 05:35:09 machine synapse[488]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules_enable(id): 1221Aug 13 05:35:09 machine synapse[488]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 month ago222Aug 13 05:35:09 machine synapse[488]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 month ago: it's 0223Aug 13 05:35:09 machine synapse[488]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 day ago224Aug 13 05:35:09 machine synapse[488]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 day ago: it's 0225Aug 13 05:35:09 machine synapse[488]: synapse.storage.util.id_generators: [main] Initialising stream generator for access_tokens(id): 1226Aug 13 05:35:09 machine synapse[488]: synapse.storage.util.id_generators: [main] Initialising stream generator for refresh_tokens(id): 1227Aug 13 05:35:09 machine synapse[488]: synapse.storage.util.id_generators: [main] Initialising stream generator for event_reports(id): 1228Aug 13 05:35:09 machine synapse[488]: synapse.storage.util.id_generators: [main] Initialising stream generator for room_reports(id): 1229Aug 13 05:35:09 machine synapse[488]: synapse.storage.util.id_generators: [main] Initialising stream generator for user_reports(id): 1230Aug 13 05:35:09 machine synapse[488]: synapse.storage.databases: [main] [database config 'master']: Starting 'state' database231Aug 13 05:35:09 machine synapse[488]: synapse.storage.databases: [main] [database config 'master']: prepared232Aug 13 05:35:09 machine synapse[488]: synapse.server: [main] Finished setting up.233Aug 13 05:35:09 machine synapse[488]: twisted: [main] Redirected stdout/stderr to logs234Aug 13 05:35:09 machine synapse[488]: synapse.app.homeserver: [main] Running235Aug 13 05:35:09 machine synapse[488]: synapse.app.homeserver: [main] Set file limit to: 524288236Aug 13 05:35:09 machine synapse[488]: synapse.push.pusher: [call_when_running] email enable notifs: False237Aug 13 05:35:10 machine synapse[488]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'profile'238Aug 13 05:35:10 machine synapse[488]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.device_list_update'239Aug 13 05:35:10 machine synapse[488]: synapse.util.caches.lrucache: [call_when_running] Expiring LRU caches after 1800 seconds240Aug 13 05:35:10 machine synapse[488]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.presence'241Aug 13 05:35:10 machine synapse[488]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.typing'242Aug 13 05:35:10 machine synapse[488]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'directory'243Aug 13 05:35:10 machine synapse[488]: synapse.handlers.deactivate_account: [user_parter_loop-0] Starting user parter244Aug 13 05:35:10 machine synapse[488]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.receipt'245Aug 13 05:35:10 machine synapse[488]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.signing_key_update'246Aug 13 05:35:10 machine synapse[488]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'org.matrix.signing_key_update'247Aug 13 05:35:10 machine synapse[488]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'client_keys'248Aug 13 05:35:10 machine synapse[488]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.direct_to_device'249Aug 13 05:35:10 machine synapse[488]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.health.HealthResource object at 0xffffee029ea0> to path b'/health'250Aug 13 05:35:10 machine synapse[488]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.EncodingResourceWrapper object at 0xffffee302f20> to path b'/_matrix/client'251Aug 13 05:35:10 machine synapse[488]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffee308c20> to path b'/.well-known'252Aug 13 05:35:10 machine synapse[488]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.http.server.JsonResource object at 0xffffee3030a0> to path b'/_synapse/admin'253Aug 13 05:35:10 machine synapse[488]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.pick_idp.PickIdpResource object at 0xffffee3090a0> to path b'/_synapse/client/pick_idp'254Aug 13 05:35:10 machine synapse[488]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffedcaf8a0> to path b'/_synapse/client/pick_username'255Aug 13 05:35:10 machine synapse[488]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.new_user_consent.NewUserConsentResource object at 0xffffee3096a0> to path b'/_synapse/client/new_user_consent'256Aug 13 05:35:10 machine synapse[488]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.sso_register.SsoRegisterResource object at 0xffffee309820> to path b'/_synapse/client/sso_register'257Aug 13 05:35:10 machine synapse[488]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.unsubscribe.UnsubscribeResource object at 0xffffee3099a0> to path b'/_synapse/client/unsubscribe'258Aug 13 05:35:10 machine synapse[488]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.media_upload_limit_exceeded.MediaUploadLimitExceededResource object at 0xffffee309b20> to path b'/_synapse/client/media_upload_limit_exceeded'259Aug 13 05:35:10 machine synapse[488]: synapse.util.httpresourcetree: [call_when_running] Attaching FilePath('/nix/store/wrbw2mlx5755960ihv9l77c8dhrrm3iv-matrix-synapse-1.158.0/lib/python3.13/site-packages/synapse/static') to path b'/_matrix/static'260Aug 13 05:35:10 machine synapse[488]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee309fa0> to path b'/_matrix/media/r0'261Aug 13 05:35:10 machine synapse[488]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee309fa0> to path b'/_matrix/media/v3'262Aug 13 05:35:10 machine synapse[488]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee309fa0> to path b'/_matrix/media/v1'263Aug 13 05:35:10 machine synapse[488]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.federation.transport.server.TransportLayerServer object at 0xffffee30aba0> to path b'/_matrix/federation'264Aug 13 05:35:10 machine synapse[488]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.key.v2.KeyResource object at 0xffffee30dd20> to path b'/_matrix/key'265Aug 13 05:35:10 machine synapse[488]: twisted: [call_when_running] SynapseSite starting on 8008266Aug 13 05:35:10 machine synapse[488]: synapse.app._base: [call_when_running] Synapse now listening on TCP port 8008267Aug 13 05:35:10 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).268Aug 13 05:35:10 machine synapse[488]: synapse.storage.background_updates: [background_updates-0] Starting background schema updates for database master269Aug 13 05:35:10 machine synapse[488]: synapse.handlers.deactivate_account: [user_parter_loop-0] User parter finished: stopping270Aug 13 05:35:10 machine synapse[488]: synapse.push.pusherpool: [start_pushers-0] Started pushers271Aug 13 05:35:10 machine bnzgj7n6gry2s8ppq7j2akw0vsk6df4m-matrix-synapse-create-users[516]: Connection to ::1 8008 port [tcp/http-alt] succeeded!272Aug 13 05:35:10 machine synapse[488]: synapse.access.http.8008: [GET-0] ::1 - 8008 - {None} Processed request: 0.001sec/0.001sec ru=(0.001sec, 0.002sec) db=(0.000sec/0.000sec/0) 140B 200 "GET /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]273Aug 13 05:35:11 machine synapse[488]: synapse.handlers.auth: [POST-1] Logging in user @admin:clan.test on device UJDGFHFGFE274Aug 13 05:35:11 machine synapse[488]: synapse.access.http.8008: [POST-1] ::1 - 8008 - {None} Processed request: 0.287sec/0.001sec ru=(0.244sec, 0.007sec) db=(0.005sec/0.029sec/12) 138B 200 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]275Aug 13 05:35:11 machine bnzgj7n6gry2s8ppq7j2akw0vsk6df4m-matrix-synapse-create-users[517]: Sending registration request...276Aug 13 05:35:11 machine bnzgj7n6gry2s8ppq7j2akw0vsk6df4m-matrix-synapse-create-users[517]: Success!277Aug 13 05:35:11 machine synapse[488]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'threads_backfill'278Aug 13 05:35:11 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)279Aug 13 05:35:11 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]280Aug 13 05:35:11 machine synapse[488]: synapse.handlers.auth: [POST-3] Logging in user @someuser:clan.test on device CGEXRLOGBF281Aug 13 05:35:11 machine synapse[488]: synapse.access.http.8008: [POST-3] ::1 - 8008 - {None} Processed request: 0.271sec/0.001sec ru=(0.240sec, 0.002sec) db=(0.004sec/0.018sec/12) 145B 200 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]282Aug 13 05:35:11 machine bnzgj7n6gry2s8ppq7j2akw0vsk6df4m-matrix-synapse-create-users[524]: Sending registration request...283Aug 13 05:35:11 machine bnzgj7n6gry2s8ppq7j2akw0vsk6df4m-matrix-synapse-create-users[524]: Success!284Aug 13 05:35:11 machine systemd[1]: Started Synapse Matrix homeserver.285Aug 13 05:35:12 machine synapse[488]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'device_lists_changes_in_room_by_room_index'286Aug 13 05:35:12 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_room287Aug 13 05:35:12 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 6ms. (total_rate=0.16666666666666666/ms, current_rate=0.16666666666666666/ms, total_updated=1, batch_size=100)288Aug 13 05:35:12 machine synapse[488]: synapse.access.http.8008: [GET-4] ::1 - 8008 - {None} Processed request: 0.001sec/-0.000sec ru=(0.000sec, 0.000sec) db=(0.000sec/0.000sec/0) 10145B 200 "GET /_matrix/static/ HTTP/1.1" "curl/8.21.0" [0 dbevts]289Aug 13 05:35:12 machine systemd[1]: Stopping Synapse Matrix homeserver...290Aug 13 05:35:12 machine synapse[488]: twisted: [sentinel] Received SIGTERM, shutting down.291Aug 13 05:35:12 machine synapse[488]: synapse.storage.databases.main.lock: [LockStore._on_shutdown-0] Dropping held locks due to shutdown292Aug 13 05:35:12 machine synapse[488]: synapse.storage.databases.main.lock: [LockStore._on_shutdown-0] Dropped locks due to shutdown293Aug 13 05:35:12 machine synapse[488]: synapse.handlers.presence: [PresenceHandler._on_shutdown-0] Performing _on_shutdown. Persisting 0 unpersisted changes294Aug 13 05:35:12 machine synapse[488]: synapse.handlers.presence: [PresenceHandler._on_shutdown-0] Finished _on_shutdown295Aug 13 05:35:12 machine synapse[488]: synapse.app._base: [log_shutdown] Shutting down...296Aug 13 05:35:12 machine synapse[488]: twisted: [sentinel] (TCP Port 8008 Closed)297Aug 13 05:35:12 machine synapse[488]: twisted: [sentinel] Main loop terminated.298Aug 13 05:35:12 machine systemd[1]: matrix-synapse.service: Deactivated successfully.299Aug 13 05:35:12 machine systemd[1]: Stopped Synapse Matrix homeserver.300Aug 13 05:35:12 machine systemd[1]: matrix-synapse.service: Consumed 7.743s CPU time over 8.699s wall clock time.301Aug 13 05:35:12 machine systemd[1]: Starting Synapse Matrix homeserver...302Aug 13 05:35:17 machine synapse_homeserver[556]: This server is configured to use 'matrix.org' as its trusted key server via the303Aug 13 05:35:17 machine synapse_homeserver[556]: 'trusted_key_servers' config option. 'matrix.org' is a good choice for a key304Aug 13 05:35:17 machine synapse_homeserver[556]: server since it is long-lived, stable and trusted. However, some admins may305Aug 13 05:35:17 machine synapse_homeserver[556]: wish to use another server for this purpose.306Aug 13 05:35:17 machine synapse_homeserver[556]: To suppress this warning and continue using 'matrix.org', admins should set307Aug 13 05:35:17 machine synapse_homeserver[556]: 'suppress_key_server_warning' to 'true' in homeserver.yaml.308Aug 13 05:35:17 machine synapse_homeserver[556]: --------------------------------------------------------------------------------309Aug 13 05:35:17 machine synapse[556]: synapse.config.logger: [main] ***** STARTING SERVER *****310Aug 13 05:35:17 machine synapse[556]: synapse.config.logger: [main] Server /nix/store/wrbw2mlx5755960ihv9l77c8dhrrm3iv-matrix-synapse-1.158.0/bin/synapse_homeserver version 1.158.0311Aug 13 05:35:17 machine synapse[556]: synapse.config.logger: [main] Copyright (c) 2023 New Vector, Inc312Aug 13 05:35:17 machine synapse[556]: synapse.config.logger: [main] Licensed under the AGPL 3.0 license. Website: https://github.com/element-hq/synapse313Aug 13 05:35:17 machine synapse[556]: synapse.config.logger: [main] Server hostname: clan.test314Aug 13 05:35:17 machine synapse[556]: synapse.config.logger: [main] Public Base URL: https://clan.test/315Aug 13 05:35:17 machine synapse[556]: synapse.config.logger: [main] Instance name: master316Aug 13 05:35:17 machine synapse[556]: synapse.config.logger: [main] Twisted reactor: EPollReactor317Aug 13 05:35:17 machine synapse[556]: synapse.app.homeserver: [main] Setting up server318Aug 13 05:35:17 machine synapse[556]: synapse.server: [main] Setting up.319Aug 13 05:35:17 machine synapse[556]: synapse.storage.databases: [main] [database config 'master']: Checking database server320Aug 13 05:35:17 machine synapse[556]: synapse.storage.databases: [main] [database config 'master']: Preparing for databases ['main', 'state']321Aug 13 05:35:17 machine synapse[556]: synapse.storage.prepare_database: [main] ['main', 'state']: Checking existing schema version322Aug 13 05:35:17 machine synapse[556]: synapse.storage.prepare_database: [main] ['main', 'state']: Existing schema is 94 (+10 deltas)323Aug 13 05:35:17 machine synapse[556]: synapse.storage.databases.main: [main] Checking database for consistency with configuration...324Aug 13 05:35:17 machine synapse[556]: synapse.storage.prepare_database: [main] Applying schema deltas for v94325Aug 13 05:35:17 machine synapse[556]: synapse.storage.prepare_database: [main] Schema now up to date326Aug 13 05:35:17 machine synapse[556]: synapse.storage.databases: [main] [database config 'master']: Starting 'main' database327Aug 13 05:35:17 machine synapse[556]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules(id): 1328Aug 13 05:35:17 machine synapse[556]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules_enable(id): 1329Aug 13 05:35:17 machine synapse[556]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 month ago330Aug 13 05:35:17 machine synapse[556]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 month ago: it's 0331Aug 13 05:35:17 machine synapse[556]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 day ago332Aug 13 05:35:17 machine synapse[556]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 day ago: it's 0333Aug 13 05:35:17 machine synapse[556]: synapse.storage.util.id_generators: [main] Initialising stream generator for access_tokens(id): 3334Aug 13 05:35:17 machine synapse[556]: synapse.storage.util.id_generators: [main] Initialising stream generator for refresh_tokens(id): 1335Aug 13 05:35:17 machine synapse[556]: synapse.storage.util.id_generators: [main] Initialising stream generator for event_reports(id): 1336Aug 13 05:35:17 machine synapse[556]: synapse.storage.util.id_generators: [main] Initialising stream generator for room_reports(id): 1337Aug 13 05:35:17 machine synapse[556]: synapse.storage.util.id_generators: [main] Initialising stream generator for user_reports(id): 1338Aug 13 05:35:17 machine synapse[556]: synapse.storage.databases: [main] [database config 'master']: Starting 'state' database339Aug 13 05:35:17 machine synapse[556]: synapse.storage.databases: [main] [database config 'master']: prepared340Aug 13 05:35:17 machine synapse[556]: synapse.server: [main] Finished setting up.341Aug 13 05:35:17 machine synapse[556]: twisted: [main] Redirected stdout/stderr to logs342Aug 13 05:35:17 machine synapse[556]: synapse.app.homeserver: [main] Running343Aug 13 05:35:17 machine synapse[556]: synapse.app.homeserver: [main] Set file limit to: 524288344Aug 13 05:35:18 machine synapse[556]: synapse.push.pusher: [call_when_running] email enable notifs: False345Aug 13 05:35:18 machine synapse[556]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'profile'346Aug 13 05:35:18 machine synapse[556]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.device_list_update'347Aug 13 05:35:18 machine synapse[556]: synapse.util.caches.lrucache: [call_when_running] Expiring LRU caches after 1800 seconds348Aug 13 05:35:18 machine synapse[556]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.presence'349Aug 13 05:35:18 machine synapse[556]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.typing'350Aug 13 05:35:18 machine synapse[556]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'directory'351Aug 13 05:35:18 machine synapse[556]: synapse.handlers.deactivate_account: [user_parter_loop-0] Starting user parter352Aug 13 05:35:18 machine synapse[556]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.receipt'353Aug 13 05:35:18 machine synapse[556]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.signing_key_update'354Aug 13 05:35:18 machine synapse[556]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'org.matrix.signing_key_update'355Aug 13 05:35:18 machine synapse[556]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'client_keys'356Aug 13 05:35:18 machine synapse[556]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.direct_to_device'357Aug 13 05:35:18 machine synapse[556]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.health.HealthResource object at 0xffffee02a020> to path b'/health'358Aug 13 05:35:18 machine synapse[556]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.EncodingResourceWrapper object at 0xffffee2c30a0> to path b'/_matrix/client'359Aug 13 05:35:18 machine synapse[556]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffee2c8da0> to path b'/.well-known'360Aug 13 05:35:18 machine synapse[556]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.http.server.JsonResource object at 0xffffee2c3220> to path b'/_synapse/admin'361Aug 13 05:35:18 machine synapse[556]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.pick_idp.PickIdpResource object at 0xffffee2c9220> to path b'/_synapse/client/pick_idp'362Aug 13 05:35:18 machine synapse[556]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffedcef760> to path b'/_synapse/client/pick_username'363Aug 13 05:35:18 machine synapse[556]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.new_user_consent.NewUserConsentResource object at 0xffffee2c9820> to path b'/_synapse/client/new_user_consent'364Aug 13 05:35:18 machine synapse[556]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.sso_register.SsoRegisterResource object at 0xffffee2c99a0> to path b'/_synapse/client/sso_register'365Aug 13 05:35:18 machine synapse[556]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.unsubscribe.UnsubscribeResource object at 0xffffee2c9b20> to path b'/_synapse/client/unsubscribe'366Aug 13 05:35:18 machine synapse[556]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.media_upload_limit_exceeded.MediaUploadLimitExceededResource object at 0xffffee2c9ca0> to path b'/_synapse/client/media_upload_limit_exceeded'367Aug 13 05:35:18 machine synapse[556]: synapse.util.httpresourcetree: [call_when_running] Attaching FilePath('/nix/store/wrbw2mlx5755960ihv9l77c8dhrrm3iv-matrix-synapse-1.158.0/lib/python3.13/site-packages/synapse/static') to path b'/_matrix/static'368Aug 13 05:35:18 machine synapse[556]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee2ca120> to path b'/_matrix/media/r0'369Aug 13 05:35:18 machine synapse[556]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee2ca120> to path b'/_matrix/media/v3'370Aug 13 05:35:18 machine synapse[556]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee2ca120> to path b'/_matrix/media/v1'371Aug 13 05:35:18 machine synapse[556]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.federation.transport.server.TransportLayerServer object at 0xffffee2cad20> to path b'/_matrix/federation'372Aug 13 05:35:18 machine synapse[556]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.key.v2.KeyResource object at 0xffffee2cdea0> to path b'/_matrix/key'373Aug 13 05:35:18 machine synapse[556]: twisted: [call_when_running] SynapseSite starting on 8008374Aug 13 05:35:18 machine synapse[556]: synapse.app._base: [call_when_running] Synapse now listening on TCP port 8008375Aug 13 05:35:18 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).376Aug 13 05:35:18 machine synapse[556]: synapse.storage.background_updates: [background_updates-0] Starting background schema updates for database master377Aug 13 05:35:18 machine synapse[556]: synapse.handlers.deactivate_account: [user_parter_loop-0] User parter finished: stopping378Aug 13 05:35:18 machine synapse[556]: synapse.push.pusherpool: [start_pushers-0] Started pushers379Aug 13 05:35:18 machine bnzgj7n6gry2s8ppq7j2akw0vsk6df4m-matrix-synapse-create-users[581]: Connection to ::1 8008 port [tcp/http-alt] succeeded!380Aug 13 05:35:18 machine synapse[556]: synapse.access.http.8008: [GET-0] ::1 - 8008 - {None} Processed request: 0.001sec/0.001sec ru=(0.002sec, 0.000sec) db=(0.000sec/0.000sec/0) 140B 200 "GET /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]381Aug 13 05:35:18 machine synapse[556]: synapse.http.server: [POST-1] <XForwardedForRequest at 0xffffe1602460 method='POST' uri='/_synapse/admin/v1/register' clientproto='HTTP/1.1' site='8008'> SynapseError: 400 - User ID already taken.382Aug 13 05:35:18 machine synapse[556]: synapse.access.http.8008: [POST-1] ::1 - 8008 - {None} Processed request: 0.236sec/0.000sec ru=(0.230sec, 0.000sec) db=(0.000sec/0.001sec/1) 60B 400 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]383Aug 13 05:35:18 machine bnzgj7n6gry2s8ppq7j2akw0vsk6df4m-matrix-synapse-create-users[582]: Sending registration request...384Aug 13 05:35:18 machine bnzgj7n6gry2s8ppq7j2akw0vsk6df4m-matrix-synapse-create-users[582]: User already exists. Skipping.385Aug 13 05:35:19 machine synapse[556]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'populate_stats_process_rooms'386Aug 13 05:35:19 machine synapse[556]: synapse.storage.background_updates: [background_updates-0] Running background update 'populate_stats_process_rooms'. Processed 1 items in 5ms. (total_rate=0.2/ms, current_rate=0.2/ms, total_updated=1, batch_size=100)387Aug 13 05:35:19 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]388Aug 13 05:35:19 machine synapse[556]: synapse.http.server: [POST-3] <XForwardedForRequest at 0xffffedce0ee0 method='POST' uri='/_synapse/admin/v1/register' clientproto='HTTP/1.1' site='8008'> SynapseError: 400 - User ID already taken.389Aug 13 05:35:19 machine synapse[556]: synapse.access.http.8008: [POST-3] ::1 - 8008 - {None} Processed request: 0.235sec/0.000sec ru=(0.231sec, 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]390Aug 13 05:35:19 machine bnzgj7n6gry2s8ppq7j2akw0vsk6df4m-matrix-synapse-create-users[588]: Sending registration request...391Aug 13 05:35:19 machine bnzgj7n6gry2s8ppq7j2akw0vsk6df4m-matrix-synapse-create-users[588]: User already exists. Skipping.392Aug 13 05:35:19 machine systemd[1]: Started Synapse Matrix homeserver.393+ systemctl --no-pager show matrix-synapse394+ nc -z -v ::1 8008395Connection to ::1 8008 port [tcp/http-alt] succeeded!396+ curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test'397