addons/register-signal-handler/test and abort/test-addon-register-signal-handler failed

[richardlau]

• Test: addons/register-signal-handler/test and abort/test-addon-register-signal-handler
• Platform: IBM i
• Console Output:

09:41:12 not ok 2801 addons/register-signal-handler/test
09:41:12   ---
09:41:12   duration_ms: 8.21
09:41:12   severity: fail
09:41:12   exitcode: 1
09:41:12   stack: |-
09:41:12     Running: node /home/iojs/build/workspace/node-test-commit-ibmi/nodes/ibmi72-ppc64/test/addons/register-signal-handler/test.js child 6 reset 1
09:41:12     Running: node /home/iojs/build/workspace/node-test-commit-ibmi/nodes/ibmi72-ppc64/test/addons/register-signal-handler/test.js child 6  1
09:41:12     Running: node /home/iojs/build/workspace/node-test-commit-ibmi/nodes/ibmi72-ppc64/test/addons/register-signal-handler/test.js child 6  2
09:41:12     Running: node /home/iojs/build/workspace/node-test-commit-ibmi/nodes/ibmi72-ppc64/test/addons/register-signal-handler/test.js child 11  1
09:41:12     Running: node /home/iojs/build/workspace/node-test-commit-ibmi/nodes/ibmi72-ppc64/test/addons/register-signal-handler/test.js child 11  2
09:41:12     assert.js:103
09:41:12       throw new AssertionError(obj);
09:41:12       ^
09:41:12     
09:41:12     AssertionError [ERR_ASSERTION]: Expected values to be strictly deep-equal:
09:41:12     + actual - expected
09:41:12     
09:41:12       [
09:41:12     -   11,
09:41:12         11
09:41:12       ]
09:41:12         at Object.<anonymous> (/home/iojs/build/workspace/node-test-commit-ibmi/nodes/ibmi72-ppc64/test/addons/register-signal-handler/test.js:54:12)
09:41:12         at Module._compile (internal/modules/cjs/loader.js:1252:30)
09:41:12         at Object.Module._extensions..js (internal/modules/cjs/loader.js:1273:10)
09:41:12         at Module.load (internal/modules/cjs/loader.js:1101:32)
09:41:12         at Function.Module._load (internal/modules/cjs/loader.js:966:14)
09:41:12         at Function.executeUserEntryPoint [as runMain] (internal/modules/run_main.js:60:12)
09:41:12         at internal/main/run_main_module.js:17:47 {
09:41:12       generatedMessage: true,
09:41:12       code: 'ERR_ASSERTION',
09:41:12       actual: [ 11 ],
09:41:12       expected: [ 11, 11 ],
09:41:12       operator: 'deepStrictEqual'
09:41:12     }
09:41:12   ...
...
09:44:59 not ok 2901 abort/test-addon-register-signal-handler
09:44:59   ---
09:44:59   duration_ms: 9.300
09:44:59   severity: fail
09:44:59   exitcode: 1
09:44:59   stack: |-
09:44:59     Running: node /home/iojs/build/workspace/node-test-commit-ibmi/nodes/ibmi72-ppc64/test/addons/register-signal-handler/test.js child 6 reset 1
09:44:59     Running: node /home/iojs/build/workspace/node-test-commit-ibmi/nodes/ibmi72-ppc64/test/addons/register-signal-handler/test.js child 6 reset 2
09:44:59     Running: node /home/iojs/build/workspace/node-test-commit-ibmi/nodes/ibmi72-ppc64/test/addons/register-signal-handler/test.js child 6  1
09:44:59     Running: node /home/iojs/build/workspace/node-test-commit-ibmi/nodes/ibmi72-ppc64/test/addons/register-signal-handler/test.js child 6  2
09:44:59     Running: node /home/iojs/build/workspace/node-test-commit-ibmi/nodes/ibmi72-ppc64/test/addons/register-signal-handler/test.js child 11  1
09:44:59     Running: node /home/iojs/build/workspace/node-test-commit-ibmi/nodes/ibmi72-ppc64/test/addons/register-signal-handler/test.js child 11  2
09:44:59     assert.js:103
09:44:59       throw new AssertionError(obj);
09:44:59       ^
09:44:59     
09:44:59     AssertionError [ERR_ASSERTION]: Expected values to be strictly deep-equal:
09:44:59     + actual - expected
09:44:59     
09:44:59       [
09:44:59     -   11,
09:44:59         11
09:44:59       ]
09:44:59         at Object.<anonymous> (/home/iojs/build/workspace/node-test-commit-ibmi/nodes/ibmi72-ppc64/test/addons/register-signal-handler/test.js:54:12)
09:44:59         at Module._compile (internal/modules/cjs/loader.js:1252:30)
09:44:59         at Object.Module._extensions..js (internal/modules/cjs/loader.js:1273:10)
09:44:59         at Module.load (internal/modules/cjs/loader.js:1101:32)
09:44:59         at Function.Module._load (internal/modules/cjs/loader.js:966:14)
09:44:59         at Module.require (internal/modules/cjs/loader.js:1141:19)
09:44:59         at require (internal/modules/cjs/helpers.js:75:18)
09:44:59         at Object.<anonymous> (/home/iojs/build/workspace/node-test-commit-ibmi/nodes/ibmi72-ppc64/test/abort/test-addon-register-signal-handler.js:7:1)
09:44:59         at Module._compile (internal/modules/cjs/loader.js:1252:30)
09:44:59         at Object.Module._extensions..js (internal/modules/cjs/loader.js:1273:10) {
09:44:59       generatedMessage: true,
09:44:59       code: 'ERR_ASSERTION',
09:44:59       actual: [ 11 ],
09:44:59       expected: [ 11, 11 ],
09:44:59       operator: 'deepStrictEqual'
09:44:59     }
09:44:59   ...

