Daily Usenet report: Jun 14 04:15:05

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Jun 14 12:35:43 newsfeed nnrpd[4821]: SERVER perl filter not defined
Jun 14 12:37:08 newsfeed nnrpd[4836]: SERVER perl filter not defined
Jun 14 12:38:37 newsfeed nnrpd[4846]: SERVER perl filter not defined
Jun 14 12:39:39 newsfeed nnrpd[4847]: SERVER perl filter not defined
Jun 14 12:40:43 newsfeed nnrpd[4909]: SERVER perl filter not defined
Jun 14 12:42:06 newsfeed nnrpd[4923]: SERVER perl filter not defined
Jun 14 12:44:05 newsfeed nnrpd[5075]: SERVER perl filter not defined
Jun 14 12:49:27 newsfeed nnrpd[5158]: SERVER perl filter not defined
Jun 14 15:05:30 newsfeed nnrpd[9559]: SERVER perl filter not defined
Jun 14 22:15:20 newsfeed pgpverify[23174]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2633447	98.4%	296.0 MB	97.9%
innd	28364	1.1%	4.4 MB	1.4%
nnrpd	8163	0.3%	866.8 KB	0.3%
innfeed	6876	0.3%	1.0 MB	0.3%
controlchan	3	0.0%	0.3 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 6	2676854	100.0%	302.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2622954	95.0%
Negative hits	89199	3.2%
Positive hits	48028	1.7%
Cache misses	1232	0.0%
TOTAL: 4	2761413	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:03:14.351	0.2%	3287	0.000	59.127	897.000
article cleanup	00:00:33.485	0.0%	2631673	0.004	0.013	0.367
article logging	00:01:36.977	0.1%	2631826	0.015	0.037	0.964
article parse	01:11:11.761	4.9%	55309176	0.032	0.077	0.196
article write	00:30:36.694	2.1%	2609722	0.364	0.704	6.123
artlog/artcncl	00:00:00.083	0.0%	3272	0.000	0.025	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:02:27.643	0.2%	68017770	0.001	0.002	0.035
hisgrep/artcncl	00:00:22.319	0.0%	1140	0.000	19.578	350.000
hishave/artcncl	00:00:31.561	0.0%	3287	0.000	9.602	279.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	06:02:10.749	25.1%	2760249	0.010	7.873	32.781
history sync	00:00:00.006	0.0%	291	0.000	0.021	1.000
history write	07:22:12.081	30.7%	2622702	0.107	10.116	42.577
hiswrite/artcncl	00:00:27.235	0.0%	2147	0.000	12.685	124.000
idle	01:16:26.848	5.3%	12250854	0.158	0.374	4.028
nntp read	00:32:59.295	2.3%	52948947	0.023	0.037	0.082
overview write	00:00:18.472	0.0%	2609722	0.001	0.007	0.176
perl filter	06:32:29.088	27.2%	2622701	5.274	8.979	17.527
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:08:26.443	0.6%	5275020	0.003	0.096	0.722
TOTAL: 24:00:46.677	23:46:05.091	99.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.248	0.0%	56265	0.000	0.004	0.526
article prepare	00:00:00.020	0.0%	70536	0.000	0.000	0.125
article read	00:00:16.546	0.0%	17484	0.000	0.946	59.917
backlog stats	00:00:00.616	0.0%	377393	0.000	0.002	17.000
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:04.142	0.0%	220084	0.000	0.019	5.623
data write	00:00:20.378	0.0%	202075	0.000	0.101	13.516
idle	23:58:01.642	99.9%	377393	0.000	228.625	8040.000
status file	00:00:04.643	0.0%	236	1.000	19.674	203.500
TOTAL: 23:59:03.815	23:58:48.235	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	20:44:28.717	99.9%	12894	0.000	5790.966	319182.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.861	0.0%	27759	0.000	0.031	1.333
readart	00:00:04.025	0.0%	460	0.000	8.750	297.000
TOTAL: 20:45:13.178	20:44:33.603	99.9%	-	-	-	-

