Daily Usenet report for csiph.com

Dec 21 04:15:00 -- Dec 22 04:15:00

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Dec 21 10:36:11 csiph nnrpd[88859]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Dec 21 10:36:11 csiph nnrpd[88859]: 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 21 10:48:03 csiph nnrpd[89455]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Dec 21 10:48:03 csiph nnrpd[89455]: 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 21 11:19:05 csiph nnrpd[91232]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Dec 21 11:19:05 csiph nnrpd[91232]: 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 21 13:31:36 csiph nnrpd[97979]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Dec 21 13:31:36 csiph nnrpd[97979]: 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 19230 48.7%4.9 MB 63.8%
innd 15642 39.6%2.1 MB 26.8%
innfeed 3803 9.6%643.8 KB 8.2%
nnrpd 823 2.1%95.7 KB 1.2%
nocem 23 0.1%2.7 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 6 39522 100.0%7.7 MB100.0%

History cache:

ReasonCount%Count
Positive hits 307604 54.1%
Negative hits 242255 42.6%
Do not exist 18562 3.3%
Cache misses 394 0.1%
TOTAL: 4 568815 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.296 0.0%40 0.000 7.400 19.833
article cleanup00:00:00.106 0.0%18259 0.000 0.006 0.029
article logging00:00:00.293 0.0%18803 0.000 0.016 0.238
article parse00:00:00.892 0.0%54922 0.000 0.016 0.116
article write00:00:13.850 0.0%15410 0.149 0.899 18.164
artlog/artcncl00:00:00.000 0.0%40 0.000 0.000 0.000
artlog/artparse00:00:00.008 0.0%336 0.000 0.024 1.000
data move00:00:00.161 0.0%529416 0.000 0.000 0.002
hisgrep/artcncl00:00:00.037 0.0%38 0.000 0.974 4.667
hishave/artcncl00:00:00.065 0.0%40 0.000 1.625 4.833
hishave/artparse00:00:00.001 0.0%331 0.000 0.003 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:10.034 0.0%569079 0.000 0.018 0.299
history sync00:00:00.876 0.0%371 0.000 2.361 17.250
history write00:00:16.165 0.0%18247 0.016 0.886 10.041
hiswrite/artcncl00:00:00.000 0.0%2 0.000 0.000 0.000
hiswrite/artparse00:00:00.372 0.0%331 0.000 1.124 14.000
idle23:48:37.146 99.8%534858 87.462 160.262 631.930
nntp read00:00:02.075 0.0%536578 0.001 0.004 0.007
overview write00:00:34.795 0.0%15410 0.091 2.258 28.627
perl filter00:00:26.011 0.0%18242 1.029 1.426 2.167
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.258 0.0%30841 0.000 0.008 0.031
TOTAL: 23:50:58.50523:50:23.441100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.043 0.0%16449 0.000 0.003 0.030
article prepare00:00:00.001 0.0%10968 0.000 0.000 0.014
article read00:00:00.226 0.0%1355 0.000 0.167 15.333
backlog stats00:00:00.203 0.0%344458 0.000 0.001 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.237 0.0%269991 0.001 0.005 0.009
data write00:00:01.569 0.0%256016 0.002 0.006 0.014
idle23:54:44.048100.0%344458 138.939 249.912 769.326
status file00:00:00.243 0.0%393 0.000 0.618 2.000
TOTAL: 23:54:53.70223:54:47.570100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle38:48:41.638 99.8%471 56.500296648.913600239.333
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:01.298 0.0%2844 0.000 0.456 35.667
readart00:00:00.765 0.0%45 12.703 17.000 42.000
TOTAL: 38:53:34.69838:48:43.701 99.8%----

