archive/error-timeout.mbox

   1 From mike  Sun Oct 12 12:41:50 2008
   2 X-VM-v5-Data: ([nil nil nil nil nil nil nil nil nil]
   3         ["71024" "Sunday" "12" "October" "2008" "01:23:45" "+0200" "Cron Daemon" "root@indexdata.dk" nil "686" "Cron <mike@kafta> cd /home/mike/cvs/irspy/bin && YAZ_LOG=irspy ./setrlimit -v -a 500 -- perl -I ../lib irspy.pl -a -n 50 -t Main -m 7,0 localhost:8018/IR-Explain---1" "^From:" nil nil "10" nil nil nil nil nil nil nil nil nil nil]
   4         nil)
   5 Return-path: <mike@indexdata.dk>
   6 Envelope-to: mike@indexdata.com
   7 Delivery-date: Sun, 12 Oct 2008 01:49:47 +0200
   8 Received: from kebab.indexdata.dk [83.133.64.60]
   9         by localhost.localdomain with POP3 (fetchmail-6.3.8)
  10         for <mike@localhost> (single-drop); Sun, 12 Oct 2008 12:41:50 +0100 (BST)
  11 Received: from user.indexdata.dk ([130.226.235.215] helo=bagel.index)
  12         by kebab.indexdata.dk with esmtps (TLS-1.0:RSA_AES_256_CBC_SHA1:32)
  13         (Exim 4.63)
  14         (envelope-from <mike@indexdata.dk>)
  15         id 1KooDS-0002ri-TX
  16         for mike@indexdata.com; Sun, 12 Oct 2008 01:49:47 +0200
  17 Received: from kafta.indexdata.dk ([83.133.64.64])
  18         by bagel.index with esmtps (TLS-1.0:RSA_AES_256_CBC_SHA1:32)
  19         (Exim 4.63)
  20         (envelope-from <mike@indexdata.dk>)
  21         id 1KooDS-0008SD-Ck
  22         for mike@indexdata.com; Sun, 12 Oct 2008 01:49:46 +0200
  23 Received: from mike by kafta.indexdata.dk with local (Exim 4.63)
  24         (envelope-from <mike@kafta.indexdata.dk>)
  25         id 1KonRZ-0008PO-KF
  26         for mike@kafta.indexdata.dk; Sun, 12 Oct 2008 01:49:45 +0200
  27 Content-Type: text/plain; charset=ANSI_X3.4-1968
  28 X-Cron-Env: <SHELL=/bin/sh>
  29 X-Cron-Env: <HOME=/home/mike>
  30 X-Cron-Env: <PATH=/usr/bin:/bin>
  31 X-Cron-Env: <LOGNAME=mike>
  32 Message-Id: <E1KonRZ-0008PO-KF@kafta.indexdata.dk>
  33 X-SA-Do-Not-Run: Yes
  34 X-SA-Do-Not-Run: Yes
  35 X-SA-Exim-Connect-IP: 130.226.235.215
  36 X-SA-Exim-Mail-From: mike@indexdata.dk
  37 X-SA-Exim-Scanned: No (on kebab.indexdata.dk); SAEximRunCond expanded to false
  38 From: root@indexdata.dk (Cron Daemon)
  39 To: mike@kafta.indexdata.dk
  40 Subject: Cron <mike@kafta> cd /home/mike/cvs/irspy/bin && YAZ_LOG=irspy ./setrlimit -v -a 500 -- perl -I ../lib irspy.pl -a -n 50 -t Main -m 7,0 localhost:8018/IR-Explain---1
  41 Date: Sun, 12 Oct 2008 01:23:45 +0200
  42
  43 01:00:13-12/10 [irspy] starting up with database 'localhost:8018/IR-Explain---1'
  44 01:00:15-12/10 [irspy] adding test 'Main'
  45 01:00:17-12/10 [irspy] adding test 'Ping'
  46 01:00:17-12/10 [irspy] adding test 'Search::Main'
  47 01:00:17-12/10 [irspy] adding test 'Search::Bib1'
  48 01:00:17-12/10 [irspy] adding test 'Search::Dan1'
  49 01:00:17-12/10 [irspy] adding test 'Search::Boolean'
  50 01:00:17-12/10 [irspy] adding test 'Search::Explain'
  51 01:00:17-12/10 [irspy] adding test 'Search::CQL'
  52 01:00:17-12/10 [irspy] adding test 'Record::Main'
  53 01:00:17-12/10 [irspy] adding test 'Record::Fetch'
  54 01:00:17-12/10 [irspy] adding test 'ResultSet::Main'
  55 01:00:17-12/10 [irspy] adding test 'ResultSet::Named'
  56 01:00:20-12/10 [irspy] 'cql.allRecords=1' found 2876 target records
  57 01:23:21-12/10 [irspy] tcp:128.171.19.17:7290/Voyager query 'rec.id="Z39.50:128.171.19.17:7290/Voyager"' found 1 record
  58 01:23:23-12/10 [irspy] tcp:128.172.1.222:9909/VCU01PUB query 'rec.id="Z39.50:128.172.1.222:9909/VCU01PUB"' found 1 record
  59 01:23:24-12/10 [irspy] tcp:128.172.2.40:20005/dcat query 'rec.id="Z39.50:128.172.2.40:20005/dcat"' found 1 record
  60 01:23:25-12/10 [irspy] tcp:129.237.122.77:210/costarica query 'rec.id="Z39.50:129.237.122.77:210/costarica"' found 1 record
  61 01:23:27-12/10 [irspy] tcp:130.111.64.9:210/INNOPAC query 'rec.id="Z39.50:130.111.64.9:210/INNOPAC"' found 1 record
  62 01:23:28-12/10 [irspy] tcp:130.159.64.152:210/HORIZON query 'rec.id="Z39.50:130.159.64.152:210/HORIZON"' found 1 record
  63 01:23:29-12/10 [irspy] tcp:130.39.131.82:210/LSUE query 'rec.id="Z39.50:130.39.131.82:210/LSUE"' found 1 record
  64 01:23:30-12/10 [irspy] tcp:130.39.131.82:210/TECH query 'rec.id="Z39.50:130.39.131.82:210/TECH"' found 1 record
  65 01:23:31-12/10 [irspy] tcp:130.86.12.1:210/Innopac query 'rec.id="Z39.50:130.86.12.1:210/Innopac"' found 1 record
  66 01:23:32-12/10 [irspy] tcp:131.112.119.70:210/report query 'rec.id="Z39.50:131.112.119.70:210/report"' found 1 record
  67 01:23:33-12/10 [irspy] tcp:131.121.188.1:210/INNOPAC query 'rec.id="Z39.50:131.121.188.1:210/INNOPAC"' found 1 record
  68 01:23:34-12/10 [irspy] tcp:134.99.136.10:2020/opac query 'rec.id="Z39.50:134.99.136.10:2020/opac"' found 1 record
  69 01:23:35-12/10 [irspy] tcp:136.206.1.14:5210/adop query 'rec.id="Z39.50:136.206.1.14:5210/adop"' found 1 record
  70 01:23:36-12/10 [irspy] tcp:137.150.180.11:210/MAIN*BIBMAST query 'rec.id="Z39.50:137.150.180.11:210/MAIN\*BIBMAST"' found 1 record
  71 01:23:36-12/10 [irspy] tcp:137.151.165.1:210/Innopac query 'rec.id="Z39.50:137.151.165.1:210/Innopac"' found 1 record
  72 01:23:38-12/10 [irspy] tcp:138.231.48.2:21210/MAIN*BIBMAST query 'rec.id="Z39.50:138.231.48.2:21210/MAIN\*BIBMAST"' found 2 records
  73 01:23:39-12/10 [irspy] tcp:147.29.116.113:2100/DAB01 query 'rec.id="Z39.50:147.29.116.113:2100/DAB01"' found 1 record
  74 01:23:39-12/10 [irspy] tcp:147.29.116.113:2100/vej01 query 'rec.id="Z39.50:147.29.116.113:2100/vej01"' found 1 record
  75 01:23:40-12/10 [irspy] tcp:152.95.54.52:2105/SKB query 'rec.id="Z39.50:152.95.54.52:2105/SKB"' found 1 record
  76 01:23:40-12/10 [irspy] tcp:155.135.4.1:210/MAIN*BIBMAST query 'rec.id="Z39.50:155.135.4.1:210/MAIN\*BIBMAST"' found 1 record
  77 01:23:41-12/10 [irspy] tcp:156.108.164.118:210/MANCOSSERVER query 'rec.id="Z39.50:156.108.164.118:210/MANCOSSERVER"' found 1 record
  78 01:23:41-12/10 [irspy] tcp:159.189.81.130:5333/nbii_metadata query 'rec.id="Z39.50:159.189.81.130:5333/nbii_metadata"' found 1 record
  79 01:23:42-12/10 [irspy] tcp:169.139.243.254:210/Horizon query 'rec.id="Z39.50:169.139.243.254:210/Horizon"' found 1 record
  80 01:23:43-12/10 [irspy] tcp:192.231.201.194:210/clarke query 'rec.id="Z39.50:192.231.201.194:210/clarke"' found 1 record
  81 01:23:44-12/10 [irspy] tcp:192.235.0.3:210/INNOPAC query 'rec.id="Z39.50:192.235.0.3:210/INNOPAC"' found 1 record
  82 01:23:45-12/10 [irspy] tcp:192.54.81.3:210/CDE query 'rec.id="Z39.50:192.54.81.3:210/CDE"' found 1 record
  83 01:23:46-12/10 [irspy] tcp:192.54.81.3:210/LAM query 'rec.id="Z39.50:192.54.81.3:210/LAM"' found 1 record
  84 01:23:46-12/10 [irspy] tcp:192.54.81.3:210/MCC query 'rec.id="Z39.50:192.54.81.3:210/MCC"' found 1 record
  85 01:23:47-12/10 [irspy] tcp:192.54.81.3:210/NEU query 'rec.id="Z39.50:192.54.81.3:210/NEU"' found 1 record
  86 01:23:47-12/10 [irspy] tcp:192.54.81.3:210/OTE query 'rec.id="Z39.50:192.54.81.3:210/OTE"' found 1 record
  87 01:23:47-12/10 [irspy] tcp:192.54.81.3:210/TRI query 'rec.id="Z39.50:192.54.81.3:210/TRI"' found 1 record
  88 01:23:48-12/10 [irspy] tcp:192.70.216.16:9991/10540 query 'rec.id="Z39.50:192.70.216.16:9991/10540"' found 1 record
  89 01:23:48-12/10 [irspy] tcp:192.84.155.210:9909/PIS01 query 'rec.id="Z39.50:192.84.155.210:9909/PIS01"' found 1 record
  90 01:23:48-12/10 [irspy] tcp:193.162.227.226:210/Semibasen query 'rec.id="Z39.50:193.162.227.226:210/Semibasen"' found 1 record
  91 01:23:49-12/10 [irspy] tcp:193.30.112.113:9909/HBZ01 query 'rec.id="Z39.50:193.30.112.113:9909/HBZ01"' found 1 record
  92 01:23:49-12/10 [irspy] tcp:193.43.102.65:21210/ADVANCE query 'rec.id="Z39.50:193.43.102.65:21210/ADVANCE"' found 1 record
  93 01:23:49-12/10 [irspy] tcp:194.190.182.90:210/1 query 'rec.id="Z39.50:194.190.182.90:210/1"' found 1 record
  94 01:23:50-12/10 [irspy] tcp:194.190.182.90:211/liber.test2 query 'rec.id="Z39.50:194.190.182.90:211/liber.test2"' found 1 record
  95 01:23:50-12/10 [irspy] tcp:194.190.186.119:2210/liber query 'rec.id="Z39.50:194.190.186.119:2210/liber"' found 1 record
  96 01:23:51-12/10 [irspy] tcp:194.239.156.133:2100/S query 'rec.id="Z39.50:194.239.156.133:2100/S"' found 1 record
  97 01:23:51-12/10 [irspy] tcp:195.31.151.66:2100/guggenheim query 'rec.id="Z39.50:195.31.151.66:2100/guggenheim"' found 1 record
  98 01:23:52-12/10 [irspy] tcp:195.49.239.18:210/elib query 'rec.id="Z39.50:195.49.239.18:210/elib"' found 1 record
  99 01:23:52-12/10 [irspy] tcp:207.63.246.34:210/Dynix query 'rec.id="Z39.50:207.63.246.34:210/Dynix"' found 1 record
 100 01:23:53-12/10 [irspy] tcp:207.64.156.8:210/dynix query 'rec.id="Z39.50:207.64.156.8:210/dynix"' found 1 record
 101 01:23:54-12/10 [irspy] tcp:207.74.210.3:210/INNOPAC query 'rec.id="Z39.50:207.74.210.3:210/INNOPAC"' found 1 record
 102 01:23:54-12/10 [irspy] tcp:208.24.160.61:210/LOCAL query 'rec.id="Z39.50:208.24.160.61:210/LOCAL"' found 2 records
 103 01:23:55-12/10 [irspy] tcp:212.45.5.164:2210/liber.fr query 'rec.id="Z39.50:212.45.5.164:2210/liber.fr"' found 1 record
 104 01:23:57-12/10 [irspy] tcp:213.151.79.29:9999/UZPI query 'rec.id="Z39.50:213.151.79.29:9999/UZPI"' found 1 record
 105 01:23:57-12/10 [irspy] tcp:217.167.201.202:210/ICCP query 'rec.id="Z39.50:217.167.201.202:210/ICCP"' found 1 record
 106 01:23:57-12/10 [irspy] tcp:62.76.8.149:210/dis query 'rec.id="Z39.50:62.76.8.149:210/dis"' found 1 record
 107 01:23:58-12/10 [irspy] beginnning with test 'Main' (timeout 90)
 108 01:24:00-12/10 [irspy] tcp:194.190.182.90:210/1 not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:194.190.182.90:210/1") from diag-set 'ZOOM'
 109 01:24:01-12/10 [irspy] tcp:194.190.182.90:210/1 has no more tests: removing
 110 01:24:01-12/10 [irspy] tcp:194.190.182.90:210/1 rewriting XML record
 111 01:24:12-12/10 [irspy] tcp:194.190.182.90:210/1 rewrote XML record
 112 01:24:12-12/10 [irspy] tcp:81.186.130.245:210/Pantazo query 'rec.id="Z39.50:81.186.130.245:210/Pantazo"' found 1 record
 113 01:24:12-12/10 [irspy] tcp:81.186.130.245:210/Pantazo entering active pool - 2518 targets remain in queue
 114 01:24:12-12/10 [irspy] tcp:136.206.1.14:5210/adop not connected: ZOOM error 10005 "Init rejected" from diag-set 'ZOOM'
 115 01:24:12-12/10 [irspy] tcp:136.206.1.14:5210/adop has no more tests: removing
 116 01:24:12-12/10 [irspy] tcp:136.206.1.14:5210/adop rewriting XML record
 117 01:24:21-12/10 [irspy] tcp:136.206.1.14:5210/adop rewrote XML record
 118 01:24:22-12/10 [irspy] tcp:Exlibris.daimi.au.dk:2104/VID01 query 'rec.id="Z39.50:Exlibris.daimi.au.dk:2104/VID01"' found 1 record
 119 01:24:22-12/10 [irspy] tcp:Exlibris.daimi.au.dk:2104/VID01 entering active pool - 2511 targets remain in queue
 120 01:24:22-12/10 [irspy] tcp:137.151.165.1:210/Innopac not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:137.151.165.1:210/Innopac") from diag-set 'ZOOM'
 121 01:24:22-12/10 [irspy] tcp:137.151.165.1:210/Innopac has no more tests: removing
 122 01:24:22-12/10 [irspy] tcp:137.151.165.1:210/Innopac rewriting XML record
 123 01:24:31-12/10 [irspy] tcp:137.151.165.1:210/Innopac rewrote XML record
 124 01:24:31-12/10 [irspy] tcp:Z3950.maribo.integrabib.dk:210/Default query 'rec.id="Z39.50:Z3950.maribo.integrabib.dk:210/Default"' found 1 record
 125 01:24:32-12/10 [irspy] tcp:Z3950.maribo.integrabib.dk:210/Default entering active pool - 2508 targets remain in queue
 126 01:24:32-12/10 [irspy] tcp:Z3950.maribo.integrabib.dk:210/Default not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:Z3950.maribo.integrabib.dk:210/Default") from diag-set 'ZOOM'
 127 01:24:32-12/10 [irspy] tcp:Z3950.maribo.integrabib.dk:210/Default has no more tests: removing
 128 01:24:32-12/10 [irspy] tcp:Z3950.maribo.integrabib.dk:210/Default rewriting XML record
 129 01:24:37-12/10 [irspy] tcp:Z3950.maribo.integrabib.dk:210/Default rewrote XML record
 130 01:24:37-12/10 [irspy] tcp:ads.ahds.ac.uk:2100/default query 'rec.id="Z39.50:ads.ahds.ac.uk:2100/default"' found 1 record
 131 01:24:38-12/10 [irspy] tcp:ads.ahds.ac.uk:2100/default entering active pool - 2504 targets remain in queue
 132 01:24:38-12/10 [irspy] tcp:169.139.243.254:210/Horizon not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:169.139.243.254:210/Horizon") from diag-set 'ZOOM'
 133 01:24:38-12/10 [irspy] tcp:169.139.243.254:210/Horizon has no more tests: removing
 134 01:24:38-12/10 [irspy] tcp:169.139.243.254:210/Horizon rewriting XML record
 135 01:24:43-12/10 [irspy] tcp:169.139.243.254:210/Horizon rewrote XML record
 136 01:24:44-12/10 [irspy] tcp:advance.biblio.polymtl.ca:210/ADVANCE query 'rec.id="Z39.50:advance.biblio.polymtl.ca:210/ADVANCE"' found 1 record
 137 01:24:44-12/10 [irspy] tcp:advance.biblio.polymtl.ca:210/ADVANCE entering active pool - 2503 targets remain in queue
 138 01:24:44-12/10 [irspy] tcp:192.231.201.194:210/clarke not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:192.231.201.194:210/clarke") from diag-set 'ZOOM'
 139 01:24:44-12/10 [irspy] tcp:192.231.201.194:210/clarke has no more tests: removing
 140 01:24:44-12/10 [irspy] tcp:192.231.201.194:210/clarke rewriting XML record
 141 01:24:51-12/10 [irspy] tcp:192.231.201.194:210/clarke rewrote XML record
 142 01:24:51-12/10 [irspy] tcp:aleph.mzk.cz:9991/MZK01 query 'rec.id="Z39.50:aleph.mzk.cz:9991/MZK01"' found 1 record
 143 01:24:52-12/10 [irspy] tcp:aleph.mzk.cz:9991/MZK01 entering active pool - 2496 targets remain in queue
 144 01:24:52-12/10 [irspy] tcp:192.54.81.3:210/CDE not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:192.54.81.3:210/CDE") from diag-set 'ZOOM'
 145 01:24:52-12/10 [irspy] tcp:192.54.81.3:210/CDE has no more tests: removing
 146 01:24:52-12/10 [irspy] tcp:192.54.81.3:210/CDE rewriting XML record
 147 01:25:01-12/10 [irspy] tcp:192.54.81.3:210/CDE rewrote XML record
 148 01:25:01-12/10 [irspy] tcp:aleph.sismelfirenze.it:9909/FEF01 query 'rec.id="Z39.50:aleph.sismelfirenze.it:9909/FEF01"' found 1 record
 149 01:25:02-12/10 [irspy] tcp:aleph.sismelfirenze.it:9909/FEF01 entering active pool - 2494 targets remain in queue
 150 01:25:02-12/10 [irspy] tcp:192.70.216.16:9991/10540 not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:192.70.216.16:9991/10540") from diag-set 'ZOOM'
 151 01:25:02-12/10 [irspy] tcp:192.70.216.16:9991/10540 has no more tests: removing
 152 01:25:02-12/10 [irspy] tcp:192.70.216.16:9991/10540 rewriting XML record
 153 01:25:13-12/10 [irspy] tcp:192.70.216.16:9991/10540 rewrote XML record
 154 01:25:13-12/10 [irspy] tcp:aleph.unibas.ch:9909/DSV01 query 'rec.id="Z39.50:aleph.unibas.ch:9909/DSV01"' found 1 record
 155 01:25:13-12/10 [irspy] tcp:aleph.unibas.ch:9909/DSV01 entering active pool - 2493 targets remain in queue
 156 01:25:14-12/10 [irspy] tcp:194.190.182.90:211/liber.test2 not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:194.190.182.90:211/liber.test2") from diag-set 'ZOOM'
 157 01:25:14-12/10 [irspy] tcp:194.190.182.90:211/liber.test2 has no more tests: removing
 158 01:25:14-12/10 [irspy] tcp:194.190.182.90:211/liber.test2 rewriting XML record
 159 01:25:22-12/10 [irspy] tcp:194.190.182.90:211/liber.test2 rewrote XML record
 160 01:25:22-12/10 [irspy] tcp:alephprod.calico.ac.za:9991/UCT01 query 'rec.id="Z39.50:alephprod.calico.ac.za:9991/UCT01"' found 1 record
 161 01:25:22-12/10 [irspy] tcp:alephprod.calico.ac.za:9991/UCT01 entering active pool - 2491 targets remain in queue
 162 01:25:23-12/10 [irspy] tcp:195.49.239.18:210/elib not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:195.49.239.18:210/elib") from diag-set 'ZOOM'
 163 01:25:23-12/10 [irspy] tcp:195.49.239.18:210/elib has no more tests: removing
 164 01:25:23-12/10 [irspy] tcp:195.49.239.18:210/elib rewriting XML record
 165 01:25:29-12/10 [irspy] tcp:195.49.239.18:210/elib rewrote XML record
 166 01:25:30-12/10 [irspy] tcp:amicus.nlc-bnc.ca:210/NL query 'rec.id="Z39.50:amicus.nlc-bnc.ca:210/NL"' found 1 record
 167 01:25:30-12/10 [irspy] tcp:amicus.nlc-bnc.ca:210/NL entering active pool - 2479 targets remain in queue
 168 01:25:31-12/10 [irspy] tcp:Exlibris.daimi.au.dk:2104/VID01 not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:Exlibris.daimi.au.dk:2104/VID01") from diag-set 'ZOOM'
 169 01:25:31-12/10 [irspy] tcp:Exlibris.daimi.au.dk:2104/VID01 has no more tests: removing
 170 01:25:31-12/10 [irspy] tcp:Exlibris.daimi.au.dk:2104/VID01 rewriting XML record
 171 01:25:39-12/10 [irspy] tcp:Exlibris.daimi.au.dk:2104/VID01 rewrote XML record
 172 01:25:39-12/10 [irspy] tcp:amicus.oszk.hu:1616/B2 query 'rec.id="Z39.50:amicus.oszk.hu:1616/B2"' found 1 record
 173 01:25:40-12/10 [irspy] tcp:amicus.oszk.hu:1616/B2 entering active pool - 2476 targets remain in queue
 174 01:25:40-12/10 [irspy] tcp:152.95.54.52:2105/SKB not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:152.95.54.52:2105/SKB") from diag-set 'ZOOM'
 175 01:25:40-12/10 [irspy] tcp:152.95.54.52:2105/SKB has no more tests: removing
 176 01:25:40-12/10 [irspy] tcp:152.95.54.52:2105/SKB rewriting XML record
 177 01:25:48-12/10 [irspy] tcp:152.95.54.52:2105/SKB rewrote XML record
 178 01:25:48-12/10 [irspy] tcp:biblio.crusca.fi.it:9909/ADC01 query 'rec.id="Z39.50:biblio.crusca.fi.it:9909/ADC01"' found 1 record
 179 01:25:48-12/10 [irspy] tcp:biblio.crusca.fi.it:9909/ADC01 entering active pool - 2451 targets remain in queue
 180 01:25:49-12/10 [irspy] tcp:192.54.81.3:210/LAM not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:192.54.81.3:210/LAM") from diag-set 'ZOOM'
 181 01:25:49-12/10 [irspy] tcp:192.54.81.3:210/LAM has no more tests: removing
 182 01:25:49-12/10 [irspy] tcp:192.54.81.3:210/LAM rewriting XML record
 183 01:25:59-12/10 [irspy] tcp:192.54.81.3:210/LAM rewrote XML record
 184 01:25:59-12/10 [irspy] tcp:biblio.iue.it:210/INNOPAC query 'rec.id="Z39.50:biblio.iue.it:210/INNOPAC"' found 1 record
 185 01:26:00-12/10 [irspy] tcp:biblio.iue.it:210/INNOPAC entering active pool - 2449 targets remain in queue
 186 01:26:00-12/10 [irspy] tcp:192.54.81.3:210/MCC not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:192.54.81.3:210/MCC") from diag-set 'ZOOM'
 187 01:26:00-12/10 [irspy] tcp:192.54.81.3:210/MCC has no more tests: removing
 188 01:26:00-12/10 [irspy] tcp:192.54.81.3:210/MCC rewriting XML record
 189 01:26:05-12/10 [irspy] tcp:192.54.81.3:210/MCC rewrote XML record
 190 01:26:05-12/10 [irspy] tcp:biblio15.uned.es:2200/unicorn query 'rec.id="Z39.50:biblio15.uned.es:2200/unicorn"' found 1 record
 191 01:26:06-12/10 [irspy] tcp:biblio15.uned.es:2200/unicorn entering active pool - 2446 targets remain in queue
 192 01:26:07-12/10 [irspy] tcp:62.76.8.149:210/dis converted 'RUSLAN Server Лицензирован для: Башкирский государственный университет' from Latin-1 to UTF-8
 193 01:26:08-12/10 [irspy] tcp:amicus.oszk.hu:1616/B2 not connected: ZOOM error 10005 "Init rejected" from diag-set 'ZOOM'
 194 01:26:08-12/10 [irspy] tcp:amicus.oszk.hu:1616/B2 has no more tests: removing
 195 01:26:08-12/10 [irspy] tcp:amicus.oszk.hu:1616/B2 rewriting XML record
 196 01:26:16-12/10 [irspy] tcp:amicus.oszk.hu:1616/B2 rewrote XML record
 197 01:26:16-12/10 [irspy] tcp:biblioteca.uc3m.es:2200/unicorn query 'rec.id="Z39.50:biblioteca.uc3m.es:2200/unicorn"' found 1 record
 198 01:26:16-12/10 [irspy] tcp:biblioteca.uc3m.es:2200/unicorn entering active pool - 2444 targets remain in queue
 199 01:26:22-12/10 [irspy] tcp:81.186.130.245:210/Pantazo has no more tests: removing
 200 01:26:22-12/10 [irspy] tcp:81.186.130.245:210/Pantazo rewriting XML record
 201 01:26:37-12/10 [irspy] tcp:81.186.130.245:210/Pantazo rewrote XML record
 202 01:26:37-12/10 [irspy] tcp:bibliotecaforal.bizkaia.net:210/EUSKO query 'rec.id="Z39.50:bibliotecaforal.bizkaia.net:210/EUSKO"' found 1 record
 203 01:26:38-12/10 [irspy] tcp:bibliotecaforal.bizkaia.net:210/EUSKO entering active pool - 2443 targets remain in queue
 204 01:26:59-12/10 [irspy] tcp:128.171.19.17:7290/Voyager has no more tests: removing
 205 01:26:59-12/10 [irspy] tcp:128.171.19.17:7290/Voyager rewriting XML record
 206 01:27:20-12/10 [irspy] tcp:128.171.19.17:7290/Voyager rewrote XML record
 207 01:27:20-12/10 [irspy] tcp:blais.claremont.edu:210/INNOPAC query 'rec.id="Z39.50:blais.claremont.edu:210/INNOPAC"' found 1 record
 208 01:27:20-12/10 [irspy] tcp:blais.claremont.edu:210/INNOPAC entering active pool - 2432 targets remain in queue
 209 01:27:21-12/10 [irspy] tcp:128.172.1.222:9909/VCU01PUB not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:128.172.1.222:9909/VCU01PUB") from diag-set 'ZOOM'
 210 01:27:21-12/10 [irspy] tcp:128.172.1.222:9909/VCU01PUB has no more tests: removing
 211 01:27:21-12/10 [irspy] tcp:128.172.1.222:9909/VCU01PUB rewriting XML record
 212 01:27:42-12/10 [irspy] tcp:128.172.1.222:9909/VCU01PUB rewrote XML record
 213 01:27:42-12/10 [irspy] tcp:bpavila.bcl.jcyl.es:210/AbsysE query 'rec.id="Z39.50:bpavila.bcl.jcyl.es:210/AbsysE"' found 1 record
 214 01:27:43-12/10 [irspy] tcp:bpavila.bcl.jcyl.es:210/AbsysE entering active pool - 2427 targets remain in queue
 215 01:27:43-12/10 [irspy] tcp:128.172.2.40:20005/dcat not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:128.172.2.40:20005/dcat") from diag-set 'ZOOM'
 216 01:27:43-12/10 [irspy] tcp:128.172.2.40:20005/dcat has no more tests: removing
 217 01:27:43-12/10 [irspy] tcp:128.172.2.40:20005/dcat rewriting XML record
 218 01:27:54-12/10 [irspy] tcp:128.172.2.40:20005/dcat rewrote XML record
 219 01:27:54-12/10 [irspy] tcp:bpburgos.bcl.jcyl.es:210/AbsysE query 'rec.id="Z39.50:bpburgos.bcl.jcyl.es:210/AbsysE"' found 1 record
 220 01:27:55-12/10 [irspy] tcp:bpburgos.bcl.jcyl.es:210/AbsysE entering active pool - 2426 targets remain in queue
 221 01:27:55-12/10 [irspy] tcp:129.237.122.77:210/costarica not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:129.237.122.77:210/costarica") from diag-set 'ZOOM'
 222 01:27:55-12/10 [irspy] tcp:129.237.122.77:210/costarica has no more tests: removing
 223 01:27:55-12/10 [irspy] tcp:129.237.122.77:210/costarica rewriting XML record
 224 01:28:04-12/10 [irspy] tcp:129.237.122.77:210/costarica rewrote XML record
 225 01:28:04-12/10 [irspy] tcp:brocar.unavarra.es:9999/libros query 'rec.id="Z39.50:brocar.unavarra.es:9999/libros"' found 1 record
 226 01:28:05-12/10 [irspy] tcp:brocar.unavarra.es:9999/libros entering active pool - 2418 targets remain in queue
 227 01:28:05-12/10 [irspy] tcp:130.159.64.152:210/HORIZON not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:130.159.64.152:210/HORIZON") from diag-set 'ZOOM'
 228 01:28:05-12/10 [irspy] tcp:130.159.64.152:210/HORIZON has no more tests: removing
 229 01:28:05-12/10 [irspy] tcp:130.159.64.152:210/HORIZON rewriting XML record
 230 01:28:13-12/10 [irspy] tcp:130.159.64.152:210/HORIZON rewrote XML record
 231 01:28:13-12/10 [irspy] tcp:bthnt.bth.rwth-aachen.de:2020/gkneu query 'rec.id="Z39.50:bthnt.bth.rwth-aachen.de:2020/gkneu"' found 1 record
 232 01:28:14-12/10 [irspy] tcp:bthnt.bth.rwth-aachen.de:2020/gkneu entering active pool - 2416 targets remain in queue
 233 01:28:14-12/10 [irspy] tcp:130.39.131.82:210/LSUE not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:130.39.131.82:210/LSUE") from diag-set 'ZOOM'
 234 01:28:14-12/10 [irspy] tcp:130.39.131.82:210/LSUE has no more tests: removing
 235 01:28:14-12/10 [irspy] tcp:130.39.131.82:210/LSUE rewriting XML record
 236 01:28:21-12/10 [irspy] tcp:130.39.131.82:210/LSUE rewrote XML record
 237 01:28:21-12/10 [irspy] tcp:cat.libraries.psu.edu:2200/Unicorn query 'rec.id="Z39.50:cat.libraries.psu.edu:2200/Unicorn"' found 2 records
 238 01:28:22-12/10 [irspy] tcp:cat.libraries.psu.edu:2200/Unicorn entering active pool - 2403 targets remain in queue
 239 01:28:22-12/10 [irspy] tcp:130.39.131.82:210/TECH not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:130.39.131.82:210/TECH") from diag-set 'ZOOM'
 240 01:28:22-12/10 [irspy] tcp:130.39.131.82:210/TECH has no more tests: removing
 241 01:28:22-12/10 [irspy] tcp:130.39.131.82:210/TECH rewriting XML record
 242 01:28:29-12/10 [irspy] tcp:130.39.131.82:210/TECH rewrote XML record
 243 01:28:29-12/10 [irspy] tcp:catalogue.halifaxpubliclibraries.ca:210/horizon query 'rec.id="Z39.50:catalogue.halifaxpubliclibraries.ca:210/horizon"' found 1 record
 244 01:28:29-12/10 [irspy] tcp:catalogue.halifaxpubliclibraries.ca:210/horizon entering active pool - 2379 targets remain in queue
 245 01:28:30-12/10 [irspy] tcp:130.86.12.1:210/Innopac not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:130.86.12.1:210/Innopac") from diag-set 'ZOOM'
 246 01:28:30-12/10 [irspy] tcp:130.86.12.1:210/Innopac has no more tests: removing
 247 01:28:30-12/10 [irspy] tcp:130.86.12.1:210/Innopac rewriting XML record
 248 01:28:39-12/10 [irspy] tcp:130.86.12.1:210/Innopac rewrote XML record
 249 01:28:39-12/10 [irspy] tcp:catalogue.lib.ed.ac.uk:7090/Voyager query 'rec.id="Z39.50:catalogue.lib.ed.ac.uk:7090/Voyager"' found 1 record
 250 01:28:39-12/10 [irspy] tcp:catalogue.lib.ed.ac.uk:7090/Voyager entering active pool - 2378 targets remain in queue
 251 01:28:40-12/10 [irspy] tcp:131.112.119.70:210/report not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:131.112.119.70:210/report") from diag-set 'ZOOM'
 252 01:28:40-12/10 [irspy] tcp:131.112.119.70:210/report has no more tests: removing
 253 01:28:40-12/10 [irspy] tcp:131.112.119.70:210/report rewriting XML record
 254 01:28:47-12/10 [irspy] tcp:131.112.119.70:210/report rewrote XML record
 255 01:28:47-12/10 [irspy] tcp:ciu.library.net:5666/ciu query 'rec.id="Z39.50:ciu.library.net:5666/ciu"' found 1 record
 256 01:28:48-12/10 [irspy] tcp:ciu.library.net:5666/ciu entering active pool - 2368 targets remain in queue
 257 01:28:48-12/10 [irspy] tcp:131.121.188.1:210/INNOPAC not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:131.121.188.1:210/INNOPAC") from diag-set 'ZOOM'
 258 01:28:48-12/10 [irspy] tcp:131.121.188.1:210/INNOPAC has no more tests: removing
 259 01:28:48-12/10 [irspy] tcp:131.121.188.1:210/INNOPAC rewriting XML record
 260 01:28:55-12/10 [irspy] tcp:131.121.188.1:210/INNOPAC rewrote XML record
 261 01:28:55-12/10 [irspy] tcp:dtslib.dts.edu:2200/Unicorn query 'rec.id="Z39.50:dtslib.dts.edu:2200/Unicorn"' found 1 record
 262 01:28:55-12/10 [irspy] tcp:dtslib.dts.edu:2200/Unicorn entering active pool - 2357 targets remain in queue
 263 01:28:56-12/10 [irspy] tcp:134.99.136.10:2020/opac not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:134.99.136.10:2020/opac") from diag-set 'ZOOM'
 264 01:28:56-12/10 [irspy] tcp:134.99.136.10:2020/opac has no more tests: removing
 265 01:28:56-12/10 [irspy] tcp:134.99.136.10:2020/opac rewriting XML record
 266 01:29:06-12/10 [irspy] tcp:134.99.136.10:2020/opac rewrote XML record
 267 01:29:06-12/10 [irspy] tcp:ecco.easterncluster.com:7090/Voyager query 'rec.id="Z39.50:ecco.easterncluster.com:7090/Voyager"' found 1 record
 268 01:29:07-12/10 [irspy] tcp:ecco.easterncluster.com:7090/Voyager entering active pool - 2355 targets remain in queue
 269 01:29:08-12/10 [irspy] tcp:137.150.180.11:210/MAIN*BIBMAST not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:137.150.180.11:210/MAIN*BIBMAST") from diag-set 'ZOOM'
 270 01:29:08-12/10 [irspy] tcp:137.150.180.11:210/MAIN*BIBMAST has no more tests: removing
 271 01:29:08-12/10 [irspy] tcp:137.150.180.11:210/MAIN*BIBMAST rewriting XML record
 272 01:29:15-12/10 [irspy] tcp:137.150.180.11:210/MAIN*BIBMAST rewrote XML record
 273 01:29:15-12/10 [irspy] tcp:gil.atlm.peachnet.edu:7290/Voyager query 'rec.id="Z39.50:gil.atlm.peachnet.edu:7290/Voyager"' found 1 record
 274 01:29:16-12/10 [irspy] tcp:gil.atlm.peachnet.edu:7290/Voyager entering active pool - 2332 targets remain in queue
 275 01:29:16-12/10 [irspy] tcp:138.231.48.2:21210/MAIN*BIBMAST not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:138.231.48.2:21210/MAIN*BIBMAST") from diag-set 'ZOOM'
 276 01:29:16-12/10 [irspy] tcp:138.231.48.2:21210/MAIN*BIBMAST has no more tests: removing
 277 01:29:16-12/10 [irspy] tcp:138.231.48.2:21210/MAIN*BIBMAST rewriting XML record
 278 01:29:25-12/10 [irspy] tcp:138.231.48.2:21210/MAIN*BIBMAST rewrote XML record
 279 01:29:25-12/10 [irspy] tcp:gil.gc.peachnet.edu:7690/Voyager query 'rec.id="Z39.50:gil.gc.peachnet.edu:7690/Voyager"' found 1 record
 280 01:29:27-12/10 [irspy] tcp:gil.gc.peachnet.edu:7690/Voyager entering active pool - 2327 targets remain in queue
 281 01:29:27-12/10 [irspy] tcp:155.135.4.1:210/MAIN*BIBMAST not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:155.135.4.1:210/MAIN*BIBMAST") from diag-set 'ZOOM'
 282 01:29:27-12/10 [irspy] tcp:155.135.4.1:210/MAIN*BIBMAST has no more tests: removing
 283 01:29:27-12/10 [irspy] tcp:155.135.4.1:210/MAIN*BIBMAST rewriting XML record
 284 01:29:38-12/10 [irspy] tcp:155.135.4.1:210/MAIN*BIBMAST rewrote XML record
 285 01:29:38-12/10 [irspy] tcp:gil.guc.peachnet.edu:7890/Voyager query 'rec.id="Z39.50:gil.guc.peachnet.edu:7890/Voyager"' found 1 record
 286 01:29:40-12/10 [irspy] tcp:gil.guc.peachnet.edu:7890/Voyager entering active pool - 2323 targets remain in queue
 287 01:29:40-12/10 [irspy] tcp:156.108.164.118:210/MANCOSSERVER not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:156.108.164.118:210/MANCOSSERVER") from diag-set 'ZOOM'
 288 01:29:40-12/10 [irspy] tcp:156.108.164.118:210/MANCOSSERVER has no more tests: removing
 289 01:29:40-12/10 [irspy] tcp:156.108.164.118:210/MANCOSSERVER rewriting XML record
 290 01:29:54-12/10 [irspy] tcp:156.108.164.118:210/MANCOSSERVER rewrote XML record
 291 01:29:55-12/10 [irspy] tcp:gil.uga.edu:7090/Voyager query 'rec.id="Z39.50:gil.uga.edu:7090/Voyager"' found 2 records
 292 01:29:56-12/10 [irspy] tcp:gil.uga.edu:7090/Voyager entering active pool - 2319 targets remain in queue
 293 01:29:57-12/10 [irspy] tcp:159.189.81.130:5333/nbii_metadata not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:159.189.81.130:5333/nbii_metadata") from diag-set 'ZOOM'
 294 01:29:57-12/10 [irspy] tcp:159.189.81.130:5333/nbii_metadata has no more tests: removing
 295 01:29:57-12/10 [irspy] tcp:159.189.81.130:5333/nbii_metadata rewriting XML record
 296 01:30:22-12/10 [irspy] tcp:159.189.81.130:5333/nbii_metadata rewrote XML record
 297 01:30:25-12/10 [irspy] tcp:gil.valdosta.edu:7890/Voyager query 'rec.id="Z39.50:gil.valdosta.edu:7890/Voyager"' found 1 record
 298 01:30:25-12/10 [irspy] tcp:gil.valdosta.edu:7890/Voyager entering active pool - 2318 targets remain in queue
 299 01:30:26-12/10 [irspy] tcp:192.54.81.3:210/NEU not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:192.54.81.3:210/NEU") from diag-set 'ZOOM'
 300 01:30:26-12/10 [irspy] tcp:192.54.81.3:210/NEU has no more tests: removing
 301 01:30:26-12/10 [irspy] tcp:192.54.81.3:210/NEU rewriting XML record
 302 01:30:43-12/10 [irspy] tcp:192.54.81.3:210/NEU rewrote XML record
 303 01:30:43-12/10 [irspy] tcp:hml.lib.hawaii.edu:7090/Voyager query 'rec.id="Z39.50:hml.lib.hawaii.edu:7090/Voyager"' found 1 record
 304 01:30:43-12/10 [irspy] tcp:hml.lib.hawaii.edu:7090/Voyager entering active pool - 2310 targets remain in queue
 305 01:30:43-12/10 [irspy] tcp:192.54.81.3:210/OTE not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:192.54.81.3:210/OTE") from diag-set 'ZOOM'
 306 01:30:43-12/10 [irspy] tcp:192.54.81.3:210/OTE has no more tests: removing
 307 01:30:43-12/10 [irspy] tcp:192.54.81.3:210/OTE rewriting XML record
 308 01:30:57-12/10 [irspy] tcp:192.54.81.3:210/OTE rewrote XML record
 309 01:30:57-12/10 [irspy] tcp:honolulucc.lib.hawaii.edu:7090/Voyager query 'rec.id="Z39.50:honolulucc.lib.hawaii.edu:7090/Voyager"' found 1 record
 310 01:30:57-12/10 [irspy] tcp:honolulucc.lib.hawaii.edu:7090/Voyager entering active pool - 2309 targets remain in queue
 311 01:30:58-12/10 [irspy] tcp:192.54.81.3:210/TRI not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:192.54.81.3:210/TRI") from diag-set 'ZOOM'
 312 01:30:58-12/10 [irspy] tcp:192.54.81.3:210/TRI has no more tests: removing
 313 01:30:58-12/10 [irspy] tcp:192.54.81.3:210/TRI rewriting XML record
 314 01:31:08-12/10 [irspy] tcp:192.54.81.3:210/TRI rewrote XML record
 315 01:31:08-12/10 [irspy] tcp:jculib.jcu.edu.au:210/database query 'rec.id="Z39.50:jculib.jcu.edu.au:210/database"' found 1 record
 316 01:31:08-12/10 [irspy] tcp:jculib.jcu.edu.au:210/database entering active pool - 2288 targets remain in queue
 317 01:31:10-12/10 [irspy] tcp:192.84.155.210:9909/PIS01 not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:192.84.155.210:9909/PIS01") from diag-set 'ZOOM'
 318 01:31:10-12/10 [irspy] tcp:192.84.155.210:9909/PIS01 has no more tests: removing
 319 01:31:10-12/10 [irspy] tcp:192.84.155.210:9909/PIS01 rewriting XML record
 320 01:31:16-12/10 [irspy] tcp:192.84.155.210:9909/PIS01 rewrote XML record
 321 01:31:16-12/10 [irspy] tcp:labordoc.ilo.org:7090/Voyager query 'rec.id="Z39.50:labordoc.ilo.org:7090/Voyager"' found 1 record
 322 01:31:17-12/10 [irspy] tcp:labordoc.ilo.org:7090/Voyager entering active pool - 2282 targets remain in queue
 323 01:31:17-12/10 [irspy] tcp:193.162.227.226:210/Semibasen not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:193.162.227.226:210/Semibasen") from diag-set 'ZOOM'
 324 01:31:17-12/10 [irspy] tcp:193.162.227.226:210/Semibasen has no more tests: removing
 325 01:31:17-12/10 [irspy] tcp:193.162.227.226:210/Semibasen rewriting XML record
 326 01:31:23-12/10 [irspy] tcp:193.162.227.226:210/Semibasen rewrote XML record
 327 01:31:23-12/10 [irspy] tcp:libcat.dartmouth.edu:210/INNOPAC query 'rec.id="Z39.50:libcat.dartmouth.edu:210/INNOPAC"' found 1 record
 328 01:31:23-12/10 [irspy] tcp:libcat.dartmouth.edu:210/INNOPAC entering active pool - 2265 targets remain in queue
 329 01:31:24-12/10 [irspy] tcp:193.30.112.113:9909/HBZ01 not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:193.30.112.113:9909/HBZ01") from diag-set 'ZOOM'
 330 01:31:24-12/10 [irspy] tcp:193.30.112.113:9909/HBZ01 has no more tests: removing
 331 01:31:24-12/10 [irspy] tcp:193.30.112.113:9909/HBZ01 rewriting XML record
 332 01:31:34-12/10 [irspy] tcp:193.30.112.113:9909/HBZ01 rewrote XML record
 333 01:31:34-12/10 [irspy] tcp:libor.pstu.ru:210/test query 'rec.id="Z39.50:libor.pstu.ru:210/test"' found 1 record
 334 01:31:34-12/10 [irspy] tcp:libor.pstu.ru:210/test entering active pool - 2258 targets remain in queue
 335 01:31:37-12/10 [irspy] tcp:194.190.186.119:2210/liber not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:194.190.186.119:2210/liber") from diag-set 'ZOOM'
 336 01:31:37-12/10 [irspy] tcp:194.190.186.119:2210/liber has no more tests: removing
 337 01:31:37-12/10 [irspy] tcp:194.190.186.119:2210/liber rewriting XML record
 338 01:31:45-12/10 [irspy] tcp:194.190.186.119:2210/liber rewrote XML record
 339 01:31:45-12/10 [irspy] tcp:library.chowan.edu:210/INNOPAC query 'rec.id="Z39.50:library.chowan.edu:210/INNOPAC"' found 1 record
 340 01:31:45-12/10 [irspy] tcp:library.chowan.edu:210/INNOPAC entering active pool - 2244 targets remain in queue
 341 01:31:46-12/10 [irspy] tcp:195.31.151.66:2100/guggenheim not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:195.31.151.66:2100/guggenheim") from diag-set 'ZOOM'
 342 01:31:46-12/10 [irspy] tcp:195.31.151.66:2100/guggenheim has no more tests: removing
 343 01:31:46-12/10 [irspy] tcp:195.31.151.66:2100/guggenheim rewriting XML record
 344 01:31:57-12/10 [irspy] tcp:195.31.151.66:2100/guggenheim rewrote XML record
 345 01:31:57-12/10 [irspy] tcp:library.ci.hayward.ca.us:210/INNOPAC query 'rec.id="Z39.50:library.ci.hayward.ca.us:210/INNOPAC"' found 1 record
 346 01:31:57-12/10 [irspy] tcp:library.ci.hayward.ca.us:210/INNOPAC entering active pool - 2242 targets remain in queue
 347 01:32:00-12/10 [irspy] tcp:207.63.246.34:210/Dynix not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:207.63.246.34:210/Dynix") from diag-set 'ZOOM'
 348 01:32:00-12/10 [irspy] tcp:207.63.246.34:210/Dynix has no more tests: removing
 349 01:32:00-12/10 [irspy] tcp:207.63.246.34:210/Dynix rewriting XML record
 350 01:32:09-12/10 [irspy] tcp:207.63.246.34:210/Dynix rewrote XML record
 351 01:32:09-12/10 [irspy] tcp:library.co.chesterfield.va.us:210/INNOPAC query 'rec.id="Z39.50:library.co.chesterfield.va.us:210/INNOPAC"' found 1 record
 352 01:32:09-12/10 [irspy] tcp:library.co.chesterfield.va.us:210/INNOPAC entering active pool - 2240 targets remain in queue
 353 01:32:09-12/10 [irspy] tcp:207.64.156.8:210/dynix not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:207.64.156.8:210/dynix") from diag-set 'ZOOM'
 354 01:32:09-12/10 [irspy] tcp:207.64.156.8:210/dynix has no more tests: removing
 355 01:32:10-12/10 [irspy] tcp:207.64.156.8:210/dynix rewriting XML record
 356 01:32:23-12/10 [irspy] tcp:207.64.156.8:210/dynix rewrote XML record
 357 01:32:24-12/10 [irspy] tcp:library.ecu.edu.au:210/INNOPAC query 'rec.id="Z39.50:library.ecu.edu.au:210/INNOPAC"' found 1 record
 358 01:32:24-12/10 [irspy] tcp:library.ecu.edu.au:210/INNOPAC entering active pool - 2232 targets remain in queue
 359 01:32:27-12/10 [irspy] tcp:208.24.160.61:210/LOCAL not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:208.24.160.61:210/LOCAL") from diag-set 'ZOOM'
 360 01:32:27-12/10 [irspy] tcp:208.24.160.61:210/LOCAL has no more tests: removing
 361 01:32:27-12/10 [irspy] tcp:208.24.160.61:210/LOCAL rewriting XML record
 362 01:32:37-12/10 [irspy] tcp:208.24.160.61:210/LOCAL rewrote XML record
 363 01:32:37-12/10 [irspy] tcp:library.ied.edu.hk:210/INNOPAC query 'rec.id="Z39.50:library.ied.edu.hk:210/INNOPAC"' found 1 record
 364 01:32:37-12/10 [irspy] tcp:library.ied.edu.hk:210/INNOPAC entering active pool - 2228 targets remain in queue
 365 01:32:37-12/10 [irspy] tcp:212.45.5.164:2210/liber.fr not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:212.45.5.164:2210/liber.fr") from diag-set 'ZOOM'
 366 01:32:37-12/10 [irspy] tcp:212.45.5.164:2210/liber.fr has no more tests: removing
 367 01:32:37-12/10 [irspy] tcp:212.45.5.164:2210/liber.fr rewriting XML record
 368 01:32:48-12/10 [irspy] tcp:212.45.5.164:2210/liber.fr rewrote XML record
 369 01:32:49-12/10 [irspy] tcp:library.lipscomb.edu:210/INNOPAC query 'rec.id="Z39.50:library.lipscomb.edu:210/INNOPAC"' found 1 record
 370 01:32:49-12/10 [irspy] tcp:library.lipscomb.edu:210/INNOPAC entering active pool - 2221 targets remain in queue
 371 01:32:50-12/10 [irspy] tcp:213.151.79.29:9999/UZPI not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:213.151.79.29:9999/UZPI") from diag-set 'ZOOM'
 372 01:32:50-12/10 [irspy] tcp:213.151.79.29:9999/UZPI has no more tests: removing
 373 01:32:50-12/10 [irspy] tcp:213.151.79.29:9999/UZPI rewriting XML record
 374 01:33:05-12/10 [irspy] tcp:213.151.79.29:9999/UZPI rewrote XML record
 375 01:33:06-12/10 [irspy] tcp:library.ltcc.edu:7090/Voyager query 'rec.id="Z39.50:library.ltcc.edu:7090/Voyager"' found 1 record
 376 01:33:07-12/10 [irspy] tcp:library.ltcc.edu:7090/Voyager entering active pool - 2219 targets remain in queue
 377 01:33:07-12/10 [irspy] tcp:bthnt.bth.rwth-aachen.de:2020/gkneu not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:bthnt.bth.rwth-aachen.de:2020/gkneu") from diag-set 'ZOOM'
 378 01:33:07-12/10 [irspy] tcp:bthnt.bth.rwth-aachen.de:2020/gkneu has no more tests: removing
 379 01:33:07-12/10 [irspy] tcp:bthnt.bth.rwth-aachen.de:2020/gkneu rewriting XML record
 380 01:33:19-12/10 [irspy] tcp:bthnt.bth.rwth-aachen.de:2020/gkneu rewrote XML record
 381 01:33:19-12/10 [irspy] tcp:lispac.lsbu.ac.uk:210/INNOPAC query 'rec.id="Z39.50:lispac.lsbu.ac.uk:210/INNOPAC"' found 1 record
 382 01:33:19-12/10 [irspy] tcp:lispac.lsbu.ac.uk:210/INNOPAC entering active pool - 2212 targets remain in queue
 383 01:33:21-12/10 [irspy] tcp:ads.ahds.ac.uk:2100/default not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:ads.ahds.ac.uk:2100/default") from diag-set 'ZOOM'
 384 01:33:21-12/10 [irspy] tcp:ads.ahds.ac.uk:2100/default has no more tests: removing
 385 01:33:21-12/10 [irspy] tcp:ads.ahds.ac.uk:2100/default rewriting XML record
 386 01:33:42-12/10 [irspy] tcp:ads.ahds.ac.uk:2100/default rewrote XML record
 387 01:33:44-12/10 [irspy] tcp:malvine.bn.pt:2100/Default query 'rec.id="Z39.50:malvine.bn.pt:2100/Default"' found 1 record
 388 01:33:45-12/10 [irspy] tcp:malvine.bn.pt:2100/Default entering active pool - 2206 targets remain in queue
 389 01:33:45-12/10 [irspy] tcp:bpavila.bcl.jcyl.es:210/AbsysE converted 'ABSYS/YAZ / Biblioteca Pública de Avila                     ' from Latin-1 to UTF-8
 390 01:33:46-12/10 [irspy] tcp:cat.libraries.psu.edu:2200/Unicorn not connected: ZOOM error 10005 "Init rejected" from diag-set 'ZOOM'
 391 01:33:46-12/10 [irspy] tcp:cat.libraries.psu.edu:2200/Unicorn has no more tests: removing
 392 01:33:46-12/10 [irspy] tcp:cat.libraries.psu.edu:2200/Unicorn rewriting XML record
 393 01:34:00-12/10 [irspy] tcp:cat.libraries.psu.edu:2200/Unicorn rewrote XML record
 394 01:34:00-12/10 [irspy] tcp:othmerlib.chemheritage.org:210/INNOPAC query 'rec.id="Z39.50:othmerlib.chemheritage.org:210/INNOPAC"' found 1 record
 395 01:34:00-12/10 [irspy] tcp:othmerlib.chemheritage.org:210/INNOPAC entering active pool - 2186 targets remain in queue
 396 01:34:01-12/10 [irspy] tcp:dtslib.dts.edu:2200/Unicorn not connected: ZOOM error 10005 "Init rejected" from diag-set 'ZOOM'
 397 01:34:01-12/10 [irspy] tcp:dtslib.dts.edu:2200/Unicorn has no more tests: removing
 398 01:34:01-12/10 [irspy] tcp:dtslib.dts.edu:2200/Unicorn rewriting XML record
 399 01:34:17-12/10 [irspy] tcp:dtslib.dts.edu:2200/Unicorn rewrote XML record
 400 01:34:17-12/10 [irspy] tcp:pac.nwrls.lib.fl.us:5666/LSPacZ query 'rec.id="Z39.50:pac.nwrls.lib.fl.us:5666/LSPacZ"' found 1 record
 401 01:34:17-12/10 [irspy] tcp:pac.nwrls.lib.fl.us:5666/LSPacZ entering active pool - 2185 targets remain in queue
 402 01:34:20-12/10 [irspy] tcp:library.ltcc.edu:7090/Voyager not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:library.ltcc.edu:7090/Voyager") from diag-set 'ZOOM'
 403 01:34:20-12/10 [irspy] tcp:library.ltcc.edu:7090/Voyager has no more tests: removing
 404 01:34:20-12/10 [irspy] tcp:library.ltcc.edu:7090/Voyager rewriting XML record
 405 01:34:39-12/10 [irspy] tcp:library.ltcc.edu:7090/Voyager rewrote XML record
 406 01:34:41-12/10 [irspy] tcp:prospero.ahds.ac.uk:8080/ History Data Service query 'rec.id="Z39.50:prospero.ahds.ac.uk:8080/ History Data Service"' found 1 record
 407 01:34:43-12/10 [irspy] tcp:prospero.ahds.ac.uk:8080/ History Data Service entering active pool - 2177 targets remain in queue
 408 01:34:43-12/10 [irspy] tcp:jculib.jcu.edu.au:210/database not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:jculib.jcu.edu.au:210/database") from diag-set 'ZOOM'
 409 01:34:43-12/10 [irspy] tcp:jculib.jcu.edu.au:210/database has no more tests: removing
 410 01:34:43-12/10 [irspy] tcp:jculib.jcu.edu.au:210/database rewriting XML record
 411 01:34:56-12/10 [irspy] tcp:jculib.jcu.edu.au:210/database rewrote XML record
 412 01:34:58-12/10 [irspy] tcp:prospero.ahds.ac.uk:8080/Archeological Data Service query 'rec.id="Z39.50:prospero.ahds.ac.uk:8080/Archeological Data Service"' found 1 record
 413 01:35:00-12/10 [irspy] tcp:prospero.ahds.ac.uk:8080/Archeological Data Service entering active pool - 2176 targets remain in queue
 414 01:35:25-12/10 [irspy] tcp:catalogue.lib.ed.ac.uk:7090/Voyager has no more tests: removing
 415 01:35:25-12/10 [irspy] tcp:catalogue.lib.ed.ac.uk:7090/Voyager rewriting XML record
 416 01:35:40-12/10 [irspy] tcp:catalogue.lib.ed.ac.uk:7090/Voyager rewrote XML record
 417 01:35:40-12/10 [irspy] tcp:swb.bsz-bw.de:210/swblite query 'rec.id="Z39.50:swb.bsz-bw.de:210/swblite"' found 1 record
 418 01:35:41-12/10 [irspy] tcp:swb.bsz-bw.de:210/swblite entering active pool - 2163 targets remain in queue
 419 01:35:42-12/10 [irspy] tcp:biblio.crusca.fi.it:9909/ADC01 has no more tests: removing
 420 01:35:42-12/10 [irspy] tcp:biblio.crusca.fi.it:9909/ADC01 rewriting XML record
 421 01:35:51-12/10 [irspy] tcp:biblio.crusca.fi.it:9909/ADC01 rewrote XML record
 422 01:35:51-12/10 [irspy] tcp:thuvienhaiphu.com.vn:210/tntv query 'rec.id="Z39.50:thuvienhaiphu.com.vn:210/tntv"' found 1 record
 423 01:35:52-12/10 [irspy] tcp:thuvienhaiphu.com.vn:210/tntv entering active pool - 2159 targets remain in queue
 424 01:36:08-12/10 [irspy] tcp:gil.gc.peachnet.edu:7690/Voyager has no more tests: removing
 425 01:36:08-12/10 [irspy] tcp:gil.gc.peachnet.edu:7690/Voyager rewriting XML record
 426 01:36:19-12/10 [irspy] tcp:gil.gc.peachnet.edu:7690/Voyager rewrote XML record
 427 01:36:19-12/10 [irspy] tcp:viking.sirsi.net:2200/Unicorn query 'rec.id="Z39.50:viking.sirsi.net:2200/Unicorn"' found 1 record
 428 01:36:19-12/10 [irspy] tcp:viking.sirsi.net:2200/Unicorn entering active pool - 2150 targets remain in queue
 429 01:36:59-12/10 [irspy] tcp:viking.sirsi.net:2200/Unicorn not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:viking.sirsi.net:2200/Unicorn") from diag-set 'ZOOM'
 430 01:36:59-12/10 [irspy] tcp:viking.sirsi.net:2200/Unicorn has no more tests: removing
 431 01:36:59-12/10 [irspy] tcp:viking.sirsi.net:2200/Unicorn rewriting XML record
 432 01:37:05-12/10 [irspy] tcp:viking.sirsi.net:2200/Unicorn rewrote XML record
 433 01:37:05-12/10 [irspy] tcp:webcat.ci.hurst.tx.us:2200/SIRSI query 'rec.id="Z39.50:webcat.ci.hurst.tx.us:2200/SIRSI"' found 1 record
 434 01:37:05-12/10 [irspy] tcp:webcat.ci.hurst.tx.us:2200/SIRSI entering active pool - 2143 targets remain in queue
 435 01:37:08-12/10 [irspy] tcp:ecco.easterncluster.com:7090/Voyager has no more tests: removing
 436 01:37:08-12/10 [irspy] tcp:ecco.easterncluster.com:7090/Voyager rewriting XML record
 437 01:37:13-12/10 [irspy] tcp:ecco.easterncluster.com:7090/Voyager rewrote XML record
 438 01:37:13-12/10 [irspy] tcp:www-2.sirsi.net:2200/Unicorn query 'rec.id="Z39.50:www-2.sirsi.net:2200/Unicorn"' found 1 record
 439 01:37:13-12/10 [irspy] tcp:www-2.sirsi.net:2200/Unicorn entering active pool - 2134 targets remain in queue
 440 01:37:53-12/10 [irspy] tcp:www-2.sirsi.net:2200/Unicorn not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:www-2.sirsi.net:2200/Unicorn") from diag-set 'ZOOM'
 441 01:37:53-12/10 [irspy] tcp:www-2.sirsi.net:2200/Unicorn has no more tests: removing
 442 01:37:53-12/10 [irspy] tcp:www-2.sirsi.net:2200/Unicorn rewriting XML record
 443 01:38:01-12/10 [irspy] tcp:www-2.sirsi.net:2200/Unicorn rewrote XML record
 444 01:38:01-12/10 [irspy] tcp:www.connetquot.lib.ny.us:2200/Unicorn query 'rec.id="Z39.50:www.connetquot.lib.ny.us:2200/Unicorn"' found 1 record
 445 01:38:01-12/10 [irspy] tcp:www.connetquot.lib.ny.us:2200/Unicorn entering active pool - 2116 targets remain in queue
 446 01:38:03-12/10 [irspy] tcp:gil.guc.peachnet.edu:7890/Voyager has no more tests: removing
 447 01:38:03-12/10 [irspy] tcp:gil.guc.peachnet.edu:7890/Voyager rewriting XML record
 448 01:38:13-12/10 [irspy] tcp:gil.guc.peachnet.edu:7890/Voyager rewrote XML record
 449 01:38:13-12/10 [irspy] tcp:z3950.eb.com:210/XXDEFAULT query 'rec.id="Z39.50:z3950.eb.com:210/XXDEFAULT"' found 1 record
 450 01:38:14-12/10 [irspy] tcp:z3950.eb.com:210/XXDEFAULT entering active pool - 2102 targets remain in queue
 451 01:38:15-12/10 [irspy] tcp:prospero.ahds.ac.uk:8080/ History Data Service not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:prospero.ahds.ac.uk:8080/ History Data Service") from diag-set 'ZOOM'
 452 01:38:15-12/10 [irspy] tcp:prospero.ahds.ac.uk:8080/ History Data Service has no more tests: removing
 453 01:38:15-12/10 [irspy] tcp:prospero.ahds.ac.uk:8080/ History Data Service rewriting XML record
 454 01:38:25-12/10 [irspy] tcp:prospero.ahds.ac.uk:8080/ History Data Service rewrote XML record
 455 01:38:26-12/10 [irspy] tcp:z3950.intute.ac.uk:210/socialsciences query 'rec.id="Z39.50:z3950.intute.ac.uk:210/socialsciences"' found 1 record
 456 01:38:26-12/10 [irspy] tcp:z3950.intute.ac.uk:210/socialsciences entering active pool - 2095 targets remain in queue
 457 01:38:26-12/10 [irspy] tcp:www.connetquot.lib.ny.us:2200/Unicorn not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:www.connetquot.lib.ny.us:2200/Unicorn") from diag-set 'ZOOM'
 458 01:38:26-12/10 [irspy] tcp:www.connetquot.lib.ny.us:2200/Unicorn has no more tests: removing
 459 01:38:26-12/10 [irspy] tcp:www.connetquot.lib.ny.us:2200/Unicorn rewriting XML record
 460 01:38:37-12/10 [irspy] tcp:www.connetquot.lib.ny.us:2200/Unicorn rewrote XML record
 461 01:38:37-12/10 [irspy] tcp:z3950.librariesaustralia.nla.gov.au:210/ANY query 'rec.id="Z39.50:z3950.librariesaustralia.nla.gov.au:210/ANY"' found 1 record
 462 01:38:37-12/10 [irspy] tcp:z3950.librariesaustralia.nla.gov.au:210/ANY entering active pool - 2092 targets remain in queue
 463 01:38:38-12/10 [irspy] tcp:prospero.ahds.ac.uk:8080/Archeological Data Service not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:prospero.ahds.ac.uk:8080/Archeological Data Service") from diag-set 'ZOOM'
 464 01:38:38-12/10 [irspy] tcp:prospero.ahds.ac.uk:8080/Archeological Data Service has no more tests: removing
 465 01:38:38-12/10 [irspy] tcp:prospero.ahds.ac.uk:8080/Archeological Data Service rewriting XML record
 466 01:38:45-12/10 [irspy] tcp:prospero.ahds.ac.uk:8080/Archeological Data Service rewrote XML record
 467 01:38:46-12/10 [irspy] tcp:z3950.regione.liguria.it:2100/cbl query 'rec.id="Z39.50:z3950.regione.liguria.it:2100/cbl"' found 1 record
 468 01:38:46-12/10 [irspy] tcp:z3950.regione.liguria.it:2100/cbl entering active pool - 2088 targets remain in queue
 469 01:38:49-12/10 [irspy] tcp:z3950.librariesaustralia.nla.gov.au:210/ANY not connected: ZOOM error 10005 "Init rejected" from diag-set 'ZOOM'
 470 01:38:49-12/10 [irspy] tcp:z3950.librariesaustralia.nla.gov.au:210/ANY has no more tests: removing
 471 01:38:49-12/10 [irspy] tcp:z3950.librariesaustralia.nla.gov.au:210/ANY rewriting XML record
 472 01:38:57-12/10 [irspy] tcp:z3950.librariesaustralia.nla.gov.au:210/ANY rewrote XML record
 473 01:38:57-12/10 [irspy] tcp:zserv.libfl.ru:210/books query 'rec.id="Z39.50:zserv.libfl.ru:210/books"' found 1 record
 474 01:38:57-12/10 [irspy] tcp:zserv.libfl.ru:210/books entering active pool - 2085 targets remain in queue
 475 01:38:58-12/10 [irspy] tcp:gil.atlm.peachnet.edu:7290/Voyager has no more tests: removing
 476 01:38:58-12/10 [irspy] tcp:gil.atlm.peachnet.edu:7290/Voyager rewriting XML record
 477 01:39:04-12/10 [irspy] tcp:gil.atlm.peachnet.edu:7290/Voyager rewrote XML record
 478 01:39:04-12/10 [irspy] tcp:129.252.41.98:20003/USCAN query 'rec.id="Z39.50:129.252.41.98:20003/USCAN"' found 1 record
 479 01:39:05-12/10 [irspy] tcp:129.252.41.98:20003/USCAN entering active pool - 2077 targets remain in queue
 480 01:39:05-12/10 [irspy] tcp:biblioteca.uc3m.es:2200/unicorn has no more tests: removing
 481 01:39:05-12/10 [irspy] tcp:biblioteca.uc3m.es:2200/unicorn rewriting XML record
 482 01:39:12-12/10 [irspy] tcp:biblioteca.uc3m.es:2200/unicorn rewrote XML record
 483 01:39:12-12/10 [irspy] tcp:208.178.237.47:210/INNOPAC query 'rec.id="Z39.50:208.178.237.47:210/INNOPAC"' found 1 record
 484 01:39:12-12/10 [irspy] tcp:208.178.237.47:210/INNOPAC entering active pool - 2072 targets remain in queue
 485 01:39:13-12/10 [irspy] tcp:thuvienhaiphu.com.vn:210/tntv not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:thuvienhaiphu.com.vn:210/tntv") from diag-set 'ZOOM'
 486 01:39:13-12/10 [irspy] tcp:thuvienhaiphu.com.vn:210/tntv has no more tests: removing
 487 01:39:13-12/10 [irspy] tcp:thuvienhaiphu.com.vn:210/tntv rewriting XML record
 488 01:39:23-12/10 [irspy] tcp:thuvienhaiphu.com.vn:210/tntv rewrote XML record
 489 01:39:23-12/10 [irspy] tcp:blpcz.bl.uk:21021/BLPC-ALL query 'rec.id="Z39.50:blpcz.bl.uk:21021/BLPC-ALL"' found 1 record
 490 01:39:24-12/10 [irspy] tcp:blpcz.bl.uk:21021/BLPC-ALL entering active pool - 2070 targets remain in queue
 491 01:39:24-12/10 [irspy] tcp:blpcz.bl.uk:21021/BLPC-ALL not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:blpcz.bl.uk:21021/BLPC-ALL") from diag-set 'ZOOM'
 492 01:39:24-12/10 [irspy] tcp:blpcz.bl.uk:21021/BLPC-ALL has no more tests: removing
 493 01:39:24-12/10 [irspy] tcp:blpcz.bl.uk:21021/BLPC-ALL rewriting XML record
 494 01:39:30-12/10 [irspy] tcp:blpcz.bl.uk:21021/BLPC-ALL rewrote XML record
 495 01:39:30-12/10 [irspy] tcp:edcsns17.cr.usgs.gov:6675/LANDSAT ETM/EDC query 'rec.id="Z39.50:edcsns17.cr.usgs.gov:6675/LANDSAT ETM/EDC"' found 1 record
 496 01:39:31-12/10 [irspy] tcp:edcsns17.cr.usgs.gov:6675/LANDSAT ETM/EDC entering active pool - 2045 targets remain in queue
 497 01:39:31-12/10 [irspy] tcp:webcat.ci.hurst.tx.us:2200/SIRSI has no more tests: removing
 498 01:39:31-12/10 [irspy] tcp:webcat.ci.hurst.tx.us:2200/SIRSI rewriting XML record
 499 01:39:39-12/10 [irspy] tcp:webcat.ci.hurst.tx.us:2200/SIRSI rewrote XML record
 500 01:39:39-12/10 [irspy] tcp:edcsns17.cr.usgs.gov:6675/LANDSAT_MSS query 'rec.id="Z39.50:edcsns17.cr.usgs.gov:6675/LANDSAT_MSS"' found 1 record
 501 01:39:39-12/10 [irspy] tcp:edcsns17.cr.usgs.gov:6675/LANDSAT_MSS entering active pool - 2044 targets remain in queue
 502 01:39:46-12/10 [irspy] tcp:gil.valdosta.edu:7890/Voyager has no more tests: removing
 503 01:39:46-12/10 [irspy] tcp:gil.valdosta.edu:7890/Voyager rewriting XML record
 504 01:39:51-12/10 [irspy] tcp:gil.valdosta.edu:7890/Voyager rewrote XML record
 505 01:39:52-12/10 [irspy] tcp:infoserver3.ciesin.org:6024/atsdr query 'rec.id="Z39.50:infoserver3.ciesin.org:6024/atsdr"' found 1 record
 506 01:39:53-12/10 [irspy] tcp:infoserver3.ciesin.org:6024/atsdr entering active pool - 2021 targets remain in queue
 507 01:39:53-12/10 [irspy] tcp:infoserver3.ciesin.org:6024/atsdr not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:infoserver3.ciesin.org:6024/atsdr") from diag-set 'ZOOM'
 508 01:39:53-12/10 [irspy] tcp:infoserver3.ciesin.org:6024/atsdr has no more tests: removing
 509 01:39:53-12/10 [irspy] tcp:infoserver3.ciesin.org:6024/atsdr rewriting XML record
 510 01:39:58-12/10 [irspy] tcp:infoserver3.ciesin.org:6024/atsdr rewrote XML record
 511 01:39:58-12/10 [irspy] tcp:iris.lib.demokritos.gr:210/libertas query 'rec.id="Z39.50:iris.lib.demokritos.gr:210/libertas"' found 1 record
 512 01:39:59-12/10 [irspy] tcp:iris.lib.demokritos.gr:210/libertas entering active pool - 2019 targets remain in queue
 513 01:40:05-12/10 [irspy] tcp:aleph.unibas.ch:9909/DSV01 has no more tests: removing
 514 01:40:05-12/10 [irspy] tcp:aleph.unibas.ch:9909/DSV01 rewriting XML record
 515 01:40:26-12/10 [irspy] tcp:aleph.unibas.ch:9909/DSV01 rewrote XML record
 516 01:40:26-12/10 [irspy] tcp:katalog.sub.su.se:7090/voyager query 'rec.id="Z39.50:katalog.sub.su.se:7090/voyager"' found 1 record
 517 01:40:27-12/10 [irspy] tcp:katalog.sub.su.se:7090/voyager entering active pool - 2017 targets remain in queue
 518 01:40:28-12/10 [irspy] tcp:katalog.sub.su.se:7090/voyager not connected: ZOOM error 10003 "Decoding failed" (addinfo: "ODR code 4:53 element= offset=0") from diag-set 'ZOOM'
 519 01:40:28-12/10 [irspy] tcp:katalog.sub.su.se:7090/voyager has no more tests: removing
 520 01:40:28-12/10 [irspy] tcp:katalog.sub.su.se:7090/voyager rewriting XML record
 521 01:40:34-12/10 [irspy] tcp:katalog.sub.su.se:7090/voyager rewrote XML record
 522 01:40:34-12/10 [irspy] tcp:leopard.arbor.edu:210/UNICORN query 'rec.id="Z39.50:leopard.arbor.edu:210/UNICORN"' found 1 record
 523 01:40:36-12/10 [irspy] tcp:leopard.arbor.edu:210/UNICORN entering active pool - 2015 targets remain in queue
 524 01:40:36-12/10 [irspy] tcp:labordoc.ilo.org:7090/Voyager has no more tests: removing
 525 01:40:36-12/10 [irspy] tcp:labordoc.ilo.org:7090/Voyager rewriting XML record
 526 01:40:41-12/10 [irspy] tcp:labordoc.ilo.org:7090/Voyager rewrote XML record
 527 01:40:42-12/10 [irspy] tcp:lib.leeds.ac.uk:210/INNOPAC query 'rec.id="Z39.50:lib.leeds.ac.uk:210/INNOPAC"' found 1 record
 528 01:40:42-12/10 [irspy] tcp:lib.leeds.ac.uk:210/INNOPAC entering active pool - 2014 targets remain in queue
 529 01:40:52-12/10 [irspy] tcp:biblio.iue.it:210/INNOPAC has no more tests: removing
 530 01:40:52-12/10 [irspy] tcp:biblio.iue.it:210/INNOPAC rewriting XML record
 531 01:41:00-12/10 [irspy] tcp:biblio.iue.it:210/INNOPAC rewrote XML record
 532 01:41:01-12/10 [irspy] tcp:lib.yar.ru:210/ypurus query 'rec.id="Z39.50:lib.yar.ru:210/ypurus"' found 1 record
 533 01:41:01-12/10 [irspy] tcp:lib.yar.ru:210/ypurus entering active pool - 2009 targets remain in queue
 534 01:42:34-12/10 [irspy] tcp:zserv.libfl.ru:210/books not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:zserv.libfl.ru:210/books") from diag-set 'ZOOM'
 535 01:42:34-12/10 [irspy] tcp:zserv.libfl.ru:210/books has no more tests: removing
 536 01:42:34-12/10 [irspy] tcp:zserv.libfl.ru:210/books rewriting XML record
 537 01:42:43-12/10 [irspy] tcp:zserv.libfl.ru:210/books rewrote XML record
 538 01:42:43-12/10 [irspy] tcp:library.ballarat.edu.au:210/INNOPAC query 'rec.id="Z39.50:library.ballarat.edu.au:210/INNOPAC"' found 1 record
 539 01:42:43-12/10 [irspy] tcp:library.ballarat.edu.au:210/INNOPAC entering active pool - 2006 targets remain in queue
 540 01:42:43-12/10 [irspy] tcp:208.178.237.47:210/INNOPAC not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:208.178.237.47:210/INNOPAC") from diag-set 'ZOOM'
 541 01:42:44-12/10 [irspy] tcp:208.178.237.47:210/INNOPAC has no more tests: removing
 542 01:42:44-12/10 [irspy] tcp:208.178.237.47:210/INNOPAC rewriting XML record
 543 01:42:54-12/10 [irspy] tcp:208.178.237.47:210/INNOPAC rewrote XML record
 544 01:42:54-12/10 [irspy] tcp:ludvig.bibsent.no:2100/RAE01 query 'rec.id="Z39.50:ludvig.bibsent.no:2100/RAE01"' found 1 record
 545 01:42:55-12/10 [irspy] tcp:ludvig.bibsent.no:2100/RAE01 entering active pool - 1973 targets remain in queue
 546 01:42:56-12/10 [irspy] tcp:129.252.41.98:20003/USCAN not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:129.252.41.98:20003/USCAN") from diag-set 'ZOOM'
 547 01:42:56-12/10 [irspy] tcp:129.252.41.98:20003/USCAN has no more tests: removing
 548 01:42:56-12/10 [irspy] tcp:129.252.41.98:20003/USCAN rewriting XML record
 549 01:43:03-12/10 [irspy] tcp:129.252.41.98:20003/USCAN rewrote XML record
 550 01:43:03-12/10 [irspy] tcp:nedi1.nedi.gov:220/LISD query 'rec.id="Z39.50:nedi1.nedi.gov:220/LISD"' found 1 record
 551 01:43:03-12/10 [irspy] tcp:nedi1.nedi.gov:220/LISD entering active pool - 1955 targets remain in queue
 552 01:43:03-12/10 [irspy] tcp:ludvig.bibsent.no:2100/RAE01 not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:ludvig.bibsent.no:2100/RAE01") from diag-set 'ZOOM'
 553 01:43:03-12/10 [irspy] tcp:ludvig.bibsent.no:2100/RAE01 has no more tests: removing
 554 01:43:03-12/10 [irspy] tcp:ludvig.bibsent.no:2100/RAE01 rewriting XML record
 555 01:43:10-12/10 [irspy] tcp:ludvig.bibsent.no:2100/RAE01 rewrote XML record
 556 01:43:10-12/10 [irspy] tcp:oracle.usu.ru:210/uapa query 'rec.id="Z39.50:oracle.usu.ru:210/uapa"' found 1 record
 557 01:43:11-12/10 [irspy] tcp:oracle.usu.ru:210/uapa entering active pool - 1929 targets remain in queue
 558 01:43:14-12/10 [irspy] tcp:iris.lib.demokritos.gr:210/libertas not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:iris.lib.demokritos.gr:210/libertas") from diag-set 'ZOOM'
 559 01:43:14-12/10 [irspy] tcp:iris.lib.demokritos.gr:210/libertas has no more tests: removing
 560 01:43:14-12/10 [irspy] tcp:iris.lib.demokritos.gr:210/libertas rewriting XML record
 561 01:43:20-12/10 [irspy] tcp:iris.lib.demokritos.gr:210/libertas rewrote XML record
 562 01:43:20-12/10 [irspy] tcp:oracle.usu.ru:210/urgu query 'rec.id="Z39.50:oracle.usu.ru:210/urgu"' found 1 record
 563 01:43:21-12/10 [irspy] tcp:oracle.usu.ru:210/urgu entering active pool - 1925 targets remain in queue
 564 01:43:22-12/10 [irspy] tcp:lib.yar.ru:210/ypurus has no more tests: removing
 565 01:43:22-12/10 [irspy] tcp:lib.yar.ru:210/ypurus rewriting XML record
 566 01:43:26-12/10 [irspy] tcp:lib.yar.ru:210/ypurus rewrote XML record
 567 01:43:26-12/10 [irspy] tcp:owl.cc.uic.edu:210/UICOPAC2 query 'rec.id="Z39.50:owl.cc.uic.edu:210/UICOPAC2"' found 1 record
 568 01:43:27-12/10 [irspy] tcp:owl.cc.uic.edu:210/UICOPAC2 entering active pool - 1923 targets remain in queue
 569 01:43:28-12/10 [irspy] tcp:193.43.102.65:21210/ADVANCE has no more tests: removing
 570 01:43:28-12/10 [irspy] tcp:193.43.102.65:21210/ADVANCE rewriting XML record
 571 01:43:34-12/10 [irspy] tcp:193.43.102.65:21210/ADVANCE rewrote XML record
 572 01:43:34-12/10 [irspy] tcp:salty.slcpl.lib.ut.us:210/INNOPAC query 'rec.id="Z39.50:salty.slcpl.lib.ut.us:210/INNOPAC"' found 1 record
 573 01:43:35-12/10 [irspy] tcp:salty.slcpl.lib.ut.us:210/INNOPAC entering active pool - 1915 targets remain in queue
 574 01:43:41-12/10 [irspy] tcp:biblio15.uned.es:2200/unicorn has no more tests: removing
 575 01:43:41-12/10 [irspy] tcp:biblio15.uned.es:2200/unicorn rewriting XML record
 576 01:43:48-12/10 [irspy] tcp:biblio15.uned.es:2200/unicorn rewrote XML record
 577 01:43:48-12/10 [irspy] tcp:sigma.nkp.cz:9909/AUJ10 query 'rec.id="Z39.50:sigma.nkp.cz:9909/AUJ10"' found 1 record
 578 01:43:49-12/10 [irspy] tcp:sigma.nkp.cz:9909/AUJ10 entering active pool - 1910 targets remain in queue
 579 01:43:49-12/10 [irspy] tcp:leopard.arbor.edu:210/UNICORN not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:leopard.arbor.edu:210/UNICORN") from diag-set 'ZOOM'
 580 01:43:49-12/10 [irspy] tcp:leopard.arbor.edu:210/UNICORN has no more tests: removing
 581 01:43:49-12/10 [irspy] tcp:leopard.arbor.edu:210/UNICORN rewriting XML record
 582 01:43:57-12/10 [irspy] tcp:leopard.arbor.edu:210/UNICORN rewrote XML record
 583 01:43:57-12/10 [irspy] tcp:sosig.ac.uk:2104/sosig query 'rec.id="Z39.50:sosig.ac.uk:2104/sosig"' found 1 record
 584 01:43:58-12/10 [irspy] tcp:sosig.ac.uk:2104/sosig entering active pool - 1906 targets remain in queue
 585 01:43:58-12/10 [irspy] tcp:sigma.nkp.cz:9909/AUJ10 not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:sigma.nkp.cz:9909/AUJ10") from diag-set 'ZOOM'
 586 01:43:58-12/10 [irspy] tcp:sigma.nkp.cz:9909/AUJ10 has no more tests: removing
 587 01:43:58-12/10 [irspy] tcp:sigma.nkp.cz:9909/AUJ10 rewriting XML record
 588 01:44:09-12/10 [irspy] tcp:sigma.nkp.cz:9909/AUJ10 rewrote XML record
 589 01:44:09-12/10 [irspy] tcp:tonb.tsu.ru:9999/LOCAL query 'rec.id="Z39.50:tonb.tsu.ru:9999/LOCAL"' found 1 record
 590 01:44:10-12/10 [irspy] tcp:tonb.tsu.ru:9999/LOCAL entering active pool - 1891 targets remain in queue
 591 01:44:10-12/10 [irspy] tcp:tonb.tsu.ru:9999/LOCAL not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:tonb.tsu.ru:9999/LOCAL") from diag-set 'ZOOM'
 592 01:44:10-12/10 [irspy] tcp:tonb.tsu.ru:9999/LOCAL has no more tests: removing
 593 01:44:10-12/10 [irspy] tcp:tonb.tsu.ru:9999/LOCAL rewriting XML record
 594 01:44:15-12/10 [irspy] tcp:tonb.tsu.ru:9999/LOCAL rewrote XML record
 595 01:44:15-12/10 [irspy] tcp:tonb.tsu.ru:9999/TOMSK query 'rec.id="Z39.50:tonb.tsu.ru:9999/TOMSK"' found 1 record
 596 01:44:16-12/10 [irspy] tcp:tonb.tsu.ru:9999/TOMSK entering active pool - 1890 targets remain in queue
 597 01:44:16-12/10 [irspy] tcp:tonb.tsu.ru:9999/TOMSK not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:tonb.tsu.ru:9999/TOMSK") from diag-set 'ZOOM'
 598 01:44:16-12/10 [irspy] tcp:tonb.tsu.ru:9999/TOMSK has no more tests: removing
 599 01:44:16-12/10 [irspy] tcp:tonb.tsu.ru:9999/TOMSK rewriting XML record
 600 01:44:20-12/10 [irspy] tcp:tonb.tsu.ru:9999/TOMSK rewrote XML record
 601 01:44:20-12/10 [irspy] tcp:www.psp.cz:2100/sntisk*1 query 'rec.id="Z39.50:www.psp.cz:2100/sntisk\*1"' found 1 record
 602 01:44:21-12/10 [irspy] tcp:www.psp.cz:2100/sntisk*1 entering active pool - 1843 targets remain in queue
 603 01:44:30-12/10 [irspy] tcp:194.239.156.133:2100/S has no more tests: removing
 604 01:44:30-12/10 [irspy] tcp:194.239.156.133:2100/S rewriting XML record
 605 01:44:41-12/10 [irspy] tcp:194.239.156.133:2100/S rewrote XML record
 606 01:44:41-12/10 [irspy] tcp:z3950.bibsys.no:2100/PERI query 'rec.id="Z39.50:z3950.bibsys.no:2100/PERI"' found 1 record
 607 01:44:41-12/10 [irspy] tcp:z3950.bibsys.no:2100/PERI entering active pool - 1822 targets remain in queue
 608 01:44:45-12/10 [irspy] tcp:aleph.sismelfirenze.it:9909/FEF01 has no more tests: removing
 609 01:44:45-12/10 [irspy] tcp:aleph.sismelfirenze.it:9909/FEF01 rewriting XML record
 610 01:44:50-12/10 [irspy] tcp:aleph.sismelfirenze.it:9909/FEF01 rewrote XML record
 611 01:44:51-12/10 [irspy] tcp:z3950.fcla.edu:210/CF query 'rec.id="Z39.50:z3950.fcla.edu:210/CF"' found 1 record
 612 01:44:51-12/10 [irspy] tcp:z3950.fcla.edu:210/CF entering active pool - 1818 targets remain in queue
 613 01:44:54-12/10 [irspy] tcp:z3950.fcla.edu:210/CF has no more tests: removing
 614 01:44:54-12/10 [irspy] tcp:z3950.fcla.edu:210/CF rewriting XML record
 615 01:44:59-12/10 [irspy] tcp:z3950.fcla.edu:210/CF rewrote XML record
 616 01:44:59-12/10 [irspy] tcp:z3950.fcla.edu:210/RE query 'rec.id="Z39.50:z3950.fcla.edu:210/RE"' found 1 record
 617 01:44:59-12/10 [irspy] tcp:z3950.fcla.edu:210/RE entering active pool - 1810 targets remain in queue
 618 01:45:00-12/10 [irspy] tcp:lispac.lsbu.ac.uk:210/INNOPAC has no more tests: removing
 619 01:45:00-12/10 [irspy] tcp:lispac.lsbu.ac.uk:210/INNOPAC rewriting XML record
 620 01:45:12-12/10 [irspy] tcp:lispac.lsbu.ac.uk:210/INNOPAC rewrote XML record
 621 01:45:12-12/10 [irspy] tcp:z3950.fcla.edu:210/SR query 'rec.id="Z39.50:z3950.fcla.edu:210/SR"' found 1 record
 622 01:45:12-12/10 [irspy] tcp:z3950.fcla.edu:210/SR entering active pool - 1807 targets remain in queue
 623 01:45:17-12/10 [irspy] tcp:gil.uga.edu:7090/Voyager has no more tests: removing
 624 01:45:17-12/10 [irspy] tcp:gil.uga.edu:7090/Voyager rewriting XML record
 625 01:45:25-12/10 [irspy] tcp:gil.uga.edu:7090/Voyager rewrote XML record
 626 01:45:25-12/10 [irspy] tcp:z3950.lillehammer.folkebibl.no:210/data query 'rec.id="Z39.50:z3950.lillehammer.folkebibl.no:210/data"' found 1 record
 627 01:45:25-12/10 [irspy] tcp:z3950.lillehammer.folkebibl.no:210/data entering active pool - 1795 targets remain in queue
 628 01:45:29-12/10 [irspy] tcp:z3950.fcla.edu:210/RE has no more tests: removing
 629 01:45:29-12/10 [irspy] tcp:z3950.fcla.edu:210/RE rewriting XML record
 630 01:45:35-12/10 [irspy] tcp:z3950.fcla.edu:210/RE rewrote XML record
 631 01:45:35-12/10 [irspy] tcp:z3950.nounb.sci-nnov.ru:210/cat43 query 'rec.id="Z39.50:z3950.nounb.sci-nnov.ru:210/cat43"' found 1 record
 632 01:45:37-12/10 [irspy] tcp:z3950.nounb.sci-nnov.ru:210/cat43 entering active pool - 1793 targets remain in queue
 633 01:45:38-12/10 [irspy] tcp:advance.biblio.polymtl.ca:210/ADVANCE has no more tests: removing
 634 01:45:38-12/10 [irspy] tcp:advance.biblio.polymtl.ca:210/ADVANCE rewriting XML record
 635 01:45:45-12/10 [irspy] tcp:advance.biblio.polymtl.ca:210/ADVANCE rewrote XML record
 636 01:45:45-12/10 [irspy] tcp:z3950.ovid.com:2213/eric query 'rec.id="Z39.50:z3950.ovid.com:2213/eric"' found 1 record
 637 01:45:46-12/10 [irspy] tcp:z3950.ovid.com:2213/eric entering active pool - 1783 targets remain in queue
 638 01:45:49-12/10 [irspy] tcp:z3950.nounb.sci-nnov.ru:210/cat43 not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:z3950.nounb.sci-nnov.ru:210/cat43") from diag-set 'ZOOM'
 639 01:45:49-12/10 [irspy] tcp:z3950.nounb.sci-nnov.ru:210/cat43 has no more tests: removing
 640 01:45:49-12/10 [irspy] tcp:z3950.nounb.sci-nnov.ru:210/cat43 rewriting XML record
 641 01:45:54-12/10 [irspy] tcp:z3950.nounb.sci-nnov.ru:210/cat43 rewrote XML record
 642 01:45:54-12/10 [irspy] tcp:z3950.skagen.integrabib.dk:210/default query 'rec.id="Z39.50:z3950.skagen.integrabib.dk:210/default"' found 1 record
 643 01:45:55-12/10 [irspy] tcp:z3950.skagen.integrabib.dk:210/default entering active pool - 1780 targets remain in queue
 644 01:45:55-12/10 [irspy] tcp:z3950.skagen.integrabib.dk:210/default not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:z3950.skagen.integrabib.dk:210/default") from diag-set 'ZOOM'
 645 01:45:55-12/10 [irspy] tcp:z3950.skagen.integrabib.dk:210/default has no more tests: removing
 646 01:45:55-12/10 [irspy] tcp:z3950.skagen.integrabib.dk:210/default rewriting XML record
 647 01:46:00-12/10 [irspy] tcp:z3950.skagen.integrabib.dk:210/default rewrote XML record
 648 01:46:00-12/10 [irspy] tcp:z3950gw.dimdi.de:2020/ME60 query 'rec.id="Z39.50:z3950gw.dimdi.de:2020/ME60"' found 1 record
 649 01:46:01-12/10 [irspy] tcp:z3950gw.dimdi.de:2020/ME60 entering active pool - 1772 targets remain in queue
 650 01:46:02-12/10 [irspy] tcp:z3950gw.dimdi.de:2020/ME60 not connected: ZOOM error 10005 "Init rejected" from diag-set 'ZOOM'
 651 01:46:02-12/10 [irspy] tcp:z3950gw.dimdi.de:2020/ME60 has no more tests: removing
 652 01:46:02-12/10 [irspy] tcp:z3950gw.dimdi.de:2020/ME60 rewriting XML record
 653 01:46:08-12/10 [irspy] tcp:z3950gw.dimdi.de:2020/ME60 rewrote XML record
 654 01:46:09-12/10 [irspy] tcp:z3950gw.dimdi.de:2020/MK77 query 'rec.id="Z39.50:z3950gw.dimdi.de:2020/MK77"' found 1 record
 655 01:46:09-12/10 [irspy] tcp:z3950gw.dimdi.de:2020/MK77 entering active pool - 1771 targets remain in queue
 656 01:46:09-12/10 [irspy] tcp:z3950gw.dimdi.de:2020/MK77 not connected: ZOOM error 10005 "Init rejected" from diag-set 'ZOOM'
 657 01:46:09-12/10 [irspy] tcp:z3950gw.dimdi.de:2020/MK77 has no more tests: removing
 658 01:46:09-12/10 [irspy] tcp:z3950gw.dimdi.de:2020/MK77 rewriting XML record
 659 01:46:14-12/10 [irspy] tcp:z3950gw.dimdi.de:2020/MK77 rewrote XML record
 660 01:46:14-12/10 [irspy] tcp:zetoc.mimas.ac.uk:2121/ZETOC query 'rec.id="Z39.50:zetoc.mimas.ac.uk:2121/ZETOC"' found 1 record
 661 01:46:15-12/10 [irspy] tcp:zetoc.mimas.ac.uk:2121/ZETOC entering active pool - 1765 targets remain in queue
 662 01:46:22-12/10 [irspy] tcp:hml.lib.hawaii.edu:7090/Voyager has no more tests: removing
 663 01:46:22-12/10 [irspy] tcp:hml.lib.hawaii.edu:7090/Voyager rewriting XML record
 664 01:46:32-12/10 [irspy] tcp:hml.lib.hawaii.edu:7090/Voyager rewrote XML record
 665 01:46:32-12/10 [irspy] tcp:libdb.lib.vsu.ru:210/educ query 'rec.id="Z39.50:libdb.lib.vsu.ru:210/educ"' found 1 record
 666 01:46:33-12/10 [irspy] tcp:libdb.lib.vsu.ru:210/educ entering active pool - 1749 targets remain in queue
 667 01:46:33-12/10 [irspy] tcp:libdb.lib.vsu.ru:210/educ not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:libdb.lib.vsu.ru:210/educ") from diag-set 'ZOOM'
 668 01:46:33-12/10 [irspy] tcp:libdb.lib.vsu.ru:210/educ has no more tests: removing
 669 01:46:33-12/10 [irspy] tcp:libdb.lib.vsu.ru:210/educ rewriting XML record
 670 01:46:38-12/10 [irspy] tcp:libdb.lib.vsu.ru:210/educ rewrote XML record
 671 01:46:38-12/10 [irspy] tcp:krokuss.lanet.lv:9909/nba01 query 'rec.id="Z39.50:krokuss.lanet.lv:9909/nba01"' found 1 record
 672 01:46:39-12/10 [irspy] tcp:krokuss.lanet.lv:9909/nba01 entering active pool - 1736 targets remain in queue
 673 01:46:39-12/10 [irspy] tcp:oracle.usu.ru:210/uapa not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:oracle.usu.ru:210/uapa") from diag-set 'ZOOM'
 674 01:46:39-12/10 [irspy] tcp:oracle.usu.ru:210/uapa has no more tests: removing
 675 01:46:39-12/10 [irspy] tcp:oracle.usu.ru:210/uapa rewriting XML record
 676 01:46:54-12/10 [irspy] tcp:oracle.usu.ru:210/uapa rewrote XML record
 677 01:46:54-12/10 [irspy] tcp:ns1.gbs.spb.ru:212/nplain query 'rec.id="Z39.50:ns1.gbs.spb.ru:212/nplain"' found 1 record
 678 01:46:55-12/10 [irspy] tcp:ns1.gbs.spb.ru:212/nplain entering active pool - 1727 targets remain in queue
 679 01:46:55-12/10 [irspy] tcp:nedi1.nedi.gov:220/LISD not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:nedi1.nedi.gov:220/LISD") from diag-set 'ZOOM'
 680 01:46:55-12/10 [irspy] tcp:nedi1.nedi.gov:220/LISD has no more tests: removing
 681 01:46:55-12/10 [irspy] tcp:nedi1.nedi.gov:220/LISD rewriting XML record
 682 01:47:07-12/10 [irspy] tcp:nedi1.nedi.gov:220/LISD rewrote XML record
 683 01:47:09-12/10 [irspy] tcp:z3950.usu.ru:210/publ query 'rec.id="Z39.50:z3950.usu.ru:210/publ"' found 1 record
 684 01:47:09-12/10 [irspy] tcp:z3950.usu.ru:210/publ entering active pool - 1713 targets remain in queue
 685 01:47:12-12/10 [irspy] tcp:oracle.usu.ru:210/urgu not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:oracle.usu.ru:210/urgu") from diag-set 'ZOOM'
 686 01:47:12-12/10 [irspy] tcp:oracle.usu.ru:210/urgu has no more tests: removing
 687 01:47:12-12/10 [irspy] tcp:oracle.usu.ru:210/urgu rewriting XML record
 688 01:47:22-12/10 [irspy] tcp:oracle.usu.ru:210/urgu rewrote XML record
 689 01:47:23-12/10 [irspy] tcp:z3950.usu.ru:210/ureu query 'rec.id="Z39.50:z3950.usu.ru:210/ureu"' found 1 record
 690 01:47:24-12/10 [irspy] tcp:z3950.usu.ru:210/ureu entering active pool - 1708 targets remain in queue
 691 01:47:24-12/10 [irspy] tcp:owl.cc.uic.edu:210/UICOPAC2 not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:owl.cc.uic.edu:210/UICOPAC2") from diag-set 'ZOOM'
 692 01:47:24-12/10 [irspy] tcp:owl.cc.uic.edu:210/UICOPAC2 has no more tests: removing
 693 01:47:24-12/10 [irspy] tcp:owl.cc.uic.edu:210/UICOPAC2 rewriting XML record
 694 01:47:36-12/10 [irspy] tcp:owl.cc.uic.edu:210/UICOPAC2 rewrote XML record
 695 01:47:38-12/10 [irspy] tcp:www.benran.ru:210/serns query 'rec.id="Z39.50:www.benran.ru:210/serns"' found 1 record
 696 01:47:38-12/10 [irspy] tcp:www.benran.ru:210/serns entering active pool - 1698 targets remain in queue
 697 01:47:39-12/10 [irspy] tcp:www.psp.cz:2100/sntisk*1 not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:www.psp.cz:2100/sntisk*1") from diag-set 'ZOOM'
 698 01:47:39-12/10 [irspy] tcp:www.psp.cz:2100/sntisk*1 has no more tests: removing
 699 01:47:39-12/10 [irspy] tcp:www.psp.cz:2100/sntisk*1 rewriting XML record
 700 01:47:49-12/10 [irspy] tcp:www.psp.cz:2100/sntisk*1 rewrote XML record
 701 01:47:51-12/10 [irspy] tcp:libr.sibsiu.ru:9999/bibl query 'rec.id="Z39.50:libr.sibsiu.ru:9999/bibl"' found 1 record
 702 01:47:52-12/10 [irspy] tcp:libr.sibsiu.ru:9999/bibl entering active pool - 1690 targets remain in queue
 703 01:47:52-12/10 [irspy] tcp:sosig.ac.uk:2104/sosig not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:sosig.ac.uk:2104/sosig") from diag-set 'ZOOM'
 704 01:47:52-12/10 [irspy] tcp:sosig.ac.uk:2104/sosig has no more tests: removing
 705 01:47:52-12/10 [irspy] tcp:sosig.ac.uk:2104/sosig rewriting XML record
 706 01:48:00-12/10 [irspy] tcp:sosig.ac.uk:2104/sosig rewrote XML record
 707 01:48:01-12/10 [irspy] tcp:libr.sibsiu.ru:9999/stgn query 'rec.id="Z39.50:libr.sibsiu.ru:9999/stgn"' found 1 record
 708 01:48:02-12/10 [irspy] tcp:libr.sibsiu.ru:9999/stgn entering active pool - 1686 targets remain in queue
 709 01:48:02-12/10 [irspy] tcp:salty.slcpl.lib.ut.us:210/INNOPAC not connected: ZOOM error 10000 "Connect failed" (addinfo: "tcp:salty.slcpl.lib.ut.us:210/INNOPAC") from diag-set 'ZOOM'
 710 01:48:02-12/10 [irspy] tcp:salty.slcpl.lib.ut.us:210/INNOPAC has no more tests: removing
 711 01:48:02-12/10 [irspy] tcp:salty.slcpl.lib.ut.us:210/INNOPAC rewriting XML record
 712 01:48:12-12/10 [irspy] tcp:salty.slcpl.lib.ut.us:210/INNOPAC rewrote XML record
 713 01:48:13-12/10 [irspy] tcp:lib.yar.ru:210/ksu query 'rec.id="Z39.50:lib.yar.ru:210/ksu"' found 1 record
 714 01:48:13-12/10 [irspy] tcp:lib.yar.ru:210/ksu entering active pool - 1680 targets remain in queue
 715 01:48:13-12/10 [irspy] tcp:ns1.gbs.spb.ru:212/nplain converted 'RUSLAN Server ìÉÃÅÎÚÉÒÏ×ÁÎ ÄÌÑ: óÁÎËÔ-ðÅÔÅÒÂÕÒÇÓËÁÑ ÇÏÓÕÄÁÒÓÔ×ÅÎÎÁÑ ÂÉÂÌÉÏÔÅËÁ ÄÌÑ ÓÌÅÐÙÈ' from Latin-1 to UTF-8
 716 01:48:20-12/10 [irspy] tcp:z3950.usu.ru:210/publ has no more tests: removing
 717 01:48:20-12/10 [irspy] tcp:z3950.usu.ru:210/publ rewriting XML record
 718 01:48:37-12/10 [irspy] tcp:z3950.usu.ru:210/publ rewrote XML record
 719 01:48:38-12/10 [irspy] tcp:lib.yar.ru:210/yarcls query 'rec.id="Z39.50:lib.yar.ru:210/yarcls"' found 1 record
 720 01:48:39-12/10 [irspy] tcp:lib.yar.ru:210/yarcls entering active pool - 1678 targets remain in queue
 721 01:48:39-12/10 [irspy] tcp:honolulucc.lib.hawaii.edu:7090/Voyager has no more tests: removing
 722 01:48:39-12/10 [irspy] tcp:honolulucc.lib.hawaii.edu:7090/Voyager rewriting XML record
 723 01:48:53-12/10 [irspy] tcp:honolulucc.lib.hawaii.edu:7090/Voyager rewrote XML record
 724 01:48:56-12/10 [irspy] tcp:www.lib.csu.ru:210/periodika query 'rec.id="Z39.50:www.lib.csu.ru:210/periodika"' found 1 record
 725 01:48:57-12/10 [irspy] tcp:www.lib.csu.ru:210/periodika entering active pool - 1665 targets remain in queue
 726 01:49:01-12/10 [irspy] tcp:aleph.mzk.cz:9991/MZK01 has no more tests: removing
 727 01:49:01-12/10 [irspy] tcp:aleph.mzk.cz:9991/MZK01 rewriting XML record
 728 ZOOM error 10007 "Timeout" from diag-set 'ZOOM'
 729