From: Hugh G. <hug...@gm...> - 2012-05-16 09:11:20
|
2012-05-16 04:54:03,604 [main] DEBUG (XmlLibraryChecker.java [check]:141) - Detected SAXParserFactory classes: org.apache.xerces.jaxp.SAXParserFactoryImpl 2012-05-16 04:54:03,610 [main] INFO (XmlLibraryChecker.java [check]:149) - Looking for a valid Parser... Checking for Xerces, found version Xerces-J 2.11.0 OK! 2012-05-16 04:54:03,727 [main] DEBUG (XmlLibraryChecker.java [check]:166) - Detected TransformerFactory classes: org.apache.xalan.processor.TransformerFactoryImpl net.sf.saxon.TransformerFactoryImpl 2012-05-16 04:54:03,728 [main] INFO (XmlLibraryChecker.java [check]:172) - Looking for a valid Transformer... Checking for Saxon, found version 9.2.1.5 OK! 2012-05-16 04:54:03,728 [main] INFO (XmlLibraryChecker.java [check]:184) - Looking for a valid Resolver... Checking for Resolver, found version XmlResolver 1.2 OK! 2012-05-16 04:54:03,733 [main] INFO (XmlLibraryChecker.java [check]:191) - Using parser org.apache.xerces.jaxp.SAXParserImpl$JAXPSAXParser 2012-05-16 04:54:03,758 [main] INFO (XmlLibraryChecker.java [check]:192) - Using transformer org.apache.xalan.transformer.TransformerIdentityImpl 2012-05-16 04:54:03,796 [main] DEBUG (ConfigurationHelper.java [getExistHome]:61) - Could not retrieve instance of brokerpool: database instance 'exist' is not available 2012-05-16 04:54:03,796 [main] DEBUG (ConfigurationHelper.java [getExistHome]:68) - Got eXist home from system property 'exist.home': /usr/share/exist-2.1 2012-05-16 04:54:03,797 [main] INFO (JettyStart.java [run]:108) - Configuring eXist from /usr/share/exist-2.1/conf.xml 2012-05-16 04:54:03,797 [main] INFO (JettyStart.java [run]:109) - 2012-05-16 04:54:03,797 [main] INFO (JettyStart.java [run]:110) - Running with Java 1.6.0_31 [Sun Microsystems Inc. (Java HotSpot(TM) Client VM) in /opt/java6/jre] 2012-05-16 04:54:03,797 [main] INFO (JettyStart.java [run]:115) - 2012-05-16 04:54:03,852 [main] INFO (JettyStart.java [run]:119) - [eXist Version : 2.1-dev] 2012-05-16 04:54:03,852 [main] INFO (JettyStart.java [run]:121) - [eXist Build : 20120515] 2012-05-16 04:54:03,853 [main] INFO (JettyStart.java [run]:123) - [eXist Home : unknown] 2012-05-16 04:54:03,854 [main] INFO (JettyStart.java [run]:125) - [SVN Revision : 0000] 2012-05-16 04:54:03,854 [main] INFO (JettyStart.java [run]:133) - [Operating System : Linux 3.0.18-linode43 i386] 2012-05-16 04:54:03,855 [main] INFO (JettyStart.java [run]:136) - [jetty.home : /usr/share/exist-2.1/tools/jetty] 2012-05-16 04:54:03,855 [main] INFO (JettyStart.java [run]:138) - [log4j.configuration : file:/usr/share/exist-2.1/log4j.xml] 2012-05-16 04:54:03,855 [main] DEBUG (ConfigurationHelper.java [getExistHome]:61) - Could not retrieve instance of brokerpool: database instance 'exist' is not available 2012-05-16 04:54:03,855 [main] DEBUG (ConfigurationHelper.java [getExistHome]:68) - Got eXist home from system property 'exist.home': /usr/share/exist-2.1 2012-05-16 04:54:03,856 [main] INFO (Configuration.java [<init>]:183) - Reading configuration from file /usr/share/exist-2.1/conf.xml 2012-05-16 04:54:03,938 [main] DEBUG (Configuration.java [configureIndexer]:1288) - indexer.stem: false 2012-05-16 04:54:03,938 [main] DEBUG (Configuration.java [configureIndexer]:1295) - indexer.store-term-freq: true 2012-05-16 04:54:03,939 [main] DEBUG (Configuration.java [configureIndexer]:1302) - indexer.tokenizer: org.exist.storage.analysis.SimpleTokenizer 2012-05-16 04:54:03,939 [main] DEBUG (Configuration.java [configureIndexer]:1309) - indexer.case-sensitive: true 2012-05-16 04:54:03,939 [main] DEBUG (Configuration.java [configureIndexer]:1338) - indexer.index-depth: 5 2012-05-16 04:54:03,939 [main] DEBUG (Configuration.java [configureIndexer]:1349) - indexer.suppress-whitespace: none 2012-05-16 04:54:03,939 [main] DEBUG (Configuration.java [configureIndexer]:1356) - indexer.preserve-whitespace-mixed-content: false 2012-05-16 04:54:03,947 [main] DEBUG (NodePath.java [addComponent]:205) - URI = 2012-05-16 04:54:03,948 [main] DEBUG (Configuration.java [configureBackend]:757) - database: native 2012-05-16 04:54:03,949 [main] DEBUG (Configuration.java [configureBackend]:770) - db-connection.data-dir: /usr/share/exist-2.1/webapp/WEB-INF/data 2012-05-16 04:54:03,949 [main] DEBUG (Configuration.java [configureBackend]:783) - db-connection.cache-size: 48m 2012-05-16 04:54:03,949 [main] DEBUG (Configuration.java [configureBackend]:799) - db-connection.check-max-cache-size: true 2012-05-16 04:54:03,949 [main] DEBUG (Configuration.java [configureBackend]:811) - db-connection.cache-shrink-threshold: 10000 2012-05-16 04:54:03,949 [main] DEBUG (Configuration.java [configureBackend]:828) - db-connection.collection-cache-mem: 24m 2012-05-16 04:54:03,949 [main] DEBUG (Configuration.java [configureBackend]:841) - db-connection.page-size: 4096 2012-05-16 04:54:03,950 [main] DEBUG (Configuration.java [configureBackend]:868) - db-connection.nodes-buffer: 1000 2012-05-16 04:54:03,962 [main] DEBUG (Configuration.java [configureBackend]:967) - db-connection.security.class: class org.exist.security.internal.SecurityManagerImpl 2012-05-16 04:54:03,962 [main] DEBUG (Configuration.java [configurePool]:1227) - db-connection.pool.min: 1 2012-05-16 04:54:03,962 [main] DEBUG (Configuration.java [configurePool]:1240) - db-connection.pool.max: 20 2012-05-16 04:54:03,963 [main] DEBUG (Configuration.java [configurePool]:1253) - db-connection.pool.sync-period: 120000 2012-05-16 04:54:03,963 [main] DEBUG (Configuration.java [configurePool]:1266) - wait-before-shutdown: 120000 2012-05-16 04:54:03,963 [main] DEBUG (Configuration.java [configureXQueryPool]:1127) - db-connection.query-pool.max-stack-size: 5 2012-05-16 04:54:03,963 [main] DEBUG (Configuration.java [configureXQueryPool]:1140) - db-connection.query-pool.size: 128 2012-05-16 04:54:03,963 [main] DEBUG (Configuration.java [configureXQueryPool]:1153) - db-connection.query-pool.timeout: 120000 2012-05-16 04:54:03,963 [main] DEBUG (Configuration.java [configureXQueryPool]:1166) - db-connection.query-pool.timeout-check-interval: 30000 2012-05-16 04:54:03,964 [main] DEBUG (Configuration.java [configureWatchdog]:1092) - db-connection.watchdog.query-timeout: -1 2012-05-16 04:54:03,964 [main] DEBUG (Configuration.java [configureWatchdog]:1105) - db-connection.watchdog.output-size-limit: 1000000 2012-05-16 04:54:03,964 [main] DEBUG (Configuration.java [configureRecovery]:1018) - db-connection.recovery.enabled: true 2012-05-16 04:54:03,964 [main] DEBUG (Configuration.java [configureRecovery]:1022) - db-connection.recovery.sync-on-commit: false 2012-05-16 04:54:03,964 [main] DEBUG (Configuration.java [configureRecovery]:1026) - db-connection.recovery.group-commit: false 2012-05-16 04:54:03,965 [main] DEBUG (Configuration.java [configureRecovery]:1039) - db-connection.recovery.journal-dir: /usr/share/exist-2.1/webapp/WEB-INF/data 2012-05-16 04:54:03,965 [main] DEBUG (Configuration.java [configureRecovery]:1053) - db-connection.recovery.size-limit: 100m 2012-05-16 04:54:03,965 [main] DEBUG (Configuration.java [configureRecovery]:1067) - db-connection.recovery.force-restart: false 2012-05-16 04:54:03,965 [main] DEBUG (Configuration.java [configureRecovery]:1076) - db-connection.recovery.consistency-check: true 2012-05-16 04:54:03,965 [main] DEBUG (Configuration.java [configureBinaryManager]:295) - binary.cache.class: org.exist.util.io.FileFilterInputStreamCache 2012-05-16 04:54:03,966 [main] DEBUG (Configuration.java [configureTransformer]:507) - transformer.class: net.sf.saxon.TransformerFactoryImpl 2012-05-16 04:54:03,966 [main] DEBUG (Configuration.java [configureTransformer]:553) - transformer.caching: true 2012-05-16 04:54:03,966 [main] DEBUG (Configuration.java [configureSerializer]:569) - serialization.enable-xinclude: yes 2012-05-16 04:54:03,966 [main] DEBUG (Configuration.java [configureSerializer]:576) - serialization.enable-xsl: no 2012-05-16 04:54:03,966 [main] DEBUG (Configuration.java [configureSerializer]:583) - serialization.indent: yes 2012-05-16 04:54:03,967 [main] DEBUG (Configuration.java [configureSerializer]:590) - serialization.compress-output: no 2012-05-16 04:54:03,967 [main] DEBUG (Configuration.java [configureSerializer]:597) - serialization.add-exist-id: none 2012-05-16 04:54:03,967 [main] DEBUG (Configuration.java [configureSerializer]:604) - serialization.match-tagging-elements: no 2012-05-16 04:54:03,967 [main] DEBUG (Configuration.java [configureSerializer]:611) - serialization.match-tagging-attributes: no 2012-05-16 04:54:03,967 [main] DEBUG (Configuration.java [configureXUpdate]:489) - xupdate.fragmentation: 100 2012-05-16 04:54:03,967 [main] DEBUG (Configuration.java [configureXUpdate]:496) - xupdate.consistency-checks: false 2012-05-16 04:54:03,968 [main] DEBUG (Configuration.java [configureXQuery]:306) - xquery.enable-java-binding: no 2012-05-16 04:54:03,968 [main] DEBUG (Configuration.java [configureXQuery]:311) - xquery.disable-deprecated-functions: false 2012-05-16 04:54:03,968 [main] DEBUG (Configuration.java [configureXQuery]:317) - xquery.enable-query-rewriting: yes 2012-05-16 04:54:03,968 [main] DEBUG (Configuration.java [configureXQuery]:329) - xquery.backwardCompatible: no 2012-05-16 04:54:03,968 [main] DEBUG (Configuration.java [configureXQuery]:334) - xquery.raise-error-on-failed-retrieval: false 2012-05-16 04:54:04,012 [main] DEBUG (Configuration.java [loadModuleClasses]:415) - Configured module 'http://exist-db.org/xquery/lucene' implemented in 'org.exist.xquery.modules.lucene.LuceneModule' 2012-05-16 04:54:04,022 [main] DEBUG (Configuration.java [loadModuleClasses]:415) - Configured module 'http://exist-db.org/xquery/ngram' implemented in 'org.exist.xquery.modules.ngram.NGramModule' 2012-05-16 04:54:04,028 [main] DEBUG (Configuration.java [loadModuleClasses]:415) - Configured module 'http://exist-db.org/xquery/sort' implemented in 'org.exist.xquery.modules.sort.SortModule' 2012-05-16 04:54:04,029 [main] DEBUG (Configuration.java [loadModuleClasses]:415) - Configured module 'http://exist-db.org/xquery/examples' implemented in 'org.exist.xquery.modules.example.ExampleModule' 2012-05-16 04:54:04,221 [main] DEBUG (Configuration.java [loadModuleClasses]:415) - Configured module 'http://exist-db.org/xquery/mail' implemented in 'org.exist.xquery.modules.mail.MailModule' 2012-05-16 04:54:04,271 [main] DEBUG (Configuration.java [loadModuleClasses]:415) - Configured module 'http://exist-db.org/xquery/request' implemented in 'org.exist.xquery.functions.request.RequestModule' 2012-05-16 04:54:04,280 [main] DEBUG (Configuration.java [loadModuleClasses]:415) - Configured module 'http://exist-db.org/xquery/response' implemented in 'org.exist.xquery.functions.response.ResponseModule' 2012-05-16 04:54:04,292 [main] DEBUG (Configuration.java [loadModuleClasses]:415) - Configured module 'http://exist-db.org/xquery/securitymanager' implemented in 'org.exist.xquery.functions.securitymanager.SecurityManagerModule' 2012-05-16 04:54:04,305 [main] DEBUG (Configuration.java [loadModuleClasses]:415) - Configured module 'http://exist-db.org/xquery/session' implemented in 'org.exist.xquery.functions.session.SessionModule' 2012-05-16 04:54:04,326 [main] DEBUG (Configuration.java [loadModuleClasses]:415) - Configured module 'http://exist-db.org/xquery/system' implemented in 'org.exist.xquery.functions.system.SystemModule' 2012-05-16 04:54:04,349 [main] DEBUG (Configuration.java [loadModuleClasses]:415) - Configured module 'http://exist-db.org/xquery/text' implemented in 'org.exist.xquery.functions.text.TextModule' 2012-05-16 04:54:04,353 [main] DEBUG (Configuration.java [loadModuleClasses]:415) - Configured module 'http://exist-db.org/xquery/transform' implemented in 'org.exist.xquery.functions.transform.TransformModule' 2012-05-16 04:54:04,481 [main] DEBUG (Configuration.java [loadModuleClasses]:415) - Configured module 'http://exist-db.org/xquery/util' implemented in 'org.exist.xquery.functions.util.UtilModule' 2012-05-16 04:54:04,490 [main] DEBUG (Configuration.java [loadModuleClasses]:415) - Configured module 'http://exist-db.org/xquery/validation' implemented in 'org.exist.xquery.functions.validation.ValidationModule' 2012-05-16 04:54:04,582 [main] DEBUG (Configuration.java [loadModuleClasses]:415) - Configured module 'http://exist-db.org/xquery/xmldb' implemented in 'org.exist.xquery.functions.xmldb.XMLDBModule' 2012-05-16 04:54:04,591 [main] DEBUG (Configuration.java [loadModuleClasses]:415) - Configured module 'http://expath.org/ns/http-client' implemented in 'org.expath.exist.HttpClientModule' 2012-05-16 04:54:04,643 [main] DEBUG (Configuration.java [loadModuleClasses]:415) - Configured module 'http://exist-db.org/xquery/httpclient' implemented in 'org.exist.xquery.modules.httpclient.HTTPClientModule' 2012-05-16 04:54:04,646 [main] DEBUG (Configuration.java [loadModuleClasses]:415) - Configured module 'http://expath.org/ns/zip' implemented in 'org.expath.exist.ZipModule' 2012-05-16 04:54:04,684 [main] DEBUG (Configuration.java [loadModuleClasses]:415) - Configured module 'http://exist-db.org/xquery/repo' implemented in 'org.exist.xquery.modules.expathrepo.ExpathPackageModule' 2012-05-16 04:54:04,690 [main] DEBUG (Configuration.java [loadModuleClasses]:415) - Configured module 'http://kuberam.ro/ns/x-crypt' implemented in 'ro.kuberam.xcrypt.XcryptModule' 2012-05-16 04:54:04,691 [main] DEBUG (Configuration.java [loadModuleClasses]:400) - Registered mapping for module 'http://exist-db.org/xquery/kwic' to 'resource:org/exist/xquery/lib/kwic.xql' 2012-05-16 04:54:04,691 [main] DEBUG (Configuration.java [loadModuleClasses]:400) - Registered mapping for module 'http://www.json.org' to 'resource:org/exist/xquery/lib/json.xq' 2012-05-16 04:54:04,691 [main] DEBUG (Configuration.java [loadModuleClasses]:400) - Registered mapping for module 'http://www.jsonp.org' to 'resource:org/exist/xquery/lib/jsonp.xq' 2012-05-16 04:54:04,691 [main] DEBUG (Configuration.java [loadModuleClasses]:400) - Registered mapping for module 'http://exist-db.org/xquery/sequences' to 'resource:org/exist/xquery/lib/sequences.xq' 2012-05-16 04:54:04,691 [main] DEBUG (Configuration.java [loadModuleClasses]:400) - Registered mapping for module 'http://exist-db.org/versioning' to 'resource:org/exist/versioning/xquery/versioning.xqm' 2012-05-16 04:54:04,691 [main] DEBUG (Configuration.java [loadModuleClasses]:400) - Registered mapping for module 'http://exist-db.org/xquery/testing' to 'resource:org/exist/xquery/lib/test.xq' 2012-05-16 04:54:04,691 [main] DEBUG (Configuration.java [loadModuleClasses]:400) - Registered mapping for module 'http://xproc.net/xproc' to 'resource:net/xproc/xprocxq/src/xquery/xproc.xqm' 2012-05-16 04:54:04,705 [main] DEBUG (Configuration.java [loadModuleClasses]:415) - Configured module 'http://exist-db.org/xquery/file' implemented in 'org.exist.xquery.modules.file.FileModule' 2012-05-16 04:54:04,737 [main] DEBUG (Configuration.java [loadModuleClasses]:415) - Configured module 'http://exist-db.org/xquery/compression' implemented in 'org.exist.xquery.modules.compression.CompressionModule' 2012-05-16 04:54:04,746 [main] DEBUG (Configuration.java [loadModuleClasses]:415) - Configured module 'http://www.w3.org/1999/XSL/Transform' implemented in 'org.exist.xslt.functions.XSLModule' 2012-05-16 04:54:04,752 [main] DEBUG (Configuration.java [loadModuleClasses]:415) - Configured module 'http://exist-db.org/xquery/xqdoc' implemented in 'org.exist.xqdoc.xquery.XQDocModule' 2012-05-16 04:54:04,753 [main] INFO (eXistURLStreamHandlerFactory.java [init]:53) - Succesfully registered eXistURLStreamHandlerFactory. 2012-05-16 04:54:04,753 [main] DEBUG (Configuration.java [configureValidation]:1406) - validation.mode: no 2012-05-16 04:54:04,754 [main] DEBUG (Configuration.java [configureValidation]:1411) - Creating eXist catalog resolver 2012-05-16 04:54:04,766 [main] DEBUG (eXistXMLCatalogResolver.java [<init>]:49) - Initializing 2012-05-16 04:54:04,766 [main] DEBUG (Configuration.java [configureValidation]:1420) - Found 1 catalog uri entries. 2012-05-16 04:54:04,766 [main] DEBUG (Configuration.java [configureValidation]:1421) - Using dbHome=/usr/share/exist-2.1 2012-05-16 04:54:04,766 [main] DEBUG (Configuration.java [configureValidation]:1437) - using webappHome=file:/usr/share/exist-2.1/webapp/ 2012-05-16 04:54:04,767 [main] INFO (Configuration.java [configureValidation]:1456) - Add catalog uri file:/usr/share/exist-2.1/webapp//WEB-INF/catalog.xml 2012-05-16 04:54:04,768 [main] INFO (GrammarPool.java [<init>]:53) - Initializing GrammarPool. 2012-05-16 04:54:04,768 [main] DEBUG (BrokerPool.java [configure]:225) - configuring database instance 'exist'... 2012-05-16 04:54:04,793 [main] INFO (BrokerPool.java [<init>]:618) - database instance 'exist' will have between 1 and 20 brokers 2012-05-16 04:54:04,793 [main] INFO (BrokerPool.java [<init>]:625) - database instance 'exist' will be synchronized every 120,000 ms 2012-05-16 04:54:04,793 [main] INFO (BrokerPool.java [<init>]:632) - database instance 'exist' will wait 120,000 ms during shutdown 2012-05-16 04:54:04,793 [main] INFO (BrokerPool.java [<init>]:638) - database instance 'exist' is enabled for transactions : true 2012-05-16 04:54:04,847 [main] INFO (Scheduler.java [getQuartzProperties]:132) - Succesfully loaded quartz.properties 2012-05-16 04:54:05,048 [main] DEBUG (BrokerPool.java [<init>]:685) - isReadOnly: false 2012-05-16 04:54:05,048 [main] DEBUG (BrokerPool.java [initialize]:752) - initializing database instance 'exist'... 2012-05-16 04:54:05,150 [main] INFO (DefaultCacheManager.java [<init>]:151) - Cache settings: 49,152k; totalPages: 12,288; maxCacheSize: 11,059; cacheShrinkThreshold: 10,000 2012-05-16 04:54:05,153 [main] DEBUG (JMXAgent.java [<init>]:65) - Creating the JMX MBeanServer. 2012-05-16 04:54:05,202 [main] INFO (XQueryPool.java [<init>]:120) - QueryPool: size = 128; maxStackSize = 5; timeout = 120,000; timeoutCheckInterval = 30,000 2012-05-16 04:54:05,217 [main] DEBUG (CollectionCacheManager.java [<init>]:54) - collection collectionCache will be using 25165824 bytes max. 2012-05-16 04:54:05,217 [main] DEBUG (BrokerPool.java [initialize]:787) - Reserved memory: 179293388; max: 518979584; min: 103795916 2012-05-16 04:54:05,226 [main] DEBUG (Journal.java [<init>]:173) - SyncOnCommit = false 2012-05-16 04:54:05,226 [main] DEBUG (Journal.java [<init>]:202) - Using directory for the journal: /usr/share/exist-2.1/webapp/WEB-INF/data 2012-05-16 04:54:05,226 [main] DEBUG (TransactionManager.java [<init>]:106) - GroupCommits = false 2012-05-16 04:54:05,226 [main] DEBUG (TransactionManager.java [<init>]:112) - ForceRestart = false 2012-05-16 04:54:05,288 [main] DEBUG (NGramIndex.java [open]:80) - Created NGram index: /usr/share/exist-2.1/webapp/WEB-INF/data/ngram.dbx 2012-05-16 04:54:05,288 [main] INFO (IndexManager.java [initIndex]:99) - Registered index org.exist.indexing.ngram.NGramIndex as ngram-index 2012-05-16 04:54:05,316 [main] DEBUG (LuceneIndex.java [configure]:56) - Configuring Lucene index 2012-05-16 04:54:05,317 [main] DEBUG (LuceneIndex.java [configure]:67) - Using buffer size: 32.0 2012-05-16 04:54:05,369 [main] DEBUG (LuceneIndex.java [configure]:78) - Using default analyzer: org.apache.lucene.analysis.standard.StandardAnalyzer 2012-05-16 04:54:05,369 [main] DEBUG (LuceneIndex.java [open]:85) - Opening Lucene index directory: /usr/share/exist-2.1/webapp/WEB-INF/data/lucene 2012-05-16 04:54:05,622 [main] INFO (IndexManager.java [initIndex]:99) - Registered index org.exist.indexing.lucene.LuceneIndex as lucene-index 2012-05-16 04:54:05,624 [main] DEBUG (SortIndex.java [open]:44) - Creating 'sort.dbx'... 2012-05-16 04:54:05,625 [main] INFO (IndexManager.java [initIndex]:99) - Registered index org.exist.indexing.sort.SortIndex as sort-index 2012-05-16 04:54:05,626 [main] DEBUG (FTIndex.java [open]:78) - Creating 'words.dbx'... 2012-05-16 04:54:05,638 [main] INFO (IndexManager.java [initIndex]:99) - Registered index org.exist.fulltext.FTIndex as ft-legacy-index 2012-05-16 04:54:05,639 [main] DEBUG (NativeStructuralIndex.java [open]:51) - Creating 'structure.dbx'... 2012-05-16 04:54:05,640 [main] INFO (IndexManager.java [initIndex]:99) - Registered index org.exist.storage.structural.NativeStructuralIndex as structural-index 2012-05-16 04:54:05,767 [main] DEBUG (TextSearchEngine.java [<init>]:111) - using tokenizer: org.exist.storage.analysis.SimpleTokenizer 2012-05-16 04:54:05,771 [main] DEBUG (NativeBroker.java [<init>]:231) - Initializing broker 400594 2012-05-16 04:54:05,775 [main] DEBUG (TransformerFactoryAllocator.java [getTransformerFactory]:91) - Set transformer factory: net.sf.saxon.TransformerFactoryImpl 2012-05-16 04:54:05,776 [main] DEBUG (TransformerFactoryAllocator.java [getTransformerFactory]:101) - Set transformer attribute: , name: http://saxon.sf.net/feature/version-warning, value: false 2012-05-16 04:54:05,815 [main] DEBUG (NativeValueIndex.java [<init>]:160) - Creating 'values.dbx'... 2012-05-16 04:54:05,817 [main] DEBUG (BrokerPool.java [createBroker]:1344) - created broker 'org.exist.storage.NativeBroker_exist_1 for database instance 'exist' 2012-05-16 04:54:05,824 [main] DEBUG (RecoveryManager.java [recover]:98) - Database is in clean state. Last checkpoint: May 16, 2012 4:53:04 AM 2012-05-16 04:54:05,825 [main] DEBUG (Journal.java [switchFiles]:395) - Creating new journal: /usr/share/exist-2.1/webapp/WEB-INF/data/0000000007.log 2012-05-16 04:54:06,003 [main] WARN (Configurator.java [configureByCurrent]:339) - Unconfigured instance [<group name="dba" id="1048575"></group>], remove the object. 2012-05-16 04:54:06,022 [main] WARN (Configurator.java [configureByCurrent]:339) - Unconfigured instance [<group name="guest" id="1048574"></group>], remove the object. 2012-05-16 04:54:06,024 [main] WARN (LuceneIndex.java [commit]:196) - Committing lucene index 2012-05-16 04:54:06,025 [main] DEBUG (NGramIndex.java [sync]:91) - SYNC NGRAM 2012-05-16 04:54:06,026 [main] INFO (NativeBroker.java [sync]:3482) - Memory: 126,720K total; 506,816K max; 95,725K free 2012-05-16 04:54:06,026 [main] INFO (BTree.java [printStatistics]:2338) - dom.dbx INDEX Buffers occupation : 0% (0 out of 64) Cache efficiency : N/A 2012-05-16 04:54:06,137 [main] INFO (DOMFile.java [printStatistics]:1370) - dom.dbx DATA Buffers occupation : 1% (3 out of 256) Cache efficiency : 99% 2012-05-16 04:54:06,137 [main] INFO (BTree.java [printStatistics]:2338) - collections.dbx INDEX Buffers occupation : 2% (1 out of 64) Cache efficiency : 96% 2012-05-16 04:54:06,137 [main] INFO (BFile.java [printStatistics]:434) - collections.dbx DATA Buffers occupation : 2% (1 out of 64) Cache efficiency : 94% 2012-05-16 04:54:06,138 [main] INFO (BTree.java [printStatistics]:2338) - values.dbx INDEX Buffers occupation : 0% (0 out of 64) Cache efficiency : N/A 2012-05-16 04:54:06,138 [main] INFO (BFile.java [printStatistics]:434) - values.dbx DATA Buffers occupation : 0% (0 out of 64) Cache efficiency : N/A 2012-05-16 04:54:06,138 [main] DEBUG (BrokerPool.java [sync]:1630) - Major sync 2012-05-16 04:54:06,138 [main] DEBUG (Journal.java [checkpoint]:328) - Checkpoint reached 2012-05-16 04:54:06,139 [main] DEBUG (NotificationService.java [debug]:94) - Registered UpdateListeners: 2012-05-16 04:54:06,164 [main] DEBUG (BrokerPool.java [initialize]:968) - database instance 'exist' initialized 2012-05-16 04:54:06,441 [main] INFO (JettyStart.java [lifeCycleStarting]:368) - Jetty server starting... 2012-05-16 04:54:07,931 [main] DEBUG (ConfigurationHelper.java [getExistHome]:55) - Got eXist home from broker: /usr/share/exist-2.1 2012-05-16 04:54:07,936 [main] INFO (AbstractExistHttpServlet.java [getOrCreateBrokerPool]:93) - Database already started. Skipping configuration ... 2012-05-16 04:54:07,936 [main] INFO (AbstractExistHttpServlet.java [doGeneralExistServletConfig]:184) - Using default user guest for all unauthorized requests. 2012-05-16 04:54:07,963 [main] DEBUG (XmlLibraryChecker.java [check]:141) - Detected SAXParserFactory classes: org.apache.xerces.jaxp.SAXParserFactoryImpl 2012-05-16 04:54:07,963 [main] INFO (XmlLibraryChecker.java [check]:149) - Looking for a valid Parser... Checking for Xerces, found version Xerces-J 2.11.0 OK! 2012-05-16 04:54:07,965 [main] DEBUG (XmlLibraryChecker.java [check]:166) - Detected TransformerFactory classes: org.apache.xalan.processor.TransformerFactoryImpl net.sf.saxon.TransformerFactoryImpl 2012-05-16 04:54:07,966 [main] INFO (XmlLibraryChecker.java [check]:172) - Looking for a valid Transformer... Checking for Saxon, found version 9.2.1.5 OK! 2012-05-16 04:54:07,966 [main] INFO (XmlLibraryChecker.java [check]:184) - Looking for a valid Resolver... Checking for Resolver, found version XmlResolver 1.2 OK! 2012-05-16 04:54:07,968 [main] INFO (XmlLibraryChecker.java [check]:191) - Using parser org.apache.xerces.jaxp.SAXParserImpl$JAXPSAXParser 2012-05-16 04:54:07,968 [main] INFO (XmlLibraryChecker.java [check]:192) - Using transformer org.apache.xalan.transformer.TransformerIdentityImpl 2012-05-16 04:54:08,839 [main] INFO (JettyStart.java [lifeCycleStarted]:374) - Jetty server started. 2012-05-16 04:54:08,840 [main] INFO (JettyStart.java [run]:210) - ----------------------------------------------------- 2012-05-16 04:54:08,840 [main] INFO (JettyStart.java [run]:211) - Server has started on ports 8080 8443. Configured contexts: 2012-05-16 04:54:08,840 [main] INFO (JettyStart.java [run]:218) - '/' 2012-05-16 04:54:08,840 [main] INFO (JettyStart.java [run]:249) - ----------------------------------------------------- 2012-05-16 04:56:06,169 [DefaultQuartzScheduler_Worker-1] DEBUG (NGramIndex.java [sync]:91) - SYNC NGRAM 2012-05-16 04:56:06,169 [DefaultQuartzScheduler_Worker-1] INFO (NativeBroker.java [sync]:3482) - Memory: 126,848K total; 506,816K max; 96,138K free 2012-05-16 04:56:06,169 [DefaultQuartzScheduler_Worker-1] INFO (BTree.java [printStatistics]:2338) - dom.dbx INDEX Buffers occupation : 0% (0 out of 64) Cache efficiency : N/A 2012-05-16 04:56:06,170 [DefaultQuartzScheduler_Worker-1] INFO (DOMFile.java [printStatistics]:1370) - dom.dbx DATA Buffers occupation : 2% (4 out of 256) Cache efficiency : 99% 2012-05-16 04:56:06,170 [DefaultQuartzScheduler_Worker-1] INFO (BTree.java [printStatistics]:2338) - collections.dbx INDEX Buffers occupation : 2% (1 out of 64) Cache efficiency : 99% 2012-05-16 04:56:06,170 [DefaultQuartzScheduler_Worker-1] INFO (BFile.java [printStatistics]:434) - collections.dbx DATA Buffers occupation : 2% (1 out of 64) Cache efficiency : 94% 2012-05-16 04:56:06,170 [DefaultQuartzScheduler_Worker-1] INFO (BTree.java [printStatistics]:2338) - values.dbx INDEX Buffers occupation : 0% (0 out of 64) Cache efficiency : N/A 2012-05-16 04:56:06,171 [DefaultQuartzScheduler_Worker-1] INFO (BFile.java [printStatistics]:434) - values.dbx DATA Buffers occupation : 0% (0 out of 64) Cache efficiency : N/A 2012-05-16 04:56:06,171 [DefaultQuartzScheduler_Worker-1] DEBUG (BrokerPool.java [sync]:1630) - Major sync 2012-05-16 04:56:06,171 [DefaultQuartzScheduler_Worker-1] DEBUG (Journal.java [checkpoint]:328) - Checkpoint reached 2012-05-16 04:56:06,171 [DefaultQuartzScheduler_Worker-1] DEBUG (NotificationService.java [debug]:94) - Registered UpdateListeners: 2012-05-16 04:58:08,669 [DefaultQuartzScheduler_Worker-3] DEBUG (NGramIndex.java [sync]:91) - SYNC NGRAM 2012-05-16 04:58:08,669 [DefaultQuartzScheduler_Worker-3] INFO (NativeBroker.java [sync]:3482) - Memory: 126,848K total; 506,816K max; 95,422K free 2012-05-16 04:58:08,670 [DefaultQuartzScheduler_Worker-3] INFO (BTree.java [printStatistics]:2338) - dom.dbx INDEX Buffers occupation : 0% (0 out of 64) Cache efficiency : N/A 2012-05-16 04:58:08,670 [DefaultQuartzScheduler_Worker-3] INFO (DOMFile.java [printStatistics]:1370) - dom.dbx DATA Buffers occupation : 2% (4 out of 256) Cache efficiency : 99% 2012-05-16 04:58:08,670 [DefaultQuartzScheduler_Worker-3] INFO (BTree.java [printStatistics]:2338) - collections.dbx INDEX Buffers occupation : 2% (1 out of 64) Cache efficiency : 99% 2012-05-16 04:58:08,670 [DefaultQuartzScheduler_Worker-3] INFO (BFile.java [printStatistics]:434) - collections.dbx DATA Buffers occupation : 2% (1 out of 64) Cache efficiency : 94% 2012-05-16 04:58:08,671 [DefaultQuartzScheduler_Worker-3] INFO (BTree.java [printStatistics]:2338) - values.dbx INDEX Buffers occupation : 0% (0 out of 64) Cache efficiency : N/A 2012-05-16 04:58:08,671 [DefaultQuartzScheduler_Worker-3] INFO (BFile.java [printStatistics]:434) - values.dbx DATA Buffers occupation : 0% (0 out of 64) Cache efficiency : N/A 2012-05-16 04:58:08,671 [DefaultQuartzScheduler_Worker-3] DEBUG (BrokerPool.java [sync]:1630) - Major sync 2012-05-16 04:58:08,671 [DefaultQuartzScheduler_Worker-3] DEBUG (Journal.java [checkpoint]:328) - Checkpoint reached 2012-05-16 04:58:08,672 [DefaultQuartzScheduler_Worker-3] DEBUG (NotificationService.java [debug]:94) - Registered UpdateListeners: 2012-05-16 05:00:11,169 [DefaultQuartzScheduler_Worker-3] DEBUG (NGramIndex.java [sync]:91) - SYNC NGRAM 2012-05-16 05:00:11,169 [DefaultQuartzScheduler_Worker-3] INFO (NativeBroker.java [sync]:3482) - Memory: 126,848K total; 506,816K max; 94,349K free 2012-05-16 05:00:11,169 [DefaultQuartzScheduler_Worker-3] INFO (BTree.java [printStatistics]:2338) - dom.dbx INDEX Buffers occupation : 0% (0 out of 64) Cache efficiency : N/A 2012-05-16 05:00:11,170 [DefaultQuartzScheduler_Worker-3] INFO (DOMFile.java [printStatistics]:1370) - dom.dbx DATA Buffers occupation : 2% (4 out of 256) Cache efficiency : 99% 2012-05-16 05:00:11,170 [DefaultQuartzScheduler_Worker-3] INFO (BTree.java [printStatistics]:2338) - collections.dbx INDEX Buffers occupation : 2% (1 out of 64) Cache efficiency : 99% 2012-05-16 05:00:11,170 [DefaultQuartzScheduler_Worker-3] INFO (BFile.java [printStatistics]:434) - collections.dbx DATA Buffers occupation : 2% (1 out of 64) Cache efficiency : 94% 2012-05-16 05:00:11,170 [DefaultQuartzScheduler_Worker-3] INFO (BTree.java [printStatistics]:2338) - values.dbx INDEX Buffers occupation : 0% (0 out of 64) Cache efficiency : N/A 2012-05-16 05:00:11,170 [DefaultQuartzScheduler_Worker-3] INFO (BFile.java [printStatistics]:434) - values.dbx DATA Buffers occupation : 0% (0 out of 64) Cache efficiency : N/A 2012-05-16 05:00:11,171 [DefaultQuartzScheduler_Worker-3] DEBUG (BrokerPool.java [sync]:1630) - Major sync 2012-05-16 05:00:11,171 [DefaultQuartzScheduler_Worker-3] DEBUG (Journal.java [checkpoint]:328) - Checkpoint reached 2012-05-16 05:00:11,171 [DefaultQuartzScheduler_Worker-3] DEBUG (NotificationService.java [debug]:94) - Registered UpdateListeners: 2012-05-16 05:02:13,669 [DefaultQuartzScheduler_Worker-3] DEBUG (NGramIndex.java [sync]:91) - SYNC NGRAM 2012-05-16 05:02:13,669 [DefaultQuartzScheduler_Worker-3] INFO (NativeBroker.java [sync]:3482) - Memory: 126,848K total; 506,816K max; 92,932K free 2012-05-16 05:02:13,669 [DefaultQuartzScheduler_Worker-3] INFO (BTree.java [printStatistics]:2338) - dom.dbx INDEX Buffers occupation : 0% (0 out of 64) Cache efficiency : N/A 2012-05-16 05:02:13,670 [DefaultQuartzScheduler_Worker-3] INFO (DOMFile.java [printStatistics]:1370) - dom.dbx DATA Buffers occupation : 2% (4 out of 256) Cache efficiency : 99% 2012-05-16 05:02:13,670 [DefaultQuartzScheduler_Worker-3] INFO (BTree.java [printStatistics]:2338) - collections.dbx INDEX Buffers occupation : 2% (1 out of 64) Cache efficiency : 100% 2012-05-16 05:02:13,670 [DefaultQuartzScheduler_Worker-3] INFO (BFile.java [printStatistics]:434) - collections.dbx DATA Buffers occupation : 2% (1 out of 64) Cache efficiency : 94% 2012-05-16 05:02:13,670 [DefaultQuartzScheduler_Worker-3] INFO (BTree.java [printStatistics]:2338) - values.dbx INDEX Buffers occupation : 0% (0 out of 64) Cache efficiency : N/A 2012-05-16 05:02:13,670 [DefaultQuartzScheduler_Worker-3] INFO (BFile.java [printStatistics]:434) - values.dbx DATA Buffers occupation : 0% (0 out of 64) Cache efficiency : N/A 2012-05-16 05:02:13,670 [DefaultQuartzScheduler_Worker-3] DEBUG (BrokerPool.java [sync]:1630) - Major sync 2012-05-16 05:02:13,671 [DefaultQuartzScheduler_Worker-3] DEBUG (Journal.java [checkpoint]:328) - Checkpoint reached 2012-05-16 05:02:13,671 [DefaultQuartzScheduler_Worker-3] DEBUG (NotificationService.java [debug]:94) - Registered UpdateListeners: 2012-05-16 05:04:16,169 [DefaultQuartzScheduler_Worker-4] DEBUG (NGramIndex.java [sync]:91) - SYNC NGRAM 2012-05-16 05:04:16,169 [DefaultQuartzScheduler_Worker-4] INFO (NativeBroker.java [sync]:3482) - Memory: 126,848K total; 506,816K max; 91,515K free 2012-05-16 05:04:16,169 [DefaultQuartzScheduler_Worker-4] INFO (BTree.java [printStatistics]:2338) - dom.dbx INDEX Buffers occupation : 0% (0 out of 64) Cache efficiency : N/A 2012-05-16 05:04:16,170 [DefaultQuartzScheduler_Worker-4] INFO (DOMFile.java [printStatistics]:1370) - dom.dbx DATA Buffers occupation : 2% (4 out of 256) Cache efficiency : 99% 2012-05-16 05:04:16,170 [DefaultQuartzScheduler_Worker-4] INFO (BTree.java [printStatistics]:2338) - collections.dbx INDEX Buffers occupation : 2% (1 out of 64) Cache efficiency : 100% 2012-05-16 05:04:16,170 [DefaultQuartzScheduler_Worker-4] INFO (BFile.java [printStatistics]:434) - collections.dbx DATA Buffers occupation : 2% (1 out of 64) Cache efficiency : 94% 2012-05-16 05:04:16,170 [DefaultQuartzScheduler_Worker-4] INFO (BTree.java [printStatistics]:2338) - values.dbx INDEX Buffers occupation : 0% (0 out of 64) Cache efficiency : N/A 2012-05-16 05:04:16,171 [DefaultQuartzScheduler_Worker-4] INFO (BFile.java [printStatistics]:434) - values.dbx DATA Buffers occupation : 0% (0 out of 64) Cache efficiency : N/A 2012-05-16 05:04:16,171 [DefaultQuartzScheduler_Worker-4] DEBUG (BrokerPool.java [sync]:1630) - Major sync 2012-05-16 05:04:16,171 [DefaultQuartzScheduler_Worker-4] DEBUG (Journal.java [checkpoint]:328) - Checkpoint reached 2012-05-16 05:04:16,171 [DefaultQuartzScheduler_Worker-4] DEBUG (NotificationService.java [debug]:94) - Registered UpdateListeners: 2012-05-16 05:06:18,669 [DefaultQuartzScheduler_Worker-4] DEBUG (NGramIndex.java [sync]:91) - SYNC NGRAM 2012-05-16 05:06:18,669 [DefaultQuartzScheduler_Worker-4] INFO (NativeBroker.java [sync]:3482) - Memory: 126,848K total; 506,816K max; 90,799K free 2012-05-16 05:06:18,669 [DefaultQuartzScheduler_Worker-4] INFO (BTree.java [printStatistics]:2338) - dom.dbx INDEX Buffers occupation : 0% (0 out of 64) Cache efficiency : N/A 2012-05-16 05:06:18,670 [DefaultQuartzScheduler_Worker-4] INFO (DOMFile.java [printStatistics]:1370) - dom.dbx DATA Buffers occupation : 2% (4 out of 256) Cache efficiency : 99% 2012-05-16 05:06:18,670 [DefaultQuartzScheduler_Worker-4] INFO (BTree.java [printStatistics]:2338) - collections.dbx INDEX Buffers occupation : 2% (1 out of 64) Cache efficiency : 100% 2012-05-16 05:06:18,670 [DefaultQuartzScheduler_Worker-4] INFO (BFile.java [printStatistics]:434) - collections.dbx DATA Buffers occupation : 2% (1 out of 64) Cache efficiency : 94% 2012-05-16 05:06:18,670 [DefaultQuartzScheduler_Worker-4] INFO (BTree.java [printStatistics]:2338) - values.dbx INDEX Buffers occupation : 0% (0 out of 64) Cache efficiency : N/A 2012-05-16 05:06:18,670 [DefaultQuartzScheduler_Worker-4] INFO (BFile.java [printStatistics]:434) - values.dbx DATA Buffers occupation : 0% (0 out of 64) Cache efficiency : N/A 2012-05-16 05:06:18,671 [DefaultQuartzScheduler_Worker-4] DEBUG (BrokerPool.java [sync]:1630) - Major sync 2012-05-16 05:06:18,671 [DefaultQuartzScheduler_Worker-4] DEBUG (Journal.java [checkpoint]:328) - Checkpoint reached 2012-05-16 05:06:18,671 [DefaultQuartzScheduler_Worker-4] DEBUG (NotificationService.java [debug]:94) - Registered UpdateListeners: 2012-05-16 05:07:50,458 [eXistThread-28] DEBUG (ConfigurationHelper.java [getExistHome]:55) - Got eXist home from broker: /usr/share/exist-2.1 2012-05-16 05:07:50,459 [eXistThread-28] INFO (Descriptor.java [<init>]:102) - Reading Descriptor from file /usr/share/exist-2.1/descriptor.xml 2012-05-16 05:07:50,493 [eXistThread-28] INFO (AbstractExistHttpServlet.java [getOrCreateBrokerPool]:93) - Database already started. Skipping configuration ... 2012-05-16 05:07:50,493 [eXistThread-28] INFO (AbstractExistHttpServlet.java [doGeneralExistServletConfig]:184) - Using default user guest for all unauthorized requests. 2012-05-16 05:07:50,494 [eXistThread-28] WARN (ContextHandler.java [log]:1783) - unavailable java.lang.NullPointerException at org.exist.atom.http.AtomServlet.init(AtomServlet.java:196) at org.eclipse.jetty.servlet.ServletHolder.initServlet(ServletHolder.java:445) at org.eclipse.jetty.servlet.ServletHolder.getServlet(ServletHolder.java:336) at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:524) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:480) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:119) at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:520) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:227) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:941) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:409) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:186) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:875) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:117) at org.eclipse.jetty.server.Dispatcher.forward(Dispatcher.java:241) at org.eclipse.jetty.server.Dispatcher.forward(Dispatcher.java:115) at org.exist.http.urlrewrite.Forward.doRewrite(Forward.java:47) at org.exist.http.urlrewrite.XQueryURLRewrite.service(XQueryURLRewrite.java:211) at javax.servlet.http.HttpServlet.service(HttpServlet.java:717) at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:547) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:480) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:119) at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:483) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:227) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:941) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:409) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:186) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:875) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:117) at org.eclipse.jetty.server.handler.HandlerCollection.handle(HandlerCollection.java:149) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:110) at org.eclipse.jetty.server.Server.handle(Server.java:349) at org.eclipse.jetty.server.HttpConnection.handleRequest(HttpConnection.java:441) at org.eclipse.jetty.server.HttpConnection$RequestHandler.headerComplete(HttpConnection.java:919) at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:582) at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:218) at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:51) at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:586) at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:44) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:598) at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:533) at java.lang.Thread.run(Thread.java:662) |