Daily Usenet report

Nov 1 04:15:19 -- Nov 2 04:15:01

Unknown entries from news log file:

First 18 / 18 lines (100.0%)

Nov 1 07:45:03 newsfeed pgpverify[19071]: pgpverify: verification failed
Nov 1 15:17:10 newsfeed nnrpd[21948]: unable to get certificate from '/etc/news/cert.pem'
Nov 1 15:17:10 newsfeed nnrpd[21948]: error initializing TLS: [CA_file: ] [CA_path: /etc/news] [cert_file: /etc/news/cert.pem] [key_file: /etc/news/key.pem]
Nov 1 15:52:44 newsfeed overchan[20265]: input line too long, skipping
Nov 1 16:28:08 newsfeed overchan[20265]: input line too long, skipping
Nov 1 16:53:08 newsfeed overchan[20265]: input line too long, skipping
Nov 1 17:09:04 newsfeed overchan[20265]: input line too long, skipping
Nov 1 17:52:47 newsfeed overchan[20265]: input line too long, skipping
Nov 1 19:22:26 newsfeed overchan[20265]: input line too long, skipping
Nov 1 19:32:49 newsfeed overchan[20265]: input line too long, skipping
Nov 1 19:37:03 newsfeed overchan[20265]: input line too long, skipping
Nov 1 19:54:57 newsfeed overchan[20265]: input line too long, skipping
Nov 1 21:00:02 newsfeed pgpverify[8447]: pgpverify: verification failed
Nov 1 21:00:02 newsfeed controlchan[20263]: skipping newgroup y group-admin@isc.org (pgpverify failed) in <cmsg-20211101200001$2726@isc.org>
Nov 1 21:00:03 newsfeed pgpverify[8455]: pgpverify: verification failed
Nov 1 21:00:03 newsfeed controlchan[20263]: skipping newgroup y group-admin@isc.org (pgpverify failed) in <cmsg-20211101200002$0bfc@isc.org>
Nov 1 23:02:36 newsfeed overchan[20265]: input line too long, skipping
Nov 2 01:10:02 newsfeed overchan[20265]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn 760041 94.9%82.2 MB 94.2%
innfeed 26771 3.3%3.2 MB 3.6%
innd 10435 1.3%1.6 MB 1.8%
nnrpd 3346 0.4%308.7 KB 0.3%
controlchan 14 0.0%2.6 KB 0.0%
overchan 12 0.0%2.0 KB 0.0%
pgpverify 3 0.0%0.3 KB 0.0%
TOTAL: 7 800622 100.0%87.2 MB100.0%

History cache:

ReasonCount%Count
Do not exist 756047 99.3%
Negative hits 4572 0.6%
Positive hits 337 0.0%
Cache misses 47 0.0%
TOTAL: 4 761003 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:02.028 0.0%117 0.000 17.333 306.000
article cleanup00:00:03.719 0.0%758803 0.001 0.005 0.009
article logging00:00:12.605 0.0%759206 0.011 0.017 0.034
article parse00:07:05.534 0.5%115738326 0.003 0.004 0.017
article write00:07:42.499 0.5%758484 0.173 0.610 329.610
artlog/artcncl00:00:00.001 0.0%117 0.000 0.009 0.333
data move00:00:22.551 0.0%120489164 0.000 0.000 0.010
hisgrep/artcncl00:00:00.583 0.0%114 0.000 5.114 220.000
hishave/artcncl00:00:00.005 0.0%117 0.000 0.043 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:13:32.063 0.9%763881 0.105 1.063 250.022
history sync00:00:00.012 0.0%291 0.000 0.041 1.000
history write01:17:00.824 5.3%758766 0.304 6.090 792.889
hiswrite/artcncl00:00:00.000 0.0%3 0.000 0.000 0.000
idle21:24:22.029 89.1%115552392 0.126 0.667 0.880
nntp read00:13:18.076 0.9%115433763 0.006 0.007 0.014
overview write00:00:01.626 0.0%758484 0.000 0.002 0.004
perl filter00:25:33.515 1.8%758766 0.727 2.021 31.082
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:12.101 0.0%1528600 0.006 0.008 0.018
TOTAL: 24:00:46.62823:49:29.771 99.2%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.031 0.0%33492 0.000 0.001 0.008
article prepare00:00:00.003 0.0%11058 0.000 0.000 0.023
article read00:00:06.714 0.0%2765 0.000 2.428 911.667
backlog stats00:00:00.051 0.0%90955 0.000 0.001 0.016
callbacks00:00:00.000 0.0%1 0.000 0.000 0.000
data read00:00:00.246 0.0%44371 0.000 0.006 0.024
data write00:00:00.665 0.0%33157 0.000 0.020 0.052
idle22:59:45.608 96.3%90955 0.000 910.182 1864.388
status file00:52:59.369 3.7%9032 0.200 352.012 22499.775
TOTAL: 23:52:54.45623:52:52.687100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:25.930 55.2%2757 0.000 9.405 10038.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.037 0.1%5882 0.000 0.006 0.500
readart00:00:00.001 0.0%36 0.000 0.028 0.500
TOTAL: 00:00:46.96600:00:25.968 55.3%----

