Daily Usenet report: Jan 26 04:15:02

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Jan 26 09:00:44 newsfeed nnrpd[11043]: SERVER perl filter not defined
Jan 26 10:09:05 newsfeed overchan[32089]: input line too long, skipping
Jan 26 11:05:24 newsfeed overchan[32089]: input line too long, skipping
Jan 26 15:31:43 newsfeed nnrpd[25952]: SERVER perl filter not defined
Jan 26 17:23:06 newsfeed overchan[32089]: input line too long, skipping
Jan 26 18:44:22 newsfeed overchan[32089]: input line too long, skipping
Jan 26 19:42:38 newsfeed overchan[32089]: input line too long, skipping
Jan 26 20:23:19 newsfeed overchan[32089]: input line too long, skipping
Jan 26 22:46:54 newsfeed overchan[32089]: input line too long, skipping
Jan 27 03:48:27 newsfeed overchan[32089]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3710939	98.0%	420.1 MB	97.7%
innfeed	44229	1.2%	5.2 MB	1.2%
innd	26813	0.7%	4.3 MB	1.0%
nnrpd	4786	0.1%	503.5 KB	0.1%
overchan	9	0.0%	1.6 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	3786777	100.0%	430.1 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3692666	96.5%
Negative hits	122986	3.2%
Positive hits	11385	0.3%
Cache misses	70	0.0%
TOTAL: 4	3827107	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.693	0.0%	110	0.000	24.482	176.000
article cleanup	00:00:42.862	0.0%	3718161	0.008	0.012	0.016
article logging	00:02:40.416	0.2%	3718723	0.027	0.043	0.091
article parse	00:46:45.328	3.2%	88741708	0.008	0.032	0.049
article write	00:19:44.582	1.4%	3709648	0.180	0.319	0.596
artlog/artcncl	00:00:00.008	0.0%	110	0.000	0.073	1.000
data move	00:03:05.908	0.2%	104602349	0.001	0.002	0.004
hisgrep/artcncl	00:00:00.461	0.0%	107	0.000	4.308	49.000
hishave/artcncl	00:00:00.120	0.0%	110	0.000	1.091	7.909
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:12:19.430	5.0%	3850534	0.287	1.127	4.435
history sync	00:00:00.013	0.0%	290	0.000	0.045	0.500
history write	03:56:08.389	16.4%	3715135	1.261	3.814	11.007
hiswrite/artcncl	00:00:00.028	0.0%	3	0.000	9.333	28.000
idle	10:09:39.017	42.3%	62542801	0.305	0.585	2.399
nntp read	00:56:45.554	3.9%	85814568	0.023	0.040	0.056
overview write	00:00:20.463	0.0%	3709648	0.004	0.006	0.008
perl filter	05:51:40.585	24.4%	3715135	3.462	5.680	8.446
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:07:10.971	0.5%	7440078	0.038	0.058	0.078
TOTAL: 24:00:03.856	23:27:06.828	97.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.187	0.0%	57725	0.000	0.003	0.030
article prepare	00:00:00.004	0.0%	11116	0.000	0.000	0.021
article read	00:01:40.049	0.1%	2779	0.000	36.002	368.667
backlog stats	00:00:00.140	0.0%	167658	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	27	0.000	0.000	0.000
data read	00:00:01.741	0.0%	90052	0.004	0.019	0.035
data write	00:00:04.630	0.0%	70407	0.026	0.066	0.242
idle	23:51:25.629	99.8%	167658	345.568	512.267	941.245
status file	00:00:20.349	0.0%	15650	0.667	1.300	18.084
TOTAL: 23:53:44.318	23:53:32.729	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:37:55.946	99.5%	6569	0.000	346.468	266360.857
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.537	0.0%	13802	0.000	0.039	0.947
readart	00:00:00.047	0.0%	49	0.000	0.959	14.333
TOTAL: 00:38:08.520	00:37:56.530	99.5%	-	-	-	-

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	571	3702077	3691357	7056	3664	99%	522:33:25
2	news.nask.pl	2	16799	10870	1074	4855	64%	24:02:55
3	news.icm.edu.pl	49	285	19	259	7	6%	16:40:46
4	localhost	3	2	2	0	0	100%	00:00:00
TOTAL: 4		625	3719163	3702248	8389	8526	99%	563:17:06

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	660.1 GB	29.3 MB	150.1 MB	660.3 GB	99%	187.4 KB
2	news.nask.pl	67.0 MB	682.7 KB	22.6 MB	90.3 MB	74%	5.9 KB
3	news.icm.edu.pl	650.9 KB	0.0 KB	360.6 KB	1011.5 KB	64%	38.9 KB
4	localhost	3.2 KB	0.0 KB	0.0 KB	3.2 KB	100%	1.6 KB
TOTAL: 4		660.2 GB	30.0 MB	173.1 MB	660.4 GB	99%	186.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 26 04:15:02 - 04:59:59	71680	1.9%	26.57	12.1 GB	1.8%	4712.34
Jan 26 05:00:00 - 05:59:59	122648	3.3%	34.07	23.1 GB	3.5%	6734.85
Jan 26 06:00:00 - 06:59:59	86927	2.3%	24.15	19.0 GB	2.9%	5532.03
Jan 26 07:00:00 - 07:59:59	122509	3.3%	34.03	29.3 GB	4.4%	8537.08
Jan 26 08:00:00 - 08:59:59	137887	3.7%	38.30	31.7 GB	4.8%	9244.07
Jan 26 09:00:00 - 09:59:59	125444	3.4%	34.85	33.6 GB	5.1%	9797.55
Jan 26 10:00:00 - 10:59:59	134231	3.6%	37.29	34.0 GB	5.1%	9909.69
Jan 26 11:00:00 - 11:59:59	129552	3.5%	35.99	34.8 GB	5.3%	10125.38
Jan 26 12:00:00 - 12:59:59	184013	5.0%	51.11	33.4 GB	5.1%	9742.17
Jan 26 13:00:00 - 13:59:59	160728	4.3%	44.65	20.8 GB	3.1%	6053.55
Jan 26 14:00:00 - 14:59:59	179610	4.9%	49.89	29.1 GB	4.4%	8482.03
Jan 26 15:00:00 - 15:59:59	252636	6.8%	70.18	32.1 GB	4.8%	9338.67
Jan 26 16:00:00 - 16:59:59	199803	5.4%	55.50	32.0 GB	4.8%	9311.82
Jan 26 17:00:00 - 17:59:59	177601	4.8%	49.33	36.4 GB	5.5%	10609.21
Jan 26 18:00:00 - 18:59:59	141573	3.8%	39.33	30.9 GB	4.7%	8989.15
Jan 26 19:00:00 - 19:59:59	131403	3.5%	36.50	25.9 GB	3.9%	7542.04
Jan 26 20:00:00 - 20:59:59	143651	3.9%	39.90	26.1 GB	3.9%	7598.07
Jan 26 21:00:00 - 21:59:59	164336	4.4%	45.65	28.0 GB	4.2%	8153.39
Jan 26 22:00:00 - 22:59:59	152249	4.1%	42.29	25.1 GB	3.8%	7300.93
Jan 26 23:00:00 - 23:59:59	122555	3.3%	34.04	21.1 GB	3.2%	6145.09
Jan 27 00:00:00 - 00:59:59	141299	3.8%	39.25	21.1 GB	3.2%	6148.39
Jan 27 01:00:00 - 01:59:59	226842	6.1%	63.01	30.1 GB	4.6%	8781.20
Jan 27 02:00:00 - 02:59:59	178639	4.8%	49.62	24.6 GB	3.7%	7153.70
Jan 27 03:00:00 - 03:59:59	181819	4.9%	50.51	22.8 GB	3.5%	6651.66
Jan 27 04:00:00 - 04:15:03	32103	0.9%	35.55	4.0 GB	0.6%	4678.91
TOTAL: 24:00:01	3701738	100.0%	42.84	661.2 GB	100.0%	8024.37

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	5074	17	238	2	4817
2	newsfeed.pionier.net.pl	4010	98	2794	2	1116
3	news.icm.edu.pl	7	0	0	0	7
TOTAL: 3		9091	115	3032	4	5940

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	55
it.tlc.cellulari.android	31
comp.mobile.android	11
de.rec.sf.startrek	8
it.comp.os.win.windows10	5
comp.sys.raspberry-pi	2
it.discussioni.energia	1
it.politica.m5s	1
comp.mobile.ipad	1
TOTAL: 9	115

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	4455
Binary in non-binary group	825
Scoring filter (8)	27
Scoring filter (9)	23
Scoring filter (10)	21
Scoring filter (18)	6
Poison newsgroup	6
HTML post	4
Scoring filter (11)	3
Scoring filter (16)	2
Scoring filter (14)	1
Too many newsgroups	1
Scoring filter (13)	1
Scoring filter (12)	1
TOTAL: 14	5376

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	356
	news.nask.pl	208
	TOTAL: 2	564
