Daily Usenet report

Jun 16 03:00:06 -- Jun 17 03:00:05

Unknown entries from news log file:

First 50 / 88 lines (56.8%)

Jun 16 03:01:17 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Jun 16 03:19:53 twilightnode nnrpd[2015777]: Reading access from /usr/local/news/etc/readers.conf
Jun 16 03:19:53 twilightnode nnrpd[2015777]: Auth strategy 'localhost' does not match client. Removing.
Jun 16 03:19:53 twilightnode nnrpd[2015777]: r3-17-11.monitoring.internet-measurement.com res <anonymous>
Jun 16 03:19:53 twilightnode nnrpd[2015777]: r3-17-11.monitoring.internet-measurement.com no_match_user <anonymous> <localhost>
Jun 16 03:19:53 twilightnode nnrpd[2015777]: r3-17-11.monitoring.internet-measurement.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 16 03:19:53 twilightnode nnrpd[2015777]: r3-17-11.monitoring.internet-measurement.com match_user <anonymous> <anonymous>
Jun 16 03:19:53 twilightnode nnrpd[2015777]: r3-17-11.monitoring.internet-measurement.com no_post anonymous-read
Jun 16 13:42:38 twilightnode nnrpd[2043101]: Reading access from /usr/local/news/etc/readers.conf
Jun 16 13:42:38 twilightnode nnrpd[2043101]: Auth strategy 'localhost' does not match client. Removing.
Jun 16 13:42:38 twilightnode nnrpd[2043101]: 176.65.149.31.ptr.pfcloud.network res <anonymous>
Jun 16 13:42:38 twilightnode nnrpd[2043101]: 176.65.149.31.ptr.pfcloud.network no_match_user <anonymous> <localhost>
Jun 16 13:42:38 twilightnode nnrpd[2043101]: 176.65.149.31.ptr.pfcloud.network no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 16 13:42:38 twilightnode nnrpd[2043101]: 176.65.149.31.ptr.pfcloud.network match_user <anonymous> <anonymous>
Jun 16 13:42:38 twilightnode nnrpd[2043101]: 176.65.149.31.ptr.pfcloud.network no_post anonymous-read
Jun 16 15:04:45 twilightnode nnrpd[2046694]: Reading access from /usr/local/news/etc/readers.conf
Jun 16 15:04:45 twilightnode nnrpd[2046694]: Auth strategy 'localhost' does not match client. Removing.
Jun 16 15:04:45 twilightnode nnrpd[2046694]: 205.210.31.214 res <anonymous>
Jun 16 15:04:45 twilightnode nnrpd[2046694]: 205.210.31.214 no_match_user <anonymous> <localhost>
Jun 16 15:04:45 twilightnode nnrpd[2046694]: 205.210.31.214 no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 16 15:04:45 twilightnode nnrpd[2046694]: 205.210.31.214 match_user <anonymous> <anonymous>
Jun 16 15:04:45 twilightnode nnrpd[2046694]: 205.210.31.214 no_post anonymous-read
Jun 16 17:51:22 twilightnode nnrpd[2054104]: Reading access from /usr/local/news/etc/readers.conf
Jun 16 17:51:22 twilightnode nnrpd[2054104]: Auth strategy 'localhost' does not match client. Removing.
Jun 16 17:51:22 twilightnode nnrpd[2054104]: azpdes0rles7.stretchoid.com res <anonymous>
Jun 16 17:51:22 twilightnode nnrpd[2054104]: azpdes0rles7.stretchoid.com no_match_user <anonymous> <localhost>
Jun 16 17:51:22 twilightnode nnrpd[2054104]: azpdes0rles7.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 16 17:51:22 twilightnode nnrpd[2054104]: azpdes0rles7.stretchoid.com match_user <anonymous> <anonymous>
Jun 16 17:51:22 twilightnode nnrpd[2054104]: azpdes0rles7.stretchoid.com no_post anonymous-read
Jun 16 17:51:32 twilightnode nnrpd[2054105]: Reading access from /usr/local/news/etc/readers.conf
Jun 16 17:51:32 twilightnode nnrpd[2054105]: Auth strategy 'localhost' does not match client. Removing.
Jun 16 17:51:32 twilightnode nnrpd[2054105]: azpdes0rles7.stretchoid.com res <anonymous>
Jun 16 17:51:32 twilightnode nnrpd[2054105]: azpdes0rles7.stretchoid.com no_match_user <anonymous> <localhost>
Jun 16 17:51:32 twilightnode nnrpd[2054105]: azpdes0rles7.stretchoid.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 16 17:51:32 twilightnode nnrpd[2054105]: azpdes0rles7.stretchoid.com match_user <anonymous> <anonymous>
Jun 16 17:51:32 twilightnode nnrpd[2054105]: azpdes0rles7.stretchoid.com no_post anonymous-read
Jun 16 21:32:30 twilightnode nnrpd[2064273]: Reading access from /usr/local/news/etc/readers.conf
Jun 16 21:32:30 twilightnode nnrpd[2064273]: Auth strategy 'localhost' does not match client. Removing.
Jun 16 21:32:30 twilightnode nnrpd[2064273]: 199.172.132.66.censys-scanner.com res <anonymous>
Jun 16 21:32:30 twilightnode nnrpd[2064273]: 199.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jun 16 21:32:30 twilightnode nnrpd[2064273]: 199.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 16 21:32:30 twilightnode nnrpd[2064273]: 199.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jun 16 21:32:30 twilightnode nnrpd[2064273]: 199.172.132.66.censys-scanner.com no_post anonymous-read
Jun 16 21:32:31 twilightnode nnrpd[2064273]: 199.172.132.66.censys-scanner.com res <anonymous>
Jun 16 21:32:31 twilightnode nnrpd[2064273]: 199.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Jun 16 21:32:31 twilightnode nnrpd[2064273]: 199.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Jun 16 21:32:31 twilightnode nnrpd[2064273]: 199.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Jun 16 21:32:31 twilightnode nnrpd[2064273]: 199.172.132.66.censys-scanner.com no_post anonymous-read
Jun 17 02:02:52 twilightnode nnrpd[2078031]: Reading access from /usr/local/news/etc/readers.conf
Jun 17 02:02:52 twilightnode nnrpd[2078031]: Auth strategy 'localhost' does not match client. Removing.

