Re: Connection closed without indication

Wed, 30 Mar 2011 05:44:47 -0700

Thnks a lot, I've removed slf4j-simple-1.5.2.jar, added slf4j-log4j12-1.5.2.jar and log4j-1.2.14.jar and it worked. 
You will find below the full debug log file if you want to have look.
This log helped me to find my error : I try to access from ftplet's thread (main thread (?)) a component that belongs 
to the GUI SWT Thread : it launches an Invalid thread access exception.

Again thanks a lot fot your help,
frank


[DEBUG] 2011-03-30 14:26:03,119 [] [] File configured, will try loading
[DEBUG] 2011-03-30 14:26:03,120 [] [] File found on file system
[DEBUG] 2011-03-30 14:26:03,174 [] [] Intializing shared thread pool executor with max threads of 10 
[ INFO] 2011-03-30 14:26:03,213 [] [] FTP server started
[DEBUG] 2011-03-30 14:26:07,619 [] [] Shutting down the thread pool executor
[DEBUG] 2011-03-30 14:26:59,625 [] [] File configured, will try loading
[DEBUG] 2011-03-30 14:26:59,626 [] [] File found on file system
[DEBUG] 2011-03-30 14:26:59,672 [] [] Intializing shared thread pool executor with max threads of 10 
[ INFO] 2011-03-30 14:26:59,710 [] [] FTP server started
[DEBUG] 2011-03-30 14:27:20,671 [] [127.0.0.1] Firing a SESSION_CREATED event for session 2 [DEBUG] 2011-03-30 14:27:20,671 [] [127.0.0.1] Firing a SESSION_CREATED event for session 2 
[ INFO] 2011-03-30 14:27:20,671 [] [127.0.0.1] CREATED
[DEBUG] 2011-03-30 14:27:20,674 [] [127.0.0.1] Event SESSION_CREATED has been fired for session 2 [DEBUG] 2011-03-30 14:27:20,674 [] [127.0.0.1] Event SESSION_CREATED has been fired for session 2 [DEBUG] 2011-03-30 14:27:20,674 [] [127.0.0.1] Firing a SESSION_OPENED event for session 2 [DEBUG] 2011-03-30 14:27:20,676 [] [127.0.0.1] Adding event SESSION_OPENED to session 2 
Queue : [SESSION_OPENED, ]
[DEBUG] 2011-03-30 14:27:20,677 [] [127.0.0.1] Event SESSION_OPENED has been fired for session 2 [DEBUG] 2011-03-30 14:27:20,677 [] [127.0.0.1] Firing a SESSION_OPENED event for session 2 [DEBUG] 2011-03-30 14:27:20,677 [] [127.0.0.1] Firing a SESSION_OPENED event for session 2 
[ INFO] 2011-03-30 14:27:20,677 [] [127.0.0.1] OPENED
[DEBUG] 2011-03-30 14:27:20,679 [] [127.0.0.1] Ftplet threw exception
org.eclipse.swt.SWTException: Invalid thread access
    at org.eclipse.swt.SWT.error(SWT.java:3884)
    at org.eclipse.swt.SWT.error(SWT.java:3799)
    at org.eclipse.swt.SWT.error(SWT.java:3770)
    at org.eclipse.swt.widgets.Widget.error(Widget.java:619)
    at org.eclipse.swt.widgets.Widget.checkWidget(Widget.java:463)
    at org.eclipse.swt.custom.StyledText.getText(StyledText.java:5013)
