Daily Usenet report

May 25 04:15:01 -- May 26 04:15:02

Unknown entries from news log file:

First 13 / 13 lines (100.0%)

May 25 06:47:21 newsfeed overchan[1516407]: input line too long, skipping
May 25 10:23:17 newsfeed overchan[1516407]: input line too long, skipping
May 25 13:29:09 newsfeed nnrpd[1542207]: SERVER perl filter not defined
May 25 13:29:21 newsfeed nnrpd[1542210]: SERVER perl filter not defined
May 25 13:29:34 newsfeed nnrpd[1542356]: SERVER perl filter not defined
May 25 16:09:22 newsfeed overchan[1516407]: input line too long, skipping
May 25 16:17:36 newsfeed overchan[1516407]: input line too long, skipping
May 25 16:38:49 newsfeed overchan[1516407]: input line too long, skipping
May 25 19:28:15 newsfeed pgpverify[1558613]: pgpverify: /usr/bin/gpg1: no such file
May 25 19:28:15 newsfeed controlchan[1516404]: skipping newgroup y control@usenet-fr.news.eu.org (pgpverify failed) in <newgroup-fr.rec.sport.jeux-olympiques-1685035323@news.trigofacile.com>
May 25 19:43:28 newsfeed overchan[1516407]: input line too long, skipping
May 25 20:13:07 newsfeed overchan[1516407]: input line too long, skipping
May 25 21:38:13 newsfeed overchan[1516407]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn 124924 79.1%16.2 MB 80.6%
innfeed 23545 14.9%2.8 MB 13.8%
nnrpd 7677 4.9%868.5 KB 4.2%
innd 1841 1.2%281.0 KB 1.4%
overchan 10 0.0%1.6 KB 0.0%
controlchan 2 0.0%0.2 KB 0.0%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 7 158000 100.0%20.0 MB100.0%

History cache:

ReasonCount%Count
Do not exist 124329 98.8%
Negative hits 1156 0.9%
Positive hits 270 0.2%
Cache misses 32 0.0%
TOTAL: 4 125787 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.629 0.0%56 0.000 11.232 30.500
article cleanup00:00:00.490 0.0%124552 0.000 0.004 0.016
article logging00:00:02.349 0.0%124907 0.004 0.019 0.179
article parse00:00:06.129 0.0%1679398 0.001 0.004 0.009
article write00:00:16.904 0.0%124414 0.042 0.136 5.948
artlog/artcncl00:00:00.001 0.0%56 0.000 0.018 0.083
data move00:00:01.059 0.0%2088637 0.000 0.001 0.003
hisgrep/artcncl00:00:00.205 0.0%55 0.000 3.727 10.250
hishave/artcncl00:00:00.143 0.0%56 0.000 2.554 13.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:07:07.231 0.5%125950 1.856 3.392 47.144
history sync00:00:00.012 0.0%295 0.000 0.041 0.500
history write00:08:36.227 0.6%124547 2.614 4.145 35.636
hiswrite/artcncl00:00:00.007 0.0%1 7.000 7.000 7.000
idle23:41:08.130 98.7%1785855 6.397 47.746 497.148
nntp read00:00:14.615 0.0%1673788 0.005 0.009 0.019
overview write00:00:00.283 0.0%124414 0.000 0.002 0.011
perl filter00:02:08.067 0.1%124547 0.593 1.028 1.620
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:02.762 0.0%257909 0.005 0.011 0.025
TOTAL: 24:00:14.69923:59:45.243100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.028 0.0%29048 0.000 0.001 0.011
article prepare00:00:00.000 0.0%3090 0.000 0.000 0.000
article read00:00:13.511 0.0%780 4.077 17.322 85.000
backlog stats00:00:00.029 0.0%70779 0.000 0.000 0.008
callbacks00:00:00.000 0.0%9 0.000 0.000 0.000
data read00:00:00.260 0.0%34365 0.000 0.008 0.040
data write00:00:00.596 0.0%25429 0.000 0.023 0.067
idle23:56:59.287100.0%70779 808.248 1218.148 2611.096
status file00:00:04.047 0.0%7740 0.217 0.523 6.744
TOTAL: 23:57:19.37123:57:17.758100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:23.186 49.3%4225 0.000 5.488 9737.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.131 0.3%10254 0.000 0.013 0.579
readart00:00:00.055 0.1%26 0.000 2.115 5.400
TOTAL: 00:00:47.03100:00:23.372 49.7%----

