Daily Usenet report for csiph.com

Jul 8 04:15:00 -- Jul 9 04:15:00

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Jul 8 10:52:41 csiph nnrpd[21635]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Jul 8 10:52:41 csiph nnrpd[21635]: error initializing TLS: [CA_file: ] [CA_path: /usr/local/news/etc] [cert_file: /usr/local/news/etc/cert.pem] [key_file: /usr/local/news/etc/key.pem]
Jul 8 10:52:41 csiph nnrpd[21640]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Jul 8 10:52:41 csiph nnrpd[21640]: error initializing TLS: [CA_file: ] [CA_path: /usr/local/news/etc] [cert_file: /usr/local/news/etc/cert.pem] [key_file: /usr/local/news/etc/key.pem]
Jul 8 10:52:42 csiph nnrpd[21643]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Jul 8 10:52:42 csiph nnrpd[21643]: error initializing TLS: [CA_file: ] [CA_path: /usr/local/news/etc] [cert_file: /usr/local/news/etc/cert.pem] [key_file: /usr/local/news/etc/key.pem]
Jul 8 10:52:42 csiph nnrpd[21646]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Jul 8 10:52:42 csiph nnrpd[21646]: error initializing TLS: [CA_file: ] [CA_path: /usr/local/news/etc] [cert_file: /usr/local/news/etc/cert.pem] [key_file: /usr/local/news/etc/key.pem]
Jul 8 10:52:43 csiph nnrpd[21649]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Jul 8 10:52:43 csiph nnrpd[21649]: error initializing TLS: [CA_file: ] [CA_path: /usr/local/news/etc] [cert_file: /usr/local/news/etc/cert.pem] [key_file: /usr/local/news/etc/key.pem]

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 43645 41.0%3.8 MB 26.0%
inn 40563 38.1%8.2 MB 56.0%
innd 18964 17.8%2.1 MB 14.4%
innfeed 3124 2.9%541.8 KB 3.6%
nocem 69 0.1%7.2 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 6 106366 100.0%14.7 MB100.0%

History cache:

ReasonCount%Count
Positive hits 511165 59.2%
Negative hits 312480 36.2%
Do not exist 39281 4.5%
Cache misses 518 0.1%
TOTAL: 4 863444 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.274 0.0%205 0.000 1.337 26.000
article cleanup00:00:00.259 0.0%38931 0.000 0.007 0.023
article logging00:00:00.654 0.0%39494 0.000 0.017 0.372
article parse00:00:02.262 0.0%125689 0.000 0.018 0.374
article write00:00:44.073 0.1%24866 0.200 1.772 17.153
artlog/artcncl00:00:00.002 0.0%191 0.000 0.010 0.250
artlog/artparse00:00:00.405 0.0%878 0.000 0.461 1.840
data move00:00:00.241 0.0%813483 0.000 0.000 0.001
hisgrep/artcncl00:00:00.082 0.0%204 0.000 0.402 25.000
hishave/artcncl00:00:00.000 0.0%205 0.000 0.000 0.000
hishave/artparse00:00:00.002 0.0%304 0.000 0.007 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:10.924 0.0%868955 0.002 0.013 0.215
history sync00:00:01.772 0.0%355 0.000 4.992 68.000
history write00:00:55.097 0.1%39494 0.209 1.395 13.974
hiswrite/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
hiswrite/artparse00:00:00.622 0.0%301 0.000 2.066 55.000
idle23:55:11.262 99.6%835014 64.047 103.126 236.364
nntp read00:00:02.894 0.0%841498 0.001 0.003 0.005
overview write00:01:16.745 0.1%24866 0.236 3.086 47.544
perl filter00:01:22.285 0.1%38916 1.388 2.114 3.430
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.480 0.0%49793 0.000 0.010 0.024
TOTAL: 24:00:48.99123:59:50.335 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.090 0.0%24818 0.000 0.004 0.023
article prepare00:00:00.006 0.0%28100 0.000 0.000 0.014
article read00:00:00.753 0.0%3513 0.000 0.214 2.938
backlog stats00:00:00.278 0.0%575292 0.000 0.000 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.173 0.0%423571 0.001 0.005 0.009
data write00:00:02.853 0.0%407928 0.002 0.007 0.012
idle23:53:16.118100.0%575292 93.634 149.483 397.324
status file00:00:00.331 0.0%256 0.000 1.293 19.000
TOTAL: 23:53:31.05023:53:22.602100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:04.005 0.0%4359 0.037 0.919 1.286
idle23:49:45.950 98.4%61618 0.000 1392.222600069.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.767 0.0%124204 0.000 0.006 0.100
readart00:00:12.360 0.0%11423 0.000 1.082 48.000
TOTAL: 24:12:49.55723:50:03.082 98.4%----

