Daily Usenet report: Oct 6 04:15:03

Unknown entries from news log file:

First 27 / 27 lines (100.0%)

Oct 6 05:28:04 newsfeed nnrpd[20911]: SERVER perl filter not defined
Oct 6 08:11:24 newsfeed nnrpd[27253]: SERVER perl filter not defined
Oct 6 08:16:03 newsfeed nnrpd[27367]: SERVER perl filter not defined
Oct 6 08:18:38 newsfeed nnrpd[27526]: SERVER perl filter not defined
Oct 6 08:29:54 newsfeed nnrpd[27920]: SERVER perl filter not defined
Oct 6 09:17:51 newsfeed overchan[18035]: input line too long, skipping
Oct 6 09:45:50 newsfeed nnrpd[30613]: SERVER perl filter not defined
Oct 6 11:01:26 newsfeed nnrpd[1008]: SERVER perl filter not defined
Oct 6 11:02:16 newsfeed nnrpd[1010]: SERVER perl filter not defined
Oct 6 11:09:23 newsfeed nnrpd[1315]: SERVER perl filter not defined
Oct 6 14:28:19 newsfeed nnrpd[8625]: SERVER perl filter not defined
Oct 6 14:28:52 newsfeed nnrpd[8633]: SERVER perl filter not defined
Oct 6 15:07:47 newsfeed overchan[18035]: input line too long, skipping
Oct 6 15:16:04 newsfeed nnrpd[10322]: SERVER perl filter not defined
Oct 6 16:17:23 newsfeed overchan[18035]: input line too long, skipping
Oct 6 17:01:45 newsfeed overchan[18035]: input line too long, skipping
Oct 6 17:06:53 newsfeed overchan[18035]: input line too long, skipping
Oct 6 17:27:50 newsfeed overchan[18035]: input line too long, skipping
Oct 6 17:34:33 newsfeed overchan[18035]: input line too long, skipping
Oct 6 17:37:38 newsfeed overchan[18035]: input line too long, skipping
Oct 6 17:52:54 newsfeed overchan[18035]: input line too long, skipping
Oct 6 17:54:07 newsfeed overchan[18035]: input line too long, skipping
Oct 6 18:15:22 newsfeed nnrpd[17101]: SERVER perl filter not defined
Oct 6 18:31:16 newsfeed overchan[18035]: input line too long, skipping
Oct 6 18:37:32 newsfeed overchan[18035]: input line too long, skipping
Oct 6 21:11:32 newsfeed nnrpd[23606]: SERVER perl filter not defined
Oct 6 23:09:18 newsfeed nnrpd[27902]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2547830	96.0%	296.9 MB	95.6%
innd	54675	2.1%	7.5 MB	2.4%
innfeed	46702	1.8%	5.5 MB	1.8%
nnrpd	4862	0.2%	506.8 KB	0.2%
overchan	13	0.0%	2.3 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2654083	100.0%	310.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2486870	84.1%
Negative hits	346135	11.7%
Positive hits	125412	4.2%
Cache misses	175	0.0%
TOTAL: 4	2958592	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.639	0.0%	185	0.000	8.859	230.000
article cleanup	00:00:31.654	0.0%	2548236	0.009	0.012	0.016
article logging	00:01:24.477	0.1%	2549157	0.023	0.033	0.180
article parse	01:04:47.170	4.5%	226808543	0.010	0.017	0.026
article write	00:19:56.605	1.4%	2477911	0.179	0.483	1.114
artlog/artcncl	00:00:00.002	0.0%	185	0.000	0.011	0.333
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:03:24.998	0.2%	243962158	0.000	0.001	0.001
hisgrep/artcncl	00:00:00.395	0.0%	183	0.000	2.158	46.000
hishave/artcncl	00:00:00.114	0.0%	185	0.000	0.616	6.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:37:39.432	2.6%	2988226	0.069	0.756	4.842
history sync	00:00:00.011	0.0%	290	0.000	0.038	0.500
history write	01:31:45.459	6.4%	2514234	0.328	2.190	7.490
hiswrite/artcncl	00:00:00.001	0.0%	2	0.000	0.500	1.000
idle	13:20:08.230	55.6%	171435761	0.198	0.280	0.397
nntp read	01:25:43.712	6.0%	225099442	0.013	0.023	0.035
overview write	00:00:11.404	0.0%	2477911	0.003	0.005	0.006
perl filter	04:45:09.275	19.8%	2514234	4.807	6.805	8.213
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:35.743	0.2%	5105054	0.030	0.042	0.054
TOTAL: 24:00:00.906	23:14:20.321	96.8%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.684	0.0%	284161	0.000	0.002	0.012
article prepare	00:00:00.028	0.0%	93484	0.000	0.000	0.006
article read	00:02:20.445	0.2%	21985	0.333	6.388	238.100
backlog stats	00:00:00.490	0.0%	961133	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	2	0.000	0.000	0.000
data read	00:00:07.501	0.0%	583222	0.003	0.013	0.045
data write	00:00:39.531	0.0%	450733	0.015	0.088	0.135
idle	23:49:42.003	99.7%	961133	25.355	89.251	790.148
status file	00:00:13.085	0.0%	16182	0.567	0.809	1.821
TOTAL: 23:53:27.747	23:53:03.767	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:22.819	65.9%	3119	0.000	7.316	170.222
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.160	0.5%	6805	0.000	0.024	0.500
readart	00:00:00.000	0.0%	28	0.000	0.000	0.000
TOTAL: 00:00:34.641	00:00:22.979	66.3%	-	-	-	-

