Daily Usenet report: Jun 4 04:15:03

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Jun 4 10:02:12 newsfeed nnrpd[12448]: SERVER perl filter not defined
Jun 4 11:50:04 newsfeed pgpverify[15878]: pgpverify: verification failed
Jun 4 11:54:31 newsfeed nnrpd[15917]: SERVER perl filter not defined
Jun 4 20:47:55 newsfeed nnrpd[589]: SERVER perl filter not defined
Jun 4 21:38:56 newsfeed nnrpd[2584]: SERVER perl filter not defined
Jun 5 01:32:33 newsfeed pgpverify[9951]: pgpverify: verification failed
Jun 5 04:15:04 newsfeed nnrpd[15158]: web.man.lodz.pl rejected Flushing log and syslog files
Jun 5 04:15:04 newsfeed nnrpd[15159]: web.man.lodz.pl rejected Flushing log and syslog files

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2195291	98.0%	248.2 MB	97.6%
innd	26243	1.2%	4.0 MB	1.6%
nnrpd	10418	0.5%	1.1 MB	0.4%
innfeed	7440	0.3%	1.1 MB	0.4%
controlchan	6	0.0%	0.9 KB	0.0%
pgpverify	2	0.0%	0.4 KB	0.0%
TOTAL: 6	2239400	100.0%	254.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2184926	94.1%
Negative hits	90482	3.9%
Positive hits	47107	2.0%
Cache misses	274	0.0%
TOTAL: 4	2322789	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:16.134	0.0%	1053	0.000	15.322	838.000
article cleanup	00:00:30.862	0.0%	2193306	0.003	0.014	0.320
article logging	00:01:14.314	0.1%	2193467	0.011	0.034	0.696
article parse	01:03:35.279	4.4%	70908861	0.025	0.054	0.193
article write	00:21:59.734	1.5%	2174854	0.351	0.607	2.757
artlog/artcncl	00:00:00.017	0.0%	1029	0.000	0.017	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:02:31.463	0.2%	82933415	0.001	0.002	0.016
hisgrep/artcncl	00:00:03.078	0.0%	327	0.000	9.413	385.000
hishave/artcncl	00:00:02.345	0.0%	1053	0.000	2.227	252.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:28:05.043	10.3%	2323535	0.052	3.824	18.677
history sync	00:00:00.010	0.0%	291	0.000	0.034	1.000
history write	03:53:16.454	16.2%	2185783	0.104	6.403	27.610
hiswrite/artcncl	00:00:03.505	0.0%	726	0.000	4.828	132.000
idle	09:38:52.681	40.2%	43497996	0.178	0.798	6.051
nntp read	00:37:02.848	2.6%	69218743	0.016	0.032	0.096
overview write	00:00:14.497	0.0%	2174854	0.000	0.007	0.034
perl filter	05:22:45.220	22.4%	2185778	6.192	8.860	19.780
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:06:22.004	0.4%	4392701	0.022	0.087	0.500
TOTAL: 23:59:40.770	23:36:55.488	98.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.205	0.0%	43000	0.000	0.005	1.138
article prepare	00:00:00.023	0.0%	58900	0.000	0.000	0.125
article read	00:00:01.659	0.0%	13592	0.000	0.122	62.889
backlog stats	00:00:00.249	0.0%	336503	0.000	0.001	0.094
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:03.010	0.0%	200602	0.000	0.015	3.941
data write	00:00:06.644	0.0%	165591	0.000	0.040	3.115
idle	23:59:42.899	100.0%	336503	0.000	256.708	11715.000
status file	00:00:03.131	0.0%	215	1.000	14.563	262.000
TOTAL: 24:00:08.210	23:59:57.820	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:05.190	0.0%	2568	1.388	2.021	2.114
idle	65:51:06.908	100.0%	65654	0.000	3610.852	450208.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:03.166	0.0%	134034	0.000	0.024	2.333
readart	00:00:06.814	0.0%	624	0.000	10.920	76.333
TOTAL: 65:52:05.948	65:51:22.078	100.0%	-	-	-	-

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
admin@openwatcom.news-admin.org	0	0	0	1	0	0
moderator@dana.de	0	0	0	1	0	0
TOTAL	0	0	0	2	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	637	2179403	2163106	905	15392	99%	367:28:13
2	news.nask.pl	4	21091	6596	12815	1680	31%	23:46:43
3	news.task.gda.pl	11	32997	5867	25760	1370	17%	46:34:45
4	news.icm.edu.pl	34	336	5	320	11	1%	18:45:29
5	localhost	5	4	4	0	0	100%	00:00:00
TOTAL: 5		691	2233831	2175578	39800	18453	97%	456:35:10

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	637.4 GB	284.9 MB	1.7 GB	639.3 GB	99%	307.7 KB
2	news.nask.pl	158.2 MB	27.3 MB	27.3 MB	212.8 MB	74%	26.3 KB
3	news.task.gda.pl	12.9 MB	74.5 KB	4.3 MB	17.3 MB	74%	2.4 KB
4	news.icm.edu.pl	239.1 KB	31.3 KB	389.0 KB	659.4 KB	36%	41.2 KB
5	localhost	7.6 KB	0.0 KB	0.0 KB	7.6 KB	100%	1.9 KB
TOTAL: 5		637.5 GB	312.3 MB	1.7 GB	639.5 GB	99%	305.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 4 04:15:03 - 04:59:59	66456	3.1%	24.64	20.7 GB	3.2%	8031.24
Jun 4 05:00:00 - 05:59:59	121985	5.6%	33.88	37.6 GB	5.9%	10948.92
Jun 4 06:00:00 - 06:59:59	116291	5.3%	32.30	35.8 GB	5.6%	10424.72
Jun 4 07:00:00 - 07:59:59	89608	4.1%	24.89	27.8 GB	4.4%	8088.24
Jun 4 08:00:00 - 08:59:59	104511	4.8%	29.03	33.1 GB	5.2%	9640.98
Jun 4 09:00:00 - 09:59:59	111763	5.1%	31.05	35.4 GB	5.6%	10323.36
Jun 4 10:00:00 - 10:59:59	88408	4.1%	24.56	30.8 GB	4.8%	8971.70
Jun 4 11:00:00 - 11:59:59	116465	5.4%	32.35	36.5 GB	5.7%	10620.90
Jun 4 12:00:00 - 12:59:59	129323	5.9%	35.92	33.9 GB	5.3%	9886.85
Jun 4 13:00:00 - 13:59:59	89443	4.1%	24.85	25.7 GB	4.0%	7485.66
Jun 4 14:00:00 - 14:59:59	63580	2.9%	17.66	19.2 GB	3.0%	5587.66
Jun 4 15:00:00 - 15:59:59	64397	3.0%	17.89	19.3 GB	3.0%	5616.58
Jun 4 16:00:00 - 16:59:59	61764	2.8%	17.16	17.2 GB	2.7%	5009.55
Jun 4 17:00:00 - 17:59:59	61692	2.8%	17.14	17.4 GB	2.7%	5070.94
Jun 4 18:00:00 - 18:59:59	60879	2.8%	16.91	17.0 GB	2.7%	4960.77
Jun 4 19:00:00 - 19:59:59	61261	2.8%	17.02	15.8 GB	2.5%	4594.99
Jun 4 20:00:00 - 20:59:59	70275	3.2%	19.52	16.4 GB	2.6%	4764.96
Jun 4 21:00:00 - 21:59:59	45684	2.1%	12.69	9.7 GB	1.5%	2824.05
Jun 4 22:00:00 - 22:59:59	45813	2.1%	12.73	11.6 GB	1.8%	3365.35
Jun 4 23:00:00 - 23:59:59	69626	3.2%	19.34	19.9 GB	3.1%	5798.68
Jun 5 00:00:00 - 00:59:59	116976	5.4%	32.49	33.1 GB	5.2%	9648.29
Jun 5 01:00:00 - 01:59:59	121434	5.6%	33.73	34.0 GB	5.3%	9913.00
Jun 5 02:00:00 - 02:59:59	140425	6.5%	39.01	41.0 GB	6.4%	11942.22
Jun 5 03:00:00 - 03:59:59	125002	5.7%	34.72	38.9 GB	6.1%	11328.72
Jun 5 04:00:00 - 04:15:04	32585	1.5%	36.05	9.8 GB	1.5%	11419.02
TOTAL: 24:00:01	2175646	100.0%	25.18	637.6 GB	100.0%	7737.46

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	15521	302	5806	0	9413
2	news.nask.pl	1724	9	1678	1	36
3	news.task.gda.pl	1360	1152	40	0	168
4	news.icm.edu.pl	11	0	2	0	9
TOTAL: 4		18616	1463	7526	1	9626

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	119
mailing.freebsd.svn-ports-head	119
uk.railway	109
mailing.openbsd.source-changes	93
mailing.freebsd.ports-bugs	63
uk.rec.cycling.moderated	59
mailing.gcc.gcc-cvs	46
mailing.freebsd.svn-src-all	45
fa.freebsd.cvs-all	44
mailing.www.horde-bugs	39
mailing.freebsd.svn-src-head	38
it.tlc.cellulari.android	38
mailing.unix.samba-technical	36
mailing.openbsd.ports-changes	35
mailing.freebsd.ports	34
mozilla.dev.identity	29
mailing.postfix.users	25
gmane.linux.alsa.devel	24
alt.politics.scorched-earth	23
alt.windows7.general	22
TOTAL: 86	1463

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

