Daily Usenet report for csiph.com

Jan 2 04:15:00 -- Jan 3 04:15:00

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Jan 2 15:13:34 csiph nnrpd[2422]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Jan 2 15:13:34 csiph nnrpd[2422]: 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 2 18:54:06 csiph nnrpd[13697]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Jan 2 18:54:06 csiph nnrpd[13697]: 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 19624 49.6%4.8 MB 64.1%
innd 14348 36.2%1.9 MB 24.9%
innfeed 3565 9.0%627.6 KB 8.1%
nnrpd 2046 5.2%220.5 KB 2.9%
nocem 20 0.1%2.5 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 6 39604 100.0%7.5 MB100.0%

History cache:

ReasonCount%Count
Positive hits 280725 50.3%
Negative hits 258225 46.2%
Do not exist 18752 3.4%
Cache misses 662 0.1%
TOTAL: 4 558364 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:10.967 0.0%312 0.000 35.151 43.000
article cleanup00:00:00.122 0.0%18550 0.000 0.007 0.054
article logging00:00:00.531 0.0%19000 0.000 0.028 3.479
article parse00:00:01.342 0.0%62563 0.000 0.021 0.567
article write00:00:59.135 0.1%15068 0.311 3.925 102.455
artlog/artcncl00:00:00.060 0.0%312 0.000 0.192 34.000
artlog/artparse00:00:00.091 0.0%297 0.000 0.306 14.333
data move00:00:00.230 0.0%526555 0.000 0.000 0.002
hisgrep/artcncl00:00:00.018 0.0%312 0.000 0.058 7.000
hishave/artcncl00:00:00.005 0.0%312 0.000 0.016 0.017
hishave/artparse00:00:00.001 0.0%294 0.000 0.003 0.125
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:31.232 0.0%559964 0.002 0.056 0.346
history sync00:00:01.005 0.0%375 0.000 2.680 15.333
history write00:00:47.900 0.1%18523 0.531 2.586 35.525
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
hiswrite/artparse00:00:00.687 0.0%294 0.000 2.337 27.000
idle23:55:03.778 99.6%519979 88.566 165.591 729.539
nntp read00:00:02.099 0.0%528895 0.001 0.004 0.011
overview write00:02:19.567 0.2%15068 0.077 9.262 997.543
perl filter00:00:27.972 0.0%18520 0.964 1.510 4.528
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.249 0.0%30156 0.000 0.008 0.057
TOTAL: 24:01:03.55724:00:26.991100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.038 0.0%18698 0.000 0.002 0.029
article prepare00:00:00.002 0.0%17764 0.000 0.000 0.010
article read00:00:01.485 0.0%2076 0.000 0.715 21.000
backlog stats00:00:00.210 0.0%332606 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.231 0.0%261879 0.001 0.005 0.011
data write00:00:01.500 0.0%244544 0.001 0.006 0.017
idle23:55:06.353100.0%332606 129.472 258.884 892.398
status file00:00:00.395 0.0%316 0.000 1.250 15.333
TOTAL: 23:55:17.69223:55:11.214100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle52:05:44.357 99.5%1057 82.000177430.801412302.667
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.017 0.0%2468 0.000 0.007 0.167
readart00:00:00.200 0.0%11 0.000 18.182 59.000
TOTAL: 52:21:45.15052:05:44.574 99.5%----

