Daily Usenet report for csiph.com

Mar 18 04:15:00 -- Mar 19 04:15:00

Unknown entries from news log file:

First 14 / 14 lines (100.0%)

Mar 18 13:03:56 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[10049]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Mar 18 13:03:56 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[10049]: 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]
Mar 18 14:39:58 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[16626]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Mar 18 14:39:58 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[16626]: 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]
Mar 18 14:40:28 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[16653]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Mar 18 14:40:28 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[16653]: 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]
Mar 18 22:03:20 842ffb22-07e1-11e5-a459-00266cf00584 innbind[50226]: cannot bind socket for 15,28,::,119: Address already in use
Mar 18 22:03:20 842ffb22-07e1-11e5-a459-00266cf00584 innd[50209]: innbind returned no output, assuming failure
Mar 18 22:03:20 842ffb22-07e1-11e5-a459-00266cf00584 innbind[50227]: cannot bind socket for 15,2,0.0.0.0,119: Address already in use
Mar 18 22:03:20 842ffb22-07e1-11e5-a459-00266cf00584 innd[50209]: innbind failed for ::, port 119
Mar 18 22:03:20 842ffb22-07e1-11e5-a459-00266cf00584 innd[50209]: innbind returned no output, assuming failure
Mar 18 22:03:20 842ffb22-07e1-11e5-a459-00266cf00584 innd[50209]: innbind failed for 0.0.0.0, port 119
Mar 18 23:16:33 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[17596]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Mar 18 23:16:33 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[17596]: 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 18258 49.8%4.6 MB 61.1%
innd 13222 36.1%2.1 MB 27.8%
innfeed 3700 10.1%656.5 KB 8.6%
nnrpd 1435 3.9%194.3 KB 2.5%
nocem 13 0.0%1.8 KB 0.0%
controlchan 2 0.0%0.2 KB 0.0%
innbind 2 0.0%0.2 KB 0.0%
TOTAL: 7 36632 100.0%7.5 MB100.0%

History cache:

ReasonCount%Count
Positive hits 182498 55.4%
Negative hits 106684 32.4%
Cache misses 21638 6.6%
Do not exist 18785 5.7%
TOTAL: 4 329605 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:02.879 0.0%35 1.000 82.257 145.900
article cleanup00:00:00.078 0.0%17393 0.000 0.004 0.051
article logging00:00:08.525 0.0%17777 0.000 0.480 6.122
article parse00:00:13.409 0.0%44662 0.000 0.300 4.374
article write00:15:09.878 1.4%14210 5.185 64.031 284.730
artlog/artcncl00:00:00.052 0.0%35 0.000 1.486 5.100
artlog/artparse00:00:00.515 0.0%330 0.000 1.561 28.000
data move00:00:00.106 0.0%212588 0.000 0.000 0.002
hisgrep/artcncl00:00:00.793 0.0%31 0.000 25.581 109.000
hishave/artcncl00:00:00.467 0.0%35 0.000 13.343 48.667
hishave/artparse00:00:00.001 0.0%327 0.000 0.003 0.067
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:04:00.076 0.4%330029 0.004 0.727 4.098
history sync00:00:24.812 0.0%214 0.000 115.944 542.000
history write00:09:21.513 0.8%17183 1.169 32.678 107.008
hiswrite/artcncl00:00:00.034 0.0%4 8.500 8.500 8.500
hiswrite/artparse00:00:12.482 0.0%327 0.000 38.171 291.000
idle12:35:01.663 68.4%184729 42.251 245.233 825.334
nntp read00:00:01.032 0.0%219287 0.002 0.005 0.009
overview write05:17:03.992 28.7%14210 0.269 1338.775171611.026
perl filter00:00:27.361 0.0%17180 1.000 1.593 6.774
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.232 0.0%28432 0.000 0.008 0.027
TOTAL: 18:24:21.76118:22:09.900 99.8%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.026 0.0%16147 0.000 0.002 0.020
article prepare00:00:00.000 0.0%1396 0.000 0.000 0.000
article read00:00:03.262 0.0%325 0.000 10.037 189.667
backlog stats00:00:00.113 0.0%189979 0.000 0.001 0.043
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.662 0.0%155033 0.000 0.004 0.011
data write00:00:00.795 0.0%166351 0.000 0.005 0.014
idle23:37:11.347 99.9%189979 13.425 447.583 18232.212
status file00:00:27.377 0.0%779 0.000 35.144 648.000
TOTAL: 23:37:58.57823:37:43.582100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle19:51:28.955 99.9%775 0.000 92243.813600115.667
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:01.438 0.0%1913 0.000 0.752 14.607
readart00:00:00.420 0.0%14 17.000 30.000 84.000
TOTAL: 19:52:52.02919:51:30.813 99.9%----