Control commands to INND:

CommandNumber
flush 27
flushlogs 2
go 2
logmode 2
lowmark 1
mode 149
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 193

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 96 124513 124375 2 136 99%47:57:36
2news.icm.edu.pl 48 247 5 240 2 2%15:32:40
3localhost 4 3 3 0 0 100%00:00:00
TOTAL: 3 148 124763 124383 242 138 99%63:30:16
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl4.1 GB176.7 KB486.9 KB4.1 GB 99%34.7 KB
2news.icm.edu.pl239.1 KB0.0 KB45.6 KB284.7 KB 83%40.7 KB
3localhost4.6 KB0.0 KB0.0 KB4.6 KB100%1.5 KB
TOTAL: 34.1 GB176.7 KB532.5 KB4.1 GB 99%34.7 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 25 04:15:01 - 04:59:59 1034 0.8% 0.3816.9 MB 0.4% 6.43
May 25 05:00:00 - 05:59:59 1625 1.3% 0.4523.7 MB 0.6% 6.73
May 25 06:00:00 - 06:59:59 1578 1.3% 0.4422.5 MB 0.5% 6.41
May 25 07:00:00 - 07:59:59 2769 2.2% 0.7742.2 MB 1.0% 12.00
May 25 08:00:00 - 08:59:59 2269 1.8% 0.6331.3 MB 0.7% 8.92
May 25 09:00:00 - 09:59:59 5862 4.7% 1.63249.0 MB 5.9% 70.83
May 25 10:00:00 - 10:59:59 19946 16.0% 5.541.1 GB 25.6% 307.01
May 25 11:00:00 - 11:59:59 10237 8.2% 2.84472.6 MB 11.2% 134.44
May 25 12:00:00 - 12:59:59 7450 6.0% 2.07347.5 MB 8.2% 98.85
May 25 13:00:00 - 13:59:59 11285 9.1% 3.13615.1 MB 14.6% 174.95
May 25 14:00:00 - 14:59:59 6252 5.0% 1.74160.3 MB 3.8% 45.60
May 25 15:00:00 - 15:59:59 6618 5.3% 1.84164.5 MB 3.9% 46.78
May 25 16:00:00 - 16:59:59 4404 3.5% 1.2276.2 MB 1.8% 21.68
May 25 17:00:00 - 17:59:59 5583 4.5% 1.55176.0 MB 4.2% 50.05
May 25 18:00:00 - 18:59:59 6681 5.4% 1.86269.9 MB 6.4% 76.77
May 25 19:00:00 - 19:59:59 3473 2.8% 0.9663.2 MB 1.5% 17.98
May 25 20:00:00 - 20:59:59 5135 4.1% 1.43126.6 MB 3.0% 36.01
May 25 21:00:00 - 21:59:59 3914 3.1% 1.0957.2 MB 1.4% 16.28
May 25 22:00:00 - 22:59:59 3059 2.5% 0.8545.9 MB 1.1% 13.07
May 25 23:00:00 - 23:59:59 2087 1.7% 0.5827.8 MB 0.7% 7.91
May 26 00:00:00 - 00:59:59 4823 3.9% 1.3453.3 MB 1.3% 15.17
May 26 01:00:00 - 01:59:59 3218 2.6% 0.8930.1 MB 0.7% 8.55
May 26 02:00:00 - 02:59:59 2596 2.1% 0.7231.6 MB 0.8% 8.99
May 26 03:00:00 - 03:59:59 1991 1.6% 0.5526.3 MB 0.6% 7.47
May 26 04:00:00 - 04:15:02 485 0.4% 0.544.7 MB 0.1% 5.28
TOTAL: 24:00:01 124374 100.0% 1.444.1 GB 100.0% 49.94
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 492 131 0 5 0 0 0 0 356
2news.icm.edu.pl 2 0 0 0 0 0 0 0 2
TOTAL: 2 494 131 0 5 0 0 0 0 358

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 62
comp.mobile.android 22
it.comp.os.win.windows10 18
it.tlc.cellulari.android 18
alt.comp.software.seamonkey 6
alt.comp.software.firefox 4
nl.actueel 1
TOTAL: 7 131

