Submitted by : simon at: 2004-10-08T09:08:33+00:00 (13 years ago)
Name :
Category : Severity : Status :
Optional subject :  
Optional comment :

If you came here from 0.37 release notes: I meant #983

It's happening about once a day now. After growing too large and being restarted by my monitor script, zope sometimes fails to come up properly. All processes are there, not busy, gdb shows they are in suspend state. You can connect to port 8080 with telnet or xml-rpc but curl or a web browser will never complete. Nothing shows up in the logs; the PTS startup messages are the last thing it prints. I found nothing relevant in the zope collector and I'm presently out of ideas - help welcome!

property change --simon, Fri, 08 Oct 2004 09:09:42 -0700 reply

Name: '#938 zwiki/zopewiki.org zope is hanging on restart' => '#938 zwiki/zopewiki.org zope is hanging, help needed'

not help, a question --Sat, 09 Oct 2004 18:16:46 -0700 reply

property change --simon, Mon, 25 Oct 2004 14:46:00 -0700 reply

Name: '#938 zwiki/zopewiki.org zope is hanging, help needed' => '#938 zwiki/zopewiki.org server sometimes fails to restart causing periodic hangs'

ho hum --Simon Michael, Thu, 28 Oct 2004 09:46:48 -0700 reply

wake, check server, restart until it comes up... ho hum.

At least it's becoming easy to reproduce. I need to sit down and restart this thing until it happens with full logging turned on, or try to find some problem product I can remove. I'll make this first zwiki priority, and will do as soon as I can.

data --simon, Fri, 29 Oct 2004 08:33:39 -0700 reply

Here's the data from last night's startup hang. It shows that all products had been loaded, so it's not as simple as a hanging product. Perhaps we can deduce something from the event.log and zeo.log below ? Also ctrl-c or a simple kill no longer stops runzope.when this happens.

Product initialisation

Start runzope repeatedly, logging I added shows all products initialised ok:

# while true; do runzope; done
/zope1/Products/COREBlog/COREBlog.py:37: DeprecationWarning: the rotor module uses an insecure algorithm and is deprecated
  from rotor import newrotor
/usr/lib/python2.3/FCNTL.py:7: DeprecationWarning: the FCNTL module is deprecated; please use fcntl
  DeprecationWarning)
starting product initialisation PluginIndexes
no product initialisation PluginIndexes
starting product initialisation BTreeFolder2
no product initialisation BTreeFolder2
starting product initialisation CMFActionIcons
no product initialisation CMFActionIcons
starting product initialisation CMFCalendar
no product initialisation CMFCalendar
starting product initialisation CMFCore
no product initialisation CMFCore
starting product initialisation CMFDefault
no product initialisation CMFDefault
starting product initialisation CMFDeployment
no product initialisation CMFDeployment
starting product initialisation CMFExternalFile
no product initialisation CMFExternalFile
starting product initialisation CMFFormController
no product initialisation CMFFormController
starting product initialisation CMFFormControllerPatch
no product initialisation CMFFormControllerPatch
starting product initialisation CMFPlone
no product initialisation CMFPlone
starting product initialisation CMFQuickInstallerTool
no product initialisation CMFQuickInstallerTool
starting product initialisation CMFTopic
no product initialisation CMFTopic
starting product initialisation COREBlog
no product initialisation COREBlog
starting product initialisation DCWorkflow
no product initialisation DCWorkflow
starting product initialisation DocFinder
no product initialisation DocFinder
starting product initialisation Epoz
no product initialisation Epoz
starting product initialisation ExtFile
no product initialisation ExtFile
starting product initialisation ExternalEditor
no product initialisation ExternalEditor
starting product initialisation ExternalFile
no product initialisation ExternalFile
starting product initialisation ExternalMethod
no product initialisation ExternalMethod
starting product initialisation FSPoll
no product initialisation FSPoll
Traceback (most recent call last):
  File "/zope1/Products/FSPoll/__init__.py", line 5, in initialize
    context.registerClass(FSPoll.FSPoll, constructors = (FSPoll.manage_addFSPollForm,FSPoll.manage_addFSPoll), icon='pollfolder.gif')
  File "/usr/local/src/Zope-2.7.2-0/lib/python/App/ProductContext.py", line 202, in registerClass
    icon=Globals.ImageFile(icon, self.__pack.__dict__)
  File "/usr/local/src/Zope-2.7.2-0/lib/python/App/ImageFile.py", line 46, in __init__
    file=open(path, 'rb')
IOError: [Errno 2] No such file or directory: '/zope1/Products/FSPoll/pollfolder.gif'
starting product initialisation FileSystemSite
no product initialisation FileSystemSite
starting product initialisation Formulator
no product initialisation Formulator
starting product initialisation GroupUserFolder
no product initialisation GroupUserFolder
starting product initialisation LatexWiki
no product initialisation LatexWiki
starting product initialisation LocalFS
no product initialisation LocalFS
starting product initialisation MIMETools
no product initialisation MIMETools
starting product initialisation MailHost
no product initialisation MailHost
starting product initialisation MaildropHost
no product initialisation MaildropHost
starting product initialisation OFSP
no product initialisation OFSP
starting product initialisation PMPSurvey
no product initialisation PMPSurvey
starting product initialisation PageTemplates
no product initialisation PageTemplates
starting product initialisation PlacelessTranslationService
no product initialisation PlacelessTranslationService
------
2004-10-28T23:15:20 PROBLEM(100) PlacelessTranslationService Message Catalog has errors
zwiki-fi.po
Traceback (most recent call last):
  File "/zope1/Products/PlacelessTranslationService/PlacelessTranslationService.py", line 271, in _load_catalog_file
    self.addCatalog(GettextMessageCatalog(id, pofile, language, domain))
  File "/zope1/Products/PlacelessTranslationService/GettextMessageCatalog.py", line 203, in __init__
    self._prepareTranslations(0)
  File "/zope1/Products/PlacelessTranslationService/GettextMessageCatalog.py", line 209, in _prepareTranslations
    try: self._doPrepareTranslations()
  File "/zope1/Products/PlacelessTranslationService/GettextMessageCatalog.py", line 232, in _doPrepareTranslations
    raise ValueError, 'potfile has no metadata, PTS needs a language and a message domain!'
ValueError: potfile has no metadata, PTS needs a language and a message domain!
------
2004-10-28T23:15:21 PROBLEM(100) PlacelessTranslationService Message Catalog has errors
zwiki-plone-fi.po
Traceback (most recent call last):
  File "/zope1/Products/PlacelessTranslationService/PlacelessTranslationService.py", line 271, in _load_catalog_file
    self.addCatalog(GettextMessageCatalog(id, pofile, language, domain))
  File "/zope1/Products/PlacelessTranslationService/GettextMessageCatalog.py", line 203, in __init__
    self._prepareTranslations(0)
  File "/zope1/Products/PlacelessTranslationService/GettextMessageCatalog.py", line 209, in _prepareTranslations
    try: self._doPrepareTranslations()
  File "/zope1/Products/PlacelessTranslationService/GettextMessageCatalog.py", line 232, in _doPrepareTranslations
    raise ValueError, 'potfile has no metadata, PTS needs a language and a message domain!'
ValueError: potfile has no metadata, PTS needs a language and a message domain!
starting product initialisation PortalTransforms
no product initialisation PortalTransforms
starting product initialisation PythonScripts
no product initialisation PythonScripts
starting product initialisation Sessions
no product initialisation Sessions
starting product initialisation SiteAccess
no product initialisation SiteAccess
starting product initialisation SiteErrorLog
no product initialisation SiteErrorLog
starting product initialisation SkinnedFolder
no product initialisation SkinnedFolder
starting product initialisation StandardCacheManagers
no product initialisation StandardCacheManagers
starting product initialisation TemporaryFolder
no product initialisation TemporaryFolder
starting product initialisation TextIndexNG2
no product initialisation TextIndexNG2
Traceback (most recent call last):
  File "/zope1/Products/TextIndexNG2/logging1.py", line 1716, in fileConfig
    h = apply(klass, args)
  File "/zope1/Products/TextIndexNG2/logging1.py", line 645, in __init__
    StreamHandler.__init__(self, open(filename, mode))
IOError: [Errno 13] Permission denied: 'textindexng.log'
starting product initialisation Transience
no product initialisation Transience
starting product initialisation ZCTextIndex
no product initialisation ZCTextIndex
starting product initialisation ZCatalog
no product initialisation ZCatalog
starting product initialisation ZGadflyDA
no product initialisation ZGadflyDA
starting product initialisation ZODBMountPoint
no product initialisation ZODBMountPoint
starting product initialisation ZReST
no product initialisation ZReST
starting product initialisation ZSQLMethods
no product initialisation ZSQLMethods
starting product initialisation ZSyncer
no product initialisation ZSyncer
starting product initialisation ZWiki
no product initialisation ZWiki
starting product initialisation ZopeProfiler
no product initialisation ZopeProfiler
starting product initialisation ZopeTutorial
no product initialisation ZopeTutorial
starting product initialisation Zpydoc
no product initialisation Zpydoc
starting product initialisation exUserFolder
no product initialisation exUserFolder
starting product initialisation generator
no product initialisation generator
starting product initialisation mxmCounter
no product initialisation mxmCounter
starting product initialisation transform
no product initialisation transform
starting product initialisation validation
no product initialisation validation

Zope responding

Check zope every minute until it stops responding:

# while true; do curl -s -o/dev/null http://localhost:8080/; date; sleep 60; done
...
Thu Oct 28 23:11:45 PDT 2004
Thu Oct 28 23:12:45 PDT 2004
Thu Oct 28 23:13:45 PDT 2004
Thu Oct 28 23:14:45 PDT 2004

checkzopemem

The checkzopemem cron script's log shows it killed off runzope:

# tail -f /zope1/log/checkzopemem.log
...
2004/10/28 23:14:02 PDT /zope1 process size: 106480
2004/10/28 23:15:01 PDT /zope1 process size: 152188
2004/10/28 23:15:01 PDT /zope1 has exceeded 110000 limit, restarting
2004/10/28 23:16:01 PDT /zope1 process size: 58100
2004/10/28 23:17:01 PDT /zope1 process size: 58100

event log

(ALL) leading up to the hang:

# tail -f /zope1/log/event.log
...
2004-10-28T23:15:28 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:28 TRACE(-300) zrpc:16513 message_output 26998 bytes: "(I530\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:28 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 531, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15B')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15B')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 1366 bytes: "(I531\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 532, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15B')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15B')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 1366 bytes: "(I532\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 533, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15B')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15B')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 1366 bytes: "(I533\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 534, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15-')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15-')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 27091 bytes: "(I534\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 535, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15-')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15-')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 27091 bytes: "(I535\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 536, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15-')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15-')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 27091 bytes: "(I536\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 537, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15C')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15C')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 1450 bytes: "(I537\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 538, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15C')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15C')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 1450 bytes: "(I538\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 539, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15C')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15C')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 1450 bytes: "(I539\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 540, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15.')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15.')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 21150 bytes: "(I540\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 541, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15.')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15.')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 21150 bytes: "(I541\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 542, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15.')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15.')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 21150 bytes: "(I542\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 543, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15D')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15D')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 1130 bytes: "(I543\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 544, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15D')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15D')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 1130 bytes: "(I544\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 545, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15D')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15D')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 1130 bytes: "(I545\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 546, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15/')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15/')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 25752 bytes: "(I546\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 547, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15/')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15/')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 25752 bytes: "(I547\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 548, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15/')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15/')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 25752 bytes: "(I548\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 549, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15E')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15E')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 1386 bytes: "(I549\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 550, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15E')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15E')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 1386 bytes: "(I550\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 551, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15E')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15E')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 1386 bytes: "(I551\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 552, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x150')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x150')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 24868 bytes: "(I552\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 553, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x150')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x150')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 24868 bytes: "(I553\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 554, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x150')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x150')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 24868 bytes: "(I554\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 555, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15F')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15F')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 1472 bytes: "(I555\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 556, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15F')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15F')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 1472 bytes: "(I556\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 557, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15F')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15F')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 1472 bytes: "(I557\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 558, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x151')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x151')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 27581 bytes: "(I558\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 559, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x151')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x151')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 27581 bytes: "(I559\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 560, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x151')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x151')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 27581 bytes: "(I560\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 561, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15G')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15G')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 1447 bytes: "(I561\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 562, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15G')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15G')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 1447 bytes: "(I562\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 563, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15G')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15G')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 1447 bytes: "(I563\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 564, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x152')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x152')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 25959 bytes: "(I564\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 565, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x152')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x152')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 25959 bytes: "(I565\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 566, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x152')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x152')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 25959 bytes: "(I566\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 567, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15H')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15H')
------
2004-10-28T23:15:29 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc:16513 message_output 1505 bytes: '(I567\nI0\nS\'.reply\'\n(S"((U\\x0eBTrees.IOBTreeq\\x01...' hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 568, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15H')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15H')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc:16513 message_output 1505 bytes: '(I568\nI0\nS\'.reply\'\n(S"((U\\x0eBTrees.IOBTreeq\\x01...' hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 569, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15H')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15H')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc:16513 message_output 1505 bytes: '(I569\nI0\nS\'.reply\'\n(S"((U\\x0eBTrees.IOBTreeq\\x01...' hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 570, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x153')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x153')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc:16513 message_output 22080 bytes: "(I570\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 571, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x153')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x153')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc:16513 message_output 22080 bytes: "(I571\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 572, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x153')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x153')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc:16513 message_output 22080 bytes: "(I572\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 573, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15I')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15I')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc:16513 message_output 1222 bytes: "(I573\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 574, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15I')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15I')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc:16513 message_output 1222 bytes: "(I574\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 575, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15I')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15I')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc:16513 message_output 1222 bytes: "(I575\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 576, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x154')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x154')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc:16513 message_output 23797 bytes: "(I576\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 577, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x154')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x154')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc:16513 message_output 23797 bytes: "(I577\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 578, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x154')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x154')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc:16513 message_output 23797 bytes: "(I578\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 579, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15J')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15J')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc:16513 message_output 1133 bytes: "(I579\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 580, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15J')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15J')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc:16513 message_output 1133 bytes: "(I580\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 581, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x15J')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x15J')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc:16513 message_output 1133 bytes: "(I581\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 582, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x80\xd3')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x80\xd3')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc:16513 message_output 43782 bytes: "(I582\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 583, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x80\xd3')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x80\xd3')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc:16513 message_output 43782 bytes: "(I583\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 584, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x80\xd3')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x80\xd3')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc:16513 message_output 43782 bytes: "(I584\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 585, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x80\xdd')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x80\xdd')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc:16513 message_output 1767 bytes: "(I585\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 586, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x80\xdd')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x80\xdd')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc:16513 message_output 1767 bytes: "(I586\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 587, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\x80\xdd')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\x80\xdd')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc:16513 message_output 1767 bytes: "(I587\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 588, 0, zeoLoad, ('\x00\x00\x00\x00\x00R\x1aX')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00R\x1aX')
------
2004-10-28T23:15:30 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 45983 bytes: "(I588\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 589, 0, zeoLoad, ('\x00\x00\x00\x00\x00R\x1aX')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00R\x1aX')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 45983 bytes: "(I589\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 590, 0, zeoLoad, ('\x00\x00\x00\x00\x00R\x1aX')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00R\x1aX')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 45983 bytes: "(I590\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 591, 0, zeoLoad, ('\x00\x00\x00\x00\x00R\x1aY')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00R\x1aY')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 1886 bytes: "(I591\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 592, 0, zeoLoad, ('\x00\x00\x00\x00\x00R\x1aY')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00R\x1aY')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 1886 bytes: "(I592\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 593, 0, zeoLoad, ('\x00\x00\x00\x00\x00R\x1aY')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00R\x1aY')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 1886 bytes: "(I593\nI0\nS'.reply'\n(S'((U\\x0eBTrees.IOBTreeq\\x01U\..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 594, 0, zeoLoad, ('\x00\x00\x00\x00\x00<V*')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00<V*')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 4514 bytes: "(I594\nI0\nS'.reply'\n(S'((U\\x18Products.ZWiki.ZWikiPa..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 595, 0, zeoLoad, ('\x00\x00\x00\x00\x00<V*')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00<V*')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 4514 bytes: "(I595\nI0\nS'.reply'\n(S'((U\\x18Products.ZWiki.ZWikiPa..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 596, 0, zeoLoad, ('\x00\x00\x00\x00\x00<V*')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00<V*')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 4514 bytes: "(I596\nI0\nS'.reply'\n(S'((U\\x18Products.ZWiki.ZWikiPa..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 597, 0, zeoLoad, ('\x00\x00\x00\x00\x00<X^')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00<X^')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 3052 bytes: "(I597\nI0\nS'.reply'\n(S'((U\\x18Products.ZWiki.ZWikiPa..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 598, 0, zeoLoad, ('\x00\x00\x00\x00\x00<X^')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00<X^')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 3052 bytes: "(I598\nI0\nS'.reply'\n(S'((U\\x18Products.ZWiki.ZWikiPa..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 599, 0, zeoLoad, ('\x00\x00\x00\x00\x00<X^')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00<X^')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 3052 bytes: "(I599\nI0\nS'.reply'\n(S'((U\\x18Products.ZWiki.ZWikiPa..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 600, 0, zeoLoad, ('\x00\x00\x00\x00\x00<O\xad')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00<O\xad')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 7024 bytes: "(I600\nI0\nS'.reply'\n(S'((U\\x18Products.ZWiki.ZWikiPa..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 601, 0, zeoLoad, ('\x00\x00\x00\x00\x00<O\xad')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00<O\xad')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 7024 bytes: "(I601\nI0\nS'.reply'\n(S'((U\\x18Products.ZWiki.ZWikiPa..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 602, 0, zeoLoad, ('\x00\x00\x00\x00\x00<O\xad')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00<O\xad')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 7024 bytes: "(I602\nI0\nS'.reply'\n(S'((U\\x18Products.ZWiki.ZWikiPa..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 603, 0, zeoLoad, ('\x00\x00\x00\x00\x00<Nr')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00<Nr')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 2135 bytes: "(I603\nI0\nS'.reply'\n(S'((U\\x18Products.ZWiki.ZWikiPa..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 604, 0, zeoLoad, ('\x00\x00\x00\x00\x00<Nr')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00<Nr')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 2135 bytes: "(I604\nI0\nS'.reply'\n(S'((U\\x18Products.ZWiki.ZWikiPa..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 605, 0, zeoLoad, ('\x00\x00\x00\x00\x00<Nr')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00<Nr')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 2135 bytes: "(I605\nI0\nS'.reply'\n(S'((U\\x18Products.ZWiki.ZWikiPa..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 606, 0, zeoLoad, ('\x00\x00\x00\x00\x00<O\xb5')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00<O\xb5')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 1512 bytes: "(I606\nI0\nS'.reply'\n(S'((U\\x18Products.ZWiki.ZWikiPa..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 607, 0, zeoLoad, ('\x00\x00\x00\x00\x00<O\xb5')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00<O\xb5')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 1512 bytes: "(I607\nI0\nS'.reply'\n(S'((U\\x18Products.ZWiki.ZWikiPa..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 608, 0, zeoLoad, ('\x00\x00\x00\x00\x00<O\xb5')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00<O\xb5')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 1512 bytes: "(I608\nI0\nS'.reply'\n(S'((U\\x18Products.ZWiki.ZWikiPa..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 609, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\xbc\x11')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\xbc\x11')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 2189 bytes: "(I609\nI0\nS'.reply'\n(S'((U\\x18Products.ZWiki.ZWikiPa..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 610, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\xbc\x11')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\xbc\x11')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 2189 bytes: "(I610\nI0\nS'.reply'\n(S'((U\\x18Products.ZWiki.ZWikiPa..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 611, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\xbc\x11')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\xbc\x11')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 2189 bytes: "(I611\nI0\nS'.reply'\n(S'((U\\x18Products.ZWiki.ZWikiPa..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 612, 0, zeoLoad, ('\x00\x00\x00\x00\x00<X\x10')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00<X\x10')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 2486 bytes: "(I612\nI0\nS'.reply'\n(S'((U\\x18Products.ZWiki.ZWikiPa..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 613, 0, zeoLoad, ('\x00\x00\x00\x00\x00<X\x10')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00<X\x10')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 2486 bytes: "(I613\nI0\nS'.reply'\n(S'((U\\x18Products.ZWiki.ZWikiPa..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 614, 0, zeoLoad, ('\x00\x00\x00\x00\x00<X\x10')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00<X\x10')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 2486 bytes: "(I614\nI0\nS'.reply'\n(S'((U\\x18Products.ZWiki.ZWikiPa..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 615, 0, zeoLoad, ('\x00\x00\x00\x00\x00RE\xe7')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00RE\xe7')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 35634 bytes: "(I615\nI0\nS'.reply'\n(S'((U\\x18Products.ZWiki.ZWikiPa..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 616, 0, zeoLoad, ('\x00\x00\x00\x00\x00RE\xe7')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00RE\xe7')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 35634 bytes: "(I616\nI0\nS'.reply'\n(S'((U\\x18Products.ZWiki.ZWikiPa..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 617, 0, zeoLoad, ('\x00\x00\x00\x00\x00RE\xe7')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00RE\xe7')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 35634 bytes: "(I617\nI0\nS'.reply'\n(S'((U\\x18Products.ZWiki.ZWikiPa..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 618, 0, zeoLoad, ('\x00\x00\x00\x00\x00<V\x90')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00<V\x90')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 5600 bytes: "(I618\nI0\nS'.reply'\n(S'((U\\x18Products.ZWiki.ZWikiPa..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 619, 0, zeoLoad, ('\x00\x00\x00\x00\x00<V\x90')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00<V\x90')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 5600 bytes: "(I619\nI0\nS'.reply'\n(S'((U\\x18Products.ZWiki.ZWikiPa..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 620, 0, zeoLoad, ('\x00\x00\x00\x00\x00<V\x90')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00<V\x90')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 5600 bytes: "(I620\nI0\nS'.reply'\n(S'((U\\x18Products.ZWiki.ZWikiPa..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 621, 0, zeoLoad, ('\x00\x00\x00\x00\x00B\x13\x08')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00B\x13\x08')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 70752 bytes: "(I621\nI0\nS'.reply'\n(S'((U\\x18Products.ZWiki.ZWikiPa..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 622, 0, zeoLoad, ('\x00\x00\x00\x00\x00B\x13\x08')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00B\x13\x08')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 70752 bytes: "(I622\nI0\nS'.reply'\n(S'((U\\x18Products.ZWiki.ZWikiPa..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 623, 0, zeoLoad, ('\x00\x00\x00\x00\x00B\x13\x08')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00B\x13\x08')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 70752 bytes: "(I623\nI0\nS'.reply'\n(S'((U\\x18Products.ZWiki.ZWikiPa..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 624, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\xb4\x9c')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\xb4\x9c')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 2660 bytes: "(I624\nI0\nS'.reply'\n(S'((U\\x18Products.ZWiki.ZWikiPa..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 625, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\xb4\x9c')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\xb4\x9c')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 2660 bytes: "(I625\nI0\nS'.reply'\n(S'((U\\x18Products.ZWiki.ZWikiPa..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 626, 0, zeoLoad, ('\x00\x00\x00\x00\x00P\xb4\x9c')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00P\xb4\x9c')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 2660 bytes: "(I626\nI0\nS'.reply'\n(S'((U\\x18Products.ZWiki.ZWikiPa..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 627, 0, zeoLoad, ('\x00\x00\x00\x00\x00R\x05\x14')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00R\x05\x14')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 1827 bytes: "(I627\nI0\nS'.reply'\n(S'((U\\x18Products.ZWiki.ZWikiPa..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 recv msg: 628, 0, zeoLoad, ('\x00\x00\x00\x00\x00R\x05\x14')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 calling zeoLoad('\x00\x00\x00\x00\x00R\x05\x14')
------
2004-10-28T23:15:31 DEBUG(-200) zrpc-conn:127.0.0.1:39183 zeoLoad returns ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc:16513 message_output 1827 bytes: "(I628\nI0\nS'.reply'\n(S'((U\\x18Products.ZWiki.ZWikiPa..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:127.0.0.1:39183 poll(), async=1

zeo log

(ALL) leading up to the hang:

# tail -f /zeo/log/zeo.log
...
2004-10-28T23:15:28 TRACE(-300) zrpc-conn:localhost:9099 wait(531), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(531): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(531): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 531, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 531, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(531): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 532, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I532\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(532), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(532): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(532): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 532, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 532, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(532): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 533, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I533\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(533), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(533): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(533): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(533): asyncore.poll(0.004)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 533, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 533, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(533): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 534, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I534\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(534), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(534): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(534): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(534): asyncore.poll(0.004)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(534): asyncore.poll(0.008)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 534, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 534, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(534): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 535, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I535\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(535), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(535): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(535): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(535): asyncore.poll(0.004)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(535): asyncore.poll(0.008)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(535): asyncore.poll(0.016)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 535, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 535, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(535): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 536, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I536\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(536), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(536): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(536): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(536): asyncore.poll(0.004)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(536): asyncore.poll(0.008)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 536, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 536, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(536): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 537, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I537\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(537), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(537): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(537): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 537, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 537, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(537): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 538, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I538\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(538), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(538): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(538): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 538, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 538, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(538): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 539, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I539\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(539), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(539): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(539): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 539, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 539, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(539): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 540, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I540\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(540), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(540): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(540): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(540): asyncore.poll(0.004)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(540): asyncore.poll(0.008)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 540, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 540, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(540): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 541, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I541\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(541), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(541): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(541): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(541): asyncore.poll(0.004)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 541, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 541, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(541): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 542, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I542\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(542), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(542): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(542): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(542): asyncore.poll(0.004)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(542): asyncore.poll(0.008)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 542, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 542, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(542): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 543, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I543\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(543), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(543): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(543): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 543, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 543, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(543): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 544, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I544\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(544), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(544): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(544): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 544, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 544, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(544): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 545, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I545\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(545), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(545): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(545): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 545, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 545, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(545): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 546, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I546\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(546), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(546): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(546): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(546): asyncore.poll(0.004)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 546, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 546, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(546): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 547, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I547\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(547), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(547): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(547): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(547): asyncore.poll(0.004)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(547): asyncore.poll(0.008)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 547, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 547, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(547): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 548, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I548\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(548), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(548): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(548): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(548): asyncore.poll(0.004)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(548): asyncore.poll(0.008)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 548, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 548, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(548): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 549, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I549\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(549), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(549): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(549): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(549): asyncore.poll(0.004)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 549, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 549, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(549): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 550, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I550\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(550), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(550): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(550): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(550): asyncore.poll(0.004)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 550, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 550, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(550): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 551, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I551\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(551), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(551): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(551): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(551): asyncore.poll(0.004)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 551, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 551, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(551): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 552, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I552\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(552), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(552): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(552): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(552): asyncore.poll(0.004)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(552): asyncore.poll(0.008)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 552, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 552, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(552): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 553, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I553\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(553), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(553): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(553): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(553): asyncore.poll(0.004)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(553): asyncore.poll(0.008)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 553, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 553, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(553): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 554, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I554\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(554), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(554): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(554): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(554): asyncore.poll(0.004)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 554, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 554, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(554): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 555, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I555\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(555), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(555): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(555): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 555, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 555, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(555): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 556, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I556\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(556), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(556): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(556): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 556, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 556, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(556): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 557, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I557\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(557), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(557): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(557): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(557): asyncore.poll(0.004)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 557, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 557, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(557): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 558, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I558\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(558), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(558): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(558): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(558): asyncore.poll(0.004)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 558, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 558, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(558): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 559, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I559\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(559), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(559): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(559): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(559): asyncore.poll(0.004)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(559): asyncore.poll(0.008)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 559, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 559, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(559): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 560, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I560\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(560), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(560): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(560): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(560): asyncore.poll(0.004)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(560): asyncore.poll(0.008)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 560, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 560, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(560): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 561, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I561\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(561), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(561): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(561): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 561, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 561, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(561): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 562, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I562\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(562), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(562): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(562): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 562, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 562, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(562): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 563, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I563\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(563), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(563): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(563): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 563, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 563, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(563): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 564, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I564\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(564), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(564): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(564): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(564): asyncore.poll(0.004)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(564): asyncore.poll(0.008)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 564, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 564, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(564): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 565, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I565\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(565), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(565): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(565): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(565): asyncore.poll(0.004)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(565): asyncore.poll(0.008)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 565, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 565, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(565): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 566, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I566\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(566), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(566): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(566): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(566): asyncore.poll(0.004)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(566): asyncore.poll(0.008)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 566, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 566, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(566): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 send msg: 567, 0, zeoLoad, ...
------
2004-10-28T23:15:29 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I567\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(567), async=0
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(567): asyncore.poll(0.001)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(567): asyncore.poll(0.002)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(567): asyncore.poll(0.004)
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 567, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 567, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:29 TRACE(-300) zrpc-conn:localhost:9099 wait(567): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 send msg: 568, 0, zeoLoad, ...
------
2004-10-28T23:15:30 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I568\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(568), async=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(568): asyncore.poll(0.001)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(568): asyncore.poll(0.002)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 568, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 568, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(568): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 send msg: 569, 0, zeoLoad, ...
------
2004-10-28T23:15:30 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I569\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(569), async=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(569): asyncore.poll(0.001)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(569): asyncore.poll(0.002)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 569, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 569, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(569): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 send msg: 570, 0, zeoLoad, ...
------
2004-10-28T23:15:30 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I570\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(570), async=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(570): asyncore.poll(0.001)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(570): asyncore.poll(0.002)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(570): asyncore.poll(0.004)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 570, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 570, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(570): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 send msg: 571, 0, zeoLoad, ...
------
2004-10-28T23:15:30 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I571\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(571), async=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(571): asyncore.poll(0.001)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(571): asyncore.poll(0.002)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(571): asyncore.poll(0.004)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(571): asyncore.poll(0.008)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 571, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 571, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(571): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 send msg: 572, 0, zeoLoad, ...
------
2004-10-28T23:15:30 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I572\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(572), async=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(572): asyncore.poll(0.001)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(572): asyncore.poll(0.002)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(572): asyncore.poll(0.004)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(572): asyncore.poll(0.008)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 572, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 572, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(572): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 send msg: 573, 0, zeoLoad, ...
------
2004-10-28T23:15:30 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I573\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(573), async=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(573): asyncore.poll(0.001)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(573): asyncore.poll(0.002)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(573): asyncore.poll(0.004)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 573, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 573, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(573): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 send msg: 574, 0, zeoLoad, ...
------
2004-10-28T23:15:30 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I574\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(574), async=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(574): asyncore.poll(0.001)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(574): asyncore.poll(0.002)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 574, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 574, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(574): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 send msg: 575, 0, zeoLoad, ...
------
2004-10-28T23:15:30 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I575\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(575), async=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(575): asyncore.poll(0.001)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(575): asyncore.poll(0.002)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 575, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 575, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(575): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 send msg: 576, 0, zeoLoad, ...
------
2004-10-28T23:15:30 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I576\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(576), async=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(576): asyncore.poll(0.001)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(576): asyncore.poll(0.002)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(576): asyncore.poll(0.004)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 576, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 576, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(576): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 send msg: 577, 0, zeoLoad, ...
------
2004-10-28T23:15:30 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I577\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(577), async=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(577): asyncore.poll(0.001)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(577): asyncore.poll(0.002)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(577): asyncore.poll(0.004)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(577): asyncore.poll(0.008)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 577, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 577, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(577): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 send msg: 578, 0, zeoLoad, ...
------
2004-10-28T23:15:30 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I578\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(578), async=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(578): asyncore.poll(0.001)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(578): asyncore.poll(0.002)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(578): asyncore.poll(0.004)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(578): asyncore.poll(0.008)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 578, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 578, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(578): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 send msg: 579, 0, zeoLoad, ...
------
2004-10-28T23:15:30 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I579\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(579), async=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(579): asyncore.poll(0.001)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(579): asyncore.poll(0.002)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 579, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 579, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(579): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 send msg: 580, 0, zeoLoad, ...
------
2004-10-28T23:15:30 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I580\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(580), async=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(580): asyncore.poll(0.001)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(580): asyncore.poll(0.002)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 580, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 580, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(580): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 send msg: 581, 0, zeoLoad, ...
------
2004-10-28T23:15:30 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I581\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(581), async=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(581): asyncore.poll(0.001)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(581): asyncore.poll(0.002)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 581, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 581, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(581): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 send msg: 582, 0, zeoLoad, ...
------
2004-10-28T23:15:30 TRACE(-300) zrpc:23207 message_output 57 bytes: "(I582\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(582), async=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(582): asyncore.poll(0.001)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(582): asyncore.poll(0.002)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(582): asyncore.poll(0.004)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(582): asyncore.poll(0.008)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(582): asyncore.poll(0.016)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 582, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 582, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(582): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 send msg: 583, 0, zeoLoad, ...
------
2004-10-28T23:15:30 TRACE(-300) zrpc:23207 message_output 57 bytes: "(I583\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(583), async=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(583): asyncore.poll(0.001)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(583): asyncore.poll(0.002)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(583): asyncore.poll(0.004)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(583): asyncore.poll(0.008)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(583): asyncore.poll(0.016)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 583, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 583, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(583): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 send msg: 584, 0, zeoLoad, ...
------
2004-10-28T23:15:30 TRACE(-300) zrpc:23207 message_output 57 bytes: "(I584\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(584), async=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(584): asyncore.poll(0.001)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(584): asyncore.poll(0.002)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(584): asyncore.poll(0.004)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(584): asyncore.poll(0.008)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(584): asyncore.poll(0.016)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 584, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 584, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(584): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 send msg: 585, 0, zeoLoad, ...
------
2004-10-28T23:15:30 TRACE(-300) zrpc:23207 message_output 57 bytes: "(I585\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(585), async=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(585): asyncore.poll(0.001)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(585): asyncore.poll(0.002)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 585, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 585, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(585): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 send msg: 586, 0, zeoLoad, ...
------
2004-10-28T23:15:30 TRACE(-300) zrpc:23207 message_output 57 bytes: "(I586\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(586), async=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(586): asyncore.poll(0.001)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(586): asyncore.poll(0.002)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 586, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 586, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(586): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 send msg: 587, 0, zeoLoad, ...
------
2004-10-28T23:15:30 TRACE(-300) zrpc:23207 message_output 57 bytes: "(I587\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(587), async=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(587): asyncore.poll(0.001)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(587): asyncore.poll(0.002)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 587, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 587, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(587): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 send msg: 588, 0, zeoLoad, ...
------
2004-10-28T23:15:30 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I588\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00R\\x..." hmac=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(588), async=0
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(588): asyncore.poll(0.001)
------
2004-10-28T23:15:30 TRACE(-300) zrpc-conn:localhost:9099 wait(588): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(588): asyncore.poll(0.004)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(588): asyncore.poll(0.008)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(588): asyncore.poll(0.016)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 588, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 588, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(588): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 589, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I589\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00R\\x..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(589), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(589): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(589): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(589): asyncore.poll(0.004)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(589): asyncore.poll(0.008)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(589): asyncore.poll(0.016)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 589, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 589, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(589): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 590, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I590\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00R\\x..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(590), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(590): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(590): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(590): asyncore.poll(0.004)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(590): asyncore.poll(0.008)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(590): asyncore.poll(0.016)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 590, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 590, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(590): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 591, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I591\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00R\\x..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(591), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(591): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(591): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(591): asyncore.poll(0.004)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 591, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 591, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(591): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 592, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I592\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00R\\x..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(592), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(592): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(592): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 592, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 592, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(592): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 593, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I593\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00R\\x..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(593), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(593): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(593): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 593, 0, .reply, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 593, 0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.((......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(593): reply=(0, ('((U\x0eBTrees.IOBTreeq\x01U\x08IOBucketq\x02tq\x03Nt.(...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 594, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 51 bytes: "(I594\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00<V*'..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(594), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(594): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(594): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 594, 0, .reply, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 594, 0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(594): reply=(0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02t...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 595, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 51 bytes: "(I595\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00<V*'..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(595), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(595): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(595): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 595, 0, .reply, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 595, 0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(595): reply=(0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02t...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 596, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 51 bytes: "(I596\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00<V*'..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(596), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(596): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(596): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(596): asyncore.poll(0.004)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 596, 0, .reply, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 596, 0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(596): reply=(0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02t...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 597, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 51 bytes: "(I597\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00<X^'..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(597), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(597): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(597): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 597, 0, .reply, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 597, 0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(597): reply=(0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02t...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 598, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 51 bytes: "(I598\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00<X^'..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(598), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(598): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(598): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 598, 0, .reply, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 598, 0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(598): reply=(0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02t...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 599, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 51 bytes: "(I599\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00<X^'..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(599), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(599): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(599): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 599, 0, .reply, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 599, 0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(599): reply=(0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02t...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 600, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I600\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00<O\\..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(600), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(600): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(600): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(600): asyncore.poll(0.004)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 600, 0, .reply, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 600, 0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(600): reply=(0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02t...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 601, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I601\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00<O\\..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(601), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(601): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(601): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 601, 0, .reply, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 601, 0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(601): reply=(0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02t...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 602, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I602\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00<O\\..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(602), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(602): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(602): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 602, 0, .reply, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 602, 0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(602): reply=(0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02t...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 603, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 51 bytes: "(I603\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00<Nr'..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(603), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(603): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(603): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 603, 0, .reply, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 603, 0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(603): reply=(0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02t...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 604, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 51 bytes: "(I604\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00<Nr'..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(604), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(604): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(604): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 604, 0, .reply, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 604, 0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(604): reply=(0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02t...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 605, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 51 bytes: "(I605\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00<Nr'..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(605), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(605): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(605): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 605, 0, .reply, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 605, 0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(605): reply=(0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02t...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 606, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I606\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00<O\\..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(606), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(606): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(606): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(606): asyncore.poll(0.004)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 606, 0, .reply, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 606, 0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(606): reply=(0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02t...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 607, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I607\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00<O\\..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(607), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(607): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(607): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 607, 0, .reply, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 607, 0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(607): reply=(0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02t...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 608, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I608\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00<O\\..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(608), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(608): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(608): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 608, 0, .reply, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 608, 0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(608): reply=(0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02t...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 609, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 57 bytes: "(I609\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(609), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(609): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(609): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 609, 0, .reply, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 609, 0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(609): reply=(0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02t...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 610, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 57 bytes: "(I610\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(610), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(610): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(610): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 610, 0, .reply, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 610, 0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(610): reply=(0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02t...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 611, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 57 bytes: "(I611\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(611), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(611): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(611): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 611, 0, .reply, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 611, 0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(611): reply=(0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02t...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 612, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I612\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00<X\\..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(612), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(612): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(612): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 612, 0, .reply, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 612, 0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(612): reply=(0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02t...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 613, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I613\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00<X\\..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(613), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(613): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(613): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 613, 0, .reply, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 613, 0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(613): reply=(0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02t...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 614, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I614\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00<X\\..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(614), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(614): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(614): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 614, 0, .reply, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 614, 0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(614): reply=(0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02t...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 615, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I615\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00RE\\..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(615), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(615): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(615): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(615): asyncore.poll(0.004)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 615, 0, .reply, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 615, 0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(615): reply=(0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02t...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 616, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I616\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00RE\\..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(616), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(616): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(616): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(616): asyncore.poll(0.004)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(616): asyncore.poll(0.008)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 616, 0, .reply, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 616, 0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(616): reply=(0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02t...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 617, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I617\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00RE\\..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(617), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(617): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(617): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(617): asyncore.poll(0.004)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(617): asyncore.poll(0.008)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 617, 0, .reply, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 617, 0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(617): reply=(0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02t...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 618, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I618\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00<V\\..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(618), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(618): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(618): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 618, 0, .reply, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 618, 0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(618): reply=(0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02t...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 619, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I619\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00<V\\..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(619), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(619): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(619): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 619, 0, .reply, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 619, 0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(619): reply=(0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02t...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 620, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 54 bytes: "(I620\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00<V\\..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(620), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(620): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(620): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 620, 0, .reply, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 620, 0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(620): reply=(0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02t...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 621, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 57 bytes: "(I621\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00B\\x..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(621), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(621): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(621): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(621): asyncore.poll(0.004)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(621): asyncore.poll(0.008)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(621): asyncore.poll(0.016)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 621, 0, .reply, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 621, 0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(621): reply=(0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02t...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 622, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 57 bytes: "(I622\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00B\\x..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(622), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(622): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(622): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(622): asyncore.poll(0.004)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(622): asyncore.poll(0.008)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(622): asyncore.poll(0.016)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(622): asyncore.poll(0.032)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 622, 0, .reply, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 622, 0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(622): reply=(0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02t...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 623, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 57 bytes: "(I623\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00B\\x..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(623), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(623): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(623): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(623): asyncore.poll(0.004)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(623): asyncore.poll(0.008)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(623): asyncore.poll(0.016)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(623): asyncore.poll(0.032)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 623, 0, .reply, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 623, 0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(623): reply=(0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02t...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 624, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 57 bytes: "(I624\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(624), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(624): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(624): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(624): asyncore.poll(0.004)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 624, 0, .reply, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 624, 0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(624): reply=(0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02t...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 625, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 57 bytes: "(I625\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(625), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(625): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(625): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 625, 0, .reply, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 625, 0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(625): reply=(0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02t...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 626, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 57 bytes: "(I626\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00P\\x..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(626), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(626): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(626): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 626, 0, .reply, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 626, 0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(626): reply=(0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02t...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 627, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 57 bytes: "(I627\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00R\\x..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(627), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(627): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(627): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 627, 0, .reply, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 627, 0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(627): reply=(0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02t...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 628, 0, zeoLoad, ...
------
2004-10-28T23:15:31 TRACE(-300) zrpc:23207 message_output 57 bytes: "(I628\nI0\nS'zeoLoad'\n(S'\\x00\\x00\\x00\\x00\\x00R\\x..." hmac=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(628), async=0
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(628): asyncore.poll(0.001)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(628): asyncore.poll(0.002)
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv msg: 628, 0, .reply, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 recv reply: 628, 0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02tq......
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 wait(628): reply=(0, ('((U\x18Products.ZWiki.ZWikiPageq\x01U\tZWikiPageq\x02t...
------
2004-10-28T23:15:31 TRACE(-300) zrpc-conn:localhost:9099 send msg: 629, 0, zeoLoad, ...

apache

Incoming requests around that time, just in case it's relevant:

# tail -f /var/log/apache/access.log
...
211.52.125.98 - - [28/Oct/2004:23:15:04 -0700] "GET /SearchPage?expr=skin HTTP/1.1" 502 459 "http://www.zwiki.org/FrontPage" "Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.0; .NET CLR 1.0.3705)"
211.52.125.98 - - [28/Oct/2004:23:15:04 -0700] "GET /SearchPage?expr=skin HTTP/1.1" 502 459 "http://www.zwiki.org/FrontPage" "Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.0; .NET CLR 1.0.3705)"
crawl-66-249-64-173.googlebot.com - - [28/Oct/2004:23:15:04 -0700] "GET /PloneBook HTTP/1.0" 502 448 "-" "Googlebot/2.1 (+http://www.google.com/bot.html)"
crawl-66-249-64-13.googlebot.com - - [28/Oct/2004:23:15:04 -0700] "GET /ZopeCredits HTTP/1.0" 502 482 "-" "Googlebot/2.1 (+http://www.google.com/bot.html)"
211.52.125.98 - - [28/Oct/2004:23:15:05 -0700] "GET /FrontPage HTTP/1.1" 502 487 "-" "Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.0; .NET CLR 1.0.3705)"
crawl-66-249-64-170.googlebot.com - - [28/Oct/2004:23:15:06 -0700] "GET /LoggingInZope HTTP/1.0" 502 486 "-" "Googlebot/2.1 (+http://www.google.com/bot.html)"
crawl-66-249-64-30.googlebot.com - - [28/Oct/2004:23:20:08 -0700] "GET /MailHost HTTP/1.0" 200 - "-" "Googlebot/2.1 (+http://www.google.com/bot.html)"

strace

Attaching strace shows just a little something going on:

# strace -p  `cat /zope1/var/Z2.pid`
Process 23207 attached - interrupt to quit
select(25, [3 4 5 8 14 17 18 19 20 21 22 24], [], [], {18, 310000}) = 1 (in [20], left {3, 130000})
recv(20, "", 4096, 0)                   = 0
close(20)                               = 0
select(25, [3 4 5 8 14 17 18 19 21 22 24], [], [], {30, 0}) = 1 (in [3], left {30, 0})
accept(3, {sa_family=AF_INET, sin_port=htons(51977), sin_addr=inet_addr("127.0.0.1")}, [16]) = 20
fcntl64(20, F_GETFL)                    = 0x2 (flags O_RDWR)
fcntl64(20, F_SETFL, O_RDWR|O_NONBLOCK) = 0
getpeername(20, {sa_family=AF_INET, sin_port=htons(51977), sin_addr=inet_addr("127.0.0.1")}, [16]) = 0
gettimeofday({1099062292, 535238}, NULL) = 0
fcntl64(20, F_SETFD, FD_CLOEXEC)        = 0
select(25, [3 4 5 8 14 17 18 19 20 21 22 24], [], [], {30, 0}) = 1 (in [20], left {30, 0})
recv(20, "GET /VirtualHostBase/http/zwiki."..., 4096, 0) = 475
gettimeofday({1099062292, 537432}, NULL) = 0
select(25, [3 4 5 8 14 17 18 19 20 21 22 24], [], [], {30, 0}) = 1 (in [19], left {27, 760000})
recv(19, "", 4096, 0)                   = 0
close(19)                               = 0
select(25, [3 4 5 8 14 17 18 20 21 22 24], [], [], {30, 0}) = 1 (in [3], left {29, 850000})
accept(3, {sa_family=AF_INET, sin_port=htons(51990), sin_addr=inet_addr("127.0.0.1")}, [16]) = 19
fcntl64(19, F_GETFL)                    = 0x2 (flags O_RDWR)
fcntl64(19, F_SETFL, O_RDWR|O_NONBLOCK) = 0
getpeername(19, {sa_family=AF_INET, sin_port=htons(51990), sin_addr=inet_addr("127.0.0.1")}, [16]) = 0
gettimeofday({1099062294, 926751}, NULL) = 0
fcntl64(19, F_SETFD, FD_CLOEXEC)        = 0
select(25, [3 4 5 8 14 17 18 19 20 21 22 24], [], [], {30, 0}) = 1 (in [19], left {30, 0})
recv(19, "GET /VirtualHostBase/http/zwiki."..., 4096, 0) = 426
gettimeofday({1099062294, 929265}, NULL) = 0
brk(0)                                  = 0x96b6000
brk(0x96d7000)                          = 0x96d7000
select(25, [3 4 5 8 14 17 18 19 20 21 22 24], [], [], {30, 0}) = 0 (Timeout)
select(25, [3 4 5 8 14 17 18 19 20 21 22 24], [], [], {30, 0}) = 0 (Timeout)
select(25, [3 4 5 8 14 17 18 19 20 21 22 24], [], [], {30, 0}) = 0 (Timeout)
select(25, [3 4 5 8 14 17 18 19 20 21 22 24], [], [], {30, 0}) = 0 (Timeout)
select(25, [3 4 5 8 14 17 18 19 20 21 22 24], [], [], {30, 0}

gdb

Attaching gdb shows:

# gdb /usr/bin/python `cat /zope1/var/Z2.pid`
...
(gdb) info threads
  5 Thread 65540 (LWP 26152)  0x400285f4 in __pthread_sigsuspend () from /lib/libpthread.so.0
  4 Thread 49155 (LWP 26151)  0x400285f4 in __pthread_sigsuspend () from /lib/libpthread.so.0
  3 Thread 32770 (LWP 26150)  0x400285f4 in __pthread_sigsuspend () from /lib/libpthread.so.0
  2 Thread 32769 (LWP 23520)  0x4016881a in poll () from /lib/libc.so.6
  1 Thread 16384 (LWP 23207)  0x4016ade1 in select () from /lib/libc.so.6

pstree

For reference, the processes are (zeo):

|-python,16481 /zope/lib/python/zdaemon/zdrun.py -S /zope/lib/python/ZEO/zeoctl.xml -C /zeo/etc/zeo.conf /zeo/bin/runzeo
|   `-python,16513 /zope/lib/python/ZEO/runzeo.py -C /zeo/etc/zeo.conf

(client):

|   |               `-python,23207 /zope/lib/python/Zope/Startup/run.py -C /zope1/etc/zope.conf
|   |                   `-python,23520 /zope/lib/python/Zope/Startup/run.py -C /zope1/etc/zope.conf
|   |                       |-python,26150 /zope/lib/python/Zope/Startup/run.py -C /zope1/etc/zope.conf
|   |                       |-python,26151 /zope/lib/python/Zope/Startup/run.py -C /zope1/etc/zope.conf
|   |                       `-python,26152 /zope/lib/python/Zope/Startup/run.py -C /zope1/etc/zope.conf

lsof

Open files:

# lsof |grep python
COMMAND     PID     USER   FD   TYPE     DEVICE      SIZE      NODE NAME
python    16481     root  cwd    DIR     130,66      2048    705148 /zeo
python    16481     root  rtd    DIR     130,66      2048  18858740 /
python    16481     root  txt    REG     130,66    983448  47842263 /usr/bin/python2.3
python    16481     root    0r   CHR        1,3            62964529 /dev/null
python    16481     root    1w   CHR        1,3            62964529 /dev/null
python    16481     root    2w   CHR        1,3            62964529 /dev/null
python    16481     root    3w   REG     130,66 118258450    983059 /zeo/log/zeo.log
python    16481     root    4u  unix 0x60ea98c0           775648021 /zeo/etc/zeo.zdsock.15073
python    16513     root  cwd    DIR     130,66      2048    705148 /zeo
python    16513     root  rtd    DIR     130,66      2048  18858740 /
python    16513     root  txt    REG     130,66    983448  47842263 /usr/bin/python2.3
python    16513     root    0r   CHR        1,3            62964529 /dev/null
python    16513     root    1w   CHR        1,3            62964529 /dev/null
python    16513     root    2w   CHR        1,3            62964529 /dev/null
python    16513     root    3w   REG     130,66 118258450    983059 /zeo/log/zeo.log
python    16513     root    4uW  REG     130,66         6    933900 /zeo/var/Data.fs.lock
python    16513     root    5u   REG     130,66    282903    934116 /zeo/var/Data.fs.tmp
python    16513     root    6u   REG     130,66 642279605    933904 /zeo/var/Data.fs
python    16513     root    7u  IPv4  775648061                 TCP *:9999 (LISTEN)
python    16513     root    8u  IPv4  776401510                 TCP localhost:9999->localhost:39183 (ESTABLISHED)
python    16513     root    9r  FIFO      130,5           776401512 pipe
python    16513     root   10w  FIFO      130,5           776401512 pipe
python    23207   nobody  cwd    DIR     130,66      2048    852485 /zeo/etc
python    23207   nobody  rtd    DIR     130,66      2048  18858740 /
python    23207   nobody  txt    REG     130,66    983448  47842263 /usr/bin/python2.3
python    23207   nobody    0u   CHR      136,0                   2 /dev/pts/0
python    23207   nobody    1u   CHR      136,0                   2 /dev/pts/0
python    23207   nobody    2u   CHR      136,0                   2 /dev/pts/0
python    23207   nobody    3u  IPv4  776401111                 TCP *:zope (LISTEN)
python    23207   nobody    4u  IPv4  776401114                 TCP *:zope-ftp (LISTEN)
python    23207   nobody    5u  IPv4  776401115                 TCP *:1980 (LISTEN)
python    23207   nobody    6wW  REG     130,66         5  26083343 /zope1/var/Z2.lock
python    23207   nobody    7u   REG     130,66     43333  45106828 (deleted) /tmp/tmp-Sj5rz.tbuf
python    23207   nobody    8r  FIFO      130,5           776401056 pipe
python    23207   nobody    9w  FIFO      130,5           776401056 pipe
python    23207   nobody   10u   REG     130,66   1779468  45107125 (deleted) /tmp/tmpSiUHdG.zec
python    23207   nobody   11r  FIFO      130,5           776401507 pipe
python    23207   nobody   12r   REG     130,66         5  31589141 /zope1/Products/GroupUserFolder/version.txt
python    23207   nobody   13w  FIFO      130,5           776401507 pipe
python    23207   nobody   14u  IPv4  776401508                 TCP localhost:39183->localhost:9999 (ESTABLISHED)
python    23207   nobody   15w   REG     130,66  78098631  26083359 /zope1/log/event.log
python    23207   nobody   16w   REG     130,66    402306  26084136 /zope1/log/Z2.log
python    23207   nobody   17u  IPv4  781273380                 TCP localhost:zope->localhost:56199 (ESTABLISHED)
python    23207   nobody   18u  IPv4  781272161                 TCP localhost:zope->localhost:56081 (ESTABLISHED)
python    23207   nobody   19u  IPv4  781273246                 TCP localhost:zope->localhost:56180 (ESTABLISHED)
python    23207   nobody   20u  IPv4  781273794                 TCP localhost:zope->localhost:56232 (ESTABLISHED)
python    23207   nobody   21u  IPv4  781278588                 TCP localhost:zope->localhost:56390 (ESTABLISHED)
python    23207   nobody   22u  IPv4  781284659                 TCP localhost:zope->localhost:56507 (ESTABLISHED)
python    23207   nobody   23r   REG     130,66     12824  32113502 /zope1/Products/latexwiki/LatexWiki/__init__.py
python    23207   nobody   24u  IPv4  780353232                 TCP localhost:zope->localhost:49495 (ESTABLISHED)
python    23520   nobody  cwd    DIR     130,66      2048    852485 /zeo/etc
python    23520   nobody  rtd    DIR     130,66      2048  18858740 /
python    23520   nobody  txt    REG     130,66    983448  47842263 /usr/bin/python2.3
python    23520   nobody    0u   CHR      136,0                   2 /dev/pts/0
python    23520   nobody    1u   CHR      136,0                   2 /dev/pts/0
python    23520   nobody    2u   CHR      136,0                   2 /dev/pts/0
python    23520   nobody    3u  IPv4  776401111                 TCP *:zope (LISTEN)
python    23520   nobody    4u  IPv4  776401114                 TCP *:zope-ftp (LISTEN)
python    23520   nobody    5u  IPv4  776401115                 TCP *:1980 (LISTEN)
python    23520   nobody    6w   REG     130,66         5  26083343 /zope1/var/Z2.lock
python    23520   nobody    7u   REG     130,66     43333  45106828 (deleted) /tmp/tmp-Sj5rz.tbuf
python    23520   nobody    8r  FIFO      130,5           776401056 pipe
python    23520   nobody    9w  FIFO      130,5           776401056 pipe
python    23520   nobody   10u   REG     130,66   1779468  45107125 (deleted) /tmp/tmpSiUHdG.zec
python    23520   nobody   11r  FIFO      130,5           776401507 pipe
python    23520   nobody   12r   REG     130,66         5  31589141 /zope1/Products/GroupUserFolder/version.txt
python    23520   nobody   13w  FIFO      130,5           776401507 pipe
python    23520   nobody   14u  IPv4  776401508                 TCP localhost:39183->localhost:9999 (ESTABLISHED)
python    23520   nobody   15w   REG     130,66  78098631  26083359 /zope1/log/event.log
python    23520   nobody   16w   REG     130,66    402306  26084136 /zope1/log/Z2.log
python    23520   nobody   17u  IPv4  781273380                 TCP localhost:zope->localhost:56199 (ESTABLISHED)
python    23520   nobody   18u  IPv4  781272161                 TCP localhost:zope->localhost:56081 (ESTABLISHED)
python    23520   nobody   19u  IPv4  781273246                 TCP localhost:zope->localhost:56180 (ESTABLISHED)
python    23520   nobody   20u  IPv4  781273794                 TCP localhost:zope->localhost:56232 (ESTABLISHED)
python    23520   nobody   21u  IPv4  781278588                 TCP localhost:zope->localhost:56390 (ESTABLISHED)
python    23520   nobody   22u  IPv4  781284659                 TCP localhost:zope->localhost:56507 (ESTABLISHED)
python    23520   nobody   23r   REG     130,66     12824  32113502 /zope1/Products/latexwiki/LatexWiki/__init__.py
python    23520   nobody   24u  IPv4  780353232                 TCP localhost:zope->localhost:49495 (ESTABLISHED)
python    26150   nobody  cwd    DIR     130,66      2048    852485 /zeo/etc
python    26150   nobody  rtd    DIR     130,66      2048  18858740 /
python    26150   nobody  txt    REG     130,66    983448  47842263 /usr/bin/python2.3
python    26150   nobody    0u   CHR      136,0                   2 /dev/pts/0
python    26150   nobody    1u   CHR      136,0                   2 /dev/pts/0
python    26150   nobody    2u   CHR      136,0                   2 /dev/pts/0
python    26150   nobody    3u  IPv4  776401111                 TCP *:zope (LISTEN)
python    26150   nobody    4u  IPv4  776401114                 TCP *:zope-ftp (LISTEN)
python    26150   nobody    5u  IPv4  776401115                 TCP *:1980 (LISTEN)
python    26150   nobody    6w   REG     130,66         5  26083343 /zope1/var/Z2.lock
python    26150   nobody    7u   REG     130,66     43333  45106828 (deleted) /tmp/tmp-Sj5rz.tbuf
python    26150   nobody    8r  FIFO      130,5           776401056 pipe
python    26150   nobody    9w  FIFO      130,5           776401056 pipe
python    26150   nobody   10u   REG     130,66   1779468  45107125 (deleted) /tmp/tmpSiUHdG.zec
python    26150   nobody   11r  FIFO      130,5           776401507 pipe
python    26150   nobody   12r   REG     130,66         5  31589141 /zope1/Products/GroupUserFolder/version.txt
python    26150   nobody   13w  FIFO      130,5           776401507 pipe
python    26150   nobody   14u  IPv4  776401508                 TCP localhost:39183->localhost:9999 (ESTABLISHED)
python    26150   nobody   15w   REG     130,66  78098631  26083359 /zope1/log/event.log
python    26150   nobody   16w   REG     130,66    402306  26084136 /zope1/log/Z2.log
python    26150   nobody   17u  IPv4  781273380                 TCP localhost:zope->localhost:56199 (ESTABLISHED)
python    26150   nobody   18u  IPv4  781272161                 TCP localhost:zope->localhost:56081 (ESTABLISHED)
python    26150   nobody   19u  IPv4  781273246                 TCP localhost:zope->localhost:56180 (ESTABLISHED)
python    26150   nobody   20u  IPv4  781273794                 TCP localhost:zope->localhost:56232 (ESTABLISHED)
python    26150   nobody   21u  IPv4  781278588                 TCP localhost:zope->localhost:56390 (ESTABLISHED)
python    26150   nobody   22u  IPv4  781284659                 TCP localhost:zope->localhost:56507 (ESTABLISHED)
python    26150   nobody   23r   REG     130,66     12824  32113502 /zope1/Products/latexwiki/LatexWiki/__init__.py
python    26150   nobody   24u  IPv4  780353232                 TCP localhost:zope->localhost:49495 (ESTABLISHED)
python    26151   nobody  cwd    DIR     130,66      2048    852485 /zeo/etc
python    26151   nobody  rtd    DIR     130,66      2048  18858740 /
python    26151   nobody  txt    REG     130,66    983448  47842263 /usr/bin/python2.3
python    26151   nobody    0u   CHR      136,0                   2 /dev/pts/0
python    26151   nobody    1u   CHR      136,0                   2 /dev/pts/0
python    26151   nobody    2u   CHR      136,0                   2 /dev/pts/0
python    26151   nobody    3u  IPv4  776401111                 TCP *:zope (LISTEN)
python    26151   nobody    4u  IPv4  776401114                 TCP *:zope-ftp (LISTEN)
python    26151   nobody    5u  IPv4  776401115                 TCP *:1980 (LISTEN)
python    26151   nobody    6w   REG     130,66         5  26083343 /zope1/var/Z2.lock
python    26151   nobody    7u   REG     130,66     43333  45106828 (deleted) /tmp/tmp-Sj5rz.tbuf
python    26151   nobody    8r  FIFO      130,5           776401056 pipe
python    26151   nobody    9w  FIFO      130,5           776401056 pipe
python    26151   nobody   10u   REG     130,66   1779468  45107125 (deleted) /tmp/tmpSiUHdG.zec
python    26151   nobody   11r  FIFO      130,5           776401507 pipe
python    26151   nobody   12r   REG     130,66         5  31589141 /zope1/Products/GroupUserFolder/version.txt
python    26151   nobody   13w  FIFO      130,5           776401507 pipe
python    26151   nobody   14u  IPv4  776401508                 TCP localhost:39183->localhost:9999 (ESTABLISHED)
python    26151   nobody   15w   REG     130,66  78098631  26083359 /zope1/log/event.log
python    26151   nobody   16w   REG     130,66    402306  26084136 /zope1/log/Z2.log
python    26151   nobody   17u  IPv4  781273380                 TCP localhost:zope->localhost:56199 (ESTABLISHED)
python    26151   nobody   18u  IPv4  781272161                 TCP localhost:zope->localhost:56081 (ESTABLISHED)
python    26151   nobody   19u  IPv4  781273246                 TCP localhost:zope->localhost:56180 (ESTABLISHED)
python    26151   nobody   20u  IPv4  781273794                 TCP localhost:zope->localhost:56232 (ESTABLISHED)
python    26151   nobody   21u  IPv4  781278588                 TCP localhost:zope->localhost:56390 (ESTABLISHED)
python    26151   nobody   22u  IPv4  781284659                 TCP localhost:zope->localhost:56507 (ESTABLISHED)
python    26151   nobody   23r   REG     130,66     12824  32113502 /zope1/Products/latexwiki/LatexWiki/__init__.py
python    26151   nobody   24u  IPv4  780353232                 TCP localhost:zope->localhost:49495 (ESTABLISHED)
python    26152   nobody  cwd    DIR     130,66      2048    852485 /zeo/etc
python    26152   nobody  rtd    DIR     130,66      2048  18858740 /
python    26152   nobody  txt    REG     130,66    983448  47842263 /usr/bin/python2.3
python    26152   nobody    0u   CHR      136,0                   2 /dev/pts/0
python    26152   nobody    1u   CHR      136,0                   2 /dev/pts/0
python    26152   nobody    2u   CHR      136,0                   2 /dev/pts/0
python    26152   nobody    3u  IPv4  776401111                 TCP *:zope (LISTEN)
python    26152   nobody    4u  IPv4  776401114                 TCP *:zope-ftp (LISTEN)
python    26152   nobody    5u  IPv4  776401115                 TCP *:1980 (LISTEN)
python    26152   nobody    6w   REG     130,66         5  26083343 /zope1/var/Z2.lock
python    26152   nobody    7u   REG     130,66     43333  45106828 (deleted) /tmp/tmp-Sj5rz.tbuf
python    26152   nobody    8r  FIFO      130,5           776401056 pipe
python    26152   nobody    9w  FIFO      130,5           776401056 pipe
python    26152   nobody   10u   REG     130,66   1779468  45107125 (deleted) /tmp/tmpSiUHdG.zec
python    26152   nobody   11r  FIFO      130,5           776401507 pipe
python    26152   nobody   12r   REG     130,66         5  31589141 /zope1/Products/GroupUserFolder/version.txt
python    26152   nobody   13w  FIFO      130,5           776401507 pipe
python    26152   nobody   14u  IPv4  776401508                 TCP localhost:39183->localhost:9999 (ESTABLISHED)
python    26152   nobody   15w   REG     130,66  78098631  26083359 /zope1/log/event.log
python    26152   nobody   16w   REG     130,66    402306  26084136 /zope1/log/Z2.log
python    26152   nobody   17u  IPv4  781273380                 TCP localhost:zope->localhost:56199 (ESTABLISHED)
python    26152   nobody   18u  IPv4  781272161                 TCP localhost:zope->localhost:56081 (ESTABLISHED)
python    26152   nobody   19u  IPv4  781273246                 TCP localhost:zope->localhost:56180 (ESTABLISHED)
python    26152   nobody   20u  IPv4  781273794                 TCP localhost:zope->localhost:56232 (ESTABLISHED)
python    26152   nobody   21u  IPv4  781278588                 TCP localhost:zope->localhost:56390 (ESTABLISHED)
python    26152   nobody   22u  IPv4  781284659                 TCP localhost:zope->localhost:56507 (ESTABLISHED)
python    26152   nobody   23r   REG     130,66     12824  32113502 /zope1/Products/latexwiki/LatexWiki/__init__.py
python    26152   nobody   24u  IPv4  780353232                 TCP localhost:zope->localhost:49495 (ESTABLISHED)

data --Bob McElrath?, Fri, 29 Oct 2004 08:55:55 -0700 reply

simon [zwiki-wiki@zwiki.org]? wrote: > Here's the data from last night's startup hang. It shows that all products > had been loaded, so it's not as simple as a hanging product. Perhaps we > can deduce something from the event.log and zeo.log below ? Also ctrl-c or > a simple kill no longer stops runzope.when this happens. > > Attaching strace shows just a little something going on: [...]? > select(25, [3 4 5 8 14 17 18 19 20 21 22 24]?, [], [], {30, 0}) = 0 (Timeout) > select(25, [3 4 5 8 14 17 18 19 20 21 22 24]?, [], [], {30, 0}) = 0 (Timeout) > select(25, [3 4 5 8 14 17 18 19 20 21 22 24]?, [], [], {30, 0}) = 0 (Timeout) > select(25, [3 4 5 8 14 17 18 19 20 21 22 24]?, [], [], {30, 0}) = 0 (Timeout) > select(25, [3 4 5 8 14 17 18 19 20 21 22 24]?, [], [], {30, 0}

This shouldn't be happening.

> Attaching gdb shows: > > # gdb /usr/bin/python cat /zope1/var/Z2.pid > ... > (gdb) info threads > 5 Thread 65540 (LWP 26152) 0x400285f4 in __pthread_sigsuspend () from /lib/libpthread.so.0 > 4 Thread 49155 (LWP 26151) 0x400285f4 in __pthread_sigsuspend () from /lib/libpthread.so.0 > 3 Thread 32770 (LWP 26150) 0x400285f4 in __pthread_sigsuspend () from /lib/libpthread.so.0 > 2 Thread 32769 (LWP 23520) 0x4016881a in poll () from /lib/libc.so.6 > 1 Thread 16384 (LWP 23207) 0x4016ade1 in select () from /lib/libc.so.6

That is weird, but a reflection of the above select timing out.

> Open files: > > # lsof |grep python > python 23207 nobody 23r REG 130,66 12824 32113502 /zope1/Products/latexwiki/LatexWiki/__init__.py > python 23520 nobody 23r REG 130,66 12824 32113502 /zope1/Products/latexwiki/LatexWiki/__init__.py > python 26150 nobody 23r REG 130,66 12824 32113502 /zope1/Products/latexwiki/LatexWiki/__init__.py > python 26151 nobody 23r REG 130,66 12824 32113502 /zope1/Products/latexwiki/LatexWiki/__init__.py > python 26152 nobody 23r REG 130,66 12824 32113502 /zope1/Products/latexwiki/LatexWiki/__init__.py

The only python files open are latexwiki, and 5 times?!?!

There was a bug in latexwiki where if ghostscript/latex generated too much output, the process would hang on read. (This is because python's popen2 is stupidly implemented) Anyway, it's fixed in 0.35 if you haven't pulled that yet.

Chances are you have a stxlatex page somewhere that is relatively large, that is directly triggering this.

data --Simon Michael, Fri, 29 Oct 2004 09:40:47 -0700 reply

>> select(25, [3 4 5 8 14 17 18 19 20 21 22 24]?, [], [], {30, 0}) = 0 (Timeout) >> select(25, [3 4 5 8 14 17 18 19 20 21 22 24]?, [], [], {30, 0}) = 0 (Timeout) >> select(25, [3 4 5 8 14 17 18 19 20 21 22 24]?, [], [], {30, 0}) = 0 (Timeout) >> select(25, [3 4 5 8 14 17 18 19 20 21 22 24]?, [], [], {30, 0}) = 0 (Timeout) >> select(25, [3 4 5 8 14 17 18 19 20 21 22 24]?, [], [], {30, 0} >> >> > >This shouldn't be happening. > > Why do you say so.. what would you expect to see ?

>The only python files open are latexwiki, and 5 times?!?! > >There was a bug in latexwiki where if ghostscript/latex generated too >much output, the process would hang on read. (This is because python's >popen2 is stupidly implemented) Anyway, it's fixed in 0.35 if you >haven't pulled that yet. > >Chances are you have a stxlatex page somewhere that is relatively large, >that is directly triggering this. > Very interesting.. thanks for the lead!

data --Simon Michael, Fri, 29 Oct 2004 13:29:25 -0700 reply

It is strange that LatexWiki/__init__.py remains open. The only other product file open is one of GRUF's. As a simple test, I have removed the LatexWiki product and set the server to restart a bit more often.

data --Bob McElrath?, Fri, 29 Oct 2004 13:35:54 -0700 reply

Simon Michael [simon@joyful.com]? wrote: > > >>select(25, [3 4 5 8 14 17 18 19 20 21 22 24]?, [], [], {30, 0}) = 0 > >>(Timeout) > >>select(25, [3 4 5 8 14 17 18 19 20 21 22 24]?, [], [], {30, 0}) = 0 > >>(Timeout) > >>select(25, [3 4 5 8 14 17 18 19 20 21 22 24]?, [], [], {30, 0}) = 0 > >>(Timeout) > >>select(25, [3 4 5 8 14 17 18 19 20 21 22 24]?, [], [], {30, 0}) = 0 > >>(Timeout) > >>select(25, [3 4 5 8 14 17 18 19 20 21 22 24]?, [], [], {30, 0} > >> > >> > > > >This shouldn't be happening. > > > > > Why do you say so.. what would you expect to see ?

Well, what is the select timing out on? (repeatedly) and what has a 30s timeout?

I expect zope to be sitting on an open LISTEN socket, but that doesn't have a timeout. A connection to a client expecting or sending data could have a timout, but should timeout exactly once. The same connection shouldn't time out repeatedly, and it would be very odd to see five different select()'s timeout right after one another.

Actually...this could be five simultaneous connections to the same client, which would all timeout at the same time (browsers request the page, then request the images and stylesheet in several parallel connections). ZWiki.org pages generally have two images (logo and external edit icon) plus the stylesheet gives four total connections that would timeout at the same time.

This might indicate that the client (a real client, not a bot) requested a page, got the HTML, then went to get the images and stylesheet. So zwiki hung after delivering the page. That is if your strace actually caught the hang.

Most connections timeout in this fashion if the browser requests keep-alive connections (most do). If your strace did not catch the hang, the above timeouts could be any random client.

> Very interesting.. thanks for the lead!

I still strongly suspect this is it. Grab 0.35 or disable latexwiki to verify.

I have also encountered a number of stx bugs that will peg the CPU (and I'm fixing them!!! ;). Is the load 100% or zero during the hang?

data --Simon Michael, Fri, 29 Oct 2004 13:44:56 -0700 reply

I'm not sure that I believe your explanation here. CPU is always idle in this situation and my assumption was that it has not started responding to any incoming requests yet. But I could be wrong.

data --Bob McElrath?, Fri, 29 Oct 2004 14:07:42 -0700 reply

Simon Michael [simon@joyful.com]? wrote: > I'm not sure that I believe your explanation here. CPU is always idle in > this situation and my assumption was that it has not started responding > to any incoming requests yet. But I could be wrong.

I'm suggesting several possible explanations...

I'm saying if your strace caught the hang, then it occured between rendering a page, and successive requests from the same client. Of course those select() timeouts can be from an unrelated client.

LatexWiki waiting-for-ghostscript would cause this, in which case the strace timeouts are unrelated.

Can you come up with a list of stxlatex pages on zwiki.org and try each of them? There can't be that many...

http://www.zwiki.org/LatexWiki seems to be hanging, or the site is already hung. Didn't you just disable LatexWiki?

data --Bob McElrath?, Fri, 29 Oct 2004 14:34:00 -0700 reply

Simon Michael [simon@joyful.com]? wrote: > No, I attached with strace after the hang

Within 30s of the hang?

> This site is not hung right now but intermittently extremely slow. I am > blaming this on high googlebot activity at the moment. I have disallowed > google in robots.txt for now but it has not taken effect.

Maybe turn on ZopeProfiler?. Of course that will make it even slower...

I will continue in my efforts to speed up the code. I found a way to shave another 1.8s of the large page render I posted earlier.

solved --simon, Sun, 31 Oct 2004 10:11:32 -0800 reply

I can't quite say why, but there is pretty strong evidence now that this was a deadlock in LatexWiki startup code. Namely:

Good news. This was really starting to make us look bad. Now that we reliably restart again, I want to solve the memory leak that makes restarts necessary in the first place. We need to be able to tell people they can set up a zwiki server and forget about it.

property change --simon, Sun, 31 Oct 2004 10:13:18 -0800 reply

Status: open => closed

property change --Bob McElrath?, Sun, 31 Oct 2004 16:30:33 -0800 reply

simon [zwiki-wiki@zwiki.org]? wrote: > Status: open => closed

Hmmm can you somehow leave this open and assign it to latexwiki?

I haven't had a chance to put a patch for this in my repo, but since you are the only person who sees this bug, I will have to ask you to try my patches to fix it. So it's not "closed" for latexwiki yet...

Thanks, Bob McElrath? [Univ. of California at Davis, Department of Physics]?

It is unpatriotic to question the Kleptocracy.

sure thing --simon, Sun, 31 Oct 2004 16:54:54 -0800 reply

Category: site-zwiki.org => general Status: closed => open

property change --simon, Sun, 28 Nov 2004 02:21:45 -0800 reply

Category: general => non-zwiki

property change --simon, Wed, 05 Jan 2005 12:01:45 -0800 reply

Name: '#938 zwiki/zopewiki.org server sometimes fails to restart causing periodic hangs' => '#938 latexwiki can occasionally hang zope at startup'

reported fixed in latest version, closing --simon, Tue, 01 Feb 2005 14:47:23 -0800 reply

Status: open => closed

... --betabug, Wed, 21 Feb 2007 08:42:57 +0000 reply

Name: '#938 calley' => '#938 latexwiki can occasionally hang zope at startup' Category: user-editing-html => user-editing-stxlatex Severity: critical => serious