Daily Usenet report for csiph.com

May 2 04:15:00 -- May 3 04:15:00

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

May 2 17:34:39 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[40993]: unable to get certificate from '/usr/local/news/etc/cert.pem'
May 2 17:34:39 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[40993]: 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 23816 45.1%3.8 MB 38.4%
inn 18390 34.8%4.5 MB 45.5%
nnrpd 6937 13.1%936.8 KB 9.2%
innfeed 3713 7.0%710.7 KB 7.0%
TOTAL: 4 52856 100.0%10.0 MB100.0%

History cache:

ReasonCount%Count
Positive hits 274595 52.4%
Negative hits 226644 43.2%
Do not exist 17532 3.3%
Cache misses 5763 1.1%
TOTAL: 4 524534 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:07.348 0.0%305 0.000 24.092 185.000
article cleanup00:00:00.119 0.0%17145 0.000 0.007 0.052
article logging00:00:01.400 0.0%17661 0.000 0.079 1.247
article parse00:00:04.326 0.0%58417 0.000 0.074 1.475
article write00:01:51.529 0.1%13869 0.139 8.042 67.234
artlog/artcncl00:00:00.004 0.0%305 0.000 0.013 0.014
artlog/artparse00:00:00.011 0.0%481 0.000 0.023 1.000
data move00:00:00.199 0.0%485026 0.000 0.000 0.002
hisgrep/artcncl00:00:00.181 0.0%305 0.000 0.593 92.000
hishave/artcncl00:00:00.065 0.0%305 0.000 0.213 5.429
hishave/artparse00:00:00.002 0.0%478 0.000 0.004 0.333
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:01:10.026 0.1%529896 0.002 0.132 0.533
history sync00:00:01.813 0.0%336 0.000 5.396 42.667
history write00:01:25.138 0.1%17115 0.198 4.974 17.167
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
hiswrite/artparse00:00:03.839 0.0%477 0.000 8.048 388.000
idle23:46:37.775 98.7%484601 83.831 176.636 620.454
nntp read00:00:02.204 0.0%497301 0.002 0.004 0.009
overview write00:12:25.833 0.9%13869 0.220 53.777 22703.400
perl filter00:00:23.001 0.0%17112 0.868 1.344 2.491
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.253 0.0%27738 0.000 0.009 0.040
TOTAL: 24:05:11.74824:04:15.066 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.042 0.0%14124 0.000 0.003 0.068
article prepare00:00:00.001 0.0%4196 0.000 0.000 0.125
article read00:00:02.032 0.0%894 0.000 2.273 907.000
backlog stats00:00:00.161 0.0%269115 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.970 0.0%208462 0.000 0.005 0.012
data write00:00:01.318 0.0%196990 0.002 0.007 0.014
idle23:54:20.326100.0%269115 133.262 319.790 1237.037
status file00:00:01.068 0.0%392 0.000 2.724 40.667
TOTAL: 23:54:35.18523:54:25.918100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle29:58:53.698 99.8%9050 0.000 11926.375207168.667
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:25.132 0.0%102368 0.000 0.246 33.903
readart00:00:12.394 0.0%1317 0.000 9.411 23.045
TOTAL: 30:03:05.15329:59:31.224 99.8%----

