Daily Usenet report

Oct 13 04:15:03 -- Oct 14 04:15:02

Unknown entries from news log file:

First 19 / 19 lines (100.0%)

Oct 13 10:34:28 newsfeed nnrpd[26839]: SERVER perl filter not defined
Oct 13 10:34:40 newsfeed nnrpd[26840]: SERVER perl filter not defined
Oct 13 11:36:47 newsfeed nnrpd[28916]: SERVER perl filter not defined
Oct 13 11:39:03 newsfeed nnrpd[28926]: SERVER perl filter not defined
Oct 13 13:34:08 newsfeed nnrpd[1807]: SERVER perl filter not defined
Oct 13 13:52:52 newsfeed nnrpd[2532]: SERVER perl filter not defined
Oct 13 14:15:20 newsfeed nnrpd[3267]: SERVER perl filter not defined
Oct 13 16:58:01 newsfeed nnrpd[8604]: SERVER perl filter not defined
Oct 13 17:11:30 newsfeed nnrpd[9041]: SERVER perl filter not defined
Oct 13 20:10:02 newsfeed nnrpd[14934]: SERVER perl filter not defined
Oct 13 21:08:45 newsfeed nnrpd[16873]: SERVER perl filter not defined
Oct 13 22:11:42 newsfeed nnrpd[18938]: SERVER perl filter not defined
Oct 13 22:12:19 newsfeed pgpverify[19089]: pgpverify: verification failed
Oct 13 22:29:06 newsfeed nnrpd[19522]: SERVER perl filter not defined
Oct 13 23:48:47 newsfeed pgpverify[22164]: pgpverify: verification failed
Oct 13 23:48:47 newsfeed controlchan[16023]: skipping newgroup y control@usenet-fr.news.eu.org (pgpverify failed) in <newgroup-fr.comp.reseaux.jntp-1413235958@usenet-fr.news.eu.org>
Oct 14 00:03:38 newsfeed pgpverify[22762]: pgpverify: verification failed
Oct 14 00:06:54 newsfeed pgpverify[22858]: pgpverify: verification failed
Oct 14 01:00:02 newsfeed pgpverify[24587]: pgpverify: verification failed

Log entries by program:

Program nameLines%LinesSize%Size
inn1619573 97.4%186.5 MB 96.9%
innd 29872 1.8%4.3 MB 2.2%
innfeed 6648 0.4%975.6 KB 0.5%
nnrpd 6338 0.4%661.5 KB 0.3%
controlchan 16 0.0%2.2 KB 0.0%
pgpverify 5 0.0%0.5 KB 0.0%
overchan 1 0.0%0.0 KB 0.0%
TOTAL: 71662453 100.0%192.4 MB100.0%

History cache:

ReasonCount%Count
Do not exist 1614778 93.6%
Negative hits 93201 5.4%
Positive hits 17590 1.0%
Cache misses 120 0.0%
TOTAL: 4 1725689 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:04.843 0.0%226 0.000 21.429 635.000
article cleanup00:00:28.795 0.0%1619638 0.004 0.018 0.040
article logging00:00:44.962 0.1%1619715 0.012 0.028 0.427
article parse01:02:37.759 4.3%138079838 0.009 0.027 0.075
article write00:14:50.777 1.0%1599293 0.201 0.557 3.770
artlog/artcncl00:00:00.010 0.0%196 0.000 0.051 1.000
artlog/artparse00:00:00.000 0.0%6 0.000 0.000 0.000
data move00:02:39.691 0.2%147960171 0.000 0.001 0.005
hisgrep/artcncl00:00:00.598 0.0%219 0.000 2.731 25.000
hishave/artcncl00:00:00.303 0.0%226 0.000 1.341 17.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup01:35:05.890 6.6%1726878 0.012 3.304 12.597
history sync00:00:00.011 0.0%292 0.000 0.038 1.000
history write02:18:34.085 9.6%1616078 0.062 5.145 50.507
hiswrite/artcncl00:00:00.003 0.0%7 0.000 0.429 1.500
idle13:42:29.544 57.1%94779262 0.346 0.521 1.765
nntp read00:58:02.195 4.0%136724854 0.011 0.025 0.039
overview write00:00:09.974 0.0%1599293 0.000 0.006 0.020
perl filter03:35:57.787 15.0%1616072 4.984 8.018 10.972
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:03:10.036 0.2%3237275 0.024 0.059 0.101
TOTAL: 24:00:19.06823:34:57.263 98.2%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.124 0.0%38663 0.000 0.003 0.143
article prepare00:00:00.012 0.0%22282 0.000 0.001 0.083
article read00:00:00.483 0.0%5327 0.000 0.091 1.000
backlog stats00:00:00.156 0.0%250359 0.000 0.001 0.022
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.535 0.0%154602 0.000 0.010 1.000
data write00:00:07.853 0.0%123998 0.000 0.063 20.000
idle23:58:53.181100.0%250359 0.000 344.838 29924.000
status file00:00:00.216 0.0%218 0.000 0.991 11.500
TOTAL: 23:59:08.88723:59:03.560100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle01:56:02.021 99.7%16889 0.000 412.222300063.333
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.810 0.0%35524 0.000 0.023 4.222
readart00:00:01.689 0.0%467 0.000 3.617 24.333
TOTAL: 01:56:24.73301:56:04.520 99.7%----

