Daily Usenet report: Oct 1 04:15:02

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

Oct 1 12:34:50 newsfeed nnrpd[21903]: SERVER perl filter not defined
Oct 1 12:41:49 newsfeed nnrpd[22207]: SERVER perl filter not defined
Oct 1 13:29:38 newsfeed nnrpd[23866]: SERVER perl filter not defined
Oct 1 14:42:09 newsfeed nnrpd[26344]: SERVER perl filter not defined
Oct 1 16:15:03 newsfeed pgpverify[29536]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3437142	98.6%	399.4 MB	98.4%
innd	38378	1.1%	5.5 MB	1.4%
nnrpd	4786	0.1%	494.8 KB	0.1%
innfeed	4765	0.1%	674.3 KB	0.2%
controlchan	3	0.0%	0.4 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 6	3485075	100.0%	406.1 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3428936	96.9%
Negative hits	96679	2.7%
Positive hits	13480	0.4%
Cache misses	135	0.0%
TOTAL: 4	3539230	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.439	0.0%	184	0.000	24.125	1337.000
article cleanup	00:00:50.559	0.1%	3434935	0.005	0.015	0.026
article logging	00:01:41.602	0.1%	3435452	0.014	0.030	0.872
article parse	01:27:23.350	6.1%	206893777	0.013	0.025	0.083
article write	00:33:38.480	2.3%	3419385	0.306	0.590	3.499
artlog/artcncl	00:00:00.002	0.0%	183	0.000	0.011	1.000
data move	00:03:20.150	0.2%	226692907	0.000	0.001	0.006
hisgrep/artcncl	00:00:01.269	0.0%	168	0.000	7.554	467.000
hishave/artcncl	00:00:00.020	0.0%	184	0.000	0.109	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:25:41.008	1.8%	3541950	0.064	0.435	10.567
history sync	00:00:00.014	0.0%	292	0.000	0.048	1.000
history write	00:39:29.880	2.7%	3431462	0.156	0.691	14.383
hiswrite/artcncl	00:00:00.006	0.0%	16	0.000	0.375	2.000
idle	11:46:02.278	49.1%	142891802	0.123	0.296	0.593
nntp read	01:16:00.016	5.3%	204075922	0.013	0.022	0.041
overview write	00:00:18.687	0.0%	3419385	0.001	0.005	0.015
perl filter	06:59:24.326	29.1%	3431462	4.918	7.333	8.887
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:29.467	0.4%	6887879	0.026	0.048	0.076
TOTAL: 23:59:19.185	23:19:25.553	97.2%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.239	0.0%	60103	0.000	0.004	0.154
article prepare	00:00:00.045	0.0%	125556	0.000	0.000	0.250
article read	00:00:35.470	0.0%	31312	0.000	1.133	279.000
backlog stats	00:00:00.288	0.0%	304920	0.000	0.001	0.111
callbacks	00:00:00.000	0.0%	14	0.000	0.000	0.000
data read	00:00:02.001	0.0%	149078	0.000	0.013	0.158
data write	00:00:18.828	0.0%	155907	0.000	0.121	1.710
idle	23:58:14.851	99.9%	304920	14.752	283.008	8636.000
status file	00:00:01.152	0.0%	1152	0.000	1.000	51.500
TOTAL: 23:59:21.980	23:59:12.874	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.080	0.0%	56	1.429	1.429	1.429
idle	19:58:05.811	99.9%	557314	0.000	128.986	130.199
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:21.057	0.0%	1115280	0.000	0.019	0.667
readart	00:00:00.266	0.0%	60	0.000	4.433	54.500
TOTAL: 19:59:09.394	19:58:27.214	99.9%	-	-	-	-

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
moderator@dana.de	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	1508	3422060	3411226	6367	4467	99%	387:09:45
2	news.nask.pl	2	24303	9872	3376	11055	40%	24:06:49
3	news.icm.edu.pl	40	292	12	246	34	4%	16:30:04
4	localhost	5	4	4	0	0	100%	00:00:00
TOTAL: 4		1555	3446659	3421114	9989	15556	99%	427:46:38

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	915.1 GB	58.6 MB	251.0 MB	915.4 GB	99%	281.0 KB
2	news.nask.pl	52.7 MB	957.7 KB	161.3 MB	214.9 MB	24%	10.5 KB
3	news.icm.edu.pl	309.3 KB	9.4 KB	2.6 MB	2.9 MB	10%	65.1 KB
4	localhost	4.9 KB	0.0 KB	0.0 KB	4.9 KB	100%	1.2 KB
TOTAL: 4		915.1 GB	59.5 MB	415.0 MB	915.6 GB	99%	279.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 1 04:15:02 - 04:59:59	106109	3.1%	39.33	29.5 GB	3.2%	11482.03
Oct 1 05:00:00 - 05:59:59	150726	4.4%	41.87	42.0 GB	4.6%	12234.57
Oct 1 06:00:00 - 06:59:59	144608	4.2%	40.17	40.9 GB	4.5%	11919.45
Oct 1 07:00:00 - 07:59:59	151096	4.4%	41.97	42.1 GB	4.6%	12258.73
Oct 1 08:00:00 - 08:59:59	153153	4.5%	42.54	42.5 GB	4.7%	12364.51
Oct 1 09:00:00 - 09:59:59	119669	3.5%	33.24	35.4 GB	3.9%	10312.35
Oct 1 10:00:00 - 10:59:59	145816	4.3%	40.50	40.0 GB	4.4%	11659.08
Oct 1 11:00:00 - 11:59:59	131348	3.8%	36.49	32.1 GB	3.5%	9351.62
Oct 1 12:00:00 - 12:59:59	127350	3.7%	35.38	34.4 GB	3.8%	10021.58
Oct 1 13:00:00 - 13:59:59	125872	3.7%	34.96	36.2 GB	4.0%	10534.98
Oct 1 14:00:00 - 14:59:59	133450	3.9%	37.07	36.1 GB	4.0%	10528.77
Oct 1 15:00:00 - 15:59:59	126779	3.7%	35.22	35.5 GB	3.9%	10334.57
Oct 1 16:00:00 - 16:59:59	120395	3.5%	33.44	33.6 GB	3.7%	9799.80
Oct 1 17:00:00 - 17:59:59	146152	4.3%	40.60	38.6 GB	4.2%	11240.05
Oct 1 18:00:00 - 18:59:59	159906	4.7%	44.42	40.7 GB	4.5%	11850.34
Oct 1 19:00:00 - 19:59:59	157759	4.6%	43.82	39.6 GB	4.4%	11542.28
Oct 1 20:00:00 - 20:59:59	157273	4.6%	43.69	41.0 GB	4.5%	11933.95
Oct 1 21:00:00 - 21:59:59	156330	4.6%	43.42	39.6 GB	4.4%	11543.28
Oct 1 22:00:00 - 22:59:59	155653	4.6%	43.24	39.9 GB	4.4%	11625.08
Oct 1 23:00:00 - 23:59:59	156044	4.6%	43.35	40.4 GB	4.4%	11767.77
Oct 2 00:00:00 - 00:59:59	122169	3.6%	33.94	31.9 GB	3.5%	9296.41
Oct 2 01:00:00 - 01:59:59	167892	4.9%	46.64	39.1 GB	4.3%	11385.51
Oct 2 02:00:00 - 02:59:59	147877	4.3%	41.08	38.2 GB	4.2%	11137.93
Oct 2 03:00:00 - 03:59:59	125041	3.7%	34.73	32.4 GB	3.6%	9431.19
Oct 2 04:00:00 - 04:15:02	32413	0.9%	35.93	8.8 GB	1.0%	10188.05
TOTAL: 24:00:00	3420880	100.0%	39.59	910.6 GB	100.0%	11051.32

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	11273	21	240	2	11010
2	newsfeed.pionier.net.pl	4765	171	3236	0	1358
3	news.icm.edu.pl	41	0	1	0	40
TOTAL: 3		16079	192	3477	2	12408

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	94
it.comp.os.win.windows10	34
it.tlc.cellulari.android	19
comp.mobile.android	14
comp.mobile.ipad	6
de.comp.lang.php	6
hr.ponuda.hardver.komponente	3
it.comp.os.win.windows7	3
de.comp.office-pakete.ms-office.word	3
comp.sys.raspberry-pi	2
nl.comp.sys.android	1
hr.ponuda.hardver.periferije	1
alt.sex.telephones	1
nl.actueel	1
nl.wetenschap.regulier	1
nl.comp.3d-printen	1
nl.comp.os.linux.techniek	1
nl.gezondheid.alternatief-genezen	1
TOTAL: 18	192

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

