Daily Usenet report

Sep 23 04:15:05 -- Sep 24 04:16:23

Unknown entries from news log file:

First 14 / 14 lines (100.0%)

Sep 23 05:11:53 newsfeed overchan[24882]: input line too long, skipping
Sep 23 05:14:18 newsfeed overchan[24882]: input line too long, skipping
Sep 23 05:37:10 newsfeed overchan[24882]: input line too long, skipping
Sep 23 08:02:08 newsfeed overchan[24882]: input line too long, skipping
Sep 23 08:02:21 newsfeed overchan[24882]: input line too long, skipping
Sep 23 13:12:30 newsfeed overchan[24882]: input line too long, skipping
Sep 23 13:12:47 newsfeed overchan[24882]: input line too long, skipping
Sep 23 16:04:57 newsfeed overchan[24882]: input line too long, skipping
Sep 23 16:06:12 newsfeed overchan[24882]: input line too long, skipping
Sep 23 16:06:32 newsfeed overchan[24882]: input line too long, skipping
Sep 23 20:00:16 newsfeed overchan[24882]: input line too long, skipping
Sep 23 20:22:02 newsfeed overchan[24882]: input line too long, skipping
Sep 24 02:59:31 newsfeed overchan[24882]: input line too long, skipping
Sep 24 02:59:51 newsfeed overchan[24882]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn1483076 96.4%174.3 MB 96.1%
innfeed 36558 2.4%4.3 MB 2.4%
innd 14581 0.9%2.2 MB 1.2%
nnrpd 4794 0.3%504.0 KB 0.3%
overchan 15 0.0%2.5 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 61539025 100.0%181.3 MB100.0%

History cache:

ReasonCount%Count
Do not exist 1480775 86.0%
Positive hits 143995 8.4%
Negative hits 88447 5.1%
Cache misses 8121 0.5%
TOTAL: 4 1721338 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:15.522 0.0%122 0.000 127.230 1257.000
article cleanup00:00:20.648 0.0%1487419 0.000 0.014 0.059
article logging00:01:24.766 0.1%1487879 0.000 0.057 0.218
article parse00:35:36.719 2.5%43738424 0.000 0.049 0.068
article write00:14:38.290 1.0%1473728 0.383 0.596 25.562
artlog/artcncl00:00:00.011 0.0%111 0.000 0.099 1.000
artlog/artparse00:00:00.007 0.0%265 0.000 0.026 0.500
data move00:01:52.053 0.1%51979614 0.000 0.002 0.037
hisgrep/artcncl00:00:03.481 0.0%119 0.000 29.252 299.000
hishave/artcncl00:00:01.009 0.0%122 0.000 8.270 28.500
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup04:29:18.567 18.7%1728018 2.903 9.351 46.416
history sync00:00:00.017 0.0%316 0.000 0.054 0.500
history write06:51:56.383 28.6%1475440 6.666 16.752 119.622
hiswrite/artcncl00:00:00.030 0.0%3 10.000 10.000 10.000
idle07:25:42.740 30.9%19164833 0.086 1.395 7044.741
nntp read00:39:17.417 2.7%42471794 0.011 0.056 0.138
overview write00:00:08.760 0.0%1473728 0.000 0.006 0.031
perl filter03:21:32.343 14.0%1475413 0.837 8.196 13.829
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:03:59.740 0.3%3220406 0.008 0.074 0.153
TOTAL: 24:00:20.94923:46:08.503 99.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:01.691 0.0%463711 0.000 0.004 0.017
article prepare00:00:00.086 0.0%342190 0.000 0.000 0.002
article read00:05:14.088 0.4%74240 0.490 4.231 99.000
backlog stats00:00:02.030 0.0%1750794 0.000 0.001 0.007
callbacks00:00:00.001 0.0%3 0.000 0.333 1.000
data read00:00:22.712 0.0%914442 0.000 0.025 0.033
data write00:04:51.799 0.3%951249 0.017 0.307 0.462
idle23:40:29.412 99.2%1750794 18.485 48.680 2510.385
status file00:00:32.470 0.0%13380 0.658 2.427 93.552
TOTAL: 23:52:34.60123:51:34.289 99.9%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.027 0.0%1 27.000 27.000 27.000
idle00:00:39.188 72.2%3136 0.000 12.496 9772.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.287 0.5%7164 0.000 0.040 1.000
readart00:00:01.084 2.0%143 0.000 7.580 17.721
TOTAL: 00:00:54.30900:00:40.586 74.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 216 14253161422301 1191 1824 99%374:16:16
2news.nask.pl 337 196983 46161 138744 12078 23%64:26:19
3news.icm.edu.pl 43 325 126 109 90 38%17:19:47
4localhost 1 0 0 0 0 0%00:00:00
TOTAL: 4 597 16226241468588 140044 13992 90%456:02:22
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl476.3 GB410.0 MB435.5 MB477.1 GB 99%351.3 KB
2news.nask.pl21.9 GB4.7 GB4.8 GB31.4 GB 69%565.7 KB
3news.icm.edu.pl3.3 MB492.1 KB2.3 MB6.1 MB 54%28.8 KB
4localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 4498.2 GB5.1 GB5.2 GB508.5 GB 97%359.7 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 23 04:15:05 - 04:59:59 55392 3.8% 20.5522.4 GB 4.5%8722.18
Sep 23 05:00:00 - 05:59:59 76674 5.2% 21.3028.4 GB 5.7%8286.33
Sep 23 06:00:00 - 06:59:59 25528 1.7% 7.099.6 GB 1.9%2806.65
Sep 23 07:00:00 - 07:59:59 214 0.0% 0.061.3 MB 0.0% 0.37
Sep 23 08:00:00 - 08:59:59 214 0.0% 0.061.1 MB 0.0% 0.32
Sep 23 09:00:00 - 09:59:59 227 0.0% 0.061021.6 KB 0.0% 0.28
Sep 23 10:00:00 - 10:59:59 260 0.0% 0.071.2 MB 0.0% 0.34
Sep 23 11:00:00 - 11:59:59 13760 0.9% 3.825.5 GB 1.1%1595.77
Sep 23 12:00:00 - 12:59:59 48378 3.3% 13.4416.7 GB 3.4%4870.66
Sep 23 13:00:00 - 13:59:59 78829 5.4% 21.9021.2 GB 4.3%6184.36
Sep 23 14:00:00 - 14:59:59 75143 5.1% 20.8724.9 GB 5.0%7266.82
Sep 23 15:00:00 - 15:59:59 63284 4.3% 17.5823.5 GB 4.7%6839.93
Sep 23 16:00:00 - 16:59:59 64866 4.4% 18.0223.0 GB 4.6%6712.22
Sep 23 17:00:00 - 17:59:59 72636 4.9% 20.1821.2 GB 4.2%6166.12
Sep 23 18:00:00 - 18:59:59 63735 4.3% 17.7021.4 GB 4.3%6233.29
Sep 23 19:00:00 - 19:59:59 68624 4.7% 19.0620.9 GB 4.2%6091.21
Sep 23 20:00:00 - 20:59:59 64659 4.4% 17.9620.0 GB 4.0%5836.34
Sep 23 21:00:00 - 21:59:59 64333 4.4% 17.8720.7 GB 4.1%6019.94
Sep 23 22:00:00 - 22:59:59 106352 7.2% 29.5436.3 GB 7.3%10577.31
Sep 23 23:00:00 - 23:59:59 110773 7.5% 30.7740.7 GB 8.2%11846.68
Sep 24 00:00:00 - 00:59:59 85188 5.8% 23.6632.0 GB 6.4%9326.44
Sep 24 01:00:00 - 01:59:59 105791 7.2% 29.3935.8 GB 7.2%10418.78
Sep 24 02:00:00 - 02:59:59 113925 7.8% 31.6536.9 GB 7.4%10750.17
Sep 24 03:00:00 - 03:59:59 103476 7.0% 28.7435.1 GB 7.0%10220.74
Sep 24 04:00:00 - 04:16:23 6304 0.4% 6.411.8 GB 0.4%1969.57
TOTAL: 24:01:18 1468565 100.0% 16.98498.2 GB 100.0%6041.15
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nask.pl 12075 3 0 10423 3 0 0 0 1646
2newsfeed.pionier.net.pl 2234 115 0 1528 7 0 0 0 584
3news.icm.edu.pl 91 0 0 37 0 0 0 0 54
TOTAL: 3 14400 118 0 11988 10 0 0 0 2284

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 56
it.tlc.cellulari.android 25
it.comp.os.win.windows10 16
comp.sys.raspberry-pi 6
nl.comp.os.linux.techniek 3
it.comp.os.win.windows7 3
comp.mobile.ipad 3
it.comp.os.linux.ubuntu 2
it.discussioni.energia 1
uk.rec.cycling.moderated 1
de.comm.software.mailreader 1
comp.mobile.android 1
TOTAL: 12 118

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

