Daily Usenet report for news.niel.me

Mar 28 04:15:01 -- Mar 29 04:15:01

Unknown entries from news log file:

First 50 / 2857 lines (1.8%)

Mar 28 04:15:21 rpi-studio nnrpd[10491]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:20:02 rpi-studio nnrpd[10651]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:20:32 rpi-studio nnrpd[10821]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:21:03 rpi-studio nnrpd[10836]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:21:33 rpi-studio nnrpd[10850]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:22:03 rpi-studio nnrpd[10867]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:23:44 rpi-studio nnrpd[11023]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:24:14 rpi-studio nnrpd[11063]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:24:44 rpi-studio nnrpd[11070]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:25:14 rpi-studio nnrpd[11082]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:25:44 rpi-studio nnrpd[11090]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:26:14 rpi-studio nnrpd[11111]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:26:44 rpi-studio nnrpd[11121]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:27:14 rpi-studio nnrpd[11133]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:27:45 rpi-studio nnrpd[11140]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:28:16 rpi-studio nnrpd[11149]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:28:46 rpi-studio nnrpd[11162]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:29:16 rpi-studio nnrpd[11171]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:29:46 rpi-studio nnrpd[11183]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:30:16 rpi-studio nnrpd[11207]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:30:46 rpi-studio nnrpd[11225]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:31:16 rpi-studio nnrpd[11234]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:31:46 rpi-studio nnrpd[11243]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:32:16 rpi-studio nnrpd[11401]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:32:46 rpi-studio nnrpd[11409]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:33:16 rpi-studio nnrpd[11425]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:33:47 rpi-studio nnrpd[11436]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:34:17 rpi-studio nnrpd[11445]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:34:47 rpi-studio nnrpd[11452]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:35:17 rpi-studio nnrpd[11478]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:35:47 rpi-studio nnrpd[11485]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:36:17 rpi-studio nnrpd[11494]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:36:47 rpi-studio nnrpd[11502]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:37:17 rpi-studio nnrpd[11519]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:37:47 rpi-studio nnrpd[11527]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:38:17 rpi-studio nnrpd[11536]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:38:48 rpi-studio nnrpd[11549]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:39:18 rpi-studio nnrpd[11559]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:39:48 rpi-studio nnrpd[11566]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:40:18 rpi-studio nnrpd[11578]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:40:48 rpi-studio nnrpd[11592]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:41:18 rpi-studio nnrpd[11624]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:41:48 rpi-studio nnrpd[11641]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:42:18 rpi-studio nnrpd[11797]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:42:48 rpi-studio nnrpd[11805]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:43:18 rpi-studio nnrpd[11828]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:43:48 rpi-studio nnrpd[11847]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:44:18 rpi-studio nnrpd[11857]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:44:48 rpi-studio nnrpd[11864]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 28 04:45:18 rpi-studio nnrpd[11876]: 2001:41d0:a:280::1 noperm ihave without permission

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 19700 75.3%2.3 MB 68.1%
inn 2500 9.6%463.8 KB 13.6%
innd 2127 8.1%355.0 KB 10.4%
innfeed 1833 7.0%272.7 KB 8.0%
controlchan 1 0.0%0.2 KB 0.0%
nocem 1 0.0%0.1 KB 0.0%
TOTAL: 6 26162 100.0%3.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 12479 53.6%
Negative hits 8323 35.7%
Do not exist 2486 10.7%
Cache misses 2 0.0%
TOTAL: 4 23290 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.003 0.0%3 1.000 1.000 1.000
article cancel00:00:00.003 0.0%4 0.000 0.750 1.000
article cleanup00:00:00.072 0.0%2488 0.000 0.029 0.222
article logging00:00:00.223 0.0%2488 0.000 0.090 0.500
article parse00:00:00.384 0.0%6145 0.000 0.062 0.333
article write00:00:01.195 0.0%2225 0.000 0.537 1.333
artlog/artcncl00:00:00.001 0.0%4 0.000 0.250 1.000
artlog/artcncl/perl00:00:00.000 0.0%3 0.000 0.000 0.000
data move00:00:00.150 0.0%24985 0.000 0.006 0.043
hisgrep/artcncl00:00:00.001 0.0%4 0.000 0.250 1.000
hisgrep/artcncl/perl00:00:00.001 0.0%3 0.000 0.333 1.000
hisgrep/perl00:00:00.000 0.0%4 0.000 0.000 0.000
hishave/artcncl00:00:00.000 0.0%4 0.000 0.000 0.000
hishave/artcncl/perl00:00:00.000 0.0%3 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.370 0.0%23297 0.000 0.016 0.056
history sync00:00:00.072 0.0%419 0.000 0.172 1.000
history write00:00:00.432 0.0%2488 0.000 0.174 0.500
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle24:00:30.917100.0%32804 1016.029 2634.768 10344.603
nntp read00:00:00.791 0.0%26382 0.000 0.030 0.073
overview write00:00:00.678 0.0%2225 0.000 0.305 4.000
perl filter00:00:13.937 0.0%2488 1.333 5.602 14.600
python filter00:00:00.026 0.0%2488 0.000 0.010 0.200
site send00:00:00.870 0.0%4521 0.000 0.192 0.667
TOTAL: 24:01:08.27324:00:50.126100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.017 0.0%2312 0.000 0.007 0.143
article prepare00:00:00.000 0.0%144 0.000 0.000 0.000
article read00:00:00.011 0.0%35 0.000 0.314 3.000
backlog stats00:00:00.173 0.0%30164 0.000 0.006 0.036
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.459 0.0%16679 0.000 0.028 0.093
data write00:00:00.766 0.0%14626 0.000 0.052 0.125
idle23:59:19.975100.0%30164 1230.211 2863.015 8847.397
status file00:00:05.657 0.0%260 1.000 21.758 87.000
TOTAL: 23:59:30.75123:59:27.058100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:25:17.302 88.2%10484 9.400 144.725 83783.667
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:01.999 0.1%21467 0.000 0.093 1.398
readart00:00:00.000 0.0%9 0.000 0.000 0.000
TOTAL: 00:28:39.85800:25:19.301 88.3%----

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.nntp4.net 1 3176 671 2197 308 21%25:12:22
2feed-out.gegeweb.org 3 2227 512 1715 0 22%23:53:38
3gegeweb-out.news.tnetconsulting.net 6 1811 479 1310 22 26%28:28:38
4news.samoylyk.net 2 3439 208 3231 0 6%15:02:37
5i2pn.org 3 4486 137 4346 3 3%23:51:51
6usenet.pasdenom.info 7 2388 79 2308 1 3%21:44:04
7news.glou.org 4 3486 50 3406 30 1%24:53:21
8nntp.terraraq.uk 3 1724 5 1718 1 0%05:32:23
937.187.2.128 0 321 4 317 0 1%06:20:45
10localhost 25 0 0 0 0 0%00:00:00
TOTAL: 10 54 23058 2145 20548 365 9%174:59:39
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news.nntp4.net2.8 MB0.0 KB2.0 MB4.8 MB 57%5.0 KB
2gegeweb-out.news.tnetconsulting.net1.6 MB0.0 KB93.1 KB1.7 MB 94%3.4 KB
3feed-out.gegeweb.org1.5 MB0.0 KB0.0 KB1.5 MB100%3.1 KB
4news.samoylyk.net1011.7 KB0.0 KB0.0 KB1011.7 KB100%4.9 KB
5i2pn.org353.7 KB0.0 KB5.7 KB359.4 KB 98%2.6 KB
6news.glou.org261.7 KB0.0 KB85.4 KB347.1 KB 75%4.3 KB
7usenet.pasdenom.info198.4 KB0.0 KB1.2 KB199.6 KB 99%2.5 KB
8nntp.terraraq.uk13.6 KB0.0 KB1.5 KB15.1 KB 90%2.5 KB
937.187.2.12810.9 KB0.0 KB0.0 KB10.9 KB100%2.7 KB
10localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 107.7 MB0.0 KB2.2 MB9.9 MB 77%4.0 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Mar 28 04:15:01 - 04:59:59 20 0.9% 0.0160.6 KB 0.7% 0.02
Mar 28 05:00:00 - 05:59:59 48 2.2% 0.01274.8 KB 3.1% 0.08
Mar 28 06:00:00 - 06:59:59 29 1.3% 0.0175.2 KB 0.8% 0.02
Mar 28 07:00:00 - 07:59:59 37 1.7% 0.01133.8 KB 1.5% 0.04
Mar 28 08:00:00 - 08:59:59 54 2.4% 0.01184.5 KB 2.1% 0.05
Mar 28 09:00:00 - 09:59:59 71 3.2% 0.02292.6 KB 3.3% 0.08
Mar 28 10:00:00 - 10:59:59 87 3.9% 0.02305.1 KB 3.4% 0.08
Mar 28 11:00:00 - 11:59:59 99 4.4% 0.03372.5 KB 4.2% 0.10
Mar 28 12:00:00 - 12:59:59 98 4.4% 0.03312.5 KB 3.5% 0.09
Mar 28 13:00:00 - 13:59:59 113 5.1% 0.03450.3 KB 5.0% 0.13
Mar 28 14:00:00 - 14:59:59 94 4.2% 0.03296.8 KB 3.3% 0.08
Mar 28 15:00:00 - 15:59:59 125 5.6% 0.03370.1 KB 4.1% 0.10
Mar 28 16:00:00 - 16:59:59 114 5.1% 0.03447.0 KB 5.0% 0.12
Mar 28 17:00:00 - 17:59:59 115 5.2% 0.03362.9 KB 4.1% 0.10
Mar 28 18:00:00 - 18:59:59 111 5.0% 0.03417.2 KB 4.7% 0.12
Mar 28 19:00:00 - 19:59:59 131 5.9% 0.04489.9 KB 5.5% 0.14
Mar 28 20:00:00 - 20:59:59 143 6.4% 0.04536.9 KB 6.0% 0.15
Mar 28 21:00:00 - 21:59:59 171 7.7% 0.05982.1 KB 11.0% 0.27
Mar 28 22:00:00 - 22:59:59 154 6.9% 0.04786.1 KB 8.8% 0.22
Mar 28 23:00:00 - 23:59:59 148 6.6% 0.04575.6 KB 6.4% 0.16
Mar 29 00:00:00 - 00:59:59 84 3.8% 0.02357.8 KB 4.0% 0.10
Mar 29 01:00:00 - 01:59:59 70 3.1% 0.02303.7 KB 3.4% 0.08
Mar 29 02:00:00 - 02:59:59 46 2.1% 0.01184.3 KB 2.1% 0.05
Mar 29 03:00:00 - 03:59:59 52 2.3% 0.01251.9 KB 2.8% 0.07
Mar 29 04:00:00 - 04:15:01 16 0.7% 0.02112.0 KB 1.3% 0.12
TOTAL: 24:00:00 2230 100.0% 0.038.7 MB 100.0% 0.10
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nntp4.net 206 115 0 0 0 0 0 0 91
2news.glou.org 29 0 0 0 0 0 0 0 29
3gegeweb-out.news.tnetconsulting.net 16 0 0 0 0 0 0 0 16
4nntp.terraraq.uk 8 0 0 0 0 0 0 0 8
5i2pn.org 3 0 0 0 0 0 0 0 3
6usenet.pasdenom.info 1 0 0 0 0 0 0 0 1
TOTAL: 6 263 115 0 0 0 0 0 0 148