Groups	Count
bit.listserv.catholic	1
pl.praca.oferowana	1
TOTAL: 2	2

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	10207
Binary in non-binary group	1387
EMP (phl)	149
Scoring filter (8)	70
Scoring filter (9)	31
Scoring filter (10)	12
Poison newsgroup	8
EMP (fsl)	6
Scoring filter (13)	5
Scoring filter (11)	4
Scoring filter (15)	4
Too many newsgroups	2
Scoring filter (14)	1
HTML post	1
Scoring filter (12)	1
Scoring filter (19)	1
Scoring filter (18)	1
Scoring filter (16)	1
TOTAL: 18	11891

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	53746	20334	19549	8357	0	127	37%	24:02:46
2	panorama	22654	1767	18484	558	34	39217	7%	23:59:42
3	poznan	15515	131	9679	11	0	0	0%	23:59:09
4	nask	6923	34	4648	136	0	0	0%	24:04:37
5	okapi	0	0	0	0	0	1496	0%	24:00:01
TOTAL: 5		98838	22266	52360	9062	34	40840	22%	120:06:15

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	3.6 GB	2.0 GB	5.6 GB	68.0 KB/s	205.1 KB	24:02:46
2	panorama	8.3 MB	2.4 MB	10.7 MB	0.1 KB/s	4.7 KB	23:59:42
3	poznan	468.5 KB	51.2 KB	519.8 KB	0.0 KB/s	3.7 KB	23:59:09
4	nask	155.5 KB	81.7 MB	81.9 MB	1.0 KB/s	493.1 KB	24:04:37
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 5		3.6 GB	2.1 GB	5.7 GB	13.8 KB/s	190.9 KB	120:06:15

Backlog files shrunk by innfeed:

Server	Size
panorama	488.3 KB
TOTAL: 1	488.3 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	46	40.1 KB	22	0	00:00:22
2	isvcs01.man.lodz.pl	4	0	0.0 KB	0	4	00:00:00
TOTAL: 2		292	46	40.1 KB	22	4	19:59:09

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	676	46	40.1 KB	22	4	00:00:27
2	unresolved	1	0	0.0 KB	0	0	19:58:41
TOTAL: 2		677	46	40.1 KB	22	4	19:59:09

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.48.195.142	28.622	42.579	19:58:41
web.man.lodz.pl	1.067	4.256	00:00:22
mon.man.lodz.pl	1.004	4.010	00:00:03
david.man.lodz.pl	0.328	1.310	00:00:01
isvcs01.man.lodz.pl	0.025	0.061	00:00:00
TOTAL: 5	31.046	52.216	19:59:09

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
185.48.195.142	1
TOTAL: 3	385

NNRP client timeouts [Top 20]:

System	Conn	Peer
185.48.195.142	2	1
TOTAL: 1	2	1

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	30
2	pl.comp.pecet	16
TOTAL: 2		46

Daily Usenet report

Oct 1 04:15:02 -- Oct 2 04:15:02