Daily Usenet report

Nov 30 04:15:01 -- Dec 1 04:15:02

Unknown entries from news log file:

First 15 / 15 lines (100.0%)

Nov 30 06:05:42 newsfeed overchan[507128]: input line too long, skipping
Nov 30 12:28:20 newsfeed overchan[507128]: input line too long, skipping
Nov 30 14:41:51 newsfeed overchan[507128]: input line too long, skipping
Nov 30 14:42:47 newsfeed overchan[507128]: input line too long, skipping
Nov 30 14:45:05 newsfeed overchan[507128]: input line too long, skipping
Nov 30 14:46:04 newsfeed overchan[507128]: input line too long, skipping
Nov 30 17:14:56 newsfeed overchan[507128]: input line too long, skipping
Nov 30 18:49:49 newsfeed overchan[507128]: input line too long, skipping
Nov 30 18:50:02 newsfeed overchan[507128]: input line too long, skipping
Nov 30 18:50:55 newsfeed overchan[507128]: input line too long, skipping
Nov 30 19:13:57 newsfeed overchan[507128]: input line too long, skipping
Nov 30 23:10:41 newsfeed overchan[507128]: input line too long, skipping
Dec 1 00:26:16 newsfeed nnrpd[562112]: SERVER perl filter not defined
Dec 1 00:40:13 newsfeed overchan[507128]: input line too long, skipping
Dec 1 01:04:55 newsfeed pgpverify[563798]: pgpverify: /usr/bin/gpg1: no such file

Log entries by program:

Program nameLines%LinesSize%Size
inn 192762 90.0%22.5 MB 90.0%
innd 12318 5.7%1.6 MB 6.3%
nnrpd 7632 3.6%775.4 KB 3.0%
innfeed 1478 0.7%161.9 KB 0.6%
controlchan 22 0.0%5.1 KB 0.0%
overchan 14 0.0%1.8 KB 0.0%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 7 214227 100.0%25.0 MB100.0%

History cache:

ReasonCount%Count
Do not exist 191648 88.7%
Negative hits 13600 6.3%
Positive hits 10692 5.0%
Cache misses 40 0.0%
TOTAL: 4 215980 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.613 0.0%86 0.000 7.128 31.667
article cleanup00:00:01.269 0.0%192894 0.002 0.007 0.020
article logging00:00:04.211 0.0%192902 0.012 0.022 0.036
article parse00:00:06.864 0.0%792006 0.003 0.009 0.014
article write00:01:05.602 0.1%181350 0.105 0.362 4.855
artlog/artcncl00:00:00.000 0.0%86 0.000 0.000 0.000
data move00:00:01.166 0.0%1106054 0.000 0.001 0.003
hisgrep/artcncl00:00:00.235 0.0%86 0.000 2.733 14.000
hishave/artcncl00:00:00.137 0.0%86 0.000 1.593 7.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:13:04.186 0.9%216504 1.691 3.622 9.508
history sync00:00:00.016 0.0%295 0.000 0.054 1.000
history write00:14:00.175 1.0%192126 2.491 4.373 9.048
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:27:39.990 97.7%962561 14.335 87.745 28579.238
nntp read00:00:15.095 0.0%801614 0.011 0.019 0.026
overview write00:00:00.564 0.0%181350 0.000 0.003 0.009
perl filter00:03:15.590 0.2%192126 0.714 1.018 1.331
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:05.912 0.0%403826 0.007 0.015 0.020
TOTAL: 24:00:22.49523:59:41.625100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.086 0.0%41046 0.000 0.002 0.014
article prepare00:00:00.000 0.0%864 0.000 0.000 0.000
article read00:00:00.006 0.0%216 0.000 0.028 0.250
backlog stats00:00:00.055 0.0%170617 0.000 0.000 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.924 0.0%96781 0.000 0.010 0.027
data write00:00:02.148 0.0%55744 0.015 0.039 0.085
idle23:53:40.612100.0%170617 213.678 504.174 3200.293
status file00:00:00.171 0.0%353 0.000 0.484 1.333
TOTAL: 23:53:46.24923:53:44.002100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:18.249 52.0%4182 0.000 4.364 9717.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.142 0.4%10119 0.000 0.014 0.500
readart00:00:00.000 0.0%16 0.000 0.000 0.000
TOTAL: 00:00:35.07000:00:18.391 52.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 70 181380 180396 26 958 99%45:51:47
2news.icm.edu.pl 5 21276 748 9933 10595 3%24:33:57
3localhost 2 1 1 0 0 100%00:00:00
TOTAL: 3 77 202657 181145 9959 11553 89%70:25:44
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl2.1 GB11.6 MB13.0 MB2.1 GB 98%12.0 KB
2news.icm.edu.pl14.4 MB2.3 MB234.0 MB250.7 MB 5%22.6 KB
3localhost1.6 KB0.0 KB0.0 KB1.6 KB100%1.6 KB
TOTAL: 32.1 GB13.8 MB247.0 MB2.3 GB 89%12.6 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Nov 30 04:15:01 - 04:59:59 3868 2.1% 1.4350.9 MB 2.4% 19.30
Nov 30 05:00:00 - 05:59:59 7183 4.0% 2.0076.1 MB 3.6% 21.66
Nov 30 06:00:00 - 06:59:59 5946 3.3% 1.6566.5 MB 3.1% 18.93
Nov 30 07:00:00 - 07:59:59 7672 4.2% 2.13104.1 MB 4.9% 29.60
Nov 30 08:00:00 - 08:59:59 5747 3.2% 1.6061.1 MB 2.9% 17.37
Nov 30 09:00:00 - 09:59:59 6619 3.7% 1.8469.2 MB 3.3% 19.67
Nov 30 10:00:00 - 10:59:59 7944 4.4% 2.2186.1 MB 4.1% 24.49
Nov 30 11:00:00 - 11:59:59 6833 3.8% 1.9071.5 MB 3.4% 20.33
Nov 30 12:00:00 - 12:59:59 14659 8.1% 4.07384.0 MB 18.1% 109.22
Nov 30 13:00:00 - 13:59:59 10281 5.7% 2.8686.8 MB 4.1% 24.68
Nov 30 14:00:00 - 14:59:59 7338 4.1% 2.0463.6 MB 3.0% 18.09
Nov 30 15:00:00 - 15:59:59 8734 4.8% 2.4376.5 MB 3.6% 21.76
Nov 30 16:00:00 - 16:59:59 9674 5.3% 2.6984.2 MB 4.0% 23.94
Nov 30 17:00:00 - 17:59:59 8698 4.8% 2.4281.8 MB 3.9% 23.26
Nov 30 18:00:00 - 18:59:59 6588 3.6% 1.8362.6 MB 3.0% 17.79
Nov 30 19:00:00 - 19:59:59 7336 4.0% 2.0470.6 MB 3.3% 20.07
Nov 30 20:00:00 - 20:59:59 7889 4.4% 2.1986.7 MB 4.1% 24.67
Nov 30 21:00:00 - 21:59:59 7324 4.0% 2.0375.0 MB 3.5% 21.34
Nov 30 22:00:00 - 22:59:59 7902 4.4% 2.19101.0 MB 4.8% 28.73
Nov 30 23:00:00 - 23:59:59 8059 4.4% 2.2482.8 MB 3.9% 23.56
Dec 1 00:00:00 - 00:59:59 6106 3.4% 1.7067.5 MB 3.2% 19.19
Dec 1 01:00:00 - 01:59:59 7413 4.1% 2.0689.2 MB 4.2% 25.36
Dec 1 02:00:00 - 02:59:59 6007 3.3% 1.6767.1 MB 3.2% 19.09
Dec 1 03:00:00 - 03:59:59 5248 2.9% 1.4651.5 MB 2.4% 14.65
Dec 1 04:00:00 - 04:15:02 88 0.0% 0.10962.4 KB 0.0% 1.07
TOTAL: 24:00:01 181156 100.0% 2.102.1 GB 100.0% 25.09
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.icm.edu.pl 10554 0 0 145 0 0 0 010409
2newsfeed.pionier.net.pl 966 333 0 621 4 0 0 0 8
TOTAL: 2 11520 333 0 766 4 0 0 010417

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.binaries.erotica.divx 108
fr.misc.actualite.covid19 56
uk.railway 47
eternal-september.support 24
comp.mobile.android 19
eternal-september.talk 16
it.tlc.cellulari.android 14
hr.sci.med 13
grisbi.user.french 4
nl.comp.os.linux.techniek 4
aioe.test 4
comp.sys.raspberry-pi 4
fr.comp.os.android 4
it.comp.os.win.windows10 3
eternal-september.newusers 3
alt.comp.software.firefox 2
fido7.real.siberian.valenok 1
fr.rec.sport.jeux-olympiques 1
uk.radio.amateur.moderated 1
fido7.ru.linux.redhat 1
TOTAL: 24 333

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

GroupsCount
christnet.bible 3
fr.comp.lang.php 1
TOTAL: 2 4

INND Perl filter [Top 20]:

ReasonCount
Binary in non-binary group 9441
Scoring filter (9) 788
Scoring filter (8) 116
Scoring filter (10) 31
EMP (md5) 13
Scoring filter (11) 12
EMP (phl) 4
Scoring filter (13) 2
Scoring filter (12) 1
Scoring filter (16) 1
TOTAL: 10 10409

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1icm 55195 165 39789 50 0 3 0%23:59:59
2poznan 632 1 490 0 0 2 0%23:23:55
3panorama 0 0 0 0 0 41039 0%23:57:50
4okapi 0 0 0 0 0 846 0%23:57:50
5nask 0 0 0 0 0 35110 0%23:57:50
TOTAL: 5 55827 166 40279 50 0 77000 0%119:17:24
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm1.2 MB306.5 KB1.5 MB0.0 KB/s7.2 KB23:59:59
2poznan29.4 KB0.0 KB29.4 KB0.0 KB/s29.4 KB23:23:55
3panorama0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:50
4okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:50
5nask0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:50
TOTAL: 51.2 MB306.5 KB1.5 MB0.0 KB/s7.3 KB119:17:24
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
panorama3.3 MB
TOTAL: 13.3 MB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 290 1616.9 KB 8 0 000:00:07
2isvcs01.man.lodz.pl 1 00.0 KB 0 1 000:00:00
TOTAL: 2 291 1616.9 KB 8 1 000:00:35

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl1721 1616.9 KB 8 1 000:00:20
2*.ch1.censys-scanner.com 1 00.0 KB 0 0 000:00:00
3unresolved 104 00.0 KB 0 0 000:00:14
TOTAL: 31826 1616.9 KB 8 1 000:00:35

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
nms2.man.lodz.pl 4.357 6.874 0.00000:00:13
107.170.232.59 0.000 0.008 0.00000:00:09
web.man.lodz.pl 0.802 1.404 0.00000:00:07
80.111.142.213 0.018 0.023 0.00000:00:02
212.51.192.185 0.295 0.491 0.00000:00:00
scanner-26.ch1.censys-scanner.com 0.009 0.000 0.00000:00:00
192.241.232.45 0.006 0.000 0.00000:00:00
194.165.16.72 0.012 0.000 0.00000:00:00
194.26.135.36 0.003 0.006 0.00000:00:00
isvcs01.man.lodz.pl 0.000 0.014 0.00000:00:00
TOTAL: 10 5.502 8.820 0.00000:00:35

Curious NNRP server explorers [Top 20]:

SystemConn
nms2.man.lodz.pl 1430
212.51.192.185 96
80.111.142.213 4
scanner-26.ch1.censys-scanner.com 1
192.241.232.45 1
194.26.135.36 1
194.165.16.72 1
107.170.232.59 1
TOTAL: 8 1535

NNRP unrecognized commands (by host):

SystemConn
194.26.135.36 1
192.241.232.45 1
scanner-26.ch1.censys-scanner.com 1
194.165.16.72 1
TOTAL: 4 4

NNRP unrecognized commands (by command):

CommandCount
#003 2
MGLNDD_212.191.2.21_119 1
EHLO www.censys.io 1
TOTAL: 3 4

NNRP client timeouts [Top 20]:

SystemConnPeer
194.26.135.36 1 1
194.165.16.72 1 1
TOTAL: 2 2 2

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 14
2pl.comp.pecet 2
TOTAL: 2 16