Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Thunderbird 128.3.3esr (64-bit) fails to connect to etesync-dav using Apple Silicon build of etebase #56

Closed
odkr opened this issue Nov 26, 2024 · 4 comments

Comments

@odkr
Copy link

odkr commented Nov 26, 2024

The Thunderbird calendar fails to connect to etesync-dav using an Apple Silicon build of etebase from this repository as of commit 89f9b0d. The macOS Calendar.app fails, too. But given how tricky it is to setup etesync in Calendar.app, I won't be trying that as long as I'm not sure it works in principle. This blocks providing Apple Silicon builds.

The good news is that compiling etebase on Apple Silicon works in principle. If this issue can be addressed and the etebase version on PyPI is updated to 89f9b0d, building Apple Silicon binaries should be as simple as adding a macos-14 runner to manual.yml; that runner runs on Apple Silicon. The current version of etebase on PyPI does not build on that runner (for the reasons given in issue #328 over at etesync-dav).

System

$ rustc --version
rustc 1.82.0 (f6e511eec 2024-10-15) (Homebrew)
$ uname -mor
Darwin 23.6.0 arm64
$ python3 --version
Python 3.9.6

Steps to reproduce

  1. Compile etesync-dav with etebase-py from GitHub:
python3 -mvenv target
. ./target/bin/activate
pip install --upgrade pip
git clone https://github.com/etesync/etebase-py.git
pip install ./etebase-py
git clone https://github.com/etesync/etesync-dav.git
pip install ./etesync-dav
# Apparently missing in dependencies
pip install pyscrypt
  1. Delete old etesync-dav data
rm -rf "$HOME/Library/Application Support/etesync-dav"
  1. Start etesync-dav:
# Still using the virtual environment in target
etesync-dav --logging-level debug
  1. Set up Calendar in Thunderbird following the instructions in the etesync-dav repository.

This leads to:

Screenshot 2024-11-26 at 23 41 57

etesync-dav's debug log reads:

/Users/odin/etesync-test/target/lib/python3.9/site-packages/urllib3/__init__.py:35: NotOpenSSLWarning: urllib3 v2 only supports OpenSSL 1.1.1+, currently the 'ssl' module is compiled with 'LibreSSL 2.8.3'. See: https://github.com/urllib3/urllib3/issues/3020
  warnings.warn(
[2024-11-26 23:36:45 +0100] [24452] [INFO] Logging of backtrace is disabled in this loglevel
[2024-11-26 23:36:45 +0100] [24452] [DEBUG] Logging of backtrace is disabled by option in this loglevel
[2024-11-26 23:36:45 +0100] [24452] [INFO] Loaded default config
[2024-11-26 23:36:45 +0100] [24452] [INFO] Loaded arguments
[2024-11-26 23:36:45 +0100] [24452] [INFO] Starting Radicale
[2024-11-26 23:36:45 +0100] [24452] [INFO] auth type is 'radicale.auth.htpasswd'
[2024-11-26 23:36:45 +0100] [24452] [INFO] auth htpasswd encryption is 'radicale.auth.htpasswd_encryption.plain'
[2024-11-26 23:36:45 +0100] [24452] [INFO] storage type is 'etesync_dav.radicale.storage'
[2024-11-26 23:36:45 +0100] [24452] [INFO] rights type is 'etesync_dav.radicale.rights'
[2024-11-26 23:36:45 +0100] [24452] [INFO] web type is 'etesync_dav.radicale.web'
[2024-11-26 23:36:45 +0100] [24452] [INFO] hook type is 'radicale.hook.none'
[2024-11-26 23:36:45 +0100] [24452] [INFO] permit delete of collection: True
[2024-11-26 23:36:45 +0100] [24452] [INFO] permit overwrite of collection: True
[2024-11-26 23:36:45 +0100] [24452] [INFO] Listening on '[127.0.0.1]:37358'
[2024-11-26 23:36:45 +0100] [24452] [INFO] Listening on '[::1]:37358'
[2024-11-26 23:36:45 +0100] [24452] [INFO] Radicale server ready
[2024-11-26 23:36:45 +0100] [24452/Thread-2] [INFO] GET request for '/' received from 127.0.0.1 using 'Mozilla/5.0 (Windows NT 10.0; rv:131.0) Gecko/20100101 Firefox/131.0'
[2024-11-26 23:36:45 +0100] [24452/Thread-2] [DEBUG] Request header: suppressed by config/option [logging] request_header_on_debug
[2024-11-26 23:36:45 +0100] [24452/Thread-2] [DEBUG] Base prefix (from SCRIPT_NAME): ''
[2024-11-26 23:36:45 +0100] [24452/Thread-2] [DEBUG] Sanitized path: '/'
[2024-11-26 23:36:45 +0100] [24452/Thread-2] [DEBUG] Response content: suppressed by config/option [logging] response_content_on_debug
[2024-11-26 23:36:45 +0100] [24452/Thread-2] [INFO] GET response status for '/' in 0.000 seconds: 302 Found
[2024-11-26 23:36:45 +0100] [24452/Thread-3] [INFO] GET request for '/.web' received from 127.0.0.1 using 'Mozilla/5.0 (Windows NT 10.0; rv:131.0) Gecko/20100101 Firefox/131.0'
[2024-11-26 23:36:45 +0100] [24452/Thread-3] [DEBUG] Request header: suppressed by config/option [logging] request_header_on_debug
[2024-11-26 23:36:45 +0100] [24452/Thread-3] [DEBUG] Base prefix (from SCRIPT_NAME): ''
[2024-11-26 23:36:45 +0100] [24452/Thread-3] [DEBUG] Sanitized path: '/.web'
[2024-11-26 23:36:45 +0100] [24452/Thread-3] [INFO] GET response status for '/.web' in 0.066 seconds: 308 Permanent Redirect
[2024-11-26 23:36:45 +0100] [24452/Thread-4] [INFO] GET request for '/.web/' received from 127.0.0.1 using 'Mozilla/5.0 (Windows NT 10.0; rv:131.0) Gecko/20100101 Firefox/131.0'
[2024-11-26 23:36:45 +0100] [24452/Thread-4] [DEBUG] Request header: suppressed by config/option [logging] request_header_on_debug
[2024-11-26 23:36:45 +0100] [24452/Thread-4] [DEBUG] Base prefix (from SCRIPT_NAME): ''
[2024-11-26 23:36:45 +0100] [24452/Thread-4] [DEBUG] Sanitized path: '/.web/'
[2024-11-26 23:36:45 +0100] [24452/Thread-4] [INFO] GET response status for '/.web/' in 0.001 seconds: 302 Found
[2024-11-26 23:36:45 +0100] [24452/Thread-5] [INFO] GET request for '/.web/add/' received from 127.0.0.1 using 'Mozilla/5.0 (Windows NT 10.0; rv:131.0) Gecko/20100101 Firefox/131.0'
[2024-11-26 23:36:45 +0100] [24452/Thread-5] [DEBUG] Request header: suppressed by config/option [logging] request_header_on_debug
[2024-11-26 23:36:45 +0100] [24452/Thread-5] [DEBUG] Base prefix (from SCRIPT_NAME): ''
[2024-11-26 23:36:45 +0100] [24452/Thread-5] [DEBUG] Sanitized path: '/.web/add/'
[2024-11-26 23:36:45 +0100] [24452/Thread-5] [INFO] GET response status for '/.web/add/' in 0.008 seconds: 200 OK
[2024-11-26 23:36:54 +0100] [24452/Thread-6] [INFO] POST request for '/.web/add/' received from 127.0.0.1 using 'Mozilla/5.0 (Windows NT 10.0; rv:131.0) Gecko/20100101 Firefox/131.0'
[2024-11-26 23:36:54 +0100] [24452/Thread-6] [DEBUG] Request header: suppressed by config/option [logging] request_header_on_debug
[2024-11-26 23:36:54 +0100] [24452/Thread-6] [DEBUG] Base prefix (from SCRIPT_NAME): ''
[2024-11-26 23:36:54 +0100] [24452/Thread-6] [DEBUG] Sanitized path: '/.web/add/'
Logging in
Saving config
Initializing account
[2024-11-26 23:36:55 +0100] [24452/Thread-6] [INFO] POST response status for '/.web/add/' in 1.199 seconds: 302 Found
[2024-11-26 23:36:55 +0100] [24452/Thread-7] [INFO] GET request for '/.web/' received from 127.0.0.1 using 'Mozilla/5.0 (Windows NT 10.0; rv:131.0) Gecko/20100101 Firefox/131.0'
[2024-11-26 23:36:55 +0100] [24452/Thread-7] [DEBUG] Request header: suppressed by config/option [logging] request_header_on_debug
[2024-11-26 23:36:55 +0100] [24452/Thread-7] [DEBUG] Base prefix (from SCRIPT_NAME): ''
[2024-11-26 23:36:55 +0100] [24452/Thread-7] [DEBUG] Sanitized path: '/.web/'
[2024-11-26 23:36:55 +0100] [24452/Thread-7] [INFO] GET response status for '/.web/' in 0.002 seconds: 302 Found
[2024-11-26 23:36:55 +0100] [24452/Thread-8] [INFO] GET request for '/.web/login/' received from 127.0.0.1 using 'Mozilla/5.0 (Windows NT 10.0; rv:131.0) Gecko/20100101 Firefox/131.0'
[2024-11-26 23:36:55 +0100] [24452/Thread-8] [DEBUG] Request header: suppressed by config/option [logging] request_header_on_debug
[2024-11-26 23:36:55 +0100] [24452/Thread-8] [DEBUG] Base prefix (from SCRIPT_NAME): ''
[2024-11-26 23:36:55 +0100] [24452/Thread-8] [DEBUG] Sanitized path: '/.web/login/'
[2024-11-26 23:36:55 +0100] [24452/Thread-8] [INFO] GET response status for '/.web/login/' in 0.009 seconds: 200 OK
[2024-11-26 23:37:00 +0100] [24452/Thread-9] [INFO] POST request for '/.web/login/' received from 127.0.0.1 using 'Mozilla/5.0 (Windows NT 10.0; rv:131.0) Gecko/20100101 Firefox/131.0'
[2024-11-26 23:37:00 +0100] [24452/Thread-9] [DEBUG] Request header: suppressed by config/option [logging] request_header_on_debug
[2024-11-26 23:37:00 +0100] [24452/Thread-9] [DEBUG] Base prefix (from SCRIPT_NAME): ''
[2024-11-26 23:37:00 +0100] [24452/Thread-9] [DEBUG] Sanitized path: '/.web/login/'
[2024-11-26 23:37:01 +0100] [24452/Thread-9] [INFO] POST response status for '/.web/login/' in 1.042 seconds: 302 Found
[2024-11-26 23:37:01 +0100] [24452/Thread-10] [INFO] GET request for '/.web/' received from 127.0.0.1 using 'Mozilla/5.0 (Windows NT 10.0; rv:131.0) Gecko/20100101 Firefox/131.0'
[2024-11-26 23:37:01 +0100] [24452/Thread-10] [DEBUG] Request header: suppressed by config/option [logging] request_header_on_debug
[2024-11-26 23:37:01 +0100] [24452/Thread-10] [DEBUG] Base prefix (from SCRIPT_NAME): ''
[2024-11-26 23:37:01 +0100] [24452/Thread-10] [DEBUG] Sanitized path: '/.web/'
[2024-11-26 23:37:01 +0100] [24452/Thread-10] [INFO] GET response status for '/.web/' in 0.008 seconds: 200 OK
[2024-11-26 23:37:27 +0100] [24452/Thread-11] [INFO] HEAD request for '/odinkroeger/' received from 127.0.0.1 using 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:128.0) Gecko/20100101 Thunderbird/128.3.3'
[2024-11-26 23:37:27 +0100] [24452/Thread-11] [DEBUG] Request header: suppressed by config/option [logging] request_header_on_debug
[2024-11-26 23:37:27 +0100] [24452/Thread-11] [DEBUG] Base prefix (from SCRIPT_NAME): ''
[2024-11-26 23:37:27 +0100] [24452/Thread-11] [DEBUG] Sanitized path: '/odinkroeger/'
[2024-11-26 23:37:27 +0100] [24452/Thread-11] [INFO] Access to '/odinkroeger/' denied for anonymous user
[2024-11-26 23:37:27 +0100] [24452/Thread-11] [DEBUG] Asking client for authentication
[2024-11-26 23:37:27 +0100] [24452/Thread-11] [DEBUG] Response content: suppressed by config/option [logging] response_content_on_debug
[2024-11-26 23:37:27 +0100] [24452/Thread-11] [INFO] HEAD response status for '/odinkroeger/' in 0.001 seconds: 401 Unauthorized
[2024-11-26 23:37:27 +0100] [24452/Thread-12] [INFO] GET request for '/odinkroeger/' received from 127.0.0.1 using 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:128.0) Gecko/20100101 Thunderbird/128.3.3'
[2024-11-26 23:37:27 +0100] [24452/Thread-12] [DEBUG] Request header: suppressed by config/option [logging] request_header_on_debug
[2024-11-26 23:37:27 +0100] [24452/Thread-12] [DEBUG] Base prefix (from SCRIPT_NAME): ''
[2024-11-26 23:37:27 +0100] [24452/Thread-12] [DEBUG] Sanitized path: '/odinkroeger/'
[2024-11-26 23:37:27 +0100] [24452/Thread-12] [INFO] Access to '/odinkroeger/' denied for anonymous user
[2024-11-26 23:37:27 +0100] [24452/Thread-12] [DEBUG] Asking client for authentication
[2024-11-26 23:37:27 +0100] [24452/Thread-12] [DEBUG] Response content: suppressed by config/option [logging] response_content_on_debug
[2024-11-26 23:37:27 +0100] [24452/Thread-12] [INFO] GET response status for '/odinkroeger/' in 0.001 seconds: 401 Unauthorized
[2024-11-26 23:37:27 +0100] [24452/Thread-13] [INFO] PROPFIND request for '/odinkroeger/' with depth '0' received from 127.0.0.1 using 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:128.0) Gecko/20100101 Thunderbird/128.3.3'
[2024-11-26 23:37:27 +0100] [24452/Thread-13] [DEBUG] Request header: suppressed by config/option [logging] request_header_on_debug
[2024-11-26 23:37:27 +0100] [24452/Thread-13] [DEBUG] Base prefix (from SCRIPT_NAME): ''
[2024-11-26 23:37:27 +0100] [24452/Thread-13] [DEBUG] Sanitized path: '/odinkroeger/'
[2024-11-26 23:37:27 +0100] [24452/Thread-13] [INFO] Access to '/odinkroeger/' denied for anonymous user
[2024-11-26 23:37:27 +0100] [24452/Thread-13] [DEBUG] Asking client for authentication
[2024-11-26 23:37:27 +0100] [24452/Thread-13] [DEBUG] Response content: suppressed by config/option [logging] response_content_on_debug
[2024-11-26 23:37:27 +0100] [24452/Thread-13] [INFO] PROPFIND response status for '/odinkroeger/' with depth '0' in 0.001 seconds: 401 Unauthorized
[2024-11-26 23:37:27 +0100] [24452/Thread-14] [INFO] PROPFIND request for '/odinkroeger/' with depth '0' received from 127.0.0.1 using 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:128.0) Gecko/20100101 Thunderbird/128.3.3'
[2024-11-26 23:37:27 +0100] [24452/Thread-14] [DEBUG] Request header: suppressed by config/option [logging] request_header_on_debug
[2024-11-26 23:37:27 +0100] [24452/Thread-14] [DEBUG] Base prefix (from SCRIPT_NAME): ''
[2024-11-26 23:37:27 +0100] [24452/Thread-14] [DEBUG] Sanitized path: '/odinkroeger/'
[2024-11-26 23:37:27 +0100] [24452/Thread-14] [INFO] Access to '/odinkroeger/' denied for anonymous user
[2024-11-26 23:37:27 +0100] [24452/Thread-14] [DEBUG] Asking client for authentication
[2024-11-26 23:37:27 +0100] [24452/Thread-14] [DEBUG] Response content: suppressed by config/option [logging] response_content_on_debug
[2024-11-26 23:37:27 +0100] [24452/Thread-14] [INFO] PROPFIND response status for '/odinkroeger/' with depth '0' in 0.001 seconds: 401 Unauthorized
[2024-11-26 23:37:33 +0100] [24452/Thread-15] [INFO] HEAD request for '/odinkroeger/' received from 127.0.0.1 using 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:128.0) Gecko/20100101 Thunderbird/128.3.3'
[2024-11-26 23:37:33 +0100] [24452/Thread-15] [DEBUG] Request header: suppressed by config/option [logging] request_header_on_debug
[2024-11-26 23:37:33 +0100] [24452/Thread-15] [DEBUG] Base prefix (from SCRIPT_NAME): ''
[2024-11-26 23:37:33 +0100] [24452/Thread-15] [DEBUG] Sanitized path: '/odinkroeger/'
[2024-11-26 23:37:33 +0100] [24452/Thread-15] [INFO] Access to '/odinkroeger/' denied for anonymous user
[2024-11-26 23:37:33 +0100] [24452/Thread-15] [DEBUG] Asking client for authentication
[2024-11-26 23:37:33 +0100] [24452/Thread-15] [DEBUG] Response content: suppressed by config/option [logging] response_content_on_debug
[2024-11-26 23:37:33 +0100] [24452/Thread-15] [INFO] HEAD response status for '/odinkroeger/' in 0.001 seconds: 401 Unauthorized
[2024-11-26 23:37:33 +0100] [24452/Thread-16] [INFO] HEAD request for '/odinkroeger/' received from 127.0.0.1 using 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:128.0) Gecko/20100101 Thunderbird/128.3.3'
[2024-11-26 23:37:33 +0100] [24452/Thread-16] [DEBUG] Request header: suppressed by config/option [logging] request_header_on_debug
[2024-11-26 23:37:33 +0100] [24452/Thread-16] [DEBUG] Base prefix (from SCRIPT_NAME): ''
[2024-11-26 23:37:33 +0100] [24452/Thread-16] [DEBUG] Sanitized path: '/odinkroeger/'
[2024-11-26 23:37:33 +0100] [24452/Thread-16] [INFO] Successful login: 'odinkroeger'
[2024-11-26 23:37:34 +0100] [24452/Thread-18] [INFO] PROPFIND request for '/odinkroeger/' with depth '0' received from 127.0.0.1 using 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:128.0) Gecko/20100101 Thunderbird/128.3.3'
[2024-11-26 23:37:34 +0100] [24452/Thread-18] [DEBUG] Request header: suppressed by config/option [logging] request_header_on_debug
[2024-11-26 23:37:34 +0100] [24452/Thread-18] [DEBUG] Base prefix (from SCRIPT_NAME): ''
[2024-11-26 23:37:34 +0100] [24452/Thread-18] [DEBUG] Sanitized path: '/odinkroeger/'
[2024-11-26 23:37:34 +0100] [24452/Thread-18] [INFO] Access to '/odinkroeger/' denied for anonymous user
[2024-11-26 23:37:34 +0100] [24452/Thread-18] [DEBUG] Asking client for authentication
[2024-11-26 23:37:34 +0100] [24452/Thread-18] [DEBUG] Response content: suppressed by config/option [logging] response_content_on_debug
[2024-11-26 23:37:34 +0100] [24452/Thread-18] [INFO] PROPFIND response status for '/odinkroeger/' with depth '0' in 0.003 seconds: 401 Unauthorized
[2024-11-26 23:37:34 +0100] [24452/Thread-19] [INFO] PROPFIND request for '/odinkroeger/' with depth '0' received from 127.0.0.1 using 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:128.0) Gecko/20100101 Thunderbird/128.3.3'
[2024-11-26 23:37:34 +0100] [24452/Thread-19] [DEBUG] Request header: suppressed by config/option [logging] request_header_on_debug
[2024-11-26 23:37:34 +0100] [24452/Thread-19] [DEBUG] Base prefix (from SCRIPT_NAME): ''
[2024-11-26 23:37:34 +0100] [24452/Thread-19] [DEBUG] Sanitized path: '/odinkroeger/'
[2024-11-26 23:37:34 +0100] [24452/Thread-19] [INFO] Successful login: 'odinkroeger'
[2024-11-26 23:37:38 +0100] [24452/Thread-16] [DEBUG] Response content: suppressed by config/option [logging] response_content_on_debug
[2024-11-26 23:37:38 +0100] [24452/Thread-16] [INFO] HEAD response status for '/odinkroeger/' in 5.575 seconds: 403 Forbidden
[2024-11-26 23:37:38 +0100] [24452/Thread-20] [INFO] GET request for '/odinkroeger/' received from 127.0.0.1 using 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:128.0) Gecko/20100101 Thunderbird/128.3.3'
[2024-11-26 23:37:38 +0100] [24452/Thread-20] [DEBUG] Request header: suppressed by config/option [logging] request_header_on_debug
[2024-11-26 23:37:38 +0100] [24452/Thread-20] [DEBUG] Base prefix (from SCRIPT_NAME): ''
[2024-11-26 23:37:38 +0100] [24452/Thread-20] [DEBUG] Sanitized path: '/odinkroeger/'
[2024-11-26 23:37:38 +0100] [24452/Thread-20] [INFO] Access to '/odinkroeger/' denied for anonymous user
[2024-11-26 23:37:38 +0100] [24452/Thread-20] [DEBUG] Asking client for authentication
[2024-11-26 23:37:38 +0100] [24452/Thread-20] [DEBUG] Response content: suppressed by config/option [logging] response_content_on_debug
[2024-11-26 23:37:38 +0100] [24452/Thread-20] [INFO] GET response status for '/odinkroeger/' in 0.002 seconds: 401 Unauthorized
[2024-11-26 23:37:38 +0100] [24452/Thread-21] [INFO] GET request for '/odinkroeger/' received from 127.0.0.1 using 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:128.0) Gecko/20100101 Thunderbird/128.3.3'
[2024-11-26 23:37:38 +0100] [24452/Thread-21] [DEBUG] Request header: suppressed by config/option [logging] request_header_on_debug
[2024-11-26 23:37:38 +0100] [24452/Thread-21] [DEBUG] Base prefix (from SCRIPT_NAME): ''
[2024-11-26 23:37:38 +0100] [24452/Thread-21] [DEBUG] Sanitized path: '/odinkroeger/'
[2024-11-26 23:37:38 +0100] [24452/Thread-21] [INFO] Successful login: 'odinkroeger'
[2024-11-26 23:37:40 +0100] [24452/Thread-19] [DEBUG] Request content (XML): suppressed by config/option [logging] request_content_on_debug
[2024-11-26 23:37:45 +0100] [24452/Thread-19] [ERROR] An exception occurred during PROPFIND request on '/odinkroeger/': discover() takes from 2 to 3 positional arguments but 5 were given
[2024-11-26 23:37:45 +0100] [24452/Thread-22] [INFO] PROPFIND request for '/.well-known/caldav' with depth '0' received from 127.0.0.1 using 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:128.0) Gecko/20100101 Thunderbird/128.3.3'
[2024-11-26 23:37:45 +0100] [24452/Thread-22] [DEBUG] Request header: suppressed by config/option [logging] request_header_on_debug
[2024-11-26 23:37:45 +0100] [24452/Thread-22] [DEBUG] Base prefix (from SCRIPT_NAME): ''
[2024-11-26 23:37:45 +0100] [24452/Thread-22] [DEBUG] Sanitized path: '/.well-known/caldav'
[2024-11-26 23:37:45 +0100] [24452/Thread-22] [DEBUG] Response content: suppressed by config/option [logging] response_content_on_debug
[2024-11-26 23:37:45 +0100] [24452/Thread-22] [INFO] PROPFIND response status for '/.well-known/caldav' with depth '0' in 0.002 seconds: 301 Moved Permanently
[2024-11-26 23:37:45 +0100] [24452/Thread-23] [INFO] PROPFIND request for '/' with depth '0' received from 127.0.0.1 using 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:128.0) Gecko/20100101 Thunderbird/128.3.3'
[2024-11-26 23:37:45 +0100] [24452/Thread-23] [DEBUG] Request header: suppressed by config/option [logging] request_header_on_debug
[2024-11-26 23:37:45 +0100] [24452/Thread-23] [DEBUG] Base prefix (from SCRIPT_NAME): ''
[2024-11-26 23:37:45 +0100] [24452/Thread-23] [DEBUG] Sanitized path: '/'
[2024-11-26 23:37:45 +0100] [24452/Thread-23] [INFO] Access to '/' denied for anonymous user
[2024-11-26 23:37:45 +0100] [24452/Thread-23] [DEBUG] Asking client for authentication
[2024-11-26 23:37:45 +0100] [24452/Thread-23] [DEBUG] Response content: suppressed by config/option [logging] response_content_on_debug
[2024-11-26 23:37:45 +0100] [24452/Thread-23] [INFO] PROPFIND response status for '/' with depth '0' in 0.003 seconds: 401 Unauthorized
[2024-11-26 23:37:45 +0100] [24452/Thread-24] [INFO] PROPFIND request for '/' with depth '0' received from 127.0.0.1 using 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:128.0) Gecko/20100101 Thunderbird/128.3.3'
[2024-11-26 23:37:45 +0100] [24452/Thread-24] [DEBUG] Request header: suppressed by config/option [logging] request_header_on_debug
[2024-11-26 23:37:45 +0100] [24452/Thread-24] [DEBUG] Base prefix (from SCRIPT_NAME): ''
[2024-11-26 23:37:45 +0100] [24452/Thread-24] [DEBUG] Sanitized path: '/'
[2024-11-26 23:37:45 +0100] [24452/Thread-24] [INFO] Successful login: 'odinkroeger'
[2024-11-26 23:37:49 +0100] [24452/Thread-21] [DEBUG] Response content: suppressed by config/option [logging] response_content_on_debug
[2024-11-26 23:37:49 +0100] [24452/Thread-21] [INFO] GET response status for '/odinkroeger/' in 10.426 seconds: 403 Forbidden
[2024-11-26 23:37:49 +0100] [24452/Thread-25] [INFO] PROPFIND request for '/odinkroeger/' with depth '0' received from 127.0.0.1 using 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:128.0) Gecko/20100101 Thunderbird/128.3.3'
[2024-11-26 23:37:49 +0100] [24452/Thread-25] [DEBUG] Request header: suppressed by config/option [logging] request_header_on_debug
[2024-11-26 23:37:49 +0100] [24452/Thread-25] [DEBUG] Base prefix (from SCRIPT_NAME): ''
[2024-11-26 23:37:49 +0100] [24452/Thread-25] [DEBUG] Sanitized path: '/odinkroeger/'
[2024-11-26 23:37:49 +0100] [24452/Thread-25] [INFO] Access to '/odinkroeger/' denied for anonymous user
[2024-11-26 23:37:49 +0100] [24452/Thread-25] [DEBUG] Asking client for authentication
[2024-11-26 23:37:49 +0100] [24452/Thread-25] [DEBUG] Response content: suppressed by config/option [logging] response_content_on_debug
[2024-11-26 23:37:49 +0100] [24452/Thread-25] [INFO] PROPFIND response status for '/odinkroeger/' with depth '0' in 0.003 seconds: 401 Unauthorized
[2024-11-26 23:37:49 +0100] [24452/Thread-26] [INFO] PROPFIND request for '/odinkroeger/' with depth '0' received from 127.0.0.1 using 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:128.0) Gecko/20100101 Thunderbird/128.3.3'
[2024-11-26 23:37:49 +0100] [24452/Thread-26] [DEBUG] Request header: suppressed by config/option [logging] request_header_on_debug
[2024-11-26 23:37:49 +0100] [24452/Thread-26] [DEBUG] Base prefix (from SCRIPT_NAME): ''
[2024-11-26 23:37:49 +0100] [24452/Thread-26] [DEBUG] Sanitized path: '/odinkroeger/'
[2024-11-26 23:37:49 +0100] [24452/Thread-26] [INFO] Successful login: 'odinkroeger'
[2024-11-26 23:37:51 +0100] [24452/Thread-24] [DEBUG] Request content (XML): suppressed by config/option [logging] request_content_on_debug
[2024-11-26 23:37:54 +0100] [24452/Thread-26] [DEBUG] Request content (XML): suppressed by config/option [logging] request_content_on_debug
[2024-11-26 23:37:56 +0100] [24452/Thread-24] [ERROR] An exception occurred during PROPFIND request on '/': discover() takes from 2 to 3 positional arguments but 5 were given
[2024-11-26 23:37:56 +0100] [24452/Thread-27] [INFO] PROPFIND request for '/' with depth '0' received from 127.0.0.1 using 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:128.0) Gecko/20100101 Thunderbird/128.3.3'
[2024-11-26 23:37:56 +0100] [24452/Thread-27] [DEBUG] Request header: suppressed by config/option [logging] request_header_on_debug
[2024-11-26 23:37:56 +0100] [24452/Thread-27] [DEBUG] Base prefix (from SCRIPT_NAME): ''
[2024-11-26 23:37:56 +0100] [24452/Thread-27] [DEBUG] Sanitized path: '/'
[2024-11-26 23:37:56 +0100] [24452/Thread-27] [INFO] Access to '/' denied for anonymous user
[2024-11-26 23:37:56 +0100] [24452/Thread-27] [DEBUG] Asking client for authentication
[2024-11-26 23:37:56 +0100] [24452/Thread-27] [DEBUG] Response content: suppressed by config/option [logging] response_content_on_debug
[2024-11-26 23:37:56 +0100] [24452/Thread-27] [INFO] PROPFIND response status for '/' with depth '0' in 0.004 seconds: 401 Unauthorized
[2024-11-26 23:37:56 +0100] [24452/Thread-28] [INFO] PROPFIND request for '/' with depth '0' received from 127.0.0.1 using 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:128.0) Gecko/20100101 Thunderbird/128.3.3'
[2024-11-26 23:37:56 +0100] [24452/Thread-28] [DEBUG] Request header: suppressed by config/option [logging] request_header_on_debug
[2024-11-26 23:37:56 +0100] [24452/Thread-28] [DEBUG] Base prefix (from SCRIPT_NAME): ''
[2024-11-26 23:37:56 +0100] [24452/Thread-28] [DEBUG] Sanitized path: '/'
[2024-11-26 23:37:57 +0100] [24452/Thread-28] [INFO] Successful login: 'odinkroeger'
[2024-11-26 23:37:59 +0100] [24452/Thread-26] [ERROR] An exception occurred during PROPFIND request on '/odinkroeger/': discover() takes from 2 to 3 positional arguments but 5 were given
[2024-11-26 23:37:59 +0100] [24452/Thread-29] [INFO] PUT request for '/odinkroeger/' received from 127.0.0.1 using 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:128.0) Gecko/20100101 Thunderbird/128.3.3'
[2024-11-26 23:37:59 +0100] [24452/Thread-29] [DEBUG] Request header: suppressed by config/option [logging] request_header_on_debug
[2024-11-26 23:37:59 +0100] [24452/Thread-29] [DEBUG] Base prefix (from SCRIPT_NAME): ''
[2024-11-26 23:37:59 +0100] [24452/Thread-29] [DEBUG] Sanitized path: '/odinkroeger/'
[2024-11-26 23:37:59 +0100] [24452/Thread-29] [INFO] Access to '/odinkroeger/' denied for anonymous user
[2024-11-26 23:37:59 +0100] [24452/Thread-29] [DEBUG] Asking client for authentication
[2024-11-26 23:37:59 +0100] [24452/Thread-29] [DEBUG] Response content: suppressed by config/option [logging] response_content_on_debug
[2024-11-26 23:37:59 +0100] [24452/Thread-29] [INFO] PUT response status for '/odinkroeger/' in 0.004 seconds: 401 Unauthorized
[2024-11-26 23:37:59 +0100] [24452/Thread-30] [INFO] PUT request for '/odinkroeger/' received from 127.0.0.1 using 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:128.0) Gecko/20100101 Thunderbird/128.3.3'
[2024-11-26 23:37:59 +0100] [24452/Thread-30] [DEBUG] Request header: suppressed by config/option [logging] request_header_on_debug
[2024-11-26 23:37:59 +0100] [24452/Thread-30] [DEBUG] Base prefix (from SCRIPT_NAME): ''
[2024-11-26 23:37:59 +0100] [24452/Thread-30] [DEBUG] Sanitized path: '/odinkroeger/'
[2024-11-26 23:38:00 +0100] [24452/Thread-30] [INFO] Successful login: 'odinkroeger'
[2024-11-26 23:38:03 +0100] [24452/Thread-28] [DEBUG] Request content (XML): suppressed by config/option [logging] request_content_on_debug
[2024-11-26 23:38:06 +0100] [24452/Thread-30] [DEBUG] Request content: suppressed by config/option [logging] request_content_on_debug
[2024-11-26 23:38:08 +0100] [24452/Thread-28] [ERROR] An exception occurred during PROPFIND request on '/': discover() takes from 2 to 3 positional arguments but 5 were given
[2024-11-26 23:38:11 +0100] [24452/Thread-30] [WARNING] Not a collection (check .Radicale.props): ''
[2024-11-26 23:38:11 +0100] [24452/Thread-30] [INFO] Access to '/odinkroeger/' denied for 'odinkroeger'
[2024-11-26 23:38:11 +0100] [24452/Thread-30] [DEBUG] Response content: suppressed by config/option [logging] response_content_on_debug
[2024-11-26 23:38:11 +0100] [24452/Thread-30] [INFO] PUT response status for '/odinkroeger/' in 11.823 seconds: 403 Forbidden

