Daily Usenet report

Apr 10 04:15:02 -- Apr 11 04:15:02

Unknown entries from news log file:

First 12 / 12 lines (100.0%)

Apr 10 10:04:36 newsfeed nnrpd[3648]: SERVER perl filter not defined
Apr 10 11:26:20 newsfeed nnrpd[6454]: unable to get certificate from '/etc/news/cert.pem'
Apr 10 11:26:20 newsfeed nnrpd[6454]: error initializing TLS: [CA_file: ] [CA_path: /etc/news] [cert_file: /etc/news/cert.pem] [key_file: /etc/news/key.pem]
Apr 10 11:26:20 newsfeed nnrpd[6453]: unable to get certificate from '/etc/news/cert.pem'
Apr 10 11:26:20 newsfeed nnrpd[6453]: error initializing TLS: [CA_file: ] [CA_path: /etc/news] [cert_file: /etc/news/cert.pem] [key_file: /etc/news/key.pem]
Apr 10 11:26:20 newsfeed nnrpd[6455]: unable to get certificate from '/etc/news/cert.pem'
Apr 10 11:26:20 newsfeed nnrpd[6455]: error initializing TLS: [CA_file: ] [CA_path: /etc/news] [cert_file: /etc/news/cert.pem] [key_file: /etc/news/key.pem]
Apr 10 11:28:03 newsfeed nnrpd[6471]: unable to get certificate from '/etc/news/cert.pem'
Apr 10 11:28:03 newsfeed nnrpd[6471]: error initializing TLS: [CA_file: ] [CA_path: /etc/news] [cert_file: /etc/news/cert.pem] [key_file: /etc/news/key.pem]
Apr 10 13:01:58 newsfeed nnrpd[9660]: SERVER perl filter not defined
Apr 10 16:00:06 newsfeed nnrpd[15851]: SERVER perl filter not defined
Apr 10 23:02:30 newsfeed nnrpd[30451]: SERVER perl filter not defined

Log entries by program:

Program nameLines%LinesSize%Size
inn2613236 97.3%300.4 MB 96.8%
innfeed 47676 1.8%5.7 MB 1.8%
innd 20696 0.8%3.6 MB 1.2%
nnrpd 4936 0.2%529.2 KB 0.2%
TOTAL: 42686544 100.0%310.2 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2606574 98.6%
Negative hits 20769 0.8%
Positive hits 15588 0.6%
Cache misses 340 0.0%
TOTAL: 4 2643271 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:12.746 0.0%2301 0.000 5.539 474.000
article cleanup00:00:39.112 0.0%2610702 0.006 0.015 0.029
article logging00:01:30.055 0.1%2611323 0.017 0.034 0.757
article parse01:17:43.992 5.4%184245598 0.012 0.025 0.078
article write00:23:01.911 1.6%2606842 0.282 0.530 3.376
artlog/artcncl00:00:00.159 0.0%2273 0.000 0.070 1.000
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:03:08.394 0.2%200763675 0.000 0.001 0.006
hisgrep/artcncl00:00:03.874 0.0%403 0.000 9.613 30.833
hishave/artcncl00:00:00.516 0.0%2301 0.000 0.224 15.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:42:58.506 3.0%2643775 0.216 0.975 12.783
history sync00:00:00.007 0.0%293 0.000 0.024 1.000
history write00:54:47.120 3.8%2607361 0.252 1.261 34.899
hiswrite/artcncl00:00:00.433 0.0%1898 0.000 0.228 18.000
idle12:46:04.273 53.2%134836151 0.121 0.341 1.216
nntp read01:14:15.030 5.2%182216631 0.013 0.024 0.040
overview write00:00:15.446 0.0%2606842 0.001 0.006 0.016
perl filter05:32:41.861 23.1%2607361 3.922 7.656 10.498
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:04:56.680 0.3%5248495 0.034 0.057 0.095
TOTAL: 24:00:15.69623:02:20.115 96.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.240 0.0%86296 0.000 0.003 0.077
article prepare00:00:00.018 0.0%39766 0.000 0.000 0.062
article read00:00:29.239 0.0%9874 0.000 2.961 301.500
backlog stats00:00:00.188 0.0%236413 0.000 0.001 0.020
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.705 0.0%123168 0.000 0.014 0.103
data write00:00:09.429 0.0%94678 0.000 0.100 1.221
idle23:58:41.019 99.9%236413 17.935 365.128 1377.087
status file00:00:15.422 0.0%16347 0.250 0.943 103.143
TOTAL: 23:59:50.99923:59:37.260100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle13:37:35.993 99.9%393914 0.000 124.535211268.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:16.265 0.0%788548 0.000 0.021 0.500
readart00:00:00.643 0.0%56 0.000 11.482 58.125
TOTAL: 13:38:40.11213:37:52.901 99.9%----

