[BUG] svelte-prepocess install fails : npm ERR! Exit handler never called!

[sandeep-gh]

Is there an existing issue for this?

• I have searched the existing issues

This issue exists in the latest npm version

• I am using the latest npm

Current Behavior

Produces error with following message:

npm ERR! Exit handler never called!

npm ERR! This is an error with npm itself. Please report this error at:
npm ERR!     <https://github.com/npm/cli/issues>

npm ERR! A complete log of this run can be found in:
npm ERR!     /home/kabira/.npm/_logs/2022-05-03T04_20_54_232Z-debug-0.log

Expected Behavior

Should have installed svelte-preprocess

Steps To Reproduce

mkdir reproduce_bug
cd reproduce_bug
npm init -y
npm install -D svelte-preprocess

Environment

• npm: 8.5.3
• Node.js: v12.22.6
• OS Name: OpenBSD
• System Model Name: Rpi4
• npm config:

; node bin location = /usr/local/bin/node
; cwd = /tmp/npm_debug
; HOME = /home/kabira
; Run `npm config ls -l` to show all defaults.

[ljharb]

Can you try with npm 8 latest?

[sandeep-gh]

I tried with npm 8.8.0, but the problem is still there.
Tailend of the log output

484 verbose unfinished npm timer command:install 1651565746256
485 verbose unfinished npm timer reify 1651565746268
486 verbose unfinished npm timer reify:build 1651565761835
487 verbose unfinished npm timer build 1651565761839
488 verbose unfinished npm timer build:deps 1651565761839
489 verbose unfinished npm timer build:run:postinstall 1651565761924
490 info ok
491 error Exit handler never called!
492 error This is an error with npm itself. Please report this error at:
493 error     <https://github.com/npm/cli/issues>
494 error A complete log of this run can be found in:
494 error     /home/kabira/.npm/_logs/2022-05-03T08_15_45_875Z-debug-0.log

[nlf]

can you include the full debug log?

[sandeep-gh]

Full debug log:

0 verbose cli node /usr/local/bin/npm
1 info using npm@8.8.0
2 info using node@v12.22.6
3 timing npm:load:whichnode Completed in 6ms
4 verbose node symlink /usr/local/bin/node
5 timing config:load:defaults Completed in 6ms
6 timing config:load:file:/usr/local/lib/node_modules/npm/npmrc Completed in 16ms
7 timing config:load:builtin Completed in 16ms
8 timing config:load:cli Completed in 7ms
9 timing config:load:env Completed in 2ms
10 timing config:load:file:/home/kabira/DrivingRange/my-svelte-project/.npmrc Completed in 1ms
11 timing config:load:project Completed in 14ms
12 timing config:load:file:/home/kabira/.npmrc Completed in 1ms
13 timing config:load:user Completed in 1ms
14 timing config:load:file:/usr/local/etc/npmrc Completed in 1ms
15 timing config:load:global Completed in 1ms
16 timing config:load:validate Completed in 1ms
17 timing config:load:credentials Completed in 3ms
18 timing config:load:setEnvs Completed in 3ms
19 timing config:load Completed in 55ms
20 timing npm:load:configload Completed in 55ms
21 timing npm:load:mkdirpcache Completed in 3ms
22 timing npm:load:mkdirplogs Completed in 2ms
23 verbose title npm install svelte-preprocess
24 verbose argv "install" "svelte-preprocess"
25 timing npm:load:setTitle Completed in 5ms
26 timing config:load:flatten Completed in 11ms
27 timing npm:load:display Completed in 19ms
28 verbose logfile logs-max:10 dir:/home/kabira/.npm/_logs
29 verbose logfile /home/kabira/.npm/_logs/2022-05-04T02_56_47_393Z-debug-0.log
30 timing npm:load:logFile Completed in 24ms
31 timing npm:load:timers Completed in 0ms
32 timing npm:load:configScope Completed in 1ms
33 timing npm:load Completed in 117ms
34 timing arborist:ctor Completed in 3ms
35 silly logfile start cleaning logs, removing 1 files
36 silly logfile done cleaning log files
37 timing idealTree:init Completed in 398ms
38 timing idealTree:userRequests Completed in 11ms
39 silly idealTree buildDeps
40 silly fetch manifest svelte-preprocess@*
41 timing arborist:ctor Completed in 0ms
42 http fetch GET 200 https://registry.npmjs.org/svelte-preprocess 1428ms (cache revalidated)
43 silly fetch manifest @babel/core@^7.10.2
44 http fetch GET 200 https://registry.npmjs.org/@babel%2fcore 52ms (cache revalidated)
45 silly fetch manifest coffeescript@^2.5.1
46 http fetch GET 200 https://registry.npmjs.org/coffeescript 1095ms (cache revalidated)
47 silly fetch manifest less@^3.11.3 || ^4.0.0
48 http fetch GET 200 https://registry.npmjs.org/less 104ms (cache revalidated)
49 silly fetch manifest postcss@^7 || ^8
50 http fetch GET 200 https://registry.npmjs.org/postcss 42ms (cache revalidated)
51 silly fetch manifest postcss-load-config@^2.1.0 || ^3.0.0
52 http fetch GET 200 https://registry.npmjs.org/postcss-load-config 59ms (cache revalidated)
53 silly fetch manifest ts-node@>=9.0.0
54 http fetch GET 200 https://registry.npmjs.org/ts-node 48ms (cache revalidated)
55 silly fetch manifest @swc/core@>=1.2.50
56 http fetch GET 200 https://registry.npmjs.org/@swc%2fcore 161ms (cache updated)
57 silly fetch manifest @swc/wasm@>=1.2.50
58 http fetch GET 200 https://registry.npmjs.org/@swc%2fwasm 60ms (cache revalidated)
59 silly fetch manifest @types/node@*
60 http fetch GET 200 https://registry.npmjs.org/@types%2fnode 51ms (cache revalidated)
61 silly fetch manifest typescript@>=2.7
62 http fetch GET 200 https://registry.npmjs.org/typescript 77ms (cache revalidated)
63 silly fetch manifest pug@^3.0.0
64 http fetch GET 200 https://registry.npmjs.org/pug 1102ms (cache revalidated)
65 silly fetch manifest sass@^1.26.8
66 http fetch GET 200 https://registry.npmjs.org/sass 69ms (cache revalidated)
67 silly fetch manifest stylus@^0.55.0
68 http fetch GET 200 https://registry.npmjs.org/stylus 59ms (cache revalidated)
69 silly fetch manifest sugarss@^2.0.0
70 http fetch GET 200 https://registry.npmjs.org/sugarss 57ms (cache revalidated)
71 silly fetch manifest svelte@^3.0.0
72 http fetch GET 200 https://registry.npmjs.org/svelte 1078ms (cache revalidated)
73 silly placeDep ROOT svelte-preprocess@4.10.6 OK for: svelte-app@1.0.0 want: *
74 silly fetch manifest @types/pug@^2.0.4
75 silly fetch manifest @types/sass@^1.16.0
76 silly fetch manifest detect-indent@^6.0.0
77 silly fetch manifest sorcery@^0.10.0
78 silly fetch manifest strip-indent@^3.0.0
79 http fetch GET 200 https://registry.npmjs.org/detect-indent 154ms (cache revalidated)
80 http fetch GET 200 https://registry.npmjs.org/strip-indent 159ms (cache revalidated)
81 http fetch GET 200 https://registry.npmjs.org/@types%2fsass 181ms (cache revalidated)
82 http fetch GET 200 https://registry.npmjs.org/sorcery 1185ms (cache revalidated)
83 http fetch GET 200 https://registry.npmjs.org/@types%2fpug 2113ms (cache revalidated)
84 timing idealTree:#root Completed in 8093ms
85 silly placeDep ROOT @types/pug@2.0.6 OK for: svelte-preprocess@4.10.6 want: ^2.0.4
86 silly placeDep ROOT @types/sass@1.43.1 OK for: svelte-preprocess@4.10.6 want: ^1.16.0
87 silly placeDep ROOT detect-indent@6.1.0 OK for: svelte-preprocess@4.10.6 want: ^6.0.0
88 silly placeDep ROOT sorcery@0.10.0 OK for: svelte-preprocess@4.10.6 want: ^0.10.0
89 silly placeDep ROOT strip-indent@3.0.0 OK for: svelte-preprocess@4.10.6 want: ^3.0.0
90 silly fetch manifest buffer-crc32@^0.2.5
91 silly fetch manifest minimist@^1.2.0
92 silly fetch manifest sander@^0.5.0
93 silly fetch manifest min-indent@^1.0.0
94 http fetch GET 200 https://registry.npmjs.org/minimist 63ms (cache revalidated)
95 http fetch GET 200 https://registry.npmjs.org/min-indent 88ms (cache revalidated)
96 http fetch GET 200 https://registry.npmjs.org/buffer-crc32 105ms (cache revalidated)
97 http fetch GET 200 https://registry.npmjs.org/sander 1082ms (cache revalidated)
98 timing idealTree:node_modules/svelte-preprocess Completed in 1143ms
99 timing idealTree:node_modules/@types/pug Completed in 0ms
100 timing idealTree:node_modules/@types/sass Completed in 0ms
101 timing idealTree:node_modules/detect-indent Completed in 0ms
102 silly placeDep ROOT buffer-crc32@0.2.13 OK for: sorcery@0.10.0 want: ^0.2.5
103 silly placeDep ROOT minimist@1.2.6 OK for: sorcery@0.10.0 want: ^1.2.0
104 silly placeDep ROOT sander@0.5.1 OK for: sorcery@0.10.0 want: ^0.5.0
105 silly fetch manifest es6-promise@^3.1.2
106 silly fetch manifest mkdirp@^0.5.1
107 silly fetch manifest rimraf@^2.5.2
108 silly fetch manifest graceful-fs@^4.1.3
109 http fetch GET 200 https://registry.npmjs.org/mkdirp 86ms (cache revalidated)
110 http fetch GET 200 https://registry.npmjs.org/es6-promise 101ms (cache revalidated)
111 http fetch GET 200 https://registry.npmjs.org/rimraf 101ms (cache revalidated)
112 http fetch GET 200 https://registry.npmjs.org/graceful-fs 108ms (cache updated)
113 timing idealTree:node_modules/sorcery Completed in 148ms
114 timing idealTree:node_modules/buffer-crc32 Completed in 0ms
115 timing idealTree:node_modules/minimist Completed in 0ms
116 silly placeDep ROOT es6-promise@3.3.1 OK for: sander@0.5.1 want: ^3.1.2
117 silly placeDep ROOT graceful-fs@4.2.10 OK for: sander@0.5.1 want: ^4.1.3
118 silly placeDep ROOT mkdirp@0.5.6 OK for: sander@0.5.1 want: ^0.5.1
119 silly placeDep ROOT rimraf@2.7.1 OK for: sander@0.5.1 want: ^2.5.2
120 timing idealTree:node_modules/sander Completed in 23ms
121 timing idealTree:node_modules/es6-promise Completed in 0ms
122 timing idealTree:node_modules/graceful-fs Completed in 0ms
123 timing idealTree:node_modules/mkdirp Completed in 0ms
124 timing idealTree:node_modules/rimraf Completed in 0ms
125 silly placeDep ROOT min-indent@1.0.1 OK for: strip-indent@3.0.0 want: ^1.0.0
126 timing idealTree:node_modules/strip-indent Completed in 5ms
127 timing idealTree:node_modules/min-indent Completed in 0ms
128 timing idealTree:buildDeps Completed in 9422ms
129 timing idealTree:fixDepFlags Completed in 5ms
130 timing idealTree Completed in 9848ms
131 timing reify:loadTrees Completed in 9851ms
132 timing reify:diffTrees Completed in 16ms
133 silly reify moves {}
134 timing reify:retireShallow Completed in 4ms
135 timing reify:createSparse Completed in 39ms
136 timing reify:loadBundles Completed in 0ms
137 silly audit bulk request {
137 silly audit   '@babel/code-frame': [ '7.16.7' ],
137 silly audit   '@babel/helper-validator-identifier': [ '7.16.7' ],
137 silly audit   '@babel/highlight': [ '7.17.9' ],
137 silly audit   '@polka/url': [ '1.0.0-next.21' ],
137 silly audit   '@rollup/plugin-commonjs': [ '17.1.0' ],
137 silly audit   '@rollup/plugin-node-resolve': [ '11.2.1' ],
137 silly audit   '@rollup/pluginutils': [ '3.1.0', '4.2.0' ],
137 silly audit   'estree-walker': [ '1.0.1', '2.0.2', '0.6.1' ],
137 silly audit   '@types/estree': [ '0.0.39' ],
137 silly audit   '@types/node': [ '17.0.23' ],
137 silly audit   '@types/resolve': [ '1.17.1' ],
137 silly audit   acorn: [ '8.7.0' ],
137 silly audit   'ansi-styles': [ '3.2.1' ],
137 silly audit   anymatch: [ '3.1.2' ],
137 silly audit   'balanced-match': [ '1.0.2' ],
137 silly audit   'binary-extensions': [ '2.2.0' ],
137 silly audit   'brace-expansion': [ '1.1.11' ],
137 silly audit   braces: [ '3.0.2' ],
137 silly audit   'buffer-from': [ '1.1.2' ],
137 silly audit   'builtin-modules': [ '3.2.0' ],
137 silly audit   chalk: [ '2.4.2' ],
137 silly audit   chokidar: [ '3.5.3' ],
137 silly audit   'color-convert': [ '1.9.3' ],
137 silly audit   'color-name': [ '1.1.3' ],
137 silly audit   commander: [ '2.20.3' ],
137 silly audit   commondir: [ '1.0.1' ],
137 silly audit   'concat-map': [ '0.0.1' ],
137 silly audit   'console-clear': [ '1.1.1' ],
137 silly audit   deepmerge: [ '4.2.2' ],
137 silly audit   'escape-string-regexp': [ '1.0.5' ],
137 silly audit   'fill-range': [ '7.0.1' ],
137 silly audit   'fs.realpath': [ '1.0.0' ],
137 silly audit   fsevents: [ '2.3.2' ],
137 silly audit   'function-bind': [ '1.1.1' ],
137 silly audit   'get-port': [ '3.2.0' ],
137 silly audit   glob: [ '7.2.0' ],
137 silly audit   'glob-parent': [ '5.1.2' ],
137 silly audit   has: [ '1.0.3' ],
137 silly audit   'has-flag': [ '3.0.0', '4.0.0' ],
137 silly audit   inflight: [ '1.0.6' ],
137 silly audit   inherits: [ '2.0.4' ],
137 silly audit   'is-binary-path': [ '2.1.0' ],
137 silly audit   'is-core-module': [ '2.8.1' ],
137 silly audit   'is-extglob': [ '2.1.1' ],
137 silly audit   'is-glob': [ '4.0.3' ],
137 silly audit   'is-module': [ '1.0.0' ],
137 silly audit   'is-number': [ '7.0.0' ],
137 silly audit   'is-reference': [ '1.2.1' ],
137 silly audit   'jest-worker': [ '26.6.2' ],
137 silly audit   'supports-color': [ '7.2.0', '5.5.0' ],
137 silly audit   'js-tokens': [ '4.0.0' ],
137 silly audit   kleur: [ '4.1.4' ],
137 silly audit   livereload: [ '0.9.3' ],
137 silly audit   'livereload-js': [ '3.3.3' ],
137 silly audit   'local-access': [ '1.1.0' ],
137 silly audit   'magic-string': [ '0.25.9' ],
137 silly audit   'merge-stream': [ '2.0.0' ],
137 silly audit   minimatch: [ '3.1.2' ],
137 silly audit   mri: [ '1.2.0' ],
137 silly audit   mrmime: [ '1.0.0' ],
137 silly audit   'normalize-path': [ '3.0.0' ],
137 silly audit   once: [ '1.4.0' ],
137 silly audit   opts: [ '2.0.2' ],
137 silly audit   'path-is-absolute': [ '1.0.1' ],
137 silly audit   'path-parse': [ '1.0.7' ],
137 silly audit   picomatch: [ '2.3.1' ],
137 silly audit   randombytes: [ '2.1.0' ],
137 silly audit   readdirp: [ '3.6.0' ],
137 silly audit   'require-relative': [ '0.8.7' ],
137 silly audit   resolve: [ '1.22.0' ],
137 silly audit   rollup: [ '2.70.1' ],
137 silly audit   'rollup-plugin-css-only': [ '3.1.0' ],
137 silly audit   'rollup-plugin-livereload': [ '2.0.5' ],
137 silly audit   'rollup-plugin-svelte': [ '7.1.0' ],
137 silly audit   'rollup-plugin-terser': [ '7.0.2' ],
137 silly audit   'rollup-pluginutils': [ '2.8.2' ],
137 silly audit   sade: [ '1.8.1' ],
137 silly audit   'safe-buffer': [ '5.2.1' ],
137 silly audit   semiver: [ '1.1.0' ],
137 silly audit   'serialize-javascript': [ '4.0.0' ],
137 silly audit   sirv: [ '2.0.2' ],
137 silly audit   'sirv-cli': [ '2.0.2' ],
137 silly audit   'source-map': [ '0.7.3', '0.6.1' ],
137 silly audit   'source-map-support': [ '0.5.21' ],
137 silly audit   'sourcemap-codec': [ '1.4.8' ],
137 silly audit   'supports-preserve-symlinks-flag': [ '1.0.0' ],
137 silly audit   svelte: [ '3.47.0' ],
137 silly audit   terser: [ '5.12.1' ],
137 silly audit   tinydate: [ '1.3.0' ],
137 silly audit   'to-regex-range': [ '5.0.1' ],
137 silly audit   totalist: [ '3.0.0' ],
137 silly audit   wrappy: [ '1.0.2' ],
137 silly audit   ws: [ '7.5.7' ],
137 silly audit   'svelte-preprocess': [ '4.10.6' ],
137 silly audit   '@types/pug': [ '2.0.6' ],
137 silly audit   '@types/sass': [ '1.43.1' ],
137 silly audit   'detect-indent': [ '6.1.0' ],
137 silly audit   sorcery: [ '0.10.0' ],
137 silly audit   'strip-indent': [ '3.0.0' ],
137 silly audit   'buffer-crc32': [ '0.2.13' ],
137 silly audit   minimist: [ '1.2.6' ],
137 silly audit   sander: [ '0.5.1' ],
137 silly audit   'es6-promise': [ '3.3.1' ],
137 silly audit   'graceful-fs': [ '4.2.10' ],
137 silly audit   mkdirp: [ '0.5.6' ],
137 silly audit   rimraf: [ '2.7.1' ],
137 silly audit   'min-indent': [ '1.0.1' ]
137 silly audit }
138 verbose reify failed optional dependency /home/kabira/DrivingRange/my-svelte-project/node_modules/fsevents
139 silly reify mark deleted [ '/home/kabira/DrivingRange/my-svelte-project/node_modules/fsevents' ]
140 timing reifyNode:node_modules/fsevents Completed in 38ms
141 timing reifyNode:node_modules/min-indent Completed in 321ms
142 timing reifyNode:node_modules/buffer-crc32 Completed in 322ms
143 timing reifyNode:node_modules/@types/pug Completed in 322ms
144 timing reifyNode:node_modules/strip-indent Completed in 334ms
145 timing reifyNode:node_modules/@types/sass Completed in 335ms
146 timing reifyNode:node_modules/detect-indent Completed in 336ms
147 http fetch POST 200 https://registry.npmjs.org/-/npm/v1/security/advisories/bulk 352ms
148 timing auditReport:getReport Completed in 368ms
149 silly audit report {}
150 timing auditReport:init Completed in 0ms
151 timing reify:audit Completed in 371ms
152 timing reifyNode:node_modules/rimraf Completed in 348ms
153 timing reifyNode:node_modules/mkdirp Completed in 348ms
154 timing reifyNode:node_modules/graceful-fs Completed in 351ms
155 timing reifyNode:node_modules/minimist Completed in 409ms
156 timing reifyNode:node_modules/sorcery Completed in 441ms
157 timing reifyNode:node_modules/es6-promise Completed in 448ms
158 timing reifyNode:node_modules/sander Completed in 468ms
159 timing reifyNode:node_modules/svelte-preprocess Completed in 486ms
160 timing reify:unpack Completed in 493ms
161 timing reify:unretire Completed in 1ms
162 timing build:queue Completed in 5ms
163 timing build:link:node_modules/mkdirp Completed in 10ms
164 timing build:link:node_modules/rimraf Completed in 8ms
165 timing build:link:node_modules/sorcery Completed in 8ms
166 timing build:link Completed in 12ms
167 timing npm Completed in 10887ms
168 verbose unfinished npm timer command:install 1651633007777
169 verbose unfinished npm timer reify 1651633007789
170 verbose unfinished npm timer reify:build 1651633018259
171 verbose unfinished npm timer build 1651633018261
172 verbose unfinished npm timer build:deps 1651633018261
173 verbose unfinished npm timer build:run:postinstall 1651633018280
174 info ok
175 error Exit handler never called!
176 error This is an error with npm itself. Please report this error at:
177 error     <https://github.com/npm/cli/issues>
178 error A complete log of this run can be found in:
178 error     /home/kabira/.npm/_logs/2022-05-04T02_56_47_393Z-debug-0.log

