Daily Usenet report

Apr 4 04:15:02 -- Apr 5 04:15:03

Unknown entries from news log file:

First 25 / 25 lines (100.0%)

Apr 4 07:53:31 newsfeed overchan[25546]: input line too long, skipping
Apr 4 09:06:56 newsfeed overchan[25546]: input line too long, skipping
Apr 4 09:11:19 newsfeed nnrpd[4627]: SERVER perl filter not defined
Apr 4 10:04:37 newsfeed nnrpd[6727]: SERVER perl filter not defined
Apr 4 11:02:47 newsfeed nnrpd[8893]: SERVER perl filter not defined
Apr 4 13:13:26 newsfeed nnrpd[13751]: SERVER perl filter not defined
Apr 4 14:46:39 newsfeed overchan[25546]: input line too long, skipping
Apr 4 15:08:31 newsfeed overchan[25546]: input line too long, skipping
Apr 4 15:19:15 newsfeed nnrpd[18461]: SERVER perl filter not defined
Apr 4 15:34:06 newsfeed overchan[25546]: input line too long, skipping
Apr 4 15:40:09 newsfeed nnrpd[19219]: SERVER perl filter not defined
Apr 4 15:41:54 newsfeed nnrpd[19247]: SERVER perl filter not defined
Apr 4 15:44:13 newsfeed nnrpd[19274]: SERVER perl filter not defined
Apr 4 15:47:07 newsfeed overchan[25546]: input line too long, skipping
Apr 4 16:05:49 newsfeed overchan[25546]: input line too long, skipping
Apr 4 16:08:24 newsfeed overchan[25546]: input line too long, skipping
Apr 4 16:25:43 newsfeed nnrpd[20855]: SERVER perl filter not defined
Apr 4 17:13:50 newsfeed overchan[25546]: input line too long, skipping
Apr 4 17:21:36 newsfeed overchan[25546]: input line too long, skipping
Apr 4 17:37:41 newsfeed nnrpd[23427]: SERVER perl filter not defined
Apr 4 19:45:31 newsfeed overchan[25546]: input line too long, skipping
Apr 4 19:51:07 newsfeed overchan[25546]: input line too long, skipping
Apr 4 20:06:31 newsfeed overchan[25546]: input line too long, skipping
Apr 5 00:13:40 newsfeed nnrpd[5585]: SERVER perl filter not defined
Apr 5 00:33:04 newsfeed overchan[25546]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn3310650 97.8%361.0 MB 97.3%
innfeed 45784 1.4%5.4 MB 1.5%
innd 24733 0.7%4.0 MB 1.1%
nnrpd 4846 0.1%513.6 KB 0.1%
overchan 15 0.0%2.6 KB 0.0%
controlchan 1 0.0%0.2 KB 0.0%
TOTAL: 63386029 100.0%370.9 MB100.0%

History cache:

