Daily Usenet report: Sep 4 04:15:04

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Sep 4 11:50:05 newsfeed pgpverify[24784]: pgpverify: verification failed
Sep 4 20:03:36 newsfeed nnrpd[7916]: SERVER perl filter not defined
Sep 4 20:09:44 newsfeed nnrpd[8144]: SERVER perl filter not defined
Sep 4 21:35:12 newsfeed nnrpd[10787]: SERVER perl filter not defined
Sep 4 21:35:38 newsfeed nnrpd[10790]: SERVER perl filter not defined
Sep 4 22:19:08 newsfeed innfeed[10870]: icm:0 connect: Network is unreachable
Sep 4 22:25:08 newsfeed innfeed[10870]: icm:0 connect: Network is unreachable
Sep 4 22:49:08 newsfeed innfeed[10870]: icm:0 connect: Network is unreachable

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1310097	97.8%	148.1 MB	97.2%
innd	15043	1.1%	2.4 MB	1.6%
nnrpd	7305	0.5%	765.5 KB	0.5%
innfeed	7043	0.5%	1.1 MB	0.7%
controlchan	3	0.0%	0.5 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	1339492	100.0%	152.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1304569	93.2%
Negative hits	52978	3.8%
Positive hits	41859	3.0%
Cache misses	352	0.0%
TOTAL: 4	1399758	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:11.413	0.0%	511	0.000	22.335	614.000
article cleanup	00:00:18.020	0.0%	1309160	0.003	0.014	0.031
article logging	00:00:41.125	0.0%	1309316	0.010	0.031	0.818
article parse	00:49:27.422	3.4%	70258475	0.016	0.042	0.096
article write	00:18:05.411	1.3%	1300684	0.457	0.834	1.995
artlog/artcncl	00:00:00.008	0.0%	464	0.000	0.017	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:01:27.499	0.1%	78946509	0.000	0.001	0.004
hisgrep/artcncl	00:00:03.373	0.0%	345	0.000	9.777	297.000
hishave/artcncl	00:00:01.822	0.0%	511	0.000	3.566	18.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:55:21.008	12.2%	1400303	1.963	7.513	15.790
history sync	00:00:00.013	0.0%	290	0.000	0.045	1.000
history write	03:38:05.761	15.1%	1305284	1.295	10.025	29.220
hiswrite/artcncl	00:00:01.803	0.0%	166	0.000	10.861	138.000
idle	11:39:32.306	48.6%	53278913	0.162	0.788	2.514
nntp read	00:32:45.602	2.3%	69292382	0.014	0.028	0.046
overview write	00:00:07.962	0.0%	1300684	0.000	0.006	0.016
perl filter	03:34:50.054	14.9%	1305284	7.182	9.875	11.551
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:02:22.491	0.2%	2654931	0.031	0.054	0.108
TOTAL: 23:59:39.516	23:33:23.093	98.2%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.187	0.0%	56069	0.000	0.003	0.071
article prepare	00:00:00.025	0.0%	84216	0.000	0.000	0.036
article read	00:00:05.389	0.0%	20491	0.000	0.263	76.000
backlog stats	00:00:00.237	0.0%	389661	0.000	0.001	1.000
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.418	0.0%	227821	0.000	0.011	1.000
data write	00:00:08.081	0.0%	200242	0.000	0.040	0.333
idle	23:59:31.349	100.0%	389661	0.000	221.658	6528.000
status file	00:00:00.550	0.0%	245	0.000	2.245	89.000
TOTAL: 23:59:55.650	23:59:48.236	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	03:14:23.121	99.7%	4446	0.000	2623.284	600286.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:15.800	0.1%	71291	0.000	0.222	2.000
readart	00:00:01.669	0.0%	483	0.000	3.455	53.789
TOTAL: 03:15:02.767	03:14:40.590	99.8%	-	-	-	-

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
TOTAL	0	0	0	1	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	458	1297753	1289957	1407	6389	99%	253:36:22
2	news.task.gda.pl	17	30620	5579	23834	1207	18%	44:20:44
3	news.nask.pl	2	17552	4870	11863	819	27%	23:50:21
4	news.icm.edu.pl	42	327	5	265	57	1%	17:51:46
5	localhost	5	4	4	0	0	100%	00:00:00
TOTAL: 5		524	1346256	1300415	37369	8472	96%	339:39:13

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	508.2 GB	77.0 MB	472.6 MB	508.7 GB	99%	411.5 KB
2	news.nask.pl	58.5 MB	4.3 MB	4.4 MB	67.3 MB	86%	12.1 KB
3	news.task.gda.pl	11.9 MB	56.9 KB	3.6 MB	15.6 MB	76%	2.4 KB
4	news.icm.edu.pl	123.6 KB	131.9 KB	1.3 MB	1.6 MB	7%	25.9 KB
5	localhost	7.0 KB	0.0 KB	0.0 KB	7.0 KB	100%	1.7 KB
TOTAL: 5		508.2 GB	81.5 MB	482.0 MB	508.8 GB	99%	407.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 4 04:15:04 - 04:59:59	62283	4.8%	23.10	25.2 GB	5.0%	9800.71
Sep 4 05:00:00 - 05:59:59	74056	5.7%	20.57	30.3 GB	6.0%	8821.37
Sep 4 06:00:00 - 06:59:59	59505	4.6%	16.53	24.2 GB	4.8%	7043.80
Sep 4 07:00:00 - 07:59:59	39959	3.1%	11.10	16.4 GB	3.2%	4773.22
Sep 4 08:00:00 - 08:59:59	41314	3.2%	11.48	16.9 GB	3.3%	4930.77
Sep 4 09:00:00 - 09:59:59	40934	3.1%	11.37	16.1 GB	3.2%	4678.64
Sep 4 10:00:00 - 10:59:59	41939	3.2%	11.65	16.2 GB	3.2%	4725.47
Sep 4 11:00:00 - 11:59:59	43555	3.3%	12.10	17.3 GB	3.4%	5046.58
Sep 4 12:00:00 - 12:59:59	44873	3.4%	12.46	17.3 GB	3.4%	5028.46
Sep 4 13:00:00 - 13:59:59	44252	3.4%	12.29	17.2 GB	3.4%	5005.76
Sep 4 14:00:00 - 14:59:59	35935	2.8%	9.98	13.5 GB	2.7%	3929.97
Sep 4 15:00:00 - 15:59:59	40170	3.1%	11.16	15.2 GB	3.0%	4420.90
Sep 4 16:00:00 - 16:59:59	38194	2.9%	10.61	14.1 GB	2.8%	4095.53
Sep 4 17:00:00 - 17:59:59	35106	2.7%	9.75	13.4 GB	2.6%	3912.13
Sep 4 18:00:00 - 18:59:59	43779	3.4%	12.16	17.8 GB	3.5%	5183.23
Sep 4 19:00:00 - 19:59:59	48796	3.8%	13.55	19.2 GB	3.8%	5605.02
Sep 4 20:00:00 - 20:59:59	47295	3.6%	13.14	19.6 GB	3.9%	5716.16
Sep 4 21:00:00 - 21:59:59	43206	3.3%	12.00	17.5 GB	3.4%	5102.10
Sep 4 22:00:00 - 22:59:59	39329	3.0%	10.92	14.3 GB	2.8%	4174.96
Sep 4 23:00:00 - 23:59:59	47557	3.7%	13.21	18.5 GB	3.6%	5402.99
Sep 5 00:00:00 - 00:59:59	80997	6.2%	22.50	30.0 GB	5.9%	8744.89
Sep 5 01:00:00 - 01:59:59	98872	7.6%	27.46	36.9 GB	7.3%	10753.27
Sep 5 02:00:00 - 02:59:59	96540	7.4%	26.82	36.7 GB	7.2%	10683.77
Sep 5 03:00:00 - 03:59:59	90884	7.0%	25.25	35.8 GB	7.0%	10429.55
Sep 5 04:00:00 - 04:15:03	21671	1.7%	24.00	8.6 GB	1.7%	10031.72
TOTAL: 23:59:59	1301001	100.0%	15.06	508.3 GB	100.0%	6169.42

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	6493	247	3050	2	3194
2	news.task.gda.pl	1227	1011	28	2	186
3	news.nask.pl	865	8	797	1	59
4	news.icm.edu.pl	47	0	1	0	46
TOTAL: 4		8632	1266	3876	5	3485

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	99
mailing.freebsd.svn-ports-head	97
it.tlc.cellulari.android	69
uk.railway	65
mailing.freebsd.ports-bugs	47
mozilla.dev.gaia	42
alt.windows7.general	42
fr.rec.loisirs-creatifs	41
uk.rec.cycling.moderated	40
mailing.openbsd.source-changes	36
fa.freebsd.cvs-all	33
mozilla.dev.platform	32
mailing.postfix.users	32
fr.comp.os.android	32
mailing.freebsd.svn-src-all	31
mailing.www.horde-bugs	31
mailing.gcc.gcc-cvs	30
mailing.freebsd.svn-src-head	30
mailing.database.pgsql-general	29
mozilla.dev.identity	29
TOTAL: 76	1266

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

