Daily Usenet report

Oct 30 04:15:02 -- Oct 31 04:15:02

Unknown entries from news log file:

First 17 / 17 lines (100.0%)

Oct 30 04:40:22 newsfeed overchan[27414]: input line too long, skipping
Oct 30 05:17:59 newsfeed nnrpd[4898]: unable to get certificate from '/etc/news/cert.pem'
Oct 30 05:17:59 newsfeed nnrpd[4898]: error initializing TLS: [CA_file: ] [CA_path: /etc/news] [cert_file: /etc/news/cert.pem] [key_file: /etc/news/key.pem]
Oct 30 08:41:10 newsfeed nnrpd[4242]: SERVER perl filter not defined
Oct 30 12:03:05 newsfeed nnrpd[3448]: SERVER perl filter not defined
Oct 30 15:34:11 newsfeed nnrpd[3305]: SERVER perl filter not defined
Oct 30 19:29:18 newsfeed overchan[27414]: input line too long, skipping
Oct 30 19:41:57 newsfeed overchan[27414]: input line too long, skipping
Oct 30 19:48:16 newsfeed overchan[27414]: input line too long, skipping
Oct 30 20:02:33 newsfeed overchan[27414]: input line too long, skipping
Oct 30 20:42:21 newsfeed overchan[27414]: input line too long, skipping
Oct 30 21:09:22 newsfeed overchan[27414]: input line too long, skipping
Oct 30 21:10:31 newsfeed nnrpd[23096]: SERVER perl filter not defined
Oct 30 21:23:10 newsfeed overchan[27414]: input line too long, skipping
Oct 30 23:09:25 newsfeed overchan[27414]: input line too long, skipping
Oct 31 00:52:56 newsfeed overchan[27414]: input line too long, skipping
Oct 31 01:21:17 newsfeed overchan[27414]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn 812901 94.1%88.5 MB 93.4%
innfeed 36688 4.2%4.3 MB 4.6%
innd 9707 1.1%1.5 MB 1.6%
nnrpd 4299 0.5%426.2 KB 0.4%
overchan 12 0.0%2.2 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 6 863608 100.0%94.7 MB100.0%

History cache:

ReasonCount%Count
Do not exist 811042 99.4%
Negative hits 4609 0.6%
Positive hits 296 0.0%
Cache misses 31 0.0%
TOTAL: 4 815978 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.185 0.0%57 0.000 20.789 166.000
article cleanup00:00:11.714 0.0%812706 0.006 0.014 0.020
article logging00:00:41.863 0.0%813126 0.039 0.051 0.073
article parse00:16:35.193 1.2%39758878 0.010 0.025 0.042
article write00:07:14.879 0.5%812506 0.093 0.535 1.029
artlog/artcncl00:00:00.001 0.0%56 0.000 0.018 0.500
data move00:00:39.909 0.0%44022938 0.001 0.001 0.004
hisgrep/artcncl00:00:00.393 0.0%56 0.000 7.018 73.000
hishave/artcncl00:00:00.035 0.0%57 0.000 0.614 3.500
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:16:44.651 1.2%817866 0.335 1.228 5.260
history sync00:00:00.015 0.0%293 0.000 0.051 0.500
history write00:29:36.956 2.1%812676 0.855 2.187 9.340
hiswrite/artcncl00:00:00.011 0.0%1 11.000 11.000 11.000
idle20:53:40.445 87.1%39026449 0.846 1.927 73.678
nntp read00:32:24.452 2.3%39407213 0.026 0.049 0.060
overview write00:00:04.771 0.0%812506 0.002 0.006 0.009
perl filter01:10:23.709 4.9%812676 1.138 5.197 8.503
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:48.281 0.1%1639423 0.017 0.029 0.036
TOTAL: 24:00:09.32623:49:08.463 99.2%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.145 0.0%45050 0.000 0.003 0.018
article prepare00:00:00.001 0.0%5926 0.000 0.000 0.042
article read00:00:12.282 0.0%1479 0.000 8.304 48.000
backlog stats00:00:00.127 0.0%109716 0.000 0.001 0.004
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.131 0.0%54468 0.000 0.021 0.049
data write00:00:02.844 0.0%40225 0.029 0.071 0.113
idle23:54:10.623 99.9%109716 482.316 784.303 2072.556
status file00:00:42.234 0.0%12810 0.667 3.297 122.838
TOTAL: 23:55:15.94823:55:09.387100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.000 0.0%1 0.000 0.000 0.000
idle00:36:49.640 99.3%3212 0.000 687.933 33099.167
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.259 0.0%7274 0.000 0.036 0.500
readart00:00:00.012 0.0%8 0.500 1.500 3.500
TOTAL: 00:37:06.23300:36:49.911 99.3%----

