Daily Usenet report: Dec 3 04:15:02

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

Dec 3 16:42:03 newsfeed innfeed[2209]: task:0 connect: Network is unreachable
Dec 3 16:42:03 newsfeed innfeed[2209]: nask:0 connect: Network is unreachable
Dec 3 16:42:03 newsfeed innfeed[2209]: icm:0 connect: Network is unreachable
Dec 3 21:16:40 newsfeed nnrpd[12572]: SERVER perl filter not defined
Dec 3 21:20:37 newsfeed nnrpd[12790]: SERVER perl filter not defined
Dec 3 21:22:05 newsfeed nnrpd[12809]: SERVER perl filter not defined
Dec 3 21:23:56 newsfeed nnrpd[12835]: SERVER perl filter not defined
Dec 3 22:59:42 newsfeed nnrpd[15838]: SERVER perl filter not defined
Dec 3 23:02:43 newsfeed overchan[2210]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1886374	98.2%	214.1 MB	97.8%
innd	16674	0.9%	2.7 MB	1.3%
nnrpd	10739	0.6%	1.1 MB	0.5%
innfeed	7375	0.4%	1.1 MB	0.5%
overchan	1	0.0%	0.1 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	1921164	100.0%	219.1 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1880338	92.0%
Negative hits	98395	4.8%
Positive hits	43960	2.2%
Cache misses	20461	1.0%
TOTAL: 4	2043154	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:11.601	0.0%	385	0.000	30.132	615.000
article cleanup	00:00:33.577	0.0%	1884991	0.007	0.018	0.033
article logging	00:01:00.819	0.1%	1885122	0.015	0.032	0.220
article parse	01:17:33.490	5.4%	174822440	0.007	0.027	0.076
article write	00:18:22.809	1.3%	1879050	0.375	0.587	29.418
artlog/artcncl	00:00:00.009	0.0%	383	0.000	0.023	1.000
artlog/artparse	00:00:00.002	0.0%	8	0.000	0.250	1.000
data move	00:03:33.721	0.2%	187455640	0.000	0.001	0.008
hisgrep/artcncl	00:00:01.799	0.0%	344	0.000	5.230	96.000
hishave/artcncl	00:00:00.739	0.0%	385	0.000	1.919	262.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:40:33.628	11.2%	2043655	1.350	4.714	60.269
history sync	00:00:00.010	0.0%	292	0.000	0.034	1.000
history write	04:39:46.221	19.4%	1881012	3.164	8.924	70.462
hiswrite/artcncl	00:00:00.354	0.0%	41	0.000	8.634	235.000
idle	08:22:39.328	34.9%	106635057	0.189	0.283	60.987
nntp read	01:04:13.740	4.5%	173424469	0.009	0.022	0.037
overview write	00:00:12.033	0.0%	1879050	0.000	0.006	0.020
perl filter	04:55:07.097	20.5%	1881004	4.826	9.414	12.661
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:03.145	0.3%	3800185	0.038	0.064	0.090
TOTAL: 23:59:10.415	23:27:54.122	97.8%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.168	0.0%	43176	0.000	0.004	0.167
article prepare	00:00:00.024	0.0%	65708	0.000	0.000	0.083
article read	00:00:02.943	0.0%	15036	0.000	0.196	50.400
backlog stats	00:00:00.258	0.0%	318427	0.000	0.001	0.023
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:02.328	0.0%	191524	0.000	0.012	0.067
data write	00:00:05.703	0.0%	159638	0.000	0.036	47.000
idle	23:57:52.654	99.9%	318427	0.000	270.934	12570.000
status file	00:00:46.592	0.1%	310	0.000	150.297	18172.000
TOTAL: 23:58:57.920	23:58:50.670	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	61:11:52.713	100.0%	108360	0.000	2033.155	600625.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:08.069	0.0%	280008	0.000	0.029	8.667
readart	00:00:02.677	0.0%	719	0.000	3.723	541.000
TOTAL: 61:13:24.538	61:12:03.459	100.0%	-	-	-	-

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	770	1892520	1863888	24776	3856	98%	348:19:14
2	news.nask.pl	2	19231	8047	10205	979	41%	24:06:39
3	news.task.gda.pl	20	31429	6199	24134	1096	19%	36:49:12
4	news.icm.edu.pl	43	327	16	291	20	4%	17:58:00
5	localhost	6	5	5	0	0	100%	00:00:00
TOTAL: 5		841	1943512	1878155	59406	5951	96%	427:13:05

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	676.3 GB	28.3 MB	57.3 MB	676.4 GB	99%	379.8 KB
2	news.nask.pl	48.3 MB	3.7 MB	3.9 MB	55.9 MB	86%	6.3 KB
3	news.task.gda.pl	14.4 MB	51.8 KB	3.2 MB	17.6 MB	81%	2.5 KB
4	news.icm.edu.pl	988.3 KB	106.5 KB	908.4 KB	2.0 MB	49%	55.6 KB
5	localhost	7.3 KB	0.0 KB	0.0 KB	7.3 KB	100%	1.5 KB
TOTAL: 5		676.4 GB	32.1 MB	65.3 MB	676.5 GB	99%	376.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 3 04:15:02 - 04:59:59	36415	1.9%	13.50	15.9 GB	2.3%	6160.66
Dec 3 05:00:00 - 05:59:59	53353	2.8%	14.82	22.8 GB	3.4%	6645.63
Dec 3 06:00:00 - 06:59:59	53828	2.9%	14.95	22.0 GB	3.2%	6402.53
Dec 3 07:00:00 - 07:59:59	53865	2.9%	14.96	21.7 GB	3.2%	6321.09
Dec 3 08:00:00 - 08:59:59	64516	3.4%	17.92	25.9 GB	3.8%	7549.26
Dec 3 09:00:00 - 09:59:59	71100	3.8%	19.75	26.5 GB	3.9%	7727.34
Dec 3 10:00:00 - 10:59:59	76292	4.1%	21.19	27.2 GB	4.0%	7911.90
Dec 3 11:00:00 - 11:59:59	71343	3.8%	19.82	28.4 GB	4.2%	8266.28
Dec 3 12:00:00 - 12:59:59	80798	4.3%	22.44	29.2 GB	4.3%	8511.01
Dec 3 13:00:00 - 13:59:59	82257	4.4%	22.85	29.9 GB	4.4%	8722.87
Dec 3 14:00:00 - 14:59:59	86762	4.6%	24.10	30.7 GB	4.5%	8941.59
Dec 3 15:00:00 - 15:59:59	86671	4.6%	24.08	30.2 GB	4.5%	8801.12
Dec 3 16:00:00 - 16:59:59	97343	5.2%	27.04	34.3 GB	5.1%	9977.64
Dec 3 17:00:00 - 17:59:59	83196	4.4%	23.11	27.9 GB	4.1%	8115.22
Dec 3 18:00:00 - 18:59:59	101812	5.4%	28.28	33.6 GB	5.0%	9775.47
Dec 3 19:00:00 - 19:59:59	95402	5.1%	26.50	33.3 GB	4.9%	9695.21
Dec 3 20:00:00 - 20:59:59	79219	4.2%	22.01	25.9 GB	3.8%	7541.44
Dec 3 21:00:00 - 21:59:59	93601	5.0%	26.00	30.1 GB	4.4%	8774.80
Dec 3 22:00:00 - 22:59:59	67487	3.6%	18.75	23.2 GB	3.4%	6754.07
Dec 3 23:00:00 - 23:59:59	81658	4.3%	22.68	27.2 GB	4.0%	7919.97
Dec 4 00:00:00 - 00:59:59	77616	4.1%	21.56	28.2 GB	4.2%	8215.72
Dec 4 01:00:00 - 01:59:59	93905	5.0%	26.08	34.8 GB	5.1%	10130.25
Dec 4 02:00:00 - 02:59:59	77133	4.1%	21.43	27.6 GB	4.1%	8046.74
Dec 4 03:00:00 - 03:59:59	92115	4.9%	25.59	32.6 GB	4.8%	9483.89
Dec 4 04:00:00 - 04:15:03	22220	1.2%	24.61	8.1 GB	1.2%	9432.71
TOTAL: 24:00:01	1879907	100.0%	21.76	677.1 GB	100.0%	8217.16

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3959	164	3042	3	750
2	news.task.gda.pl	1133	932	27	0	174
3	news.nask.pl	973	25	922	4	22
4	news.icm.edu.pl	19	0	1	0	18
TOTAL: 4		6084	1121	3992	7	964

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	79
mailing.freebsd.svn-ports-all	63
mailing.freebsd.svn-ports-head	63
mailing.freebsd.ports-bugs	44
mailing.openbsd.misc	43
fa.freebsd.cvs-all	40
mailing.freebsd.svn-src-all	39
mailing.gcc.gcc-cvs	38
alt.politics.scorched-earth	38
alt.windows7.general	35
gmane.linux.alsa.devel	33
mailing.unix.samba-technical	33
mozilla.dev.platform	28
comp.sys.raspberry-pi	28
fr.usenet.abus.nocem	27
mailing.openbsd.source-changes	26
mailing.freebsd.svn-src-head	25
mozilla.dev.gaia	23
mailing.unix.dovecot	20
mailing.dragonflybsd.commits	18
TOTAL: 88	1121

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

