Daily Usenet report: Dec 29 04:15:03

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

Dec 29 11:22:17 newsfeed nnrpd[4046]: SERVER perl filter not defined
Dec 29 13:56:27 newsfeed nnrpd[9809]: SERVER perl filter not defined
Dec 29 15:14:00 newsfeed overchan[19502]: input line too long, skipping
Dec 29 15:31:03 newsfeed overchan[19502]: input line too long, skipping
Dec 29 16:20:57 newsfeed overchan[19502]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2238285	97.2%	270.4 MB	97.0%
innfeed	44174	1.9%	5.2 MB	1.9%
innd	15031	0.7%	2.5 MB	0.9%
nnrpd	4865	0.2%	521.2 KB	0.2%
overchan	4	0.0%	0.7 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2302360	100.0%	278.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2208175	88.0%
Positive hits	186091	7.4%
Negative hits	108799	4.3%
Cache misses	6202	0.2%
TOTAL: 4	2509267	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.394	0.0%	92	0.000	36.891	825.000
article cleanup	00:00:28.733	0.0%	2237175	0.009	0.013	0.018
article logging	00:01:44.424	0.1%	2237735	0.031	0.047	0.109
article parse	00:40:39.868	2.8%	76011734	0.021	0.032	0.046
article write	00:15:35.863	1.1%	2211979	0.199	0.423	0.901
artlog/artcncl	00:00:00.006	0.0%	91	0.000	0.066	1.000
artlog/artparse	00:00:00.001	0.0%	8	0.000	0.125	0.143
data move	00:01:37.166	0.1%	88431647	0.001	0.001	0.002
hisgrep/artcncl	00:00:00.538	0.0%	90	0.000	5.978	25.000
hishave/artcncl	00:00:00.132	0.0%	92	0.000	1.435	11.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:44:10.435	11.4%	2521345	1.301	3.907	8.685
history sync	00:00:00.013	0.0%	290	0.000	0.045	0.500
history write	04:46:56.519	19.9%	2213801	3.841	7.777	12.961
hiswrite/artcncl	00:00:00.013	0.0%	2	0.000	6.500	13.000
idle	10:09:45.843	42.3%	51565721	0.210	0.709	2.216
nntp read	00:56:11.473	3.9%	74190219	0.032	0.045	0.058
overview write	00:00:11.313	0.0%	2211979	0.003	0.005	0.008
perl filter	03:55:32.514	16.4%	2213799	4.242	6.384	8.098
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:13.261	0.4%	4677152	0.050	0.067	0.084
TOTAL: 24:00:04.094	23:38:11.509	98.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:01.549	0.0%	469747	0.000	0.003	0.009
article prepare	00:00:00.046	0.0%	177652	0.000	0.000	0.125
article read	00:05:45.396	0.4%	43185	0.143	7.998	85.333
backlog stats	00:00:01.434	0.0%	1510479	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:18.711	0.0%	887368	0.006	0.021	0.032
data write	00:01:41.425	0.1%	731976	0.017	0.139	0.412
idle	23:42:46.263	99.4%	1510479	14.222	56.516	1175.726
status file	00:00:20.075	0.0%	16459	0.788	1.220	8.153
TOTAL: 23:51:43.290	23:50:54.899	99.9%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:30:32.974	99.3%	3025	0.000	605.942	302855.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.521	0.0%	6677	0.000	0.078	3.316
readart	00:00:00.004	0.0%	28	0.000	0.143	0.500
TOTAL: 00:30:45.487	00:30:33.499	99.4%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	27
flushlogs	2
go	2
logmode	2
mode	148
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	192

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	272	2193648	2188357	1794	3497	99%	279:48:23
2	news.nask.pl	35	212422	23941	166919	21562	11%	31:13:16
3	news.icm.edu.pl	31	407	130	137	140	31%	17:16:19
4	localhost	3	2	2	0	0	100%	00:00:00
TOTAL: 4		341	2406479	2212430	168850	25199	91%	328:17:58

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	630.2 GB	808.4 MB	811.1 MB	631.8 GB	99%	302.3 KB
2	news.nask.pl	8.2 GB	7.0 GB	7.1 GB	22.3 GB	36%	514.5 KB
3	news.icm.edu.pl	2.4 MB	76.5 KB	4.3 MB	6.8 MB	35%	25.8 KB
4	localhost	2.7 KB	0.0 KB	0.0 KB	2.7 KB	100%	1.3 KB
TOTAL: 4		638.5 GB	7.8 GB	7.9 GB	654.2 GB	97%	306.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 29 04:15:03 - 04:59:59	48138	2.2%	17.85	15.1 GB	2.4%	5856.64
Dec 29 05:00:00 - 05:59:59	72779	3.3%	20.22	24.0 GB	3.8%	6994.67
Dec 29 06:00:00 - 06:59:59	71329	3.2%	19.81	23.2 GB	3.6%	6766.75
Dec 29 07:00:00 - 07:59:59	92497	4.2%	25.69	31.0 GB	4.9%	9033.43
Dec 29 08:00:00 - 08:59:59	104426	4.7%	29.01	34.5 GB	5.4%	10038.18
Dec 29 09:00:00 - 09:59:59	90130	4.1%	25.04	30.3 GB	4.7%	8826.43
Dec 29 10:00:00 - 10:59:59	96795	4.4%	26.89	33.6 GB	5.3%	9799.69
Dec 29 11:00:00 - 11:59:59	100854	4.6%	28.02	34.4 GB	5.4%	10008.03
Dec 29 12:00:00 - 12:59:59	150141	6.8%	41.71	33.7 GB	5.3%	9823.20
Dec 29 13:00:00 - 13:59:59	125274	5.7%	34.80	32.0 GB	5.0%	9315.79
Dec 29 14:00:00 - 14:59:59	109297	4.9%	30.36	34.2 GB	5.4%	9974.68
Dec 29 15:00:00 - 15:59:59	97240	4.4%	27.01	25.2 GB	4.0%	7351.34
Dec 29 16:00:00 - 16:59:59	121179	5.5%	33.66	21.8 GB	3.4%	6335.61
Dec 29 17:00:00 - 17:59:59	77951	3.5%	21.65	24.1 GB	3.8%	7008.19
Dec 29 18:00:00 - 18:59:59	65900	3.0%	18.31	17.6 GB	2.8%	5119.92
Dec 29 19:00:00 - 19:59:59	82586	3.7%	22.94	20.5 GB	3.2%	5981.64
Dec 29 20:00:00 - 20:59:59	68438	3.1%	19.01	22.1 GB	3.5%	6446.15
Dec 29 21:00:00 - 21:59:59	70499	3.2%	19.58	22.9 GB	3.6%	6658.74
Dec 29 22:00:00 - 22:59:59	53492	2.4%	14.86	15.8 GB	2.5%	4616.22
Dec 29 23:00:00 - 23:59:59	109891	5.0%	30.53	31.7 GB	5.0%	9221.72
Dec 30 00:00:00 - 00:59:59	82740	3.7%	22.98	11.6 GB	1.8%	3366.58
Dec 30 01:00:00 - 01:59:59	88953	4.0%	24.71	25.5 GB	4.0%	7414.13
Dec 30 02:00:00 - 02:59:59	107630	4.9%	29.90	35.4 GB	5.6%	10325.24
Dec 30 03:00:00 - 03:59:59	98781	4.5%	27.44	30.3 GB	4.7%	8819.22
Dec 30 04:00:00 - 04:15:04	25488	1.2%	28.19	7.8 GB	1.2%	9002.09
TOTAL: 24:00:01	2212428	100.0%	25.61	638.2 GB	100.0%	7745.48

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	21620	2	19997	1	1620
2	newsfeed.pionier.net.pl	3999	72	3377	1	549
3	news.icm.edu.pl	147	0	7	0	140
TOTAL: 3		25766	74	23381	2	2309

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	27
it.tlc.cellulari.android	22
it.comp.os.win.windows10	15
comp.mobile.ipad	5
comp.mobile.android	3
de.comm.software.mailreader	1
uk.rec.cycling.moderated	1
TOTAL: 7	74

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)	1547
Binary in non-binary group	81
EMP (phl)	73
Scoring filter (8)	18
Scoring filter (16)	11
Scoring filter (9)	5
Scoring filter (18)	3
Scoring filter (12)	1
TOTAL: 8	1739

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad command received
	news.nask.pl	119
	TOTAL: 1	119
