Daily Usenet Report

Aug 25 00:01:01 -- Aug 26 00:01:01

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Aug 25 04:16:43 newsfeed nnrpd[800444]: 47.189.156.68 auth: program exited with status 8
Aug 25 08:13:54 newsfeed innd: message repeated 2 times: [ ctlinnd command s]
Aug 25 19:28:04 newsfeed nnrpd[1208961]: 47.186.40.153 auth: program exited with status 8
Aug 26 00:00:01 newsfeed nnrpd[1287840]: localhost auth: program exited with status 8

Log entries by program:

Program nameLines%LinesSize%Size
inn 17205 36.8%4.5 MB 53.8%
innd 16332 34.9%2.1 MB 25.1%
nnrpd 8539 18.3%881.2 KB 10.3%
innfeed 4132 8.8%823.4 KB 9.6%
cnfsstat 432 0.9%63.2 KB 0.7%
nocem 145 0.3%33.7 KB 0.4%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 7 46786 100.0%8.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 145699 49.8%
Negative hits 75506 25.8%
Cache misses 54576 18.7%
Do not exist 16643 5.7%
TOTAL: 4 292424 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.233 0.0%515 0.000 2.394 51.429
article cleanup00:00:00.173 0.0%16641 0.000 0.010 0.200
article logging00:00:00.840 0.0%16739 0.000 0.050 2.700
article parse00:00:00.322 0.0%24675 0.000 0.013 0.082
article write00:00:02.244 0.0%7418 0.000 0.303 3.021
artlog/artcncl00:00:00.020 0.0%461 0.000 0.043 0.333
artlog/artparse00:00:00.000 0.0%3 0.000 0.000 0.000
data move00:00:01.182 0.0%234816 0.000 0.005 0.461
hisgrep/artcncl00:00:00.483 0.0%521 0.000 0.927 6.000
hishave/artcncl00:00:00.111 0.0%493 0.000 0.225 2.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:05:24.577 0.4%292305 0.023 1.110 5.894
history sync00:00:00.064 0.0%390 0.000 0.164 1.000
history write00:03:05.724 0.2%16642 3.225 11.160 89.667
hiswrite/artcncl00:00:00.037 0.0%14 2.643 2.643 2.643
idle23:47:12.314 99.0%234564 62.707 365.070 1912.605
nntp read00:00:04.188 0.0%236354 0.003 0.018 1.002
overview write00:03:18.488 0.2%7418 0.526 26.758 3010.364
perl filter00:00:50.856 0.1%16639 1.505 3.056 25.444
python filter00:00:02.905 0.0%16639 0.000 0.175 7.783
site send00:00:00.942 0.0%14911 0.000 0.063 0.191
TOTAL: 24:01:04.53924:00:06.703 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:03.969 0.0%4031384 0.000 0.001 0.053
article prepare00:00:00.010 0.0%76182 0.000 0.000 0.005
article read00:00:36.881 0.0%12787 0.000 2.884 10.401
backlog stats00:00:01.790 0.0%1685637 0.000 0.001 0.004
callbacks00:00:00.000 0.0%6 0.000 0.000 0.000
data read00:00:11.815 0.0%992737 0.002 0.012 0.042
data write00:00:12.082 0.0%987274 0.006 0.012 0.103
idle23:48:06.370 99.7%1685637 18.321 50.833 1786.475
status file00:00:00.412 0.0%312 1.000 1.321 25.000
TOTAL: 23:51:57.81523:49:13.329 99.8%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle04:15:43.061 93.0%8693 0.000 1764.990 47766.098
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:04.115 0.0%96282 0.000 0.043 1.000
readart00:00:00.041 0.0%69 0.462 0.594 0.625
TOTAL: 04:35:05.25004:15:47.217 93.0%----

Control commands to INND:

CommandNumber
flush 5
flushlogs 2
go 2
logmode 2
lowmark 1
mode 98
name 3
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 121

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1outgoing.news.lysator.liu.se 1 6590 1433 5049 108 21%23:59:57
2usenet.blueworldhosting.com 713 8134 948 6456 730 11%23:58:54
3endofthelinebbs-out.news.weretis.net 2 13072 751 12087 234 5%44:50:23
4nntp.comgw.net 1 7716 712 6812 192 9%23:59:56
5news.hispagatos.org 21 8003 533 7062 408 6%24:52:49
6newsfeed.xs3.de 2 5798 520 5185 93 8%23:59:52
7news.nntp4.net 12 56085 518 51861 3706 0%03:36:47
8usenet.goja.nl.eu.org 12 47772 487 43688 3597 1%22:59:01
9news.corradoroberto.it 4 1406 383 1023 0 27%23:57:14
10i2pn.org 2 7509 237 7231 41 3%23:59:19
11newsfeed.bofh.team 1 8187 178 8001 8 2%23:59:57
12usenet.network 1 5800 154 5644 2 2%23:59:58
13endofthelinebbs.peers.news.panix.com 24 7229 146 7066 17 2%23:57:32
14endoftheline-out.news.chmurka.net 2 3651 144 3503 4 3%23:58:26
15news.samoylyk.net 0 6486 133 6351 2 2%23:38:53
16news.netfront.net 1 5500 11 5440 49 0%22:29:47
17glockenspiel.complete.org 2 5187 11 5175 1 0%23:57:59
18news.bbs.nz 2 6135 4 6130 1 0%23:59:38
19news.furie.org.uk 2 3700 4 3695 1 0%23:59:34
20photonic.trudheim.com 3 2158 2 2156 0 0%30:22:59
21eotl-out.news.tnetconsulting.net 1 0 0 0 0 0%00:10:05
22localhost 35 0 0 0 0 0%00:00:00
23news.nk.ca 2 0 0 0 0 0%00:20:13
TOTAL: 23 846 216118 7309 199615 9194 3%485:09:13
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1outgoing.news.lysator.liu.se4.7 MB0.0 KB163.2 KB4.9 MB 96%3.2 KB
2endofthelinebbs-out.news.weretis.net3.0 MB1.5 KB427.9 KB3.4 MB 87%3.5 KB
3news.nntp4.net2.3 MB0.0 KB6.0 MB8.3 MB 27%2.0 KB
4usenet.blueworldhosting.com2.3 MB0.0 KB1.4 MB3.7 MB 62%2.2 KB
5news.corradoroberto.it2.3 MB0.0 KB0.0 KB2.3 MB100%6.1 KB
6nntp.comgw.net2.2 MB0.0 KB535.3 KB2.8 MB 81%3.1 KB
7usenet.goja.nl.eu.org1.9 MB0.0 KB9.2 MB11.2 MB 17%2.8 KB
8news.hispagatos.org1.7 MB0.0 KB3.2 MB4.9 MB 35%5.3 KB
9newsfeed.xs3.de1.5 MB0.0 KB360.7 KB1.8 MB 80%3.0 KB
10usenet.network1.5 MB0.0 KB2.0 KB1.5 MB 99%9.6 KB
11news.samoylyk.net948.5 KB0.0 KB1.8 KB950.3 KB 99%7.0 KB
12i2pn.org867.1 KB0.0 KB58.5 KB925.6 KB 93%3.3 KB
13endofthelinebbs.peers.news.panix.com452.9 KB1.5 KB31.7 KB486.0 KB 93%3.0 KB
14newsfeed.bofh.team435.0 KB0.0 KB12.9 KB447.9 KB 97%2.4 KB
15endoftheline-out.news.chmurka.net330.1 KB0.0 KB6.6 KB336.7 KB 98%2.3 KB
16glockenspiel.complete.org104.0 KB0.0 KB0.7 KB104.7 KB 99%8.7 KB
17news.netfront.net33.1 KB0.0 KB372.0 KB405.1 KB 8%6.8 KB
18news.bbs.nz16.1 KB0.0 KB0.8 KB17.0 KB 95%3.4 KB
19news.furie.org.uk15.2 KB0.0 KB3.0 KB18.1 KB 83%3.6 KB
20photonic.trudheim.com4.5 KB0.0 KB0.0 KB4.5 KB100%2.3 KB
21eotl-out.news.tnetconsulting.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
22localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
23news.nk.ca0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 2326.5 MB3.0 KB21.7 MB48.2 MB 54%3.0 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 25 00:01:01 - 00:59:59 173 2.3% 0.05649.7 KB 2.4% 0.18
Aug 25 01:00:00 - 01:59:59 213 2.9% 0.06732.1 KB 2.7% 0.20
Aug 25 02:00:00 - 02:59:59 286 3.9% 0.081022.1 KB 3.8% 0.28
Aug 25 03:00:00 - 03:59:59 402 5.4% 0.111.1 MB 4.3% 0.32
Aug 25 04:00:00 - 04:59:59 348 4.7% 0.10924.1 KB 3.4% 0.26
Aug 25 05:00:00 - 05:59:59 392 5.3% 0.111.5 MB 5.8% 0.44
Aug 25 06:00:00 - 06:59:59 323 4.4% 0.091.4 MB 5.2% 0.39
Aug 25 07:00:00 - 07:59:59 298 4.0% 0.08859.0 KB 3.2% 0.24
Aug 25 08:00:00 - 08:59:59 325 4.4% 0.091001.7 KB 3.7% 0.28
Aug 25 09:00:00 - 09:59:59 350 4.7% 0.101.4 MB 5.2% 0.39
Aug 25 10:00:00 - 10:59:59 356 4.8% 0.101.2 MB 4.6% 0.35
Aug 25 11:00:00 - 11:59:59 386 5.2% 0.111.3 MB 4.8% 0.36
Aug 25 12:00:00 - 12:59:59 362 4.9% 0.101.3 MB 4.9% 0.37
Aug 25 13:00:00 - 13:59:59 394 5.3% 0.111.2 MB 4.7% 0.35
Aug 25 14:00:00 - 14:59:59 357 4.8% 0.101.0 MB 3.9% 0.30
Aug 25 15:00:00 - 15:59:59 334 4.5% 0.091.3 MB 4.7% 0.36
Aug 25 16:00:00 - 16:59:59 294 4.0% 0.08936.9 KB 3.4% 0.26
Aug 25 17:00:00 - 17:59:59 229 3.1% 0.061002.8 KB 3.7% 0.28
Aug 25 18:00:00 - 18:59:59 176 2.4% 0.05826.8 KB 3.0% 0.23
Aug 25 19:00:00 - 19:59:59 622 8.4% 0.172.4 MB 9.2% 0.70
Aug 25 20:00:00 - 20:59:59 400 5.4% 0.111.9 MB 7.3% 0.55
Aug 25 21:00:00 - 21:59:59 93 1.3% 0.03511.8 KB 1.9% 0.14
Aug 25 22:00:00 - 22:59:59 109 1.5% 0.03397.2 KB 1.5% 0.11
Aug 25 23:00:00 - 23:59:59 190 2.6% 0.05715.1 KB 2.6% 0.20
Aug 26 00:00:00 - 00:01:01 6 0.1% 0.1025.9 KB 0.1% 0.42
TOTAL: 24:00:00 7418 100.0% 0.0926.6 MB 100.0% 0.31
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
CYCNGS1NGS1536.0 GB48.0 MB 0.0% 1 0.00 0.00
CYCNGS2NGS1536.0 GB96.7 GB 6.3% 0 0.4838674.89
CYCTXT1TEXT5.0 GB3.8 GB 76.5% 0 0.03 1936.57
TOTAL: 3-3077.0 GB100.5 GB 3.3% 1 0.35-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nntp4.net 3707 0 0 0 0 0 0 0 3707
2usenet.goja.nl.eu.org 3608 0 0 0 0 0 0 0 3608
3usenet.blueworldhosting.com 755 0 0 0 0 0 0 0 755
4news.hispagatos.org 404 0 0 0 0 0 0 0 404
5endofthelinebbs-out.news.weretis.net 269 0 0 1 0 0 0 0 268
6nntp.comgw.net 220 0 0 0 0 0 0 0 220
7outgoing.news.lysator.liu.se 112 0 0 0 0 0 0 0 112
8newsfeed.xs3.de 110 0 0 0 0 0 0 0 110
9news.netfront.net 55 0 0 0 0 0 0 0 55
10i2pn.org 46 0 0 0 0 0 0 0 46
11endofthelinebbs.peers.news.panix.com 22 0 0 1 0 0 0 0 21
12newsfeed.bofh.team 10 0 0 0 0 0 0 0 10
13endoftheline-out.news.chmurka.net 5 0 0 0 0 0 0 0 5
14news.samoylyk.net 3 0 0 0 0 0 0 0 3
15usenet.network 2 0 0 0 0 0 0 0 2
16news.bbs.nz 1 0 0 0 0 0 0 0 1
17news.corradoroberto.it 1 0 0 0 0 0 0 0 1
18news.furie.org.uk 1 0 0 0 0 0 0 0 1
TOTAL: 18 9331 0 0 2 0 0 0 0 9329

