Daily Usenet report

May 2 04:15:02 -- May 3 04:15:01

Unknown entries from news log file:

First 18 / 18 lines (100.0%)

May 2 05:01:31 newsfeed overchan[13697]: input line too long, skipping
May 2 05:40:02 newsfeed controlchan[13695]: Unknown "tags" control by robomod@news.nic.it
May 2 05:40:54 newsfeed overchan[13697]: input line too long, skipping
May 2 06:04:24 newsfeed overchan[13697]: input line too long, skipping
May 2 06:07:49 newsfeed overchan[13697]: input line too long, skipping
May 2 06:16:34 newsfeed overchan[13697]: input line too long, skipping
May 2 10:20:18 newsfeed overchan[13697]: input line too long, skipping
May 2 17:59:02 newsfeed overchan[13697]: input line too long, skipping
May 2 18:06:25 newsfeed overchan[13697]: input line too long, skipping
May 2 18:12:44 newsfeed overchan[13697]: input line too long, skipping
May 2 18:19:09 newsfeed overchan[13697]: input line too long, skipping
May 2 18:28:04 newsfeed overchan[13697]: input line too long, skipping
May 2 18:29:12 newsfeed overchan[13697]: input line too long, skipping
May 2 18:35:28 newsfeed overchan[13697]: input line too long, skipping
May 2 18:57:04 newsfeed overchan[13697]: input line too long, skipping
May 2 18:57:08 newsfeed overchan[13697]: input line too long, skipping
May 2 20:05:30 newsfeed overchan[13697]: input line too long, skipping
May 2 23:36:44 newsfeed overchan[13697]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn2386693 97.2%274.8 MB 96.9%
innfeed 45670 1.9%5.4 MB 1.9%
innd 18660 0.8%3.0 MB 1.1%
nnrpd 4774 0.2%509.1 KB 0.2%
overchan 18 0.0%3.0 KB 0.0%
controlchan 2 0.0%0.2 KB 0.0%
TOTAL: 62455817 100.0%283.7 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2379871 96.1%
Negative hits 85476 3.5%
Positive hits 10481 0.4%
Cache misses 390 0.0%
TOTAL: 4 2476218 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:07.815 0.0%456 0.000 17.138 106.333
article cleanup00:00:33.753 0.0%2388992 0.010 0.014 0.018
article logging00:01:59.174 0.1%2389703 0.034 0.050 0.193
article parse00:51:49.544 3.6%85019972 0.022 0.037 0.048
article write00:24:32.265 1.7%2385382 0.261 0.617 1.654
artlog/artcncl00:00:00.017 0.0%456 0.000 0.037 1.000
artlog/artparse00:00:00.000 0.0%2 0.000 0.000 0.000
data move00:03:33.409 0.2%99234463 0.001 0.002 0.003
hisgrep/artcncl00:00:02.778 0.0%456 0.000 6.092 12.000
hishave/artcncl00:00:01.009 0.0%456 0.000 2.213 9.667
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:47:49.031 3.3%2484160 0.059 1.155 5.055
history sync00:00:00.009 0.0%290 0.000 0.031 0.500
history write02:15:04.652 9.4%2387942 0.563 3.394 7.528
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle13:10:07.298 54.9%63803841 0.456 0.743 1.741
nntp read01:06:11.531 4.6%83203602 0.028 0.048 0.066
overview write00:00:13.832 0.0%2385382 0.003 0.006 0.008
perl filter04:44:12.309 19.7%2387940 4.024 7.141 9.003
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:05:10.471 0.4%4796181 0.044 0.065 0.085
TOTAL: 24:00:03.91223:31:28.897 98.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.177 0.0%63967 0.000 0.003 0.015
article prepare00:00:00.006 0.0%22850 0.000 0.000 0.023
article read00:00:56.456 0.1%5711 0.000 9.885 174.889
backlog stats00:00:00.164 0.0%186847 0.000 0.001 0.004
callbacks00:00:00.000 0.0%4 0.000 0.000 0.000
data read00:00:01.968 0.0%99235 0.000 0.020 0.032
data write00:00:09.666 0.0%77503 0.019 0.125 0.386
idle23:52:33.794 99.9%186847 173.385 460.022 932.436
status file00:00:14.305 0.0%16024 0.550 0.893 1.303
TOTAL: 23:54:16.11523:53:56.536100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle22:43:45.894 99.9%660847 0.000 123.820 10025.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:45.054 0.1%1322283 0.000 0.034 0.667
readart00:00:00.001 0.0%4 0.000 0.250 0.500
TOTAL: 22:45:31.95922:44:30.949 99.9%----

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 931 23834562374101 7433 1922 99%409:26:01
2news.nask.pl 2 11857 8495 1684 1678 71%23:40:14
3news.icm.edu.pl 37 259 18 232 9 6%16:57:54
4localhost 1 0 0 0 0 0%00:00:00
TOTAL: 4 971 23955722382614 9349 3609 99%450:04:09
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl762.6 GB12.3 MB140.7 MB762.7 GB 99%336.6 KB
2news.nask.pl32.4 MB327.1 KB17.7 MB50.5 MB 64%5.1 KB
3news.icm.edu.pl504.2 KB0.0 KB146.7 KB650.9 KB 77%24.1 KB
4localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 4762.6 GB12.6 MB158.6 MB762.8 GB 99%335.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 2 04:15:02 - 04:59:59 85321 3.6% 31.6226.0 GB 3.4%10117.40
May 2 05:00:00 - 05:59:59 100206 4.2% 27.8434.4 GB 4.5%10006.64
May 2 06:00:00 - 06:59:59 101213 4.2% 28.1135.0 GB 4.6%10203.80
May 2 07:00:00 - 07:59:59 103015 4.3% 28.6235.9 GB 4.7%10469.27
May 2 08:00:00 - 08:59:59 99994 4.2% 27.7835.6 GB 4.7%10355.00
May 2 09:00:00 - 09:59:59 104839 4.4% 29.1237.3 GB 4.9%10874.65
May 2 10:00:00 - 10:59:59 105556 4.4% 29.3238.3 GB 5.0%11142.80
May 2 11:00:00 - 11:59:59 113384 4.8% 31.5039.0 GB 5.1%11367.70
May 2 12:00:00 - 12:59:59 190960 8.0% 53.0426.4 GB 3.5%7703.19
May 2 13:00:00 - 13:59:59 109598 4.6% 30.4434.4 GB 4.5%10010.16
May 2 14:00:00 - 14:59:59 96965 4.1% 26.9333.5 GB 4.4%9748.18
May 2 15:00:00 - 15:59:59 92532 3.9% 25.7032.4 GB 4.2%9434.58
May 2 16:00:00 - 16:59:59 84293 3.5% 23.4130.0 GB 3.9%8726.06
May 2 17:00:00 - 17:59:59 109114 4.6% 30.3134.7 GB 4.6%10109.18
May 2 18:00:00 - 18:59:59 83771 3.5% 23.2729.5 GB 3.9%8588.72
May 2 19:00:00 - 19:59:59 72722 3.1% 20.2024.8 GB 3.3%7215.95
May 2 20:00:00 - 20:59:59 99334 4.2% 27.5930.9 GB 4.1%9014.42
May 2 21:00:00 - 21:59:59 106185 4.5% 29.5029.3 GB 3.8%8522.91
May 2 22:00:00 - 22:59:59 92548 3.9% 25.7130.9 GB 4.1%8991.97
May 2 23:00:00 - 23:59:59 115983 4.9% 32.2234.1 GB 4.5%9917.88
May 3 00:00:00 - 00:59:59 68795 2.9% 19.1121.8 GB 2.9%6356.08
May 3 01:00:00 - 01:59:59 90532 3.8% 25.1531.3 GB 4.1%9106.90
May 3 02:00:00 - 02:59:59 73507 3.1% 20.4227.2 GB 3.6%7923.47
May 3 03:00:00 - 03:59:59 63962 2.7% 17.7723.7 GB 3.1%6906.43
May 3 04:00:00 - 04:15:01 17605 0.7% 19.546.0 GB 0.8%6942.80
TOTAL: 23:59:59 2381934 100.0% 27.57762.2 GB 100.0%9250.77
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 2383 191 0 899 3 0 0 0 1290
2news.nask.pl 1909 13 0 133 2 0 0 0 1761
3news.icm.edu.pl 11 0 0 0 0 0 0 0 11
TOTAL: 3 4303 204 0 1032 5 0 0 0 3062

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 98
it.comp.os.win.windows10 55
it.tlc.cellulari.android 20
uk.rec.cycling.moderated 13
comp.mobile.android 12
it.comp.os.linux.ubuntu 1
comp.sys.raspberry-pi 1
de.comp.lang.php 1
it.politica.m5s 1
it.comp.virtualizzazione 1
it.comp.os.win.windows8 1
TOTAL: 11 204

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

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

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1345
Binary in non-binary group 886
Scoring filter (8) 60
Scoring filter (9) 11
EMP (phl) 11
Scoring filter (18) 10
Scoring filter (10) 9
HTML post 7
EMP (fsl) 5
Scoring filter (11) 4
Scoring filter (12) 1
Too many newsgroups 1
TOTAL: 12 2350

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 479
news.nask.pl 229
news.icm.edu.pl 2
TOTAL: 3 710
TOTAL: 1 710

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1panorama 43529 724 15867 226 17 47329 1%23:59:57
2poznan 13993 169 8422 10 0 0 1%23:59:57
3icm 28001 45 18222 3828 0 0 0%23:59:52
4nask 4018 4 2809 1 0 1 0%23:59:57
5okapi 0 0 0 0 0 982 0%23:57:49
TOTAL: 5 89541 942 45320 4065 17 48312 1%119:57:32
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm8.5 MB1.3 GB1.3 GB16.3 KB/s363.6 KB23:59:52
2panorama2.3 MB762.0 KB3.0 MB0.0 KB/s3.3 KB23:59:57
3poznan564.1 KB27.2 KB591.2 KB0.0 KB/s3.3 KB23:59:57
4nask10.2 KB9.3 KB19.5 KB0.0 KB/s3.9 KB23:59:57
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:49
TOTAL: 511.3 MB1.3 GB1.3 GB3.3 KB/s282.0 KB119:57:32
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 287 43.6 KB 2 0 000:00:28
TOTAL: 1 287 43.6 KB 2 0 022:45:31

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 671 43.6 KB 2 0 000:00:33
2*.static.corbina.ru 5 00.0 KB 0 0 000:00:43
3unresolved 2 00.0 KB 0 0 022:44:13
4*.us-west-1.compute.amazonaws.com 1 00.0 KB 0 0 000:00:00
TOTAL: 4 679 43.6 KB 2 0 022:45:31

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
89.38.40.54 43.508 74.160 0.00022:44:13
0890788407.static.corbina.ru 0.036 0.088 0.00000:00:43
web.man.lodz.pl 1.684 5.428 0.00000:00:28
mon.man.lodz.pl 1.464 5.148 0.00000:00:03
david.man.lodz.pl 0.500 1.872 0.00000:00:01
ec2-54-215-241-75.us-west-1.compute.amazonaws.com 0.004 0.024 0.00000:00:00
TOTAL: 6 47.196 86.720 0.00022:45:31

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
david.man.lodz.pl 96
0890788407.static.corbina.ru 5
89.38.40.54 2
ec2-54-215-241-75.us-west-1.compute.amazonaws.com 1
TOTAL: 5 392

Newsgroup request counts (by category):

CategoryCountPct
1pl 4100.0%
TOTAL: 1 4100.0%

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

NewsgroupCount
1pl.comp.pecet 4
TOTAL: 1 4