Daily Usenet report

May 28 04:15:02 -- May 29 04:15:02

Unknown entries from news log file:

First 21 / 21 lines (100.0%)

May 28 08:37:42 newsfeed nnrpd[10202]: SERVER perl filter not defined
May 28 08:56:03 newsfeed nnrpd[10915]: SERVER perl filter not defined
May 28 10:19:22 newsfeed nnrpd[13900]: SERVER perl filter not defined
May 28 10:30:05 newsfeed overchan[32462]: input line too long, skipping
May 28 11:31:17 newsfeed nnrpd[16491]: SERVER perl filter not defined
May 28 11:45:39 newsfeed nnrpd[8672]: SERVER perl filter not defined
May 28 11:46:39 newsfeed nnrpd[8871]: SERVER perl filter not defined
May 28 13:33:13 newsfeed nnrpd[16052]: SERVER perl filter not defined
May 28 13:48:25 newsfeed overchan[1039]: input line too long, skipping
May 28 13:50:26 newsfeed overchan[1039]: input line too long, skipping
May 28 15:13:28 newsfeed overchan[1039]: input line too long, skipping
May 28 17:04:03 newsfeed overchan[1039]: input line too long, skipping
May 28 17:04:24 newsfeed overchan[1039]: input line too long, skipping
May 28 17:36:38 newsfeed overchan[1039]: input line too long, skipping
May 28 18:03:36 newsfeed nnrpd[25890]: SERVER perl filter not defined
May 28 18:04:20 newsfeed nnrpd[25908]: SERVER perl filter not defined
May 28 19:48:32 newsfeed overchan[1039]: input line too long, skipping
May 28 23:08:45 newsfeed overchan[1039]: input line too long, skipping
May 28 23:09:10 newsfeed overchan[1039]: input line too long, skipping
May 28 23:10:11 newsfeed overchan[1039]: input line too long, skipping
May 28 23:10:38 newsfeed overchan[1039]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn2109642 96.7%244.3 MB 96.4%
innfeed 47220 2.2%5.6 MB 2.2%
innd 18793 0.9%3.0 MB 1.2%
nnrpd 4907 0.2%524.0 KB 0.2%
overchan 13 0.0%2.3 KB 0.0%
controlchan 2 0.0%0.1 KB 0.0%
TOTAL: 62180577 100.0%253.5 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2027504 64.7%
Negative hits 965443 30.8%
Positive hits 141855 4.5%
Cache misses 329 0.0%
TOTAL: 4 3135131 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:02.246 0.0%267 0.000 8.412 116.000
article cleanup00:00:24.959 0.0%2095513 0.008 0.012 0.016
article logging00:01:29.273 0.1%2096053 0.033 0.043 0.089
article parse00:37:03.918 2.6%81185188 0.016 0.027 0.037
article write00:14:02.310 1.0%2027658 0.271 0.415 0.744
artlog/artcncl00:00:00.008 0.0%261 0.000 0.031 0.500
artlog/artparse00:00:00.000 0.0%1 0.000 0.000 0.000
data move00:02:59.540 0.2%94156942 0.001 0.002 0.003
hisgrep/artcncl00:00:01.089 0.0%256 0.000 4.254 51.200
hishave/artcncl00:00:00.074 0.0%267 0.000 0.277 1.833
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:53:06.842 3.7%3139833 0.134 1.015 3.640
history sync00:00:00.016 0.0%288 0.000 0.056 0.500
history write02:17:36.020 9.6%2030551 0.571 4.066 10.634
hiswrite/artcncl00:00:00.014 0.0%11 0.000 1.273 7.000
idle14:54:39.457 62.6%68167916 0.490 0.787 2.581
nntp read00:52:58.293 3.7%80168781 0.031 0.040 0.052
overview write00:00:10.282 0.0%2027658 0.003 0.005 0.008
perl filter03:30:48.006 14.7%2030548 4.719 6.229 7.856
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:04:24.880 0.3%4316665 0.047 0.061 0.080
TOTAL: 23:50:05.08523:29:47.227 98.6%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:01.127 0.0%459390 0.000 0.002 0.007
article prepare00:00:00.017 0.0%79360 0.000 0.000 0.006
article read00:00:55.247 0.1%15797 0.222 3.497 50.632
backlog stats00:00:00.784 0.0%1410475 0.000 0.001 0.001
callbacks00:00:00.000 0.0%7 0.000 0.000 0.000
data read00:00:12.801 0.0%883143 0.007 0.014 0.024
data write00:00:40.292 0.0%638761 0.030 0.063 0.152
idle23:48:31.371 99.8%1410475 26.010 60.768 327.207
status file00:00:16.090 0.0%16714 0.608 0.963 3.706
TOTAL: 23:51:11.75423:50:37.729100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:03:31.158 93.9%3168 0.000 66.653 9688.333
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.262 0.1%6932 0.000 0.038 3.000
readart00:00:00.023 0.0%34 0.000 0.676 8.500
TOTAL: 00:03:44.78300:03:31.443 94.1%----

Control commands to INND:

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 1186 20401921944580 32560 63052 95%418:46:29
2news.nask.pl 32 145837 95753 44897 5187 65%28:22:26
3news.icm.edu.pl 41 198 27 165 6 13%15:26:41
4localhost 10 9 9 0 0 100%00:00:00
TOTAL: 4 1269 21862362040369 77622 68245 93%462:35:36
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl549.3 GB21.7 GB21.7 GB592.7 GB 92%309.6 KB
2news.nask.pl30.9 GB822.5 MB838.3 MB32.5 GB 95%337.7 KB
3news.icm.edu.pl424.6 KB0.0 KB115.9 KB540.5 KB 78%16.4 KB
4localhost12.1 KB0.0 KB0.0 KB12.1 KB100%1.3 KB
TOTAL: 4580.2 GB22.5 GB22.5 GB625.2 GB 92%310.9 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 28 04:15:02 - 04:59:59 56787 2.8% 21.0516.9 GB 2.9%6549.72
May 28 05:00:00 - 05:59:59 88892 4.4% 24.6924.7 GB 4.3%7183.72
May 28 06:00:00 - 06:59:59 97786 4.8% 27.1628.1 GB 4.8%8178.85
May 28 07:00:00 - 07:59:59 90166 4.4% 25.0521.1 GB 3.6%6143.69
May 28 08:00:00 - 08:59:59 94667 4.6% 26.3026.5 GB 4.6%7721.28
May 28 09:00:00 - 09:59:59 61803 3.0% 17.1717.9 GB 3.1%5201.59
May 28 10:00:00 - 10:59:59 77081 3.8% 21.4122.3 GB 3.8%6489.06
May 28 11:00:00 - 11:59:59 89256 4.4% 24.7926.2 GB 4.5%7635.43
May 28 12:00:00 - 12:59:59 89920 4.4% 24.9828.2 GB 4.9%8222.23
May 28 13:00:00 - 13:59:59 54303 2.7% 15.0817.5 GB 3.0%5095.56
May 28 14:00:00 - 14:59:59 60263 3.0% 16.7418.0 GB 3.1%5234.56
May 28 15:00:00 - 15:59:59 74324 3.6% 20.6521.0 GB 3.6%6127.95
May 28 16:00:00 - 16:59:59 79796 3.9% 22.1723.0 GB 4.0%6699.26
May 28 17:00:00 - 17:59:59 73663 3.6% 20.4619.2 GB 3.3%5599.41
May 28 18:00:00 - 18:59:59 91890 4.5% 25.5226.8 GB 4.6%7805.95
May 28 19:00:00 - 19:59:59 104409 5.1% 29.0027.6 GB 4.7%8025.69
May 28 20:00:00 - 20:59:59 113218 5.5% 31.4530.3 GB 5.2%8840.01
May 28 21:00:00 - 21:59:59 67457 3.3% 18.7419.8 GB 3.4%5766.90
May 28 22:00:00 - 22:59:59 93856 4.6% 26.0727.2 GB 4.7%7914.02
May 28 23:00:00 - 23:59:59 87587 4.3% 24.3325.9 GB 4.5%7539.30
May 29 00:00:00 - 00:59:59 98128 4.8% 27.2625.8 GB 4.5%7521.84
May 29 01:00:00 - 01:59:59 104366 5.1% 28.9930.2 GB 5.2%8792.57
May 29 02:00:00 - 02:59:59 104816 5.1% 29.1230.0 GB 5.2%8740.62
May 29 03:00:00 - 03:59:59 65813 3.2% 18.2819.5 GB 3.4%5674.61
May 29 04:00:00 - 04:15:02 20299 1.0% 22.506.7 GB 1.2%7806.23
TOTAL: 24:00:00 2040546 100.0% 23.62580.3 GB 100.0%7042.44
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 63505 99 0 62952 1 0 0 0 453
2news.nask.pl 5322 9 0 2451 6 0 0 0 2856
3news.icm.edu.pl 8 0 0 0 0 0 0 0 8
TOTAL: 3 68835 108 0 65403 7 0 0 0 3317

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 52
it.comp.os.win.windows10 18
comp.mobile.android 10
it.tlc.cellulari.android 10
nl.comp.os.linux.techniek 7
it.politica.m5s 4
de.rec.sf.startrek 4
de.comp.lang.vbclassic 2
it.comp.os.win.windows7 1
TOTAL: 9 108

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

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

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 2522
Binary in non-binary group 112
Scoring filter (8) 47
EMP (phl) 27
Scoring filter (9) 26
Scoring filter (10) 15
HTML post 8
Scoring filter (18) 4
Scoring filter (11) 4
Scoring filter (13) 2
Scoring filter (12) 2
Scoring filter (14) 1
Too many newsgroups 1
TOTAL: 13 2771

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 405
news.nask.pl 136
news.icm.edu.pl 2
TOTAL: 3 543
TOTAL: 1 543

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nask 194354 9469 149806 9 0 4 4%23:54:21
2panorama 213074 1861 182693 494 16 277086 0%23:59:19
3icm 316734 1072 255152 6511 0 7 0%23:54:21
4poznan 13025 104 8013 13 0 0 0%23:54:22
5okapi 0 0 0 0 0 1073 0%23:52:14
TOTAL: 5 737187 12506 595664 7027 16 278170 1%119:34:37
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nask3.4 GB2.6 MB3.4 GB41.3 KB/s374.8 KB23:54:21
2icm394.5 MB2.3 GB2.7 GB32.7 KB/s371.6 KB23:54:21
3panorama16.5 MB11.2 MB27.7 MB0.3 KB/s12.1 KB23:59:19
4poznan474.1 KB43.6 KB517.7 KB0.0 KB/s4.4 KB23:54:22
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:52:14
TOTAL: 53.8 GB2.3 GB6.1 GB14.9 KB/s327.6 KB119:34:37
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
panorama2.9 MB
TOTAL: 12.9 MB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 1412.1 KB 7 0 000:00:21
20890788407.static.corbina.ru 9 912.1 KB 5 0 000:01:20
3radius2.p.lodz.pl 1 21.6 KB 1 1 000:01:56
4isvcs01.man.lodz.pl 8 00.0 KB 0 8 000:00:00
TOTAL: 4 306 2525.9 KB 13 9 000:03:44

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 679 1412.1 KB 7 8 000:00:27
2*.static.corbina.ru 9 912.1 KB 5 0 000:01:20
3*.p.lodz.pl 1 21.6 KB 1 1 000:01:56
TOTAL: 3 689 2525.9 KB 13 9 000:03:44

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
radius2.p.lodz.pl 0.012 0.028 0.00000:01:56
0890788407.static.corbina.ru 0.060 0.116 0.00000:01:20
web.man.lodz.pl 1.592 3.392 0.00000:00:21
mon.man.lodz.pl 1.288 3.156 0.00000:00:03
david.man.lodz.pl 0.392 1.036 0.00000:00:01
isvcs01.man.lodz.pl 0.068 0.092 0.00000:00:00
TOTAL: 6 3.412 7.820 0.00000:03:44

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 287
david.man.lodz.pl 96
TOTAL: 2 383

Newsgroup request counts (by category):

CategoryCountPct
1pl 14 56.0%
2lodman 8 32.0%
3lodz 3 12.0%
TOTAL: 3 25100.0%

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

NewsgroupCount
1pl.comp.os.ms-windows.winnt 10
2lodman.info 8
3pl.comp.pecet 4
4lodz.test 3
TOTAL: 4 25