Daily Usenet report: Nov 3 04:15:02

Unknown entries from news log file:

First 13 / 13 lines (100.0%)

Nov 3 08:19:12 newsfeed nnrpd[9314]: SERVER perl filter not defined
Nov 3 09:57:50 newsfeed nnrpd[12880]: SERVER perl filter not defined
Nov 3 10:07:37 newsfeed nnrpd[13267]: SERVER perl filter not defined
Nov 3 12:01:17 newsfeed nnrpd[17467]: SERVER perl filter not defined
Nov 3 13:56:37 newsfeed nnrpd[13342]: SERVER perl filter not defined
Nov 3 17:21:34 newsfeed overchan[2396]: input line too long, skipping
Nov 3 17:27:16 newsfeed overchan[2396]: input line too long, skipping
Nov 3 17:41:40 newsfeed overchan[2396]: input line too long, skipping
Nov 3 17:44:38 newsfeed overchan[2396]: input line too long, skipping
Nov 3 17:49:16 newsfeed overchan[2396]: input line too long, skipping
Nov 3 18:11:26 newsfeed overchan[2396]: input line too long, skipping
Nov 3 20:15:42 newsfeed nnrpd[7563]: SERVER perl filter not defined
Nov 3 22:18:53 newsfeed overchan[2396]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2650592	96.7%	306.1 MB	96.4%
innfeed	46214	1.7%	5.4 MB	1.7%
innd	39502	1.4%	5.6 MB	1.8%
nnrpd	4859	0.2%	511.1 KB	0.2%
overchan	8	0.0%	1.2 KB	0.0%
controlchan	2	0.0%	0.2 KB	0.0%
TOTAL: 6	2741177	100.0%	317.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2642157	93.1%
Negative hits	112717	4.0%
Cache misses	77981	2.7%
Positive hits	3640	0.1%
TOTAL: 4	2836495	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.507	0.0%	159	0.000	22.057	1238.000
article cleanup	00:00:36.031	0.0%	2646761	0.009	0.014	0.021
article logging	00:01:46.309	0.1%	2647320	0.025	0.040	0.092
article parse	00:48:40.196	3.4%	126774137	0.015	0.023	0.035
article write	00:20:07.404	1.4%	2623162	0.197	0.460	0.936
artlog/artcncl	00:00:00.010	0.0%	159	0.000	0.063	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:02:53.183	0.2%	141843756	0.001	0.001	0.002
hisgrep/artcncl	00:00:01.231	0.0%	142	0.000	8.669	233.333
hishave/artcncl	00:00:00.132	0.0%	159	0.000	0.830	6.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:11:56.179	5.0%	2851817	0.049	1.513	7.324
history sync	00:00:00.017	0.0%	292	0.000	0.058	1.000
history write	02:25:01.880	10.1%	2644806	0.216	3.290	11.767
hiswrite/artcncl	00:00:00.002	0.0%	17	0.000	0.118	1.000
idle	13:07:04.741	54.7%	102258069	0.243	0.462	6.096
nntp read	00:54:53.564	3.8%	124760701	0.018	0.026	0.044
overview write	00:00:14.169	0.0%	2623162	0.004	0.005	0.008
perl filter	04:38:44.393	19.4%	2644805	4.324	6.323	7.694
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:21.527	0.3%	5266442	0.034	0.050	0.071
TOTAL: 24:00:05.943	23:36:24.475	98.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.160	0.0%	60916	0.000	0.003	0.014
article prepare	00:00:00.002	0.0%	12846	0.000	0.000	0.013
article read	00:01:30.097	0.1%	3232	0.000	27.877	278.438
backlog stats	00:00:00.120	0.0%	148659	0.000	0.001	0.006
callbacks	00:00:00.000	0.0%	26	0.000	0.000	0.000
data read	00:00:01.471	0.0%	81729	0.000	0.018	0.041
data write	00:00:03.258	0.0%	63251	0.015	0.052	0.210
idle	23:52:11.836	99.9%	148659	127.747	578.047	2191.467
status file	00:00:13.713	0.0%	16158	0.528	0.849	2.577
TOTAL: 23:54:13.702	23:54:00.657	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:01:15.453	83.9%	3164	0.000	23.847	10893.800
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.195	0.2%	7172	0.000	0.027	1.000
readart	00:00:00.038	0.0%	134	0.000	0.284	9.000
TOTAL: 00:01:29.975	00:01:15.686	84.1%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	27
flushlogs	2
go	2
logmode	2
mode	149
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	193

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1003	2643994	2623525	834	19635	99%	373:01:33
2	news.nask.pl	4	84225	1439	78791	3995	1%	01:07:05
3	news.icm.edu.pl	45	169	76	72	21	44%	11:52:47
4	localhost	7	6	6	0	0	100%	00:00:00
TOTAL: 4		1059	2728394	2625046	79697	23651	96%	386:01:25

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	686.8 GB	351.9 MB	3.2 GB	690.3 GB	99%	273.8 KB
2	news.nask.pl	5.0 MB	1.4 MB	448.4 MB	454.8 MB	1%	85.7 KB
3	news.icm.edu.pl	1.3 MB	10.7 KB	462.2 KB	1.8 MB	73%	18.6 KB
4	localhost	8.6 KB	0.0 KB	0.0 KB	8.6 KB	100%	1.4 KB
TOTAL: 4		686.8 GB	353.4 MB	3.6 GB	690.7 GB	99%	273.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 3 04:15:02 - 04:59:59	38907	1.5%	14.42	11.8 GB	1.7%	4580.34
Nov 3 05:00:00 - 05:59:59	63202	2.4%	17.56	19.1 GB	2.8%	5566.31
Nov 3 06:00:00 - 06:59:59	59168	2.3%	16.44	18.3 GB	2.7%	5328.17
Nov 3 07:00:00 - 07:59:59	71431	2.7%	19.84	18.9 GB	2.7%	5494.67
Nov 3 08:00:00 - 08:59:59	68427	2.6%	19.01	17.2 GB	2.5%	4995.74
Nov 3 09:00:00 - 09:59:59	59817	2.3%	16.62	17.1 GB	2.5%	4968.29
Nov 3 10:00:00 - 10:59:59	67672	2.6%	18.80	19.1 GB	2.8%	5563.77
Nov 3 11:00:00 - 11:59:59	64005	2.4%	17.78	19.4 GB	2.8%	5636.20
Nov 3 12:00:00 - 12:59:59	50834	1.9%	14.12	16.4 GB	2.4%	4779.59
Nov 3 13:00:00 - 13:59:59	64275	2.4%	17.85	20.3 GB	3.0%	5921.59
Nov 3 14:00:00 - 14:59:59	83686	3.2%	23.25	20.8 GB	3.0%	6048.66
Nov 3 15:00:00 - 15:59:59	153293	5.8%	42.58	37.0 GB	5.4%	10763.20
Nov 3 16:00:00 - 16:59:59	135977	5.2%	37.77	41.4 GB	6.0%	12061.79
Nov 3 17:00:00 - 17:59:59	137385	5.2%	38.16	40.4 GB	5.9%	11770.87
Nov 3 18:00:00 - 18:59:59	137112	5.2%	38.09	40.1 GB	5.8%	11668.15
Nov 3 19:00:00 - 19:59:59	142141	5.4%	39.48	40.7 GB	5.9%	11857.86
Nov 3 20:00:00 - 20:59:59	101290	3.9%	28.14	28.9 GB	4.2%	8424.45
Nov 3 21:00:00 - 21:59:59	142200	5.4%	39.50	31.0 GB	4.5%	9034.96
Nov 3 22:00:00 - 22:59:59	237526	9.0%	65.98	38.3 GB	5.6%	11145.93
Nov 3 23:00:00 - 23:59:59	130915	5.0%	36.37	36.8 GB	5.4%	10719.73
Nov 4 00:00:00 - 00:59:59	107442	4.1%	29.84	30.8 GB	4.5%	8973.63
Nov 4 01:00:00 - 01:59:59	161412	6.1%	44.84	42.2 GB	6.1%	12281.86
Nov 4 02:00:00 - 02:59:59	192350	7.3%	53.43	37.8 GB	5.5%	11013.44
Nov 4 03:00:00 - 03:59:59	125211	4.8%	34.78	33.4 GB	4.9%	9741.39
Nov 4 04:00:00 - 04:15:03	30511	1.2%	33.79	9.2 GB	1.3%	10666.61
TOTAL: 24:00:01	2626189	100.0%	30.40	686.2 GB	100.0%	8327.76

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	20150	270	2120	5	17755
2	news.nask.pl	4070	8	55	2	4005
3	news.icm.edu.pl	24	0	1	0	23
TOTAL: 3		24244	278	2176	7	21783

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	188
it.comp.os.win.windows10	30
comp.mobile.android	19
it.tlc.cellulari.android	9
comp.mobile.ipad	8
de.comp.lang.vbclassic	7
uk.net.providers.gradwell	6
it.comp.os.linux.ubuntu	4
nl.comp.os.linux.techniek	4
de.comm.software.mailreader	2
alt.sex.telephones	1
TOTAL: 11	278

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

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

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	19693
EMP (md5)	1390
Scoring filter (9)	59
HTML post	35
Scoring filter (8)	20
Scoring filter (10)	15
Scoring filter (11)	4
Scoring filter (15)	4
Attached HTML file	1
Scoring filter (14)	1
Scoring filter (13)	1
Poison newsgroup	1
TOTAL: 12	21224

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	507
	news.nask.pl	48
	news.icm.edu.pl	3
	TOTAL: 3	558