Control commands to INND:

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1usenet.blueworldhosting.com 288 29425 6206 21094 2125 21%23:44:28
2csiph-out.feeder.erje.net 1152 130817 4144 118307 8366 3%94:58:17
3feeder.eternal-september.org 4 33098 3924 28809 365 11%24:26:01
4news-out.mixmin.net 2 27759 2477 24779 503 8%24:08:51
5csiph-out.news.weretis.net 4 58410 2144 55717 549 3%49:13:00
6news-out.aioe.org 2 23989 1915 22055 19 7%24:09:56
7newsfeed.neva.ru 150 56990 1527 53311 2152 2%71:30:06
8newsfeed.fu-berlin.de 57 27867 1051 26161 655 3%34:49:09
9newsfeeds.xmission.com 1 23471 470 22972 29 2%20:57:55
10news.fcku.it 1 10441 296 10119 26 2%23:23:08
11news.freedyn.net 3 27412 252 27141 19 0%24:11:00
12au2pb.net 1 26927 107 26786 34 0%24:12:48
13news.redatomik.org 1 21917 83 21830 4 0%22:26:07
14news.swapon.de 4 1823 78 1743 2 4%24:07:55
15news.bbs.geek.nz 2 24321 72 24249 0 0%24:08:57
16xanadu-bbs.net 15 657 17 628 12 2%22:34:02
17nntp.pasdenom.info 3 25687 10 25662 15 0%24:08:01
18localhost 42 8 8 0 0 100%00:00:00
TOTAL: 18 1732 551019 24781 511363 14875 4%557:09:41
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1csiph-out.feeder.erje.net17.6 MB16.1 KB23.0 MB40.6 MB 43%3.3 KB
2usenet.blueworldhosting.com16.8 MB1.3 KB25.0 MB41.8 MB 40%5.1 KB
3feeder.eternal-september.org9.9 MB3.4 KB5.2 MB15.1 MB 65%3.6 KB
4news-out.mixmin.net9.0 MB5.9 KB1.3 MB10.3 MB 87%3.5 KB
5csiph-out.news.weretis.net6.0 MB2.9 KB14.4 MB20.4 MB 29%7.8 KB
6news-out.aioe.org4.7 MB0.0 KB24.1 KB4.7 MB 99%2.5 KB
7newsfeed.neva.ru3.7 MB6.2 KB8.3 MB12.1 MB 30%3.4 KB
8newsfeed.fu-berlin.de2.3 MB0.0 KB1.2 MB3.5 MB 65%2.1 KB
9newsfeeds.xmission.com1.2 MB0.0 KB1.2 MB2.4 MB 51%4.9 KB
10news.freedyn.net850.2 KB0.0 KB126.6 KB976.8 KB 87%3.6 KB
11news.fcku.it607.8 KB0.0 KB724.6 KB1.3 MB 45%4.1 KB
12au2pb.net280.6 KB2.9 KB553.3 KB836.8 KB 33%5.9 KB
13news.redatomik.org197.8 KB0.0 KB364.3 KB562.1 KB 35%6.5 KB
14news.swapon.de175.4 KB0.0 KB11.8 KB187.2 KB 93%2.3 KB
15news.bbs.geek.nz92.4 KB0.0 KB0.0 KB92.4 KB100%1.3 KB
16xanadu-bbs.net32.3 KB0.0 KB26.8 KB59.1 KB 54%2.0 KB
17nntp.pasdenom.info17.5 KB0.0 KB35.7 KB53.1 KB 32%2.1 KB
18localhost7.9 KB0.0 KB0.0 KB7.9 KB100%1.0 KB
TOTAL: 1873.3 MB38.8 KB81.5 MB154.8 MB 47%4.0 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jul 8 04:15:00 - 04:59:59 495 2.0% 0.181.9 MB 2.6% 0.73
Jul 8 05:00:00 - 05:59:59 521 2.1% 0.141.6 MB 2.2% 0.46
Jul 8 06:00:00 - 06:59:59 733 2.9% 0.202.0 MB 2.7% 0.57
Jul 8 07:00:00 - 07:59:59 1068 4.3% 0.303.1 MB 4.2% 0.89
Jul 8 08:00:00 - 08:59:59 1109 4.5% 0.312.8 MB 3.9% 0.81
Jul 8 09:00:00 - 09:59:59 1227 4.9% 0.343.4 MB 4.6% 0.96
Jul 8 10:00:00 - 10:59:59 1398 5.6% 0.394.4 MB 5.9% 1.24
Jul 8 11:00:00 - 11:59:59 1335 5.4% 0.374.2 MB 5.8% 1.20
Jul 8 12:00:00 - 12:59:59 1290 5.2% 0.363.4 MB 4.6% 0.95
Jul 8 13:00:00 - 13:59:59 1309 5.3% 0.363.4 MB 4.7% 0.97
Jul 8 14:00:00 - 14:59:59 1444 5.8% 0.404.5 MB 6.1% 1.28
Jul 8 15:00:00 - 15:59:59 1490 6.0% 0.414.4 MB 6.0% 1.26
Jul 8 16:00:00 - 16:59:59 1425 5.7% 0.404.5 MB 6.2% 1.29
Jul 8 17:00:00 - 17:59:59 1392 5.6% 0.393.7 MB 5.0% 1.05
Jul 8 18:00:00 - 18:59:59 1265 5.1% 0.353.6 MB 4.8% 1.01
Jul 8 19:00:00 - 19:59:59 1184 4.8% 0.333.3 MB 4.5% 0.94
Jul 8 20:00:00 - 20:59:59 1168 4.7% 0.323.6 MB 4.9% 1.02
Jul 8 21:00:00 - 21:59:59 1056 4.2% 0.292.9 MB 4.0% 0.84
Jul 8 22:00:00 - 22:59:59 864 3.5% 0.242.5 MB 3.4% 0.70
Jul 8 23:00:00 - 23:59:59 717 2.9% 0.202.1 MB 2.9% 0.60
Jul 9 00:00:00 - 00:59:59 695 2.8% 0.192.8 MB 3.8% 0.79
Jul 9 01:00:00 - 01:59:59 535 2.2% 0.151.7 MB 2.4% 0.49
Jul 9 02:00:00 - 02:59:59 525 2.1% 0.151.7 MB 2.3% 0.47
Jul 9 03:00:00 - 03:59:59 489 2.0% 0.141.6 MB 2.1% 0.45
Jul 9 04:00:00 - 04:15:00 135 0.5% 0.15444.9 KB 0.6% 0.49
TOTAL: 24:00:00 24869 100.0% 0.2973.6 MB 100.0% 0.87
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1csiph-out.feeder.erje.net 8406 749 0 3 1 0 0 0 7653
2newsfeed.neva.ru 2165 1041 0 2 0 0 0 0 1122
3usenet.blueworldhosting.com 2147 731 0 1 0 0 0 0 1415
4newsfeed.fu-berlin.de 1027 21 0 0 0 0 0 0 1006
5csiph-out.news.weretis.net 613 188 0 1 0 0 0 0 424
6news-out.mixmin.net 525 5 0 2 0 0 0 0 518
7feeder.eternal-september.org 424 80 0 1 0 0 0 0 343
8news.fcku.it 41 0 0 0 0 0 0 0 41
9au2pb.net 34 23 0 1 0 0 0 0 10
10newsfeeds.xmission.com 30 12 0 0 0 0 0 0 18
11news-out.aioe.org 26 19 0 0 0 0 0 0 7
12news.freedyn.net 19 1 0 0 0 0 0 0 18
13nntp.pasdenom.info 15 9 0 0 0 2 0 0 4
14news.swapon.de 14 0 0 0 0 0 0 0 14
15xanadu-bbs.net 12 0 0 0 0 0 0 0 12
16news.redatomik.org 4 0 0 0 0 0 0 0 4
TOTAL: 16 15502 2879 0 11 1 2 0 012609

