[AG-TECH] ssl error when connecting to venue server

George Estes gestes at ncsa.uiuc.edu
Fri Mar 6 09:07:01 CST 2009


Thanks Tom.  Here's the segment from the VenueServer.log where the user tries to connect to the server.  Do you see anything that might give a clue as to why he can't connect?  Lenny Friedhof is the user.



03/05/09 13:15:41 -1252201584 Hosting     ServiceContainer.py:146 ERROR None
Traceback (most recent call last):
  File "/usr/lib/python2.4/site-packages/M2Crypto/SSL/SSLServer.py", line 29, in handle_request
    request, client_address = self.get_request()
  File "/usr/lib/python2.4/SocketServer.py", line 373, in get_request
    return self.socket.accept()
  File "/usr/lib/python2.4/site-packages/M2Crypto/SSL/Connection.py", line 136, in accept
    ssl.accept_ssl()
  File "/usr/lib/python2.4/site-packages/M2Crypto/SSL/Connection.py", line 125, in accept_ssl
    return m2.ssl_accept(self.ssl)
SSLError: unexpected eof
03/05/09 13:15:41 -1252201584 Hosting     Server.py:70 ERROR Exception in SOAP server main loop
Traceback (most recent call last):
  File "/usr/lib/python2.4/site-packages/AccessGrid3/AccessGrid/hosting/ZSI/Server.py", line 68, in Run
    self._server.handle_request()
  File "/usr/lib/python2.4/site-packages/M2Crypto/SSL/SSLServer.py", line 33, in handle_request
    self.handle_error(request, client_address)
  File "/usr/lib/python2.4/site-packages/AccessGrid3/AccessGrid/hosting/ZSI/ServiceContainer.py", line 147, in handle_error
    SSL.SSLServer.handle_error(self)
TypeError: handle_error() takes exactly 3 arguments (1 given)
03/05/09 13:15:41 -1273181296 Security     AuthorizationManager.py:201 DEBUG Authorizing action Enter for None
03/05/09 13:15:41 -1273181296 Security     AuthorizationManager.py:213 DEBUG Accepting access from unidentified user as part of Everybody role
03/05/09 13:15:41 -1273181296 VenueServer     Venue.py:1075 DEBUG Enter called.
03/05/09 13:15:41 -1273181296 VenueServer     Venue.py:1080 DEBUG Enter: Assigning connection id: 8d8ee02917bc48ce6983934775cf6927
03/05/09 13:15:41 -1273181296 Usage     Venue.py:1093 INFO  "Enter","","Schoolhouse","8d8ee0290c980c244c97d3bd8d6f1789"
03/05/09 13:15:41 -1273181296 VenueServer     Venue.py:1094 DEBUG Current users:
03/05/09 13:15:41 -1273181296 VenueServer     Venue.py:1096 DEBUG    VenueClientState(name=Lenny Freidhof connectionId=8d8ee02917bc48ce6983934775cf6927)
03/05/09 13:15:41 -1273181296 VenueServer     Venue.py:1096 DEBUG    VenueClientState(name=conf2-ncsa connectionId=8d8ee02917bc48cbbc7fada9dd651961)
03/05/09 13:15:41 -1273181296 VenueServer     Venue.py:1097 DEBUG Enter: Distribute enter event 
03/05/09 13:15:41 -1273181296 VenueServer     Venue.py:1104 INFO  User already in venue users list: 
03/05/09 13:15:41 -1273181296 VenueServer     Venue.py:1108 DEBUG state: <AccessGrid.Descriptions.VenueState instance at 0xa71332c>
03/05/09 13:15:41 -1252201584 Hosting     ServiceContainer.py:146 ERROR None
Traceback (most recent call last):
  File "/usr/lib/python2.4/site-packages/M2Crypto/SSL/SSLServer.py", line 29, in handle_request
    request, client_address = self.get_request()
  File "/usr/lib/python2.4/SocketServer.py", line 373, in get_request
    return self.socket.accept()
  File "/usr/lib/python2.4/site-packages/M2Crypto/SSL/Connection.py", line 136, in accept
    ssl.accept_ssl()