Control commands to INND:

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1news-out.aioe.org 3 14729 3367 11211 151 22%24:09:10
2newsfeeds.xmission.com 3 14345 2508 11783 54 17%23:01:53
3feeder.eternal-september.org 18 6124 2379 3624 121 38%27:36:26
4csiph-out.news.xs4all.nl 503 17864 1856 14927 1081 10%67:01:08
5news-out.mixmin.net 2 16525 951 15316 258 5%24:08:52
6newsfeed.fu-berlin.de 17 14028 849 12902 277 6%23:13:09
7csiph-out.news.weretis.net 2 30714 802 29671 241 2%47:59:49
8csiph-out.feeder.erje.net 1440 76651 750 75642 259 0%118:47:10
9newsfeed.neva.ru 152 14322 651 13357 314 4%72:20:21
10csiph-out.feed.uzoreto.com 864 24272 309 23637 326 1%71:12:23
11news-out.neodome.net 2 16502 300 16193 9 1%23:59:55
12news.fcku.it 13 1613 246 1366 1 15%21:03:40
13news.freedyn.net 1 17803 95 17499 209 0%23:59:57
14news.swapon.de 6 1480 54 1422 4 3%23:12:13
15nntp.pasdenom.info 2 10360 52 10303 5 0%23:59:52
16csiph-out.news.tnetconsulting.net 11 26589 40 26526 23 0%41:07:51
17news.samoylyk.net 1 14950 10 14939 1 0%23:59:54
18news.bbs.nz 1 13697 1 13696 0 0%23:59:51
19xanadu-bbs.net 30 413 0 413 0 0%21:26:33
20localhost 26 0 0 0 0 0%00:00:01
TOTAL: 20 3097 332981 15220 314427 3334 4%726:20:08
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news-out.aioe.org8.1 MB0.0 KB568.7 KB8.6 MB 93%2.5 KB
2newsfeeds.xmission.com7.8 MB0.0 KB3.4 MB11.3 MB 69%4.5 KB
3feeder.eternal-september.org6.0 MB0.0 KB751.8 KB6.7 MB 89%2.8 KB
4csiph-out.feeder.erje.net5.4 MB35.6 KB2.9 MB8.3 MB 64%8.4 KB
5csiph-out.news.xs4all.nl4.8 MB0.0 KB5.4 MB10.2 MB 47%3.6 KB
6news-out.mixmin.net2.5 MB0.0 KB537.7 KB3.0 MB 82%2.6 KB
7csiph-out.news.weretis.net2.2 MB0.0 KB2.7 MB4.9 MB 44%4.8 KB
8newsfeed.fu-berlin.de1.9 MB0.0 KB623.3 KB2.5 MB 75%2.3 KB
9newsfeed.neva.ru1.6 MB0.0 KB1.1 MB2.8 MB 59%2.9 KB
10news-out.neodome.net1.0 MB0.0 KB102.4 KB1.1 MB 91%3.8 KB
11csiph-out.feed.uzoreto.com1015.0 KB0.0 KB6.0 MB7.0 MB 14%11.3 KB
12news.freedyn.net406.0 KB0.0 KB3.2 MB3.6 MB 10%12.2 KB
13news.fcku.it370.7 KB0.0 KB1.0 KB371.7 KB 99%1.5 KB
14csiph-out.news.tnetconsulting.net144.3 KB0.0 KB134.8 KB279.1 KB 51%4.4 KB
15news.swapon.de119.2 KB0.0 KB7.2 KB126.4 KB 94%2.2 KB
16nntp.pasdenom.info101.6 KB0.0 KB6.1 KB107.7 KB 94%1.9 KB
17news.samoylyk.net26.8 KB0.0 KB63.3 KB90.1 KB 29%8.2 KB
18news.bbs.nz2.6 KB0.0 KB0.0 KB2.6 KB100%2.6 KB
19xanadu-bbs.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
20localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 2043.5 MB35.6 KB27.5 MB71.1 MB 61%3.9 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Dec 21 04:15:00 - 04:59:59 172 1.1% 0.06460.2 KB 1.0% 0.17
Dec 21 05:00:00 - 05:59:59 316 2.0% 0.09908.3 KB 2.0% 0.25
Dec 21 06:00:00 - 06:59:59 349 2.3% 0.10957.3 KB 2.1% 0.27
Dec 21 07:00:00 - 07:59:59 466 3.0% 0.131.3 MB 2.8% 0.36
Dec 21 08:00:00 - 08:59:59 554 3.6% 0.152.3 MB 5.1% 0.64
Dec 21 09:00:00 - 09:59:59 725 4.7% 0.201.9 MB 4.3% 0.54
Dec 21 10:00:00 - 10:59:59 784 5.1% 0.222.1 MB 4.6% 0.58
Dec 21 11:00:00 - 11:59:59 771 5.0% 0.212.0 MB 4.4% 0.56
Dec 21 12:00:00 - 12:59:59 851 5.5% 0.242.2 MB 4.9% 0.62
Dec 21 13:00:00 - 13:59:59 757 4.9% 0.212.0 MB 4.4% 0.56
Dec 21 14:00:00 - 14:59:59 872 5.6% 0.242.2 MB 4.9% 0.61
Dec 21 15:00:00 - 15:59:59 1063 6.9% 0.303.4 MB 7.6% 0.96
Dec 21 16:00:00 - 16:59:59 969 6.3% 0.273.2 MB 7.3% 0.92
Dec 21 17:00:00 - 17:59:59 973 6.3% 0.272.7 MB 6.2% 0.78
Dec 21 18:00:00 - 18:59:59 789 5.1% 0.222.1 MB 4.7% 0.60
Dec 21 19:00:00 - 19:59:59 775 5.0% 0.222.2 MB 4.9% 0.62
Dec 21 20:00:00 - 20:59:59 768 5.0% 0.212.3 MB 5.2% 0.65
Dec 21 21:00:00 - 21:59:59 671 4.3% 0.191.9 MB 4.3% 0.54
Dec 21 22:00:00 - 22:59:59 586 3.8% 0.161.7 MB 3.8% 0.48
Dec 21 23:00:00 - 23:59:59 517 3.3% 0.141.6 MB 3.6% 0.45
Dec 22 00:00:00 - 00:59:59 386 2.5% 0.111.1 MB 2.5% 0.31
Dec 22 01:00:00 - 01:59:59 394 2.6% 0.111.1 MB 2.5% 0.31
Dec 22 02:00:00 - 02:59:59 439 2.8% 0.121.3 MB 3.0% 0.37
Dec 22 03:00:00 - 03:59:59 427 2.8% 0.121.3 MB 3.0% 0.38
Dec 22 04:00:00 - 04:15:00 75 0.5% 0.08314.0 KB 0.7% 0.35
TOTAL: 24:00:00 15449 100.0% 0.1844.3 MB 100.0% 0.52
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1csiph-out.news.xs4all.nl 1093 71 0 0 0 0 0 0 1022
2newsfeed.fu-berlin.de 683 11 0 0 0 0 0 0 672
3csiph-out.feed.uzoreto.com 330 31 0 0 1 0 0 0 298
4newsfeed.neva.ru 314 52 0 0 0 0 0 0 262
5news-out.mixmin.net 257 1 0 0 0 0 0 0 256
6news-out.aioe.org 243 137 0 0 0 0 0 0 106
7news.freedyn.net 205 1 0 0 0 0 0 0 204
8csiph-out.news.weretis.net 203 67 0 0 0 0 0 0 136
9csiph-out.feeder.erje.net 165 31 0 17 0 0 0 0 117
10feeder.eternal-september.org 126 74 0 0 0 0 0 0 52
11newsfeeds.xmission.com 60 5 0 0 0 0 0 0 55
12news.swapon.de 32 0 0 0 0 0 0 0 32
13news-out.neodome.net 12 0 0 0 0 0 0 0 12
14csiph-out.news.tnetconsulting.net 10 10 0 0 0 0 0 0 0
15nntp.pasdenom.info 5 0 0 0 0 0 0 0 5
16news.samoylyk.net 2 0 0 0 0 0 0 0 2
17news.fcku.it 1 1 0 0 0 0 0 0 0
TOTAL: 17 3741 492 0 17 1 0 0 0 3231

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.computer.workshop 214
lada.talk 33
scinet.stats 29
mozilla.support.firefox 13
it-alt.sport.calcio.juventus 12
alt.politics 11
mozilla.general 11
alt.survival 10
alt.english.usage 10
att.jobs 10
oesterreich.tratsch 8
alt.binaries.pictures.purebeauty 8
aus.jobs 7
lada.auto 7
spline.eisfair 7
alt.music.pink-floyd.publius 7
at.jobs 7
cakewalk.audio 6
alt.job 6
alt.tv.comedy-central.daily-show 5
TOTAL: 67 492

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