Log entries by program:

Program nameLines%LinesSize%Size
inn 5891 69.8%958.6 KB 73.0%
innfeed 1597 18.9%214.6 KB 16.4%
innd 820 9.7%124.4 KB 9.5%
nnrpd 134 1.6%14.7 KB 1.1%
controlchan 3 0.0%0.2 KB 0.0%
TOTAL: 5 8445 100.0%1.3 MB100.0%

History cache:

ReasonCount%Count
Negative hits 6330 34.8%
Positive hits 6003 33.0%
Do not exist 5837 32.1%
Cache misses 0 0.0%
TOTAL: 4 18170 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.002 0.0%1 2.000 2.000 2.000
article cleanup00:00:00.050 0.0%5866 0.000 0.009 0.158
article logging00:00:00.321 0.0%5898 0.000 0.054 0.200
article parse00:00:00.102 0.0%7112 0.000 0.014 0.200
article write00:00:04.422 0.0%4369 0.444 1.012 3.708
data move00:00:00.055 0.0%19590 0.000 0.003 0.042
hisgrep/artcncl00:00:00.002 0.0%1 2.000 2.000 2.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:05.014 0.0%18262 0.122 0.275 1.333
history sync00:00:00.070 0.0%413 0.000 0.169 1.000
history write00:00:03.431 0.0%5866 0.150 0.585 5.073
idle24:00:43.436100.0%19504 2144.993 4432.088 22236.370
nntp read00:00:00.621 0.0%19120 0.000 0.032 0.328
overview write00:00:02.216 0.0%4369 0.149 0.507 1.875
perl filter00:00:10.636 0.0%5866 1.109 1.813 5.296
python filter00:00:00.905 0.0%5866 0.000 0.154 0.385
site send00:00:00.133 0.0%4369 0.000 0.030 0.167
TOTAL: 1724:01:11.416100.0%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.011 0.0%4347 0.000 0.003 0.091
article prepare00:00:00.003 0.0%25336 0.000 0.000 0.007
article read00:00:00.696 0.0%4346 0.000 0.160 0.667
backlog stats00:00:00.029 0.0%28145 0.000 0.001 0.009
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.213 0.0%15785 0.000 0.013 0.056
data write00:00:00.331 0.0%11497 0.000 0.029 0.172
idle23:50:39.630100.0%28145 1462.757 3049.907 8558.803
status file00:00:00.224 0.0%325 0.000 0.689 5.000
TOTAL: 923:50:41.137100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:45.036100.0%17 61.500 2649.176 10405.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.002 0.0%80 0.000 0.025 0.500
TOTAL: 300:00:45.038100.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 5 12203 4342 6433 1428 35%50:23:17
2news.sklaffkom.se 42 192 58 49 85 30%15:50:25
3localhost 25 0 0 0 0 0%00:00:00
4news.chimia.se 1 0 0 0 0 0%00:10:00
TOTAL: 4 73 12395 4400 6482 1513 35%66:23:42
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1twilightnode-out.news.weretis.net12.9 MB0.0 KB4.6 MB17.5 MB 73%3.1 KB
2news.sklaffkom.se250.4 KB0.0 KB195.1 KB445.4 KB 56%3.1 KB
3localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
4news.chimia.se0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 413.2 MB0.0 KB4.8 MB18.0 MB 73%3.1 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 16 03:00:06 - 03:59:59 123 2.8% 0.03538.1 KB 4.0% 0.15
Jun 16 04:00:00 - 04:59:59 103 2.4% 0.03289.3 KB 2.2% 0.08
Jun 16 05:00:00 - 05:59:59 78 1.8% 0.02266.3 KB 2.0% 0.07
Jun 16 06:00:00 - 06:59:59 114 2.6% 0.03333.4 KB 2.5% 0.09
Jun 16 07:00:00 - 07:59:59 121 2.8% 0.03381.4 KB 2.8% 0.11
Jun 16 08:00:00 - 08:59:59 153 3.5% 0.04413.5 KB 3.1% 0.11
Jun 16 09:00:00 - 09:59:59 241 5.5% 0.07712.6 KB 5.3% 0.20
Jun 16 10:00:00 - 10:59:59 198 4.5% 0.06497.2 KB 3.7% 0.14
Jun 16 11:00:00 - 11:59:59 170 3.9% 0.05467.8 KB 3.5% 0.13
Jun 16 12:00:00 - 12:59:59 174 4.0% 0.05446.2 KB 3.3% 0.12
Jun 16 13:00:00 - 13:59:59 186 4.3% 0.05487.0 KB 3.6% 0.14
Jun 16 14:00:00 - 14:59:59 166 3.8% 0.05455.7 KB 3.4% 0.13
Jun 16 15:00:00 - 15:59:59 207 4.7% 0.06565.7 KB 4.2% 0.16
Jun 16 16:00:00 - 16:59:59 241 5.5% 0.07746.2 KB 5.6% 0.21
Jun 16 17:00:00 - 17:59:59 361 8.3% 0.101.3 MB 9.9% 0.37
Jun 16 18:00:00 - 18:59:59 242 5.5% 0.07840.7 KB 6.3% 0.23
Jun 16 19:00:00 - 19:59:59 225 5.2% 0.06670.5 KB 5.0% 0.19
Jun 16 20:00:00 - 20:59:59 231 5.3% 0.06688.3 KB 5.1% 0.19
Jun 16 21:00:00 - 21:59:59 207 4.7% 0.06689.3 KB 5.1% 0.19
Jun 16 22:00:00 - 22:59:59 215 4.9% 0.06724.8 KB 5.4% 0.20
Jun 16 23:00:00 - 23:59:59 216 5.0% 0.06665.3 KB 5.0% 0.18
Jun 17 00:00:00 - 00:59:59 151 3.5% 0.04481.5 KB 3.6% 0.13
Jun 17 01:00:00 - 01:59:59 136 3.1% 0.04415.8 KB 3.1% 0.12
Jun 17 02:00:00 - 02:59:59 94 2.2% 0.03282.5 KB 2.1% 0.08
Jun 17 03:00:00 - 03:00:05 10 0.2% 2.0042.1 KB 0.3% 8.42
TOTAL: 23:59:59 4363 100.0% 0.0513.1 MB 100.0% 0.16
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1twilightnode-out.news.weretis.net 1443 1363 0 0 0 0 0 0 80
2news.sklaffkom.se 85 85 0 0 0 0 0 0 0
TOTAL: 2 1528 1448 0 0 0 0 0 0 80

