Daily Usenet report

Jun 19 04:15:02 -- Jun 20 04:15:02

Unknown entries from news log file:

First 17 / 17 lines (100.0%)

Jun 19 08:29:42 newsfeed overchan[30292]: input line too long, skipping
Jun 19 08:30:51 newsfeed overchan[30292]: input line too long, skipping
Jun 19 08:31:18 newsfeed overchan[30292]: input line too long, skipping
Jun 19 08:48:43 newsfeed nnrpd[8619]: SERVER perl filter not defined
Jun 19 09:24:51 newsfeed nnrpd[10117]: SERVER perl filter not defined
Jun 19 17:59:20 newsfeed overchan[30292]: input line too long, skipping
Jun 19 18:32:34 newsfeed overchan[30292]: input line too long, skipping
Jun 19 19:30:41 newsfeed overchan[30292]: input line too long, skipping
Jun 19 20:15:49 newsfeed nnrpd[1873]: SERVER perl filter not defined
Jun 19 21:03:45 newsfeed nnrpd[3712]: SERVER perl filter not defined
Jun 19 21:24:28 newsfeed nnrpd[4461]: SERVER perl filter not defined
Jun 19 23:33:21 newsfeed nnrpd[9245]: SERVER perl filter not defined
Jun 19 23:54:39 newsfeed nnrpd[9989]: SERVER perl filter not defined
Jun 20 01:14:51 newsfeed nnrpd[12995]: SERVER perl filter not defined
Jun 20 01:29:41 newsfeed overchan[30292]: input line too long, skipping
Jun 20 01:30:04 newsfeed overchan[30292]: input line too long, skipping
Jun 20 01:30:28 newsfeed overchan[30292]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn2832803 98.0%325.4 MB 97.7%
innfeed 32725 1.1%4.0 MB 1.2%
innd 18875 0.7%3.2 MB 1.0%
nnrpd 4974 0.2%531.2 KB 0.2%
overchan 10 0.0%1.8 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 62889388 100.0%333.1 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2726382 61.4%
Negative hits 1462738 32.9%
Positive hits 250348 5.6%
Cache misses 970 0.0%
TOTAL: 4 4440438 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:03.137 0.0%229 0.000 13.699 279.000
article cleanup00:00:36.131 0.0%2830843 0.009 0.013 0.018
article logging00:02:16.428 0.2%2831394 0.031 0.048 0.167
article parse00:49:20.092 3.4%93665942 0.015 0.032 0.047
article write00:20:26.304 1.4%2728430 0.215 0.449 0.950
artlog/artcncl00:00:00.003 0.0%227 0.000 0.013 0.500
artlog/artparse00:00:00.000 0.0%1 0.000 0.000 0.000
data move00:02:06.689 0.1%110508127 0.001 0.001 0.003
hisgrep/artcncl00:00:00.714 0.0%216 0.000 3.306 191.000
hishave/artcncl00:00:00.102 0.0%229 0.000 0.445 4.385
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:35:54.734 2.5%4450939 0.058 0.484 3.598
history sync00:00:00.016 0.0%291 0.000 0.055 0.500
history write01:48:37.596 7.5%2730665 0.292 2.387 10.399
hiswrite/artcncl00:00:00.003 0.0%13 0.000 0.231 1.000
idle13:53:02.634 57.8%72240295 0.410 0.692 1.963
nntp read00:57:19.122 4.0%92111600 0.025 0.037 0.054
overview write00:00:14.162 0.0%2728430 0.003 0.005 0.008
perl filter04:54:15.113 20.4%2730652 4.150 6.466 9.188
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:06:16.988 0.4%5911439 0.047 0.064 0.087
TOTAL: 24:00:04.63423:30:29.968 97.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:01.817 0.0%736104 0.000 0.002 0.007
article prepare00:00:00.035 0.0%157696 0.000 0.000 0.007
article read00:05:29.677 0.4%36701 0.040 8.983 35.187
backlog stats00:00:01.415 0.0%2286389 0.000 0.001 0.001
callbacks00:00:00.000 0.0%4 0.000 0.000 0.000
data read00:00:23.983 0.0%1436963 0.009 0.017 0.026
data write00:02:01.029 0.1%1055782 0.041 0.115 0.638
idle23:41:33.563 99.4%2286389 12.826 37.305 203.459
status file00:00:10.432 0.0%12025 0.000 0.868 8.000
TOTAL: 23:50:34.75123:49:41.951 99.9%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:02:17.256 86.2%3250 0.000 42.233 862.100
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.277 0.2%7216 0.000 0.038 0.500
readart00:00:01.714 1.1%77 0.000 22.260 51.273
TOTAL: 00:02:39.26600:02:19.247 87.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 487 27036822533705 79392 90585 93%317:18:34
2news.nask.pl 6 280551 196372 72466 11713 69%24:31:17
3news.icm.edu.pl 35 437 103 306 28 23%18:54:37
4localhost 9 8 8 0 0 100%00:00:00
TOTAL: 4 537 29846782730188 152164 102326 91%360:44:28
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl673.3 GB32.7 GB32.7 GB738.8 GB 91%295.2 KB
2news.nask.pl70.1 GB3.3 GB3.3 GB76.6 GB 91%386.1 KB
3news.icm.edu.pl2.0 MB181.2 KB1.1 MB3.3 MB 61%26.0 KB
4localhost10.1 KB0.0 KB0.0 KB10.1 KB100%1.3 KB
TOTAL: 4743.4 GB36.0 GB36.0 GB815.4 GB 91%301.9 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 19 04:15:02 - 04:59:59 58996 2.2% 21.8716.7 GB 2.2%6489.15
Jun 19 05:00:00 - 05:59:59 112566 4.1% 31.2731.5 GB 4.2%9174.60
Jun 19 06:00:00 - 06:59:59 77850 2.9% 21.6222.5 GB 3.0%6545.28
Jun 19 07:00:00 - 07:59:59 126090 4.6% 35.0235.3 GB 4.8%10295.40
Jun 19 08:00:00 - 08:59:59 122818 4.5% 34.1236.0 GB 4.8%10474.05
Jun 19 09:00:00 - 09:59:59 92584 3.4% 25.7226.3 GB 3.5%7666.60
Jun 19 10:00:00 - 10:59:59 177358 6.5% 49.2737.3 GB 5.0%10874.53
Jun 19 11:00:00 - 11:59:59 135324 5.0% 37.5936.6 GB 4.9%10666.04
Jun 19 12:00:00 - 12:59:59 99185 3.6% 27.5529.7 GB 4.0%8646.72
Jun 19 13:00:00 - 13:59:59 128135 4.7% 35.5937.1 GB 5.0%10802.32
Jun 19 14:00:00 - 14:59:59 134681 4.9% 37.4137.2 GB 5.0%10829.07
Jun 19 15:00:00 - 15:59:59 132706 4.9% 36.8635.8 GB 4.8%10420.51
Jun 19 16:00:00 - 16:59:59 111434 4.1% 30.9531.2 GB 4.2%9095.40
Jun 19 17:00:00 - 17:59:59 125867 4.6% 34.9634.3 GB 4.6%9993.77
Jun 19 18:00:00 - 18:59:59 129335 4.7% 35.9335.2 GB 4.7%10255.21
Jun 19 19:00:00 - 19:59:59 133795 4.9% 37.1737.2 GB 5.0%10834.80
Jun 19 20:00:00 - 20:59:59 161292 5.9% 44.8034.1 GB 4.6%9942.53
Jun 19 21:00:00 - 21:59:59 134936 4.9% 37.4835.2 GB 4.7%10262.40
Jun 19 22:00:00 - 22:59:59 118264 4.3% 32.8534.8 GB 4.7%10134.10
Jun 19 23:00:00 - 23:59:59 106763 3.9% 29.6632.3 GB 4.3%9399.33
Jun 20 00:00:00 - 00:59:59 78220 2.9% 21.7319.6 GB 2.6%5710.26
Jun 20 01:00:00 - 01:59:59 76417 2.8% 21.2321.7 GB 2.9%6330.72
Jun 20 02:00:00 - 02:59:59 77882 2.9% 21.6321.8 GB 2.9%6352.46
Jun 20 03:00:00 - 03:59:59 60433 2.2% 16.7918.7 GB 2.5%5457.42
Jun 20 04:00:00 - 04:15:02 16777 0.6% 18.604.8 GB 0.6%5600.62
TOTAL: 24:00:00 2729708 100.0% 31.59743.1 GB 100.0%9017.92
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 90948 188 0 90238 3 0 0 0 519
2news.nask.pl 11887 18 0 9815 13 0 0 0 2041
3news.icm.edu.pl 34 0 0 5 0 0 0 0 29
TOTAL: 3102869 206 0100058 16 0 0 0 2589

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 135
it.tlc.cellulari.android 34
de.comm.software.mailreader 16
comp.sys.raspberry-pi 5
it.comp.os.win.windows10 5
it.comp.os.win.windows8 5
hr.ponuda.hardver.komponente 2
comp.mobile.ipad 2
nl.comp.os.linux.techniek 1
comp.mobile.android 1
TOTAL: 10 206

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

GroupsCount
pl.sci.matematyka 8
pl.praca.oferowana 6
bit.listserv.catholic 1
christnet.prayer 1
TOTAL: 4 16

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1800
Binary in non-binary group 173
Scoring filter (8) 19
Scoring filter (9) 12
Scoring filter (16) 6
Scoring filter (10) 6
Too many newsgroups 3
HTML post 3
Scoring filter (14) 1
TOTAL: 9 2023

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Article posted in the future
newsfeed.pionier.net.pl 12
TOTAL: 1 12
Including strange strings
newsfeed.pionier.net.pl 370
news.nask.pl 174
news.icm.edu.pl 9
TOTAL: 3 553
TOTAL: 2 565

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1panorama 321758 17228 280875 1647 18 441725 5%23:59:57
2nask 302403 14806 234535 20 0 0 4%23:59:57
3poznan 11851 316 7559 15 0 0 2%23:59:57
4icm 524116 61 448190 5120 0 9 0%23:59:57
5okapi 0 0 0 0 0 1063 0%23:57:50
TOTAL: 51160128 32411 971159 6802 18 442797 2%119:57:38
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1panorama5.6 GB433.4 MB6.0 GB72.6 KB/s332.4 KB23:59:57
2nask5.5 GB7.2 MB5.5 GB66.7 KB/s388.9 KB23:59:57
3icm8.0 MB1.9 GB1.9 GB22.7 KB/s378.7 KB23:59:57
4poznan1.3 MB70.8 KB1.4 MB0.0 KB/s4.2 KB23:59:57
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:50
TOTAL: 511.1 GB2.3 GB13.4 GB32.4 KB/s357.1 KB119:57:38
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
panorama15.3 MB
okapi488.3 KB
TOTAL: 215.7 MB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1ool-4578089a.dyn.optonline.net 1 3320.9 KB 1 0 000:00:09
2web.man.lodz.pl 288 2820.8 KB 13 0 000:00:23
30890788407.static.corbina.ru 13 810.1 KB 5 0 000:01:55
4isvcs01.man.lodz.pl 8 00.0 KB 0 8 000:00:05
TOTAL: 4 310 6951.8 KB 19 8 000:02:38

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.dyn.optonline.net 1 3320.9 KB 1 0 000:00:09
2*.man.lodz.pl 680 2820.8 KB 13 8 000:00:33
3*.static.corbina.ru 13 810.1 KB 5 0 000:01:55
TOTAL: 3 694 6951.8 KB 19 8 000:02:38

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
0890788407.static.corbina.ru 0.096 0.256 0.00000:01:55
web.man.lodz.pl 1.672 6.464 0.00000:00:23
ool-4578089a.dyn.optonline.net 0.016 0.020 0.00000:00:09
isvcs01.man.lodz.pl 0.040 0.160 0.00000:00:05
mon.man.lodz.pl 1.284 5.392 0.00000:00:03
david.man.lodz.pl 0.420 1.832 0.00000:00:01
TOTAL: 6 3.528 14.124 0.00000:02:38

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 41 59.4%
2pl 28 40.6%
TOTAL: 2 69100.0%

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

NewsgroupCount
1lodman.info 41
2pl.comp.pecet 26
3pl.comp.os.ms-windows.winnt 2
TOTAL: 3 69