TestProject Forum

OpenShift: Failed connecting to Agent socket

Hi!
I deployed TP agent to my corporate OpenShift namespace, it’s up and running!
But when i run tests from Jenkins, get connection error!
Everything seems to work in my local docker environment thought but not in OpenShift!
I followed Docker Hub

Log from the Jenkins job console output:

[ERROR] setup(VisaSidanTest) Time elapsed: 9.082 s <<< FAILURE!
io.testproject.sdk.internal.exceptions.AgentConnectException: Failed connecting to Agent socket
Caused by: java.net.ConnectException: Connection refused (Connection refused)

Log from Agent running in OpenShift:

2021-10-26 12:56:13.070 [ERROR] i.t.a.m.b.a Failed to receive development socket connection in timely manner (30s)
java.net.SocketException: Socket closed
at java.base/sun.nio.ch.NioSocketImpl.endAccept(NioSocketImpl.java:689)
at java.base/sun.nio.ch.NioSocketImpl.accept(NioSocketImpl.java:762)
at java.base/java.net.ServerSocket.implAccept(ServerSocket.java:649)
at java.base/java.net.ServerSocket.platformImplAccept(ServerSocket.java:615)
at java.base/java.net.ServerSocket.implAccept(ServerSocket.java:591)
at java.base/java.net.ServerSocket.implAccept(ServerSocket.java:548)
at java.base/java.net.ServerSocket.accept(ServerSocket.java:505)
at io.testproject.agent.managers.b.a.h(TestProjectAgent:703)
at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at io.testproject.agent.c.d.run(TestProjectAgent:33)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
at java.base/java.lang.Thread.run(Thread.java:830)

Hi @adas.wh,
Can you share what your docker-compose looks like?
It’s hard to determine the issue only by these snippets,
Can you share your full logs to our support team over the live chat? simply follow the flow to report a problem.

Hi

I used the docker-compose in ’ TestProject SDK and Docker Agent’ and it works fine!
https://hub.docker.com/r/testproject/agent

But I couldn’t make it work after deployed to OpenShift using ’ TestProject Agent on Kubernetes’ in the same link above.

Full log:

2021-10-28 17:28:13.344 [INFO ] i.t.a.b.b.c                              Token Development is valid
2021-10-28 17:28:13.366 [INFO ] i.t.a.w.r.e.DevelopmentEndpoints         Received request to start development mode
2021-10-28 17:28:13.445 [INFO ] i.t.a.f.l.a.a                            Handling request to start independent development session.
2021-10-28 17:28:13.452 [INFO ] i.t.a.m.q                                Updating Edge driver to match browser version 93.0.961.37
2021-10-28 17:28:13.452 [INFO ] i.t.a.m.q                                Browser Edge browser (93.0.961.37) is external, skipping driver update
2021-10-28 17:28:13.460 [INFO ] i.t.a.m.d.e                              Initializing driver Edge...
2021-10-28 17:28:19.306 [INFO ] i.t.a.m.d.e                              Edge Driver initialized.
2021-10-28 17:28:19.587 [INFO ] i.t.a.b.a.d                              POST https://api.testproject.io/executions/results/local-execution  - 200 (KZFHUDk2): No Message
2021-10-28 17:28:19.590 [INFO ] i.t.a.b.b.c                              Reporting execution state 'Executing' for package B_Y_mPBapUCK9ganKEA0fA 
2021-10-28 17:28:19.646 [INFO ] i.t.a.m.b.a                              Environment variable TP_SDK_PORT is defined: 8686
2021-10-28 17:28:19.647 [INFO ] i.t.a.m.b.a                              Using SDK port: 8686
2021-10-28 17:28:19.647 [INFO ] i.t.a.m.b.a                              Awaiting development socket connection on port: 8686
2021-10-28 17:28:19.651 [INFO ] i.t.a.b.b.c                              Reporting state: Dev
2021-10-28 17:28:19.731 [INFO ] i.t.a.b.a.d                              PUT https://api.testproject.io/api/v1/executions/B_Y_mPBapUCK9ganKEA0fA/state  - 200 (HTInBq6d): State was updated
2021-10-28 17:28:19.744 [INFO ] i.t.a.b.a.d                              POST https://api.testproject.io/executions/progress/projects/oDmyhIA09E6N6KGxdcmPIQ/executions/B_Y_mPBapUCK9ganKEA0fA/progress  - 200 (aaaaaaaa): No Message
2021-10-28 17:28:22.025 [INFO ] i.t.a.m.A                                Reported Agent state and got response - [Agent Version: 3.3.0]
2021-10-28 17:28:23.514 [INFO ] i.t.a.w.r.e.DevelopmentEndpoints         Received request to start development mode
2021-10-28 17:28:23.514 [WARN ] i.t.a.w.r.e.DevelopmentEndpoints         Agent is already in Development state
2021-10-28 17:28:23.514 [INFO ] i.t.a.w.r.e.DevelopmentEndpoints         Resetting development state...
2021-10-28 17:28:23.597 [ERROR] i.t.a.m.b.a                              Failed to receive development socket connection in timely manner (30s)
java.net.SocketException: Socket closed
	at java.base/sun.nio.ch.NioSocketImpl.endAccept(NioSocketImpl.java:689)
	at java.base/sun.nio.ch.NioSocketImpl.accept(NioSocketImpl.java:762)
	at java.base/java.net.ServerSocket.implAccept(ServerSocket.java:649)
	at java.base/java.net.ServerSocket.platformImplAccept(ServerSocket.java:615)
	at java.base/java.net.ServerSocket.implAccept(ServerSocket.java:591)
	at java.base/java.net.ServerSocket.implAccept(ServerSocket.java:548)
	at java.base/java.net.ServerSocket.accept(ServerSocket.java:505)
	at io.testproject.agent.managers.b.a.h(TestProjectAgent:703)
	at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
	at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
	at io.testproject.agent.c.d.run(TestProjectAgent:33)
	at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
	at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
	at java.base/java.lang.Thread.run(Thread.java:830)