Control commands to INND:

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

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1145	2506871	2444414	9212	53245	97%	378:54:32
2	news.nask.pl	2	131140	31260	82120	17760	23%	24:12:05
3	news.icm.edu.pl	38	409	45	305	59	11%	18:04:40
4	localhost	16	15	15	0	0	100%	00:00:00
TOTAL: 4		1201	2638435	2475734	91637	71064	93%	421:11:17

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	800.8 GB	9.4 GB	13.8 GB	824.0 GB	97%	345.9 KB
2	news.nask.pl	7.4 GB	94.3 MB	2.8 GB	10.3 GB	71%	221.0 KB
3	news.icm.edu.pl	934.2 KB	8.4 KB	794.3 KB	1.7 MB	53%	16.7 KB
4	localhost	20.9 KB	0.0 KB	0.0 KB	20.9 KB	100%	1.4 KB
TOTAL: 4		808.3 GB	9.5 GB	16.6 GB	834.3 GB	96%	343.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 6 04:15:03 - 04:59:59	76279	3.1%	28.28	29.5 GB	3.6%	11460.30
Oct 6 05:00:00 - 05:59:59	109266	4.4%	30.35	39.6 GB	4.9%	11524.15
Oct 6 06:00:00 - 06:59:59	104350	4.2%	28.99	37.4 GB	4.6%	10887.79
Oct 6 07:00:00 - 07:59:59	94178	3.8%	26.16	32.8 GB	4.1%	9544.96
Oct 6 08:00:00 - 08:59:59	76527	3.1%	21.26	20.4 GB	2.5%	5941.69
Oct 6 09:00:00 - 09:59:59	126084	5.1%	35.02	21.8 GB	2.7%	6350.53
Oct 6 10:00:00 - 10:59:59	123800	5.0%	34.39	30.1 GB	3.7%	8754.59
Oct 6 11:00:00 - 11:59:59	102479	4.1%	28.47	33.7 GB	4.2%	9802.49
Oct 6 12:00:00 - 12:59:59	98033	4.0%	27.23	31.9 GB	4.0%	9304.73
Oct 6 13:00:00 - 13:59:59	111688	4.5%	31.02	37.0 GB	4.6%	10790.01
Oct 6 14:00:00 - 14:59:59	111426	4.5%	30.95	37.7 GB	4.7%	10978.74
Oct 6 15:00:00 - 15:59:59	103795	4.2%	28.83	36.5 GB	4.5%	10620.41
Oct 6 16:00:00 - 16:59:59	112049	4.5%	31.12	37.8 GB	4.7%	11006.47
Oct 6 17:00:00 - 17:59:59	109024	4.4%	30.28	36.2 GB	4.5%	10530.90
Oct 6 18:00:00 - 18:59:59	104884	4.2%	29.13	35.5 GB	4.4%	10339.79
Oct 6 19:00:00 - 19:59:59	96048	3.9%	26.68	34.7 GB	4.3%	10095.36
Oct 6 20:00:00 - 20:59:59	106432	4.3%	29.56	35.1 GB	4.3%	10221.51
Oct 6 21:00:00 - 21:59:59	103745	4.2%	28.82	34.5 GB	4.3%	10046.36
Oct 6 22:00:00 - 22:59:59	113302	4.6%	31.47	37.5 GB	4.6%	10916.28
Oct 6 23:00:00 - 23:59:59	84859	3.4%	23.57	29.6 GB	3.7%	8611.72
Oct 7 00:00:00 - 00:59:59	93986	3.8%	26.11	29.9 GB	3.7%	8717.19
Oct 7 01:00:00 - 01:59:59	97791	4.0%	27.16	32.2 GB	4.0%	9372.98
Oct 7 02:00:00 - 02:59:59	102130	4.1%	28.37	36.8 GB	4.6%	10719.70
Oct 7 03:00:00 - 03:59:59	90326	3.6%	25.09	31.9 GB	3.9%	9277.84
Oct 7 04:00:00 - 04:15:02	23045	0.9%	25.55	8.2 GB	1.0%	9496.49
TOTAL: 23:59:59	2475526	100.0%	28.65	807.9 GB	100.0%	9805.16

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	53671	172	33765	2	19732
2	news.nask.pl	18375	20	522	3	17830
3	news.icm.edu.pl	73	0	1	0	72
TOTAL: 3		72119	192	34288	5	37634

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	61
it.comp.os.win.windows10	61
nl.comp.os.linux.techniek	26
comp.mobile.ipad	12
it.comp.os.win.windows7	10
it.tlc.cellulari.android	6
hr.rec.glazba.hifi	5
de.rec.sf.startrek	5
comp.sys.raspberry-pi	2
comp.mobile.android	2
alt.sex.telephones	1
de.comp.office-pakete.ms-office.word	1
TOTAL: 12	192

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

