ubuntuone.SyncDaemon.ActionQueue - WARNING - Connection lost: [('SSL routines', 'SSL23_READ', 'ssl handshake failure')]

[Peter Westergren]

Keeps getting disconnected from Ubuntu One...

In the syncdaemon log the following entries keep comming over and over again...

ubuntuone.SyncDaemon.ActionQueue - WARNING - Connection lost: [('SSL routines', 'SSL23_READ', 'ssl handshake failure')]

[Rafael Kansy (pirat83)]

Can I provide help? A log file or some information?

[Peter Westergren (peter-westergren)]

Ok...heres some portion of the log file. Syncdaemon.log

2011-11-09 11:30:01,845 - ubuntuone.SyncDaemon.ActionQueue - INFO - The request 'protocol_version' failed with the error: [('SSL routines', 'SSL23_READ', 'ssl handshake failure')]
2011-11-09 11:30:01,846 - ubuntuone.SyncDaemon.ActionQueue - WARNING - Connection lost: [('SSL routines', 'SSL23_READ', 'ssl handshake failure')]
2011-11-09 11:30:07,915 - ubuntuone.SyncDaemon.Main - NOTE - ---- MARK (state: <State: 'WAITING' (queues WORKING connection 'With User With Network')>; queue: 155; hash: 0) ----
2011-11-09 11:32:01,852 - ubuntuone.SyncDaemon.ActionQueue - INFO - Connection started to host fs-1.ubuntuone.com, port 443.
2011-11-09 11:32:01,857 - ubuntuone.SyncDaemon.ActionQueue - INFO - Connection made.
2011-11-09 11:32:01,857 - ubuntuone.SyncDaemon.StorageClient - INFO - Connection made.
2011-11-09 11:32:01,898 - ubuntuone.SyncDaemon.StorageClient - INFO - Connection lost, reason: [Failure instance: Traceback (failure with no frames): <class 'OpenSSL.SSL.Error'>: [('SSL routines', 'SSL23_READ', 'ssl handshake failure')]
].
2011-11-09 11:32:01,901 - ubuntuone.SyncDaemon.ActionQueue - INFO - The request 'protocol_version' failed with the error: [('SSL routines', 'SSL23_READ', 'ssl handshake failure')]
2011-11-09 11:32:01,903 - ubuntuone.SyncDaemon.ActionQueue - WARNING - Connection lost: [('SSL routines', 'SSL23_READ', 'ssl handshake failure')]
2011-11-09 11:32:07,914 - ubuntuone.SyncDaemon.Main - NOTE - ---- MARK (state: <State: 'WAITING' (queues WORKING connection 'With User With Network')>; queue: 155; hash: 0) ----
2011-11-09 11:34:01,907 - ubuntuone.SyncDaemon.ActionQueue - INFO - Connection started to host fs-1.ubuntuone.com, port 443.
2011-11-09 11:34:01,909 - ubuntuone.SyncDaemon.ActionQueue - INFO - Connection made.
2011-11-09 11:34:01,910 - ubuntuone.SyncDaemon.StorageClient - INFO - Connection made.
2011-11-09 11:34:01,948 - ubuntuone.SyncDaemon.StorageClient - INFO - Connection lost, reason: [Failure instance: Traceback (failure with no frames): <class 'OpenSSL.SSL.Error'>: [('SSL routines', 'SSL23_READ', 'ssl handshake failure')]
].
2011-11-09 11:34:01,948 - ubuntuone.SyncDaemon.ActionQueue - INFO - The request 'protocol_version' failed with the error: [('SSL routines', 'SSL23_READ', 'ssl handshake failure')]
2011-11-09 11:34:01,951 - ubuntuone.SyncDaemon.ActionQueue - WARNING - Connection lost: [('SSL routines', 'SSL23_READ', 'ssl handshake failure')]
2011-11-09 11:34:07,914 - ubuntuone.SyncDaemon.Main - NOTE - ---- MARK (state: <State: 'WAITING' (queues WORKING connection 'With User With Network')>; queue: 155; hash: 0) ----
2011-11-09 11:36:02,085 - ubuntuone.SyncDaemon.ActionQueue - INFO - Connection started to host fs-2.ubuntuone.com, port 443.
2011-11-09 11:36:02,087 - ubuntuone.SyncDaemon.ActionQueue - INFO - Connection made.
2011-11-09 11:36:02,088 - ubuntuone.SyncDaemon.StorageClient - INFO - Connection made.
2011-11-09 11:36:02,161 - ubuntuone.SyncDaemon.StorageClient - INFO - Connection lost, reason: [Failure instance: Traceback (failure with no frames): <class 'OpenSSL.SSL.Error'>: [('SSL routines', 'SSL23_READ', 'ssl handshake failure')]
].
2011-11-09 11:...

