Daily Usenet report

May 7 04:15:03 -- May 8 04:15:03

Unknown entries from news log file:

First 22 / 22 lines (100.0%)

May 7 08:15:26 newsfeed nnrpd[25390]: SERVER perl filter not defined
May 7 09:51:37 newsfeed overchan[15928]: input line too long, skipping
May 7 09:51:55 newsfeed overchan[15928]: input line too long, skipping
May 7 09:52:13 newsfeed overchan[15928]: input line too long, skipping
May 7 10:00:12 newsfeed nnrpd[29317]: SERVER perl filter not defined
May 7 11:43:48 newsfeed nnrpd[587]: SERVER perl filter not defined
May 7 15:12:23 newsfeed nnrpd[8514]: SERVER perl filter not defined
May 7 17:36:37 newsfeed nnrpd[13738]: SERVER perl filter not defined
May 7 17:59:34 newsfeed nnrpd[14646]: SERVER perl filter not defined
May 7 18:10:00 newsfeed nnrpd[15042]: SERVER perl filter not defined
May 7 19:29:44 newsfeed nnrpd[17967]: SERVER perl filter not defined
May 7 19:30:17 newsfeed nnrpd[18011]: SERVER perl filter not defined
May 7 19:51:17 newsfeed overchan[15928]: input line too long, skipping
May 7 19:52:00 newsfeed overchan[15928]: input line too long, skipping
May 7 19:53:45 newsfeed overchan[15928]: input line too long, skipping
May 8 00:44:44 newsfeed overchan[15928]: input line too long, skipping
May 8 00:45:04 newsfeed overchan[15928]: input line too long, skipping
May 8 00:45:56 newsfeed overchan[15928]: input line too long, skipping
May 8 00:47:27 newsfeed overchan[15928]: input line too long, skipping
May 8 00:51:07 newsfeed overchan[15928]: input line too long, skipping
May 8 00:51:28 newsfeed overchan[15928]: input line too long, skipping
May 8 00:51:46 newsfeed overchan[15928]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn2231040 97.0%261.6 MB 96.7%
innfeed 46648 2.0%5.5 MB 2.0%
innd 18222 0.8%2.9 MB 1.1%
nnrpd 4841 0.2%515.2 KB 0.2%
overchan 14 0.0%2.3 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 62300766 100.0%270.6 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2208136 97.6%
Negative hits 40806 1.8%
Positive hits 13216 0.6%
Cache misses 132 0.0%
TOTAL: 4 2262290 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:03.349 0.0%170 0.000 19.700 431.000
article cleanup00:00:35.850 0.0%2229516 0.013 0.016 0.021
article logging00:01:38.607 0.1%2230070 0.035 0.044 0.119
article parse00:59:58.748 4.2%146058469 0.017 0.025 0.035
article write00:22:33.175 1.6%2219172 0.430 0.610 1.136
artlog/artcncl00:00:00.005 0.0%158 0.000 0.032 0.250
data move00:03:08.526 0.2%160048310 0.001 0.001 0.002
hisgrep/artcncl00:00:01.275 0.0%161 0.000 7.919 249.000
hishave/artcncl00:00:00.125 0.0%170 0.000 0.735 6.333
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:33:15.213 2.3%2279157 0.083 0.875 6.232
history sync00:00:00.026 0.0%290 0.000 0.090 1.000
history write01:30:24.553 6.3%2222747 0.377 2.440 7.986
hiswrite/artcncl00:00:00.029 0.0%9 0.000 3.222 5.800
idle13:54:30.127 58.0%108595906 0.205 0.461 0.852
nntp read01:15:28.821 5.2%144346431 0.021 0.031 0.042
overview write00:00:13.614 0.0%2219172 0.004 0.006 0.009
perl filter04:35:09.794 19.1%2222747 6.319 7.428 8.760
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:04:11.558 0.3%4463153 0.042 0.056 0.079
TOTAL: 24:00:01.49323:21:13.395 97.3%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.219 0.0%63695 0.000 0.003 0.018
article prepare00:00:00.007 0.0%21020 0.000 0.000 0.006
article read00:00:56.088 0.1%5254 0.125 10.675 172.389
backlog stats00:00:00.180 0.0%195720 0.000 0.001 0.004
callbacks00:00:00.000 0.0%12 0.000 0.000 0.000
data read00:00:02.206 0.0%103780 0.004 0.021 0.057
data write00:00:08.603 0.0%80634 0.038 0.107 0.243
idle23:52:28.520 99.9%195720 204.801 439.140 837.929
status file00:00:16.161 0.0%16193 0.700 0.998 3.221
TOTAL: 23:54:04.45123:53:51.984100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:07:29.788 96.0%4391 0.000 102.434 83525.333
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:01.795 0.4%69884 0.000 0.026 0.667
readart00:00:00.002 0.0%40 0.000 0.050 0.500
TOTAL: 00:07:48.33000:07:31.585 96.4%----

Control commands to INND:

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 715 22199792212902 1058 6019 99%325:49:29
2news.nask.pl 2 16766 7220 5173 4373 43%24:12:54
3localhost 10 9 9 0 0 100%00:00:00
4news.icm.edu.pl 44 298 6 285 7 2%18:34:29
TOTAL: 4 771 22370522220137 6516 10399 99%368:36:52
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl687.2 GB1001.8 MB1002.5 MB689.2 GB 99%325.7 KB
2news.nask.pl1005.6 MB4.2 MB23.7 MB1.0 GB 97%91.3 KB
3news.icm.edu.pl63.9 KB59.3 KB196.3 KB319.5 KB 20%24.6 KB
4localhost13.5 KB0.0 KB0.0 KB13.5 KB100%1.5 KB
TOTAL: 4688.2 GB1006.1 MB1.0 GB690.2 GB 99%324.5 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 7 04:15:03 - 04:59:59 77011 3.5% 28.5527.5 GB 4.0%10687.31
May 7 05:00:00 - 05:59:59 97476 4.4% 27.0832.0 GB 4.7%9334.90
May 7 06:00:00 - 06:59:59 118139 5.3% 32.8240.5 GB 5.9%11808.63
May 7 07:00:00 - 07:59:59 124780 5.6% 34.6643.5 GB 6.3%12677.38
May 7 08:00:00 - 08:59:59 119046 5.4% 33.0739.7 GB 5.8%11549.73
May 7 09:00:00 - 09:59:59 127584 5.7% 35.4440.1 GB 5.8%11673.39
May 7 10:00:00 - 10:59:59 104124 4.7% 28.9231.1 GB 4.5%9065.78
May 7 11:00:00 - 11:59:59 113109 5.1% 31.4232.5 GB 4.7%9453.88
May 7 12:00:00 - 12:59:59 113498 5.1% 31.5331.3 GB 4.5%9102.92
May 7 13:00:00 - 13:59:59 67516 3.0% 18.7518.2 GB 2.7%5308.42
May 7 14:00:00 - 14:59:59 60376 2.7% 16.7717.1 GB 2.5%4973.06
May 7 15:00:00 - 15:59:59 57656 2.6% 16.0216.6 GB 2.4%4830.38
May 7 16:00:00 - 16:59:59 54581 2.5% 15.1616.0 GB 2.3%4649.41
May 7 17:00:00 - 17:59:59 49385 2.2% 13.7215.4 GB 2.2%4472.04
May 7 18:00:00 - 18:59:59 55823 2.5% 15.5116.6 GB 2.4%4831.77
May 7 19:00:00 - 19:59:59 53055 2.4% 14.7417.0 GB 2.5%4942.26
May 7 20:00:00 - 20:59:59 51770 2.3% 14.3816.7 GB 2.4%4857.56
May 7 21:00:00 - 21:59:59 68675 3.1% 19.0819.7 GB 2.9%5727.79
May 7 22:00:00 - 22:59:59 75295 3.4% 20.9220.6 GB 3.0%6005.22
May 7 23:00:00 - 23:59:59 69177 3.1% 19.2220.8 GB 3.0%6048.24
May 8 00:00:00 - 00:59:59 121227 5.5% 33.6736.5 GB 5.3%10637.49
May 8 01:00:00 - 01:59:59 141000 6.4% 39.1743.4 GB 6.3%12645.75
May 8 02:00:00 - 02:59:59 139898 6.3% 38.8643.9 GB 6.4%12776.16
May 8 03:00:00 - 03:59:59 126377 5.7% 35.1040.2 GB 5.8%11708.37
May 8 04:00:00 - 04:15:03 33409 1.5% 37.0010.7 GB 1.6%12429.42
TOTAL: 24:00:00 2219987 100.0% 25.69687.3 GB 100.0%8341.87
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 6483 98 0 5839 1 0 0 0 545
2news.nask.pl 4404 23 0 927 2 0 0 0 3452
3news.icm.edu.pl 8 0 0 3 0 0 0 0 5
TOTAL: 3 10895 121 0 6769 3 0 0 0 4002

