Daily Usenet report for csiph.com

Mar 10 04:15:00 -- Mar 11 04:15:00

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Mar 10 12:30:04 csiph controlchan[26125]: Multiple control headers in article <tjrQf-3VY-47@gated-at.bofh.it>

Log entries by program:

Program nameLines%LinesSize%Size
inn 51205 58.9%9.3 MB 70.2%
innd 31413 36.1%3.3 MB 25.2%
innfeed 3166 3.6%501.4 KB 3.7%
nnrpd 1096 1.3%124.8 KB 0.9%
nocem 57 0.1%6.1 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 6 86938 100.0%13.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 439303 54.5%
Negative hits 316403 39.2%
Do not exist 50217 6.2%
Cache misses 831 0.1%
TOTAL: 4 806754 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:02.659 0.0%280 0.000 9.496 77.667
article cleanup00:00:00.264 0.0%42233 0.000 0.006 0.024
article logging00:00:06.491 0.0%42858 0.000 0.151 1.348
article parse00:01:55.717 0.1%1151764 0.005 0.100 0.439
article write00:04:48.395 0.3%24282 0.178 11.877 59.486
artlog/artcncl00:00:00.094 0.0%249 0.000 0.378 4.556
artlog/artparse00:00:03.980 0.0%8342 0.000 0.477 3.857
data move00:00:01.108 0.0%1741011 0.000 0.001 0.005
hisgrep/artcncl00:00:00.452 0.0%226 0.000 2.000 18.889
hishave/artcncl00:00:00.675 0.0%280 0.000 2.411 25.889
hishave/artparse00:00:00.040 0.0%8342 0.000 0.005 0.056
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:03:25.854 0.2%802441 0.002 0.257 1.111
history sync00:00:06.936 0.0%351 0.000 19.761 173.500
history write00:05:32.924 0.4%42146 0.125 7.899 57.143
hiswrite/artcncl00:00:00.466 0.0%54 0.000 8.630 10.594
hiswrite/artparse00:01:36.457 0.1%8342 0.000 11.563 39.600
idle23:33:58.231 98.1%1679766 4.496 50.506 350.640
nntp read00:00:07.474 0.0%1731651 0.002 0.004 0.007
overview write00:07:14.115 0.5%24282 0.692 17.878 288.711
perl filter00:01:43.016 0.1%42074 1.500 2.448 3.565
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.473 0.0%48616 0.000 0.010 0.025
TOTAL: 24:00:47.74624:00:45.821100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.071 0.0%24226 0.000 0.003 0.020
article prepare00:00:00.004 0.0%41896 0.000 0.000 0.011
article read00:00:02.156 0.0%8267 0.014 0.261 13.706
backlog stats00:00:00.201 0.0%431416 0.000 0.000 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.223 0.0%338767 0.002 0.007 0.012
data write00:00:01.896 0.0%324438 0.002 0.006 0.012
idle23:53:21.210100.0%431416 130.716 199.346 667.128
status file00:00:00.689 0.0%293 0.000 2.352 192.000
TOTAL: 23:53:38.87323:53:28.450100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.413 0.0%94 0.000 4.394 68.000
idle65:47:38.074 99.8%632 0.000374775.434900176.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.684 0.0%1373 0.000 0.498 15.667
readart00:00:00.820 0.0%48 0.000 17.083 61.000
TOTAL: 65:56:22.86765:47:39.991 99.8%----

Control commands to INND:

CommandNumber
L 1
flush 5
flushlogs 2
go 2
logmode 2
mode 143
pause 2
paused 2
reload 2
reserve 2
TOTAL: 10 163

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1csiph-out.news.weretis.net 4 54475 6311 46630 1534 11%50:34:59
2csiph-out.feeder.erje.net 1152 130563 6241 115321 9001 4%95:02:43
3feeder.eternal-september.org 32 46593 2981 32247 11365 6%32:33:44
4news-out.aioe.org 2 23637 2323 21269 45 9%24:09:56
5news-out.mixmin.net 3 25697 2150 23033 514 8%24:08:43
6newsfeed.neva.ru 154 51264 1560 47097 2607 3%71:39:35
7newsfeed.fu-berlin.de 55 26612 1251 25116 245 4%35:07:32
8newsfeeds.xmission.com 3 17850 564 17276 10 3%18:43:57
9news.redatomik.org 1 21737 553 21179 5 2%23:04:35
10news.freedyn.net 4 27576 147 27288 141 0%24:08:57
11news.fcku.it 2 10570 122 10299 149 1%23:59:54
12news.swapon.de 4 1912 105 1806 1 5%24:56:21
13nntp.pasdenom.info 2 22951 74 22871 6 0%23:58:42
14xanadu-bbs.net 13 710 35 669 6 4%23:49:02
15localhost 40 7 7 0 0 100%00:00:02
16news.bbs.geek.nz 1 16935 1 16934 0 0%23:59:48
TOTAL: 16 1472 479089 24425 429035 25629 5%519:58:30
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1csiph-out.feeder.erje.net19.8 MB628.8 KB62.1 MB82.5 MB 24%5.5 KB
2csiph-out.news.weretis.net17.1 MB0.0 KB15.4 MB32.5 MB 52%4.2 KB
3news-out.mixmin.net7.8 MB15.3 KB1.4 MB9.2 MB 84%3.6 KB
4feeder.eternal-september.org7.8 MB28.9 KB1.7 GB1.7 GB 0%122.0 KB
5news-out.aioe.org5.7 MB4.1 KB59.7 KB5.7 MB 98%2.5 KB
6newsfeed.neva.ru3.7 MB94.7 KB26.3 MB30.1 MB 12%7.4 KB
7newsfeed.fu-berlin.de2.7 MB0.0 KB550.8 KB3.3 MB 83%2.2 KB
8news.redatomik.org1.7 MB9.0 KB457.2 KB2.1 MB 78%3.9 KB
9newsfeeds.xmission.com1.4 MB0.0 KB613.7 KB2.0 MB 69%3.5 KB
10news.freedyn.net636.7 KB6.3 KB2.5 MB3.2 MB 19%11.2 KB
11news.swapon.de268.3 KB0.0 KB24.5 KB292.8 KB 91%2.8 KB
12nntp.pasdenom.info162.2 KB4.8 KB29.9 KB196.8 KB 82%2.5 KB
13news.fcku.it156.8 KB0.0 KB715.3 KB872.1 KB 17%3.2 KB
14xanadu-bbs.net98.0 KB0.0 KB67.3 KB165.3 KB 59%4.0 KB
15localhost21.9 KB0.0 KB0.0 KB21.9 KB100%3.1 KB
16news.bbs.geek.nz1.9 KB0.0 KB0.0 KB1.9 KB100%1.9 KB
TOTAL: 1669.1 MB791.9 KB1.8 GB1.8 GB 3%38.5 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Mar 10 04:15:00 - 04:59:59 429 1.8% 0.161.2 MB 1.8% 0.46
Mar 10 05:00:00 - 05:59:59 638 2.6% 0.182.3 MB 3.4% 0.65
Mar 10 06:00:00 - 06:59:59 721 3.0% 0.203.2 MB 4.7% 0.91
Mar 10 07:00:00 - 07:59:59 680 2.8% 0.191.8 MB 2.7% 0.52
Mar 10 08:00:00 - 08:59:59 977 4.0% 0.272.4 MB 3.6% 0.69
Mar 10 09:00:00 - 09:59:59 1286 5.3% 0.363.6 MB 5.3% 1.03
Mar 10 10:00:00 - 10:59:59 1186 4.9% 0.333.4 MB 4.9% 0.95
Mar 10 11:00:00 - 11:59:59 1196 4.9% 0.333.1 MB 4.5% 0.87
Mar 10 12:00:00 - 12:59:59 1300 5.4% 0.363.3 MB 4.8% 0.94
Mar 10 13:00:00 - 13:59:59 1405 5.8% 0.393.7 MB 5.4% 1.06
Mar 10 14:00:00 - 14:59:59 1357 5.6% 0.383.5 MB 5.1% 1.00
Mar 10 15:00:00 - 15:59:59 1316 5.4% 0.373.6 MB 5.3% 1.04
Mar 10 16:00:00 - 16:59:59 1369 5.6% 0.384.0 MB 5.9% 1.15
Mar 10 17:00:00 - 17:59:59 1190 4.9% 0.333.2 MB 4.7% 0.92
Mar 10 18:00:00 - 18:59:59 1335 5.5% 0.373.4 MB 5.0% 0.98
Mar 10 19:00:00 - 19:59:59 1128 4.7% 0.313.0 MB 4.4% 0.86
Mar 10 20:00:00 - 20:59:59 1218 5.0% 0.343.3 MB 4.8% 0.94
Mar 10 21:00:00 - 21:59:59 1200 5.0% 0.333.2 MB 4.6% 0.90
Mar 10 22:00:00 - 22:59:59 1082 4.5% 0.302.9 MB 4.2% 0.82
Mar 10 23:00:00 - 23:59:59 894 3.7% 0.252.9 MB 4.2% 0.82
Mar 11 00:00:00 - 00:59:59 744 3.1% 0.212.3 MB 3.3% 0.65
Mar 11 01:00:00 - 01:59:59 542 2.2% 0.151.6 MB 2.4% 0.46
Mar 11 02:00:00 - 02:59:59 536 2.2% 0.151.5 MB 2.3% 0.44
Mar 11 03:00:00 - 03:59:59 420 1.7% 0.121.4 MB 2.0% 0.40
Mar 11 04:00:00 - 04:15:00 88 0.4% 0.10476.7 KB 0.7% 0.53
TOTAL: 24:00:00 24237 100.0% 0.2868.5 MB 100.0% 0.81
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1feeder.eternal-september.org 11957 63 0 5 0 0 0 011889
2csiph-out.feeder.erje.net 9046 323 0 62 0 0 0 0 8661
3newsfeed.neva.ru 2559 301 0 9 0 0 0 0 2249
4csiph-out.news.weretis.net 1522 276 0 0 0 0 0 0 1246
5newsfeed.fu-berlin.de 702 23 0 0 0 0 0 0 679
6news-out.mixmin.net 543 1 0 5 0 0 0 0 537
7news.fcku.it 151 0 0 0 0 0 0 0 151
8news.freedyn.net 134 12 0 1 0 0 0 0 121
9news-out.aioe.org 53 42 0 1 0 0 0 0 10
10newsfeeds.xmission.com 16 0 0 0 0 0 0 0 16
11news.swapon.de 15 0 0 0 0 0 0 0 15
12xanadu-bbs.net 8 0 0 0 0 0 0 0 8
13news.redatomik.org 7 0 0 2 0 0 0 0 5
14nntp.pasdenom.info 6 1 0 2 0 1 0 0 2
TOTAL: 14 26719 1042 0 87 0 1 0 025589

