Daily Usenet report: Sep 21 04:15:02

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

Sep 21 10:04:40 newsfeed nnrpd[18714]: SERVER perl filter not defined
Sep 21 11:24:20 newsfeed nnrpd[21452]: SERVER perl filter not defined
Sep 21 14:32:41 newsfeed nnrpd[28012]: SERVER perl filter not defined
Sep 21 14:33:43 newsfeed nnrpd[28021]: SERVER perl filter not defined
Sep 21 14:36:51 newsfeed nnrpd[28112]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2420045	98.4%	286.2 MB	98.1%
innd	29823	1.2%	4.3 MB	1.5%
innfeed	4819	0.2%	674.2 KB	0.2%
nnrpd	4805	0.2%	489.0 KB	0.2%
TOTAL: 4	2459492	100.0%	291.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2416187	95.6%
Negative hits	94829	3.8%
Positive hits	15181	0.6%
Cache misses	68	0.0%
TOTAL: 4	2526265	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.295	0.0%	155	0.000	21.258	450.000
article cleanup	00:00:35.785	0.0%	2419730	0.000	0.015	0.034
article logging	00:01:16.682	0.1%	2420272	0.015	0.032	0.842
article parse	01:01:05.371	4.2%	202369957	0.007	0.018	0.050
article write	00:20:50.273	1.4%	2406265	0.104	0.520	3.113
artlog/artcncl	00:00:00.003	0.0%	147	0.000	0.020	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:19.437	0.2%	217756403	0.000	0.001	0.003
hisgrep/artcncl	00:00:00.267	0.0%	111	0.000	2.405	21.000
hishave/artcncl	00:00:00.251	0.0%	155	0.000	1.619	11.333
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:27:18.703	1.9%	2526974	0.103	0.648	8.170
history sync	00:00:00.019	0.0%	291	0.000	0.065	2.000
history write	00:38:11.330	2.7%	2416916	0.205	0.948	10.820
hiswrite/artcncl	00:00:00.054	0.0%	44	0.000	1.227	10.000
idle	14:46:14.807	61.5%	158554584	0.209	0.335	110.673
nntp read	01:03:20.000	4.4%	200533775	0.010	0.019	0.041
overview write	00:00:13.871	0.0%	2406265	0.000	0.006	0.016
perl filter	04:45:25.407	19.8%	2416916	2.321	7.086	10.863
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:00.869	0.3%	4842799	0.035	0.050	0.102
TOTAL: 24:00:14.143	23:10:56.424	96.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.129	0.0%	38018	0.000	0.003	0.111
article prepare	00:00:00.007	0.0%	34222	0.000	0.000	0.019
article read	00:00:41.724	0.0%	8575	0.000	4.866	567.000
backlog stats	00:00:00.167	0.0%	184496	0.000	0.001	0.027
callbacks	00:00:00.000	0.0%	22	0.000	0.000	0.000
data read	00:00:01.296	0.0%	101646	0.000	0.013	0.085
data write	00:00:06.274	0.0%	79593	0.000	0.079	0.567
idle	23:59:14.618	99.9%	184496	114.571	468.057	3546.294
status file	00:00:00.593	0.0%	844	0.000	0.703	3.000
TOTAL: 24:00:09.617	24:00:04.808	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	04:34:07.797	99.9%	75576	0.000	217.633	413071.750
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:02.947	0.0%	151801	0.000	0.019	0.667
readart	00:00:00.039	0.0%	40	0.000	0.975	18.500
TOTAL: 04:34:25.785	04:34:10.783	99.9%	-	-	-	-

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	1006	2406798	2396067	7607	3124	99%	297:53:02
2	news.nask.pl	2	24853	10057	4474	10322	40%	23:59:57
3	news.icm.edu.pl	38	314	26	270	18	8%	16:44:54
4	localhost	6	5	5	0	0	100%	00:00:00
TOTAL: 4		1052	2431970	2406155	12351	13464	98%	338:37:53

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	714.7 GB	104.2 MB	149.3 MB	714.9 GB	99%	312.4 KB
2	news.nask.pl	43.0 MB	939.9 KB	144.8 MB	188.7 MB	22%	9.5 KB
3	news.icm.edu.pl	560.1 KB	0.0 KB	318.8 KB	878.9 KB	63%	20.0 KB
4	localhost	8.5 KB	0.0 KB	0.0 KB	8.5 KB	100%	1.7 KB
TOTAL: 4		714.7 GB	105.1 MB	294.4 MB	715.1 GB	99%	309.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 21 04:15:02 - 04:59:59	78028	3.2%	28.92	25.1 GB	3.5%	9773.75
Sep 21 05:00:00 - 05:59:59	92222	3.8%	25.62	30.2 GB	4.2%	8794.13
Sep 21 06:00:00 - 06:59:59	92149	3.8%	25.60	31.1 GB	4.4%	9048.31
Sep 21 07:00:00 - 07:59:59	106268	4.4%	29.52	34.7 GB	4.9%	10110.21
Sep 21 08:00:00 - 08:59:59	133895	5.6%	37.19	40.9 GB	5.7%	11899.56
Sep 21 09:00:00 - 09:59:59	150793	6.3%	41.89	41.4 GB	5.8%	12064.31
Sep 21 10:00:00 - 10:59:59	103919	4.3%	28.87	29.8 GB	4.2%	8670.32
Sep 21 11:00:00 - 11:59:59	136990	5.7%	38.05	39.2 GB	5.5%	11406.11
Sep 21 12:00:00 - 12:59:59	97836	4.1%	27.18	26.9 GB	3.8%	7833.88
Sep 21 13:00:00 - 13:59:59	141402	5.9%	39.28	38.9 GB	5.4%	11321.06
Sep 21 14:00:00 - 14:59:59	161148	6.7%	44.76	39.7 GB	5.6%	11566.02
Sep 21 15:00:00 - 15:59:59	98670	4.1%	27.41	28.2 GB	3.9%	8207.38
Sep 21 16:00:00 - 16:59:59	85331	3.5%	23.70	26.7 GB	3.7%	7764.35
Sep 21 17:00:00 - 17:59:59	84252	3.5%	23.40	26.3 GB	3.7%	7668.10
Sep 21 18:00:00 - 18:59:59	69393	2.9%	19.28	21.2 GB	3.0%	6172.60
Sep 21 19:00:00 - 19:59:59	76088	3.2%	21.14	22.6 GB	3.2%	6593.74
Sep 21 20:00:00 - 20:59:59	85872	3.6%	23.85	24.3 GB	3.4%	7078.24
Sep 21 21:00:00 - 21:59:59	76889	3.2%	21.36	23.0 GB	3.2%	6708.68
Sep 21 22:00:00 - 22:59:59	96751	4.0%	26.88	28.8 GB	4.0%	8381.25
Sep 21 23:00:00 - 23:59:59	96038	4.0%	26.68	28.4 GB	4.0%	8278.70
Sep 22 00:00:00 - 00:59:59	81753	3.4%	22.71	23.5 GB	3.3%	6852.20
Sep 22 01:00:00 - 01:59:59	77755	3.2%	21.60	24.0 GB	3.4%	6977.76
Sep 22 02:00:00 - 02:59:59	90958	3.8%	25.27	28.2 GB	4.0%	8218.90
Sep 22 03:00:00 - 03:59:59	65719	2.7%	18.26	22.3 GB	3.1%	6494.15
Sep 22 04:00:00 - 04:15:02	25772	1.1%	28.57	8.5 GB	1.2%	9907.27
TOTAL: 24:00:00	2405891	100.0%	27.85	713.8 GB	100.0%	8663.21

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	10531	8	197	2	10324
2	newsfeed.pionier.net.pl	3458	196	2617	1	644
3	news.icm.edu.pl	18	0	0	0	18
TOTAL: 3		14007	204	2814	3	10986

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	109
it.tlc.cellulari.android	29
it.comp.os.win.windows10	23
it.comp.os.win.windows7	16
comp.mobile.android	6
uk.rec.cycling.moderated	5
hr.alt.cellular.gsm.iphone	3
comp.mobile.ipad	3
it.discussioni.energia	2
nl.comp.os.linux.techniek	2
comp.sys.raspberry-pi	1
it.comp.virtualizzazione	1
de.rec.sf.startrek	1
alt.sex.telephones	1
sci.physics.acoustics	1
hr.rec.glazba.hifi	1
TOTAL: 16	204

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	9566
Binary in non-binary group	545
EMP (phl)	122
Scoring filter (8)	84
Scoring filter (9)	74
Scoring filter (10)	13
Scoring filter (13)	9
Scoring filter (11)	6
Scoring filter (14)	4
HTML post	3
Scoring filter (19)	3
Scoring filter (16)	3
Scoring filter (15)	2
Scoring filter (24)	2
Scoring filter (12)	2
Scoring filter (23)	1
Attached HTML file	1
Scoring filter (25)	1
Scoring filter (21)	1
Too many newsgroups	1
TOTAL: 21	10444

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	7777	1902	3824	698	43	31587	24%	24:02:35
2	icm	37056	286	23961	5335	0	0	0%	23:59:04
3	poznan	15491	79	9967	14	0	0	0%	23:57:20
4	nask	8805	39	5844	4	0	0	0%	23:58:28
5	okapi	0	0	0	0	0	2052	0%	24:00:01
TOTAL: 5		69129	2306	43596	6051	43	33639	3%	119:57:28

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	8.4 MB	11.7 MB	20.1 MB	0.2 KB/s	7.9 KB	24:02:35
2	icm	8.0 MB	1.3 GB	1.3 GB	15.4 KB/s	236.7 KB	23:59:04
3	poznan	334.5 KB	34.4 KB	369.0 KB	0.0 KB/s	4.0 KB	23:57:20
4	nask	279.5 KB	7.0 KB	286.5 KB	0.0 KB/s	6.7 KB	23:58:28
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 5		17.0 MB	1.3 GB	1.3 GB	3.1 KB/s	161.8 KB	119:57:28

