Daily Usenet report: Jan 15 04:15:07

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Jan 15 08:57:33 newsfeed nnrpd[28089]: SERVER perl filter not defined
Jan 15 10:04:07 newsfeed overchan[17282]: input line too long, skipping
Jan 15 13:47:50 newsfeed nnrpd[6618]: SERVER perl filter not defined
Jan 15 13:54:11 newsfeed nnrpd[6884]: SERVER perl filter not defined
Jan 15 16:03:46 newsfeed overchan[17282]: input line too long, skipping
Jan 15 16:19:54 newsfeed overchan[17282]: input line too long, skipping
Jan 15 16:25:45 newsfeed overchan[17282]: input line too long, skipping
Jan 15 17:00:05 newsfeed pgpverify[13777]: pgpverify: verification failed
Jan 15 22:42:45 newsfeed nnrpd[26263]: SERVER perl filter not defined
Jan 15 23:43:01 newsfeed overchan[17282]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	4315714	97.9%	485.0 MB	97.5%
innd	45643	1.0%	6.8 MB	1.4%
innfeed	41520	0.9%	4.9 MB	1.0%
nnrpd	4814	0.1%	499.4 KB	0.1%
overchan	6	0.0%	0.8 KB	0.0%
controlchan	4	0.0%	0.5 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 7	4407702	100.0%	497.2 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	4291391	97.0%
Negative hits	106434	2.4%
Positive hits	24659	0.6%
Cache misses	108	0.0%
TOTAL: 4	4422592	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:08.346	0.0%	90	0.333	92.733	1072.500
article cleanup	00:00:42.599	0.0%	4314166	0.007	0.010	0.014
article logging	00:02:51.950	0.2%	4315179	0.026	0.040	0.153
article parse	00:50:40.035	3.5%	87131396	0.023	0.035	0.056
article write	00:20:56.581	1.5%	4277298	0.163	0.294	0.498
artlog/artcncl	00:00:00.001	0.0%	89	0.000	0.011	0.500
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:03:55.635	0.3%	105214322	0.001	0.002	0.004
hisgrep/artcncl	00:00:00.953	0.0%	84	0.000	11.345	201.000
hishave/artcncl	00:00:00.139	0.0%	90	0.000	1.544	127.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:43:53.770	7.2%	4431797	0.134	1.407	6.127
history sync	00:00:00.009	0.0%	290	0.000	0.031	0.500
history write	05:48:11.026	24.2%	4299756	0.537	4.859	13.306
hiswrite/artcncl	00:00:00.013	0.0%	6	0.000	2.167	9.000
idle	07:20:01.597	30.6%	49390174	0.156	0.535	1.062
nntp read	00:54:46.487	3.8%	83560476	0.025	0.039	0.062
overview write	00:00:20.886	0.0%	4277298	0.004	0.005	0.007
perl filter	06:20:35.069	26.4%	4299754	3.573	5.311	7.309
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:07:16.564	0.5%	8574709	0.029	0.051	0.078
TOTAL: 24:00:05.931	23:34:21.660	98.2%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.158	0.0%	57255	0.000	0.003	0.016
article prepare	00:00:00.003	0.0%	9014	0.000	0.000	0.028
article read	00:01:49.002	0.1%	2222	0.000	49.056	334.095
backlog stats	00:00:00.138	0.0%	161868	0.000	0.001	0.005
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.430	0.0%	87667	0.004	0.016	0.031
data write	00:00:03.587	0.0%	68122	0.023	0.053	0.107
idle	23:51:27.489	99.8%	161868	321.843	530.602	922.716
status file	00:00:41.248	0.0%	15693	0.561	2.628	42.075
TOTAL: 23:54:18.109	23:54:03.055	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	08:58:00.331	99.9%	255915	0.000	126.137	360.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:14.244	0.0%	512538	0.000	0.028	0.737
readart	00:00:00.007	0.0%	84	0.000	0.083	1.000
TOTAL: 08:58:43.844	08:58:14.582	99.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
group-admin@isc.org	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	314	4293733	4266318	8323	19092	99%	531:45:27
2	news.nask.pl	3	30103	10713	1680	17710	35%	24:00:42
3	news.icm.edu.pl	44	328	24	296	8	7%	18:10:20
4	localhost	5	4	4	0	0	100%	00:00:00
TOTAL: 4		366	4324168	4277059	10299	36810	98%	573:56:29

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	825.0 GB	2.1 GB	2.9 GB	830.0 GB	99%	203.1 KB
2	news.nask.pl	84.1 MB	18.1 MB	2.2 GB	2.3 GB	3%	84.2 KB
3	news.icm.edu.pl	454.2 KB	27.7 KB	263.6 KB	745.5 KB	60%	23.3 KB
4	localhost	9.8 KB	0.0 KB	0.0 KB	9.8 KB	100%	2.4 KB
TOTAL: 4		825.0 GB	2.1 GB	5.1 GB	832.3 GB	99%	202.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 15 04:15:07 - 04:59:59	98060	2.3%	36.41	22.6 GB	2.7%	8805.07
Jan 15 05:00:00 - 05:59:59	142470	3.3%	39.58	33.8 GB	4.1%	9854.98
Jan 15 06:00:00 - 06:59:59	137951	3.2%	38.32	32.7 GB	4.0%	9520.33
Jan 15 07:00:00 - 07:59:59	153276	3.6%	42.58	36.9 GB	4.5%	10734.83
Jan 15 08:00:00 - 08:59:59	161843	3.8%	44.96	35.9 GB	4.4%	10466.77
Jan 15 09:00:00 - 09:59:59	165068	3.9%	45.85	37.0 GB	4.5%	10766.30
Jan 15 10:00:00 - 10:59:59	166689	3.9%	46.30	36.4 GB	4.4%	10595.10
Jan 15 11:00:00 - 11:59:59	160591	3.8%	44.61	36.5 GB	4.4%	10632.18
Jan 15 12:00:00 - 12:59:59	151627	3.5%	42.12	37.1 GB	4.5%	10791.73
Jan 15 13:00:00 - 13:59:59	159805	3.7%	44.39	37.8 GB	4.6%	11002.54
Jan 15 14:00:00 - 14:59:59	162327	3.8%	45.09	35.9 GB	4.3%	10453.98
Jan 15 15:00:00 - 15:59:59	167807	3.9%	46.61	36.0 GB	4.4%	10476.59
Jan 15 16:00:00 - 16:59:59	158827	3.7%	44.12	35.5 GB	4.3%	10347.89
Jan 15 17:00:00 - 17:59:59	167425	3.9%	46.51	37.7 GB	4.6%	10967.72
Jan 15 18:00:00 - 18:59:59	225891	5.3%	62.75	33.6 GB	4.1%	9790.22
Jan 15 19:00:00 - 19:59:59	236142	5.5%	65.59	32.5 GB	3.9%	9461.51
Jan 15 20:00:00 - 20:59:59	218814	5.1%	60.78	35.0 GB	4.2%	10183.02
Jan 15 21:00:00 - 21:59:59	278605	6.5%	77.39	33.6 GB	4.1%	9788.73
Jan 15 22:00:00 - 22:59:59	266068	6.2%	73.91	34.2 GB	4.1%	9960.71
Jan 15 23:00:00 - 23:59:59	249717	5.8%	69.37	33.1 GB	4.0%	9647.24
Jan 16 00:00:00 - 00:59:59	158593	3.7%	44.05	27.3 GB	3.3%	7940.70
Jan 16 01:00:00 - 01:59:59	168680	3.9%	46.86	33.4 GB	4.0%	9719.72
Jan 16 02:00:00 - 02:59:59	167992	3.9%	46.66	35.5 GB	4.3%	10328.89
Jan 16 03:00:00 - 03:59:59	121110	2.8%	33.64	29.1 GB	3.5%	8486.18
Jan 16 04:00:00 - 04:15:03	32354	0.8%	35.83	7.1 GB	0.9%	8193.01
TOTAL: 23:59:56	4277732	100.0%	49.51	825.9 GB	100.0%	10023.78

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	19458	297	14056	2	5103
2	news.nask.pl	18426	60	356	2	18008
3	news.icm.edu.pl	9	0	2	0	7
TOTAL: 3		37893	357	14414	4	23118

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	224
it.comp.os.win.windows10	38
comp.mobile.android	32
it.tlc.cellulari.android	25
comp.sys.raspberry-pi	20
comp.mobile.ipad	11
de.rec.sf.startrek	3
it.comp.os.win.windows7	2
it.discussioni.energia	1
hr.ponuda.hardver.komponente	1
TOTAL: 10	357

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

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

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	17676
EMP (md5)	4161
Scoring filter (11)	66
Scoring filter (8)	53
Scoring filter (12)	48
Scoring filter (9)	33
EMP (phl)	29
HTML post	13
Scoring filter (10)	13
Scoring filter (18)	8
Scoring filter (22)	1
Too many newsgroups	1
Too many newsgroups (low_xpost)	1
TOTAL: 13	22103

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news.nask.pl	642
	newsfeed.pionier.net.pl	370
	news.icm.edu.pl	1
	TOTAL: 3	1013
