Daily Usenet report: Oct 27 04:15:02

Unknown entries from news log file:

First 17 / 17 lines (100.0%)

Oct 27 05:11:43 newsfeed overchan[25358]: input line too long, skipping
Oct 27 08:34:24 newsfeed nnrpd[2958]: SERVER perl filter not defined
Oct 27 12:55:26 newsfeed nnrpd[12548]: SERVER perl filter not defined
Oct 27 14:52:24 newsfeed nnrpd[17071]: SERVER perl filter not defined
Oct 27 14:53:40 newsfeed nnrpd[17094]: SERVER perl filter not defined
Oct 27 14:57:57 newsfeed nnrpd[17325]: SERVER perl filter not defined
Oct 27 14:59:53 newsfeed nnrpd[17367]: SERVER perl filter not defined
Oct 27 15:48:16 newsfeed nnrpd[19178]: SERVER perl filter not defined
Oct 27 16:27:25 newsfeed overchan[25358]: input line too long, skipping
Oct 27 16:38:11 newsfeed overchan[25358]: input line too long, skipping
Oct 27 17:01:31 newsfeed nnrpd[21843]: SERVER perl filter not defined
Oct 27 22:48:02 newsfeed overchan[25358]: input line too long, skipping
Oct 27 22:55:48 newsfeed overchan[25358]: input line too long, skipping
Oct 27 23:03:22 newsfeed overchan[25358]: input line too long, skipping
Oct 27 23:11:24 newsfeed overchan[25358]: input line too long, skipping
Oct 27 23:43:46 newsfeed overchan[25358]: input line too long, skipping
Oct 28 00:14:05 newsfeed overchan[25358]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3187031	97.2%	369.6 MB	96.9%
innd	59147	1.8%	8.1 MB	2.1%
innfeed	28474	0.9%	3.4 MB	0.9%
nnrpd	4768	0.1%	495.5 KB	0.1%
overchan	10	0.0%	1.4 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	3279431	100.0%	381.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3154258	91.4%
Negative hits	264367	7.7%
Positive hits	33659	1.0%
Cache misses	174	0.0%
TOTAL: 4	3452458	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.507	0.0%	150	0.000	16.713	155.000
article cleanup	00:00:47.192	0.1%	3193286	0.009	0.015	0.020
article logging	00:02:04.999	0.1%	3193869	0.029	0.039	0.164
article parse	01:10:40.874	4.9%	199499915	0.013	0.021	0.038
article write	00:23:15.233	1.6%	3136021	0.271	0.445	0.982
artlog/artcncl	00:00:00.008	0.0%	150	0.000	0.053	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:03:06.468	0.2%	218752980	0.001	0.001	0.003
hisgrep/artcncl	00:00:01.096	0.0%	148	0.000	7.405	44.333
hishave/artcncl	00:00:00.079	0.0%	150	0.000	0.527	5.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:47:00.237	3.3%	3474461	0.090	0.812	4.208
history sync	00:00:00.009	0.0%	290	0.000	0.031	0.500
history write	02:18:36.108	9.6%	3174804	0.318	2.619	8.877
hiswrite/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
idle	11:24:24.276	47.5%	136804876	0.142	0.300	0.777
nntp read	01:21:31.155	5.7%	197048438	0.016	0.025	0.039
overview write	00:00:15.948	0.0%	3136021	0.004	0.005	0.007
perl filter	05:37:38.872	23.4%	3174804	4.993	6.381	7.738
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:33.285	0.3%	6293200	0.034	0.043	0.055
TOTAL: 24:00:02.574	23:13:58.346	96.8%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.172	0.0%	52659	0.000	0.003	0.021
article prepare	00:00:00.002	0.0%	7914	0.000	0.000	0.013
article read	00:01:02.365	0.1%	1971	0.000	31.641	230.667
backlog stats	00:00:00.127	0.0%	154018	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	4	0.000	0.000	0.000
data read	00:00:01.971	0.0%	84216	0.003	0.023	0.052
data write	00:00:03.295	0.0%	63505	0.013	0.052	0.137
idle	23:54:08.640	99.9%	154018	256.046	558.692	979.617
status file	00:00:09.888	0.0%	9809	0.500	1.008	7.650
TOTAL: 23:55:37.593	23:55:26.460	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:17.498	54.5%	3040	0.000	5.756	29.778
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.196	0.6%	6608	0.000	0.030	0.667
TOTAL: 00:00:32.080	00:00:17.694	55.2%	-	-	-	-

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	982	3161581	3119244	9343	32994	98%	455:50:18
2	news.nask.pl	2	40125	10090	5757	24278	25%	24:14:18
3	news.icm.edu.pl	39	210	15	172	23	7%	14:25:22
4	localhost	9	8	8	0	0	100%	00:00:01
TOTAL: 4		1032	3201924	3129357	15272	57295	97%	494:29:59

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	876.7 GB	2.7 GB	5.5 GB	884.9 GB	99%	294.3 KB
2	news.nask.pl	66.0 MB	33.7 MB	3.4 GB	3.5 GB	1%	106.5 KB
3	news.icm.edu.pl	512.2 KB	16.9 KB	1.6 MB	2.1 MB	23%	57.4 KB
4	localhost	13.2 KB	0.0 KB	0.0 KB	13.2 KB	100%	1.6 KB
TOTAL: 4		876.7 GB	2.8 GB	8.9 GB	888.4 GB	98%	292.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 27 04:15:02 - 04:59:59	93358	3.0%	34.60	29.9 GB	3.4%	11614.10
Oct 27 05:00:00 - 05:59:59	117208	3.7%	32.56	34.4 GB	3.9%	10010.10
Oct 27 06:00:00 - 06:59:59	112172	3.6%	31.16	37.1 GB	4.2%	10807.51
Oct 27 07:00:00 - 07:59:59	132844	4.2%	36.90	42.0 GB	4.8%	12238.16
Oct 27 08:00:00 - 08:59:59	124718	4.0%	34.64	41.1 GB	4.7%	11981.47
Oct 27 09:00:00 - 09:59:59	127865	4.1%	35.52	40.7 GB	4.6%	11847.32
Oct 27 10:00:00 - 10:59:59	130210	4.2%	36.17	39.8 GB	4.5%	11590.60
Oct 27 11:00:00 - 11:59:59	126822	4.1%	35.23	38.4 GB	4.4%	11188.47
Oct 27 12:00:00 - 12:59:59	143125	4.6%	39.76	36.8 GB	4.2%	10706.14
Oct 27 13:00:00 - 13:59:59	139141	4.4%	38.65	35.1 GB	4.0%	10237.97
Oct 27 14:00:00 - 14:59:59	120910	3.9%	33.59	38.8 GB	4.4%	11299.45
Oct 27 15:00:00 - 15:59:59	122503	3.9%	34.03	37.1 GB	4.2%	10810.44
Oct 27 16:00:00 - 16:59:59	120179	3.8%	33.38	37.9 GB	4.3%	11045.71
Oct 27 17:00:00 - 17:59:59	127215	4.1%	35.34	39.2 GB	4.5%	11405.00
Oct 27 18:00:00 - 18:59:59	140800	4.5%	39.11	40.7 GB	4.6%	11867.37
Oct 27 19:00:00 - 19:59:59	173439	5.5%	48.18	36.0 GB	4.1%	10497.91
Oct 27 20:00:00 - 20:59:59	134144	4.3%	37.26	35.6 GB	4.1%	10382.36
Oct 27 21:00:00 - 21:59:59	142024	4.5%	39.45	38.1 GB	4.3%	11099.15
Oct 27 22:00:00 - 22:59:59	153218	4.9%	42.56	38.8 GB	4.4%	11298.50
Oct 27 23:00:00 - 23:59:59	162898	5.2%	45.25	38.2 GB	4.4%	11121.02
Oct 28 00:00:00 - 00:59:59	153474	4.9%	42.63	35.2 GB	4.0%	10245.67
Oct 28 01:00:00 - 01:59:59	133556	4.3%	37.10	33.4 GB	3.8%	9737.31
Oct 28 02:00:00 - 02:59:59	106046	3.4%	29.46	27.5 GB	3.1%	8016.42
Oct 28 03:00:00 - 03:59:59	77928	2.5%	21.65	20.6 GB	2.4%	6005.96
Oct 28 04:00:00 - 04:15:03	13273	0.4%	14.70	4.1 GB	0.5%	4715.67
TOTAL: 24:00:01	3129070	100.0%	36.22	876.6 GB	100.0%	10638.50

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	33334	135	18097	2	15100
2	news.nask.pl	24453	19	369	1	24064
3	news.icm.edu.pl	24	0	1	0	23
TOTAL: 3		57811	154	18467	3	39187

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	88
it.comp.os.win.windows10	31
comp.mobile.android	8
it.discussioni.energia	7
de.comm.provider.telefonie	5
comp.sys.raspberry-pi	4
it.tlc.cellulari.android	3
comp.mobile.ipad	3
it.comp.os.win.windows7	2
rec.arts.tv.x-factor	2
de.rec.sf.startrek	1
TOTAL: 11	154

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
Binary in non-binary group	32978
EMP (md5)	5514
Scoring filter (8)	43
Scoring filter (9)	33
Scoring filter (10)	13
Scoring filter (11)	9
HTML post	5
Poison newsgroup	3
Scoring filter (12)	2
Scoring filter (13)	1
Too many newsgroups	1
EMP (fsl)	1
Scoring filter (17)	1
TOTAL: 13	38604

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	355
	news.nask.pl	227
	news.icm.edu.pl	1
	TOTAL: 3	583