Backlog files shrunk by innfeed:

Server	Size
panorama	2.4 MB
TOTAL: 1	2.4 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	40	46.1 KB	18	0	00:00:19
2	isvcs01.man.lodz.pl	5	0	0.0 KB	0	5	00:00:00
TOTAL: 2		293	40	46.1 KB	18	5	04:34:25

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	677	40	46.1 KB	18	5	00:00:24
2	unresolved	3	0	0.0 KB	0	0	04:31:51
3	*.centertel.pl	1	0	0.0 KB	0	0	00:00:14
4	*.dynamic-ra-08.vectranet.pl	1	0	0.0 KB	0	0	00:01:55
TOTAL: 4		682	40	46.1 KB	18	5	04:34:25

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
89.38.41.67	4.332	4.990	04:04:19
89.174.170.111	0.008	0.016	00:27:32
078088031016.dynamic-ra-08.vectranet.pl	0.041	0.015	00:01:55
web.man.lodz.pl	0.929	4.934	00:00:19
public-gprs358093.centertel.pl	0.008	0.013	00:00:14
mon.man.lodz.pl	0.965	4.628	00:00:03
david.man.lodz.pl	0.273	1.447	00:00:01
isvcs01.man.lodz.pl	0.025	0.067	00:00:00
TOTAL: 8	6.581	16.110	04:34:25

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
89.38.41.67	2
078088031016.dynamic-ra-08.vectranet.pl	1
public-gprs358093.centertel.pl	1
89.174.170.111	1
TOTAL: 6	389

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.pecet	20
2	pl.comp.os.ms-windows.winnt	20
TOTAL: 2		40

Daily Usenet report

Sep 21 04:15:02 -- Sep 22 04:15:02