Daily Usenet report for news.niel.me

Sep 17 04:15:01 -- Sep 18 04:15:01

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

Sep 17 04:17:44 rpi-studio innfeed[8483]: news.pasdenom.info:0 connect: Network is unreachable
Sep 17 04:22:54 rpi-studio innfeed[8483]: news.pasdenom.info:0 connect: Network is unreachable
Sep 17 04:37:06 rpi-studio innfeed[8483]: news.pasdenom.info:0 connect: Network is unreachable
Sep 17 05:27:18 rpi-studio innfeed[8483]: news.pasdenom.info:0 connect: Network is unreachable
Sep 17 07:29:30 rpi-studio innfeed[8483]: news.pasdenom.info:0 connect: Network is unreachable
Sep 17 09:31:42 rpi-studio innfeed[8483]: news.pasdenom.info:0 connect: Network is unreachable
Sep 17 18:12:33 rpi-studio innfeed[8483]: news.pasdenom.info:0 connect: Network is unreachable
Sep 17 18:18:34 rpi-studio innfeed[8483]: news.pasdenom.info:0 connect: Network is unreachable
Sep 17 18:42:36 rpi-studio innfeed[8483]: news.pasdenom.info:0 connect: Network is unreachable

Log entries by program:

Program nameLines%LinesSize%Size
inn 4986 41.6%866.5 KB 46.4%
innd 3433 28.6%507.5 KB 27.2%
nnrpd 1907 15.9%210.0 KB 11.2%
innfeed 1665 13.9%283.7 KB 15.2%
controlchan 1 0.0%0.0 KB 0.0%
nocem 1 0.0%0.2 KB 0.0%
TOTAL: 6 11993 100.0%1.8 MB100.0%

History cache:

ReasonCount%Count
Positive hits 23247 64.5%
Negative hits 7267 20.2%
Do not exist 4866 13.5%
Cache misses 675 1.9%
TOTAL: 4 36055 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.051 0.0%47 0.000 1.085 3.000
article cancel00:00:00.007 0.0%53 0.000 0.132 1.000
article cleanup00:00:00.122 0.0%4865 0.000 0.025 0.154
article logging00:00:00.399 0.0%4886 0.000 0.082 0.263
article parse00:00:00.804 0.0%7776 0.000 0.103 0.267
article write00:00:02.918 0.0%4351 0.222 0.671 9.806
artlog/artcncl00:00:00.001 0.0%53 0.000 0.019 0.100
artlog/artcncl/perl00:00:00.003 0.0%47 0.000 0.064 1.000
data move00:00:00.108 0.0%38919 0.000 0.003 0.013
hisgrep/artcncl00:00:00.002 0.0%47 0.000 0.043 1.000
hisgrep/artcncl/perl00:00:00.003 0.0%47 0.000 0.064 1.000
hisgrep/perl00:00:00.004 0.0%54 0.000 0.074 1.000
hishave/artcncl00:00:00.000 0.0%53 0.000 0.000 0.000
hishave/artcncl/perl00:00:00.000 0.0%47 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.568 0.0%35988 0.000 0.016 0.039
history sync00:00:00.062 0.0%410 0.000 0.151 4.667
history write00:00:01.075 0.0%4865 0.000 0.221 0.513
hiswrite/artcncl00:00:00.000 0.0%6 0.000 0.000 0.000
idle24:00:17.476 99.9%39646 868.772 2179.727 8227.795
nntp read00:00:01.334 0.0%38974 0.013 0.034 0.072
overview write00:00:02.876 0.0%4351 0.100 0.661 3.833
perl filter00:00:22.847 0.0%4865 2.925 4.696 8.480
python filter00:00:00.024 0.0%8870 0.000 0.003 0.030
site send00:00:01.326 0.0%8706 0.000 0.152 0.516
TOTAL: 24:01:06.39024:00:52.010100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.052 0.0%6023 0.000 0.009 0.118
article prepare00:00:00.000 0.0%920 0.000 0.000 0.000
article read00:00:00.048 0.0%216 0.000 0.222 8.000
backlog stats00:00:00.137 0.0%64883 0.000 0.002 0.012
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.902 0.0%36879 0.005 0.024 0.051
data write00:00:02.425 0.0%32454 0.030 0.075 0.131
idle23:59:45.391100.0%64883 480.771 1331.403 3553.746
status file00:00:00.434 0.0%182 1.000 2.385 7.000
TOTAL: 23:59:52.60423:59:49.389100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:43.348 27.8%1195 19.250 36.274 1752.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.107 0.1%2811 0.000 0.038 1.000
readart00:00:00.014 0.0%12 1.167 1.167 1.167
TOTAL: 00:02:35.98600:00:43.469 27.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
1news-out.aioe.org 1 4349 2214 2031 104 50%23:06:27
2i2pn.org 1 4652 1228 3364 60 26%24:13:40
3feed-out.gegeweb.org 291 3010 373 2637 0 12%23:56:17
4news.nntp4.net 3 4650 211 4352 87 4%23:49:28
5nntp.terraraq.uk 1 3293 203 2841 249 6%22:03:36
6usenet.pasdenom.info 6 3339 51 3288 0 1%14:23:32
7gegeweb-out.news.tnetconsulting.net 1 4223 9 4207 7 0%23:57:16
8localhost 25 0 0 0 0 0%00:00:00
TOTAL: 8 329 27516 4289 22720 507 15%155:30:16
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news-out.aioe.org6.9 MB0.0 KB409.8 KB7.3 MB 94%3.2 KB
2i2pn.org5.1 MB0.0 KB213.6 KB5.3 MB 96%4.2 KB
3nntp.terraraq.uk2.3 MB0.0 KB1.4 MB3.7 MB 63%8.4 KB
4news.nntp4.net1011.9 KB0.0 KB328.0 KB1.3 MB 75%4.5 KB
5feed-out.gegeweb.org919.8 KB0.0 KB0.0 KB919.8 KB100%2.5 KB
6gegeweb-out.news.tnetconsulting.net194.8 KB0.0 KB28.8 KB223.6 KB 87%14.0 KB
7usenet.pasdenom.info104.8 KB0.0 KB0.0 KB104.8 KB100%2.1 KB
8localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 816.5 MB0.0 KB2.3 MB18.8 MB 87%4.0 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 17 04:15:01 - 04:59:59 83 1.9% 0.03300.0 KB 1.7% 0.11
Sep 17 05:00:00 - 05:59:59 105 2.4% 0.03325.5 KB 1.9% 0.09
Sep 17 06:00:00 - 06:59:59 132 3.0% 0.04473.1 KB 2.7% 0.13
Sep 17 07:00:00 - 07:59:59 121 2.8% 0.03512.5 KB 3.0% 0.14
Sep 17 08:00:00 - 08:59:59 132 3.0% 0.04915.5 KB 5.3% 0.25
Sep 17 09:00:00 - 09:59:59 138 3.2% 0.04432.3 KB 2.5% 0.12
Sep 17 10:00:00 - 10:59:59 166 3.8% 0.05453.0 KB 2.6% 0.13
Sep 17 11:00:00 - 11:59:59 194 4.5% 0.05811.5 KB 4.7% 0.23
Sep 17 12:00:00 - 12:59:59 151 3.5% 0.04408.4 KB 2.4% 0.11
Sep 17 13:00:00 - 13:59:59 169 3.9% 0.05651.5 KB 3.8% 0.18
Sep 17 14:00:00 - 14:59:59 172 4.0% 0.05533.7 KB 3.1% 0.15
Sep 17 15:00:00 - 15:59:59 197 4.5% 0.051.1 MB 6.4% 0.30
Sep 17 16:00:00 - 16:59:59 181 4.2% 0.05487.9 KB 2.8% 0.14
Sep 17 17:00:00 - 17:59:59 210 4.8% 0.06791.3 KB 4.6% 0.22
Sep 17 18:00:00 - 18:59:59 205 4.7% 0.061.1 MB 6.8% 0.33
Sep 17 19:00:00 - 19:59:59 238 5.5% 0.07884.8 KB 5.1% 0.25
Sep 17 20:00:00 - 20:59:59 274 6.3% 0.081.5 MB 8.6% 0.41
Sep 17 21:00:00 - 21:59:59 200 4.6% 0.06713.9 KB 4.1% 0.20
Sep 17 22:00:00 - 22:59:59 281 6.5% 0.08970.0 KB 5.6% 0.27
Sep 17 23:00:00 - 23:59:59 249 5.7% 0.07940.9 KB 5.5% 0.26
Sep 18 00:00:00 - 00:59:59 183 4.2% 0.05581.3 KB 3.4% 0.16
Sep 18 01:00:00 - 01:59:59 208 4.8% 0.06706.2 KB 4.1% 0.20
Sep 18 02:00:00 - 02:59:59 188 4.3% 0.05653.1 KB 3.8% 0.18
Sep 18 03:00:00 - 03:59:59 130 3.0% 0.04757.7 KB 4.4% 0.21
Sep 18 04:00:00 - 04:15:01 43 1.0% 0.05144.0 KB 0.8% 0.16
TOTAL: 24:00:00 4350 100.0% 0.0516.8 MB 100.0% 0.20
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1nntp.terraraq.uk 273 0 0 0 0 0 0 0 273
2news-out.aioe.org 114 1 0 0 0 0 0 0 113
3news.nntp4.net 87 0 0 0 0 0 0 0 87
4i2pn.org 60 0 0 0 0 0 0 0 60
5gegeweb-out.news.tnetconsulting.net 2 0 0 0 0 0 0 0 2
TOTAL: 5 536 1 0 0 0 0 0 0 535

