Daily Usenet report

Dec 5 04:15:01 -- Dec 6 04:15:01

Unknown entries from news log file:

First 14 / 14 lines (100.0%)

Dec 5 09:43:33 newsfeed nnrpd[1633933]: SERVER perl filter not defined
Dec 5 09:43:33 newsfeed nnrpd[1633933]: SERVER perl filter not defined
Dec 5 09:47:48 newsfeed nnrpd[1634425]: SERVER perl filter not defined
Dec 5 09:47:48 newsfeed nnrpd[1634425]: SERVER perl filter not defined
Dec 5 11:55:04 newsfeed nnrpd[1649696]: SERVER perl filter not defined
Dec 5 11:55:04 newsfeed nnrpd[1649696]: SERVER perl filter not defined
Dec 5 11:58:26 newsfeed nnrpd[1650114]: SERVER perl filter not defined
Dec 5 11:58:26 newsfeed nnrpd[1650114]: SERVER perl filter not defined
Dec 5 12:00:49 newsfeed nnrpd[1650477]: SERVER perl filter not defined
Dec 5 12:00:49 newsfeed nnrpd[1650477]: SERVER perl filter not defined
Dec 6 03:31:25 newsfeed nnrpd[1762729]: bad ownership or permissions on private key '/etc/news/key.pem': private key must be a regular file, readable by nnrpd, and not world-readable
Dec 6 03:31:25 newsfeed nnrpd[1762729]: bad ownership or permissions on private key '/etc/news/key.pem': private key must be a regular file, readable by nnrpd, and not world-readable
Dec 6 03:31:25 newsfeed nnrpd[1762729]: error initializing TLS: [CA_file: ] [CA_path: /etc/news] [cert_file: /etc/news/cert.pem] [key_file: /etc/news/key.pem]
Dec 6 03:31:25 newsfeed nnrpd[1762729]: error initializing TLS: [CA_file: ] [CA_path: /etc/news] [cert_file: /etc/news/cert.pem] [key_file: /etc/news/key.pem]

Log entries by program:

Program nameLines%LinesSize%Size
inn 147334 78.7%16.4 MB 81.5%
innd 22734 12.1%2.1 MB 10.5%
nnrpd 15900 8.5%1.5 MB 7.3%
innfeed 1282 0.7%151.9 KB 0.7%
overchan 4 0.0%0.3 KB 0.0%
controlchan 4 0.0%0.2 KB 0.0%
TOTAL: 6 187258 100.0%20.1 MB100.0%

History cache:

ReasonCount%Count
Do not exist 292322 99.5%
Negative hits 1388 0.5%
Positive hits 110 0.0%
Cache misses 12 0.0%
TOTAL: 4 293832 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.020 0.0%58 0.000 0.345 2.000
article cleanup00:00:00.810 0.0%293810 0.000 0.003 0.009
article logging00:00:25.902 0.0%293850 0.003 0.088 13.999
article parse00:00:05.876 0.0%1020510 0.003 0.006 0.012
article write00:00:11.638 0.0%293810 0.017 0.040 0.197
artlog/artcncl00:00:00.000 0.0%18 0.000 0.000 0.000
data move00:00:00.866 0.0%1482038 0.000 0.001 0.004
hisgrep/artcncl00:00:00.004 0.0%76 0.000 0.053 1.000
hishave/artcncl00:00:00.000 0.0%18 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:01.104 0.0%295526 0.000 0.004 0.013
history sync00:00:00.036 0.0%784 0.000 0.046 0.500
history write00:00:18.454 0.0%293810 0.033 0.063 0.151
idle47:34:54.828 99.7%1255110 61.316 136.478 1790.916
nntp read00:00:08.604 0.0%986692 0.004 0.009 0.016
overview write00:00:00.472 0.0%293810 0.000 0.002 0.006
perl filter00:02:29.032 0.1%293810 0.362 0.507 0.701
python filter00:00:00.066 0.0%293820 0.000 0.000 0.002
site send00:00:04.106 0.0%606080 0.002 0.007 0.013
TOTAL: 47:42:10.17647:38:41.818 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.036 0.0%18420 0.000 0.002 0.050
article prepare00:00:00.000 0.0%1280 0.000 0.000 0.000
article read00:00:00.006 0.0%320 0.000 0.019 1.000
backlog stats00:00:00.024 0.0%104234 0.000 0.000 0.006
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.400 0.0%51818 0.000 0.008 0.043
data write00:00:00.878 0.0%32512 0.000 0.027 0.071
idle47:35:57.314100.0%104234 955.578 1643.968 3846.712
status file00:00:00.146 0.0%340 0.000 0.429 4.000
TOTAL: 47:35:59.69447:35:58.804100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:04:21.098 89.2%8898 0.000 29.343 10000.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.354 0.1%29940 0.000 0.012 0.500
readart00:00:00.000 0.0%12 0.000 0.000 0.000
TOTAL: 00:04:52.55200:04:21.452 89.4%----

