Daily Usenet report for csiph.com

Jan 17 04:15:00 -- Jan 18 04:15:00

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Jan 17 12:18:08 csiph nnrpd[66327]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Jan 17 12:18:08 csiph nnrpd[66327]: 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 17 23:48:03 csiph nnrpd[2323]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Jan 17 23:48:03 csiph nnrpd[2323]: 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 18291 48.0%4.6 MB 62.9%
innd 14048 36.9%1.8 MB 25.2%
innfeed 3656 9.6%658.8 KB 8.9%
nnrpd 2053 5.4%222.8 KB 3.0%
nocem 39 0.1%4.4 KB 0.1%
controlchan 2 0.0%0.1 KB 0.0%
TOTAL: 6 38089 100.0%7.2 MB100.0%

History cache:

ReasonCount%Count
Positive hits 278260 52.2%
Negative hits 236476 44.3%
Do not exist 18345 3.4%
Cache misses 241 0.0%
TOTAL: 4 533322 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.238 0.0%18 0.000 13.222 42.000
article cleanup00:00:00.097 0.0%17630 0.000 0.006 0.040
article logging00:00:00.307 0.0%18045 0.000 0.017 0.294
article parse00:00:00.702 0.0%57224 0.000 0.012 0.161
article write00:00:11.127 0.0%14260 0.115 0.780 32.071
artlog/artcncl00:00:00.000 0.0%18 0.000 0.000 0.000
artlog/artparse00:00:00.003 0.0%230 0.000 0.013 0.500
data move00:00:00.186 0.0%503061 0.000 0.000 0.002
hisgrep/artcncl00:00:00.020 0.0%17 0.000 1.176 2.286
hishave/artcncl00:00:00.002 0.0%18 0.000 0.111 0.286
hishave/artparse00:00:00.000 0.0%224 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:07.113 0.0%533381 0.000 0.013 0.445
history sync00:00:00.909 0.0%378 0.000 2.405 17.500
history write00:00:12.170 0.0%17633 0.000 0.690 10.188
hiswrite/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
hiswrite/artparse00:00:00.234 0.0%224 0.000 1.045 8.000
idle23:58:48.575 99.8%505427 83.150 170.803 761.265
nntp read00:00:02.069 0.0%506525 0.001 0.004 0.008
overview write00:00:37.304 0.0%14260 0.102 2.616 47.197
perl filter00:00:25.396 0.0%17627 1.061 1.441 2.662
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.228 0.0%28558 0.000 0.008 0.049
TOTAL: 24:01:07.09124:00:26.680100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.027 0.0%14244 0.000 0.002 0.045
article prepare00:00:00.002 0.0%10100 0.000 0.000 0.021
article read00:00:00.292 0.0%1131 0.000 0.258 22.000
backlog stats00:00:00.221 0.0%310093 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.114 0.0%244534 0.000 0.005 0.010
data write00:00:01.517 0.0%232796 0.002 0.007 0.016
idle23:44:20.075100.0%310093 152.762 275.595 1166.340
status file00:00:00.338 0.0%323 0.000 1.046 5.321
TOTAL: 23:44:34.47323:44:23.586100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle91:34:31.219 98.7%263341 614.962 1251.880600192.667
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:08.054 0.0%1096354 0.000 0.007 0.167
readart00:55:14.035 1.0%251341 8.800 13.185 42.000
TOTAL: 92:45:23.61092:29:53.308 99.7%----

Control commands to INND:

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1csiph-out.news.xs4all.nl 0 8168 3789 2511 1868 46%73:39:37
2newsfeeds.xmission.com 2 13955 2214 11729 12 15%25:34:31
3feeder.eternal-september.org 15 4900 2081 2730 89 42%28:04:21
4news-out.aioe.org 4 14259 1417 12790 52 9%24:24:04
5news-out.mixmin.net 1 15819 910 14672 237 5%23:59:33
6csiph-out.news.weretis.net 4 28455 834 27392 229 2%47:59:36
7csiph-out.feeder.erje.net 1440 73574 751 71941 882 1%118:47:57
8newsfeed.fu-berlin.de 11 14137 740 13181 216 5%23:49:16
9newsfeed.neva.ru 141 11854 499 11058 297 4%71:43:44
10news-out.neodome.net 2 16046 353 15679 14 2%23:59:47
11csiph-out.feed.uzoreto.com 864 23875 340 23314 221 1%71:15:50
12news.fcku.it 16 1253 149 1101 3 11%21:47:27
13nntp.pasdenom.info 1 9295 120 9170 5 1%23:59:56
14news.freedyn.net 1 17025 87 16856 82 0%23:59:56
15csiph-out.news.tnetconsulting.net 1 13467 69 13393 5 0%23:59:57
16news.swapon.de 9 1445 52 1393 0 3%22:44:56
17news.samoylyk.net 2 13785 6 13779 0 0%24:54:34
18news.bbs.nz 1 13238 4 13234 0 0%23:59:53
19localhost 26 0 0 0 0 0%00:00:03
20xanadu-bbs.net 37 372 0 372 0 0%21:01:46
TOTAL: 20 2578 294922 14415 276295 4212 4%719:46:44
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1csiph-out.news.xs4all.nl10.4 MB0.0 KB10.4 MB20.8 MB 49%3.8 KB
2newsfeeds.xmission.com6.5 MB0.0 KB264.1 KB6.8 MB 96%3.1 KB
3feeder.eternal-september.org6.0 MB0.0 KB451.5 KB6.4 MB 93%3.0 KB
4csiph-out.feeder.erje.net5.5 MB0.0 KB4.8 MB10.4 MB 53%6.5 KB
5news-out.aioe.org3.4 MB0.0 KB89.0 KB3.4 MB 97%2.4 KB
6news-out.mixmin.net2.5 MB3.1 KB631.9 KB3.2 MB 80%2.8 KB
7csiph-out.news.weretis.net2.4 MB0.0 KB2.0 MB4.4 MB 54%4.2 KB
8newsfeed.fu-berlin.de1.6 MB0.0 KB454.6 KB2.1 MB 78%2.2 KB
9csiph-out.feed.uzoreto.com1.4 MB0.0 KB4.5 MB5.9 MB 23%10.7 KB
10newsfeed.neva.ru1.3 MB61.3 KB1.8 MB3.1 MB 40%4.0 KB
11news-out.neodome.net1000.5 KB0.0 KB109.7 KB1.1 MB 90%3.0 KB
12news.freedyn.net407.2 KB0.0 KB2.8 MB3.2 MB 12%19.5 KB
13nntp.pasdenom.info250.8 KB0.0 KB6.6 KB257.4 KB 97%2.1 KB
14csiph-out.news.tnetconsulting.net239.4 KB0.0 KB194.6 KB434.1 KB 55%5.9 KB
15news.fcku.it223.0 KB0.0 KB3.5 KB226.4 KB 98%1.5 KB
16news.swapon.de131.6 KB0.0 KB0.0 KB131.6 KB100%2.5 KB
17news.samoylyk.net19.2 KB0.0 KB0.0 KB19.2 KB100%3.2 KB
18news.bbs.nz4.4 KB0.0 KB0.0 KB4.4 KB100%1.1 KB
19localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
20xanadu-bbs.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 2043.3 MB64.4 KB28.5 MB71.9 MB 60%3.9 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jan 17 04:15:00 - 04:59:59 209 1.5% 0.08743.6 KB 1.7% 0.28
Jan 17 05:00:00 - 05:59:59 304 2.1% 0.08878.1 KB 2.0% 0.24
Jan 17 06:00:00 - 06:59:59 202 1.4% 0.06607.1 KB 1.4% 0.17
Jan 17 07:00:00 - 07:59:59 359 2.5% 0.101.5 MB 3.5% 0.43
Jan 17 08:00:00 - 08:59:59 454 3.2% 0.131.6 MB 3.6% 0.44
Jan 17 09:00:00 - 09:59:59 691 4.8% 0.192.2 MB 5.2% 0.64
Jan 17 10:00:00 - 10:59:59 733 5.1% 0.202.2 MB 5.1% 0.62
Jan 17 11:00:00 - 11:59:59 703 4.9% 0.202.0 MB 4.6% 0.56
Jan 17 12:00:00 - 12:59:59 728 5.1% 0.202.0 MB 4.8% 0.58
Jan 17 13:00:00 - 13:59:59 818 5.7% 0.232.3 MB 5.4% 0.66
Jan 17 14:00:00 - 14:59:59 769 5.4% 0.212.1 MB 4.9% 0.60
Jan 17 15:00:00 - 15:59:59 919 6.4% 0.262.4 MB 5.6% 0.68
Jan 17 16:00:00 - 16:59:59 874 6.1% 0.242.6 MB 6.0% 0.73
Jan 17 17:00:00 - 17:59:59 827 5.8% 0.232.4 MB 5.5% 0.67
Jan 17 18:00:00 - 18:59:59 715 5.0% 0.201.9 MB 4.3% 0.53
Jan 17 19:00:00 - 19:59:59 742 5.2% 0.212.1 MB 5.0% 0.61
Jan 17 20:00:00 - 20:59:59 760 5.3% 0.212.3 MB 5.5% 0.67
Jan 17 21:00:00 - 21:59:59 721 5.1% 0.202.1 MB 4.8% 0.58
Jan 17 22:00:00 - 22:59:59 662 4.6% 0.182.0 MB 4.6% 0.56
Jan 17 23:00:00 - 23:59:59 627 4.4% 0.172.1 MB 5.0% 0.61
Jan 18 00:00:00 - 00:59:59 474 3.3% 0.131.5 MB 3.5% 0.43
Jan 18 01:00:00 - 01:59:59 364 2.6% 0.101.5 MB 3.4% 0.42
Jan 18 02:00:00 - 02:59:59 287 2.0% 0.081020.3 KB 2.3% 0.28
Jan 18 03:00:00 - 03:59:59 268 1.9% 0.07808.3 KB 1.8% 0.22
Jan 18 04:00:00 - 04:15:00 49 0.3% 0.05143.1 KB 0.3% 0.16
TOTAL: 24:00:00 14259 100.0% 0.1742.8 MB 100.0% 0.51
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1csiph-out.news.xs4all.nl 1905 63 0 0 0 0 0 0 1842
2newsfeed.fu-berlin.de 546 32 0 0 0 0 0 0 514
3newsfeed.neva.ru 297 68 0 2 0 0 0 0 227
4csiph-out.feeder.erje.net 281 66 0 0 0 0 0 0 215
5news-out.mixmin.net 240 8 0 1 0 0 0 0 231
6csiph-out.feed.uzoreto.com 223 10 0 0 1 0 0 0 212
7csiph-out.news.weretis.net 208 74 0 0 0 0 0 0 134
8feeder.eternal-september.org 91 63 0 0 0 0 0 0 28
9news.freedyn.net 82 0 0 0 0 0 0 0 82
10news-out.aioe.org 56 46 0 0 0 0 0 0 10
11news.swapon.de 26 0 0 0 0 0 0 0 26
12newsfeeds.xmission.com 24 8 0 0 0 0 0 0 16
13csiph-out.news.tnetconsulting.net 15 13 0 0 0 0 0 0 2
14news-out.neodome.net 12 1 0 0 0 0 0 0 11
15nntp.pasdenom.info 5 0 0 0 0 0 0 0 5
16news.fcku.it 3 3 0 0 0 0 0 0 0
TOTAL: 16 4014 455 0 3 1 0 0 0 3555

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.computer.workshop 125
lada.talk 42
it-alt.sport.calcio.juventus 34
alt.binaries.pictures.purebeauty 28
scinet.stats 25
alt.survival 16
alt.politics 15
spline.eisfair.dev 13
mozilla.support.seamonkey 12
spline.fli4l.dev 12
alt.tv.comedy-central.daily-show 10
alt.philosophy 8
mozilla.support.thunderbird 7
mozilla.general 7
alt.binaries.mp3.abooks 6
alt.binaries.ebook 6
mozilla.support.firefox 5
lada.auto 4
alt.bbs.synchronet 4
alt.politics.usa 4
TOTAL: 67 455

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

GroupsCount
uk.legal.moderated 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 2117
EMP (phn path) 265
EMP (phn nph) 207
EMP (phl) 92
Too many newsgroups 82
0 56
Binary Payload 42
Binary: misplaced binary 20
Scoring filter 13
HTML Multipart 12
HTML post 4
TOTAL: 11 2910

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 88
csiph-out.news.xs4all.nl 61
news.freedyn.net 30
csiph-out.feed.uzoreto.com 21
csiph-out.news.weretis.net 14
news-out.mixmin.net 4
newsfeeds.xmission.com 3
csiph-out.news.tnetconsulting.net 2
news-out.neodome.net 1
feeder.eternal-september.org 1
TOTAL: 10 225
Including strange strings
newsfeed.fu-berlin.de 332
news.swapon.de 26
csiph-out.news.xs4all.nl 18
newsfeeds.xmission.com 12
csiph-out.news.weretis.net 8
news-out.mixmin.net 7
news-out.aioe.org 4
csiph-out.feeder.erje.net 3
feeder.eternal-september.org 2
csiph-out.feed.uzoreto.com 2
TOTAL: 11 415
No colon-space in header
csiph-out.news.xs4all.nl 4
csiph-out.feeder.erje.net 2
TOTAL: 2 6
TOTAL: 4 691

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1xanadu-bbs 17132 855 13403 2 0 0 4%23:59:48
2fu-berlin.de 13368 504 12864 0 0 0 3%23:59:48
3news.samoylyk.net 14585 495 13748 8 0 0 3%23:59:39
4news.bbs.nz 26918 231 13487 27 0 1 0%23:59:47
5xmission 13940 91 11434 2 0 0 0%23:59:14
6newsfeed.xs4all.nl 10884 47 10307 1 0 19 0%23:59:47
7tnet 14808 22 13727 2 0 0 0%23:59:48
8neva.ru 13055 19 12884 104 0 0 0%23:59:48
9usenet.pasdenom.info 13935 8 13578 41 0 0 0%23:59:48
10neodome.net 14208 7 13875 0 0 0 0%23:59:48
11news.mixmin.net 12877 4 12620 0 0 0 0%23:59:47
12news.uzoreto.com 13441 4 13258 3 0 0 0%23:59:48
13news.freedyn.net 12496 3 12215 38 0 0 0%23:59:48
14aioe 11540 3 11208 0 0 12 0%23:59:47
15erje 13461 2 13268 10 0 0 0%23:59:48
16weretis.net 13101 0 12837 1 0 0 0%23:59:38
17eternal-september 0 0 0 0 0 11573 0%23:59:48
18news.swapon.de 1396 0 1391 0 0 0 0%23:29:56
19news.fcku.it 1011 0 1010 0 0 0 0%21:00:22
TOTAL: 19 232156 2295 207114 239 0 11605 0%452:25:57
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1xanadu-bbs5.7 MB8.8 KB5.7 MB0.1 KB/s6.8 KB23:59:48
2fu-berlin.de4.8 MB0.0 KB4.8 MB0.1 KB/s9.8 KB23:59:48
3news.samoylyk.net4.8 MB7.9 KB4.8 MB0.1 KB/s9.8 KB23:59:39
4news.bbs.nz565.0 KB82.1 KB647.1 KB0.0 KB/s2.5 KB23:59:47
5xmission192.6 KB2.4 KB194.9 KB0.0 KB/s2.1 KB23:59:14
6newsfeed.xs4all.nl128.5 KB2.6 KB131.1 KB0.0 KB/s2.7 KB23:59:47
7tnet38.6 KB2.5 KB41.0 KB0.0 KB/s1.7 KB23:59:48
8neva.ru29.9 KB250.6 KB280.5 KB0.0 KB/s2.3 KB23:59:48
9neodome.net15.2 KB0.0 KB15.2 KB0.0 KB/s2.2 KB23:59:48
10usenet.pasdenom.info10.8 KB64.1 KB74.9 KB0.0 KB/s1.5 KB23:59:48
11news.uzoreto.com7.4 KB22.1 KB29.5 KB0.0 KB/s4.2 KB23:59:48
12aioe6.2 KB0.0 KB6.2 KB0.0 KB/s2.1 KB23:59:47
13news.mixmin.net5.9 KB0.0 KB5.9 KB0.0 KB/s1.5 KB23:59:47
14news.freedyn.net5.7 KB402.3 KB408.0 KB0.0 KB/s10.0 KB23:59:48
15erje2.3 KB23.0 KB25.3 KB0.0 KB/s2.1 KB23:59:48
16weretis.net0.0 KB1.1 KB1.1 KB0.0 KB/s1.1 KB23:59:38
17eternal-september0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:48
18news.swapon.de0.0 KB0.0 KB0.0 KB0.0 KB/s023:29:56
19news.fcku.it0.0 KB0.0 KB0.0 KB0.0 KB/s021:00:22
TOTAL: 1916.3 MB869.4 KB17.1 MB0.0 KB/s6.9 KB452:25:57
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1212.90.61.180 1251213774.7 MB 9 0 045:45:42
2172.83.96.97 8 613.3 KB 7 0 003:36:02
3a131033.upc-a.chello.nl 50 22.7 KB 2 0 009:38:01
TOTAL: 3 59251221774.7 MB 18 0 092:45:21

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 230251219774.7 MB 16 0 077:39:56
2*.upc-a.chello.nl 50 22.7 KB 2 0 009:38:01
3*.net.upcbroadband.cz 53 00.0 KB 0 0 004:23:29
4*.range86-138.btcentralplus.com 2 00.0 KB 0 0 000:03:50
5*.hsd1.wa.comcast.net 2 00.0 KB 0 0 001:00:02
TOTAL: 5 337251221774.7 MB 18 0 092:45:21

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
212.90.61.180 31.755 84.785 0.00045:45:42
91.187.33.87 13.815 3.406 0.00028:18:12
a131033.upc-a.chello.nl 3.200 0.764 0.00009:38:01
ip-78-102-198-192.net.upcbroadband.cz 3.247 0.854 0.00004:23:29
172.83.96.97 0.731 0.156 0.00003:36:02
c-73-140-175-55.hsd1.wa.comcast.net 0.132 0.024 0.00001:00:02
host86-138-214-35.range86-138.btcentralplus.com 0.155 0.015 0.00000:03:50
TOTAL: 7 53.035 90.004 0.00092:45:21

Curious NNRP server explorers [Top 20]:

SystemConn
91.187.33.87 221
ip-78-102-198-192.net.upcbroadband.cz 53
c-73-140-175-55.hsd1.wa.comcast.net 2
host86-138-214-35.range86-138.btcentralplus.com 2
TOTAL: 4 278

NNRP client timeouts [Top 20]:

SystemConnPeer
172.83.96.97 7 0
c-73-140-175-55.hsd1.wa.comcast.net 2 0
TOTAL: 2 9 0

Newsgroup request counts (by category):

CategoryCountPct
1alt 250459100.0%
2comp 9 0.0%
TOTAL: 2 250468100.0%

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

NewsgroupCount
1alt.atheism 250428
2alt.anonymous.messages 13
3comp.sys.ibm.ps2.hardware 9
4alt.arts.poetry.comments 8
5alt.appalachian 4
6alt.abortion 2
7alt.archeology 1
8alt.activism 1
9alt.astronomy 1
10alt.astrology 1
TOTAL: 10 250468