Jest: mixup with sderr and stdout

Created on 30 May 2017  ·  2Comments  ·  Source: facebook/jest

I get the below error; Expected onBeforeMountComponent() parent and onSetChildren() to be consistent. according to https://github.com/facebook/react/issues/8692 This will be a non issue in React 16. I'm using Enzyme for this test suite, so I can't upgrade to the latest React Native untile Enzyme supports React 16.
I wanted to suppress the error for now, and I noticed something strange:

The test results get posted to stderr instead of stdout and when I turn on verbose logging it get even stranger, stdout gets the errors and most of what I would expect in stdout gets logged to stderr?
(the failing test and console log are on purpose)

The full output of my test:

$ npm test -- --coverage

> App@ test /media/dev/app
> jest "--coverage"

 FAIL  core/tests/components/settings.js
  ● Console

    console.error node_modules/react-native/Libraries/Core/ExceptionsManager.js:71
      Warning: ReactTestUtils has been moved to react-dom/test-utils. Update references to remove this warning.
    console.error node_modules/react-native/Libraries/Core/ExceptionsManager.js:71
      Warning: Exception thrown by hook while handling onSetChildren: Invariant Violation: Expected onBeforeMountComponent() parent and onSetChildren() to be consistent (7 has parents 0 and 5).
      Invariant Violation: Expected onBeforeMountComponent() parent and onSetChildren() to be consistent (7 has parents 0 and 5).
          at invariant (/media/dj/dev/scan-app/node_modules/fbjs/lib/invariant.js:44:15)
          at Object.onSetChildren (/media/dj/dev/scan-app/node_modules/react/lib/ReactComponentTreeHook.js:186:78)
          at callHook (/media/dj/dev/scan-app/node_modules/react-test-renderer/lib/ReactDebugTool.js:27:8)
          at emitEvent (/media/dj/dev/scan-app/node_modules/react-test-renderer/lib/ReactDebugTool.js:39:7)
          at Object.onSetChildren (/media/dj/dev/scan-app/node_modules/react-test-renderer/lib/ReactDebugTool.js:311:5)
          at ReactTestComponent.setChildrenForInstrumentation (/media/dj/dev/scan-app/node_modules/react-test-renderer/lib/ReactMultiChild.js:157:38)
          at ReactTestComponent._updateChildren (/media/dj/dev/scan-app/node_modules/react-test-renderer/lib/ReactMultiChild.js:360:39)
          at ReactTestComponent.updateChildren (/media/dj/dev/scan-app/node_modules/react-test-renderer/lib/ReactMultiChild.js:299:12)
          at ReactTestComponent.receiveComponent (/media/dj/dev/scan-app/node_modules/react-test-renderer/lib/ReactTestRenderer.js:68:10)
          at Object.receiveComponent (/media/dj/dev/scan-app/node_modules/react-test-renderer/lib/ReactReconciler.js:125:22)
    console.log core/tests/components/settings.js:97
      [ 1, 3, 2 ]

  ● settings functions work as expected › Settings.getAllSelected(product) works as expected

    expect(received).toEqual(expected)

    Expected value to equal:
      false
    Received:
      true

      at Object.<anonymous> (core/tests/components/settings.js:99:35)

 PASS  src/tests/view-test.js
 PASS  core/tests/components/details.js
 PASS  core/tests/components/scan.js
 PASS  core/tests/components/search.js
 PASS  core/tests/components/login.js
 PASS  core/tests/helpers/collections.js
 PASS  core/tests/helpers/search-guests.js
 PASS  core/tests/helpers/urls-dev.js
 PASS  core/tests/components/init.js
 PASS  core/tests/helpers/urls-prod.js

=============================== Coverage summary ===============================
Statements   : 43.59% ( 558/1280 )
Branches     : 33.72% ( 175/519 )
Functions    : 50% ( 227/454 )
Lines        : 49.26% ( 467/948 )
================================================================================

Test Suites: 1 failed, 10 passed, 11 total
Tests:       1 failed, 1 skipped, 34 passed, 36 total
Snapshots:   13 passed, 13 total
Time:        5.437s
Ran all test suites.
npm ERR! Test failed.  See above for more details.

only stdout:

$ npm test -- --coverage 2>/dev/null

> App@ test /media/dev/app
> jest "--coverage"

Determining test suites to run...
=============================== Coverage summary ===============================
Statements   : 43.59% ( 558/1280 )
Branches     : 33.72% ( 175/519 )
Functions    : 50% ( 227/454 )
Lines        : 49.26% ( 467/948 )
================================================================================

only stderr:

$ npm test -- --coverage 1>/dev/null
 FAIL  core/tests/components/settings.js
  ● Console

    console.error node_modules/react-native/Libraries/Core/ExceptionsManager.js:71
      Warning: ReactTestUtils has been moved to react-dom/test-utils. Update references to remove this warning.
    console.error node_modules/react-native/Libraries/Core/ExceptionsManager.js:71
      Warning: Exception thrown by hook while handling onSetChildren: Invariant Violation: Expected onBeforeMountComponent() parent and onSetChildren() to be consistent (7 has parents 0 and 5).
      Invariant Violation: Expected onBeforeMountComponent() parent and onSetChildren() to be consistent (7 has parents 0 and 5).
          at invariant (/media/dj/dev/scan-app/node_modules/fbjs/lib/invariant.js:44:15)
          at Object.onSetChildren (/media/dj/dev/scan-app/node_modules/react/lib/ReactComponentTreeHook.js:186:78)
          at callHook (/media/dj/dev/scan-app/node_modules/react-test-renderer/lib/ReactDebugTool.js:27:8)
          at emitEvent (/media/dj/dev/scan-app/node_modules/react-test-renderer/lib/ReactDebugTool.js:39:7)
          at Object.onSetChildren (/media/dj/dev/scan-app/node_modules/react-test-renderer/lib/ReactDebugTool.js:311:5)
          at ReactTestComponent.setChildrenForInstrumentation (/media/dj/dev/scan-app/node_modules/react-test-renderer/lib/ReactMultiChild.js:157:38)
          at ReactTestComponent._updateChildren (/media/dj/dev/scan-app/node_modules/react-test-renderer/lib/ReactMultiChild.js:360:39)
          at ReactTestComponent.updateChildren (/media/dj/dev/scan-app/node_modules/react-test-renderer/lib/ReactMultiChild.js:299:12)
          at ReactTestComponent.receiveComponent (/media/dj/dev/scan-app/node_modules/react-test-renderer/lib/ReactTestRenderer.js:68:10)
          at Object.receiveComponent (/media/dj/dev/scan-app/node_modules/react-test-renderer/lib/ReactReconciler.js:125:22)
    console.log core/tests/components/settings.js:97
      [ 1, 3, 2 ]

  ● settings functions work as expected › Settings.getAllSelected(product) works as expected

    expect(received).toEqual(expected)

    Expected value to equal:
      false
    Received:
      true

      at Object.<anonymous> (core/tests/components/settings.js:99:35)

 PASS  src/tests/view-test.js
 PASS  core/tests/components/search.js
 PASS  core/tests/components/details.js
 PASS  core/tests/components/scan.js
 PASS  core/tests/components/login.js
 PASS  core/tests/helpers/collections.js
 PASS  core/tests/helpers/search-guests.js
 PASS  core/tests/helpers/urls-dev.js
 PASS  core/tests/components/init.js
 PASS  core/tests/helpers/urls-prod.js

Test Suites: 1 failed, 10 passed, 11 total
Tests:       1 failed, 1 skipped, 34 passed, 36 total
Snapshots:   13 passed, 13 total
Time:        5.985s
Ran all test suites.
npm ERR! Test failed.  See above for more details.

even more strange, when I turn on verbose the error gets logged to stdout..

 $ npm test -- --coverage --verbose 2>/dev/null

> Chipta@ test /media/dj/dev/scan-app
> jest "--coverage" "--verbose"

  console.error node_modules/react-native/Libraries/Core/ExceptionsManager.js:71
    Warning: ReactTestUtils has been moved to react-dom/test-utils. Update references to remove this warning.

  console.error node_modules/react-native/Libraries/Core/ExceptionsManager.js:71
    Warning: Exception thrown by hook while handling onSetChildren: Invariant Violation: Expected onBeforeMountComponent() parent and onSetChildren() to be consistent (7 has parents 0 and 5).
    Invariant Violation: Expected onBeforeMountComponent() parent and onSetChildren() to be consistent (7 has parents 0 and 5).
        at invariant (/media/dj/dev/scan-app/node_modules/fbjs/lib/invariant.js:44:15)
        at Object.onSetChildren (/media/dj/dev/scan-app/node_modules/react/lib/ReactComponentTreeHook.js:186:78)
        at callHook (/media/dj/dev/scan-app/node_modules/react-test-renderer/lib/ReactDebugTool.js:27:8)
        at emitEvent (/media/dj/dev/scan-app/node_modules/react-test-renderer/lib/ReactDebugTool.js:39:7)
        at Object.onSetChildren (/media/dj/dev/scan-app/node_modules/react-test-renderer/lib/ReactDebugTool.js:311:5)
        at ReactTestComponent.setChildrenForInstrumentation (/media/dj/dev/scan-app/node_modules/react-test-renderer/lib/ReactMultiChild.js:157:38)
        at ReactTestComponent._updateChildren (/media/dj/dev/scan-app/node_modules/react-test-renderer/lib/ReactMultiChild.js:360:39)
        at ReactTestComponent.updateChildren (/media/dj/dev/scan-app/node_modules/react-test-renderer/lib/ReactMultiChild.js:299:12)
        at ReactTestComponent.receiveComponent (/media/dj/dev/scan-app/node_modules/react-test-renderer/lib/ReactTestRenderer.js:68:10)
        at Object.receiveComponent (/media/dj/dev/scan-app/node_modules/react-test-renderer/lib/ReactReconciler.js:125:22)

  console.log core/tests/components/settings.js:97
    [ 1, 3, 2 ]