Control commands to INND:

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1csiph-out.news.xs4all.nl 2454 16251 4797 9167 2287 29%122:58:05
2feeder.eternal-september.org 17 3559 1837 1583 139 51%27:34:50
3news-out.aioe.org 2 12781 1239 11493 49 9%23:59:49
4newsfeed.fu-berlin.de 9 14358 950 13039 369 6%25:08:32
5csiph-out.feeder.erje.net 1440 71105 929 69489 687 1%119:06:50
6news-out.mixmin.net 2 14993 921 13887 185 6%23:58:44
7csiph-out.news.weretis.net 4 27005 762 26195 48 2%47:58:04
8newsfeeds.xmission.com 2 13099 512 12576 11 3%23:37:07
9nntp.pasdenom.info 10 11242 438 10793 11 3%34:05:12
10csiph-out.news.tnetconsulting.net 2 22989 355 22623 11 1%38:36:56
11newsfeed.neva.ru 146 17424 347 17004 73 1%71:39:48
12news-out.neodome.net 2 15563 223 15302 38 1%23:59:54
13csiph-out.feed.uzoreto.com 864 31708 161 31393 154 0%71:12:44
14news.freedyn.net 3 15940 134 15771 35 0%22:09:52
15news.fcku.it 14 1136 108 1028 0 9%21:54:12
16news.swapon.de 10 1382 48 1334 0 3%23:18:43
17news.bbs.nz 2 13160 13 13146 1 0%24:04:47
18localhost 26 0 0 0 0 0%00:00:00
TOTAL: 18 5009 303695 13774 285823 4098 4%745:24:09
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1csiph-out.news.xs4all.nl13.5 MB0.0 KB20.6 MB34.1 MB 39%4.9 KB
2csiph-out.feeder.erje.net7.5 MB86.6 KB5.7 MB13.4 MB 56%8.5 KB
3feeder.eternal-september.org5.1 MB2.5 KB8.0 MB13.2 MB 38%6.8 KB
4news-out.aioe.org3.2 MB0.0 KB70.6 KB3.3 MB 97%2.6 KB
5newsfeed.fu-berlin.de2.4 MB0.0 KB5.6 MB8.0 MB 29%6.2 KB
6csiph-out.news.weretis.net2.3 MB15.5 KB1.5 MB3.9 MB 60%4.9 KB
7news-out.mixmin.net2.2 MB3.8 KB629.9 KB2.8 MB 78%2.6 KB
8newsfeeds.xmission.com1.7 MB0.0 KB890.3 KB2.6 MB 66%5.0 KB
9csiph-out.news.tnetconsulting.net1.2 MB0.0 KB83.4 KB1.3 MB 93%3.6 KB
10newsfeed.neva.ru872.0 KB0.0 KB163.7 KB1.0 MB 84%2.5 KB
11nntp.pasdenom.info834.9 KB0.0 KB27.9 KB862.8 KB 96%1.9 KB
12csiph-out.feed.uzoreto.com566.3 KB0.0 KB2.9 MB3.4 MB 16%11.1 KB
13news-out.neodome.net550.1 KB0.0 KB121.7 KB671.8 KB 81%2.6 KB
14news.freedyn.net539.0 KB0.0 KB526.8 KB1.0 MB 50%6.3 KB
15news.fcku.it147.4 KB0.0 KB0.0 KB147.4 KB100%1.4 KB
16news.swapon.de107.6 KB0.0 KB0.0 KB107.6 KB100%2.2 KB
17news.bbs.nz45.9 KB0.0 KB3.1 KB49.0 KB 93%3.5 KB
18localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1842.7 MB108.3 KB46.9 MB89.7 MB 47%5.1 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 2 04:15:00 - 04:59:59 182 1.3% 0.07709.9 KB 1.6% 0.26
May 2 05:00:00 - 05:59:59 593 4.3% 0.162.4 MB 5.8% 0.70
May 2 06:00:00 - 06:59:59 501 3.6% 0.141.8 MB 4.2% 0.51
May 2 07:00:00 - 07:59:59 527 3.8% 0.151.7 MB 4.0% 0.49
May 2 08:00:00 - 08:59:59 635 4.6% 0.181.9 MB 4.5% 0.55
May 2 09:00:00 - 09:59:59 689 5.0% 0.191.7 MB 4.1% 0.49
May 2 10:00:00 - 10:59:59 698 5.0% 0.192.6 MB 6.0% 0.73
May 2 11:00:00 - 11:59:59 638 4.6% 0.181.7 MB 4.1% 0.49
May 2 12:00:00 - 12:59:59 702 5.1% 0.202.0 MB 4.6% 0.56
May 2 13:00:00 - 13:59:59 784 5.7% 0.221.9 MB 4.5% 0.55
May 2 14:00:00 - 14:59:59 898 6.5% 0.252.2 MB 5.3% 0.64
May 2 15:00:00 - 15:59:59 802 5.8% 0.223.3 MB 7.8% 0.94
May 2 16:00:00 - 16:59:59 773 5.6% 0.212.1 MB 5.0% 0.61
May 2 17:00:00 - 17:59:59 861 6.2% 0.243.6 MB 8.4% 1.01
May 2 18:00:00 - 18:59:59 721 5.2% 0.202.0 MB 4.7% 0.57
May 2 19:00:00 - 19:59:59 663 4.8% 0.181.7 MB 4.0% 0.49
May 2 20:00:00 - 20:59:59 606 4.4% 0.171.5 MB 3.6% 0.44
May 2 21:00:00 - 21:59:59 481 3.5% 0.131.5 MB 3.4% 0.42
May 2 22:00:00 - 22:59:59 534 3.9% 0.151.6 MB 3.6% 0.44
May 2 23:00:00 - 23:59:59 398 2.9% 0.111.1 MB 2.7% 0.32
May 3 00:00:00 - 00:59:59 306 2.2% 0.091.0 MB 2.4% 0.29
May 3 01:00:00 - 01:59:59 313 2.3% 0.09823.9 KB 1.9% 0.23
May 3 02:00:00 - 02:59:59 235 1.7% 0.07777.1 KB 1.8% 0.22
May 3 03:00:00 - 03:59:59 211 1.5% 0.06600.8 KB 1.4% 0.17
May 3 04:00:00 - 04:15:00 76 0.5% 0.08227.1 KB 0.5% 0.25
TOTAL: 24:00:00 13827 100.0% 0.1642.6 MB 100.0% 0.50
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1csiph-out.news.xs4all.nl 2382 181 0 0 11 0 0 0 2190
2newsfeed.fu-berlin.de 756 15 0 0 0 0 0 0 741
3csiph-out.feeder.erje.net 344 69 0 29 1 0 0 0 245
4news-out.mixmin.net 190 4 0 1 0 0 0 0 185
5csiph-out.feed.uzoreto.com 158 16 0 0 0 0 0 0 142
6feeder.eternal-september.org 126 32 0 2 0 0 0 0 92
7newsfeed.neva.ru 75 43 0 0 0 0 0 0 32
8news-out.aioe.org 50 34 0 0 0 0 0 0 16
9csiph-out.news.weretis.net 47 7 0 1 0 0 0 0 39
10news.freedyn.net 47 1 0 0 0 0 0 0 46
11news-out.neodome.net 41 1 0 0 0 0 0 0 40
12news.swapon.de 18 0 0 0 0 0 0 0 18
13newsfeeds.xmission.com 13 0 0 0 0 0 0 0 13
14csiph-out.news.tnetconsulting.net 7 7 0 0 0 0 0 0 0
15nntp.pasdenom.info 7 0 0 0 0 0 0 0 7
TOTAL: 15 4261 410 0 33 12 0 0 0 3806

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.computer.workshop 89
it-alt.sport.calcio.juventus 49
alt.news.misc 48
alt.binaries.by-hash.shv1 30
alt.binaries.pictures.purebeauty 18
lada.talk 17
alt.windows7.general 14
spline.eisfair 9
biz.marketplace 6
alt.binaries.e-books.flood 6
lada.movies 5
alt.slack.goathead 5
mozilla.support.seamonkey 4
free.it.religioni.scientology 4
alt.politics 4
alt.finance 4
alt.computer.hardware 3
alt.comp.lang.raku 3
0.test 3
alt.religion 3
TOTAL: 97 410

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

