Daily Usenet report: Jul 4 04:15:04

Unknown entries from news log file:

First 7 / 7 lines (100.0%)

Jul 4 08:16:05 newsfeed nnrpd[29420]: SERVER perl filter not defined
Jul 4 10:29:13 newsfeed nnrpd[1264]: SERVER perl filter not defined
Jul 4 10:30:58 newsfeed pgpverify[1314]: pgpverify: verification failed
Jul 4 10:38:17 newsfeed nnrpd[1450]: SERVER perl filter not defined
Jul 4 11:50:06 newsfeed pgpverify[4134]: pgpverify: verification failed
Jul 4 12:40:04 newsfeed nnrpd[5687]: SERVER perl filter not defined
Jul 5 00:30:03 newsfeed nnrpd[28089]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1261060	97.9%	142.3 MB	97.3%
innd	11392	0.9%	2.0 MB	1.3%
nnrpd	8164	0.6%	861.0 KB	0.6%
innfeed	7321	0.6%	1.1 MB	0.7%
controlchan	4	0.0%	0.5 KB	0.0%
pgpverify	2	0.0%	0.3 KB	0.0%
TOTAL: 6	1287943	100.0%	146.1 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1254714	92.4%
Negative hits	56404	4.2%
Positive hits	40659	3.0%
Cache misses	6322	0.5%
TOTAL: 4	1358099	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:11.075	0.0%	359	0.000	30.850	865.000
article cleanup	00:00:17.815	0.0%	1260001	0.000	0.014	0.500
article logging	00:00:36.891	0.0%	1260312	0.009	0.029	0.672
article parse	00:37:33.507	2.6%	73422638	0.007	0.031	0.108
article write	00:17:20.489	1.2%	1253738	0.354	0.830	5.446
artlog/artcncl	00:00:00.004	0.0%	357	0.000	0.011	1.000
artlog/artparse	00:00:00.009	0.0%	219	0.000	0.041	0.500
data move	00:01:15.433	0.1%	81164206	0.000	0.001	0.011
hisgrep/artcncl	00:00:02.846	0.0%	264	0.000	10.780	278.000
hishave/artcncl	00:00:01.718	0.0%	359	0.000	4.786	287.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:59:04.656	12.4%	1359518	0.193	7.903	26.022
history sync	00:00:00.014	0.0%	292	0.000	0.048	1.000
history write	03:54:51.146	16.3%	1256184	0.663	11.217	34.071
hiswrite/artcncl	00:00:01.317	0.0%	95	0.000	13.863	328.000
idle	12:23:36.555	51.6%	58938367	0.130	0.757	3.656
nntp read	00:25:40.451	1.8%	72447593	0.008	0.021	0.069
overview write	00:00:07.407	0.0%	1253738	0.000	0.006	0.090
perl filter	02:55:30.448	12.2%	1255906	5.234	8.385	15.406
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:51.717	0.3%	2562847	0.017	0.090	1.164
TOTAL: 23:59:47.906	23:40:03.498	98.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.295	0.0%	56375	0.000	0.005	0.761
article prepare	00:00:00.021	0.0%	78568	0.000	0.000	0.062
article read	00:00:54.231	0.1%	19393	0.000	2.796	221.750
backlog stats	00:00:00.321	0.0%	409191	0.000	0.001	0.147
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:03.545	0.0%	238758	0.000	0.015	3.651
data write	00:00:13.410	0.0%	212228	0.000	0.063	19.689
idle	23:58:33.268	99.9%	409191	0.000	210.936	14050.000
status file	00:00:05.938	0.0%	283	0.000	20.982	320.500
TOTAL: 24:00:03.082	23:59:51.029	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:05.316	0.0%	109512	0.049	0.049	0.049
idle	43:21:24.264	99.9%	729149	0.000	214.064	450221.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:34.433	0.0%	1460149	0.000	0.024	0.778
readart	00:00:22.512	0.0%	109875	0.000	0.205	23.000
TOTAL: 43:23:39.566	43:22:26.525	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
control@usenet.org.uk	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	254	1253736	1242392	7575	3769	99%	176:59:44
2	news.task.gda.pl	20	29638	5620	22871	1147	18%	46:36:44
3	news.nask.pl	2	19654	5439	12648	1567	27%	23:51:41
4	news.icm.edu.pl	39	258	8	237	13	3%	18:11:59
5	localhost	6	5	5	0	0	100%	00:00:02
TOTAL: 5		321	1303291	1253464	43331	6496	96%	265:40:10

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	433.1 GB	14.2 MB	182.7 MB	433.3 GB	99%	364.6 KB
2	news.nask.pl	19.0 MB	3.3 MB	4.7 MB	27.0 MB	70%	3.9 KB
3	news.task.gda.pl	11.5 MB	59.8 KB	3.7 MB	15.3 MB	75%	2.3 KB
4	news.icm.edu.pl	139.8 KB	8.3 KB	1.1 MB	1.2 MB	11%	58.4 KB
5	localhost	6.8 KB	0.0 KB	0.0 KB	6.8 KB	100%	1.4 KB
TOTAL: 5		433.1 GB	17.5 MB	192.2 MB	433.3 GB	99%	360.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jul 4 04:15:04 - 04:59:59	64340	5.1%	23.86	18.2 GB	4.2%	7076.63
Jul 4 05:00:00 - 05:59:59	95929	7.7%	26.65	30.3 GB	7.0%	8812.69
Jul 4 06:00:00 - 06:59:59	49144	3.9%	13.65	20.4 GB	4.7%	5955.10
Jul 4 07:00:00 - 07:59:59	39929	3.2%	11.09	19.0 GB	4.4%	5543.07
Jul 4 08:00:00 - 08:59:59	44961	3.6%	12.49	19.6 GB	4.5%	5706.08
Jul 4 09:00:00 - 09:59:59	47335	3.8%	13.15	20.7 GB	4.8%	6017.18
Jul 4 10:00:00 - 10:59:59	35629	2.8%	9.90	14.9 GB	3.4%	4342.65
Jul 4 11:00:00 - 11:59:59	25432	2.0%	7.06	10.0 GB	2.3%	2919.69
Jul 4 12:00:00 - 12:59:59	41192	3.3%	11.44	15.8 GB	3.7%	4609.68
Jul 4 13:00:00 - 13:59:59	28862	2.3%	8.02	10.7 GB	2.5%	3118.89
Jul 4 14:00:00 - 14:59:59	30044	2.4%	8.35	10.5 GB	2.4%	3056.26
Jul 4 15:00:00 - 15:59:59	28602	2.3%	7.95	10.3 GB	2.4%	3001.37
Jul 4 16:00:00 - 16:59:59	28304	2.3%	7.86	9.8 GB	2.3%	2859.51
Jul 4 17:00:00 - 17:59:59	41718	3.3%	11.59	15.4 GB	3.6%	4484.22
Jul 4 18:00:00 - 18:59:59	29952	2.4%	8.32	9.8 GB	2.3%	2852.86
Jul 4 19:00:00 - 19:59:59	27177	2.2%	7.55	9.1 GB	2.1%	2649.28
Jul 4 20:00:00 - 20:59:59	30006	2.4%	8.34	9.7 GB	2.2%	2823.34
Jul 4 21:00:00 - 21:59:59	41490	3.3%	11.53	12.4 GB	2.9%	3607.72
Jul 4 22:00:00 - 22:59:59	30202	2.4%	8.39	8.5 GB	2.0%	2476.94
Jul 4 23:00:00 - 23:59:59	49594	4.0%	13.78	16.6 GB	3.8%	4839.18
Jul 5 00:00:00 - 00:59:59	94423	7.5%	26.23	31.5 GB	7.3%	9163.77
Jul 5 01:00:00 - 01:59:59	104639	8.3%	29.07	32.3 GB	7.5%	9410.51
Jul 5 02:00:00 - 02:59:59	114386	9.1%	31.77	35.5 GB	8.2%	10352.09
Jul 5 03:00:00 - 03:59:59	106699	8.5%	29.64	34.2 GB	7.9%	9969.85
Jul 5 04:00:00 - 04:15:03	23921	1.9%	26.49	8.0 GB	1.8%	9245.26
TOTAL: 23:59:59	1253910	100.0%	14.51	433.2 GB	100.0%	5258.00

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	4048	259	2757	4	1028
2	news.nask.pl	1616	293	1252	4	67
3	news.task.gda.pl	1116	954	25	2	135
4	news.icm.edu.pl	13	0	1	0	12
TOTAL: 4		6793	1506	4035	10	1242

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.sex.telephones	284
uk.railway	111
mailing.freebsd.ports-bugs	73
mailing.freebsd.svn-src-all	69
fa.freebsd.cvs-all	69
mailing.freebsd.svn-src-head	59
gmane.linux.alsa.devel	57
alt.politics.scorched-earth	57
alt.windows7.general	41
mailing.openbsd.misc	37
mailing.freebsd.svn-ports-all	36
mailing.freebsd.svn-ports-head	36
it.tlc.cellulari.android	34
24hoursupport.helpdesk	32
mailing.gcc.gcc-cvs	31
mailing.openbsd.source-changes	29
mailing.database.pgsql-general	28
gnu.hurd.bug	27
mailing.freebsd.current	25
mailing.openbsd.ports-changes	20
TOTAL: 84	1506

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

