Daily Usenet report for news.niel.me

May 13 04:15:01 -- May 14 04:15:01

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

May 13 17:10:37 rpi-studio innd: filter: No Cancel-Key[sha1:xT0YgqlyPoQT/hvhhPOn+Tagf8w=] matches Cancel-Lock[sha1:snEXQGIfb9pBifxCa/HeNj3sJfs=] target=<627e740a$0$18750$426a74cc@news.free.fr>
May 14 01:30:02 rpi-studio innd: filter: No Cancel-Key[sha1:FqQmxgXIJks0zEDC92pMGz668Ns=] matches Cancel-Lock[sha1:gC0+ggzVVYjox8P7Gd+WKbTBCcU=] target=<de-gruppen-finden-07.05.2022@dnqmail.priggish.de>

Log entries by program:

Program nameLines%LinesSize%Size
inn 4213 42.1%801.8 KB 47.7%
innd 2433 24.3%430.4 KB 25.6%
innfeed 1722 17.2%289.5 KB 17.2%
nnrpd 1641 16.4%159.7 KB 9.5%
nocem 5 0.0%0.5 KB 0.0%
controlchan 1 0.0%0.0 KB 0.0%
TOTAL: 6 10015 100.0%1.6 MB100.0%

History cache:

ReasonCount%Count
Positive hits 24127 63.6%
Negative hits 9701 25.6%
Do not exist 4127 10.9%
Cache misses 4 0.0%
TOTAL: 4 37959 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.023 0.0%15 0.000 1.533 5.000
article cancel00:00:00.001 0.0%19 0.000 0.053 1.000
article cleanup00:00:00.095 0.0%4136 0.000 0.023 0.156
article logging00:00:00.319 0.0%4172 0.000 0.076 2.200
article parse00:00:00.857 0.0%9678 0.000 0.089 0.350
article write00:00:07.795 0.0%3665 0.148 2.127 467.111
artlog/artcncl00:00:00.001 0.0%17 0.000 0.059 1.000
artlog/artcncl/perl00:00:00.002 0.0%15 0.000 0.133 1.000
data move00:00:00.123 0.0%43190 0.000 0.003 0.017
hisgrep/artcncl00:00:00.000 0.0%18 0.000 0.000 0.000
hisgrep/artcncl/perl00:00:00.000 0.0%15 0.000 0.000 0.000
hisgrep/perl00:00:00.001 0.0%26 0.000 0.038 1.000
hishave/artcncl00:00:00.000 0.0%19 0.000 0.000 0.000
hishave/artcncl/perl00:00:00.000 0.0%15 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.519 0.0%38032 0.000 0.014 0.039
history sync00:00:00.043 0.0%407 0.000 0.106 0.667
history write00:00:01.026 0.0%4136 0.000 0.248 1.447
hiswrite/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
idle24:00:26.465 99.9%43828 789.599 1971.946 8955.955
nntp read00:00:01.378 0.0%43180 0.000 0.032 0.069
overview write00:00:03.295 0.0%3665 0.000 0.899 70.222
perl filter00:00:18.777 0.0%4136 2.373 4.540 8.756
python filter00:00:00.007 0.0%4136 0.000 0.002 0.091
site send00:00:01.312 0.0%7403 0.000 0.177 0.500
TOTAL: 24:01:15.53524:01:02.039100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.041 0.0%3693 0.000 0.011 0.125
article prepare00:00:00.000 0.0%180 0.000 0.000 0.000
article read00:00:00.010 0.0%40 0.000 0.250 7.000
backlog stats00:00:00.094 0.0%52381 0.000 0.002 0.012
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.742 0.0%32457 0.000 0.023 0.068
data write00:00:01.826 0.0%28849 0.021 0.063 0.112
idle23:49:50.655100.0%52381 683.596 1637.820 5953.158
status file00:00:00.572 0.0%182 1.000 3.143 9.769
TOTAL: 23:49:58.35223:49:53.940100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:41.961 27.0%1471 16.600 28.525 9464.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.112 0.1%3413 0.000 0.033 1.000
readart00:00:00.004 0.0%2 2.000 2.000 2.000
TOTAL: 00:02:35.23900:00:42.077 27.1%----

Control commands to INND:

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1news-out.aioe.org 2 3639 1658 1912 69 45%22:44:33
2i2pn.org 1 3761 1039 2563 159 27%23:18:50
3feed-out.gegeweb.org 3 2468 389 2079 0 15%23:58:20
4usenet.pasdenom.info 2 3055 214 2826 15 7%25:13:09
5gegeweb-out.news.tnetconsulting.net 2 3818 167 3643 8 4%23:59:49
6news.samoylyk.net 2 6451 79 6359 13 1%40:26:10
7nntp.terraraq.uk 2 4619 60 4496 63 1%29:23:16
8news.nntp4.net 1 4271 31 4100 140 0%25:20:58
9localhost 27 0 0 0 0 0%00:00:00
TOTAL: 9 42 32082 3637 27978 467 11%214:25:05
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news-out.aioe.org5.5 MB0.0 KB205.3 KB5.7 MB 96%3.4 KB
2i2pn.org3.2 MB0.0 KB609.7 KB3.8 MB 84%3.2 KB
3news.samoylyk.net3.2 MB0.0 KB14.6 KB3.2 MB 99%35.6 KB
4gegeweb-out.news.tnetconsulting.net1.5 MB0.0 KB19.6 KB1.6 MB 98%9.1 KB
5news.nntp4.net1.2 MB0.0 KB4.1 MB5.3 MB 22%31.7 KB
6feed-out.gegeweb.org1.0 MB0.0 KB0.0 KB1.0 MB100%2.7 KB
7usenet.pasdenom.info614.8 KB0.0 KB58.2 KB673.1 KB 91%2.9 KB
8nntp.terraraq.uk529.1 KB0.0 KB272.1 KB801.1 KB 66%6.5 KB
9localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 916.7 MB0.0 KB5.2 MB22.0 MB 76%5.5 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 13 04:15:01 - 04:59:59 81 2.2% 0.03361.8 KB 2.2% 0.13
May 13 05:00:00 - 05:59:59 73 2.0% 0.02309.5 KB 1.9% 0.09
May 13 06:00:00 - 06:59:59 82 2.2% 0.02582.4 KB 3.5% 0.16
May 13 07:00:00 - 07:59:59 102 2.8% 0.03290.7 KB 1.8% 0.08
May 13 08:00:00 - 08:59:59 115 3.1% 0.03808.0 KB 4.9% 0.22
May 13 09:00:00 - 09:59:59 117 3.2% 0.03463.8 KB 2.8% 0.13
May 13 10:00:00 - 10:59:59 96 2.6% 0.03318.6 KB 1.9% 0.09
May 13 11:00:00 - 11:59:59 95 2.6% 0.03411.8 KB 2.5% 0.11
May 13 12:00:00 - 12:59:59 128 3.5% 0.04360.3 KB 2.2% 0.10
May 13 13:00:00 - 13:59:59 195 5.3% 0.05656.7 KB 4.0% 0.18
May 13 14:00:00 - 14:59:59 132 3.6% 0.04426.5 KB 2.6% 0.12
May 13 15:00:00 - 15:59:59 145 3.9% 0.04671.8 KB 4.1% 0.19
May 13 16:00:00 - 16:59:59 154 4.2% 0.041.3 MB 8.3% 0.38
May 13 17:00:00 - 17:59:59 194 5.3% 0.051.0 MB 6.4% 0.29
May 13 18:00:00 - 18:59:59 193 5.3% 0.051.1 MB 6.9% 0.32
May 13 19:00:00 - 19:59:59 255 6.9% 0.07755.8 KB 4.6% 0.21
May 13 20:00:00 - 20:59:59 231 6.3% 0.06656.1 KB 4.0% 0.18
May 13 21:00:00 - 21:59:59 243 6.6% 0.071.6 MB 9.7% 0.45
May 13 22:00:00 - 22:59:59 253 6.9% 0.07929.3 KB 5.6% 0.26
May 13 23:00:00 - 23:59:59 200 5.4% 0.06677.4 KB 4.1% 0.19
May 14 00:00:00 - 00:59:59 154 4.2% 0.04656.8 KB 4.0% 0.18
May 14 01:00:00 - 01:59:59 144 3.9% 0.04907.8 KB 5.5% 0.25
May 14 02:00:00 - 02:59:59 126 3.4% 0.04443.2 KB 2.7% 0.12
May 14 03:00:00 - 03:59:59 131 3.6% 0.04562.5 KB 3.4% 0.16
May 14 04:00:00 - 04:15:01 35 1.0% 0.04102.8 KB 0.6% 0.11
TOTAL: 24:00:00 3674 100.0% 0.0416.1 MB 100.0% 0.19
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1i2pn.org 161 0 0 0 0 0 0 0 161
2news.nntp4.net 138 0 0 0 0 0 0 0 138
3news-out.aioe.org 97 1 0 0 0 0 0 0 96
4nntp.terraraq.uk 47 0 0 0 0 0 0 0 47
5gegeweb-out.news.tnetconsulting.net 30 0 0 0 0 0 0 0 30
6news.samoylyk.net 21 0 0 0 0 0 0 0 21
7usenet.pasdenom.info 12 0 0 0 0 0 0 0 12
8feed-out.gegeweb.org 1 0 0 0 0 0 0 0 1
TOTAL: 8 507 1 0 0 0 0 0 0 506