GroupsCount
fido7.z7.test 1
fido7.mikdim.test 1
fido7.esib.test 1
fido7.dn.test 1
fido7.f157.test 1
fido7.f400.test 1
fido7.kharkov.blin.test 1
fido7.pskov.test 1
fido7.donbass.test 1
fido7.cris.test 1
fido7.krs.test 1
fido7.kharkov.star.test 1
TOTAL: 12 12

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1778
Binary: misplaced binary 298
Too many newsgroups 260
EMP (phn nph) 181
Binary Payload 92
EMP (phn path) 87
EMP (phl) 72
0 38
HTML Multipart 3
Too many newsgroups (meow) 2
bofh violation - invalid distribution 1
MID-Bot 1
HTML post 1
TOTAL: 13 2814

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Huge articles
csiph-out.news.xs4all.nl 238
feeder.eternal-september.org 79
csiph-out.feeder.erje.net 76
csiph-out.feed.uzoreto.com 38
csiph-out.news.weretis.net 18
newsfeeds.xmission.com 11
news-out.mixmin.net 7
news.freedyn.net 6
newsfeed.fu-berlin.de 2
TOTAL: 9 475
Including strange strings
newsfeed.fu-berlin.de 370
csiph-out.news.xs4all.nl 99
news.swapon.de 18
csiph-out.feeder.erje.net 8
csiph-out.feed.uzoreto.com 5
news-out.mixmin.net 4
news-out.aioe.org 3
news-out.neodome.net 2
newsfeed.neva.ru 2
newsfeeds.xmission.com 2
TOTAL: 11 514
No colon-space in header
csiph-out.news.xs4all.nl 1
csiph-out.feeder.erje.net 1
TOTAL: 2 2
TOTAL: 3 991

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1fu-berlin.de 12608 456 12152 0 0 0 3%23:50:09
2news.bbs.nz 25580 139 13048 15 0 0 0%23:50:09
3xmission 17180 76 12455 9 0 0 0%23:50:09
4tnet 14449 35 13235 1 0 0 0%23:58:51
5newsfeed.xs4all.nl 9038 29 8591 1 0 70 0%23:54:24
6erje 13328 27 12668 19 0 0 0%23:56:20
7usenet.pasdenom.info 13101 27 12800 13 0 39 0%23:59:53
8news.freedyn.net 14126 18 13653 101 0 45 0%23:55:47
9weretis.net 12591 12 12165 5 0 0 0%23:52:42
10news.uzoreto.com 12987 8 12820 38 0 0 0%23:59:00
11news.mixmin.net 12110 8 11992 1 0 0 0%23:59:05
12neodome.net 13692 7 13513 0 0 0 0%23:50:08
13neva.ru 12772 2 12712 3 0 0 0%23:52:32
14aioe 11652 0 11358 0 0 0 0%23:54:13
15news.fcku.it 945 0 944 0 0 0 0%21:26:03
16xanadu-bbs 0 0 0 0 0 13827 0%23:58:54
17eternal-september 0 0 0 0 0 11293 0%23:50:09
18news.samoylyk.net 0 0 0 0 0 13781 0%23:50:11
19news.swapon.de 1317 0 1310 0 0 0 0%22:56:34
TOTAL: 19 197476 844 175416 206 0 39055 0%450:45:13
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1fu-berlin.de5.5 MB0.0 KB5.5 MB0.1 KB/s12.4 KB23:50:09
2news.bbs.nz346.3 KB70.0 KB416.4 KB0.0 KB/s2.7 KB23:50:09
3newsfeed.xs4all.nl293.2 KB1.2 KB294.3 KB0.0 KB/s9.8 KB23:54:24
4xmission219.1 KB16.7 KB235.8 KB0.0 KB/s2.8 KB23:50:09
5usenet.pasdenom.info193.0 KB93.3 KB286.3 KB0.0 KB/s7.2 KB23:59:53
6news.freedyn.net191.6 KB1.4 MB1.6 MB0.0 KB/s13.9 KB23:55:47
7news.mixmin.net160.8 KB0.8 KB161.6 KB0.0 KB/s18.0 KB23:59:05
8tnet139.7 KB1.4 KB141.0 KB0.0 KB/s3.9 KB23:58:51
9news.uzoreto.com58.3 KB357.7 KB416.0 KB0.0 KB/s9.0 KB23:59:00
10erje53.1 KB93.7 KB146.8 KB0.0 KB/s3.2 KB23:56:20
11neodome.net40.0 KB0.0 KB40.0 KB0.0 KB/s5.7 KB23:50:08
12weretis.net32.9 KB16.0 KB48.9 KB0.0 KB/s2.9 KB23:52:42
13neva.ru2.7 KB74.5 KB77.2 KB0.0 KB/s15.4 KB23:52:32
14aioe0.0 KB0.0 KB0.0 KB0.0 KB/s023:54:13
15news.fcku.it0.0 KB0.0 KB0.0 KB0.0 KB/s021:26:03
16xanadu-bbs0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:54
17eternal-september0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:09
18news.samoylyk.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:11
19news.swapon.de0.0 KB0.0 KB0.0 KB0.0 KB/s022:56:34
TOTAL: 197.2 MB2.1 MB9.4 MB0.0 KB/s9.1 KB450:45:13
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1217-121-77-183.cable.dynamic.v4.ziggo.nl 90 13172.0 MB 19 0 000:29:25
TOTAL: 1 90 13172.0 MB 19 0 030:02:44

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.cable.dynamic.v4.ziggo.nl 90 13172.0 MB 19 0 000:29:25
2*.ch1.censys-scanner.com 1 00.0 KB 0 0 000:00:00
3*.security.ipip.net 1 00.0 KB 0 0 000:00:01
4*.upc-a.chello.nl 23 00.0 KB 0 0 003:40:02
5unresolved 288 00.0 KB 0 0 023:52:45
6*.163.24.10.ip.us.as30241.net 4 00.0 KB 0 0 002:00:28
TOTAL: 6 407 13172.0 MB 19 0 030:02:44

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
91.187.33.87 17.260 4.349 0.00023:52:45
a131033.upc-a.chello.nl 1.396 0.277 0.00003:40:02
192.163.24.10.ip.us.as30241.net 0.415 0.095 0.00002:00:28
217-121-77-183.cable.dynamic.v4.ziggo.nl 9.149 4.747 0.00000:29:25
scan-141.security.ipip.net 0.057 0.016 0.00000:00:01
scanner-08.ch1.censys-scanner.com 0.070 0.009 0.00000:00:00
TOTAL: 6 28.347 9.493 0.00030:02:44

