Daily Usenet report for csiph.com

Mar 10 04:15:00 -- Mar 11 04:15:00

Unknown entries from news log file:

First 12 / 12 lines (100.0%)

Mar 10 10:03:31 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[56721]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Mar 10 10:03:31 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[56721]: 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 10 10:03:54 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[56759]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Mar 10 10:03:54 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[56759]: 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 10 18:00:39 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[83236]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Mar 10 18:00:39 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[83236]: 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 10 18:32:13 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[84935]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Mar 10 18:32:13 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[84935]: 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 10 21:24:21 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[94401]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Mar 10 21:24:21 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[94401]: 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 10 23:23:15 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[987]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Mar 10 23:23:15 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[987]: 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 22704 48.0%5.2 MB 56.8%
innd 17623 37.3%2.8 MB 30.4%
innfeed 3887 8.2%776.1 KB 8.3%
nnrpd 3035 6.4%419.5 KB 4.5%
nocem 17 0.0%2.3 KB 0.0%
controlchan 2 0.0%0.2 KB 0.0%
TOTAL: 6 47268 100.0%9.1 MB100.0%

History cache:

ReasonCount%Count
Positive hits 269782 51.5%
Negative hits 231634 44.2%
Do not exist 21970 4.2%
Cache misses 389 0.1%
TOTAL: 4 523775 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.716 0.0%39 0.000 18.359 85.000
article cleanup00:00:00.145 0.0%21813 0.000 0.007 0.028
article logging00:00:02.446 0.0%22328 0.000 0.110 1.500
article parse00:00:03.617 0.0%63768 0.000 0.057 0.485
article write00:04:41.687 0.3%14873 0.124 18.939 42.429
artlog/artcncl00:00:00.000 0.0%39 0.000 0.000 0.000
artlog/artparse00:00:00.013 0.0%361 0.000 0.036 5.000
data move00:00:00.169 0.0%490922 0.000 0.000 0.002
hisgrep/artcncl00:00:00.021 0.0%39 0.000 0.538 10.000
hishave/artcncl00:00:00.042 0.0%39 0.000 1.077 5.125
hishave/artparse00:00:00.001 0.0%358 0.000 0.003 0.091
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:02:13.186 0.2%527357 0.000 0.253 0.552
history sync00:00:03.217 0.0%365 0.000 8.814 61.000
history write00:02:42.907 0.2%21768 0.199 7.484 16.971
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
hiswrite/artparse00:00:03.055 0.0%357 0.000 8.557 151.000
idle23:30:40.462 97.9%464574 93.488 182.189 569.617
nntp read00:00:02.300 0.0%497075 0.000 0.005 0.021
overview write00:18:56.757 1.3%14873 0.219 76.431151232.000
perl filter00:00:27.440 0.0%21765 0.875 1.261 2.318
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.289 0.0%29762 0.000 0.010 0.038
TOTAL: 24:01:25.51723:59:58.470 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.024 0.0%14907 0.000 0.002 0.022
article prepare00:00:00.002 0.0%6732 0.000 0.000 0.021
article read00:00:01.962 0.0%1535 0.000 1.278 158.667
backlog stats00:00:00.153 0.0%280526 0.000 0.001 0.004
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.016 0.0%220431 0.000 0.005 0.011
data write00:00:01.323 0.0%207842 0.000 0.006 0.012
idle23:45:50.737100.0%280526 147.237 304.965 1861.353
status file00:00:00.992 0.0%439 0.000 2.260 52.000
TOTAL: 23:46:11.24523:45:56.209100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle40:43:27.022 88.1%2746 0.000 53389.302600200.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:05.426 0.0%127361 0.000 0.043 6.286
readart05:12:57.887 11.3%1358860 6.833 13.819 13.819
TOTAL: 46:14:03.07245:56:30.335 99.4%----