ReasonCount%Count
Do not exist 3299540 96.0%
Negative hits 124085 3.6%
Positive hits 14301 0.4%
Cache misses 122 0.0%
TOTAL: 4 3438048 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.204 0.0%254 0.000 4.740 61.000
article cleanup00:00:44.670 0.1%3308096 0.010 0.014 0.018
article logging00:02:28.915 0.2%3308776 0.031 0.045 0.111
article parse00:54:20.180 3.8%99215612 0.014 0.033 0.044
article write00:22:11.996 1.5%3302217 0.221 0.403 0.752
artlog/artcncl00:00:00.011 0.0%253 0.000 0.043 1.000
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:04:40.467 0.3%115023068 0.001 0.002 0.004
hisgrep/artcncl00:00:00.292 0.0%245 0.000 1.192 8.300
hishave/artcncl00:00:00.102 0.0%254 0.000 0.402 5.400
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:45:14.572 3.1%3443823 0.054 0.788 5.535
history sync00:00:00.013 0.0%290 0.000 0.045 0.500
history write01:47:53.625 7.5%3305209 0.182 1.959 6.710
hiswrite/artcncl00:00:00.029 0.0%9 0.000 3.222 8.500
idle12:11:10.150 50.8%73709038 0.375 0.595 1.716
nntp read01:20:55.390 5.6%96584850 0.031 0.050 0.066
overview write00:00:19.465 0.0%3302217 0.004 0.006 0.008
perl filter05:44:02.148 23.9%3305209 3.808 6.245 7.613
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:06:46.955 0.5%6626605 0.046 0.061 0.076
TOTAL: 24:00:05.65423:20:50.184 97.3%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.158 0.0%59274 0.000 0.003 0.016
article prepare00:00:00.006 0.0%13078 0.000 0.000 0.021
article read00:00:43.281 0.1%3293 0.286 13.143 729.500
backlog stats00:00:00.157 0.0%179190 0.000 0.001 0.004
callbacks00:00:00.000 0.0%27 0.000 0.000 0.000
data read00:00:02.044 0.0%94912 0.009 0.022 0.075
data write00:00:06.909 0.0%74534 0.030 0.093 0.344
idle23:53:24.239 99.9%179190 237.674 479.961 971.155
status file00:00:19.213 0.0%16245 0.691 1.183 9.516
TOTAL: 23:54:56.47623:54:36.007100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:18.978 55.8%3122 0.000 6.079 21.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.308 0.9%6865 0.000 0.045 0.667
readart00:00:00.004 0.0%56 0.000 0.071 1.500
TOTAL: 00:00:33.98700:00:19.290 56.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 1215 33040933293065 7864 3164 99%451:46:17
2news.nask.pl 2 14889 10569 1629 2691 70%23:47:11
3news.icm.edu.pl 41 2008 176 1818 14 8%18:57:03
4localhost 12 11 11 0 0 100%00:00:00
TOTAL: 4 1270 33210013303821 11311 5869 99%494:30:31
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl704.1 GB206.4 MB237.4 MB704.6 GB 99%224.1 KB
2news.nask.pl198.3 MB1.2 MB31.3 MB230.8 MB 85%17.8 KB
3news.icm.edu.pl21.5 MB970.7 KB1.8 MB24.2 MB 88%130.2 KB
4localhost18.1 KB0.0 KB0.0 KB18.1 KB100%1.6 KB
TOTAL: 4704.3 GB208.5 MB270.4 MB704.8 GB 99%223.3 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Apr 4 04:15:02 - 04:59:59 85378 2.6% 31.6418.7 GB 2.7%7264.70
Apr 4 05:00:00 - 05:59:59 107828 3.3% 29.9528.0 GB 4.0%8141.90
Apr 4 06:00:00 - 06:59:59 119672 3.6% 33.2432.4 GB 4.6%9426.53
Apr 4 07:00:00 - 07:59:59 118630 3.6% 32.9532.0 GB 4.5%9313.39
Apr 4 08:00:00 - 08:59:59 88469 2.7% 24.5722.5 GB 3.2%6565.07
Apr 4 09:00:00 - 09:59:59 113514 3.4% 31.5329.1 GB 4.1%8485.78
Apr 4 10:00:00 - 10:59:59 111517 3.4% 30.9826.2 GB 3.7%7629.24
Apr 4 11:00:00 - 11:59:59 117756 3.6% 32.7127.9 GB 4.0%8123.30
Apr 4 12:00:00 - 12:59:59 141247 4.3% 39.2434.5 GB 4.9%10036.23
Apr 4 13:00:00 - 13:59:59 168104 5.1% 46.7035.5 GB 5.0%10337.99
Apr 4 14:00:00 - 14:59:59 192489 5.8% 53.4735.2 GB 5.0%10244.65
Apr 4 15:00:00 - 15:59:59 155260 4.7% 43.1334.7 GB 4.9%10113.41
Apr 4 16:00:00 - 16:59:59 180100 5.5% 50.0334.4 GB 4.9%10033.84
Apr 4 17:00:00 - 17:59:59 108894 3.3% 30.2525.4 GB 3.6%7401.52
Apr 4 18:00:00 - 18:59:59 145429 4.4% 40.4030.6 GB 4.3%8909.78
Apr 4 19:00:00 - 19:59:59 146360 4.4% 40.6631.8 GB 4.5%9258.13
Apr 4 20:00:00 - 20:59:59 199255 6.0% 55.3531.8 GB 4.5%9258.43
Apr 4 21:00:00 - 21:59:59 220472 6.7% 61.2430.6 GB 4.3%8914.79
Apr 4 22:00:00 - 22:59:59 121343 3.7% 33.7126.1 GB 3.7%7602.90
Apr 4 23:00:00 - 23:59:59 93055 2.8% 25.8519.4 GB 2.7%5638.54
Apr 5 00:00:00 - 00:59:59 96383 2.9% 26.7717.5 GB 2.5%5087.21
Apr 5 01:00:00 - 01:59:59 151762 4.6% 42.1628.6 GB 4.1%8338.73
Apr 5 02:00:00 - 02:59:59 148461 4.5% 41.2433.4 GB 4.7%9734.34
Apr 5 03:00:00 - 03:59:59 137677 4.2% 38.2430.3 GB 4.3%8825.67
Apr 5 04:00:00 - 04:15:03 34794 1.1% 38.537.9 GB 1.1%9227.49
TOTAL: 24:00:01 3303849 100.0% 38.24704.4 GB 100.0%8549.09
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 3614 193 0 2760 4 0 0 0 657
2news.nask.pl 2919 28 0 109 11 0 0 0 2771
3news.icm.edu.pl 15 0 0 7 0 0 0 0 8
TOTAL: 3 6548 221 0 2876 15 0 0 0 3436

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 116
it.comp.os.win.windows10 33
it.tlc.cellulari.android 28
comp.mobile.android 18
comp.sys.raspberry-pi 11
de.rec.sf.startrek 8
de.comm.software.mailreader 2
comp.mobile.ipad 1
uk.rec.cycling.moderated 1
it.comp.os.win.windows7 1
de.comp.office-pakete.ms-office.word 1
it.comp.os.linux.ubuntu 1
TOTAL: 12 221

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