INND Perl filter [Top 20]:

ReasonCount
EMP (phn path) 3893
EMP (phr path) 3575
Too many newsgroups 664
EMP (md5) 614
EMP (phn nph) 338
User-issued cancel 55
HTML Multipart 34
Too many newsgroups (meow) 24
EMP (fsl) 22
Binary: misplaced binary 8
Cancel for rejected article 1
TOTAL: 11 9228

NoCeM on Spool:

Issuer (type)NoticesBad PGPCancelSkipTotal
i2pn2-nocem@i2pn2.org (spam)7004680468
robot@pasdenom.info (spam2)30033
robot@pasdenom.info (spam3)10022
TOTAL: 37404685473

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
usenet.blueworldhosting.com 25
endofthelinebbs-out.news.weretis.net 23
newsfeed.xs3.de 17
nntp.comgw.net 10
usenet.goja.nl.eu.org 9
endofthelinebbs.peers.news.panix.com 4
outgoing.news.lysator.liu.se 4
i2pn.org 3
news.nntp4.net 1
news.corradoroberto.it 1
TOTAL: 11 98
No colon-space in header field
usenet.blueworldhosting.com 3
TOTAL: 1 3
TOTAL: 2 101

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.nntp4.net4030858 4115 115607 4830 963905860 0%23:59:53
2news.netfront.net 10961 2551 4851 0 0 9 23%23:59:53
3nyheter.lysator.liu.se 6177 1368 4378 230 0 0 22%23:59:52
4photonic.trudheim.com 7912 452 6034 898 1 0 5%23:59:29
5endofthelinebbs.peers.news.panix.com 7620 389 7023 2 0 0 5%23:59:53
6news.hispagatos.org 8107 382 6359 114 0 0 4%23:59:53
7news.furie.org.uk 4696 349 3667 8 0 4 7%23:59:53
8usenet.network 7408 341 5912 35 0 0 4%23:59:53
9news.bbs.nz 12665 256 6861 279 0 39 2%23:59:01
10usenet.goja.nl.eu.org 7980 224 6597 76 0 0 2%23:59:39
11news.nk.ca 10601 187 6684 453 0 23 1%23:59:53
12newsfeed.bofh.team 7963 144 6964 0 0 0 1%23:59:53
13news.tnetconsulting.net 8119 107 7172 134 0 0 1%23:59:53
14news.corradoroberto.it 1046 99 760 0 0 0 9%23:31:13
15news.weretis.net 7667 97 7251 62 0 0 1%23:59:53
16i2pn.org 8076 96 7039 21 0 31 1%23:59:53
17nntp.comgw.net 7522 84 6541 41 0 0 1%23:59:53
18news.quux.org 7760 79 6967 300 0 0 1%23:59:53
19newsfeed.xs3.de 8155 49 6758 83 0 2 0%23:59:53
20news.chmurka.net 4523 32 3812 2 0 2 0%23:59:53
21news.samoylyk.net 8392 18 6712 53 0 440 0%23:59:53
22usenet.blueworldhosting.com 6011 1 5673 0 0 0 0%23:59:21
23feed.opticnetworks.net 0 0 0 0 0 7418 0%23:59:53
TOTAL: 234190219 11420 239622 7621 973913828 0%551:26:36
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.nntp4.net13.9 MB57.2 MB71.2 MB0.8 KB/s8.1 KB23:59:53
2news.netfront.net9.1 MB0.0 KB9.1 MB0.1 KB/s3.6 KB23:59:53
3nyheter.lysator.liu.se5.3 MB1.2 MB6.6 MB0.1 KB/s4.2 KB23:59:52
4photonic.trudheim.com2.2 MB2.9 MB5.1 MB0.1 KB/s3.9 KB23:59:29
5news.furie.org.uk2.0 MB142.9 KB2.1 MB0.0 KB/s6.1 KB23:59:53
6news.quux.org1.4 MB939.9 KB2.3 MB0.0 KB/s6.2 KB23:59:53
7endofthelinebbs.peers.news.panix.com1.4 MB10.2 KB1.4 MB0.0 KB/s3.6 KB23:59:53
8news.hispagatos.org1.2 MB878.5 KB2.0 MB0.0 KB/s4.2 KB23:59:53
9usenet.network1.1 MB92.1 KB1.2 MB0.0 KB/s3.3 KB23:59:53
10newsfeed.bofh.team1001.3 KB0.0 KB1001.3 KB0.0 KB/s7.0 KB23:59:53
11news.bbs.nz823.4 KB1.7 MB2.6 MB0.0 KB/s4.9 KB23:59:01
12news.samoylyk.net751.9 KB330.1 KB1.1 MB0.0 KB/s15.2 KB23:59:53
13news.tnetconsulting.net655.3 KB424.2 KB1.1 MB0.0 KB/s4.5 KB23:59:53
14usenet.goja.nl.eu.org564.4 KB400.0 KB964.4 KB0.0 KB/s3.2 KB23:59:39
15news.weretis.net527.3 KB1.2 MB1.7 MB0.0 KB/s11.3 KB23:59:53
16news.nk.ca433.8 KB2.2 MB2.6 MB0.0 KB/s4.1 KB23:59:53
17news.corradoroberto.it402.0 KB0.0 KB402.0 KB0.0 KB/s4.1 KB23:31:13
18i2pn.org298.2 KB132.7 KB430.9 KB0.0 KB/s3.7 KB23:59:53
19newsfeed.xs3.de266.1 KB1.1 MB1.4 MB0.0 KB/s10.7 KB23:59:53
20nntp.comgw.net234.6 KB1.1 MB1.3 MB0.0 KB/s11.0 KB23:59:53
21news.chmurka.net128.7 KB5.1 KB133.7 KB0.0 KB/s3.9 KB23:59:53
22usenet.blueworldhosting.com1.1 KB0.0 KB1.1 KB0.0 KB/s1.1 KB23:59:21
23feed.opticnetworks.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
TOTAL: 2343.5 MB72.0 MB115.5 MB0.1 KB/s6.2 KB551:26:36
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
147.189.156.68 1 23.8 KB 1 0 003:23:00
247.186.40.153 1 11.4 KB 1 0 000:30:42
TOTAL: 2 2 35.2 KB 2 0 004:35:03

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 14 35.2 KB 2 0 003:54:46
2*.demos.su2774 00.0 KB 0 0 000:29:11
3? 2 00.0 KB 0 0 000:11:03
4*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
TOTAL: 42791 35.2 KB 2 0 004:35:03

