Daily Usenet report

Jul 25 03:00:06 -- Jul 26 03:00:03

Unknown entries from news log file:

First 50 / 64 lines (78.1%)

Jul 25 03:01:23 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jul 25 09:26:16 twilightnode nnrpd[624999]: Reading access from /usr/local/news/etc/readers.conf
Jul 25 09:26:16 twilightnode nnrpd[624999]: Auth strategy 'localhost' does not match client. Removing.
Jul 25 09:26:16 twilightnode nnrpd[624999]: 185.223.235.7 res <anonymous>
Jul 25 09:26:16 twilightnode nnrpd[624999]: 185.223.235.7 no_match_user <anonymous> <localhost>
Jul 25 09:26:16 twilightnode nnrpd[624999]: 185.223.235.7 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 25 09:26:16 twilightnode nnrpd[624999]: 185.223.235.7 match_user <anonymous> <anonymous>
Jul 25 09:26:16 twilightnode nnrpd[624999]: 185.223.235.7 no_post anonymous-read
Jul 25 09:26:26 twilightnode nnrpd[625002]: Reading access from /usr/local/news/etc/readers.conf
Jul 25 09:26:26 twilightnode nnrpd[625002]: Auth strategy 'localhost' does not match client. Removing.
Jul 25 09:26:26 twilightnode nnrpd[625002]: 188.240.59.61 res <anonymous>
Jul 25 09:26:26 twilightnode nnrpd[625002]: 188.240.59.61 no_match_user <anonymous> <localhost>
Jul 25 09:26:26 twilightnode nnrpd[625002]: 188.240.59.61 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 25 09:26:26 twilightnode nnrpd[625002]: 188.240.59.61 match_user <anonymous> <anonymous>
Jul 25 09:26:26 twilightnode nnrpd[625002]: 188.240.59.61 no_post anonymous-read
Jul 25 12:38:08 twilightnode nnrpd[633405]: Reading access from /usr/local/news/etc/readers.conf
Jul 25 12:38:08 twilightnode nnrpd[633405]: Auth strategy 'localhost' does not match client. Removing.
Jul 25 12:38:08 twilightnode nnrpd[633405]: o025.scanner.modat.io res <anonymous>
Jul 25 12:38:08 twilightnode nnrpd[633405]: o025.scanner.modat.io no_match_user <anonymous> <localhost>
Jul 25 12:38:08 twilightnode nnrpd[633405]: o025.scanner.modat.io no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 25 12:38:08 twilightnode nnrpd[633405]: o025.scanner.modat.io match_user <anonymous> <anonymous>
Jul 25 12:38:08 twilightnode nnrpd[633405]: o025.scanner.modat.io no_post anonymous-read
Jul 25 17:32:25 twilightnode nnrpd[645689]: Reading access from /usr/local/news/etc/readers.conf
Jul 25 17:32:25 twilightnode nnrpd[645689]: Auth strategy 'localhost' does not match client. Removing.
Jul 25 17:32:25 twilightnode nnrpd[645689]: 38.195.132.66.censys-scanner.com res <anonymous>
Jul 25 17:32:25 twilightnode nnrpd[645689]: 38.195.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jul 25 17:32:25 twilightnode nnrpd[645689]: 38.195.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 25 17:32:25 twilightnode nnrpd[645689]: 38.195.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jul 25 17:32:25 twilightnode nnrpd[645689]: 38.195.132.66.censys-scanner.com no_post anonymous-read
Jul 25 17:32:26 twilightnode nnrpd[645689]: 38.195.132.66.censys-scanner.com res <anonymous>
Jul 25 17:32:26 twilightnode nnrpd[645689]: 38.195.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jul 25 17:32:26 twilightnode nnrpd[645689]: 38.195.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 25 17:32:26 twilightnode nnrpd[645689]: 38.195.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jul 25 17:32:26 twilightnode nnrpd[645689]: 38.195.132.66.censys-scanner.com no_post anonymous-read
Jul 25 20:18:23 twilightnode nnrpd[652792]: Reading access from /usr/local/news/etc/readers.conf
Jul 25 20:18:23 twilightnode nnrpd[652792]: Auth strategy 'localhost' does not match client. Removing.
Jul 25 20:18:23 twilightnode nnrpd[652792]: azpdcguw45bv.stretchoid.com res <anonymous>
Jul 25 20:18:23 twilightnode nnrpd[652792]: azpdcguw45bv.stretchoid.com no_match_user <anonymous> <localhost>
Jul 25 20:18:23 twilightnode nnrpd[652792]: azpdcguw45bv.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 25 20:18:23 twilightnode nnrpd[652792]: azpdcguw45bv.stretchoid.com match_user <anonymous> <anonymous>
Jul 25 20:18:23 twilightnode nnrpd[652792]: azpdcguw45bv.stretchoid.com no_post anonymous-read
Jul 25 20:18:33 twilightnode nnrpd[652797]: Reading access from /usr/local/news/etc/readers.conf
Jul 25 20:18:33 twilightnode nnrpd[652797]: Auth strategy 'localhost' does not match client. Removing.
Jul 25 20:18:33 twilightnode nnrpd[652797]: azpdcguw45bv.stretchoid.com res <anonymous>
Jul 25 20:18:33 twilightnode nnrpd[652797]: azpdcguw45bv.stretchoid.com no_match_user <anonymous> <localhost>
Jul 25 20:18:33 twilightnode nnrpd[652797]: azpdcguw45bv.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jul 25 20:18:33 twilightnode nnrpd[652797]: azpdcguw45bv.stretchoid.com match_user <anonymous> <anonymous>
Jul 25 20:18:33 twilightnode nnrpd[652797]: azpdcguw45bv.stretchoid.com no_post anonymous-read
Jul 25 22:19:23 twilightnode innd: message repeated 3 times: [ ctlinnd command s]
Jul 25 22:20:25 twilightnode innd: message repeated 5 times: [ ctlinnd command s]

