Daily Usenet report: May 1 04:15:02

Unknown entries from news log file:

First 12 / 12 lines (100.0%)

May 1 08:28:17 newsfeed overchan[23179]: input line too long, skipping
May 1 08:33:07 newsfeed overchan[23179]: input line too long, skipping
May 1 08:44:36 newsfeed overchan[23179]: input line too long, skipping
May 1 11:30:03 newsfeed pgpverify[16826]: pgpverify: verification failed
May 1 11:51:06 newsfeed overchan[23179]: input line too long, skipping
May 1 12:40:18 newsfeed overchan[23179]: input line too long, skipping
May 1 16:16:19 newsfeed overchan[23179]: input line too long, skipping
May 1 17:35:58 newsfeed overchan[23179]: input line too long, skipping
May 1 18:37:19 newsfeed overchan[23179]: input line too long, skipping
May 2 01:18:00 newsfeed overchan[23179]: input line too long, skipping
May 2 01:33:09 newsfeed overchan[23179]: input line too long, skipping
May 2 02:29:51 newsfeed overchan[23179]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	610000	91.1%	70.6 MB	90.7%
innfeed	45173	6.7%	5.3 MB	6.8%
innd	9302	1.4%	1.4 MB	1.8%
nnrpd	4953	0.7%	527.0 KB	0.7%
overchan	12	0.0%	1.9 KB	0.0%
controlchan	4	0.0%	0.5 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 7	669445	100.0%	77.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	601840	97.7%
Negative hits	13855	2.2%
Positive hits	253	0.0%
Cache misses	41	0.0%
TOTAL: 4	615989	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.434	0.0%	75	0.000	59.120	408.000
article cleanup	00:00:08.721	0.0%	612165	0.007	0.014	0.020
article logging	00:00:31.269	0.0%	612763	0.038	0.051	0.062
article parse	00:14:52.266	1.0%	28510727	0.020	0.031	0.069
article write	00:08:03.255	0.6%	611839	0.171	0.790	10.779
artlog/artcncl	00:00:00.003	0.0%	75	0.000	0.040	0.300
data move	00:00:36.972	0.0%	32380638	0.001	0.001	0.013
hisgrep/artcncl	00:00:01.533	0.0%	74	0.000	20.716	93.100
hishave/artcncl	00:00:00.370	0.0%	75	0.000	4.933	26.900
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:08:05.063	8.9%	626336	0.875	12.270	92.885
history sync	00:00:00.024	0.0%	302	0.000	0.079	1.000
history write	02:53:12.481	12.0%	612115	5.959	16.978	252.206
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
idle	16:44:10.982	69.7%	26357063	0.093	2.286	59.146
nntp read	00:24:24.728	1.7%	28163941	0.036	0.052	0.061
overview write	00:00:03.364	0.0%	611839	0.002	0.005	0.009
perl filter	01:17:33.477	5.4%	612115	3.183	7.602	9.453
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:37.381	0.0%	1241533	0.021	0.030	0.036
TOTAL: 24:00:18.429	23:52:26.323	99.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.159	0.0%	57107	0.000	0.003	0.014
article prepare	00:00:00.002	0.0%	8178	0.000	0.000	0.010
article read	00:00:47.483	0.1%	2030	0.000	23.391	2015.000
backlog stats	00:00:00.124	0.0%	122498	0.000	0.001	0.005
callbacks	00:00:00.000	0.0%	2	0.000	0.000	0.000
data read	00:00:01.309	0.0%	63381	0.000	0.021	0.045
data write	00:00:02.947	0.0%	45675	0.028	0.065	0.101
idle	23:50:46.101	99.7%	122498	455.048	700.796	1217.957
status file	00:02:47.117	0.2%	16746	0.732	9.980	405.745
TOTAL: 23:54:31.631	23:54:25.242	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	01:29:00.845	99.8%	3105	0.000	1720.079	300080.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.235	0.0%	6934	0.000	0.034	0.500
readart	00:00:00.008	0.0%	52	0.000	0.154	0.500
TOTAL: 01:29:12.964	01:29:01.088	99.8%	-	-	-	-