Groups	Count
pl.praca.oferowana	3
pl.sci.matematyka	1
bit.listserv.catholic	1
TOTAL: 3	5

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	31916
EMP (md5)	4447
Scoring filter (9)	97
Scoring filter (8)	85
Scoring filter (11)	58
EMP (phl)	16
Poison newsgroup	13
HTML post	13
Too many newsgroups	6
Scoring filter (12)	5
Scoring filter (10)	3
Scoring filter (13)	1
Attached HTML file	1
Angle-bracket bot	1
Scoring filter (16)	1
TOTAL: 15	36663

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news.nask.pl	537
	newsfeed.pionier.net.pl	426
	news.icm.edu.pl	8
	TOTAL: 3	971
TOTAL: 1		971

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	nask	178788	16761	92418	1	0	0	9%	23:59:40
2	panorama	143664	2419	113419	769	10	168519	1%	23:59:57
3	poznan	15324	364	8934	58	0	0	2%	23:59:56
4	icm	176820	104	145217	1943	0	0	0%	23:59:49
5	okapi	0	0	0	0	0	1065	0%	23:57:50
TOTAL: 5		514596	19648	359988	2771	10	169584	3%	119:57:12

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nask	5.7 GB	4.6 KB	5.7 GB	68.6 KB/s	353.7 KB	23:59:40
2	panorama	37.6 MB	18.2 MB	55.9 MB	0.7 KB/s	17.9 KB	23:59:57
3	icm	10.0 MB	667.6 MB	677.6 MB	8.0 KB/s	339.0 KB	23:59:49
4	poznan	1.5 MB	502.3 KB	2.0 MB	0.0 KB/s	4.9 KB	23:59:56
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:50
TOTAL: 5		5.7 GB	686.4 MB	6.4 GB	15.5 KB/s	298.0 KB	119:57:12

Backlog files shrunk by innfeed:

Server	Size
panorama	1.9 MB
TOTAL: 1	1.9 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	28	36.0 KB	12	0	00:00:26
2	isvcs01.man.lodz.pl	15	0	0.0 KB	0	15	00:00:01
TOTAL: 2		303	28	36.0 KB	12	15	00:00:34

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	687	28	36.0 KB	12	15	00:00:33
2	unresolved	2	0	0.0 KB	0	0	00:00:00
3	*.us-west-1.compute.amazonaws.com	1	0	0.0 KB	0	0	00:00:00
TOTAL: 3		690	28	36.0 KB	12	15	00:00:34

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.064	4.432	00:00:26
mon.man.lodz.pl	1.196	4.724	00:00:03
isvcs01.man.lodz.pl	0.108	0.232	00:00:01
david.man.lodz.pl	0.340	1.460	00:00:01
31.41.232.23	0.004	0.020	00:00:00
ec2-54-153-2-63.us-west-1.compute.amazonaws.com	0.008	0.008	00:00:00
TOTAL: 6	2.720	10.876	00:00:34

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
31.41.232.23	2
ec2-54-153-2-63.us-west-1.compute.amazonaws.com	1
TOTAL: 4	387

Newsgroup request counts (by category):

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

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

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

Daily Usenet report

Oct 6 04:15:03 -- Oct 7 04:15:02