Daily Usenet report

May 26 04:15:01 -- May 27 04:15:02

Unknown entries from news log file:

First 23 / 23 lines (100.0%)

May 26 06:19:53 newsfeed overchan[2680]: input line too long, skipping
May 26 06:40:26 newsfeed overchan[2680]: input line too long, skipping
May 26 07:18:53 newsfeed overchan[2680]: input line too long, skipping
May 26 07:29:10 newsfeed overchan[2680]: input line too long, skipping
May 26 07:35:12 newsfeed overchan[2680]: input line too long, skipping
May 26 07:51:01 newsfeed overchan[2680]: input line too long, skipping
May 26 08:02:24 newsfeed overchan[2680]: input line too long, skipping
May 26 09:30:20 newsfeed nnrpd[19805]: SERVER perl filter not defined
May 26 09:30:55 newsfeed nnrpd[19845]: SERVER perl filter not defined
May 26 10:41:43 newsfeed nnrpd[30869]: SERVER perl filter not defined
May 26 13:16:01 newsfeed nnrpd[22368]: SERVER perl filter not defined
May 26 14:44:36 newsfeed nnrpd[3640]: SERVER perl filter not defined
May 26 14:48:44 newsfeed nnrpd[4353]: SERVER perl filter not defined
May 26 15:25:24 newsfeed nnrpd[10082]: unable to get certificate from '/etc/news/cert.pem'
May 26 15:25:24 newsfeed nnrpd[10082]: error initializing TLS: [CA_file: ] [CA_path: /etc/news] [cert_file: /etc/news/cert.pem] [key_file: /etc/news/key.pem]
May 26 16:32:44 newsfeed overchan[2680]: input line too long, skipping
May 26 17:17:26 newsfeed overchan[2680]: input line too long, skipping
May 26 17:35:28 newsfeed overchan[2680]: input line too long, skipping
May 26 19:59:46 newsfeed overchan[2680]: input line too long, skipping
May 26 20:46:23 newsfeed overchan[2680]: input line too long, skipping
May 26 23:20:02 newsfeed overchan[2680]: input line too long, skipping
May 26 23:51:14 newsfeed overchan[2680]: input line too long, skipping
May 27 03:33:48 newsfeed overchan[2680]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn 728449 92.1%78.9 MB 91.2%
innfeed 48598 6.1%5.7 MB 6.6%
innd 9721 1.2%1.5 MB 1.7%
nnrpd 4209 0.5%428.8 KB 0.5%
overchan 16 0.0%2.8 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 6 790994 100.0%86.6 MB100.0%

History cache:

ReasonCount%Count
Do not exist 724583 99.1%
Negative hits 6042 0.8%
Positive hits 298 0.0%
Cache misses 60 0.0%
TOTAL: 4 730983 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:02.872 0.0%70 0.000 41.029 205.200
article cleanup00:00:11.627 0.0%727937 0.010 0.016 0.020
article logging00:00:40.463 0.0%728260 0.038 0.056 0.080
article parse00:21:39.628 1.5%43969989 0.022 0.030 0.040
article write00:23:12.591 1.6%727660 0.510 1.914 143.022
artlog/artcncl00:00:00.003 0.0%70 0.000 0.043 1.000
data move00:00:53.334 0.1%48712564 0.001 0.001 0.002
hisgrep/artcncl00:00:01.234 0.0%70 0.000 17.629 134.000
hishave/artcncl00:00:00.404 0.0%70 0.000 5.771 61.600
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:45:37.858 3.2%734267 0.402 3.729 15.590
history sync00:00:00.031 0.0%290 0.000 0.107 0.500
history write00:29:58.675 2.1%727869 0.656 2.471 12.766
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle19:40:34.575 82.0%35847367 1.414 1.976 4.508
nntp read00:35:42.163 2.5%43467503 0.039 0.049 0.065
overview write00:00:05.098 0.0%727660 0.005 0.007 0.011
perl filter01:25:25.539 5.9%727869 3.199 7.042 9.509
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:45.963 0.1%1468634 0.023 0.031 0.042
TOTAL: 24:00:04.78623:44:52.058 98.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.169 0.0%56309 0.000 0.003 0.013
article prepare00:00:00.001 0.0%5924 0.000 0.000 0.031
article read00:00:13.643 0.0%1478 0.000 9.231 153.333
backlog stats00:00:00.099 0.0%111121 0.000 0.001 0.005
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.194 0.0%55668 0.004 0.021 0.049
data write00:00:02.481 0.0%42522 0.027 0.058 0.096
idle23:53:50.008 99.9%111121 68.415 774.201 1476.333
status file00:01:00.334 0.1%16685 0.708 3.616 106.629
TOTAL: 23:55:14.94523:55:07.929100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:19.964 55.9%3061 0.000 6.522 153.667
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.498 1.4%6701 0.000 0.074 4.158
readart00:00:00.002 0.0%10 0.000 0.200 0.500
TOTAL: 00:00:35.68900:00:20.464 57.3%----

