Daily Usenet report: Jan 29 04:15:03

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Jan 29 14:22:35 newsfeed nnrpd[22990]: SERVER perl filter not defined
Jan 29 22:00:05 newsfeed pgpverify[5779]: pgpverify: verification failed
Jan 29 23:52:09 newsfeed nnrpd[9474]: SERVER perl filter not defined
Jan 30 00:24:18 newsfeed nnrpd[10505]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1929414	97.9%	229.4 MB	97.4%
innd	16370	0.8%	2.7 MB	1.2%
innfeed	14578	0.7%	2.2 MB	0.9%
nnrpd	11067	0.6%	1.1 MB	0.5%
controlchan	2	0.0%	0.2 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 6	1971432	100.0%	235.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1922452	94.3%
Negative hits	73414	3.6%
Positive hits	43338	2.1%
Cache misses	299	0.0%
TOTAL: 4	2039503	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:09.731	0.0%	285	0.000	34.144	454.000
article cleanup	00:00:29.617	0.0%	1928726	0.005	0.015	0.032
article logging	00:01:01.850	0.1%	1928921	0.014	0.032	0.440
article parse	00:59:06.713	4.1%	110941960	0.013	0.032	0.087
article write	00:14:12.097	1.0%	1921179	0.274	0.444	1.235
artlog/artcncl	00:00:00.003	0.0%	274	0.000	0.011	1.000
artlog/artparse	00:00:00.000	0.0%	4	0.000	0.000	0.000
data move	00:02:27.170	0.2%	121599752	0.001	0.001	0.006
hisgrep/artcncl	00:00:01.748	0.0%	277	0.000	6.310	129.000
hishave/artcncl	00:00:01.282	0.0%	285	0.000	4.498	214.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	04:27:23.811	18.6%	2040532	2.080	7.863	14.961
history sync	00:00:00.005	0.0%	293	0.000	0.017	1.000
history write	05:00:38.387	20.9%	1923614	5.597	9.377	18.141
hiswrite/artcncl	00:00:00.019	0.0%	8	0.000	2.375	15.000
idle	07:41:33.443	32.1%	71548196	0.166	0.387	4.404
nntp read	00:46:44.172	3.2%	109413116	0.013	0.026	0.040
overview write	00:00:12.599	0.0%	1921179	0.000	0.007	0.016
perl filter	04:05:12.021	17.0%	1923610	5.233	7.648	10.640
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:30.911	0.3%	3885335	0.008	0.070	0.092
TOTAL: 23:59:53.528	23:23:45.579	97.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.740	0.0%	241129	0.000	0.003	0.091
article prepare	00:00:00.078	0.0%	188182	0.000	0.000	0.250
article read	00:02:01.472	0.1%	47641	0.000	2.550	67.000
backlog stats	00:00:00.647	0.0%	781119	0.000	0.001	1.000
callbacks	00:00:00.010	0.0%	1159	0.000	0.009	1.000
data read	00:00:05.147	0.0%	399657	0.000	0.013	1.000
data write	00:00:23.429	0.0%	396384	0.000	0.059	11.000
idle	23:56:54.032	99.8%	781119	0.000	110.372	3683.000
status file	00:00:01.760	0.0%	223	0.000	7.892	175.500
TOTAL: 23:59:45.725	23:59:27.315	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	68:46:51.302	100.0%	52895	0.000	4681.185	600061.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.843	0.0%	108308	0.000	0.017	0.667
readart	00:00:03.321	0.0%	582	0.000	5.706	315.000
TOTAL: 68:47:53.816	68:46:56.466	100.0%	-	-	-	-

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	rmgroup	Bad PGP	OK
group-admin@isc.org	0	1	0
sipaq@gmx.de	1	0	1
TOTAL	1	1	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	586	1915299	1907956	2780	4563	99%	308:12:21
2	news.nask.pl	4	18961	6830	10538	1593	36%	24:16:40
3	news.task.gda.pl	14	32528	6648	24524	1356	20%	46:29:38
4	localhost	5	4	4	0	0	100%	00:00:00
5	news.icm.edu.pl	51	358	0	329	29	0%	17:42:08
TOTAL: 5		660	1967150	1921438	38171	7541	97%	396:40:47

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	496.4 GB	42.1 MB	66.9 MB	496.5 GB	99%	272.2 KB
2	news.nask.pl	38.9 MB	4.8 MB	4.9 MB	48.6 MB	80%	5.9 KB
3	news.task.gda.pl	13.6 MB	66.3 KB	4.3 MB	18.0 MB	75%	2.3 KB
4	localhost	6.2 KB	0.0 KB	0.0 KB	6.2 KB	100%	1.5 KB
5	news.icm.edu.pl	0.0 KB	0.0 KB	1.1 MB	1.1 MB	0%	37.4 KB
TOTAL: 5		496.5 GB	46.9 MB	77.2 MB	496.6 GB	99%	269.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 29 04:15:03 - 04:59:59	67945	3.5%	25.19	20.9 GB	4.2%	8138.33
Jan 29 05:00:00 - 05:59:59	96192	5.0%	26.72	27.9 GB	5.6%	8121.31
Jan 29 06:00:00 - 06:59:59	91079	4.7%	25.30	28.5 GB	5.7%	8313.31
Jan 29 07:00:00 - 07:59:59	57685	3.0%	16.02	18.5 GB	3.7%	5392.11
Jan 29 08:00:00 - 08:59:59	63241	3.3%	17.57	17.6 GB	3.5%	5119.70
Jan 29 09:00:00 - 09:59:59	69795	3.6%	19.39	18.3 GB	3.7%	5331.85
Jan 29 10:00:00 - 10:59:59	57914	3.0%	16.09	16.1 GB	3.2%	4690.42
Jan 29 11:00:00 - 11:59:59	54991	2.9%	15.28	14.7 GB	3.0%	4283.97
Jan 29 12:00:00 - 12:59:59	74714	3.9%	20.75	18.0 GB	3.6%	5248.42
Jan 29 13:00:00 - 13:59:59	82213	4.3%	22.84	20.0 GB	4.0%	5838.19
Jan 29 14:00:00 - 14:59:59	81260	4.2%	22.57	20.1 GB	4.1%	5867.82
Jan 29 15:00:00 - 15:59:59	77770	4.0%	21.60	19.9 GB	4.0%	5802.44
Jan 29 16:00:00 - 16:59:59	58676	3.1%	16.30	15.6 GB	3.1%	4547.11
Jan 29 17:00:00 - 17:59:59	62371	3.2%	17.33	17.8 GB	3.6%	5175.12
Jan 29 18:00:00 - 18:59:59	60271	3.1%	16.74	16.3 GB	3.3%	4761.30
Jan 29 19:00:00 - 19:59:59	64510	3.4%	17.92	15.8 GB	3.2%	4610.13
Jan 29 20:00:00 - 20:59:59	70097	3.6%	19.47	16.6 GB	3.3%	4830.52
Jan 29 21:00:00 - 21:59:59	80973	4.2%	22.49	17.2 GB	3.5%	5021.28
Jan 29 22:00:00 - 22:59:59	91541	4.8%	25.43	19.1 GB	3.8%	5559.54
Jan 29 23:00:00 - 23:59:59	89096	4.6%	24.75	21.2 GB	4.3%	6181.38
Jan 30 00:00:00 - 00:59:59	95987	5.0%	26.66	26.1 GB	5.3%	7615.02
Jan 30 01:00:00 - 01:59:59	98990	5.2%	27.50	25.2 GB	5.1%	7353.94
Jan 30 02:00:00 - 02:59:59	133573	7.0%	37.10	30.6 GB	6.2%	8911.69
Jan 30 03:00:00 - 03:59:59	118542	6.2%	32.93	28.7 GB	5.8%	8351.08
Jan 30 04:00:00 - 04:15:04	21963	1.1%	24.30	5.5 GB	1.1%	6359.65
TOTAL: 24:00:01	1921389	100.0%	22.24	496.5 GB	100.0%	6025.83

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	4716	281	3543	2	890
2	news.nask.pl	1627	38	1541	3	45
3	news.task.gda.pl	1379	1141	34	0	204
4	news.icm.edu.pl	29	0	0	0	29
TOTAL: 4		7751	1460	5118	5	1168

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	117
mailing.freebsd.svn-ports-head	116
alt.politics.scorched-earth	114
it.tlc.cellulari.android	72
mailing.gcc.gcc-cvs	71
comp.mobile.ipad	69
mailing.freebsd.ports-bugs	59
mailing.postfix.users	53
mailing.freebsd.current	48
uk.railway	48
alt.windows7.general	35
mailing.freebsd.ports	34
comp.sys.raspberry-pi	33
gmane.linux.kernel.firewire.devel	28
24hoursupport.helpdesk	28
mailing.database.pgsql-general	27
it.comp.os.win.windows8	25
mailing.openbsd.source-changes	23
mailing.freebsd.svn-src-all	23
fa.freebsd.cvs-all	23
TOTAL: 83	1460

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

