Closed Bug 1892654 Opened 1 year ago Closed 1 year ago

Intermittent AssertionError: True != False : Value a message from info expected in log: | single tracking bug

Categories

(Testing :: XPCShell Harness, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, intermittent-testcase)

Filed by: ctuns [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=455333193&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/KCf7NSxXRk22vubrJs8P2A/runs/0/artifacts/public/logs/live_backing.log


[task 2024-04-22T04:52:36.235Z] 04:52:36     INFO -  Ensure a simple test with an uncaught rejection is reported. ... ok
[task 2024-04-22T04:52:36.237Z] 04:52:36     INFO -  testUnexpectedPass (__main__.XPCShellTests.runSelfTest.<locals>.XPCShellTestsTests.testUnexpectedPass)
[task 2024-04-22T04:52:36.786Z] 04:52:36     INFO -  Check that a simple failing test marked as known-fail in the manifest ... ok
[task 2024-04-22T04:52:36.787Z] 04:52:36     INFO -  testUnicodeInAssertMethods (__main__.XPCShellTests.runSelfTest.<locals>.XPCShellTestsTests.testUnicodeInAssertMethods)
[task 2024-04-22T04:52:37.217Z] 04:52:37     INFO -  Check that passing unicode characters through an assertion method works. ... ok
[task 2024-04-22T04:52:37.219Z] 04:52:37     INFO -  ======================================================================
[task 2024-04-22T04:52:37.219Z] 04:52:37     INFO -  FAIL: testDoPrintWhenVerboseInManifest (__main__.XPCShellTests.runSelfTest.<locals>.XPCShellTestsTests.testDoPrintWhenVerboseInManifest)
[task 2024-04-22T04:52:37.219Z] 04:52:37     INFO -  Check that info() and similar calls that generate output have the
[task 2024-04-22T04:52:37.219Z] 04:52:37     INFO -  ----------------------------------------------------------------------
[task 2024-04-22T04:52:37.220Z] 04:52:37     INFO -  Traceback (most recent call last):
[task 2024-04-22T04:52:37.220Z] 04:52:37     INFO -    File "D:\task_171376075334508\build\tests\xpcshell\selftest.py", line 1312, in testDoPrintWhenVerboseInManifest
[task 2024-04-22T04:52:37.220Z] 04:52:37     INFO -      self.assertInLog("a message from info")
[task 2024-04-22T04:52:37.220Z] 04:52:37     INFO -    File "D:\task_171376075334508\build\tests\xpcshell\selftest.py", line 573, in assertInLog
[task 2024-04-22T04:52:37.221Z] 04:52:37     INFO -      self._assertLog(s, True)
[task 2024-04-22T04:52:37.221Z] 04:52:37     INFO -    File "D:\task_171376075334508\build\tests\xpcshell\selftest.py", line 559, in _assertLog
[task 2024-04-22T04:52:37.221Z] 04:52:37     INFO -      self.assertEqual(
[task 2024-04-22T04:52:37.221Z] 04:52:37     INFO -  AssertionError: True != False : Value a message from info expected in log:
[task 2024-04-22T04:52:37.221Z] 04:52:37     INFO -  ========
[task 2024-04-22T04:52:37.222Z] 04:52:37     INFO -  These variables are available in the mozinfo environment and can be used to skip tests conditionally:
[task 2024-04-22T04:52:37.222Z] 04:52:37     INFO -      apple_catalina: False
[task 2024-04-22T04:52:37.222Z] 04:52:37     INFO -      apple_silicon: False
[task 2024-04-22T04:52:37.222Z] 04:52:37     INFO -      appname: firefox
[task 2024-04-22T04:52:37.222Z] 04:52:37     INFO -      arch: x86_64
[task 2024-04-22T04:52:37.222Z] 04:52:37     INFO -      artifact: False
[task 2024-04-22T04:52:37.223Z] 04:52:37     INFO -      asan: False
[task 2024-04-22T04:52:37.223Z] 04:52:37     INFO -      automation: True
[task 2024-04-22T04:52:37.223Z] 04:52:37     INFO -      bin_suffix: .exe
[task 2024-04-22T04:52:37.223Z] 04:52:37     INFO -      bits: 64
[task 2024-04-22T04:52:37.223Z] 04:52:37     INFO -      buildapp: browser
[task 2024-04-22T04:52:37.223Z] 04:52:37     INFO -      buildtype: opt
[task 2024-04-22T04:52:37.224Z] 04:52:37     INFO -      buildtype_guess: opt
[task 2024-04-22T04:52:37.224Z] 04:52:37     INFO -      cc_type: clang-cl
[task 2024-04-22T04:52:37.224Z] 04:52:37     INFO -      ccov: False
[task 2024-04-22T04:52:37.225Z] 04:52:37     INFO -      condprof: False
[task 2024-04-22T04:52:37.225Z] 04:52:37     INFO -      crashreporter: True
[task 2024-04-22T04:52:37.225Z] 04:52:37     INFO -      datareporting: True
[task 2024-04-22T04:52:37.225Z] 04:52:37     INFO -      debug: False
[task 2024-04-22T04:52:37.225Z] 04:52:37     INFO -      devedition: False
[task 2024-04-22T04:52:37.225Z] 04:52:37     INFO -      display: None
[task 2024-04-22T04:52:37.226Z] 04:52:37     INFO -      domstreams: True
[task 2024-04-22T04:52:37.226Z] 04:52:37     INFO -      early_beta_or_earlier: True
[task 2024-04-22T04:52:37.226Z] 04:52:37     INFO -      fission: True
[task 2024-04-22T04:52:37.226Z] 04:52:37     INFO -      has_sandbox: True
[task 2024-04-22T04:52:37.226Z] 04:52:37     INFO -      healthreport: True
[task 2024-04-22T04:52:37.226Z] 04:52:37     INFO -      is_ubuntu: False
[task 2024-04-22T04:52:37.226Z] 04:52:37     INFO -      isolated_process: False
[task 2024-04-22T04:52:37.227Z] 04:52:37     INFO -      mozconfig: /builds/worker/checkouts/gecko/.mozconfig
[task 2024-04-22T04:52:37.227Z] 04:52:37     INFO -      nightly_build: True
[task 2024-04-22T04:52:37.227Z] 04:52:37     INFO -      normandy: True
[task 2024-04-22T04:52:37.227Z] 04:52:37     INFO -      official: True
[task 2024-04-22T04:52:37.227Z] 04:52:37     INFO -      os: win
[task 2024-04-22T04:52:37.227Z] 04:52:37     INFO -      os_version: 10.2009
[task 2024-04-22T04:52:37.228Z] 04:52:37     INFO -      pgo: False
[task 2024-04-22T04:52:37.228Z] 04:52:37     INFO -      platform_guess: win64
[task 2024-04-22T04:52:37.228Z] 04:52:37     INFO -      processor: x86_64
[task 2024-04-22T04:52:37.228Z] 04:52:37     INFO -      release_or_beta: False
[task 2024-04-22T04:52:37.228Z] 04:52:37     INFO -      require_signing: False
[task 2024-04-22T04:52:37.228Z] 04:52:37     INFO -      serviceworker_e10s: True
[task 2024-04-22T04:52:37.229Z] 04:52:37     INFO -      sessionHistoryInParent: True
[task 2024-04-22T04:52:37.229Z] 04:52:37     INFO -      socketprocess_networking: False
[task 2024-04-22T04:52:37.229Z] 04:52:37     INFO -      stylo: True
[task 2024-04-22T04:52:37.229Z] 04:52:37     INFO -      sync: True
[task 2024-04-22T04:52:37.229Z] 04:52:37     INFO -      telemetry: False
[task 2024-04-22T04:52:37.229Z] 04:52:37     INFO -      tests_enabled: True
[task 2024-04-22T04:52:37.229Z] 04:52:37     INFO -      toolkit: windows
[task 2024-04-22T04:52:37.230Z] 04:52:37     INFO -      topobjdir: /builds/worker/workspace/obj-build
[task 2024-04-22T04:52:37.230Z] 04:52:37     INFO -      topsrcdir: /builds/worker/checkouts/gecko
[task 2024-04-22T04:52:37.230Z] 04:52:37     INFO -      tsan: False
[task 2024-04-22T04:52:37.230Z] 04:52:37     INFO -      ubsan: False
[task 2024-04-22T04:52:37.230Z] 04:52:37     INFO -      updater: True
[task 2024-04-22T04:52:37.230Z] 04:52:37     INFO -      verify: False
[task 2024-04-22T04:52:37.230Z] 04:52:37     INFO -      version: 10.0.19045
[task 2024-04-22T04:52:37.231Z] 04:52:37     INFO -      win10_2009: True
[task 2024-04-22T04:52:37.231Z] 04:52:37     INFO -      win11_2009: False
[task 2024-04-22T04:52:37.231Z] 04:52:37     INFO -  Found node at D:/task_171376075334508/fetches\node/node.exe
[task 2024-04-22T04:52:37.232Z] 04:52:37     INFO -  Found moz-http2 at D:\task_171376075334508\build\tests\xpcshell\moz-http2\moz-http2.js
[task 2024-04-22T04:52:37.232Z] 04:52:37     INFO -  mozserve | Found Http3Server path: D:\task_171376075334508\build\tests\xpcshell\http3server\http3server.exe
[task 2024-04-22T04:52:37.232Z] 04:52:37     INFO -  mozserve | cert db path: D:\task_171376075334508\build\tests\xpcshell\http3server\http3serverDB
[task 2024-04-22T04:52:37.233Z] 04:52:37     INFO -  mozserve | http3 server msg: HTTP3 server listening on ports 61185, 61186, 61187, 61188 and 61189. EchConfig is @AE3+DQBJBwAgACDm9+M70xjulZVjlaIWyZjppjCQAaxoXC8jEK019ubcEAAQAAEAAQABAAMAAgABAAIAA0AOcHVibGljLmV4YW1wbGUAAA==@
[task 2024-04-22T04:52:37.233Z] 04:52:37     INFO -  Running tests sequentially.
[task 2024-04-22T04:52:37.233Z] 04:52:37     INFO -  SUITE-START | Running 1 tests
[task 2024-04-22T04:52:37.233Z] 04:52:37     INFO -  profile dir is C:\Users\task_171376075334508\AppData\Local\Temp\xpcshell\xpcshellprofile
[task 2024-04-22T04:52:37.233Z] 04:52:37     INFO -  test_verbose.js: Per-test extra prefs will be set:
[task 2024-04-22T04:52:37.234Z] 04:52:37     INFO -  TEST-START | test_verbose.js
Status: NEW → RESOLVED
Closed: 1 year ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.