Unwanted newsgroups [Top 20]:

NewsgroupCount
fa.linux.kernel 781
mailing.freebsd.pkg-fallout 213
mailing.freebsd.ports-bugs 129
lada.talk 97
mailing.freebsd.svn-src-all 55
fa.freebsd.cvs-all 54
mailing.freebsd.svn-ports-all 48
lada.auto 47
it-alt.sport.calcio.juventus 47
mailing.freebsd.svn-ports-head 42
mailing.freebsd.svn-src-head 38
fa.freebsd.bugs 36
mailing.gcc.gcc-cvs 36
mailing.freebsd.bugs 36
alt.survival 34
fa.caml 32
mailing.openssl.cvs 30
mailing.openbsd.misc 30
mpc.lists.nanog 29
alt.politics.scorched-earth 29
TOTAL: 221 2879

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

GroupsCount
nz.politics.announce 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 6793
EMP (phn path) 2263
PFM bot misc 770
EMP (phl) 658
EMP (phn nph) 243
Scoring filter (10) 46
Scoring filter (12) 46
Too many newsgroups 44
Scoring filter (21) 36
Scoring filter (22) 32
Scoring filter (18) 31
Scoring filter (14) 23
HTML Multipart 18
User-issued spam cancel 17
Scoring filter (11) 16
Binary Payload 14
Scoring filter (13) 12
Scoring filter (15) 12
Scoring filter (19) 12
Scoring filter (20) 12
TOTAL: 40 11170