[Rafael Kansy (pirat83)]

==> syncdaemon.log <==
2011-11-09 11:39:15,487 - ubuntuone.SyncDaemon.StorageClient - INFO - Connection lost, reason: [Failure instance: Traceback (failure with no frames): <class 'OpenSSL.SSL.Error'>: [('SSL routines', 'SSL23_READ', 'ssl handshake failure')]
].
2011-11-09 11:39:15,487 - ubuntuone.SyncDaemon.ActionQueue - INFO - The request 'protocol_version' failed with the error: [('SSL routines', 'SSL23_READ', 'ssl handshake failure')]

==> status.log <==
2011-11-09 11:39:15,487 - ubuntuone.status - DEBUG - server connection lost

==> syncdaemon.log <==
2011-11-09 11:39:15,488 - ubuntuone.SyncDaemon.ActionQueue - WARNING - Connection lost: [('SSL routines', 'SSL23_READ', 'ssl handshake failure')]
2011-11-09 11:40:05,360 - ubuntuone.SyncDaemon.Main - NOTE - ---- MARK (state: <State: 'WAITING' (queues WORKING connection 'With User With Network')>; queue: 246; hash: 0) ----
2011-11-09 11:41:15,490 - ubuntuone.SyncDaemon.ActionQueue - INFO - Connection started to host fs-2.ubuntuone.com, port 443.
2011-11-09 11:41:15,514 - ubuntuone.SyncDaemon.ActionQueue - INFO - Connection made.
2011-11-09 11:41:15,514 - ubuntuone.SyncDaemon.StorageClient - INFO - Connection made.

==> status.log <==
2011-11-09 11:41:15,514 - ubuntuone.status - DEBUG - server connection made
2011-11-09 11:41:15,555 - ubuntuone.status - DEBUG - notification shown: <filename> und xxx andere Dateien werden auf Ihren Rechner heruntergeladen.

[Peter Westergren (peter-westergren)]

Could this be a server issue? On https://wiki.ubuntu.com/UbuntuOne/status
everythings seems ok with files syncing. Or its not updated???

[Peter Westergren (peter-westergren)]

An update... On Ubuntu Ones status page there is now
an issue with file sync connection.

Server related after all maybe...

[Natalia Bidart (nataliabidart)]

Hello all!

The ('SSL routines', 'SSL23_READ', 'ssl handshake failure') error means there was a problem when connecting to the servers. Sycndaemon will try to reconnect automatically, so there is nothing to worry about.

Last Friday we had an issue in our servers where syncdaemon was not able to connect for a couple of hours, but that is fixed now.

Closing this bug report as Invalid.

Thanks for helping make Ubuntu One better!

[Rafael Kansy (pirat83)]

