Daily Usenet Report

Aug 23 00:01:02 -- Aug 24 00:01:01

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Aug 23 08:14:58 newsfeed innd: message repeated 2 times: [ ctlinnd command s]
Aug 24 00:00:01 newsfeed nnrpd[4024693]: localhost auth: program exited with status 8

Log entries by program:

Program nameLines%LinesSize%Size
inn 9431 30.8%3.7 MB 56.3%
nnrpd 8358 27.3%869.1 KB 12.8%
innd 8147 26.6%1.1 MB 17.4%
innfeed 4097 13.4%816.6 KB 12.0%
cnfsstat 432 1.4%67.2 KB 1.0%
nocem 127 0.4%32.5 KB 0.5%
controlchan 1 0.0%0.2 KB 0.0%
TOTAL: 7 30593 100.0%6.6 MB100.0%

History cache:

ReasonCount%Count
Positive hits 116230 60.3%
Negative hits 67650 35.1%
Do not exist 8766 4.5%
Cache misses 77 0.0%
TOTAL: 4 192723 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.999 0.0%576 0.000 1.734 44.500
article cleanup00:00:00.117 0.0%8817 0.000 0.013 0.200
article logging00:00:00.419 0.0%8928 0.000 0.047 0.185
article parse00:00:00.230 0.0%13291 0.000 0.017 0.059
article write00:00:02.457 0.0%7617 0.000 0.323 10.057
artlog/artcncl00:00:00.012 0.0%501 0.000 0.024 0.286
artlog/artparse00:00:00.000 0.0%1 0.000 0.000 0.000
data move00:00:00.946 0.0%193570 0.000 0.005 0.181
hisgrep/artcncl00:00:00.481 0.0%561 0.000 0.857 9.000
hishave/artcncl00:00:00.114 0.0%516 0.000 0.221 2.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:02:41.468 0.2%193523 0.087 0.834 5.967
history sync00:00:00.073 0.0%403 0.000 0.181 1.000
history write00:01:55.234 0.1%8806 3.286 13.086 108.604
hiswrite/artcncl00:00:00.038 0.0%15 2.533 2.533 2.533
idle23:42:16.408 98.7%192214 78.991 443.966 1774.849
nntp read00:00:03.108 0.0%193918 0.001 0.016 0.534
overview write00:12:42.589 0.9%7617 0.625 100.117 5849.737
perl filter00:00:26.569 0.0%8805 1.688 3.017 19.725
python filter00:00:00.939 0.0%8805 0.000 0.107 1.118
site send00:00:00.905 0.0%15300 0.000 0.059 0.186
TOTAL: 24:01:13.39224:00:13.106 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:04.113 0.0%4521904 0.000 0.001 0.002
article prepare00:00:00.011 0.0%55956 0.000 0.000 0.012
article read00:00:00.632 0.0%4914 0.000 0.129 0.308
backlog stats00:00:02.023 0.0%2031974 0.000 0.001 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:13.108 0.0%1249144 0.006 0.010 0.038
data write00:00:12.938 0.0%1245315 0.007 0.010 0.021
idle23:45:20.914 99.7%2031974 30.083 42.088 78.560
status file00:00:00.309 0.0%258 0.750 1.198 16.000
TOTAL: 23:50:11.84223:45:54.048 99.7%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:22:58.124 56.0%8285 0.250 166.340 10050.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:04.284 0.2%95367 0.000 0.045 1.000
TOTAL: 00:41:00.99300:23:02.408 56.2%----