Unwanted newsgroups [Top 20]:

NewsgroupCount
it.sport.calcio.milan 57
lada.talk 42
uk.d-i-y 41
it.hobby.cucina 39
eternal-september.talk 36
uk.legal.moderated 32
pl.soc.polityka 31
fido7.ru.military 30
nl.actueel 29
alt.prophecies.nostradamus 28
alt.food.fast-food 27
linux.debian.maint.hams 26
alt.usage.english 25
it.discussioni.commercialisti 25
dk.politik 24
nl.politiek 22
de.test 21
cn.comp 20
de.alt.folklore.computer 20
fido7.ru.home 20
TOTAL: 208 1448

Perl filter (innd) [Top 20]:

ReasonCount
User-issued cancel 30
EMP (phn path) 13
Too many newsgroups (meow) 3
EMP (md5) 1
HTML Multipart 1
TOTAL: 5 48

Miscellaneous innd statistics [Top 10]:

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

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1chimia.se 4364 4363 0 0 0 0 99%23:59:55
2sklaffkom.se 4755 784 2356 1214 0 4 16%23:59:41
3weretis.net 80 0 58 0 0 0 0%07:10:56
4sklaff.dev 0 0 0 0 0 4357 0%23:59:54
5thebunker.nu 0 0 0 0 0 4363 0%23:59:55
6usenet.blueworldhosting.com 0 0 0 0 0 4055 0%23:59:55
TOTAL: 6 9199 5147 2414 1214 0 12779 55%127:10:16
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1chimia.se13.1 MB0.0 KB13.1 MB0.2 KB/s3.1 KB23:59:55
2sklaffkom.se1.5 MB3.9 MB5.3 MB0.1 KB/s2.7 KB23:59:41
3sklaff.dev0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:54
4thebunker.nu0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:55
5usenet.blueworldhosting.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:55
6weretis.net0.0 KB0.0 KB0.0 KB0.0 KB/s007:10:56
TOTAL: 614.6 MB3.9 MB18.5 MB0.0 KB/s3.0 KB127:10:16
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1139.154.45.199.censys-scanner.com 3 00.0 KB 0 0 000:00:04
2azpdcsq7gbf8.stretchoid.com 2 00.0 KB 0 0 000:00:10
3azpdes0rles7.stretchoid.com 2 00.0 KB 0 0 000:00:10
4176.65.149.31.ptr.pfcloud.network 1 00.0 KB 0 0 000:00:14
5199.172.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
6205.210.31.214 1 00.0 KB 0 0 000:00:00
7r3-17-11.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
TOTAL: 7 11 00.0 KB 0 0 000:00:45

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

