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