Including strange strings
	newsfeed.pionier.net.pl	496
	news.nask.pl	58
	news.icm.edu.pl	6
	TOTAL: 3	560
TOTAL: 2		679

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	nask	271069	26417	183853	12407	11895	9%	23:59:57
2	panorama	229875	1244	200872	167	256368	0%	23:59:58
3	icm	251983	37	240380	3815	0	0%	23:59:58
4	poznan	3069	21	2190	6	0	0%	23:26:45
5	okapi	0	0	0	0	966	0%	23:57:51
TOTAL: 5		755996	27719	627295	16395	269229	3%	119:24:29

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nask	9.9 GB	4.7 GB	14.6 GB	176.8 KB/s	393.4 KB	23:59:57
2	panorama	306.1 MB	10.2 MB	316.3 MB	3.7 KB/s	229.5 KB	23:59:58
3	icm	1.4 MB	1.4 GB	1.4 GB	16.8 KB/s	377.3 KB	23:59:58
4	poznan	122.3 KB	13.4 KB	135.7 KB	0.0 KB/s	5.0 KB	23:26:45
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:51
TOTAL: 5		10.2 GB	6.1 GB	16.3 GB	39.7 KB/s	386.5 KB	119:24:29

Backlog files shrunk by innfeed:

Server	Size
panorama	7.2 MB
TOTAL: 1	7.2 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	28	38.3 KB	14	0	00:00:21
2	isvcs01.man.lodz.pl	2	0	0.0 KB	0	2	00:00:00
TOTAL: 2		290	28	38.3 KB	14	2	00:30:44

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	578	28	38.3 KB	14	2	00:00:25
2	unresolved	100	0	0.0 KB	0	0	00:30:19
TOTAL: 2		678	28	38.3 KB	14	2	00:30:44

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
150.254.173.8	0.016	0.044	00:30:17
web.man.lodz.pl	1.508	5.856	00:00:21
mon.man.lodz.pl	1.372	5.672	00:00:03
212.51.192.185	0.392	1.720	00:00:01
isvcs01.man.lodz.pl	0.020	0.040	00:00:00
TOTAL: 5	3.308	13.332	00:30:44

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
212.51.192.185	96
150.254.173.8	4
TOTAL: 3	388

Newsgroup request counts (by category):

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

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

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

Daily Usenet report

Dec 29 04:15:03 -- Dec 30 04:15:04