Daily Usenet report for news.niel.me

Jul 1 04:15:01 -- Jul 2 04:15:01

Log entries by program:

Program nameLines%LinesSize%Size
inn 2605 30.8%487.1 KB 35.7%
innd 2272 26.9%390.3 KB 28.6%
nnrpd 1822 21.6%196.4 KB 14.4%
innfeed 1753 20.7%291.3 KB 21.3%
nocem 1 0.0%0.1 KB 0.0%
controlchan 1 0.0%0.0 KB 0.0%
TOTAL: 6 8454 100.0%1.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 14300 59.4%
Negative hits 7212 29.9%
Do not exist 2572 10.7%
Cache misses 5 0.0%
TOTAL: 4 24089 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.014 0.0%9 0.000 1.556 3.000
article cancel00:00:00.000 0.0%9 0.000 0.000 0.000
article cleanup00:00:00.053 0.0%2580 0.000 0.021 0.286
article logging00:00:00.167 0.0%2582 0.000 0.065 0.500
article parse00:00:00.351 0.0%7769 0.000 0.045 0.300
article write00:00:01.149 0.0%2301 0.000 0.499 1.625
artlog/artcncl00:00:00.000 0.0%9 0.000 0.000 0.000
artlog/artcncl/perl00:00:00.001 0.0%9 0.000 0.111 1.000
data move00:00:00.117 0.0%26744 0.000 0.004 0.025
hisgrep/artcncl00:00:00.000 0.0%9 0.000 0.000 0.000
hisgrep/artcncl/perl00:00:00.000 0.0%9 0.000 0.000 0.000
hisgrep/perl00:00:00.001 0.0%19 0.000 0.053 0.500
hishave/artcncl00:00:00.000 0.0%9 0.000 0.000 0.000
hishave/artcncl/perl00:00:00.000 0.0%9 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.357 0.0%24125 0.000 0.015 0.061
history sync00:00:00.048 0.0%355 0.000 0.135 1.000
history write00:00:00.417 0.0%2580 0.000 0.162 0.556
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle24:00:37.909100.0%29383 939.882 2941.766 13653.614
nntp read00:00:00.728 0.0%28714 0.000 0.025 0.065
overview write00:00:01.005 0.0%2301 0.000 0.437 4.500
perl filter00:00:11.219 0.0%2580 2.125 4.348 15.000
python filter00:00:00.006 0.0%2580 0.000 0.002 0.071
site send00:00:00.900 0.0%4687 0.000 0.192 0.538
TOTAL: 24:01:02.39224:00:54.442100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.014 0.0%2426 0.000 0.006 0.118
article prepare00:00:00.005 0.0%604 0.000 0.008 0.250
article read00:00:00.025 0.0%149 0.000 0.168 3.000
backlog stats00:00:00.183 0.0%34099 0.000 0.005 0.035
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.391 0.0%18447 0.000 0.021 0.068
data write00:00:00.903 0.0%16425 0.000 0.055 0.117
idle23:59:46.983100.0%34099 1034.588 2533.417 7080.424
status file00:00:00.528 0.0%201 1.000 2.627 13.000
TOTAL: 23:59:54.34423:59:49.032100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:49.830 24.5%1468 10.000 33.944 5018.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.205 0.1%3439 0.000 0.060 0.581
readart00:00:00.603 0.3%10987 0.055 0.055 0.055
TOTAL: 00:03:23.42800:00:50.638 24.9%----

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
1feed-out.gegeweb.org 7 2233 757 1476 0 33%27:49:18
2news.samoylyk.net 1 3240 526 2704 10 16%30:29:53
3news.nntp4.net 2 2676 358 2259 59 13%26:37:54
4usenet.pasdenom.info 5 2508 295 2178 35 11%38:42:12
5gegeweb-out.news.tnetconsulting.net 2 1882 265 1552 65 14%23:18:18
6i2pn.org 1 2047 192 1854 1 9%21:40:55
7news.glou.org 3 2044 100 1833 111 4%24:48:08
8nntp.terraraq.uk 2 1027 31 996 0 3%15:40:00
9localhost 25 0 0 0 0 0%00:00:00
TOTAL: 9 48 17657 2524 14852 281 14%209:06:38
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news.samoylyk.net3.6 MB0.0 KB31.9 KB3.6 MB 99%6.9 KB
2feed-out.gegeweb.org2.8 MB0.0 KB0.0 KB2.8 MB100%3.8 KB
3news.nntp4.net1.9 MB0.0 KB217.5 KB2.1 MB 89%5.2 KB
4gegeweb-out.news.tnetconsulting.net1.7 MB0.0 KB337.3 KB2.0 MB 83%6.2 KB
5usenet.pasdenom.info711.3 KB0.0 KB62.2 KB773.5 KB 91%2.3 KB
6i2pn.org608.4 KB0.0 KB3.9 KB612.3 KB 99%3.2 KB
7news.glou.org359.9 KB0.0 KB266.8 KB626.8 KB 57%3.0 KB
8nntp.terraraq.uk79.4 KB0.0 KB0.0 KB79.4 KB100%2.6 KB
9localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 911.7 MB0.0 KB919.6 KB12.6 MB 92%4.6 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jul 1 04:15:01 - 04:59:59 47 2.0% 0.02276.7 KB 2.5% 0.10
Jul 1 05:00:00 - 05:59:59 60 2.6% 0.02196.5 KB 1.8% 0.05
Jul 1 06:00:00 - 06:59:59 31 1.3% 0.01110.6 KB 1.0% 0.03
Jul 1 07:00:00 - 07:59:59 49 2.1% 0.01135.9 KB 1.2% 0.04
Jul 1 08:00:00 - 08:59:59 71 3.1% 0.02456.1 KB 4.1% 0.13
Jul 1 09:00:00 - 09:59:59 107 4.6% 0.031.3 MB 11.9% 0.37
Jul 1 10:00:00 - 10:59:59 62 2.7% 0.02261.7 KB 2.4% 0.07
Jul 1 11:00:00 - 11:59:59 65 2.8% 0.02414.7 KB 3.8% 0.12
Jul 1 12:00:00 - 12:59:59 117 5.1% 0.03739.8 KB 6.7% 0.21
Jul 1 13:00:00 - 13:59:59 96 4.2% 0.03286.3 KB 2.6% 0.08
Jul 1 14:00:00 - 14:59:59 99 4.3% 0.03341.3 KB 3.1% 0.09
Jul 1 15:00:00 - 15:59:59 114 4.9% 0.03385.3 KB 3.5% 0.11
Jul 1 16:00:00 - 16:59:59 113 4.9% 0.03375.6 KB 3.4% 0.10
Jul 1 17:00:00 - 17:59:59 119 5.1% 0.03818.9 KB 7.4% 0.23
Jul 1 18:00:00 - 18:59:59 146 6.3% 0.04751.4 KB 6.8% 0.21
Jul 1 19:00:00 - 19:59:59 138 6.0% 0.04757.5 KB 6.9% 0.21
Jul 1 20:00:00 - 20:59:59 189 8.2% 0.05707.4 KB 6.4% 0.20
Jul 1 21:00:00 - 21:59:59 127 5.5% 0.04505.4 KB 4.6% 0.14
Jul 1 22:00:00 - 22:59:59 120 5.2% 0.03573.9 KB 5.2% 0.16
Jul 1 23:00:00 - 23:59:59 88 3.8% 0.02269.5 KB 2.4% 0.07
Jul 2 00:00:00 - 00:59:59 96 4.2% 0.03296.4 KB 2.7% 0.08
Jul 2 01:00:00 - 01:59:59 76 3.3% 0.02323.7 KB 2.9% 0.09
Jul 2 02:00:00 - 02:59:59 94 4.1% 0.03340.2 KB 3.1% 0.09
Jul 2 03:00:00 - 03:59:59 67 2.9% 0.02208.5 KB 1.9% 0.06
Jul 2 04:00:00 - 04:15:01 20 0.9% 0.02161.2 KB 1.5% 0.18
TOTAL: 24:00:00 2311 100.0% 0.0310.8 MB 100.0% 0.13
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.glou.org 111 0 0 0 0 0 0 0 111
2gegeweb-out.news.tnetconsulting.net 74 0 0 0 0 0 0 0 74
3news.nntp4.net 58 0 0 0 0 0 0 0 58
4usenet.pasdenom.info 24 0 0 0 0 0 0 0 24
5news.samoylyk.net 8 0 0 0 0 0 0 0 8
6i2pn.org 1 0 0 0 0 0 0 0 1
TOTAL: 6 276 0 0 0 0 0 0 0 276

