Daily Usenet report

Oct 27 04:15:02 -- Oct 28 04:15:03

Unknown entries from news log file:

First 15 / 15 lines (100.0%)

Oct 27 04:46:17 newsfeed nnrpd[21068]: unable to get certificate from '/etc/news/cert.pem'
Oct 27 04:46:17 newsfeed nnrpd[21068]: error initializing TLS: [CA_file: ] [CA_path: /etc/news] [cert_file: /etc/news/cert.pem] [key_file: /etc/news/key.pem]
Oct 27 11:02:14 newsfeed overchan[19892]: input line too long, skipping
Oct 27 11:02:27 newsfeed overchan[19892]: input line too long, skipping
Oct 27 13:23:20 newsfeed nnrpd[6936]: SERVER perl filter not defined
Oct 27 14:28:29 newsfeed nnrpd[9113]: SERVER perl filter not defined
Oct 27 16:38:38 newsfeed overchan[19892]: input line too long, skipping
Oct 27 17:06:08 newsfeed overchan[19892]: input line too long, skipping
Oct 27 18:00:54 newsfeed overchan[19892]: input line too long, skipping
Oct 27 18:23:20 newsfeed overchan[19892]: input line too long, skipping
Oct 27 21:09:50 newsfeed overchan[19892]: input line too long, skipping
Oct 27 21:13:09 newsfeed overchan[19892]: input line too long, skipping
Oct 28 00:19:56 newsfeed overchan[19892]: input line too long, skipping
Oct 28 02:31:57 newsfeed overchan[19892]: input line too long, skipping
Oct 28 02:36:14 newsfeed overchan[19892]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn2924080 97.6%333.3 MB 97.2%
innfeed 47233 1.6%5.6 MB 1.6%
innd 20026 0.7%3.3 MB 1.0%
nnrpd 5123 0.2%549.1 KB 0.2%
overchan 12 0.0%2.1 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 62996475 100.0%342.8 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2891142 97.4%
Negative hits 57579 1.9%
Positive hits 18591 0.6%
Cache misses 199 0.0%
TOTAL: 4 2967511 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:03.088 0.0%86 0.000 35.907 527.000
article cleanup00:00:43.287 0.0%2921874 0.011 0.015 0.019
article logging00:02:31.219 0.2%2922356 0.037 0.052 0.098
article parse00:59:52.595 4.0%84268072 0.026 0.043 0.055
article write00:23:59.553 1.6%2913876 0.311 0.494 1.056
artlog/artcncl00:00:00.004 0.0%78 0.000 0.051 1.000
artlog/artparse00:00:00.000 0.0%1 0.000 0.000 0.000
data move00:03:25.904 0.2%99248414 0.001 0.002 0.004
hisgrep/artcncl00:00:00.373 0.0%80 0.000 4.662 15.000
hishave/artcncl00:00:00.158 0.0%86 0.000 1.837 13.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup02:10:06.312 8.7%2994505 0.481 2.607 6.402
history sync00:00:00.017 0.0%302 0.000 0.056 0.500
history write04:29:48.110 18.0%2916591 2.099 5.550 8.968
hiswrite/artcncl00:00:00.001 0.0%6 0.167 0.167 0.167
idle09:13:24.743 36.9%52713474 0.173 0.630 1.639
nntp read01:19:07.387 5.3%81908504 0.041 0.058 0.069
overview write00:00:17.067 0.0%2913876 0.004 0.006 0.008
perl filter05:39:42.500 22.6%2916583 5.035 6.988 8.815
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:07:03.426 0.5%5862900 0.048 0.072 0.091
TOTAL: 25:00:03.90224:30:05.744 98.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.395 0.0%135285 0.000 0.003 0.023
article prepare00:00:00.020 0.0%48556 0.000 0.000 0.062
article read00:00:29.719 0.0%12091 0.000 2.458 157.000
backlog stats00:00:00.326 0.0%293214 0.000 0.001 0.006
callbacks00:00:00.000 0.0%3 0.000 0.000 0.000
data read00:00:04.024 0.0%158621 0.011 0.025 0.062
data write00:00:30.142 0.0%136162 0.048 0.221 0.665
idle24:53:15.533 99.9%293214 34.319 305.564 1772.254
status file00:00:22.623 0.0%16936 0.783 1.336 47.000
TOTAL: 24:55:03.34324:54:42.782100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:21.377 57.0%3154 0.000 6.778 132.500
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.373 1.0%7028 0.000 0.053 2.000
readart00:00:00.034 0.1%26 0.000 1.308 9.000
TOTAL: 00:00:37.51400:00:21.784 58.1%----

