Daily Usenet report: Jan 15 04:15:02

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Jan 15 23:38:21 newsfeed pgpverify[23239]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3734023	98.8%	429.4 MB	98.6%
innd	34597	0.9%	5.1 MB	1.2%
nnrpd	4817	0.1%	497.7 KB	0.1%
innfeed	4704	0.1%	681.2 KB	0.2%
controlchan	3	0.0%	0.4 KB	0.0%
pgpverify	1	0.0%	0.0 KB	0.0%
TOTAL: 6	3778145	100.0%	435.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3726983	97.4%
Negative hits	87881	2.3%
Positive hits	12567	0.3%
Cache misses	110	0.0%
TOTAL: 4	3827541	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.525	0.0%	189	0.000	13.360	444.500
article cleanup	00:00:52.674	0.1%	3734147	0.005	0.014	0.027
article logging	00:02:26.771	0.2%	3734712	0.019	0.039	0.748
article parse	01:33:23.369	6.5%	172563285	0.018	0.032	0.070
article write	01:25:10.566	5.9%	3717999	0.334	1.375	5.859
artlog/artcncl	00:00:00.006	0.0%	171	0.000	0.035	0.500
data move	00:04:41.052	0.3%	193246463	0.001	0.001	0.005
hisgrep/artcncl	00:00:00.295	0.0%	164	0.000	1.799	42.000
hishave/artcncl	00:00:00.054	0.0%	189	0.000	0.286	3.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:37:59.439	2.6%	3828775	0.149	0.595	8.164
history sync	00:00:00.009	0.0%	290	0.000	0.031	1.000
history write	00:51:20.440	3.6%	3728247	0.254	0.826	11.222
hiswrite/artcncl	00:00:00.008	0.0%	25	0.000	0.320	1.000
idle	10:25:08.781	43.4%	88391350	0.273	0.424	0.748
nntp read	01:09:14.687	4.8%	169390946	0.016	0.025	0.039
overview write	00:00:24.194	0.0%	3717999	0.002	0.007	0.014
perl filter	07:12:15.979	30.0%	3728247	5.301	6.957	9.609
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:06:41.098	0.5%	7722302	0.035	0.052	0.078
TOTAL: 24:00:33.461	23:29:41.947	97.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:01.130	0.0%	308895	0.000	0.004	0.333
article prepare	00:00:00.248	0.0%	1098852	0.000	0.000	0.125
article read	00:08:43.252	0.6%	274226	0.000	1.908	87.500
backlog stats	00:00:01.794	0.0%	1543108	0.000	0.001	0.056
callbacks	00:00:00.000	0.0%	10	0.000	0.000	0.000
data read	00:00:08.574	0.0%	623110	0.000	0.014	0.121
data write	00:02:10.589	0.2%	937003	0.000	0.139	0.370
idle	23:47:18.468	99.2%	1543108	15.185	55.497	8169.500
status file	00:00:01.349	0.0%	1625	0.000	0.830	8.333
TOTAL: 23:59:02.683	23:58:25.404	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	02:16:16.936	99.8%	15492	0.000	527.817	10051.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.809	0.0%	31760	0.000	0.025	2.500
readart	00:00:00.202	0.0%	100	0.000	2.020	99.500
TOTAL: 02:16:31.213	02:16:17.947	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
group-admin@isc.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	641	3717188	3706760	4505	5923	99%	509:37:30
2	news.nask.pl	2	22347	10225	1863	10259	45%	24:04:54
3	news.icm.edu.pl	47	296	22	267	7	7%	16:26:05
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		691	3739831	3717007	6635	16189	99%	550:08:29

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	1027.3 GB	152.1 MB	185.6 MB	1027.7 GB	99%	290.2 KB
2	news.nask.pl	139.9 MB	1.4 MB	143.1 MB	284.4 MB	49%	14.2 KB
3	news.icm.edu.pl	718.5 KB	10.9 KB	153.3 KB	882.7 KB	81%	30.4 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		1027.5 GB	153.6 MB	328.9 MB	1027.9 GB	99%	288.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 15 04:15:02 - 04:59:59	78314	2.1%	29.03	28.3 GB	2.8%	11008.34
Jan 15 05:00:00 - 05:59:59	133108	3.6%	36.97	40.0 GB	3.9%	11639.53
Jan 15 06:00:00 - 06:59:59	153531	4.1%	42.65	45.5 GB	4.4%	13260.89
Jan 15 07:00:00 - 07:59:59	132464	3.6%	36.80	45.2 GB	4.4%	13164.42
Jan 15 08:00:00 - 08:59:59	119354	3.2%	33.15	43.2 GB	4.2%	12570.57
Jan 15 09:00:00 - 09:59:59	137297	3.7%	38.14	44.1 GB	4.3%	12836.12
Jan 15 10:00:00 - 10:59:59	116531	3.1%	32.37	37.7 GB	3.7%	10973.40
Jan 15 11:00:00 - 11:59:59	152650	4.1%	42.40	41.6 GB	4.0%	12125.51
Jan 15 12:00:00 - 12:59:59	155638	4.2%	43.23	42.8 GB	4.2%	12474.48
Jan 15 13:00:00 - 13:59:59	168323	4.5%	46.76	43.6 GB	4.2%	12707.49
Jan 15 14:00:00 - 14:59:59	169492	4.6%	47.08	43.2 GB	4.2%	12577.47
Jan 15 15:00:00 - 15:59:59	170725	4.6%	47.42	43.2 GB	4.2%	12587.05
Jan 15 16:00:00 - 16:59:59	177157	4.8%	49.21	43.6 GB	4.2%	12690.98
Jan 15 17:00:00 - 17:59:59	177223	4.8%	49.23	43.7 GB	4.3%	12727.22
Jan 15 18:00:00 - 18:59:59	169596	4.6%	47.11	44.4 GB	4.3%	12927.04
Jan 15 19:00:00 - 19:59:59	160521	4.3%	44.59	43.4 GB	4.2%	12648.37
Jan 15 20:00:00 - 20:59:59	171995	4.6%	47.78	46.0 GB	4.5%	13389.31
Jan 15 21:00:00 - 21:59:59	171140	4.6%	47.54	45.0 GB	4.4%	13105.96
Jan 15 22:00:00 - 22:59:59	169126	4.5%	46.98	45.5 GB	4.4%	13242.40
Jan 15 23:00:00 - 23:59:59	169893	4.6%	47.19	44.7 GB	4.3%	13005.35
Jan 16 00:00:00 - 00:59:59	140011	3.8%	38.89	37.6 GB	3.7%	10960.85
Jan 16 01:00:00 - 01:59:59	168069	4.5%	46.69	43.5 GB	4.2%	12657.58
Jan 16 02:00:00 - 02:59:59	168060	4.5%	46.68	43.6 GB	4.2%	12692.02
Jan 16 03:00:00 - 03:59:59	147667	4.0%	41.02	38.5 GB	3.7%	11206.45
Jan 16 04:00:00 - 04:15:01	39270	1.1%	43.58	10.3 GB	1.0%	12011.83
TOTAL: 23:59:59	3717155	100.0%	43.02	1028.0 GB	100.0%	12476.27

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	10482	17	491	4	9970
2	newsfeed.pionier.net.pl	6207	120	5404	0	683
3	news.icm.edu.pl	8	0	1	0	7
TOTAL: 3		16697	137	5896	4	10660

