Daily Usenet report: Apr 6 04:15:02

Unknown entries from news log file:

First 7 / 7 lines (100.0%)

Apr 6 07:56:16 newsfeed nnrpd[15552]: SERVER perl filter not defined
Apr 6 14:50:51 newsfeed nnrpd[3221]: SERVER perl filter not defined
Apr 6 14:53:31 newsfeed nnrpd[3236]: SERVER perl filter not defined
Apr 6 14:56:24 newsfeed nnrpd[3469]: SERVER perl filter not defined
Apr 6 15:04:25 newsfeed nnrpd[3883]: SERVER perl filter not defined
Apr 6 18:30:35 newsfeed nnrpd[11305]: SERVER perl filter not defined
Apr 6 20:34:22 newsfeed nnrpd[15477]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2591422	98.3%	300.8 MB	98.1%
innd	35198	1.3%	4.9 MB	1.6%
nnrpd	4852	0.2%	487.5 KB	0.2%
innfeed	4606	0.2%	656.1 KB	0.2%
TOTAL: 4	2636078	100.0%	306.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2586245	95.9%
Negative hits	95352	3.5%
Positive hits	14475	0.5%
Cache misses	188	0.0%
TOTAL: 4	2696260	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.844	0.0%	250	0.000	19.376	729.000
article cleanup	00:00:38.039	0.0%	2589385	0.005	0.015	0.028
article logging	00:01:20.560	0.1%	2589961	0.016	0.031	0.759
article parse	01:16:19.018	5.3%	220718988	0.013	0.021	0.036
article write	00:29:01.184	2.0%	2570988	0.371	0.677	5.476
artlog/artcncl	00:00:00.006	0.0%	230	0.000	0.026	1.000
artlog/artparse	00:00:00.071	0.0%	1445	0.000	0.049	1.000
data move	00:03:51.185	0.3%	238026187	0.000	0.001	0.002
hisgrep/artcncl	00:00:02.109	0.0%	228	0.000	9.250	314.250
hishave/artcncl	00:00:00.045	0.0%	250	0.000	0.180	8.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:18:30.305	1.3%	2696791	0.064	0.412	6.524
history sync	00:00:00.021	0.0%	290	0.000	0.072	2.000
history write	00:32:02.530	2.2%	2586915	0.150	0.743	9.120
hiswrite/artcncl	00:00:00.010	0.0%	22	0.000	0.455	5.000
idle	13:22:17.137	55.7%	158012098	0.212	0.305	0.627
nntp read	01:25:32.448	5.9%	218720005	0.014	0.023	0.038
overview write	00:00:15.165	0.0%	2570988	0.001	0.006	0.016
perl filter	05:43:31.476	23.9%	2585470	6.027	7.972	10.531
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:47.120	0.3%	5198504	0.037	0.055	0.122
TOTAL: 24:00:09.342	23:18:13.273	97.1%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.226	0.0%	60648	0.000	0.004	0.250
article prepare	00:00:00.036	0.0%	152340	0.000	0.000	0.250
article read	00:01:11.097	0.1%	37941	0.000	1.874	1145.000
backlog stats	00:00:00.306	0.0%	327687	0.000	0.001	0.031
callbacks	00:00:00.000	0.0%	9	0.000	0.000	0.000
data read	00:00:02.099	0.0%	154173	0.000	0.014	0.250
data write	00:00:18.036	0.0%	170366	0.000	0.106	0.227
idle	23:59:05.484	99.9%	327687	16.385	263.500	4752.615
status file	00:00:01.314	0.0%	1593	0.000	0.825	3.000
TOTAL: 24:00:46.915	24:00:38.598	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:30:33.193	99.1%	3133	0.000	585.124	151154.167
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.204	0.0%	6975	0.000	0.029	0.667
readart	00:00:03.316	0.2%	99	0.000	33.495	253.077
TOTAL: 00:30:50.565	00:30:36.713	99.3%	-	-	-	-

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1174	2572877	2560130	6522	6225	99%	319:37:24
2	news.nask.pl	2	27714	10460	3705	13549	37%	23:57:03
3	news.icm.edu.pl	44	387	62	302	23	16%	20:22:15
4	localhost	8	7	7	0	0	100%	00:00:00
TOTAL: 4		1228	2600985	2570659	10529	19797	98%	363:56:42

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	874.9 GB	46.6 MB	748.0 MB	875.7 GB	99%	357.8 KB
2	news.nask.pl	58.9 MB	561.1 KB	158.2 MB	217.6 MB	27%	9.3 KB
3	news.icm.edu.pl	1.0 MB	16.9 KB	272.9 KB	1.3 MB	78%	15.7 KB
4	localhost	10.3 KB	0.0 KB	0.0 KB	10.3 KB	100%	1.5 KB
TOTAL: 4		875.0 GB	47.2 MB	906.4 MB	875.9 GB	99%	354.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 6 04:15:02 - 04:59:59	69655	2.7%	25.82	24.8 GB	2.8%	9655.32
Apr 6 05:00:00 - 05:59:59	105811	4.1%	29.39	39.2 GB	4.5%	11424.20
Apr 6 06:00:00 - 06:59:59	100768	3.9%	27.99	40.3 GB	4.6%	11748.77
Apr 6 07:00:00 - 07:59:59	107289	4.2%	29.80	40.5 GB	4.6%	11785.36
Apr 6 08:00:00 - 08:59:59	114197	4.4%	31.72	41.2 GB	4.7%	12013.72
Apr 6 09:00:00 - 09:59:59	98130	3.8%	27.26	34.9 GB	4.0%	10170.54
Apr 6 10:00:00 - 10:59:59	118708	4.6%	32.97	39.7 GB	4.5%	11568.03
Apr 6 11:00:00 - 11:59:59	115300	4.5%	32.03	38.8 GB	4.4%	11290.52
Apr 6 12:00:00 - 12:59:59	112418	4.4%	31.23	37.9 GB	4.3%	11045.52
Apr 6 13:00:00 - 13:59:59	111195	4.3%	30.89	37.7 GB	4.3%	10975.93
Apr 6 14:00:00 - 14:59:59	107298	4.2%	29.80	35.3 GB	4.0%	10279.99
Apr 6 15:00:00 - 15:59:59	108876	4.2%	30.24	35.7 GB	4.1%	10384.23
Apr 6 16:00:00 - 16:59:59	105221	4.1%	29.23	35.9 GB	4.1%	10447.72
Apr 6 17:00:00 - 17:59:59	112649	4.4%	31.29	36.5 GB	4.2%	10630.18
Apr 6 18:00:00 - 18:59:59	113715	4.4%	31.59	36.1 GB	4.1%	10503.40
Apr 6 19:00:00 - 19:59:59	107202	4.2%	29.78	34.6 GB	4.0%	10085.74
Apr 6 20:00:00 - 20:59:59	107719	4.2%	29.92	35.4 GB	4.0%	10303.05
Apr 6 21:00:00 - 21:59:59	110002	4.3%	30.56	36.1 GB	4.1%	10504.10
Apr 6 22:00:00 - 22:59:59	116209	4.5%	32.28	37.9 GB	4.3%	11028.23
Apr 6 23:00:00 - 23:59:59	118364	4.6%	32.88	39.0 GB	4.5%	11352.44
Apr 7 00:00:00 - 00:59:59	93550	3.6%	25.99	32.0 GB	3.7%	9334.79
Apr 7 01:00:00 - 01:59:59	104524	4.1%	29.03	31.3 GB	3.6%	9120.76
Apr 7 02:00:00 - 02:59:59	91454	3.6%	25.40	30.7 GB	3.5%	8937.65
Apr 7 03:00:00 - 03:59:59	96467	3.8%	26.80	34.3 GB	3.9%	9985.84
Apr 7 04:00:00 - 04:15:01	24076	0.9%	26.72	8.7 GB	1.0%	10111.19
TOTAL: 23:59:59	2570797	100.0%	29.75	874.4 GB	100.0%	10612.10

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	13803	38	209	9	13547
2	newsfeed.pionier.net.pl	6569	161	3702	4	2702
3	news.icm.edu.pl	23	0	1	0	22
TOTAL: 3		20395	199	3912	13	16271

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	63
it.comp.os.win.windows10	61
comp.sys.raspberry-pi	37
it.tlc.cellulari.android	23
comp.mobile.android	3
alt.sex.telephones	2
de.comp.lang.php	2
uk.rec.cycling.moderated	2
nl.comp.os.linux.techniek	2
hr.ponuda.hardver.komponente	1
hr.rec.glazba.hifi	1
it.comp.os.linux.ubuntu	1
it.comp.os.win.windows8	1
TOTAL: 13	199

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

