UnhandledPromiseRejectionWarning: Unhandled promise rejection

[Arcanemagus]

Description

watcher-stress locked up while running an --exercise serial test with an Unable to open directory handle directory.

Steps to Reproduce

1. Run watcher-stress --exercise serial --watcher-count 1000 --logging-dir C:\Users\abneyl\watcher-logs

Expected behavior:

Stress test to complete.

Actual behavior:

This was printed to the console:

starting watcher #262 on directory-19850
(node:14508) UnhandledPromiseRejectionWarning: Unhandled promise rejection (rejection id: 1): Error: Unable to open directory handle (3) The system cannot find the path specified
(node:14508) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code.

main.log

[ ..\src\hub.cpp:114] Sending command [Message [CommandPayload id 786 add C:\Users\abneyl\AppData\Local\Temp\serial-117923-14508-1b2648s.pjmbg\directory-28\directory-47\directory-1222\directory-4197\directory-5020 at channel 262]] to Thread[worker thread].
[ ..\src\hub.cpp:143] Received ack message [Message [AckPayload ack 786]].
[ ..\src\hub.cpp:173] Received filesystem event message [Message [FileSystemPayload channel 262 directory modified C:\Users\abneyl\AppData\Local\Temp\serial-117923-14508-1b2648s.pjmbg\directory-28\directory-47\directory-1222\directory-4197\directory-5020\directory-19628]].
[ ..\src\hub.cpp:224] Dispatching 1 event(s) on channel 262 to node callbacks.
[ ..\src\hub.cpp:173] Received filesystem event message [Message [FileSystemPayload channel 262 directory modified C:\Users\abneyl\AppData\Local\Temp\serial-117923-14508-1b2648s.pjmbg\directory-28\directory-47\directory-1222\directory-4197\directory-5020\directory-7753]].
[ ..\src\hub.cpp:224] Dispatching 1 event(s) on channel 262 to node callbacks.
[ ..\src\hub.cpp:173] Received filesystem event message [Message [FileSystemPayload channel 262 file modified C:\Users\abneyl\AppData\Local\Temp\serial-117923-14508-1b2648s.pjmbg\directory-28\directory-47\directory-1222\directory-4197\directory-5020\file-6977.txt]].
[ ..\src\hub.cpp:224] Dispatching 1 event(s) on channel 262 to node callbacks.
[ ..\src\hub.cpp:173] Received filesystem event message [Message [FileSystemPayload channel 262 file modified C:\Users\abneyl\AppData\Local\Temp\serial-117923-14508-1b2648s.pjmbg\directory-28\directory-47\directory-1222\directory-4197\directory-5020\file-6051.txt]].
[ ..\src\hub.cpp:224] Dispatching 1 event(s) on channel 262 to node callbacks.
[ ..\src\hub.cpp:173] Received filesystem event message [Message [FileSystemPayload channel 262 file modified C:\Users\abneyl\AppData\Local\Temp\serial-117923-14508-1b2648s.pjmbg\directory-28\directory-47\directory-1222\directory-4197\directory-5020\file-6515.txt]].
[ ..\src\hub.cpp:224] Dispatching 1 event(s) on channel 262 to node callbacks.
[ ..\src\hub.cpp:173] Received filesystem event message [Message [FileSystemPayload channel 262 file modified C:\Users\abneyl\AppData\Local\Temp\serial-117923-14508-1b2648s.pjmbg\directory-28\directory-47\directory-1222\directory-4197\directory-5020\file-5210.txt]].
[ ..\src\hub.cpp:224] Dispatching 1 event(s) on channel 262 to node callbacks.
[ ..\src\hub.cpp:173] Received filesystem event message [Message [FileSystemPayload channel 262 file modified C:\Users\abneyl\AppData\Local\Temp\serial-117923-14508-1b2648s.pjmbg\directory-28\directory-47\directory-1222\directory-4197\directory-5020\file-10903.txt]].
[ ..\src\hub.cpp:224] Dispatching 1 event(s) on channel 262 to node callbacks.
[ ..\src\hub.cpp:173] Received filesystem event message [Message [FileSystemPayload channel 262 file modified C:\Users\abneyl\AppData\Local\Temp\serial-117923-14508-1b2648s.pjmbg\directory-28\directory-47\directory-1222\directory-4197\directory-5020\file-6515.txt]].
[ ..\src\hub.cpp:224] Dispatching 1 event(s) on channel 262 to node callbacks.
[ ..\src\hub.cpp:173] Received filesystem event message [Message [FileSystemPayload channel 262 file modified C:\Users\abneyl\AppData\Local\Temp\serial-117923-14508-1b2648s.pjmbg\directory-28\directory-47\directory-1222\directory-4197\directory-5020\file-9058.txt]].
[ ..\src\hub.cpp:224] Dispatching 1 event(s) on channel 262 to node callbacks.
[ ..\src\hub.cpp:173] Received filesystem event message [Message [FileSystemPayload channel 262 file modified C:\Users\abneyl\AppData\Local\Temp\serial-117923-14508-1b2648s.pjmbg\directory-28\directory-47\directory-1222\directory-4197\directory-5020\file-16355.txt]].
[ ..\src\hub.cpp:224] Dispatching 1 event(s) on channel 262 to node callbacks.
[ ..\src\hub.cpp:173] Received filesystem event message [Message [FileSystemPayload channel 262 file modified C:\Users\abneyl\AppData\Local\Temp\serial-117923-14508-1b2648s.pjmbg\directory-28\directory-47\directory-1222\directory-4197\directory-5020\file-19394.txt]].
[ ..\src\hub.cpp:224] Dispatching 1 event(s) on channel 262 to node callbacks.
[ ..\src\hub.cpp:173] Received filesystem event message [Message [FileSystemPayload channel 262 file modified C:\Users\abneyl\AppData\Local\Temp\serial-117923-14508-1b2648s.pjmbg\directory-28\directory-47\directory-1222\directory-4197\directory-5020\file-17391.txt]].
[ ..\src\hub.cpp:224] Dispatching 1 event(s) on channel 262 to node callbacks.
[ ..\src\hub.cpp:114] Sending command [Message [CommandPayload id 787 remove channel 262]] to Thread[worker thread].
[ ..\src\hub.cpp:114] Sending command [Message [CommandPayload id 788 remove channel 262]] to Thread[polling thread].
[..\src\thread.cpp: 95] Processing offline command: [CommandPayload id 788 remove channel 262].
[..\src\thread.cpp: 97] Result: OK.
[ ..\src\hub.cpp:143] Received ack message [Message [AckPayload ack 788]].
[ ..\src\hub.cpp:143] Received ack message [Message [AckPayload ack 787]].
[ ..\src\hub.cpp:114] Sending command [Message [CommandPayload id 789 add C:\Users\abneyl\AppData\Local\Temp\serial-117923-14508-1b2648s.pjmbg\directory-28\directory-47\directory-862\directory-1507\directory-1798\directory-3204\directory-3660\directory-8607\directory-8811\directory-16417\directory-17797\directory-18457\directory-19850 at channel 263]] to Thread[worker thread].
[ ..\src\hub.cpp:143] Received ack message [Message [AckPayload ack 789]].

