Daily Usenet report for csiph.com

Nov 4 04:15:00 -- Nov 5 04:15:00

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

Nov 4 10:50:03 csiph pgpverify[18741]: pgpverify: verification failed
Nov 4 20:36:55 csiph pgpverify[45191]: pgpverify: verification failed
Nov 4 21:28:04 csiph controlchan[194]: Unknown "canceluser" control by juju@juju.net
Nov 4 21:35:59 csiph controlchan[194]: Unknown "canceluser" control by juju@juju.net
Nov 4 21:41:27 csiph controlchan[194]: Unknown "canceluser" control by juju@juju.net

Log entries by program:

Program nameLines%LinesSize%Size
inn 58692 47.6%8.1 MB 57.7%
nnrpd 34286 27.8%2.9 MB 20.8%
innd 28537 23.1%2.7 MB 19.4%
innfeed 1886 1.5%294.0 KB 2.1%
controlchan 10 0.0%1.2 KB 0.0%
pgpverify 2 0.0%0.2 KB 0.0%
TOTAL: 6 123413 100.0%14.0 MB100.0%

History cache:

ReasonCount%Count
Positive hits 276749 45.4%
Negative hits 275084 45.1%
Do not exist 57837 9.5%
Cache misses 431 0.1%
TOTAL: 4 610101 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.432 0.0%237 0.000 1.823 24.333
article cleanup00:00:00.331 0.0%57617 0.000 0.006 0.025
article logging00:00:00.725 0.0%58113 0.000 0.012 0.038
article parse00:00:02.707 0.0%294155 0.004 0.009 0.022
article write00:00:12.606 0.0%27498 0.190 0.458 0.817
artlog/artcncl00:00:00.002 0.0%191 0.000 0.010 0.500
artlog/artparse00:00:00.009 0.0%733 0.000 0.012 1.000
data move00:00:00.324 0.0%761522 0.000 0.000 0.002
hisgrep/artcncl00:00:00.001 0.0%234 0.000 0.004 1.000
hishave/artcncl00:00:00.000 0.0%237 0.000 0.000 0.000
hishave/artparse00:00:00.002 0.0%733 0.000 0.003 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:01.911 0.0%613907 0.000 0.003 0.006
history sync00:00:00.600 0.0%304 0.000 1.974 4.000
history write00:00:17.588 0.0%57612 0.101 0.305 0.409
hiswrite/artcncl00:00:00.000 0.0%3 0.000 0.000 0.000
hiswrite/artparse00:00:00.253 0.0%733 0.000 0.345 4.000
idle23:56:33.888 99.7%735613 32.558 117.173 329.925
nntp read00:00:02.351 0.0%738344 0.001 0.003 0.005
overview write00:00:17.781 0.0%27498 0.120 0.647 2.146
perl filter00:02:22.271 0.2%57612 0.938 2.469 4.874
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.458 0.0%55001 0.000 0.008 0.029
TOTAL: 24:00:29.95723:59:54.240100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.111 0.0%28519 0.000 0.004 0.028
article prepare00:00:00.000 0.0%6056 0.000 0.000 0.000
article read00:00:00.196 0.0%1139 0.000 0.172 11.250
backlog stats00:00:00.118 0.0%302179 0.000 0.000 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.233 0.0%234700 0.001 0.005 0.013
data write00:00:01.611 0.0%210933 0.003 0.008 0.015
idle23:54:52.396100.0%302179 172.906 284.905 689.421
status file00:00:00.094 0.0%192 0.000 0.490 1.000
TOTAL: 23:54:59.97923:54:55.759100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:02.677 0.0%78246 0.000 0.034 1.000
idle264:11:25.182 99.9%110098 0.000 8638.533600631.667
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:01.417 0.0%221627 0.000 0.006 0.571
readart00:00:06.428 0.0%4041 0.000 1.591 1.609
TOTAL: 264:22:20.197264:11:35.704 99.9%----

Control commands to INND:

CommandNumber
L 1
flush 4
flushlogs 2
go 2
logmode 2
mode 146
pause 2
paused 2
reload 2
reserve 2
TOTAL: 10 165

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
admin@openwatcom.news-admin.org000100
moderator@dana.de000100
TOTAL000200

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1feeder.eternal-september.org 32 53050 7081 36243 9726 13%30:26:27
2au2pb.net 1 30527 6466 22900 1161 21%24:32:18
3news-out.mixmin.net 5 34102 3824 27240 3038 11%24:19:33
4xanadu-bbs.net 2 57318 3755 47547 6016 6%24:46:12
5newsfeed.neva.ru 141 70138 2686 57570 9882 3%71:51:55
6newsfeeds.xmission.com 1 27259 1714 25464 81 6%22:51:18
7newsfeed.fu-berlin.de 58 29537 1207 27840 490 4%30:52:19
8nntp.pasdenom.info 7 28132 363 27621 148 1%24:23:45
9news.swapon.de 1 2503 267 2222 14 10%22:18:58
10localhost 26 0 0 0 0 0%00:00:00
TOTAL: 10 274 332566 27363 274647 30556 8%276:22:45
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1feeder.eternal-september.org20.3 MB39.7 KB104.0 MB124.3 MB 16%7.6 KB
2au2pb.net18.3 MB0.0 KB5.7 MB24.0 MB 76%3.2 KB
3xanadu-bbs.net11.4 MB35.3 KB114.5 MB126.0 MB 9%13.2 KB
4news-out.mixmin.net11.1 MB17.4 KB5.8 MB16.9 MB 65%2.5 KB
5newsfeed.neva.ru9.1 MB0.0 KB90.3 MB99.4 MB 9%8.1 KB
6newsfeeds.xmission.com5.2 MB0.0 KB3.2 MB8.3 MB 62%4.7 KB
7newsfeed.fu-berlin.de2.8 MB0.0 KB1.4 MB4.1 MB 66%2.5 KB
8nntp.pasdenom.info844.9 KB0.0 KB793.2 KB1.6 MB 51%3.2 KB
9news.swapon.de720.2 KB0.0 KB33.7 KB753.9 KB 95%2.7 KB
10localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1079.7 MB92.5 KB325.6 MB405.4 MB 19%7.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Nov 4 04:15:00 - 04:59:59 434 1.6% 0.161.4 MB 1.7% 0.52
Nov 4 05:00:00 - 05:59:59 720 2.6% 0.202.3 MB 2.9% 0.66
Nov 4 06:00:00 - 06:59:59 712 2.6% 0.202.6 MB 3.3% 0.75
Nov 4 07:00:00 - 07:59:59 804 2.9% 0.222.2 MB 2.8% 0.62
Nov 4 08:00:00 - 08:59:59 1110 4.0% 0.313.9 MB 4.8% 1.10
Nov 4 09:00:00 - 09:59:59 1245 4.5% 0.353.5 MB 4.4% 1.00
Nov 4 10:00:00 - 10:59:59 1436 5.2% 0.404.5 MB 5.6% 1.28
Nov 4 11:00:00 - 11:59:59 1239 4.5% 0.343.2 MB 4.1% 0.92
Nov 4 12:00:00 - 12:59:59 1310 4.8% 0.363.6 MB 4.6% 1.04
Nov 4 13:00:00 - 13:59:59 1315 4.8% 0.373.5 MB 4.3% 0.98
Nov 4 14:00:00 - 14:59:59 1329 4.8% 0.373.7 MB 4.7% 1.06
Nov 4 15:00:00 - 15:59:59 1526 5.5% 0.424.2 MB 5.2% 1.18
Nov 4 16:00:00 - 16:59:59 1510 5.5% 0.423.9 MB 4.9% 1.10
Nov 4 17:00:00 - 17:59:59 1701 6.2% 0.474.8 MB 6.0% 1.36
Nov 4 18:00:00 - 18:59:59 1751 6.4% 0.494.8 MB 6.1% 1.38
Nov 4 19:00:00 - 19:59:59 1577 5.7% 0.444.5 MB 5.6% 1.27
Nov 4 20:00:00 - 20:59:59 1427 5.2% 0.403.9 MB 4.9% 1.11
Nov 4 21:00:00 - 21:59:59 1374 5.0% 0.383.6 MB 4.5% 1.03
Nov 4 22:00:00 - 22:59:59 1269 4.6% 0.353.6 MB 4.5% 1.03
Nov 4 23:00:00 - 23:59:59 1016 3.7% 0.283.0 MB 3.8% 0.87
Nov 5 00:00:00 - 00:59:59 745 2.7% 0.212.3 MB 2.9% 0.66
Nov 5 01:00:00 - 01:59:59 632 2.3% 0.182.0 MB 2.5% 0.57
Nov 5 02:00:00 - 02:59:59 604 2.2% 0.172.0 MB 2.5% 0.57
Nov 5 03:00:00 - 03:59:59 582 2.1% 0.162.2 MB 2.7% 0.62
Nov 5 04:00:00 - 04:15:00 163 0.6% 0.18552.3 KB 0.7% 0.61
TOTAL: 24:00:00 27531 100.0% 0.3279.8 MB 100.0% 0.95
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 9783 1423 0 0 0 0 0 0 8360
2feeder.eternal-september.org 9683 460 0 3 0 0 0 0 9220
3xanadu-bbs.net 6090 501 0 1 0 0 0 0 5588
4news-out.mixmin.net 3052 17 0 1 0 0 0 0 3034
5au2pb.net 1163 1098 0 0 0 0 0 0 65
6newsfeed.fu-berlin.de 921 50 0 0 0 0 0 0 871
7nntp.pasdenom.info 147 133 0 0 0 0 0 0 14
8newsfeeds.xmission.com 94 54 0 0 0 0 0 0 40
9news.swapon.de 37 0 0 0 0 0 0 0 37
TOTAL: 9 30970 3736 0 5 0 0 0 027229

