Daily Usenet report: Jul 8 04:15:01

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Jul 8 04:56:03 newsfeed overchan[133054]: input line too long, skipping
Jul 8 09:16:26 newsfeed overchan[133054]: input line too long, skipping
Jul 8 11:56:42 newsfeed nnrpd[161966]: SERVER perl filter not defined
Jul 8 12:08:27 newsfeed nnrpd[162732]: SERVER perl filter not defined
Jul 8 15:42:41 newsfeed overchan[133054]: input line too long, skipping
Jul 8 16:34:07 newsfeed nnrpd[178023]: SERVER perl filter not defined
Jul 8 16:34:44 newsfeed nnrpd[178031]: SERVER perl filter not defined
Jul 8 18:48:14 newsfeed overchan[133054]: input line too long, skipping
Jul 8 19:28:58 newsfeed overchan[133054]: input line too long, skipping
Jul 8 22:07:56 newsfeed overchan[133054]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1130242	96.5%	126.0 MB	96.0%
innfeed	25699	2.2%	3.1 MB	2.3%
innd	11994	1.0%	1.9 MB	1.4%
nnrpd	3359	0.3%	320.4 KB	0.2%
overchan	7	0.0%	1.2 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	1171302	100.0%	131.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1123304	99.6%
Negative hits	3843	0.3%
Positive hits	228	0.0%
Cache misses	16	0.0%
TOTAL: 4	1127391	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.968	0.0%	71	0.000	13.634	268.000
article cleanup	00:00:06.548	0.0%	1132379	0.003	0.006	0.009
article logging	00:00:21.945	0.0%	1132686	0.013	0.019	0.027
article parse	00:08:29.799	0.6%	152481048	0.003	0.003	0.004
article write	00:06:08.110	0.4%	1132154	0.179	0.325	0.903
artlog/artcncl	00:00:00.000	0.0%	70	0.000	0.000	0.000
data move	00:00:37.160	0.0%	159967793	0.000	0.000	0.000
hisgrep/artcncl	00:00:00.332	0.0%	60	0.000	5.533	188.000
hishave/artcncl	00:00:00.004	0.0%	71	0.000	0.056	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:11:21.371	0.8%	1136576	0.111	0.599	15.440
history sync	00:00:00.015	0.0%	290	0.000	0.052	0.500
history write	00:08:28.072	0.6%	1132368	0.171	0.449	6.811
hiswrite/artcncl	00:00:00.005	0.0%	11	0.000	0.455	0.556
idle	21:53:39.838	91.2%	150303701	0.385	0.524	0.870
nntp read	00:27:36.896	1.9%	151812569	0.010	0.011	0.013
overview write	00:00:02.797	0.0%	1132154	0.001	0.002	0.004
perl filter	00:47:34.376	3.3%	1132368	1.935	2.521	3.601
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:27.413	0.0%	2274733	0.010	0.012	0.016
TOTAL: 24:00:01.618	23:44:55.649	99.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.039	0.0%	32603	0.000	0.001	0.008
article prepare	00:00:00.001	0.0%	4632	0.000	0.000	0.019
article read	00:00:08.795	0.0%	1156	0.000	7.608	111.429
backlog stats	00:00:00.045	0.0%	92664	0.000	0.000	0.004
callbacks	00:00:00.000	0.0%	2	0.000	0.000	0.000
data read	00:00:00.440	0.0%	43477	0.000	0.010	0.035
data write	00:00:00.996	0.0%	33757	0.000	0.030	0.088
idle	23:54:35.940	100.0%	92664	571.327	928.904	2014.698
status file	00:00:11.048	0.0%	8813	0.415	1.254	64.350
TOTAL: 23:54:59.702	23:54:57.304	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:30:12.118	99.5%	2773	0.000	653.486	600060.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.093	0.0%	5845	0.000	0.016	0.500
readart	00:00:00.000	0.0%	4	0.000	0.000	0.000
TOTAL: 00:30:21.050	00:30:12.211	99.5%	-	-	-	-

