Daily Usenet report: Jun 6 04:15:02

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Jun 6 12:08:05 newsfeed nnrpd[1994]: SERVER perl filter not defined
Jun 6 16:39:30 newsfeed overchan[2518]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2515771	97.4%	290.3 MB	97.0%
innfeed	35471	1.4%	4.2 MB	1.4%
innd	27326	1.1%	4.2 MB	1.4%
nnrpd	5184	0.2%	549.1 KB	0.2%
overchan	1	0.0%	0.1 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2583754	100.0%	299.2 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2506759	94.7%
Negative hits	117738	4.4%
Positive hits	22792	0.9%
Cache misses	149	0.0%
TOTAL: 4	2647438	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.233	0.0%	215	0.000	5.735	276.000
article cleanup	00:00:31.763	0.0%	2514320	0.004	0.013	0.023
article logging	00:01:08.224	0.1%	2515011	0.014	0.027	0.555
article parse	01:09:25.463	4.8%	226122657	0.009	0.018	0.061
article write	00:21:51.385	1.5%	2500135	0.322	0.525	1.852
artlog/artcncl	00:00:00.005	0.0%	196	0.000	0.026	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:02:37.592	0.2%	242804094	0.000	0.001	0.004
hisgrep/artcncl	00:00:00.186	0.0%	191	0.000	0.974	25.000
hishave/artcncl	00:00:00.066	0.0%	215	0.000	0.307	12.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:25:11.266	1.8%	2647960	0.077	0.571	12.611
history sync	00:00:00.014	0.0%	289	0.000	0.048	1.000
history write	00:35:32.266	2.5%	2507408	0.159	0.850	52.275
hiswrite/artcncl	00:00:00.016	0.0%	24	0.000	0.667	7.000
idle	14:25:29.940	60.1%	179061149	0.109	0.290	0.534
nntp read	01:07:40.646	4.7%	224279719	0.010	0.018	0.031
overview write	00:00:12.691	0.0%	2500135	0.000	0.005	0.015
perl filter	05:06:22.306	21.3%	2507407	4.911	7.331	11.068
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:02.353	0.3%	5045095	0.031	0.048	0.077
TOTAL: 23:59:09.855	23:20:07.415	97.3%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.221	0.0%	103636	0.000	0.002	0.125
article prepare	00:00:00.012	0.0%	53360	0.000	0.000	0.062
article read	00:00:45.068	0.1%	13183	0.000	3.419	303.400
backlog stats	00:00:00.203	0.0%	316918	0.000	0.001	0.013
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.934	0.0%	175612	0.000	0.011	0.087
data write	00:00:09.735	0.0%	139171	0.000	0.070	1.527
idle	23:58:32.370	99.9%	316918	20.034	272.349	1232.041
status file	00:00:08.799	0.0%	11769	0.000	0.748	36.818
TOTAL: 23:59:50.182	23:59:38.342	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	05:24:39.217	99.8%	10010	0.000	1945.976	600181.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.426	0.0%	21054	0.000	0.020	0.500
readart	00:00:02.062	0.0%	142	0.000	14.521	20.580
TOTAL: 05:25:10.396	05:24:41.705	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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1104	2497360	2480637	9495	7228	99%	281:46:17
2	news.nask.pl	6	32554	19436	6126	6992	59%	24:44:07
3	news.icm.edu.pl	43	259	28	217	14	10%	15:52:10
4	localhost	2	1	1	0	0	100%	00:00:00
TOTAL: 4		1155	2530174	2500102	15838	14234	98%	322:22:34

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	790.6 GB	397.4 MB	501.7 MB	791.4 GB	99%	333.6 KB
2	news.nask.pl	1.2 GB	29.4 MB	120.2 MB	1.3 GB	89%	53.4 KB
3	news.icm.edu.pl	661.0 KB	0.0 KB	1.4 MB	2.1 MB	30%	50.9 KB
4	localhost	1.4 KB	0.0 KB	0.0 KB	1.4 KB	100%	1.4 KB
TOTAL: 4		791.8 GB	426.7 MB	623.4 MB	792.8 GB	99%	330.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 6 04:15:02 - 04:59:59	66762	2.7%	24.74	22.2 GB	2.8%	8637.57
Jun 6 05:00:00 - 05:59:59	92798	3.7%	25.78	30.9 GB	3.9%	9014.28
Jun 6 06:00:00 - 06:59:59	96196	3.8%	26.72	32.3 GB	4.1%	9408.97
Jun 6 07:00:00 - 07:59:59	107226	4.3%	29.79	36.8 GB	4.7%	10719.09
Jun 6 08:00:00 - 08:59:59	115770	4.6%	32.16	38.9 GB	4.9%	11342.46
Jun 6 09:00:00 - 09:59:59	92345	3.7%	25.65	32.7 GB	4.1%	9536.84
Jun 6 10:00:00 - 10:59:59	78359	3.1%	21.77	29.6 GB	3.8%	8633.73
Jun 6 11:00:00 - 11:59:59	95364	3.8%	26.49	33.4 GB	4.2%	9718.60
Jun 6 12:00:00 - 12:59:59	97606	3.9%	27.11	32.0 GB	4.0%	9318.74
Jun 6 13:00:00 - 13:59:59	109695	4.4%	30.47	33.9 GB	4.3%	9860.51
Jun 6 14:00:00 - 14:59:59	82425	3.3%	22.90	26.0 GB	3.3%	7584.08
Jun 6 15:00:00 - 15:59:59	115131	4.6%	31.98	35.1 GB	4.4%	10222.92
Jun 6 16:00:00 - 16:59:59	115704	4.6%	32.14	34.9 GB	4.4%	10158.59
Jun 6 17:00:00 - 17:59:59	126408	5.1%	35.11	37.0 GB	4.7%	10766.68
Jun 6 18:00:00 - 18:59:59	110322	4.4%	30.64	31.5 GB	4.0%	9164.74
Jun 6 19:00:00 - 19:59:59	102578	4.1%	28.49	29.4 GB	3.7%	8563.90
Jun 6 20:00:00 - 20:59:59	111645	4.5%	31.01	33.6 GB	4.3%	9791.64
Jun 6 21:00:00 - 21:59:59	114777	4.6%	31.88	34.0 GB	4.3%	9904.38
Jun 6 22:00:00 - 22:59:59	107602	4.3%	29.89	32.9 GB	4.2%	9581.58
Jun 6 23:00:00 - 23:59:59	111351	4.5%	30.93	34.2 GB	4.3%	9969.56
Jun 7 00:00:00 - 00:59:59	90556	3.6%	25.15	28.2 GB	3.6%	8214.00
Jun 7 01:00:00 - 01:59:59	113862	4.6%	31.63	35.0 GB	4.4%	10203.84
Jun 7 02:00:00 - 02:59:59	113542	4.5%	31.54	35.1 GB	4.4%	10230.43
Jun 7 03:00:00 - 03:59:59	105948	4.2%	29.43	32.4 GB	4.1%	9446.75
Jun 7 04:00:00 - 04:15:03	26726	1.1%	29.60	8.2 GB	1.0%	9564.16
TOTAL: 24:00:01	2500698	100.0%	28.94	790.4 GB	100.0%	9592.75

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	7717	194	6374	1	1148
2	news.nask.pl	7145	23	539	3	6580
3	news.icm.edu.pl	15	0	0	0	15
TOTAL: 3		14877	217	6913	4	7743

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	95
it.tlc.cellulari.android	31
it.comp.os.win.windows10	24
comp.mobile.android	15
uk.rec.cycling.moderated	12
it.comp.os.win.windows8	12
hr.alt.cellular.gsm.android	11
comp.mobile.ipad	6
it.tlc.cellulari.iphone	4
it.comp.os.linux.ubuntu	2
comp.sys.raspberry-pi	1
it.comp.os.win.windows7	1
alt.sex.telephones	1
de.comp.lang.vbclassic	1
de.comm.software.mailreader	1
TOTAL: 15	217

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	5581
Binary in non-binary group	923
PFM bot misc	203
Scoring filter (8)	185
Poison newsgroup	50
Scoring filter (10)	27
Scoring filter (9)	21
EMP (fsl)	20
EMP (phl)	12
Scoring filter (11)	11
Scoring filter (13)	8
HTML post	6
Scoring filter (14)	2
Scoring filter (12)	1
Scoring filter (15)	1
TOTAL: 15	7051

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	63663	2235	41517	454	6	58957	3%	23:55:02
2	icm	51012	1050	30667	7774	0	0	2%	23:50:01
3	poznan	28547	1000	17004	46	0	69	3%	24:00:01
4	nask	12653	259	8186	8	0	0	2%	23:50:02
5	okapi	0	0	0	0	0	2454	0%	23:50:00
TOTAL: 5		155875	4544	97374	8282	6	61480	2%	119:25:06

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	poznan	208.6 MB	2.6 MB	211.2 MB	2.5 KB/s	206.8 KB	24:00:01
2	panorama	99.0 MB	2.1 MB	101.2 MB	1.2 KB/s	38.5 KB	23:55:02
3	icm	65.0 MB	1.5 GB	1.6 GB	19.4 KB/s	188.7 KB	23:50:01
4	nask	57.6 MB	1.5 MB	59.1 MB	0.7 KB/s	226.6 KB	23:50:02
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:00
TOTAL: 5		430.3 MB	1.5 GB	2.0 GB	4.8 KB/s	159.5 KB	119:25:06

