Daily Usenet report for csiph.com

May 17 04:15:00 -- May 18 04:15:00

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

May 17 11:10:03 csiph pgpverify[83761]: pgpverify: verification failed
May 17 20:10:02 csiph controlchan[65293]: Multiple control headers in article <tIdqG-7Gg-27@gated-at.bofh.it>

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 143668 73.6%14.2 MB 60.0%
inn 33056 16.9%7.2 MB 30.4%
innd 15006 7.7%1.7 MB 7.3%
innfeed 3291 1.7%535.5 KB 2.2%
nocem 71 0.0%7.0 KB 0.0%
controlchan 4 0.0%0.6 KB 0.0%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 7 195097 100.0%23.7 MB100.0%

History cache:

ReasonCount%Count
Positive hits 404210 56.5%
Negative hits 279087 39.0%
Do not exist 31953 4.5%
Cache misses 473 0.1%
TOTAL: 4 715723 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.785 0.0%260 0.000 6.865 61.000
article cleanup00:00:00.234 0.0%31774 0.000 0.007 0.027
article logging00:00:05.057 0.0%32374 0.000 0.156 1.875
article parse00:00:07.055 0.0%95440 0.003 0.074 0.928
article write00:03:53.522 0.3%23763 0.200 9.827 57.581
artlog/artcncl00:00:00.002 0.0%210 0.000 0.010 0.143
artlog/artparse00:00:00.310 0.0%520 0.000 0.596 12.667
data move00:00:00.247 0.0%678661 0.000 0.000 0.004
hisgrep/artcncl00:00:00.591 0.0%258 0.000 2.291 42.000
hishave/artcncl00:00:00.160 0.0%260 0.000 0.615 4.300
hishave/artparse00:00:00.003 0.0%517 0.000 0.006 0.091
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:02:52.000 0.2%720533 0.003 0.239 1.301
history sync00:00:05.017 0.0%348 0.000 14.417 86.000
history write00:04:18.860 0.3%31737 0.068 8.156 53.543
hiswrite/artcncl00:00:00.000 0.0%2 0.000 0.000 0.000
hiswrite/artparse00:00:05.554 0.0%517 0.000 10.743 94.500
idle23:39:49.414 98.5%653347 69.500 130.389 338.913
nntp read00:00:03.394 0.0%688384 0.002 0.005 0.066
overview write00:06:50.902 0.5%23763 0.331 17.292 127.862
perl filter00:01:10.808 0.1%31731 1.629 2.232 8.182
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.427 0.0%47590 0.000 0.009 0.035
TOTAL: 24:01:00.17223:59:25.342 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.065 0.0%23805 0.000 0.003 0.014
article prepare00:00:00.006 0.0%29176 0.000 0.000 0.008
article read00:00:01.694 0.0%5026 0.000 0.337 10.571
backlog stats00:00:00.231 0.0%406570 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.148 0.0%327070 0.003 0.007 0.011
data write00:00:01.821 0.0%311869 0.001 0.006 0.012
idle23:54:10.109100.0%406570 139.646 211.649 612.418
status file00:00:00.384 0.0%330 0.000 1.164 40.000
TOTAL: 23:54:27.29523:54:16.458100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.165 0.0%62 2.661 2.661 2.661
idle20:49:23.609 99.5%182188 116.997 411.463570747.333
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:02.077 0.0%376014 0.000 0.006 0.035
readart00:04:57.711 0.4%27345 0.000 10.887 62.000
TOTAL: 20:56:12.41820:54:23.562 99.9%----

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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
newsmaster@carnet.hr000100
TOTAL000100

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1csiph-out.news.weretis.net 3 50848 6627 43558 663 13%51:21:39
2csiph-out.feeder.erje.net 1440 132115 5461 121648 5006 4%118:57:19
3feeder.eternal-september.org 4 29870 3301 25626 943 11%24:26:25
4news-out.mixmin.net 3 24922 1654 22877 391 6%24:08:53
5news-out.aioe.org 4 24017 1639 22315 63 6%26:42:04
6newsfeed.neva.ru 146 31090 1607 28548 935 5%72:09:00
7newsfeed.fu-berlin.de 49 22588 1041 21218 329 4%30:14:43
8news.redatomik.org 1 24550 1018 23523 9 4%26:14:19
9newsfeeds.xmission.com 3 20815 695 20110 10 3%20:35:18
10news.fcku.it 2 10622 488 10024 110 4%23:59:53
11xanadu-bbs.net 14 1575 262 1299 14 16%39:45:13
12news.swapon.de 2 2931 103 2825 3 3%30:51:40
13news.freedyn.net 2 25453 53 25374 26 0%23:59:44
14nntp.pasdenom.info 2 22826 18 22807 1 0%23:59:23
15news.bbs.geek.nz 2 21240 3 21237 0 0%23:59:42
16localhost 36 2 2 0 0 100%00:00:08
TOTAL: 16 1713 445464 23972 412989 8503 5%561:25:23
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1csiph-out.feeder.erje.net22.7 MB216.0 KB27.8 MB50.7 MB 44%5.0 KB
2csiph-out.news.weretis.net19.6 MB0.0 KB7.1 MB26.7 MB 73%3.8 KB
3feeder.eternal-september.org9.3 MB8.2 KB23.9 MB33.2 MB 27%8.0 KB
4news-out.mixmin.net6.8 MB16.4 KB1.2 MB8.0 MB 84%4.0 KB
5newsfeed.neva.ru4.7 MB20.7 KB4.3 MB9.0 MB 52%3.6 KB
6news-out.aioe.org4.2 MB0.0 KB81.0 KB4.3 MB 98%2.6 KB
7news.redatomik.org3.0 MB0.0 KB80.3 KB3.1 MB 97%3.1 KB
8newsfeed.fu-berlin.de2.6 MB0.0 KB880.9 KB3.5 MB 75%2.6 KB
9newsfeeds.xmission.com2.3 MB0.0 KB408.3 KB2.6 MB 84%3.8 KB
10news.fcku.it1.3 MB0.0 KB1.1 MB2.4 MB 54%4.1 KB
11xanadu-bbs.net868.3 KB0.0 KB686.1 KB1.5 MB 55%5.6 KB
12news.swapon.de265.1 KB0.0 KB1.3 MB1.5 MB 16%14.8 KB
13news.freedyn.net229.9 KB0.0 KB56.4 KB286.2 KB 80%3.6 KB
14nntp.pasdenom.info38.0 KB0.0 KB4.1 KB42.2 KB 90%2.2 KB
15news.bbs.geek.nz6.2 KB0.0 KB0.0 KB6.2 KB100%2.1 KB
16localhost2.5 KB0.0 KB0.0 KB2.5 KB100%1.2 KB
TOTAL: 1677.8 MB261.3 KB68.9 MB147.0 MB 52%4.6 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 17 04:15:00 - 04:59:59 622 2.6% 0.233.5 MB 4.5% 1.32
May 17 05:00:00 - 05:59:59 600 2.5% 0.172.5 MB 3.3% 0.72
May 17 06:00:00 - 06:59:59 760 3.2% 0.212.7 MB 3.5% 0.78
May 17 07:00:00 - 07:59:59 1022 4.3% 0.283.3 MB 4.2% 0.93
May 17 08:00:00 - 08:59:59 1133 4.8% 0.313.0 MB 3.8% 0.85
May 17 09:00:00 - 09:59:59 1043 4.4% 0.292.8 MB 3.6% 0.80
May 17 10:00:00 - 10:59:59 1213 5.1% 0.344.2 MB 5.4% 1.20
May 17 11:00:00 - 11:59:59 1098 4.6% 0.303.4 MB 4.4% 0.98
May 17 12:00:00 - 12:59:59 1107 4.7% 0.313.1 MB 4.0% 0.88
May 17 13:00:00 - 13:59:59 1272 5.4% 0.353.7 MB 4.7% 1.05
May 17 14:00:00 - 14:59:59 1226 5.2% 0.343.8 MB 4.9% 1.08
May 17 15:00:00 - 15:59:59 1376 5.8% 0.384.9 MB 6.3% 1.39
May 17 16:00:00 - 16:59:59 1333 5.6% 0.374.5 MB 5.9% 1.29
May 17 17:00:00 - 17:59:59 1283 5.4% 0.364.3 MB 5.5% 1.22
May 17 18:00:00 - 18:59:59 1149 4.8% 0.323.9 MB 5.1% 1.12
May 17 19:00:00 - 19:59:59 1133 4.8% 0.313.0 MB 3.9% 0.86
May 17 20:00:00 - 20:59:59 1260 5.3% 0.353.8 MB 4.9% 1.07
May 17 21:00:00 - 21:59:59 1069 4.5% 0.303.2 MB 4.2% 0.92
May 17 22:00:00 - 22:59:59 920 3.9% 0.263.0 MB 3.8% 0.85
May 17 23:00:00 - 23:59:59 758 3.2% 0.212.3 MB 2.9% 0.64
May 18 00:00:00 - 00:59:59 582 2.5% 0.161.7 MB 2.2% 0.48
May 18 01:00:00 - 01:59:59 577 2.4% 0.161.8 MB 2.4% 0.53
May 18 02:00:00 - 02:59:59 560 2.4% 0.162.6 MB 3.3% 0.73
May 18 03:00:00 - 03:59:59 511 2.2% 0.142.0 MB 2.6% 0.58
May 18 04:00:00 - 04:15:00 136 0.6% 0.15492.6 KB 0.6% 0.55
TOTAL: 24:00:00 23743 100.0% 0.2777.6 MB 100.0% 0.92
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1csiph-out.feeder.erje.net 5047 301 0 27 2 0 0 0 4717
2feeder.eternal-september.org 954 45 0 2 0 0 0 0 907
3newsfeed.neva.ru 931 254 0 5 0 0 0 0 672
4newsfeed.fu-berlin.de 798 14 0 0 0 0 0 0 784
5csiph-out.news.weretis.net 711 220 0 1 0 0 0 0 490
6news-out.mixmin.net 416 0 0 3 0 0 0 0 413
7news.fcku.it 106 13 0 1 0 0 0 0 92
8news-out.aioe.org 69 56 0 0 0 0 0 0 13
9news.freedyn.net 26 6 0 0 0 0 0 0 20
10xanadu-bbs.net 13 0 0 0 0 0 0 0 13
11newsfeeds.xmission.com 12 0 0 0 0 0 0 0 12
12news.redatomik.org 11 0 0 1 0 0 0 0 10
13news.swapon.de 9 0 0 0 0 0 0 0 9
14nntp.pasdenom.info 1 1 0 0 0 0 0 0 0
TOTAL: 14 9104 910 0 40 2 0 0 0 8152

