NethServer Version: NS8
Module: webtop V1.4.3, mail V1.7.1-dev.2
Dear Community,
I do have an issue with active sync/z-push on Samsung Android Device:
Exchange Server setup for webtop on Android is ok - acount creating looks everything normal, user can login, exchange account is created: Then starting sync, email folder structure is seen but Emails cannot be synced to mobile email client.
Tried already (several times) the following
- deleting Exchange account on mobile device completely
- deleting WebTop Synchronization context (in Webtop-WebGui/Settings/System/Synchronization)
- reestablishment of Exchange account on mobile
- turned off calendar sync (as one of the error points to iCalender.php, contact sync, task sync (only keeping email sync)
Calender sync is performed with DavX5 without issues.
Mail sync is working on other Android devices (with different user) - also do not get such errors in log (PHP Fatal error, eas-server.Error) for other users - very strange.
Not sure it correlates with installation of mail V1.7.1-dev.2, due to the mail-relay issue - not really beleaving that as other device are working with email sync.
Nevertheless I suppose these errors will have a root cause?
Webtop Logs: (after starting sync on mobile device)
2025-07-16T10:52:48Z [1:webtop1:z-push] ::1 - USER1@DOMAIN.A 16/Jul/2025:10:52:47 +0000 "POST /Microsoft-Server-ActiveSync" 200
2025-07-16T10:52:48Z [1:webtop1:z-push] NOTICE: PHP message: PHP Fatal error: require_once(): Failed opening required 'XMLElement.php' (include_path='.:/usr/local/lib/php:/usr/share/awl/inc:/usr/share/webtop/z-push/vendor/z-push/z-push/src/backend/imap/') in /usr/share/webtop/z-push/vendor/z-push/z-push/src/include/iCalendar.php on line 49
2025-07-16T10:52:48Z [1:webtop1:z-push] [2025-07-16 12:52:48] eas-server.ERROR: ZPCore - [ 19] [USER1@DOMAIN.A] Fatal error: /usr/share/webtop/z-push/vendor/z-push/z-push/src/include/iCalendar.php:49 - require_once(): Failed opening required 'XMLElement.php' (include_path='.:/usr/local/lib/php:/usr/share/awl/inc:/usr/share/webtop/z-push/vendor/z-push/z-push/src/backend/imap/') (64) [] []
2025-07-16T10:52:48Z [1:webtop1:apache] [Wed Jul 16 10:52:48.939199 2025] [proxy_fcgi:error] [pid 63:tid 63] [client 10.0.2.100:48332] AH01071: Got error 'PHP message: PHP Fatal error: require_once(): Failed opening required 'XMLElement.php' (include_path='.:/usr/local/lib/php:/usr/share/awl/inc:/usr/share/webtop/z-push/vendor/z-push/z-push/src/backend/imap/') in /usr/share/webtop/z-push/vendor/z-push/z-push/src/include/iCalendar.php on line 49'
2025-07-16T10:52:48Z [1:webtop1:apache] 10.0.2.100 - - [16/Jul/2025:10:52:47 +0000] "POST /Microsoft-Server-ActiveSync?Cmd=Sync&User=USER1%40DOMAIN.A&DeviceId=<SAMSUNG_DEV_ID>&DeviceType=SamsungDevice HTTP/1.1" 200 15332523
2025-07-16T10:52:56Z [1:webtop1:apache] [Wed Jul 16 10:52:56.778627 2025] [proxy_fcgi:error] [pid 60:tid 60] (70007)The timeout specified has expired: [client 10.0.2.100:40310] AH01075: Error dispatching request to :8081: (polling)
2025-07-16T10:52:56Z [1:webtop1:apache] 10.0.2.100 - - [16/Jul/2025:10:51:56 +0000] "POST /Microsoft-Server-ActiveSync?Cmd=Ping&User=USER1%40DOMAIN.A&DeviceId=<SAMSUNG_DEV_ID>&DeviceType=SamsungDevice HTTP/1.1" 504 247
2025-07-16T10:53:01Z [1:webtop1:apache] [Wed Jul 16 10:53:01.611646 2025] [proxy_fcgi:error] [pid 57:tid 57] (70007)The timeout specified has expired: [client 10.0.2.100:54426] AH01075: Error dispatching request to :8081: (polling)
2025-07-16T10:53:01Z [1:webtop1:apache] 10.0.2.100 - - [16/Jul/2025:10:52:01 +0000] "POST /Microsoft-Server-ActiveSync?Cmd=Ping&User=%5Cheini%40home.lan&DeviceId=androidc1985701698&DeviceType=Android HTTP/1.1" 504 247
2025-07-16T10:53:03Z [1:webtop1:apache] 10.0.2.100 - - [16/Jul/2025:10:53:03 +0000] "GET /?file=..%2f..%2f..%2fetc%2fpasswd HTTP/1.1" 301 279
2025-07-16T10:53:03Z [1:webtop1:apache] 10.0.2.100 - - [16/Jul/2025:10:53:03 +0000] "GET /webtop?file=..%2f..%2f..%2fetc%2fpasswd HTTP/1.1" 302 -
other example:
2025-07-16T10:57:11Z [1:webtop1:apache] [Wed Jul 16 10:57:11.226612 2025] [proxy_fcgi:error] [pid 66:tid 66] (70007)The timeout specified has expired: [client 10.0.2.100:34926] AH01075: Error dispatching request to :8081: (polling)
2025-07-16T10:57:11Z [1:webtop1:apache] 10.0.2.100 - - [16/Jul/2025:10:56:11 +0000] "POST /Microsoft-Server-ActiveSync?Cmd=Ping&User=%5Cheini%40home.lan&DeviceId=androidc1985701698&DeviceType=Android HTTP/1.1" 504 247
2025-07-16T10:57:22Z [1:webtop1:z-push] ::1 - USER1@DOMAIN.A 16/Jul/2025:10:57:20 +0000 "POST /Microsoft-Server-ActiveSync" 200
2025-07-16T10:57:22Z [1:webtop1:z-push] NOTICE: PHP message: PHP Fatal error: require_once(): Failed opening required 'XMLElement.php' (include_path='.:/usr/local/lib/php:/usr/share/awl/inc:/usr/share/webtop/z-push/vendor/z-push/z-push/src/backend/imap/') in /usr/share/webtop/z-push/vendor/z-push/z-push/src/include/iCalendar.php on line 49
2025-07-16T10:57:22Z [1:webtop1:z-push] [2025-07-16 12:57:22] eas-server.ERROR: ZPCore - [ 28] [USER1@DOMAIN.A] Fatal error: /usr/share/webtop/z-push/vendor/z-push/z-push/src/include/iCalendar.php:49 - require_once(): Failed opening required 'XMLElement.php' (include_path='.:/usr/local/lib/php:/usr/share/awl/inc:/usr/share/webtop/z-push/vendor/z-push/z-push/src/backend/imap/') (64) [] []
2025-07-16T10:57:22Z [1:webtop1:apache] [Wed Jul 16 10:57:22.662756 2025] [proxy_fcgi:error] [pid 64:tid 64] [client 10.0.2.100:39458] AH01071: Got error 'PHP message: PHP Fatal error: require_once(): Failed opening required 'XMLElement.php' (include_path='.:/usr/local/lib/php:/usr/share/awl/inc:/usr/share/webtop/z-push/vendor/z-push/z-push/src/backend/imap/') in /usr/share/webtop/z-push/vendor/z-push/z-push/src/include/iCalendar.php on line 49'
2025-07-16T10:57:22Z [1:webtop1:apache] 10.0.2.100 - - [16/Jul/2025:10:57:20 +0000] "POST /Microsoft-Server-ActiveSync?Cmd=Sync&User=USER1%40DOMAIN.A&DeviceId=<SAMSUNG_DEV_ID>&DeviceType=SamsungDevice HTTP/1.1" 200 15332523
2025-07-16T10:57:42Z [1:webtop1:z-push] ::1 - USER1@DOMAIN.A 16/Jul/2025:10:57:40 +0000 "POST /Microsoft-Server-ActiveSync" 200
2025-07-16T10:57:42Z [1:webtop1:z-push] NOTICE: PHP message: PHP Fatal error: require_once(): Failed opening required 'XMLElement.php' (include_path='.:/usr/local/lib/php:/usr/share/awl/inc:/usr/share/webtop/z-push/vendor/z-push/z-push/src/backend/imap/') in /usr/share/webtop/z-push/vendor/z-push/z-push/src/include/iCalendar.php on line 49
2025-07-16T10:57:42Z [1:webtop1:z-push] [2025-07-16 12:57:42] eas-server.ERROR: ZPCore - [ 27] [USER1@DOMAIN.A] Fatal error: /usr/share/webtop/z-push/vendor/z-push/z-push/src/include/iCalendar.php:49 - require_once(): Failed opening required 'XMLElement.php' (include_path='.:/usr/local/lib/php:/usr/share/awl/inc:/usr/share/webtop/z-push/vendor/z-push/z-push/src/backend/imap/') (64) [] []
2025-07-16T10:57:42Z [1:webtop1:apache] [Wed Jul 16 10:57:42.367544 2025] [proxy_fcgi:error] [pid 63:tid 63] [client 10.0.2.100:33788] AH01071: Got error 'PHP message: PHP Fatal error: require_once(): Failed opening required 'XMLElement.php' (include_path='.:/usr/local/lib/php:/usr/share/awl/inc:/usr/share/webtop/z-push/vendor/z-push/z-push/src/backend/imap/') in /usr/share/webtop/z-push/vendor/z-push/z-push/src/include/iCalendar.php on line 49'
2025-07-16T10:57:42Z [1:webtop1:apache] 10.0.2.100 - - [16/Jul/2025:10:57:40 +0000] "POST /Microsoft-Server-ActiveSync?Cmd=Sync&User=USER1%40DOMAIN.A&DeviceId=<SAMSUNG_DEV_ID>&DeviceType=SamsungDevice HTTP/1.1" 200 15332523
2025-07-16T10:57:46Z [1:webtop1:pecbridge] INFO com.sonicle.pecbridge.Main - Running mailboxes scan
2025-07-16T10:57:46Z [1:webtop1:pecbridge] INFO com.sonicle.pecbridge.Configuration - Refreshing relays
2025-07-16T10:57:54Z [1:webtop1:z-push] ::1 - \heini@home.lan 16/Jul/2025:10:49:53 +0000 "POST /Microsoft-Server-ActiveSync" 200
2025-07-16T10:57:54Z [1:webtop1:apache] [Wed Jul 16 10:57:54.221578 2025] [proxy_fcgi:error] [pid 89:tid 89] (70007)The timeout specified has expired: [client 10.0.2.100:42490] AH01075: Error dispatching request to :8081: (polling)
2025-07-16T10:57:54Z [1:webtop1:apache] 10.0.2.100 - - [16/Jul/2025:10:56:54 +0000] "POST /Microsoft-Server-ActiveSync?Cmd=Ping&User=USER1%40DOMAIN.A&DeviceId=<SAMSUNG_DEV_ID>&DeviceType=SamsungDevice HTTP/1.1" 504 247
2025-07-16T10:57:55Z [1:webtop1:apache] [Wed Jul 16 10:57:55.417626 2025] [proxy_fcgi:error] [pid 59:tid 59] (70007)The timeout specified has expired: [client 10.0.2.100:42498] AH01075: Error dispatching request to :8081: (polling)
2025-07-16T10:57:55Z [1:webtop1:apache] 10.0.2.100 - - [16/Jul/2025:10:56:55 +0000] "POST /Microsoft-Server-ActiveSync?Cmd=Ping&User=USER1%40DOMAIN.A&DeviceId=<SAMSUNG_DEV_ID>&DeviceType=SamsungDevice HTTP/1.1" 504 247
2025-07-16T10:57:55Z [1:webtop1:apache] [Wed Jul 16 10:57:55.985548 2025] [proxy_fcgi:error] [pid 60:tid 60] (70007)The timeout specified has expired: [client 10.0.2.100:42500] AH01075: Error dispatching request to :8081: (polling)
2025-07-16T10:57:55Z [1:webtop1:apache] 10.0.2.100 - - [16/Jul/2025:10:56:55 +0000] "POST /Microsoft-Server-ActiveSync?Cmd=Ping&User=USER1%40DOMAIN.A&DeviceId=<SAMSUNG_DEV_ID>&DeviceType=SamsungDevice HTTP/1.1" 504 247
Example with z-push log-level DEBUG:
2025-07-16T19:33:34Z [1:webtop1:webapp] 2025-07-16 21:33:34 [INFO ] c.s.w.core.app.ConnectionManager - Initialized
2025-07-16T19:33:34Z [1:webtop1:webapp] 2025-07-16 21:33:34 [INFO ] c.s.webtop.core.app.AuditLogManager - Initialized
2025-07-16T19:33:34Z [1:webtop1:webapp] 2025-07-16 21:33:34 [INFO ] c.s.webtop.core.app.SessionManager - Initialized
2025-07-16T19:33:34Z [1:webtop1:webapp] 2025-07-16 21:33:34 [INFO ] c.sonicle.webtop.core.app.WebTopApp - Checking home structure...
2025-07-16T19:33:34Z [1:webtop1:webapp] 2025-07-16 21:33:34 [INFO ] c.sonicle.webtop.core.app.WebTopApp - [TemplateEngine] Initializing...
2025-07-16T19:33:34Z [1:webtop1:webapp] 2025-07-16 21:33:34 [INFO ] c.s.webtop.core.app.LicenseManager - Initialized
2025-07-16T19:33:36Z [1:webtop1:webapp] 2025-07-16 21:33:36 [INFO ] c.s.webtop.core.app.WebTopManager - Initialized
2025-07-16T19:33:36Z [1:webtop1:webapp] 2025-07-16 21:33:36 [INFO ] c.sonicle.webtop.core.app.OTPManager - Initialized
2025-07-16T19:33:36Z [1:webtop1:webapp] 2025-07-16 21:33:36 [INFO ] c.s.webtop.core.app.ReportManager - Initialized
2025-07-16T19:33:36Z [1:webtop1:webapp] 2025-07-16 21:33:36 [INFO ] c.s.webtop.core.app.DocEditorManager - Initialized
2025-07-16T19:33:36Z [1:webtop1:webapp] 2025-07-16 21:33:36 [INFO ] c.s.webtop.core.app.ServiceManager - [com.sonicle.webtop.core] Registering service...
2025-07-16T19:33:36Z [1:webtop1:webapp] 2025-07-16 21:33:36 [INFO ] c.s.webtop.core.app.ServiceManager - [private:true, public:true, background:true, userOptions:true]
2025-07-16T19:33:37Z [1:webtop1:webapp] 2025-07-16 21:33:37 [INFO ] c.s.webtop.core.app.ServiceManager - [com.sonicle.webtop.calendar] Registering service...
2025-07-16T19:33:37Z [1:webtop1:webapp] 2025-07-16 21:33:37 [INFO ] c.s.webtop.core.app.ServiceManager - [private:true, public:false, background:true, userOptions:true]
2025-07-16T19:33:37Z [1:webtop1:webapp] 2025-07-16 21:33:37 [INFO ] c.s.webtop.core.app.ServiceManager - [private:true, public:false, background:true, userOptions:true]
2025-07-16T19:33:37Z [1:webtop1:webapp] 2025-07-16 21:33:37 [INFO ] c.s.webtop.core.app.ServiceManager - [com.sonicle.webtop.vfs] Registering service...
2025-07-16T19:33:37Z [1:webtop1:webapp] 2025-07-16 21:33:37 [INFO ] c.s.webtop.core.app.ServiceManager - Not upgraded! [5.11.0 = 5.11.0]
2025-07-16T19:33:38Z [1:webtop1:webapp] 2025-07-16 21:33:38 [INFO ] c.sonicle.webtop.core.app.WebTopApp - WTA initialization completed [webtop]
2025-07-16T19:33:40Z [1:webtop1:webapp] 16-Jul-2025 21:33:40.252 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [/usr/local/tomcat
/webapps/webtop] has finished in [18,354] ms
2025-07-16T19:33:40Z [1:webtop1:webapp] 16-Jul-2025 21:33:40.264 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-8080"]
2025-07-16T19:33:40Z [1:webtop1:webapp] 16-Jul-2025 21:33:40.293 INFO [main] org.apache.catalina.startup.Catalina.start Server startup in 18477 ms
2025-07-16T19:33:41Z [1:webtop1:pecbridge] INFO com.sonicle.pecbridge.Configuration - Refreshing relays
2025-07-16T19:33:41Z [1:webtop1:z-push] [2025-07-16 21:33:41] eas-server.DEBUG: ZPCore - [ 2] [USER1@DOMAIN.A] Combined->Logon() success [] []
2025-07-16T19:33:41Z [1:webtop1:z-push] [2025-07-16 21:33:41] eas-server.DEBUG: ZPCore - [ 2] [USER1@DOMAIN.A] ZPush::CommandNeedsPlainInput(0): false [] []
2025-07-16T19:33:41Z [1:webtop1:z-push] [2025-07-16 21:33:41] eas-server.DEBUG: ZPCore - [ 2] [USER1@DOMAIN.A] Combined->Setup('', 'false', '', 'false') [] []
2025-07-16T19:33:41Z [1:webtop1:z-push] [2025-07-16 21:33:41] eas-server.DEBUG: ZPCore - [ 2] [USER1@DOMAIN.A] Combined->Setup('', 'false', '', 'false') [] []
2025-07-16T19:33:41Z [1:webtop1:z-push] [2025-07-16 21:33:41] eas-server.DEBUG: ZPCore - [ 2] [USER1@DOMAIN.A] BackendIMAP->StatMessage('ce6a7a2d','79992') [] []
2025-07-16T19:33:41Z [1:webtop1:z-push] [2025-07-16 21:33:41] eas-server.DEBUG: ZPCore - [ 2] [USER1@DOMAIN.A] BackendIMAP->StatMessage('ce6a7a2d','79992') [] []
2025-07-16T19:33:41Z [1:webtop1:z-push] [2025-07-16 21:33:41] eas-server.DEBUG: ZPCore - [ 2] [USER1@DOMAIN.A] BackendIMAP->GetMessage('ce6a7a2d', '79995', '2') [] []
2025-07-16T19:33:41Z [1:webtop1:z-push] [2025-07-16 21:33:41] eas-server.DEBUG: ZPCore - [ 2] [USER1@DOMAIN.A] BackendIMAP->StatMessage('ce6a7a2d','80037') [] []
2025-07-16T19:33:41Z [1:webtop1:z-push] [2025-07-16 21:33:41] eas-server.DEBUG: ZPCore - [ 2] [USER1@DOMAIN.A] BackendIMAP->StatMessage('ce6a7a2d','80037') [] []
2025-07-16T19:33:41Z [1:webtop1:z-push] [2025-07-16 21:33:41] eas-server.DEBUG: ZPCore - [ 2] [USER1@DOMAIN.A] Utils::getRawMailHeaders(): subject = =?UTF-8?Q?Elternverein_Nextcloud_Hub_Server_Adressen
_=C3=84nderung?= [] []
2025-07-16T19:33:41Z [1:webtop1:apache] [Wed Jul 16 19:33:41.544206 2025] [proxy_fcgi:error] [pid 48:tid 48] [client 10.0.2.100:58046] AH01071: Got error 'PHP message: PHP Fatal error: require_once(): Faile
d opening required 'XMLElement.php' (include_path='.:/usr/local/lib/php:/usr/share/awl/inc:/usr/share/webtop/z-push/vendor/z-push/z-push/src/backend/imap/') in /usr/share/webtop/z-push/vendor/z-push/z-push/s
rc/include/iCalendar.php on line 49'
2025-07-16T19:33:43Z [1:webtop1:webapp] 2025-07-16 21:33:43 [INFO ] c.sonicle.webtop.core.app.WebTopApp - Checking webapp version...
2025-07-16T19:33:43Z [1:webtop1:webapp] 2025-07-16 21:33:43 [WARN ] c.sonicle.webtop.core.app.WebTopApp - No configuration found for TomcatManager [tomcat.manager.uri]
2025-07-16T19:33:43Z [1:webtop1:webapp] 2025-07-16 21:33:43 [WARN ] c.sonicle.webtop.core.app.WebTopApp - Webapp version automatic check will NOT be performed!
2025-07-16T19:33:43Z [1:webtop1:webapp] 2025-07-16 21:33:43 [INFO ] c.sonicle.webtop.core.app.WebTopApp - This webapp [webtop] is the latest
Is there server side some more cache which could be cleared?
Not sure what else I could try to debug?
Many thanks upfront for a hint.