ServerException: ('exceptions.IOError', (2, 'No such file or directory'))

[zopyx]

I have seen sporadic errors of this kind with Plone 5.1 with ZODB 5.3.0. However I could not find a way to reproduce the error or to find the related filename causing the issue.

2018-02-21 08:52:15 ERROR Zope.SiteErrorLog 1519199535.80.252769638019 http://dynamore.zopyx.de:5080/dynamore-se/en/resources/POST_application_json_
Traceback (innermost last):
  Module ZPublisher.Publish, line 146, in publish
  Module Zope2.App.startup, line 303, in commit
  Module transaction._manager, line 131, in commit
  Module transaction._transaction, line 310, in commit
  Module transaction._transaction, line 301, in commit
  Module transaction._transaction, line 446, in _commitResources
  Module transaction._transaction, line 423, in _commitResources
  Module ZODB.Connection, line 693, in tpc_vote
  Module ZEO.ClientStorage, line 750, in tpc_vote
  Module ZEO.asyncio.client, line 764, in call
  Module ZEO.asyncio.client, line 743, in call
  Module ZEO.asyncio.client, line 756, in wait_for_result
  Module concurrent.futures._base, line 422, in result
  Module concurrent.futures._base, line 381, in __get_result
ServerException: ('exceptions.IOError', (2, 'No such file or directory'))
^C2018-02-21 08:53:17 INFO SignalHandler Caught signal SIGINT
2018-02-21 08:53:17 INFO Z2 Shutting down

[mgedmin]

Can you find the corresponding error from the ZEO server logs?

[zopyx]

Nothing special in the zeo.log

2018-02-21T09:23:38 daemonizing the process
2018-02-21T09:23:38 set current directory: '/home/plone/sandboxes/dynamore.buildout.plone5/parts/zeo'
2018-02-21T09:23:38 daemon manager started
2018-02-21T09:23:38 spawned process pid=29481
2018-02-21T09:23:39 (29481) created PID file '/home/plone/sandboxes/dynamore.buildout.plone5/var/zeo.pid'
2018-02-21T09:23:39 (29481) opening storage '1' using FileStorage
2018-02-21T09:23:39 (29481) Blob directory /home/plone/sandboxes/dynamore.buildout.plone5/var/blobstorage does not exist. Selected `bushy` layout. 
2018-02-21T09:23:39 (29481) Blob directory '/home/plone/sandboxes/dynamore.buildout.plone5/var/blobstorage/' does not exist. Created new directory.
2018-02-21T09:23:39 (29481) Blob temporary directory '/home/plone/sandboxes/dynamore.buildout.plone5/var/blobstorage/tmp' does not exist. Created new directory.
2018-02-21T09:23:39 StorageServer created RW with storages: 1:RW:/home/plone/sandboxes/dynamore.buildout.plone5/var/filestorage/Data.fs
2018-02-21T09:23:39 listening on ('', 5200)
2018-02-21T09:23:48 Connected server protocol
2018-02-21T09:23:48 received handshake 'Z5'
2018-02-21T09:23:56 (:5200) disconnected
2018-02-21T09:24:02 Connected server protocol
2018-02-21T09:24:02 received handshake 'Z5'
2018-02-21T12:01:13 Connected server protocol
2018-02-21T12:01:13 received handshake 'Z5'
2018-02-21T12:01:14 (:5200) pack(time=1519124474.821143) started...
2018-02-21T12:01:21 (:5200) pack(time=1519124474.821143) complete
2018-02-21T12:01:21 (:5200) disconnected
2018-02-21T12:01:45 (:5200) pack(time=1519210905.615539) started...
2018-02-21T12:02:04 (:5200) pack(time=1519210905.615539) complete

[mgedmin]

I'm assuming both Zope and ZEO servers use the same timezone. The error happened at 2018-02-21 08:53:17. Your ZEO log shows the ZEO server started up 30 minutes later, at 2018-02-21T09:23:38.

Do you not have an older ZEO log, one that has entries for the right timestamps?

Does restarting your ZEO server truncate the log instead of appending to it? You may want to check your logging configuration.