Control commands to INND:

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 227 728477 728218 1 258 99%138:54:43
2news.icm.edu.pl 44 290 53 219 18 18%15:22:37
3localhost 1447 6 6 0 0 100%00:00:04
TOTAL: 3 1718 728773 728277 220 276 99%154:17:24
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl283.2 GB2.9 MB3.4 MB283.2 GB 99%407.6 KB
2news.icm.edu.pl2.7 MB41.0 KB400.1 KB3.1 MB 86%45.3 KB
3localhost9.1 KB0.0 KB0.0 KB9.1 KB100%1.5 KB
TOTAL: 3283.2 GB3.0 MB3.8 MB283.2 GB 99%407.6 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 26 04:15:01 - 04:59:59 20110 2.8% 7.456.8 GB 2.4%2625.43
May 26 05:00:00 - 05:59:59 21949 3.0% 6.1011.0 GB 3.9%3210.16
May 26 06:00:00 - 06:59:59 24459 3.4% 6.7911.6 GB 4.1%3365.04
May 26 07:00:00 - 07:59:59 25069 3.4% 6.9611.6 GB 4.1%3365.15
May 26 08:00:00 - 08:59:59 29800 4.1% 8.2811.2 GB 3.9%3252.69
May 26 09:00:00 - 09:59:59 37247 5.1% 10.3511.6 GB 4.1%3388.51
May 26 10:00:00 - 10:59:59 28480 3.9% 7.9111.9 GB 4.2%3455.20
May 26 11:00:00 - 11:59:59 27062 3.7% 7.5211.8 GB 4.2%3437.30
May 26 12:00:00 - 12:59:59 29660 4.1% 8.2412.3 GB 4.4%3595.11
May 26 13:00:00 - 13:59:59 28015 3.8% 7.7813.1 GB 4.6%3813.76
May 26 14:00:00 - 14:59:59 31643 4.3% 8.7911.9 GB 4.2%3480.44
May 26 15:00:00 - 15:59:59 21686 3.0% 6.027.9 GB 2.8%2305.46
May 26 16:00:00 - 16:59:59 21007 2.9% 5.846.1 GB 2.2%1790.33
May 26 17:00:00 - 17:59:59 33077 4.5% 9.1910.9 GB 3.8%3165.02
May 26 18:00:00 - 18:59:59 39747 5.5% 11.0412.1 GB 4.3%3517.67
May 26 19:00:00 - 19:59:59 33569 4.6% 9.3212.2 GB 4.3%3540.72
May 26 20:00:00 - 20:59:59 32993 4.5% 9.1611.5 GB 4.1%3362.43
May 26 21:00:00 - 21:59:59 40949 5.6% 11.3714.6 GB 5.2%4257.28
May 26 22:00:00 - 22:59:59 39030 5.4% 10.8414.6 GB 5.1%4241.16
May 26 23:00:00 - 23:59:59 36550 5.0% 10.1513.3 GB 4.7%3872.24
May 27 00:00:00 - 00:59:59 34971 4.8% 9.7113.4 GB 4.7%3892.77
May 27 01:00:00 - 01:59:59 32895 4.5% 9.1412.7 GB 4.5%3705.25
May 27 02:00:00 - 02:59:59 29287 4.0% 8.1413.7 GB 4.8%3990.09
May 27 03:00:00 - 03:59:59 22541 3.1% 6.2611.9 GB 4.2%3474.73
May 27 04:00:00 - 04:15:02 5985 0.8% 6.643.4 GB 1.2%3953.14
TOTAL: 24:00:01 727781 100.0% 8.42283.0 GB 100.0%3434.85
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 579 189 0 64 5 0 0 0 321
2news.icm.edu.pl 19 0 0 4 0 0 0 0 15
TOTAL: 2 598 189 0 68 5 0 0 0 336

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 113
comp.mobile.android 43
alt.comp.software.firefox 9
it.tlc.cellulari.android 8
it.comp.os.win.windows10 4
comp.sys.raspberry-pi 3
it.comp.os.win.windows7 3
de.comp.lang.vbclassic 3
alt.comp.software.seamonkey 2
de.comp.office-pakete.ms-office.excel 1
TOTAL: 10 189

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

