Daily Usenet report: Feb 8 04:15:01

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

Feb 8 08:03:31 newsfeed overchan[16977]: input line too long, skipping
Feb 8 09:12:19 newsfeed overchan[16977]: input line too long, skipping
Feb 8 12:07:10 newsfeed nnrpd[1427]: SERVER perl filter not defined
Feb 8 15:10:49 newsfeed nnrpd[8047]: SERVER perl filter not defined
Feb 8 15:56:40 newsfeed overchan[16977]: input line too long, skipping
Feb 8 16:17:52 newsfeed overchan[16977]: input line too long, skipping
Feb 8 16:26:14 newsfeed overchan[16977]: input line too long, skipping
Feb 8 19:53:21 newsfeed overchan[16977]: input line too long, skipping
Feb 8 21:04:21 newsfeed nnrpd[20139]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2101680	96.9%	241.9 MB	96.5%
innfeed	46947	2.2%	5.6 MB	2.2%
innd	15785	0.7%	2.6 MB	1.0%
nnrpd	4995	0.2%	542.0 KB	0.2%
overchan	7	0.0%	1.1 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2169415	100.0%	250.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2087611	98.8%
Negative hits	15658	0.7%
Positive hits	10532	0.5%
Cache misses	18	0.0%
TOTAL: 4	2113819	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.450	0.0%	102	0.000	4.412	78.000
article cleanup	00:00:26.381	0.0%	2104494	0.009	0.013	0.017
article logging	00:01:24.844	0.1%	2105133	0.030	0.040	0.054
article parse	00:29:52.114	2.1%	66339827	0.018	0.027	0.042
article write	00:12:08.681	0.8%	2099141	0.163	0.347	0.634
artlog/artcncl	00:00:00.003	0.0%	102	0.000	0.029	1.000
data move	00:01:10.105	0.1%	77623427	0.001	0.001	0.002
hisgrep/artcncl	00:00:00.077	0.0%	81	0.000	0.951	16.000
hishave/artcncl	00:00:00.065	0.0%	102	0.000	0.637	4.667
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:24:10.727	1.7%	2127192	0.012	0.682	6.083
history sync	00:00:00.014	0.0%	290	0.000	0.048	1.000
history write	00:19:19.987	1.3%	2100928	0.091	0.552	2.101
hiswrite/artcncl	00:00:00.023	0.0%	21	0.000	1.095	2.100
idle	18:19:31.381	76.4%	51958342	0.799	1.270	2.415
nntp read	00:46:47.877	3.2%	64617001	0.029	0.043	0.068
overview write	00:00:10.310	0.0%	2099141	0.003	0.005	0.007
perl filter	03:00:39.395	12.5%	2100928	3.249	5.159	7.344
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:13.206	0.3%	4213944	0.042	0.060	0.084
TOTAL: 24:00:02.579	23:39:55.640	98.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.137	0.0%	56998	0.000	0.002	0.010
article prepare	00:00:00.000	0.0%	5118	0.000	0.000	0.000
article read	00:00:28.471	0.0%	1271	0.000	22.400	202.583
backlog stats	00:00:00.143	0.0%	147485	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.328	0.0%	76356	0.002	0.017	0.043
data write	00:00:03.448	0.0%	60783	0.028	0.057	0.154
idle	23:53:45.500	99.9%	147485	414.629	583.283	926.049
status file	00:00:14.615	0.0%	16299	0.598	0.897	2.429
TOTAL: 23:54:50.319	23:54:33.642	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:13.087	0.0%	6389	0.000	2.048	12.203
idle	13:50:13.088	99.9%	9452	0.000	5270.111	901015.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.730	0.0%	19528	0.000	0.037	1.211
readart	00:00:00.431	0.0%	24	0.000	17.958	234.000
TOTAL: 13:50:43.786	13:50:27.336	100.0%	-	-	-	-

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	739	2095661	2094370	28	1263	99%	224:30:34
2	news.nask.pl	2	11794	1201	6616	3977	10%	23:52:06
3	news.icm.edu.pl	27	547	78	352	117	14%	22:02:21
4	localhost	4	3	3	0	0	100%	00:00:00
TOTAL: 4		772	2108005	2095652	6996	5357	99%	270:25:01

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	525.8 GB	15.8 MB	16.2 MB	525.8 GB	99%	263.1 KB
2	news.nask.pl	14.2 MB	12.3 MB	25.9 MB	52.4 MB	27%	10.4 KB
3	news.icm.edu.pl	3.0 MB	41.4 KB	6.6 MB	9.6 MB	31%	50.7 KB
4	localhost	4.5 KB	0.0 KB	0.0 KB	4.5 KB	100%	1.5 KB
TOTAL: 4		525.8 GB	28.1 MB	48.8 MB	525.9 GB	99%	262.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 8 04:15:01 - 04:59:59	33095	1.6%	12.26	9.8 GB	1.9%	3819.04
Feb 8 05:00:00 - 05:59:59	73450	3.5%	20.40	20.4 GB	3.9%	5945.35
Feb 8 06:00:00 - 06:59:59	96651	4.6%	26.85	25.6 GB	4.9%	7462.80
Feb 8 07:00:00 - 07:59:59	120295	5.7%	33.42	25.0 GB	4.8%	7284.98
Feb 8 08:00:00 - 08:59:59	67435	3.2%	18.73	19.6 GB	3.7%	5710.34
Feb 8 09:00:00 - 09:59:59	88102	4.2%	24.47	24.6 GB	4.7%	7169.56
Feb 8 10:00:00 - 10:59:59	90395	4.3%	25.11	24.5 GB	4.7%	7122.42
Feb 8 11:00:00 - 11:59:59	99535	4.7%	27.65	27.0 GB	5.2%	7875.63
Feb 8 12:00:00 - 12:59:59	89767	4.3%	24.94	24.4 GB	4.7%	7119.23
Feb 8 13:00:00 - 13:59:59	115238	5.5%	32.01	24.6 GB	4.7%	7175.65
Feb 8 14:00:00 - 14:59:59	99506	4.7%	27.64	24.8 GB	4.7%	7214.24
Feb 8 15:00:00 - 15:59:59	71029	3.4%	19.73	21.0 GB	4.0%	6112.54
Feb 8 16:00:00 - 16:59:59	80578	3.8%	22.38	22.6 GB	4.3%	6580.46
Feb 8 17:00:00 - 17:59:59	75305	3.6%	20.92	22.5 GB	4.3%	6557.45
Feb 8 18:00:00 - 18:59:59	84410	4.0%	23.45	23.4 GB	4.5%	6824.98
Feb 8 19:00:00 - 19:59:59	90758	4.3%	25.21	24.4 GB	4.7%	7116.75
Feb 8 20:00:00 - 20:59:59	94137	4.5%	26.15	25.3 GB	4.8%	7378.19
Feb 8 21:00:00 - 21:59:59	92504	4.4%	25.70	25.7 GB	4.9%	7475.92
Feb 8 22:00:00 - 22:59:59	84369	4.0%	23.44	24.1 GB	4.6%	7011.55
Feb 8 23:00:00 - 23:59:59	116568	5.6%	32.38	23.0 GB	4.4%	6704.07
Feb 9 00:00:00 - 00:59:59	92676	4.4%	25.74	14.1 GB	2.7%	4096.05
Feb 9 01:00:00 - 01:59:59	59084	2.8%	16.41	13.8 GB	2.6%	4017.90
Feb 9 02:00:00 - 02:59:59	121665	5.8%	33.80	18.5 GB	3.5%	5382.49
Feb 9 03:00:00 - 03:59:59	47966	2.3%	13.32	12.8 GB	2.4%	3737.09
Feb 9 04:00:00 - 04:15:02	11082	0.5%	12.29	2.9 GB	0.6%	3415.10
TOTAL: 24:00:01	2095600	100.0%	24.25	524.6 GB	100.0%	6366.37

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	3965	1	2483	3	1478
2	newsfeed.pionier.net.pl	1895	167	1085	6	637
3	news.icm.edu.pl	118	0	3	0	115
TOTAL: 3		5978	168	3571	9	2230

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	76
it.comp.os.win.windows10	23
it.tlc.cellulari.android	21
comp.mobile.android	21
comp.sys.raspberry-pi	14
it.comp.os.win.windows7	5
de.rec.sf.startrek	4
uk.rec.cycling.moderated	2
hr.ponuda.hardver.komponente	1
it.comp.virtualizzazione	1
TOTAL: 10	168

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

