[ZODB-Dev] Zope2.6 + ZEO 2
Igor Stroh
stroh@scan-plus.de
08 Apr 2003 10:21:44 +0200
--=-VJJdBsjQgGeXqBQ1JmET
Content-Type: text/plain
Content-Transfer-Encoding: 7bit
On Mon, 2003-04-07 at 19:48, Jeremy Hylton wrote:
> It sounds like your setup should work. Can you provide more details
> about what is going wrong? In particular, turn on the debug log for
> Zope and the ZEO server and see how far it is getting. I'd start by
> using -100 as the log level, but you may need to set it lower to get all
> the details.
>
> Interesting questions to ask of the log: Does the ZEO client establish
> a connection? Is the initial handshake finished?
I attached the log file of one session (without the stuff after
SIGTERM). EVENT_LOG_SEVERITYis set to -600, I know it's perhaps a bit
too low, but I wanted to make sure that everything is beeing logged :)
One thing that makes me a bit confused about it is that there are no
entries about UA (lynx, mozilla) connections. I tried to load a page
several times but the log doesn't even mention a connection attempt...
Although port 80 is definitely beeing used by zope:
jenner@muaddib:~$ sudo lsof | grep LISTEN
[...]
python2.1 19783 zope 8u IPv4 417535 TCP localhost:7700 (LISTEN)
python2.1 21835 zope 16u IPv4 449681 TCP localhost:www (LISTEN)
python2.1 21835 zope 17u IPv4 449683 TCP localhost:ftp (LISTEN)
python2.1 21836 root 16u IPv4 449681 TCP localhost:www (LISTEN)
python2.1 21836 root 17u IPv4 449683 TCP localhost:ftp (LISTEN)
[...]
> I don't recall the details, but we fixed some problem with import locks
> and ZEO. Does the client process deadlock? If so, then this could be
> the problem.
No, it doesn't. At least according to `ps` all python processes are in
the "sleeping" state:
jenner@muaddib:~$ ps ax | grep python
[...]
21834 pty/s1 S 0:00 /usr/bin/python2.1 /usr/sbin/zope-z2 -P
127.0.0.1:0 -t 7 -D -u zope
21835 pty/s1 S 0:01 /usr/bin/python2.1 /usr/sbin/zope-z2 -P
127.0.0.1:0 -t 7 -D -u zope
[...]
Is there any other way to determine whether the client has deadlocked?
Maybe some special log entries I should look at?
Greetings,
Igor
--=-VJJdBsjQgGeXqBQ1JmET
Content-Description: Example of a zope session
Content-Disposition: attachment; filename=zope_log.txt
Content-Type: text/plain; charset=ISO-8859-1
Content-Transfer-Encoding: quoted-printable
------
2003-04-08T10:01:16 BLATHER(-100) Z2 Installed sighandler for SIGTERM
------
2003-04-08T10:01:16 BLATHER(-100) Z2 Installed sighandler for SIGINT
------
2003-04-08T10:01:16 BLATHER(-100) Z2 Installed sighandler for SIGHUP
------
2003-04-08T10:01:16 BLATHER(-100) Z2 Installed sighandler for SIGUSR2
------
2003-04-08T10:01:16 INFO(0) zdaemon Started subprocess: pid 21835
------
2003-04-08T10:01:16 BLATHER(-100) Z2 Installed sighandler for SIGTERM
------
2003-04-08T10:01:16 BLATHER(-100) Z2 Installed sighandler for SIGINT
------
2003-04-08T10:01:16 BLATHER(-100) Z2 Installed sighandler for SIGHUP
------
2003-04-08T10:01:16 BLATHER(-100) Z2 Installed sighandler for SIGUSR2
------
2003-04-08T10:01:17 INFO(0) ZCS:21835 ClientStorage (pid=3D21835) created R=
W/normal for storage: '1'
------
2003-04-08T10:01:17 INFO(0) ZEC:1 ClientCache: storage=3D'1', size=3D209715=
20; file[0]=3DNone
------
2003-04-08T10:01:17 BLATHER(-100) zrpc:21835 CM.connect(): starting Connect=
Thread
------
2003-04-08T10:01:17 BLATHER(-100) zrpc:21835 CT: attempting to connect on 1=
sockets
------
2003-04-08T10:01:17 BLATHER(-100) zrpc:21835 CW: attempt to connect to ('lo=
calhost', 7700)
------
2003-04-08T10:01:17 BLATHER(-100) zrpc:21835 CW: connect_ex(('localhost', 7=
700)) returned EINPROGRESS
------
2003-04-08T10:01:17 BLATHER(-100) zrpc:21835 CW: connect_ex(('localhost', 7=
700)) returned 0
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 4 bytes: 'Z200'
------
2003-04-08T10:01:17 INFO(0) ZCS:21835 Testing connection <ManagedConnection=
('127.0.0.1', 7700)>
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 0, 0, re=
gister, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 24 bytes: '(K\x00=
K\x00U\x08register(U\x011K\x00tt.'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(0), async=3D0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(0): asyncore.=
poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(0): asyncore.=
poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 0, 0, .r=
eply, None
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 0, 0, =
None
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(0): reply=3D(=
0, None)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 1, 0, ge=
t_info, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 18 bytes: '(K\x01=
K\x00U\x08get_info)t.'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(1), async=3D0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(1): asyncore.=
poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(1): asyncore.=
poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 1, 0, .r=
eply, {'size': 1325861L, 'supportsUndo': 1, 'supportsTransactional...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 1, 0, =
{'size': 1325861L, 'supportsUndo': 1, 'supportsTransactional...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(1): reply=3D(=
0, {'size': 1325861L, 'supportsUndo': 1, 'supportsTransacti...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 2, 1, be=
ginZeoVerify, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 24 bytes: '(K\x02=
K\x01U\x0ebeginZeoVerify)t.'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 poll(), async=3D0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 3, 1, en=
dZeoVerify, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 22 bytes: '(K\x03=
K\x01U\x0cendZeoVerify)t.'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 poll(), async=3D0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 0, 1, be=
gin, ()
------
2003-04-08T10:01:17 BLATHER(-100) zrpc-conn:localhost:7700 calling begin()
------
2003-04-08T10:01:17 INFO(0) ZCS:21835 begin cache verification
------
2003-04-08T10:01:17 INFO(0) ZCS:21835 Connected to storage: ('127.0.0.1', 7=
700)
------
2003-04-08T10:01:17 BLATHER(-100) zrpc:21835 CM.connect_done(preferred=3D1)
------
2003-04-08T10:01:17 BLATHER(-100) zrpc:21835 CT: exiting thread: Connect([(=
2, ('localhost', 7700))])
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 4, 0, ze=
oLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K\x04=
K\x00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x...'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(4), async=3D0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(4): asyncore.=
poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 1, 1, en=
d, ()
------
2003-04-08T10:01:17 BLATHER(-100) zrpc-conn:localhost:7700 calling end()
------
2003-04-08T10:01:17 INFO(0) ZCS:21835 end cache verification (0 invalidatio=
ns, 0.00278 seconds)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(4): asyncore.=
poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 4, 0, .r=
eply, ('((U\x0bPersistenceq\x01U\x11PersistentMappingq\x02tq\x03Nt...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 4, 0, =
('((U\x0bPersistenceq\x01U\x11PersistentMappingq\x02tq\x03Nt...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(4): reply=3D(=
0, ('((U\x0bPersistenceq\x01U\x11PersistentMappingq\x02tq\x...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 5, 0, ze=
oLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K\x05=
K\x00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x...'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(5), async=3D0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(5): asyncore.=
poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(5): asyncore.=
poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 5, 0, .r=
eply, ('((U\x0fOFS.Applicationq\x01U\x0bApplicationq\x02tq\x03Nt.}...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 5, 0, =
('((U\x0fOFS.Applicationq\x01U\x0bApplicationq\x02tq\x03Nt.}...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(5): reply=3D(=
0, ('((U\x0fOFS.Applicationq\x01U\x0bApplicationq\x02tq\x03...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 6, 0, ze=
oLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K\x06=
K\x00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x...'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(6), async=3D0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(6): asyncore.=
poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(6): asyncore.=
poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 6, 0, .r=
eply, ('((U\x16App.ApplicationManagerq\x01U\x12ApplicationManagerq...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 6, 0, =
('((U\x16App.ApplicationManagerq\x01U\x12ApplicationManagerq...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(6): reply=3D(=
0, ('((U\x16App.ApplicationManagerq\x01U\x12ApplicationMana...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 7, 0, ze=
oLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K\x07=
K\x00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x...'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(7), async=3D0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(7): asyncore.=
poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(7): asyncore.=
poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 7, 0, .r=
eply, ("((U(Products.TemporaryFolder.TemporaryFolderq\x01U\x16Moun...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 7, 0, =
("((U(Products.TemporaryFolder.TemporaryFolderq\x01U\x16Moun...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(7): reply=3D(=
0, ("((U(Products.TemporaryFolder.TemporaryFolderq\x01U\x16...
------
2003-04-08T10:01:17 INFO(0) ZODB Opening database for mounting: '143965864_=
1049709369.967858'
------
2003-04-08T10:01:17 INFO(0) ZODB Mounted database '143965864_1049709369.967=
858' at /temp_folder
------
2003-04-08T10:01:17 INFO(0) Zope Default Object Creation /usr/lib/zope/impo=
rt/Examples.zexp examples import file could not be found.
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 8, 0, ze=
oLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K\x08=
K\x00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x...'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(8), async=3D0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(8): asyncore.=
poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(8): asyncore.=
poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 8, 0, .r=
eply, ('(cAccessControl.User\nUserFolder\nq\x00Ntq\x01.}q\x02U\x04...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 8, 0, =
('(cAccessControl.User\nUserFolder\nq\x00Ntq\x01.}q\x02U\x04...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(8): reply=3D(=
0, ('(cAccessControl.User\nUserFolder\nq\x00Ntq\x01.}q\x02U...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 9, 0, ze=
oLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K\tK\=
x00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x00...'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(9), async=3D0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(9): asyncore.=
poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(9): asyncore.=
poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 9, 0, .r=
eply, ('(cPersistence\nPersistentMapping\nq\x00Ntq\x01.}q\x02U\n_c...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 9, 0, =
('(cPersistence\nPersistentMapping\nq\x00Ntq\x01.}q\x02U\n_c...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(9): reply=3D(=
0, ('(cPersistence\nPersistentMapping\nq\x00Ntq\x01.}q\x02U...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 10, 0, z=
eoLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K\nK\=
x00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x00...'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(10), async=3D=
0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(10): asyncore=
.poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(10): asyncore=
.poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 10, 0, .=
reply, ('((U\x0bApp.Productq\x01U\rProductFolderq\x02tq\x03Nt.}q\x0...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 10, 0,=
('((U\x0bApp.Productq\x01U\rProductFolderq\x02tq\x03Nt.}q\x0...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(10): reply=3D=
(0, ('((U\x0bApp.Productq\x01U\rProductFolderq\x02tq\x03Nt.}...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 11, 0, z=
eoLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K\x0b=
K\x00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x...'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(11), async=3D=
0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(11): asyncore=
.poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(11): asyncore=
.poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 11, 0, .=
reply, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 11, 0,=
('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(11): reply=3D=
(0, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x0...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 12, 0, z=
eoLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K\x0c=
K\x00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x...'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(12), async=3D=
0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(12): asyncore=
.poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(12): asyncore=
.poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 12, 0, .=
reply, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 12, 0,=
('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(12): reply=3D=
(0, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x0...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 13, 0, z=
eoLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K\rK\=
x00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x00...'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(13), async=3D=
0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(13): asyncore=
.poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(13): asyncore=
.poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 13, 0, .=
reply, ('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03Nt.}...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 13, 0,=
('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03Nt.}...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(13): reply=3D=
(0, ('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 14, 0, z=
eoLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K\x0e=
K\x00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x...'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(14), async=3D=
0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(14): asyncore=
.poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(14): asyncore=
.poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 14, 0, .=
reply, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 14, 0,=
('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(14): reply=3D=
(0, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x0...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 15, 0, z=
eoLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K\x0f=
K\x00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x...'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(15), async=3D=
0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(15): asyncore=
.poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(15): asyncore=
.poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 15, 0, .=
reply, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 15, 0,=
('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(15): reply=3D=
(0, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x0...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 16, 0, z=
eoLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K\x10=
K\x00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x...'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(16), async=3D=
0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(16): asyncore=
.poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(16): asyncore=
.poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 16, 0, .=
reply, ('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03Nt.}...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 16, 0,=
('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03Nt.}...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(16): reply=3D=
(0, ('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 17, 0, z=
eoLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K\x11=
K\x00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x...'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(17), async=3D=
0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(17): asyncore=
.poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(17): asyncore=
.poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 17, 0, .=
reply, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 17, 0,=
('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(17): reply=3D=
(0, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x0...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 18, 0, z=
eoLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K\x12=
K\x00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x...'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(18), async=3D=
0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(18): asyncore=
.poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(18): asyncore=
.poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(18): asyncore=
.poll(0.004)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 18, 0, .=
reply, ('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03Nt.}...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 18, 0,=
('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03Nt.}...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(18): reply=3D=
(0, ('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 19, 0, z=
eoLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K\x13=
K\x00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x...'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(19), async=3D=
0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(19): asyncore=
.poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(19): asyncore=
.poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 19, 0, .=
reply, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 19, 0,=
('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(19): reply=3D=
(0, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x0...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 20, 0, z=
eoLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K\x14=
K\x00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x...'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(20), async=3D=
0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(20): asyncore=
.poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(20): asyncore=
.poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 20, 0, .=
reply, ('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03Nt.}...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 20, 0,=
('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03Nt.}...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(20): reply=3D=
(0, ('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 21, 0, z=
eoLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K\x15=
K\x00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x...'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(21), async=3D=
0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(21): asyncore=
.poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(21): asyncore=
.poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 21, 0, .=
reply, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 21, 0,=
('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(21): reply=3D=
(0, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x0...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 22, 0, z=
eoLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K\x16=
K\x00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x...'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(22), async=3D=
0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(22): asyncore=
.poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(22): asyncore=
.poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 22, 0, .=
reply, ('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03Nt.}...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 22, 0,=
('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03Nt.}...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(22): reply=3D=
(0, ('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 23, 0, z=
eoLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K\x17=
K\x00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x...'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(23), async=3D=
0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(23): asyncore=
.poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(23): asyncore=
.poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 23, 0, .=
reply, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 23, 0,=
('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(23): reply=3D=
(0, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x0...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 24, 0, z=
eoLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K\x18=
K\x00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x...'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(24), async=3D=
0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(24): asyncore=
.poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(24): asyncore=
.poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 24, 0, .=
reply, ('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03Nt.}...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 24, 0,=
('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03Nt.}...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(24): reply=3D=
(0, ('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 25, 0, z=
eoLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K\x19=
K\x00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x...'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(25), async=3D=
0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(25): asyncore=
.poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(25): asyncore=
.poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 25, 0, .=
reply, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 25, 0,=
('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(25): reply=3D=
(0, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x0...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 26, 0, z=
eoLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K\x1a=
K\x00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x...'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(26), async=3D=
0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(26): asyncore=
.poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(26): asyncore=
.poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 26, 0, .=
reply, ('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03Nt.}...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 26, 0,=
('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03Nt.}...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(26): reply=3D=
(0, ('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 27, 0, z=
eoLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K\x1b=
K\x00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x...'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(27), async=3D=
0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(27): asyncore=
.poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(27): asyncore=
.poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 27, 0, .=
reply, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 27, 0,=
('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(27): reply=3D=
(0, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x0...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 28, 0, z=
eoLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K\x1c=
K\x00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x...'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(28), async=3D=
0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(28): asyncore=
.poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(28): asyncore=
.poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 28, 0, .=
reply, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 28, 0,=
('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(28): reply=3D=
(0, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x0...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 29, 0, z=
eoLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K\x1d=
K\x00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x...'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(29), async=3D=
0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(29): asyncore=
.poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(29): asyncore=
.poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 29, 0, .=
reply, ('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03Nt.}...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 29, 0,=
('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03Nt.}...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(29): reply=3D=
(0, ('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 30, 0, z=
eoLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K\x1e=
K\x00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x...'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(30), async=3D=
0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(30): asyncore=
.poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(30): asyncore=
.poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 30, 0, .=
reply, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 30, 0,=
('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(30): reply=3D=
(0, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x0...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 31, 0, z=
eoLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K\x1f=
K\x00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x...'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(31), async=3D=
0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(31): asyncore=
.poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(31): asyncore=
.poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 31, 0, .=
reply, ('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03Nt.}...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 31, 0,=
('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03Nt.}...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(31): reply=3D=
(0, ('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 32, 0, z=
eoLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K K\x=
00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x07\...'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(32), async=3D=
0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(32): asyncore=
.poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(32): asyncore=
.poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 32, 0, .=
reply, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 32, 0,=
('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(32): reply=3D=
(0, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x0...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 33, 0, z=
eoLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K!K\x=
00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x07 tt.'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(33), async=3D=
0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(33): asyncore=
.poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(33): asyncore=
.poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 33, 0, .=
reply, ('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03Nt.}...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 33, 0,=
('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03Nt.}...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(33): reply=3D=
(0, ('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 34, 0, z=
eoLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K"K\x=
00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x07ntt.'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(34), async=3D=
0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(34): asyncore=
.poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(34): asyncore=
.poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 34, 0, .=
reply, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 34, 0,=
('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(34): reply=3D=
(0, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x0...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 35, 0, z=
eoLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K#K\x=
00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x07ott.'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(35), async=3D=
0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(35): asyncore=
.poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(35): asyncore=
.poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 35, 0, .=
reply, ('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03Nt.}...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 35, 0,=
('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03Nt.}...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(35): reply=3D=
(0, ('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 36, 0, z=
eoLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K$K\x=
00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x07\...'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(36), async=3D=
0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(36): asyncore=
.poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(36): asyncore=
.poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 36, 0, .=
reply, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 36, 0,=
('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(36): reply=3D=
(0, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x0...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 37, 0, z=
eoLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K%K\x=
00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x07\...'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(37), async=3D=
0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(37): asyncore=
.poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(37): asyncore=
.poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 37, 0, .=
reply, ('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03Nt.}...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 37, 0,=
('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03Nt.}...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(37): reply=3D=
(0, ('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 38, 0, z=
eoLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K&K\x=
00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x00\...'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(38), async=3D=
0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(38): asyncore=
.poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(38): asyncore=
.poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 38, 0, .=
reply, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 38, 0,=
('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(38): reply=3D=
(0, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x0...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 39, 0, z=
eoLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: "(K'K\x=
00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x00\..."
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(39), async=3D=
0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(39): asyncore=
.poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(39): asyncore=
.poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 39, 0, .=
reply, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 39, 0,=
('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(39): reply=3D=
(0, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x0...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 40, 0, z=
eoLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K(K\x=
00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x08\...'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(40), async=3D=
0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(40): asyncore=
.poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(40): asyncore=
.poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 40, 0, .=
reply, ('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03Nt.}...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 40, 0,=
('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03Nt.}...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(40): reply=3D=
(0, ('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 41, 0, z=
eoLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K)K\x=
00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x08\...'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(41), async=3D=
0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(41): asyncore=
.poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(41): asyncore=
.poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 41, 0, .=
reply, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 41, 0,=
('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x04(U\...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(41): reply=3D=
(0, ('((U\x0bApp.Productq\x01U\x07Productq\x02tq\x03Nt.}q\x0...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 42, 0, z=
eoLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K*K\x=
00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x08]tt.'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(42), async=3D=
0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(42): asyncore=
.poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(42): asyncore=
.poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 42, 0, .=
reply, ('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03Nt.}...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 42, 0,=
('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03Nt.}...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(42): reply=3D=
(0, ('((U\x0fHelpSys.HelpSysq\x01U\x0bProductHelpq\x02tq\x03...
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 send msg: 43, 0, z=
eoLoad, ...
------
2003-04-08T10:01:17 TRACE(-300) zrpc:21835 message_output 28 bytes: '(K+K\x=
00U\x07zeoLoad(U\x08\x00\x00\x00\x00\x00\x00\x00[tt.'
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(43), async=3D=
0
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(43): asyncore=
.poll(0.001)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 wait(43): asyncore=
.poll(0.002)
------
2003-04-08T10:01:17 TRACE(-300) zrpc-conn:localhost:7700 recv msg: 43, 0, .=
reply, ('((U\x05BTreeq\x01U\x05BTreeq\x02tq\x03Nt.).', '\x03L\x16\x...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 recv reply: 43, 0,=
('((U\x05BTreeq\x01U\x05BTreeq\x02tq\x03Nt.).', '\x03L\x16\x...
------
2003-04-08T10:01:17 DEBUG(-200) zrpc-conn:localhost:7700 wait(43): reply=3D=
(0, ('((U\x05BTreeq\x01U\x05BTreeq\x02tq\x03Nt.).', '\x03L\x...
------
2003-04-08T10:01:17 BLATHER(-100) z2 Using access log file /var/lib/zope/va=
r/Z2.log
------
2003-04-08T10:01:17 INFO(0) ZServer HTTP server started at Tue Apr 8 10:01=
:17 2003
Hostname: localhost
Port: 80
------
2003-04-08T10:01:17 INFO(0) ZServer FTP server started at Tue Apr 8 10:01:=
17 2003
Hostname: muaddib
Port: 21
------
2003-04-08T10:01:17 INFO(0) ZServer PCGI Server started at Tue Apr 8 10:01=
:17 2003
Unix socket: /var/lib/zope/var/Z2-pcgi.soc
------
2003-04-08T10:01:17 DEBUG(-200) zrpc:21835 CM.set_async({15: <ManagedConnec=
tion ('127.0.0.1', 7700)>, 18: <ZServer.PCGIServer.PCGIServer listening '/v=
ar/lib/zope/var/Z2-pcgi.soc' at 0x88cdbbc>, 10: <select-trigger (pipe) at 8=
23cedc>, 17: <ZServer.FTPServer.FTPServer listening 127.0.0.1:21 at 0x898b7=
ac>, 16: <ZServer.HTTPServer.zhttp_server listening 127.0.0.1:80 at 0x8990a=
6c>})
------
2003-04-08T10:01:17 BLATHER(-100) zrpc:21835 CM.set_async(): first call
--=-VJJdBsjQgGeXqBQ1JmET--