Log entries by program:

Program nameLines%LinesSize%Size
inn 5276 64.5%796.7 KB 65.8%
innd 1429 17.5%204.8 KB 16.9%
innfeed 1379 16.8%198.9 KB 16.4%
nnrpd 100 1.2%10.1 KB 0.8%
controlchan 2 0.0%0.2 KB 0.0%
TOTAL: 5 8186 100.0%1.2 MB100.0%

History cache:

ReasonCount%Count
Positive hits 11886 48.3%
Negative hits 7508 30.5%
Do not exist 5204 21.2%
Cache misses 0 0.0%
TOTAL: 4 24598 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.004 0.0%3 1.000 1.333 2.000
article cleanup00:00:00.066 0.0%5223 0.000 0.013 0.111
article logging00:00:00.195 0.0%5254 0.000 0.037 0.182
article parse00:00:00.091 0.0%6821 0.000 0.013 0.158
article write00:00:04.315 0.0%3772 0.444 1.144 4.705
data move00:00:00.085 0.0%26452 0.000 0.003 0.028
hisgrep/artcncl00:00:00.000 0.0%3 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.171 0.0%24666 0.045 0.169 0.439
history sync00:00:00.071 0.0%413 0.000 0.172 1.333
history write00:00:02.943 0.0%5221 0.154 0.564 1.769
idle23:50:41.499100.0%26426 1414.559 3248.373 17163.400
nntp read00:00:00.685 0.0%25988 0.000 0.026 0.088
overview write00:00:01.916 0.0%3772 0.171 0.508 2.150
perl filter00:00:08.313 0.0%5221 0.762 1.592 7.568
python filter00:00:00.644 0.0%5221 0.000 0.123 0.531
site send00:00:00.100 0.0%3771 0.000 0.027 0.222
TOTAL: 1723:51:05.098100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.017 0.0%3804 0.000 0.004 0.083
article prepare00:00:00.000 0.0%13068 0.000 0.000 0.000
article read00:00:00.665 0.0%2469 0.000 0.269 0.727
backlog stats00:00:00.031 0.0%43472 0.000 0.001 0.008
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.365 0.0%22812 0.000 0.016 0.067
data write00:00:00.620 0.0%18942 0.000 0.033 0.157
idle23:42:55.870100.0%43472 547.918 1963.928 6603.154
status file00:00:00.159 0.0%267 0.000 0.596 4.000
TOTAL: 923:42:57.727100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:15.298100.0%12 76.500 1274.833 10714.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.000 0.0%51 0.000 0.000 0.000
TOTAL: 300:00:15.298100.0%----

