Lucee failed install extensions

Spark · May 30, 2023, 10:51am

I am running wildlfy with Lucee-5.3.4.80 WAR
Windows Server 2016 Datacenter
JDK1.8

Lucee start with Errors, and multiple extensions are not installed. And unable to install extensions from Administrator → Application, same errors.

java.io.IOException: The system cannot find the path specified
	at java.io.WinNTFileSystem.createFileExclusively(Native Method)
	at java.io.File.createTempFile(File.java:2024)
	at java.io.File.createTempFile(File.java:2070)
	at lucee.loader.engine.CFMLEngineFactory.deployBundledBundle(CFMLEngineFactory.java:786)
	at lucee.loader.engine.CFMLEngineFactory.downloadBundle(CFMLEngineFactory.java:685)
	at lucee.loader.osgi.BundleLoader.loadBundles(BundleLoader.java:141)
	at lucee.loader.engine.CFMLEngineFactory._getCore(CFMLEngineFactory.java:545)
	at lucee.loader.engine.CFMLEngineFactory.initEngine(CFMLEngineFactory.java:358)
	at lucee.loader.engine.CFMLEngineFactory.initEngineIfNecessary(CFMLEngineFactory.java:261)
	at lucee.loader.engine.CFMLEngineFactory.getInstance(CFMLEngineFactory.java:167)

This works fine with Local machine or other VM(Windows Server 2012 R2 Datacenter).

Any suggestions?

Spark · May 30, 2023, 11:42am

More logs from deploy -

"INFO","ServerService Thread Pool -- 96","05/30/2023","04:45:42","controller","copy bundled extension to local extension directory (if never done before)"
"INFO","ServerService Thread Pool -- 96","05/30/2023","04:45:42","controller","detected Extensions to install (new;new-fresh):7E673D15-D87C-41A6-8B5F1956528C605F;name=MySQL;label=MySQL;version=8.0.15, 99A4EF8D-F2FD-40C8-8FB8C2E67A4EEEB6;name=MSSQL;label=MS SQL Server;version=4.0.2206.100, 671B01B8-B3B3-42B9-AC055A356BED5281;name=PostgreSQL;label=PostgreSQL;version=9.4.1212, 2BCD080F-4E1E-48F5-BEFE794232A21AF6;name=JDTsSQL;label=jTDS (MSSQL);version=1.3.1, CED6227E-0F49-6367-A68D21AACA6B07E8;name=Admin;label=Lucee Administrator;version=1.0.0.3, D46D49C3-EB85-8D97-30BEC2F38561E985;name=Doc;label=Lucee Documentation;version=1.0.0.2, 17AB52DE-B300-A94B-E058BD978511E39E;name=S3;label=S3;version=0.9.4.119, 87FE44E5-179C-43A3-A87B3D38BEF4652E;name=EHCache;label=EHCache;version=2.10.0.31, D46B46A9-A0E3-44E1-D972A04AC3A8DC10;name=Chart;label=CFChart;version=1.0.19.24, FAD1E8CB-4F45-4184-86359145767C29DE;name=Hibernate;label=Hibernate;version=3.5.5.75-SNAPSHOT, EFDEB172-F52E-4D84-9CD1A1F561B3DFC8;name=Lucene;label=Lucene;version=2.4.1.32, 66E312DD-D083-27C0-64189D16753FD6F0;name=PDF;label=PDF;version=1.0.0.75-SNAPSHOT, FAD67145-E3AE-30F8-1C11A6CCF544F0B7;name=Form;label=Form tags;version=1.0.0.9;since=5.1.0.21, DF28D0A4-6748-44B9-A2FDC12E4E2E4D38;name=Axis;label=Axis 1;version=1.4.0.25-SNAPSHOT;since=5.3.0.20-ALPHA, B737ABC4-D43F-4D91-8E8E973E37C40D1B;name=Image;label=Image;version=1.0.0.27-SNAPSHOT;since=5.3.0.35-ALPHA, 37C61C0A-5D7E-4256-8572639BE0CF5838;name=Esapi;label=ESAPI;version=2.1.0.17-SNAPSHOT;since=5.3.0.37-ALPHA, 8D7FB0DF-08BB-1589-FE3975678F07DB17;name=Compress;label=Compress;version=1.0.0.2-SNAPSHOT;since=5.3.2.31-SNAPSHOT, 6E2CB28F-98FB-4B51-B6BE6C64ADF35473;name=Ajax;label=Ajax;version=1.0.0.3-SNAPSHOT;since=5.3.2.40-SNAPSHOT"
"INFO","ServerService Thread Pool -- 96","05/30/2023","04:45:42","extension","installing the extension 7E673D15-D87C-41A6-8B5F1956528C605F;name=MySQL;label=MySQL;version=8.0.15"
"INFO","ServerService Thread Pool -- 96","05/30/2023","04:45:42","extension","installing the extension 7E673D15-D87C-41A6-8B5F1956528C605F;name=MySQL;label=MySQL;version=8.0.15 from remote extension provider"
"INFO","ServerService Thread Pool -- 96","05/30/2023","04:45:42","main","check for extension at : http://extension.lucee.org/rest/extension/provider/full/7E673D15-D87C-41A6-8B5F1956528C605F?version=8.0.15"
"INFO","ServerService Thread Pool -- 96","05/30/2023","04:45:44","main","downloaded extension [7E673D15-D87C-41A6-8B5F1956528C605F;name=MySQL;label=MySQL;version=8.0.15] to [G:\App\av.lucee\log\7E673D15-D87C-41A6-8B5F1956528C605F-8.0.15.lex]"
"ERROR","ServerService Thread Pool -- 96","05/30/2023","04:45:44","extension","The system cannot find the path specified;The system cannot find the path specified;lucee.runtime.exp.NativeException: The system cannot find the path specified