GroupsCount
pl.sci.matematyka 11
pl.praca.oferowana 3
bit.listserv.catholic 1
TOTAL: 3 15

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 2280
Binary in non-binary group 367
EMP (phl) 58
Scoring filter (9) 15
Scoring filter (8) 13
Scoring filter (10) 7
Too many newsgroups 6
HTML post 5
Scoring filter (16) 4
Scoring filter (18) 1
TOTAL: 10 2756

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 460
news.nask.pl 219
news.icm.edu.pl 1
TOTAL: 3 680
TOTAL: 1 680

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1panorama 41963 778 13889 176 81 44524 1%23:59:57
2icm 28109 47 20148 1569 0 1 0%23:59:50
3poznan 15921 29 9566 13 0 1 0%23:59:54
4nask 3820 3 2955 0 0 0 0%23:59:57
5okapi 0 0 0 0 0 998 0%23:57:50
TOTAL: 5 89813 857 46558 1758 81 45524 0%119:57:28
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm5.3 MB587.9 MB593.2 MB7.0 KB/s375.9 KB23:59:50
2panorama2.2 MB605.7 KB2.8 MB0.0 KB/s3.0 KB23:59:57
3poznan146.1 KB44.8 KB190.9 KB0.0 KB/s4.5 KB23:59:54
4nask8.5 KB0.0 KB8.5 KB0.0 KB/s2.8 KB23:59:57
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:50
TOTAL: 57.7 MB588.5 MB596.2 MB1.4 KB/s233.5 KB119:57:28
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 5663.3 KB 25 0 000:00:25
2isvcs01.man.lodz.pl 11 00.0 KB 0 11 000:00:01
TOTAL: 2 299 5663.3 KB 25 11 000:00:32

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 683 5663.3 KB 25 11 000:00:32
2*.us-west-1.compute.amazonaws.com 1 00.0 KB 0 0 000:00:00
TOTAL: 2 684 5663.3 KB 25 11 000:00:32

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
web.man.lodz.pl 1.908 6.388 0.00000:00:25
mon.man.lodz.pl 1.696 5.636 0.00000:00:04
isvcs01.man.lodz.pl 0.104 0.248 0.00000:00:01
david.man.lodz.pl 0.488 1.640 0.00000:00:01
ec2-13-57-254-57.us-west-1.compute.amazonaws.com 0.000 0.008 0.00000:00:00
TOTAL: 5 4.196 13.920 0.00000:00:32

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
david.man.lodz.pl 96
ec2-13-57-254-57.us-west-1.compute.amazonaws.com 1
TOTAL: 3 385

Newsgroup request counts (by category):

CategoryCountPct
1pl 56100.0%
TOTAL: 1 56100.0%

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

NewsgroupCount
1pl.comp.os.ms-windows.winnt 30
2pl.comp.pecet 26
TOTAL: 2 56