Daily Usenet report

Apr 9 04:15:03 -- Apr 10 04:15:03

Unknown entries from news log file:

First 11 / 11 lines (100.0%)

Apr 9 09:01:13 newsfeed overchan[15702]: input line too long, skipping
Apr 9 11:50:14 newsfeed nnrpd[617]: SERVER perl filter not defined
Apr 9 15:43:06 newsfeed overchan[15702]: input line too long, skipping
Apr 9 16:00:31 newsfeed overchan[15702]: input line too long, skipping
Apr 9 16:04:18 newsfeed overchan[15702]: input line too long, skipping
Apr 9 16:13:53 newsfeed overchan[15702]: input line too long, skipping
Apr 9 16:23:43 newsfeed overchan[15702]: input line too long, skipping
Apr 9 16:45:17 newsfeed overchan[15702]: input line too long, skipping
Apr 9 19:42:35 newsfeed nnrpd[17806]: SERVER perl filter not defined
Apr 9 21:29:57 newsfeed overchan[15702]: input line too long, skipping
Apr 9 23:46:17 newsfeed nnrpd[3876]: SERVER perl filter not defined

Log entries by program:

Program nameLines%LinesSize%Size
inn3184170 98.1%350.4 MB 97.7%
innfeed 33577 1.0%3.9 MB 1.1%
innd 24204 0.7%3.9 MB 1.1%
nnrpd 5006 0.2%530.1 KB 0.1%
overchan 9 0.0%1.5 KB 0.0%
controlchan 3 0.0%0.2 KB 0.0%
TOTAL: 63246969 100.0%358.7 MB100.0%

History cache:

ReasonCount%Count
Do not exist 3151196 96.5%
Negative hits 101245 3.1%
Positive hits 11545 0.4%
Cache misses 124 0.0%
TOTAL: 4 3264110 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:03.788 0.0%239 0.000 15.849 486.000
article cleanup00:00:39.873 0.0%3158914 0.010 0.013 0.016
article logging00:02:24.284 0.2%3159474 0.032 0.046 0.134
article parse00:49:19.778 3.4%80361926 0.025 0.037 0.048
article write00:21:44.881 1.5%3154742 0.184 0.414 1.128
artlog/artcncl00:00:00.008 0.0%237 0.000 0.034 1.000
artlog/artparse00:00:00.000 0.0%1 0.000 0.000 0.000
data move00:02:26.397 0.2%95442979 0.001 0.002 0.004
hisgrep/artcncl00:00:00.792 0.0%229 0.000 3.459 17.852
hishave/artcncl00:00:00.120 0.0%239 0.000 0.502 5.143
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:43:43.427 3.1%3270342 0.083 0.802 5.309
history sync00:00:00.013 0.0%287 0.000 0.045 0.500
history write02:00:09.150 8.4%3157318 0.292 2.283 7.977
hiswrite/artcncl00:00:00.015 0.0%10 0.000 1.500 14.000
idle12:54:34.852 54.2%59012452 0.471 0.788 1.704
nntp read00:57:28.739 4.0%77884704 0.032 0.044 0.065
overview write00:00:17.582 0.0%3154742 0.004 0.006 0.007
perl filter05:21:08.384 22.5%3157317 4.163 6.103 7.553
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:06:09.402 0.4%6332426 0.044 0.058 0.075
TOTAL: 23:50:04.52423:20:11.485 97.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.264 0.0%133516 0.000 0.002 0.022
article prepare00:00:00.019 0.0%100880 0.000 0.000 0.250
article read00:02:22.914 0.2%25553 0.000 5.593 526.500
backlog stats00:00:00.226 0.0%289491 0.000 0.001 0.005
callbacks00:00:00.000 0.0%216 0.000 0.000 0.000
data read00:00:02.698 0.0%151608 0.004 0.018 0.057
data write00:00:11.546 0.0%132509 0.028 0.087 0.395
idle23:40:31.024 99.8%289491 6.230 294.417 937.059
status file00:00:11.813 0.0%12530 0.000 0.943 3.000
TOTAL: 23:43:38.44023:43:20.504100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle10:48:43.601 99.1%366203 0.000 106.290 10050.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:23.628 0.1%733010 0.000 0.032 0.667
readart00:00:00.002 0.0%40 0.000 0.050 0.500
TOTAL: 10:54:49.78510:49:07.231 99.1%----