worker.log

[..\src\worker\windows\windows_worker_platform.cpp:141] Added directory root C:\Users\abneyl\AppData\Local\Temp\serial-117923-14508-1b2648s.pjmbg\directory-28\directory-47\directory-1222\directory-4197\directory-5020 at channel 262.
[..\src\worker\windows\subscription.cpp: 54] Scheduling the next change callback for channel 262.
[..\src\worker\windows\subscription.cpp: 54] Scheduling the next change callback for channel 262.
[..\src\message_buffer.cpp: 24] Emitting filesystem message [Message [FileSystemPayload channel 262 directory modified C:\Users\abneyl\AppData\Local\Temp\serial-117923-14508-1b2648s.pjmbg\directory-28\directory-47\directory-1222\directory-4197\directory-5020\directory-19628]]
[..\src\worker\windows\subscription.cpp: 54] Scheduling the next change callback for channel 262.
[..\src\message_buffer.cpp: 24] Emitting filesystem message [Message [FileSystemPayload channel 262 directory modified C:\Users\abneyl\AppData\Local\Temp\serial-117923-14508-1b2648s.pjmbg\directory-28\directory-47\directory-1222\directory-4197\directory-5020\directory-7753]]
[..\src\worker\windows\subscription.cpp: 54] Scheduling the next change callback for channel 262.
[..\src\message_buffer.cpp: 24] Emitting filesystem message [Message [FileSystemPayload channel 262 file modified C:\Users\abneyl\AppData\Local\Temp\serial-117923-14508-1b2648s.pjmbg\directory-28\directory-47\directory-1222\directory-4197\directory-5020\file-6977.txt]]
[..\src\worker\windows\subscription.cpp: 54] Scheduling the next change callback for channel 262.
[..\src\message_buffer.cpp: 24] Emitting filesystem message [Message [FileSystemPayload channel 262 file modified C:\Users\abneyl\AppData\Local\Temp\serial-117923-14508-1b2648s.pjmbg\directory-28\directory-47\directory-1222\directory-4197\directory-5020\file-6051.txt]]
[..\src\worker\windows\subscription.cpp: 54] Scheduling the next change callback for channel 262.
[..\src\message_buffer.cpp: 24] Emitting filesystem message [Message [FileSystemPayload channel 262 file modified C:\Users\abneyl\AppData\Local\Temp\serial-117923-14508-1b2648s.pjmbg\directory-28\directory-47\directory-1222\directory-4197\directory-5020\file-6515.txt]]
[..\src\worker\windows\subscription.cpp: 54] Scheduling the next change callback for channel 262.
[..\src\message_buffer.cpp: 24] Emitting filesystem message [Message [FileSystemPayload channel 262 file modified C:\Users\abneyl\AppData\Local\Temp\serial-117923-14508-1b2648s.pjmbg\directory-28\directory-47\directory-1222\directory-4197\directory-5020\file-5210.txt]]
[..\src\worker\windows\subscription.cpp: 54] Scheduling the next change callback for channel 262.
[..\src\message_buffer.cpp: 24] Emitting filesystem message [Message [FileSystemPayload channel 262 file modified C:\Users\abneyl\AppData\Local\Temp\serial-117923-14508-1b2648s.pjmbg\directory-28\directory-47\directory-1222\directory-4197\directory-5020\file-10903.txt]]
[..\src\worker\windows\subscription.cpp: 54] Scheduling the next change callback for channel 262.
[..\src\message_buffer.cpp: 24] Emitting filesystem message [Message [FileSystemPayload channel 262 file modified C:\Users\abneyl\AppData\Local\Temp\serial-117923-14508-1b2648s.pjmbg\directory-28\directory-47\directory-1222\directory-4197\directory-5020\file-6515.txt]]
[..\src\worker\windows\subscription.cpp: 54] Scheduling the next change callback for channel 262.
[..\src\message_buffer.cpp: 24] Emitting filesystem message [Message [FileSystemPayload channel 262 file modified C:\Users\abneyl\AppData\Local\Temp\serial-117923-14508-1b2648s.pjmbg\directory-28\directory-47\directory-1222\directory-4197\directory-5020\file-9058.txt]]
[..\src\worker\windows\subscription.cpp: 54] Scheduling the next change callback for channel 262.
[..\src\message_buffer.cpp: 24] Emitting filesystem message [Message [FileSystemPayload channel 262 file modified C:\Users\abneyl\AppData\Local\Temp\serial-117923-14508-1b2648s.pjmbg\directory-28\directory-47\directory-1222\directory-4197\directory-5020\file-16355.txt]]
[..\src\worker\windows\subscription.cpp: 54] Scheduling the next change callback for channel 262.
[..\src\message_buffer.cpp: 24] Emitting filesystem message [Message [FileSystemPayload channel 262 file modified C:\Users\abneyl\AppData\Local\Temp\serial-117923-14508-1b2648s.pjmbg\directory-28\directory-47\directory-1222\directory-4197\directory-5020\file-19394.txt]]
[..\src\worker\windows\subscription.cpp: 54] Scheduling the next change callback for channel 262.
[..\src\message_buffer.cpp: 24] Emitting filesystem message [Message [FileSystemPayload channel 262 file modified C:\Users\abneyl\AppData\Local\Temp\serial-117923-14508-1b2648s.pjmbg\directory-28\directory-47\directory-1222\directory-4197\directory-5020\file-17391.txt]]
[..\src\worker\windows\windows_worker_platform.cpp:170] Subscription for channel 262 stopped.
[..\src\worker\windows\windows_worker_platform.cpp:188] ERROR_OPERATION_ABORTED encountered on channel 262.

Reproduces how often:

Rarely?

Versions

smashwilson/watcher-stress@5b969c2
@atom/[email protected]

Additional Information

Likely fixed/handled by #67.

[smashwilson]

To clarify a bit: #67 will fix the dangling Promise rejection, but not the reason the Promise is rejecting 😄 I'll have to dig into the serial test a bit to see how this could be triggered there.