NoCeM on Spool:

IdGoodBadUniqueTotal
bleachbot@httrack.com6206464
TOTAL: 16206464

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
csiph-out.feeder.erje.net 4
usenet.blueworldhosting.com 1
TOTAL: 2 5
Huge articles
csiph-out.news.weretis.net 123
usenet.blueworldhosting.com 105
feeder.eternal-september.org 29
news.fcku.it 18
newsfeeds.xmission.com 13
news-out.mixmin.net 10
au2pb.net 2
news.redatomik.org 2
news.freedyn.net 1
TOTAL: 9 303
Including strange strings
newsfeed.fu-berlin.de 378
feeder.eternal-september.org 58
csiph-out.feeder.erje.net 44
csiph-out.news.weretis.net 24
news-out.mixmin.net 21
newsfeed.neva.ru 14
news.swapon.de 12
news-out.aioe.org 6
usenet.blueworldhosting.com 2
news.fcku.it 1
TOTAL: 10 560
No colon-space in header
csiph-out.feeder.erje.net 574
TOTAL: 1 574
TOTAL: 4 1442

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.bbs.geek.nz 45802 2024 22022 36 0 1 4%23:59:56
2news.freedyn.net 27476 985 23613 7 0 1 3%23:59:56
3xanadu-bbs 28067 928 23907 0 0 0 3%23:59:56
4fu-berlin.de 23592 755 22837 0 0 0 3%23:59:44
5weretis.net 23328 246 21237 27 0 0 1%23:59:56
6aioe 23589 183 20685 17 0 1 0%23:59:56
7xmission 28656 173 23383 2 0 0 0%23:59:48
8usenet.pasdenom.info 32646 156 23693 1011 0 13 0%23:59:56
9au2pb 26342 87 23928 0 0 0 0%23:59:33
10erje 20986 71 20463 129 0 0 0%23:59:53
11news.redatomik.org 25536 71 23833 19 0 0 0%23:58:18
12news.mixmin.net 23425 27 21038 1 0 1 0%23:59:55
13eternal-september 23521 16 19720 0 0 1 0%23:59:55
14bwh 20162 12 17598 0 0 0 0%23:59:26
15neva.ru 27299 9 22237 31 0 1 0%23:59:52
16news.swapon.de 1968 5 1812 3 0 0 0%23:51:21
17news.fcku.it 3608 4 2992 0 0 0 0%21:20:48
TOTAL: 17 406003 5752 334998 1283 0 19 1%405:08:09
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.freedyn.net9.1 MB64.8 KB9.2 MB0.1 KB/s9.5 KB23:59:56
2xanadu-bbs9.0 MB0.0 KB9.0 MB0.1 KB/s9.9 KB23:59:56
3fu-berlin.de8.5 MB0.0 KB8.5 MB0.1 KB/s11.6 KB23:59:44
4news.bbs.geek.nz5.2 MB121.2 KB5.4 MB0.1 KB/s2.7 KB23:59:56
5weretis.net733.5 KB134.0 KB867.5 KB0.0 KB/s3.2 KB23:59:56
6xmission588.5 KB9.0 KB597.5 KB0.0 KB/s3.4 KB23:59:48
7aioe415.6 KB40.2 KB455.8 KB0.0 KB/s2.3 KB23:59:56
8usenet.pasdenom.info372.9 KB9.1 MB9.5 MB0.1 KB/s8.3 KB23:59:56
9news.redatomik.org181.0 KB27.8 KB208.8 KB0.0 KB/s2.3 KB23:58:18
10au2pb180.0 KB0.0 KB180.0 KB0.0 KB/s2.1 KB23:59:33
11erje127.2 KB609.4 KB736.7 KB0.0 KB/s3.7 KB23:59:53
12news.mixmin.net43.7 KB1.6 KB45.4 KB0.0 KB/s1.6 KB23:59:55
13eternal-september35.4 KB0.0 KB35.4 KB0.0 KB/s2.2 KB23:59:55
14bwh20.4 KB0.0 KB20.4 KB0.0 KB/s1.7 KB23:59:26
15neva.ru9.8 KB78.6 KB88.3 KB0.0 KB/s2.2 KB23:59:52
16news.fcku.it8.7 KB0.0 KB8.7 KB0.0 KB/s2.2 KB21:20:48
17news.swapon.de4.8 KB3.2 KB7.9 KB0.0 KB/s1.0 KB23:51:21
TOTAL: 1734.5 MB10.2 MB44.7 MB0.0 KB/s6.5 KB405:08:09
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1mtrlpq2314w-lp140-04-1177941735.dsl.bell.ca 120 54157.1 KB 42 0 000:38:58
2202.89.176.220 288 3898.5 KB 34 0 005:22:44
368-185-181-64.dhcp.mdsn.wi.charter.com 16 1010.2 KB 7 8 504:48:29
4dhcp-089-099-203-254.chello.nl 21 66.0 KB 2 0 003:10:13
TOTAL: 4 445 108271.8 KB 85 8 524:12:44

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.dsl.bell.ca 120 54157.1 KB 42 0 000:38:58
2unresolved 302 3898.5 KB 34 0 013:43:24
3*.dhcp.mdsn.wi.charter.com 16 1010.2 KB 7 8 504:48:29
4*.chello.nl 21 66.0 KB 2 0 003:10:13
5*.nielsenmedia.com 5 00.0 KB 0 0 001:51:39
TOTAL: 5 464 108271.8 KB 85 8 524:12:44