GroupsCount
pl.praca.oferowana 5
TOTAL: 1 5

INND Perl filter [Top 20]:

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

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1panorama 45497 255 16909 779 0 38511 0%23:59:56
2icm 16266 117 12805 14 0 0 0%23:53:26
3poznan 39 0 29 0 0 0 0%03:53:56
4okapi 0 0 0 0 0 570 0%23:50:03
5nask 0 0 0 0 0 8369 0%23:50:03
TOTAL: 5 61802 372 29743 793 0 47450 0%99:27:24
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1panorama654.1 KB3.5 MB4.2 MB0.0 KB/s4.1 KB23:59:56
2icm344.9 KB59.0 KB403.9 KB0.0 KB/s3.1 KB23:53:26
3poznan0.0 KB0.0 KB0.0 KB0.0 KB/s003:53:56
4okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:03
5nask0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:03
TOTAL: 5999.0 KB3.6 MB4.6 MB0.0 KB/s4.0 KB99:27:24
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 1016.1 KB 5 0 000:00:25
2isvcs01.man.lodz.pl 6 00.0 KB 0 6 000:00:00
TOTAL: 2 294 1016.1 KB 5 6 000:00:35

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 582 1016.1 KB 5 6 000:00:30
2*.security.ipip.net 1 00.0 KB 0 0 000:00:00
3unresolved 100 00.0 KB 0 0 000:00:03
4*.ch1.censys-scanner.com 1 00.0 KB 0 0 000:00:00
TOTAL: 4 684 1016.1 KB 5 6 000:00:35

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
web.man.lodz.pl 2.210 5.482 0.00000:00:25
mon.man.lodz.pl 1.771 3.602 0.00000:00:03
80.111.142.213 0.016 0.048 0.00000:00:02
212.51.192.185 0.496 1.177 0.00000:00:01
isvcs01.man.lodz.pl 0.060 0.088 0.00000:00:00
scan-141.security.ipip.net 0.004 0.016 0.00000:00:00
scanner-05.ch1.censys-scanner.com 0.011 0.008 0.00000:00:00
TOTAL: 7 4.568 10.421 0.00000:00:35

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
212.51.192.185 96
80.111.142.213 4
scan-141.security.ipip.net 1
scanner-05.ch1.censys-scanner.com 1
TOTAL: 5 390

NNRP unrecognized commands (by host):

SystemConn
scan-141.security.ipip.net 4
scanner-05.ch1.censys-scanner.com 1
TOTAL: 2 5

NNRP unrecognized commands (by command):

CommandCount
GET / HTTP/1.1 1
Connection: close 1
Host: 212.191.2.21:119 1
EHLO www.censys.io 1
User-Agent: HTTP Banner Detection (https... 1
TOTAL: 5 5

Newsgroup request counts (by category):

CategoryCountPct
1pl 10100.0%
TOTAL: 1 10100.0%

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

NewsgroupCount
1pl.comp.pecet 8
2pl.comp.os.ms-windows.winnt 2
TOTAL: 2 10