Control commands to innd:

CommandNumber
flush 2
flushlogs 2
go 2
logmode 2
lowmark 1
mode 182
name 35
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 234

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 4 8247 3289 4191 767 39%45:23:19
2newsfeed.endofthelinebbs.com 2 3782 277 2948 557 7%22:31:15
3news.tebibyte.org 3 5007 95 4846 66 1%23:59:39
4news.sklaffkom.se 40 129 44 22 63 34%12:42:09
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 74 17165 3705 12007 1453 21%104:36:22
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net9.3 MB10.9 KB2.4 MB11.8 MB 79%3.0 KB
2newsfeed.endofthelinebbs.com2.4 MB0.0 KB2.0 MB4.4 MB 55%5.4 KB
3news.tebibyte.org334.1 KB0.0 KB289.6 KB623.6 KB 53%3.9 KB
4news.sklaffkom.se113.2 KB0.0 KB154.4 KB267.6 KB 42%2.5 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 512.2 MB10.9 KB4.8 MB17.0 MB 71%3.4 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jul 25 03:00:06 - 03:59:59 105 2.8% 0.03423.6 KB 3.3% 0.12
Jul 25 04:00:00 - 04:59:59 102 2.7% 0.03447.0 KB 3.5% 0.12
Jul 25 05:00:00 - 05:59:59 89 2.4% 0.02377.5 KB 3.0% 0.10
Jul 25 06:00:00 - 06:59:59 100 2.6% 0.03359.6 KB 2.8% 0.10
Jul 25 07:00:00 - 07:59:59 73 1.9% 0.02126.0 KB 1.0% 0.04
Jul 25 08:00:00 - 08:59:59 143 3.8% 0.04400.0 KB 3.2% 0.11
Jul 25 09:00:00 - 09:59:59 231 6.1% 0.06912.7 KB 7.2% 0.25
Jul 25 10:00:00 - 10:59:59 192 5.1% 0.05500.8 KB 4.0% 0.14
Jul 25 11:00:00 - 11:59:59 152 4.0% 0.04470.6 KB 3.7% 0.13
Jul 25 12:00:00 - 12:59:59 209 5.5% 0.06483.7 KB 3.8% 0.13
Jul 25 13:00:00 - 13:59:59 201 5.3% 0.06680.9 KB 5.4% 0.19
Jul 25 14:00:00 - 14:59:59 173 4.6% 0.05467.8 KB 3.7% 0.13
Jul 25 15:00:00 - 15:59:59 213 5.6% 0.06629.2 KB 5.0% 0.17
Jul 25 16:00:00 - 16:59:59 216 5.7% 0.06765.2 KB 6.0% 0.21
Jul 25 17:00:00 - 17:59:59 221 5.8% 0.061.0 MB 8.4% 0.29
Jul 25 18:00:00 - 18:59:59 271 7.2% 0.08825.3 KB 6.5% 0.23
Jul 25 19:00:00 - 19:59:59 201 5.3% 0.06693.4 KB 5.5% 0.19
Jul 25 20:00:00 - 20:59:59 162 4.3% 0.04440.3 KB 3.5% 0.12
Jul 25 21:00:00 - 21:59:59 157 4.1% 0.04540.1 KB 4.3% 0.15
Jul 25 22:00:00 - 22:59:59 163 4.3% 0.05467.2 KB 3.7% 0.13
Jul 25 23:00:00 - 23:59:59 124 3.3% 0.03446.4 KB 3.5% 0.12
Jul 26 00:00:00 - 00:59:59 123 3.2% 0.03394.9 KB 3.1% 0.11
Jul 26 01:00:00 - 01:59:59 90 2.4% 0.03355.4 KB 2.8% 0.10
Jul 26 02:00:00 - 02:59:59 69 1.8% 0.02404.6 KB 3.2% 0.11
Jul 26 03:00:00 - 03:00:03 6 0.2% 2.006.9 KB 0.1% 2.30
TOTAL: 23:59:57 3786 100.0% 0.0412.4 MB 100.0% 0.15
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 852 711 0 2 0 0 0 0 139
2newsfeed.endofthelinebbs.com 523 56 0 0 0 0 0 0 467
3news.sklaffkom.se 63 63 0 0 0 0 0 0 0
4news.tebibyte.org 52 49 0 0 0 0 0 0 3
TOTAL: 4 1490 879 0 2 0 0 0 0 609

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.checkmate 45
linux.debian.vote 40
eternal-september.support 37
pl.misc.elektronika 25
pl.soc.polityka 25
alt.slack 24
alt.usage.english 24
pl.soc.prawo 24
linux.debian.l10n.french 23
fr.sci.maths 22
eternal-september.talk 21
cn.comp 20
alt.prophecies.nostradamus 18
lada.talk 16
uk.tech.broadcast 15
dk.politik 14
alt.fan.rush-limbaurgh 13
alt.home.repair 13
hfx.general 13
alt.recovery.aa 12
TOTAL: 175 879

