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.

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

Activity Log is inconsistent #2579

Closed
rhuss opened this Issue May 9, 2018 · 15 comments

Comments

Projects
9 participants
@rhuss
Collaborator

rhuss commented May 9, 2018

This is a...


[ ] Feature request
[ ] Regression (a behavior that used to work and stopped working in a new release)
[X] Bug report  
[ ] Documentation issue or request

The problem

I'm using simple twitter mention connector with a log step afterwards where I select the "log body" option.

In the activity logs there are entries with missing body, but also an entry missing (see the log in the next comment)

image

@pure-bot pure-bot bot added the notif/triage label May 9, 2018

@rhuss

This comment has been minimized.

Show comment
Hide comment
@rhuss

rhuss May 9, 2018

Collaborator
Pod Log
Starting the Java application using /opt/run-java/run-java.sh ...
exec java -javaagent:/opt/jolokia/jolokia.jar=config=/opt/jolokia/etc/jolokia.properties -javaagent:/opt/prometheus/jmx_prometheus_javaagent.jar=9779:/tmp/src/prometheus-config.yml -Xmx256m -XX:ParallelGCThreads=1 -XX:ConcGCThreads=1 -Djava.util.concurrent.ForkJoinPool.common.parallelism=1 -XX:CICompilerCount=2 -XX:+UseParallelGC -XX:GCTimeRatio=4 -XX:AdaptiveSizePolicyWeight=90 -XX:MinHeapFreeRatio=20 -XX:MaxHeapFreeRatio=40 -XX:+ExitOnOutOfMemoryError -cp . -jar /deployments/project-0.1-SNAPSHOT.jar
      _______.                 _               _
    /       |                 | |             (_)
   |   (----`_   _  ____    _ | |  ____   ___  _   ___
    \   \   | | | ||  _ \  / || | / _  ) /___)| | /___)
.----)   |  | |_| || | | |( (_| |( (/ / |___ || ||___ |
|_______/    \__  ||_| |_| \____| \____)(___/ |_|(___/
============ (____/ ===================================
:: Integration ::  :: v


2018-05-09 16:49:33.006  INFO 1 --- [           main] io.syndesis.example.Application          : Starting Application on i-twitter-to-slack-2-8j4sn with PID 1 (/deployments/project-0.1-SNAPSHOT.jar started by ? in /deployments)
2018-05-09 16:49:33.012 DEBUG 1 --- [           main] io.syndesis.example.Application          : Running with Spring Boot v1.5.8.RELEASE, Spring v4.3.12.RELEASE
2018-05-09 16:49:33.013  INFO 1 --- [           main] io.syndesis.example.Application          : No active profile set, falling back to default profiles: default
I> No access restrictor found, access to any MBean is allowed
Jolokia: Agent started with URL https://10.129.34.118:8778/jolokia/
2018-05-09 16:49:33.301  INFO 1 --- [           main] ationConfigEmbeddedWebApplicationContext : Refreshing org.springframework.boot.context.embedded.AnnotationConfigEmbeddedWebApplicationContext@341b80b2: startup date [Wed May 09 16:49:33 UTC 2018]; root of context hierarchy
2018-05-09 16:49:37.623  INFO 1 --- [           main] trationDelegate$BeanPostProcessorChecker : Bean 'org.apache.camel.spring.boot.CamelAutoConfiguration' of type [org.apache.camel.spring.boot.CamelAutoConfiguration$$EnhancerBySpringCGLIB$$22c2f35e] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2018-05-09 16:49:38.429  INFO 1 --- [           main] org.xnio                                 : XNIO version 3.3.8.Final
2018-05-09 16:49:38.450  INFO 1 --- [           main] org.xnio.nio                             : XNIO NIO Implementation Version 3.3.8.Final
2018-05-09 16:49:38.538  WARN 1 --- [           main] io.undertow.websockets.jsr               : UT026009: XNIO worker was not set on WebSocketDeploymentInfo, the default worker will be used
2018-05-09 16:49:38.538  WARN 1 --- [           main] io.undertow.websockets.jsr               : UT026010: Buffer pool was not set on WebSocketDeploymentInfo, the default pool will be used
2018-05-09 16:49:38.623  INFO 1 --- [           main] io.undertow.servlet                      : Initializing Spring embedded WebApplicationContext
2018-05-09 16:49:38.623  INFO 1 --- [           main] o.s.web.context.ContextLoader            : Root WebApplicationContext: initialization completed in 5323 ms
2018-05-09 16:49:39.118  INFO 1 --- [           main] o.s.b.w.servlet.ServletRegistrationBean  : Mapping servlet: 'dispatcherServlet' to [/]
2018-05-09 16:49:39.123  INFO 1 --- [           main] o.s.b.w.servlet.FilterRegistrationBean   : Mapping filter: 'metricsFilter' to: [/*]
2018-05-09 16:49:39.124  INFO 1 --- [           main] o.s.b.w.servlet.FilterRegistrationBean   : Mapping filter: 'characterEncodingFilter' to: [/*]
2018-05-09 16:49:39.125  INFO 1 --- [           main] o.s.b.w.servlet.FilterRegistrationBean   : Mapping filter: 'hiddenHttpMethodFilter' to: [/*]
2018-05-09 16:49:39.125  INFO 1 --- [           main] o.s.b.w.servlet.FilterRegistrationBean   : Mapping filter: 'httpPutFormContentFilter' to: [/*]
2018-05-09 16:49:39.125  INFO 1 --- [           main] o.s.b.w.servlet.FilterRegistrationBean   : Mapping filter: 'requestContextFilter' to: [/*]
2018-05-09 16:49:39.125  INFO 1 --- [           main] o.s.b.w.servlet.FilterRegistrationBean   : Mapping filter: 'webRequestLoggingFilter' to: [/*]
2018-05-09 16:49:39.125  INFO 1 --- [           main] o.s.b.w.servlet.FilterRegistrationBean   : Mapping filter: 'applicationContextIdFilter' to: [/*]
2018-05-09 16:49:39.937  INFO 1 --- [           main] s.w.s.m.m.a.RequestMappingHandlerAdapter : Looking for @ControllerAdvice: org.springframework.boot.context.embedded.AnnotationConfigEmbeddedWebApplicationContext@341b80b2: startup date [Wed May 09 16:49:33 UTC 2018]; root of context hierarchy
2018-05-09 16:49:40.201  INFO 1 --- [           main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/error],produces=[text/html]}" onto public org.springframework.web.servlet.ModelAndView org.springframework.boot.autoconfigure.web.BasicErrorController.errorHtml(javax.servlet.http.HttpServletRequest,javax.servlet.http.HttpServletResponse)
2018-05-09 16:49:40.202  INFO 1 --- [           main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/error]}" onto public org.springframework.http.ResponseEntity<java.util.Map<java.lang.String, java.lang.Object>> org.springframework.boot.autoconfigure.web.BasicErrorController.error(javax.servlet.http.HttpServletRequest)
2018-05-09 16:49:40.299  INFO 1 --- [           main] o.s.w.s.handler.SimpleUrlHandlerMapping  : Mapped URL path [/webjars/**] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler]
2018-05-09 16:49:40.299  INFO 1 --- [           main] o.s.w.s.handler.SimpleUrlHandlerMapping  : Mapped URL path [/**] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler]
2018-05-09 16:49:40.403  INFO 1 --- [           main] o.s.w.s.handler.SimpleUrlHandlerMapping  : Mapped URL path [/**/favicon.ico] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler]
2018-05-09 16:49:41.220  INFO 1 --- [           main] o.a.c.s.boot.CamelAutoConfiguration      : Using custom InterceptStrategy with id: integrationLoggingInterceptStrategy and implementation: io.syndesis.integration.runtime.logging.ActivityTrackingInterceptStrategy@53d1b9b3
2018-05-09 16:49:41.520  INFO 1 --- [           main] o.a.c.i.converter.DefaultTypeConverter   : Type converters loaded (core: 193, classpath: 6)
2018-05-09 16:49:43.238  INFO 1 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/health || /health.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.HealthMvcEndpoint.invoke(javax.servlet.http.HttpServletRequest,java.security.Principal)
2018-05-09 16:49:43.635  INFO 1 --- [           main] o.s.j.e.a.AnnotationMBeanExporter        : Registering beans for JMX exposure on startup
2018-05-09 16:49:43.699  INFO 1 --- [           main] o.s.c.support.DefaultLifecycleProcessor  : Starting beans in phase 0
2018-05-09 16:49:43.728 DEBUG 1 --- [           main] o.a.camel.spring.boot.RoutesCollector    : Post-processing CamelContext bean: twitter-to-slack
2018-05-09 16:49:43.729 DEBUG 1 --- [           main] o.a.camel.spring.boot.RoutesCollector    : CamelContextConfiguration found. Invoking beforeApplicationStart: io.syndesis.integration.runtime.IntegrationRuntimeAutoConfiguration$1@2c4d1ac
2018-05-09 16:49:43.741  INFO 1 --- [           main] i.s.i.runtime.IntegrationRouteBuilder    : Loading integration from: classpath:syndesis/integration/integration.json
2018-05-09 16:49:44.833 DEBUG 1 --- [           main] i.s.i.runtime.IntegrationRouteBuilder    : Step kind: endpoint, handler: io.syndesis.integration.runtime.handlers.ConnectorStepHandler
2018-05-09 16:49:45.015 DEBUG 1 --- [           main] i.s.i.c.proxy.ComponentProxyComponent    : Starting connector: twitter-timeline-1
2018-05-09 16:49:45.403 DEBUG 1 --- [           main] i.s.i.runtime.IntegrationRouteBuilder    : Step kind: log, handler: io.syndesis.integration.runtime.handlers.LogStepHandler
2018-05-09 16:49:45.405 DEBUG 1 --- [           main] i.s.i.runtime.IntegrationRouteBuilder    : Step kind: ruleFilter, handler: io.syndesis.integration.runtime.handlers.RuleFilterStepHandler
2018-05-09 16:49:45.499 DEBUG 1 --- [           main] i.s.i.runtime.IntegrationRouteBuilder    : Step kind: extension, handler: io.syndesis.integration.runtime.handlers.ExtensionStepHandler
2018-05-09 16:49:45.530 DEBUG 1 --- [           main] i.s.i.runtime.IntegrationRouteBuilder    : Step kind: mapper, handler: io.syndesis.integration.runtime.handlers.DataMapperStepHandler
2018-05-09 16:49:45.531 DEBUG 1 --- [           main] i.s.i.runtime.IntegrationRouteBuilder    : Step kind: endpoint, handler: io.syndesis.integration.runtime.handlers.ConnectorStepHandler
2018-05-09 16:49:45.605 DEBUG 1 --- [           main] i.s.i.c.proxy.ComponentProxyComponent    : Starting connector: slack-6
2018-05-09 16:49:45.607 DEBUG 1 --- [           main] o.a.camel.spring.boot.RoutesCollector    : CamelContextConfiguration found. Invoking beforeApplicationStart: io.syndesis.integration.runtime.jmx.IntegrationMetadataAutoConfiguration$1@242aa8d9
2018-05-09 16:49:45.608  INFO 1 --- [           main] r.j.IntegrationMetadataAutoConfiguration : Added Syndesis MBean Service
2018-05-09 16:49:45.608 DEBUG 1 --- [           main] o.a.camel.spring.boot.RoutesCollector    : CamelContextConfiguration found. Invoking beforeApplicationStart: io.syndesis.integration.runtime.logging.IntegrationLoggingAutoConfiguration$1@11eadcba
2018-05-09 16:49:45.625  INFO 1 --- [           main] o.a.camel.spring.SpringCamelContext      : Apache Camel 2.21.0 (CamelContext: twitter-to-slack) is starting
2018-05-09 16:49:45.626  INFO 1 --- [           main] o.a.camel.spring.SpringCamelContext      : StreamCaching is enabled on CamelContext: twitter-to-slack
2018-05-09 16:49:45.626  INFO 1 --- [           main] o.a.c.m.ManagedManagementStrategy        : JMX is enabled
2018-05-09 16:49:46.017  INFO 1 --- [           main] i.s.i.c.proxy.ComponentProxyComponent    : Connector resolved: twitter-timeline-1 -> twitter-timeline://MENTIONS?accessToken=890116203867406336-GaXZhE2Zc1kePsI630O9AcdZp2LBKGR&accessTokenSecret=5Gv6qKeC7ghNkM0kyLARwqMObbv4xFTpRaKCsBGIgQNk8&consumerKey=oQJdK7ew5OfFSmEmlAwTi5jUZ&consumerSecret=OBxMEUVk9zoExyh3b2XNAhSSduPNzQLGqKCinIBmRrHrfpfC4W&delay=30000
2018-05-09 16:49:46.300  INFO 1 --- [           main] i.s.i.c.proxy.ComponentProxyComponent    : Connector resolved: slack-6 -> slack://%23rh-summit?iconEmoji=%3Acamel%3A&username=rh-summit&webhookUrl=https%3A%2F%2Fhooks.slack.com%2Fservices%2F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
2018-05-09 16:49:46.312  INFO 1 --- [           main] o.a.c.impl.DefaultStreamCachingStrategy  : StreamCaching in use with spool directory: /tmp/camel/camel-tmp-826f1d0a-bd6f-4e94-b343-f1e7f0ce1bc4 and rules: [Spool > 128K body size]
2018-05-09 16:49:46.512  INFO 1 --- [           main] i.s.i.r.jmx.CamelContextMetadataMBean    : Registered mbean io.syndesis.camel:context=twitter-to-slack,type=context,name="twitter-to-slack"
2018-05-09 16:49:46.521  INFO 1 --- [           main] o.a.camel.spring.SpringCamelContext      : Route: -LC4mdgesUHCR3vLoiF5 started and consuming from: twitter-timeline://MENTIONS?accessToken=890116203867406336-GaXZhE2Zc1kePsI630O9AcdZp2LBKGR&accessTokenSecret=5Gv6qKeC7ghNkM0kyLARwqMObbv4xFTpRaKCsBGIgQNk8&consumerKey=oQJdK7ew5OfFSmEmlAwTi5jUZ&consumerSecret=OBxMEUVk9zoExyh3b2XNAhSSduPNzQLGqKCinIBmRrHrfpfC4W&delay=30000
2018-05-09 16:49:46.522  INFO 1 --- [           main] o.a.camel.spring.SpringCamelContext      : Total 1 routes, of which 1 are started
2018-05-09 16:49:46.522  INFO 1 --- [           main] o.a.camel.spring.SpringCamelContext      : Apache Camel 2.21.0 (CamelContext: twitter-to-slack) started in 0.897 seconds
2018-05-09 16:49:46.523 DEBUG 1 --- [           main] o.a.camel.spring.boot.RoutesCollector    : CamelContextConfiguration found. Invoking afterApplicationStart: io.syndesis.integration.runtime.IntegrationRuntimeAutoConfiguration$1@2c4d1ac
2018-05-09 16:49:48.113 DEBUG 1 --- [           main] .i.r.IntegrationRuntimeAutoConfiguration : Routes: 
<?xml version="1.0" encoding="UTF-8" standalone="yes"?>
<routes xmlns="http://camel.apache.org/schema/spring">
    <route customId="true" id="-LC4mdgesUHCR3vLoiF5">
        <from customId="true" id="-LC4mdgesUHCR3vLoiF5" uri="twitter-timeline-1"/>
        <setHeader headerName="Syndesis.STEP_ID" id="setHeader1">
            <constant>-LC4mdgesUHCR3vLoiF5</constant>
        </setHeader>
        <process id="process1"/>
        <pipeline customId="true" id="step:-LC4my-asUHCR3vLoiF7">
            <setHeader headerName="Syndesis.STEP_ID" id="setHeader2">
                <constant>-LC4my-asUHCR3vLoiF7</constant>
            </setHeader>
            <log id="log1" loggingLevel="INFO" marker="-LC4my-asUHCR3vLoiF7" message="Body: [${body}] "/>
            <process id="process2"/>
        </pipeline>
        <pipeline customId="true" id="step:-LC4n0ZosUHCR3vLoiF8">
            <setHeader headerName="Syndesis.STEP_ID" id="setHeader3">
                <constant>-LC4n0ZosUHCR3vLoiF8</constant>
            </setHeader>
            <filter id="filter1">
                <expressionDefinition>io.syndesis.integration.runtime.util.JsonSimplePredicate@1736c1e4</expressionDefinition>
                <process id="process3"/>
                <pipeline customId="true" id="step:-LC4nNLTsUHCR3vLoiF8">
                    <setHeader headerName="Syndesis.STEP_ID" id="setHeader4">
                        <constant>-LC4nNLTsUHCR3vLoiF8</constant>
                    </setHeader>
                    <process id="process4"/>
                    <process id="process5"/>
                </pipeline>
                <pipeline customId="true" id="step:-LC4mm4RsUHCR3vLoiF6">
                    <setHeader headerName="Syndesis.STEP_ID" id="setHeader5">
                        <constant>-LC4mm4RsUHCR3vLoiF6</constant>
                    </setHeader>
                    <to id="to1" uri="atlas:mapping-step-5.json?sourceMapName=Syndesis.CAPTURED_OUT_MESSAGES_MAP"/>
                    <process id="process6"/>
                </pipeline>
                <pipeline customId="true" id="step:-LC4mgbgsUHCR3vLoiF5">
                    <setHeader headerName="Syndesis.STEP_ID" id="setHeader6">
                        <constant>-LC4mgbgsUHCR3vLoiF5</constant>
                    </setHeader>
                    <to id="to2" uri="slack-6"/>
                    <process id="process7"/>
                </pipeline>
            </filter>
        </pipeline>
    </route>
</routes>

2018-05-09 16:49:48.113 DEBUG 1 --- [           main] o.a.camel.spring.boot.RoutesCollector    : CamelContextConfiguration found. Invoking afterApplicationStart: io.syndesis.integration.runtime.jmx.IntegrationMetadataAutoConfiguration$1@242aa8d9
2018-05-09 16:49:48.113 DEBUG 1 --- [           main] o.a.camel.spring.boot.RoutesCollector    : CamelContextConfiguration found. Invoking afterApplicationStart: io.syndesis.integration.runtime.logging.IntegrationLoggingAutoConfiguration$1@11eadcba
2018-05-09 16:49:48.304  INFO 1 --- [           main] b.c.e.u.UndertowEmbeddedServletContainer : Undertow started on port(s) 8080 (http)
2018-05-09 16:49:48.326  INFO 1 --- [           main] io.syndesis.example.Application          : Started Application in 17.403 seconds (JVM running for 19.975)
{"exchange":"i-LC4nl6M-ssGEVMSPU_ez","status":"begin"}
{"exchange":"i-LC4nl6M-ssGEVMSPU_ez","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl6d-ssGEVMSPU_hz","message":"Body: [Tue Aug 08 10:21:08 UTC 2017 (ro14nd) @syndesis_d_test Gruesse aus Pegnitz !] "}
2018-05-09 16:49:48.521  INFO 1 --- [line://MENTIONS] -LC4mdgesUHCR3vLoiF5                     : Body: [Tue Aug 08 10:21:08 UTC 2017 (ro14nd) @syndesis_d_test Gruesse aus Pegnitz !] 
{"exchange":"i-LC4nl6M-ssGEVMSPU_ez","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl6b-ssGEVMSPU_gz","duration":2813503}
{"exchange":"i-LC4nl6M-ssGEVMSPU_ez","step":"-LC4n0ZosUHCR3vLoiF8","id":"i-LC4nl6f-ssGEVMSPU_iz","duration":91826931}
{"exchange":"i-LC4nl6M-ssGEVMSPU_ez","status":"done","failed":false}
{"exchange":"i-LC4nl87-ssGEVMSPU_jz","status":"begin"}
{"exchange":"i-LC4nl87-ssGEVMSPU_jz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl88-ssGEVMSPU_mz","message":"Body: [Tue Aug 08 10:21:08 UTC 2017 (j0l0kia) @syndesis_d_test Gruesse aus Pegnitz !] "}
2018-05-09 16:49:48.617  INFO 1 --- [line://MENTIONS] -LC4mdgesUHCR3vLoiF5                     : Body: [Tue Aug 08 10:21:08 UTC 2017 (j0l0kia) @syndesis_d_test Gruesse aus Pegnitz !] 
{"exchange":"i-LC4nl87-ssGEVMSPU_jz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl87-ssGEVMSPU_lz","duration":1277521}
{"exchange":"i-LC4nl87-ssGEVMSPU_jz","step":"-LC4n0ZosUHCR3vLoiF8","id":"i-LC4nl89-ssGEVMSPU_nz","duration":593230}
{"exchange":"i-LC4nl87-ssGEVMSPU_jz","status":"done","failed":false}
{"exchange":"i-LC4nl8A-ssGEVMSPU_oz","status":"begin"}
{"exchange":"i-LC4nl8A-ssGEVMSPU_oz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8B-ssGEVMSPU_rz","message":"Body: [Fri Sep 01 14:37:34 UTC 2017 (ro14nd) @syndesis_d_test How are you ? 'hope Sprint 16 worked well ..] "}
2018-05-09 16:49:48.620  INFO 1 --- [line://MENTIONS] -LC4mdgesUHCR3vLoiF5                     : Body: [Fri Sep 01 14:37:34 UTC 2017 (ro14nd) @syndesis_d_test How are you ? 'hope Sprint 16 worked well ..] 
{"exchange":"i-LC4nl8A-ssGEVMSPU_oz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8A-ssGEVMSPU_qz","duration":746338}
{"exchange":"i-LC4nl8A-ssGEVMSPU_oz","step":"-LC4n0ZosUHCR3vLoiF8","id":"i-LC4nl8B-ssGEVMSPU_sz","duration":589991}
{"exchange":"i-LC4nl8A-ssGEVMSPU_oz","status":"done","failed":false}
{"exchange":"i-LC4nl8C-ssGEVMSPU_tz","status":"begin"}
{"exchange":"i-LC4nl8C-ssGEVMSPU_tz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8D-ssGEVMSPU_wz","message":"Body: [Fri Sep 01 14:39:18 UTC 2017 (ro14nd) @syndesis_d_test Looking forward to Sprint 17] "}
2018-05-09 16:49:48.622  INFO 1 --- [line://MENTIONS] -LC4mdgesUHCR3vLoiF5                     : Body: [Fri Sep 01 14:39:18 UTC 2017 (ro14nd) @syndesis_d_test Looking forward to Sprint 17] 
{"exchange":"i-LC4nl8C-ssGEVMSPU_tz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8D-ssGEVMSPU_vz","duration":771937}
{"exchange":"i-LC4nl8C-ssGEVMSPU_tz","step":"-LC4n0ZosUHCR3vLoiF8","id":"i-LC4nl8E-ssGEVMSPU_xz","duration":585497}
{"exchange":"i-LC4nl8C-ssGEVMSPU_tz","status":"done","failed":false}
{"exchange":"i-LC4nl8F-ssGEVMSPU_yz","status":"begin"}
{"exchange":"i-LC4nl8F-ssGEVMSPU_yz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8G-ssGEVMSPUa0z","message":"Body: [Fri Sep 01 14:40:04 UTC 2017 (ro14nd) @syndesis_d_test Sprint 16 is over now] "}
2018-05-09 16:49:48.625  INFO 1 --- [line://MENTIONS] -LC4mdgesUHCR3vLoiF5                     : Body: [Fri Sep 01 14:40:04 UTC 2017 (ro14nd) @syndesis_d_test Sprint 16 is over now] 
{"exchange":"i-LC4nl8F-ssGEVMSPU_yz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8G-ssGEVMSPUa-z","duration":726874}
{"exchange":"i-LC4nl8F-ssGEVMSPU_yz","step":"-LC4n0ZosUHCR3vLoiF8","id":"i-LC4nl8H-ssGEVMSPUa1z","duration":581349}
{"exchange":"i-LC4nl8F-ssGEVMSPU_yz","status":"done","failed":false}
{"exchange":"i-LC4nl8H-ssGEVMSPUa2z","status":"begin"}
{"exchange":"i-LC4nl8H-ssGEVMSPUa2z","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8I-ssGEVMSPUa5z","message":"Body: [Fri Sep 01 14:40:57 UTC 2017 (zregvart) @syndesis_d_test Hi Roland!] "}
2018-05-09 16:49:48.627  INFO 1 --- [line://MENTIONS] -LC4mdgesUHCR3vLoiF5                     : Body: [Fri Sep 01 14:40:57 UTC 2017 (zregvart) @syndesis_d_test Hi Roland!] 
{"exchange":"i-LC4nl8H-ssGEVMSPUa2z","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8I-ssGEVMSPUa4z","duration":703176}
{"exchange":"i-LC4nl8H-ssGEVMSPUa2z","step":"-LC4n0ZosUHCR3vLoiF8","id":"i-LC4nl8J-ssGEVMSPUa6z","duration":625293}
{"exchange":"i-LC4nl8H-ssGEVMSPUa2z","status":"done","failed":false}
{"exchange":"i-LC4nl8K-ssGEVMSPUa7z","status":"begin"}
{"exchange":"i-LC4nl8K-ssGEVMSPUa7z","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8L-ssGEVMSPUaAz","message":"Body: [Fri Sep 01 14:41:50 UTC 2017 (zregvart) @syndesis_d_test Awesome demo!] "}
2018-05-09 16:49:48.630  INFO 1 --- [line://MENTIONS] -LC4mdgesUHCR3vLoiF5                     : Body: [Fri Sep 01 14:41:50 UTC 2017 (zregvart) @syndesis_d_test Awesome demo!] 
{"exchange":"i-LC4nl8K-ssGEVMSPUa7z","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8K-ssGEVMSPUa9z","duration":564832}
{"exchange":"i-LC4nl8K-ssGEVMSPUa7z","step":"-LC4n0ZosUHCR3vLoiF8","id":"i-LC4nl8L-ssGEVMSPUaBz","duration":498022}
{"exchange":"i-LC4nl8K-ssGEVMSPUa7z","status":"done","failed":false}
{"exchange":"i-LC4nl8M-ssGEVMSPUaCz","status":"begin"}
{"exchange":"i-LC4nl8M-ssGEVMSPUaCz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8N-ssGEVMSPUaFz","message":"Body: [Sun Oct 08 14:50:41 UTC 2017 (k8spatterns) @syndesis_d_test Hello ! Touch down ?] "}
2018-05-09 16:49:48.632  INFO 1 --- [line://MENTIONS] -LC4mdgesUHCR3vLoiF5                     : Body: [Sun Oct 08 14:50:41 UTC 2017 (k8spatterns) @syndesis_d_test Hello ! Touch down ?] 
{"exchange":"i-LC4nl8M-ssGEVMSPUaCz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8M-ssGEVMSPUaEz","duration":560466}
{"exchange":"i-LC4nl8M-ssGEVMSPUaCz","step":"-LC4n0ZosUHCR3vLoiF8","id":"i-LC4nl8N-ssGEVMSPUaGz","duration":506401}
{"exchange":"i-LC4nl8M-ssGEVMSPUaCz","status":"done","failed":false}
{"exchange":"i-LC4nl8O-ssGEVMSPUaHz","status":"begin"}
{"exchange":"i-LC4nl8O-ssGEVMSPUaHz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8O-ssGEVMSPUaKz","message":"Body: [Thu Oct 19 19:21:45 UTC 2017 (syndesisio) @syndesis_d_test TP2 is coming !] "}
2018-05-09 16:49:48.634  INFO 1 --- [line://MENTIONS] -LC4mdgesUHCR3vLoiF5                     : Body: [Thu Oct 19 19:21:45 UTC 2017 (syndesisio) @syndesis_d_test TP2 is coming !] 
{"exchange":"i-LC4nl8O-ssGEVMSPUaHz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8O-ssGEVMSPUaJz","duration":509723}
{"exchange":"i-LC4nl8O-ssGEVMSPUaHz","step":"-LC4n0ZosUHCR3vLoiF8","id":"i-LC4nl8P-ssGEVMSPUaLz","duration":498537}
{"exchange":"i-LC4nl8O-ssGEVMSPUaHz","status":"done","failed":false}
{"exchange":"i-LC4nl8Q-ssGEVMSPUaMz","status":"begin"}
{"exchange":"i-LC4nl8Q-ssGEVMSPUaMz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8Q-ssGEVMSPUaPz","message":"Body: [Wed Oct 25 08:38:42 UTC 2017 (syndesisio) @syndesis_d_test It's getting cold these days ...] "}
2018-05-09 16:49:48.636  INFO 1 --- [line://MENTIONS] -LC4mdgesUHCR3vLoiF5                     : Body: [Wed Oct 25 08:38:42 UTC 2017 (syndesisio) @syndesis_d_test It's getting cold these days ...] 
{"exchange":"i-LC4nl8Q-ssGEVMSPUaMz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8Q-ssGEVMSPUaOz","duration":535953}
{"exchange":"i-LC4nl8Q-ssGEVMSPUaMz","step":"-LC4n0ZosUHCR3vLoiF8","id":"i-LC4nl8R-ssGEVMSPUaQz","duration":466405}
{"exchange":"i-LC4nl8Q-ssGEVMSPUaMz","status":"done","failed":false}
{"exchange":"i-LC4nl8S-ssGEVMSPUaRz","status":"begin"}
{"exchange":"i-LC4nl8S-ssGEVMSPUaRz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8S-ssGEVMSPUaUz","message":"Body: [Wed Oct 25 08:38:42 UTC 2017 (j0l0kia) @syndesis_d_test It's getting cold these days ...] "}
2018-05-09 16:49:48.637  INFO 1 --- [line://MENTIONS] -LC4mdgesUHCR3vLoiF5                     : Body: [Wed Oct 25 08:38:42 UTC 2017 (j0l0kia) @syndesis_d_test It's getting cold these days ...] 
{"exchange":"i-LC4nl8S-ssGEVMSPUaRz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8S-ssGEVMSPUaTz","duration":570789}
{"exchange":"i-LC4nl8S-ssGEVMSPUaRz","step":"-LC4n0ZosUHCR3vLoiF8","id":"i-LC4nl8T-ssGEVMSPUaVz","duration":59468597}
{"exchange":"i-LC4nl8S-ssGEVMSPUaRz","status":"done","failed":false}
{"exchange":"i-LC4nl9P-ssGEVMSPUaWz","status":"begin"}
{"exchange":"i-LC4nl9P-ssGEVMSPUaWz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl9P-ssGEVMSPUaZz","message":"Body: [Wed Nov 01 10:07:24 UTC 2017 (j0l0kia) @syndesis_d_test no matter how great and destructive your problems may seem now, remember, you've probably only seen the tip of them.] "}
2018-05-09 16:49:48.698  INFO 1 --- [line://MENTIONS] -LC4mdgesUHCR3vLoiF5                     : Body: [Wed Nov 01 10:07:24 UTC 2017 (j0l0kia) @syndesis_d_test no matter how great and destructive your problems may seem now, remember, you've probably only seen the tip of them.] 
{"exchange":"i-LC4nl9P-ssGEVMSPUaWz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl9P-ssGEVMSPUaYz","duration":623428}
{"exchange":"i-LC4nl9P-ssGEVMSPUaWz","step":"-LC4nNLTsUHCR3vLoiF8","id":"i-LC4nl9R-ssGEVMSPUaaz","duration":1050347}
2018-05-09 16:49:48.721  WARN 1 --- [line://MENTIONS] i.a.core.DefaultAtlasConversionService   : Converter between java.util.Date and java.time.ZonedDateTime aleady exists.
2018-05-09 16:49:48.727  WARN 1 --- [line://MENTIONS] i.a.core.DefaultAtlasConversionService   : Converter between java.time.LocalDate and java.time.ZonedDateTime aleady exists.
2018-05-09 16:49:48.728  WARN 1 --- [line://MENTIONS] i.a.core.DefaultAtlasConversionService   : Converter between java.time.LocalDateTime and java.time.ZonedDateTime aleady exists.
2018-05-09 16:49:48.729  WARN 1 --- [line://MENTIONS] i.a.core.DefaultAtlasConversionService   : Converter between java.time.LocalTime and java.time.ZonedDateTime aleady exists.
{"exchange":"i-LC4nl9P-ssGEVMSPUaWz","step":"-LC4mm4RsUHCR3vLoiF6","id":"i-LC4nl9S-ssGEVMSPUabz","duration":817600446}
{"exchange":"i-LC4nl9P-ssGEVMSPUaWz","step":"-LC4mgbgsUHCR3vLoiF5","id":"i-LC4nlME-ssGEVMSPUadz","duration":588065922}
{"exchange":"i-LC4nl9P-ssGEVMSPUaWz","step":"-LC4mgbgsUHCR3vLoiF5","id":"i-LC4nl9Q-ssGEVMSPUa_z","duration":1408030292}
{"exchange":"i-LC4nl9P-ssGEVMSPUaWz","status":"done","failed":false}
{"exchange":"i-LC4o6a2-ssGEVMSPUaez","status":"begin"}
{"exchange":"i-LC4o6a2-ssGEVMSPUaez","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4o6a3-ssGEVMSPUahz","message":"Body: [Wed May 09 16:50:51 UTC 2018 (syndesisio) @syndesis_d_test wink, remember there are always dragons out there causing problems.] "}
2018-05-09 16:51:20.581  INFO 1 --- [line://MENTIONS] -LC4mdgesUHCR3vLoiF5                     : Body: [Wed May 09 16:50:51 UTC 2018 (syndesisio) @syndesis_d_test wink, remember there are always dragons out there causing problems.] 
{"exchange":"i-LC4o6a2-ssGEVMSPUaez","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4o6a3-ssGEVMSPUagz","duration":600858}
{"exchange":"i-LC4o6a2-ssGEVMSPUaez","step":"-LC4nNLTsUHCR3vLoiF8","id":"i-LC4o6a5-ssGEVMSPUajz","duration":250495}
{"exchange":"i-LC4o6a2-ssGEVMSPUaez","step":"-LC4mm4RsUHCR3vLoiF6","id":"i-LC4o6a5-ssGEVMSPUakz","duration":1762889}
{"exchange":"i-LC4o6a2-ssGEVMSPUaez","step":"-LC4mgbgsUHCR3vLoiF5","id":"i-LC4o6a7-ssGEVMSPUamz","duration":78436493}
{"exchange":"i-LC4o6a2-ssGEVMSPUaez","step":"-LC4mgbgsUHCR3vLoiF5","id":"i-LC4o6a4-ssGEVMSPUaiz","duration":81587720}
{"exchange":"i-LC4o6a2-ssGEVMSPUaez","status":"done","failed":false}
Collaborator

rhuss commented May 9, 2018

Pod Log
Starting the Java application using /opt/run-java/run-java.sh ...
exec java -javaagent:/opt/jolokia/jolokia.jar=config=/opt/jolokia/etc/jolokia.properties -javaagent:/opt/prometheus/jmx_prometheus_javaagent.jar=9779:/tmp/src/prometheus-config.yml -Xmx256m -XX:ParallelGCThreads=1 -XX:ConcGCThreads=1 -Djava.util.concurrent.ForkJoinPool.common.parallelism=1 -XX:CICompilerCount=2 -XX:+UseParallelGC -XX:GCTimeRatio=4 -XX:AdaptiveSizePolicyWeight=90 -XX:MinHeapFreeRatio=20 -XX:MaxHeapFreeRatio=40 -XX:+ExitOnOutOfMemoryError -cp . -jar /deployments/project-0.1-SNAPSHOT.jar
      _______.                 _               _
    /       |                 | |             (_)
   |   (----`_   _  ____    _ | |  ____   ___  _   ___
    \   \   | | | ||  _ \  / || | / _  ) /___)| | /___)
