Daily Usenet report for news.niel.me

Mar 12 04:15:02 -- Mar 13 04:15:02

Unknown entries from news log file:

First 50 / 2870 lines (1.7%)

Mar 12 04:15:10 rpi-studio nnrpd[25830]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:15:40 rpi-studio nnrpd[25994]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:16:10 rpi-studio nnrpd[26065]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:16:40 rpi-studio nnrpd[26079]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:17:10 rpi-studio nnrpd[26099]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:17:40 rpi-studio nnrpd[26107]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:18:10 rpi-studio nnrpd[26118]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:18:40 rpi-studio nnrpd[26142]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:19:10 rpi-studio nnrpd[26151]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:19:40 rpi-studio nnrpd[26161]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:20:10 rpi-studio nnrpd[26178]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:20:40 rpi-studio nnrpd[26191]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:21:10 rpi-studio nnrpd[26223]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:21:40 rpi-studio nnrpd[26230]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:22:10 rpi-studio nnrpd[26243]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:22:40 rpi-studio nnrpd[26256]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:23:11 rpi-studio nnrpd[26267]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:23:41 rpi-studio nnrpd[26275]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:24:11 rpi-studio nnrpd[26289]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:24:41 rpi-studio nnrpd[26301]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:25:11 rpi-studio nnrpd[26319]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:25:41 rpi-studio nnrpd[26468]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:26:11 rpi-studio nnrpd[26477]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:26:41 rpi-studio nnrpd[26487]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:27:11 rpi-studio nnrpd[26501]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:27:41 rpi-studio nnrpd[26508]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:28:11 rpi-studio nnrpd[26518]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:28:42 rpi-studio nnrpd[26528]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:29:12 rpi-studio nnrpd[26544]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:29:42 rpi-studio nnrpd[26551]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:30:12 rpi-studio nnrpd[26573]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:30:42 rpi-studio nnrpd[26588]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:31:12 rpi-studio nnrpd[26598]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:31:42 rpi-studio nnrpd[26605]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:32:12 rpi-studio nnrpd[26616]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:32:42 rpi-studio nnrpd[26623]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:33:12 rpi-studio nnrpd[26634]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:33:42 rpi-studio nnrpd[26644]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:34:12 rpi-studio nnrpd[26661]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:34:42 rpi-studio nnrpd[26668]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:35:12 rpi-studio nnrpd[26680]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:35:42 rpi-studio nnrpd[26829]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:36:12 rpi-studio nnrpd[26838]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:36:42 rpi-studio nnrpd[26851]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:37:12 rpi-studio nnrpd[26860]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:37:42 rpi-studio nnrpd[26867]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:38:13 rpi-studio nnrpd[26898]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:38:44 rpi-studio nnrpd[26905]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:39:14 rpi-studio nnrpd[26916]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 12 04:39:44 rpi-studio nnrpd[26923]: 2001:41d0:a:280::1 noperm ihave without permission

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 19202 74.2%2.2 MB 67.5%
inn 2769 10.7%487.3 KB 14.4%
innd 2096 8.1%356.0 KB 10.5%
innfeed 1818 7.0%253.9 KB 7.5%
nocem 1 0.0%0.1 KB 0.0%
controlchan 1 0.0%0.2 KB 0.0%
TOTAL: 6 25887 100.0%3.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 12167 56.4%
Negative hits 6656 30.9%
Do not exist 2742 12.7%
Cache misses 2 0.0%
TOTAL: 4 21567 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.013 0.0%8 1.000 1.625 5.000
article cancel00:00:00.000 0.0%8 0.000 0.000 0.000
article cleanup00:00:00.073 0.0%2746 0.000 0.027 0.200
article logging00:00:00.214 0.0%2746 0.000 0.078 0.429
article parse00:00:00.556 0.0%8972 0.000 0.062 0.286
article write00:00:01.108 0.0%2206 0.000 0.502 1.167
artlog/artcncl00:00:00.000 0.0%8 0.000 0.000 0.000
artlog/artcncl/perl00:00:00.000 0.0%8 0.000 0.000 0.000
data move00:00:00.162 0.0%26781 0.000 0.006 0.022
hisgrep/artcncl00:00:00.000 0.0%8 0.000 0.000 0.000
hisgrep/artcncl/perl00:00:00.000 0.0%8 0.000 0.000 0.000
hisgrep/perl00:00:00.000 0.0%8 0.000 0.000 0.000
hishave/artcncl00:00:00.000 0.0%8 0.000 0.000 0.000
hishave/artcncl/perl00:00:00.000 0.0%8 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.306 0.0%21595 0.000 0.014 0.075
history sync00:00:00.074 0.0%423 0.000 0.175 1.000
history write00:00:00.490 0.0%2746 0.000 0.178 0.833
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle24:00:41.135100.0%33644 440.938 2569.288 7802.792
nntp read00:00:00.854 0.0%27190 0.000 0.031 0.086
overview write00:00:00.677 0.0%2206 0.000 0.307 2.583
perl filter00:00:10.007 0.0%2746 1.183 3.644 9.000
python filter00:00:00.013 0.0%2746 0.000 0.005 0.125
site send00:00:00.851 0.0%4500 0.000 0.189 0.625
TOTAL: 24:01:12.43224:00:56.533100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.023 0.0%2477 0.000 0.009 0.250
article prepare00:00:00.001 0.0%296 0.000 0.003 0.083
article read00:00:00.011 0.0%69 0.000 0.159 1.000
backlog stats00:00:00.181 0.0%29445 0.000 0.006 0.040
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.401 0.0%15920 0.000 0.025 0.102
data write00:00:00.802 0.0%14116 0.000 0.057 0.196
idle23:50:05.115100.0%29445 845.363 2914.081 7917.803
status file00:00:00.782 0.0%230 0.000 3.400 31.000
TOTAL: 23:50:10.83423:50:07.316100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:25:17.951 90.2%13430 4.500 113.027 10037.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:01.343 0.1%33795 0.000 0.040 1.000
readart00:00:00.532 0.0%3245 0.164 0.164 0.164
TOTAL: 00:28:03.76400:25:19.826 90.3%----

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
1feed-out.gegeweb.org 2 2244 739 1505 0 32%23:59:35
2news.nntp4.net 2 2705 675 1529 501 24%23:54:52
3news.samoylyk.net 1 2049 235 1814 0 11%21:10:59
4gegeweb-out.news.tnetconsulting.net 2 1365 224 1126 15 16%23:14:46
5usenet.pasdenom.info 8 1611 198 1407 6 12%27:50:16
6nntp.terraraq.uk 5 1606 40 1565 1 2%23:50:56
7news.glou.org 3 1566 32 1518 16 2%23:54:19
8i2pn.org 1 67 4 63 0 5%01:00:45
9localhost 25 0 0 0 0 0%00:00:00
TOTAL: 9 49 13213 2147 10527 539 16%168:56:28
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news.nntp4.net4.5 MB0.0 KB5.3 MB9.8 MB 45%8.5 KB
2feed-out.gegeweb.org2.5 MB0.0 KB0.0 KB2.5 MB100%3.5 KB
3news.samoylyk.net2.4 MB0.0 KB0.0 KB2.4 MB100%10.5 KB
4gegeweb-out.news.tnetconsulting.net1.1 MB0.0 KB48.5 KB1.2 MB 95%5.1 KB
5usenet.pasdenom.info502.7 KB0.0 KB7.0 KB509.7 KB 98%2.5 KB
6nntp.terraraq.uk166.0 KB0.0 KB2.0 KB168.0 KB 98%4.1 KB
7news.glou.org104.0 KB0.0 KB42.3 KB146.3 KB 71%3.0 KB
8i2pn.org4.2 KB0.0 KB0.0 KB4.2 KB100%1.0 KB
9localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 911.3 MB0.0 KB5.4 MB16.7 MB 67%6.4 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Mar 12 04:15:02 - 04:59:59 57 2.6% 0.02314.6 KB 2.5% 0.12
Mar 12 05:00:00 - 05:59:59 43 1.9% 0.01184.3 KB 1.5% 0.05
Mar 12 06:00:00 - 06:59:59 36 1.6% 0.01125.6 KB 1.0% 0.03
Mar 12 07:00:00 - 07:59:59 48 2.2% 0.01228.3 KB 1.8% 0.06
Mar 12 08:00:00 - 08:59:59 55 2.5% 0.02320.7 KB 2.6% 0.09
Mar 12 09:00:00 - 09:59:59 58 2.6% 0.02252.4 KB 2.0% 0.07
Mar 12 10:00:00 - 10:59:59 103 4.7% 0.03429.7 KB 3.5% 0.12
Mar 12 11:00:00 - 11:59:59 96 4.3% 0.03579.3 KB 4.7% 0.16
Mar 12 12:00:00 - 12:59:59 96 4.3% 0.03360.2 KB 2.9% 0.10
Mar 12 13:00:00 - 13:59:59 101 4.6% 0.03381.6 KB 3.1% 0.11
Mar 12 14:00:00 - 14:59:59 78 3.5% 0.02301.2 KB 2.4% 0.08
Mar 12 15:00:00 - 15:59:59 80 3.6% 0.02303.4 KB 2.4% 0.08
Mar 12 16:00:00 - 16:59:59 94 4.3% 0.03594.8 KB 4.8% 0.17
Mar 12 17:00:00 - 17:59:59 250 11.3% 0.072.7 MB 22.4% 0.78
Mar 12 18:00:00 - 18:59:59 147 6.7% 0.04664.9 KB 5.3% 0.18
Mar 12 19:00:00 - 19:59:59 137 6.2% 0.04547.0 KB 4.4% 0.15
Mar 12 20:00:00 - 20:59:59 113 5.1% 0.03470.0 KB 3.8% 0.13
Mar 12 21:00:00 - 21:59:59 105 4.8% 0.03350.7 KB 2.8% 0.10
Mar 12 22:00:00 - 22:59:59 108 4.9% 0.03879.2 KB 7.1% 0.24
Mar 12 23:00:00 - 23:59:59 113 5.1% 0.03627.5 KB 5.0% 0.17
Mar 13 00:00:00 - 00:59:59 86 3.9% 0.02717.9 KB 5.8% 0.20
Mar 13 01:00:00 - 01:59:59 80 3.6% 0.02273.3 KB 2.2% 0.08
Mar 13 02:00:00 - 02:59:59 66 3.0% 0.02553.7 KB 4.4% 0.15
Mar 13 03:00:00 - 03:59:59 45 2.0% 0.01119.7 KB 1.0% 0.03
Mar 13 04:00:00 - 04:15:02 13 0.6% 0.0180.6 KB 0.6% 0.09
TOTAL: 24:00:00 2208 100.0% 0.0312.2 MB 100.0% 0.14
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nntp4.net 501 405 0 0 0 0 0 0 96
2gegeweb-out.news.tnetconsulting.net 16 0 0 0 0 0 0 0 16
3news.glou.org 16 0 0 0 0 0 0 0 16
4usenet.pasdenom.info 7 0 0 0 0 0 0 0 7
5nntp.terraraq.uk 3 0 0 0 0 0 0 0 3
6i2pn.org 2 0 0 0 0 0 0 0 2
TOTAL: 6 545 405 0 0 0 0 0 0 140

