Daily Usenet report for csiph.com

Dec 23 04:15:00 -- Dec 24 04:15:00

Unknown entries from news log file:

First 12 / 12 lines (100.0%)

Dec 23 11:42:14 csiph nnrpd[48030]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Dec 23 11:42:14 csiph nnrpd[48030]: 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 23 12:48:02 csiph nnrpd[51583]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Dec 23 12:48:02 csiph nnrpd[51583]: 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 23 16:54:41 csiph nnrpd[64498]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Dec 23 16:54:41 csiph nnrpd[64498]: 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 23 22:40:21 csiph nnrpd[82460]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Dec 23 22:40:21 csiph nnrpd[82460]: 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 23 22:45:59 csiph nnrpd[82829]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Dec 23 22:45:59 csiph nnrpd[82829]: 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 24 00:24:26 csiph nnrpd[87968]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Dec 24 00:24:26 csiph nnrpd[87968]: 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 19306 48.4%4.8 MB 63.2%
innd 16153 40.5%2.1 MB 27.6%
innfeed 3642 9.1%624.8 KB 8.0%
nnrpd 798 2.0%84.9 KB 1.1%
nocem 14 0.0%1.6 KB 0.0%
controlchan 1 0.0%0.2 KB 0.0%
TOTAL: 6 39914 100.0%7.6 MB100.0%

History cache:

ReasonCount%Count
Positive hits 290167 54.0%
Negative hits 228525 42.5%
Do not exist 18734 3.5%
Cache misses 276 0.1%
TOTAL: 4 537702 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.273 0.0%27 0.000 10.111 29.000
article cleanup00:00:00.125 0.0%18442 0.000 0.007 0.044
article logging00:00:00.351 0.0%18935 0.000 0.019 0.378
article parse00:00:01.155 0.0%56726 0.000 0.020 0.341
article write00:00:26.644 0.0%15028 0.155 1.773 55.390
artlog/artcncl00:00:00.000 0.0%27 0.000 0.000 0.000
artlog/artparse00:00:00.001 0.0%377 0.000 0.003 0.500
data move00:00:00.172 0.0%505362 0.000 0.000 0.002
hisgrep/artcncl00:00:00.067 0.0%27 0.000 2.481 14.000
hishave/artcncl00:00:00.017 0.0%27 0.000 0.630 7.500
hishave/artparse00:00:00.000 0.0%371 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:10.770 0.0%540377 0.000 0.020 0.405
history sync00:00:01.212 0.0%381 0.000 3.181 94.667
history write00:00:14.894 0.0%18448 0.122 0.807 9.750
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
hiswrite/artparse00:00:00.599 0.0%371 0.000 1.615 46.000
idle23:57:45.493 99.8%510073 98.258 169.124 506.466
nntp read00:00:01.948 0.0%513419 0.001 0.004 0.007
overview write00:01:17.155 0.1%15028 0.122 5.134 107.976
perl filter00:00:26.581 0.0%18442 1.106 1.441 3.433
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.232 0.0%30070 0.000 0.008 0.030
TOTAL: 24:01:00.49724:00:27.689100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.035 0.0%18679 0.000 0.002 0.027
article prepare00:00:00.001 0.0%10140 0.000 0.000 0.018
article read00:00:00.244 0.0%1478 0.000 0.165 14.333
backlog stats00:00:00.221 0.0%323946 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.149 0.0%248382 0.001 0.005 0.012
data write00:00:01.491 0.0%235181 0.001 0.006 0.017
idle23:54:34.570100.0%323946 103.782 265.707 824.020
status file00:00:00.888 0.0%354 0.000 2.508 38.000
TOTAL: 23:54:44.88123:54:38.599100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:03:42.513 0.0%198722 1.120 1.120 1.120
idle131:03:19.909 98.2%979492 10.500 481.678600273.333
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:18.226 0.0%2670277 0.000 0.007 0.167
readart02:14:26.105 1.7%971938 0.000 8.299 42.000
TOTAL: 133:27:27.824133:21:46.753 99.9%----