Unwanted newsgroups [Top 20]:

NewsgroupCount
news.test 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
Too many newsgroups 238
EMP (phn nph) 129
Bad path (news.giganews.com.POSTED) 97
EMP (phl) 29
EMP (md5) 12
User-issued spam cancel 8
Cancel of non-existing ID <2511505c-5c05-49ce-9092-05e0bca218cfn@googlegroups.com> 1
TOTAL: 7 514

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
news-out.aioe.org 19
nntp.terraraq.uk 2
TOTAL: 2 21
TOTAL: 1 21

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1i2pn.org 5883 56 3045 7 0 0 0%23:59:56
2news.pasdenom.info 5989 26 4586 13 0 952 0%23:57:47
3news.tnetconsulting.net 7212 23 4326 1 0 0 0%23:59:56
4news.gegeweb.org 4117 17 3878 72 0 0 0%23:59:41
5nntp.terraraq.uk 3573 11 2990 0 0 0 0%23:59:44
6news.aioe.org 1706 1 1573 3 0 0 0%23:53:52
7news.nntp4.net 5051 0 4168 0 0 0 0%23:59:50
TOTAL: 7 33531 134 24566 96 0 952 0%167:50:46
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1i2pn.org167.4 KB15.3 KB182.6 KB0.0 KB/s2.9 KB23:59:56
2news.gegeweb.org115.0 KB272.9 KB387.9 KB0.0 KB/s4.4 KB23:59:41
3news.tnetconsulting.net93.1 KB2.2 KB95.3 KB0.0 KB/s4.0 KB23:59:56
4news.pasdenom.info91.4 KB65.9 KB157.3 KB0.0 KB/s4.0 KB23:57:47
5nntp.terraraq.uk45.0 KB0.0 KB45.0 KB0.0 KB/s4.1 KB23:59:44
6news.aioe.org4.1 KB11.4 KB15.5 KB0.0 KB/s3.9 KB23:53:52
7news.nntp4.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:50
TOTAL: 7516.0 KB367.7 KB883.6 KB0.0 KB/s3.8 KB167:50:46
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1108b-gniel 1 2161.6 KB 2 0 000:00:17
TOTAL: 1 1 2161.6 KB 2 0 000:02:35

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1? 1 2161.6 KB 2 0 000:00:17
2unresolved 6 00.0 KB 0 0 000:00:03
3*.50-31-21.static.steadfastdns.net 3 00.0 KB 0 0 000:00:13
4*.208-100-26.static.steadfastdns.net 10 00.0 KB 0 0 000:00:02
5*.gegeweb.eu 288 00.0 KB 0 0 000:01:56
6*.zbau.f3netze.de 1 00.0 KB 0 0 000:00:02
TOTAL: 6 309 2161.6 KB 2 0 000:02: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
sys.gegeweb.eu 7.986 8.974 2.96800:01:56
108b-gniel 0.023 0.055 0.00000:00:17
ip8.50-31-21.static.steadfastdns.net 0.112 0.102 0.00000:00:13
88.165.5.23 0.157 0.209 0.00000:00:03
ip237.208-100-26.static.steadfastdns.net 0.254 0.176 0.00000:00:02
tor-exit-5.zbau.f3netze.de 0.019 0.039 0.00000:00:02
TOTAL: 6 8.551 9.555 2.96800:02:35

Curious NNRP server explorers [Top 20]:

SystemConn
sys.gegeweb.eu 288
ip237.208-100-26.static.steadfastdns.net 10
88.165.5.23 6
ip8.50-31-21.static.steadfastdns.net 3
tor-exit-5.zbau.f3netze.de 1
TOTAL: 5 308

NNRP gethostbyaddr failures [Top 20]:

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

Newsgroup request counts (by category):

CategoryCountPct
1fr 1 50.0%
2news 1 50.0%
TOTAL: 2 2100.0%

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

NewsgroupCount
1fr.usenet.documents 1
2news.announce.newgroups 1
TOTAL: 2 2