Daily Usenet report for csiph.com

Apr 11 04:15:00 -- Apr 12 04:15:00

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Apr 11 08:16:13 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[2847]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Apr 11 08:16:13 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[2847]: 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]
Apr 11 09:16:09 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[6376]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Apr 11 09:16:09 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[6376]: 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]
Apr 11 11:05:47 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[12947]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Apr 11 11:05:47 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[12947]: 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
innd 23076 49.8%3.7 MB 40.7%
inn 17521 37.8%4.4 MB 48.2%
innfeed 3611 7.8%728.1 KB 7.8%
nnrpd 2116 4.6%295.7 KB 3.2%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 46325 100.0%9.1 MB100.0%

History cache:

ReasonCount%Count
Positive hits 269624 53.4%
Negative hits 218468 43.2%
Do not exist 16926 3.3%
Cache misses 282 0.1%
TOTAL: 4 505300 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.144 0.0%32 0.000 35.750 151.000
article cleanup00:00:00.119 0.0%16820 0.000 0.007 0.044
article logging00:00:00.821 0.0%17331 0.000 0.047 0.915
article parse00:00:01.244 0.0%52885 0.000 0.024 0.205
article write00:01:20.889 0.1%13544 0.275 5.972 61.562
artlog/artcncl00:00:00.001 0.0%32 0.000 0.031 0.333
artlog/artparse00:00:00.006 0.0%235 0.000 0.026 1.000
data move00:00:00.167 0.0%470448 0.000 0.000 0.001
hisgrep/artcncl00:00:00.193 0.0%32 0.000 6.031 24.000
hishave/artcncl00:00:00.078 0.0%32 0.000 2.438 32.000
hishave/artparse00:00:00.002 0.0%233 0.000 0.009 0.143
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:01:12.945 0.1%508973 0.002 0.143 0.497
history sync00:00:02.274 0.0%366 0.000 6.213 166.500
history write00:01:19.283 0.1%16818 0.373 4.714 22.057
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
hiswrite/artparse00:00:00.807 0.0%233 0.000 3.464 50.000
idle23:46:31.611 98.6%471534 106.300 181.517 665.351
nntp read00:00:02.119 0.0%482830 0.001 0.004 0.010
overview write00:14:35.695 1.0%13544 0.150 64.656 9187.310
perl filter00:00:23.285 0.0%16816 0.989 1.385 4.114
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.243 0.0%27088 0.000 0.009 0.046
TOTAL: 24:06:28.65324:05:32.926 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.036 0.0%13557 0.000 0.003 0.022
article prepare00:00:00.001 0.0%3400 0.000 0.000 0.036
article read00:00:00.402 0.0%736 0.000 0.546 129.000
backlog stats00:00:00.147 0.0%255216 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.859 0.0%201915 0.000 0.004 0.019
data write00:00:01.281 0.0%189322 0.001 0.007 0.059
idle23:55:30.905100.0%255216 191.283 337.482 1023.625
status file00:00:00.667 0.0%349 0.000 1.911 96.000
TOTAL: 23:55:43.11623:55:34.298100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle40:02:26.606 99.9%1168 0.000123413.190600163.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.030 0.0%2720 0.000 0.011 1.429
readart00:00:00.075 0.0%3 1.000 25.000 37.000
TOTAL: 40:04:14.65040:02:26.711 99.9%----