GroupsCount
pl.sci.matematyka 9
bit.listserv.catholic 1
TOTAL: 2 10

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1381
Binary in non-binary group 92
EMP (phl) 45
Scoring filter (8) 13
Scoring filter (19) 8
Scoring filter (16) 8
Scoring filter (9) 6
Too many newsgroups 1
Scoring filter (11) 1
HTML post 1
TOTAL: 10 1556

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Article posted in the future
newsfeed.pionier.net.pl 3
TOTAL: 1 3
Bad command received
news.nask.pl 2581
TOTAL: 1 2581
Including strange strings
newsfeed.pionier.net.pl 410
news.nask.pl 48
news.icm.edu.pl 2
TOTAL: 3 460
No colon-space in header
news.nask.pl 23
TOTAL: 1 23
TOTAL: 4 3067

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nask 281800 63861 154038 2932 0 3736 22%20:20:03
2panorama 218197 5060 190889 284 5 269018 2%24:00:46
3poznan 2301 39 2013 9 0 1289 1%22:16:13
4icm 280492 23 259564 12934 0 24 0%24:00:45
5okapi 0 0 0 0 0 865 0%23:58:38
TOTAL: 5 782790 68983 606504 16159 5 274932 8%114:36:25
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nask32.0 GB1.1 GB33.2 GB475.2 KB/s520.8 KB20:20:03
2panorama2.0 GB17.4 MB2.0 GB24.1 KB/s389.9 KB24:00:46
3icm6.5 MB6.2 GB6.2 GB75.3 KB/s502.5 KB24:00:45
4poznan369.5 KB27.0 KB396.5 KB0.0 KB/s8.3 KB22:16:13
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:38
TOTAL: 534.0 GB7.4 GB41.4 GB105.1 KB/s509.5 KB114:36:25
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
panorama2.9 MB
okapi488.3 KB
TOTAL: 23.3 MB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 8293.8 KB 32 0 000:00:26
2ool-4578089a.dyn.optonline.net 1 6138.7 KB 1 0 000:00:11
TOTAL: 2 289 143132.5 KB 33 0 000:00:53

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 672 8293.8 KB 32 0 000:00:31
2*.dyn.optonline.net 1 6138.7 KB 1 0 000:00:11
3unresolved 1 00.0 KB 0 0 000:00:09
4*.dip0.t-ipconnect.de 2 00.0 KB 0 0 000:00:00
TOTAL: 4 676 143132.5 KB 33 0 000:00:53

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
web.man.lodz.pl 1.372 5.292 0.00000:00:26
ool-4578089a.dyn.optonline.net 0.048 0.020 0.00000:00:11
107.170.203.113 0.004 0.008 0.00000:00:09
mon.man.lodz.pl 1.284 5.368 0.00000:00:04
david.man.lodz.pl 0.428 1.664 0.00000:00:01
p5489d106.dip0.t-ipconnect.de 0.036 0.048 0.00000:00:00
TOTAL: 6 3.172 12.400 0.00000:00:53

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
david.man.lodz.pl 96
p5489d106.dip0.t-ipconnect.de 2
107.170.203.113 1
TOTAL: 4 387

NNRP client timeouts [Top 20]:

SystemConnPeer
p5489d106.dip0.t-ipconnect.de 1 1
TOTAL: 1 1 1

Newsgroup request counts (by category):

CategoryCountPct
1pl 82 57.3%
2lodman 61 42.7%
TOTAL: 2 143100.0%

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

NewsgroupCount
1pl.comp.pecet 72
2lodman.info 61
3pl.comp.os.ms-windows.winnt 10
TOTAL: 3 143
Valid XHTML 1.1 Strict CSS is valid!
innreport 3.1.0 (c) 1996-1999 by Fabien Tassin <fta@sofaraway.org>