Daily Usenet report for csiph.com

Jan 14 04:15:00 -- Jan 15 04:15:00

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Jan 14 08:22:17 csiph nnrpd[86463]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Jan 14 08:22:17 csiph nnrpd[86463]: 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]
Jan 15 02:26:42 csiph nnrpd[83368]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Jan 15 02:26:42 csiph nnrpd[83368]: 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]
Jan 15 02:38:41 csiph nnrpd[84056]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Jan 15 02:38:41 csiph nnrpd[84056]: 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]
Jan 15 02:41:26 csiph nnrpd[84169]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Jan 15 02:41:26 csiph nnrpd[84169]: 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 20049 50.3%5.1 MB 65.3%
innd 13863 34.8%1.8 MB 23.8%
innfeed 3602 9.0%613.6 KB 7.7%
nnrpd 2296 5.8%246.9 KB 3.1%
nocem 31 0.1%3.5 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 6 39842 100.0%7.7 MB100.0%

History cache:

ReasonCount%Count
Positive hits 310184 53.7%
Negative hits 242234 42.0%
Do not exist 19543 3.4%
Cache misses 5405 0.9%
TOTAL: 4 577366 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.378 0.0%37 0.000 10.216 30.333
article cleanup00:00:00.116 0.0%18986 0.000 0.006 0.036
article logging00:00:00.524 0.0%19463 0.000 0.027 1.092
article parse00:00:01.916 0.0%65779 0.000 0.029 0.185
article write00:00:43.698 0.1%15880 0.174 2.752 62.896
artlog/artcncl00:00:00.000 0.0%37 0.000 0.000 0.000
artlog/artparse00:00:00.010 0.0%566 0.000 0.018 1.000
data move00:00:00.218 0.0%551355 0.000 0.000 0.002
hisgrep/artcncl00:00:00.074 0.0%37 0.000 2.000 17.667
hishave/artcncl00:00:00.002 0.0%37 0.000 0.054 1.000
hishave/artparse00:00:00.001 0.0%554 0.000 0.002 0.100
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:31.125 0.0%579317 0.002 0.054 0.893
history sync00:00:01.280 0.0%385 0.000 3.325 40.000
history write00:00:37.874 0.0%18996 0.602 1.994 14.667
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
hiswrite/artparse00:00:01.308 0.0%554 0.000 2.361 43.500
idle23:56:11.518 99.7%549169 79.624 156.913 513.844
nntp read00:00:02.223 0.0%555542 0.001 0.004 0.010
overview write00:01:43.396 0.1%15880 0.161 6.511 104.169
perl filter00:00:27.566 0.0%18984 1.119 1.452 2.767
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.262 0.0%31790 0.000 0.008 0.035
TOTAL: 24:01:02.62624:00:23.489100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.035 0.0%16057 0.000 0.002 0.035
article prepare00:00:00.001 0.0%14132 0.000 0.000 0.036
article read00:00:00.433 0.0%1704 0.000 0.254 11.000
backlog stats00:00:00.203 0.0%353435 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.247 0.0%275639 0.000 0.005 0.010
data write00:00:01.606 0.0%262432 0.002 0.006 0.012
idle23:54:02.089100.0%353435 135.424 243.445 712.419
status file00:00:00.327 0.0%293 0.000 1.116 6.111
TOTAL: 23:54:12.10523:54:05.941100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle67:03:19.574 99.7%1327 59.000181913.771458373.667
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.029 0.0%3165 0.000 0.009 0.250
readart00:00:00.474 0.0%27 0.000 17.556 55.000
TOTAL: 67:16:21.73867:03:20.077 99.7%----

Control commands to INND:

CommandNumber
flush 5
flushlogs 2
go 2
logmode 2
lowmark 1
mode 129
pause 3
paused 2
reload 2
reserve 2
TOTAL: 10 150

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1csiph-out.news.xs4all.nl 0 14180 3854 8588 1738 27%73:45:49
2newsfeeds.xmission.com 2 15196 3184 11984 28 20%25:04:29
3feeder.eternal-september.org 12 6393 2413 3901 79 37%27:09:12
4news-out.mixmin.net 3 17148 1389 15512 247 8%24:08:50
5newsfeed.fu-berlin.de 20 17440 970 16144 326 5%29:14:48
6csiph-out.news.weretis.net 2 27225 839 26201 185 3%41:28:30
7news-out.aioe.org 4 15765 833 14864 68 5%24:02:41
8newsfeed.neva.ru 137 15376 750 14408 218 4%71:49:56
9csiph-out.feeder.erje.net 1438 79928 718 78553 657 0%119:30:51
10csiph-out.feed.uzoreto.com 864 27581 307 27063 211 1%71:17:37
11news.fcku.it 9 1824 287 1533 4 15%22:28:53
12news-out.neodome.net 2 17584 197 17376 11 1%23:59:54
13nntp.pasdenom.info 2 10635 137 10473 25 1%23:59:09
14news.freedyn.net 2 18336 100 18155 81 0%23:59:56
15news.swapon.de 5 1591 63 1528 0 3%27:09:24
16csiph-out.news.tnetconsulting.net 2 11224 31 11169 24 0%18:31:37
17news.samoylyk.net 3 15350 8 15342 0 0%24:53:32
18news.bbs.nz 1 11501 3 11498 0 0%19:11:29
19xanadu-bbs.net 14 519 0 519 0 0%22:04:01
20localhost 26 0 0 0 0 0%00:00:02
TOTAL: 20 2548 324796 16083 304811 3902 4%713:50:40
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1csiph-out.news.xs4all.nl9.9 MB0.0 KB16.8 MB26.7 MB 37%4.9 KB
2newsfeeds.xmission.com8.9 MB0.0 KB343.9 KB9.2 MB 96%2.9 KB
3csiph-out.feeder.erje.net6.1 MB0.0 KB12.8 MB18.9 MB 32%14.1 KB
4feeder.eternal-september.org6.0 MB0.0 KB1.2 MB7.2 MB 83%2.9 KB
5news-out.mixmin.net3.1 MB0.0 KB535.5 KB3.6 MB 85%2.3 KB
6newsfeed.fu-berlin.de2.4 MB0.0 KB987.6 KB3.4 MB 71%2.7 KB
7csiph-out.news.weretis.net2.4 MB0.0 KB2.6 MB5.0 MB 48%5.0 KB
8news-out.aioe.org2.4 MB0.0 KB126.0 KB2.5 MB 95%2.8 KB
9newsfeed.neva.ru1.8 MB1.2 KB509.2 KB2.3 MB 78%2.5 KB
10csiph-out.feed.uzoreto.com1.0 MB0.0 KB7.8 MB8.8 MB 11%17.5 KB
11news-out.neodome.net543.2 KB1.3 KB19.0 KB563.5 KB 96%2.7 KB
12news.fcku.it452.9 KB0.0 KB4.0 KB456.9 KB 99%1.6 KB
13nntp.pasdenom.info291.1 KB0.0 KB69.1 KB360.2 KB 80%2.2 KB
14news.freedyn.net259.9 KB0.0 KB3.3 MB3.6 MB 7%20.2 KB
15news.swapon.de131.3 KB0.0 KB0.0 KB131.3 KB100%2.1 KB
16csiph-out.news.tnetconsulting.net102.2 KB0.0 KB669.2 KB771.4 KB 13%14.0 KB
17news.samoylyk.net60.1 KB0.0 KB0.0 KB60.1 KB100%7.5 KB
18news.bbs.nz4.6 KB0.0 KB0.0 KB4.6 KB100%1.5 KB
19xanadu-bbs.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
20localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 2045.8 MB2.5 KB47.7 MB93.5 MB 48%4.8 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jan 14 04:15:00 - 04:59:59 201 1.3% 0.07617.2 KB 1.3% 0.23
Jan 14 05:00:00 - 05:59:59 381 2.4% 0.112.1 MB 4.7% 0.60
Jan 14 06:00:00 - 06:59:59 299 1.9% 0.08873.0 KB 1.9% 0.24
Jan 14 07:00:00 - 07:59:59 460 2.9% 0.131.2 MB 2.6% 0.33
Jan 14 08:00:00 - 08:59:59 670 4.2% 0.192.0 MB 4.4% 0.56
Jan 14 09:00:00 - 09:59:59 771 4.9% 0.212.1 MB 4.8% 0.61
Jan 14 10:00:00 - 10:59:59 780 4.9% 0.222.1 MB 4.8% 0.61
Jan 14 11:00:00 - 11:59:59 896 5.6% 0.252.1 MB 4.8% 0.61
Jan 14 12:00:00 - 12:59:59 823 5.2% 0.232.2 MB 4.9% 0.63
Jan 14 13:00:00 - 13:59:59 820 5.2% 0.232.1 MB 4.6% 0.58
Jan 14 14:00:00 - 14:59:59 869 5.5% 0.242.8 MB 6.3% 0.80
Jan 14 15:00:00 - 15:59:59 1040 6.6% 0.293.0 MB 6.6% 0.85
Jan 14 16:00:00 - 16:59:59 1019 6.4% 0.282.7 MB 6.0% 0.77
Jan 14 17:00:00 - 17:59:59 874 5.5% 0.242.4 MB 5.3% 0.67
Jan 14 18:00:00 - 18:59:59 930 5.9% 0.262.6 MB 5.7% 0.73
Jan 14 19:00:00 - 19:59:59 876 5.5% 0.242.3 MB 5.0% 0.64
Jan 14 20:00:00 - 20:59:59 819 5.2% 0.232.2 MB 4.8% 0.61
Jan 14 21:00:00 - 21:59:59 665 4.2% 0.181.7 MB 3.9% 0.50
Jan 14 22:00:00 - 22:59:59 651 4.1% 0.182.0 MB 4.4% 0.56
Jan 14 23:00:00 - 23:59:59 545 3.4% 0.151.7 MB 3.7% 0.48
Jan 15 00:00:00 - 00:59:59 417 2.6% 0.121.1 MB 2.5% 0.32
Jan 15 01:00:00 - 01:59:59 361 2.3% 0.101.1 MB 2.4% 0.30
Jan 15 02:00:00 - 02:59:59 327 2.1% 0.091.1 MB 2.4% 0.31
Jan 15 03:00:00 - 03:59:59 300 1.9% 0.08827.6 KB 1.8% 0.23
Jan 15 04:00:00 - 04:15:00 77 0.5% 0.09200.6 KB 0.4% 0.22
TOTAL: 24:00:00 15871 100.0% 0.1844.9 MB 100.0% 0.53
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1csiph-out.news.xs4all.nl 1764 94 0 0 0 0 0 0 1670
2newsfeed.fu-berlin.de 627 15 0 0 0 0 0 0 612
3csiph-out.feeder.erje.net 475 87 0 0 0 0 0 0 388
4news-out.mixmin.net 252 24 0 0 0 0 0 0 228
5newsfeed.neva.ru 224 58 0 1 0 0 0 0 165
6csiph-out.feed.uzoreto.com 218 14 0 0 1 0 0 0 203
7csiph-out.news.weretis.net 190 92 0 0 0 0 0 0 98
8feeder.eternal-september.org 83 54 0 0 0 0 0 0 29
9news.freedyn.net 81 3 0 0 0 0 0 0 78
10news-out.aioe.org 70 59 0 0 0 0 0 0 11
11newsfeeds.xmission.com 46 24 0 0 0 0 0 0 22
12csiph-out.news.tnetconsulting.net 36 20 0 0 0 0 0 0 16
13news.swapon.de 35 0 0 0 0 0 0 0 35
14nntp.pasdenom.info 25 21 0 0 0 0 0 0 4
15news-out.neodome.net 11 2 0 1 0 0 0 0 8
16news.fcku.it 4 3 0 0 0 0 0 0 1
TOTAL: 16 4141 570 0 2 1 0 0 0 3568

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.computer.workshop 107
fr.misc.automobile.electrique 48
lada.talk 48
alt.binaries.pictures.purebeauty 46
alt.politics 42
mozilla.general 33
scinet.stats 26
scinet.general.chatter 25
scinet.bbs.software 19
alt.tv.comedy-central.daily-show 18
it-alt.sport.calcio.juventus 14
scinet.sysop 10
mozilla.support.thunderbird 9
alt.politics.usa 8
alt.job 8
alt.bbs.synchronet 7
at.jobs 7
scinet.bbs.mystic 6
spline.eisfair 5
scinet.gaming 4
TOTAL: 68 570

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