Control commands to INND:

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1csiph-out.news.xs4all.nl 2305 15941 2761 11642 1538 17%122:40:05
2feeder.eternal-september.org 15 4833 1895 2772 166 39%27:24:30
3newsfeeds.xmission.com 3 12124 1703 10420 1 14%23:32:24
4news-out.mixmin.net 2 15028 1475 13123 430 9%23:59:26
5csiph-out.feeder.erje.net 1440 69354 1333 67301 720 1%118:47:53
6csiph-out.news.weretis.net 3 26799 923 25820 56 3%47:59:41
7newsfeed.fu-berlin.de 9 13950 826 12988 136 5%25:23:44
8csiph-out.feed.uzoreto.com 868 26235 672 24968 595 2%71:14:49
9news-out.aioe.org 2 12231 473 11736 22 3%23:59:54
10nntp.pasdenom.info 4 15037 375 14652 10 2%33:48:04
11newsfeed.neva.ru 145 17003 370 16575 58 2%71:42:43
12news-out.neodome.net 3 15100 349 14703 48 2%24:59:56
13news.freedyn.net 1 15930 132 15718 80 0%23:59:56
14csiph-out.news.tnetconsulting.net 2 13366 125 13235 6 0%23:59:52
15news.fcku.it 15 1039 81 958 0 7%21:56:32
16news.swapon.de 8 1262 38 1224 0 3%23:40:26
17news.bbs.nz 2 13098 2 13096 0 0%23:59:40
18localhost 26 0 0 0 0 0%00:00:00
TOTAL: 18 4853 288330 13533 270931 3866 4%733:09:35
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1csiph-out.feeder.erje.net8.4 MB16.7 KB5.0 MB13.4 MB 62%6.7 KB
2csiph-out.news.xs4all.nl6.6 MB0.0 KB9.4 MB16.1 MB 41%3.8 KB
3feeder.eternal-september.org5.3 MB0.0 KB8.9 MB14.1 MB 37%7.0 KB
4newsfeeds.xmission.com5.1 MB0.0 KB41.4 KB5.2 MB 99%3.1 KB
5news-out.mixmin.net3.2 MB0.0 KB816.3 KB4.0 MB 80%2.2 KB
6csiph-out.news.weretis.net2.5 MB0.0 KB1.1 MB3.6 MB 68%3.8 KB
7csiph-out.feed.uzoreto.com2.0 MB0.0 KB6.2 MB8.2 MB 24%6.6 KB
8newsfeed.fu-berlin.de1.8 MB0.0 KB305.8 KB2.1 MB 85%2.2 KB
9news-out.aioe.org1.6 MB0.0 KB39.8 KB1.7 MB 97%3.5 KB
10newsfeed.neva.ru1.0 MB0.0 KB156.7 KB1.2 MB 86%2.8 KB
11news-out.neodome.net832.1 KB0.0 KB167.4 KB999.5 KB 83%2.5 KB
12nntp.pasdenom.info767.2 KB0.0 KB17.6 KB784.8 KB 97%2.0 KB
13news.freedyn.net485.0 KB0.0 KB566.6 KB1.0 MB 46%5.0 KB
14csiph-out.news.tnetconsulting.net401.9 KB0.0 KB9.6 KB411.6 KB 97%3.1 KB
15news.fcku.it116.6 KB0.0 KB0.0 KB116.6 KB100%1.4 KB
16news.swapon.de78.6 KB0.0 KB0.0 KB78.6 KB100%2.1 KB
17news.bbs.nz5.1 KB0.0 KB0.0 KB5.1 KB100%2.6 KB
18localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1840.3 MB16.7 KB32.7 MB73.0 MB 55%4.3 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Apr 11 04:15:00 - 04:59:59 175 1.3% 0.06556.9 KB 1.3% 0.21
Apr 11 05:00:00 - 05:59:59 287 2.1% 0.08883.6 KB 2.1% 0.25
Apr 11 06:00:00 - 06:59:59 366 2.7% 0.101.6 MB 4.0% 0.47
Apr 11 07:00:00 - 07:59:59 627 4.6% 0.172.3 MB 5.6% 0.64
Apr 11 08:00:00 - 08:59:59 730 5.4% 0.202.4 MB 5.8% 0.67
Apr 11 09:00:00 - 09:59:59 762 5.6% 0.212.1 MB 5.2% 0.60
Apr 11 10:00:00 - 10:59:59 702 5.2% 0.202.2 MB 5.3% 0.61
Apr 11 11:00:00 - 11:59:59 733 5.4% 0.202.9 MB 7.2% 0.83
Apr 11 12:00:00 - 12:59:59 649 4.8% 0.181.6 MB 4.0% 0.46
Apr 11 13:00:00 - 13:59:59 671 5.0% 0.191.9 MB 4.6% 0.53
Apr 11 14:00:00 - 14:59:59 722 5.3% 0.202.1 MB 5.1% 0.59
Apr 11 15:00:00 - 15:59:59 656 4.9% 0.181.5 MB 3.6% 0.42
Apr 11 16:00:00 - 16:59:59 814 6.0% 0.232.5 MB 6.1% 0.70
Apr 11 17:00:00 - 17:59:59 735 5.4% 0.202.1 MB 5.1% 0.59
Apr 11 18:00:00 - 18:59:59 734 5.4% 0.201.8 MB 4.5% 0.52
Apr 11 19:00:00 - 19:59:59 685 5.1% 0.191.9 MB 4.6% 0.53
Apr 11 20:00:00 - 20:59:59 659 4.9% 0.181.8 MB 4.4% 0.50
Apr 11 21:00:00 - 21:59:59 567 4.2% 0.161.6 MB 3.9% 0.45
Apr 11 22:00:00 - 22:59:59 408 3.0% 0.111.4 MB 3.4% 0.39
Apr 11 23:00:00 - 23:59:59 434 3.2% 0.121.3 MB 3.2% 0.37
Apr 12 00:00:00 - 00:59:59 384 2.8% 0.111.1 MB 2.8% 0.32
Apr 12 01:00:00 - 01:59:59 329 2.4% 0.091.1 MB 2.8% 0.33
Apr 12 02:00:00 - 02:59:59 285 2.1% 0.08861.3 KB 2.1% 0.24
Apr 12 03:00:00 - 03:59:59 290 2.1% 0.08938.5 KB 2.3% 0.26
Apr 12 04:00:00 - 04:15:00 98 0.7% 0.11330.0 KB 0.8% 0.37
TOTAL: 24:00:00 13502 100.0% 0.1640.5 MB 100.0% 0.48
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1csiph-out.news.xs4all.nl 1596 84 0 0 0 0 0 0 1512
2csiph-out.feed.uzoreto.com 608 45 0 0 0 0 0 0 563
3newsfeed.fu-berlin.de 488 33 0 0 0 0 0 0 455
4news-out.mixmin.net 434 12 0 0 0 0 0 0 422
5csiph-out.feeder.erje.net 346 93 0 4 0 0 0 0 249
6feeder.eternal-september.org 181 36 0 0 0 0 0 0 145
7news.freedyn.net 89 4 0 0 0 0 0 0 85
8csiph-out.news.weretis.net 71 8 0 0 0 0 0 0 63
9newsfeed.neva.ru 59 19 0 0 0 0 0 0 40
10news-out.neodome.net 48 3 0 0 0 0 0 0 45
11news-out.aioe.org 25 11 0 0 0 0 0 0 14
12news.swapon.de 20 0 0 0 0 0 0 0 20
13nntp.pasdenom.info 18 1 0 0 0 0 0 0 17
14newsfeeds.xmission.com 7 2 0 0 0 0 0 0 5
15csiph-out.news.tnetconsulting.net 3 3 0 0 0 0 0 0 0
TOTAL: 15 3993 354 0 4 0 0 0 0 3635

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.computer.workshop 83
alt.news.misc 50
alt.binaries.by-hash.shv1 43
alt.bbs.synchronet 25
spline.eisfair 24
alt.binaries.pictures.purebeauty 15
lada.talk 9
spline.fli4l.dev 9
lada.auto 8
alt.politics 7
alt.att 5
alt.philosophy 5
it-alt.sport.calcio.juventus 5
alt.computer.hardware 4
mozilla.support.seamonkey 4
alt.binaries.ebooks 4
alt.christian.bible 3
cakewalk.audio 3
alt.finance 2
alt.science 2
TOTAL: 53 354

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 2148
EMP (phn path) 245
EMP (phl) 175
Too many newsgroups 151
EMP (phn nph) 85
0 35
Binary Payload 18
Binary: misplaced binary 17
Scoring filter 14
TOTAL: 9 2888

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
csiph-out.feeder.erje.net 50
TOTAL: 1 50
Huge articles
feeder.eternal-september.org 73
csiph-out.feeder.erje.net 71
csiph-out.news.xs4all.nl 40
csiph-out.feed.uzoreto.com 33
csiph-out.news.weretis.net 10
news.freedyn.net 3
news-out.mixmin.net 2
newsfeeds.xmission.com 1
news-out.neodome.net 1
TOTAL: 9 234
Including strange strings
newsfeed.fu-berlin.de 358
csiph-out.news.xs4all.nl 66
news.swapon.de 20
csiph-out.feed.uzoreto.com 19
csiph-out.news.weretis.net 16
csiph-out.feeder.erje.net 12
news-out.mixmin.net 4
news-out.aioe.org 4
newsfeeds.xmission.com 4
news.freedyn.net 3
TOTAL: 14 511
No colon-space in header
csiph-out.news.xs4all.nl 2
TOTAL: 1 2
TOTAL: 4 797

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1fu-berlin.de 12527 466 12061 0 0 0 3%23:55:03
2news.bbs.nz 24692 120 12761 15 0 0 0%23:51:08
3xmission 13683 46 11008 13 0 0 0%23:59:11
4newsfeed.xs4all.nl 10926 21 10427 0 0 79 0%23:54:22
5news.uzoreto.com 12650 18 11821 5 0 0 0%23:50:09
6tnet 13581 18 12908 4 0 0 0%23:56:17
7neodome.net 13288 7 13076 0 0 0 0%23:56:04
8news.freedyn.net 13491 6 13256 45 0 0 0%23:59:05
9news.mixmin.net 11206 6 11055 0 0 0 0%23:50:09
10erje 11805 6 11751 30 0 0 0%23:50:09
11weretis.net 12170 3 11841 8 0 0 0%23:56:16
12usenet.pasdenom.info 12732 3 12565 0 0 1 0%23:59:49
13aioe 11302 1 11081 0 0 0 0%23:51:17
14news.swapon.de 1389 1 1374 0 0 0 0%23:50:52
15news.samoylyk.net 0 0 0 0 0 13451 0%23:50:07
16neva.ru 12374 0 12298 6 0 2 0%23:52:19
17xanadu-bbs 0 0 0 0 0 13502 0%23:58:42
18news.fcku.it 904 0 904 0 0 0 0%21:27:21
19eternal-september 0 0 0 0 0 10977 0%23:50:09
TOTAL: 19 188720 722 170187 126 0 38012 0%451:38:29
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1fu-berlin.de5.2 MB0.0 KB5.2 MB0.1 KB/s11.5 KB23:55:03
2news.bbs.nz311.8 KB63.2 KB375.0 KB0.0 KB/s2.8 KB23:51:08
3newsfeed.xs4all.nl180.1 KB0.0 KB180.1 KB0.0 KB/s8.6 KB23:54:22
4xmission150.4 KB23.6 KB174.0 KB0.0 KB/s2.9 KB23:59:11
5news.uzoreto.com136.6 KB25.1 KB161.7 KB0.0 KB/s7.0 KB23:50:09
6neodome.net67.6 KB0.0 KB67.6 KB0.0 KB/s9.7 KB23:56:04
7news.freedyn.net49.2 KB1.0 MB1.1 MB0.0 KB/s21.5 KB23:59:05
8news.mixmin.net46.0 KB0.0 KB46.0 KB0.0 KB/s7.7 KB23:50:09
9usenet.pasdenom.info41.1 KB0.0 KB41.1 KB0.0 KB/s13.7 KB23:59:49
10tnet36.7 KB5.2 KB41.9 KB0.0 KB/s1.9 KB23:56:17
11erje34.8 KB255.5 KB290.3 KB0.0 KB/s8.1 KB23:50:09
12news.swapon.de32.1 KB0.0 KB32.1 KB0.0 KB/s32.1 KB23:50:52
13weretis.net7.3 KB23.4 KB30.8 KB0.0 KB/s2.8 KB23:56:16
14aioe1.3 KB0.0 KB1.3 KB0.0 KB/s1.3 KB23:51:17
15news.samoylyk.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:07
16neva.ru0.0 KB44.0 KB44.0 KB0.0 KB/s7.3 KB23:52:19
17xanadu-bbs0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:42
18news.fcku.it0.0 KB0.0 KB0.0 KB0.0 KB/s021:27:21
19eternal-september0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:09
TOTAL: 196.3 MB1.5 MB7.7 MB0.0 KB/s9.4 KB451:38:29
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1208.72.53.136 13 22.6 KB 2 0 005:34:13
2p5dee96ca.dip0.t-ipconnect.de 1 11.3 KB 1 0 000:00:05
TOTAL: 2 14 33.9 KB 6 0 040:03:56

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 240 22.6 KB 5 0 026:54:02
2*.dip0.t-ipconnect.de 1 11.3 KB 1 0 000:00:05
3*.range86-139.btcentralplus.com 2 00.0 KB 0 0 000:00:30
4*.ch1.censys-scanner.com 4 00.0 KB 0 0 000:00:03
5*.net.upcbroadband.cz 49 00.0 KB 0 0 004:03:26
6*.hsd1.wa.comcast.net 1 00.0 KB 0 0 000:30:00
7*.upc-a.chello.nl 53 00.0 KB 0 0 008:35:46
TOTAL: 7 350 33.9 KB 6 0 040:03:56

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
91.187.33.87 13.427 2.964 0.00020:19:28
a131033.upc-a.chello.nl 3.252 0.587 0.00008:35:46
208.72.53.136 0.876 0.205 0.00005:34:13
ip-78-102-198-192.net.upcbroadband.cz 2.929 0.626 0.00004:03:26
172.83.100.199 0.207 0.030 0.00001:00:09
c-73-140-175-55.hsd1.wa.comcast.net 0.064 0.009 0.00000:30:00
host86-139-107-9.range86-139.btcentralplus.com 0.122 0.029 0.00000:00:30
143.198.77.253 0.058 0.015 0.00000:00:10
p5dee96ca.dip0.t-ipconnect.de 0.102 0.023 0.00000:00:05
scanner-07.ch1.censys-scanner.com 0.262 0.015 0.00000:00:03
TOTAL: 10 21.299 4.503 0.00040:03:56