Unwanted newsgroups [Top 20]:

NewsgroupCount
linux.kernel.netdev 84
linux.kernel.git 30
news.test 1
TOTAL: 3 115

INND Perl filter [Top 20]:

ReasonCount
Too many newsgroups 76
Bad path (news.giganews.com.POSTED) 66
HTML Multipart 4
EMP (md5) 1
Cancels abusifs 1
TOTAL: 5 148

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1i2pn.org 3139 6 2079 8 0 54 0%23:55:03
2news.tnetconsulting.net 3308 4 2208 14 0 30 0%23:54:02
3news.gegeweb.org 1852 4 1719 0 0 0 0%23:53:33
4news.samoylyk.net 2069 0 1799 0 0 0 0%23:50:03
5news.aioe.org 0 0 0 0 0 1829 0%24:00:00
6news.nntp4.net 1659 0 1571 0 0 0 0%23:44:37
7nntp.terraraq.uk 1327 0 1292 0 0 0 0%23:42:23
8news.pasdenom.info 1664 0 1604 0 0 0 0%23:45:11
TOTAL: 8 15018 14 12272 22 0 1913 0%190:44:52
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1i2pn.org14.3 KB28.8 KB43.1 KB0.0 KB/s3.1 KB23:55:03
2news.gegeweb.org13.7 KB0.0 KB13.7 KB0.0 KB/s3.4 KB23:53:33
3news.tnetconsulting.net11.8 KB69.7 KB81.5 KB0.0 KB/s4.5 KB23:54:02
4news.samoylyk.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:03
5news.aioe.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:00
6news.nntp4.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:44:37
7nntp.terraraq.uk0.0 KB0.0 KB0.0 KB0.0 KB/s023:42:23
8news.pasdenom.info0.0 KB0.0 KB0.0 KB0.0 KB/s023:45:11
TOTAL: 839.8 KB98.5 KB138.3 KB0.0 KB/s3.8 KB190:44:52
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1syn-066-061-096-239.res.spectrum.com 37 913.5 KB 5 0 000:16:34
TOTAL: 1 37 913.5 KB 5 0 000:28:35

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.res.spectrum.com 37 913.5 KB 5 0 000:16:34
2*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
3*.210.203.35.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:00
4*.gegeweb.eu 288 00.0 KB 0 0 000:01:37
5?2862 00.0 KB 0 0 000:09:31
6*.149.216.162.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:01
7*.stretchoid.com 2 00.0 KB 0 0 000:00:10
8*.211.203.35.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:01
9unresolved 104 00.0 KB 0 0 000:00:36
TOTAL: 93297 913.5 KB 5 0 000:28:35

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.938 0.758 0.00000:16:34
2001:41d0:a:280::1 62.197 49.304 0.00000:09:19
mail.gegeweb.eu 4.604 4.240 1.83700:01:37
104.156.155.33 2.289 1.033 0.00000:00:17
165.154.51.27 0.364 0.182 0.00000:00:09
azpdesiwc3ty.stretchoid.com 0.035 0.017 0.00000:00:09
2a06:4882:1000::1b 0.058 0.015 0.00000:00:04
205.210.31.144 0.030 0.021 0.00000:00:03
2a06:4882:1000::11 0.022 0.029 0.00000:00:03
2a06:4882:3000::37 0.035 0.016 0.00000:00:02
206.168.34.64 0.029 0.029 0.00000:00:02
fantastical.monitoring.internet-measurement.com 0.032 0.020 0.00000:00:01
198.235.24.59 0.030 0.021 0.00000:00:01
19.211.203.35.bc.googleusercontent.com 0.028 0.012 0.00000:00:01
254.149.216.162.bc.googleusercontent.com 0.018 0.022 0.00000:00:01
36.210.203.35.bc.googleusercontent.com 0.028 0.024 0.00000:00:00
198.235.24.117 0.015 0.008 0.00000:00:00
2a06:4883:7000::91 0.039 0.023 0.00000:00:00
194.165.16.164 0.036 0.020 0.00000:00:00
2a06:4883:7000::74 0.034 0.025 0.00000:00:00
TOTAL: 23 70.935 55.874 1.83700:28:35