Control commands to INND:

CommandNumber
flush 5
flushlogs 2
go 1
logmode 2
mode 38
pause 1
paused 1
reload 2
TOTAL: 8 52

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1csiph-out.news.weretis.net 16 37527 5785 31384 358 15%49:29:32
2csiph-out.news.xs4all.nl 437 9293 2862 5442 989 30%46:28:46
3csiph-out.feeder.erje.net 915 34209 882 33042 285 2%88:14:11
4csiph-out.feed.uzoreto.com 816 19800 791 14663 4346 3%53:00:02
5newsfeeds.xmission.com 7 13210 725 12481 4 5%16:30:18
6news-out.mixmin.net 7 15250 555 14600 95 3%16:11:44
7feeder.eternal-september.org 29 14755 506 13633 616 3%12:04:02
8news-out.aioe.org 4 4949 496 4445 8 10%16:26:46
9newsfeed.neva.ru 100 6990 385 6477 128 5%54:51:43
10newsfeed.fu-berlin.de 14 4730 299 4372 59 6%17:15:58
11news.freedyn.net 8 17106 264 16312 530 1%17:07:51
12csiph-out.news.tnetconsulting.net 5 10251 218 10024 9 2%19:50:28
13news.fcku.it 14 1585 86 1498 1 5%13:35:45
14news-out.neodome.net 8 16240 81 16089 70 0%19:17:09
15nntp.pasdenom.info 5 9258 47 9210 1 0%16:28:47
16news.bbs.nz 6 17012 27 16982 3 0%29:08:56
17news.swapon.de 14 1245 12 1233 0 0%11:34:52
18localhost 19 0 0 0 0 0%00:00:03
TOTAL: 18 2424 233410 14021 211887 7502 6%497:36:53
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1csiph-out.news.weretis.net15.2 MB346.4 KB2.9 MB18.4 MB 82%3.1 KB
2csiph-out.news.xs4all.nl7.5 MB13.2 KB9.6 MB17.1 MB 43%4.6 KB
3csiph-out.feeder.erje.net4.0 MB246.6 KB1.4 MB5.6 MB 70%5.0 KB
4csiph-out.feed.uzoreto.com2.3 MB46.2 KB4.2 MB6.6 MB 35%1.3 KB
5newsfeeds.xmission.com2.1 MB0.0 KB159.6 KB2.3 MB 93%3.2 KB
6feeder.eternal-september.org1.5 MB0.0 KB2.1 MB3.7 MB 41%3.4 KB
7news-out.mixmin.net1.3 MB5.6 KB264.3 KB1.6 MB 83%2.5 KB
8news-out.aioe.org1.3 MB5.6 KB17.6 KB1.3 MB 98%2.6 KB
9newsfeed.neva.ru1002.2 KB11.1 KB443.5 KB1.4 MB 68%2.8 KB
10newsfeed.fu-berlin.de772.9 KB0.0 KB122.5 KB895.4 KB 86%2.5 KB
11csiph-out.news.tnetconsulting.net715.6 KB1.5 KB82.6 KB799.7 KB 89%3.5 KB
12news.freedyn.net701.1 KB0.0 KB6.2 MB6.9 MB 9%8.9 KB
13news-out.neodome.net190.5 KB11.8 KB495.4 KB697.6 KB 27%4.6 KB
14news.fcku.it128.2 KB0.0 KB1.2 KB129.4 KB 99%1.5 KB
15nntp.pasdenom.info102.4 KB4.1 KB4.1 KB110.7 KB 92%2.3 KB
16news.bbs.nz63.6 KB1.6 KB6.1 KB71.3 KB 89%2.4 KB
17news.swapon.de22.4 KB0.0 KB0.0 KB22.4 KB100%1.9 KB
18localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1838.9 MB693.7 KB28.0 MB67.6 MB 57%3.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Mar 18 04:15:00 - 04:59:59 170 1.2% 0.06554.4 KB 0.0% 0.21
Mar 18 05:00:00 - 05:59:59 247 1.7% 0.071.3 MB 0.0% 0.36
Mar 18 06:00:00 - 06:59:59 22 0.2% 0.0173.5 KB 0.0% 0.02
Mar 18 07:00:00 - 07:59:59 661 4.6% 0.181.9 MB 0.0% 0.55
Mar 18 08:00:00 - 08:59:59 521 3.6% 0.141.4 MB 0.0% 0.39
Mar 18 09:00:00 - 09:59:59 560 3.9% 0.162.0 MB 0.0% 0.58
Mar 18 10:00:00 - 10:59:59 264 1.8% 0.07704.2 KB 0.0% 0.20
Mar 18 11:00:00 - 11:59:59 0 0.0% 0.000.0 KB 0.0% 0.00
Mar 18 12:00:00 - 12:59:59 1516 10.6% 0.423.7 MB 0.0% 1.06
Mar 18 13:00:00 - 13:59:59 628 4.4% 0.171.8 MB 0.0% 0.51
Mar 18 14:00:00 - 14:59:59 592 4.1% 0.161.6 MB 0.0% 0.46
Mar 18 15:00:00 - 15:59:59 801 5.6% 0.222.0 MB 0.0% 0.58
Mar 18 16:00:00 - 16:59:59 269 1.9% 0.07812.6 KB 0.0% 0.23
Mar 18 17:00:00 - 17:59:59 0 0.0% 0.000.0 KB 0.0% 0.00
Mar 18 18:00:00 - 18:59:59 0 0.0% 0.000.0 KB 0.0% 0.00
Mar 18 19:00:00 - 19:59:59 0 0.0% 0.000.0 KB 0.0% 0.00
Mar 18 20:00:00 - 20:59:59 0 0.0% 0.000.0 KB 0.0% 0.00
Mar 18 21:00:00 - 21:59:59 0 0.0% 0.000.0 KB 0.0% 0.00
Mar 18 22:00:00 - 22:59:59 5962 41.7% 1.6615.5 MB 0.0% 4.42
Mar 18 23:00:00 - 23:59:59 549 3.8% 0.151.8 MB 0.0% 0.50
Mar 19 00:00:00 - 00:59:59 435 3.0% 0.121.2 MB 0.0% 0.35
Mar 19 01:00:00 - 01:59:59 351 2.5% 0.101.1 MB 0.0% 0.31
Mar 19 02:00:00 - 02:59:59 331 2.3% 0.09947.6 KB 0.0% 0.26
Mar 19 03:00:00 - 03:59:59 331 2.3% 0.091.1 MB 0.0% 0.31
Mar 19 04:00:00 - 04:15:00 85 0.6% 0.09218.2 KB 0.0% 0.24
TOTAL: 24:00:00 14295 100.0% 0.170.0 KB 100.0% 0.00
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1csiph-out.news.xs4all.nl 1170 77 0 2 0 0 0 0 1091
2feeder.eternal-september.org 620 60 0 0 0 0 0 0 560
3news.freedyn.net 529 3 0 0 0 0 0 0 526
4csiph-out.news.weretis.net 462 81 0 112 0 0 0 0 269
5csiph-out.feed.uzoreto.com 415 44 0 9 0 0 0 0 362
6csiph-out.feeder.erje.net 204 32 0 77 0 0 0 0 95
7newsfeed.fu-berlin.de 174 15 0 0 0 0 0 0 159
8newsfeed.neva.ru 138 9 0 4 0 0 0 0 125
9news-out.mixmin.net 98 6 0 3 0 0 0 0 89
10news-out.neodome.net 68 23 0 1 0 0 0 0 44
11csiph-out.news.tnetconsulting.net 15 6 0 1 0 0 0 0 8
12newsfeeds.xmission.com 12 0 0 0 0 0 0 0 12
13news.swapon.de 8 0 0 0 0 0 0 0 8
14news-out.aioe.org 8 4 0 2 0 0 0 0 2
15news.bbs.nz 5 2 0 2 0 0 0 0 1
16nntp.pasdenom.info 1 0 0 1 0 0 0 0 0
17news.fcku.it 1 1 0 0 0 0 0 0 0
TOTAL: 17 3928 363 0 214 0 0 0 0 3351

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.computer.workshop 87
alt.news.misc 36
spline.eisfair.dev 33
it-alt.sport.calcio.juventus 15
alt.binaries.pictures.purebeauty 14
alt.computer.hardware 14
alt.bbs.synchronet 14
alt.job 11
alt.math 8
alt.slack.goathead 8
mozilla.support.seamonkey 7
alt.all-your-base-are-belong-to-us 6
at.jobs 6
alt.windows7.general 6
it-alt.comp.software.mozilla 5
mozilla.support.thunderbird 5
spline.fli4l.geschnatter 5
alt.testing 5
alt.politics 4
alt.finance 4
TOTAL: 62 363

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1957
Too many newsgroups 195
EMP (phn path) 121
Binary Payload 121
EMP (phl) 106
EMP (phn nph) 70
0 41
Binary: misplaced binary 11
Scoring filter 10
HTML post 2
Too many newsgroups (meow) 2
U2 violation - invalid distribution 1
TOTAL: 12 2637