Groups	Count
pl.sci.matematyka	4
pl.sci.historia	3
TOTAL: 2	7

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	529
EMP (fsl)	133
Attached HTML file	123
EMP (md5)	24
HTML post	13
Scoring filter (12)	1
Scoring filter (10)	1
Scoring filter (8)	1
TOTAL: 8	825

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	62353	6672	31080	3906	13	1063	10%	23:53:21
2	panorama	43624	4700	36452	984	1	0	10%	24:00:00
3	poznan	17034	74	13759	33	0	0	0%	23:50:00
4	task	2140	19	1138	4	0	1	0%	21:52:41
5	nask	20274	13	14025	0	0	6	0%	23:50:30
6	okapi	0	0	0	0	0	3057	0%	24:00:01
TOTAL: 6		145425	11478	96454	4927	14	4127	7%	141:26:33

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	77.8 MB	504.0 MB	581.8 MB	6.9 KB/s	56.3 KB	23:53:21
2	panorama	28.7 MB	4.6 MB	33.3 MB	0.4 KB/s	6.0 KB	24:00:00
3	poznan	295.7 KB	195.5 KB	491.2 KB	0.0 KB/s	4.6 KB	23:50:00
4	nask	66.5 KB	0.0 KB	66.5 KB	0.0 KB/s	5.1 KB	23:50:30
5	task	44.2 KB	10.2 KB	54.4 KB	0.0 KB/s	2.4 KB	21:52:41
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 6		106.9 MB	508.8 MB	615.7 MB	1.2 KB/s	38.4 KB	141:26:33

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	vegas.cm.p.lodz.pl	288	328	573.9 KB	288	0	0	00:00:23
2	web.man.lodz.pl	288	194	172.5 KB	84	0	0	00:00:56
3	lze200.lze.lodz.pl	5	133	257.2 KB	9	0	0	01:02:55
4	212.191.78.190	329	60	109.1 KB	38	0	0	47:12:25
5	user-188-33-7-223.play-internet.pl	5	2	1.6 KB	1	0	0	00:16:27
6	ip-176.105.135.22.tvsat364.lodz.pl	13	2	2.7 KB	2	0	0	03:10:31
7	kujawiak.man.lodz.pl	5	0	0.0 KB	0	5	0	00:00:00
8	user-188-33-1-183.play-internet.pl	2	0	0.0 KB	0	0	1	00:07:10
TOTAL: 8		935	719	1.1 MB	422	5	1	61:13:23

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.cm.p.lodz.pl	288	328	573.9 KB	288	0	0	00:00:23
2	*.man.lodz.pl	669	194	172.5 KB	84	5	0	00:20:32
3	*.lze.lodz.pl	5	133	257.2 KB	9	0	0	01:02:55
4	unresolved	330	60	109.1 KB	38	0	0	47:14:30
5	*.play-internet.pl	10	2	1.6 KB	1	0	1	00:59:44
6	*.105.135.22.tvsat364.lodz.pl	13	2	2.7 KB	2	0	0	03:10:31
7	*.105.131.121.tvsat364.lodz.pl	1	0	0.0 KB	0	0	0	00:16:04
8	*.p.lodz.pl	1	0	0.0 KB	0	0	0	00:03:47
9	*.191.14.62.static.jazztel.es	4	0	0.0 KB	0	0	0	04:31:56
10	*.adsl.inetia.pl	1	0	0.0 KB	0	0	0	00:32:50
11	*.neoplus.adsl.tpnet.pl	2	0	0.0 KB	0	0	0	00:33:44
12	*.lodz.mm.pl	16	0	0.0 KB	0	0	0	02:18:01
13	*.toya.net.pl	85	0	0.0 KB	0	0	0	00:08:19
TOTAL: 13		1425	719	1.1 MB	422	5	1	61:13:23

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	0.784	3.896	47:12:25
140.191.14.62.static.jazztel.es	5.792	8.384	04:31:56
ip-176.105.135.22.tvsat364.lodz.pl	0.036	0.104	03:10:31
host-89-228-165-199.lodz.mm.pl	0.024	0.192	02:18:01
lze200.lze.lodz.pl	0.020	0.132	01:02:55
ecb86.neoplus.adsl.tpnet.pl	0.008	0.016	00:33:44
user-31-174-120-42.play-internet.pl	0.032	0.024	00:32:56
37-128-47-26.adsl.inetia.pl	0.056	0.020	00:32:50
pc-vicki.man.lodz.pl	0.000	0.024	00:19:28
user-188-33-7-223.play-internet.pl	0.092	0.048	00:16:27
ip-176.105.131.121.tvsat364.lodz.pl	0.004	0.008	00:16:04
staticline-31-182-70-182.toya.net.pl	0.156	0.008	00:08:08
user-188-33-1-183.play-internet.pl	0.060	0.028	00:07:10
radius2.p.lodz.pl	0.288	0.156	00:03:47
user-188-33-254-138.play-internet.pl	0.028	0.020	00:03:09
194.247.62.163	0.056	0.012	00:02:05
web.man.lodz.pl	0.508	2.804	00:00:56
vegas.cm.p.lodz.pl	0.464	3.104	00:00:23
staticline41435.toya.net.pl	0.204	1.028	00:00:11
mon.man.lodz.pl	0.604	3.320	00:00:05
TOTAL: 23	9.412	24.428	61:13:23

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	290
david.man.lodz.pl	85
staticline41435.toya.net.pl	84
host-89-228-165-199.lodz.mm.pl	16
140.191.14.62.static.jazztel.es	4
user-31-174-120-42.play-internet.pl	2
ecb86.neoplus.adsl.tpnet.pl	2
staticline-31-182-70-182.toya.net.pl	1
194.247.62.163	1
pc-vicki.man.lodz.pl	1
radius2.p.lodz.pl	1
user-188-33-254-138.play-internet.pl	1
ip-176.105.131.121.tvsat364.lodz.pl	1
37-128-47-26.adsl.inetia.pl	1
TOTAL: 14	490