NNRP auth users [Top 20]:

UserConn
nelgin 2
getlist 1
TOTAL: 2 3

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
47.189.156.68 0.018 0.062 0.07403:23:00
47.186.40.153 0.018 0.050 0.07300:30:42
ddt.demos.su 9.569 37.415 0.00000:29:11
localhost 176.634 369.831 0.07400:11:00
206.168.34.39 0.021 0.078 0.00000:00:45
198.235.24.252 0.003 0.012 0.00000:00:10
167.94.138.36 0.018 0.049 0.00000:00:04
147.185.132.234 0.004 0.012 0.00000:00:03
2607:ff10:c8:594::5 0.006 0.011 0.00000:00:02
agreeable.monitoring.internet-measurement.com 0.009 0.009 0.00000:00:01
88.214.25.61 0.000 0.017 0.00000:00:00
91.238.181.21 0.003 0.013 0.00000:00:00
TOTAL: 12 186.303 407.559 0.22104:35:03

Curious NNRP server explorers [Top 20]:

SystemConn
ddt.demos.su 2774
206.168.34.39 5
167.94.138.36 3
147.185.132.234 1
198.235.24.252 1
2607:ff10:c8:594::5 1
88.214.25.61 1
91.238.181.21 1
agreeable.monitoring.internet-measurement.com 1
localhost 1
TOTAL: 10 2789

NNRP unrecognized commands (by host) [Top 20]:

SystemConn
206.168.34.39 3
167.94.138.36 1
88.214.25.61 1
91.238.181.21 1
TOTAL: 4 6

NNRP unrecognized commands (by command) [Top 20]:

CommandCount
#003 2
EHLO www.censys.io 2
GET / HTTP/1.1 1
Host: 144.172.126.95:119 1
TOTAL: 4 6

NNRP client timeouts [Top 20]:

SystemConnPeer
206.168.34.39 2 2
167.94.138.36 1 2
47.189.156.68 1 0
88.214.25.61 1 1
91.238.181.21 1 1
TOTAL: 5 6 7

Newsgroup request counts (by hierarchy):

HierarchyCountPct
1news 3100.0%
TOTAL: 1 3100.0%

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

NewsgroupCount
1news.software.nntp 2
2news.admin.peering 1
TOTAL: 2 3