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

Jenkins retriggers job on same changelist every time it polls

    Details

    • Type: Bug
    • Status: Closed (View Workflow)
    • Priority: Major
    • Resolution: Fixed
    • Component/s: p4-plugin
    • Labels:
    • Environment:
      Windows 8.1 64-bit; Jenkins 1.607 (java 1.7 32-bit); P4 Plugin 1.2.1;
    • Similar Issues:

      Description

      Jenkins has repeatedly triggered a CI run on the same Perforce changelist every time it polls. The job is set to poll every two minutes and trigger a new CI run per every new change list. Currently, it keeps running a new job every two minutes even though there are no new changelists committed to the repository. You can tell in the logs below that it stops updating the "current" CL at build 182 and keeps finding changes (408326+), even though they've already been tested (and passed).

      Here's a breakdown of what's going on...

      ///////////////////////////
      // Perforce Checkins
      ///////////////////////////

      405702 4/2/2015 @ 11:17PM
      408326 4/3/2015 @ 10:49AM
      408455 4/3/2015 @ 11:22AM

      ///////////////////////////
      // Jenkins Build Setup:
      ///////////////////////////

      Build Triggers
      ----------------
      -Poll SCM:
      H/2 * * * * (every two minutes)

      SCM
      ----------------
      -Workspace Behavior
      Static
      -Populate Options:
      Sync Only
      Populate have list checked
      -Polling Build Filters:
      "Polling per change" enabled

      ///////////////////////////
      // Polling Logs
      ///////////////////////////

      =========
      BUILD 180
      =========
      Started on Apr 2, 2015 11:04:00 PM
      Polling SCM changes on worker_node_001
      P4: Polling on: worker_node_001 with:my_project_build
      Connected to node: worker_node_001
      Connected to server: perforce01.XXX.com:3666
      Connected to client: my_project_build
      P4: Polling with label/change: 404445,now
      listing changes: //my_project_build/...@404445,now
      ... found change: 405641
      next change: 405641
      Done. Took 1.9 sec
      Changes found

      =========
      BUILD 181
      =========
      Started on Apr 2, 2015 11:18:00 PM
      Polling SCM changes on worker_node_001
      P4: Polling on: worker_node_001 with:my_project_build
      Connected to node: worker_node_001
      Connected to server: perforce01.XXX.com:3666
      Connected to client: my_project_build
      P4: Polling with label/change: 405641,now
      listing changes: //my_project_build/...@405641,now
      ... found change: 405702
      next change: 405702
      Done. Took 1.4 sec
      Changes found

      =========
      BUILD 182
      =========
      Started on Apr 3, 2015 10:50:01 AM
      Polling SCM changes on worker_node_001
      P4: Polling on: worker_node_001 with:my_project_build
      Connected to node: worker_node_001
      Connected to server: perforce01.XXX.com:3666
      Connected to client: my_project_build
      P4: Polling with label/change: 405702,now
      listing changes: //my_project_build/...@405702,now
      ... found change: 408326
      next change: 408326
      Done. Took 2.1 sec
      Changes found

      =========
      BUILD 183
      =========
      Started on Apr 3, 2015 10:52:01 AM
      Polling SCM changes on worker_node_001
      P4: Polling on: worker_node_001 with:my_project_build
      Connected to node: worker_node_001
      Connected to server: perforce01.XXX.com:3666
      Connected to client: my_project_build
      P4: Polling with label/change: 405702,now
      listing changes: //my_project_build_build/...@405702,now
      ... found change: 408326
      next change: 408326
      Done. Took 1.7 sec
      Changes found

      <...kept doing this every two minutes...>

      <I disabled it and updated P4 Plugin from 1.1.2 to 1.2.1>

      =========
      BUILD 193
      =========
      Started on Apr 3, 2015 11:28:00 AM
      Polling SCM changes on worker_node_001
      P4: Polling on: worker_node_001 with:my_project_build
      Connected to node: worker_node_001
      Connected to server: perforce01.XXX.com:3666
      Connected to client: my_project_build
      P4: Polling with label/change: 405702,now
      listing changes: //my_project_build/...@405702,now
      ... found change: 408455
      ... found change: 408326
      next change: 408326
      Done. Took 3.2 sec
      Changes found

      =========
      BUILD 194
      =========
      Started on Apr 3, 2015 11:30:00 AM
      Polling SCM changes on worker_node_001
      P4: Polling on: worker_node_001 with:my_project_build
      Connected to node: worker_node_001
      Connected to server: perforce01.XXX.com:3666
      Connected to client: my_project_build
      P4: Polling with label/change: 405702,now
      listing changes: //my_project_build/...@405702,now
      ... found change: 408455
      ... found change: 408326
      next change: 408326
      Done. Took 2.1 sec
      Changes found

      <...and so on and so forth until I disabled it>

        Attachments

        1. build.xml
          1 kB
        2. changelog.xml
          0.1 kB
        3. log
          1 kB
        4. polling.log
          2 kB

          Issue Links

            Activity

            mhulth Martin Hulth created issue -
            mhulth Martin Hulth made changes -
            Field Original Value New Value
            Assignee Paul Allen [ p4paul ]
            mhulth Martin Hulth made changes -
            Description Jenkins has repeatedly triggered a CI run on the same Perforce changelist every time it polls. The job is set to poll every two minutes and trigger a new CI run per every new change list. Currently, it keeps running a new job every two minutes even though there are no new changelists committed to the repository. You can tell in the logs below that it stops updating the "current" CL at build 182 and keeps finding changes (408326+), even though they've already been tested (and passed).

            Here's a breakdown of what's going on...

            ///////////////////////////
            // Perforce Checkins
            ///////////////////////////

            405702 4/2/2015 @ 11:17PM
            408326 4/3/2015 @ 10:49AM
            408455 4/3/2015 @ 11:22AM


            ///////////////////////////
            // Jenkins Build Setup:
            ///////////////////////////

            Build Triggers
            ----------------
            -Poll SCM:
                H/2 * * * * (every two minutes)
                
            SCM
            ----------------
            -Workspace Behavior
                Static
            -Populate Options:
                Sync Only
                Populate have list checked
            -Polling Build Filters:
                "Polling per change" enabled


            ///////////////////////////
            // Polling Logs
            ///////////////////////////

            =========
            BUILD 180
            =========
            Started on Apr 2, 2015 11:04:00 PM
            Polling SCM changes on worker_node_001
            P4: Polling on: worker_node_001 with:my_project_build
            Connected to node: worker_node_001
            Connected to server: perforce01.XXX.com:3666
            Connected to client: my_project_build
            P4: Polling with label/change: 404445,now
            listing changes: //my_project_build/...@404445,now
            ... found change: 405641
            next change: 405641
            Done. Took 1.9 sec
            Changes found

            =========
            BUILD 181
            =========
            Started on Apr 2, 2015 11:18:00 PM
            Polling SCM changes on worker_node_001
            P4: Polling on: worker_node_001 with:my_project_build
            Connected to node: worker_node_001
            Connected to server: perforce01.XXX.com:3666
            Connected to client: my_project_build
            P4: Polling with label/change: 405641,now
            listing changes: //my_project_build/...@405641,now
            ... found change: 405702
            next change: 405702
            Done. Took 1.4 sec
            Changes found

            =========
            BUILD 182
            =========
            Started on Apr 3, 2015 10:50:01 AM
            Polling SCM changes on worker_node_001
            P4: Polling on: worker_node_001 with:my_project_build
            Connected to node: worker_node_001
            Connected to server: perforce01.XXX.com:3666
            Connected to client: my_project_build
            P4: Polling with label/change: 405702,now
            listing changes: //my_project_build/...@405702,now
            ... found change: 408326
            next change: 408326
            Done. Took 2.1 sec
            Changes found


            =========
            BUILD 183
            =========
            Started on Apr 3, 2015 10:52:01 AM
            Polling SCM changes on worker_node_001
            P4: Polling on: worker_node_001 with:my_project_build
            Connected to node: worker_node_001
            Connected to server: perforce01.XXX.com:3666
            Connected to client: my_project_build
            P4: Polling with label/change: 405702,now
            listing changes: //my_project_build_build/...@405702,now
            ... found change: 408326
            next change: 408326
            Done. Took 1.7 sec
            Changes found

            <...kept doing this every two minutes...>

            <I disabled it and updated P4 Plugin from 1.1.2 to 1.2.1>

            =========
            BUILD 193
            =========
            Started on Apr 3, 2015 11:28:00 AM
            Polling SCM changes on worker_node_001
            P4: Polling on: worker_node_001 with:my_project_build
            Connected to node: worker_node_001
            Connected to server: perforce01.XXX.com:3666
            Connected to client: my_project_build
            P4: Polling with label/change: 405702,now
            listing changes: //my_project_build/...@405702,now
            ... found change: 408455
            ... found change: 408326
            next change: 408326
            Done. Took 3.2 sec
            Changes found

            =========
            BUILD 193
            =========
            Started on Apr 3, 2015 11:28:00 AM
            Polling SCM changes on worker_node_001
            P4: Polling on: worker_node_001 with:my_project_build
            Connected to node: worker_node_001
            Connected to server: perforce01.XXX.com:3666
            Connected to client: my_project_build
            P4: Polling with label/change: 405702,now
            listing changes: //my_project_build/...@405702,now
            ... found change: 408455
            ... found change: 408326
            next change: 408326
            Done. Took 3.2 sec
            Changes found

            =========
            BUILD 194
            =========
            Started on Apr 3, 2015 11:30:00 AM
            Polling SCM changes on worker_node_001
            P4: Polling on: worker_node_001 with:my_project_build
            Connected to node: worker_node_001
            Connected to server: perforce01.XXX.com:3666
            Connected to client: my_project_build
            P4: Polling with label/change: 405702,now
            listing changes: //my_project_build/...@405702,now
            ... found change: 408455
            ... found change: 408326
            next change: 408326
            Done. Took 2.1 sec
            Changes found

            <...and so on and so forth until I disabled it>
            Jenkins has repeatedly triggered a CI run on the same Perforce changelist every time it polls. The job is set to poll every two minutes and trigger a new CI run per every new change list. Currently, it keeps running a new job every two minutes even though there are no new changelists committed to the repository. You can tell in the logs below that it stops updating the "current" CL at build 182 and keeps finding changes (408326+), even though they've already been tested (and passed).

            Here's a breakdown of what's going on...

            ///////////////////////////
            // Perforce Checkins
            ///////////////////////////

            405702 4/2/2015 @ 11:17PM
            408326 4/3/2015 @ 10:49AM
            408455 4/3/2015 @ 11:22AM


            ///////////////////////////
            // Jenkins Build Setup:
            ///////////////////////////

            Build Triggers
            ----------------
            -Poll SCM:
                H/2 * * * * (every two minutes)
                
            SCM
            ----------------
            -Workspace Behavior
                Static
            -Populate Options:
                Sync Only
                Populate have list checked
            -Polling Build Filters:
                "Polling per change" enabled


            ///////////////////////////
            // Polling Logs
            ///////////////////////////

            =========
            BUILD 180
            =========
            Started on Apr 2, 2015 11:04:00 PM
            Polling SCM changes on worker_node_001
            P4: Polling on: worker_node_001 with:my_project_build
            Connected to node: worker_node_001
            Connected to server: perforce01.XXX.com:3666
            Connected to client: my_project_build
            P4: Polling with label/change: 404445,now
            listing changes: //my_project_build/...@404445,now
            ... found change: 405641
            next change: 405641
            Done. Took 1.9 sec
            Changes found

            =========
            BUILD 181
            =========
            Started on Apr 2, 2015 11:18:00 PM
            Polling SCM changes on worker_node_001
            P4: Polling on: worker_node_001 with:my_project_build
            Connected to node: worker_node_001
            Connected to server: perforce01.XXX.com:3666
            Connected to client: my_project_build
            P4: Polling with label/change: 405641,now
            listing changes: //my_project_build/...@405641,now
            ... found change: 405702
            next change: 405702
            Done. Took 1.4 sec
            Changes found

            =========
            BUILD 182
            =========
            Started on Apr 3, 2015 10:50:01 AM
            Polling SCM changes on worker_node_001
            P4: Polling on: worker_node_001 with:my_project_build
            Connected to node: worker_node_001
            Connected to server: perforce01.XXX.com:3666
            Connected to client: my_project_build
            P4: Polling with label/change: 405702,now
            listing changes: //my_project_build/...@405702,now
            ... found change: 408326
            next change: 408326
            Done. Took 2.1 sec
            Changes found


            =========
            BUILD 183
            =========
            Started on Apr 3, 2015 10:52:01 AM
            Polling SCM changes on worker_node_001
            P4: Polling on: worker_node_001 with:my_project_build
            Connected to node: worker_node_001
            Connected to server: perforce01.XXX.com:3666
            Connected to client: my_project_build
            P4: Polling with label/change: 405702,now
            listing changes: //my_project_build_build/...@405702,now
            ... found change: 408326
            next change: 408326
            Done. Took 1.7 sec
            Changes found

            <...kept doing this every two minutes...>

            <I disabled it and updated P4 Plugin from 1.1.2 to 1.2.1>

            =========
            BUILD 193
            =========
            Started on Apr 3, 2015 11:28:00 AM
            Polling SCM changes on worker_node_001
            P4: Polling on: worker_node_001 with:my_project_build
            Connected to node: worker_node_001
            Connected to server: perforce01.XXX.com:3666
            Connected to client: my_project_build
            P4: Polling with label/change: 405702,now
            listing changes: //my_project_build/...@405702,now
            ... found change: 408455
            ... found change: 408326
            next change: 408326
            Done. Took 3.2 sec
            Changes found

            =========
            BUILD 194
            =========
            Started on Apr 3, 2015 11:30:00 AM
            Polling SCM changes on worker_node_001
            P4: Polling on: worker_node_001 with:my_project_build
            Connected to node: worker_node_001
            Connected to server: perforce01.XXX.com:3666
            Connected to client: my_project_build
            P4: Polling with label/change: 405702,now
            listing changes: //my_project_build/...@405702,now
            ... found change: 408455
            ... found change: 408326
            next change: 408326
            Done. Took 2.1 sec
            Changes found

            <...and so on and so forth until I disabled it>
            mhulth Martin Hulth made changes -
            Attachment build.xml [ 29475 ]
            Attachment changelog.xml [ 29476 ]
            Attachment log [ 29477 ]
            Attachment polling.log [ 29478 ]
            dantran dan tran made changes -
            Link This issue is related to JENKINS-29235 [ JENKINS-29235 ]
            p4paul Paul Allen made changes -
            Status Open [ 1 ] Closed [ 6 ]
            Resolution Fixed [ 1 ]
            rtyler R. Tyler Croy made changes -
            Workflow JNJira [ 162134 ] JNJira + In-Review [ 208599 ]

              People

              • Assignee:
                p4paul Paul Allen
                Reporter:
                mhulth Martin Hulth
              • Votes:
                2 Vote for this issue
                Watchers:
                5 Start watching this issue

                Dates

                • Created:
                  Updated:
                  Resolved: