Daily Usenet report

May 31 04:15:03 -- Jun 1 04:15:03

Unknown entries from news log file:

First 16 / 16 lines (100.0%)

May 31 08:31:37 newsfeed nnrpd[21057]: SERVER perl filter not defined
May 31 08:34:54 newsfeed nnrpd[21110]: SERVER perl filter not defined
May 31 08:37:50 newsfeed nnrpd[21304]: SERVER perl filter not defined
May 31 10:42:39 newsfeed overchan[11069]: input line too long, skipping
May 31 10:43:01 newsfeed overchan[11069]: input line too long, skipping
May 31 14:31:28 newsfeed nnrpd[1892]: SERVER perl filter not defined
May 31 14:38:05 newsfeed nnrpd[2153]: SERVER perl filter not defined
May 31 15:19:37 newsfeed overchan[11069]: input line too long, skipping
May 31 15:44:55 newsfeed overchan[11069]: input line too long, skipping
May 31 16:00:22 newsfeed overchan[11069]: input line too long, skipping
May 31 16:50:06 newsfeed overchan[11069]: input line too long, skipping
May 31 16:50:21 newsfeed overchan[11069]: input line too long, skipping
May 31 19:40:50 newsfeed nnrpd[13132]: SERVER perl filter not defined
May 31 20:06:19 newsfeed nnrpd[14114]: SERVER perl filter not defined
Jun 1 00:08:17 newsfeed overchan[11069]: input line too long, skipping
Jun 1 00:08:43 newsfeed overchan[11069]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn2317165 96.9%265.6 MB 96.5%
innfeed 48115 2.0%5.8 MB 2.1%
innd 19839 0.8%3.3 MB 1.2%
nnrpd 4980 0.2%532.2 KB 0.2%
overchan 10 0.0%1.7 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 62390110 100.0%275.1 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2228619 62.3%
Negative hits 1177254 32.9%
Positive hits 173279 4.8%
Cache misses 434 0.0%
TOTAL: 4 3579586 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:02.056 0.0%206 0.000 9.981 57.000
article cleanup00:00:28.044 0.0%2317475 0.008 0.012 0.017
article logging00:01:40.440 0.1%2318115 0.033 0.043 0.213
article parse00:36:57.909 2.6%79999386 0.017 0.028 0.039
article write00:14:50.567 1.0%2236684 0.176 0.398 0.971
artlog/artcncl00:00:00.009 0.0%205 0.000 0.044 1.000
artlog/artparse00:00:00.000 0.0%2 0.000 0.000 0.000
data move00:03:22.936 0.2%93829092 0.001 0.002 0.003
hisgrep/artcncl00:00:00.773 0.0%201 0.000 3.846 20.000
hishave/artcncl00:00:00.248 0.0%206 0.000 1.204 11.625
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup01:04:37.895 4.5%3597688 0.099 1.078 8.544
history sync00:00:00.019 0.0%290 0.000 0.066 0.500
history write02:16:59.903 9.5%2239959 0.463 3.670 14.941
hiswrite/artcncl00:00:00.036 0.0%5 0.500 7.200 17.000
idle14:48:09.933 61.7%64563408 0.363 0.825 3.573
nntp read00:51:42.334 3.6%78870250 0.027 0.039 0.054
overview write00:00:11.177 0.0%2236684 0.003 0.005 0.010
perl filter03:36:46.438 15.1%2239957 2.025 5.807 8.649
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:04:56.490 0.3%4810619 0.047 0.062 0.089
TOTAL: 24:00:07.05823:40:47.207 98.7%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:01.424 0.0%663543 0.000 0.002 0.004
article prepare00:00:00.011 0.0%63806 0.000 0.000 0.006
article read00:00:26.342 0.0%13909 0.000 1.894 44.341
backlog stats00:00:01.086 0.0%1815276 0.000 0.001 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:17.277 0.0%1150177 0.010 0.015 0.022
data write00:00:54.534 0.1%833265 0.030 0.065 0.113
idle23:48:33.289 99.8%1815276 27.318 47.218 281.476
status file00:00:15.291 0.0%17045 0.589 0.897 1.944
TOTAL: 23:51:11.03223:50:29.254100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle02:16:47.520 99.7%79395 0.000 103.376 10050.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:04.127 0.1%159424 0.000 0.026 0.500
readart00:00:00.002 0.0%46 0.000 0.043 0.500
TOTAL: 02:17:11.66302:16:51.649 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 1098 22351462125477 38262 71407 95%383:43:31
2news.nask.pl 2 178327 110116 58836 9375 61%24:00:22
3news.icm.edu.pl 48 215 32 179 4 14%16:45:27
4localhost 8 7 7 0 0 100%00:00:00
TOTAL: 4 1156 24136952235632 97277 80786 92%424:29:20
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl572.6 GB24.6 GB24.6 GB621.7 GB 92%296.8 KB
2news.nask.pl37.5 GB2.1 GB2.1 GB41.6 GB 90%365.4 KB
3news.icm.edu.pl582.3 KB0.0 KB74.5 KB656.8 KB 88%18.2 KB
4localhost10.2 KB0.0 KB0.0 KB10.2 KB100%1.5 KB
TOTAL: 4610.1 GB26.6 GB26.7 GB663.4 GB 91%300.3 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 31 04:15:03 - 04:59:59 70792 3.2% 26.2521.0 GB 3.4%8152.67
May 31 05:00:00 - 05:59:59 79228 3.5% 22.0123.3 GB 3.8%6776.97
May 31 06:00:00 - 06:59:59 23010 1.0% 6.396.7 GB 1.1%1961.23
May 31 07:00:00 - 07:59:59 48966 2.2% 13.606.4 GB 1.0%1867.96
May 31 08:00:00 - 08:59:59 17543 0.8% 4.876.7 GB 1.1%1940.71
May 31 09:00:00 - 09:59:59 46069 2.1% 12.8015.1 GB 2.5%4403.88
May 31 10:00:00 - 10:59:59 89864 4.0% 24.9626.8 GB 4.4%7819.22
May 31 11:00:00 - 11:59:59 102222 4.6% 28.3929.1 GB 4.8%8485.25
May 31 12:00:00 - 12:59:59 100027 4.5% 27.7931.5 GB 5.2%9183.75
May 31 13:00:00 - 13:59:59 122933 5.5% 34.1533.3 GB 5.5%9710.55
May 31 14:00:00 - 14:59:59 100800 4.5% 28.0031.5 GB 5.2%9183.35
May 31 15:00:00 - 15:59:59 74874 3.3% 20.8021.3 GB 3.5%6194.35
May 31 16:00:00 - 16:59:59 91047 4.1% 25.2926.3 GB 4.3%7646.85
May 31 17:00:00 - 17:59:59 100448 4.5% 27.9026.4 GB 4.3%7688.65
May 31 18:00:00 - 18:59:59 90775 4.1% 25.2223.8 GB 3.9%6941.74
May 31 19:00:00 - 19:59:59 83249 3.7% 23.1225.2 GB 4.1%7353.89
May 31 20:00:00 - 20:59:59 110512 4.9% 30.7033.3 GB 5.4%9687.46
May 31 21:00:00 - 21:59:59 137290 6.1% 38.1431.6 GB 5.2%9203.89
May 31 22:00:00 - 22:59:59 173413 7.8% 48.1737.2 GB 6.1%10842.49
May 31 23:00:00 - 23:59:59 152822 6.8% 42.4536.0 GB 5.9%10487.39
Jun 1 00:00:00 - 00:59:59 104662 4.7% 29.0723.9 GB 3.9%6974.04
Jun 1 01:00:00 - 01:59:59 100988 4.5% 28.0529.5 GB 4.8%8591.25
Jun 1 02:00:00 - 02:59:59 105128 4.7% 29.2032.2 GB 5.3%9386.97
Jun 1 03:00:00 - 03:59:59 87019 3.9% 24.1726.3 GB 4.3%7663.61
Jun 1 04:00:00 - 04:15:03 21850 1.0% 24.206.3 GB 1.0%7302.38
TOTAL: 24:00:00 2235531 100.0% 25.87610.9 GB 100.0%7413.95
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 71948 95 0 71235 1 0 0 0 617
2news.nask.pl 9474 5 0 6275 1 0 0 0 3193
3news.icm.edu.pl 7 0 0 0 0 0 0 0 7
TOTAL: 3 81429 100 0 77510 2 0 0 0 3817

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 51
it.tlc.cellulari.android 18
it.comp.os.win.windows10 14
comp.mobile.android 11
comp.sys.raspberry-pi 2
de.rec.sf.startrek 1
it.comp.os.win.windows7 1
it.comp.os.win.windows8 1
de.comp.lang.vbclassic 1
TOTAL: 9 100

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

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

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 2954
Binary in non-binary group 155
Scoring filter (8) 25
Scoring filter (9) 10
Scoring filter (10) 10
Too many newsgroups 5
Scoring filter (18) 4
Scoring filter (11) 4
HTML post 3
Scoring filter (12) 1
Scoring filter (14) 1
Scoring filter (13) 1
Scoring filter (20) 1
TOTAL: 13 3174

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 539
news.nask.pl 101
news.icm.edu.pl 1
TOTAL: 3 641
TOTAL: 1 641

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nask 261209 9604 210809 12 0 6 3%23:59:58
2panorama 346405 1319 316067 317 3 350893 0%23:59:58
3icm 407639 618 332513 3935 0 18 0%23:59:58
4poznan 9466 193 6115 29 0 1 2%23:59:55
5okapi 0 0 0 0 0 1024 0%23:57:51
TOTAL: 51024719 11734 865504 4293 3 351942 1%119:57:40
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nask3.5 GB4.2 MB3.5 GB42.0 KB/s377.8 KB23:59:58
2icm137.4 MB1.4 GB1.6 GB18.8 KB/s357.0 KB23:59:58
3panorama44.2 MB26.7 MB70.8 MB0.8 KB/s44.3 KB23:59:58
4poznan933.3 KB74.6 KB1007.9 KB0.0 KB/s4.5 KB23:59:55
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:51
TOTAL: 53.6 GB1.4 GB5.1 GB12.3 KB/s332.7 KB119:57:40
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
panorama1.4 MB
okapi488.3 KB
TOTAL: 21.9 MB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 3228.9 KB 15 0 000:00:18
20890788407.static.corbina.ru 14 710.3 KB 3 0 000:02:04
3isvcs01.man.lodz.pl 7 00.0 KB 0 7 000:00:01
TOTAL: 3 309 3939.2 KB 18 7 002:17:10

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 679 3228.9 KB 15 7 000:00:24
2*.static.corbina.ru 14 710.3 KB 3 0 000:02:04
3unresolved 3 00.0 KB 0 0 002:14:41
TOTAL: 3 696 3939.2 KB 18 7 002:17:10

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
89.38.40.118 4.100 6.700 0.00002:14:31
0890788407.static.corbina.ru 0.084 0.164 0.00000:02:04
web.man.lodz.pl 1.444 3.136 0.00000:00:18
107.170.230.97 0.004 0.004 0.00000:00:09
mon.man.lodz.pl 1.260 2.568 0.00000:00:03
isvcs01.man.lodz.pl 0.052 0.072 0.00000:00:01
david.man.lodz.pl 0.368 0.836 0.00000:00:01
TOTAL: 7 7.312 13.480 0.00002:17:10

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
david.man.lodz.pl 96
89.38.40.118 2
107.170.230.97 1
TOTAL: 4 387

NNRP client timeouts [Top 20]:

SystemConnPeer
89.38.40.118 1 1
TOTAL: 1 1 1

Newsgroup request counts (by category):

CategoryCountPct
1pl 32 82.1%
2lodman 7 17.9%
TOTAL: 2 39100.0%

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

NewsgroupCount
1pl.comp.os.ms-windows.winnt 16
2pl.comp.pecet 16
3lodman.info 7
TOTAL: 3 39