[sandeep-gh]

Hello, Do we have any inclination as to why this issue is occuring. Is it an OpenBSD based issue. Would moving to other OS resolve. I would soonish be blocked on this. Any suggestions/workaround/fixes would be helpful.

[tacozMacleo]

I seem to have the same problem.
It is also kind of similar to: #3938
FIle: https://filebin.net/u9geii2wl63ua3v3
Head of file:

0 verbose cli node /usr/bin/npm
1 info using npm@8.8.0
2 info using node@v16.4.2
3 timing npm:load:whichnode Completed in 0ms

Tail of file:

759 timing build:link Completed in 11ms
760 timing npm Completed in 3426ms
761 verbose unfinished npm timer command:install 1653297521418
762 verbose unfinished npm timer reify 1653297521420
763 verbose unfinished npm timer reify:build 1653297524768
764 verbose unfinished npm timer build 1653297524769
765 verbose unfinished npm timer build:deps 1653297524769
766 verbose unfinished npm timer build:run:postinstall 1653297524782
767 info ok
768 error Exit handler never called!
769 error This is an error with npm itself. Please report this error at:
770 error     <https://github.com/npm/cli/issues>
771 error A complete log of this run can be found in:
771 error     /npm_cache/_logs/2022-05-23T09_18_41_356Z-debug-0.log