at analyse.gui.SharingContainer.logServerMessage(SharingContainer.java:552) at analyse.gui.SharingContainer.updateMessage(SharingContainer.java:582) at ftpsharing.FTPShareExperimentsServer.notifyObserversMessage(FTPShareExperimentsServer.java:96) at ftpsharing.FTPShareExperimentsServer.onConnect(FTPShareExperimentsServer.java:116) at org.apache.ftpserver.ftpletcontainer.impl.DefaultFtpletContainer.onConnect(DefaultFtpletContainer.java:105) at org.apache.ftpserver.impl.DefaultFtpHandler.sessionOpened(DefaultFtpHandler.java:77) at org.apache.ftpserver.listener.nio.FtpHandlerAdapter.sessionOpened(FtpHandlerAdapter.java:90) at org.apache.mina.core.filterchain.DefaultIoFilterChain$TailFilter.sessionOpened(DefaultIoFilterChain.java:654) at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextSessionOpened(DefaultIoFilterChain.java:372) at org.apache.mina.core.filterchain.DefaultIoFilterChain.access$800(DefaultIoFilterChain.java:46) at org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.sessionOpened(DefaultIoFilterChain.java:773) at org.apache.mina.filter.logging.LoggingFilter.sessionOpened(LoggingFilter.java:197) at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextSessionOpened(DefaultIoFilterChain.java:372) at org.apache.mina.core.filterchain.DefaultIoFilterChain.access$800(DefaultIoFilterChain.java:46) at org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.sessionOpened(DefaultIoFilterChain.java:773) at org.apache.mina.core.filterchain.IoFilterEvent.fire(IoFilterEvent.java:92) at org.apache.mina.filter.logging.MdcInjectionFilter.filter(MdcInjectionFilter.java:136) at org.apache.mina.filter.util.CommonEventFilter.sessionOpened(CommonEventFilter.java:50) at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextSessionOpened(DefaultIoFilterChain.java:372) at org.apache.mina.core.filterchain.DefaultIoFilterChain.access$800(DefaultIoFilterChain.java:46) at org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.sessionOpened(DefaultIoFilterChain.java:773) at org.apache.mina.core.filterchain.IoFilterAdapter.sessionOpened(IoFilterAdapter.java:87) at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextSessionOpened(DefaultIoFilterChain.java:372) at org.apache.mina.core.filterchain.DefaultIoFilterChain.access$800(DefaultIoFilterChain.java:46) at org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.sessionOpened(DefaultIoFilterChain.java:773) at org.apache.mina.core.filterchain.IoFilterEvent.fire(IoFilterEvent.java:92) 
    at org.apache.mina.core.session.IoEvent.run(IoEvent.java:63)
at org.apache.mina.filter.executor.OrderedThreadPoolExecutor$Worker.runTask(OrderedThreadPoolExecutor.java:780) at org.apache.mina.filter.executor.OrderedThreadPoolExecutor$Worker.runTasks(OrderedThreadPoolExecutor.java:772) at org.apache.mina.filter.executor.OrderedThreadPoolExecutor$Worker.run(OrderedThreadPoolExecutor.java:714) 
    at java.lang.Thread.run(Thread.java:655)
