Daily Usenet report

Jul 20 04:15:02 -- Jul 21 04:15:02

Unknown entries from news log file:

First 22 / 22 lines (100.0%)

Jul 20 08:13:55 newsfeed nnrpd[6507]: SERVER perl filter not defined
Jul 20 08:14:45 newsfeed nnrpd[6517]: SERVER perl filter not defined
Jul 20 08:24:32 newsfeed nnrpd[6862]: SERVER perl filter not defined
Jul 20 08:25:37 newsfeed nnrpd[6930]: SERVER perl filter not defined
Jul 20 08:26:31 newsfeed nnrpd[6941]: SERVER perl filter not defined
Jul 20 08:29:14 newsfeed nnrpd[7106]: SERVER perl filter not defined
Jul 20 08:38:38 newsfeed nnrpd[7298]: SERVER perl filter not defined
Jul 20 09:22:42 newsfeed nnrpd[8917]: SERVER perl filter not defined
Jul 20 10:19:14 newsfeed nnrpd[10911]: SERVER perl filter not defined
Jul 20 10:46:35 newsfeed nnrpd[11769]: SERVER perl filter not defined
Jul 20 10:57:39 newsfeed nnrpd[12110]: SERVER perl filter not defined
Jul 20 11:09:02 newsfeed nnrpd[12631]: SERVER perl filter not defined
Jul 20 14:58:53 newsfeed nnrpd[20425]: SERVER perl filter not defined
Jul 20 15:00:38 newsfeed nnrpd[20670]: SERVER perl filter not defined
Jul 20 15:01:36 newsfeed nnrpd[20683]: SERVER perl filter not defined
Jul 20 15:02:33 newsfeed nnrpd[20685]: SERVER perl filter not defined
Jul 20 15:04:57 newsfeed nnrpd[20714]: SERVER perl filter not defined
Jul 20 15:20:32 newsfeed nnrpd[21359]: SERVER perl filter not defined
Jul 20 15:47:46 newsfeed nnrpd[22143]: SERVER perl filter not defined
Jul 20 19:34:50 newsfeed nnrpd[30005]: SERVER perl filter not defined
Jul 20 23:05:00 newsfeed pgpverify[4938]: pgpverify: verification failed
Jul 20 23:05:00 newsfeed controlchan[2710]: skipping newgroup y gcn@news.nic.it (pgpverify failed) in <20150720205838$77e7@wonderland.linux.it>

Log entries by program:

Program nameLines%LinesSize%Size
inn2809192 98.1%325.3 MB 97.7%
innfeed 26437 0.9%3.2 MB 1.0%
innd 22126 0.8%3.8 MB 1.1%
nnrpd 5279 0.2%570.1 KB 0.2%
controlchan 2 0.0%0.2 KB 0.0%
pgpverify 1 0.0%0.2 KB 0.0%
TOTAL: 62863037 100.0%332.9 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2804696 98.5%
Negative hits 24559 0.9%
Positive hits 17060 0.6%
Cache misses 113 0.0%
TOTAL: 4 2846428 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.498 0.0%242 0.000 2.058 53.000
article cleanup00:00:33.186 0.0%2808925 0.004 0.012 0.028
article logging00:01:18.512 0.1%2809242 0.016 0.028 0.149
article parse01:03:27.362 4.4%119115681 0.024 0.032 0.058
article write00:32:50.544 2.3%2805106 0.346 0.702 2.874
artlog/artcncl00:00:00.003 0.0%228 0.000 0.013 1.000
artlog/artparse00:00:00.000 0.0%2 0.000 0.000 0.000
data move00:01:50.380 0.1%135861921 0.000 0.001 0.003
hisgrep/artcncl00:00:00.152 0.0%209 0.000 0.727 16.000
hishave/artcncl00:00:00.012 0.0%242 0.000 0.050 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:09:08.318 0.6%2847105 0.022 0.193 4.581
history sync00:00:00.011 0.0%293 0.000 0.038 2.000
history write00:21:29.155 1.5%2805554 0.053 0.460 6.726
hiswrite/artcncl00:00:00.005 0.0%33 0.000 0.152 1.000
idle14:11:02.536 59.1%88976669 0.445 0.574 1.545
nntp read00:50:38.808 3.5%116869670 0.018 0.026 0.033
overview write00:00:15.682 0.0%2805106 0.000 0.006 0.016
perl filter06:07:20.069 25.5%2805552 6.180 7.856 9.657
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:04:54.399 0.3%5729396 0.035 0.051 0.083
TOTAL: 24:00:13.31323:24:49.632 97.5%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.451 0.0%167066 0.000 0.003 0.071
article prepare00:00:00.021 0.0%53756 0.000 0.000 0.250
article read00:00:33.507 0.0%13424 0.000 2.496 280.500
backlog stats00:00:00.275 0.0%332531 0.000 0.001 0.020
callbacks00:00:00.000 0.0%10 0.000 0.000 0.000
data read00:00:02.588 0.0%213435 0.000 0.012 0.211
data write00:00:08.936 0.0%101247 0.000 0.088 0.812
idle23:59:15.267 99.9%332531 24.295 259.691 1707.583
status file00:00:06.532 0.0%8064 0.000 0.810 4.667
TOTAL: 24:00:19.61524:00:07.577100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.690 0.0%4295 0.040 0.161 0.171
idle12:20:50.888 99.9%200514 0.000 221.685300429.500
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:07.501 0.0%401970 0.000 0.019 0.500
readart00:00:00.582 0.0%174 0.000 3.345 16.000
TOTAL: 12:21:22.77112:20:59.661 99.9%----

Control commands to INND:

CommandNumber
L 1
flush 24
flushlogs 2
go 2
logmode 2
mode 148
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 189

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
presidentobola@mail.com100011
TOTAL100011

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 1117 28044472800512 453 3482 99%274:29:51
2news.nask.pl 2 17843 4151 13424 268 23%24:53:03
3news.icm.edu.pl 45 292 38 171 83 13%16:08:41
4localhost 21 20 20 0 0 100%00:00:00
TOTAL: 4 1185 28226022804721 14048 3833 99%315:31:35
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl813.5 GB15.1 MB23.6 MB813.5 GB 99%304.2 KB
2news.nask.pl24.1 MB674.4 KB785.4 KB25.5 MB 94%5.9 KB
3news.icm.edu.pl784.1 KB114.2 KB1.4 MB2.3 MB 33%19.3 KB
4localhost32.3 KB0.0 KB0.0 KB32.3 KB100%1.6 KB
TOTAL: 4813.5 GB15.9 MB25.8 MB813.6 GB 99%303.7 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jul 20 04:15:02 - 04:59:59 75640 2.7% 28.0421.2 GB 2.6%8252.56
Jul 20 05:00:00 - 05:59:59 157785 5.6% 43.8337.6 GB 4.6%10944.66
Jul 20 06:00:00 - 06:59:59 135204 4.8% 37.5634.4 GB 4.2%10013.84
Jul 20 07:00:00 - 07:59:59 129252 4.6% 35.9035.3 GB 4.3%10284.42
Jul 20 08:00:00 - 08:59:59 125691 4.5% 34.9134.7 GB 4.3%10114.55
Jul 20 09:00:00 - 09:59:59 126224 4.5% 35.0633.7 GB 4.1%9813.76
Jul 20 10:00:00 - 10:59:59 123352 4.4% 34.2632.8 GB 4.0%9552.20
Jul 20 11:00:00 - 11:59:59 111698 4.0% 31.0331.4 GB 3.9%9142.94
Jul 20 12:00:00 - 12:59:59 110662 3.9% 30.7430.3 GB 3.7%8836.81
Jul 20 13:00:00 - 13:59:59 121799 4.3% 33.8333.9 GB 4.2%9879.53
Jul 20 14:00:00 - 14:59:59 129836 4.6% 36.0736.2 GB 4.5%10551.09
Jul 20 15:00:00 - 15:59:59 125970 4.5% 34.9936.5 GB 4.5%10630.93
Jul 20 16:00:00 - 16:59:59 132454 4.7% 36.7937.5 GB 4.6%10920.87
Jul 20 17:00:00 - 17:59:59 128772 4.6% 35.7736.1 GB 4.4%10525.50
Jul 20 18:00:00 - 18:59:59 106260 3.8% 29.5233.2 GB 4.1%9680.18
Jul 20 19:00:00 - 19:59:59 96748 3.4% 26.8734.0 GB 4.2%9892.41
Jul 20 20:00:00 - 20:59:59 101007 3.6% 28.0635.8 GB 4.4%10425.28
Jul 20 21:00:00 - 21:59:59 116213 4.1% 32.2837.1 GB 4.6%10818.44
Jul 20 22:00:00 - 22:59:59 102743 3.7% 28.5431.7 GB 3.9%9223.65
Jul 20 23:00:00 - 23:59:59 102950 3.7% 28.6033.2 GB 4.1%9673.72
Jul 21 00:00:00 - 00:59:59 100026 3.6% 27.7929.8 GB 3.7%8666.87
Jul 21 01:00:00 - 01:59:59 106355 3.8% 29.5431.7 GB 3.9%9235.36
Jul 21 02:00:00 - 02:59:59 115325 4.1% 32.0334.6 GB 4.3%10069.73
Jul 21 03:00:00 - 03:59:59 103237 3.7% 28.6833.3 GB 4.1%9710.59
Jul 21 04:00:00 - 04:15:02 19624 0.7% 21.766.6 GB 0.8%7693.95
TOTAL: 24:00:00 2804827 100.0% 32.46812.7 GB 100.0%9863.33
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 3754 177 0 3148 1 0 0 0 428
2news.nask.pl 299 12 0 220 19 0 0 0 48
3news.icm.edu.pl 84 0 0 4 0 0 0 0 80
TOTAL: 3 4137 189 0 3372 20 0 0 0 556

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 88
it.tlc.cellulari.android 24
de.comp.lang.php 16
comp.sys.raspberry-pi 15
it.comp.os.win.windows8 9
it.comp.os.win.windows7 8
uk.rec.cycling.moderated 6
hr.ponuda.hardver.komponente 5
comp.mobile.android 5
de.comp.lang.vbclassic 4
it.discussioni.energia 3
alt.sex.telephones 3
it.comp.os.win.windows10 1
hr.alt.cellular.gsm.android 1
hr.ponuda.hardver.konfiguracije 1
TOTAL: 15 189

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

