Daily Usenet report

Jun 26 03:00:05 -- Jun 27 03:00:05

Unknown entries from news log file:

First 50 / 62 lines (80.6%)

Jun 26 03:01:21 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jun 26 04:05:29 twilightnode nnrpd[2674795]: Reading access from /usr/local/news/etc/readers.conf
Jun 26 04:05:29 twilightnode nnrpd[2674795]: Auth strategy 'localhost' does not match client. Removing.
Jun 26 04:05:29 twilightnode nnrpd[2674795]: 193.46.255.142 res <anonymous>
Jun 26 04:05:29 twilightnode nnrpd[2674795]: 193.46.255.142 no_match_user <anonymous> <localhost>
Jun 26 04:05:29 twilightnode nnrpd[2674795]: 193.46.255.142 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 26 04:05:29 twilightnode nnrpd[2674795]: 193.46.255.142 match_user <anonymous> <anonymous>
Jun 26 04:05:29 twilightnode nnrpd[2674795]: 193.46.255.142 no_post anonymous-read
Jun 26 08:08:14 twilightnode nnrpd[2685116]: Reading access from /usr/local/news/etc/readers.conf
Jun 26 08:08:14 twilightnode nnrpd[2685116]: Auth strategy 'localhost' does not match client. Removing.
Jun 26 08:08:14 twilightnode nnrpd[2685116]: 89.37.172.158 res <anonymous>
Jun 26 08:08:14 twilightnode nnrpd[2685116]: 89.37.172.158 no_match_user <anonymous> <localhost>
Jun 26 08:08:14 twilightnode nnrpd[2685116]: 89.37.172.158 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 26 08:08:14 twilightnode nnrpd[2685116]: 89.37.172.158 match_user <anonymous> <anonymous>
Jun 26 08:08:14 twilightnode nnrpd[2685116]: 89.37.172.158 no_post anonymous-read
Jun 26 08:08:23 twilightnode nnrpd[2685119]: Reading access from /usr/local/news/etc/readers.conf
Jun 26 08:08:23 twilightnode nnrpd[2685119]: Auth strategy 'localhost' does not match client. Removing.
Jun 26 08:08:23 twilightnode nnrpd[2685119]: 89.37.172.141 res <anonymous>
Jun 26 08:08:23 twilightnode nnrpd[2685119]: 89.37.172.141 no_match_user <anonymous> <localhost>
Jun 26 08:08:23 twilightnode nnrpd[2685119]: 89.37.172.141 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 26 08:08:23 twilightnode nnrpd[2685119]: 89.37.172.141 match_user <anonymous> <anonymous>
Jun 26 08:08:23 twilightnode nnrpd[2685119]: 89.37.172.141 no_post anonymous-read
Jun 26 14:17:33 twilightnode nnrpd[2702015]: Reading access from /usr/local/news/etc/readers.conf
Jun 26 14:17:33 twilightnode nnrpd[2702015]: Auth strategy 'localhost' does not match client. Removing.
Jun 26 14:17:33 twilightnode nnrpd[2702015]: 198.235.24.244 res <anonymous>
Jun 26 14:17:33 twilightnode nnrpd[2702015]: 198.235.24.244 no_match_user <anonymous> <localhost>
Jun 26 14:17:33 twilightnode nnrpd[2702015]: 198.235.24.244 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 26 14:17:33 twilightnode nnrpd[2702015]: 198.235.24.244 match_user <anonymous> <anonymous>
Jun 26 14:17:33 twilightnode nnrpd[2702015]: 198.235.24.244 no_post anonymous-read
Jun 26 16:31:33 twilightnode nnrpd[2707876]: Reading access from /usr/local/news/etc/readers.conf
Jun 26 16:31:33 twilightnode nnrpd[2707876]: Auth strategy 'localhost' does not match client. Removing.
Jun 26 16:31:33 twilightnode nnrpd[2707876]: 187.186.132.66.censys-scanner.com res <anonymous>
Jun 26 16:31:33 twilightnode nnrpd[2707876]: 187.186.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jun 26 16:31:33 twilightnode nnrpd[2707876]: 187.186.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 26 16:31:33 twilightnode nnrpd[2707876]: 187.186.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jun 26 16:31:33 twilightnode nnrpd[2707876]: 187.186.132.66.censys-scanner.com no_post anonymous-read
Jun 26 16:31:34 twilightnode nnrpd[2707876]: 187.186.132.66.censys-scanner.com res <anonymous>
Jun 26 16:31:34 twilightnode nnrpd[2707876]: 187.186.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jun 26 16:31:34 twilightnode nnrpd[2707876]: 187.186.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 26 16:31:34 twilightnode nnrpd[2707876]: 187.186.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jun 26 16:31:34 twilightnode nnrpd[2707876]: 187.186.132.66.censys-scanner.com no_post anonymous-read
Jun 26 17:21:03 twilightnode nnrpd[2709985]: Reading access from /usr/local/news/etc/readers.conf
Jun 26 17:21:03 twilightnode nnrpd[2709985]: Auth strategy 'localhost' does not match client. Removing.
Jun 26 17:21:03 twilightnode nnrpd[2709985]: aron.probe.onyphe.net res <anonymous>
Jun 26 17:21:03 twilightnode nnrpd[2709985]: aron.probe.onyphe.net no_match_user <anonymous> <localhost>
Jun 26 17:21:03 twilightnode nnrpd[2709985]: aron.probe.onyphe.net no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 26 17:21:03 twilightnode nnrpd[2709985]: aron.probe.onyphe.net match_user <anonymous> <anonymous>
Jun 26 17:21:03 twilightnode nnrpd[2709985]: aron.probe.onyphe.net no_post anonymous-read
Jun 26 17:21:04 twilightnode nnrpd[2709986]: Reading access from /usr/local/news/etc/readers.conf
Jun 26 17:21:04 twilightnode nnrpd[2709986]: Auth strategy 'localhost' does not match client. Removing.