Unwanted newsgroups [Top 20]:

Newsgroup	Count
it.tlc.cellulari.android	40
uk.railway	25
it.comp.os.win.windows10	19
comp.mobile.android	12
uk.net.providers.gradwell	9
uk.rec.cycling.moderated	8
de.comm.software.mailreader	6
it.comp.os.linux.ubuntu	5
it.discussioni.energia	4
it.comp.virtualizzazione	3
hr.alt.cellular.gsm.android	2
comp.sys.mac.vintage	1
it.comp.os.win.windows7	1
alt.sex.telephones	1
comp.sys.raspberry-pi	1
TOTAL: 15	137

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	9224
Binary in non-binary group	453
EMP (phl)	352
Scoring filter (8)	27
Scoring filter (9)	16
Scoring filter (10)	15
Scoring filter (15)	2
HTML post	2
Poison newsgroup	1
Scoring filter (12)	1
Too many newsgroups	1
Scoring filter (17)	1
TOTAL: 12	10095

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	286802	262273	13940	9202	22	17802	91%	23:56:14
2	panorama	47767	1773	42356	937	36	245925	3%	24:00:02
3	poznan	14126	133	9705	14	0	0	0%	23:56:14
4	nask	4441	11	3240	172	0	0	0%	24:01:33
5	okapi	0	0	0	0	0	1645	0%	24:00:01
TOTAL: 5		353136	264190	69241	10325	58	265372	74%	119:54:04

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	47.1 GB	833.1 MB	47.9 GB	582.7 KB/s	185.0 KB	23:56:14
2	panorama	5.7 MB	10.8 MB	16.5 MB	0.2 KB/s	6.2 KB	24:00:02
3	poznan	573.8 KB	222.2 KB	796.0 KB	0.0 KB/s	5.4 KB	23:56:14
4	nask	48.0 KB	56.2 MB	56.3 MB	0.7 KB/s	314.9 KB	24:01:33
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 5		47.1 GB	900.4 MB	48.0 GB	116.5 KB/s	183.2 KB	119:54:04

Backlog files shrunk by innfeed:

Server	Size
panorama	21.9 MB
TOTAL: 1	21.9 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	web.man.lodz.pl	288	100	90.9 KB	40	0	0	00:00:23
TOTAL: 1		288	100	90.9 KB	40	0	0	02:16:31

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.man.lodz.pl	672	100	90.9 KB	40	00:00:28
2	*.dynamic.chello.pl	1	0	0.0 KB	0	00:00:00
3	unresolved	6	0	0.0 KB	0	02:16:02
TOTAL: 3		679	100	90.9 KB	40	02:16:31

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
89.38.40.24	0.298	0.538	01:09:04
89.38.41.228	0.404	0.797	01:06:57
web.man.lodz.pl	1.000	5.566	00:00:23
mon.man.lodz.pl	0.931	5.066	00:00:03
david.man.lodz.pl	0.253	1.740	00:00:01
89-69-69-232.dynamic.chello.pl	0.008	0.008	00:00:00
TOTAL: 6	2.894	13.715	02:16:31

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
89.38.40.24	4
89.38.41.228	2
89-69-69-232.dynamic.chello.pl	1
TOTAL: 5	391

NNRP client timeouts [Top 20]:

System	Conn	Peer
89.38.41.228	1	0
89.38.40.24	1	0
TOTAL: 2	2	0

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	100	100.0%
TOTAL: 1		100	100.0%

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

Newsgroup		Count
1	pl.comp.pecet	80
2	pl.comp.os.ms-windows.winnt	20
TOTAL: 2		100

Daily Usenet report

Jan 15 04:15:02 -- Jan 16 04:15:01