Daily Usenet report for csiph.com

Mar 9 04:15:00 -- Mar 10 04:15:00

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Mar 9 12:52:48 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[86259]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Mar 9 12:52:48 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[86259]: 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 9 14:47:21 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[92641]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Mar 9 14:47:21 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[92641]: 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 9 15:07:15 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[93764]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Mar 9 15:07:15 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[93764]: 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 9 21:08:35 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[14763]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Mar 9 21:08:35 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[14763]: 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 25517 51.7%6.0 MB 60.7%
innd 17924 36.3%2.9 MB 29.1%
innfeed 3695 7.5%718.0 KB 7.1%
nnrpd 2239 4.5%309.6 KB 3.1%
nocem 16 0.0%2.1 KB 0.0%
controlchan 1 0.0%0.2 KB 0.0%
TOTAL: 6 49392 100.0%9.8 MB100.0%

History cache:

ReasonCount%Count
Positive hits 315668 51.1%
Negative hits 266933 43.2%
Do not exist 24916 4.0%
Cache misses 10087 1.6%
TOTAL: 4 617604 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.274 0.0%22 0.000 12.455 22.111
article cleanup00:00:00.152 0.0%24752 0.000 0.006 0.034
article logging00:00:01.337 0.0%25161 0.000 0.053 1.802
article parse00:00:01.378 0.0%71875 0.000 0.019 0.260
article write00:01:27.245 0.1%17506 0.159 4.984 37.141
artlog/artcncl00:00:00.000 0.0%22 0.000 0.000 0.000
artlog/artparse00:00:00.024 0.0%321 0.000 0.075 8.500
data move00:00:00.175 0.0%572672 0.000 0.000 0.001
hisgrep/artcncl00:00:00.025 0.0%22 0.000 1.136 10.000
hishave/artcncl00:00:00.003 0.0%22 0.000 0.136 0.500
hishave/artparse00:00:00.001 0.0%318 0.000 0.003 0.333
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:59.640 0.1%620982 0.004 0.096 0.856
history sync00:00:01.640 0.0%374 0.000 4.385 86.000
history write00:01:05.129 0.1%24712 0.242 2.636 11.784
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
hiswrite/artparse00:00:00.781 0.0%313 0.000 2.495 62.500
idle23:32:31.390 98.3%567211 89.018 149.418 469.087
nntp read00:00:02.438 0.0%578047 0.002 0.004 0.010
overview write00:19:03.938 1.3%17506 0.118 65.345 5150.944
perl filter00:00:30.396 0.0%24709 0.863 1.230 1.840
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.327 0.0%35028 0.000 0.009 0.028
TOTAL: 23:56:46.67223:55:46.293 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.120 0.0%27804 0.000 0.004 0.673
article prepare00:00:00.000 0.0%4140 0.000 0.000 0.000
article read00:00:01.798 0.0%883 0.000 2.036 1421.000
backlog stats00:00:00.199 0.0%335772 0.000 0.001 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.179 0.0%265773 0.001 0.004 0.008
data write00:00:01.585 0.0%250749 0.002 0.006 0.015
idle23:54:09.441100.0%335772 101.631 256.273 705.726
status file00:00:00.461 0.0%373 0.000 1.236 20.000
TOTAL: 23:54:25.33823:54:14.783100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:03.475 0.0%543 6.400 6.400 6.400
idle152:53:55.949 98.9%1402438 0.000 392.485600120.333
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:53.824 0.0%4113509 0.000 0.013 40.467
readart01:21:25.471 0.9%836396 0.000 5.841 9.000
TOTAL: 154:34:43.139154:16:18.719 99.8%----

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
1newsfeed.neva.ru 145 25089 3735 21058 296 14%71:27:05
2feeder.eternal-september.org 28 4862 2193 2566 103 45%31:55:47
3csiph-out.news.xs4all.nl 0 4826 1795 2157 874 37%64:00:20
4newsfeeds.xmission.com 3 16599 1721 14865 13 10%23:25:47
5news-out.aioe.org 3 16455 1564 14797 94 9%24:09:55
6news.freedyn.net 2 19999 1042 18542 415 5%23:59:55
7csiph-out.news.weretis.net 21 40502 1009 39373 120 2%50:32:05
8news-out.mixmin.net 2 18766 962 17571 233 5%24:09:49
9csiph-out.feeder.erje.net 1440 87931 876 86723 332 0%118:46:08
10newsfeed.fu-berlin.de 16 22247 844 16607 4796 3%27:16:59
11csiph-out.feed.uzoreto.com 864 27018 793 25737 488 2%71:16:48
12news-out.neodome.net 3 20113 387 19683 43 1%24:59:56
13csiph-out.news.tnetconsulting.net 1 31054 324 30704 26 1%37:55:59
14news.fcku.it 10 1760 251 1499 10 14%22:10:06
15nntp.pasdenom.info 11 9448 101 9345 2 1%23:54:20
16news.swapon.de 5 1039 33 1006 0 3%21:31:26
17news.bbs.nz 2 14273 10 14263 0 0%06:03:35
18localhost 26 0 0 0 0 0%00:00:00
TOTAL: 18 2582 361981 17640 336496 7845 4%667:36:00
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.neva.ru10.1 MB7.4 KB912.1 KB11.0 MB 91%2.8 KB
2csiph-out.feeder.erje.net7.3 MB56.0 KB3.5 MB10.9 MB 67%9.2 KB
3feeder.eternal-september.org5.5 MB5.3 KB1.1 MB6.6 MB 83%3.0 KB
4newsfeeds.xmission.com5.2 MB3.9 KB763.6 KB5.9 MB 87%3.5 KB
5csiph-out.news.xs4all.nl4.7 MB23.3 KB6.3 MB11.1 MB 42%4.3 KB
6news-out.aioe.org4.1 MB0.0 KB143.4 KB4.3 MB 96%2.6 KB
7csiph-out.news.weretis.net2.9 MB34.3 KB909.3 KB3.8 MB 75%3.4 KB
8news.freedyn.net2.8 MB0.0 KB2.0 MB4.8 MB 57%3.4 KB
9csiph-out.feed.uzoreto.com2.7 MB0.0 KB9.3 MB12.1 MB 22%9.6 KB
10news-out.mixmin.net2.5 MB3.1 KB515.7 KB3.0 MB 83%2.6 KB
11newsfeed.fu-berlin.de2.1 MB0.0 KB16.1 MB18.2 MB 11%3.3 KB
12csiph-out.news.tnetconsulting.net1.2 MB40.2 KB1.2 MB2.4 MB 47%7.2 KB
13news-out.neodome.net1.2 MB5.3 KB1.4 MB2.5 MB 45%6.0 KB
14news.fcku.it365.6 KB0.0 KB16.9 KB382.5 KB 95%1.5 KB
15nntp.pasdenom.info216.4 KB0.0 KB3.9 KB220.3 KB 98%2.1 KB
16news.swapon.de70.8 KB0.0 KB0.0 KB70.8 KB100%2.1 KB
17news.bbs.nz43.8 KB0.0 KB0.0 KB43.8 KB100%4.4 KB
18localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1853.0 MB178.7 KB44.2 MB97.3 MB 54%3.9 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Mar 9 04:15:00 - 04:59:59 434 2.5% 0.161.2 MB 2.2% 0.44
Mar 9 05:00:00 - 05:59:59 687 3.9% 0.193.1 MB 5.9% 0.87
Mar 9 06:00:00 - 06:59:59 711 4.1% 0.202.5 MB 4.7% 0.70
Mar 9 07:00:00 - 07:59:59 742 4.2% 0.212.1 MB 4.0% 0.59
Mar 9 08:00:00 - 08:59:59 805 4.6% 0.222.5 MB 4.7% 0.71
Mar 9 09:00:00 - 09:59:59 823 4.7% 0.232.2 MB 4.2% 0.63
Mar 9 10:00:00 - 10:59:59 883 5.0% 0.252.2 MB 4.3% 0.64
Mar 9 11:00:00 - 11:59:59 827 4.7% 0.232.2 MB 4.1% 0.62
Mar 9 12:00:00 - 12:59:59 721 4.1% 0.202.0 MB 3.8% 0.57
Mar 9 13:00:00 - 13:59:59 730 4.2% 0.202.3 MB 4.4% 0.66
Mar 9 14:00:00 - 14:59:59 850 4.9% 0.242.1 MB 4.1% 0.60
Mar 9 15:00:00 - 15:59:59 834 4.8% 0.232.1 MB 4.0% 0.60
Mar 9 16:00:00 - 16:59:59 1082 6.2% 0.302.9 MB 5.5% 0.82
Mar 9 17:00:00 - 17:59:59 1039 5.9% 0.294.0 MB 7.6% 1.13
Mar 9 18:00:00 - 18:59:59 930 5.3% 0.262.7 MB 5.1% 0.76
Mar 9 19:00:00 - 19:59:59 818 4.7% 0.232.7 MB 5.2% 0.77
Mar 9 20:00:00 - 20:59:59 723 4.1% 0.201.9 MB 3.6% 0.54
Mar 9 21:00:00 - 21:59:59 733 4.2% 0.202.2 MB 4.1% 0.61
Mar 9 22:00:00 - 22:59:59 646 3.7% 0.181.9 MB 3.6% 0.54
Mar 9 23:00:00 - 23:59:59 545 3.1% 0.151.8 MB 3.4% 0.50
Mar 10 00:00:00 - 00:59:59 526 3.0% 0.151.7 MB 3.3% 0.48
Mar 10 01:00:00 - 01:59:59 485 2.8% 0.131.5 MB 2.9% 0.43
Mar 10 02:00:00 - 02:59:59 466 2.7% 0.131.4 MB 2.6% 0.39
Mar 10 03:00:00 - 03:59:59 406 2.3% 0.111.2 MB 2.3% 0.34
Mar 10 04:00:00 - 04:15:00 59 0.3% 0.07161.7 KB 0.3% 0.18
TOTAL: 24:00:00 17505 100.0% 0.2052.3 MB 100.0% 0.62
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.fu-berlin.de 5016 27 0 0 0 0 0 0 4989
2csiph-out.news.xs4all.nl 915 48 0 6 0 0 0 0 861
3csiph-out.feed.uzoreto.com 503 37 0 0 1 0 0 0 465
4news.freedyn.net 414 16 0 0 0 0 0 0 398
5newsfeed.neva.ru 303 198 0 4 0 0 0 0 101
6news-out.mixmin.net 238 9 0 2 0 0 0 0 227
7csiph-out.feeder.erje.net 166 28 0 23 0 0 0 0 115
8csiph-out.news.weretis.net 124 59 0 2 0 0 0 0 63
9news-out.aioe.org 110 57 0 0 0 0 0 0 53
10feeder.eternal-september.org 104 34 0 3 0 0 0 0 67
11news-out.neodome.net 25 3 0 2 0 0 0 0 20
12csiph-out.news.tnetconsulting.net 25 15 0 0 0 0 0 0 10
13newsfeeds.xmission.com 18 1 0 1 0 0 0 0 16
14news.swapon.de 16 0 0 0 0 0 0 0 16
15news.fcku.it 10 6 0 0 0 0 0 0 4
16nntp.pasdenom.info 2 1 0 0 0 0 0 0 1
17news.bbs.nz 1 0 0 0 0 0 0 0 1
TOTAL: 17 7990 539 0 43 1 0 0 0 7407

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.politics 213
it-alt.sport.calcio.juventus 82
alt.computer.workshop 53
alt.bbs.synchronet 37
mozilla.dev.platform 10
mozilla.support.seamonkey 9
alt.job 8
alt.binaries.pictures.purebeauty 7
it-alt.comp.software.mozilla 6
at.jobs 6
mozilla.support.thunderbird 6
lada.talk 6
alt.windows7.general 5
spline.fli4l.dev 5
alt.binaries.ebook 5
mozilla.support.firefox 5
alt.music.pink-floyd.publius 4
alt.binaries.by-hash.shv1 4
alt.bestjobsusa.newjersey.jobs 4
alt.philosophy 3
TOTAL: 69 539

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

