Add Console.WriteLine(...) tracing to troubleshoot issue #335.

Author: drieseng

src/Renci.SshNet/Channels/Channel.cs

@@ -398,6 +398,11 @@ protected virtual void OnEof()
         /// </summary>
         protected virtual void OnClose()
         {
+            Console.WriteLine("[{0}] SSH_MSG_CHANNEL_CLOSE received from server {1}/{2}.",
+                              Thread.CurrentThread.ManagedThreadId,
+                              LocalChannelNumber,
+                              RemoteChannelNumber);
+
             _closeMessageReceived = true;
 
             // Signal that SSH_MSG_CHANNEL_CLOSE message was received from server.
@@ -407,6 +412,11 @@ protected virtual void OnClose()
             if (channelClosedWaitHandle != null)
                 channelClosedWaitHandle.Set();
 
+            Console.WriteLine("[{0}] Closing channel after receiving SSH_MSG_CHANNEL_CLOSE from server {1}/{2}.",
+                              Thread.CurrentThread.ManagedThreadId,
+                              LocalChannelNumber,
+                              RemoteChannelNumber);
+
             // close the channel
             Close();
         }
@@ -522,6 +532,11 @@ protected virtual void Close()
             // same time we would otherwise risk sending the SSH_MSG_CHANNEL_EOF after the SSH_MSG_CHANNEL_CLOSE
             // message causing the server to disconnect the session.
 
+            Console.WriteLine("[{0}] In Close() {1}/{2}.",
+                              Thread.CurrentThread.ManagedThreadId,
+                              LocalChannelNumber,
+                              RemoteChannelNumber);
+
             lock (this)
             {
                 // send EOF message first the following conditions are met:
@@ -532,8 +547,10 @@ protected virtual void Close()
                 // * the session is connected
                 if (!_eofMessageSent && !_closeMessageReceived && !_eofMessageReceived && IsOpen && IsConnected)
                 {
+                    Console.WriteLine("[{0}] Sending SSH_MSG_CHANNEL_EOF for channel {1}/{2}.", Thread.CurrentThread.ManagedThreadId, LocalChannelNumber, RemoteChannelNumber);
                     if (TrySendMessage(new ChannelEofMessage(RemoteChannelNumber)))
                     {
+                        Console.WriteLine("[{0}] Sent SSH_MSG_CHANNEL_EOF for channel {1}/{2}.", Thread.CurrentThread.ManagedThreadId, LocalChannelNumber, RemoteChannelNumber);
                         _eofMessageSent = true;
                     }
                 }
@@ -542,21 +559,38 @@ protected virtual void Close()
                 // and the channel is open and the session is connected
                 if (!_closeMessageSent && IsOpen && IsConnected)
                 {
+                    Console.WriteLine("[{0}] Sending SSH_MSG_CHANNEL_CLOSE for channel {1}/{2}.", Thread.CurrentThread.ManagedThreadId, LocalChannelNumber, RemoteChannelNumber);
                     if (TrySendMessage(new ChannelCloseMessage(RemoteChannelNumber)))
                     {
+                        Console.WriteLine("[{0}] Sent SSH_MSG_CHANNEL_CLOSE for channel {1}/{2}.", Thread.CurrentThread.ManagedThreadId, LocalChannelNumber, RemoteChannelNumber);
                         _closeMessageSent = true;
 
                         // only wait for the channel to be closed by the server if we didn't send a
                         // SSH_MSG_CHANNEL_CLOSE as response to a SSH_MSG_CHANNEL_CLOSE sent by the
                         // server
+                        Console.WriteLine("[{0}] Waiting for server to close channel {1}/{2}.", Thread.CurrentThread.ManagedThreadId, LocalChannelNumber, RemoteChannelNumber);
                         try
                         {
                             WaitOnHandle(_channelClosedWaitHandle);
+                            Console.WriteLine("[{0}] Finished waiting for server to close channel {1}/{2}.", Thread.CurrentThread.ManagedThreadId, LocalChannelNumber, RemoteChannelNumber);
                         }
-                        catch (SshConnectionException)
+                        catch (SshConnectionException ex)
                         {
+                            Console.WriteLine("[{0}] SSH connection exception waiting for server to close channel {1}/{2}: {3}.",
+                                              Thread.CurrentThread.ManagedThreadId,
+                                              LocalChannelNumber,
+                                              RemoteChannelNumber,
+                                              ex.ToString());
                             // ignore connection failures as we're closing the channel anyway
                         }
+                        catch (Exception ex)
+                        {
+                            Console.WriteLine("[{0}] Exception waiting for server to close channel {1}/{2}: {3}.",
+                                              Thread.CurrentThread.ManagedThreadId,
+                                              LocalChannelNumber,
+                                              RemoteChannelNumber,
+                                              ex.ToString());
+                        }
                     }
                 }
 

src/Renci.SshNet/ShellStream.cs

@@ -695,6 +695,9 @@ public void WriteLine(string line)
         /// <param name="disposing"><c>true</c> to release both managed and unmanaged resources; <c>false</c> to release only unmanaged resources.</param>
         protected override void Dispose(bool disposing)
         {
+            Console.WriteLine("[{0}] Disposing ShellStream.",
+                              Thread.CurrentThread.ManagedThreadId);
+
             base.Dispose(disposing);
 
             if (_isDisposed)
@@ -706,9 +709,16 @@ protected override void Dispose(bool disposing)
 
                 if (_channel != null)
                 {
+                    Console.WriteLine("[{0}] Disposing channel from ShellStream.Dispose(bool).",
+                                      Thread.CurrentThread.ManagedThreadId);
+
                     _channel.DataReceived -= Channel_DataReceived;
                     _channel.Closed -= Channel_Closed;
                     _channel.Dispose();
+
+                    Console.WriteLine("[{0}] Disposed channel from ShellStream.Dispose(bool).",
+                                      Thread.CurrentThread.ManagedThreadId);
+
                     _channel = null;
                 }
 
@@ -750,11 +760,19 @@ private void Session_ErrorOccured(object sender, ExceptionEventArgs e)
         private void Session_Disconnected(object sender, EventArgs e)
         {
             if (_channel != null)
+            {
+                Console.WriteLine("[{0}] Disposing channel from Session_Disconnected(...).",
+                                  Thread.CurrentThread.ManagedThreadId);
+
                 _channel.Dispose();
+            }
         }
 
         private void Channel_Closed(object sender, ChannelEventArgs e)
         {
+            Console.WriteLine("[{0}] Disposing ShellStream from Channel_Closed(...).",
+                              Thread.CurrentThread.ManagedThreadId);
+
             //  TODO:   Do we need to call dispose here ??
             Dispose();
         }