2021-10-28 17:28:23.597 [INFO ] i.t.a.m.b.a                              Development socket connected on port: 8686
2021-10-28 17:28:23.599 [INFO ] i.t.a.m.d.p                              There are 1 execution reports pending to be uploaded
2021-10-28 17:28:23.600 [INFO ] i.t.a.m.b.a                              Development socket disconnected
2021-10-28 17:28:23.600 [WARN ] i.t.a.f.l.b                              Can't process 'Reset' when in 'Ready' state
2021-10-28 17:28:23.600 [INFO ] i.t.a.m.b.a                              Development socket on port 8686 disconnected
2021-10-28 17:28:23.600 [WARN ] i.t.a.f.l.b                              Can't process 'Done' when in 'Ready' state
2021-10-28 17:28:23.601 [INFO ] i.t.a.b.b.c                              Reporting state: Idle
2021-10-28 17:28:23.603 [INFO ] i.t.a.m.d.p                              Initiating upload for execution report B_Y_mPBapUCK9ganKEA0fA
2021-10-28 17:28:23.604 [INFO ] i.t.a.m.d.p                              Finished reporting task
2021-10-28 17:28:23.608 [INFO ] i.t.a.m.d.p                              FYI - Report for execution B_Y_mPBapUCK9ganKEA0fA has not been finalized
2021-10-28 17:28:23.608 [ERROR] i.t.a.m.d.p                              Report B_Y_mPBapUCK9ganKEA0fA uploading windows time span has expired.
2021-10-28 17:28:23.616 [INFO ] i.t.a.f.l.a.a                            Handling request to start independent development session.
2021-10-28 17:28:23.616 [INFO ] i.t.a.m.q                                Updating Edge driver to match browser version 93.0.961.37
2021-10-28 17:28:23.616 [INFO ] i.t.a.m.q                                Browser Edge browser (93.0.961.37) is external, skipping driver update
2021-10-28 17:28:23.616 [INFO ] i.t.a.m.d.e                              Initializing driver Edge...
2021-10-28 17:28:23.705 [ERROR] i.t.a.b.a.d                              POST https://api.testproject.io/executions/results/projects/oDmyhIA09E6N6KGxdcmPIQ/executions/0cGvT42VQ0mY7erTdAl24w/missing  - 400 (ZVLTmY8O): Could not find execution 0cGvT42VQ0mY7erTdAl24w
2021-10-28 17:28:23.705 [ERROR] i.t.a.b.a.c                              POST https://api.testproject.io/executions/results/projects/oDmyhIA09E6N6KGxdcmPIQ/executions/0cGvT42VQ0mY7erTdAl24w/missing  - 400 (ZVLTmY8O): Could not find execution 0cGvT42VQ0mY7erTdAl24w
2021-10-28 17:28:23.705 [WARN ] i.t.a.b.b.j                              Failed to report execution as missing (400)
2021-10-28 17:28:23.705 [INFO ] i.t.a.m.d.p                              Deleting execution folder B_Y_mPBapUCK9ganKEA0fA
2021-10-28 17:28:25.731 [INFO ] i.t.a.m.A                                Reported Agent state and got response - [Agent Version: 3.3.0]
2021-10-28 17:28:29.170 [INFO ] i.t.a.m.d.e                              Edge Driver initialized.
2021-10-28 17:28:29.355 [INFO ] i.t.a.b.a.d                              POST https://api.testproject.io/executions/results/local-execution  - 200 (SGq8CQIy): No Message
2021-10-28 17:28:29.355 [INFO ] i.t.a.b.b.c                              Reporting execution state 'Executing' for package 9pw7UqCG5UKKnppAgUjeCw 
2021-10-28 17:28:29.362 [INFO ] i.t.a.m.b.a                              Environment variable TP_SDK_PORT is defined: 8686
2021-10-28 17:28:29.362 [INFO ] i.t.a.m.b.a                              Using SDK port: 8686
2021-10-28 17:28:29.363 [INFO ] i.t.a.b.b.c                              Reporting state: Dev
2021-10-28 17:28:29.363 [INFO ] i.t.a.m.b.a                              Awaiting development socket connection on port: 8686
2021-10-28 17:28:29.470 [INFO ] i.t.a.b.a.d                              PUT https://api.testproject.io/api/v1/executions/9pw7UqCG5UKKnppAgUjeCw/state  - 200 (teuOUHO9): State was updated
2021-10-28 17:28:29.481 [INFO ] i.t.a.b.a.d                              POST https://api.testproject.io/executions/progress/projects/oDmyhIA09E6N6KGxdcmPIQ/executions/9pw7UqCG5UKKnppAgUjeCw/progress  - 200 (aaaaaaaa): No Message
2021-10-28 17:28:29.923 [INFO ] i.t.a.b.a.d                              POST https://api.testproject.io/executions/progress/projects/oDmyhIA09E6N6KGxdcmPIQ/executions/B_Y_mPBapUCK9ganKEA0fA/progress  - 200 (aaaaaaaa): No Message
2021-10-28 17:28:31.475 [INFO ] i.t.a.m.A                                Reported Agent state and got response - [Agent Version: 3.3.0]
2021-10-28 17:28:32.059 [INFO ] i.t.a.b.a.d                              GET https://api.testproject.io/api/v1/agent/tokens?type=Development&token=LyJXZbh0WlSHllpstmHsmWui52RUElonE4KzRE7y-ns1  - 200 (iMxYAw6j): No Message
2021-10-28 17:28:32.059 [INFO ] i.t.a.b.b.c                              Token Development is valid
2021-10-28 17:28:32.060 [INFO ] i.t.a.w.r.e.DevelopmentEndpoints         Received request to start development mode
2021-10-28 17:28:32.061 [WARN ] i.t.a.w.r.e.DevelopmentEndpoints         Agent is already in Development state
2021-10-28 17:28:32.061 [INFO ] i.t.a.w.r.e.DevelopmentEndpoints         Resetting development state...
2021-10-28 17:28:32.358 [INFO ] i.t.a.b.b.c                              Reporting state: Idle
2021-10-28 17:28:32.358 [INFO ] i.t.a.m.d.p                              There are 2 execution reports pending to be uploaded
2021-10-28 17:28:32.358 [INFO ] i.t.a.m.d.p                              Initiating upload for execution report 9pw7UqCG5UKKnppAgUjeCw
2021-10-28 17:28:32.358 [ERROR] i.t.a.m.b.a                              Failed to receive development socket connection in timely manner (30s)
java.net.SocketException: Socket closed
	at java.base/sun.nio.ch.NioSocketImpl.endAccept(NioSocketImpl.java:689)
	at java.base/sun.nio.ch.NioSocketImpl.accept(NioSocketImpl.java:762)
	at java.base/java.net.ServerSocket.implAccept(ServerSocket.java:649)
	at java.base/java.net.ServerSocket.platformImplAccept(ServerSocket.java:615)
	at java.base/java.net.ServerSocket.implAccept(ServerSocket.java:591)
	at java.base/java.net.ServerSocket.implAccept(ServerSocket.java:548)
	at java.base/java.net.ServerSocket.accept(ServerSocket.java:505)
	at io.testproject.agent.managers.b.a.h(TestProjectAgent:703)
	at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
	at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
	at io.testproject.agent.c.d.run(TestProjectAgent:33)
	at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
	at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
	at java.base/java.lang.Thread.run(Thread.java:830)
