Daily Usenet report

Oct 31 04:15:01 -- Nov 1 04:15:01

Unknown entries from news log file:

First 18 / 18 lines (100.0%)

Oct 31 07:52:39 newsfeed nnrpd[98228]: SERVER perl filter not defined
Oct 31 11:54:18 newsfeed nnrpd[108780]: SERVER perl filter not defined
Oct 31 12:11:57 newsfeed nnrpd[109511]: SERVER perl filter not defined
Oct 31 12:15:49 newsfeed nnrpd[109793]: SERVER perl filter not defined
Oct 31 13:09:16 newsfeed nnrpd[112023]: SERVER perl filter not defined
Oct 31 16:25:39 newsfeed overchan[1453]: input line too long, skipping
Oct 31 16:30:08 newsfeed overchan[1453]: input line too long, skipping
Oct 31 16:33:29 newsfeed overchan[1453]: input line too long, skipping
Oct 31 16:47:07 newsfeed overchan[1453]: input line too long, skipping
Oct 31 17:08:21 newsfeed overchan[1453]: input line too long, skipping
Oct 31 17:40:06 newsfeed overchan[1453]: input line too long, skipping
Oct 31 17:41:08 newsfeed overchan[1453]: input line too long, skipping
Oct 31 17:47:43 newsfeed overchan[1453]: input line too long, skipping
Oct 31 20:16:26 newsfeed overchan[1453]: input line too long, skipping
Oct 31 20:18:31 newsfeed overchan[1453]: input line too long, skipping
Oct 31 20:59:53 newsfeed overchan[1453]: input line too long, skipping
Nov 1 00:53:02 newsfeed overchan[1453]: input line too long, skipping
Nov 1 01:04:03 newsfeed pgpverify[31025]: pgpverify: /usr/bin/gpg1: no such file

Log entries by program:

Program nameLines%LinesSize%Size
inn 171992 88.4%19.8 MB 88.3%
innd 13460 6.9%1.7 MB 7.5%
nnrpd 7669 3.9%773.2 KB 3.4%
innfeed 1508 0.8%174.2 KB 0.8%
controlchan 23 0.0%5.3 KB 0.0%
overchan 13 0.0%1.6 KB 0.0%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 7 194666 100.0%22.5 MB100.0%

History cache:

ReasonCount%Count
Do not exist 170170 91.7%
Negative hits 13604 7.3%
Positive hits 1774 1.0%
Cache misses 26 0.0%
TOTAL: 4 185574 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.929 0.0%53 0.000 17.528 39.500
article cleanup00:00:01.193 0.0%171238 0.000 0.007 0.014
article logging00:00:03.865 0.0%171249 0.014 0.023 0.041
article parse00:00:08.362 0.0%724391 0.007 0.012 0.017
article write00:00:23.606 0.0%159165 0.067 0.148 1.287
artlog/artcncl00:00:00.000 0.0%53 0.000 0.000 0.000
data move00:00:01.024 0.0%1047271 0.000 0.001 0.003
hisgrep/artcncl00:00:00.398 0.0%50 0.000 7.960 18.357
hishave/artcncl00:00:00.177 0.0%53 0.000 3.340 6.500
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:13:03.632 0.9%186116 1.674 4.210 6.615
history sync00:00:00.013 0.0%359 0.000 0.036 0.667
history write00:16:36.558 1.2%170714 3.103 5.838 9.531
hiswrite/artcncl00:00:00.019 0.0%3 0.000 6.333 10.000
idle23:16:21.244 97.6%877596 8.219 95.467 241.698
nntp read00:00:15.957 0.0%732555 0.016 0.022 0.030
overview write00:00:00.518 0.0%159165 0.000 0.003 0.007
perl filter00:03:16.827 0.2%170714 0.865 1.153 1.852
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:05.548 0.0%343248 0.009 0.016 0.024
TOTAL: 23:50:52.22023:50:19.870100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.066 0.0%24800 0.000 0.003 0.015
article prepare00:00:00.000 0.0%216 0.000 0.000 0.000
article read00:00:00.002 0.0%54 0.000 0.037 1.000
backlog stats00:00:00.036 0.0%99606 0.000 0.000 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.526 0.0%56423 0.000 0.009 0.024
data write00:00:01.318 0.0%31780 0.008 0.041 0.103
idle23:45:21.222100.0%99606 415.809 858.595 1755.477
status file00:00:00.207 0.0%386 0.000 0.536 3.833
TOTAL: 23:45:25.03823:45:23.377100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:10.987 34.9%4216 0.000 2.606 1831.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.132 0.4%10202 0.000 0.013 0.500
readart00:00:00.025 0.1%30 0.000 0.833 8.000
TOTAL: 00:00:31.45900:00:11.144 35.4%----

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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
bofh-control@killfile.org001001
us-control@lists.killfile.org000100
TOTAL001101

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 67 159789 159104 10 675 99%39:16:51
2news.icm.edu.pl 2 13126 491 1244 11391 3%23:50:41
3localhost 6 5 5 0 0 100%00:00:00
TOTAL: 3 75 172920 159600 1254 12066 92%63:07:32
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl2.2 GB7.0 MB7.5 MB2.2 GB 99%14.5 KB
2news.icm.edu.pl8.2 MB1.1 MB193.1 MB202.4 MB 4%17.4 KB
3localhost7.2 KB0.0 KB0.0 KB7.2 KB100%1.4 KB
TOTAL: 32.2 GB8.0 MB200.6 MB2.4 GB 91%14.7 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 31 04:15:01 - 04:59:59 3444 2.2% 1.2853.2 MB 2.4% 20.20
Oct 31 05:00:00 - 05:59:59 5380 3.4% 1.4958.7 MB 2.6% 16.70
Oct 31 06:00:00 - 06:59:59 5314 3.3% 1.4857.7 MB 2.6% 16.40
Oct 31 07:00:00 - 07:59:59 4761 3.0% 1.3280.9 MB 3.6% 23.00
Oct 31 08:00:00 - 08:59:59 10007 6.3% 2.78425.7 MB 18.9% 121.09
Oct 31 09:00:00 - 09:59:59 7127 4.5% 1.9880.3 MB 3.6% 22.85
Oct 31 10:00:00 - 10:59:59 6800 4.3% 1.8968.7 MB 3.1% 19.54
Oct 31 11:00:00 - 11:59:59 8624 5.4% 2.40181.3 MB 8.1% 51.56
Oct 31 12:00:00 - 12:59:59 6230 3.9% 1.7367.4 MB 3.0% 19.17
Oct 31 13:00:00 - 13:59:59 6386 4.0% 1.7764.0 MB 2.8% 18.20
Oct 31 14:00:00 - 14:59:59 5068 3.2% 1.4149.4 MB 2.2% 14.05
Oct 31 15:00:00 - 15:59:59 6141 3.8% 1.7182.2 MB 3.7% 23.37
Oct 31 16:00:00 - 16:59:59 10548 6.6% 2.93131.3 MB 5.8% 37.34
Oct 31 17:00:00 - 17:59:59 6270 3.9% 1.7471.0 MB 3.2% 20.20
Oct 31 18:00:00 - 18:59:59 5154 3.2% 1.4349.3 MB 2.2% 14.02
Oct 31 19:00:00 - 19:59:59 8978 5.6% 2.49103.5 MB 4.6% 29.44
Oct 31 20:00:00 - 20:59:59 7463 4.7% 2.0792.4 MB 4.1% 26.29
Oct 31 21:00:00 - 21:59:59 5676 3.6% 1.5860.1 MB 2.7% 17.11
Oct 31 22:00:00 - 22:59:59 5462 3.4% 1.5259.3 MB 2.6% 16.85
Oct 31 23:00:00 - 23:59:59 9993 6.3% 2.78119.0 MB 5.3% 33.85
Nov 1 00:00:00 - 00:59:59 9599 6.0% 2.67110.6 MB 4.9% 31.47
Nov 1 01:00:00 - 01:59:59 5724 3.6% 1.5978.7 MB 3.5% 22.37
Nov 1 02:00:00 - 02:59:59 4648 2.9% 1.2956.0 MB 2.5% 15.93
Nov 1 03:00:00 - 03:59:59 4113 2.6% 1.1442.6 MB 1.9% 12.11
Nov 1 04:00:00 - 04:15:01 878 0.5% 0.977.3 MB 0.3% 8.28
TOTAL: 24:00:00 159788 100.0% 1.852.2 GB 100.0% 26.67
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.icm.edu.pl 11463 0 0 90 0 0 0 011373
2newsfeed.pionier.net.pl 688 235 0 437 3 0 0 0 13
TOTAL: 2 12151 235 0 527 3 0 0 011386

Unwanted newsgroups [Top 20]:

NewsgroupCount
fr.misc.actualite.covid19 50
uk.railway 45
eternal-september.support 40
alt.comp.software.firefox 32
it.comp.os.win.windows10 17
comp.mobile.android 9
eternal-september.test 9
alt.comp.software.seamonkey 5
eternal-september.newusers 5
comp.mobile.ipad 4
eternal-september.talk 3
fr.comp.os.android 3
comp.sys.raspberry-pi 3
it.tlc.cellulari.android 2
de.comp.office-pakete.ms-office.outlook 2
it.comp.os.win.windows7 1
grisbi.test 1
de.comp.lang.vbclassic 1
de.comm.software.mailreader 1
fido7.ru.linux.redhat 1
TOTAL: 21 235

Supposedly-moderated groups with unmoderated postings [Top 20]:

GroupsCount
pl.sci.matematyka 3
TOTAL: 1 3

INND Perl filter [Top 20]:

ReasonCount
Binary in non-binary group 11360
EMP (md5) 7
Scoring filter (8) 4
Scoring filter (9) 3
Scoring filter (11) 1
TOTAL: 5 11375

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1icm 31506 45 24400 9 0 3 0%24:06:30
2poznan 545 1 490 0 0 0 0%23:00:10
3okapi 0 0 0 0 0 632 0%23:45:57
4panorama 0 0 0 0 0 24840 0%23:45:57
5nask 0 0 0 0 0 20628 0%23:45:57
TOTAL: 5 32051 46 24890 9 0 46103 0%118:24:31
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm241.5 KB30.2 KB271.7 KB0.0 KB/s5.0 KB24:06:30
2poznan11.1 KB0.0 KB11.1 KB0.0 KB/s11.1 KB23:00:10
3okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:45:57
4panorama0.0 KB0.0 KB0.0 KB0.0 KB/s023:45:57
5nask0.0 KB0.0 KB0.0 KB0.0 KB/s023:45:57
TOTAL: 5252.6 KB30.2 KB282.8 KB0.0 KB/s5.1 KB118:24:31
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 287 3059.7 KB 15 0 000:00:08
2isvcs01.man.lodz.pl 5 00.0 KB 0 5 000:00:00
TOTAL: 2 292 3059.7 KB 15 5 000:00:31

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl1732 3059.7 KB 15 5 000:00:23
2unresolved 101 00.0 KB 0 0 000:00:06
3*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
4*.ch1.censys-scanner.com 1 00.0 KB 0 0 000:00:00
TOTAL: 41835 3059.7 KB 15 5 000:00:31

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
nms2.man.lodz.pl 5.708 7.378 0.00000:00:14
web.man.lodz.pl 1.047 1.519 0.00000:00:08
141.98.9.50 0.005 0.006 0.00000:00:03
80.111.142.213 0.016 0.030 0.00000:00:02
dexterous.monitoring.internet-measurement.com 0.006 0.005 0.00000:00:01
212.51.192.185 0.418 0.463 0.00000:00:01
scanner-25.ch1.censys-scanner.com 0.005 0.002 0.00000:00:00
isvcs01.man.lodz.pl 0.035 0.038 0.00000:00:00
TOTAL: 8 7.240 9.441 0.00000:00:31

Curious NNRP server explorers [Top 20]:

SystemConn
nms2.man.lodz.pl 1440
212.51.192.185 96
80.111.142.213 4
141.98.9.50 1
scanner-25.ch1.censys-scanner.com 1
dexterous.monitoring.internet-measurement.com 1
TOTAL: 6 1543

NNRP unrecognized commands (by host):

SystemConn
141.98.9.50 1
scanner-25.ch1.censys-scanner.com 1
TOTAL: 2 2

NNRP unrecognized commands (by command):

CommandCount
EHLO www.censys.io 1
#003 1
TOTAL: 2 2

NNRP client timeouts [Top 20]:

SystemConnPeer
141.98.9.50 1 1
TOTAL: 1 1 1

Newsgroup request counts (by category):

CategoryCountPct
1pl 30100.0%
TOTAL: 1 30100.0%

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

NewsgroupCount
1pl.comp.pecet 26
2pl.comp.os.ms-windows.winnt 4
TOTAL: 2 30