Daily Usenet report for news.niel.me

Nov 12 04:15:01 -- Nov 13 04:15:02

Log entries by program:

Program nameLines%LinesSize%Size
inn 5038 40.2%867.9 KB 44.9%
innd 3535 28.2%532.5 KB 27.6%
nnrpd 2386 19.0%265.7 KB 13.8%
innfeed 1570 12.5%265.3 KB 13.7%
nocem 1 0.0%0.1 KB 0.0%
controlchan 1 0.0%0.2 KB 0.0%
TOTAL: 6 12531 100.0%1.9 MB100.0%

History cache:

ReasonCount%Count
Positive hits 23338 64.6%
Negative hits 7773 21.5%
Do not exist 4983 13.8%
Cache misses 5 0.0%
TOTAL: 4 36099 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.017 0.0%11 1.000 1.545 5.000
article cancel00:00:00.004 0.0%11 0.000 0.364 1.000
article cleanup00:00:00.104 0.0%4992 0.000 0.021 0.100
article logging00:00:00.373 0.0%5016 0.000 0.074 1.733
article parse00:00:00.879 0.0%9064 0.000 0.097 0.217
article write00:00:02.837 0.0%4395 0.083 0.646 3.320
artlog/artcncl00:00:00.001 0.0%11 0.000 0.091 0.500
artlog/artcncl/perl00:00:00.000 0.0%11 0.000 0.000 0.000
data move00:00:00.101 0.0%40547 0.000 0.002 0.017
hisgrep/artcncl00:00:00.000 0.0%11 0.000 0.000 0.000
hisgrep/artcncl/perl00:00:00.000 0.0%11 0.000 0.000 0.000
hisgrep/perl00:00:00.000 0.0%13 0.000 0.000 0.000
hishave/artcncl00:00:00.001 0.0%11 0.000 0.091 1.000
hishave/artcncl/perl00:00:00.000 0.0%11 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.537 0.0%36140 0.000 0.015 0.054
history sync00:00:00.065 0.0%407 0.000 0.160 6.333
history write00:00:01.052 0.0%4992 0.050 0.211 0.485
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle24:00:22.472 99.9%41183 701.227 2098.499 9683.274
nntp read00:00:01.353 0.0%40318 0.008 0.034 0.087
overview write00:00:02.644 0.0%4395 0.077 0.602 6.500
perl filter00:00:22.141 0.0%4992 2.204 4.435 10.045
python filter00:00:00.016 0.0%4994 0.000 0.003 0.077
site send00:00:00.934 0.0%8794 0.000 0.106 0.452
TOTAL: 24:01:08.90724:00:55.531100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.053 0.0%4568 0.000 0.012 0.083
article prepare00:00:00.000 0.0%636 0.000 0.000 0.000
article read00:00:00.028 0.0%138 0.000 0.203 5.000
backlog stats00:00:00.123 0.0%66368 0.000 0.002 0.011
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.888 0.0%37598 0.000 0.024 0.067
data write00:00:02.358 0.0%33139 0.015 0.071 0.119
idle23:50:05.257100.0%66368 589.771 1292.871 4059.561
status file00:00:00.419 0.0%176 1.000 2.381 15.000
TOTAL: 23:50:12.74223:50:09.126100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.000 0.0%2 0.000 0.000 0.000
idle00:15:02.457 88.2%1380 3.500 653.954 8325.793
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.128 0.0%3497 0.000 0.037 0.667
readart00:00:00.066 0.0%66 0.600 1.000 8.500
TOTAL: 00:17:03.71800:15:02.651 88.2%----

