CloudWatch Log groups /ecs/gocd-stg-ecs ECS/GoCD-auth/GoCD13808ad7836d4a13845e9e585f87072e/c000109f5bf04ebaabfb3263d2b19294 Log events You can use the filter bar below to search for and match terms, phrases, or values in your log events. Learn more about filter patterns Timestamp Message There are older events to load. Load more. 2023-01-04T14:44:15.006-06:00 /docker-entrypoint.sh: Creating directories and symlinks to hold GoCD configuration, data, and logs 2023-01-04T14:44:15.007-06:00 $ mkdir -v /godata/config 2023-01-04T14:44:15.073-06:00 mkdir: created directory ‘/godata/config’ 2023-01-04T14:44:15.074-06:00 $ ln -sv /godata/config /go/config 2023-01-04T14:44:15.093-06:00 ‘/go/config’ -> ‘/godata/config’ 2023-01-04T14:44:15.093-06:00 $ mkdir -v /godata/logs 2023-01-04T14:44:15.094-06:00 mkdir: created directory ‘/godata/logs’ 2023-01-04T14:44:15.094-06:00 $ ln -sv /godata/logs /go/logs 2023-01-04T14:44:15.095-06:00 ‘/go/logs’ -> ‘/godata/logs’ 2023-01-04T14:44:15.095-06:00 $ mkdir -v /godata/pipelines 2023-01-04T14:44:15.096-06:00 mkdir: created directory ‘/godata/pipelines’ 2023-01-04T14:44:15.096-06:00 $ ln -sv /godata/pipelines /go/pipelines 2023-01-04T14:44:15.097-06:00 ‘/go/pipelines’ -> ‘/godata/pipelines’ 2023-01-04T14:44:15.097-06:00 /docker-entrypoint.sh: Creating directories and symlinks to hold GoCD wrapper binaries 2023-01-04T14:44:15.097-06:00 $ ln -sv /go-agent/bin /go/bin 2023-01-04T14:44:15.098-06:00 ‘/go/bin’ -> ‘/go-agent/bin’ 2023-01-04T14:44:15.098-06:00 $ ln -sv /go-agent/lib /go/lib 2023-01-04T14:44:15.099-06:00 ‘/go/lib’ -> ‘/go-agent/lib’ 2023-01-04T14:44:15.099-06:00 $ ln -sv /go-agent/run /go/run 2023-01-04T14:44:15.100-06:00 ‘/go/run’ -> ‘/go-agent/run’ 2023-01-04T14:44:15.100-06:00 $ ln -sv /go-agent/wrapper /go/wrapper 2023-01-04T14:44:15.101-06:00 ‘/go/wrapper’ -> ‘/go-agent/wrapper’ 2023-01-04T14:44:15.101-06:00 $ ln -sv /go-agent/wrapper-config /go/wrapper-config 2023-01-04T14:44:15.102-06:00 ‘/go/wrapper-config’ -> ‘/go-agent/wrapper-config’ 2023-01-04T14:44:15.102-06:00 $ cp -rfv /go-agent/config/agent-bootstrapper-logback-include.xml /go/config/agent-bootstrapper-logback-include.xml 2023-01-04T14:44:15.139-06:00 ‘/go-agent/config/agent-bootstrapper-logback-include.xml’ -> ‘/go/config/agent-bootstrapper-logback-include.xml’ 2023-01-04T14:44:15.139-06:00 $ cp -rfv /go-agent/config/agent-launcher-logback-include.xml /go/config/agent-launcher-logback-include.xml 2023-01-04T14:44:15.144-06:00 ‘/go-agent/config/agent-launcher-logback-include.xml’ -> ‘/go/config/agent-launcher-logback-include.xml’ 2023-01-04T14:44:15.144-06:00 $ cp -rfv /go-agent/config/agent-logback-include.xml /go/config/agent-logback-include.xml 2023-01-04T14:44:15.149-06:00 ‘/go-agent/config/agent-logback-include.xml’ -> ‘/go/config/agent-logback-include.xml’ 2023-01-04T14:44:15.155-06:00 /docker-entrypoint.sh: Running custom scripts in /docker-entrypoint.d/ ... 2023-01-04T14:44:15.159-06:00 $ sed -i -e s@wrapper.logfile=.*@/wrapper.logfile=/go/logs/go-agent-bootstrapper-wrapper.log@g -e s@wrapper.java.command=.*@wrapper.java.command=/gocd-jre/bin/java@g -e s@wrapper.working.dir=.*@wrapper.working.dir=/go@g /go-agent/wrapper-config/wrapper.conf 2023-01-04T14:44:15.210-06:00 $ exec /usr/local/sbin/tini -g -- /go/bin/go-agent console 2023-01-04T14:44:15.545-06:00 Running go-agent... 2023-01-04T14:44:21.243-06:00 wrapper | --> Wrapper Started as Console 2023-01-04T14:44:21.243-06:00 ]0;go-agentwrapper | Java Service Wrapper Standard Edition 64-bit 3.5.41 2023-01-04T14:44:21.243-06:00 wrapper | Copyright (C) 1999-2019 Tanuki Software, Ltd. All Rights Reserved. 2023-01-04T14:44:21.243-06:00 wrapper | http://wrapper.tanukisoftware.com 2023-01-04T14:44:21.243-06:00 wrapper | Licensed to ThoughtWorks for GoCD Agent 2023-01-04T14:44:21.243-06:00 wrapper | 2023-01-04T14:44:21.243-06:00 wrapper | Launching a JVM... 2023-01-04T14:44:21.243-06:00 jvm 1 | WrapperManager: Initializing... 2023-01-04T14:44:21.243-06:00 jvm 1 | [Wed Jan 04 20:44:16 UTC 2023] Starting process: 2023-01-04T14:44:21.243-06:00 jvm 1 | [Wed Jan 04 20:44:16 UTC 2023] Working directory : /go 2023-01-04T14:44:21.243-06:00 jvm 1 | [Wed Jan 04 20:44:16 UTC 2023] Application arguments: [-serverUrl, https://somedevops.com/go] 2023-01-04T14:44:21.243-06:00 jvm 1 | [Wed Jan 04 20:44:16 UTC 2023] GoCD Version: 21.1.0-12439 2023-01-04T14:44:21.243-06:00 jvm 1 | [Wed Jan 04 20:44:16 UTC 2023] Java Version: 15.0.1 2023-01-04T14:44:21.243-06:00 jvm 1 | [Wed Jan 04 20:44:16 UTC 2023] Operating System: Linux(4.14.225-169.362.amzn2.x86_64) 2023-01-04T14:44:21.243-06:00 jvm 1 | Could not find file `config/agent-bootstrapper-logback.xml'. Attempting to load from classpath. 2023-01-04T14:44:21.243-06:00 jvm 1 | Using classpath resource `jar:onejar:lib/agent-bootstrapper-21.1.0-12439-classes.jar!/config/agent-bootstrapper-logback.xml'. 2023-01-04T14:44:21.243-06:00 jvm 1 | 2023-01-04 20:44:17,916 INFO [WrapperJarAppMain] DefaultAgentLauncherCreatorImpl:67 - Attempting to load com.thoughtworks.go.agent.launcher.AgentLauncherImpl as specified by manifest key Go-Agent-Launcher-Class 2023-01-04T14:44:21.243-06:00 jvm 1 | 2023-01-04 20:44:17,941 INFO [WrapperJarAppMain] ParentClassAccessFilteringClassloader:43 - Loading com.thoughtworks.cruise.agent.common.launcher.AgentLauncher using java.net.URLClassLoader 2023-01-04T14:44:21.243-06:00 jvm 1 | SLF4J: Class path contains multiple SLF4J bindings. 2023-01-04T14:44:21.243-06:00 jvm 1 | SLF4J: Found binding in [jar:onejar:lib/logback-classic-1.2.3.jar!/org/slf4j/impl/StaticLoggerBinder.class] 2023-01-04T14:44:21.243-06:00 jvm 1 | SLF4J: Found binding in [jar:file:/go/data/deps-46f25a4015b98086-agent-launcher.jar/logback-classic-1.2.3.jar!/org/slf4j/impl/StaticLoggerBinder.class] 2023-01-04T14:44:21.243-06:00 jvm 1 | SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation. 2023-01-04T14:44:21.243-06:00 jvm 1 | SLF4J: Actual binding is of type [ch.qos.logback.classic.util.ContextSelectorStaticBinder] 2023-01-04T14:44:21.243-06:00 jvm 1 | 2023-01-04 20:44:17,993 INFO [WrapperJarAppMain] AgentBootstrapper:74 - Attempting create and start launcher... 2023-01-04T14:44:21.243-06:00 jvm 1 | 2023-01-04 20:44:17,996 INFO [WrapperJarAppMain] ParentClassAccessFilteringClassloader:43 - Loading com.thoughtworks.cruise.agent.common.launcher.AgentLaunchDescriptor using java.net.URLClassLoader 2023-01-04T14:44:21.243-06:00 jvm 1 | Could not find file `config/agent-launcher-logback.xml'. Attempting to load from classpath. 2023-01-04T14:44:21.243-06:00 jvm 1 | Using classpath resource `jar:file:/go/data/deps-46f25a4015b98086-agent-launcher.jar/agent-launcher-21.1.0-12439-classes.jar!/config/agent-launcher-logback.xml'. 2023-01-04T14:44:21.243-06:00 jvm 1 | 2023-01-04 20:44:18,057 INFO [TouchLoopThread-1] Lockfile:103 - Using lock file: /go/.agent-bootstrapper.running 2023-01-04T14:44:21.243-06:00 jvm 1 | 2023-01-04 20:44:18,984 INFO [WrapperJarAppMain] ServerBinaryDownloader:114 - Downloading agent-launcher.jar 2023-01-04T14:44:21.243-06:00 jvm 1 | 2023-01-04 20:44:19,013 INFO [WrapperJarAppMain] ServerBinaryDownloader:121 - Got server response 2023-01-04T14:44:21.243-06:00 jvm 1 | 2023-01-04 20:44:19,388 INFO [WrapperJarAppMain] ServerBinaryDownloader:129 - Piped the stream to admin/agent-launcher.jar 2023-01-04T14:44:21.243-06:00 jvm 1 | 2023-01-04 20:44:19,403 INFO [WrapperJarAppMain] PerfTimer:52 - Performance: Downloading new admin/agent-launcher.jar with md5 signature: 1fad7ac3e235ce0b244c8117b88f3568 took 419ms 2023-01-04T14:44:21.243-06:00 jvm 1 | 2023-01-04 20:44:19,406 INFO [WrapperJarAppMain] AgentBootstrapper:78 - Launcher returned with code 60(0x3C) 2023-01-04T14:44:21.243-06:00 jvm 1 | 2023-01-04 20:44:20,541 INFO [WrapperJarAppMain] DefaultAgentLauncherCreatorImpl:87 - Attempt No: 1 to cleanup launcher temp files 2023-01-04T14:44:21.243-06:00 jvm 1 | 2023-01-04 20:44:20,565 INFO [WrapperJarAppMain] AgentBootstrapper:104 - Waiting for 200 ms before re-launch.... 2023-01-04T14:44:21.243-06:00 jvm 1 | 2023-01-04 20:44:21,172 INFO [WrapperJarAppMain] DefaultAgentLauncherCreatorImpl:67 - Attempting to load com.thoughtworks.go.agent.launcher.AgentLauncherImpl as specified by manifest key Go-Agent-Launcher-Class 2023-01-04T14:44:24.249-06:00 jvm 1 | 2023-01-04 20:44:21,189 INFO [WrapperJarAppMain] ParentClassAccessFilteringClassloader:43 - Loading com.thoughtworks.cruise.agent.common.launcher.AgentLauncher using java.net.URLClassLoader 2023-01-04T14:44:24.249-06:00 jvm 1 | SLF4J: Class path contains multiple SLF4J bindings. 2023-01-04T14:44:24.249-06:00 jvm 1 | SLF4J: Found binding in [jar:onejar:lib/logback-classic-1.2.3.jar!/org/slf4j/impl/StaticLoggerBinder.class] 2023-01-04T14:44:24.249-06:00 jvm 1 | SLF4J: Found binding in [jar:file:/go/data/deps-43dd1d40566b2137-agent-launcher.jar/logback-classic-1.2.11.jar!/org/slf4j/impl/StaticLoggerBinder.class] 2023-01-04T14:44:24.249-06:00 jvm 1 | SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation. 2023-01-04T14:44:24.249-06:00 jvm 1 | SLF4J: Actual binding is of type [ch.qos.logback.classic.util.ContextSelectorStaticBinder] 2023-01-04T14:44:24.249-06:00 jvm 1 | 2023-01-04 20:44:21,212 INFO [WrapperJarAppMain] AgentBootstrapper:74 - Attempting create and start launcher... 2023-01-04T14:44:24.249-06:00 jvm 1 | 2023-01-04 20:44:21,217 INFO [WrapperJarAppMain] ParentClassAccessFilteringClassloader:43 - Loading com.thoughtworks.cruise.agent.common.launcher.AgentLaunchDescriptor using java.net.URLClassLoader 2023-01-04T14:44:24.249-06:00 jvm 1 | Could not find file `config/agent-launcher-logback.xml'. Attempting to load from classpath. 2023-01-04T14:44:24.249-06:00 jvm 1 | Using classpath resource `jar:file:/go/data/deps-43dd1d40566b2137-agent-launcher.jar/agent-launcher-22.1.0-13913-classes.jar!/config/agent-launcher-logback.xml'. 2023-01-04T14:44:24.249-06:00 jvm 1 | 2023-01-04 20:44:21,265 INFO [TouchLoopThread-3] Lockfile:103 - Using lock file: /go/.agent-bootstrapper.running 2023-01-04T14:44:24.249-06:00 jvm 1 | 2023-01-04 20:44:21,580 INFO [WrapperJarAppMain] ServerBinaryDownloader:114 - Downloading agent.jar 2023-01-04T14:44:24.249-06:00 jvm 1 | 2023-01-04 20:44:21,608 INFO [WrapperJarAppMain] ServerBinaryDownloader:121 - Got server response 2023-01-04T14:44:24.249-06:00 jvm 1 | 2023-01-04 20:44:22,014 INFO [WrapperJarAppMain] ServerBinaryDownloader:129 - Piped the stream to admin/agent 2023-01-04T14:44:24.249-06:00 jvm 1 | 2023-01-04 20:44:22,025 INFO [WrapperJarAppMain] PerfTimer:52 - Performance: Downloading new admin/agent with md5 signature: eabe138e3f243e9613ac370aab80a47c took 445ms 2023-01-04T14:44:24.249-06:00 jvm 1 | 2023-01-04 20:44:23,164 INFO [WrapperJarAppMain] AgentProcessParentImpl:51 - Agent is version: 21.1.0-12439 2023-01-04T14:44:24.249-06:00 jvm 1 | 2023-01-04 20:44:23,293 INFO [WrapperJarAppMain] ServerBinaryDownloader:114 - Downloading agent-plugins.zip 2023-01-04T14:44:24.249-06:00 jvm 1 | 2023-01-04 20:44:23,319 INFO [WrapperJarAppMain] ServerBinaryDownloader:121 - Got server response 2023-01-04T14:44:24.249-06:00 jvm 1 | 2023-01-04 20:44:23,923 INFO [WrapperJarAppMain] ServerBinaryDownloader:129 - Piped the stream to admin/agent-plugins.zip 2023-01-04T14:44:24.249-06:00 jvm 1 | 2023-01-04 20:44:23,932 INFO [WrapperJarAppMain] PerfTimer:52 - Performance: Downloading new admin/agent-plugins.zip with md5 signature: 9311c731a8c08fc0628f89da7267dfbe took 639ms 2023-01-04T14:44:24.249-06:00 jvm 1 | 2023-01-04 20:44:23,958 INFO [WrapperJarAppMain] ServerBinaryDownloader:114 - Downloading tfs-impl.jar 2023-01-04T14:44:24.249-06:00 jvm 1 | 2023-01-04 20:44:23,985 INFO [WrapperJarAppMain] ServerBinaryDownloader:121 - Got server response 2023-01-04T14:44:24.249-06:00 jvm 1 | 2023-01-04 20:44:24,069 INFO [WrapperJarAppMain] ServerBinaryDownloader:129 - Piped the stream to admin/tfs-impl.jar 2023-01-04T14:44:24.249-06:00 jvm 1 | 2023-01-04 20:44:24,085 INFO [WrapperJarAppMain] PerfTimer:52 - Performance: Downloading new admin/tfs-impl.jar with md5 signature: 8c36de6f9628eb159a3a82210061c79b took 127ms 2023-01-04T14:44:24.249-06:00 jvm 1 | 2023-01-04 20:44:24,087 INFO [WrapperJarAppMain] AgentProcessParentImpl:68 - Launching Agent with command: /gocd-jre/bin/java -Xms128m -Xmx256m -Dgo.console.stdout=true -Xms1024m -Xmx2g -Dgo.console.stdout=true -Xms1024m -Xmx2g -Dagent.plugins.md5=9311c731a8c08fc0628f89da7267dfbe -Dagent.binary.md5=eabe138e3f243e9613ac370aab80a47c -Dagent.launcher.md5=1fad7ac3e235ce0b244c8117b88f3568 -Dagent.tfs.md5=8c36de6f9628eb159a3a82210061c79b -Dagent.bootstrapper.version=21.1.0-12439 -jar agent.jar -serverUrl https://somedevops.com/go -sslVerificationMode FULL 2023-01-04T14:44:24.249-06:00 jvm 1 | 2023-01-04 20:44:24,204 [stderr] - [Wed Jan 04 20:44:24 UTC 2023] Starting process: 2023-01-04T14:44:24.249-06:00 jvm 1 | 2023-01-04 20:44:24,204 [stderr] - [Wed Jan 04 20:44:24 UTC 2023] Working directory : /go 2023-01-04T14:44:29.563-06:00 jvm 1 | 2023-01-04 20:44:24,204 [stderr] - [Wed Jan 04 20:44:24 UTC 2023] Application arguments: [-serverUrl, https://somedevops.com/go, -sslVerificationMode, FULL] 2023-01-04T14:44:29.563-06:00 jvm 1 | 2023-01-04 20:44:24,205 [stderr] - [Wed Jan 04 20:44:24 UTC 2023] GoCD Version: 22.1.0-13913 2023-01-04T14:44:29.563-06:00 jvm 1 | 2023-01-04 20:44:24,205 [stderr] - [Wed Jan 04 20:44:24 UTC 2023] Java Version: 15.0.1 2023-01-04T14:44:29.563-06:00 jvm 1 | 2023-01-04 20:44:24,206 [stderr] - [Wed Jan 04 20:44:24 UTC 2023] Operating System: Linux(4.14.225-169.362.amzn2.x86_64) 2023-01-04T14:44:29.563-06:00 jvm 1 | 2023-01-04 20:44:25,521 [stderr] - Could not find file `config/agent-logback.xml'. Attempting to load from classpath. 2023-01-04T14:44:29.563-06:00 jvm 1 | 2023-01-04 20:44:25,521 [stderr] - Using classpath resource `jar:onejar:lib/agent-22.1.0-13913-classes.jar!/config/agent-logback.xml'. 2023-01-04T14:44:29.563-06:00 jvm 1 | 2023-01-04 20:44:25,641 [stdout] - 2023-01-04 20:44:25,638 INFO [main] ClassPathXmlApplicationContext:583 - Refreshing org.springframework.context.support.ClassPathXmlApplicationContext@74e52303: startup date [Wed Jan 04 20:44:25 UTC 2023]; root of context hierarchy 2023-01-04T14:44:29.563-06:00 jvm 1 | 2023-01-04 20:44:25,667 [stdout] - 2023-01-04 20:44:25,667 INFO [main] XmlBeanDefinitionReader:317 - Loading XML bean definitions from class path resource [applicationContext.xml] 2023-01-04T14:44:29.563-06:00 jvm 1 | 2023-01-04 20:44:25,930 [stdout] - 2023-01-04 20:44:25,930 INFO [main] XmlBeanDefinitionReader:317 - Loading XML bean definitions from class path resource [applicationContext-plugin-infra.xml] 2023-01-04T14:44:29.563-06:00 jvm 1 | 2023-01-04 20:44:26,596 [stdout] - 2023-01-04 20:44:26,595 INFO [main] DefaultListableBeanFactory:821 - Overriding bean definition for bean 'pluginLoader' with a different definition: replacing [Generic bean: class [com.thoughtworks.go.plugin.infra.PluginLoader]; scope=singleton; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null; defined in URL [jar:onejar:lib/go-plugin-infra-22.1.0-13913.jar!/com/thoughtworks/go/plugin/infra/PluginLoader.class]] with [Generic bean: class [com.thoughtworks.go.plugin.infra.PluginLoader]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null; defined in class path resource [applicationContext-plugin-infra.xml]] 2023-01-04T14:44:29.563-06:00 jvm 1 | 2023-01-04 20:44:26,912 [stdout] - 2023-01-04 20:44:26,911 INFO [main] AutowiredAnnotationBeanPostProcessor:156 - JSR-330 'javax.inject.Inject' annotation found and supported for autowiring 2023-01-04T14:44:29.563-06:00 jvm 1 | 2023-01-04 20:44:28,162 [stdout] - 2023-01-04 20:44:28,161 INFO [main] AgentStatusHttpd:90 - Agent status HTTP API server running on http://localhost:8152. 2023-01-04T14:44:29.563-06:00 jvm 1 | 2023-01-04 20:44:28,240 [stdout] - 2023-01-04 20:44:28,239 INFO [main] AgentHTTPClientController:96 - Configured remoting type: RemotingClient 2023-01-04T14:44:29.563-06:00 jvm 1 | 2023-01-04 20:44:28,254 [stdout] - 2023-01-04 20:44:28,253 INFO [main] ThreadPoolTaskScheduler:166 - Initializing ExecutorService 'scheduler' 2023-01-04T14:44:29.563-06:00 jvm 1 | 2023-01-04 20:44:29,250 [stdout] - 2023-01-04 20:44:29,249 INFO [main] DefaultPluginJarChangeListener:67 - Plugin load starting: /go/plugins/bundled/gocd-yum-repository-poller-plugin.jar 2023-01-04T14:44:29.563-06:00 jvm 1 | 2023-01-04 20:44:29,451 [stdout] - 2023-01-04 20:44:29,450 INFO [scheduler-1] SslInfrastructureService:78 - [Agent Registration] Starting to register agent. 2023-01-04T14:44:29.563-06:00 jvm 1 | 2023-01-04 20:44:29,451 [stdout] - 2023-01-04 20:44:29,451 INFO [scheduler-1] SslInfrastructureService:88 - [Agent Registration] Fetching token from server. 2023-01-04T14:44:29.563-06:00 jvm 1 | 2023-01-04 20:44:29,456 [stdout] - 2023-01-04 20:44:29,456 INFO [scheduler-1] TokenRequester:56 - The server has generated token for the agent. 2023-01-04T14:44:29.563-06:00 jvm 1 | 2023-01-04 20:44:29,458 [stdout] - 2023-01-04 20:44:29,457 INFO [scheduler-1] SslInfrastructureService:91 - [Agent Registration] Got a token from server. 2023-01-04T14:44:29.563-06:00 jvm 1 | 2023-01-04 20:44:29,468 [stdout] - 2023-01-04 20:44:29,468 INFO [scheduler-1] SslInfrastructureService:170 - This agent is now approved by the server. 2023-01-04T14:44:29.863-06:00 jvm 1 | 2023-01-04 20:44:29,468 [stdout] - 2023-01-04 20:44:29,468 INFO [scheduler-1] SslInfrastructureService:119 - [Agent Registration] Retrieved registration from Go server. 2023-01-04T14:44:29.863-06:00 jvm 1 | 2023-01-04 20:44:29,496 [stdout] - 2023-01-04 20:44:29,496 INFO [scheduler-1] SslInfrastructureService:81 - [Agent Registration] Successfully registered agent. 2023-01-04T14:44:29.863-06:00 jvm 1 | 2023-01-04 20:44:29,532 [stdout] - 2023-01-04 20:44:29,532 INFO [main] DefaultPluginJarChangeListener:74 - Plugin load finished: /go/plugins/bundled/gocd-yum-repository-poller-plugin.jar 2023-01-04T14:44:29.863-06:00 jvm 1 | 2023-01-04 20:44:29,540 [stdout] - 2023-01-04 20:44:29,540 INFO [main] DefaultPluginJarChangeListener:67 - Plugin load starting: /go/plugins/external/somedevops-gocd-generic-nexus-task-plugin-1.1.jar 2023-01-04T14:44:29.863-06:00 jvm 1 | 2023-01-04 20:44:29,824 [stdout] - 2023-01-04 20:44:29,823 ERROR [scheduler-2] RemotingClient:166 - Server responded to action `ping` with: status[422 Unprocessable Entity], body[{ 2023-01-04T14:44:29.863-06:00 jvm 1 | 2023-01-04 20:44:29,824 [stdout] - "message" : "Agent [Agent [7aaa2bf687d0, 172.17.0.2, 0d2d9d4d-fbe2-4780-82d3-6cdde3501288, null]] has no cookie set; nested exception is com.thoughtworks.go.server.service.AgentNoCookieSetException: Agent [Agent [7aaa2bf687d0, 172.17.0.2, 0d2d9d4d-fbe2-4780-82d3-6cdde3501288, null]] has no cookie set" 2023-01-04T14:44:29.863-06:00 jvm 1 | 2023-01-04 20:44:29,824 [stdout] - }] 2023-01-04T14:44:29.863-06:00 jvm 1 | 2023-01-04 20:44:29,825 [stdout] - 2023-01-04 20:44:29,825 ERROR [scheduler-2] AgentHTTPClientController:114 - Error occurred when agent tried to ping server: 2023-01-04T14:44:29.863-06:00 jvm 1 | 2023-01-04 20:44:29,825 [stdout] - java.lang.RuntimeException: org.apache.http.client.ClientProtocolException: The server returned status code 422. Possible reasons include: 2023-01-04T14:44:29.863-06:00 jvm 1 | 2023-01-04 20:44:29,825 [stdout] - - This agent has been deleted from the configuration 2023-01-04T14:44:29.863-06:00 jvm 1 | 2023-01-04 20:44:29,825 [stdout] - - This agent is pending approval 2023-01-04T14:44:29.863-06:00 jvm 1 | 2023-01-04 20:44:29,825 [stdout] - - There is possibly a reverse proxy (or load balancer) that has been misconfigured. See https://docs.gocd.org/22.1.0/installation/configure-reverse-proxy.html#agents-and-reverse-proxies for details. 2023-01-04T14:44:29.863-06:00 jvm 1 | 2023-01-04 20:44:29,825 [stdout] - at com.thoughtworks.go.agent.RemotingClient.post(RemotingClient.java:118) 2023-01-04T14:44:29.863-06:00 jvm 1 | 2023-01-04 20:44:29,825 [stdout] - at com.thoughtworks.go.agent.RemotingClient.ping(RemotingClient.java:74) 2023-01-04T14:44:29.863-06:00 jvm 1 | 2023-01-04 20:44:29,825 [stdout] - at com.thoughtworks.go.agent.AgentHTTPClientController.ping(AgentHTTPClientController.java:109) 2023-01-04T14:44:29.863-06:00 jvm 1 | 2023-01-04 20:44:29,825 [stdout] - at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) 2023-01-04T14:44:29.863-06:00 jvm 1 | 2023-01-04 20:44:29,825 [stdout] - at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) 2023-01-04T14:44:29.863-06:00 jvm 1 | 2023-01-04 20:44:29,825 [stdout] - at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) 2023-01-04T14:44:29.863-06:00 jvm 1 | 2023-01-04 20:44:29,826 [stdout] - at java.base/java.lang.reflect.Method.invoke(Unknown Source) 2023-01-04T14:44:29.863-06:00 jvm 1 | 2023-01-04 20:44:29,826 [stdout] - at org.springframework.scheduling.support.ScheduledMethodRunnable.run(ScheduledMethodRunnable.java:65) 2023-01-04T14:44:29.863-06:00 jvm 1 | 2023-01-04 20:44:29,826 [stdout] - at org.springframework.scheduling.support.DelegatingErrorHandlingRunnable.run(DelegatingErrorHandlingRunnable.java:54) 2023-01-04T14:44:29.863-06:00 jvm 1 | 2023-01-04 20:44:29,826 [stdout] - at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source) 2023-01-04T14:44:29.863-06:00 jvm 1 | 2023-01-04 20:44:29,826 [stdout] - at java.base/java.util.concurrent.FutureTask.runAndReset(Unknown Source) 2023-01-04T14:44:29.863-06:00 jvm 1 | 2023-01-04 20:44:29,826 [stdout] - at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(Unknown Source) 2023-01-04T14:44:29.863-06:00 jvm 1 | 2023-01-04 20:44:29,826 [stdout] - at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) 2023-01-04T14:44:29.863-06:00 jvm 1 | 2023-01-04 20:44:29,826 [stdout] - at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) 2023-01-04T14:44:29.863-06:00 jvm 1 | 2023-01-04 20:44:29,826 [stdout] - at java.base/java.lang.Thread.run(Unknown Source) 2023-01-04T14:44:34.875-06:00 jvm 1 | 2023-01-04 20:44:29,826 [stdout] - Caused by: org.apache.http.client.ClientProtocolException: The server returned status code 422. Possible reasons include: 2023-01-04T14:44:34.875-06:00 jvm 1 | 2023-01-04 20:44:29,826 [stdout] - - This agent has been deleted from the configuration 2023-01-04T14:44:34.875-06:00 jvm 1 | 2023-01-04 20:44:29,826 [stdout] - - This agent is pending approval 2023-01-04T14:44:34.875-06:00 jvm 1 | 2023-01-04 20:44:29,826 [stdout] - - There is possibly a reverse proxy (or load balancer) that has been misconfigured. See https://docs.gocd.org/22.1.0/installation/configure-reverse-proxy.html#agents-and-reverse-proxies for details. 2023-01-04T14:44:34.875-06:00 jvm 1 | 2023-01-04 20:44:29,826 [stdout] - at com.thoughtworks.go.agent.RemotingClient.validateResponse(RemotingClient.java:141) 2023-01-04T14:44:34.875-06:00 jvm 1 | 2023-01-04 20:44:29,826 [stdout] - at com.thoughtworks.go.agent.RemotingClient.post(RemotingClient.java:114) 2023-01-04T14:44:34.875-06:00 jvm 1 | 2023-01-04 20:44:29,826 [stdout] - ... 14 common frames omitted 2023-01-04T14:44:34.875-06:00 jvm 1 | 2023-01-04 20:44:31,173 [stdout] - 2023-01-04 20:44:31,172 INFO [main] DefaultPluginJarChangeListener:74 - Plugin load finished: /go/plugins/external/somedevops-gocd-generic-nexus-task-plugin-1.1.jar 2023-01-04T14:44:34.875-06:00 jvm 1 | 2023-01-04 20:44:31,181 [stdout] - 2023-01-04 20:44:31,181 INFO [main] DefaultPluginJarChangeListener:67 - Plugin load starting: /go/plugins/external/somedevops-gocd-nexus-task-plugin-1.6.jar 2023-01-04T14:44:34.875-06:00 jvm 1 | 2023-01-04 20:44:33,455 [stdout] - 2023-01-04 20:44:33,454 INFO [main] DefaultPluginJarChangeListener:74 - Plugin load finished: /go/plugins/external/somedevops-gocd-nexus-task-plugin-1.6.jar 2023-01-04T14:44:34.875-06:00 jvm 1 | 2023-01-04 20:44:33,462 [stdout] - 2023-01-04 20:44:33,462 INFO [main] DefaultPluginJarChangeListener:67 - Plugin load starting: /go/plugins/external/gocd-git-path-material-plugin-2.2.0-164.jar 2023-01-04T14:44:34.875-06:00 jvm 1 | 2023-01-04 20:44:33,961 [stdout] - 2023-01-04 20:44:33,960 INFO [main] p.g.c.t.g.s.p.GitPathMaterialPlugin:72 [plugin-git-path] - Loading GitPathMaterialPlugin... 2023-01-04T14:44:34.875-06:00 jvm 1 | 2023-01-04 20:44:34,005 [stdout] - 2023-01-04 20:44:34,005 INFO [main] p.g.c.t.g.s.p.HelperFactory:82 [plugin-git-path] - Command line git found [git version 2.12.2] 2023-01-04T14:44:34.875-06:00 jvm 1 | 2023-01-04 20:44:34,006 [stdout] - 2023-01-04 20:44:34,005 INFO [main] p.g.c.t.g.s.p.GitPathMaterialPlugin:82 [plugin-git-path] - Type is CMD 2023-01-04T14:44:34.875-06:00 jvm 1 | 2023-01-04 20:44:34,831 [stdout] - 2023-01-04 20:44:34,831 ERROR [scheduler-2] RemotingClient:166 - Server responded to action `ping` with: status[422 Unprocessable Entity], body[{ 2023-01-04T14:44:34.875-06:00 jvm 1 | 2023-01-04 20:44:34,831 [stdout] - "message" : "Agent [Agent [7aaa2bf687d0, 172.17.0.2, 0d2d9d4d-fbe2-4780-82d3-6cdde3501288, null]] has no cookie set; nested exception is com.thoughtworks.go.server.service.AgentNoCookieSetException: Agent [Agent [7aaa2bf687d0, 172.17.0.2, 0d2d9d4d-fbe2-4780-82d3-6cdde3501288, null]] has no cookie set" 2023-01-04T14:44:34.875-06:00 jvm 1 | 2023-01-04 20:44:34,831 [stdout] - }] 2023-01-04T14:44:34.875-06:00 jvm 1 | 2023-01-04 20:44:34,831 [stdout] - 2023-01-04 20:44:34,831 ERROR [scheduler-2] AgentHTTPClientController:114 - Error occurred when agent tried to ping server: 2023-01-04T14:44:34.875-06:00 jvm 1 | 2023-01-04 20:44:34,832 [stdout] - java.lang.RuntimeException: org.apache.http.client.ClientProtocolException: The server returned status code 422. Possible reasons include: 2023-01-04T14:44:34.875-06:00 jvm 1 | 2023-01-04 20:44:34,832 [stdout] - - This agent has been deleted from the configuration 2023-01-04T14:44:34.875-06:00 jvm 1 | 2023-01-04 20:44:34,832 [stdout] - - This agent is pending approval 2023-01-04T14:44:34.875-06:00 jvm 1 | 2023-01-04 20:44:34,832 [stdout] - - There is possibly a reverse proxy (or load balancer) that has been misconfigured. See https://docs.gocd.org/22.1.0/installation/configure-reverse-proxy.html#agents-and-reverse-proxies for details. 2023-01-04T14:44:34.875-06:00 jvm 1 | 2023-01-04 20:44:34,832 [stdout] - at com.thoughtworks.go.agent.RemotingClient.post(RemotingClient.java:118) 2023-01-04T14:44:34.875-06:00 jvm 1 | 2023-01-04 20:44:34,832 [stdout] - at com.thoughtworks.go.agent.RemotingClient.ping(RemotingClient.java:74) 2023-01-04T14:44:34.875-06:00 jvm 1 | 2023-01-04 20:44:34,832 [stdout] - at com.thoughtworks.go.agent.AgentHTTPClientController.ping(AgentHTTPClientController.java:109) 2023-01-04T14:44:34.875-06:00 jvm 1 | 2023-01-04 20:44:34,832 [stdout] - at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) 2023-01-04T14:44:39.892-06:00 jvm 1 | 2023-01-04 20:44:34,832 [stdout] - at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) 2023-01-04T14:44:39.892-06:00 jvm 1 | 2023-01-04 20:44:34,832 [stdout] - at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) 2023-01-04T14:44:39.892-06:00 jvm 1 | 2023-01-04 20:44:34,832 [stdout] - at java.base/java.lang.reflect.Method.invoke(Unknown Source) 2023-01-04T14:44:39.892-06:00 jvm 1 | 2023-01-04 20:44:34,832 [stdout] - at org.springframework.scheduling.support.ScheduledMethodRunnable.run(ScheduledMethodRunnable.java:65) 2023-01-04T14:44:39.892-06:00 jvm 1 | 2023-01-04 20:44:34,832 [stdout] - at org.springframework.scheduling.support.DelegatingErrorHandlingRunnable.run(DelegatingErrorHandlingRunnable.java:54) 2023-01-04T14:44:39.892-06:00 jvm 1 | 2023-01-04 20:44:34,832 [stdout] - at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source) 2023-01-04T14:44:39.892-06:00 jvm 1 | 2023-01-04 20:44:34,832 [stdout] - at java.base/java.util.concurrent.FutureTask.runAndReset(Unknown Source) 2023-01-04T14:44:39.892-06:00 jvm 1 | 2023-01-04 20:44:34,832 [stdout] - at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(Unknown Source) 2023-01-04T14:44:39.892-06:00 jvm 1 | 2023-01-04 20:44:34,832 [stdout] - at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) 2023-01-04T14:44:39.892-06:00 jvm 1 | 2023-01-04 20:44:34,832 [stdout] - at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) 2023-01-04T14:44:39.892-06:00 jvm 1 | 2023-01-04 20:44:34,832 [stdout] - at java.base/java.lang.Thread.run(Unknown Source) 2023-01-04T14:44:39.892-06:00 jvm 1 | 2023-01-04 20:44:34,832 [stdout] - Caused by: org.apache.http.client.ClientProtocolException: The server returned status code 422. Possible reasons include: 2023-01-04T14:44:39.892-06:00 jvm 1 | 2023-01-04 20:44:34,832 [stdout] - - This agent has been deleted from the configuration 2023-01-04T14:44:39.892-06:00 jvm 1 | 2023-01-04 20:44:34,832 [stdout] - - This agent is pending approval 2023-01-04T14:44:39.892-06:00 jvm 1 | 2023-01-04 20:44:34,832 [stdout] - - There is possibly a reverse proxy (or load balancer) that has been misconfigured. See https://docs.gocd.org/22.1.0/installation/configure-reverse-proxy.html#agents-and-reverse-proxies for details. 2023-01-04T14:44:39.892-06:00 jvm 1 | 2023-01-04 20:44:34,832 [stdout] - at com.thoughtworks.go.agent.RemotingClient.validateResponse(RemotingClient.java:141) 2023-01-04T14:44:39.892-06:00 jvm 1 | 2023-01-04 20:44:34,832 [stdout] - at com.thoughtworks.go.agent.RemotingClient.post(RemotingClient.java:114) 2023-01-04T14:44:39.892-06:00 jvm 1 | 2023-01-04 20:44:34,832 [stdout] - ... 14 common frames omitted 2023-01-04T14:44:39.892-06:00 jvm 1 | 2023-01-04 20:44:36,037 [stdout] - 2023-01-04 20:44:36,036 INFO [main] DefaultPluginJarChangeListener:74 - Plugin load finished: /go/plugins/external/gocd-git-path-material-plugin-2.2.0-164.jar 2023-01-04T14:44:39.892-06:00 jvm 1 | 2023-01-04 20:44:36,043 [stdout] - 2023-01-04 20:44:36,043 INFO [main] DefaultPluginJarChangeListener:67 - Plugin load starting: /go/plugins/external/script-executor-0.3.0.jar 2023-01-04T14:44:39.892-06:00 jvm 1 | 2023-01-04 20:44:38,058 [stdout] - 2023-01-04 20:44:38,057 INFO [main] DefaultPluginJarChangeListener:74 - Plugin load finished: /go/plugins/external/script-executor-0.3.0.jar 2023-01-04T14:44:39.892-06:00 jvm 1 | 2023-01-04 20:44:38,067 [stdout] - 2023-01-04 20:44:38,067 INFO [main] DefaultPluginJarChangeListener:67 - Plugin load starting: /go/plugins/external/somedevops-gocd-nexus-material-plugin-1.1.jar 2023-01-04T14:44:39.892-06:00 jvm 1 | 2023-01-04 20:44:39,837 [stdout] - 2023-01-04 20:44:39,837 ERROR [scheduler-2] RemotingClient:166 - Server responded to action `ping` with: status[422 Unprocessable Entity], body[{ 2023-01-04T14:44:39.892-06:00 jvm 1 | 2023-01-04 20:44:39,837 [stdout] - "message" : "Agent [Agent [7aaa2bf687d0, 172.17.0.2, 0d2d9d4d-fbe2-4780-82d3-6cdde3501288, null]] has no cookie set; nested exception is com.thoughtworks.go.server.service.AgentNoCookieSetException: Agent [Agent [7aaa2bf687d0, 172.17.0.2, 0d2d9d4d-fbe2-4780-82d3-6cdde3501288, null]] has no cookie set" 2023-01-04T14:44:39.892-06:00 jvm 1 | 2023-01-04 20:44:39,837 [stdout] - }] 2023-01-04T14:44:39.892-06:00 jvm 1 | 2023-01-04 20:44:39,838 [stdout] - 2023-01-04 20:44:39,837 ERROR [scheduler-2] AgentHTTPClientController:114 - Error occurred when agent tried to ping server: 2023-01-04T14:44:39.892-06:00 jvm 1 | 2023-01-04 20:44:39,838 [stdout] - java.lang.RuntimeException: org.apache.http.client.ClientProtocolException: The server returned status code 422. Possible reasons include: 2023-01-04T14:44:44.901-06:00 jvm 1 | 2023-01-04 20:44:39,838 [stdout] - - This agent has been deleted from the configuration 2023-01-04T14:44:44.901-06:00 jvm 1 | 2023-01-04 20:44:39,838 [stdout] - - This agent is pending approval 2023-01-04T14:44:44.901-06:00 jvm 1 | 2023-01-04 20:44:39,838 [stdout] - - There is possibly a reverse proxy (or load balancer) that has been misconfigured. See https://docs.gocd.org/22.1.0/installation/configure-reverse-proxy.html#agents-and-reverse-proxies for details. 2023-01-04T14:44:44.901-06:00 jvm 1 | 2023-01-04 20:44:39,838 [stdout] - at com.thoughtworks.go.agent.RemotingClient.post(RemotingClient.java:118) 2023-01-04T14:44:44.901-06:00 jvm 1 | 2023-01-04 20:44:39,838 [stdout] - at com.thoughtworks.go.agent.RemotingClient.ping(RemotingClient.java:74) 2023-01-04T14:44:44.901-06:00 jvm 1 | 2023-01-04 20:44:39,838 [stdout] - at com.thoughtworks.go.agent.AgentHTTPClientController.ping(AgentHTTPClientController.java:109) 2023-01-04T14:44:44.901-06:00 jvm 1 | 2023-01-04 20:44:39,838 [stdout] - at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) 2023-01-04T14:44:44.901-06:00 jvm 1 | 2023-01-04 20:44:39,838 [stdout] - at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) 2023-01-04T14:44:44.901-06:00 jvm 1 | 2023-01-04 20:44:39,838 [stdout] - at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) 2023-01-04T14:44:44.901-06:00 jvm 1 | 2023-01-04 20:44:39,838 [stdout] - at java.base/java.lang.reflect.Method.invoke(Unknown Source) 2023-01-04T14:44:44.901-06:00 jvm 1 | 2023-01-04 20:44:39,838 [stdout] - at org.springframework.scheduling.support.ScheduledMethodRunnable.run(ScheduledMethodRunnable.java:65) 2023-01-04T14:44:44.901-06:00 jvm 1 | 2023-01-04 20:44:39,838 [stdout] - at org.springframework.scheduling.support.DelegatingErrorHandlingRunnable.run(DelegatingErrorHandlingRunnable.java:54) 2023-01-04T14:44:44.901-06:00 jvm 1 | 2023-01-04 20:44:39,838 [stdout] - at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source) 2023-01-04T14:44:44.901-06:00 jvm 1 | 2023-01-04 20:44:39,838 [stdout] - at java.base/java.util.concurrent.FutureTask.runAndReset(Unknown Source) 2023-01-04T14:44:44.901-06:00 jvm 1 | 2023-01-04 20:44:39,838 [stdout] - at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(Unknown Source) 2023-01-04T14:44:44.901-06:00 jvm 1 | 2023-01-04 20:44:39,838 [stdout] - at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) 2023-01-04T14:44:44.901-06:00 jvm 1 | 2023-01-04 20:44:39,838 [stdout] - at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) 2023-01-04T14:44:44.901-06:00 jvm 1 | 2023-01-04 20:44:39,838 [stdout] - at java.base/java.lang.Thread.run(Unknown Source) 2023-01-04T14:44:44.901-06:00 jvm 1 | 2023-01-04 20:44:39,838 [stdout] - Caused by: org.apache.http.client.ClientProtocolException: The server returned status code 422. Possible reasons include: 2023-01-04T14:44:44.901-06:00 jvm 1 | 2023-01-04 20:44:39,838 [stdout] - - This agent has been deleted from the configuration 2023-01-04T14:44:44.901-06:00 jvm 1 | 2023-01-04 20:44:39,838 [stdout] - - This agent is pending approval 2023-01-04T14:44:44.901-06:00 jvm 1 | 2023-01-04 20:44:39,838 [stdout] - - There is possibly a reverse proxy (or load balancer) that has been misconfigured. See https://docs.gocd.org/22.1.0/installation/configure-reverse-proxy.html#agents-and-reverse-proxies for details. 2023-01-04T14:44:44.901-06:00 jvm 1 | 2023-01-04 20:44:39,838 [stdout] - at com.thoughtworks.go.agent.RemotingClient.validateResponse(RemotingClient.java:141) 2023-01-04T14:44:44.901-06:00 jvm 1 | 2023-01-04 20:44:39,838 [stdout] - at com.thoughtworks.go.agent.RemotingClient.post(RemotingClient.java:114) 2023-01-04T14:44:44.901-06:00 jvm 1 | 2023-01-04 20:44:39,838 [stdout] - ... 14 common frames omitted 2023-01-04T14:44:44.901-06:00 jvm 1 | 2023-01-04 20:44:40,709 [stdout] - 2023-01-04 20:44:40,708 INFO [main] DefaultPluginJarChangeListener:74 - Plugin load finished: /go/plugins/external/somedevops-gocd-nexus-material-plugin-1.1.jar 2023-01-04T14:44:44.901-06:00 jvm 1 | 2023-01-04 20:44:40,715 [stdout] - 2023-01-04 20:44:40,714 INFO [main] DefaultPluginJarChangeListener:67 - Plugin load starting: /go/plugins/external/somedevops-gocd-nexus-component-task-plugin-1.2.jar 2023-01-04T14:44:44.901-06:00 jvm 1 | 2023-01-04 20:44:44,429 [stdout] - 2023-01-04 20:44:44,429 INFO [main] DefaultPluginJarChangeListener:74 - Plugin load finished: /go/plugins/external/somedevops-gocd-nexus-component-task-plugin-1.2.jar 2023-01-04T14:44:44.901-06:00 jvm 1 | 2023-01-04 20:44:44,438 [stdout] - 2023-01-04 20:44:44,438 INFO [main] DefaultPluginJarChangeListener:67 - Plugin load starting: /go/plugins/external/product-nexus-gocd-2.0-SNAPSHOT.jar 2023-01-04T14:44:44.902-06:00 jvm 1 | 2023-01-04 20:44:44,845 [stdout] - 2023-01-04 20:44:44,844 ERROR [scheduler-2] RemotingClient:166 - Server responded to action `ping` with: status[422 Unprocessable Entity], body[{ 2023-01-04T14:44:44.902-06:00 jvm 1 | 2023-01-04 20:44:44,845 [stdout] - "message" : "Agent [Agent [7aaa2bf687d0, 172.17.0.2, 0d2d9d4d-fbe2-4780-82d3-6cdde3501288, null]] has no cookie set; nested exception is com.thoughtworks.go.server.service.AgentNoCookieSetException: Agent [Agent [7aaa2bf687d0, 172.17.0.2, 0d2d9d4d-fbe2-4780-82d3-6cdde3501288, null]] has no cookie set" 2023-01-04T14:44:44.902-06:00 jvm 1 | 2023-01-04 20:44:44,845 [stdout] - }] 2023-01-04T14:44:44.902-06:00 jvm 1 | 2023-01-04 20:44:44,845 [stdout] - 2023-01-04 20:44:44,845 ERROR [scheduler-2] AgentHTTPClientController:114 - Error occurred when agent tried to ping server: 2023-01-04T14:44:44.902-06:00 jvm 1 | 2023-01-04 20:44:44,845 [stdout] - java.lang.RuntimeException: org.apache.http.client.ClientProtocolException: The server returned status code 422. Possible reasons include: 2023-01-04T14:44:44.902-06:00 jvm 1 | 2023-01-04 20:44:44,845 [stdout] - - This agent has been deleted from the configuration 2023-01-04T14:44:44.902-06:00 jvm 1 | 2023-01-04 20:44:44,845 [stdout] - - This agent is pending approval 2023-01-04T14:44:44.902-06:00 jvm 1 | 2023-01-04 20:44:44,845 [stdout] - - There is possibly a reverse proxy (or load balancer) that has been misconfigured. See https://docs.gocd.org/22.1.0/installation/configure-reverse-proxy.html#agents-and-reverse-proxies for details. 2023-01-04T14:44:44.902-06:00 jvm 1 | 2023-01-04 20:44:44,845 [stdout] - at com.thoughtworks.go.agent.RemotingClient.post(RemotingClient.java:118) 2023-01-04T14:44:44.902-06:00 jvm 1 | 2023-01-04 20:44:44,845 [stdout] - at com.thoughtworks.go.agent.RemotingClient.ping(RemotingClient.java:74) 2023-01-04T14:44:44.902-06:00 jvm 1 | 2023-01-04 20:44:44,845 [stdout] - at com.thoughtworks.go.agent.AgentHTTPClientController.ping(AgentHTTPClientController.java:109) 2023-01-04T14:44:44.902-06:00 jvm 1 | 2023-01-04 20:44:44,845 [stdout] - at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) 2023-01-04T14:44:44.902-06:00 jvm 1 | 2023-01-04 20:44:44,845 [stdout] - at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) 2023-01-04T14:44:44.902-06:00 jvm 1 | 2023-01-04 20:44:44,845 [stdout] - at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) 2023-01-04T14:44:44.902-06:00 jvm 1 | 2023-01-04 20:44:44,845 [stdout] - at java.base/java.lang.reflect.Method.invoke(Unknown Source) 2023-01-04T14:44:44.902-06:00 jvm 1 | 2023-01-04 20:44:44,846 [stdout] - at org.springframework.scheduling.support.ScheduledMethodRunnable.run(ScheduledMethodRunnable.java:65) 2023-01-04T14:44:44.902-06:00 jvm 1 | 2023-01-04 20:44:44,846 [stdout] - at org.springframework.scheduling.support.DelegatingErrorHandlingRunnable.run(DelegatingErrorHandlingRunnable.java:54) 2023-01-04T14:44:44.902-06:00 jvm 1 | 2023-01-04 20:44:44,846 [stdout] - at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source) 2023-01-04T14:44:44.902-06:00 jvm 1 | 2023-01-04 20:44:44,846 [stdout] - at java.base/java.util.concurrent.FutureTask.runAndReset(Unknown Source) 2023-01-04T14:44:44.902-06:00 jvm 1 | 2023-01-04 20:44:44,846 [stdout] - at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(Unknown Source) 2023-01-04T14:44:44.902-06:00 jvm 1 | 2023-01-04 20:44:44,846 [stdout] - at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) 2023-01-04T14:44:44.902-06:00 jvm 1 | 2023-01-04 20:44:44,846 [stdout] - at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) 2023-01-04T14:44:44.902-06:00 jvm 1 | 2023-01-04 20:44:44,846 [stdout] - at java.base/java.lang.Thread.run(Unknown Source) 2023-01-04T14:44:44.902-06:00 jvm 1 | 2023-01-04 20:44:44,846 [stdout] - Caused by: org.apache.http.client.ClientProtocolException: The server returned status code 422. Possible reasons include: 2023-01-04T14:44:44.902-06:00 jvm 1 | 2023-01-04 20:44:44,846 [stdout] - - This agent has been deleted from the configuration 2023-01-04T14:44:44.902-06:00 jvm 1 | 2023-01-04 20:44:44,846 [stdout] - - This agent is pending approval 2023-01-04T14:44:44.902-06:00 jvm 1 | 2023-01-04 20:44:44,846 [stdout] - - There is possibly a reverse proxy (or load balancer) that has been misconfigured. See https://docs.gocd.org/22.1.0/installation/configure-reverse-proxy.html#agents-and-reverse-proxies for details. 2023-01-04T14:44:44.902-06:00 jvm 1 | 2023-01-04 20:44:44,846 [stdout] - at com.thoughtworks.go.agent.RemotingClient.validateResponse(RemotingClient.java:141) 2023-01-04T14:44:59.926-06:00 jvm 1 | 2023-01-04 20:44:44,846 [stdout] - at com.thoughtworks.go.agent.RemotingClient.post(RemotingClient.java:114) 2023-01-04T14:44:59.926-06:00 jvm 1 | 2023-01-04 20:44:44,846 [stdout] - ... 14 common frames omitted 2023-01-04T14:44:59.926-06:00 jvm 1 | 2023-01-04 20:44:47,626 [stdout] - 2023-01-04 20:44:47,626 INFO [main] DefaultPluginJarChangeListener:74 - Plugin load finished: /go/plugins/external/product-nexus-gocd-2.0-SNAPSHOT.jar 2023-01-04T14:44:59.926-06:00 jvm 1 | 2023-01-04 20:44:47,626 [stdout] - 2023-01-04 20:44:47,626 INFO [scheduler-1] AgentHTTPClientController:139 - About to get cookie from the server. 2023-01-04T14:44:59.926-06:00 jvm 1 | 2023-01-04 20:44:47,636 [stdout] - 2023-01-04 20:44:47,635 INFO [scheduler-1] AgentHTTPClientController:142 - Got cookie: 26575b75-f95e-414b-9c16-ca5a735d3171 2023-01-04T14:44:59.926-06:00 jvm 1 | 2023-01-04 20:44:57,723 [stdout] - 2023-01-04 20:44:57,722 INFO [scheduler-1] HttpService:125 - Got back 200 from server 2023-01-04T14:44:59.926-06:00 jvm 1 | 2023-01-04 20:44:57,723 [stdout] - 2023-01-04 20:44:57,723 INFO [scheduler-1] DefaultGoPublisher:88 - Agent [7aaa2bf687d0, 172.17.0.2, 0d2d9d4d-fbe2-4780-82d3-6cdde3501288] is reporting status [Preparing] to Go Server for Build [Q3UNIT-Infra-QA/9/GetNexusArtifact/114/GetNexusArtifact/150684] 2023-01-04T14:44:59.926-06:00 jvm 1 | 2023-01-04 20:44:57,767 [stdout] - 2023-01-04 20:44:57,767 INFO [scheduler-1] HttpService:125 - Got back 200 from server 2023-01-04T14:44:59.926-06:00 jvm 1 | 2023-01-04 20:44:57,768 [stdout] - 2023-01-04 20:44:57,767 INFO [scheduler-1] DefaultGoPublisher:88 - Agent [7aaa2bf687d0, 172.17.0.2, 0d2d9d4d-fbe2-4780-82d3-6cdde3501288] is reporting status [Building] to Go Server for Build [Q3UNIT-Infra-QA/9/GetNexusArtifact/114/GetNexusArtifact/150684] 2023-01-04T14:44:59.926-06:00 jvm 1 | 2023-01-04 20:44:58,573 [stdout] - 2023-01-04 20:44:58,573 INFO [scheduler-1] HttpService:125 - Got back 200 from server 2023-01-04T14:44:59.926-06:00 jvm 1 | 2023-01-04 20:44:58,574 [stdout] - 2023-01-04 20:44:58,574 INFO [scheduler-1] DefaultGoPublisher:94 - Agent [7aaa2bf687d0, 172.17.0.2, 0d2d9d4d-fbe2-4780-82d3-6cdde3501288] is reporting build result [Passed] to Go Server for Build [Q3UNIT-Infra-QA/9/GetNexusArtifact/114/GetNexusArtifact/150684] 2023-01-04T14:44:59.926-06:00 jvm 1 | 2023-01-04 20:44:58,606 [stdout] - 2023-01-04 20:44:58,605 INFO [scheduler-1] HttpService:125 - Got back 200 from server 2023-01-04T14:44:59.926-06:00 jvm 1 | 2023-01-04 20:44:58,606 [stdout] - 2023-01-04 20:44:58,606 INFO [scheduler-1] DefaultGoPublisher:88 - Agent [7aaa2bf687d0, 172.17.0.2, 0d2d9d4d-fbe2-4780-82d3-6cdde3501288] is reporting status [Completing] to Go Server for Build [Q3UNIT-Infra-QA/9/GetNexusArtifact/114/GetNexusArtifact/150684] 2023-01-04T14:44:59.926-06:00 jvm 1 | 2023-01-04 20:44:58,630 [stdout] - 2023-01-04 20:44:58,630 INFO [scheduler-1] ArtifactsPublisher:68 - Pluggable metadata folder is empty. 2023-01-04T14:44:59.926-06:00 jvm 1 | 2023-01-04 20:44:58,630 [stdout] - 2023-01-04 20:44:58,630 INFO [scheduler-1] DefaultGoPublisher:100 - Agent [7aaa2bf687d0, 172.17.0.2, 0d2d9d4d-fbe2-4780-82d3-6cdde3501288] is reporting build result [Passed] to Go Server for Build [Q3UNIT-Infra-QA/9/GetNexusArtifact/114/GetNexusArtifact/150684] 2023-01-04T14:44:59.926-06:00 jvm 1 | 2023-01-04 20:44:58,634 [stdout] - 2023-01-04 20:44:58,634 INFO [scheduler-1] HttpService:125 - Got back 200 from server 2023-01-04T14:44:59.926-06:00 jvm 1 | 2023-01-04 20:44:58,721 [stdout] - 2023-01-04 20:44:58,720 INFO [scheduler-1] DefaultGoPublisher:82 - Stopping Transmission for Build [Q3UNIT-Infra-QA/9/GetNexusArtifact/114/GetNexusArtifact/150684] 2023-01-04T14:44:59.926-06:00 jvm 1 | 2023-01-04 20:44:59,867 [stdout] - 2023-01-04 20:44:59,867 ERROR [scheduler-2] RemotingClient:166 - Server responded to action `ping` with: status[403 Forbidden], body[] 2023-01-04T14:44:59.926-06:00 jvm 1 | 2023-01-04 20:44:59,868 [stdout] - 2023-01-04 20:44:59,867 ERROR [scheduler-2] AgentHTTPClientController:114 - Error occurred when agent tried to ping server: 2023-01-04T14:44:59.926-06:00 jvm 1 | 2023-01-04 20:44:59,868 [stdout] - java.lang.RuntimeException: org.apache.http.client.ClientProtocolException: The server returned status code 403. Possible reasons include: 2023-01-04T14:44:59.926-06:00 jvm 1 | 2023-01-04 20:44:59,868 [stdout] - - This agent has been deleted from the configuration 2023-01-04T14:44:59.926-06:00 jvm 1 | 2023-01-04 20:44:59,868 [stdout] - - This agent is pending approval 2023-01-04T14:45:00.511-06:00 jvm 1 | 2023-01-04 20:44:59,868 [stdout] - - There is possibly a reverse proxy (or load balancer) that has been misconfigured. See https://docs.goc jvm 1 | 2023-01-04 20:44:59,868 [stdout] - - There is possibly a reverse proxy (or load balancer) that has been misconfigured. See https://docs.goc No newer events at this moment. Auto retry paused. Resume