Propagate trace context in Block Steps #909
Draft
ci.jenkins.io / Tests / windows-11 / Build (windows-11)
failed
Jul 29, 2024 in 0s
failed: 7, skipped: 23, passed: 71
Send us feedback
Details
io.jenkins.plugins.opentelemetry.JenkinsOtelPluginIntegrationTest.testSpanContextPropagationSynchronousTestStep
No value present
Stack trace
java.util.NoSuchElementException: No value present
at java.base/java.util.Optional.get(Optional.java:148)
at io.jenkins.plugins.opentelemetry.BaseIntegrationTest.checkChainOfSpans(BaseIntegrationTest.java:146)
at io.jenkins.plugins.opentelemetry.JenkinsOtelPluginIntegrationTest.testSpanContextPropagationSynchronousTestStep(JenkinsOtelPluginIntegrationTest.java:542)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at org.jvnet.hudson.test.JenkinsRule$1.evaluate(JenkinsRule.java:655)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at java.base/java.lang.Thread.run(Thread.java:829)
Standard error
0.095 [id=17] INFO o.jvnet.hudson.test.WarExploder#explode: Picking up existing exploded jenkins.war at C:\Jenkins\agent\workspace\gins_opentelemetry-plugin_PR-909\target\jenkins-for-test
0.394 [id=17] INFO o.jvnet.hudson.test.JenkinsRule#createWebServer: Running on http://localhost:50170/jenkins/
1.210 [id=32] INFO jenkins.InitReactorRunner$1#onAttained: Started initialization
2.189 [id=35] INFO hudson.PluginManager#considerDetachedPlugin: Loading a detached plugin as a dependency: C:\Jenkins\agent\workspace\gins_opentelemetry-plugin_PR-909\target\tmp\jenkins12068691405153736485\plugins\sshd.jpi
2.229 [id=35] INFO hudson.PluginManager#considerDetachedPlugin: Loading a detached plugin as a dependency: C:\Jenkins\agent\workspace\gins_opentelemetry-plugin_PR-909\target\tmp\jenkins12068691405153736485\plugins\javax-mail-api.jpi
4.147 [id=34] INFO hudson.PluginManager#considerDetachedPlugin: Loading a detached plugin as a dependency: C:\Jenkins\agent\workspace\gins_opentelemetry-plugin_PR-909\target\tmp\jenkins12068691405153736485\plugins\matrix-auth.jpi
4.191 [id=34] INFO hudson.PluginManager#considerDetachedPlugin: Loading a detached plugin as a dependency: C:\Jenkins\agent\workspace\gins_opentelemetry-plugin_PR-909\target\tmp\jenkins12068691405153736485\plugins\command-launcher.jpi
4.247 [id=34] INFO hudson.PluginManager#considerDetachedPlugin: Loading a detached plugin as a dependency: C:\Jenkins\agent\workspace\gins_opentelemetry-plugin_PR-909\target\tmp\jenkins12068691405153736485\plugins\jdk-tool.jpi
7.375 [id=36] INFO jenkins.InitReactorRunner$1#onAttained: Listed all plugins
7.395 [id=33] INFO j.b.api.BouncyCastlePlugin#start: C:\Jenkins\agent\workspace\gins_opentelemetry-plugin_PR-909\target\tmp\j h1941832754063125354\plugins\bouncycastle-api\WEB-INF\optional-lib not found; for non RealJenkinsRule this is fine and can be ignored.
19.538 [id=32] INFO jenkins.InitReactorRunner$1#onAttained: Prepared all plugins
19.561 [id=35] INFO jenkins.InitReactorRunner$1#onAttained: Started all plugins
19.566 [id=32] INFO jenkins.InitReactorRunner$1#onAttained: Augmented all extensions
19.927 [id=33] INFO i.j.p.o.a.ReconfigurableOpenTelemetry#init: OpenTelemetry initialized as NoOp
21.149 [id=33] INFO jenkins.InitReactorRunner$1#onAttained: System config loaded
21.250 [id=33] INFO jenkins.InitReactorRunner$1#onAttained: System config adapted
21.555 [id=33] INFO i.j.p.o.a.ReconfigurableOpenTelemetry#configure: OpenTelemetry initialized: SDK [config: otel.traces.exporter=testing, otel.metrics.exporter=testing, resource: service.name=jenkins, service.namespace=jenkins, service.version=2.440.3]
21.722 [id=33] INFO i.j.p.o.s.TraceContextServletFilter#afterConfiguration: Jenkins trace context propagation disabled on inbound HTTP requests (eg. build triggers). To enable it, set the property otel.instrumentation.jenkins.remote.span.enabled to true.
21.982 [id=35] INFO jenkins.InitReactorRunner$1#onAttained: Loaded all jobs
21.985 [id=35] INFO jenkins.InitReactorRunner$1#onAttained: Configuration for all jobs updated
22.004 [id=36] INFO jenkins.InitReactorRunner$1#onAttained: Completed initialization
22.585 [id=69] WARNING i.o.s.internal.ThrottlingLogger#doLog: Failed to export logs. Server responded with gRPC status code 2. Error message: Failed to connect to localhost/0:0:0:0:0:0:0:1:4317
23.587 [id=69] WARNING i.o.s.internal.ThrottlingLogger#doLog: Failed to export logs. Server responded with gRPC status code 2. Error message: Failed to connect to localhost/0:0:0:0:0:0:0:1:4317
23.977 [id=17] INFO i.j.p.o.BaseIntegrationTest#beforeClass: beforeClass()
23.977 [id=17] INFO i.j.p.o.BaseIntegrationTest#beforeClass: Wait for jenkins to start...
24.042 [id=17] INFO i.j.p.o.BaseIntegrationTest#beforeClass: Jenkins started
24.044 [id=17] INFO i.j.p.o.BaseIntegrationTest#beforeClass: Initialize OTel with configuration {otel.traces.exporter=testing, otel.imr.export.interval=10ms, otel.metrics.exporter=testing}
24.194 [id=69] WARNING i.o.s.internal.ThrottlingLogger#doLog: Failed to export logs. Server responded with gRPC status code 2. Error message: Failed to connect to localhost/0:0:0:0:0:0:0:1:4317
24.200 [id=17] INFO i.j.p.o.a.ReconfigurableOpenTelemetry#configure: OpenTelemetry initialized: SDK [config: otel.traces.exporter=testing, otel.metrics.exporter=testing, resource: service.name=jenkins, service.namespace=jenkins]
24.201 [id=17] INFO i.j.p.o.s.TraceContextServletFilter#afterConfiguration: Jenkins trace context propagation disabled on inbound HTTP requests (eg. build triggers). To enable it, set the property otel.instrumentation.jenkins.remote.span.enabled to true.
24.201 [id=17] INFO i.j.p.o.BaseIntegrationTest#beforeClass: OTel plugin initialized
24.245 [id=17] INFO o.jvnet.hudson.test.JenkinsRule#waitOnline: Launching slave0…
25.112 [id=87] WARNING i.o.s.internal.ThrottlingLogger#doLog: Failed to export logs. Server responded with gRPC status code 2. Error message: Failed to connect to localhost/0:0:0:0:0:0:0:1:4317
25.560 [id=75] INFO o.j.h.test.SimpleCommandLauncher#launch: agent launched for slave0
25.562 [id=17] INFO o.jvnet.hudson.test.JenkinsRule#waitOnline: …finished launching slave0.
25.842 [test-SpanContextPropagationSynchronousTestStep-1 #1] Started
26.116 [id=87] WARNING i.o.s.internal.ThrottlingLogger#doLog: Failed to export logs. Server responded with gRPC status code 2. Error message: Failed to connect to localhost/0:0:0:0:0:0:0:1:4317
26.314 [test-SpanContextPropagationSynchronousTestStep-1 #1] [Pipeline] Start of Pipeline
26.524 [test-SpanContextPropagationSynchronousTestStep-1 #1] [Pipeline] node
26.631 [id=94] INFO i.j.p.o.j.MonitoringPipelineListener#debug: test-SpanContextPropagationSynchronousTestStep-1 #1 - onAfterOtherBlockStepStartNode - node // Allocate node : Start, descriptor (class:org.jenkinsci.plugins.workflow.support.steps.ExecutorStep$DescriptorImpl, node), Console Output, Timing, class io.jenkins.plugins.opentelemetry.job.action.FlowNodeMonitoringAction, class io.jenkins.plugins.opentelemetry.job.action.FlowNodeMonitoringAction, class org.jenkinsci.plugins.workflow.support.steps.ExecutorStepExecution$QueueItemActionImpl, Workspace, node.parent: FlowStartNode[id=2, exec=CpsFlowExecution[Owner[test-SpanContextPropagationSynchronousTestStep-1/1:test-SpanContextPropagationSynchronousTestStep-1 #1]]], thread: Running CpsFlowExecution[Owner[test-SpanContextPropagationSynchronousTestStep-1/1:test-SpanContextPropagationSynchronousTestStep-1 #1]]
26.636 [test-SpanContextPropagationSynchronousTestStep-1 #1] Running on slave0 in C:\Jenkins\agent\workspace\gins_opentelemetry-plugin_PR-909\target\tmp\j h1941832754063125354\agent-work-dirs\slave0\workspace\test-SpanContextPropagationSynchronousTestStep-1
26.637 [test-SpanContextPropagationSynchronousTestStep-1 #1] [Pipeline] {
26.684 [id=94] INFO i.j.p.o.j.MonitoringPipelineListener#debug: test-SpanContextPropagationSynchronousTestStep-1 #1 - onAfterOtherBlockStepStartNode - { // Allocate node : Body : Start, descriptor (class:org.jenkinsci.plugins.workflow.support.steps.ExecutorStep$DescriptorImpl, node), class org.jenkinsci.plugins.workflow.actions.BodyInvocationAction, Timing, node.parent: StepStartNode[id=3, exec=CpsFlowExecution[Owner[test-SpanContextPropagationSynchronousTestStep-1/1:test-SpanContextPropagationSynchronousTestStep-1 #1]]], thread: Running CpsFlowExecution[Owner[test-SpanContextPropagationSynchronousTestStep-1/1:test-SpanContextPropagationSynchronousTestStep-1 #1]]
26.691 [id=94] WARNING i.j.p.o.j.j.GraphListenerAdapterToPipelineListener#fireOnAfterOtherBlockStepStartNode: Exception invoking `onAfterStepStartNode` on TracingPipelineListener{}
java.lang.IllegalStateException: scope to close not found: FlowNode[name: Allocate node : Body : Start, function: {, id: 4] in test-SpanContextPropagationSynchronousTestStep-1 #1
at io.jenkins.plugins.opentelemetry.job.OtelTraceService.lambda$closeCurrentScope$2(OtelTraceService.java:167)
at java.base/java.util.Optional.ifPresentOrElse(Optional.java:203)
at io.jenkins.plugins.opentelemetry.job.OtelTraceService.closeCurrentScope(OtelTraceService.java:164)
at io.jenkins.plugins.opentelemetry.job.MonitoringPipelineListener.onAfterOtherBlockStepStartNode(MonitoringPipelineListener.java:290)
at io.jenkins.plugins.opentelemetry.job.jenkins.GraphListenerAdapterToPipelineListener.fireOnAfterOtherBlockStepStartNode(GraphListenerAdapterToPipelineListener.java:144)
at io.jenkins.plugins.opentelemetry.job.jenkins.GraphListenerAdapterToPipelineListener.processPreviousNodes(GraphListenerAdapterToPipelineListener.java:71)
at io.jenkins.plugins.opentelemetry.job.jenkins.GraphListenerAdapterToPipelineListener.onNewHead(GraphListenerAdapterToPipelineListener.java:48)
at org.jenkinsci.plugins.workflow.cps.CpsFlowExecution.notifyListeners(CpsFlowExecution.java:1565)
at org.jenkinsci.plugins.workflow.cps.CpsThreadGroup.notifyNewHead(CpsThreadGroup.java:495)
at org.jenkinsci.plugins.workflow.cps.FlowHead.setNewHead(FlowHead.java:158)
at org.jenkinsci.plugins.workflow.cps.DSL.invokeStep(DSL.java:289)
at org.jenkinsci.plugins.workflow.cps.DSL.invokeMethod(DSL.java:196)
at org.jenkinsci.plugins.workflow.cps.CpsScript.invokeMethod(CpsScript.java:124)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at org.codehaus.groovy.reflection.CachedMethod.invoke(CachedMethod.java:98)
at groovy.lang.MetaMethod.doMethodInvoke(MetaMethod.java:325)
at groovy.lang.MetaClassImpl.invokeMethod(MetaClassImpl.java:1225)
at groovy.lang.MetaClassImpl.invokeMethod(MetaClassImpl.java:1034)
at org.codehaus.groovy.runtime.callsite.PogoMetaClassSite.call(PogoMetaClassSite.java:41)
at org.codehaus.groovy.runtime.callsite.CallSiteArray.defaultCall(CallSiteArray.java:47)
at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:116)
at org.kohsuke.groovy.sandbox.impl.Checker$1.call(Checker.java:180)
at org.kohsuke.groovy.sandbox.GroovyInterceptor.onMethodCall(GroovyInterceptor.java:23)
at org.jenkinsci.plugins.scriptsecurity.sandbox.groovy.SandboxInterceptor.onMethodCall(SandboxInterceptor.java:163)
at org.kohsuke.groovy.sandbox.impl.Checker$1.call(Checker.java:178)
at org.kohsuke.groovy.sandbox.impl.Checker.checkedCall(Checker.java:182)
at org.kohsuke.groovy.sandbox.impl.Checker.checkedCall(Checker.java:152)
at com.cloudbees.groovy.cps.sandbox.SandboxInvoker.methodCall(SandboxInvoker.java:17)
at org.jenkinsci.plugins.workflow.cps.LoggingInvoker.methodCall(LoggingInvoker.java:105)
at com.cloudbees.groovy.cps.impl.ContinuationGroup.methodCall(ContinuationGroup.java:90)
at com.cloudbees.groovy.cps.impl.FunctionCallBlock$ContinuationImpl.dispatchOrArg(FunctionCallBlock.java:116)
at com.cloudbees.groovy.cps.impl.FunctionCallBlock$ContinuationImpl.fixArg(FunctionCallBlock.java:85)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at com.cloudbees.groovy.cps.impl.ContinuationPtr$ContinuationImpl.receive(ContinuationPtr.java:72)
at com.cloudbees.groovy.cps.impl.ClosureBlock.eval(ClosureBlock.java:46)
at com.cloudbees.groovy.cps.Next.step(Next.java:83)
at com.cloudbees.groovy.cps.Continuable.run0(Continuable.java:147)
at org.jenkinsci.plugins.workflow.cps.SandboxContinuable.access$001(SandboxContinuable.java:17)
at org.jenkinsci.plugins.workflow.cps.SandboxContinuable.run0(SandboxContinuable.java:49)
at org.jenkinsci.plugins.workflow.cps.CpsThread.runNextChunk(CpsThread.java:180)
at org.jenkinsci.plugins.workflow.cps.CpsThreadGroup.run(CpsThreadGroup.java:423)
at org.jenkinsci.plugins.workflow.cps.CpsThreadGroup$2.call(CpsThreadGroup.java:331)
at org.jenkinsci.plugins.workflow.cps.CpsThreadGroup$2.call(CpsThreadGroup.java:295)
at org.jenkinsci.plugins.workflow.cps.CpsVmExecutorService.lambda$wrap$4(CpsVmExecutorService.java:136)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at hudson.remoting.SingleLaneExecutorService$1.run(SingleLaneExecutorService.java:139)
at jenkins.util.ContextResettingExecutorService$1.run(ContextResettingExecutorService.java:28)
at jenkins.security.ImpersonatingExecutorService$1.run(ImpersonatingExecutorService.java:68)
at jenkins.util.ErrorLoggingExecutorService.lambda$wrap$0(ErrorLoggingExecutorService.java:51)
at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
at org.jenkinsci.plugins.workflow.cps.CpsVmExecutorService$1.call(CpsVmExecutorService.java:53)
at org.jenkinsci.plugins.workflow.cps.CpsVmExecutorService$1.call(CpsVmExecutorService.java:50)
at org.codehaus.groovy.runtime.GroovyCategorySupport$ThreadCategoryInfo.use(GroovyCategorySupport.java:136)
at org.codehaus.groovy.runtime.GroovyCategorySupport.use(GroovyCategorySupport.java:275)
at org.jenkinsci.plugins.workflow.cps.CpsVmExecutorService.lambda$categoryThreadFactory$0(CpsVmExecutorService.java:50)
at java.base/java.lang.Thread.run(Thread.java:829)
26.695 [id=94] INFO i.j.p.o.j.MonitoringPipelineListener#debug: test-SpanContextPropagationSynchronousTestStep-1 #1 - onOnOtherBlockStepStartNode - stage // Stage : Start, descriptor (class:org.jenkinsci.plugins.workflow.support.steps.StageStep$DescriptorImpl, stage), Console Output, class org.jenkinsci.plugins.workflow.cps.actions.ArgumentsActionImpl, Timing, node.parent: StepStartNode[id=4, exec=CpsFlowExecution[Owner[test-SpanContextPropagationSynchronousTestStep-1/1:test-SpanContextPropagationSynchronousTestStep-1 #1]]], thread: Running CpsFlowExecution[Owner[test-SpanContextPropagationSynchronousTestStep-1/1:test-SpanContextPropagationSynchronousTestStep-1 #1]]
26.696 [test-SpanContextPropagationSynchronousTestStep-1 #1] [Pipeline] stage
26.701 [id=94] INFO i.j.p.o.j.MonitoringPipelineListener#debug: test-SpanContextPropagationSynchronousTestStep-1 #1 - onOnOtherBlockStepStartNode - stage // Stage : Start, descriptor (class:org.jenkinsci.plugins.workflow.support.steps.StageStep$DescriptorImpl, stage), Console Output, class org.jenkinsci.plugins.workflow.cps.actions.ArgumentsActionImpl, Timing, class io.jenkins.plugins.opentelemetry.job.action.FlowNodeMonitoringAction, node.parent: StepStartNode[id=4, exec=CpsFlowExecution[Owner[test-SpanContextPropagationSynchronousTestStep-1/1:test-SpanContextPropagationSynchronousTestStep-1 #1]]], thread: Running CpsFlowExecution[Owner[test-SpanContextPropagationSynchronousTestStep-1/1:test-SpanContextPropagationSynchronousTestStep-1 #1]]
26.741 [id=94] INFO i.j.p.o.j.MonitoringPipelineListener#debug: test-SpanContextPropagationSynchronousTestStep-1 #1 - onAfterOtherBlockStepStartNode - stage // Stage : Start, descriptor (class:org.jenkinsci.plugins.workflow.support.steps.StageStep$DescriptorImpl, stage), Console Output, class org.jenkinsci.plugins.workflow.cps.actions.ArgumentsActionImpl, Timing, class io.jenkins.plugins.opentelemetry.job.action.FlowNodeMonitoringAction, node.parent: StepStartNode[id=4, exec=CpsFlowExecution[Owner[test-SpanContextPropagationSynchronousTestStep-1/1:test-SpanContextPropagationSynchronousTestStep-1 #1]]], thread: Running CpsFlowExecution[Owner[test-SpanContextPropagationSynchronousTestStep-1/1:test-SpanContextPropagationSynchronousTestStep-1 #1]]
26.756 [test-SpanContextPropagationSynchronousTestStep-1 #1] [Pipeline] { (ze-stage1)
26.775 [id=95] INFO i.j.p.o.j.MonitoringPipelineListener#debug: test-SpanContextPropagationSynchronousTestStep-1 #1 - onAfterOtherBlockStepStartNode - { (ze-stage1) // ze-stage1, descriptor (class:org.jenkinsci.plugins.workflow.support.steps.StageStep$DescriptorImpl, stage), class org.jenkinsci.plugins.workflow.actions.BodyInvocationAction, ze-stage1, Timing, class io.jenkins.plugins.opentelemetry.job.action.FlowNodeMonitoringAction, node.parent: StepStartNode[id=5, exec=CpsFlowExecution[Owner[test-SpanContextPropagationSynchronousTestStep-1/1:test-SpanContextPropagationSynchronousTestStep-1 #1]]], thread: Running CpsFlowExecution[Owner[test-SpanContextPropagationSynchronousTestStep-1/1:test-SpanContextPropagationSynchronousTestStep-1 #1]]
26.797 [id=95] INFO i.j.p.o.j.MonitoringPipelineListener#debug: test-SpanContextPropagationSynchronousTestStep-1 #1 - onOtherBlockStepEndNode - } // Stage : Body : End, descriptor (class:org.jenkinsci.plugins.workflow.support.steps.StageStep$DescriptorImpl, stage), class org.jenkinsci.plugins.workflow.actions.BodyInvocationAction, Timing, node.parent: StepAtomNode[id=8, exec=CpsFlowExecution[Owner[test-SpanContextPropagationSynchronousTestStep-1/1:test-SpanContextPropagationSynchronousTestStep-1 #1]]], thread: Running CpsFlowExecution[Owner[test-SpanContextPropagationSynchronousTestStep-1/1:test-SpanContextPropagationSynchronousTestStep-1 #1]]
26.815 [test-SpanContextPropagationSynchronousTestStep-1 #1] [Pipeline] echo
26.815 [test-SpanContextPropagationSynchronousTestStep-1 #1] hello
26.815 [test-SpanContextPropagationSynchronousTestStep-1 #1] [Pipeline] spanContextPropagationSynchronousTestStep
26.816 [test-SpanContextPropagationSynchronousTestStep-1 #1] io.jenkins.plugins.opentelemetry.job.step.SpanContextPropagationSynchronousTestStep$StepExecution
26.816 [test-SpanContextPropagationSynchronousTestStep-1 #1] [Pipeline] }
26.823 [id=94] INFO i.j.p.o.j.MonitoringPipelineListener#debug: test-SpanContextPropagationSynchronousTestStep-1 #1 - onOtherBlockStepEndNode - // stage // Stage : End, descriptor (class:org.jenkinsci.plugins.workflow.support.steps.StageStep$DescriptorImpl, stage), Timing, node.parent: StepEndNode[id=9, exec=CpsFlowExecution[Owner[test-SpanContextPropagationSynchronousTestStep-1/1:test-SpanContextPropagationSynchronousTestStep-1 #1]]], thread: Running CpsFlowExecution[Owner[test-SpanContextPropagationSynchronousTestStep-1/1:test-SpanContextPropagationSynchronousTestStep-1 #1]]
26.830 [id=95] INFO i.j.p.o.j.MonitoringPipelineListener#debug: test-SpanContextPropagationSynchronousTestStep-1 #1 - onAfterOtherBlockStepEndNode - // stage // Stage : End, descriptor (class:org.jenkinsci.plugins.workflow.support.steps.StageStep$DescriptorImpl, stage), Timing, node.parent: StepEndNode[id=9, exec=CpsFlowExecution[Owner[test-SpanContextPropagationSynchronousTestStep-1/1:test-SpanContextPropagationSynchronousTestStep-1 #1]]], thread: Running CpsFlowExecution[Owner[test-SpanContextPropagationSynchronousTestStep-1/1:test-SpanContextPropagationSynchronousTestStep-1 #1]]
26.831 [id=95] INFO i.j.p.o.j.MonitoringPipelineListener#debug: test-SpanContextPropagationSynchronousTestStep-1 #1 - onOtherBlockStepEndNode - } // Allocate node : Body : End, descriptor (class:org.jenkinsci.plugins.workflow.support.steps.ExecutorStep$DescriptorImpl, node), class org.jenkinsci.plugins.workflow.actions.BodyInvocationAction, Timing, node.parent: StepEndNode[id=10, exec=CpsFlowExecution[Owner[test-SpanContextPropagationSynchronousTestStep-1/1:test-SpanContextPropagationSynchronousTestStep-1 #1]]], thread: Running CpsFlowExecution[Owner[test-SpanContextPropagationSynchronousTestStep-1/1:test-SpanContextPropagationSynchronousTestStep-1 #1]]
26.851 [id=95] INFO i.j.p.o.j.MonitoringPipelineListener#debug: test-SpanContextPropagationSynchronousTestStep-1 #1 - onAfterOtherBlockStepEndNode - } // Allocate node : Body : End, descriptor (class:org.jenkinsci.plugins.workflow.support.steps.ExecutorStep$DescriptorImpl, node), class org.jenkinsci.plugins.workflow.actions.BodyInvocationAction, Timing, node.parent: StepEndNode[id=10, exec=CpsFlowExecution[Owner[test-SpanContextPropagationSynchronousTestStep-1/1:test-SpanContextPropagationSynchronousTestStep-1 #1]]], thread: Running CpsFlowExecution[Owner[test-SpanContextPropagationSynchronousTestStep-1/1:test-SpanContextPropagationSynchronousTestStep-1 #1]]
26.851 [id=95] INFO i.j.p.o.j.MonitoringPipelineListener#debug: test-SpanContextPropagationSynchronousTestStep-1 #1 - onOtherBlockStepEndNode - // node // Allocate node : End, descriptor (class:org.jenkinsci.plugins.workflow.support.steps.ExecutorStep$DescriptorImpl, node), Timing, node.parent: StepEndNode[id=11, exec=CpsFlowExecution[Owner[test-SpanContextPropagationSynchronousTestStep-1/1:test-SpanContextPropagationSynchronousTestStep-1 #1]]], thread: Running CpsFlowExecution[Owner[test-SpanContextPropagationSynchronousTestStep-1/1:test-SpanContextPropagationSynchronousTestStep-1 #1]]
26.877 [test-SpanContextPropagationSynchronousTestStep-1 #1] [Pipeline] // stage
26.877 [test-SpanContextPropagationSynchronousTestStep-1 #1] [Pipeline] }
26.877 [test-SpanContextPropagationSynchronousTestStep-1 #1] [Pipeline] // node
26.877 [test-SpanContextPropagationSynchronousTestStep-1 #1] [Pipeline] End of Pipeline
27.004 [test-SpanContextPropagationSynchronousTestStep-1 #1] Finished: SUCCESS
Standard out
=== Starting io.jenkins.plugins.opentelemetry.JenkinsOtelPluginIntegrationTest
class io.jenkins.plugins.opentelemetry.job.step.SpanContextPropagationSynchronousTestStep -> []
class org.jenkinsci.plugins.workflow.steps.EchoStep -> []
class org.jenkinsci.plugins.workflow.steps.EchoStep$DescriptorImpl -> [org.jenkinsci.plugins.workflow.steps.EchoStep$DescriptorImpl@64f02e66]
$ "C:\tools\jdk-11/bin/java" -Djava.awt.headless=true -Xmx512m -XX:+PrintCommandLineFlags -jar "C:\Jenkins\agent\workspace\gins_opentelemetry-plugin_PR-909\target\jenkins-for-test\WEB-INF\lib\remoting-3206.vb_15dcf73f6a_9.jar"
-XX:G1ConcRefinementThreads=4 -XX:GCDrainStackTargetSize=64 -XX:InitialHeapSize=268417088 -XX:MaxHeapSize=536870912 -XX:+PrintCommandLineFlags -XX:ReservedCodeCacheSize=251658240 -XX:+SegmentedCodeCache -XX:+UseCompressedClassPointers -XX:+UseCompressedOops -XX:+UseG1GC -XX:-UseLargePagesIndividualAllocation
<===[JENKINS REMOTING CAPACITY]===>&#0;&#0;&#0;channel started
Remoting version: 3206.vb_15dcf73f6a_9
Launcher: SimpleCommandLauncher
Communication Protocol: Standard in/out
This is a Windows agent
## TREE VIEW OF SPANS ##
Stage: ze-stage1, function: stage, name: ze-stage1, node.id: 6
|
|- spanContextPropagationSynchronousTestStep, function: spanContextPropagationSynchronousTestStep, name: spanContextPropagationSynchronousTestStep, node.id: 8
|
|- SpanContextPropagationTestStep.execution
BUILD test-SpanContextPropagationSynchronousTestStep-1
|
|- Phase: Start
|
|- Phase: Run
| |
| |- Agent, function: node, name: agent, node.id: 3
| |
| |- Agent Allocation, function: node, name: agent.allocate, node.id: 3
|
|- Phase: Finalise
io.jenkins.plugins.opentelemetry.JenkinsOtelPluginIntegrationTest.testPipelineWithError
No value present
Stack trace
java.util.NoSuchElementException: No value present
at java.base/java.util.Optional.get(Optional.java:148)
at io.jenkins.plugins.opentelemetry.BaseIntegrationTest.checkChainOfSpans(BaseIntegrationTest.java:146)
at io.jenkins.plugins.opentelemetry.JenkinsOtelPluginIntegrationTest.testPipelineWithError(JenkinsOtelPluginIntegrationTest.java:292)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at org.jvnet.hudson.test.JenkinsRule$1.evaluate(JenkinsRule.java:655)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at java.base/java.lang.Thread.run(Thread.java:829)
Standard error
27.114 [id=17] INFO o.jvnet.hudson.test.JenkinsRule#waitOnline: Launching slave1…
27.121 [id=87] WARNING i.o.s.internal.ThrottlingLogger#doLog: Failed to export logs. Server responded with gRPC status code 2. Error message: Failed to connect to localhost/0:0:0:0:0:0:0:1:4317
28.054 [id=85] INFO o.j.h.test.SimpleCommandLauncher#launch: agent launched for slave1
28.056 [id=17] INFO o.jvnet.hudson.test.JenkinsRule#waitOnline: …finished launching slave1.
28.113 [test-pipeline-with-error-2 #1] Started
28.125 [id=87] WARNING i.o.s.internal.ThrottlingLogger#doLog: Failed to export logs. Server responded with gRPC status code 2. Error message: Failed to connect to localhost/0:0:0:0:0:0:0:1:4317
28.165 [test-pipeline-with-error-2 #1] [Pipeline] Start of Pipeline
28.166 [test-pipeline-with-error-2 #1] [Pipeline] node
28.183 [id=95] INFO i.j.p.o.j.MonitoringPipelineListener#debug: test-pipeline-with-error-2 #1 - onAfterOtherBlockStepStartNode - node // Allocate node : Start, descriptor (class:org.jenkinsci.plugins.workflow.support.steps.ExecutorStep$DescriptorImpl, node), Console Output, Timing, class io.jenkins.plugins.opentelemetry.job.action.FlowNodeMonitoringAction, class io.jenkins.plugins.opentelemetry.job.action.FlowNodeMonitoringAction, class org.jenkinsci.plugins.workflow.support.steps.ExecutorStepExecution$QueueItemActionImpl, Workspace, node.parent: FlowStartNode[id=2, exec=CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]], thread: Running CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]
28.206 [id=95] INFO i.j.p.o.j.MonitoringPipelineListener#debug: test-pipeline-with-error-2 #1 - onAfterOtherBlockStepStartNode - { // Allocate node : Body : Start, descriptor (class:org.jenkinsci.plugins.workflow.support.steps.ExecutorStep$DescriptorImpl, node), class org.jenkinsci.plugins.workflow.actions.BodyInvocationAction, Timing, node.parent: StepStartNode[id=3, exec=CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]], thread: Running CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]
28.207 [id=95] WARNING i.j.p.o.j.j.GraphListenerAdapterToPipelineListener#fireOnAfterOtherBlockStepStartNode: Exception invoking `onAfterStepStartNode` on TracingPipelineListener{}
java.lang.IllegalStateException: scope to close not found: FlowNode[name: Allocate node : Body : Start, function: {, id: 4] in test-pipeline-with-error-2 #1
at io.jenkins.plugins.opentelemetry.job.OtelTraceService.lambda$closeCurrentScope$2(OtelTraceService.java:167)
at java.base/java.util.Optional.ifPresentOrElse(Optional.java:203)
at io.jenkins.plugins.opentelemetry.job.OtelTraceService.closeCurrentScope(OtelTraceService.java:164)
at io.jenkins.plugins.opentelemetry.job.MonitoringPipelineListener.onAfterOtherBlockStepStartNode(MonitoringPipelineListener.java:290)
at io.jenkins.plugins.opentelemetry.job.jenkins.GraphListenerAdapterToPipelineListener.fireOnAfterOtherBlockStepStartNode(GraphListenerAdapterToPipelineListener.java:144)
at io.jenkins.plugins.opentelemetry.job.jenkins.GraphListenerAdapterToPipelineListener.processPreviousNodes(GraphListenerAdapterToPipelineListener.java:71)
at io.jenkins.plugins.opentelemetry.job.jenkins.GraphListenerAdapterToPipelineListener.onNewHead(GraphListenerAdapterToPipelineListener.java:48)
at org.jenkinsci.plugins.workflow.cps.CpsFlowExecution.notifyListeners(CpsFlowExecution.java:1565)
at org.jenkinsci.plugins.workflow.cps.CpsThreadGroup.notifyNewHead(CpsThreadGroup.java:495)
at org.jenkinsci.plugins.workflow.cps.FlowHead.setNewHead(FlowHead.java:158)
at org.jenkinsci.plugins.workflow.cps.DSL.invokeStep(DSL.java:289)
at org.jenkinsci.plugins.workflow.cps.DSL.invokeMethod(DSL.java:196)
at org.jenkinsci.plugins.workflow.cps.CpsScript.invokeMethod(CpsScript.java:124)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at org.codehaus.groovy.reflection.CachedMethod.invoke(CachedMethod.java:98)
at groovy.lang.MetaMethod.doMethodInvoke(MetaMethod.java:325)
at groovy.lang.MetaClassImpl.invokeMethod(MetaClassImpl.java:1225)
at groovy.lang.MetaClassImpl.invokeMethod(MetaClassImpl.java:1034)
at org.codehaus.groovy.runtime.callsite.PogoMetaClassSite.call(PogoMetaClassSite.java:41)
at org.codehaus.groovy.runtime.callsite.CallSiteArray.defaultCall(CallSiteArray.java:47)
at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:116)
at org.kohsuke.groovy.sandbox.impl.Checker$1.call(Checker.java:180)
at org.kohsuke.groovy.sandbox.GroovyInterceptor.onMethodCall(GroovyInterceptor.java:23)
at org.jenkinsci.plugins.scriptsecurity.sandbox.groovy.SandboxInterceptor.onMethodCall(SandboxInterceptor.java:163)
at org.kohsuke.groovy.sandbox.impl.Checker$1.call(Checker.java:178)
at org.kohsuke.groovy.sandbox.impl.Checker.checkedCall(Checker.java:182)
at org.kohsuke.groovy.sandbox.impl.Checker.checkedCall(Checker.java:152)
at com.cloudbees.groovy.cps.sandbox.SandboxInvoker.methodCall(SandboxInvoker.java:17)
at org.jenkinsci.plugins.workflow.cps.LoggingInvoker.methodCall(LoggingInvoker.java:105)
at com.cloudbees.groovy.cps.impl.ContinuationGroup.methodCall(ContinuationGroup.java:90)
at com.cloudbees.groovy.cps.impl.FunctionCallBlock$ContinuationImpl.dispatchOrArg(FunctionCallBlock.java:116)
at com.cloudbees.groovy.cps.impl.FunctionCallBlock$ContinuationImpl.fixArg(FunctionCallBlock.java:85)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at com.cloudbees.groovy.cps.impl.ContinuationPtr$ContinuationImpl.receive(ContinuationPtr.java:72)
at com.cloudbees.groovy.cps.impl.ClosureBlock.eval(ClosureBlock.java:46)
at com.cloudbees.groovy.cps.Next.step(Next.java:83)
at com.cloudbees.groovy.cps.Continuable.run0(Continuable.java:147)
at org.jenkinsci.plugins.workflow.cps.SandboxContinuable.access$001(SandboxContinuable.java:17)
at org.jenkinsci.plugins.workflow.cps.SandboxContinuable.run0(SandboxContinuable.java:49)
at org.jenkinsci.plugins.workflow.cps.CpsThread.runNextChunk(CpsThread.java:180)
at org.jenkinsci.plugins.workflow.cps.CpsThreadGroup.run(CpsThreadGroup.java:423)
at org.jenkinsci.plugins.workflow.cps.CpsThreadGroup$2.call(CpsThreadGroup.java:331)
at org.jenkinsci.plugins.workflow.cps.CpsThreadGroup$2.call(CpsThreadGroup.java:295)
at org.jenkinsci.plugins.workflow.cps.CpsVmExecutorService.lambda$wrap$4(CpsVmExecutorService.java:136)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at hudson.remoting.SingleLaneExecutorService$1.run(SingleLaneExecutorService.java:139)
at jenkins.util.ContextResettingExecutorService$1.run(ContextResettingExecutorService.java:28)
at jenkins.security.ImpersonatingExecutorService$1.run(ImpersonatingExecutorService.java:68)
at jenkins.util.ErrorLoggingExecutorService.lambda$wrap$0(ErrorLoggingExecutorService.java:51)
at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
at org.jenkinsci.plugins.workflow.cps.CpsVmExecutorService$1.call(CpsVmExecutorService.java:53)
at org.jenkinsci.plugins.workflow.cps.CpsVmExecutorService$1.call(CpsVmExecutorService.java:50)
at org.codehaus.groovy.runtime.GroovyCategorySupport$ThreadCategoryInfo.use(GroovyCategorySupport.java:136)
at org.codehaus.groovy.runtime.GroovyCategorySupport.use(GroovyCategorySupport.java:275)
at org.jenkinsci.plugins.workflow.cps.CpsVmExecutorService.lambda$categoryThreadFactory$0(CpsVmExecutorService.java:50)
at java.base/java.lang.Thread.run(Thread.java:829)
28.209 [id=95] INFO i.j.p.o.j.MonitoringPipelineListener#debug: test-pipeline-with-error-2 #1 - onOnOtherBlockStepStartNode - stage // Stage : Start, descriptor (class:org.jenkinsci.plugins.workflow.support.steps.StageStep$DescriptorImpl, stage), Console Output, class org.jenkinsci.plugins.workflow.cps.actions.ArgumentsActionImpl, Timing, node.parent: StepStartNode[id=4, exec=CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]], thread: Running CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]
28.213 [id=95] INFO i.j.p.o.j.MonitoringPipelineListener#debug: test-pipeline-with-error-2 #1 - onOnOtherBlockStepStartNode - stage // Stage : Start, descriptor (class:org.jenkinsci.plugins.workflow.support.steps.StageStep$DescriptorImpl, stage), Console Output, class org.jenkinsci.plugins.workflow.cps.actions.ArgumentsActionImpl, Timing, class io.jenkins.plugins.opentelemetry.job.action.FlowNodeMonitoringAction, node.parent: StepStartNode[id=4, exec=CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]], thread: Running CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]
28.225 [test-pipeline-with-error-2 #1] Running on Jenkins in C:\Jenkins\agent\workspace\gins_opentelemetry-plugin_PR-909\target\tmp\j h1941832754063125354\workspace\test-pipeline-with-error-2
28.225 [test-pipeline-with-error-2 #1] [Pipeline] {
28.225 [test-pipeline-with-error-2 #1] [Pipeline] stage
28.244 [id=95] INFO i.j.p.o.j.MonitoringPipelineListener#debug: test-pipeline-with-error-2 #1 - onAfterOtherBlockStepStartNode - stage // Stage : Start, descriptor (class:org.jenkinsci.plugins.workflow.support.steps.StageStep$DescriptorImpl, stage), Console Output, class org.jenkinsci.plugins.workflow.cps.actions.ArgumentsActionImpl, Timing, class io.jenkins.plugins.opentelemetry.job.action.FlowNodeMonitoringAction, node.parent: StepStartNode[id=4, exec=CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]], thread: Running CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]
28.264 [id=95] INFO i.j.p.o.j.MonitoringPipelineListener#debug: test-pipeline-with-error-2 #1 - onAfterOtherBlockStepStartNode - { (ze-stage1) // ze-stage1, descriptor (class:org.jenkinsci.plugins.workflow.support.steps.StageStep$DescriptorImpl, stage), class org.jenkinsci.plugins.workflow.actions.BodyInvocationAction, ze-stage1, Timing, class io.jenkins.plugins.opentelemetry.job.action.FlowNodeMonitoringAction, node.parent: StepStartNode[id=5, exec=CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]], thread: Running CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]
28.289 [test-pipeline-with-error-2 #1] [Pipeline] { (ze-stage1)
28.289 [test-pipeline-with-error-2 #1] [Pipeline] isUnix
28.350 [test-pipeline-with-error-2 #1] [Pipeline] bat
28.435 [id=95] INFO i.j.p.o.j.MonitoringPipelineListener#debug: test-pipeline-with-error-2 #1 - onOtherBlockStepEndNode - } // Stage : Body : End, descriptor (class:org.jenkinsci.plugins.workflow.support.steps.StageStep$DescriptorImpl, stage), class org.jenkinsci.plugins.workflow.actions.BodyInvocationAction, Timing, node.parent: StepAtomNode[id=8, exec=CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]], thread: Running CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]
28.450 [id=94] INFO i.j.p.o.j.MonitoringPipelineListener#debug: test-pipeline-with-error-2 #1 - onOtherBlockStepEndNode - // stage // Stage : End, descriptor (class:org.jenkinsci.plugins.workflow.support.steps.StageStep$DescriptorImpl, stage), Timing, node.parent: StepEndNode[id=9, exec=CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]], thread: Running CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]
28.462 [test-pipeline-with-error-2 #1]
28.462 [test-pipeline-with-error-2 #1] C:\Jenkins\agent\workspace\gins_opentelemetry-plugin_PR-909\target\tmp\j h1941832754063125354\workspace\test-pipeline-with-error-2>echo ze-echo-1
28.462 [test-pipeline-with-error-2 #1] ze-echo-1
28.462 [test-pipeline-with-error-2 #1] [Pipeline] }
28.462 [test-pipeline-with-error-2 #1] [Pipeline] // stage
28.465 [id=95] INFO i.j.p.o.j.MonitoringPipelineListener#debug: test-pipeline-with-error-2 #1 - onAfterOtherBlockStepEndNode - // stage // Stage : End, descriptor (class:org.jenkinsci.plugins.workflow.support.steps.StageStep$DescriptorImpl, stage), Timing, node.parent: StepEndNode[id=9, exec=CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]], thread: Running CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]
28.465 [id=95] INFO i.j.p.o.j.MonitoringPipelineListener#debug: test-pipeline-with-error-2 #1 - onOnOtherBlockStepStartNode - stage // Stage : Start, descriptor (class:org.jenkinsci.plugins.workflow.support.steps.StageStep$DescriptorImpl, stage), Console Output, class org.jenkinsci.plugins.workflow.cps.actions.ArgumentsActionImpl, Timing, node.parent: StepEndNode[id=10, exec=CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]], thread: Running CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]
28.473 [id=95] INFO i.j.p.o.j.MonitoringPipelineListener#debug: test-pipeline-with-error-2 #1 - onOnOtherBlockStepStartNode - stage // Stage : Start, descriptor (class:org.jenkinsci.plugins.workflow.support.steps.StageStep$DescriptorImpl, stage), Console Output, class org.jenkinsci.plugins.workflow.cps.actions.ArgumentsActionImpl, Timing, class io.jenkins.plugins.opentelemetry.job.action.FlowNodeMonitoringAction, node.parent: StepEndNode[id=10, exec=CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]], thread: Running CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]
28.478 [id=95] INFO i.j.p.o.j.MonitoringPipelineListener#debug: test-pipeline-with-error-2 #1 - onAfterOtherBlockStepStartNode - stage // Stage : Start, descriptor (class:org.jenkinsci.plugins.workflow.support.steps.StageStep$DescriptorImpl, stage), Console Output, class org.jenkinsci.plugins.workflow.cps.actions.ArgumentsActionImpl, Timing, class io.jenkins.plugins.opentelemetry.job.action.FlowNodeMonitoringAction, node.parent: StepEndNode[id=10, exec=CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]], thread: Running CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]
28.499 [id=95] INFO i.j.p.o.j.MonitoringPipelineListener#debug: test-pipeline-with-error-2 #1 - onAfterOtherBlockStepStartNode - { (ze-stage2) // ze-stage2, descriptor (class:org.jenkinsci.plugins.workflow.support.steps.StageStep$DescriptorImpl, stage), class org.jenkinsci.plugins.workflow.actions.BodyInvocationAction, ze-stage2, Timing, class io.jenkins.plugins.opentelemetry.job.action.FlowNodeMonitoringAction, node.parent: StepStartNode[id=11, exec=CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]], thread: Running CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]
28.519 [test-pipeline-with-error-2 #1] [Pipeline] stage
28.519 [test-pipeline-with-error-2 #1] [Pipeline] { (ze-stage2)
28.519 [test-pipeline-with-error-2 #1] [Pipeline] isUnix
28.519 [test-pipeline-with-error-2 #1] [Pipeline] bat
28.659 [id=95] INFO i.j.p.o.j.MonitoringPipelineListener#debug: test-pipeline-with-error-2 #1 - onOtherBlockStepEndNode - } // Stage : Body : End, descriptor (class:org.jenkinsci.plugins.workflow.support.steps.StageStep$DescriptorImpl, stage), class org.jenkinsci.plugins.workflow.actions.BodyInvocationAction, Timing, node.parent: StepAtomNode[id=15, exec=CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]], thread: Running CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]
28.676 [test-pipeline-with-error-2 #1]
28.676 [test-pipeline-with-error-2 #1] C:\Jenkins\agent\workspace\gins_opentelemetry-plugin_PR-909\target\tmp\j h1941832754063125354\workspace\test-pipeline-with-error-2>echo ze-echo-2
28.676 [test-pipeline-with-error-2 #1] ze-echo-2
28.676 [test-pipeline-with-error-2 #1] [Pipeline] error
28.676 [test-pipeline-with-error-2 #1] [Pipeline] }
28.678 [id=95] INFO i.j.p.o.j.MonitoringPipelineListener#debug: test-pipeline-with-error-2 #1 - onOtherBlockStepEndNode - // stage // Stage : End, descriptor (class:org.jenkinsci.plugins.workflow.support.steps.StageStep$DescriptorImpl, stage), Timing, node.parent: StepEndNode[id=16, exec=CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]], thread: Running CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]
28.688 [id=94] INFO i.j.p.o.j.MonitoringPipelineListener#debug: test-pipeline-with-error-2 #1 - onAfterOtherBlockStepEndNode - // stage // Stage : End, descriptor (class:org.jenkinsci.plugins.workflow.support.steps.StageStep$DescriptorImpl, stage), Timing, ze-pipeline-error, node.parent: StepEndNode[id=16, exec=CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]], thread: Running CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]
28.688 [id=94] INFO i.j.p.o.j.MonitoringPipelineListener#debug: test-pipeline-with-error-2 #1 - onOtherBlockStepEndNode - } // Allocate node : Body : End, descriptor (class:org.jenkinsci.plugins.workflow.support.steps.ExecutorStep$DescriptorImpl, node), class org.jenkinsci.plugins.workflow.actions.BodyInvocationAction, Timing, node.parent: StepEndNode[id=17, exec=CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]], thread: Running CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]
28.711 [id=94] INFO i.j.p.o.j.MonitoringPipelineListener#debug: test-pipeline-with-error-2 #1 - onAfterOtherBlockStepEndNode - } // Allocate node : Body : End, descriptor (class:org.jenkinsci.plugins.workflow.support.steps.ExecutorStep$DescriptorImpl, node), class org.jenkinsci.plugins.workflow.actions.BodyInvocationAction, Timing, ze-pipeline-error, node.parent: StepEndNode[id=17, exec=CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]], thread: Running CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]
28.712 [id=94] INFO i.j.p.o.j.MonitoringPipelineListener#debug: test-pipeline-with-error-2 #1 - onOtherBlockStepEndNode - // node // Allocate node : End, descriptor (class:org.jenkinsci.plugins.workflow.support.steps.ExecutorStep$DescriptorImpl, node), Timing, node.parent: StepEndNode[id=18, exec=CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]], thread: Running CpsFlowExecution[Owner[test-pipeline-with-error-2/1:test-pipeline-with-error-2 #1]]
28.733 [test-pipeline-with-error-2 #1] [Pipeline] // stage
28.734 [test-pipeline-with-error-2 #1] [Pipeline] }
28.734 [test-pipeline-with-error-2 #1] [Pipeline] // node
28.778 [test-pipeline-with-error-2 #1] [Pipeline] End of Pipeline
28.779 [test-pipeline-with-error-2 #1] ERROR: ze-pipeline-error
28.779 [test-pipeline-with-error-2 #1] Finished: FAILURE
Standard out
$ "C:\tools\jdk-11/bin/java" -Djava.awt.headless=true -Xmx512m -XX:+PrintCommandLineFlags -jar "C:\Jenkins\agent\workspace\gins_opentelemetry-plugin_PR-909\target\jenkins-for-test\WEB-INF\lib\remoting-3206.vb_15dcf73f6a_9.jar"
-XX:G1ConcRefinementThreads=4 -XX:GCDrainStackTargetSize=64 -XX:InitialHeapSize=268417088 -XX:MaxHeapSize=536870912 -XX:+PrintCommandLineFlags -XX:ReservedCodeCacheSize=251658240 -XX:+SegmentedCodeCache -XX:+UseCompressedClassPointers -XX:+UseCompressedOops -XX:+UseG1GC -XX:-UseLargePagesIndividualAllocation
<===[JENKINS REMOTING CAPACITY]===>&#0;&#0;&#0;channel started
Remoting version: 3206.vb_15dcf73f6a_9
Launcher: SimpleCommandLauncher
Communication Protocol: Standard in/out
This is a Windows agent
## TREE VIEW OF SPANS ##
Stage: ze-stage1, function: stage, name: ze-stage1, node.id: 6
|
|- shell-1, function: bat, name: Windows Batch Script, node.id: 8
Stage: ze-stage2, function: stage, name: ze-stage2, node.id: 12
|
|- shell-2, function: bat, name: Windows Batch Script, node.id: 14
|
|- error, function: error, name: Error signal, node.id: 15
BUILD test-pipeline-with-error-2
|
|- Phase: Start
|
|- Phase: Run
| |
| |- Agent, function: node, name: agent, node.id: 3
| |
| |- Agent Allocation, function: node, name: agent.allocate, node.id: 3
|
|- Phase: Finalise
more test results are not shown here, view them on Jenkins
Loading