Daily Usenet report

Jun 26 04:15:02 -- Jun 27 04:15:04

Unknown entries from news log file:

First 17 / 17 lines (100.0%)

Jun 26 08:12:29 newsfeed nnrpd[4662]: SERVER perl filter not defined
Jun 26 08:14:59 newsfeed nnrpd[4862]: SERVER perl filter not defined
Jun 26 08:40:24 newsfeed overchan[27616]: input line too long, skipping
Jun 26 08:40:48 newsfeed overchan[27616]: input line too long, skipping
Jun 26 08:41:14 newsfeed overchan[27616]: input line too long, skipping
Jun 26 09:11:54 newsfeed nnrpd[6895]: SERVER perl filter not defined
Jun 26 09:14:21 newsfeed nnrpd[7113]: SERVER perl filter not defined
Jun 26 09:21:56 newsfeed nnrpd[7279]: SERVER perl filter not defined
Jun 26 11:06:29 newsfeed nnrpd[11282]: SERVER perl filter not defined
Jun 26 15:38:57 newsfeed overchan[27616]: input line too long, skipping
Jun 26 15:39:15 newsfeed overchan[27616]: input line too long, skipping
Jun 26 15:39:31 newsfeed overchan[27616]: input line too long, skipping
Jun 26 17:29:55 newsfeed overchan[27616]: input line too long, skipping
Jun 26 18:11:16 newsfeed overchan[27616]: input line too long, skipping
Jun 26 23:05:45 newsfeed overchan[27616]: input line too long, skipping
Jun 26 23:06:13 newsfeed overchan[27616]: input line too long, skipping
Jun 26 23:06:44 newsfeed overchan[27616]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn2350595 97.2%262.3 MB 96.8%
innfeed 45243 1.9%5.4 MB 2.0%
innd 16443 0.7%2.8 MB 1.0%
nnrpd 4891 0.2%518.5 KB 0.2%
overchan 12 0.0%2.2 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 62417185 100.0%271.0 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2267283 62.3%
Negative hits 1183489 32.5%
Positive hits 187782 5.2%
Cache misses 648 0.0%
TOTAL: 4 3639202 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:03.342 0.0%230 0.000 14.530 518.000
article cleanup00:00:32.179 0.0%2347723 0.010 0.014 0.018
article logging00:01:49.763 0.1%2348305 0.034 0.047 0.141
article parse00:42:31.241 3.0%87549419 0.015 0.029 0.052
article write00:16:04.109 1.1%2271681 0.273 0.424 0.900
artlog/artcncl00:00:00.014 0.0%221 0.000 0.063 1.000
artlog/artparse00:00:00.000 0.0%1 0.000 0.000 0.000
data move00:01:45.185 0.1%101638281 0.001 0.001 0.003
hisgrep/artcncl00:00:00.506 0.0%225 0.000 2.249 70.333
hishave/artcncl00:00:00.901 0.0%230 0.000 3.917 267.333
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:30:25.419 2.1%3652239 0.060 0.500 4.154
history sync00:00:00.022 0.0%290 0.000 0.076 1.000
history write01:19:07.798 5.5%2273788 0.227 2.088 8.561
hiswrite/artcncl00:00:00.000 0.0%5 0.000 0.000 0.000
idle15:37:35.221 65.1%73041914 0.489 0.770 1.346
nntp read00:55:53.182 3.9%86330396 0.026 0.039 0.066
overview write00:00:11.902 0.0%2271681 0.003 0.005 0.008
perl filter03:59:04.600 16.6%2273787 4.228 6.309 8.377
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:05:20.947 0.4%4851012 0.048 0.066 0.109
TOTAL: 24:00:02.38923:30:26.331 97.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:01.418 0.0%607224 0.000 0.002 0.007
article prepare00:00:00.004 0.0%44008 0.000 0.000 0.005
article read00:00:32.826 0.0%9829 0.032 3.340 87.933
backlog stats00:00:01.012 0.0%1610695 0.000 0.001 0.001
callbacks00:00:00.000 0.0%3 0.000 0.000 0.000
data read00:00:16.447 0.0%999546 0.009 0.016 0.023
data write00:00:53.424 0.1%708100 0.035 0.075 0.146
idle23:48:11.958 99.8%1610695 23.648 53.202 93.649
status file00:00:17.053 0.0%16803 0.675 1.015 3.950
TOTAL: 23:50:55.47923:50:14.142100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:31:35.782 99.3%3179 0.000 596.345 86997.333
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.293 0.0%7084 0.000 0.041 0.842
readart00:00:00.175 0.0%92 0.000 1.902 10.688
TOTAL: 00:31:49.11700:31:36.250 99.3%----

Control commands to INND:

CommandNumber
L 1
flush 27
flushlogs 2
go 2
logmode 2
mode 149
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 193

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 343 22617522119169 72579 70004 93%172:00:57
2news.nask.pl 129 202789 154586 42087 6116 76%38:47:43
3news.icm.edu.pl 43 216 36 171 9 16%16:43:42
4localhost 7 6 6 0 0 100%00:00:00
TOTAL: 4 522 24647632273797 114837 76129 92%227:32:22
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl552.8 GB23.8 GB23.8 GB600.3 GB 92%287.5 KB
2news.nask.pl52.1 GB1.3 GB1.3 GB54.8 GB 95%357.4 KB
3news.icm.edu.pl535.1 KB12.4 KB209.5 KB757.0 KB 70%16.8 KB
4localhost8.8 KB0.0 KB0.0 KB8.8 KB100%1.5 KB
TOTAL: 4604.9 GB25.1 GB25.1 GB655.1 GB 92%292.3 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 26 04:15:02 - 04:59:59 60354 2.7% 22.3716.5 GB 2.7%6410.76
Jun 26 05:00:00 - 05:59:59 88690 3.9% 24.6423.6 GB 3.9%6867.78
Jun 26 06:00:00 - 06:59:59 108809 4.8% 30.2227.5 GB 4.6%8023.44
Jun 26 07:00:00 - 07:59:59 63257 2.8% 17.5717.6 GB 2.9%5137.88
Jun 26 08:00:00 - 08:59:59 57524 2.5% 15.9816.1 GB 2.7%4696.13
Jun 26 09:00:00 - 09:59:59 108537 4.8% 30.1530.7 GB 5.1%8949.25
Jun 26 10:00:00 - 10:59:59 123192 5.4% 34.2232.9 GB 5.4%9587.87
Jun 26 11:00:00 - 11:59:59 122065 5.4% 33.9134.0 GB 5.6%9890.36
Jun 26 12:00:00 - 12:59:59 82064 3.6% 22.8022.0 GB 3.6%6405.94
Jun 26 13:00:00 - 13:59:59 105229 4.6% 29.2327.1 GB 4.5%7887.89
Jun 26 14:00:00 - 14:59:59 92564 4.1% 25.7125.0 GB 4.1%7278.74
Jun 26 15:00:00 - 15:59:59 136946 6.0% 38.0436.9 GB 6.1%10753.63
Jun 26 16:00:00 - 16:59:59 82898 3.6% 23.0323.0 GB 3.8%6710.45
Jun 26 17:00:00 - 17:59:59 88286 3.9% 24.5223.9 GB 4.0%6958.78
Jun 26 18:00:00 - 18:59:59 87651 3.9% 24.3524.4 GB 4.0%7107.25
Jun 26 19:00:00 - 19:59:59 47753 2.1% 13.2614.3 GB 2.4%4158.43
Jun 26 20:00:00 - 20:59:59 59119 2.6% 16.4216.5 GB 2.7%4817.81
Jun 26 21:00:00 - 21:59:59 72069 3.2% 20.0221.2 GB 3.5%6167.52
Jun 26 22:00:00 - 22:59:59 127418 5.6% 35.3927.0 GB 4.5%7853.62
Jun 26 23:00:00 - 23:59:59 120821 5.3% 33.5631.6 GB 5.2%9200.58
Jun 27 00:00:00 - 00:59:59 100503 4.4% 27.9226.2 GB 4.3%7642.91
Jun 27 01:00:00 - 01:59:59 123374 5.4% 34.2731.2 GB 5.2%9093.35
Jun 27 02:00:00 - 02:59:59 97081 4.3% 26.9725.4 GB 4.2%7401.68
Jun 27 03:00:00 - 03:59:59 91232 4.0% 25.3423.0 GB 3.8%6696.02
Jun 27 04:00:00 - 04:15:04 26232 1.2% 29.027.1 GB 1.2%8244.93
TOTAL: 24:00:02 2273668 100.0% 26.31604.8 GB 100.0%7339.92
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 70352 121 0 69852 0 0 0 0 379
2news.nask.pl 6344 22 0 4174 3 0 0 0 2145
3news.icm.edu.pl 10 0 0 1 0 0 0 0 9
TOTAL: 3 76706 143 0 74027 3 0 0 0 2533

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 67
it.tlc.cellulari.android 23
comp.sys.raspberry-pi 18
it.comp.os.win.windows10 16
comp.mobile.android 9
it.comp.os.win.windows7 6
it.comp.os.linux.ubuntu 2
uk.net.providers.gradwell 1
de.comp.lang.vbclassic 1
TOTAL: 9 143

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

GroupsCount
bit.listserv.catholic 1
pl.praca.oferowana 1
christnet.prayer 1
TOTAL: 3 3

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1798
Binary in non-binary group 76
EMP (phl) 26
Scoring filter (8) 20
Scoring filter (11) 7
Scoring filter (9) 5
Scoring filter (10) 5
Too many newsgroups 4
HTML post 4
Scoring filter (14) 2
Scoring filter (16) 2
Scoring filter (12) 1
EMP (fsl) 1
TOTAL: 13 1951

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 351
news.nask.pl 229
news.icm.edu.pl 1
TOTAL: 3 581
TOTAL: 1 581

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nask 178970 5953 139180 17 0 1 3%23:59:59
2panorama 321710 1736 291118 497 12 316851 0%24:00:00
3poznan 12083 453 7252 17 0 0 3%24:00:00
4icm 371219 37 304493 2111 0 8 0%23:59:59
5okapi 0 0 0 0 0 1109 0%23:57:52
TOTAL: 5 883982 8179 742043 2642 12 317969 0%119:57:50
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nask2.1 GB5.4 MB2.1 GB25.6 KB/s370.1 KB23:59:59
2panorama41.6 MB25.2 MB66.8 MB0.8 KB/s30.6 KB24:00:00
3poznan2.5 MB31.8 KB2.5 MB0.0 KB/s5.4 KB24:00:00
4icm507.8 KB756.5 MB757.0 MB9.0 KB/s360.9 KB23:59:59
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:52
TOTAL: 52.1 GB787.2 MB2.9 GB7.1 KB/s282.4 KB119:57:50
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
panorama1.4 MB
okapi488.4 KB
TOTAL: 21.9 MB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 6459.6 KB 27 0 000:00:22
2radius2.p.lodz.pl 1 1614.1 KB 2 0 000:30:27
30890788407.static.corbina.ru 6 68.8 KB 2 0 000:00:53
4isvcs01.man.lodz.pl 6 00.0 KB 0 6 000:00:00
TOTAL: 4 301 8682.5 KB 31 6 000:31:48

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 678 6459.6 KB 27 6 000:00:27
2*.p.lodz.pl 1 1614.1 KB 2 0 000:30:27
3*.static.corbina.ru 6 68.8 KB 2 0 000:00:53
TOTAL: 3 685 8682.5 KB 31 6 000:31:48

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
radius2.p.lodz.pl 0.012 0.028 0.00000:30:27
0890788407.static.corbina.ru 0.056 0.104 0.00000:00:53
web.man.lodz.pl 1.712 6.688 0.00000:00:22
mon.man.lodz.pl 1.388 5.820 0.00000:00:03
david.man.lodz.pl 0.468 1.876 0.00000:00:01
isvcs01.man.lodz.pl 0.036 0.128 0.00000:00:00
TOTAL: 6 3.672 14.644 0.00000:31:48

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
david.man.lodz.pl 96
TOTAL: 2 384

NNRP client timeouts [Top 20]:

SystemConnPeer
radius2.p.lodz.pl 1 0
TOTAL: 1 1 0

Newsgroup request counts (by category):

CategoryCountPct
1pl 64 74.4%
2lodz 16 18.6%
3lodman 6 7.0%
TOTAL: 3 86100.0%

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

NewsgroupCount
1pl.comp.os.ms-windows.winnt 56
2lodz.test 16
3pl.comp.pecet 8
4lodman.info 6
TOTAL: 4 86