Daily Usenet report for news.niel.me

Dec 6 04:15:01 -- Dec 7 04:15:01

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Dec 6 11:41:08 rpi-studio innd: filter: No Cancel-Key[sha1:SZPrXZBJgBiKNYN5wwUZvKxFAlU=#015#012 sha256:NbEfG42fP1Z4yr5WZxFFWbXSHjUCHWXDBgSXNWGNv1o=] matches Cancel-Lock[sha1:5wlqkepmnd3x8XpOBArIO++g6z4=] target=<BOVzIz8Y4V8@news.spitfire-nntp.fr>
Dec 6 12:18:00 rpi-studio innd: filter: No Cancel-Key[sha1:N5HhkMaMzsB4PqyIqdcYWkytMD8=#015#012 sha256:hM1OGBkgT0u3TA+A31v7/nTZPIfs3TBBdo4LH9WGiUQ=] matches Cancel-Lock[sha1:I1D0ioogFhvGBCc1WGqzw40bobw=] target=<C6QKvd0NdHw@news.spitfire-nntp.fr>

Log entries by program:

Program nameLines%LinesSize%Size
inn 4325 43.6%829.0 KB 48.9%
innd 2408 24.2%404.4 KB 23.9%
innfeed 1720 17.3%322.7 KB 19.1%
nnrpd 1471 14.8%137.0 KB 8.1%
nocem 5 0.1%0.4 KB 0.0%
controlchan 1 0.0%0.2 KB 0.0%
TOTAL: 6 9930 100.0%1.7 MB100.0%

History cache:

ReasonCount%Count
Positive hits 28642 61.5%
Negative hits 13636 29.3%
Do not exist 4278 9.2%
Cache misses 1 0.0%
TOTAL: 4 46557 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.016 0.0%15 1.000 1.067 2.000
article cancel00:00:00.000 0.0%18 0.000 0.000 0.000
article cleanup00:00:00.089 0.0%4280 0.000 0.021 0.167
article logging00:00:00.319 0.0%4292 0.000 0.074 0.263
article parse00:00:00.851 0.0%9643 0.000 0.088 0.280
article write00:00:02.313 0.0%3998 0.200 0.579 1.900
artlog/artcncl00:00:00.000 0.0%15 0.000 0.000 0.000
artlog/artcncl/perl00:00:00.002 0.0%15 0.000 0.133 1.000
data move00:00:00.129 0.0%49955 0.000 0.003 0.014
hisgrep/artcncl00:00:00.000 0.0%18 0.000 0.000 0.000
hisgrep/artcncl/perl00:00:00.001 0.0%15 0.000 0.067 0.500
hisgrep/perl00:00:00.001 0.0%19 0.000 0.053 1.000
hishave/artcncl00:00:00.000 0.0%18 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.559 0.0%46541 0.000 0.012 0.034
history sync00:00:00.037 0.0%406 0.000 0.091 0.667
history write00:00:00.893 0.0%4280 0.000 0.209 0.552
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle24:00:32.013 99.9%50116 711.491 1724.639 8110.216
nntp read00:00:01.522 0.0%50025 0.000 0.030 0.070
overview write00:00:02.673 0.0%3998 0.000 0.669 6.667
perl filter00:00:17.484 0.0%4280 2.237 4.085 9.290
python filter00:00:00.008 0.0%4280 0.000 0.002 0.056
site send00:00:01.498 0.0%8059 0.000 0.186 0.520
TOTAL: 24:01:15.52124:01:00.408100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.048 0.0%4247 0.000 0.011 0.083
article prepare00:00:00.002 0.0%508 0.000 0.004 0.083
article read00:00:00.017 0.0%72 0.000 0.236 1.000
backlog stats00:00:00.118 0.0%58835 0.000 0.002 0.013
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.844 0.0%36231 0.000 0.023 0.096
data write00:00:01.897 0.0%31748 0.011 0.060 0.117
idle23:50:26.709100.0%58835 662.269 1458.770 6196.268
status file00:00:00.656 0.0%187 1.000 3.508 12.000
TOTAL: 23:50:33.71223:50:30.291100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:46.109 58.0%1447 11.400 31.865 10033.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.118 0.1%3182 0.000 0.037 0.273
TOTAL: 00:01:19.54700:00:46.227 58.1%----

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 4069 1775 2278 16 43%24:49:35
2i2pn.org 2 3976 953 2861 162 23%23:09:15
3news.glou.org 1 3396 486 2892 18 14%23:59:56
4feed-out.gegeweb.org 2 2709 356 2353 0 13%23:58:15
5usenet.pasdenom.info 1 2739 130 2601 8 4%22:49:23
6news.nntp4.net 2 4145 95 3989 61 2%24:19:29
7gegeweb-out.news.tnetconsulting.net 4 5934 63 5862 9 1%36:17:21
8nntp.terraraq.uk 1 3623 42 3579 2 1%23:59:54
9localhost 27 0 0 0 0 0%00:00:00
10news.samoylyk.net 1 243 0 243 0 0%02:07:45
TOTAL: 10 43 30834 3900 26658 276 12%205:30:53
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news.glou.org6.1 MB0.0 KB121.2 KB6.3 MB 98%12.7 KB
2news-out.aioe.org5.7 MB0.0 KB51.7 KB5.8 MB 99%3.3 KB
3i2pn.org3.1 MB0.0 KB455.9 KB3.6 MB 87%3.3 KB
4feed-out.gegeweb.org1.3 MB0.0 KB0.0 KB1.3 MB100%3.6 KB
5news.nntp4.net498.4 KB0.0 KB481.8 KB980.3 KB 50%6.3 KB
6gegeweb-out.news.tnetconsulting.net377.7 KB0.0 KB27.8 KB405.5 KB 93%5.6 KB
7usenet.pasdenom.info326.8 KB0.0 KB11.5 KB338.3 KB 96%2.5 KB
8nntp.terraraq.uk274.6 KB0.0 KB39.1 KB313.7 KB 87%7.1 KB
9localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
10news.samoylyk.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1017.7 MB0.0 KB1.2 MB18.8 MB 93%4.6 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Dec 6 04:15:01 - 04:59:59 41 1.0% 0.02222.0 KB 1.2% 0.08
Dec 6 05:00:00 - 05:59:59 91 2.3% 0.031.0 MB 5.4% 0.29
Dec 6 06:00:00 - 06:59:59 63 1.6% 0.02183.0 KB 1.0% 0.05
Dec 6 07:00:00 - 07:59:59 77 1.9% 0.02258.9 KB 1.4% 0.07
Dec 6 08:00:00 - 08:59:59 79 2.0% 0.02371.5 KB 1.9% 0.10
Dec 6 09:00:00 - 09:59:59 117 2.9% 0.031023.2 KB 5.3% 0.28
Dec 6 10:00:00 - 10:59:59 119 3.0% 0.03343.7 KB 1.8% 0.10
Dec 6 11:00:00 - 11:59:59 175 4.4% 0.05524.3 KB 2.7% 0.15
Dec 6 12:00:00 - 12:59:59 150 3.7% 0.04679.7 KB 3.6% 0.19
Dec 6 13:00:00 - 13:59:59 212 5.3% 0.062.0 MB 10.6% 0.57
Dec 6 14:00:00 - 14:59:59 208 5.2% 0.06727.4 KB 3.8% 0.20
Dec 6 15:00:00 - 15:59:59 155 3.9% 0.04565.3 KB 3.0% 0.16
Dec 6 16:00:00 - 16:59:59 267 6.7% 0.07861.0 KB 4.5% 0.24
Dec 6 17:00:00 - 17:59:59 316 7.9% 0.091.1 MB 5.8% 0.31
Dec 6 18:00:00 - 18:59:59 260 6.5% 0.071.1 MB 5.9% 0.32
Dec 6 19:00:00 - 19:59:59 252 6.3% 0.07805.2 KB 4.2% 0.22
Dec 6 20:00:00 - 20:59:59 244 6.1% 0.07935.1 KB 4.9% 0.26
Dec 6 21:00:00 - 21:59:59 195 4.9% 0.051.3 MB 6.8% 0.36
Dec 6 22:00:00 - 22:59:59 243 6.1% 0.071.7 MB 9.3% 0.49
Dec 6 23:00:00 - 23:59:59 209 5.2% 0.06993.4 KB 5.2% 0.28
Dec 7 00:00:00 - 00:59:59 150 3.7% 0.04720.0 KB 3.8% 0.20
Dec 7 01:00:00 - 01:59:59 144 3.6% 0.04629.6 KB 3.3% 0.17
Dec 7 02:00:00 - 02:59:59 107 2.7% 0.03350.4 KB 1.8% 0.10
Dec 7 03:00:00 - 03:59:59 108 2.7% 0.03503.1 KB 2.6% 0.14
Dec 7 04:00:00 - 04:15:01 19 0.5% 0.0246.0 KB 0.2% 0.05
TOTAL: 24:00:00 4001 100.0% 0.0518.7 MB 100.0% 0.22
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1i2pn.org 170 0 0 0 0 0 0 0 170
2news.nntp4.net 61 0 0 0 0 0 0 0 61
3news.glou.org 18 0 0 0 0 0 0 0 18
4news-out.aioe.org 17 1 0 0 0 0 0 0 16
5news.samoylyk.net 10 0 0 0 0 0 0 0 10
6usenet.pasdenom.info 9 0 0 0 0 0 0 0 9
7nntp.terraraq.uk 4 0 0 0 0 0 0 0 4
8gegeweb-out.news.tnetconsulting.net 4 0 0 0 0 0 0 0 4
9feed-out.gegeweb.org 1 0 0 0 0 0 0 0 1
TOTAL: 9 294 1 0 0 0 0 0 0 293

Unwanted newsgroups [Top 20]:

NewsgroupCount
news.test 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
Bad path (news.giganews.com.POSTED) 106
EMP (phl) 98
EMP (md5) 55
EMP (phn nph) 9
Too many newsgroups 5
HTML Multipart 2
No Cancel-Key matches Cancel-Lock. target=<BOVzIz8Y4V8@news.spitfire-nntp.fr> 1
Cancels abusifs 1
HTML post 1
Cancel of non-existing ID <638f41eb$0$25956$426a74cc@news.free.fr> 1
Cancel of <mn.34857e6c85d9ea2d.145333@gmail.com> without Cancel-Key 1
No Cancel-Key matches Cancel-Lock. target=<C6QKvd0NdHw@news.spitfire-nntp.fr> 1
TOTAL: 12 281

NoCeM on Spool:

IdGoodBadUniqueTotal
bleachbot@httrack.com3033
TOTAL: 13033

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
news.samoylyk.net 7
i2pn.org 2
news-out.aioe.org 2
feed-out.gegeweb.org 1
TOTAL: 4 12
TOTAL: 1 12

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.nntp4.net 4252 34 3868 0 0 0 0%23:59:56
2i2pn.org 5232 12 2923 7 0 146 0%23:58:33
3news.aioe.org 1851 8 1633 1 0 3 0%23:42:35
4nntp.terraraq.uk 2920 6 2419 36 0 0 0%23:57:59
5news.tnetconsulting.net 6273 6 3987 6 0 113 0%23:58:15
6news.gegeweb.org 3687 3 3640 1 0 0 0%23:59:56
7news.samoylyk.net 4751 1 3976 6 0 1 0%23:59:07
8news.pasdenom.info 3584 0 3202 0 0 0 0%23:59:47
TOTAL: 8 32550 70 25648 57 0 263 0%191:36:08
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.nntp4.net1.6 MB0.0 KB1.6 MB0.0 KB/s46.8 KB23:59:56
2news.gegeweb.org125.4 KB4.6 KB130.0 KB0.0 KB/s32.5 KB23:59:56
3news.samoylyk.net90.8 KB22.7 KB113.5 KB0.0 KB/s16.2 KB23:59:07
4i2pn.org49.7 KB31.2 KB80.9 KB0.0 KB/s4.3 KB23:58:33
5news.aioe.org37.6 KB1.2 KB38.8 KB0.0 KB/s4.3 KB23:42:35
6nntp.terraraq.uk30.0 KB2.9 MB2.9 MB0.0 KB/s70.7 KB23:57:59
7news.tnetconsulting.net19.3 KB22.7 KB42.0 KB0.0 KB/s3.5 KB23:58:15
8news.pasdenom.info0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:47
TOTAL: 81.9 MB3.0 MB4.8 MB0.0 KB/s39.1 KB191:36:08
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1143.110.190.34 1 00.0 KB 0 0 000:00:10
2103.48.193.51 1 00.0 KB 0 0 000:00:11
392.255.85.173 1 00.0 KB 0 0 000:00:00
4resounding.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:03
589.248.165.121 1 00.0 KB 0 0 000:00:00
6mail.gegeweb.eu 288 00.0 KB 0 0 000:00:53
TOTAL: 6 293 00.0 KB 0 0 000:01:19

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:03
2unresolved 4 00.0 KB 0 0 000:00:22
3*.gegeweb.eu 288 00.0 KB 0 0 000:00:53
TOTAL: 3 293 00.0 KB 0 0 000:01:19

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.471 7.313 2.44000:00:53
103.48.193.51 0.018 0.036 0.00000:00:11
143.110.190.34 0.020 0.030 0.00000:00:10
resounding.monitoring.internet-measurement.com 0.011 0.044 0.00000:00:03
89.248.165.121 0.043 0.011 0.00000:00:00
92.255.85.173 0.027 0.027 0.00000:00:00
TOTAL: 6 8.590 7.461 2.44000:01:19

NNRP unrecognized commands (by host):

SystemConn
89.248.165.121 1
92.255.85.173 1
TOTAL: 2 2

NNRP unrecognized commands (by command):

CommandCount
#003 2
TOTAL: 1 2

NNRP client timeouts [Top 20]:

SystemConnPeer
92.255.85.173 1 1
89.248.165.121 1 1
TOTAL: 2 2 2