container-test-run-matrix-synapse
checks.aarch64-linux.matrix-synapse
· build #1076
· raw
1build01: building 7bay0pybs0zbj8r1cncjw3s85nfc7q60-container-test-run-matrix-synapse.drv2additionally exposed symbols:3 machine,4 start_all, machines, driver, Machine, wait_for_signal5Starting machine6░ Spawning container machine on /build/tmpl6ia14do/machine.78<<< NixOS Stage 2 >>>910booting system configuration /nix/store/5xd1rdyix7261bs0hw5iimghh0jqslp6-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 9ecd0d07-2b0e-4da9-875e-edb515e6e099'30Running as unit: run-p476-i10927738.service; invocation ID: 37ccae581176415a9a9b473ef2694bd931To attach to container machine run on the same machine that runs the test:32 sudo nsenter --user --target $(\pgrep -f '^/bin/sh.*9ecd0d07-2b0e-4da9-875e-edb515e6e099') --mount --uts --ipc --net --pid --cgroup /bin/sh -c bash 3334To inject external network and continue test, run:35sudo /nix/store/q1khrhbn506imjxx7kiqcbihycawa6kl-python3-3.13.15/bin/python3.13 /nix/store/6kasz8qw4mw4szwg29sxyra6pgbwz4zq-test-driver-0.0.1/lib/python3.13/site-packages/test_driver/inject_network.py 9ecd0d07-2b0e-4da9-875e-edb515e6e09936+ systemctl --no-pager show matrix-synapse37+ systemctl --no-pager show matrix-synapse38+ systemctl --no-pager show matrix-synapse39+ systemctl --no-pager show matrix-synapse40+ systemctl --no-pager show matrix-synapse41+ systemctl --no-pager show matrix-synapse42+ systemctl --no-pager show matrix-synapse43+ systemctl --no-pager show matrix-synapse44+ systemctl --no-pager show matrix-synapse45+ systemctl --no-pager show matrix-synapse46+ systemctl --no-pager show matrix-synapse47+ nc -z -v ::1 800848Connection to ::1 8008 port [tcp/http-alt] succeeded!49machine: waiting for success: curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test'50+ curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test'51(finished: waiting for success: curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test', in 0.02 seconds)52+ systemctl restart matrix-synapse53+ journalctl -u matrix-synapse --no-pager54Oct 22 10:15:32 machine systemd[1]: Starting Synapse Matrix homeserver...55Oct 22 10:15:35 machine matrix-synapse-pre-start[482]: Generating signing key file /var/lib/matrix-synapse/homeserver.signing.key56Oct 22 10:15:39 machine synapse_homeserver[495]: This server is configured to use 'matrix.org' as its trusted key server via the57Oct 22 10:15:39 machine synapse_homeserver[495]: 'trusted_key_servers' config option. 'matrix.org' is a good choice for a key58Oct 22 10:15:39 machine synapse_homeserver[495]: server since it is long-lived, stable and trusted. However, some admins may59Oct 22 10:15:39 machine synapse_homeserver[495]: wish to use another server for this purpose.60Oct 22 10:15:39 machine synapse_homeserver[495]: To suppress this warning and continue using 'matrix.org', admins should set61Oct 22 10:15:39 machine synapse_homeserver[495]: 'suppress_key_server_warning' to 'true' in homeserver.yaml.62Oct 22 10:15:39 machine synapse_homeserver[495]: --------------------------------------------------------------------------------63Oct 22 10:15:39 machine synapse[495]: synapse.config.logger: [main] ***** STARTING SERVER *****64Oct 22 10:15:39 machine synapse[495]: synapse.config.logger: [main] Server /nix/store/g22adqn9l5sdmdzw41l3d91h8l24cw0x-matrix-synapse-1.163.0/bin/synapse_homeserver version 1.163.065Oct 22 10:15:39 machine synapse[495]: synapse.config.logger: [main] Copyright (c) 2023 New Vector, Inc66Oct 22 10:15:39 machine synapse[495]: synapse.config.logger: [main] Licensed under the AGPL 3.0 license. Website: https://github.com/element-hq/synapse67Oct 22 10:15:39 machine synapse[495]: synapse.config.logger: [main] Server hostname: clan.test68Oct 22 10:15:39 machine synapse[495]: synapse.config.logger: [main] Public Base URL: https://clan.test/69Oct 22 10:15:39 machine synapse[495]: synapse.config.logger: [main] Instance name: master70Oct 22 10:15:39 machine synapse[495]: synapse.config.logger: [main] Twisted reactor: EPollReactor71Oct 22 10:15:39 machine synapse[495]: synapse.app.homeserver: [main] Setting up server72Oct 22 10:15:39 machine synapse[495]: synapse.server: [main] Setting up.73Oct 22 10:15:39 machine synapse[495]: synapse.storage.databases: [main] [database config 'master']: Checking database server74Oct 22 10:15:39 machine synapse[495]: synapse.storage.databases: [main] [database config 'master']: Preparing for databases ['main', 'state']75Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] ['main', 'state']: Checking existing schema version76Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] ['main', 'state']: Initialising new database77Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema deltas for v7378Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 73/01event_failed_pull_attempts.sql79Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 73/02add_pusher_enabled.sql80Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 73/02room_id_indexes_for_purging.sql81Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 73/03pusher_device_id.sql82Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 73/03users_approved_column.sql83Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 73/04partial_join_details.sql84Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 73/04pending_device_list_updates.sql85Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/05old_push_actions.sql.postgres86Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 73/06thread_notifications_thread_id_idx.sql87Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/08thread_receipts_non_null.sql.postgres88Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 73/09partial_joined_via_destination.sql89Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 73/09threads_table.sql90Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Running 73/10_update_sqlite_fts4_tokenizer.py:run_create91Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 73/10login_tokens.sql92Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/11event_search_room_id_n_distinct.sql.postgres93Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 73/12refactor_device_list_outbound_pokes.sql94Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 73/13add_device_lists_index.sql95Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 73/20_un_partial_stated_room_stream.sql96Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/21_un_partial_stated_room_stream_seq.sql.postgres97Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 73/22_rebuild_user_dir_stats.sql98Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 73/22_un_partial_stated_event_stream.sql99Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 73/23_fix_thread_index.sql100Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/23_un_partial_stated_room_stream_seq.sql.postgres101Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 73/24_events_jump_to_date_index.sql102Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 73/25drop_presence.sql103Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema deltas for v74104Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 74/01_user_directory_stale_remote_users.sql105Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 74/02_set_device_id_for_pushers_bg_update.sql106Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying engine-specific schema 74/03_membership_tables_event_stream_ordering.sql.postgres107Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 74/03_room_membership_index.sql108Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 74/04_delete_e2e_backup_keys_for_deactivated_users.sql109Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Running 74/04_membership_tables_event_stream_ordering_triggers.py:run_create110Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 74/05_events_txn_id_device_id.sql111Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying engine-specific schema 74/90COMMENTS_destinations.sql.postgres112Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema deltas for v75113Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema deltas for v76114Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 76/01_add_profiles_full_user_id_column.sql115Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 76/02_add_user_filters_full_user_id_column.sql116Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 76/03_per_user_experimental_features.sql117Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 76/04_add_room_forgetter.sql118Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema deltas for v77119Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/01_add_profiles_not_valid_check.sql.postgres120Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/02_add_user_filters_not_valid_check.sql.postgres121Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 77/03bg_populate_full_user_id_profiles.sql122Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 77/04bg_populate_full_user_id_user_filters.sql123Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 77/05thread_notifications_backfill.sql124Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_actions.sql.postgres125Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_actions_staging.sql.postgres126Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_summary.sql.postgres127Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 77/14bg_indices_event_stream_ordering.sql128Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema deltas for v78129Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Running 78/03event_extremities_constraints.py:run_create130Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Running 78/04_add_full_user_id_index_user_filters.py:run_create131Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema deltas for v79132Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying engine-specific schema 79/03_read_write_locks_triggers.sql.postgres133Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Running 79/04_mitigate_stream_ordering_update_race.py:run_create134Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying engine-specific schema 79/05_read_write_locks_triggers.sql.postgres135Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema deltas for v80136Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 80/01_users_alter_locked.sql137Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/02_read_write_locks_unlogged.sql.postgres138Oct 22 10:15:39 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 80/02_scheduled_tasks.sql139Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/03_read_write_locks_triggers.sql.postgres140Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/04_read_write_locks_deadlock.sql.postgres141Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema deltas for v81142Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema deltas for v82143Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 82/02_scheduled_tasks_index.sql144Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 82/04_add_indices_for_purging_rooms.sql145Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 82/05gaps.sql146Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema deltas for v83147Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 83/01_drop_old_tables.sql148Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 83/05_cross_signing_key_update_grant.sql149Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 83/06_event_push_summary_room.sql150Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema deltas for v84151Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying engine-specific schema 84/01_auth_links_stats.sql.postgres152Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 84/02_auth_links_index.sql153Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying engine-specific schema 84/03_auth_links_analyze.sql.postgres154Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 84/04_access_token_index.sql155Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema deltas for v85156Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 85/01_add_suspended.sql157Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 85/02_add_instance_names.sql158Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying engine-specific schema 85/03_new_sequences.sql.postgres159Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 85/04_cleanup_device_federation_outbox.sql160Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 85/05_add_instance_names_converted_pos.sql161Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 85/06_add_room_reports.sql162Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema deltas for v86163Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 86/01_authenticate_media.sql164Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 86/02_receipts_event_id_index.sql165Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema deltas for v87166Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 87/01_sliding_sync_memberships.sql167Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 87/02_per_connection_state.sql168Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 87/03_current_state_index.sql169Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema deltas for v88170Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 88/01_add_delayed_events.sql171Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 88/01_custom_profile_fields.sql172Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 88/02_fix_sliding_sync_membership_snapshots_forgotten_column.sql173Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 88/03_add_otk_ts_added_index.sql174Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 88/04_current_state_delta_index.sql175Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying engine-specific schema 88/05_drop_old_otks.sql.postgres176Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 88/05_sliding_sync_room_config_index.sql177Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 88/06_events_received_ts_index.sql178Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema deltas for v89179Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 89/01_sliding_sync_membership_snapshot_index.sql180Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 89/01_state_groups_deletion.sql181Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema deltas for v90182Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 90/01_add_column_participant_room_memberships_table.sql183Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 90/02_delete_unreferenced_state_groups.sql184Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 90/03_remove_old_deletion_bg_update.sql185Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema deltas for v91186Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 91/01_media_hash.sql187Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema deltas for v92188Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/01_remove_trigger.sql.postgres189Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 92/02_remove_populate_participant_bg_update.sql190Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 92/04_ss_membership_snapshot_idx.sql191Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 92/04_thread_subscriptions.sql192Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/04_thread_subscriptions_seq.sql.postgres193Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 92/05_fixup_max_depth_cap.sql194Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/05_thread_subscriptions_comments.sql.postgres195Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 92/06_device_federation_inbox_index.sql196Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/06_threads_last_sent_stream_ordering_comments.sql.postgres197Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 92/07_add_user_reports.sql198Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 92/07_event_txn_id_device_id_txn_id2.sql199Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 92/08_room_ban_redactions.sql200Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/08_thread_subscriptions_seq_fixup.sql.postgres201Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 92/09_thread_subscriptions_update.sql202Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/09_thread_subscriptions_update.sql.postgres203Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema deltas for v93204Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 93/01_add_delayed_events.sql205Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 93/01_sticky_events.sql206Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying engine-specific schema 93/01_sticky_events_seq.sql.postgres207Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 93/02_sliding_sync_members.sql208Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 93/03_sss_pos_last_used.sql209Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Running 93/04_make_delayed_event_content_text.py:run_create210Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema deltas for v94211Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 94/01_redactions_recheck.sql212Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying engine-specific schema 94/01_state_groups_persisting_inserted_ts.sql.postgres213Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 94/02_redactions_recheck_bg_update.sql214Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 94/03_device_lists_room_timestamp.sql215Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 94/03_quarantined_media_tracking.sql216Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying engine-specific schema 94/03_quarantined_media_tracking_seq.sql.postgres217Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 94/03_state_dag_fwd_extrems.sql218Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 94/04_device_lists_changes_max_pruned.sql219Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 94/05_rerun_flag_existing_quarantined_media.sql220Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 94/06_sliding_sync_connections_last_used_ts_index.sql221Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 94/07_profile_updates.sql222Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying engine-specific schema 94/07_profile_updates_seq.sql.postgres223Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 94/07_sliding_sync_lazy_members_position_index.sql224Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 94/08_device_lists_changes_in_room_unconverted_idx.sql225Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 94/09_e2e_cross_signing_signatures_index.sql226Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 94/10_e2e_cross_signing_signatures_add_key_id_to_index.sql227Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Applying schema 94/11_destination_room_sticky_events.sql228Oct 22 10:15:40 machine synapse[495]: synapse.storage.prepare_database: [main] Schema now up to date229Oct 22 10:15:40 machine synapse[495]: synapse.storage.databases: [main] [database config 'master']: Starting 'main' database230Oct 22 10:15:40 machine synapse[495]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules(id): 1231Oct 22 10:15:40 machine synapse[495]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules_enable(id): 1232Oct 22 10:15:40 machine synapse[495]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 month ago233Oct 22 10:15:40 machine synapse[495]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 month ago: it's 0234Oct 22 10:15:40 machine synapse[495]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 day ago235Oct 22 10:15:40 machine synapse[495]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 day ago: it's 0236Oct 22 10:15:40 machine synapse[495]: synapse.storage.util.id_generators: [main] Initialising stream generator for access_tokens(id): 1237Oct 22 10:15:40 machine synapse[495]: synapse.storage.util.id_generators: [main] Initialising stream generator for refresh_tokens(id): 1238Oct 22 10:15:40 machine synapse[495]: synapse.storage.util.id_generators: [main] Initialising stream generator for event_reports(id): 1239Oct 22 10:15:40 machine synapse[495]: synapse.storage.util.id_generators: [main] Initialising stream generator for room_reports(id): 1240Oct 22 10:15:40 machine synapse[495]: synapse.storage.util.id_generators: [main] Initialising stream generator for user_reports(id): 1241Oct 22 10:15:40 machine synapse[495]: synapse.storage.databases: [main] [database config 'master']: Starting 'state' database242Oct 22 10:15:40 machine synapse[495]: synapse.storage.databases: [main] [database config 'master']: prepared243Oct 22 10:15:40 machine synapse[495]: synapse.server: [main] Finished setting up.244Oct 22 10:15:40 machine synapse[495]: twisted: [main] Redirected stdout/stderr to logs245Oct 22 10:15:40 machine synapse[495]: synapse.app.homeserver: [main] Running246Oct 22 10:15:40 machine synapse[495]: synapse.app.homeserver: [main] Set file limit to: 524288247Oct 22 10:15:40 machine synapse[495]: synapse.push.pusher: [call_when_running] email enable notifs: False248Oct 22 10:15:40 machine synapse[495]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'profile'249Oct 22 10:15:40 machine synapse[495]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.device_list_update'250Oct 22 10:15:40 machine synapse[495]: synapse.util.caches.lrucache: [call_when_running] Expiring LRU caches after 1800 seconds251Oct 22 10:15:40 machine synapse[495]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.presence'252Oct 22 10:15:40 machine synapse[495]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.typing'253Oct 22 10:15:40 machine synapse[495]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'directory'254Oct 22 10:15:40 machine synapse[495]: synapse.handlers.deactivate_account: [user_parter_loop-0] Starting user parter255Oct 22 10:15:40 machine synapse[495]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.receipt'256Oct 22 10:15:40 machine synapse[495]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.signing_key_update'257Oct 22 10:15:40 machine synapse[495]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'org.matrix.signing_key_update'258Oct 22 10:15:40 machine synapse[495]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'client_keys'259Oct 22 10:15:40 machine synapse[495]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.direct_to_device'260Oct 22 10:15:40 machine synapse[495]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.health.HealthResource object at 0xffffee09c720> to path b'/health'261Oct 22 10:15:40 machine synapse[495]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.EncodingResourceWrapper object at 0xffffee3c7d20> to path b'/_matrix/client'262Oct 22 10:15:40 machine synapse[495]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffee3cda20> to path b'/.well-known'263Oct 22 10:15:40 machine synapse[495]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.http.server.JsonResource object at 0xffffee3c7ea0> to path b'/_synapse/admin'264Oct 22 10:15:40 machine synapse[495]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.pick_idp.PickIdpResource object at 0xffffee3cdea0> to path b'/_synapse/client/pick_idp'265Oct 22 10:15:40 machine synapse[495]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffe3e0a020> to path b'/_synapse/client/pick_username'266Oct 22 10:15:40 machine synapse[495]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.new_user_consent.NewUserConsentResource object at 0xffffee3ce4a0> to path b'/_synapse/client/new_user_consent'267Oct 22 10:15:40 machine synapse[495]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.sso_register.SsoRegisterResource object at 0xffffee3ce620> to path b'/_synapse/client/sso_register'268Oct 22 10:15:40 machine synapse[495]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.unsubscribe.UnsubscribeResource object at 0xffffee3ce7a0> to path b'/_synapse/client/unsubscribe'269Oct 22 10:15:40 machine synapse[495]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.media_upload_limit_exceeded.MediaUploadLimitExceededResource object at 0xffffee3ce920> to path b'/_synapse/client/media_upload_limit_exceeded'270Oct 22 10:15:40 machine synapse[495]: synapse.util.httpresourcetree: [call_when_running] Attaching FilePath('/nix/store/g22adqn9l5sdmdzw41l3d91h8l24cw0x-matrix-synapse-1.163.0/lib/python3.13/site-packages/synapse/static') to path b'/_matrix/static'271Oct 22 10:15:40 machine synapse[495]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee3ceda0> to path b'/_matrix/media/r0'272Oct 22 10:15:40 machine synapse[495]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee3ceda0> to path b'/_matrix/media/v3'273Oct 22 10:15:40 machine synapse[495]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee3ceda0> to path b'/_matrix/media/v1'274Oct 22 10:15:40 machine synapse[495]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.federation.transport.server.TransportLayerServer object at 0xffffee3cf9a0> to path b'/_matrix/federation'275Oct 22 10:15:40 machine synapse[495]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.key.v2.KeyResource object at 0xffffee3d2b20> to path b'/_matrix/key'276Oct 22 10:15:40 machine synapse[495]: twisted: [call_when_running] SynapseSite starting on 8008277Oct 22 10:15:40 machine synapse[495]: synapse.app._base: [call_when_running] Synapse now listening on TCP port 8008278Oct 22 10:15:40 machine synapse[495]: 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).279Oct 22 10:15:40 machine synapse[495]: synapse.storage.background_updates: [background_updates-0] Starting background schema updates for database master280Oct 22 10:15:40 machine synapse[495]: synapse.handlers.deactivate_account: [user_parter_loop-0] User parter finished: stopping281Oct 22 10:15:40 machine synapse[495]: synapse.push.pusherpool: [start_pushers-0] Started pushers282Oct 22 10:15:40 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[524]: Connection to ::1 8008 port [tcp/http-alt] succeeded!283Oct 22 10:15:41 machine synapse[495]: 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]284Oct 22 10:15:41 machine synapse[495]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'threads_backfill'285Oct 22 10:15:41 machine synapse[495]: synapse.storage.background_updates: [background_updates-0] Running background update 'threads_backfill'. Processed 0 items in 44ms. (total_rate=None/ms, current_rate=None/ms, total_updated=0, batch_size=100)286Oct 22 10:15:41 machine synapse[495]: synapse.handlers.auth: [POST-1] Logging in user @admin:clan.test on device ACTFTUZPDN287Oct 22 10:15:41 machine synapse[495]: synapse.access.http.8008: [POST-1] ::1 - 8008 - {None} Processed request: 0.470sec/0.001sec ru=(0.261sec, 0.004sec) db=(0.005sec/0.202sec/12) 138B 200 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]288Oct 22 10:15:41 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[525]: Sending registration request...289Oct 22 10:15:41 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[525]: Success!290Oct 22 10:15:42 machine synapse[495]: synapse.access.http.8008: [GET-2] ::1 - 8008 - {None} Processed request: 0.000sec/0.000sec ru=(0.000sec, 0.000sec) db=(0.000sec/0.000sec/0) 140B 200 "GET /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]291Oct 22 10:15:42 machine synapse[495]: synapse.handlers.auth: [POST-3] Logging in user @someuser:clan.test on device FEIYCJHNUK292Oct 22 10:15:42 machine synapse[495]: synapse.access.http.8008: [POST-3] ::1 - 8008 - {None} Processed request: 0.270sec/0.001sec ru=(0.243sec, 0.001sec) db=(0.005sec/0.016sec/12) 145B 200 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]293Oct 22 10:15:42 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[532]: Sending registration request...294Oct 22 10:15:42 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[532]: Success!295Oct 22 10:15:42 machine synapse[495]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'device_lists_changes_in_room_by_room_index'296Oct 22 10:15:42 machine synapse[495]: synapse.storage.background_updates: [background_updates-0] Adding index device_lists_changes_in_room_by_room_idx to device_lists_changes_in_room297Oct 22 10:15:42 machine synapse[495]: 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)298Oct 22 10:15:42 machine systemd[1]: Started Synapse Matrix homeserver.299Oct 22 10:15:43 machine synapse[495]: 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]300Oct 22 10:15:43 machine synapse[495]: twisted: [sentinel] Received SIGTERM, shutting down.301Oct 22 10:15:43 machine systemd[1]: Stopping Synapse Matrix homeserver...302Oct 22 10:15:43 machine synapse[495]: synapse.storage.databases.main.lock: [LockStore._on_shutdown-0] Dropping held locks due to shutdown303Oct 22 10:15:43 machine synapse[495]: synapse.storage.databases.main.lock: [LockStore._on_shutdown-0] Dropped locks due to shutdown304Oct 22 10:15:43 machine synapse[495]: synapse.handlers.presence: [PresenceHandler._on_shutdown-0] Performing _on_shutdown. Persisting 0 unpersisted changes305Oct 22 10:15:43 machine synapse[495]: synapse.handlers.presence: [PresenceHandler._on_shutdown-0] Finished _on_shutdown306Oct 22 10:15:43 machine synapse[495]: synapse.app._base: [log_shutdown] Shutting down...307Oct 22 10:15:43 machine synapse[495]: twisted: [sentinel] (TCP Port 8008 Closed)308Oct 22 10:15:43 machine synapse[495]: twisted: [sentinel] Main loop terminated.309Oct 22 10:15:43 machine systemd[1]: matrix-synapse.service: Deactivated successfully.310Oct 22 10:15:43 machine systemd[1]: Stopped Synapse Matrix homeserver.311Oct 22 10:15:43 machine systemd[1]: matrix-synapse.service: Consumed 8.909s CPU time over 10.857s wall clock time.312Oct 22 10:15:43 machine systemd[1]: Starting Synapse Matrix homeserver...313Oct 22 10:15:49 machine synapse_homeserver[564]: This server is configured to use 'matrix.org' as its trusted key server via the314Oct 22 10:15:49 machine synapse_homeserver[564]: 'trusted_key_servers' config option. 'matrix.org' is a good choice for a key315Oct 22 10:15:49 machine synapse_homeserver[564]: server since it is long-lived, stable and trusted. However, some admins may316Oct 22 10:15:49 machine synapse_homeserver[564]: wish to use another server for this purpose.317Oct 22 10:15:49 machine synapse_homeserver[564]: To suppress this warning and continue using 'matrix.org', admins should set318Oct 22 10:15:49 machine synapse_homeserver[564]: 'suppress_key_server_warning' to 'true' in homeserver.yaml.319Oct 22 10:15:49 machine synapse_homeserver[564]: --------------------------------------------------------------------------------320Oct 22 10:15:49 machine synapse[564]: synapse.config.logger: [main] ***** STARTING SERVER *****321Oct 22 10:15:49 machine synapse[564]: synapse.config.logger: [main] Server /nix/store/g22adqn9l5sdmdzw41l3d91h8l24cw0x-matrix-synapse-1.163.0/bin/synapse_homeserver version 1.163.0322Oct 22 10:15:49 machine synapse[564]: synapse.config.logger: [main] Copyright (c) 2023 New Vector, Inc323Oct 22 10:15:49 machine synapse[564]: synapse.config.logger: [main] Licensed under the AGPL 3.0 license. Website: https://github.com/element-hq/synapse324Oct 22 10:15:49 machine synapse[564]: synapse.config.logger: [main] Server hostname: clan.test325Oct 22 10:15:49 machine synapse[564]: synapse.config.logger: [main] Public Base URL: https://clan.test/326Oct 22 10:15:49 machine synapse[564]: synapse.config.logger: [main] Instance name: master327Oct 22 10:15:49 machine synapse[564]: synapse.config.logger: [main] Twisted reactor: EPollReactor328Oct 22 10:15:49 machine synapse[564]: synapse.app.homeserver: [main] Setting up server329Oct 22 10:15:49 machine synapse[564]: synapse.server: [main] Setting up.330Oct 22 10:15:49 machine synapse[564]: synapse.storage.databases: [main] [database config 'master']: Checking database server331Oct 22 10:15:49 machine synapse[564]: synapse.storage.databases: [main] [database config 'master']: Preparing for databases ['main', 'state']332Oct 22 10:15:49 machine synapse[564]: synapse.storage.prepare_database: [main] ['main', 'state']: Checking existing schema version333Oct 22 10:15:49 machine synapse[564]: synapse.storage.prepare_database: [main] ['main', 'state']: Existing schema is 94 (+17 deltas)334Oct 22 10:15:49 machine synapse[564]: synapse.storage.databases.main: [main] Checking database for consistency with configuration...335Oct 22 10:15:49 machine synapse[564]: synapse.storage.prepare_database: [main] Applying schema deltas for v94336Oct 22 10:15:49 machine synapse[564]: synapse.storage.prepare_database: [main] Schema now up to date337Oct 22 10:15:50 machine synapse[564]: synapse.storage.databases: [main] [database config 'master']: Starting 'main' database338Oct 22 10:15:50 machine synapse[564]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules(id): 1339Oct 22 10:15:50 machine synapse[564]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules_enable(id): 1340Oct 22 10:15:50 machine synapse[564]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 month ago341Oct 22 10:15:50 machine synapse[564]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 month ago: it's 0342Oct 22 10:15:50 machine synapse[564]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 day ago343Oct 22 10:15:50 machine synapse[564]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 day ago: it's 0344Oct 22 10:15:50 machine synapse[564]: synapse.storage.util.id_generators: [main] Initialising stream generator for access_tokens(id): 3345Oct 22 10:15:50 machine synapse[564]: synapse.storage.util.id_generators: [main] Initialising stream generator for refresh_tokens(id): 1346Oct 22 10:15:50 machine synapse[564]: synapse.storage.util.id_generators: [main] Initialising stream generator for event_reports(id): 1347Oct 22 10:15:50 machine synapse[564]: synapse.storage.util.id_generators: [main] Initialising stream generator for room_reports(id): 1348Oct 22 10:15:50 machine synapse[564]: synapse.storage.util.id_generators: [main] Initialising stream generator for user_reports(id): 1349Oct 22 10:15:50 machine synapse[564]: synapse.storage.databases: [main] [database config 'master']: Starting 'state' database350Oct 22 10:15:50 machine synapse[564]: synapse.storage.databases: [main] [database config 'master']: prepared351Oct 22 10:15:50 machine synapse[564]: synapse.server: [main] Finished setting up.352Oct 22 10:15:50 machine synapse[564]: twisted: [main] Redirected stdout/stderr to logs353Oct 22 10:15:50 machine synapse[564]: synapse.app.homeserver: [main] Running354Oct 22 10:15:50 machine synapse[564]: synapse.app.homeserver: [main] Set file limit to: 524288355Oct 22 10:15:50 machine synapse[564]: synapse.push.pusher: [call_when_running] email enable notifs: False356Oct 22 10:15:50 machine synapse[564]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'profile'357Oct 22 10:15:50 machine synapse[564]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.device_list_update'358Oct 22 10:15:50 machine synapse[564]: synapse.util.caches.lrucache: [call_when_running] Expiring LRU caches after 1800 seconds359Oct 22 10:15:50 machine synapse[564]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.presence'360Oct 22 10:15:50 machine synapse[564]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.typing'361Oct 22 10:15:50 machine synapse[564]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'directory'362Oct 22 10:15:50 machine synapse[564]: synapse.handlers.deactivate_account: [user_parter_loop-0] Starting user parter363Oct 22 10:15:50 machine synapse[564]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.receipt'364Oct 22 10:15:50 machine synapse[564]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.signing_key_update'365Oct 22 10:15:50 machine synapse[564]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'org.matrix.signing_key_update'366Oct 22 10:15:50 machine synapse[564]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'client_keys'367Oct 22 10:15:50 machine synapse[564]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.direct_to_device'368Oct 22 10:15:50 machine synapse[564]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.health.HealthResource object at 0xffffee09c8a0> to path b'/health'369Oct 22 10:15:50 machine synapse[564]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.EncodingResourceWrapper object at 0xffffee3a7ea0> to path b'/_matrix/client'370Oct 22 10:15:50 machine synapse[564]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffee3adba0> to path b'/.well-known'371Oct 22 10:15:50 machine synapse[564]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.http.server.JsonResource object at 0xffffee3a8020> to path b'/_synapse/admin'372Oct 22 10:15:50 machine synapse[564]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.pick_idp.PickIdpResource object at 0xffffee3ae020> to path b'/_synapse/client/pick_idp'373Oct 22 10:15:50 machine synapse[564]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffe3e09ee0> to path b'/_synapse/client/pick_username'374Oct 22 10:15:50 machine synapse[564]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.new_user_consent.NewUserConsentResource object at 0xffffee3ae620> to path b'/_synapse/client/new_user_consent'375Oct 22 10:15:50 machine synapse[564]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.sso_register.SsoRegisterResource object at 0xffffee3ae7a0> to path b'/_synapse/client/sso_register'376Oct 22 10:15:50 machine synapse[564]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.unsubscribe.UnsubscribeResource object at 0xffffee3ae920> to path b'/_synapse/client/unsubscribe'377Oct 22 10:15:50 machine synapse[564]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.media_upload_limit_exceeded.MediaUploadLimitExceededResource object at 0xffffee3aeaa0> to path b'/_synapse/client/media_upload_limit_exceeded'378Oct 22 10:15:50 machine synapse[564]: synapse.util.httpresourcetree: [call_when_running] Attaching FilePath('/nix/store/g22adqn9l5sdmdzw41l3d91h8l24cw0x-matrix-synapse-1.163.0/lib/python3.13/site-packages/synapse/static') to path b'/_matrix/static'379Oct 22 10:15:50 machine synapse[564]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee3aef20> to path b'/_matrix/media/r0'380Oct 22 10:15:50 machine synapse[564]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee3aef20> to path b'/_matrix/media/v3'381Oct 22 10:15:50 machine synapse[564]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee3aef20> to path b'/_matrix/media/v1'382Oct 22 10:15:50 machine synapse[564]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.federation.transport.server.TransportLayerServer object at 0xffffee3afb20> to path b'/_matrix/federation'383Oct 22 10:15:50 machine synapse[564]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.key.v2.KeyResource object at 0xffffee3b2ca0> to path b'/_matrix/key'384Oct 22 10:15:50 machine synapse[564]: twisted: [call_when_running] SynapseSite starting on 8008385Oct 22 10:15:50 machine synapse[564]: synapse.app._base: [call_when_running] Synapse now listening on TCP port 8008386Oct 22 10:15:50 machine synapse[564]: synapse.app._base: [call_when_running] garbage collector: Freezing all allocated objects in the hopes that (almost) everything currently allocated are things that will be used by the homeserver for the rest of time. Doing so means less work each GC (hopefully).387Oct 22 10:15:50 machine synapse[564]: synapse.storage.background_updates: [background_updates-0] Starting background schema updates for database master388Oct 22 10:15:50 machine synapse[564]: synapse.handlers.deactivate_account: [user_parter_loop-0] User parter finished: stopping389Oct 22 10:15:50 machine synapse[564]: synapse.push.pusherpool: [start_pushers-0] Started pushers390Oct 22 10:15:50 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[589]: Connection to ::1 8008 port [tcp/http-alt] succeeded!391Oct 22 10:15:51 machine synapse[564]: 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]392Oct 22 10:15:52 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[590]: Sending registration request...393Oct 22 10:15:52 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[590]: User already exists. Skipping.394Oct 22 10:15:51 machine synapse[564]: synapse.http.server: [POST-1] <XForwardedForRequest at 0xffffe3e049e0 method='POST' uri='/_synapse/admin/v1/register' clientproto='HTTP/1.1' site='8008'> SynapseError: 400 - User ID already taken.395Oct 22 10:15:51 machine synapse[564]: synapse.access.http.8008: [POST-1] ::1 - 8008 - {None} Processed request: 0.264sec/0.000sec ru=(0.256sec, 0.000sec) db=(0.000sec/0.002sec/1) 60B 400 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]396Oct 22 10:15:51 machine synapse[564]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'populate_stats_process_rooms'397Oct 22 10:15:52 machine synapse[564]: synapse.access.http.8008: [GET-2] ::1 - 8008 - {None} Processed request: 0.000sec/0.000sec ru=(0.000sec, 0.000sec) db=(0.000sec/0.000sec/0) 140B 200 "GET /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]398Oct 22 10:15:52 machine synapse[564]: synapse.storage.background_updates: [background_updates-0] Running background update 'populate_stats_process_rooms'. Processed 1 items in 546ms. (total_rate=0.0018315018315018315/ms, current_rate=0.0018315018315018315/ms, total_updated=1, batch_size=100)399Oct 22 10:15:52 machine synapse[564]: synapse.http.server: [POST-3] <XForwardedForRequest at 0xffffedc4a260 method='POST' uri='/_synapse/admin/v1/register' clientproto='HTTP/1.1' site='8008'> SynapseError: 400 - User ID already taken.400Oct 22 10:15:52 machine synapse[564]: synapse.access.http.8008: [POST-3] ::1 - 8008 - {None} Processed request: 0.240sec/0.000sec ru=(0.234sec, 0.000sec) db=(0.000sec/0.001sec/1) 60B 400 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]401Oct 22 10:15:52 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[596]: Sending registration request...402Oct 22 10:15:52 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[596]: User already exists. Skipping.403Oct 22 10:15:52 machine systemd[1]: Started Synapse Matrix homeserver.404+ systemctl --no-pager show matrix-synapse405+ nc -z -v ::1 8008406Connection to ::1 8008 port [tcp/http-alt] succeeded!407+ curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test'408Container machine terminated by signal KILL.