Daily Usenet report: Dec 18 04:15:02

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Dec 18 10:12:25 newsfeed nnrpd[27082]: SERVER perl filter not defined
Dec 18 13:36:31 newsfeed nnrpd[1788]: SERVER perl filter not defined
Dec 18 16:55:12 newsfeed nnrpd[7763]: message repeated 4 times: [ 79.162.255.8 group lodz.test 0]
Dec 18 17:46:11 newsfeed nnrpd[10497]: SERVER perl filter not defined
Dec 18 19:40:04 newsfeed nnrpd[14400]: SERVER perl filter not defined
Dec 18 19:42:20 newsfeed nnrpd[14416]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3012327	97.6%	344.5 MB	97.2%
innfeed	45426	1.5%	5.4 MB	1.5%
innd	23512	0.8%	4.0 MB	1.1%
nnrpd	5165	0.2%	555.2 KB	0.2%
TOTAL: 4	3086430	100.0%	354.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3005508	98.3%
Negative hits	35824	1.2%
Positive hits	17379	0.6%
Cache misses	98	0.0%
TOTAL: 4	3058809	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:05.077	0.0%	261	0.000	19.452	1006.000
article cleanup	00:00:50.512	0.1%	3010222	0.006	0.017	0.029
article logging	00:01:24.082	0.1%	3010668	0.014	0.028	0.358
article parse	01:30:10.027	6.3%	271975213	0.008	0.020	0.094
article write	00:33:29.413	2.3%	3005372	0.319	0.669	2.691
artlog/artcncl	00:00:00.004	0.0%	261	0.000	0.015	1.000
data move	00:05:01.983	0.3%	290980166	0.000	0.001	0.007
hisgrep/artcncl	00:00:00.210	0.0%	210	0.000	1.000	25.500
hishave/artcncl	00:00:00.078	0.0%	261	0.000	0.299	12.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:37:10.390	2.6%	3059125	0.090	0.729	16.203
history sync	00:00:00.017	0.0%	292	0.000	0.058	1.000
history write	00:50:28.082	3.5%	3005929	0.166	1.007	26.720
hiswrite/artcncl	00:00:00.021	0.0%	51	0.000	0.412	9.000
idle	11:43:49.397	48.9%	176611231	0.086	0.239	0.397
nntp read	01:27:27.014	6.1%	269524956	0.009	0.019	0.042
overview write	00:00:17.765	0.0%	3005372	0.001	0.006	0.015
perl filter	06:01:24.334	25.1%	3005929	4.573	7.214	10.554
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:50.979	0.4%	6073625	0.025	0.058	0.089
TOTAL: 23:59:17.434	22:57:29.385	95.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.341	0.0%	130405	0.000	0.003	0.062
article prepare	00:00:00.010	0.0%	47754	0.000	0.000	0.071
article read	00:01:56.670	0.1%	11906	0.000	9.799	1045.000
backlog stats	00:00:00.258	0.0%	294525	0.000	0.001	0.039
callbacks	00:00:00.000	0.0%	9	0.000	0.000	0.000
data read	00:00:01.914	0.0%	168065	0.000	0.011	0.076
data write	00:00:09.362	0.0%	109642	0.000	0.085	1.643
idle	23:57:26.311	99.8%	294525	32.578	292.832	1652.579
status file	00:00:12.265	0.0%	15498	0.167	0.791	14.583
TOTAL: 24:00:00.742	23:59:47.131	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	10:45:58.694	99.9%	267864	0.000	144.695	446812.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:09.618	0.0%	536548	0.000	0.018	1.474
readart	00:00:00.003	0.0%	54	0.000	0.056	0.500
TOTAL: 10:46:37.921	10:46:08.315	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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1211	3005607	3000477	787	4343	99%	357:16:36
2	news.nask.pl	2	18329	5973	11944	412	32%	23:45:06
3	news.icm.edu.pl	37	301	46	167	88	15%	15:46:12
4	localhost	6	5	5	0	0	100%	00:00:01
TOTAL: 4		1256	3024242	3006501	12898	4843	99%	396:47:55

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	895.7 GB	16.6 MB	57.7 MB	895.8 GB	99%	312.6 KB
2	news.nask.pl	30.3 MB	1.3 MB	1.3 MB	32.9 MB	92%	5.3 KB
3	news.icm.edu.pl	991.6 KB	37.7 KB	1.4 MB	2.4 MB	40%	18.3 KB
4	localhost	7.8 KB	0.0 KB	0.0 KB	7.8 KB	100%	1.6 KB
TOTAL: 4		895.8 GB	17.9 MB	60.4 MB	895.8 GB	99%	311.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 18 04:15:02 - 04:59:59	75913	2.5%	28.14	27.5 GB	3.1%	10688.78
Dec 18 05:00:00 - 05:59:59	107293	3.6%	29.80	37.1 GB	4.2%	10812.48
Dec 18 06:00:00 - 06:59:59	107113	3.6%	29.75	36.5 GB	4.1%	10642.40
Dec 18 07:00:00 - 07:59:59	121383	4.0%	33.72	38.3 GB	4.3%	11163.40
Dec 18 08:00:00 - 08:59:59	135878	4.5%	37.74	39.6 GB	4.4%	11525.06
Dec 18 09:00:00 - 09:59:59	135598	4.5%	37.67	39.0 GB	4.4%	11346.50
Dec 18 10:00:00 - 10:59:59	132592	4.4%	36.83	38.2 GB	4.3%	11124.79
Dec 18 11:00:00 - 11:59:59	121233	4.0%	33.68	34.5 GB	3.9%	10048.93
Dec 18 12:00:00 - 12:59:59	115585	3.8%	32.11	33.9 GB	3.8%	9883.19
Dec 18 13:00:00 - 13:59:59	142312	4.7%	39.53	38.0 GB	4.3%	11077.44
Dec 18 14:00:00 - 14:59:59	138123	4.6%	38.37	38.0 GB	4.3%	11081.91
Dec 18 15:00:00 - 15:59:59	142384	4.7%	39.55	39.3 GB	4.4%	11436.06
Dec 18 16:00:00 - 16:59:59	137060	4.6%	38.07	37.8 GB	4.2%	11020.85
Dec 18 17:00:00 - 17:59:59	121258	4.0%	33.68	35.8 GB	4.0%	10426.51
Dec 18 18:00:00 - 18:59:59	121493	4.0%	33.75	36.0 GB	4.0%	10490.07
Dec 18 19:00:00 - 19:59:59	125125	4.2%	34.76	36.7 GB	4.1%	10687.75
Dec 18 20:00:00 - 20:59:59	136872	4.6%	38.02	38.6 GB	4.3%	11228.86
Dec 18 21:00:00 - 21:59:59	131555	4.4%	36.54	39.0 GB	4.4%	11373.37
Dec 18 22:00:00 - 22:59:59	128496	4.3%	35.69	38.0 GB	4.3%	11061.47
Dec 18 23:00:00 - 23:59:59	128272	4.3%	35.63	38.3 GB	4.3%	11152.05
Dec 19 00:00:00 - 00:59:59	97989	3.3%	27.22	31.3 GB	3.5%	9110.81
Dec 19 01:00:00 - 01:59:59	137142	4.6%	38.09	39.5 GB	4.4%	11496.47
Dec 19 02:00:00 - 02:59:59	129343	4.3%	35.93	37.9 GB	4.2%	11033.51
Dec 19 03:00:00 - 03:59:59	108591	3.6%	30.16	35.1 GB	3.9%	10230.93
Dec 19 04:00:00 - 04:15:03	28168	0.9%	31.19	9.4 GB	1.0%	10888.51
TOTAL: 24:00:01	3006771	100.0%	34.80	893.3 GB	100.0%	10841.40

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	4706	200	3890	1	615
2	news.nask.pl	500	10	402	1	87
3	news.icm.edu.pl	89	0	1	0	88
TOTAL: 3		5295	210	4293	2	790

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	120
it.comp.os.win.windows10	36
comp.mobile.android	14
it.tlc.cellulari.android	13
de.comp.lang.vbclassic	8
hr.rec.glazba.hifi	4
it.comp.os.win.windows7	4
comp.sys.raspberry-pi	2
it.comp.os.win.windows8	2
de.rec.sf.startrek	2
nl.comp.os.linux.techniek	1
hr.ponuda.hardver.komponente	1
de.comp.lang.php	1
it.discussioni.energia	1
it.comp.os.linux.ubuntu	1
TOTAL: 15	210

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

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

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	223
EMP (md5)	85
EMP (fsl)	26
HTML post	6
Too many newsgroups	4
Scoring filter (9)	1
TOTAL: 6	345

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	77262	2219	48713	635	24	78800	2%	23:59:55
2	icm	48647	503	26875	7279	1	8	1%	23:54:06
3	poznan	8531	132	5839	60	0	0	1%	23:56:29
4	okapi	0	0	0	0	0	2421	0%	24:00:03
5	nask	0	0	0	0	0	13448	0%	24:00:03
TOTAL: 5		134440	2854	81427	7974	25	94677	2%	119:50:36

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	21.1 MB	1.7 GB	1.8 GB	21.4 KB/s	236.2 KB	23:54:06
2	panorama	12.7 MB	3.3 MB	16.1 MB	0.2 KB/s	5.8 KB	23:59:55
3	poznan	553.2 KB	192.5 KB	745.6 KB	0.0 KB/s	3.9 KB	23:56:29
4	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:03
5	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:03
TOTAL: 5		34.4 MB	1.7 GB	1.8 GB	4.3 KB/s	171.3 KB	119:50:36

