Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Disabling scheduling for Pipeline Material still schedules pipeline (sometimes) #9870

Open
badbort opened this issue Nov 12, 2021 · 15 comments

Comments

@badbort
Copy link

badbort commented Nov 12, 2021

Bug Report
Summary

I'm encountering issues where a pipeline runs in response to a commit as expected, but triggers dependencies despite those defining this pipeline as "do not schedule".

This pipeline contains common configuration and settings files and is I do not wish for it to trigger anything except the 'artifacting' pipeline.

image

In the above image, the offending pipeline here is Configuration. This pipeline merely artifacts some settings files from a git repo.

As you can see in the image, I have disabled the scheduling for the next pipeline. We'll call that pipeline 'Project-A'. Project-A has a git material called Client1.

The bug I'm reporting is Configuration still triggers Project-A, but the behaviour is not consistent. There are many pipelines just like Project-A but for different projects and clients - just different content files. Some of these pipelines aren't triggered.

Other things to note about the pipeline setup:

  • Many projects are contained in this Client1 repo, and there is a pipeline for each. e.g. Project-B, ect..
  • Due to the above, each pipeline has its own content folder in the git repo, and the pipelines have a whitelist to match changes in their associated folder in Client1.
    image

The following image is a real world example of Configuration it triggering many pipelines and in all cases it has had scheduling disabled. I regularly have to play whack-a-mole:
image

The workaround I will be attempting for now was mentioned in another thread; remove the pipeline material and reference the git repo directly, with a blacklist that matches all files: */

What I think is happening is after Configuration completes it checks all dependent pipelines. This check on Project-A does not appear to obey the whitelist for Client1. Commits to Client1 do get filtered through the whitelist, but Configuration does not appear to.

I may abandon this intermediate artifacting pipeline if a direct git reference approach doesn't exhibit this triggering behaviour - so we will see.

@chadlwilson
Copy link
Member

Are you able to clarify how you are confident that the reason for the trigger is due to Configuration completing? i.e If you look on the dashboard do you see the Pipeline material for Condfiguration is Yellow/highlighted (this is the changed material) but the other materials are not?

image

Or the equivalent (subtle, undocumented) "information" icon on the stage details for one of these ones you cancelled:

image

Also, side note - which GoCD version is this? It looks older.

@badbort
Copy link
Author

badbort commented Nov 12, 2021

Are you able to clarify how you are confident that the reason for the trigger is due to Configuration completing

Pretty certain. It's part of my procedure to check and swat pipelines after I commit to the Configuration repo.

The ClientN repositories contain 3d content which don't change that often. They are also config repos and contain .gocd.yaml pipelines. The whitelists just have 3d content filters

image
image
image

Also, side note - which GoCD version is this? It looks older.

Ahh yes, had some trouble going through the upgrade process.
GoCD Version: 20.4.0 (11749-5553066d29d315951efa5ead0c087374df6338b9).

@chadlwilson
Copy link
Member

chadlwilson commented Nov 12, 2021

Yes, that 20.5.0 upgrade is a tougher one due to the db migrations.

I'm not aware of a specific bug that might have been fixed, but I am curious about the use of the type: configrepo syntactic sugar from the yaml plugin.

It's also detecting a change in that same repo. Would that change have normally passed the whitelist? (noting that you have tried to disable auto_updates on that repo/material)

I think to figure out what is going on we might need to look at server logs when the Configuration pipeline completes to get more clues.

@arvindsv
Copy link
Member

I wasn't able to reproduce this. Not sure I have the setup right.

2021_11_12_21-15-26

2021_11_12_21-15-39

The above trigger happened properly on a commit to repo.2. A commit to repo.1 did not trigger the downstream pipeline. Might need a bit of your help to come up with a simplified case which reproduces your scenario.

This is the config I used:

pipelines:
  upstream-pipeline-1:
    group: test
    materials:
      mygit:
        git: /tmp/repo.1
    stages:
      - stage1:
          jobs:
            job1:
              tasks:
               - exec:
                   command: echo
  downstream-pipeline-1:
    group: test
    materials:
      mygit:
        git: /tmp/repo.2
      upstream:
        ignore_for_scheduling: true
        pipeline: upstream-pipeline-1
        stage: stage1
    stages:
      - stage1a:
          jobs:
            job1a:
              tasks:
               - exec:
                   command: echo
                   arguments:
                    - "hello"

I see Chad noted the fact that you use type: configrepo. I'll try to set something up using that and see.

@arvindsv
Copy link
Member

arvindsv commented Nov 12, 2021

Taking notes as I try this.

1. Everything is stable:

2021_11_12_21-24-54

test.gocd.yaml in the config repo looks like this:
pipelines:
  upstream-pipeline-1:
    group: test
    materials:
      mygit:
        git: /tmp/repo.1
    stages:
      - stage1:
          jobs:
            job1:
              tasks:
               - exec:
                   command: echo
  downstream-pipeline-1:
    group: test
    materials:
      models:
        type: configrepo
        shallow_clone: true
        auto_update: false
        whitelist:
          - downstream/down.gocd.yaml
      upstream:
        ignore_for_scheduling: true
        pipeline: upstream-pipeline-1
        stage: stage1
    stages:
      - stage1a:
          jobs:
            job1a:
              tasks:
               - exec:
                   command: echo
                   arguments:
                    - "hello"
downstream/down.gocd.yaml in the config repo looks like this:
pipelines:
  test:
    group: test
    materials:
      mygit:
        git: /tmp/repo.2
    stages:
      - stage1:
          jobs:
            job1:
              tasks:
               - exec:
                   command: echo

2. A commit to repo.2

I expect only the test pipeline triggers.

2021_11_12_21-27-21

That went as expected:

2021_11_12_21-28-23

Everything stable again.

3. A commit to repo.1

I expect only upstream-pipeline-1 to trigger and then stop (not triggering downstream-pipeline-1).

2021_11_12_21-30-26

Yes, that's fine. Everything stable again.

4. A commit to the config repo itself - but not to an allow-listed file

I expected it to trigger nothing, due to the allow-list.

2021_11_12_21-34-23

But it triggered the downstream pipeline. Is that the bug you're seeing?

5. A commit to the config repo itself - but to an allow-listed file

I expect it to trigger the downstream-pipeline-1.

2021_11_12_21-38-59

Yes, that's correct.

2021_11_12_21-39-05

6. Another commit to a non-allow-listed file (like in step 4)

I thought that the allow-list might now take effect. And it did. Nothing was triggered.

2021_11_12_21-40-13


So, if step 4 is a reproduction of the bug, then the fact that the upstream pipeline has a "waiting" run which did not trigger a downstream pipeline line might somehow be causing it to trigger the pipeline, when a related material has an allow-list which should have prevented it from triggering.

Meaning: In step 4, the downstream pipeline should be checked against two materials (git commit with a non-allow-listed file changed, and a pipeline with an upstream run available but not allowed to be used due to the ignore_for_scheduling flag set to true. In this case, the pipeline should not be triggered. But is.

@arvindsv
Copy link
Member

arvindsv commented Nov 12, 2021

If that is really what is happening ... I'm not sure I know enough about that part of the code to fix it for sure. But, is adding a second stage to upstream-pipeline-1 an option? In your case, a second stage to Configuration pipeline. Then the downstreams could depend on that second stage instead. That way, you control when you want to downstreams of Configuration to run, by triggering that manual gate.

2021_11_12_21-54-48

In my tests, once I got a green "Stage 2" (just the one time), the bug shown in Step 4 earlier did not happen. The allowlist worked properly.

test.gocd.yaml in the config repo looks like this:
pipelines:
  upstream-pipeline-1:
    group: test
    materials:
      mygit:
        git: /tmp/repo.1
    stages:
      - stage1:
          jobs:
            job1:
              tasks:
               - exec:
                   command: echo
      - stage2:
          approval:
            type: manual
          jobs:
            job1:
              tasks:
               - exec:
                   command: echo

  downstream-pipeline-1:
    group: test
    materials:
      models:
        type: configrepo
        shallow_clone: true
        auto_update: false
        whitelist:
          - downstream/down.gocd.yaml
      upstream:
        pipeline: upstream-pipeline-1
        stage: stage2
    stages:
      - stage1a:
          jobs:
            job1a:
              tasks:
               - exec:
                   command: echo
                   arguments:
                    - "hello"

@badbort
Copy link
Author

badbort commented Nov 15, 2021

arvindsv I think the steps you are missing occur after 6. That is, you must commit to the configrepo with non-allow-listed file changes, and then you are in a situation where upstream pipeline runs will trigger the downstream.
7) commit changes to /tmp/repo.1.

I have reproduced the issue with the latest gocd server, and simplified the pipelines so there is no config repos or yaml pipelines. Server Version: GoCD Version: 21.3.0 (13067-4c4bb4780eb0d3fc4cacfc4cfcc0b07e2eaf0595).

See the following triggered pipeline:
image

Full server config added to bottom of post, and the git repos are public so feel free to commit. Setup looks like:

image

Test pipeline materials: (note the whitelist targeting txt files in the Proj1 directory)

<materials>
  <git url="https://github.com/badbort/gocd-bug1.git" invertFilter="true">
    <filter>
      <ignore pattern="Proj1/**/*.txt" />
    </filter>
  </git>
  <pipeline pipelineName="Config" stageName="Config" ignoreForScheduling="true" materialName="Config" />
</materials>

Repos involved:
SharedRepo: https://github.com/badbort/gocd-bug1-shared.git
ProjRepo: https://github.com/badbort/gocd-bug1.git

Now do the following:

  1. Make changes to Proj1/input.txt in ProjRepo and push. This should schedule the pipeline as we expect.
  2. Change a text file in SharedRepo and push the changes
  3. The Config pipeline is scheduled. When it completes it won't trigger our proj 1 pipeline. This is what we expect.
  4. Make changes to Proj2/input.txt and push. This doesn't trigger pipeline as expected.
  5. Change a text file in SharedRepo again and push
  6. Note how our Test pipeline gets scheduled.

See below for pipeline run that was triggered by above steps.

28VSMRevision: 948aacd2798e0bf2527425a1ff3f80e784ceb69e
15 Nov, 2021 at 14:25:03 Local Time
Triggered by changes

I have had another confusing issue which the reverse happened: made a commit to SharedRepo, and then after I changed Proj2/input.txt. This proj2 change also triggered the pipeline. May be worth ignoring this scenario though as it just complicates things.

