Daily Usenet report for news.niel.me

Jun 18 04:15:01 -- Jun 19 04:15:02

Unknown entries from news log file:

First 50 / 181 lines (27.6%)

Jun 18 19:38:41 rpi-studio innd: filter: No Cancel-Key[sha1:4Dmz/ocS12PhZo+rcR07esXH2dc= sha256:31M9nHejUFFNUBaHmE4Fqu3APUKZrEzUlgdC4yHfR40=] matches Cancel-Lock[sha1:Xhc7nNf8mIs4EPDlUMvelquPch4= sha256:QShUtHqFfIqR+yR+KkJRxanH6a4m1+Fj3jeaPK/pF3o=] target=<4bm35k18lfojrjhpjkmlru8cmqkjimrgau@news.usenet.ovh>
Jun 18 22:00:04 rpi-studio pgpverify[13387]: pgpverify: verification failed
Jun 18 22:00:10 rpi-studio pgpverify[13395]: pgpverify: verification failed
Jun 18 22:01:11 rpi-studio pgpverify[13425]: pgpverify: verification failed
Jun 18 22:01:13 rpi-studio pgpverify[13433]: pgpverify: verification failed
Jun 18 22:02:14 rpi-studio pgpverify[13457]: pgpverify: verification failed
Jun 18 22:02:15 rpi-studio pgpverify[13465]: pgpverify: verification failed
Jun 18 22:03:16 rpi-studio pgpverify[13498]: pgpverify: verification failed
Jun 18 22:03:17 rpi-studio pgpverify[13506]: pgpverify: verification failed
Jun 18 22:04:18 rpi-studio pgpverify[13542]: pgpverify: verification failed
Jun 18 22:04:20 rpi-studio pgpverify[13552]: pgpverify: verification failed
Jun 18 22:05:21 rpi-studio pgpverify[13581]: pgpverify: verification failed
Jun 18 22:05:22 rpi-studio pgpverify[13589]: pgpverify: verification failed
Jun 18 22:06:23 rpi-studio pgpverify[13618]: pgpverify: verification failed
Jun 18 22:06:24 rpi-studio pgpverify[13626]: pgpverify: verification failed
Jun 18 22:07:25 rpi-studio pgpverify[13671]: pgpverify: verification failed
Jun 18 22:07:26 rpi-studio pgpverify[13679]: pgpverify: verification failed
Jun 18 22:08:27 rpi-studio pgpverify[13703]: pgpverify: verification failed
Jun 18 22:08:28 rpi-studio pgpverify[13712]: pgpverify: verification failed
Jun 18 22:09:29 rpi-studio pgpverify[13886]: pgpverify: verification failed
Jun 18 22:09:30 rpi-studio pgpverify[13896]: pgpverify: verification failed
Jun 18 22:10:31 rpi-studio pgpverify[13927]: pgpverify: verification failed
Jun 18 22:10:33 rpi-studio pgpverify[13935]: pgpverify: verification failed
Jun 18 22:11:33 rpi-studio pgpverify[13974]: pgpverify: verification failed
Jun 18 22:11:35 rpi-studio pgpverify[13982]: pgpverify: verification failed
Jun 18 22:12:35 rpi-studio pgpverify[14018]: pgpverify: verification failed
Jun 18 22:12:37 rpi-studio pgpverify[14026]: pgpverify: verification failed
Jun 18 22:13:37 rpi-studio pgpverify[14052]: pgpverify: verification failed
Jun 18 22:13:39 rpi-studio pgpverify[14060]: pgpverify: verification failed
Jun 18 22:14:39 rpi-studio pgpverify[14090]: pgpverify: verification failed
Jun 18 22:14:41 rpi-studio pgpverify[14100]: pgpverify: verification failed
Jun 18 22:15:42 rpi-studio pgpverify[14131]: pgpverify: verification failed
Jun 18 22:15:43 rpi-studio pgpverify[14139]: pgpverify: verification failed
Jun 18 22:16:44 rpi-studio pgpverify[14168]: pgpverify: verification failed
Jun 18 22:16:46 rpi-studio pgpverify[14176]: pgpverify: verification failed
Jun 18 22:17:46 rpi-studio pgpverify[14210]: pgpverify: verification failed
Jun 18 22:17:48 rpi-studio pgpverify[14218]: pgpverify: verification failed
Jun 18 22:18:48 rpi-studio pgpverify[14254]: pgpverify: verification failed
Jun 18 22:18:50 rpi-studio pgpverify[14262]: pgpverify: verification failed
Jun 18 22:19:50 rpi-studio pgpverify[14445]: pgpverify: verification failed
Jun 18 22:19:52 rpi-studio pgpverify[14453]: pgpverify: verification failed
Jun 18 22:20:52 rpi-studio pgpverify[14483]: pgpverify: verification failed
Jun 18 22:20:54 rpi-studio pgpverify[14491]: pgpverify: verification failed
Jun 18 22:21:54 rpi-studio pgpverify[14525]: pgpverify: verification failed
Jun 18 22:21:56 rpi-studio pgpverify[14533]: pgpverify: verification failed
Jun 18 22:22:57 rpi-studio pgpverify[14558]: pgpverify: verification failed
Jun 18 22:22:58 rpi-studio pgpverify[14566]: pgpverify: verification failed
Jun 18 22:23:59 rpi-studio pgpverify[14644]: pgpverify: verification failed
Jun 18 22:24:01 rpi-studio pgpverify[14656]: pgpverify: verification failed
Jun 18 22:25:02 rpi-studio pgpverify[14682]: pgpverify: verification failed

