Hi Gert,
Thanks very much for your response. I've attached extracts from each of the
logs.
I can access the fedorasgearch rest pages but am unable to do updateIndex
fromPid.
Regards,
Caleb
From: Gert Schmeltz Pedersen [mailto:g...@dtic.dtu.dk]
Sent: 06 October 2011 08:51
To: Support and info exchange list for Fedora users.
Subject: Re: [fcrepo-user] Gsearch/ solr indexing issue
Please, let us see extracts from fedora.log, fedoragsearch.log and
catalina.out from around a time, when you observe such errors. Can you
access fedoragsearch/rest pages and do updateIndex fromPid actions?
Best
Gert
On 05/10/2011, at 15.51, Caleb Derven wrote:
Hello,
We've recently encountered an issue where newly ingested objects are indexed
in the risearch index and the SQL database but not in solr (via gsearch).
We're running fedora version 3.4.2.
Following messages regarding the addition of objects and the processing of
datastreams to update the SQL and resource indexes the fedora.log shows the
following message:
ERROR 2011-10-04 06:31:48.152 [http-8080-39] (AuthFilterJAAS) Login Failure:
all modules ignored
The fedoragsearch.log has this message:
ERROR 2011-10-04 12:17:48,116 (RESTImpl)
dk.defxws.fedoragsearch.server.errors.FedoraObjectNotFoundException: Fedora
Object ucdlib:22350 not found at gsearch_solr; nested exception is:
(401)Unauthorized
I've confirmed that no authentication details have been modified in any of
the gsearch configuration files (or fedora files). There have been no issues
accessing fedora via the client, web administrator or APIM's.
To test this behaviour we've been exporting objects, purging them, then
adding the object again with the ingest object function of the admin client;
they have been adding successfully and are indexed in the research and the
SQL database but not solr.
Any help or advice would be much appreciated.
Regards,
Caleb
<ATT00001.c><ATT00002.c>
Oct 4, 2011 6:33:17 AM org.apache.solr.core.SolrCore execute
INFO: [] webapp=/solr path=/select/
params={facet=true&facet.mincount=1&facet.limit=100&qf=title^1&qf=subject^0.5&qf=emb.summary^0.5&hl.simple.pre=<span+class%3D"highlight">&hl.fl=contributor&hl.fl=subject&hl.fl=description&hl.fl=creator&wt=json&hl=true&rows=10&hl.snippets=4&start=0&q=(dubliners)+AND+NOT+(+dc.relation:isPartOf+)&hl.simple.post=</span>&facet.field=genre_facet&facet.field=subject_topic_facet&facet.field=type_facet}
hits=1081 status=0 QTime=183
Oct 4, 2011 6:33:56 AM org.apache.solr.core.SolrCore execute
INFO: [] webapp=/solr path=/select/
params={facet=true&facet.mincount=1&facet.limit=100&qf=title^1&qf=subject^0.5&qf=emb.summary^0.5&hl.simple.pre=<span+class%3D"highlight">&hl.fl=contributor&hl.fl=subject&hl.fl=description&hl.fl=creator&wt=json&hl=true&rows=10&hl.snippets=4&start=0&q=(james+joyce+dubliners)+AND+NOT+(+dc.relation:isPartOf+)&hl.simple.post=</span>&facet.field=genre_facet&facet.field=subject_topic_facet&facet.field=type_facet}
hits=1428 status=0 QTime=161
Oct 4, 2011 6:49:33 AM org.apache.solr.core.SolrDeletionPolicy onInit
INFO: SolrDeletionPolicy.onInit: commits:num=1
commit{dir=/var/opt/fedora34/gsearch_solr/solr/data/index,segFN=segments_1f0z,version=1308231187025,generation=66131,filenames=[_1f5j.nrm,
_1f5j.tis, _1f5j.tii, _1f5j.fnm, _1f5j.fdx, segments_1f0z, _1f5j_3.del,
_1f5j.prx, _1f5j.fdt, _1f5j.frq]
Oct 4, 2011 6:49:33 AM org.apache.solr.core.SolrDeletionPolicy updateCommits
INFO: newest commit = 1308231187025
Oct 4, 2011 6:49:33 AM org.apache.solr.update.processor.LogUpdateProcessor
finish
INFO: {delete=[ucdlib:22350]} 0 7
Oct 4, 2011 6:49:33 AM org.apache.solr.update.DirectUpdateHandler2 commit
INFO: start
commit(optimize=false,waitFlush=true,waitSearcher=true,expungeDeletes=false)
Oct 4, 2011 6:49:33 AM org.apache.solr.core.SolrCore execute
INFO: [] webapp=/solr path=/update params={} status=0 QTime=7
Oct 4, 2011 6:49:33 AM org.apache.solr.search.SolrIndexSearcher <init>
INFO: Opening Searcher@5eeb48d main
Oct 4, 2011 6:49:33 AM org.apache.solr.update.DirectUpdateHandler2 commit
INFO: end_commit_flush
Oct 4, 2011 6:49:33 AM org.apache.solr.search.SolrIndexSearcher warm
INFO: autowarming Searcher@5eeb48d main from Searcher@18adb210 main
fieldValueCache{lookups=15,hits=9,hitratio=0.60,inserts=3,evictions=0,size=3,warmupTime=0,cumulative_lookups=21,cumulative_hits=9,cumulative_hitratio=0.42,cumulative_inserts=6,cumulative_evictions=0,item_type_facet={field=type_facet,memSize=66166,tindexSize=64,time=2,phase1=1,nTerms=10,bigTerms=2,termInstances=560,uses=4},item_subject_topic_facet={field=subject_topic_facet,memSize=97452,tindexSize=970,time=49,phase1=48,nTerms=2503,bigTerms=4,termInstances=15018,uses=4},item_genre_facet={field=genre_facet,memSize=66200,tindexSize=98,time=3,phase1=3,nTerms=68,bigTerms=2,termInstances=2906,uses=4}}
Oct 4, 2011 6:49:33 AM org.apache.solr.search.SolrIndexSearcher warm
INFO: autowarming result for Searcher@5eeb48d main
fieldValueCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,warmupTime=0,cumulative_lookups=21,cumulative_hits=9,cumulative_hitratio=0.42,cumulative_inserts=6,cumulative_evictions=0}
Oct 4, 2011 6:49:33 AM org.apache.solr.search.SolrIndexSearcher warm
INFO: autowarming Searcher@5eeb48d main from Searcher@18adb210 main
filterCache{lookups=40,hits=40,hitratio=1.00,inserts=14,evictions=0,size=13,warmupTime=14,cumulative_lookups=56,cumulative_hits=48,cumulative_hitratio=0.85,cumulative_inserts=13,cumulative_evictions=0}
Oct 4, 2011 6:49:33 AM org.apache.solr.search.SolrIndexSearcher warm
INFO: autowarming result for Searcher@5eeb48d main
filterCache{lookups=0,hits=0,hitratio=0.00,inserts=13,evictions=0,size=13,warmupTime=120,cumulative_lookups=56,cumulative_hits=48,cumulative_hitratio=0.85,cumulative_inserts=13,cumulative_evictions=0}
Oct 4, 2011 6:49:33 AM org.apache.solr.search.SolrIndexSearcher warm
INFO: autowarming Searcher@5eeb48d main from Searcher@18adb210 main
DEBUG 2011-10-04 06:29:25,055 (UpdateListener) Received Fedora Message:
ActiveMQTextMessage {commandId = 155, responseRequired = true, messageId =
ID:libucd-54295-1317648296092-2:0:1:1:9, originalDestination = null,
originalTransactionId = null, producerId =
ID:libucd-54295-1317648296092-2:0:1:1, destination =
topic://fedora.apim.update, transactionId = null, expiration = 0, timestamp =
1317706165047, arrival = 0, brokerInTime = 1317706165048, brokerOutTime =
1317706165052, correlationId = null, replyTo = null, persistent = true, type =
null, priority = 4, groupID = null, groupSequence = 0, targetConsumerId = null,
compressed = false, userID = null, content =
org.apache.activemq.util.ByteSequence@68d7c870, marshalledProperties =
org.apache.activemq.util.ByteSequence@2ea866d6, dataStructure = null,
redeliveryCounter = 0, size = 0, properties = null, readOnlyProperties = true,
readOnlyBody = true, droppable = false, text = null}
DEBUG 2011-10-04 06:29:25,061 (UpdateListener) Message Text: <?xml
version="1.0" encoding="UTF-8"?>
<entry xmlns="http://www.w3.org/2005/Atom";
xmlns:xsd="http://www.w3.org/2001/XMLSchema";
xmlns:fedora-types="http://www.fedora.info/definitions/1/0/types/";>
<id>urn:uuid:01d3b397-5dac-44cc-8a17-27493a51887e</id>
<updated>2011-10-04T05:29:24.801Z</updated>
<author>
<name>fedoraAdmin</name>
<uri>http://libucd.ucd.ie:8080/fedora</uri>
</author>
<title type="text">ingest</title>
<category term="null" scheme="fedora-types:logMessage"
label="xsd:string"></category>
<category term="info:fedora/fedora-system:FOXML-1.1"
scheme="fedora-types:format" label="xsd:string"></category>
<summary type="text">ucdlib:22350</summary>
<content type="text">ucdlib:22350</content>
<category term="3.4.2"
scheme="info:fedora/fedora-system:def/view#version"></category>
<category term="info:fedora/fedora-system:ATOM-APIM-1.0"
scheme="http://www.fedora.info/definitions/1/0/types/formatURI";></category>
</entry>
DEBUG 2011-10-04 06:29:25,068 (GenericOperationsImpl) updateIndex
action=fromPid value=ucdlib:22350 repositoryName= indexNames= indexDocXslt=
resultPageXslt=
DEBUG 2011-10-04 06:29:25,068 (Config) indexName=gsearch_solr
operationsImpl=dk.defxws.fgssolr.OperationsImpl
DEBUG 2011-10-04 06:29:25,068 (Config) operationsImplClass=class
dk.defxws.fgssolr.OperationsImpl
DEBUG 2011-10-04 06:29:25,068 (Config)
ops=dk.defxws.fgssolr.OperationsImpl@31f78388
DEBUG 2011-10-04 06:29:25,075 (OperationsImpl) getIndexReader
indexName=gsearch_solr docCount=15421
INFO 2011-10-04 06:29:25,076 (GenericOperationsImpl) getFoxmlFromPid
pid=ucdlib:22350 repositoryName=gsearch_solr
DEBUG 2011-10-04 06:29:25,119 (OperationsImpl) getIndexReader
indexName=gsearch_solr docCount=15421
DEBUG 2011-10-04 06:29:25,119 (OperationsImpl) closeIndexReader
indexName=gsearch_solr docCount=15421
DEBUG 2011-10-04 06:29:25,119 (OperationsImpl) initDocCount=15421
docCount=15421 updateTotal=0
ERROR 2011-10-04 06:29:25,119 (UpdateListener) Unable to perform index update
due to Exception: Fedora Object ucdlib:22350 not found at gsearch_solr; nested
exception is:
(401)Unauthorized
dk.defxws.fedoragsearch.server.errors.FedoraObjectNotFoundException: Fedora
Object ucdlib:22350 not found at gsearch_solr; nested exception is:
(401)Unauthorized
at
dk.defxws.fedoragsearch.server.GenericOperationsImpl.getFoxmlFromPid(GenericOperationsImpl.java:340)
at dk.defxws.fgssolr.OperationsImpl.fromPid(OperationsImpl.java:389)
at dk.defxws.fgssolr.OperationsImpl.updateIndex(OperationsImpl.java:241)
at
dk.defxws.fedoragsearch.server.GenericOperationsImpl.updateIndex(GenericOperationsImpl.java:308)
at
dk.defxws.fedoragsearch.server.UpdateListener.onMessage(UpdateListener.java:198)
at
fedora.client.messaging.JmsMessagingClient.onMessage(JmsMessagingClient.java:330)
at
org.apache.activemq.ActiveMQMessageConsumer.dispatch(ActiveMQMessageConsumer.java:967)
at
org.apache.activemq.ActiveMQSessionExecutor.dispatch(ActiveMQSessionExecutor.java:122)
at
org.apache.activemq.ActiveMQSessionExecutor.iterate(ActiveMQSessionExecutor.java:192)
at
org.apache.activemq.thread.PooledTaskRunner.runTask(PooledTaskRunner.java:122)
at
org.apache.activemq.thread.PooledTaskRunner$1.run(PooledTaskRunner.java:43)
at
java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:662)
Caused by: (401)Unauthorized
at
org.apache.axis.transport.http.HTTPSender.readFromSocket(HTTPSender.java:744)
at org.apache.axis.transport.http.HTTPSender.invoke(HTTPSender.java:144)
at
org.apache.axis.strategies.InvocationStrategy.visit(InvocationStrategy.java:32)
at org.apache.axis.SimpleChain.doVisiting(SimpleChain.java:118)
at org.apache.axis.SimpleChain.invoke(SimpleChain.java:83)
at org.apache.axis.client.AxisClient.invoke(AxisClient.java:165)
at org.apache.axis.client.Call.invokeEngine(Call.java:2784)
at org.apache.axis.client.Call.invoke(Call.java:2767)
at org.apache.axis.client.Call.invoke(Call.java:2443)
at org.apache.axis.client.Call.invoke(Call.java:2366)
at org.apache.axis.client.Call.invoke(Call.java:1812)
at
fedora.server.management.FedoraAPIMBindingSOAPHTTPStub.export(FedoraAPIMBindingSOAPHTTPStub.java:639)
at
dk.defxws.fedoragsearch.server.GenericOperationsImpl.getFoxmlFromPid(GenericOperationsImpl.java:338)
... 13 more
DEBUG 2011-10-04 06:30:44,754 (UpdateListener) Received Fedora Message:
ActiveMQTextMessage {commandId = 159, responseRequired = true, messageId =
ID:libucd-54295-1317648296092-2:0:1:1:10, originalDestination = null,
originalTransactionId = null, producerId =
ID:libucd-54295-1317648296092-2:0:1:1, destination =
topic://fedora.apim.update, transactionId = null, expiration = 0, timestamp =
1317706244747, arrival = 0, brokerInTime = 1317706244748, brokerOutTime =
1317706244752, correlationId = null, replyTo = null, persistent = true, type =
null, priority = 4, groupID = null, groupSequence = 0, targetConsumerId = null,
compressed = false, userID = null, content =
org.apache.activemq.util.ByteSequence@2b1daa4d, marshalledProperties =
org.apache.activemq.util.ByteSequence@2897bb58, dataStructure = null,
redeliveryCounter = 0, size = 0, properties = null, readOnlyProperties = true,
readOnlyBody = true, droppable = false, text = null}
DEBUG 2011-10-04 06:30:44,756 (UpdateListener) Message Text: <?xml
version="1.0" encoding="UTF-8"?>
<entry xmlns="http://www.w3.org/2005/Atom";
xmlns:xsd="http://www.w3.org/2001/XMLSchema";
xmlns:fedora-types="http://www.fedora.info/definitions/1/0/types/";>
<id>urn:uuid:6b2fbe57-3ecb-49c9-ab34-aa0730203430</id>
<updated>2011-10-04T05:30:44.621Z</updated>
<author>
<name>fedoraAdmin</name>
<uri>http://libucd.ucd.ie:8080/fedora</uri>
</author>
<title type="text">modifyDatastreamByValue</title>
<category term="ucdlib:22350" scheme="fedora-types:pid"
label="xsd:string"></category>
<category term="DC" scheme="fedora-types:dsID" label="xsd:string"></category>
<category term="" scheme="fedora-types:altIDs"
label="fedora-types:ArrayOfString"></category>
<category term="null" scheme="fedora-types:dsLabel"
label="xsd:string"></category>
<category term="null" scheme="fedora-types:formatURI"
label="xsd:string"></category>
<category term="[OMITTED]" scheme="fedora-types:dsContent"
label="xsd:base64Binary"></category>
<category term="null" scheme="fedora-types:checksumType"
label="xsd:string"></category>
<category term="null" scheme="fedora-types:checksum"
label="xsd:string"></category>
<category term="null" scheme="fedora-types:logMessage"
label="xsd:string"></category>
<summary type="text">ucdlib:22350</summary>
<content type="text">2011-10-04T05:30:44.616Z</content>
<category term="3.4.2"
scheme="info:fedora/fedora-system:def/view#version"></category>
<category term="info:fedora/fedora-system:ATOM-APIM-1.0"
scheme="http://www.fedora.info/definitions/1/0/types/formatURI";></category>
</entry>
DEBUG 2011-10-04 06:30:44,764 (GenericOperationsImpl) updateIndex
action=fromPid value=ucdlib:22350 repositoryName= indexNames= indexDocXslt=
resultPageXslt=
DEBUG 2011-10-04 06:30:44,764 (Config) indexName=gsearch_solr
operationsImpl=dk.defxws.fgssolr.OperationsImpl
DEBUG 2011-10-04 06:30:44,764 (Config) operationsImplClass=class
dk.defxws.fgssolr.OperationsImpl
DEBUG 2011-10-04 06:30:44,764 (Config)
ops=dk.defxws.fgssolr.OperationsImpl@383035a1
DEBUG 2011-10-04 06:30:44,772 (OperationsImpl) getIndexReader
indexName=gsearch_solr docCount=15421
INFO 2011-10-04 06:30:44,772 (GenericOperationsImpl) getFoxmlFromPid
pid=ucdlib:22350 repositoryName=gsearch_solr
DEBUG 2011-10-04 06:30:44,793 (OperationsImpl) getIndexReader
indexName=gsearch_solr docCount=15421
DEBUG 2011-10-04 06:30:44,793 (OperationsImpl) closeIndexReader
indexName=gsearch_solr docCount=15421
DEBUG 2011-10-04 06:30:44,793 (OperationsImpl) initDocCount=15421
docCount=15421 updateTotal=0
ERROR 2011-10-04 06:30:44,793 (UpdateListener) Unable to perform index update
due to Exception: Fedora Object ucdlib:22350 not found at gsearch_solr; nested
exception is:
(401)Unauthorized
dk.defxws.fedoragsearch.server.errors.FedoraObjectNotFoundException: Fedora
Object ucdlib:22350 not found at gsearch_solr; nested exception is:
(401)Unauthorized
at
dk.defxws.fedoragsearch.server.GenericOperationsImpl.getFoxmlFromPid(GenericOperationsImpl.java:340)
at dk.defxws.fgssolr.OperationsImpl.fromPid(OperationsImpl.java:389)
at dk.defxws.fgssolr.OperationsImpl.updateIndex(OperationsImpl.java:241)
at
dk.defxws.fedoragsearch.server.GenericOperationsImpl.updateIndex(GenericOperationsImpl.java:308)
at
dk.defxws.fedoragsearch.server.UpdateListener.onMessage(UpdateListener.java:198)
at
fedora.client.messaging.JmsMessagingClient.onMessage(JmsMessagingClient.java:330)
at
org.apache.activemq.ActiveMQMessageConsumer.dispatch(ActiveMQMessageConsumer.java:967)
at
org.apache.activemq.ActiveMQSessionExecutor.dispatch(ActiveMQSessionExecutor.java:122)
at
org.apache.activemq.ActiveMQSessionExecutor.iterate(ActiveMQSessionExecutor.java:192)
at
org.apache.activemq.thread.PooledTaskRunner.runTask(PooledTaskRunner.java:122)
at
org.apache.activemq.thread.PooledTaskRunner$1.run(PooledTaskRunner.java:43)
at
java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:662)
Caused by: (401)Unauthorized
at
org.apache.axis.transport.http.HTTPSender.readFromSocket(HTTPSender.java:744)
at org.apache.axis.transport.http.HTTPSender.invoke(HTTPSender.java:144)
at
org.apache.axis.strategies.InvocationStrategy.visit(InvocationStrategy.java:32)
at org.apache.axis.SimpleChain.doVisiting(SimpleChain.java:118)
at org.apache.axis.SimpleChain.invoke(SimpleChain.java:83)
at org.apache.axis.client.AxisClient.invoke(AxisClient.java:165)
at org.apache.axis.client.Call.invokeEngine(Call.java:2784)
at org.apache.axis.client.Call.invoke(Call.java:2767)
at org.apache.axis.client.Call.invoke(Call.java:2443)
at org.apache.axis.client.Call.invoke(Call.java:2366)
at org.apache.axis.client.Call.invoke(Call.java:1812)
at
fedora.server.management.FedoraAPIMBindingSOAPHTTPStub.export(FedoraAPIMBindingSOAPHTTPStub.java:639)
at
dk.defxws.fedoragsearch.server.GenericOperationsImpl.getFoxmlFromPid(GenericOperationsImpl.java:338)
... 13 more
DEBUG 2011-10-04 06:30:57,600 (UpdateListener) Received Fedora Message:
ActiveMQTextMessage {commandId = 162, responseRequired = true, messageId =
ID:libucd-54295-1317648296092-2:0:1:1:11, originalDestination = null,
originalTransactionId = null, producerId =
ID:libucd-54295-1317648296092-2:0:1:1, destination =
topic://fedora.apim.update, transactionId = null, expiration = 0, timestamp =
1317706257594, arrival = 0, brokerInTime = 1317706257594, brokerOutTime =
1317706257597, correlationId = null, replyTo = null, persistent = true, type =
null, priority = 4, groupID = null, groupSequence = 0, targetConsumerId = null,
compressed = false, userID = null, content =
org.apache.activemq.util.ByteSequence@24d4fdf1, marshalledProperties =
org.apache.activemq.util.ByteSequence@1b6bec1b, dataStructure = null,
redeliveryCounter = 0, size = 0, properties = null, readOnlyProperties = true,
readOnlyBody = true, droppable = false, text = null}
DEBUG 2011-10-04 06:30:57,600 (UpdateListener) Message Text: <?xml
version="1.0" encoding="UTF-8"?>
<entry xmlns="http://www.w3.org/2005/Atom";
xmlns:xsd="http://www.w3.org/2001/XMLSchema";
xmlns:fedora-types="http://www.fedora.info/definitions/1/0/types/";>
<id>urn:uuid:be2bc023-cf7c-494d-8e63-85ecb8bdff8e</id>
<updated>2011-10-04T05:30:57.451Z</updated>
<author>
<name>fedoraAdmin</name>
<uri>http://libucd.ucd.ie:8080/fedora</uri>
</author>
<title type="text">modifyDatastreamByValue</title>
<category term="ucdlib:22350" scheme="fedora-types:pid"
label="xsd:string"></category>
<category term="DC" scheme="fedora-types:dsID" label="xsd:string"></category>
<category term="" scheme="fedora-types:altIDs"
label="fedora-types:ArrayOfString"></category>
<category term="Dublin Core Record for this object"
scheme="fedora-types:dsLabel" label="xsd:string"></category>
<category term="http://www.openarchives.org/OAI/2.0/oai_dc/";
scheme="fedora-types:formatURI" label="xsd:string"></category>
<category term="null" scheme="fedora-types:dsContent"
label="xsd:base64Binary"></category>
<category term="Disabled" scheme="fedora-types:checksumType"
label="xsd:string"></category>
<category term="null" scheme="fedora-types:checksum"
label="xsd:string"></category>
<category term="null" scheme="fedora-types:logMessage"
label="xsd:string"></category>
<summary type="text">ucdlib:22350</summary>
<content type="text">2011-10-04T05:30:57.447Z</content>
<category term="3.4.2"
scheme="info:fedora/fedora-system:def/view#version"></category>
<category term="info:fedora/fedora-system:ATOM-APIM-1.0"
scheme="http://www.fedora.info/definitions/1/0/types/formatURI";></category>
</entry>
DEBUG 2011-10-04 06:30:57,608 (GenericOperationsImpl) updateIndex
action=fromPid value=ucdlib:22350 repositoryName= indexNames= indexDocXslt=
resultPageXslt=
DEBUG 2011-10-04 06:30:57,608 (Config) indexName=gsearch_solr
operationsImpl=dk.defxws.fgssolr.OperationsImpl
DEBUG 2011-10-04 06:30:57,608 (Config) operationsImplClass=class
dk.defxws.fgssolr.OperationsImpl
DEBUG 2011-10-04 06:30:57,609 (Config)
ops=dk.defxws.fgssolr.OperationsImpl@4a59eead
DEBUG 2011-10-04 06:30:57,617 (OperationsImpl) getIndexReader
indexName=gsearch_solr docCount=15421
INFO 2011-10-04 06:30:57,617 (GenericOperationsImpl) getFoxmlFromPid
pid=ucdlib:22350 repositoryName=gsearch_solr
DEBUG 2011-10-04 06:30:57,642 (OperationsImpl) getIndexReader
indexName=gsearch_solr docCount=15421
DEBUG 2011-10-04 06:30:57,642 (OperationsImpl) closeIndexReader
indexName=gsearch_solr docCount=15421
DEBUG 2011-10-04 06:30:57,642 (OperationsImpl) initDocCount=15421
docCount=15421 updateTotal=0
ERROR 2011-10-04 06:30:57,643 (UpdateListener) Unable to perform index update
due to Exception: Fedora Object ucdlib:22350 not found at gsearch_solr; nested
exception is:
(401)Unauthorized
dk.defxws.fedoragsearch.server.errors.FedoraObjectNotFoundException: Fedora
Object ucdlib:22350 not found at gsearch_solr; nested exception is:
(401)Unauthorized
at
dk.defxws.fedoragsearch.server.GenericOperationsImpl.getFoxmlFromPid(GenericOperationsImpl.java:340)
at dk.defxws.fgssolr.OperationsImpl.fromPid(OperationsImpl.java:389)
at dk.defxws.fgssolr.OperationsImpl.updateIndex(OperationsImpl.java:241)
at
dk.defxws.fedoragsearch.server.GenericOperationsImpl.updateIndex(GenericOperationsImpl.java:308)
at
dk.defxws.fedoragsearch.server.UpdateListener.onMessage(UpdateListener.java:198)
at
fedora.client.messaging.JmsMessagingClient.onMessage(JmsMessagingClient.java:330)
at
org.apache.activemq.ActiveMQMessageConsumer.dispatch(ActiveMQMessageConsumer.java:967)
at
org.apache.activemq.ActiveMQSessionExecutor.dispatch(ActiveMQSessionExecutor.java:122)
at
org.apache.activemq.ActiveMQSessionExecutor.iterate(ActiveMQSessionExecutor.java:192)
at
org.apache.activemq.thread.PooledTaskRunner.runTask(PooledTaskRunner.java:122)
at
org.apache.activemq.thread.PooledTaskRunner$1.run(PooledTaskRunner.java:43)
at
java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:662)
Caused by: (401)Unauthorized
at
org.apache.axis.transport.http.HTTPSender.readFromSocket(HTTPSender.java:744)
at org.apache.axis.transport.http.HTTPSender.invoke(HTTPSender.java:144)
at
org.apache.axis.strategies.InvocationStrategy.visit(InvocationStrategy.java:32)
at org.apache.axis.SimpleChain.doVisiting(SimpleChain.java:118)
at org.apache.axis.SimpleChain.invoke(SimpleChain.java:83)
at org.apache.axis.client.AxisClient.invoke(AxisClient.java:165)
at org.apache.axis.client.Call.invokeEngine(Call.java:2784)
at org.apache.axis.client.Call.invoke(Call.java:2767)
at org.apache.axis.client.Call.invoke(Call.java:2443)
at org.apache.axis.client.Call.invoke(Call.java:2366)
at org.apache.axis.client.Call.invoke(Call.java:1812)
at
fedora.server.management.FedoraAPIMBindingSOAPHTTPStub.export(FedoraAPIMBindingSOAPHTTPStub.java:639)
at
dk.defxws.fedoragsearch.server.GenericOperationsImpl.getFoxmlFromPid(GenericOperationsImpl.java:338)
... 13 more
DEBUG 2011-10-04 06:31:48,121 (UpdateListener) Received Fedora Message:
ActiveMQTextMessage {commandId = 166, responseRequired = true, messageId =
ID:libucd-54295-1317648296092-2:0:1:1:12, originalDestination = null,
originalTransactionId = null, producerId =
ID:libucd-54295-1317648296092-2:0:1:1, destination =
topic://fedora.apim.update, transactionId = null, expiration = 0, timestamp =
1317706308114, arrival = 0, brokerInTime = 1317706308115, brokerOutTime =
1317706308118, correlationId = null, replyTo = null, persistent = true, type =
null, priority = 4, groupID = null, groupSequence = 0, targetConsumerId = null,
compressed = false, userID = null, content =
org.apache.activemq.util.ByteSequence@ace1333, marshalledProperties =
org.apache.activemq.util.ByteSequence@5f4e7ecf, dataStructure = null,
redeliveryCounter = 0, size = 0, properties = null, readOnlyProperties = true,
readOnlyBody = true, droppable = false, text = null}
DEBUG 2011-10-04 06:31:48,121 (UpdateListener) Message Text: <?xml
version="1.0" encoding="UTF-8"?>
<entry xmlns="http://www.w3.org/2005/Atom";
xmlns:xsd="http://www.w3.org/2001/XMLSchema";
xmlns:fedora-types="http://www.fedora.info/definitions/1/0/types/";>
<id>urn:uuid:931a6e2e-a08a-4323-b2f8-6b27ec47cab5</id>
<updated>2011-10-04T05:31:47.908Z</updated>
<author>
<name>fedoraAdmin</name>
<uri>http://libucd.ucd.ie:8080/fedora</uri>
</author>
<title type="text">addDatastream</title>
<category term="ucdlib:22350" scheme="fedora-types:pid"
label="xsd:string"></category>
<category term="descMetadata" scheme="fedora-types:dsID"
label="xsd:string"></category>
<category term="" scheme="fedora-types:altIDs"
label="fedora-types:ArrayOfString"></category>
<category term="MODS metadata" scheme="fedora-types:dsLabel"
label="xsd:string"></category>
<category term="true" scheme="fedora-types:versionable"
label="xsd:boolean"></category>
<category term="" scheme="fedora-types:formatURI"
label="xsd:string"></category>
<category term="uploaded://46771" scheme="fedora-types:dsLocation"
label="xsd:string"></category>
<category term="X" scheme="fedora-types:controlGroup"
label="xsd:string"></category>
<category term="A" scheme="fedora-types:dsState"
label="xsd:string"></category>
<category term="Default" scheme="fedora-types:checksumType"
label="xsd:string"></category>
<category term="null" scheme="fedora-types:checksum"
label="xsd:string"></category>
<category term="null" scheme="fedora-types:logMessage"
label="xsd:string"></category>
<summary type="text">ucdlib:22350</summary>
<content type="text">descMetadata</content>
<category term="3.4.2"
scheme="info:fedora/fedora-system:def/view#version"></category>
<category term="info:fedora/fedora-system:ATOM-APIM-1.0"
scheme="http://www.fedora.info/definitions/1/0/types/formatURI";></category>
</entry>
DEBUG 2011-10-04 06:31:48,128 (GenericOperationsImpl) updateIndex
action=fromPid value=ucdlib:22350 repositoryName= indexNames= indexDocXslt=
resultPageXslt=
DEBUG 2011-10-04 06:31:48,129 (Config) indexName=gsearch_solr
operationsImpl=dk.defxws.fgssolr.OperationsImpl
DEBUG 2011-10-04 06:31:48,129 (Config) operationsImplClass=class
dk.defxws.fgssolr.OperationsImpl
DEBUG 2011-10-04 06:31:48,129 (Config)
ops=dk.defxws.fgssolr.OperationsImpl@1a6778eb
DEBUG 2011-10-04 06:31:48,136 (OperationsImpl) getIndexReader
indexName=gsearch_solr docCount=15421
INFO 2011-10-04 06:31:48,136 (GenericOperationsImpl) getFoxmlFromPid
pid=ucdlib:22350 repositoryName=gsearch_solr
DEBUG 2011-10-04 06:31:48,156 (OperationsImpl) getIndexReader
indexName=gsearch_solr docCount=15421
DEBUG 2011-10-04 06:31:48,157 (OperationsImpl) closeIndexReader
indexName=gsearch_solr docCount=15421
DEBUG 2011-10-04 06:31:48,157 (OperationsImpl) initDocCount=15421
docCount=15421 updateTotal=0
INFO 2011-10-04 06:29:24.959 [http-8080-24] (DefaultDOManager) New object PID
is ucdlib:22350
INFO 2011-10-04 06:29:24.970 [http-8080-24] (DefaultDOManager) Committing
addition of ucdlib:22350
INFO 2011-10-04 06:29:24.978 [http-8080-24] (DefaultDOManager) Adding to
ResourceIndex
INFO 2011-10-04 06:29:25.024 [http-8080-24] (DefaultDOManager) Updating
dissemination index
INFO 2011-10-04 06:29:25.024 [http-8080-24] (DefaultDOManager) Updating
FieldSearch index
INFO 2011-10-04 06:29:25.031 [http-8080-24] (DefaultManagement) Completed
ingest(objectXML, format: info:fedora/fedora-system:FOXML-1.1, encoding: UTF-8,
pid : ucdlib:22350, logMessage: null)
ERROR 2011-10-04 06:29:25.115 [http-8080-39] (AuthFilterJAAS) Login Failure:
all modules ignored
INFO 2011-10-04 06:29:35.887 [http-8080-24] (DefaultManagement) Completed
getDatastream(pid: ucdlib:22350, datastreamID: DC, asOfDateTime: null)
INFO 2011-10-04 06:30:44.628 [http-8080-39] (DefaultManagement) Completed
getDatastream(pid: ucdlib:22350, datastreamID: DC, asOfDateTime: null)
INFO 2011-10-04 06:30:44.671 [http-8080-39] (DefaultDOManager) Committing
modification of ucdlib:22350
INFO 2011-10-04 06:30:44.678 [http-8080-39] (DefaultDOManager) Adding to
ResourceIndex
INFO 2011-10-04 06:30:44.671 [http-8080-39] (DefaultDOManager) Committing
modification of ucdlib:22350
INFO 2011-10-04 06:30:44.678 [http-8080-39] (DefaultDOManager) Adding to
ResourceIndex
INFO 2011-10-04 06:30:44.694 [http-8080-39] (DefaultDOManager) Updating
dissemination index
INFO 2011-10-04 06:30:44.694 [http-8080-39] (DefaultDOManager) Updating
FieldSearch index
INFO 2011-10-04 06:30:44.702 [http-8080-39] (DefaultManagement) Completed
modifyDatastreamByValue(pid: ucdlib:22350, datastreamId: DC, altIDs: , dsLabel:
Dublin Core Record for this object, mimeType: text/xml, formatURI:
http://www.openarchives.org/OAI/2.0/oai_dc/, dsContent , checksumType:
DISABLED, checksum: null, logMessage: null)
INFO 2011-10-04 06:30:44.710 [http-8080-39] (DefaultManagement) Completed
getDatastream(pid: ucdlib:22350, datastreamID: DC, asOfDateTime: null)
ERROR 2011-10-04 06:30:44.789 [http-8080-24] (AuthFilterJAAS) Login Failure:
all modules ignored
INFO 2011-10-04 06:30:57.465 [http-8080-24] (DefaultManagement) Completed
getDatastream(pid: ucdlib:22350, datastreamID: DC, asOfDateTime: null)
INFO 2011-10-04 06:30:57.496 [http-8080-24] (DefaultDOManager) Committing
modification of ucdlib:22350
INFO 2011-10-04 06:30:57.508 [http-8080-24] (DefaultDOManager) Adding to
ResourceIndex
INFO 2011-10-04 06:30:57.530 [http-8080-24] (DefaultDOManager) Updating
dissemination index
INFO 2011-10-04 06:30:57.530 [http-8080-24] (DefaultDOManager) Updating
FieldSearch index
INFO 2011-10-04 06:30:57.538 [http-8080-24] (DefaultManagement) Completed
modifyDatastreamByValue(pid: ucdlib:22350, datastreamId: DC, altIDs: '',
dsLabel: Dublin Core Record for this object, mimeType: text/xml, formatURI:
http://www.openarchives.org/OAI/2.0/oai_dc/, dsContent , checksumType:
DISABLED, checksum: null, logMessage: null)
INFO 2011-10-04 06:30:57.562 [http-8080-24] (DefaultManagement) Completed
getDatastream(pid: ucdlib:22350, datastreamID: DC, asOfDateTime: null)
ERROR 2011-10-04 06:30:57.637 [http-8080-31] (AuthFilterJAAS) Login Failure:
all modules ignored
INFO 2011-10-04 06:30:57.799 [http-8080-39] (DefaultManagement) Completed
getDatastream(pid: ucdlib:22350, datastreamID: DC, asOfDateTime: null)
INFO 2011-10-04 06:31:47.919 [http-8080-24] (DefaultManagement) Completed
getDatastream(pid: ucdlib:22350, datastreamID: descMetadata, asOfDateTime: null)
INFO 2011-10-04 06:31:47.945 [http-8080-24] (DefaultManagement) Removed
uploaded file '46770' because it expired.
INFO 2011-10-04 06:31:48.010 [http-8080-24] (DefaultDOManager) Committing
modification of ucdlib:22350
INFO 2011-10-04 06:31:48.027 [http-8080-24] (DefaultDOManager) Adding to
ResourceIndex
INFO 2011-10-04 06:31:48.044 [http-8080-24] (DefaultDOManager) Updating
dissemination index
INFO 2011-10-04 06:31:48.044 [http-8080-24] (DefaultDOManager) Updating
FieldSearch index
INFO 2011-10-04 06:31:48.050 [http-8080-24] (DefaultManagement) Completed
addDatastream(pid: ucdlib:22350, dsID: descMetadata, altIDs: '', dsLabel: MODS
metadata, versionable: true, MIMEType: text/xml, formatURI: , dsLocation:
uploaded://46771, controlGroup: X, dsState: A, checksumType: Default, checksum:
null, logMessage: null)
INFO 2011-10-04 06:31:48.075 [http-8080-24] (DefaultManagement) Completed
getDatastream(pid: ucdlib:22350, datastreamID: descMetadata, asOfDateTime: null)
ERROR 2011-10-04 06:31:48.152 [http-8080-39] (AuthFilterJAAS) Login Failure:
all modules ignored
------------------------------------------------------------------------------
All of the data generated in your IT infrastructure is seriously valuable.
Why? It contains a definitive record of application performance, security
threats, fraudulent activity, and more. Splunk takes this data and makes
sense of it. IT sense. And common sense.
http://p.sf.net/sfu/splunk-d2dcopy2
_______________________________________________
Fedora-commons-users mailing list
Fedora-commons-users@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/fedora-commons-users
