Daily Usenet report: Sep 29 04:15:02

Unknown entries from news log file:

First 11 / 11 lines (100.0%)

Sep 29 08:24:02 newsfeed nnrpd[9631]: SERVER perl filter not defined
Sep 29 16:07:11 newsfeed overchan[31958]: input line too long, skipping
Sep 29 16:09:49 newsfeed overchan[31958]: input line too long, skipping
Sep 29 17:27:57 newsfeed overchan[31958]: input line too long, skipping
Sep 29 17:41:30 newsfeed overchan[31958]: input line too long, skipping
Sep 29 20:16:29 newsfeed overchan[31958]: input line too long, skipping
Sep 29 20:19:11 newsfeed overchan[31958]: input line too long, skipping
Sep 29 20:37:41 newsfeed overchan[31958]: input line too long, skipping
Sep 29 21:11:25 newsfeed overchan[31958]: input line too long, skipping
Sep 29 21:51:40 newsfeed overchan[31958]: input line too long, skipping
Sep 30 03:55:17 newsfeed overchan[31958]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2855665	97.7%	335.7 MB	97.5%
innfeed	41238	1.4%	4.9 MB	1.4%
innd	19845	0.7%	3.2 MB	0.9%
nnrpd	4836	0.2%	513.9 KB	0.1%
overchan	11	0.0%	1.8 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2921596	100.0%	344.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2833287	97.0%
Negative hits	57034	2.0%
Positive hits	31657	1.1%
Cache misses	111	0.0%
TOTAL: 4	2922089	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.352	0.0%	97	0.000	34.557	375.000
article cleanup	00:00:39.105	0.0%	2859686	0.009	0.014	0.018
article logging	00:02:12.596	0.2%	2860221	0.034	0.046	0.103
article parse	00:59:55.769	4.2%	101871146	0.021	0.035	0.054
article write	00:23:26.114	1.6%	2852372	0.329	0.493	0.727
artlog/artcncl	00:00:00.005	0.0%	86	0.000	0.058	0.571
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:36.869	0.2%	119030448	0.001	0.001	0.002
hisgrep/artcncl	00:00:01.180	0.0%	95	0.000	12.421	73.000
hishave/artcncl	00:00:00.118	0.0%	97	0.000	1.216	13.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:09:42.728	9.0%	2944859	0.332	2.643	7.042
history sync	00:00:00.015	0.0%	290	0.000	0.052	1.500
history write	04:35:00.148	19.1%	2854162	2.261	5.781	10.872
hiswrite/artcncl	00:00:00.008	0.0%	2	0.000	4.000	8.000
idle	08:49:21.935	36.8%	63399620	0.283	0.501	0.925
nntp read	01:16:30.234	5.3%	99593857	0.032	0.046	0.064
overview write	00:00:14.571	0.0%	2852372	0.003	0.005	0.007
perl filter	05:04:35.538	21.2%	2854162	4.620	6.403	8.330
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:29.561	0.4%	5754698	0.044	0.057	0.079
TOTAL: 24:00:03.365	23:29:49.846	97.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.297	0.0%	86492	0.000	0.003	0.041
article prepare	00:00:00.009	0.0%	51812	0.000	0.000	0.005
article read	00:00:29.372	0.0%	12894	0.000	2.278	192.000
backlog stats	00:00:00.392	0.0%	347796	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:04.602	0.0%	186690	0.002	0.025	0.128
data write	00:00:36.103	0.0%	169247	0.026	0.213	0.378
idle	23:51:49.818	99.9%	347796	36.907	247.012	1061.850
status file	00:00:20.659	0.0%	16402	0.638	1.260	2.512
TOTAL: 23:53:44.172	23:53:21.252	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:20.820	59.1%	3011	0.000	6.915	68.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.458	1.3%	6650	0.000	0.069	3.500
readart	00:00:00.038	0.1%	28	0.000	1.357	16.000
TOTAL: 00:00:35.239	00:00:21.316	60.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	833	2846198	2842877	913	2408	99%	514:26:51
2	news.nask.pl	2	33567	3326	25308	4933	9%	24:16:25
3	news.icm.edu.pl	45	254	59	173	22	23%	16:46:28
4	localhost	2	1	1	0	0	100%	00:00:00
TOTAL: 4		882	2880020	2846263	26394	7363	98%	555:29:44

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	941.7 GB	30.7 MB	34.2 MB	941.8 GB	99%	347.1 KB
2	news.nask.pl	59.4 MB	346.4 MB	355.4 MB	761.2 MB	7%	94.4 KB
3	news.icm.edu.pl	1.4 MB	38.0 KB	1.3 MB	2.7 MB	50%	34.4 KB
4	localhost	1.2 KB	0.0 KB	0.0 KB	1.2 KB	100%	1.2 KB
TOTAL: 4		941.8 GB	377.2 MB	391.0 MB	942.5 GB	99%	346.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 29 04:15:02 - 04:59:59	84520	3.0%	31.33	27.6 GB	2.9%	10742.21
Sep 29 05:00:00 - 05:59:59	95582	3.4%	26.55	36.3 GB	3.9%	10587.67
Sep 29 06:00:00 - 06:59:59	93843	3.3%	26.07	37.6 GB	4.0%	10961.21
Sep 29 07:00:00 - 07:59:59	102061	3.6%	28.35	38.8 GB	4.1%	11303.78
Sep 29 08:00:00 - 08:59:59	109811	3.9%	30.50	41.9 GB	4.5%	12204.59
Sep 29 09:00:00 - 09:59:59	110689	3.9%	30.75	41.7 GB	4.4%	12155.58
Sep 29 10:00:00 - 10:59:59	114929	4.0%	31.92	42.7 GB	4.5%	12437.91
Sep 29 11:00:00 - 11:59:59	130838	4.6%	36.34	43.5 GB	4.6%	12678.99
Sep 29 12:00:00 - 12:59:59	129608	4.6%	36.00	42.9 GB	4.6%	12508.38
Sep 29 13:00:00 - 13:59:59	101217	3.6%	28.12	32.8 GB	3.5%	9565.98
Sep 29 14:00:00 - 14:59:59	108591	3.8%	30.16	37.8 GB	4.0%	11005.49
Sep 29 15:00:00 - 15:59:59	116072	4.1%	32.24	38.1 GB	4.1%	11108.28
Sep 29 16:00:00 - 16:59:59	120937	4.2%	33.59	38.6 GB	4.1%	11244.33
Sep 29 17:00:00 - 17:59:59	119405	4.2%	33.17	38.9 GB	4.1%	11344.51
Sep 29 18:00:00 - 18:59:59	115739	4.1%	32.15	37.9 GB	4.0%	11045.98
Sep 29 19:00:00 - 19:59:59	123856	4.3%	34.40	38.5 GB	4.1%	11214.20
Sep 29 20:00:00 - 20:59:59	133182	4.7%	36.99	39.5 GB	4.2%	11497.57
Sep 29 21:00:00 - 21:59:59	132988	4.7%	36.94	42.8 GB	4.5%	12467.88
Sep 29 22:00:00 - 22:59:59	137439	4.8%	38.18	42.6 GB	4.5%	12396.14
Sep 29 23:00:00 - 23:59:59	134567	4.7%	37.38	42.2 GB	4.5%	12290.57
Sep 30 00:00:00 - 00:59:59	112025	3.9%	31.12	36.7 GB	3.9%	10680.70
Sep 30 01:00:00 - 01:59:59	134095	4.7%	37.25	39.3 GB	4.2%	11453.15
Sep 30 02:00:00 - 02:59:59	119962	4.2%	33.32	35.5 GB	3.8%	10338.63
Sep 30 03:00:00 - 03:59:59	137538	4.8%	38.20	37.7 GB	4.0%	10976.47
Sep 30 04:00:00 - 04:15:03	28221	1.0%	31.25	8.7 GB	0.9%	10155.15
TOTAL: 24:00:01	2847715	100.0%	32.96	940.9 GB	100.0%	11419.28

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	4970	3	3339	16	1612
2	newsfeed.pionier.net.pl	2871	130	2193	3	545
3	news.icm.edu.pl	23	0	4	0	19
TOTAL: 3		7864	133	5536	19	2176

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	65
it.tlc.cellulari.android	28
it.comp.os.win.windows10	12
comp.sys.raspberry-pi	9
comp.mobile.android	9
nl.comp.os.linux.techniek	7
it.comp.virtualizzazione	1
uk.rec.cycling.moderated	1
de.comp.office-pakete.ms-office.outlook	1
TOTAL: 9	133

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

