Daily Usenet report for csiph.com

Oct 4 04:15:00 -- Oct 5 04:15:00

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Oct 4 09:50:13 csiph pgpverify[52590]: pgpverify: verification failed

Log entries by program:

Program nameLines%LinesSize%Size
inn 40369 44.7%6.0 MB 57.0%
nnrpd 32437 35.9%2.7 MB 25.4%
innd 15636 17.3%1.6 MB 15.1%
innfeed 1841 2.0%270.4 KB 2.5%
controlchan 4 0.0%0.4 KB 0.0%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 6 90288 100.0%10.6 MB100.0%

History cache:

ReasonCount%Count
Positive hits 223685 48.0%
Negative hits 199990 42.9%
Do not exist 39648 8.5%
Cache misses 3008 0.6%
TOTAL: 4 466331 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.547 0.0%259 0.000 2.112 29.000
article cleanup00:00:00.231 0.0%39191 0.000 0.006 0.033
article logging00:00:00.488 0.0%39640 0.000 0.012 0.154
article parse00:00:01.529 0.0%178450 0.001 0.009 0.032
article write00:00:05.845 0.0%23663 0.175 0.247 0.786
artlog/artcncl00:00:00.001 0.0%163 0.000 0.006 0.077
artlog/artparse00:00:00.002 0.0%587 0.000 0.003 0.007
data move00:00:00.217 0.0%573524 0.000 0.000 0.002
hisgrep/artcncl00:00:00.064 0.0%258 0.000 0.248 29.000
hishave/artcncl00:00:00.000 0.0%259 0.000 0.000 0.000
hishave/artparse00:00:00.002 0.0%587 0.000 0.003 0.333
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:01.311 0.0%466757 0.000 0.003 0.009
history sync00:00:00.605 0.0%355 0.000 1.704 20.000
history write00:00:13.309 0.0%39190 0.067 0.340 3.616
hiswrite/artcncl00:00:00.003 0.0%1 3.000 3.000 3.000
hiswrite/artparse00:00:00.127 0.0%587 0.000 0.216 18.000
idle23:58:22.244 99.8%564633 32.844 152.847 482.765
nntp read00:00:01.933 0.0%563186 0.001 0.003 0.008
overview write00:00:24.053 0.0%23663 0.101 1.016 5.937
perl filter00:01:08.950 0.1%39190 0.934 1.759 3.483
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.430 0.0%47327 0.000 0.009 0.026
TOTAL: 24:00:54.67224:00:21.891100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.082 0.0%23632 0.000 0.003 0.031
article prepare00:00:00.001 0.0%7308 0.000 0.000 0.008
article read00:00:00.199 0.0%1327 0.000 0.150 22.000
backlog stats00:00:00.106 0.0%276000 0.000 0.000 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.209 0.0%207939 0.000 0.006 0.013
data write00:00:01.600 0.0%186519 0.000 0.009 0.019
idle23:53:30.622100.0%276000 178.947 311.633 838.008
status file00:00:00.077 0.0%174 0.000 0.443 2.000
TOTAL: 23:53:38.66023:53:33.896100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.540 0.0%18966 0.000 0.028 0.116
idle68:08:32.609100.0%50554 0.000 4852.487600145.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:13.625 0.0%435995 0.000 0.031 1.259
readart00:00:04.596 0.0%20959 0.000 0.219 57.000
TOTAL: 68:10:21.27068:08:51.370100.0%----

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
TOTAL000100

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1feeder.eternal-september.org 5 36632 6549 26932 3151 17%24:37:37
2au2pb.net 1 24305 5422 18284 599 22%22:54:01
3xanadu-bbs.net 1 39600 3823 30176 5601 9%23:59:58
4news-out.mixmin.net 4 30876 2944 24976 2956 9%24:12:33
5newsfeed.neva.ru 141 47527 1608 42639 3280 3%71:29:41
6newsfeeds.xmission.com 1 23656 1442 22165 49 6%24:03:12
7newsfeed.fu-berlin.de 42 27692 1339 25896 457 4%30:41:45
8news.swapon.de 4 3886 439 3447 0 11%46:02:58
9nntp.pasdenom.info 4 38903 240 38640 23 0%38:29:26
10localhost 49 9840 1 9839 0 0%00:00:56
TOTAL: 10 252 282917 23807 242994 16116 8%306:32:07
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1feeder.eternal-september.org17.6 MB0.0 KB20.3 MB37.9 MB 46%4.0 KB
2au2pb.net14.3 MB0.0 KB2.7 MB17.0 MB 83%2.9 KB
3xanadu-bbs.net10.7 MB0.0 KB78.9 MB89.6 MB 11%9.7 KB
4news-out.mixmin.net9.6 MB0.0 KB6.9 MB16.5 MB 58%2.9 KB
5newsfeed.neva.ru5.4 MB1.1 KB23.1 MB28.5 MB 19%6.0 KB
6newsfeeds.xmission.com4.3 MB0.0 KB414.6 KB4.7 MB 91%3.2 KB
7newsfeed.fu-berlin.de2.9 MB0.0 KB1.0 MB3.9 MB 73%2.2 KB
8news.swapon.de1.2 MB0.0 KB0.0 KB1.2 MB100%2.8 KB
9nntp.pasdenom.info493.0 KB1.3 KB472.4 KB966.7 KB 51%3.7 KB
10localhost0.9 KB0.0 KB0.0 KB0.9 KB100%0.9 KB
TOTAL: 1066.4 MB2.4 KB133.8 MB200.2 MB 33%5.1 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 4 04:15:00 - 04:59:59 253 1.1% 0.09685.0 KB 1.0% 0.25
Oct 4 05:00:00 - 05:59:59 434 1.8% 0.121.3 MB 2.0% 0.37
Oct 4 06:00:00 - 06:59:59 636 2.7% 0.182.6 MB 3.9% 0.73
Oct 4 07:00:00 - 07:59:59 876 3.7% 0.242.5 MB 3.7% 0.70
Oct 4 08:00:00 - 08:59:59 951 4.0% 0.262.3 MB 3.5% 0.65
Oct 4 09:00:00 - 09:59:59 1114 4.7% 0.312.8 MB 4.3% 0.81
Oct 4 10:00:00 - 10:59:59 1126 4.8% 0.313.0 MB 4.6% 0.86
Oct 4 11:00:00 - 11:59:59 1022 4.3% 0.282.7 MB 4.0% 0.76
Oct 4 12:00:00 - 12:59:59 1167 4.9% 0.323.1 MB 4.7% 0.89
Oct 4 13:00:00 - 13:59:59 1116 4.7% 0.312.9 MB 4.3% 0.82
Oct 4 14:00:00 - 14:59:59 1181 5.0% 0.333.3 MB 4.9% 0.93
Oct 4 15:00:00 - 15:59:59 1314 5.6% 0.363.2 MB 4.8% 0.91
Oct 4 16:00:00 - 16:59:59 1450 6.1% 0.404.2 MB 6.4% 1.20
Oct 4 17:00:00 - 17:59:59 1483 6.3% 0.414.2 MB 6.3% 1.19
Oct 4 18:00:00 - 18:59:59 1520 6.4% 0.424.2 MB 6.3% 1.19
Oct 4 19:00:00 - 19:59:59 1479 6.3% 0.414.0 MB 6.1% 1.14
Oct 4 20:00:00 - 20:59:59 1342 5.7% 0.373.4 MB 5.2% 0.98
Oct 4 21:00:00 - 21:59:59 1214 5.1% 0.343.2 MB 4.9% 0.92
Oct 4 22:00:00 - 22:59:59 890 3.8% 0.252.5 MB 3.7% 0.70
Oct 4 23:00:00 - 23:59:59 673 2.8% 0.191.9 MB 2.9% 0.54
Oct 5 00:00:00 - 00:59:59 690 2.9% 0.192.1 MB 3.1% 0.59
Oct 5 01:00:00 - 01:59:59 507 2.1% 0.141.7 MB 2.6% 0.49
Oct 5 02:00:00 - 02:59:59 593 2.5% 0.162.5 MB 3.8% 0.71
Oct 5 03:00:00 - 03:59:59 489 2.1% 0.141.5 MB 2.3% 0.44
Oct 5 04:00:00 - 04:15:00 134 0.6% 0.15537.7 KB 0.8% 0.60
TOTAL: 24:00:00 23654 100.0% 0.2766.2 MB 100.0% 0.79
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1xanadu-bbs.net 5567 432 0 0 0 0 0 0 5135
2newsfeed.neva.ru 3293 694 0 1 1 0 0 0 2597
3feeder.eternal-september.org 3183 269 0 0 0 0 0 0 2914
4news-out.mixmin.net 2971 4 0 0 0 0 0 0 2967
5newsfeed.fu-berlin.de 792 28 0 0 0 0 0 0 764
6au2pb.net 654 613 0 0 0 0 0 0 41
7newsfeeds.xmission.com 51 42 0 0 0 0 0 0 9
8news.swapon.de 31 0 0 0 0 0 0 0 31
9nntp.pasdenom.info 10 6 0 0 0 0 0 0 4
TOTAL: 9 16552 2088 0 1 1 0 0 014462