Control commands to INND:

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeeds.xmission.com 3 14641 2943 11640 58 20%25:29:14
2csiph-out.news.xs4all.nl 537 18117 2465 13977 1675 13%67:41:57
3news-out.mixmin.net 3 16347 2434 13676 237 14%24:08:55
4feeder.eternal-september.org 14 6009 2128 3779 102 35%26:11:09
5news-out.aioe.org 1 14425 1256 13001 168 8%23:59:55
6csiph-out.feeder.erje.net 1440 75147 906 73870 371 1%118:46:23
7newsfeed.fu-berlin.de 9 14521 793 13475 253 5%22:49:16
8csiph-out.news.weretis.net 3 31216 661 30322 233 2%47:26:45
9newsfeed.neva.ru 139 13155 581 12200 374 4%67:38:27
10news-out.neodome.net 2 16439 251 16179 9 1%23:59:54
11csiph-out.feed.uzoreto.com 864 24703 204 24202 297 0%71:16:40
12news.fcku.it 15 1640 191 1442 7 11%21:56:02
13nntp.pasdenom.info 1 10065 109 9936 20 1%23:59:54
14news.freedyn.net 2 18149 59 17935 155 0%23:59:53
15news.swapon.de 6 1393 45 1346 2 3%23:16:08
16csiph-out.news.tnetconsulting.net 2 15679 36 15548 95 0%23:59:56
17news.samoylyk.net 0 4011 3 4008 0 0%08:40:17
18localhost 27 1 1 0 0 100%00:00:00
19xanadu-bbs.net 32 405 0 404 1 0%20:37:34
20news.bbs.nz 1 14525 0 14525 0 0%23:59:52
TOTAL: 20 3101 310588 15066 291465 4057 4%689:58:11
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeeds.xmission.com9.2 MB0.0 KB3.9 MB13.1 MB 70%4.5 KB
2csiph-out.feeder.erje.net6.3 MB0.0 KB2.2 MB8.5 MB 74%6.8 KB
3csiph-out.news.xs4all.nl6.0 MB0.0 KB10.6 MB16.6 MB 36%4.1 KB
4feeder.eternal-september.org5.5 MB0.0 KB720.5 KB6.2 MB 88%2.8 KB
5news-out.mixmin.net5.0 MB0.0 KB423.6 KB5.4 MB 92%2.1 KB
6news-out.aioe.org4.4 MB0.0 KB617.1 KB5.1 MB 88%3.6 KB
7csiph-out.news.weretis.net1.8 MB0.0 KB2.4 MB4.2 MB 42%4.8 KB
8newsfeed.fu-berlin.de1.8 MB0.0 KB577.6 KB2.3 MB 75%2.3 KB
9newsfeed.neva.ru1.6 MB0.0 KB981.0 KB2.5 MB 62%2.7 KB
10csiph-out.feed.uzoreto.com879.1 KB0.0 KB6.3 MB7.2 MB 11%14.6 KB
11news-out.neodome.net804.7 KB0.0 KB60.1 KB864.7 KB 93%3.3 KB
12nntp.pasdenom.info274.9 KB0.0 KB24.3 KB299.1 KB 91%2.3 KB
13news.fcku.it267.4 KB0.0 KB15.7 KB283.1 KB 94%1.4 KB
14news.freedyn.net189.6 KB0.0 KB3.0 MB3.2 MB 5%15.5 KB
15csiph-out.news.tnetconsulting.net163.2 KB0.0 KB196.0 KB359.2 KB 45%2.7 KB
16news.swapon.de88.2 KB0.0 KB3.6 KB91.8 KB 96%2.0 KB
17news.samoylyk.net15.1 KB0.0 KB0.0 KB15.1 KB100%5.0 KB
18localhost0.9 KB0.0 KB0.0 KB0.9 KB100%0.9 KB
19xanadu-bbs.net0.0 KB0.0 KB1.0 KB1.0 KB 0%1.0 KB
20news.bbs.nz0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 2044.2 MB0.0 KB31.9 MB76.2 MB 58%4.1 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Dec 23 04:15:00 - 04:59:59 220 1.5% 0.08805.4 KB 1.8% 0.30
Dec 23 05:00:00 - 05:59:59 315 2.1% 0.091.0 MB 2.3% 0.29
Dec 23 06:00:00 - 06:59:59 318 2.1% 0.091.1 MB 2.4% 0.30
Dec 23 07:00:00 - 07:59:59 458 3.1% 0.131.5 MB 3.4% 0.42
Dec 23 08:00:00 - 08:59:59 616 4.1% 0.172.2 MB 5.0% 0.62
Dec 23 09:00:00 - 09:59:59 651 4.3% 0.182.4 MB 5.5% 0.69
Dec 23 10:00:00 - 10:59:59 735 4.9% 0.202.1 MB 4.9% 0.61
Dec 23 11:00:00 - 11:59:59 760 5.1% 0.212.1 MB 4.7% 0.58
Dec 23 12:00:00 - 12:59:59 698 4.7% 0.191.8 MB 4.0% 0.50
Dec 23 13:00:00 - 13:59:59 785 5.2% 0.222.3 MB 5.2% 0.65
Dec 23 14:00:00 - 14:59:59 793 5.3% 0.222.5 MB 5.6% 0.70
Dec 23 15:00:00 - 15:59:59 936 6.2% 0.262.6 MB 6.0% 0.74
Dec 23 16:00:00 - 16:59:59 855 5.7% 0.242.2 MB 5.1% 0.64
Dec 23 17:00:00 - 17:59:59 847 5.6% 0.242.3 MB 5.3% 0.66
Dec 23 18:00:00 - 18:59:59 827 5.5% 0.232.1 MB 4.9% 0.61
Dec 23 19:00:00 - 19:59:59 763 5.1% 0.212.2 MB 4.9% 0.62
Dec 23 20:00:00 - 20:59:59 894 6.0% 0.252.5 MB 5.8% 0.73
Dec 23 21:00:00 - 21:59:59 792 5.3% 0.222.1 MB 4.7% 0.59
Dec 23 22:00:00 - 22:59:59 686 4.6% 0.191.8 MB 4.1% 0.52
Dec 23 23:00:00 - 23:59:59 580 3.9% 0.161.8 MB 4.2% 0.52
Dec 24 00:00:00 - 00:59:59 465 3.1% 0.131.3 MB 2.9% 0.36
Dec 24 01:00:00 - 01:59:59 379 2.5% 0.111.3 MB 3.0% 0.38
Dec 24 02:00:00 - 02:59:59 286 1.9% 0.08835.7 KB 1.9% 0.23
Dec 24 03:00:00 - 03:59:59 283 1.9% 0.08942.0 KB 2.1% 0.26
Dec 24 04:00:00 - 04:15:00 63 0.4% 0.07197.1 KB 0.4% 0.22
TOTAL: 24:00:00 15005 100.0% 0.1743.9 MB 100.0% 0.52
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1csiph-out.news.xs4all.nl 1694 86 0 0 1 0 0 0 1607
2newsfeed.fu-berlin.de 593 4 0 0 0 0 0 0 589
3newsfeed.neva.ru 374 78 0 0 0 0 0 0 296
4csiph-out.feed.uzoreto.com 298 13 0 0 1 0 0 0 284
5news-out.mixmin.net 293 40 0 0 0 0 0 0 253
6csiph-out.news.weretis.net 207 46 0 0 0 0 0 0 161
7news-out.aioe.org 193 149 0 0 0 0 0 0 44
8csiph-out.feeder.erje.net 168 81 0 0 1 0 0 0 86
9news.freedyn.net 155 1 0 0 0 0 0 0 154
10feeder.eternal-september.org 106 64 0 0 0 0 0 0 42
11csiph-out.news.tnetconsulting.net 67 14 0 0 0 0 0 0 53
12newsfeeds.xmission.com 61 5 0 0 0 0 0 0 56
13news.swapon.de 26 0 0 0 0 0 0 0 26
14nntp.pasdenom.info 20 1 0 0 0 0 0 0 19
15news-out.neodome.net 11 1 0 0 0 0 0 0 10
16news.fcku.it 7 7 0 0 0 0 0 0 0
17xanadu-bbs.net 1 0 0 0 0 0 0 0 1
TOTAL: 17 4274 590 0 0 3 0 0 0 3681

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.computer.workshop 252
lada.talk 54
scinet.stats 28
alt.binaries.pictures.purebeauty 27
it-alt.sport.calcio.juventus 20
lada.auto 12
mozilla.support.firefox 10
at.jobs 10
alt.politics 10
alt.job 9
mozilla.support.bugzilla 9
alt.bbs.synchronet 8
it-alt.comp.software.mozilla 8
mozilla.support.seamonkey 7
mozilla.support.thunderbird 7
trollnet.cinema 7
oesterreich.tratsch 7
alt.politics.usa 6
spline.eisfair 5
alt.tv.comedy-central.daily-show 5
TOTAL: 73 590

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

