Daily Usenet report

Sep 25 04:15:02 -- Sep 26 04:15:03

Unknown entries from news log file:

First 17 / 17 lines (100.0%)

Sep 25 04:15:21 newsfeed overchan[26979]: input line too long, skipping
Sep 25 09:15:07 newsfeed nnrpd[6170]: SERVER perl filter not defined
Sep 25 09:16:21 newsfeed nnrpd[6184]: SERVER perl filter not defined
Sep 25 09:26:37 newsfeed nnrpd[6679]: SERVER perl filter not defined
Sep 25 09:41:45 newsfeed nnrpd[7151]: SERVER perl filter not defined
Sep 25 09:42:21 newsfeed nnrpd[7152]: SERVER perl filter not defined
Sep 25 10:42:29 newsfeed overchan[26979]: input line too long, skipping
Sep 25 10:49:12 newsfeed nnrpd[9617]: SERVER perl filter not defined
Sep 25 11:30:19 newsfeed nnrpd[11157]: SERVER perl filter not defined
Sep 25 12:11:53 newsfeed nnrpd[12644]: SERVER perl filter not defined
Sep 25 15:33:43 newsfeed nnrpd[20146]: SERVER perl filter not defined
Sep 25 18:38:55 newsfeed overchan[26979]: input line too long, skipping
Sep 25 18:55:35 newsfeed nnrpd[27529]: SERVER perl filter not defined
Sep 25 20:18:53 newsfeed overchan[26979]: input line too long, skipping
Sep 25 21:04:11 newsfeed overchan[26979]: input line too long, skipping
Sep 25 21:12:14 newsfeed overchan[26979]: input line too long, skipping
Sep 25 21:19:19 newsfeed nnrpd[441]: SERVER perl filter not defined

Log entries by program:

Program nameLines%LinesSize%Size
inn3109231 96.7%363.2 MB 96.3%
innd 57239 1.8%8.0 MB 2.1%
innfeed 44566 1.4%5.3 MB 1.4%
nnrpd 5343 0.2%562.5 KB 0.1%
overchan 7 0.0%1.2 KB 0.0%
controlchan 1 0.0%0.2 KB 0.0%
TOTAL: 63216387 100.0%377.1 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2990535 65.0%
Negative hits 1332632 29.0%
Positive hits 274154 6.0%
Cache misses 737 0.0%
TOTAL: 4 4598058 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:02.732 0.0%220 0.000 12.418 356.000
article cleanup00:00:48.659 0.1%3108285 0.013 0.016 0.021
article logging00:02:35.644 0.2%3109080 0.035 0.050 0.207
article parse01:23:11.846 5.8%185312795 0.020 0.027 0.053
article write00:32:49.262 2.3%2951815 0.304 0.667 1.322
artlog/artcncl00:00:00.008 0.0%212 0.000 0.038 0.500
data move00:04:53.512 0.3%205117609 0.001 0.001 0.004
hisgrep/artcncl00:00:00.696 0.0%215 0.000 3.237 41.167
hishave/artcncl00:00:00.240 0.0%220 0.000 1.091 10.700
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:30:52.333 2.1%4598415 0.051 0.403 2.510
history sync00:00:00.018 0.0%290 0.000 0.062 0.500
history write01:23:00.746 5.8%2988378 0.259 1.667 7.258
hiswrite/artcncl00:00:00.005 0.0%5 0.000 1.000 5.000
idle10:53:51.074 45.4%108742419 0.210 0.361 0.525
nntp read01:30:57.121 6.3%183303226 0.022 0.030 0.040
overview write00:00:18.634 0.0%2951815 0.005 0.006 0.008
perl filter06:55:55.009 28.9%2988378 4.420 8.351 9.518
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:06:33.318 0.5%6329049 0.051 0.062 0.078
TOTAL: 24:00:02.81523:25:50.857 97.6%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:02.302 0.0%787728 0.001 0.003 0.005
article prepare00:00:00.042 0.0%135914 0.000 0.000 0.003
article read00:01:34.964 0.1%32939 0.294 2.883 133.800
backlog stats00:00:01.587 0.0%2103951 0.000 0.001 0.002
callbacks00:00:00.000 0.0%2 0.000 0.000 0.000
data read00:00:26.214 0.0%1428709 0.010 0.018 0.028
data write00:01:27.465 0.1%1035847 0.038 0.084 0.257
idle23:45:46.041 99.7%2103951 25.094 40.660 267.134
status file00:00:22.362 0.0%16521 0.775 1.354 11.592
TOTAL: 23:50:40.12823:49:40.977 99.9%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle05:26:18.725 99.8%3704 0.000 5285.833297395.667
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.910 0.0%8661 0.000 0.105 5.364
readart00:00:03.553 0.0%201 0.000 17.677 85.286
TOTAL: 05:26:58.54605:26:23.188 99.8%----

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 1160 29846822818773 32353 133556 94%459:24:32
2news.nask.pl 2 278653 133341 122327 22985 47%23:52:44
3news.icm.edu.pl 33 294 102 178 14 34%17:21:05
4localhost 12 11 11 0 0 100%00:00:01
TOTAL: 4 1207 32636402952227 154858 156555 90%500:38:22
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl897.3 GB35.3 GB40.6 GB973.2 GB 92%345.7 KB
2news.nask.pl41.6 GB3.8 GB5.4 GB50.8 GB 81%340.7 KB
3news.icm.edu.pl2.0 MB27.3 KB404.7 KB2.4 MB 82%21.2 KB
4localhost15.8 KB0.0 KB0.0 KB15.8 KB100%1.4 KB
TOTAL: 4938.9 GB39.0 GB46.0 GB1024.0 GB 91%345.4 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 25 04:15:02 - 04:59:59 94590 3.2% 35.0630.6 GB 3.3%11903.73
Sep 25 05:00:00 - 05:59:59 123746 4.2% 34.3740.1 GB 4.3%11687.86
Sep 25 06:00:00 - 06:59:59 124432 4.2% 34.5640.4 GB 4.3%11779.51
Sep 25 07:00:00 - 07:59:59 132765 4.5% 36.8841.6 GB 4.4%12127.45
Sep 25 08:00:00 - 08:59:59 124905 4.2% 34.7039.8 GB 4.2%11596.90
Sep 25 09:00:00 - 09:59:59 121910 4.1% 33.8640.7 GB 4.3%11854.08
Sep 25 10:00:00 - 10:59:59 125364 4.2% 34.8241.8 GB 4.5%12185.05
Sep 25 11:00:00 - 11:59:59 122802 4.2% 34.1139.9 GB 4.3%11616.97
Sep 25 12:00:00 - 12:59:59 123192 4.2% 34.2240.7 GB 4.3%11850.81
Sep 25 13:00:00 - 13:59:59 112898 3.8% 31.3637.1 GB 4.0%10806.26
Sep 25 14:00:00 - 14:59:59 125343 4.2% 34.8241.3 GB 4.4%12043.09
Sep 25 15:00:00 - 15:59:59 128874 4.4% 35.8041.4 GB 4.4%12071.22
Sep 25 16:00:00 - 16:59:59 121715 4.1% 33.8138.4 GB 4.1%11178.75
Sep 25 17:00:00 - 17:59:59 114650 3.9% 31.8538.2 GB 4.1%11124.08
Sep 25 18:00:00 - 18:59:59 134726 4.6% 37.4238.0 GB 4.1%11061.70
Sep 25 19:00:00 - 19:59:59 135590 4.6% 37.6640.6 GB 4.3%11831.39
Sep 25 20:00:00 - 20:59:59 132210 4.5% 36.7341.4 GB 4.4%12052.68
Sep 25 21:00:00 - 21:59:59 129218 4.4% 35.8940.1 GB 4.3%11677.25
Sep 25 22:00:00 - 22:59:59 154165 5.2% 42.8237.6 GB 4.0%10947.00
Sep 25 23:00:00 - 23:59:59 114484 3.9% 31.8036.9 GB 3.9%10745.29
Sep 26 00:00:00 - 00:59:59 98434 3.3% 27.3432.0 GB 3.4%9323.44
Sep 26 01:00:00 - 01:59:59 108926 3.7% 30.2637.5 GB 4.0%10917.76
Sep 26 02:00:00 - 02:59:59 119858 4.1% 33.2939.1 GB 4.2%11379.54
Sep 26 03:00:00 - 03:59:59 102533 3.5% 28.4833.7 GB 3.6%9804.50
Sep 26 04:00:00 - 04:15:03 24424 0.8% 27.058.5 GB 0.9%9896.08
TOTAL: 24:00:01 2951754 100.0% 34.16937.5 GB 100.0%11377.62
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl133907 147 0107979 2 0 0 025779
2news.nask.pl 23334 18 0 11925 7 0 0 011384
3news.icm.edu.pl 24 0 0 3 0 0 0 0 21
TOTAL: 3157265 165 0119907 9 0 0 037184

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 95
it.tlc.cellulari.android 27
de.rec.sf.startrek 14
it.comp.os.win.windows10 13
comp.sys.raspberry-pi 6
comp.mobile.ipad 5
hr.rec.glazba.hifi 3
it.discussioni.energia 1
comp.mobile.android 1
TOTAL: 9 165

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

