Daily Usenet report for csiph.com

Dec 9 04:15:00 -- Dec 10 04:15:00

Unknown entries from news log file:

First 14 / 14 lines (100.0%)

Dec 9 08:56:24 csiph nnrpd[67428]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Dec 9 08:56:24 csiph nnrpd[67428]: 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]
Dec 9 11:00:00 csiph nnrpd[74390]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Dec 9 11:00:00 csiph nnrpd[74390]: 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]
Dec 9 11:53:53 csiph nnrpd[77306]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Dec 9 11:53:53 csiph nnrpd[77306]: 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]
Dec 9 16:05:43 csiph nnrpd[91283]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Dec 9 16:05:43 csiph nnrpd[91283]: 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]
Dec 9 16:16:01 csiph nnrpd[91799]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Dec 9 16:16:01 csiph nnrpd[91799]: 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]
Dec 9 16:48:45 csiph nnrpd[93543]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Dec 9 16:48:45 csiph nnrpd[93543]: 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]
Dec 9 20:03:13 csiph nnrpd[3628]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Dec 9 20:03:13 csiph nnrpd[3628]: 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 19275 49.2%4.9 MB 64.5%
innd 15177 38.8%2.0 MB 26.0%
innfeed 3487 8.9%616.4 KB 7.9%
nnrpd 1195 3.1%128.4 KB 1.6%
nocem 25 0.1%2.8 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 6 39160 100.0%7.7 MB100.0%

History cache:

ReasonCount%Count
Positive hits 301894 49.5%
Negative hits 246499 40.4%
Cache misses 42446 7.0%
Do not exist 18696 3.1%
TOTAL: 4 609535 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.240 0.0%37 0.000 6.486 19.667
article cleanup00:00:00.126 0.0%18533 0.000 0.007 0.038
article logging00:00:00.315 0.0%19056 0.000 0.017 0.144
article parse00:00:00.892 0.0%54753 0.000 0.016 0.282
article write00:00:21.670 0.0%15706 0.145 1.380 34.650
artlog/artcncl00:00:00.002 0.0%37 0.000 0.054 1.000
artlog/artparse00:00:00.000 0.0%178 0.000 0.000 0.000
data move00:00:00.159 0.0%558984 0.000 0.000 0.001
hisgrep/artcncl00:00:00.008 0.0%37 0.000 0.216 2.000
hishave/artcncl00:00:00.000 0.0%37 0.000 0.000 0.000
hishave/artparse00:00:00.000 0.0%162 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:12.169 0.0%610660 0.001 0.020 0.119
history sync00:00:02.062 0.0%374 0.000 5.513 56.000
history write00:00:47.256 0.1%18545 0.212 2.548 12.846
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
hiswrite/artparse00:00:00.429 0.0%162 0.000 2.648 17.000
idle23:57:22.850 99.7%563351 29.272 153.089 802.904
nntp read00:00:02.119 0.0%567307 0.001 0.004 0.008
overview write00:01:00.670 0.1%15706 0.187 3.863 540.950
perl filter00:00:27.777 0.0%18529 1.198 1.499 2.447
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.241 0.0%31436 0.000 0.008 0.032
TOTAL: 24:01:03.55424:00:18.985 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.034 0.0%15726 0.000 0.002 0.023
article prepare00:00:00.003 0.0%12752 0.000 0.000 0.015
article read00:00:00.241 0.0%1464 0.000 0.165 8.800
backlog stats00:00:00.236 0.0%332842 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.174 0.0%257335 0.001 0.005 0.012
data write00:00:01.503 0.0%244314 0.000 0.006 0.014
idle23:55:00.858100.0%332842 140.080 258.684 1051.154
status file00:00:00.323 0.0%293 0.000 1.102 30.000
TOTAL: 23:55:11.79623:55:04.372100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle49:30:39.012 99.8%790 261.500225619.003600188.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.009 0.0%2072 0.000 0.004 0.167
readart00:00:00.524 0.0%63 0.000 8.317 42.000
TOTAL: 49:36:07.19449:30:39.545 99.8%----

Control commands to INND:

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1news-out.mixmin.net 2 16466 2826 13382 258 17%24:08:54
2newsfeeds.xmission.com 3 14972 2433 12507 32 16%24:59:01
3feeder.eternal-september.org 19 5439 2329 2977 133 42%26:53:44
4csiph-out.news.xs4all.nl 464 51599 1761 49208 630 3%68:09:58
5news-out.aioe.org 2 14867 1407 13381 79 9%24:09:02
6csiph-out.feeder.erje.net 1440 78107 1198 76420 489 1%118:44:18
7newsfeed.fu-berlin.de 14 15369 900 14168 301 5%25:49:10
8csiph-out.news.weretis.net 4 30582 898 29564 120 2%48:08:48
9newsfeed.neva.ru 149 10680 653 9754 273 6%71:27:14
10csiph-out.feed.uzoreto.com 864 39244 545 37892 807 1%71:21:31
11news.fcku.it 8 1992 323 1644 25 16%21:38:26
12news-out.neodome.net 2 16953 246 16631 76 1%23:59:52
13news.swapon.de 5 1570 63 1507 0 4%23:53:52
14csiph-out.news.tnetconsulting.net 2 14068 51 13989 28 0%23:59:47
15news.freedyn.net 1 17937 22 17870 45 0%23:59:55
16news.samoylyk.net 2 15016 11 15003 2 0%24:57:20
17localhost 29 3 3 0 0 100%00:00:02
18xanadu-bbs.net 21 529 0 529 0 0%22:10:55
19news.bbs.nz 2 13850 0 13850 0 0%23:59:45
TOTAL: 19 3033 359243 15669 340276 3298 4%692:31:34
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeeds.xmission.com8.0 MB0.0 KB2.4 MB10.3 MB 77%4.3 KB
2csiph-out.feeder.erje.net8.0 MB0.0 KB2.6 MB10.5 MB 75%6.4 KB
3news-out.mixmin.net6.0 MB0.0 KB537.3 KB6.6 MB 92%2.2 KB
4feeder.eternal-september.org5.8 MB0.0 KB1.5 MB7.3 MB 79%3.0 KB
5news-out.aioe.org5.0 MB0.0 KB271.9 KB5.3 MB 94%3.7 KB
6csiph-out.news.xs4all.nl4.7 MB0.0 KB3.3 MB8.0 MB 58%3.4 KB
7csiph-out.news.weretis.net2.5 MB0.8 KB1.3 MB3.8 MB 65%3.9 KB
8csiph-out.feed.uzoreto.com2.4 MB0.7 KB6.7 MB9.1 MB 26%6.9 KB
9newsfeed.fu-berlin.de2.0 MB0.0 KB789.0 KB2.8 MB 72%2.4 KB
10newsfeed.neva.ru1.6 MB0.0 KB759.0 KB2.3 MB 67%2.6 KB
11news-out.neodome.net877.3 KB0.0 KB1.2 MB2.1 MB 40%6.7 KB
12news.fcku.it538.6 KB0.0 KB132.9 KB671.5 KB 80%1.9 KB
13csiph-out.news.tnetconsulting.net212.2 KB0.0 KB85.8 KB298.0 KB 71%3.8 KB
14news.swapon.de141.8 KB0.0 KB0.0 KB141.8 KB100%2.3 KB
15news.freedyn.net129.5 KB0.8 KB494.2 KB624.5 KB 20%9.3 KB
16news.samoylyk.net23.7 KB0.0 KB107.3 KB131.0 KB 18%10.1 KB
17localhost2.9 KB0.0 KB0.0 KB2.9 KB100%1.0 KB
18xanadu-bbs.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
19news.bbs.nz0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1947.9 MB2.4 KB22.1 MB70.0 MB 68%3.8 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Dec 9 04:15:00 - 04:59:59 176 1.1% 0.07546.4 KB 1.1% 0.20
Dec 9 05:00:00 - 05:59:59 446 2.8% 0.122.6 MB 5.4% 0.73
Dec 9 06:00:00 - 06:59:59 487 3.1% 0.141.5 MB 3.2% 0.44
Dec 9 07:00:00 - 07:59:59 471 3.0% 0.131.4 MB 2.8% 0.39
Dec 9 08:00:00 - 08:59:59 645 4.1% 0.181.9 MB 4.0% 0.55
Dec 9 09:00:00 - 09:59:59 782 5.0% 0.222.5 MB 5.1% 0.70
Dec 9 10:00:00 - 10:59:59 815 5.2% 0.232.4 MB 5.0% 0.68
Dec 9 11:00:00 - 11:59:59 860 5.5% 0.242.4 MB 5.0% 0.68
Dec 9 12:00:00 - 12:59:59 698 4.4% 0.192.1 MB 4.3% 0.59
Dec 9 13:00:00 - 13:59:59 748 4.8% 0.211.9 MB 4.1% 0.55
Dec 9 14:00:00 - 14:59:59 822 5.2% 0.232.5 MB 5.2% 0.70
Dec 9 15:00:00 - 15:59:59 839 5.3% 0.233.5 MB 7.3% 1.00
Dec 9 16:00:00 - 16:59:59 958 6.1% 0.272.5 MB 5.3% 0.72
Dec 9 17:00:00 - 17:59:59 1060 6.7% 0.293.2 MB 6.7% 0.91
Dec 9 18:00:00 - 18:59:59 818 5.2% 0.232.4 MB 4.9% 0.67
Dec 9 19:00:00 - 19:59:59 796 5.1% 0.222.3 MB 4.7% 0.64
Dec 9 20:00:00 - 20:59:59 811 5.2% 0.232.1 MB 4.4% 0.60
Dec 9 21:00:00 - 21:59:59 741 4.7% 0.212.0 MB 4.1% 0.56
Dec 9 22:00:00 - 22:59:59 736 4.7% 0.202.0 MB 4.2% 0.58
Dec 9 23:00:00 - 23:59:59 584 3.7% 0.161.8 MB 3.7% 0.50
Dec 10 00:00:00 - 00:59:59 446 2.8% 0.121.6 MB 3.4% 0.46
Dec 10 01:00:00 - 01:59:59 294 1.9% 0.08934.4 KB 1.9% 0.26
Dec 10 02:00:00 - 02:59:59 241 1.5% 0.07734.0 KB 1.5% 0.20
Dec 10 03:00:00 - 03:59:59 341 2.2% 0.09975.9 KB 2.0% 0.27
Dec 10 04:00:00 - 04:15:00 94 0.6% 0.10301.9 KB 0.6% 0.34
TOTAL: 24:00:00 15709 100.0% 0.1847.9 MB 100.0% 0.57
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1csiph-out.feed.uzoreto.com 808 45 0 1 0 0 0 0 762
2newsfeed.fu-berlin.de 664 11 0 0 0 0 0 0 653
3csiph-out.news.xs4all.nl 648 54 0 0 0 0 0 0 594
4news-out.mixmin.net 310 8 0 0 0 0 0 0 302
5newsfeed.neva.ru 276 88 0 0 0 0 0 0 188
6csiph-out.feeder.erje.net 221 51 0 0 0 0 0 0 170
7feeder.eternal-september.org 134 70 0 0 0 0 0 0 64
8csiph-out.news.weretis.net 129 56 0 2 0 0 0 0 71
9news-out.aioe.org 116 75 0 0 0 0 0 0 41
10news-out.neodome.net 77 2 0 0 0 0 0 0 75
11news.freedyn.net 44 1 0 1 0 0 0 0 42
12newsfeeds.xmission.com 35 2 0 0 0 0 0 0 33
13news.swapon.de 30 0 0 0 0 0 0 0 30
14news.fcku.it 25 22 0 0 0 0 0 0 3
15csiph-out.news.tnetconsulting.net 10 9 0 0 0 0 0 0 1
16news.samoylyk.net 2 0 0 0 0 0 0 0 2
TOTAL: 16 3529 494 0 4 0 0 0 0 3031

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.computer.workshop 115
it-alt.sport.calcio.juventus 69
lada.talk 63
scinet.stats 19
mozilla.support.firefox 18
lada.auto 15
alt.philosophy 15
alt.politics 13
alt.politics.usa 13
at.jobs 10
free.it.religioni.scientology 8
trollnet.cinema 8
alt.binaries.pictures.purebeauty 8
alt.windows7.general 8
att.jobs 7
lada.movies 6
cakewalk.audio 6
alt.job 6
mozilla.support.thunderbird 5
it-alt.comp.software.mozilla 5
TOTAL: 66 494

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1499
EMP (phn path) 320
EMP (phl) 180
EMP (phn nph) 158
Too many newsgroups 84
0 49
HTML Multipart 12
Binary Payload 12
Binary: misplaced binary 9
Too many newsgroups (meow) 4
Scoring filter 3
TOTAL: 11 2330

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
csiph-out.feeder.erje.net 45
TOTAL: 1 45
Huge articles
csiph-out.feeder.erje.net 41
newsfeeds.xmission.com 28
news-out.neodome.net 22
csiph-out.feed.uzoreto.com 22
csiph-out.news.xs4all.nl 21
csiph-out.news.weretis.net 9
news.freedyn.net 6
feeder.eternal-september.org 4
newsfeed.fu-berlin.de 3
news-out.mixmin.net 2
TOTAL: 13 162
Including strange strings
newsfeed.fu-berlin.de 368
news-out.mixmin.net 51
news-out.aioe.org 36
news.swapon.de 30
csiph-out.news.xs4all.nl 17
csiph-out.news.weretis.net 7
newsfeeds.xmission.com 5
newsfeed.neva.ru 3
feeder.eternal-september.org 3
csiph-out.feed.uzoreto.com 3
TOTAL: 10 523
No colon-space in header
csiph-out.news.xs4all.nl 14
csiph-out.feeder.erje.net 2
TOTAL: 2 16
TOTAL: 4 746

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1xanadu-bbs 18988 875 14801 18 0 0 4%23:55:20
2fu-berlin.de 14552 526 14026 0 0 0 3%23:54:01
3news.samoylyk.net 16043 519 15151 7 0 12 3%23:53:14
4news.bbs.nz 29611 345 14765 30 0 0 1%23:57:30
5newsfeed.xs4all.nl 15494 237 13614 1 0 20 1%23:54:28
6tnet 16991 143 14960 3 0 0 0%23:50:08
7xmission 15386 91 12497 10 0 0 0%23:50:08
8neva.ru 14116 16 13893 121 0 0 0%23:50:08
9erje 14171 13 13988 116 0 0 0%23:49:52
10news.mixmin.net 12114 12 11864 0 0 0 0%23:50:08
11news.freedyn.net 15669 10 15367 58 0 0 0%23:50:08
12neodome.net 15711 9 15388 0 0 0 0%23:50:08
13weretis.net 14502 8 14132 0 0 0 0%23:58:36
14news.uzoreto.com 14810 5 14533 8 0 0 0%23:54:31
15aioe 10936 4 10590 0 0 0 0%23:50:08
16news.swapon.de 1579 3 1574 0 0 0 0%23:40:32
17usenet.pasdenom.info 0 0 0 0 0 15123 0%23:50:10
18eternal-september 0 0 0 0 0 12629 0%23:50:08
19news.fcku.it 1522 0 1522 0 0 0 0%20:53:43
TOTAL: 19 242195 2816 212665 372 0 27784 1%450:23:01
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1xanadu-bbs6.7 MB62.0 KB6.8 MB0.1 KB/s7.8 KB23:55:20
2fu-berlin.de5.8 MB0.0 KB5.8 MB0.1 KB/s11.2 KB23:54:01
3news.samoylyk.net5.7 MB9.5 KB5.7 MB0.1 KB/s11.2 KB23:53:14
4news.bbs.nz855.0 KB145.3 KB1000.3 KB0.0 KB/s2.7 KB23:57:30
5newsfeed.xs4all.nl684.5 KB1.4 KB685.8 KB0.0 KB/s2.9 KB23:54:28
6tnet333.8 KB5.3 KB339.1 KB0.0 KB/s2.3 KB23:50:08
7xmission220.9 KB21.0 KB241.9 KB0.0 KB/s2.4 KB23:50:08
8neodome.net31.1 KB0.0 KB31.1 KB0.0 KB/s3.5 KB23:50:08
9neva.ru28.2 KB357.4 KB385.6 KB0.0 KB/s2.8 KB23:50:08
10news.freedyn.net24.2 KB957.9 KB982.0 KB0.0 KB/s14.4 KB23:50:08
11erje20.4 KB452.6 KB473.0 KB0.0 KB/s3.7 KB23:49:52
12news.mixmin.net15.1 KB0.0 KB15.1 KB0.0 KB/s1.3 KB23:50:08
13weretis.net12.2 KB0.0 KB12.2 KB0.0 KB/s1.5 KB23:58:36
14news.uzoreto.com4.9 KB60.5 KB65.4 KB0.0 KB/s5.0 KB23:54:31
15aioe4.2 KB0.0 KB4.2 KB0.0 KB/s1.1 KB23:50:08
16news.swapon.de2.9 KB0.0 KB2.9 KB0.0 KB/s1.0 KB23:40:32
17usenet.pasdenom.info0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:10
18eternal-september0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:08
19news.fcku.it0.0 KB0.0 KB0.0 KB0.0 KB/s020:53:43
TOTAL: 1920.4 MB2.0 MB22.4 MB0.0 KB/s7.2 KB450:23:01
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1a131033.upc-a.chello.nl 84 2746.4 KB 5 0 020:10:59
2aorleans-654-1-57-30.w90-20.abo.wanadoo.fr 2 1757.4 KB 1 0 000:00:27
391.187.33.87 84 1626.4 KB 20 1 125:00:56
4071-013-248-064.res.spectrum.com 5 33.5 KB 3 2 101:07:54
TOTAL: 4 175 63133.7 KB 29 3 249:36:05

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.upc-a.chello.nl 84 2746.4 KB 5 0 020:10:59
2*.w90-20.abo.wanadoo.fr 2 1757.4 KB 1 0 000:00:27
3unresolved 84 1626.4 KB 20 1 125:00:56
4*.res.spectrum.com 5 33.5 KB 3 2 101:07:54
5*.ph.ph.cox.net 5 00.0 KB 0 0 002:30:18
6*.range86-130.btcentralplus.com 7 00.0 KB 0 0 000:45:29
TOTAL: 6 187 63133.7 KB 29 3 249:36:05