Control commands to INND:

CommandNumber
flush 54
flushlogs 4
go 4
logmode 4
lowmark 2
mode 298
name 4
pause 4
paused 4
reload 4
reserve 4
shutdown 6
TOTAL: 12 392

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 108 294600 294600 0 0 100%66:29:46
2localhost 12 10 10 0 0 100%00:00:00
3news.icm.edu.pl 60 104 0 104 0 0%13:16:46
TOTAL: 3 180 294714 294610 104 0 99%79:46:32
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl3.0 GB0.0 KB0.0 KB3.0 GB100%10.5 KB
2localhost13.3 KB0.0 KB0.0 KB13.3 KB100%1.3 KB
3news.icm.edu.pl0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 33.0 GB0.0 KB0.0 KB3.0 GB100%10.5 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Dec 5 04:15:01 - 04:59:59 3277 2.2% 1.2136.5 MB 2.4% 13.84
Dec 5 05:00:00 - 05:59:59 5257 3.6% 1.4657.6 MB 3.8% 16.39
Dec 5 06:00:00 - 06:59:59 4334 2.9% 1.2045.5 MB 3.0% 12.94
Dec 5 07:00:00 - 07:59:59 3795 2.6% 1.0534.1 MB 2.3% 9.69
Dec 5 08:00:00 - 08:59:59 5356 3.6% 1.4950.3 MB 3.3% 14.30
Dec 5 09:00:00 - 09:59:59 5667 3.8% 1.5756.2 MB 3.7% 15.99
Dec 5 10:00:00 - 10:59:59 5992 4.1% 1.6664.1 MB 4.2% 18.23
Dec 5 11:00:00 - 11:59:59 5188 3.5% 1.4453.5 MB 3.5% 15.22
Dec 5 12:00:00 - 12:59:59 5637 3.8% 1.5769.5 MB 4.6% 19.78
Dec 5 13:00:00 - 13:59:59 7216 4.9% 2.0084.8 MB 5.6% 24.13
Dec 5 14:00:00 - 14:59:59 5184 3.5% 1.4450.7 MB 3.4% 14.41
Dec 5 15:00:00 - 15:59:59 11371 7.7% 3.16101.2 MB 6.7% 28.78
Dec 5 16:00:00 - 16:59:59 12417 8.4% 3.45113.5 MB 7.5% 32.30
Dec 5 17:00:00 - 17:59:59 11795 8.0% 3.28109.2 MB 7.2% 31.06
Dec 5 18:00:00 - 18:59:59 9252 6.3% 2.5783.7 MB 5.5% 23.81
Dec 5 19:00:00 - 19:59:59 6228 4.2% 1.7366.6 MB 4.4% 18.94
Dec 5 20:00:00 - 20:59:59 6028 4.1% 1.6769.3 MB 4.6% 19.71
Dec 5 21:00:00 - 21:59:59 4758 3.2% 1.3259.3 MB 3.9% 16.87
Dec 5 22:00:00 - 22:59:59 4064 2.8% 1.1349.9 MB 3.3% 14.20
Dec 5 23:00:00 - 23:59:59 5848 4.0% 1.6262.6 MB 4.1% 17.82
Dec 6 00:00:00 - 00:59:59 3804 2.6% 1.0641.7 MB 2.8% 11.87
Dec 6 01:00:00 - 01:59:59 4494 3.1% 1.2550.5 MB 3.3% 14.36
Dec 6 02:00:00 - 02:59:59 5729 3.9% 1.5952.5 MB 3.5% 14.93
Dec 6 03:00:00 - 03:59:59 3807 2.6% 1.0639.3 MB 2.6% 11.18
Dec 6 04:00:00 - 04:15:01 807 0.5% 0.909.3 MB 0.6% 10.55
TOTAL: 24:00:00 147305 100.0% 1.701.5 GB 100.0% 17.91
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 20 0 0 0 0 0 0 0 20
TOTAL: 1 20 0 0 0 0 0 0 0 20

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1icm 35054 314 18252 8 0 2 0%47:59:40
2poznan 0 0 0 0 0 0 0%00:40:00
TOTAL: 2 35054 314 18252 8 0 2 0%48:39:40
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm465.4 KB18.4 KB483.8 KB0.0 KB/s1.5 KB47:59:40
2poznan0.0 KB0.0 KB0.0 KB0.0 KB/s000:40:00
TOTAL: 2465.4 KB18.4 KB483.8 KB0.0 KB/s1.5 KB48:39:40
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1172.16.2.4 576 1227.3 KB 6 0 000:00:33
2isvcs01.man.lodz.pl 10 00.0 KB 0 10 000:00:00
TOTAL: 2 586 1227.3 KB 6 10 000:04:53

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 614 1227.3 KB 6 0 000:04:08
2*.stretchoid.com 4 00.0 KB 0 0 000:00:20
3*.man.lodz.pl2890 00.0 KB 0 10 000:00:23
4*.scanner.modat.io 4 00.0 KB 0 0 000:00:00
TOTAL: 43512 1227.3 KB 6 10 000:04:53

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
88.135.182.88 0.048 0.014 0.00000:02:55
172.16.2.4 1.206 2.830 0.00000:00:33
nms1.man.lodz.pl 5.922 12.938 0.00000:00:23
azpdsg3ry68v.stretchoid.com 0.004 0.020 0.00000:00:20
66.61.98.95 0.040 0.070 0.00000:00:14
205.210.31.185 0.008 0.006 0.00000:00:08
80.111.142.213 0.016 0.042 0.00000:00:05
104.152.52.56 0.006 0.008 0.00000:00:03
206.168.34.211 0.066 0.006 0.00000:00:03
147.185.132.54 0.008 0.006 0.00000:00:02
177.70.19.245 0.004 0.010 0.00000:00:00
198.235.24.48 0.002 0.010 0.00000:00:00
isvcs01.man.lodz.pl 0.026 0.056 0.00000:00:00
o45.scanner.modat.io 0.002 0.010 0.00000:00:00
o49.scanner.modat.io 0.002 0.010 0.00000:00:00
TOTAL: 15 7.360 16.036 0.00000:04:53

Curious NNRP server explorers [Top 20]:

SystemConn
nms1.man.lodz.pl 2880
66.61.98.95 16
80.111.142.213 8
azpdsg3ry68v.stretchoid.com 4
206.168.34.211 2
104.152.52.56 2
o49.scanner.modat.io 2
198.235.24.48 2
177.70.19.245 2
205.210.31.185 2
o45.scanner.modat.io 2
147.185.132.54 2
88.135.182.88 2
TOTAL: 13 2926

NNRP unrecognized commands (by host) [Top 20]:

SystemConn
azpdsg3ry68v.stretchoid.com 2
206.168.34.211 2
TOTAL: 2 4

NNRP unrecognized commands (by command) [Top 20]:

CommandCount
EHLO www.censys.io 2
MGLNDD_212.191.2.21_119 2
TOTAL: 2 4

Newsgroup request counts (by hierarchy):

HierarchyCountPct
1pl 12100.0%
TOTAL: 1 12100.0%

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

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