Control commands to INND:

CommandNumber
L 1
flush 27
flushlogs 2
go 2
logmode 2
mode 143
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 187

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
moderator@dana.de001101
TOTAL001101

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 393 759284 758983 2 299 99%109:41:00
2news.icm.edu.pl 38 306 53 233 20 17%16:37:40
3localhost 1441 0 0 0 0 0%00:01:31
TOTAL: 3 1872 759590 759036 235 319 99%126:20:11
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl272.9 GB2.3 MB3.1 MB272.9 GB 99%376.8 KB
2news.icm.edu.pl1.9 MB0.0 KB399.7 KB2.3 MB 83%32.5 KB
3localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 3272.9 GB2.3 MB3.5 MB272.9 GB 99%376.8 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Nov 1 04:15:19 - 04:59:59 3067 0.4% 1.14357.2 MB 0.1% 136.42
Nov 1 05:00:00 - 05:59:59 25136 3.3% 6.9810.3 GB 3.8%2990.37
Nov 1 06:00:00 - 06:59:59 32684 4.3% 9.0812.6 GB 4.6%3684.17
Nov 1 07:00:00 - 07:59:59 32409 4.3% 9.0013.0 GB 4.7%3776.07
Nov 1 08:00:00 - 08:59:59 31364 4.1% 8.7111.3 GB 4.1%3300.32
Nov 1 09:00:00 - 09:59:59 29589 3.9% 8.2210.7 GB 3.9%3124.59
Nov 1 10:00:00 - 10:59:59 28037 3.7% 7.7910.6 GB 3.9%3091.51
Nov 1 11:00:00 - 11:59:59 32094 4.2% 8.9112.5 GB 4.6%3629.82
Nov 1 12:00:00 - 12:59:59 32152 4.2% 8.9312.8 GB 4.7%3730.47
Nov 1 13:00:00 - 13:59:59 32373 4.3% 8.9912.3 GB 4.5%3573.40
Nov 1 14:00:00 - 14:59:59 33993 4.5% 9.4413.0 GB 4.7%3777.44
Nov 1 15:00:00 - 15:59:59 34935 4.6% 9.7011.0 GB 4.0%3217.07
Nov 1 16:00:00 - 16:59:59 34799 4.6% 9.6711.2 GB 4.1%3255.99
Nov 1 17:00:00 - 17:59:59 33832 4.5% 9.4012.0 GB 4.4%3507.81
Nov 1 18:00:00 - 18:59:59 34876 4.6% 9.6911.7 GB 4.3%3420.18
Nov 1 19:00:00 - 19:59:59 39993 5.3% 11.1113.2 GB 4.8%3841.02
Nov 1 20:00:00 - 20:59:59 39764 5.2% 11.0511.5 GB 4.2%3362.33
Nov 1 21:00:00 - 21:59:59 29870 3.9% 8.3010.9 GB 4.0%3167.22
Nov 1 22:00:00 - 22:59:59 32021 4.2% 8.8911.6 GB 4.2%3369.24
Nov 1 23:00:00 - 23:59:59 30642 4.0% 8.5110.6 GB 3.9%3082.90
Nov 2 00:00:00 - 00:59:59 32821 4.3% 9.1211.2 GB 4.1%3252.13
Nov 2 01:00:00 - 01:59:59 33224 4.4% 9.2312.5 GB 4.6%3640.02
Nov 2 02:00:00 - 02:59:59 32807 4.3% 9.1112.4 GB 4.5%3600.22
Nov 2 03:00:00 - 03:59:59 29116 3.8% 8.0911.6 GB 4.2%3374.79
Nov 2 04:00:00 - 04:15:01 7604 1.0% 8.442.9 GB 1.1%3344.90
TOTAL: 23:59:42 759202 100.0% 8.79273.7 GB 100.0%3321.85
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 701 257 0 37 3 0 0 0 404
2news.icm.edu.pl 21 0 0 0 0 0 0 0 21
TOTAL: 2 722 257 0 37 3 0 0 0 425

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 164
it.tlc.cellulari.android 41
alt.comp.software.seamonkey 14
comp.mobile.android 12
de.rec.sf.startrek 10
comp.sys.raspberry-pi 9
it.comp.os.win.windows10 7
TOTAL: 7 257

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