Control commands to INND:

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 1324 31748013165332 7746 1723 99%438:31:30
2news.nask.pl 4 14308 9950 1909 2449 69%24:03:06
3news.icm.edu.pl 42 253 17 229 7 6%18:18:36
4localhost 4 3 3 0 0 100%00:00:00
TOTAL: 4 1374 31893653175302 9884 4179 99%480:53:12
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl706.9 GB17.7 MB40.3 MB707.0 GB 99%234.1 KB
2news.nask.pl35.6 MB318.7 KB23.8 MB59.6 MB 59%4.9 KB
3news.icm.edu.pl306.1 KB0.0 KB242.1 KB548.2 KB 55%22.8 KB
4localhost3.9 KB0.0 KB0.0 KB3.9 KB100%1.3 KB
TOTAL: 4706.9 GB18.0 MB64.3 MB707.0 GB 99%233.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Apr 9 04:15:03 - 04:59:59 83109 2.6% 30.8220.3 GB 2.9%7880.68
Apr 9 05:00:00 - 05:59:59 133040 4.2% 36.9632.0 GB 4.5%9307.97
Apr 9 06:00:00 - 06:59:59 129766 4.1% 36.0533.1 GB 4.7%9648.85
Apr 9 07:00:00 - 07:59:59 124053 3.9% 34.4635.4 GB 5.0%10302.89
Apr 9 08:00:00 - 08:59:59 151722 4.8% 42.1530.6 GB 4.3%8912.75
Apr 9 09:00:00 - 09:59:59 153524 4.8% 42.6532.1 GB 4.5%9345.08
Apr 9 10:00:00 - 10:59:59 129931 4.1% 36.0927.0 GB 3.8%7856.21
Apr 9 11:00:00 - 11:59:59 127339 4.0% 35.3724.7 GB 3.5%7189.81
Apr 9 12:00:00 - 12:59:59 152562 4.8% 42.3832.8 GB 4.6%9544.93
Apr 9 13:00:00 - 13:59:59 153006 4.8% 42.5032.1 GB 4.5%9338.80
Apr 9 14:00:00 - 14:59:59 103020 3.2% 28.6225.7 GB 3.6%7497.20
Apr 9 15:00:00 - 15:59:59 123472 3.9% 34.3028.1 GB 4.0%8189.23
Apr 9 16:00:00 - 16:59:59 115578 3.6% 32.1027.3 GB 3.9%7945.52
Apr 9 17:00:00 - 17:59:59 122876 3.9% 34.1329.9 GB 4.2%8713.66
Apr 9 18:00:00 - 18:59:59 107967 3.4% 29.9925.4 GB 3.6%7410.43
Apr 9 19:00:00 - 19:59:59 134021 4.2% 37.2328.7 GB 4.1%8371.66
Apr 9 20:00:00 - 20:59:59 157618 5.0% 43.7833.9 GB 4.8%9862.08
Apr 9 21:00:00 - 21:59:59 159314 5.0% 44.2534.1 GB 4.8%9936.51
Apr 9 22:00:00 - 22:59:59 150912 4.7% 41.9233.0 GB 4.7%9623.55
Apr 9 23:00:00 - 23:59:59 164197 5.2% 45.6133.7 GB 4.8%9807.64
Apr 10 00:00:00 - 00:59:59 98891 3.1% 27.4718.8 GB 2.7%5488.57
Apr 10 01:00:00 - 01:59:59 124784 3.9% 34.6627.0 GB 3.8%7861.13
Apr 10 02:00:00 - 02:59:59 146219 4.6% 40.6229.8 GB 4.2%8689.17
Apr 10 03:00:00 - 03:59:59 106609 3.4% 29.6125.9 GB 3.7%7546.87
Apr 10 04:00:00 - 04:15:03 25609 0.8% 28.366.1 GB 0.9%7047.14
TOTAL: 24:00:00 3179139 100.0% 36.80707.5 GB 100.0%8585.92
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nask.pl 2652 30 0 134 2 0 0 0 2486
2newsfeed.pionier.net.pl 2134 163 0 1465 0 0 0 0 506
3news.icm.edu.pl 7 0 0 0 0 0 0 0 7
TOTAL: 3 4793 193 0 1599 2 0 0 0 2999

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 83
it.tlc.cellulari.android 51
comp.mobile.android 24
de.rec.sf.startrek 9
uk.rec.cycling.moderated 8
it.comp.os.win.windows7 5
it.comp.os.win.windows10 5
comp.sys.raspberry-pi 3
it.comp.os.linux.ubuntu 2
de.comp.lang.perl 2
comp.sys.mac.vintage 1
TOTAL: 11 193

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

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

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 2138
Binary in non-binary group 166
EMP (phl) 34
Scoring filter (10) 19
Scoring filter (8) 18
Scoring filter (9) 17
HTML post 13
Scoring filter (16) 11
EMP (fsl) 7
Scoring filter (18) 7
Scoring filter (11) 2
Too many newsgroups 1
Scoring filter (21) 1
TOTAL: 13 2434

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 411
news.nask.pl 153
TOTAL: 2 564
TOTAL: 1 564

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1panorama 116133 8367 73638 12413 704 39177 7%23:58:07
2poznan 16263 520 9373 24 0 0 3%23:53:07
3icm 29859 36 19400 3362 0 0 0%23:53:07
4nask 4175 2 3129 0 0 0 0%23:53:07
5okapi 0 0 0 0 0 986 0%23:51:00
TOTAL: 5 166430 8925 105540 15799 704 40163 5%119:28:28
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1panorama22.3 MB141.6 MB164.0 MB1.9 KB/s8.1 KB23:58:07
2icm5.2 MB1.2 GB1.2 GB14.8 KB/s373.7 KB23:53:07
3poznan1.7 MB93.8 KB1.8 MB0.0 KB/s3.5 KB23:53:07
4nask5.6 KB0.0 KB5.6 KB0.0 KB/s2.8 KB23:53:07
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:51:00
TOTAL: 529.3 MB1.3 GB1.4 GB3.3 KB/s58.2 KB119:28:28
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 4046.7 KB 18 0 000:00:25
2isvcs01.man.lodz.pl 3 00.0 KB 0 3 000:00:00
TOTAL: 2 291 4046.7 KB 18 3 010:54:48

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 675 4046.7 KB 18 3 000:00:30
2unresolved 44 00.0 KB 0 0 010:54:17
TOTAL: 2 719 4046.7 KB 18 3 010:54:48

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
89.38.40.229 23.256 38.108 0.00010:49:10
164.52.7.165 0.232 0.732 0.00000:05:06
web.man.lodz.pl 1.836 6.156 0.00000:00:25
mon.man.lodz.pl 1.460 5.480 0.00000:00:03
david.man.lodz.pl 0.520 1.580 0.00000:00:01
isvcs01.man.lodz.pl 0.036 0.056 0.00000:00:00
TOTAL: 6 27.340 52.112 0.00010:54:48

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
david.man.lodz.pl 96
164.52.7.165 42
89.38.40.229 2
TOTAL: 4 428

NNRP unrecognized commands (by host):

SystemConn
164.52.7.165 19
TOTAL: 1 19

NNRP unrecognized commands (by command):

CommandCount
#003 7
#003printer 1
#005d#005É 1
À/À+À'À#À#023À 1
INFO ALL 1
fox a 1 -1 fox hello 1
#026#003#001#001"#001 1
stats 1
USER test +iw test :Test Wuz Here 1
OPTIONS * RTSP/1.0 1
W 1
GET / HTTP/1.1 1
@RSYNCD: 29 1
TOTAL: 13 19

NNRP client timeouts [Top 20]:

SystemConnPeer
89.38.40.229 1 1
TOTAL: 1 1 1

Newsgroup request counts (by category):

CategoryCountPct
1pl 40100.0%
TOTAL: 1 40100.0%

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

NewsgroupCount
1pl.comp.pecet 22
2pl.comp.os.ms-windows.winnt 18
TOTAL: 2 40