Control commands to INND:

CommandNumber
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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 126 812254 812057 1 196 99%65:36:36
2news.icm.edu.pl 44 278 31 243 4 11%16:20:55
3localhost 1445 4 4 0 0 100%00:00:00
TOTAL: 3 1615 812536 812092 244 200 99%81:57:31
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl237.3 GB1.5 MB1.9 MB237.3 GB 99%306.3 KB
2news.icm.edu.pl1.6 MB15.0 KB119.7 KB1.7 MB 92%49.3 KB
3localhost6.3 KB0.0 KB0.0 KB6.3 KB100%1.6 KB
TOTAL: 3237.3 GB1.5 MB2.0 MB237.3 GB 99%306.3 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 30 04:15:02 - 04:59:59 12959 1.6% 4.80138.4 MB 0.1% 52.53
Oct 30 05:00:00 - 05:59:59 21093 2.6% 5.86214.9 MB 0.1% 61.14
Oct 30 06:00:00 - 06:59:59 15253 1.9% 4.24161.5 MB 0.1% 45.95
Oct 30 07:00:00 - 07:59:59 23501 2.9% 6.53205.9 MB 0.1% 58.58
Oct 30 08:00:00 - 08:59:59 18272 2.2% 5.08205.8 MB 0.1% 58.55
Oct 30 09:00:00 - 09:59:59 23587 2.9% 6.554.0 GB 1.7%1163.71
Oct 30 10:00:00 - 10:59:59 42273 5.2% 11.7412.3 GB 5.2%3579.68
Oct 30 11:00:00 - 11:59:59 43153 5.3% 11.9911.5 GB 4.9%3364.09
Oct 30 12:00:00 - 12:59:59 41213 5.1% 11.4512.3 GB 5.2%3580.64
Oct 30 13:00:00 - 13:59:59 34383 4.2% 9.5512.1 GB 5.1%3531.95
Oct 30 14:00:00 - 14:59:59 115624 14.2% 32.1216.0 GB 6.7%4660.59
Oct 30 15:00:00 - 15:59:59 38500 4.7% 10.6913.4 GB 5.6%3894.72
Oct 30 16:00:00 - 16:59:59 34113 4.2% 9.4813.3 GB 5.6%3878.39
Oct 30 17:00:00 - 17:59:59 36266 4.5% 10.0713.4 GB 5.6%3910.05
Oct 30 18:00:00 - 18:59:59 35854 4.4% 9.9612.8 GB 5.4%3718.61
Oct 30 19:00:00 - 19:59:59 38759 4.8% 10.7712.6 GB 5.3%3657.24
Oct 30 20:00:00 - 20:59:59 38970 4.8% 10.8212.5 GB 5.3%3650.89
Oct 30 21:00:00 - 21:59:59 39334 4.8% 10.9312.3 GB 5.2%3582.63
Oct 30 22:00:00 - 22:59:59 31215 3.8% 8.6713.0 GB 5.5%3796.48
Oct 30 23:00:00 - 23:59:59 25459 3.1% 7.0712.1 GB 5.1%3535.51
Oct 31 00:00:00 - 00:59:59 23697 2.9% 6.5811.5 GB 4.8%3339.55
Oct 31 01:00:00 - 01:59:59 24394 3.0% 6.7812.6 GB 5.3%3670.09
Oct 31 02:00:00 - 02:59:59 27355 3.4% 7.6014.5 GB 6.1%4215.99
Oct 31 03:00:00 - 03:59:59 21477 2.6% 5.9711.7 GB 4.9%3406.25
Oct 31 04:00:00 - 04:15:02 5521 0.7% 6.123.0 GB 1.2%3434.34
TOTAL: 24:00:00 812225 100.0% 9.40237.8 GB 100.0%2885.88
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 614 148 0 29 19 0 0 0 418
2news.icm.edu.pl 6 0 0 1 0 0 0 0 5
TOTAL: 2 620 148 0 30 19 0 0 0 423

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 43
it.comp.os.win.windows10 39
it.tlc.cellulari.android 35
comp.mobile.android 13
de.comp.office-pakete.ms-office.excel 10
comp.sys.raspberry-pi 3
de.rec.sf.startrek 3
it.comp.virtualizzazione 1
it.comp.os.win.windows7 1
TOTAL: 9 148

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

