Submitted by : simon at: 2007-06-03T12:58:08-07:00 (10 years ago)
Name :
Category : Severity : Status :
Optional subject :  
Optional comment :

I've seen this a number of times recently when saving something:

   * Module ZPublisher.Publish, line 202, in publish_module_standard
   * Module ZPublisher.Publish, line 150, in publish
   * Module Zope2.App.startup, line 221, in zpublisher_exception_hook
   * Module ZPublisher.Publish, line 125, in publish
   * Module Zope2.App.startup, line 238, in commit
   * Module transaction._manager, line 96, in commit
   * Module transaction._transaction, line 395, in commit
   * Module transaction._transaction, line 503, in _commitResources
   * Module ZODB.Connection, line 696, in tpc_finish
   * Module ZEO.ClientStorage, line 955, in tpc_finish
   * Module ZEO.ClientStorage, line 980, in _update_cache
   * Module ZEO.cache, line 375, in invalidate
AssertionError

Despite the error, the operation seems to succeed. Repeating the post gives the same error, until you restart the zeo client and/or server. But it comes back. I don't have a permanent zeo cache. I haven't reproduced this error when not using zeo. I'd like to be able to leave zeo turned on.

... --Frank Laurijssens, Sun, 03 Jun 2007 13:47:36 -0700 reply

Saw this as well :( #1358 and #1359 are duplicates because of this.

... --Frank Laurijssens, Sun, 03 Jun 2007 13:49:43 -0700 reply

No they're not. The second submission mailed out under the number of #1358 ?!

more details --simon, Mon, 04 Jun 2007 09:48:37 -0700 reply

Here it is again, with another error preceding (but it also happens by itself):

2007-06-04T09:31:08 BLATHER ZWiki indexing /zwikib/1345SwitchToUnicodeInternally
------
2007-06-04T09:31:10 ERROR ZODB.Connection Couldn't load state for 0x07226a
Traceback (most recent call last):
  File "/zope-2.10.3/lib/python/ZODB/Connection.py", line 749, in setstate
    self._setstate(obj)
  File "/zope-2.10.3/lib/python/ZODB/Connection.py", line 789, in _setstate
    p, serial = self._storage.load(obj._p_oid, self._version)
  File "/zope-2.10.3/lib/python/ZODB/Connection.py", line 1146, in load
    return self._storage.load(oid, self._base_version)
  File "/zope-2.10.3/lib/python/ZEO/ClientStorage.py", line 727, in load
    return self.loadEx(oid, version)[:2]
  File "/zope-2.10.3/lib/python/ZEO/ClientStorage.py", line 755, in loadEx
    self._cache.store(oid, ver, tid, None, data)
  File "/zope-2.10.3/lib/python/ZEO/cache.py", line 279, in store
    raise ValueError(
ValueError: already have current data for oid
------
2007-06-04T09:31:10 BLATHER ZWiki failed to index 1345SwitchToUnicodeInternally
 Traceback (most recent call last):
  File "/zope2/Products/ZWiki/Catalog.py", line 107, in index_object
    self.catalog().catalog_object(self,self.url(),idxs)
  File "/zope-2.10.3/lib/python/Products/ZCatalog/ZCatalog.py", line 535, in catalog_object
    update_metadata=update_metadata)
  File "/zope-2.10.3/lib/python/Products/ZCatalog/Catalog.py", line 360, in catalogObject
    blah = x.index_object(index, object, threshold)
  File "/zope-2.10.3/lib/python/Products/PluginIndexes/TextIndex/TextIndex.py", line 330, in index_object
    currentWids=IISet(self._unindex.get(documentId, []))
  File "/zope-2.10.3/lib/python/ZODB/Connection.py", line 749, in setstate
    self._setstate(obj)
  File "/zope-2.10.3/lib/python/ZODB/Connection.py", line 789, in _setstate
    p, serial = self._storage.load(obj._p_oid, self._version)
  File "/zope-2.10.3/lib/python/ZODB/Connection.py", line 1146, in load
    return self._storage.load(oid, self._base_version)
  File "/zope-2.10.3/lib/python/ZEO/ClientStorage.py", line 727, in load
    return self.loadEx(oid, version)[:2]
  File "/zope-2.10.3/lib/python/ZEO/ClientStorage.py", line 755, in loadEx
    self._cache.store(oid, ver, tid, None, data)
  File "/zope-2.10.3/lib/python/ZEO/cache.py", line 279, in store
    raise ValueError(
ValueError: already have current data for oid

------
2007-06-04T09:31:11 CRITICAL txn.65540 A storage error occurred during the second phase of the two-phase commit.  Resources may be in an inconsistent state.
------
2007-06-04T09:31:11 ERROR Zope.SiteErrorLog http://zwiki.org/1345SwitchToUnicodeInternally/PUT
Traceback (innermost last):
  Module ZPublisher.Publish, line 125, in publish
  Module Zope2.App.startup, line 238, in commit
  Module transaction._manager, line 96, in commit
  Module transaction._transaction, line 395, in commit
  Module transaction._transaction, line 503, in _commitResources
  Module ZODB.Connection, line 696, in tpc_finish
  Module ZEO.ClientStorage, line 955, in tpc_finish
  Module ZEO.ClientStorage, line 980, in _update_cache
  Module ZEO.cache, line 375, in invalidate
AssertionError
------

update --simon, Mon, 04 Jun 2007 12:47:05 -0700 reply

I think the first traceback above is all that matters. This needs to be brought up on the ZODB list next. For today I have turned off ZEO again and so far all is calm.

http://www.zope.org/Collectors/Zope/1975 --chrisw, Sun, 24 Jun 2007 13:18:31 -0700 reply

See https://bugs.launchpad.net/zope2/+bug/143594 for occurences of this, would be nice to make some progress...

closing --simon, Sun, 27 Apr 2008 12:01:10 -0700 reply

Status: open => closed

Seems to have gone away, I have been running with ZEO for some time and have not seen it.