Unwanted newsgroups [Top 20]:

NewsgroupCount
linux.kernel.netdev 313
linux.kernel.git 91
news.test 1
TOTAL: 3 405

INND Perl filter [Top 20]:

ReasonCount
Too many newsgroups 84
Bad path (news.giganews.com.POSTED) 51
Cancels abusifs 5
TOTAL: 3 140

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1i2pn.org 3112 24 2015 7 0 120 0%23:59:58
2news.tnetconsulting.net 3124 12 2176 16 0 54 0%23:59:58
3news.gegeweb.org 1626 9 1479 0 0 0 0%23:59:58
4news.nntp4.net 1601 1 1514 0 0 0 0%23:51:29
5news.samoylyk.net 2521 1 1916 4 0 105 0%23:57:28
6news.pasdenom.info 1563 0 1495 0 0 0 0%23:54:16
7nntp.terraraq.uk 1170 0 1131 0 0 0 0%23:45:59
8news.aioe.org 0 0 0 0 0 1813 0%24:00:00
TOTAL: 8 14717 47 11726 27 0 2092 0%191:29:06
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1i2pn.org80.2 KB85.9 KB166.1 KB0.0 KB/s5.4 KB23:59:58
2news.tnetconsulting.net27.5 KB138.6 KB166.1 KB0.0 KB/s5.9 KB23:59:58
3news.gegeweb.org24.7 KB0.0 KB24.7 KB0.0 KB/s2.7 KB23:59:58
4news.nntp4.net5.4 KB0.0 KB5.4 KB0.0 KB/s5.4 KB23:51:29
5news.samoylyk.net3.7 KB22.4 KB26.1 KB0.0 KB/s5.2 KB23:57:28
6news.pasdenom.info0.0 KB0.0 KB0.0 KB0.0 KB/s023:54:16
7nntp.terraraq.uk0.0 KB0.0 KB0.0 KB0.0 KB/s023:45:59
8news.aioe.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:00
TOTAL: 8141.5 KB246.9 KB388.5 KB0.0 KB/s5.2 KB191:29:06
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1syn-066-061-096-239.res.spectrum.com 2 32453.8 MB 6 0 000:21:56
TOTAL: 1 2 32453.8 MB 6 0 000:27:59

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.res.spectrum.com 2 32453.8 MB 6 0 000:21:56
2*.211.203.35.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:01
3unresolved 20 00.0 KB 0 0 000:00:45
4*.scan.bufferover.run 1 00.0 KB 0 0 000:00:01
5*.150.216.162.bc.googleusercontent.com 2 00.0 KB 0 0 000:00:01
6*.stretchoid.com 1 00.0 KB 0 0 000:00:09
7?2870 00.0 KB 0 0 000:03:14
8*.gegeweb.eu 288 00.0 KB 0 0 000:01:24
9*.monitoring.internet-measurement.com 6 00.0 KB 0 0 000:00:07
10*.probe.onyphe.net 2 00.0 KB 0 0 000:00:03
11*.internet-census.org 1 00.0 KB 0 0 000:00:10
12*.us-east-2.compute.amazonaws.com 6 00.0 KB 0 0 000:00:01
TOTAL: 123200 32453.8 MB 6 0 000:27:59