GroupsCount
uk.legal.moderated 2
it.scienza.astronomia 1
TOTAL: 2 3

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1742
EMP (phn nph) 410
EMP (phn path) 386
EMP (phl) 162
0 46
Too many newsgroups 37
Binary: misplaced binary 10
HTML Multipart 10
Too many newsgroups (meow) 4
HTML post 2
Scoring filter 1
TOTAL: 11 2810

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 130
csiph-out.feed.uzoreto.com 84
newsfeeds.xmission.com 51
news.freedyn.net 38
csiph-out.feeder.erje.net 38
csiph-out.news.weretis.net 25
feeder.eternal-september.org 5
csiph-out.news.tnetconsulting.net 2
newsfeed.fu-berlin.de 1
news-out.neodome.net 1
TOTAL: 10 375
Including strange strings
newsfeed.fu-berlin.de 333
news-out.mixmin.net 63
news-out.aioe.org 33
news.swapon.de 24
csiph-out.news.xs4all.nl 19
csiph-out.news.weretis.net 7
newsfeeds.xmission.com 5
csiph-out.feed.uzoreto.com 4
feeder.eternal-september.org 1
newsfeed.neva.ru 1
TOTAL: 10 490
No colon-space in header
csiph-out.news.xs4all.nl 4
csiph-out.feed.uzoreto.com 2
TOTAL: 2 6
TOTAL: 4 916

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1xanadu-bbs 18279 965 14030 4 0 0 5%23:59:55
2fu-berlin.de 13920 453 13467 0 0 0 3%23:49:26
3news.bbs.nz 28295 369 14090 20 0 0 1%23:51:33
4news.samoylyk.net 4045 196 3762 0 0 11023 4%23:53:52
5xmission 13849 81 11531 3 0 0 0%23:57:39
6newsfeed.xs4all.nl 13208 80 12284 2 0 111 0%23:54:26
7tnet 22178 79 14393 1 0 0 0%23:50:11
8erje 15955 19 13747 112 0 0 0%23:55:48
9neva.ru 13623 18 13488 33 0 3581 0%23:56:15
10news.freedyn.net 14272 6 13976 65 0 0 0%23:50:11
11neodome.net 15069 5 14704 0 0 0 0%23:53:31
12usenet.pasdenom.info 14799 4 14384 8 0 0 0%23:59:55
13news.mixmin.net 12008 4 11784 0 0 0 0%23:50:11
14weretis.net 14019 1 13594 0 0 0 0%23:54:28
15news.swapon.de 1356 1 1351 0 0 0 0%23:24:15
16aioe 10467 1 10165 0 0 0 0%23:50:11
17eternal-september 0 0 0 0 0 12296 0%23:50:11
18news.fcku.it 1382 0 1381 0 0 0 0%21:07:13
19news.uzoreto.com 14464 0 14212 4 0 0 0%23:53:13
TOTAL: 19 241188 2282 206343 252 0 27011 0%450:42:24
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1xanadu-bbs6.5 MB11.7 KB6.5 MB0.1 KB/s6.8 KB23:59:55
2fu-berlin.de5.0 MB0.0 KB5.0 MB0.1 KB/s11.3 KB23:49:26
3news.samoylyk.net2.7 MB0.0 KB2.7 MB0.0 KB/s14.1 KB23:53:52
4news.bbs.nz932.2 KB77.0 KB1009.2 KB0.0 KB/s2.6 KB23:51:33
5xmission230.7 KB12.9 KB243.5 KB0.0 KB/s2.9 KB23:57:39
6newsfeed.xs4all.nl219.7 KB36.8 KB256.5 KB0.0 KB/s3.1 KB23:54:26
7tnet205.9 KB9.9 KB215.8 KB0.0 KB/s2.7 KB23:50:11
8news.mixmin.net67.1 KB0.0 KB67.1 KB0.0 KB/s16.8 KB23:50:11
9news.freedyn.net41.2 KB1.2 MB1.2 MB0.0 KB/s17.9 KB23:50:11
10erje38.1 KB384.7 KB422.9 KB0.0 KB/s3.2 KB23:55:48
11usenet.pasdenom.info35.6 KB16.1 KB51.7 KB0.0 KB/s4.3 KB23:59:55
12neva.ru33.7 KB116.4 KB150.1 KB0.0 KB/s2.9 KB23:56:15
13neodome.net10.7 KB0.0 KB10.7 KB0.0 KB/s2.1 KB23:53:31
14weretis.net0.9 KB0.0 KB0.9 KB0.0 KB/s0.9 KB23:54:28
15news.swapon.de0.9 KB0.0 KB0.9 KB0.0 KB/s0.9 KB23:24:15
16aioe0.9 KB0.0 KB0.9 KB0.0 KB/s0.9 KB23:50:11
17eternal-september0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:11
18news.fcku.it0.0 KB0.0 KB0.0 KB0.0 KB/s021:07:13
19news.uzoreto.com0.0 KB67.1 KB67.1 KB0.0 KB/s16.8 KB23:53:13
TOTAL: 1915.9 MB1.9 MB17.9 MB0.0 KB/s7.2 KB450:42:24
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1a131033.upc-a.chello.nl 35 610.6 KB 4 0 010:21:32
291.187.33.87 79 59.0 KB 5 1 125:33:59
TOTAL: 2 1143257451.1 GB 10 1 1133:27:26

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 863257391.1 GB 6 1 1122:23:33
2*.upc-a.chello.nl 35 610.6 KB 4 0 010:21:32
3*.range31-50.btcentralplus.com 5 00.0 KB 0 0 000:42:19
4*.ch1.censys-scanner.com 1 00.0 KB 0 0 000:00:00
TOTAL: 4 1273257451.1 GB 10 1 1133:27:26

