Daily Usenet report

Oct 6 04:15:03 -- Oct 7 04:15:02

Unknown entries from news log file:

First 14 / 14 lines (100.0%)

Oct 6 10:05:41 newsfeed overchan[557]: input line too long, skipping
Oct 6 11:49:19 newsfeed overchan[557]: input line too long, skipping
Oct 6 11:50:24 newsfeed overchan[557]: input line too long, skipping
Oct 6 11:50:58 newsfeed overchan[557]: input line too long, skipping
Oct 6 14:48:51 newsfeed overchan[557]: input line too long, skipping
Oct 6 14:49:06 newsfeed overchan[557]: input line too long, skipping
Oct 6 15:04:21 newsfeed overchan[557]: input line too long, skipping
Oct 6 16:04:01 newsfeed overchan[557]: input line too long, skipping
Oct 6 17:04:14 newsfeed overchan[557]: input line too long, skipping
Oct 6 17:10:52 newsfeed overchan[557]: input line too long, skipping
Oct 6 19:52:38 newsfeed overchan[557]: input line too long, skipping
Oct 6 20:57:52 newsfeed overchan[557]: input line too long, skipping
Oct 7 00:35:45 newsfeed overchan[557]: input line too long, skipping
Oct 7 04:14:30 newsfeed overchan[557]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn2495975 97.6%292.2 MB 97.4%
innfeed 38287 1.5%4.5 MB 1.5%
innd 18327 0.7%3.0 MB 1.0%
nnrpd 4863 0.2%517.9 KB 0.2%
overchan 15 0.0%2.6 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 62557468 100.0%300.1 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2473214 88.7%
Positive hits 231644 8.3%
Negative hits 80629 2.9%
Cache misses 1926 0.1%
TOTAL: 4 2787413 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:02.270 0.0%89 0.000 25.506 494.000
article cleanup00:00:30.383 0.0%2492791 0.009 0.012 0.018
article logging00:01:51.852 0.1%2493275 0.034 0.045 0.103
article parse00:45:56.415 3.2%101022639 0.017 0.027 0.049
article write00:19:14.120 1.3%2478977 0.242 0.466 0.687
artlog/artcncl00:00:00.001 0.0%87 0.000 0.011 0.500
artlog/artparse00:00:00.000 0.0%1 0.000 0.000 0.000
data move00:03:03.860 0.2%116356927 0.001 0.002 0.004
hisgrep/artcncl00:00:00.379 0.0%84 0.000 4.512 71.000
hishave/artcncl00:00:00.413 0.0%89 0.000 4.640 188.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup02:25:00.023 10.1%2798183 0.968 3.109 6.965
history sync00:00:00.009 0.0%290 0.000 0.031 1.000
history write04:37:59.287 19.3%2481616 2.559 6.721 11.656
hiswrite/artcncl00:00:00.076 0.0%5 0.000 15.200 41.000
idle09:48:57.357 40.9%70845040 0.187 0.499 1.198
nntp read01:01:48.824 4.3%99267624 0.028 0.037 0.056
overview write00:00:12.328 0.0%2478977 0.003 0.005 0.008
perl filter04:28:14.732 18.6%2481602 4.189 6.486 9.660
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:05:20.036 0.4%5260499 0.045 0.061 0.085
TOTAL: 24:00:01.12423:38:12.365 98.5%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:01.582 0.0%593841 0.001 0.003 0.007
article prepare00:00:00.059 0.0%268940 0.000 0.000 0.001
article read00:01:37.322 0.1%62995 0.257 1.545 45.180
backlog stats00:00:01.679 0.0%2121279 0.000 0.001 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:20.486 0.0%1108537 0.011 0.018 0.040
data write00:03:10.531 0.2%1102126 0.076 0.173 0.437
idle23:43:56.192 99.5%2121279 9.229 40.276 114.554
status file00:00:19.088 0.0%15585 0.333 1.225 5.667
TOTAL: 23:50:23.52923:49:26.939 99.9%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:36.253 69.5%3083 0.000 11.759 9594.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.375 0.7%6953 0.000 0.054 1.474
readart00:00:00.789 1.5%95 0.000 8.305 15.959
TOTAL: 00:00:52.18500:00:37.417 71.7%----

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 684 24815622477222 1647 2693 99%499:28:06
2news.nask.pl 4 238808 5723 222035 11050 2%24:13:59
3news.icm.edu.pl 48 266 75 131 60 28%15:59:34
4localhost 1 0 0 0 0 0%00:00:00
TOTAL: 4 737 27206362483020 223813 13803 91%539:41:39
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl822.1 GB443.5 MB450.9 MB822.9 GB 99%348.0 KB
2news.nask.pl1.3 GB4.2 GB4.2 GB9.7 GB 13%607.5 KB
3news.icm.edu.pl1.2 MB53.3 KB1.9 MB3.2 MB 38%24.0 KB
4localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 4823.4 GB4.6 GB4.6 GB832.6 GB 98%349.7 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 6 04:15:03 - 04:59:59 63383 2.6% 23.5022.9 GB 2.8%8899.28
Oct 6 05:00:00 - 05:59:59 109823 4.4% 30.5139.1 GB 4.8%11400.61
Oct 6 06:00:00 - 06:59:59 110796 4.5% 30.7839.4 GB 4.8%11485.99
Oct 6 07:00:00 - 07:59:59 110489 4.5% 30.6940.8 GB 5.0%11891.84
Oct 6 08:00:00 - 08:59:59 102659 4.1% 28.5238.7 GB 4.7%11281.95
Oct 6 09:00:00 - 09:59:59 98557 4.0% 27.3836.6 GB 4.5%10664.45
Oct 6 10:00:00 - 10:59:59 100894 4.1% 28.0327.8 GB 3.4%8092.36
Oct 6 11:00:00 - 11:59:59 134334 5.4% 37.3133.5 GB 4.1%9755.14
Oct 6 12:00:00 - 12:59:59 109820 4.4% 30.5138.1 GB 4.6%11106.63
Oct 6 13:00:00 - 13:59:59 104554 4.2% 29.0437.8 GB 4.6%11021.28
Oct 6 14:00:00 - 14:59:59 112624 4.5% 31.2838.0 GB 4.6%11058.68
Oct 6 15:00:00 - 15:59:59 90394 3.6% 25.1130.8 GB 3.7%8981.81
Oct 6 16:00:00 - 16:59:59 104388 4.2% 29.0032.4 GB 3.9%9447.98
Oct 6 17:00:00 - 17:59:59 122855 5.0% 34.1337.8 GB 4.6%11006.33
Oct 6 18:00:00 - 18:59:59 78497 3.2% 21.8025.0 GB 3.0%7293.82
Oct 6 19:00:00 - 19:59:59 104658 4.2% 29.0731.9 GB 3.9%9291.27
Oct 6 20:00:00 - 20:59:59 135269 5.5% 37.5736.3 GB 4.4%10573.21
Oct 6 21:00:00 - 21:59:59 110729 4.5% 30.7636.5 GB 4.4%10638.03
Oct 6 22:00:00 - 22:59:59 101603 4.1% 28.2234.2 GB 4.2%9965.56
Oct 6 23:00:00 - 23:59:59 89215 3.6% 24.7831.8 GB 3.9%9275.60
Oct 7 00:00:00 - 00:59:59 72546 2.9% 20.1524.8 GB 3.0%7213.46
Oct 7 01:00:00 - 01:59:59 91029 3.7% 25.2931.6 GB 3.8%9189.95
Oct 7 02:00:00 - 02:59:59 96164 3.9% 26.7133.8 GB 4.1%9835.60
Oct 7 03:00:00 - 03:59:59 101913 4.1% 28.3133.9 GB 4.1%9884.89
Oct 7 04:00:00 - 04:15:02 24416 1.0% 27.078.7 GB 1.1%10123.97
TOTAL: 23:59:59 2481609 100.0% 28.72822.5 GB 100.0%9981.79
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nask.pl 11109 4 0 8695 4 0 0 0 2406
2newsfeed.pionier.net.pl 3116 166 0 2451 4 0 0 0 495
3news.icm.edu.pl 54 0 0 5 0 0 0 0 49
TOTAL: 3 14279 170 0 11151 8 0 0 0 2950

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 55
it.comp.os.win.windows10 52
it.tlc.cellulari.android 20
comp.mobile.android 8
uk.rec.cycling.moderated 7
nl.comp.sys.android 7
it.comp.os.win.windows7 4
comp.mobile.ipad 4
it.comp.os.linux.ubuntu 3
comp.sys.raspberry-pi 2
nl.comp.os.linux.techniek 2
nl.gezondheid.alternatief-genezen 1
nl.wetenschap.regulier 1
nl.actueel 1
de.comp.lang.php 1
nl.comp.3d-printen 1
de.comp.lang.vbclassic 1
TOTAL: 17 170

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

