Daily Usenet report

Oct 18 04:15:01 -- Oct 19 04:15:02

Unknown entries from news log file:

First 20 / 20 lines (100.0%)

Oct 18 07:28:08 newsfeed overchan[757105]: input line too long, skipping
Oct 18 07:33:59 newsfeed overchan[757105]: input line too long, skipping
Oct 18 08:55:11 newsfeed overchan[757105]: input line too long, skipping
Oct 18 09:03:47 newsfeed overchan[757105]: input line too long, skipping
Oct 18 11:48:47 newsfeed overchan[757105]: input line too long, skipping
Oct 18 14:09:23 newsfeed nnrpd[784377]: SERVER perl filter not defined
Oct 18 14:16:10 newsfeed nnrpd[784644]: SERVER perl filter not defined
Oct 18 14:48:17 newsfeed overchan[757105]: input line too long, skipping
Oct 18 14:49:11 newsfeed overchan[757105]: input line too long, skipping
Oct 18 15:38:41 newsfeed nnrpd[788818]: SERVER perl filter not defined
Oct 18 15:49:36 newsfeed nnrpd[789263]: SERVER perl filter not defined
Oct 18 17:44:18 newsfeed overchan[757105]: input line too long, skipping
Oct 18 17:48:13 newsfeed overchan[757105]: input line too long, skipping
Oct 18 17:50:07 newsfeed overchan[757105]: input line too long, skipping
Oct 18 18:06:01 newsfeed overchan[757105]: input line too long, skipping
Oct 18 18:14:33 newsfeed overchan[757105]: input line too long, skipping
Oct 18 18:47:24 newsfeed overchan[757105]: input line too long, skipping
Oct 18 18:47:50 newsfeed overchan[757105]: input line too long, skipping
Oct 18 22:13:38 newsfeed overchan[757105]: input line too long, skipping
Oct 19 01:24:28 newsfeed overchan[757105]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn 186231 87.8%21.0 MB 87.4%
innd 16355 7.7%2.1 MB 8.7%
nnrpd 7654 3.6%775.0 KB 3.1%
innfeed 1751 0.8%197.8 KB 0.8%
overchan 17 0.0%2.2 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 6 212009 100.0%24.0 MB100.0%

History cache:

ReasonCount%Count
Do not exist 185270 92.0%
Negative hits 15172 7.5%
Positive hits 962 0.5%
Cache misses 48 0.0%
TOTAL: 4 201452 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.330 0.0%82 0.000 16.220 40.000
article cleanup00:00:01.452 0.0%186389 0.003 0.008 0.014
article logging00:00:04.444 0.0%186415 0.016 0.024 0.036
article parse00:00:06.602 0.0%742369 0.003 0.009 0.015
article write00:00:24.382 0.0%171693 0.071 0.142 0.753
artlog/artcncl00:00:00.003 0.0%72 0.000 0.042 1.000
data move00:00:01.160 0.0%973225 0.000 0.001 0.004
hisgrep/artcncl00:00:00.554 0.0%80 0.000 6.925 19.750
hishave/artcncl00:00:00.210 0.0%82 0.000 2.561 13.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:10:33.618 0.7%202455 1.029 3.130 12.439
history sync00:00:00.021 0.0%349 0.000 0.060 1.000
history write00:14:29.226 1.0%186278 2.537 4.666 9.408
hiswrite/artcncl00:00:00.009 0.0%2 4.500 4.500 4.500
idle23:30:42.486 97.9%914913 34.699 92.514 221.848
nntp read00:00:14.434 0.0%753675 0.011 0.019 0.035
overview write00:00:00.593 0.0%171693 0.000 0.003 0.011
perl filter00:03:18.860 0.2%186278 0.790 1.068 1.343
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:06.043 0.0%372081 0.009 0.016 0.023
TOTAL: 24:00:46.21324:00:05.427100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.071 0.0%28612 0.000 0.002 0.018
article prepare00:00:00.000 0.0%752 0.000 0.000 0.000
article read00:00:00.009 0.0%188 0.000 0.048 1.000
backlog stats00:00:00.054 0.0%123258 0.000 0.000 0.004
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.615 0.0%68537 0.000 0.009 0.022
data write00:00:01.743 0.0%39942 0.013 0.044 0.088
idle23:54:54.498100.0%123258 294.806 698.490 1813.226
status file00:00:00.350 0.0%520 0.000 0.673 4.000
TOTAL: 23:54:59.15223:54:57.340100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:21.289 42.7%4199 0.000 5.070 9737.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.188 0.4%10151 0.000 0.019 0.500
readart00:00:00.000 0.0%16 0.000 0.000 0.000
TOTAL: 00:00:49.81600:00:21.477 43.1%----

Control commands to INND:

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 45 171706 171412 3 291 99%37:59:49
2news.icm.edu.pl 2 15380 115 825 14440 0%23:55:12
3localhost 5 4 4 0 0 100%00:00:00
TOTAL: 3 52 187090 171531 828 14731 91%61:55:01
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl1.5 GB3.2 MB3.7 MB1.5 GB 99%9.0 KB
2news.icm.edu.pl3.7 MB317.2 KB167.4 MB171.4 MB 2%12.1 KB
3localhost5.6 KB0.0 KB0.0 KB5.6 KB100%1.4 KB
TOTAL: 31.5 GB3.6 MB171.0 MB1.6 GB 89%9.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 18 04:15:01 - 04:59:59 4405 2.6% 1.6351.5 MB 3.4% 19.52
Oct 18 05:00:00 - 05:59:59 5213 3.0% 1.4547.0 MB 3.1% 13.37
Oct 18 06:00:00 - 06:59:59 5402 3.2% 1.5056.9 MB 3.8% 16.19
Oct 18 07:00:00 - 07:59:59 5577 3.3% 1.5579.9 MB 5.3% 22.74
Oct 18 08:00:00 - 08:59:59 6509 3.8% 1.8185.0 MB 5.7% 24.18
Oct 18 09:00:00 - 09:59:59 5476 3.2% 1.5250.1 MB 3.3% 14.26
Oct 18 10:00:00 - 10:59:59 6559 3.8% 1.8261.1 MB 4.1% 17.38
Oct 18 11:00:00 - 11:59:59 5672 3.3% 1.5850.8 MB 3.4% 14.44
Oct 18 12:00:00 - 12:59:59 6209 3.6% 1.7253.7 MB 3.6% 15.28
Oct 18 13:00:00 - 13:59:59 6297 3.7% 1.7565.1 MB 4.3% 18.51
Oct 18 14:00:00 - 14:59:59 6714 3.9% 1.8664.9 MB 4.3% 18.46
Oct 18 15:00:00 - 15:59:59 6019 3.5% 1.6752.8 MB 3.5% 15.02
Oct 18 16:00:00 - 16:59:59 7908 4.6% 2.2060.9 MB 4.1% 17.31
Oct 18 17:00:00 - 17:59:59 10692 6.2% 2.9798.2 MB 6.6% 27.92
Oct 18 18:00:00 - 18:59:59 7007 4.1% 1.9545.9 MB 3.1% 13.06
Oct 18 19:00:00 - 19:59:59 8323 4.9% 2.3163.8 MB 4.3% 18.16
Oct 18 20:00:00 - 20:59:59 10959 6.4% 3.0491.3 MB 6.1% 25.96
Oct 18 21:00:00 - 21:59:59 10364 6.0% 2.8885.5 MB 5.7% 24.33
Oct 18 22:00:00 - 22:59:59 9822 5.7% 2.7372.5 MB 4.8% 20.63
Oct 18 23:00:00 - 23:59:59 8447 4.9% 2.3566.6 MB 4.4% 18.95
Oct 19 00:00:00 - 00:59:59 6966 4.1% 1.9450.6 MB 3.4% 14.40
Oct 19 01:00:00 - 01:59:59 5982 3.5% 1.6638.5 MB 2.6% 10.94
Oct 19 02:00:00 - 02:59:59 7621 4.4% 2.1261.1 MB 4.1% 17.39
Oct 19 03:00:00 - 03:59:59 5954 3.5% 1.6537.2 MB 2.5% 10.57
Oct 19 04:00:00 - 04:15:02 1372 0.8% 1.526.7 MB 0.5% 7.66
TOTAL: 24:00:01 171469 100.0% 1.981.5 GB 100.0% 17.75
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.icm.edu.pl 14373 0 0 10 0 0 0 014363
2newsfeed.pionier.net.pl 317 187 0 101 0 0 0 0 29
TOTAL: 2 14690 187 0 111 0 0 0 014392

Unwanted newsgroups [Top 20]:

NewsgroupCount
fr.misc.actualite.covid19 46
comp.mobile.android 31
uk.railway 29
eternal-september.support 20
it.tlc.cellulari.android 19
comp.sys.raspberry-pi 17
it.comp.os.win.windows10 9
comp.infosystems.gemini 4
alt.comp.software.seamonkey 3
perl.cpan.testers.discuss 3
eternal-september.test 2
alt.comp.software.firefox 2
grisbi.test 1
aioe.test 1
TOTAL: 14 187

INND Perl filter [Top 20]:

ReasonCount
Binary in non-binary group 14225
Scoring filter (8) 103
EMP (md5) 19
Scoring filter (10) 12
Scoring filter (9) 6
Scoring filter (11) 1
TOTAL: 6 14366

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1icm 39894 175 28129 13 0 2 0%23:59:54
2poznan 140 0 116 0 0 0 0%14:34:42
3nask 0 0 0 0 0 24193 0%23:57:49
4okapi 0 0 0 0 0 738 0%23:57:49
5panorama 0 0 0 0 0 28654 0%23:57:49
TOTAL: 5 40034 175 28245 13 0 53587 0%110:28:03
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm460.3 KB44.4 KB504.8 KB0.0 KB/s2.7 KB23:59:54
2poznan0.0 KB0.0 KB0.0 KB0.0 KB/s014:34:42
3nask0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:49
4okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:49
5panorama0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:49
TOTAL: 5460.3 KB44.4 KB504.8 KB0.0 KB/s2.7 KB110:28:03
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
panorama2.4 MB
TOTAL: 12.4 MB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 1612.1 KB 8 0 000:00:09
2isvcs01.man.lodz.pl 4 00.0 KB 0 4 000:00:00
TOTAL: 2 292 1612.1 KB 8 4 000:00:50

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl1726 1612.1 KB 8 4 000:00:24
2unresolved 104 00.0 KB 0 0 000:00:23
3*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
TOTAL: 31831 1612.1 KB 8 4 000:00:50

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
nms1.man.lodz.pl 5.179 8.971 0.00000:00:14
194.165.16.76 0.008 0.006 0.00000:00:10
198.199.113.4 0.003 0.010 0.00000:00:09
web.man.lodz.pl 1.090 1.733 0.00000:00:09
80.111.142.213 0.010 0.042 0.00000:00:02
admirable.monitoring.internet-measurement.com 0.000 0.013 0.00000:00:01
212.51.192.185 0.375 0.645 0.00000:00:01
162.243.136.55 0.000 0.007 0.00000:00:00
94.102.61.41 0.004 0.008 0.00000:00:00
isvcs01.man.lodz.pl 0.032 0.017 0.00000:00:00
TOTAL: 10 6.701 11.452 0.00000:00:50

Curious NNRP server explorers [Top 20]:

SystemConn
nms1.man.lodz.pl 1434
212.51.192.185 96
80.111.142.213 4
194.165.16.76 1
admirable.monitoring.internet-measurement.com 1
162.243.136.55 1
94.102.61.41 1
198.199.113.4 1
TOTAL: 8 1539

NNRP unrecognized commands (by host):

SystemConn
94.102.61.41 5
162.243.136.55 1
194.165.16.76 1
TOTAL: 3 7

NNRP unrecognized commands (by command):

CommandCount
Host: 212.191.2.21:119 1
User-Agent: Mozilla/5.0 (Windows NT 10.0... 1
GET / HTTP/1.1 1
Accept: */* 1
Accept-Encoding: gzip 1
#003 1
MGLNDD_212.191.2.21_119 1
TOTAL: 7 7

NNRP client timeouts [Top 20]:

SystemConnPeer
194.165.16.76 1 1
TOTAL: 1 1 1

Newsgroup request counts (by category):

CategoryCountPct
1pl 16100.0%
TOTAL: 1 16100.0%

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

NewsgroupCount
1pl.comp.os.ms-windows.winnt 16
TOTAL: 1 16