TypeError: handle_error() takes exactly 3 arguments (1 given)
03/05/09 13:15:44 -1241703536 VenueServer     Venue.py:685 DEBUG CleanupClients: now=1236280544
03/05/09 13:15:44 -1241703536 VenueServer     Venue.py:685 DEBUG CleanupClients: now=1236280544
03/05/09 13:15:44 -1241703536 VenueServer     Venue.py:685 DEBUG CleanupClients: now=1236280544
03/05/09 13:15:44 -1241703536 VenueServer     Venue.py:694 DEBUG CleanupClients: client 8d8ee02917bc48ce6983934775cf6927 Lenny Freidhof timeout=1236280661
03/05/09 13:15:44 -1241703536 VenueServer     Venue.py:239 DEBUG Client Timeout Check: 1236280661 vs 1236280544
03/05/09 13:15:44 -1241703536 VenueServer     Venue.py:694 DEBUG CleanupClients: client 8d8ee02917bc48cbbc7fada9dd651961 conf2-ncsa timeout=1236280639
03/05/09 13:15:44 -1241703536 VenueServer     Venue.py:239 DEBUG Client Timeout Check: 1236280639 vs 1236280544
03/05/09 13:15:44 -1241703536 VenueServer     Venue.py:685 DEBUG CleanupClients: now=1236280544
03/05/09 13:15:44 -1241703536 VenueServer     Venue.py:685 DEBUG CleanupClients: now=1236280544
03/05/09 13:15:44 -1241703536 VenueServer     Venue.py:685 DEBUG CleanupClients: now=1236280544
03/05/09 13:15:44 -1241703536 VenueServer     Venue.py:685 DEBUG CleanupClients: now=1236280544
03/05/09 13:15:44 -1241703536 VenueServer     Venue.py:694 DEBUG CleanupClients: client 8d8ee02917bc47c7e218520b8672a75d George timeout=1236280642
03/05/09 13:15:44 -1241703536 VenueServer     Venue.py:239 DEBUG Client Timeout Check: 1236280642 vs 1236280544
03/05/09 13:15:44 -1241703536 VenueServer     Venue.py:685 DEBUG CleanupClients: now=1236280544
03/05/09 13:15:44 -1241703536 VenueServer     Venue.py:685 DEBUG CleanupClients: now=1236280544
03/05/09 13:15:55 -1273181296 Security     AuthorizationManager.py:201 DEBUG Authorizing action UpdateLifetime for None
03/05/09 13:15:55 -1273181296 Security     AuthorizationManager.py:213 DEBUG Accepting access from unidentified user as part of Everybody role
03/05/09 13:15:55 -1273181296 VenueServer     Venue.py:879 DEBUG UpdateLifetime: 8d8ee02917bc48cbbc7fada9dd651961 conf2-ncsa ; next heartbeat by 36
03/05/09 13:15:59 -1273181296 Security     AuthorizationManager.py:201 DEBUG Authorizing action UpdateLifetime for None
03/05/09 13:15:59 -1273181296 Security     AuthorizationManager.py:213 DEBUG Accepting access from unidentified user as part of Everybody role
03/05/09 13:15:59 -1273181296 VenueServer     Venue.py:879 DEBUG UpdateLifetime: 8d8ee02917bc47c7e218520b8672a75d George ; next heartbeat by 36
03/05/09 13:15:59 -1241703536 VenueServer     Venue.py:685 DEBUG CleanupClients: now=1236280559
03/05/09 13:15:59 -1241703536 VenueServer     Venue.py:685 DEBUG CleanupClients: now=1236280559
03/05/09 13:15:59 -1241703536 VenueServer     Venue.py:685 DEBUG CleanupClients: now=1236280559
03/05/09 13:15:59 -1241703536 VenueServer     Venue.py:694 DEBUG CleanupClients: client 8d8ee02917bc48ce6983934775cf6927 Lenny Freidhof timeout=1236280661
03/05/09 13:15:59 -1241703536 VenueServer     Venue.py:239 DEBUG Client Timeout Check: 1236280661 vs 1236280559
03/05/09 13:15:59 -1241703536 VenueServer     Venue.py:694 DEBUG CleanupClients: client 8d8ee02917bc48cbbc7fada9dd651961 conf2-ncsa timeout=1236280675
03/05/09 13:15:59 -1241703536 VenueServer     Venue.py:239 DEBUG Client Timeout Check: 1236280675 vs 1236280559
03/05/09 13:15:59 -1241703536 VenueServer     Venue.py:685 DEBUG CleanupClients: now=1236280559
03/05/09 13:15:59 -1241703536 VenueServer     Venue.py:685 DEBUG CleanupClients: now=1236280559
03/05/09 13:15:59 -1241703536 VenueServer     Venue.py:685 DEBUG CleanupClients: now=1236280559
03/05/09 13:15:59 -1241703536 VenueServer     Venue.py:685 DEBUG CleanupClients: now=1236280559
03/05/09 13:15:59 -1241703536 VenueServer     Venue.py:694 DEBUG CleanupClients: client 8d8ee02917bc47c7e218520b8672a75d George timeout=1236280679
03/05/09 13:15:59 -1241703536 VenueServer     Venue.py:239 DEBUG Client Timeout Check: 1236280679 vs 1236280559
03/05/09 13:15:59 -1241703536 VenueServer     Venue.py:685 DEBUG CleanupClients: now=1236280559
03/05/09 13:15:59 -1241703536 VenueServer     Venue.py:685 DEBUG CleanupClients: now=1236280559
03/05/09 13:16:00 -1231213680 VenueServer     VenueServer.py:755 INFO  Checkpoint starting at: Thu Mar  5 13:16:00 2009
03/05/09 13:16:00 -1231213680 VenueServer     VenueServer.py:787 INFO  Checkpointing completed at: Thu Mar  5 13:16:00 2009
03/05/09 13:16:14 -1241703536 VenueServer     Venue.py:685 DEBUG CleanupClients: now=1236280574
03/05/09 13:16:14 -1241703536 VenueServer     Venue.py:685 DEBUG CleanupClients: now=1236280574
03/05/09 13:16:14 -1241703536 VenueServer     Venue.py:685 DEBUG CleanupClients: now=1236280574
03/05/09 13:16:14 -1241703536 VenueServer     Venue.py:694 DEBUG CleanupClients: client 8d8ee02917bc48ce6983934775cf6927 Lenny Freidhof timeout=1236280661
03/05/09 13:16:14 -1241703536 VenueServer     Venue.py:239 DEBUG Client Timeout Check: 1236280661 vs 1236280574
03/05/09 13:16:14 -1241703536 VenueServer     Venue.py:694 DEBUG CleanupClients: client 8d8ee02917bc48cbbc7fada9dd651961 conf2-ncsa timeout=1236280675
03/05/09 13:16:14 -1241703536 VenueServer     Venue.py:239 DEBUG Client Timeout Check: 1236280675 vs 1236280574
03/05/09 13:16:14 -1241703536 VenueServer     Venue.py:685 DEBUG CleanupClients: now=1236280574
03/05/09 13:16:14 -1241703536 VenueServer     Venue.py:685 DEBUG CleanupClients: now=1236280574
03/05/09 13:16:14 -1241703536 VenueServer     Venue.py:685 DEBUG CleanupClients: now=1236280574
03/05/09 13:16:14 -1241703536 VenueServer     Venue.py:685 DEBUG CleanupClients: now=1236280574
03/05/09 13:16:14 -1241703536 VenueServer     Venue.py:694 DEBUG CleanupClients: client 8d8ee02917bc47c7e218520b8672a75d George timeout=1236280679
03/05/09 13:16:14 -1241703536 VenueServer     Venue.py:239 DEBUG Client Timeout Check: 1236280679 vs 1236280574
03/05/09 13:16:14 -1241703536 VenueServer     Venue.py:685 DEBUG CleanupClients: now=1236280574
03/05/09 13:16:14 -1241703536 VenueServer     Venue.py:685 DEBUG CleanupClients: now=1236280574
03/05/09 13:16:29 -1241703536 VenueServer     Venue.py:685 DEBUG CleanupClients: now=1236280589
03/05/09 13:16:29 -1241703536 VenueServer     Venue.py:685 DEBUG CleanupClients: now=1236280589
03/05/09 13:16:29 -1241703536 VenueServer     Venue.py:685 DEBUG CleanupClients: now=1236280589
03/05/09 13:16:29 -1241703536 VenueServer     Venue.py:694 DEBUG CleanupClients: client 8d8ee02917bc48ce6983934775cf6927 Lenny Freidhof timeout=1236280661
03/05/09 13:16:29 -1241703536 VenueServer     Venue.py:239 DEBUG Client Timeout Check: 1236280661 vs 1236280589
03/05/09 13:16:29 -1241703536 VenueServer     Venue.py:694 DEBUG CleanupClients: client 8d8ee02917bc48cbbc7fada9dd651961 conf2-ncsa timeout=1236280675
03/05/09 13:16:29 -1241703536 VenueServer     Venue.py:239 DEBUG Client Timeout Check: 1236280675 vs 1236280589
03/05/09 13:16:29 -1241703536 VenueServer     Venue.py:685 DEBUG CleanupClients: now=1236280589
----- Original Message -----
From: "Thomas D. Uram" <turam at mcs.anl.gov>
To: "George Estes" <gestes at ncsa.uiuc.edu>
Cc: "ag-tech mailing list" <ag-tech at mcs.anl.gov>
Sent: Thursday, March 5, 2009 1:59:02 PM GMT -06:00 US/Canada Central
Subject: Re: [AG-TECH] ssl error when connecting to venue server

