search

mozilla-services / updatebot

Automation for updating third party libraries for Firefox
Mozilla Public License 2.0
8 stars 5 forks source link

2022 05 13 fuzzy path #245

Closed tomrittervg closed 2 years ago

updatebot-ci commented 2 years ago 
Python 3.5 will no longer be supported in the next feature release of Poetry (1.2).
You should consider updating your Python version to a supported one.

Note that you will still be able to manage Python 3.5 projects by using the env command.
See https://python-poetry.org/docs/managing-environments/ for more information.

./apis/taskcluster.py:64:18: E261 at least two spaces before inline comment
updatebot-ci commented 2 years ago 
curl failed.  The response from Github was:
{
  "message": "Validation Failed",
  "errors": [
    {
      "resource": "IssueComment",
      "code": "unprocessable",
      "field": "data",
      "message": "Body is too long (maximum is 65536 characters)"
    }
  ],
  "documentation_url": "https://docs.github.com/rest/reference/issues#create-an-issue-comment"
}
updatebot-ci commented 2 years ago 
curl failed.  The response from Github was:
{
  "message": "Validation Failed",
  "errors": [
    {
      "resource": "IssueComment",
      "code": "unprocessable",
      "field": "data",
      "message": "Body is too long (maximum is 65536 characters)"
    }
  ],
  "documentation_url": "https://docs.github.com/rest/reference/issues#create-an-issue-comment"
}
updatebot-ci commented 2 years ago 
Python 3.5 will no longer be supported in the next feature release of Poetry (1.2).
You should consider updating your Python version to a supported one.

Note that you will still be able to manage Python 3.5 projects by using the env command.
See https://python-poetry.org/docs/managing-environments/ for more information.