Unwanted newsgroups [Top 20]:

NewsgroupCount
ut.test 147
it-alt.sport.calcio.juventus 116
lada.auto 72
lada.talk 65
mpc.lists.freebsd.ports.bugs 37
it.annunci.immobiliari 34
it.annunci.varie 31
alt.windows7.general 26
mozilla.support.thunderbird 26
lada.commerce 24
gmane.linux.kernel.renesas-soc 24
mozilla.dev.platform 22
mozilla.support.seamonkey 22
mozilla.dev.security.policy 21
it.annunci.commerciali 19
lada.photo 16
gmane.linux.alsa.devel 15
mpc.lists.freebsd.gnome 14
gmane.linux.kernel 14
alt.survival 10
TOTAL: 100 910

Supposedly-moderated groups with unmoderated postings [Top 20]:

GroupsCount
nz.politics.announce 2
TOTAL: 1 2

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 5911
EMP (phn path) 734
EMP (phl) 126
Too many newsgroups 51
User-issued cancel 50
EMP (phn nph) 37
HTML Multipart 36
Scoring filter (8) 22
Binary Payload 18
Scoring filter (10) 9
User-issued spam cancel 4
Scoring filter (9) 4
HTML post 4
HTML Image Tags 4
Scoring filter (13) 4
Scoring filter (12) 3
Too many newsgroups (meow) 2
Scoring filter (14) 2
Scoring filter (15) 2
Binary Image: misplaced gif 1
TOTAL: 23 7027