GroupsCount
uk.legal.moderated 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1567
EMP (phn path) 390
EMP (phn nph) 140
EMP (phl) 76
Too many newsgroups 55
0 49
HTML Multipart 20
Binary Payload 19
Scoring filter 16
Binary: misplaced binary 5
Too many newsgroups (meow) 3
HTML post 2
TOTAL: 12 2342

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
csiph-out.feeder.erje.net 45
TOTAL: 1 45
Huge articles
csiph-out.feed.uzoreto.com 87
csiph-out.news.xs4all.nl 70
csiph-out.feeder.erje.net 51
newsfeeds.xmission.com 49
news.freedyn.net 40
csiph-out.news.weretis.net 29
feeder.eternal-september.org 7
news-out.mixmin.net 3
news-out.neodome.net 1
news.samoylyk.net 1
TOTAL: 10 338
Including strange strings
newsfeed.fu-berlin.de 399
news-out.aioe.org 90
news.swapon.de 28
csiph-out.news.weretis.net 9
csiph-out.news.xs4all.nl 8
newsfeeds.xmission.com 5
csiph-out.feed.uzoreto.com 3
news-out.neodome.net 1
csiph-out.feeder.erje.net 1
feeder.eternal-september.org 1
TOTAL: 11 546
No colon-space in header
csiph-out.news.xs4all.nl 3
csiph-out.feeder.erje.net 1
csiph-out.feed.uzoreto.com 1
TOTAL: 3 5
TOTAL: 4 934

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1xanadu-bbs 19014 806 14623 20 0 58 4%23:59:56
2fu-berlin.de 14338 474 13844 0 0 97 3%23:59:56
3news.samoylyk.net 15829 469 14963 7 0 0 2%23:59:56
4news.bbs.nz 29414 311 14622 24 0 1 1%23:59:34
5newsfeed.xs4all.nl 14518 174 13326 0 0 831 1%23:59:55
6erje 16092 78 14267 91 0 1 0%23:59:27
7xmission 14396 55 12204 3 0 0 0%23:59:56
8tnet 16312 41 14915 0 0 36 0%23:59:56
9neva.ru 14021 30 13856 43 0 0 0%23:59:12
10usenet.pasdenom.info 15315 8 14888 6 0 0 0%23:59:34
11news.freedyn.net 14945 6 14677 69 0 0 0%23:59:56
12neodome.net 15338 6 15032 0 0 0 0%23:59:50
13news.uzoreto.com 14908 3 14694 13 0 0 0%23:59:56
14news.swapon.de 1421 3 1415 0 0 0 0%23:26:18
15news.mixmin.net 13867 2 13671 0 0 0 0%23:59:56
16aioe 10687 2 10406 0 0 0 0%23:59:49
17news.fcku.it 1340 0 1336 0 0 0 0%20:54:15
18eternal-september 0 0 0 0 0 12476 0%23:59:56
19weretis.net 14310 0 13880 0 0 0 0%23:59:56
TOTAL: 19 256065 2468 226619 276 0 13500 0%452:17:14
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1xanadu-bbs5.6 MB60.8 KB5.6 MB0.1 KB/s7.0 KB23:59:56
2fu-berlin.de4.6 MB0.0 KB4.6 MB0.1 KB/s10.0 KB23:59:56
3news.samoylyk.net4.5 MB95.5 KB4.6 MB0.1 KB/s9.9 KB23:59:56
4news.bbs.nz882.9 KB100.4 KB983.4 KB0.0 KB/s2.9 KB23:59:34
5newsfeed.xs4all.nl458.4 KB0.0 KB458.4 KB0.0 KB/s2.6 KB23:59:55
6erje217.7 KB544.1 KB761.8 KB0.0 KB/s4.5 KB23:59:27
7xmission157.8 KB4.7 KB162.5 KB0.0 KB/s2.8 KB23:59:56
8tnet105.1 KB0.0 KB105.1 KB0.0 KB/s2.6 KB23:59:56
9neva.ru77.2 KB143.2 KB220.4 KB0.0 KB/s3.0 KB23:59:12
10usenet.pasdenom.info54.1 KB7.8 KB61.9 KB0.0 KB/s4.4 KB23:59:34
11news.freedyn.net53.3 KB1.2 MB1.2 MB0.0 KB/s17.0 KB23:59:56
12news.mixmin.net42.7 KB0.0 KB42.7 KB0.0 KB/s21.3 KB23:59:56
13neodome.net34.2 KB0.0 KB34.2 KB0.0 KB/s5.7 KB23:59:50
14aioe6.8 KB0.0 KB6.8 KB0.0 KB/s3.4 KB23:59:49
15news.uzoreto.com5.9 KB122.6 KB128.5 KB0.0 KB/s8.0 KB23:59:56
16news.swapon.de5.4 KB0.0 KB5.4 KB0.0 KB/s1.8 KB23:26:18
17news.fcku.it0.0 KB0.0 KB0.0 KB0.0 KB/s020:54:15
18eternal-september0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:56
19weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:56
TOTAL: 1916.8 MB2.2 MB19.0 MB0.0 KB/s7.1 KB452:17:14
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1aorleans-654-1-9-13.w90-20.abo.wanadoo.fr 4 3773.0 KB 1 0 200:00:38
2a131033.upc-a.chello.nl 45 49.4 KB 4 0 013:00:51
391.187.33.87 78 49.2 KB 4 0 025:40:04
TOTAL: 3 127 4591.6 KB 31 0 238:53:33

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.w90-20.abo.wanadoo.fr 4 3773.0 KB 1 0 200:00:38
2*.upc-a.chello.nl 45 49.4 KB 4 0 013:00:51
3unresolved 79 49.2 KB 26 0 025:40:04
4*.range31-50.btcentralplus.com 4 00.0 KB 0 0 000:11:50
5*.dip0.t-ipconnect.de 1 00.0 KB 0 0 000:00:06
6*.security.ipip.net 1 00.0 KB 0 0 000:00:01
TOTAL: 6 134 4591.6 KB 31 0 238:53:33

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
91.187.33.87 5.066 1.212 0.00025:40:04
a131033.upc-a.chello.nl 3.114 0.631 0.00013:00:51
host31-50-220-99.range31-50.btcentralplus.com 0.275 0.040 0.00000:11:50
aorleans-654-1-9-13.w90-20.abo.wanadoo.fr 0.312 0.127 0.00000:00:38
p3ee23077.dip0.t-ipconnect.de 0.098 0.060 0.00000:00:06
scan-141.security.ipip.net 0.058 0.017 0.00000:00:01
45.146.165.32 0.070 0.008 0.00000:00:00
TOTAL: 7 8.993 2.095 0.00038:53:33

