Daily Usenet report

Nov 6 04:15:02 -- Nov 7 04:15:02

Unknown entries from news log file:

First 21 / 21 lines (100.0%)

Nov 6 12:14:26 newsfeed nnrpd[6380]: SERVER perl filter not defined
Nov 6 12:27:11 newsfeed overchan[13544]: input line too long, skipping
Nov 6 19:13:05 newsfeed overchan[13544]: input line too long, skipping
Nov 6 21:37:57 newsfeed overchan[13544]: input line too long, skipping
Nov 6 22:41:50 newsfeed overchan[13544]: input line too long, skipping
Nov 6 22:54:07 newsfeed overchan[13544]: input line too long, skipping
Nov 6 23:19:26 newsfeed overchan[13544]: input line too long, skipping
Nov 6 23:31:54 newsfeed nnrpd[29399]: SERVER perl filter not defined
Nov 6 23:38:38 newsfeed overchan[13544]: input line too long, skipping
Nov 7 00:14:37 newsfeed nnrpd[30955]: SERVER perl filter not defined
Nov 7 00:15:34 newsfeed nnrpd[31003]: SERVER perl filter not defined
Nov 7 00:16:31 newsfeed nnrpd[31011]: SERVER perl filter not defined
Nov 7 00:18:07 newsfeed nnrpd[31052]: SERVER perl filter not defined
Nov 7 00:22:18 newsfeed nnrpd[31145]: SERVER perl filter not defined
Nov 7 00:37:28 newsfeed overchan[13544]: input line too long, skipping
Nov 7 00:45:48 newsfeed overchan[13544]: input line too long, skipping
Nov 7 00:58:43 newsfeed overchan[13544]: input line too long, skipping
Nov 7 01:00:55 newsfeed overchan[13544]: input line too long, skipping
Nov 7 01:13:51 newsfeed overchan[13544]: input line too long, skipping
Nov 7 02:36:25 newsfeed nnrpd[3319]: SERVER perl filter not defined
Nov 7 03:01:20 newsfeed overchan[13544]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn2683756 97.5%312.4 MB 97.3%
innfeed 43360 1.6%5.1 MB 1.6%
innd 19217 0.7%3.1 MB 1.0%
nnrpd 4950 0.2%524.4 KB 0.2%
overchan 14 0.0%2.5 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 62751298 100.0%321.2 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2664511 96.6%
Negative hits 64818 2.3%
Positive hits 29981 1.1%
Cache misses 152 0.0%
TOTAL: 4 2759462 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:04.618 0.0%164 0.000 28.159 194.000
article cleanup00:00:31.832 0.0%2680918 0.009 0.012 0.018
article logging00:01:47.361 0.1%2681481 0.028 0.040 0.064
article parse00:46:28.168 3.2%95584099 0.018 0.029 0.041
article write00:18:06.927 1.3%2670481 0.231 0.407 0.708
artlog/artcncl00:00:00.006 0.0%154 0.000 0.039 1.000
artlog/artparse00:00:00.000 0.0%1 0.000 0.000 0.000
data move00:04:30.167 0.3%110893940 0.001 0.002 0.005
hisgrep/artcncl00:00:02.732 0.0%164 0.000 16.659 68.750
hishave/artcncl00:00:00.200 0.0%164 0.000 1.220 6.462
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup02:46:35.329 11.6%2768102 0.714 3.611 7.165
history sync00:00:00.024 0.0%290 0.000 0.083 1.000
history write04:49:12.221 20.1%2672998 2.075 6.492 12.635
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle09:14:53.970 38.5%65422044 0.266 0.509 1.401
nntp read01:11:07.960 4.9%93481640 0.030 0.046 0.065
overview write00:00:12.870 0.0%2670481 0.003 0.005 0.008
perl filter04:19:53.884 18.0%2672997 3.962 5.834 7.766
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:05:19.083 0.4%5388338 0.031 0.059 0.077
TOTAL: 24:00:04.49923:38:47.352 98.5%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.315 0.0%116783 0.000 0.003 0.029
article prepare00:00:00.007 0.0%44876 0.000 0.000 0.017
article read00:00:14.309 0.0%9793 0.000 1.461 255.000
backlog stats00:00:00.267 0.0%341257 0.000 0.001 0.003
callbacks00:00:00.000 0.0%2 0.000 0.000 0.000
data read00:00:03.490 0.0%193461 0.003 0.018 0.043
data write00:00:18.948 0.0%157766 0.022 0.120 0.297
idle23:52:56.696 99.9%341257 48.020 251.941 1102.824
status file00:00:17.719 0.0%16869 0.567 1.050 2.942
TOTAL: 23:54:08.98923:53:51.751100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:15.428 42.8%3136 0.000 4.920 130.500
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.766 2.1%7011 0.000 0.109 49.667
readart00:00:00.006 0.0%98 0.000 0.061 1.000
TOTAL: 00:00:36.06400:00:16.200 44.9%----

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 696 26725862667158 1042 4386 99%495:46:00
2news.nask.pl 2 33917 5448 22398 6071 16%24:21:44
3news.icm.edu.pl 53 241 77 151 13 31%17:40:41
4localhost 9 8 8 0 0 100%00:00:00
TOTAL: 4 760 27067522672691 23591 10470 98%537:48:25
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl798.9 GB272.0 MB399.5 MB799.6 GB 99%313.8 KB
2news.nask.pl493.2 MB717.7 MB728.5 MB1.9 GB 25%172.4 KB
3news.icm.edu.pl1.3 MB9.2 KB522.2 KB1.8 MB 71%20.5 KB
4localhost11.6 KB0.0 KB0.0 KB11.6 KB100%1.5 KB
TOTAL: 4799.4 GB989.6 MB1.1 GB801.5 GB 99%313.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Nov 6 04:15:02 - 04:59:59 72206 2.7% 26.7624.5 GB 3.1%9503.04
Nov 6 05:00:00 - 05:59:59 93384 3.5% 25.9434.8 GB 4.4%10147.05
Nov 6 06:00:00 - 06:59:59 90456 3.4% 25.1334.5 GB 4.3%10052.84
Nov 6 07:00:00 - 07:59:59 92304 3.5% 25.6435.0 GB 4.4%10206.49
Nov 6 08:00:00 - 08:59:59 117205 4.4% 32.5636.2 GB 4.5%10549.65
Nov 6 09:00:00 - 09:59:59 100525 3.8% 27.9237.1 GB 4.6%10811.57
Nov 6 10:00:00 - 10:59:59 94920 3.6% 26.3732.8 GB 4.1%9567.47
Nov 6 11:00:00 - 11:59:59 116322 4.4% 32.3139.3 GB 4.9%11455.75
Nov 6 12:00:00 - 12:59:59 150823 5.6% 41.9041.5 GB 5.2%12094.49
Nov 6 13:00:00 - 13:59:59 144386 5.4% 40.1138.1 GB 4.8%11091.32
Nov 6 14:00:00 - 14:59:59 116326 4.4% 32.3136.0 GB 4.5%10473.19
Nov 6 15:00:00 - 15:59:59 94829 3.5% 26.3433.0 GB 4.1%9623.48
Nov 6 16:00:00 - 16:59:59 107072 4.0% 29.7432.5 GB 4.1%9475.37
Nov 6 17:00:00 - 17:59:59 125541 4.7% 34.8731.1 GB 3.9%9066.38
Nov 6 18:00:00 - 18:59:59 96857 3.6% 26.9026.7 GB 3.3%7787.85
Nov 6 19:00:00 - 19:59:59 77174 2.9% 21.4424.7 GB 3.1%7187.41
Nov 6 20:00:00 - 20:59:59 92486 3.5% 25.6927.0 GB 3.4%7855.49
Nov 6 21:00:00 - 21:59:59 103544 3.9% 28.7630.5 GB 3.8%8891.22
Nov 6 22:00:00 - 22:59:59 131732 4.9% 36.5932.4 GB 4.1%9448.45
Nov 6 23:00:00 - 23:59:59 209704 7.8% 58.2540.1 GB 5.0%11678.43
Nov 7 00:00:00 - 00:59:59 98294 3.7% 27.3025.3 GB 3.2%7362.25
Nov 7 01:00:00 - 01:59:59 106659 4.0% 29.6330.5 GB 3.8%8871.37
Nov 7 02:00:00 - 02:59:59 104088 3.9% 28.9131.8 GB 4.0%9264.12
Nov 7 03:00:00 - 03:59:59 112501 4.2% 31.2535.3 GB 4.4%10283.82
Nov 7 04:00:00 - 04:15:02 23255 0.9% 25.788.7 GB 1.1%10105.22
TOTAL: 24:00:00 2672593 100.0% 30.93799.6 GB 100.0%9704.14
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nask.pl 6174 15 0 4419 4 0 0 0 1736
2newsfeed.pionier.net.pl 4821 156 0 3496 5 0 0 0 1164
3news.icm.edu.pl 14 0 0 1 0 0 0 0 13
TOTAL: 3 11009 171 0 7916 9 0 0 0 2913

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 81
comp.mobile.android 33
it.comp.os.win.windows10 22
it.tlc.cellulari.android 13
it.comp.os.win.windows7 8
nl.comp.sys.android 5
it.comp.os.linux.ubuntu 4
comp.sys.raspberry-pi 2
it.discussioni.energia 1
de.comm.software.mailreader 1
de.rec.sf.startrek 1
TOTAL: 11 171

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

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

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1510
Binary in non-binary group 765
EMP (phl) 23
Scoring filter (16) 16
Scoring filter (8) 10
Scoring filter (15) 5
Scoring filter (10) 5
Too many newsgroups 5
Scoring filter (18) 4
Scoring filter (9) 4
Scoring filter (14) 1
EMP (fsl) 1
HTML post 1
TOTAL: 13 2350

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 440
news.nask.pl 121
news.icm.edu.pl 1
TOTAL: 3 562
TOTAL: 1 562

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nask 48847 7971 27221 161 0 0 16%23:59:53
2panorama 77954 797 48985 183 4 66974 1%23:59:58
3poznan 5766 141 3957 14 0 0 2%23:59:58
4icm 52007 30 45042 1586 0 0 0%23:59:38
5okapi 0 0 0 0 0 1160 0%23:57:51
TOTAL: 5 184574 8939 125205 1944 4 68134 4%119:57:18
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nask2.7 GB55.5 MB2.7 GB33.2 KB/s353.2 KB23:59:53
2icm4.6 MB545.5 MB550.0 MB6.5 KB/s348.5 KB23:59:38
3panorama2.9 MB637.1 KB3.5 MB0.0 KB/s3.6 KB23:59:58
4poznan522.4 KB40.0 KB562.4 KB0.0 KB/s3.6 KB23:59:58
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:51
TOTAL: 52.7 GB601.7 MB3.3 GB8.0 KB/s316.0 KB119:57:18
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 98111.7 KB 44 0 000:00:19
2isvcs01.man.lodz.pl 8 00.0 KB 0 8 000:00:00
TOTAL: 2 296 98111.7 KB 44 8 000:00:34

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 584 98111.7 KB 44 8 000:00:32
2*.security.ipip.net 1 00.0 KB 0 0 000:00:01
3unresolved 96 00.0 KB 0 0 000:00:01
TOTAL: 3 681 98111.7 KB 44 8 000:00:34

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
web.man.lodz.pl 1.308 5.044 0.00000:00:19
mon.man.lodz.pl 2.248 11.080 0.00000:00:12
212.51.192.185 0.348 1.660 0.00000:00:01
scan-141.security.ipip.net 0.008 0.016 0.00000:00:01
isvcs01.man.lodz.pl 0.044 0.144 0.00000:00:00
TOTAL: 5 3.956 17.944 0.00000:00:34

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
212.51.192.185 96
scan-141.security.ipip.net 1
TOTAL: 3 385

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
Host: 212.191.2.21:119 1
Connection: close 1
User-Agent: HTTP Banner Detection (https... 1
TOTAL: 4 4

Newsgroup request counts (by category):

CategoryCountPct
1pl 98100.0%
TOTAL: 1 98100.0%

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

NewsgroupCount
1pl.comp.pecet 62
2pl.comp.os.ms-windows.winnt 36
TOTAL: 2 98