2021-10-28 17:28:32.358 [INFO ] i.t.a.m.b.a                              Development socket connected on port: 8686
2021-10-28 17:28:32.358 [INFO ] i.t.a.m.b.a                              Development socket disconnected
2021-10-28 17:28:32.358 [INFO ] i.t.a.m.b.a                              Development socket on port 8686 disconnected
2021-10-28 17:28:32.358 [INFO ] i.t.a.m.d.p                              Initiating upload for execution report B_Y_mPBapUCK9ganKEA0fA
2021-10-28 17:28:32.359 [INFO ] i.t.a.m.d.p                              Finished reporting task
2021-10-28 17:28:32.359 [INFO ] i.t.a.f.l.a.a                            Handling request to start independent development session.
2021-10-28 17:28:32.359 [INFO ] i.t.a.m.d.p                              There are 2 execution reports pending to be uploaded
2021-10-28 17:28:32.359 [INFO ] i.t.a.m.q                                Updating Edge driver to match browser version 93.0.961.37
2021-10-28 17:28:32.359 [INFO ] i.t.a.m.d.p                              Execution 9pw7UqCG5UKKnppAgUjeCw report uploading is still in progress...
2021-10-28 17:28:32.359 [WARN ] i.t.a.f.l.b                              Can't process 'Done' when in 'Ready' state
2021-10-28 17:28:32.359 [INFO ] i.t.a.m.q                                Browser Edge browser (93.0.961.37) is external, skipping driver update
2021-10-28 17:28:32.359 [INFO ] i.t.a.m.d.p                              Execution B_Y_mPBapUCK9ganKEA0fA report uploading is still in progress...
2021-10-28 17:28:32.359 [INFO ] i.t.a.m.d.e                              Initializing driver Edge...
2021-10-28 17:28:32.359 [INFO ] i.t.a.m.d.p                              Finished reporting task
2021-10-28 17:28:32.360 [INFO ] i.t.a.b.b.c                              Reporting state: Idle
2021-10-28 17:28:32.362 [INFO ] i.t.a.m.d.p                              FYI - Report for execution B_Y_mPBapUCK9ganKEA0fA has not been finalized
2021-10-28 17:28:32.362 [ERROR] i.t.a.m.d.p                              Report B_Y_mPBapUCK9ganKEA0fA uploading windows time span has expired.
2021-10-28 17:28:32.364 [INFO ] i.t.a.m.d.p                              FYI - Report for execution 9pw7UqCG5UKKnppAgUjeCw has not been finalized
2021-10-28 17:28:32.364 [ERROR] i.t.a.m.d.p                              Report 9pw7UqCG5UKKnppAgUjeCw uploading windows time span has expired.
2021-10-28 17:28:32.365 [ERROR] i.t.a.f.l.a.a                            Failed to activate Developing state
org.openqa.selenium.remote.UnreachableBrowserException: Could not start a new session. Possible causes are invalid address of the remote server or browser start-up failure.
Build info: version: '3.141.59', revision: 'e82be7d358', time: '2018-11-14T08:17:03'
System info: host: 'testproject-agent-6676c87468-rn6g5', ip: '10.129.9.71', os.name: 'Linux', os.arch: 'amd64', os.version: '3.10.0-1160.11.1.el7.x86_64', java.version: '13-ea'
Driver info: driver.version: RemoteWebDriver
	at org.openqa.selenium.remote.RemoteWebDriver.execute(RemoteWebDriver.java:573)
	at org.openqa.selenium.remote.RemoteWebDriver.startSession(RemoteWebDriver.java:213)
	at org.openqa.selenium.remote.RemoteWebDriver.<init>(RemoteWebDriver.java:131)
	at org.openqa.selenium.remote.RemoteWebDriver.<init>(RemoteWebDriver.java:144)
	at io.testproject.agent.managers.d.e.a(TestProjectAgent:1492)
	at io.testproject.agent.managers.b.a.a(TestProjectAgent:297)
	at io.testproject.agent.fsm.local.a.a.a(TestProjectAgent:232)
	at io.testproject.agent.fsm.local.a.b.a(TestProjectAgent:102)
	at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
	at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
	at io.testproject.agent.c.d.run(TestProjectAgent:33)
	at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
	at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
	at java.base/java.lang.Thread.run(Thread.java:830)
Caused by: java.io.InterruptedIOException: interrupted
	at okio.Timeout.throwIfReached(Timeout.kt:116)
	at okio.OutputStreamSink.write(Okio.kt:62)
	at okio.AsyncTimeout$sink$1.write(AsyncTimeout.kt:106)
	at okio.RealBufferedSink.flush(RealBufferedSink.kt:222)
	at okhttp3.internal.http1.Http1ExchangeCodec.finishRequest(Http1ExchangeCodec.kt:158)
	at okhttp3.internal.connection.Exchange.finishRequest(Exchange.kt:90)
	at okhttp3.internal.http.CallServerInterceptor.intercept(CallServerInterceptor.kt:76)
	at okhttp3.internal.http.RealInterceptorChain.proceed(RealInterceptorChain.kt:112)
	at okhttp3.internal.http.RealInterceptorChain.proceed(RealInterceptorChain.kt:87)
	at org.openqa.selenium.remote.internal.OkHttpClient$Factory$1.lambda$createClient$1(OkHttpClient.java:152)
	at okhttp3.internal.http.RealInterceptorChain.proceed(RealInterceptorChain.kt:112)
	at okhttp3.internal.connection.ConnectInterceptor.intercept(ConnectInterceptor.kt:37)
	at okhttp3.internal.http.RealInterceptorChain.proceed(RealInterceptorChain.kt:112)
	at okhttp3.internal.http.RealInterceptorChain.proceed(RealInterceptorChain.kt:87)
	at okhttp3.internal.cache.CacheInterceptor.intercept(CacheInterceptor.kt:82)
	at okhttp3.internal.http.RealInterceptorChain.proceed(RealInterceptorChain.kt:112)
	at okhttp3.internal.http.RealInterceptorChain.proceed(RealInterceptorChain.kt:87)
	at okhttp3.internal.http.BridgeInterceptor.intercept(BridgeInterceptor.kt:84)
	at okhttp3.internal.http.RealInterceptorChain.proceed(RealInterceptorChain.kt:112)
	at okhttp3.internal.http.RetryAndFollowUpInterceptor.intercept(RetryAndFollowUpInterceptor.kt:71)
	at okhttp3.internal.http.RealInterceptorChain.proceed(RealInterceptorChain.kt:112)
	at okhttp3.internal.http.RealInterceptorChain.proceed(RealInterceptorChain.kt:87)
	at okhttp3.RealCall.getResponseWithInterceptorChain(RealCall.kt:184)
	at okhttp3.RealCall.execute(RealCall.kt:66)
	at org.openqa.selenium.remote.internal.OkHttpClient.execute(OkHttpClient.java:103)
	at org.openqa.selenium.remote.ProtocolHandshake.createSession(ProtocolHandshake.java:105)
	at org.openqa.selenium.remote.ProtocolHandshake.createSession(ProtocolHandshake.java:74)
	at org.openqa.selenium.remote.HttpCommandExecutor.execute(HttpCommandExecutor.java:136)
	at org.openqa.selenium.remote.RemoteWebDriver.execute(RemoteWebDriver.java:552)
	... 13 common frames omitted