Curious NNRP server explorers [Top 20]:

SystemConn
91.187.33.87 224
a131033.upc-a.chello.nl 53
ip-78-102-198-192.net.upcbroadband.cz 49
scanner-07.ch1.censys-scanner.com 4
172.83.100.199 2
host86-139-107-9.range86-139.btcentralplus.com 2
c-73-140-175-55.hsd1.wa.comcast.net 1
143.198.77.253 1
TOTAL: 8 336

NNRP unrecognized commands (by host):

SystemConn
scanner-07.ch1.censys-scanner.com 3
TOTAL: 1 3

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

SystemConnPeer
208.72.53.136 9 0
172.83.100.199 2 0
scanner-07.ch1.censys-scanner.com 1 2
c-73-140-175-55.hsd1.wa.comcast.net 1 0
TOTAL: 4 13 2

Newsgroup request counts (by category):

CategoryCountPct
1free 415966100.0%
2de 104 0.0%
3pt 28 0.0%
4alt 1 0.0%
5news 1 0.0%
6comp 1 0.0%
TOTAL: 6 416101100.0%

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

NewsgroupCount
1free.pt 415966
2de.comp.text.pdf 104
3pt.rec.desporto.futebol 28
4comp.sys.ibm.ps2.hardware 1
5alt.free.newsservers 1
6news.software.nntp 1
TOTAL: 6 416101
innreport from INN 2.6.4 based on work (c) 1996-1999 by Fabien Tassin <fta@sofaraway.org>
Local contact: admin@kev009.com