manifoldcf-user mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Delapasse, Deanna" <ddelapa...@oceaneering.com>
Subject Re: Timeout and strange url in logfile (CMIS=>ES)
Date Mon, 18 May 2015 22:45:38 GMT
I did use curl earlier to run the part that I recognize (the http://...)
and that worked fine.  It looks really strange, but I found a very similar
line in my functional Windows log.  I just realized that "Cancelling
request execution" is also in the 'good log'.  Here's the similar section
from the "good log" which isn''t encoded and is much nicer to read.

IN this one the index='fileshare2' and the mapping='file2' and they show up
in the same weird place so I guess that part is fine.

thanks Karl - I will try to create new indexes and start over.

Deanna

DEBUG 2015-05-18 15:04:50,039 (Thread-1116) - Opening connection {}->
http://localhost:9200
DEBUG 2015-05-18 15:04:50,040 (Thread-1116) - Connecting to localhost/
127.0.0.1:9200
DEBUG 2015-05-18 15:04:50,041 (Thread-1116) - Connection established
127.0.0.1:61274<->127.0.0.1:9200
DEBUG 2015-05-18 15:04:50,041 (Thread-1116) - Executing request PUT
/fileshare2/file2/http%3A%2F%2Flocalhost%3A8080%2Falfresco%2Fapi%2F-default-%2Fpublic%2Fcmis%2Fversions%2F1.1%2Fatom%2Fcontent%2FMySearch%3Fid%3D7dad930f-bcd7-42a5-8935-9ac88fad59ca%253B1.0
HTTP/1.1
DEBUG 2015-05-18 15:04:50,041 (Thread-1116) - Target auth state:
UNCHALLENGED
DEBUG 2015-05-18 15:04:50,041 (Thread-1116) - Proxy auth state: UNCHALLENGED
DEBUG 2015-05-18 15:04:50,042 (Thread-1116) - http-outgoing-0 >> PUT
/fileshare2/file2/http%3A%2F%2Flocalhost%3A8080%2Falfresco%2Fapi%2F-default-%2Fpublic%2Fcmis%2Fversions%2F1.1%2Fatom%2Fcontent%2FMySearch%3Fid%3D7dad930f-bcd7-42a5-8935-9ac88fad59ca%253B1.0
HTTP/1.1
DEBUG 2015-05-18 15:04:50,042 (Thread-1116) - http-outgoing-0 >>
Transfer-Encoding: chunked
DEBUG 2015-05-18 15:04:50,042 (Thread-1116) - http-outgoing-0 >>
Content-type: application/x-www-form-urlencoded
DEBUG 2015-05-18 15:04:50,042 (Thread-1116) - http-outgoing-0 >> Host:
localhost:9200
DEBUG 2015-05-18 15:04:50,042 (Thread-1116) - http-outgoing-0 >>
Connection: Keep-Alive
DEBUG 2015-05-18 15:04:50,042 (Thread-1116) - http-outgoing-0 >>
User-Agent: Apache-HttpClient/4.3.5 (java 1.5)
DEBUG 2015-05-18 15:04:50,042 (Thread-1116) - http-outgoing-0 >> Expect:
100-continue
DEBUG 2015-05-18 15:04:50,042 (Thread-1116) - http-outgoing-0 >>
Accept-Encoding: gzip,deflate
DEBUG 2015-05-18 15:04:50,043 (Thread-1116) - http-outgoing-0 >> "PUT
/fileshare2/file2/http%3A%2F%2Flocalhost%3A8080%2Falfresco%2Fapi%2F-default-%2Fpublic%2Fcmis%2Fversions%2F1.1%2Fatom%2Fcontent%2FMySearch%3Fid%3D7dad930f-bcd7-42a5-8935-9ac88fad59ca%253B1.0
HTTP/1.1[\r][\n]"
DEBUG 2015-05-18 15:04:50,043 (Thread-1116) - http-outgoing-0 >>
"Transfer-Encoding: chunked[\r][\n]"
DEBUG 2015-05-18 15:04:50,043 (Thread-1116) - http-outgoing-0 >>
"Content-type: application/x-www-form-urlencoded[\r][\n]"
DEBUG 2015-05-18 15:04:50,043 (Thread-1116) - http-outgoing-0 >> "Host:
localhost:9200[\r][\n]"
DEBUG 2015-05-18 15:04:50,043 (Thread-1116) - http-outgoing-0 >>
"Connection: Keep-Alive[\r][\n]"
DEBUG 2015-05-18 15:04:50,043 (Thread-1116) - http-outgoing-0 >>
"User-Agent: Apache-HttpClient/4.3.5 (java 1.5)[\r][\n]"
DEBUG 2015-05-18 15:04:50,043 (Thread-1116) - http-outgoing-0 >> "Expect:
100-continue[\r][\n]"
DEBUG 2015-05-18 15:04:50,043 (Thread-1116) - http-outgoing-0 >>
"Accept-Encoding: gzip,deflate[\r][\n]"
DEBUG 2015-05-18 15:04:50,043 (Thread-1116) - http-outgoing-0 >> "[\r][\n]"
DEBUG 2015-05-18 15:04:50,043 (Thread-1116) - http-outgoing-0 << "HTTP/1.1
100 Continue[\r][\n]"
DEBUG 2015-05-18 15:04:50,043 (Thread-1116) - http-outgoing-0 << "[\r][\n]"
DEBUG 2015-05-18 15:04:50,045 (Thread-1116) - http-outgoing-0 << HTTP/1.1
100 Continue
DEBUG 2015-05-18 15:04:50,046 (Thread-1116) - http-outgoing-0 >>
"15d[\r][\n]"
DEBUG 2015-05-18 15:04:50,046 (Thread-1116) - http-outgoing-0 >>
"{"allow_token_document" : "__nosecurity__","deny_token_document" :
"__nosecurity__","allow_token_share" : "__nosecurity__","deny_token_share"
: "__nosecurity__","allow_token_parent" :
"__nosecurity__","deny_token_parent" : "__nosecurity__","file" :
{"_content_type" : "text\/plain","_name" : "MySearch", "_content" :
"c2VhcmNoCnRleHQKZm9yCnp1bHUK"}}[\r][\n]"
DEBUG 2015-05-18 15:04:50,048 (Thread-1116) - http-outgoing-0 >> "0[\r][\n]"
DEBUG 2015-05-18 15:04:50,048 (Thread-1116) - http-outgoing-0 >> "[\r][\n]"
DEBUG 2015-05-18 15:04:50,053 (Thread-1116) - http-outgoing-0 << "HTTP/1.1
201 Created[\r][\n]"
DEBUG 2015-05-18 15:04:50,053 (Thread-1116) - http-outgoing-0 <<
"Content-Type: application/json; charset=UTF-8[\r][\n]"
DEBUG 2015-05-18 15:04:50,053 (Thread-1116) - http-outgoing-0 <<
"Content-Length: 213[\r][\n]"
DEBUG 2015-05-18 15:04:50,053 (Thread-1116) - http-outgoing-0 << "[\r][\n]"
DEBUG 2015-05-18 15:04:50,054 (Thread-1116) - http-outgoing-0 <<
"{"_index":"fileshare2","_type":"file2","_id":"
http://localhost:8080/alfresco/api/-default-/public/cmis/versions/1.1/atom/content/MySearch?id=7dad930f-bcd7-42a5-8935-9ac88fad59ca%3B1.0
","_version":1,"created":true}"
DEBUG 2015-05-18 15:04:50,054 (Thread-1116) - http-outgoing-0 << HTTP/1.1
201 Created
DEBUG 2015-05-18 15:04:50,054 (Thread-1116) - http-outgoing-0 <<
Content-Type: application/json; charset=UTF-8
DEBUG 2015-05-18 15:04:50,054 (Thread-1116) - http-outgoing-0 <<
Content-Length: 213
DEBUG 2015-05-18 15:04:50,056 (Thread-1116) - Connection can be kept alive
indefinitely
DEBUG 2015-05-18 15:04:50,060 (Thread-1116) - Connection [id: 0][route: {}->
http://localhost:9200] can be kept alive indefinitely
DEBUG 2015-05-18 15:04:50,060 (Thread-1116) - Connection released: [id:
0][route: {}->http://localhost:9200][total kept alive: 1; route allocated:
1 of 2; total allocated: 1 of 20]
DEBUG 2015-05-18 15:04:50,060 (Thread-1116) - Cancelling request execution
DEBUG 2015-05-18 15:04:50,063 (Worker thread '11') - dld completed: ingest
doc
DEBUG 2015-05-18 15:04:50,063 (Worker thread '11') - dld Completed
processing all documents!
DEBUG 2015-05-18 15:04:53,701 (Worker thread '13') - dld CMIS: Processing
document identifier


On Mon, May 18, 2015 at 5:33 PM, Karl Wright <daddywri@gmail.com> wrote:

> Hi Deanna,
>
> Do you have the "curl" utility installed?
>
> The request that is failing looks fine as far as I can tell.  I would try
> submitting it with curl and debugging the ElasticSearch side, because
> clearly that's where something is going wrong.
>
> PUT /csss/base/http%3A%2F%2Flocalhost%3A8080%2Falfresco%
> 2Fapi%2F-default-%2Fpublic%2Fcmis%2Fversions%2F1.1%
> 2Fatom%2Fcontent%2F6.5.txt%3Fid%3D082ded6d-fef4-47d6-
> a53a-e27d3a4ec581%253B1.2
>
> Content-type:  application/x-www-form-urlencoded
>
> Content:
>
> {"csss:csssBase_bookOwner" : "someBookOwner","cmis:name" :
> "6.5.txt","csss:csssBase_cageCode" : "someCageCode","allow_token_document"
> : "__nosecurity__","deny_token_document" : "__nosecurity__","allow_token_share"
> : "__nosecurity__","deny_token_share" : "__nosecurity__","allow_token_parent"
> : "__nosecurity__","deny_token_parent" : "__nosecurity__","file" :
> {"_content_type" : "text\/plain","_name" : "6.5.txt", "_content" : "
> DQpJIGFtIGFuIEFsZnJlc2NvIGZpbGUuDQoNCmtleXdvcmQga2FuZ2Fyb28N
> Cg0KenVsdQ0KenVsdQ0KDQo="}}
>
>
> Karl
>
>
> On Mon, May 18, 2015 at 6:11 PM, Delapasse, Deanna <
> ddelapasse@oceaneering.com> wrote:
>
>> I've been running locally on windows (CMIS->ElasticSearch)  successfully
>> and today I tried to run the same configuration.  It worked for a while,
>> but I deleted my ES indexes and recreated them.  Since then I've had
>> problems.  I am currently just trying to index a single row (ie my CMIS
>> query returns 1 row).  I see the data for the row so the select worked, but
>> I can't figure out what is wrong with the ES insert.  The new indexes look
>> find in the browser.
>>
>> Could y'all please take a look and advise me?  The sequence is similar to
>> my windows, but gets "No content is provided" and then cancels the job.
>>
>> thanks!
>> Deanna
>>
>> ps. Yes, I have restarted Manifold, ES & even Alfresco :-).
>>
>>
>> ==================== error log =====================
>>
>> DEBUG 2015-05-18 17:44:56,900 (Thread-6470) - Connection request: [route:
>> {}->http://localhost:9200][total kept alive: 0; route allocated: 0 of 2;
>>  total allocated: 0 of 20]
>> DEBUG 2015-05-18 17:44:56,900 (Thread-6470) - Connection leased: [id:
>> 4][route: {}->http://localhost:9200][total kept alive: 0; route
>> allocated: 1
>>  of 2; total allocated: 1 of 20]
>> DEBUG 2015-05-18 17:44:56,900 (Thread-6470) - Opening connection {}->
>> http://localhost:9200
>> DEBUG 2015-05-18 17:44:56,900 (Thread-6470) - Connecting to localhost/
>> 127.0.0.1:9200
>> DEBUG 2015-05-18 17:44:56,900 (Thread-6470) - Connection established
>> 127.0.0.1:55692<->127.0.0.1:9200
>> DEBUG 2015-05-18 17:44:56,900 (Thread-6470) - Executing request PUT
>> /csss/base/http%3A%2F%2Flocalhost%3A8080%2Falfresco%2Fapi%2F-default-%2Fpublic
>> %2Fcmis%2Fversions%2F1.1%2Fatom%2Fcontent%2F6.5.txt%3Fid%3D082ded6d-fef4-47d6-a53a-e27d3a4ec581%253B1.2
>> HTTP/1.1
>> DEBUG 2015-05-18 17:44:56,900 (Thread-6470) - Target auth state:
>> UNCHALLENGED
>> DEBUG 2015-05-18 17:44:56,900 (Thread-6470) - Proxy auth state:
>> UNCHALLENGED
>> DEBUG 2015-05-18 17:44:56,900 (Thread-6470) - http-outgoing-4 >> PUT
>> /csss/base/http%3A%2F%2Flocalhost%3A8080%2Falfresco%2Fapi%2F-default-%2Fpubli
>> c%2Fcmis%2Fversions%2F1.1%2Fatom%2Fcontent%2F6.5.txt%3Fid%3D082ded6d-fef4-47d6-a53a-e27d3a4ec581%253B1.2
>> HTTP/1.1
>> DEBUG 2015-05-18 17:44:56,901 (Thread-6470) - http-outgoing-4 >>
>> Transfer-Encoding: chunked
>> DEBUG 2015-05-18 17:44:56,901 (Thread-6470) - http-outgoing-4 >>
>> Content-type: application/x-www-form-urlencoded
>> DEBUG 2015-05-18 17:44:56,901 (Thread-6470) - http-outgoing-4 >> Host:
>> localhost:9200
>> DEBUG 2015-05-18 17:44:56,901 (Thread-6470) - http-outgoing-4 >>
>> Connection: Keep-Alive
>> DEBUG 2015-05-18 17:44:56,901 (Thread-6470) - http-outgoing-4 >>
>> User-Agent: Apache-HttpClient/4.3.5 (java 1.5)
>> DEBUG 2015-05-18 17:44:56,901 (Thread-6470) - http-outgoing-4 >> Expect:
>> 100-continue
>> DEBUG 2015-05-18 17:44:56,901 (Thread-6470) - http-outgoing-4 >>
>> Accept-Encoding: gzip,deflate
>> DEBUG 2015-05-18 17:44:56,901 (Thread-6470) - http-outgoing-4 >> "PUT
>> /csss/base/http%3A%2F%2Flocalhost%3A8080%2Falfresco%2Fapi%2F-default-%2Fpubl
>> ic%2Fcmis%2Fversions%2F1.1%2Fatom%2Fcontent%2F6.5.txt%3Fid%3D082ded6d-fef4-47d6-a53a-e27d3a4ec581%253B1.2
>> HTTP/1.1[\r][\n]"
>> DEBUG 2015-05-18 17:44:56,901 (Thread-6470) - http-outgoing-4 >>
>> "Transfer-Encoding: chunked[\r][\n]"
>> DEBUG 2015-05-18 17:44:56,901 (Thread-6470) - http-outgoing-4 >>
>> "Content-type: application/x-www-form-urlencoded[\r][\n]"
>> DEBUG 2015-05-18 17:44:56,901 (Thread-6470) - http-outgoing-4 >> "Host:
>> localhost:9200[\r][\n]"
>> DEBUG 2015-05-18 17:44:56,901 (Thread-6470) - http-outgoing-4 >>
>> "Connection: Keep-Alive[\r][\n]"
>> DEBUG 2015-05-18 17:44:56,901 (Thread-6470) - http-outgoing-4 >>
>> "User-Agent: Apache-HttpClient/4.3.5 (java 1.5)[\r][\n]"
>> DEBUG 2015-05-18 17:44:56,901 (Thread-6470) - http-outgoing-4 >> "Expect:
>> 100-continue[\r][\n]"
>> DEBUG 2015-05-18 17:44:56,901 (Thread-6470) - http-outgoing-4 >>
>> "Accept-Encoding: gzip,deflate[\r][\n]"
>> DEBUG 2015-05-18 17:44:56,901 (Thread-6470) - http-outgoing-4 >>
>> "[\r][\n]"
>> DEBUG 2015-05-18 17:44:56,901 (Thread-6470) - http-outgoing-4 <<
>> "HTTP/1.1 100 Continue[\r][\n]"
>> DEBUG 2015-05-18 17:44:56,901 (Thread-6470) - http-outgoing-4 <<
>> "[\r][\n]"
>> DEBUG 2015-05-18 17:44:56,901 (Thread-6470) - http-outgoing-4 << HTTP/1.1
>> 100 Continue
>> DEBUG 2015-05-18 17:44:56,902 (Thread-6470) - http-outgoing-4 >>
>> "202[\r][\n]"
>> DEBUG 2015-05-18 17:44:56,902 (Thread-6470) - http-outgoing-4 >>
>> "{"csss:csssBase_bookOwner" : "someBookOwner","cmis:name" :
>> "6.5.txt","csss:csssBase_cageCode" : "someCageCode","allow_token_document"
>> : "__nosecurity__","deny_token_document" :
>> "__nosecurity__","allow_token_share" : "__nosecurity__","deny_token_share"
>> : "__nosecurity__","allow_token_parent" :
>> "__nosecurity__","deny_token_parent" : "__nosecurity__","file" :
>> {"_content_type" : "text\/plain","_name" : "6.5.txt", "_content" :
>> "DQpJIGFtIGFuIEFsZnJlc2NvIGZpbGUuDQoNCmtleXdvcmQga2FuZ2Fyb28NCg0KenVsdQ0KenVsdQ0KDQo="}}[\r][\n]"
>> DEBUG 2015-05-18 17:44:56,902 (Thread-6470) - http-outgoing-4 >>
>> "0[\r][\n]"
>> DEBUG 2015-05-18 17:44:56,902 (Thread-6470) - http-outgoing-4 >>
>> "[\r][\n]"
>> DEBUG 2015-05-18 17:44:56,904 (Thread-6470) - http-outgoing-4 <<
>> "HTTP/1.1 400 Bad Request[\r][\n]"
>> DEBUG 2015-05-18 17:44:56,904 (Thread-6470) - http-outgoing-4 <<
>> "Content-Type: application/json; charset=UTF-8[\r][\n]"
>> DEBUG 2015-05-18 17:44:56,904 (Thread-6470) - http-outgoing-4 <<
>> "Content-Length: 72[\r][\n]"
>> DEBUG 2015-05-18 17:44:56,904 (Thread-6470) - http-outgoing-4 <<
>> "[\r][\n]"
>> DEBUG 2015-05-18 17:44:56,904 (Thread-6470) - http-outgoing-4 <<
>> "{"error":"MapperParsingException[No content is provided.]","status":400}"
>> DEBUG 2015-05-18 17:44:56,904 (Thread-6470) - http-outgoing-4 << HTTP/1.1
>> 400 Bad Request
>> DEBUG 2015-05-18 17:44:56,904 (Thread-6470) - http-outgoing-4 <<
>> Content-Type: application/json; charset=UTF-8
>> DEBUG 2015-05-18 17:44:56,904 (Thread-6470) - http-outgoing-4 <<
>> Content-Length: 72
>> DEBUG 2015-05-18 17:44:56,904 (Thread-6470) - Connection can be kept
>> alive indefinitely
>> DEBUG 2015-05-18 17:44:56,904 (Thread-6470) - Connection [id: 4][route:
>> {}->http://localhost:9200] can be kept alive indefinitely
>> DEBUG 2015-05-18 17:44:56,904 (Thread-6470) - Connection released: [id:
>> 4][route: {}->http://localhost:9200][total kept alive: 1; route
>> allocated: 1 of 2; total allocated: 1 of 20]
>> DEBUG 2015-05-18 17:44:56,904 (Thread-6470) - Cancelling request execution
>> DEBUG 2015-05-18 17:45:01,496 (Thread-6577) - CookieSpec selected:
>> best-match
>> DEBUG 2015-05-18 17:45:01,497 (Thread-6577) - Auth cache not set in the
>> context
>> DEBUG 2015-05-18 17:45:01,497 (Thread-6577) - Connection request: [route:
>> {}->http://localhost:9200][total kept alive: 1; route allocated: 1 of 2;
>>
>>
>

Mime
View raw message