Control commands to INND:

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1news-out.aioe.org 2 4550 2887 1520 143 63%24:11:02
2news.nntp4.net 1 5995 585 5317 93 9%30:38:30
3feed-out.gegeweb.org 290 2946 413 2512 21 14%23:56:27
4i2pn.org 2 4974 350 4571 53 7%26:12:56
5nntp.terraraq.uk 2 4701 282 4043 376 5%26:28:38
6usenet.pasdenom.info 1 6752 179 6567 6 2%47:54:31
7gegeweb-out.news.tnetconsulting.net 2 4234 19 4212 3 0%23:56:13
8localhost 27 2 2 0 0 100%00:00:00
TOTAL: 8 327 34154 4717 28742 695 13%203:18:17
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news-out.aioe.org9.1 MB0.0 KB455.3 KB9.6 MB 95%3.2 KB
2news.nntp4.net4.8 MB0.0 KB227.1 KB5.0 MB 95%7.6 KB
3nntp.terraraq.uk4.1 MB0.0 KB1.5 MB5.6 MB 72%8.7 KB
4i2pn.org1.3 MB0.0 KB217.9 KB1.6 MB 86%3.9 KB
5feed-out.gegeweb.org923.2 KB0.0 KB22.7 KB945.9 KB 97%2.2 KB
6usenet.pasdenom.info393.7 KB0.0 KB8.0 KB401.7 KB 97%2.2 KB
7gegeweb-out.news.tnetconsulting.net27.6 KB0.0 KB6.9 KB34.5 KB 80%1.6 KB
8localhost3.7 KB0.0 KB0.0 KB3.7 KB100%1.9 KB
TOTAL: 820.6 MB0.0 KB2.4 MB23.1 MB 89%4.4 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Nov 12 04:15:01 - 04:59:59 95 2.2% 0.04419.6 KB 2.1% 0.16
Nov 12 05:00:00 - 05:59:59 81 1.8% 0.02298.4 KB 1.5% 0.08
Nov 12 06:00:00 - 06:59:59 93 2.1% 0.03560.5 KB 2.8% 0.16
Nov 12 07:00:00 - 07:59:59 92 2.1% 0.03392.4 KB 2.0% 0.11
Nov 12 08:00:00 - 08:59:59 163 3.7% 0.052.4 MB 12.7% 0.70
Nov 12 09:00:00 - 09:59:59 170 3.9% 0.05501.2 KB 2.5% 0.14
Nov 12 10:00:00 - 10:59:59 197 4.5% 0.05621.9 KB 3.2% 0.17
Nov 12 11:00:00 - 11:59:59 155 3.5% 0.04934.0 KB 4.7% 0.26
Nov 12 12:00:00 - 12:59:59 130 3.0% 0.04824.8 KB 4.2% 0.23
Nov 12 13:00:00 - 13:59:59 153 3.5% 0.04501.7 KB 2.5% 0.14
Nov 12 14:00:00 - 14:59:59 185 4.2% 0.05815.6 KB 4.1% 0.23
Nov 12 15:00:00 - 15:59:59 243 5.5% 0.07779.7 KB 4.0% 0.22
Nov 12 16:00:00 - 16:59:59 197 4.5% 0.051.3 MB 6.7% 0.37
Nov 12 17:00:00 - 17:59:59 259 5.9% 0.07791.2 KB 4.0% 0.22
Nov 12 18:00:00 - 18:59:59 244 5.5% 0.07792.0 KB 4.0% 0.22
Nov 12 19:00:00 - 19:59:59 248 5.6% 0.07685.8 KB 3.5% 0.19
Nov 12 20:00:00 - 20:59:59 199 4.5% 0.06546.3 KB 2.8% 0.15
Nov 12 21:00:00 - 21:59:59 275 6.3% 0.081.4 MB 7.2% 0.39
Nov 12 22:00:00 - 22:59:59 316 7.2% 0.091.1 MB 5.6% 0.31
Nov 12 23:00:00 - 23:59:59 230 5.2% 0.06869.4 KB 4.4% 0.24
Nov 13 00:00:00 - 00:59:59 169 3.8% 0.05563.4 KB 2.9% 0.16
Nov 13 01:00:00 - 01:59:59 222 5.0% 0.061.1 MB 5.7% 0.31
Nov 13 02:00:00 - 02:59:59 123 2.8% 0.03801.6 KB 4.1% 0.22
Nov 13 03:00:00 - 03:59:59 124 2.8% 0.03419.5 KB 2.1% 0.12
Nov 13 04:00:00 - 04:15:02 34 0.8% 0.04104.4 KB 0.5% 0.12
TOTAL: 24:00:01 4397 100.0% 0.0519.2 MB 100.0% 0.23
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1nntp.terraraq.uk 320 0 0 0 0 0 0 0 320
2news-out.aioe.org 154 1 0 0 0 0 0 0 153
3news.nntp4.net 69 0 0 0 0 0 0 0 69
4i2pn.org 51 0 0 0 0 0 0 0 51
5feed-out.gegeweb.org 21 0 0 0 0 0 0 0 21
6usenet.pasdenom.info 4 0 0 0 0 0 0 0 4
TOTAL: 6 619 1 0 0 0 0 0 0 618

Unwanted newsgroups [Top 20]:

NewsgroupCount
news.test 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
Too many newsgroups 199
EMP (phn nph) 124
Bad path (news.giganews.com.POSTED) 123
EMP (md5) 75
User-issued spam cancel 57
EMP (phl) 13
Cancel of <smlf6o$lfr$1@shakotay.alphanet.ch> without Cancel-Key 1
HTML Multipart 1
Cancel of <smlkqp$mej$1@dont-email.me> without Cancel-Key 1
TOTAL: 9 594

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
news-out.aioe.org 23
news.nntp4.net 1
TOTAL: 2 24
TOTAL: 1 24

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tnetconsulting.net 8284 33 4350 9 0 82 0%23:59:57
2i2pn.org 7965 28 4064 28 0 93 0%23:59:28
3news.gegeweb.org 4020 15 3903 14 0 0 0%23:59:39
4news.pasdenom.info 4941 7 3658 19 0 0 0%23:59:57
5news.aioe.org 1482 3 1313 0 0 18 0%23:44:16
6news.nntp4.net 4248 3 3907 0 0 0 0%23:59:57
7nntp.terraraq.uk 2724 0 2699 0 0 0 0%23:59:57
TOTAL: 7 33664 89 23894 70 0 193 0%167:43:11
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tnetconsulting.net185.8 KB22.3 KB208.0 KB0.0 KB/s5.0 KB23:59:57
2news.gegeweb.org88.7 KB44.9 KB133.6 KB0.0 KB/s4.6 KB23:59:39
3i2pn.org86.6 KB51.8 KB138.4 KB0.0 KB/s2.5 KB23:59:28
4news.nntp4.net36.0 KB0.0 KB36.0 KB0.0 KB/s12.0 KB23:59:57
5news.pasdenom.info14.7 KB1.5 MB1.5 MB0.0 KB/s60.6 KB23:59:57
6news.aioe.org5.8 KB0.0 KB5.8 KB0.0 KB/s1.9 KB23:44:16
7nntp.terraraq.uk0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:57
TOTAL: 7417.6 KB1.6 MB2.0 MB0.0 KB/s13.2 KB167:43:11
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1108b-gniel 2 3676.6 KB 10 2 000:14:27
TOTAL: 1 2 3676.6 KB 10 2 000:17:03

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1? 2 3676.6 KB 10 2 000:14:27
2*.ip.linodeusercontent.com 97 00.0 KB 0 0 000:00:20
3*.gegeweb.eu 288 00.0 KB 0 0 000:02:08
4unresolved 15 00.0 KB 0 0 000:00:06
5*.ip-145-239-154.eu 4 00.0 KB 0 0 000:00:01
TOTAL: 5 406 3676.6 KB 10 2 000:17:03

NNRP auth users [Top 20]:

UserConn
mailman 288
TOTAL: 1 288

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
108b-gniel 0.155 0.107 0.00000:14:27
sys.gegeweb.eu 8.004 9.014 3.03100:02:08
88-80-186-144.ip.linodeusercontent.com 2.472 1.876 0.00000:00:20
208.100.26.235 0.154 0.192 0.00000:00:02
208.100.26.232 0.052 0.064 0.00000:00:01
212.90.61.180 0.042 0.034 0.00000:00:01
ns31410049.ip-145-239-154.eu 0.044 0.059 0.00000:00:00
167.94.138.116 0.026 0.044 0.00000:00:00
ns31410046.ip-145-239-154.eu 0.050 0.052 0.00000:00:00
80.66.76.28 0.038 0.028 0.00000:00:00
TOTAL: 10 11.037 11.470 3.03100:17:03

Curious NNRP server explorers [Top 20]:

SystemConn
sys.gegeweb.eu 288
88-80-186-144.ip.linodeusercontent.com 97
208.100.26.235 10
208.100.26.232 2
ns31410049.ip-145-239-154.eu 2
ns31410046.ip-145-239-154.eu 2
167.94.138.116 1
212.90.61.180 1
80.66.76.28 1
TOTAL: 9 404

NNRP gethostbyaddr failures [Top 20]:

SystemConn
? (can't getpeername) 20
TOTAL: 1 20

NNRP unrecognized commands (by host):

SystemConn
ns31410049.ip-145-239-154.eu 1
ns31410046.ip-145-239-154.eu 1
167.94.138.116 1
TOTAL: 3 3

NNRP unrecognized commands (by command):

CommandCount
#026#003 2
EHLO www.censys.io 1
TOTAL: 2 3

Newsgroup request counts (by category):

CategoryCountPct
1fr 30 83.3%
2grisbi 6 16.7%
TOTAL: 2 36100.0%

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

NewsgroupCount
1fr.usenet.distribution 19
2fr.comp.usenet.lecteurs-de-news 6
3grisbi.user.french 5
4fr.usenet.forums.evolution 2
5fr.comp.usenet.serveurs 1
6fr.comp.sys.mac.programmation 1
7grisbi.test 1
8fr.usenet.divers 1
TOTAL: 8 36