Control commands to INND:

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1outgoing.news.lysator.liu.se 1 6647 1639 4857 151 24%23:59:57
2usenet.blueworldhosting.com 721 8630 1023 7076 531 11%23:49:48
3endofthelinebbs-out.news.weretis.net 3 13458 901 12444 113 6%45:57:53
4usenet.goja.nl.eu.org 10 8020 894 6991 135 11%23:51:40
5nntp.comgw.net 1 8019 514 7427 78 6%23:59:56
6news.hispagatos.org 5 7740 494 7231 15 6%24:26:20
7news.corradoroberto.it 9 1520 421 1099 0 27%25:51:31
8usenet.network 2 5927 368 5537 22 6%23:59:38
9newsfeed.xs3.de 2 6245 324 5858 63 5%23:58:32
10newsfeed.bofh.team 2 8618 238 8359 21 2%23:58:33
11i2pn.org 1 7780 208 7559 13 2%23:59:51
12endoftheline-out.news.chmurka.net 2 3916 196 3717 3 5%23:59:19
13endofthelinebbs.peers.news.panix.com 26 8116 168 7925 23 2%23:52:31
14news.samoylyk.net 2 6922 117 6804 1 1%23:59:46
15news.bbs.nz 1 6691 13 6678 0 0%23:59:34
16news.furie.org.uk 1 4448 10 4437 1 0%23:59:52
17glockenspiel.complete.org 2 5818 9 5809 0 0%24:03:34
18news.netfront.net 1 4795 3 4738 54 0%21:53:58
19photonic.trudheim.com 2 2029 0 2028 1 0%28:27:04
20eotl-out.news.tnetconsulting.net 1 0 0 0 0 0%00:10:08
21localhost 32 0 0 0 0 0%00:00:00
22news.nk.ca 2 0 0 0 0 0%00:20:12
23news.nntp4.net 1 0 0 0 0 0%00:10:00
TOTAL: 23 830 125339 7540 116574 1225 6%482:49:37
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1outgoing.news.lysator.liu.se5.9 MB0.0 KB206.6 KB6.1 MB 96%3.5 KB
2endofthelinebbs-out.news.weretis.net3.3 MB0.0 KB191.1 KB3.4 MB 94%3.5 KB
3usenet.network3.1 MB34.6 KB10.6 KB3.2 MB 98%8.4 KB
4usenet.blueworldhosting.com3.0 MB8.5 KB827.2 KB3.8 MB 78%2.5 KB
5news.corradoroberto.it2.8 MB0.0 KB0.0 KB2.8 MB100%6.9 KB
6usenet.goja.nl.eu.org2.6 MB0.0 KB190.8 KB2.8 MB 93%2.8 KB
7nntp.comgw.net1.6 MB0.0 KB150.0 KB1.7 MB 91%3.0 KB
8news.hispagatos.org1.5 MB0.0 KB39.9 KB1.5 MB 97%3.1 KB
9newsfeed.xs3.de1.0 MB0.0 KB220.7 KB1.2 MB 82%3.3 KB
10i2pn.org822.2 KB0.0 KB21.9 KB844.1 KB 97%3.8 KB
11endoftheline-out.news.chmurka.net745.1 KB0.0 KB2.0 KB747.1 KB 99%3.8 KB
12newsfeed.bofh.team671.0 KB0.0 KB29.6 KB700.6 KB 95%2.7 KB
13news.samoylyk.net566.0 KB0.0 KB1.2 KB567.2 KB 99%4.8 KB
14endofthelinebbs.peers.news.panix.com459.1 KB0.0 KB27.0 KB486.1 KB 94%2.5 KB
15glockenspiel.complete.org92.3 KB0.0 KB0.0 KB92.3 KB100%10.3 KB
16news.furie.org.uk50.6 KB0.0 KB0.7 KB51.2 KB 98%4.7 KB
17news.bbs.nz34.4 KB0.0 KB0.0 KB34.4 KB100%2.6 KB
18news.netfront.net4.4 KB0.0 KB371.5 KB375.9 KB 1%6.6 KB
19eotl-out.news.tnetconsulting.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
20localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
21news.nk.ca0.0 KB0.0 KB0.0 KB0.0 KB 0%0
22news.nntp4.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
23photonic.trudheim.com0.0 KB0.0 KB2.2 KB2.2 KB 0%2.2 KB
TOTAL: 2328.2 MB43.1 KB2.2 MB30.5 MB 92%3.6 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 23 00:01:02 - 00:59:59 185 2.4% 0.05635.5 KB 2.1% 0.18
Aug 23 01:00:00 - 01:59:59 224 2.9% 0.06764.0 KB 2.6% 0.21
Aug 23 02:00:00 - 02:59:59 303 4.0% 0.08907.4 KB 3.0% 0.25
Aug 23 03:00:00 - 03:59:59 350 4.6% 0.101.1 MB 3.8% 0.32
Aug 23 04:00:00 - 04:59:59 404 5.3% 0.111.4 MB 4.9% 0.40
Aug 23 05:00:00 - 05:59:59 441 5.8% 0.121.8 MB 6.3% 0.52
Aug 23 06:00:00 - 06:59:59 417 5.5% 0.121.5 MB 5.1% 0.42
Aug 23 07:00:00 - 07:59:59 302 4.0% 0.081.2 MB 4.1% 0.34
Aug 23 08:00:00 - 08:59:59 431 5.7% 0.121.6 MB 5.3% 0.44
Aug 23 09:00:00 - 09:59:59 554 7.3% 0.152.2 MB 7.6% 0.63
Aug 23 10:00:00 - 10:59:59 454 6.0% 0.131.6 MB 5.5% 0.45
Aug 23 11:00:00 - 11:59:59 503 6.6% 0.141.7 MB 5.8% 0.48
Aug 23 12:00:00 - 12:59:59 415 5.4% 0.121.5 MB 5.0% 0.41
Aug 23 13:00:00 - 13:59:59 410 5.4% 0.111.4 MB 5.0% 0.41
Aug 23 14:00:00 - 14:59:59 330 4.3% 0.091.1 MB 3.9% 0.32
Aug 23 15:00:00 - 15:59:59 354 4.6% 0.101.7 MB 5.8% 0.48
Aug 23 16:00:00 - 16:59:59 264 3.5% 0.071.3 MB 4.3% 0.36
Aug 23 17:00:00 - 17:59:59 293 3.8% 0.081.4 MB 4.9% 0.40
Aug 23 18:00:00 - 18:59:59 194 2.5% 0.05843.7 KB 2.8% 0.23
Aug 23 19:00:00 - 19:59:59 154 2.0% 0.04489.2 KB 1.6% 0.14
Aug 23 20:00:00 - 20:59:59 90 1.2% 0.03353.7 KB 1.2% 0.10
Aug 23 21:00:00 - 21:59:59 203 2.7% 0.06953.4 KB 3.2% 0.26
Aug 23 22:00:00 - 22:59:59 147 1.9% 0.041.1 MB 3.7% 0.31
Aug 23 23:00:00 - 23:59:59 189 2.5% 0.05713.0 KB 2.4% 0.20
Aug 24 00:00:00 - 00:01:01 5 0.1% 0.0812.1 KB 0.0% 0.20
TOTAL: 23:59:59 7616 100.0% 0.0929.1 MB 100.0% 0.34
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.6 GB 6.3% 0 0.5235850.73
CYCTXT1TEXT5.0 GB3.8 GB 76.5% 0 0.03 2018.92
TOTAL: 3-3077.0 GB100.5 GB 3.3% 1 0.36-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 559 0 0 4 0 0 0 0 555
2usenet.goja.nl.eu.org 157 0 0 0 0 0 0 0 157
3outgoing.news.lysator.liu.se 154 0 0 0 0 0 0 0 154
4endofthelinebbs-out.news.weretis.net 144 0 0 0 0 0 0 0 144
5nntp.comgw.net 77 0 0 0 0 0 0 0 77
6newsfeed.xs3.de 72 0 0 0 0 0 0 0 72
7news.netfront.net 47 0 0 0 0 0 0 0 47
8endofthelinebbs.peers.news.panix.com 27 0 0 0 0 0 0 0 27
9usenet.network 22 0 0 8 0 0 0 0 14
10newsfeed.bofh.team 21 0 0 0 0 0 0 0 21
11i2pn.org 14 0 0 0 0 0 0 0 14
12news.hispagatos.org 14 0 0 0 0 0 0 0 14
13endoftheline-out.news.chmurka.net 3 0 0 0 0 0 0 0 3
14news.samoylyk.net 2 0 0 0 0 0 0 0 2
15news.furie.org.uk 1 0 0 0 0 0 0 0 1
TOTAL: 15 1314 0 0 12 0 0 0 0 1302

