Daily Usenet report: Feb 25 04:15:03

Unknown entries from news log file:

First 18 / 18 lines (100.0%)

Feb 25 05:43:55 newsfeed overchan[24976]: input line too long, skipping
Feb 25 05:48:06 newsfeed overchan[24976]: input line too long, skipping
Feb 25 08:53:28 newsfeed nnrpd[3621]: SERVER perl filter not defined
Feb 25 09:00:55 newsfeed nnrpd[3975]: SERVER perl filter not defined
Feb 25 09:12:38 newsfeed nnrpd[4372]: SERVER perl filter not defined
Feb 25 09:20:18 newsfeed nnrpd[4695]: SERVER perl filter not defined
Feb 25 10:19:09 newsfeed overchan[24976]: input line too long, skipping
Feb 25 10:22:16 newsfeed nnrpd[6938]: SERVER perl filter not defined
Feb 25 10:48:09 newsfeed nnrpd[7925]: SERVER perl filter not defined
Feb 25 10:59:37 newsfeed nnrpd[8302]: SERVER perl filter not defined
Feb 25 14:42:11 newsfeed overchan[24976]: input line too long, skipping
Feb 25 16:04:41 newsfeed overchan[24976]: input line too long, skipping
Feb 25 16:04:50 newsfeed overchan[24976]: input line too long, skipping
Feb 25 16:05:02 newsfeed overchan[24976]: input line too long, skipping
Feb 25 17:44:13 newsfeed nnrpd[23388]: SERVER perl filter not defined
Feb 25 19:16:11 newsfeed nnrpd[26783]: SERVER perl filter not defined
Feb 25 21:06:15 newsfeed overchan[24976]: input line too long, skipping
Feb 25 21:24:59 newsfeed overchan[24976]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3716004	98.0%	425.9 MB	97.7%
innfeed	44583	1.2%	5.3 MB	1.2%
innd	26622	0.7%	4.3 MB	1.0%
nnrpd	4824	0.1%	509.7 KB	0.1%
overchan	11	0.0%	2.0 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	3792045	100.0%	436.1 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3673049	98.9%
Negative hits	32088	0.9%
Positive hits	10523	0.3%
Cache misses	109	0.0%
TOTAL: 4	3715769	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.453	0.0%	185	0.000	7.854	75.800
article cleanup	00:00:42.165	0.0%	3716268	0.009	0.011	0.014
article logging	00:02:32.825	0.2%	3716918	0.030	0.041	0.107
article parse	00:48:29.660	3.4%	75401051	0.022	0.039	0.048
article write	00:18:25.649	1.3%	3704096	0.165	0.298	0.394
artlog/artcncl	00:00:00.005	0.0%	175	0.000	0.029	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:04:28.884	0.3%	88895856	0.001	0.003	0.004
hisgrep/artcncl	00:00:00.657	0.0%	177	0.000	3.712	56.700
hishave/artcncl	00:00:00.017	0.0%	185	0.000	0.092	1.067
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:15:16.010	1.1%	3749964	0.013	0.244	3.641
history sync	00:00:00.017	0.0%	290	0.000	0.059	0.500
history write	01:06:27.014	4.6%	3706766	0.122	1.076	6.690
hiswrite/artcncl	00:00:00.001	0.0%	8	0.000	0.125	0.500
idle	14:38:49.702	61.0%	59190003	0.640	0.891	3.058
nntp read	01:06:38.057	4.6%	73468985	0.035	0.054	0.072
overview write	00:00:18.991	0.0%	3704096	0.004	0.005	0.006
perl filter	05:01:12.984	20.9%	3706765	3.041	4.876	6.163
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:07:35.624	0.5%	7425395	0.039	0.061	0.076
TOTAL: 24:00:05.156	23:30:59.715	98.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.150	0.0%	52893	0.000	0.003	0.016
article prepare	00:00:00.002	0.0%	13078	0.000	0.000	0.014
article read	00:01:13.853	0.1%	3262	2.143	22.640	204.474
backlog stats	00:00:00.122	0.0%	164574	0.000	0.001	0.006
callbacks	00:00:00.000	0.0%	18	0.000	0.000	0.000
data read	00:00:01.554	0.0%	84823	0.003	0.018	0.037
data write	00:00:03.873	0.0%	67849	0.024	0.057	0.111
idle	23:52:10.761	99.9%	164574	308.922	522.141	1100.842
status file	00:00:13.919	0.0%	15643	0.608	0.890	1.656
TOTAL: 23:54:01.421	23:53:44.234	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:17.904	55.1%	3101	0.000	5.774	83.556
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.331	1.0%	6842	0.000	0.048	1.211
readart	00:00:00.003	0.0%	56	0.000	0.054	1.500
TOTAL: 00:00:32.470	00:00:18.238	56.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	1200	3702633	3692866	256	9511	99%	337:34:14
2	news.nask.pl	3	13741	10565	524	2652	76%	23:52:32
3	localhost	10	9	9	0	0	100%	00:00:00
4	news.icm.edu.pl	44	264	6	252	6	2%	16:31:41
TOTAL: 4		1257	3716647	3703446	1032	12169	99%	377:58:27

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	656.8 GB	35.4 MB	36.4 MB	656.9 GB	99%	186.0 KB
2	news.nask.pl	40.3 MB	584.5 KB	11.0 MB	51.8 MB	77%	4.0 KB
3	news.icm.edu.pl	98.2 KB	17.4 KB	183.7 KB	299.3 KB	32%	24.9 KB
4	localhost	13.6 KB	0.0 KB	0.0 KB	13.6 KB	100%	1.5 KB
TOTAL: 4		656.8 GB	36.0 MB	47.6 MB	656.9 GB	99%	185.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 25 04:15:03 - 04:59:59	99619	2.7%	36.94	19.5 GB	3.0%	7569.56
Feb 25 05:00:00 - 05:59:59	146218	3.9%	40.62	28.8 GB	4.4%	8387.79
Feb 25 06:00:00 - 06:59:59	139870	3.8%	38.85	26.5 GB	4.0%	7719.81
Feb 25 07:00:00 - 07:59:59	143016	3.9%	39.73	27.4 GB	4.2%	7991.91
Feb 25 08:00:00 - 08:59:59	148604	4.0%	41.28	28.7 GB	4.4%	8361.70
Feb 25 09:00:00 - 09:59:59	201159	5.4%	55.88	28.5 GB	4.3%	8305.02
Feb 25 10:00:00 - 10:59:59	150415	4.1%	41.78	27.0 GB	4.1%	7859.26
Feb 25 11:00:00 - 11:59:59	154230	4.2%	42.84	27.9 GB	4.3%	8140.57
Feb 25 12:00:00 - 12:59:59	152869	4.1%	42.46	28.7 GB	4.4%	8348.76
Feb 25 13:00:00 - 13:59:59	156983	4.2%	43.61	27.5 GB	4.2%	8014.39
Feb 25 14:00:00 - 14:59:59	154702	4.2%	42.97	29.1 GB	4.4%	8465.70
Feb 25 15:00:00 - 15:59:59	152716	4.1%	42.42	29.6 GB	4.5%	8607.07
Feb 25 16:00:00 - 16:59:59	130606	3.5%	36.28	27.6 GB	4.2%	8031.32
Feb 25 17:00:00 - 17:59:59	148065	4.0%	41.13	30.0 GB	4.6%	8743.49
Feb 25 18:00:00 - 18:59:59	178940	4.8%	49.71	30.5 GB	4.7%	8888.65
Feb 25 19:00:00 - 19:59:59	170677	4.6%	47.41	29.0 GB	4.4%	8451.30
Feb 25 20:00:00 - 20:59:59	195191	5.3%	54.22	31.5 GB	4.8%	9181.19
Feb 25 21:00:00 - 21:59:59	174612	4.7%	48.50	31.1 GB	4.7%	9046.83
Feb 25 22:00:00 - 22:59:59	175852	4.7%	48.85	29.9 GB	4.6%	8703.29
Feb 25 23:00:00 - 23:59:59	176441	4.8%	49.01	28.9 GB	4.4%	8429.45
Feb 26 00:00:00 - 00:59:59	106873	2.9%	29.69	14.1 GB	2.1%	4103.44
Feb 26 01:00:00 - 01:59:59	144090	3.9%	40.02	25.5 GB	3.9%	7414.28
Feb 26 02:00:00 - 02:59:59	144003	3.9%	40.00	24.2 GB	3.7%	7037.51
Feb 26 03:00:00 - 03:59:59	126539	3.4%	35.15	19.6 GB	3.0%	5718.30
Feb 26 04:00:00 - 04:15:02	30708	0.8%	34.04	4.8 GB	0.7%	5623.88
TOTAL: 23:59:59	3702998	100.0%	42.86	655.9 GB	100.0%	7959.72

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	9889	169	9304	0	416
2	news.nask.pl	2937	17	198	2	2720
3	news.icm.edu.pl	6	0	2	0	4
TOTAL: 3		12832	186	9504	2	3140

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	93
it.tlc.cellulari.android	50
it.comp.os.win.windows10	23
comp.sys.raspberry-pi	10
de.comp.office-pakete.ms-office.word	4
comp.mobile.android	3
comp.mobile.ipad	1
it.comp.os.win.windows7	1
it.comp.os.linux.ubuntu	1
TOTAL: 9	186

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

