Daily Usenet report for news.niel.me

Oct 15 04:15:01 -- Oct 16 04:15:01

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Oct 15 17:00:05 rpi-studio pgpverify[17180]: pgpverify: verification failed
Oct 15 17:00:13 rpi-studio pgpverify[17202]: pgpverify: verification failed

Log entries by program:

Program nameLines%LinesSize%Size
inn 28329 73.5%5.5 MB 79.7%
innd 6328 16.4%890.2 KB 12.7%
innfeed 2055 5.3%330.2 KB 4.7%
nnrpd 1824 4.7%200.1 KB 2.9%
controlchan 13 0.0%2.2 KB 0.0%
pgpverify 2 0.0%0.3 KB 0.0%
nocem 1 0.0%0.1 KB 0.0%
TOTAL: 7 38552 100.0%6.8 MB100.0%

History cache:

ReasonCount%Count
Positive hits 81776 50.9%
Negative hits 50663 31.5%
Do not exist 28196 17.6%
Cache misses 9 0.0%
TOTAL: 4 160644 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.028 0.0%19 0.000 1.474 6.000
article cancel00:00:00.003 0.0%20 0.000 0.150 1.000
article cleanup00:00:00.593 0.0%28344 0.000 0.021 0.070
article logging00:00:02.164 0.0%28344 0.028 0.076 0.155
article parse00:00:07.383 0.0%137328 0.025 0.054 0.082
article write00:00:25.128 0.0%24107 0.481 1.042 2.486
artlog/artcncl00:00:00.001 0.0%20 0.000 0.050 0.200
artlog/artcncl/perl00:00:00.001 0.0%19 0.000 0.053 0.200
data move00:00:01.659 0.0%305614 0.001 0.005 0.014
hisgrep/artcncl00:00:00.000 0.0%19 0.000 0.000 0.000
hisgrep/artcncl/perl00:00:00.000 0.0%19 0.000 0.000 0.000
hisgrep/perl00:00:00.000 0.0%20 0.000 0.000 0.000
hishave/artcncl00:00:00.000 0.0%20 0.000 0.000 0.000
hishave/artcncl/perl00:00:00.000 0.0%19 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:02.748 0.0%161355 0.007 0.017 0.040
history sync00:00:00.050 0.0%381 0.000 0.131 1.000
history write00:00:05.671 0.0%28344 0.000 0.200 0.372
hiswrite/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
idle23:57:26.548 99.8%269349 157.302 320.204 2563.385
nntp read00:00:07.217 0.0%269224 0.012 0.027 0.044
overview write00:00:07.206 0.0%24107 0.107 0.299 1.207
perl filter00:01:38.184 0.1%28344 2.574 3.464 6.457
python filter00:00:00.139 0.0%28344 0.000 0.005 0.040
site send00:00:06.345 0.0%49997 0.050 0.127 0.250
TOTAL: 24:00:56.55424:00:11.068 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.232 0.0%24065 0.000 0.010 0.039
article prepare00:00:00.031 0.0%7056 0.000 0.004 0.062
article read00:00:00.207 0.0%1192 0.000 0.174 0.667
backlog stats00:00:01.714 0.0%383210 0.000 0.004 0.011
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:05.909 0.0%258539 0.010 0.023 0.042
data write00:00:09.749 0.0%199055 0.031 0.049 0.071
idle23:54:31.500100.0%383210 107.507 224.607 1835.813
status file00:00:00.423 0.0%219 0.000 1.932 4.000
TOTAL: 23:55:06.71323:54:49.765100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:31.114 22.2%1448 10.600 21.488 9161.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.101 0.1%3228 0.000 0.031 1.000
TOTAL: 00:02:19.87500:00:31.215 22.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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
group-admin@isc.org002202
TOTAL002202

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1news.nntp4.net 6 25844 9427 14539 1878 36%24:25:44
2nntp.terraraq.uk 6 18836 3851 13495 1490 20%25:53:42
3gegeweb-out.news.tnetconsulting.net 4 22427 3068 19310 49 13%23:01:57
4news.glou.org 2 8439 2720 4888 831 32%24:32:40
5feed-out.gegeweb.org 3 3489 2487 1002 0 71%24:02:38
6news.samoylyk.net 3 24059 2265 21794 0 9%24:25:01
7usenet.pasdenom.info 3 1307 69 1234 4 5%13:06:34
8i2pn.org 1 4440 62 4377 1 1%23:59:57
9localhost 25 0 0 0 0 0%00:00:00
TOTAL: 9 53 108841 23949 80639 4253 22%183:28:13
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news.nntp4.net82.7 MB0.0 KB19.8 MB102.4 MB 80%9.3 KB
2nntp.terraraq.uk34.0 MB0.0 KB7.4 MB41.4 MB 82%7.9 KB
3gegeweb-out.news.tnetconsulting.net26.5 MB0.0 KB575.9 KB27.0 MB 97%8.9 KB
4news.samoylyk.net20.9 MB0.0 KB0.0 KB20.9 MB100%9.4 KB
5news.glou.org15.9 MB0.0 KB4.5 MB20.3 MB 78%5.9 KB
6feed-out.gegeweb.org11.7 MB0.0 KB0.0 KB11.7 MB100%4.8 KB
7i2pn.org426.3 KB0.0 KB1.1 KB427.4 KB 99%6.8 KB
8usenet.pasdenom.info155.6 KB0.0 KB5.2 KB160.9 KB 96%2.2 KB
9localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 9192.1 MB0.0 KB32.2 MB224.3 MB 85%8.1 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 15 04:15:01 - 04:59:59 912 3.8% 0.346.8 MB 3.5% 2.60
Oct 15 05:00:00 - 05:59:59 774 3.2% 0.216.6 MB 3.4% 1.86
Oct 15 06:00:00 - 06:59:59 208 0.9% 0.061.4 MB 0.7% 0.39
Oct 15 07:00:00 - 07:59:59 341 1.4% 0.093.3 MB 1.7% 0.94
Oct 15 08:00:00 - 08:59:59 336 1.4% 0.093.0 MB 1.6% 0.86
Oct 15 09:00:00 - 09:59:59 438 1.8% 0.123.7 MB 1.9% 1.07
Oct 15 10:00:00 - 10:59:59 357 1.5% 0.102.7 MB 1.4% 0.77
Oct 15 11:00:00 - 11:59:59 883 3.7% 0.257.0 MB 3.6% 2.00
Oct 15 12:00:00 - 12:59:59 582 2.4% 0.164.9 MB 2.5% 1.40
Oct 15 13:00:00 - 13:59:59 1156 4.8% 0.3210.4 MB 5.4% 2.97
Oct 15 14:00:00 - 14:59:59 1682 7.0% 0.4715.2 MB 7.8% 4.31
Oct 15 15:00:00 - 15:59:59 1211 5.0% 0.349.8 MB 5.0% 2.79
Oct 15 16:00:00 - 16:59:59 1166 4.8% 0.3210.3 MB 5.3% 2.93
Oct 15 17:00:00 - 17:59:59 1567 6.5% 0.4413.1 MB 6.8% 3.74
Oct 15 18:00:00 - 18:59:59 1278 5.3% 0.359.8 MB 5.0% 2.78
Oct 15 19:00:00 - 19:59:59 1357 5.6% 0.3810.4 MB 5.3% 2.95
Oct 15 20:00:00 - 20:59:59 1525 6.3% 0.4212.6 MB 6.5% 3.58
Oct 15 21:00:00 - 21:59:59 1267 5.3% 0.3511.7 MB 6.0% 3.33
Oct 15 22:00:00 - 22:59:59 868 3.6% 0.248.2 MB 4.2% 2.33
Oct 15 23:00:00 - 23:59:59 960 4.0% 0.276.6 MB 3.4% 1.87
Oct 16 00:00:00 - 00:59:59 1386 5.8% 0.399.4 MB 4.8% 2.67
Oct 16 01:00:00 - 01:59:59 1430 5.9% 0.4010.0 MB 5.2% 2.86
Oct 16 02:00:00 - 02:59:59 1118 4.6% 0.317.8 MB 4.0% 2.23
Oct 16 03:00:00 - 03:59:59 994 4.1% 0.287.9 MB 4.1% 2.24
Oct 16 04:00:00 - 04:15:01 272 1.1% 0.302.0 MB 1.0% 2.30
TOTAL: 24:00:00 24068 100.0% 0.28194.7 MB 100.0% 2.31
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nntp4.net 1876 0 0 0 0 0 0 0 1876
2nntp.terraraq.uk 1474 1 0 0 0 0 0 0 1473
3news.glou.org 813 1 0 0 0 0 0 0 812
4gegeweb-out.news.tnetconsulting.net 51 0 0 0 0 0 0 0 51
5usenet.pasdenom.info 8 0 0 0 0 0 0 0 8
TOTAL: 5 4222 2 0 0 0 0 0 0 4220