Control commands to INND:

CommandNumber
L 1
flush 28
flushlogs 2
go 2
logmode 2
mode 154
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 199

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 501 29164512912521 635 3295 99%437:30:01
2news.nask.pl 2 23553 3057 15832 4664 12%24:54:12
3news.icm.edu.pl 54 241 41 151 49 17%15:22:18
4localhost 3 2 2 0 0 100%00:00:00
TOTAL: 4 560 29402472915621 16618 8008 99%477:46:31
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl785.3 GB52.2 MB251.9 MB785.6 GB 99%282.5 KB
2news.nask.pl79.5 MB271.8 MB285.1 MB636.5 MB 12%84.4 KB
3news.icm.edu.pl974.8 KB8.5 KB3.4 MB4.3 MB 22%49.1 KB
4localhost2.6 KB0.0 KB0.0 KB2.6 KB100%1.3 KB
TOTAL: 4785.4 GB324.1 MB540.3 MB786.2 GB 99%282.0 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 27 04:15:02 - 04:59:59 95860 3.3% 35.5322.9 GB 2.9%8895.77
Oct 27 05:00:00 - 05:59:59 121001 4.2% 33.6132.6 GB 4.1%9491.23
Oct 27 06:00:00 - 06:59:59 142750 4.9% 39.6537.1 GB 4.7%10812.85
Oct 27 07:00:00 - 07:59:59 111136 3.8% 30.8730.7 GB 3.9%8928.18
Oct 27 08:00:00 - 08:59:59 131637 4.5% 36.5736.4 GB 4.6%10614.56
Oct 27 09:00:00 - 09:59:59 137838 4.7% 38.2938.4 GB 4.9%11178.40
Oct 27 10:00:00 - 10:59:59 126765 4.3% 35.2135.0 GB 4.5%10180.19
Oct 27 11:00:00 - 11:59:59 113335 3.9% 31.4832.2 GB 4.1%9369.88
Oct 27 12:00:00 - 12:59:59 105710 3.6% 29.3628.1 GB 3.6%8198.11
Oct 27 13:00:00 - 13:59:59 128996 4.4% 35.8335.2 GB 4.5%10247.69
Oct 27 14:00:00 - 14:59:59 118565 4.1% 32.9335.8 GB 4.6%10425.08
Oct 27 15:00:00 - 15:59:59 117558 4.0% 32.6631.4 GB 4.0%9135.43
Oct 27 16:00:00 - 16:59:59 101642 3.5% 28.2327.7 GB 3.5%8072.85
Oct 27 17:00:00 - 17:59:59 103376 3.5% 28.7230.1 GB 3.8%8775.30
Oct 27 18:00:00 - 18:59:59 124732 4.3% 34.6533.5 GB 4.3%9762.59
Oct 27 19:00:00 - 19:59:59 82003 2.8% 22.7824.4 GB 3.1%7114.60
Oct 27 20:00:00 - 20:59:59 95797 3.3% 26.6127.5 GB 3.5%7996.54
Oct 27 21:00:00 - 21:59:59 89150 3.1% 24.7622.6 GB 2.9%6593.11
Oct 27 22:00:00 - 22:59:59 99252 3.4% 27.5724.0 GB 3.1%6993.94
Oct 27 23:00:00 - 23:59:59 128753 4.4% 35.7627.7 GB 3.5%8080.50
Oct 28 00:00:00 - 00:59:59 96392 3.3% 26.7824.9 GB 3.2%7244.50
Oct 28 01:00:00 - 01:59:59 133824 4.6% 37.1736.2 GB 4.6%10544.56
Oct 28 02:00:00 - 02:59:59 256811 8.8% 71.3469.0 GB 8.8%20088.48
Oct 28 03:00:00 - 03:59:59 124505 4.3% 34.5834.0 GB 4.3%9915.01
Oct 28 04:00:00 - 04:15:03 28141 1.0% 31.167.8 GB 1.0%9109.08
TOTAL: 24:00:01 2915529 100.0% 33.74785.2 GB 100.0%9529.70
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nask.pl 4701 0 0 3079 1 0 0 0 1621
2newsfeed.pionier.net.pl 3723 103 0 2196 0 0 0 0 1424
3news.icm.edu.pl 49 0 0 1 0 0 0 0 48
TOTAL: 3 8473 103 0 5276 1 0 0 0 3093

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 42
it.comp.os.win.windows10 29
it.tlc.cellulari.android 21
comp.mobile.android 7
de.comp.os.os2 1
comp.mobile.ipad 1
de.comp.lang.vbclassic 1
it.comp.os.win.windows7 1
TOTAL: 8 103

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

