container-test-run-matrix-synapse
checks.aarch64-linux.matrix-synapse
· build #1008
· raw
1build01: building 2bsqb7rlkh74wxns57fdcli9vlawch0b-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/gk3p86mhmx9jswzrwj6y34f41z97dhvb-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 7f211916-8165-4132-ad3f-f81fc46ab5b3'30Running as unit: run-p480-i377910.service; invocation ID: c6df712ee79140c5aa02a99424bb886c31To attach to container machine run on the same machine that runs the test:32 sudo nsenter --user --target $(\pgrep -f '^/bin/sh.*7f211916-8165-4132-ad3f-f81fc46ab5b3') --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 7f211916-8165-4132-ad3f-f81fc46ab5b336+ 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+ nc -z -v ::1 800847Connection to ::1 8008 port [tcp/http-alt] succeeded!48machine: waiting for success: curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test'49+ curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test'50(finished: waiting for success: curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test', in 0.02 seconds)51+ systemctl restart matrix-synapse52+ journalctl -u matrix-synapse --no-pager53Oct 16 10:07:15 machine systemd[1]: Starting Synapse Matrix homeserver...54Oct 16 10:07:19 machine matrix-synapse-pre-start[475]: Generating signing key file /var/lib/matrix-synapse/homeserver.signing.key55Oct 16 10:07:23 machine synapse_homeserver[492]: This server is configured to use 'matrix.org' as its trusted key server via the56Oct 16 10:07:23 machine synapse_homeserver[492]: 'trusted_key_servers' config option. 'matrix.org' is a good choice for a key57Oct 16 10:07:23 machine synapse_homeserver[492]: server since it is long-lived, stable and trusted. However, some admins may58Oct 16 10:07:23 machine synapse_homeserver[492]: wish to use another server for this purpose.59Oct 16 10:07:23 machine synapse_homeserver[492]: To suppress this warning and continue using 'matrix.org', admins should set60Oct 16 10:07:23 machine synapse_homeserver[492]: 'suppress_key_server_warning' to 'true' in homeserver.yaml.61Oct 16 10:07:23 machine synapse_homeserver[492]: --------------------------------------------------------------------------------62Oct 16 10:07:23 machine synapse[492]: synapse.config.logger: [main] ***** STARTING SERVER *****63Oct 16 10:07:23 machine synapse[492]: synapse.config.logger: [main] Server /nix/store/928l52anrm0pvcn6k9haijzhbygjrjs5-matrix-synapse-1.162.0/bin/synapse_homeserver version 1.162.064Oct 16 10:07:23 machine synapse[492]: synapse.config.logger: [main] Copyright (c) 2023 New Vector, Inc65Oct 16 10:07:23 machine synapse[492]: synapse.config.logger: [main] Licensed under the AGPL 3.0 license. Website: https://github.com/element-hq/synapse66Oct 16 10:07:23 machine synapse[492]: synapse.config.logger: [main] Server hostname: clan.test67Oct 16 10:07:23 machine synapse[492]: synapse.config.logger: [main] Public Base URL: https://clan.test/68Oct 16 10:07:23 machine synapse[492]: synapse.config.logger: [main] Instance name: master69Oct 16 10:07:23 machine synapse[492]: synapse.config.logger: [main] Twisted reactor: EPollReactor70Oct 16 10:07:23 machine synapse[492]: synapse.app.homeserver: [main] Setting up server71Oct 16 10:07:23 machine synapse[492]: synapse.server: [main] Setting up.72Oct 16 10:07:23 machine synapse[492]: synapse.storage.databases: [main] [database config 'master']: Checking database server73Oct 16 10:07:23 machine synapse[492]: synapse.storage.databases: [main] [database config 'master']: Preparing for databases ['main', 'state']74Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] ['main', 'state']: Checking existing schema version75Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] ['main', 'state']: Initialising new database76Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v7377Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/01event_failed_pull_attempts.sql78Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/02add_pusher_enabled.sql79Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/02room_id_indexes_for_purging.sql80Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/03pusher_device_id.sql81Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/03users_approved_column.sql82Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/04partial_join_details.sql83Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/04pending_device_list_updates.sql84Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/05old_push_actions.sql.postgres85Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/06thread_notifications_thread_id_idx.sql86Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/08thread_receipts_non_null.sql.postgres87Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/09partial_joined_via_destination.sql88Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/09threads_table.sql89Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Running 73/10_update_sqlite_fts4_tokenizer.py:run_create90Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/10login_tokens.sql91Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/11event_search_room_id_n_distinct.sql.postgres92Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/12refactor_device_list_outbound_pokes.sql93Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/13add_device_lists_index.sql94Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/20_un_partial_stated_room_stream.sql95Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/21_un_partial_stated_room_stream_seq.sql.postgres96Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/22_rebuild_user_dir_stats.sql97Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/22_un_partial_stated_event_stream.sql98Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/23_fix_thread_index.sql99Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/23_un_partial_stated_room_stream_seq.sql.postgres100Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/24_events_jump_to_date_index.sql101Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 73/25drop_presence.sql102Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v74103Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 74/01_user_directory_stale_remote_users.sql104Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 74/02_set_device_id_for_pushers_bg_update.sql105Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 74/03_membership_tables_event_stream_ordering.sql.postgres106Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 74/03_room_membership_index.sql107Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 74/04_delete_e2e_backup_keys_for_deactivated_users.sql108Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Running 74/04_membership_tables_event_stream_ordering_triggers.py:run_create109Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 74/05_events_txn_id_device_id.sql110Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 74/90COMMENTS_destinations.sql.postgres111Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v75112Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v76113Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 76/01_add_profiles_full_user_id_column.sql114Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 76/02_add_user_filters_full_user_id_column.sql115Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 76/03_per_user_experimental_features.sql116Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 76/04_add_room_forgetter.sql117Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v77118Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/01_add_profiles_not_valid_check.sql.postgres119Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/02_add_user_filters_not_valid_check.sql.postgres120Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 77/03bg_populate_full_user_id_profiles.sql121Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 77/04bg_populate_full_user_id_user_filters.sql122Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 77/05thread_notifications_backfill.sql123Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_actions.sql.postgres124Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_actions_staging.sql.postgres125Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_summary.sql.postgres126Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 77/14bg_indices_event_stream_ordering.sql127Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v78128Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Running 78/03event_extremities_constraints.py:run_create129Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Running 78/04_add_full_user_id_index_user_filters.py:run_create130Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v79131Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 79/03_read_write_locks_triggers.sql.postgres132Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Running 79/04_mitigate_stream_ordering_update_race.py:run_create133Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 79/05_read_write_locks_triggers.sql.postgres134Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v80135Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 80/01_users_alter_locked.sql136Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/02_read_write_locks_unlogged.sql.postgres137Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 80/02_scheduled_tasks.sql138Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/03_read_write_locks_triggers.sql.postgres139Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/04_read_write_locks_deadlock.sql.postgres140Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v81141Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v82142Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 82/02_scheduled_tasks_index.sql143Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 82/04_add_indices_for_purging_rooms.sql144Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 82/05gaps.sql145Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v83146Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 83/01_drop_old_tables.sql147Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 83/05_cross_signing_key_update_grant.sql148Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 83/06_event_push_summary_room.sql149Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v84150Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 84/01_auth_links_stats.sql.postgres151Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 84/02_auth_links_index.sql152Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 84/03_auth_links_analyze.sql.postgres153Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 84/04_access_token_index.sql154Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v85155Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 85/01_add_suspended.sql156Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 85/02_add_instance_names.sql157Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 85/03_new_sequences.sql.postgres158Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 85/04_cleanup_device_federation_outbox.sql159Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 85/05_add_instance_names_converted_pos.sql160Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 85/06_add_room_reports.sql161Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v86162Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 86/01_authenticate_media.sql163Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 86/02_receipts_event_id_index.sql164Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v87165Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 87/01_sliding_sync_memberships.sql166Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 87/02_per_connection_state.sql167Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 87/03_current_state_index.sql168Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v88169Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 88/01_add_delayed_events.sql170Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 88/01_custom_profile_fields.sql171Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 88/02_fix_sliding_sync_membership_snapshots_forgotten_column.sql172Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 88/03_add_otk_ts_added_index.sql173Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 88/04_current_state_delta_index.sql174Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 88/05_drop_old_otks.sql.postgres175Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 88/05_sliding_sync_room_config_index.sql176Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 88/06_events_received_ts_index.sql177Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v89178Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 89/01_sliding_sync_membership_snapshot_index.sql179Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 89/01_state_groups_deletion.sql180Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v90181Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 90/01_add_column_participant_room_memberships_table.sql182Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 90/02_delete_unreferenced_state_groups.sql183Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 90/03_remove_old_deletion_bg_update.sql184Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v91185Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 91/01_media_hash.sql186Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v92187Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/01_remove_trigger.sql.postgres188Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 92/02_remove_populate_participant_bg_update.sql189Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 92/04_ss_membership_snapshot_idx.sql190Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 92/04_thread_subscriptions.sql191Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/04_thread_subscriptions_seq.sql.postgres192Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 92/05_fixup_max_depth_cap.sql193Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/05_thread_subscriptions_comments.sql.postgres194Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 92/06_device_federation_inbox_index.sql195Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/06_threads_last_sent_stream_ordering_comments.sql.postgres196Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 92/07_add_user_reports.sql197Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 92/07_event_txn_id_device_id_txn_id2.sql198Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 92/08_room_ban_redactions.sql199Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/08_thread_subscriptions_seq_fixup.sql.postgres200Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 92/09_thread_subscriptions_update.sql201Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/09_thread_subscriptions_update.sql.postgres202Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v93203Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 93/01_add_delayed_events.sql204Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 93/01_sticky_events.sql205Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 93/01_sticky_events_seq.sql.postgres206Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 93/02_sliding_sync_members.sql207Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 93/03_sss_pos_last_used.sql208Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Running 93/04_make_delayed_event_content_text.py:run_create209Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema deltas for v94210Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 94/01_redactions_recheck.sql211Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 94/01_state_groups_persisting_inserted_ts.sql.postgres212Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 94/02_redactions_recheck_bg_update.sql213Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 94/03_device_lists_room_timestamp.sql214Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 94/03_quarantined_media_tracking.sql215Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 94/03_quarantined_media_tracking_seq.sql.postgres216Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 94/03_state_dag_fwd_extrems.sql217Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 94/04_device_lists_changes_max_pruned.sql218Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 94/05_rerun_flag_existing_quarantined_media.sql219Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 94/06_sliding_sync_connections_last_used_ts_index.sql220Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 94/07_profile_updates.sql221Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying engine-specific schema 94/07_profile_updates_seq.sql.postgres222Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 94/07_sliding_sync_lazy_members_position_index.sql223Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 94/08_device_lists_changes_in_room_unconverted_idx.sql224Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 94/09_e2e_cross_signing_signatures_index.sql225Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Applying schema 94/10_e2e_cross_signing_signatures_add_key_id_to_index.sql226Oct 16 10:07:23 machine synapse[492]: synapse.storage.prepare_database: [main] Schema now up to date227Oct 16 10:07:23 machine synapse[492]: synapse.storage.databases: [main] [database config 'master']: Starting 'main' database228Oct 16 10:07:23 machine synapse[492]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules(id): 1229Oct 16 10:07:23 machine synapse[492]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules_enable(id): 1230Oct 16 10:07:23 machine synapse[492]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 month ago231Oct 16 10:07:23 machine synapse[492]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 month ago: it's 0232Oct 16 10:07:23 machine synapse[492]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 day ago233Oct 16 10:07:23 machine synapse[492]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 day ago: it's 0234Oct 16 10:07:23 machine synapse[492]: synapse.storage.util.id_generators: [main] Initialising stream generator for access_tokens(id): 1235Oct 16 10:07:23 machine synapse[492]: synapse.storage.util.id_generators: [main] Initialising stream generator for refresh_tokens(id): 1236Oct 16 10:07:23 machine synapse[492]: synapse.storage.util.id_generators: [main] Initialising stream generator for event_reports(id): 1237Oct 16 10:07:23 machine synapse[492]: synapse.storage.util.id_generators: [main] Initialising stream generator for room_reports(id): 1238Oct 16 10:07:23 machine synapse[492]: synapse.storage.util.id_generators: [main] Initialising stream generator for user_reports(id): 1239Oct 16 10:07:23 machine synapse[492]: synapse.storage.databases: [main] [database config 'master']: Starting 'state' database240Oct 16 10:07:23 machine synapse[492]: synapse.storage.databases: [main] [database config 'master']: prepared241Oct 16 10:07:23 machine synapse[492]: synapse.server: [main] Finished setting up.242Oct 16 10:07:23 machine synapse[492]: twisted: [main] Redirected stdout/stderr to logs243Oct 16 10:07:23 machine synapse[492]: synapse.app.homeserver: [main] Running244Oct 16 10:07:23 machine synapse[492]: synapse.app.homeserver: [main] Set file limit to: 524288245Oct 16 10:07:23 machine synapse[492]: synapse.push.pusher: [call_when_running] email enable notifs: False246Oct 16 10:07:23 machine synapse[492]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'profile'247Oct 16 10:07:23 machine synapse[492]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.device_list_update'248Oct 16 10:07:23 machine synapse[492]: synapse.util.caches.lrucache: [call_when_running] Expiring LRU caches after 1800 seconds249Oct 16 10:07:23 machine synapse[492]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.presence'250Oct 16 10:07:23 machine synapse[492]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.typing'251Oct 16 10:07:23 machine synapse[492]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'directory'252Oct 16 10:07:23 machine synapse[492]: synapse.handlers.deactivate_account: [user_parter_loop-0] Starting user parter253Oct 16 10:07:23 machine synapse[492]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.receipt'254Oct 16 10:07:23 machine synapse[492]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.signing_key_update'255Oct 16 10:07:23 machine synapse[492]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'org.matrix.signing_key_update'256Oct 16 10:07:23 machine synapse[492]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'client_keys'257Oct 16 10:07:23 machine synapse[492]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.direct_to_device'258Oct 16 10:07:23 machine synapse[492]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.health.HealthResource object at 0xffffee08b820> to path b'/health'259Oct 16 10:07:23 machine synapse[492]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.EncodingResourceWrapper object at 0xffffee3c60a0> to path b'/_matrix/client'260Oct 16 10:07:23 machine synapse[492]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffee3cbda0> to path b'/.well-known'261Oct 16 10:07:23 machine synapse[492]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.http.server.JsonResource object at 0xffffee3c6220> to path b'/_synapse/admin'262Oct 16 10:07:23 machine synapse[492]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.pick_idp.PickIdpResource object at 0xffffee3cc220> to path b'/_synapse/client/pick_idp'263Oct 16 10:07:23 machine synapse[492]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffe3e47320> to path b'/_synapse/client/pick_username'264Oct 16 10:07:23 machine synapse[492]: 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'265Oct 16 10:07:23 machine synapse[492]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.sso_register.SsoRegisterResource object at 0xffffee3cc9a0> to path b'/_synapse/client/sso_register'266Oct 16 10:07:23 machine synapse[492]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.unsubscribe.UnsubscribeResource object at 0xffffee3ccb20> to path b'/_synapse/client/unsubscribe'267Oct 16 10:07:23 machine synapse[492]: 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'268Oct 16 10:07:23 machine synapse[492]: 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'269Oct 16 10:07:23 machine synapse[492]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee3cd120> to path b'/_matrix/media/r0'270Oct 16 10:07:23 machine synapse[492]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee3cd120> to path b'/_matrix/media/v3'271Oct 16 10:07:23 machine synapse[492]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee3cd120> to path b'/_matrix/media/v1'272Oct 16 10:07:23 machine synapse[492]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.federation.transport.server.TransportLayerServer object at 0xffffee3cdd20> to path b'/_matrix/federation'273Oct 16 10:07:23 machine synapse[492]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.key.v2.KeyResource object at 0xffffee3d0ea0> to path b'/_matrix/key'274Oct 16 10:07:23 machine synapse[492]: twisted: [call_when_running] SynapseSite starting on 8008275Oct 16 10:07:23 machine synapse[492]: synapse.app._base: [call_when_running] Synapse now listening on TCP port 8008276Oct 16 10:07:23 machine synapse[492]: 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).277Oct 16 10:07:24 machine synapse[492]: synapse.storage.background_updates: [background_updates-0] Starting background schema updates for database master278Oct 16 10:07:24 machine synapse[492]: synapse.handlers.deactivate_account: [user_parter_loop-0] User parter finished: stopping279Oct 16 10:07:24 machine synapse[492]: synapse.push.pusherpool: [start_pushers-0] Started pushers280Oct 16 10:07:24 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[521]: Connection to ::1 8008 port [tcp/http-alt] succeeded!281Oct 16 10:07:24 machine synapse[492]: 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]282Oct 16 10:07:25 machine synapse[492]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'threads_backfill'283Oct 16 10:07:25 machine synapse[492]: synapse.storage.background_updates: [background_updates-0] Running background update 'threads_backfill'. Processed 0 items in 6ms. (total_rate=None/ms, current_rate=None/ms, total_updated=0, batch_size=100)284Oct 16 10:07:25 machine synapse[492]: synapse.handlers.auth: [POST-1] Logging in user @admin:clan.test on device YRATDDGKDY285Oct 16 10:07:25 machine synapse[492]: synapse.access.http.8008: [POST-1] ::1 - 8008 - {None} Processed request: 0.287sec/0.001sec ru=(0.250sec, 0.005sec) db=(0.005sec/0.021sec/12) 138B 200 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]286Oct 16 10:07:25 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[522]: Sending registration request...287Oct 16 10:07:25 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[522]: Success!288Oct 16 10:07:25 machine synapse[492]: synapse.access.http.8008: [GET-2] ::1 - 8008 - {None} Processed request: 0.000sec/0.000sec 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]289Oct 16 10:07:25 machine synapse[492]: synapse.handlers.auth: [POST-3] Logging in user @someuser:clan.test on device XNWGBEXISA290Oct 16 10:07:26 machine synapse[492]: synapse.access.http.8008: [POST-3] ::1 - 8008 - {None} Processed request: 0.270sec/0.001sec ru=(0.241sec, 0.002sec) db=(0.004sec/0.017sec/12) 145B 200 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.33.1" [0 dbevts]291Oct 16 10:07:26 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[529]: Sending registration request...292Oct 16 10:07:26 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[529]: Success!293Oct 16 10:07:26 machine synapse[492]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'device_lists_changes_in_room_by_room_index'294Oct 16 10:07:26 machine synapse[492]: synapse.storage.background_updates: [background_updates-0] Adding index device_lists_changes_in_room_by_room_idx to device_lists_changes_in_room295Oct 16 10:07:26 machine synapse[492]: synapse.storage.background_updates: [background_updates-0] Running background update 'device_lists_changes_in_room_by_room_index'. Processed 1 items in 5ms. (total_rate=0.2/ms, current_rate=0.2/ms, total_updated=1, batch_size=100)296Oct 16 10:07:26 machine systemd[1]: Started Synapse Matrix homeserver.297Oct 16 10:07:26 machine synapse[492]: 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]298Oct 16 10:07:26 machine systemd[1]: Stopping Synapse Matrix homeserver...299Oct 16 10:07:26 machine synapse[492]: twisted: [sentinel] Received SIGTERM, shutting down.300Oct 16 10:07:26 machine synapse[492]: synapse.storage.databases.main.lock: [LockStore._on_shutdown-0] Dropping held locks due to shutdown301Oct 16 10:07:26 machine synapse[492]: synapse.storage.databases.main.lock: [LockStore._on_shutdown-0] Dropped locks due to shutdown302Oct 16 10:07:26 machine synapse[492]: synapse.handlers.presence: [PresenceHandler._on_shutdown-0] Performing _on_shutdown. Persisting 0 unpersisted changes303Oct 16 10:07:26 machine synapse[492]: synapse.handlers.presence: [PresenceHandler._on_shutdown-0] Finished _on_shutdown304Oct 16 10:07:26 machine synapse[492]: synapse.app._base: [log_shutdown] Shutting down...305Oct 16 10:07:26 machine synapse[492]: twisted: [sentinel] (TCP Port 8008 Closed)306Oct 16 10:07:26 machine synapse[492]: twisted: [sentinel] Main loop terminated.307Oct 16 10:07:26 machine systemd[1]: matrix-synapse.service: Deactivated successfully.308Oct 16 10:07:26 machine systemd[1]: Stopped Synapse Matrix homeserver.309Oct 16 10:07:26 machine systemd[1]: matrix-synapse.service: Consumed 9.064s CPU time over 11.274s wall clock time.310Oct 16 10:07:26 machine systemd[1]: Starting Synapse Matrix homeserver...311Oct 16 10:07:33 machine synapse_homeserver[561]: This server is configured to use 'matrix.org' as its trusted key server via the312Oct 16 10:07:33 machine synapse_homeserver[561]: 'trusted_key_servers' config option. 'matrix.org' is a good choice for a key313Oct 16 10:07:33 machine synapse_homeserver[561]: server since it is long-lived, stable and trusted. However, some admins may314Oct 16 10:07:33 machine synapse_homeserver[561]: wish to use another server for this purpose.315Oct 16 10:07:33 machine synapse_homeserver[561]: To suppress this warning and continue using 'matrix.org', admins should set316Oct 16 10:07:33 machine synapse_homeserver[561]: 'suppress_key_server_warning' to 'true' in homeserver.yaml.317Oct 16 10:07:33 machine synapse_homeserver[561]: --------------------------------------------------------------------------------318Oct 16 10:07:33 machine synapse[561]: synapse.config.logger: [main] ***** STARTING SERVER *****319Oct 16 10:07:33 machine synapse[561]: synapse.config.logger: [main] Server /nix/store/928l52anrm0pvcn6k9haijzhbygjrjs5-matrix-synapse-1.162.0/bin/synapse_homeserver version 1.162.0320Oct 16 10:07:33 machine synapse[561]: synapse.config.logger: [main] Copyright (c) 2023 New Vector, Inc321Oct 16 10:07:33 machine synapse[561]: synapse.config.logger: [main] Licensed under the AGPL 3.0 license. Website: https://github.com/element-hq/synapse322Oct 16 10:07:33 machine synapse[561]: synapse.config.logger: [main] Server hostname: clan.test323Oct 16 10:07:33 machine synapse[561]: synapse.config.logger: [main] Public Base URL: https://clan.test/324Oct 16 10:07:33 machine synapse[561]: synapse.config.logger: [main] Instance name: master325Oct 16 10:07:33 machine synapse[561]: synapse.config.logger: [main] Twisted reactor: EPollReactor326Oct 16 10:07:33 machine synapse[561]: synapse.app.homeserver: [main] Setting up server327Oct 16 10:07:33 machine synapse[561]: synapse.server: [main] Setting up.328Oct 16 10:07:33 machine synapse[561]: synapse.storage.databases: [main] [database config 'master']: Checking database server329Oct 16 10:07:33 machine synapse[561]: synapse.storage.databases: [main] [database config 'master']: Preparing for databases ['main', 'state']330Oct 16 10:07:33 machine synapse[561]: synapse.storage.prepare_database: [main] ['main', 'state']: Checking existing schema version331Oct 16 10:07:33 machine synapse[561]: synapse.storage.prepare_database: [main] ['main', 'state']: Existing schema is 94 (+16 deltas)332Oct 16 10:07:33 machine synapse[561]: synapse.storage.databases.main: [main] Checking database for consistency with configuration...333Oct 16 10:07:33 machine synapse[561]: synapse.storage.prepare_database: [main] Applying schema deltas for v94334Oct 16 10:07:33 machine synapse[561]: synapse.storage.prepare_database: [main] Schema now up to date335Oct 16 10:07:33 machine synapse[561]: synapse.storage.databases: [main] [database config 'master']: Starting 'main' database336Oct 16 10:07:33 machine synapse[561]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules(id): 1337Oct 16 10:07:33 machine synapse[561]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules_enable(id): 1338Oct 16 10:07:33 machine synapse[561]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 month ago339Oct 16 10:07:33 machine synapse[561]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 month ago: it's 0340Oct 16 10:07:33 machine synapse[561]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 day ago341Oct 16 10:07:33 machine synapse[561]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 day ago: it's 0342Oct 16 10:07:33 machine synapse[561]: synapse.storage.util.id_generators: [main] Initialising stream generator for access_tokens(id): 3343Oct 16 10:07:33 machine synapse[561]: synapse.storage.util.id_generators: [main] Initialising stream generator for refresh_tokens(id): 1344Oct 16 10:07:33 machine synapse[561]: synapse.storage.util.id_generators: [main] Initialising stream generator for event_reports(id): 1345Oct 16 10:07:33 machine synapse[561]: synapse.storage.util.id_generators: [main] Initialising stream generator for room_reports(id): 1346Oct 16 10:07:33 machine synapse[561]: synapse.storage.util.id_generators: [main] Initialising stream generator for user_reports(id): 1347Oct 16 10:07:33 machine synapse[561]: synapse.storage.databases: [main] [database config 'master']: Starting 'state' database348Oct 16 10:07:33 machine synapse[561]: synapse.storage.databases: [main] [database config 'master']: prepared349Oct 16 10:07:33 machine synapse[561]: synapse.server: [main] Finished setting up.350Oct 16 10:07:33 machine synapse[561]: twisted: [main] Redirected stdout/stderr to logs351Oct 16 10:07:33 machine synapse[561]: synapse.app.homeserver: [main] Running352Oct 16 10:07:33 machine synapse[561]: synapse.app.homeserver: [main] Set file limit to: 524288353Oct 16 10:07:33 machine synapse[561]: synapse.push.pusher: [call_when_running] email enable notifs: False354Oct 16 10:07:33 machine synapse[561]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'profile'355Oct 16 10:07:33 machine synapse[561]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.device_list_update'356Oct 16 10:07:33 machine synapse[561]: synapse.util.caches.lrucache: [call_when_running] Expiring LRU caches after 1800 seconds357Oct 16 10:07:33 machine synapse[561]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.presence'358Oct 16 10:07:33 machine synapse[561]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.typing'359Oct 16 10:07:33 machine synapse[561]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'directory'360Oct 16 10:07:33 machine synapse[561]: synapse.handlers.deactivate_account: [user_parter_loop-0] Starting user parter361Oct 16 10:07:33 machine synapse[561]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.receipt'362Oct 16 10:07:33 machine synapse[561]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.signing_key_update'363Oct 16 10:07:33 machine synapse[561]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'org.matrix.signing_key_update'364Oct 16 10:07:33 machine synapse[561]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'client_keys'365Oct 16 10:07:33 machine synapse[561]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.direct_to_device'366Oct 16 10:07:33 machine synapse[561]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.health.HealthResource object at 0xffffee09b9a0> to path b'/health'367Oct 16 10:07:33 machine synapse[561]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.EncodingResourceWrapper object at 0xffffee3d6220> to path b'/_matrix/client'368Oct 16 10:07:33 machine synapse[561]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffee3dbf20> to path b'/.well-known'369Oct 16 10:07:33 machine synapse[561]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.http.server.JsonResource object at 0xffffee3d63a0> to path b'/_synapse/admin'370Oct 16 10:07:33 machine synapse[561]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.pick_idp.PickIdpResource object at 0xffffee3dc3a0> to path b'/_synapse/client/pick_idp'371Oct 16 10:07:33 machine synapse[561]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffe3e471e0> to path b'/_synapse/client/pick_username'372Oct 16 10:07:33 machine synapse[561]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.new_user_consent.NewUserConsentResource object at 0xffffee3dc9a0> to path b'/_synapse/client/new_user_consent'373Oct 16 10:07:33 machine synapse[561]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.sso_register.SsoRegisterResource object at 0xffffee3dcb20> to path b'/_synapse/client/sso_register'374Oct 16 10:07:33 machine synapse[561]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.unsubscribe.UnsubscribeResource object at 0xffffee3dcca0> to path b'/_synapse/client/unsubscribe'375Oct 16 10:07:33 machine synapse[561]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.media_upload_limit_exceeded.MediaUploadLimitExceededResource object at 0xffffee3dce20> to path b'/_synapse/client/media_upload_limit_exceeded'376Oct 16 10:07:33 machine synapse[561]: 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'377Oct 16 10:07:33 machine synapse[561]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee3dd2a0> to path b'/_matrix/media/r0'378Oct 16 10:07:33 machine synapse[561]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee3dd2a0> to path b'/_matrix/media/v3'379Oct 16 10:07:33 machine synapse[561]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffee3dd2a0> to path b'/_matrix/media/v1'380Oct 16 10:07:33 machine synapse[561]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.federation.transport.server.TransportLayerServer object at 0xffffee3ddea0> to path b'/_matrix/federation'381Oct 16 10:07:33 machine synapse[561]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.key.v2.KeyResource object at 0xffffee3e1020> to path b'/_matrix/key'382Oct 16 10:07:33 machine synapse[561]: twisted: [call_when_running] SynapseSite starting on 8008383Oct 16 10:07:33 machine synapse[561]: synapse.app._base: [call_when_running] Synapse now listening on TCP port 8008384Oct 16 10:07:33 machine synapse[561]: 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).385Oct 16 10:07:33 machine synapse[561]: synapse.storage.background_updates: [background_updates-0] Starting background schema updates for database master386Oct 16 10:07:33 machine synapse[561]: synapse.handlers.deactivate_account: [user_parter_loop-0] User parter finished: stopping387Oct 16 10:07:33 machine synapse[561]: synapse.push.pusherpool: [start_pushers-0] Started pushers388Oct 16 10:07:33 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[586]: Connection to ::1 8008 port [tcp/http-alt] succeeded!389Oct 16 10:07:34 machine synapse[561]: 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]390Oct 16 10:07:34 machine synapse[561]: synapse.http.server: [POST-1] <XForwardedForRequest at 0xffffe3e42d20 method='POST' uri='/_synapse/admin/v1/register' clientproto='HTTP/1.1' site='8008'> SynapseError: 400 - User ID already taken.391Oct 16 10:07:34 machine synapse[561]: synapse.access.http.8008: [POST-1] ::1 - 8008 - {None} Processed request: 0.236sec/0.000sec ru=(0.229sec, 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]392Oct 16 10:07:34 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[587]: Sending registration request...393Oct 16 10:07:34 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[587]: User already exists. Skipping.394Oct 16 10:07:34 machine synapse[561]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'populate_stats_process_rooms'395Oct 16 10:07:34 machine synapse[561]: synapse.storage.background_updates: [background_updates-0] Running background update 'populate_stats_process_rooms'. Processed 1 items in 5ms. (total_rate=0.2/ms, current_rate=0.2/ms, total_updated=1, batch_size=100)396Oct 16 10:07:34 machine synapse[561]: 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]397Oct 16 10:07:35 machine synapse[561]: synapse.http.server: [POST-3] <XForwardedForRequest at 0xffffe3e425a0 method='POST' uri='/_synapse/admin/v1/register' clientproto='HTTP/1.1' site='8008'> SynapseError: 400 - User ID already taken.398Oct 16 10:07:35 machine synapse[561]: synapse.access.http.8008: [POST-3] ::1 - 8008 - {None} Processed request: 0.276sec/0.000sec ru=(0.271sec, 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]399Oct 16 10:07:35 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[593]: Sending registration request...400Oct 16 10:07:35 machine jsq23kh2mhjk7vd6dbx3bss5lc5smnls-matrix-synapse-create-users[593]: User already exists. Skipping.401Oct 16 10:07:35 machine systemd[1]: Started Synapse Matrix homeserver.402+ systemctl --no-pager show matrix-synapse403+ nc -z -v ::1 8008404Connection to ::1 8008 port [tcp/http-alt] succeeded!405+ curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test'406