Control commands to INND:

Command	Number
flush	27
flushlogs	2
go	2
logmode	2
lowmark	1
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	277	1129750	1129584	0	166	99%	107:20:33
2	news.icm.edu.pl	54	242	8	175	59	3%	17:45:59
3	localhost	1445	4	4	0	0	100%	00:00:00
TOTAL: 3		1776	1129996	1129596	175	225	99%	125:06:32

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	352.9 GB	592.6 KB	1.3 MB	352.9 GB	99%	327.5 KB
2	news.icm.edu.pl	408.7 KB	14.1 KB	2.9 MB	3.3 MB	11%	50.9 KB
3	localhost	6.6 KB	0.0 KB	0.0 KB	6.6 KB	100%	1.6 KB
TOTAL: 3		352.9 GB	606.7 KB	4.2 MB	352.9 GB	99%	327.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jul 8 04:15:01 - 04:59:59	22929	2.0%	8.50	10.8 GB	3.1%	4212.29
Jul 8 05:00:00 - 05:59:59	35931	3.2%	9.98	14.3 GB	4.0%	4165.63
Jul 8 06:00:00 - 06:59:59	44635	4.0%	12.40	14.4 GB	4.1%	4188.68
Jul 8 07:00:00 - 07:59:59	33125	2.9%	9.20	13.6 GB	3.9%	3968.67
Jul 8 08:00:00 - 08:59:59	36376	3.2%	10.10	15.0 GB	4.2%	4366.07
Jul 8 09:00:00 - 09:59:59	39082	3.5%	10.86	13.2 GB	3.7%	3835.88
Jul 8 10:00:00 - 10:59:59	64458	5.7%	17.91	16.3 GB	4.6%	4741.14
Jul 8 11:00:00 - 11:59:59	76001	6.7%	21.11	16.5 GB	4.7%	4808.70
Jul 8 12:00:00 - 12:59:59	84235	7.5%	23.40	17.6 GB	5.0%	5125.61
Jul 8 13:00:00 - 13:59:59	37166	3.3%	10.32	14.4 GB	4.1%	4198.84
Jul 8 14:00:00 - 14:59:59	36034	3.2%	10.01	14.2 GB	4.0%	4121.84
Jul 8 15:00:00 - 15:59:59	59464	5.3%	16.52	16.7 GB	4.7%	4869.72
Jul 8 16:00:00 - 16:59:59	32417	2.9%	9.00	14.5 GB	4.1%	4227.93
Jul 8 17:00:00 - 17:59:59	65582	5.8%	18.22	17.5 GB	5.0%	5111.42
Jul 8 18:00:00 - 18:59:59	33463	3.0%	9.30	14.6 GB	4.1%	4245.86
Jul 8 19:00:00 - 19:59:59	36402	3.2%	10.11	14.2 GB	4.0%	4145.29
Jul 8 20:00:00 - 20:59:59	50190	4.4%	13.94	15.3 GB	4.3%	4463.65
Jul 8 21:00:00 - 21:59:59	72825	6.4%	20.23	16.0 GB	4.5%	4654.17
Jul 8 22:00:00 - 22:59:59	93314	8.3%	25.92	17.3 GB	4.9%	5042.51
Jul 8 23:00:00 - 23:59:59	40128	3.6%	11.15	12.4 GB	3.5%	3607.80
Jul 9 00:00:00 - 00:59:59	38538	3.4%	10.71	13.5 GB	3.8%	3944.11
Jul 9 01:00:00 - 01:59:59	35073	3.1%	9.74	12.6 GB	3.6%	3679.70
Jul 9 02:00:00 - 02:59:59	27102	2.4%	7.53	13.3 GB	3.8%	3876.94
Jul 9 03:00:00 - 03:59:59	26431	2.3%	7.34	11.7 GB	3.3%	3399.98
Jul 9 04:00:00 - 04:15:02	8739	0.8%	9.69	3.4 GB	1.0%	3990.92
TOTAL: 24:00:01	1129640	100.0%	13.07	353.4 GB	100.0%	4289.46

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	472	148	10	3	311
2	news.icm.edu.pl	60	0	1	0	59
TOTAL: 2		532	148	11	3	370

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	130
alt.comp.software.seamonkey	5
comp.sys.raspberry-pi	4
it.tlc.cellulari.android	3
comp.mobile.android	2
de.rec.sf.startrek	2
it.comp.os.win.windows10	1
it.comp.os.win.windows7	1
TOTAL: 8	148

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