INND Perl filter [Top 20]:

ReasonCount
EMP (phn path) 149
Bad path (news.giganews.com.POSTED) 85
Too many newsgroups 28
Cancel of <v5u631$1mp2j$2@i2pn2.org> without Cancel-Key 1
Cancel of <v5vhev$1ohju$1@i2pn2.org> without Cancel-Key 1
Cancel of <v5uk67$1ncd3$1@i2pn2.org> without Cancel-Key 1
Cancel of <v5vg8d$1og82$1@i2pn2.org> without Cancel-Key 1
Cancel of <v5u9ak$1mt7f$1@i2pn2.org> without Cancel-Key 1
Cancel of <v5u8hp$1ms7b$1@i2pn2.org> without Cancel-Key 1
Cancel of <v5uhqv$1n960$1@i2pn2.org> without Cancel-Key 1
HTML Multipart 1
Cancel of <v5u630$1mp2j$1@i2pn2.org> without Cancel-Key 1
Too many newsgroups (low_xpost) 1
Cancel of <v5u67m$1mp66$1@i2pn2.org> without Cancel-Key 1
Cancel of <v5ua0s$1mtuc$1@i2pn2.org> without Cancel-Key 1
TOTAL: 15 274

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1i2pn.org 3382 68 2004 18 0 23 2%23:59:48
2news.gegeweb.org 1661 52 1493 0 0 12 3%23:59:57
3news.tnetconsulting.net 3762 7 2299 0 0 37 0%23:59:57
4news.pasdenom.info 2073 3 1855 0 0 19 0%23:59:57
5news.nntp4.net 2112 1 1988 2 0 9 0%23:58:59
6news.samoylyk.net 2236 0 1874 0 0 8 0%23:59:57
7nntp.terraraq.uk 1496 0 1408 0 0 8 0%23:59:57
8news.aioe.org 0 0 0 0 0 1903 0%24:00:00
TOTAL: 8 16722 131 12921 20 0 2019 0%191:58:32
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1i2pn.org192.5 KB77.6 KB270.1 KB0.0 KB/s3.1 KB23:59:48
2news.gegeweb.org148.9 KB0.0 KB148.9 KB0.0 KB/s2.9 KB23:59:57
3news.tnetconsulting.net26.3 KB0.0 KB26.3 KB0.0 KB/s3.8 KB23:59:57
4news.pasdenom.info18.7 KB0.0 KB18.7 KB0.0 KB/s6.2 KB23:59:57
5news.nntp4.net3.1 KB6.0 KB9.1 KB0.0 KB/s3.0 KB23:58:59
6news.samoylyk.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:57
7nntp.terraraq.uk0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:57
8news.aioe.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:00
TOTAL: 8389.5 KB83.6 KB473.1 KB0.0 KB/s3.1 KB191:58:32
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
187.251.64.129 1 00.0 KB 0 0 000:00:00
2distinguished.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
3sys.gegeweb.eu 2 00.0 KB 0 0 000:00:07
4206.168.34.50 1 00.0 KB 0 0 000:00:05
5classical.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
6reverent.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:06
7azpdcs31.stretchoid.com 1 00.0 KB 0 0 000:00:09
8mail.gegeweb.eu 285 00.0 KB 0 0 000:02:16
962.211.203.35.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:00
10206.168.34.117 3 00.0 KB 0 0 000:00:18
11118.210.203.35.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:00
12pool-100-38-239-226.nycmny.fios.verizon.net 1 00.0 KB 0 0 000:00:14
13adorable.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
14grand.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
1588.214.25.62 1 00.0 KB 0 0 000:00:00
1671.6.134.230 1 00.0 KB 0 0 000:00:01
TOTAL: 16 303 00.0 KB 0 0 000:03:22

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.stretchoid.com 1 00.0 KB 0 0 000:00:09
2*.210.203.35.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:00
3*.monitoring.internet-measurement.com 5 00.0 KB 0 0 000:00:06
4*.211.203.35.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:00
5unresolved 7 00.0 KB 0 0 000:00:26
6*.gegeweb.eu 287 00.0 KB 0 0 000:02:23
7*.nycmny.fios.verizon.net 1 00.0 KB 0 0 000:00:14
TOTAL: 7 303 00.0 KB 0 0 000:03:22