Curious NNRP server explorers [Top 20]:

SystemConn
host31-50-220-99.range31-50.btcentralplus.com 4
scan-141.security.ipip.net 1
45.146.165.32 1
p3ee23077.dip0.t-ipconnect.de 1
TOTAL: 4 7

NNRP unrecognized commands (by host):

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

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

SystemConnPeer
91.187.33.87 6 0
45.146.165.32 1 1
a131033.upc-a.chello.nl 1 0
TOTAL: 3 8 1

Newsgroup request counts (by category):

CategoryCountPct
1alt 127462100.0%
2fr 37 0.0%
3comp 8 0.0%
TOTAL: 3 127507100.0%

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

NewsgroupCount
1alt.astrology.pro 63177
2alt.assassination.jfk 61154
3alt.astrology 1781
4alt.arts.poetry.comments 382
5alt.astrology.metapsych 366
6alt.asshole.al-gore 229
7alt.arts.poetry.urban 129
8alt.asshole.george.w.bush 53
9alt.astrology.marketplace 46
10fr.comp.os.mac-os.x 37
11alt.ascii-art 33
12alt.asshat.dowap 29
13alt.asshole.roger-wiseman 23
14alt.ast.test 15
15alt.astrology.moderated 11
16alt.astrology.asian 10
17comp.sys.ibm.ps2.hardware 8
18alt.arts.storytelling 8
19alt.asian-movies 6
20alt.asshole.chadwick-stone 3
21alt.asshole-factory.dowap 3
22alt.asshat 2
23alt.assassination.jfk.uncensored 1
24alt.astrology.cujo 1
TOTAL: 24 127507