Daily Usenet report

Jan 17 04:15:01 -- Jan 18 04:15:02

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

Jan 17 04:15:01 newsfeed nnrpd[27592]: web.man.lodz.pl rejected Flushing log and syslog files
Jan 17 04:15:02 newsfeed nnrpd[27595]: web.man.lodz.pl rejected Flushing log and syslog files
Jan 17 04:15:02 newsfeed nnrpd[27603]: web.man.lodz.pl rejected Flushing log and syslog files
Jan 17 10:33:40 newsfeed nnrpd[8401]: SERVER perl filter not defined
Jan 17 13:10:02 newsfeed pgpverify[13647]: pgpverify: verification failed
Jan 17 17:13:29 newsfeed nnrpd[21709]: SERVER perl filter not defined
Jan 18 01:29:12 newsfeed pgpverify[5609]: pgpverify: verification failed
Jan 18 01:29:12 newsfeed pgpverify[5615]: pgpverify: verification failed
Jan 18 01:29:12 newsfeed controlchan[10997]: skipping newgroup y moderator@dana.de (pgpverify failed) in <newgroup-de.comp.lang.delphi.misc-20150117@dana.de>

Log entries by program:

Program nameLines%LinesSize%Size
inn2032722 98.5%237.8 MB 98.1%
innd 18224 0.9%3.0 MB 1.2%
nnrpd 6964 0.3%726.6 KB 0.3%
innfeed 6486 0.3%995.4 KB 0.4%
controlchan 5 0.0%0.6 KB 0.0%
pgpverify 3 0.0%0.4 KB 0.0%
TOTAL: 62064404 100.0%242.4 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2027366 97.6%
Negative hits 35310 1.7%
Positive hits 14737 0.7%
Cache misses 144 0.0%
TOTAL: 4 2077557 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:06.694 0.0%308 0.000 21.734 583.000
article cleanup00:00:32.008 0.0%2032909 0.000 0.016 0.038
article logging00:00:57.432 0.1%2033018 0.000 0.028 0.357
article parse01:12:18.863 5.0%130004699 0.009 0.033 0.052
article write00:22:36.054 1.6%2026771 0.332 0.669 3.184
artlog/artcncl00:00:00.008 0.0%257 0.000 0.031 1.000
artlog/artparse00:00:00.000 0.0%4 0.000 0.000 0.000
data move00:02:38.936 0.2%141956578 0.000 0.001 0.014
hisgrep/artcncl00:00:00.737 0.0%273 0.000 2.700 112.000
hishave/artcncl00:00:00.378 0.0%308 0.000 1.227 47.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup02:10:15.028 9.0%2079082 0.002 3.759 9.317
history sync00:00:00.012 0.0%292 0.000 0.041 1.000
history write02:54:13.558 12.1%2028897 0.071 5.152 28.500
hiswrite/artcncl00:00:00.077 0.0%35 0.000 2.200 17.000
idle11:07:57.008 46.4%76662478 0.220 0.523 96.160
nntp read00:56:40.216 3.9%128255326 0.010 0.027 0.039
overview write00:00:13.741 0.0%2026771 0.000 0.007 0.019
perl filter04:30:13.042 18.8%2028893 1.462 7.991 9.906
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:04:03.648 0.3%4107702 0.020 0.059 0.151
TOTAL: 24:00:26.17723:22:47.440 97.4%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.192 0.0%54170 0.000 0.004 0.167
article prepare00:00:00.008 0.0%16524 0.000 0.000 0.083
article read00:00:00.461 0.0%4082 0.000 0.113 3.333
backlog stats00:00:00.142 0.0%254182 0.000 0.001 1.000
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.874 0.0%165309 0.000 0.011 1.000
data write00:00:04.765 0.0%114438 0.000 0.042 2.000
idle24:00:38.823100.0%254182 0.000 340.067 26100.000
status file00:00:00.891 0.0%192 0.000 4.641 122.000
TOTAL: 24:00:52.81224:00:47.156100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.259 0.0%109 0.167 2.376 3.000
idle12:59:46.122 99.9%256030 0.000 182.737 97094.500
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:09.144 0.0%513720 0.000 0.018 0.500
readart00:00:00.183 0.0%373 0.000 0.491 16.000
TOTAL: 13:00:32.40612:59:55.708 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
moderator@dana.de000100
newsmaster@carnet.hr000100
TOTAL000200

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 713 20288212022872 542 5407 99%366:04:57
2news.nask.pl 4 14234 3973 9683 578 27%23:33:57
3news.icm.edu.pl 39 356 36 178 142 10%17:40:01
4localhost 3 2 2 0 0 100%00:00:00
TOTAL: 4 759 20434132026883 10403 6127 99%407:18:55
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl593.5 GB83.7 MB339.9 MB593.9 GB 99%307.1 KB
2news.nask.pl17.2 MB1.5 MB1.5 MB20.2 MB 85%4.5 KB
3news.icm.edu.pl1.1 MB302.5 KB4.5 MB5.9 MB 19%34.0 KB
4localhost3.2 KB0.0 KB0.0 KB3.2 KB100%1.6 KB
TOTAL: 4593.5 GB85.4 MB345.9 MB594.0 GB 99%306.4 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jan 17 04:15:01 - 04:59:59 48502 2.4% 17.9717.9 GB 3.0%6942.70
Jan 17 05:00:00 - 05:59:59 40316 2.0% 11.2013.6 GB 2.3%3963.06
Jan 17 06:00:00 - 06:59:59 72124 3.6% 20.0325.3 GB 4.3%7355.81
Jan 17 07:00:00 - 07:59:59 77132 3.8% 21.4326.2 GB 4.4%7636.44
Jan 17 08:00:00 - 08:59:59 92268 4.6% 25.6330.2 GB 5.1%8806.59
Jan 17 09:00:00 - 09:59:59 102140 5.0% 28.3731.3 GB 5.3%9107.65
Jan 17 10:00:00 - 10:59:59 91831 4.5% 25.5129.6 GB 5.0%8617.59
Jan 17 11:00:00 - 11:59:59 82842 4.1% 23.0126.0 GB 4.4%7574.30
Jan 17 12:00:00 - 12:59:59 80706 4.0% 22.4228.3 GB 4.8%8251.97
Jan 17 13:00:00 - 13:59:59 111880 5.5% 31.0830.3 GB 5.1%8820.97
Jan 17 14:00:00 - 14:59:59 111673 5.5% 31.0228.1 GB 4.7%8179.91
Jan 17 15:00:00 - 15:59:59 100512 5.0% 27.9224.6 GB 4.2%7178.85
Jan 17 16:00:00 - 16:59:59 81588 4.0% 22.6624.9 GB 4.2%7258.27
Jan 17 17:00:00 - 17:59:59 79096 3.9% 21.9722.8 GB 3.8%6635.86
Jan 17 18:00:00 - 18:59:59 80100 4.0% 22.2523.7 GB 4.0%6916.19
Jan 17 19:00:00 - 19:59:59 100628 5.0% 27.9526.7 GB 4.5%7787.80
Jan 17 20:00:00 - 20:59:59 88630 4.4% 24.6224.4 GB 4.1%7113.65
Jan 17 21:00:00 - 21:59:59 95653 4.7% 26.5724.9 GB 4.2%7240.18
Jan 17 22:00:00 - 22:59:59 84874 4.2% 23.5826.0 GB 4.4%7572.80
Jan 17 23:00:00 - 23:59:59 89156 4.4% 24.7725.3 GB 4.3%7361.63
Jan 18 00:00:00 - 00:59:59 85865 4.2% 23.8519.9 GB 3.4%5808.58
Jan 18 01:00:00 - 01:59:59 87802 4.3% 24.3923.9 GB 4.0%6951.56
Jan 18 02:00:00 - 02:59:59 79306 3.9% 22.0322.4 GB 3.8%6521.80
Jan 18 03:00:00 - 03:59:59 49742 2.5% 13.8213.6 GB 2.3%3969.76
Jan 18 04:00:00 - 04:15:02 11848 0.6% 13.143.4 GB 0.6%3956.89
TOTAL: 24:00:01 2026214 100.0% 23.45593.4 GB 100.0%7201.07
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 5499 210 0 3418 0 0 0 0 1871
2news.nask.pl 610 7 0 583 2 0 0 0 18
3news.icm.edu.pl 142 0 0 15 0 0 0 0 127
TOTAL: 3 6251 217 0 4016 2 0 0 0 2016

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 137
it.tlc.cellulari.android 23
comp.mobile.android 18
it.comp.os.win.windows7 14
it.comp.os.win.windows8 5
hr.ponuda.hardver.komponente 4
uk.rec.cycling.moderated 3
comp.mobile.ipad 3
alt.sex.telephones 2
hr.rec.sport.rukomet 2
de.comp.office-pakete.ms-office.excel 2
nl.comp.sys.android 2
uk.current-events.charlie-hebdo 1
hr.ponuda.hardver.konfiguracije 1
TOTAL: 14 217

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

GroupsCount
pl.sci.historia 2
TOTAL: 1 2

INND Perl filter [Top 20]:

ReasonCount
Binary in non-binary group 1777
EMP (md5) 121
HTML post 3
EMP (fsl) 1
Too many newsgroups 1
TOTAL: 5 1903

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1panorama 54867 1534 52284 266 0 0 2%23:55:24
2icm 31171 132 23916 2047 0 8 0%23:58:44
3nask 16567 18 11403 91 0 0 0%23:59:40
4poznan 5080 15 3776 18 0 0 0%23:53:29
5okapi 0 0 0 0 0 1766 0%24:00:00
TOTAL: 5 107685 1699 91379 2422 0 1774 1%119:47:17
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1panorama5.5 MB1.0 MB6.5 MB0.1 KB/s3.7 KB23:55:24
2icm398.3 KB478.8 MB479.2 MB5.7 KB/s225.2 KB23:58:44
3nask154.7 KB49.6 MB49.7 MB0.6 KB/s467.1 KB23:59:40
4poznan31.0 KB226.9 KB257.9 KB0.0 KB/s7.8 KB23:53:29
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:00
TOTAL: 56.0 MB529.6 MB535.7 MB1.3 KB/s133.1 KB119:47:17
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1pc-skrzep2.cm.p.lodz.pl 288 312483.5 KB 288 0 000:00:09
2web.man.lodz.pl 292 6059.5 KB 28 0 000:00:25
3staticline-31-183-83-7.toya.net.pl 3 12.9 KB 1 0 100:07:46
4isvcs01.man.lodz.pl 2 00.0 KB 0 2 000:00:00
TOTAL: 4 585 373545.9 KB 317 2 113:00:31

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.cm.p.lodz.pl 288 312483.5 KB 288 0 000:00:09
2*.man.lodz.pl 678 6059.5 KB 28 2 000:00:32
3*.toya.net.pl 24 12.9 KB 1 0 100:07:47
4*.elsnet.pl 2 00.0 KB 0 0 000:00:21
5unresolved 6 00.0 KB 0 0 012:51:40
TOTAL: 5 998 373545.9 KB 317 2 113:00:31

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
185.48.192.95 12.555 18.277 0.00012:51:40
staticline-31-183-83-7.toya.net.pl 0.038 0.064 0.00000:07:46
web.man.lodz.pl 1.047 5.902 0.00000:00:25
host227.elsnet.pl 0.000 0.045 0.00000:00:21
pc-skrzep2.cm.p.lodz.pl 1.015 5.741 0.00000:00:09
mon.man.lodz.pl 1.014 5.747 0.00000:00:04
staticline41435.toya.net.pl 0.072 0.412 0.00000:00:01
david.man.lodz.pl 0.398 1.839 0.00000:00:01
isvcs01.man.lodz.pl 0.022 0.037 0.00000:00:00
TOTAL: 9 16.161 38.064 0.00013:00:31

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
david.man.lodz.pl 96
staticline41435.toya.net.pl 21
185.48.192.95 6
host227.elsnet.pl 2
TOTAL: 5 413

NNRP client timeouts [Top 20]:

SystemConnPeer
185.48.192.95 3 1
TOTAL: 1 3 1

Newsgroup request counts (by category):

CategoryCountPct
1lodman 313 83.9%
2pl 60 16.1%
TOTAL: 2 373100.0%

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

NewsgroupCount
1lodman.info 312
2pl.comp.pecet 52
3pl.comp.os.ms-windows.winnt 4
4pl.internet.polip 4
5lodman.test 1
TOTAL: 5 373