Log entries by program:

Program nameLines%LinesSize%Size
innfeed 41211 81.8%5.7 MB 77.8%
inn 7112 14.1%1.3 MB 17.5%
innd 1937 3.8%337.3 KB 4.5%
nnrpd 101 0.2%11.1 KB 0.1%
controlchan 2 0.0%0.1 KB 0.0%
TOTAL: 5 50363 100.0%7.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 17180 51.0%
Negative hits 9379 27.9%
Do not exist 7086 21.0%
Cache misses 27 0.1%
TOTAL: 4 33672 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.008 0.0%1 8.000 8.000 8.000
article cleanup00:00:00.079 0.0%7097 0.000 0.011 0.111
article logging00:00:00.294 0.0%7111 0.000 0.041 0.222
article parse00:00:00.113 0.0%9431 0.000 0.012 0.111
article write00:00:04.383 0.0%4468 0.577 0.981 7.000
data move00:00:00.095 0.0%33332 0.000 0.003 0.061
hisgrep/artcncl00:00:00.001 0.0%1 1.000 1.000 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:05.431 0.0%33723 0.053 0.161 0.632
history sync00:00:00.047 0.0%411 0.000 0.114 2.000
history write00:00:03.837 0.0%7097 0.118 0.541 1.710
idle24:00:35.206100.0%33130 475.624 2608.971 15402.821
nntp read00:00:00.770 0.0%32838 0.004 0.023 0.161
overview write00:00:02.330 0.0%4468 0.191 0.521 3.000
perl filter00:00:08.692 0.0%7097 0.594 1.225 11.421
python filter00:00:00.672 0.0%7097 0.000 0.095 0.947
site send00:00:00.111 0.0%4468 0.000 0.025 0.286
TOTAL: 1724:01:02.069100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.028 0.0%10068 0.000 0.003 0.088
article prepare00:00:00.003 0.0%26278 0.000 0.000 0.006
article read00:00:00.639 0.0%4460 0.000 0.143 1.000
backlog stats00:00:00.062 0.0%68672 0.000 0.001 0.007
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.520 0.0%33376 0.000 0.016 0.073
data write00:00:00.772 0.0%28977 0.000 0.027 0.112
idle23:58:47.793100.0%68672 834.352 1257.103 1714.231
status file00:00:10.739 0.0%17049 0.447 0.630 2.967
TOTAL: 923:59:00.556100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:08.274100.0%14 113.000 591.000 2998.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.000 0.0%49 0.000 0.000 0.000
TOTAL: 300:00:08.274100.0%----

Control commands to innd:

CommandNumber
flush 3
flushlogs 2
go 2
logmode 2
lowmark 1
mode 147
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 167

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1twilightnode-out.news.weretis.net 3 11191 3823 5442 1926 34%42:58:12
2newsfeed.endofthelinebbs.com 1 4854 346 4072 436 7%23:20:00
3news.sklaffkom.se 34 94 62 14 18 65%09:09:18
4news.tebibyte.org 1 6485 52 6269 164 0%23:04:55
5localhost 25 0 0 0 0 0%00:00:00
6news.chimia.se 1 0 0 0 0 0%00:10:00
TOTAL: 6 65 22624 4283 15797 2544 18%98:42:25
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net10.5 MB0.0 KB5.2 MB15.7 MB 67%2.8 KB
2newsfeed.endofthelinebbs.com2.6 MB0.0 KB1.6 MB4.2 MB 61%5.5 KB
3news.sklaffkom.se354.0 KB0.0 KB39.4 KB393.3 KB 89%4.9 KB
4news.tebibyte.org244.7 KB0.0 KB485.6 KB730.4 KB 33%3.4 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
6news.chimia.se0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 613.7 MB0.0 KB7.3 MB21.0 MB 65%3.1 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 26 03:00:05 - 03:59:59 67 1.5% 0.02219.7 KB 1.5% 0.06
Jun 26 04:00:00 - 04:59:59 73 1.6% 0.02258.7 KB 1.7% 0.07
Jun 26 05:00:00 - 05:59:59 75 1.7% 0.02196.8 KB 1.3% 0.05
Jun 26 06:00:00 - 06:59:59 142 3.2% 0.04514.9 KB 3.5% 0.14
Jun 26 07:00:00 - 07:59:59 137 3.1% 0.04358.7 KB 2.4% 0.10
Jun 26 08:00:00 - 08:59:59 150 3.4% 0.04495.7 KB 3.4% 0.14
Jun 26 09:00:00 - 09:59:59 179 4.0% 0.05484.6 KB 3.3% 0.13
Jun 26 10:00:00 - 10:59:59 285 6.4% 0.08789.0 KB 5.3% 0.22
Jun 26 11:00:00 - 11:59:59 244 5.5% 0.07577.1 KB 3.9% 0.16
Jun 26 12:00:00 - 12:59:59 199 4.5% 0.06593.9 KB 4.0% 0.16
Jun 26 13:00:00 - 13:59:59 220 4.9% 0.06700.7 KB 4.7% 0.19
Jun 26 14:00:00 - 14:59:59 238 5.3% 0.07643.9 KB 4.4% 0.18
Jun 26 15:00:00 - 15:59:59 242 5.4% 0.071.0 MB 7.0% 0.29
Jun 26 16:00:00 - 16:59:59 279 6.2% 0.08943.4 KB 6.4% 0.26
Jun 26 17:00:00 - 17:59:59 242 5.4% 0.07845.2 KB 5.7% 0.23
Jun 26 18:00:00 - 18:59:59 266 6.0% 0.07951.8 KB 6.4% 0.26
Jun 26 19:00:00 - 19:59:59 306 6.8% 0.091.1 MB 7.5% 0.31
Jun 26 20:00:00 - 20:59:59 234 5.2% 0.07754.1 KB 5.1% 0.21
Jun 26 21:00:00 - 21:59:59 214 4.8% 0.06940.4 KB 6.4% 0.26
Jun 26 22:00:00 - 22:59:59 174 3.9% 0.05614.6 KB 4.2% 0.17
Jun 26 23:00:00 - 23:59:59 137 3.1% 0.04424.9 KB 2.9% 0.12
Jun 27 00:00:00 - 00:59:59 165 3.7% 0.05713.8 KB 4.8% 0.20
Jun 27 01:00:00 - 01:59:59 86 1.9% 0.02274.1 KB 1.9% 0.08
Jun 27 02:00:00 - 02:59:59 107 2.4% 0.03349.0 KB 2.4% 0.10
Jun 27 03:00:00 - 03:00:05 9 0.2% 1.807.2 KB 0.0% 1.44
TOTAL: 24:00:00 4470 100.0% 0.0514.4 MB 100.0% 0.17
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1979 1339 0 0 0 0 0 0 640
2newsfeed.endofthelinebbs.com 481 58 0 0 0 0 0 0 423
3news.tebibyte.org 164 20 0 0 0 0 0 0 144
4news.sklaffkom.se 18 18 0 0 0 0 0 0 0
TOTAL: 4 2642 1435 0 0 0 0 0 0 1207

Unwanted newsgroups [Top 20]:

NewsgroupCount
fido7.ru.news 150
it.hobby.cucina 52
de.alt.talk.unmut 51
fido7.ru.home 40
fido7.ru.fidonet.today 34
lada.talk 34
de.alt.folklore.ddr 32
fr.comp.os.mac-os.x 32
alt.usage.english 31
muc.lists.freebsd.bugs 28
pl.soc.polityka 27
de.alt.folklore.computer 26
pl.soc.prawo 26
dk.politik 25
pl.biznes.banki 25
uk.legal.moderated 24
cn.comp 20
pl.pregierz 20
bln.test 19
it.sport.calcio.inter-fc 18
TOTAL: 212 1435

Perl filter (innd) [Top 20]:

ReasonCount
EMP (phn path) 595
EMP (md5) 396
EMP (phl) 143
User-issued cancel 56
HTML Multipart 3
TOTAL: 5 1193

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1chimia.se 4418 4384 0 33 53 0 99%23:59:54
2sklaffkom.se 4761 903 2317 1235 6 3 18%23:58:36
3news.tebibyte.org 2838 0 0 0 0 7301 0%23:59:54
4newsfeed.endofthelinebbs.com 2771 0 0 0 0 6872 0%23:59:54
5weretis.net 526 0 488 0 0 0 0%21:36:19
6sklaff.dev 0 0 0 0 0 4463 0%23:57:03
7thebunker.nu 0 0 0 0 0 4470 0%23:59:54
8usenet.blueworldhosting.com 0 0 0 0 0 4162 0%23:59:54
TOTAL: 8 15314 5287 2805 1268 59 27271 34%189:31:28
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1chimia.se14.3 MB61.7 KB14.4 MB0.2 KB/s3.3 KB23:59:54
2sklaffkom.se1.5 MB6.0 MB7.5 MB0.1 KB/s3.6 KB23:58:36
3news.tebibyte.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:54
4newsfeed.endofthelinebbs.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:54
5sklaff.dev0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:03
6thebunker.nu0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:54
7usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:54
8weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s021:36:19
TOTAL: 815.8 MB6.1 MB21.9 MB0.0 KB/s3.4 KB189:31:28
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1187.186.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
2193.46.255.142 1 00.0 KB 0 0 000:00:01
3198.235.24.244 1 00.0 KB 0 0 000:00:00
489.37.172.141 1 00.0 KB 0 0 000:00:00
589.37.172.158 1 00.0 KB 0 0 000:00:00
6aron.probe.onyphe.net 1 00.0 KB 0 0 000:00:00
7schneider.probe.onyphe.net 1 00.0 KB 0 0 000:00:02
8soda.census.shodan.io 1 00.0 KB 0 0 000:00:00
TOTAL: 8 8 00.0 KB 0 0 000:00:08

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 4 00.0 KB 0 0 000:00:02
2*.probe.onyphe.net 2 00.0 KB 0 0 000:00:03
3*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
4*.census.shodan.io 1 00.0 KB 0 0 000:00:00
TOTAL: 4 8 00.0 KB 0 0 000:00:08

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
schneider.probe.onyphe.net 0.001 0.007 0.00000:00:02
187.186.132.66.censys-scanner.com 0.030 0.021 0.00000:00:01
193.46.255.142 0.003 0.014 0.00000:00:01
aron.probe.onyphe.net 0.003 0.018 0.00000:00:00
89.37.172.158 0.006 0.016 0.00000:00:00
198.235.24.244 0.003 0.037 0.00000:00:00
soda.census.shodan.io 0.004 0.037 0.00000:00:00
89.37.172.141 0.002 0.007 0.00000:00:00
TOTAL: 8 0.052 0.157 0.00000:00:08

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

SystemConn
aron.probe.onyphe.net 3
193.46.255.142 2
187.186.132.66.censys-scanner.com 1
TOTAL: 3 6

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

CommandCount
#001 1
#026#003#001 1
#026#003#003#002c#001 1
*?"#022?II? ?-?"NA?!?#022*2 ?#007?h`?=?/?#035#013?S... 1
?$?,?r?s?#023#002#023#001?#024?#007?#022?#023?'?/?#024?(?0?`?a... 1
EHLO www.censys.io 1
TOTAL: 6 6

NNRP client timeouts [Top 20]:

SystemConnPeer
89.37.172.141 1 1
TOTAL: 1 1 3
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