Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

Error creating CKAN datastore in row mode #2150

Open
manucarrace opened this issue Apr 8, 2022 · 6 comments
Open

Error creating CKAN datastore in row mode #2150

manucarrace opened this issue Apr 8, 2022 · 6 comments
Milestone
release/2.18.0

Comments

@manucarrace
Copy link
Collaborator

Given a CKAN sink in row mode, when trying to persist data into a non-existing resource, cygnus is not able to create the datastore. It shows the following traces:

It seems to have problems in creating the view ... No estoy seguro de para qué se necesita la creación de la vista ...

time=2022-04-07T16:00:26.959Z | lvl=INFO | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=getEvents | comp=Cygnus | msg=com.telefonica.iot.cygnus.handlers.NGSIRestHandler[324] : [NGSIRestHandler] Received data ({"subscriptionId":"624eb32ec5ee416c8634382b","data":[{"id":"estacion001","type":"estacion","humedad":{"type":"int","value":95,"metadata":{}},"temperatura":{"type":"int","value":5,"metadata":{}}}]})
time=2022-04-07T16:00:26.960Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=getEvents | comp=Cygnus | msg=com.telefonica.iot.cygnus.handlers.NGSIRestHandler[342] : [NGSIRestHandler] Parsed NotifyContextRequest on normalized NGSIv2: {"subscriptionId":"624eb32ec5ee416c8634382b","originator":"null","contextResponses":[{"contextElement":{"id":"estacion001","type":"estacion","isPattern":"null","attributes":[{"name":"humedad","type":"int","value":95,"metadatas":[]},{"name":"temperatura","type":"int","value":5,"metadatas":[]}]},"statusCode":{"code":"null","reasonPhrase":"null"}}]}
time=2022-04-07T16:00:26.960Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=getEvents | comp=Cygnus | msg=com.telefonica.iot.cygnus.handlers.NGSIRestHandler[391] : [NGSIRestHandler] NGSI event created for ContextElementResponse: {"contextElement":{"id":"estacion001","type":"estacion","isPattern":"null","attributes":[{"name":"humedad","type":"int","value":95,"metadatas":[]},{"name":"temperatura","type":"int","value":5,"metadatas":[]}]},"statusCode":{"code":"null","reasonPhrase":"null"}}
time=2022-04-07T16:00:26.960Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=getEvents | comp=Cygnus | msg=com.telefonica.iot.cygnus.handlers.NGSIRestHandler[396] : [NGSIRestHandler] Header added to NGSI event (fiware-service: test)
time=2022-04-07T16:00:26.960Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=getEvents | comp=Cygnus | msg=com.telefonica.iot.cygnus.handlers.NGSIRestHandler[399] : [NGSIRestHandler] Header added to NGSI event (fiware-servicepath: /elena)
time=2022-04-07T16:00:26.960Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=getEvents | comp=Cygnus | msg=com.telefonica.iot.cygnus.handlers.NGSIRestHandler[402] : [NGSIRestHandler] Header added to NGSI event (fiware-correlator: e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1)
time=2022-04-07T16:00:26.960Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=getEvents | comp=Cygnus | msg=com.telefonica.iot.cygnus.handlers.NGSIRestHandler[405] : [NGSIRestHandler] Header added to NGSI event (transaction-id: 8a537731-1221-473c-8fd6-9d64ca7b5484)
time=2022-04-07T16:00:26.960Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=getEvents | comp=Cygnus | msg=com.telefonica.iot.cygnus.handlers.NGSIRestHandler[409] : [NGSIRestHandler] Header added to NGSI event (ngsiv2-attrsformat: normalized)
time=2022-04-07T16:00:26.960Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=getEvents | comp=Cygnus | msg=com.telefonica.iot.cygnus.handlers.NGSIRestHandler[435] : [NGSIRestHandler] NGSI events put in the channel, ids=166740111
time=2022-04-07T16:00:26.961Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=intercept | comp=Cygnus | msg=com.telefonica.iot.cygnus.interceptors.NGSINameMappingsInterceptor[85] : [nmi] Event intercepted, id=166740111
time=2022-04-07T16:00:26.961Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=doMap | comp=Cygnus | msg=com.telefonica.iot.cygnus.interceptors.NGSINameMappingsInterceptor[329] : [nmi] FIWARE service found: test
time=2022-04-07T16:00:26.961Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=doMap | comp=Cygnus | msg=com.telefonica.iot.cygnus.interceptors.NGSINameMappingsInterceptor[349] : [nmi] checking with servicePathMappings: {"originalServicePath":"/elena","newServicePath":"/elena","entityMappings": [{"originalEntityId":"catedral","originalEntityType":"estMeteo","newEntityId":"catedral","newEntityType":"estMeteo","attributeMappings":[]}]}
time=2022-04-07T16:00:26.961Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=doMap | comp=Cygnus | msg=com.telefonica.iot.cygnus.interceptors.NGSINameMappingsInterceptor[354] : [nmi] FIWARE service path found: /elena
time=2022-04-07T16:00:26.961Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=doMap | comp=Cygnus | msg=com.telefonica.iot.cygnus.interceptors.NGSINameMappingsInterceptor[357] : [nmi] checking em: {"originalEntityId":"catedral","originalEntityType":"estMeteo","newEntityId":"catedral","newEntityType":"estMeteo","attributeMappings":[]}
time=2022-04-07T16:00:26.961Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=doMap | comp=Cygnus | msg=com.telefonica.iot.cygnus.interceptors.NGSINameMappingsInterceptor[387] : [nmi] FIWARE service path not found: /elena
time=2022-04-07T16:00:26.961Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=intercept | comp=Cygnus | msg=com.telefonica.iot.cygnus.interceptors.NGSINameMappingsInterceptor[100] : [nmi] Mapped ContextElement: {"id":"estacion001","type":"estacion","isPattern":"null","attributes":[{"name":"humedad","type":"int","value":95,"metadatas":[]},{"name":"temperatura","type":"int","value":5,"metadatas":[]}]}
time=2022-04-07T16:00:26.961Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=intercept | comp=Cygnus | msg=com.telefonica.iot.cygnus.interceptors.NGSINameMappingsInterceptor[112] : [nmi] newBody: {"id":"estacion001","type":"estacion","isPattern":"null","attributes":[{"name":"humedad","type":"int","value":95,"metadatas":[]},{"name":"temperatura","type":"int","value":5,"metadatas":[]}]}xffff{"id":"estacion001","type":"estacion","isPattern":"null","attributes":[{"name":"humedad","type":"int","value":95,"metadatas":[]},{"name":"temperatura","type":"int","value":5,"metadatas":[]}]}
time=2022-04-07T16:00:26.961Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=intercept | comp=Cygnus | msg=com.telefonica.iot.cygnus.interceptors.NGSINameMappingsInterceptor[116] : [nmi] Header added to NGSI event (mapped-fiware-service: test)
time=2022-04-07T16:00:26.962Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=intercept | comp=Cygnus | msg=com.telefonica.iot.cygnus.interceptors.NGSINameMappingsInterceptor[119] : [nmi] Header added to NGSI event (mapped-fiware-servicepath: /elena)
time=2022-04-07T16:00:26.962Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=intercept | comp=Cygnus | msg=com.telefonica.iot.cygnus.interceptors.NGSINameMappingsInterceptor[123] : [nmi] Event put in the channel, id=166740111
time=2022-04-07T16:00:26.962Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=sendResponse | comp=Cygnus | msg=org.eclipse.jetty.server.HttpChannel[693] : sendResponse info=null content=HeapByteBuffer@eaf5bf0[p=0,l=0,c=0,r=0]={<<<>>>} complete=false committing=true callback=Blocker@35ddc547{null}
time=2022-04-07T16:00:26.962Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=commit | comp=Cygnus | msg=org.eclipse.jetty.server.HttpChannel[747] : COMMIT for /notify on HttpChannelOverHttp@4e265c30{r=1,c=true,a=DISPATCHED,uri=//iot-cygnus:5052/notify}
200 null HTTP/1.1
Date: Thu, 07 Apr 2022 16:00:26 GMT

time=2022-04-07T16:00:26.962Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=process | comp=Cygnus | msg=org.eclipse.jetty.server.HttpConnection$SendCallback[694] : org.eclipse.jetty.server.HttpConnection$SendCallback@64171b9[PROCESSING][i=HTTP/1.1{s=200,h=1,cl=-1},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@dbebdc6] generate: NEED_HEADER (null,[p=0,l=0,c=0,r=0],false)@start
time=2022-04-07T16:00:26.963Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=aggregate | comp=Cygnus | msg=com.telefonica.iot.cygnus.aggregation.NGSIGenericRowAggregator[68] : [NGSIUtils.GenericRowAggregator] Processing context element (id=estacion001, type=estacion)
time=2022-04-07T16:00:26.963Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=generateHeaders | comp=Cygnus | msg=org.eclipse.jetty.http.HttpGenerator[642] : generateHeaders HTTP/1.1{s=200,h=1,cl=-1} last=false content=HeapByteBuffer@eaf5bf0[p=0,l=0,c=0,r=0]={<<<>>>}
time=2022-04-07T16:00:26.963Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=aggregate | comp=Cygnus | msg=com.telefonica.iot.cygnus.aggregation.NGSIGenericRowAggregator[88] : [NGSIUtils.GenericRowAggregator] Processing context attribute (name=humedad, type=int)
time=2022-04-07T16:00:26.963Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=generateHeaders | comp=Cygnus | msg=org.eclipse.jetty.http.HttpGenerator[643] : Date: Thu, 07 Apr 2022 16:00:26 GMT

time=2022-04-07T16:00:26.963Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=aggregate | comp=Cygnus | msg=com.telefonica.iot.cygnus.aggregation.NGSIGenericRowAggregator[88] : [NGSIUtils.GenericRowAggregator] Processing context attribute (name=temperatura, type=int)
time=2022-04-07T16:00:26.963Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=generateHeaders | comp=Cygnus | msg=org.eclipse.jetty.http.HttpGenerator[815] : CHUNKED_CONTENT
time=2022-04-07T16:00:26.963Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=process | comp=Cygnus | msg=org.eclipse.jetty.server.HttpConnection$SendCallback[694] : org.eclipse.jetty.server.HttpConnection$SendCallback@64171b9[PROCESSING][i=HTTP/1.1{s=200,h=1,cl=-1},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@dbebdc6] generate: FLUSH ([p=0,l=116,c=8192,r=116],[p=0,l=0,c=0,r=0],false)@committed
time=2022-04-07T16:00:26.964Z | lvl=INFO | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=persistAggregation | comp=Cygnus | msg=com.telefonica.iot.cygnus.sinks.NGSICKANSink[380] : [ckan-sink] Persisting data at NGSICKANSink (orgName=test, pkgName=test_elena, resName=estacion001_estacion, data={"recvTimeTs":"1649347226961","recvTime":"2022-04-07T16:00:26.961Z","fiwareServicePath":"/elena","entityId":"estacion001","entityType":"estacion","attrName":"humedad","attrType":"int","attrValue":95},{"recvTimeTs":"1649347226961","recvTime":"2022-04-07T16:00:26.961Z","fiwareServicePath":"/elena","entityId":"estacion001","entityType":"estacion","attrName":"temperatura","attrType":"int","attrValue":5})
time=2022-04-07T16:00:26.964Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=persist | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.ckan.CKANBackendImpl[83] : Going to lookup for the resource id, the cache may be updated during the process (orgName=test, pkgName=test_elena, resName=estacion001_estacion)
time=2022-04-07T16:00:26.964Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=flush | comp=Cygnus | msg=org.eclipse.jetty.io.ChannelEndPoint[288] : flushed 116 SocketChannelEndPoint@f1b560b{/172.17.0.28:42756<->/172.17.0.16:5052,OPEN,fill=-,flush=W,to=15/30000}{io=0/0,kio=0,kro=1}->HttpConnection@7fa2d352[p=HttpParser{s=END,196 of 196},g=HttpGenerator@5232a2d5{s=COMMITTED}]=>HttpChannelOverHttp@4e265c30{r=1,c=true,a=DISPATCHED,uri=//iot-cygnus:5052/notify}
time=2022-04-07T16:00:26.964Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=flush | comp=Cygnus | msg=org.eclipse.jetty.io.WriteFlusher[433] : Flushed=true 116/116+0 WriteFlusher@79ba175a{WRITING}->null
time=2022-04-07T16:00:26.964Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=process | comp=Cygnus | msg=org.eclipse.jetty.server.HttpConnection$SendCallback[694] : org.eclipse.jetty.server.HttpConnection$SendCallback@64171b9[PROCESSING][i=HTTP/1.1{s=200,h=1,cl=-1},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@dbebdc6] generate: DONE ([p=116,l=116,c=8192,r=0],[p=0,l=0,c=0,r=0],false)@committed
time=2022-04-07T16:00:26.964Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=doScope | comp=Cygnus | msg=org.eclipse.jetty.server.session.SessionHandler[1574] : FinalSession=null old_session_manager=null this=org.eclipse.jetty.server.session.SessionHandler133263006==dftMaxIdleSec=-1
time=2022-04-07T16:00:26.965Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=handle | comp=Cygnus | msg=org.eclipse.jetty.server.Server[567] : handled=true async=false committed=true on HttpChannelOverHttp@4e265c30{r=1,c=true,a=DISPATCHED,uri=//iot-cygnus:5052/notify}
time=2022-04-07T16:00:26.965Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=unhandle | comp=Cygnus | msg=org.eclipse.jetty.server.HttpChannelState[397] : unhandle HttpChannelState@2a5c731c{s=DISPATCHED a=NOT_ASYNC i=true r=READY w=false}
time=2022-04-07T16:00:26.965Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=handle | comp=Cygnus | msg=org.eclipse.jetty.server.HttpChannel[286] : HttpChannelOverHttp@4e265c30{r=1,c=true,a=COMPLETING,uri=//iot-cygnus:5052/notify} action COMPLETE
time=2022-04-07T16:00:26.965Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=sendResponse | comp=Cygnus | msg=org.eclipse.jetty.server.HttpChannel[693] : sendResponse info=null content=HeapByteBuffer@eaf5bf0[p=0,l=0,c=0,r=0]={<<<>>>} complete=true committing=false callback=Blocker@35ddc547{null}
time=2022-04-07T16:00:26.965Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=process | comp=Cygnus | msg=org.eclipse.jetty.server.HttpConnection$SendCallback[694] : org.eclipse.jetty.server.HttpConnection$SendCallback@64171b9[PROCESSING][i=null,cb=Blocker@35ddc547{null}] generate: CONTINUE (null,[p=0,l=0,c=0,r=0],true)@completing
time=2022-04-07T16:00:26.964Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=isCachedOrg | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.ckan.CKANCache[177] : Organization not found in the cache, querying CKAN for it (orgName=test)
time=2022-04-07T16:00:26.965Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=process | comp=Cygnus | msg=org.eclipse.jetty.server.HttpConnection$SendCallback[694] : org.eclipse.jetty.server.HttpConnection$SendCallback@64171b9[PROCESSING][i=null,cb=Blocker@35ddc547{null}] generate: NEED_CHUNK (null,[p=0,l=0,c=0,r=0],true)@completing
time=2022-04-07T16:00:26.965Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=doCKANCacheRequest | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.ckan.CKANCache[525] : doCKACacheNRequest GET to /api/3/action/organization_show?id=test&include_datasets=true
time=2022-04-07T16:00:26.965Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=process | comp=Cygnus | msg=org.eclipse.jetty.server.HttpConnection$SendCallback[694] : org.eclipse.jetty.server.HttpConnection$SendCallback@64171b9[PROCESSING][i=null,cb=Blocker@35ddc547{null}] generate: FLUSH (null,[p=0,l=0,c=0,r=0],true)@completing
time=2022-04-07T16:00:26.966Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=doRequest | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.http.HttpBackend[212] : Http request: GET http://172.17.0.22:5000/api/3/action/organization_show?id=test&include_datasets=true HTTP/1.1
time=2022-04-07T16:00:26.966Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=flush | comp=Cygnus | msg=org.eclipse.jetty.io.ChannelEndPoint[288] : flushed 5 SocketChannelEndPoint@f1b560b{/172.17.0.28:42756<->/172.17.0.16:5052,OPEN,fill=-,flush=W,to=2/30000}{io=0/0,kio=0,kro=1}->HttpConnection@7fa2d352[p=HttpParser{s=END,196 of 196},g=HttpGenerator@5232a2d5{s=COMPLETING}]=>HttpChannelOverHttp@4e265c30{r=1,c=true,a=COMPLETING,uri=//iot-cygnus:5052/notify}
time=2022-04-07T16:00:26.966Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=flush | comp=Cygnus | msg=org.eclipse.jetty.io.WriteFlusher[433] : Flushed=true 5/5+0 WriteFlusher@79ba175a{WRITING}->null
time=2022-04-07T16:00:26.966Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=process | comp=Cygnus | msg=org.eclipse.jetty.server.HttpConnection$SendCallback[694] : org.eclipse.jetty.server.HttpConnection$SendCallback@64171b9[PROCESSING][i=null,cb=Blocker@35ddc547{null}] generate: DONE (null,[p=0,l=0,c=0,r=0],true)@EnD
time=2022-04-07T16:00:26.966Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=onComplete | comp=Cygnus | msg=org.eclipse.jetty.server.HttpChannelState[855] : onComplete HttpChannelState@2a5c731c{s=COMPLETING a=NOT_ASYNC i=false r=READY w=false}
time=2022-04-07T16:00:26.966Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=onCompleted | comp=Cygnus | msg=org.eclipse.jetty.server.HttpChannel[624] : COMPLETE for /notify written=0
time=2022-04-07T16:00:26.966Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=onReadEof | comp=Cygnus | msg=org.eclipse.jetty.server.HttpChannelState[1288] : onEof HttpChannelState@2a5c731c{s=COMPLETED a=NOT_ASYNC i=false r=READY w=false}
time=2022-04-07T16:00:26.966Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=recycle | comp=Cygnus | msg=org.eclipse.jetty.server.HttpChannelState[912] : recycle HttpChannelState@2a5c731c{s=COMPLETED a=NOT_ASYNC i=false r=READY w=false}
time=2022-04-07T16:00:26.966Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=reset | comp=Cygnus | msg=org.eclipse.jetty.http.HttpParser[1666] : reset HttpParser{s=END,196 of 196}
time=2022-04-07T16:00:26.966Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=setState | comp=Cygnus | msg=org.eclipse.jetty.http.HttpParser[1687] : END --> START
time=2022-04-07T16:00:26.967Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=handle | comp=Cygnus | msg=org.eclipse.jetty.server.HttpChannel[465] : HttpChannelOverHttp@4e265c30{r=1,c=false,a=IDLE,uri=null} handle exit, result COMPLETE
time=2022-04-07T16:00:26.967Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=fill | comp=Cygnus | msg=org.eclipse.jetty.io.ChannelEndPoint[242] : filled 0 SocketChannelEndPoint@f1b560b{/172.17.0.28:42756<->/172.17.0.16:5052,OPEN,fill=-,flush=-,to=1/30000}{io=0/0,kio=0,kro=1}->HttpConnection@7fa2d352[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5232a2d5{s=START}]=>HttpChannelOverHttp@4e265c30{r=1,c=false,a=IDLE,uri=null}
time=2022-04-07T16:00:26.967Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=fill | comp=Cygnus | msg=org.eclipse.jetty.io.ChannelEndPoint[242] : filled 0 SocketChannelEndPoint@f1b560b{/172.17.0.28:42756<->/172.17.0.16:5052,OPEN,fill=-,flush=-,to=1/30000}{io=0/0,kio=0,kro=1}->HttpConnection@7fa2d352[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5232a2d5{s=START}]=>HttpChannelOverHttp@4e265c30{r=1,c=false,a=IDLE,uri=null}
time=2022-04-07T16:00:26.968Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=fillRequestBuffer | comp=Cygnus | msg=org.eclipse.jetty.server.HttpConnection[331] : HttpConnection@7fa2d352[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5232a2d5{s=START}]=>HttpChannelOverHttp@4e265c30{r=1,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@f1b560b{/172.17.0.28:42756<->/172.17.0.16:5052,OPEN,fill=-,flush=-,to=2/30000}{io=0/0,kio=0,kro=1}->HttpConnection@7fa2d352[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5232a2d5{s=START}]=>HttpChannelOverHttp@4e265c30{r=1,c=false,a=IDLE,uri=null} filled 0 HeapByteBuffer@69dfbfc0[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 200 OK\r\n...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
time=2022-04-07T16:00:26.968Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=parseRequestBuffer | comp=Cygnus | msg=org.eclipse.jetty.server.HttpConnection[349] : HttpConnection@7fa2d352[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5232a2d5{s=START}]=>HttpChannelOverHttp@4e265c30{r=1,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@f1b560b{/172.17.0.28:42756<->/172.17.0.16:5052,OPEN,fill=-,flush=-,to=2/30000}{io=0/0,kio=0,kro=1}->HttpConnection@7fa2d352[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5232a2d5{s=START}]=>HttpChannelOverHttp@4e265c30{r=1,c=false,a=IDLE,uri=null} parse HeapByteBuffer@69dfbfc0[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 200 OK\r\n...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} {}
time=2022-04-07T16:00:26.969Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=parseNext | comp=Cygnus | msg=org.eclipse.jetty.http.HttpParser[1344] : parseNext s=START HeapByteBuffer@69dfbfc0[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 200 OK\r\n...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
time=2022-04-07T16:00:26.969Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=parseRequestBuffer | comp=Cygnus | msg=org.eclipse.jetty.server.HttpConnection[354] : HttpConnection@7fa2d352[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5232a2d5{s=START}]=>HttpChannelOverHttp@4e265c30{r=1,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@f1b560b{/172.17.0.28:42756<->/172.17.0.16:5052,OPEN,fill=-,flush=-,to=3/30000}{io=0/0,kio=0,kro=1}->HttpConnection@7fa2d352[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5232a2d5{s=START}]=>HttpChannelOverHttp@4e265c30{r=1,c=false,a=IDLE,uri=null} parsed false HttpParser{s=START,0 of -1}
time=2022-04-07T16:00:26.970Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=releaseRequestBuffer | comp=Cygnus | msg=org.eclipse.jetty.server.HttpConnection[200] : releaseRequestBuffer HttpConnection@7fa2d352[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5232a2d5{s=START}]=>HttpChannelOverHttp@4e265c30{r=1,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@f1b560b{/172.17.0.28:42756<->/172.17.0.16:5052,OPEN,fill=-,flush=-,to=4/30000}{io=0/0,kio=0,kro=1}->HttpConnection@7fa2d352[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5232a2d5{s=START}]=>HttpChannelOverHttp@4e265c30{r=1,c=false,a=IDLE,uri=null}
time=2022-04-07T16:00:26.970Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=fillInterested | comp=Cygnus | msg=org.eclipse.jetty.io.AbstractConnection[133] : fillInterested HttpConnection@7fa2d352[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5232a2d5{s=START}]=>HttpChannelOverHttp@4e265c30{r=1,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@f1b560b{/172.17.0.28:42756<->/172.17.0.16:5052,OPEN,fill=-,flush=-,to=4/30000}{io=0/0,kio=0,kro=1}->HttpConnection@7fa2d352[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5232a2d5{s=START}]=>HttpChannelOverHttp@4e265c30{r=1,c=false,a=IDLE,uri=null}
time=2022-04-07T16:00:26.970Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=tryRegister | comp=Cygnus | msg=org.eclipse.jetty.io.FillInterest[85] : FillInterest@2e09a37f{true,AC.ReadCB@7fa2d352{HttpConnection@7fa2d352[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5232a2d5{s=START}]=>HttpChannelOverHttp@4e265c30{r=1,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@f1b560b{/172.17.0.28:42756<->/172.17.0.16:5052,OPEN,fill=FI,flush=-,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@7fa2d352[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5232a2d5{s=START}]=>HttpChannelOverHttp@4e265c30{r=1,c=false,a=IDLE,uri=null}}} register AC.ReadCB@7fa2d352{HttpConnection@7fa2d352[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5232a2d5{s=START}]=>HttpChannelOverHttp@4e265c30{r=1,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@f1b560b{/172.17.0.28:42756<->/172.17.0.16:5052,OPEN,fill=FI,flush=-,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@7fa2d352[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5232a2d5{s=START}]=>HttpChannelOverHttp@4e265c30{r=1,c=false,a=IDLE,uri=null}}
time=2022-04-07T16:00:26.971Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=changeInterests | comp=Cygnus | msg=org.eclipse.jetty.io.ChannelEndPoint[426] : changeInterests p=false 0->1 for SocketChannelEndPoint@f1b560b{/172.17.0.28:42756<->/172.17.0.16:5052,OPEN,fill=FI,flush=-,to=1/30000}{io=0/1,kio=0,kro=1}->HttpConnection@7fa2d352[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5232a2d5{s=START}]=>HttpChannelOverHttp@4e265c30{r=1,c=false,a=IDLE,uri=null}
time=2022-04-07T16:00:26.971Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=submit | comp=Cygnus | msg=org.eclipse.jetty.io.ManagedSelector[128] : Queued change CEP:SocketChannelEndPoint@f1b560b{/172.17.0.28:42756<->/172.17.0.16:5052,OPEN,fill=FI,flush=-,to=1/30000}{io=0/1,kio=0,kro=1}->HttpConnection@7fa2d352[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5232a2d5{s=START}]=>HttpChannelOverHttp@4e265c30{r=1,c=false,a=IDLE,uri=null}:runUpdateKey:NON_BLOCKING on org.eclipse.jetty.io.ManagedSelector@14c66548 id=0 keys=1 selected=0
time=2022-04-07T16:00:26.971Z | lvl=DEBUG | corr=N/A | trans=N/A | srv=N/A | subsrv=N/A | op=select | comp=Cygnus | msg=org.eclipse.jetty.io.ManagedSelector$SelectorProducer[236] : Selector loop woken up from select, 0/1 selected
time=2022-04-07T16:00:26.971Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=onFillable | comp=Cygnus | msg=org.eclipse.jetty.server.HttpConnection[272] : HttpConnection@7fa2d352[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5232a2d5{s=START}]=>HttpChannelOverHttp@4e265c30{r=1,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@f1b560b{/172.17.0.28:42756<->/172.17.0.16:5052,OPEN,fill=FI,flush=-,to=1/30000}{io=0/1,kio=0,kro=1}->HttpConnection@7fa2d352[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5232a2d5{s=START}]=>HttpChannelOverHttp@4e265c30{r=1,c=false,a=IDLE,uri=null} onFillable exit HttpChannelState@2a5c731c{s=IDLE a=NOT_ASYNC i=true r=IDLE w=false} null
time=2022-04-07T16:00:26.971Z | lvl=DEBUG | corr=N/A | trans=N/A | srv=N/A | subsrv=N/A | op=nextAction | comp=Cygnus | msg=org.eclipse.jetty.io.ManagedSelector$SelectorProducer[214] : Running action CEP:SocketChannelEndPoint@f1b560b{/172.17.0.28:42756<->/172.17.0.16:5052,OPEN,fill=FI,flush=-,to=1/30000}{io=0/1,kio=0,kro=1}->HttpConnection@7fa2d352[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5232a2d5{s=START}]=>HttpChannelOverHttp@4e265c30{r=1,c=false,a=IDLE,uri=null}:runUpdateKey:NON_BLOCKING
time=2022-04-07T16:00:26.973Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=invoke | comp=Cygnus | msg=org.eclipse.jetty.util.thread.Invocable$InvocableExecutor[224] : org.eclipse.jetty.util.thread.Invocable$InvocableExecutor@2cdd343f invoked CEP:SocketChannelEndPoint@f1b560b{/172.17.0.28:42756<->/172.17.0.16:5052,OPEN,fill=FI,flush=-,to=2/30000}{io=0/1,kio=0,kro=1}->HttpConnection@7fa2d352[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5232a2d5{s=START}]=>HttpChannelOverHttp@4e265c30{r=1,c=false,a=IDLE,uri=null}:runFillable:BLOCKING
time=2022-04-07T16:00:26.973Z | lvl=DEBUG | corr=N/A | trans=N/A | srv=N/A | subsrv=N/A | op=updateKey | comp=Cygnus | msg=org.eclipse.jetty.io.ChannelEndPoint[392] : Key interests updated 0 -> 1 on SocketChannelEndPoint@f1b560b{/172.17.0.28:42756<->/172.17.0.16:5052,OPEN,fill=FI,flush=-,to=3/30000}{io=1/1,kio=1,kro=1}->HttpConnection@7fa2d352[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5232a2d5{s=START}]=>HttpChannelOverHttp@4e265c30{r=1,c=false,a=IDLE,uri=null}
time=2022-04-07T16:00:26.973Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=doProduce | comp=Cygnus | msg=org.eclipse.jetty.util.thread.strategy.EatWhatYouKill[314] : EatWhatYouKill@1b6d3b78/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@1e65eb4d/PRODUCING/1/1 produce exit
time=2022-04-07T16:00:26.973Z | lvl=DEBUG | corr=N/A | trans=N/A | srv=N/A | subsrv=N/A | op=select | comp=Cygnus | msg=org.eclipse.jetty.io.ManagedSelector$SelectorProducer[233] : Selector loop waiting on select
time=2022-04-07T16:00:26.974Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=test | subsrv=/elena | op=run | comp=Cygnus | msg=org.eclipse.jetty.util.thread.QueuedThreadPool$2[593] : ran org.eclipse.jetty.io.ManagedSelector$$Lambda$26/1440407085@519543a0
time=2022-04-07T16:00:27.015Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=createJsonResponse | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.http.HttpBackend[324] : Http response status line: HTTP/1.0 200 OK
time=2022-04-07T16:00:27.015Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=createJsonResponse | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.http.HttpBackend[360] : Http response payload: {"help": "https://opendata-test.territoriointeligente.jcyl.es/api/3/action/help_show?name=organization_show", "success": true, "result": {"users": [{"email_hash": "a3175a452c7a8fea80c62a198a40f6c9", "about": "\N", "capacity": "admin", "name": "admin_user", "created": "2019-09-24T14:55:46.831998", "sysadmin": true, "activity_streams_email_notifications": false, "state": "active", "number_of_edits": 18, "display_name": "admin", "fullname": "admin", "id": "7d13cad1-a494-4a7f-b55f-667e262da9b8", "number_created_packages": 1}], "display_name": "test", "description": "", "image_display_url": "", "package_count": 1, "created": "2022-04-07T09:51:54.414304", "name": "test", "is_organization": true, "state": "active", "extras": [], "image_url": "", "groups": [], "type": "organization", "title": "", "revision_id": "fb95b924-2c36-402a-81e8-9a8693cd9c52", "packages": [{"owner_org": "17d96df5-9a2f-4d64-9834-76950fca035e", "maintainer": null, "relationships_as_object": [], "private": false, "maintainer_email": null, "num_tags": 0, "id": "6a3d11d5-b738-4658-b691-a07badaab3e2", "metadata_created": "2022-04-07T12:20:43.615295", "metadata_modified": "2022-04-07T14:56:01.850695", "author": null, "author_email": null, "state": "active", "version": null, "license_id": null, "type": "dataset", "num_resources": 2, "tags": [], "title": "test_elena", "groups": [], "creator_user_id": "7d13cad1-a494-4a7f-b55f-667e262da9b8", "relationships_as_subject": [], "name": "test_elena", "isopen": false, "url": null, "notes": null, "license_title": null, "extras": [], "organization": {"description": "", "title": "", "created": "2022-04-07T09:51:54.414304", "approval_status": "approved", "is_organization": true, "state": "active", "image_url": "", "revision_id": "fb95b924-2c36-402a-81e8-9a8693cd9c52", "type": "organization", "id": "17d96df5-9a2f-4d64-9834-76950fca035e", "name": "test"}, "revision_id": "00d6c559-90fb-4f61-88e6-02a605850eff"}], "num_followers": 0, "id": "17d96df5-9a2f-4d64-9834-76950fca035e", "tags": [], "approval_status": "approved"}}
time=2022-04-07T16:00:27.016Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=isCachedOrg | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.ckan.CKANCache[202] : Organization found in CKAN, now cached (orgName/orgId=test/17d96df5-9a2f-4d64-9834-76950fca035e)
time=2022-04-07T16:00:27.016Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=isCachedOrg | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.ckan.CKANCache[206] : Going to populate the packages cache (orgName=test)
time=2022-04-07T16:00:27.016Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=populatePackagesMap | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.ckan.CKANCache[368] : Packages to be populated: {"isopen":false,"private":false,"notes":null,"num_resources":2,"extras":[],"owner_org":"17d96df5-9a2f-4d64-9834-76950fca035e","type":"dataset","title":"test_elena","maintainer":null,"num_tags":0,"author_email":null,"id":"6a3d11d5-b738-4658-b691-a07badaab3e2","state":"active","relationships_as_object":[],"revision_id":"00d6c559-90fb-4f61-88e6-02a605850eff","creator_user_id":"7d13cad1-a494-4a7f-b55f-667e262da9b8","metadata_modified":"2022-04-07T14:56:01.850695","author":null,"relationships_as_subject":[],"metadata_created":"2022-04-07T12:20:43.615295","groups":[],"version":null,"url":null,"tags":[],"license_id":null,"organization":{"created":"2022-04-07T09:51:54.414304","approval_status":"approved","image_url":"","name":"test","description":"","state":"active","id":"17d96df5-9a2f-4d64-9834-76950fca035e","title":"","is_organization":true,"type":"organization","revision_id":"fb95b924-2c36-402a-81e8-9a8693cd9c52"},"name":"test_elena","maintainer_email":null,"license_title":null}
time=2022-04-07T16:00:27.016Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=populatePackagesMap | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.ckan.CKANCache[389] : Package found in CKAN, now cached (orgName=test -> pkgName/pkgId=test_elena/6a3d11d5-b738-4658-b691-a07badaab3e2)
time=2022-04-07T16:00:27.016Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=getResources | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.ckan.CKANCache[443] : Going to get the resources list from package test_elena
time=2022-04-07T16:00:27.016Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=doCKANCacheRequest | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.ckan.CKANCache[525] : doCKACacheNRequest GET to /api/3/action/package_show?id=test_elena
time=2022-04-07T16:00:27.016Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=doRequest | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.http.HttpBackend[212] : Http request: GET http://172.17.0.22:5000/api/3/action/package_show?id=test_elena HTTP/1.1
time=2022-04-07T16:00:27.034Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=createJsonResponse | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.http.HttpBackend[324] : Http response status line: HTTP/1.0 200 OK
time=2022-04-07T16:00:27.035Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=createJsonResponse | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.http.HttpBackend[360] : Http response payload: {"help": "https://opendata-test.territoriointeligente.jcyl.es/api/3/action/help_show?name=package_show", "success": true, "result": {"license_title": null, "maintainer": null, "relationships_as_object": [], "private": false, "maintainer_email": null, "num_tags": 0, "id": "6a3d11d5-b738-4658-b691-a07badaab3e2", "metadata_created": "2022-04-07T12:20:43.615295", "metadata_modified": "2022-04-07T14:56:01.850695", "author": null, "author_email": null, "state": "active", "version": null, "creator_user_id": "7d13cad1-a494-4a7f-b55f-667e262da9b8", "type": "dataset", "resources": [{"mimetype": null, "cache_url": null, "hash": "", "description": "", "name": "catedral_estmeteo", "format": "CSV", "url": "https://opendata-test.territoriointeligente.jcyl.es/dataset/6a3d11d5-b738-4658-b691-a07badaab3e2/resource/e198ac50-9fe5-4b9f-8653-8665b3b51e11/download/", "datastore_active": true, "cache_last_updated": null, "package_id": "6a3d11d5-b738-4658-b691-a07badaab3e2", "created": "2022-04-07T12:24:39.625750", "state": "active", "mimetype_inner": null, "last_modified": "2022-04-07T14:43:07.855746", "position": 0, "revision_id": "0a0592fd-cb1f-4269-af59-77c9e5714322", "url_type": "upload", "id": "e198ac50-9fe5-4b9f-8653-8665b3b51e11", "resource_type": null, "size": 0}, {"mimetype": null, "cache_url": null, "hash": "", "description": "", "name": "iglesia_estmeteo", "format": "", "url": "http://none", "datastore_active": true, "cache_last_updated": null, "package_id": "6a3d11d5-b738-4658-b691-a07badaab3e2", "created": "2022-04-07T14:56:01.861622", "state": "active", "mimetype_inner": null, "last_modified": null, "position": 1, "revision_id": "4f92aa95-e3fb-4fc3-adf3-cbd7af424925", "url_type": null, "id": "33b9d54d-77fc-43f3-9dd3-72c093461054", "resource_type": null, "size": null}], "num_resources": 2, "tags": [], "groups": [], "license_id": null, "relationships_as_subject": [], "organization": {"description": "", "title": "", "created": "2022-04-07T09:51:54.414304", "approval_status": "approved", "is_organization": true, "state": "active", "image_url": "", "revision_id": "fb95b924-2c36-402a-81e8-9a8693cd9c52", "type": "organization", "id": "17d96df5-9a2f-4d64-9834-76950fca035e", "name": "test"}, "name": "test_elena", "isopen": false, "url": null, "notes": null, "owner_org": "17d96df5-9a2f-4d64-9834-76950fca035e", "extras": [], "title": "test_elena", "revision_id": "00d6c559-90fb-4f61-88e6-02a605850eff"}}
time=2022-04-07T16:00:27.035Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=populatePackagesMap | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.ckan.CKANCache[396] : Going to populate the resources cache (orgName=test, pkgName=test_elena)
time=2022-04-07T16:00:27.035Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=populateResourcesMap | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.ckan.CKANCache[416] : Resources to be populated: [{"created":"2022-04-07T12:24:39.625750","url_type":"upload","format":"CSV","resource_type":null,"description":"","package_id":"6a3d11d5-b738-4658-b691-a07badaab3e2","cache_last_updated":null,"url":"https://opendata-test.territoriointeligente.jcyl.es/dataset/6a3d11d5-b738-4658-b691-a07badaab3e2/resource/e198ac50-9fe5-4b9f-8653-8665b3b51e11/download/","cache_url":null,"size":0,"datastore_active":true,"name":"catedral_estmeteo","mimetype":null,"state":"active","position":0,"id":"e198ac50-9fe5-4b9f-8653-8665b3b51e11","last_modified":"2022-04-07T14:43:07.855746","hash":"","mimetype_inner":null,"revision_id":"0a0592fd-cb1f-4269-af59-77c9e5714322"},{"created":"2022-04-07T14:56:01.861622","url_type":null,"format":"","resource_type":null,"description":"","package_id":"6a3d11d5-b738-4658-b691-a07badaab3e2","cache_last_updated":null,"url":"http://none","cache_url":null,"size":null,"datastore_active":true,"name":"iglesia_estmeteo","mimetype":null,"state":"active","position":1,"id":"33b9d54d-77fc-43f3-9dd3-72c093461054","last_modified":null,"hash":"","mimetype_inner":null,"revision_id":"4f92aa95-e3fb-4fc3-adf3-cbd7af424925"}](orgName=test, pkgName=test_elena)
time=2022-04-07T16:00:27.036Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=populateResourcesMap | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.ckan.CKANCache[437] : Resource found in CKAN, now cached (orgName=test -> pkgName=test_elena -> resourceName/resourceId=catedral_estmeteo/e198ac50-9fe5-4b9f-8653-8665b3b51e11)
time=2022-04-07T16:00:27.036Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=populateResourcesMap | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.ckan.CKANCache[437] : Resource found in CKAN, now cached (orgName=test -> pkgName=test_elena -> resourceName/resourceId=iglesia_estmeteo/33b9d54d-77fc-43f3-9dd3-72c093461054)
time=2022-04-07T16:00:27.036Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=resourceLookupOrCreate | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.ckan.CKANBackendImpl[120] : The organization was cached (orgName=test)
time=2022-04-07T16:00:27.036Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=isCachedPkg | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.ckan.CKANCache[231] : Package found in the cache (orgName=test, pkgName=test_elena)
time=2022-04-07T16:00:27.036Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=resourceLookupOrCreate | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.ckan.CKANBackendImpl[141] : The package was cached (orgName=test, pkgName=test_elena)
time=2022-04-07T16:00:27.036Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=isCachedRes | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.ckan.CKANCache[298] : Resource not found in the cache, querying CKAN for the whole package containing it (orgName=test, pkgName=test_elena, resName=estacion001_estacion)
time=2022-04-07T16:00:27.036Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=doCKANCacheRequest | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.ckan.CKANCache[525] : doCKACacheNRequest GET to /api/3/action/package_show?id=test_elena
time=2022-04-07T16:00:27.036Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=doRequest | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.http.HttpBackend[212] : Http request: GET http://172.17.0.22:5000/api/3/action/package_show?id=test_elena HTTP/1.1
time=2022-04-07T16:00:27.052Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=createJsonResponse | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.http.HttpBackend[324] : Http response status line: HTTP/1.0 200 OK
time=2022-04-07T16:00:27.052Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=createJsonResponse | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.http.HttpBackend[360] : Http response payload: {"help": "https://opendata-test.territoriointeligente.jcyl.es/api/3/action/help_show?name=package_show", "success": true, "result": {"license_title": null, "maintainer": null, "relationships_as_object": [], "private": false, "maintainer_email": null, "num_tags": 0, "id": "6a3d11d5-b738-4658-b691-a07badaab3e2", "metadata_created": "2022-04-07T12:20:43.615295", "metadata_modified": "2022-04-07T14:56:01.850695", "author": null, "author_email": null, "state": "active", "version": null, "creator_user_id": "7d13cad1-a494-4a7f-b55f-667e262da9b8", "type": "dataset", "resources": [{"mimetype": null, "cache_url": null, "hash": "", "description": "", "name": "catedral_estmeteo", "format": "CSV", "url": "https://opendata-test.territoriointeligente.jcyl.es/dataset/6a3d11d5-b738-4658-b691-a07badaab3e2/resource/e198ac50-9fe5-4b9f-8653-8665b3b51e11/download/", "datastore_active": true, "cache_last_updated": null, "package_id": "6a3d11d5-b738-4658-b691-a07badaab3e2", "created": "2022-04-07T12:24:39.625750", "state": "active", "mimetype_inner": null, "last_modified": "2022-04-07T14:43:07.855746", "position": 0, "revision_id": "0a0592fd-cb1f-4269-af59-77c9e5714322", "url_type": "upload", "id": "e198ac50-9fe5-4b9f-8653-8665b3b51e11", "resource_type": null, "size": 0}, {"mimetype": null, "cache_url": null, "hash": "", "description": "", "name": "iglesia_estmeteo", "format": "", "url": "http://none", "datastore_active": true, "cache_last_updated": null, "package_id": "6a3d11d5-b738-4658-b691-a07badaab3e2", "created": "2022-04-07T14:56:01.861622", "state": "active", "mimetype_inner": null, "last_modified": null, "position": 1, "revision_id": "4f92aa95-e3fb-4fc3-adf3-cbd7af424925", "url_type": null, "id": "33b9d54d-77fc-43f3-9dd3-72c093461054", "resource_type": null, "size": null}], "num_resources": 2, "tags": [], "groups": [], "license_id": null, "relationships_as_subject": [], "organization": {"description": "", "title": "", "created": "2022-04-07T09:51:54.414304", "approval_status": "approved", "is_organization": true, "state": "active", "image_url": "", "revision_id": "fb95b924-2c36-402a-81e8-9a8693cd9c52", "type": "organization", "id": "17d96df5-9a2f-4d64-9834-76950fca035e", "name": "test"}, "name": "test_elena", "isopen": false, "url": null, "notes": null, "owner_org": "17d96df5-9a2f-4d64-9834-76950fca035e", "extras": [], "title": "test_elena", "revision_id": "00d6c559-90fb-4f61-88e6-02a605850eff"}}
time=2022-04-07T16:00:27.056Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=isCachedRes | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.ckan.CKANCache[314] : Package found in CKAN, going to update the cached resources (orgName=test, pkgName=test_elena)
time=2022-04-07T16:00:27.056Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=isCachedRes | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.ckan.CKANCache[328] : Going to populate the resources cache (orgName=test, pkgName=test_elena)
time=2022-04-07T16:00:27.056Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=populateResourcesMap | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.ckan.CKANCache[416] : Resources to be populated: [{"created":"2022-04-07T12:24:39.625750","url_type":"upload","format":"CSV","resource_type":null,"description":"","package_id":"6a3d11d5-b738-4658-b691-a07badaab3e2","cache_last_updated":null,"url":"https://opendata-test.territoriointeligente.jcyl.es/dataset/6a3d11d5-b738-4658-b691-a07badaab3e2/resource/e198ac50-9fe5-4b9f-8653-8665b3b51e11/download/","cache_url":null,"size":0,"datastore_active":true,"name":"catedral_estmeteo","mimetype":null,"state":"active","position":0,"id":"e198ac50-9fe5-4b9f-8653-8665b3b51e11","last_modified":"2022-04-07T14:43:07.855746","hash":"","mimetype_inner":null,"revision_id":"0a0592fd-cb1f-4269-af59-77c9e5714322"},{"created":"2022-04-07T14:56:01.861622","url_type":null,"format":"","resource_type":null,"description":"","package_id":"6a3d11d5-b738-4658-b691-a07badaab3e2","cache_last_updated":null,"url":"http://none","cache_url":null,"size":null,"datastore_active":true,"name":"iglesia_estmeteo","mimetype":null,"state":"active","position":1,"id":"33b9d54d-77fc-43f3-9dd3-72c093461054","last_modified":null,"hash":"","mimetype_inner":null,"revision_id":"4f92aa95-e3fb-4fc3-adf3-cbd7af424925"}](orgName=test, pkgName=test_elena)
time=2022-04-07T16:00:27.056Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=isCachedRes | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.ckan.CKANCache[338] : Resource not found in the cache, once queried CKAN (orgName=test, pkgName=test_elena, resName=estacion001_estacion)
time=2022-04-07T16:00:27.056Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=resourceLookupOrCreate | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.ckan.CKANBackendImpl[144] : The resource was not cached nor existed in CKAN (orgName=test, pkgName=test_elena, resName=estacion001_estacion)
time=2022-04-07T16:00:27.056Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=doCKANRequest | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.ckan.CKANBackendImpl[571] : doCKANRequest POST to /api/3/action/resource_create with body { "name": "estacion001_estacion", "url": "none", "format": "", "package_id": "6a3d11d5-b738-4658-b691-a07badaab3e2" }
time=2022-04-07T16:00:27.057Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=doRequest | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.http.HttpBackend[212] : Http request: POST http://172.17.0.22:5000/api/3/action/resource_create HTTP/1.1
time=2022-04-07T16:00:27.190Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=createJsonResponse | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.http.HttpBackend[324] : Http response status line: HTTP/1.0 200 OK
time=2022-04-07T16:00:27.190Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=createJsonResponse | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.http.HttpBackend[360] : Http response payload: {"help": "https://opendata-test.territoriointeligente.jcyl.es/api/3/action/help_show?name=resource_create", "success": true, "result": {"cache_last_updated": null, "cache_url": null, "mimetype_inner": null, "hash": "", "description": "", "format": "", "url": "http://none", "created": "2022-04-07T16:00:27.094443", "state": "active", "package_id": "6a3d11d5-b738-4658-b691-a07badaab3e2", "last_modified": null, "mimetype": null, "url_type": null, "position": 2, "revision_id": "b6ea0e42-2e32-4243-b66d-fab9bd463cfc", "size": null, "datastore_active": false, "id": "7ae46267-b212-4244-a074-fa924c1424a0", "resource_type": null, "name": "estacion001_estacion"}}
time=2022-04-07T16:00:27.190Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=createResource | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.ckan.CKANBackendImpl[285] : Successful resource creation (resName/resId=estacion001_estacion/7ae46267-b212-4244-a074-fa924c1424a0)
time=2022-04-07T16:00:27.190Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=doCKANRequest | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.ckan.CKANBackendImpl[571] : doCKANRequest POST to /api/3/action/datastore_create with body { "resource_id": "7ae46267-b212-4244-a074-fa924c1424a0", "fields": [ { "id": "recvTimeTs", "type": "int"},{ "id": "recvTime", "type": "timestamp"},{ "id": "fiwareServicePath", "type": "text"},{ "id": "entityId", "type": "text"},{ "id": "entityType", "type": "text"},{ "id": "attrName", "type": "text"},{ "id": "attrType", "type": "text"},{ "id": "attrValue", "type": "json"},{ "id": "attrMd", "type": "json"}], "force": "true" }
time=2022-04-07T16:00:27.190Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=doRequest | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.http.HttpBackend[212] : Http request: POST http://172.17.0.22:5000/api/3/action/datastore_create HTTP/1.1
time=2022-04-07T16:00:27.257Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=createJsonResponse | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.http.HttpBackend[324] : Http response status line: HTTP/1.0 200 OK
time=2022-04-07T16:00:27.257Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=createJsonResponse | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.http.HttpBackend[360] : Http response payload: {"help": "https://opendata-test.territoriointeligente.jcyl.es/api/3/action/help_show?name=datastore_create", "success": true, "result": {"fields": [{"type": "int", "id": "recvTimeTs"}, {"type": "timestamp", "id": "recvTime"}, {"type": "text", "id": "fiwareServicePath"}, {"type": "text", "id": "entityId"}, {"type": "text", "id": "entityType"}, {"type": "text", "id": "attrName"}, {"type": "text", "id": "attrType"}, {"type": "json", "id": "attrValue"}, {"type": "json", "id": "attrMd"}], "method": "insert", "resource_id": "7ae46267-b212-4244-a074-fa924c1424a0"}}
time=2022-04-07T16:00:27.257Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=createDataStore | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.ckan.CKANBackendImpl[324] : Successful datastore creation (resourceId=7ae46267-b212-4244-a074-fa924c1424a0)
time=2022-04-07T16:00:27.257Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=doCKANRequest | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.ckan.CKANBackendImpl[571] : doCKANRequest POST to /api/3/action/resource_view_list with body { "id": "7ae46267-b212-4244-a074-fa924c1424a0" }
time=2022-04-07T16:00:27.257Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=doRequest | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.http.HttpBackend[212] : Http request: POST http://172.17.0.22:5000/api/3/action/resource_view_list HTTP/1.1
time=2022-04-07T16:00:27.268Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=createJsonResponse | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.http.HttpBackend[324] : Http response status line: HTTP/1.0 200 OK
time=2022-04-07T16:00:27.268Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=createJsonResponse | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.http.HttpBackend[360] : Http response payload: {"help": "https://opendata-test.territoriointeligente.jcyl.es/api/3/action/help_show?name=resource_view_list", "success": true, "result": []}
time=2022-04-07T16:00:27.268Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=existsView | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.ckan.CKANBackendImpl[372] : Successful view listing (resourceId=7ae46267-b212-4244-a074-fa924c1424a0)
time=2022-04-07T16:00:27.269Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=doCKANRequest | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.ckan.CKANBackendImpl[571] : doCKANRequest POST to /api/3/action/resource_view_create with body { "resource_id": "7ae46267-b212-4244-a074-fa924c1424a0","view_type": "recline_grid_view","title": "Recline grid view" }
time=2022-04-07T16:00:27.269Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=doRequest | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.http.HttpBackend[212] : Http request: POST http://172.17.0.22:5000/api/3/action/resource_view_create HTTP/1.1
time=2022-04-07T16:00:27.279Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=createJsonResponse | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.http.HttpBackend[324] : Http response status line: HTTP/1.0 409 CONFLICT
time=2022-04-07T16:00:27.280Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=createJsonResponse | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.http.HttpBackend[360] : Http response payload: {"help": "https://opendata-test.territoriointeligente.jcyl.es/api/3/action/help_show?name=resource_view_create", "success": false, "error": {"_type": "Validation Error", "view_type": "No plugin found for view_type recline_grid_view"}}
time=2022-04-07T16:00:27.280Z | lvl=ERROR | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=processNewBatches | comp=Cygnus | msg=com.telefonica.iot.cygnus.sinks.NGSISink[626] : CygnusPersistenceError. Could not create the datastore (resId=7ae46267-b212-4244-a074-fa924c1424a0, statusCode=409). Sink: ckan-sink Destination: test/elena_estacion001_estacion Stack trace: [com.telefonica.iot.cygnus.backends.ckan.CKANBackendImpl.createView(CKANBackendImpl.java:355), com.telefonica.iot.cygnus.backends.ckan.CKANBackendImpl.resourceLookupOrCreate(CKANBackendImpl.java:152), com.telefonica.iot.cygnus.backends.ckan.CKANBackendImpl.persist(CKANBackendImpl.java:86), com.telefonica.iot.cygnus.sinks.NGSICKANSink.persistAggregation(NGSICKANSink.java:388), com.telefonica.iot.cygnus.sinks.NGSICKANSink.persistBatch(NGSICKANSink.java:301), com.telefonica.iot.cygnus.sinks.NGSISink.processNewBatches(NGSISink.java:604), com.telefonica.iot.cygnus.sinks.NGSISink.process(NGSISink.java:381), org.apache.flume.sink.DefaultSinkProcessor.process(DefaultSinkProcessor.java:67), org.apache.flume.SinkRunner$PollingRunner.run(SinkRunner.java:145), java.lang.Thread.run(Thread.java:748)]
@AlvaroVega
Copy link
Member

AlvaroVega commented Apr 8, 2022 •
edited
Loading

msg=com.telefonica.iot.cygnus.sinks.NGSISink[626] : CygnusPersistenceError. Could not create the datastore (resId=7ae46267-b212-4244-a074-fa924c1424a0, statusCode=409).

fiware-cygnus/cygnus-common/src/main/java/com/telefonica/iot/cygnus/backends/ckan/CKANBackendImpl.java

Lines 350 to 355 in a1f69e1

// check the status
if (res.getStatusCode() == 200) {
LOGGER.debug("Successful view creation (resourceId=" + resId + ")");
} else {
throw new CygnusPersistenceError("Could not create the datastore (resId=" + resId
+ ", statusCode=" + res.getStatusCode() + ")");

row mode is always trying to create:

fiware-cygnus/cygnus-ngsi/src/main/java/com/telefonica/iot/cygnus/sinks/NGSICKANSink.java

Lines 387 to 391 in a1f69e1

if (aggregator instanceof NGSIGenericRowAggregator) {
persistenceBackend.persist(orgName, pkgName, resName, aggregation, true);
} else {
persistenceBackend.persist(orgName, pkgName, resName, aggregation, false);
} // if else

@AlvaroVega
Copy link
Member

AlvaroVega commented Apr 8, 2022 •
edited
Loading

time=2022-04-07T16:00:27.268Z | lvl=DEBUG | corr=e9dfd507-bca9-4fb2-bfae-e043b89c3e83; cbnotif=1 | trans=8a537731-1221-473c-8fd6-9d64ca7b5484 | srv=N/A | subsrv=N/A | op=existsView | comp=Cygnus | msg=com.telefonica.iot.cygnus.backends.ckan.CKANBackendImpl[372] : Successful view listing (resourceId=7ae46267-b212-4244-a074-fa924c1424a0)

View is found but it's empty:

Http response payload: {"help": "https://opendata-test.territoriointeligente.jcyl.es/api/3/action/help_show?name=resource_view_list", "success": true, "result": []}

fiware-cygnus/cygnus-common/src/main/java/com/telefonica/iot/cygnus/backends/ckan/CKANBackendImpl.java

Line 373 in a1f69e1

return (((JSONArray) res.getJsonObject().get("result")).size() > 0);

@AlvaroVega AlvaroVega mentioned this issue Apr 8, 2022
Merged
@AlvaroVega
Copy link
Member

Maybe the same bug #1946

@fgalan fgalan added this to the release/2.18.0 milestone Apr 25, 2022
@fgalan
Copy link
Member

fgalan commented Apr 25, 2022

PR #2151

Should this issue be closed?

@AlvaroVega
Copy link
Member

Maybe related #1899 ?

@fgalan
Copy link
Member

fgalan commented May 11, 2022

Fix included in release 2.18.0. Pending to be tested in real deployment with that version.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
release/2.18.0
Development

No branches or pull requests
3 participants
@AlvaroVega @fgalan @manucarrace