Daily Usenet report for csiph.com

Dec 18 04:15:00 -- Dec 19 04:15:00

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Dec 18 09:39:04 csiph nnrpd[59409]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Dec 18 09:39:04 csiph nnrpd[59409]: 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]
Dec 18 18:37:34 csiph nnrpd[87656]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Dec 18 18:37:34 csiph nnrpd[87656]: 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 18576 47.7%4.7 MB 62.7%
innd 15955 41.0%2.1 MB 28.0%
innfeed 3637 9.3%632.8 KB 8.3%
nnrpd 718 1.8%78.5 KB 1.0%
nocem 29 0.1%3.4 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 6 38916 100.0%7.5 MB100.0%

History cache:

ReasonCount%Count
Positive hits 293622 53.5%
Negative hits 232733 42.4%
Do not exist 17934 3.3%
Cache misses 4330 0.8%
TOTAL: 4 548619 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.160 0.0%27 0.000 5.926 12.714
article cleanup00:00:00.116 0.0%17450 0.000 0.007 0.033
article logging00:00:00.267 0.0%18022 0.000 0.015 0.145
article parse00:00:00.993 0.0%69910 0.000 0.014 0.031
article write00:00:07.308 0.0%14601 0.108 0.501 14.349
artlog/artcncl00:00:00.001 0.0%27 0.000 0.037 0.143
artlog/artparse00:00:00.007 0.0%527 0.000 0.013 0.333
data move00:00:00.154 0.0%533537 0.000 0.000 0.001
hisgrep/artcncl00:00:00.005 0.0%27 0.000 0.185 0.429
hishave/artcncl00:00:00.003 0.0%27 0.000 0.111 0.333
hishave/artparse00:00:00.002 0.0%522 0.000 0.004 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:03.407 0.0%549434 0.001 0.006 0.030
history sync00:00:00.773 0.0%372 0.000 2.078 7.333
history write00:00:07.960 0.0%17441 0.036 0.456 4.438
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
hiswrite/artparse00:00:00.291 0.0%522 0.000 0.557 2.250
idle23:59:21.276 99.9%540120 86.166 159.893 505.830
nntp read00:00:02.059 0.0%540136 0.002 0.004 0.008
overview write00:00:24.981 0.0%14601 0.100 1.711 76.821
perl filter00:00:24.987 0.0%17436 1.117 1.433 1.984
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.250 0.0%29231 0.000 0.009 0.035
TOTAL: 24:01:13.04824:00:35.000100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.024 0.0%14753 0.000 0.002 0.030
article prepare00:00:00.001 0.0%10460 0.000 0.000 0.009
article read00:00:00.217 0.0%1350 0.000 0.161 11.250
backlog stats00:00:00.186 0.0%321077 0.000 0.001 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.176 0.0%250982 0.000 0.005 0.012
data write00:00:01.605 0.0%238424 0.000 0.007 0.014
idle23:54:12.572100.0%321077 140.326 268.012 742.021
status file00:00:00.220 0.0%346 0.000 0.636 2.286
TOTAL: 23:54:21.78623:54:16.001100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.287 0.0%822 0.349 0.349 0.349
idle42:32:40.666 99.6%25170 140.000 6085.048600170.667
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:03.278 0.0%155453 0.000 0.021 0.167
readart00:05:25.605 0.2%18766 0.176 17.351 20.489
TOTAL: 42:41:53.72242:38:09.836 99.9%----

Control commands to INND:

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1news-out.aioe.org 2 13936 3247 10541 148 23%24:09:12
2feeder.eternal-september.org 14 5884 2477 3356 51 42%26:14:58
3newsfeeds.xmission.com 2 14411 2383 11979 49 16%25:43:10
4csiph-out.news.xs4all.nl 557 16954 1727 14105 1122 10%67:55:53
5newsfeed.fu-berlin.de 11 15426 865 14325 236 5%25:46:54
6news-out.mixmin.net 2 15918 804 14848 266 5%23:59:34
7csiph-out.news.weretis.net 2 30775 768 29558 449 2%47:59:28
8csiph-out.feeder.erje.net 1440 76710 687 75754 269 0%118:46:58
9newsfeed.neva.ru 150 13220 596 12402 222 4%71:52:49
10csiph-out.feed.uzoreto.com 864 27219 368 26300 551 1%71:16:58
11news-out.neodome.net 2 16416 273 16126 17 1%23:59:48
12news.fcku.it 17 1585 210 1374 1 13%21:39:30
13nntp.pasdenom.info 0 7280 104 7166 10 1%17:05:53
14news.freedyn.net 1 17208 82 17006 120 0%23:59:57
15news.swapon.de 6 1568 74 1494 0 4%25:17:42
16csiph-out.news.tnetconsulting.net 1 14923 51 14822 50 0%23:59:55
17news.bbs.nz 2 14540 1 14539 0 0%23:59:42
18xanadu-bbs.net 35 352 1 351 0 0%20:27:16
19news.samoylyk.net 4 14227 1 14225 1 0%25:53:27
20localhost 26 0 0 0 0 0%00:00:00
TOTAL: 20 3138 318552 14719 300271 3562 4%710:09:04
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news-out.aioe.org8.1 MB0.0 KB481.6 KB8.6 MB 94%2.6 KB
2newsfeeds.xmission.com7.8 MB1.7 KB2.6 MB10.4 MB 74%4.4 KB
3feeder.eternal-september.org6.4 MB0.0 KB504.1 KB6.9 MB 92%2.8 KB
4csiph-out.feeder.erje.net5.9 MB11.5 KB2.3 MB8.3 MB 71%8.9 KB
5csiph-out.news.xs4all.nl4.4 MB0.0 KB4.8 MB9.2 MB 48%3.3 KB
6news-out.mixmin.net2.2 MB1.7 KB580.2 KB2.7 MB 79%2.6 KB
7csiph-out.news.weretis.net2.2 MB3.6 KB3.4 MB5.6 MB 39%4.7 KB
8newsfeed.fu-berlin.de2.1 MB0.0 KB617.1 KB2.7 MB 77%2.5 KB
9csiph-out.feed.uzoreto.com1.6 MB0.0 KB54.4 MB55.9 MB 2%62.3 KB
10newsfeed.neva.ru1.4 MB2.6 KB687.5 KB2.1 MB 67%2.6 KB
11news-out.neodome.net878.7 KB1.7 KB144.4 KB1.0 MB 85%3.5 KB
12news.freedyn.net474.8 KB1.8 KB2.9 MB3.3 MB 13%16.9 KB
13news.fcku.it330.8 KB0.0 KB91.8 KB422.6 KB 78%2.0 KB
14nntp.pasdenom.info238.2 KB0.0 KB15.4 KB253.6 KB 93%2.2 KB
15csiph-out.news.tnetconsulting.net188.9 KB0.0 KB318.1 KB507.0 KB 37%5.0 KB
16news.swapon.de174.5 KB0.0 KB0.0 KB174.5 KB100%2.4 KB
17xanadu-bbs.net16.9 KB0.0 KB0.0 KB16.9 KB100%16.9 KB
18news.bbs.nz2.4 KB0.0 KB0.0 KB2.4 KB100%2.4 KB
19news.samoylyk.net1.5 KB0.0 KB1.1 KB2.5 KB 58%1.3 KB
20localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 2044.3 MB24.6 KB73.7 MB118.0 MB 37%6.6 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Dec 18 04:15:00 - 04:59:59 184 1.3% 0.07535.3 KB 1.2% 0.20
Dec 18 05:00:00 - 05:59:59 379 2.6% 0.112.5 MB 5.6% 0.70
Dec 18 06:00:00 - 06:59:59 330 2.3% 0.091012.9 KB 2.3% 0.28
Dec 18 07:00:00 - 07:59:59 422 2.9% 0.121.1 MB 2.5% 0.31
Dec 18 08:00:00 - 08:59:59 613 4.2% 0.171.6 MB 3.7% 0.46
Dec 18 09:00:00 - 09:59:59 770 5.3% 0.212.3 MB 5.3% 0.66
Dec 18 10:00:00 - 10:59:59 760 5.2% 0.212.1 MB 4.7% 0.59
Dec 18 11:00:00 - 11:59:59 820 5.6% 0.231.9 MB 4.3% 0.54
Dec 18 12:00:00 - 12:59:59 696 4.8% 0.191.9 MB 4.3% 0.54
Dec 18 13:00:00 - 13:59:59 781 5.4% 0.222.0 MB 4.6% 0.57
Dec 18 14:00:00 - 14:59:59 920 6.3% 0.263.5 MB 8.0% 1.00
Dec 18 15:00:00 - 15:59:59 977 6.7% 0.273.5 MB 7.9% 0.98
Dec 18 16:00:00 - 16:59:59 941 6.4% 0.262.8 MB 6.4% 0.79
Dec 18 17:00:00 - 17:59:59 879 6.0% 0.242.4 MB 5.5% 0.68
Dec 18 18:00:00 - 18:59:59 834 5.7% 0.232.2 MB 5.1% 0.63
Dec 18 19:00:00 - 19:59:59 804 5.5% 0.222.4 MB 5.4% 0.67
Dec 18 20:00:00 - 20:59:59 618 4.2% 0.171.6 MB 3.7% 0.47
Dec 18 21:00:00 - 21:59:59 545 3.7% 0.151.7 MB 3.9% 0.48
Dec 18 22:00:00 - 22:59:59 505 3.5% 0.141.4 MB 3.1% 0.38
Dec 18 23:00:00 - 23:59:59 410 2.8% 0.111.2 MB 2.7% 0.34
Dec 19 00:00:00 - 00:59:59 370 2.5% 0.101.2 MB 2.8% 0.35
Dec 19 01:00:00 - 01:59:59 305 2.1% 0.08975.0 KB 2.2% 0.27
Dec 19 02:00:00 - 02:59:59 307 2.1% 0.09894.4 KB 2.0% 0.25
Dec 19 03:00:00 - 03:59:59 347 2.4% 0.101.0 MB 2.4% 0.30
Dec 19 04:00:00 - 04:15:00 81 0.6% 0.09280.8 KB 0.6% 0.31
TOTAL: 24:00:00 14598 100.0% 0.1743.9 MB 100.0% 0.52
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1csiph-out.news.xs4all.nl 1132 39 0 0 0 0 0 0 1093
2newsfeed.fu-berlin.de 653 48 0 0 0 0 0 0 605
3csiph-out.feed.uzoreto.com 555 41 0 0 2 0 0 0 512
4csiph-out.news.weretis.net 394 93 0 2 0 0 0 0 299
5news-out.mixmin.net 268 1 0 1 0 0 0 0 266
6news-out.aioe.org 245 114 0 0 0 0 0 0 131
7newsfeed.neva.ru 223 82 0 2 0 0 0 0 139
8csiph-out.feeder.erje.net 159 68 0 6 0 0 0 0 85
9news.freedyn.net 116 0 0 1 0 0 0 0 115
10feeder.eternal-september.org 57 19 0 0 0 0 0 0 38
11newsfeeds.xmission.com 51 6 0 1 0 0 0 0 44
12news.swapon.de 42 0 0 0 0 0 0 0 42
13csiph-out.news.tnetconsulting.net 26 24 0 0 0 0 0 0 2
14news-out.neodome.net 18 4 0 1 0 0 0 0 13
15nntp.pasdenom.info 10 0 0 0 0 0 0 0 10
16news.fcku.it 1 0 0 0 0 0 0 0 1
17news.samoylyk.net 1 0 0 0 0 0 0 0 1
TOTAL: 17 3951 539 0 14 2 0 0 0 3396

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.computer.workshop 99
lada.talk 51
alt.binaries.pictures.purebeauty 41
spline.eisfair.dev 36
alt.tv.comedy-central.daily-show 34
scinet.stats 30
mozilla.support.thunderbird 27
alt.bbs.synchronet 26
alt.survival 15
at.jobs 11
alt.politics 11
it-alt.sport.calcio.juventus 10
lada.auto 10
lada.movies 10
mozilla.support.seamonkey 8
alt.math.undergrad 8
spline.fli4l.geschnatter 7
alt.job 7
mozilla.support.firefox 6
att.jobs 6
TOTAL: 77 539

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