GroupsCount
pl.praca.oferowana 18
pl.sci.historia 1
bit.listserv.catholic 1
TOTAL: 3 20

INND Perl filter [Top 20]:

ReasonCount
Binary in non-binary group 92
EMP (md5) 84
EMP (fsl) 54
HTML post 6
Attached HTML file 1
TOTAL: 5 237

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1panorama 48650 1571 33038 689 34 132081 3%24:00:25
2icm 53262 369 26771 10084 0 12 0%24:01:43
3poznan 6258 111 3934 30 0 1 1%23:54:29
4nask 0 0 0 0 0 14094 0%24:00:00
5okapi 0 0 0 0 0 2174 0%24:00:00
TOTAL: 5 108170 2051 63743 10803 34 148362 1%119:56:37
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1panorama6.9 MB3.8 MB10.7 MB0.1 KB/s4.9 KB24:00:25
2poznan6.2 MB1.2 MB7.4 MB0.1 KB/s53.4 KB23:54:29
3icm6.1 MB2.4 GB2.4 GB28.8 KB/s238.7 KB24:01:43
4nask0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:00
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:00
TOTAL: 519.1 MB2.4 GB2.4 GB5.8 KB/s195.5 KB119:56:37
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
panorama5.2 MB
TOTAL: 15.2 MB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1auriga.rz.izeto.pl 3 86134.7 KB 2 0 000:00:31
2lze200.lze.lodz.pl 16 67118.1 KB 9 0 001:17:33
3web.man.lodz.pl 288 2018.1 KB 9 0 000:00:29
4aorleans-258-1-153-9.w86-196.abo.wanadoo.fr 1 10.7 KB 1 0 000:30:02
5isvcs01.man.lodz.pl 20 00.0 KB 0 20 000:00:01
TOTAL: 5 328 174271.6 KB 21 20 012:21:22

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.rz.izeto.pl 3 86134.7 KB 2 0 000:00:31
2*.lze.lodz.pl 16 67118.1 KB 9 0 001:17:33
3*.man.lodz.pl 692 2018.1 KB 9 20 000:00:34
4*.w86-196.abo.wanadoo.fr 1 10.7 KB 1 0 000:30:02
5*.toya.net.pl 55 00.0 KB 0 0 000:00:03
6*.adsl.inetia.pl 2 00.0 KB 0 0 000:00:09
7unresolved 7 00.0 KB 0 0 010:32:28
TOTAL: 7 776 174271.6 KB 21 20 012:21:22

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
89.38.41.79 11.051 15.870 0.00010:32:28
lze200.lze.lodz.pl 0.032 0.286 0.00001:17:33
aorleans-258-1-153-9.w86-196.abo.wanadoo.fr 0.007 0.010 0.00000:30:02
auriga.rz.izeto.pl 0.018 0.063 0.00000:00:31
web.man.lodz.pl 1.006 4.057 0.00000:00:29
87-205-230-8.adsl.inetia.pl 0.009 0.028 0.00000:00:09
staticline41435.toya.net.pl 0.198 0.885 0.00000:00:03
mon.man.lodz.pl 0.989 4.398 0.00000:00:03
isvcs01.man.lodz.pl 0.126 0.335 0.00000:00:01
david.man.lodz.pl 0.316 1.345 0.00000:00:01
? 0.006 0.006 0.00000:00:00
TOTAL: 11 13.758 27.283 0.00012:21:22

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
david.man.lodz.pl 96
staticline41435.toya.net.pl 55
89.38.41.79 7
87-205-230-8.adsl.inetia.pl 2
TOTAL: 5 448

NNRP gethostbyaddr failures [Top 20]:

SystemConn
? (can't getpeername) 1
TOTAL: 1 1

NNRP client timeouts [Top 20]:

SystemConnPeer
lze200.lze.lodz.pl 2 0
89.38.41.79 1 0
aorleans-258-1-153-9.w86-196.abo.wanadoo.fr 1 0
TOTAL: 3 4 0

Newsgroup request counts (by category):

CategoryCountPct
1pl 87 50.0%
2lodman 86 49.4%
3lodz 1 0.6%
TOTAL: 3 174100.0%

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

NewsgroupCount
1lodman.info 86
2pl.comp.os.ms-windows.winnt 35
3pl.comp.pecet 28
4pl.rec.dom 14
5pl.rec.kuchnia 9
6lodz.test 1
7pl.comp.os.linux 1
TOTAL: 7 174