Unwanted newsgroups [Top 20]:

NewsgroupCount
fa.linux.kernel 244
alt.politics.scorched-earth 122
alt.politics 114
lada.talk 105
mailing.freebsd.svn-ports-all 69
mailing.freebsd.svn-ports-head 67
it-alt.sport.calcio.juventus 64
free.it.calcio.juventus 51
ut.test 50
mailing.openbsd.source-changes 50
alt.survival 47
pld.cvs.commit 43
mpc.lists.freebsd.ports.bugs 42
mailing.freebsd.ports-bugs 42
mailing.freebsd.pkg-fallout 36
mozilla.support.seamonkey 36
fa.openbsd.source-changes 35
mozilla.dev.tree-alerts 33
mozilla.support.thunderbird 30
mpc.lists.nanog 29
TOTAL: 210 2088

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

GroupsCount
uk.legal.moderated 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 9618
EMP (phn path) 2531
EMP (phl) 914
EMP (phn nph) 127
PFM bot misc 75
Too many newsgroups 57
Binary Image: misplaced jpg 28
HTML post 27
HTML Multipart 17
Binary: misplaced torr 9
Too many newsgroups (low_xpost) 8
User-issued spam cancel 7
Binary Payload 4
HTML Image Tags 3
Binary: misplaced par2 2
Binary: misplaced doc 1
TOTAL: 16 13428

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Huge articles
xanadu-bbs.net 424
feeder.eternal-september.org 94
news-out.mixmin.net 36
au2pb.net 22
newsfeeds.xmission.com 5
newsfeed.fu-berlin.de 3
nntp.pasdenom.info 1
TOTAL: 7 585
Including strange strings
newsfeed.fu-berlin.de 344
news.swapon.de 31
feeder.eternal-september.org 27
news-out.mixmin.net 19
au2pb.net 13
xanadu-bbs.net 10
newsfeed.neva.ru 4
newsfeeds.xmission.com 1
TOTAL: 8 449
TOTAL: 2 1034

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1au2pb 18997 236 17675 179 0 0 1%23:59:50
2usenet.pasdenom.info 30212 187 22588 724 0 15 0%23:59:56
3xanadu-bbs 21205 102 19665 61 0 4 0%23:59:57
4fu-berlin.de 21967 45 21882 40 0 0 0%23:59:56
5neva.ru 23569 22 21792 149 0 0 0%23:59:56
6news.mixmin.net 21463 22 20595 22 0 0 0%23:59:56
7xmission 28282 19 22045 0 0 0 0%23:59:56
8eternal-september 17956 13 16791 6 0 0 0%23:59:55
9news.swapon.de 1939 1 1921 2 0 0 0%23:57:59
TOTAL: 9 185590 647 164954 1183 0 19 0%215:57:21
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1au2pb970.7 KB433.6 KB1.4 MB0.0 KB/s3.4 KB23:59:50
2usenet.pasdenom.info753.7 KB1.4 MB2.1 MB0.0 KB/s2.4 KB23:59:56
3xanadu-bbs563.1 KB127.1 KB690.2 KB0.0 KB/s4.2 KB23:59:57
4fu-berlin.de260.0 KB54.0 KB314.0 KB0.0 KB/s3.7 KB23:59:56
5news.mixmin.net139.9 KB45.1 KB184.9 KB0.0 KB/s4.2 KB23:59:56
6neva.ru120.4 KB618.7 KB739.1 KB0.0 KB/s4.3 KB23:59:56
7eternal-september38.7 KB9.4 KB48.2 KB0.0 KB/s2.5 KB23:59:55
8xmission33.3 KB0.0 KB33.3 KB0.0 KB/s1.8 KB23:59:56
9news.swapon.de1.1 KB4.1 KB5.2 KB0.0 KB/s1.7 KB23:57:59
TOTAL: 92.8 MB2.7 MB5.5 MB0.0 KB/s3.1 KB215:57:21
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1202.89.176.220 288 64172.4 KB 58 0 004:19:03
2tempe0.bbox.io 4 4084.2 KB 6 1 000:13:56
3dhcp-089-099-203-254.chello.nl 16 13.4 KB 1 0 007:33:43
471.94.21.184 14 10.6 KB 1 0 018:04:45
TOTAL: 4 322 106260.7 KB 66 1 068:10:16

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 435 65173.0 KB 59 0 057:05:35
2*.bbox.io 4 4084.2 KB 6 1 000:13:56
3*.chello.nl 16 13.4 KB 1 0 007:33:43
4*.tampfl.dsl-w.verizon.net 8 00.0 KB 0 0 003:17:02
TOTAL: 4 463 106260.7 KB 66 1 068:10:16

