Daily Usenet report

Oct 26 04:15:01 -- Oct 27 04:15:01

Unknown entries from news log file:

First 17 / 17 lines (100.0%)

Oct 26 11:30:51 newsfeed nnrpd[385256]: SERVER perl filter not defined
Oct 26 11:31:00 newsfeed nnrpd[385259]: SERVER perl filter not defined
Oct 26 15:40:56 newsfeed overchan[363168]: input line too long, skipping
Oct 26 15:59:26 newsfeed overchan[363168]: input line too long, skipping
Oct 26 16:06:02 newsfeed overchan[363168]: input line too long, skipping
Oct 26 16:18:26 newsfeed overchan[363168]: input line too long, skipping
Oct 26 16:51:37 newsfeed overchan[363168]: input line too long, skipping
Oct 26 17:05:21 newsfeed overchan[363168]: input line too long, skipping
Oct 26 17:07:38 newsfeed overchan[363168]: input line too long, skipping
Oct 26 18:43:40 newsfeed overchan[363168]: input line too long, skipping
Oct 26 18:55:57 newsfeed overchan[363168]: input line too long, skipping
Oct 26 19:07:03 newsfeed overchan[363168]: input line too long, skipping
Oct 26 22:43:44 newsfeed nnrpd[414969]: SERVER perl filter not defined
Oct 26 22:46:18 newsfeed nnrpd[415090]: SERVER perl filter not defined
Oct 26 23:01:00 newsfeed overchan[363168]: input line too long, skipping
Oct 27 00:06:10 newsfeed nnrpd[418747]: SERVER perl filter not defined
Oct 27 02:15:07 newsfeed overchan[363168]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn 216369 85.6%25.4 MB 85.2%
innd 26951 10.7%3.5 MB 11.6%
nnrpd 7700 3.0%781.7 KB 2.6%
innfeed 1628 0.6%179.5 KB 0.6%
overchan 13 0.0%1.7 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 6 252662 100.0%29.8 MB100.0%

History cache:

ReasonCount%Count
Do not exist 215191 87.7%
Negative hits 27588 11.2%
Positive hits 2560 1.0%
Cache misses 48 0.0%
TOTAL: 4 245387 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.251 0.0%80 0.000 15.637 47.000
article cleanup00:00:01.523 0.0%215938 0.002 0.007 0.014
article logging00:00:04.805 0.0%215949 0.015 0.022 0.034
article parse00:00:09.145 0.0%816598 0.005 0.011 0.016
article write00:00:28.849 0.0%190860 0.039 0.151 1.130
artlog/artcncl00:00:00.004 0.0%80 0.000 0.050 0.333
data move00:00:01.264 0.0%1170617 0.000 0.001 0.003
hisgrep/artcncl00:00:00.454 0.0%80 0.000 5.675 23.000
hishave/artcncl00:00:00.154 0.0%80 0.000 1.925 7.889
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:10:17.112 0.7%246050 0.746 2.508 7.705
history sync00:00:00.007 0.0%304 0.000 0.023 0.500
history write00:15:53.513 1.1%215700 1.918 4.421 11.061
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:28:28.880 97.8%963890 21.434 87.675 270.519
nntp read00:00:16.806 0.0%815720 0.012 0.021 0.027
overview write00:00:00.628 0.0%190860 0.000 0.003 0.010
perl filter00:03:51.165 0.3%215700 0.459 1.072 1.522
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:06.313 0.0%410717 0.008 0.015 0.027
TOTAL: 24:00:24.81523:59:41.873100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.077 0.0%28934 0.000 0.003 0.026
article prepare00:00:00.000 0.0%664 0.000 0.000 0.000
article read00:00:00.008 0.0%166 0.000 0.048 1.000
backlog stats00:00:00.064 0.0%128009 0.000 0.000 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.657 0.0%70537 0.000 0.009 0.031
data write00:00:01.710 0.0%41522 0.008 0.041 0.105
idle23:54:55.741100.0%128009 255.947 672.576 1691.697
status file00:00:00.215 0.0%436 0.000 0.493 1.000
TOTAL: 23:55:00.29623:54:58.472100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:19.340 31.4%4228 0.000 4.574 10048.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.147 0.2%10223 0.000 0.014 0.500
readart00:00:00.000 0.0%24 0.000 0.000 0.000
TOTAL: 00:01:01.66500:00:19.487 31.6%----

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 101 191367 190915 75 377 99%52:02:03
2news.icm.edu.pl 1 27426 308 2265 24853 1%24:34:11
3localhost 6 5 5 0 0 100%00:00:00
TOTAL: 3 108 218798 191228 2340 25230 87%76:36:14
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl2.3 GB7.2 MB7.6 MB2.4 GB 99%12.9 KB
2news.icm.edu.pl14.0 MB321.3 KB304.6 MB318.9 MB 4%13.0 KB
3localhost7.9 KB0.0 KB0.0 KB7.9 KB100%1.6 KB
TOTAL: 32.4 GB7.5 MB312.2 MB2.7 GB 88%12.9 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 26 04:15:01 - 04:59:59 3543 1.9% 1.3151.0 MB 2.1% 19.36
Oct 26 05:00:00 - 05:59:59 6694 3.5% 1.8689.6 MB 3.7% 25.49
Oct 26 06:00:00 - 06:59:59 4518 2.4% 1.2552.7 MB 2.2% 15.00
Oct 26 07:00:00 - 07:59:59 4242 2.2% 1.1866.8 MB 2.8% 19.00
Oct 26 08:00:00 - 08:59:59 6103 3.2% 1.7097.9 MB 4.1% 27.84
Oct 26 09:00:00 - 09:59:59 7959 4.2% 2.21107.5 MB 4.5% 30.59
Oct 26 10:00:00 - 10:59:59 7545 3.9% 2.10106.4 MB 4.4% 30.27
Oct 26 11:00:00 - 11:59:59 33218 17.4% 9.23113.3 MB 4.7% 32.24
Oct 26 12:00:00 - 12:59:59 8455 4.4% 2.35175.5 MB 7.3% 49.91
Oct 26 13:00:00 - 13:59:59 9302 4.9% 2.58159.8 MB 6.7% 45.46
Oct 26 14:00:00 - 14:59:59 7302 3.8% 2.0381.7 MB 3.4% 23.23
Oct 26 15:00:00 - 15:59:59 6269 3.3% 1.7469.3 MB 2.9% 19.71
Oct 26 16:00:00 - 16:59:59 8806 4.6% 2.45131.3 MB 5.5% 37.36
Oct 26 17:00:00 - 17:59:59 8287 4.3% 2.30147.4 MB 6.1% 41.92
Oct 26 18:00:00 - 18:59:59 7010 3.7% 1.9586.4 MB 3.6% 24.57
Oct 26 19:00:00 - 19:59:59 7681 4.0% 2.13110.3 MB 4.6% 31.37
Oct 26 20:00:00 - 20:59:59 6678 3.5% 1.8589.5 MB 3.7% 25.45
Oct 26 21:00:00 - 21:59:59 5923 3.1% 1.65104.9 MB 4.4% 29.83
Oct 26 22:00:00 - 22:59:59 7828 4.1% 2.1793.3 MB 3.9% 26.53
Oct 26 23:00:00 - 23:59:59 6277 3.3% 1.7497.8 MB 4.1% 27.82
Oct 27 00:00:00 - 00:59:59 7502 3.9% 2.08112.7 MB 4.7% 32.06
Oct 27 01:00:00 - 01:59:59 6206 3.2% 1.72100.0 MB 4.2% 28.43
Oct 27 02:00:00 - 02:59:59 6702 3.5% 1.8677.6 MB 3.2% 22.06
Oct 27 03:00:00 - 03:59:59 5505 2.9% 1.5360.7 MB 2.5% 17.26
Oct 27 04:00:00 - 04:15:01 1549 0.8% 1.7216.9 MB 0.7% 19.16
TOTAL: 24:00:00 191104 100.0% 2.212.3 GB 100.0% 28.45
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.icm.edu.pl 24798 0 0 23 0 0 0 024775
2newsfeed.pionier.net.pl 387 159 0 215 2 0 0 0 11
TOTAL: 2 25185 159 0 238 2 0 0 024786

Unwanted newsgroups [Top 20]:

NewsgroupCount
it.tlc.cellulari.android 42
it.comp.os.win.windows10 26
eternal-september.support 25
fr.misc.actualite.covid19 10
alt.comp.software.firefox 10
de.comm.software.mailreader 10
comp.sys.raspberry-pi 10
uk.railway 9
comp.mobile.android 8
eternal-september.newusers 3
eternal-september.talk 2
nf.test 1
eternal-september.test 1
grisbi.test 1
rocksolid.nodes 1
TOTAL: 15 159

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

GroupsCount
christnet.bible 2
TOTAL: 1 2

INND Perl filter [Top 20]:

ReasonCount
Binary in non-binary group 24727
Scoring filter (8) 24
Scoring filter (11) 8
EMP (md5) 7
Scoring filter (9) 6
Scoring filter (12) 2
Scoring filter (10) 1
TOTAL: 7 24775

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 41718 157 28423 9 0 1 0%24:09:56
2panorama 0 0 0 0 0 29083 0%24:05:36
3nask 0 0 0 0 0 24672 0%24:05:35
4poznan 395 0 299 0 0 0 0%21:08:10
5okapi 0 0 0 0 0 606 0%24:05:36
TOTAL: 5 42113 157 28722 9 0 54362 0%117:34:53
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm956.7 KB25.2 KB981.9 KB0.0 KB/s5.9 KB24:09:56
2panorama0.0 KB0.0 KB0.0 KB0.0 KB/s024:05:36
3nask0.0 KB0.0 KB0.0 KB0.0 KB/s024:05:35
4poznan0.0 KB0.0 KB0.0 KB0.0 KB/s021:08:10
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s024:05:36
TOTAL: 5956.7 KB25.2 KB981.9 KB0.0 KB/s5.9 KB117:34:53
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
panorama2.9 MB
TOTAL: 12.9 MB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 2425.9 KB 12 0 000:00:08
2isvcs01.man.lodz.pl 5 00.0 KB 0 5 000:00:00
TOTAL: 2 293 2425.9 KB 12 5 000:01:02

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl1733 2425.9 KB 12 5 000:00:22
2*.150.216.162.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:00
3unresolved 106 00.0 KB 0 0 000:00:39
TOTAL: 31840 2425.9 KB 12 5 000:01:02

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
118.193.58.187 0.006 0.024 0.00000:00:34
nms2.man.lodz.pl 4.996 8.344 0.00000:00:13
web.man.lodz.pl 1.025 1.590 0.00000:00:08
80.111.142.213 0.018 0.032 0.00000:00:02
212.51.192.185 0.361 0.528 0.00000:00:01
79.124.62.106 0.000 0.007 0.00000:00:01
198.199.108.70 0.010 0.000 0.00000:00:00
98.150.216.162.bc.googleusercontent.com 0.005 0.007 0.00000:00:00
isvcs01.man.lodz.pl 0.042 0.039 0.00000:00:00
45.227.254.48 0.005 0.005 0.00000:00:00
TOTAL: 10 6.468 10.576 0.00000:01:02

Curious NNRP server explorers [Top 20]:

SystemConn
nms2.man.lodz.pl 1440
212.51.192.185 96
80.111.142.213 4
118.193.58.187 3
79.124.62.106 1
98.150.216.162.bc.googleusercontent.com 1
45.227.254.48 1
198.199.108.70 1
TOTAL: 8 1547

NNRP unrecognized commands (by host):

SystemConn
118.193.58.187 3
98.150.216.162.bc.googleusercontent.com 3
79.124.62.106 1
45.227.254.48 1
198.199.108.70 1
TOTAL: 5 9

NNRP unrecognized commands (by command):

CommandCount
#003 2
User-Agent: Expanse, a Palo Alto Network... 1
MGLNDD_212.191.2.21_119 1
#026#003#001#001#027#001 1
À#024 1
Host: 212.191.2.21:119 1
#022¼½8ÿ¨û Ýeq\x7fÔCuS#013M¥À#032SÖvïð âEŽt0 Q@SÖì#031 1
GET / HTTP/1.1 1
TOTAL: 8 9

NNRP client timeouts [Top 20]:

SystemConnPeer
45.227.254.48 1 1
79.124.62.106 1 1
TOTAL: 2 2 2

Newsgroup request counts (by category):

CategoryCountPct
1pl 24100.0%
TOTAL: 1 24100.0%

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

NewsgroupCount
1pl.comp.pecet 16
2pl.comp.os.ms-windows.winnt 8
TOTAL: 2 24