Daily Usenet report: Apr 2 04:15:02

Unknown entries from news log file:

First 20 / 20 lines (100.0%)

Apr 2 04:30:11 newsfeed overchan[13752]: input line too long, skipping
Apr 2 05:20:03 newsfeed pgpverify[16493]: pgpverify: verification failed
Apr 2 05:47:51 newsfeed overchan[13752]: input line too long, skipping
Apr 2 08:38:14 newsfeed nnrpd[24667]: SERVER perl filter not defined
Apr 2 09:48:25 newsfeed overchan[13752]: input line too long, skipping
Apr 2 09:48:37 newsfeed overchan[13752]: input line too long, skipping
Apr 2 10:54:03 newsfeed overchan[13752]: input line too long, skipping
Apr 2 14:37:22 newsfeed overchan[13752]: input line too long, skipping
Apr 2 16:54:21 newsfeed overchan[13752]: input line too long, skipping
Apr 2 17:01:34 newsfeed overchan[13752]: input line too long, skipping
Apr 2 17:46:04 newsfeed overchan[13752]: input line too long, skipping
Apr 2 17:46:18 newsfeed overchan[13752]: input line too long, skipping
Apr 2 18:25:32 newsfeed overchan[13752]: input line too long, skipping
Apr 2 20:03:57 newsfeed overchan[13752]: input line too long, skipping
Apr 2 20:29:46 newsfeed overchan[13752]: input line too long, skipping
Apr 2 20:41:16 newsfeed overchan[13752]: input line too long, skipping
Apr 2 23:11:30 newsfeed overchan[13752]: input line too long, skipping
Apr 2 23:11:55 newsfeed overchan[13752]: input line too long, skipping
Apr 3 03:45:36 newsfeed overchan[13752]: input line too long, skipping
Apr 3 03:45:49 newsfeed overchan[13752]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3253498	97.7%	353.7 MB	97.3%
innfeed	46951	1.4%	5.6 MB	1.5%
innd	23792	0.7%	3.9 MB	1.1%
nnrpd	4770	0.1%	505.0 KB	0.1%
overchan	19	0.0%	3.5 KB	0.0%
controlchan	4	0.0%	0.5 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 7	3329035	100.0%	363.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3243773	96.5%
Negative hits	107312	3.2%
Positive hits	11374	0.3%
Cache misses	85	0.0%
TOTAL: 4	3362544	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.913	0.0%	412	0.000	9.498	56.000
article cleanup	00:00:41.873	0.0%	3251074	0.010	0.013	0.018
article logging	00:02:20.301	0.2%	3251668	0.030	0.043	0.099
article parse	00:51:15.900	3.6%	99279969	0.018	0.031	0.049
article write	00:20:18.363	1.4%	3246091	0.205	0.375	0.758
artlog/artcncl	00:00:00.018	0.0%	410	0.000	0.044	0.500
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:04:28.366	0.3%	115214879	0.001	0.002	0.003
hisgrep/artcncl	00:00:00.381	0.0%	123	0.000	3.098	26.000
hishave/artcncl	00:00:01.479	0.0%	412	0.000	3.590	4.679
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:44:30.972	3.1%	3367701	0.056	0.793	5.479
history sync	00:00:00.020	0.0%	290	0.000	0.069	0.500
history write	01:55:09.436	8.0%	3248647	0.192	2.127	8.019
hiswrite/artcncl	00:00:01.440	0.0%	289	0.000	4.983	5.000
idle	12:31:19.471	52.2%	78327889	0.381	0.576	2.245
nntp read	01:17:27.594	5.4%	96790199	0.033	0.048	0.063
overview write	00:00:18.619	0.0%	3246091	0.004	0.006	0.007
perl filter	05:29:14.928	22.9%	3248647	4.202	6.081	7.676
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:06:39.981	0.5%	6510777	0.040	0.061	0.083
TOTAL: 24:00:02.866	23:23:53.055	97.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.170	0.0%	55494	0.000	0.003	0.020
article prepare	00:00:00.004	0.0%	11840	0.000	0.000	0.083
article read	00:01:00.684	0.1%	2990	0.000	20.296	273.000
backlog stats	00:00:00.168	0.0%	164995	0.000	0.001	0.004
callbacks	00:00:00.001	0.0%	27	0.000	0.037	1.000
data read	00:00:01.771	0.0%	85718	0.007	0.021	0.035
data write	00:00:06.053	0.0%	68881	0.031	0.088	0.269
idle	23:51:57.220	99.9%	164995	348.171	520.726	956.537
status file	00:00:16.546	0.0%	16199	0.424	1.021	6.375
TOTAL: 23:53:41.493	23:53:22.617	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:18.105	56.5%	3045	0.000	5.946	46.444
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.408	1.3%	6786	0.000	0.060	2.842
readart	00:00:00.034	0.1%	62	0.000	0.548	14.000
TOTAL: 00:00:32.049	00:00:18.547	57.9%	-	-	-	-

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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
hun-mnt@news.sztaki.hu	0	0	0	1	0	0
TOTAL	0	0	0	1	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1118	3246984	3236983	7703	2298	99%	458:45:55
2	news.nask.pl	2	13978	10237	1046	2695	73%	24:32:27
3	news.icm.edu.pl	41	214	19	194	1	8%	16:56:47
4	localhost	2	1	1	0	0	100%	00:00:00
TOTAL: 4		1163	3261177	3247240	8943	4994	99%	500:15:09

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	686.5 GB	12.1 MB	13.5 MB	686.6 GB	99%	222.2 KB
2	news.nask.pl	36.7 MB	706.1 KB	17.4 MB	54.8 MB	66%	4.3 KB
3	news.icm.edu.pl	491.6 KB	0.0 KB	19.7 KB	511.3 KB	96%	25.6 KB
4	localhost	1.4 KB	0.0 KB	0.0 KB	1.4 KB	100%	1.4 KB
TOTAL: 4		686.6 GB	12.8 MB	31.0 MB	686.6 GB	99%	221.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 2 04:15:02 - 04:59:59	85343	2.6%	31.63	22.9 GB	3.3%	8892.31
Apr 2 05:00:00 - 05:59:59	112802	3.5%	31.33	30.6 GB	4.5%	8920.58
Apr 2 06:00:00 - 06:59:59	106883	3.3%	29.69	32.9 GB	4.8%	9590.12
Apr 2 07:00:00 - 07:59:59	134038	4.1%	37.23	32.9 GB	4.8%	9568.69
Apr 2 08:00:00 - 08:59:59	118752	3.7%	32.99	32.0 GB	4.7%	9323.54
Apr 2 09:00:00 - 09:59:59	127931	3.9%	35.54	31.2 GB	4.5%	9081.25
Apr 2 10:00:00 - 10:59:59	149993	4.6%	41.66	37.2 GB	5.4%	10840.06
Apr 2 11:00:00 - 11:59:59	131326	4.0%	36.48	32.9 GB	4.8%	9575.31
Apr 2 12:00:00 - 12:59:59	111225	3.4%	30.90	29.1 GB	4.2%	8470.97
Apr 2 13:00:00 - 13:59:59	105655	3.3%	29.35	25.2 GB	3.7%	7347.21
Apr 2 14:00:00 - 14:59:59	91189	2.8%	25.33	21.6 GB	3.1%	6294.77
Apr 2 15:00:00 - 15:59:59	130343	4.0%	36.21	28.2 GB	4.1%	8220.00
Apr 2 16:00:00 - 16:59:59	133195	4.1%	37.00	31.0 GB	4.5%	9027.79
Apr 2 17:00:00 - 17:59:59	92035	2.8%	25.57	21.1 GB	3.1%	6138.32
Apr 2 18:00:00 - 18:59:59	122372	3.8%	33.99	30.0 GB	4.4%	8724.01
Apr 2 19:00:00 - 19:59:59	110926	3.4%	30.81	24.5 GB	3.6%	7134.71
Apr 2 20:00:00 - 20:59:59	158212	4.9%	43.95	27.0 GB	3.9%	7864.97
Apr 2 21:00:00 - 21:59:59	182813	5.6%	50.78	26.9 GB	3.9%	7841.66
Apr 2 22:00:00 - 22:59:59	241326	7.4%	67.03	35.4 GB	5.2%	10320.67
Apr 2 23:00:00 - 23:59:59	186848	5.8%	51.90	26.9 GB	3.9%	7845.57
Apr 3 00:00:00 - 00:59:59	148515	4.6%	41.25	20.9 GB	3.0%	6084.01
Apr 3 01:00:00 - 01:59:59	173117	5.3%	48.09	29.0 GB	4.2%	8450.59
Apr 3 02:00:00 - 02:59:59	139105	4.3%	38.64	27.0 GB	3.9%	7857.34
Apr 3 03:00:00 - 03:59:59	120856	3.7%	33.57	24.3 GB	3.5%	7087.86
Apr 3 04:00:00 - 04:15:02	32699	1.0%	36.25	7.2 GB	1.0%	8361.40
TOTAL: 24:00:00	3247499	100.0%	37.59	687.9 GB	100.0%	8348.72

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	2903	18	260	3	2622
2	newsfeed.pionier.net.pl	2684	90	2168	1	425
3	news.icm.edu.pl	2	0	0	0	2
TOTAL: 3		5589	108	2428	4	3049

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	50
it.comp.os.win.windows10	24
comp.mobile.android	11
it.tlc.cellulari.android	11
comp.sys.raspberry-pi	5
de.rec.sf.startrek	2
openwatcom.users.assembler	2
comp.mobile.ipad	1
nl.comp.sys.android	1
it.comp.os.linux.ubuntu	1
TOTAL: 10	108

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2110
Binary in non-binary group	190
EMP (phl)	74
Scoring filter (9)	49
Scoring filter (8)	22
HTML post	3
Too many newsgroups	2
Scoring filter (10)	2
Scoring filter (16)	2
Scoring filter (18)	1
Scoring filter (11)	1
Scoring filter (12)	1
TOTAL: 12	2457

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	385
	news.nask.pl	206
	news.icm.edu.pl	1
	TOTAL: 3	592