NNRP gethostbyaddr failures [Top 20]:

System	Conn
? (can't getpeername)	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
212.191.78.190	2	3
ip-176.105.135.22.tvsat364.lodz.pl	2	0
ecb86.neoplus.adsl.tpnet.pl	1	0
user-31-174-120-42.play-internet.pl	1	0
37-128-47-26.adsl.inetia.pl	1	0
TOTAL: 5	7	3

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	389	54.1%
2	lodman	328	45.6%
3	lodzman	2	0.3%
TOTAL: 3		719	100.0%

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

Newsgroup		Count
1	lodman.info	328
2	pl.comp.pecet	122
3	pl.comp.os.ms-windows.winnt	112
4	pl.rec.kuchnia	37
5	pl.misc.telefonia.gsm	34
6	pl.misc.kolej	23
7	pl.comp.os.linux	13
8	pl.rec.dom	11
9	pl.soc.prawo	9
10	pl.rec.rowery	8
11	pl.regionalne.warszawa	7
12	pl.regionalne.lodz	7
13	pl.rec.gory	3
14	pl.praca.dyskusje	2
15	lodzman.test	2
16	pl.soc.dzieci	1
TOTAL: 16		719

Daily Usenet report

Dec 3 04:15:02 -- Dec 4 04:15:03