Unwanted newsgroups [Top 20]:

NewsgroupCount
fa.linux.kernel 796
alt.politics.scorched-earth 250
lada.talk 155
it-alt.sport.calcio.juventus 155
ut.test 137
mailing.freebsd.svn-ports-all 101
mailing.freebsd.svn-ports-head 101
mailing.freebsd.ports-bugs 82
gmane.linux.alsa.devel 74
alt.survival 66
alt.politics 63
mailing.openbsd.tech 59
mailing.freebsd.pkg-fallout 58
mailing.gcc.gcc-cvs 56
fa.openbsd.tech 56
pld.cvs.commit 52
mozilla.support.firefox 47
alt.windows7.general 44
mailing.openbsd.misc 38
mailing.openbsd.source-changes 37
TOTAL: 277 3736

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 20595
EMP (phn path) 3976
EMP (phl) 840
PFM bot misc 207
EMP (phn nph) 147
HTML Multipart 81
Too many newsgroups 60
Binary Image: misplaced jpg 45
HTML post 33
HTML Image Tags 8
Too many newsgroups (low_xpost) 5
Binary Payload 1
TOTAL: 12 25998

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Huge articles
xanadu-bbs.net 594
feeder.eternal-september.org 56
au2pb.net 28
newsfeeds.xmission.com 28
news-out.mixmin.net 19
newsfeed.fu-berlin.de 4
nntp.pasdenom.info 4
TOTAL: 7 733
Including strange strings
newsfeed.fu-berlin.de 395
news.swapon.de 23
feeder.eternal-september.org 21
news-out.mixmin.net 20
au2pb.net 18
newsfeeds.xmission.com 9
xanadu-bbs.net 8
newsfeed.neva.ru 4
TOTAL: 8 498
TOTAL: 2 1231

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1au2pb 21821 189 20858 113 0 0 0%23:59:57
2usenet.pasdenom.info 32604 158 26311 694 0 17 0%23:59:57
3xanadu-bbs 25326 126 23577 29 0 1029 0%23:59:57
4fu-berlin.de 25849 40 25789 20 0 0 0%23:59:57
5xmission 32178 32 25519 6 0 0 0%23:59:57
6news.mixmin.net 24719 30 23604 4 0 1 0%23:59:43
7eternal-september 20767 8 19741 5 0 0 0%23:59:13
8neva.ru 25948 6 24656 50 0 0 0%23:59:57
9news.swapon.de 2314 1 2287 6 0 0 0%23:59:57
TOTAL: 9 211526 590 192342 927 0 1047 0%215:58:35
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1au2pb788.6 KB316.2 KB1.1 MB0.0 KB/s3.7 KB23:59:57
2usenet.pasdenom.info665.4 KB1.9 MB2.5 MB0.0 KB/s3.0 KB23:59:57
3xanadu-bbs583.6 KB57.6 KB641.2 KB0.0 KB/s4.1 KB23:59:57
4fu-berlin.de208.2 KB27.4 KB235.6 KB0.0 KB/s3.9 KB23:59:57
5xmission118.6 KB9.3 KB128.0 KB0.0 KB/s3.4 KB23:59:57
6news.mixmin.net112.6 KB24.6 KB137.2 KB0.0 KB/s4.0 KB23:59:43
7neva.ru31.4 KB250.1 KB281.5 KB0.0 KB/s5.0 KB23:59:57
8eternal-september20.5 KB6.6 KB27.0 KB0.0 KB/s2.1 KB23:59:13
9news.swapon.de3.3 KB15.0 KB18.2 KB0.0 KB/s2.6 KB23:59:57
TOTAL: 92.5 MB2.6 MB5.0 MB0.0 KB/s3.4 KB215:58:35
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1202.89.176.220 288 49136.6 KB 45 0 004:46:34
TOTAL: 1 288 49136.6 KB 45 0 0264:22:11

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 800 49136.6 KB 45 0 0258:00:54
2*.nielsenmedia.com 5 00.0 KB 0 0 002:02:21
3*.chello.nl 16 00.0 KB 0 0 002:28:29
4*.147.22.98.dynamic.ip.windstream.net 1 00.0 KB 0 0 001:00:35
5*.ip-188-165-239.eu 2 00.0 KB 0 0 000:02:44
6*.tampfl.dsl-w.verizon.net 2 00.0 KB 0 0 000:47:06
TOTAL: 6 826 49136.6 KB 45 0 0264:22:11