TOTAL: 1		592

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	40591	461	12967	120	78	42038	1%	23:59:57
2	poznan	16562	113	9956	4	0	0	0%	23:59:57
3	icm	24277	37	16718	1596	0	0	0%	23:59:57
4	nask	2380	6	1667	1	0	0	0%	23:59:14
5	okapi	0	0	0	0	0	1073	0%	23:57:50
TOTAL: 5		83810	617	41308	1721	78	43111	0%	119:56:55

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	4.1 MB	588.7 MB	592.7 MB	7.0 KB/s	371.7 KB	23:59:57
2	panorama	1.2 MB	831.1 KB	2.0 MB	0.0 KB/s	3.6 KB	23:59:57
3	poznan	368.9 KB	10.3 KB	379.2 KB	0.0 KB/s	3.2 KB	23:59:57
4	nask	82.8 KB	1.6 KB	84.3 KB	0.0 KB/s	12.0 KB	23:59:14
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:50
TOTAL: 5		5.7 MB	589.5 MB	595.2 MB	1.4 KB/s	260.7 KB	119:56:55

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	62	60.9 KB	31	0	00:00:25
2	isvcs01.man.lodz.pl	1	0	0.0 KB	0	1	00:00:00
TOTAL: 2		289	62	60.9 KB	31	1	00:00:31

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.man.lodz.pl	673	62	60.9 KB	31	1	0	00:00:31
TOTAL: 1		673	62	60.9 KB	31	1	0	00:00:31

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.912	5.560	00:00:25
mon.man.lodz.pl	1.700	5.084	00:00:04
david.man.lodz.pl	0.436	1.496	00:00:01
isvcs01.man.lodz.pl	0.012	0.020	00:00:00
TOTAL: 4	4.060	12.160	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	62	100.0%
TOTAL: 1		62	100.0%

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

Newsgroup		Count
1	pl.comp.pecet	38
2	pl.comp.os.ms-windows.winnt	24
TOTAL: 2		62

Daily Usenet report

Apr 2 04:15:02 -- Apr 3 04:15:02