Daily Usenet report: Dec 22 04:15:03

Unknown entries from news log file:

First 13 / 13 lines (100.0%)

Dec 22 09:38:20 newsfeed nnrpd[7263]: SERVER perl filter not defined
Dec 22 09:57:09 newsfeed nnrpd[7952]: SERVER perl filter not defined
Dec 22 12:31:19 newsfeed nnrpd[13651]: SERVER perl filter not defined
Dec 22 13:37:53 newsfeed nnrpd[15941]: SERVER perl filter not defined
Dec 22 17:51:52 newsfeed overchan[27199]: input line too long, skipping
Dec 22 18:00:01 newsfeed overchan[27199]: input line too long, skipping
Dec 22 18:14:54 newsfeed overchan[27199]: input line too long, skipping
Dec 22 18:45:00 newsfeed overchan[27199]: input line too long, skipping
Dec 22 19:04:04 newsfeed overchan[27199]: input line too long, skipping
Dec 22 19:08:28 newsfeed overchan[27199]: input line too long, skipping
Dec 22 21:17:03 newsfeed overchan[27199]: input line too long, skipping
Dec 23 00:25:44 newsfeed overchan[27199]: input line too long, skipping
Dec 23 00:26:24 newsfeed overchan[27199]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	4429512	98.2%	501.6 MB	97.9%
innfeed	45961	1.0%	5.4 MB	1.1%
innd	31267	0.7%	5.1 MB	1.0%
nnrpd	4784	0.1%	503.8 KB	0.1%
overchan	10	0.0%	1.8 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	4511535	100.0%	512.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	4399786	97.4%
Negative hits	105722	2.3%
Positive hits	12773	0.3%
Cache misses	129	0.0%
TOTAL: 4	4518410	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:11.740	0.0%	154	0.000	76.234	1534.500
article cleanup	00:00:58.422	0.1%	4429253	0.010	0.013	0.017
article logging	00:02:47.009	0.2%	4429761	0.023	0.038	0.108
article parse	01:05:52.303	4.6%	103419042	0.023	0.038	0.067
article write	00:24:27.492	1.7%	4419004	0.194	0.332	0.556
artlog/artcncl	00:00:00.003	0.0%	152	0.000	0.020	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:03:58.264	0.3%	122007531	0.001	0.002	0.003
hisgrep/artcncl	00:00:01.073	0.0%	153	0.000	7.013	191.500
hishave/artcncl	00:00:00.334	0.0%	154	0.000	2.169	27.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:27:39.850	6.1%	4548504	0.350	1.156	6.563
history sync	00:00:00.009	0.0%	300	0.000	0.030	0.500
history write	04:33:38.645	19.0%	4426442	1.504	3.709	11.387
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
idle	06:53:23.174	28.7%	60063661	0.105	0.413	0.823
nntp read	01:05:39.163	4.6%	99615095	0.024	0.040	0.061
overview write	00:00:24.635	0.0%	4419004	0.004	0.006	0.007
perl filter	07:42:18.922	32.1%	4426422	4.193	6.267	7.994
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:06:58.455	0.5%	8855884	0.038	0.047	0.060
TOTAL: 24:00:12.247	23:28:19.493	97.8%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.152	0.0%	57207	0.000	0.003	0.009
article prepare	00:00:00.002	0.0%	8338	0.000	0.000	0.025
article read	00:01:23.760	0.1%	2074	0.000	40.386	231.875
backlog stats	00:00:00.147	0.0%	150757	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	10	0.000	0.000	0.000
data read	00:00:01.380	0.0%	80697	0.005	0.017	0.033
data write	00:00:03.660	0.0%	63326	0.025	0.058	0.126
idle	23:51:21.239	99.8%	150757	371.625	569.667	1123.818
status file	00:00:28.135	0.0%	16167	0.600	1.740	17.340
TOTAL: 23:53:37.290	23:53:18.475	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:26:15.978	99.0%	14057	0.000	112.113	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.903	0.1%	28754	0.000	0.031	1.000
readart	00:00:00.002	0.0%	40	0.000	0.050	0.500
TOTAL: 00:26:31.666	00:26:16.883	99.1%	-	-	-	-

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	281	4421837	4408177	8627	5033	99%	540:37:30
2	news.nask.pl	2	16561	10314	1054	5193	62%	24:11:43
3	news.icm.edu.pl	41	299	16	279	4	5%	17:54:25
4	localhost	5	4	4	0	0	100%	00:00:01
TOTAL: 4		329	4438701	4418511	9960	10230	99%	582:43:39

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	896.2 GB	213.1 MB	554.3 MB	897.0 GB	99%	213.1 KB
2	news.nask.pl	58.5 MB	1.8 MB	220.0 MB	280.4 MB	20%	18.5 KB
3	news.icm.edu.pl	516.1 KB	0.0 KB	265.6 KB	781.7 KB	66%	39.1 KB
4	localhost	5.2 KB	0.0 KB	0.0 KB	5.2 KB	100%	1.3 KB
TOTAL: 4		896.3 GB	214.9 MB	774.6 MB	897.2 GB	99%	212.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 22 04:15:03 - 04:59:59	117774	2.7%	43.67	25.2 GB	2.8%	9801.51
Dec 22 05:00:00 - 05:59:59	164699	3.7%	45.75	37.4 GB	4.2%	10905.18
Dec 22 06:00:00 - 06:59:59	153657	3.5%	42.68	37.1 GB	4.1%	10809.33
Dec 22 07:00:00 - 07:59:59	161783	3.7%	44.94	39.3 GB	4.4%	11459.06
Dec 22 08:00:00 - 08:59:59	195325	4.4%	54.26	37.5 GB	4.2%	10916.78
Dec 22 09:00:00 - 09:59:59	182508	4.1%	50.70	40.1 GB	4.5%	11669.89
Dec 22 10:00:00 - 10:59:59	203211	4.6%	56.45	40.1 GB	4.5%	11684.66
Dec 22 11:00:00 - 11:59:59	203151	4.6%	56.43	40.3 GB	4.5%	11739.46
Dec 22 12:00:00 - 12:59:59	254805	5.8%	70.78	39.3 GB	4.4%	11440.66
Dec 22 13:00:00 - 13:59:59	252055	5.7%	70.02	37.7 GB	4.2%	10974.15
Dec 22 14:00:00 - 14:59:59	177345	4.0%	49.26	37.5 GB	4.2%	10918.62
Dec 22 15:00:00 - 15:59:59	165591	3.7%	46.00	33.4 GB	3.7%	9716.27
Dec 22 16:00:00 - 16:59:59	164440	3.7%	45.68	36.4 GB	4.1%	10598.30
Dec 22 17:00:00 - 17:59:59	173344	3.9%	48.15	37.7 GB	4.2%	10973.59
Dec 22 18:00:00 - 18:59:59	183972	4.2%	51.10	38.3 GB	4.3%	11157.13
Dec 22 19:00:00 - 19:59:59	190150	4.3%	52.82	41.4 GB	4.6%	12064.52
Dec 22 20:00:00 - 20:59:59	186455	4.2%	51.79	37.7 GB	4.2%	10976.66
Dec 22 21:00:00 - 21:59:59	195191	4.4%	54.22	39.5 GB	4.4%	11515.34
Dec 22 22:00:00 - 22:59:59	194421	4.4%	54.01	38.6 GB	4.3%	11233.21
Dec 22 23:00:00 - 23:59:59	186668	4.2%	51.85	37.1 GB	4.1%	10819.77
Dec 23 00:00:00 - 00:59:59	158388	3.6%	44.00	31.3 GB	3.5%	9126.67
Dec 23 01:00:00 - 01:59:59	178085	4.0%	49.47	38.2 GB	4.3%	11119.52
Dec 23 02:00:00 - 02:59:59	188988	4.3%	52.50	38.5 GB	4.3%	11227.12
Dec 23 03:00:00 - 03:59:59	136003	3.1%	37.78	30.1 GB	3.4%	8767.29
Dec 23 04:00:00 - 04:15:03	50591	1.1%	56.03	7.3 GB	0.8%	8427.07
TOTAL: 24:00:00	4418600	100.0%	51.14	897.0 GB	100.0%	10886.25

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	5438	18	169	5	5246
2	newsfeed.pionier.net.pl	5312	121	2642	1	2548
3	news.icm.edu.pl	10	0	0	0	10
TOTAL: 3		10760	139	2811	6	7804

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	73
it.tlc.cellulari.android	21
comp.mobile.android	13
it.comp.os.win.windows10	13
comp.mobile.ipad	9
it.comp.os.linux.ubuntu	3
uk.rec.cycling.moderated	3
comp.sys.raspberry-pi	2
it.comp.os.win.windows7	2
TOTAL: 9	139

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

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

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	3855
EMP (md5)	3338
Scoring filter (9)	26
Scoring filter (8)	16
HTML post	13
EMP (phl)	10
Scoring filter (11)	5
Scoring filter (16)	4
Too many newsgroups	3
Scoring filter (10)	2
Scoring filter (13)	1
Scoring filter (12)	1
Scoring filter (14)	1
Scoring filter (15)	1
TOTAL: 14	7276

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	277
	news.nask.pl	231
	TOTAL: 2	508