Backlog files shrunk by innfeed:

Server	Size
okapi	488.3 KB
TOTAL: 1	488.3 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-212-51-218-225.p.lodz.pl	9	108	198.6 KB	5	0	03:33:51
2	web.man.lodz.pl	288	30	25.3 KB	12	0	00:00:23
3	dmcs2.p.lodz.pl	4	4	7.4 KB	3	0	01:08:18
4	isvcs01.man.lodz.pl	1	0	0.0 KB	0	1	00:00:00
TOTAL: 4		302	142	231.3 KB	20	1	05:25:10

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.p.lodz.pl	13	112	206.1 KB	8	0	04:42:10
2	*.man.lodz.pl	672	30	25.3 KB	12	1	00:00:28
3	unresolved	9	0	0.0 KB	0	0	00:42:26
4	*.toya.net.pl	76	0	0.0 KB	0	0	00:00:04
TOTAL: 4		770	142	231.3 KB	20	1	05:25:10

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-212-51-218-225.p.lodz.pl	0.031	0.124	03:33:51
dmcs2.p.lodz.pl	0.017	0.054	01:08:18
195.3.152.193	0.003	0.005	00:30:15
185.48.195.188	0.310	0.475	00:12:06
web.man.lodz.pl	0.952	3.132	00:00:23
staticline41435.toya.net.pl	0.236	0.789	00:00:04
mon.man.lodz.pl	0.824	3.053	00:00:03
212.51.203.199	0.005	0.030	00:00:03
212.191.70.106	0.004	0.040	00:00:01
david.man.lodz.pl	0.313	0.970	00:00:01
isvcs01.man.lodz.pl	0.006	0.015	00:00:00
TOTAL: 11	2.701	8.687	05:25:10

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	287
david.man.lodz.pl	96
staticline41435.toya.net.pl	76
212.191.70.106	4
185.48.195.188	2
212.51.203.199	2
195.3.152.193	1
TOTAL: 7	468

NNRP unrecognized commands (by host):

System	Conn
212.191.70.106	5
TOTAL: 1	5

NNRP unrecognized commands (by command):

Command	Count
À"À!	2
#026#003#001#002	2
À/À+À'À#À#023À#011À#037À#036	1
TOTAL: 3	5

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc-212-51-218-225.p.lodz.pl	3	0
195.3.152.193	1	0
185.48.195.188	1	1
212.191.70.106	1	1
dmcs2.p.lodz.pl	1	0
TOTAL: 5	7	2

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.misc.kolej	64
2	pl.misc.elektronika	48
3	pl.comp.pecet	26
4	pl.comp.os.ms-windows.winnt	4
TOTAL: 4		142

Daily Usenet report

Jun 6 04:15:02 -- Jun 7 04:15:03