Daily Usenet report: Aug 23 04:15:02

Unknown entries from news log file:

First 23 / 23 lines (100.0%)

Aug 23 05:08:38 newsfeed pgpverify[6508]: pgpverify: verification failed
Aug 23 05:08:38 newsfeed controlchan[2301]: skipping newgroup y gcn@news.nic.it (pgpverify failed) in <20130823030517$56e9@wonderland.linux.it>
Aug 23 05:08:38 newsfeed pgpverify[6514]: pgpverify: verification failed
Aug 23 05:14:37 newsfeed pgpverify[6750]: pgpverify: verification failed
Aug 23 05:26:58 newsfeed pgpverify[7121]: pgpverify: verification failed
Aug 23 05:26:58 newsfeed controlchan[2301]: skipping newgroup m gcn@news.nic.it (pgpverify failed) in <20130823032515$1255@wonderland.linux.it>
Aug 23 05:30:17 newsfeed pgpverify[7209]: pgpverify: verification failed
Aug 23 06:07:30 newsfeed innfeed[2302]: icm:0 connect: Network is unreachable
Aug 23 08:07:30 newsfeed innfeed[2302]: icm:0 connect: Network is unreachable
Aug 23 08:30:19 newsfeed nnrpd[13110]: SERVER perl filter not defined
Aug 23 10:07:30 newsfeed innfeed[2302]: icm:0 connect: Network is unreachable
Aug 23 12:07:30 newsfeed innfeed[2302]: icm:0 connect: Network is unreachable
Aug 23 12:50:47 newsfeed nnrpd[21299]: SERVER perl filter not defined
Aug 23 14:07:30 newsfeed innfeed[2302]: icm:0 connect: Network is unreachable
Aug 23 15:02:03 newsfeed nnrpd[25417]: SERVER perl filter not defined
Aug 23 15:12:55 newsfeed nnrpd[25875]: SERVER perl filter not defined
Aug 23 15:13:34 newsfeed nnrpd[25908]: SERVER perl filter not defined
Aug 23 16:07:30 newsfeed innfeed[2302]: icm:0 connect: Network is unreachable
Aug 23 18:07:30 newsfeed innfeed[2302]: icm:0 connect: Network is unreachable
Aug 23 21:07:31 newsfeed innfeed[2302]: icm:0 connect: Network is unreachable
Aug 23 23:07:31 newsfeed innfeed[2302]: icm:0 connect: Network is unreachable
Aug 24 01:07:32 newsfeed innfeed[2302]: icm:0 connect: Network is unreachable
Aug 24 03:07:32 newsfeed innfeed[2302]: icm:0 connect: Network is unreachable

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1028988	97.7%	116.5 MB	97.1%
innd	9994	0.9%	1.7 MB	1.4%
nnrpd	7833	0.7%	832.8 KB	0.7%
innfeed	6393	0.6%	976.9 KB	0.8%
controlchan	11	0.0%	1.3 KB	0.0%
pgpverify	5	0.0%	0.8 KB	0.0%
TOTAL: 6	1053224	100.0%	120.0 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1023467	93.2%
Positive hits	40154	3.7%
Negative hits	34120	3.1%
Cache misses	204	0.0%
TOTAL: 4	1097945	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:07.562	0.0%	293	0.000	25.809	332.500
article cleanup	00:00:13.583	0.0%	1028518	0.000	0.013	0.031
article logging	00:00:24.637	0.0%	1028723	0.008	0.024	0.342
article parse	00:38:44.335	2.7%	77133207	0.016	0.030	0.058
article write	00:11:00.101	0.8%	1022832	0.415	0.645	2.154
artlog/artcncl	00:00:00.008	0.0%	290	0.000	0.028	1.000
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:01:05.440	0.1%	84072609	0.000	0.001	0.002
hisgrep/artcncl	00:00:01.378	0.0%	227	0.000	6.070	31.150
hishave/artcncl	00:00:01.199	0.0%	293	0.000	4.092	251.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:49:39.753	7.6%	1099239	0.010	5.986	17.671
history sync	00:00:00.009	0.0%	292	0.000	0.031	1.000
history write	02:29:02.466	10.3%	1024727	0.070	8.727	27.982
hiswrite/artcncl	00:00:00.537	0.0%	66	0.000	8.136	50.400
idle	15:23:09.018	64.1%	69441063	0.377	0.798	2.292
nntp read	00:29:28.403	2.0%	76360085	0.013	0.023	0.033
overview write	00:00:05.834	0.0%	1022832	0.000	0.006	0.018
perl filter	02:36:12.640	10.8%	1024725	6.375	9.146	11.929
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:01:41.323	0.1%	2081721	0.020	0.049	0.078
TOTAL: 24:00:22.971	23:40:58.226	98.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.100	0.0%	36053	0.000	0.003	0.111
article prepare	00:00:00.003	0.0%	13592	0.000	0.000	0.250
article read	00:00:00.095	0.0%	3344	0.000	0.028	1.000
backlog stats	00:00:00.100	0.0%	185045	0.000	0.001	0.024
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.035	0.0%	114306	0.000	0.009	0.107
data write	00:00:01.990	0.0%	78288	0.000	0.025	0.150
idle	24:00:19.943	100.0%	185045	0.000	467.021	13947.000
status file	00:00:00.942	0.0%	244	0.000	3.861	121.500
TOTAL: 24:00:29.519	24:00:24.208	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	06:30:46.813	99.9%	155566	0.000	150.719	600074.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.410	0.0%	313097	0.000	0.014	0.500
readart	00:00:00.213	0.0%	447	0.000	0.477	5.778
TOTAL: 06:31:12.487	06:30:51.436	99.9%	-	-	-	-

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
gcn@news.nic.it	0	0	0	2	0	0
linux-admin@bofh.it	0	0	0	1	0	0
TOTAL	0	0	0	3	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	191	1017131	1012506	1058	3567	99%	100:47:58
2	news.task.gda.pl	19	29421	5688	22577	1156	19%	46:08:03
3	news.nask.pl	2	18201	4329	12910	962	23%	24:25:58
4	localhost	6	5	5	0	0	100%	00:00:00
5	news.icm.edu.pl	1	14	0	14	0	0%	00:27:43
TOTAL: 5		219	1064772	1022528	36559	5685	96%	171:49:42

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	373.8 GB	34.8 MB	82.4 MB	373.9 GB	99%	385.9 KB
2	news.nask.pl	15.5 MB	2.5 MB	2.9 MB	20.9 MB	74%	4.0 KB
3	news.task.gda.pl	12.4 MB	32.9 KB	3.5 MB	16.0 MB	77%	2.4 KB
4	localhost	6.5 KB	0.0 KB	0.0 KB	6.5 KB	100%	1.3 KB
5	news.icm.edu.pl	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 5		373.8 GB	37.4 MB	88.8 MB	373.9 GB	99%	381.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 23 04:15:02 - 04:59:59	26992	2.6%	10.00	11.3 GB	3.0%	4393.02
Aug 23 05:00:00 - 05:59:59	39549	3.9%	10.99	17.9 GB	4.8%	5208.30
Aug 23 06:00:00 - 06:59:59	45301	4.4%	12.58	19.3 GB	5.2%	5619.22
Aug 23 07:00:00 - 07:59:59	42530	4.2%	11.81	18.2 GB	4.9%	5298.03
Aug 23 08:00:00 - 08:59:59	51864	5.1%	14.41	19.2 GB	5.1%	5595.20
Aug 23 09:00:00 - 09:59:59	44834	4.4%	12.45	17.8 GB	4.8%	5194.35
Aug 23 10:00:00 - 10:59:59	37991	3.7%	10.55	14.8 GB	4.0%	4322.76
Aug 23 11:00:00 - 11:59:59	28585	2.8%	7.94	11.1 GB	3.0%	3226.12
Aug 23 12:00:00 - 12:59:59	29884	2.9%	8.30	11.1 GB	3.0%	3225.62
Aug 23 13:00:00 - 13:59:59	28825	2.8%	8.01	11.2 GB	3.0%	3252.80
Aug 23 14:00:00 - 14:59:59	30983	3.0%	8.61	11.1 GB	3.0%	3224.21
Aug 23 15:00:00 - 15:59:59	33911	3.3%	9.42	11.2 GB	3.0%	3268.71
Aug 23 16:00:00 - 16:59:59	46580	4.6%	12.94	14.1 GB	3.8%	4113.89
Aug 23 17:00:00 - 17:59:59	63073	6.2%	17.52	19.0 GB	5.1%	5532.80
Aug 23 18:00:00 - 18:59:59	31059	3.0%	8.63	11.4 GB	3.0%	3309.53
Aug 23 19:00:00 - 19:59:59	31775	3.1%	8.83	11.4 GB	3.0%	3306.27
Aug 23 20:00:00 - 20:59:59	31680	3.1%	8.80	11.5 GB	3.1%	3339.73
Aug 23 21:00:00 - 21:59:59	38847	3.8%	10.79	14.5 GB	3.9%	4227.20
Aug 23 22:00:00 - 22:59:59	33917	3.3%	9.42	11.6 GB	3.1%	3374.98
Aug 23 23:00:00 - 23:59:59	33280	3.3%	9.24	11.6 GB	3.1%	3370.31
Aug 24 00:00:00 - 00:59:59	69235	6.8%	19.23	23.8 GB	6.4%	6924.29
Aug 24 01:00:00 - 01:59:59	58985	5.8%	16.38	20.2 GB	5.4%	5870.24
Aug 24 02:00:00 - 02:59:59	55859	5.5%	15.52	19.6 GB	5.2%	5716.38
Aug 24 03:00:00 - 03:59:59	63725	6.2%	17.70	23.2 GB	6.2%	6745.07
Aug 24 04:00:00 - 04:15:01	23538	2.3%	26.12	8.5 GB	2.3%	9886.39
TOTAL: 23:59:59	1022802	100.0%	11.84	374.3 GB	100.0%	4543.08

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3738	212	2823	0	703
2	news.task.gda.pl	1145	939	21	0	185
3	news.nask.pl	1013	10	952	2	49
TOTAL: 3		5896	1161	3796	2	937

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	81
mailing.freebsd.current	65
mailing.freebsd.svn-src-all	65
fa.freebsd.cvs-all	64
mailing.www.horde-bugs	45
gmane.comp.handhelds.openmoko.community	43
mozilla.dev.tree-management	43
alt.windows7.general	42
mailing.freebsd.svn-src-head	42
mailing.freebsd.ports-bugs	42
it.tlc.cellulari.android	41
mailing.freebsd.svn-ports-all	33
mailing.freebsd.svn-ports-head	33
mailing.postfix.users	31
gmane.linux.alsa.devel	29
mailing.gcc.gcc-cvs	27
mailing.unix.samba-technical	27
mailing.openbsd.ports-changes	22
gmane.comp.gis.openstreetmap	19
24hoursupport.helpdesk	18
TOTAL: 80	1161

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

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

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	550
EMP (fsl)	158
EMP (md5)	19
Attached HTML file	2
HTML post	1
TOTAL: 5	730

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	panorama	38501	2568	32788	724	0	6%	24:01:45
2	poznan	11842	35	9874	15	0	0%	23:54:43
3	nask	22950	26	14568	2	0	0%	24:01:19
4	task	1925	23	988	11	0	1%	21:22:03
5	okapi	0	0	0	0	2334	0%	24:00:02
6	icm	0	0	0	0	34734	0%	24:00:00
TOTAL: 6		75218	2652	58218	752	37068	3%	141:19:52

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	7.5 MB	2.3 MB	9.8 MB	0.1 KB/s	3.1 KB	24:01:45
2	poznan	105.7 KB	30.6 KB	136.4 KB	0.0 KB/s	2.7 KB	23:54:43
3	nask	82.2 KB	14.6 KB	96.8 KB	0.0 KB/s	3.5 KB	24:01:19
4	task	54.3 KB	18.5 KB	72.8 KB	0.0 KB/s	2.1 KB	21:22:03
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:02
6	icm	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 6		7.8 MB	2.4 MB	10.1 MB	0.0 KB/s	3.0 KB	141:19:52

