Daily Usenet report for news.niel.me

Mar 15 04:15:02 -- Mar 16 04:15:01

Unknown entries from news log file:

First 50 / 2871 lines (1.7%)

Mar 15 04:15:08 rpi-studio nnrpd[30288]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:15:38 rpi-studio nnrpd[30449]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:16:08 rpi-studio nnrpd[30519]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:16:38 rpi-studio nnrpd[30526]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:17:08 rpi-studio nnrpd[30545]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:17:38 rpi-studio nnrpd[30552]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:18:08 rpi-studio nnrpd[30563]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:18:38 rpi-studio nnrpd[30714]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:19:09 rpi-studio nnrpd[30724]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:19:40 rpi-studio nnrpd[30734]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:20:11 rpi-studio nnrpd[30747]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:20:41 rpi-studio nnrpd[30755]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:21:11 rpi-studio nnrpd[30764]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:21:41 rpi-studio nnrpd[30780]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:22:11 rpi-studio nnrpd[30789]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:22:41 rpi-studio nnrpd[30796]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:23:12 rpi-studio nnrpd[30814]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:23:42 rpi-studio nnrpd[30828]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:24:12 rpi-studio nnrpd[30837]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:24:42 rpi-studio nnrpd[30845]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:25:12 rpi-studio nnrpd[30856]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:25:42 rpi-studio nnrpd[30870]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:26:12 rpi-studio nnrpd[30881]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:26:42 rpi-studio nnrpd[30889]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:27:12 rpi-studio nnrpd[30903]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:27:42 rpi-studio nnrpd[30910]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:28:12 rpi-studio nnrpd[30922]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:28:42 rpi-studio nnrpd[31073]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:29:12 rpi-studio nnrpd[31089]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:29:42 rpi-studio nnrpd[31103]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:30:12 rpi-studio nnrpd[31118]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:30:42 rpi-studio nnrpd[31127]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:31:12 rpi-studio nnrpd[31138]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:31:42 rpi-studio nnrpd[31146]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:32:12 rpi-studio nnrpd[31157]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:32:43 rpi-studio nnrpd[31164]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:33:13 rpi-studio nnrpd[31173]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:33:43 rpi-studio nnrpd[31188]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:34:13 rpi-studio nnrpd[31197]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:34:43 rpi-studio nnrpd[31212]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:35:13 rpi-studio nnrpd[31230]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:35:43 rpi-studio nnrpd[31237]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:36:13 rpi-studio nnrpd[31247]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:36:43 rpi-studio nnrpd[31256]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:37:13 rpi-studio nnrpd[31273]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:37:43 rpi-studio nnrpd[31281]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:38:13 rpi-studio nnrpd[31295]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:38:43 rpi-studio nnrpd[31443]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:39:13 rpi-studio nnrpd[31452]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 15 04:39:43 rpi-studio nnrpd[31460]: 2001:41d0:a:280::1 noperm ihave without permission

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 19144 74.8%2.2 MB 67.6%
inn 2477 9.7%458.2 KB 13.7%
innd 2128 8.3%355.2 KB 10.6%
innfeed 1827 7.1%268.8 KB 8.0%
controlchan 13 0.1%2.3 KB 0.1%
pgpverify 2 0.0%0.2 KB 0.0%
nocem 1 0.0%0.2 KB 0.0%
TOTAL: 7 25592 100.0%3.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 12493 56.0%
Negative hits 7362 33.0%
Do not exist 2459 11.0%
Cache misses 5 0.0%
TOTAL: 4 22319 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.010 0.0%6 0.000 1.667 6.000
article cancel00:00:00.001 0.0%6 0.000 0.167 0.500
article cleanup00:00:00.062 0.0%2467 0.000 0.025 0.273
article logging00:00:00.197 0.0%2468 0.000 0.080 0.400
article parse00:00:00.417 0.0%7147 0.000 0.058 0.500
article write00:00:01.114 0.0%2256 0.125 0.494 1.429
artlog/artcncl00:00:00.001 0.0%6 0.000 0.167 0.500
artlog/artcncl/perl00:00:00.000 0.0%6 0.000 0.000 0.000
data move00:00:00.140 0.0%25610 0.000 0.005 0.042
hisgrep/artcncl00:00:00.000 0.0%6 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.000 0.0%7 0.000 0.000 0.000
hishave/artcncl00:00:00.000 0.0%6 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.345 0.0%22377 0.000 0.015 0.049
history sync00:00:00.055 0.0%419 0.000 0.131 0.667
history write00:00:00.396 0.0%2467 0.000 0.161 0.400
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle24:00:41.661100.0%32760 446.711 2638.634 8696.058
nntp read00:00:00.805 0.0%26313 0.000 0.031 0.125
overview write00:00:00.642 0.0%2256 0.000 0.285 3.000
perl filter00:00:09.265 0.0%2467 1.269 3.756 9.500
python filter00:00:00.021 0.0%2467 0.000 0.009 0.167
site send00:00:00.958 0.0%4579 0.000 0.209 0.500
TOTAL: 24:01:11.60924:00:56.090100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.020 0.0%2361 0.000 0.008 0.200
article prepare00:00:00.001 0.0%260 0.000 0.004 0.125
article read00:00:00.011 0.0%63 0.000 0.175 3.000
backlog stats00:00:00.149 0.0%30612 0.000 0.005 0.040
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.440 0.0%16747 0.000 0.026 0.083
data write00:00:00.833 0.0%14694 0.000 0.057 0.222
idle23:59:33.629100.0%30612 998.342 2821.561 8705.319
status file00:00:00.595 0.0%267 1.000 2.228 23.000
TOTAL: 23:59:39.53223:59:35.678100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle01:24:01.266 96.6%22055 8.000 228.577 10041.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:02.786 0.1%67663 0.000 0.041 1.000
readart00:00:02.156 0.0%11584 0.186 0.186 0.186
TOTAL: 01:26:59.77801:24:06.208 96.7%----

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 4 2175 535 1640 0 24%23:56:05
2news.nntp4.net 3 2264 534 1568 162 23%22:42:57
3news.samoylyk.net 4 4283 513 3769 1 11%45:14:11
4gegeweb-out.news.tnetconsulting.net 2 1573 329 1235 9 20%24:23:10
5usenet.pasdenom.info 9 2171 227 1941 3 10%41:33:25
6i2pn.org 3 2356 143 2206 7 6%27:12:18
7nntp.terraraq.uk 6 1475 60 1415 0 4%23:04:10
8news.glou.org 7 770 8 760 2 1%13:51:32
9localhost 25 0 0 0 0 0%00:00:00
TOTAL: 9 63 17067 2349 14534 184 13%221:57:48
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news.samoylyk.net5.5 MB367.1 KB367.1 KB6.2 MB 88%12.4 KB
2news.nntp4.net2.9 MB0.0 KB1.2 MB4.1 MB 71%6.1 KB
3feed-out.gegeweb.org1.7 MB0.0 KB0.0 KB1.7 MB100%3.3 KB
4gegeweb-out.news.tnetconsulting.net996.0 KB0.0 KB50.5 KB1.0 MB 95%3.1 KB
5usenet.pasdenom.info533.0 KB0.0 KB3.3 KB536.3 KB 99%2.3 KB
6nntp.terraraq.uk398.9 KB0.0 KB0.0 KB398.9 KB100%6.6 KB
7i2pn.org377.0 KB0.0 KB13.4 KB390.4 KB 96%2.6 KB
8news.glou.org20.5 KB0.0 KB3.8 KB24.3 KB 84%2.4 KB
9localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 912.5 MB367.1 KB1.6 MB14.4 MB 86%5.8 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Mar 15 04:15:02 - 04:59:59 32 1.4% 0.0188.5 KB 0.7% 0.03
Mar 15 05:00:00 - 05:59:59 56 2.5% 0.02169.6 KB 1.4% 0.05
Mar 15 06:00:00 - 06:59:59 25 1.1% 0.0168.3 KB 0.6% 0.02
Mar 15 07:00:00 - 07:59:59 42 1.9% 0.01150.2 KB 1.2% 0.04
Mar 15 08:00:00 - 08:59:59 30 1.3% 0.01296.3 KB 2.4% 0.08
Mar 15 09:00:00 - 09:59:59 63 2.8% 0.02552.5 KB 4.5% 0.15
Mar 15 10:00:00 - 10:59:59 117 5.2% 0.03523.1 KB 4.3% 0.15
Mar 15 11:00:00 - 11:59:59 171 7.6% 0.052.6 MB 21.5% 0.73
Mar 15 12:00:00 - 12:59:59 118 5.2% 0.03385.7 KB 3.1% 0.11
Mar 15 13:00:00 - 13:59:59 101 4.5% 0.03341.5 KB 2.8% 0.09
Mar 15 14:00:00 - 14:59:59 125 5.5% 0.03787.4 KB 6.4% 0.22
Mar 15 15:00:00 - 15:59:59 99 4.4% 0.03306.3 KB 2.5% 0.09
Mar 15 16:00:00 - 16:59:59 147 6.5% 0.041.2 MB 10.0% 0.34
Mar 15 17:00:00 - 17:59:59 126 5.6% 0.04561.6 KB 4.6% 0.16
Mar 15 18:00:00 - 18:59:59 127 5.6% 0.04648.0 KB 5.3% 0.18
Mar 15 19:00:00 - 19:59:59 122 5.4% 0.03446.7 KB 3.6% 0.12
Mar 15 20:00:00 - 20:59:59 91 4.0% 0.03382.5 KB 3.1% 0.11
Mar 15 21:00:00 - 21:59:59 103 4.6% 0.03461.4 KB 3.8% 0.13
Mar 15 22:00:00 - 22:59:59 123 5.5% 0.03546.9 KB 4.5% 0.15
Mar 15 23:00:00 - 23:59:59 91 4.0% 0.03390.5 KB 3.2% 0.11
Mar 16 00:00:00 - 00:59:59 91 4.0% 0.03338.2 KB 2.8% 0.09
Mar 16 01:00:00 - 01:59:59 59 2.6% 0.02203.6 KB 1.7% 0.06
Mar 16 02:00:00 - 02:59:59 75 3.3% 0.02252.8 KB 2.1% 0.07
Mar 16 03:00:00 - 03:59:59 103 4.6% 0.03446.5 KB 3.6% 0.12
Mar 16 04:00:00 - 04:15:01 16 0.7% 0.0270.3 KB 0.6% 0.08
TOTAL: 23:59:59 2253 100.0% 0.0312.0 MB 100.0% 0.14
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nntp4.net 181 95 0 0 0 0 0 0 86
2gegeweb-out.news.tnetconsulting.net 18 0 0 0 0 0 0 0 18
3news.glou.org 8 0 0 0 0 0 0 0 8
4i2pn.org 2 0 0 0 0 0 0 0 2
5nntp.terraraq.uk 1 0 0 0 0 0 0 0 1
6usenet.pasdenom.info 1 0 0 0 0 0 0 0 1
7feed-out.gegeweb.org 1 0 0 0 0 0 0 0 1
TOTAL: 7 212 95 0 0 0 0 0 0 117