GroupsCount
pl.praca.oferowana 8
bit.listserv.catholic 1
TOTAL: 2 9

INND Perl filter [Top 20]:

ReasonCount
Binary in non-binary group 33342
EMP (md5) 2944
Scoring filter (8) 43
Scoring filter (9) 27
Scoring filter (10) 14
Scoring filter (12) 4
Poison newsgroup 4
Scoring filter (11) 3
Too many newsgroups 3
HTML post 3
Excessively crossposted test article 1
Too many newsgroups (low_xpost) 1
TOTAL: 12 36389

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
news.nask.pl 397
newsfeed.pionier.net.pl 390
news.icm.edu.pl 8
TOTAL: 3 795
TOTAL: 1 795

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nask 367573 25594 253670 57 0 0 6%23:59:59
2panorama 388809 1854 358764 402 3 428907 0%23:59:59
3poznan 15283 326 9281 67 0 1 2%23:59:57
4icm 445798 31 418197 5519 0 0 0%23:59:59
5okapi 0 0 0 0 0 1997 0%23:57:52
TOTAL: 51217463 278051039912 6045 3 430905 2%119:57:46
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nask8.6 GB18.6 MB8.6 GB104.4 KB/s351.7 KB23:59:59
2panorama97.5 MB21.7 MB119.2 MB1.4 KB/s54.1 KB23:59:59
3icm4.5 MB1.9 GB1.9 GB23.5 KB/s365.8 KB23:59:59
4poznan1.3 MB650.4 KB1.9 MB0.0 KB/s5.1 KB23:59:57
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:52
TOTAL: 58.7 GB2.0 GB10.7 GB25.9 KB/s330.1 KB119:57:46
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
panorama6.7 MB
TOTAL: 16.7 MB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1user56-061.satfilm.com.pl 51 172202.4 KB 47 0 005:19:45
2web.man.lodz.pl 288 2825.9 KB 14 0 000:00:32
3188.121.0.9 3 10.8 KB 1 0 100:06:22
4isvcs01.man.lodz.pl 11 00.0 KB 0 11 000:00:02
TOTAL: 4 353 201229.0 KB 62 11 105:26:57

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.satfilm.com.pl 51 172202.4 KB 47 0 005:19:45
2*.man.lodz.pl 683 2825.9 KB 14 11 000:00:48
3unresolved 3 10.8 KB 1 0 100:06:22
TOTAL: 3 737 201229.0 KB 62 11 105:26:57

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
user56-061.satfilm.com.pl 0.912 1.152 0.00005:19:45
188.121.0.9 0.064 0.056 0.00000:06:22
web.man.lodz.pl 1.652 4.292 0.00000:00:32
mon.man.lodz.pl 1.292 4.176 0.00000:00:10
david.man.lodz.pl 0.448 1.360 0.00000:00:03
isvcs01.man.lodz.pl 0.084 0.180 0.00000:00:02
TOTAL: 6 4.452 11.216 0.00005:26:57

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
david.man.lodz.pl 96
TOTAL: 2 384

Newsgroup request counts (by category):

CategoryCountPct
1lodz 114 56.7%
2lodman 51 25.4%
3pl 28 13.9%
4lodzman 8 4.0%
TOTAL: 4 201100.0%

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

NewsgroupCount
1lodz.test 103
2lodman.info 36
3pl.comp.pecet 28
4lodman.test 15
5lodz.ogloszenia 11
6lodzman.test 8
TOTAL: 6 201