Log entries by program:

Program nameLines%LinesSize%Size
inn 2845 29.8%495.7 KB 33.9%
innd 2642 27.7%444.3 KB 30.4%
nnrpd 1934 20.3%210.4 KB 14.4%
innfeed 1762 18.5%272.7 KB 18.7%
controlchan 181 1.9%13.7 KB 0.9%
pgpverify 180 1.9%24.7 KB 1.7%
nocem 1 0.0%0.1 KB 0.0%
TOTAL: 7 9545 100.0%1.4 MB100.0%

History cache:

ReasonCount%Count
Positive hits 12286 56.6%
Negative hits 6597 30.4%
Do not exist 2823 13.0%
Cache misses 4 0.0%
TOTAL: 4 21710 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.008 0.0%6 1.000 1.333 2.000
article cancel00:00:00.001 0.0%7 0.000 0.143 1.000
article cleanup00:00:00.059 0.0%2825 0.000 0.021 0.250
article logging00:00:00.218 0.0%2826 0.000 0.077 0.375
article parse00:00:00.458 0.0%6890 0.000 0.066 0.200
article write00:00:01.045 0.0%2077 0.000 0.503 2.333
artlog/artcncl00:00:00.000 0.0%7 0.000 0.000 0.000
artlog/artcncl/perl00:00:00.001 0.0%6 0.000 0.167 1.000
data move00:00:00.184 0.0%25587 0.000 0.007 0.056
hisgrep/artcncl00:00:00.000 0.0%7 0.000 0.000 0.000
hisgrep/artcncl/perl00:00:00.000 0.0%6 0.000 0.000 0.000
hisgrep/perl00:00:00.001 0.0%7 0.000 0.143 1.000
hishave/artcncl00:00:00.000 0.0%7 0.000 0.000 0.000
hishave/artcncl/perl00:00:00.000 0.0%6 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.331 0.0%21723 0.000 0.015 0.058
history sync00:00:00.065 0.0%401 0.000 0.162 1.000
history write00:00:00.578 0.0%2825 0.000 0.205 0.733
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle24:00:45.894100.0%26646 1053.325 3244.235 12781.532
nntp read00:00:00.722 0.0%25831 0.000 0.028 0.136
overview write00:00:00.648 0.0%2077 0.000 0.312 3.000
perl filter00:00:15.080 0.0%2825 1.688 5.338 19.833
python filter00:00:00.012 0.0%2825 0.000 0.004 0.100
site send00:00:00.659 0.0%4399 0.000 0.150 0.438
TOTAL: 24:01:16.04224:01:05.964100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.012 0.0%2097 0.000 0.006 0.125
article prepare00:00:00.005 0.0%764 0.000 0.007 0.125
article read00:00:00.023 0.0%153 0.000 0.150 3.000
backlog stats00:00:00.170 0.0%30733 0.000 0.006 0.030
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.477 0.0%16493 0.000 0.029 0.121
data write00:00:00.775 0.0%14525 0.000 0.053 0.120
idle23:59:06.324100.0%30733 849.069 2809.564 8954.612
status file00:00:06.918 0.0%218 0.000 31.734 92.000
TOTAL: 23:59:17.95923:59:14.704100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:04:16.754 51.2%1461 9.200 175.739 95561.500
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.138 0.0%3303 0.000 0.042 0.500
TOTAL: 00:08:21.48300:04:16.892 51.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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
group-admin@isc.org00018000
TOTAL00018000

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1news.nntp4.net 1 2793 977 1132 684 34%24:03:14
2feed-out.gegeweb.org 2 1931 524 1407 0 27%22:47:39
3news.glou.org 91 3105 199 2830 76 6%45:26:20
4news.samoylyk.net 2 2018 165 1853 0 8%23:05:46
5gegeweb-out.news.tnetconsulting.net 2 1659 99 1557 3 5%21:54:32
6nntp.terraraq.uk 7 1136 58 1074 4 5%17:50:56
7localhost 25 0 0 0 0 0%00:00:00
8i2pn.org 1 113 0 113 0 0%02:03:19
9usenet.pasdenom.info 6 1041 0 1041 0 0%21:11:28
TOTAL: 9 137 13796 2022 11007 767 14%178:23:14
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news.nntp4.net4.0 MB0.0 KB5.0 MB8.9 MB 44%5.5 KB
2feed-out.gegeweb.org1.6 MB0.0 KB0.0 KB1.6 MB100%3.2 KB
3news.samoylyk.net1.3 MB0.0 KB0.0 KB1.3 MB100%8.3 KB
4news.glou.org646.9 KB0.0 KB235.1 KB882.1 KB 73%3.2 KB
5gegeweb-out.news.tnetconsulting.net448.9 KB0.0 KB12.0 KB460.9 KB 97%4.5 KB
6nntp.terraraq.uk164.6 KB0.0 KB9.6 KB174.2 KB 94%2.8 KB
7localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
8i2pn.org0.0 KB0.0 KB0.0 KB0.0 KB 0%0
9usenet.pasdenom.info0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 98.2 MB0.0 KB5.2 MB13.4 MB 60%4.9 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 18 04:15:01 - 04:59:59 28 1.3% 0.01103.0 KB 1.2% 0.04
Jun 18 05:00:00 - 05:59:59 57 2.7% 0.02185.0 KB 2.2% 0.05
Jun 18 06:00:00 - 06:59:59 48 2.3% 0.01185.0 KB 2.2% 0.05
Jun 18 07:00:00 - 07:59:59 41 2.0% 0.01233.8 KB 2.8% 0.06
Jun 18 08:00:00 - 08:59:59 51 2.5% 0.01150.6 KB 1.8% 0.04
Jun 18 09:00:00 - 09:59:59 51 2.5% 0.01641.0 KB 7.5% 0.18
Jun 18 10:00:00 - 10:59:59 63 3.0% 0.02262.3 KB 3.1% 0.07
Jun 18 11:00:00 - 11:59:59 120 5.8% 0.03378.3 KB 4.5% 0.11
Jun 18 12:00:00 - 12:59:59 128 6.2% 0.04608.9 KB 7.2% 0.17
Jun 18 13:00:00 - 13:59:59 115 5.5% 0.03351.8 KB 4.1% 0.10
Jun 18 14:00:00 - 14:59:59 101 4.9% 0.03333.5 KB 3.9% 0.09
Jun 18 15:00:00 - 15:59:59 69 3.3% 0.02228.8 KB 2.7% 0.06
Jun 18 16:00:00 - 16:59:59 82 3.9% 0.02281.2 KB 3.3% 0.08
Jun 18 17:00:00 - 17:59:59 104 5.0% 0.03338.5 KB 4.0% 0.09
Jun 18 18:00:00 - 18:59:59 98 4.7% 0.03357.4 KB 4.2% 0.10
Jun 18 19:00:00 - 19:59:59 95 4.6% 0.03690.7 KB 8.1% 0.19
Jun 18 20:00:00 - 20:59:59 64 3.1% 0.02337.4 KB 4.0% 0.09
Jun 18 21:00:00 - 21:59:59 118 5.7% 0.03447.1 KB 5.3% 0.12
Jun 18 22:00:00 - 22:59:59 247 11.9% 0.07855.9 KB 10.1% 0.24
Jun 18 23:00:00 - 23:59:59 148 7.1% 0.04533.3 KB 6.3% 0.15
Jun 19 00:00:00 - 00:59:59 62 3.0% 0.02273.5 KB 3.2% 0.08
Jun 19 01:00:00 - 01:59:59 60 2.9% 0.02241.8 KB 2.8% 0.07
Jun 19 02:00:00 - 02:59:59 34 1.6% 0.01126.8 KB 1.5% 0.04
Jun 19 03:00:00 - 03:59:59 84 4.0% 0.02304.8 KB 3.6% 0.08
Jun 19 04:00:00 - 04:15:02 13 0.6% 0.0146.6 KB 0.5% 0.05
TOTAL: 24:00:01 2081 100.0% 0.028.3 MB 100.0% 0.10
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nntp4.net 685 425 0 0 0 0 0 0 260
2news.glou.org 54 0 0 0 0 0 0 0 54
3nntp.terraraq.uk 8 0 0 0 0 0 0 0 8
4gegeweb-out.news.tnetconsulting.net 3 0 0 0 0 0 0 0 3
5i2pn.org 1 0 0 0 0 0 0 0 1
TOTAL: 5 751 425 0 0 0 0 0 0 326

