Daily Usenet report for news.niel.me

Jun 20 04:15:02 -- Jun 21 04:15:02

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Jun 20 14:47:22 rpi-studio innd: filter: No Cancel-Key[sha1:auHyZbo09INaJxe5alci+EyunoY=] matches Cancel-Lock[sha256:MJslBvIAJ90dyNR2gdRUyp7XEALNOlDX871LEkKivUw=] target=<sandbb$8o7$1@dont-email.me>
Jun 20 18:24:26 rpi-studio innd: filter: No Cancel-Key[sha1:FqggY4mKb85bOe98XztCjKDICEU=] matches Cancel-Lock[SnXuOuNIpSo00P/m5An7xNFWbus=] target=<sanpvt$v3i$1@dont-email.me>
Jun 20 18:31:44 rpi-studio innd: filter: No Cancel-Key[sha1:FqggY4mKb85bOe98XztCjKDICEU=] matches Cancel-Lock[SnXuOuNIpSo00P/m5An7xNFWbus=] target=<sanpvt$v3i$1@dont-email.me>
Jun 20 22:35:54 rpi-studio innd: filter: No Cancel-Key[sha256:LLsyJIh99/dh91QfuMiO6NEqKrw=*)] matches Cancel-Lock[sha256:V5kNfbKsAdDeOePzrSsxnpVEXzw9kVQ9UdgMisjHvy0=] target=<sao8lr$6gg$1@dont-email.me>

Log entries by program:

Program nameLines%LinesSize%Size
inn 4228 42.2%727.5 KB 45.6%
innd 2398 24.0%400.1 KB 25.1%
nnrpd 1797 18.0%196.2 KB 12.3%
innfeed 1583 15.8%269.8 KB 16.9%
nocem 1 0.0%0.1 KB 0.0%
controlchan 1 0.0%0.2 KB 0.0%
TOTAL: 6 10008 100.0%1.6 MB100.0%

History cache:

ReasonCount%Count
Positive hits 21784 68.3%
Negative hits 5978 18.7%
Do not exist 4149 13.0%
Cache misses 1 0.0%
TOTAL: 4 31912 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.030 0.0%23 0.000 1.304 4.000
article cancel00:00:00.002 0.0%24 0.000 0.083 0.500
article cleanup00:00:00.083 0.0%4154 0.000 0.020 0.182
article logging00:00:00.335 0.0%4163 0.000 0.080 0.931
article parse00:00:00.841 0.0%7928 0.000 0.106 0.235
article write00:00:02.617 0.0%3576 0.200 0.732 8.000
artlog/artcncl00:00:00.001 0.0%24 0.000 0.042 0.500
artlog/artcncl/perl00:00:00.002 0.0%23 0.000 0.087 0.500
data move00:00:00.109 0.0%35399 0.000 0.003 0.016
hisgrep/artcncl00:00:00.000 0.0%24 0.000 0.000 0.000
hisgrep/artcncl/perl00:00:00.003 0.0%23 0.000 0.130 1.000
hisgrep/perl00:00:00.004 0.0%28 0.000 0.143 1.000
hishave/artcncl00:00:00.000 0.0%24 0.000 0.000 0.000
hishave/artcncl/perl00:00:00.000 0.0%23 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.476 0.0%31910 0.000 0.015 0.036
history sync00:00:00.055 0.0%401 0.000 0.137 1.000
history write00:00:00.916 0.0%4154 0.000 0.221 0.562
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:50:29.630100.0%36046 679.471 2381.114 6905.977
nntp read00:00:01.166 0.0%35437 0.000 0.033 0.093
overview write00:00:02.406 0.0%3576 0.000 0.673 3.609
perl filter00:00:20.484 0.0%4154 2.900 4.931 10.958
python filter00:00:00.014 0.0%7126 0.000 0.002 0.050
site send00:00:00.984 0.0%7156 0.000 0.138 0.500
TOTAL: 23:51:11.96023:51:00.158100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.040 0.0%3681 0.000 0.011 0.100
article prepare00:00:00.005 0.0%2600 0.000 0.002 0.125
article read00:00:00.094 0.0%528 0.000 0.178 7.000
backlog stats00:00:00.108 0.0%58536 0.000 0.002 0.012
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.761 0.0%32026 0.000 0.024 0.070
data write00:00:02.146 0.0%28412 0.039 0.076 0.144
idle23:58:40.832100.0%58536 510.480 1474.662 4364.312
status file00:00:00.434 0.0%171 1.000 2.538 10.000
TOTAL: 23:58:47.98123:58:44.420100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:30.658 71.3%1461 6.400 20.984 10026.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.101 0.2%3311 0.000 0.031 0.182
readart00:00:00.000 0.0%3 0.000 0.000 0.000
TOTAL: 00:00:43.02500:00:30.759 71.5%----

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 3507 1683 1738 86 47%22:46:18
2feed-out.gegeweb.org 2 2708 751 1955 2 27%23:59:02
3usenet.pasdenom.info 4 5767 713 5049 5 12%47:01:58
4nntp.terraraq.uk 3 2883 290 2296 297 10%22:12:32
5i2pn.org 1 3478 64 3272 142 1%24:03:11
6news.nntp4.net 9 832 42 788 2 5%08:05:35
7gegeweb-out.news.tnetconsulting.net 2 3483 28 3421 34 0%23:59:05
8localhost 25 0 0 0 0 0%00:00:00
TOTAL: 8 48 22658 3571 18519 568 15%172:07:41
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news-out.aioe.org5.0 MB0.0 KB335.1 KB5.3 MB 93%3.1 KB
2nntp.terraraq.uk2.9 MB0.0 KB5.7 MB8.6 MB 33%15.0 KB
3feed-out.gegeweb.org2.3 MB0.0 KB2.1 KB2.3 MB 99%3.1 KB
4usenet.pasdenom.info1.8 MB0.0 KB14.1 KB1.8 MB 99%2.6 KB
5i2pn.org295.7 KB0.0 KB268.3 KB564.0 KB 52%2.7 KB
6news.nntp4.net254.1 KB0.0 KB3.9 KB258.0 KB 98%5.9 KB
7gegeweb-out.news.tnetconsulting.net173.0 KB0.0 KB114.3 KB287.3 KB 60%4.6 KB
8localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 812.7 MB0.0 KB6.4 MB19.1 MB 66%4.7 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 20 04:15:02 - 04:59:59 80 2.2% 0.03296.0 KB 2.0% 0.11
Jun 20 05:00:00 - 05:59:59 77 2.1% 0.02448.1 KB 3.1% 0.12
Jun 20 06:00:00 - 06:59:59 99 2.8% 0.03416.8 KB 2.8% 0.12
Jun 20 07:00:00 - 07:59:59 81 2.3% 0.02447.8 KB 3.1% 0.12
Jun 20 08:00:00 - 08:59:59 103 2.9% 0.03322.5 KB 2.2% 0.09
Jun 20 09:00:00 - 09:59:59 79 2.2% 0.02375.1 KB 2.6% 0.10
Jun 20 10:00:00 - 10:59:59 134 3.7% 0.04434.2 KB 3.0% 0.12
Jun 20 11:00:00 - 11:59:59 128 3.6% 0.04384.8 KB 2.6% 0.11
Jun 20 12:00:00 - 12:59:59 196 5.5% 0.05501.5 KB 3.4% 0.14
Jun 20 13:00:00 - 13:59:59 95 2.6% 0.03234.9 KB 1.6% 0.07
Jun 20 14:00:00 - 14:59:59 125 3.5% 0.03278.4 KB 1.9% 0.08
Jun 20 15:00:00 - 15:59:59 174 4.8% 0.05538.0 KB 3.7% 0.15
Jun 20 16:00:00 - 16:59:59 187 5.2% 0.05472.1 KB 3.2% 0.13
Jun 20 17:00:00 - 17:59:59 214 6.0% 0.06703.3 KB 4.8% 0.20
Jun 20 18:00:00 - 18:59:59 220 6.1% 0.061.9 MB 13.6% 0.55
Jun 20 19:00:00 - 19:59:59 230 6.4% 0.06680.8 KB 4.6% 0.19
Jun 20 20:00:00 - 20:59:59 254 7.1% 0.071.2 MB 8.4% 0.34
Jun 20 21:00:00 - 21:59:59 246 6.8% 0.07938.5 KB 6.4% 0.26
Jun 20 22:00:00 - 22:59:59 197 5.5% 0.051.7 MB 11.6% 0.47
Jun 20 23:00:00 - 23:59:59 137 3.8% 0.04404.6 KB 2.8% 0.11
Jun 21 00:00:00 - 00:59:59 232 6.5% 0.06812.4 KB 5.5% 0.23
Jun 21 01:00:00 - 01:59:59 91 2.5% 0.03311.0 KB 2.1% 0.09
Jun 21 02:00:00 - 02:59:59 104 2.9% 0.03374.6 KB 2.6% 0.10
Jun 21 03:00:00 - 03:59:59 86 2.4% 0.02291.4 KB 2.0% 0.08
Jun 21 04:00:00 - 04:15:02 25 0.7% 0.0382.7 KB 0.6% 0.09
TOTAL: 24:00:00 3594 100.0% 0.0414.3 MB 100.0% 0.17
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1nntp.terraraq.uk 304 0 0 0 0 0 0 0 304
2i2pn.org 152 0 0 0 0 0 0 0 152
3news-out.aioe.org 98 1 0 0 0 0 0 0 97
4news.nntp4.net 16 0 0 0 0 0 0 0 16
5gegeweb-out.news.tnetconsulting.net 10 0 0 0 0 0 0 0 10
6usenet.pasdenom.info 5 0 0 0 0 0 0 0 5
7feed-out.gegeweb.org 2 0 0 0 0 0 0 0 2
TOTAL: 7 587 1 0 0 0 0 0 0 586