[DEBUG] 2011-03-30 14:27:20,680 [] [127.0.0.1] Ftplet returned DISCONNECT, session will be closed [DEBUG] 2011-03-30 14:27:20,681 [] [127.0.0.1] Firing a CLOSE event for session 2 [DEBUG] 2011-03-30 14:27:20,681 [] [127.0.0.1] Firing a CLOSE event for session 2 [DEBUG] 2011-03-30 14:27:20,681 [] [127.0.0.1] Event CLOSE has been fired for session 2 [DEBUG] 2011-03-30 14:27:20,681 [] [127.0.0.1] Event CLOSE has been fired for session 2 [DEBUG] 2011-03-30 14:27:20,682 [] [127.0.0.1] Event SESSION_OPENED has been fired for session 2 [DEBUG] 2011-03-30 14:27:20,682 [] [127.0.0.1] Firing a SESSION_CLOSED event for session 2 [DEBUG] 2011-03-30 14:27:20,682 [] [127.0.0.1] Adding event SESSION_CLOSED to session 2 
Queue : [SESSION_CLOSED, ]
[DEBUG] 2011-03-30 14:27:20,682 [] [127.0.0.1] Event SESSION_CLOSED has been fired for session 2 [DEBUG] 2011-03-30 14:27:20,682 [] [] Event SESSION_OPENED has been fired for session 2 [DEBUG] 2011-03-30 14:27:20,683 [] [] Firing a SESSION_CLOSED event for session 2 [DEBUG] 2011-03-30 14:27:20,684 [] [127.0.0.1] Firing a SESSION_CLOSED event for session 2 
[ INFO] 2011-03-30 14:27:20,684 [] [127.0.0.1] CLOSED
[DEBUG] 2011-03-30 14:27:20,684 [] [127.0.0.1] Closing session
[DEBUG] 2011-03-30 14:27:20,685 [] [127.0.0.1] Statistics login and connection count decreased due to session close 
[DEBUG] 2011-03-30 14:27:20,685 [] [127.0.0.1] Session closed
[DEBUG] 2011-03-30 14:27:20,685 [] [127.0.0.1] Event SESSION_CLOSED has been fired for session 2 [DEBUG] 2011-03-30 14:27:20,685 [] [] Event SESSION_CLOSED has been fired for session 2 
[DEBUG] 2011-03-30 14:27:25,519 [] [] Shutting down the thread pool executor
[DEBUG] 2011-03-30 14:28:22,290 [] [] File configured, will try loading
[DEBUG] 2011-03-30 14:28:22,291 [] [] File found on file system
[DEBUG] 2011-03-30 14:28:22,337 [] [] Intializing shared thread pool executor with max threads of 10 
[ INFO] 2011-03-30 14:28:22,375 [] [] FTP server started
[DEBUG] 2011-03-30 14:28:29,544 [] [127.0.0.1] Firing a SESSION_CREATED event for session 2 [DEBUG] 2011-03-30 14:28:29,544 [] [127.0.0.1] Firing a SESSION_CREATED event for session 2 
[ INFO] 2011-03-30 14:28:29,545 [] [127.0.0.1] CREATED
[DEBUG] 2011-03-30 14:28:29,549 [] [127.0.0.1] Event SESSION_CREATED has been fired for session 2 [DEBUG] 2011-03-30 14:28:29,549 [] [127.0.0.1] Event SESSION_CREATED has been fired for session 2 [DEBUG] 2011-03-30 14:28:29,549 [] [127.0.0.1] Firing a SESSION_OPENED event for session 2 [DEBUG] 2011-03-30 14:28:29,550 [] [127.0.0.1] Adding event SESSION_OPENED to session 2 
Queue : [SESSION_OPENED, ]
[DEBUG] 2011-03-30 14:28:29,550 [] [127.0.0.1] Event SESSION_OPENED has been fired for session 2 [DEBUG] 2011-03-30 14:28:29,551 [] [127.0.0.1] Firing a SESSION_OPENED event for session 2 [DEBUG] 2011-03-30 14:28:29,551 [] [127.0.0.1] Firing a SESSION_OPENED event for session 2 
[ INFO] 2011-03-30 14:28:29,551 [] [127.0.0.1] OPENED
[DEBUG] 2011-03-30 14:28:29,554 [] [127.0.0.1] Ftplet threw exception
org.eclipse.swt.SWTException: Invalid thread access
    at org.eclipse.swt.SWT.error(SWT.java:3884)
    at org.eclipse.swt.SWT.error(SWT.java:3799)
    at org.eclipse.swt.SWT.error(SWT.java:3770)
    at org.eclipse.swt.widgets.Widget.error(Widget.java:619)
    at org.eclipse.swt.widgets.Widget.checkWidget(Widget.java:463)
    at org.eclipse.swt.custom.StyledText.getText(StyledText.java:5013)
at analyse.gui.SharingContainer.logServerMessage(SharingContainer.java:552) at analyse.gui.SharingContainer.updateMessage(SharingContainer.java:582) at ftpsharing.FTPShareExperimentsServer.notifyObserversMessage(FTPShareExperimentsServer.java:96) at ftpsharing.FTPShareExperimentsServer.onConnect(FTPShareExperimentsServer.java:116) at org.apache.ftpserver.ftpletcontainer.impl.DefaultFtpletContainer.onConnect(DefaultFtpletContainer.java:105) at org.apache.ftpserver.impl.DefaultFtpHandler.sessionOpened(DefaultFtpHandler.java:77) at org.apache.ftpserver.listener.nio.FtpHandlerAdapter.sessionOpened(FtpHandlerAdapter.java:90) at org.apache.mina.core.filterchain.DefaultIoFilterChain$TailFilter.sessionOpened(DefaultIoFilterChain.java:654) at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextSessionOpened(DefaultIoFilterChain.java:372) at org.apache.mina.core.filterchain.DefaultIoFilterChain.access$800(DefaultIoFilterChain.java:46) at org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.sessionOpened(DefaultIoFilterChain.java:773) at org.apache.mina.filter.logging.LoggingFilter.sessionOpened(LoggingFilter.java:197) at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextSessionOpened(DefaultIoFilterChain.java:372) at org.apache.mina.core.filterchain.DefaultIoFilterChain.access$800(DefaultIoFilterChain.java:46) at org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.sessionOpened(DefaultIoFilterChain.java:773) at org.apache.mina.core.filterchain.IoFilterEvent.fire(IoFilterEvent.java:92) at org.apache.mina.filter.logging.MdcInjectionFilter.filter(MdcInjectionFilter.java:136) at org.apache.mina.filter.util.CommonEventFilter.sessionOpened(CommonEventFilter.java:50) at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextSessionOpened(DefaultIoFilterChain.java:372) at org.apache.mina.core.filterchain.DefaultIoFilterChain.access$800(DefaultIoFilterChain.java:46) at org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.sessionOpened(DefaultIoFilterChain.java:773) at org.apache.mina.core.filterchain.IoFilterAdapter.sessionOpened(IoFilterAdapter.java:87) at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextSessionOpened(DefaultIoFilterChain.java:372) at org.apache.mina.core.filterchain.DefaultIoFilterChain.access$800(DefaultIoFilterChain.java:46) at org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.sessionOpened(DefaultIoFilterChain.java:773) at org.apache.mina.core.filterchain.IoFilterEvent.fire(IoFilterEvent.java:92) 
    at org.apache.mina.core.session.IoEvent.run(IoEvent.java:63)
