Cannot update a stable version to nightly on macOS

[scotttrinh]

Steps to reproduce

On macOS:

1. edgedb project init and select the current stable (5.7 as of today)
2. edgedb project upgrade --to-nightly

Observe:

$ edgedb project upgrade --to-nightly
Downloading package...
00:00:01 [====================] 51.77 MiB/51.77 MiB 34.88 MiB/s | ETA: 0s
Successfully installed 6.0-dev.9043+4c05a1a
The schema is forward compatible. Ready for upgrade.
Upgrading to a major version 6.0-dev.9043+4c05a1a
Dumping the database...
Starting dump for database `main`...
  Finished dump for `main`. Total size: 0 B                                                                                                                                                                                                   Restoring the database...
[edgedb] CRITICAL 15986 - 2024-12-03T14:23:18.475 postgres: the database system is starting up
edgedb error: cannot restore "tt": Connection error: ClientConnectionFailedError: cannot establish connection for 300s
To undo run:
  edgedb instance revert -I "tt"

Notes

Initializing with a nightly instance works fine, so it's something with the actual upgrade flow that is breaking.

Running the RUST_LOG=trace, you get this:

  Finished dump for `main`. Total size: 0 B                                                                                                                            [2024-11-26T15:42:36Z TRACE mio::poll] deregistering event source from poller
