Daily Usenet report

Jan 17 04:15:02 -- Jan 18 04:15:01

Unknown entries from news log file:

First 50 / 170 lines (29.4%)

Jan 17 06:30:23 newsfeed nnrpd[27411]: unable to get certificate from '/etc/news/cert.pem'
Jan 17 06:30:23 newsfeed nnrpd[27411]: error initializing TLS: [CA_file: ] [CA_path: /etc/news] [cert_file: /etc/news/cert.pem] [key_file: /etc/news/key.pem]
Jan 17 06:50:03 newsfeed overchan[6025]: input line too long, skipping
Jan 17 06:50:08 newsfeed overchan[6025]: message repeated 3 times: [ input line too long, skipping]
Jan 17 06:50:14 newsfeed overchan[6025]: input line too long, skipping
Jan 17 06:50:26 newsfeed overchan[6025]: message repeated 5 times: [ input line too long, skipping]
Jan 17 06:50:37 newsfeed overchan[6025]: input line too long, skipping
Jan 17 06:50:45 newsfeed overchan[6025]: message repeated 3 times: [ input line too long, skipping]
Jan 17 06:50:47 newsfeed overchan[6025]: input line too long, skipping
Jan 17 06:50:54 newsfeed overchan[6025]: message repeated 5 times: [ input line too long, skipping]
Jan 17 06:51:02 newsfeed overchan[6025]: input line too long, skipping
Jan 17 06:51:11 newsfeed overchan[6025]: message repeated 4 times: [ input line too long, skipping]
Jan 17 06:51:14 newsfeed overchan[6025]: input line too long, skipping
Jan 17 06:51:16 newsfeed overchan[6025]: message repeated 2 times: [ input line too long, skipping]
Jan 17 06:51:23 newsfeed overchan[6025]: input line too long, skipping
Jan 17 06:51:26 newsfeed overchan[6025]: input line too long, skipping
Jan 17 06:51:35 newsfeed overchan[6025]: input line too long, skipping
Jan 17 06:51:40 newsfeed overchan[6025]: message repeated 3 times: [ input line too long, skipping]
Jan 17 06:51:42 newsfeed overchan[6025]: input line too long, skipping
Jan 17 06:51:55 newsfeed overchan[6025]: message repeated 5 times: [ input line too long, skipping]
Jan 17 06:51:56 newsfeed overchan[6025]: input line too long, skipping
Jan 17 06:51:58 newsfeed overchan[6025]: input line too long, skipping
Jan 17 06:52:00 newsfeed overchan[6025]: input line too long, skipping
Jan 17 06:52:12 newsfeed overchan[6025]: message repeated 6 times: [ input line too long, skipping]
Jan 17 06:52:22 newsfeed overchan[6025]: input line too long, skipping
Jan 17 06:52:29 newsfeed overchan[6025]: message repeated 4 times: [ input line too long, skipping]
Jan 17 06:52:31 newsfeed overchan[6025]: input line too long, skipping
Jan 17 06:52:33 newsfeed overchan[6025]: input line too long, skipping
Jan 17 06:52:34 newsfeed overchan[6025]: input line too long, skipping
Jan 17 06:52:59 newsfeed overchan[6025]: message repeated 7 times: [ input line too long, skipping]
Jan 17 06:53:03 newsfeed overchan[6025]: input line too long, skipping
Jan 17 06:53:05 newsfeed overchan[6025]: input line too long, skipping
Jan 17 06:53:09 newsfeed overchan[6025]: message repeated 3 times: [ input line too long, skipping]
Jan 17 06:53:13 newsfeed overchan[6025]: input line too long, skipping
Jan 17 06:53:25 newsfeed overchan[6025]: message repeated 5 times: [ input line too long, skipping]
Jan 17 06:53:28 newsfeed overchan[6025]: input line too long, skipping
Jan 17 06:53:32 newsfeed overchan[6025]: input line too long, skipping
Jan 17 06:53:36 newsfeed overchan[6025]: input line too long, skipping
Jan 17 06:53:39 newsfeed overchan[6025]: input line too long, skipping
Jan 17 06:53:59 newsfeed overchan[6025]: message repeated 10 times: [ input line too long, skipping]
Jan 17 06:54:11 newsfeed overchan[6025]: input line too long, skipping
Jan 17 06:54:12 newsfeed overchan[6025]: message repeated 2 times: [ input line too long, skipping]
Jan 17 06:54:15 newsfeed overchan[6025]: input line too long, skipping
Jan 17 06:54:16 newsfeed overchan[6025]: input line too long, skipping
Jan 17 06:54:24 newsfeed overchan[6025]: message repeated 2 times: [ input line too long, skipping]
Jan 17 06:54:29 newsfeed overchan[6025]: input line too long, skipping
Jan 17 06:54:33 newsfeed overchan[6025]: input line too long, skipping
Jan 17 06:54:43 newsfeed overchan[6025]: input line too long, skipping
Jan 17 06:54:56 newsfeed overchan[6025]: message repeated 3 times: [ input line too long, skipping]
Jan 17 06:54:59 newsfeed overchan[6025]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn 620849 92.1%67.9 MB 91.2%
innfeed 41075 6.1%4.8 MB 6.5%
innd 8649 1.3%1.3 MB 1.8%
nnrpd 3429 0.5%323.5 KB 0.4%
overchan 167 0.0%19.3 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 6 674170 100.0%74.4 MB100.0%