INND misc events:

EventsCount
RCreader 42
CCreader 1
TOTAL: 2 43

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
csiph-out.feeder.erje.net 30
TOTAL: 1 30
Huge articles
csiph-out.news.xs4all.nl 150
csiph-out.feed.uzoreto.com 58
news.freedyn.net 48
csiph-out.news.weretis.net 22
csiph-out.feeder.erje.net 19
feeder.eternal-september.org 18
news-out.neodome.net 7
newsfeeds.xmission.com 3
news-out.mixmin.net 2
csiph-out.news.tnetconsulting.net 1
TOTAL: 10 328
Including strange strings
newsfeed.fu-berlin.de 136
csiph-out.news.weretis.net 121
csiph-out.news.xs4all.nl 84
csiph-out.feed.uzoreto.com 11
newsfeed.neva.ru 10
news.swapon.de 8
newsfeeds.xmission.com 7
csiph-out.feeder.erje.net 5
feeder.eternal-september.org 2
news.bbs.nz 1
TOTAL: 10 385
No colon-space in header
csiph-out.news.xs4all.nl 2
csiph-out.feed.uzoreto.com 1
TOTAL: 2 3
TOTAL: 4 746

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1fu-berlin.de 13165 204 12960 0 0 79 1%14:12:06
2xmission 13874 36 13157 2 0 0 0%14:02:47
3news.bbs.nz 16909 33 13949 11 0 587 0%14:06:31
4newsfeed.xs4all.nl 10999 30 10672 0 0 55 0%14:57:33
5tnet 14167 7 14025 0 0 0 0%14:10:44
6erje 12380 3 12295 3 0 0 0%14:06:11
7usenet.pasdenom.info 14080 3 13929 1 0 161 0%14:06:46
8news.mixmin.net 12119 2 12077 0 0 0 0%14:06:57
9news.uzoreto.com 12318 1 12287 1 0 190 0%14:02:54
10neodome.net 14014 1 13969 0 0 270 0%23:48:48
11aioe 11728 0 11662 0 0 0 0%14:08:17
12weretis.net 7924 0 7861 0 0 0 0%14:09:42
13neva.ru 12917 0 12895 3 0 346 0%14:02:47
14news.swapon.de 1324 0 1315 0 0 0 0%13:13:52
15eternal-september 0 0 0 0 0 12154 0%23:40:06
16news.samoylyk.net 0 0 0 0 0 14287 0%23:48:34
17news.freedyn.net 13638 0 13595 8 0 196 0%14:04:13
18news.fcku.it 1177 0 1177 0 0 0 0%10:22:03
19xanadu-bbs 0 0 0 0 0 14262 0%23:40:03
TOTAL: 19 182733 320 177825 29 0 42587 0%302:50:54
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1fu-berlin.de1.7 MB0.0 KB1.7 MB0.0 KB/s8.6 KB14:12:06
2newsfeed.xs4all.nl85.7 KB0.0 KB85.7 KB0.0 KB/s2.9 KB14:57:33
3news.bbs.nz81.9 KB30.0 KB112.0 KB0.0 KB/s2.5 KB14:06:31
4xmission81.1 KB2.1 KB83.2 KB0.0 KB/s2.2 KB14:02:47
5tnet16.4 KB0.0 KB16.4 KB0.0 KB/s2.3 KB14:10:44
6news.mixmin.net7.6 KB0.0 KB7.6 KB0.0 KB/s3.8 KB14:06:57
7news.uzoreto.com7.4 KB1.7 KB9.1 KB0.0 KB/s4.5 KB14:02:54
8erje6.5 KB9.9 KB16.4 KB0.0 KB/s2.7 KB14:06:11
9usenet.pasdenom.info4.8 KB1.4 KB6.2 KB0.0 KB/s1.6 KB14:06:46
10neodome.net2.6 KB0.0 KB2.6 KB0.0 KB/s2.6 KB23:48:48
11aioe0.0 KB0.0 KB0.0 KB0.0 KB/s014:08:17
12weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s014:09:42
13neva.ru0.0 KB3.6 KB3.6 KB0.0 KB/s1.2 KB14:02:47
14news.swapon.de0.0 KB0.0 KB0.0 KB0.0 KB/s013:13:52
15eternal-september0.0 KB0.0 KB0.0 KB0.0 KB/s023:40:06
16news.samoylyk.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:48:34
17news.freedyn.net0.0 KB208.0 KB208.0 KB0.0 KB/s26.0 KB14:04:13
18news.fcku.it0.0 KB0.0 KB0.0 KB0.0 KB/s010:22:03
19xanadu-bbs0.0 KB0.0 KB0.0 KB0.0 KB/s023:40:03
TOTAL: 192.0 MB256.7 KB2.3 MB0.0 KB/s6.6 KB302:50:54
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1172.83.100.231 9 1428.4 KB 14 0 002:45:18
TOTAL: 1 9 1428.4 KB 14 0 019:52:50

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 187 1428.4 KB 14 0 016:09:36
2*.upc-a.chello.nl 23 00.0 KB 0 0 003:40:07
3*.range31-49.btcentralplus.com 4 00.0 KB 0 0 000:03:06
TOTAL: 3 214 1428.4 KB 14 0 019:52:50

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
91.187.33.87 10.127 1.869 0.00013:24:15
a131033.upc-a.chello.nl 1.480 0.182 0.00003:40:07
172.83.100.231 0.857 0.453 0.00002:45:18
host31-49-229-214.range31-49.btcentralplus.com 0.244 0.057 0.00000:03:06
134.122.37.163 0.540 0.087 0.00000:00:01
79.124.62.254 0.070 0.000 0.00000:00:01
TOTAL: 6 13.318 2.648 0.00019:52:50