Unwanted newsgroups [Top 20]:

NewsgroupCount
it.comp.os.win.windows10 33
comp.mobile.android 30
it.tlc.cellulari.android 20
uk.railway 19
comp.sys.raspberry-pi 11
de.comp.office-pakete.ms-office.misc 4
de.comp.lang.vbclassic 2
it.comp.os.win.windows7 1
de.comp.os.os2 1
TOTAL: 9 121

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

GroupsCount
pl.sci.matematyka 2
bit.listserv.catholic 1
TOTAL: 2 3

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 3225
Binary in non-binary group 121
Scoring filter (8) 21
Scoring filter (10) 19
Scoring filter (9) 18
EMP (phl) 13
Too many newsgroups 13
HTML post 5
Scoring filter (12) 4
Scoring filter (14) 4
Scoring filter (15) 2
EMP (fsl) 1
Excessively crossposted test article 1
TOTAL: 13 3447

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 466
news.nask.pl 88
news.icm.edu.pl 1
TOTAL: 3 555
TOTAL: 1 555

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1panorama 43414 504 15655 239 24 47422 1%23:59:57
2poznan 8345 132 5349 33 0 0 1%23:59:57
3icm 32414 45 20440 3500 0 0 0%23:59:57
4nask 10768 5 7012 196 0 0 0%23:59:57
5okapi 0 0 0 0 0 1203 0%23:57:50
TOTAL: 5 94941 686 48456 3968 24 48625 0%119:57:38
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nask1.9 MB71.8 MB73.7 MB0.9 KB/s375.6 KB23:59:57
2panorama1.2 MB939.8 KB2.2 MB0.0 KB/s3.0 KB23:59:57
3icm956.1 KB735.9 MB736.8 MB8.7 KB/s212.8 KB23:59:57
4poznan493.0 KB116.8 KB609.8 KB0.0 KB/s3.7 KB23:59:57
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:50
TOTAL: 54.6 MB808.8 MB813.3 MB1.9 KB/s178.9 KB119:57:38
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 4041.1 KB 18 0 000:00:21
2isvcs01.man.lodz.pl 9 00.0 KB 0 9 000:00:03
TOTAL: 2 297 4041.1 KB 18 9 000:07:47

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 681 4041.1 KB 18 9 000:00:30
2*.p.lodz.pl 1 00.0 KB 0 0 000:04:12
3*.census.shodan.io 1 00.0 KB 0 0 000:00:01
4unresolved 4 00.0 KB 0 0 000:03:03
TOTAL: 4 687 4041.1 KB 18 9 000:07:47

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
pc-212-191-92-112.p.lodz.pl 0.104 0.092 0.00000:04:12
185.48.194.49 0.052 0.172 0.00000:02:52
web.man.lodz.pl 1.308 4.780 0.00000:00:21
23.232.193.4 0.004 0.040 0.00000:00:10
mon.man.lodz.pl 1.200 4.268 0.00000:00:04
isvcs01.man.lodz.pl 0.060 0.188 0.00000:00:03
cloud.census.shodan.io 0.008 0.008 0.00000:00:01
david.man.lodz.pl 0.404 1.452 0.00000:00:01
TOTAL: 8 3.140 11.000 0.00000:07:47

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
david.man.lodz.pl 96
185.48.194.49 2
23.232.193.4 2
pc-212-191-92-112.p.lodz.pl 1
cloud.census.shodan.io 1
TOTAL: 6 390

Newsgroup request counts (by category):

CategoryCountPct
1pl 40100.0%
TOTAL: 1 40100.0%

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

NewsgroupCount
1pl.comp.pecet 26
2pl.comp.os.ms-windows.winnt 14
TOTAL: 2 40