Unwanted newsgroups [Top 20]:

NewsgroupCount
linux.kernel.netdev 378
linux.kernel.git 45
news.test 1
soc.culture.iran 1
TOTAL: 4 425

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 169
Bad path (news.giganews.com.POSTED) 85
Too many newsgroups 70
Cancels abusifs 1
TOTAL: 4 325

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
news.nntp4.net 1
TOTAL: 1 1
TOTAL: 1 1

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.pasdenom.info 1817 61 1637 17 0 0 3%23:59:59
2news.gegeweb.org 1615 11 1524 0 0 0 0%23:48:45
3news.tnetconsulting.net 3035 10 2048 19 0 6 0%23:59:58
4news.samoylyk.net 2437 8 1860 38 0 2 0%23:59:58
5news.nntp4.net 1194 8 1091 0 0 0 0%23:49:21
6i2pn.org 2911 2 1957 10 0 5 0%23:59:58
7news.aioe.org 0 0 0 0 0 1706 0%24:00:01
8nntp.terraraq.uk 1509 0 1393 7 0 0 0%23:53:18
TOTAL: 8 14518 100 11510 91 0 1719 0%191:31:18
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.pasdenom.info256.6 KB25.5 KB282.1 KB0.0 KB/s3.6 KB23:59:59
2news.tnetconsulting.net83.1 KB46.8 KB129.9 KB0.0 KB/s4.5 KB23:59:58
3news.gegeweb.org26.0 KB0.0 KB26.0 KB0.0 KB/s2.4 KB23:48:45
4news.samoylyk.net21.6 KB411.8 KB433.4 KB0.0 KB/s9.4 KB23:59:58
5news.nntp4.net10.0 KB0.0 KB10.0 KB0.0 KB/s1.3 KB23:49:21
6i2pn.org4.8 KB15.7 KB20.4 KB0.0 KB/s1.7 KB23:59:58
7news.aioe.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:01
8nntp.terraraq.uk0.0 KB9.0 KB9.0 KB0.0 KB/s1.3 KB23:53:18
TOTAL: 8402.0 KB508.7 KB910.7 KB0.0 KB/s4.8 KB191:31:18
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
145.142.193.185 2 00.0 KB 0 0 000:00:39
2147.185.132.169 2 00.0 KB 0 0 000:00:02
3poignant.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:02
4198.235.24.16 1 00.0 KB 0 0 000:00:03
5205.210.31.7 3 00.0 KB 0 0 000:03:12
6splendid.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
7hari.probe.onyphe.net 1 00.0 KB 0 0 000:00:13
8206.168.34.78 1 00.0 KB 0 0 000:00:31
9185.210.203.35.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:00
10oconnor.probe.onyphe.net 1 00.0 KB 0 0 000:00:05
11206.168.34.61 1 00.0 KB 0 0 000:00:32
12kind.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:03
13136.149.216.162.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:00
14198.235.24.88 1 00.0 KB 0 0 000:00:03
1551.159.234.118 1 00.0 KB 0 0 000:00:01
16206.168.34.208 1 00.0 KB 0 0 000:00:31
17ravishing.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
18laudable.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
19198.235.24.157 3 00.0 KB 0 0 000:00:10
20uplifting.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:03
21206.168.34.85 1 00.0 KB 0 0 000:00:07
22mail.gegeweb.eu 288 00.0 KB 0 0 000:01:53
2393.149.216.162.bc.googleusercontent.com 2 00.0 KB 0 0 000:00:03
TOTAL: 23 317 00.0 KB 0 0 000:08:24

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 17 00.0 KB 0 0 000:05:56
2*.149.216.162.bc.googleusercontent.com 3 00.0 KB 0 0 000:00:04
3*.probe.onyphe.net 2 00.0 KB 0 0 000:00:19
4*.210.203.35.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:00
5*.gegeweb.eu 288 00.0 KB 0 0 000:01:53
6*.monitoring.internet-measurement.com 6 00.0 KB 0 0 000:00:09
TOTAL: 6 317 00.0 KB 0 0 000:08:24