Groups	Count
pl.praca.oferowana	9
pl.sci.matematyka	3
bit.listserv.catholic	1
TOTAL: 3	13

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	12919
Binary in non-binary group	1157
Scoring filter (8)	52
Poison newsgroup	43
Scoring filter (9)	30
Scoring filter (10)	16
EMP (phl)	12
Scoring filter (18)	5
Scoring filter (16)	4
HTML post	4
Scoring filter (12)	3
Too many newsgroups	3
Attached HTML file	2
Scoring filter (17)	1
TOTAL: 14	14251

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	62545	33792	20809	766	0	3373	54%	23:58:34
2	panorama	18423	2071	12916	833	24	41511	11%	23:59:50
3	poznan	16523	243	10145	14	0	0	1%	23:53:47
4	nask	7143	14	4646	35	0	0	0%	23:56:44
5	okapi	0	0	0	0	0	1962	0%	24:00:01
TOTAL: 5		104634	36120	48516	1648	24	46846	34%	119:48:56

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	6.1 GB	15.6 MB	6.1 GB	73.7 KB/s	184.1 KB	23:58:34
2	panorama	7.1 MB	14.1 MB	21.3 MB	0.3 KB/s	7.5 KB	23:59:50
3	poznan	966.3 KB	79.3 KB	1.0 MB	0.0 KB/s	4.1 KB	23:53:47
4	nask	224.5 KB	10.8 MB	11.0 MB	0.1 KB/s	230.3 KB	23:56:44
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 5		6.1 GB	40.6 MB	6.1 GB	14.8 KB/s	169.4 KB	119:48:56