Control commands to INND:

CommandNumber
L 1
flush 24
flushlogs 2
go 2
logmode 2
mode 149
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 190

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 1023 26066312603196 58 3377 99%298:22:41
2news.nask.pl 2 15299 3072 11779 448 20%23:10:56
3news.icm.edu.pl 44 285 115 136 34 40%16:05:07
4localhost 5 4 4 0 0 100%00:00:00
TOTAL: 4 1074 26222192606387 11973 3859 99%337:38:44
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl806.6 GB27.6 MB53.8 MB806.6 GB 99%324.5 KB
2news.nask.pl17.6 MB1.8 MB1.8 MB21.2 MB 82%6.2 KB
3news.icm.edu.pl7.9 MB50.6 KB1.2 MB9.2 MB 86%63.1 KB
4localhost5.0 KB0.0 KB0.0 KB5.0 KB100%1.3 KB
TOTAL: 4806.6 GB29.5 MB56.9 MB806.7 GB 99%324.0 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Apr 10 04:15:02 - 04:59:59 70302 2.7% 26.0626.1 GB 3.2%10155.79
Apr 10 05:00:00 - 05:59:59 86726 3.3% 24.0932.1 GB 4.0%9363.63
Apr 10 06:00:00 - 06:59:59 93152 3.6% 25.8833.1 GB 4.1%9636.55
Apr 10 07:00:00 - 07:59:59 91637 3.5% 25.4533.0 GB 4.1%9623.26
Apr 10 08:00:00 - 08:59:59 85865 3.3% 23.8532.3 GB 4.0%9408.00
Apr 10 09:00:00 - 09:59:59 97681 3.7% 27.1334.9 GB 4.3%10176.85
Apr 10 10:00:00 - 10:59:59 98563 3.8% 27.3832.9 GB 4.1%9596.56
Apr 10 11:00:00 - 11:59:59 121408 4.7% 33.7235.3 GB 4.4%10290.35
Apr 10 12:00:00 - 12:59:59 116421 4.5% 32.3433.6 GB 4.2%9780.56
Apr 10 13:00:00 - 13:59:59 114461 4.4% 31.7933.0 GB 4.1%9621.90
Apr 10 14:00:00 - 14:59:59 123464 4.7% 34.3033.4 GB 4.1%9732.09
Apr 10 15:00:00 - 15:59:59 121968 4.7% 33.8834.6 GB 4.3%10080.40
Apr 10 16:00:00 - 16:59:59 106789 4.1% 29.6631.3 GB 3.9%9124.73
Apr 10 17:00:00 - 17:59:59 115985 4.4% 32.2232.8 GB 4.1%9554.00
Apr 10 18:00:00 - 18:59:59 105894 4.1% 29.4131.1 GB 3.9%9044.87
Apr 10 19:00:00 - 19:59:59 118634 4.6% 32.9532.4 GB 4.0%9442.39
Apr 10 20:00:00 - 20:59:59 126812 4.9% 35.2333.0 GB 4.1%9608.20
Apr 10 21:00:00 - 21:59:59 111742 4.3% 31.0433.6 GB 4.2%9778.81
Apr 10 22:00:00 - 22:59:59 116552 4.5% 32.3834.3 GB 4.3%9983.28
Apr 10 23:00:00 - 23:59:59 126990 4.9% 35.2735.6 GB 4.4%10364.82
Apr 11 00:00:00 - 00:59:59 96212 3.7% 26.7328.7 GB 3.6%8361.38
Apr 11 01:00:00 - 01:59:59 116257 4.5% 32.2936.7 GB 4.6%10686.26
Apr 11 02:00:00 - 02:59:59 121982 4.7% 33.8839.1 GB 4.9%11402.90
Apr 11 03:00:00 - 03:59:59 94978 3.6% 26.3833.5 GB 4.2%9757.17
Apr 11 04:00:00 - 04:15:02 26003 1.0% 28.839.0 GB 1.1%10497.39
TOTAL: 24:00:00 2606478 100.0% 30.17805.6 GB 100.0%9777.51
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 3950 264 0 2901 3 0 0 0 782
2news.nask.pl 499 7 0 441 2 0 0 0 49
3news.icm.edu.pl 36 0 0 2 0 0 0 0 34
TOTAL: 3 4485 271 0 3344 5 0 0 0 865

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 98
it.comp.os.win.windows10 66
it.tlc.cellulari.android 58
it.comp.os.win.windows7 12
comp.sys.raspberry-pi 11
de.rec.sf.startrek 8
uk.rec.cycling.moderated 6
comp.mobile.android 6
it.comp.os.win.windows8 2
alt.sex.telephones 1
nl.comp.os.linux.techniek 1
comp.sys.mac.vintage 1
nl.actueel 1
TOTAL: 13 271

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