Groups	Count
courts.usa.federal.supreme	13
pl.praca.oferowana	5
bit.listserv.catholic	1
TOTAL: 3	19

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1369
Binary in non-binary group	130
EMP (phl)	90
Scoring filter (8)	17
Scoring filter (9)	9
Too many newsgroups	7
Scoring filter (16)	4
Scoring filter (10)	4
Scoring filter (11)	3
Scoring filter (18)	3
Excessively crossposted test article	1
Scoring filter (14)	1
TOTAL: 12	1638

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	459
	news.nask.pl	78
	news.icm.edu.pl	1
	TOTAL: 3	538
TOTAL: 1		538

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	nask	56196	8017	30104	20	0	5	14%	23:59:58
2	panorama	48046	623	20035	148	2	67124	1%	23:59:58
3	poznan	4430	50	3154	9	0	1	1%	23:59:42
4	icm	55939	43	45881	4150	0	6	0%	23:59:58
5	okapi	0	0	0	0	0	998	0%	23:57:51
TOTAL: 5		164611	8733	99174	4327	2	68134	5%	119:57:27

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nask	2.7 GB	794.6 KB	2.7 GB	32.7 KB/s	351.7 KB	23:59:58
2	panorama	28.6 MB	508.9 KB	29.0 MB	0.3 KB/s	38.6 KB	23:59:58
3	icm	7.4 MB	1.5 GB	1.5 GB	17.9 KB/s	368.7 KB	23:59:58
4	poznan	225.0 KB	31.9 KB	256.9 KB	0.0 KB/s	4.4 KB	23:59:42
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:51
TOTAL: 5		2.7 GB	1.5 GB	4.2 GB	10.2 KB/s	337.1 KB	119:57:27

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	28	40.3 KB	14	0	00:00:28
2	isvcs01.man.lodz.pl	1	0	0.0 KB	0	1	00:00:00
TOTAL: 2		289	28	40.3 KB	14	1	00:00:34

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	577	28	40.3 KB	14	1	00:00:32
2	unresolved	96	0	0.0 KB	0	0	00:00:01
TOTAL: 2		673	28	40.3 KB	14	1	00:00:34

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.520	5.612	00:00:28
mon.man.lodz.pl	1.412	5.228	00:00:04
212.51.192.185	0.376	1.716	00:00:01
isvcs01.man.lodz.pl	0.008	0.032	00:00:00
TOTAL: 4	3.316	12.588	00:00:34

Curious NNRP server explorers [Top 20]:

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

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.pecet	28
TOTAL: 1		28

Daily Usenet report

Sep 29 04:15:02 -- Sep 30 04:15:03