container-test-run-matrix-synapse
checks.aarch64-linux.matrix-synapse
· build #771
· raw
1build01: building hp1qv6gm6bjzr7rdj2w0xc8n2a4vw35m-container-test-run-matrix-synapse.drv2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)56Test will time out and terminate in 3600.0 seconds7run the VM test script8additionally exposed symbols:9 machine,10 vlan1,11 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh12start all VMs13machine: systemd-nspawn running (pid 51)14machine: Waiting for journal at /build/vm-state-machine/var/log/journal...15(finished: start all VMs, in 0.00 seconds)16machine: waiting for unit matrix-synapse17nixos-nspawn(machine): TAP vde-tap1 not found; container will be isolated from VDE18nixos-nspawn(machine): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.19Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.20░ Spawning container machine on /build/vm-state-machine.21machine # No journal files were found.22machine # No journal boot entry found for the specified boot (+0).23machine # [1784354.558509] machine systemd-journald[98]: Journal started24machine # [1784354.558566] machine systemd-journald[98]: Runtime Journal (/run/log/journal/8b1e088ced554413bb3ddf958de0aa89) is 8M, max 2.5G, 2.4G free.25machine # [1784354.565484] machine systemd[1]: Finished Apply Kernel Variables.26machine # [1784354.578291] machine systemd[1]: Finished Create Static Device Nodes in /dev gracefully.27machine # [1784354.592898] machine systemd[1]: Starting Flush Journal to Persistent Storage...28machine # [1784354.593767] machine systemd[1]: Starting Network Name Resolution...29machine # [1784354.594513] machine systemd[1]: Starting Create Static Device Nodes in /dev...30machine # [1784354.643411] machine systemd-journald[98]: Time spent on flushing to /var/log/journal/8b1e088ced554413bb3ddf958de0aa89 is 1.931ms for 7 entries.31machine # [1784354.643411] machine systemd-journald[98]: System Journal (/var/log/journal/8b1e088ced554413bb3ddf958de0aa89) is 8M, max 4G, 3.9G free.32machine # [1784354.651815] machine systemd[1]: Finished Create Static Device Nodes in /dev.33machine # [1784354.652478] machine systemd[1]: Reached target Preparation for Local File Systems.34machine # [1784354.652600] machine systemd[1]: Reached target Local File Systems.35machine # [1784354.653412] machine systemd[1]: Listening on Boot Loader Control Service Socket.36machine # [1784354.653459] machine systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container37machine # [1784354.654361] machine systemd[1]: Starting Save Transient machine-id to Disk...38machine # [1784354.654393] machine systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys39machine # [1784354.674398] machine systemd[1]: Finished Flush Journal to Persistent Storage.40machine # [1784354.675477] machine systemd[1]: Starting Create System Files and Directories...41machine # [1784354.693651] machine systemd-tmpfiles[177]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted42machine # [1784354.693866] machine systemd-tmpfiles[177]: fchmod() of /var/log/journal failed: Operation not permitted43machine # [1784354.694011] machine systemd-tmpfiles[177]: fchmod() of /var/log/journal/8b1e088ced554413bb3ddf958de0aa89 failed: Operation not permitted44machine # [1784354.694226] machine systemd-tmpfiles[177]: fchmod() of /run/log/journal failed: Operation not permitted45machine # [1784354.695612] machine systemd[1]: Finished Create System Files and Directories.46machine # [1784354.696880] machine systemd[1]: Starting Rebuild Journal Catalog...47machine # [1784354.697914] machine systemd[1]: Starting Record System Boot/Shutdown in UTMP...48machine # [1784354.714480] machine systemd[1]: Finished Record System Boot/Shutdown in UTMP.49machine # [1784354.721508] machine systemd[1]: Finished Rebuild Journal Catalog.50machine # [1784354.722710] machine systemd[1]: Starting Update is Completed...51machine # [1784354.734737] machine systemd[1]: Finished Firewall.52machine # [1784354.734997] machine systemd[1]: Finished Update is Completed.53machine # [1784354.735644] machine systemd[1]: Reached target Preparation for Network.54machine # [1784354.735978] machine systemd[1]: Listening on Network Management Resolve Hook Socket.55machine # [1784354.737355] machine systemd[1]: Starting Network Management...56machine # [1784355.471682] machine systemd-networkd[215]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted57machine # [1784355.471771] machine systemd-networkd[215]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted58machine # [1784355.481787] machine systemd-networkd[215]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.59machine # [1784355.481953] machine systemd-networkd[215]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.60machine # [1784355.482107] machine systemd-networkd[215]: lo: Link UP61machine # [1784355.482110] machine systemd-networkd[215]: lo: Gained carrier62machine # [1784355.482283] machine systemd-networkd[215]: eth1: Configuring with /etc/systemd/network/40-eth1.network.63machine # [1784355.482701] machine systemd[1]: Started Network Management.64machine # [1784355.482765] machine systemd-networkd[215]: eth1: Link UP65machine # [1784355.483037] machine systemd-networkd[215]: eth1: Gained carrier66machine # [1784355.484862] machine systemd[1]: Starting Enable Persistent Storage in systemd-networkd...67machine # [1784355.526185] machine systemd[1]: Finished Enable Persistent Storage in systemd-networkd.68machine # [1784355.894352] machine systemd-resolved[132]: Positive Trust Anchors:69machine # [1784355.894364] machine systemd-resolved[132]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d70machine # [1784355.894366] machine systemd-resolved[132]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b1671machine # [1784355.894402] machine systemd-resolved[132]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test72machine # [1784355.916094] machine systemd-resolved[132]: Using system hostname 'machine'.73machine # [1784355.917487] machine systemd[1]: Started Network Name Resolution.74machine # [1784355.917618] machine systemd[1]: Reached target Network.75machine # [1784355.917726] machine systemd[1]: Reached target Network is Online.76machine # [1784355.917813] machine systemd[1]: Reached target System Initialization.77machine # [1784355.917919] machine systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container78machine # [1784355.917980] machine systemd[1]: Started Daily Cleanup of Temporary Directories.79machine # [1784355.918024] machine systemd[1]: Reached target Timer Units.80machine # [1784355.918250] machine systemd[1]: Listening on D-Bus System Message Bus Socket.81machine # [1784355.918466] machine systemd[1]: Listening on Nix Daemon Socket.82machine # [1784355.918686] machine systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.83machine # [1784355.918738] machine systemd[1]: Reached target Socket Units.84machine # [1784355.918817] machine systemd[1]: Reached target Basic System.85machine # [1784355.921132] machine systemd[1]: Starting Import lastlog data into lastlog2 database...86machine # [1784355.922153] machine systemd[1]: Starting Nginx Web Server...87machine # [1784355.923044] machine systemd[1]: Starting Name Service Cache Daemon (nsncd)...88machine # [1784355.924044] machine systemd[1]: Starting PostgreSQL Server...89machine # [1784355.925350] machine systemd[1]: Starting D-Bus System Message Bus...90machine # [1784355.979680] machine systemd[1]: Finished Import lastlog data into lastlog2 database.91machine # [1784356.168174] machine nsncd[222]: Sep 24 23:06:16.153 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"92machine # [1784356.168159] machine systemd[1]: Started Name Service Cache Daemon (nsncd).93machine # [1784356.168264] machine systemd[1]: Reached target Host and Network Name Lookups.94machine # [1784356.168379] machine systemd[1]: Reached target User and Group Name Lookups.95machine # [1784356.170454] machine systemd[1]: Starting User Login Management...96machine # [1784356.171910] machine systemd[1]: Starting Permit User Sessions...97machine # [1784356.240334] machine systemd[1]: Finished Permit User Sessions.98machine # [1784356.242058] machine systemd[1]: Started Console Getty.99machine # [1784356.242135] machine systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0100machine # [1784356.242178] machine systemd[1]: Reached target Login Prompts.101machine # [1784356.337649] machine dbus-broker-launch[224]: Looking up NSS user entry for 'systemd-timesync'...102machine # [1784356.339629] machine dbus-broker-launch[224]: NSS returned no entry for 'systemd-timesync'103machine # [1784356.339629] machine dbus-broker-launch[224]: Invalid user-name in /nix/store/fjr65g9cjnyl9i9wdwx1c5ivc4dy961s-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"104machine # [1784356.340152] machine systemd[1]: Started D-Bus System Message Bus.105machine # [1784356.349191] machine dbus-broker-launch[224]: Ready106machine # [1784357.003004] machine postgresql-pre-start[257]: The files belonging to this database system will be owned by user "postgres".107machine # [1784357.003004] machine postgresql-pre-start[257]: This user must also own the server process.108machine # [1784357.003316] machine postgresql-pre-start[257]: The database cluster will be initialized with locale "en_US.UTF-8".109machine # [1784357.003316] machine postgresql-pre-start[257]: The default database encoding has accordingly been set to "UTF8".110machine # [1784357.003316] machine postgresql-pre-start[257]: The default text search configuration will be set to "english".111machine # [1784357.003316] machine postgresql-pre-start[257]: Data page checksums are enabled.112machine # [1784357.003316] machine postgresql-pre-start[257]: fixing permissions on existing directory /var/lib/postgresql/18 ... ok113machine # [1784357.003831] machine postgresql-pre-start[257]: creating subdirectories ... ok114machine # [1784357.003930] machine postgresql-pre-start[257]: selecting dynamic shared memory implementation ... posix115machine # [1784357.006626] machine nginx-pre-start[258]: nginx: the configuration file /nix/store/qiw0rmpcsj050hsmx1hq5vhv6v8pghdg-nginx.conf syntax is ok116machine # [1784357.006848] machine nginx-pre-start[258]: nginx: configuration file /nix/store/qiw0rmpcsj050hsmx1hq5vhv6v8pghdg-nginx.conf test is successful117machine # [1784357.016335] machine systemd[1]: Started Nginx Web Server.118machine # [1784357.037487] machine postgresql-pre-start[257]: selecting default "max_connections" ... 100119machine # [1784357.068832] machine postgresql-pre-start[257]: selecting default "shared_buffers" ... 128MB120machine # [1784357.124012] machine systemd-logind[245]: New seat seat0.121machine # [1784357.124206] machine systemd[1]: Started User Login Management.122machine # [1784357.126489] machine systemd[1]: Starting linger-users.service...123machine # [1784357.170278] machine systemd[1]: linger-users.service: Deactivated successfully.124machine # [1784357.170491] machine systemd[1]: Finished linger-users.service.125machine # [1784357.346735] machine postgresql-pre-start[257]: selecting default time zone ... UTC126machine # [1784357.347940] machine postgresql-pre-start[257]: creating configuration files ... ok127machine # [1784357.482443] machine postgresql-pre-start[257]: running bootstrap script ... ok128machine # [1784357.504162] machine systemd-networkd[215]: eth1: Gained IPv6LL129machine # [1784357.870784] machine postgresql-pre-start[257]: performing post-bootstrap initialization ... ok130machine # [1784357.997754] machine postgresql-pre-start[257]: syncing data to disk ... ok131machine # [1784357.997754] machine postgresql-pre-start[257]: initdb: warning: enabling "trust" authentication for local connections132machine # [1784357.997754] machine postgresql-pre-start[257]: initdb: hint: You can change this by editing pg_hba.conf or using the option -A, or --auth-local and --auth-host, the next time you run initdb.133machine # [1784357.997754] machine postgresql-pre-start[257]: Success. You can now start the database server using:134machine # [1784357.997754] machine postgresql-pre-start[257]: pg_ctl -D /var/lib/postgresql/18 -l logfile start135machine # [1784359.112504] machine postgres[280]: [280] LOG: starting PostgreSQL 18.6 on aarch64-unknown-linux-gnu, compiled by clang version 21.1.8, 64-bit136machine # [1784359.113920] machine postgres[280]: [280] LOG: listening on IPv6 address "::1", port 5432137machine # [1784359.113920] machine postgres[280]: [280] LOG: listening on IPv4 address "127.0.0.1", port 5432138machine # [1784359.117597] machine postgres[280]: [280] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"139machine # [1784359.122367] machine postgres[289]: [289] LOG: database system was shut down at 2026-09-24 23:06:17 GMT140machine # [1784359.128046] machine postgres[280]: [280] LOG: database system is ready to accept connections141machine # [1784359.128992] machine systemd[1]: Started PostgreSQL Server.142machine # [1784359.131092] machine systemd[1]: Starting Create the matrix-synapse database with C collation...143machine # [1784359.132757] machine systemd[1]: Starting PostgreSQL Setup Scripts...144machine # [1784359.205633] machine postgres[304]: [304] ERROR: role "matrix-synapse" does not exist145machine # [1784359.205633] machine postgres[304]: [304] STATEMENT: CREATE DATABASE "matrix-synapse" OWNER "matrix-synapse" ENCODING 'UTF8' TEMPLATE template0 LC_COLLATE 'C' LC_CTYPE 'C';146machine # [1784359.206201] machine matrix-synapse-postgres-start[301]: createdb: error: database creation failed: ERROR: role "matrix-synapse" does not exist147machine # [1784359.207020] machine systemd[1]: matrix-synapse-postgres.service: Main process exited, code=exited, status=1/FAILURE148machine # [1784359.207133] machine systemd[1]: matrix-synapse-postgres.service: Failed with result 'exit-code'.149machine # [1784359.207377] machine systemd[1]: Failed to start Create the matrix-synapse database with C collation.150machine # [1784359.218613] machine postgresql-setup-start[307]: CREATE ROLE151machine # [1784359.220314] machine systemd[1]: Finished PostgreSQL Setup Scripts.152machine # [1784359.220701] machine systemd[1]: Reached target PostgreSQL.153machine # [1784359.222040] machine systemd[1]: Starting Synapse Matrix homeserver...154machine # [1784362.249478] machine matrix-synapse-pre-start[316]: Generating signing key file /var/lib/matrix-synapse/homeserver.signing.key155machine: still waiting for container 'machine' to reach ready state...156machine # [1784365.323137] machine synapse_homeserver[327]: This server is configured to use 'matrix.org' as its trusted key server via the157machine # [1784365.323137] machine synapse_homeserver[327]: 'trusted_key_servers' config option. 'matrix.org' is a good choice for a key158machine # [1784365.323137] machine synapse_homeserver[327]: server since it is long-lived, stable and trusted. However, some admins may159machine # [1784365.323137] machine synapse_homeserver[327]: wish to use another server for this purpose.160machine # [1784365.323137] machine synapse_homeserver[327]: To suppress this warning and continue using 'matrix.org', admins should set161machine # [1784365.323137] machine synapse_homeserver[327]: 'suppress_key_server_warning' to 'true' in homeserver.yaml.162machine # [1784365.323137] machine synapse_homeserver[327]: --------------------------------------------------------------------------------163machine # [1784365.374822] machine synapse[327]: synapse.config.logger: [main] ***** STARTING SERVER *****164machine # [1784365.374932] machine synapse[327]: synapse.config.logger: [main] Server /nix/store/lv57dn8m32v75w568vmq0fr0wxx1zjzd-matrix-synapse-1.161.0/bin/synapse_homeserver version 1.161.0165machine # [1784365.374998] machine synapse[327]: synapse.config.logger: [main] Copyright (c) 2023 New Vector, Inc166machine # [1784365.375064] machine synapse[327]: synapse.config.logger: [main] Licensed under the AGPL 3.0 license. Website: https://github.com/element-hq/synapse167machine # [1784365.375254] machine synapse[327]: synapse.config.logger: [main] Server hostname: clan.test168machine # [1784365.375332] machine synapse[327]: synapse.config.logger: [main] Public Base URL: https://clan.test/169machine # [1784365.375398] machine synapse[327]: synapse.config.logger: [main] Instance name: master170machine # [1784365.375459] machine synapse[327]: synapse.config.logger: [main] Twisted reactor: EPollReactor171machine # [1784365.375517] machine synapse[327]: synapse.app.homeserver: [main] Setting up server172machine # [1784365.375587] machine synapse[327]: synapse.server: [main] Setting up.173machine # [1784365.378895] machine postgres[334]: [334] FATAL: database "matrix-synapse" does not exist174machine # [1784365.381533] machine synapse[327]: synapse.app._base: [main] Exception during startup175machine # Traceback (most recent call last):176machine # File "/nix/store/lv57dn8m32v75w568vmq0fr0wxx1zjzd-matrix-synapse-1.161.0/lib/python3.14/site-packages/synapse/app/homeserver.py", line 486, in main177machine # setup(hs)178machine # ~~~~~^^^^179machine # File "/nix/store/lv57dn8m32v75w568vmq0fr0wxx1zjzd-matrix-synapse-1.161.0/lib/python3.14/site-packages/synapse/app/homeserver.py", line 422, in setup180machine # hs.setup()181machine # ~~~~~~~~^^182machine # File "/nix/store/lv57dn8m32v75w568vmq0fr0wxx1zjzd-matrix-synapse-1.161.0/lib/python3.14/site-packages/synapse/server.py", line 638, in setup183machine # self.datastores = Databases(self.DATASTORE_CLASS, self)184machine # ~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^185machine # File "/nix/store/lv57dn8m32v75w568vmq0fr0wxx1zjzd-matrix-synapse-1.161.0/lib/python3.14/site-packages/synapse/storage/databases/__init__.py", line 86, in __init__186machine # with make_conn(187machine # ~~~~~~~~~^188machine # db_config=database_config,189machine # ^^^^^^^^^^^^^^^^^^^^^^^^^^190machine # ...<2 lines>...191machine # server_name=server_name,192machine # ^^^^^^^^^^^^^^^^^^^^^^^^193machine # ) as db_conn:194machine # ^195machine # File "/nix/store/lv57dn8m32v75w568vmq0fr0wxx1zjzd-matrix-synapse-1.161.0/lib/python3.14/site-packages/synapse/storage/database.py", line 196, in make_conn196machine # native_db_conn = engine.module.connect(**db_params)197machine # File "/nix/store/cba7nnhqyc9f9ji8ynfp83fc5wqvn6xr-python3-3.14.7-env/lib/python3.14/site-packages/psycopg2/__init__.py", line 122, in connect198machine # conn = _connect(dsn, connection_factory=connection_factory, **kwasync)199machine # psycopg2.OperationalError: connection to server on socket "/run/postgresql/.s.PGSQL.5432" failed: FATAL: database "matrix-synapse" does not exist200machine # [1784365.382459] machine synapse_homeserver[327]: **********************************************************************************201machine # [1784365.382459] machine synapse_homeserver[327]: Error during initialisation:202machine # [1784365.382459] machine synapse_homeserver[327]: Traceback (most recent call last):203machine # [1784365.382459] machine synapse_homeserver[327]: File "/nix/store/lv57dn8m32v75w568vmq0fr0wxx1zjzd-matrix-synapse-1.161.0/lib/python3.14/site-packages/synapse/app/homeserver.py", line 486, in main204machine # [1784365.382459] machine synapse_homeserver[327]: setup(hs)205machine # [1784365.382459] machine synapse_homeserver[327]: ~~~~~^^^^206machine # [1784365.382459] machine synapse_homeserver[327]: File "/nix/store/lv57dn8m32v75w568vmq0fr0wxx1zjzd-matrix-synapse-1.161.0/lib/python3.14/site-packages/synapse/app/homeserver.py", line 422, in setup207machine # [1784365.382459] machine synapse_homeserver[327]: hs.setup()208machine # [1784365.382459] machine synapse_homeserver[327]: ~~~~~~~~^^209machine # [1784365.382459] machine synapse_homeserver[327]: File "/nix/store/lv57dn8m32v75w568vmq0fr0wxx1zjzd-matrix-synapse-1.161.0/lib/python3.14/site-packages/synapse/server.py", line 638, in setup210machine # [1784365.382459] machine synapse_homeserver[327]: self.datastores = Databases(self.DATASTORE_CLASS, self)211machine # [1784365.382459] machine synapse_homeserver[327]: ~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^212machine # [1784365.382459] machine synapse_homeserver[327]: File "/nix/store/lv57dn8m32v75w568vmq0fr0wxx1zjzd-matrix-synapse-1.161.0/lib/python3.14/site-packages/synapse/storage/databases/__init__.py", line 86, in __init__213machine # [1784365.382459] machine synapse_homeserver[327]: with make_conn(214machine # [1784365.382459] machine synapse_homeserver[327]: ~~~~~~~~~^215machine # [1784365.382459] machine synapse_homeserver[327]: db_config=database_config,216machine # [1784365.382459] machine synapse_homeserver[327]: ^^^^^^^^^^^^^^^^^^^^^^^^^^217machine # [1784365.382459] machine synapse_homeserver[327]: ...<2 lines>...218machine # [1784365.382459] machine synapse_homeserver[327]: server_name=server_name,219machine # [1784365.382459] machine synapse_homeserver[327]: ^^^^^^^^^^^^^^^^^^^^^^^^220machine # [1784365.382459] machine synapse_homeserver[327]: ) as db_conn:221machine # [1784365.383288] machine synapse_homeserver[327]: ^222machine # [1784365.383288] machine synapse_homeserver[327]: File "/nix/store/lv57dn8m32v75w568vmq0fr0wxx1zjzd-matrix-synapse-1.161.0/lib/python3.14/site-packages/synapse/storage/database.py", line 196, in make_conn223machine # [1784365.383288] machine synapse_homeserver[327]: native_db_conn = engine.module.connect(**db_params)224machine # [1784365.383288] machine synapse_homeserver[327]: File "/nix/store/cba7nnhqyc9f9ji8ynfp83fc5wqvn6xr-python3-3.14.7-env/lib/python3.14/site-packages/psycopg2/__init__.py", line 122, in connect225machine # [1784365.383288] machine synapse_homeserver[327]: conn = _connect(dsn, connection_factory=connection_factory, **kwasync)226machine # [1784365.383288] machine synapse_homeserver[327]: psycopg2.OperationalError: connection to server on socket "/run/postgresql/.s.PGSQL.5432" failed: FATAL: database "matrix-synapse" does not exist227machine # [1784365.383288] machine synapse_homeserver[327]:228machine # [1784365.383288] machine synapse_homeserver[327]:229machine # [1784365.383288] machine synapse_homeserver[327]: There may be more information in the logs.230machine # [1784365.383288] machine synapse_homeserver[327]: **********************************************************************************231machine # [1784365.742034] machine systemd[1]: matrix-synapse.service: Main process exited, code=exited, status=1/FAILURE232machine # [1784365.742256] machine systemd[1]: matrix-synapse.service: Failed with result 'exit-code'.233machine # [1784365.742705] machine systemd[1]: Failed to start Synapse Matrix homeserver.234machine # [1784365.742977] machine systemd[1]: matrix-synapse.service: Consumed 6.072s CPU time over 6.520s wall clock time.235machine # [1784365.745612] machine systemd[1]: Reached target Multi-User System.236machine # [1784366.055703] machine systemd[1]: matrix-synapse.service: Scheduled restart job, restart counter is at 1.237machine # [1784366.057992] machine systemd[1]: Starting Create the matrix-synapse database with C collation...238machine # [1784366.344893] machine systemd[1]: matrix-synapse-postgres.service: Deactivated successfully.239machine # [1784366.345220] machine systemd[1]: Finished Create the matrix-synapse database with C collation.240machine # [1784366.348121] machine systemd[1]: Starting Synapse Matrix homeserver...241machine # [1784367.005964] machine systemd[1]: etc-machine\x2did.mount: Deactivated successfully.242machine # [1784367.008542] machine systemd[1]: Finished Save Transient machine-id to Disk.243machine # [1784372.055569] machine synapse_homeserver[363]: This server is configured to use 'matrix.org' as its trusted key server via the244machine # [1784372.055569] machine synapse_homeserver[363]: 'trusted_key_servers' config option. 'matrix.org' is a good choice for a key245machine # [1784372.055569] machine synapse_homeserver[363]: server since it is long-lived, stable and trusted. However, some admins may246machine # [1784372.055569] machine synapse_homeserver[363]: wish to use another server for this purpose.247machine # [1784372.055569] machine synapse_homeserver[363]: To suppress this warning and continue using 'matrix.org', admins should set248machine # [1784372.055569] machine synapse_homeserver[363]: 'suppress_key_server_warning' to 'true' in homeserver.yaml.249machine # [1784372.055569] machine synapse_homeserver[363]: --------------------------------------------------------------------------------250machine # [1784372.101565] machine synapse[363]: synapse.config.logger: [main] ***** STARTING SERVER *****251machine # [1784372.101686] machine synapse[363]: synapse.config.logger: [main] Server /nix/store/lv57dn8m32v75w568vmq0fr0wxx1zjzd-matrix-synapse-1.161.0/bin/synapse_homeserver version 1.161.0252machine # [1784372.101751] machine synapse[363]: synapse.config.logger: [main] Copyright (c) 2023 New Vector, Inc253machine # [1784372.101810] machine synapse[363]: synapse.config.logger: [main] Licensed under the AGPL 3.0 license. Website: https://github.com/element-hq/synapse254machine # [1784372.101992] machine synapse[363]: synapse.config.logger: [main] Server hostname: clan.test255machine # [1784372.102071] machine synapse[363]: synapse.config.logger: [main] Public Base URL: https://clan.test/256machine # [1784372.102134] machine synapse[363]: synapse.config.logger: [main] Instance name: master257machine # [1784372.102193] machine synapse[363]: synapse.config.logger: [main] Twisted reactor: EPollReactor258machine # [1784372.102250] machine synapse[363]: synapse.app.homeserver: [main] Setting up server259machine # [1784372.102305] machine synapse[363]: synapse.server: [main] Setting up.260machine # [1784372.110796] machine synapse[363]: synapse.storage.databases: [main] [database config 'master']: Checking database server261machine # [1784372.111973] machine synapse[363]: synapse.storage.databases: [main] [database config 'master']: Preparing for databases ['main', 'state']262machine # [1784372.112181] machine synapse[363]: synapse.storage.prepare_database: [main] ['main', 'state']: Checking existing schema version263machine # [1784372.119543] machine synapse[363]: synapse.storage.prepare_database: [main] ['main', 'state']: Initialising new database264machine # [1784372.319138] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema deltas for v73265machine # [1784372.320200] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 73/01event_failed_pull_attempts.sql266machine # [1784372.323262] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 73/02add_pusher_enabled.sql267machine # [1784372.324143] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 73/02room_id_indexes_for_purging.sql268machine # [1784372.325746] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 73/03pusher_device_id.sql269machine # [1784372.326551] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 73/03users_approved_column.sql270machine # [1784372.327407] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 73/04partial_join_details.sql271machine # [1784372.328777] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 73/04pending_device_list_updates.sql272machine # [1784372.330782] machine synapse[363]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/05old_push_actions.sql.postgres273machine # [1784372.331904] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 73/06thread_notifications_thread_id_idx.sql274machine # [1784372.333116] machine synapse[363]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/08thread_receipts_non_null.sql.postgres275machine # [1784372.334303] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 73/09partial_joined_via_destination.sql276machine # [1784372.335317] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 73/09threads_table.sql277machine # [1784372.338053] machine synapse[363]: synapse.storage.prepare_database: [main] Running 73/10_update_sqlite_fts4_tokenizer.py:run_create278machine # [1784372.338278] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 73/10login_tokens.sql279machine # [1784372.340539] machine synapse[363]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/11event_search_room_id_n_distinct.sql.postgres280machine # [1784372.341343] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 73/12refactor_device_list_outbound_pokes.sql281machine # [1784372.343962] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 73/13add_device_lists_index.sql282machine # [1784372.344729] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 73/20_un_partial_stated_room_stream.sql283machine # [1784372.347015] machine synapse[363]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/21_un_partial_stated_room_stream_seq.sql.postgres284machine # [1784372.348213] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 73/22_rebuild_user_dir_stats.sql285machine # [1784372.349012] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 73/22_un_partial_stated_event_stream.sql286machine # [1784372.351271] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 73/23_fix_thread_index.sql287machine # [1784372.352116] machine synapse[363]: synapse.storage.prepare_database: [main] Applying engine-specific schema 73/23_un_partial_stated_room_stream_seq.sql.postgres288machine # [1784372.353233] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 73/24_events_jump_to_date_index.sql289machine # [1784372.353933] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 73/25drop_presence.sql290machine # [1784372.355058] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema deltas for v74291machine # [1784372.355603] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 74/01_user_directory_stale_remote_users.sql292machine # [1784372.358186] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 74/02_set_device_id_for_pushers_bg_update.sql293machine # [1784372.359377] machine synapse[363]: synapse.storage.prepare_database: [main] Applying engine-specific schema 74/03_membership_tables_event_stream_ordering.sql.postgres294machine # [1784372.361412] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 74/03_room_membership_index.sql295machine # [1784372.362581] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 74/04_delete_e2e_backup_keys_for_deactivated_users.sql296machine # [1784372.364406] machine synapse[363]: synapse.storage.prepare_database: [main] Running 74/04_membership_tables_event_stream_ordering_triggers.py:run_create297machine # [1784372.366071] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 74/05_events_txn_id_device_id.sql298machine # [1784372.369757] machine synapse[363]: synapse.storage.prepare_database: [main] Applying engine-specific schema 74/90COMMENTS_destinations.sql.postgres299machine # [1784372.371304] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema deltas for v75300machine # [1784372.372046] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema deltas for v76301machine # [1784372.372884] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 76/01_add_profiles_full_user_id_column.sql302machine # [1784372.374164] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 76/02_add_user_filters_full_user_id_column.sql303machine # [1784372.375394] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 76/03_per_user_experimental_features.sql304machine # [1784372.377908] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 76/04_add_room_forgetter.sql305machine # [1784372.380417] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema deltas for v77306machine # [1784372.381492] machine synapse[363]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/01_add_profiles_not_valid_check.sql.postgres307machine # [1784372.382711] machine synapse[363]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/02_add_user_filters_not_valid_check.sql.postgres308machine # [1784372.383911] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 77/03bg_populate_full_user_id_profiles.sql309machine # [1784372.384795] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 77/04bg_populate_full_user_id_user_filters.sql310machine # [1784372.385460] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 77/05thread_notifications_backfill.sql311machine # [1784372.387338] machine synapse[363]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_actions.sql.postgres312machine # [1784372.388182] machine synapse[363]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_actions_staging.sql.postgres313machine # [1784372.389369] machine synapse[363]: synapse.storage.prepare_database: [main] Applying engine-specific schema 77/06thread_notifications_not_null_event_push_summary.sql.postgres314machine # [1784372.390601] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 77/14bg_indices_event_stream_ordering.sql315machine # [1784372.391747] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema deltas for v78316machine # [1784372.395578] machine synapse[363]: synapse.storage.prepare_database: [main] Running 78/03event_extremities_constraints.py:run_create317machine # [1784372.396720] machine synapse[363]: synapse.storage.prepare_database: [main] Running 78/04_add_full_user_id_index_user_filters.py:run_create318machine # [1784372.396950] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema deltas for v79319machine # [1784372.397444] machine synapse[363]: synapse.storage.prepare_database: [main] Applying engine-specific schema 79/03_read_write_locks_triggers.sql.postgres320machine # [1784372.404313] machine synapse[363]: synapse.storage.prepare_database: [main] Running 79/04_mitigate_stream_ordering_update_race.py:run_create321machine # [1784372.404955] machine synapse[363]: synapse.storage.prepare_database: [main] Applying engine-specific schema 79/05_read_write_locks_triggers.sql.postgres322machine # [1784372.406496] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema deltas for v80323machine # [1784372.407030] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 80/01_users_alter_locked.sql324machine # [1784372.408015] machine synapse[363]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/02_read_write_locks_unlogged.sql.postgres325machine # [1784372.414182] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 80/02_scheduled_tasks.sql326machine # [1784372.418392] machine synapse[363]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/03_read_write_locks_triggers.sql.postgres327machine # [1784372.419605] machine synapse[363]: synapse.storage.prepare_database: [main] Applying engine-specific schema 80/04_read_write_locks_deadlock.sql.postgres328machine # [1784372.420826] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema deltas for v81329machine # [1784372.421267] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema deltas for v82330machine # [1784372.421755] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 82/02_scheduled_tasks_index.sql331machine # [1784372.422906] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 82/04_add_indices_for_purging_rooms.sql332machine # [1784372.423684] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 82/05gaps.sql333machine # [1784372.426077] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema deltas for v83334machine # [1784372.426994] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 83/01_drop_old_tables.sql335machine # [1784372.431761] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 83/05_cross_signing_key_update_grant.sql336machine # [1784372.434353] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 83/06_event_push_summary_room.sql337machine # [1784372.435540] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema deltas for v84338machine # [1784372.436498] machine synapse[363]: synapse.storage.prepare_database: [main] Applying engine-specific schema 84/01_auth_links_stats.sql.postgres339machine # [1784372.437747] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 84/02_auth_links_index.sql340machine # [1784372.438857] machine synapse[363]: synapse.storage.prepare_database: [main] Applying engine-specific schema 84/03_auth_links_analyze.sql.postgres341machine # [1784372.440211] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 84/04_access_token_index.sql342machine # [1784372.441330] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema deltas for v85343machine # [1784372.442277] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 85/01_add_suspended.sql344machine # [1784372.443694] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 85/02_add_instance_names.sql345machine # [1784372.445602] machine synapse[363]: synapse.storage.prepare_database: [main] Applying engine-specific schema 85/03_new_sequences.sql.postgres346machine # [1784372.450511] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 85/04_cleanup_device_federation_outbox.sql347machine # [1784372.451556] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 85/05_add_instance_names_converted_pos.sql348machine # [1784372.452766] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 85/06_add_room_reports.sql349machine # [1784372.455402] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema deltas for v86350machine # [1784372.456293] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 86/01_authenticate_media.sql351machine # [1784372.457992] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 86/02_receipts_event_id_index.sql352machine # [1784372.459086] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema deltas for v87353machine # [1784372.459932] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 87/01_sliding_sync_memberships.sql354machine # [1784372.469460] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 87/02_per_connection_state.sql355machine # [1784372.481803] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 87/03_current_state_index.sql356machine # [1784372.483213] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema deltas for v88357machine # [1784372.484292] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 88/01_add_delayed_events.sql358machine # [1784372.490453] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 88/01_custom_profile_fields.sql359machine # [1784372.491803] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 88/02_fix_sliding_sync_membership_snapshots_forgotten_column.sql360machine # [1784372.493182] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 88/03_add_otk_ts_added_index.sql361machine # [1784372.494332] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 88/04_current_state_delta_index.sql362machine # [1784372.495429] machine synapse[363]: synapse.storage.prepare_database: [main] Applying engine-specific schema 88/05_drop_old_otks.sql.postgres363machine # [1784372.497001] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 88/05_sliding_sync_room_config_index.sql364machine # [1784372.498148] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 88/06_events_received_ts_index.sql365machine # [1784372.499235] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema deltas for v89366machine # [1784372.500151] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 89/01_sliding_sync_membership_snapshot_index.sql367machine # [1784372.501393] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 89/01_state_groups_deletion.sql368machine # [1784372.506518] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema deltas for v90369machine # [1784372.507447] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 90/01_add_column_participant_room_memberships_table.sql370machine # [1784372.509018] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 90/02_delete_unreferenced_state_groups.sql371machine # [1784372.510345] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 90/03_remove_old_deletion_bg_update.sql372machine # [1784372.511499] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema deltas for v91373machine # [1784372.512385] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 91/01_media_hash.sql374machine # [1784372.514069] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema deltas for v92375machine # [1784372.515341] machine synapse[363]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/01_remove_trigger.sql.postgres376machine # [1784372.516574] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 92/02_remove_populate_participant_bg_update.sql377machine # [1784372.517939] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 92/04_ss_membership_snapshot_idx.sql378machine # [1784372.519089] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 92/04_thread_subscriptions.sql379machine # [1784372.525574] machine synapse[363]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/04_thread_subscriptions_seq.sql.postgres380machine # [1784372.527431] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 92/05_fixup_max_depth_cap.sql381machine # [1784372.528675] machine synapse[363]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/05_thread_subscriptions_comments.sql.postgres382machine # [1784372.529972] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 92/06_device_federation_inbox_index.sql383machine # [1784372.531122] machine synapse[363]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/06_threads_last_sent_stream_ordering_comments.sql.postgres384machine # [1784372.532390] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 92/07_add_user_reports.sql385machine # [1784372.536722] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 92/07_event_txn_id_device_id_txn_id2.sql386machine # [1784372.538037] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 92/08_room_ban_redactions.sql387machine # [1784372.541095] machine synapse[363]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/08_thread_subscriptions_seq_fixup.sql.postgres388machine # [1784372.542501] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 92/09_thread_subscriptions_update.sql389machine # [1784372.543896] machine synapse[363]: synapse.storage.prepare_database: [main] Applying engine-specific schema 92/09_thread_subscriptions_update.sql.postgres390machine # [1784372.545927] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema deltas for v93391machine # [1784372.547038] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 93/01_add_delayed_events.sql392machine # [1784372.548617] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 93/01_sticky_events.sql393machine # [1784372.553848] machine synapse[363]: synapse.storage.prepare_database: [main] Applying engine-specific schema 93/01_sticky_events_seq.sql.postgres394machine # [1784372.555672] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 93/02_sliding_sync_members.sql395machine # [1784372.561495] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 93/03_sss_pos_last_used.sql396machine # [1784372.563692] machine synapse[363]: synapse.storage.prepare_database: [main] Running 93/04_make_delayed_event_content_text.py:run_create397machine # [1784372.568354] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema deltas for v94398machine # [1784372.569793] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 94/01_redactions_recheck.sql399machine # [1784372.572401] machine synapse[363]: synapse.storage.prepare_database: [main] Applying engine-specific schema 94/01_state_groups_persisting_inserted_ts.sql.postgres400machine # [1784372.574264] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 94/02_redactions_recheck_bg_update.sql401machine # [1784372.575537] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 94/03_device_lists_room_timestamp.sql402machine # [1784372.576961] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 94/03_quarantined_media_tracking.sql403machine # [1784372.580432] machine synapse[363]: synapse.storage.prepare_database: [main] Applying engine-specific schema 94/03_quarantined_media_tracking_seq.sql.postgres404machine # [1784372.582251] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 94/03_state_dag_fwd_extrems.sql405machine # [1784372.590749] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 94/04_device_lists_changes_max_pruned.sql406machine # [1784372.593478] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 94/05_rerun_flag_existing_quarantined_media.sql407machine # [1784372.594710] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 94/06_sliding_sync_connections_last_used_ts_index.sql408machine # [1784372.595775] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 94/07_profile_updates.sql409machine # [1784372.599652] machine synapse[363]: synapse.storage.prepare_database: [main] Applying engine-specific schema 94/07_profile_updates_seq.sql.postgres410machine # [1784372.601107] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 94/07_sliding_sync_lazy_members_position_index.sql411machine # [1784372.602237] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 94/08_device_lists_changes_in_room_unconverted_idx.sql412machine # [1784372.603303] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 94/09_e2e_cross_signing_signatures_index.sql413machine # [1784372.604365] machine synapse[363]: synapse.storage.prepare_database: [main] Applying schema 94/10_e2e_cross_signing_signatures_add_key_id_to_index.sql414machine # [1784372.605438] machine synapse[363]: synapse.storage.prepare_database: [main] Schema now up to date415machine # [1784372.606455] machine synapse[363]: synapse.storage.databases: [main] [database config 'master']: Starting 'main' database416machine # [1784372.643976] machine synapse[363]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules(id): 1417machine # [1784372.645001] machine synapse[363]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules_enable(id): 1418machine # [1784372.656322] machine synapse[363]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 month ago419machine # [1784372.656945] machine synapse[363]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 month ago: it's 0420machine # [1784372.657163] machine synapse[363]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 day ago421machine # [1784372.657730] machine synapse[363]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 day ago: it's 0422machine # [1784372.665646] machine synapse[363]: synapse.storage.util.id_generators: [main] Initialising stream generator for access_tokens(id): 1423machine # [1784372.666583] machine synapse[363]: synapse.storage.util.id_generators: [main] Initialising stream generator for refresh_tokens(id): 1424machine # [1784372.672203] machine synapse[363]: synapse.storage.util.id_generators: [main] Initialising stream generator for event_reports(id): 1425machine # [1784372.672992] machine synapse[363]: synapse.storage.util.id_generators: [main] Initialising stream generator for room_reports(id): 1426machine # [1784372.673922] machine synapse[363]: synapse.storage.util.id_generators: [main] Initialising stream generator for user_reports(id): 1427machine # [1784372.686898] machine synapse[363]: synapse.storage.databases: [main] [database config 'master']: Starting 'state' database428machine # [1784372.689730] machine synapse[363]: synapse.storage.databases: [main] [database config 'master']: prepared429machine # [1784372.690009] machine synapse[363]: synapse.server: [main] Finished setting up.430machine # [1784372.690541] machine synapse[363]: twisted: [main] Redirected stdout/stderr to logs431machine # [1784372.690815] machine synapse[363]: synapse.app.homeserver: [main] Running432machine # [1784372.699898] machine synapse[363]: synapse.app.homeserver: [main] Set file limit to: 524288433machine # [1784372.755187] machine synapse[363]: synapse.push.pusher: [call_when_running] email enable notifs: False434machine # [1784372.785759] machine synapse[363]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'profile'435machine # [1784372.787360] machine synapse[363]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.device_list_update'436machine # [1784372.787910] machine synapse[363]: synapse.util.caches.lrucache: [call_when_running] Expiring LRU caches after 1800 seconds437machine # [1784372.791573] machine synapse[363]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.presence'438machine # [1784372.791965] machine synapse[363]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.typing'439machine # [1784372.793839] machine synapse[363]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'directory'440machine # [1784372.801679] machine synapse[363]: synapse.handlers.deactivate_account: [user_parter_loop-0] Starting user parter441machine # [1784372.802602] machine synapse[363]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.receipt'442machine # [1784372.802851] machine synapse[363]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.signing_key_update'443machine # [1784372.802936] machine synapse[363]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'org.matrix.signing_key_update'444machine # [1784372.803014] machine synapse[363]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'client_keys'445machine # [1784372.803637] machine synapse[363]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.direct_to_device'446machine # [1784372.811195] machine synapse[363]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.health.HealthResource object at 0xffffec6378a0> to path b'/health'447machine # [1784372.811309] machine synapse[363]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.EncodingResourceWrapper object at 0xffffec8e0f20> to path b'/_matrix/client'448machine # [1784372.811420] machine synapse[363]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffec8e6c20> to path b'/.well-known'449machine # [1784372.811507] machine synapse[363]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.http.server.JsonResource object at 0xffffec8e10a0> to path b'/_synapse/admin'450machine # [1784372.811593] machine synapse[363]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.pick_idp.PickIdpResource object at 0xffffec8e70a0> to path b'/_synapse/client/pick_idp'451machine # [1784372.811684] machine synapse[363]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffec288ce0> to path b'/_synapse/client/pick_username'452machine # [1784372.811785] machine synapse[363]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.new_user_consent.NewUserConsentResource object at 0xffffec8e76a0> to path b'/_synapse/client/new_user_consent'453machine # [1784372.811873] machine synapse[363]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.sso_register.SsoRegisterResource object at 0xffffec8e7820> to path b'/_synapse/client/sso_register'454machine # [1784372.811956] machine synapse[363]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.unsubscribe.UnsubscribeResource object at 0xffffec8e79a0> to path b'/_synapse/client/unsubscribe'455machine # [1784372.812052] machine synapse[363]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.media_upload_limit_exceeded.MediaUploadLimitExceededResource object at 0xffffec8e7b20> to path b'/_synapse/client/media_upload_limit_exceeded'456machine # [1784372.812155] machine synapse[363]: synapse.util.httpresourcetree: [call_when_running] Attaching FilePath('/nix/store/lv57dn8m32v75w568vmq0fr0wxx1zjzd-matrix-synapse-1.161.0/lib/python3.14/site-packages/synapse/static') to path b'/_matrix/static'457machine # [1784372.812238] machine synapse[363]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffec8e7fa0> to path b'/_matrix/media/r0'458machine # [1784372.812324] machine synapse[363]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffec8e7fa0> to path b'/_matrix/media/v3'459machine # [1784372.812404] machine synapse[363]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffec8e7fa0> to path b'/_matrix/media/v1'460machine # [1784372.812485] machine synapse[363]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.federation.transport.server.TransportLayerServer object at 0xffffec8e8ba0> to path b'/_matrix/federation'461machine # [1784372.812562] machine synapse[363]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.key.v2.KeyResource object at 0xffffec8ebd20> to path b'/_matrix/key'462machine # [1784372.814854] machine synapse[363]: twisted: [call_when_running] SynapseSite starting on 8008463machine # [1784372.815080] machine synapse[363]: synapse.app._base: [call_when_running] Synapse now listening on TCP port 8008464machine # [1784372.817076] machine synapse[363]: 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).465machine # [1784372.894247] machine synapse[363]: synapse.storage.background_updates: [background_updates-0] Starting background schema updates for database master466machine # [1784372.894646] machine synapse[363]: synapse.handlers.deactivate_account: [user_parter_loop-0] User parter finished: stopping467machine # [1784372.905088] machine synapse[363]: synapse.push.pusherpool: [start_pushers-0] Started pushers468machine # [1784373.017810] machine ap456hr5zj95nqqgz59ghzjb4s59w90h-matrix-synapse-create-users[388]: Connection to ::1 8008 port [tcp/http-alt] succeeded!469machine: still waiting for container 'machine' to reach ready state...470machine # [1784373.503617] machine synapse[363]: 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.34.2" [0 dbevts]471machine # [1784373.800809] machine synapse[363]: synapse.handlers.auth: [POST-1] Logging in user @admin:clan.test on device NSJTUZQVCE472machine # [1784373.810624] machine synapse[363]: synapse.access.http.8008: [POST-1] ::1 - 8008 - {None} Processed request: 0.302sec/0.001sec ru=(0.260sec, 0.003sec) db=(0.006sec/0.031sec/12) 138B 200 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.34.2" [0 dbevts]473machine # [1784373.812591] machine ap456hr5zj95nqqgz59ghzjb4s59w90h-matrix-synapse-create-users[389]: Sending registration request...474machine # [1784373.812591] machine ap456hr5zj95nqqgz59ghzjb4s59w90h-matrix-synapse-create-users[389]: Success!475machine # [1784373.901693] machine synapse[363]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'threads_backfill'476machine # [1784373.908798] machine synapse[363]: 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)477machine # [1784374.377577] machine synapse[363]: 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.34.2" [0 dbevts]478machine # [1784374.664221] machine synapse[363]: synapse.handlers.auth: [POST-3] Logging in user @someuser:clan.test on device BATIYKTMXW479machine # [1784374.672433] machine synapse[363]: synapse.access.http.8008: [POST-3] ::1 - 8008 - {None} Processed request: 0.290sec/0.001sec ru=(0.259sec, 0.002sec) db=(0.005sec/0.018sec/12) 145B 200 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.34.2" [0 dbevts]480machine # [1784374.673874] machine ap456hr5zj95nqqgz59ghzjb4s59w90h-matrix-synapse-create-users[395]: Sending registration request...481machine # [1784374.673874] machine ap456hr5zj95nqqgz59ghzjb4s59w90h-matrix-synapse-create-users[395]: Success!482machine # [1784374.766077] machine systemd[1]: Started Synapse Matrix homeserver.483machine # [1784374.766724] machine systemd[1]: Startup finished in 20.707s.484machine # [1784374.912390] machine synapse[363]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'device_lists_changes_in_room_by_room_index'485machine # [1784374.914109] machine synapse[363]: synapse.storage.background_updates: [background_updates-0] Adding index device_lists_changes_in_room_by_room_idx to device_lists_changes_in_room486machine # [1784374.920554] machine synapse[363]: 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)487machine: (finished: waiting for unit matrix-synapse, in 21.70 seconds)488machine: must succeed: nc -z -v ::1 8008489Connection to ::1 8008 port [tcp/http-alt] succeeded!490machine: (finished: must succeed: nc -z -v ::1 8008, in 0.01 seconds)491machine: waiting for success: curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test'492machine: (finished: waiting for success: curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test', in 0.02 seconds)493machine # [1784375.199878] machine synapse[363]: 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]494machine # [1784375.201257] machine nginx[276]: machine nginx: ::1 - - [24/Sep/2026:23:06:35 +0000] "GET /_matrix/static/ HTTP/1.1" 200 10145 "-" "curl/8.22.0"495machine # [1784375.217091] machine systemd[1]: Stopping Synapse Matrix homeserver...496machine # [1784375.217095] machine synapse[363]: twisted: [sentinel] Received SIGTERM, shutting down.497machine # [1784375.217799] machine synapse[363]: synapse.storage.databases.main.lock: [LockStore._on_shutdown-0] Dropping held locks due to shutdown498machine # [1784375.218077] machine synapse[363]: synapse.storage.databases.main.lock: [LockStore._on_shutdown-0] Dropped locks due to shutdown499machine # [1784375.219282] machine synapse[363]: synapse.handlers.presence: [PresenceHandler._on_shutdown-0] Performing _on_shutdown. Persisting 0 unpersisted changes500machine # [1784375.219482] machine synapse[363]: synapse.handlers.presence: [PresenceHandler._on_shutdown-0] Finished _on_shutdown501machine # [1784375.220062] machine synapse[363]: synapse.app._base: [log_shutdown] Shutting down...502machine # [1784375.220844] machine synapse[363]: twisted: [sentinel] (TCP Port 8008 Closed)503machine # [1784375.224704] machine synapse[363]: twisted: [sentinel] Main loop terminated.504machine # [1784375.300479] machine systemd[1]: matrix-synapse.service: Deactivated successfully.505machine # [1784375.300845] machine systemd[1]: Stopped Synapse Matrix homeserver.506machine # [1784375.300893] machine systemd[1]: matrix-synapse.service: Consumed 7.901s CPU time over 9.557s wall clock time.507machine # [1784375.305171] machine systemd[1]: Starting Create the matrix-synapse database with C collation...508machine # [1784375.355178] machine systemd[1]: matrix-synapse-postgres.service: Deactivated successfully.509machine # [1784375.355500] machine systemd[1]: Finished Create the matrix-synapse database with C collation.510machine # [1784375.358649] machine systemd[1]: Starting Synapse Matrix homeserver...511machine # [1784380.963840] machine synapse_homeserver[431]: This server is configured to use 'matrix.org' as its trusted key server via the512machine # [1784380.963840] machine synapse_homeserver[431]: 'trusted_key_servers' config option. 'matrix.org' is a good choice for a key513machine # [1784380.963840] machine synapse_homeserver[431]: server since it is long-lived, stable and trusted. However, some admins may514machine # [1784380.963840] machine synapse_homeserver[431]: wish to use another server for this purpose.515machine # [1784380.963840] machine synapse_homeserver[431]: To suppress this warning and continue using 'matrix.org', admins should set516machine # [1784380.963840] machine synapse_homeserver[431]: 'suppress_key_server_warning' to 'true' in homeserver.yaml.517machine # [1784380.963840] machine synapse_homeserver[431]: --------------------------------------------------------------------------------518machine # [1784381.010199] machine synapse[431]: synapse.config.logger: [main] ***** STARTING SERVER *****519machine # [1784381.010309] machine synapse[431]: synapse.config.logger: [main] Server /nix/store/lv57dn8m32v75w568vmq0fr0wxx1zjzd-matrix-synapse-1.161.0/bin/synapse_homeserver version 1.161.0520machine # [1784381.010378] machine synapse[431]: synapse.config.logger: [main] Copyright (c) 2023 New Vector, Inc521machine # [1784381.010445] machine synapse[431]: synapse.config.logger: [main] Licensed under the AGPL 3.0 license. Website: https://github.com/element-hq/synapse522machine # [1784381.010634] machine synapse[431]: synapse.config.logger: [main] Server hostname: clan.test523machine # [1784381.010717] machine synapse[431]: synapse.config.logger: [main] Public Base URL: https://clan.test/524machine # [1784381.010791] machine synapse[431]: synapse.config.logger: [main] Instance name: master525machine # [1784381.010854] machine synapse[431]: synapse.config.logger: [main] Twisted reactor: EPollReactor526machine # [1784381.010912] machine synapse[431]: synapse.app.homeserver: [main] Setting up server527machine # [1784381.010973] machine synapse[431]: synapse.server: [main] Setting up.528machine # [1784381.016265] machine synapse[431]: synapse.storage.databases: [main] [database config 'master']: Checking database server529machine # [1784381.017522] machine synapse[431]: synapse.storage.databases: [main] [database config 'master']: Preparing for databases ['main', 'state']530machine # [1784381.017714] machine synapse[431]: synapse.storage.prepare_database: [main] ['main', 'state']: Checking existing schema version531machine # [1784381.019375] machine synapse[431]: synapse.storage.prepare_database: [main] ['main', 'state']: Existing schema is 94 (+16 deltas)532machine # [1784381.019460] machine synapse[431]: synapse.storage.databases.main: [main] Checking database for consistency with configuration...533machine # [1784381.020324] machine synapse[431]: synapse.storage.prepare_database: [main] Applying schema deltas for v94534machine # [1784381.021377] machine synapse[431]: synapse.storage.prepare_database: [main] Schema now up to date535machine # [1784381.022055] machine synapse[431]: synapse.storage.databases: [main] [database config 'master']: Starting 'main' database536machine # [1784381.044473] machine synapse[431]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules(id): 1537machine # [1784381.044987] machine synapse[431]: synapse.storage.util.id_generators: [main] Initialising stream generator for push_rules_enable(id): 1538machine # [1784381.051557] machine synapse[431]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 month ago539machine # [1784381.051884] machine synapse[431]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 month ago: it's 0540machine # [1784381.051965] machine synapse[431]: synapse.storage.databases.main.event_push_actions: [main] Searching for stream ordering 1 day ago541machine # [1784381.052271] machine synapse[431]: synapse.storage.databases.main.event_push_actions: [main] Found stream ordering 1 day ago: it's 0542machine # [1784381.056580] machine synapse[431]: synapse.storage.util.id_generators: [main] Initialising stream generator for access_tokens(id): 3543machine # [1784381.057141] machine synapse[431]: synapse.storage.util.id_generators: [main] Initialising stream generator for refresh_tokens(id): 1544machine # [1784381.060566] machine synapse[431]: synapse.storage.util.id_generators: [main] Initialising stream generator for event_reports(id): 1545machine # [1784381.061018] machine synapse[431]: synapse.storage.util.id_generators: [main] Initialising stream generator for room_reports(id): 1546machine # [1784381.061527] machine synapse[431]: synapse.storage.util.id_generators: [main] Initialising stream generator for user_reports(id): 1547machine # [1784381.070104] machine synapse[431]: synapse.storage.databases: [main] [database config 'master']: Starting 'state' database548machine # [1784381.072043] machine synapse[431]: synapse.storage.databases: [main] [database config 'master']: prepared549machine # [1784381.072206] machine synapse[431]: synapse.server: [main] Finished setting up.550machine # [1784381.072506] machine synapse[431]: twisted: [main] Redirected stdout/stderr to logs551machine # [1784381.072647] machine synapse[431]: synapse.app.homeserver: [main] Running552machine # [1784381.077419] machine synapse[431]: synapse.app.homeserver: [main] Set file limit to: 524288553machine # [1784381.110685] machine synapse[431]: synapse.push.pusher: [call_when_running] email enable notifs: False554machine # [1784381.133071] machine synapse[431]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'profile'555machine # [1784381.134382] machine synapse[431]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.device_list_update'556machine # [1784381.134828] machine synapse[431]: synapse.util.caches.lrucache: [call_when_running] Expiring LRU caches after 1800 seconds557machine # [1784381.137482] machine synapse[431]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.presence'558machine # [1784381.137833] machine synapse[431]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.typing'559machine # [1784381.139154] machine synapse[431]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'directory'560machine # [1784381.144893] machine synapse[431]: synapse.handlers.deactivate_account: [user_parter_loop-0] Starting user parter561machine # [1784381.145659] machine synapse[431]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.receipt'562machine # [1784381.146668] machine synapse[431]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.signing_key_update'563machine # [1784381.146746] machine synapse[431]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'org.matrix.signing_key_update'564machine # [1784381.146809] machine synapse[431]: synapse.federation.federation_server: [call_when_running] Registering federation query handler for 'client_keys'565machine # [1784381.147351] machine synapse[431]: synapse.federation.federation_server: [call_when_running] Registering federation EDU handler for 'm.direct_to_device'566machine # [1784381.153204] machine synapse[431]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.health.HealthResource object at 0xffffec658020> to path b'/health'567machine # [1784381.153301] machine synapse[431]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.EncodingResourceWrapper object at 0xffffec8916a0> to path b'/_matrix/client'568machine # [1784381.153395] machine synapse[431]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffec8973a0> to path b'/.well-known'569machine # [1784381.153460] machine synapse[431]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.http.server.JsonResource object at 0xffffec891820> to path b'/_synapse/admin'570machine # [1784381.153529] machine synapse[431]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.pick_idp.PickIdpResource object at 0xffffec897820> to path b'/_synapse/client/pick_idp'571machine # [1784381.153601] machine synapse[431]: synapse.util.httpresourcetree: [call_when_running] Attaching <twisted.web.resource.Resource object at 0xffffec249460> to path b'/_synapse/client/pick_username'572machine # [1784381.153664] machine synapse[431]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.new_user_consent.NewUserConsentResource object at 0xffffec897e20> to path b'/_synapse/client/new_user_consent'573machine # [1784381.153739] machine synapse[431]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.sso_register.SsoRegisterResource object at 0xffffec897fa0> to path b'/_synapse/client/sso_register'574machine # [1784381.153825] machine synapse[431]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.unsubscribe.UnsubscribeResource object at 0xffffec898120> to path b'/_synapse/client/unsubscribe'575machine # [1784381.153905] machine synapse[431]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.synapse.client.media_upload_limit_exceeded.MediaUploadLimitExceededResource object at 0xffffec8982a0> to path b'/_synapse/client/media_upload_limit_exceeded'576machine # [1784381.153989] machine synapse[431]: synapse.util.httpresourcetree: [call_when_running] Attaching FilePath('/nix/store/lv57dn8m32v75w568vmq0fr0wxx1zjzd-matrix-synapse-1.161.0/lib/python3.14/site-packages/synapse/static') to path b'/_matrix/static'577machine # [1784381.154058] machine synapse[431]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffec898720> to path b'/_matrix/media/r0'578machine # [1784381.154123] machine synapse[431]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffec898720> to path b'/_matrix/media/v3'579machine # [1784381.154189] machine synapse[431]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.media.media_repository_resource.MediaRepositoryResource object at 0xffffec898720> to path b'/_matrix/media/v1'580machine # [1784381.154251] machine synapse[431]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.federation.transport.server.TransportLayerServer object at 0xffffec899320> to path b'/_matrix/federation'581machine # [1784381.154314] machine synapse[431]: synapse.util.httpresourcetree: [call_when_running] Attaching <synapse.rest.key.v2.KeyResource object at 0xffffec89c4a0> to path b'/_matrix/key'582machine # [1784381.156092] machine synapse[431]: twisted: [call_when_running] SynapseSite starting on 8008583machine # [1784381.156550] machine synapse[431]: synapse.app._base: [call_when_running] Synapse now listening on TCP port 8008584machine # [1784381.158100] machine synapse[431]: 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).585machine # [1784381.231984] machine synapse[431]: synapse.storage.background_updates: [background_updates-0] Starting background schema updates for database master586machine # [1784381.238215] machine synapse[431]: synapse.handlers.deactivate_account: [user_parter_loop-0] User parter finished: stopping587machine # [1784381.240261] machine synapse[431]: synapse.push.pusherpool: [start_pushers-0] Started pushers588machine # [1784381.366535] machine ap456hr5zj95nqqgz59ghzjb4s59w90h-matrix-synapse-create-users[456]: Connection to ::1 8008 port [tcp/http-alt] succeeded!589machine # [1784381.841440] machine synapse[431]: synapse.access.http.8008: [GET-0] ::1 - 8008 - {None} Processed request: 0.001sec/0.001sec ru=(0.003sec, 0.000sec) db=(0.000sec/0.000sec/0) 140B 200 "GET /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.34.2" [0 dbevts]590machine # [1784382.093075] machine synapse[431]: synapse.http.server: [POST-1] <XForwardedForRequest at 0xffffec23fbe0 method='POST' uri='/_synapse/admin/v1/register' clientproto='HTTP/1.1' site='8008'> SynapseError: 400 - User ID already taken.591machine # [1784382.094191] machine synapse[431]: synapse.access.http.8008: [POST-1] ::1 - 8008 - {None} Processed request: 0.248sec/0.000sec ru=(0.242sec, 0.000sec) db=(0.000sec/0.001sec/1) 60B 400 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.34.2" [0 dbevts]592machine # [1784382.096245] machine ap456hr5zj95nqqgz59ghzjb4s59w90h-matrix-synapse-create-users[457]: Sending registration request...593machine # [1784382.096245] machine ap456hr5zj95nqqgz59ghzjb4s59w90h-matrix-synapse-create-users[457]: User already exists. Skipping.594machine # [1784382.244309] machine synapse[431]: synapse.storage.background_updates: [background_updates-0] Starting update batch on background update 'populate_stats_process_rooms'595machine # [1784382.251151] machine synapse[431]: 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)596machine # [1784382.651844] machine synapse[431]: 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.34.2" [0 dbevts]597machine # [1784382.893867] machine synapse[431]: synapse.http.server: [POST-3] <XForwardedForRequest at 0xffffec21cd20 method='POST' uri='/_synapse/admin/v1/register' clientproto='HTTP/1.1' site='8008'> SynapseError: 400 - User ID already taken.598machine # [1784382.894903] machine synapse[431]: synapse.access.http.8008: [POST-3] ::1 - 8008 - {None} Processed request: 0.239sec/0.000sec ru=(0.232sec, 0.000sec) db=(0.000sec/0.001sec/1) 60B 400 "POST /_synapse/admin/v1/register HTTP/1.1" "python-requests/2.34.2" [0 dbevts]599machine # [1784382.896515] machine ap456hr5zj95nqqgz59ghzjb4s59w90h-matrix-synapse-create-users[463]: Sending registration request...600machine # [1784382.896515] machine ap456hr5zj95nqqgz59ghzjb4s59w90h-matrix-synapse-create-users[463]: User already exists. Skipping.601machine: waiting for unit matrix-synapse602machine: (finished: waiting for unit matrix-synapse, in 0.03 seconds)603machine: must succeed: nc -z -v ::1 8008604Connection to ::1 8008 port [tcp/http-alt] succeeded!605machine: (finished: must succeed: nc -z -v ::1 8008, in 0.01 seconds)606machine: must succeed: curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test'607machine: (finished: must succeed: curl -Ssf -L http://localhost/_matrix/static/ -H 'Host: matrix.clan.test', in 0.02 seconds)608(finished: run the VM test script, in 29.58 seconds)609test script finished in 29.78s610cleanup611kill NspawnMachine (pid 51)612machine # [1784382.986434] machine systemd[1]: Started Synapse Matrix homeserver.613machine # [1784383.046213] machine nginx[276]: 2026/09/24 23:06:43 [error] 276#276: *3 connect() failed (111: Connection refused) while connecting to upstream, client: ::1, server: matrix.clan.test, request: "GET /_matrix/static/ HTTP/1.1", upstream: "http://127.0.0.1:8008/_matrix/static/", host: "matrix.clan.test"614machine # [1784383.049238] machine synapse[431]: synapse.access.http.8008: [GET-4] ::1 - 8008 - {None} Processed request: 0.001sec/-0.000sec ru=(0.002sec, 0.000sec) db=(0.000sec/0.000sec/0) 10145B 200 "GET /_matrix/static/ HTTP/1.1" "curl/8.22.0" [0 dbevts]615machine # [1784383.050225] machine nginx[276]: machine nginx: ::1 - - [24/Sep/2026:23:06:43 +0000] "GET /_matrix/static/ HTTP/1.1" 200 10145 "-" "curl/8.22.0"616Container machine terminated by signal KILL.617(finished: cleanup, in 0.16 seconds)