Unwanted newsgroups [Top 20]:

NewsgroupCount
linux.kernel.netdev 79
linux.kernel.git 15
news.test 1
TOTAL: 3 95

INND Perl filter [Top 20]:

ReasonCount
Too many newsgroups 82
Bad path (news.giganews.com.POSTED) 32
No Cancel-Key matches Cancel-Lock. target=<vr3h3a$63s$1@rasp.pasdenom.info> 1
EMP (md5) 1
TOTAL: 4 116

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
feed-out.gegeweb.org 1
TOTAL: 1 1
TOTAL: 1 1

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1i2pn.org 3151 20 2031 5 0 50 0%23:56:47
2news.tnetconsulting.net 3359 18 2219 7 0 61 0%23:56:46
3news.gegeweb.org 1895 12 1698 0 0 0 0%23:55:56
4nntp.terraraq.uk 1274 1 1216 0 0 0 0%23:37:28
5news.pasdenom.info 1652 0 1571 2 0 0 0%23:54:47
6news.aioe.org 0 0 0 0 0 1902 0%23:59:59
7news.samoylyk.net 2086 0 1733 0 0 0 0%23:56:43
8news.nntp4.net 1729 0 1647 0 0 0 0%23:47:54
TOTAL: 8 15146 51 12115 14 0 2013 0%191:06:20
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1i2pn.org83.5 KB87.9 KB171.3 KB0.0 KB/s6.9 KB23:56:47
2news.tnetconsulting.net74.8 KB26.2 KB100.9 KB0.0 KB/s4.0 KB23:56:46
3news.gegeweb.org37.3 KB0.0 KB37.3 KB0.0 KB/s3.1 KB23:55:56
4nntp.terraraq.uk2.9 KB0.0 KB2.9 KB0.0 KB/s2.9 KB23:37:28
5news.pasdenom.info0.0 KB5.2 KB5.2 KB0.0 KB/s2.6 KB23:54:47
6news.aioe.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:59
7news.samoylyk.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:56:43
8news.nntp4.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:47:54
TOTAL: 8198.5 KB119.2 KB317.7 KB0.0 KB/s4.9 KB191:06:20
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1syn-066-061-096-239.res.spectrum.com 8 1158418.6 MB 9 0 001:21:04
TOTAL: 1 8 1158418.6 MB 9 0 001:26:55

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.res.spectrum.com 8 1158418.6 MB 9 0 001:21:04
2*.monitoring.internet-measurement.com 5 00.0 KB 0 0 000:00:07
3*.211.203.35.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:01
4*.stretchoid.com 2 00.0 KB 0 0 000:00:10
5?2869 00.0 KB 0 0 000:03:21
6*.150.216.162.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:00
7unresolved 16 00.0 KB 0 0 000:00:40
8*.gegeweb.eu 288 00.0 KB 0 0 000:01:28
TOTAL: 83190 1158418.6 MB 9 0 001:26:55

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 2.222 5.438 0.00001:21:04
2001:41d0:a:280::1 63.251 49.991 0.00000:03:18
mail.gegeweb.eu 4.578 4.119 1.77100:01:28
198.235.24.69 0.011 0.011 0.00000:00:10
205.210.31.137 0.027 0.024 0.00000:00:10
azpdcs3iaalm.stretchoid.com 0.008 0.016 0.00000:00:09
199.45.154.157 0.254 0.113 0.00000:00:06
206.168.34.74 0.089 0.055 0.00000:00:05
intuitive.monitoring.internet-measurement.com 0.028 0.005 0.00000:00:03
206.168.34.121 0.027 0.013 0.00000:00:03
enchanted.monitoring.internet-measurement.com 0.029 0.011 0.00000:00:03
2a06:4882:7000::7f 0.027 0.018 0.00000:00:02
91.238.181.93 0.020 0.008 0.00000:00:01
147.45.112.185 0.013 0.009 0.00000:00:01
86.211.203.35.bc.googleusercontent.com 0.056 0.022 0.00000:00:01
azpdeg49.stretchoid.com 0.029 0.017 0.00000:00:00
192.150.216.162.bc.googleusercontent.com 0.032 0.008 0.00000:00:00
audacious.monitoring.internet-measurement.com 0.041 0.025 0.00000:00:00
nifty.monitoring.internet-measurement.com 0.015 0.015 0.00000:00:00
194.164.107.6 0.027 0.023 0.00000:00:00
TOTAL: 21 70.817 59.966 1.77101:26:55