Backlog files shrunk by innfeed:

Server	Size
okapi	488.4 KB
TOTAL: 1	488.4 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	vegas.cm.p.lodz.pl	288	338	508.4 KB	288	0	00:00:05
2	web.man.lodz.pl	288	88	84.4 KB	40	0	00:00:18
3	lze200.lze.lodz.pl	6	21	44.7 KB	3	0	00:15:06
4	kujawiak.man.lodz.pl	5	0	0.0 KB	0	5	00:00:00
TOTAL: 4		587	447	637.5 KB	331	5	06:31:12

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	338	508.4 KB	288	0	00:00:05
2	*.man.lodz.pl	677	88	84.4 KB	40	5	00:00:24
3	*.lze.lodz.pl	6	21	44.7 KB	3	0	00:15:06
4	*.versanet.de	160	0	0.0 KB	0	0	00:00:20
5	*.207.15.62.static.jazztel.es	1	0	0.0 KB	0	0	05:07:57
6	*.cbmm.lodz.pl	4	0	0.0 KB	0	0	00:37:12
7	*.internetdsl.tpnet.pl	3	0	0.0 KB	0	0	00:00:00
8	*.105.135.22.tvsat364.lodz.pl	1	0	0.0 KB	0	0	00:30:00
9	*.toya.net.pl	56	0	0.0 KB	0	0	00:00:04
TOTAL: 9		1196	447	637.5 KB	331	5	06:31:12

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
200.207.15.62.static.jazztel.es	5.576	9.385	05:07:57
router2.cbmm.lodz.pl	0.012	0.052	00:37:12
ip-176.105.135.22.tvsat364.lodz.pl	0.000	0.016	00:30:00
lze200.lze.lodz.pl	0.016	0.084	00:15:06
i59f5cb8b.versanet.de	0.396	2.008	00:00:20
web.man.lodz.pl	0.760	3.780	00:00:18
vegas.cm.p.lodz.pl	0.768	3.716	00:00:05
staticline41435.toya.net.pl	0.152	0.752	00:00:04
f-oper-v100.man.lodz.pl	0.836	3.632	00:00:03
david.man.lodz.pl	0.248	1.256	00:00:01
gcq193.internetdsl.tpnet.pl	0.008	0.040	00:00:00
kujawiak.man.lodz.pl	0.028	0.072	00:00:00
TOTAL: 12	8.800	24.793	06:31:12

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	288
i59f5cb8b.versanet.de	160
david.man.lodz.pl	96
staticline41435.toya.net.pl	56
router2.cbmm.lodz.pl	4
gcq193.internetdsl.tpnet.pl	3
200.207.15.62.static.jazztel.es	1
ip-176.105.135.22.tvsat364.lodz.pl	1
TOTAL: 8	609

NNRP client timeouts [Top 20]:

System	Conn	Peer
router2.cbmm.lodz.pl	1	0
ip-176.105.135.22.tvsat364.lodz.pl	1	0
TOTAL: 2	2	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	338	75.6%
2	pl	109	24.4%
TOTAL: 2		447	100.0%

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

Newsgroup		Count
1	lodman.info	338
2	pl.comp.pecet	74
3	pl.comp.os.ms-windows.winnt	26
4	pl.rec.dom	9
TOTAL: 4		447

Daily Usenet report

Aug 23 04:15:02 -- Aug 24 04:15:01