abort/test-addon-register-signal-handler runs the test from addons/register-signal-handler/test with environment variable ALLOW_CRASHES = true.

node/test/abort/test-addon-register-signal-handler.js

Lines 4 to 7 in 08e8997

	// This is a sibling test to test/addons/register-signal-handler/
	process.env.ALLOW_CRASHES = true;
	require('../addons/register-signal-handler/test');

cc @nodejs/platform-ibmi

• Build Links:

• https://ci.nodejs.org/job/node-daily-master/2004/
  • https://ci.nodejs.org/job/node-test-commit-ibmi/34/nodes=ibmi72-ppc64/

[richardlau]

Didn't fail in the subsequent manually requested run of the master branch: https://ci.nodejs.org/job/node-test-commit-ibmi/35/

[richardlau]

Has not reoccurred on any of the subsequent daily builds.

[richardlau]

I've still not seen this fail again. Stress run (100x): https://ci.nodejs.org/job/node-stress-single-test/141/nodes=ibmi72-ppc64/

[richardlau]

The 100x stress run all passed.
Here's another with 1000x: https://ci.nodejs.org/job/node-stress-single-test/143/nodes=ibmi72-ppc64/

If that passes then I'm leaning towards closing this as unrecreatable.

[richardlau]

Those all passed too. At this point I'm going to close as unrecreatable. We can always reopen if it does reoccur.

[richardlau]

ugh, it's back: https://ci.nodejs.org/job/node-test-commit-ibmi/45/nodes=ibmi72-ppc64/

[ThePrez]

@dmabupt, any ideas?

[richardlau]

Another 1000 runs, no failures: https://ci.nodejs.org/job/node-test-commit-custom-suites-freestyle/15579/

[Trott]

Here it is failing around 9 hours ago. https://ci.nodejs.org/job/node-test-commit-ibmi/45/

Host: test-iinthecloud-ibmi72-ppc64_be-1

01:35:42 not ok 2906 abort/test-addon-register-signal-handler
01:35:42   ---
01:35:42   duration_ms: 6.608
01:35:42   severity: fail
01:35:42   exitcode: 1
01:35:42   stack: |-
01:35:42     Running: node /home/iojs/build/workspace/node-test-commit-ibmi/nodes/ibmi72-ppc64/test/addons/register-signal-handler/test.js child 6 reset 1
01:35:42     Running: node /home/iojs/build/workspace/node-test-commit-ibmi/nodes/ibmi72-ppc64/test/addons/register-signal-handler/test.js child 6 reset 2
01:35:42     Running: node /home/iojs/build/workspace/node-test-commit-ibmi/nodes/ibmi72-ppc64/test/addons/register-signal-handler/test.js child 6  1
01:35:42     Running: node /home/iojs/build/workspace/node-test-commit-ibmi/nodes/ibmi72-ppc64/test/addons/register-signal-handler/test.js child 6  2
01:35:42     assert.js:103
01:35:42       throw new AssertionError(obj);
01:35:42       ^
01:35:42     
01:35:42     AssertionError [ERR_ASSERTION]: Expected values to be strictly deep-equal:
01:35:42     + actual - expected
01:35:42     
01:35:42       [
01:35:42     -   6,
01:35:42         6
01:35:42       ]
01:35:42         at Object.<anonymous> (/home/iojs/build/workspace/node-test-commit-ibmi/nodes/ibmi72-ppc64/test/addons/register-signal-handler/test.js:54:12)
01:35:42         at Module._compile (internal/modules/cjs/loader.js:1270:30)
01:35:42         at Object.Module._extensions..js (internal/modules/cjs/loader.js:1291:10)
01:35:42         at Module.load (internal/modules/cjs/loader.js:1119:32)
01:35:42         at Function.Module._load (internal/modules/cjs/loader.js:981:14)
01:35:42         at Module.require (internal/modules/cjs/loader.js:1159:19)
01:35:42         at require (internal/modules/cjs/helpers.js:75:18)
01:35:42         at Object.<anonymous> (/home/iojs/build/workspace/node-test-commit-ibmi/nodes/ibmi72-ppc64/test/abort/test-addon-register-signal-handler.js:7:1)
01:35:42         at Module._compile (internal/modules/cjs/loader.js:1270:30)
01:35:42         at Object.Module._extensions..js (internal/modules/cjs/loader.js:1291:10) {
01:35:42       generatedMessage: true,
01:35:42       code: 'ERR_ASSERTION',
01:35:42       actual: [ 6 ],
01:35:42       expected: [ 6, 6 ],
01:35:42       operator: 'deepStrictEqual'
01:35:42     }
01:35:42   ...

[Trott]

@richardlau Every failure documented here has been on test-iinthecloud-ibmi72-ppc64_be-1 and every successful stress test has been on test-iinthecloud-ibmi72-ppc64_be-2 so maybe there's a difference between the two machines?

[richardlau]

Well since the machines aren't doing anything outside of the daily builds...

• test-iinthecloud-ibmi72-ppc64_be-2
  https://ci.nodejs.org/job/node-test-commit-custom-suites-freestyle/15584/
  bindings weren't built so the test was skipped
• test-iinthecloud-ibmi72-ppc64_be-1
  https://ci.nodejs.org/job/node-test-commit-custom-suites-freestyle/15585/
  bindings weren't built so the test was skipped