=============================== Coverage summary ===============================
Statements   : 43.59% ( 558/1280 )
Branches     : 33.72% ( 175/519 )
Functions    : 50% ( 227/454 )
Lines        : 49.26% ( 467/948 )
================================================================================

$ npm test -- --coverage --verbose 1>/dev/null
 FAIL  core/tests/components/settings.js
  ● settings functions work as expected › Settings.getAllSelected(product) works as expected

    expect(received).toEqual(expected)

    Expected value to equal:
      false
    Received:
      true

      at Object.<anonymous> (core/tests/components/settings.js:99:35)

  Settings renders correctly
    ✓ without event (32ms)
    ✓ with event (30ms)
  settings functions work as expected
    ✓ Settings.selectEventProduct(product) adds product as expected (22ms)
    ✓ Settings.selectEventProduct(product) removes product as expected (18ms)
    ✓ Settings.getSelected(product) works as expected (9ms)
    ✕ Settings.getAllSelected(product) works as expected (28ms)
    ✓ Settings.selectAll() works as expected (20ms)
    ○ skipped 1 test

 PASS  src/tests/view-test.js
  ✓ LoginView renders correctly (39ms)
  ✓ SettingsView renders correctly (10ms)
  ✓ DrawerView renders correctly (65ms)
  ✓ ScanView renders correctly (4ms)
  ✓ ResultView renders correctly (17ms)
  ✓ DetailsView renders correctly (19ms)

 PASS  core/tests/components/details.js
  ✓ Details renderers correctly (47ms)

 PASS  core/tests/components/scan.js
  ✓ Scan renderers correctly (22ms)

 PASS  core/tests/components/login.js
  ✓ Login renders correctly (19ms)

 PASS  core/tests/components/search.js
  ✓ Search renderers correctly (14ms)

 PASS  core/tests/helpers/collections.js
  models are created as expected
    ✓ event is created properly (2ms)
    ✓ guest is created properly (1ms)
    ✓ event product is created properly
    ✓ guest product is created properly (1ms)
  guest functions work as expected
    ✓ guest.getFullName() returns full name of user (1ms)
    ✓ guest.getFullName() returns email of guest if no name is found (1ms)
    ✓ guest.getTotals() returns [used products, total products] (2ms)
  guest product functions work as expected
    ✓ guestProduct.isValid() checks if the product is valid (1ms)
    ✓ guestProduct.confirm(eventId, status) sets last use time on product (3ms)

 PASS  core/tests/helpers/search-guests.js
  searchGuests works as expected
    ✓ searchGuests works as expected without searchTerm (1ms)
    ✓ searchGuests works as expected with name (3ms)
    ✓ searchGuests works as expected with barcode (2ms)

 PASS  core/tests/helpers/urls-dev.js
  ✓ makeGuestProductUrl works as expected
  URLconfig is correct for dev
    ✓ ajaxURL
    ✓ socketURL (1ms)

 PASS  core/tests/components/init.js
  ✓ Init renders correctly (5ms)

 PASS  core/tests/helpers/urls-prod.js
  URLconfig is correct for prod
    ✓ ajaxURL
    ✓ socketURL

Test Suites: 1 failed, 10 passed, 11 total
Tests:       1 failed, 1 skipped, 34 passed, 36 total
Snapshots:   13 passed, 13 total
Time:        5.485s
Ran all test suites.
npm ERR! Test failed.  See above for more details.
picture ThaJay
👍1

Most helpful comment

pass or fail is not error, it is expected output.
error is error, not expected output.

I can only find it strange.
Is there documentation on this I could read?

picture ThaJay on 20 Jun 2017
👍10

All 2 comments

This is how it works in Jest and it's not a bug.

thymikee picture thymikee on 19 Jun 2017
😕8 👎2

pass or fail is not error, it is expected output.
error is error, not expected output.

I can only find it strange.
Is there documentation on this I could read?

ThaJay picture ThaJay on 20 Jun 2017
👍10
Was this page helpful?
0 / 5 - 0 ratings

Related issues

udbhav picture udbhav  ·  236Comments
SimenB picture SimenB  ·  131Comments
sterpe picture sterpe  ·  70Comments
SimenB picture SimenB  ·  69Comments
calebmer picture calebmer  ·  72Comments