Hi Ashwanth Kumar, Thanks for looking into it. Please find the attached logfile from go-agent
On Wednesday, January 27, 2021 at 8:27:12 AM UTC+5:30 [email protected] wrote: > It would help if you can share the stacktrace or logs from the go-agent > that is failing. > > > > On Tue, Jan 26, 2021 at 6:02 PM Sathish Ravi <[email protected]> wrote: > >> Hi, >> >> Gocd agent not starting after reinstalled in the server. >> >> Server setup failing in the AWS opsworks instance >> >> *[2021-01-26T07:03:52+00:00] INFO: Processing >> directory[/var/log/go-agent-1] action create (gocd::application line 117)* >> *[2021-01-26T07:03:52+00:00] INFO: Processing service[go-server] action >> enable (gocd::services line 1)* >> *[2021-01-26T07:03:52+00:00] INFO: Processing service[go-server] action >> start (gocd::services line 1)* >> *[2021-01-26T07:04:22+00:00] INFO: service[go-server] started* >> *[2021-01-26T07:04:22+00:00] INFO: Processing service[go-agent] action >> enable (gocd::services line 5)* >> *[2021-01-26T07:04:22+00:00] INFO: Processing service[go-agent] action >> start (gocd::services line 5)* >> >> >> *================================================================================* >> *Error executing action `start` on resource 'service[go-agent]'* >> >> *================================================================================* >> >> *Mixlib::ShellOut::ShellCommandFailed* >> *------------------------------------* >> *Expected process to exit with [0], but received '255'* >> *---- Begin output of /sbin/service go-agent start ----* >> *STDOUT: Error starting Go Agent.* >> *STDERR: * >> *---- End output of /sbin/service go-agent start ----* >> *Ran /sbin/service go-agent start returned 255* >> >> *Resource Declaration:* >> *---------------------* >> *# In >> /var/chef/runs/0d2f1572-10a3-496b-a859-381be040b531/local-mode-cache/cache/cookbooks/gocd/recipes/services.rb* >> >> *5: service 'go-agent' do* >> *6: action [:enable, :start]* >> *7: end* >> *8: * >> >> *Compiled Resource:* >> *------------------* >> *# Declared in >> /var/chef/runs/0d2f1572-10a3-496b-a859-381be040b531/local-mode-cache/cache/cookbooks/gocd/recipes/services.rb:5:in >> >> `from_file'* >> >> *service("go-agent") do* >> *action [:enable, :start]* >> *supports {:restart=>nil, :reload=>nil, :status=>nil}* >> *retries 0* >> *retry_delay 2* >> *default_guard_interpreter :default* >> *service_name "go-agent"* >> *enabled true* >> *pattern "go-agent"* >> *declared_type :service* >> *cookbook_name "gocd"* >> *recipe_name "services"* >> *end* >> >> *Platform:* >> *---------* >> *x86_64-linux* >> >> *[2021-01-26T07:04:37+00:00] INFO: Running queued delayed notifications >> before re-raising exception* >> *[2021-01-26T07:04:37+00:00] INFO: template[/etc/go/cruise-config.xml] >> sending restart action to service[go-server] (delayed)* >> *[2021-01-26T07:04:37+00:00] INFO: Processing service[go-server] action >> restart (gocd::services line 1)* >> *[2021-01-26T07:05:14+00:00] INFO: service[go-server] restarted* >> *[2021-01-26T07:05:14+00:00] ERROR: Running exception handlers* >> *[2021-01-26T07:05:14+00:00] ERROR: Exception handlers complete* >> *[2021-01-26T07:05:14+00:00] FATAL: Stacktrace dumped to >> /var/chef/runs/0d2f1572-10a3-496b-a859-381be040b531/local-mode-cache/cache/chef-stacktrace.out* >> *[2021-01-26T07:05:14+00:00] FATAL: Please provide the contents of the >> stacktrace.out file if you file a bug report* >> *[2021-01-26T07:05:14+00:00] ERROR: service[go-agent] (gocd::services >> line 5) had an error: Mixlib::ShellOut::ShellCommandFailed: Expected >> process to exit with [0], but received '255'* >> *---- Begin output of /sbin/service go-agent start ----* >> *STDOUT: Error starting Go Agent.* >> *STDERR: * >> *---- End output of /sbin/service go-agent start ----* >> *Ran /sbin/service go-agent start returned 255* >> *[2021-01-26T07:05:14+00:00] FATAL: Chef::Exceptions::ChildConvergeError: >> Chef run process exited unsuccessfully (exit code 1)* >> >> -- >> You received this message because you are subscribed to the Google Groups >> "go-cd" group. >> To unsubscribe from this group and stop receiving emails from it, send an >> email to [email protected]. >> To view this discussion on the web visit >> https://groups.google.com/d/msgid/go-cd/40a387f7-227f-4841-bc76-1108f1dbd4e1n%40googlegroups.com >> >> <https://groups.google.com/d/msgid/go-cd/40a387f7-227f-4841-bc76-1108f1dbd4e1n%40googlegroups.com?utm_medium=email&utm_source=footer> >> . >> > > > -- > > Ashwanth Kumar / ashwanthkumar.in > > -- You received this message because you are subscribed to the Google Groups "go-cd" group. To unsubscribe from this group and stop receiving emails from it, send an email to [email protected]. To view this discussion on the web visit https://groups.google.com/d/msgid/go-cd/dc70070f-49ca-4266-945c-ee9230c3eb18n%40googlegroups.com.
2021-01-01 07:03:34,257 INFO [main] ClassPathXmlApplicationContext:503 - Refreshing org.springframework.context.support.ClassPathXmlApplicationContext@1a3869f4: startup date [Fri Jan 01 07:03:34 UTC 2021]; root of context hierarchy 2021-01-01 07:03:34,407 INFO [main] XmlBeanDefinitionReader:315 - Loading XML bean definitions from class path resource [applicationContext.xml] 2021-01-01 07:03:34,822 INFO [main] XmlBeanDefinitionReader:315 - Loading XML bean definitions from class path resource [applicationContext-plugin-infra.xml] 2021-01-01 07:03:35,133 INFO [main] ClassPathBeanDefinitionScanner:210 - JSR-330 'javax.inject.Named' annotation found and supported for component scanning 2021-01-01 07:03:36,044 INFO [main] PropertyPlaceholderConfigurer:177 - Loading properties file from URL [jar:onejar:lib/agent-18.3.0-6540-classes.jar!/agent.properties] 2021-01-01 07:03:36,066 INFO [main] AutowiredAnnotationBeanPostProcessor:139 - JSR-330 'javax.inject.Inject' annotation found and supported for autowiring 2021-01-01 07:03:36,102 INFO [main] DefaultListableBeanFactory:577 - Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@41fbdac4: defining beans [org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor,defaultPluginJarChangeListener,defaultPluginJarLocationMonitor,defaultPluginRegistry,goPluginDescriptorBuilder,pluginWriter,pluginValidator,goFileSystem,defaultPluginManager,felixGoPluginOSGiFramework,defaultGoPluginOSGiManifestCreator,pluginRequestProcessorRegistry,pluginsZip,zipUtil,systemEnvironment,agentHealthHolder,agentStatusHttpd,isConnectedToServerV1,propertyConfigurer,buildLoopServer,urlService,httpService,artifactsManipulator,sslInfrastructureService,agentUpgradeService,agentRegistry,subprocessLogger,agentPluginsInitializer,packageRepositoryExtension,scmExtension,taskExtension,artifactExtension,httpClient,httpClientBuilder,webSocketClientBuilder,webSocketClientHandler,webSocketSessionHandler,agentControllerFactory,agentController,clock,agentControllerLooper,agentControllerPinger,agentControllerInstructionExecutor,loopThread,pingThread,instructionExecuteThread,org.springframework.context.annotation.ConfigurationClassPostProcessor.importAwareProcessor]; root of factory hierarchy 2021-01-01 07:03:37,681 INFO [main] AgentStatusHttpd:91 - Agent status HTTP API server running on http://localhost:8152. 2021-01-01 07:03:38,080 INFO [main] AgentControllerFactory:120 - Connecting to server using HTTP(S) 2021-01-01 07:03:38,201 INFO [main] TimerFactoryBean:97 - Initializing Timer 2021-01-01 07:03:38,216 INFO [main] TimerFactoryBean:97 - Initializing Timer 2021-01-01 07:03:38,223 INFO [main] TimerFactoryBean:97 - Initializing Timer 2021-01-01 07:03:39,379 ERROR [loopThread] AgentUpgradeService:86 - [Agent Upgrade] Couldn't connect to: https://127.0.0.1:8154/go/admin/latest-agent.status: org.apache.http.conn.HttpHostConnectException: Connect to 127.0.0.1:8154 [/127.0.0.1] failed: Connection refused (Connection refused) 2021-01-01 07:03:39,382 ERROR [loopThread] AgentController:91 - [Agent Loop] Error occurred during loop: org.apache.http.conn.HttpHostConnectException: Connect to 127.0.0.1:8154 [/127.0.0.1] failed: Connection refused (Connection refused) at org.apache.http.impl.conn.DefaultHttpClientConnectionOperator.connect(DefaultHttpClientConnectionOperator.java:159) at org.apache.http.impl.conn.PoolingHttpClientConnectionManager.connect(PoolingHttpClientConnectionManager.java:373) at org.apache.http.impl.execchain.MainClientExec.establishRoute(MainClientExec.java:381) at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:237) at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185) at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89) at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:111) at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:108) at com.thoughtworks.go.agent.common.ssl.GoAgentServerHttpClient.execute(GoAgentServerHttpClient.java:49) at com.thoughtworks.go.agent.service.AgentUpgradeService.checkForUpgrade(AgentUpgradeService.java:75) at com.thoughtworks.go.agent.service.AgentUpgradeService.checkForUpgrade(AgentUpgradeService.java:69) at com.thoughtworks.go.agent.AgentController.loop(AgentController.java:83) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at org.springframework.util.MethodInvoker.invoke(MethodInvoker.java:273) at org.springframework.scheduling.support.MethodInvokingRunnable.run(MethodInvokingRunnable.java:65) at org.springframework.scheduling.timer.DelegatingTimerTask.run(DelegatingTimerTask.java:70) at java.util.TimerThread.mainLoop(Timer.java:555) at java.util.TimerThread.run(Timer.java:505) Caused by: java.net.ConnectException: Connection refused (Connection refused) at java.net.PlainSocketImpl.socketConnect(Native Method) at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:350) at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:206) at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:188) at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392) at java.net.Socket.connect(Socket.java:607) at org.apache.http.conn.ssl.SSLConnectionSocketFactory.connectSocket(SSLConnectionSocketFactory.java:339) at org.apache.http.impl.conn.DefaultHttpClientConnectionOperator.connect(DefaultHttpClientConnectionOperator.java:142) ... 22 common frames omitted 2021-01-01 07:03:49,214 ERROR [loopThread] AgentUpgradeService:86 - [Agent Upgrade] Couldn't connect to: https://127.0.0.1:8154/go/admin/latest-agent.status: org.apache.http.conn.HttpHostConnectException: Connect to 127.0.0.1:8154 [/127.0.0.1] failed: Connection refused (Connection refused) 2021-01-01 07:03:49,215 ERROR [loopThread] AgentController:91 - [Agent Loop] Error occurred during loop: org.apache.http.conn.HttpHostConnectException: Connect to 127.0.0.1:8154 [/127.0.0.1] failed: Connection refused (Connection refused) at org.apache.http.impl.conn.DefaultHttpClientConnectionOperator.connect(DefaultHttpClientConnectionOperator.java:159) at org.apache.http.impl.conn.PoolingHttpClientConnectionManager.connect(PoolingHttpClientConnectionManager.java:373) at org.apache.http.impl.execchain.MainClientExec.establishRoute(MainClientExec.java:381) at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:237) at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185) at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89) at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:111) at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:108) at com.thoughtworks.go.agent.common.ssl.GoAgentServerHttpClient.execute(GoAgentServerHttpClient.java:49) at com.thoughtworks.go.agent.service.AgentUpgradeService.checkForUpgrade(AgentUpgradeService.java:75) at com.thoughtworks.go.agent.service.AgentUpgradeService.checkForUpgrade(AgentUpgradeService.java:69) at com.thoughtworks.go.agent.AgentController.loop(AgentController.java:83) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at org.springframework.util.MethodInvoker.invoke(MethodInvoker.java:273) at org.springframework.scheduling.support.MethodInvokingRunnable.run(MethodInvokingRunnable.java:65) at org.springframework.scheduling.timer.DelegatingTimerTask.run(DelegatingTimerTask.java:70) at java.util.TimerThread.mainLoop(Timer.java:555) at java.util.TimerThread.run(Timer.java:505) Caused by: java.net.ConnectException: Connection refused (Connection refused) at java.net.PlainSocketImpl.socketConnect(Native Method) at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:350) at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:206) at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:188) at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392) at java.net.Socket.connect(Socket.java:607) at org.apache.http.conn.ssl.SSLConnectionSocketFactory.connectSocket(SSLConnectionSocketFactory.java:339) at org.apache.http.impl.conn.DefaultHttpClientConnectionOperator.connect(DefaultHttpClientConnectionOperator.java:142) ... 22 common frames omitted 2021-01-01 07:03:58,989 INFO [Thread-7] ClassPathXmlApplicationContext:1032 - Closing org.springframework.context.support.ClassPathXmlApplicationContext@1a3869f4: startup date [Fri Jan 01 07:03:34 UTC 2021]; root of context hierarchy 2021-01-01 07:03:58,993 INFO [Thread-7] DefaultListableBeanFactory:434 - Destroying singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@41fbdac4: defining beans [org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor,defaultPluginJarChangeListener,defaultPluginJarLocationMonitor,defaultPluginRegistry,goPluginDescriptorBuilder,pluginWriter,pluginValidator,goFileSystem,defaultPluginManager,felixGoPluginOSGiFramework,defaultGoPluginOSGiManifestCreator,pluginRequestProcessorRegistry,pluginsZip,zipUtil,systemEnvironment,agentHealthHolder,agentStatusHttpd,isConnectedToServerV1,propertyConfigurer,buildLoopServer,urlService,httpService,artifactsManipulator,sslInfrastructureService,agentUpgradeService,agentRegistry,subprocessLogger,agentPluginsInitializer,packageRepositoryExtension,scmExtension,taskExtension,artifactExtension,httpClient,httpClientBuilder,webSocketClientBuilder,webSocketClientHandler,webSocketSessionHandler,agentControllerFactory,agentController,clock,agentControllerLooper,agentControllerPinger,agentControllerInstructionExecutor,loopThread,pingThread,instructionExecuteThread,org.springframework.context.annotation.ConfigurationClassPostProcessor.importAwareProcessor]; root of factory hierarchy 2021-01-01 07:03:58,994 INFO [Thread-7] TimerFactoryBean:165 - Cancelling Timer 2021-01-01 07:03:59,003 INFO [Thread-7] TimerFactoryBean:165 - Cancelling Timer 2021-01-01 07:03:59,003 INFO [Thread-7] TimerFactoryBean:165 - Cancelling Timer