Control commands to INND:

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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
newgroups-request@fido7.ru	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	128	2614290	2592211	3490	18589	99%	559:25:54
2	news.nask.pl	4	19874	10070	7798	2006	50%	23:50:26
3	news.task.gda.pl	12	35671	5836	28484	1351	16%	46:45:07
4	localhost	10	9	9	0	0	100%	00:00:01
5	news.icm.edu.pl	34	349	4	324	21	1%	20:23:23
TOTAL: 5		188	2670193	2608130	40096	21967	97%	650:24:51

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	791.1 GB	848.8 MB	2.5 GB	794.4 GB	99%	319.0 KB
2	news.nask.pl	297.1 MB	29.7 MB	30.5 MB	357.4 MB	83%	30.3 KB
3	news.task.gda.pl	12.6 MB	121.0 KB	3.9 MB	16.7 MB	75%	2.4 KB
4	news.icm.edu.pl	47.1 KB	54.6 KB	298.7 KB	400.4 KB	11%	16.0 KB
5	localhost	15.3 KB	0.0 KB	0.0 KB	15.3 KB	100%	1.7 KB
TOTAL: 5		791.4 GB	878.7 MB	2.5 GB	794.7 GB	99%	316.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 14 04:15:05 - 04:59:59	57171	2.2%	21.21	17.0 GB	2.1%	6613.21
Jun 14 05:00:00 - 05:59:59	95679	3.7%	26.58	31.3 GB	4.0%	9121.31
Jun 14 06:00:00 - 06:59:59	95470	3.7%	26.52	30.5 GB	3.9%	8891.28
Jun 14 07:00:00 - 07:59:59	92351	3.5%	25.65	28.6 GB	3.6%	8341.67
Jun 14 08:00:00 - 08:59:59	108282	4.2%	30.08	34.3 GB	4.3%	10005.10
Jun 14 09:00:00 - 09:59:59	103378	4.0%	28.72	34.0 GB	4.3%	9910.59
Jun 14 10:00:00 - 10:59:59	112025	4.3%	31.12	35.0 GB	4.4%	10196.13
Jun 14 11:00:00 - 11:59:59	114021	4.4%	31.67	34.8 GB	4.4%	10141.60
Jun 14 12:00:00 - 12:59:59	122664	4.7%	34.07	34.2 GB	4.3%	9958.06
Jun 14 13:00:00 - 13:59:59	121125	4.6%	33.65	34.7 GB	4.4%	10107.64
Jun 14 14:00:00 - 14:59:59	115532	4.4%	32.09	36.0 GB	4.6%	10492.57
Jun 14 15:00:00 - 15:59:59	118000	4.5%	32.78	37.1 GB	4.7%	10818.56
Jun 14 16:00:00 - 16:59:59	108392	4.2%	30.11	34.2 GB	4.3%	9971.62
Jun 14 17:00:00 - 17:59:59	117499	4.5%	32.64	35.2 GB	4.4%	10253.40
Jun 14 18:00:00 - 18:59:59	125942	4.8%	34.98	35.4 GB	4.5%	10304.74
Jun 14 19:00:00 - 19:59:59	123974	4.8%	34.44	34.8 GB	4.4%	10143.53
Jun 14 20:00:00 - 20:59:59	111404	4.3%	30.95	33.5 GB	4.2%	9762.73
Jun 14 21:00:00 - 21:59:59	122731	4.7%	34.09	34.1 GB	4.3%	9934.76
Jun 14 22:00:00 - 22:59:59	108022	4.1%	30.01	36.9 GB	4.7%	10746.47
Jun 14 23:00:00 - 23:59:59	106312	4.1%	29.53	36.3 GB	4.6%	10574.24
Jun 15 00:00:00 - 00:59:59	110446	4.2%	30.68	31.1 GB	3.9%	9050.95
Jun 15 01:00:00 - 01:59:59	110399	4.2%	30.67	33.7 GB	4.3%	9812.66
Jun 15 02:00:00 - 02:59:59	83559	3.2%	23.21	25.2 GB	3.2%	7352.77
Jun 15 03:00:00 - 03:59:59	99911	3.8%	27.75	26.2 GB	3.3%	7618.50
Jun 15 04:00:00 - 04:15:04	23784	0.9%	26.31	7.0 GB	0.9%	8158.84
TOTAL: 23:59:59	2608073	100.0%	30.19	791.4 GB	100.0%	9604.71

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	18699	328	7066	5	11300
2	news.nask.pl	2072	184	1838	2	48
3	news.task.gda.pl	1305	1087	57	0	161
4	news.icm.edu.pl	21	0	4	0	17
TOTAL: 4		22097	1599	8965	7	11526

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.sex.telephones	166
mailing.freebsd.svn-ports-all	109
mailing.freebsd.svn-ports-head	109
uk.railway	104
hr.alt.cellular.gsm.android	59
it.tlc.cellulari.android	51
mailing.freebsd.svn-src-all	46
fa.freebsd.cvs-all	43
alt.politics.scorched-earth	42
mailing.freebsd.current	41
mailing.postfix.users	41
mailing.unix.dovecot	41
uk.rec.cycling.moderated	35
mailing.gcc.gcc-cvs	33
gmane.linux.alsa.devel	32
mailing.openbsd.source-changes	32
24hoursupport.helpdesk	30
mailing.freebsd.svn-src-head	30
mozilla.dev.gaia	28
mailing.freebsd.ports	27
TOTAL: 83	1599

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

