Daily Usenet report: Dec 5 04:15:02

Unknown entries from news log file:

First 12 / 12 lines (100.0%)

Dec 5 10:28:34 newsfeed nnrpd[7382]: SERVER perl filter not defined
Dec 5 10:28:42 newsfeed nnrpd[7383]: SERVER perl filter not defined
Dec 5 10:30:44 newsfeed nnrpd[7590]: SERVER perl filter not defined
Dec 5 10:31:08 newsfeed nnrpd[7592]: SERVER perl filter not defined
Dec 5 11:00:09 newsfeed nnrpd[8943]: SERVER perl filter not defined
Dec 5 11:00:21 newsfeed nnrpd[8949]: SERVER perl filter not defined
Dec 5 11:00:56 newsfeed nnrpd[8950]: SERVER perl filter not defined
Dec 5 11:01:11 newsfeed nnrpd[8951]: SERVER perl filter not defined
Dec 6 04:15:02 newsfeed nnrpd[12282]: web.man.lodz.pl rejected Flushing log and syslog files
Dec 6 04:15:02 newsfeed nnrpd[12285]: web.man.lodz.pl rejected Flushing log and syslog files
Dec 6 04:15:02 newsfeed nnrpd[12295]: web.man.lodz.pl rejected Flushing log and syslog files
Dec 6 04:15:02 newsfeed nnrpd[12296]: web.man.lodz.pl rejected Flushing log and syslog files

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3074860	98.4%	352.3 MB	98.2%
innd	39170	1.3%	5.4 MB	1.5%
nnrpd	4912	0.2%	510.3 KB	0.1%
innfeed	4812	0.2%	683.7 KB	0.2%
TOTAL: 4	3123754	100.0%	358.9 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3067911	96.5%
Negative hits	94570	3.0%
Positive hits	15872	0.5%
Cache misses	105	0.0%
TOTAL: 4	3178458	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.763	0.0%	230	0.000	12.013	608.000
article cleanup	00:00:45.544	0.1%	3074522	0.007	0.015	0.028
article logging	00:01:26.040	0.1%	3075225	0.012	0.028	0.672
article parse	01:22:39.825	5.7%	197043105	0.016	0.025	0.073
article write	00:24:16.063	1.7%	3055180	0.297	0.477	2.662
artlog/artcncl	00:00:00.006	0.0%	218	0.000	0.028	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:05:30.602	0.4%	216043314	0.001	0.002	0.008
hisgrep/artcncl	00:00:00.419	0.0%	213	0.000	1.967	66.250
hishave/artcncl	00:00:00.048	0.0%	230	0.000	0.209	22.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:27:50.382	1.9%	3180822	0.098	0.525	8.286
history sync	00:00:00.013	0.0%	291	0.000	0.045	1.000
history write	00:42:55.947	3.0%	3070356	0.203	0.839	10.217
hiswrite/artcncl	00:00:00.004	0.0%	17	0.000	0.235	1.000
idle	12:02:26.965	50.2%	132560429	0.134	0.327	0.932
nntp read	01:32:07.320	6.4%	194583203	0.017	0.028	0.048
overview write	00:00:17.403	0.0%	3055180	0.001	0.006	0.012
perl filter	06:26:43.944	26.9%	3070355	5.004	7.557	9.743
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:00.997	0.3%	6137518	0.033	0.049	0.200
TOTAL: 24:00:15.310	23:12:04.285	96.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.142	0.0%	43044	0.000	0.003	0.167
article prepare	00:00:00.006	0.0%	19152	0.000	0.000	0.250
article read	00:00:37.840	0.0%	4864	0.000	7.780	1303.500
backlog stats	00:00:00.135	0.0%	173510	0.000	0.001	0.056
callbacks	00:00:00.001	0.0%	51	0.000	0.020	1.000
data read	00:00:01.375	0.0%	96224	0.000	0.014	0.103
data write	00:00:03.842	0.0%	73466	0.000	0.052	0.250
idle	23:58:43.487	99.9%	173510	24.041	497.513	5357.214
status file	00:00:00.655	0.0%	849	0.000	0.771	3.000
TOTAL: 23:59:32.470	23:59:27.483	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.001	0.0%	5	0.200	0.200	0.200
idle	00:34:21.948	99.3%	3221	0.000	640.158	123891.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.175	0.0%	7272	0.000	0.024	0.500
readart	00:00:03.452	0.2%	130	0.000	26.554	225.000
TOTAL: 00:34:37.466	00:34:25.576	99.4%	-	-	-	-

