Daily Usenet report: Jan 15 04:15:02

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Jan 15 14:55:44 newsfeed nnrpd[13936]: SERVER perl filter not defined
Jan 15 15:50:14 newsfeed nnrpd[15778]: SERVER perl filter not defined
Jan 15 16:06:35 newsfeed nnrpd[16402]: SERVER perl filter not defined
Jan 15 18:40:25 newsfeed pgpverify[21712]: pgpverify: verification failed
Jan 15 21:05:57 newsfeed nnrpd[26851]: SERVER perl filter not defined
Jan 16 00:08:54 newsfeed nnrpd[761]: cpc73515-shef13-2-0-cust748.17-1.cable.virginm.net noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2521808	97.6%	293.4 MB	97.2%
innfeed	36821	1.4%	4.4 MB	1.5%
innd	20928	0.8%	3.6 MB	1.2%
nnrpd	5075	0.2%	545.2 KB	0.2%
controlchan	3	0.0%	0.4 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 6	2584636	100.0%	301.9 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2514621	97.6%
Negative hits	42661	1.7%
Positive hits	18038	0.7%
Cache misses	111	0.0%
TOTAL: 4	2575431	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:07.523	0.0%	274	0.000	27.456	1835.000
article cleanup	00:00:36.066	0.0%	2522114	0.000	0.014	0.333
article logging	00:01:12.425	0.1%	2522777	0.000	0.029	0.458
article parse	01:14:52.620	5.2%	227451441	0.000	0.020	0.222
article write	00:28:57.234	2.0%	2515528	0.000	0.691	6.000
artlog/artcncl	00:00:00.008	0.0%	273	0.000	0.029	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:04:47.007	0.3%	243388029	0.000	0.001	0.045
hisgrep/artcncl	00:00:00.709	0.0%	203	0.000	3.493	352.000
hishave/artcncl	00:00:00.070	0.0%	274	0.000	0.255	2.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:36:25.525	2.5%	2576654	0.000	0.848	26.223
history sync	00:00:00.021	0.0%	399	0.000	0.053	3.000
history write	00:53:08.354	3.7%	2515966	0.000	1.267	51.037
hiswrite/artcncl	00:00:00.038	0.0%	71	0.000	0.535	8.000
idle	12:57:15.073	54.0%	150347716	0.087	0.310	8662.429
nntp read	01:28:46.439	6.2%	225426766	0.000	0.024	0.182
overview write	00:00:14.170	0.0%	2515528	0.000	0.006	0.250
perl filter	05:20:01.184	22.2%	2515966	0.756	7.632	187.750
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:23.258	0.3%	5093356	0.000	0.052	0.267
TOTAL: 24:00:10.680	23:10:47.724	96.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.332	0.0%	111554	0.000	0.003	0.062
article prepare	00:00:00.005	0.0%	27800	0.000	0.000	0.042
article read	00:00:50.594	0.1%	6896	0.000	7.337	931.000
backlog stats	00:00:00.206	0.0%	248094	0.000	0.001	0.022
callbacks	00:00:00.000	0.0%	9	0.000	0.000	0.000
data read	00:00:01.756	0.0%	145770	0.000	0.012	0.083
data write	00:00:04.819	0.0%	85506	0.000	0.056	0.555
idle	23:59:20.989	99.9%	248094	41.398	348.098	2420.423
status file	00:00:09.610	0.0%	12684	0.000	0.758	2.000
TOTAL: 24:00:39.009	24:00:28.311	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:17:58.137	98.5%	3315	0.000	325.230	38404.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.166	0.0%	7495	0.000	0.022	2.000
readart	00:00:00.952	0.1%	82	0.000	11.610	25.026
TOTAL: 00:18:14.588	00:17:59.255	98.6%	-	-	-	-