INND Perl filter [Top 20]:

ReasonCount
EMP (phn path) 673
EMP (phr path) 323
EMP (md5) 79
Too many newsgroups 53
EMP (phn nph) 37
User-issued cancel 19
HTML Multipart 3
Cancel for rejected article 1
hierarchy violation - crosspost outside sdnet 1
TOTAL: 9 1189

NoCeM on Spool:

Issuer (type)NoticesBad PGPCancelSkipTotal
i2pn2-nocem@i2pn2.org (spam)6005160516
robot@pasdenom.info (spam2)40044
robot@pasdenom.info (spam3)20033
TOTAL: 36605167523

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
endofthelinebbs-out.news.weretis.net 32
usenet.blueworldhosting.com 29
usenet.goja.nl.eu.org 22
newsfeed.xs3.de 10
nntp.comgw.net 8
endofthelinebbs.peers.news.panix.com 5
outgoing.news.lysator.liu.se 3
newsfeed.bofh.team 1
news.samoylyk.net 1
news.hispagatos.org 1
TOTAL: 10 112
No colon-space in header field
usenet.blueworldhosting.com 1
TOTAL: 1 1
TOTAL: 2 113

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.netfront.net 10967 3522 4078 0 0 22 32%23:59:52
2nyheter.lysator.liu.se 6181 1621 3895 456 0 1 26%23:59:52
3news.furie.org.uk 5263 757 3405 6 0 0 14%23:56:12
4photonic.trudheim.com 7979 752 5712 1136 0 2 9%23:59:52
5news.hispagatos.org 8582 541 6404 177 0 4 6%23:59:52
6endofthelinebbs.peers.news.panix.com 7922 454 7155 5 0 0 5%23:59:26
7usenet.network 7791 441 6127 54 0 5 5%23:59:51
8news.bbs.nz 13722 264 6887 446 0 116 1%23:59:51
9usenet.goja.nl.eu.org 8165 235 6309 152 0 0 2%23:59:48
10news.nk.ca 11323 204 6577 727 0 66 1%23:59:52
11newsfeed.bofh.team 8820 197 7161 0 0 0 2%23:59:52
12news.tnetconsulting.net 8503 111 7285 217 0 15 1%23:59:53
13news.weretis.net 7966 109 7364 142 0 0 1%23:59:28
14nntp.comgw.net 8397 102 6927 94 0 0 1%23:59:52
15i2pn.org 8642 95 7215 73 0 118 1%23:59:52
16news.corradoroberto.it 1074 89 798 6 0 0 8%23:19:30
17news.quux.org 8168 78 7053 469 0 18 0%23:59:52
18newsfeed.xs3.de 9007 64 7087 140 0 5 0%23:59:24
19news.chmurka.net 4476 60 3456 0 0 0 1%23:59:40
20news.samoylyk.net 9123 26 7407 30 0 14 0%23:59:52
21news.nntp4.net4552263 0 0 0 04552273 0%23:59:52
22usenet.blueworldhosting.com 6756 0 6271 0 0 9 0%23:59:53
23feed.opticnetworks.net 0 0 0 0 0 7616 0%23:59:52
TOTAL: 234721090 9722 124573 4330 04560284 0%551:11:20
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.netfront.net14.3 MB0.0 KB14.3 MB0.2 KB/s4.2 KB23:59:52
2nyheter.lysator.liu.se5.5 MB4.1 MB9.6 MB0.1 KB/s4.8 KB23:59:52
3photonic.trudheim.com5.0 MB3.8 MB8.8 MB0.1 KB/s4.8 KB23:59:52
4news.furie.org.uk4.4 MB66.4 KB4.5 MB0.1 KB/s6.0 KB23:56:12
5endofthelinebbs.peers.news.panix.com2.5 MB8.2 KB2.5 MB0.0 KB/s5.5 KB23:59:26
6news.hispagatos.org1.9 MB1.8 MB3.7 MB0.0 KB/s5.2 KB23:59:52
7newsfeed.bofh.team1.5 MB0.0 KB1.5 MB0.0 KB/s7.7 KB23:59:52
8usenet.network1.5 MB191.0 KB1.6 MB0.0 KB/s3.4 KB23:59:51
9news.bbs.nz916.6 KB3.1 MB4.0 MB0.0 KB/s5.8 KB23:59:51
10news.nk.ca568.0 KB4.2 MB4.8 MB0.1 KB/s5.3 KB23:59:52
11usenet.goja.nl.eu.org561.6 KB1.8 MB2.4 MB0.0 KB/s6.3 KB23:59:48
12news.quux.org538.7 KB3.2 MB3.7 MB0.0 KB/s7.0 KB23:59:52
13news.weretis.net515.1 KB1.5 MB2.0 MB0.0 KB/s8.0 KB23:59:28
14news.tnetconsulting.net464.9 KB1.6 MB2.1 MB0.0 KB/s6.5 KB23:59:53
15news.corradoroberto.it367.0 KB70.1 KB437.1 KB0.0 KB/s4.6 KB23:19:30
16nntp.comgw.net322.2 KB1.2 MB1.5 MB0.0 KB/s8.0 KB23:59:52
17newsfeed.xs3.de275.8 KB1.3 MB1.5 MB0.0 KB/s7.7 KB23:59:24
18news.samoylyk.net265.6 KB147.2 KB412.8 KB0.0 KB/s7.4 KB23:59:52
19i2pn.org249.3 KB278.5 KB527.8 KB0.0 KB/s3.1 KB23:59:52
20news.chmurka.net236.1 KB0.0 KB236.1 KB0.0 KB/s3.9 KB23:59:40
21feed.opticnetworks.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:52
22news.nntp4.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:52
23usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
TOTAL: 2341.7 MB28.4 MB70.0 MB0.0 KB/s5.1 KB551:11:20
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1ddt.demos.su2757 00.0 KB 0 0 000:29:26
247.236.17.216 4 00.0 KB 0 0 000:00:34
3167.94.138.113 3 00.0 KB 0 0 000:00:03
447.237.64.115 2 00.0 KB 0 0 000:00:21
5147.185.132.228 1 00.0 KB 0 0 000:00:00
6198.235.24.167 1 00.0 KB 0 0 000:00:10
7198.235.24.179 1 00.0 KB 0 0 000:00:10
871.6.134.232 1 00.0 KB 0 0 000:00:00
991.238.181.33 1 00.0 KB 0 0 000:00:00
10azpdegb14.stretchoid.com 1 00.0 KB 0 0 000:00:00
11localhost 1 00.0 KB 0 0 000:10:10
12optimistic.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:02
TOTAL: 122774 00.0 KB 0 0 000:40:59

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.demos.su2757 00.0 KB 0 0 000:29:26
2unresolved 14 00.0 KB 0 0 000:01:19
3*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:02
4*.stretchoid.com 1 00.0 KB 0 0 000:00:00
5? 1 00.0 KB 0 0 000:10:10
TOTAL: 52774 00.0 KB 0 0 000:40:59

