Daily Usenet report

Sep 27 04:15:02 -- Sep 28 04:15:05

Unknown entries from news log file:

First 12 / 12 lines (100.0%)

Sep 27 05:29:27 newsfeed overchan[18872]: input line too long, skipping
Sep 27 10:19:47 newsfeed nnrpd[758]: SERVER perl filter not defined
Sep 27 14:52:22 newsfeed overchan[18872]: input line too long, skipping
Sep 27 16:45:49 newsfeed nnrpd[15259]: SERVER perl filter not defined
Sep 27 16:46:50 newsfeed nnrpd[15277]: SERVER perl filter not defined
Sep 27 16:54:53 newsfeed overchan[18872]: input line too long, skipping
Sep 27 16:55:02 newsfeed overchan[18872]: input line too long, skipping
Sep 27 18:35:02 newsfeed overchan[18872]: input line too long, skipping
Sep 27 19:45:21 newsfeed overchan[18872]: input line too long, skipping
Sep 27 21:24:17 newsfeed overchan[18872]: input line too long, skipping
Sep 27 21:49:31 newsfeed overchan[18872]: input line too long, skipping
Sep 28 01:18:28 newsfeed overchan[18872]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn2277046 97.3%270.5 MB 97.1%
innfeed 41685 1.8%4.9 MB 1.8%
innd 17151 0.7%2.8 MB 1.0%
nnrpd 4770 0.2%501.2 KB 0.2%
overchan 10 0.0%1.6 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 62340663 100.0%278.7 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2246395 87.3%
Positive hits 239870 9.3%
Negative hits 84613 3.3%
Cache misses 1615 0.1%
TOTAL: 4 2572493 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:05.827 0.0%169 0.000 34.479 172.000
article cleanup00:00:33.015 0.0%2275579 0.010 0.015 0.018
article logging00:01:52.546 0.1%2275988 0.035 0.049 0.075
article parse00:59:48.801 4.2%96575628 0.024 0.037 0.046
article write00:21:20.211 1.5%2258292 0.407 0.567 1.559
artlog/artcncl00:00:00.007 0.0%168 0.000 0.042 1.000
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:02:40.246 0.2%111514004 0.001 0.001 0.002
hisgrep/artcncl00:00:01.925 0.0%168 0.000 11.458 27.333
hishave/artcncl00:00:00.087 0.0%169 0.000 0.515 8.500
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup01:46:49.588 7.4%2590504 0.568 2.474 5.705
history sync00:00:00.014 0.0%290 0.000 0.048 0.500
history write04:13:58.945 17.6%2260361 2.313 6.742 15.797
hiswrite/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
idle09:28:43.736 39.5%65882736 0.257 0.518 0.872
nntp read01:20:28.180 5.6%95058147 0.034 0.051 0.060
overview write00:00:12.770 0.0%2258292 0.004 0.006 0.008
perl filter05:08:55.101 21.5%2260358 5.895 8.200 9.727
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:05:11.969 0.4%4827322 0.050 0.065 0.081
TOTAL: 24:00:05.15923:30:42.968 98.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:01.775 0.0%601269 0.001 0.003 0.007
article prepare00:00:00.065 0.0%240892 0.000 0.000 0.002
article read00:02:16.490 0.2%57609 0.258 2.369 24.585
backlog stats00:00:01.836 0.0%1968717 0.000 0.001 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:24.828 0.0%1099200 0.014 0.023 0.033
data write00:03:44.556 0.3%937194 0.149 0.240 0.509
idle23:42:13.561 99.4%1968717 20.780 43.345 81.643
status file00:00:40.970 0.0%17117 0.709 2.394 103.700
TOTAL: 23:50:28.19523:49:24.081 99.9%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:30:17.679 99.2%3037 0.000 598.511450029.750
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.375 0.0%6712 0.000 0.056 2.000
readart00:00:00.031 0.0%36 0.000 0.861 8.500
TOTAL: 00:30:33.12500:30:18.085 99.2%----

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 795 22586902253268 1599 3823 99%499:34:53
2news.nask.pl 7 249411 6144 229670 13597 2%24:56:45
3news.icm.edu.pl 47 238 132 103 3 55%17:20:47
4localhost 4 3 3 0 0 100%00:00:00
TOTAL: 4 853 25083422259547 231372 17423 90%541:52:25
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl825.6 GB744.3 MB745.8 MB827.0 GB 99%384.2 KB
2news.nask.pl1.2 GB4.8 GB4.8 GB10.8 GB 10%575.5 KB
3news.icm.edu.pl2.1 MB9.3 KB47.9 KB2.1 MB 97%16.0 KB
4localhost3.9 KB0.0 KB0.0 KB3.9 KB100%1.3 KB
TOTAL: 4826.8 GB5.6 GB5.6 GB837.9 GB 98%385.9 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 27 04:15:02 - 04:59:59 69248 3.1% 25.6727.7 GB 3.4%10766.44
Sep 27 05:00:00 - 05:59:59 89236 3.9% 24.7936.3 GB 4.4%10581.28
Sep 27 06:00:00 - 06:59:59 91531 4.1% 25.4337.0 GB 4.5%10789.07
Sep 27 07:00:00 - 07:59:59 90374 4.0% 25.1037.6 GB 4.5%10944.06
Sep 27 08:00:00 - 08:59:59 89954 4.0% 24.9937.0 GB 4.5%10767.41
Sep 27 09:00:00 - 09:59:59 108584 4.8% 30.1640.6 GB 4.9%11831.61
Sep 27 10:00:00 - 10:59:59 96422 4.3% 26.7836.2 GB 4.4%10529.50
Sep 27 11:00:00 - 11:59:59 104949 4.6% 29.1534.0 GB 4.1%9904.65
Sep 27 12:00:00 - 12:59:59 86877 3.8% 24.1330.5 GB 3.7%8870.93
Sep 27 13:00:00 - 13:59:59 75335 3.3% 20.9324.6 GB 3.0%7171.86
Sep 27 14:00:00 - 14:59:59 88367 3.9% 24.5530.2 GB 3.7%8806.52
Sep 27 15:00:00 - 15:59:59 95689 4.2% 26.5835.8 GB 4.3%10426.64
Sep 27 16:00:00 - 16:59:59 94501 4.2% 26.2536.4 GB 4.4%10588.73
Sep 27 17:00:00 - 17:59:59 96508 4.3% 26.8134.3 GB 4.2%9992.67
Sep 27 18:00:00 - 18:59:59 92982 4.1% 25.8332.4 GB 3.9%9439.78
Sep 27 19:00:00 - 19:59:59 78613 3.5% 21.8426.1 GB 3.2%7606.92
Sep 27 20:00:00 - 20:59:59 90518 4.0% 25.1430.9 GB 3.7%8986.21
Sep 27 21:00:00 - 21:59:59 96960 4.3% 26.9333.6 GB 4.1%9783.85
Sep 27 22:00:00 - 22:59:59 101227 4.5% 28.1236.6 GB 4.4%10646.61
Sep 27 23:00:00 - 23:59:59 106665 4.7% 29.6338.0 GB 4.6%11069.84
Sep 28 00:00:00 - 00:59:59 86517 3.8% 24.0333.5 GB 4.1%9767.03
Sep 28 01:00:00 - 01:59:59 100367 4.4% 27.8836.3 GB 4.4%10586.67
Sep 28 02:00:00 - 02:59:59 100373 4.4% 27.8835.7 GB 4.3%10391.88
Sep 28 03:00:00 - 03:59:59 100086 4.4% 27.8035.6 GB 4.3%10362.45
Sep 28 04:00:00 - 04:15:05 27289 1.2% 30.159.1 GB 1.1%10547.11
TOTAL: 24:00:03 2259172 100.0% 26.15825.9 GB 100.0%10023.26
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nask.pl 13529 6 0 11624 14 0 0 0 1885
2newsfeed.pionier.net.pl 4171 184 0 3596 5 0 0 0 386
3news.icm.edu.pl 5 0 0 1 0 0 0 0 4
TOTAL: 3 17705 190 0 15221 19 0 0 0 2275