Groups	Count
pl.sci.historia	5
bit.listserv.catholic	2
pl.praca.oferowana	2
TOTAL: 3	9

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1464
Binary in non-binary group	48
Scoring filter (9)	18
Scoring filter (10)	12
Scoring filter (8)	10
Scoring filter (21)	7
Scoring filter (11)	5
Scoring filter (18)	4
Scoring filter (19)	4
Scoring filter (13)	4
Scoring filter (14)	4
Poison newsgroup	4
Too many newsgroups	2
Scoring filter (12)	2
Scoring filter (22)	1
TOTAL: 15	1589

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	631
	news.nask.pl	9
	news.icm.edu.pl	1
	TOTAL: 3	641
TOTAL: 1		641

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	45261	527	17466	130	3	39060	1%	23:59:54
2	icm	23657	107	15217	21	0	0	0%	23:50:01
3	poznan	1915	15	1258	0	0	0	0%	23:51:44
4	nask	10327	2	8652	2	0	0	0%	23:50:01
5	okapi	0	0	0	0	0	1097	0%	23:50:02
TOTAL: 5		81160	651	42593	153	3	40157	0%	119:21:42

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	icm	1.8 MB	75.7 KB	1.9 MB	15.1 KB	23:50:01
2	panorama	1.4 MB	622.1 KB	2.0 MB	3.1 KB	23:59:54
3	poznan	234.5 KB	0.0 KB	234.5 KB	15.6 KB	23:51:44
4	nask	11.5 KB	10.0 KB	21.6 KB	5.4 KB	23:50:01
5	okapi	0.0 KB	0.0 KB	0.0 KB	0	23:50:02
TOTAL: 5		3.4 MB	707.8 KB	4.1 MB	5.3 KB	119:21:42

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	18	40.8 KB	9	0	00:00:19
2	az1-ss13.a2hosting.com	2	1	0.7 KB	1	0	00:00:13
3	isvcs01.man.lodz.pl	3	0	0.0 KB	0	3	00:00:00
TOTAL: 3		293	19	41.5 KB	10	3	13:50:43

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	579	18	40.8 KB	9	3	00:00:23
2	*.a2hosting.com	2	1	0.7 KB	1	0	00:00:13
3	unresolved	96	0	0.0 KB	0	0	00:00:01
4	*.consolidated.net	30	0	0.0 KB	0	0	13:50:05
TOTAL: 4		707	19	41.5 KB	10	3	13:50:43

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
client-66-116-2-88.consolidated.net	0.704	1.656	13:50:05
web.man.lodz.pl	1.360	2.752	00:00:19
az1-ss13.a2hosting.com	0.024	0.008	00:00:13
mon.man.lodz.pl	1.080	2.516	00:00:03
212.51.192.185	0.340	0.772	00:00:01
isvcs01.man.lodz.pl	0.012	0.032	00:00:00
TOTAL: 6	3.520	7.736	13:50:43

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
212.51.192.185	96
client-66-116-2-88.consolidated.net	30
TOTAL: 3	414

NNRP client timeouts [Top 20]:

System	Conn	Peer
client-66-116-2-88.consolidated.net	18	0
TOTAL: 1	18	0

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	18	94.7%
2	lodzman	1	5.3%
TOTAL: 2		19	100.0%

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

Newsgroup		Count
1	pl.comp.pecet	10
2	pl.comp.os.ms-windows.winnt	6
3	pl.internet.polip	2
4	lodzman.test	1
TOTAL: 4		19

Daily Usenet report

Feb 8 04:15:01 -- Feb 9 04:15:02