NNRP auth users [Top 20]:

UserConn
mailman 288
TOTAL: 1 288

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
syn-066-061-096-239.res.spectrum.com 0.588 1.551 0.00000:21:56
2001:41d0:a:280::1 63.554 50.707 0.00000:03:14
mail.gegeweb.eu 4.843 4.346 1.87600:01:24
165.154.174.206 0.030 0.038 0.00000:00:11
198.235.24.115 0.024 0.020 0.00000:00:11
sh-ams-nl-gp1-wk111b.internet-census.org 0.019 0.005 0.00000:00:10
205.210.31.135 0.018 0.027 0.00000:00:10
azpdssqzcr3t.stretchoid.com 0.020 0.032 0.00000:00:09
199.45.154.131 0.248 0.133 0.00000:00:07
affable.monitoring.internet-measurement.com 0.045 0.018 0.00000:00:03
confident.monitoring.internet-measurement.com 0.027 0.034 0.00000:00:03
ayoub.probe.onyphe.net 0.025 0.025 0.00000:00:02
ec2-18-118-106-198.us-east-2.compute.amazonaws.com 0.136 0.079 0.00000:00:01
prague.scan.bufferover.run 0.067 0.021 0.00000:00:01
147.45.112.185 0.021 0.025 0.00000:00:01
101.150.216.162.bc.googleusercontent.com 0.032 0.008 0.00000:00:01
105.211.203.35.bc.googleusercontent.com 0.024 0.016 0.00000:00:01
91.238.181.94 0.028 0.020 0.00000:00:00
196.251.112.87 0.020 0.024 0.00000:00:00
212.102.40.218 0.053 0.031 0.00000:00:00
TOTAL: 26 70.007 57.274 1.87600:27:59