Groups	Count
bit.listserv.catholic	1
pl.sci.matematyka	1
TOTAL: 2	2

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2312
Binary in non-binary group	97
EMP (phl)	39
Scoring filter (8)	22
Scoring filter (9)	6
Too many newsgroups	5
Scoring filter (16)	4
Scoring filter (12)	2
HTML post	2
Scoring filter (10)	1
TOTAL: 10	2490

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	378
	news.nask.pl	271
	TOTAL: 2	649
TOTAL: 1		649

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	40810	1148	13417	221	46	38239	2%	23:59:57
2	poznan	18355	829	9752	34	1	1	4%	23:59:45
3	icm	23973	38	17071	7	0	0	0%	23:59:56
4	nask	1474	2	1035	0	0	0	0%	23:27:02
5	okapi	0	0	0	0	0	1282	0%	23:57:50
TOTAL: 5		84612	2017	41275	262	47	39522	2%	119:24:30

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	6.8 MB	707.7 KB	7.5 MB	0.1 KB/s	5.6 KB	23:59:57
2	icm	3.9 MB	20.6 KB	3.9 MB	0.0 KB/s	89.6 KB	23:59:56
3	poznan	2.7 MB	111.7 KB	2.8 MB	0.0 KB/s	3.4 KB	23:59:45
4	nask	5.1 KB	0.0 KB	5.1 KB	0.0 KB/s	2.5 KB	23:27:02
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:50
TOTAL: 5		13.5 MB	839.9 KB	14.3 MB	0.0 KB/s	6.4 KB	119:24:30

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	56	63.4 KB	24	0	00:00:25
2	isvcs01.man.lodz.pl	9	0	0.0 KB	0	9	00:00:00
TOTAL: 2		297	56	63.4 KB	24	9	00:00:31

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.man.lodz.pl	681	56	63.4 KB	24	9	0	00:00:31
TOTAL: 1		681	56	63.4 KB	24	9	0	00:00:31

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.808	4.148	00:00:25
mon.man.lodz.pl	1.416	3.496	00:00:03
david.man.lodz.pl	0.560	1.004	00:00:01
isvcs01.man.lodz.pl	0.100	0.080	00:00:00
TOTAL: 4	3.884	8.728	00:00:31

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
TOTAL: 2	384

Newsgroup request counts (by category):

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

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

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

Daily Usenet report

Feb 25 04:15:03 -- Feb 26 04:15:02