Unwanted newsgroups [Top 20]:

NewsgroupCount
comp.lang.go 2
TOTAL: 1 2

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 3158
EMP (phr nph) 411
Too many newsgroups 408
EMP (phl) 85
EMP (phn path) 75
Bad path (news.giganews.com.POSTED) 73
Cancel for rejected article 3
Too many newsgroups (meow) 3
HTML Multipart 2
EMP (phn nph) 1
Cancels abusifs 1
TOTAL: 11 4220

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.nntp4.net 16934 387 14027 1 0 0 2%23:59:57
2nntp.terraraq.uk 21478 222 19098 34 0 19 1%23:59:57
3news.samoylyk.net 31472 196 21678 54 0 17 0%23:59:57
4i2pn.org 40704 145 23160 515 0 36 0%23:59:57
5news.tnetconsulting.net 39922 91 23901 53 0 12 0%23:59:52
6news.gegeweb.org 23145 33 21549 9 0 0 0%23:59:57
7news.aioe.org 0 0 0 0 0 4384 0%23:59:57
8news.pasdenom.info 25395 0 23625 36 0 4 0%23:59:57
TOTAL: 8 199050 1074 147038 702 0 4472 0%191:59:31
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.nntp4.net2.7 MB1.7 KB2.8 MB0.0 KB/s7.3 KB23:59:57
2nntp.terraraq.uk1.6 MB294.8 KB1.9 MB0.0 KB/s7.6 KB23:59:57
3news.samoylyk.net1.2 MB291.3 KB1.5 MB0.0 KB/s6.2 KB23:59:57
4news.gegeweb.org824.5 KB107.0 KB931.5 KB0.0 KB/s22.2 KB23:59:57
5news.tnetconsulting.net582.0 KB256.3 KB838.4 KB0.0 KB/s5.8 KB23:59:52
6i2pn.org572.4 KB3.7 MB4.3 MB0.1 KB/s6.6 KB23:59:57
7news.aioe.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:57
8news.pasdenom.info0.0 KB348.3 KB348.3 KB0.0 KB/s9.7 KB23:59:57
TOTAL: 87.5 MB5.0 MB12.5 MB0.0 KB/s7.2 KB191:59:31
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
145.133.172.77 8 00.0 KB 0 0 000:00:00
2mail.gegeweb.eu 288 00.0 KB 0 0 000:01:52
3gracious.monitoring.internet-measurement.com 2 00.0 KB 0 0 000:00:06
4192.241.195.12 1 00.0 KB 0 0 000:00:09
5tantalizing.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
680.66.88.215 2 00.0 KB 0 0 000:00:00
7robust.monitoring.internet-measurement.com 2 00.0 KB 0 0 000:00:02
8stellar.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:05
9kind.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
10214.150.216.162.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:00
TOTAL: 10 307 00.0 KB 0 0 000:02:19

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 11 00.0 KB 0 0 000:00:10
2*.monitoring.internet-measurement.com 7 00.0 KB 0 0 000:00:16
3*.gegeweb.eu 288 00.0 KB 0 0 000:01:52
4*.150.216.162.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:00
TOTAL: 4 307 00.0 KB 0 0 000:02:19