NNRP auth users [Top 20]:

UserConn
moussa 288
TOTAL: 1 288

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
63.142.120.8 48.514 10.949 0.000198:46:59
162.212.88.214 0.669 0.673 0.00054:27:06
202.89.176.220 27.935 5.754 0.73004:46:34
dhcp-089-099-203-254.chello.nl 1.316 0.220 0.00002:28:29
challenger.nielsenmedia.com 0.429 0.105 0.00002:02:21
h168.147.22.98.dynamic.ip.windstream.net 0.141 0.023 0.00001:00:35
pool-71-98-160-84.tampfl.dsl-w.verizon.net 0.157 0.030 0.00000:47:06
ns342002.ip-188-165-239.eu 0.224 0.058 0.00000:02:44
216.240.144.111 0.254 0.246 0.00000:00:12
69.160.37.54 2.137 0.427 0.00000:00:02
TOTAL: 10 81.776 18.485 0.730264:22:11

Curious NNRP server explorers [Top 20]:

SystemConn
63.142.120.8 482
69.160.37.54 25
dhcp-089-099-203-254.chello.nl 16
challenger.nielsenmedia.com 5
162.212.88.214 4
ns342002.ip-188-165-239.eu 2
pool-71-98-160-84.tampfl.dsl-w.verizon.net 2
h168.147.22.98.dynamic.ip.windstream.net 1
216.240.144.111 1
TOTAL: 9 538

NNRP client timeouts [Top 20]:

SystemConnPeer
challenger.nielsenmedia.com 4 0
h168.147.22.98.dynamic.ip.windstream.net 2 0
pool-71-98-160-84.tampfl.dsl-w.verizon.net 1 0
TOTAL: 3 7 0

Newsgroup request counts (by category):

CategoryCountPct
1comp 49100.0%
TOTAL: 1 49100.0%

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

NewsgroupCount
1comp.os.vms 21
2comp.sys.ibm.pc.games.war-historical 14
3comp.os.linux.networking 3
4comp.ai.neural-nets 3
5comp.arch.embedded 2
6comp.os.msdos.misc 1
7comp.os.os2.misc 1
8comp.ai.philosophy 1
9comp.os.os2.apps 1
10comp.sys.ibm.pc.games.adventure 1
11comp.os.os2.ecomstation 1
TOTAL: 11 49