Control commands to INND:

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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
moderator@dana.de	0	0	0	1	0	0
TOTAL	0	0	0	1	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	251	609528	609225	1	302	99%	161:33:33
2	news.icm.edu.pl	49	234	40	170	24	17%	17:46:03
3	localhost	1441	0	0	0	0	0%	00:00:29
TOTAL: 3		1741	609762	609265	171	326	99%	179:20:05

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	218.8 GB	2.6 MB	3.3 MB	218.8 GB	99%	376.4 KB
2	news.icm.edu.pl	1.5 MB	127.2 KB	511.5 KB	2.1 MB	70%	34.2 KB
3	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 3		218.8 GB	2.7 MB	3.8 MB	218.8 GB	99%	376.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 1 04:15:02 - 04:59:59	21737	3.6%	8.06	4.0 GB	1.8%	1541.29
May 1 05:00:00 - 05:59:59	12399	2.0%	3.44	4.3 GB	2.0%	1248.67
May 1 06:00:00 - 06:59:59	12318	2.0%	3.42	3.8 GB	1.7%	1098.35
May 1 07:00:00 - 07:59:59	28628	4.7%	7.95	6.3 GB	2.9%	1837.26
May 1 08:00:00 - 08:59:59	29300	4.8%	8.14	5.5 GB	2.5%	1590.80
May 1 09:00:00 - 09:59:59	30104	4.9%	8.36	1.7 GB	0.8%	494.82
May 1 10:00:00 - 10:59:59	26269	4.3%	7.30	4.0 GB	1.8%	1172.85
May 1 11:00:00 - 11:59:59	19259	3.2%	5.35	5.3 GB	2.4%	1532.13
May 1 12:00:00 - 12:59:59	24676	4.1%	6.85	6.7 GB	3.0%	1938.39
May 1 13:00:00 - 13:59:59	31878	5.2%	8.86	14.5 GB	6.6%	4220.88
May 1 14:00:00 - 14:59:59	24825	4.1%	6.90	11.5 GB	5.2%	3346.30
May 1 15:00:00 - 15:59:59	27799	4.6%	7.72	12.4 GB	5.6%	3598.91
May 1 16:00:00 - 16:59:59	29739	4.9%	8.26	12.8 GB	5.9%	3735.30
May 1 17:00:00 - 17:59:59	28891	4.7%	8.03	11.7 GB	5.4%	3422.42
May 1 18:00:00 - 18:59:59	23366	3.8%	6.49	9.7 GB	4.4%	2817.88
May 1 19:00:00 - 19:59:59	26216	4.3%	7.28	11.3 GB	5.1%	3285.38
May 1 20:00:00 - 20:59:59	24203	4.0%	6.72	10.7 GB	4.9%	3129.69
May 1 21:00:00 - 21:59:59	24959	4.1%	6.93	11.4 GB	5.2%	3315.92
May 1 22:00:00 - 22:59:59	23968	3.9%	6.66	11.4 GB	5.2%	3326.56
May 1 23:00:00 - 23:59:59	29285	4.8%	8.13	12.2 GB	5.6%	3566.33
May 2 00:00:00 - 00:59:59	30672	5.0%	8.52	13.8 GB	6.3%	4008.61
May 2 01:00:00 - 01:59:59	27853	4.6%	7.74	13.4 GB	6.1%	3914.82
May 2 02:00:00 - 02:59:59	13244	2.2%	3.68	4.3 GB	2.0%	1260.30
May 2 03:00:00 - 03:59:59	29765	4.9%	8.27	12.9 GB	5.9%	3754.71
May 2 04:00:00 - 04:15:02	7648	1.3%	8.48	3.6 GB	1.6%	4141.74
TOTAL: 24:00:00	609001	100.0%	7.05	219.1 GB	100.0%	2658.76

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	899	255	42	4	598
2	news.icm.edu.pl	25	0	8	0	17
TOTAL: 2		924	255	50	4	615

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	197
it.comp.os.win.windows10	27
comp.sys.raspberry-pi	12
de.comp.lang.vbclassic	7
comp.mobile.android	3
de.comp.lang.php	2
de.rec.sf.startrek	2
it.comp.os.linux.ubuntu	2
it.tlc.cellulari.android	2
it.comp.os.win.windows7	1
TOTAL: 10	255

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