TOTAL: 1		508

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	43639	910	15603	177	21	40710	2%	23:59:57
2	poznan	15316	39	10117	22	0	0	0%	23:59:57
3	icm	21137	29	17389	99	0	0	0%	23:59:36
4	nask	2119	1	1649	0	0	0	0%	23:54:50
5	okapi	0	0	0	0	0	1245	0%	23:57:50
TOTAL: 5		82211	979	44758	298	21	41955	1%	119:52:10

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	9.8 MB	622.9 KB	10.4 MB	0.1 KB/s	9.8 KB	23:59:57
2	icm	3.5 MB	32.0 MB	35.5 MB	0.4 KB/s	284.0 KB	23:59:36
3	poznan	290.0 KB	170.4 KB	460.4 KB	0.0 KB/s	7.5 KB	23:59:57
4	nask	1.1 KB	0.0 KB	1.1 KB	0.0 KB/s	1.1 KB	23:54:50
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:50
TOTAL: 5		13.6 MB	32.8 MB	46.4 MB	0.1 KB/s	37.2 KB	119:52:10

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	40	43.2 KB	17	0	00:00:29
2	isvcs01.man.lodz.pl	4	0	0.0 KB	0	4	00:00:00
TOTAL: 2		292	40	43.2 KB	17	4	00:26:30

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	676	40	43.2 KB	17	4	00:00:35
2	unresolved	3	0	0.0 KB	0	0	00:25:55
TOTAL: 2		679	40	43.2 KB	17	4	00:26:30

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.48.192.177	0.652	1.172	00:25:55
web.man.lodz.pl	1.520	8.384	00:00:29
mon.man.lodz.pl	1.188	7.692	00:00:04
david.man.lodz.pl	0.372	2.256	00:00:01
isvcs01.man.lodz.pl	0.052	0.112	00:00:00
TOTAL: 5	3.784	19.616	00:26:30

Curious NNRP server explorers [Top 20]:

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

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.os.ms-windows.winnt	38
2	pl.comp.pecet	2
TOTAL: 2		40

Daily Usenet report

Dec 22 04:15:03 -- Dec 23 04:15:03