GroupsCount
uk.legal.moderated 2
TOTAL: 1 2

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1460
EMP (phn path) 492
EMP (phn nph) 135
EMP (phl) 98
0 44
Too many newsgroups 32
HTML Multipart 18
Too many newsgroups (meow) 7
HTML post 4
Binary: misplaced binary 3
Binary Payload 2
Scoring filter 1
TOTAL: 12 2296

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
csiph-out.feeder.erje.net 45
csiph-out.news.xs4all.nl 1
csiph-out.feed.uzoreto.com 1
TOTAL: 3 47
Huge articles
csiph-out.feed.uzoreto.com 344
csiph-out.feeder.erje.net 42
newsfeeds.xmission.com 41
csiph-out.news.xs4all.nl 36
news.freedyn.net 32
csiph-out.news.weretis.net 20
feeder.eternal-september.org 5
news-out.mixmin.net 3
csiph-out.news.tnetconsulting.net 2
news.fcku.it 1
TOTAL: 11 527
Including strange strings
newsfeed.fu-berlin.de 386
news-out.aioe.org 105
news.swapon.de 42
csiph-out.news.weretis.net 13
csiph-out.news.xs4all.nl 10
csiph-out.feeder.erje.net 6
news-out.mixmin.net 4
newsfeeds.xmission.com 3
csiph-out.feed.uzoreto.com 3
newsfeed.neva.ru 1
TOTAL: 10 573
No colon-space in header
csiph-out.news.xs4all.nl 4
csiph-out.feed.uzoreto.com 1
TOTAL: 2 5
TOTAL: 4 1152

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1xanadu-bbs 17389 778 13809 9 0 0 4%23:59:56
2news.samoylyk.net 14796 469 14067 5 0 0 3%23:50:09
3fu-berlin.de 13541 467 13074 0 0 0 3%23:59:35
4news.bbs.nz 27711 311 13757 23 0 0 1%23:58:58
5newsfeed.xs4all.nl 13845 145 12607 8 0 190 1%23:54:32
6xmission 14790 78 11677 2 0 0 0%23:59:34
7tnet 15299 36 14030 1 0 0 0%23:52:52
8erje 14390 23 13638 82 0 0 0%23:59:56
9neva.ru 13255 15 13102 43 0 0 0%23:59:53
10neodome.net 14540 8 14288 0 0 0 0%23:54:36
11news.freedyn.net 13978 7 13755 57 0 0 0%23:59:56
12news.uzoreto.com 13834 6 13651 37 0 0 0%23:50:08
13usenet.pasdenom.info 10291 3 10081 1 0 3911 0%23:59:56
14news.mixmin.net 13166 3 13020 0 0 0 0%23:59:56
15aioe 10252 2 10010 0 0 0 0%23:50:09
16weretis.net 13563 1 13226 1 0 0 0%23:53:49
17news.fcku.it 1314 0 1314 0 0 0 0%20:54:50
18news.swapon.de 1444 0 1438 0 0 0 0%23:33:28
19eternal-september 0 0 0 0 0 11457 0%23:59:56
TOTAL: 19 237398 2352 210544 269 0 15558 0%451:32:09
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1xanadu-bbs6.1 MB29.6 KB6.1 MB0.1 KB/s7.9 KB23:59:56
2fu-berlin.de5.2 MB0.0 KB5.2 MB0.1 KB/s11.4 KB23:59:35
3news.samoylyk.net5.2 MB66.8 KB5.2 MB0.1 KB/s11.3 KB23:50:09
4news.bbs.nz867.7 KB107.7 KB975.4 KB0.0 KB/s2.9 KB23:58:58
5newsfeed.xs4all.nl434.1 KB19.6 KB453.7 KB0.0 KB/s3.0 KB23:54:32
6xmission198.3 KB6.5 KB204.8 KB0.0 KB/s2.6 KB23:59:34
7tnet92.3 KB1.2 KB93.6 KB0.0 KB/s2.5 KB23:52:52
8erje41.6 KB347.5 KB389.1 KB0.0 KB/s3.7 KB23:59:56
9news.freedyn.net40.1 KB1.1 MB1.1 MB0.0 KB/s17.5 KB23:59:56
10neva.ru27.9 KB111.2 KB139.1 KB0.0 KB/s2.4 KB23:59:53
11news.mixmin.net13.4 KB0.0 KB13.4 KB0.0 KB/s4.5 KB23:59:56
12neodome.net12.8 KB0.0 KB12.8 KB0.0 KB/s1.6 KB23:54:36
13news.uzoreto.com12.7 KB117.0 KB129.7 KB0.0 KB/s3.0 KB23:50:08
14usenet.pasdenom.info9.8 KB1.2 KB10.9 KB0.0 KB/s2.7 KB23:59:56
15weretis.net5.6 KB1.2 KB6.8 KB0.0 KB/s3.4 KB23:53:49
16aioe2.7 KB0.0 KB2.7 KB0.0 KB/s1.4 KB23:50:09
17news.fcku.it0.0 KB0.0 KB0.0 KB0.0 KB/s020:54:50
18news.swapon.de0.0 KB0.0 KB0.0 KB0.0 KB/s023:33:28
19eternal-september0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:56
TOTAL: 1918.2 MB1.8 MB20.0 MB0.0 KB/s7.8 KB451:32:09
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1212.90.61.180 2 11113.1 MB 9 0 013:13:32
291.187.33.87 52 2564.4 KB 30 0 017:09:12
TOTAL: 2 54 11363.2 MB 39 0 042:41:52

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 58 11363.2 MB 39 0 030:22:45
2*.net.upcbroadband.cz 21 00.0 KB 0 0 006:58:12
3*.range31-50.btcentralplus.com 2 00.0 KB 0 0 000:00:23
4*.upc-a.chello.nl 33 00.0 KB 0 0 005:20:31
TOTAL: 4 114 11363.2 MB 39 0 042:41:52

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
91.187.33.87 3.306 0.762 0.00017:09:12
212.90.61.180 3.241 10.677 0.00013:13:32
ip-78-102-198-192.net.upcbroadband.cz 1.304 0.307 0.00006:58:12
a131033.upc-a.chello.nl 2.032 0.501 0.00005:20:31
host31-50-220-99.range31-50.btcentralplus.com 0.143 0.016 0.00000:00:23
165.227.137.178 0.127 0.023 0.00000:00:00
104.248.39.218 0.102 0.052 0.00000:00:00
TOTAL: 7 10.255 12.338 0.00042:41:52

Curious NNRP server explorers [Top 20]:

SystemConn
a131033.upc-a.chello.nl 33
ip-78-102-198-192.net.upcbroadband.cz 21
host31-50-220-99.range31-50.btcentralplus.com 2
104.248.39.218 2
165.227.137.178 2
TOTAL: 5 60

NNRP client timeouts [Top 20]:

SystemConnPeer
91.187.33.87 3 0
ip-78-102-198-192.net.upcbroadband.cz 2 0
TOTAL: 2 5 0

Newsgroup request counts (by category):

CategoryCountPct
1alt 1111 97.4%
2comp 30 2.6%
TOTAL: 2 1141100.0%

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

NewsgroupCount
1alt.appalachian 827
2alt.anonymous.messages 235
3comp.sys.ibm.ps2.hardware 30
4alt.apache.configuration 28
5alt.apocalypse 10
6alt.anything 7
7alt.antichristnet 2
8alt.aol-sucks 1
9alt.antiques.delaware.joe 1
TOTAL: 9 1141