Curious NNRP server explorers [Top 20]:

SystemConn
91.187.33.87 168
a131033.upc-a.chello.nl 23
134.122.37.163 9
host31-49-229-214.range31-49.btcentralplus.com 4
79.124.62.254 1
TOTAL: 5 205

NNRP unrecognized commands (by host):

SystemConn
134.122.37.163 48
79.124.62.254 1
TOTAL: 2 49

NNRP unrecognized commands (by command):

CommandCount
Connection: keep-alive 8
Host: 69.160.37.58:119 8
Accept-Encoding: gzip, deflate 8
Accept: */* 8
User-Agent: Mozilla/5.0 (Windows NT 10.0... 8
GET /system_api.php HTTP/1.1 2
GET /streaming/clients_live.php HTTP/1.1 1
GET /c/version.js HTTP/1.1 1
GET /stalker_portal/c/version.js HTTP/1.... 1
GET /stream/rtmp.php HTTP/1.1 1
GET /client_area/ HTTP/1.1 1
GET /stalker_portal/c/ HTTP/1.1 1
^C 1
TOTAL: 13 49

NNRP client timeouts [Top 20]:

SystemConnPeer
172.83.100.231 5 0
79.124.62.254 1 1
TOTAL: 2 6 1

Newsgroup request counts (by category):

CategoryCountPct
1comp 9 60.0%
2news 6 40.0%
TOTAL: 2 15100.0%

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

NewsgroupCount
1comp.unix.bsd.freebsd.misc 7
2news.software.nntp 6
3comp.unix.bsd.netbsd.misc 1
4comp.sys.ibm.ps2.hardware 1
TOTAL: 4 15
innreport from INN 2.6.4 based on work (c) 1996-1999 by Fabien Tassin <fta@sofaraway.org>
Local contact: admin@kev009.com