Unwanted newsgroups [Top 20]:

NewsgroupCount
news.test 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
Too many newsgroups 195
Bad path (news.giganews.com.POSTED) 142
EMP (phn nph) 125
EMP (md5) 77
EMP (phl) 24
User-issued spam cancel 9
No Cancel-Key matches Cancel-Lock. target=<sanpvt$v3i$1@dont-email.me> 2
Cancel of <sao6gm$v2l$1@pi2.pasdenom.info> without Cancel-Key 1
No Cancel-Key matches Cancel-Lock. target=<sandbb$8o7$1@dont-email.me> 1
Cancels abusifs 1
TOTAL: 10 577

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
news-out.aioe.org 9
TOTAL: 1 9
TOTAL: 1 9

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1i2pn.org 6435 241 3051 219 0 17 3%23:59:57
2news.tnetconsulting.net 6242 87 3447 50 0 12 1%23:59:57
3news.pasdenom.info 4524 27 2818 5 0 9 0%23:59:57
4news.gegeweb.org 2921 13 2829 0 0 0 0%23:59:57
5news.nntp4.net 3957 4 3500 0 0 40 0%23:59:57
6nntp.terraraq.uk 2227 4 2010 0 0 12 0%23:59:55
7news.aioe.org 1648 0 1527 0 0 0 0%23:58:20
TOTAL: 7 27954 376 19182 274 0 90 1%167:58:00
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1i2pn.org518.4 KB481.6 KB1000.1 KB0.0 KB/s2.2 KB23:59:57
2news.tnetconsulting.net243.1 KB124.4 KB367.5 KB0.0 KB/s2.7 KB23:59:57
3news.gegeweb.org119.4 KB0.0 KB119.4 KB0.0 KB/s9.2 KB23:59:57
4news.pasdenom.info99.1 KB20.7 KB119.8 KB0.0 KB/s3.7 KB23:59:57
5news.nntp4.net12.6 KB0.0 KB12.6 KB0.0 KB/s3.1 KB23:59:57
6nntp.terraraq.uk8.1 KB0.0 KB8.1 KB0.0 KB/s2.0 KB23:59:55
7news.aioe.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:20
TOTAL: 71000.7 KB626.7 KB1.6 MB0.0 KB/s2.5 KB167:58:00
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1mbp-gniel.niel.me 1 11.9 KB 1 0 000:00:10
TOTAL: 1 1 11.9 KB 1 0 000:00:42

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.niel.me 1 11.9 KB 1 0 000:00:10
2*.gegeweb.eu 288 00.0 KB 0 0 000:00:20
3unresolved 1 00.0 KB 0 0 000:00:10
4*.ch1.censys-scanner.com 1 00.0 KB 0 0 000:00:00
TOTAL: 4 291 11.9 KB 1 0 000:00:42

NNRP auth users [Top 20]:

UserConn
mailman 288
TOTAL: 1 288

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
mail.gegeweb.eu 8.445 8.801 3.09900:00:20
143.110.247.113 0.029 0.029 0.00000:00:10
mbp-gniel.niel.me 0.048 0.024 0.00000:00:10
scanner-06.ch1.censys-scanner.com 0.046 0.027 0.00000:00:00
TOTAL: 4 8.568 8.881 3.09900:00:42

Curious NNRP server explorers [Top 20]:

SystemConn
mail.gegeweb.eu 288
143.110.247.113 1
scanner-06.ch1.censys-scanner.com 1
TOTAL: 3 290

NNRP unrecognized commands (by host):

SystemConn
scanner-06.ch1.censys-scanner.com 1
TOTAL: 1 1

NNRP unrecognized commands (by command):

CommandCount
EHLO www.censys.io 1
TOTAL: 1 1

Newsgroup request counts (by category):

CategoryCountPct
1fr 1100.0%
TOTAL: 1 1100.0%

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

NewsgroupCount
1fr.usenet.forums.evolution 1
TOTAL: 1 1
innreport from INN 2.6.4 based on work (c) 1996-1999 by Fabien Tassin <fta@sofaraway.org>
Local contact: usenet@niel.me