GroupsCount
pl.praca.oferowana 3
TOTAL: 1 3

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 18
Binary in non-binary group 3
Too many newsgroups 1
TOTAL: 3 22

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1icm 18408 2306 8818 132 0 0 12%23:59:31
2panorama 22397 113 7882 41 1 25615 0%23:44:43
3nask 0 0 0 0 0 8031 0%23:56:07
4poznan 54 0 40 0 0 0 0%04:13:00
5okapi 0 0 0 0 0 777 0%23:22:54
TOTAL: 5 40859 2419 16740 173 1 34423 5%99:16:15
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm7.6 MB508.2 KB8.1 MB0.1 KB/s3.4 KB23:59:31
2panorama333.4 KB142.6 KB476.0 KB0.0 KB/s3.1 KB23:44:43
3nask0.0 KB0.0 KB0.0 KB0.0 KB/s023:56:07
4poznan0.0 KB0.0 KB0.0 KB0.0 KB/s004:13:00
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:22:54
TOTAL: 57.9 MB650.7 KB8.5 MB0.0 KB/s3.4 KB99:16:15
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 3638.0 KB 18 0 000:00:07
TOTAL: 1 288 3638.0 KB 18 0 000:00:47

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 288 3638.0 KB 18 0 000:00:07
2unresolved 104 00.0 KB 0 0 000:00:38
3*.ch1.censys-scanner.com 1 00.0 KB 0 0 000:00:00
TOTAL: 3 393 3638.0 KB 18 0 000:00:47

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
212.51.192.185 0.204 0.415 0.00000:00:16
165.232.146.58 0.000 0.006 0.00000:00:10
170.106.151.40 0.000 0.006 0.00000:00:10
web.man.lodz.pl 0.598 1.262 0.00000:00:07
80.111.142.213 0.000 0.025 0.00000:00:02
scanner-07.ch1.censys-scanner.com 0.007 0.000 0.00000:00:00
193.3.19.243 0.005 0.005 0.00000:00:00
TOTAL: 7 0.814 1.719 0.00000:00:47

Curious NNRP server explorers [Top 20]:

SystemConn
212.51.192.185 96
80.111.142.213 4
193.3.19.243 2
scanner-07.ch1.censys-scanner.com 1
165.232.146.58 1
170.106.151.40 1
TOTAL: 6 105

NNRP unrecognized commands (by host):

SystemConn
193.3.19.243 2
scanner-07.ch1.censys-scanner.com 1
TOTAL: 2 3

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

SystemConnPeer
193.3.19.243 2 2
TOTAL: 1 2 2

Newsgroup request counts (by category):

CategoryCountPct
1pl 36100.0%
TOTAL: 1 36100.0%

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

NewsgroupCount
1pl.comp.pecet 30
2pl.comp.os.ms-windows.winnt 6
TOTAL: 2 36