Curious NNRP server explorers [Top 20]:

SystemConn
2001:41d0:a:280::1 2870
mail.gegeweb.eu 288
199.45.154.131 7
ec2-18-118-106-198.us-east-2.compute.amazonaws.com 6
212.102.40.218 3
165.154.174.206 3
196.251.112.87 2
198.235.24.115 1
sunlit.monitoring.internet-measurement.com 1
sh-ams-nl-gp1-wk111b.internet-census.org 1
205.210.31.135 1
lucid.monitoring.internet-measurement.com 1
ayoub.probe.onyphe.net 1
147.45.112.185 1
affable.monitoring.internet-measurement.com 1
azpdssqzcr3t.stretchoid.com 1
successful.monitoring.internet-measurement.com 1
prague.scan.bufferover.run 1
194.165.16.165 1
101.150.216.162.bc.googleusercontent.com 1
TOTAL: 26 3198

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

SystemConn
ec2-18-118-106-198.us-east-2.compute.amazonaws.com 15
105.211.203.35.bc.googleusercontent.com 4
101.150.216.162.bc.googleusercontent.com 4
58.150.216.162.bc.googleusercontent.com 4
165.154.174.206 3
arwel.probe.onyphe.net 2
199.45.154.131 1
147.45.112.185 1
TOTAL: 8 34

NNRP unrecognized commands (by command):

CommandCount
Accept-Encoding: gzip 5
GET / HTTP/1.1 5
User-Agent: Expanse, a Palo Alto Network... 3
Host: 45.13.105.36:563 3
Host: 45.13.105.36:119 2
#001 2
#026#003#001 2
Accept: */* 2
User-Agent: Mozilla/5.0 (Macintosh; Inte... 2
EHLO www.censys.io 1
#026#003#001#001#027#001 1
À$À­À¯À,ÀrÀsÌ©#023#002#023#001Ì#024À#007À#022À#023À'À/À#024À(À0À`Àa... 1
À#024 1
#003 1
õ#003Ha»:±w¼ 1
#026#003#003#001¥#001 1
SSH-2.0-Go 1
TOTAL: 17 34

NNRP client timeouts [Top 20]:

SystemConnPeer
147.45.112.185 1 1
TOTAL: 1 1 3

Newsgroup request counts (by category):

CategoryCountPct
1grisbi 2618 80.7%
2local 627 19.3%
TOTAL: 2 3245100.0%

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

NewsgroupCount
1grisbi.test 1661
2grisbi.bugsreports 796
3local.test 565
4grisbi.cvs 96
5grisbi.user 65
6local.gegeweb.support 62
TOTAL: 6 3245
innreport from INN 2.6.4 based on work (c) 1996-1999 by Fabien Tassin <fta@sofaraway.org>
Local contact: usenet@niel.me