GroupsCount
uk.legal.moderated 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
Binary: misplaced binary 4633
EMP (md5) 1636
Too many newsgroups 131
EMP (phl) 120
EMP (phn nph) 65
EMP (phn path) 49
0 35
HTML Multipart 5
Too many newsgroups (meow) 1
Scoring filter 1
TOTAL: 10 6676

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 122
csiph-out.news.xs4all.nl 68
csiph-out.feeder.erje.net 62
news.freedyn.net 21
feeder.eternal-september.org 13
newsfeeds.xmission.com 10
csiph-out.news.tnetconsulting.net 10
csiph-out.news.weretis.net 6
news-out.neodome.net 5
newsfeed.fu-berlin.de 1
TOTAL: 11 319
Including strange strings
newsfeed.fu-berlin.de 314
csiph-out.news.xs4all.nl 29
news.swapon.de 16
csiph-out.feed.uzoreto.com 15
csiph-out.news.weretis.net 12
newsfeeds.xmission.com 5
news-out.mixmin.net 4
csiph-out.feeder.erje.net 4
newsfeed.neva.ru 3
news-out.aioe.org 3
TOTAL: 14 410
No colon-space in header
csiph-out.feeder.erje.net 2
csiph-out.feed.uzoreto.com 1
TOTAL: 2 3
TOTAL: 4 777

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1fu-berlin.de 16435 537 15898 0 0 0 3%23:59:50
2news.bbs.nz 41769 105 26329 16 0 669 0%23:59:35
3xmission 19796 96 15037 12 0 0 0%23:59:37
4newsfeed.xs4all.nl 16328 83 15423 0 0 135 0%23:59:51
5tnet 18299 55 16897 4 0 0 0%23:59:52
6news.freedyn.net 16436 10 16199 40 0 0 0%23:59:52
7weretis.net 16327 9 15839 2 0 0 0%23:59:45
8usenet.pasdenom.info 17391 9 16844 2 0 11 0%23:59:52
9news.mixmin.net 15926 8 15648 0 0 0 0%23:59:52
10news.uzoreto.com 16132 4 15931 17 0 0 0%23:59:51
11aioe 15182 3 14686 0 0 0 0%23:59:52
12neodome.net 17493 3 17185 0 0 0 0%23:59:52
13erje 16269 1 16146 15 0 0 0%23:59:52
14news.samoylyk.net 0 0 0 0 0 17505 0%23:58:38
15eternal-september 0 0 0 0 0 14701 0%23:59:52
16xanadu-bbs 0 0 0 0 0 17505 0%23:58:43
17news.swapon.de 1208 0 1187 0 0 0 0%23:40:01
18news.fcku.it 1374 0 1373 0 0 0 0%21:23:08
19neva.ru 12649 0 12584 5 0 0 0%23:59:52
TOTAL: 19 259014 923 233206 113 0 50526 0%452:57:47
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1fu-berlin.de6.3 MB0.0 KB6.3 MB0.1 KB/s12.0 KB23:59:50
2news.bbs.nz322.2 KB51.9 KB374.0 KB0.0 KB/s3.1 KB23:59:35
3newsfeed.xs4all.nl309.4 KB0.0 KB309.4 KB0.0 KB/s3.7 KB23:59:51
4xmission234.7 KB28.9 KB263.7 KB0.0 KB/s2.4 KB23:59:37
5tnet116.9 KB12.3 KB129.2 KB0.0 KB/s2.2 KB23:59:52
6news.freedyn.net39.7 KB853.0 KB892.7 KB0.0 KB/s17.9 KB23:59:52
7usenet.pasdenom.info33.9 KB3.4 KB37.3 KB0.0 KB/s3.4 KB23:59:52
8news.mixmin.net28.1 KB0.0 KB28.1 KB0.0 KB/s3.5 KB23:59:52
9neodome.net24.1 KB0.0 KB24.1 KB0.0 KB/s8.0 KB23:59:52
10news.uzoreto.com22.9 KB107.5 KB130.4 KB0.0 KB/s6.2 KB23:59:51
11weretis.net20.2 KB2.3 KB22.4 KB0.0 KB/s2.0 KB23:59:45
12aioe6.0 KB0.0 KB6.0 KB0.0 KB/s2.0 KB23:59:52
13erje3.8 KB62.5 KB66.2 KB0.0 KB/s4.1 KB23:59:52
14news.samoylyk.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:38
15eternal-september0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:52
16xanadu-bbs0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:43
17news.swapon.de0.0 KB0.0 KB0.0 KB0.0 KB/s023:40:01
18news.fcku.it0.0 KB0.0 KB0.0 KB0.0 KB/s021:23:08
19neva.ru0.0 KB14.4 KB14.4 KB0.0 KB/s2.9 KB23:59:52
TOTAL: 197.4 MB1.1 MB8.5 MB0.0 KB/s8.4 KB452:57:47
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1208.72.53.137 12 59.7 KB 6 0 005:08:03
279.132.248.114 4 11.2 KB 1 0 000:06:42
345.11.24.31.ftth.as8758.net 1 12.7 KB 1 0 000:26:29
TOTAL: 3 17281213667.0 MB 8 0 0154:34:24

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 227281212667.0 MB 7 0 0140:40:20
2*.11.24.31.ftth.as8758.net 1 12.7 KB 1 0 000:26:29
3*.net.upcbroadband.cz 67 00.0 KB 0 0 005:31:50
4*.bstnma.fios.verizon.net 2 00.0 KB 0 0 000:01:09
5*.upc-a.chello.nl 48 00.0 KB 0 0 007:50:03
6*.ch1.censys-scanner.com 1 00.0 KB 0 0 000:00:01
7*.range31-49.btcentralplus.com 3 00.0 KB 0 0 000:04:30
TOTAL: 7 349281213667.0 MB 8 0 0154:34:24

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
212.90.61.180 75.423 124.535 0.000115:16:46
91.187.33.87 12.405 2.851 0.00018:38:31
a131033.upc-a.chello.nl 2.965 0.526 0.00007:50:03
ip-78-102-198-192.net.upcbroadband.cz 3.956 0.960 0.00005:31:50
208.72.53.137 0.832 0.147 0.00005:08:03
208.72.53.130 0.192 0.052 0.00001:00:10
27.115.124.9 0.051 0.022 0.00000:30:00
45.11.24.31.ftth.as8758.net 0.092 0.054 0.00000:26:29
79.132.248.114 0.410 0.139 0.00000:06:42
host31-49-229-214.range31-49.btcentralplus.com 0.190 0.044 0.00000:04:30
pool-173-48-57-54.bstnma.fios.verizon.net 0.142 0.088 0.00000:01:09
27.115.124.74 0.060 0.015 0.00000:00:03
91.220.163.60 0.066 0.007 0.00000:00:01
scanner-06.ch1.censys-scanner.com 0.058 0.019 0.00000:00:01
27.115.124.99 0.051 0.022 0.00000:00:01
TOTAL: 15 96.893 129.481 0.000154:34:24