Control commands to INND:

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

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	1107	2515920	2508194	1609	6117	99%	326:50:02
2	news.nask.pl	2	16505	6086	10037	382	36%	24:07:08
3	news.icm.edu.pl	42	301	44	196	61	14%	18:23:02
4	localhost	5	4	4	0	0	100%	00:00:00
TOTAL: 4		1156	2532730	2514328	11842	6560	99%	369:20:12

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	745.3 GB	524.6 MB	532.8 MB	746.4 GB	99%	311.3 KB
2	news.nask.pl	20.2 MB	969.8 KB	1.0 MB	22.1 MB	91%	3.5 KB
3	news.icm.edu.pl	1.9 MB	100.6 KB	5.1 MB	7.2 MB	27%	70.1 KB
4	localhost	6.5 KB	0.0 KB	0.0 KB	6.5 KB	100%	1.6 KB
TOTAL: 4		745.4 GB	525.6 MB	539.0 MB	746.4 GB	99%	310.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 15 04:15:02 - 04:59:59	73469	2.9%	27.23	22.5 GB	3.0%	8756.58
Jan 15 05:00:00 - 05:59:59	98749	3.9%	27.43	30.2 GB	4.1%	8794.13
Jan 15 06:00:00 - 06:59:59	114649	4.6%	31.85	34.6 GB	4.7%	10073.34
Jan 15 07:00:00 - 07:59:59	117344	4.7%	32.60	36.4 GB	4.9%	10610.02
Jan 15 08:00:00 - 08:59:59	120710	4.8%	33.53	36.4 GB	4.9%	10592.48
Jan 15 09:00:00 - 09:59:59	118235	4.7%	32.84	35.3 GB	4.7%	10272.91
Jan 15 10:00:00 - 10:59:59	120951	4.8%	33.60	33.0 GB	4.4%	9611.29
Jan 15 11:00:00 - 11:59:59	137138	5.5%	38.09	35.5 GB	4.8%	10350.91
Jan 15 12:00:00 - 12:59:59	123111	4.9%	34.20	34.5 GB	4.6%	10048.54
Jan 15 13:00:00 - 13:59:59	108769	4.3%	30.21	32.8 GB	4.4%	9568.11
Jan 15 14:00:00 - 14:59:59	111252	4.4%	30.90	34.6 GB	4.7%	10084.91
Jan 15 15:00:00 - 15:59:59	112849	4.5%	31.35	35.1 GB	4.7%	10214.14
Jan 15 16:00:00 - 16:59:59	113800	4.5%	31.61	34.9 GB	4.7%	10154.50
Jan 15 17:00:00 - 17:59:59	114669	4.6%	31.85	34.6 GB	4.7%	10089.97
Jan 15 18:00:00 - 18:59:59	115298	4.6%	32.03	34.5 GB	4.6%	10042.83
Jan 15 19:00:00 - 19:59:59	122647	4.9%	34.07	34.4 GB	4.6%	10006.65
Jan 15 20:00:00 - 20:59:59	100531	4.0%	27.93	29.2 GB	3.9%	8511.86
Jan 15 21:00:00 - 21:59:59	111569	4.4%	30.99	32.8 GB	4.4%	9545.08
Jan 15 22:00:00 - 22:59:59	110121	4.4%	30.59	32.5 GB	4.4%	9463.65
Jan 15 23:00:00 - 23:59:59	113992	4.5%	31.66	33.4 GB	4.5%	9731.38
Jan 16 00:00:00 - 00:59:59	90851	3.6%	25.24	28.0 GB	3.8%	8151.59
Jan 16 01:00:00 - 01:59:59	122341	4.9%	33.98	36.3 GB	4.9%	10584.73
Jan 16 02:00:00 - 02:59:59	40550	1.6%	11.26	12.1 GB	1.6%	3524.58
Jan 16 03:00:00 - 03:59:59	539	0.0%	0.15	3.9 MB	0.0%	1.12
Jan 16 04:00:00 - 04:15:01	155	0.0%	0.17	838.6 KB	0.0%	0.93
TOTAL: 23:59:59	2514289	100.0%	29.10	743.6 GB	100.0%	9024.75

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	6685	189	5783	0	713
2	news.nask.pl	475	15	361	7	92
3	news.icm.edu.pl	86	0	2	0	84
TOTAL: 3		7246	204	6146	7	889

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	106
it.tlc.cellulari.android	39
comp.sys.raspberry-pi	13
comp.mobile.android	9
de.comp.office-pakete.ms-office.excel	8
hr.ponuda.hardver.komponente	8
it.comp.os.win.windows10	8
it.comp.os.win.windows7	3
alt.sex.telephones	3
hr.alt.cellular.gsm.android	2
nl.comp.os.linux.techniek	1
de.comp.office-pakete.ms-office.outlook	1
hr.ponuda.hardver.konfiguracije	1
it.comp.os.linux.ubuntu	1
comp.mobile.ipad	1
TOTAL: 15	204

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

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

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	141
EMP (md5)	72
EMP (fsl)	6
HTML post	4
Too many newsgroups	3
Scoring filter (8)	1
TOTAL: 6	227

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	57676	1750	34250	703	26	74776	3%	23:59:46
2	icm	42802	669	25697	2743	0	0	1%	23:56:58
3	poznan	7742	279	4663	3	0	1126	3%	24:02:21
4	nask	0	0	0	0	0	11092	0%	24:00:05
5	okapi	0	0	0	0	0	2752	0%	24:00:05
TOTAL: 5		108220	2698	64610	3449	26	89746	2%	119:59:15

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	9.2 MB	639.6 MB	648.8 MB	7.7 KB/s	194.7 KB	23:56:58
2	panorama	5.8 MB	3.9 MB	9.7 MB	0.1 KB/s	4.0 KB	23:59:46
3	poznan	892.9 KB	6.7 KB	899.6 KB	0.0 KB/s	3.2 KB	24:02:21
4	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:05
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:05
TOTAL: 5		15.9 MB	643.5 MB	659.4 MB	1.6 KB/s	109.8 KB	119:59:15