2021-10-28 17:28:32.368 [INFO ] i.t.a.h                                  Stopping...
2021-10-28 17:28:32.368 [INFO ] i.t.a.h                                  Stopping Identity Server client...
2021-10-28 17:28:32.369 [ERROR] i.t.a.w.r.m.AppExceptionMapper           io.testproject.java.exceptions.AppException: Could not start a new session. Possible causes are invalid address of the remote server or browser start-up failure.
Build info: version: '3.141.59', revision: 'e82be7d358', time: '2018-11-14T08:17:03'
System info: host: 'testproject-agent-6676c87468-rn6g5', ip: '10.129.9.71', os.name: 'Linux', os.arch: 'amd64', os.version: '3.10.0-1160.11.1.el7.x86_64', java.version: '13-ea'
Driver info: driver.version: RemoteWebDriver
2021-10-28 17:28:32.369 [INFO ] i.t.a.h                                  Stopping Signal...
2021-10-28 17:28:32.370 [INFO ] i.t.a.b.c.a                              Sent shutdown event to Signal server
2021-10-28 17:28:32.370 [INFO ] i.t.a.b.c.a                              Disconnecting Signal as part of the SignalClient stop sequence
2021-10-28 17:28:32.370 [INFO ] i.t.a.f.c.d                              Agent state machine stopped.
2021-10-28 17:28:32.370 [INFO ] i.t.a.h                                  Stopping internal web server...
2021-10-28 17:28:32.371 [INFO ] i.t.a.b.c.a                              Signal disconnected - io client disconnect
2021-10-28 17:28:32.449 [ERROR] i.t.a.b.a.d                              POST https://api.testproject.io/executions/results/projects/oDmyhIA09E6N6KGxdcmPIQ/executions/0cGvT42VQ0mY7erTdAl24w/missing  - 400 (Izxvlrfp): Could not find execution 0cGvT42VQ0mY7erTdAl24w
2021-10-28 17:28:32.450 [ERROR] i.t.a.b.a.c                              POST https://api.testproject.io/executions/results/projects/oDmyhIA09E6N6KGxdcmPIQ/executions/0cGvT42VQ0mY7erTdAl24w/missing  - 400 (Izxvlrfp): Could not find execution 0cGvT42VQ0mY7erTdAl24w
2021-10-28 17:28:32.450 [WARN ] i.t.a.b.b.j                              Failed to report execution as missing (400)
2021-10-28 17:28:32.450 [INFO ] i.t.a.m.d.p                              Deleting execution folder B_Y_mPBapUCK9ganKEA0fA
2021-10-28 17:28:32.458 [INFO ] i.t.a.h                                  Stopping other servers (Selenium & Appium)...
2021-10-28 17:28:32.463 [INFO ] i.t.a.a.a.c.a                            Stopping ADB...
2021-10-28 17:28:32.464 [INFO ] i.t.a.m.A                                Reported Agent state and got response - [Agent Version: 3.3.0]
2021-10-28 17:28:32.468 [INFO ] i.t.a.a.a.c.a                            ADB Stopped.
2021-10-28 17:28:32.548 [INFO ] i.t.a.h                                  All modules stopped.
2021-10-28 17:28:32.548 [INFO ] i.t.a.f.l.d                              Agent state machine stopped.
2021-10-28 17:28:32.548 [INFO ] i.t.a.m.A                                Reporting state Idle was canceled
2021-10-28 17:28:32.548 [INFO ] i.t.a.Program                            Agent will exit with code: 0
2021-10-28 17:28:32.548 [INFO ] i.t.a.Program                            *** AGENT MANAGER - STOP ***
2021-10-28 17:28:32.548 [ERROR] i.t.a.m.A                                Interrupted while trying to report application state!
java.lang.InterruptedException: null
	at java.base/java.util.concurrent.FutureTask.awaitDone(FutureTask.java:418)
	at java.base/java.util.concurrent.FutureTask.get(FutureTask.java:190)
	at io.testproject.agent.managers.A.c(TestProjectAgent:124)
	at io.testproject.agent.fsm.local.a.z.a(TestProjectAgent:111)
	at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
	at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
	at io.testproject.agent.c.d.run(TestProjectAgent:33)
	at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
	at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
	at java.base/java.lang.Thread.run(Thread.java:830)

k8s-testproject.yaml

# To execute this yml file use this command in your local kubernetes environment `kubectl apply -f k8s-testproject.yaml
#***************************************
#SELENIUM STANDALONE CHROME
#***************************************
apiVersion: v1
kind: Service
metadata:
  name: chrome
  labels:
    name: selenium-standalone-chrome
spec:
  selector:
    app: selenium-standalone-chrome
  ports:
    - name: "chrome"
      protocol: TCP    
      port: 4444
      targetPort: 4444
---
apiVersion: apps/v1
kind: Deployment
metadata:
  name: chrome
  labels:
    app: selenium-standalone-chrome
    name: selenium-standalone-chrome
spec:
  replicas: 1
  selector:
    matchLabels:
      app: selenium-standalone-chrome
  strategy:
    type: Recreate
  template:
    metadata:
      labels:
        app: selenium-standalone-chrome
        name: selenium-standalone-chrome
      labels:
        app: selenium-standalone-chrome
        name: selenium-standalone-chrome                              
    spec:
      restartPolicy: Always        
      containers:
        - image: selenium/standalone-chrome
          name: chrome       
          ports:
            - containerPort: 4444       
          volumeMounts:
            - name: dshm
              mountPath: /dev/shm
      volumes:
        - name: dshm
          emptyDir: { "medium": "Memory" }
---          
#***************************************
#SELENIUM STANDALONE EDGE
#*************************************** 
apiVersion: v1
kind: Service
metadata:
  name: edge
  labels:
    name: selenium-standalone-edge
spec:
  selector:
    app: selenium-standalone-edge
  ports:
    - name: "edge"
      protocol: TCP    
      port: 4444
      targetPort: 4444
---
apiVersion: apps/v1
kind: Deployment
metadata:
  name: edge
  labels:
    app: selenium-standalone-edge
    name: selenium-standalone-edge