NNRP auth users [Top 20]:

UserConn
moussa 287
ohlandl 5
TOTAL: 2 292

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
216.246.166.30 0.668 0.471 0.00007:20:34
202.89.176.220 27.683 7.488 0.75605:22:44
68-185-181-64.dhcp.mdsn.wi.charter.com 1.646 0.357 0.01504:48:29
dhcp-089-099-203-254.chello.nl 1.947 0.377 0.00003:10:13
challenger.nielsenmedia.com 0.410 0.103 0.00001:51:39
71.98.160.36 0.129 0.032 0.00001:00:00
mtrlpq2314w-lp140-04-1177941735.dsl.bell.ca 15.041 4.155 0.00000:38:58
94.160.253.201 0.155 0.032 0.00000:00:02
192.96.206.82 0.398 0.076 0.00000:00:02
TOTAL: 9 48.077 13.091 0.77124:12:44

Curious NNRP server explorers [Top 20]:

SystemConn
192.96.206.82 6
challenger.nielsenmedia.com 5
216.246.166.30 4
71.98.160.36 2
94.160.253.201 2
TOTAL: 5 19

NNRP client timeouts [Top 20]:

SystemConnPeer
68-185-181-64.dhcp.mdsn.wi.charter.com 4 0
challenger.nielsenmedia.com 3 0
71.98.160.36 2 0
TOTAL: 3 9 0

Newsgroup request counts (by category):

CategoryCountPct
1comp 54 50.0%
2alt 22 20.4%
3talk 13 12.0%
4rec 12 11.1%
5uk 4 3.7%
6sci 2 1.9%
7soc 1 0.9%
TOTAL: 7 108100.0%

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

NewsgroupCount
1comp.sys.ibm.ps2.hardware 24
2comp.os.vms 12
3talk.politics.guns 11
4rec.arts.tv 10
5alt.home.repair 6
6alt.fan.rush-limbaugh 5
7comp.arch.fpga 4
8comp.arch.embedded 4
9comp.os.linux.networking 4
10comp.os.cpm 3
11alt.christnet.christianlife 3
12sci.physics 2
13uk.politics.misc 2
14talk.politics.misc 2
15alt.life.sucks 2
16uk.railway 1
17rec.motorcycles 1
18comp.unix.aix 1
19alt.bumbling.idiots.the.fbi 1
20comp.ai.neural-nets 1
21comp.sys.ibm.pc.hardware.storage 1
22alt.usage.english 1
23alt.global-warming 1
24alt.society.liberalism 1
25rec.outdoors.rv-travel 1
26alt.comp.freeware 1
27alt.politics.economics 1
28uk.legal 1
29soc.culture.usa 1
TOTAL: 29 108