Control commands to INND:

CommandNumber
L 1
flush 24
flushlogs 2
go 2
logmode 2
mode 149
name 2
newgroup 1
pause 2
paused 2
reload 2
reserve 2
TOTAL: 12 191

Newsgroups created:

GroupMode
alt.binaries.sounds.lossless.christiany
TOTAL1

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
control@usenet-fr.news.eu.org000200
control@usenet.org.uk000100
god@heaven.com200022
grisbi-control@grisbi.org000100
TOTAL200422

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 458 16153551594528 1471 19356 98%268:03:12
2news.nask.pl 2 18093 4756 12409 928 26%24:08:20
3news.icm.edu.pl 46 221 29 155 37 13%16:37:53
4localhost 14 13 13 0 0 100%00:00:00
TOTAL: 4 520 16336821599326 14035 20321 97%308:49:25
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl472.6 GB15.6 MB2.3 GB474.9 GB 99%308.6 KB
2news.nask.pl23.5 MB2.4 MB2.5 MB28.4 MB 82%5.1 KB
3news.icm.edu.pl469.9 KB123.6 KB1.6 MB2.2 MB 21%33.8 KB
4localhost22.2 KB0.0 KB0.0 KB22.2 KB100%1.7 KB
TOTAL: 4472.6 GB18.1 MB2.3 GB474.9 GB 99%307.5 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 13 04:15:03 - 04:59:59 61211 3.8% 22.7018.5 GB 3.9%7208.46
Oct 13 05:00:00 - 05:59:59 72148 4.5% 20.0421.1 GB 4.5%6140.73
Oct 13 06:00:00 - 06:59:59 62519 3.9% 17.3720.5 GB 4.3%5966.16
Oct 13 07:00:00 - 07:59:59 60883 3.8% 16.9121.7 GB 4.6%6314.14
Oct 13 08:00:00 - 08:59:59 66512 4.2% 18.4821.5 GB 4.6%6274.99
Oct 13 09:00:00 - 09:59:59 64878 4.1% 18.0221.0 GB 4.4%6115.40
Oct 13 10:00:00 - 10:59:59 46673 2.9% 12.9614.7 GB 3.1%4293.60
Oct 13 11:00:00 - 11:59:59 66607 4.2% 18.5020.4 GB 4.3%5936.05
Oct 13 12:00:00 - 12:59:59 66146 4.1% 18.3715.5 GB 3.3%4501.86
Oct 13 13:00:00 - 13:59:59 82789 5.2% 23.0023.4 GB 5.0%6825.94
Oct 13 14:00:00 - 14:59:59 75346 4.7% 20.9321.9 GB 4.6%6387.27
Oct 13 15:00:00 - 15:59:59 66564 4.2% 18.4920.9 GB 4.4%6078.24
Oct 13 16:00:00 - 16:59:59 81244 5.1% 22.5722.2 GB 4.7%6455.64
Oct 13 17:00:00 - 17:59:59 76721 4.8% 21.3123.2 GB 4.9%6768.22
Oct 13 18:00:00 - 18:59:59 77127 4.8% 21.4224.1 GB 5.1%7010.97
Oct 13 19:00:00 - 19:59:59 66014 4.1% 18.3419.8 GB 4.2%5769.73
Oct 13 20:00:00 - 20:59:59 52731 3.3% 14.6514.7 GB 3.1%4278.03
Oct 13 21:00:00 - 21:59:59 58575 3.7% 16.2716.9 GB 3.6%4911.78
Oct 13 22:00:00 - 22:59:59 70748 4.4% 19.6520.9 GB 4.4%6075.37
Oct 13 23:00:00 - 23:59:59 79357 5.0% 22.0423.3 GB 4.9%6783.68
Oct 14 00:00:00 - 00:59:59 55733 3.5% 15.4814.0 GB 3.0%4083.62
Oct 14 01:00:00 - 01:59:59 54937 3.4% 15.2615.2 GB 3.2%4435.58
Oct 14 02:00:00 - 02:59:59 61142 3.8% 16.9817.0 GB 3.6%4953.60
Oct 14 03:00:00 - 03:59:59 52023 3.3% 14.4514.7 GB 3.1%4278.52
Oct 14 04:00:00 - 04:15:02 20321 1.3% 22.535.3 GB 1.1%6201.77
TOTAL: 23:59:59 1598949 100.0% 18.51472.4 GB 100.0%5733.12
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 19424 171 0 2669 10 0 0 016574
2news.nask.pl 941 18 0 893 16 0 0 0 14
3news.icm.edu.pl 63 0 0 4 0 0 0 0 59
TOTAL: 3 20428 189 0 3566 26 0 0 016647

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 42
it.tlc.cellulari.android 29
comp.sys.raspberry-pi 15
de.comm.provider.telefonie 13
de.comp.lang.vbclassic 12
it.comp.os.win.windows7 12
comp.mobile.android 12
uk.rec.cycling.moderated 11
nl.comp.os.linux.techniek 10
hr.alt.cellular.gsm.android 7
rec.arts.tv.x-factor 5
de.comp.office-pakete.ms-office.word 5
comp.mobile.ipad 5
hr.ponuda.hardver.komponente 4
hr.alt.cellular.gsm.iphone 2
hr.ponuda.hardver.periferije 1
hr.rec.glazba.hifi 1
de.comp.lang.perl 1
de.rec.kunst 1
it.comp.os.win.windows8 1
TOTAL: 20 189

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

GroupsCount
pl.sci.historia 22
fido7.ru.guitar.soft 4
TOTAL: 2 26

INND Perl filter [Top 20]:

ReasonCount
Binary in non-binary group 16491
EMP (md5) 63
EMP (fsl) 5
HTML post 4
Scoring filter (12) 1
TOTAL: 5 16564

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1panorama 39559 2075 36296 289 0 0 5%23:59:53
2icm 41498 1277 30085 473 1 7 3%23:59:26
3nask 26331 874 15412 491 0 0 3%23:57:16
4poznan 6738 66 4406 25 0 0 0%23:59:55
5okapi 0 0 0 0 0 3085 0%23:54:22
TOTAL: 5 114126 4292 86199 1278 1 3092 3%119:50:52
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm134.0 MB79.3 MB213.3 MB2.5 KB/s124.8 KB23:59:26
2nask109.4 MB120.2 MB229.6 MB2.7 KB/s172.3 KB23:57:16
3panorama11.5 MB1.2 MB12.7 MB0.2 KB/s5.5 KB23:59:53
4poznan252.1 KB75.8 KB327.9 KB0.0 KB/s3.6 KB23:59:55
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:54:22
TOTAL: 5255.2 MB200.7 MB455.9 MB1.1 KB/s83.8 KB119:50:52
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
okapi488.4 KB
TOTAL: 1488.4 KB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1pc-skrzep2.cm.p.lodz.pl 149 213329.1 KB 149 0 000:00:06
2web.man.lodz.pl 288 158130.7 KB 56 0 000:00:27
3lze200.lze.lodz.pl 5 96190.5 KB 6 0 001:27:34
4isvcs01.man.lodz.pl 13 00.0 KB 0 13 000:00:01
TOTAL: 4 455 467650.4 KB 211 13 001:56:24

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.cm.p.lodz.pl 149 213329.1 KB 149 0 000:00:06
2*.man.lodz.pl 685 158130.7 KB 56 13 000:00:33
3*.lze.lodz.pl 5 96190.5 KB 6 0 001:27:34
4*.181.14.62.static.jazztel.es 8 00.0 KB 0 0 000:28:04
5*.toya.net.pl 73 00.0 KB 0 0 000:00:04
TOTAL: 5 920 467650.4 KB 211 13 001:56:24

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
lze200.lze.lodz.pl 0.031 0.081 0.00001:27:34
98.181.14.62.static.jazztel.es 0.564 0.877 0.00000:28:04
web.man.lodz.pl 1.032 2.547 0.00000:00:27
pc-skrzep2.cm.p.lodz.pl 0.598 1.428 0.00000:00:06
staticline41435.toya.net.pl 0.244 0.683 0.00000:00:04
mon.man.lodz.pl 0.980 2.645 0.00000:00:04
david.man.lodz.pl 0.288 0.907 0.00000:00:01
isvcs01.man.lodz.pl 0.081 0.150 0.00000:00:01
TOTAL: 8 3.818 9.318 0.00001:56:24

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
david.man.lodz.pl 96
staticline41435.toya.net.pl 73
98.181.14.62.static.jazztel.es 8
TOTAL: 4 465

NNRP client timeouts [Top 20]:

SystemConnPeer
lze200.lze.lodz.pl 2 0
TOTAL: 1 2 0

Newsgroup request counts (by category):

CategoryCountPct
1pl 254 54.4%
2lodman 213 45.6%
TOTAL: 2 467100.0%

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

NewsgroupCount
1lodman.info 213
2pl.comp.pecet 161
3pl.rec.kuchnia 42
4pl.comp.os.ms-windows.winnt 25
5pl.rec.dom 13
6pl.misc.telefonia.gsm 13
TOTAL: 6 467