Groups	Count
pl.sci.historia	6
bit.listserv.catholic	1
TOTAL: 2	7

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	11075
EMP (fsl)	114
HTML post	88
Attached HTML file	56
EMP (md5)	34
Scoring filter (9)	3
Scoring filter (11)	1
Scoring filter (12)	1
TOTAL: 8	11372

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Blocked server feeds
	overview!	2
	TOTAL: 1	2
TOTAL: 1		2

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	panorama	56854	2993	51387	1138	0	5%	24:02:25
2	icm	69972	2791	38507	10590	730	3%	24:00:02
3	poznan	15908	61	13900	41	0	0%	23:58:37
4	task	1821	8	1037	5	0	0%	21:32:54
5	nask	15555	1	13471	0	1	0%	24:00:02
6	okapi	0	0	0	0	2625	0%	24:00:03
TOTAL: 6		160110	5854	118302	11774	3356	3%	141:34:03

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	361.9 MB	3.1 GB	3.5 GB	42.2 KB/s	272.8 KB	24:00:02
2	panorama	14.8 MB	12.4 MB	27.2 MB	0.3 KB/s	6.8 KB	24:02:25
3	poznan	287.5 KB	196.4 KB	483.9 KB	0.0 KB/s	4.7 KB	23:58:37
4	task	12.7 KB	9.4 KB	22.1 KB	0.0 KB/s	1.7 KB	21:32:54
5	nask	2.0 KB	0.0 KB	2.0 KB	0.0 KB/s	2.0 KB	24:00:02
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:03
TOTAL: 6		377.0 MB	3.1 GB	3.5 GB	7.2 KB/s	208.7 KB	141:34:03

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	vegas.cm.p.lodz.pl	288	356	621.7 KB	288	0	00:00:12
2	web.man.lodz.pl	288	64	61.5 KB	30	0	00:00:22
3	212.191.196.1	2	26	66.8 KB	4	0	00:31:54
4	router.cbmm.lodz.pl	1	7	11.6 KB	1	0	00:03:56
5	ip-176.105.135.22.tvsat364.lodz.pl	1	7	11.7 KB	1	0	00:22:32
6	kujawiak.man.lodz.pl	9	0	0.0 KB	0	9	00:00:02
TOTAL: 6		589	460	773.3 KB	324	9	20:45:12

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	356	621.7 KB	288	0	00:00:12
2	*.man.lodz.pl	681	64	61.5 KB	30	9	00:00:36
3	unresolved	98	26	66.8 KB	4	0	17:37:07
4	*.cbmm.lodz.pl	5	7	11.6 KB	1	0	00:15:58
5	*.105.135.22.tvsat364.lodz.pl	1	7	11.7 KB	1	0	00:22:32
6	*.181.14.62.static.jazztel.es	3	0	0.0 KB	0	0	02:27:23
7	*.toya.net.pl	69	0	0.0 KB	0	0	00:01:22
TOTAL: 7		1145	460	773.3 KB	324	9	20:45:12

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	0.376	3.768	17:05:13
144.181.14.62.static.jazztel.es	0.628	1.076	02:27:23
212.191.196.1	0.000	0.244	00:31:54
ip-176.105.135.22.tvsat364.lodz.pl	0.000	0.068	00:22:32
router2.cbmm.lodz.pl	0.020	0.132	00:12:01
router.cbmm.lodz.pl	0.008	0.048	00:03:56
staticline41435.toya.net.pl	0.444	4.328	00:01:22
web.man.lodz.pl	2.104	28.736	00:00:22
vegas.cm.p.lodz.pl	1.652	15.140	00:00:12
f-oper-v100.man.lodz.pl	1.740	24.820	00:00:09
kujawiak.man.lodz.pl	0.124	0.536	00:00:02
david.man.lodz.pl	0.380	3.816	00:00:01
TOTAL: 12	7.476	82.712	20:45:12

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	288
212.191.78.190	96
david.man.lodz.pl	96
staticline41435.toya.net.pl	69
router2.cbmm.lodz.pl	4
144.181.14.62.static.jazztel.es	3
TOTAL: 6	556

NNRP client timeouts [Top 20]:

System	Conn	Peer
212.191.78.190	2	0
144.181.14.62.static.jazztel.es	1	0
TOTAL: 2	3	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	356	77.4%
2	pl	104	22.6%
TOTAL: 2		460	100.0%

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

Newsgroup		Count
1	lodman.info	356
2	pl.comp.pecet	56
3	pl.regionalne.lodz	20
4	pl.misc.paranauki	8
5	pl.comp.os.ms-windows.winnt	8
6	pl.sci.filozofia	6
7	pl.comp.bazy-danych	6
TOTAL: 7		460

Daily Usenet report

Jun 14 04:15:05 -- Jun 15 04:15:04