Backlog files shrunk by innfeed:

Server	Size
panorama	3.8 MB
TOTAL: 1	3.8 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	84	87.5 KB	36	0	00:00:24
2	pc-tom.man.lodz.pl	1	2	3.3 KB	1	0	00:30:13
3	isvcs01.man.lodz.pl	7	0	0.0 KB	0	7	00:00:00
TOTAL: 3		296	86	90.7 KB	37	7	00:30:50

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	680	86	90.7 KB	37	7	00:30:44
2	unresolved	1	0	0.0 KB	0	0	00:00:05
TOTAL: 2		681	86	90.7 KB	37	7	00:30:50

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-tom.man.lodz.pl	0.004	0.011	00:30:13
web.man.lodz.pl	1.040	4.457	00:00:24
196.52.20.209	0.000	0.022	00:00:05
mon.man.lodz.pl	0.900	3.440	00:00:03
david.man.lodz.pl	0.292	1.216	00:00:01
isvcs01.man.lodz.pl	0.050	0.082	00:00:00
TOTAL: 6	2.286	9.228	00:30:50

Curious NNRP server explorers [Top 20]:

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc-tom.man.lodz.pl	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	84	97.7%
2	lodman	2	2.3%
TOTAL: 2		86	100.0%

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	54
2	pl.comp.pecet	30
3	lodman.info	2
TOTAL: 3		86

Daily Usenet report

Apr 6 04:15:02 -- Apr 7 04:15:01