GroupsCount
pl.praca.oferowana 13
pl.sci.matematyka 6
TOTAL: 2 19

INND Perl filter [Top 20]:

ReasonCount
Binary in non-binary group 3
TOTAL: 1 3

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 418
news.icm.edu.pl 2
TOTAL: 2 420
TOTAL: 1 420

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1panorama 32867 411 11198 167 1 33380 1%23:59:00
2icm 21478 387 13709 112 0 0 1%24:02:14
3poznan 25 1 17 0 0 0 4%02:44:43
4okapi 0 0 0 0 0 937 0%24:05:35
5nask 0 0 0 0 0 9344 0%24:05:33
TOTAL: 5 54370 799 24924 279 1 43661 1%98:57:05
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm1.2 MB11.7 MB12.9 MB0.2 KB/s26.5 KB24:02:14
2panorama1.2 MB713.6 KB1.9 MB0.0 KB/s3.3 KB23:59:00
3poznan8.2 KB0.0 KB8.2 KB0.0 KB/s8.2 KB02:44:43
4okapi0.0 KB0.0 KB0.0 KB0.0 KB/s024:05:35
5nask0.0 KB0.0 KB0.0 KB0.0 KB/s024:05:33
TOTAL: 52.4 MB12.4 MB14.8 MB0.0 KB/s14.1 KB98:57:05
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1109.63.165.59 6 46.3 KB 4 0 000:00:53
2isvcs01.man.lodz.pl 4 00.0 KB 0 4 000:00:00
TOTAL: 2 10 46.3 KB 4 4 000:37:06

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 108 46.3 KB 4 0 000:34:05
2*.man.lodz.pl 580 00.0 KB 0 4 000:00:31
3*.compute-1.amazonaws.com 1 00.0 KB 0 0 000:02:26
4*.internet-census.org 6 00.0 KB 0 0 000:00:01
5*.play-internet.pl 1 00.0 KB 0 0 000:00:01
TOTAL: 5 696 46.3 KB 4 4 000:37:06

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
176.221.122.106 0.482 0.134 0.00000:33:07
ec2-34-229-22-197.compute-1.amazonaws.com 0.034 0.022 0.00000:02:26
109.63.165.59 0.037 0.097 0.00000:00:53
web.man.lodz.pl 1.821 3.938 0.00000:00:26
mon.man.lodz.pl 1.450 3.280 0.00000:00:04
80.111.142.213 0.031 0.059 0.00000:00:02
sh-chi-us-gp1-wk114.internet-census.org 0.026 0.073 0.00000:00:01
user-5-173-177-110.play-internet.pl 0.002 0.010 0.00000:00:01
212.51.192.185 0.561 0.953 0.00000:00:01
isvcs01.man.lodz.pl 0.054 0.064 0.00000:00:00
TOTAL: 10 4.498 8.630 0.00000:37:06

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
web.man.lodz.pl 288
212.51.192.185 96
sh-chi-us-gp1-wk114.internet-census.org 6
80.111.142.213 4
176.221.122.106 2
user-5-173-177-110.play-internet.pl 1
ec2-34-229-22-197.compute-1.amazonaws.com 1
TOTAL: 8 686

NNRP unrecognized commands (by host):

SystemConn
sh-chi-us-gp1-wk114.internet-census.org 10
TOTAL: 1 10

NNRP unrecognized commands (by command):

CommandCount
EHLO sh-chi-us-gp1-wk114.internet-census... 2
Host: 212.191.2.21:119 1
Accept-Encoding: gzip 1
Accept: */* 1
SSH-2.0-Go 1
GET / HTTP/1.1 1
User-Agent: Mozilla/5.0 (Windows NT 10.0... 1
#001 1
#026#003#001 1
TOTAL: 9 10

Newsgroup request counts (by category):

CategoryCountPct
1lodman 4100.0%
TOTAL: 1 4100.0%

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

NewsgroupCount
1lodman.info 4
TOTAL: 1 4