Daily Usenet report

May 11 04:15:02 -- May 12 04:15:03

Unknown entries from news log file:

First 20 / 20 lines (100.0%)

May 11 07:31:09 newsfeed overchan[30349]: input line too long, skipping
May 11 08:55:07 newsfeed nnrpd[9296]: SERVER perl filter not defined
May 11 15:04:40 newsfeed overchan[30349]: input line too long, skipping
May 11 16:12:26 newsfeed nnrpd[25078]: SERVER perl filter not defined
May 11 16:13:42 newsfeed nnrpd[25113]: SERVER perl filter not defined
May 11 16:35:51 newsfeed nnrpd[26041]: SERVER perl filter not defined
May 11 16:51:11 newsfeed nnrpd[26502]: SERVER perl filter not defined
May 11 16:51:37 newsfeed nnrpd[26508]: SERVER perl filter not defined
May 11 16:58:11 newsfeed overchan[30349]: input line too long, skipping
May 11 17:42:53 newsfeed nnrpd[28322]: SERVER perl filter not defined
May 11 17:46:41 newsfeed nnrpd[28580]: SERVER perl filter not defined
May 11 17:50:22 newsfeed overchan[30349]: input line too long, skipping
May 11 17:53:09 newsfeed overchan[30349]: input line too long, skipping
May 11 17:53:30 newsfeed overchan[30349]: input line too long, skipping
May 11 17:54:16 newsfeed overchan[30349]: input line too long, skipping
May 11 17:56:48 newsfeed overchan[30349]: input line too long, skipping
May 11 18:57:30 newsfeed nnrpd[31101]: SERVER perl filter not defined
May 11 20:51:17 newsfeed nnrpd[2792]: SERVER perl filter not defined
May 12 00:23:36 newsfeed nnrpd[10526]: SERVER perl filter not defined
May 12 01:12:21 newsfeed nnrpd[12308]: SERVER perl filter not defined

Log entries by program:

Program nameLines%LinesSize%Size
inn3053421 97.6%347.9 MB 97.2%
innfeed 46266 1.5%5.5 MB 1.5%
innd 23913 0.8%3.8 MB 1.1%
nnrpd 4934 0.2%524.6 KB 0.1%
overchan 9 0.0%1.6 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 63128544 100.0%357.8 MB100.0%

History cache:

ReasonCount%Count
Do not exist 3043923 96.7%
Negative hits 93088 3.0%
Positive hits 10804 0.3%
Cache misses 143 0.0%
TOTAL: 4 3147958 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:02.710 0.0%287 0.000 9.443 199.000
article cleanup00:00:36.465 0.0%3051273 0.009 0.012 0.015
article logging00:02:02.045 0.1%3051769 0.026 0.040 0.111
article parse00:46:45.148 3.2%93899130 0.022 0.030 0.038
article write00:21:52.976 1.5%3045459 0.167 0.431 0.826
artlog/artcncl00:00:00.005 0.0%271 0.000 0.018 0.333
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:01:49.861 0.1%109969532 0.001 0.001 0.003
hisgrep/artcncl00:00:00.678 0.0%231 0.000 2.935 35.727
hishave/artcncl00:00:00.136 0.0%287 0.000 0.474 8.364
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:20:42.276 1.4%3152752 0.042 0.394 2.750
history sync00:00:00.015 0.0%291 0.000 0.052 0.500
history write01:45:32.268 7.3%3048545 0.213 2.077 9.276
hiswrite/artcncl00:00:00.047 0.0%56 0.000 0.839 0.979
idle14:20:28.547 59.8%73379213 0.518 0.704 1.244
nntp read00:54:21.129 3.8%91569748 0.027 0.036 0.044
overview write00:00:14.913 0.0%3045459 0.004 0.005 0.007
perl filter04:51:06.157 20.2%3048539 3.723 5.729 7.670
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:05:42.756 0.4%6116175 0.040 0.056 0.075
TOTAL: 24:00:01.69523:31:18.132 98.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.159 0.0%69344 0.000 0.002 0.009
article prepare00:00:00.002 0.0%3456 0.000 0.001 0.010
article read00:00:04.787 0.0%868 0.000 5.515 142.375
backlog stats00:00:00.140 0.0%174784 0.000 0.001 0.004
callbacks00:00:00.000 0.0%7 0.000 0.000 0.000
data read00:00:01.524 0.0%94735 0.002 0.016 0.031
data write00:00:03.612 0.0%69650 0.025 0.052 0.091
idle23:53:59.857100.0%174784 338.660 492.264 864.748
status file00:00:14.344 0.0%15734 0.000 0.912 10.250
TOTAL: 23:54:41.43823:54:24.425100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:01:34.031 87.2%3195 0.000 29.431 855.800
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.284 0.3%6957 0.000 0.041 0.632
readart00:00:00.004 0.0%36 0.000 0.111 1.000
TOTAL: 00:01:47.78700:01:34.319 87.5%----

Control commands to INND:

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 1277 30467313037897 6128 2706 99%404:14:27
2news.nask.pl 2 13641 8888 1639 3114 65%23:56:46
3news.icm.edu.pl 40 314 30 237 47 9%16:25:28
4localhost 13 12 12 0 0 100%00:00:00
TOTAL: 4 1332 30606983046827 8004 5867 99%444:36:41
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl764.2 GB21.4 MB22.3 MB764.3 GB 99%263.6 KB
2news.nask.pl40.5 MB700.7 KB18.0 MB59.1 MB 68%5.0 KB
3news.icm.edu.pl550.1 KB0.0 KB3.4 MB3.9 MB 13%51.9 KB
4localhost18.9 KB0.0 KB0.0 KB18.9 KB100%1.6 KB
TOTAL: 4764.3 GB22.0 MB43.7 MB764.4 GB 99%262.5 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 11 04:15:02 - 04:59:59 82456 2.7% 30.5620.5 GB 2.7%7956.50
May 11 05:00:00 - 05:59:59 90808 3.0% 25.2227.5 GB 3.6%8017.15
May 11 06:00:00 - 06:59:59 94410 3.1% 26.2326.8 GB 3.5%7798.38
May 11 07:00:00 - 07:59:59 113170 3.7% 31.4432.5 GB 4.2%9472.04
May 11 08:00:00 - 08:59:59 100685 3.3% 27.9731.9 GB 4.2%9298.50
May 11 09:00:00 - 09:59:59 104782 3.4% 29.1131.5 GB 4.1%9175.09
May 11 10:00:00 - 10:59:59 177842 5.8% 49.4033.3 GB 4.3%9687.24
May 11 11:00:00 - 11:59:59 196975 6.5% 54.7235.5 GB 4.6%10335.59
May 11 12:00:00 - 12:59:59 134344 4.4% 37.3235.8 GB 4.7%10419.42
May 11 13:00:00 - 13:59:59 125513 4.1% 34.8636.6 GB 4.8%10667.23
May 11 14:00:00 - 14:59:59 136210 4.5% 37.8437.2 GB 4.9%10833.06
May 11 15:00:00 - 15:59:59 117325 3.9% 32.5934.3 GB 4.5%9997.95
May 11 16:00:00 - 16:59:59 110049 3.6% 30.5731.9 GB 4.2%9295.83
May 11 17:00:00 - 17:59:59 116934 3.8% 32.4836.4 GB 4.8%10589.72
May 11 18:00:00 - 18:59:59 124689 4.1% 34.6436.6 GB 4.8%10666.30
May 11 19:00:00 - 19:59:59 133261 4.4% 37.0234.1 GB 4.5%9925.31
May 11 20:00:00 - 20:59:59 130336 4.3% 36.2032.9 GB 4.3%9591.70
May 11 21:00:00 - 21:59:59 121758 4.0% 33.8234.6 GB 4.5%10064.53
May 11 22:00:00 - 22:59:59 168535 5.5% 46.8230.0 GB 3.9%8736.73
May 11 23:00:00 - 23:59:59 165747 5.4% 46.0431.1 GB 4.1%9071.44
May 12 00:00:00 - 00:59:59 113862 3.7% 31.6323.5 GB 3.1%6831.46
May 12 01:00:00 - 01:59:59 166329 5.5% 46.2029.7 GB 3.9%8637.07
May 12 02:00:00 - 02:59:59 106642 3.5% 29.6228.5 GB 3.7%8311.51
May 12 03:00:00 - 03:59:59 89884 3.0% 24.9726.1 GB 3.4%7592.23
May 12 04:00:00 - 04:15:03 24309 0.8% 26.926.6 GB 0.9%7612.04
TOTAL: 24:00:01 3046855 100.0% 35.26765.2 GB 100.0%9286.88
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nask.pl 3235 21 0 199 10 0 0 0 3005
2newsfeed.pionier.net.pl 3011 140 0 2531 7 0 0 0 333
3news.icm.edu.pl 49 0 0 0 0 0 0 0 49
TOTAL: 3 6295 161 0 2730 17 0 0 0 3387

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 100
comp.mobile.android 19
it.tlc.cellulari.android 18
it.comp.os.win.windows10 9
comp.sys.raspberry-pi 7
it.comp.os.win.windows7 5
it.discussioni.energia 1
de.comp.office-pakete.ms-office.outlook 1
openwatcom.users.assembler 1
TOTAL: 9 161

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

GroupsCount
pl.sci.matematyka 14
pl.praca.oferowana 2
bit.listserv.catholic 1
TOTAL: 3 17

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 2674
Binary in non-binary group 129
Scoring filter (9) 24
Scoring filter (8) 23
EMP (phl) 8
Scoring filter (10) 6
Too many newsgroups 6
Scoring filter (11) 5
Scoring filter (18) 4
HTML post 2
Excessively crossposted test article 2
Scoring filter (13) 1
Scoring filter (12) 1
Scoring filter (16) 1
TOTAL: 14 2886

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Article posted in the future
newsfeed.pionier.net.pl 6
TOTAL: 1 6
Including strange strings
newsfeed.pionier.net.pl 300
news.nask.pl 193
news.icm.edu.pl 2
TOTAL: 3 495
TOTAL: 2 501

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1panorama 48519 519 21685 63 20 47240 1%23:59:59
2icm 24513 59 17348 12 0 0 0%23:59:59
3poznan 14381 55 8803 9 0 1 0%23:59:58
4nask 3692 7 2708 0 0 0 0%23:59:59
5okapi 0 0 0 0 0 1024 0%23:57:51
TOTAL: 5 91105 640 50544 84 20 48265 0%119:57:46
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm5.5 MB33.3 KB5.6 MB0.1 KB/s80.4 KB23:59:59
2panorama1.8 MB267.9 KB2.1 MB0.0 KB/s3.6 KB23:59:59
3poznan422.6 KB32.1 KB454.6 KB0.0 KB/s7.1 KB23:59:58
4nask24.0 KB0.0 KB24.0 KB0.0 KB/s3.4 KB23:59:59
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:51
TOTAL: 57.8 MB333.3 KB8.1 MB0.0 KB/s11.4 KB119:57:46
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
10890788407.static.corbina.ru 9 1219.0 KB 7 0 000:01:20
2web.man.lodz.pl 288 1213.5 KB 6 0 000:00:20
3isvcs01.man.lodz.pl 12 00.0 KB 0 12 000:00:01
TOTAL: 3 309 2432.5 KB 13 12 000:01:46

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 684 1213.5 KB 6 12 000:00:26
2*.static.corbina.ru 9 1219.0 KB 7 0 000:01:20
TOTAL: 2 693 2432.5 KB 13 12 000:01:46

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
0890788407.static.corbina.ru 0.036 0.216 0.00000:01:20
web.man.lodz.pl 1.528 6.804 0.00000:00:20
mon.man.lodz.pl 1.364 6.560 0.00000:00:03
david.man.lodz.pl 0.416 2.044 0.00000:00:01
isvcs01.man.lodz.pl 0.132 0.364 0.00000:00:01
TOTAL: 5 3.476 15.988 0.00000:01:46

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
1lodman 12 50.0%
2pl 12 50.0%
TOTAL: 2 24100.0%

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

NewsgroupCount
1lodman.info 12
2pl.comp.os.ms-windows.winnt 10
3pl.comp.pecet 2
TOTAL: 3 24