TOTAL: 1		564

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	43176	905	15954	195	86	40708	2%	23:59:57
2	poznan	17139	430	10345	25	0	1	2%	23:59:54
3	icm	24855	192	19748	158	0	1	0%	23:59:49
4	nask	2859	4	2418	0	0	0	0%	23:58:50
5	okapi	0	0	0	0	0	1186	0%	23:57:50
TOTAL: 5		88029	1531	48465	378	86	41896	1%	119:56:20

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	64.7 MB	41.5 MB	106.1 MB	1.3 KB/s	310.5 KB	23:59:49
2	panorama	6.2 MB	611.2 KB	6.8 MB	0.1 KB/s	6.3 KB	23:59:57
3	poznan	1.6 MB	195.0 KB	1.8 MB	0.0 KB/s	4.1 KB	23:59:54
4	nask	101.9 KB	0.0 KB	101.9 KB	0.0 KB/s	25.5 KB	23:58:50
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:50
TOTAL: 5		72.6 MB	42.3 MB	114.8 MB	0.3 KB/s	61.6 KB	119:56:20

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	46	79.8 KB	23	0	00:00:25
2	pc-vicki.man.lodz.pl	1	3	10.5 KB	1	0	00:31:04
3	isvcs01.man.lodz.pl	2	0	0.0 KB	0	2	00:00:00
TOTAL: 3		291	49	90.3 KB	24	2	00:38:07

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	675	49	90.3 KB	24	2	00:31:35
2	unresolved	3	0	0.0 KB	0	0	00:06:32
TOTAL: 2		678	49	90.3 KB	24	2	00:38:07

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-vicki.man.lodz.pl	0.004	0.024	00:31:04
185.48.195.160	0.236	0.432	00:06:32
web.man.lodz.pl	1.752	6.104	00:00:25
mon.man.lodz.pl	1.448	5.116	00:00:03
david.man.lodz.pl	0.524	2.020	00:00:01
isvcs01.man.lodz.pl	0.016	0.016	00:00:00
TOTAL: 6	3.980	13.712	00:38:07

Curious NNRP server explorers [Top 20]:

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
185.48.195.160	1	1
pc-vicki.man.lodz.pl	1	0
TOTAL: 2	2	1

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.pecet	24
2	pl.comp.os.ms-windows.winnt	22
3	pl.sci.medycyna	3
TOTAL: 3		49

Daily Usenet report

Jan 26 04:15:02 -- Jan 27 04:15:03