Curious NNRP server explorers [Top 20]:

SystemConn
91.187.33.87 288
a131033.upc-a.chello.nl 23
192.163.24.10.ip.us.as30241.net 4
scan-141.security.ipip.net 1
scanner-08.ch1.censys-scanner.com 1
TOTAL: 5 317

NNRP unrecognized commands (by host):

SystemConn
scan-141.security.ipip.net 4
scanner-08.ch1.censys-scanner.com 1
TOTAL: 2 5

NNRP unrecognized commands (by command):

CommandCount
Connection: close 1
EHLO www.censys.io 1
GET / HTTP/1.1 1
User-Agent: HTTP Banner Detection (https... 1
Host: 69.160.37.58:119 1
TOTAL: 5 5

NNRP client timeouts [Top 20]:

SystemConnPeer
192.163.24.10.ip.us.as30241.net 4 0
TOTAL: 1 4 0

Newsgroup request counts (by category):

CategoryCountPct
1free 1282 97.3%
2rec 33 2.5%
3uk 1 0.1%
4xs4all 1 0.1%
TOTAL: 4 1317100.0%

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

NewsgroupCount
1free.willey 772
2free.usenet 431
3free.pt 79
4rec.arts.tv 33
5xs4all.general 1
6uk.radio.amateur 1
TOTAL: 6 1317