TOTAL: 1		583

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	poznan	16397	617	9259	52	0	0	3%	23:59:34
2	panorama	33890	520	17018	79	7	35120	1%	23:58:40
3	icm	24334	87	19256	249	0	0	0%	23:59:58
4	nask	3892	13	2825	1	0	0	0%	23:59:58
5	okapi	0	0	0	0	0	1174	0%	23:57:51
TOTAL: 5		78513	1237	48358	381	7	36294	1%	119:56:01

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	8.5 MB	63.1 MB	71.6 MB	0.8 KB/s	218.1 KB	23:59:58
2	panorama	2.5 MB	409.7 KB	2.9 MB	0.0 KB/s	4.9 KB	23:58:40
3	poznan	2.4 MB	251.3 KB	2.6 MB	0.0 KB/s	4.0 KB	23:59:34
4	nask	18.7 KB	1.1 KB	19.8 KB	0.0 KB/s	1.4 KB	23:59:58
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:51
TOTAL: 5		13.3 MB	63.7 MB	77.0 MB	0.2 KB/s	48.8 KB	119:56:01

NNRP readership statistics:

System		Conn	Post	Elapsed
1	david.man.lodz.pl	96	0	00:00:01
2	isvcs01.man.lodz.pl	8	8	00:00:02
3	web.man.lodz.pl	288	0	00:00:24
4	mon.man.lodz.pl	288	0	00:00:04
TOTAL: 4		680	8	00:00:31

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.man.lodz.pl	680	0	0.0 KB	0	8	0	00:00:31
TOTAL: 1		680	0	0.0 KB	0	8	0	00:00:31

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.248	12.240	00:00:24
mon.man.lodz.pl	0.996	11.852	00:00:04
isvcs01.man.lodz.pl	0.052	0.396	00:00:02
david.man.lodz.pl	0.312	3.936	00:00:01
TOTAL: 4	2.608	28.424	00:00:31

Daily Usenet report

Oct 27 04:15:02 -- Oct 28 04:15:03