NoCeM on Spool:

IdGoodBadUniqueTotal
bleachbot@httrack.com6307474
TOTAL: 16307474

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Article posted in the future
csiph-out.feeder.erje.net 3
TOTAL: 1 3
Bad Message-ID's offered
csiph-out.feeder.erje.net 10
TOTAL: 1 10
Huge articles
csiph-out.feeder.erje.net 208
feeder.eternal-september.org 204
csiph-out.news.weretis.net 71
news-out.mixmin.net 11
xanadu-bbs.net 10
newsfeeds.xmission.com 7
newsfeed.fu-berlin.de 3
news.swapon.de 2
TOTAL: 8 516
Including strange strings
newsfeed.fu-berlin.de 461
csiph-out.feeder.erje.net 50
csiph-out.news.weretis.net 43
news-out.mixmin.net 24
newsfeed.neva.ru 7
news.swapon.de 7
news-out.aioe.org 6
news.redatomik.org 3
newsfeeds.xmission.com 2
TOTAL: 9 603
No colon-space in header
csiph-out.feeder.erje.net 3
TOTAL: 1 3
TOTAL: 5 1135

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.bbs.geek.nz 41501 3373 19612 62 0 0 8%23:59:53
2news.freedyn.net 25032 821 22849 13 0 0 3%23:59:53
3xanadu-bbs 24482 706 22890 0 0 0 2%23:59:50
4fu-berlin.de 22425 675 21750 0 0 0 3%23:59:43
5usenet.pasdenom.info 25012 48 22136 1541 0 6 0%23:59:53
6news.redatomik.org 22460 12 22082 2 0 0 0%23:59:53
7aioe 20252 7 19329 2 0 706 0%23:59:06
8xmission 23844 7 22064 0 0 0 0%23:59:53
9weretis.net 16299 6 16179 0 0 0 0%23:59:19
10news.mixmin.net 21571 4 21152 0 0 0 0%23:59:53
11news.swapon.de 2075 2 2055 0 0 4 0%23:59:27
12neva.ru 21442 2 21016 7 0 0 0%23:59:53
13eternal-september 20082 2 19321 0 0 0 0%23:59:53
14news.fcku.it 2311 0 2298 0 0 0 0%22:51:48
15bwh 0 0 0 0 0 23051 0%23:58:42
16erje 18277 0 18172 15 0 0 0%23:59:38
17au2pb 0 0 0 0 0 23049 0%23:58:42
TOTAL: 17 307065 5665 272905 1642 0 46816 1%406:45:19
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.freedyn.net9.2 MB94.1 KB9.2 MB0.1 KB/s11.3 KB23:59:53
2news.bbs.geek.nz9.0 MB393.5 KB9.4 MB0.1 KB/s2.8 KB23:59:53
3xanadu-bbs8.7 MB0.0 KB8.7 MB0.1 KB/s12.7 KB23:59:50
4fu-berlin.de8.7 MB0.0 KB8.7 MB0.1 KB/s13.1 KB23:59:43
5usenet.pasdenom.info217.7 KB10.4 MB10.6 MB0.1 KB/s6.8 KB23:59:53
6news.mixmin.net43.8 KB0.0 KB43.8 KB0.0 KB/s10.9 KB23:59:53
7news.redatomik.org24.8 KB41.5 KB66.3 KB0.0 KB/s4.7 KB23:59:53
8aioe15.4 KB3.4 KB18.8 KB0.0 KB/s2.1 KB23:59:06
9xmission15.1 KB0.0 KB15.1 KB0.0 KB/s2.2 KB23:59:53
10weretis.net9.4 KB0.0 KB9.4 KB0.0 KB/s1.6 KB23:59:19
11news.swapon.de2.5 KB0.0 KB2.5 KB0.0 KB/s1.2 KB23:59:27
12neva.ru2.5 KB25.5 KB28.0 KB0.0 KB/s3.1 KB23:59:53
13eternal-september2.5 KB0.0 KB2.5 KB0.0 KB/s1.2 KB23:59:53
14news.fcku.it0.0 KB0.0 KB0.0 KB0.0 KB/s022:51:48
15bwh0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:42
16erje0.0 KB76.6 KB76.6 KB0.0 KB/s5.1 KB23:59:38
17au2pb0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:42
TOTAL: 1735.9 MB11.0 MB46.9 MB0.0 KB/s6.6 KB406:45:19
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1mtrlpq2314w-lp140-06-70-55-72-7.dsl.bell.ca 150 528119.8 MB 29 0 007:31:41
2144.138.90.254 288 113431.9 KB 101 0 003:42:42
3204-73-239-109.cust.centrio.cz 11 1339.7 KB 5 0 002:26:55
468-185-181-64.dhcp.mdsn.wi.charter.com 22 22.5 KB 2 2 205:42:08
TOTAL: 4 471 540920.3 MB 137 2 220:56:07

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.dsl.bell.ca 150 528119.8 MB 29 0 007:31:41
2unresolved 293 113431.9 KB 101 0 003:42:55
3*.cust.centrio.cz 11 1339.7 KB 5 0 002:26:55
4*.dhcp.mdsn.wi.charter.com 22 22.5 KB 2 2 205:42:08
5*.156.22.98.dynamic.ip.windstream.net 2 00.0 KB 0 0 000:32:22
6*.bbox.io 2 00.0 KB 0 0 001:00:04
TOTAL: 6 480 540920.3 MB 137 2 220:56:07