SystemConnArtsSizeGroupsPostRejElapsed
1*.stretchoid.com 4 00.0 KB 0 0 000:00:21
2*.199.censys-scanner.com 3 00.0 KB 0 0 000:00:04
3*.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
4*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
5*.ptr.pfcloud.network 1 00.0 KB 0 0 000:00:14
6unresolved 1 00.0 KB 0 0 000:00:00
TOTAL: 6 11 00.0 KB 0 0 000:00:45

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
176.65.149.31.ptr.pfcloud.network 0.001 0.018 0.00000:00:14
azpdes0rles7.stretchoid.com 0.007 0.025 0.00000:00:10
azpdcsq7gbf8.stretchoid.com 0.010 0.021 0.00000:00:10
139.154.45.199.censys-scanner.com 0.036 0.027 0.00000:00:04
199.172.132.66.censys-scanner.com 0.029 0.024 0.00000:00:01
r3-17-11.monitoring.internet-measurement.com 0.007 0.012 0.00000:00:01
205.210.31.214 0.004 0.018 0.00000:00:00
TOTAL: 7 0.094 0.145 0.00000:00:45

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

SystemConn
139.154.45.199.censys-scanner.com 1
176.65.149.31.ptr.pfcloud.network 1
199.172.132.66.censys-scanner.com 1
azpdcsq7gbf8.stretchoid.com 1
azpdes0rles7.stretchoid.com 1
TOTAL: 5 5

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

CommandCount
EHLO www.censys.io 2
MGLNDD_172.234.115.63_119 2
SSH-2.0-OpenSSH 1
TOTAL: 3 5

NNRP client timeouts [Top 20]:

SystemConnPeer
139.154.45.199.censys-scanner.com 1 2
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