This problem occurs again :-(

[Natalia Bidart (nataliabidart)]

Hello Rafael,

The error means that your client can not connect to our servers, perhaps because some heavy load we were experiencing yesterday.
Syncdaemon will keep trying to connect, so there is nothing else to do about this bug.

If you have any other issue, please open a new bug and attach all your Ubuntu One logs.

Thanks!

[Rafael Kansy (pirat83)]

The syncdeamon does not sync down:

My syncdeamon.log shows:
2011-12-08 10:30:06,323 - ubuntuone.SyncDaemon.Main - NOTE - ---- MARK (state: <State: 'QUEUE_MANAGER' (queues WORKING connection 'With User With Network')>; queue: 92; hash: 0) ----

But nothing happens...

[Natalia Bidart (nataliabidart)]

On 12/08/2011 07:41 AM, Rafael Kansy wrote:
> The syncdeamon does not sync down:
>
> My syncdeamon.log shows:
> 2011-12-08 10:30:06,323 - ubuntuone.SyncDaemon.Main - NOTE - ---- MARK (state:<State: 'QUEUE_MANAGER' (queues WORKING connection 'With User With Network')>; queue: 92; hash: 0) ----
>
> But nothing happens...
Hello,

from that log line I can say syncdaemon is working, may be a bit slow
but is indeed working.
Anyways, like I mentioned before, please open a new bug attaching your
logs if you have another issue.

Thanks, Natalia.

[GreyGeek (greygeek77)]

I installed my UbuntuOne account on my new Acer AS7739 yesterday and it immediately began populating my local UbuntuOne directory with files from the cloud. Today I boot my computer and a few moments after the desktop is up and running I began getting a repeating msg that the same three files are being uploaded to the cloud. I open the client and click the "Connect" button. A "Files are syncing ..." msg appears for a few moments and then it spontaneously disconnects. The logs show:

2012-01-09 09:40:50,853 - ubuntuone.SyncDaemon.ActionQueue - DEBUG - Using record: <RR name=_https._tcp.fs.ubuntuone.com type=SRV class=IN ttl=99s auth=False>
2012-01-09 09:40:50,855 - ubuntuone.SyncDaemon.ActionQueue - INFO - Connection started to host fs-1.ubuntuone.com, port 443.
2012-01-09 09:40:51,139 - ubuntuone.SyncDaemon.ActionQueue - INFO - Connection made.
2012-01-09 09:40:51,141 - ubuntuone.SyncDaemon.StorageClient - INFO - Connection made.
2012-01-09 09:40:51,141 - ubuntuone.SyncDaemon.EQ - DEBUG - push_event: SYS_CONNECTION_MADE, kwargs: {}
2012-01-09 09:40:51,141 - ubuntuone.SyncDaemon.StateManager - DEBUG - received event 'SYS_CONNECTION_MADE'
2012-01-09 09:40:51,142 - ubuntuone.SyncDaemon.StateManager - DEBUG - Transition READY --[SYS_CONNECTION_MADE]--> CHECK_VERSION (queues: IDLE; connection: With User With Network)
2012-01-09 09:40:51,143 - ubuntuone.SyncDaemon.ConnectionManager - DEBUG - Setting up the 'handshake' timer on 50 secs
2012-01-09 09:40:51,143 - ubuntuone.SyncDaemon.EQ - DEBUG - push_event: SYS_STATE_CHANGED, kwargs: {'state': CHECK_VERSION (error=False connected=True online=False) Queue: IDLE Connection: With User With Network}
2012-01-09 09:40:51,190 - ubuntuone.SyncDaemon.InteractionInterfaces - DEBUG - handle_SYS_STATE_CHANGED: args (<ubuntuone.syncdaemon.interaction_interfaces.SyncdaemonEventListener object at 0x1cf7ad0>,), kwargs {'state': CHECK_VERSION (error=False connected=True online=False) Queue: IDLE Connection: With User With Network}.
2012-01-09 09:40:51,190 - ubuntuone.SyncDaemon.InteractionInterfaces - DEBUG - handle_SYS_STATE_CHANGED: result None.
2012-01-09 09:40:54,262 - ubuntuone.SyncDaemon.StorageClient - INFO - Connection lost, reason: [Failure instance: Traceback: <class 'OpenSSL.SSL.Error'>: [('SSL routines', 'SSL23_READ', 'ssl handshake failure')]
/usr/lib/python2.7/dist-packages/twisted/internet/tcp.py:257:readConnectionLost
/usr/lib/python2.7/dist-packages/twisted/internet/tcp.py:433:connectionLost
/usr/lib/python2.7/dist-packages/twisted/internet/tcp.py:277:connectionLost
/usr/lib/python2.7/dist-packages/twisted/protocols/tls.py:456:connectionLost
--- <exception caught here> ---
/usr/lib/python2.7/dist-packages/twisted/protocols/tls.py:352:_flushReceiveBIO
].
2012-01-09 09:40:54,263 - ubuntuone.SyncDaemon.ActionQueue - INFO - The request 'protocol_version' failed with the error: [('SSL routines', 'SSL23_READ', 'ssl handshake failure')]
2012-01-09 09:40:54,263 - ubuntuone.SyncDaemon.EQ - DEBUG - push_event: SYS_CONNECTION_LOST, kwargs: {}
2012-01-09 09:40:54,263 - ubuntuone.SyncDaemon.StateManager - DEBUG - received event 'SYS_CONNECTION_LOST'
2012-01-09 09:40:54,263 - ubuntuone.SyncDaemon.State...

[Bob M Brown (gurubob)]

Same issue here. Noticed it because the notification message was complaining about a file in a directory for the last few days so I tarred it all up but still get the message regarding the new file.

I have rebooted between and my machine has been offline for the weekend (work PC).

I went digging in the logs and found this relevant line:

ubuntuone.SyncDaemon.StorageClient - INFO - Connection lost, reason: [Failure instance: Traceback (failure with no frames): <class 'OpenSSL.SSL.Error'>: [('SSL routines', 'SSL23_READ', 'ssl handshake failure')]

Hmm ... after poking and prodding it things have come right - but all I've done is look at the log file and use the Ubuntu One GUI to stop and start the system. I wonder if this is server load related. Here is something odd from the log file that may help with diagnosis of future instances of this:

2012-02-27 09:53:48,258 - ubuntuone.SyncDaemon.ActionQueue - INFO - Connection started to host fs-1.ubuntuone.com, port 443.
2012-02-27 09:53:48,793 - ubuntuone.SyncDaemon.ActionQueue - INFO - Connection made.
2012-02-27 09:53:48,793 - ubuntuone.SyncDaemon.StorageClient - INFO - Connection made.
2012-02-27 09:53:49,754 - ubuntuone.SyncDaemon.ActionQueue - INFO - The request 'protocol_version' finished OK.
2012-02-27 09:53:50,065 - ubuntuone.SyncDaemon.ActionQueue - INFO - The request 'caps_raising_if_not_accepted' finished OK.
2012-02-27 09:53:50,375 - ubuntuone.SyncDaemon.ActionQueue - INFO - The request 'caps_raising_if_not_accepted' finished OK.
2012-02-27 09:53:50,697 - ubuntuone.SyncDaemon.ActionQueue - INFO - The request 'oauth_authenticate' finished OK.
2012-02-27 09:53:50,697 - ubuntuone.SyncDaemon.ActionQueue - NOTE - Session ID: 'ab291d74-904b-4acd-a136-0543033ce6c6'
2012-02-27 09:54:52,184 - ubuntuone.SyncDaemon.Main - NOTE - ---- MARK (state: <State: 'SERVER_RESCAN' (queues WORKING connection 'With User With Network')>; queue: 91; hash: 0) ----
2012-02-27 09:55:51,916 - twisted - ERROR - Unhandled Error
Traceback (most recent call last):
  File "/usr/lib/python2.7/dist-packages/twisted/python/log.py", line 84, in callWithLogger
    return callWithContext({"system": lp}, func, *args, **kw)
  File "/usr/lib/python2.7/dist-packages/twisted/python/log.py", line 69, in callWithContext
    return context.call({ILogContext: newCtx}, func, *args, **kw)
  File "/usr/lib/python2.7/dist-packages/twisted/python/context.py", line 118, in callWithContext
    return self.currentContext().callWithContext(ctx, func, *args, **kw)
  File "/usr/lib/python2.7/dist-packages/twisted/python/context.py", line 81, in callWithContext
    return func(*args,**kw)
--- <exception caught here> ---
  File "/usr/lib/python2.7/dist-packages/twisted/internet/gtk2reactor.py", line 288, in _doReadOrWrite
    why = source.doRead()
  File "/usr/lib/python2.7/dist-packages/twisted/internet/tcp.py", line 137, in doRead
    return Connection.doRead(self)
  File "/usr/lib/python2.7/dist-packages/twisted/internet/tcp.py", line 460, in doRead
    rval = self.protocol.dataReceived(data)
  File "/usr/lib/python2.7/dist-packages/ubuntuone-storage-protocol/ubuntuone/storageprotocol/client.py", line 1601, in dataReceived
    StorageClient.d...