NNRP auth users [Top 20]:

UserConn
mailman 288
TOTAL: 1 288

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
mail.gegeweb.eu 4.553 4.117 1.59900:01:52
192.241.195.12 0.018 0.005 0.00000:00:09
gracious.monitoring.internet-measurement.com 0.075 0.038 0.00000:00:06
stellar.monitoring.internet-measurement.com 0.067 0.009 0.00000:00:05
robust.monitoring.internet-measurement.com 0.046 0.030 0.00000:00:02
tantalizing.monitoring.internet-measurement.com 0.008 0.016 0.00000:00:01
80.66.88.215 0.064 0.035 0.00000:00:00
214.150.216.162.bc.googleusercontent.com 0.056 0.028 0.00000:00:00
kind.monitoring.internet-measurement.com 0.020 0.008 0.00000:00:00
TOTAL: 9 4.907 4.286 1.59900:02:19

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

SystemConn
214.150.216.162.bc.googleusercontent.com 3
80.66.88.215 1
TOTAL: 2 4

NNRP unrecognized commands (by command):

CommandCount
GET / HTTP/1.1 1
#003 1
Host: 45.13.105.36:563 1
User-Agent: Expanse, a Palo Alto Network... 1
TOTAL: 4 4

NNRP client timeouts [Top 20]:

SystemConnPeer
80.66.88.215 1 1
TOTAL: 1 1 1