Daily Usenet report for news.niel.me

Jul 15 04:15:01 -- Jul 16 04:15:01

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Jul 15 09:40:50 rpi-studio innd: filter: No Cancel-Key[sha1:QxW3DjXbO86MuqMyEL1xVDaFpU0=] matches Cancel-Lock[sha1:E+Qt5DxGVytpWudbYqJySLvnY1k=] target=<6694d0b7$0$8257$426a34cc@news.free.fr>
Jul 15 17:00:06 rpi-studio pgpverify[20599]: pgpverify: verification failed
Jul 15 17:00:17 rpi-studio pgpverify[20618]: pgpverify: verification failed

Log entries by program:

Program nameLines%LinesSize%Size
inn 3097 33.6%589.8 KB 39.1%
innd 2182 23.6%382.1 KB 25.3%
nnrpd 2141 23.2%231.0 KB 15.3%
innfeed 1791 19.4%303.5 KB 20.1%
controlchan 13 0.1%2.2 KB 0.1%
pgpverify 2 0.0%0.5 KB 0.0%
nocem 1 0.0%0.2 KB 0.0%
TOTAL: 7 9227 100.0%1.5 MB100.0%

History cache:

ReasonCount%Count
Positive hits 16776 58.0%
Negative hits 9069 31.4%
Do not exist 3074 10.6%
Cache misses 5 0.0%
TOTAL: 4 28924 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.011 0.0%10 0.000 1.100 4.000
article cancel00:00:00.002 0.0%10 0.000 0.200 1.000
article cleanup00:00:00.061 0.0%3079 0.000 0.020 0.211
article logging00:00:00.228 0.0%3079 0.000 0.074 0.417
article parse00:00:00.382 0.0%8429 0.000 0.045 0.208
article write00:00:01.410 0.0%2807 0.067 0.502 1.000
artlog/artcncl00:00:00.001 0.0%10 0.000 0.100 1.000
artlog/artcncl/perl00:00:00.000 0.0%10 0.000 0.000 0.000
data move00:00:00.149 0.0%32076 0.000 0.005 0.019
hisgrep/artcncl00:00:00.000 0.0%10 0.000 0.000 0.000
hisgrep/artcncl/perl00:00:00.000 0.0%10 0.000 0.000 0.000
hisgrep/perl00:00:00.001 0.0%19 0.000 0.053 1.000
hishave/artcncl00:00:00.000 0.0%10 0.000 0.000 0.000
hishave/artcncl/perl00:00:00.000 0.0%10 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.399 0.0%28938 0.000 0.014 0.065
history sync00:00:00.035 0.0%335 0.000 0.104 1.000
history write00:00:00.495 0.0%3078 0.000 0.161 0.400
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle24:00:32.521100.0%34560 993.137 2500.941 10172.966
nntp read00:00:00.849 0.0%33809 0.000 0.025 0.081
overview write00:00:01.090 0.0%2807 0.000 0.388 3.556
perl filter00:00:12.671 0.0%3078 2.321 4.117 9.429
python filter00:00:00.010 0.0%3078 0.000 0.003 0.056
site send00:00:00.977 0.0%5704 0.000 0.171 0.435
TOTAL: 24:01:00.83024:00:51.292100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.030 0.0%2841 0.000 0.011 0.154
article prepare00:00:00.003 0.0%820 0.000 0.004 0.250
article read00:00:00.028 0.0%203 0.000 0.138 1.000
backlog stats00:00:00.200 0.0%40264 0.000 0.005 0.019
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.527 0.0%22797 0.000 0.023 0.068
data write00:00:01.059 0.0%20174 0.008 0.052 0.110
idle23:58:36.609100.0%40264 929.003 2143.766 5774.760
status file00:00:00.356 0.0%193 0.000 1.845 5.000
TOTAL: 23:58:44.39723:58:38.812100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:03:17.667 69.0%1855 3.500 106.559 10017.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.421 0.1%4728 0.000 0.089 0.667
readart00:00:00.042 0.0%279 0.151 0.151 0.151
TOTAL: 00:04:46.66800:03:18.130 69.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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
group-admin@isc.org002202
TOTAL002202

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1feed-out.gegeweb.org 2 2735 1102 1633 0 40%23:59:57
2news.samoylyk.net 2 3306 498 2806 2 15%28:00:52
3news.nntp4.net 2 3699 415 3201 83 11%31:06:04
4gegeweb-out.news.tnetconsulting.net 1 2456 384 2000 72 15%24:30:17
5i2pn.org 1 2591 201 2377 13 7%21:24:18
6usenet.pasdenom.info 1 1551 177 1346 28 11%22:12:43
7news.glou.org 1 2456 106 2260 90 4%23:59:58
8nntp.terraraq.uk 1 365 17 348 0 4%05:48:25
9localhost 25 0 0 0 0 0%00:00:00
TOTAL: 9 36 19159 2900 15971 288 15%181:02:34
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1feed-out.gegeweb.org4.0 MB0.0 KB0.0 KB4.0 MB100%3.7 KB
2news.samoylyk.net3.7 MB0.0 KB3.7 KB3.7 MB 99%7.6 KB
3news.nntp4.net1.9 MB0.0 KB415.2 KB2.3 MB 82%4.8 KB
4gegeweb-out.news.tnetconsulting.net1.1 MB9.4 KB300.1 KB1.4 MB 78%3.2 KB
5news.glou.org520.7 KB0.0 KB249.3 KB770.0 KB 67%3.9 KB
6i2pn.org504.7 KB0.0 KB113.4 KB618.1 KB 81%2.9 KB
7usenet.pasdenom.info438.5 KB0.0 KB48.4 KB486.9 KB 90%2.4 KB
8nntp.terraraq.uk42.0 KB0.0 KB0.0 KB42.0 KB100%2.5 KB
9localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 912.2 MB9.4 KB1.1 MB13.3 MB 91%4.3 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jul 15 04:15:01 - 04:59:59 53 1.9% 0.02245.4 KB 2.1% 0.09
Jul 15 05:00:00 - 05:59:59 57 2.0% 0.02222.2 KB 1.9% 0.06
Jul 15 06:00:00 - 06:59:59 121 4.3% 0.03395.1 KB 3.4% 0.11
Jul 15 07:00:00 - 07:59:59 91 3.2% 0.03309.9 KB 2.7% 0.09
Jul 15 08:00:00 - 08:59:59 78 2.8% 0.02259.3 KB 2.2% 0.07
Jul 15 09:00:00 - 09:59:59 80 2.9% 0.02327.8 KB 2.8% 0.09
Jul 15 10:00:00 - 10:59:59 104 3.7% 0.03346.9 KB 3.0% 0.10
Jul 15 11:00:00 - 11:59:59 65 2.3% 0.02245.1 KB 2.1% 0.07
Jul 15 12:00:00 - 12:59:59 98 3.5% 0.03327.2 KB 2.8% 0.09
Jul 15 13:00:00 - 13:59:59 99 3.5% 0.03334.0 KB 2.9% 0.09
Jul 15 14:00:00 - 14:59:59 132 4.7% 0.04889.4 KB 7.7% 0.25
Jul 15 15:00:00 - 15:59:59 140 5.0% 0.04543.3 KB 4.7% 0.15
Jul 15 16:00:00 - 16:59:59 169 6.0% 0.05570.9 KB 4.9% 0.16
Jul 15 17:00:00 - 17:59:59 139 5.0% 0.04833.4 KB 7.2% 0.23
Jul 15 18:00:00 - 18:59:59 176 6.3% 0.05870.4 KB 7.5% 0.24
Jul 15 19:00:00 - 19:59:59 167 6.0% 0.05519.4 KB 4.5% 0.14
Jul 15 20:00:00 - 20:59:59 170 6.1% 0.05628.4 KB 5.4% 0.17
Jul 15 21:00:00 - 21:59:59 157 5.6% 0.04727.1 KB 6.3% 0.20
Jul 15 22:00:00 - 22:59:59 171 6.1% 0.05701.8 KB 6.0% 0.19
Jul 15 23:00:00 - 23:59:59 108 3.8% 0.03438.1 KB 3.8% 0.12
Jul 16 00:00:00 - 00:59:59 112 4.0% 0.03328.0 KB 2.8% 0.09
Jul 16 01:00:00 - 01:59:59 98 3.5% 0.03793.2 KB 6.8% 0.22
Jul 16 02:00:00 - 02:59:59 98 3.5% 0.03355.6 KB 3.1% 0.10
Jul 16 03:00:00 - 03:59:59 99 3.5% 0.03339.0 KB 2.9% 0.09
Jul 16 04:00:00 - 04:15:01 24 0.9% 0.0369.5 KB 0.6% 0.08
TOTAL: 24:00:00 2806 100.0% 0.0311.3 MB 100.0% 0.13
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.glou.org 90 0 0 0 0 0 0 0 90
2news.nntp4.net 82 0 0 0 0 0 0 0 82
3gegeweb-out.news.tnetconsulting.net 65 0 0 1 0 0 0 0 64
4usenet.pasdenom.info 27 0 0 0 0 0 0 0 27
5i2pn.org 3 0 0 0 0 0 0 0 3
6news.samoylyk.net 2 0 0 0 0 0 0 0 2
7nntp.terraraq.uk 2 0 0 0 0 0 0 0 2
TOTAL: 7 271 0 0 1 0 0 0 0 270

INND Perl filter [Top 20]:

ReasonCount
EMP (phn path) 122
Bad path (news.giganews.com.POSTED) 75
Too many newsgroups 56
EMP (md5) 3
Too many newsgroups (meow) 3
Cancel of <3e3df86975bda2d8bcc29722b0ac3ca45e636052@i2pn2.org> without Cancel-Key 1
Cancel of <b644e341653f5ac93ca81a192b8bd77d8a35cf03@i2pn2.org> without Cancel-Key 1
Cancel of <64bb485e9f4d2d07204610b59cbccfefb7092737@i2pn2.org> without Cancel-Key 1
Cancel of <939879a5b0c0df129dcf13254399e32ca3682864@i2pn2.org> without Cancel-Key 1
Cancel of <16a547d1f66cbe1d984ead85b2a2e3b10e565191@i2pn2.org> without Cancel-Key 1
hierarchy violation - crosspost outside local 1
HTML Multipart 1
No Cancel-Key matches Cancel-Lock. target=<6694d0b7$0$8257$426a34cc@news.free.fr> 1
Cancel of <217652b09486d3443d43eba1b6946a51d4aab7de@i2pn2.org> without Cancel-Key 1
Cancel of <b96c0d0a3fe2b2f14398c1fa09c697c37832615c@i2pn2.org> without Cancel-Key 1
Cancel of non-existing ID <v72r3h$2l9f$1@cabale.usenet-fr.net> 1
TOTAL: 16 270

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1i2pn.org 4158 129 2455 14 0 26 3%23:59:56
2news.gegeweb.org 1778 45 1650 0 0 0 2%23:59:56
3news.tnetconsulting.net 4559 12 2784 3 0 10 0%23:59:56
4news.pasdenom.info 2510 1 2313 0 0 0 0%23:59:56
5news.samoylyk.net 2785 1 2362 0 0 0 0%23:59:56
6nntp.terraraq.uk 1935 0 1834 0 0 0 0%23:59:32
7news.aioe.org 0 0 0 0 0 2257 0%24:00:00
8news.nntp4.net 2591 0 2464 0 0 0 0%23:59:56
TOTAL: 8 20316 188 15862 17 0 2293 0%191:59:08
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1i2pn.org364.5 KB124.1 KB488.6 KB0.0 KB/s3.4 KB23:59:56
2news.gegeweb.org151.3 KB0.0 KB151.3 KB0.0 KB/s3.4 KB23:59:56
3news.tnetconsulting.net31.1 KB9.0 KB40.2 KB0.0 KB/s2.7 KB23:59:56
4news.samoylyk.net4.5 KB0.0 KB4.5 KB0.0 KB/s4.5 KB23:59:56
5news.pasdenom.info3.1 KB0.0 KB3.1 KB0.0 KB/s3.1 KB23:59:56
6nntp.terraraq.uk0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:32
7news.aioe.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:00
8news.nntp4.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:56
TOTAL: 8554.5 KB133.2 KB687.7 KB0.0 KB/s3.4 KB191:59:08
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
169.10.48.174 1 279965.1 KB 4 0 000:02:23
TOTAL: 1 1 279965.1 KB 4 0 000:04:46

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 10 279965.1 KB 4 0 000:02:47
2*.stretchoid.com 1 00.0 KB 0 0 000:00:00
3*.210.203.35.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:00
4*.ip.linodeusercontent.com 106 00.0 KB 0 0 000:00:22
5*.gegeweb.eu 288 00.0 KB 0 0 000:01:31
6*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:02
TOTAL: 6 407 279965.1 KB 4 0 000:04:46

NNRP auth users [Top 20]:

UserConn
mailman 288
TOTAL: 1 288

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
69.10.48.174 0.060 0.160 0.00000:02:23
mail.gegeweb.eu 4.285 4.231 1.45100:01:31
172-105-246-139.ip.linodeusercontent.com 2.681 1.554 0.00000:00:21
199.45.154.127 0.033 0.046 0.00000:00:10
104.152.52.133 0.025 0.047 0.00000:00:10
fragrant.monitoring.internet-measurement.com 0.036 0.020 0.00000:00:02
91.238.181.71 0.029 0.017 0.00000:00:00
azpdsgb85.stretchoid.com 0.015 0.011 0.00000:00:00
67.210.203.35.bc.googleusercontent.com 0.066 0.012 0.00000:00:00
147.185.132.83 0.070 0.017 0.00000:00:00
185.242.226.39 0.032 0.032 0.00000:00:00
172-232-203-205.ip.linodeusercontent.com 0.019 0.005 0.00000:00:00
91.238.181.21 0.020 0.008 0.00000:00:00
172-232-211-124.ip.linodeusercontent.com 0.036 0.013 0.00000:00:00
? 0.043 0.043 0.00000:00:00
TOTAL: 15 7.450 6.216 1.45100:04:46

Curious NNRP server explorers [Top 20]:

