Yes, ftpdw.exe runs and all the parameters look OK (though this is a very uninformed assessment). In particular, classpath is the same as when run from the command line.
My understanding is that the java bin folder is in the library path by default and I don't see any failed attempt to find the DLL in the logs from process monitor. It is more as is the driver rejects the URI before getting to authentication. The driver jar file is being opened and read successfully when running as a service, so no permissions issues there. I added '-D C:\java\jdk1.7.0\bin' to Java Options using ftpdw.exe, restarted the service and got the same error. UAC is turned off. I have attached error.log and ftpd.log after setting log4j log level to TRACE, but I suspect the error has occurred before anything is written to the log. Do you know a way to get more information from the JDBC driver manager and/or Microsoft SQL Server JDBC driver? On Fri, 2011-08-26 at 18:23 -0500, Sai Pullabhotla wrote: > Can you run the ftpdw.exe from the bin folder of the FTP server > folder? If it does launch, go to the Java tab and make sure all > settings (JVM path, Java Class Path). Make sure the folder in which > you placed the DLL is in java.library.path. I'm not quite sure if jre > \bin is automatically searched for DLLs or not. You can also place the > DLL in any other folder and hopefully be able to add the path for the > DLL in the ftpdw.exe window (under the Java Options using -D option). > > If everything looks good, then I suspect you might be running into > some permissions issue on the jar/dlls. Make sure the service is > running under the correct profile (local system account or another > valid local/domain profile) that has appropriate permissions to the > FTP server folder and sub-folders. Finally, if UAC is ON, I would try > turning that off temporarily and see if you get better luck. > > If none of this works, posting the log file (in debug mode) with > complete stacktrace might help. > > Sai Pullabhotla
log4j:ERROR Failed to excute sql java.sql.SQLException: No suitable driver found for jdbc:sqlserver://aphrodite;databaseName=MobileFrame;integratedSecurity=true; at java.sql.DriverManager.getConnection(DriverManager.java:604) at java.sql.DriverManager.getConnection(DriverManager.java:221) at org.apache.log4j.jdbc.JDBCAppender.getConnection(JDBCAppender.java:212) at org.apache.log4j.jdbc.JDBCAppender.execute(JDBCAppender.java:175) at org.apache.log4j.jdbc.JDBCAppender.flushBuffer(JDBCAppender.java:250) at org.apache.log4j.jdbc.JDBCAppender.append(JDBCAppender.java:146) at org.apache.log4j.AppenderSkeleton.doAppend(AppenderSkeleton.java:230) at org.apache.log4j.helpers.AppenderAttachableImpl.appendLoopOnAppenders(AppenderAttachableImpl.java:65) at org.apache.log4j.Category.callAppenders(Category.java:203) at org.apache.log4j.Category.forcedLog(Category.java:388) at org.apache.log4j.Category.log(Category.java:853) at org.slf4j.impl.Log4jLoggerAdapter.info(Log4jLoggerAdapter.java:306) at org.apache.ftpserver.main.Daemon.getConfiguration(Daemon.java:103) at org.apache.ftpserver.main.Daemon.main(Daemon.java:52)
[ INFO] 2011-08-27 12:24:43,880 [] [] Using xml configuration file C:\apache-ftpserver-1.0.6\res\conf\ftpd-xenacom.xml... [ INFO] 2011-08-27 12:24:44,227 [] [] Refreshing org.springframework.context.support.FileSystemXmlApplicationContext@1372bdb: display name [org.springframework.context.support.FileSystemXmlApplicationContext@1372bdb]; startup date [Sat Aug 27 12:24:44 NZST 2011]; root of context hierarchy [TRACE] 2011-08-27 12:24:44,285 [] [] Creating [java.util.concurrent.ConcurrentHashMap] [TRACE] 2011-08-27 12:24:44,286 [] [] Creating [java.util.concurrent.ConcurrentHashMap] [TRACE] 2011-08-27 12:24:44,287 [] [] Creating [java.util.concurrent.ConcurrentHashMap] [TRACE] 2011-08-27 12:24:44,287 [] [] Creating [java.util.concurrent.ConcurrentHashMap] [TRACE] 2011-08-27 12:24:44,288 [] [] Creating [java.util.concurrent.ConcurrentHashMap] [TRACE] 2011-08-27 12:24:44,289 [] [] Creating [java.util.concurrent.ConcurrentHashMap] [TRACE] 2011-08-27 12:24:44,299 [] [] Creating [java.util.concurrent.ConcurrentHashMap] [TRACE] 2011-08-27 12:24:44,302 [] [] Creating [java.util.concurrent.ConcurrentHashMap] [ INFO] 2011-08-27 12:24:44,375 [] [] Loading XML bean definitions from file [C:\apache-ftpserver-1.0.6\res\conf\ftpd-xenacom.xml] [DEBUG] 2011-08-27 12:24:44,418 [] [] Using JAXP provider [com.sun.org.apache.xerces.internal.jaxp.DocumentBuilderFactoryImpl] [TRACE] 2011-08-27 12:24:44,482 [] [] Trying to resolve XML entity with public id [null] and system id [http://mina.apache.org/ftpserver/ftpserver-1.0.xsd] [DEBUG] 2011-08-27 12:24:44,483 [] [] Loading schema mappings from [META-INF/spring.schemas] [DEBUG] 2011-08-27 12:24:44,504 [] [] Loaded schema mappings: {http://www.springframework.org/schema/lang/spring-lang.xsd=org/springframework/scripting/config/spring-lang-2.5.xsd, http://www.springframework.org/schema/lang/spring-lang-2.5.xsd=org/springframework/scripting/config/spring-lang-2.5.xsd, http://www.springframework.org/schema/context/spring-context-2.5.xsd=org/springframework/context/config/spring-context-2.5.xsd, http://www.springframework.org/schema/context/spring-context.xsd=org/springframework/context/config/spring-context-2.5.xsd, http://www.springframework.org/schema/util/spring-util-2.0.xsd=org/springframework/beans/factory/xml/spring-util-2.0.xsd, http://www.springframework.org/schema/util/spring-util-2.5.xsd=org/springframework/beans/factory/xml/spring-util-2.5.xsd, http://www.springframework.org/schema/tool/spring-tool-2.0.xsd=org/springframework/beans/factory/xml/spring-tool-2.0.xsd, http://www.springframework.org/schema/beans/spring-beans-2.0.xsd=org/springframework/beans/factory/xml/spring-beans-2.0.xsd, http://www.springframework.org/schema/tool/spring-tool-2.5.xsd=org/springframework/beans/factory/xml/spring-tool-2.5.xsd, http://www.springframework.org/schema/beans/spring-beans-2.5.xsd=org/springframework/beans/factory/xml/spring-beans-2.5.xsd, http://www.springframework.org/schema/beans/spring-beans.xsd=org/springframework/beans/factory/xml/spring-beans-2.5.xsd, http://www.springframework.org/schema/jee/spring-jee.xsd=org/springframework/ejb/config/spring-jee-2.5.xsd, http://mina.apache.org/ftpserver/ftpserver-1.0.xsd=org/apache/ftpserver/config/spring/ftpserver-1.0.xsd, http://www.springframework.org/schema/tool/spring-tool.xsd=org/springframework/beans/factory/xml/spring-tool-2.5.xsd, http://www.springframework.org/schema/jee/spring-jee-2.0.xsd=org/springframework/ejb/config/spring-jee-2.0.xsd, http://www.springframework.org/schema/jee/spring-jee-2.5.xsd=org/springframework/ejb/config/spring-jee-2.5.xsd, http://www.springframework.org/schema/lang/spring-lang-2.0.xsd=org/springframework/scripting/config/spring-lang-2.0.xsd, http://www.springframework.org/schema/util/spring-util.xsd=org/springframework/beans/factory/xml/spring-util-2.5.xsd} [DEBUG] 2011-08-27 12:24:44,509 [] [] Found XML schema [http://mina.apache.org/ftpserver/ftpserver-1.0.xsd] in classpath: org/apache/ftpserver/config/spring/ftpserver-1.0.xsd [TRACE] 2011-08-27 12:24:44,578 [] [] Trying to resolve XML entity with public id [null] and system id [http://www.springframework.org/schema/beans/spring-beans-2.5.xsd] [DEBUG] 2011-08-27 12:24:44,583 [] [] Found XML schema [http://www.springframework.org/schema/beans/spring-beans-2.5.xsd] in classpath: org/springframework/beans/factory/xml/spring-beans-2.5.xsd [DEBUG] 2011-08-27 12:24:44,749 [] [] Loading bean definitions [DEBUG] 2011-08-27 12:24:44,770 [] [] Loaded mappings [{http://www.springframework.org/schema/p=org.springframework.beans.factory.xml.SimplePropertyNamespaceHandler, http://www.springframework.org/schema/lang=org.springframework.scripting.config.LangNamespaceHandler, http://www.springframework.org/schema/jee=org.springframework.ejb.config.JeeNamespaceHandler, http://mina.apache.org/ftpserver/spring/v1=org.apache.ftpserver.config.spring.FtpServerNamespaceHandler, http://www.springframework.org/schema/util=org.springframework.beans.factory.xml.UtilNamespaceHandler, http://www.springframework.org/schema/context=org.springframework.context.config.ContextNamespaceHandler}] [DEBUG] 2011-08-27 12:24:44,814 [] [] Loading key store from "C:\apache-ftpserver-1.0.6\.\res\ftpserver.jks", using the key store type "jks" [DEBUG] 2011-08-27 12:24:44,816 [] [] Trying to load store from file [DEBUG] 2011-08-27 12:24:44,876 [] [] SSL configuration found for the listener, falling back for that for the data connection [DEBUG] 2011-08-27 12:24:44,892 [] [] Loaded 4 bean definitions from location pattern [C:\apache-ftpserver-1.0.6\res\conf\ftpd-xenacom.xml] [ INFO] 2011-08-27 12:24:44,893 [] [] Bean factory for application context [org.springframework.context.support.FileSystemXmlApplicationContext@1372bdb]: org.springframework.beans.factory.support.DefaultListableBeanFactory@b8ec9a [DEBUG] 2011-08-27 12:24:44,896 [] [] 4 beans defined in org.springframework.context.support.FileSystemXmlApplicationContext@1372bdb: display name [org.springframework.context.support.FileSystemXmlApplicationContext@1372bdb]; startup date [Sat Aug 27 12:24:44 NZST 2011]; root of context hierarchy [DEBUG] 2011-08-27 12:24:44,927 [] [] Unable to locate MessageSource with name 'messageSource': using default [org.springframework.context.support.DelegatingMessageSource@108db0b] [DEBUG] 2011-08-27 12:24:44,932 [] [] Unable to locate ApplicationEventMulticaster with name 'applicationEventMulticaster': using default [org.springframework.context.event.SimpleApplicationEventMulticaster@10b9691] [ INFO] 2011-08-27 12:24:44,936 [] [] Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@b8ec9a: defining beans [org.apache.ftpserver.listener.ListenerFactory#0,org.apache.ftpserver.usermanager.PropertiesUserManagerFactory#0,org.apache.ftpserver.FtpServerFactory#0,myServer]; root of factory hierarchy [DEBUG] 2011-08-27 12:24:44,939 [] [] Creating shared instance of singleton bean 'org.apache.ftpserver.listener.ListenerFactory#0' [DEBUG] 2011-08-27 12:24:44,948 [] [] Creating instance of bean 'org.apache.ftpserver.listener.ListenerFactory#0' [DEBUG] 2011-08-27 12:24:44,982 [] [] Eagerly caching bean 'org.apache.ftpserver.listener.ListenerFactory#0' to allow for resolving potential circular references [TRACE] 2011-08-27 12:24:44,994 [] [] Getting BeanInfo for class [org.apache.ftpserver.listener.ListenerFactory] [TRACE] 2011-08-27 12:24:45,022 [] [] Caching PropertyDescriptors for class [org.apache.ftpserver.listener.ListenerFactory] [TRACE] 2011-08-27 12:24:45,023 [] [] Found bean property 'blockedAddresses' of type [java.util.List] [TRACE] 2011-08-27 12:24:45,026 [] [] Found bean property 'blockedSubnets' of type [java.util.List] [TRACE] 2011-08-27 12:24:45,044 [] [] Found bean property 'class' of type [java.lang.Class] [TRACE] 2011-08-27 12:24:45,047 [] [] Found bean property 'dataConnectionConfiguration' of type [org.apache.ftpserver.DataConnectionConfiguration] [TRACE] 2011-08-27 12:24:45,049 [] [] Found bean property 'idleTimeout' of type [int] [TRACE] 2011-08-27 12:24:45,052 [] [] Found bean property 'implicitSsl' of type [boolean] [TRACE] 2011-08-27 12:24:45,065 [] [] Found bean property 'ipFilter' of type [org.apache.ftpserver.ipfilter.IpFilter] [TRACE] 2011-08-27 12:24:45,067 [] [] Found bean property 'port' of type [int] [TRACE] 2011-08-27 12:24:45,070 [] [] Found bean property 'serverAddress' of type [java.lang.String] [TRACE] 2011-08-27 12:24:45,072 [] [] Found bean property 'sslConfiguration' of type [org.apache.ftpserver.ssl.SslConfiguration] [DEBUG] 2011-08-27 12:24:45,122 [] [] Finished creating instance of bean 'org.apache.ftpserver.listener.ListenerFactory#0' [DEBUG] 2011-08-27 12:24:45,125 [] [] Creating shared instance of singleton bean 'org.apache.ftpserver.usermanager.PropertiesUserManagerFactory#0' [DEBUG] 2011-08-27 12:24:45,129 [] [] Creating instance of bean 'org.apache.ftpserver.usermanager.PropertiesUserManagerFactory#0' [DEBUG] 2011-08-27 12:24:45,147 [] [] Eagerly caching bean 'org.apache.ftpserver.usermanager.PropertiesUserManagerFactory#0' to allow for resolving potential circular references [TRACE] 2011-08-27 12:24:45,148 [] [] Getting BeanInfo for class [org.apache.ftpserver.usermanager.PropertiesUserManagerFactory] [TRACE] 2011-08-27 12:24:45,155 [] [] Caching PropertyDescriptors for class [org.apache.ftpserver.usermanager.PropertiesUserManagerFactory] [TRACE] 2011-08-27 12:24:45,164 [] [] Found bean property 'adminName' of type [java.lang.String] [TRACE] 2011-08-27 12:24:45,166 [] [] Found bean property 'class' of type [java.lang.Class] [TRACE] 2011-08-27 12:24:45,169 [] [] Found bean property 'file' of type [java.io.File] [TRACE] 2011-08-27 12:24:45,172 [] [] Found bean property 'passwordEncryptor' of type [org.apache.ftpserver.usermanager.PasswordEncryptor] [TRACE] 2011-08-27 12:24:45,175 [] [] Found bean property 'url' of type [java.net.URL] [TRACE] 2011-08-27 12:24:45,178 [] [] Converting String to [class java.io.File] using property editor [org.springframework.beans.propertyeditors.FileEditor@1a2f3f2] [DEBUG] 2011-08-27 12:24:45,183 [] [] Finished creating instance of bean 'org.apache.ftpserver.usermanager.PropertiesUserManagerFactory#0' [DEBUG] 2011-08-27 12:24:45,191 [] [] Creating shared instance of singleton bean 'org.apache.ftpserver.FtpServerFactory#0' [DEBUG] 2011-08-27 12:24:45,195 [] [] Creating instance of bean 'org.apache.ftpserver.FtpServerFactory#0' [DEBUG] 2011-08-27 12:24:45,327 [] [] Eagerly caching bean 'org.apache.ftpserver.FtpServerFactory#0' to allow for resolving potential circular references [DEBUG] 2011-08-27 12:24:45,331 [] [] Creating instance of bean '(inner bean)' [DEBUG] 2011-08-27 12:24:45,344 [] [] Returning cached instance of singleton bean 'org.apache.ftpserver.listener.ListenerFactory#0' [DEBUG] 2011-08-27 12:24:45,351 [] [] Finished creating instance of bean '(inner bean)' [TRACE] 2011-08-27 12:24:45,354 [] [] Getting BeanInfo for class [org.apache.ftpserver.FtpServerFactory] [TRACE] 2011-08-27 12:24:45,361 [] [] Caching PropertyDescriptors for class [org.apache.ftpserver.FtpServerFactory] [TRACE] 2011-08-27 12:24:45,364 [] [] Found bean property 'class' of type [java.lang.Class] [TRACE] 2011-08-27 12:24:45,380 [] [] Found bean property 'commandFactory' of type [org.apache.ftpserver.command.CommandFactory] [TRACE] 2011-08-27 12:24:45,405 [] [] Found bean property 'connectionConfig' of type [org.apache.ftpserver.ConnectionConfig] [TRACE] 2011-08-27 12:24:45,409 [] [] Found bean property 'fileSystem' of type [org.apache.ftpserver.ftplet.FileSystemFactory] [TRACE] 2011-08-27 12:24:45,418 [] [] Found bean property 'ftplets' of type [java.util.Map] [TRACE] 2011-08-27 12:24:45,422 [] [] Found bean property 'listeners' of type [java.util.Map] [TRACE] 2011-08-27 12:24:45,425 [] [] Found bean property 'messageResource' of type [org.apache.ftpserver.message.MessageResource] [TRACE] 2011-08-27 12:24:45,435 [] [] Found bean property 'userManager' of type [org.apache.ftpserver.ftplet.UserManager] [DEBUG] 2011-08-27 12:24:45,440 [] [] Creating instance of bean '(inner bean)#1' [DEBUG] 2011-08-27 12:24:45,443 [] [] Returning cached instance of singleton bean 'org.apache.ftpserver.usermanager.PropertiesUserManagerFactory#0' [DEBUG] 2011-08-27 12:24:45,447 [] [] File configured, will try loading [DEBUG] 2011-08-27 12:24:45,451 [] [] File found on file system [DEBUG] 2011-08-27 12:24:45,456 [] [] Finished creating instance of bean '(inner bean)#1' [DEBUG] 2011-08-27 12:24:45,460 [] [] Finished creating instance of bean 'org.apache.ftpserver.FtpServerFactory#0' [DEBUG] 2011-08-27 12:24:45,463 [] [] Creating shared instance of singleton bean 'myServer' [DEBUG] 2011-08-27 12:24:45,468 [] [] Creating instance of bean 'myServer' [DEBUG] 2011-08-27 12:24:45,471 [] [] Returning cached instance of singleton bean 'org.apache.ftpserver.FtpServerFactory#0' [DEBUG] 2011-08-27 12:24:45,479 [] [] Eagerly caching bean 'myServer' to allow for resolving potential circular references [DEBUG] 2011-08-27 12:24:45,482 [] [] Finished creating instance of bean 'myServer' [DEBUG] 2011-08-27 12:24:45,486 [] [] Publishing event in context [org.springframework.context.support.FileSystemXmlApplicationContext@1372bdb]: org.springframework.context.event.ContextRefreshedEvent[source=org.springframework.context.support.FileSystemXmlApplicationContext@1372bdb: display name [org.springframework.context.support.FileSystemXmlApplicationContext@1372bdb]; startup date [Sat Aug 27 12:24:44 NZST 2011]; root of context hierarchy] [DEBUG] 2011-08-27 12:24:45,489 [] [] Returning cached instance of singleton bean 'myServer' [ INFO] 2011-08-27 12:24:45,494 [] [] Starting FTP server daemon [DEBUG] 2011-08-27 12:24:45,664 [] [] Intializing shared thread pool executor with max threads of 10 [ INFO] 2011-08-27 12:24:45,790 [] [] FTP server started