Unwanted newsgroups [Top 20]:

NewsgroupCount
lada.talk 139
ut.test 121
it-alt.sport.calcio.juventus 109
lada.auto 64
alt.courts.pretrial-services 56
mozilla.support.thunderbird 49
gmane.comp.gis.openstreetmap.tagging 42
spline.eisfair.dev 29
mozilla.dev.planning 21
mozilla.dev.platform 21
cakewalk.audio 19
mpc.lists.freebsd.x11 18
gmane.comp.bacula.user 17
mozilla.support.firefox 16
lada.commerce 16
mozilla.general 13
mozilla.dev.security.policy 13
mozilla.support.seamonkey 13
mpc.lists.nanog 10
alt.windows7.general 10
TOTAL: 127 1042

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 14596
EMP (phn path) 1604
Too many newsgroups (meow) 174
EMP (phn nph) 103
Too many newsgroups 60
EMP (phl) 54
HTML Multipart 35
Binary Payload 25
User-issued cancel 24
Scoring filter (8) 14
Scoring filter (15) 13
Scoring filter (14) 10
Binary Image: misplaced jpg 8
Scoring filter (9) 5
HTML post 4
Too many newsgroups (low_xpost) 4
Binary: misplaced nzb 1
Angle-bracket bot 1
Binary: misplaced db 1
Binary: misplaced pdf 1
TOTAL: 20 16737