TOTAL: 1		558

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	45137	852	17382	231	57	42509	1%	23:58:54
2	icm	22646	17	17827	1378	0	0	0%	22:59:39
3	poznan	1729	12	1443	47	0	0	0%	08:50:28
4	nask	11725	11	10281	0	0	66	0%	22:42:29
5	okapi	0	0	0	0	0	1230	0%	23:51:53
TOTAL: 5		81237	892	46933	1656	57	43805	1%	102:23:23

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	2.4 MB	1020.6 KB	3.4 MB	0.0 KB/s	3.3 KB	23:58:54
2	icm	1.2 MB	507.7 MB	508.9 MB	6.3 KB/s	373.6 KB	22:59:39
3	nask	166.5 KB	0.0 KB	166.5 KB	0.0 KB/s	15.1 KB	22:42:29
4	poznan	25.0 KB	143.9 KB	168.9 KB	0.0 KB/s	2.9 KB	08:50:28
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:51:53
TOTAL: 5		3.9 MB	508.9 MB	512.7 MB	1.4 KB/s	206.1 KB	102:23:23

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	291	134	135.7 KB	46	0	00:00:26
2	isvcs01.man.lodz.pl	6	0	0.0 KB	0	6	00:00:02
TOTAL: 2		297	134	135.7 KB	46	6	00:01:29

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	680	134	135.7 KB	46	6	00:00:34
2	*.175.237.207.maxnet.net.pl	1	0	0.0 KB	0	0	00:00:54
TOTAL: 2		681	134	135.7 KB	46	6	00:01:29

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
46.175.237.207.maxnet.net.pl	0.060	0.036	00:00:54
web.man.lodz.pl	1.468	6.340	00:00:26
mon.man.lodz.pl	1.056	5.476	00:00:03
isvcs01.man.lodz.pl	0.052	0.228	00:00:02
david.man.lodz.pl	0.332	1.804	00:00:01
TOTAL: 5	2.968	13.884	00:01:29

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	287
david.man.lodz.pl	96
46.175.237.207.maxnet.net.pl	1
TOTAL: 3	384

Newsgroup request counts (by category):

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

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

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

Daily Usenet report

Nov 3 04:15:02 -- Nov 4 04:15:03