container-test-run-matrix-synapse
checks.aarch64-linux.matrix-synapse
· build #904
· raw
1build01: building wxxsrps0prwqj7y217iffajzi93l0x51-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/hh6jf45l3v86bn9pl0i91djyzqcz2w6m-nixos-system-machine-test11running activation script...12setting up /etc...132: host0@if3: <BROADCAST,MULTICAST> mtu 1500 qdisc noop state DOWN mode DEFAULT group default qlen 100014 link/ether 0e:74:26:22:81:c2 brd ff:ff:ff:ff:ff:ff link-netnsid 015setting up age secrets...16starting systemd...17systemd 260.5 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)18Detected virtualization systemd-nspawn.19Detected architecture arm64.20Detected first boot.21Initializing machine ID from container UUID.22Applying preset policy.23Populated /etc with preset unit settings.24Queued start job for default target Multi-User System.25262728<<< Welcome to NixOS test (aarch64) - console >>>29+ systemd-run /bin/sh -c '/nix/store/3l7ygqcc6v0rbi9d4j1m6phi6sz86j98-coreutils-9.11/bin/sleep 999999999 && echo 2c5c9290-0117-4e3a-9242-b78098d10303'30Running as unit: run-p481-i35962436.service; invocation ID: c9097961e28e4d2a913cbb9f54b0f00c31To attach to container machine run on the same machine that runs the test:32 sudo nsenter --user --target $(\pgrep -f '^/bin/sh.*2c5c9290-0117-4e3a-9242-b78098d10303') --mount --uts --ipc --net --pid --cgroup /bin/sh -c bash 3334To inject external network and continue test, run:35sudo /nix/store/q1khrhbn506imjxx7kiqcbihycawa6kl-python3-3.13.15/bin/python3.13 /nix/store/qq5ppp119nyp6wdn1gi4pr1xkmgs2ny1-test-driver-0.0.1/lib/python3.13/site-packages/test_driver/inject_network.py 2c5c9290-0117-4e3a-9242-b78098d1030336+ 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 07 15:43:16 machine systemd[1]: Starting Synapse Matrix homeserver...51Oct 07 15:43:19 machine matrix-synapse-pre-start[476]: Generating signing key file /var/lib/matrix-synapse/homeserver.signing.key52Oct 07 15:43:22 machine synapse_homeserver[491]: This server is configured to use 'matrix.org' as its trusted key server via the53Oct 07 15:43:22 machine synapse_homeserver[491]: 'trusted_key_servers' config option. 'matrix.org' is a good choice for a key54Oct 07 15:43:22 machine synapse_homeserver[491]: server since it is long-lived, stable and trusted. However, some admins may55Oct 07 15:43:22 machine synapse_homeserver[491]: wish to use another server for this purpose.56Oct 07 15:43:22 machine synapse_homeserver[491]: To suppress this warning and continue using 'matrix.org', admins should set57Oct 07 15:43:22 machine synapse_homeserver[491]: 'suppress_key_server_warning' to 'true' in homeserver.yaml.58Oct 07 15:43:22 machine synapse_homeserver[491]: --------------------------------------------------------------------------------59Oct 07 15:43:22 machine synapse[491]: synapse.config.logger: [main] ***** STARTING SERVER *****60Oct 07 15:43:22 machine synapse[491]: synapse.config.logger: [main] Server /nix/store/928l52anrm0pvcn6k9haijzhbygjrjs5-matrix-synapse-1.162.0/bin/synapse_homeserver version 1.162.061Oct 07 15:43:22 machine synapse[491]: synapse.config.logger: [main] Copyright (c) 2023 New Vector, Inc62Oct 07 15:43:22 machine synapse[491]: synapse.config.logger: [main] Licensed under the AGPL 3.0 license. Website: https://github.com/element-hq/synapse63Oct 07 15:43:22 machine synapse[491]: synapse.config.logger: [main] Server hostname: clan.test64Oct 07 15:43:22 machine synapse[491]: synapse.config.logger: [main] Public Base URL: https://clan.test/65Oct 07 15:43:22 machine synapse[491]: synapse.config.logger: [main] Instance name: master66Oct 07 15:43:22 machine synapse[491]: synapse.config.logger: [main] Twisted reactor: EPollReactor67Oct 07 15:43:22 machine synapse[491]: synapse.app.homeserver: [main] Setting up server68Oct 07 15:43:22 machine synapse[491]: synapse.server: [main] Setting up.69Oct 07 15:43:22 machine synapse[491]: synapse.storage.databases: [main] [database config 'master']: Checking database server70Oct 07 15:43:22 machine synapse[491]: synapse.storage.databases: [main] [database config 'master']: Preparing for databases ['main', 'state']71Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] ['main', 'state']: Checking existing schema version72Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] ['main', 'state']: Initialising new database73Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v7374Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/01event_failed_pull_attempts.sql75Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/02add_pusher_enabled.sql76Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/02room_id_indexes_for_purging.sql77Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/03pusher_device_id.sql78Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/03users_approved_column.sql79Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/04partial_join_details.sql80Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/04pending_device_list_updates.sql81Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/05old_push_actions.sql.postgres82Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/06thread_notifications_thread_id_idx.sql83Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/08thread_receipts_non_null.sql.postgres84Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/09partial_joined_via_destination.sql85Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/09threads_table.sql86Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Running 73/10_update_sqlite_fts4_tokenizer.py:run_create87Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/10login_tokens.sql88Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/11event_search_room_id_n_distinct.sql.postgres89Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/12refactor_device_list_outbound_pokes.sql90Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/13add_device_lists_index.sql91Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/20_un_partial_stated_room_stream.sql92Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/21_un_partial_stated_room_stream_seq.sql.postgres93Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/22_rebuild_user_dir_stats.sql94Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/22_un_partial_stated_event_stream.sql95Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/23_fix_thread_index.sql96Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/23_un_partial_stated_room_stream_seq.sql.postgres97Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/24_events_jump_to_date_index.sql98Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 73/25drop_presence.sql99Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v74100Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 74/01_user_directory_stale_remote_users.sql101Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 74/02_set_device_id_for_pushers_bg_update.sql102Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 74/03_membership_tables_event_stream_ordering.sql.postgres103Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 74/03_room_membership_index.sql104Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 74/04_delete_e2e_backup_keys_for_deactivated_users.sql105Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Running 74/04_membership_tables_event_stream_ordering_triggers.py:run_create106Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 74/05_events_txn_id_device_id.sql107Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 74/90COMMENTS_destinations.sql.postgres108Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v75109Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v76110Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 76/01_add_profiles_full_user_id_column.sql111Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 76/02_add_user_filters_full_user_id_column.sql112Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 76/03_per_user_experimental_features.sql113Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 76/04_add_room_forgetter.sql114Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v77115Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/01_add_profiles_not_valid_check.sql.postgres116Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/02_add_user_filters_not_valid_check.sql.postgres117Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 77/03bg_populate_full_user_id_profiles.sql118Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 77/04bg_populate_full_user_id_user_filters.sql119Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 77/05thread_notifications_backfill.sql120Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_actions.sql.postgres121Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_actions_staging.sql.postgres122Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_summary.sql.postgres123Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 77/14bg_indices_event_stream_ordering.sql124Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v78125Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Running 78/03event_extremities_constraints.py:run_create126Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Running 78/04_add_full_user_id_index_user_filters.py:run_create127Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v79128Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 79/03_read_write_locks_triggers.sql.postgres129Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Running 79/04_mitigate_stream_ordering_update_race.py:run_create130Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 79/05_read_write_locks_triggers.sql.postgres131Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v80132Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 80/01_users_alter_locked.sql133Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/02_read_write_locks_unlogged.sql.postgres134Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 80/02_scheduled_tasks.sql135Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/03_read_write_locks_triggers.sql.postgres136Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/04_read_write_locks_deadlock.sql.postgres137Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v81138Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v82139Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 82/02_scheduled_tasks_index.sql140Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 82/04_add_indices_for_purging_rooms.sql141Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 82/05gaps.sql142Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v83143Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 83/01_drop_old_tables.sql144Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 83/05_cross_signing_key_update_grant.sql145Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 83/06_event_push_summary_room.sql146Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v84147Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 84/01_auth_links_stats.sql.postgres148Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 84/02_auth_links_index.sql149Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 84/03_auth_links_analyze.sql.postgres150Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 84/04_access_token_index.sql151Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v85152Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 85/01_add_suspended.sql153Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 85/02_add_instance_names.sql154Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 85/03_new_sequences.sql.postgres155Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 85/04_cleanup_device_federation_outbox.sql156Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 85/05_add_instance_names_converted_pos.sql157Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 85/06_add_room_reports.sql158Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v86159Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 86/01_authenticate_media.sql160Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 86/02_receipts_event_id_index.sql161Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v87162Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 87/01_sliding_sync_memberships.sql163Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 87/02_per_connection_state.sql164Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 87/03_current_state_index.sql165Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v88166Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 88/01_add_delayed_events.sql167Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 88/01_custom_profile_fields.sql168Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 88/02_fix_sliding_sync_membership_snapshots_forgotten_column.sql169Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 88/03_add_otk_ts_added_index.sql170Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 88/04_current_state_delta_index.sql171Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 88/05_drop_old_otks.sql.postgres172Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 88/05_sliding_sync_room_config_index.sql173Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 88/06_events_received_ts_index.sql174Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v89175Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 89/01_sliding_sync_membership_snapshot_index.sql176Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 89/01_state_groups_deletion.sql177Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v90178Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 90/01_add_column_participant_room_memberships_table.sql179Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 90/02_delete_unreferenced_state_groups.sql180Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 90/03_remove_old_deletion_bg_update.sql181Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v91182Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 91/01_media_hash.sql183Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v92184Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/01_remove_trigger.sql.postgres185Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/02_remove_populate_participant_bg_update.sql186Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/04_ss_membership_snapshot_idx.sql187Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/04_thread_subscriptions.sql188Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/04_thread_subscriptions_seq.sql.postgres189Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/05_fixup_max_depth_cap.sql190Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/05_thread_subscriptions_comments.sql.postgres191Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/06_device_federation_inbox_index.sql192Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/06_threads_last_sent_stream_ordering_comments.sql.postgres193Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/07_add_user_reports.sql194Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/07_event_txn_id_device_id_txn_id2.sql195Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/08_room_ban_redactions.sql196Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/08_thread_subscriptions_seq_fixup.sql.postgres197Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 92/09_thread_subscriptions_update.sql198Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/09_thread_subscriptions_update.sql.postgres199Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v93200Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 93/01_add_delayed_events.sql201Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 93/01_sticky_events.sql202Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 93/01_sticky_events_seq.sql.postgres203Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 93/02_sliding_sync_members.sql204Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 93/03_sss_pos_last_used.sql205Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Running 93/04_make_delayed_event_content_text.py:run_create206Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema deltas for v94207Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/01_redactions_recheck.sql208Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 94/01_state_groups_persisting_inserted_ts.sql.postgres209Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/02_redactions_recheck_bg_update.sql210Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/03_device_lists_room_timestamp.sql211Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/03_quarantined_media_tracking.sql212Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 94/03_quarantined_media_tracking_seq.sql.postgres213Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/03_state_dag_fwd_extrems.sql214Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/04_device_lists_changes_max_pruned.sql215Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/05_rerun_flag_existing_quarantined_media.sql216Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/06_sliding_sync_connections_last_used_ts_index.sql217Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/07_profile_updates.sql218Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying engine-specific schema 94/07_profile_updates_seq.sql.postgres219Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/07_sliding_sync_lazy_members_position_index.sql220Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/08_device_lists_changes_in_room_unconverted_idx.sql221Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/09_e2e_cross_signing_signatures_index.sql222Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Applying schema 94/10_e2e_cross_signing_signatures_add_key_id_to_index.sql223Oct 07 15:43:22 machine synapse[491]: synapse.storage.prepare_database: [main] Schema now up to date224Oct 07 15:43:22 machine synapse[491]: synapse.storage.databases: [main] [database config 'master']: Starting 'main' database225Oct 07 15:43:22 machine synapse[491]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules(id): 1226Oct 07 15:43:22 machine synapse[491]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules_enable(id): 1227Oct 07 15:43:22 machine synapse[491]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 month ago228Oct 07 15:43:22 machine synapse[491]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 month ago: it's 0229Oct 07 15:43:22 machine synapse[491]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 day ago230Oct 07 15:43:22 machine synapse[491]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 day ago: it's 0231Oct 07 15:43:22 machine synapse[491]: synapse.storage.util.id_generators: [main] Initialising stream generator for access_tokens(id): 1232Oct 07 15:43:22 machine synapse[491]: synapse.storage.util.id_generators: [main] Initialising stream generator for refresh_tokens(id): 1233Oct 07 15:43:22 machine synapse[491]: synapse.storage.util.id_generators: [main] Initialising stream generator for event_reports(id): 1234Oct 07 15:43:22 machine synapse[491]: synapse.storage.util.id_generators: [main] Initialising stream generator for room_reports(id): 1235Oct 07 15:43:22 machine synapse[491]: synapse.storage.util.id_generators: [main] Initialising stream generator for user_reports(id): 1236Oct 07 15:43:22 machine synapse[491]: synapse.storage.databases: [main] [database config 'master']: Starting 'state' database237Oct 07 15:43:22 machine synapse[491]: synapse.storage.databases: [main] [database config 'master']: prepared238Oct 07 15:43:22 machine synapse[491]: synapse.server: [main] Finished setting up.239Oct 07 15:43:22 machine synapse[491]: twisted: [main] Redirected stdout/stderr to logs240Oct 07 15:43:22 machine synapse[491]: synapse.app.homeserver: [main] Running241Oct 07 15:43:22 machine synapse[491]: synapse.app.homeserver: [main] Set file limit to: 524288242Oct 07 15:43:22 machine synapse[491]: synapse.push.pusher: [call_when_running] email enable notifs: False243Oct 07 15:43:23 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'profile'244Oct 07 15:43:23 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.device_list_update'245Oct 07 15:43:23 machine synapse[491]: synapse.util.caches.lrucache: [call_when_running] Expiring LRU caches after 1800 seconds246Oct 07 15:43:23 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.presence'247Oct 07 15:43:23 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.typing'248Oct 07 15:43:23 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'directory'249Oct 07 15:43:23 machine synapse[491]: synapse.handlers.deactivate_account: [user_parter_loop-0] Starting user parter250Oct 07 15:43:23 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.receipt'251Oct 07 15:43:23 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.signing_key_update'252Oct 07 15:43:23 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'org.matrix.signing_key_update'253Oct 07 15:43:23 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'client_keys'254Oct 07 15:43:23 machine synapse[491]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.direct_to_device'255Oct 07 15:43:23 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.health.HealthResource object at 0xffffee093ca0> to path b'/health'256Oct 07 15:43:23 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.EncodingResourceWrapper object at 0xffffee3c60a0> to path b'/_matrix/client'257Oct 07 15:43:23 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffee3cbda0> to path b'/.well-known'258Oct 07 15:43:23 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.http.server.JsonResource object at 0xffffee3c6220> to path b'/_synapse/admin'259Oct 07 15:43:23 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 07 15:43:23 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 07 15:43:23 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 07 15:43:23 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 07 15:43:23 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 07 15:43:23 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 07 15:43:23 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching FilePath('/nix/store/928l52anrm0pvcn6k9haijzhbygjrjs5-matrix-synapse-1.162.0/lib/python3.13/site-packages/synapse/static') to path b'/_matrix/static'266Oct 07 15:43:23 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 07 15:43:23 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 07 15:43:23 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 07 15:43:23 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.federation.transport.server.TransportLayerServer object at 0xffffee3cdd20> to path b'/_matrix/federation'270Oct 07 15:43:23 machine synapse[491]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.key.v2.KeyResource object at 0xffffee3d0ea0> to path b'/_matrix/key'271Oct 07 15:43:23 machine synapse[491]: twisted: [call_when_running] SynapseSite starting on 8008272Oct 07 15:43:23 machine synapse[491]: synapse.app._base: [call_when_running] Synapse now listening on TCP port 8008273Oct 07 15:43:23 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 07 15:43:23 machine synapse[491]: synapse.storage.background_updates: [background_updates-0] Starting background schema updates for database master275Oct 07 15:43:23 machine synapse[491]: synapse.handlers.deactivate_account: [user_parter_loop-0] User parter finished: stopping276Oct 07 15:43:23 machine synapse[491]: synapse.push.pusherpool: [start_pushers-0] Started pushers277Oct 07 15:43:23 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[520]: Connection to ::1 8008 port [tcp/http-alt] succeeded!278Oct 07 15:43:23 machine synapse[491]: 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]279Oct 07 15:43:23 machine synapse[491]: synapse.handlers.auth: [POST-1] Logging in user @admin:clan.test on device GVAINXRLLL280Oct 07 15:43:24 machine synapse[491]: synapse.access.http.8008: [POST-1] ::1 - 8008 - {None} Processed request: 0.298sec/0.001sec ru=(0.260sec, 0.000sec) db=(0.004sec/0.029sec/12) 138B 200 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]281Oct 07 15:43:24 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[521]: Sending registration request...282Oct 07 15:43:24 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[521]: Success!283Oct 07 15:43:24 machine synapse[491]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'threads_backfill'284Oct 07 15:43:24 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 07 15:43:24 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 07 15:43:24 machine synapse[491]: synapse.handlers.auth: [POST-3] Logging in user @someuser:clan.test on device VLCTGQCPLY287Oct 07 15:43:24 machine synapse[491]: synapse.access.http.8008: [POST-3] ::1 - 8008 - {None} Processed request: 0.307sec/0.001sec ru=(0.275sec, 0.000sec) db=(0.005sec/0.020sec/12) 145B 200 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]288Oct 07 15:43:24 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[528]: Sending registration request...289Oct 07 15:43:24 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[528]: Success!290Oct 07 15:43:24 machine systemd[1]: Started Synapse Matrix homeserver.291Oct 07 15:43:25 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 07 15:43:25 machine systemd[1]: Stopping Synapse Matrix homeserver...293Oct 07 15:43:25 machine synapse[491]: twisted: [sentinel] Received SIGTERM, shutting down.294Oct 07 15:43:25 machine synapse[491]: synapse.storage.databases.main.lock: [LockStore._on_shutdown-0] Dropping held locks due to shutdown295Oct 07 15:43:25 machine synapse[491]: synapse.storage.databases.main.lock: [LockStore._on_shutdown-0] Dropped locks due to shutdown296Oct 07 15:43:25 machine synapse[491]: synapse.handlers.presence: [PresenceHandler._on_shutdown-0] Performing _on_shutdown. Persisting 0 unpersisted changes297Oct 07 15:43:25 machine synapse[491]: synapse.handlers.presence: [PresenceHandler._on_shutdown-0] Finished _on_shutdown298Oct 07 15:43:25 machine synapse[491]: synapse.app._base: [log_shutdown] Shutting down...299Oct 07 15:43:25 machine synapse[491]: twisted: [sentinel] (TCP Port 8008 Closed)300Oct 07 15:43:25 machine synapse[491]: twisted: [sentinel] Main loop terminated.301Oct 07 15:43:25 machine systemd[1]: matrix-synapse.service: Deactivated successfully.302Oct 07 15:43:25 machine systemd[1]: Stopped Synapse Matrix homeserver.303Oct 07 15:43:25 machine systemd[1]: matrix-synapse.service: Consumed 7.918s CPU time over 8.612s wall clock time.304Oct 07 15:43:25 machine systemd[1]: Starting Synapse Matrix homeserver...305Oct 07 15:43:31 machine synapse_homeserver[559]: This server is configured to use 'matrix.org' as its trusted key server via the306Oct 07 15:43:31 machine synapse_homeserver[559]: 'trusted_key_servers' config option. 'matrix.org' is a good choice for a key307Oct 07 15:43:31 machine synapse_homeserver[559]: server since it is long-lived, stable and trusted. However, some admins may308Oct 07 15:43:31 machine synapse_homeserver[559]: wish to use another server for this purpose.309Oct 07 15:43:31 machine synapse_homeserver[559]: To suppress this warning and continue using 'matrix.org', admins should set310Oct 07 15:43:31 machine synapse_homeserver[559]: 'suppress_key_server_warning' to 'true' in homeserver.yaml.311Oct 07 15:43:31 machine synapse_homeserver[559]: --------------------------------------------------------------------------------312Oct 07 15:43:31 machine synapse[559]: synapse.config.logger: [main] ***** STARTING SERVER *****313Oct 07 15:43:31 machine synapse[559]: synapse.config.logger: [main] Server /nix/store/928l52anrm0pvcn6k9haijzhbygjrjs5-matrix-synapse-1.162.0/bin/synapse_homeserver version 1.162.0314Oct 07 15:43:31 machine synapse[559]: synapse.config.logger: [main] Copyright (c) 2023 New Vector, Inc315Oct 07 15:43:31 machine synapse[559]: synapse.config.logger: [main] Licensed under the AGPL 3.0 license. Website: https://github.com/element-hq/synapse316Oct 07 15:43:31 machine synapse[559]: synapse.config.logger: [main] Server hostname: clan.test317Oct 07 15:43:31 machine synapse[559]: synapse.config.logger: [main] Public Base URL: https://clan.test/318Oct 07 15:43:31 machine synapse[559]: synapse.config.logger: [main] Instance name: master319Oct 07 15:43:31 machine synapse[559]: synapse.config.logger: [main] Twisted reactor: EPollReactor320Oct 07 15:43:31 machine synapse[559]: synapse.app.homeserver: [main] Setting up server321Oct 07 15:43:31 machine synapse[559]: synapse.server: [main] Setting up.322Oct 07 15:43:31 machine synapse[559]: synapse.storage.databases: [main] [database config 'master']: Checking database server323Oct 07 15:43:31 machine synapse[559]: synapse.storage.databases: [main] [database config 'master']: Preparing for databases ['main', 'state']324Oct 07 15:43:31 machine synapse[559]: synapse.storage.prepare_database: [main] ['main', 'state']: Checking existing schema version325Oct 07 15:43:31 machine synapse[559]: synapse.storage.prepare_database: [main] ['main', 'state']: Existing schema is 94 (+16 deltas)326Oct 07 15:43:31 machine synapse[559]: synapse.storage.databases.main: [main] Checking database for consistency with configuration...327Oct 07 15:43:31 machine synapse[559]: synapse.storage.prepare_database: [main] Applying schema deltas for v94328Oct 07 15:43:31 machine synapse[559]: synapse.storage.prepare_database: [main] Schema now up to date329Oct 07 15:43:31 machine synapse[559]: synapse.storage.databases: [main] [database config 'master']: Starting 'main' database330Oct 07 15:43:31 machine synapse[559]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules(id): 1331Oct 07 15:43:31 machine synapse[559]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules_enable(id): 1332Oct 07 15:43:31 machine synapse[559]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 month ago333Oct 07 15:43:31 machine synapse[559]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 month ago: it's 0334Oct 07 15:43:31 machine synapse[559]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 day ago335Oct 07 15:43:31 machine synapse[559]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 day ago: it's 0336Oct 07 15:43:31 machine synapse[559]: synapse.storage.util.id_generators: [main] Initialising stream generator for access_tokens(id): 3337Oct 07 15:43:31 machine synapse[559]: synapse.storage.util.id_generators: [main] Initialising stream generator for refresh_tokens(id): 1338Oct 07 15:43:31 machine synapse[559]: synapse.storage.util.id_generators: [main] Initialising stream generator for event_reports(id): 1339Oct 07 15:43:31 machine synapse[559]: synapse.storage.util.id_generators: [main] Initialising stream generator for room_reports(id): 1340Oct 07 15:43:31 machine synapse[559]: synapse.storage.util.id_generators: [main] Initialising stream generator for user_reports(id): 1341Oct 07 15:43:31 machine synapse[559]: synapse.storage.databases: [main] [database config 'master']: Starting 'state' database342Oct 07 15:43:31 machine synapse[559]: synapse.storage.databases: [main] [database config 'master']: prepared343Oct 07 15:43:31 machine synapse[559]: synapse.server: [main] Finished setting up.344Oct 07 15:43:31 machine synapse[559]: twisted: [main] Redirected stdout/stderr to logs345Oct 07 15:43:31 machine synapse[559]: synapse.app.homeserver: [main] Running346Oct 07 15:43:31 machine synapse[559]: synapse.app.homeserver: [main] Set file limit to: 524288347Oct 07 15:43:31 machine synapse[559]: synapse.push.pusher: [call_when_running] email enable notifs: False348Oct 07 15:43:31 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'profile'349Oct 07 15:43:31 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.device_list_update'350Oct 07 15:43:31 machine synapse[559]: synapse.util.caches.lrucache: [call_when_running] Expiring LRU caches after 1800 seconds351Oct 07 15:43:31 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.presence'352Oct 07 15:43:31 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.typing'353Oct 07 15:43:31 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'directory'354Oct 07 15:43:31 machine synapse[559]: synapse.handlers.deactivate_account: [user_parter_loop-0] Starting user parter355Oct 07 15:43:31 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.receipt'356Oct 07 15:43:31 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.signing_key_update'357Oct 07 15:43:31 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'org.matrix.signing_key_update'358Oct 07 15:43:31 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'client_keys'359Oct 07 15:43:31 machine synapse[559]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.direct_to_device'360Oct 07 15:43:31 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.health.HealthResource object at 0xffffee08b9a0> to path b'/health'361Oct 07 15:43:31 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.EncodingResourceWrapper object at 0xffffee3b6220> to path b'/_matrix/client'362Oct 07 15:43:31 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffee3bbf20> to path b'/.well-known'363Oct 07 15:43:31 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.http.server.JsonResource object at 0xffffee3b63a0> to path b'/_synapse/admin'364Oct 07 15:43:31 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 07 15:43:31 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffe3e371e0> to path b'/_synapse/client/pick_username'366Oct 07 15:43:31 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 07 15:43:31 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 07 15:43:31 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 07 15:43:31 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 07 15:43:31 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching FilePath('/nix/store/928l52anrm0pvcn6k9haijzhbygjrjs5-matrix-synapse-1.162.0/lib/python3.13/site-packages/synapse/static') to path b'/_matrix/static'371Oct 07 15:43:31 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 07 15:43:31 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 07 15:43:31 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 07 15:43:31 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.federation.transport.server.TransportLayerServer object at 0xffffee3bdea0> to path b'/_matrix/federation'375Oct 07 15:43:31 machine synapse[559]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.key.v2.KeyResource object at 0xffffee3c1020> to path b'/_matrix/key'376Oct 07 15:43:31 machine synapse[559]: twisted: [call_when_running] SynapseSite starting on 8008377Oct 07 15:43:31 machine synapse[559]: synapse.app._base: [call_when_running] Synapse now listening on TCP port 8008378Oct 07 15:43:31 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 07 15:43:31 machine synapse[559]: synapse.storage.background_updates: [background_updates-0] Starting background schema updates for database master380Oct 07 15:43:31 machine synapse[559]: synapse.handlers.deactivate_account: [user_parter_loop-0] User parter finished: stopping381Oct 07 15:43:31 machine synapse[559]: synapse.push.pusherpool: [start_pushers-0] Started pushers382Oct 07 15:43:31 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[584]: Connection to ::1 8008 port [tcp/http-alt] succeeded!383Oct 07 15:43:32 machine synapse[559]: synapse.access.http.8008: [GET-0] ::1 - 8008 - {None} Processed request: 0.001sec/0.001sec ru=(0.001sec, 0.000sec) db=(0.000sec/0.000sec/0) 140B 200 "GET /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]384Oct 07 15:43:32 machine synapse[559]: synapse.http.server: [POST-1] <XForwardedForRequest at 0xffffe3e349e0 method='POST' uri='/_synapse/admin/v1/register' clientproto='HTTP/1.1' site='8008'> SynapseError: 400 - User ID already taken.385Oct 07 15:43:32 machine synapse[559]: synapse.access.http.8008: [POST-1] ::1 - 8008 - {None} Processed request: 0.245sec/0.000sec ru=(0.234sec, 0.003sec) db=(0.000sec/0.001sec/1) 60B 400 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]386Oct 07 15:43:32 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[585]: Sending registration request...387Oct 07 15:43:32 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[585]: User already exists. Skipping.388Oct 07 15:43:32 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 07 15:43:32 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 07 15:43:32 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 07 15:43:32 machine synapse[559]: 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]392Oct 07 15:43:33 machine synapse[559]: synapse.http.server: [POST-3] <XForwardedForRequest at 0xffffe3e32e60 method='POST' uri='/_synapse/admin/v1/register' clientproto='HTTP/1.1' site='8008'> SynapseError: 400 - User ID already taken.393Oct 07 15:43:33 machine synapse[559]: synapse.access.http.8008: [POST-3] ::1 - 8008 - {None} Processed request: 0.235sec/0.000sec ru=(0.229sec, 0.000sec) db=(0.001sec/0.001sec/1) 60B 400 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]394Oct 07 15:43:33 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[591]: Sending registration request...395Oct 07 15:43:33 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[591]: User already exists. Skipping.396Oct 07 15:43:33 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