NoCeM on Spool:

IdGoodBadUniqueTotal
bleachbot@httrack.com5106161
TOTAL: 15106161

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Article posted in the future
csiph-out.feeder.erje.net 71
TOTAL: 1 71
Huge articles
feeder.eternal-september.org 8034
csiph-out.news.weretis.net 60
news.freedyn.net 21
newsfeeds.xmission.com 14
news.fcku.it 12
news-out.mixmin.net 11
newsfeed.fu-berlin.de 3
news.redatomik.org 1
xanadu-bbs.net 1
TOTAL: 9 8157
Including strange strings
newsfeed.fu-berlin.de 450
csiph-out.news.weretis.net 62
csiph-out.feeder.erje.net 40
news-out.mixmin.net 30
news.swapon.de 14
newsfeed.neva.ru 8
news-out.aioe.org 8
feeder.eternal-september.org 7
xanadu-bbs.net 2
news.redatomik.org 2
TOTAL: 11 624
TOTAL: 3 8852

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.bbs.geek.nz 39767 6564 16841 179 0 0 16%23:50:00
2news.freedyn.net 25000 768 23258 1 0 0 3%23:50:00
3xanadu-bbs 26381 609 23550 0 0 14 2%23:55:31
4fu-berlin.de 22699 501 22198 0 0 0 2%23:51:42
5usenet.pasdenom.info 26388 164 22530 1451 0 15 0%23:57:29
6xmission 25572 54 22802 1 0 0 0%23:50:00
7aioe 21017 35 19658 6 0 0 0%23:50:05
8weretis.net 17283 31 16691 0 0 0 0%23:50:00
9news.redatomik.org 24001 29 22959 10 0 0 0%23:50:00
10eternal-september 21278 17 19901 0 0 0 0%23:53:52
11news.mixmin.net 21741 8 20895 0 0 0 0%23:52:19
12neva.ru 22540 6 21687 12 0 0 0%23:50:00
13news.swapon.de 1885 6 1845 2 0 0 0%23:44:24
14erje 18008 1 17849 17 0 0 0%23:50:00
15bwh 0 0 0 0 0 23579 0%23:50:08
16news.fcku.it 3519 0 3386 0 0 0 0%22:54:08
17au2pb 0 0 0 0 0 23594 0%23:50:08
TOTAL: 17 317079 8793 276050 1679 0 47202 2%404:29:46
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.bbs.geek.nz16.6 MB496.8 KB17.1 MB0.2 KB/s2.6 KB23:50:00
2news.freedyn.net5.0 MB1.3 KB5.0 MB0.1 KB/s6.6 KB23:50:00
3xanadu-bbs4.5 MB0.0 KB4.5 MB0.1 KB/s7.6 KB23:55:31
4fu-berlin.de4.3 MB0.0 KB4.3 MB0.1 KB/s8.7 KB23:51:42
5usenet.pasdenom.info440.6 KB5.9 MB6.3 MB0.1 KB/s4.0 KB23:57:29
6xmission191.6 KB1.1 KB192.7 KB0.0 KB/s3.5 KB23:50:00
7aioe101.0 KB12.2 KB113.2 KB0.0 KB/s2.8 KB23:50:05
8weretis.net91.6 KB0.0 KB91.6 KB0.0 KB/s3.0 KB23:50:00
9news.redatomik.org68.5 KB22.4 KB90.8 KB0.0 KB/s2.3 KB23:50:00
10eternal-september47.8 KB0.0 KB47.8 KB0.0 KB/s2.8 KB23:53:52
11news.mixmin.net23.9 KB0.0 KB23.9 KB0.0 KB/s3.0 KB23:52:19
12news.swapon.de10.0 KB16.3 KB26.3 KB0.0 KB/s3.3 KB23:44:24
13neva.ru9.8 KB36.5 KB46.4 KB0.0 KB/s2.6 KB23:50:00
14erje2.1 KB117.1 KB119.2 KB0.0 KB/s6.6 KB23:50:00
15bwh0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:08
16news.fcku.it0.0 KB0.0 KB0.0 KB0.0 KB/s022:54:08
17au2pb0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:08
TOTAL: 1731.3 MB6.5 MB37.9 MB0.0 KB/s3.7 KB404:29:46
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1tempe0.bbox.io 22 2138.4 KB 11 1 005:38:48
268-185-181-64.dhcp.mdsn.wi.charter.com 30 1344.6 KB 10 7 710:35:42
3dhcp-089-099-203-254.chello.nl 17 1140.6 KB 5 0 002:52:16
4ess-p-144-138-90-254.mega.tmns.net.au 3 34.0 KB 1 0 000:06:54
TOTAL: 4 72 48127.6 KB 27 8 765:56:21

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.bbox.io 22 2138.4 KB 11 1 005:38:48
2*.dhcp.mdsn.wi.charter.com 30 1344.6 KB 10 7 710:35:42
3*.chello.nl 17 1140.6 KB 5 0 002:52:16
4*.mega.tmns.net.au 3 34.0 KB 1 0 000:06:54
5unresolved 94 00.0 KB 0 0 046:08:34
6*.cust.centrio.cz 3 00.0 KB 0 0 000:34:04
TOTAL: 6 169 48127.6 KB 27 8 765:56:21