NNRP auth users [Top 20]:

UserConn
mailman 288
TOTAL: 1 288

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
205.210.31.7 0.073 0.039 0.00000:03:12
mail.gegeweb.eu 4.863 4.258 1.85200:01:53
45.142.193.185 0.064 0.028 0.00000:00:39
206.168.34.61 0.029 0.025 0.00000:00:32
206.168.34.208 0.045 0.021 0.00000:00:31
206.168.34.78 0.040 0.022 0.00000:00:31
hari.probe.onyphe.net 0.014 0.011 0.00000:00:13
198.235.24.157 0.061 0.036 0.00000:00:10
206.168.34.85 0.022 0.009 0.00000:00:07
oconnor.probe.onyphe.net 0.033 0.013 0.00000:00:05
198.235.24.16 0.033 0.014 0.00000:00:03
93.149.216.162.bc.googleusercontent.com 0.177 0.058 0.00000:00:03
kind.monitoring.internet-measurement.com 0.014 0.018 0.00000:00:03
198.235.24.88 0.012 0.012 0.00000:00:03
uplifting.monitoring.internet-measurement.com 0.063 0.009 0.00000:00:03
poignant.monitoring.internet-measurement.com 0.036 0.012 0.00000:00:02
147.185.132.169 0.134 0.026 0.00000:00:02
51.159.234.118 0.104 0.028 0.00000:00:01
136.149.216.162.bc.googleusercontent.com 0.054 0.025 0.00000:00:00
185.210.203.35.bc.googleusercontent.com 0.026 0.015 0.00000:00:00
TOTAL: 21 5.911 4.693 1.85200:08:24

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

