2017-10-06 16:16:27,424 tools/run_tests/helper_scripts/run_node.sh: line 17: /Users/kbuilder/.nvm/nvm.sh: No such file or directory
tools/run_tests/helper_scripts/run_node.sh: line 19: nvm: command not found
+ CONFIG=opt
++ dirname tools/run_tests/helper_scripts/run_node.sh
+ cd tools/run_tests/helper_scripts/../../..
++ pwd
+ root=/Volumes/BuildData/tmpfs/src/github/grpc/workspace_node_macos_opt_native
+ test_directory=src/node/test
+ timeout=8000
+ '[' opt = gcov ']'
+ JUNIT_REPORT_PATH=src/node/report.xml
+ JUNIT_REPORT_STACK=1
+ ./node_modules/.bin/mocha --timeout 8000 --reporter mocha-jenkins-reporter src/node/test
dyld: lazy symbol binding failed: Symbol not found: _grpc_timer_list_init
  Referenced from: /Volumes/BuildData/tmpfs/src/github/grpc/workspace_node_macos_opt_native/src/node/extension_binary/node-v57-darwin-x64/grpc_node.node
  Expected in: flat namespace

dyld: Symbol not found: _grpc_timer_list_init
  Referenced from: /Volumes/BuildData/tmpfs/src/github/grpc/workspace_node_macos_opt_native/src/node/extension_binary/node-v57-darwin-x64/grpc_node.node
  Expected in: flat namespace

tools/run_tests/helper_scripts/run_node.sh: line 48: 49624 Trace/BPT trap: 5       JUNIT_REPORT_PATH=src/node/report.xml JUNIT_REPORT_STACK=1 ./node_modules/.bin/mocha --timeout $timeout --reporter mocha-jenkins-reporter $test_directory

2017-10-06 16:16:27,425 FAILED: tools/run_tests/helper_scripts/run_node.sh [ret=133, pid=49620, time=0.9sec]
2017-10-06 16:16:27,429 FAILED: tools/run_tests/helper_scripts/run_node.sh
2017-10-06 16:16:27,437 FAILED: Some tests failed

