Daily Usenet report for csiph.com

Jan 5 04:15:00 -- Jan 6 04:15:00

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Jan 5 08:47:34 csiph nnrpd[4762]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Jan 5 08:47:34 csiph nnrpd[4762]: 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]
Jan 5 14:43:04 csiph nnrpd[23521]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Jan 5 14:43:04 csiph nnrpd[23521]: 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]
Jan 5 16:39:55 csiph nnrpd[29414]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Jan 5 16:39:55 csiph nnrpd[29414]: 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]
Jan 5 21:28:40 csiph nnrpd[44402]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Jan 5 21:28:40 csiph nnrpd[44402]: 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
inn 19537 50.4%4.9 MB 65.2%
innd 13577 35.0%1.8 MB 24.0%
innfeed 3640 9.4%622.7 KB 8.1%
nnrpd 1998 5.2%214.5 KB 2.8%
nocem 7 0.0%0.9 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 6 38760 100.0%7.6 MB100.0%

History cache:

ReasonCount%Count
Positive hits 299090 46.1%
Negative hits 272496 42.0%
Cache misses 58950 9.1%
Do not exist 18719 2.9%
TOTAL: 4 649255 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.280 0.0%37 0.000 7.568 17.667
article cleanup00:00:00.098 0.0%18629 0.000 0.005 0.051
article logging00:00:00.724 0.0%19314 0.000 0.037 1.164
article parse00:00:00.882 0.0%58579 0.000 0.015 0.224
article write00:01:14.589 0.1%15595 0.397 4.783 72.776
artlog/artcncl00:00:00.000 0.0%37 0.000 0.000 0.000
artlog/artparse00:00:00.010 0.0%196 0.000 0.051 7.000
data move00:00:00.213 0.0%589876 0.000 0.000 0.002
hisgrep/artcncl00:00:00.015 0.0%36 0.000 0.417 4.500
hishave/artcncl00:00:00.001 0.0%37 0.000 0.027 1.000
hishave/artparse00:00:00.000 0.0%184 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:01:07.647 0.1%652616 0.001 0.104 0.865
history sync00:00:01.245 0.0%344 0.000 3.619 28.667
history write00:00:54.906 0.1%18635 0.514 2.946 18.771
hiswrite/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
hiswrite/artparse00:00:00.365 0.0%184 0.000 1.984 28.000
idle23:51:38.490 99.4%585002 33.313 146.835 495.063
nntp read00:00:02.321 0.0%594576 0.001 0.004 0.009
overview write00:04:36.391 0.3%15595 0.121 17.723 1857.465
perl filter00:00:28.340 0.0%18623 1.135 1.522 3.687
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.252 0.0%31197 0.000 0.008 0.044
TOTAL: 24:00:52.79424:00:06.769 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.044 0.0%15716 0.000 0.003 0.036
article prepare00:00:00.002 0.0%14228 0.000 0.000 0.008
article read00:00:00.372 0.0%1557 0.000 0.239 11.000
backlog stats00:00:00.205 0.0%344849 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.235 0.0%269561 0.000 0.005 0.011
data write00:00:01.595 0.0%256977 0.001 0.006 0.014
idle23:54:16.949100.0%344849 133.325 249.550 816.008
status file00:00:00.347 0.0%324 0.000 1.071 29.000
TOTAL: 23:54:29.83923:54:20.749100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle52:40:59.736 99.5%1074 0.000176591.933409707.333
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:02.960 0.0%48056 0.000 0.062 86.500
readart00:00:00.430 0.0%35 0.333 12.286 42.000
TOTAL: 52:57:11.76952:41:03.126 99.5%----

Control commands to INND:

CommandNumber
flush 5
flushlogs 2
go 2
logmode 2
lowmark 1
mode 126
pause 2
paused 2
reload 2
reserve 2
TOTAL: 10 146

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1csiph-out.news.xs4all.nl 0 39272 3833 34021 1418 9%68:33:23
2feeder.eternal-september.org 12 4364 2268 2031 65 51%27:23:59
3news-out.mixmin.net 2 16697 1931 14467 299 11%23:59:47
4csiph-out.feeder.erje.net 1440 77011 1400 75255 356 1%118:40:21
5csiph-out.news.weretis.net 3 31744 1224 30198 322 3%46:42:50
6newsfeed.fu-berlin.de 26 14428 995 13252 181 6%26:05:58
7newsfeeds.xmission.com 3 15739 944 14783 12 5%27:02:58
8newsfeed.neva.ru 150 10081 744 9075 262 7%71:59:49
9news-out.aioe.org 4 15067 742 14272 53 4%24:09:35
10csiph-out.feed.uzoreto.com 864 58863 470 58060 333 0%71:17:01
11news-out.neodome.net 3 17450 409 17017 24 2%24:08:59
12news.fcku.it 7 1605 238 1362 5 14%22:08:37
13nntp.pasdenom.info 2 10308 127 10178 3 1%23:59:48
14news.swapon.de 10 1726 75 1651 0 4%24:08:48
15csiph-out.news.tnetconsulting.net 1 15821 39 15781 1 0%23:59:56
16news.freedyn.net 2 18201 38 18111 52 0%23:59:49
17news.samoylyk.net 2 15120 8 15109 3 0%23:57:21
18news.bbs.nz 1 15234 6 15227 1 0%23:59:49
19xanadu-bbs.net 30 467 1 466 0 0%22:08:51
20localhost 26 0 0 0 0 0%00:00:02
TOTAL: 20 2588 379198 15492 360316 3390 4%718:27:41
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1csiph-out.news.xs4all.nl10.7 MB0.0 KB10.0 MB20.7 MB 51%4.0 KB
2csiph-out.feeder.erje.net7.4 MB43.3 KB2.6 MB10.1 MB 73%5.9 KB
3feeder.eternal-september.org5.9 MB0.0 KB228.1 KB6.1 MB 96%2.7 KB
4news-out.mixmin.net4.6 MB15.3 KB1.3 MB5.8 MB 77%2.7 KB
5csiph-out.news.weretis.net3.6 MB0.0 KB2.2 MB5.8 MB 62%3.9 KB
6newsfeeds.xmission.com2.6 MB0.0 KB974.4 KB3.6 MB 73%3.8 KB
7news-out.aioe.org2.4 MB15.3 KB170.4 KB2.5 MB 92%3.3 KB
8newsfeed.fu-berlin.de2.3 MB0.0 KB627.6 KB2.9 MB 78%2.5 KB
9newsfeed.neva.ru2.3 MB0.0 KB816.9 KB3.1 MB 74%3.1 KB
10csiph-out.feed.uzoreto.com1.7 MB0.0 KB6.1 MB7.8 MB 21%9.9 KB
11news-out.neodome.net1.5 MB0.0 KB927.0 KB2.4 MB 62%5.7 KB
12news.fcku.it373.6 KB0.0 KB5.2 KB378.8 KB 98%1.6 KB
13nntp.pasdenom.info327.7 KB0.0 KB3.7 KB331.4 KB 98%2.5 KB
14csiph-out.news.tnetconsulting.net177.3 KB0.0 KB60.4 KB237.7 KB 74%5.9 KB
15news.freedyn.net175.0 KB0.0 KB3.3 MB3.4 MB 4%39.0 KB
16news.swapon.de157.3 KB0.0 KB0.0 KB157.3 KB100%2.1 KB
17news.samoylyk.net26.1 KB15.3 KB136.4 KB177.8 KB 14%16.2 KB
18news.bbs.nz13.9 KB0.0 KB0.7 KB14.6 KB 95%2.1 KB
19xanadu-bbs.net1.9 KB0.0 KB0.0 KB1.9 KB100%1.9 KB
20localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 2046.2 MB89.2 KB29.3 MB75.6 MB 61%4.1 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jan 5 04:15:00 - 04:59:59 225 1.4% 0.08740.1 KB 1.6% 0.27
Jan 5 05:00:00 - 05:59:59 316 2.0% 0.092.0 MB 4.3% 0.57
Jan 5 06:00:00 - 06:59:59 319 2.0% 0.091.2 MB 2.6% 0.35
Jan 5 07:00:00 - 07:59:59 379 2.4% 0.111.1 MB 2.3% 0.31
Jan 5 08:00:00 - 08:59:59 583 3.7% 0.161.5 MB 3.3% 0.44
Jan 5 09:00:00 - 09:59:59 756 4.9% 0.212.5 MB 5.5% 0.72
Jan 5 10:00:00 - 10:59:59 692 4.4% 0.192.0 MB 4.2% 0.56
Jan 5 11:00:00 - 11:59:59 743 4.8% 0.212.0 MB 4.3% 0.57
Jan 5 12:00:00 - 12:59:59 786 5.0% 0.222.0 MB 4.2% 0.56
Jan 5 13:00:00 - 13:59:59 823 5.3% 0.232.1 MB 4.5% 0.60
Jan 5 14:00:00 - 14:59:59 904 5.8% 0.252.3 MB 5.0% 0.67
Jan 5 15:00:00 - 15:59:59 997 6.4% 0.282.8 MB 5.9% 0.78
Jan 5 16:00:00 - 16:59:59 1050 6.7% 0.293.0 MB 6.3% 0.84
Jan 5 17:00:00 - 17:59:59 944 6.1% 0.262.5 MB 5.3% 0.71
Jan 5 18:00:00 - 18:59:59 923 5.9% 0.262.6 MB 5.5% 0.73
Jan 5 19:00:00 - 19:59:59 701 4.5% 0.191.7 MB 3.6% 0.48
Jan 5 20:00:00 - 20:59:59 704 4.5% 0.202.3 MB 5.0% 0.66
Jan 5 21:00:00 - 21:59:59 788 5.1% 0.222.4 MB 5.1% 0.68
Jan 5 22:00:00 - 22:59:59 828 5.3% 0.232.7 MB 5.7% 0.76
Jan 5 23:00:00 - 23:59:59 583 3.7% 0.161.7 MB 3.6% 0.48
Jan 6 00:00:00 - 00:59:59 517 3.3% 0.141.5 MB 3.2% 0.42
Jan 6 01:00:00 - 01:59:59 360 2.3% 0.101.2 MB 2.5% 0.33
Jan 6 02:00:00 - 02:59:59 334 2.1% 0.091.8 MB 3.8% 0.50
Jan 6 03:00:00 - 03:59:59 264 1.7% 0.07972.2 KB 2.0% 0.27
Jan 6 04:00:00 - 04:15:00 65 0.4% 0.07269.4 KB 0.6% 0.30
TOTAL: 24:00:00 15584 100.0% 0.1846.6 MB 100.0% 0.55
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1csiph-out.news.xs4all.nl 1464 109 0 0 0 0 0 0 1355
2newsfeed.fu-berlin.de 733 50 0 0 0 0 0 0 683
3csiph-out.feed.uzoreto.com 340 36 0 0 0 0 0 0 304
4csiph-out.news.weretis.net 324 96 0 0 0 0 0 0 228
5news-out.mixmin.net 303 17 0 1 0 0 0 0 285
6newsfeed.neva.ru 262 99 0 0 0 0 0 0 163
7csiph-out.feeder.erje.net 206 64 0 3 0 0 0 0 139
8feeder.eternal-september.org 68 26 0 0 0 0 0 0 42
9news-out.aioe.org 62 45 0 1 0 0 0 0 16
10news.swapon.de 53 0 0 0 0 0 0 0 53
11news.freedyn.net 49 4 0 0 0 0 0 0 45
12newsfeeds.xmission.com 31 0 0 0 0 0 0 0 31
13news-out.neodome.net 11 1 0 0 0 0 0 0 10
14news.fcku.it 5 4 0 0 0 0 0 0 1
15nntp.pasdenom.info 3 0 0 0 0 0 0 0 3
16csiph-out.news.tnetconsulting.net 1 0 0 0 0 0 0 0 1
17news.samoylyk.net 1 0 0 1 0 0 0 0 0
TOTAL: 17 3916 551 0 6 0 0 0 0 3359

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.computer.workshop 140
lada.talk 84
spline.eisfair 45
scinet.stats 29
mozilla.general 22
alt.politics.usa 15
alt.survival 14
scinet.general.chatter 13
it-alt.sport.calcio.juventus 13
mozilla.support.firefox 12
alt.bbs.synchronet 12
oesterreich.tratsch 11
alt.tv.comedy-central.daily-show 10
at.jobs 9
alt.binaries.pictures.purebeauty 9
scinet.bbs.mystic 9
alt.job 7
alt.testing 6
alt.politics 6
att.jobs 6
TOTAL: 68 551

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1614
EMP (phn path) 532
EMP (phn nph) 159
Too many newsgroups 58
EMP (phl) 36
HTML Multipart 30
0 30
Binary: misplaced binary 6
Binary Payload 3
HTML post 2
TOTAL: 10 2470

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
csiph-out.feeder.erje.net 45
TOTAL: 1 45
Huge articles
csiph-out.news.xs4all.nl 50
csiph-out.feeder.erje.net 44
csiph-out.feed.uzoreto.com 32
news.freedyn.net 31
csiph-out.news.weretis.net 15
newsfeeds.xmission.com 13
newsfeed.fu-berlin.de 4
feeder.eternal-september.org 2
news-out.mixmin.net 1
news-out.neodome.net 1
TOTAL: 11 194
Including strange strings
newsfeed.fu-berlin.de 526
news.swapon.de 53
csiph-out.news.xs4all.nl 39
newsfeeds.xmission.com 18
csiph-out.news.weretis.net 14
csiph-out.feeder.erje.net 12
news-out.aioe.org 7
csiph-out.feed.uzoreto.com 5
feeder.eternal-september.org 4
news-out.mixmin.net 3
TOTAL: 11 683
No colon-space in header
csiph-out.news.xs4all.nl 8
csiph-out.feed.uzoreto.com 2
csiph-out.feeder.erje.net 1
TOTAL: 3 11
TOTAL: 4 933

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1xanadu-bbs 17829 851 14730 2 0 0 4%23:59:53
2news.bbs.nz 29139 532 14490 27 0 0 1%23:59:53
3fu-berlin.de 14271 496 13775 0 0 0 3%23:59:41
4news.samoylyk.net 15907 485 15080 11 0 42 3%23:59:53
5tnet 16692 252 14809 0 0 0 1%23:59:36
6xmission 18222 167 13955 5 0 0 0%23:59:53
7erje 15206 98 13624 251 0 0 0%23:59:51
8neva.ru 14159 65 13890 105 0 0 0%23:59:29
9newsfeed.xs4all.nl 12100 47 11447 1 0 103 0%23:59:53
10usenet.pasdenom.info 15283 14 14863 55 0 0 0%23:59:53
11news.uzoreto.com 14742 10 14448 20 0 0 0%23:59:43
12neodome.net 15359 8 15019 0 0 0 0%23:59:39
13news.freedyn.net 13988 8 13664 36 0 0 0%23:59:49
14news.mixmin.net 13070 7 12759 0 0 0 0%23:59:49
15aioe 12403 5 12110 0 0 0 0%23:59:45
16weretis.net 13966 1 13686 1 0 0 0%23:59:53
17news.swapon.de 1718 0 1713 0 0 0 0%23:25:27
18news.fcku.it 1333 0 1333 0 0 0 0%21:55:15
19eternal-september 0 0 0 0 0 12707 0%23:59:53
TOTAL: 19 255387 3046 225395 514 0 12852 1%453:17:08
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1xanadu-bbs5.9 MB9.4 KB5.9 MB0.1 KB/s7.0 KB23:59:53
2fu-berlin.de5.0 MB0.0 KB5.0 MB0.1 KB/s10.3 KB23:59:41
3news.samoylyk.net4.9 MB70.1 KB5.0 MB0.1 KB/s10.3 KB23:59:53
4news.bbs.nz1.3 MB113.2 KB1.4 MB0.0 KB/s2.6 KB23:59:53
5tnet573.8 KB0.0 KB573.8 KB0.0 KB/s2.3 KB23:59:36
6xmission444.4 KB11.4 KB455.8 KB0.0 KB/s2.6 KB23:59:53
7erje217.6 KB622.3 KB839.9 KB0.0 KB/s2.4 KB23:59:51
8neva.ru130.6 KB237.5 KB368.1 KB0.0 KB/s2.2 KB23:59:29
9newsfeed.xs4all.nl103.3 KB2.7 KB105.9 KB0.0 KB/s2.2 KB23:59:53
10news.freedyn.net45.0 KB425.7 KB470.7 KB0.0 KB/s10.7 KB23:59:49
11news.uzoreto.com32.1 KB67.6 KB99.7 KB0.0 KB/s3.3 KB23:59:43
12neodome.net28.4 KB0.0 KB28.4 KB0.0 KB/s3.5 KB23:59:39
13usenet.pasdenom.info27.5 KB91.6 KB119.1 KB0.0 KB/s1.7 KB23:59:53
14aioe13.0 KB0.0 KB13.0 KB0.0 KB/s2.6 KB23:59:45
15weretis.net8.9 KB9.3 KB18.2 KB0.0 KB/s9.1 KB23:59:53
16news.mixmin.net8.3 KB0.0 KB8.3 KB0.0 KB/s1.2 KB23:59:49
17news.swapon.de0.0 KB0.0 KB0.0 KB0.0 KB/s023:25:27
18news.fcku.it0.0 KB0.0 KB0.0 KB0.0 KB/s021:55:15
19eternal-september0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
TOTAL: 1918.7 MB1.6 MB20.3 MB0.0 KB/s5.8 KB453:17:08
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
191-165-244-119.subs.proxad.net 2 2635.2 KB 30 0 000:31:47
2a131033.upc-a.chello.nl 33 510.6 KB 3 0 010:04:38
391.187.33.87 291 46.9 KB 4 0 042:17:04
TOTAL: 3 326 3552.7 KB 37 0 052:57:09

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.subs.proxad.net 2 2635.2 KB 30 0 000:31:47
2*.upc-a.chello.nl 33 510.6 KB 3 0 010:04:38
3unresolved 291 46.9 KB 4 0 042:17:04
4*.us-west-1.compute.amazonaws.com 1 00.0 KB 0 0 000:00:00
5*.range31-50.btcentralplus.com 4 00.0 KB 0 0 000:03:38
TOTAL: 5 331 3552.7 KB 37 0 052:57:09

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
91.187.33.87 18.186 4.777 0.00042:17:04
a131033.upc-a.chello.nl 2.201 0.492 0.00010:04:38
91-165-244-119.subs.proxad.net 0.255 0.320 0.00000:31:47
host31-50-220-99.range31-50.btcentralplus.com 0.261 0.054 0.00000:03:38
ec2-54-219-167-21.us-west-1.compute.amazonaws.com 0.064 0.016 0.00000:00:00
TOTAL: 5 20.967 5.659 0.00052:57:09

Curious NNRP server explorers [Top 20]:

SystemConn
host31-50-220-99.range31-50.btcentralplus.com 4
ec2-54-219-167-21.us-west-1.compute.amazonaws.com 1
TOTAL: 2 5

Newsgroup request counts (by category):

CategoryCountPct
1alt 30 76.9%
2comp 9 23.1%
TOTAL: 2 39100.0%

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

NewsgroupCount
1alt.sex.anal 27
2comp.sys.ibm.ps2.hardware 9
3alt.bainaries.pictures.erotica.female.anal 1
4alt.binaries.pictures.erotica.anal 1
5alt.binaries.pictures.erotica.female.anal 1
TOTAL: 5 39