Control commands to INND:

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1csiph-out.news.xs4all.nl 0 7597 4036 1739 1822 53%73:54:52
2news-out.mixmin.net 2 16402 2134 13837 431 13%24:08:57
3feeder.eternal-september.org 14 4368 2055 2238 75 47%27:39:04
4csiph-out.news.weretis.net 4 38516 1464 36820 232 3%60:46:30
5newsfeeds.xmission.com 1 14615 1402 13163 50 9%25:57:10
6newsfeed.fu-berlin.de 23 18259 985 17081 193 5%34:17:29
7csiph-out.feeder.erje.net 1440 75433 938 74048 447 1%118:40:29
8newsfeed.neva.ru 148 10242 630 9320 292 6%72:16:47
9news-out.aioe.org 3 13995 630 13346 19 4%23:59:53
10csiph-out.feed.uzoreto.com 864 24067 368 23379 320 1%71:15:44
11news-out.neodome.net 1 16431 275 16140 16 1%23:59:54
12news.freedyn.net 2 17924 122 17680 122 0%23:59:57
13news.fcku.it 19 1148 106 1042 0 9%21:00:26
14nntp.pasdenom.info 3 9518 101 9405 12 1%21:29:55
15news.swapon.de 7 1349 47 1301 1 3%23:20:38
16csiph-out.news.tnetconsulting.net 1 13737 39 13697 1 0%23:59:57
17news.samoylyk.net 2 14300 6 14294 0 0%23:59:54
18news.bbs.nz 1 13423 4 13419 0 0%23:59:50
19localhost 26 0 0 0 0 0%00:00:01
20xanadu-bbs.net 28 405 0 405 0 0%21:26:56
TOTAL: 20 2589 311729 15342 292354 4033 4%740:14:23
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1csiph-out.news.xs4all.nl11.7 MB2.9 KB10.6 MB22.4 MB 52%3.9 KB
2csiph-out.feeder.erje.net8.6 MB115.6 KB4.4 MB13.1 MB 65%9.7 KB
3news-out.mixmin.net6.7 MB9.0 KB2.3 MB9.0 MB 74%3.6 KB
4feeder.eternal-september.org5.7 MB0.0 KB934.4 KB6.6 MB 86%3.2 KB
5csiph-out.news.weretis.net5.1 MB0.0 KB2.6 MB7.8 MB 66%4.7 KB
6newsfeeds.xmission.com4.6 MB0.0 KB2.4 MB7.0 MB 66%4.9 KB
7newsfeed.fu-berlin.de2.4 MB0.0 KB559.5 KB2.9 MB 81%2.5 KB
8news-out.aioe.org2.2 MB0.0 KB43.2 KB2.3 MB 98%3.6 KB
9newsfeed.neva.ru2.2 MB1.2 KB797.6 KB3.0 MB 73%3.3 KB
10csiph-out.feed.uzoreto.com1.2 MB0.0 KB4.9 MB6.1 MB 20%9.0 KB
11news-out.neodome.net909.9 KB0.0 KB46.4 KB956.3 KB 95%3.3 KB
12news.freedyn.net615.1 KB0.0 KB3.0 MB3.6 MB 16%15.1 KB
13nntp.pasdenom.info262.6 KB0.0 KB14.7 KB277.4 KB 94%2.5 KB
14news.fcku.it183.9 KB0.0 KB0.0 KB183.9 KB100%1.7 KB
15csiph-out.news.tnetconsulting.net150.8 KB0.0 KB92.3 KB243.1 KB 62%6.1 KB
16news.swapon.de112.8 KB0.0 KB0.7 KB113.6 KB 99%2.4 KB
17news.samoylyk.net18.8 KB0.0 KB0.0 KB18.8 KB100%3.1 KB
18news.bbs.nz9.9 KB0.0 KB0.0 KB9.9 KB100%2.5 KB
19localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
20xanadu-bbs.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 2052.7 MB128.6 KB32.6 MB85.4 MB 61%4.5 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jan 2 04:15:00 - 04:59:59 202 1.3% 0.07714.3 KB 1.3% 0.26
Jan 2 05:00:00 - 05:59:59 582 3.9% 0.162.5 MB 4.8% 0.71
Jan 2 06:00:00 - 06:59:59 238 1.6% 0.07766.8 KB 1.4% 0.21
Jan 2 07:00:00 - 07:59:59 269 1.8% 0.07801.8 KB 1.5% 0.22
Jan 2 08:00:00 - 08:59:59 545 3.6% 0.152.0 MB 3.9% 0.57
Jan 2 09:00:00 - 09:59:59 617 4.1% 0.171.6 MB 3.1% 0.47
Jan 2 10:00:00 - 10:59:59 746 4.9% 0.212.7 MB 5.3% 0.78
Jan 2 11:00:00 - 11:59:59 784 5.2% 0.223.2 MB 6.2% 0.92
Jan 2 12:00:00 - 12:59:59 788 5.2% 0.222.6 MB 5.1% 0.75
Jan 2 13:00:00 - 13:59:59 822 5.5% 0.232.9 MB 5.6% 0.83
Jan 2 14:00:00 - 14:59:59 819 5.4% 0.232.2 MB 4.2% 0.63
Jan 2 15:00:00 - 15:59:59 793 5.3% 0.222.7 MB 5.2% 0.77
Jan 2 16:00:00 - 16:59:59 1027 6.8% 0.295.0 MB 9.6% 1.42
Jan 2 17:00:00 - 17:59:59 919 6.1% 0.263.4 MB 6.5% 0.96
Jan 2 18:00:00 - 18:59:59 860 5.7% 0.242.6 MB 5.1% 0.75
Jan 2 19:00:00 - 19:59:59 760 5.0% 0.212.0 MB 3.9% 0.58
Jan 2 20:00:00 - 20:59:59 699 4.6% 0.192.4 MB 4.6% 0.68
Jan 2 21:00:00 - 21:59:59 635 4.2% 0.182.1 MB 4.0% 0.59
Jan 2 22:00:00 - 22:59:59 579 3.8% 0.161.8 MB 3.4% 0.51
Jan 2 23:00:00 - 23:59:59 565 3.7% 0.161.9 MB 3.7% 0.55
Jan 3 00:00:00 - 00:59:59 458 3.0% 0.131.6 MB 3.0% 0.45
Jan 3 01:00:00 - 01:59:59 461 3.1% 0.131.4 MB 2.7% 0.41
Jan 3 02:00:00 - 02:59:59 502 3.3% 0.141.5 MB 2.9% 0.43
Jan 3 03:00:00 - 03:59:59 329 2.2% 0.091.3 MB 2.4% 0.36
Jan 3 04:00:00 - 04:15:00 83 0.6% 0.09259.3 KB 0.5% 0.29
TOTAL: 24:00:00 15082 100.0% 0.1752.1 MB 100.0% 0.62
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1csiph-out.news.xs4all.nl 1916 90 0 1 0 0 0 0 1825
2newsfeed.fu-berlin.de 538 15 0 0 0 0 0 0 523
3news-out.mixmin.net 435 8 0 3 0 0 0 0 424
4csiph-out.feed.uzoreto.com 323 22 0 0 0 0 0 0 301
5newsfeed.neva.ru 294 46 0 1 0 0 0 0 247
6csiph-out.feeder.erje.net 213 41 0 25 0 0 0 0 147
7csiph-out.news.weretis.net 180 55 0 0 0 0 0 0 125
8news.freedyn.net 122 0 0 0 0 0 0 0 122
9feeder.eternal-september.org 76 20 0 0 0 0 0 0 56
10newsfeeds.xmission.com 68 14 0 0 0 0 0 0 54
11news-out.aioe.org 20 15 0 0 0 0 0 0 5
12news.swapon.de 18 0 0 0 0 0 0 0 18
13news-out.neodome.net 15 2 0 0 0 0 0 0 13
14nntp.pasdenom.info 12 0 0 0 0 0 0 0 12
TOTAL: 14 4230 328 0 30 0 0 0 0 3872

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.computer.workshop 56
alt.politics 48
lada.talk 33
scinet.stats 30
alt.binaries.pictures.purebeauty 20
alt.survival 18
alt.tv.comedy-central.daily-show 10
it-alt.comp.software.mozilla 9
spline.eisfair 8
alt.math.undergrad 5
oesterreich.tratsch 5
lada.auto 4
alt.politics.usa 4
mozilla.general 4
mozilla.test 4
mozilla.support.seamonkey 4
rec.travel.resorts.all 3
alt.pl.comp.os.linux.ubuntu 3
lada.commerce 3
alt.philosophy 3
TOTAL: 60 328

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 2302
EMP (phn path) 386
EMP (phn nph) 236
Too many newsgroups 73
EMP (phl) 52
0 48
HTML Multipart 11
Binary: misplaced binary 10
Binary Payload 4
Scoring filter 2
HTML post 1
U2 violation - invalid distribution 1
TOTAL: 12 3126

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
csiph-out.feeder.erje.net 50
csiph-out.feed.uzoreto.com 2
csiph-out.news.xs4all.nl 2
TOTAL: 3 54
Huge articles
csiph-out.feeder.erje.net 80
csiph-out.news.xs4all.nl 55
csiph-out.feed.uzoreto.com 38
news.freedyn.net 37
newsfeeds.xmission.com 36
csiph-out.news.weretis.net 27
feeder.eternal-september.org 9
news-out.mixmin.net 8
newsfeed.fu-berlin.de 4
TOTAL: 9 294
Including strange strings
newsfeed.fu-berlin.de 360
csiph-out.news.xs4all.nl 31
news.swapon.de 17
newsfeeds.xmission.com 17
csiph-out.news.weretis.net 8
news-out.mixmin.net 5
newsfeed.neva.ru 3
csiph-out.feed.uzoreto.com 3
csiph-out.feeder.erje.net 2
news.freedyn.net 1
TOTAL: 12 449
No colon-space in header
csiph-out.feeder.erje.net 2
csiph-out.news.xs4all.nl 1
TOTAL: 2 3
TOTAL: 4 800

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1xanadu-bbs 17452 1256 13819 7 0 0 7%23:59:51
2news.bbs.nz 27265 819 13473 33 0 0 3%23:50:07
3news.samoylyk.net 15450 655 14363 7 0 0 4%23:50:07
4fu-berlin.de 14012 653 13358 0 0 0 4%23:50:07
5tnet 16271 194 14134 4 0 0 1%23:50:07
6usenet.pasdenom.info 25083 161 13986 125 0 3619 0%23:59:55
7xmission 14601 109 12812 5 0 0 0%23:59:42
8newsfeed.xs4all.nl 11376 51 10736 0 0 13 0%23:54:33
9neva.ru 13583 12 13300 213 0 0 0%23:59:55
10erje 14028 12 13853 20 0 0 0%23:54:47
11news.freedyn.net 14024 5 13575 63 0 0 0%23:57:24
12aioe 12040 4 11705 0 0 0 0%23:55:38
13neodome.net 15003 3 14635 0 0 0 0%23:50:07
14weretis.net 12892 2 12730 1 0 0 0%23:50:07
15news.mixmin.net 12121 2 11798 1 0 0 0%23:50:07
16news.uzoreto.com 13832 2 13573 10 0 0 0%23:50:07
17eternal-september 0 0 0 0 0 12251 0%23:59:55
18news.fcku.it 995 0 995 0 0 0 0%20:14:55
19news.swapon.de 1311 0 1305 0 0 0 0%23:14:13
TOTAL: 19 251339 3940 214150 489 0 15883 1%449:51:44
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1xanadu-bbs9.3 MB47.3 KB9.3 MB0.1 KB/s7.6 KB23:59:51
2fu-berlin.de7.7 MB0.0 KB7.7 MB0.1 KB/s12.0 KB23:50:07
3news.samoylyk.net7.6 MB95.2 KB7.7 MB0.1 KB/s11.9 KB23:50:07
4news.bbs.nz2.4 MB227.8 KB2.6 MB0.0 KB/s3.1 KB23:50:07
5usenet.pasdenom.info562.0 KB262.5 KB824.5 KB0.0 KB/s2.9 KB23:59:55
6tnet526.8 KB5.4 KB532.2 KB0.0 KB/s2.7 KB23:50:07
7xmission247.4 KB9.8 KB257.2 KB0.0 KB/s2.3 KB23:59:42
8newsfeed.xs4all.nl118.5 KB0.0 KB118.5 KB0.0 KB/s2.3 KB23:54:33
9neva.ru18.7 KB493.9 KB512.7 KB0.0 KB/s2.3 KB23:59:55
10erje16.6 KB38.9 KB55.6 KB0.0 KB/s1.7 KB23:54:47
11aioe11.9 KB0.0 KB11.9 KB0.0 KB/s3.0 KB23:55:38
12news.freedyn.net11.6 KB821.7 KB833.3 KB0.0 KB/s12.3 KB23:57:24
13neodome.net6.3 KB0.0 KB6.3 KB0.0 KB/s2.1 KB23:50:07
14weretis.net3.6 KB1.1 KB4.8 KB0.0 KB/s1.6 KB23:50:07
15news.mixmin.net2.5 KB1.4 KB3.9 KB0.0 KB/s1.3 KB23:50:07
16news.uzoreto.com2.4 KB100.3 KB102.7 KB0.0 KB/s8.6 KB23:50:07
17eternal-september0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:55
18news.fcku.it0.0 KB0.0 KB0.0 KB0.0 KB/s020:14:55
19news.swapon.de0.0 KB0.0 KB0.0 KB0.0 KB/s023:14:13
TOTAL: 1928.4 MB2.1 MB30.5 MB0.0 KB/s7.0 KB449:51:44
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1aorleans-654-1-138-187.w90-20.abo.wanadoo.fr 1 533.1 KB 1 0 000:00:05
291.187.33.87 285 35.0 KB 3 0 042:10:42
3a131033.upc-a.chello.nl 55 35.0 KB 2 0 010:09:20
4212.90.61.180 1 00.0 KB 11 0 000:00:00
TOTAL: 4 342 1143.0 KB 17 0 052:21:42

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.w90-20.abo.wanadoo.fr 1 533.1 KB 1 0 000:00:05
2unresolved 286 35.0 KB 14 0 042:10:42
3*.upc-a.chello.nl 55 35.0 KB 2 0 010:09:20
4*.security.ipip.net 1 00.0 KB 0 0 000:00:00
5*.range31-50.btcentralplus.com 2 00.0 KB 0 0 000:01:33
TOTAL: 5 345 1143.0 KB 17 0 052:21:42

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
91.187.33.87 17.890 4.390 0.00042:10:42
a131033.upc-a.chello.nl 3.489 0.849 0.00010:09:20
host31-50-220-99.range31-50.btcentralplus.com 0.124 0.036 0.00000:01:33
aorleans-654-1-138-187.w90-20.abo.wanadoo.fr 0.089 0.032 0.00000:00:05
scan-141.security.ipip.net 0.059 0.017 0.00000:00:00
TOTAL: 5 21.651 5.324 0.00052:21:42