Server XML
<?xml version="1.0" encoding="utf-8"?>
<cruise xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:noNamespaceSchemaLocation="cruise-config.xsd" schemaVersion="139">
  <server agentAutoRegisterKey="d4326f3c-eb92-4faa-80dd-3b396c258a3e" webhookSecret="df7c6c0f-6b1a-46cf-a6bb-0db3a30592ef" serverId="13f0fe7e-3117-4612-8900-c996ba677476" tokenGenerationKey="e9d54d9c-d58c-4b19-86cd-e15a134d57de">
    <backup emailOnSuccess="true" emailOnFailure="true" />
    <artifacts>
      <artifactsDir>artifacts</artifactsDir>
    </artifacts>
  </server>
  <pipelines group="defaultGroup">
    <pipeline name="Config">
      <materials>
        <git url="https://github.com/badbort/gocd-bug1-shared.git" branch="main" />
      </materials>
      <stage name="Config">
        <jobs>
          <job name="Config">
            <tasks>
              <exec command="echo">
                <arg>Hello</arg>
              </exec>
            </tasks>
            <artifacts>
              <artifact type="build" src="*.txt" dest="Config" />
            </artifacts>
          </job>
        </jobs>
      </stage>
    </pipeline>
    <pipeline name="Test">
      <materials>
        <git url="https://github.com/badbort/gocd-bug1.git" invertFilter="true">
          <filter>
            <ignore pattern="Proj1/**/*.txt" />
          </filter>
        </git>
        <pipeline pipelineName="Config" stageName="Config" ignoreForScheduling="true" materialName="Config" />
      </materials>
      <stage name="Test">
        <jobs>
          <job name="Test">
            <tasks>
              <exec command="echo">
                <arg>123</arg>
              </exec>
            </tasks>
          </job>
        </jobs>
      </stage>
    </pipeline>
  </pipelines>