Groups	Count
pl.praca.oferowana	2
alt.penthouse.sex.spanking	1
TOTAL: 2	3

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	41
Binary in non-binary group	21
Too many newsgroups	1
TOTAL: 3	63

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	306
	news.icm.edu.pl	1
	TOTAL: 2	307
TOTAL: 1		307

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	18932	499	9307	257	0	1	2%	23:59:56
2	panorama	23921	183	9214	45	4	23258	0%	23:59:34
3	poznan	10	0	6	0	0	0	0%	01:10:20
4	nask	0	0	0	0	0	7019	0%	23:57:49
5	okapi	0	0	0	0	0	634	0%	23:57:49
TOTAL: 5		42863	682	18527	302	4	30912	1%	97:05:28

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	4.0 MB	55.7 MB	59.7 MB	0.7 KB/s	80.9 KB	23:59:56
2	panorama	630.9 KB	127.1 KB	757.9 KB	0.0 KB/s	3.3 KB	23:59:34
3	poznan	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	01:10:20
4	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:49
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:49
TOTAL: 5		4.6 MB	55.8 MB	60.4 MB	0.2 KB/s	62.9 KB	97:05:28

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	289	4	11.0 KB	2	0	00:00:10
2	isvcs01.man.lodz.pl	4	0	0.0 KB	0	4	00:00:00
TOTAL: 2		293	4	11.0 KB	2	4	00:30:21

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	293	4	11.0 KB	2	4	00:00:10
2	*.ip-145-239-154.eu	6	0	0.0 KB	0	0	00:00:01
3	unresolved	101	0	0.0 KB	0	0	00:00:04
4	*.static.ip.netia.com.pl	2	0	0.0 KB	0	0	00:30:05
TOTAL: 4		402	4	11.0 KB	2	4	00:30:21

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
89-171-130-138.static.ip.netia.com.pl	0.028	0.004	00:30:05
web.man.lodz.pl	0.896	1.769	00:00:10
80.111.142.213	0.018	0.021	00:00:02
212.51.192.185	0.222	0.490	00:00:01
ns31410049.ip-145-239-154.eu	0.005	0.016	00:00:00
ns31410048.ip-145-239-154.eu	0.000	0.020	00:00:00
ns31410046.ip-145-239-154.eu	0.009	0.017	00:00:00
192.241.222.25	0.000	0.008	00:00:00
isvcs01.man.lodz.pl	0.019	0.027	00:00:00
TOTAL: 9	1.197	2.372	00:30:21

Curious NNRP server explorers [Top 20]:

System	Conn
212.51.192.185	96
80.111.142.213	4
ns31410046.ip-145-239-154.eu	2
ns31410048.ip-145-239-154.eu	2
89-171-130-138.static.ip.netia.com.pl	2
ns31410049.ip-145-239-154.eu	2
192.241.222.25	1
TOTAL: 7	109

NNRP unrecognized commands (by host):

System	Conn
192.241.222.25	1
ns31410049.ip-145-239-154.eu	1
ns31410046.ip-145-239-154.eu	1
ns31410048.ip-145-239-154.eu	1
TOTAL: 4	4

NNRP unrecognized commands (by command):

Command	Count
#026#003	3
MGLNDD_212.191.2.21_119	1
TOTAL: 2	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
89-171-130-138.static.ip.netia.com.pl	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.pecet	4
TOTAL: 1		4

Daily Usenet report

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