Daily Usenet report

Oct 11 04:15:01 -- Oct 12 04:15:01

Unknown entries from news log file:

First 50 / 56 lines (89.3%)

Oct 11 12:00:47 newsfeed nnrpd[320546]: SERVER perl filter not defined
Oct 11 12:02:40 newsfeed nnrpd[320564]: SERVER perl filter not defined
Oct 11 17:23:42 newsfeed overchan[298835]: input line too long, skipping
Oct 11 17:23:48 newsfeed overchan[298835]: input line too long, skipping
Oct 11 17:24:43 newsfeed overchan[298835]: input line too long, skipping
Oct 11 17:25:33 newsfeed overchan[298835]: input line too long, skipping
Oct 11 17:25:33 newsfeed overchan[298835]: input line too long, skipping
Oct 11 17:25:34 newsfeed overchan[298835]: input line too long, skipping
Oct 11 17:25:38 newsfeed overchan[298835]: input line too long, skipping
Oct 11 17:26:04 newsfeed overchan[298835]: input line too long, skipping
Oct 11 17:27:48 newsfeed overchan[298835]: input line too long, skipping
Oct 11 17:27:49 newsfeed overchan[298835]: input line too long, skipping
Oct 11 17:27:50 newsfeed overchan[298835]: input line too long, skipping
Oct 11 17:27:53 newsfeed overchan[298835]: input line too long, skipping
Oct 11 17:27:53 newsfeed overchan[298835]: input line too long, skipping
Oct 11 17:27:54 newsfeed overchan[298835]: input line too long, skipping
Oct 11 17:29:05 newsfeed overchan[298835]: input line too long, skipping
Oct 11 17:29:05 newsfeed overchan[298835]: input line too long, skipping
Oct 11 17:29:52 newsfeed overchan[298835]: input line too long, skipping
Oct 11 17:30:21 newsfeed overchan[298835]: input line too long, skipping
Oct 11 17:30:22 newsfeed overchan[298835]: input line too long, skipping
Oct 11 17:30:23 newsfeed pgpverify[337429]: pgpverify: /usr/bin/gpg1: no such file
Oct 11 17:30:23 newsfeed controlchan[298833]: skipping newgroup y group-admin@isc.org (pgpverify failed) in <cmsg-20231009200003$1bd5@isc.org>
Oct 11 17:30:23 newsfeed pgpverify[337436]: pgpverify: /usr/bin/gpg1: no such file
Oct 11 17:30:23 newsfeed controlchan[298833]: skipping newgroup y group-admin@isc.org (pgpverify failed) in <cmsg-20231009200003$3131@isc.org>
Oct 11 17:31:23 newsfeed overchan[298835]: input line too long, skipping
Oct 11 17:31:24 newsfeed overchan[298835]: input line too long, skipping
Oct 11 17:32:42 newsfeed overchan[298835]: input line too long, skipping
Oct 11 17:32:50 newsfeed overchan[298835]: input line too long, skipping
Oct 11 17:32:55 newsfeed overchan[298835]: input line too long, skipping
Oct 11 17:32:55 newsfeed overchan[298835]: input line too long, skipping
Oct 12 00:05:12 newsfeed pgpverify[355040]: pgpverify: /usr/bin/gpg1: no such file
Oct 12 00:05:12 newsfeed controlchan[298833]: skipping newgroup y group-admin@isc.org (pgpverify failed) in <cmsg-20231010200002$0960@isc.org>
Oct 12 00:05:13 newsfeed pgpverify[355047]: pgpverify: /usr/bin/gpg1: no such file
Oct 12 00:05:13 newsfeed controlchan[298833]: skipping newgroup y group-admin@isc.org (pgpverify failed) in <cmsg-20231010200002$135f@isc.org>
Oct 12 00:05:27 newsfeed overchan[298835]: input line too long, skipping
Oct 12 00:05:27 newsfeed overchan[298835]: input line too long, skipping
Oct 12 00:05:28 newsfeed overchan[298835]: input line too long, skipping
Oct 12 00:06:42 newsfeed overchan[298835]: input line too long, skipping
Oct 12 00:06:42 newsfeed overchan[298835]: input line too long, skipping
Oct 12 00:07:08 newsfeed overchan[298835]: input line too long, skipping
Oct 12 00:07:09 newsfeed overchan[298835]: input line too long, skipping
Oct 12 00:07:11 newsfeed overchan[298835]: input line too long, skipping
Oct 12 00:07:11 newsfeed overchan[298835]: message repeated 2 times: [ input line too long, skipping]
Oct 12 00:07:17 newsfeed overchan[298835]: input line too long, skipping
Oct 12 00:07:18 newsfeed overchan[298835]: message repeated 2 times: [ input line too long, skipping]
Oct 12 00:07:48 newsfeed overchan[298835]: input line too long, skipping
Oct 12 00:12:33 newsfeed overchan[298835]: input line too long, skipping
Oct 12 00:13:50 newsfeed overchan[298835]: input line too long, skipping
Oct 12 00:16:13 newsfeed overchan[298835]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn 158914 85.9%19.7 MB 86.7%
innd 16879 9.1%2.1 MB 9.3%
nnrpd 7641 4.1%770.0 KB 3.3%
innfeed 1407 0.8%154.0 KB 0.7%
overchan 45 0.0%6.4 KB 0.0%
controlchan 5 0.0%0.4 KB 0.0%
pgpverify 4 0.0%0.5 KB 0.0%
TOTAL: 7 184895 100.0%22.7 MB100.0%

History cache:

ReasonCount%Count
Do not exist 158254 88.6%
Negative hits 19258 10.8%
Positive hits 884 0.5%
Cache misses 259 0.1%
TOTAL: 4 178655 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.837 0.0%222 0.000 3.770 6.600
article cleanup00:00:00.828 0.0%158649 0.000 0.005 0.049
article logging00:00:02.325 0.0%158686 0.000 0.015 0.081
article parse00:00:03.274 0.0%474160 0.000 0.007 0.015
article write00:00:32.974 0.0%142125 0.000 0.232 36.500
artlog/artcncl00:00:00.001 0.0%217 0.000 0.005 0.011
data move00:00:00.521 0.0%497959 0.000 0.001 0.008
hisgrep/artcncl00:00:00.199 0.0%181 0.000 1.099 4.000
hishave/artcncl00:00:00.173 0.0%222 0.000 0.779 1.800
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:06:09.711 0.4%178717 1.132 2.069 5.201
history sync00:00:00.017 0.0%315 0.000 0.054 1.000
history write00:07:44.020 0.5%158351 1.565 2.930 10.981
hiswrite/artcncl00:00:00.116 0.0%41 2.571 2.829 3.556
idle23:43:56.143 98.9%468516 3.211 182.355 2764.484
nntp read00:00:04.924 0.0%414108 0.000 0.012 0.037
overview write00:00:00.373 0.0%142125 0.000 0.003 0.333
perl filter00:01:27.556 0.1%158351 0.271 0.553 4.600
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:04.013 0.0%398641 0.000 0.010 0.111
TOTAL: 24:00:27.12124:00:08.005100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.108 0.0%123456 0.000 0.001 0.167
article prepare00:00:00.002 0.0%35372 0.000 0.000 0.006
article read00:00:00.383 0.0%8843 0.000 0.043 0.044
backlog stats00:00:00.066 0.0%352619 0.000 0.000 0.045
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.123 0.0%223248 0.000 0.005 0.250
data write00:00:01.617 0.0%124231 0.000 0.013 0.082
idle23:58:27.648100.0%352619 4.957 244.762 19404.129
status file00:00:00.263 0.0%392 0.000 0.671 6.857
TOTAL: 23:58:33.23723:58:31.210100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:01:14.020 5.9%4212 0.000 17.574 10050.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.156 0.0%10200 0.000 0.015 0.500
readart00:00:00.000 0.0%44 0.000 0.000 0.000
TOTAL: 00:21:04.73500:01:14.176 5.9%----

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 49 142693 141116 49 1528 98%10:09:16
2news.icm.edu.pl 2 16641 1069 607 14965 6%23:58:14
3localhost 5 4 4 0 0 100%00:00:01
TOTAL: 3 56 159338 142189 656 16493 89%34:07:31
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl767.1 MB6.2 MB11.6 MB785.0 MB 97%5.6 KB
2news.icm.edu.pl23.3 MB342.7 KB156.2 MB179.9 MB 12%11.5 KB
3localhost5.9 KB0.0 KB0.0 KB5.9 KB100%1.5 KB
TOTAL: 3790.4 MB6.6 MB167.9 MB964.9 MB 81%6.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 11 04:15:01 - 04:59:59 48 0.0% 0.021.1 MB 0.1% 0.43
Oct 11 05:00:00 - 05:59:59 101 0.1% 0.031.9 MB 0.2% 0.55
Oct 11 06:00:00 - 06:59:59 70 0.0% 0.022.0 MB 0.3% 0.58
Oct 11 07:00:00 - 07:59:59 44 0.0% 0.011.1 MB 0.1% 0.30
Oct 11 08:00:00 - 08:59:59 84 0.1% 0.021.4 MB 0.2% 0.40
Oct 11 09:00:00 - 09:59:59 51 0.0% 0.011019.9 KB 0.1% 0.28
Oct 11 10:00:00 - 10:59:59 39 0.0% 0.01854.1 KB 0.1% 0.24
Oct 11 11:00:00 - 11:59:59 39 0.0% 0.011.2 MB 0.1% 0.33
Oct 11 12:00:00 - 12:59:59 107 0.1% 0.032.1 MB 0.3% 0.59
Oct 11 13:00:00 - 13:59:59 62 0.0% 0.021.2 MB 0.2% 0.34
Oct 11 14:00:00 - 14:59:59 35 0.0% 0.01672.8 KB 0.1% 0.19
Oct 11 15:00:00 - 15:59:59 24 0.0% 0.01418.8 KB 0.1% 0.12
Oct 11 16:00:00 - 16:59:59 1258 0.9% 0.3514.5 MB 1.8% 4.12
Oct 11 17:00:00 - 17:59:59 64577 45.4% 17.94268.2 MB 33.9% 76.28
Oct 11 18:00:00 - 18:59:59 19 0.0% 0.01489.4 KB 0.1% 0.14
Oct 11 19:00:00 - 19:59:59 23 0.0% 0.01364.8 KB 0.0% 0.10
Oct 11 20:00:00 - 20:59:59 45 0.0% 0.011.0 MB 0.1% 0.29
Oct 11 21:00:00 - 21:59:59 40 0.0% 0.011010.2 KB 0.1% 0.28
Oct 11 22:00:00 - 22:59:59 34 0.0% 0.011.0 MB 0.1% 0.29
Oct 11 23:00:00 - 23:59:59 4008 2.8% 1.1155.4 MB 7.0% 15.75
Oct 12 00:00:00 - 00:59:59 55082 38.7% 15.30254.1 MB 32.1% 72.29
Oct 12 01:00:00 - 01:59:59 5688 4.0% 1.5888.0 MB 11.1% 25.03
Oct 12 02:00:00 - 02:59:59 4543 3.2% 1.2640.8 MB 5.2% 11.62
Oct 12 03:00:00 - 03:59:59 5187 3.6% 1.4443.4 MB 5.5% 12.34
Oct 12 04:00:00 - 04:15:01 990 0.7% 1.108.2 MB 1.0% 9.30
TOTAL: 24:00:00 142198 100.0% 1.65791.4 MB 100.0% 9.38
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.icm.edu.pl 14935 0 0 18 0 0 0 014917
2newsfeed.pionier.net.pl 1564 940 0 280 3 0 0 0 341
TOTAL: 2 16499 940 0 298 3 0 0 015258

Unwanted newsgroups [Top 20]:

NewsgroupCount
comp.mobile.android 183
fr.misc.actualite.covid19 177
uk.railway 165
eternal-september.support 101
it.comp.os.win.windows10 45
alt.comp.software.seamonkey 39
alt.comp.software.firefox 33
eternal-september.talk 28
eternal-september.test 26
eternal-september.newusers 20
nl.comp.os.linux.techniek 16
comp.lang.go 14
it.tlc.cellulari.android 14
rocksolid.spam 13
rocksolid.nodes.help 10
de.comp.lang.vbclassic 7
fr.misc.automobile.electrique 7
nl.comp.sys.android 4
perl.cpan.workers 4
it.comp.os.win.windows7 4
TOTAL: 39 940

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

GroupsCount
pl.sci.matematyka 2
christnet.bible 1
TOTAL: 2 3

INND Perl filter [Top 20]:

ReasonCount
Binary in non-binary group 14832
EMP (md5) 305
Scoring filter (9) 36
Scoring filter (8) 28
Scoring filter (10) 16
EMP (fsl) 3
EMP (phl) 1
TOTAL: 7 15221

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 36
news.icm.edu.pl 1
TOTAL: 2 37
TOTAL: 1 37

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1poznan 9107 6146 432 2509 0 938 67%21:30:55
2icm 116431 177 112328 11 0 0 0%05:12:52
3panorama 0 0 0 0 0 114396 0%23:57:47
4nask 0 0 0 0 0 88561 0%23:57:47
5okapi 0 0 0 0 0 3341 0%23:57:47
TOTAL: 5 125538 6323 112760 2520 0 207236 5%98:37:08
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1poznan131.7 MB49.2 MB180.9 MB2.4 KB/s21.4 KB21:30:55
2icm1.3 MB94.7 KB1.4 MB0.1 KB/s7.8 KB05:12:52
3panorama0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:47
4nask0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:47
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:47
TOTAL: 5133.1 MB49.3 MB182.4 MB0.5 KB/s21.1 KB98:37:08
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
panorama9.5 MB
TOTAL: 19.5 MB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 287 4439.0 KB 6 0 000:00:09
2isvcs01.man.lodz.pl 4 00.0 KB 0 4 000:00:00
TOTAL: 2 291 4439.0 KB 6 4 000:21:05

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl1688 4439.0 KB 6 4 000:00:29
2*.shodan.io 1 00.0 KB 0 0 000:00:03
3*.us-west-1.compute.amazonaws.com 3 00.0 KB 0 0 000:00:00
4unresolved 130 00.0 KB 0 0 000:20:31
5*.ch1.censys-scanner.com 1 00.0 KB 0 0 000:00:00
TOTAL: 51823 4439.0 KB 6 4 000:21:05

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
195.96.137.7 0.174 0.187 0.00000:20:27
nms2.man.lodz.pl 4.609 7.312 0.00000:00:19
web.man.lodz.pl 0.957 1.456 0.00000:00:09
census9.shodan.io 0.006 0.006 0.00000:00:03
80.111.142.213 0.019 0.024 0.00000:00:02
212.51.192.185 0.387 0.480 0.00000:00:01
ec2-3-101-216-110.us-west-1.compute.amazonaws.com 0.011 0.011 0.00000:00:00
scanner-25.ch1.censys-scanner.com 0.008 0.004 0.00000:00:00
162.243.134.64 0.002 0.004 0.00000:00:00
ec2-13-52-249-41.us-west-1.compute.amazonaws.com 0.008 0.004 0.00000:00:00
isvcs01.man.lodz.pl 0.031 0.022 0.00000:00:00
TOTAL: 11 6.212 9.510 0.00000:21:05

Curious NNRP server explorers [Top 20]:

SystemConn
nms2.man.lodz.pl 1397
212.51.192.185 95
195.96.137.7 30
80.111.142.213 4
ec2-3-101-216-110.us-west-1.compute.amazonaws.com 2
scanner-25.ch1.censys-scanner.com 1
census9.shodan.io 1
162.243.134.64 1
ec2-13-52-249-41.us-west-1.compute.amazonaws.com 1
TOTAL: 9 1532

NNRP unrecognized commands (by host):

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

NNRP unrecognized commands (by command):

CommandCount
EHLO www.censys.io 1
MGLNDD_212.191.2.21_119 1
TOTAL: 2 2

Newsgroup request counts (by category):

CategoryCountPct
1pl 44100.0%
TOTAL: 1 44100.0%

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

NewsgroupCount
1pl.comp.pecet 28
2pl.comp.os.ms-windows.winnt 16
TOTAL: 2 44
Valid XHTML 1.1 Strict CSS is valid!
innreport 3.1.0 (c) 1996-1999 by Fabien Tassin <fta@sofaraway.org>