Unwanted newsgroups [Top 20]:

NewsgroupCount
it.comp.os.win.windows10 65
uk.railway 52
it.tlc.cellulari.android 36
nl.comp.os.linux.techniek 15
comp.mobile.android 6
comp.sys.raspberry-pi 6
it.comp.os.win.windows7 3
it.comp.os.linux.ubuntu 3
uk.rec.cycling.moderated 2
de.rec.sf.startrek 1
de.comp.office-pakete.ms-office.outlook 1
TOTAL: 11 190

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

GroupsCount
pl.praca.oferowana 8
courts.usa.federal.supreme 5
clari.usa.law.supreme 4
bit.listserv.catholic 1
pl.sci.matematyka 1
TOTAL: 5 19

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1735
Binary in non-binary group 53
EMP (phl) 43
Scoring filter (8) 13
Scoring filter (16) 8
Scoring filter (9) 3
Too many newsgroups 2
Scoring filter (18) 2
Scoring filter (11) 1
Scoring filter (13) 1
Scoring filter (12) 1
HTML post 1
TOTAL: 12 1863

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad command received
news.nask.pl 48
TOTAL: 1 48
Including strange strings
newsfeed.pionier.net.pl 343
news.nask.pl 64
news.icm.edu.pl 2
TOTAL: 3 409
TOTAL: 2 457

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nask 443169 47457 248147 1694 0 5848 10%23:59:59
2panorama 302565 619 273226 62 0 324245 0%23:59:59
3poznan 5330 69 3824 10 0 0 1%23:58:23
4icm 343079 25 299292 10533 0 4 0%23:59:59
5okapi 0 0 0 0 0 963 0%23:57:52
TOTAL: 51094143 48170 824489 12299 0 331060 4%119:56:12
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nask24.4 GB845.0 MB25.3 GB306.6 KB/s538.9 KB23:59:59
2panorama217.7 MB21.1 MB238.8 MB2.8 KB/s359.1 KB23:59:59
3icm5.2 MB5.0 GB5.0 GB61.2 KB/s500.8 KB23:59:59
4poznan462.7 KB39.9 KB502.6 KB0.0 KB/s6.4 KB23:58:23
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:52
TOTAL: 524.7 GB5.9 GB30.5 GB74.2 KB/s529.5 KB119:56:12
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
panorama1.4 MB
TOTAL: 11.4 MB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 3635.5 KB 18 0 000:00:24
2isvcs01.man.lodz.pl 3 00.0 KB 0 3 000:00:01
TOTAL: 2 291 3635.5 KB 18 3 000:30:32

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 675 3635.5 KB 18 3 000:00:31
2*.cm.p.lodz.pl 1 00.0 KB 0 0 000:30:00
TOTAL: 2 676 3635.5 KB 18 3 000:30:32

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
pc-mstarzak.cm.p.lodz.pl 0.000 0.012 0.00000:30:00
web.man.lodz.pl 1.516 5.856 0.00000:00:24
mon.man.lodz.pl 1.476 5.768 0.00000:00:04
david.man.lodz.pl 0.500 2.028 0.00000:00:01
isvcs01.man.lodz.pl 0.012 0.084 0.00000:00:01
TOTAL: 5 3.504 13.748 0.00000:30:32

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
david.man.lodz.pl 96
pc-mstarzak.cm.p.lodz.pl 1
TOTAL: 3 385

NNRP client timeouts [Top 20]:

SystemConnPeer
pc-mstarzak.cm.p.lodz.pl 1 0
TOTAL: 1 1 0

Newsgroup request counts (by category):

CategoryCountPct
1pl 36100.0%
TOTAL: 1 36100.0%

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

NewsgroupCount
1pl.comp.pecet 20
2pl.comp.os.ms-windows.winnt 16
TOTAL: 2 36