NNRP auth users [Top 20]:

UserConn
ohlandl 7
ctomczyk 2
moussa 2
TOTAL: 3 11

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
216.239.174.218 6.829 1.651 0.00046:08:34
68-185-181-64.dhcp.mdsn.wi.charter.com 2.963 0.610 0.02710:35:42
tempe0.bbox.io 2.070 0.965 0.00405:38:48
dhcp-089-099-203-254.chello.nl 1.566 0.322 0.00002:52:16
204-73-239-109.cust.centrio.cz 0.153 0.039 0.00900:34:04
ess-p-144-138-90-254.mega.tmns.net.au 0.312 0.065 0.00900:06:54
TOTAL: 6 13.893 3.652 0.04965:56:21

Curious NNRP server explorers [Top 20]:

SystemConn
216.239.174.218 94
204-73-239-109.cust.centrio.cz 3
TOTAL: 2 97

NNRP client timeouts [Top 20]:

SystemConnPeer
216.239.174.218 90 0
68-185-181-64.dhcp.mdsn.wi.charter.com 12 0
tempe0.bbox.io 9 0
204-73-239-109.cust.centrio.cz 1 0
TOTAL: 4 112 0

Newsgroup request counts (by category):

CategoryCountPct
1comp 48100.0%
TOTAL: 1 48100.0%

Newsgroup request counts (by newsgroup) [Top 100]:

NewsgroupCount
1comp.sys.ibm.ps2.hardware 28
2comp.sys.m68k 6
3comp.sys.next.marketplace 3
4comp.sys.sgi.hardware 2
5comp.sys.sun.hardware 2
6comp.sys.tandem 2
7comp.sys.powerpc.advocacy 1
8comp.sys.super 1
9comp.unix.aix 1
10comp.sys.sun.wanted 1
11comp.sys.powerpc.tech 1
TOTAL: 11 48