Backlog files shrunk by innfeed:

Server	Size
panorama	976.8 KB
TOTAL: 1	976.8 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	web.man.lodz.pl	288	54	47.9 KB	26	0	0	00:00:31
2	staticline-31-182-89-106.toya.net.pl	7	0	0.0 KB	0	0	5	00:35:27
3	isvcs01.man.lodz.pl	5	0	0.0 KB	0	5	0	00:00:00
TOTAL: 3		300	54	47.9 KB	26	5	5	10:46:37

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.man.lodz.pl	677	54	47.9 KB	26	5	0	00:00:36
2	unresolved	7	0	0.0 KB	0	0	0	10:09:43
3	*.static.tpgi.com.au	1	0	0.0 KB	0	0	0	00:00:09
4	*.toya.net.pl	73	0	0.0 KB	0	0	5	00:35:42
5	*.neoplus.adsl.tpnet.pl	1	0	0.0 KB	0	0	0	00:00:26
TOTAL: 5		759	54	47.9 KB	26	5	5	10:46:37

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.48.193.134	13.881	18.601	09:39:34
staticline-31-182-89-106.toya.net.pl	0.058	0.108	00:35:27
79.162.255.8	0.008	0.024	00:30:06
web.man.lodz.pl	0.863	3.784	00:00:31
abga68.neoplus.adsl.tpnet.pl	0.005	0.014	00:00:26
staticline41435.toya.net.pl	0.179	0.855	00:00:15
14-200-3-242.static.tpgi.com.au	0.009	0.023	00:00:09
mon.man.lodz.pl	0.957	3.425	00:00:03
71.94.21.184	0.015	0.042	00:00:02
david.man.lodz.pl	0.316	1.187	00:00:00
isvcs01.man.lodz.pl	0.036	0.084	00:00:00
TOTAL: 11	16.327	28.147	10:46:37

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	66
71.94.21.184	3
185.48.193.134	2
79.162.255.8	2
abga68.neoplus.adsl.tpnet.pl	1
14-200-3-242.static.tpgi.com.au	1
TOTAL: 8	459

NNRP unrecognized commands (by host):

System	Conn
14-200-3-242.static.tpgi.com.au	1
TOTAL: 1	1

NNRP unrecognized commands (by command):

Command	Count
ÿû#037ÿû ÿû#030ÿû'ÿý#001ÿû#003ÿý#003list	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
185.48.193.134	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report

Dec 18 04:15:02 -- Dec 19 04:15:03