Unwanted newsgroups [Top 20]:

NewsgroupCount
news.test 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
Bad path (news.giganews.com.POSTED) 210
EMP (phn nph) 123
EMP (md5) 86
EMP (phl) 40
HTML Multipart 1
Cancel of <t5mdrj$she$4@dont-email.me> without Cancel-Key 1
Cancel of <t5mfid$917$4@dont-email.me> without Cancel-Key 1
Cancel of <t5lsjn$n71$1@dont-email.me> without Cancel-Key 1
Cancel of <t5meca$1ah$1@dont-email.me> without Cancel-Key 1
No Cancel-Key matches Cancel-Lock. target=<627e740a$0$18750$426a74cc@news.free.fr> 1
Cancel of <t5m503$mg7$11@dont-email.me> without Cancel-Key 1
Cancel of <t5mfru$917$10@dont-email.me> without Cancel-Key 1
Cancel of <t5llu4$487$1@dont-email.me> without Cancel-Key 1
Cancel of non-existing ID <je7lutFet7cU1@mid.individual.net> 1
Cancel of <t5m4iv$mg7$4@dont-email.me> without Cancel-Key 1
TOTAL: 15 470

NoCeM on Spool:

IdGoodBadUniqueTotal
bleachbot@httrack.com3033
TOTAL: 13033

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
news-out.aioe.org 28
news.samoylyk.net 6
i2pn.org 1
feed-out.gegeweb.org 1
TOTAL: 4 36
TOTAL: 1 36

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tnetconsulting.net 5977 17 3656 1 0 20 0%23:59:40
2i2pn.org 4814 15 2530 1 0 24 0%23:59:41
3news.gegeweb.org 3342 5 3281 1 0 0 0%23:58:41
4news.aioe.org 1462 2 1407 0 0 0 0%23:57:50
5nntp.terraraq.uk 2682 1 2626 0 0 0 0%23:59:32
6news.samoylyk.net 3731 0 3622 1 0 0 0%23:58:49
7news.nntp4.net 3940 0 3645 0 0 0 0%23:59:55
8news.pasdenom.info 3321 0 3282 1 0 0 0%23:59:55
TOTAL: 8 29269 40 24049 5 0 44 0%191:54:03
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tnetconsulting.net57.3 KB35.4 KB92.8 KB0.0 KB/s5.2 KB23:59:40
2i2pn.org40.7 KB35.4 KB76.2 KB0.0 KB/s4.8 KB23:59:41
3news.gegeweb.org12.0 KB3.4 KB15.4 KB0.0 KB/s2.6 KB23:58:41
4news.aioe.org6.7 KB0.0 KB6.7 KB0.0 KB/s3.4 KB23:57:50
5nntp.terraraq.uk4.7 KB0.0 KB4.7 KB0.0 KB/s4.7 KB23:59:32
6news.samoylyk.net0.0 KB1.7 KB1.7 KB0.0 KB/s1.7 KB23:58:49
7news.nntp4.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:55
8news.pasdenom.info0.0 KB2.3 KB2.3 KB0.0 KB/s2.3 KB23:59:55
TOTAL: 8121.5 KB78.2 KB199.7 KB0.0 KB/s4.4 KB191:54:03
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
12a0e:e701:1154:0:b521:8a38:3c1c:eb 1 23.4 KB 1 0 000:00:52
TOTAL: 1 1 23.4 KB 1 0 000:02:34

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1? 1 23.4 KB 1 0 000:00:52
2*.gegeweb.eu 288 00.0 KB 0 0 000:01:24
3unresolved 21 00.0 KB 0 0 000:00:17
TOTAL: 3 310 23.4 KB 1 0 000:02:34

NNRP auth users [Top 20]:

UserConn
mailman 288
gerald 1
TOTAL: 2 289

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
mail.gegeweb.eu 7.463 8.509 2.32900:01:24
2a0e:e701:1154:0:b521:8a38:3c1c:eb 0.084 0.023 0.00600:00:52
192.241.222.163 0.035 0.012 0.00000:00:09
208.100.26.233 0.170 0.082 0.00000:00:04
88.165.5.23 0.301 0.258 0.00000:00:03
TOTAL: 5 8.053 8.884 2.33500:02:34

Curious NNRP server explorers [Top 20]:

SystemConn
mail.gegeweb.eu 288
208.100.26.233 10
88.165.5.23 10
192.241.222.163 1
TOTAL: 4 309

NNRP gethostbyaddr failures [Top 20]:

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

Newsgroup request counts (by category):

CategoryCountPct
1news 2100.0%
TOTAL: 1 2100.0%

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

NewsgroupCount
1news.admin.peering 2
TOTAL: 1 2