container-test-run-matrix-synapse
checks.aarch64-linux.matrix-synapse
· build #828
· raw
1build01: building wzgfvvphm0z3driq06wqbqq6da5gjfg8-container-test-run-matrix-synapse.drv2additionally exposed symbols:3 machine,4 start_all, machines, driver, Machine, wait_for_signal5Starting machine678<<< NixOS Stage 2 >>>910booting system configuration /nix/store/0w2dj4sqannd465k3vwqvwg9syj1y7is-nixos-system-machine-test11running activation script...12setting up /etc...132: host0@if3: <BROADCAST,MULTICAST> mtu 1500 qdisc noop state DOWN mode DEFAULT group default qlen 100014 link/ether 0e:74:26:22:81:c2 brd ff:ff:ff:ff:ff:ff link-netnsid 015setting up age secrets...16starting systemd...17systemd 260.4 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/fl0k50cdb33a5xxzlzmz5782yw66rj4r-coreutils-9.11/bin/sleep 999999999 && echo 13cd4d39-b84a-4be9-a92d-73ef3e54ea2d'30Running as unit: run-p481-i29662673.service; invocation ID: f6cb704059ab443e83a92068b15cef2a31To attach to container machine run on the same machine that runs the test:32 sudo nsenter --user --target $(\pgrep -f '^/bin/sh.*13cd4d39-b84a-4be9-a92d-73ef3e54ea2d') --mount --uts --ipc --net --pid --cgroup /bin/sh -c bash 3334To inject external network and continue test, run:35sudo /nix/store/76jq0mngg7m8zv5yjz1qgg0xspzi8x2w-python3-3.13.15/bin/python3.13 /nix/store/1v002aslhnn1b7kr1fq8m14lldran2b0-test-driver-0.0.1/lib/python3.13/site-packages/test_driver/inject_network.py 13cd4d39-b84a-4be9-a92d-73ef3e54ea2d36+ 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-pager50Oct 02 05:35:18 machine systemd[1]: Starting Synapse Matrix homeserver...51Oct 02 05:35:21 machine matrix-synapse-pre-start[476]: Generating signing key file /var/lib/matrix-synapse/homeserver.signing.key52Oct 02 05:35:24 machine synapse_homeserver[491]: This server is configured to use 'matrix.org' as its trusted key server via the53Oct 02 05:35:24 machine synapse_homeserver[491]: 'trusted_key_servers' config option. 'matrix.org' is a good choice for a key54Oct 02 05:35:24 machine synapse_homeserver[491]: server since it is long-lived, stable and trusted. However, some admins may55Oct 02 05:35:24 machine synapse_homeserver[491]: wish to use another server for this purpose.56Oct 02 05:35:24 machine synapse_homeserver[491]: To suppress this warning and continue using 'matrix.org', admins should set57Oct 02 05:35:24 machine synapse_homeserver[491]: 'suppress_key_server_warning' to 'true' in homeserver.yaml.58Oct 02 05:35:24 machine synapse_homeserver[491]: --------------------------------------------------------------------------------59Oct 02 05:35:24 machine synapse[491]: synapse.config.logger: [main] ***** STARTING SERVER *****60Oct 02 05:35:24 machine synapse[491]: synapse.config.logger: [main] Server /nix/store/724bvq231d0dja7f12fkk1rxcwm76nyq-matrix-synapse-1.162.0/bin/synapse_homeserver version 1.162.061Oct 02 05:35:24 machine synapse[491]: synapse.config.logger: [main] Copyright (c) 2023 New Vector, Inc62Oct 02 05:35:24 machine synapse[491]: synapse.config.logger: [main] Licensed under the AGPL 3.0 license. Website: https://github.com/element-hq/synapse63Oct 02 05:35:24 machine synapse[491]: synapse.config.logger: [main] Server hostname: clan.test64Oct 02 05:35:24 machine synapse[491]: synapse.config.logger: [main] Public Base URL: https://clan.test/65Oct 02 05:35:24 machine synapse[491]: synapse.config.logger: [main] Instance name: master66Oct 02 05:35:24 machine synapse[491]: synapse.config.logger: [main] Twisted reactor: EPollReactor67Oct 02 05:35:24 machine synapse[491]: synapse.app.homeserver: [main] Setting up server68Oct 02 05:35:24 machine synapse[491]: synapse.server: [main] Setting up.69Oct 02 05:35:24 machine synapse[491]: synapse.storage.databases: [main] [database config 'master']: Checking database server70Oct 02 05:35:24 machine synapse[491]: synapse.storage.databases: [main] [database config 'master']: Preparing for databases ['main', 'state']71Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] ['main', 'state']: Checking existing schema version72Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] ['main', 'state']: Initialising new database73Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v7374Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/01event_failed_pull_attempts.sql75Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/02add_pusher_enabled.sql76Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/02room_id_indexes_for_purging.sql77Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/03pusher_device_id.sql78Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/03users_approved_column.sql79Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/04partial_join_details.sql80Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/04pending_device_list_updates.sql81Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/05old_push_actions.sql.postgres82Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/06thread_notifications_thread_id_idx.sql83Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/08thread_receipts_non_null.sql.postgres84Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/09partial_joined_via_destination.sql85Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/09threads_table.sql86Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Running 73/10_update_sqlite_fts4_tokenizer.py:run_create87Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/10login_tokens.sql88Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/11event_search_room_id_n_distinct.sql.postgres89Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/12refactor_device_list_outbound_pokes.sql90Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/13add_device_lists_index.sql91Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/20_un_partial_stated_room_stream.sql92Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/21_un_partial_stated_room_stream_seq.sql.postgres93Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/22_rebuild_user_dir_stats.sql94Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/22_un_partial_stated_event_stream.sql95Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/23_fix_thread_index.sql96Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/23_un_partial_stated_room_stream_seq.sql.postgres97Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/24_events_jump_to_date_index.sql98Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/25drop_presence.sql99Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v74100Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 74/01_user_directory_stale_remote_users.sql101Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 74/02_set_device_id_for_pushers_bg_update.sql102Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 74/03_membership_tables_event_stream_ordering.sql.postgres103Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 74/03_room_membership_index.sql104Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 74/04_delete_e2e_backup_keys_for_deactivated_users.sql105Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Running 74/04_membership_tables_event_stream_ordering_triggers.py:run_create106Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 74/05_events_txn_id_device_id.sql107Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 74/90COMMENTS_destinations.sql.postgres108Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v75109Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v76110Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 76/01_add_profiles_full_user_id_column.sql111Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 76/02_add_user_filters_full_user_id_column.sql112Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 76/03_per_user_experimental_features.sql113Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 76/04_add_room_forgetter.sql114Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v77115Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/01_add_profiles_not_valid_check.sql.postgres116Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/02_add_user_filters_not_valid_check.sql.postgres117Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 77/03bg_populate_full_user_id_profiles.sql118Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 77/04bg_populate_full_user_id_user_filters.sql119Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 77/05thread_notifications_backfill.sql120Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_actions.sql.postgres121Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_actions_staging.sql.postgres122Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_summary.sql.postgres123Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 77/14bg_indices_event_stream_ordering.sql124Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v78125Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Running 78/03event_extremities_constraints.py:run_create126Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Running 78/04_add_full_user_id_index_user_filters.py:run_create127Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v79128Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 79/03_read_write_locks_triggers.sql.postgres129Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Running 79/04_mitigate_stream_ordering_update_race.py:run_create130Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 79/05_read_write_locks_triggers.sql.postgres131Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v80132Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 80/01_users_alter_locked.sql133Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/02_read_write_locks_unlogged.sql.postgres134Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 80/02_scheduled_tasks.sql135Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/03_read_write_locks_triggers.sql.postgres136Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/04_read_write_locks_deadlock.sql.postgres137Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v81138Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v82139Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 82/02_scheduled_tasks_index.sql140Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 82/04_add_indices_for_purging_rooms.sql141Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 82/05gaps.sql142Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v83143Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 83/01_drop_old_tables.sql144Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 83/05_cross_signing_key_update_grant.sql145Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 83/06_event_push_summary_room.sql146Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v84147Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 84/01_auth_links_stats.sql.postgres148Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 84/02_auth_links_index.sql149Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 84/03_auth_links_analyze.sql.postgres150Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 84/04_access_token_index.sql151Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v85152Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 85/01_add_suspended.sql153Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 85/02_add_instance_names.sql154Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 85/03_new_sequences.sql.postgres155Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 85/04_cleanup_device_federation_outbox.sql156Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 85/05_add_instance_names_converted_pos.sql157Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 85/06_add_room_reports.sql158Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v86159Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 86/01_authenticate_media.sql160Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 86/02_receipts_event_id_index.sql161Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v87162Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 87/01_sliding_sync_memberships.sql163Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 87/02_per_connection_state.sql164Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 87/03_current_state_index.sql165Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v88166Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 88/01_add_delayed_events.sql167Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 88/01_custom_profile_fields.sql168Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 88/02_fix_sliding_sync_membership_snapshots_forgotten_column.sql169Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 88/03_add_otk_ts_added_index.sql170Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 88/04_current_state_delta_index.sql171Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 88/05_drop_old_otks.sql.postgres172Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 88/05_sliding_sync_room_config_index.sql173Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 88/06_events_received_ts_index.sql174Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v89175Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 89/01_sliding_sync_membership_snapshot_index.sql176Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 89/01_state_groups_deletion.sql177Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v90178Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 90/01_add_column_participant_room_memberships_table.sql179Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 90/02_delete_unreferenced_state_groups.sql180Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 90/03_remove_old_deletion_bg_update.sql181Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v91182Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 91/01_media_hash.sql183Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v92184Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/01_remove_trigger.sql.postgres185Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/02_remove_populate_participant_bg_update.sql186Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/04_ss_membership_snapshot_idx.sql187Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/04_thread_subscriptions.sql188Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/04_thread_subscriptions_seq.sql.postgres189Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/05_fixup_max_depth_cap.sql190Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/05_thread_subscriptions_comments.sql.postgres191Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/06_device_federation_inbox_index.sql192Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/06_threads_last_sent_stream_ordering_comments.sql.postgres193Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/07_add_user_reports.sql194Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/07_event_txn_id_device_id_txn_id2.sql195Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/08_room_ban_redactions.sql196Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/08_thread_subscriptions_seq_fixup.sql.postgres197Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/09_thread_subscriptions_update.sql198Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/09_thread_subscriptions_update.sql.postgres199Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v93200Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 93/01_add_delayed_events.sql201Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 93/01_sticky_events.sql202Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 93/01_sticky_events_seq.sql.postgres203Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 93/02_sliding_sync_members.sql204Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 93/03_sss_pos_last_used.sql205Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Running 93/04_make_delayed_event_content_text.py:run_create206Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v94207Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/01_redactions_recheck.sql208Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 94/01_state_groups_persisting_inserted_ts.sql.postgres209Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/02_redactions_recheck_bg_update.sql210Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/03_device_lists_room_timestamp.sql211Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/03_quarantined_media_tracking.sql212Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 94/03_quarantined_media_tracking_seq.sql.postgres213Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/03_state_dag_fwd_extrems.sql214Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/04_device_lists_changes_max_pruned.sql215Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/05_rerun_flag_existing_quarantined_media.sql216Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/06_sliding_sync_connections_last_used_ts_index.sql217Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/07_profile_updates.sql218Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 94/07_profile_updates_seq.sql.postgres219Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/07_sliding_sync_lazy_members_position_index.sql220Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/08_device_lists_changes_in_room_unconverted_idx.sql221Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/09_e2e_cross_signing_signatures_index.sql222Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/10_e2e_cross_signing_signatures_add_key_id_to_index.sql223Oct 02 05:35:24 machine synapse[491]: synapse.storage.prepare_database: [main] Schema now up to date224Oct 02 05:35:24 machine synapse[491]: synapse.storage.databases: [main] [database config 'master']: Starting 'main' database225Oct 02 05:35:24 machine synapse[491]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules(id): 1226Oct 02 05:35:24 machine synapse[491]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules_enable(id): 1227Oct 02 05:35:24 machine synapse[491]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 month ago228Oct 02 05:35:24 machine synapse[491]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 month ago: it's 0229Oct 02 05:35:24 machine synapse[491]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 day ago230Oct 02 05:35:24 machine synapse[491]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 day ago: it's 0231Oct 02 05:35:25 machine synapse[491]: synapse.storage.util.id_generators: [main] Initialising stream generator for access_tokens(id): 1232Oct 02 05:35:25 machine synapse[491]: synapse.storage.util.id_generators: [main] Initialising stream generator for refresh_tokens(id): 1233Oct 02 05:35:25 machine synapse[491]: synapse.storage.util.id_generators: [main] Initialising stream generator for event_reports(id): 1234Oct 02 05:35:25 machine synapse[491]: synapse.storage.util.id_generators: [main] Initialising stream generator for room_reports(id): 1235Oct 02 05:35:25 machine synapse[491]: synapse.storage.util.id_generators: [main] Initialising stream generator for user_reports(id): 1236Oct 02 05:35:25 machine synapse[491]: synapse.storage.databases: [main] [database config 'master']: Starting 'state' database237Oct 02 05:35:25 machine synapse[491]: synapse.storage.databases: [main] [database config 'master']: prepared238Oct 02 05:35:25 machine synapse[491]: synapse.server: [main] Finished setting up.239Oct 02 05:35:25 machine synapse[491]: twisted: [main] Redirected stdout/stderr to logs240Oct 02 05:35:25 machine synapse[491]: synapse.app.homeserver: [main] Running241Oct 02 05:35:25 machine synapse[491]: synapse.app.homeserver: [main] Set file limit to: 524288242Oct 02 05:35:25 machine synapse[491]: synapse.push.pusher: [call_when_running] email enable notifs: False243Oct 02 05:35:25 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'profile'244Oct 02 05:35:25 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.device_list_update'245Oct 02 05:35:25 machine synapse[491]: synapse.util.caches.lrucache: [call_when_running] Expiring LRU caches after 1800 seconds246Oct 02 05:35:25 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.presence'247Oct 02 05:35:25 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.typing'248Oct 02 05:35:25 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'directory'249Oct 02 05:35:25 machine synapse[491]: synapse.handlers.deactivate_account: [user_parter_loop-0] Starting user parter250Oct 02 05:35:25 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.receipt'251Oct 02 05:35:25 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.signing_key_update'252Oct 02 05:35:25 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'org.matrix.signing_key_update'253Oct 02 05:35:25 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'client_keys'254Oct 02 05:35:25 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.direct_to_device'255Oct 02 05:35:25 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.health.HealthResource object at 0xffffee08b820> to path b'/health'256Oct 02 05:35:25 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.EncodingResourceWrapper object at 0xffffee3c60a0> to path b'/_matrix/client'257Oct 02 05:35:25 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffee3cbda0> to path b'/.well-known'258Oct 02 05:35:25 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.http.server.JsonResource object at 0xffffee3c6220> to path b'/_synapse/admin'259Oct 02 05:35:25 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.pick_idp.PickIdpResource object at 0xffffee3cc220> to path b'/_synapse/client/pick_idp'260Oct 02 05:35:25 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffe3e47320> to path b'/_synapse/client/pick_username'261Oct 02 05:35:25 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.new_user_consent.NewUserConsentResource object at 0xffffee3cc820> to path b'/_synapse/client/new_user_consent'262Oct 02 05:35:25 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.sso_register.SsoRegisterResource object at 0xffffee3cc9a0> to path b'/_synapse/client/sso_register'263Oct 02 05:35:25 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.unsubscribe.UnsubscribeResource object at 0xffffee3ccb20> to path b'/_synapse/client/unsubscribe'264Oct 02 05:35:25 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.media_upload_limit_exceeded.MediaUploadLimitExceededResource object at 0xffffee3ccca0> to path b'/_synapse/client/media_upload_limit_exceeded'265Oct 02 05:35:25 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching FilePath('/nix/store/724bvq231d0dja7f12fkk1rxcwm76nyq-matrix-synapse-1.162.0/lib/python3.13/site-packages/synapse/static') to path b'/_matrix/static'266Oct 02 05:35:25 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee3cd120> to path b'/_matrix/media/r0'267Oct 02 05:35:25 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee3cd120> to path b'/_matrix/media/v3'268Oct 02 05:35:25 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee3cd120> to path b'/_matrix/media/v1'269Oct 02 05:35:25 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.federation.transport.server.TransportLayerServer object at 0xffffee3cdd20> to path b'/_matrix/federation'270Oct 02 05:35:25 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.key.v2.KeyResource object at 0xffffee3d0ea0> to path b'/_matrix/key'271Oct 02 05:35:25 machine synapse[491]: twisted: [call_when_running] SynapseSite starting on 8008272Oct 02 05:35:25 machine synapse[491]: synapse.app._base: [call_when_running] Synapse now listening on TCP port 8008273Oct 02 05:35:25 machine synapse[491]: synapse.app._base: [call_when_running] garbage collector: Freezing all allocated objects in the hopes that (almost) everything currently allocated are things that will be used by the homeserver for the rest of time. Doing so means less work each GC (hopefully).274Oct 02 05:35:25 machine synapse[491]: synapse.storage.background_updates: [background_updates-0] Starting background schema updates for database master275Oct 02 05:35:25 machine synapse[491]: synapse.handlers.deactivate_account: [user_parter_loop-0] User parter finished: stopping276Oct 02 05:35:25 machine synapse[491]: synapse.push.pusherpool: [start_pushers-0] Started pushers277Oct 02 05:35:25 machine bif8zmjfikz632ghgsbbd20zy0ldh452-matrix-synapse-create-users[520]: Connection to ::1 8008 port [tcp/http-alt] succeeded!278Oct 02 05:35:25 machine synapse[491]: synapse.access.http.8008: [GET-0] ::1 - 8008 - {None} Processed request: 0.001sec/0.001sec ru=(0.003sec, 0.000sec) db=(0.000sec/0.000sec/0) 140B 200 "GET /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]279Oct 02 05:35:26 machine synapse[491]: synapse.handlers.auth: [POST-1] Logging in user @admin:clan.test on device FWYYZEGIGD280Oct 02 05:35:26 machine synapse[491]: synapse.access.http.8008: [POST-1] ::1 - 8008 - {None} Processed request: 0.301sec/0.001sec ru=(0.252sec, 0.007sec) db=(0.004sec/0.031sec/12) 138B 200 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]281Oct 02 05:35:26 machine bif8zmjfikz632ghgsbbd20zy0ldh452-matrix-synapse-create-users[521]: Sending registration request...282Oct 02 05:35:26 machine bif8zmjfikz632ghgsbbd20zy0ldh452-matrix-synapse-create-users[521]: Success!283Oct 02 05:35:26 machine synapse[491]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'threads_backfill'284Oct 02 05:35:26 machine synapse[491]: synapse.storage.background_updates: [background_updates-0] Running background update 'threads_backfill'. Processed 0 items in 5ms. (total_rate=None/ms, current_rate=None/ms, total_updated=0, batch_size=100)285Oct 02 05:35:26 machine synapse[491]: synapse.access.http.8008: [GET-2] ::1 - 8008 - {None} Processed request: 0.000sec/0.001sec ru=(0.001sec, 0.000sec) db=(0.000sec/0.000sec/0) 140B 200 "GET /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]286Oct 02 05:35:26 machine synapse[491]: synapse.handlers.auth: [POST-3] Logging in user @someuser:clan.test on device MBOHANHUNU287Oct 02 05:35:26 machine synapse[491]: synapse.access.http.8008: [POST-3] ::1 - 8008 - {None} Processed request: 0.297sec/0.001sec ru=(0.261sec, 0.003sec) db=(0.004sec/0.018sec/12) 145B 200 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]288Oct 02 05:35:26 machine bif8zmjfikz632ghgsbbd20zy0ldh452-matrix-synapse-create-users[528]: Sending registration request...289Oct 02 05:35:26 machine bif8zmjfikz632ghgsbbd20zy0ldh452-matrix-synapse-create-users[528]: Success!290Oct 02 05:35:27 machine systemd[1]: Started Synapse Matrix homeserver.291Oct 02 05:35:27 machine synapse[491]: 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.22.0" [0 dbevts]292Oct 02 05:35:27 machine systemd[1]: Stopping Synapse Matrix homeserver...293Oct 02 05:35:27 machine synapse[491]: twisted: [sentinel] Received SIGTERM, shutting down.294Oct 02 05:35:27 machine synapse[491]: synapse.storage.databases.main.lock: [LockStore._on_shutdown-0] Dropping held locks due to shutdown295Oct 02 05:35:27 machine synapse[491]: synapse.storage.databases.main.lock: [LockStore._on_shutdown-0] Dropped locks due to shutdown296Oct 02 05:35:27 machine synapse[491]: synapse.handlers.presence: [PresenceHandler._on_shutdown-0] Performing _on_shutdown. Persisting 0 unpersisted changes297Oct 02 05:35:27 machine synapse[491]: synapse.handlers.presence: [PresenceHandler._on_shutdown-0] Finished _on_shutdown298Oct 02 05:35:27 machine synapse[491]: synapse.app._base: [log_shutdown] Shutting down...299Oct 02 05:35:27 machine synapse[491]: twisted: [sentinel] (TCP Port 8008 Closed)300Oct 02 05:35:27 machine synapse[491]: twisted: [sentinel] Main loop terminated.301Oct 02 05:35:27 machine systemd[1]: matrix-synapse.service: Deactivated successfully.302Oct 02 05:35:27 machine systemd[1]: Stopped Synapse Matrix homeserver.303Oct 02 05:35:27 machine systemd[1]: matrix-synapse.service: Consumed 7.897s CPU time over 8.634s wall clock time.304Oct 02 05:35:27 machine systemd[1]: Starting Synapse Matrix homeserver...305Oct 02 05:35:33 machine synapse_homeserver[559]: This server is configured to use 'matrix.org' as its trusted key server via the306Oct 02 05:35:33 machine synapse_homeserver[559]: 'trusted_key_servers' config option. 'matrix.org' is a good choice for a key307Oct 02 05:35:33 machine synapse_homeserver[559]: server since it is long-lived, stable and trusted. However, some admins may308Oct 02 05:35:33 machine synapse_homeserver[559]: wish to use another server for this purpose.309Oct 02 05:35:33 machine synapse_homeserver[559]: To suppress this warning and continue using 'matrix.org', admins should set310Oct 02 05:35:33 machine synapse_homeserver[559]: 'suppress_key_server_warning' to 'true' in homeserver.yaml.311Oct 02 05:35:33 machine synapse_homeserver[559]: --------------------------------------------------------------------------------312Oct 02 05:35:33 machine synapse[559]: synapse.config.logger: [main] ***** STARTING SERVER *****313Oct 02 05:35:33 machine synapse[559]: synapse.config.logger: [main] Server /nix/store/724bvq231d0dja7f12fkk1rxcwm76nyq-matrix-synapse-1.162.0/bin/synapse_homeserver version 1.162.0314Oct 02 05:35:33 machine synapse[559]: synapse.config.logger: [main] Copyright (c) 2023 New Vector, Inc315Oct 02 05:35:33 machine synapse[559]: synapse.config.logger: [main] Licensed under the AGPL 3.0 license. Website: https://github.com/element-hq/synapse316Oct 02 05:35:33 machine synapse[559]: synapse.config.logger: [main] Server hostname: clan.test317Oct 02 05:35:33 machine synapse[559]: synapse.config.logger: [main] Public Base URL: https://clan.test/318Oct 02 05:35:33 machine synapse[559]: synapse.config.logger: [main] Instance name: master319Oct 02 05:35:33 machine synapse[559]: synapse.config.logger: [main] Twisted reactor: EPollReactor320Oct 02 05:35:33 machine synapse[559]: synapse.app.homeserver: [main] Setting up server321Oct 02 05:35:33 machine synapse[559]: synapse.server: [main] Setting up.322Oct 02 05:35:33 machine synapse[559]: synapse.storage.databases: [main] [database config 'master']: Checking database server323Oct 02 05:35:33 machine synapse[559]: synapse.storage.databases: [main] [database config 'master']: Preparing for databases ['main', 'state']324Oct 02 05:35:33 machine synapse[559]: synapse.storage.prepare_database: [main] ['main', 'state']: Checking existing schema version325Oct 02 05:35:33 machine synapse[559]: synapse.storage.prepare_database: [main] ['main', 'state']: Existing schema is 94 (+16 deltas)326Oct 02 05:35:33 machine synapse[559]: synapse.storage.databases.main: [main] Checking database for consistency with configuration...327Oct 02 05:35:33 machine synapse[559]: synapse.storage.prepare_database: [main] Applying schema deltas for v94328Oct 02 05:35:33 machine synapse[559]: synapse.storage.prepare_database: [main] Schema now up to date329Oct 02 05:35:33 machine synapse[559]: synapse.storage.databases: [main] [database config 'master']: Starting 'main' database330Oct 02 05:35:33 machine synapse[559]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules(id): 1331Oct 02 05:35:33 machine synapse[559]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules_enable(id): 1332Oct 02 05:35:33 machine synapse[559]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 month ago333Oct 02 05:35:33 machine synapse[559]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 month ago: it's 0334Oct 02 05:35:33 machine synapse[559]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 day ago335Oct 02 05:35:33 machine synapse[559]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 day ago: it's 0336Oct 02 05:35:33 machine synapse[559]: synapse.storage.util.id_generators: [main] Initialising stream generator for access_tokens(id): 3337Oct 02 05:35:33 machine synapse[559]: synapse.storage.util.id_generators: [main] Initialising stream generator for refresh_tokens(id): 1338Oct 02 05:35:33 machine synapse[559]: synapse.storage.util.id_generators: [main] Initialising stream generator for event_reports(id): 1339Oct 02 05:35:33 machine synapse[559]: synapse.storage.util.id_generators: [main] Initialising stream generator for room_reports(id): 1340Oct 02 05:35:33 machine synapse[559]: synapse.storage.util.id_generators: [main] Initialising stream generator for user_reports(id): 1341Oct 02 05:35:33 machine synapse[559]: synapse.storage.databases: [main] [database config 'master']: Starting 'state' database342Oct 02 05:35:33 machine synapse[559]: synapse.storage.databases: [main] [database config 'master']: prepared343Oct 02 05:35:33 machine synapse[559]: synapse.server: [main] Finished setting up.344Oct 02 05:35:33 machine synapse[559]: twisted: [main] Redirected stdout/stderr to logs345Oct 02 05:35:33 machine synapse[559]: synapse.app.homeserver: [main] Running346Oct 02 05:35:33 machine synapse[559]: synapse.app.homeserver: [main] Set file limit to: 524288347Oct 02 05:35:33 machine synapse[559]: synapse.push.pusher: [call_when_running] email enable notifs: False348Oct 02 05:35:33 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'profile'349Oct 02 05:35:33 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.device_list_update'350Oct 02 05:35:33 machine synapse[559]: synapse.util.caches.lrucache: [call_when_running] Expiring LRU caches after 1800 seconds351Oct 02 05:35:33 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.presence'352Oct 02 05:35:33 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.typing'353Oct 02 05:35:33 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'directory'354Oct 02 05:35:33 machine synapse[559]: synapse.handlers.deactivate_account: [user_parter_loop-0] Starting user parter355Oct 02 05:35:33 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.receipt'356Oct 02 05:35:33 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.signing_key_update'357Oct 02 05:35:33 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'org.matrix.signing_key_update'358Oct 02 05:35:33 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'client_keys'359Oct 02 05:35:33 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.direct_to_device'360Oct 02 05:35:33 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.health.HealthResource object at 0xffffee08b9a0> to path b'/health'361Oct 02 05:35:33 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.EncodingResourceWrapper object at 0xffffee3b6220> to path b'/_matrix/client'362Oct 02 05:35:33 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffee3bbf20> to path b'/.well-known'363Oct 02 05:35:33 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.http.server.JsonResource object at 0xffffee3b63a0> to path b'/_synapse/admin'364Oct 02 05:35:33 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.pick_idp.PickIdpResource object at 0xffffee3bc3a0> to path b'/_synapse/client/pick_idp'365Oct 02 05:35:33 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffe3e071e0> to path b'/_synapse/client/pick_username'366Oct 02 05:35:33 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.new_user_consent.NewUserConsentResource object at 0xffffee3bc9a0> to path b'/_synapse/client/new_user_consent'367Oct 02 05:35:33 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.sso_register.SsoRegisterResource object at 0xffffee3bcb20> to path b'/_synapse/client/sso_register'368Oct 02 05:35:33 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.unsubscribe.UnsubscribeResource object at 0xffffee3bcca0> to path b'/_synapse/client/unsubscribe'369Oct 02 05:35:33 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.media_upload_limit_exceeded.MediaUploadLimitExceededResource object at 0xffffee3bce20> to path b'/_synapse/client/media_upload_limit_exceeded'370Oct 02 05:35:33 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching FilePath('/nix/store/724bvq231d0dja7f12fkk1rxcwm76nyq-matrix-synapse-1.162.0/lib/python3.13/site-packages/synapse/static') to path b'/_matrix/static'371Oct 02 05:35:33 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee3bd2a0> to path b'/_matrix/media/r0'372Oct 02 05:35:33 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee3bd2a0> to path b'/_matrix/media/v3'373Oct 02 05:35:33 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee3bd2a0> to path b'/_matrix/media/v1'374Oct 02 05:35:33 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.federation.transport.server.TransportLayerServer object at 0xffffee3bdea0> to path b'/_matrix/federation'375Oct 02 05:35:33 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.key.v2.KeyResource object at 0xffffee3c1020> to path b'/_matrix/key'376Oct 02 05:35:33 machine synapse[559]: twisted: [call_when_running] SynapseSite starting on 8008377Oct 02 05:35:33 machine synapse[559]: synapse.app._base: [call_when_running] Synapse now listening on TCP port 8008378Oct 02 05:35:33 machine synapse[559]: 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).379Oct 02 05:35:33 machine synapse[559]: synapse.storage.background_updates: [background_updates-0] Starting background schema updates for database master380Oct 02 05:35:33 machine synapse[559]: synapse.handlers.deactivate_account: [user_parter_loop-0] User parter finished: stopping381Oct 02 05:35:33 machine synapse[559]: synapse.push.pusherpool: [start_pushers-0] Started pushers382Oct 02 05:35:33 machine bif8zmjfikz632ghgsbbd20zy0ldh452-matrix-synapse-create-users[584]: Connection to ::1 8008 port [tcp/http-alt] succeeded!383Oct 02 05:35:33 machine synapse[559]: 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]384Oct 02 05:35:34 machine synapse[559]: synapse.http.server: [POST-1] <XForwardedForRequest at 0xffffe3e04c60 method='POST' uri='/_synapse/admin/v1/register' clientproto='HTTP/1.1' site='8008'> SynapseError: 400 - User ID already taken.385Oct 02 05:35:34 machine synapse[559]: synapse.access.http.8008: [POST-1] ::1 - 8008 - {None} Processed request: 0.246sec/0.000sec ru=(0.242sec, 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]386Oct 02 05:35:34 machine bif8zmjfikz632ghgsbbd20zy0ldh452-matrix-synapse-create-users[585]: Sending registration request...387Oct 02 05:35:34 machine bif8zmjfikz632ghgsbbd20zy0ldh452-matrix-synapse-create-users[585]: User already exists. Skipping.388Oct 02 05:35:34 machine synapse[559]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'device_lists_changes_in_room_by_room_index'389Oct 02 05:35:34 machine synapse[559]: synapse.storage.background_updates: [background_updates-0] Adding index device_lists_changes_in_room_by_room_idx to device_lists_changes_in_room390Oct 02 05:35:34 machine synapse[559]: synapse.storage.background_updates: [background_updates-0] Running background update 'device_lists_changes_in_room_by_room_index'. Processed 1 items in 7ms. (total_rate=0.14285714285714285/ms, current_rate=0.14285714285714285/ms, total_updated=1, batch_size=100)391Oct 02 05:35:34 machine synapse[559]: synapse.access.http.8008: [GET-2] ::1 - 8008 - {None} Processed request: 0.000sec/0.001sec ru=(0.001sec, 0.000sec) db=(0.000sec/0.000sec/0) 140B 200 "GET /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]392Oct 02 05:35:34 machine synapse[559]: synapse.http.server: [POST-3] <XForwardedForRequest at 0xffffe3e00de0 method='POST' uri='/_synapse/admin/v1/register' clientproto='HTTP/1.1' site='8008'> SynapseError: 400 - User ID already taken.393Oct 02 05:35:34 machine synapse[559]: synapse.access.http.8008: [POST-3] ::1 - 8008 - {None} Processed request: 0.260sec/0.000sec ru=(0.255sec, 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]394Oct 02 05:35:34 machine bif8zmjfikz632ghgsbbd20zy0ldh452-matrix-synapse-create-users[591]: Sending registration request...395Oct 02 05:35:34 machine bif8zmjfikz632ghgsbbd20zy0ldh452-matrix-synapse-create-users[591]: User already exists. Skipping.396Oct 02 05:35:35 machine systemd[1]: Started Synapse Matrix homeserver.397+ systemctl --no-pager show matrix-synapse398+ nc -z -v ::1 8008399Connection to ::1 8008 port [tcp/http-alt] succeeded!400+ curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test'401