I haven't seen this problem.

There should be an associated error in the VenueServer.log. Since you 
control the server, can you check the log to see what information is in 
that log?

I'm running AG3.1 on XP SP3 without issues.

Tom


On 3/5/09 1:46 PM, George Estes wrote:
> ps.  He can telnet to the the venue server but can't connect using the venue client.  He's running Service Pack 3.  Are there any known problems with AG3.1 and Windows XP SP3?
>
>
> ----- Original Message -----
> From: "George Estes" <gestes at ncsa.uiuc.edu>
> To: "ag-tech mailing list" <ag-tech at mcs.anl.gov>
> Sent: Thursday, March 5, 2009 1:06:49 PM GMT -06:00 US/Canada Central
> Subject: [AG-TECH] ssl error when connecting to venue server
>
> Hello,
>
>   I have a user who get an ssl 0 error when trying to connect to our venue server.  We're wondering if perhaps his identity cert has been corrupted.  This is the segment from the client log.  How does the identity cert play into the client connection to the venue server?
>
> 12/11/08 12:49:09 10080 VenueClient     venueclient.py:1320 DEBUG calling __EnterVenue
> 12/11/08 12:49:09 10080 VenueClient     venueclient.py:1021 DEBUG EnterVenue: Invoke Venue.Enter
> 12/11/08 12:49:09 10080 VenueClient     venueclient.py:1345 ERROR EnterVenue: failed
> Traceback (most recent call last):
>   File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\VenueClient.py", line 1321, in EnterVenue
>     self.__EnterVenue(URL,withcert)
>   File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\VenueClient.py", line 1022, in __EnterVenue
>     self.profile.connectionId = self.__venueProxy.Enter( self.profile )
>   File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\interfaces\Venue_client.py", line 38, in Enter
>     self.binding.Send(None, None, request, soapaction="urn:#Enter", **kw)
>   File "C:\Python24\lib\site-packages\ZSI\client.py", line 266, in Send
>     self.h.connect()
>   File "C:\Python24\lib\site-packages\M2Crypto\httpslib.py", line 50, in connect
>     self.sock.connect((self.host, self.port))
>   File "C:\Python24\lib\site-packages\M2Crypto\SSL\Connection.py", line 169, in connect
>     ret = self.connect_ssl()
>   File "C:\Python24\lib\site-packages\M2Crypto\SSL\Connection.py", line 162, in connect_ssl
>     return m2.ssl_connect(self.ssl)
> SSLError: (0, 'Error')
> 12/11/08 12:49:09 10080 VenueClient     venueclientui.py:3182 DEBUG bin.VenueClient::EnterVenue: Enter venue with url: https://msp-ag.ncsa.uiuc.edu:8000/Venues/default
> 12/11/08 12:49:09 10080 VenueClient     venueclientui.py:3198 DEBUG warningString: (0, 'Error')
>
> Thanks,
> George
>
>
>   



More information about the ag-tech mailing list