NNRP auth users [Top 20]:

UserConn
mailman 287
TOTAL: 1 287

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
mail.gegeweb.eu 4.363 4.109 1.49600:02:16
206.168.34.117 0.088 0.054 0.00000:00:18
pool-100-38-239-226.nycmny.fios.verizon.net 0.282 0.684 0.00000:00:14
azpdcs31.stretchoid.com 0.032 0.020 0.00000:00:09
sys.gegeweb.eu 0.070 0.040 0.00900:00:07
reverent.monitoring.internet-measurement.com 0.037 0.021 0.00000:00:06
206.168.34.50 0.032 0.024 0.00000:00:05
71.6.134.230 0.017 0.007 0.00000:00:01
62.211.203.35.bc.googleusercontent.com 0.049 0.033 0.00000:00:00
118.210.203.35.bc.googleusercontent.com 0.071 0.015 0.00000:00:00
88.214.25.62 0.026 0.029 0.00000:00:00
87.251.64.129 0.018 0.009 0.00000:00:00
adorable.monitoring.internet-measurement.com 0.046 0.018 0.00000:00:00
grand.monitoring.internet-measurement.com 0.014 0.014 0.00000:00:00
distinguished.monitoring.internet-measurement.com 0.020 0.008 0.00000:00:00
TOTAL: 15 5.165 5.085 1.50500:03:22

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

SystemConn
118.210.203.35.bc.googleusercontent.com 4
62.211.203.35.bc.googleusercontent.com 4
206.168.34.117 1
88.214.25.62 1
206.168.34.50 1
TOTAL: 5 11

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

SystemConnPeer
206.168.34.117 1 2
88.214.25.62 1 1
TOTAL: 2 2 3