Groups	Count
pl.sci.historia	4
pl.sci.matematyka	1
TOTAL: 2	5

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	618
EMP (fsl)	177
Attached HTML file	134
EMP (md5)	29
HTML post	11
TOTAL: 5	969

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	245640	19947	195129	23502	1273	77	8%	24:00:04
2	panorama	44643	2414	40257	433	0	0	5%	24:01:29
3	poznan	15579	44	13159	14	0	0	0%	24:02:31
4	task	2686	8	1423	1	0	11	0%	22:34:41
5	nask	20108	6	14208	0	0	0	0%	24:00:27
6	okapi	0	0	0	0	0	2815	0%	24:00:00
TOTAL: 6		328656	22419	264176	23950	1273	2903	6%	142:39:12

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	2.4 GB	2.7 GB	5.1 GB	62.1 KB/s	123.5 KB	24:00:04
2	panorama	18.6 MB	1.3 MB	19.9 MB	0.2 KB/s	7.2 KB	24:01:29
3	poznan	294.5 KB	200.0 KB	494.5 KB	0.0 KB/s	8.5 KB	24:02:31
4	task	101.3 KB	1.9 KB	103.2 KB	0.0 KB/s	11.5 KB	22:34:41
5	nask	33.7 KB	0.0 KB	33.7 KB	0.0 KB/s	5.6 KB	24:00:27
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 6		2.4 GB	2.7 GB	5.1 GB	10.5 KB/s	116.2 KB	142:39:12

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	vegas.cm.p.lodz.pl	288	312	422.2 KB	288	0	00:00:20
2	web.man.lodz.pl	288	154	161.5 KB	65	0	00:00:30
3	212.191.78.190	314	87	159.2 KB	39	0	47:46:44
4	router2.cbmm.lodz.pl	4	29	56.7 KB	5	2	00:50:00
5	kujawiak.man.lodz.pl	2	0	0.0 KB	0	2	00:00:00
TOTAL: 5		896	582	799.5 KB	397	4	68:47:52

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	312	422.2 KB	288	0	00:00:20
2	*.man.lodz.pl	674	154	161.5 KB	65	2	00:00:37
3	unresolved	314	87	159.2 KB	39	0	47:46:44
4	*.cbmm.lodz.pl	4	29	56.7 KB	5	2	00:50:00
5	*.pa.nsw.optusnet.com.au	71	0	0.0 KB	0	0	00:02:05
6	*.4web.pl	94	0	0.0 KB	0	0	15:19:42
7	*.neoplus.adsl.tpnet.pl	2	0	0.0 KB	0	0	00:36:46
8	*.206.15.62.static.jazztel.es	4	0	0.0 KB	0	0	04:11:17
9	*.toya.net.pl	65	0	0.0 KB	0	0	00:00:18
TOTAL: 9		1516	582	799.5 KB	397	4	68:47:52

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	0.824	4.428	47:46:44
net133-43.4web.pl	0.220	1.332	15:19:42
241.206.15.62.static.jazztel.es	2.804	3.780	04:11:17
router2.cbmm.lodz.pl	0.020	0.072	00:50:00
ecl28.neoplus.adsl.tpnet.pl	0.008	0.024	00:36:46
pa49-181-246-191.pa.nsw.optusnet.com.au	0.192	0.956	00:02:05
web.man.lodz.pl	0.644	4.308	00:00:30
vegas.cm.p.lodz.pl	0.624	4.292	00:00:20
staticline41435.toya.net.pl	0.140	0.912	00:00:18
mon.man.lodz.pl	0.740	3.976	00:00:04
david.man.lodz.pl	0.292	1.260	00:00:01
kujawiak.man.lodz.pl	0.016	0.036	00:00:00
TOTAL: 12	6.524	25.376	68:47:52

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
net133-43.4web.pl	94
pa49-181-246-191.pa.nsw.optusnet.com.au	71
staticline41435.toya.net.pl	65
241.206.15.62.static.jazztel.es	4
ecl28.neoplus.adsl.tpnet.pl	2
TOTAL: 7	620

NNRP client timeouts [Top 20]:

System	Conn	Peer
212.191.78.190	2	1
ecl28.neoplus.adsl.tpnet.pl	1	0
TOTAL: 2	3	1

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	312	53.6%
2	pl	270	46.4%
TOTAL: 2		582	100.0%

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

Newsgroup		Count
1	lodman.info	312
2	pl.comp.pecet	130
3	pl.regionalne.lodz	43
4	pl.misc.kolej	41
5	pl.comp.os.ms-windows.winnt	24
6	pl.rec.rowery	10
7	pl.soc.prawo	8
8	pl.regionalne.warszawa	6
9	pl.rec.gory	5
10	pl.comp.os.linux	3
TOTAL: 10		582

Daily Usenet report

Jan 29 04:15:03 -- Jan 30 04:15:04