NNRP auth users [Top 20]:

UserConn
getlist 1
TOTAL: 1 1

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
ddt.demos.su 9.358 36.934 0.00000:29:26
localhost 176.695 353.647 0.07100:10:10
47.236.17.216 0.008 0.056 0.00000:00:34
47.237.64.115 0.007 0.025 0.00000:00:21
198.235.24.179 0.008 0.008 0.00000:00:10
198.235.24.167 0.000 0.016 0.00000:00:10
167.94.138.113 0.022 0.039 0.00000:00:03
optimistic.monitoring.internet-measurement.com 0.004 0.016 0.00000:00:02
91.238.181.33 0.004 0.012 0.00000:00:00
azpdegb14.stretchoid.com 0.000 0.016 0.00000:00:00
147.185.132.228 0.000 0.016 0.00000:00:00
71.6.134.232 0.009 0.009 0.00000:00:00
TOTAL: 12 186.115 390.794 0.07100:40:59

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

SystemConn
167.94.138.113 1
91.238.181.33 1
azpdegb14.stretchoid.com 1
TOTAL: 3 3

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

CommandCount
#003 1
EHLO www.censys.io 1
MGLNDD_144.172.126.95_119 1
TOTAL: 3 3

NNRP client timeouts [Top 20]:

SystemConnPeer
167.94.138.113 1 2
91.238.181.33 1 1
TOTAL: 2 2 4