GroupsCount
uk.legal.moderated 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1765
EMP (phn path) 305
EMP (phl) 130
EMP (phn nph) 117
Binary Payload 64
Too many newsgroups 52
0 43
Binary: misplaced binary 26
Scoring filter 9
HTML Multipart 8
EMP (fsl) 5
U2 violation - invalid distribution 3
TOTAL: 12 2527

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
csiph-out.feeder.erje.net 45
TOTAL: 1 45
Huge articles
csiph-out.feeder.erje.net 212
csiph-out.news.xs4all.nl 177
csiph-out.feed.uzoreto.com 83
news.freedyn.net 36
csiph-out.news.weretis.net 19
csiph-out.news.tnetconsulting.net 16
newsfeeds.xmission.com 4
newsfeed.fu-berlin.de 3
feeder.eternal-september.org 3
news-out.mixmin.net 2
TOTAL: 10 555
Including strange strings
newsfeed.fu-berlin.de 349
news.swapon.de 35
csiph-out.news.xs4all.nl 29
newsfeeds.xmission.com 18
csiph-out.news.weretis.net 14
csiph-out.feeder.erje.net 8
csiph-out.feed.uzoreto.com 7
newsfeed.neva.ru 6
news-out.mixmin.net 5
feeder.eternal-september.org 3
TOTAL: 11 475
No colon-space in header
csiph-out.news.xs4all.nl 8
csiph-out.feeder.erje.net 2
csiph-out.feed.uzoreto.com 2
TOTAL: 3 12
TOTAL: 4 1087

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1xanadu-bbs 18326 939 14932 0 0 0 5%23:59:56
2fu-berlin.de 14737 573 14163 0 0 0 3%23:59:41
3news.samoylyk.net 16511 569 15283 9 0 101 3%23:59:56
4news.bbs.nz 29733 434 14829 19 0 0 1%23:59:38
5erje 17060 135 14598 276 0 0 0%23:59:56
6tnet 16419 116 15168 1 0 0 0%23:59:45
7xmission 14450 83 12031 6 0 0 0%23:59:56
8newsfeed.xs4all.nl 12729 76 11878 0 0 93 0%23:59:56
9news.uzoreto.com 15146 11 14795 45 0 0 0%23:59:56
10usenet.pasdenom.info 15747 9 15096 39 0 15 0%23:59:56
11news.freedyn.net 14307 7 13875 70 0 0 0%23:59:56
12neodome.net 16125 7 15637 0 0 0 0%23:59:56
13neva.ru 14212 5 13984 101 0 0 0%23:59:56
14news.mixmin.net 14020 3 13636 0 0 0 0%23:59:28
15aioe 13521 1 12896 0 0 0 0%23:59:56
16news.swapon.de 1518 1 1504 0 0 0 0%23:52:42
17eternal-september 0 0 0 0 0 12775 0%23:59:56
18weretis.net 14659 0 14286 2 0 0 0%23:59:54
19news.fcku.it 1477 0 1472 0 0 0 0%21:20:34
TOTAL: 19 260697 2969 230063 568 0 12984 1%453:10:54
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1xanadu-bbs6.6 MB0.0 KB6.6 MB0.1 KB/s7.2 KB23:59:56
2fu-berlin.de5.7 MB0.0 KB5.7 MB0.1 KB/s10.2 KB23:59:41
3news.samoylyk.net5.7 MB78.6 KB5.7 MB0.1 KB/s10.2 KB23:59:56
4news.bbs.nz1.0 MB63.7 KB1.1 MB0.0 KB/s2.5 KB23:59:38
5erje300.9 KB964.0 KB1.2 MB0.0 KB/s3.1 KB23:59:56
6tnet223.1 KB1.2 KB224.3 KB0.0 KB/s1.9 KB23:59:45
7xmission184.1 KB10.3 KB194.4 KB0.0 KB/s2.2 KB23:59:56
8newsfeed.xs4all.nl182.4 KB0.0 KB182.4 KB0.0 KB/s2.4 KB23:59:56
9news.uzoreto.com72.1 KB230.6 KB302.7 KB0.0 KB/s5.4 KB23:59:56
10usenet.pasdenom.info69.9 KB65.2 KB135.1 KB0.0 KB/s2.8 KB23:59:56
11neodome.net67.9 KB0.0 KB67.9 KB0.0 KB/s9.7 KB23:59:56
12news.freedyn.net33.0 KB1.2 MB1.2 MB0.0 KB/s15.7 KB23:59:56
13neva.ru5.5 KB239.3 KB244.9 KB0.0 KB/s2.3 KB23:59:56
14news.mixmin.net5.1 KB0.0 KB5.1 KB0.0 KB/s1.7 KB23:59:28
15news.swapon.de2.8 KB0.0 KB2.8 KB0.0 KB/s2.8 KB23:52:42
16aioe1.4 KB0.0 KB1.4 KB0.0 KB/s1.4 KB23:59:56
17eternal-september0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:56
18weretis.net0.0 KB7.3 KB7.3 KB0.0 KB/s3.7 KB23:59:54
19news.fcku.it0.0 KB0.0 KB0.0 KB0.0 KB/s021:20:34
TOTAL: 1920.1 MB2.8 MB22.9 MB0.0 KB/s6.6 KB453:10:54
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1208.72.53.153 5 1225.5 KB 15 0 002:32:02
2a131033.upc-a.chello.nl 94 1125.4 KB 10 0 027:47:26
391.187.33.87 226 48.3 KB 5 0 031:48:48
TOTAL: 3 325 2759.1 KB 30 0 067:16:19

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 235 1633.8 KB 20 0 035:21:03
2*.upc-a.chello.nl 94 1125.4 KB 10 0 027:47:26
3*.range86-138.btcentralplus.com 4 00.0 KB 0 0 000:07:43
4*.security.ipip.net 1 00.0 KB 0 0 000:00:01
5*.net.upcbroadband.cz 48 00.0 KB 0 0 004:00:03
TOTAL: 5 382 2759.1 KB 30 0 067:16:19

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
91.187.33.87 14.614 3.653 0.00031:48:48
a131033.upc-a.chello.nl 6.498 1.433 0.00027:47:26
ip-78-102-198-192.net.upcbroadband.cz 2.992 0.763 0.00004:00:03
208.72.53.153 0.512 0.104 0.00002:32:02
172.83.100.199 0.202 0.040 0.00001:00:10
host86-138-214-35.range86-138.btcentralplus.com 0.270 0.054 0.00000:07:43
185.100.87.249 0.124 0.023 0.00000:00:02
scan-141.security.ipip.net 0.054 0.023 0.00000:00:01
TOTAL: 8 25.266 6.093 0.00067:16:19

Curious NNRP server explorers [Top 20]:

SystemConn
ip-78-102-198-192.net.upcbroadband.cz 48
host86-138-214-35.range86-138.btcentralplus.com 4
172.83.100.199 2
185.100.87.249 2
scan-141.security.ipip.net 1
TOTAL: 5 57

NNRP unrecognized commands (by host):

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

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

SystemConnPeer
208.72.53.153 5 0
172.83.100.199 2 0
TOTAL: 2 7 0

Newsgroup request counts (by category):

CategoryCountPct
1comp 31100.0%
TOTAL: 1 31100.0%

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

NewsgroupCount
1comp.sys.ibm.ps2.hardware 31
TOTAL: 1 31