Groups	Count
pl.praca.oferowana	4
TOTAL: 1	4

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	9
Binary in non-binary group	4
Too many newsgroups	4
TOTAL: 3	17

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	44174	924	15255	313	3	40804	2%	23:59:58
2	icm	20619	206	15962	17	0	0	0%	23:59:42
3	nask	0	0	0	0	0	10909	0%	23:57:51
4	okapi	0	0	0	0	0	1189	0%	23:57:51
5	poznan	35	0	28	0	0	0	0%	04:02:53
TOTAL: 5		64828	1130	31245	330	3	52902	1%	99:58:15

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	2.9 MB	1.6 MB	4.4 MB	0.1 KB/s	3.7 KB	23:59:58
2	icm	565.4 KB	73.1 KB	638.5 KB	0.0 KB/s	2.9 KB	23:59:42
3	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:51
4	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:51
5	poznan	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	04:02:53
TOTAL: 5		3.4 MB	1.6 MB	5.0 MB	0.0 KB/s	3.5 KB	99:58:15

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	web.man.lodz.pl	288	52	47.0 KB	25	0	0	00:00:17
TOTAL: 1		288	52	47.0 KB	25	0	0	01:29:12

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.man.lodz.pl	576	52	47.0 KB	25	00:00:19
2	*.security.ipip.net	1	0	0.0 KB	0	00:00:00
3	*.20.97.83.ro.ovo.sc	1	0	0.0 KB	0	00:00:10
4	unresolved	101	0	0.0 KB	0	00:00:14
5	*.play-internet.pl	8	0	0.0 KB	0	01:28:27
TOTAL: 5		687	52	47.0 KB	25	01:29:12

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
user-5-173-196-168.play-internet.pl	0.036	0.032	01:02:02
user-5-173-187-201.play-internet.pl	0.020	0.008	00:13:25
user-5-173-178-75.play-internet.pl	0.004	0.012	00:12:58
web.man.lodz.pl	1.568	2.724	00:00:17
35.20.97.83.ro.ovo.sc	0.008	0.004	00:00:10
162.243.138.27	0.004	0.004	00:00:09
80.111.142.213	0.024	0.052	00:00:03
mon.man.lodz.pl	1.308	2.268	00:00:02
212.51.192.185	0.528	0.672	00:00:00
scan-141.security.ipip.net	0.008	0.000	00:00:00
TOTAL: 10	3.508	5.776	01:29:12

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
212.51.192.185	96
user-5-173-196-168.play-internet.pl	4
80.111.142.213	4
user-5-173-187-201.play-internet.pl	2
user-5-173-178-75.play-internet.pl	2
scan-141.security.ipip.net	1
35.20.97.83.ro.ovo.sc	1
162.243.138.27	1
TOTAL: 9	399

NNRP unrecognized commands (by host):

System	Conn
scan-141.security.ipip.net	4
TOTAL: 1	4

NNRP unrecognized commands (by command):

Command	Count
Connection: close	1
User-Agent: HTTP Banner Detection (https...	1
GET / HTTP/1.1	1
Host: 212.191.2.21:119	1
TOTAL: 4	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
user-5-173-196-168.play-internet.pl	2	0
scan-141.security.ipip.net	1	1
TOTAL: 2	3	1

Newsgroup request counts (by category):

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

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

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

Daily Usenet report

May 1 04:15:02 -- May 2 04:15:02