at org.apache.mina.filter.executor.OrderedThreadPoolExecutor$Worker.runTask(OrderedThreadPoolExecutor.java:780) at org.apache.mina.filter.executor.OrderedThreadPoolExecutor$Worker.runTasks(OrderedThreadPoolExecutor.java:772) at org.apache.mina.filter.executor.OrderedThreadPoolExecutor$Worker.run(OrderedThreadPoolExecutor.java:714) 
    at java.lang.Thread.run(Thread.java:655)
[DEBUG] 2011-03-30 14:28:29,555 [] [127.0.0.1] Ftplet returned DISCONNECT, session will be closed [DEBUG] 2011-03-30 14:28:29,556 [] [127.0.0.1] Firing a CLOSE event for session 2 [DEBUG] 2011-03-30 14:28:29,556 [] [127.0.0.1] Firing a CLOSE event for session 2 [DEBUG] 2011-03-30 14:28:29,556 [] [127.0.0.1] Event CLOSE has been fired for session 2 [DEBUG] 2011-03-30 14:28:29,556 [] [127.0.0.1] Event CLOSE has been fired for session 2 [DEBUG] 2011-03-30 14:28:29,556 [] [127.0.0.1] Event SESSION_OPENED has been fired for session 2 [DEBUG] 2011-03-30 14:28:29,557 [] [127.0.0.1] Firing a SESSION_CLOSED event for session 2 [DEBUG] 2011-03-30 14:28:29,557 [] [127.0.0.1] Adding event SESSION_CLOSED to session 2 
Queue : [SESSION_CLOSED, ]
[DEBUG] 2011-03-30 14:28:29,557 [] [127.0.0.1] Event SESSION_CLOSED has been fired for session 2 [DEBUG] 2011-03-30 14:28:29,557 [] [] Event SESSION_OPENED has been fired for session 2 [DEBUG] 2011-03-30 14:28:29,557 [] [] Firing a SESSION_CLOSED event for session 2 [DEBUG] 2011-03-30 14:28:29,558 [] [127.0.0.1] Firing a SESSION_CLOSED event for session 2 
[ INFO] 2011-03-30 14:28:29,558 [] [127.0.0.1] CLOSED
[DEBUG] 2011-03-30 14:28:29,558 [] [127.0.0.1] Closing session
[DEBUG] 2011-03-30 14:28:29,559 [] [127.0.0.1] Statistics login and connection count decreased due to session close 
[DEBUG] 2011-03-30 14:28:29,559 [] [127.0.0.1] Session closed
[DEBUG] 2011-03-30 14:28:29,560 [] [127.0.0.1] Event SESSION_CLOSED has been fired for session 2 [DEBUG] 2011-03-30 14:28:29,560 [] [] Event SESSION_CLOSED has been fired for session 2 
[DEBUG] 2011-03-30 14:28:32,944 [] [] Shutting down the thread pool executor



On Wed, Mar 30, 2011 at 2:11 PM, frank buloup<[email protected]>  wrote:

Obviously and because of the use of ftpserver, there are slf4j-api-1.5.2.jar
and slf4j-simple-1.5.2.jar libs on class path.

Right, slf4j-simple is a logging framework. To print debug statements,
you need to replace that with log4j (and slf4j-log4j) and a
configuration file like the one your using.

I hope that slf4j-simple was not included by default by FtpServer.
FtpServer should never include (via Maven transitive dependencies)
include a logging framework, only the slf4j facade.

/niklas




--

Frank Buloup

04 91 17 22 71

Institut des Sciences du Mouvement

UMR 6233 CNRS & Université de le Méditerranée

163 avenue de Luminy

13288 Marseille Cedex 9

Fax : 04 91 17 22 52

Reply via email to