NNRP auth users [Top 20]:

UserConn
slavotinek 1
ohlandl 1
TOTAL: 2 2

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
91.187.33.87 5.629 1.598 0.00325:00:56
a131033.upc-a.chello.nl 5.631 1.531 0.00020:10:59
ip72-222-200-117.ph.ph.cox.net 0.516 0.121 0.00002:30:18
071-013-248-064.res.spectrum.com 0.363 0.127 0.00301:07:54
host86-130-133-6.range86-130.btcentralplus.com 0.471 0.119 0.00000:45:29
aorleans-654-1-57-30.w90-20.abo.wanadoo.fr 0.173 0.038 0.00000:00:27
TOTAL: 6 12.783 3.534 0.00649:36:05

Curious NNRP server explorers [Top 20]:

SystemConn
host86-130-133-6.range86-130.btcentralplus.com 7
ip72-222-200-117.ph.ph.cox.net 5
TOTAL: 2 12

NNRP client timeouts [Top 20]:

SystemConnPeer
91.187.33.87 7 0
ip72-222-200-117.ph.ph.cox.net 5 0
071-013-248-064.res.spectrum.com 1 0
a131033.upc-a.chello.nl 1 0
host86-130-133-6.range86-130.btcentralplus.com 1 0
TOTAL: 5 15 0

Newsgroup request counts (by category):

CategoryCountPct
1comp 50 74.6%
2fr 17 25.4%
TOTAL: 2 67100.0%

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

NewsgroupCount
1comp.sys.ibm.ps2.hardware 50
2fr.usenet.forums.annonces 17
TOTAL: 2 67