SystemConn
45.142.193.185 7
hari.probe.onyphe.net 6
147.185.132.169 4
185.210.203.35.bc.googleusercontent.com 4
136.149.216.162.bc.googleusercontent.com 4
93.149.216.162.bc.googleusercontent.com 4
oconnor.probe.onyphe.net 3
206.168.34.85 1
205.210.31.7 1
198.235.24.157 1
TOTAL: 10 35

NNRP unrecognized commands (by command):

CommandCount
GET / HTTP/1.1 5
Host: 45.13.105.36:563 5
Accept: */* 3
User-Agent: Expanse, a Palo Alto Network... 2
#026#003 2
User-Agent: curl/7.68.0 2
Accept-Encoding: gzip 2
À+Ì©À#011À0À#024À/̨À#023 1
User-Agent: Mozilla/5.0 (X11; Ubuntu; Li... 1
#026#003#003#001½#001 1
À$À­À¯À,ÀrÀsÌ©#023#002#023#001Ì#024À#007À#022À#023À'À/À#024À(À0À`Àa... 1
EHLO www.censys.io 1
#026#003#001 1
Host: [2a0e:e701:1154:0:4e10:5c89:4af0:d... 1
Connection: close 1
Accept-Encoding: deflate, gzip 1
Accept: text/html,application/xhtml+xml,... 1
Accept-Language: en-US,en;q=0.5 1
?Ó #025#033ôÀ¹kè#002Ž(#030#037î>–r¦“†¦oš#003#016ÄJm#020Ø#022 1
#001 1
User-Agent: Mozilla/5.0 (Windows NT 6.1;... 1
TOTAL: 21 35

NNRP client timeouts [Top 20]:

SystemConnPeer
198.235.24.157 2 3
205.210.31.7 2 3
93.149.216.162.bc.googleusercontent.com 1 2
147.185.132.169 1 2
TOTAL: 4 6 12