The lines

[2024-11-26 23:37:45 +0100] [24452/Thread-19] [ERROR] An exception occurred during PROPFIND request on '/odinkroeger/': discover() takes from 2 to 3 positional arguments but 5 were given
[...]
[2024-11-26 23:38:11 +0100] [24452/Thread-30] [INFO] Access to '/odinkroeger/' denied for 'odinkroeger'

seem particularly interesting.

@odkr
Copy link
Author

odkr commented Nov 27, 2024

I tried to setup etesync-dav under Debian on an AMD64 VM, just to make sure that there's nothing wrong with my account and that ARM64, macOS, or the new version of etebase respectively are actually what causes the issue. But I can't get that to work either. When I try to install etesync-dav with pip install etesync-dav I get the same build error that I got when I tried to do that on my MacBook (i.e., the one from #328). When I use the version of etebase from GitHub, I can build etesync-dav. But it raises SIGILL immediately after launch, regardless of whether I give any options (or which). I fired up pdb and the illegal instruction occurs when the Manager class is imported from etesync_dav.manage. I don't know the codebase and it's late, so I'll leave it at that for now. Any ideas?

@odkr
Copy link
Author

odkr commented Nov 29, 2024

Okay, the offending instruction is apparently part of the cryptography module:

$ python -mpdb etesync-dav
> /home/odin/etesync-test/bin/etesync-dav(3)<module>()
-> import os
(Pdb) b /home/odin/etesync-test/lib/python3.11/site-packages/etesync/crypto.py:15
Breakpoint 1 at /home/odin/etesync-test/lib/python3.11/site-packages/etesync/crypto.py:15
(Pdb) c
> /home/odin/etesync-test/lib/python3.11/site-packages/etesync/crypto.py(15)<module>()
-> from cryptography.hazmat.backends import default_backend
(Pdb) n
> /home/odin/etesync-test/lib/python3.11/site-packages/etesync/crypto.py(16)<module>()
-> from cryptography.hazmat.primitives import serialization, hashes, padding
(Pdb) 
Illegal instruction

I'd open an issue in the respective repository, but I can't find the repository that hosts the etesync package. At any rate, this should probably be filed with pyca.

Maybe I can find some old AMD64 laptop on which I can test things instead.

@odkr
Copy link
Author

odkr commented Nov 29, 2024

Filed at pyca/cryptography#12069

@odkr
Copy link
Author

odkr commented Nov 30, 2024

The radicale downgrade fixed that issue! That leaves the build error and the missing scrypt module. Should I open a separate issue for the build error? Or can you just transfer etesync/etesync-dav#328 here? Upgrading etebase on PyPI will probably fix the build error. I'll file a PR in the etesync-dav repo for the missing scrypt module. Thanks!

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

1 participant