Control commands to INND:

Command	Number
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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1378	3056018	3044740	5918	5360	99%	370:17:21
2	news.nask.pl	1	29538	10247	5346	13945	34%	24:18:17
3	news.icm.edu.pl	35	409	25	350	34	6%	16:02:01
4	localhost	9	8	8	0	0	100%	00:00:00
TOTAL: 4		1423	3085973	3055020	11614	19339	98%	410:37:39

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	936.8 GB	168.3 MB	372.7 MB	937.3 GB	99%	322.2 KB
2	news.nask.pl	137.7 MB	4.0 MB	239.3 MB	381.1 MB	36%	16.1 KB
3	news.icm.edu.pl	714.0 KB	37.6 KB	1.2 MB	1.9 MB	36%	33.3 KB
4	localhost	10.3 KB	0.0 KB	0.0 KB	10.3 KB	100%	1.3 KB
TOTAL: 4		937.0 GB	172.3 MB	613.2 MB	937.7 GB	99%	319.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 5 04:15:02 - 04:59:59	99669	3.3%	36.94	30.2 GB	3.2%	11725.84
Dec 5 05:00:00 - 05:59:59	138703	4.5%	38.53	41.1 GB	4.4%	11961.88
Dec 5 06:00:00 - 06:59:59	144193	4.7%	40.05	40.0 GB	4.3%	11646.19
Dec 5 07:00:00 - 07:59:59	137833	4.5%	38.29	42.5 GB	4.5%	12371.17
Dec 5 08:00:00 - 08:59:59	125368	4.1%	34.82	41.4 GB	4.4%	12054.31
Dec 5 09:00:00 - 09:59:59	127083	4.2%	35.30	41.9 GB	4.5%	12206.47
Dec 5 10:00:00 - 10:59:59	124726	4.1%	34.65	41.2 GB	4.4%	12003.22
Dec 5 11:00:00 - 11:59:59	120301	3.9%	33.42	39.8 GB	4.3%	11603.67
Dec 5 12:00:00 - 12:59:59	120121	3.9%	33.37	39.8 GB	4.3%	11578.84
Dec 5 13:00:00 - 13:59:59	121203	4.0%	33.67	39.5 GB	4.2%	11500.87
Dec 5 14:00:00 - 14:59:59	113339	3.7%	31.48	38.9 GB	4.2%	11331.16
Dec 5 15:00:00 - 15:59:59	111483	3.6%	30.97	37.1 GB	4.0%	10807.70
Dec 5 16:00:00 - 16:59:59	122901	4.0%	34.14	40.0 GB	4.3%	11641.37
Dec 5 17:00:00 - 17:59:59	132079	4.3%	36.69	39.2 GB	4.2%	11410.23
Dec 5 18:00:00 - 18:59:59	137580	4.5%	38.22	38.8 GB	4.2%	11315.56
Dec 5 19:00:00 - 19:59:59	137380	4.5%	38.16	38.9 GB	4.2%	11319.16
Dec 5 20:00:00 - 20:59:59	129979	4.3%	36.11	38.0 GB	4.1%	11063.85
Dec 5 21:00:00 - 21:59:59	130149	4.3%	36.15	38.9 GB	4.2%	11335.90
Dec 5 22:00:00 - 22:59:59	129927	4.3%	36.09	38.6 GB	4.1%	11250.43
Dec 5 23:00:00 - 23:59:59	129276	4.2%	35.91	38.8 GB	4.2%	11299.41
Dec 6 00:00:00 - 00:59:59	106337	3.5%	29.54	33.9 GB	3.6%	9864.49
Dec 6 01:00:00 - 01:59:59	135649	4.4%	37.68	37.6 GB	4.0%	10956.56
Dec 6 02:00:00 - 02:59:59	133087	4.4%	36.97	37.6 GB	4.0%	10965.56
Dec 6 03:00:00 - 03:59:59	116092	3.8%	32.25	31.9 GB	3.4%	9278.59
Dec 6 04:00:00 - 04:15:02	30140	1.0%	33.41	8.2 GB	0.9%	9528.69
TOTAL: 24:00:00	3054598	100.0%	35.35	933.6 GB	100.0%	11330.91

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	14138	21	308	5	13804
2	newsfeed.pionier.net.pl	5866	110	3856	3	1897
3	news.icm.edu.pl	40	0	1	0	39
TOTAL: 3		20044	131	4165	8	15740

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	66
it.tlc.cellulari.android	16
it.comp.os.win.windows10	15
comp.sys.raspberry-pi	12
it.comp.os.win.windows7	5
comp.sys.mac.vintage	3
comp.mobile.ipad	3
uk.rec.cycling.moderated	3
de.rec.sf.startrek	2
comp.mobile.android	2
de.comp.lang.vbclassic	2
alt.sex.telephones	1
hr.rec.glazba.hifi	1
TOTAL: 13	131

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