Question -

is it normal to download extension to a log folder?
is it required to download extension? I see they are bundled extensions, why not just install them?

Zackster · May 30, 2023, 11:44am

hmmm, that’s a pretty old release, over three years old - Feb 24, 2020.

can you try out newer stable releases until you find one which works and backtrack from there looking at the changelogs

Spark · May 30, 2023, 11:46am

Hey Zack,

Thanks for your reply. It works on some of our VMs and local machine, just not working in another VM.
Can you review above deploy logs and see if anything abnormal?

Thanks.

Zackster · May 30, 2023, 11:50am

could be due to http being blocked at the server level? later versions use https

try doing a cfhttp for http://extension.lucee.org/rest/extension/provider/full/7E673D15-D87C-41A6-8B5F1956528C605F?version=8.0.15 and dump out the result?

Spark · May 30, 2023, 11:53am

Is there a way disable extension update/download? The bundled extensions should already do the work.

Spark · May 30, 2023, 12:03pm

Just run -

<cfhttp url="http://extension.lucee.org/rest/extension/provider/full/7E673D15-D87C-41A6-8B5F1956528C605F?version=8.0.15" method="get" result="result" charset="utf-8"/>
<cfdump var="#result#"/>

And the result seems fine -

Spark · May 30, 2023, 1:23pm

Screenshot from Lucee Administrator → Extension → Application

It was working partially (able to load installed extensions, but not able to upload new extensions), but today now it totally breaks.

Roberto_Marzialetti · May 30, 2023, 2:11pm

Sounds like an operating system permissions issue. Have you tried checking the “Security” tab for the Lucee installation folder?

Spark · May 30, 2023, 2:23pm

Yes, we tried that - we were suspecting permission issue as well.
It is running with SYSTEM or Administrator will all access to those directories, but it is still failing.

Spark · May 30, 2023, 2:33pm

And I am facing a very strange issue - Lucee-5.3.4.80
I am using lucee-light.jar(without extensions), but when I start my service, it is still trying to download and install all those extensions.
I checked lucee.jar that has an extensions folder with only one empty .index file. But still I am seeing logs that getting extensions -