Curious NNRP server explorers [Top 20]:

SystemConn
2001:41d0:a:280::1 2868
mail.gegeweb.eu 288
199.45.154.157 7
206.168.34.74 3
91.238.181.93 1
192.150.216.162.bc.googleusercontent.com 1
86.211.203.35.bc.googleusercontent.com 1
nifty.monitoring.internet-measurement.com 1
azpdcs3iaalm.stretchoid.com 1
intuitive.monitoring.internet-measurement.com 1
curious.monitoring.internet-measurement.com 1
enchanted.monitoring.internet-measurement.com 1
205.210.31.137 1
147.45.112.185 1
194.164.107.6 1
2a06:4882:7000::7f 1
audacious.monitoring.internet-measurement.com 1
azpdeg49.stretchoid.com 1
206.168.34.121 1
198.235.24.69 1
TOTAL: 20 3182

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

SystemConn
192.150.216.162.bc.googleusercontent.com 4
86.211.203.35.bc.googleusercontent.com 4
147.45.112.185 1
206.168.34.121 1
azpdeg49.stretchoid.com 1
199.45.154.157 1
TOTAL: 6 12

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

SystemConnPeer
147.45.112.185 1 1
TOTAL: 1 1 1

Newsgroup request counts (by category):

CategoryCountPct
1grisbi 10957 94.6%
2local 627 5.4%
TOTAL: 2 11584100.0%

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

NewsgroupCount
1grisbi.bugsreports 4740
2grisbi.test 3444
3grisbi.user.french 1661
4grisbi.devel 948
5local.test 565
6grisbi.cvs 96
7grisbi.user 66
8local.gegeweb.support 62
9grisbi.admin 2
TOTAL: 9 11584
innreport from INN 2.6.4 based on work (c) 1996-1999 by Fabien Tassin <fta@sofaraway.org>
Local contact: usenet@niel.me