GroupsCount
bit.listserv.catholic 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1467
Binary in non-binary group 987
EMP (phl) 74
Scoring filter (16) 34
Scoring filter (8) 15
Scoring filter (10) 7
EMP (fsl) 6
Scoring filter (9) 4
Too many newsgroups 4
Scoring filter (18) 2
Scoring filter (11) 2
HTML post 1
Scoring filter (12) 1
TOTAL: 13 2604

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 426
news.nask.pl 55
TOTAL: 2 481
TOTAL: 1 481

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nask 26963 4102 16686 4 0 6 15%24:59:48
2panorama 106403 935 76043 1320 4 56983 0%24:59:58
3poznan 4059 74 2849 9 0 0 1%24:59:58
4icm 37736 49 28803 5259 0 0 0%24:59:57
5okapi 0 0 0 0 0 1081 0%24:57:51
TOTAL: 5 175161 5160 124381 6592 4 58070 2%124:57:32
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nask1.4 GB1.0 MB1.4 GB16.1 KB/s352.7 KB24:59:48
2icm12.0 MB1.9 GB1.9 GB21.8 KB/s370.2 KB24:59:57
3panorama3.3 MB5.9 MB9.2 MB0.1 KB/s4.2 KB24:59:58
4poznan325.4 KB34.1 KB359.5 KB0.0 KB/s4.3 KB24:59:58
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s024:57:51
TOTAL: 51.4 GB1.9 GB3.3 GB7.6 KB/s291.3 KB124:57:32
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
okapi488.4 KB
TOTAL: 1488.4 KB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 300 2629.7 KB 12 0 000:00:27
2isvcs01.man.lodz.pl 2 00.0 KB 0 2 000:00:00
TOTAL: 2 302 2629.7 KB 12 2 000:00:36

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 602 2629.7 KB 12 2 000:00:32
2*.security.ipip.net 1 00.0 KB 0 0 000:00:01
3unresolved 107 00.0 KB 0 0 000:00:02
TOTAL: 3 710 2629.7 KB 12 2 000:00:36

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
web.man.lodz.pl 1.816 6.068 0.00000:00:27
mon.man.lodz.pl 1.576 5.372 0.00000:00:04
212.51.192.185 0.520 1.756 0.00000:00:01
185.232.64.32 0.040 0.152 0.00000:00:01
scan-141.security.ipip.net 0.000 0.032 0.00000:00:01
isvcs01.man.lodz.pl 0.024 0.052 0.00000:00:00
TOTAL: 6 3.976 13.432 0.00000:00:36

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 300
212.51.192.185 100
185.232.64.32 7
scan-141.security.ipip.net 1
TOTAL: 4 408

NNRP unrecognized commands (by host):

SystemConn
185.232.64.32 32
scan-141.security.ipip.net 4
TOTAL: 2 36

NNRP unrecognized commands (by command):

CommandCount
Host: 212.191.2.21:119 5
Connection: close 5
X-AnyConnect-Platform: mac-intel 4
X-Aggregate-Auth: 1 4
Accept-Encoding: identity 4
X-Transcend-Version: 1 4
User-Agent: AnyConnect Darwin_i386 3.1.0... 4
Accept: */* 4
User-Agent: HTTP Banner Detection (https... 1
GET / HTTP/1.1 1
TOTAL: 10 36

NNRP client timeouts [Top 20]:

SystemConnPeer
185.232.64.32 3 3
TOTAL: 1 3 3

Newsgroup request counts (by category):

CategoryCountPct
1pl 26100.0%
TOTAL: 1 26100.0%

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

NewsgroupCount
1pl.comp.pecet 20
2pl.comp.os.ms-windows.winnt 6
TOTAL: 2 26