Daily Usenet report

Nov 16 04:15:02 -- Nov 17 04:15:02

Unknown entries from news log file:

First 16 / 16 lines (100.0%)

Nov 16 07:18:58 newsfeed overchan[26656]: input line too long, skipping
Nov 16 07:19:15 newsfeed overchan[26656]: input line too long, skipping
Nov 16 08:40:00 newsfeed nnrpd[3854]: SERVER perl filter not defined
Nov 16 11:48:27 newsfeed nnrpd[10271]: SERVER perl filter not defined
Nov 16 12:10:09 newsfeed nnrpd[11012]: SERVER perl filter not defined
Nov 16 13:38:34 newsfeed nnrpd[14142]: SERVER perl filter not defined
Nov 16 15:16:37 newsfeed overchan[1503]: input line too long, skipping
Nov 16 15:19:55 newsfeed overchan[1503]: input line too long, skipping
Nov 16 15:25:27 newsfeed nnrpd[12478]: SERVER perl filter not defined
Nov 16 15:28:38 newsfeed nnrpd[12524]: SERVER perl filter not defined
Nov 16 15:32:18 newsfeed nnrpd[12757]: SERVER perl filter not defined
Nov 16 16:21:18 newsfeed overchan[1503]: input line too long, skipping
Nov 16 17:31:12 newsfeed nnrpd[16713]: SERVER perl filter not defined
Nov 16 17:35:33 newsfeed nnrpd[16806]: SERVER perl filter not defined
Nov 16 20:51:13 newsfeed overchan[1503]: input line too long, skipping
Nov 17 01:51:19 newsfeed overchan[1503]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn2850707 97.7%329.8 MB 97.4%
innfeed 43834 1.5%5.2 MB 1.5%
innd 18133 0.6%3.0 MB 0.9%
nnrpd 4904 0.2%519.3 KB 0.1%
overchan 8 0.0%1.5 KB 0.0%
controlchan 2 0.0%0.1 KB 0.0%
TOTAL: 62917588 100.0%338.6 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2813402 96.4%
Negative hits 58987 2.0%
Positive hits 46057 1.6%
Cache misses 962 0.0%
TOTAL: 4 2919408 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:05.067 0.0%68 0.000 74.515 2142.000
article cleanup00:00:34.495 0.0%2834518 0.008 0.012 0.017
article logging00:02:00.116 0.1%2835080 0.027 0.042 0.063
article parse00:51:19.422 3.6%90703926 0.021 0.034 0.047
article write00:21:35.031 1.5%2822143 0.220 0.459 0.715
artlog/artcncl00:00:00.001 0.0%68 0.000 0.015 1.000
artlog/artparse00:00:00.000 0.0%1 0.000 0.000 0.000
data move00:03:37.518 0.3%106195043 0.001 0.002 0.005
hisgrep/artcncl00:00:00.580 0.0%67 0.000 8.657 194.000
hishave/artcncl00:00:01.743 0.0%68 0.000 25.632 1560.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup02:59:52.580 12.6%2930103 0.201 3.683 8.210
history sync00:00:00.016 0.0%287 0.000 0.056 1.000
history write04:49:35.790 20.2%2823552 1.179 6.154 11.472
hiswrite/artcncl00:00:00.030 0.0%1 30.000 30.000 30.000
idle08:24:27.506 35.3%52320199 0.132 0.579 1.119
nntp read01:05:04.086 4.5%88370086 0.030 0.044 0.057
overview write00:00:14.376 0.0%2822143 0.003 0.005 0.010
perl filter04:45:34.792 20.0%2823537 3.974 6.069 8.018
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:05:46.166 0.4%5717983 0.040 0.061 0.077
TOTAL: 23:50:07.74323:29:49.315 98.6%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.459 0.0%161120 0.000 0.003 0.011
article prepare00:00:00.021 0.0%75066 0.000 0.000 0.010
article read00:00:35.171 0.0%18401 0.153 1.911 32.455
backlog stats00:00:00.407 0.0%501122 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:05.659 0.0%284216 0.006 0.020 0.036
data write00:00:34.381 0.0%235030 0.020 0.146 0.397
idle23:51:32.484 99.9%501122 32.668 171.400 922.822
status file00:00:28.049 0.0%16502 0.750 1.700 10.250
TOTAL: 23:53:38.57823:53:16.631100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:19.215 48.8%3070 0.000 6.259 171.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.712 1.8%6690 0.000 0.106 5.263
readart00:00:00.301 0.8%12 0.000 25.083 150.500
TOTAL: 00:00:39.38100:00:20.228 51.4%----

Control commands to INND:

CommandNumber
L 1
flush 27
flushlogs 2
go 2
logmode 2
mode 150
name 3
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 195

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 485 28352142830504 720 3990 99%519:48:53
2news.nask.pl 9 49000 5579 35096 8325 11%23:13:32
3news.icm.edu.pl 45 190 43 120 27 22%15:06:31
4localhost 10 9 9 0 0 100%00:00:03
TOTAL: 4 549 28844132836135 35936 12342 98%558:08:59
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl853.5 GB480.4 MB485.2 MB854.4 GB 99%316.1 KB
2news.nask.pl818.8 MB1.7 GB1.7 GB4.2 GB 19%313.5 KB
3news.icm.edu.pl792.1 KB94.9 KB559.7 KB1.4 MB 54%20.7 KB
4localhost17.8 KB0.0 KB0.0 KB17.8 KB100%2.0 KB
TOTAL: 4854.3 GB2.1 GB2.2 GB858.6 GB 99%316.1 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Nov 16 04:15:02 - 04:59:59 90553 3.2% 33.5628.9 GB 3.4%11226.86
Nov 16 05:00:00 - 05:59:59 101243 3.6% 28.1238.4 GB 4.5%11184.91
Nov 16 06:00:00 - 06:59:59 107458 3.8% 29.8537.8 GB 4.4%11002.60
Nov 16 07:00:00 - 07:59:59 126443 4.5% 35.1237.4 GB 4.4%10896.99
Nov 16 08:00:00 - 08:59:59 124248 4.4% 34.5139.0 GB 4.6%11371.77
Nov 16 09:00:00 - 09:59:59 108544 3.8% 30.1538.6 GB 4.5%11246.26
Nov 16 10:00:00 - 10:59:59 127785 4.5% 35.5038.6 GB 4.5%11240.90
Nov 16 11:00:00 - 11:59:59 143710 5.1% 39.9239.9 GB 4.7%11623.31
Nov 16 12:00:00 - 12:59:59 142173 5.0% 39.4936.9 GB 4.3%10741.35
Nov 16 13:00:00 - 13:59:59 143902 5.1% 39.9734.3 GB 4.0%9986.27
Nov 16 14:00:00 - 14:59:59 100217 3.5% 27.8427.9 GB 3.3%8118.35
Nov 16 15:00:00 - 15:59:59 149933 5.3% 41.6537.4 GB 4.4%10906.83
Nov 16 16:00:00 - 16:59:59 119141 4.2% 33.0935.3 GB 4.1%10276.80
Nov 16 17:00:00 - 17:59:59 88094 3.1% 24.4728.2 GB 3.3%8227.85
Nov 16 18:00:00 - 18:59:59 80816 2.8% 22.4527.0 GB 3.2%7878.62
Nov 16 19:00:00 - 19:59:59 139103 4.9% 38.6431.3 GB 3.7%9113.47
Nov 16 20:00:00 - 20:59:59 141297 5.0% 39.2541.9 GB 4.9%12207.99
Nov 16 21:00:00 - 21:59:59 119475 4.2% 33.1941.2 GB 4.8%11991.16
Nov 16 22:00:00 - 22:59:59 140310 4.9% 38.9841.0 GB 4.8%11930.79
Nov 16 23:00:00 - 23:59:59 120789 4.3% 33.5541.0 GB 4.8%11935.89
Nov 17 00:00:00 - 00:59:59 110065 3.9% 30.5731.0 GB 3.6%9035.30
Nov 17 01:00:00 - 01:59:59 111144 3.9% 30.8733.2 GB 3.9%9670.50
Nov 17 02:00:00 - 02:59:59 88764 3.1% 24.6630.8 GB 3.6%8965.31
Nov 17 03:00:00 - 03:59:59 88086 3.1% 24.4729.8 GB 3.5%8666.56
Nov 17 04:00:00 - 04:15:02 24366 0.9% 27.017.8 GB 0.9%9058.48
TOTAL: 24:00:00 2837659 100.0% 32.84854.5 GB 100.0%10370.97
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nask.pl 8467 5 0 7282 2 0 0 0 1178
2newsfeed.pionier.net.pl 4485 181 0 3716 1 0 0 0 587
3news.icm.edu.pl 28 0 0 8 0 0 0 0 20
TOTAL: 3 12980 186 0 11006 3 0 0 0 1785

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 61
it.comp.os.win.windows10 48
it.tlc.cellulari.android 33
comp.mobile.android 20
it.comp.os.win.windows7 16
comp.sys.raspberry-pi 2
uk.rec.cycling.moderated 2
de.comp.lang.php 2
de.comp.lang.perl 1
comp.mobile.ipad 1
TOTAL: 10 186

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

GroupsCount
pl.praca.oferowana 2
bit.listserv.catholic 1
TOTAL: 2 3

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 856
Binary in non-binary group 213
Scoring filter (16) 59
EMP (phl) 19
Scoring filter (8) 18
HTML post 9
Too many newsgroups 8
Scoring filter (10) 7
Scoring filter (9) 7
Scoring filter (18) 4
Scoring filter (11) 2
Scoring filter (17) 2
Scoring filter (12) 1
TOTAL: 13 1205

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Article posted in the future
newsfeed.pionier.net.pl 14
TOTAL: 1 14
Bad command received
news.nask.pl 58
TOTAL: 1 58
Including strange strings
newsfeed.pionier.net.pl 494
news.nask.pl 69
news.icm.edu.pl 1
TOTAL: 3 564
TOTAL: 3 636

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nask 76564 10861 45035 772 0 512 14%23:56:47
2icm 78007 2804 67762 2516 0 0 3%23:56:47
3panorama 97961 858 69503 330 1 90033 0%23:58:45
4poznan 4584 145 3231 32 0 0 3%23:56:47
5okapi 0 0 0 0 0 1041 0%23:54:41
TOTAL: 5 257116 14668 185531 3650 1 91586 5%119:43:47
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nask3.7 GB266.5 MB3.9 GB47.7 KB/s353.6 KB23:56:47
2icm1.9 GB878.4 MB2.7 GB33.2 KB/s538.1 KB23:56:47
3panorama2.1 MB2.4 MB4.6 MB0.1 KB/s3.9 KB23:58:45
4poznan375.4 KB80.6 KB456.0 KB0.0 KB/s2.6 KB23:56:47
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:54:41
TOTAL: 55.5 GB1.1 GB6.7 GB16.2 KB/s381.1 KB119:43:47
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 1210.9 KB 6 0 000:00:26
2isvcs01.man.lodz.pl 9 00.0 KB 0 9 000:00:03
TOTAL: 2 297 1210.9 KB 6 9 000:00:38

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 585 1210.9 KB 6 9 000:00:34
2*.census.shodan.io 1 00.0 KB 0 0 000:00:00
3*.security.ipip.net 2 00.0 KB 0 0 000:00:01
4unresolved 96 00.0 KB 0 0 000:00:01
TOTAL: 4 684 1210.9 KB 6 9 000:00:38

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
web.man.lodz.pl 1.532 5.560 0.00000:00:26
mon.man.lodz.pl 1.564 6.196 0.00000:00:04
isvcs01.man.lodz.pl 0.068 0.140 0.00000:00:03
scan-141.security.ipip.net 0.012 0.032 0.00000:00:01
212.51.192.185 0.436 1.496 0.00000:00:01
pirate.census.shodan.io 0.008 0.016 0.00000:00:00
TOTAL: 6 3.620 13.440 0.00000:00:38

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
212.51.192.185 96
scan-141.security.ipip.net 2
pirate.census.shodan.io 1
TOTAL: 4 387

NNRP unrecognized commands (by host):

SystemConn
scan-141.security.ipip.net 8
TOTAL: 1 8

NNRP unrecognized commands (by command):

CommandCount
GET / HTTP/1.1 2
User-Agent: HTTP Banner Detection (https... 2
Connection: close 2
Host: 212.191.2.21:119 2
TOTAL: 4 8

Newsgroup request counts (by category):

CategoryCountPct
1pl 12100.0%
TOTAL: 1 12100.0%

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

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