Notifications
Clear all
Topic starter
05/11/2022 16:15
Hello everyone,
We installed a new Carbonio mail server but we could not run the mailbox service.
I tried to start the zmmailboxdctl service but it didn't start. (zmmailboxdctl restart)
Users can't access their e-mails and can't log in from the web panel.
I am waiting for your solutions. Thanks.
zextras@mail:~$ zmcontrol status Host xxx amavis Running antispam Running antivirus Running directory-server Running logger Running mailbox Stopped zmmailboxdctl is not running. memcached Running mta Running opendkim Running proxy Running service webapp Stopped zmmailboxdctl is not running. stats Running zimbra webapp Stopped zmmailboxdctl is not running. zimbraAdmin webapp Stopped zmmailboxdctl is not running. zimlet webapp Stopped zmmailboxdctl is not running. zmconfigd Running
I've found some logs.
carbonio.log:
May 11 16:09:59 mail /postfix-script[88011]: the Postfix mail system is running: PID: 77367 May 11 16:09:59 mail zmmailboxdmgr[88045]: stale pid 87166 found in /opt/zextras/log/zmmailboxd_manager.pid: No such process May 11 16:09:59 mail zmmailboxdmgr[88045]: assuming no other instance is running May 11 16:09:59 mail zmmailboxdmgr[88045]: file /opt/zextras/log/zmmailboxd.pid does not exist May 11 16:09:59 mail zmmailboxdmgr[88045]: assuming no other instance is running May 11 16:09:59 mail zmmailboxdmgr[88045]: no manager process is running May 11 16:09:59 mail zmconfigd[73946]: Tracking service service May 11 16:10:00 mail zmmailboxdmgr[88106]: stale pid 87166 found in /opt/zextras/log/zmmailboxd_manager.pid: No such process May 11 16:10:00 mail zmmailboxdmgr[88106]: assuming no other instance is running May 11 16:10:00 mail zmmailboxdmgr[88106]: file /opt/zextras/log/zmmailboxd.pid does not exist May 11 16:10:00 mail zmmailboxdmgr[88106]: assuming no other instance is running May 11 16:10:00 mail zmmailboxdmgr[88106]: no manager process is running May 11 16:10:00 mail zmconfigd[73946]: Tracking service zimbra May 11 16:10:00 mail zmmailboxdmgr[88116]: stale pid 87166 found in /opt/zextras/log/zmmailboxd_manager.pid: No such process May 11 16:10:00 mail zmmailboxdmgr[88116]: assuming no other instance is running May 11 16:10:00 mail zmmailboxdmgr[88116]: file /opt/zextras/log/zmmailboxd.pid does not exist May 11 16:10:00 mail zmmailboxdmgr[88116]: assuming no other instance is running May 11 16:10:00 mail zmmailboxdmgr[88116]: no manager process is running May 11 16:10:00 mail zmconfigd[73946]: Tracking service zimbraAdmin May 11 16:10:00 mail zmmailboxdmgr[88126]: stale pid 87166 found in /opt/zextras/log/zmmailboxd_manager.pid: No such process May 11 16:10:00 mail zmmailboxdmgr[88126]: assuming no other instance is running May 11 16:10:00 mail zmmailboxdmgr[88126]: file /opt/zextras/log/zmmailboxd.pid does not exist May 11 16:10:00 mail zmmailboxdmgr[88126]: assuming no other instance is running May 11 16:10:00 mail zmmailboxdmgr[88126]: no manager process is running
mailbox.log:
2022-05-11 16:10:24,015 INFO [main] [] misc - DoSFilter: Configured whitelist IPs = 100.66.6.205,127.0.0.1,100.66.6.61,127.0.0.1,::1,0:0:0:0:0:0:0:1
2022-05-11 16:10:24,028 INFO [main] [] misc - DoSFilter: Configured whitelist IPs = 100.66.6.205,127.0.0.1,100.66.6.61,127.0.0.1,::1,0:0:0:0:0:0:0:1
2022-05-11 16:10:24,035 INFO [main] [] misc - ZimbraInvalidLoginFilter initialized
2022-05-11 16:10:24,044 INFO [main] [] misc - Initialized with /service:max=80%, /zimbra:max=15%, /zimbraAdmin:max=5%
2022-05-11 16:10:24,045 INFO [main] [] misc - Thread pool was configured to max=250
2022-05-11 16:10:24,061 INFO [main] [] misc - CSRF filter was initialized: CSRFAllowedRefHost: []
2022-05-11 16:10:24,199 INFO [main] [] soap - Servlet SoapServlet starting up
2022-05-11 16:10:24,214 INFO [main] [] soap - Adding service AccountService to SoapServlet
2022-05-11 16:10:24,253 INFO [main] [] soap - Adding service MailService to SoapServlet
2022-05-11 16:10:24,298 INFO [main] [] misc - version=22.4.0_ZEXTRAS_202204 release=agent builddate=20220415-1401 buildhost=${zimbra.server.hostname}
2022-05-11 16:10:24,298 INFO [main] [] misc - LANG environment is set to: C
2022-05-11 16:10:24,298 INFO [main] [] misc - System property java.home=/opt/zextras/common/lib/jvm/java
2022-05-11 16:10:24,298 INFO [main] [] misc - System property java.runtime.version=13.0.2+8
2022-05-11 16:10:24,298 INFO [main] [] misc - System property java.version=13.0.2
2022-05-11 16:10:24,299 INFO [main] [] misc - System property java.vm.info=mixed mode, sharing
2022-05-11 16:10:24,299 INFO [main] [] misc - System property java.vm.name=OpenJDK 64-Bit Server VM
2022-05-11 16:10:24,299 INFO [main] [] misc - System property java.vm.version=13.0.2+8
2022-05-11 16:10:24,304 INFO [main] [] misc - System property os.arch=amd64
2022-05-11 16:10:24,304 INFO [main] [] misc - System property os.name=Linux
2022-05-11 16:10:24,304 INFO [main] [] misc - System property os.version=5.4.0-110-generic
2022-05-11 16:10:24,304 INFO [main] [] misc - System property sun.arch.data.model=64
2022-05-11 16:10:24,304 INFO [main] [] misc - System property sun.cpu.endian=little
2022-05-11 16:10:24,305 INFO [main] [] misc - System property sun.cpu.isalist=null
2022-05-11 16:10:24,305 INFO [main] [] misc - System property sun.os.patch.level=null
2022-05-11 16:10:24,317 INFO [main] [] system - Setting mysql connector property: maxActive=100
2022-05-11 16:10:24,318 INFO [main] [] system - Setting mysql connector property: maxActive=100
2022-05-11 16:10:24,380 INFO [main] [] sqltrace - Setting slow SQL threshold to 2000ms.
2022-05-11 16:10:24,637 INFO [main] [] ZimbraHttpConnectionManager - Starting http client idle connection reaper thread for Internal http client connection manager - reaper sleep interval=300000, reaper connection timeout=300000
2022-05-11 16:10:24,638 INFO [main] [] ZimbraHttpConnectionManager - Starting http client idle connection reaper thread for External http client connection manager - reaper sleep interval=300000, reaper connection timeout=300000
2022-05-11 16:10:24,642 WARN [main] [] ephemeral - Replacing ephemeral factory class 'com.zimbra.cs.ephemeral.LdapEphemeralStore$Factory' registered for 'ldap' with 'com.zimbra.cs.ephemeral.LdapEphemeralStore$Factory'
2022-05-11 16:10:24,643 INFO [main] [] extensions - Loading extensions from /opt/zextras/lib/ext
2022-05-11 16:10:24,644 INFO [main] [] extensions - extension com.zimbra.cs.nginx.NginxLookupExtension found in /opt/zextras/lib/ext/nginx-lookup/nginx-lookup.jar
2022-05-11 16:10:24,644 INFO [main] [] extensions - extension com.zimbra.graphql.resources.GQLExtension found in /opt/zextras/lib/ext/zm-gql/zmgql.jar
2022-05-11 16:10:24,645 INFO [main] [] extensions - extension com.zimbra.cert.ZimbraCertMgrExt found in /opt/zextras/lib/ext/com_zimbra_cert_manager/com_zimbra_cert_manager.jar
2022-05-11 16:10:24,645 WARN [main] [] extensions - no Zimbra-Extension-Class found, ignored: /opt/zextras/lib/ext/openidconsumer
2022-05-11 16:10:24,645 INFO [main] [] extensions - extension com.zimbra.clam.ClamScannerExt found in /opt/zextras/lib/ext/clamscanner/clamscanner.jar
2022-05-11 16:10:24,645 INFO [main] [] extensions - extension com.zimbra.ldaputils.ZimbraLDAPUtilsExtension found in /opt/zextras/lib/ext/zimbraldaputils/zimbraldaputils.jar
2022-05-11 16:10:24,646 INFO [main] [] extensions - Initializing extensions
2022-05-11 16:10:24,652 INFO [main] [] extensions - registered handler at /nginx-lookup
2022-05-11 16:10:24,654 INFO [main] [] extensions - Initialized extension nginx-lookup: com.zimbra.cs.nginx.NginxLookupExtension@com.zimbra.cs.extension.ZimbraExtensionClassLoader@57ad1178
2022-05-11 16:10:24,656 INFO [main] [] extensions - Registering zm-gql
2022-05-11 16:10:24,767 INFO [main] [] extensions - Loading standard graphql schema builder.
2022-05-11 16:10:24,769 INFO [main] [] extensions - Loading com.zimbra.graphql.repositories.impl.ZXMLAccountRepository . . .
2022-05-11 16:10:24,772 INFO [main] [] extensions - Loading com.zimbra.graphql.repositories.impl.ZXMLAuthRepository . . .
2022-05-11 16:10:24,772 INFO [main] [] extensions - Loading com.zimbra.graphql.repositories.impl.ZNativeAuthRepository . . .
2022-05-11 16:10:24,773 INFO [main] [] extensions - Loading com.zimbra.graphql.repositories.impl.ZXMLCalendarRepository . . .
2022-05-11 16:10:24,775 INFO [main] [] extensions - Loading com.zimbra.graphql.repositories.impl.ZXMLContactRepository . . .
2022-05-11 16:10:24,776 INFO [main] [] extensions - Loading com.zimbra.graphql.repositories.impl.ZXMLFolderRepository . . .
2022-05-11 16:10:24,777 INFO [main] [] extensions - Loading com.zimbra.graphql.repositories.impl.ZXMLMessageRepository . . .
2022-05-11 16:10:24,778 INFO [main] [] extensions - Loading com.zimbra.graphql.repositories.impl.ZXMLSearchRepository . . .
2022-05-11 16:10:24,779 INFO [main] [] extensions - Loading com.zimbra.graphql.repositories.impl.ZXMLTaskRepository . . .
2022-05-11 16:10:24,779 INFO [main] [] extensions - Generating schema with loaded resolvers . . .
2022-05-11 16:10:25,364 WARN [main] [] AnnotatedArgumentBuilder - No explicit argument name given and the parameter name lost in compilation: public abstract boolean org.w3c.dom.TypeInfo.isDerivedFrom(java.lang.String,java.lang.String,int)#java.lang.String arg0. For details and possible solutions see https://github.com/leangen/graphql-spqr/wiki/Errors#missing-argument-name
2022-05-11 16:10:25,365 WARN [main] [] AnnotatedArgumentBuilder - No explicit argument name given and the parameter name lost in compilation: public abstract boolean org.w3c.dom.TypeInfo.isDerivedFrom(java.lang.String,java.lang.String,int)#java.lang.String arg1. For details and possible solutions see https://github.com/leangen/graphql-spqr/wiki/Errors#missing-argument-name
2022-05-11 16:10:25,365 WARN [main] [] AnnotatedArgumentBuilder - No explicit argument name given and the parameter name lost in compilation: public abstract boolean org.w3c.dom.TypeInfo.isDerivedFrom(java.lang.String,java.lang.String,int)#int arg2. For details and possible solutions see https://github.com/leangen/graphql-spqr/wiki/Errors#missing-argument-name
2022-05-11 16:10:25,710 INFO [main] [] extensions - registered handler at /graphql
2022-05-11 16:10:25,710 INFO [main] [] extensions - Initialized extension zm-gql: com.zimbra.graphql.resources.GQLExtension@com.zimbra.cs.extension.ZimbraExtensionClassLoader@273a5a8a
2022-05-11 16:10:25,716 INFO [main] [] extensions - registered handler at /com_zimbra_cert_manager/downloadcsr
2022-05-11 16:10:25,716 INFO [main] [] extensions - Initialized extension com_zimbra_cert_manager: com.zimbra.cert.ZimbraCertMgrExt@com.zimbra.cs.extension.ZimbraExtensionClassLoader@7f4701b1
2022-05-11 16:10:25,718 INFO [main] [] extensions - attachment scan is disabled
2022-05-11 16:10:25,718 INFO [main] [] extensions - Initialized extension clamscanner: com.zimbra.clam.ClamScannerExt@com.zimbra.cs.extension.ZimbraExtensionClassLoader@3b2553d9
2022-05-11 16:10:25,720 INFO [main] [] extensions - Initialized extension zimbrasamba: com.zimbra.ldaputils.ZimbraLDAPUtilsExtension@com.zimbra.cs.extension.ZimbraExtensionClassLoader@21b6c9c2
2022-05-11 16:10:25,733 INFO [IncomingDirectorySweeper] [] store - IncomingDirectorySweeper thread starting
2022-05-11 16:10:25,739 INFO [main] [] store - Starting up FileCache at /opt/zextras/data/tmp/uncompressed. maxFiles=2147483647, maxBytes=9223372036854775807.
2022-05-11 16:10:25,740 INFO [main] [] FileDescriptorCache - Loading settings: zimbraMailFileDescriptorCacheSize=1000.
2022-05-11 16:10:25,899 INFO [main] [] EhcacheManager - Cache 'imap-active-session-cache' created in EhcacheManager.
2022-05-11 16:10:25,968 INFO [main] [] OffHeapDiskStore - The index for data file ehcache-disk-store.data is more recent than the data file itself by 81659211ms : this is harmless.
2022-05-11 16:10:25,974 INFO [main] [] EhcacheManager - Cache 'imap-inactive-session-cache' created in EhcacheManager.
2022-05-11 16:10:25,978 INFO [main] [] AnnotationSizeOfFilter - Using regular expression provided through VM argument org.ehcache.sizeof.filters.AnnotationSizeOfFilter.pattern for IgnoreSizeOf annotation : ^.*cache\..*IgnoreSizeOf$
2022-05-11 16:10:25,988 INFO [main] [] JvmInformation - Detected JVM data model settings of: 64-Bit OpenJDK JVM with Compressed OOPs
2022-05-11 16:10:26,022 INFO [main] [] AgentLoader - Failed to attach to VM and load the agent: class java.io.IOException: Can not attach to current VM
2022-05-11 16:10:26,033 INFO [main] [] OffHeapDiskStore - The index for data file ehcache-disk-store.data is more recent than the data file itself by 5784797591ms : this is harmless.
2022-05-11 16:10:26,035 INFO [main] [] EhcacheManager - Cache 'sync-state-item-cache' created in EhcacheManager.
2022-05-11 16:10:26,053 INFO [main] [] redolog - Starting pre-startup crash recovery
2022-05-11 16:10:26,060 INFO [main] [] redolog - waiting for FileLogWriter.FsyncThread-1652274626056 to finish.
2022-05-11 16:10:26,060 INFO [FileLogWriter.FsyncThread-1652274626056] [] redolog - Starting fsync thread with interval 10
2022-05-11 16:10:26,071 INFO [FileLogWriter.FsyncThread-1652274626056] [] redolog - fsync thread exiting
2022-05-11 16:10:26,072 INFO [main] [] redolog - FileLogWriter.FsyncThread-1652274626056 finished
2022-05-11 16:10:26,074 INFO [main] [] redolog - No uncommitted transactions to redo
2022-05-11 16:10:26,074 INFO [main] [] redolog - waiting for FileLogWriter.FsyncThread-1652274626073 to finish.
2022-05-11 16:10:26,074 INFO [FileLogWriter.FsyncThread-1652274626073] [] redolog - Starting fsync thread with interval 10
2022-05-11 16:10:26,085 INFO [FileLogWriter.FsyncThread-1652274626073] [] redolog - fsync thread exiting
2022-05-11 16:10:26,085 INFO [main] [] redolog - FileLogWriter.FsyncThread-1652274626073 finished
2022-05-11 16:10:26,085 INFO [main] [] redolog - Finished pre-startup crash recovery
2022-05-11 16:10:26,093 INFO [main] [] misc - MINA setUseDirectBuffers(false)
2022-05-11 16:10:26,094 INFO [FileLogWriter.FsyncThread-1652274626086] [] redolog - Starting fsync thread with interval 10
2022-05-11 16:10:26,099 INFO [main] [] lmtp - Adding LMTP callback: com.zimbra.cs.mailbox.Notification
2022-05-11 16:10:26,100 INFO [main] [] lmtp - Adding LMTP callback: com.zimbra.cs.mailbox.QuotaWarning
2022-05-11 16:10:26,107 INFO [LmtpServer] [] TcpServer/7025 - Starting accept loop: 1 core threads, 20 max threads.
2022-05-11 16:10:26,151 INFO [main] [] pop - Starting Pop3Server on /0.0.0.0:110
2022-05-11 16:10:26,178 INFO [main] [] pop - Starting Pop3SSLServer on /0.0.0.0:995
2022-05-11 16:10:26,186 INFO [main] [] imap - Starting ImapServer on /0.0.0.0:143
2022-05-11 16:10:26,189 INFO [main] [] imap - Starting ImapSSLServer on /0.0.0.0:993
2022-05-11 16:10:26,199 INFO [main] [] scheduler - Loaded 1 scheduled data source tasks
2022-05-11 16:10:26,202 INFO [main] [] misc - Starting zimbraMailPurgeSleepInterval thread with 1m interval
2022-05-11 16:10:26,204 INFO [MailboxPurge] [] purge - Purge thread sleeping for 1800000ms before doing work.
2022-05-11 16:10:26,215 INFO [main] [] extensions - Post-Initializing extensions
2022-05-11 16:10:26,223 INFO [main] [] soap - Servlet AdminServlet starting up
2022-05-11 16:10:26,257 INFO [main] [] soap - Adding service AdminService to AdminServlet
2022-05-11 16:10:26,280 INFO [main] [] soap - Adding service AccountService to AdminServlet
2022-05-11 16:10:26,281 INFO [main] [] soap - Adding service MailService to AdminServlet
2022-05-11 16:10:26,281 INFO [main] [] soap - Adding service ZimbraCertMgrService to AdminServlet
2022-05-11 16:10:26,283 INFO [main] [] soap - Adding service ZimbraLDAPUtilsService to AdminServlet
2022-05-11 16:10:26,286 INFO [main] [] account - Servlet CertAuthServlet starting up
2022-05-11 16:10:26,287 INFO [main] [] ContentServlet - Servlet ContentServlet starting up
2022-05-11 16:10:26,289 INFO [main] [] account - Servlet ExternalUserProvServlet starting up
2022-05-11 16:10:26,291 INFO [main] [] account - Servlet PreAuthServlet starting up
2022-05-11 16:10:26,292 INFO [main] [] PublicICalServlet - Servlet PublicICalServlet starting up
2022-05-11 16:10:26,292 INFO [main] [] account - Servlet SpnegoAuthServlet starting up
2022-05-11 16:10:26,298 INFO [main] [] UserServlet - Starting up
2022-05-11 16:10:26,302 INFO [main] [] FileUploadServlet - Servlet FileUploadServlet starting up
2022-05-11 16:10:26,303 INFO [main] [] StatsImageServlet - Servlet StatsImageServlet starting up
2022-05-11 16:10:26,320 INFO [main] [] AutoDiscoverServlet - Starting up
2022-05-11 16:10:26,649 INFO [main] [] filter - Destroying CSRF filter.
2022-05-11 16:10:26,650 INFO [main] [] misc - ZimbraInvalidLoginFilter destroyed
2022-05-11 16:10:26,651 INFO [main] [] AutoDiscoverServlet - Shutting down
2022-05-11 16:10:26,653 INFO [main] [] StatsImageServlet - Servlet StatsImageServlet shutting down
2022-05-11 16:10:26,653 INFO [main] [] FileUploadServlet - Servlet FileUploadServlet shutting down
2022-05-11 16:10:26,653 INFO [main] [] PublicICalServlet - Servlet PublicICalServlet shutting down
2022-05-11 16:10:26,653 INFO [main] [] account - Servlet SpnegoAuthServlet shutting down
2022-05-11 16:10:26,653 INFO [main] [] account - Servlet CertAuthServlet shutting down
2022-05-11 16:10:26,653 INFO [main] [] account - Servlet ExternalUserProvServlet shutting down
2022-05-11 16:10:26,654 INFO [main] [] account - Servlet PreAuthServlet shutting down
2022-05-11 16:10:26,654 INFO [main] [] UserServlet - Shutting down
2022-05-11 16:10:26,654 INFO [main] [] ContentServlet - Servlet ContentServlet shutting down
2022-05-11 16:10:26,655 INFO [main] [] soap - Servlet AdminServlet shutting down
2022-05-11 16:10:26,655 INFO [main] [] autoprov - shutdown() called, but auto provision thread is not running.
2022-05-11 16:10:26,655 INFO [MailboxPurge] [] purge - Shutting down purge thread.
2022-05-11 16:10:26,657 INFO [main] [] TcpServer/7025 - LmtpServer initiating shutdown
2022-05-11 16:10:26,662 INFO [LmtpServer] [] TcpServer/7025 - finished accept loop
2022-05-11 16:10:26,663 INFO [main] [] TcpServer/7025 - LmtpServer shutting down idle thread pool
2022-05-11 16:10:26,663 INFO [main] [] pop - Initiating shutdown
2022-05-11 16:10:26,665 INFO [main] [] pop - Initiating shutdown
2022-05-11 16:10:26,666 INFO [main] [] imap - Initiating shutdown
2022-05-11 16:10:26,666 INFO [main] [] imap - Initiating shutdown
2022-05-11 16:10:26,670 INFO [main] [] index - Using Index Store LuceneIndex
2022-05-11 16:10:26,678 INFO [main] [] redolog - waiting for FileLogWriter.FsyncThread-1652274626086 to finish.
2022-05-11 16:10:26,684 INFO [FileLogWriter.FsyncThread-1652274626086] [] redolog - fsync thread exiting
2022-05-11 16:10:26,684 INFO [main] [] redolog - FileLogWriter.FsyncThread-1652274626086 finished
2022-05-11 16:10:26,687 INFO [main] [] redolog - Logged: 0 items, 0.0ms/item
2022-05-11 16:10:26,687 INFO [main] [] extensions - Destroying extensions
2022-05-11 16:10:26,691 INFO [main] [] extensions - Destroyed extension nginx-lookup: com.zimbra.cs.nginx.NginxLookupExtension@com.zimbra.cs.extension.ZimbraExtensionClassLoader@57ad1178
2022-05-11 16:10:26,692 INFO [main] [] extensions - Destroyed extension zm-gql: com.zimbra.graphql.resources.GQLExtension@com.zimbra.cs.extension.ZimbraExtensionClassLoader@273a5a8a
2022-05-11 16:10:26,692 INFO [main] [] extensions - Destroyed extension com_zimbra_cert_manager: com.zimbra.cert.ZimbraCertMgrExt@com.zimbra.cs.extension.ZimbraExtensionClassLoader@7f4701b1
2022-05-11 16:10:26,692 INFO [main] [] extensions - Destroyed extension clamscanner: com.zimbra.clam.ClamScannerExt@com.zimbra.cs.extension.ZimbraExtensionClassLoader@3b2553d9
2022-05-11 16:10:26,692 INFO [main] [] extensions - Destroyed extension zimbrasamba: com.zimbra.ldaputils.ZimbraLDAPUtilsExtension@com.zimbra.cs.extension.ZimbraExtensionClassLoader@21b6c9c2
2022-05-11 16:10:26,702 INFO [main] [] EhcacheManager - Cache 'sync-state-item-cache' removed from EhcacheManager.
2022-05-11 16:10:26,729 INFO [main] [] EhcacheManager - Cache 'imap-active-session-cache' removed from EhcacheManager.
2022-05-11 16:10:26,748 INFO [main] [] EhcacheManager - Cache 'imap-inactive-session-cache' removed from EhcacheManager.
2022-05-11 16:10:26,749 INFO [IncomingDirectorySweeper] [] store - IncomingDirectorySweeper thread exiting
2022-05-11 16:10:26,749 INFO [main] [] ZimbraHttpConnectionManager - shutting down http client idle connection reaper thread
2022-05-11 16:10:26,750 INFO [main] [] ZimbraHttpConnectionManager - shutting down http client idle connection reaper thread
2022-05-11 16:10:26,755 INFO [main] [] soap - Servlet SoapServlet shutting down
2022-05-11 16:10:29,614 INFO [main] [] misc - DoSFilter: Configured whitelist IPs = 100.66.6.205,127.0.0.1,100.66.6.61,127.0.0.1,::1,0:0:0:0:0:0:0:1
2022-05-11 16:10:29,627 INFO [main] [] misc - DoSFilter: Configured whitelist IPs = 100.66.6.205,127.0.0.1,100.66.6.61,127.0.0.1,::1,0:0:0:0:0:0:0:1
2022-05-11 16:10:29,634 INFO [main] [] misc - ZimbraInvalidLoginFilter initialized
2022-05-11 16:10:29,643 INFO [main] [] misc - Initialized with /service:max=80%, /zimbra:max=15%, /zimbraAdmin:max=5%
2022-05-11 16:10:29,644 INFO [main] [] misc - Thread pool was configured to max=250
2022-05-11 16:10:29,657 INFO [main] [] misc - CSRF filter was initialized: CSRFAllowedRefHost: []
2022-05-11 16:10:29,767 INFO [main] [] soap - Servlet SoapServlet starting up
2022-05-11 16:10:29,779 INFO [main] [] soap - Adding service AccountService to SoapServlet
2022-05-11 16:10:29,817 INFO [main] [] soap - Adding service MailService to SoapServlet
2022-05-11 16:10:29,862 INFO [main] [] misc - version=22.4.0_ZEXTRAS_202204 release=agent builddate=20220415-1401 buildhost=${zimbra.server.hostname}
2022-05-11 16:10:29,862 INFO [main] [] misc - LANG environment is set to: C
2022-05-11 16:10:29,862 INFO [main] [] misc - System property java.home=/opt/zextras/common/lib/jvm/java
2022-05-11 16:10:29,862 INFO [main] [] misc - System property java.runtime.version=13.0.2+8
2022-05-11 16:10:29,863 INFO [main] [] misc - System property java.version=13.0.2
2022-05-11 16:10:29,863 INFO [main] [] misc - System property java.vm.info=mixed mode, sharing
2022-05-11 16:10:29,863 INFO [main] [] misc - System property java.vm.name=OpenJDK 64-Bit Server VM
2022-05-11 16:10:29,863 INFO [main] [] misc - System property java.vm.version=13.0.2+8
2022-05-11 16:10:29,863 INFO [main] [] misc - System property os.arch=amd64
2022-05-11 16:10:29,863 INFO [main] [] misc - System property os.name=Linux
2022-05-11 16:10:29,863 INFO [main] [] misc - System property os.version=5.4.0-110-generic
2022-05-11 16:10:29,863 INFO [main] [] misc - System property sun.arch.data.model=64
2022-05-11 16:10:29,863 INFO [main] [] misc - System property sun.cpu.endian=little
2022-05-11 16:10:29,863 INFO [main] [] misc - System property sun.cpu.isalist=null
2022-05-11 16:10:29,864 INFO [main] [] misc - System property sun.os.patch.level=null
2022-05-11 16:10:29,876 INFO [main] [] system - Setting mysql connector property: maxActive=100
2022-05-11 16:10:29,876 INFO [main] [] system - Setting mysql connector property: maxActive=100
2022-05-11 16:10:29,938 INFO [main] [] sqltrace - Setting slow SQL threshold to 2000ms.
2022-05-11 16:10:30,194 INFO [main] [] ZimbraHttpConnectionManager - Starting http client idle connection reaper thread for Internal http client connection manager - reaper sleep interval=300000, reaper connection timeout=300000
2022-05-11 16:10:30,195 INFO [main] [] ZimbraHttpConnectionManager - Starting http client idle connection reaper thread for External http client connection manager - reaper sleep interval=300000, reaper connection timeout=300000
2022-05-11 16:10:30,199 WARN [main] [] ephemeral - Replacing ephemeral factory class 'com.zimbra.cs.ephemeral.LdapEphemeralStore$Factory' registered for 'ldap' with 'com.zimbra.cs.ephemeral.LdapEphemeralStore$Factory'
2022-05-11 16:10:30,201 INFO [main] [] extensions - Loading extensions from /opt/zextras/lib/ext
2022-05-11 16:10:30,203 INFO [main] [] extensions - extension com.zimbra.cs.nginx.NginxLookupExtension found in /opt/zextras/lib/ext/nginx-lookup/nginx-lookup.jar
2022-05-11 16:10:30,203 INFO [main] [] extensions - extension com.zimbra.graphql.resources.GQLExtension found in /opt/zextras/lib/ext/zm-gql/zmgql.jar
2022-05-11 16:10:30,203 INFO [main] [] extensions - extension com.zimbra.cert.ZimbraCertMgrExt found in /opt/zextras/lib/ext/com_zimbra_cert_manager/com_zimbra_cert_manager.jar
2022-05-11 16:10:30,203 WARN [main] [] extensions - no Zimbra-Extension-Class found, ignored: /opt/zextras/lib/ext/openidconsumer
2022-05-11 16:10:30,204 INFO [main] [] extensions - extension com.zimbra.clam.ClamScannerExt found in /opt/zextras/lib/ext/clamscanner/clamscanner.jar
2022-05-11 16:10:30,204 INFO [main] [] extensions - extension com.zimbra.ldaputils.ZimbraLDAPUtilsExtension found in /opt/zextras/lib/ext/zimbraldaputils/zimbraldaputils.jar
2022-05-11 16:10:30,204 INFO [main] [] extensions - Initializing extensions
2022-05-11 16:10:30,210 INFO [main] [] extensions - registered handler at /nginx-lookup
2022-05-11 16:10:30,212 INFO [main] [] extensions - Initialized extension nginx-lookup: com.zimbra.cs.nginx.NginxLookupExtension@com.zimbra.cs.extension.ZimbraExtensionClassLoader@7cac93fe
2022-05-11 16:10:30,213 INFO [main] [] extensions - Registering zm-gql
2022-05-11 16:10:30,357 INFO [main] [] extensions - Loading standard graphql schema builder.
2022-05-11 16:10:30,359 INFO [main] [] extensions - Loading com.zimbra.graphql.repositories.impl.ZXMLAccountRepository . . .
2022-05-11 16:10:30,360 INFO [main] [] extensions - Loading com.zimbra.graphql.repositories.impl.ZXMLAuthRepository . . .
2022-05-11 16:10:30,360 INFO [main] [] extensions - Loading com.zimbra.graphql.repositories.impl.ZNativeAuthRepository . . .
2022-05-11 16:10:30,361 INFO [main] [] extensions - Loading com.zimbra.graphql.repositories.impl.ZXMLCalendarRepository . . .
2022-05-11 16:10:30,363 INFO [main] [] extensions - Loading com.zimbra.graphql.repositories.impl.ZXMLContactRepository . . .
2022-05-11 16:10:30,364 INFO [main] [] extensions - Loading com.zimbra.graphql.repositories.impl.ZXMLFolderRepository . . .
2022-05-11 16:10:30,365 INFO [main] [] extensions - Loading com.zimbra.graphql.repositories.impl.ZXMLMessageRepository . . .
2022-05-11 16:10:30,366 INFO [main] [] extensions - Loading com.zimbra.graphql.repositories.impl.ZXMLSearchRepository . . .
2022-05-11 16:10:30,367 INFO [main] [] extensions - Loading com.zimbra.graphql.repositories.impl.ZXMLTaskRepository . . .
2022-05-11 16:10:30,367 INFO [main] [] extensions - Generating schema with loaded resolvers . . .
2022-05-11 16:10:30,962 WARN [main] [] AnnotatedArgumentBuilder - No explicit argument name given and the parameter name lost in compilation: public abstract boolean org.w3c.dom.TypeInfo.isDerivedFrom(java.lang.String,java.lang.String,int)#java.lang.String arg0. For details and possible solutions see https://github.com/leangen/graphql-spqr/wiki/Errors#missing-argument-name
2022-05-11 16:10:30,962 WARN [main] [] AnnotatedArgumentBuilder - No explicit argument name given and the parameter name lost in compilation: public abstract boolean org.w3c.dom.TypeInfo.isDerivedFrom(java.lang.String,java.lang.String,int)#java.lang.String arg1. For details and possible solutions see https://github.com/leangen/graphql-spqr/wiki/Errors#missing-argument-name
2022-05-11 16:10:30,962 WARN [main] [] AnnotatedArgumentBuilder - No explicit argument name given and the parameter name lost in compilation: public abstract boolean org.w3c.dom.TypeInfo.isDerivedFrom(java.lang.String,java.lang.String,int)#int arg2. For details and possible solutions see https://github.com/leangen/graphql-spqr/wiki/Errors#missing-argument-name
2022-05-11 16:10:31,303 INFO [main] [] extensions - registered handler at /graphql
2022-05-11 16:10:31,303 INFO [main] [] extensions - Initialized extension zm-gql: com.zimbra.graphql.resources.GQLExtension@com.zimbra.cs.extension.ZimbraExtensionClassLoader@2b1cd7bc
2022-05-11 16:10:31,308 INFO [main] [] extensions - registered handler at /com_zimbra_cert_manager/downloadcsr
2022-05-11 16:10:31,309 INFO [main] [] extensions - Initialized extension com_zimbra_cert_manager: com.zimbra.cert.ZimbraCertMgrExt@com.zimbra.cs.extension.ZimbraExtensionClassLoader@3f5156a6
2022-05-11 16:10:31,310 INFO [main] [] extensions - attachment scan is disabled
2022-05-11 16:10:31,310 INFO [main] [] extensions - Initialized extension clamscanner: com.zimbra.clam.ClamScannerExt@com.zimbra.cs.extension.ZimbraExtensionClassLoader@2f14b0f6
2022-05-11 16:10:31,313 INFO [main] [] extensions - Initialized extension zimbrasamba: com.zimbra.ldaputils.ZimbraLDAPUtilsExtension@com.zimbra.cs.extension.ZimbraExtensionClassLoader@66de00f2
2022-05-11 16:10:31,326 INFO [IncomingDirectorySweeper] [] store - IncomingDirectorySweeper thread starting
2022-05-11 16:10:31,332 INFO [main] [] store - Starting up FileCache at /opt/zextras/data/tmp/uncompressed. maxFiles=2147483647, maxBytes=9223372036854775807.
2022-05-11 16:10:31,334 INFO [main] [] FileDescriptorCache - Loading settings: zimbraMailFileDescriptorCacheSize=1000.
2022-05-11 16:10:31,479 INFO [main] [] EhcacheManager - Cache 'imap-active-session-cache' created in EhcacheManager.
2022-05-11 16:10:31,543 INFO [main] [] OffHeapDiskStore - The index for data file ehcache-disk-store.data is more recent than the data file itself by 81732430ms : this is harmless.
2022-05-11 16:10:31,549 INFO [main] [] EhcacheManager - Cache 'imap-inactive-session-cache' created in EhcacheManager.
2022-05-11 16:10:31,553 INFO [main] [] AnnotationSizeOfFilter - Using regular expression provided through VM argument org.ehcache.sizeof.filters.AnnotationSizeOfFilter.pattern for IgnoreSizeOf annotation : ^.*cache\..*IgnoreSizeOf$
2022-05-11 16:10:31,562 INFO [main] [] JvmInformation - Detected JVM data model settings of: 64-Bit OpenJDK JVM with Compressed OOPs
2022-05-11 16:10:31,590 INFO [main] [] AgentLoader - Failed to attach to VM and load the agent: class java.io.IOException: Can not attach to current VM
2022-05-11 16:10:31,599 INFO [main] [] OffHeapDiskStore - The index for data file ehcache-disk-store.data is more recent than the data file itself by 5784870807ms : this is harmless.
2022-05-11 16:10:31,601 INFO [main] [] EhcacheManager - Cache 'sync-state-item-cache' created in EhcacheManager.
2022-05-11 16:10:31,620 INFO [main] [] redolog - Starting pre-startup crash recovery
2022-05-11 16:10:31,628 INFO [main] [] redolog - waiting for FileLogWriter.FsyncThread-1652274631624 to finish.
2022-05-11 16:10:31,629 INFO [FileLogWriter.FsyncThread-1652274631624] [] redolog - Starting fsync thread with interval 10
2022-05-11 16:10:31,640 INFO [FileLogWriter.FsyncThread-1652274631624] [] redolog - fsync thread exiting
2022-05-11 16:10:31,640 INFO [main] [] redolog - FileLogWriter.FsyncThread-1652274631624 finished
2022-05-11 16:10:31,642 INFO [main] [] redolog - No uncommitted transactions to redo
2022-05-11 16:10:31,642 INFO [FileLogWriter.FsyncThread-1652274631641] [] redolog - Starting fsync thread with interval 10
2022-05-11 16:10:31,642 INFO [main] [] redolog - waiting for FileLogWriter.FsyncThread-1652274631641 to finish.
2022-05-11 16:10:31,653 INFO [FileLogWriter.FsyncThread-1652274631641] [] redolog - fsync thread exiting
2022-05-11 16:10:31,653 INFO [main] [] redolog - FileLogWriter.FsyncThread-1652274631641 finished
2022-05-11 16:10:31,654 INFO [main] [] redolog - Finished pre-startup crash recovery
2022-05-11 16:10:31,657 INFO [FileLogWriter.FsyncThread-1652274631655] [] redolog - Starting fsync thread with interval 10
2022-05-11 16:10:31,663 INFO [main] [] misc - MINA setUseDirectBuffers(false)
2022-05-11 16:10:31,668 INFO [main] [] lmtp - Adding LMTP callback: com.zimbra.cs.mailbox.Notification
2022-05-11 16:10:31,669 INFO [main] [] lmtp - Adding LMTP callback: com.zimbra.cs.mailbox.QuotaWarning
2022-05-11 16:10:31,678 INFO [LmtpServer] [] TcpServer/7025 - Starting accept loop: 1 core threads, 20 max threads.
2022-05-11 16:10:31,722 INFO [main] [] pop - Starting Pop3Server on /0.0.0.0:110
2022-05-11 16:10:31,747 INFO [main] [] pop - Starting Pop3SSLServer on /0.0.0.0:995
2022-05-11 16:10:31,754 INFO [main] [] imap - Starting ImapServer on /0.0.0.0:143
2022-05-11 16:10:31,759 INFO [main] [] imap - Starting ImapSSLServer on /0.0.0.0:993
2022-05-11 16:10:31,769 INFO [main] [] scheduler - Loaded 1 scheduled data source tasks
2022-05-11 16:10:31,774 INFO [main] [] misc - Starting zimbraMailPurgeSleepInterval thread with 1m interval
2022-05-11 16:10:31,777 INFO [MailboxPurge] [] purge - Purge thread sleeping for 1800000ms before doing work.
2022-05-11 16:10:31,784 INFO [main] [] extensions - Post-Initializing extensions
2022-05-11 16:10:31,791 INFO [main] [] soap - Servlet AdminServlet starting up
2022-05-11 16:10:31,822 INFO [main] [] soap - Adding service AdminService to AdminServlet
2022-05-11 16:10:31,843 INFO [main] [] soap - Adding service AccountService to AdminServlet
2022-05-11 16:10:31,843 INFO [main] [] soap - Adding service MailService to AdminServlet
2022-05-11 16:10:31,843 INFO [main] [] soap - Adding service ZimbraCertMgrService to AdminServlet
2022-05-11 16:10:31,845 INFO [main] [] soap - Adding service ZimbraLDAPUtilsService to AdminServlet
2022-05-11 16:10:31,847 INFO [main] [] account - Servlet CertAuthServlet starting up
2022-05-11 16:10:31,849 INFO [main] [] ContentServlet - Servlet ContentServlet starting up
2022-05-11 16:10:31,850 INFO [main] [] account - Servlet ExternalUserProvServlet starting up
2022-05-11 16:10:31,852 INFO [main] [] account - Servlet PreAuthServlet starting up
2022-05-11 16:10:31,853 INFO [main] [] PublicICalServlet - Servlet PublicICalServlet starting up
2022-05-11 16:10:31,853 INFO [main] [] account - Servlet SpnegoAuthServlet starting up
2022-05-11 16:10:31,859 INFO [main] [] UserServlet - Starting up
2022-05-11 16:10:31,863 INFO [main] [] FileUploadServlet - Servlet FileUploadServlet starting up
2022-05-11 16:10:31,864 INFO [main] [] StatsImageServlet - Servlet StatsImageServlet starting up
2022-05-11 16:10:31,880 INFO [main] [] AutoDiscoverServlet - Starting up
2022-05-11 16:10:32,195 INFO [main] [] filter - Destroying CSRF filter.
2022-05-11 16:10:32,196 INFO [main] [] misc - ZimbraInvalidLoginFilter destroyed
2022-05-11 16:10:32,197 INFO [main] [] AutoDiscoverServlet - Shutting down
2022-05-11 16:10:32,197 INFO [main] [] StatsImageServlet - Servlet StatsImageServlet shutting down
2022-05-11 16:10:32,199 INFO [main] [] FileUploadServlet - Servlet FileUploadServlet shutting down
2022-05-11 16:10:32,200 INFO [main] [] PublicICalServlet - Servlet PublicICalServlet shutting down
2022-05-11 16:10:32,200 INFO [main] [] account - Servlet SpnegoAuthServlet shutting down
2022-05-11 16:10:32,200 INFO [main] [] account - Servlet CertAuthServlet shutting down
2022-05-11 16:10:32,200 INFO [main] [] account - Servlet ExternalUserProvServlet shutting down
2022-05-11 16:10:32,200 INFO [main] [] account - Servlet PreAuthServlet shutting down
2022-05-11 16:10:32,200 INFO [main] [] UserServlet - Shutting down
2022-05-11 16:10:32,200 INFO [main] [] ContentServlet - Servlet ContentServlet shutting down
2022-05-11 16:10:32,201 INFO [main] [] soap - Servlet AdminServlet shutting down
2022-05-11 16:10:32,201 INFO [MailboxPurge] [] purge - Shutting down purge thread.
2022-05-11 16:10:32,201 INFO [main] [] autoprov - shutdown() called, but auto provision thread is not running.
2022-05-11 16:10:32,203 INFO [main] [] TcpServer/7025 - LmtpServer initiating shutdown
2022-05-11 16:10:32,203 INFO [main] [] TcpServer/7025 - LmtpServer shutting down idle thread pool
2022-05-11 16:10:32,203 INFO [main] [] pop - Initiating shutdown
2022-05-11 16:10:32,203 INFO [LmtpServer] [] TcpServer/7025 - finished accept loop
2022-05-11 16:10:32,204 INFO [main] [] pop - Initiating shutdown
2022-05-11 16:10:32,206 INFO [main] [] imap - Initiating shutdown
2022-05-11 16:10:32,206 INFO [main] [] imap - Initiating shutdown
2022-05-11 16:10:32,210 INFO [main] [] index - Using Index Store LuceneIndex
2022-05-11 16:10:32,218 INFO [main] [] redolog - waiting for FileLogWriter.FsyncThread-1652274631655 to finish.
2022-05-11 16:10:32,223 INFO [FileLogWriter.FsyncThread-1652274631655] [] redolog - fsync thread exiting
2022-05-11 16:10:32,223 INFO [main] [] redolog - FileLogWriter.FsyncThread-1652274631655 finished
2022-05-11 16:10:32,226 INFO [main] [] redolog - Logged: 0 items, 0.0ms/item
2022-05-11 16:10:32,226 INFO [main] [] extensions - Destroying extensions
2022-05-11 16:10:32,228 INFO [main] [] extensions - Destroyed extension nginx-lookup: com.zimbra.cs.nginx.NginxLookupExtension@com.zimbra.cs.extension.ZimbraExtensionClassLoader@7cac93fe
2022-05-11 16:10:32,228 INFO [main] [] extensions - Destroyed extension zm-gql: com.zimbra.graphql.resources.GQLExtension@com.zimbra.cs.extension.ZimbraExtensionClassLoader@2b1cd7bc
2022-05-11 16:10:32,228 INFO [main] [] extensions - Destroyed extension com_zimbra_cert_manager: com.zimbra.cert.ZimbraCertMgrExt@com.zimbra.cs.extension.ZimbraExtensionClassLoader@3f5156a6
2022-05-11 16:10:32,228 INFO [main] [] extensions - Destroyed extension clamscanner: com.zimbra.clam.ClamScannerExt@com.zimbra.cs.extension.ZimbraExtensionClassLoader@2f14b0f6
2022-05-11 16:10:32,228 INFO [main] [] extensions - Destroyed extension zimbrasamba: com.zimbra.ldaputils.ZimbraLDAPUtilsExtension@com.zimbra.cs.extension.ZimbraExtensionClassLoader@66de00f2
2022-05-11 16:10:32,239 INFO [main] [] EhcacheManager - Cache 'sync-state-item-cache' removed from EhcacheManager.
2022-05-11 16:10:32,261 INFO [main] [] EhcacheManager - Cache 'imap-active-session-cache' removed from EhcacheManager.
2022-05-11 16:10:32,286 INFO [main] [] EhcacheManager - Cache 'imap-inactive-session-cache' removed from EhcacheManager.
2022-05-11 16:10:32,287 INFO [IncomingDirectorySweeper] [] store - IncomingDirectorySweeper thread exiting
2022-05-11 16:10:32,287 INFO [main] [] ZimbraHttpConnectionManager - shutting down http client idle connection reaper thread
2022-05-11 16:10:32,287 INFO [main] [] ZimbraHttpConnectionManager - shutting down http client idle connection reaper thread
2022-05-11 16:10:32,293 INFO [main] [] soap - Servlet SoapServlet shutting down
zmmailboxd.out:
[0.015s][info][gc] Using G1
OpenJDK 64-Bit Server VM warning: .hotspot_compiler file is present but has been ignored. Run with -XX:CompileCommandFile=.hotspot_compiler to load the file.
[0.026s][info][gc] Periodic GC disabled
SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
SLF4J: Defaulting to no-operation (NOP) logger implementation
SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details.
JettyMonitor monitoring thread pool QueuedThreadPool[qtp1150284200]@448ff1a8{STOPPED,10<=0<=250,i=0,r=-1,q=0}[NO_TRY]
Zimbra server reserving server socket port=110 bindaddr=null ssl=false
Zimbra server reserving server socket port=995 bindaddr=null ssl=false
Zimbra server reserving server socket port=143 bindaddr=null ssl=false
Zimbra server reserving server socket port=993 bindaddr=null ssl=false
Zimbra server reserving server socket port=7025 bindaddr=null ssl=false
[1.669s][info][gc] GC(0) Pause Young (Normal) (G1 Evacuation Pause) 300M->23M(1996M) 26.669ms
log4j:ERROR Could not find value for key log4j.appender.EWS
log4j:ERROR Could not instantiate appender named "EWS".
[2.439s][info][gc] GC(1) Pause Young (Concurrent Start) (Metadata GC Threshold) 315M->35M(1996M) 50.597ms
[2.439s][info][gc] GC(2) Concurrent Cycle
[2.461s][info][gc] GC(2) Pause Remark 39M->39M(1996M) 2.240ms
[2.478s][info][gc] GC(2) Pause Cleanup 39M->39M(1996M) 0.231ms
[2.494s][info][gc] GC(2) Concurrent Cycle 55.496ms
log4j:ERROR Could not find value for key log4j.appender.EWS
log4j:ERROR Could not instantiate appender named "EWS".
log4j:ERROR Could not find value for key log4j.appender.EWS
log4j:ERROR Could not instantiate appender named "EWS".
[3.435s][info][gc] GC(3) Pause Young (Concurrent Start) (Metadata GC Threshold) 174M->41M(1996M) 26.793ms
[3.437s][info][gc] GC(4) Concurrent Cycle
[3.473s][info][gc] GC(4) Pause Remark 44M->44M(1996M) 8.969ms
[3.487s][info][gc] GC(4) Pause Cleanup 46M->46M(1996M) 0.297ms
[3.502s][info][gc] GC(4) Concurrent Cycle 65.093ms
log4j:ERROR Could not find value for key log4j.appender.EWS
log4j:ERROR Could not instantiate appender named "EWS".
java.lang.reflect.InvocationTargetException
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:567)
at org.eclipse.jetty.start.Main.invokeMain(Main.java:218)
at org.eclipse.jetty.start.Main.start(Main.java:491)
at org.eclipse.jetty.start.Main.main(Main.java:77)
Caused by: java.security.PrivilegedActionException: org.xml.sax.SAXParseException; lineNumber: 1; columnNumber: 1; Content is not allowed in prolog.
at java.base/java.security.AccessController.doPrivileged(AccessController.java:558)
at org.eclipse.jetty.xml.XmlConfiguration.main(XmlConfiguration.java:1857)
... 7 more
Caused by: org.xml.sax.SAXParseException; lineNumber: 1; columnNumber: 1; Content is not allowed in prolog.
at org.apache.xerces.util.ErrorHandlerWrapper.createSAXParseException(Unknown Source)
at org.apache.xerces.util.ErrorHandlerWrapper.fatalError(Unknown Source)
at org.apache.xerces.impl.XMLErrorReporter.reportError(Unknown Source)
at org.apache.xerces.impl.XMLErrorReporter.reportError(Unknown Source)
at org.apache.xerces.impl.XMLErrorReporter.reportError(Unknown Source)
at org.apache.xerces.impl.XMLScanner.reportFatalError(Unknown Source)
at org.apache.xerces.impl.XMLDocumentScannerImpl$PrologDispatcher.dispatch(Unknown Source)
at org.apache.xerces.impl.XMLDocumentFragmentScannerImpl.scanDocument(Unknown Source)
at org.apache.xerces.parsers.XML11Configuration.parse(Unknown Source)
at org.apache.xerces.parsers.XML11Configuration.parse(Unknown Source)
at org.apache.xerces.parsers.XMLParser.parse(Unknown Source)
at org.apache.xerces.parsers.AbstractSAXParser.parse(Unknown Source)
at org.apache.xerces.jaxp.SAXParserImpl$JAXPSAXParser.parse(Unknown Source)
at org.apache.xerces.jaxp.SAXParserImpl.parse(Unknown Source)
at org.eclipse.jetty.xml.XmlParser.parse(XmlParser.java:244)
at org.eclipse.jetty.xml.XmlConfiguration.<init>(XmlConfiguration.java:226)
at org.eclipse.jetty.xml.XmlConfiguration.<init>(XmlConfiguration.java:242)
at org.eclipse.jetty.plus.webapp.EnvConfiguration.configure(EnvConfiguration.java:116)
at org.eclipse.jetty.webapp.WebAppContext.configure(WebAppContext.java:498)
at org.eclipse.jetty.webapp.WebAppContext.startContext(WebAppContext.java:1409)
at org.eclipse.jetty.server.handler.ContextHandler.doStart(ContextHandler.java:910)
at org.eclipse.jetty.servlet.ServletContextHandler.doStart(ServletContextHandler.java:288)
at org.eclipse.jetty.webapp.WebAppContext.doStart(WebAppContext.java:524)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:117)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:117)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:110)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:110)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.server.handler.gzip.GzipHandler.doStart(GzipHandler.java:426)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:110)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.server.handler.DebugHandler.doStart(DebugHandler.java:140)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.server.Server.start(Server.java:423)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:110)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.server.Server.doStart(Server.java:387)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.xml.XmlConfiguration.lambda$main$3(XmlConfiguration.java:1907)
at java.base/java.security.AccessController.doPrivileged(AccessController.java:554)
... 8 more
java.lang.reflect.InvocationTargetException
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:567)
at org.eclipse.jetty.start.Main.invokeMain(Main.java:218)
at org.eclipse.jetty.start.Main.start(Main.java:491)
at org.eclipse.jetty.start.Main.main(Main.java:77)
Caused by: java.security.PrivilegedActionException: org.xml.sax.SAXParseException; lineNumber: 1; columnNumber: 1; Content is not allowed in prolog.
at java.base/java.security.AccessController.doPrivileged(AccessController.java:558)
at org.eclipse.jetty.xml.XmlConfiguration.main(XmlConfiguration.java:1857)
... 7 more
Caused by: org.xml.sax.SAXParseException; lineNumber: 1; columnNumber: 1; Content is not allowed in prolog.
at org.apache.xerces.util.ErrorHandlerWrapper.createSAXParseException(Unknown Source)
at org.apache.xerces.util.ErrorHandlerWrapper.fatalError(Unknown Source)
at org.apache.xerces.impl.XMLErrorReporter.reportError(Unknown Source)
at org.apache.xerces.impl.XMLErrorReporter.reportError(Unknown Source)
at org.apache.xerces.impl.XMLErrorReporter.reportError(Unknown Source)
at org.apache.xerces.impl.XMLScanner.reportFatalError(Unknown Source)
at org.apache.xerces.impl.XMLDocumentScannerImpl$PrologDispatcher.dispatch(Unknown Source)
at org.apache.xerces.impl.XMLDocumentFragmentScannerImpl.scanDocument(Unknown Source)
at org.apache.xerces.parsers.XML11Configuration.parse(Unknown Source)
at org.apache.xerces.parsers.XML11Configuration.parse(Unknown Source)
at org.apache.xerces.parsers.XMLParser.parse(Unknown Source)
at org.apache.xerces.parsers.AbstractSAXParser.parse(Unknown Source)
at org.apache.xerces.jaxp.SAXParserImpl$JAXPSAXParser.parse(Unknown Source)
at org.apache.xerces.jaxp.SAXParserImpl.parse(Unknown Source)
at org.eclipse.jetty.xml.XmlParser.parse(XmlParser.java:244)
at org.eclipse.jetty.xml.XmlConfiguration.<init>(XmlConfiguration.java:226)
at org.eclipse.jetty.xml.XmlConfiguration.<init>(XmlConfiguration.java:242)
at org.eclipse.jetty.plus.webapp.EnvConfiguration.configure(EnvConfiguration.java:116)
at org.eclipse.jetty.webapp.WebAppContext.configure(WebAppContext.java:498)
at org.eclipse.jetty.webapp.WebAppContext.startContext(WebAppContext.java:1409)
at org.eclipse.jetty.server.handler.ContextHandler.doStart(ContextHandler.java:910)
at org.eclipse.jetty.servlet.ServletContextHandler.doStart(ServletContextHandler.java:288)
at org.eclipse.jetty.webapp.WebAppContext.doStart(WebAppContext.java:524)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:117)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:117)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:110)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:110)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.server.handler.gzip.GzipHandler.doStart(GzipHandler.java:426)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:110)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.server.handler.DebugHandler.doStart(DebugHandler.java:140)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.server.Server.start(Server.java:423)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:110)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.server.Server.doStart(Server.java:387)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.xml.XmlConfiguration.lambda$main$3(XmlConfiguration.java:1907)
at java.base/java.security.AccessController.doPrivileged(AccessController.java:554)
... 8 more
Usage: java -jar $JETTY_HOME/start.jar [options] [properties] [configs]
java -jar $JETTY_HOME/start.jar --help # for more information
05/11/2022 22:23
Hi Aynur,
i suggest the following operations:
1) as root open this file ( nano or vi )
/opt/zextras/conf/log4j.properties.in
2) remove all the lines that start with this syntax
log4j.appender.EWS
3) remove these two lines
log4j.additivity.zimbra.ews=false log4j.logger.zimbra.ews=INFO,EWS
4) save file
5) restart the mailbox service
su - zextras zmmailboxdctl restart
Topic starter
05/12/2022 13:48
I deleted the lines and restarted the service but the problem is not solved.
I still can't run the service.
zmmailboxd.out:
root@mail:/opt/zextras/log# cat zmmailboxd.out
[0.017s][info][gc] Using G1
OpenJDK 64-Bit Server VM warning: .hotspot_compiler file is present but has been ignored. Run with -XX:CompileCommandFile=.hotspot_compiler to load the file.
[0.030s][info][gc] Periodic GC disabled
SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
SLF4J: Defaulting to no-operation (NOP) logger implementation
SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details.
JettyMonitor monitoring thread pool QueuedThreadPool[qtp1150284200]@448ff1a8{STOPPED,10<=0<=250,i=0,r=-1,q=0}[NO_TRY]
Zimbra server reserving server socket port=110 bindaddr=null ssl=false
Zimbra server reserving server socket port=995 bindaddr=null ssl=false
Zimbra server reserving server socket port=143 bindaddr=null ssl=false
Zimbra server reserving server socket port=993 bindaddr=null ssl=false
Zimbra server reserving server socket port=7025 bindaddr=null ssl=false
[2.856s][info][gc] GC(0) Pause Young (Normal) (GCLocker Initiated GC) 300M->24M(1996M) 43.621ms
[3.760s][info][gc] GC(1) Pause Young (Concurrent Start) (Metadata GC Threshold) 315M->34M(1996M) 51.629ms
[3.760s][info][gc] GC(2) Concurrent Cycle
[3.797s][info][gc] GC(2) Pause Remark 39M->39M(1996M) 3.250ms
[3.807s][info][gc] GC(2) Pause Cleanup 40M->40M(1996M) 0.283ms
[3.819s][info][gc] GC(2) Concurrent Cycle 58.712ms
[4.844s][info][gc] GC(3) Pause Young (Concurrent Start) (Metadata GC Threshold) 184M->41M(1996M) 22.891ms
[4.844s][info][gc] GC(4) Concurrent Cycle
[4.899s][info][gc] GC(4) Pause Remark 47M->47M(1996M) 11.332ms
[4.912s][info][gc] GC(4) Pause Cleanup 48M->48M(1996M) 0.212ms
[4.933s][info][gc] GC(4) Concurrent Cycle 88.157ms
java.lang.reflect.InvocationTargetException
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:567)
at org.eclipse.jetty.start.Main.invokeMain(Main.java:218)
at org.eclipse.jetty.start.Main.start(Main.java:491)
at org.eclipse.jetty.start.Main.main(Main.java:77)
Caused by: java.security.PrivilegedActionException: org.xml.sax.SAXParseException; lineNumber: 1; columnNumber: 1; Content is not allowed in prolog.
at java.base/java.security.AccessController.doPrivileged(AccessController.java:558)
at org.eclipse.jetty.xml.XmlConfiguration.main(XmlConfiguration.java:1857)
... 7 more
Caused by: org.xml.sax.SAXParseException; lineNumber: 1; columnNumber: 1; Content is not allowed in prolog.
at org.apache.xerces.util.ErrorHandlerWrapper.createSAXParseException(Unknown Source)
at org.apache.xerces.util.ErrorHandlerWrapper.fatalError(Unknown Source)
at org.apache.xerces.impl.XMLErrorReporter.reportError(Unknown Source)
at org.apache.xerces.impl.XMLErrorReporter.reportError(Unknown Source)
at org.apache.xerces.impl.XMLErrorReporter.reportError(Unknown Source)
at org.apache.xerces.impl.XMLScanner.reportFatalError(Unknown Source)
at org.apache.xerces.impl.XMLDocumentScannerImpl$PrologDispatcher.dispatch(Unknown Source)
at org.apache.xerces.impl.XMLDocumentFragmentScannerImpl.scanDocument(Unknown Source)
at org.apache.xerces.parsers.XML11Configuration.parse(Unknown Source)
at org.apache.xerces.parsers.XML11Configuration.parse(Unknown Source)
at org.apache.xerces.parsers.XMLParser.parse(Unknown Source)
at org.apache.xerces.parsers.AbstractSAXParser.parse(Unknown Source)
at org.apache.xerces.jaxp.SAXParserImpl$JAXPSAXParser.parse(Unknown Source)
at org.apache.xerces.jaxp.SAXParserImpl.parse(Unknown Source)
at org.eclipse.jetty.xml.XmlParser.parse(XmlParser.java:244)
at org.eclipse.jetty.xml.XmlConfiguration.<init>(XmlConfiguration.java:226)
at org.eclipse.jetty.xml.XmlConfiguration.<init>(XmlConfiguration.java:242)
at org.eclipse.jetty.plus.webapp.EnvConfiguration.configure(EnvConfiguration.java:116)
at org.eclipse.jetty.webapp.WebAppContext.configure(WebAppContext.java:498)
at org.eclipse.jetty.webapp.WebAppContext.startContext(WebAppContext.java:1409)
at org.eclipse.jetty.server.handler.ContextHandler.doStart(ContextHandler.java:910)
at org.eclipse.jetty.servlet.ServletContextHandler.doStart(ServletContextHandler.java:288)
at org.eclipse.jetty.webapp.WebAppContext.doStart(WebAppContext.java:524)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:117)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:117)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:110)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:110)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.server.handler.gzip.GzipHandler.doStart(GzipHandler.java:426)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:110)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.server.handler.DebugHandler.doStart(DebugHandler.java:140)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.server.Server.start(Server.java:423)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:110)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.server.Server.doStart(Server.java:387)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.xml.XmlConfiguration.lambda$main$3(XmlConfiguration.java:1907)
at java.base/java.security.AccessController.doPrivileged(AccessController.java:554)
... 8 more
java.lang.reflect.InvocationTargetException
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:567)
at org.eclipse.jetty.start.Main.invokeMain(Main.java:218)
at org.eclipse.jetty.start.Main.start(Main.java:491)
at org.eclipse.jetty.start.Main.main(Main.java:77)
Caused by: java.security.PrivilegedActionException: org.xml.sax.SAXParseException; lineNumber: 1; columnNumber: 1; Content is not allowed in prolog.
at java.base/java.security.AccessController.doPrivileged(AccessController.java:558)
at org.eclipse.jetty.xml.XmlConfiguration.main(XmlConfiguration.java:1857)
... 7 more
Caused by: org.xml.sax.SAXParseException; lineNumber: 1; columnNumber: 1; Content is not allowed in prolog.
at org.apache.xerces.util.ErrorHandlerWrapper.createSAXParseException(Unknown Source)
at org.apache.xerces.util.ErrorHandlerWrapper.fatalError(Unknown Source)
at org.apache.xerces.impl.XMLErrorReporter.reportError(Unknown Source)
at org.apache.xerces.impl.XMLErrorReporter.reportError(Unknown Source)
at org.apache.xerces.impl.XMLErrorReporter.reportError(Unknown Source)
at org.apache.xerces.impl.XMLScanner.reportFatalError(Unknown Source)
at org.apache.xerces.impl.XMLDocumentScannerImpl$PrologDispatcher.dispatch(Unknown Source)
at org.apache.xerces.impl.XMLDocumentFragmentScannerImpl.scanDocument(Unknown Source)
at org.apache.xerces.parsers.XML11Configuration.parse(Unknown Source)
at org.apache.xerces.parsers.XML11Configuration.parse(Unknown Source)
at org.apache.xerces.parsers.XMLParser.parse(Unknown Source)
at org.apache.xerces.parsers.AbstractSAXParser.parse(Unknown Source)
at org.apache.xerces.jaxp.SAXParserImpl$JAXPSAXParser.parse(Unknown Source)
at org.apache.xerces.jaxp.SAXParserImpl.parse(Unknown Source)
at org.eclipse.jetty.xml.XmlParser.parse(XmlParser.java:244)
at org.eclipse.jetty.xml.XmlConfiguration.<init>(XmlConfiguration.java:226)
at org.eclipse.jetty.xml.XmlConfiguration.<init>(XmlConfiguration.java:242)
at org.eclipse.jetty.plus.webapp.EnvConfiguration.configure(EnvConfiguration.java:116)
at org.eclipse.jetty.webapp.WebAppContext.configure(WebAppContext.java:498)
at org.eclipse.jetty.webapp.WebAppContext.startContext(WebAppContext.java:1409)
at org.eclipse.jetty.server.handler.ContextHandler.doStart(ContextHandler.java:910)
at org.eclipse.jetty.servlet.ServletContextHandler.doStart(ServletContextHandler.java:288)
at org.eclipse.jetty.webapp.WebAppContext.doStart(WebAppContext.java:524)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:117)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:117)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:110)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:110)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.server.handler.gzip.GzipHandler.doStart(GzipHandler.java:426)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:110)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.server.handler.DebugHandler.doStart(DebugHandler.java:140)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.server.Server.start(Server.java:423)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:110)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.server.Server.doStart(Server.java:387)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.xml.XmlConfiguration.lambda$main$3(XmlConfiguration.java:1907)
at java.base/java.security.AccessController.doPrivileged(AccessController.java:554)
... 8 more
Usage: java -jar $JETTY_HOME/start.jar [options] [properties] [configs]
java -jar $JETTY_HOME/start.jar --help # for more information
[0.021s][info][gc] Using G1
OpenJDK 64-Bit Server VM warning: .hotspot_compiler file is present but has been ignored. Run with -XX:CompileCommandFile=.hotspot_compiler to load the file.
[0.037s][info][gc] Periodic GC disabled
SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
SLF4J: Defaulting to no-operation (NOP) logger implementation
SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details.
JettyMonitor monitoring thread pool QueuedThreadPool[qtp1150284200]@448ff1a8{STOPPED,10<=0<=250,i=0,r=-1,q=0}[NO_TRY]
Zimbra server reserving server socket port=110 bindaddr=null ssl=false
Zimbra server reserving server socket port=995 bindaddr=null ssl=false
Zimbra server reserving server socket port=143 bindaddr=null ssl=false
Zimbra server reserving server socket port=993 bindaddr=null ssl=false
Zimbra server reserving server socket port=7025 bindaddr=null ssl=false
[1.829s][info][gc] GC(0) Pause Young (Normal) (GCLocker Initiated GC) 300M->24M(1996M) 33.237ms
[2.739s][info][gc] GC(1) Pause Young (Concurrent Start) (Metadata GC Threshold) 315M->35M(1996M) 64.738ms
[2.739s][info][gc] GC(2) Concurrent Cycle
[2.766s][info][gc] GC(2) Pause Remark 39M->39M(1996M) 8.155ms
[2.782s][info][gc] GC(2) Pause Cleanup 40M->40M(1996M) 0.411ms
[2.791s][info][gc] GC(2) Concurrent Cycle 51.758ms
[3.717s][info][gc] GC(3) Pause Young (Concurrent Start) (Metadata GC Threshold) 173M->41M(1996M) 24.148ms
[3.717s][info][gc] GC(4) Concurrent Cycle
[3.765s][info][gc] GC(4) Pause Remark 45M->45M(1996M) 11.760ms
[3.783s][info][gc] GC(4) Pause Cleanup 47M->47M(1996M) 0.218ms
[3.791s][info][gc] GC(4) Concurrent Cycle 74.710ms
java.lang.reflect.InvocationTargetException
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:567)
at org.eclipse.jetty.start.Main.invokeMain(Main.java:218)
at org.eclipse.jetty.start.Main.start(Main.java:491)
at org.eclipse.jetty.start.Main.main(Main.java:77)
Caused by: java.security.PrivilegedActionException: org.xml.sax.SAXParseException; lineNumber: 1; columnNumber: 1; Content is not allowed in prolog.
at java.base/java.security.AccessController.doPrivileged(AccessController.java:558)
at org.eclipse.jetty.xml.XmlConfiguration.main(XmlConfiguration.java:1857)
... 7 more
Caused by: org.xml.sax.SAXParseException; lineNumber: 1; columnNumber: 1; Content is not allowed in prolog.
at org.apache.xerces.util.ErrorHandlerWrapper.createSAXParseException(Unknown Source)
at org.apache.xerces.util.ErrorHandlerWrapper.fatalError(Unknown Source)
at org.apache.xerces.impl.XMLErrorReporter.reportError(Unknown Source)
at org.apache.xerces.impl.XMLErrorReporter.reportError(Unknown Source)
at org.apache.xerces.impl.XMLErrorReporter.reportError(Unknown Source)
at org.apache.xerces.impl.XMLScanner.reportFatalError(Unknown Source)
at org.apache.xerces.impl.XMLDocumentScannerImpl$PrologDispatcher.dispatch(Unknown Source)
at org.apache.xerces.impl.XMLDocumentFragmentScannerImpl.scanDocument(Unknown Source)
at org.apache.xerces.parsers.XML11Configuration.parse(Unknown Source)
at org.apache.xerces.parsers.XML11Configuration.parse(Unknown Source)
at org.apache.xerces.parsers.XMLParser.parse(Unknown Source)
at org.apache.xerces.parsers.AbstractSAXParser.parse(Unknown Source)
at org.apache.xerces.jaxp.SAXParserImpl$JAXPSAXParser.parse(Unknown Source)
at org.apache.xerces.jaxp.SAXParserImpl.parse(Unknown Source)
at org.eclipse.jetty.xml.XmlParser.parse(XmlParser.java:244)
at org.eclipse.jetty.xml.XmlConfiguration.<init>(XmlConfiguration.java:226)
at org.eclipse.jetty.xml.XmlConfiguration.<init>(XmlConfiguration.java:242)
at org.eclipse.jetty.plus.webapp.EnvConfiguration.configure(EnvConfiguration.java:116)
at org.eclipse.jetty.webapp.WebAppContext.configure(WebAppContext.java:498)
at org.eclipse.jetty.webapp.WebAppContext.startContext(WebAppContext.java:1409)
at org.eclipse.jetty.server.handler.ContextHandler.doStart(ContextHandler.java:910)
at org.eclipse.jetty.servlet.ServletContextHandler.doStart(ServletContextHandler.java:288)
at org.eclipse.jetty.webapp.WebAppContext.doStart(WebAppContext.java:524)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:117)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:117)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:110)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:110)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.server.handler.gzip.GzipHandler.doStart(GzipHandler.java:426)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:110)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.server.handler.DebugHandler.doStart(DebugHandler.java:140)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.server.Server.start(Server.java:423)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:110)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.server.Server.doStart(Server.java:387)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.xml.XmlConfiguration.lambda$main$3(XmlConfiguration.java:1907)
at java.base/java.security.AccessController.doPrivileged(AccessController.java:554)
... 8 more
java.lang.reflect.InvocationTargetException
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:567)
at org.eclipse.jetty.start.Main.invokeMain(Main.java:218)
at org.eclipse.jetty.start.Main.start(Main.java:491)
at org.eclipse.jetty.start.Main.main(Main.java:77)
Caused by: java.security.PrivilegedActionException: org.xml.sax.SAXParseException; lineNumber: 1; columnNumber: 1; Content is not allowed in prolog.
at java.base/java.security.AccessController.doPrivileged(AccessController.java:558)
at org.eclipse.jetty.xml.XmlConfiguration.main(XmlConfiguration.java:1857)
... 7 more
Caused by: org.xml.sax.SAXParseException; lineNumber: 1; columnNumber: 1; Content is not allowed in prolog.
at org.apache.xerces.util.ErrorHandlerWrapper.createSAXParseException(Unknown Source)
at org.apache.xerces.util.ErrorHandlerWrapper.fatalError(Unknown Source)
at org.apache.xerces.impl.XMLErrorReporter.reportError(Unknown Source)
at org.apache.xerces.impl.XMLErrorReporter.reportError(Unknown Source)
at org.apache.xerces.impl.XMLErrorReporter.reportError(Unknown Source)
at org.apache.xerces.impl.XMLScanner.reportFatalError(Unknown Source)
at org.apache.xerces.impl.XMLDocumentScannerImpl$PrologDispatcher.dispatch(Unknown Source)
at org.apache.xerces.impl.XMLDocumentFragmentScannerImpl.scanDocument(Unknown Source)
at org.apache.xerces.parsers.XML11Configuration.parse(Unknown Source)
at org.apache.xerces.parsers.XML11Configuration.parse(Unknown Source)
at org.apache.xerces.parsers.XMLParser.parse(Unknown Source)
at org.apache.xerces.parsers.AbstractSAXParser.parse(Unknown Source)
at org.apache.xerces.jaxp.SAXParserImpl$JAXPSAXParser.parse(Unknown Source)
at org.apache.xerces.jaxp.SAXParserImpl.parse(Unknown Source)
at org.eclipse.jetty.xml.XmlParser.parse(XmlParser.java:244)
at org.eclipse.jetty.xml.XmlConfiguration.<init>(XmlConfiguration.java:226)
at org.eclipse.jetty.xml.XmlConfiguration.<init>(XmlConfiguration.java:242)
at org.eclipse.jetty.plus.webapp.EnvConfiguration.configure(EnvConfiguration.java:116)
at org.eclipse.jetty.webapp.WebAppContext.configure(WebAppContext.java:498)
at org.eclipse.jetty.webapp.WebAppContext.startContext(WebAppContext.java:1409)
at org.eclipse.jetty.server.handler.ContextHandler.doStart(ContextHandler.java:910)
at org.eclipse.jetty.servlet.ServletContextHandler.doStart(ServletContextHandler.java:288)
at org.eclipse.jetty.webapp.WebAppContext.doStart(WebAppContext.java:524)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:117)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:117)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:110)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:110)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.server.handler.gzip.GzipHandler.doStart(GzipHandler.java:426)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:110)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.server.handler.DebugHandler.doStart(DebugHandler.java:140)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:169)
at org.eclipse.jetty.server.Server.start(Server.java:423)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:110)
at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:97)
at org.eclipse.jetty.server.Server.doStart(Server.java:387)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.xml.XmlConfiguration.lambda$main$3(XmlConfiguration.java:1907)
at java.base/java.security.AccessController.doPrivileged(AccessController.java:554)
... 8 more
Usage: java -jar $JETTY_HOME/start.jar [options] [properties] [configs]
java -jar $JETTY_HOME/start.jar --help # for more information
Services Status :
zextras@mail:~$ zmcontrol status Host xxx amavis Running antispam Running antivirus Running directory-server Running logger Running mailbox Stopped zmmailboxdctl is not running. memcached Running mta Running opendkim Running proxy Running service webapp Stopped zmmailboxdctl is not running. stats Running zimbra webapp Stopped zmmailboxdctl is not running. zimbraAdmin webapp Stopped zmmailboxdctl is not running. zimlet webapp Stopped zmmailboxdctl is not running. zmconfigd Running
05/15/2022 17:35
I upgraded to latest version without issue. I also did an apt purge "carbonio*" and apt autoremove, /bin/rm -rf /opt/zextras and started again with new install and that worked too. I am running Ubuntu 18.04.6 LTS and did the following this morning trying to reproduce your issue:
# apt update # apt upgrade # su - zextras % zextras@carbonio:~$ zmcontrol -v Release 22.5.0 Community Edition.
Perhaps report more about your environment, how you are doing updates, any modification you may have made, what version you are running, etc. Make sure you are doing apt upgrade vs apt-get upgrade for instance to install all new dependencies. Just throwing out ideas. I am not a debian/ubuntu admin as our production servers run RHEL here.
Here is my settings for /opt/zextras/log4j.properties
% grep '\.ews' log4j.properties log4j.additivity.zimbra.ews=false log4j.logger.zimbra.ews=INFO,EWS
05/16/2022 14:20
Hi @aynur-yilmaz ,
the first problem was related to an old configuration of ews, here the error inside the first zmmailbox.out file :
log4j:ERROR Could not find value for key log4j.appender.EWS log4j:ERROR Could not instantiate appender named "EWS".
That's because i suggested you to remove the ews line.
Next problem seems to be related to an xml files, probabily jetty, but as JDunphy told you, if you used an old version of carbonio and then upgrade to last one, it may be possible that something was wrong on upgrade process.
Remember that carbonio CE is in beta status and not ready for use in production.
https://community.zextras.com/forum/carbonio-news/some-question-about-carbonio-ce/
05/17/2022 11:20
Hello Aynur,
>
May 11 1659 mail /postfix-script[88011]: the Postfix mail system is running: PID: 77367 May 11 1659 mail zmmailboxdmgr[88045]: stale pid 87166 found in /opt/zextras/log/zmmailboxd_manager.pid: No such process May 11 1659 mail zmmailboxdmgr[88045]: assuming no other instance is running May 11 1659 mail zmmailboxdmgr[88045]: file /opt/zextras/log/zmmailboxd.pid does not exist May 11 1659 mail zmmailboxdmgr[88045]: assuming no other instance is running May 11 1659 mail zmmailboxdmgr[88045]: no manager process is running
Restart the server after a "touch /opt/zimbra/log/zmmailboxd.pid".
Has it been doing this since installation or is it due to an update?
Have you configured the ports correctly?
Please refer to the [guide]( https://docs.zextras.com/carbonio/html/installation.html ) to avoid any mistakes.
Thanks in advance.
This post was modified 3 years ago by Arman Khosravi
11/29/2022 18:38
Hello
Maybe my answer is a little late but I had a similar problem with my Zimbra server when updating and renewing the ssl certificates.
After a couple of weeks reviewing information and posts of similar problems I found a solution that was not related to the problem but it helped me to start all the Zimbra services again.
SOLUTION
Reinstall all zimbra services without deleting the information according to the following post https://linuxyotrascosas.wordpress.com/2020/01/08/respaldo-y-restauracion-de-sistemas-de-correo-zimbra-opensource/#:~ :text=Make%20%20backups%20in%20zimbra%20es,backup%20of%20all%20the%20environment.&text=If%20we%20want%20to%20backup%20only%20one,in%20the same%20%20way%20in%20simple.
With this process, I was able to reactivate all the Zimbra services without losing information or configuration.
I hope it helps someone
Arman Khosravi reacted