INND Perl filter [Top 20]:

ReasonCount
Binary in non-binary group 2
TOTAL: 1 2

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 356
TOTAL: 1 356
TOTAL: 1 356

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1panorama 20488 167 7426 202 15 21307 0%24:00:16
2icm 13347 24 9022 3 0 0 0%24:09:56
3poznan 6 0 5 0 0 0 0%01:00:05
4nask 0 0 0 0 0 6382 0%24:05:33
5okapi 0 0 0 0 0 465 0%24:05:33
TOTAL: 5 33841 191 16453 205 15 28154 0%97:21:23
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1panorama506.5 KB5.3 MB5.8 MB0.1 KB/s16.0 KB24:00:16
2icm87.2 KB29.7 KB116.9 KB0.0 KB/s4.3 KB24:09:56
3poznan0.0 KB0.0 KB0.0 KB0.0 KB/s001:00:05
4nask0.0 KB0.0 KB0.0 KB0.0 KB/s024:05:33
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s024:05:33
TOTAL: 5593.7 KB5.3 MB5.9 MB0.0 KB/s15.2 KB97:21:23
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
okapi488.3 KB
TOTAL: 1488.3 KB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 289 1623.5 KB 7 0 000:00:08
246.219.209.156 1 812.2 KB 1 0 000:00:01
3isvcs01.man.lodz.pl 3 00.0 KB 0 3 000:00:00
TOTAL: 3 293 2435.7 KB 8 3 000:00:47

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl1732 1623.5 KB 7 3 000:00:28
2unresolved 104 812.2 KB 1 0 000:00:15
3*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:03
4*.us-west-1.compute.amazonaws.com 2 00.0 KB 0 0 000:00:00
TOTAL: 41839 2435.7 KB 8 3 000:00:47

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
nms1.man.lodz.pl 2.461 9.637 0.00000:00:19
198.199.115.27 0.000 0.008 0.00000:00:09
web.man.lodz.pl 0.549 2.015 0.00000:00:08
incredible.monitoring.internet-measurement.com 0.004 0.004 0.00000:00:03
80.111.142.213 0.012 0.029 0.00000:00:02
46.219.209.156 0.014 0.007 0.00000:00:01
212.51.192.185 0.177 0.601 0.00000:00:01
ec2-3-101-216-106.us-west-1.compute.amazonaws.com 0.000 0.016 0.00000:00:00
194.165.16.37 0.005 0.005 0.00000:00:00
107.170.252.16 0.004 0.004 0.00000:00:00
isvcs01.man.lodz.pl 0.007 0.033 0.00000:00:00
TOTAL: 11 3.233 12.359 0.00000:00:47

Curious NNRP server explorers [Top 20]:

SystemConn
nms1.man.lodz.pl 1440
212.51.192.185 96
80.111.142.213 4
ec2-3-101-216-106.us-west-1.compute.amazonaws.com 2
194.165.16.37 1
107.170.252.16 1
198.199.115.27 1
incredible.monitoring.internet-measurement.com 1
TOTAL: 8 1546

NNRP unrecognized commands (by host):

SystemConn
194.165.16.37 1
107.170.252.16 1
TOTAL: 2 2

NNRP unrecognized commands (by command):

CommandCount
MGLNDD_212.191.2.21_119 1
#003 1
TOTAL: 2 2

NNRP client timeouts [Top 20]:

SystemConnPeer
194.165.16.37 1 1
TOTAL: 1 1 1

Newsgroup request counts (by category):

CategoryCountPct
1pl 16 66.7%
2lodman 8 33.3%
TOTAL: 2 24100.0%

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

NewsgroupCount
1pl.comp.pecet 16
2lodman.info 8
TOTAL: 2 24