testJobs (tests.database.TestDatabaeQueries) ... ok
testClose (tests.bugzilla.TestBugzillaProvider) ... ok
testComment (tests.bugzilla.TestBugzillaProvider) ... ok
testFile (tests.bugzilla.TestBugzillaProvider) ... ok
testGet (tests.bugzilla.TestBugzillaProvider) ... ok
testStatus (tests.bugzilla.TestBugzillaProvider) ... ok
testConfigurationPassing (tests.automation_configuration.TestCommandRunner) ... ok
testCommand (tests.run_command.TestCommandRunner) ... ok
testAlertAcrossFFVersions (tests.functionality_commitalert.TestFunctionality) ... ok
testNoAlert (tests.functionality_commitalert.TestFunctionality) ... ok
testOneAlertCloseItAnotherAlertBumpFF (tests.functionality_commitalert.TestFunctionality) ... ok
testSimpleAlert (tests.functionality_commitalert.TestFunctionality) ... ok
testSimpleAlertAcrossBranch (tests.functionality_commitalert.TestFunctionality) ... ok
testSimpleAlertOnBranch (tests.functionality_commitalert.TestFunctionality) ... ok
testTwoAlertsBumpFF (tests.functionality_commitalert.TestFunctionality) ... ok
testTwoAlertsNewCommitsNoUpdate (tests.functionality_commitalert.TestFunctionality) ... ok
testTwoSimpleAlerts (tests.functionality_commitalert.TestFunctionality) ... ok
testTwoSimpleAlertsSkip2 (tests.functionality_commitalert.TestFunctionality) ... ok
testTwoSimpleAlertsTimeLagged (tests.functionality_commitalert.TestFunctionality) ... ok
testUnaffectedVersion (tests.functionality_commitalert.TestFunctionality) ... ok
testAllNewJobs (tests.functionality_all_platforms.TestFunctionality) ... ok
testAllNewJobsWithFuzzyPath (tests.functionality_all_platforms.TestFunctionality) ... ok
testAllNewJobsWithFuzzyQuery (tests.functionality_all_platforms.TestFunctionality) ... ok
testBumpFFVersion (tests.functionality_all_platforms.TestFunctionality) ... ok
testExistingJobAllSuccess (tests.functionality_all_platforms.TestFunctionality) ... ok
testExistingJobBuildFailed (tests.functionality_all_platforms.TestFunctionality) ... ok
testExistingJobClassifiedFailures (tests.functionality_all_platforms.TestFunctionality) ... ok
testExistingJobUnclassifiedFailureNoRetriggers (tests.functionality_all_platforms.TestFunctionality) ... ok
testExistingJobUnclassifiedFailuresNeedingRetriggers (tests.functionality_all_platforms.TestFunctionality) ... ok
testFailsDuringCommit (tests.functionality_all_platforms.TestFunctionality) ... ok
testFailsDuringPatching (tests.functionality_all_platforms.TestFunctionality) ... 
Stdout:
[Info]    Beginning TestFunctionality.testFailsDuringPatching
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.check_database
[Debug]   MySQLDatabase:  Arguments: (,)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase._check_and_get_configuration
[Debug]   MySQLDatabase:  Arguments: (,)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase._create_database
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase._create_database
[Info]    MySQLDatabase: Ending MySQLDatabase._check_and_get_configuration
[Info]    MySQLDatabase: Ending MySQLDatabase.check_database
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    TestCommandProvider: Mocked Command executed git log -1 --oneline
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    Running Updatebot version: 0481f1c (HEAD -> issue-115-add-revision-to-log, origin/issue-115-add-revision-to-log) Issue #115 - Add revision of updatebot to log output
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.updatebot_is_enabled
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.updatebot_is_enabled
[Info]    Changing to gecko directory '.'
[Info]    Skipping dav1d because it doesn't meet the filter 'png'
[Info]    Skipping cubeb-query because it doesn't meet the filter 'png'
[Info]    Skipping cubeb-path because it doesn't meet the filter 'png'
[Info]    Skipping cube-2commits because it doesn't meet the filter 'png'
[Info]    Skipping aom because it doesn't meet the filter 'png'
[Info]    Skipping aom because it doesn't meet the filter 'png'
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.check_for_update
[Debug]   VendorProvider:  Arguments: (, )
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor --check-for-update mozilla-central/source/media/libpng/moz.yaml
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.check_for_update
[Info]    VendorTaskRunner: Processing libpng for an upstream revision try_rev.
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    VendorTaskRunner: try_rev is a brand new revision to updatebot.
[Debug]   VendorTaskRunner: ================================================
[Info]    VendorTaskRunner: Beginning VendorTaskRunner._process_new_job
[Debug]   VendorTaskRunner:  Arguments: (, , , 'try_rev', '2020-08-21 15:13:49')
[Debug]   VendorTaskRunner: ================================================
[Info]    VendorTaskRunner: Beginning BaseTaskRunner._should_process_new_job
[Debug]   VendorTaskRunner:  Arguments: (, , , 'try_rev')
[Info]    VendorTaskRunner: Task frequency is 'every' so processing the new job.
[Info]    VendorTaskRunner: Ending BaseTaskRunner._should_process_new_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_all_jobs_for_library
[Debug]   MySQLDatabase:  Arguments: (, )
[Debug]   MySQLDatabase: Function returned a list of 0 objects
[Info]    MySQLDatabase: Ending MySQLDatabase.get_all_jobs_for_library
[Info]    VendorTaskRunner: Found 0 active jobs for this library
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_all_jobs_for_library
[Debug]   MySQLDatabase:  Arguments: (, , False)
[Debug]   MySQLDatabase: Function returned a list of 0 objects
[Info]    MySQLDatabase: Ending MySQLDatabase.get_all_jobs_for_library
[Debug]   SCMProvider: ================================================
[Info]    SCMProvider: Beginning SCMProvider.check_for_update
[Debug]   SCMProvider:  Arguments: (, , , 'try_rev', [])
[Info]    TestCommandProvider: Mocked Command executed git clone https://example.invalid .
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed git merge-base _current origin/try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Debug]   SCMProvider: Our common ancestor is _current.
[Info]    TestCommandProvider: Mocked Command executed git log --pretty=%H|%ai|%ci --no-merges _current..origin/try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Debug]   SCMProvider: We've never run a job for this library before.
[Info]    TestCommandProvider: Mocked Command executed git diff --name-status try_rev^ try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed git log --pretty=%s -1 try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed git log --pretty=%an -1 try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed git log --pretty=%b -1 try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed git diff --name-status try_rev^ try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed git log --pretty=%s -1 try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed git log --pretty=%an -1 try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed git log --pretty=%b -1 try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    SCMProvider: Ending SCMProvider.check_for_update
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.create_job
[Debug]   MySQLDatabase:  Arguments: (, , , 'try_rev', 87, , , 0)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    MySQLDatabase: Ending MySQLDatabase.create_job
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.vendor
[Debug]   VendorProvider:  Arguments: (, , 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor mozilla-central/source/media/libpng/moz.yaml --revision try_rev --patch-mode none
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.vendor
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_add_bug_id
[Debug]   MySQLDatabase:  Arguments: (, , 50)
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_add_bug_id
[Debug]   MercurialProvider: ================================================
[Info]    MercurialProvider: Beginning MercurialProvider.commit
[Debug]   MercurialProvider:  Arguments: (, , 50, 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed hg commit -m Bug 50 - Update libpng to try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    MercurialProvider: Ending MercurialProvider.commit
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.patch
[Debug]   VendorProvider:  Arguments: (, , 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor --patch-mode only mozilla-central/source/media/libpng/moz.yaml
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.patch
[Debug]   MercurialProvider: ================================================
[Info]    MercurialProvider: Beginning MercurialProvider.commit_patches
[Debug]   MercurialProvider:  Arguments: (, , 50, 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed hg commit -m Bug 50 - Apply mozilla patches for libpng
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    MercurialProvider: Ending MercurialProvider.commit_patches
[Debug]   TaskclusterProvider: ================================================
[Info]    TaskclusterProvider: Beginning TaskclusterProvider.submit_to_try
[Debug]   TaskclusterProvider:  Arguments: (, , '')
[Debug]   TaskclusterProvider: ================================================
[Info]    TaskclusterProvider: Beginning TaskclusterProvider._vcs_setup
[Debug]   TaskclusterProvider:  Arguments: (,)
[Info]    TaskclusterProvider: Ending TaskclusterProvider._vcs_setup
[Info]    TestCommandProvider: Mocked Command executed ./mach try auto
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TaskclusterProvider: Submitted try run at https://treeherder.mozilla.org/#/jobs?repo=try&revision=try_rev
[Info]    TaskclusterProvider: Ending TaskclusterProvider.submit_to_try
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.add_try_run
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', 'all platforms')
[Info]    MySQLDatabase: Ending MySQLDatabase.add_try_run
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_status
[Debug]   MySQLDatabase:  Arguments: (, )
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_status
[Debug]   PhabricatorProvider: ================================================
[Info]    PhabricatorProvider: Beginning PhabricatorProvider.submit_patch
[Debug]   PhabricatorProvider:  Arguments: (, 50)
[Info]    TestCommandProvider: Mocked Command executed arc diff --verbatim --conduit-uri https://phabricator-dev.allizom.org/ --
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed echo '{"transactions": [{"type":"bugzilla.bug-id", "value":"50"}], "objectIdentifier": "83050"}' | arc call-conduit --conduit-uri=https://phabricator-dev.allizom.org/ differential.revision.edit --
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    PhabricatorProvider: Submitted phabricator patch at https://phabricator-dev.allizom.org/83050
[Info]    PhabricatorProvider: Ending PhabricatorProvider.submit_patch
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_add_phab_revision
[Debug]   MySQLDatabase:  Arguments: (, , '83050')
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_add_phab_revision
[Info]    VendorTaskRunner: Ending VendorTaskRunner._process_new_job
[Info]    VendorTaskRunner: Removing any outgoing commits before moving on.
[Info]    TestCommandProvider: Mocked Command executed hg status
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed hg strip roots(outgoing()) --no-backup
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Info]    MySQLDatabase: Dropping tmp database updatebot_test_1653403473164299_6653
FAIL
testFailsDuringPatchingCommit (tests.functionality_all_platforms.TestFunctionality) ... ok
testFailsDuringPhabSubmit (tests.functionality_all_platforms.TestFunctionality) ... ok
testFailsDuringTrySubmit (tests.functionality_all_platforms.TestFunctionality) ... ok
testFailsDuringTrySubmitThenGoAgain (tests.functionality_all_platforms.TestFunctionality) ... ok
testFailsDuringVendor (tests.functionality_all_platforms.TestFunctionality) ... ok
testFrequencyCommits (tests.functionality_all_platforms.TestFunctionality) ... ok
testPatchJob (tests.functionality_all_platforms.TestFunctionality) ... 
Stdout:
[Info]    Beginning TestFunctionality.testPatchJob
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.check_database
[Debug]   MySQLDatabase:  Arguments: (,)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase._check_and_get_configuration
[Debug]   MySQLDatabase:  Arguments: (,)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase._create_database
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase._create_database
[Info]    MySQLDatabase: Ending MySQLDatabase._check_and_get_configuration
[Info]    MySQLDatabase: Ending MySQLDatabase.check_database
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    TestCommandProvider: Mocked Command executed git log -1 --oneline
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    Running Updatebot version: 0481f1c (HEAD -> issue-115-add-revision-to-log, origin/issue-115-add-revision-to-log) Issue #115 - Add revision of updatebot to log output
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.updatebot_is_enabled
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.updatebot_is_enabled
[Info]    Changing to gecko directory '.'
[Info]    Skipping dav1d because it doesn't meet the filter 'png'
[Info]    Skipping cubeb-query because it doesn't meet the filter 'png'
[Info]    Skipping cubeb-path because it doesn't meet the filter 'png'
[Info]    Skipping cube-2commits because it doesn't meet the filter 'png'
[Info]    Skipping aom because it doesn't meet the filter 'png'
[Info]    Skipping aom because it doesn't meet the filter 'png'
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.check_for_update
[Debug]   VendorProvider:  Arguments: (, )
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor --check-for-update mozilla-central/source/media/libpng/moz.yaml
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.check_for_update
[Info]    VendorTaskRunner: Processing libpng for an upstream revision try_rev.
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    VendorTaskRunner: try_rev is a brand new revision to updatebot.
[Debug]   VendorTaskRunner: ================================================
[Info]    VendorTaskRunner: Beginning VendorTaskRunner._process_new_job
[Debug]   VendorTaskRunner:  Arguments: (, , , 'try_rev', '2020-08-21 15:13:49')
[Debug]   VendorTaskRunner: ================================================
[Info]    VendorTaskRunner: Beginning BaseTaskRunner._should_process_new_job
[Debug]   VendorTaskRunner:  Arguments: (, , , 'try_rev')
[Info]    VendorTaskRunner: Task frequency is 'every' so processing the new job.
[Info]    VendorTaskRunner: Ending BaseTaskRunner._should_process_new_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_all_jobs_for_library
[Debug]   MySQLDatabase:  Arguments: (, )
[Debug]   MySQLDatabase: Function returned a list of 0 objects
[Info]    MySQLDatabase: Ending MySQLDatabase.get_all_jobs_for_library
[Info]    VendorTaskRunner: Found 0 active jobs for this library
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_all_jobs_for_library
[Debug]   MySQLDatabase:  Arguments: (, , False)
[Debug]   MySQLDatabase: Function returned a list of 0 objects
[Info]    MySQLDatabase: Ending MySQLDatabase.get_all_jobs_for_library
[Debug]   SCMProvider: ================================================
[Info]    SCMProvider: Beginning SCMProvider.check_for_update
[Debug]   SCMProvider:  Arguments: (, , , 'try_rev', [])
[Info]    SCMProvider: Ending SCMProvider.check_for_update
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.create_job
[Debug]   MySQLDatabase:  Arguments: (, , , 'try_rev', 87, , , 0)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    MySQLDatabase: Ending MySQLDatabase.create_job
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.vendor
[Debug]   VendorProvider:  Arguments: (, , 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor mozilla-central/source/media/libpng/moz.yaml --revision try_rev --patch-mode none
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.vendor
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_add_bug_id
[Debug]   MySQLDatabase:  Arguments: (, , 50)
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_add_bug_id
[Debug]   MercurialProvider: ================================================
[Info]    MercurialProvider: Beginning MercurialProvider.commit
[Debug]   MercurialProvider:  Arguments: (, , 50, 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed hg commit -m Bug 50 - Update libpng to try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    MercurialProvider: Ending MercurialProvider.commit
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.patch
[Debug]   VendorProvider:  Arguments: (, , 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor --patch-mode only mozilla-central/source/media/libpng/moz.yaml
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.patch
[Debug]   MercurialProvider: ================================================
[Info]    MercurialProvider: Beginning MercurialProvider.commit_patches
[Debug]   MercurialProvider:  Arguments: (, , 50, 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed hg commit -m Bug 50 - Apply mozilla patches for libpng
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    MercurialProvider: Ending MercurialProvider.commit_patches
[Debug]   TaskclusterProvider: ================================================
[Info]    TaskclusterProvider: Beginning TaskclusterProvider.submit_to_try
[Debug]   TaskclusterProvider:  Arguments: (, , '')
[Debug]   TaskclusterProvider: ================================================
[Info]    TaskclusterProvider: Beginning TaskclusterProvider._vcs_setup
[Debug]   TaskclusterProvider:  Arguments: (,)
[Info]    TaskclusterProvider: Ending TaskclusterProvider._vcs_setup
[Info]    TestCommandProvider: Mocked Command executed ./mach try auto
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TaskclusterProvider: Submitted try run at https://treeherder.mozilla.org/#/jobs?repo=try&revision=try_rev
[Info]    TaskclusterProvider: Ending TaskclusterProvider.submit_to_try
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.add_try_run
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', 'all platforms')
[Info]    MySQLDatabase: Ending MySQLDatabase.add_try_run
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_status
[Debug]   MySQLDatabase:  Arguments: (, )
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_status
[Debug]   PhabricatorProvider: ================================================
[Info]    PhabricatorProvider: Beginning PhabricatorProvider.submit_patch
[Debug]   PhabricatorProvider:  Arguments: (, 50)
[Info]    TestCommandProvider: Mocked Command executed arc diff --verbatim --conduit-uri https://phabricator-dev.allizom.org/ --
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed echo '{"transactions": [{"type":"bugzilla.bug-id", "value":"50"}], "objectIdentifier": "83050"}' | arc call-conduit --conduit-uri=https://phabricator-dev.allizom.org/ differential.revision.edit --
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    PhabricatorProvider: Submitted phabricator patch at https://phabricator-dev.allizom.org/83050
[Info]    PhabricatorProvider: Ending PhabricatorProvider.submit_patch
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_add_phab_revision
[Debug]   MySQLDatabase:  Arguments: (, , '83050')
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_add_phab_revision
[Info]    VendorTaskRunner: Ending VendorTaskRunner._process_new_job
[Info]    VendorTaskRunner: Removing any outgoing commits before moving on.
[Info]    TestCommandProvider: Mocked Command executed hg status
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed hg strip roots(outgoing()) --no-backup
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Info]    MySQLDatabase: Dropping tmp database updatebot_test_1653403474921617_6312
FAIL
testSecondJobButFirstIsntDone (tests.functionality_all_platforms.TestFunctionality) ... ok
testSecondJobReferencesFirst (tests.functionality_all_platforms.TestFunctionality) ... ok
testThreeJobs (tests.functionality_all_platforms.TestFunctionality) ... ok
testThreeJobsButDontLetThemFinish (tests.functionality_all_platforms.TestFunctionality) ... ok
testThreeJobsReopenFirst (tests.functionality_all_platforms.TestFunctionality) ... ok
testAllNewJobs (tests.functionality_two_platforms.TestFunctionality) ... ok
testAllNewJobsWithFuzzyPath (tests.functionality_two_platforms.TestFunctionality) ... ok
testAllNewJobsWithFuzzyQuery (tests.functionality_two_platforms.TestFunctionality) ... ok
testBumpFFVersion (tests.functionality_two_platforms.TestFunctionality) ... ok
testExistingJobAllSuccess (tests.functionality_two_platforms.TestFunctionality) ... ok
testExistingJobBuildFailed (tests.functionality_two_platforms.TestFunctionality) ... ok
testExistingJobClassifiedFailures (tests.functionality_two_platforms.TestFunctionality) ... ok
testExistingJobUnclassifiedFailureNoRetriggers (tests.functionality_two_platforms.TestFunctionality) ... ok
testExistingJobUnclassifiedFailuresNeedingRetriggers (tests.functionality_two_platforms.TestFunctionality) ... ok
testFailsDuringCommit (tests.functionality_two_platforms.TestFunctionality) ... ok
testFailsDuringPatching (tests.functionality_two_platforms.TestFunctionality) ... 
Stdout:
[Info]    ================================================
[Info]    Beginning TestFunctionality.testFailsDuringPatching
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.check_database
[Debug]   MySQLDatabase:  Arguments: (,)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase._check_and_get_configuration
[Debug]   MySQLDatabase:  Arguments: (,)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase._create_database
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase._create_database
[Info]    MySQLDatabase: Ending MySQLDatabase._check_and_get_configuration
[Info]    MySQLDatabase: Ending MySQLDatabase.check_database
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    TestCommandProvider: Mocked Command executed git log -1 --oneline
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    Running Updatebot version: 0481f1c (HEAD -> issue-115-add-revision-to-log, origin/issue-115-add-revision-to-log) Issue #115 - Add revision of updatebot to log output
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.updatebot_is_enabled
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.updatebot_is_enabled
[Info]    Changing to gecko directory '.'
[Info]    Skipping dav1d because it doesn't meet the filter 'png'
[Info]    Skipping cubeb-query because it doesn't meet the filter 'png'
[Info]    Skipping cubeb-path because it doesn't meet the filter 'png'
[Info]    Skipping cube-2commits because it doesn't meet the filter 'png'
[Info]    Skipping aom because it doesn't meet the filter 'png'
[Info]    Skipping aom because it doesn't meet the filter 'png'
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.check_for_update
[Debug]   VendorProvider:  Arguments: (, )
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor --check-for-update mozilla-central/source/media/libpng/moz.yaml
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.check_for_update
[Info]    VendorTaskRunner: Processing libpng for an upstream revision try_rev.
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    VendorTaskRunner: try_rev is a brand new revision to updatebot.
[Debug]   VendorTaskRunner: ================================================
[Info]    VendorTaskRunner: Beginning VendorTaskRunner._process_new_job
[Debug]   VendorTaskRunner:  Arguments: (, , , 'try_rev', '2020-08-21 15:13:49')
[Debug]   VendorTaskRunner: ================================================
[Info]    VendorTaskRunner: Beginning BaseTaskRunner._should_process_new_job
[Debug]   VendorTaskRunner:  Arguments: (, , , 'try_rev')
[Info]    VendorTaskRunner: Task frequency is 'every' so processing the new job.
[Info]    VendorTaskRunner: Ending BaseTaskRunner._should_process_new_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_all_jobs_for_library
[Debug]   MySQLDatabase:  Arguments: (, )
[Debug]   MySQLDatabase: Function returned a list of 0 objects
[Info]    MySQLDatabase: Ending MySQLDatabase.get_all_jobs_for_library
[Info]    VendorTaskRunner: Found 0 active jobs for this library
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_all_jobs_for_library
[Debug]   MySQLDatabase:  Arguments: (, , False)
[Debug]   MySQLDatabase: Function returned a list of 0 objects
[Info]    MySQLDatabase: Ending MySQLDatabase.get_all_jobs_for_library
[Debug]   SCMProvider: ================================================
[Info]    SCMProvider: Beginning SCMProvider.check_for_update
[Debug]   SCMProvider:  Arguments: (, , , 'try_rev', [])
[Info]    SCMProvider: Ending SCMProvider.check_for_update
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.create_job
[Debug]   MySQLDatabase:  Arguments: (, , , 'try_rev', 87, , , 0)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    MySQLDatabase: Ending MySQLDatabase.create_job
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.vendor
[Debug]   VendorProvider:  Arguments: (, , 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor mozilla-central/source/media/libpng/moz.yaml --revision try_rev --patch-mode none
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.vendor
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_add_bug_id
[Debug]   MySQLDatabase:  Arguments: (, , 50)
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_add_bug_id
[Debug]   MercurialProvider: ================================================
[Info]    MercurialProvider: Beginning MercurialProvider.commit
[Debug]   MercurialProvider:  Arguments: (, , 50, 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed hg commit -m Bug 50 - Update libpng to try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    MercurialProvider: Ending MercurialProvider.commit
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.patch
[Debug]   VendorProvider:  Arguments: (, , 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor --patch-mode only mozilla-central/source/media/libpng/moz.yaml
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.patch
[Debug]   MercurialProvider: ================================================
[Info]    MercurialProvider: Beginning MercurialProvider.commit_patches
[Debug]   MercurialProvider:  Arguments: (, , 50, 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed hg commit -m Bug 50 - Apply mozilla patches for libpng
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    MercurialProvider: Ending MercurialProvider.commit_patches
[Debug]   TaskclusterProvider: ================================================
[Info]    TaskclusterProvider: Beginning TaskclusterProvider.submit_to_try
[Debug]   TaskclusterProvider:  Arguments: (, , 'linux64')
[Debug]   TaskclusterProvider: ================================================
[Info]    TaskclusterProvider: Beginning TaskclusterProvider._vcs_setup
[Debug]   TaskclusterProvider:  Arguments: (,)
[Info]    TaskclusterProvider: Ending TaskclusterProvider._vcs_setup
[Info]    TestCommandProvider: Mocked Command executed ./mach try auto --tasks-regex linux64
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TaskclusterProvider: Submitted try run at https://treeherder.mozilla.org/#/jobs?repo=try&revision=try_rev
[Info]    TaskclusterProvider: Ending TaskclusterProvider.submit_to_try
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.add_try_run
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', 'initial platform')
[Info]    MySQLDatabase: Ending MySQLDatabase.add_try_run
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_status
[Debug]   MySQLDatabase:  Arguments: (, )
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_status
[Debug]   PhabricatorProvider: ================================================
[Info]    PhabricatorProvider: Beginning PhabricatorProvider.submit_patch
[Debug]   PhabricatorProvider:  Arguments: (, 50)
[Info]    TestCommandProvider: Mocked Command executed arc diff --verbatim --conduit-uri https://phabricator-dev.allizom.org/ --
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed echo '{"transactions": [{"type":"bugzilla.bug-id", "value":"50"}], "objectIdentifier": "83050"}' | arc call-conduit --conduit-uri=https://phabricator-dev.allizom.org/ differential.revision.edit --
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    PhabricatorProvider: Submitted phabricator patch at https://phabricator-dev.allizom.org/83050
[Info]    PhabricatorProvider: Ending PhabricatorProvider.submit_patch
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_add_phab_revision
[Debug]   MySQLDatabase:  Arguments: (, , '83050')
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_add_phab_revision
[Info]    VendorTaskRunner: Ending VendorTaskRunner._process_new_job
[Info]    VendorTaskRunner: Removing any outgoing commits before moving on.
[Info]    TestCommandProvider: Mocked Command executed hg status
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed hg strip roots(outgoing()) --no-backup
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Info]    MySQLDatabase: Dropping tmp database updatebot_test_1653403481884761_5374
FAIL
testFailsDuringPatchingCommit (tests.functionality_two_platforms.TestFunctionality) ... ok
testFailsDuringPhabSubmit (tests.functionality_two_platforms.TestFunctionality) ... ok
testFailsDuringTrySubmit (tests.functionality_two_platforms.TestFunctionality) ... ok
testFailsDuringTrySubmitThenGoAgain (tests.functionality_two_platforms.TestFunctionality) ... ok
testFailsDuringVendor (tests.functionality_two_platforms.TestFunctionality) ... ok
testFrequencyCommits (tests.functionality_two_platforms.TestFunctionality) ... ok
testPatchJob (tests.functionality_two_platforms.TestFunctionality) ... 
Stdout:
[Info]    ================================================
[Info]    Beginning TestFunctionality.testPatchJob
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.check_database
[Debug]   MySQLDatabase:  Arguments: (,)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase._check_and_get_configuration
[Debug]   MySQLDatabase:  Arguments: (,)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase._create_database
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase._create_database
[Info]    MySQLDatabase: Ending MySQLDatabase._check_and_get_configuration
[Info]    MySQLDatabase: Ending MySQLDatabase.check_database
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    TestCommandProvider: Mocked Command executed git log -1 --oneline
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    Running Updatebot version: 0481f1c (HEAD -> issue-115-add-revision-to-log, origin/issue-115-add-revision-to-log) Issue #115 - Add revision of updatebot to log output
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.updatebot_is_enabled
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.updatebot_is_enabled
[Info]    Changing to gecko directory '.'
[Info]    Skipping dav1d because it doesn't meet the filter 'png'
[Info]    Skipping cubeb-query because it doesn't meet the filter 'png'
[Info]    Skipping cubeb-path because it doesn't meet the filter 'png'
[Info]    Skipping cube-2commits because it doesn't meet the filter 'png'
[Info]    Skipping aom because it doesn't meet the filter 'png'
[Info]    Skipping aom because it doesn't meet the filter 'png'
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.check_for_update
[Debug]   VendorProvider:  Arguments: (, )
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor --check-for-update mozilla-central/source/media/libpng/moz.yaml
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.check_for_update
[Info]    VendorTaskRunner: Processing libpng for an upstream revision try_rev.
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    VendorTaskRunner: try_rev is a brand new revision to updatebot.
[Debug]   VendorTaskRunner: ================================================
[Info]    VendorTaskRunner: Beginning VendorTaskRunner._process_new_job
[Debug]   VendorTaskRunner:  Arguments: (, , , 'try_rev', '2020-08-21 15:13:49')
[Debug]   VendorTaskRunner: ================================================
[Info]    VendorTaskRunner: Beginning BaseTaskRunner._should_process_new_job
[Debug]   VendorTaskRunner:  Arguments: (, , , 'try_rev')
[Info]    VendorTaskRunner: Task frequency is 'every' so processing the new job.
[Info]    VendorTaskRunner: Ending BaseTaskRunner._should_process_new_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_all_jobs_for_library
[Debug]   MySQLDatabase:  Arguments: (, )
[Debug]   MySQLDatabase: Function returned a list of 0 objects
[Info]    MySQLDatabase: Ending MySQLDatabase.get_all_jobs_for_library
[Info]    VendorTaskRunner: Found 0 active jobs for this library
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_all_jobs_for_library
[Debug]   MySQLDatabase:  Arguments: (, , False)
[Debug]   MySQLDatabase: Function returned a list of 0 objects
[Info]    MySQLDatabase: Ending MySQLDatabase.get_all_jobs_for_library
[Debug]   SCMProvider: ================================================
[Info]    SCMProvider: Beginning SCMProvider.check_for_update
[Debug]   SCMProvider:  Arguments: (, , , 'try_rev', [])
[Info]    SCMProvider: Ending SCMProvider.check_for_update
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.create_job
[Debug]   MySQLDatabase:  Arguments: (, , , 'try_rev', 87, , , 0)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    MySQLDatabase: Ending MySQLDatabase.create_job
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.vendor
[Debug]   VendorProvider:  Arguments: (, , 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor mozilla-central/source/media/libpng/moz.yaml --revision try_rev --patch-mode none
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.vendor
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_add_bug_id
[Debug]   MySQLDatabase:  Arguments: (, , 50)
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_add_bug_id
[Debug]   MercurialProvider: ================================================
[Info]    MercurialProvider: Beginning MercurialProvider.commit
[Debug]   MercurialProvider:  Arguments: (, , 50, 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed hg commit -m Bug 50 - Update libpng to try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    MercurialProvider: Ending MercurialProvider.commit
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.patch
[Debug]   VendorProvider:  Arguments: (, , 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor --patch-mode only mozilla-central/source/media/libpng/moz.yaml
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.patch
[Debug]   MercurialProvider: ================================================
[Info]    MercurialProvider: Beginning MercurialProvider.commit_patches
[Debug]   MercurialProvider:  Arguments: (, , 50, 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed hg commit -m Bug 50 - Apply mozilla patches for libpng
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    MercurialProvider: Ending MercurialProvider.commit_patches
[Debug]   TaskclusterProvider: ================================================
[Info]    TaskclusterProvider: Beginning TaskclusterProvider.submit_to_try
[Debug]   TaskclusterProvider:  Arguments: (, , 'linux64')
[Debug]   TaskclusterProvider: ================================================
[Info]    TaskclusterProvider: Beginning TaskclusterProvider._vcs_setup
[Debug]   TaskclusterProvider:  Arguments: (,)
[Info]    TaskclusterProvider: Ending TaskclusterProvider._vcs_setup
[Info]    TestCommandProvider: Mocked Command executed ./mach try auto --tasks-regex linux64
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TaskclusterProvider: Submitted try run at https://treeherder.mozilla.org/#/jobs?repo=try&revision=try_rev
[Info]    TaskclusterProvider: Ending TaskclusterProvider.submit_to_try
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.add_try_run
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', 'initial platform')
[Info]    MySQLDatabase: Ending MySQLDatabase.add_try_run
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_status
[Debug]   MySQLDatabase:  Arguments: (, )
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_status
[Debug]   PhabricatorProvider: ================================================
[Info]    PhabricatorProvider: Beginning PhabricatorProvider.submit_patch
[Debug]   PhabricatorProvider:  Arguments: (, 50)
[Info]    TestCommandProvider: Mocked Command executed arc diff --verbatim --conduit-uri https://phabricator-dev.allizom.org/ --
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed echo '{"transactions": [{"type":"bugzilla.bug-id", "value":"50"}], "objectIdentifier": "83050"}' | arc call-conduit --conduit-uri=https://phabricator-dev.allizom.org/ differential.revision.edit --
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    PhabricatorProvider: Submitted phabricator patch at https://phabricator-dev.allizom.org/83050
[Info]    PhabricatorProvider: Ending PhabricatorProvider.submit_patch
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_add_phab_revision
[Debug]   MySQLDatabase:  Arguments: (, , '83050')
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_add_phab_revision
[Info]    VendorTaskRunner: Ending VendorTaskRunner._process_new_job
[Info]    VendorTaskRunner: Removing any outgoing commits before moving on.
[Info]    TestCommandProvider: Mocked Command executed hg status
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed hg strip roots(outgoing()) --no-backup
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    In _check_jobs looking for status JOBSTATUS.AWAITING_INITIAL_PLATFORM_TRY_RESULTS and outcome JOBOUTCOME.PENDING
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Info]    MySQLDatabase: Dropping tmp database updatebot_test_1653403483689943_9076
FAIL
testSecondJobButFirstIsntDone (tests.functionality_two_platforms.TestFunctionality) ... ok
testSecondJobReferencesFirst (tests.functionality_two_platforms.TestFunctionality) ... ok
testThreeJobs (tests.functionality_two_platforms.TestFunctionality) ... ok
testThreeJobsButDontLetThemFinish (tests.functionality_two_platforms.TestFunctionality) ... ok
testThreeJobsReopenFirst (tests.functionality_two_platforms.TestFunctionality) ... ok
testInheritance (tests.python_language.TestCommandRunner) ... ok
testString (tests.python_language.TestCommandRunner) ... ok
test_combine (tests.treeherder_api.TestTaskclusterProvider) ... ok
test_correlation (tests.treeherder_api.TestTaskclusterProvider) ... ok
test_failure_classification (tests.treeherder_api.TestTaskclusterProvider) ... ok
test_job_details (tests.treeherder_api.TestTaskclusterProvider) ... ok
test_push_exception_handling (tests.treeherder_api.TestTaskclusterProvider) ... ok
test_push_health (tests.treeherder_api.TestTaskclusterProvider) ... ok
test_retrigger (tests.treeherder_api.TestTaskclusterProvider) ... ok
test_transform (tests.treeherder_api.TestTaskclusterProvider) ... ok
testLibraryExceptions (tests.library.TestLibraryProvider) ... ok
testLibraryFindAndImport (tests.library.TestLibraryProvider) ... ok
testLambda1 (tests.lambda_capture.TestLambdaCapture) ... ok
testLambda1 (tests.class_passing.TestClassPassing) ... ok
testFrequency (tests.frequency.TestTaskFrequency) ... ok

======================================================================
FAIL: testFailsDuringPatching (tests.functionality_all_platforms.TestFunctionality)
----------------------------------------------------------------------
Traceback (most recent call last):
  File "/home/circleci/project/components/logging.py", line 38, in func_wrapper
    ret = func(*args, **kwargs)
  File "/home/circleci/project/tests/functionality_all_platforms.py", line 348, in testFailsDuringPatching
    self.assertEqual(JOBSTATUS.DONE, j.status, "Expected status JOBSTATUS.DONE, got status %s" % (j.status.name))
AssertionError:  !=  : Expected status JOBSTATUS.DONE, got status AWAITING_SECOND_PLATFORMS_TRY_RESULTS

Stdout:
[Info]    Beginning TestFunctionality.testFailsDuringPatching
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.check_database
[Debug]   MySQLDatabase:  Arguments: (,)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase._check_and_get_configuration
[Debug]   MySQLDatabase:  Arguments: (,)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase._create_database
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase._create_database
[Info]    MySQLDatabase: Ending MySQLDatabase._check_and_get_configuration
[Info]    MySQLDatabase: Ending MySQLDatabase.check_database
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    TestCommandProvider: Mocked Command executed git log -1 --oneline
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    Running Updatebot version: 0481f1c (HEAD -> issue-115-add-revision-to-log, origin/issue-115-add-revision-to-log) Issue #115 - Add revision of updatebot to log output
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.updatebot_is_enabled
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.updatebot_is_enabled
[Info]    Changing to gecko directory '.'
[Info]    Skipping dav1d because it doesn't meet the filter 'png'
[Info]    Skipping cubeb-query because it doesn't meet the filter 'png'
[Info]    Skipping cubeb-path because it doesn't meet the filter 'png'
[Info]    Skipping cube-2commits because it doesn't meet the filter 'png'
[Info]    Skipping aom because it doesn't meet the filter 'png'
[Info]    Skipping aom because it doesn't meet the filter 'png'
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.check_for_update
[Debug]   VendorProvider:  Arguments: (, )
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor --check-for-update mozilla-central/source/media/libpng/moz.yaml
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.check_for_update
[Info]    VendorTaskRunner: Processing libpng for an upstream revision try_rev.
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    VendorTaskRunner: try_rev is a brand new revision to updatebot.
[Debug]   VendorTaskRunner: ================================================
[Info]    VendorTaskRunner: Beginning VendorTaskRunner._process_new_job
[Debug]   VendorTaskRunner:  Arguments: (, , , 'try_rev', '2020-08-21 15:13:49')
[Debug]   VendorTaskRunner: ================================================
[Info]    VendorTaskRunner: Beginning BaseTaskRunner._should_process_new_job
[Debug]   VendorTaskRunner:  Arguments: (, , , 'try_rev')
[Info]    VendorTaskRunner: Task frequency is 'every' so processing the new job.
[Info]    VendorTaskRunner: Ending BaseTaskRunner._should_process_new_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_all_jobs_for_library
[Debug]   MySQLDatabase:  Arguments: (, )
[Debug]   MySQLDatabase: Function returned a list of 0 objects
[Info]    MySQLDatabase: Ending MySQLDatabase.get_all_jobs_for_library
[Info]    VendorTaskRunner: Found 0 active jobs for this library
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_all_jobs_for_library
[Debug]   MySQLDatabase:  Arguments: (, , False)
[Debug]   MySQLDatabase: Function returned a list of 0 objects
[Info]    MySQLDatabase: Ending MySQLDatabase.get_all_jobs_for_library
[Debug]   SCMProvider: ================================================
[Info]    SCMProvider: Beginning SCMProvider.check_for_update
[Debug]   SCMProvider:  Arguments: (, , , 'try_rev', [])
[Info]    TestCommandProvider: Mocked Command executed git clone https://example.invalid .
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed git merge-base _current origin/try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Debug]   SCMProvider: Our common ancestor is _current.
[Info]    TestCommandProvider: Mocked Command executed git log --pretty=%H|%ai|%ci --no-merges _current..origin/try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Debug]   SCMProvider: We've never run a job for this library before.
[Info]    TestCommandProvider: Mocked Command executed git diff --name-status try_rev^ try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed git log --pretty=%s -1 try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed git log --pretty=%an -1 try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed git log --pretty=%b -1 try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed git diff --name-status try_rev^ try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed git log --pretty=%s -1 try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed git log --pretty=%an -1 try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed git log --pretty=%b -1 try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    SCMProvider: Ending SCMProvider.check_for_update
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.create_job
[Debug]   MySQLDatabase:  Arguments: (, , , 'try_rev', 87, , , 0)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    MySQLDatabase: Ending MySQLDatabase.create_job
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.vendor
[Debug]   VendorProvider:  Arguments: (, , 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor mozilla-central/source/media/libpng/moz.yaml --revision try_rev --patch-mode none
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.vendor
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_add_bug_id
[Debug]   MySQLDatabase:  Arguments: (, , 50)
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_add_bug_id
[Debug]   MercurialProvider: ================================================
[Info]    MercurialProvider: Beginning MercurialProvider.commit
[Debug]   MercurialProvider:  Arguments: (, , 50, 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed hg commit -m Bug 50 - Update libpng to try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    MercurialProvider: Ending MercurialProvider.commit
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.patch
[Debug]   VendorProvider:  Arguments: (, , 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor --patch-mode only mozilla-central/source/media/libpng/moz.yaml
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.patch
[Debug]   MercurialProvider: ================================================
[Info]    MercurialProvider: Beginning MercurialProvider.commit_patches
[Debug]   MercurialProvider:  Arguments: (, , 50, 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed hg commit -m Bug 50 - Apply mozilla patches for libpng
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    MercurialProvider: Ending MercurialProvider.commit_patches
[Debug]   TaskclusterProvider: ================================================
[Info]    TaskclusterProvider: Beginning TaskclusterProvider.submit_to_try
[Debug]   TaskclusterProvider:  Arguments: (, , '')
[Debug]   TaskclusterProvider: ================================================
[Info]    TaskclusterProvider: Beginning TaskclusterProvider._vcs_setup
[Debug]   TaskclusterProvider:  Arguments: (,)
[Info]    TaskclusterProvider: Ending TaskclusterProvider._vcs_setup
[Info]    TestCommandProvider: Mocked Command executed ./mach try auto
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TaskclusterProvider: Submitted try run at https://treeherder.mozilla.org/#/jobs?repo=try&revision=try_rev
[Info]    TaskclusterProvider: Ending TaskclusterProvider.submit_to_try
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.add_try_run
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', 'all platforms')
[Info]    MySQLDatabase: Ending MySQLDatabase.add_try_run
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_status
[Debug]   MySQLDatabase:  Arguments: (, )
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_status
[Debug]   PhabricatorProvider: ================================================
[Info]    PhabricatorProvider: Beginning PhabricatorProvider.submit_patch
[Debug]   PhabricatorProvider:  Arguments: (, 50)
[Info]    TestCommandProvider: Mocked Command executed arc diff --verbatim --conduit-uri https://phabricator-dev.allizom.org/ --
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed echo '{"transactions": [{"type":"bugzilla.bug-id", "value":"50"}], "objectIdentifier": "83050"}' | arc call-conduit --conduit-uri=https://phabricator-dev.allizom.org/ differential.revision.edit --
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    PhabricatorProvider: Submitted phabricator patch at https://phabricator-dev.allizom.org/83050
[Info]    PhabricatorProvider: Ending PhabricatorProvider.submit_patch
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_add_phab_revision
[Debug]   MySQLDatabase:  Arguments: (, , '83050')
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_add_phab_revision
[Info]    VendorTaskRunner: Ending VendorTaskRunner._process_new_job
[Info]    VendorTaskRunner: Removing any outgoing commits before moving on.
[Info]    TestCommandProvider: Mocked Command executed hg status
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed hg strip roots(outgoing()) --no-backup
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job

======================================================================
FAIL: testPatchJob (tests.functionality_all_platforms.TestFunctionality)
----------------------------------------------------------------------
Traceback (most recent call last):
  File "/home/circleci/project/components/logging.py", line 38, in func_wrapper
    ret = func(*args, **kwargs)
  File "/home/circleci/project/tests/functionality_all_platforms.py", line 276, in testPatchJob
    self.assertTrue(was_patched, "Did not successfully patch as expected.")
AssertionError: False is not true : Did not successfully patch as expected.

Stdout:
[Info]    Beginning TestFunctionality.testPatchJob
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.check_database
[Debug]   MySQLDatabase:  Arguments: (,)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase._check_and_get_configuration
[Debug]   MySQLDatabase:  Arguments: (,)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase._create_database
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase._create_database
[Info]    MySQLDatabase: Ending MySQLDatabase._check_and_get_configuration
[Info]    MySQLDatabase: Ending MySQLDatabase.check_database
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    TestCommandProvider: Mocked Command executed git log -1 --oneline
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    Running Updatebot version: 0481f1c (HEAD -> issue-115-add-revision-to-log, origin/issue-115-add-revision-to-log) Issue #115 - Add revision of updatebot to log output
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.updatebot_is_enabled
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.updatebot_is_enabled
[Info]    Changing to gecko directory '.'
[Info]    Skipping dav1d because it doesn't meet the filter 'png'
[Info]    Skipping cubeb-query because it doesn't meet the filter 'png'
[Info]    Skipping cubeb-path because it doesn't meet the filter 'png'
[Info]    Skipping cube-2commits because it doesn't meet the filter 'png'
[Info]    Skipping aom because it doesn't meet the filter 'png'
[Info]    Skipping aom because it doesn't meet the filter 'png'
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.check_for_update
[Debug]   VendorProvider:  Arguments: (, )
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor --check-for-update mozilla-central/source/media/libpng/moz.yaml
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.check_for_update
[Info]    VendorTaskRunner: Processing libpng for an upstream revision try_rev.
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    VendorTaskRunner: try_rev is a brand new revision to updatebot.
[Debug]   VendorTaskRunner: ================================================
[Info]    VendorTaskRunner: Beginning VendorTaskRunner._process_new_job
[Debug]   VendorTaskRunner:  Arguments: (, , , 'try_rev', '2020-08-21 15:13:49')
[Debug]   VendorTaskRunner: ================================================
[Info]    VendorTaskRunner: Beginning BaseTaskRunner._should_process_new_job
[Debug]   VendorTaskRunner:  Arguments: (, , , 'try_rev')
[Info]    VendorTaskRunner: Task frequency is 'every' so processing the new job.
[Info]    VendorTaskRunner: Ending BaseTaskRunner._should_process_new_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_all_jobs_for_library
[Debug]   MySQLDatabase:  Arguments: (, )
[Debug]   MySQLDatabase: Function returned a list of 0 objects
[Info]    MySQLDatabase: Ending MySQLDatabase.get_all_jobs_for_library
[Info]    VendorTaskRunner: Found 0 active jobs for this library
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_all_jobs_for_library
[Debug]   MySQLDatabase:  Arguments: (, , False)
[Debug]   MySQLDatabase: Function returned a list of 0 objects
[Info]    MySQLDatabase: Ending MySQLDatabase.get_all_jobs_for_library
[Debug]   SCMProvider: ================================================
[Info]    SCMProvider: Beginning SCMProvider.check_for_update
[Debug]   SCMProvider:  Arguments: (, , , 'try_rev', [])
[Info]    SCMProvider: Ending SCMProvider.check_for_update
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.create_job
[Debug]   MySQLDatabase:  Arguments: (, , , 'try_rev', 87, , , 0)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    MySQLDatabase: Ending MySQLDatabase.create_job
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.vendor
[Debug]   VendorProvider:  Arguments: (, , 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor mozilla-central/source/media/libpng/moz.yaml --revision try_rev --patch-mode none
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.vendor
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_add_bug_id
[Debug]   MySQLDatabase:  Arguments: (, , 50)
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_add_bug_id
[Debug]   MercurialProvider: ================================================
[Info]    MercurialProvider: Beginning MercurialProvider.commit
[Debug]   MercurialProvider:  Arguments: (, , 50, 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed hg commit -m Bug 50 - Update libpng to try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    MercurialProvider: Ending MercurialProvider.commit
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.patch
[Debug]   VendorProvider:  Arguments: (, , 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor --patch-mode only mozilla-central/source/media/libpng/moz.yaml
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.patch
[Debug]   MercurialProvider: ================================================
[Info]    MercurialProvider: Beginning MercurialProvider.commit_patches
[Debug]   MercurialProvider:  Arguments: (, , 50, 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed hg commit -m Bug 50 - Apply mozilla patches for libpng
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    MercurialProvider: Ending MercurialProvider.commit_patches
[Debug]   TaskclusterProvider: ================================================
[Info]    TaskclusterProvider: Beginning TaskclusterProvider.submit_to_try
[Debug]   TaskclusterProvider:  Arguments: (, , '')
[Debug]   TaskclusterProvider: ================================================
[Info]    TaskclusterProvider: Beginning TaskclusterProvider._vcs_setup
[Debug]   TaskclusterProvider:  Arguments: (,)
[Info]    TaskclusterProvider: Ending TaskclusterProvider._vcs_setup
[Info]    TestCommandProvider: Mocked Command executed ./mach try auto
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TaskclusterProvider: Submitted try run at https://treeherder.mozilla.org/#/jobs?repo=try&revision=try_rev
[Info]    TaskclusterProvider: Ending TaskclusterProvider.submit_to_try
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.add_try_run
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', 'all platforms')
[Info]    MySQLDatabase: Ending MySQLDatabase.add_try_run
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_status
[Debug]   MySQLDatabase:  Arguments: (, )
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_status
[Debug]   PhabricatorProvider: ================================================
[Info]    PhabricatorProvider: Beginning PhabricatorProvider.submit_patch
[Debug]   PhabricatorProvider:  Arguments: (, 50)
[Info]    TestCommandProvider: Mocked Command executed arc diff --verbatim --conduit-uri https://phabricator-dev.allizom.org/ --
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed echo '{"transactions": [{"type":"bugzilla.bug-id", "value":"50"}], "objectIdentifier": "83050"}' | arc call-conduit --conduit-uri=https://phabricator-dev.allizom.org/ differential.revision.edit --
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    PhabricatorProvider: Submitted phabricator patch at https://phabricator-dev.allizom.org/83050
[Info]    PhabricatorProvider: Ending PhabricatorProvider.submit_patch
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_add_phab_revision
[Debug]   MySQLDatabase:  Arguments: (, , '83050')
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_add_phab_revision
[Info]    VendorTaskRunner: Ending VendorTaskRunner._process_new_job
[Info]    VendorTaskRunner: Removing any outgoing commits before moving on.
[Info]    TestCommandProvider: Mocked Command executed hg status
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed hg strip roots(outgoing()) --no-backup
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job

======================================================================
FAIL: testFailsDuringPatching (tests.functionality_two_platforms.TestFunctionality)
----------------------------------------------------------------------
Traceback (most recent call last):
  File "/home/circleci/project/components/logging.py", line 38, in func_wrapper
    ret = func(*args, **kwargs)
  File "/home/circleci/project/tests/functionality_two_platforms.py", line 370, in testFailsDuringPatching
    self.assertEqual(JOBSTATUS.DONE, j.status, "Expected status JOBSTATUS.DONE, got status %s" % (j.status.name))
AssertionError:  !=  : Expected status JOBSTATUS.DONE, got status AWAITING_INITIAL_PLATFORM_TRY_RESULTS

Stdout:
[Info]    ================================================
[Info]    Beginning TestFunctionality.testFailsDuringPatching
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.check_database
[Debug]   MySQLDatabase:  Arguments: (,)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase._check_and_get_configuration
[Debug]   MySQLDatabase:  Arguments: (,)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase._create_database
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase._create_database
[Info]    MySQLDatabase: Ending MySQLDatabase._check_and_get_configuration
[Info]    MySQLDatabase: Ending MySQLDatabase.check_database
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    TestCommandProvider: Mocked Command executed git log -1 --oneline
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    Running Updatebot version: 0481f1c (HEAD -> issue-115-add-revision-to-log, origin/issue-115-add-revision-to-log) Issue #115 - Add revision of updatebot to log output
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.updatebot_is_enabled
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.updatebot_is_enabled
[Info]    Changing to gecko directory '.'
[Info]    Skipping dav1d because it doesn't meet the filter 'png'
[Info]    Skipping cubeb-query because it doesn't meet the filter 'png'
[Info]    Skipping cubeb-path because it doesn't meet the filter 'png'
[Info]    Skipping cube-2commits because it doesn't meet the filter 'png'
[Info]    Skipping aom because it doesn't meet the filter 'png'
[Info]    Skipping aom because it doesn't meet the filter 'png'
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.check_for_update
[Debug]   VendorProvider:  Arguments: (, )
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor --check-for-update mozilla-central/source/media/libpng/moz.yaml
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.check_for_update
[Info]    VendorTaskRunner: Processing libpng for an upstream revision try_rev.
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    VendorTaskRunner: try_rev is a brand new revision to updatebot.
[Debug]   VendorTaskRunner: ================================================
[Info]    VendorTaskRunner: Beginning VendorTaskRunner._process_new_job
[Debug]   VendorTaskRunner:  Arguments: (, , , 'try_rev', '2020-08-21 15:13:49')
[Debug]   VendorTaskRunner: ================================================
[Info]    VendorTaskRunner: Beginning BaseTaskRunner._should_process_new_job
[Debug]   VendorTaskRunner:  Arguments: (, , , 'try_rev')
[Info]    VendorTaskRunner: Task frequency is 'every' so processing the new job.
[Info]    VendorTaskRunner: Ending BaseTaskRunner._should_process_new_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_all_jobs_for_library
[Debug]   MySQLDatabase:  Arguments: (, )
[Debug]   MySQLDatabase: Function returned a list of 0 objects
[Info]    MySQLDatabase: Ending MySQLDatabase.get_all_jobs_for_library
[Info]    VendorTaskRunner: Found 0 active jobs for this library
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_all_jobs_for_library
[Debug]   MySQLDatabase:  Arguments: (, , False)
[Debug]   MySQLDatabase: Function returned a list of 0 objects
[Info]    MySQLDatabase: Ending MySQLDatabase.get_all_jobs_for_library
[Debug]   SCMProvider: ================================================
[Info]    SCMProvider: Beginning SCMProvider.check_for_update
[Debug]   SCMProvider:  Arguments: (, , , 'try_rev', [])
[Info]    SCMProvider: Ending SCMProvider.check_for_update
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.create_job
[Debug]   MySQLDatabase:  Arguments: (, , , 'try_rev', 87, , , 0)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    MySQLDatabase: Ending MySQLDatabase.create_job
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.vendor
[Debug]   VendorProvider:  Arguments: (, , 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor mozilla-central/source/media/libpng/moz.yaml --revision try_rev --patch-mode none
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.vendor
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_add_bug_id
[Debug]   MySQLDatabase:  Arguments: (, , 50)
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_add_bug_id
[Debug]   MercurialProvider: ================================================
[Info]    MercurialProvider: Beginning MercurialProvider.commit
[Debug]   MercurialProvider:  Arguments: (, , 50, 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed hg commit -m Bug 50 - Update libpng to try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    MercurialProvider: Ending MercurialProvider.commit
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.patch
[Debug]   VendorProvider:  Arguments: (, , 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor --patch-mode only mozilla-central/source/media/libpng/moz.yaml
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.patch
[Debug]   MercurialProvider: ================================================
[Info]    MercurialProvider: Beginning MercurialProvider.commit_patches
[Debug]   MercurialProvider:  Arguments: (, , 50, 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed hg commit -m Bug 50 - Apply mozilla patches for libpng
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    MercurialProvider: Ending MercurialProvider.commit_patches
[Debug]   TaskclusterProvider: ================================================
[Info]    TaskclusterProvider: Beginning TaskclusterProvider.submit_to_try
[Debug]   TaskclusterProvider:  Arguments: (, , 'linux64')
[Debug]   TaskclusterProvider: ================================================
[Info]    TaskclusterProvider: Beginning TaskclusterProvider._vcs_setup
[Debug]   TaskclusterProvider:  Arguments: (,)
[Info]    TaskclusterProvider: Ending TaskclusterProvider._vcs_setup
[Info]    TestCommandProvider: Mocked Command executed ./mach try auto --tasks-regex linux64
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TaskclusterProvider: Submitted try run at https://treeherder.mozilla.org/#/jobs?repo=try&revision=try_rev
[Info]    TaskclusterProvider: Ending TaskclusterProvider.submit_to_try
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.add_try_run
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', 'initial platform')
[Info]    MySQLDatabase: Ending MySQLDatabase.add_try_run
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_status
[Debug]   MySQLDatabase:  Arguments: (, )
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_status
[Debug]   PhabricatorProvider: ================================================
[Info]    PhabricatorProvider: Beginning PhabricatorProvider.submit_patch
[Debug]   PhabricatorProvider:  Arguments: (, 50)
[Info]    TestCommandProvider: Mocked Command executed arc diff --verbatim --conduit-uri https://phabricator-dev.allizom.org/ --
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed echo '{"transactions": [{"type":"bugzilla.bug-id", "value":"50"}], "objectIdentifier": "83050"}' | arc call-conduit --conduit-uri=https://phabricator-dev.allizom.org/ differential.revision.edit --
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    PhabricatorProvider: Submitted phabricator patch at https://phabricator-dev.allizom.org/83050
[Info]    PhabricatorProvider: Ending PhabricatorProvider.submit_patch
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_add_phab_revision
[Debug]   MySQLDatabase:  Arguments: (, , '83050')
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_add_phab_revision
[Info]    VendorTaskRunner: Ending VendorTaskRunner._process_new_job
[Info]    VendorTaskRunner: Removing any outgoing commits before moving on.
[Info]    TestCommandProvider: Mocked Command executed hg status
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed hg strip roots(outgoing()) --no-backup
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job

======================================================================
FAIL: testPatchJob (tests.functionality_two_platforms.TestFunctionality)
----------------------------------------------------------------------
Traceback (most recent call last):
  File "/home/circleci/project/components/logging.py", line 38, in func_wrapper
    ret = func(*args, **kwargs)
  File "/home/circleci/project/tests/functionality_two_platforms.py", line 295, in testPatchJob
    self.assertTrue(was_patched, "Did not successfully patch as expected.")
AssertionError: False is not true : Did not successfully patch as expected.

Stdout:
[Info]    ================================================
[Info]    Beginning TestFunctionality.testPatchJob
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.check_database
[Debug]   MySQLDatabase:  Arguments: (,)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase._check_and_get_configuration
[Debug]   MySQLDatabase:  Arguments: (,)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase._create_database
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase._create_database
[Info]    MySQLDatabase: Ending MySQLDatabase._check_and_get_configuration
[Info]    MySQLDatabase: Ending MySQLDatabase.check_database
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    TestCommandProvider: Mocked Command executed git log -1 --oneline
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    Running Updatebot version: 0481f1c (HEAD -> issue-115-add-revision-to-log, origin/issue-115-add-revision-to-log) Issue #115 - Add revision of updatebot to log output
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.updatebot_is_enabled
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.updatebot_is_enabled
[Info]    Changing to gecko directory '.'
[Info]    Skipping dav1d because it doesn't meet the filter 'png'
[Info]    Skipping cubeb-query because it doesn't meet the filter 'png'
[Info]    Skipping cubeb-path because it doesn't meet the filter 'png'
[Info]    Skipping cube-2commits because it doesn't meet the filter 'png'
[Info]    Skipping aom because it doesn't meet the filter 'png'
[Info]    Skipping aom because it doesn't meet the filter 'png'
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.check_for_update
[Debug]   VendorProvider:  Arguments: (, )
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor --check-for-update mozilla-central/source/media/libpng/moz.yaml
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.check_for_update
[Info]    VendorTaskRunner: Processing libpng for an upstream revision try_rev.
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    VendorTaskRunner: try_rev is a brand new revision to updatebot.
[Debug]   VendorTaskRunner: ================================================
[Info]    VendorTaskRunner: Beginning VendorTaskRunner._process_new_job
[Debug]   VendorTaskRunner:  Arguments: (, , , 'try_rev', '2020-08-21 15:13:49')
[Debug]   VendorTaskRunner: ================================================
[Info]    VendorTaskRunner: Beginning BaseTaskRunner._should_process_new_job
[Debug]   VendorTaskRunner:  Arguments: (, , , 'try_rev')
[Info]    VendorTaskRunner: Task frequency is 'every' so processing the new job.
[Info]    VendorTaskRunner: Ending BaseTaskRunner._should_process_new_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_all_jobs_for_library
[Debug]   MySQLDatabase:  Arguments: (, )
[Debug]   MySQLDatabase: Function returned a list of 0 objects
[Info]    MySQLDatabase: Ending MySQLDatabase.get_all_jobs_for_library
[Info]    VendorTaskRunner: Found 0 active jobs for this library
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_all_jobs_for_library
[Debug]   MySQLDatabase:  Arguments: (, , False)
[Debug]   MySQLDatabase: Function returned a list of 0 objects
[Info]    MySQLDatabase: Ending MySQLDatabase.get_all_jobs_for_library
[Debug]   SCMProvider: ================================================
[Info]    SCMProvider: Beginning SCMProvider.check_for_update
[Debug]   SCMProvider:  Arguments: (, , , 'try_rev', [])
[Info]    SCMProvider: Ending SCMProvider.check_for_update
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.create_job
[Debug]   MySQLDatabase:  Arguments: (, , , 'try_rev', 87, , , 0)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    MySQLDatabase: Ending MySQLDatabase.create_job
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.vendor
[Debug]   VendorProvider:  Arguments: (, , 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor mozilla-central/source/media/libpng/moz.yaml --revision try_rev --patch-mode none
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.vendor
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_add_bug_id
[Debug]   MySQLDatabase:  Arguments: (, , 50)
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_add_bug_id
[Debug]   MercurialProvider: ================================================
[Info]    MercurialProvider: Beginning MercurialProvider.commit
[Debug]   MercurialProvider:  Arguments: (, , 50, 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed hg commit -m Bug 50 - Update libpng to try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    MercurialProvider: Ending MercurialProvider.commit
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.patch
[Debug]   VendorProvider:  Arguments: (, , 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor --patch-mode only mozilla-central/source/media/libpng/moz.yaml
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.patch
[Debug]   MercurialProvider: ================================================
[Info]    MercurialProvider: Beginning MercurialProvider.commit_patches
[Debug]   MercurialProvider:  Arguments: (, , 50, 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed hg commit -m Bug 50 - Apply mozilla patches for libpng
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    MercurialProvider: Ending MercurialProvider.commit_patches
[Debug]   TaskclusterProvider: ================================================
[Info]    TaskclusterProvider: Beginning TaskclusterProvider.submit_to_try
[Debug]   TaskclusterProvider:  Arguments: (, , 'linux64')
[Debug]   TaskclusterProvider: ================================================
[Info]    TaskclusterProvider: Beginning TaskclusterProvider._vcs_setup
[Debug]   TaskclusterProvider:  Arguments: (,)
[Info]    TaskclusterProvider: Ending TaskclusterProvider._vcs_setup
[Info]    TestCommandProvider: Mocked Command executed ./mach try auto --tasks-regex linux64
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TaskclusterProvider: Submitted try run at https://treeherder.mozilla.org/#/jobs?repo=try&revision=try_rev
[Info]    TaskclusterProvider: Ending TaskclusterProvider.submit_to_try
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.add_try_run
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', 'initial platform')
[Info]    MySQLDatabase: Ending MySQLDatabase.add_try_run
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_status
[Debug]   MySQLDatabase:  Arguments: (, )
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_status
[Debug]   PhabricatorProvider: ================================================
[Info]    PhabricatorProvider: Beginning PhabricatorProvider.submit_patch
[Debug]   PhabricatorProvider:  Arguments: (, 50)
[Info]    TestCommandProvider: Mocked Command executed arc diff --verbatim --conduit-uri https://phabricator-dev.allizom.org/ --
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed echo '{"transactions": [{"type":"bugzilla.bug-id", "value":"50"}], "objectIdentifier": "83050"}' | arc call-conduit --conduit-uri=https://phabricator-dev.allizom.org/ differential.revision.edit --
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    PhabricatorProvider: Submitted phabricator patch at https://phabricator-dev.allizom.org/83050
[Info]    PhabricatorProvider: Ending PhabricatorProvider.submit_patch
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_add_phab_revision
[Debug]   MySQLDatabase:  Arguments: (, , '83050')
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_add_phab_revision
[Info]    VendorTaskRunner: Ending VendorTaskRunner._process_new_job
[Info]    VendorTaskRunner: Removing any outgoing commits before moving on.
[Info]    TestCommandProvider: Mocked Command executed hg status
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed hg strip roots(outgoing()) --no-backup
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    In _check_jobs looking for status JOBSTATUS.AWAITING_INITIAL_PLATFORM_TRY_RESULTS and outcome JOBOUTCOME.PENDING
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job

----------------------------------------------------------------------
Ran 81 tests in 22.641s

FAILED (failures=4)
[Info]    MySQLDatabase: Dropping tmp database updatebot_test_1653403466378444_4591
updatebot-ci commented 2 years ago 
Python 3.5 will no longer be supported in the next feature release of Poetry (1.2).
You should consider updating your Python version to a supported one.

Note that you will still be able to manage Python 3.5 projects by using the env command.
See https://python-poetry.org/docs/managing-environments/ for more information.

testJobs (tests.database.TestDatabaeQueries) ... ok
testClose (tests.bugzilla.TestBugzillaProvider) ... ok
testComment (tests.bugzilla.TestBugzillaProvider) ... ok
testFile (tests.bugzilla.TestBugzillaProvider) ... ok
testGet (tests.bugzilla.TestBugzillaProvider) ... ok
testStatus (tests.bugzilla.TestBugzillaProvider) ... ok
testConfigurationPassing (tests.automation_configuration.TestCommandRunner) ... ok
testCommand (tests.run_command.TestCommandRunner) ... ok
testAlertAcrossFFVersions (tests.functionality_commitalert.TestFunctionality) ... ok
testNoAlert (tests.functionality_commitalert.TestFunctionality) ... ok
testOneAlertCloseItAnotherAlertBumpFF (tests.functionality_commitalert.TestFunctionality) ... ok
testSimpleAlert (tests.functionality_commitalert.TestFunctionality) ... ok
testSimpleAlertAcrossBranch (tests.functionality_commitalert.TestFunctionality) ... ok
testSimpleAlertOnBranch (tests.functionality_commitalert.TestFunctionality) ... ok
testTwoAlertsBumpFF (tests.functionality_commitalert.TestFunctionality) ... ok
testTwoAlertsNewCommitsNoUpdate (tests.functionality_commitalert.TestFunctionality) ... ok
testTwoSimpleAlerts (tests.functionality_commitalert.TestFunctionality) ... ok
testTwoSimpleAlertsSkip2 (tests.functionality_commitalert.TestFunctionality) ... ok
testTwoSimpleAlertsTimeLagged (tests.functionality_commitalert.TestFunctionality) ... ok
testUnaffectedVersion (tests.functionality_commitalert.TestFunctionality) ... ok
testAllNewJobs (tests.functionality_all_platforms.TestFunctionality) ... ok
testAllNewJobsWithFuzzyPath (tests.functionality_all_platforms.TestFunctionality) ... ok
testAllNewJobsWithFuzzyQuery (tests.functionality_all_platforms.TestFunctionality) ... ok
testBumpFFVersion (tests.functionality_all_platforms.TestFunctionality) ... ok
testExistingJobAllSuccess (tests.functionality_all_platforms.TestFunctionality) ... ok
testExistingJobBuildFailed (tests.functionality_all_platforms.TestFunctionality) ... ok
testExistingJobClassifiedFailures (tests.functionality_all_platforms.TestFunctionality) ... ok
testExistingJobUnclassifiedFailureNoRetriggers (tests.functionality_all_platforms.TestFunctionality) ... ok
testExistingJobUnclassifiedFailuresNeedingRetriggers (tests.functionality_all_platforms.TestFunctionality) ... ok
testFailsDuringCommit (tests.functionality_all_platforms.TestFunctionality) ... ok
testFailsDuringPatching (tests.functionality_all_platforms.TestFunctionality) ... 
Stdout:
[Info]    Beginning TestFunctionality.testFailsDuringPatching
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.check_database
[Debug]   MySQLDatabase:  Arguments: (,)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase._check_and_get_configuration
[Debug]   MySQLDatabase:  Arguments: (,)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase._create_database
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase._create_database
[Info]    MySQLDatabase: Ending MySQLDatabase._check_and_get_configuration
[Info]    MySQLDatabase: Ending MySQLDatabase.check_database
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    TestCommandProvider: Mocked Command executed git log -1 --oneline
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    Running Updatebot version: 0481f1c (HEAD -> issue-115-add-revision-to-log, origin/issue-115-add-revision-to-log) Issue #115 - Add revision of updatebot to log output
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.updatebot_is_enabled
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.updatebot_is_enabled
[Info]    Changing to gecko directory '.'
[Info]    Skipping dav1d because it doesn't meet the filter 'png'
[Info]    Skipping cubeb-query because it doesn't meet the filter 'png'
[Info]    Skipping cubeb-path because it doesn't meet the filter 'png'
[Info]    Skipping cube-2commits because it doesn't meet the filter 'png'
[Info]    Skipping aom because it doesn't meet the filter 'png'
[Info]    Skipping aom because it doesn't meet the filter 'png'
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.check_for_update
[Debug]   VendorProvider:  Arguments: (, )
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor --check-for-update mozilla-central/source/media/libpng/moz.yaml
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.check_for_update
[Info]    VendorTaskRunner: Processing libpng for an upstream revision try_rev.
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    VendorTaskRunner: try_rev is a brand new revision to updatebot.
[Debug]   VendorTaskRunner: ================================================
[Info]    VendorTaskRunner: Beginning VendorTaskRunner._process_new_job
[Debug]   VendorTaskRunner:  Arguments: (, , , 'try_rev', '2020-08-21 15:13:49')
[Debug]   VendorTaskRunner: ================================================
[Info]    VendorTaskRunner: Beginning BaseTaskRunner._should_process_new_job
[Debug]   VendorTaskRunner:  Arguments: (, , , 'try_rev')
[Info]    VendorTaskRunner: Task frequency is 'every' so processing the new job.
[Info]    VendorTaskRunner: Ending BaseTaskRunner._should_process_new_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_all_jobs_for_library
[Debug]   MySQLDatabase:  Arguments: (, )
[Debug]   MySQLDatabase: Function returned a list of 0 objects
[Info]    MySQLDatabase: Ending MySQLDatabase.get_all_jobs_for_library
[Info]    VendorTaskRunner: Found 0 active jobs for this library
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_all_jobs_for_library
[Debug]   MySQLDatabase:  Arguments: (, , False)
[Debug]   MySQLDatabase: Function returned a list of 0 objects
[Info]    MySQLDatabase: Ending MySQLDatabase.get_all_jobs_for_library
[Debug]   SCMProvider: ================================================
[Info]    SCMProvider: Beginning SCMProvider.check_for_update
[Debug]   SCMProvider:  Arguments: (, , , 'try_rev', [])
[Info]    TestCommandProvider: Mocked Command executed git clone https://example.invalid .
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed git merge-base _current origin/try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Debug]   SCMProvider: Our common ancestor is _current.
[Info]    TestCommandProvider: Mocked Command executed git log --pretty=%H|%ai|%ci --no-merges _current..origin/try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Debug]   SCMProvider: We've never run a job for this library before.
[Info]    TestCommandProvider: Mocked Command executed git diff --name-status try_rev^ try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed git log --pretty=%s -1 try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed git log --pretty=%an -1 try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed git log --pretty=%b -1 try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed git diff --name-status try_rev^ try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed git log --pretty=%s -1 try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed git log --pretty=%an -1 try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed git log --pretty=%b -1 try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    SCMProvider: Ending SCMProvider.check_for_update
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.create_job
[Debug]   MySQLDatabase:  Arguments: (, , , 'try_rev', 87, , , 0)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    MySQLDatabase: Ending MySQLDatabase.create_job
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.vendor
[Debug]   VendorProvider:  Arguments: (, , 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor mozilla-central/source/media/libpng/moz.yaml --revision try_rev --patch-mode none
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.vendor
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_add_bug_id
[Debug]   MySQLDatabase:  Arguments: (, , 50)
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_add_bug_id
[Debug]   MercurialProvider: ================================================
[Info]    MercurialProvider: Beginning MercurialProvider.commit
[Debug]   MercurialProvider:  Arguments: (, , 50, 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed hg commit -m Bug 50 - Update libpng to try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    MercurialProvider: Ending MercurialProvider.commit
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.patch
[Debug]   VendorProvider:  Arguments: (, , 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor --patch-mode only mozilla-central/source/media/libpng/moz.yaml
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.patch
[Debug]   MercurialProvider: ================================================
[Info]    MercurialProvider: Beginning MercurialProvider.commit_patches
[Debug]   MercurialProvider:  Arguments: (, , 50, 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed hg commit -m Bug 50 - Apply mozilla patches for libpng
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    MercurialProvider: Ending MercurialProvider.commit_patches
[Debug]   TaskclusterProvider: ================================================
[Info]    TaskclusterProvider: Beginning TaskclusterProvider.submit_to_try
[Debug]   TaskclusterProvider:  Arguments: (, , '')
[Debug]   TaskclusterProvider: ================================================
[Info]    TaskclusterProvider: Beginning TaskclusterProvider._vcs_setup
[Debug]   TaskclusterProvider:  Arguments: (,)
[Info]    TaskclusterProvider: Ending TaskclusterProvider._vcs_setup
[Info]    TestCommandProvider: Mocked Command executed ./mach try auto
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TaskclusterProvider: Submitted try run at https://treeherder.mozilla.org/#/jobs?repo=try&revision=try_rev
[Info]    TaskclusterProvider: Ending TaskclusterProvider.submit_to_try
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.add_try_run
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', 'all platforms')
[Info]    MySQLDatabase: Ending MySQLDatabase.add_try_run
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_status
[Debug]   MySQLDatabase:  Arguments: (, )
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_status
[Debug]   PhabricatorProvider: ================================================
[Info]    PhabricatorProvider: Beginning PhabricatorProvider.submit_patch
[Debug]   PhabricatorProvider:  Arguments: (, 50)
[Info]    TestCommandProvider: Mocked Command executed arc diff --verbatim --conduit-uri https://phabricator-dev.allizom.org/ --
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed echo '{"transactions": [{"type":"bugzilla.bug-id", "value":"50"}], "objectIdentifier": "83050"}' | arc call-conduit --conduit-uri=https://phabricator-dev.allizom.org/ differential.revision.edit --
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    PhabricatorProvider: Submitted phabricator patch at https://phabricator-dev.allizom.org/83050
[Info]    PhabricatorProvider: Ending PhabricatorProvider.submit_patch
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_add_phab_revision
[Debug]   MySQLDatabase:  Arguments: (, , '83050')
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_add_phab_revision
[Info]    VendorTaskRunner: Ending VendorTaskRunner._process_new_job
[Info]    VendorTaskRunner: Removing any outgoing commits before moving on.
[Info]    TestCommandProvider: Mocked Command executed hg status
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed hg strip roots(outgoing()) --no-backup
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Info]    MySQLDatabase: Dropping tmp database updatebot_test_1653405047261638_9904
FAIL
testFailsDuringPatchingCommit (tests.functionality_all_platforms.TestFunctionality) ... ok
testFailsDuringPhabSubmit (tests.functionality_all_platforms.TestFunctionality) ... ok
testFailsDuringTrySubmit (tests.functionality_all_platforms.TestFunctionality) ... ok
testFailsDuringTrySubmitThenGoAgain (tests.functionality_all_platforms.TestFunctionality) ... ok
testFailsDuringVendor (tests.functionality_all_platforms.TestFunctionality) ... ok
testFrequencyCommits (tests.functionality_all_platforms.TestFunctionality) ... ok
testPatchJob (tests.functionality_all_platforms.TestFunctionality) ... 
Stdout:
[Info]    Beginning TestFunctionality.testPatchJob
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.check_database
[Debug]   MySQLDatabase:  Arguments: (,)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase._check_and_get_configuration
[Debug]   MySQLDatabase:  Arguments: (,)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase._create_database
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase._create_database
[Info]    MySQLDatabase: Ending MySQLDatabase._check_and_get_configuration
[Info]    MySQLDatabase: Ending MySQLDatabase.check_database
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    TestCommandProvider: Mocked Command executed git log -1 --oneline
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    Running Updatebot version: 0481f1c (HEAD -> issue-115-add-revision-to-log, origin/issue-115-add-revision-to-log) Issue #115 - Add revision of updatebot to log output
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.updatebot_is_enabled
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.updatebot_is_enabled
[Info]    Changing to gecko directory '.'
[Info]    Skipping dav1d because it doesn't meet the filter 'png'
[Info]    Skipping cubeb-query because it doesn't meet the filter 'png'
[Info]    Skipping cubeb-path because it doesn't meet the filter 'png'
[Info]    Skipping cube-2commits because it doesn't meet the filter 'png'
[Info]    Skipping aom because it doesn't meet the filter 'png'
[Info]    Skipping aom because it doesn't meet the filter 'png'
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.check_for_update
[Debug]   VendorProvider:  Arguments: (, )
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor --check-for-update mozilla-central/source/media/libpng/moz.yaml
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.check_for_update
[Info]    VendorTaskRunner: Processing libpng for an upstream revision try_rev.
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    VendorTaskRunner: try_rev is a brand new revision to updatebot.
[Debug]   VendorTaskRunner: ================================================
[Info]    VendorTaskRunner: Beginning VendorTaskRunner._process_new_job
[Debug]   VendorTaskRunner:  Arguments: (, , , 'try_rev', '2020-08-21 15:13:49')
[Debug]   VendorTaskRunner: ================================================
[Info]    VendorTaskRunner: Beginning BaseTaskRunner._should_process_new_job
[Debug]   VendorTaskRunner:  Arguments: (, , , 'try_rev')
[Info]    VendorTaskRunner: Task frequency is 'every' so processing the new job.
[Info]    VendorTaskRunner: Ending BaseTaskRunner._should_process_new_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_all_jobs_for_library
[Debug]   MySQLDatabase:  Arguments: (, )
[Debug]   MySQLDatabase: Function returned a list of 0 objects
[Info]    MySQLDatabase: Ending MySQLDatabase.get_all_jobs_for_library
[Info]    VendorTaskRunner: Found 0 active jobs for this library
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_all_jobs_for_library
[Debug]   MySQLDatabase:  Arguments: (, , False)
[Debug]   MySQLDatabase: Function returned a list of 0 objects
[Info]    MySQLDatabase: Ending MySQLDatabase.get_all_jobs_for_library
[Debug]   SCMProvider: ================================================
[Info]    SCMProvider: Beginning SCMProvider.check_for_update
[Debug]   SCMProvider:  Arguments: (, , , 'try_rev', [])
[Info]    SCMProvider: Ending SCMProvider.check_for_update
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.create_job
[Debug]   MySQLDatabase:  Arguments: (, , , 'try_rev', 87, , , 0)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    MySQLDatabase: Ending MySQLDatabase.create_job
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.vendor
[Debug]   VendorProvider:  Arguments: (, , 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor mozilla-central/source/media/libpng/moz.yaml --revision try_rev --patch-mode none
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.vendor
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_add_bug_id
[Debug]   MySQLDatabase:  Arguments: (, , 50)
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_add_bug_id
[Debug]   MercurialProvider: ================================================
[Info]    MercurialProvider: Beginning MercurialProvider.commit
[Debug]   MercurialProvider:  Arguments: (, , 50, 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed hg commit -m Bug 50 - Update libpng to try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    MercurialProvider: Ending MercurialProvider.commit
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.patch
[Debug]   VendorProvider:  Arguments: (, , 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor --patch-mode only mozilla-central/source/media/libpng/moz.yaml
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.patch
[Debug]   MercurialProvider: ================================================
[Info]    MercurialProvider: Beginning MercurialProvider.commit_patches
[Debug]   MercurialProvider:  Arguments: (, , 50, 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed hg commit -m Bug 50 - Apply mozilla patches for libpng
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    MercurialProvider: Ending MercurialProvider.commit_patches
[Debug]   TaskclusterProvider: ================================================
[Info]    TaskclusterProvider: Beginning TaskclusterProvider.submit_to_try
[Debug]   TaskclusterProvider:  Arguments: (, , '')
[Debug]   TaskclusterProvider: ================================================
[Info]    TaskclusterProvider: Beginning TaskclusterProvider._vcs_setup
[Debug]   TaskclusterProvider:  Arguments: (,)
[Info]    TaskclusterProvider: Ending TaskclusterProvider._vcs_setup
[Info]    TestCommandProvider: Mocked Command executed ./mach try auto
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TaskclusterProvider: Submitted try run at https://treeherder.mozilla.org/#/jobs?repo=try&revision=try_rev
[Info]    TaskclusterProvider: Ending TaskclusterProvider.submit_to_try
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.add_try_run
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', 'all platforms')
[Info]    MySQLDatabase: Ending MySQLDatabase.add_try_run
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_status
[Debug]   MySQLDatabase:  Arguments: (, )
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_status
[Debug]   PhabricatorProvider: ================================================
[Info]    PhabricatorProvider: Beginning PhabricatorProvider.submit_patch
[Debug]   PhabricatorProvider:  Arguments: (, 50)
[Info]    TestCommandProvider: Mocked Command executed arc diff --verbatim --conduit-uri https://phabricator-dev.allizom.org/ --
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed echo '{"transactions": [{"type":"bugzilla.bug-id", "value":"50"}], "objectIdentifier": "83050"}' | arc call-conduit --conduit-uri=https://phabricator-dev.allizom.org/ differential.revision.edit --
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    PhabricatorProvider: Submitted phabricator patch at https://phabricator-dev.allizom.org/83050
[Info]    PhabricatorProvider: Ending PhabricatorProvider.submit_patch
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_add_phab_revision
[Debug]   MySQLDatabase:  Arguments: (, , '83050')
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_add_phab_revision
[Info]    VendorTaskRunner: Ending VendorTaskRunner._process_new_job
[Info]    VendorTaskRunner: Removing any outgoing commits before moving on.
[Info]    TestCommandProvider: Mocked Command executed hg status
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed hg strip roots(outgoing()) --no-backup
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Info]    MySQLDatabase: Dropping tmp database updatebot_test_1653405048655605_6274
FAIL
testSecondJobButFirstIsntDone (tests.functionality_all_platforms.TestFunctionality) ... ok
testSecondJobReferencesFirst (tests.functionality_all_platforms.TestFunctionality) ... ok
testThreeJobs (tests.functionality_all_platforms.TestFunctionality) ... ok
testThreeJobsButDontLetThemFinish (tests.functionality_all_platforms.TestFunctionality) ... ok
testThreeJobsReopenFirst (tests.functionality_all_platforms.TestFunctionality) ... ok
testAllNewJobs (tests.functionality_two_platforms.TestFunctionality) ... ok
testAllNewJobsWithFuzzyPath (tests.functionality_two_platforms.TestFunctionality) ... ok
testAllNewJobsWithFuzzyQuery (tests.functionality_two_platforms.TestFunctionality) ... ok
testBumpFFVersion (tests.functionality_two_platforms.TestFunctionality) ... ok
testExistingJobAllSuccess (tests.functionality_two_platforms.TestFunctionality) ... ok
testExistingJobBuildFailed (tests.functionality_two_platforms.TestFunctionality) ... ok
testExistingJobClassifiedFailures (tests.functionality_two_platforms.TestFunctionality) ... ok
testExistingJobUnclassifiedFailureNoRetriggers (tests.functionality_two_platforms.TestFunctionality) ... ok
testExistingJobUnclassifiedFailuresNeedingRetriggers (tests.functionality_two_platforms.TestFunctionality) ... ok
testFailsDuringCommit (tests.functionality_two_platforms.TestFunctionality) ... ok
testFailsDuringPatching (tests.functionality_two_platforms.TestFunctionality) ... 
Stdout:
[Info]    ================================================
[Info]    Beginning TestFunctionality.testFailsDuringPatching
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.check_database
[Debug]   MySQLDatabase:  Arguments: (,)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase._check_and_get_configuration
[Debug]   MySQLDatabase:  Arguments: (,)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase._create_database
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase._create_database
[Info]    MySQLDatabase: Ending MySQLDatabase._check_and_get_configuration
[Info]    MySQLDatabase: Ending MySQLDatabase.check_database
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    TestCommandProvider: Mocked Command executed git log -1 --oneline
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    Running Updatebot version: 0481f1c (HEAD -> issue-115-add-revision-to-log, origin/issue-115-add-revision-to-log) Issue #115 - Add revision of updatebot to log output
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.updatebot_is_enabled
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.updatebot_is_enabled
[Info]    Changing to gecko directory '.'
[Info]    Skipping dav1d because it doesn't meet the filter 'png'
[Info]    Skipping cubeb-query because it doesn't meet the filter 'png'
[Info]    Skipping cubeb-path because it doesn't meet the filter 'png'
[Info]    Skipping cube-2commits because it doesn't meet the filter 'png'
[Info]    Skipping aom because it doesn't meet the filter 'png'
[Info]    Skipping aom because it doesn't meet the filter 'png'
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.check_for_update
[Debug]   VendorProvider:  Arguments: (, )
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor --check-for-update mozilla-central/source/media/libpng/moz.yaml
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.check_for_update
[Info]    VendorTaskRunner: Processing libpng for an upstream revision try_rev.
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    VendorTaskRunner: try_rev is a brand new revision to updatebot.
[Debug]   VendorTaskRunner: ================================================
[Info]    VendorTaskRunner: Beginning VendorTaskRunner._process_new_job
[Debug]   VendorTaskRunner:  Arguments: (, , , 'try_rev', '2020-08-21 15:13:49')
[Debug]   VendorTaskRunner: ================================================
[Info]    VendorTaskRunner: Beginning BaseTaskRunner._should_process_new_job
[Debug]   VendorTaskRunner:  Arguments: (, , , 'try_rev')
[Info]    VendorTaskRunner: Task frequency is 'every' so processing the new job.
[Info]    VendorTaskRunner: Ending BaseTaskRunner._should_process_new_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_all_jobs_for_library
[Debug]   MySQLDatabase:  Arguments: (, )
[Debug]   MySQLDatabase: Function returned a list of 0 objects
[Info]    MySQLDatabase: Ending MySQLDatabase.get_all_jobs_for_library
[Info]    VendorTaskRunner: Found 0 active jobs for this library
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_all_jobs_for_library
[Debug]   MySQLDatabase:  Arguments: (, , False)
[Debug]   MySQLDatabase: Function returned a list of 0 objects
[Info]    MySQLDatabase: Ending MySQLDatabase.get_all_jobs_for_library
[Debug]   SCMProvider: ================================================
[Info]    SCMProvider: Beginning SCMProvider.check_for_update
[Debug]   SCMProvider:  Arguments: (, , , 'try_rev', [])
[Info]    SCMProvider: Ending SCMProvider.check_for_update
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.create_job
[Debug]   MySQLDatabase:  Arguments: (, , , 'try_rev', 87, , , 0)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    MySQLDatabase: Ending MySQLDatabase.create_job
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.vendor
[Debug]   VendorProvider:  Arguments: (, , 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor mozilla-central/source/media/libpng/moz.yaml --revision try_rev --patch-mode none
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.vendor
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_add_bug_id
[Debug]   MySQLDatabase:  Arguments: (, , 50)
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_add_bug_id
[Debug]   MercurialProvider: ================================================
[Info]    MercurialProvider: Beginning MercurialProvider.commit
[Debug]   MercurialProvider:  Arguments: (, , 50, 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed hg commit -m Bug 50 - Update libpng to try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    MercurialProvider: Ending MercurialProvider.commit
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.patch
[Debug]   VendorProvider:  Arguments: (, , 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor --patch-mode only mozilla-central/source/media/libpng/moz.yaml
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.patch
[Debug]   MercurialProvider: ================================================
[Info]    MercurialProvider: Beginning MercurialProvider.commit_patches
[Debug]   MercurialProvider:  Arguments: (, , 50, 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed hg commit -m Bug 50 - Apply mozilla patches for libpng
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    MercurialProvider: Ending MercurialProvider.commit_patches
[Debug]   TaskclusterProvider: ================================================
[Info]    TaskclusterProvider: Beginning TaskclusterProvider.submit_to_try
[Debug]   TaskclusterProvider:  Arguments: (, , 'linux64')
[Debug]   TaskclusterProvider: ================================================
[Info]    TaskclusterProvider: Beginning TaskclusterProvider._vcs_setup
[Debug]   TaskclusterProvider:  Arguments: (,)
[Info]    TaskclusterProvider: Ending TaskclusterProvider._vcs_setup
[Info]    TestCommandProvider: Mocked Command executed ./mach try auto --tasks-regex linux64
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TaskclusterProvider: Submitted try run at https://treeherder.mozilla.org/#/jobs?repo=try&revision=try_rev
[Info]    TaskclusterProvider: Ending TaskclusterProvider.submit_to_try
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.add_try_run
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', 'initial platform')
[Info]    MySQLDatabase: Ending MySQLDatabase.add_try_run
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_status
[Debug]   MySQLDatabase:  Arguments: (, )
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_status
[Debug]   PhabricatorProvider: ================================================
[Info]    PhabricatorProvider: Beginning PhabricatorProvider.submit_patch
[Debug]   PhabricatorProvider:  Arguments: (, 50)
[Info]    TestCommandProvider: Mocked Command executed arc diff --verbatim --conduit-uri https://phabricator-dev.allizom.org/ --
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed echo '{"transactions": [{"type":"bugzilla.bug-id", "value":"50"}], "objectIdentifier": "83050"}' | arc call-conduit --conduit-uri=https://phabricator-dev.allizom.org/ differential.revision.edit --
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    PhabricatorProvider: Submitted phabricator patch at https://phabricator-dev.allizom.org/83050
[Info]    PhabricatorProvider: Ending PhabricatorProvider.submit_patch
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_add_phab_revision
[Debug]   MySQLDatabase:  Arguments: (, , '83050')
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_add_phab_revision
[Info]    VendorTaskRunner: Ending VendorTaskRunner._process_new_job
[Info]    VendorTaskRunner: Removing any outgoing commits before moving on.
[Info]    TestCommandProvider: Mocked Command executed hg status
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed hg strip roots(outgoing()) --no-backup
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Info]    MySQLDatabase: Dropping tmp database updatebot_test_1653405054009468_1178
FAIL
testFailsDuringPatchingCommit (tests.functionality_two_platforms.TestFunctionality) ... ok
testFailsDuringPhabSubmit (tests.functionality_two_platforms.TestFunctionality) ... ok
testFailsDuringTrySubmit (tests.functionality_two_platforms.TestFunctionality) ... ok
testFailsDuringTrySubmitThenGoAgain (tests.functionality_two_platforms.TestFunctionality) ... ok
testFailsDuringVendor (tests.functionality_two_platforms.TestFunctionality) ... ok
testFrequencyCommits (tests.functionality_two_platforms.TestFunctionality) ... ok
testPatchJob (tests.functionality_two_platforms.TestFunctionality) ... 
Stdout:
[Info]    ================================================
[Info]    Beginning TestFunctionality.testPatchJob
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.check_database
[Debug]   MySQLDatabase:  Arguments: (,)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase._check_and_get_configuration
[Debug]   MySQLDatabase:  Arguments: (,)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase._create_database
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase._create_database
[Info]    MySQLDatabase: Ending MySQLDatabase._check_and_get_configuration
[Info]    MySQLDatabase: Ending MySQLDatabase.check_database
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    TestCommandProvider: Mocked Command executed git log -1 --oneline
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    Running Updatebot version: 0481f1c (HEAD -> issue-115-add-revision-to-log, origin/issue-115-add-revision-to-log) Issue #115 - Add revision of updatebot to log output
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.updatebot_is_enabled
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.updatebot_is_enabled
[Info]    Changing to gecko directory '.'
[Info]    Skipping dav1d because it doesn't meet the filter 'png'
[Info]    Skipping cubeb-query because it doesn't meet the filter 'png'
[Info]    Skipping cubeb-path because it doesn't meet the filter 'png'
[Info]    Skipping cube-2commits because it doesn't meet the filter 'png'
[Info]    Skipping aom because it doesn't meet the filter 'png'
[Info]    Skipping aom because it doesn't meet the filter 'png'
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.check_for_update
[Debug]   VendorProvider:  Arguments: (, )
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor --check-for-update mozilla-central/source/media/libpng/moz.yaml
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.check_for_update
[Info]    VendorTaskRunner: Processing libpng for an upstream revision try_rev.
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    VendorTaskRunner: try_rev is a brand new revision to updatebot.
[Debug]   VendorTaskRunner: ================================================
[Info]    VendorTaskRunner: Beginning VendorTaskRunner._process_new_job
[Debug]   VendorTaskRunner:  Arguments: (, , , 'try_rev', '2020-08-21 15:13:49')
[Debug]   VendorTaskRunner: ================================================
[Info]    VendorTaskRunner: Beginning BaseTaskRunner._should_process_new_job
[Debug]   VendorTaskRunner:  Arguments: (, , , 'try_rev')
[Info]    VendorTaskRunner: Task frequency is 'every' so processing the new job.
[Info]    VendorTaskRunner: Ending BaseTaskRunner._should_process_new_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_all_jobs_for_library
[Debug]   MySQLDatabase:  Arguments: (, )
[Debug]   MySQLDatabase: Function returned a list of 0 objects
[Info]    MySQLDatabase: Ending MySQLDatabase.get_all_jobs_for_library
[Info]    VendorTaskRunner: Found 0 active jobs for this library
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_all_jobs_for_library
[Debug]   MySQLDatabase:  Arguments: (, , False)
[Debug]   MySQLDatabase: Function returned a list of 0 objects
[Info]    MySQLDatabase: Ending MySQLDatabase.get_all_jobs_for_library
[Debug]   SCMProvider: ================================================
[Info]    SCMProvider: Beginning SCMProvider.check_for_update
[Debug]   SCMProvider:  Arguments: (, , , 'try_rev', [])
[Info]    SCMProvider: Ending SCMProvider.check_for_update
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.create_job
[Debug]   MySQLDatabase:  Arguments: (, , , 'try_rev', 87, , , 0)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    MySQLDatabase: Ending MySQLDatabase.create_job
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.vendor
[Debug]   VendorProvider:  Arguments: (, , 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor mozilla-central/source/media/libpng/moz.yaml --revision try_rev --patch-mode none
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.vendor
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_add_bug_id
[Debug]   MySQLDatabase:  Arguments: (, , 50)
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_add_bug_id
[Debug]   MercurialProvider: ================================================
[Info]    MercurialProvider: Beginning MercurialProvider.commit
[Debug]   MercurialProvider:  Arguments: (, , 50, 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed hg commit -m Bug 50 - Update libpng to try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    MercurialProvider: Ending MercurialProvider.commit
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.patch
[Debug]   VendorProvider:  Arguments: (, , 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor --patch-mode only mozilla-central/source/media/libpng/moz.yaml
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.patch
[Debug]   MercurialProvider: ================================================
[Info]    MercurialProvider: Beginning MercurialProvider.commit_patches
[Debug]   MercurialProvider:  Arguments: (, , 50, 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed hg commit -m Bug 50 - Apply mozilla patches for libpng
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    MercurialProvider: Ending MercurialProvider.commit_patches
[Debug]   TaskclusterProvider: ================================================
[Info]    TaskclusterProvider: Beginning TaskclusterProvider.submit_to_try
[Debug]   TaskclusterProvider:  Arguments: (, , 'linux64')
[Debug]   TaskclusterProvider: ================================================
[Info]    TaskclusterProvider: Beginning TaskclusterProvider._vcs_setup
[Debug]   TaskclusterProvider:  Arguments: (,)
[Info]    TaskclusterProvider: Ending TaskclusterProvider._vcs_setup
[Info]    TestCommandProvider: Mocked Command executed ./mach try auto --tasks-regex linux64
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TaskclusterProvider: Submitted try run at https://treeherder.mozilla.org/#/jobs?repo=try&revision=try_rev
[Info]    TaskclusterProvider: Ending TaskclusterProvider.submit_to_try
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.add_try_run
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', 'initial platform')
[Info]    MySQLDatabase: Ending MySQLDatabase.add_try_run
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_status
[Debug]   MySQLDatabase:  Arguments: (, )
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_status
[Debug]   PhabricatorProvider: ================================================
[Info]    PhabricatorProvider: Beginning PhabricatorProvider.submit_patch
[Debug]   PhabricatorProvider:  Arguments: (, 50)
[Info]    TestCommandProvider: Mocked Command executed arc diff --verbatim --conduit-uri https://phabricator-dev.allizom.org/ --
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed echo '{"transactions": [{"type":"bugzilla.bug-id", "value":"50"}], "objectIdentifier": "83050"}' | arc call-conduit --conduit-uri=https://phabricator-dev.allizom.org/ differential.revision.edit --
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    PhabricatorProvider: Submitted phabricator patch at https://phabricator-dev.allizom.org/83050
[Info]    PhabricatorProvider: Ending PhabricatorProvider.submit_patch
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_add_phab_revision
[Debug]   MySQLDatabase:  Arguments: (, , '83050')
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_add_phab_revision
[Info]    VendorTaskRunner: Ending VendorTaskRunner._process_new_job
[Info]    VendorTaskRunner: Removing any outgoing commits before moving on.
[Info]    TestCommandProvider: Mocked Command executed hg status
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed hg strip roots(outgoing()) --no-backup
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    In _check_jobs looking for status JOBSTATUS.AWAITING_INITIAL_PLATFORM_TRY_RESULTS and outcome JOBOUTCOME.PENDING
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Info]    MySQLDatabase: Dropping tmp database updatebot_test_1653405055371052_2568
FAIL
testSecondJobButFirstIsntDone (tests.functionality_two_platforms.TestFunctionality) ... ok
testSecondJobReferencesFirst (tests.functionality_two_platforms.TestFunctionality) ... ok
testThreeJobs (tests.functionality_two_platforms.TestFunctionality) ... ok
testThreeJobsButDontLetThemFinish (tests.functionality_two_platforms.TestFunctionality) ... ok
testThreeJobsReopenFirst (tests.functionality_two_platforms.TestFunctionality) ... ok
testInheritance (tests.python_language.TestCommandRunner) ... ok
testString (tests.python_language.TestCommandRunner) ... ok
test_combine (tests.treeherder_api.TestTaskclusterProvider) ... ok
test_correlation (tests.treeherder_api.TestTaskclusterProvider) ... ok
test_failure_classification (tests.treeherder_api.TestTaskclusterProvider) ... ok
test_job_details (tests.treeherder_api.TestTaskclusterProvider) ... ok
test_push_exception_handling (tests.treeherder_api.TestTaskclusterProvider) ... ok
test_push_health (tests.treeherder_api.TestTaskclusterProvider) ... ok
test_retrigger (tests.treeherder_api.TestTaskclusterProvider) ... ok
test_transform (tests.treeherder_api.TestTaskclusterProvider) ... ok
testLibraryExceptions (tests.library.TestLibraryProvider) ... ok
testLibraryFindAndImport (tests.library.TestLibraryProvider) ... ok
testLambda1 (tests.lambda_capture.TestLambdaCapture) ... ok
testLambda1 (tests.class_passing.TestClassPassing) ... ok
testFrequency (tests.frequency.TestTaskFrequency) ... ok

======================================================================
FAIL: testFailsDuringPatching (tests.functionality_all_platforms.TestFunctionality)
----------------------------------------------------------------------
Traceback (most recent call last):
  File "/home/circleci/project/components/logging.py", line 38, in func_wrapper
    ret = func(*args, **kwargs)
  File "/home/circleci/project/tests/functionality_all_platforms.py", line 348, in testFailsDuringPatching
    self.assertEqual(JOBSTATUS.DONE, j.status, "Expected status JOBSTATUS.DONE, got status %s" % (j.status.name))
AssertionError:  !=  : Expected status JOBSTATUS.DONE, got status AWAITING_SECOND_PLATFORMS_TRY_RESULTS

Stdout:
[Info]    Beginning TestFunctionality.testFailsDuringPatching
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.check_database
[Debug]   MySQLDatabase:  Arguments: (,)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase._check_and_get_configuration
[Debug]   MySQLDatabase:  Arguments: (,)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase._create_database
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase._create_database
[Info]    MySQLDatabase: Ending MySQLDatabase._check_and_get_configuration
[Info]    MySQLDatabase: Ending MySQLDatabase.check_database
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    TestCommandProvider: Mocked Command executed git log -1 --oneline
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    Running Updatebot version: 0481f1c (HEAD -> issue-115-add-revision-to-log, origin/issue-115-add-revision-to-log) Issue #115 - Add revision of updatebot to log output
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.updatebot_is_enabled
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.updatebot_is_enabled
[Info]    Changing to gecko directory '.'
[Info]    Skipping dav1d because it doesn't meet the filter 'png'
[Info]    Skipping cubeb-query because it doesn't meet the filter 'png'
[Info]    Skipping cubeb-path because it doesn't meet the filter 'png'
[Info]    Skipping cube-2commits because it doesn't meet the filter 'png'
[Info]    Skipping aom because it doesn't meet the filter 'png'
[Info]    Skipping aom because it doesn't meet the filter 'png'
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.check_for_update
[Debug]   VendorProvider:  Arguments: (, )
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor --check-for-update mozilla-central/source/media/libpng/moz.yaml
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.check_for_update
[Info]    VendorTaskRunner: Processing libpng for an upstream revision try_rev.
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    VendorTaskRunner: try_rev is a brand new revision to updatebot.
[Debug]   VendorTaskRunner: ================================================
[Info]    VendorTaskRunner: Beginning VendorTaskRunner._process_new_job
[Debug]   VendorTaskRunner:  Arguments: (, , , 'try_rev', '2020-08-21 15:13:49')
[Debug]   VendorTaskRunner: ================================================
[Info]    VendorTaskRunner: Beginning BaseTaskRunner._should_process_new_job
[Debug]   VendorTaskRunner:  Arguments: (, , , 'try_rev')
[Info]    VendorTaskRunner: Task frequency is 'every' so processing the new job.
[Info]    VendorTaskRunner: Ending BaseTaskRunner._should_process_new_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_all_jobs_for_library
[Debug]   MySQLDatabase:  Arguments: (, )
[Debug]   MySQLDatabase: Function returned a list of 0 objects
[Info]    MySQLDatabase: Ending MySQLDatabase.get_all_jobs_for_library
[Info]    VendorTaskRunner: Found 0 active jobs for this library
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_all_jobs_for_library
[Debug]   MySQLDatabase:  Arguments: (, , False)
[Debug]   MySQLDatabase: Function returned a list of 0 objects
[Info]    MySQLDatabase: Ending MySQLDatabase.get_all_jobs_for_library
[Debug]   SCMProvider: ================================================
[Info]    SCMProvider: Beginning SCMProvider.check_for_update
[Debug]   SCMProvider:  Arguments: (, , , 'try_rev', [])
[Info]    TestCommandProvider: Mocked Command executed git clone https://example.invalid .
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed git merge-base _current origin/try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Debug]   SCMProvider: Our common ancestor is _current.
[Info]    TestCommandProvider: Mocked Command executed git log --pretty=%H|%ai|%ci --no-merges _current..origin/try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Debug]   SCMProvider: We've never run a job for this library before.
[Info]    TestCommandProvider: Mocked Command executed git diff --name-status try_rev^ try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed git log --pretty=%s -1 try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed git log --pretty=%an -1 try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed git log --pretty=%b -1 try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed git diff --name-status try_rev^ try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed git log --pretty=%s -1 try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed git log --pretty=%an -1 try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed git log --pretty=%b -1 try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    SCMProvider: Ending SCMProvider.check_for_update
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.create_job
[Debug]   MySQLDatabase:  Arguments: (, , , 'try_rev', 87, , , 0)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    MySQLDatabase: Ending MySQLDatabase.create_job
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.vendor
[Debug]   VendorProvider:  Arguments: (, , 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor mozilla-central/source/media/libpng/moz.yaml --revision try_rev --patch-mode none
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.vendor
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_add_bug_id
[Debug]   MySQLDatabase:  Arguments: (, , 50)
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_add_bug_id
[Debug]   MercurialProvider: ================================================
[Info]    MercurialProvider: Beginning MercurialProvider.commit
[Debug]   MercurialProvider:  Arguments: (, , 50, 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed hg commit -m Bug 50 - Update libpng to try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    MercurialProvider: Ending MercurialProvider.commit
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.patch
[Debug]   VendorProvider:  Arguments: (, , 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor --patch-mode only mozilla-central/source/media/libpng/moz.yaml
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.patch
[Debug]   MercurialProvider: ================================================
[Info]    MercurialProvider: Beginning MercurialProvider.commit_patches
[Debug]   MercurialProvider:  Arguments: (, , 50, 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed hg commit -m Bug 50 - Apply mozilla patches for libpng
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    MercurialProvider: Ending MercurialProvider.commit_patches
[Debug]   TaskclusterProvider: ================================================
[Info]    TaskclusterProvider: Beginning TaskclusterProvider.submit_to_try
[Debug]   TaskclusterProvider:  Arguments: (, , '')
[Debug]   TaskclusterProvider: ================================================
[Info]    TaskclusterProvider: Beginning TaskclusterProvider._vcs_setup
[Debug]   TaskclusterProvider:  Arguments: (,)
[Info]    TaskclusterProvider: Ending TaskclusterProvider._vcs_setup
[Info]    TestCommandProvider: Mocked Command executed ./mach try auto
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TaskclusterProvider: Submitted try run at https://treeherder.mozilla.org/#/jobs?repo=try&revision=try_rev
[Info]    TaskclusterProvider: Ending TaskclusterProvider.submit_to_try
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.add_try_run
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', 'all platforms')
[Info]    MySQLDatabase: Ending MySQLDatabase.add_try_run
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_status
[Debug]   MySQLDatabase:  Arguments: (, )
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_status
[Debug]   PhabricatorProvider: ================================================
[Info]    PhabricatorProvider: Beginning PhabricatorProvider.submit_patch
[Debug]   PhabricatorProvider:  Arguments: (, 50)
[Info]    TestCommandProvider: Mocked Command executed arc diff --verbatim --conduit-uri https://phabricator-dev.allizom.org/ --
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed echo '{"transactions": [{"type":"bugzilla.bug-id", "value":"50"}], "objectIdentifier": "83050"}' | arc call-conduit --conduit-uri=https://phabricator-dev.allizom.org/ differential.revision.edit --
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    PhabricatorProvider: Submitted phabricator patch at https://phabricator-dev.allizom.org/83050
[Info]    PhabricatorProvider: Ending PhabricatorProvider.submit_patch
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_add_phab_revision
[Debug]   MySQLDatabase:  Arguments: (, , '83050')
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_add_phab_revision
[Info]    VendorTaskRunner: Ending VendorTaskRunner._process_new_job
[Info]    VendorTaskRunner: Removing any outgoing commits before moving on.
[Info]    TestCommandProvider: Mocked Command executed hg status
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed hg strip roots(outgoing()) --no-backup
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job

======================================================================
FAIL: testPatchJob (tests.functionality_all_platforms.TestFunctionality)
----------------------------------------------------------------------
Traceback (most recent call last):
  File "/home/circleci/project/components/logging.py", line 38, in func_wrapper
    ret = func(*args, **kwargs)
  File "/home/circleci/project/tests/functionality_all_platforms.py", line 276, in testPatchJob
    self.assertTrue(was_patched, "Did not successfully patch as expected.")
AssertionError: False is not true : Did not successfully patch as expected.

Stdout:
[Info]    Beginning TestFunctionality.testPatchJob
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.check_database
[Debug]   MySQLDatabase:  Arguments: (,)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase._check_and_get_configuration
[Debug]   MySQLDatabase:  Arguments: (,)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase._create_database
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase._create_database
[Info]    MySQLDatabase: Ending MySQLDatabase._check_and_get_configuration
[Info]    MySQLDatabase: Ending MySQLDatabase.check_database
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    TestCommandProvider: Mocked Command executed git log -1 --oneline
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    Running Updatebot version: 0481f1c (HEAD -> issue-115-add-revision-to-log, origin/issue-115-add-revision-to-log) Issue #115 - Add revision of updatebot to log output
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.updatebot_is_enabled
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.updatebot_is_enabled
[Info]    Changing to gecko directory '.'
[Info]    Skipping dav1d because it doesn't meet the filter 'png'
[Info]    Skipping cubeb-query because it doesn't meet the filter 'png'
[Info]    Skipping cubeb-path because it doesn't meet the filter 'png'
[Info]    Skipping cube-2commits because it doesn't meet the filter 'png'
[Info]    Skipping aom because it doesn't meet the filter 'png'
[Info]    Skipping aom because it doesn't meet the filter 'png'
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.check_for_update
[Debug]   VendorProvider:  Arguments: (, )
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor --check-for-update mozilla-central/source/media/libpng/moz.yaml
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.check_for_update
[Info]    VendorTaskRunner: Processing libpng for an upstream revision try_rev.
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    VendorTaskRunner: try_rev is a brand new revision to updatebot.
[Debug]   VendorTaskRunner: ================================================
[Info]    VendorTaskRunner: Beginning VendorTaskRunner._process_new_job
[Debug]   VendorTaskRunner:  Arguments: (, , , 'try_rev', '2020-08-21 15:13:49')
[Debug]   VendorTaskRunner: ================================================
[Info]    VendorTaskRunner: Beginning BaseTaskRunner._should_process_new_job
[Debug]   VendorTaskRunner:  Arguments: (, , , 'try_rev')
[Info]    VendorTaskRunner: Task frequency is 'every' so processing the new job.
[Info]    VendorTaskRunner: Ending BaseTaskRunner._should_process_new_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_all_jobs_for_library
[Debug]   MySQLDatabase:  Arguments: (, )
[Debug]   MySQLDatabase: Function returned a list of 0 objects
[Info]    MySQLDatabase: Ending MySQLDatabase.get_all_jobs_for_library
[Info]    VendorTaskRunner: Found 0 active jobs for this library
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_all_jobs_for_library
[Debug]   MySQLDatabase:  Arguments: (, , False)
[Debug]   MySQLDatabase: Function returned a list of 0 objects
[Info]    MySQLDatabase: Ending MySQLDatabase.get_all_jobs_for_library
[Debug]   SCMProvider: ================================================
[Info]    SCMProvider: Beginning SCMProvider.check_for_update
[Debug]   SCMProvider:  Arguments: (, , , 'try_rev', [])
[Info]    SCMProvider: Ending SCMProvider.check_for_update
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.create_job
[Debug]   MySQLDatabase:  Arguments: (, , , 'try_rev', 87, , , 0)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    MySQLDatabase: Ending MySQLDatabase.create_job
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.vendor
[Debug]   VendorProvider:  Arguments: (, , 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor mozilla-central/source/media/libpng/moz.yaml --revision try_rev --patch-mode none
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.vendor
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_add_bug_id
[Debug]   MySQLDatabase:  Arguments: (, , 50)
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_add_bug_id
[Debug]   MercurialProvider: ================================================
[Info]    MercurialProvider: Beginning MercurialProvider.commit
[Debug]   MercurialProvider:  Arguments: (, , 50, 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed hg commit -m Bug 50 - Update libpng to try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    MercurialProvider: Ending MercurialProvider.commit
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.patch
[Debug]   VendorProvider:  Arguments: (, , 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor --patch-mode only mozilla-central/source/media/libpng/moz.yaml
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.patch
[Debug]   MercurialProvider: ================================================
[Info]    MercurialProvider: Beginning MercurialProvider.commit_patches
[Debug]   MercurialProvider:  Arguments: (, , 50, 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed hg commit -m Bug 50 - Apply mozilla patches for libpng
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    MercurialProvider: Ending MercurialProvider.commit_patches
[Debug]   TaskclusterProvider: ================================================
[Info]    TaskclusterProvider: Beginning TaskclusterProvider.submit_to_try
[Debug]   TaskclusterProvider:  Arguments: (, , '')
[Debug]   TaskclusterProvider: ================================================
[Info]    TaskclusterProvider: Beginning TaskclusterProvider._vcs_setup
[Debug]   TaskclusterProvider:  Arguments: (,)
[Info]    TaskclusterProvider: Ending TaskclusterProvider._vcs_setup
[Info]    TestCommandProvider: Mocked Command executed ./mach try auto
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TaskclusterProvider: Submitted try run at https://treeherder.mozilla.org/#/jobs?repo=try&revision=try_rev
[Info]    TaskclusterProvider: Ending TaskclusterProvider.submit_to_try
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.add_try_run
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', 'all platforms')
[Info]    MySQLDatabase: Ending MySQLDatabase.add_try_run
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_status
[Debug]   MySQLDatabase:  Arguments: (, )
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_status
[Debug]   PhabricatorProvider: ================================================
[Info]    PhabricatorProvider: Beginning PhabricatorProvider.submit_patch
[Debug]   PhabricatorProvider:  Arguments: (, 50)
[Info]    TestCommandProvider: Mocked Command executed arc diff --verbatim --conduit-uri https://phabricator-dev.allizom.org/ --
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed echo '{"transactions": [{"type":"bugzilla.bug-id", "value":"50"}], "objectIdentifier": "83050"}' | arc call-conduit --conduit-uri=https://phabricator-dev.allizom.org/ differential.revision.edit --
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    PhabricatorProvider: Submitted phabricator patch at https://phabricator-dev.allizom.org/83050
[Info]    PhabricatorProvider: Ending PhabricatorProvider.submit_patch
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_add_phab_revision
[Debug]   MySQLDatabase:  Arguments: (, , '83050')
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_add_phab_revision
[Info]    VendorTaskRunner: Ending VendorTaskRunner._process_new_job
[Info]    VendorTaskRunner: Removing any outgoing commits before moving on.
[Info]    TestCommandProvider: Mocked Command executed hg status
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed hg strip roots(outgoing()) --no-backup
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job

======================================================================
FAIL: testFailsDuringPatching (tests.functionality_two_platforms.TestFunctionality)
----------------------------------------------------------------------
Traceback (most recent call last):
  File "/home/circleci/project/components/logging.py", line 38, in func_wrapper
    ret = func(*args, **kwargs)
  File "/home/circleci/project/tests/functionality_two_platforms.py", line 370, in testFailsDuringPatching
    self.assertEqual(JOBSTATUS.DONE, j.status, "Expected status JOBSTATUS.DONE, got status %s" % (j.status.name))
AssertionError:  !=  : Expected status JOBSTATUS.DONE, got status AWAITING_INITIAL_PLATFORM_TRY_RESULTS

Stdout:
[Info]    ================================================
[Info]    Beginning TestFunctionality.testFailsDuringPatching
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.check_database
[Debug]   MySQLDatabase:  Arguments: (,)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase._check_and_get_configuration
[Debug]   MySQLDatabase:  Arguments: (,)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase._create_database
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase._create_database
[Info]    MySQLDatabase: Ending MySQLDatabase._check_and_get_configuration
[Info]    MySQLDatabase: Ending MySQLDatabase.check_database
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    TestCommandProvider: Mocked Command executed git log -1 --oneline
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    Running Updatebot version: 0481f1c (HEAD -> issue-115-add-revision-to-log, origin/issue-115-add-revision-to-log) Issue #115 - Add revision of updatebot to log output
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.updatebot_is_enabled
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.updatebot_is_enabled
[Info]    Changing to gecko directory '.'
[Info]    Skipping dav1d because it doesn't meet the filter 'png'
[Info]    Skipping cubeb-query because it doesn't meet the filter 'png'
[Info]    Skipping cubeb-path because it doesn't meet the filter 'png'
[Info]    Skipping cube-2commits because it doesn't meet the filter 'png'
[Info]    Skipping aom because it doesn't meet the filter 'png'
[Info]    Skipping aom because it doesn't meet the filter 'png'
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.check_for_update
[Debug]   VendorProvider:  Arguments: (, )
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor --check-for-update mozilla-central/source/media/libpng/moz.yaml
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.check_for_update
[Info]    VendorTaskRunner: Processing libpng for an upstream revision try_rev.
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    VendorTaskRunner: try_rev is a brand new revision to updatebot.
[Debug]   VendorTaskRunner: ================================================
[Info]    VendorTaskRunner: Beginning VendorTaskRunner._process_new_job
[Debug]   VendorTaskRunner:  Arguments: (, , , 'try_rev', '2020-08-21 15:13:49')
[Debug]   VendorTaskRunner: ================================================
[Info]    VendorTaskRunner: Beginning BaseTaskRunner._should_process_new_job
[Debug]   VendorTaskRunner:  Arguments: (, , , 'try_rev')
[Info]    VendorTaskRunner: Task frequency is 'every' so processing the new job.
[Info]    VendorTaskRunner: Ending BaseTaskRunner._should_process_new_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_all_jobs_for_library
[Debug]   MySQLDatabase:  Arguments: (, )
[Debug]   MySQLDatabase: Function returned a list of 0 objects
[Info]    MySQLDatabase: Ending MySQLDatabase.get_all_jobs_for_library
[Info]    VendorTaskRunner: Found 0 active jobs for this library
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_all_jobs_for_library
[Debug]   MySQLDatabase:  Arguments: (, , False)
[Debug]   MySQLDatabase: Function returned a list of 0 objects
[Info]    MySQLDatabase: Ending MySQLDatabase.get_all_jobs_for_library
[Debug]   SCMProvider: ================================================
[Info]    SCMProvider: Beginning SCMProvider.check_for_update
[Debug]   SCMProvider:  Arguments: (, , , 'try_rev', [])
[Info]    SCMProvider: Ending SCMProvider.check_for_update
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.create_job
[Debug]   MySQLDatabase:  Arguments: (, , , 'try_rev', 87, , , 0)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    MySQLDatabase: Ending MySQLDatabase.create_job
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.vendor
[Debug]   VendorProvider:  Arguments: (, , 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor mozilla-central/source/media/libpng/moz.yaml --revision try_rev --patch-mode none
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.vendor
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_add_bug_id
[Debug]   MySQLDatabase:  Arguments: (, , 50)
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_add_bug_id
[Debug]   MercurialProvider: ================================================
[Info]    MercurialProvider: Beginning MercurialProvider.commit
[Debug]   MercurialProvider:  Arguments: (, , 50, 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed hg commit -m Bug 50 - Update libpng to try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    MercurialProvider: Ending MercurialProvider.commit
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.patch
[Debug]   VendorProvider:  Arguments: (, , 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor --patch-mode only mozilla-central/source/media/libpng/moz.yaml
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.patch
[Debug]   MercurialProvider: ================================================
[Info]    MercurialProvider: Beginning MercurialProvider.commit_patches
[Debug]   MercurialProvider:  Arguments: (, , 50, 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed hg commit -m Bug 50 - Apply mozilla patches for libpng
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    MercurialProvider: Ending MercurialProvider.commit_patches
[Debug]   TaskclusterProvider: ================================================
[Info]    TaskclusterProvider: Beginning TaskclusterProvider.submit_to_try
[Debug]   TaskclusterProvider:  Arguments: (, , 'linux64')
[Debug]   TaskclusterProvider: ================================================
[Info]    TaskclusterProvider: Beginning TaskclusterProvider._vcs_setup
[Debug]   TaskclusterProvider:  Arguments: (,)
[Info]    TaskclusterProvider: Ending TaskclusterProvider._vcs_setup
[Info]    TestCommandProvider: Mocked Command executed ./mach try auto --tasks-regex linux64
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TaskclusterProvider: Submitted try run at https://treeherder.mozilla.org/#/jobs?repo=try&revision=try_rev
[Info]    TaskclusterProvider: Ending TaskclusterProvider.submit_to_try
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.add_try_run
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', 'initial platform')
[Info]    MySQLDatabase: Ending MySQLDatabase.add_try_run
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_status
[Debug]   MySQLDatabase:  Arguments: (, )
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_status
[Debug]   PhabricatorProvider: ================================================
[Info]    PhabricatorProvider: Beginning PhabricatorProvider.submit_patch
[Debug]   PhabricatorProvider:  Arguments: (, 50)
[Info]    TestCommandProvider: Mocked Command executed arc diff --verbatim --conduit-uri https://phabricator-dev.allizom.org/ --
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed echo '{"transactions": [{"type":"bugzilla.bug-id", "value":"50"}], "objectIdentifier": "83050"}' | arc call-conduit --conduit-uri=https://phabricator-dev.allizom.org/ differential.revision.edit --
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    PhabricatorProvider: Submitted phabricator patch at https://phabricator-dev.allizom.org/83050
[Info]    PhabricatorProvider: Ending PhabricatorProvider.submit_patch
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_add_phab_revision
[Debug]   MySQLDatabase:  Arguments: (, , '83050')
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_add_phab_revision
[Info]    VendorTaskRunner: Ending VendorTaskRunner._process_new_job
[Info]    VendorTaskRunner: Removing any outgoing commits before moving on.
[Info]    TestCommandProvider: Mocked Command executed hg status
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed hg strip roots(outgoing()) --no-backup
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job

======================================================================
FAIL: testPatchJob (tests.functionality_two_platforms.TestFunctionality)
----------------------------------------------------------------------
Traceback (most recent call last):
  File "/home/circleci/project/components/logging.py", line 38, in func_wrapper
    ret = func(*args, **kwargs)
  File "/home/circleci/project/tests/functionality_two_platforms.py", line 295, in testPatchJob
    self.assertTrue(was_patched, "Did not successfully patch as expected.")
AssertionError: False is not true : Did not successfully patch as expected.

Stdout:
[Info]    ================================================
[Info]    Beginning TestFunctionality.testPatchJob
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.check_database
[Debug]   MySQLDatabase:  Arguments: (,)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase._check_and_get_configuration
[Debug]   MySQLDatabase:  Arguments: (,)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase._create_database
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase._create_database
[Info]    MySQLDatabase: Ending MySQLDatabase._check_and_get_configuration
[Info]    MySQLDatabase: Ending MySQLDatabase.check_database
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    TestCommandProvider: Mocked Command executed git log -1 --oneline
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    Running Updatebot version: 0481f1c (HEAD -> issue-115-add-revision-to-log, origin/issue-115-add-revision-to-log) Issue #115 - Add revision of updatebot to log output
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.updatebot_is_enabled
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.updatebot_is_enabled
[Info]    Changing to gecko directory '.'
[Info]    Skipping dav1d because it doesn't meet the filter 'png'
[Info]    Skipping cubeb-query because it doesn't meet the filter 'png'
[Info]    Skipping cubeb-path because it doesn't meet the filter 'png'
[Info]    Skipping cube-2commits because it doesn't meet the filter 'png'
[Info]    Skipping aom because it doesn't meet the filter 'png'
[Info]    Skipping aom because it doesn't meet the filter 'png'
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.check_for_update
[Debug]   VendorProvider:  Arguments: (, )
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor --check-for-update mozilla-central/source/media/libpng/moz.yaml
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.check_for_update
[Info]    VendorTaskRunner: Processing libpng for an upstream revision try_rev.
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    VendorTaskRunner: try_rev is a brand new revision to updatebot.
[Debug]   VendorTaskRunner: ================================================
[Info]    VendorTaskRunner: Beginning VendorTaskRunner._process_new_job
[Debug]   VendorTaskRunner:  Arguments: (, , , 'try_rev', '2020-08-21 15:13:49')
[Debug]   VendorTaskRunner: ================================================
[Info]    VendorTaskRunner: Beginning BaseTaskRunner._should_process_new_job
[Debug]   VendorTaskRunner:  Arguments: (, , , 'try_rev')
[Info]    VendorTaskRunner: Task frequency is 'every' so processing the new job.
[Info]    VendorTaskRunner: Ending BaseTaskRunner._should_process_new_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_all_jobs_for_library
[Debug]   MySQLDatabase:  Arguments: (, )
[Debug]   MySQLDatabase: Function returned a list of 0 objects
[Info]    MySQLDatabase: Ending MySQLDatabase.get_all_jobs_for_library
[Info]    VendorTaskRunner: Found 0 active jobs for this library
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_all_jobs_for_library
[Debug]   MySQLDatabase:  Arguments: (, , False)
[Debug]   MySQLDatabase: Function returned a list of 0 objects
[Info]    MySQLDatabase: Ending MySQLDatabase.get_all_jobs_for_library
[Debug]   SCMProvider: ================================================
[Info]    SCMProvider: Beginning SCMProvider.check_for_update
[Debug]   SCMProvider:  Arguments: (, , , 'try_rev', [])
[Info]    SCMProvider: Ending SCMProvider.check_for_update
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.create_job
[Debug]   MySQLDatabase:  Arguments: (, , , 'try_rev', 87, , , 0)
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    MySQLDatabase: Ending MySQLDatabase.create_job
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.vendor
[Debug]   VendorProvider:  Arguments: (, , 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor mozilla-central/source/media/libpng/moz.yaml --revision try_rev --patch-mode none
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.vendor
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_add_bug_id
[Debug]   MySQLDatabase:  Arguments: (, , 50)
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_add_bug_id
[Debug]   MercurialProvider: ================================================
[Info]    MercurialProvider: Beginning MercurialProvider.commit
[Debug]   MercurialProvider:  Arguments: (, , 50, 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed hg commit -m Bug 50 - Update libpng to try_rev
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    MercurialProvider: Ending MercurialProvider.commit
[Debug]   VendorProvider: ================================================
[Info]    VendorProvider: Beginning VendorProvider.patch
[Debug]   VendorProvider:  Arguments: (, , 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed ./mach vendor --patch-mode only mozilla-central/source/media/libpng/moz.yaml
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    VendorProvider: Ending VendorProvider.patch
[Debug]   MercurialProvider: ================================================
[Info]    MercurialProvider: Beginning MercurialProvider.commit_patches
[Debug]   MercurialProvider:  Arguments: (, , 50, 'try_rev')
[Info]    TestCommandProvider: Mocked Command executed hg commit -m Bug 50 - Apply mozilla patches for libpng
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    MercurialProvider: Ending MercurialProvider.commit_patches
[Debug]   TaskclusterProvider: ================================================
[Info]    TaskclusterProvider: Beginning TaskclusterProvider.submit_to_try
[Debug]   TaskclusterProvider:  Arguments: (, , 'linux64')
[Debug]   TaskclusterProvider: ================================================
[Info]    TaskclusterProvider: Beginning TaskclusterProvider._vcs_setup
[Debug]   TaskclusterProvider:  Arguments: (,)
[Info]    TaskclusterProvider: Ending TaskclusterProvider._vcs_setup
[Info]    TestCommandProvider: Mocked Command executed ./mach try auto --tasks-regex linux64
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TaskclusterProvider: Submitted try run at https://treeherder.mozilla.org/#/jobs?repo=try&revision=try_rev
[Info]    TaskclusterProvider: Ending TaskclusterProvider.submit_to_try
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.add_try_run
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', 'initial platform')
[Info]    MySQLDatabase: Ending MySQLDatabase.add_try_run
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_status
[Debug]   MySQLDatabase:  Arguments: (, )
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_status
[Debug]   PhabricatorProvider: ================================================
[Info]    PhabricatorProvider: Beginning PhabricatorProvider.submit_patch
[Debug]   PhabricatorProvider:  Arguments: (, 50)
[Info]    TestCommandProvider: Mocked Command executed arc diff --verbatim --conduit-uri https://phabricator-dev.allizom.org/ --
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed echo '{"transactions": [{"type":"bugzilla.bug-id", "value":"50"}], "objectIdentifier": "83050"}' | arc call-conduit --conduit-uri=https://phabricator-dev.allizom.org/ differential.revision.edit --
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    PhabricatorProvider: Submitted phabricator patch at https://phabricator-dev.allizom.org/83050
[Info]    PhabricatorProvider: Ending PhabricatorProvider.submit_patch
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.update_job_add_phab_revision
[Debug]   MySQLDatabase:  Arguments: (, , '83050')
[Info]    MySQLDatabase: Ending MySQLDatabase.update_job_add_phab_revision
[Info]    VendorTaskRunner: Ending VendorTaskRunner._process_new_job
[Info]    VendorTaskRunner: Removing any outgoing commits before moving on.
[Info]    TestCommandProvider: Mocked Command executed hg status
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Info]    TestCommandProvider: Mocked Command executed hg strip roots(outgoing()) --no-backup
[Info]    TestCommandProvider: We found a mapped response, providing it.
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.get_job
[Debug]   MySQLDatabase:  Arguments: (, , 'try_rev', False)
[Info]    MySQLDatabase: Ending MySQLDatabase.get_job
[Info]    In _check_jobs looking for status JOBSTATUS.AWAITING_INITIAL_PLATFORM_TRY_RESULTS and outcome JOBOUTCOME.PENDING
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job
[Debug]   MySQLDatabase: ================================================
[Info]    MySQLDatabase: Beginning MySQLDatabase.delete_job
[Debug]   MySQLDatabase:  Arguments: (,)
[Info]    MySQLDatabase: Ending MySQLDatabase.delete_job

----------------------------------------------------------------------
Ran 81 tests in 19.414s

FAILED (failures=4)
[Info]    MySQLDatabase: Dropping tmp database updatebot_test_1653405039634679_6024
tomrittervg commented 2 years ago

Okay, debugged the intermittent failure and attached another commit for it.