Curious NNRP server explorers [Top 20]:

SystemConn
host31-50-220-99.range31-50.btcentralplus.com 2
scan-141.security.ipip.net 1
TOTAL: 2 3

NNRP unrecognized commands (by host):

SystemConn
scan-141.security.ipip.net 4
TOTAL: 1 4

NNRP unrecognized commands (by command):

CommandCount
User-Agent: HTTP Banner Detection (https... 1
Host: 69.160.37.58:119 1
Connection: close 1
GET / HTTP/1.1 1
TOTAL: 4 4

Newsgroup request counts (by category):

CategoryCountPct
1soc 29135 94.1%
2alt 1749 5.7%
3comp 55 0.2%
4fr 5 0.0%
5no 2 0.0%
TOTAL: 5 30946100.0%

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

NewsgroupCount
1soc.culture.vietnamese 29135
2alt.tv.star-trek.voyager 611
3alt.fan.tolkien 478
4alt.uk.edinburgh.misc 395
5alt.comp.test 160
6alt.fan.countries.libya 53
7comp.sys.palmtops.pilot 49
8alt.fan.sailor-moon 27
9alt.games.abandonware 24
10comp.sys.ibm.ps2.hardware 6
11fr.usenet.forums.annonces 5
12no.fritid.slektsforsking.it 2
13alt.drugs.banana-peels 1
TOTAL: 13 30946