History cache:

ReasonCount%Count
Do not exist 617273 99.2%
Negative hits 4313 0.7%
Positive hits 358 0.1%
Cache misses 32 0.0%
TOTAL: 4 621976 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.431 0.0%83 0.000 17.241 78.000
article cleanup00:00:05.244 0.0%621290 0.004 0.008 0.014
article logging00:00:16.303 0.0%621690 0.020 0.026 0.032
article parse00:10:49.835 0.8%79540183 0.007 0.008 0.012
article write00:04:49.433 0.3%620983 0.371 0.466 0.667
artlog/artcncl00:00:00.003 0.0%81 0.000 0.037 1.000
data move00:01:19.360 0.1%84591447 0.001 0.001 0.001
hisgrep/artcncl00:00:00.378 0.0%78 0.000 4.846 29.000
hishave/artcncl00:00:00.007 0.0%83 0.000 0.084 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:07:48.938 0.5%625958 0.188 0.749 5.024
history sync00:00:00.013 0.0%291 0.000 0.045 1.000
history write00:43:18.299 3.0%621231 0.357 4.183 16.860
hiswrite/artcncl00:00:00.024 0.0%5 0.000 4.800 22.000
idle21:28:01.653 89.4%79077816 0.623 0.977 2.158
nntp read00:23:57.546 1.7%79176856 0.016 0.018 0.023
overview write00:00:02.281 0.0%620983 0.002 0.004 0.007
perl filter00:42:01.071 2.9%621231 3.605 4.058 4.762
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:17.719 0.0%1255564 0.011 0.014 0.018
TOTAL: 24:00:03.87423:42:49.538 98.8%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.092 0.0%47696 0.000 0.002 0.016
article prepare00:00:00.008 0.0%11730 0.000 0.001 0.017
article read00:00:08.020 0.0%2926 0.000 2.741 23.657
backlog stats00:00:00.131 0.0%119956 0.000 0.001 0.015
callbacks00:00:00.000 0.0%1 0.000 0.000 0.000
data read00:00:00.588 0.0%57766 0.000 0.010 0.024
data write00:00:01.290 0.0%44471 0.000 0.029 0.054
idle23:54:54.161 99.9%119956 388.057 717.715 1527.829
status file00:00:38.103 0.0%13843 0.314 2.753 326.547
TOTAL: 23:55:46.42923:55:42.393100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.468 0.0%1017 0.000 0.460 13.000
idle01:03:05.593 99.5%3838 0.000 986.345276403.286
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.430 0.0%8193 0.000 0.052 12.120
readart00:00:00.068 0.0%39 0.000 1.744 43.000
TOTAL: 01:03:25.95601:03:06.559 99.5%----

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 98 620282 620048 0 234 99%68:19:33
2news.icm.edu.pl 32 437 69 294 74 15%20:49:13
3localhost 1444 3 3 0 0 100%00:00:01
TOTAL: 3 1574 620722 620120 294 308 99%89:08:47
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl310.4 GB4.8 MB5.4 MB310.4 GB 99%524.8 KB
2news.icm.edu.pl5.5 MB141.1 KB1.5 MB7.2 MB 77%51.5 KB
3localhost4.4 KB0.0 KB0.0 KB4.4 KB100%1.5 KB
TOTAL: 3310.4 GB5.0 MB6.9 MB310.4 GB 99%524.7 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jan 17 04:15:02 - 04:59:59 20784 3.4% 7.7010.7 GB 3.4%4150.48
Jan 17 05:00:00 - 05:59:59 25439 4.1% 7.0713.2 GB 4.2%3837.33
Jan 17 06:00:00 - 06:59:59 25049 4.0% 6.9613.0 GB 4.2%3782.48
Jan 17 07:00:00 - 07:59:59 24017 3.9% 6.6712.9 GB 4.1%3751.20
Jan 17 08:00:00 - 08:59:59 25335 4.1% 7.0412.5 GB 4.0%3631.19
Jan 17 09:00:00 - 09:59:59 25828 4.2% 7.1712.3 GB 3.9%3570.76
Jan 17 10:00:00 - 10:59:59 26974 4.4% 7.4913.5 GB 4.4%3940.87
Jan 17 11:00:00 - 11:59:59 28908 4.7% 8.0314.1 GB 4.5%4110.33
Jan 17 12:00:00 - 12:59:59 26027 4.2% 7.2312.3 GB 4.0%3593.17
Jan 17 13:00:00 - 13:59:59 25964 4.2% 7.2113.2 GB 4.2%3834.66
Jan 17 14:00:00 - 14:59:59 27596 4.5% 7.6714.2 GB 4.6%4131.88
Jan 17 15:00:00 - 15:59:59 25604 4.1% 7.1113.2 GB 4.2%3842.50
Jan 17 16:00:00 - 16:59:59 29373 4.7% 8.1615.2 GB 4.9%4430.82
Jan 17 17:00:00 - 17:59:59 25895 4.2% 7.1913.0 GB 4.2%3787.69
Jan 17 18:00:00 - 18:59:59 23640 3.8% 6.5712.0 GB 3.8%3481.45
Jan 17 19:00:00 - 19:59:59 26282 4.2% 7.3013.4 GB 4.3%3890.57
Jan 17 20:00:00 - 20:59:59 24132 3.9% 6.7012.5 GB 4.0%3630.72
Jan 17 21:00:00 - 21:59:59 29808 4.8% 8.2814.7 GB 4.7%4292.17
Jan 17 22:00:00 - 22:59:59 27589 4.4% 7.6613.7 GB 4.4%3984.83
Jan 17 23:00:00 - 23:59:59 26415 4.3% 7.3412.5 GB 4.0%3635.13
Jan 18 00:00:00 - 00:59:59 25619 4.1% 7.1212.5 GB 4.0%3631.83
Jan 18 01:00:00 - 01:59:59 25771 4.2% 7.1612.3 GB 3.9%3570.98
Jan 18 02:00:00 - 02:59:59 21521 3.5% 5.9811.0 GB 3.5%3203.07
Jan 18 03:00:00 - 03:59:59 21546 3.5% 5.9910.7 GB 3.4%3120.11
Jan 18 04:00:00 - 04:15:01 4947 0.8% 5.492.6 GB 0.8%3053.27
TOTAL: 23:59:59 620063 100.0% 7.18310.9 GB 100.0%3773.40
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 635 176 0 54 4 0 0 0 401
2news.icm.edu.pl 71 0 0 3 0 0 0 0 68
TOTAL: 2 706 176 0 57 4 0 0 0 469

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 83
alt.comp.software.firefox 29
it.comp.os.win.windows10 14
nl.comp.os.linux.techniek 11
comp.mobile.android 8
de.rec.sf.startrek 6
alt.comp.software.seamonkey 5
it.tlc.cellulari.android 5
de.comp.lang.php 5
comp.mobile.ipad 3
it.discussioni.energia 2
it.comp.os.linux.ubuntu 2
comp.sys.raspberry-pi 1
nl.actueel 1
it.comp.dotnet 1
TOTAL: 15 176

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