</cruise>
2021-11-15 14:06:33,158 DEBUG [144@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from GitMaterial{url=https://github.com/badbort/gocd-bug1-shared.git, branch='main', shallowClone=false, submoduleFolder='null'} in flyweight 79d5e38a-5b23-4629-a5dd-f85ba1a0388c
2021-11-15 14:06:33,158 DEBUG [143@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from GitMaterial{url=https://github.com/badbort/gocd-bug1.git, branch='master', shallowClone=false, submoduleFolder='null'} in flyweight 840e3dfc-eac9-4309-832d-8a2dba9794a2
2021-11-15 14:06:33,191 TRACE [144@MessageListener for MaterialUpdateListener] GitMaterial:422 - Current repository url of [pipelines\flyweight\79d5e38a-5b23-4629-a5dd-f85ba1a0388c]: https://github.com/badbort/gocd-bug1-shared.git
2021-11-15 14:06:33,192 TRACE [144@MessageListener for MaterialUpdateListener] GitMaterial:423 - Target repository url: https://github.com/badbort/gocd-bug1-shared.git
2021-11-15 14:06:33,192 TRACE [143@MessageListener for MaterialUpdateListener] GitMaterial:422 - Current repository url of [pipelines\flyweight\840e3dfc-eac9-4309-832d-8a2dba9794a2]: https://github.com/badbort/gocd-bug1.git
2021-11-15 14:06:33,192 TRACE [143@MessageListener for MaterialUpdateListener] GitMaterial:423 - Target repository url: https://github.com/badbort/gocd-bug1.git
2021-11-15 14:06:33,262 DEBUG [144@MessageListener for MaterialUpdateListener] GitCommand:577 - Fetching changes
2021-11-15 14:06:33,263 DEBUG [143@MessageListener for MaterialUpdateListener] GitCommand:577 - Fetching changes
2021-11-15 14:06:34,094 DEBUG [144@MessageListener for MaterialUpdateListener] GitCommand:577 - Performing git gc
2021-11-15 14:06:34,115 DEBUG [143@MessageListener for MaterialUpdateListener] GitCommand:577 - Performing git gc
2021-11-15 14:06:34,163 DEBUG [144@MessageListener for MaterialUpdateListener] ScmMaterialUpdater:58 - [Material Update] Did not find any new modifications for material 'GitMaterial{url=https://github.com/badbort/gocd-bug1-shared.git, branch='main', shallowClone=false, submoduleFolder='null'}' with flyweight '713694d5dd172ebc3b01284ea95555c02f36fc72f8419555bdc2f6af67821408' using working directory 'C:\Program Files (x86)\Go Server\pipelines\flyweight\79d5e38a-5b23-4629-a5dd-f85ba1a0388c'
2021-11-15 14:06:34,184 DEBUG [143@MessageListener for MaterialUpdateListener] ScmMaterialUpdater:58 - [Material Update] Did not find any new modifications for material 'GitMaterial{url=https://github.com/badbort/gocd-bug1.git, branch='master', shallowClone=false, submoduleFolder='null'}' with flyweight '47ef00bfc9bba8b4b58a4a78c25767f169459baa9aff03d3b56fee162746f373' using working directory 'C:\Program Files (x86)\Go Server\pipelines\flyweight\840e3dfc-eac9-4309-832d-8a2dba9794a2'
2021-11-15 14:08:03,161 DEBUG [145@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from GitMaterial{url=https://github.com/badbort/gocd-bug1.git, branch='master', shallowClone=false, submoduleFolder='null'} in flyweight 840e3dfc-eac9-4309-832d-8a2dba9794a2
2021-11-15 14:08:03,161 DEBUG [146@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from GitMaterial{url=https://github.com/badbort/gocd-bug1-shared.git, branch='main', shallowClone=false, submoduleFolder='null'} in flyweight 79d5e38a-5b23-4629-a5dd-f85ba1a0388c
2021-11-15 14:08:03,196 TRACE [145@MessageListener for MaterialUpdateListener] GitMaterial:422 - Current repository url of [pipelines\flyweight\840e3dfc-eac9-4309-832d-8a2dba9794a2]: https://github.com/badbort/gocd-bug1.git
2021-11-15 14:08:03,196 TRACE [145@MessageListener for MaterialUpdateListener] GitMaterial:423 - Target repository url: https://github.com/badbort/gocd-bug1.git
2021-11-15 14:08:03,196 TRACE [146@MessageListener for MaterialUpdateListener] GitMaterial:422 - Current repository url of [pipelines\flyweight\79d5e38a-5b23-4629-a5dd-f85ba1a0388c]: https://github.com/badbort/gocd-bug1-shared.git
2021-11-15 14:08:03,196 TRACE [146@MessageListener for MaterialUpdateListener] GitMaterial:423 - Target repository url: https://github.com/badbort/gocd-bug1-shared.git
2021-11-15 14:08:03,265 DEBUG [145@MessageListener for MaterialUpdateListener] GitCommand:577 - Fetching changes
2021-11-15 14:08:03,266 DEBUG [146@MessageListener for MaterialUpdateListener] GitCommand:577 - Fetching changes
2021-11-15 14:08:04,095 DEBUG [145@MessageListener for MaterialUpdateListener] GitCommand:577 - Performing git gc
2021-11-15 14:08:04,123 DEBUG [146@MessageListener for MaterialUpdateListener] GitCommand:577 - Performing git gc
2021-11-15 14:08:04,161 DEBUG [145@MessageListener for MaterialUpdateListener] ScmMaterialUpdater:58 - [Material Update] Did not find any new modifications for material 'GitMaterial{url=https://github.com/badbort/gocd-bug1.git, branch='master', shallowClone=false, submoduleFolder='null'}' with flyweight '47ef00bfc9bba8b4b58a4a78c25767f169459baa9aff03d3b56fee162746f373' using working directory 'C:\Program Files (x86)\Go Server\pipelines\flyweight\840e3dfc-eac9-4309-832d-8a2dba9794a2'
2021-11-15 14:08:04,189 DEBUG [146@MessageListener for MaterialUpdateListener] ScmMaterialUpdater:58 - [Material Update] Did not find any new modifications for material 'GitMaterial{url=https://github.com/badbort/gocd-bug1-shared.git, branch='main', shallowClone=false, submoduleFolder='null'}' with flyweight '713694d5dd172ebc3b01284ea95555c02f36fc72f8419555bdc2f6af67821408' using working directory 'C:\Program Files (x86)\Go Server\pipelines\flyweight\79d5e38a-5b23-4629-a5dd-f85ba1a0388c'
2021-11-15 14:09:33,163 DEBUG [148@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from GitMaterial{url=https://github.com/badbort/gocd-bug1-shared.git, branch='main', shallowClone=false, submoduleFolder='null'} in flyweight 79d5e38a-5b23-4629-a5dd-f85ba1a0388c
2021-11-15 14:09:33,164 DEBUG [147@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from GitMaterial{url=https://github.com/badbort/gocd-bug1.git, branch='master', shallowClone=false, submoduleFolder='null'} in flyweight 840e3dfc-eac9-4309-832d-8a2dba9794a2
2021-11-15 14:09:33,200 TRACE [148@MessageListener for MaterialUpdateListener] GitMaterial:422 - Current repository url of [pipelines\flyweight\79d5e38a-5b23-4629-a5dd-f85ba1a0388c]: https://github.com/badbort/gocd-bug1-shared.git
2021-11-15 14:09:33,200 TRACE [148@MessageListener for MaterialUpdateListener] GitMaterial:423 - Target repository url: https://github.com/badbort/gocd-bug1-shared.git
2021-11-15 14:09:33,201 TRACE [147@MessageListener for MaterialUpdateListener] GitMaterial:422 - Current repository url of [pipelines\flyweight\840e3dfc-eac9-4309-832d-8a2dba9794a2]: https://github.com/badbort/gocd-bug1.git
2021-11-15 14:09:33,201 TRACE [147@MessageListener for MaterialUpdateListener] GitMaterial:423 - Target repository url: https://github.com/badbort/gocd-bug1.git
2021-11-15 14:09:33,270 DEBUG [147@MessageListener for MaterialUpdateListener] GitCommand:577 - Fetching changes
2021-11-15 14:09:33,271 DEBUG [148@MessageListener for MaterialUpdateListener] GitCommand:577 - Fetching changes
2021-11-15 14:09:34,229 DEBUG [148@MessageListener for MaterialUpdateListener] GitCommand:577 - Performing git gc
2021-11-15 14:09:34,297 DEBUG [148@MessageListener for MaterialUpdateListener] ScmMaterialUpdater:58 - [Material Update] Did not find any new modifications for material 'GitMaterial{url=https://github.com/badbort/gocd-bug1-shared.git, branch='main', shallowClone=false, submoduleFolder='null'}' with flyweight '713694d5dd172ebc3b01284ea95555c02f36fc72f8419555bdc2f6af67821408' using working directory 'C:\Program Files (x86)\Go Server\pipelines\flyweight\79d5e38a-5b23-4629-a5dd-f85ba1a0388c'
2021-11-15 14:09:34,449 DEBUG [147@MessageListener for MaterialUpdateListener] GitCommand:577 - Performing git gc
2021-11-15 14:09:34,548 INFO  [147@MessageListener for MaterialUpdateListener] ScmMaterialUpdater:60 - [Material Update] Found '1' modifications for material 'GitMaterial{url=https://github.com/badbort/gocd-bug1.git, branch='master', shallowClone=false, submoduleFolder='null'}' with flyweight '47ef00bfc9bba8b4b58a4a78c25767f169459baa9aff03d3b56fee162746f373' using working directory 'C:\Program Files (x86)\Go Server\pipelines\flyweight\840e3dfc-eac9-4309-832d-8a2dba9794a2'
2021-11-15 14:09:43,396 INFO  [ThreadPoolTaskScheduler-10] ScheduleService:160 - [Pipeline Schedule] Scheduling pipeline Test with build cause [ModificationBuildCause: modified by Alek ]
2021-11-15 14:09:43,400 INFO  [ThreadPoolTaskScheduler-10] PipelineRepository:78 - Start updating pipeline timeline
2021-11-15 14:09:43,402 INFO  [ThreadPoolTaskScheduler-10] PipelineRepository:84 - Pipeline timeline updated
2021-11-15 14:09:44,254 INFO  [120@MessageListener for WorkFinder] BuildAssignmentService:190 - [Agent Assignment] Assigned job [JobIdentifier[Test, 25, 25, Test, 1, Proj1, 101]] to agent [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e]]
2021-11-15 14:09:54,272 INFO  [qtp523417241-43] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status [Preparing] for [Build [Test/25/Test/1/Proj1/101]]
2021-11-15 14:09:59,033 INFO  [qtp523417241-37] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status [Building] for [Build [Test/25/Test/1/Proj1/101]]
2021-11-15 14:09:59,065 INFO  [qtp523417241-38] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting result [Passed] for [Build [Test/25/Test/1/Proj1/101]]
2021-11-15 14:09:59,075 INFO  [qtp523417241-42] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status [Completing] for [Build [Test/25/Test/1/Proj1/101]]
2021-11-15 14:09:59,084 INFO  [qtp523417241-55] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status and result [Completed, Passed] for [Build [Test/25/Test/1/Proj1/101]]
2021-11-15 14:09:59,087 INFO  [qtp523417241-55] Stage:236 - Stage is being completed by transition id: 286
2021-11-15 14:11:03,166 DEBUG [139@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from GitMaterial{url=https://github.com/badbort/gocd-bug1.git, branch='master', shallowClone=false, submoduleFolder='null'} in flyweight 840e3dfc-eac9-4309-832d-8a2dba9794a2
2021-11-15 14:11:03,166 DEBUG [140@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from GitMaterial{url=https://github.com/badbort/gocd-bug1-shared.git, branch='main', shallowClone=false, submoduleFolder='null'} in flyweight 79d5e38a-5b23-4629-a5dd-f85ba1a0388c
2021-11-15 14:11:03,200 TRACE [140@MessageListener for MaterialUpdateListener] GitMaterial:422 - Current repository url of [pipelines\flyweight\79d5e38a-5b23-4629-a5dd-f85ba1a0388c]: https://github.com/badbort/gocd-bug1-shared.git
2021-11-15 14:11:03,200 TRACE [140@MessageListener for MaterialUpdateListener] GitMaterial:423 - Target repository url: https://github.com/badbort/gocd-bug1-shared.git
2021-11-15 14:11:03,200 TRACE [139@MessageListener for MaterialUpdateListener] GitMaterial:422 - Current repository url of [pipelines\flyweight\840e3dfc-eac9-4309-832d-8a2dba9794a2]: https://github.com/badbort/gocd-bug1.git
2021-11-15 14:11:03,200 TRACE [139@MessageListener for MaterialUpdateListener] GitMaterial:423 - Target repository url: https://github.com/badbort/gocd-bug1.git
2021-11-15 14:11:03,269 DEBUG [140@MessageListener for MaterialUpdateListener] GitCommand:577 - Fetching changes
2021-11-15 14:11:03,270 DEBUG [139@MessageListener for MaterialUpdateListener] GitCommand:577 - Fetching changes
2021-11-15 14:11:04,079 DEBUG [140@MessageListener for MaterialUpdateListener] GitCommand:577 - Performing git gc
2021-11-15 14:11:04,085 DEBUG [139@MessageListener for MaterialUpdateListener] GitCommand:577 - Performing git gc
2021-11-15 14:11:04,145 DEBUG [140@MessageListener for MaterialUpdateListener] ScmMaterialUpdater:58 - [Material Update] Did not find any new modifications for material 'GitMaterial{url=https://github.com/badbort/gocd-bug1-shared.git, branch='main', shallowClone=false, submoduleFolder='null'}' with flyweight '713694d5dd172ebc3b01284ea95555c02f36fc72f8419555bdc2f6af67821408' using working directory 'C:\Program Files (x86)\Go Server\pipelines\flyweight\79d5e38a-5b23-4629-a5dd-f85ba1a0388c'
2021-11-15 14:11:04,150 DEBUG [139@MessageListener for MaterialUpdateListener] ScmMaterialUpdater:58 - [Material Update] Did not find any new modifications for material 'GitMaterial{url=https://github.com/badbort/gocd-bug1.git, branch='master', shallowClone=false, submoduleFolder='null'}' with flyweight '47ef00bfc9bba8b4b58a4a78c25767f169459baa9aff03d3b56fee162746f373' using working directory 'C:\Program Files (x86)\Go Server\pipelines\flyweight\840e3dfc-eac9-4309-832d-8a2dba9794a2'
2021-11-15 14:12:33,168 DEBUG [142@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from GitMaterial{url=https://github.com/badbort/gocd-bug1-shared.git, branch='main', shallowClone=false, submoduleFolder='null'} in flyweight 79d5e38a-5b23-4629-a5dd-f85ba1a0388c
2021-11-15 14:12:33,168 DEBUG [141@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from GitMaterial{url=https://github.com/badbort/gocd-bug1.git, branch='master', shallowClone=false, submoduleFolder='null'} in flyweight 840e3dfc-eac9-4309-832d-8a2dba9794a2
2021-11-15 14:12:33,202 TRACE [142@MessageListener for MaterialUpdateListener] GitMaterial:422 - Current repository url of [pipelines\flyweight\79d5e38a-5b23-4629-a5dd-f85ba1a0388c]: https://github.com/badbort/gocd-bug1-shared.git
2021-11-15 14:12:33,202 TRACE [142@MessageListener for MaterialUpdateListener] GitMaterial:423 - Target repository url: https://github.com/badbort/gocd-bug1-shared.git
2021-11-15 14:12:33,202 TRACE [141@MessageListener for MaterialUpdateListener] GitMaterial:422 - Current repository url of [pipelines\flyweight\840e3dfc-eac9-4309-832d-8a2dba9794a2]: https://github.com/badbort/gocd-bug1.git
2021-11-15 14:12:33,202 TRACE [141@MessageListener for MaterialUpdateListener] GitMaterial:423 - Target repository url: https://github.com/badbort/gocd-bug1.git
2021-11-15 14:12:33,270 DEBUG [142@MessageListener for MaterialUpdateListener] GitCommand:577 - Fetching changes
2021-11-15 14:12:33,271 DEBUG [141@MessageListener for MaterialUpdateListener] GitCommand:577 - Fetching changes
2021-11-15 14:12:34,075 DEBUG [142@MessageListener for MaterialUpdateListener] GitCommand:577 - Performing git gc
2021-11-15 14:12:34,109 DEBUG [141@MessageListener for MaterialUpdateListener] GitCommand:577 - Performing git gc
2021-11-15 14:12:34,145 DEBUG [142@MessageListener for MaterialUpdateListener] ScmMaterialUpdater:58 - [Material Update] Did not find any new modifications for material 'GitMaterial{url=https://github.com/badbort/gocd-bug1-shared.git, branch='main', shallowClone=false, submoduleFolder='null'}' with flyweight '713694d5dd172ebc3b01284ea95555c02f36fc72f8419555bdc2f6af67821408' using working directory 'C:\Program Files (x86)\Go Server\pipelines\flyweight\79d5e38a-5b23-4629-a5dd-f85ba1a0388c'
2021-11-15 14:12:34,177 DEBUG [141@MessageListener for MaterialUpdateListener] ScmMaterialUpdater:58 - [Material Update] Did not find any new modifications for material 'GitMaterial{url=https://github.com/badbort/gocd-bug1.git, branch='master', shallowClone=false, submoduleFolder='null'}' with flyweight '47ef00bfc9bba8b4b58a4a78c25767f169459baa9aff03d3b56fee162746f373' using working directory 'C:\Program Files (x86)\Go Server\pipelines\flyweight\840e3dfc-eac9-4309-832d-8a2dba9794a2'
2021-11-15 14:14:03,170 DEBUG [144@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from GitMaterial{url=https://github.com/badbort/gocd-bug1-shared.git, branch='main', shallowClone=false, submoduleFolder='null'} in flyweight 79d5e38a-5b23-4629-a5dd-f85ba1a0388c
2021-11-15 14:14:03,170 DEBUG [143@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from GitMaterial{url=https://github.com/badbort/gocd-bug1.git, branch='master', shallowClone=false, submoduleFolder='null'} in flyweight 840e3dfc-eac9-4309-832d-8a2dba9794a2
2021-11-15 14:14:03,203 TRACE [143@MessageListener for MaterialUpdateListener] GitMaterial:422 - Current repository url of [pipelines\flyweight\840e3dfc-eac9-4309-832d-8a2dba9794a2]: https://github.com/badbort/gocd-bug1.git
2021-11-15 14:14:03,203 TRACE [144@MessageListener for MaterialUpdateListener] GitMaterial:422 - Current repository url of [pipelines\flyweight\79d5e38a-5b23-4629-a5dd-f85ba1a0388c]: https://github.com/badbort/gocd-bug1-shared.git
2021-11-15 14:14:03,203 TRACE [143@MessageListener for MaterialUpdateListener] GitMaterial:423 - Target repository url: https://github.com/badbort/gocd-bug1.git
2021-11-15 14:14:03,203 TRACE [144@MessageListener for MaterialUpdateListener] GitMaterial:423 - Target repository url: https://github.com/badbort/gocd-bug1-shared.git
2021-11-15 14:14:03,272 DEBUG [143@MessageListener for MaterialUpdateListener] GitCommand:577 - Fetching changes
2021-11-15 14:14:03,273 DEBUG [144@MessageListener for MaterialUpdateListener] GitCommand:577 - Fetching changes
2021-11-15 14:14:04,047 DEBUG [143@MessageListener for MaterialUpdateListener] GitCommand:577 - Performing git gc
2021-11-15 14:14:04,113 DEBUG [143@MessageListener for MaterialUpdateListener] ScmMaterialUpdater:58 - [Material Update] Did not find any new modifications for material 'GitMaterial{url=https://github.com/badbort/gocd-bug1.git, branch='master', shallowClone=false, submoduleFolder='null'}' with flyweight '47ef00bfc9bba8b4b58a4a78c25767f169459baa9aff03d3b56fee162746f373' using working directory 'C:\Program Files (x86)\Go Server\pipelines\flyweight\840e3dfc-eac9-4309-832d-8a2dba9794a2'
2021-11-15 14:14:04,345 DEBUG [144@MessageListener for MaterialUpdateListener] GitCommand:577 - Performing git gc
2021-11-15 14:14:04,445 INFO  [144@MessageListener for MaterialUpdateListener] ScmMaterialUpdater:60 - [Material Update] Found '1' modifications for material 'GitMaterial{url=https://github.com/badbort/gocd-bug1-shared.git, branch='main', shallowClone=false, submoduleFolder='null'}' with flyweight '713694d5dd172ebc3b01284ea95555c02f36fc72f8419555bdc2f6af67821408' using working directory 'C:\Program Files (x86)\Go Server\pipelines\flyweight\79d5e38a-5b23-4629-a5dd-f85ba1a0388c'
2021-11-15 14:14:13,655 INFO  [ThreadPoolTaskScheduler-5] ScheduleService:160 - [Pipeline Schedule] Scheduling pipeline Config with build cause [ModificationBuildCause: modified by Alek ]
2021-11-15 14:14:13,659 INFO  [ThreadPoolTaskScheduler-5] PipelineRepository:78 - Start updating pipeline timeline
2021-11-15 14:14:13,661 INFO  [ThreadPoolTaskScheduler-5] PipelineRepository:84 - Pipeline timeline updated
2021-11-15 14:14:19,459 INFO  [120@MessageListener for WorkFinder] BuildAssignmentService:190 - [Agent Assignment] Assigned job [JobIdentifier[Config, 7, 7, Config, 1, Config, 102]] to agent [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e]]
2021-11-15 14:14:29,469 INFO  [qtp523417241-55] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status [Preparing] for [Build [Config/7/Config/1/Config/102]]
2021-11-15 14:14:32,172 INFO  [qtp523417241-43] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status [Building] for [Build [Config/7/Config/1/Config/102]]
2021-11-15 14:14:32,203 INFO  [qtp523417241-478] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting result [Passed] for [Build [Config/7/Config/1/Config/102]]
2021-11-15 14:14:32,214 INFO  [qtp523417241-38] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status [Completing] for [Build [Config/7/Config/1/Config/102]]
2021-11-15 14:14:32,244 INFO  [qtp523417241-598] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status and result [Completed, Passed] for [Build [Config/7/Config/1/Config/102]]
2021-11-15 14:14:32,247 INFO  [qtp523417241-598] Stage:236 - Stage is being completed by transition id: 292
2021-11-15 14:14:32,250 DEBUG [153@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from DependencyMaterial{pipelineName='Config', stageName='Config'} in flyweight 28289bad-1410-4c2d-8028-dcc7b44c5213
2021-11-15 14:14:32,251 INFO  [153@MessageListener for MaterialUpdateListener] MaterialRepository:435 - Saving revision [DependencyMaterial{pipelineName='Config', stageName='Config'}, DependencyMaterialRevision[Config/7/Config/1] [pipelineLabel = 7];[Last Modified: 2021/11/15 14:14:32
Revision: Config/7/Config/1
UserName: null
EmailAddress: null
Comment: null
PipelineLabel: 7
]]
2021-11-15 14:15:33,172 DEBUG [146@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from GitMaterial{url=https://github.com/badbort/gocd-bug1-shared.git, branch='main', shallowClone=false, submoduleFolder='null'} in flyweight 79d5e38a-5b23-4629-a5dd-f85ba1a0388c
2021-11-15 14:15:33,172 DEBUG [145@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from GitMaterial{url=https://github.com/badbort/gocd-bug1.git, branch='master', shallowClone=false, submoduleFolder='null'} in flyweight 840e3dfc-eac9-4309-832d-8a2dba9794a2
2021-11-15 14:15:33,206 TRACE [146@MessageListener for MaterialUpdateListener] GitMaterial:422 - Current repository url of [pipelines\flyweight\79d5e38a-5b23-4629-a5dd-f85ba1a0388c]: https://github.com/badbort/gocd-bug1-shared.git
2021-11-15 14:15:33,206 TRACE [146@MessageListener for MaterialUpdateListener] GitMaterial:423 - Target repository url: https://github.com/badbort/gocd-bug1-shared.git
2021-11-15 14:15:33,207 TRACE [145@MessageListener for MaterialUpdateListener] GitMaterial:422 - Current repository url of [pipelines\flyweight\840e3dfc-eac9-4309-832d-8a2dba9794a2]: https://github.com/badbort/gocd-bug1.git
2021-11-15 14:15:33,207 TRACE [145@MessageListener for MaterialUpdateListener] GitMaterial:423 - Target repository url: https://github.com/badbort/gocd-bug1.git
2021-11-15 14:15:33,277 DEBUG [145@MessageListener for MaterialUpdateListener] GitCommand:577 - Fetching changes
2021-11-15 14:15:33,277 DEBUG [146@MessageListener for MaterialUpdateListener] GitCommand:577 - Fetching changes
2021-11-15 14:15:34,089 DEBUG [146@MessageListener for MaterialUpdateListener] GitCommand:577 - Performing git gc
2021-11-15 14:15:34,097 DEBUG [145@MessageListener for MaterialUpdateListener] GitCommand:577 - Performing git gc
2021-11-15 14:15:34,154 DEBUG [146@MessageListener for MaterialUpdateListener] ScmMaterialUpdater:58 - [Material Update] Did not find any new modifications for material 'GitMaterial{url=https://github.com/badbort/gocd-bug1-shared.git, branch='main', shallowClone=false, submoduleFolder='null'}' with flyweight '713694d5dd172ebc3b01284ea95555c02f36fc72f8419555bdc2f6af67821408' using working directory 'C:\Program Files (x86)\Go Server\pipelines\flyweight\79d5e38a-5b23-4629-a5dd-f85ba1a0388c'
2021-11-15 14:15:34,162 DEBUG [145@MessageListener for MaterialUpdateListener] ScmMaterialUpdater:58 - [Material Update] Did not find any new modifications for material 'GitMaterial{url=https://github.com/badbort/gocd-bug1.git, branch='master', shallowClone=false, submoduleFolder='null'}' with flyweight '47ef00bfc9bba8b4b58a4a78c25767f169459baa9aff03d3b56fee162746f373' using working directory 'C:\Program Files (x86)\Go Server\pipelines\flyweight\840e3dfc-eac9-4309-832d-8a2dba9794a2'
2021-11-15 14:17:03,176 DEBUG [148@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from GitMaterial{url=https://github.com/badbort/gocd-bug1-shared.git, branch='main', shallowClone=false, submoduleFolder='null'} in flyweight 79d5e38a-5b23-4629-a5dd-f85ba1a0388c
2021-11-15 14:17:03,177 DEBUG [147@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from GitMaterial{url=https://github.com/badbort/gocd-bug1.git, branch='master', shallowClone=false, submoduleFolder='null'} in flyweight 840e3dfc-eac9-4309-832d-8a2dba9794a2
2021-11-15 14:17:03,210 TRACE [148@MessageListener for MaterialUpdateListener] GitMaterial:422 - Current repository url of [pipelines\flyweight\79d5e38a-5b23-4629-a5dd-f85ba1a0388c]: https://github.com/badbort/gocd-bug1-shared.git
2021-11-15 14:17:03,210 TRACE [148@MessageListener for MaterialUpdateListener] GitMaterial:423 - Target repository url: https://github.com/badbort/gocd-bug1-shared.git
2021-11-15 14:17:03,210 TRACE [147@MessageListener for MaterialUpdateListener] GitMaterial:422 - Current repository url of [pipelines\flyweight\840e3dfc-eac9-4309-832d-8a2dba9794a2]: https://github.com/badbort/gocd-bug1.git
2021-11-15 14:17:03,210 TRACE [147@MessageListener for MaterialUpdateListener] GitMaterial:423 - Target repository url: https://github.com/badbort/gocd-bug1.git
2021-11-15 14:17:03,282 DEBUG [148@MessageListener for MaterialUpdateListener] GitCommand:577 - Fetching changes
2021-11-15 14:17:03,282 DEBUG [147@MessageListener for MaterialUpdateListener] GitCommand:577 - Fetching changes
2021-11-15 14:17:04,377 DEBUG [147@MessageListener for MaterialUpdateListener] GitCommand:577 - Performing git gc
2021-11-15 14:17:04,379 DEBUG [148@MessageListener for MaterialUpdateListener] GitCommand:577 - Performing git gc
2021-11-15 14:17:04,486 INFO  [148@MessageListener for MaterialUpdateListener] ScmMaterialUpdater:60 - [Material Update] Found '1' modifications for material 'GitMaterial{url=https://github.com/badbort/gocd-bug1-shared.git, branch='main', shallowClone=false, submoduleFolder='null'}' with flyweight '713694d5dd172ebc3b01284ea95555c02f36fc72f8419555bdc2f6af67821408' using working directory 'C:\Program Files (x86)\Go Server\pipelines\flyweight\79d5e38a-5b23-4629-a5dd-f85ba1a0388c'
2021-11-15 14:17:04,486 INFO  [147@MessageListener for MaterialUpdateListener] ScmMaterialUpdater:60 - [Material Update] Found '1' modifications for material 'GitMaterial{url=https://github.com/badbort/gocd-bug1.git, branch='master', shallowClone=false, submoduleFolder='null'}' with flyweight '47ef00bfc9bba8b4b58a4a78c25767f169459baa9aff03d3b56fee162746f373' using working directory 'C:\Program Files (x86)\Go Server\pipelines\flyweight\840e3dfc-eac9-4309-832d-8a2dba9794a2'
2021-11-15 14:17:13,839 INFO  [ThreadPoolTaskScheduler-4] ScheduleService:160 - [Pipeline Schedule] Scheduling pipeline Config with build cause [ModificationBuildCause: modified by Alek ]
2021-11-15 14:17:13,842 INFO  [ThreadPoolTaskScheduler-4] PipelineRepository:78 - Start updating pipeline timeline
2021-11-15 14:17:13,844 INFO  [ThreadPoolTaskScheduler-4] PipelineRepository:84 - Pipeline timeline updated
2021-11-15 14:17:13,847 INFO  [ThreadPoolTaskScheduler-4] ScheduleService:160 - [Pipeline Schedule] Scheduling pipeline Test with build cause [ModificationBuildCause: modified by Alek ]
2021-11-15 14:17:13,851 INFO  [ThreadPoolTaskScheduler-4] PipelineRepository:78 - Start updating pipeline timeline
2021-11-15 14:17:13,852 INFO  [ThreadPoolTaskScheduler-4] PipelineRepository:84 - Pipeline timeline updated
2021-11-15 14:17:22,487 INFO  [120@MessageListener for WorkFinder] BuildAssignmentService:190 - [Agent Assignment] Assigned job [JobIdentifier[Config, 8, 8, Config, 1, Config, 103]] to agent [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e]]
2021-11-15 14:17:32,516 INFO  [qtp523417241-680] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status [Preparing] for [Build [Config/8/Config/1/Config/103]]
2021-11-15 14:17:35,170 INFO  [qtp523417241-55] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status [Building] for [Build [Config/8/Config/1/Config/103]]
2021-11-15 14:17:35,200 INFO  [qtp523417241-478] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting result [Passed] for [Build [Config/8/Config/1/Config/103]]
2021-11-15 14:17:35,209 INFO  [qtp523417241-43] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status [Completing] for [Build [Config/8/Config/1/Config/103]]
2021-11-15 14:17:35,241 INFO  [qtp523417241-625] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status and result [Completed, Passed] for [Build [Config/8/Config/1/Config/103]]
2021-11-15 14:17:35,243 INFO  [qtp523417241-625] Stage:236 - Stage is being completed by transition id: 299
2021-11-15 14:17:35,245 DEBUG [154@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from DependencyMaterial{pipelineName='Config', stageName='Config'} in flyweight 28289bad-1410-4c2d-8028-dcc7b44c5213
2021-11-15 14:17:35,246 INFO  [154@MessageListener for MaterialUpdateListener] MaterialRepository:435 - Saving revision [DependencyMaterial{pipelineName='Config', stageName='Config'}, DependencyMaterialRevision[Config/8/Config/1] [pipelineLabel = 8];[Last Modified: 2021/11/15 14:17:35
Revision: Config/8/Config/1
UserName: null
EmailAddress: null
Comment: null
PipelineLabel: 8
]]
2021-11-15 14:17:45,258 INFO  [120@MessageListener for WorkFinder] BuildAssignmentService:190 - [Agent Assignment] Assigned job [JobIdentifier[Test, 26, 26, Test, 1, Proj1, 104]] to agent [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e]]
2021-11-15 14:17:55,282 INFO  [qtp523417241-625] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status [Preparing] for [Build [Test/26/Test/1/Proj1/104]]
2021-11-15 14:17:59,917 INFO  [qtp523417241-598] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status [Building] for [Build [Test/26/Test/1/Proj1/104]]
2021-11-15 14:17:59,948 INFO  [qtp523417241-309] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting result [Passed] for [Build [Test/26/Test/1/Proj1/104]]
2021-11-15 14:17:59,958 INFO  [qtp523417241-771] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status [Completing] for [Build [Test/26/Test/1/Proj1/104]]
2021-11-15 14:17:59,967 INFO  [qtp523417241-44] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status and result [Completed, Passed] for [Build [Test/26/Test/1/Proj1/104]]
2021-11-15 14:17:59,970 INFO  [qtp523417241-44] Stage:236 - Stage is being completed by transition id: 304
2021-11-15 14:18:33,178 DEBUG [139@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from GitMaterial{url=https://github.com/badbort/gocd-bug1.git, branch='master', shallowClone=false, submoduleFolder='null'} in flyweight 840e3dfc-eac9-4309-832d-8a2dba9794a2
2021-11-15 14:18:33,178 DEBUG [140@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from GitMaterial{url=https://github.com/badbort/gocd-bug1-shared.git, branch='main', shallowClone=false, submoduleFolder='null'} in flyweight 79d5e38a-5b23-4629-a5dd-f85ba1a0388c
2021-11-15 14:18:33,211 TRACE [139@MessageListener for MaterialUpdateListener] GitMaterial:422 - Current repository url of [pipelines\flyweight\840e3dfc-eac9-4309-832d-8a2dba9794a2]: https://github.com/badbort/gocd-bug1.git
2021-11-15 14:18:33,211 TRACE [139@MessageListener for MaterialUpdateListener] GitMaterial:423 - Target repository url: https://github.com/badbort/gocd-bug1.git
2021-11-15 14:18:33,212 TRACE [140@MessageListener for MaterialUpdateListener] GitMaterial:422 - Current repository url of [pipelines\flyweight\79d5e38a-5b23-4629-a5dd-f85ba1a0388c]: https://github.com/badbort/gocd-bug1-shared.git
2021-11-15 14:18:33,212 TRACE [140@MessageListener for MaterialUpdateListener] GitMaterial:423 - Target repository url: https://github.com/badbort/gocd-bug1-shared.git
2021-11-15 14:18:33,280 DEBUG [139@MessageListener for MaterialUpdateListener] GitCommand:577 - Fetching changes
2021-11-15 14:18:33,281 DEBUG [140@MessageListener for MaterialUpdateListener] GitCommand:577 - Fetching changes
2021-11-15 14:18:34,084 DEBUG [139@MessageListener for MaterialUpdateListener] GitCommand:577 - Performing git gc
2021-11-15 14:18:34,096 DEBUG [140@MessageListener for MaterialUpdateListener] GitCommand:577 - Performing git gc
2021-11-15 14:18:34,148 DEBUG [139@MessageListener for MaterialUpdateListener] ScmMaterialUpdater:58 - [Material Update] Did not find any new modifications for material 'GitMaterial{url=https://github.com/badbort/gocd-bug1.git, branch='master', shallowClone=false, submoduleFolder='null'}' with flyweight '47ef00bfc9bba8b4b58a4a78c25767f169459baa9aff03d3b56fee162746f373' using working directory 'C:\Program Files (x86)\Go Server\pipelines\flyweight\840e3dfc-eac9-4309-832d-8a2dba9794a2'
2021-11-15 14:18:34,160 DEBUG [140@MessageListener for MaterialUpdateListener] ScmMaterialUpdater:58 - [Material Update] Did not find any new modifications for material 'GitMaterial{url=https://github.com/badbort/gocd-bug1-shared.git, branch='main', shallowClone=false, submoduleFolder='null'}' with flyweight '713694d5dd172ebc3b01284ea95555c02f36fc72f8419555bdc2f6af67821408' using working directory 'C:\Program Files (x86)\Go Server\pipelines\flyweight\79d5e38a-5b23-4629-a5dd-f85ba1a0388c'
2021-11-15 14:20:03,180 DEBUG [142@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from GitMaterial{url=https://github.com/badbort/gocd-bug1-shared.git, branch='main', shallowClone=false, submoduleFolder='null'} in flyweight 79d5e38a-5b23-4629-a5dd-f85ba1a0388c
2021-11-15 14:20:03,180 DEBUG [141@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from GitMaterial{url=https://github.com/badbort/gocd-bug1.git, branch='master', shallowClone=false, submoduleFolder='null'} in flyweight 840e3dfc-eac9-4309-832d-8a2dba9794a2
2021-11-15 14:20:03,215 TRACE [142@MessageListener for MaterialUpdateListener] GitMaterial:422 - Current repository url of [pipelines\flyweight\79d5e38a-5b23-4629-a5dd-f85ba1a0388c]: https://github.com/badbort/gocd-bug1-shared.git
2021-11-15 14:20:03,215 TRACE [141@MessageListener for MaterialUpdateListener] GitMaterial:422 - Current repository url of [pipelines\flyweight\840e3dfc-eac9-4309-832d-8a2dba9794a2]: https://github.com/badbort/gocd-bug1.git
2021-11-15 14:20:03,215 TRACE [142@MessageListener for MaterialUpdateListener] GitMaterial:423 - Target repository url: https://github.com/badbort/gocd-bug1-shared.git
2021-11-15 14:20:03,215 TRACE [141@MessageListener for MaterialUpdateListener] GitMaterial:423 - Target repository url: https://github.com/badbort/gocd-bug1.git
2021-11-15 14:20:03,283 DEBUG [141@MessageListener for MaterialUpdateListener] GitCommand:577 - Fetching changes
2021-11-15 14:20:03,285 DEBUG [142@MessageListener for MaterialUpdateListener] GitCommand:577 - Fetching changes
2021-11-15 14:20:04,118 DEBUG [142@MessageListener for MaterialUpdateListener] GitCommand:577 - Performing git gc
2021-11-15 14:20:04,183 DEBUG [142@MessageListener for MaterialUpdateListener] ScmMaterialUpdater:58 - [Material Update] Did not find any new modifications for material 'GitMaterial{url=https://github.com/badbort/gocd-bug1-shared.git, branch='main', shallowClone=false, submoduleFolder='null'}' with flyweight '713694d5dd172ebc3b01284ea95555c02f36fc72f8419555bdc2f6af67821408' using working directory 'C:\Program Files (x86)\Go Server\pipelines\flyweight\79d5e38a-5b23-4629-a5dd-f85ba1a0388c'
2021-11-15 14:20:04,434 DEBUG [141@MessageListener for MaterialUpdateListener] GitCommand:577 - Performing git gc
2021-11-15 14:20:04,532 INFO  [141@MessageListener for MaterialUpdateListener] ScmMaterialUpdater:60 - [Material Update] Found '1' modifications for material 'GitMaterial{url=https://github.com/badbort/gocd-bug1.git, branch='master', shallowClone=false, submoduleFolder='null'}' with flyweight '47ef00bfc9bba8b4b58a4a78c25767f169459baa9aff03d3b56fee162746f373' using working directory 'C:\Program Files (x86)\Go Server\pipelines\flyweight\840e3dfc-eac9-4309-832d-8a2dba9794a2'
2021-11-15 14:20:14,239 INFO  [ThreadPoolTaskScheduler-5] ScheduleService:160 - [Pipeline Schedule] Scheduling pipeline Test with build cause [ModificationBuildCause: modified by Alek ]
2021-11-15 14:20:14,242 INFO  [ThreadPoolTaskScheduler-5] PipelineRepository:78 - Start updating pipeline timeline
2021-11-15 14:20:14,244 INFO  [ThreadPoolTaskScheduler-5] PipelineRepository:84 - Pipeline timeline updated
2021-11-15 14:20:20,177 INFO  [120@MessageListener for WorkFinder] BuildAssignmentService:190 - [Agent Assignment] Assigned job [JobIdentifier[Test, 27, 27, Test, 1, Proj1, 105]] to agent [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e]]
2021-11-15 14:20:30,207 INFO  [qtp523417241-37] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status [Preparing] for [Build [Test/27/Test/1/Proj1/105]]
2021-11-15 14:20:34,895 INFO  [qtp523417241-511] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status [Building] for [Build [Test/27/Test/1/Proj1/105]]
2021-11-15 14:20:34,926 INFO  [qtp523417241-43] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting result [Passed] for [Build [Test/27/Test/1/Proj1/105]]
2021-11-15 14:20:34,937 INFO  [qtp523417241-480] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status [Completing] for [Build [Test/27/Test/1/Proj1/105]]
2021-11-15 14:20:34,947 INFO  [qtp523417241-625] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status and result [Completed, Passed] for [Build [Test/27/Test/1/Proj1/105]]
2021-11-15 14:20:34,950 INFO  [qtp523417241-625] Stage:236 - Stage is being completed by transition id: 310
2021-11-15 14:21:33,182 DEBUG [143@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from GitMaterial{url=https://github.com/badbort/gocd-bug1.git, branch='master', shallowClone=false, submoduleFolder='null'} in flyweight 840e3dfc-eac9-4309-832d-8a2dba9794a2
2021-11-15 14:21:33,182 DEBUG [144@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from GitMaterial{url=https://github.com/badbort/gocd-bug1-shared.git, branch='main', shallowClone=false, submoduleFolder='null'} in flyweight 79d5e38a-5b23-4629-a5dd-f85ba1a0388c
2021-11-15 14:21:33,215 TRACE [144@MessageListener for MaterialUpdateListener] GitMaterial:422 - Current repository url of [pipelines\flyweight\79d5e38a-5b23-4629-a5dd-f85ba1a0388c]: https://github.com/badbort/gocd-bug1-shared.git
2021-11-15 14:21:33,215 TRACE [144@MessageListener for MaterialUpdateListener] GitMaterial:423 - Target repository url: https://github.com/badbort/gocd-bug1-shared.git
2021-11-15 14:21:33,215 TRACE [143@MessageListener for MaterialUpdateListener] GitMaterial:422 - Current repository url of [pipelines\flyweight\840e3dfc-eac9-4309-832d-8a2dba9794a2]: https://github.com/badbort/gocd-bug1.git
2021-11-15 14:21:33,215 TRACE [143@MessageListener for MaterialUpdateListener] GitMaterial:423 - Target repository url: https://github.com/badbort/gocd-bug1.git
2021-11-15 14:21:33,283 DEBUG [144@MessageListener for MaterialUpdateListener] GitCommand:577 - Fetching changes
2021-11-15 14:21:33,283 DEBUG [143@MessageListener for MaterialUpdateListener] GitCommand:577 - Fetching changes
2021-11-15 14:21:34,047 DEBUG [144@MessageListener for MaterialUpdateListener] GitCommand:577 - Performing git gc
2021-11-15 14:21:34,112 DEBUG [144@MessageListener for MaterialUpdateListener] ScmMaterialUpdater:58 - [Material Update] Did not find any new modifications for material 'GitMaterial{url=https://github.com/badbort/gocd-bug1-shared.git, branch='main', shallowClone=false, submoduleFolder='null'}' with flyweight '713694d5dd172ebc3b01284ea95555c02f36fc72f8419555bdc2f6af67821408' using working directory 'C:\Program Files (x86)\Go Server\pipelines\flyweight\79d5e38a-5b23-4629-a5dd-f85ba1a0388c'
2021-11-15 14:21:34,350 DEBUG [143@MessageListener for MaterialUpdateListener] GitCommand:577 - Performing git gc
2021-11-15 14:21:34,447 INFO  [143@MessageListener for MaterialUpdateListener] ScmMaterialUpdater:60 - [Material Update] Found '1' modifications for material 'GitMaterial{url=https://github.com/badbort/gocd-bug1.git, branch='master', shallowClone=false, submoduleFolder='null'}' with flyweight '47ef00bfc9bba8b4b58a4a78c25767f169459baa9aff03d3b56fee162746f373' using working directory 'C:\Program Files (x86)\Go Server\pipelines\flyweight\840e3dfc-eac9-4309-832d-8a2dba9794a2'
2021-11-15 14:23:03,185 DEBUG [146@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from GitMaterial{url=https://github.com/badbort/gocd-bug1-shared.git, branch='main', shallowClone=false, submoduleFolder='null'} in flyweight 79d5e38a-5b23-4629-a5dd-f85ba1a0388c
2021-11-15 14:23:03,185 DEBUG [145@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from GitMaterial{url=https://github.com/badbort/gocd-bug1.git, branch='master', shallowClone=false, submoduleFolder='null'} in flyweight 840e3dfc-eac9-4309-832d-8a2dba9794a2
2021-11-15 14:23:03,218 TRACE [146@MessageListener for MaterialUpdateListener] GitMaterial:422 - Current repository url of [pipelines\flyweight\79d5e38a-5b23-4629-a5dd-f85ba1a0388c]: https://github.com/badbort/gocd-bug1-shared.git
2021-11-15 14:23:03,218 TRACE [146@MessageListener for MaterialUpdateListener] GitMaterial:423 - Target repository url: https://github.com/badbort/gocd-bug1-shared.git
2021-11-15 14:23:03,220 TRACE [145@MessageListener for MaterialUpdateListener] GitMaterial:422 - Current repository url of [pipelines\flyweight\840e3dfc-eac9-4309-832d-8a2dba9794a2]: https://github.com/badbort/gocd-bug1.git
2021-11-15 14:23:03,220 TRACE [145@MessageListener for MaterialUpdateListener] GitMaterial:423 - Target repository url: https://github.com/badbort/gocd-bug1.git
2021-11-15 14:23:03,291 DEBUG [145@MessageListener for MaterialUpdateListener] GitCommand:577 - Fetching changes
2021-11-15 14:23:03,291 DEBUG [146@MessageListener for MaterialUpdateListener] GitCommand:577 - Fetching changes
2021-11-15 14:23:04,059 DEBUG [146@MessageListener for MaterialUpdateListener] GitCommand:577 - Performing git gc
2021-11-15 14:23:04,125 DEBUG [146@MessageListener for MaterialUpdateListener] ScmMaterialUpdater:58 - [Material Update] Did not find any new modifications for material 'GitMaterial{url=https://github.com/badbort/gocd-bug1-shared.git, branch='main', shallowClone=false, submoduleFolder='null'}' with flyweight '713694d5dd172ebc3b01284ea95555c02f36fc72f8419555bdc2f6af67821408' using working directory 'C:\Program Files (x86)\Go Server\pipelines\flyweight\79d5e38a-5b23-4629-a5dd-f85ba1a0388c'
2021-11-15 14:23:04,417 DEBUG [145@MessageListener for MaterialUpdateListener] GitCommand:577 - Performing git gc
2021-11-15 14:23:04,516 INFO  [145@MessageListener for MaterialUpdateListener] ScmMaterialUpdater:60 - [Material Update] Found '1' modifications for material 'GitMaterial{url=https://github.com/badbort/gocd-bug1.git, branch='master', shallowClone=false, submoduleFolder='null'}' with flyweight '47ef00bfc9bba8b4b58a4a78c25767f169459baa9aff03d3b56fee162746f373' using working directory 'C:\Program Files (x86)\Go Server\pipelines\flyweight\840e3dfc-eac9-4309-832d-8a2dba9794a2'
2021-11-15 14:24:33,187 DEBUG [147@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from GitMaterial{url=https://github.com/badbort/gocd-bug1.git, branch='master', shallowClone=false, submoduleFolder='null'} in flyweight 840e3dfc-eac9-4309-832d-8a2dba9794a2
2021-11-15 14:24:33,187 DEBUG [148@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from GitMaterial{url=https://github.com/badbort/gocd-bug1-shared.git, branch='main', shallowClone=false, submoduleFolder='null'} in flyweight 79d5e38a-5b23-4629-a5dd-f85ba1a0388c
2021-11-15 14:24:33,220 TRACE [147@MessageListener for MaterialUpdateListener] GitMaterial:422 - Current repository url of [pipelines\flyweight\840e3dfc-eac9-4309-832d-8a2dba9794a2]: https://github.com/badbort/gocd-bug1.git
2021-11-15 14:24:33,220 TRACE [147@MessageListener for MaterialUpdateListener] GitMaterial:423 - Target repository url: https://github.com/badbort/gocd-bug1.git
2021-11-15 14:24:33,221 TRACE [148@MessageListener for MaterialUpdateListener] GitMaterial:422 - Current repository url of [pipelines\flyweight\79d5e38a-5b23-4629-a5dd-f85ba1a0388c]: https://github.com/badbort/gocd-bug1-shared.git
2021-11-15 14:24:33,221 TRACE [148@MessageListener for MaterialUpdateListener] GitMaterial:423 - Target repository url: https://github.com/badbort/gocd-bug1-shared.git
2021-11-15 14:24:33,290 DEBUG [147@MessageListener for MaterialUpdateListener] GitCommand:577 - Fetching changes
2021-11-15 14:24:33,291 DEBUG [148@MessageListener for MaterialUpdateListener] GitCommand:577 - Fetching changes
2021-11-15 14:24:34,048 DEBUG [147@MessageListener for MaterialUpdateListener] GitCommand:577 - Performing git gc
2021-11-15 14:24:34,113 DEBUG [147@MessageListener for MaterialUpdateListener] ScmMaterialUpdater:58 - [Material Update] Did not find any new modifications for material 'GitMaterial{url=https://github.com/badbort/gocd-bug1.git, branch='master', shallowClone=false, submoduleFolder='null'}' with flyweight '47ef00bfc9bba8b4b58a4a78c25767f169459baa9aff03d3b56fee162746f373' using working directory 'C:\Program Files (x86)\Go Server\pipelines\flyweight\840e3dfc-eac9-4309-832d-8a2dba9794a2'
2021-11-15 14:24:34,360 DEBUG [148@MessageListener for MaterialUpdateListener] GitCommand:577 - Performing git gc
2021-11-15 14:24:34,463 INFO  [148@MessageListener for MaterialUpdateListener] ScmMaterialUpdater:60 - [Material Update] Found '1' modifications for material 'GitMaterial{url=https://github.com/badbort/gocd-bug1-shared.git, branch='main', shallowClone=false, submoduleFolder='null'}' with flyweight '713694d5dd172ebc3b01284ea95555c02f36fc72f8419555bdc2f6af67821408' using working directory 'C:\Program Files (x86)\Go Server\pipelines\flyweight\79d5e38a-5b23-4629-a5dd-f85ba1a0388c'
2021-11-15 14:24:43,479 INFO  [ThreadPoolTaskScheduler-1] ScheduleService:160 - [Pipeline Schedule] Scheduling pipeline Config with build cause [ModificationBuildCause: modified by Alek ]
2021-11-15 14:24:43,482 INFO  [ThreadPoolTaskScheduler-1] PipelineRepository:78 - Start updating pipeline timeline
2021-11-15 14:24:43,484 INFO  [ThreadPoolTaskScheduler-1] PipelineRepository:84 - Pipeline timeline updated
2021-11-15 14:24:45,287 INFO  [120@MessageListener for WorkFinder] BuildAssignmentService:190 - [Agent Assignment] Assigned job [JobIdentifier[Config, 9, 9, Config, 1, Config, 106]] to agent [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e]]
2021-11-15 14:24:55,312 INFO  [qtp523417241-480] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status [Preparing] for [Build [Config/9/Config/1/Config/106]]
2021-11-15 14:24:57,991 INFO  [qtp523417241-772] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status [Building] for [Build [Config/9/Config/1/Config/106]]
2021-11-15 14:24:58,022 INFO  [qtp523417241-55] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting result [Passed] for [Build [Config/9/Config/1/Config/106]]
2021-11-15 14:24:58,032 INFO  [qtp523417241-37] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status [Completing] for [Build [Config/9/Config/1/Config/106]]
2021-11-15 14:24:58,062 INFO  [qtp523417241-771] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status and result [Completed, Passed] for [Build [Config/9/Config/1/Config/106]]
2021-11-15 14:24:58,065 INFO  [qtp523417241-771] Stage:236 - Stage is being completed by transition id: 316
2021-11-15 14:24:58,068 DEBUG [155@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from DependencyMaterial{pipelineName='Config', stageName='Config'} in flyweight 28289bad-1410-4c2d-8028-dcc7b44c5213
2021-11-15 14:24:58,068 INFO  [155@MessageListener for MaterialUpdateListener] MaterialRepository:435 - Saving revision [DependencyMaterial{pipelineName='Config', stageName='Config'}, DependencyMaterialRevision[Config/9/Config/1] [pipelineLabel = 9];[Last Modified: 2021/11/15 14:24:58
Revision: Config/9/Config/1
UserName: null
EmailAddress: null
Comment: null
PipelineLabel: 9
]]
2021-11-15 14:25:03,503 INFO  [ThreadPoolTaskScheduler-7] ScheduleService:160 - [Pipeline Schedule] Scheduling pipeline Test with build cause [ModificationBuildCause: modified by Alek ]
2021-11-15 14:25:03,506 INFO  [ThreadPoolTaskScheduler-7] PipelineRepository:78 - Start updating pipeline timeline
2021-11-15 14:25:03,508 INFO  [ThreadPoolTaskScheduler-7] PipelineRepository:84 - Pipeline timeline updated
2021-11-15 14:25:08,086 INFO  [120@MessageListener for WorkFinder] BuildAssignmentService:190 - [Agent Assignment] Assigned job [JobIdentifier[Test, 28, 28, Test, 1, Proj1, 107]] to agent [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e]]
2021-11-15 14:25:18,108 INFO  [qtp523417241-598] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status [Preparing] for [Build [Test/28/Test/1/Proj1/107]]
2021-11-15 14:25:23,064 INFO  [qtp523417241-625] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status [Building] for [Build [Test/28/Test/1/Proj1/107]]
2021-11-15 14:25:23,094 INFO  [qtp523417241-598] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting result [Passed] for [Build [Test/28/Test/1/Proj1/107]]
2021-11-15 14:25:23,104 INFO  [qtp523417241-777] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status [Completing] for [Build [Test/28/Test/1/Proj1/107]]
2021-11-15 14:25:23,113 INFO  [qtp523417241-480] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status and result [Completed, Passed] for [Build [Test/28/Test/1/Proj1/107]]
2021-11-15 14:25:23,115 INFO  [qtp523417241-480] Stage:236 - Stage is being completed by transition id: 322
2021-11-15 14:26:03,190 DEBUG [140@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from GitMaterial{url=https://github.com/badbort/gocd-bug1-shared.git, branch='main', shallowClone=false, submoduleFolder='null'} in flyweight 79d5e38a-5b23-4629-a5dd-f85ba1a0388c
2021-11-15 14:26:03,190 DEBUG [139@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from GitMaterial{url=https://github.com/badbort/gocd-bug1.git, branch='master', shallowClone=false, submoduleFolder='null'} in flyweight 840e3dfc-eac9-4309-832d-8a2dba9794a2
2021-11-15 14:26:03,224 TRACE [139@MessageListener for MaterialUpdateListener] GitMaterial:422 - Current repository url of [pipelines\flyweight\840e3dfc-eac9-4309-832d-8a2dba9794a2]: https://github.com/badbort/gocd-bug1.git
2021-11-15 14:26:03,224 TRACE [140@MessageListener for MaterialUpdateListener] GitMaterial:422 - Current repository url of [pipelines\flyweight\79d5e38a-5b23-4629-a5dd-f85ba1a0388c]: https://github.com/badbort/gocd-bug1-shared.git
2021-11-15 14:26:03,224 TRACE [139@MessageListener for MaterialUpdateListener] GitMaterial:423 - Target repository url: https://github.com/badbort/gocd-bug1.git
2021-11-15 14:26:03,225 TRACE [140@MessageListener for MaterialUpdateListener] GitMaterial:423 - Target repository url: https://github.com/badbort/gocd-bug1-shared.git
2021-11-15 14:26:03,296 DEBUG [140@MessageListener for MaterialUpdateListener] GitCommand:577 - Fetching changes
2021-11-15 14:26:03,297 DEBUG [139@MessageListener for MaterialUpdateListener] GitCommand:577 - Fetching changes
2021-11-15 14:26:04,132 DEBUG [140@MessageListener for MaterialUpdateListener] GitCommand:577 - Performing git gc
2021-11-15 14:26:04,197 DEBUG [140@MessageListener for MaterialUpdateListener] ScmMaterialUpdater:58 - [Material Update] Did not find any new modifications for material 'GitMaterial{url=https://github.com/badbort/gocd-bug1-shared.git, branch='main', shallowClone=false, submoduleFolder='null'}' with flyweight '713694d5dd172ebc3b01284ea95555c02f36fc72f8419555bdc2f6af67821408' using working directory 'C:\Program Files (x86)\Go Server\pipelines\flyweight\79d5e38a-5b23-4629-a5dd-f85ba1a0388c'
2021-11-15 14:26:04,446 DEBUG [139@MessageListener for MaterialUpdateListener] GitCommand:577 - Performing git gc
2021-11-15 14:26:04,543 INFO  [139@MessageListener for MaterialUpdateListener] ScmMaterialUpdater:60 - [Material Update] Found '1' modifications for material 'GitMaterial{url=https://github.com/badbort/gocd-bug1.git, branch='master', shallowClone=false, submoduleFolder='null'}' with flyweight '47ef00bfc9bba8b4b58a4a78c25767f169459baa9aff03d3b56fee162746f373' using working directory 'C:\Program Files (x86)\Go Server\pipelines\flyweight\840e3dfc-eac9-4309-832d-8a2dba9794a2'
2021-11-15 14:27:33,193 DEBUG [142@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from GitMaterial{url=https://github.com/badbort/gocd-bug1-shared.git, branch='main', shallowClone=false, submoduleFolder='null'} in flyweight 79d5e38a-5b23-4629-a5dd-f85ba1a0388c
2021-11-15 14:27:33,193 DEBUG [141@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from GitMaterial{url=https://github.com/badbort/gocd-bug1.git, branch='master', shallowClone=false, submoduleFolder='null'} in flyweight 840e3dfc-eac9-4309-832d-8a2dba9794a2
2021-11-15 14:27:33,226 TRACE [142@MessageListener for MaterialUpdateListener] GitMaterial:422 - Current repository url of [pipelines\flyweight\79d5e38a-5b23-4629-a5dd-f85ba1a0388c]: https://github.com/badbort/gocd-bug1-shared.git
2021-11-15 14:27:33,226 TRACE [142@MessageListener for MaterialUpdateListener] GitMaterial:423 - Target repository url: https://github.com/badbort/gocd-bug1-shared.git
2021-11-15 14:27:33,228 TRACE [141@MessageListener for MaterialUpdateListener] GitMaterial:422 - Current repository url of [pipelines\flyweight\840e3dfc-eac9-4309-832d-8a2dba9794a2]: https://github.com/badbort/gocd-bug1.git
2021-11-15 14:27:33,228 TRACE [141@MessageListener for MaterialUpdateListener] GitMaterial:423 - Target repository url: https://github.com/badbort/gocd-bug1.git
2021-11-15 14:27:33,297 DEBUG [141@MessageListener for MaterialUpdateListener] GitCommand:577 - Fetching changes
2021-11-15 14:27:33,297 DEBUG [142@MessageListener for MaterialUpdateListener] GitCommand:577 - Fetching changes
2021-11-15 14:27:34,095 DEBUG [141@MessageListener for MaterialUpdateListener] GitCommand:577 - Performing git gc
2021-11-15 14:27:34,158 DEBUG [141@MessageListener for MaterialUpdateListener] ScmMaterialUpdater:58 - [Material Update] Did not find any new modifications for material 'GitMaterial{url=https://github.com/badbort/gocd-bug1.git, branch='master', shallowClone=false, submoduleFolder='null'}' with flyweight '47ef00bfc9bba8b4b58a4a78c25767f169459baa9aff03d3b56fee162746f373' using working directory 'C:\Program Files (x86)\Go Server\pipelines\flyweight\840e3dfc-eac9-4309-832d-8a2dba9794a2'
2021-11-15 14:27:34,415 DEBUG [142@MessageListener for MaterialUpdateListener] GitCommand:577 - Performing git gc
2021-11-15 14:27:34,513 INFO  [142@MessageListener for MaterialUpdateListener] ScmMaterialUpdater:60 - [Material Update] Found '1' modifications for material 'GitMaterial{url=https://github.com/badbort/gocd-bug1-shared.git, branch='main', shallowClone=false, submoduleFolder='null'}' with flyweight '713694d5dd172ebc3b01284ea95555c02f36fc72f8419555bdc2f6af67821408' using working directory 'C:\Program Files (x86)\Go Server\pipelines\flyweight\79d5e38a-5b23-4629-a5dd-f85ba1a0388c'
2021-11-15 14:27:43,647 INFO  [ThreadPoolTaskScheduler-9] ScheduleService:160 - [Pipeline Schedule] Scheduling pipeline Config with build cause [ModificationBuildCause: modified by Alek ]
2021-11-15 14:27:43,650 INFO  [ThreadPoolTaskScheduler-9] PipelineRepository:78 - Start updating pipeline timeline
2021-11-15 14:27:43,651 INFO  [ThreadPoolTaskScheduler-9] PipelineRepository:84 - Pipeline timeline updated
2021-11-15 14:27:53,324 INFO  [120@MessageListener for WorkFinder] BuildAssignmentService:190 - [Agent Assignment] Assigned job [JobIdentifier[Config, 10, 10, Config, 1, Config, 108]] to agent [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e]]
2021-11-15 14:28:03,346 INFO  [qtp523417241-55] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status [Preparing] for [Build [Config/10/Config/1/Config/108]]
2021-11-15 14:28:06,063 INFO  [qtp523417241-772] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status [Building] for [Build [Config/10/Config/1/Config/108]]
2021-11-15 14:28:06,093 INFO  [qtp523417241-43] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting result [Passed] for [Build [Config/10/Config/1/Config/108]]
2021-11-15 14:28:06,102 INFO  [qtp523417241-511] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status [Completing] for [Build [Config/10/Config/1/Config/108]]
2021-11-15 14:28:06,133 INFO  [qtp523417241-774] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status and result [Completed, Passed] for [Build [Config/10/Config/1/Config/108]]
2021-11-15 14:28:06,135 INFO  [qtp523417241-774] Stage:236 - Stage is being completed by transition id: 328
2021-11-15 14:28:06,137 DEBUG [153@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from DependencyMaterial{pipelineName='Config', stageName='Config'} in flyweight 28289bad-1410-4c2d-8028-dcc7b44c5213
2021-11-15 14:28:06,138 INFO  [153@MessageListener for MaterialUpdateListener] MaterialRepository:435 - Saving revision [DependencyMaterial{pipelineName='Config', stageName='Config'}, DependencyMaterialRevision[Config/10/Config/1] [pipelineLabel = 10];[Last Modified: 2021/11/15 14:28:06
Revision: Config/10/Config/1
UserName: null
EmailAddress: null
Comment: null
PipelineLabel: 10
]]
2021-11-15 14:28:13,682 INFO  [ThreadPoolTaskScheduler-7] ScheduleService:160 - [Pipeline Schedule] Scheduling pipeline Test with build cause [ModificationBuildCause: modified by Alek ]
2021-11-15 14:28:13,685 INFO  [ThreadPoolTaskScheduler-7] PipelineRepository:78 - Start updating pipeline timeline
2021-11-15 14:28:13,686 INFO  [ThreadPoolTaskScheduler-7] PipelineRepository:84 - Pipeline timeline updated
2021-11-15 14:28:16,155 INFO  [120@MessageListener for WorkFinder] BuildAssignmentService:190 - [Agent Assignment] Assigned job [JobIdentifier[Test, 29, 29, Test, 1, Proj1, 109]] to agent [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e]]
2021-11-15 14:28:26,173 INFO  [qtp523417241-480] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status [Preparing] for [Build [Test/29/Test/1/Proj1/109]]
2021-11-15 14:28:30,903 INFO  [qtp523417241-625] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status [Building] for [Build [Test/29/Test/1/Proj1/109]]
2021-11-15 14:28:30,934 INFO  [qtp523417241-777] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting result [Passed] for [Build [Test/29/Test/1/Proj1/109]]
2021-11-15 14:28:30,944 INFO  [qtp523417241-55] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status [Completing] for [Build [Test/29/Test/1/Proj1/109]]
2021-11-15 14:28:30,952 INFO  [qtp523417241-680] BuildRepositoryRemoteImpl:99 - [Agent [Auriga, 127.0.0.1, a5ee166c-3389-4cb9-87ac-e43ef6698a1e, db56c967-d2b8-4c07-9431-10f108c44a16]] is reporting status and result [Completed, Passed] for [Build [Test/29/Test/1/Proj1/109]]
2021-11-15 14:28:30,954 INFO  [qtp523417241-680] Stage:236 - Stage is being completed by transition id: 334
2021-11-15 14:29:03,195 DEBUG [144@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from GitMaterial{url=https://github.com/badbort/gocd-bug1-shared.git, branch='main', shallowClone=false, submoduleFolder='null'} in flyweight 79d5e38a-5b23-4629-a5dd-f85ba1a0388c
2021-11-15 14:29:03,195 DEBUG [143@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from GitMaterial{url=https://github.com/badbort/gocd-bug1.git, branch='master', shallowClone=false, submoduleFolder='null'} in flyweight 840e3dfc-eac9-4309-832d-8a2dba9794a2
2021-11-15 14:29:03,229 TRACE [143@MessageListener for MaterialUpdateListener] GitMaterial:422 - Current repository url of [pipelines\flyweight\840e3dfc-eac9-4309-832d-8a2dba9794a2]: https://github.com/badbort/gocd-bug1.git
2021-11-15 14:29:03,229 TRACE [143@MessageListener for MaterialUpdateListener] GitMaterial:423 - Target repository url: https://github.com/badbort/gocd-bug1.git
2021-11-15 14:29:03,229 TRACE [144@MessageListener for MaterialUpdateListener] GitMaterial:422 - Current repository url of [pipelines\flyweight\79d5e38a-5b23-4629-a5dd-f85ba1a0388c]: https://github.com/badbort/gocd-bug1-shared.git
2021-11-15 14:29:03,229 TRACE [144@MessageListener for MaterialUpdateListener] GitMaterial:423 - Target repository url: https://github.com/badbort/gocd-bug1-shared.git
2021-11-15 14:29:03,297 DEBUG [143@MessageListener for MaterialUpdateListener] GitCommand:577 - Fetching changes
2021-11-15 14:29:03,299 DEBUG [144@MessageListener for MaterialUpdateListener] GitCommand:577 - Fetching changes
2021-11-15 14:29:04,102 DEBUG [143@MessageListener for MaterialUpdateListener] GitCommand:577 - Performing git gc
2021-11-15 14:29:04,122 DEBUG [144@MessageListener for MaterialUpdateListener] GitCommand:577 - Performing git gc
2021-11-15 14:29:04,168 DEBUG [143@MessageListener for MaterialUpdateListener] ScmMaterialUpdater:58 - [Material Update] Did not find any new modifications for material 'GitMaterial{url=https://github.com/badbort/gocd-bug1.git, branch='master', shallowClone=false, submoduleFolder='null'}' with flyweight '47ef00bfc9bba8b4b58a4a78c25767f169459baa9aff03d3b56fee162746f373' using working directory 'C:\Program Files (x86)\Go Server\pipelines\flyweight\840e3dfc-eac9-4309-832d-8a2dba9794a2'
2021-11-15 14:29:04,187 DEBUG [144@MessageListener for MaterialUpdateListener] ScmMaterialUpdater:58 - [Material Update] Did not find any new modifications for material 'GitMaterial{url=https://github.com/badbort/gocd-bug1-shared.git, branch='main', shallowClone=false, submoduleFolder='null'}' with flyweight '713694d5dd172ebc3b01284ea95555c02f36fc72f8419555bdc2f6af67821408' using working directory 'C:\Program Files (x86)\Go Server\pipelines\flyweight\79d5e38a-5b23-4629-a5dd-f85ba1a0388c'
2021-11-15 14:30:33,198 DEBUG [146@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from GitMaterial{url=https://github.com/badbort/gocd-bug1-shared.git, branch='main', shallowClone=false, submoduleFolder='null'} in flyweight 79d5e38a-5b23-4629-a5dd-f85ba1a0388c
2021-11-15 14:30:33,198 DEBUG [145@MessageListener for MaterialUpdateListener] MaterialDatabaseUpdater:102 - [Material Update] Existing material repository, fetching new revisions from GitMaterial{url=https://github.com/badbort/gocd-bug1.git, branch='master', shallowClone=false, submoduleFolder='null'} in flyweight 840e3dfc-eac9-4309-832d-8a2dba9794a2
2021-11-15 14:30:33,231 TRACE [146@MessageListener for MaterialUpdateListener] GitMaterial:422 - Current repository url of [pipelines\flyweight\79d5e38a-5b23-4629-a5dd-f85ba1a0388c]: https://github.com/badbort/gocd-bug1-shared.git
2021-11-15 14:30:33,231 TRACE [146@MessageListener for MaterialUpdateListener] GitMaterial:423 - Target repository url: https://github.com/badbort/gocd-bug1-shared.git
2021-11-15 14:30:33,232 TRACE [145@MessageListener for MaterialUpdateListener] GitMaterial:422 - Current repository url of [pipelines\flyweight\840e3dfc-eac9-4309-832d-8a2dba9794a2]: https://github.com/badbort/gocd-bug1.git
2021-11-15 14:30:33,232 TRACE [145@MessageListener for MaterialUpdateListener] GitMaterial:423 - Target repository url: https://github.com/badbort/gocd-bug1.git
2021-11-15 14:30:33,300 DEBUG [146@MessageListener for MaterialUpdateListener] GitCommand:577 - Fetching changes
2021-11-15 14:30:33,301 DEBUG [145@MessageListener for MaterialUpdateListener] GitCommand:577 - Fetching changes
2021-11-15 14:30:34,117 DEBUG [145@MessageListener for MaterialUpdateListener] GitCommand:577 - Performing git gc
2021-11-15 14:30:34,118 DEBUG [146@MessageListener for MaterialUpdateListener] GitCommand:577 - Performing git gc
2021-11-15 14:30:34,185 DEBUG [146@MessageListener for MaterialUpdateListener] ScmMaterialUpdater:58 - [Material Update] Did not find any new modifications for material 'GitMaterial{url=https://github.com/badbort/gocd-bug1-shared.git, branch='main', shallowClone=false, submoduleFolder='null'}' with flyweight '713694d5dd172ebc3b01284ea95555c02f36fc72f8419555bdc2f6af67821408' using working directory 'C:\Program Files (x86)\Go Server\pipelines\flyweight\79d5e38a-5b23-4629-a5dd-f85ba1a0388c'
2021-11-15 14:30:34,186 DEBUG [145@MessageListener for MaterialUpdateListener] ScmMaterialUpdater:58 - [Material Update] Did not find any new modifications for material 'GitMaterial{url=https://github.com/badbort/gocd-bug1.git, branch='master', shallowClone=false, submoduleFolder='null'}' with flyweight '47ef00bfc9bba8b4b58a4a78c25767f169459baa9aff03d3b56fee162746f373' using working directory 'C:\Program Files (x86)\Go Server\pipelines\flyweight\840e3dfc-eac9-4309-832d-8a2dba9794a2'
Server Log

@badbort
Copy link
Author

badbort commented Nov 15, 2021

Continuing from above post

You can continuously repeat the the process.

  1. Change Proj2/input.txt and push. Give some time for gocd server to poll - not sure if this is important
  2. Change a file in SharedRepo and push
    image
    Pipeline gets triggered above, and once completed triggers the downstream which should not be happening
    image

No changes to the Proj1 dir have been made at all.
image

I think you can repeat these steps endlessly. I've been giving gocd time in between commits just to make sure the server polls and is aware of commits.

Manually running Config pipeline also triggers pipeline

Steps:

  1. Make changes to Proj2/input.txt and push. I pause here to give time for gocd to poll and be aware of the commit
  2. Manually run the Config pipeline. There was a pause after it completed, but it did end up running the downstream pipeline.
    image
    image

@badbort
Copy link
Author

badbort commented Nov 15, 2021

Workaround

Seeing as I can reliably trigger the Test pipeline by only committing to SharedRepo and Proj2 folder in ProjRepo I wanted to see if the workaround approach will avoid these scheduling errors.

Setup:
image

Once again, push changes to Proj2/input.txt, wait, and then push changes to SharedRepo. Test pipeline eventually gets triggered, but the Test2GitMaterials pipeline is not triggered.

image

Pipeline:

<pipeline name="Test2GitMaterials">
      <materials>
        <git url="https://github.com/badbort/gocd-bug1.git" invertFilter="true" dest="Project">
          <filter>
            <ignore pattern="Proj1/**/*.txt" />
          </filter>
        </git>
        <git url="https://github.com/badbort/gocd-bug1-shared.git" branch="main" dest="Shared">
          <filter>
            <ignore pattern="**/*.*" />
          </filter>
        </git>
      </materials>
      <stage name="Test">
        <jobs>
          <job name="Proj1">
            <tasks>
              <exec command="echo">
                <arg>123</arg>
              </exec>
            </tasks>
          </job>
        </jobs>
      </stage>
    </pipeline>

@badbort
Copy link
Author

badbort commented Nov 18, 2021

Can you confirm you can reproduce the issue in the server xml define in this post? #9870 (comment)

I've put together the simplest scenario I can above

@arvindsv
Copy link
Member

@badbort Hello! I haven't tried that. But, I was able to reproduce it in Step 4 (in one of my previous comments). Is that wrong? Either way, as I mentioned, I agree that it's a bug. Tricky to reproduce but possible. I'm not sure exactly why it happens (in code, I mean).

Hopefully the workaround you mentioned, or the one I mentioned helps in this situation.

@badbort
Copy link
Author

badbort commented Nov 19, 2021

@arvindsv I think its similar scenario but slightly different. Root cause may be the same.

Tricky to reproduce but possible

The server xml config I've linked earlier reproduces the issue consistently and simplifies the configuration, removing yaml and config pipelines from the equation

Now do the following:

  1. Make changes to Proj1/input.txt in ProjRepo and push. This should schedule the pipeline as we expect.
  2. Change a text file in SharedRepo and push the changes
  3. The Config pipeline is scheduled. When it completes it won't trigger our proj 1 pipeline. This is what we expect.
  4. Make changes to Proj2/input.txt and push. This doesn't trigger pipeline as expected.
  5. Change a text file in SharedRepo again and push
  6. Note how our Test pipeline gets scheduled.

And following post discovered you can continue to reproduce this doing the following:

  1. Change Proj2/input.txt and push. Give some time for gocd server to poll - not sure if this is important
  2. Change a file in SharedRepo and push
    i.e. Repeat 7 and 8

Note: 8 can be replaced by manually running the upstream pipeline

For now I'm converting pipelines to use the workaround mentioned, so this is a low priority issue for me.

@stale
Copy link

stale bot commented May 25, 2022

This issue has been automatically marked as stale because it has not had activity in the last 90 days.
If you can still reproduce this error on the master branch using local development environment or on the latest GoCD Release, please reply with all of the information you have about it in order to keep the issue open.
Thank you for all your contributions.

@stale stale bot added the stale label May 25, 2022
@chadlwilson chadlwilson added no stalebot Don't mark this stale. and removed stale labels May 26, 2022
@bemica
Copy link

bemica commented Oct 18, 2023

Hi, I'm encountering this bug with version 22.2.0. Has there been any work done on this? The workaround mentioned isn't an option, as the pipeline concerned needs to depend on both an upstream pipeline and a git repo.

I'm not familiar with the GoCD codebase, but if there was some previous work done to build on, or even if someone could point me to the right place in the code to look at, I could spend some time trying a fix

@chadlwilson
Copy link
Member

I think it's probably safe to say that no work has been done to figure out what is happening here, or what the unintended consequences might be of trying to fix it 😅

Personally i haven't grokked the specific scenario enough to guess at where the issue might be, unfortunately.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Projects
None yet
Development

No branches or pull requests

4 participants