Control commands to INND:

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1feeder.eternal-september.org 15 4797 2364 2228 205 49%26:57:22
2csiph-out.news.xs4all.nl 0 5483 2148 2396 939 39%79:15:50
3newsfeeds.xmission.com 4 13949 1791 12145 13 12%24:38:08
4news-out.aioe.org 3 14274 1488 12711 75 10%24:08:57
5news-out.mixmin.net 4 15970 1242 14459 269 7%24:08:34
6csiph-out.feeder.erje.net 1449 73728 1166 72184 378 1%119:33:24
7csiph-out.news.weretis.net 4 29120 969 28040 111 3%45:26:35
8csiph-out.feed.uzoreto.com 864 24080 873 22645 562 3%71:17:14
9newsfeed.fu-berlin.de 17 18383 856 13292 4235 4%25:18:53
10news.freedyn.net 2 17064 783 15984 297 4%23:59:57
11newsfeed.neva.ru 150 18767 711 17838 218 3%71:53:45
12news.fcku.it 11 1630 209 1408 13 12%21:53:03
13news-out.neodome.net 2 16605 160 16419 26 0%23:59:54
14csiph-out.news.tnetconsulting.net 3 13144 110 13013 21 0%20:57:30
15nntp.pasdenom.info 2 9993 74 9919 0 0%24:53:31
16news.swapon.de 8 1287 57 1229 1 4%25:42:17
17news.bbs.nz 2 15376 4 15372 0 0%24:05:46
18localhost 26 0 0 0 0 0%00:00:00
TOTAL: 18 2566 293650 15005 271282 7363 5%678:10:40
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1csiph-out.feeder.erje.net7.7 MB204.3 KB3.8 MB11.6 MB 65%7.7 KB
2feeder.eternal-september.org6.2 MB0.0 KB3.6 MB9.8 MB 62%3.9 KB
3csiph-out.news.xs4all.nl5.7 MB0.0 KB7.7 MB13.4 MB 42%4.4 KB
4newsfeeds.xmission.com5.1 MB0.0 KB448.6 KB5.5 MB 92%3.1 KB
5news-out.aioe.org3.6 MB0.0 KB129.8 KB3.8 MB 96%2.5 KB
6news-out.mixmin.net3.0 MB0.0 KB543.2 KB3.5 MB 84%2.4 KB
7csiph-out.news.weretis.net2.7 MB0.0 KB1.3 MB4.0 MB 66%3.8 KB
8csiph-out.feed.uzoreto.com2.6 MB19.6 KB11.0 MB13.6 MB 19%9.7 KB
9news.freedyn.net2.2 MB0.0 KB1.8 MB4.1 MB 54%3.8 KB
10newsfeed.fu-berlin.de2.1 MB0.0 KB10.2 MB12.3 MB 17%2.5 KB
11newsfeed.neva.ru2.0 MB0.0 KB674.0 KB2.6 MB 74%2.9 KB
12news-out.neodome.net472.9 KB0.0 KB169.6 KB642.6 KB 73%3.5 KB
13csiph-out.news.tnetconsulting.net388.2 KB0.0 KB41.9 KB430.1 KB 90%3.3 KB
14news.fcku.it327.2 KB0.0 KB17.9 KB345.1 KB 94%1.6 KB
15nntp.pasdenom.info165.9 KB0.0 KB0.0 KB165.9 KB100%2.2 KB
16news.swapon.de150.7 KB0.0 KB0.7 KB151.5 KB 99%2.6 KB
17news.bbs.nz7.5 KB0.0 KB0.0 KB7.5 KB100%1.9 KB
18localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1844.3 MB223.9 KB41.4 MB85.9 MB 51%3.9 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Mar 10 04:15:00 - 04:59:59 182 1.2% 0.07477.2 KB 1.1% 0.18
Mar 10 05:00:00 - 05:59:59 326 2.2% 0.092.3 MB 5.2% 0.64
Mar 10 06:00:00 - 06:59:59 447 3.0% 0.121.6 MB 3.7% 0.46
Mar 10 07:00:00 - 07:59:59 472 3.2% 0.131.3 MB 2.9% 0.37
Mar 10 08:00:00 - 08:59:59 584 3.9% 0.161.4 MB 3.2% 0.41
Mar 10 09:00:00 - 09:59:59 716 4.8% 0.201.9 MB 4.4% 0.55
Mar 10 10:00:00 - 10:59:59 669 4.5% 0.191.7 MB 3.9% 0.48
Mar 10 11:00:00 - 11:59:59 746 5.0% 0.212.2 MB 4.9% 0.62
Mar 10 12:00:00 - 12:59:59 672 4.5% 0.191.7 MB 4.0% 0.50
Mar 10 13:00:00 - 13:59:59 838 5.6% 0.232.1 MB 4.8% 0.60
Mar 10 14:00:00 - 14:59:59 826 5.6% 0.232.2 MB 5.0% 0.63
Mar 10 15:00:00 - 15:59:59 1023 6.9% 0.283.5 MB 8.0% 1.00
Mar 10 16:00:00 - 16:59:59 868 5.8% 0.242.2 MB 5.0% 0.63
Mar 10 17:00:00 - 17:59:59 819 5.5% 0.232.5 MB 5.8% 0.72
Mar 10 18:00:00 - 18:59:59 858 5.8% 0.242.5 MB 5.6% 0.70
Mar 10 19:00:00 - 19:59:59 828 5.6% 0.232.5 MB 5.7% 0.71
Mar 10 20:00:00 - 20:59:59 777 5.2% 0.222.1 MB 4.7% 0.59
Mar 10 21:00:00 - 21:59:59 628 4.2% 0.171.6 MB 3.7% 0.46
Mar 10 22:00:00 - 22:59:59 575 3.9% 0.161.8 MB 4.2% 0.52
Mar 10 23:00:00 - 23:59:59 528 3.6% 0.151.9 MB 4.3% 0.54
Mar 11 00:00:00 - 00:59:59 449 3.0% 0.121.2 MB 2.8% 0.35
Mar 11 01:00:00 - 01:59:59 356 2.4% 0.101.1 MB 2.6% 0.32
Mar 11 02:00:00 - 02:59:59 280 1.9% 0.08788.0 KB 1.8% 0.22
Mar 11 03:00:00 - 03:59:59 331 2.2% 0.091.0 MB 2.3% 0.29
Mar 11 04:00:00 - 04:15:00 72 0.5% 0.08212.7 KB 0.5% 0.24
TOTAL: 24:00:00 14870 100.0% 0.1743.9 MB 100.0% 0.52
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.fu-berlin.de 4732 10 0 0 0 0 0 0 4722
2csiph-out.news.xs4all.nl 939 60 0 0 0 0 0 0 879
3csiph-out.feed.uzoreto.com 588 40 0 6 0 0 0 0 542
4news.freedyn.net 293 9 0 0 0 0 0 0 284
5news-out.mixmin.net 289 16 0 0 0 0 0 0 273
6newsfeed.neva.ru 218 94 0 0 0 0 0 0 124
7csiph-out.feeder.erje.net 217 57 0 42 1 0 0 0 117
8feeder.eternal-september.org 212 71 0 0 0 0 0 0 141
9csiph-out.news.weretis.net 135 67 0 0 0 0 0 0 68
10news-out.aioe.org 67 40 0 0 0 0 0 0 27
11news.swapon.de 28 0 0 0 0 0 0 0 28
12news-out.neodome.net 24 2 0 0 0 0 0 0 22
13csiph-out.news.tnetconsulting.net 20 19 0 0 0 0 0 0 1
14newsfeeds.xmission.com 20 0 0 0 0 0 0 0 20
15news.fcku.it 13 13 0 0 0 0 0 0 0
TOTAL: 15 7795 498 0 48 1 0 0 0 7248