Perl filter (innd) [Top 20]:

ReasonCount
EMP (md5) 467
User-issued cancel 51
EMP (phl) 31
EMP (phn path) 23
HTML Multipart 4
Too many newsgroups 1
Too many newsgroups (meow) 1
TOTAL: 7 578

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
twilightnode-out.news.weretis.net 31
TOTAL: 1 31
TOTAL: 1 31

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tebibyte.org 5269 814 2967 2 0 5 15%23:56:56
2sklaffkom.se 3944 805 1348 1632 0 0 20%23:56:56
3newsfeed.endofthelinebbs.com 6560 21 3519 8 0 45 0%23:56:56
4weretis.net 345 1 331 0 0 0 0%20:00:12
TOTAL: 4 16118 1641 8165 1642 0 50 10%91:51:00
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tebibyte.org2.3 MB87.6 KB2.4 MB0.0 KB/s3.0 KB23:56:56
2sklaffkom.se1.3 MB6.4 MB7.7 MB0.1 KB/s3.2 KB23:56:56
3newsfeed.endofthelinebbs.com53.6 KB18.6 KB72.2 KB0.0 KB/s2.5 KB23:56:56
4weretis.net1.9 KB0.0 KB1.9 KB0.0 KB/s1.9 KB20:00:12
TOTAL: 43.6 MB6.5 MB10.2 MB0.0 KB/s3.2 KB91:51:00
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1azpdcguw45bv.stretchoid.com 2 00.0 KB 0 0 000:00:10
2185.223.235.12 1 00.0 KB 0 0 000:00:00
3185.223.235.7 1 00.0 KB 0 0 000:00:00
4188.240.59.61 1 00.0 KB 0 0 000:00:00
5195.140.214.26 1 00.0 KB 0 0 000:00:00
638.195.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:02
7o025.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 7 8 00.0 KB 0 0 000:00:15

NNRP connection statistics (by domain) [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 4 00.0 KB 0 0 000:00:01
2*.stretchoid.com 2 00.0 KB 0 0 000:00:10
3*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:02
4*.scanner.modat.io 1 00.0 KB 0 0 000:00:00
TOTAL: 4 8 00.0 KB 0 0 000:00:15

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
azpdcguw45bv.stretchoid.com 0.008 0.023 0.00000:00:10
38.195.132.66.censys-scanner.com 0.034 0.014 0.00000:00:02
188.240.59.61 0.003 0.006 0.00000:00:00
o025.scanner.modat.io 0.002 0.015 0.00000:00:00
185.223.235.7 0.005 0.015 0.00000:00:00
195.140.214.26 0.004 0.006 0.00000:00:00
185.223.235.12 0.002 0.009 0.00000:00:00
TOTAL: 7 0.058 0.088 0.00000:00:15

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

SystemConn
38.195.132.66.censys-scanner.com 1
azpdcguw45bv.stretchoid.com 1
TOTAL: 2 2

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

CommandCount
EHLO www.censys.io 1
MGLNDD_172.234.115.63_119 1
TOTAL: 2 2

NNRP client timeouts [Top 20]:

SystemConnPeer
188.240.59.61 1 1
195.140.214.26 1 1
TOTAL: 2 2 4
innreport from INN 2.7.3 based on initial work (©) 1996-2001 by Fabien Tassin <fta@sofaraway.org> and enhanced by several contributors since then