NNRP auth users [Top 20]:

UserConn
moussa 288
ctomczyk 7
ohlandl 2
TOTAL: 3 297

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
mtrlpq2314w-lp140-06-70-55-72-7.dsl.bell.ca 20.376 10.873 0.00007:31:41
68-185-181-64.dhcp.mdsn.wi.charter.com 1.898 0.466 0.00805:42:08
144.138.90.254 27.384 6.850 1.04903:42:42
204-73-239-109.cust.centrio.cz 1.042 0.187 0.02302:26:55
tempe0.bbox.io 0.235 0.051 0.00001:00:04
h215.156.22.98.dynamic.ip.windstream.net 0.068 0.041 0.00000:32:22
195.38.136.204 0.151 0.008 0.00000:00:05
92.54.28.204 0.134 0.030 0.00000:00:05
212.61.180.204 0.079 0.024 0.00000:00:02
TOTAL: 9 51.367 18.530 1.08020:56:07

Curious NNRP server explorers [Top 20]:

SystemConn
tempe0.bbox.io 2
h215.156.22.98.dynamic.ip.windstream.net 2
92.54.28.204 2
195.38.136.204 2
212.61.180.204 1
TOTAL: 5 9

NNRP client timeouts [Top 20]:

SystemConnPeer
68-185-181-64.dhcp.mdsn.wi.charter.com 6 0
204-73-239-109.cust.centrio.cz 4 0
tempe0.bbox.io 2 0
h215.156.22.98.dynamic.ip.windstream.net 1 0
TOTAL: 4 13 0

Newsgroup request counts (by category):

CategoryCountPct
1alt 3150 58.2%
2de 1399 25.9%
3free 728 13.5%
4comp 127 2.3%
5uk 4 0.1%
6pl 1 0.0%
TOTAL: 6 5409100.0%

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

NewsgroupCount
1alt.free.newsservers 3137
2de.alt.comm.android 1399
3free.usenet 728
4comp.arch.embedded 53
5comp.arch.fpga 19
6comp.os.vms 15
7comp.ai.philosophy 13
8comp.lang.javascript 12
9alt.drugs 6
10comp.arch 4
11comp.sys.ibm.ps2.hardware 4
12uk.rec.drugs.cannabis 4
13alt.drugs.hard 3
14alt.support.chronic-pain 3
15comp.sys.ibm.pc.games.action 2
16comp.sys.ibm.pc.hardware.storage 2
17comp.os.linux.networking 1
18comp.ai.neural-nets 1
19alt.arts.poetry.comments 1
20comp.os.cpm 1
21pl.comp.www 1
TOTAL: 21 5409