Curious NNRP server explorers [Top 20]:

SystemConn
2001:41d0:a:280::1 2856
mail.gegeweb.eu 288
104.156.155.33 85
165.154.51.27 13
2a06:4882:3000::37 1
36.210.203.35.bc.googleusercontent.com 1
198.235.24.59 1
fantastical.monitoring.internet-measurement.com 1
194.165.16.164 1
2a06:4882:1000::11 1
19.211.203.35.bc.googleusercontent.com 1
2a06:4883:7000::74 1
194.165.16.165 1
2a06:4883:7000::91 1
azpdesiwc3ty.stretchoid.com 1
2a06:4882:1000::1b 1
azpdwgtoc7bg.stretchoid.com 1
2a06:4883:7000::7b 1
198.235.24.117 1
205.210.31.144 1
TOTAL: 22 3260

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

SystemConn
254.149.216.162.bc.googleusercontent.com 4
36.210.203.35.bc.googleusercontent.com 4
19.211.203.35.bc.googleusercontent.com 4
azpdwgtoc7bg.stretchoid.com 1
206.168.34.64 1
194.165.16.165 1
TOTAL: 6 15

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

SystemConnPeer
194.165.16.165 1 1
TOTAL: 1 1 4

Newsgroup request counts (by category):

CategoryCountPct
1grisbi 7 77.8%
2local 2 22.2%
TOTAL: 2 9100.0%

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

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