Failure
4084000 123145303912448 timer_generic.cc:463]           .. shard[20]->queue_deadline_cap --> 6756
D1006 16:07:38.054112000 123145303375872 chttp2_transport.cc:1220]     complete_closure_step: t=0x7fe3ec805600 0x7fe3eb900000 refs=0 flags=0x0000 desc=op->on_complete err="No Error" write_state=WRITING+MORE
D1006 16:07:38.054145000 123145303375872 call_combiner.cc:64]          ==> grpc_call_combiner_start() [0x7fe3ec81be30] closure=0x7fe3eb900090 [on_complete (cancel_stream)] error="No Error"
D1006 16:07:38.054125000 123145303912448 timer_generic.cc:529]           .. shard[20] popped 0
D1006 16:07:38.054155000 123145303375872 call_combiner.cc:73]            size: 2 -> 3
D1006 16:07:38.054171000 123145303912448 timer_generic.cc:571]           .. result --> 1, shard[20]->min_deadline 4032 --> 6757, now=5756
I1006 16:07:38.054188000 123145303375872 call_combiner.cc:84]            QUEUING
D1006 16:07:38.054207000 123145303912448 timer_generic.cc:488]           .. shard[21]: heap_empty=true
D1006 16:07:38.054233000 123145303375872 combiner.cc:290]              C:0x7fe3eb8007c0 finish old_state=7
D1006 16:07:38.054251000 123145303912448 timer_generic.cc:463]           .. shard[21]->queue_deadline_cap --> 6756
D1006 16:07:38.054278000 123145303375872 call_combiner.cc:96]          ==> grpc_call_combiner_stop() [0x7fe3ec81be30] [on_complete]
D1006 16:07:38.054298000 123145303912448 timer_generic.cc:529]           .. shard[21] popped 0
D1006 16:07:38.054311000 123145303375872 call_combiner.cc:103]           size: 3 -> 2
D1006 16:07:38.054336000 123145303912448 timer_generic.cc:571]           .. result --> 1, shard[21]->min_deadline 4032 --> 6757, now=5756
D1006 16:07:38.054356000 123145303375872 call_combiner.cc:110]           checking queue
D1006 16:07:38.054377000 123145303912448 timer_generic.cc:488]           .. shard[22]: heap_empty=true
D1006 16:07:38.054399000 123145303375872 call_combiner.cc:124]           EXECUTING FROM QUEUE: closure=0x7fe3ec81cc88 error="No Error"
D1006 16:07:38.054413000 123145303912448 timer_generic.cc:463]           .. shard[22]->queue_deadline_cap --> 6756
D1006 16:07:38.054437000 123145303375872 call_combiner.cc:96]          ==> grpc_call_combiner_stop() [0x7fe3ec81be30] [recv_initial_metadata_ready]
D1006 16:07:38.054461000 123145303912448 timer_generic.cc:529]           .. shard[22] popped 0
D1006 16:07:38.054488000 123145303375872 call_combiner.cc:103]           size: 2 -> 1
D1006 16:07:38.054510000 123145303912448 timer_generic.cc:571]           .. result --> 1, shard[22]->min_deadline 4032 --> 6757, now=5756
D1006 16:07:38.797168000 123145303375872 call_combiner.cc:110]           checking queue
D1006 16:07:38.797199000 123145303912448 timer_generic.cc:488]           .. shard[23]: heap_empty=true
D1006 16:07:38.797469000 123145303375872 call_combiner.cc:124]           EXECUTING FROM QUEUE: closure=0x7fe3eb900090 error="No Error"
D1006 16:07:38.797503000 123145303912448 timer_generic.cc:463]           .. shard[23]->queue_deadline_cap --> 6756
D1006 16:07:38.797536000 123145303375872 call.cc:768]                  get_final_status CLI
D1006 16:07:38.797560000 123145303912448 timer_generic.cc:529]           .. shard[23] popped 0
D1006 16:07:38.797596000 123145303375872 call.cc:771]                    1: {"created":"@1507331258.797531000","description":"Error received from peer","file":"src/core/lib/surface/call.cc","file_line":1168,"grpc_message":"Deadline Exceeded","grpc_status":4}
D1006 16:07:38.797619000 123145303912448 timer_generic.cc:571]           .. result --> 1, shard[23]->min_deadline 4032 --> 6757, now=5756
I1006 16:07:38.797644000 123145303375872 completion_queue.cc:601]      cq_end_op_for_next(exec_ctx=0x700000103e50, cq=0x7fe3e9f00470, tag=0x12e, error="No Error", done=0x1071b7ab0, done_arg=0x7fe3ec81dda0, storage=0x7fe3ec81dda8)
D1006 16:07:38.797665000 123145303912448 timer_generic.cc:488]           .. shard[24]: heap_empty=true
D1006 16:07:38.797696000 123145303375872 call_combiner.cc:96]          ==> grpc_call_combiner_stop() [0x7fe3ec81be30] [got on_complete from cancel_stream batch]
D1006 16:07:38.797718000 123145303912448 timer_generic.cc:463]           .. shard[24]->queue_deadline_cap --> 6756
D1006 16:07:38.797739000 140735312461824 ev_poll_posix.cc:996]         0x7fe3e9f00540 poll=1
D1006 16:07:38.797761000 123145303375872 call_combiner.cc:103]           size: 1 -> 0
D1006 16:07:38.797781000 123145303912448 timer_generic.cc:529]           .. shard[24] popped 0
D1006 16:07:38.797808000 140735312461824 ev_poll_posix.cc:1020]        0x7fe3e9f00540: got_wakeup
D1006 16:07:38.797829000 123145303375872 call_combiner.cc:131]           queue empty
D1006 16:07:38.797851000 123145303912448 timer_generic.cc:571]           .. result --> 1, shard[24]->min_deadline 4032 --> 6757, now=5756
D1006 16:07:38.797875000 140735312461824 ev_poll_posix.cc:1030]        0x7fe3e9f00540 got_event: 5 r:0 w:0 [0]
D1006 16:07:38.797898000 123145303375872 combiner.cc:220]              C:0x7fe3eb8007c0 grpc_combiner_continue_exec_ctx contended=1 exec_ctx_ready_to_finish=0 time_to_execute_final_list=0
D1006 16:07:38.797924000 123145303912448 timer_generic.cc:488]           .. shard[26]: heap_empty=true
D1006 16:07:38.797948000 140735312461824 ev_poll_posix.cc:1030]        0x7fe3e9f00540 got_event: 6 r:0 w:0 [0]
D1006 16:07:38.797972000 123145303375872 combiner.cc:245]              C:0x7fe3eb8007c0 maybe_finish_one n=0x7fe3ec805728
D1006 16:07:38.797994000 123145303912448 timer_generic.cc:463]           .. shard[26]->queue_deadline_cap --> 6756
D1006 16:07:38.798017000 140735312461824 ev_poll_posix.cc:1030]        0x7fe3e9f00540 got_event: 9 r:0 w:0 [0]
D1006 16:07:38.798067000 123145303375872 hpack_parser.cc:657]          Decode: ':status: 200', elem_interned=0 [2], k_interned=1, v_interned=0
D1006 16:07:38.798067000 123145303912448 timer_generic.cc:529]           .. shard[26] popped 0
D1006 16:07:38.798087000 140735312461824 ev_poll_posix.cc:1030]        0x7fe3e9f00540 got_event: 10 r:0 w:0 [0]
D1006 16:07:38.798112000 123145303375872 hpack_parser.cc:657]          Decode: 'content-type: application/grpc', elem_interned=0 [2], k_interned=1, v_interned=0
D1006 16:07:38.798127000 123145303912448 timer_generic.cc:571]           .. result --> 1, shard[26]->min_deadline 4032 --> 6757, now=5756
I1006 16:07:38.798148000 140735312461824 completion_queue.cc:917]      RETURN_EVENT[0x7fe3e9f00470]: OP_COMPLETE: tag:0x12e OK
D1006 16:07:38.798165000 123145303375872 hpack_parser.cc:657]          Decode: 'grpc-status: 4', elem_interned=0 [2], k_interned=1, v_interned=0
D1006 16:07:38.798173000 123145303912448 timer_generic.cc:488]           .. shard[27]: heap_empty=true
I1006 16:07:38.798191000 140735312461824 completion_queue.cc:816]      grpc_completion_queue_next(cq=0x7fe3e9f00470, deadline=gpr_timespec { tv_sec: 10, tv_nsec: 27992531, clock_type: 0 }, reserved=0x0)
D1006 16:07:39.525412000 123145303375872 hpack_parser.cc:657]          Decode: 'grpc-message: Deadline Exceeded', elem_interned=0 [2], k_interned=1, v_interned=0
D1006 16:07:39.525696000 123145303912448 timer_generic.cc:463]           .. shard[27]->queue_deadline_cap --> 6756
D1006 16:07:39.525748000 140735312461824 ev_poll_posix.cc:996]         0x7fe3e9f00540 poll=0
D1006 16:07:39.525832000 123145303375872 bdp_estimator.cc:73]          bdp[ipv6:[::1]:13448]:sched acc=245 est=65536
D1006 16:07:39.525856000 123145303912448 timer_generic.cc:529]           .. shard[27] popped 0
I1006 16:07:39.525881000 140735312461824 completion_queue.cc:917]      RETURN_EVENT[0x7fe3e9f00470]: QUEUE_TIMEOUT
D1006 16:07:39.526074000 123145303375872 combiner.cc:290]              C:0x7fe3eb8007c0 finish old_state=5
D1006 16:07:39.526141000 123145303912448 timer_generic.cc:571]           .. result --> 1, shard[27]->min_deadline 4032 --> 6757, now=5756
E1006 16:07:39.526174000 140735312461824 cq_verifier.c:219]            no event received, but expected:
tag(201) GRPC_OP_COMPLETE success=1 test/core/end2end/tests/max_concurrent_streams.c:584
tag(401) GRPC_OP_COMPLETE success=1 test/core/end2end/tests/max_concurrent_streams.c:583

*******************************
Caught signal SIGABRT
  no backtrace