Groups	Count
pl.praca.oferowana	7
bit.listserv.catholic	1
TOTAL: 2	8

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	12608
Binary in non-binary group	2106
Poison newsgroup	116
Scoring filter (8)	84
EMP (phl)	55
Scoring filter (9)	22
Scoring filter (10)	13
Too many newsgroups	10
Scoring filter (13)	10
HTML post	5
Scoring filter (11)	3
Scoring filter (12)	2
Bad X-Cancelled-By	1
Scoring filter (15)	1
TOTAL: 14	15036

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	16088	1234	12169	1180	189	28161	7%	23:58:36
2	poznan	15592	215	9670	54	0	0	1%	24:05:05
3	nask	10287	140	6391	8	0	0	1%	23:55:09
4	icm	33026	111	24115	1713	0	0	0%	23:52:02
5	okapi	0	0	0	0	0	2287	0%	24:00:01
TOTAL: 5		74993	1700	52345	2955	189	30448	2%	119:50:53

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	4.9 MB	200.6 MB	205.5 MB	2.4 KB/s	87.2 KB	23:58:36
2	icm	4.8 MB	396.5 MB	401.3 MB	4.8 KB/s	225.3 KB	23:52:02
3	poznan	1.2 MB	406.0 KB	1.6 MB	0.0 KB/s	6.1 KB	24:05:05
4	nask	913.4 KB	14.5 KB	927.9 KB	0.0 KB/s	6.3 KB	23:55:09
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 5		11.8 MB	597.5 MB	609.3 MB	1.4 KB/s	134.0 KB	119:50:53

Backlog files shrunk by innfeed:

Server	Size
okapi	488.4 KB
panorama	488.3 KB
TOTAL: 2	976.7 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	lze200.lze.lodz.pl	7	70	137.2 KB	6	0	00:33:57
2	web.man.lodz.pl	291	60	47.9 KB	26	0	00:00:22
3	isvcs01.man.lodz.pl	8	0	0.0 KB	0	8	00:00:00
TOTAL: 3		306	130	185.1 KB	32	8	00:34:37

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.lze.lodz.pl	7	70	137.2 KB	6	0	00:33:57
2	*.man.lodz.pl	683	60	47.9 KB	26	8	00:00:27
3	unresolved	3	0	0.0 KB	0	0	00:00:12
TOTAL: 3		693	130	185.1 KB	32	8	00:34:37

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
lze200.lze.lodz.pl	0.033	0.148	00:33:57
web.man.lodz.pl	0.949	4.986	00:00:22
185.48.195.200	0.011	0.019	00:00:12
mon.man.lodz.pl	0.936	4.487	00:00:03
david.man.lodz.pl	0.309	1.579	00:00:01
isvcs01.man.lodz.pl	0.043	0.174	00:00:00
TOTAL: 6	2.281	11.393	00:34:37

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
185.48.195.200	3
TOTAL: 3	387

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	130	100.0%
TOTAL: 1		130	100.0%

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

Newsgroup		Count
1	pl.comp.pecet	72
2	pl.misc.telefonia.gsm	25
3	pl.comp.os.ms-windows.winnt	19
4	pl.rec.kuchnia	10
5	pl.rec.dom	2
6	pl.internet.polip	2
TOTAL: 6		130

Daily Usenet report

Dec 5 04:15:02 -- Dec 6 04:15:02