Benachrichtigung über neue Mails werden bei manchen Konten nicht signalisiert

chrizzlibaer · 2023-09-17T19:59:25+00:00

Hallo zusammen, ich habe bei einem relativ neuen community grommunio Server (aktuell gepatcht) folgendes Problem: Manche Konten erhalten Mail-Benachrichtigu...

WalterH

systemctl restart php-fpm.service

Die Logs kann man mit:
tail /var/log/grommunio/*.log -f
und
journalctl -f
ansehen.
Platz ist ausreichend vorhanden?
df -h

chrizzlibaer

Df -h:

Filesystem      Size  Used Avail Use% Mounted on
devtmpfs        4.0M     0  4.0M   0% /dev
tmpfs           2.0G   44K  2.0G   1% /dev/shm
tmpfs           781M  724K  781M   1% /run
tmpfs           4.0M     0  4.0M   0% /sys/fs/cgroup
/dev/sda3       200G  7.8G  191G   4% /
/dev/sda3       200G  7.8G  191G   4% /.snapshots
/dev/sda3       200G  7.8G  191G   4% /srv
/dev/sda3       200G  7.8G  191G   4% /usr/local
/dev/sda3       200G  7.8G  191G   4% /home
/dev/sda3       200G  7.8G  191G   4% /var/crash
/dev/sda3       200G  7.8G  191G   4% /boot/grub2/x86_64-efi
/dev/sda3       200G  7.8G  191G   4% /var/lib/grommunio-archive
/dev/sda3       200G  7.8G  191G   4% /boot/grub2/i386-pc
/dev/sda3       200G  7.8G  191G   4% /tmp
/dev/sda3       200G  7.8G  191G   4% /var/cache
/dev/sda3       200G  7.8G  191G   4% /opt
/dev/sda3       200G  7.8G  191G   4% /var/lib/grommunio-chat
/dev/sda3       200G  7.8G  191G   4% /var/lib/grommunio-files
/dev/sda3       200G  7.8G  191G   4% /var/opt
/dev/sda3       200G  7.8G  191G   4% /var/lib/mariadb
/dev/sda3       200G  7.8G  191G   4% /var/lib/mailman
/dev/sda3       200G  7.8G  191G   4% /var/lib/named
/dev/sda3       200G  7.8G  191G   4% /var/lib/machines
/dev/sda3       200G  7.8G  191G   4% /var/tmp
/dev/sda3       200G  7.8G  191G   4% /var/lib/gromox
/dev/sda3       200G  7.8G  191G   4% /var/lib/mysql
/dev/sda3       200G  7.8G  191G   4% /var/spool
/dev/sda3       200G  7.8G  191G   4% /var/log
/dev/sda2        20M  3.0M   17M  15% /boot/efi
tmpfs           391M     0  391M   0% /run/user/0

Logs folgen

chrizzlibaer

PHP-FPM sieht soweit normal aus, schätze ich?

mailbox:~ # systemctl restart php-fpm.service
mailbox:~ # systemctl status php-fpm.service
● php-fpm.service - The PHP FastCGI Process Manager
     Loaded: loaded (/usr/lib/systemd/system/php-fpm.service; enabled; vendor preset: disabled)
     Active: active (running) since Mon 2023-09-18 16:06:11 CEST; 8s ago
   Main PID: 5965 (php-fpm)
     Status: "Ready to handle connections"
      Tasks: 19 (limit: 4652)
     CGroup: /system.slice/php-fpm.service
             ├─ 5965 "php-fpm: master process (/etc/php8/fpm/php-fpm.conf)" "" "" "" "" "" "" "" "" ">
             ├─ 5966 "php-fpm: pool gromox" "" "" "" "" "" "" "" "" "" "" "" "" "" "" "" "" "" "" "" >
             ├─ 5967 "php-fpm: pool grommunio-dav-pool" "" "" "" "" "" "" "" "" "" "" "" "" "" "" "" >
             ├─ 5968 "php-fpm: pool grommunio-dav-pool" "" "" "" "" "" "" "" "" "" "" "" "" "" "" "" >
             ├─ 5969 "php-fpm: pool grommunio-dav-pool" "" "" "" "" "" "" "" "" "" "" "" "" "" "" "" >
             ├─ 5970 "php-fpm: pool grommunio-dav-pool" "" "" "" "" "" "" "" "" "" "" "" "" "" "" "" >
             ├─ 5971 "php-fpm: pool grommunio-dav-pool" "" "" "" "" "" "" "" "" "" "" "" "" "" "" "" >
             ├─ 5972 "php-fpm: pool grommunio-dav-pool" "" "" "" "" "" "" "" "" "" "" "" "" "" "" "" >
             ├─ 5973 "php-fpm: pool grommunio-dav-pool" "" "" "" "" "" "" "" "" "" "" "" "" "" "" "" >
             ├─ 5974 "php-fpm: pool grommunio-dav-pool" "" "" "" "" "" "" "" "" "" "" "" "" "" "" "" >
             ├─ 5975 "php-fpm: pool grommunio-sync-pool" "" "" "" "" "" "" "" "" "" "" "" "" "" "" "">
             ├─ 5976 "php-fpm: pool grommunio-sync-pool" "" "" "" "" "" "" "" "" "" "" "" "" "" "" "">
             ├─ 5977 "php-fpm: pool grommunio-sync-pool" "" "" "" "" "" "" "" "" "" "" "" "" "" "" "">
             ├─ 5978 "php-fpm: pool grommunio-sync-pool" "" "" "" "" "" "" "" "" "" "" "" "" "" "" "">
             ├─ 5979 "php-fpm: pool grommunio-sync-pool" "" "" "" "" "" "" "" "" "" "" "" "" "" "" "">
             ├─ 5980 "php-fpm: pool grommunio-sync-pool" "" "" "" "" "" "" "" "" "" "" "" "" "" "" "">
             ├─ 5981 "php-fpm: pool grommunio-sync-pool" "" "" "" "" "" "" "" "" "" "" "" "" "" "" "">
             ├─ 5982 "php-fpm: pool grommunio-sync-pool" "" "" "" "" "" "" "" "" "" "" "" "" "" "" "">
             └─ 5983 "php-fpm: pool grommunio-web-pool" "" "" "" "" "" "" "" "" "" "" "" "" "" "" "" >

Sep 18 16:06:11 mailbox systemd[1]: Starting The PHP FastCGI Process Manager...
Sep 18 16:06:11 mailbox systemd[1]: Started The PHP FastCGI Process Manager.

Hier ein Auszug vom Log, von dem ich glaube, dass es vielleicht aussagekräftig sein könnte:

mailbox:~ # tail /var/log/grommunio/*.log -f
==> /var/log/grommunio/nginx-access.log <==
192.168.42.2 - - [17/Sep/2023:18:32:56 +0200] "GET /wp-login.php HTTP/1.1" 404 11069 "http://autodiscover.st.team/wp-login.php" "Mozilla/5.0 (X11; Fedora; Linux x86_64; rv:94.0) Gecko/20100101 Firefox/95.0"
192.168.42.2 - ra@st.team [18/Sep/2023:01:00:59 +0200] "POST /Microsoft-Server-ActiveSync?Cmd=Ping&User=ra%40st.team&DeviceId=androidc604232438&DeviceType=Android HTTP/1.1" 500 10786 "-" "Android-Mail/2023.08.20.561750975.Release"
192.168.42.2 - ka@st.team [18/Sep/2023:01:00:59 +0200] "POST /Microsoft-Server-ActiveSync?oRIJBBBOaW5lNDYwMENENzYwMTA0BCu3YRMHQW5kcm9pZAcBAA== HTTP/1.1" 500 10786 "-" "Nine-a52xqnaeea/TP1A.220624.014"
192.168.42.2 - th@st.team [18/Sep/2023:01:00:59 +0200] "POST /Microsoft-Server-ActiveSync?oRIJBBBOaW5lOEI1QjEwNUNCQUFGBIkkbRUHQW5kcm9pZAcBAA== HTTP/1.1" 500 10786 "-" "Nine-XQ-AS52_EEA/58.2.A.10.126"
192.168.42.2 - ch@st.team [18/Sep/2023:01:45:03 +0200] "POST /Microsoft-Server-ActiveSync?oRIJBBBOaW5lODQwOUYyMzgwMTc1BKhpiR0HQW5kcm9pZAcBAA== HTTP/1.1" 500 10786 "-" "Nine-dm2qxeea/TP1A.220624.014"
192.168.42.2 - - [18/Sep/2023:01:57:49 +0200] "GET /style.php?sig=rename HTTP/1.1" 404 11069 "-" "Mozilla/5.0 (Windows NT 10.0; WOW64; rv:45.0) Gecko/20100101 Firefox/45.0"
192.168.42.2 - ra@st.team [18/Sep/2023:07:08:57 +0200] "POST /Microsoft-Server-ActiveSync?Cmd=Ping&User=ra%40st.team&DeviceId=androidc604232438&DeviceType=Android HTTP/1.1" 502 10825 "-" "Android-Mail/2023.08.20.561750975.Release"
192.168.42.2 - th@st.team [18/Sep/2023:16:06:11 +0200] "POST /Microsoft-Server-ActiveSync?oRIJBBBOaW5lOEI1QjEwNUNCQUFGBIkkbRUHQW5kcm9pZAcBAA== HTTP/1.1" 502 10825 "-" "Nine-XQ-AS52_EEA/58.2.A.10.126"
192.168.42.2 - ra@st.team [18/Sep/2023:16:06:11 +0200] "POST /Microsoft-Server-ActiveSync?Cmd=Ping&User=ra%40st.team&DeviceId=androidc604232438&DeviceType=Android HTTP/1.1" 502 10825 "-" "Android-Mail/2023.08.20.561750975.Release"
192.168.42.2 - ch@st.team [18/Sep/2023:16:06:11 +0200] "POST /Microsoft-Server-ActiveSync?oRIJBBBOaW5lMkM0NjFFQzNDRDNFBBrcuQcHQW5kcm9pZAcBAA== HTTP/1.1" 502 10825 "-" "Nine-OnePlus5T/TQ3A.230805.001"

==> /var/log/grommunio/nginx-admin-access.log <==
192.168.42.150 - - [18/Sep/2023:16:12:39 +0200] "GET /api/v1/system/sync/top?filterEnded=20&filterUpdated=120 HTTP/1.1" 200 486 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/117.0"
192.168.42.150 - - [18/Sep/2023:16:12:41 +0200] "GET /api/v1/system/sync/top?filterEnded=20&filterUpdated=120 HTTP/1.1" 200 486 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/117.0"
192.168.42.150 - - [18/Sep/2023:16:12:43 +0200] "GET /api/v1/system/sync/top?filterEnded=20&filterUpdated=120 HTTP/1.1" 200 486 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/117.0"
192.168.42.150 - - [18/Sep/2023:16:12:45 +0200] "GET /api/v1/system/sync/top?filterEnded=20&filterUpdated=120 HTTP/1.1" 200 536 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/117.0"
192.168.42.150 - - [18/Sep/2023:16:12:47 +0200] "GET /api/v1/system/sync/top?filterEnded=20&filterUpdated=120 HTTP/1.1" 200 536 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/117.0"
192.168.42.150 - - [18/Sep/2023:16:12:49 +0200] "GET /api/v1/system/sync/top?filterEnded=20&filterUpdated=120 HTTP/1.1" 200 534 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/117.0"
192.168.42.150 - - [18/Sep/2023:16:12:51 +0200] "GET /api/v1/system/sync/top?filterEnded=20&filterUpdated=120 HTTP/1.1" 200 534 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/117.0"
192.168.42.150 - - [18/Sep/2023:16:12:53 +0200] "GET /api/v1/system/sync/top?filterEnded=20&filterUpdated=120 HTTP/1.1" 200 534 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/117.0"
192.168.42.150 - - [18/Sep/2023:16:12:55 +0200] "GET /api/v1/system/sync/top?filterEnded=20&filterUpdated=120 HTTP/1.1" 200 534 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/117.0"
192.168.42.150 - - [18/Sep/2023:16:12:57 +0200] "GET /api/v1/system/sync/top?filterEnded=20&filterUpdated=120 HTTP/1.1" 200 538 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/117.0"

==> /var/log/grommunio/nginx-admin-error.log <==
2023/08/25 11:42:54 [error] 1673#1673: *5810 recv() failed (104: Connection reset by peer) while reading response header from upstream, client: 192.168.42.111, server: _, request: "GET /api/v1/system/dashboard HTTP/1.1", upstream: "uwsgi://unix:///run/grommunio/admin-api.socket:", host: "192.168.42.3:8443"
2023/08/25 11:42:54 [error] 1673#1673: *6089 recv() failed (104: Connection reset by peer) while reading response header from upstream, client: 192.168.42.111, server: _, request: "GET /api/v1/system/logs/Postfix?after=2023-08-25%2011:40:28.391569&n=20 HTTP/1.1", upstream: "uwsgi://unix:///run/grommunio/admin-api.socket:", host: "192.168.42.3:8443"

==> /var/log/grommunio/nginx-autodiscover-access.log <==
192.168.42.2 - th@st.team [18/Sep/2023:16:12:09 +0200] "POST /autodiscover/autodiscover.xml HTTP/1.1" 200 284 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"
192.168.42.2 - th@st.team [18/Sep/2023:16:12:09 +0200] "POST /autodiscover/autodiscover.xml HTTP/1.1" 200 284 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"
192.168.42.2 - th@st.team [18/Sep/2023:16:12:09 +0200] "POST /autodiscover/autodiscover.xml HTTP/1.1" 200 284 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"
192.168.42.2 - th@st.team [18/Sep/2023:16:12:09 +0200] "POST /autodiscover/autodiscover.xml HTTP/1.1" 200 284 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"
192.168.42.2 - th@st.team [18/Sep/2023:16:12:52 +0200] "POST /autodiscover/autodiscover.xml HTTP/1.1" 200 284 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"
192.168.42.2 - th@st.team [18/Sep/2023:16:12:52 +0200] "POST /autodiscover/autodiscover.xml HTTP/1.1" 200 284 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"
192.168.42.2 - th@st.team [18/Sep/2023:16:12:52 +0200] "POST /autodiscover/autodiscover.xml HTTP/1.1" 200 284 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"
192.168.42.2 - th@st.team [18/Sep/2023:16:13:15 +0200] "POST /autodiscover/autodiscover.xml HTTP/1.1" 200 284 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"
192.168.42.2 - th@st.team [18/Sep/2023:16:13:15 +0200] "POST /autodiscover/autodiscover.xml HTTP/1.1" 200 284 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"
192.168.42.2 - th@st.team [18/Sep/2023:16:13:15 +0200] "POST /autodiscover/autodiscover.xml HTTP/1.1" 200 284 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"

==> /var/log/grommunio/nginx-autodiscover-error.log <==

==> /var/log/grommunio/nginx-dav-access.log <==

==> /var/log/grommunio/nginx-dav-error.log <==

==> /var/log/grommunio/nginx-error.log <==
2023/09/17 12:19:11 [error] 1507#1507: *344 open() "/usr//html/wp" failed (2: No such file or directory), client: 192.168.42.2, server: _, request: "HEAD /wp HTTP/1.1", host: "autodiscover.st.team", referrer: "http://autodiscover.st.team/wp"
2023/09/17 12:19:11 [error] 1507#1507: *345 open() "/usr//html/bc" failed (2: No such file or directory), client: 192.168.42.2, server: _, request: "HEAD /bc HTTP/1.1", host: "autodiscover.st.team", referrer: "http://autodiscover.st.team/bc"
2023/09/17 12:19:11 [error] 1507#1507: *346 open() "/usr//html/bk" failed (2: No such file or directory), client: 192.168.42.2, server: _, request: "HEAD /bk HTTP/1.1", host: "autodiscover.st.team", referrer: "http://autodiscover.st.team/bk"
2023/09/17 12:19:11 [error] 1507#1507: *347 open() "/usr//html/backup" failed (2: No such file or directory), client: 192.168.42.2, server: _, request: "HEAD /backup HTTP/1.1", host: "autodiscover.st.team", referrer: "http://autodiscover.st.team/backup"
2023/09/17 12:19:11 [error] 1507#1507: *348 open() "/usr//html/old" failed (2: No such file or directory), client: 192.168.42.2, server: _, request: "HEAD /old HTTP/1.1", host: "autodiscover.st.team", referrer: "http://autodiscover.st.team/old"
2023/09/17 12:19:11 [error] 1507#1507: *349 open() "/usr//html/new" failed (2: No such file or directory), client: 192.168.42.2, server: _, request: "HEAD /new HTTP/1.1", host: "autodiscover.st.team", referrer: "http://autodiscover.st.team/new"
2023/09/17 12:19:11 [error] 1507#1507: *350 open() "/usr//html/main" failed (2: No such file or directory), client: 192.168.42.2, server: _, request: "HEAD /main HTTP/1.1", host: "autodiscover.st.team", referrer: "http://autodiscover.st.team/main"
2023/09/17 12:19:11 [error] 1507#1507: *351 open() "/usr//html/home" failed (2: No such file or directory), client: 192.168.42.2, server: _, request: "HEAD /home HTTP/1.1", host: "autodiscover.st.team", referrer: "http://autodiscover.st.team/home"
2023/09/17 18:32:56 [error] 1507#1507: *13389 open() "/usr//html/wp-login.php" failed (2: No such file or directory), client: 192.168.42.2, server: _, request: "GET /wp-login.php HTTP/1.1", host: "autodiscover.st.team", referrer: "http://autodiscover.st.team/wp-login.php"
2023/09/18 01:57:49 [error] 1511#1511: *38 open() "/usr//html/style.php" failed (2: No such file or directory), client: 192.168.42.2, server: _, request: "GET /style.php?sig=rename HTTP/1.1", host: "autodiscover.st.team"

==> /var/log/grommunio/nginx-ews-access.log <==
192.168.42.2 - th@st.team [18/Sep/2023:11:52:13 +0200] "POST /EWS/Exchange.asmx HTTP/1.1" 200 901 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"
192.168.42.2 - th@st.team [18/Sep/2023:12:22:19 +0200] "POST /EWS/Exchange.asmx HTTP/1.1" 200 901 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"
192.168.42.2 - th@st.team [18/Sep/2023:12:52:19 +0200] "POST /EWS/Exchange.asmx HTTP/1.1" 200 901 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"
192.168.42.2 - th@st.team [18/Sep/2023:13:22:23 +0200] "POST /EWS/Exchange.asmx HTTP/1.1" 200 901 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"
192.168.42.2 - th@st.team [18/Sep/2023:13:52:37 +0200] "POST /EWS/Exchange.asmx HTTP/1.1" 200 901 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"
192.168.42.2 - th@st.team [18/Sep/2023:14:22:49 +0200] "POST /EWS/Exchange.asmx HTTP/1.1" 200 901 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"
192.168.42.2 - th@st.team [18/Sep/2023:14:51:01 +0200] "POST /EWS/Exchange.asmx HTTP/1.1" 500 327 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"
192.168.42.2 - th@st.team [18/Sep/2023:14:52:54 +0200] "POST /EWS/Exchange.asmx HTTP/1.1" 200 901 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"
192.168.42.2 - th@st.team [18/Sep/2023:15:23:04 +0200] "POST /EWS/Exchange.asmx HTTP/1.1" 200 901 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"
192.168.42.2 - th@st.team [18/Sep/2023:15:53:08 +0200] "POST /EWS/Exchange.asmx HTTP/1.1" 200 901 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"

==> /var/log/grommunio/nginx-ews-error.log <==

==> /var/log/grommunio/nginx-mapi-access.log <==
192.168.42.2 - th@st.team [18/Sep/2023:16:12:44 +0200] "POST /mapi/emsmdb/?MailboxId=74686f6d-6173-4073-7465-727a06000000@st.team HTTP/1.1" 200 121 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"
192.168.42.2 - th@st.team [18/Sep/2023:16:12:44 +0200] "POST /mapi/emsmdb/?MailboxId=74686f6d-6173-4073-7465-727a06000000@st.team HTTP/1.1" 200 283 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"
192.168.42.2 - - [18/Sep/2023:16:12:52 +0200] "POST /mapi/emsmdb/?MailboxId=746f6d40-7374-6572-7a69-6e6705000000@st.team HTTP/1.1" 401 0 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"
192.168.42.2 - th@st.team [18/Sep/2023:16:12:52 +0200] "POST /mapi/emsmdb/?MailboxId=746f6d40-7374-6572-7a69-6e6705000000@st.team HTTP/1.1" 200 156 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"
192.168.42.2 - - [18/Sep/2023:16:12:52 +0200] "POST /mapi/emsmdb/?MailboxId=746f6d40-7374-6572-7a69-6e6705000000@st.team HTTP/1.1" 401 0 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"
192.168.42.2 - th@st.team [18/Sep/2023:16:12:52 +0200] "POST /mapi/emsmdb/?MailboxId=746f6d40-7374-6572-7a69-6e6705000000@st.team HTTP/1.1" 200 156 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"
192.168.42.2 - - [18/Sep/2023:16:13:15 +0200] "POST /mapi/emsmdb/?MailboxId=746f6d40-7374-6572-7a69-6e6705000000@st.team HTTP/1.1" 401 0 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"
192.168.42.2 - th@st.team [18/Sep/2023:16:13:15 +0200] "POST /mapi/emsmdb/?MailboxId=746f6d40-7374-6572-7a69-6e6705000000@st.team HTTP/1.1" 200 156 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"
192.168.42.2 - - [18/Sep/2023:16:13:15 +0200] "POST /mapi/emsmdb/?MailboxId=746f6d40-7374-6572-7a69-6e6705000000@st.team HTTP/1.1" 401 0 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"
192.168.42.2 - th@st.team [18/Sep/2023:16:13:15 +0200] "POST /mapi/emsmdb/?MailboxId=746f6d40-7374-6572-7a69-6e6705000000@st.team HTTP/1.1" 200 156 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"

==> /var/log/grommunio/nginx-mapi-error.log <==
2023/08/19 23:18:45 [error] 1590#1590: *704831 upstream prematurely closed connection while reading upstream, client: 192.168.42.2, server: _, request: "POST /mapi/emsmdb/?MailboxId=6b61746a-6140-7374-6572-7a6902000000@st.team HTTP/1.1", upstream: "https://127.0.0.1:10443/mapi/emsmdb/?MailboxId=6b61746a-6140-7374-6572-7a6902000000@st.team", host: "mail.st.team"
2023/08/19 23:18:45 [error] 1590#1590: *704841 connect() failed (111: Connection refused) while connecting to upstream, client: 192.168.42.2, server: _, request: "POST /mapi/emsmdb/?MailboxId=6b61746a-6140-7374-6572-7a6902000000@st.team HTTP/1.1", upstream: "https://127.0.0.1:10443/mapi/emsmdb/?MailboxId=6b61746a-6140-7374-6572-7a6902000000@st.team", host: "mail.st.team"
2023/08/19 23:18:45 [error] 1590#1590: *704841 connect() failed (111: Connection refused) while connecting to upstream, client: 192.168.42.2, server: _, request: "POST /mapi/emsmdb/?MailboxId=6b61746a-6140-7374-6572-7a6902000000@st.team HTTP/1.1", upstream: "https://[::1]:10443/mapi/emsmdb/?MailboxId=6b61746a-6140-7374-6572-7a6902000000@st.team", host: "mail.st.team"
2023/08/19 23:18:46 [error] 1590#1590: *704844 no live upstreams while connecting to upstream, client: 192.168.42.2, server: _, request: "POST /mapi/emsmdb/?MailboxId=6b61746a-6140-7374-6572-7a6902000000@st.team HTTP/1.1", upstream: "https://gromoxhttp/mapi/emsmdb/?MailboxId=6b61746a-6140-7374-6572-7a6902000000@st.team", host: "mail.st.team"
2023/08/19 23:18:51 [error] 1590#1590: *704845 no live upstreams while connecting to upstream, client: 192.168.42.2, server: _, request: "POST /mapi/emsmdb/?MailboxId=6b61746a-6140-7374-6572-7a6902000000@st.team HTTP/1.1", upstream: "https://gromoxhttp/mapi/emsmdb/?MailboxId=6b61746a-6140-7374-6572-7a6902000000@st.team", host: "mail.st.team"
2023/08/19 23:20:18 [error] 7048#7048: *705162 upstream prematurely closed connection while reading upstream, client: 192.168.42.2, server: _, request: "POST /mapi/emsmdb/?MailboxId=7261696e-6572-4073-7465-727a03000000@st.team HTTP/1.1", upstream: "https://[::1]:10443/mapi/emsmdb/?MailboxId=7261696e-6572-4073-7465-727a03000000@st.team", host: "mail.st.team"

==> /var/log/grommunio/nginx-oab-access.log <==
192.168.42.2 - - [18/Sep/2023:14:53:03 +0200] "HEAD /OAB/oab.xml HTTP/1.1" 401 0 "-" "Microsoft BITS/7.8"
192.168.42.2 - - [18/Sep/2023:14:53:05 +0200] "HEAD /OAB/oab.xml HTTP/1.1" 401 0 "-" "Microsoft BITS/7.8"
192.168.42.2 - th@st.team [18/Sep/2023:14:53:05 +0200] "HEAD /OAB/oab.xml HTTP/1.1" 200 0 "-" "Microsoft BITS/7.8"
192.168.42.2 - - [18/Sep/2023:14:53:09 +0200] "GET /OAB/oab.xml HTTP/1.1" 401 0 "-" "Microsoft BITS/7.8"
192.168.42.2 - th@st.team [18/Sep/2023:14:53:09 +0200] "GET /OAB/oab.xml HTTP/1.1" 200 49 "-" "Microsoft BITS/7.8"
192.168.42.2 - - [18/Sep/2023:15:53:18 +0200] "HEAD /OAB/oab.xml HTTP/1.1" 401 0 "-" "Microsoft BITS/7.8"
192.168.42.2 - - [18/Sep/2023:15:53:20 +0200] "HEAD /OAB/oab.xml HTTP/1.1" 401 0 "-" "Microsoft BITS/7.8"
192.168.42.2 - th@st.team [18/Sep/2023:15:53:20 +0200] "HEAD /OAB/oab.xml HTTP/1.1" 200 0 "-" "Microsoft BITS/7.8"
192.168.42.2 - - [18/Sep/2023:15:53:23 +0200] "GET /OAB/oab.xml HTTP/1.1" 401 0 "-" "Microsoft BITS/7.8"
192.168.42.2 - th@st.team [18/Sep/2023:15:53:23 +0200] "GET /OAB/oab.xml HTTP/1.1" 200 49 "-" "Microsoft BITS/7.8"

==> /var/log/grommunio/nginx-oab-error.log <==

==> /var/log/grommunio/nginx-rpc-access.log <==

==> /var/log/grommunio/nginx-rpc-error.log <==

==> /var/log/grommunio/nginx-sync-access.log <==
192.168.42.2 - ch@st.team [18/Sep/2023:16:09:07 +0200] "POST /Microsoft-Server-ActiveSync?oRIJBBBOaW5lNTlDODIxQ0YwREExBJNQlx4HQW5kcm9pZAcBAA== HTTP/1.1" 499 0 "-" "Nine-r9qxeea/TP1A.220624.014"
192.168.42.2 - ch@st.team [18/Sep/2023:16:09:43 +0200] "POST /Microsoft-Server-ActiveSync?oRIJBBBOaW5lMkM0NjFFQzNDRDNFBBrcuQcHQW5kcm9pZAcBAA== HTTP/1.1" 499 0 "-" "Nine-OnePlus5T/TQ3A.230805.001"
192.168.42.2 - ch@st.team [18/Sep/2023:16:10:07 +0200] "POST /Microsoft-Server-ActiveSync?oRIJBBBOaW5lODQwOUYyMzgwMTc1BKhpiR0HQW5kcm9pZAcBAA== HTTP/1.1" 499 0 "-" "Nine-dm2qxeea/TP1A.220624.014"
192.168.42.2 - ra@st.team [18/Sep/2023:16:10:19 +0200] "POST /Microsoft-Server-ActiveSync?Cmd=Ping&User=ra%40st.team&DeviceId=androidc604232438&DeviceType=Android HTTP/1.1" 499 0 "-" "Android-Mail/2023.08.20.561750975.Release"
192.168.42.2 - ka@st.team [18/Sep/2023:16:10:28 +0200] "POST /Microsoft-Server-ActiveSync?oRIJBBBOaW5lNDYwMENENzYwMTA0BCu3YRMHQW5kcm9pZAcBAA== HTTP/1.1" 499 0 "-" "Nine-a52xqnaeea/TP1A.220624.014"
192.168.42.2 - ch@st.team [18/Sep/2023:16:11:13 +0200] "POST /Microsoft-Server-ActiveSync?oRIJBBBOaW5lODQwOUYyMzgwMTc1BKhpiR0HQW5kcm9pZAcBAA== HTTP/1.1" 499 0 "-" "Nine-dm2qxeea/TP1A.220624.014"
192.168.42.2 - ra@st.team [18/Sep/2023:16:11:57 +0200] "POST /Microsoft-Server-ActiveSync?Cmd=Ping&User=ra%40st.team&DeviceId=androidc604232438&DeviceType=Android HTTP/1.1" 200 13 "-" "Android-Mail/2023.08.20.561750975.Release"
192.168.42.2 - th@st.team [18/Sep/2023:16:12:16 +0200] "POST /Microsoft-Server-ActiveSync?oRIJBBBOaW5lOEI1QjEwNUNCQUFGBIkkbRUHQW5kcm9pZAcBAA== HTTP/1.1" 499 0 "-" "Nine-XQ-AS52_EEA/58.2.A.10.126"
192.168.42.2 - ra@st.team [18/Sep/2023:16:13:27 +0200] "POST /Microsoft-Server-ActiveSync?Cmd=Ping&User=ra%40st.team&DeviceId=androidc604232438&DeviceType=Android HTTP/1.1" 499 0 "-" "Android-Mail/2023.08.20.561750975.Release"
192.168.42.2 - ka@st.team [18/Sep/2023:16:13:48 +0200] "POST /Microsoft-Server-ActiveSync?oRIJBBBOaW5lNDYwMENENzYwMTA0BCu3YRMHQW5kcm9pZAcBAA== HTTP/1.1" 499 0 "-" "Nine-a52xqnaeea/TP1A.220624.014"

==> /var/log/grommunio/nginx-sync-error.log <==
2023/08/25 11:52:32 [error] 1666#1666: *302 FastCGI sent in stderr: "Unable to open primary script: /usr/share/grommunio-sync/index.php (Permission denied)" while reading response header from upstream, client: 192.168.42.2, server: _, request: "POST /Microsoft-Server-ActiveSync?oRQJBBBOaW5lMjUzOUI3MTc3NUZCBBTgTy4HQW5kcm9pZAcBAA== HTTP/1.1", upstream: "fastcgi://unix:/run/php-fpm/php-grommunio-sync-fpm.sock:", host: "mail.st.team"
2023/08/25 11:52:39 [error] 1666#1666: *308 FastCGI sent in stderr: "Unable to open primary script: /usr/share/grommunio-sync/index.php (Permission denied)" while reading response header from upstream, client: 192.168.42.2, server: _, request: "POST /Microsoft-Server-ActiveSync?oRIJBBBOaW5lMjUzOUI3MTc3NUZCBBTgTy4HQW5kcm9pZAcBAA== HTTP/1.1", upstream: "fastcgi://unix:/run/php-fpm/php-grommunio-sync-fpm.sock:", host: "mail.st.team"
2023/08/25 11:55:02 [error] 14980#14980: *43 FastCGI sent in stderr: "Unable to open primary script: /usr/share/grommunio-sync/index.php (Permission denied)" while reading response header from upstream, client: 192.168.42.2, server: _, request: "POST /Microsoft-Server-ActiveSync?oRIJBBBOaW5lRDc5MDY1MkNDNzgwBOI+pTcHQW5kcm9pZAcBAA== HTTP/1.1", upstream: "fastcgi://unix:/run/php-fpm/php-grommunio-sync-fpm.sock:", host: "mail.st.team"
2023/09/13 20:38:10 [error] 1674#1674: *6376 recv() failed (104: Connection reset by peer) while reading response header from upstream, client: 192.168.42.2, server: _, request: "POST /Microsoft-Server-ActiveSync?oRIJBBBOaW5lNDYwMENENzYwMTA0BCu3YRMHQW5kcm9pZAcBAA== HTTP/1.1", upstream: "fastcgi://unix:/run/php-fpm/php-grommunio-sync-fpm.sock:", host: "mail.st.team"
2023/09/13 20:38:10 [error] 1674#1674: *6378 recv() failed (104: Connection reset by peer) while reading response header from upstream, client: 192.168.42.2, server: _, request: "POST /Microsoft-Server-ActiveSync?oRIJBBBOaW5lOEI1QjEwNUNCQUFGBPP63RUHQW5kcm9pZAcBAA== HTTP/1.1", upstream: "fastcgi://unix:/run/php-fpm/php-grommunio-sync-fpm.sock:", host: "mail.st.team"
2023/09/17 10:58:01 [error] 1669#1669: *8966 recv() failed (104: Connection reset by peer) while reading response header from upstream, client: 192.168.42.2, server: _, request: "POST /Microsoft-Server-ActiveSync?Cmd=Ping&User=ra%40st.team&DeviceId=androidc604232438&DeviceType=Android HTTP/1.1", upstream: "fastcgi://unix:/run/php-fpm/php-grommunio-sync-fpm.sock:", host: "mail.st.team"
2023/09/18 07:08:57 [error] 1511#1511: *3856 recv() failed (104: Connection reset by peer) while reading response header from upstream, client: 192.168.42.2, server: _, request: "POST /Microsoft-Server-ActiveSync?Cmd=Ping&User=ra%40st.team&DeviceId=androidc604232438&DeviceType=Android HTTP/1.1", upstream: "fastcgi://unix:/run/php-fpm/php-grommunio-sync-fpm.sock:", host: "mail.st.team"
2023/09/18 16:06:11 [error] 1511#1511: *31033 recv() failed (104: Connection reset by peer) while reading response header from upstream, client: 192.168.42.2, server: _, request: "POST /Microsoft-Server-ActiveSync?oRIJBBBOaW5lOEI1QjEwNUNCQUFGBIkkbRUHQW5kcm9pZAcBAA== HTTP/1.1", upstream: "fastcgi://unix:/run/php-fpm/php-grommunio-sync-fpm.sock:", host: "mail.st.team"
2023/09/18 16:06:11 [error] 1511#1511: *30981 recv() failed (104: Connection reset by peer) while reading response header from upstream, client: 192.168.42.2, server: _, request: "POST /Microsoft-Server-ActiveSync?Cmd=Ping&User=ra%40st.team&DeviceId=androidc604232438&DeviceType=Android HTTP/1.1", upstream: "fastcgi://unix:/run/php-fpm/php-grommunio-sync-fpm.sock:", host: "mail.st.team"
2023/09/18 16:06:11 [error] 1511#1511: *31028 recv() failed (104: Connection reset by peer) while reading response header from upstream, client: 192.168.42.2, server: _, request: "POST /Microsoft-Server-ActiveSync?oRIJBBBOaW5lMkM0NjFFQzNDRDNFBBrcuQcHQW5kcm9pZAcBAA== HTTP/1.1", upstream: "fastcgi://unix:/run/php-fpm/php-grommunio-sync-fpm.sock:", host: "mail.st.team"

==> /var/log/grommunio/nginx-web-access.log <==
192.168.42.2 - - [16/Sep/2023:20:54:54 +0200] "POST /web/grommunio.php?service=fingerprint HTTP/1.1" 200 11 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/116.0.0.0 Safari/537.36 Edg/116.0.1938.81"
192.168.42.2 - - [16/Sep/2023:20:54:54 +0200] "POST /web/grommunio.php?service=fingerprint&type=keepalive HTTP/1.1" 200 18 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/116.0.0.0 Safari/537.36 Edg/116.0.1938.81"
192.168.42.2 - - [17/Sep/2023:11:51:29 +0200] "GET /web/ HTTP/1.1" 200 2675 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/117.0.0.0 Safari/537.36 Edg/117.0.2045.31"
192.168.42.2 - - [17/Sep/2023:11:51:30 +0200] "POST /web/grommunio.php?service=fingerprint HTTP/1.1" 200 11 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/117.0.0.0 Safari/537.36 Edg/117.0.2045.31"
192.168.42.2 - - [17/Sep/2023:11:51:30 +0200] "POST /web/grommunio.php?service=fingerprint&type=keepalive HTTP/1.1" 200 18 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/117.0.0.0 Safari/537.36 Edg/117.0.2045.31"
192.168.42.2 - - [17/Sep/2023:12:07:49 +0200] "GET /web/ HTTP/1.1" 200 2675 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/117.0.0.0 Safari/537.36 Edg/117.0.2045.31"
192.168.42.2 - - [17/Sep/2023:12:07:49 +0200] "POST /web/grommunio.php?service=fingerprint HTTP/1.1" 200 11 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/117.0.0.0 Safari/537.36 Edg/117.0.2045.31"
192.168.42.2 - - [17/Sep/2023:12:07:49 +0200] "POST /web/grommunio.php?service=fingerprint&type=keepalive HTTP/1.1" 200 18 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/117.0.0.0 Safari/537.36 Edg/117.0.2045.31"
192.168.42.2 - - [17/Sep/2023:12:19:10 +0200] "HEAD /web/ HTTP/1.1" 200 0 "https://autodiscover.st.team/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/74.0.3729.169 Safari/537.36"
192.168.42.2 - - [17/Sep/2023:12:19:50 +0200] "POST /web/grommunio.php?service=fingerprint&type=keepalive HTTP/1.1" 200 18 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/117.0.0.0 Safari/537.36 Edg/117.0.2045.31"

==> /var/log/grommunio/nginx-web-error.log <==
2023/08/25 11:54:09 [error] 14980#14980: *8 FastCGI sent in stderr: "Unable to open primary script: /usr/share/grommunio-web/index.php (Permission denied)" while reading response header from upstream, client: 192.168.42.2, server: _, request: "GET /web/ HTTP/1.1", upstream: "fastcgi://unix:/run/php-fpm/php-grommunio-web-fpm.sock:", host: "mail.st.team"
2023/08/25 11:54:10 [error] 14980#14980: *12 FastCGI sent in stderr: "Unable to open primary script: /usr/share/grommunio-web/index.php (Permission denied)" while reading response header from upstream, client: 192.168.42.2, server: _, request: "GET /web/ HTTP/1.1", upstream: "fastcgi://unix:/run/php-fpm/php-grommunio-web-fpm.sock:", host: "mail.st.team"
2023/08/25 11:54:37 [error] 14980#14980: *24 FastCGI sent in stderr: "Unable to open primary script: /usr/share/grommunio-web/index.php (Permission denied)" while reading response header from upstream, client: 192.168.42.2, server: _, request: "GET /web/ HTTP/1.1", upstream: "fastcgi://unix:/run/php-fpm/php-grommunio-web-fpm.sock:", host: "mail.st.team"
2023/08/25 11:54:37 [error] 14980#14980: *26 FastCGI sent in stderr: "Unable to open primary script: /usr/share/grommunio-web/index.php (Permission denied)" while reading response header from upstream, client: 192.168.42.2, server: _, request: "GET /web/ HTTP/1.1", upstream: "fastcgi://unix:/run/php-fpm/php-grommunio-web-fpm.sock:", host: "mail.st.team"
2023/08/25 11:54:37 [error] 14980#14980: *28 FastCGI sent in stderr: "Unable to open primary script: /usr/share/grommunio-web/index.php (Permission denied)" while reading response header from upstream, client: 192.168.42.2, server: _, request: "GET /web/ HTTP/1.1", upstream: "fastcgi://unix:/run/php-fpm/php-grommunio-web-fpm.sock:", host: "mail.st.team"
2023/08/25 11:54:47 [error] 14980#14980: *35 FastCGI sent in stderr: "Unable to open primary script: /usr/share/grommunio-web/index.php (Permission denied)" while reading response header from upstream, client: 192.168.42.2, server: _, request: "GET /web/ HTTP/1.1", upstream: "fastcgi://unix:/run/php-fpm/php-grommunio-web-fpm.sock:", host: "mail.st.team"
2023/08/25 11:57:34 [error] 15149#15149: *40 FastCGI sent in stderr: "Unable to open primary script: /usr/share/grommunio-web/index.php (Permission denied)" while reading response header from upstream, client: 192.168.42.2, server: _, request: "GET /web/ HTTP/1.1", upstream: "fastcgi://unix:/run/php-fpm/php-grommunio-web-fpm.sock:", host: "mail.st.team"
2023/08/25 11:57:35 [error] 15149#15149: *42 FastCGI sent in stderr: "Unable to open primary script: /usr/share/grommunio-web/index.php (Permission denied)" while reading response header from upstream, client: 192.168.42.2, server: _, request: "GET /web/ HTTP/1.1", upstream: "fastcgi://unix:/run/php-fpm/php-grommunio-web-fpm.sock:", host: "mail.st.team"
2023/09/04 20:34:54 [error] 1669#1669: *41721 FastCGI sent in stderr: "PHP message: grommunio Web user: ka: authentication failure at MAPI" while reading response header from upstream, client: 192.168.42.2, server: _, request: "POST /web/?logon HTTP/1.1", upstream: "fastcgi://unix:/run/php-fpm/php-grommunio-web-fpm.sock:", host: "mail.st.team"
2023/09/16 06:23:31 [error] 1978#1978: *995 FastCGI sent in stderr: "PHP message: grommunio Web user: admin: authentication failure at MAPI" while reading response header from upstream, client: 192.168.42.2, server: _, request: "POST /web/?logon HTTP/1.1", upstream: "fastcgi://unix:/run/php-fpm/php-grommunio-web-fpm.sock:", host: "mail.st.team"

==> /var/log/grommunio/nginx-mapi-access.log <==
192.168.42.2 - - [18/Sep/2023:16:14:05 +0200] "POST /mapi/emsmdb/?MailboxId=746f6d40-7374-6572-7a69-6e6705000000@st.team HTTP/1.1" 401 0 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"
192.168.42.2 - th@st.team [18/Sep/2023:16:14:05 +0200] "POST /mapi/emsmdb/?MailboxId=746f6d40-7374-6572-7a69-6e6705000000@st.team HTTP/1.1" 200 156 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"
192.168.42.2 - - [18/Sep/2023:16:14:05 +0200] "POST /mapi/emsmdb/?MailboxId=746f6d40-7374-6572-7a69-6e6705000000@st.team HTTP/1.1" 401 0 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"
192.168.42.2 - th@st.team [18/Sep/2023:16:14:05 +0200] "POST /mapi/emsmdb/?MailboxId=746f6d40-7374-6572-7a69-6e6705000000@st.team HTTP/1.1" 200 156 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"

==> /var/log/grommunio/nginx-autodiscover-access.log <==
192.168.42.2 - th@st.team [18/Sep/2023:16:14:05 +0200] "POST /autodiscover/autodiscover.xml HTTP/1.1" 200 284 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"
192.168.42.2 - th@st.team [18/Sep/2023:16:14:05 +0200] "POST /autodiscover/autodiscover.xml HTTP/1.1" 200 284 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"
192.168.42.2 - th@st.team [18/Sep/2023:16:14:05 +0200] "POST /autodiscover/autodiscover.xml HTTP/1.1" 200 284 "-" "Microsoft Office/16.0 (Windows NT 10.0; Microsoft Outlook 16.0.5404; Pro)"

==> /var/log/grommunio/nginx-sync-access.log <==
192.168.42.2 - th@st.team [18/Sep/2023:16:14:13 +0200] "POST /Microsoft-Server-ActiveSync?oRIJBBBOaW5lOEI1QjEwNUNCQUFGBIkkbRUHQW5kcm9pZAcBAA== HTTP/1.1" 499 0 "-" "Nine-XQ-AS52_EEA/58.2.A.10.126"

chrizzlibaer

Ich habe den Eindruck, dass dieser Teil des Logs auf das Problem hinweist, ich weiß aber nicht, wie ich das Problem beheben kann:

==> /var/log/grommunio/nginx-sync-error.log <==
2023/08/25 11:52:32 [error] 1666#1666: *302 FastCGI sent in stderr: "Unable to open primary script: /usr/share/grommunio-sync/index.php (Permission denied)" while reading response header from upstream, client: 192.168.42.2, server: _, request: "POST /Microsoft-Server-ActiveSync?oRQJBBBOaW5lMjUzOUI3MTc3NUZCBBTgTy4HQW5kcm9pZAcBAA== HTTP/1.1", upstream: "fastcgi://unix:/run/php-fpm/php-grommunio-sync-fpm.sock:", host: "mail.st.team"
2023/08/25 11:52:39 [error] 1666#1666: *308 FastCGI sent in stderr: "Unable to open primary script: /usr/share/grommunio-sync/index.php (Permission denied)" while reading response header from upstream, client: 192.168.42.2, server: _, request: "POST /Microsoft-Server-ActiveSync?oRIJBBBOaW5lMjUzOUI3MTc3NUZCBBTgTy4HQW5kcm9pZAcBAA== HTTP/1.1", upstream: "fastcgi://unix:/run/php-fpm/php-grommunio-sync-fpm.sock:", host: "mail.st.team"
2023/08/25 11:55:02 [error] 14980#14980: *43 FastCGI sent in stderr: "Unable to open primary script: /usr/share/grommunio-sync/index.php (Permission denied)" while reading response header from upstream, client: 192.168.42.2, server: _, request: "POST /Microsoft-Server-ActiveSync?oRIJBBBOaW5lRDc5MDY1MkNDNzgwBOI+pTcHQW5kcm9pZAcBAA== HTTP/1.1", upstream: "fastcgi://unix:/run/php-fpm/php-grommunio-sync-fpm.sock:", host: "mail.st.team"
2023/09/13 20:38:10 [error] 1674#1674: *6376 recv() failed (104: Connection reset by peer) while reading response header from upstream, client: 192.168.42.2, server: _, request: "POST /Microsoft-Server-ActiveSync?oRIJBBBOaW5lNDYwMENENzYwMTA0BCu3YRMHQW5kcm9pZAcBAA== HTTP/1.1", upstream: "fastcgi://unix:/run/php-fpm/php-grommunio-sync-fpm.sock:", host: "mail.st.team"
2023/09/13 20:38:10 [error] 1674#1674: *6378 recv() failed (104: Connection reset by peer) while reading response header from upstream, client: 192.168.42.2, server: _, request: "POST /Microsoft-Server-ActiveSync?oRIJBBBOaW5lOEI1QjEwNUNCQUFGBPP63RUHQW5kcm9pZAcBAA== HTTP/1.1", upstream: "fastcgi://unix:/run/php-fpm/php-grommunio-sync-fpm.sock:", host: "mail.st.team"
2023/09/17 10:58:01 [error] 1669#1669: *8966 recv() failed (104: Connection reset by peer) while reading response header from upstream, client: 192.168.42.2, server: _, request: "POST /Microsoft-Server-ActiveSync?Cmd=Ping&User=ra%40st.team&DeviceId=androidc604232438&DeviceType=Android HTTP/1.1", upstream: "fastcgi://unix:/run/php-fpm/php-grommunio-sync-fpm.sock:", host: "mail.st.team"
2023/09/18 07:08:57 [error] 1511#1511: *3856 recv() failed (104: Connection reset by peer) while reading response header from upstream, client: 192.168.42.2, server: _, request: "POST /Microsoft-Server-ActiveSync?Cmd=Ping&User=ra%40st.team&DeviceId=androidc604232438&DeviceType=Android HTTP/1.1", upstream: "fastcgi://unix:/run/php-fpm/php-grommunio-sync-fpm.sock:", host: "mail.st.team"
2023/09/18 16:06:11 [error] 1511#1511: *31033 recv() failed (104: Connection reset by peer) while reading response header from upstream, client: 192.168.42.2, server: _, request: "POST /Microsoft-Server-ActiveSync?oRIJBBBOaW5lOEI1QjEwNUNCQUFGBIkkbRUHQW5kcm9pZAcBAA== HTTP/1.1", upstream: "fastcgi://unix:/run/php-fpm/php-grommunio-sync-fpm.sock:", host: "mail.st.team"
2023/09/18 16:06:11 [error] 1511#1511: *30981 recv() failed (104: Connection reset by peer) while reading response header from upstream, client: 192.168.42.2, server: _, request: "POST /Microsoft-Server-ActiveSync?Cmd=Ping&User=ra%40st.team&DeviceId=androidc604232438&DeviceType=Android HTTP/1.1", upstream: "fastcgi://unix:/run/php-fpm/php-grommunio-sync-fpm.sock:", host: "mail.st.team"
2023/09/18 16:06:11 [error] 1511#1511: *31028 recv() failed (104: Connection reset by peer) while reading response header from upstream, client: 192.168.42.2, server: _, request: "POST /Microsoft-Server-ActiveSync?oRIJBBBOaW5lMkM0NjFFQzNDRDNFBBrcuQcHQW5kcm9pZAcBAA== HTTP/1.1", upstream: "fastcgi://unix:/run/php-fpm/php-grommunio-sync-fpm.sock:", host: "mail.st.team"

Weiß jemand weiter?

crpb

chrizzlibaer

Das sind ja nur sehr wenige Fehler und glaube ich nicht tragend in deinem problem.

Wie sind die Geräte denn eingestellt was das Synchronisationsverhalten angeht? Push/30min/sonstwas?
Falls du die Geräte einmal wie folgt neu synchronisieren lässt wäre vielleicht auch gut um zu sehen ob es hilft.
- Account auf Gerät entfernen
- Gerät in Admin-UI oder über die Einstellungsseite in https://grommunio.host/web im Benutzer direkt löschen.
- vielleicht mal kurz durchatmen
- Account im Gerät wieder hinzufügen
- Erstmal auf PUSH einstellen 🤷

Ansonsten kann man noch Benutzerbezogene erweiterte Logs aktivieren.

Also in /etc/grommunio-sync/grommunio-sync.conf.php
- etwa so...specialLogUsers = array('k..@st.team', 't..@st.team');

Das loggt dann nach /var/log/grommunio-sync/user_domain_XYZ...log oder so. Hab grad nirgends welche aktiv. Da ist dann natürlich "ein haufen" kram drin aber zumindest könnte man da vielleicht was erkennen.

Aber ich würde erstmal das mit dem hinzufügen/entfernen/PUSH probieren.

chrizzlibaer

Hallo @crpb,

Erst mal herzlichen Dank für den vielen input!

Das erneute hinzufügen vom Konto haben wir bereits mehrfach (5x oder mehr) versucht. Immer nach ca 24 Stunden verliert das Gerät dann die push Benachrichtigungen.
Wir nutzen die Nine App in standard Einstellungen (ganz bewusst) auf Push.
Ich aktiviere heute Abend mal das erweiterte logging und lass etwas sammeln, danach teile ich mal das Ergebnis. Hoffentlich kommt dadurch Licht ins Dunkel.

Euch ein schönes Wochenende!

crpb

Ich würde als alternative auch mal die mitgelieferte Google-Variante probieren..
Kenne Nine App selbst.. Hat die was besonderes was die Standard-App nicht kann?

Andy

Ich verwende die Nine APP auch. Diese funktioniert bei mir ohne Probleme, auch bei dem hier beschriebenen Thema habe ich keine Einschränkungen.

Das besondere an der Nine APP ist, dass darin die Funktionen für Mail, Kalender, Kontakte, Aufgaben, Notizen vergleichbar wie in Outlook in einer APP integriert sind. Ich nutze die deshalb auch sehr gerne. Der Unterschied liegt dann aber in den Geräten. Während ich bei einem Samsung die Anzahl ungelesener Email am Icon angezeigt bekomme, bekomme ich bei meinem OnePlus nur einen roten Punkt. Insofern könnte das "Problem" vielleicht auch gerätspezifisch sein.

chrizzlibaer

Ich hab das Logging jetzt mal aktiviert und sammle es demnächst ein und packs hier mal ins Forum.

Die Nine App hat einfach endlos viele, sinnvolle Einstellungsmöglichkeiten.
Buchen von einzelnen Unterordner in den Sync
Direkt hinterlegen von bevorzugten Farben auf geteilte Kalender
Sichern und Wiederherstellen aller Einstellungen
Gute und übersichtliche Widgets
Wie @Andy schon geschrieben hat, die gesamte Groupware Umgebung in einer App gebündelt

Wir hatten Nine sogar mal bei einem Kunden innerhalb seiner Enterprise MDM Lösung genutzt, hat sehr gut funktioniert ;-)

Ich melde mich wieder mit den Logs.

chrizzlibaer

@crpb und @WalterH:
ich habe hier jetzt megabyte-große Logs.
Wie verfahre ich mit denen? soll ich diese hierher laden oder bereitstellen?

Über einen kurzen Tipp wäre ich sehr dankbar! :-)

chrizzlibaer

Ich habe einen Eintrag in den Logs gefunden:

01/10/2023 00:10:38 [21424] [DEBUG] LoopDetection->Detect(): folderid:'U523b0' uuid:'e01e4d1d-552e-444e-a24d-5c56a49b1ddc' counter:'28' max:'20' queued:'208'

Ich habe gelernt, dass die kurze Folderid im Log auf eine lange BackendID übersetzt wird, z.B.
"06000000a5187b6fbcdcea1ed03c565700000000000a"
und diese ID wohl in den Eigenschaften im Webmailer angezeigt werden kann.

Wie kann ich die FolderID oder die uuid über die Konsole / SSH zurückverfolgen?

Hier hab ich auch einen Debuglog, der auf EntryIDs hinweist, aber ich weiß nicht, wie ich diese aufspüren kann:

	Zeile 112623: 30/09/2023 11:06:37 [19003] [DEBUG] PHPWrapper->ImportMessageChange(): Getting message from MAPIProvider, sourcekey: '06000000a5187b6fbcdcea1ed03c56570000001d5622', parentsourcekey: '06000000a5187b6fbcdcea1ed03c565700000000000a', entryid: '000000001c0a6b8380d9e641af1bd58db12ce24d070006000000a5187b6fbcdcea1ed03c565700000000000a000006000000a5187b6fbcdcea1ed03c56570000001d56220000'
	Zeile 112624: 30/09/2023 11:06:37 [19003] [DEBUG] BodyPreference types: 2, 1
	Zeile 112625: 30/09/2023 11:06:37 [19003] [DEBUG] GetBodyPreferenceBestMatch: 2
	Zeile 112626: 30/09/2023 11:06:37 [19003] [DEBUG] bpo: truncation size:'0', allornone:'0', preview:'0'
	Zeile 112627: 30/09/2023 11:06:37 [19003] [DEBUG] StringStreamWrapper::stream_open(): initialized stream length: 7 - HTML-safe-truncate: false
	Zeile 112628: 30/09/2023 11:06:37 [19003] [DEBUG] StringStreamWrapper::stream_open(): initialized stream length: 246 - HTML-safe-truncate: true
	Zeile 112629: 30/09/2023 11:06:37 [19003] [DEBUG] TimezoneUtil::FillTZNames() filling up bias -60
	Zeile 112630: 30/09/2023 11:06:37 [19003] [WBXML] Grommunio->GetUserDetails for 't@s.team'.
	Zeile 112631: 30/09/2023 11:06:37 [19003] [FATAL] Fatal error: /usr/share/php-mapi/class.meetingrequest.php:1962 - Uncaught TypeError: mapi_getprops(): Argument #1 ($any) must be of type resource, bool given in /usr/share/php-mapi/class.meetingrequest.php:1962

andreaslang

Hi @chrizzlibaer ,

chrizzlibaer Wie kann ich die FolderID oder die uuid über die Konsole / SSH zurückverfolgen?

wahrscheinlich nur direkt über die exchange.sqlite3 des Benutzers. Z.B.:
select folder_id from folder_properties where hex(propval) = '06000000a5187b6fbcdcea1ed03c565700000000000a' collate nocase ;

Man sollte aber nie etwas direkt in der Datenbank ändern!

chrizzlibaer

andreaslang hallo Andreas,
Danke für die Rückmeldung.
Ich habe absolut nicht vor etwas an der Datenbank zu ändern 🙃
Dann schau ich mal ob ich damit auf die Suche gehen kann... 😁

chrizzlibaer

Wir haben es jetzt mit der "hau drauf" Methode gelöst:
Mails und Kalendereinträge in Gänze in ein zweites, als Archiv genutztes Postfach verschoben und "neu" angefangen.
Seitdem sind die Probleme nicht mehr aufgetreten.

Also für andere, die auf dasselbe Problem stoßen:
Ursache waren in unserem Fall vom Exchange migrierte Einträge in Kalendern und im Posteingang.
Welche Einträge es genau waren, können wir leider nicht beantworten.

Case closed von meiner Seite.
Danke an alle Unterstützer!