Groups	Count
pl.sci.matematyka	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	9133
EMP (fsl)	136
HTML post	100
Attached HTML file	79
EMP (md5)	8
Scoring filter (9)	3
Scoring filter (12)	1
TOTAL: 7	9460

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	63606	7234	31146	3035	14	0	11%	23:59:51
2	panorama	49078	3070	38872	1121	0	0	6%	24:02:24
3	poznan	13634	104	11357	62	0	0	0%	24:08:34
4	nask	24556	56	15711	42	0	0	0%	24:00:01
5	task	2342	19	1215	15	0	0	0%	22:24:51
6	okapi	0	0	0	0	0	3211	0%	24:00:00
TOTAL: 6		153216	10483	98301	4275	14	3211	6%	142:35:41

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	77.6 MB	209.1 MB	286.8 MB	3.4 KB/s	28.6 KB	23:59:51
2	panorama	15.9 MB	13.8 MB	29.7 MB	0.4 KB/s	7.3 KB	24:02:24
3	nask	5.9 MB	43.5 MB	49.4 MB	0.6 KB/s	516.4 KB	24:00:01
4	poznan	531.4 KB	484.1 KB	1015.5 KB	0.0 KB/s	6.1 KB	24:08:34
5	task	34.6 KB	23.2 KB	57.8 KB	0.0 KB/s	1.7 KB	22:24:51
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 6		100.0 MB	266.9 MB	366.9 MB	0.7 KB/s	25.5 KB	142:35:41

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	vegas.cm.p.lodz.pl	288	312	740.0 KB	288	0	00:00:10
2	lze200.lze.lodz.pl	4	113	220.6 KB	7	0	00:49:08
3	212.191.78.190	238	102	191.0 KB	32	2	35:50:13
4	web.man.lodz.pl	289	68	80.3 KB	26	0	00:00:24
5	ip-176.105.135.22.tvsat364.lodz.pl	4	11	17.0 KB	5	0	01:30:18
6	eby162.neoplus.adsl.tpnet.pl	3	8	14.4 KB	2	0	00:00:34
7	router2.cbmm.lodz.pl	5	4	5.4 KB	2	0	00:41:36
8	212.191.196.1	2	4	6.0 KB	2	0	00:02:48
9	pc-vicki.man.lodz.pl	1	2	2.7 KB	1	0	00:30:18
10	kujawiak.man.lodz.pl	2	0	0.0 KB	0	2	00:00:00
TOTAL: 10		836	624	1.2 MB	365	4	65:52:04

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	312	740.0 KB	288	0	00:00:10
2	*.lze.lodz.pl	4	113	220.6 KB	7	0	00:49:08
3	unresolved	248	106	197.0 KB	34	2	35:53:47
4	*.man.lodz.pl	676	70	83.1 KB	27	2	00:30:50
5	*.105.135.22.tvsat364.lodz.pl	4	11	17.0 KB	5	0	01:30:18
6	*.neoplus.adsl.tpnet.pl	5	8	14.4 KB	2	0	00:04:46
7	*.cbmm.lodz.pl	5	4	5.4 KB	2	0	00:41:36
8	*.versanet.de	160	0	0.0 KB	0	0	00:00:36
9	*.207.15.62.static.jazztel.es	8	0	0.0 KB	0	0	26:19:32
10	*.internetdsl.tpnet.pl	1	0	0.0 KB	0	0	00:00:00
11	*.toya.net.pl	92	0	0.0 KB	0	0	00:01:16
TOTAL: 11		1491	624	1.2 MB	365	4	65:52:04

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	0.880	4.344	35:50:13
85.207.15.62.static.jazztel.es	5.040	6.676	25:06:24
ip-176.105.135.22.tvsat364.lodz.pl	0.020	0.092	01:30:18
232.207.15.62.static.jazztel.es	0.328	1.208	01:13:07
lze200.lze.lodz.pl	0.196	1.500	00:49:08
router2.cbmm.lodz.pl	0.012	0.128	00:41:36
pc-vicki.man.lodz.pl	0.004	0.064	00:30:18
aadq165.neoplus.adsl.tpnet.pl	0.244	0.116	00:04:12
212.191.196.1	0.020	0.228	00:02:48
staticline-31-182-167-137.toya.net.pl	0.024	0.080	00:01:00
i59f5e377.versanet.de	0.468	1.976	00:00:36
eby162.neoplus.adsl.tpnet.pl	0.008	0.136	00:00:34
194.167.18.244	0.012	0.100	00:00:26
web.man.lodz.pl	1.644	11.396	00:00:24
212.51.203.199	0.004	0.024	00:00:19
staticline41435.toya.net.pl	0.580	3.204	00:00:16
vegas.cm.p.lodz.pl	1.392	10.740	00:00:10
f-oper-v100.man.lodz.pl	1.312	11.008	00:00:05
david.man.lodz.pl	0.336	1.996	00:00:01
kujawiak.man.lodz.pl	0.060	0.268	00:00:00
TOTAL: 21	12.584	55.300	65:52:04

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	288
i59f5e377.versanet.de	160
david.man.lodz.pl	96
staticline41435.toya.net.pl	90
232.207.15.62.static.jazztel.es	6
194.167.18.244	6
aadq165.neoplus.adsl.tpnet.pl	2
85.207.15.62.static.jazztel.es	2
staticline-31-182-167-137.toya.net.pl	2
212.51.203.199	2
gcq193.internetdsl.tpnet.pl	1
TOTAL: 11	655

NNRP client timeouts [Top 20]:

System	Conn	Peer
85.207.15.62.static.jazztel.es	3	0
ip-176.105.135.22.tvsat364.lodz.pl	2	0
232.207.15.62.static.jazztel.es	1	1
pc-vicki.man.lodz.pl	1	0
router2.cbmm.lodz.pl	1	0
TOTAL: 5	8	2

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	320	51.3%
2	pl	304	48.7%
TOTAL: 2		624	100.0%

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

Newsgroup		Count
1	lodman.info	320
2	pl.rec.kuchnia	72
3	pl.comp.pecet	68
4	pl.rec.rowery	43
5	pl.misc.kolej	35
6	pl.regionalne.lodz	34
7	pl.rec.gory	14
8	pl.regionalne.warszawa	9
9	pl.soc.polityka	7
10	pl.comp.os.ms-windows.winnt	6
11	pl.soc.prawo	5
12	pl.sci.medycyna	2
13	pl.misc.paranauki	2
14	pl.internet.polip	2
15	pl.listserv.chomor-l	2
16	pl.comp.os.linux	2
17	pl.soc.dzieci	1
TOTAL: 17		624

Daily Usenet report

Jun 4 04:15:03 -- Jun 5 04:15:04