[2024-11-26T15:42:36Z TRACE mio::poll] deregistering event source from poller
[2024-11-26T15:42:36Z INFO  edgedb::portable::upgrade] Stopping instance before executable upgrade
[2024-11-26T15:42:36Z TRACE mio::poll] registering event source with poller: token=Token(1), interests=READABLE
[2024-11-26T15:42:36Z INFO  edgedb::process] Running detect launchd: Command { std: LANG="C.UTF-8" LC_ALL="C.UTF-8" LC_CTYPE="UTF-8" "/bin/launchctl" "print-disabled" "gui/501", kill_on_drop: false }
[2024-11-26T15:42:36Z TRACE mio::poll] registering event source with poller: token=Token(4485832320), interests=READABLE | WRITABLE
[2024-11-26T15:42:36Z TRACE mio::poll] registering event source with poller: token=Token(4485832576), interests=READABLE | WRITABLE
[2024-11-26T15:42:36Z DEBUG edgedb::process] Writing pid file None (pid: 60666)
[2024-11-26T15:42:36Z TRACE mio::poll] deregistering event source from poller
[2024-11-26T15:42:36Z TRACE mio::poll] deregistering event source from poller
[2024-11-26T15:42:36Z DEBUG edgedb::process] Result of detect launchd: exit status: 0
[2024-11-26T15:42:36Z DEBUG edgedb::portable::control] Service running by "launchctl"
[2024-11-26T15:42:36Z TRACE mio::poll] registering event source with poller: token=Token(1), interests=READABLE
[2024-11-26T15:42:36Z INFO  edgedb::process] Running service info: Command { std: LANG="C.UTF-8" LC_ALL="C.UTF-8" LC_CTYPE="UTF-8" "launchctl" "print" "gui/501/edgedb-server-empty", kill_on_drop: false }
[2024-11-26T15:42:36Z TRACE mio::poll] registering event source with poller: token=Token(5291148160), interests=READABLE | WRITABLE
[2024-11-26T15:42:36Z TRACE mio::poll] registering event source with poller: token=Token(5291148416), interests=READABLE | WRITABLE
[2024-11-26T15:42:36Z DEBUG edgedb::process] Writing pid file None (pid: 60707)
[2024-11-26T15:42:36Z TRACE mio::poll] deregistering event source from poller
[2024-11-26T15:42:36Z TRACE mio::poll] deregistering event source from poller
[2024-11-26T15:42:36Z DEBUG edgedb::process] Result of service info: exit status: 0
[2024-11-26T15:42:36Z INFO  edgedb::portable::macos] Unloading service
[2024-11-26T15:42:36Z TRACE mio::poll] registering event source with poller: token=Token(1), interests=READABLE
[2024-11-26T15:42:36Z INFO  edgedb::process] Running remove service: Command { std: LANG="C.UTF-8" LC_ALL="C.UTF-8" LC_CTYPE="UTF-8" "launchctl" "bootout" "gui/501/edgedb-server-empty", kill_on_drop: false }
[2024-11-26T15:42:36Z DEBUG edgedb::process] Writing pid file None (pid: 60748)
[2024-11-26T15:42:36Z DEBUG edgedb::process] Result of remove service: exit status: 0
[2024-11-26T15:42:36Z TRACE mio::poll] registering event source with poller: token=Token(1), interests=READABLE
[2024-11-26T15:42:36Z INFO  edgedb::process] Running service info: Command { std: LANG="C.UTF-8" LC_ALL="C.UTF-8" LC_CTYPE="UTF-8" "launchctl" "print" "gui/501/edgedb-server-empty", kill_on_drop: false }
[2024-11-26T15:42:36Z TRACE mio::poll] registering event source with poller: token=Token(4754260224), interests=READABLE | WRITABLE
[2024-11-26T15:42:36Z TRACE mio::poll] registering event source with poller: token=Token(4754260480), interests=READABLE | WRITABLE
[2024-11-26T15:42:36Z DEBUG edgedb::process] Writing pid file None (pid: 60789)
[2024-11-26T15:42:36Z TRACE mio::poll] deregistering event source from poller
[2024-11-26T15:42:36Z TRACE mio::poll] deregistering event source from poller
[2024-11-26T15:42:36Z DEBUG edgedb::process] Result of service info: exit status: 0
[2024-11-26T15:42:36Z TRACE mio::poll] registering event source with poller: token=Token(1), interests=READABLE
[2024-11-26T15:42:36Z INFO  edgedb::process] Running service info: Command { std: LANG="C.UTF-8" LC_ALL="C.UTF-8" LC_CTYPE="UTF-8" "launchctl" "print" "gui/501/edgedb-server-empty", kill_on_drop: false }
[2024-11-26T15:42:36Z TRACE mio::poll] registering event source with poller: token=Token(4754260224), interests=READABLE | WRITABLE
[2024-11-26T15:42:36Z TRACE mio::poll] registering event source with poller: token=Token(4386210048), interests=READABLE | WRITABLE
[2024-11-26T15:42:36Z DEBUG edgedb::process] Writing pid file None (pid: 60832)
[2024-11-26T15:42:36Z TRACE mio::poll] deregistering event source from poller
[2024-11-26T15:42:36Z TRACE mio::poll] deregistering event source from poller
[2024-11-26T15:42:36Z DEBUG edgedb::process] Result of service info: exit status: 0
[2024-11-26T15:42:37Z TRACE mio::poll] registering event source with poller: token=Token(1), interests=READABLE
[2024-11-26T15:42:37Z INFO  edgedb::process] Running service info: Command { std: LANG="C.UTF-8" LC_ALL="C.UTF-8" LC_CTYPE="UTF-8" "launchctl" "print" "gui/501/edgedb-server-empty", kill_on_drop: false }
[2024-11-26T15:42:37Z TRACE mio::poll] registering event source with poller: token=Token(5310006272), interests=READABLE | WRITABLE
[2024-11-26T15:42:37Z TRACE mio::poll] registering event source with poller: token=Token(5310007296), interests=READABLE | WRITABLE
[2024-11-26T15:42:37Z DEBUG edgedb::process] Writing pid file None (pid: 60873)
[2024-11-26T15:42:37Z TRACE mio::poll] deregistering event source from poller
[2024-11-26T15:42:37Z TRACE mio::poll] deregistering event source from poller
[2024-11-26T15:42:37Z DEBUG edgedb::process] Result of service info: exit status: 113
[2024-11-26T15:42:37Z DEBUG edgedb::portable::macos] `launchctl print gui/501/edgedb-server-empty` errored out with [66, 97, 100, 32, 114, 101, 113, 117, 101, 115, 116, 46, 10, 67, 111, 117, 108, 100, 32, 110, 111, 116, 32, 102, 105, 110, 100, 32, 115, 101, 114, 118, 105, 99, 101, 32, 34, 101, 100, 103, 101, 100, 98, 45, 115, 101, 114, 118, 101, 114, 45, 101, 109, 112, 116, 121, 34, 32, 105, 110, 32, 100, 111, 109, 97, 105, 110, 32, 102, 111, 114, 32, 117, 115, 101, 114, 32, 103, 117, 105, 58, 32, 53, 48, 49, 10]. Assuming service is not loaded.
[2024-11-26T15:42:37Z INFO  edgedb::portable::macos] Removing unit file /Users/scotttrinh/Library/LaunchAgents/com.edgedb.edgedb-server-empty.plist
Restoring the database...
[2024-11-26T15:42:37Z TRACE mio::poll] registering event source with poller: token=Token(1), interests=READABLE
[2024-11-26T15:42:37Z INFO  edgedb::process] Running edgedb: Command { std: EDGEDB_SERVER_CONFIG_cfg::auto_rebuild_query_cache="false" EDGEDB_SERVER_HTTP_ENDPOINT_SECURITY="optional" EDGEDB_SERVER_INSTANCE_NAME="empty" EDGEDB_SERVER_LOG_LEVEL="warn" LANG="C.UTF-8" LC_ALL="C.UTF-8" LC_CTYPE="UTF-8" "/Users/scotttrinh/Library/Application Support/edgedb/portable/6.0-dev.9019/bin/edgedb-server" "--data-dir" "/Users/scotttrinh/Library/Application Support/edgedb/data/empty" "--runstate-dir" "/Users/scotttrinh/Library/Caches/edgedb/run/empty" "--port" "10780" "--compiler-pool-mode=on_demand" "--admin-ui=enabled" "--tls-cert-mode=generate_self_signed" "--jose-key-mode=generate", kill_on_drop: false }
[2024-11-26T15:42:37Z TRACE mio::poll] registering event source with poller: token=Token(4386215808), interests=READABLE | WRITABLE
[2024-11-26T15:42:37Z TRACE mio::poll] registering event source with poller: token=Token(4386217472), interests=READABLE | WRITABLE
[2024-11-26T15:42:37Z DEBUG edgedb::process] Writing pid file None (pid: 60874)
[2024-11-26T15:42:37Z INFO  edgedb::portable::upgrade] Restoring instance "empty"
[2024-11-26T15:42:37Z INFO  edgedb_tokio::raw::connection] Connecting via Unix `/Users/scotttrinh/Library/Caches/edgedb/run/empty/.s.EDGEDB.admin.10780`
[2024-11-26T15:42:37Z DEBUG edgedb_tokio::raw::connection] Temporary connection error: ClientConnectionError: Connection refused (os error 61)

And that final edgedb_tokio error repeats until the timeout kicks in.