Uploaded image for project: 'Jenkins'
  1. Jenkins
  2. JENKINS-7700

Perforce plugin failing occasionally with PerforceException: No output for:

    Details

    • Type: Bug
    • Status: Resolved (View Workflow)
    • Priority: Critical
    • Resolution: Duplicate
    • Component/s: perforce-plugin
    • Labels:
      None
    • Environment:
      hudson 1.379, perforce plugin 1.1.9, openSUSE 11.2 (x86_64), perforce Rev. P4/LINUX26X86_64/2009.2/232252 (2010/01/27).
    • Similar Issues:

      Description

      After migrating from hudson 1.346 with perforce plugin 1.1.8 to hudson 1.379 with perforce plugin 1.1.9 I get error messages stating "PerforceException: No output for: ..." from approximately one of 4 builds. Only slave machines are affected, builds on the master work fine. Retriggering the build manually often heals the problem but is not really an option for me with about one build a minute for most of the day.

      I get the "No output for:" message for several commands: p4 counter change, p4 workspace -o, p4 describe, etc.

      Looks quite the same as issue JENKINS-7664, but I could not comment on that issue, so I had to post a separate one. Please feel free to mark this as a duplicate.

      Started by upstream project "anaconda-dbtest-hamburg-limbo" build number 380
      Building remotely on oehhp73k
      Using remote perforce client: cbuild_QS_anaconda-conversiontest-hamburg-trunk_OEHHP73L--1596120843
      [anaconda-conversiontest-hamburg-limbo] $ /opt/perforce/bin/p4 workspace -o cbuild_QS_anaconda-conversiontest-hamburg-trunk_OEHHP73L--1596120843
      Saving modified client cbuild_QS_anaconda-conversiontest-hamburg-trunk_OEHHP73L--1596120843
      [anaconda-conversiontest-hamburg-limbo] $ /opt/perforce/bin/p4 -s client -i
      Last sync'd change: 306855
      [anaconda-conversiontest-hamburg-limbo] $ /opt/perforce/bin/p4 counter change
      [anaconda-conversiontest-hamburg-limbo] $ /opt/perforce/bin/p4 -s changes //cbuild_QS_anaconda-conversiontest-hamburg-trunk_OEHHP73L--1596120843/...@306856,@307025
      [anaconda-conversiontest-hamburg-limbo] $ /opt/perforce/bin/p4 describe -s 307021
      Caught exception communicating with perforce. No output for: /opt/perforce/bin/p4 describe -s 307021 com.tek42.perforce.PerforceException: No output for: /opt/perforce/bin/p4 describe -s 307021 
      	at com.tek42.perforce.parse.AbstractPerforceTemplate.getPerforceResponse(AbstractPerforceTemplate.java:341)
      	at com.tek42.perforce.parse.Changes.getChangelist(Changes.java:63)
      	at com.tek42.perforce.parse.Changes.getChangelistsFromNumbers(Changes.java:381)
      	at hudson.plugins.perforce.PerforceSCM.checkout(PerforceSCM.java:552)
      	at hudson.model.AbstractProject.checkout(AbstractProject.java:1082)
      	at hudson.model.AbstractBuild$AbstractRunner.checkout(AbstractBuild.java:479)
      	at hudson.model.AbstractBuild$AbstractRunner.run(AbstractBuild.java:411)
      	at hudson.model.Run.run(Run.java:1280)
      	at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:46)
      	at hudson.model.ResourceController.execute(ResourceController.java:88)
      	at hudson.model.Executor.run(Executor.java:137)
      Recording test results
      Email was triggered for: Failure
      Sending email for trigger: Failure
      Sending email to: ...some guys...
      Finished: FAILURE
      

        Attachments

          Issue Links

            Activity

            Hide
            swoyde swoyde added a comment -

            Upgraded perforce plugin to 1.1.10, same behaviour.

            Found some more related info in /var(log/hudson/hudson.log:

            1662 [SCM polling for hudson.model.FreeStyleProject@5d36d1d7[anaconda-doc-limbo]] WARN perforce - Perforce proce
            ss terminated suddenly
            1662 [SCM polling for hudson.model.FreeStyleProject@5d36d1d7[anaconda-doc-limbo]] WARN perforce - java.io.IOException: Write end dead
                    at java.io.PipedInputStream.read(PipedInputStream.java:294)
                    at java.io.PipedInputStream.read(PipedInputStream.java:361)
                    at sun.nio.cs.StreamDecoder.readBytes(StreamDecoder.java:264)
                    at sun.nio.cs.StreamDecoder.implRead(StreamDecoder.java:306)
                    at sun.nio.cs.StreamDecoder.read(StreamDecoder.java:158)
                    at java.io.InputStreamReader.read(InputStreamReader.java:167)
                    at java.io.BufferedReader.fill(BufferedReader.java:136)
                    at java.io.BufferedReader.readLine(BufferedReader.java:299)
                    at java.io.BufferedReader.readLine(BufferedReader.java:362)
                    at com.tek42.perforce.parse.AbstractPerforceTemplate.getPerforceResponse(AbstractPerforceTemplate.java:2
            97)
                    at com.tek42.perforce.parse.Changes.getChangeNumbers(Changes.java:137)
                    at hudson.plugins.perforce.PerforceSCM.needToBuild(PerforceSCM.java:884)
                    at hudson.plugins.perforce.PerforceSCM.pollChanges(PerforceSCM.java:780)
                    at hudson.scm.SCM.poll(SCM.java:372)
                    at hudson.model.AbstractProject.poll(AbstractProject.java:1195)
                    at hudson.triggers.SCMTrigger$Runner.runPolling(SCMTrigger.java:417)
                    at hudson.triggers.SCMTrigger$Runner.run(SCMTrigger.java:446)
                    at hudson.util.SequentialExecutionQueue$QueueEntry.run(SequentialExecutionQueue.java:118)
                    at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441)
                    at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
                    at java.util.concurrent.FutureTask.run(FutureTask.java:138)
                    at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
                    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
                    at java.lang.Thread.run(Thread.java:619)
            
            Show
            swoyde swoyde added a comment - Upgraded perforce plugin to 1.1.10, same behaviour. Found some more related info in /var(log/hudson/hudson.log: 1662 [SCM polling for hudson.model.FreeStyleProject@5d36d1d7[anaconda-doc-limbo]] WARN perforce - Perforce proce ss terminated suddenly 1662 [SCM polling for hudson.model.FreeStyleProject@5d36d1d7[anaconda-doc-limbo]] WARN perforce - java.io.IOException: Write end dead at java.io.PipedInputStream.read(PipedInputStream.java:294) at java.io.PipedInputStream.read(PipedInputStream.java:361) at sun.nio.cs.StreamDecoder.readBytes(StreamDecoder.java:264) at sun.nio.cs.StreamDecoder.implRead(StreamDecoder.java:306) at sun.nio.cs.StreamDecoder.read(StreamDecoder.java:158) at java.io.InputStreamReader.read(InputStreamReader.java:167) at java.io.BufferedReader.fill(BufferedReader.java:136) at java.io.BufferedReader.readLine(BufferedReader.java:299) at java.io.BufferedReader.readLine(BufferedReader.java:362) at com.tek42.perforce.parse.AbstractPerforceTemplate.getPerforceResponse(AbstractPerforceTemplate.java:2 97) at com.tek42.perforce.parse.Changes.getChangeNumbers(Changes.java:137) at hudson.plugins.perforce.PerforceSCM.needToBuild(PerforceSCM.java:884) at hudson.plugins.perforce.PerforceSCM.pollChanges(PerforceSCM.java:780) at hudson.scm.SCM.poll(SCM.java:372) at hudson.model.AbstractProject.poll(AbstractProject.java:1195) at hudson.triggers.SCMTrigger$Runner.runPolling(SCMTrigger.java:417) at hudson.triggers.SCMTrigger$Runner.run(SCMTrigger.java:446) at hudson.util.SequentialExecutionQueue$QueueEntry.run(SequentialExecutionQueue.java:118) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303) at java.util.concurrent.FutureTask.run(FutureTask.java:138) at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908) at java.lang.Thread.run(Thread.java:619)
            Hide
            swoyde swoyde added a comment - - edited

            I downgraded the perforce plugin to 1.1.8. The builds do not fail anymore, but I still see error messages in the /var/log/hudson/hudson.log:

            Edit: After some time the builds fail again, even with 1.1.8

            3658662 [SCM polling for hudson.model.FreeStyleProject@118ee2ee[anaconda-doc-devdef]] WARN perforce - Perforce process terminated suddenly
            3658662 [SCM polling for hudson.model.FreeStyleProject@118ee2ee[anaconda-doc-devdef]] WARN perforce - java.io.IOException: Write end dead
                    at java.io.PipedInputStream.read(PipedInputStream.java:294)
                    at java.io.PipedInputStream.read(PipedInputStream.java:361)
                    at sun.nio.cs.StreamDecoder.readBytes(StreamDecoder.java:264)
                    at sun.nio.cs.StreamDecoder.implRead(StreamDecoder.java:306)
                    at sun.nio.cs.StreamDecoder.read(StreamDecoder.java:158)
                    at java.io.InputStreamReader.read(InputStreamReader.java:167)
                    at java.io.BufferedReader.fill(BufferedReader.java:136)
                    at java.io.BufferedReader.readLine(BufferedReader.java:299)
                    at java.io.BufferedReader.readLine(BufferedReader.java:362)
                    at com.tek42.perforce.parse.AbstractPerforceTemplate.getPerforceResponse(AbstractPerforceTemplate.java:297)
                    at com.tek42.perforce.parse.Changes.getChangeNumbers(Changes.java:137)
                    at hudson.plugins.perforce.PerforceSCM.needToBuild(PerforceSCM.java:849)
                    at hudson.plugins.perforce.PerforceSCM.pollChanges(PerforceSCM.java:745)
                    at hudson.scm.SCM.poll(SCM.java:372)
                    at hudson.model.AbstractProject.poll(AbstractProject.java:1195)
                    at hudson.triggers.SCMTrigger$Runner.runPolling(SCMTrigger.java:417)
                    at hudson.triggers.SCMTrigger$Runner.run(SCMTrigger.java:446)
                    at hudson.util.SequentialExecutionQueue$QueueEntry.run(SequentialExecutionQueue.java:118)
                    at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441)
                    at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
                    at java.util.concurrent.FutureTask.run(FutureTask.java:138)
                    at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
                    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
                    at java.lang.Thread.run(Thread.java:619)
            
            Show
            swoyde swoyde added a comment - - edited I downgraded the perforce plugin to 1.1.8. The builds do not fail anymore, but I still see error messages in the /var/log/hudson/hudson.log: Edit: After some time the builds fail again, even with 1.1.8 3658662 [SCM polling for hudson.model.FreeStyleProject@118ee2ee[anaconda-doc-devdef]] WARN perforce - Perforce process terminated suddenly 3658662 [SCM polling for hudson.model.FreeStyleProject@118ee2ee[anaconda-doc-devdef]] WARN perforce - java.io.IOException: Write end dead at java.io.PipedInputStream.read(PipedInputStream.java:294) at java.io.PipedInputStream.read(PipedInputStream.java:361) at sun.nio.cs.StreamDecoder.readBytes(StreamDecoder.java:264) at sun.nio.cs.StreamDecoder.implRead(StreamDecoder.java:306) at sun.nio.cs.StreamDecoder.read(StreamDecoder.java:158) at java.io.InputStreamReader.read(InputStreamReader.java:167) at java.io.BufferedReader.fill(BufferedReader.java:136) at java.io.BufferedReader.readLine(BufferedReader.java:299) at java.io.BufferedReader.readLine(BufferedReader.java:362) at com.tek42.perforce.parse.AbstractPerforceTemplate.getPerforceResponse(AbstractPerforceTemplate.java:297) at com.tek42.perforce.parse.Changes.getChangeNumbers(Changes.java:137) at hudson.plugins.perforce.PerforceSCM.needToBuild(PerforceSCM.java:849) at hudson.plugins.perforce.PerforceSCM.pollChanges(PerforceSCM.java:745) at hudson.scm.SCM.poll(SCM.java:372) at hudson.model.AbstractProject.poll(AbstractProject.java:1195) at hudson.triggers.SCMTrigger$Runner.runPolling(SCMTrigger.java:417) at hudson.triggers.SCMTrigger$Runner.run(SCMTrigger.java:446) at hudson.util.SequentialExecutionQueue$QueueEntry.run(SequentialExecutionQueue.java:118) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303) at java.util.concurrent.FutureTask.run(FutureTask.java:138) at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908) at java.lang.Thread.run(Thread.java:619)
            Hide
            rpetti Rob Petti added a comment -

            This is a duplicate.

            Show
            rpetti Rob Petti added a comment - This is a duplicate.
            Hide
            rpetti Rob Petti added a comment -

            There's no reason you shouldn't be able to comment on the original issue, btw.

            Show
            rpetti Rob Petti added a comment - There's no reason you shouldn't be able to comment on the original issue, btw.

              People

              • Assignee:
                Unassigned
                Reporter:
                swoyde swoyde
              • Votes:
                0 Vote for this issue
                Watchers:
                1 Start watching this issue

                Dates

                • Created:
                  Updated:
                  Resolved: