Daily Usenet report

Sep 15 04:15:02 -- Sep 16 04:15:02

Unknown entries from news log file:

First 15 / 15 lines (100.0%)

Sep 15 09:24:06 newsfeed nnrpd[32607]: SERVER perl filter not defined
Sep 15 09:27:03 newsfeed nnrpd[734]: SERVER perl filter not defined
Sep 15 09:28:00 newsfeed nnrpd[851]: SERVER perl filter not defined
Sep 15 09:29:07 newsfeed nnrpd[965]: SERVER perl filter not defined
Sep 15 09:30:15 newsfeed nnrpd[1274]: SERVER perl filter not defined
Sep 15 12:45:24 newsfeed overchan[16744]: input line too long, skipping
Sep 15 13:55:58 newsfeed nnrpd[8599]: SERVER perl filter not defined
Sep 15 14:00:10 newsfeed nnrpd[9295]: SERVER perl filter not defined
Sep 15 14:07:21 newsfeed nnrpd[10390]: SERVER perl filter not defined
Sep 15 14:07:22 newsfeed nnrpd[10391]: SERVER perl filter not defined
Sep 15 16:32:23 newsfeed nnrpd[32040]: unable to get certificate from '/etc/news/cert.pem'
Sep 15 16:32:23 newsfeed nnrpd[32040]: error initializing TLS: [CA_file: ] [CA_path: /etc/news] [cert_file: /etc/news/cert.pem] [key_file: /etc/news/key.pem]
Sep 15 17:00:48 newsfeed pgpverify[4033]: pgpverify: verification failed
Sep 15 17:27:51 newsfeed nnrpd[8119]: SERVER perl filter not defined
Sep 15 17:43:01 newsfeed overchan[16744]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn 373960 91.4%41.1 MB 90.6%
innfeed 24142 5.9%2.9 MB 6.3%
innd 7451 1.8%1.1 MB 2.4%
nnrpd 3460 0.8%324.5 KB 0.7%
controlchan 12 0.0%2.5 KB 0.0%
overchan 3 0.0%0.4 KB 0.0%
pgpverify 1 0.0%0.2 KB 0.0%
TOTAL: 7 409029 100.0%45.4 MB100.0%

History cache:

ReasonCount%Count
Do not exist 373161 99.1%
Negative hits 3252 0.9%
Positive hits 276 0.1%
Cache misses 39 0.0%
TOTAL: 4 376728 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.627 0.0%74 0.000 21.986 141.000
article cleanup00:00:02.957 0.0%373519 0.004 0.008 0.013
article logging00:00:09.500 0.0%373892 0.016 0.025 0.039
article parse00:04:58.127 0.3%78127162 0.003 0.004 0.007
article write00:02:42.516 0.2%373290 0.241 0.435 1.986
artlog/artcncl00:00:00.000 0.0%74 0.000 0.000 0.000
data move00:00:24.577 0.0%81033583 0.000 0.000 0.000
hisgrep/artcncl00:00:00.535 0.0%71 0.000 7.535 141.000
hishave/artcncl00:00:00.083 0.0%74 0.000 1.122 5.889
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:34:15.829 2.4%376989 1.818 5.453 25.487
history sync00:00:00.005 0.0%291 0.000 0.017 0.500
history write00:47:13.520 3.3%373490 3.815 7.587 52.117
hiswrite/artcncl00:00:00.008 0.0%3 0.000 2.667 8.000
idle21:40:44.965 90.3%76443698 0.518 1.021 1.493
nntp read00:16:20.825 1.1%77858216 0.011 0.013 0.017
overview write00:00:01.167 0.0%373290 0.001 0.003 0.006
perl filter00:22:27.198 1.6%373490 2.654 3.607 4.244
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:10.004 0.0%759134 0.009 0.013 0.018
TOTAL: 24:00:02.61523:49:33.443 99.3%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.041 0.0%36942 0.000 0.001 0.010
article prepare00:00:00.001 0.0%2860 0.000 0.000 0.062
article read00:00:01.131 0.0%715 0.000 1.582 82.500
backlog stats00:00:00.046 0.0%88184 0.000 0.001 0.004
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.385 0.0%44488 0.000 0.009 0.067
data write00:00:00.919 0.0%32078 0.008 0.029 0.078
idle23:54:24.573100.0%88184 330.599 975.966 3379.972
status file00:00:28.919 0.0%7823 0.000 3.697 522.840
TOTAL: 23:54:58.29623:54:56.015100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:14.705 68.7%2840 0.000 5.178 1150.500
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.072 0.3%6042 0.000 0.012 0.500
readart00:00:00.001 0.0%26 0.000 0.038 0.500
TOTAL: 00:00:21.40900:00:14.778 69.0%----

Control commands to INND:

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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
group-admin@isc.org001101
TOTAL001101

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 98 373663 373460 0 203 99%67:12:32
2news.icm.edu.pl 47 282 39 217 26 13%17:16:17
3localhost 1449 10 10 0 0 100%00:00:01
TOTAL: 3 1594 373955 373509 217 229 99%84:28:50
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl170.9 GB4.0 MB4.5 MB170.9 GB 99%479.5 KB
2news.icm.edu.pl4.8 MB30.1 KB479.2 KB5.3 MB 90%83.2 KB
3localhost17.1 KB0.0 KB0.0 KB17.1 KB100%1.7 KB
TOTAL: 3170.9 GB4.1 MB5.0 MB170.9 GB 99%479.4 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 15 04:15:02 - 04:59:59 8955 2.4% 3.324.3 GB 2.5%1681.35
Sep 15 05:00:00 - 05:59:59 11721 3.1% 3.265.9 GB 3.4%1704.39
Sep 15 06:00:00 - 06:59:59 11512 3.1% 3.205.8 GB 3.4%1697.11
Sep 15 07:00:00 - 07:59:59 11830 3.2% 3.295.8 GB 3.4%1691.88
Sep 15 08:00:00 - 08:59:59 11607 3.1% 3.225.9 GB 3.4%1705.89
Sep 15 09:00:00 - 09:59:59 13026 3.5% 3.625.8 GB 3.4%1688.31
Sep 15 10:00:00 - 10:59:59 12641 3.4% 3.515.8 GB 3.4%1693.59
Sep 15 11:00:00 - 11:59:59 13392 3.6% 3.725.8 GB 3.4%1697.73
Sep 15 12:00:00 - 12:59:59 17652 4.7% 4.906.7 GB 3.9%1941.03
Sep 15 13:00:00 - 13:59:59 14480 3.9% 4.025.9 GB 3.4%1714.24
Sep 15 14:00:00 - 14:59:59 19469 5.2% 5.417.5 GB 4.4%2182.79
Sep 15 15:00:00 - 15:59:59 17564 4.7% 4.886.8 GB 4.0%1979.87
Sep 15 16:00:00 - 16:59:59 13141 3.5% 3.655.9 GB 3.5%1720.64
Sep 15 17:00:00 - 17:59:59 14878 4.0% 4.136.0 GB 3.5%1755.45
Sep 15 18:00:00 - 18:59:59 14257 3.8% 3.966.0 GB 3.5%1743.95
Sep 15 19:00:00 - 19:59:59 13745 3.7% 3.826.0 GB 3.5%1738.68
Sep 15 20:00:00 - 20:59:59 15864 4.2% 4.416.6 GB 3.8%1908.68
Sep 15 21:00:00 - 21:59:59 21378 5.7% 5.9410.9 GB 6.4%3175.24
Sep 15 22:00:00 - 22:59:59 22770 6.1% 6.3311.6 GB 6.8%3384.22
Sep 15 23:00:00 - 23:59:59 24135 6.5% 6.7011.9 GB 6.9%3458.81
Sep 16 00:00:00 - 00:59:59 20930 5.6% 5.8110.4 GB 6.1%3015.41
Sep 16 01:00:00 - 01:59:59 13147 3.5% 3.656.0 GB 3.5%1760.07
Sep 16 02:00:00 - 02:59:59 12042 3.2% 3.355.6 GB 3.3%1635.61
Sep 16 03:00:00 - 03:59:59 18883 5.1% 5.259.7 GB 5.7%2824.52
Sep 16 04:00:00 - 04:15:02 4265 1.1% 4.732.4 GB 1.4%2804.73
TOTAL: 24:00:00 373284 100.0% 4.32170.9 GB 100.0%2074.21
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 576 172 0 26 5 0 0 0 373
2news.icm.edu.pl 26 0 0 3 0 0 0 0 23
TOTAL: 2 602 172 0 29 5 0 0 0 396

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 61
it.tlc.cellulari.android 42
it.comp.os.win.windows10 27
alt.comp.software.seamonkey 22
de.comm.software.mailreader 6
comp.mobile.ipad 4
it.comp.os.linux.ubuntu 4
comp.mobile.android 3
alt.comp.software.firefox 2
it.comp.os.win.windows7 1
TOTAL: 10 172

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

GroupsCount
pl.praca.oferowana 3
pl.sci.historia 2
TOTAL: 2 5

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 12
Binary in non-binary group 6
Scoring filter (8) 4
Scoring filter (10) 1
TOTAL: 4 23

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1icm 18038 626 11590 31 0 0 3%24:02:42
2panorama 25234 8 12515 34 0 24405 0%24:00:36
3poznan 41 0 32 0 0 0 0%04:09:08
4okapi 0 0 0 0 0 1101 0%23:57:48
5nask 0 0 0 0 0 8439 0%23:57:48
TOTAL: 5 43313 634 24137 65 0 33945 1%100:08:02
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm1.9 MB109.3 KB2.0 MB0.0 KB/s3.1 KB24:02:42
2panorama692.7 KB138.3 KB831.1 KB0.0 KB/s19.8 KB24:00:36
3poznan0.0 KB0.0 KB0.0 KB0.0 KB/s004:09:08
4okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:48
5nask0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:48
TOTAL: 52.5 MB247.6 KB2.8 MB0.0 KB/s4.1 KB100:08:02
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 2621.3 KB 13 0 000:00:10
2isvcs01.man.lodz.pl 10 00.0 KB 0 10 000:00:00
TOTAL: 2 298 2621.3 KB 13 10 000:00:21

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 298 2621.3 KB 13 10 000:00:11
2unresolved 108 00.0 KB 0 0 000:00:09
3*.ch1.censys-scanner.com 1 00.0 KB 0 0 000:00:00
4*.security.ipip.net 1 00.0 KB 0 0 000:00:00
TOTAL: 4 408 2621.3 KB 13 10 000:00:21

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
web.man.lodz.pl 0.893 1.460 0.00000:00:10
80.111.142.213 0.006 0.028 0.00000:00:02
167.172.78.211 0.015 0.032 0.00000:00:02
170.106.115.15 0.008 0.000 0.00000:00:02
212.51.192.185 0.318 0.400 0.00000:00:01
isvcs01.man.lodz.pl 0.037 0.067 0.00000:00:00
scan-141.security.ipip.net 0.000 0.007 0.00000:00:00
scanner-27.ch1.censys-scanner.com 0.009 0.000 0.00000:00:00
TOTAL: 8 1.286 1.994 0.00000:00:21

Curious NNRP server explorers [Top 20]:

SystemConn
212.51.192.185 96
167.172.78.211 7
80.111.142.213 4
scan-141.security.ipip.net 1
scanner-27.ch1.censys-scanner.com 1
170.106.115.15 1
TOTAL: 6 110

NNRP unrecognized commands (by host):

SystemConn
167.172.78.211 36
scan-141.security.ipip.net 4
scanner-27.ch1.censys-scanner.com 1
170.106.115.15 1
TOTAL: 4 42

NNRP unrecognized commands (by command):

CommandCount
Host: 212.191.2.21:119 7
Accept-Encoding: gzip, deflate 6
Accept: */* 6
Connection: keep-alive 6
User-Agent: Mozilla/5.0 (Windows NT 10.0... 5
GET /system_api.php HTTP/1.1 1
Connection: close 1
GET /stalker_portal/c/version.js HTTP/1.... 1
GET /c/version.js HTTP/1.1 1
GET /stream/live.php HTTP/1.1 1
User-Agent: AlexaMediaPlayer/2.1.4676.0 ... 1
EHLO www.censys.io 1
GET /streaming/clients_live.php HTTP/1.1 1
GET / HTTP/1.1 1
GET /flu/403.html HTTP/1.1 1
User-Agent: HTTP Banner Detection (https... 1
#026#003#001 1
TOTAL: 17 42

NNRP client timeouts [Top 20]:

SystemConnPeer
170.106.115.15 1 1
TOTAL: 1 1 1

Newsgroup request counts (by category):

CategoryCountPct
1pl 26100.0%
TOTAL: 1 26100.0%

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

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