"INFO","ServerService Thread Pool -- 57","05/30/2023","09:26:52","extract-extension","the following extensions are bundled with the lucee.jar []"
"INFO","ServerService Thread Pool -- 57","05/30/2023","09:26:52","controller","copy bundled extension to local extension directory (if never done before)"
"INFO","ServerService Thread Pool -- 57","05/30/2023","09:26:52","controller","detected Extensions to install (new;new-fresh):7E673D15-D87C-41A6-8B5F1956528C605F;name=MySQL;label=MySQL;version=8.0.15, 99A4EF8D-F2FD-40C8-8FB8C2E67A4EEEB6;name=MSSQL;label=MS SQL Server;version=4.0.2206.100, 671B01B8-B3B3-42B9-AC055A356BED5281;name=PostgreSQL;label=PostgreSQL;version=9.4.1212, 2BCD080F-4E1E-48F5-BEFE794232A21AF6;name=JDTsSQL;label=jTDS (MSSQL);version=1.3.1, CED6227E-0F49-6367-A68D21AACA6B07E8;name=Admin;label=Lucee Administrator;version=1.0.0.3, D46D49C3-EB85-8D97-30BEC2F38561E985;name=Doc;label=Lucee Documentation;version=1.0.0.2, 17AB52DE-B300-A94B-E058BD978511E39E;name=S3;label=S3;version=0.9.4.119, 87FE44E5-179C-43A3-A87B3D38BEF4652E;name=EHCache;label=EHCache;version=2.10.0.31, D46B46A9-A0E3-44E1-D972A04AC3A8DC10;name=Chart;label=CFChart;version=1.0.19.24, FAD1E8CB-4F45-4184-86359145767C29DE;name=Hibernate;label=Hibernate;version=3.5.5.75-SNAPSHOT, EFDEB172-F52E-4D84-9CD1A1F561B3DFC8;name=Lucene;label=Lucene;version=2.4.1.32, 66E312DD-D083-27C0-64189D16753FD6F0;name=PDF;label=PDF;version=1.0.0.75-SNAPSHOT, FAD67145-E3AE-30F8-1C11A6CCF544F0B7;name=Form;label=Form tags;version=1.0.0.9;since=5.1.0.21, DF28D0A4-6748-44B9-A2FDC12E4E2E4D38;name=Axis;label=Axis 1;version=1.4.0.25-SNAPSHOT;since=5.3.0.20-ALPHA, B737ABC4-D43F-4D91-8E8E973E37C40D1B;name=Image;label=Image;version=1.0.0.27-SNAPSHOT;since=5.3.0.35-ALPHA, 37C61C0A-5D7E-4256-8572639BE0CF5838;name=Esapi;label=ESAPI;version=2.1.0.17-SNAPSHOT;since=5.3.0.37-ALPHA, 8D7FB0DF-08BB-1589-FE3975678F07DB17;name=Compress;label=Compress;version=1.0.0.2-SNAPSHOT;since=5.3.2.31-SNAPSHOT, 6E2CB28F-98FB-4B51-B6BE6C64ADF35473;name=Ajax;label=Ajax;version=1.0.0.3-SNAPSHOT;since=5.3.2.40-SNAPSHOT"
"INFO","ServerService Thread Pool -- 57","05/30/2023","09:26:52","extension","installing the extension 7E673D15-D87C-41A6-8B5F1956528C605F;name=MySQL;label=MySQL;version=8.0.15"
"INFO","ServerService Thread Pool -- 57","05/30/2023","09:26:52","extension","installing the extension 7E673D15-D87C-41A6-8B5F1956528C605F;name=MySQL;label=MySQL;version=8.0.15 from remote extension provider"
"INFO","ServerService Thread Pool -- 57","05/30/2023","09:26:52","main","check for extension at : http://extension.lucee.org/rest/extension/provider/full/7E673D15-D87C-41A6-8B5F1956528C605F?version=8.0.15"
"INFO","ServerService Thread Pool -- 57","05/30/2023","09:26:54","main","downloaded extension [7E673D15-D87C-41A6-8B5F1956528C605F;name=MySQL;label=MySQL;version=8.0.15] to [G:\Accela\av.lucee\log\7E673D15-D87C-41A6-8B5F1956528C605F-8.0.15.lex]"
"ERROR","ServerService Thread Pool -- 57","05/30/2023","09:26:54","extension","The system cannot find the path specified;The system cannot find the path specified;lucee.runtime.exp.NativeException: The system cannot find the path specified
	at java.io.WinNTFileSystem.createFileExclusively(Native Method)
	at java.io.File.createTempFile(File.java:2024)
	at java.io.File.createTempFile(File.java:2070)
	at lucee.runtime.osgi.BundleInfo.getInstance(BundleInfo.java:73)
	at lucee.runtime.extension.RHExtension.load(RHExtension.java:321)
	at lucee.runtime.extension.RHExtension.init(RHExtension.java:227)
	at lucee.runtime.extension.RHExtension.<init>(RHExtension.java:216)
	at lucee.runtime.config.XMLConfigAdmin.updateRHExtension(XMLConfigAdmin.java:4584)
	at lucee.runtime.config.XMLConfigAdmin._updateRHExtension(XMLConfigAdmin.java:4574)
	at lucee.runtime.config.DeployHandler.deployExtension(DeployHandler.java:272)
	at lucee.runtime.config.DeployHandler.deployExtensions(DeployHandler.java:140)
	at lucee.runtime.engine.CFMLEngineImpl.<init>(CFMLEngineImpl.java:356)

Anything I am missing here?

Spark · June 1, 2023, 11:48am

Figured it requires this server properties -Djava.io.tmpdir=..\tmp to specify the tmp dir(don’t understand why some VM requires it, some VMs not). And now everything works fine.

Zackster · June 1, 2023, 1:21pm

great that you figured it out, thanks for letting us know!

@Brad_Wood did raise this related ticket a while back [LDEV-4450] - Lucee

bdw429s · June 1, 2023, 4:24pm

The last time I ran into this issue, the user’s sysadmin had set an environment variable called TMP on their machines that pointed to a non-existent directory that Java was picking up by convention and trying to use as the temp dir. You may want to check for that.

echo %TMP%