GroupsCount
pl.sci.matematyka 3
bit.listserv.catholic 1
hr.alt.hackers 1
TOTAL: 3 5

INND Perl filter [Top 20]:

ReasonCount
EMP (fsl) 124
Binary in non-binary group 88
EMP (md5) 30
Scoring filter (8) 1
HTML post 1
TOTAL: 5 244

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1icm 46536 1910 24131 6753 0 0 4%23:59:20
2panorama 55745 530 27919 211 1 57630 0%23:59:50
3poznan 5128 89 3155 1 0 0 1%24:06:12
4nask 0 0 0 0 0 14042 0%24:00:01
5okapi 0 0 0 0 0 1931 0%24:00:01
TOTAL: 5 107409 2529 55205 6965 1 73603 2%120:05:24
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm199.5 MB1.6 GB1.8 GB22.0 KB/s218.9 KB23:59:20
2panorama2.4 MB1.4 MB3.8 MB0.0 KB/s5.3 KB23:59:50
3poznan722.3 KB2.9 KB725.2 KB0.0 KB/s8.1 KB24:06:12
4nask0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:01
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:01
TOTAL: 5202.6 MB1.6 GB1.8 GB4.4 KB/s200.2 KB120:05:24
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 4246.4 KB 16 0 000:00:33
2auriga.rz.izeto.pl 3 1414.4 KB 3 0 000:11:39
3isvcs01.man.lodz.pl 4 00.0 KB 0 4 000:00:01
TOTAL: 3 295 5660.8 KB 19 4 013:38:39

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 676 4246.4 KB 16 4 000:00:39
2*.rz.izeto.pl 3 1414.4 KB 3 0 000:11:39
3unresolved 7 00.0 KB 0 0 013:25:32
4*.aspadmin.net 2 00.0 KB 0 0 000:00:10
5*.elsnet.pl 2 00.0 KB 0 0 000:00:35
6*.toya.net.pl 18 00.0 KB 0 0 000:00:02
TOTAL: 6 708 5660.8 KB 19 4 013:38:39

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
89.38.41.34 21.781 31.393 0.00013:25:21
auriga.rz.izeto.pl 0.006 0.058 0.00000:11:39
host227.elsnet.pl 0.011 0.035 0.00000:00:35
web.man.lodz.pl 0.999 5.609 0.00000:00:33
103.239.103.231 0.025 0.095 0.00000:00:11
ubuntu14219146.aspadmin.net 0.004 0.043 0.00000:00:10
mon.man.lodz.pl 0.949 5.139 0.00000:00:03
staticline41435.toya.net.pl 0.090 0.313 0.00000:00:02
isvcs01.man.lodz.pl 0.020 0.107 0.00000:00:01
david.man.lodz.pl 0.327 1.771 0.00000:00:01
? 0.003 0.006 0.00000:00:00
TOTAL: 11 24.215 44.569 0.00013:38:39

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
david.man.lodz.pl 96
staticline41435.toya.net.pl 18
103.239.103.231 5
ubuntu14219146.aspadmin.net 2
89.38.41.34 2
host227.elsnet.pl 2
TOTAL: 7 413

NNRP gethostbyaddr failures [Top 20]:

SystemConn
? (can't getpeername) 1
TOTAL: 1 1

NNRP client timeouts [Top 20]:

SystemConnPeer
89.38.41.34 2 2
TOTAL: 1 2 2

Newsgroup request counts (by category):

CategoryCountPct
1pl 42 75.0%
2lodz 13 23.2%
3lodman 1 1.8%
TOTAL: 3 56100.0%

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

NewsgroupCount
1pl.comp.pecet 36
2lodz.test 13
3pl.comp.os.ms-windows.winnt 6
4lodman.test 1
TOTAL: 4 56