.----)   |  | |_| || | | |( (_| |( (/ / |___ || ||___ |
|_______/    \__  ||_| |_| \____| \____)(___/ |_|(___/
============ (____/ ===================================
:: Integration ::  :: v


2018-05-09 16:49:33.006  INFO 1 --- [           main] io.syndesis.example.Application          : Starting Application on i-twitter-to-slack-2-8j4sn with PID 1 (/deployments/project-0.1-SNAPSHOT.jar started by ? in /deployments)
2018-05-09 16:49:33.012 DEBUG 1 --- [           main] io.syndesis.example.Application          : Running with Spring Boot v1.5.8.RELEASE, Spring v4.3.12.RELEASE
2018-05-09 16:49:33.013  INFO 1 --- [           main] io.syndesis.example.Application          : No active profile set, falling back to default profiles: default
I> No access restrictor found, access to any MBean is allowed
Jolokia: Agent started with URL https://10.129.34.118:8778/jolokia/
2018-05-09 16:49:33.301  INFO 1 --- [           main] ationConfigEmbeddedWebApplicationContext : Refreshing org.springframework.boot.context.embedded.AnnotationConfigEmbeddedWebApplicationContext@341b80b2: startup date [Wed May 09 16:49:33 UTC 2018]; root of context hierarchy
2018-05-09 16:49:37.623  INFO 1 --- [           main] trationDelegate$BeanPostProcessorChecker : Bean 'org.apache.camel.spring.boot.CamelAutoConfiguration' of type [org.apache.camel.spring.boot.CamelAutoConfiguration$$EnhancerBySpringCGLIB$$22c2f35e] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2018-05-09 16:49:38.429  INFO 1 --- [           main] org.xnio                                 : XNIO version 3.3.8.Final
2018-05-09 16:49:38.450  INFO 1 --- [           main] org.xnio.nio                             : XNIO NIO Implementation Version 3.3.8.Final
2018-05-09 16:49:38.538  WARN 1 --- [           main] io.undertow.websockets.jsr               : UT026009: XNIO worker was not set on WebSocketDeploymentInfo, the default worker will be used
2018-05-09 16:49:38.538  WARN 1 --- [           main] io.undertow.websockets.jsr               : UT026010: Buffer pool was not set on WebSocketDeploymentInfo, the default pool will be used
2018-05-09 16:49:38.623  INFO 1 --- [           main] io.undertow.servlet                      : Initializing Spring embedded WebApplicationContext
2018-05-09 16:49:38.623  INFO 1 --- [           main] o.s.web.context.ContextLoader            : Root WebApplicationContext: initialization completed in 5323 ms
2018-05-09 16:49:39.118  INFO 1 --- [           main] o.s.b.w.servlet.ServletRegistrationBean  : Mapping servlet: 'dispatcherServlet' to [/]
2018-05-09 16:49:39.123  INFO 1 --- [           main] o.s.b.w.servlet.FilterRegistrationBean   : Mapping filter: 'metricsFilter' to: [/*]
2018-05-09 16:49:39.124  INFO 1 --- [           main] o.s.b.w.servlet.FilterRegistrationBean   : Mapping filter: 'characterEncodingFilter' to: [/*]
2018-05-09 16:49:39.125  INFO 1 --- [           main] o.s.b.w.servlet.FilterRegistrationBean   : Mapping filter: 'hiddenHttpMethodFilter' to: [/*]
2018-05-09 16:49:39.125  INFO 1 --- [           main] o.s.b.w.servlet.FilterRegistrationBean   : Mapping filter: 'httpPutFormContentFilter' to: [/*]
2018-05-09 16:49:39.125  INFO 1 --- [           main] o.s.b.w.servlet.FilterRegistrationBean   : Mapping filter: 'requestContextFilter' to: [/*]
2018-05-09 16:49:39.125  INFO 1 --- [           main] o.s.b.w.servlet.FilterRegistrationBean   : Mapping filter: 'webRequestLoggingFilter' to: [/*]
2018-05-09 16:49:39.125  INFO 1 --- [           main] o.s.b.w.servlet.FilterRegistrationBean   : Mapping filter: 'applicationContextIdFilter' to: [/*]
2018-05-09 16:49:39.937  INFO 1 --- [           main] s.w.s.m.m.a.RequestMappingHandlerAdapter : Looking for @ControllerAdvice: org.springframework.boot.context.embedded.AnnotationConfigEmbeddedWebApplicationContext@341b80b2: startup date [Wed May 09 16:49:33 UTC 2018]; root of context hierarchy
2018-05-09 16:49:40.201  INFO 1 --- [           main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/error],produces=[text/html]}" onto public org.springframework.web.servlet.ModelAndView org.springframework.boot.autoconfigure.web.BasicErrorController.errorHtml(javax.servlet.http.HttpServletRequest,javax.servlet.http.HttpServletResponse)
2018-05-09 16:49:40.202  INFO 1 --- [           main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/error]}" onto public org.springframework.http.ResponseEntity<java.util.Map<java.lang.String, java.lang.Object>> org.springframework.boot.autoconfigure.web.BasicErrorController.error(javax.servlet.http.HttpServletRequest)
2018-05-09 16:49:40.299  INFO 1 --- [           main] o.s.w.s.handler.SimpleUrlHandlerMapping  : Mapped URL path [/webjars/**] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler]
2018-05-09 16:49:40.299  INFO 1 --- [           main] o.s.w.s.handler.SimpleUrlHandlerMapping  : Mapped URL path [/**] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler]
2018-05-09 16:49:40.403  INFO 1 --- [           main] o.s.w.s.handler.SimpleUrlHandlerMapping  : Mapped URL path [/**/favicon.ico] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler]
2018-05-09 16:49:41.220  INFO 1 --- [           main] o.a.c.s.boot.CamelAutoConfiguration      : Using custom InterceptStrategy with id: integrationLoggingInterceptStrategy and implementation: io.syndesis.integration.runtime.logging.ActivityTrackingInterceptStrategy@53d1b9b3
2018-05-09 16:49:41.520  INFO 1 --- [           main] o.a.c.i.converter.DefaultTypeConverter   : Type converters loaded (core: 193, classpath: 6)
2018-05-09 16:49:43.238  INFO 1 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/health || /health.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.HealthMvcEndpoint.invoke(javax.servlet.http.HttpServletRequest,java.security.Principal)
2018-05-09 16:49:43.635  INFO 1 --- [           main] o.s.j.e.a.AnnotationMBeanExporter        : Registering beans for JMX exposure on startup
2018-05-09 16:49:43.699  INFO 1 --- [           main] o.s.c.support.DefaultLifecycleProcessor  : Starting beans in phase 0
2018-05-09 16:49:43.728 DEBUG 1 --- [           main] o.a.camel.spring.boot.RoutesCollector    : Post-processing CamelContext bean: twitter-to-slack
2018-05-09 16:49:43.729 DEBUG 1 --- [           main] o.a.camel.spring.boot.RoutesCollector    : CamelContextConfiguration found. Invoking beforeApplicationStart: io.syndesis.integration.runtime.IntegrationRuntimeAutoConfiguration$1@2c4d1ac
2018-05-09 16:49:43.741  INFO 1 --- [           main] i.s.i.runtime.IntegrationRouteBuilder    : Loading integration from: classpath:syndesis/integration/integration.json
2018-05-09 16:49:44.833 DEBUG 1 --- [           main] i.s.i.runtime.IntegrationRouteBuilder    : Step kind: endpoint, handler: io.syndesis.integration.runtime.handlers.ConnectorStepHandler
2018-05-09 16:49:45.015 DEBUG 1 --- [           main] i.s.i.c.proxy.ComponentProxyComponent    : Starting connector: twitter-timeline-1
2018-05-09 16:49:45.403 DEBUG 1 --- [           main] i.s.i.runtime.IntegrationRouteBuilder    : Step kind: log, handler: io.syndesis.integration.runtime.handlers.LogStepHandler
2018-05-09 16:49:45.405 DEBUG 1 --- [           main] i.s.i.runtime.IntegrationRouteBuilder    : Step kind: ruleFilter, handler: io.syndesis.integration.runtime.handlers.RuleFilterStepHandler
2018-05-09 16:49:45.499 DEBUG 1 --- [           main] i.s.i.runtime.IntegrationRouteBuilder    : Step kind: extension, handler: io.syndesis.integration.runtime.handlers.ExtensionStepHandler
2018-05-09 16:49:45.530 DEBUG 1 --- [           main] i.s.i.runtime.IntegrationRouteBuilder    : Step kind: mapper, handler: io.syndesis.integration.runtime.handlers.DataMapperStepHandler
2018-05-09 16:49:45.531 DEBUG 1 --- [           main] i.s.i.runtime.IntegrationRouteBuilder    : Step kind: endpoint, handler: io.syndesis.integration.runtime.handlers.ConnectorStepHandler
2018-05-09 16:49:45.605 DEBUG 1 --- [           main] i.s.i.c.proxy.ComponentProxyComponent    : Starting connector: slack-6
2018-05-09 16:49:45.607 DEBUG 1 --- [           main] o.a.camel.spring.boot.RoutesCollector    : CamelContextConfiguration found. Invoking beforeApplicationStart: io.syndesis.integration.runtime.jmx.IntegrationMetadataAutoConfiguration$1@242aa8d9
2018-05-09 16:49:45.608  INFO 1 --- [           main] r.j.IntegrationMetadataAutoConfiguration : Added Syndesis MBean Service
2018-05-09 16:49:45.608 DEBUG 1 --- [           main] o.a.camel.spring.boot.RoutesCollector    : CamelContextConfiguration found. Invoking beforeApplicationStart: io.syndesis.integration.runtime.logging.IntegrationLoggingAutoConfiguration$1@11eadcba
2018-05-09 16:49:45.625  INFO 1 --- [           main] o.a.camel.spring.SpringCamelContext      : Apache Camel 2.21.0 (CamelContext: twitter-to-slack) is starting
2018-05-09 16:49:45.626  INFO 1 --- [           main] o.a.camel.spring.SpringCamelContext      : StreamCaching is enabled on CamelContext: twitter-to-slack
2018-05-09 16:49:45.626  INFO 1 --- [           main] o.a.c.m.ManagedManagementStrategy        : JMX is enabled
2018-05-09 16:49:46.017  INFO 1 --- [           main] i.s.i.c.proxy.ComponentProxyComponent    : Connector resolved: twitter-timeline-1 -> twitter-timeline://MENTIONS?accessToken=890116203867406336-GaXZhE2Zc1kePsI630O9AcdZp2LBKGR&accessTokenSecret=5Gv6qKeC7ghNkM0kyLARwqMObbv4xFTpRaKCsBGIgQNk8&consumerKey=oQJdK7ew5OfFSmEmlAwTi5jUZ&consumerSecret=OBxMEUVk9zoExyh3b2XNAhSSduPNzQLGqKCinIBmRrHrfpfC4W&delay=30000
2018-05-09 16:49:46.300  INFO 1 --- [           main] i.s.i.c.proxy.ComponentProxyComponent    : Connector resolved: slack-6 -> slack://%23rh-summit?iconEmoji=%3Acamel%3A&username=rh-summit&webhookUrl=https%3A%2F%2Fhooks.slack.com%2Fservices%2F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
2018-05-09 16:49:46.312  INFO 1 --- [           main] o.a.c.impl.DefaultStreamCachingStrategy  : StreamCaching in use with spool directory: /tmp/camel/camel-tmp-826f1d0a-bd6f-4e94-b343-f1e7f0ce1bc4 and rules: [Spool > 128K body size]
2018-05-09 16:49:46.512  INFO 1 --- [           main] i.s.i.r.jmx.CamelContextMetadataMBean    : Registered mbean io.syndesis.camel:context=twitter-to-slack,type=context,name="twitter-to-slack"
2018-05-09 16:49:46.521  INFO 1 --- [           main] o.a.camel.spring.SpringCamelContext      : Route: -LC4mdgesUHCR3vLoiF5 started and consuming from: twitter-timeline://MENTIONS?accessToken=890116203867406336-GaXZhE2Zc1kePsI630O9AcdZp2LBKGR&accessTokenSecret=5Gv6qKeC7ghNkM0kyLARwqMObbv4xFTpRaKCsBGIgQNk8&consumerKey=oQJdK7ew5OfFSmEmlAwTi5jUZ&consumerSecret=OBxMEUVk9zoExyh3b2XNAhSSduPNzQLGqKCinIBmRrHrfpfC4W&delay=30000
2018-05-09 16:49:46.522  INFO 1 --- [           main] o.a.camel.spring.SpringCamelContext      : Total 1 routes, of which 1 are started
2018-05-09 16:49:46.522  INFO 1 --- [           main] o.a.camel.spring.SpringCamelContext      : Apache Camel 2.21.0 (CamelContext: twitter-to-slack) started in 0.897 seconds
2018-05-09 16:49:46.523 DEBUG 1 --- [           main] o.a.camel.spring.boot.RoutesCollector    : CamelContextConfiguration found. Invoking afterApplicationStart: io.syndesis.integration.runtime.IntegrationRuntimeAutoConfiguration$1@2c4d1ac
2018-05-09 16:49:48.113 DEBUG 1 --- [           main] .i.r.IntegrationRuntimeAutoConfiguration : Routes: 
<?xml version="1.0" encoding="UTF-8" standalone="yes"?>
<routes xmlns="http://camel.apache.org/schema/spring">
    <route customId="true" id="-LC4mdgesUHCR3vLoiF5">
        <from customId="true" id="-LC4mdgesUHCR3vLoiF5" uri="twitter-timeline-1"/>
        <setHeader headerName="Syndesis.STEP_ID" id="setHeader1">
            <constant>-LC4mdgesUHCR3vLoiF5</constant>
        </setHeader>
        <process id="process1"/>
        <pipeline customId="true" id="step:-LC4my-asUHCR3vLoiF7">
            <setHeader headerName="Syndesis.STEP_ID" id="setHeader2">
                <constant>-LC4my-asUHCR3vLoiF7</constant>
            </setHeader>
            <log id="log1" loggingLevel="INFO" marker="-LC4my-asUHCR3vLoiF7" message="Body: [${body}] "/>
            <process id="process2"/>
        </pipeline>
        <pipeline customId="true" id="step:-LC4n0ZosUHCR3vLoiF8">
            <setHeader headerName="Syndesis.STEP_ID" id="setHeader3">
                <constant>-LC4n0ZosUHCR3vLoiF8</constant>
            </setHeader>
            <filter id="filter1">
                <expressionDefinition>io.syndesis.integration.runtime.util.JsonSimplePredicate@1736c1e4</expressionDefinition>
                <process id="process3"/>
                <pipeline customId="true" id="step:-LC4nNLTsUHCR3vLoiF8">
                    <setHeader headerName="Syndesis.STEP_ID" id="setHeader4">
                        <constant>-LC4nNLTsUHCR3vLoiF8</constant>
                    </setHeader>
                    <process id="process4"/>
                    <process id="process5"/>
                </pipeline>
                <pipeline customId="true" id="step:-LC4mm4RsUHCR3vLoiF6">
                    <setHeader headerName="Syndesis.STEP_ID" id="setHeader5">
                        <constant>-LC4mm4RsUHCR3vLoiF6</constant>
                    </setHeader>
                    <to id="to1" uri="atlas:mapping-step-5.json?sourceMapName=Syndesis.CAPTURED_OUT_MESSAGES_MAP"/>
                    <process id="process6"/>
                </pipeline>
                <pipeline customId="true" id="step:-LC4mgbgsUHCR3vLoiF5">
                    <setHeader headerName="Syndesis.STEP_ID" id="setHeader6">
                        <constant>-LC4mgbgsUHCR3vLoiF5</constant>
                    </setHeader>
                    <to id="to2" uri="slack-6"/>
                    <process id="process7"/>
                </pipeline>
            </filter>
        </pipeline>
    </route>
</routes>

2018-05-09 16:49:48.113 DEBUG 1 --- [           main] o.a.camel.spring.boot.RoutesCollector    : CamelContextConfiguration found. Invoking afterApplicationStart: io.syndesis.integration.runtime.jmx.IntegrationMetadataAutoConfiguration$1@242aa8d9
2018-05-09 16:49:48.113 DEBUG 1 --- [           main] o.a.camel.spring.boot.RoutesCollector    : CamelContextConfiguration found. Invoking afterApplicationStart: io.syndesis.integration.runtime.logging.IntegrationLoggingAutoConfiguration$1@11eadcba
2018-05-09 16:49:48.304  INFO 1 --- [           main] b.c.e.u.UndertowEmbeddedServletContainer : Undertow started on port(s) 8080 (http)
2018-05-09 16:49:48.326  INFO 1 --- [           main] io.syndesis.example.Application          : Started Application in 17.403 seconds (JVM running for 19.975)
{"exchange":"i-LC4nl6M-ssGEVMSPU_ez","status":"begin"}
{"exchange":"i-LC4nl6M-ssGEVMSPU_ez","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl6d-ssGEVMSPU_hz","message":"Body: [Tue Aug 08 10:21:08 UTC 2017 (ro14nd) @syndesis_d_test Gruesse aus Pegnitz !] "}
2018-05-09 16:49:48.521  INFO 1 --- [line://MENTIONS] -LC4mdgesUHCR3vLoiF5                     : Body: [Tue Aug 08 10:21:08 UTC 2017 (ro14nd) @syndesis_d_test Gruesse aus Pegnitz !] 
{"exchange":"i-LC4nl6M-ssGEVMSPU_ez","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl6b-ssGEVMSPU_gz","duration":2813503}
{"exchange":"i-LC4nl6M-ssGEVMSPU_ez","step":"-LC4n0ZosUHCR3vLoiF8","id":"i-LC4nl6f-ssGEVMSPU_iz","duration":91826931}
{"exchange":"i-LC4nl6M-ssGEVMSPU_ez","status":"done","failed":false}
{"exchange":"i-LC4nl87-ssGEVMSPU_jz","status":"begin"}
{"exchange":"i-LC4nl87-ssGEVMSPU_jz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl88-ssGEVMSPU_mz","message":"Body: [Tue Aug 08 10:21:08 UTC 2017 (j0l0kia) @syndesis_d_test Gruesse aus Pegnitz !] "}
2018-05-09 16:49:48.617  INFO 1 --- [line://MENTIONS] -LC4mdgesUHCR3vLoiF5                     : Body: [Tue Aug 08 10:21:08 UTC 2017 (j0l0kia) @syndesis_d_test Gruesse aus Pegnitz !] 
{"exchange":"i-LC4nl87-ssGEVMSPU_jz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl87-ssGEVMSPU_lz","duration":1277521}
{"exchange":"i-LC4nl87-ssGEVMSPU_jz","step":"-LC4n0ZosUHCR3vLoiF8","id":"i-LC4nl89-ssGEVMSPU_nz","duration":593230}
{"exchange":"i-LC4nl87-ssGEVMSPU_jz","status":"done","failed":false}
{"exchange":"i-LC4nl8A-ssGEVMSPU_oz","status":"begin"}
{"exchange":"i-LC4nl8A-ssGEVMSPU_oz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8B-ssGEVMSPU_rz","message":"Body: [Fri Sep 01 14:37:34 UTC 2017 (ro14nd) @syndesis_d_test How are you ? 'hope Sprint 16 worked well ..] "}
2018-05-09 16:49:48.620  INFO 1 --- [line://MENTIONS] -LC4mdgesUHCR3vLoiF5                     : Body: [Fri Sep 01 14:37:34 UTC 2017 (ro14nd) @syndesis_d_test How are you ? 'hope Sprint 16 worked well ..] 
{"exchange":"i-LC4nl8A-ssGEVMSPU_oz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8A-ssGEVMSPU_qz","duration":746338}
{"exchange":"i-LC4nl8A-ssGEVMSPU_oz","step":"-LC4n0ZosUHCR3vLoiF8","id":"i-LC4nl8B-ssGEVMSPU_sz","duration":589991}
{"exchange":"i-LC4nl8A-ssGEVMSPU_oz","status":"done","failed":false}
{"exchange":"i-LC4nl8C-ssGEVMSPU_tz","status":"begin"}
{"exchange":"i-LC4nl8C-ssGEVMSPU_tz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8D-ssGEVMSPU_wz","message":"Body: [Fri Sep 01 14:39:18 UTC 2017 (ro14nd) @syndesis_d_test Looking forward to Sprint 17] "}
2018-05-09 16:49:48.622  INFO 1 --- [line://MENTIONS] -LC4mdgesUHCR3vLoiF5                     : Body: [Fri Sep 01 14:39:18 UTC 2017 (ro14nd) @syndesis_d_test Looking forward to Sprint 17] 
{"exchange":"i-LC4nl8C-ssGEVMSPU_tz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8D-ssGEVMSPU_vz","duration":771937}
{"exchange":"i-LC4nl8C-ssGEVMSPU_tz","step":"-LC4n0ZosUHCR3vLoiF8","id":"i-LC4nl8E-ssGEVMSPU_xz","duration":585497}
{"exchange":"i-LC4nl8C-ssGEVMSPU_tz","status":"done","failed":false}
{"exchange":"i-LC4nl8F-ssGEVMSPU_yz","status":"begin"}
{"exchange":"i-LC4nl8F-ssGEVMSPU_yz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8G-ssGEVMSPUa0z","message":"Body: [Fri Sep 01 14:40:04 UTC 2017 (ro14nd) @syndesis_d_test Sprint 16 is over now] "}
2018-05-09 16:49:48.625  INFO 1 --- [line://MENTIONS] -LC4mdgesUHCR3vLoiF5                     : Body: [Fri Sep 01 14:40:04 UTC 2017 (ro14nd) @syndesis_d_test Sprint 16 is over now] 
{"exchange":"i-LC4nl8F-ssGEVMSPU_yz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8G-ssGEVMSPUa-z","duration":726874}
{"exchange":"i-LC4nl8F-ssGEVMSPU_yz","step":"-LC4n0ZosUHCR3vLoiF8","id":"i-LC4nl8H-ssGEVMSPUa1z","duration":581349}
{"exchange":"i-LC4nl8F-ssGEVMSPU_yz","status":"done","failed":false}
{"exchange":"i-LC4nl8H-ssGEVMSPUa2z","status":"begin"}
{"exchange":"i-LC4nl8H-ssGEVMSPUa2z","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8I-ssGEVMSPUa5z","message":"Body: [Fri Sep 01 14:40:57 UTC 2017 (zregvart) @syndesis_d_test Hi Roland!] "}
2018-05-09 16:49:48.627  INFO 1 --- [line://MENTIONS] -LC4mdgesUHCR3vLoiF5                     : Body: [Fri Sep 01 14:40:57 UTC 2017 (zregvart) @syndesis_d_test Hi Roland!] 
{"exchange":"i-LC4nl8H-ssGEVMSPUa2z","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8I-ssGEVMSPUa4z","duration":703176}
{"exchange":"i-LC4nl8H-ssGEVMSPUa2z","step":"-LC4n0ZosUHCR3vLoiF8","id":"i-LC4nl8J-ssGEVMSPUa6z","duration":625293}
{"exchange":"i-LC4nl8H-ssGEVMSPUa2z","status":"done","failed":false}
{"exchange":"i-LC4nl8K-ssGEVMSPUa7z","status":"begin"}
{"exchange":"i-LC4nl8K-ssGEVMSPUa7z","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8L-ssGEVMSPUaAz","message":"Body: [Fri Sep 01 14:41:50 UTC 2017 (zregvart) @syndesis_d_test Awesome demo!] "}
2018-05-09 16:49:48.630  INFO 1 --- [line://MENTIONS] -LC4mdgesUHCR3vLoiF5                     : Body: [Fri Sep 01 14:41:50 UTC 2017 (zregvart) @syndesis_d_test Awesome demo!] 
{"exchange":"i-LC4nl8K-ssGEVMSPUa7z","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8K-ssGEVMSPUa9z","duration":564832}
{"exchange":"i-LC4nl8K-ssGEVMSPUa7z","step":"-LC4n0ZosUHCR3vLoiF8","id":"i-LC4nl8L-ssGEVMSPUaBz","duration":498022}
{"exchange":"i-LC4nl8K-ssGEVMSPUa7z","status":"done","failed":false}
{"exchange":"i-LC4nl8M-ssGEVMSPUaCz","status":"begin"}
{"exchange":"i-LC4nl8M-ssGEVMSPUaCz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8N-ssGEVMSPUaFz","message":"Body: [Sun Oct 08 14:50:41 UTC 2017 (k8spatterns) @syndesis_d_test Hello ! Touch down ?] "}
2018-05-09 16:49:48.632  INFO 1 --- [line://MENTIONS] -LC4mdgesUHCR3vLoiF5                     : Body: [Sun Oct 08 14:50:41 UTC 2017 (k8spatterns) @syndesis_d_test Hello ! Touch down ?] 
{"exchange":"i-LC4nl8M-ssGEVMSPUaCz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8M-ssGEVMSPUaEz","duration":560466}
{"exchange":"i-LC4nl8M-ssGEVMSPUaCz","step":"-LC4n0ZosUHCR3vLoiF8","id":"i-LC4nl8N-ssGEVMSPUaGz","duration":506401}
{"exchange":"i-LC4nl8M-ssGEVMSPUaCz","status":"done","failed":false}
{"exchange":"i-LC4nl8O-ssGEVMSPUaHz","status":"begin"}
{"exchange":"i-LC4nl8O-ssGEVMSPUaHz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8O-ssGEVMSPUaKz","message":"Body: [Thu Oct 19 19:21:45 UTC 2017 (syndesisio) @syndesis_d_test TP2 is coming !] "}
2018-05-09 16:49:48.634  INFO 1 --- [line://MENTIONS] -LC4mdgesUHCR3vLoiF5                     : Body: [Thu Oct 19 19:21:45 UTC 2017 (syndesisio) @syndesis_d_test TP2 is coming !] 
{"exchange":"i-LC4nl8O-ssGEVMSPUaHz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8O-ssGEVMSPUaJz","duration":509723}
{"exchange":"i-LC4nl8O-ssGEVMSPUaHz","step":"-LC4n0ZosUHCR3vLoiF8","id":"i-LC4nl8P-ssGEVMSPUaLz","duration":498537}
{"exchange":"i-LC4nl8O-ssGEVMSPUaHz","status":"done","failed":false}
{"exchange":"i-LC4nl8Q-ssGEVMSPUaMz","status":"begin"}
{"exchange":"i-LC4nl8Q-ssGEVMSPUaMz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8Q-ssGEVMSPUaPz","message":"Body: [Wed Oct 25 08:38:42 UTC 2017 (syndesisio) @syndesis_d_test It's getting cold these days ...] "}
2018-05-09 16:49:48.636  INFO 1 --- [line://MENTIONS] -LC4mdgesUHCR3vLoiF5                     : Body: [Wed Oct 25 08:38:42 UTC 2017 (syndesisio) @syndesis_d_test It's getting cold these days ...] 
{"exchange":"i-LC4nl8Q-ssGEVMSPUaMz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8Q-ssGEVMSPUaOz","duration":535953}
{"exchange":"i-LC4nl8Q-ssGEVMSPUaMz","step":"-LC4n0ZosUHCR3vLoiF8","id":"i-LC4nl8R-ssGEVMSPUaQz","duration":466405}
{"exchange":"i-LC4nl8Q-ssGEVMSPUaMz","status":"done","failed":false}
{"exchange":"i-LC4nl8S-ssGEVMSPUaRz","status":"begin"}
{"exchange":"i-LC4nl8S-ssGEVMSPUaRz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8S-ssGEVMSPUaUz","message":"Body: [Wed Oct 25 08:38:42 UTC 2017 (j0l0kia) @syndesis_d_test It's getting cold these days ...] "}
2018-05-09 16:49:48.637  INFO 1 --- [line://MENTIONS] -LC4mdgesUHCR3vLoiF5                     : Body: [Wed Oct 25 08:38:42 UTC 2017 (j0l0kia) @syndesis_d_test It's getting cold these days ...] 
{"exchange":"i-LC4nl8S-ssGEVMSPUaRz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl8S-ssGEVMSPUaTz","duration":570789}
{"exchange":"i-LC4nl8S-ssGEVMSPUaRz","step":"-LC4n0ZosUHCR3vLoiF8","id":"i-LC4nl8T-ssGEVMSPUaVz","duration":59468597}
{"exchange":"i-LC4nl8S-ssGEVMSPUaRz","status":"done","failed":false}
{"exchange":"i-LC4nl9P-ssGEVMSPUaWz","status":"begin"}
{"exchange":"i-LC4nl9P-ssGEVMSPUaWz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl9P-ssGEVMSPUaZz","message":"Body: [Wed Nov 01 10:07:24 UTC 2017 (j0l0kia) @syndesis_d_test no matter how great and destructive your problems may seem now, remember, you've probably only seen the tip of them.] "}
2018-05-09 16:49:48.698  INFO 1 --- [line://MENTIONS] -LC4mdgesUHCR3vLoiF5                     : Body: [Wed Nov 01 10:07:24 UTC 2017 (j0l0kia) @syndesis_d_test no matter how great and destructive your problems may seem now, remember, you've probably only seen the tip of them.] 
{"exchange":"i-LC4nl9P-ssGEVMSPUaWz","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4nl9P-ssGEVMSPUaYz","duration":623428}
{"exchange":"i-LC4nl9P-ssGEVMSPUaWz","step":"-LC4nNLTsUHCR3vLoiF8","id":"i-LC4nl9R-ssGEVMSPUaaz","duration":1050347}
2018-05-09 16:49:48.721  WARN 1 --- [line://MENTIONS] i.a.core.DefaultAtlasConversionService   : Converter between java.util.Date and java.time.ZonedDateTime aleady exists.
2018-05-09 16:49:48.727  WARN 1 --- [line://MENTIONS] i.a.core.DefaultAtlasConversionService   : Converter between java.time.LocalDate and java.time.ZonedDateTime aleady exists.
2018-05-09 16:49:48.728  WARN 1 --- [line://MENTIONS] i.a.core.DefaultAtlasConversionService   : Converter between java.time.LocalDateTime and java.time.ZonedDateTime aleady exists.
2018-05-09 16:49:48.729  WARN 1 --- [line://MENTIONS] i.a.core.DefaultAtlasConversionService   : Converter between java.time.LocalTime and java.time.ZonedDateTime aleady exists.
{"exchange":"i-LC4nl9P-ssGEVMSPUaWz","step":"-LC4mm4RsUHCR3vLoiF6","id":"i-LC4nl9S-ssGEVMSPUabz","duration":817600446}
{"exchange":"i-LC4nl9P-ssGEVMSPUaWz","step":"-LC4mgbgsUHCR3vLoiF5","id":"i-LC4nlME-ssGEVMSPUadz","duration":588065922}
{"exchange":"i-LC4nl9P-ssGEVMSPUaWz","step":"-LC4mgbgsUHCR3vLoiF5","id":"i-LC4nl9Q-ssGEVMSPUa_z","duration":1408030292}
{"exchange":"i-LC4nl9P-ssGEVMSPUaWz","status":"done","failed":false}
{"exchange":"i-LC4o6a2-ssGEVMSPUaez","status":"begin"}
{"exchange":"i-LC4o6a2-ssGEVMSPUaez","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4o6a3-ssGEVMSPUahz","message":"Body: [Wed May 09 16:50:51 UTC 2018 (syndesisio) @syndesis_d_test wink, remember there are always dragons out there causing problems.] "}
2018-05-09 16:51:20.581  INFO 1 --- [line://MENTIONS] -LC4mdgesUHCR3vLoiF5                     : Body: [Wed May 09 16:50:51 UTC 2018 (syndesisio) @syndesis_d_test wink, remember there are always dragons out there causing problems.] 
{"exchange":"i-LC4o6a2-ssGEVMSPUaez","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4o6a3-ssGEVMSPUagz","duration":600858}
{"exchange":"i-LC4o6a2-ssGEVMSPUaez","step":"-LC4nNLTsUHCR3vLoiF8","id":"i-LC4o6a5-ssGEVMSPUajz","duration":250495}
{"exchange":"i-LC4o6a2-ssGEVMSPUaez","step":"-LC4mm4RsUHCR3vLoiF6","id":"i-LC4o6a5-ssGEVMSPUakz","duration":1762889}
{"exchange":"i-LC4o6a2-ssGEVMSPUaez","step":"-LC4mgbgsUHCR3vLoiF5","id":"i-LC4o6a7-ssGEVMSPUamz","duration":78436493}
{"exchange":"i-LC4o6a2-ssGEVMSPUaez","step":"-LC4mgbgsUHCR3vLoiF5","id":"i-LC4o6a4-ssGEVMSPUaiz","duration":81587720}
{"exchange":"i-LC4o6a2-ssGEVMSPUaez","status":"done","failed":false}
@gashcrumb

This comment has been minimized.

Show comment
Hide comment
@gashcrumb

gashcrumb May 9, 2018

Contributor

If you happen to get the response data from the network tab in your browser that'd be awesome too, ideally that should correlate to the pod log or not which helps narrow it down.

Contributor

gashcrumb commented May 9, 2018

If you happen to get the response data from the network tab in your browser that'd be awesome too, ideally that should correlate to the pod log or not which helps narrow it down.

@rhuss

This comment has been minimized.

Show comment
Hide comment
@rhuss

rhuss May 9, 2018

Collaborator

Here it is

json response
[
  {
    "id": "i-LC4o6a2-ssGEVMSPUaez",
    "logts": "2018-05-09T16:51:20.580465018Z",
    "at": 1525884680579,
    "pod": "i-twitter-to-slack-2-8j4sn",
    "ver": "1",
    "status": "done",
    "failed": false,
    "steps": [
      {
        "id": "-LC4my-asUHCR3vLoiF7",
        "at": 1525884680580,
        "duration": 600858
      },
      {
        "id": "-LC4nNLTsUHCR3vLoiF8",
        "at": 1525884680582,
        "duration": 250495
      },
      {
        "id": "-LC4mm4RsUHCR3vLoiF6",
        "at": 1525884680582,
        "duration": 1762889
      },
      {
        "id": "-LC4mgbgsUHCR3vLoiF5",
        "at": 1525884680584,
        "duration": 81587720
      }
    ]
  },
  {
    "id": "i-LC4nl8S-ssGEVMSPUaRz",
    "logts": "2018-05-09T16:49:48.637315332Z",
    "at": 1525884588637,
    "pod": "i-twitter-to-slack-2-8j4sn",
    "ver": "1",
    "status": "done",
    "failed": false,
    "steps": [
      {
        "id": "-LC4my-asUHCR3vLoiF7",
        "at": 1525884588637,
        "duration": 570789,
        "messages": [
          "Body: [Wed Oct 25 08:38:42 UTC 2017 (j0l0kia) @syndesis_d_test It's getting cold these days ...] "
        ]
      },
      {
        "id": "-LC4n0ZosUHCR3vLoiF8",
        "at": 1525884588638,
        "duration": 59468597
      }
    ]
  },
  {
    "id": "i-LC4nl8Q-ssGEVMSPUaMz",
    "logts": "2018-05-09T16:49:48.635339006Z",
    "at": 1525884588635,
    "pod": "i-twitter-to-slack-2-8j4sn",
    "ver": "1",
    "status": "done",
    "failed": false,
    "steps": [
      {
        "id": "-LC4my-asUHCR3vLoiF7",
        "at": 1525884588635,
        "duration": 535953,
        "messages": [
          "Body: [Wed Oct 25 08:38:42 UTC 2017 (syndesisio) @syndesis_d_test It's getting cold these days ...] "
        ]
      },
      {
        "id": "-LC4n0ZosUHCR3vLoiF8",
        "at": 1525884588636,
        "duration": 466405
      }
    ]
  },
  {
    "id": "i-LC4nl8O-ssGEVMSPUaHz",
    "logts": "2018-05-09T16:49:48.633423863Z",
    "at": 1525884588633,
    "pod": "i-twitter-to-slack-2-8j4sn",
    "ver": "1",
    "status": "done",
    "failed": false,
    "steps": [
      {
        "id": "-LC4my-asUHCR3vLoiF7",
        "at": 1525884588633,
        "duration": 509723,
        "messages": [
          "Body: [Thu Oct 19 19:21:45 UTC 2017 (syndesisio) @syndesis_d_test TP2 is coming !] "
        ]
      },
      {
        "id": "-LC4n0ZosUHCR3vLoiF8",
        "at": 1525884588634,
        "duration": 498537
      }
    ]
  },
  {
    "id": "i-LC4nl8M-ssGEVMSPUaCz",
    "logts": "2018-05-09T16:49:48.631454846Z",
    "at": 1525884588631,
    "pod": "i-twitter-to-slack-2-8j4sn",
    "ver": "1",
    "status": "done",
    "failed": false,
    "steps": [
      {
        "id": "-LC4my-asUHCR3vLoiF7",
        "at": 1525884588632,
        "duration": 560466,
        "messages": [
          "Body: [Sun Oct 08 14:50:41 UTC 2017 (k8spatterns) @syndesis_d_test Hello ! Touch down ?] "
        ]
      },
      {
        "id": "-LC4n0ZosUHCR3vLoiF8",
        "at": 1525884588632,
        "duration": 506401
      }
    ]
  },
  {
    "id": "i-LC4nl8K-ssGEVMSPUa7z",
    "logts": "2018-05-09T16:49:48.629448119Z",
    "at": 1525884588629,
    "pod": "i-twitter-to-slack-2-8j4sn",
    "ver": "1",
    "status": "done",
    "failed": false,
    "steps": [
      {
        "id": "-LC4my-asUHCR3vLoiF7",
        "at": 1525884588630,
        "duration": 564832,
        "messages": [
          "Body: [Fri Sep 01 14:41:50 UTC 2017 (zregvart) @syndesis_d_test Awesome demo!] "
        ]
      },
      {
        "id": "-LC4n0ZosUHCR3vLoiF8",
        "at": 1525884588630,
        "duration": 498022
      }
    ]
  },
  {
    "id": "i-LC4nl8H-ssGEVMSPUa2z",
    "logts": "2018-05-09T16:49:48.627963871Z",
    "at": 1525884588626,
    "pod": "i-twitter-to-slack-2-8j4sn",
    "ver": "1",
    "status": "done",
    "failed": false,
    "steps": [
      {
        "id": "-LC4my-asUHCR3vLoiF7",
        "at": 1525884588627,
        "duration": 703176,
        "messages": [
          "Body: [Fri Sep 01 14:40:57 UTC 2017 (zregvart) @syndesis_d_test Hi Roland!] "
        ]
      },
      {
        "id": "-LC4n0ZosUHCR3vLoiF8",
        "at": 1525884588628,
        "duration": 625293
      }
    ]
  },
  {
    "id": "i-LC4nl8F-ssGEVMSPU_yz",
    "logts": "2018-05-09T16:49:48.624704491Z",
    "at": 1525884588624,
    "pod": "i-twitter-to-slack-2-8j4sn",
    "ver": "1",
    "status": "done",
    "failed": false,
    "steps": [
      {
        "id": "-LC4my-asUHCR3vLoiF7",
        "at": 1525884588625,
        "duration": 726874,
        "messages": [
          "Body: [Fri Sep 01 14:40:04 UTC 2017 (ro14nd) @syndesis_d_test Sprint 16 is over now] "
        ]
      },
      {
        "id": "-LC4n0ZosUHCR3vLoiF8",
        "at": 1525884588626,
        "duration": 581349
      }
    ]
  },
  {
    "id": "i-LC4nl8C-ssGEVMSPU_tz",
    "logts": "2018-05-09T16:49:48.622045625Z",
    "at": 1525884588621,
    "pod": "i-twitter-to-slack-2-8j4sn",
    "ver": "1",
    "status": "done",
    "failed": false,
    "steps": [
      {
        "id": "-LC4my-asUHCR3vLoiF7",
        "at": 1525884588622,
        "duration": 771937,
        "messages": [
          "Body: [Fri Sep 01 14:39:18 UTC 2017 (ro14nd) @syndesis_d_test Looking forward to Sprint 17] "
        ]
      },
      {
        "id": "-LC4n0ZosUHCR3vLoiF8",
        "at": 1525884588623,
        "duration": 585497
      }
    ]
  },
  {
    "id": "i-LC4nl8A-ssGEVMSPU_oz",
    "logts": "2018-05-09T16:49:48.619655929Z",
    "at": 1525884588619,
    "pod": "i-twitter-to-slack-2-8j4sn",
    "ver": "1",
    "status": "done",
    "failed": false,
    "steps": [
      {
        "id": "-LC4my-asUHCR3vLoiF7",
        "at": 1525884588620,
        "duration": 746338,
        "messages": [
          "Body: [Fri Sep 01 14:37:34 UTC 2017 (ro14nd) @syndesis_d_test How are you ? 'hope Sprint 16 worked well ..] "
        ]
      },
      {
        "id": "-LC4n0ZosUHCR3vLoiF8",
        "at": 1525884588620,
        "duration": 589991
      }
    ]
  }
]
Collaborator

rhuss commented May 9, 2018

Here it is

json response
[
  {
    "id": "i-LC4o6a2-ssGEVMSPUaez",
    "logts": "2018-05-09T16:51:20.580465018Z",
    "at": 1525884680579,
    "pod": "i-twitter-to-slack-2-8j4sn",
    "ver": "1",
    "status": "done",
    "failed": false,
    "steps": [
      {
        "id": "-LC4my-asUHCR3vLoiF7",
        "at": 1525884680580,
        "duration": 600858
      },
      {
        "id": "-LC4nNLTsUHCR3vLoiF8",
        "at": 1525884680582,
        "duration": 250495
      },
      {
        "id": "-LC4mm4RsUHCR3vLoiF6",
        "at": 1525884680582,
        "duration": 1762889
      },
      {
        "id": "-LC4mgbgsUHCR3vLoiF5",
        "at": 1525884680584,
        "duration": 81587720
      }
    ]
  },
  {
    "id": "i-LC4nl8S-ssGEVMSPUaRz",
    "logts": "2018-05-09T16:49:48.637315332Z",
    "at": 1525884588637,
    "pod": "i-twitter-to-slack-2-8j4sn",
    "ver": "1",
    "status": "done",
    "failed": false,
    "steps": [
      {
        "id": "-LC4my-asUHCR3vLoiF7",
        "at": 1525884588637,
        "duration": 570789,
        "messages": [
          "Body: [Wed Oct 25 08:38:42 UTC 2017 (j0l0kia) @syndesis_d_test It's getting cold these days ...] "
        ]
      },
      {
        "id": "-LC4n0ZosUHCR3vLoiF8",
        "at": 1525884588638,
        "duration": 59468597
      }
    ]
  },
  {
    "id": "i-LC4nl8Q-ssGEVMSPUaMz",
    "logts": "2018-05-09T16:49:48.635339006Z",
    "at": 1525884588635,
    "pod": "i-twitter-to-slack-2-8j4sn",
    "ver": "1",
    "status": "done",
    "failed": false,
    "steps": [
      {
        "id": "-LC4my-asUHCR3vLoiF7",
        "at": 1525884588635,
        "duration": 535953,
        "messages": [
          "Body: [Wed Oct 25 08:38:42 UTC 2017 (syndesisio) @syndesis_d_test It's getting cold these days ...] "
        ]
      },
      {
        "id": "-LC4n0ZosUHCR3vLoiF8",
        "at": 1525884588636,
        "duration": 466405
      }
    ]
  },
  {
    "id": "i-LC4nl8O-ssGEVMSPUaHz",
    "logts": "2018-05-09T16:49:48.633423863Z",
    "at": 1525884588633,
    "pod": "i-twitter-to-slack-2-8j4sn",
    "ver": "1",
    "status": "done",
    "failed": false,
    "steps": [
      {
        "id": "-LC4my-asUHCR3vLoiF7",
        "at": 1525884588633,
        "duration": 509723,
        "messages": [
          "Body: [Thu Oct 19 19:21:45 UTC 2017 (syndesisio) @syndesis_d_test TP2 is coming !] "
        ]
      },
      {
        "id": "-LC4n0ZosUHCR3vLoiF8",
        "at": 1525884588634,
        "duration": 498537
      }
    ]
  },
  {
    "id": "i-LC4nl8M-ssGEVMSPUaCz",
    "logts": "2018-05-09T16:49:48.631454846Z",
    "at": 1525884588631,
    "pod": "i-twitter-to-slack-2-8j4sn",
    "ver": "1",
    "status": "done",
    "failed": false,
    "steps": [
      {
        "id": "-LC4my-asUHCR3vLoiF7",
        "at": 1525884588632,
        "duration": 560466,
        "messages": [
          "Body: [Sun Oct 08 14:50:41 UTC 2017 (k8spatterns) @syndesis_d_test Hello ! Touch down ?] "
        ]
      },
      {
        "id": "-LC4n0ZosUHCR3vLoiF8",
        "at": 1525884588632,
        "duration": 506401
      }
    ]
  },
  {
    "id": "i-LC4nl8K-ssGEVMSPUa7z",
    "logts": "2018-05-09T16:49:48.629448119Z",
    "at": 1525884588629,
    "pod": "i-twitter-to-slack-2-8j4sn",
    "ver": "1",
    "status": "done",
    "failed": false,
    "steps": [
      {
        "id": "-LC4my-asUHCR3vLoiF7",
        "at": 1525884588630,
        "duration": 564832,
        "messages": [
          "Body: [Fri Sep 01 14:41:50 UTC 2017 (zregvart) @syndesis_d_test Awesome demo!] "
        ]
      },
      {
        "id": "-LC4n0ZosUHCR3vLoiF8",
        "at": 1525884588630,
        "duration": 498022
      }
    ]
  },
  {
    "id": "i-LC4nl8H-ssGEVMSPUa2z",
    "logts": "2018-05-09T16:49:48.627963871Z",
    "at": 1525884588626,
    "pod": "i-twitter-to-slack-2-8j4sn",
    "ver": "1",
    "status": "done",
    "failed": false,
    "steps": [
      {
        "id": "-LC4my-asUHCR3vLoiF7",
        "at": 1525884588627,
        "duration": 703176,
        "messages": [
          "Body: [Fri Sep 01 14:40:57 UTC 2017 (zregvart) @syndesis_d_test Hi Roland!] "
        ]
      },
      {
        "id": "-LC4n0ZosUHCR3vLoiF8",
        "at": 1525884588628,
        "duration": 625293
      }
    ]
  },
  {
    "id": "i-LC4nl8F-ssGEVMSPU_yz",
    "logts": "2018-05-09T16:49:48.624704491Z",
    "at": 1525884588624,
    "pod": "i-twitter-to-slack-2-8j4sn",
    "ver": "1",
    "status": "done",
    "failed": false,
    "steps": [
      {
        "id": "-LC4my-asUHCR3vLoiF7",
        "at": 1525884588625,
        "duration": 726874,
        "messages": [
          "Body: [Fri Sep 01 14:40:04 UTC 2017 (ro14nd) @syndesis_d_test Sprint 16 is over now] "
        ]
      },
      {
        "id": "-LC4n0ZosUHCR3vLoiF8",
        "at": 1525884588626,
        "duration": 581349
      }
    ]
  },
  {
    "id": "i-LC4nl8C-ssGEVMSPU_tz",
    "logts": "2018-05-09T16:49:48.622045625Z",
    "at": 1525884588621,
    "pod": "i-twitter-to-slack-2-8j4sn",
    "ver": "1",
    "status": "done",
    "failed": false,
    "steps": [
      {
        "id": "-LC4my-asUHCR3vLoiF7",
        "at": 1525884588622,
        "duration": 771937,
        "messages": [
          "Body: [Fri Sep 01 14:39:18 UTC 2017 (ro14nd) @syndesis_d_test Looking forward to Sprint 17] "
        ]
      },
      {
        "id": "-LC4n0ZosUHCR3vLoiF8",
        "at": 1525884588623,
        "duration": 585497
      }
    ]
  },
  {
    "id": "i-LC4nl8A-ssGEVMSPU_oz",
    "logts": "2018-05-09T16:49:48.619655929Z",
    "at": 1525884588619,
    "pod": "i-twitter-to-slack-2-8j4sn",
    "ver": "1",
    "status": "done",
    "failed": false,
    "steps": [
      {
        "id": "-LC4my-asUHCR3vLoiF7",
        "at": 1525884588620,
        "duration": 746338,
        "messages": [
          "Body: [Fri Sep 01 14:37:34 UTC 2017 (ro14nd) @syndesis_d_test How are you ? 'hope Sprint 16 worked well ..] "
        ]
      },
      {
        "id": "-LC4n0ZosUHCR3vLoiF8",
        "at": 1525884588620,
        "duration": 589991
      }
    ]
  }
]
@rhuss

This comment has been minimized.

Show comment
Hide comment
@rhuss

rhuss May 9, 2018

Collaborator

It doesn't look like an UI issue, more like a backend issue when storing or retrieving the activity entries.

Collaborator

rhuss commented May 9, 2018

It doesn't look like an UI issue, more like a backend issue when storing or retrieving the activity entries.

@rhuss

This comment has been minimized.

Show comment
Hide comment
@rhuss

rhuss May 9, 2018

Collaborator

@chirino When looking a bit closer to the log, it looks like that a single step is logged twice, once with message and once without:

{"exchange":"i-LC4o6a2-ssGEVMSPUaez","status":"begin"}
{"exchange":"i-LC4o6a2-ssGEVMSPUaez","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4o6a3-ssGEVMSPUahz","message":"Body: [Wed May 09 16:50:51 UTC 2018 (syndesisio) @syndesis_d_test wink, remember there are always dragons out there causing problems.] "}
2018-05-09 16:51:20.581  INFO 1 --- [line://MENTIONS] -LC4mdgesUHCR3vLoiF5                     : Body: [Wed May 09 16:50:51 UTC 2018 (syndesisio) @syndesis_d_test wink, remember there are always dragons out there causing problems.] 
{"exchange":"i-LC4o6a2-ssGEVMSPUaez","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4o6a3-ssGEVMSPUagz","duration":600858}
{"exchange":"i-LC4o6a2-ssGEVMSPUaez","step":"-LC4nNLTsUHCR3vLoiF8","id":"i-LC4o6a5-ssGEVMSPUajz","duration":250495}
{"exchange":"i-LC4o6a2-ssGEVMSPUaez","step":"-LC4mm4RsUHCR3vLoiF6","id":"i-LC4o6a5-ssGEVMSPUakz","duration":1762889}
{"exchange":"i-LC4o6a2-ssGEVMSPUaez","step":"-LC4mgbgsUHCR3vLoiF5","id":"i-LC4o6a7-ssGEVMSPUamz","duration":78436493}
{"exchange":"i-LC4o6a2-ssGEVMSPUaez","step":"-LC4mgbgsUHCR3vLoiF5","id":"i-LC4o6a4-ssGEVMSPUaiz","duration":81587720}
{"exchange":"i-LC4o6a2-ssGEVMSPUaez","status":"done","failed":false}

Does this ring some bells ? The version I tested btw is the tagged 1.3.5 upstream version.

Collaborator

rhuss commented May 9, 2018

@chirino When looking a bit closer to the log, it looks like that a single step is logged twice, once with message and once without:

{"exchange":"i-LC4o6a2-ssGEVMSPUaez","status":"begin"}
{"exchange":"i-LC4o6a2-ssGEVMSPUaez","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4o6a3-ssGEVMSPUahz","message":"Body: [Wed May 09 16:50:51 UTC 2018 (syndesisio) @syndesis_d_test wink, remember there are always dragons out there causing problems.] "}
2018-05-09 16:51:20.581  INFO 1 --- [line://MENTIONS] -LC4mdgesUHCR3vLoiF5                     : Body: [Wed May 09 16:50:51 UTC 2018 (syndesisio) @syndesis_d_test wink, remember there are always dragons out there causing problems.] 
{"exchange":"i-LC4o6a2-ssGEVMSPUaez","step":"-LC4my-asUHCR3vLoiF7","id":"i-LC4o6a3-ssGEVMSPUagz","duration":600858}
{"exchange":"i-LC4o6a2-ssGEVMSPUaez","step":"-LC4nNLTsUHCR3vLoiF8","id":"i-LC4o6a5-ssGEVMSPUajz","duration":250495}
{"exchange":"i-LC4o6a2-ssGEVMSPUaez","step":"-LC4mm4RsUHCR3vLoiF6","id":"i-LC4o6a5-ssGEVMSPUakz","duration":1762889}
{"exchange":"i-LC4o6a2-ssGEVMSPUaez","step":"-LC4mgbgsUHCR3vLoiF5","id":"i-LC4o6a7-ssGEVMSPUamz","duration":78436493}
{"exchange":"i-LC4o6a2-ssGEVMSPUaez","step":"-LC4mgbgsUHCR3vLoiF5","id":"i-LC4o6a4-ssGEVMSPUaiz","duration":81587720}
{"exchange":"i-LC4o6a2-ssGEVMSPUaez","status":"done","failed":false}

Does this ring some bells ? The version I tested btw is the tagged 1.3.5 upstream version.

@dsimansk

This comment has been minimized.

Show comment
Hide comment
@dsimansk

dsimansk May 9, 2018

Member

@rhuss this is quiet similar to #2487.

@lburgazzoli might have more insight.

Member

dsimansk commented May 9, 2018

@rhuss this is quiet similar to #2487.

@lburgazzoli might have more insight.

@rhuss

This comment has been minimized.

Show comment
Hide comment
@rhuss

rhuss May 9, 2018

Collaborator

Also just seen that sometimes a step like a filter step was completely missing in the list (unfortunately I already deleted that integration)

Collaborator

rhuss commented May 9, 2018

Also just seen that sometimes a step like a filter step was completely missing in the list (unfortunately I already deleted that integration)

@rhuss

This comment has been minimized.

Show comment
Hide comment
@rhuss

rhuss May 10, 2018

Collaborator

@paoloantinori tbh, I consider this issue a blocker, too. Could you please triage ?

Collaborator

rhuss commented May 10, 2018

@paoloantinori tbh, I consider this issue a blocker, too. Could you please triage ?

@lburgazzoli

This comment has been minimized.

Show comment
Hide comment
@lburgazzoli

lburgazzoli May 10, 2018

Collaborator

The step -LC4my-asUHCR3vLoiF7 is logged twice because:

  • the first time, it intercepts the log eip
  • the second time, it intercept when the step ends

I do not know how this should be handled as for built in steps we can maybe implement a workaround but this can be quickly shows its limitation if you write an extension that uses the log EIP while performing its processing.

My opinion is that we should see a step as a logical group of processors so we should take into account that a step may produce more than one event that need to be shown somehow by the activity view so we may need to turn message into an array or find a better way to represent step related events.

Collaborator

lburgazzoli commented May 10, 2018

The step -LC4my-asUHCR3vLoiF7 is logged twice because:

  • the first time, it intercepts the log eip
  • the second time, it intercept when the step ends

I do not know how this should be handled as for built in steps we can maybe implement a workaround but this can be quickly shows its limitation if you write an extension that uses the log EIP while performing its processing.

My opinion is that we should see a step as a logical group of processors so we should take into account that a step may produce more than one event that need to be shown somehow by the activity view so we may need to turn message into an array or find a better way to represent step related events.

@chirino

This comment has been minimized.

Show comment
Hide comment
@chirino

chirino Jun 12, 2018

Collaborator

@rhuss are you still seeing this with the latest nightly? Log handling has been updated to be much more consistent.

Collaborator

chirino commented Jun 12, 2018

@rhuss are you still seeing this with the latest nightly? Log handling has been updated to be much more consistent.

@heiko-braun heiko-braun added this to Backlog in Bug Backlog Jun 23, 2018

@rhuss

This comment has been minimized.

Show comment
Hide comment
@rhuss

rhuss Jun 25, 2018

Collaborator

Let me check later, now that I'm back.

Collaborator

rhuss commented Jun 25, 2018

Let me check later, now that I'm back.

@paoloantinori

This comment has been minimized.

Show comment
Hide comment
@paoloantinori

paoloantinori Jul 4, 2018

Contributor

hi @rhuss any update on this?

Contributor

paoloantinori commented Jul 4, 2018

hi @rhuss any update on this?

@heiko-braun

This comment has been minimized.

Show comment
Hide comment
@heiko-braun

heiko-braun Jul 18, 2018

Collaborator

Should be fixed, but needs verification

Collaborator

heiko-braun commented Jul 18, 2018

Should be fixed, but needs verification

@project-bot project-bot bot moved this from Backlog to Closed in Bug Backlog Jul 18, 2018

@heiko-braun heiko-braun reopened this Jul 18, 2018

@heiko-braun heiko-braun moved this from Closed to Backlog in Bug Backlog Jul 18, 2018

@tplevko

This comment has been minimized.

Show comment
Hide comment
@tplevko

tplevko Jul 24, 2018

Contributor

As mentioned, this should be fixed now. The activity log seems to be much more consistent, with the newest master I couldn't reproduce the inconsistent behavior. Closing.

Contributor

tplevko commented Jul 24, 2018

As mentioned, this should be fixed now. The activity log seems to be much more consistent, with the newest master I couldn't reproduce the inconsistent behavior. Closing.

@tplevko tplevko closed this Jul 24, 2018

@project-bot project-bot bot moved this from Backlog to Closed in Bug Backlog Jul 24, 2018

@heiko-braun

This comment has been minimized.

Show comment
Hide comment
@heiko-braun

heiko-braun Jul 24, 2018

Collaborator

thanks for the verification @tplevko

Collaborator

heiko-braun commented Jul 24, 2018

thanks for the verification @tplevko

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment