Daily Usenet report

Nov 22 04:15:02 -- Nov 23 04:15:02

Unknown entries from news log file:

First 18 / 18 lines (100.0%)

Nov 22 07:49:40 newsfeed overchan[27352]: input line too long, skipping
Nov 22 13:17:58 newsfeed nnrpd[13876]: SERVER perl filter not defined
Nov 22 13:19:50 newsfeed nnrpd[13889]: SERVER perl filter not defined
Nov 22 13:20:47 newsfeed nnrpd[13934]: SERVER perl filter not defined
Nov 22 13:22:36 newsfeed nnrpd[13970]: SERVER perl filter not defined
Nov 22 13:43:16 newsfeed overchan[27352]: input line too long, skipping
Nov 22 14:11:15 newsfeed overchan[27352]: input line too long, skipping
Nov 22 14:54:03 newsfeed nnrpd[16959]: SERVER perl filter not defined
Nov 22 14:55:01 newsfeed nnrpd[16971]: SERVER perl filter not defined
Nov 22 14:55:37 newsfeed nnrpd[17157]: SERVER perl filter not defined
Nov 22 15:24:51 newsfeed overchan[27352]: input line too long, skipping
Nov 22 18:58:05 newsfeed nnrpd[25209]: SERVER perl filter not defined
Nov 22 19:21:23 newsfeed nnrpd[25958]: SERVER perl filter not defined
Nov 22 19:46:54 newsfeed nnrpd[26854]: SERVER perl filter not defined
Nov 22 21:06:55 newsfeed overchan[27352]: input line too long, skipping
Nov 22 22:29:48 newsfeed nnrpd[32248]: SERVER perl filter not defined
Nov 22 22:52:17 newsfeed overchan[27352]: input line too long, skipping
Nov 22 23:25:33 newsfeed overchan[27352]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn2344691 97.2%273.3 MB 96.9%
innfeed 45336 1.9%5.4 MB 1.9%
innd 16552 0.7%2.8 MB 1.0%
nnrpd 5068 0.2%532.1 KB 0.2%
overchan 8 0.0%1.4 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 62411656 100.0%282.0 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2335773 91.9%
Positive hits 107528 4.2%
Negative hits 97066 3.8%
Cache misses 1574 0.1%
TOTAL: 4 2541941 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:02.421 0.0%94 0.000 25.755 192.000
article cleanup00:00:27.629 0.0%2347086 0.009 0.012 0.016
article logging00:01:41.448 0.1%2347721 0.032 0.043 0.066
article parse00:39:35.234 2.7%85785583 0.015 0.028 0.046
article write00:16:54.198 1.2%2339102 0.230 0.434 1.158
artlog/artcncl00:00:00.003 0.0%94 0.000 0.032 1.000
data move00:02:21.380 0.2%99316348 0.001 0.001 0.003
hisgrep/artcncl00:00:00.672 0.0%93 0.000 7.226 33.889
hishave/artcncl00:00:00.180 0.0%94 0.000 1.915 17.250
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup03:25:55.323 14.3%2548359 1.403 4.848 11.080
history sync00:00:00.014 0.0%290 0.000 0.048 0.500
history write04:58:14.462 20.7%2341437 2.916 7.643 14.474
hiswrite/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
idle09:14:54.124 38.5%60569833 0.107 0.550 1.191
nntp read00:55:32.618 3.9%84072043 0.034 0.040 0.048
overview write00:00:11.469 0.0%2339102 0.003 0.005 0.006
perl filter03:56:10.239 16.4%2341437 4.163 6.052 7.715
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:04:57.610 0.3%4918772 0.043 0.061 0.076
TOTAL: 24:00:03.97423:36:59.024 98.4%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:01.343 0.0%475315 0.000 0.003 0.007
article prepare00:00:00.118 0.0%507026 0.000 0.000 0.001
article read00:03:26.578 0.2%119038 0.000 1.735 57.000
backlog stats00:00:01.740 0.0%1937055 0.000 0.001 0.003
callbacks00:00:00.000 0.0%2 0.000 0.000 0.000
data read00:00:17.006 0.0%945183 0.010 0.018 0.039
data write00:03:21.246 0.2%1045993 0.029 0.192 0.410
idle23:43:18.037 99.4%1937055 17.069 44.087 653.705
status file00:00:20.740 0.0%16495 0.702 1.257 8.467
TOTAL: 23:51:41.66423:50:46.808 99.9%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:08:44.344 97.1%3346 0.000 156.708 45245.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.333 0.1%7705 0.000 0.043 1.105
readart00:00:00.008 0.0%256 0.000 0.031 0.500
TOTAL: 00:09:00.18300:08:44.685 97.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 498 23338272326567 4800 2460 99%508:07:36
2news.nask.pl 2 112609 9136 98005 5468 8%23:42:36
3news.icm.edu.pl 46 227 111 93 23 48%15:50:07
4localhost 12 11 11 0 0 100%00:00:00
TOTAL: 4 558 24466742335825 102898 7951 95%547:40:19
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl683.8 GB155.7 MB160.2 MB684.1 GB 99%308.0 KB
2news.nask.pl919.6 MB774.1 MB784.1 MB2.4 GB 37%173.7 KB
3news.icm.edu.pl1.9 MB60.3 KB638.1 KB2.6 MB 73%20.0 KB
4localhost25.0 KB0.0 KB0.0 KB25.0 KB100%2.3 KB
TOTAL: 4684.7 GB929.8 MB944.9 MB686.5 GB 99%307.1 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Nov 22 04:15:02 - 04:59:59 72999 3.1% 27.0624.5 GB 3.6%9521.23
Nov 22 05:00:00 - 05:59:59 108226 4.6% 30.0635.5 GB 5.2%10326.97
Nov 22 06:00:00 - 06:59:59 102276 4.4% 28.4134.2 GB 5.0%9949.79
Nov 22 07:00:00 - 07:59:59 75375 3.2% 20.9424.3 GB 3.6%7087.28
Nov 22 08:00:00 - 08:59:59 99175 4.2% 27.5523.0 GB 3.4%6710.62
Nov 22 09:00:00 - 09:59:59 109472 4.7% 30.4123.2 GB 3.4%6754.52
Nov 22 10:00:00 - 10:59:59 89407 3.8% 24.8421.4 GB 3.1%6243.51
Nov 22 11:00:00 - 11:59:59 77365 3.3% 21.4923.8 GB 3.5%6944.46
Nov 22 12:00:00 - 12:59:59 85112 3.6% 23.6424.7 GB 3.6%7197.99
Nov 22 13:00:00 - 13:59:59 104356 4.5% 28.9932.4 GB 4.7%9431.65
Nov 22 14:00:00 - 14:59:59 95596 4.1% 26.5526.7 GB 3.9%7765.52
Nov 22 15:00:00 - 15:59:59 97980 4.2% 27.2231.1 GB 4.5%9069.81
Nov 22 16:00:00 - 16:59:59 97851 4.2% 27.1833.2 GB 4.9%9675.87
Nov 22 17:00:00 - 17:59:59 102306 4.4% 28.4233.7 GB 4.9%9830.25
Nov 22 18:00:00 - 18:59:59 113807 4.9% 31.6128.5 GB 4.2%8305.02
Nov 22 19:00:00 - 19:59:59 147222 6.3% 40.9037.9 GB 5.5%11029.08
Nov 22 20:00:00 - 20:59:59 140301 6.0% 38.9736.7 GB 5.4%10679.33
Nov 22 21:00:00 - 21:59:59 85687 3.7% 23.8026.4 GB 3.9%7698.73
Nov 22 22:00:00 - 22:59:59 113903 4.9% 31.6432.7 GB 4.8%9520.18
Nov 22 23:00:00 - 23:59:59 120460 5.2% 33.4629.7 GB 4.3%8661.81
Nov 23 00:00:00 - 00:59:59 57631 2.5% 16.0119.0 GB 2.8%5547.39
Nov 23 01:00:00 - 01:59:59 73159 3.1% 20.3225.5 GB 3.7%7441.06
Nov 23 02:00:00 - 02:59:59 59427 2.5% 16.5122.1 GB 3.2%6440.32
Nov 23 03:00:00 - 03:59:59 95939 4.1% 26.6530.5 GB 4.5%8898.05
Nov 23 04:00:00 - 04:15:02 10929 0.5% 12.123.6 GB 0.5%4197.39
TOTAL: 24:00:00 2335961 100.0% 27.04684.6 GB 100.0%8308.19
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nask.pl 5746 23 0 3380 5 0 0 0 2338
2newsfeed.pionier.net.pl 2866 152 0 2274 5 0 0 0 435
3news.icm.edu.pl 24 0 0 3 0 0 0 0 21
TOTAL: 3 8636 175 0 5657 10 0 0 0 2794

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 59
it.tlc.cellulari.android 46
comp.mobile.android 25
it.comp.os.win.windows10 19
it.comp.os.win.windows7 12
nl.comp.os.linux.techniek 6
comp.sys.raspberry-pi 4
hr.ponuda.hardver.komponente 2
de.comp.lang.vbclassic 1
it.comp.os.linux.ubuntu 1
TOTAL: 10 175

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

GroupsCount
pl.praca.oferowana 9
bit.listserv.catholic 1
TOTAL: 2 10

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 2020
Binary in non-binary group 48
Scoring filter (16) 46
Scoring filter (8) 11
Scoring filter (9) 11
HTML post 10
Too many newsgroups 5
Scoring filter (10) 3
Scoring filter (18) 2
EMP (fsl) 1
Too many newsgroups (low_xpost) 1
TOTAL: 11 2158

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 397
news.nask.pl 238
news.icm.edu.pl 1
TOTAL: 3 636
TOTAL: 1 636

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nask 290545 114788 106282 440 0 9053 39%23:59:57
2panorama 252493 1526 223345 320 3 244187 0%23:59:57
3icm 264660 398 229594 9161 0 4 0%23:59:56
4poznan 8953 127 6819 24 0 0 1%23:59:36
5okapi 0 0 0 0 0 1259 0%23:57:50
TOTAL: 5 816651 116839 566040 9945 3 254503 14%119:57:16
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nask42.6 GB169.8 MB42.8 GB519.5 KB/s389.5 KB23:59:57
2icm292.9 MB3.4 GB3.7 GB44.5 KB/s401.9 KB23:59:56
3panorama211.1 MB8.5 MB219.6 MB2.6 KB/s121.8 KB23:59:57
4poznan588.2 KB97.2 KB685.3 KB0.0 KB/s4.5 KB23:59:36
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:50
TOTAL: 543.1 GB3.6 GB46.7 GB113.4 KB/s386.1 KB119:57:16
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 256247.9 KB 94 0 000:00:22
2isvcs01.man.lodz.pl 11 00.0 KB 0 11 000:00:01
TOTAL: 2 299 256247.9 KB 94 11 000:08:59

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 587 256247.9 KB 94 11 000:00:28
2*.security.ipip.net 1 00.0 KB 0 0 000:00:01
3unresolved 95 00.0 KB 0 0 000:00:01
4*.dynamic.gprs.plus.pl 2 00.0 KB 0 0 000:08:28
TOTAL: 4 685 256247.9 KB 94 11 000:08:59

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
apn-31-0-42-80.dynamic.gprs.plus.pl 0.200 0.076 0.00000:08:28
web.man.lodz.pl 1.400 5.476 0.00000:00:22
mon.man.lodz.pl 1.176 5.240 0.00000:00:04
isvcs01.man.lodz.pl 0.072 0.240 0.00000:00:01
212.51.192.185 0.404 1.416 0.00000:00:01
scan-141.security.ipip.net 0.004 0.024 0.00000:00:01
TOTAL: 6 3.256 12.472 0.00000:08:59

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
212.51.192.185 95
apn-31-0-42-80.dynamic.gprs.plus.pl 2
scan-141.security.ipip.net 1
TOTAL: 4 386

NNRP unrecognized commands (by host):

SystemConn
scan-141.security.ipip.net 4
TOTAL: 1 4

NNRP unrecognized commands (by command):

CommandCount
GET / HTTP/1.1 1
User-Agent: HTTP Banner Detection (https... 1
Host: 212.191.2.21:119 1
Connection: close 1
TOTAL: 4 4

NNRP client timeouts [Top 20]:

SystemConnPeer
scan-141.security.ipip.net 1 1
apn-31-0-42-80.dynamic.gprs.plus.pl 1 1
TOTAL: 2 2 2

Newsgroup request counts (by category):

CategoryCountPct
1pl 256100.0%
TOTAL: 1 256100.0%

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

NewsgroupCount
1pl.comp.os.ms-windows.winnt 212
2pl.comp.pecet 44
TOTAL: 2 256