NNRP auth users [Top 20]:

UserConn
moussa 288
kev009 1
TOTAL: 2 289

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
71.94.21.184 1.630 0.922 0.00018:04:45
162.212.88.198 0.787 0.448 0.00018:01:15
108.175.241.207 8.580 2.062 0.00016:40:28
dhcp-089-099-203-254.chello.nl 1.438 0.250 0.00007:33:43
202.89.176.220 27.443 5.795 0.72804:19:03
pool-71-98-160-84.tampfl.dsl-w.verizon.net 0.564 0.137 0.00003:17:02
tempe0.bbox.io 1.252 1.009 0.00600:13:56
69.160.37.54 1.839 0.402 0.00000:00:02
TOTAL: 8 43.533 11.025 0.73468:10:16

Curious NNRP server explorers [Top 20]:

SystemConn
108.175.241.207 100
69.160.37.54 25
162.212.88.198 8
pool-71-98-160-84.tampfl.dsl-w.verizon.net 8
TOTAL: 4 141

NNRP unrecognized commands (by host):

SystemConn
71.94.21.184 12
TOTAL: 1 12

NNRP unrecognized commands (by command):

CommandCount
XFEATURE COMPRESS GZIP 12
TOTAL: 1 12

NNRP client timeouts [Top 20]:

SystemConnPeer
dhcp-089-099-203-254.chello.nl 15 0
pool-71-98-160-84.tampfl.dsl-w.verizon.net 6 0
TOTAL: 2 21 0

Newsgroup request counts (by category):

CategoryCountPct
1comp 65 72.2%
2news 24 26.7%
3alt 1 1.1%
TOTAL: 3 90100.0%

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

NewsgroupCount
1comp.os.vms 29
2news.software.nntp 23
3comp.arch.embedded 14
4comp.arch 7
5comp.sys.ibm.pc.games.action 5
6comp.sys.ibm.pc.hardware.storage 3
7comp.arch.fpga 2
8comp.sys.ibm.as400.misc 2
9comp.sys.ibm.pc.games.war-historical 1
10comp.sys.ibm.ps2.hardware 1
11comp.answers 1
12alt.test 1
13news.answers 1
TOTAL: 13 90