Groups	Count
pl.sci.historia	6
fr.comp.securite	2
bit.listserv.catholic	1
pl.sci.matematyka	1
TOTAL: 4	10

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	511
EMP (fsl)	114
EMP (md5)	19
HTML post	9
TOTAL: 4	653

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	panorama	58158	2689	51743	1093	0	4%	24:03:33
2	icm	74895	1353	36979	14488	984	1%	24:04:21
3	task	2291	96	1187	10	0	4%	21:34:56
4	nask	22855	54	15736	0	0	0%	24:07:00
5	poznan	12634	19	10773	9	0	0%	24:00:21
6	okapi	0	0	0	0	2440	0%	24:00:01
TOTAL: 6		170833	4211	116418	15600	3424	2%	141:50:12

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	18.9 MB	3.2 GB	3.2 GB	39.2 KB/s	214.5 KB	24:04:21
2	panorama	7.8 MB	10.0 MB	17.9 MB	0.2 KB/s	4.8 KB	24:03:33
3	task	195.0 KB	16.6 KB	211.6 KB	0.0 KB/s	2.0 KB	21:34:56
4	nask	163.1 KB	0.0 KB	163.1 KB	0.0 KB/s	3.0 KB	24:07:00
5	poznan	68.1 KB	16.4 KB	84.4 KB	0.0 KB/s	3.0 KB	24:00:21
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 6		27.2 MB	3.2 GB	3.3 GB	6.7 KB/s	172.5 KB	141:50:12

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	vegas.cm.p.lodz.pl	288	348	499.8 KB	288	0	00:00:07
2	web.man.lodz.pl	288	56	67.2 KB	28	0	00:00:48
3	router2.cbmm.lodz.pl	5	3	7.2 KB	1	0	00:36:35
4	212.191.78.190	115	1	3.4 KB	1	0	19:10:39
5	kujawiak.man.lodz.pl	5	0	0.0 KB	0	5	00:00:01
TOTAL: 5		701	408	577.6 KB	318	5	43:23:38

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	348	499.8 KB	288	0	00:00:07
2	*.man.lodz.pl	677	56	67.2 KB	28	5	00:00:57
3	*.cbmm.lodz.pl	5	3	7.2 KB	1	0	00:36:35
4	unresolved	115	1	3.4 KB	1	0	19:10:39
5	*.191.14.62.static.jazztel.es	5	0	0.0 KB	0	0	23:34:34
6	*.internetdsl.tpnet.pl	3	0	0.0 KB	0	0	00:00:00
7	*.toya.net.pl	31	0	0.0 KB	0	0	00:00:44
TOTAL: 7		1124	408	577.6 KB	318	5	43:23:38

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
171.191.14.62.static.jazztel.es	53.923	83.574	23:34:34
212.191.78.190	0.324	3.200	19:10:39
router2.cbmm.lodz.pl	0.064	0.288	00:36:35
web.man.lodz.pl	0.888	8.272	00:00:48
staticline41435.toya.net.pl	0.052	0.712	00:00:44
vegas.cm.p.lodz.pl	0.800	6.568	00:00:07
f-oper-v100.man.lodz.pl	1.212	13.252	00:00:07
kujawiak.man.lodz.pl	0.048	0.368	00:00:01
david.man.lodz.pl	0.248	2.104	00:00:00
gcq193.internetdsl.tpnet.pl	0.028	0.356	00:00:00
TOTAL: 10	57.587	118.694	43:23:38

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	31
171.191.14.62.static.jazztel.es	5
gcq193.internetdsl.tpnet.pl	3
TOTAL: 5	423

NNRP client timeouts [Top 20]:

System	Conn	Peer
router2.cbmm.lodz.pl	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	348	85.3%
2	pl	60	14.7%
TOTAL: 2		408	100.0%

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

Newsgroup		Count
1	lodman.info	348
2	pl.comp.os.ms-windows.winnt	28
3	pl.comp.pecet	18
4	pl.internet.polip	8
5	pl.regionalne.lodz	3
6	pl.rec.mineraly	2
7	pl.soc.prawo	1
TOTAL: 7		408

Daily Usenet report

Jul 4 04:15:04 -- Jul 5 04:15:03