TOTAL: 1		1013

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	44374	719	17217	313	3	39086	1%	23:59:53
2	icm	23466	272	19254	152	0	250	1%	23:59:53
3	poznan	16797	229	10297	35	0	1	1%	23:59:53
4	nask	2966	5	2604	0	0	0	0%	23:59:46
5	okapi	0	0	0	0	0	1371	0%	23:57:46
TOTAL: 5		87603	1225	49372	500	3	40708	1%	119:57:11

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	9.8 MB	10.7 MB	20.6 MB	0.2 KB/s	20.4 KB	23:59:53
2	icm	3.6 MB	37.0 MB	40.7 MB	0.5 KB/s	98.2 KB	23:59:53
3	poznan	933.9 KB	1.0 MB	2.0 MB	0.0 KB/s	7.6 KB	23:59:53
4	nask	11.0 KB	0.0 KB	11.0 KB	0.0 KB/s	2.2 KB	23:59:46
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:46
TOTAL: 5		14.4 MB	48.8 MB	63.2 MB	0.1 KB/s	37.5 KB	119:57:11

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	84	92.8 KB	35	0	00:00:39
2	isvcs01.man.lodz.pl	4	0	0.0 KB	0	4	00:00:01
TOTAL: 2		292	84	92.8 KB	35	4	08:58:43

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	676	84	92.8 KB	35	4	00:00:45
2	unresolved	2	0	0.0 KB	0	0	08:57:57
TOTAL: 2		678	84	92.8 KB	35	4	08:58:43

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.48.195.97	14.520	22.980	08:57:57
web.man.lodz.pl	1.500	6.316	00:00:39
mon.man.lodz.pl	1.176	5.296	00:00:03
isvcs01.man.lodz.pl	0.052	0.108	00:00:01
david.man.lodz.pl	0.448	1.928	00:00:01
TOTAL: 5	17.696	36.628	08:58:43

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
185.48.195.97	2
TOTAL: 3	386

NNRP client timeouts [Top 20]:

System	Conn	Peer
185.48.195.97	3	0
TOTAL: 1	3	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	54
2	pl.comp.pecet	30
TOTAL: 2		84

Daily Usenet report

Jan 15 04:15:07 -- Jan 16 04:15:03