Publishing artifacts: Failed to perform remote command 'pingAndRegister'

Completed

Permanently deleted user

Created March 04, 2020 14:31

Hi Support team,

we have a build job running on a build agent which is producing successfully artifacts.

Last step is to publish those artifacts from the work dir to the teamcity server.

This step is constantly failing with the following log:

[14:05:39] : Publishing internal artifacts (2s)
[14:05:41] :	 [Publishing internal artifacts] Publishing 1 file using [ArtifactsCachePublisher]
[14:05:41] :	 [Publishing internal artifacts] Publishing 1 file using [WebPublisher]
[14:05:39]W: Publishing artifacts (4m:53s)
[14:05:39] :	 [Publishing artifacts] Collecting files to publish: [**/*.dump]
[14:05:42] :	 [Publishing artifacts] Publishing 1 file using [ArtifactsCachePublisher]: **/*.dump
[14:05:42] :	 [Publishing artifacts] Publishing 1 file using [WebPublisher]: **/*.dump
[14:05:42]W:	 [Publishing artifacts] Recoverable problem publishing artifacts (will retry): java.net.SocketException: Protocol wrong type for socket (Write failed)
[14:06:17] :	 [Publishing artifacts] Publishing 1 file using [ArtifactsCachePublisher]: **/*.dump
[14:06:17] :	 [Publishing artifacts] Publishing 1 file using [WebPublisher]: **/*.dump
[14:06:18]W:	 [Publishing artifacts] Recoverable problem publishing artifacts (will retry): java.net.SocketException: Broken pipe (Write failed)
[14:06:48] :	 [Publishing artifacts] Publishing 1 file using [ArtifactsCachePublisher]: **/*.dump
[14:06:48] :	 [Publishing artifacts] Publishing 1 file using [WebPublisher]: **/*.dump
[14:06:48]W:	 [Publishing artifacts] Recoverable problem publishing artifacts (will retry): java.net.SocketException: Broken pipe (Write failed)
[14:07:24] :	 [Publishing artifacts] Publishing 1 file using [ArtifactsCachePublisher]: **/*.dump
[14:07:24] :	 [Publishing artifacts] Publishing 1 file using [WebPublisher]: **/*.dump
[14:07:24]W:	 [Publishing artifacts] Recoverable problem publishing artifacts (will retry): java.net.SocketException: Broken pipe (Write failed)
[14:07:54] :	 [Publishing artifacts] Publishing 1 file using [ArtifactsCachePublisher]: **/*.dump
[14:07:54] :	 [Publishing artifacts] Publishing 1 file using [WebPublisher]: **/*.dump
[14:07:54]W:	 [Publishing artifacts] Recoverable problem publishing artifacts (will retry): java.net.SocketException: Broken pipe (Write failed)
[14:08:30] :	 [Publishing artifacts] Publishing 1 file using [ArtifactsCachePublisher]: **/*.dump
[14:08:30] :	 [Publishing artifacts] Publishing 1 file using [WebPublisher]: **/*.dump
[14:08:30]W:	 [Publishing artifacts] Recoverable problem publishing artifacts (will retry): java.net.SocketException: Broken pipe (Write failed)
[14:09:00] :	 [Publishing artifacts] Publishing 1 file using [ArtifactsCachePublisher]: **/*.dump
[14:09:00] :	 [Publishing artifacts] Publishing 1 file using [WebPublisher]: **/*.dump
[14:09:01]W:	 [Publishing artifacts] Recoverable problem publishing artifacts (will retry): java.net.SocketException: Broken pipe (Write failed)
[14:09:31] :	 [Publishing artifacts] Publishing 1 file using [ArtifactsCachePublisher]: **/*.dump
[14:09:31] :	 [Publishing artifacts] Publishing 1 file using [WebPublisher]: **/*.dump
[14:09:31]W:	 [Publishing artifacts] Recoverable problem publishing artifacts (will retry): java.net.SocketException: Broken pipe (Write failed)
[14:10:06] :	 [Publishing artifacts] Publishing 1 file using [ArtifactsCachePublisher]: **/*.dump
[14:10:07] :	 [Publishing artifacts] Publishing 1 file using [WebPublisher]: **/*.dump
[14:10:07]W:	 [Publishing artifacts] Recoverable problem publishing artifacts (will retry): java.net.SocketException: Broken pipe (Write failed)
[14:10:32] :	 [Publishing artifacts] Artifacts publishing has been interrupted
[14:10:33] : Build canceled

Snippet agent.log:

[2020-03-04 14:07:24,330]   INFO - r.agent.impl.AgentLogProxyImpl - Adding ping and re-register command on top of the remote command queue
[2020-03-04 14:07:24,331]  DEBUG - brains.buildServer.NetworkUtil - Found Network Interfaces: [/172.27.239.240, /192.168.4.59, /192.168.3.164]
[2020-03-04 14:07:24,332]  DEBUG - t.SbtRunnerBuildServiceFactory - SbtRunnerBuildServiceFactory.getBuildRunnerInfo
[2020-03-04 14:07:24,332]  DEBUG - ade.AgentVersionControllerImpl - Loading agent versions file from: /Users/dfbuildserver/TeamCityAgents/buildAgent-df13/system/.teamcity-agent/teamcity-agent.xml
[2020-03-04 14:07:24,333]   INFO - buildServer.AGENT.registration - Registering on server via URL "https://teamcity.ssa.dfman.info": AgentDetails{Name='DF-13 Agent [Mac mini]', AgentId=148, BuildId=214066, AgentOwnAddress='null', AlternativeAddresses=[192.168.3.164, 192.168.4.59, 172.27.239.240], Port=9091, Version='66439', PluginsVersion='66439-md5-f189c8c1b409e52f98803d2f1eda24c9', AvailableRunners=[Ant, cargo-deploy-runner, DockerCommand, DockerCompose, dotnet.cli, Duplicator, ftp-deploy-runner, gradle-runner, Inspection, jb.nuget.installer, jb.nuget.pack, jb.nuget.publish, jetbrains.dotNetGenericRunner, jetbrains_powershell, jonnyzzz.grunt, jonnyzzz.gulp, jonnyzzz.node, jonnyzzz.npm, jonnyzzz.nvm, jonnyzzz.phantom, JPS, Maven2, MSBuild, NAnt, NUnit, rake-runner, SBT, simpleRunner, sln2003, smb-deploy-runner, ssh-deploy-runner, ssh-exec-runner, VS.Solution, Xcode], AvailableVcs=[tfs, cvs, jetbrains.git, mercurial, svn, perforce], AuthorizationToken='39a4e08ba0a7f5ffba63f6ab138e8d91', PingCode='0JvzNzdHzHoRO9PjvfYUrc7Gx1QD3uML'}
[2020-03-04 14:07:24,349]   INFO - buildServer.AGENT.registration - Server supports the following communication protocols: [polling]
[2020-03-04 14:07:24,349]   INFO - buildServer.AGENT.registration - Trying to register on server using 'polling' protocol.
[2020-03-04 14:07:24,349]  DEBUG - buildServer.AGENT.registration - Sending polling protocol registration request: POST https://teamcity.ssa.dfman.info/app/agents/v1/register
[2020-03-04 14:07:24,367]  DEBUG - buildServer.AGENT.registration - Server confirmed registration, id = 148
[2020-03-04 14:07:29,333]  DEBUG - r.agent.impl.AgentLogProxyImpl - Flush messages for AgentLogProxyImpl::executor
[2020-03-04 14:07:29,333]  DEBUG - r.agent.impl.AgentLogProxyImpl - Later executor invocation for 3 remote commands, requestor: AgentLogProxyImpl::executor
[2020-03-04 14:07:29,333]  DEBUG -    jetbrains.buildServer.AGENT - Ping build server start
[2020-03-04 14:07:29,333]  DEBUG -   jetbrains.buildServer.XMLRPC - >>> XML-RPC request to https://teamcity.ssa.dfman.info/RPC2
[2020-03-04 14:07:29,334]  DEBUG -   jetbrains.buildServer.XMLRPC - <?xml version="1.0"?><methodCall><methodName>buildServer.ping</methodName><params></params></methodCall>
[2020-03-04 14:07:29,347]  DEBUG -   jetbrains.buildServer.XMLRPC - <<< XML-RPC response with status 200
[2020-03-04 14:07:29,347]  DEBUG -   jetbrains.buildServer.XMLRPC - <?xml version="1.0"?><methodResponse><params><param><value><boolean>1</boolean></value></param></params></methodResponse>
[2020-03-04 14:07:29,347]  DEBUG -    jetbrains.buildServer.AGENT - Ping build server returned
[2020-03-04 14:07:29,347]   INFO - r.agent.impl.AgentLogProxyImpl - Failed to perform remote command 'pingAndReregister' for build with id 214066, error: java.lang.Exception: Unable to register on server, pingable: java.lang.Exception: Unable to register on server, pingable
[2020-03-04 14:07:29,347]  DEBUG - r.agent.impl.AgentLogProxyImpl - Failed to perform remote command 'pingAndReregister' for build with id 214066, error: java.lang.Exception: Unable to register on server, pingable
java.lang.Exception: Unable to register on server, pingable
	at jetbrains.buildServer.agent.impl.AgentLogProxyImpl$PingAndReregisterCommand.run(AgentLogProxyImpl.java:378)
	at jetbrains.buildServer.agent.impl.AgentLogProxyImpl$LaterExecutorTask.flushMessages(AgentLogProxyImpl.java:452)
	at jetbrains.buildServer.agent.impl.AgentLogProxyImpl$LaterExecutorTask.access$600(AgentLogProxyImpl.java:419)
	at jetbrains.buildServer.agent.impl.AgentLogProxyImpl$LaterExecutorTask$1.run(AgentLogProxyImpl.java:430)
	at jetbrains.buildServer.util.NamedThreadFactory.executeWithNewThreadName(NamedThreadFactory.java:76)
	at jetbrains.buildServer.agent.impl.AgentLogProxyImpl$LaterExecutorTask.run(AgentLogProxyImpl.java:428)
	at jetbrains.buildServer.util.NamedThreadFactory.executeWithNewThreadName(NamedThreadFactory.java:76)
	at jetbrains.buildServer.util.ExceptionUtil$1.run(ExceptionUtil.java:41)
	at jetbrains.buildServer.agent.impl.AgentLogProxyImpl$1.run(AgentLogProxyImpl.java:66)
	at java.lang.Thread.run(Thread.java:748)
[2020-03-04 14:07:34,350]  DEBUG - r.agent.impl.AgentLogProxyImpl - Flush messages for AgentLogProxyImpl::executor
[2020-03-04 14:07:34,350]  DEBUG - r.agent.impl.AgentLogProxyImpl - Later executor invocation for 3 remote commands, requestor: AgentLogProxyImpl::executor
[2020-03-04 14:07:34,350]  DEBUG -    jetbrains.buildServer.AGENT - Ping build server start
[2020-03-04 14:07:34,350]  DEBUG -   jetbrains.buildServer.XMLRPC - >>> XML-RPC request to https://teamcity.ssa.dfman.info/RPC2
[2020-03-04 14:07:34,350]  DEBUG -   jetbrains.buildServer.XMLRPC - <?xml version="1.0"?><methodCall><methodName>buildServer.ping</methodName><params></params></methodCall>
[2020-03-04 14:07:34,364]  DEBUG -   jetbrains.buildServer.XMLRPC - <<< XML-RPC response with status 200
[2020-03-04 14:07:34,364]  DEBUG -   jetbrains.buildServer.XMLRPC - <?xml version="1.0"?><methodResponse><params><param><value><boolean>1</boolean></value></param></params></methodResponse>
[2020-03-04 14:07:34,365]  DEBUG -    jetbrains.buildServer.AGENT - Ping build server returned
[2020-03-04 14:07:34,365]   INFO - r.agent.impl.AgentLogProxyImpl - Failed to perform remote command 'pingAndReregister' for build with id 214066, error: java.lang.Exception: Unable to register on server, pingable: java.lang.Exception: Unable to register on server, pingable
[2020-03-04 14:07:34,365]  DEBUG - r.agent.impl.AgentLogProxyImpl - Failed to perform remote command 'pingAndReregister' for build with id 214066, error: java.lang.Exception: Unable to register on server, pingable
java.lang.Exception: Unable to register on server, pingable
	at jetbrains.buildServer.agent.impl.AgentLogProxyImpl$PingAndReregisterCommand.run(AgentLogProxyImpl.java:378)
	at jetbrains.buildServer.agent.impl.AgentLogProxyImpl$LaterExecutorTask.flushMessages(AgentLogProxyImpl.java:452)
	at jetbrains.buildServer.agent.impl.AgentLogProxyImpl$LaterExecutorTask.access$600(AgentLogProxyImpl.java:419)
	at jetbrains.buildServer.agent.impl.AgentLogProxyImpl$LaterExecutorTask$1.run(AgentLogProxyImpl.java:430)
	at jetbrains.buildServer.util.NamedThreadFactory.executeWithNewThreadName(NamedThreadFactory.java:76)
	at jetbrains.buildServer.agent.impl.AgentLogProxyImpl$LaterExecutorTask.run(AgentLogProxyImpl.java:428)
	at jetbrains.buildServer.util.NamedThreadFactory.executeWithNewThreadName(NamedThreadFactory.java:76)
	at jetbrains.buildServer.util.ExceptionUtil$1.run(ExceptionUtil.java:41)
	at jetbrains.buildServer.agent.impl.AgentLogProxyImpl$1.run(AgentLogProxyImpl.java:66)
	at java.lang.Thread.run(Thread.java:748)
[2020-03-04 14:07:39,366]  DEBUG - r.agent.impl.AgentLogProxyImpl - Flush messages for AgentLogProxyImpl::executor
[2020-03-04 14:07:39,366]  DEBUG - r.agent.impl.AgentLogProxyImpl - Later executor invocation for 3 remote commands, requestor: AgentLogProxyImpl::executor
[2020-03-04 14:07:39,366]  DEBUG -    jetbrains.buildServer.AGENT - Ping build server start
[2020-03-04 14:07:39,366]  DEBUG -   jetbrains.buildServer.XMLRPC - >>> XML-RPC request to https://teamcity.ssa.dfman.info/RPC2
[2020-03-04 14:07:39,366]  DEBUG -   jetbrains.buildServer.XMLRPC - <?xml version="1.0"?><methodCall><methodName>buildServer.ping</methodName><params></params></methodCall>
[2020-03-04 14:07:39,380]  DEBUG -   jetbrains.buildServer.XMLRPC - <<< XML-RPC response with status 200
[2020-03-04 14:07:39,380]  DEBUG -   jetbrains.buildServer.XMLRPC - <?xml version="1.0"?><methodResponse><params><param><value><boolean>1</boolean></value></param></params></methodResponse>
[2020-03-04 14:07:39,380]  DEBUG -    jetbrains.buildServer.AGENT - Ping build server returned
[2020-03-04 14:07:39,380]   INFO - r.agent.impl.AgentLogProxyImpl - Failed to perform remote command 'pingAndReregister' for build with id 214066, error: java.lang.Exception: Unable to register on server, pingable: java.lang.Exception: Unable to register on server, pingable
[2020-03-04 14:07:39,380]  DEBUG - r.agent.impl.AgentLogProxyImpl - Failed to perform remote command 'pingAndReregister' for build with id 214066, error: java.lang.Exception: Unable to register on server, pingable
java.lang.Exception: Unable to register on server, pingable
	at jetbrains.buildServer.agent.impl.AgentLogProxyImpl$PingAndReregisterCommand.run(AgentLogProxyImpl.java:378)
	at jetbrains.buildServer.agent.impl.AgentLogProxyImpl$LaterExecutorTask.flushMessages(AgentLogProxyImpl.java:452)
	at jetbrains.buildServer.agent.impl.AgentLogProxyImpl$LaterExecutorTask.access$600(AgentLogProxyImpl.java:419)
	at jetbrains.buildServer.agent.impl.AgentLogProxyImpl$LaterExecutorTask$1.run(AgentLogProxyImpl.java:430)
	at jetbrains.buildServer.util.NamedThreadFactory.executeWithNewThreadName(NamedThreadFactory.java:76)
	at jetbrains.buildServer.agent.impl.AgentLogProxyImpl$LaterExecutorTask.run(AgentLogProxyImpl.java:428)
	at jetbrains.buildServer.util.NamedThreadFactory.executeWithNewThreadName(NamedThreadFactory.java:76)
	at jetbrains.buildServer.util.ExceptionUtil$1.run(ExceptionUtil.java:41)
	at jetbrains.buildServer.agent.impl.AgentLogProxyImpl$1.run(AgentLogProxyImpl.java:66)
	at java.lang.Thread.run(Thread.java:748)
[2020-03-04 14:07:44,383]  DEBUG - r.agent.impl.AgentLogProxyImpl - Flush messages for AgentLogProxyImpl::executor
[2020-03-04 14:07:44,383]  DEBUG - r.agent.impl.AgentLogProxyImpl - Later executor invocation for 3 remote commands, requestor: AgentLogProxyImpl::executor
[2020-03-04 14:07:44,383]  DEBUG -    jetbrains.buildServer.AGENT - Ping build server start
[2020-03-04 14:07:44,384]  DEBUG -   jetbrains.buildServer.XMLRPC - >>> XML-RPC request to https://teamcity.ssa.dfman.info/RPC2
[2020-03-04 14:07:44,384]  DEBUG -   jetbrains.buildServer.XMLRPC - <?xml version="1.0"?><methodCall><methodName>buildServer.ping</methodName><params></params></methodCall>
[2020-03-04 14:07:44,398]  DEBUG -   jetbrains.buildServer.XMLRPC - <<< XML-RPC response with status 200
[2020-03-04 14:07:44,398]  DEBUG -   jetbrains.buildServer.XMLRPC - <?xml version="1.0"?><methodResponse><params><param><value><boolean>1</boolean></value></param></params></methodResponse>
[2020-03-04 14:07:44,398]  DEBUG -    jetbrains.buildServer.AGENT - Ping build server returned
[2020-03-04 14:07:44,398]   INFO - r.agent.impl.AgentLogProxyImpl - Failed to perform remote command 'pingAndReregister' for build with id 214066, error: java.lang.Exception: Unable to register on server, pingable: java.lang.Exception: Unable to register on server, pingable

1 comment

Denis Lapuente

Created April 03, 2020 14:08

Hi Andreas,

thanks for your report. unfortunately this error message is often times misleading: https://youtrack.jetbrains.com/issue/TW-61191 so it is not useful in troubleshooting this issue (see how they are either INFO or DEBUG but not ERROR level)

If the issue still replicates, may I ask you to check the server logs, particularly teamcity-server.log for errors correlated on the timeframe to this issue? If you would like us to check them, please consider sharing them via the Submit a request button on top of this page, which allows for private attachments.

Please sign in to leave a comment.