NNRP auth users [Top 20]:

UserConn
slavotinek 1
TOTAL: 1 1

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
212.90.61.180 65.255 172.847 0.00096:49:33
91.187.33.87 5.290 1.117 0.00325:33:59
a131033.upc-a.chello.nl 2.409 0.492 0.00010:21:32
host31-50-220-99.range31-50.btcentralplus.com 0.352 0.046 0.00000:42:19
scanner-09.ch1.censys-scanner.com 0.057 0.024 0.00000:00:00
178.128.9.102 0.417 0.130 0.00000:00:00
TOTAL: 6 73.780 174.656 0.003133:27:26

Curious NNRP server explorers [Top 20]:

SystemConn
178.128.9.102 7
host31-50-220-99.range31-50.btcentralplus.com 5
scanner-09.ch1.censys-scanner.com 1
TOTAL: 3 13

NNRP unrecognized commands (by host):

SystemConn
178.128.9.102 36
scanner-09.ch1.censys-scanner.com 1
TOTAL: 2 37

NNRP unrecognized commands (by command):

CommandCount
Host: 69.160.37.58:119 6
Accept: */* 6
Connection: keep-alive 6
Accept-Encoding: gzip, deflate 6
User-Agent: Mozilla/5.0 (Windows NT 10.0... 6
GET /stalker_portal/c/version.js HTTP/1.... 1
GET /system_api.php HTTP/1.1 1
EHLO www.censys.io 1
GET /streaming/clients_live.php HTTP/1.1 1
GET /c/version.js HTTP/1.1 1
GET /client_area/ HTTP/1.1 1
GET /stalker_portal/c/ HTTP/1.1 1
TOTAL: 12 37

NNRP client timeouts [Top 20]:

SystemConnPeer
91.187.33.87 5 0
212.90.61.180 1 1
host31-50-220-99.range31-50.btcentralplus.com 1 0
TOTAL: 3 7 1

Newsgroup request counts (by category):

CategoryCountPct
1alt 165092100.0%
2comp 11 0.0%
TOTAL: 2 165103100.0%

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

NewsgroupCount
1alt.atheism 165092
2comp.sys.ibm.ps2.hardware 11
TOTAL: 2 165103