GroupsCount
pl.sci.matematyka 5
pl.praca.oferowana 2
bit.listserv.catholic 1
TOTAL: 3 8

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 2335
Binary in non-binary group 62
EMP (phl) 17
Scoring filter (8) 10
Scoring filter (16) 9
Scoring filter (9) 9
EMP (fsl) 4
Scoring filter (13) 2
Scoring filter (10) 2
Scoring filter (14) 1
Too many newsgroups 1
TOTAL: 11 2452

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Article posted in the future
newsfeed.pionier.net.pl 13
TOTAL: 1 13
Including strange strings
newsfeed.pionier.net.pl 424
news.nask.pl 59
news.icm.edu.pl 1
TOTAL: 3 484
TOTAL: 2 497

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nask 431771 50251 237938 4096 0 3425 11%23:59:57
2panorama 329783 1408 302047 155 0 290154 0%23:59:57
3icm 344232 103 290917 12300 0 1 0%23:59:57
4poznan 4903 58 3570 15 0 0 1%23:59:56
5okapi 0 0 0 0 0 757 0%23:57:50
TOTAL: 51110689 51820 834472 16566 0 294337 4%119:57:37
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nask27.3 GB2.5 GB29.8 GB362.0 KB/s575.5 KB23:59:57
2panorama662.7 MB31.7 MB694.4 MB8.2 KB/s454.9 KB23:59:57
3icm10.9 MB6.4 GB6.4 GB78.0 KB/s543.1 KB23:59:57
4poznan272.2 KB47.1 KB319.3 KB0.0 KB/s4.4 KB23:59:56
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:50
TOTAL: 528.0 GB9.0 GB36.9 GB89.7 KB/s566.2 KB119:57:37
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1ool-4578089a.dyn.optonline.net 1 4931.0 KB 1 0 000:00:09
2web.man.lodz.pl 288 4648.8 KB 22 0 000:00:26
TOTAL: 2 289 9579.8 KB 23 0 000:00:51

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.dyn.optonline.net 1 4931.0 KB 1 0 000:00:09
2*.man.lodz.pl 576 4648.8 KB 22 0 000:00:30
3unresolved 97 00.0 KB 0 0 000:00:11
TOTAL: 3 674 9579.8 KB 23 0 000:00:51

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
web.man.lodz.pl 1.424 5.148 0.00000:00:26
ool-4578089a.dyn.optonline.net 0.016 0.028 0.00000:00:09
107.170.197.144 0.004 0.008 0.00000:00:09
mon.man.lodz.pl 1.248 4.112 0.00000:00:04
212.51.192.185 0.416 1.308 0.00000:00:01
TOTAL: 5 3.108 10.604 0.00000:00:51

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
212.51.192.185 96
107.170.197.144 1
TOTAL: 3 385

Newsgroup request counts (by category):

CategoryCountPct
1lodman 49 51.6%
2pl 46 48.4%
TOTAL: 2 95100.0%

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

NewsgroupCount
1lodman.info 49
2pl.comp.os.ms-windows.winnt 30
3pl.comp.pecet 16
TOTAL: 3 95