Daily Usenet report

Jun 17 04:15:02 -- Jun 18 04:15:02

Unknown entries from news log file:

First 12 / 12 lines (100.0%)

Jun 17 09:40:54 newsfeed nnrpd[7017]: SERVER perl filter not defined
Jun 17 10:29:04 newsfeed nnrpd[8661]: SERVER perl filter not defined
Jun 17 11:27:14 newsfeed nnrpd[10619]: SERVER perl filter not defined
Jun 17 11:45:17 newsfeed nnrpd[11273]: SERVER perl filter not defined
Jun 17 12:22:28 newsfeed nnrpd[12684]: SERVER perl filter not defined
Jun 17 12:48:08 newsfeed nnrpd[13603]: SERVER perl filter not defined
Jun 17 13:10:01 newsfeed pgpverify[14416]: pgpverify: verification failed
Jun 17 13:54:33 newsfeed nnrpd[16025]: SERVER perl filter not defined
Jun 17 13:55:56 newsfeed nnrpd[16091]: SERVER perl filter not defined
Jun 17 14:02:00 newsfeed nnrpd[16390]: SERVER perl filter not defined
Jun 17 18:30:07 newsfeed overchan[2619]: input line too long, skipping
Jun 17 23:13:54 newsfeed nnrpd[21638]: SERVER perl filter not defined

Log entries by program:

Program nameLines%LinesSize%Size
inn2646286 98.6%304.8 MB 98.2%
innd 29057 1.1%4.3 MB 1.4%
innfeed 4852 0.2%684.2 KB 0.2%
nnrpd 4820 0.2%496.4 KB 0.2%
controlchan 4 0.0%0.5 KB 0.0%
pgpverify 1 0.0%0.1 KB 0.0%
overchan 1 0.0%0.1 KB 0.0%
TOTAL: 72685021 100.0%310.2 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2636067 95.8%
Negative hits 99841 3.6%
Positive hits 16005 0.6%
Cache misses 106 0.0%
TOTAL: 4 2752019 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:02.964 0.0%173 0.000 17.133 1417.000
article cleanup00:00:34.252 0.0%2642731 0.004 0.013 0.025
article logging00:01:15.817 0.1%2643391 0.013 0.029 0.640
article parse01:10:51.803 4.9%202533138 0.011 0.021 0.062
article write00:32:30.326 2.3%2628381 0.315 0.742 5.832
artlog/artcncl00:00:00.006 0.0%173 0.000 0.035 1.000
artlog/artparse00:00:00.038 0.0%1390 0.000 0.027 0.068
data move00:02:40.417 0.2%219409974 0.000 0.001 0.002
hisgrep/artcncl00:00:00.314 0.0%156 0.000 2.013 48.000
hishave/artcncl00:00:00.038 0.0%173 0.000 0.220 4.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:32:17.390 2.2%2753760 0.115 0.704 8.771
history sync00:00:00.013 0.0%293 0.000 0.044 2.000
history write00:44:09.857 3.1%2637817 0.209 1.005 22.232
hiswrite/artcncl00:00:00.019 0.0%17 0.000 1.118 10.000
idle13:37:02.158 56.8%157499000 0.140 0.311 0.701
nntp read01:04:19.799 4.5%200538724 0.012 0.019 0.031
overview write00:00:13.967 0.0%2628381 0.000 0.005 0.013
perl filter05:29:40.350 22.9%2636427 5.165 7.503 9.193
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:04:39.033 0.3%5355556 0.026 0.052 0.122
TOTAL: 23:58:16.80523:20:18.561 97.4%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.401 0.0%100798 0.000 0.004 0.222
article prepare00:00:00.088 0.0%307084 0.000 0.000 0.062
article read00:00:16.192 0.0%76512 0.000 0.212 196.000
backlog stats00:00:00.427 0.0%531249 0.000 0.001 0.024
callbacks00:00:00.000 0.0%2 0.000 0.000 0.000
data read00:00:03.207 0.0%257515 0.000 0.012 0.100
data write00:00:32.133 0.0%277562 0.000 0.116 0.309
idle23:57:40.810 99.9%531249 13.989 162.374 2867.905
status file00:00:01.082 0.0%1454 0.000 0.744 8.000
TOTAL: 23:58:47.00223:58:34.340100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:03:51.215 95.0%3141 0.000 73.612 42944.333
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.156 0.1%6993 0.000 0.022 1.000
readart00:00:00.225 0.1%115 0.000 1.957 2.587
TOTAL: 00:04:03.36400:03:51.596 95.2%----

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
newsmaster@carnet.hr000100
TOTAL000100

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 1174 26288292614306 6395 8128 99%337:51:39
2news.nask.pl 3 24854 14201 3067 7586 57%23:56:48
3news.icm.edu.pl 45 248 62 182 4 25%14:21:57
4localhost 11 10 10 0 0 100%00:00:00
TOTAL: 4 1233 26539412628579 9644 15718 99%376:10:24
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl802.3 GB147.8 MB247.0 MB802.7 GB 99%320.9 KB
2news.nask.pl91.1 MB1.3 MB145.9 MB238.3 MB 38%11.2 KB
3news.icm.edu.pl3.9 MB0.0 KB77.2 KB4.0 MB 98%61.6 KB
4localhost14.4 KB0.0 KB0.0 KB14.4 KB100%1.4 KB
TOTAL: 4802.4 GB149.1 MB393.0 MB802.9 GB 99%318.4 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 17 04:15:02 - 04:59:59 77430 2.9% 28.7024.8 GB 3.1%9645.38
Jun 17 05:00:00 - 05:59:59 102637 3.9% 28.5132.2 GB 4.0%9369.07
Jun 17 06:00:00 - 06:59:59 108632 4.1% 30.1835.1 GB 4.4%10216.47
Jun 17 07:00:00 - 07:59:59 122327 4.7% 33.9837.2 GB 4.6%10837.79
Jun 17 08:00:00 - 08:59:59 117067 4.5% 32.5235.2 GB 4.4%10264.37
Jun 17 09:00:00 - 09:59:59 121846 4.6% 33.8536.0 GB 4.5%10488.19
Jun 17 10:00:00 - 10:59:59 131056 5.0% 36.4035.6 GB 4.4%10357.42
Jun 17 11:00:00 - 11:59:59 113095 4.3% 31.4236.1 GB 4.5%10506.93
Jun 17 12:00:00 - 12:59:59 113297 4.3% 31.4734.6 GB 4.3%10085.09
Jun 17 13:00:00 - 13:59:59 103040 3.9% 28.6234.0 GB 4.2%9913.83
Jun 17 14:00:00 - 14:59:59 101435 3.9% 28.1835.4 GB 4.4%10298.58
Jun 17 15:00:00 - 15:59:59 112978 4.3% 31.3837.6 GB 4.7%10947.49
Jun 17 16:00:00 - 16:59:59 126004 4.8% 35.0038.8 GB 4.8%11290.77
Jun 17 17:00:00 - 17:59:59 116648 4.4% 32.4036.3 GB 4.5%10574.21
Jun 17 18:00:00 - 18:59:59 128203 4.9% 35.6137.0 GB 4.6%10782.76
Jun 17 19:00:00 - 19:59:59 134933 5.1% 37.4838.7 GB 4.8%11263.65
Jun 17 20:00:00 - 20:59:59 140408 5.3% 39.0038.0 GB 4.7%11069.03
Jun 17 21:00:00 - 21:59:59 137893 5.2% 38.3036.1 GB 4.5%10508.79
Jun 17 22:00:00 - 22:59:59 109835 4.2% 30.5133.7 GB 4.2%9821.95
Jun 17 23:00:00 - 23:59:59 96519 3.7% 26.8128.8 GB 3.6%8383.83
Jun 18 00:00:00 - 00:59:59 71534 2.7% 19.8721.2 GB 2.6%6176.30
Jun 18 01:00:00 - 01:59:59 75696 2.9% 21.0324.7 GB 3.1%7195.01
Jun 18 02:00:00 - 02:59:59 73763 2.8% 20.4923.4 GB 2.9%6811.90
Jun 18 03:00:00 - 03:59:59 74367 2.8% 20.6624.5 GB 3.1%7146.52
Jun 18 04:00:00 - 04:15:02 19072 0.7% 21.146.6 GB 0.8%7692.91
TOTAL: 24:00:00 2629715 100.0% 30.44801.5 GB 100.0%9727.76
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 8625 191 0 5876 1 0 0 0 2557
2news.nask.pl 7769 33 0 423 6 0 0 0 7307
3news.icm.edu.pl 4 0 0 0 0 0 0 0 4
TOTAL: 3 16398 224 0 6299 7 0 0 0 9868

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 92
it.tlc.cellulari.android 37
comp.mobile.ipad 19
uk.rec.cycling.moderated 17
it.comp.os.win.windows10 15
comp.mobile.android 11
it.comp.os.win.windows7 9
comp.sys.raspberry-pi 8
it.comp.os.win.windows8 7
de.rec.sf.startrek 4
it.comp.os.linux.ubuntu 3
alt.sex.telephones 1
it.comp.dotnet 1
TOTAL: 13 224

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

GroupsCount
pl.praca.oferowana 5
pl.sci.historia 1
bit.listserv.catholic 1
TOTAL: 3 7

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 5529
Binary in non-binary group 1287
PFM bot misc 422
Scoring filter (8) 265
Scoring filter (9) 69
Scoring filter (10) 51
Scoring filter (11) 46
EMP (phl) 37
Scoring filter (13) 25
EMP (fsl) 20
Scoring filter (12) 20
Scoring filter (15) 14
Scoring filter (14) 7
Rogue cancel (newsgroups) 6
Adult group ECP 4
Scoring filter (19) 4
Scoring filter (23) 3
Scoring filter (24) 2
Scoring filter (18) 2
Poison newsgroup 2
TOTAL: 23 7818

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1icm 104295 68397 23618 5748 2 155 65%24:00:42
2panorama 18119 1780 13569 555 8 84685 9%23:57:39
3poznan 22536 82 14132 26 0 0 0%23:58:16
4nask 6108 18 4123 1 0 0 0%24:08:10
5okapi 0 0 0 0 0 2252 0%23:50:04
TOTAL: 5 151058 70277 55442 6330 10 87092 46%119:54:51
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm12.1 GB859.7 MB13.0 GB157.5 KB/s183.6 KB24:00:42
2panorama10.1 MB4.5 MB14.6 MB0.2 KB/s6.4 KB23:57:39
3poznan338.1 KB246.8 KB584.9 KB0.0 KB/s5.4 KB23:58:16
4nask159.9 KB6.3 KB166.2 KB0.0 KB/s8.7 KB24:08:10
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:04
TOTAL: 512.2 GB864.4 MB13.0 GB31.6 KB/s177.9 KB119:54:51
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
panorama7.2 MB
TOTAL: 17.2 MB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1vegas.cm.p.lodz.pl 1 6393.9 KB 1 0 000:00:00
2web.man.lodz.pl 287 1821.3 KB 9 0 000:00:21
3isvcs01.man.lodz.pl 10 00.0 KB 0 10 000:00:01
TOTAL: 3 298 81115.1 KB 10 10 000:04:03

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.cm.p.lodz.pl 1 6393.9 KB 1 0 000:00:00
2*.man.lodz.pl 681 1821.3 KB 9 10 000:00:27
3unresolved 1 00.0 KB 0 0 000:00:01
4*.neoplus.adsl.tpnet.pl 2 00.0 KB 0 0 000:03:23
5*.elsnet.pl 2 00.0 KB 0 0 000:00:10
6*.geo.uni.lodz.pl 1 00.0 KB 0 0 000:00:00
TOTAL: 6 688 81115.1 KB 10 10 000:04:03

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
cfo163.neoplus.adsl.tpnet.pl 0.076 0.028 0.00000:03:23
web.man.lodz.pl 0.970 3.773 0.00000:00:21
host227.elsnet.pl 0.006 0.018 0.00000:00:10
mon.man.lodz.pl 0.943 3.220 0.00000:00:03
196.52.18.30 0.010 0.006 0.00000:00:01
david.man.lodz.pl 0.317 1.110 0.00000:00:01
isvcs01.man.lodz.pl 0.075 0.162 0.00000:00:01
vegas.cm.p.lodz.pl 0.009 0.014 0.00000:00:00
TOTAL: 8 2.406 8.331 0.00000:04:03

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
david.man.lodz.pl 96
host227.elsnet.pl 2
cfo163.neoplus.adsl.tpnet.pl 2
pc225.geo.uni.lodz.pl 1
196.52.18.30 1
TOTAL: 6 390

Newsgroup request counts (by category):

CategoryCountPct
1lodman 63 77.8%
2pl 18 22.2%
TOTAL: 2 81100.0%

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

NewsgroupCount
1lodman.info 63
2pl.comp.pecet 14
3pl.comp.os.ms-windows.winnt 4
TOTAL: 3 81