Backlog files shrunk by innfeed:

Server	Size
panorama	488.4 KB
TOTAL: 1	488.4 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	44	43.6 KB	22	0	00:00:31
2	lze200.lze.lodz.pl	3	38	69.1 KB	4	0	00:16:13
3	isvcs01.man.lodz.pl	4	0	0.0 KB	0	4	00:00:00
TOTAL: 3		295	82	112.6 KB	26	4	00:18:14

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	676	44	43.6 KB	22	4	00:00:37
2	*.lze.lodz.pl	3	38	69.1 KB	4	0	00:16:13
3	*.neoplus.adsl.tpnet.pl	1	0	0.0 KB	0	0	00:01:00
4	*.17-1.cable.virginm.net	1	0	0.0 KB	0	0	00:00:00
5	*.toya.net.pl	59	0	0.0 KB	0	0	00:00:04
6	*.elsnet.pl	4	0	0.0 KB	0	0	00:00:18
TOTAL: 6		744	82	112.6 KB	26	4	00:18:14

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
lze200.lze.lodz.pl	0.026	0.071	00:16:13
ayw117.neoplus.adsl.tpnet.pl	0.000	0.022	00:01:00
web.man.lodz.pl	0.997	4.658	00:00:31
host227.elsnet.pl	0.008	0.075	00:00:18
staticline41435.toya.net.pl	0.222	0.977	00:00:04
mon.man.lodz.pl	0.833	4.591	00:00:04
david.man.lodz.pl	0.320	1.426	00:00:00
isvcs01.man.lodz.pl	0.027	0.080	00:00:00
cpc73515-shef13-2-0-cust748.17-1.cable.virginm.net	0.000	0.009	00:00:00
TOTAL: 9	2.433	11.909	00:18:14

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	59
host227.elsnet.pl	4
ayw117.neoplus.adsl.tpnet.pl	1
cpc73515-shef13-2-0-cust748.17-1.cable.virginm.net	1
TOTAL: 6	449

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.pecet	39
2	pl.comp.os.ms-windows.winnt	36
3	pl.misc.telefonia.gsm	4
4	pl.comp.os.linux	3
TOTAL: 4		82

Daily Usenet report

Jan 15 04:15:02 -- Jan 16 04:15:01