Groups	Count
pl.sci.historia	2
fr.comp.lang.php	1
bit.listserv.catholic	1
fr.comp.applications.libres	1
TOTAL: 4	5

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	3114
EMP (fsl)	134
EMP (md5)	70
HTML post	9
Scoring filter (8)	1
Attached HTML file	1
TOTAL: 6	3329

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	72360	10190	34116	7494	2	2499	14%	24:03:17
2	panorama	55092	2508	50480	753	0	0	4%	24:05:29
3	poznan	11882	70	10107	39	0	0	0%	24:07:04
4	task	2004	55	1023	9	0	5	2%	21:40:43
5	nask	21107	8	14599	1	0	0	0%	23:51:38
6	okapi	0	0	0	0	0	2503	0%	24:00:02
TOTAL: 6		162445	12831	110325	8296	2	5007	7%	141:48:13

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	514.7 MB	2.2 GB	2.7 GB	32.9 KB/s	161.0 KB	24:03:17
2	panorama	7.1 MB	3.2 MB	10.3 MB	0.1 KB/s	3.2 KB	24:05:29
3	poznan	210.7 KB	63.8 KB	274.5 KB	0.0 KB/s	2.5 KB	24:07:04
4	nask	171.2 KB	253.3 KB	424.5 KB	0.0 KB/s	47.2 KB	23:51:38
5	task	117.7 KB	18.2 KB	136.0 KB	0.0 KB/s	2.1 KB	21:40:43
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:02
TOTAL: 6		522.3 MB	2.2 GB	2.7 GB	5.6 KB/s	135.3 KB	141:48:13

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	vegas.cm.p.lodz.pl	288	336	630.6 KB	288	0	00:00:06
2	web.man.lodz.pl	288	72	67.8 KB	31	0	00:00:20
3	lze200.lze.lodz.pl	10	34	63.4 KB	10	0	01:16:19
4	proxy.filmschool.lodz.pl	2	19	87.0 KB	1	0	00:19:12
5	212.191.196.1	3	16	33.7 KB	4	0	00:16:45
6	staticline-31-183-11-147.toya.net.pl	1	6	8.8 KB	3	0	00:11:26
7	kujawiak.man.lodz.pl	4	0	0.0 KB	0	4	00:00:00
TOTAL: 7		596	483	891.2 KB	337	4	03:15:02

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	336	630.6 KB	288	0	00:00:06
2	*.man.lodz.pl	676	72	67.8 KB	31	4	00:00:27
3	*.lze.lodz.pl	10	34	63.4 KB	10	0	01:16:19
4	*.filmschool.lodz.pl	2	19	87.0 KB	1	0	00:19:12
5	unresolved	3	16	33.7 KB	4	0	00:16:45
6	*.toya.net.pl	61	6	8.8 KB	3	0	00:11:32
7	*.cbmm.lodz.pl	5	0	0.0 KB	0	0	00:09:53
8	*.neoplus.adsl.tpnet.pl	1	0	0.0 KB	0	0	00:00:42
9	*.internetdsl.tpnet.pl	2	0	0.0 KB	0	0	00:00:00
10	*.105.135.22.tvsat364.lodz.pl	2	0	0.0 KB	0	0	01:00:01
TOTAL: 10		1050	483	891.2 KB	337	4	03:15:02

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
lze200.lze.lodz.pl	0.024	0.200	01:16:19
ip-176.105.135.22.tvsat364.lodz.pl	0.000	0.044	01:00:01
proxy.filmschool.lodz.pl	0.316	0.128	00:19:12
212.191.196.1	0.000	0.068	00:16:45
staticline-31-183-11-147.toya.net.pl	0.004	0.024	00:11:26
router2.cbmm.lodz.pl	0.032	0.072	00:09:53
ebw119.neoplus.adsl.tpnet.pl	0.004	0.016	00:00:42
web.man.lodz.pl	0.776	5.292	00:00:20
vegas.cm.p.lodz.pl	0.736	5.080	00:00:06
staticline41435.toya.net.pl	0.128	1.120	00:00:05
f-oper-v100.man.lodz.pl	0.740	5.172	00:00:04
david.man.lodz.pl	0.160	1.800	00:00:02
kujawiak.man.lodz.pl	0.008	0.088	00:00:00
gcq193.internetdsl.tpnet.pl	0.004	0.040	00:00:00
TOTAL: 14	2.932	19.144	03:15:02

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	60
router2.cbmm.lodz.pl	5
gcq193.internetdsl.tpnet.pl	2
ip-176.105.135.22.tvsat364.lodz.pl	2
ebw119.neoplus.adsl.tpnet.pl	1
TOTAL: 7	454

NNRP client timeouts [Top 20]:

System	Conn	Peer
lze200.lze.lodz.pl	2	0
ip-176.105.135.22.tvsat364.lodz.pl	2	0
TOTAL: 2	4	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	339	70.2%
2	pl	141	29.2%
3	lodz	3	0.6%
TOTAL: 3		483	100.0%

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

Newsgroup		Count
1	lodman.info	338
2	pl.comp.pecet	80
3	pl.comp.os.ms-windows.winnt	21
4	pl.rec.foto.cyfrowa	19
5	pl.sci.filozofia	6
6	pl.comp.bazy-danych	4
7	pl.regionalne.lodz	4
8	pl.rec.dom	3
9	lodz.test	3
10	pl.listserv.chomor-l	2
11	pl.internet.polip	2
12	lodman.test	1
TOTAL: 12		483

Daily Usenet report

Sep 4 04:15:04 -- Sep 5 04:15:03