spec:
  replicas: 1
  selector:
    matchLabels:
      app: selenium-standalone-edge
  strategy:
    type: Recreate
  template:
    metadata:
      labels:
        app: selenium-standalone-edge
        name: selenium-standalone-edge
      labels:
        app: selenium-standalone-edge
        name: selenium-standalone-edge                              
    spec:
      restartPolicy: Always        
      containers:
        - image: selenium/standalone-edge:93.0-20210902
          name: edge       
          ports:
            - containerPort: 4444
          volumeMounts:
            - name: dshm
              mountPath: /dev/shm
      volumes:
        - name: dshm
          emptyDir: { "medium": "Memory" }
---            
#***************************************
#TESTPROJECT AGENT
#***************************************           
apiVersion: v1
kind: Service
metadata:
  name: testproject-agent
  labels:
    name: testproject-agent
spec:
  selector:
    app: testproject-agent
  ports:
    - name: "8585"
      port: 8585
      targetPort: 8585
    - name: "8686"
      port: 8686
      targetPort: 8686
---
apiVersion: apps/v1
kind: Deployment
metadata:
  labels:
    app: testproject-agent
    name: testproject-agent
  name: testproject-agent
spec:
  replicas: 1
  selector:
    matchLabels:
      app: testproject-agent
  template:
    metadata:
      labels:
        app: testproject-agent
        name: testproject-agent
    spec:
      restartPolicy: Always    
      containers:
        - env:
            - name: TP_API_KEY # The API key can be created in the TestProject Web Application (as described https://docs.testproject.io/testproject-integrations/integration-with-jenkins#creating-a-testproject-api-key).
              value: "Ns2voTyjGq2e3s9CHZ-UefdzUrKcyt9_lE9ilSncKYE1"
            - name: CHROME
              value: chrome:4444
            - name: EDGE
              value: edge:4444
            - name: TP_AGENT_TEMP
              value: "true"
            - name: TP_AGENT_ALIAS # OPTIONAL variable that can be used for registering the agent with a custom alias.
              value: "AF TestProject Agent - OpenShift" 
            - name: TP_SDK_PORT # Agent will listen on port 8686 for SDK connections
              value: "8686"
            - name: CHROME_EXT
              value: "chrome-info-samverkan-cicd.tocp.arbetsformedlingen.se"
            - name: EDGE_EXT
              value: "edge-info-samverkan-cicd.tocp.arbetsformedlingen.se"
            - name: HTTPS_PROXY
              value: 'http://10.132.90.21:3128'
            - name: HTTP_PROXY
              value: 'http://10.132.90.21:3128'
            - name: https_proxy
              value: 'http://10.132.90.21:3128'
            - name: http_proxy
              value: 'http://10.132.90.21:3128'
            - name: ROUTER_CIPHERS
              value: modern
          image: testproject/agent:latest
          name: testproject-agent
          ports:
            - containerPort: 8585
            - containerPort: 8686
          volumeMounts:
            - mountPath: /var/testproject/agent
              name: agent-volume

Hi @adas.wh,
Can you please make sure you can reach OpenShift_address:8585 and that you can load the page correctly from where you are running the open SDK session?
You should be able to see a black page with the agent details on it.
Similar to the one you can find on localhost:8585.

Hi @Amit.Lacher
Yes like the localhost:8585 I am also able to reach the black page of openshift address (external route)
But I am confused, as you said OpenShift_address:8686
Because the black page only loads when the route’s Target Port is 8585, if i change it to 8686 i get Application Not Found!
I am attaching screenshot so you can verify something not looking right!

Hi @Amit.Lacher

I moved one step forward by using correct link to TP_agent as internal host:port
Now the driver gets initiated as,
driver = new RemoteWebDriver(new URL(TP_agent), new ChromeOptions(), TP_project, TP_job);
But anything i want to do using it ex. driver.get(url) fails with following error!

Driver info: driver.version: RemoteWebDriver
at VisaSidanTest.pre_steps(VisaSidanTest.java:49)

[INFO]
[INFO] Results:
[INFO]
[ERROR] Failures:
[ERROR] VisaSidanTest.pre_steps:49 ? Json Unable to determine type from: <. Last 1 cha…

Hi @adas.wh,
That is correct you should reach 8585.
regarding the error that you see can you send us the full logs folder through our chat or support mail.

Hi @Amit.Lacher

How do i send the full log? Because the download option doesn’t show up when i click on the Agent’s

Hi,
Local logs can be found here:

Hi @Amit.Lacher
The agent is running in OpenShift, not in local!
And, as I don’t see the download option for the remote agent like the reference you sent, I can’t export log! If you notice the screenshot i attached in my previous message, you would hopefully understand!

Anyway I am sending you the log from agent in OCP.

TestProject agent log

(Attachment testproject-agent-665fbcdcb6-2m6m7.log is missing)

And the log from Jenkins job:

`[INFO] -------------------------------------------------------
[INFO] T E S T S
[INFO] -------------------------------------------------------
[INFO] Running TestSuite
SLF4J: Failed to load class “org.slf4j.impl.StaticLoggerBinder”.
SLF4J: Defaulting to no-operation (NOP) logger implementation
SLF4J: See SLF4J Error Codes for further details.
[ERROR] Tests run: 3, Failures: 1, Errors: 0, Skipped: 2, Time elapsed: 14 s <<< FAILURE! - in TestSuite
[ERROR] pre_steps(VisaSidanTest) Time elapsed: 12.503 s <<< FAILURE!
org.openqa.selenium.json.JsonException:
Unable to determine type from: <. Last 1 characters read: <
Build info: version: ‘3.141.59’, revision: ‘e82be7d358’, time: ‘2018-11-14T08:17:03’
System info: host: ‘openjdk11-agent-2spm7’, ip: ‘10.129.8.79’, os.name: ‘Linux’, os.arch: ‘amd64’, os.version: ‘3.10.0-1160.11.1.el7.x86_64’, java.version: ‘11.0.2’
Driver info: driver.version: RemoteWebDriver
at VisaSidanTest.pre_steps(VisaSidanTest.java:49)

[INFO]
[INFO] Results:
[INFO]
[ERROR] Failures:
[ERROR] VisaSidanTest.pre_steps:49 ? Json Unable to determine type from: <. Last 1 cha…`

i can’t seem to upload a log file!
so i took a screenshot instead.

The same log in text form:

Using Agent Alias: AF TestProject Agent - OpenShift
Marking Agent as temporary…
Obtained execution configuration for Agent: AF TestProject Agent - OpenShift
2021-11-19 00:14:43.065 [INFO ] i.t.a.Program *** AGENT MANAGER - START ***
2021-11-19 00:14:44.456 [INFO ] i.t.a.h TestProject Agent 3.1.0 (90f1a15b4) on Linux
2021-11-19 00:14:44.562 [INFO ] i.t.a.h Running under user ? (?) of type Unknown with Guest privileges
2021-11-19 00:14:44.562 [INFO ] i.t.a.h Running inside docker testproject-agent-665fbcdcb6-2m6m7
2021-11-19 00:14:44.566 [INFO ] i.t.a.h No X11 available - headless mode.
2021-11-19 00:14:44.570 [INFO ] i.t.a.s.IdentityManager No identity file was found - Agent is not registered
2021-11-19 00:14:44.662 [INFO ] i.t.a.h Working folder: /opt/testproject/agent
2021-11-19 00:14:44.662 [INFO ] i.t.a.h Data folder: /var/testproject/agent
2021-11-19 00:14:44.669 [INFO ] i.t.a.h Agent IP addresses: 10.129.8.78
2021-11-19 00:14:46.847 [INFO ] i.t.a.m.c.a Running in docker - ADB version querying will be skipped.
2021-11-19 00:14:46.848 [INFO ] i.t.a.m.c.a Detected Java [13-ea]
2021-11-19 00:14:46.848 [INFO ] i.t.a.m.c.a Detected DotNet [2.2.8]
2021-11-19 00:14:46.851 [INFO ] i.t.a.m.c.b Searching for installed browsers…
2021-11-19 00:14:46.853 [INFO ] i.t.a.m.c.b Getting external browser version via http://chrome:4444/wd/hub
2021-11-19 00:14:50.555 [INFO ] i.t.a.m.c.b External CHROME version is 92.0.4515.107
2021-11-19 00:14:50.649 [INFO ] i.t.a.m.c.b Getting external browser version via http://edge:4444/wd/hub
2021-11-19 00:14:54.662 [INFO ] i.t.a.m.c.b External EDGE version is 93.0.961.37
2021-11-19 00:14:54.872 [INFO ] i.t.a.m.c.b Finished detecting installed browsers.
2021-11-19 00:14:54.873 [INFO ] i.t.a.m.c.a Running in docker - drivers detection will be skipped.
2021-11-19 00:14:55.058 [INFO ] i.t.a.m.b Starting gRPC Server on port localhost:39880
2021-11-19 00:14:55.956 [INFO ] i.t.a.m.Q Runtime information saved
2021-11-19 00:14:55.956 [INFO ] i.t.a.h Agent initialization is complete.
2021-11-19 00:14:55.958 [INFO ] i.t.a.s.IdentityManager No identity file was found - Agent is not registered
2021-11-19 00:14:55.963 [INFO ] i.t.a.w.b Starting Web Server…
2021-11-19 00:14:55.963 [INFO ] i.t.a.w.b Starting internal web server…
2021-11-19 00:14:56.047 [INFO ] i.t.a.m.M Checking Agent’s connectivity with the outside world…
2021-11-19 00:14:56.744 [INFO ] i.t.a.m.M Agent failed communicating with the outside world.
2021-11-19 00:14:56.745 [INFO ] i.t.a.f.c.a.k Direct connection is not possible, proxy is needed?
2021-11-19 00:14:56.745 [INFO ] i.t.a.m.J Trying to discover proxy…
2021-11-19 00:14:56.846 [INFO ] i.t.a.m.J Discovered HTTP proxy 10.132.90.21:3128
2021-11-19 00:14:56.848 [INFO ] i.t.a.m.J Configured HTTP proxy 10.132.90.21:3128
2021-11-19 00:14:56.851 [INFO ] i.t.a.m.M Checking Agent’s connectivity with the outside world…
2021-11-19 00:14:56.852 [INFO ] i.t.a.m.J Trying to discover proxy…
2021-11-19 00:14:56.852 [INFO ] i.t.a.m.J Discovered HTTP proxy 10.132.90.21:3128
2021-11-19 00:14:56.852 [INFO ] i.t.a.m.J Configured HTTP proxy 10.132.90.21:3128
2021-11-19 00:14:58.961 [INFO ] i.t.a.m.M Connection established successfully.
2021-11-19 00:14:58.962 [INFO ] i.t.a.f.c.a.k External connectivity is possible, proceeding to Authentication…
2021-11-19 00:14:59.044 [INFO ] i.t.a.f.c.a.a Trying to load Agent remote identity…
2021-11-19 00:14:59.045 [INFO ] i.t.a.s.IdentityManager No identity file was found - Agent is not registered
2021-11-19 00:14:59.045 [INFO ] i.t.a.f.c.a.a Checking if configuration passed via environment variable is present…
2021-11-19 00:14:59.045 [INFO ] i.t.a.f.c.a.a Trying to register with configuration provided…
2021-11-19 00:14:59.045 [INFO ] i.t.a.f.c.a.a Registering agent with configuration
2021-11-19 00:14:59.245 [INFO ] i.t.a.b.b.h Connecting TestProject servers…
2021-11-19 00:14:59.246 [INFO ] i.t.a.m.M Checking Agent’s connectivity with the outside world…
2021-11-19 00:14:59.353 [INFO ] i.t.a.m.M Agent failed communicating with the outside world.
2021-11-19 00:14:59.355 [INFO ] i.t.a.m.M Checking Agent’s connectivity with the outside world…
2021-11-19 00:14:59.356 [INFO ] i.t.a.m.J Trying to discover proxy…
2021-11-19 00:14:59.356 [INFO ] i.t.a.m.J Discovered HTTP proxy 10.132.90.21:3128
2021-11-19 00:14:59.357 [INFO ] i.t.a.m.J Configured HTTP proxy 10.132.90.21:3128
2021-11-19 00:14:59.744 [INFO ] i.t.a.m.M Connection established successfully.
2021-11-19 00:14:59.744 [INFO ] i.t.a.b.b.h Registering Agent…
2021-11-19 00:14:59.745 [INFO ] i.t.a.b.b.h Preparing to send registration request to host https://api.testproject.io/
2021-11-19 00:15:00.053 [INFO ] i.t.a.b.b.h Sending registration request to https://api.testproject.io/agents/registration/agents
2021-11-19 00:15:02.245 [INFO ] i.t.a.b.a.d POST https://api.testproject.io/agents/registration/agents - 200 (Ni4v6Uiw): No Message
2021-11-19 00:15:02.246 [INFO ] i.t.a.b.b.h Registration request succeeded.
2021-11-19 00:15:02.748 [INFO ] i.t.a.m.N Registered successfully
2021-11-19 00:15:02.852 [INFO ] i.t.a.s.IdentityManager Agent identity has been saved
2021-11-19 00:15:02.952 [INFO ] i.t.a.f.c.a.a Agent is registered as [AF TestProject Agent - OpenShift] (vWWpEO2Sg0a8_SxWTvD7mA), proceeding to authentication…
2021-11-19 00:15:02.954 [INFO ] i.t.a.f.c.a.a Authenticating with TestProject…
2021-11-19 00:15:02.956 [INFO ] i.t.a.s.c Requesting authenticating token…
2021-11-19 00:15:03.455 [INFO ] i.t.a.s.c Authenticated successfully
2021-11-19 00:15:03.555 [INFO ] i.t.a.f.c.a.h Starting Signal…
2021-11-19 00:15:04.846 [INFO ] i.t.a.w.b Web server started on http://localhost:8585
2021-11-19 00:15:04.848 [INFO ] i.t.a.b.b.c Reporting state: Idle
2021-11-19 00:15:04.853 [INFO ] i.t.a.s.IdentityManager Requesting remote identity…
2021-11-19 00:15:04.949 [INFO ] i.t.a.b.c.a Signal connected, awaiting authentication result…
2021-11-19 00:15:04.951 [INFO ] i.t.a.b.c.a Sent empty Session ID
2021-11-19 00:15:05.423 [INFO ] i.t.a.b.a.d GET https://api.testproject.io/api/v1/agent - 200 (sDNrZXOq): No Message
2021-11-19 00:15:05.431 [WARN ] i.t.a.s.IdentityManager Latest version is unknown, Agent might be outdated
2021-11-19 00:15:05.433 [INFO ] i.t.a.s.IdentityManager Agent identity has been saved
2021-11-19 00:15:05.433 [INFO ] i.t.a.m.w.b Configuring concurrent workers amount: 1
2021-11-19 00:15:06.078 [INFO ] i.t.a.b.c.a Signal authentication finished
2021-11-19 00:15:06.080 [INFO ] i.t.a.b.c.a Connection Session ID: 9HxfjfysWo
2021-11-19 00:15:06.080 [INFO ] i.t.a.b.c.a Signal authorized
2021-11-19 00:15:06.588 [INFO ] i.t.a.b.a.d POST https://api.testproject.io/api/v1/agent/certificate - 200 (MB1RbxK9): No Message
2021-11-19 00:15:06.751 [INFO ] i.t.a.w.b Added SSL connector
2021-11-19 00:15:06.751 [INFO ] i.t.a.f.c.a.d Account plan: Free
2021-11-19 00:15:06.751 [INFO ] i.t.a.h Refreshing & reporting capabilities…
2021-11-19 00:15:06.770 [INFO ] i.t.a.m.c.a Running in docker - ADB version querying will be skipped.
2021-11-19 00:15:06.770 [INFO ] i.t.a.m.c.a Detected Java [13-ea]
2021-11-19 00:15:06.771 [INFO ] i.t.a.m.c.a Detected DotNet [2.2.8]
2021-11-19 00:15:06.771 [INFO ] i.t.a.m.c.a Running in docker - drivers detection will be skipped.
2021-11-19 00:15:07.010 [INFO ] i.t.a.b.a.d POST https://api.testproject.io/api/v1/agent/capabilities - 200 (Qxma15SQ): Capabilities were received
2021-11-19 00:15:07.011 [INFO ] i.t.a.b.b.c Reporting state: Idle
2021-11-19 00:15:07.165 [INFO ] i.t.a.m.A Reported Agent state and got response - [Agent Version: 3.3.1]
2021-11-19 00:15:07.169 [INFO ] i.t.a.m.d.p Report uploading manager started.
2021-11-19 00:15:07.635 [INFO ] i.t.a.m.A Reported Agent state and got response - [Agent Version: null]
2021-11-19 00:59:50.569 [INFO ] i.t.a.b.a.d GET https://api.testproject.io/api/v1/agent/tokens?type=Development&token=LyJXZbh0WlSHllpstmHsmWui52RUElonE4KzRE7y-ns1 - 200 (iEOvVuQE): No Message
2021-11-19 00:59:50.570 [INFO ] i.t.a.b.b.c Token Development is valid
2021-11-19 00:59:50.589 [INFO ] i.t.a.w.r.e.DevelopmentEndpoints Received request to start development mode
2021-11-19 00:59:50.644 [INFO ] i.t.a.f.l.a.a Handling request to start independent development session.
2021-11-19 00:59:50.650 [INFO ] i.t.a.m.q Updating Chrome driver to match browser version 92.0.4515.107
2021-11-19 00:59:50.650 [INFO ] i.t.a.m.q Browser Chrome browser (92.0.4515.107) is external, skipping driver update
2021-11-19 00:59:50.666 [INFO ] i.t.a.m.d.e Initializing driver Chrome…
2021-11-19 00:59:50.669 [INFO ] i.t.a.m.d.e Chrome will be launched in headless mode
2021-11-19 00:59:51.652 [INFO ] i.t.a.m.d.e Chrome Driver initialized.
2021-11-19 00:59:52.135 [INFO ] i.t.a.b.a.d POST https://api.testproject.io/executions/results/local-execution - 200 (blCWnbFQ): No Message
2021-11-19 00:59:52.138 [INFO ] i.t.a.b.b.c Reporting execution state ‘Executing’ for package _YupMV9bWkOwThhf1O_O4A
2021-11-19 00:59:52.163 [INFO ] i.t.a.m.b.a Environment variable TP_SDK_PORT is defined: 8686
2021-11-19 00:59:52.164 [INFO ] i.t.a.m.b.a Using SDK port: 8686
2021-11-19 00:59:52.164 [INFO ] i.t.a.m.b.a Awaiting development socket connection on port: 8686
2021-11-19 00:59:52.171 [INFO ] i.t.a.b.b.c Reporting state: Dev
2021-11-19 00:59:52.344 [INFO ] i.t.a.b.a.d PUT https://api.testproject.io/api/v1/executions/_YupMV9bWkOwThhf1O_O4A/state - 200 (ExhyZ9S5): State was updated
2021-11-19 00:59:52.392 [INFO ] i.t.a.b.a.d POST https://api.testproject.io/executions/progress/projects/oDmyhIA09E6N6KGxdcmPIQ/executions/_YupMV9bWkOwThhf1O_O4A/progress - 200 (aaaaaaaa): No Message
2021-11-19 00:59:54.404 [INFO ] i.t.a.m.A Reported Agent state and got response - [Agent Version: 3.3.1]
2021-11-19 00:59:54.452 [INFO ] i.t.a.m.b.a Development socket connected on port: 8686
2021-11-19 00:59:58.397 [INFO ] i.t.a.m.b.a Development socket disconnected
2021-11-19 00:59:58.398 [INFO ] i.t.a.m.b.a Development socket on port 8686 disconnected
2021-11-19 00:59:58.455 [INFO ] i.t.a.m.d.p There are 1 execution reports pending to be uploaded
2021-11-19 00:59:58.457 [INFO ] i.t.a.b.b.c Reporting state: Idle
2021-11-19 00:59:58.462 [INFO ] i.t.a.m.d.p Initiating upload for execution report _YupMV9bWkOwThhf1O_O4A
2021-11-19 00:59:58.464 [INFO ] i.t.a.m.d.p Finished reporting task
2021-11-19 00:59:58.468 [INFO ] i.t.a.m.d.p FYI - Report for execution _YupMV9bWkOwThhf1O_O4A has not been finalized
2021-11-19 00:59:58.468 [ERROR] i.t.a.m.d.p Report _YupMV9bWkOwThhf1O_O4A uploading windows time span has expired.
2021-11-19 00:59:58.604 [ERROR] i.t.a.b.a.d POST https://api.testproject.io/executions/results/projects/oDmyhIA09E6N6KGxdcmPIQ/executions/JIKlUPfYTpKAxtfSYQ8bzA/missing - 400 (z2TRTR9u): Could not find execution JIKlUPfYTpKAxtfSYQ8bzA
2021-11-19 00:59:58.605 [ERROR] i.t.a.b.a.c POST https://api.testproject.io/executions/results/projects/oDmyhIA09E6N6KGxdcmPIQ/executions/JIKlUPfYTpKAxtfSYQ8bzA/missing - 400 (z2TRTR9u): Could not find execution JIKlUPfYTpKAxtfSYQ8bzA
2021-11-19 00:59:58.605 [WARN ] i.t.a.b.b.j Failed to report execution as missing (400)
2021-11-19 00:59:58.605 [INFO ] i.t.a.m.d.p Deleting execution folder _YupMV9bWkOwThhf1O_O4A
2021-11-19 01:00:00.601 [INFO ] i.t.a.m.A Reported Agent state and got response - [Agent Version: 3.3.1]
2021-11-19 01:00:02.625 [INFO ] i.t.a.b.a.d POST https://api.testproject.io/executions/progress/projects/oDmyhIA09E6N6KGxdcmPIQ/executions/_YupMV9bWkOwThhf1O_O4A/progress - 200 (aaaaaaaa): No Message
2021-11-19 01:00:07.175 [INFO ] i.t.a.m.d.p There are 1 execution reports pending to be uploaded
2021-11-19 01:00:07.175 [INFO ] i.t.a.m.d.p Initiating upload for execution report _YupMV9bWkOwThhf1O_O4A
2021-11-19 01:00:07.175 [INFO ] i.t.a.m.d.p Finished reporting task
2021-11-19 01:00:07.178 [INFO ] i.t.a.m.d.p FYI - Report for execution _YupMV9bWkOwThhf1O_O4A has not been finalized
2021-11-19 01:00:07.178 [ERROR] i.t.a.m.d.p Report _YupMV9bWkOwThhf1O_O4A uploading windows time span has expired.
2021-11-19 01:00:07.300 [ERROR] i.t.a.b.a.d POST https://api.testproject.io/executions/results/projects/oDmyhIA09E6N6KGxdcmPIQ/executions/JIKlUPfYTpKAxtfSYQ8bzA/missing - 400 (lmsQFkRu): Could not find execution JIKlUPfYTpKAxtfSYQ8bzA
2021-11-19 01:00:07.301 [ERROR] i.t.a.b.a.c POST https://api.testproject.io/executions/results/projects/oDmyhIA09E6N6KGxdcmPIQ/executions/JIKlUPfYTpKAxtfSYQ8bzA/missing - 400 (lmsQFkRu): Could not find execution JIKlUPfYTpKAxtfSYQ8bzA
2021-11-19 01:00:07.301 [WARN ] i.t.a.b.b.j Failed to report execution as missing (400)
2021-11-19 01:00:07.303 [INFO ] i.t.a.m.d.p Deleting execution folder _YupMV9bWkOwThhf1O_O4A
2021-11-19 01:00:12.938 [INFO ] i.t.a.b.a.d POST https://api.testproject.io/executions/progress/projects/oDmyhIA09E6N6KGxdcmPIQ/executions/_YupMV9bWkOwThhf1O_O4A/progress - 200 (aaaaaaaa): No Message
2021-11-19 01:00:23.146 [INFO ] i.t.a.b.a.d POST https://api.testproject.io/executions/progress/projects/oDmyhIA09E6N6KGxdcmPIQ/executions/_YupMV9bWkOwThhf1O_O4A/progress - 200 (aaaaaaaa): No Message
2021-11-19 01:00:33.319 [INFO ] i.t.a.b.a.d POST https://api.testproject.io/executions/progress/projects/oDmyhIA09E6N6KGxdcmPIQ/executions/_YupMV9bWkOwThhf1O_O4A/progress - 200 (aaaaaaaa): No Message
2021-11-19 01:00:43.501 [INFO ] i.t.a.b.a.d POST https://api.testproject.io/executions/progress/projects/oDmyhIA09E6N6KGxdcmPIQ/executions/_YupMV9bWkOwThhf1O_O4A/progress - 200 (aaaaaaaa): No Message
2021-11-19 01:00:53.642 [INFO ] i.t.a.b.a.d POST https://api.testproject.io/executions/progress/projects/oDmyhIA09E6N6KGxdcmPIQ/executions/_YupMV9bWkOwThhf1O_O4A/progress - 200 (aaaaaaaa): No Message
2021-11-19 01:01:03.799 [INFO ] i.t.a.b.a.d POST https://api.testproject.io/executions/progress/projects/oDmyhIA09E6N6KGxdcmPIQ/executions/_YupMV9bWkOwThhf1O_O4A/progress - 200 (aaaaaaaa): No Message
2021-11-19 01:01:13.985 [INFO ] i.t.a.b.a.d POST https://api.testproject.io/executions/progress/projects/oDmyhIA09E6N6KGxdcmPIQ/executions/_YupMV9bWkOwThhf1O_O4A/progress - 200 (aaaaaaaa): No Message
2021-11-19 01:01:24.271 [INFO ] i.t.a.b.a.d POST https://api.testproject.io/executions/progress/projects/oDmyhIA09E6N6KGxdcmPIQ/executions/_YupMV9bWkOwThhf1O_O4A/progress - 200 (aaaaaaaa): No Message
2021-11-19 01:01:34.416 [INFO ] i.t.a.b.a.d POST https://api.testproject.io/executions/progress/projects/oDmyhIA09E6N6KGxdcmPIQ/executions/_YupMV9bWkOwThhf1O_O4A/progress - 200 (aaaaaaaa): No Message
2021-11-19 01:01:44.632 [INFO ] i.t.a.b.a.d POST https://api.testproject.io/executions/progress/projects/oDmyhIA09E6N6KGxdcmPIQ/executions/_YupMV9bWkOwThhf1O_O4A/progress - 200 (aaaaaaaa): No Message