GroupsCount
pl.praca.oferowana 4
TOTAL: 1 4

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 46
Too many newsgroups 10
Binary in non-binary group 6
Scoring filter (8) 4
Scoring filter (10) 1
Scoring filter (9) 1
TOTAL: 6 68

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 401
TOTAL: 1 401
TOTAL: 1 401

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1icm 22813 1823 11215 150 0 2 7%23:59:46
2panorama 35437 402 12133 159 3 35088 1%23:59:53
3poznan 53 0 39 0 0 0 0%04:22:05
4nask 0 0 0 0 0 8839 0%23:57:46
5okapi 0 0 0 0 0 950 0%23:57:45
TOTAL: 5 58303 2225 23387 309 3 44879 3%100:17:15
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm6.0 MB12.1 MB18.1 MB0.2 KB/s9.4 KB23:59:46
2panorama1.1 MB558.2 KB1.6 MB0.0 KB/s3.0 KB23:59:53
3poznan0.0 KB0.0 KB0.0 KB0.0 KB/s004:22:05
4nask0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:46
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:45
TOTAL: 57.1 MB12.6 MB19.8 MB0.1 KB/s8.0 KB100:17:15
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 287 3231.6 KB 15 0 000:00:09
2212.90.61.180 4 34.4 KB 3 0 000:00:04
3isvcs01.man.lodz.pl 3 00.0 KB 0 3 000:00:00
TOTAL: 3 294 3536.0 KB 18 3 001:03:25

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 290 3231.6 KB 15 3 000:00:10
2unresolved 117 34.4 KB 3 0 000:01:06
3*.slubice.vectranet.pl 1 00.0 KB 0 0 000:32:15
4*.ftth.glasoperator.nl 2 00.0 KB 0 0 000:29:29
5*.a2hosting.com 2 00.0 KB 0 0 000:00:17
6*.ch1.censys-scanner.com 1 00.0 KB 0 0 000:00:00
7*.for-privacy.net 1 00.0 KB 0 0 000:00:06
TOTAL: 7 414 3536.0 KB 18 3 001:03:25

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
095160182224.slubice.vectranet.pl 0.022 0.026 0.00000:32:15
176-210-177-143.ftth.glasoperator.nl 0.023 0.219 0.00000:29:29
46.232.210.48 0.020 0.236 0.00000:00:38
az1-ss13.a2hosting.com 0.018 0.053 0.00000:00:17
78.159.86.36 0.016 0.024 0.00000:00:10
143.198.157.115 0.004 0.039 0.00000:00:10
web.man.lodz.pl 0.906 7.815 0.00000:00:09
tor-exit-56.for-privacy.net 0.004 0.026 0.00000:00:06
212.90.61.180 0.055 0.123 0.00000:00:04
80.111.142.213 0.015 0.107 0.00000:00:02
212.51.192.185 0.266 2.355 0.00000:00:00
scanner-05.ch1.censys-scanner.com 0.000 0.029 0.00000:00:00
79.124.62.106 0.007 0.018 0.00000:00:00
isvcs01.man.lodz.pl 0.010 0.087 0.00000:00:00
TOTAL: 14 1.366 11.157 0.00001:03:25

Curious NNRP server explorers [Top 20]:

SystemConn
212.51.192.185 96
46.232.210.48 10
80.111.142.213 4
176-210-177-143.ftth.glasoperator.nl 2
az1-ss13.a2hosting.com 2
tor-exit-56.for-privacy.net 1
79.124.62.106 1
scanner-05.ch1.censys-scanner.com 1
095160182224.slubice.vectranet.pl 1
143.198.157.115 1
78.159.86.36 1
TOTAL: 11 120

NNRP unrecognized commands (by host):

SystemConn
scanner-05.ch1.censys-scanner.com 1
79.124.62.106 1
TOTAL: 2 2

NNRP unrecognized commands (by command):

CommandCount
EHLO www.censys.io 1
#003 1
TOTAL: 2 2

NNRP client timeouts [Top 20]:

SystemConnPeer
095160182224.slubice.vectranet.pl 1 0
79.124.62.106 1 1
TOTAL: 2 2 1

Newsgroup request counts (by category):

CategoryCountPct
1pl 32 91.4%
2lodman 3 8.6%
TOTAL: 2 35100.0%

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

NewsgroupCount
1pl.comp.os.ms-windows.winnt 20
2pl.comp.pecet 12
3lodman.info 3
TOTAL: 3 35