SystemConn
mail.gegeweb.eu 288
172-105-246-139.ip.linodeusercontent.com 104
199.45.154.127 3
104.152.52.133 2
172-232-203-205.ip.linodeusercontent.com 1
67.210.203.35.bc.googleusercontent.com 1
azpdsgb85.stretchoid.com 1
185.242.226.39 1
91.238.181.71 1
fragrant.monitoring.internet-measurement.com 1
91.238.181.21 1
172-232-211-124.ip.linodeusercontent.com 1
147.185.132.83 1
TOTAL: 13 406

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

SystemConn
67.210.203.35.bc.googleusercontent.com 4
147.185.132.83 4
199.45.154.127 1
91.238.181.71 1
azpdsgb85.stretchoid.com 1
TOTAL: 5 11

NNRP unrecognized commands (by command):

CommandCount
Accept-Encoding: gzip 2
Host: 45.13.105.36:563 2
User-Agent: Expanse, a Palo Alto Network... 2
GET / HTTP/1.1 2
EHLO www.censys.io 1
#003 1
MGLNDD_45.13.105.36_119 1
TOTAL: 7 11

NNRP client timeouts [Top 20]:

SystemConnPeer
91.238.181.71 1 1
199.45.154.127 1 2
TOTAL: 2 2 3

Newsgroup request counts (by category):

CategoryCountPct
1grisbi 176 63.1%
2local 103 36.9%
TOTAL: 2 279100.0%

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

NewsgroupCount
1local.test 103
2grisbi.user.french 64
3grisbi.devel 58
4grisbi.test 54
TOTAL: 4 279
innreport from INN 2.6.4 based on work (c) 1996-1999 by Fabien Tassin <fta@sofaraway.org>
Local contact: usenet@niel.me