Unwanted newsgroups [Top 20]:

NewsgroupCount
it-alt.sport.calcio.juventus 98
lada.talk 88
alt.computer.workshop 67
alt.bbs.synchronet 28
alt.binaries.by-hash.shv1 17
alt.job 14
mozilla.dev.platform 11
mozilla.support.thunderbird 10
mozilla.dev.l10n 9
it-alt.comp.software.mozilla 9
mozilla.dev.security.policy 8
alt.politics 8
mozilla.support.seamonkey 8
at.jobs 8
alt.music.pink-floyd.publius 7
lada.kitchen 6
1.test 5
lada.auto 5
alt.politics.usa 5
mozilla.support.firefox 4
TOTAL: 74 498

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 4254
EMP (md5) 1754
Too many newsgroups 145
EMP (phn path) 115
EMP (phl) 62
0 24
EMP (phn nph) 9
Scoring filter 5
HTML Multipart 3
Too many newsgroups (meow) 2
TOTAL: 10 6373

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
csiph-out.feeder.erje.net 50
csiph-out.news.xs4all.nl 1
csiph-out.feed.uzoreto.com 1
TOTAL: 3 52
Huge articles
csiph-out.feed.uzoreto.com 142
csiph-out.news.xs4all.nl 83
csiph-out.feeder.erje.net 57
feeder.eternal-september.org 35
news.freedyn.net 18
csiph-out.news.weretis.net 13
newsfeeds.xmission.com 6
newsfeed.fu-berlin.de 3
news-out.mixmin.net 1
TOTAL: 9 358
Including strange strings
newsfeed.fu-berlin.de 370
csiph-out.news.xs4all.nl 32
news.swapon.de 27
csiph-out.feed.uzoreto.com 26
news-out.mixmin.net 21
csiph-out.news.weretis.net 15
newsfeeds.xmission.com 7
csiph-out.feeder.erje.net 5
news-out.aioe.org 4
feeder.eternal-september.org 4
TOTAL: 11 514
No colon-space in header
csiph-out.feeder.erje.net 2
csiph-out.feed.uzoreto.com 1
TOTAL: 2 3
TOTAL: 4 927

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1fu-berlin.de 13720 578 13142 0 0 0 4%23:56:50
2erje 14449 292 12478 400 0 15 2%23:56:12
3news.bbs.nz 26835 106 14098 17 0 0 0%23:56:56
4newsfeed.xs4all.nl 13629 91 12674 0 0 102 0%23:54:08
5xmission 14330 67 12367 7 0 3 0%23:56:50
6tnet 14961 26 14199 1 0 0 0%23:56:50
7usenet.pasdenom.info 14420 18 14126 6 0 0 0%23:56:50
8aioe 12178 11 11936 0 0 0 0%23:58:55
9news.mixmin.net 12711 9 12581 0 0 0 0%23:56:50
10news.uzoreto.com 13189 8 13096 4 0 0 0%23:56:50
11news.freedyn.net 13946 5 13823 30 0 0 0%23:56:45
12neodome.net 14729 5 14494 0 0 67 0%23:56:57
13weretis.net 13210 2 12886 2 0 0 0%23:56:50
14news.fcku.it 1294 0 1293 0 0 0 0%21:57:20
15news.samoylyk.net 0 0 0 0 0 14861 0%23:55:44
16neva.ru 13085 0 13030 4 0 0 0%23:55:47
17eternal-september 0 0 0 0 0 11699 0%23:56:58
18xanadu-bbs 0 0 0 0 0 14861 0%23:55:51
19news.swapon.de 1309 0 1294 0 0 0 0%23:45:12
TOTAL: 19 207995 1218 187517 471 0 41608 0%452:44:35
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1fu-berlin.de5.6 MB0.0 KB5.6 MB0.1 KB/s9.9 KB23:56:50
2erje844.2 KB1.1 MB1.9 MB0.0 KB/s2.9 KB23:56:12
3newsfeed.xs4all.nl371.7 KB0.0 KB371.7 KB0.0 KB/s4.1 KB23:54:08
4news.bbs.nz270.7 KB52.7 KB323.4 KB0.0 KB/s2.6 KB23:56:56
5xmission190.7 KB13.5 KB204.1 KB0.0 KB/s2.8 KB23:56:50
6tnet59.7 KB1.2 KB61.0 KB0.0 KB/s2.3 KB23:56:50
7usenet.pasdenom.info43.5 KB15.2 KB58.7 KB0.0 KB/s2.4 KB23:56:50
8neodome.net36.9 KB0.0 KB36.9 KB0.0 KB/s7.4 KB23:56:57
9news.mixmin.net26.3 KB0.0 KB26.3 KB0.0 KB/s2.9 KB23:56:50
10aioe22.1 KB0.0 KB22.1 KB0.0 KB/s2.0 KB23:58:55
11news.uzoreto.com18.1 KB29.5 KB47.6 KB0.0 KB/s4.0 KB23:56:50
12news.freedyn.net12.8 KB485.3 KB498.1 KB0.0 KB/s14.2 KB23:56:45
13weretis.net3.3 KB6.5 KB9.9 KB0.0 KB/s2.5 KB23:56:50
14news.fcku.it0.0 KB0.0 KB0.0 KB0.0 KB/s021:57:20
15news.samoylyk.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:55:44
16neva.ru0.0 KB8.1 KB8.1 KB0.0 KB/s2.0 KB23:55:47
17eternal-september0.0 KB0.0 KB0.0 KB0.0 KB/s023:56:58
18xanadu-bbs0.0 KB0.0 KB0.0 KB0.0 KB/s023:55:51
19news.swapon.de0.0 KB0.0 KB0.0 KB0.0 KB/s023:45:12
TOTAL: 197.5 MB1.7 MB9.2 MB0.0 KB/s5.6 KB452:44:35
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1208.72.53.144 8 614.1 KB 7 0 003:26:29
TOTAL: 1 8 614.1 KB 7 0 046:13:32

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 299 614.1 KB 7 0 028:42:14
2*.range31-49.btcentralplus.com 6 00.0 KB 0 0 000:47:18
3*.hsd1.wa.comcast.net 1 00.0 KB 0 0 000:30:03
4*.security.ipip.net 1 00.0 KB 0 0 000:00:01
5*.upc-a.chello.nl 66 00.0 KB 0 0 010:57:09
6*.dyn.optonline.net 1 00.0 KB 0 0 005:16:17
7*.16.68.85.rev.sfr.net 1 00.0 KB 0 0 000:00:26
TOTAL: 7 375 614.1 KB 7 0 046:13:32

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
91.187.33.87 17.504 4.147 0.00024:15:24
a131033.upc-a.chello.nl 3.968 0.896 0.00010:57:09
ool-45780ab3.dyn.optonline.net 53.227 135.108 0.00005:16:17
208.72.53.144 0.573 0.182 0.00003:26:29
208.72.53.137 0.211 0.016 0.00001:00:10
host31-49-229-214.range31-49.btcentralplus.com 0.386 0.082 0.00000:47:18
c-73-140-175-55.hsd1.wa.comcast.net 0.064 0.014 0.00000:30:03
124.16.68.85.rev.sfr.net 0.135 0.034 0.00000:00:26
192.241.228.35 0.062 0.008 0.00000:00:09
scan-141.security.ipip.net 0.057 0.016 0.00000:00:01
TOTAL: 10 76.187 140.503 0.00046:13:32

Curious NNRP server explorers [Top 20]:

SystemConn
91.187.33.87 288
a131033.upc-a.chello.nl 66
host31-49-229-214.range31-49.btcentralplus.com 6
208.72.53.137 2
ool-45780ab3.dyn.optonline.net 1
192.241.228.35 1
124.16.68.85.rev.sfr.net 1
c-73-140-175-55.hsd1.wa.comcast.net 1
scan-141.security.ipip.net 1
TOTAL: 9 367

NNRP unrecognized commands (by host):

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

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

SystemConnPeer
208.72.53.144 5 0
208.72.53.137 2 0
host31-49-229-214.range31-49.btcentralplus.com 1 0
91.187.33.87 1 0
c-73-140-175-55.hsd1.wa.comcast.net 1 0
TOTAL: 5 10 0

Newsgroup request counts (by category):

CategoryCountPct
1comp 7100.0%
TOTAL: 1 7100.0%

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

NewsgroupCount
1comp.arch 7
TOTAL: 1 7