Curious NNRP server explorers [Top 20]:

SystemConn
91.187.33.87 205
ip-78-102-198-192.net.upcbroadband.cz 67
a131033.upc-a.chello.nl 48
host31-49-229-214.range31-49.btcentralplus.com 3
pool-173-48-57-54.bstnma.fios.verizon.net 2
208.72.53.130 2
27.115.124.74 1
91.220.163.60 1
scanner-06.ch1.censys-scanner.com 1
27.115.124.9 1
27.115.124.99 1
TOTAL: 11 332

NNRP unrecognized commands (by host):

SystemConn
27.115.124.9 2
scanner-06.ch1.censys-scanner.com 1
91.220.163.60 1
TOTAL: 3 4

NNRP unrecognized commands (by command):

CommandCount
EHLO www.censys.io 1
^C 1
^A 1
^V^C^A 1
TOTAL: 4 4

NNRP client timeouts [Top 20]:

SystemConnPeer
208.72.53.137 9 0
208.72.53.130 2 0
27.115.124.9 1 0
91.220.163.60 1 1
212.90.61.180 1 1
TOTAL: 5 14 2

Newsgroup request counts (by category):

CategoryCountPct
1comp 7 87.5%
2fr 1 12.5%
TOTAL: 2 8100.0%

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

NewsgroupCount
1comp.arch 5
2comp.sys.ibm.ps2.hardware 2
3fr.comp.usenet.serveurs 1
TOTAL: 3 8