Daily Usenet report

Nov 9 04:15:03 -- Nov 10 04:15:03

Unknown entries from news log file:

First 15 / 15 lines (100.0%)

Nov 9 05:04:32 newsfeed overchan[10695]: input line too long, skipping
Nov 9 05:04:48 newsfeed overchan[10695]: input line too long, skipping
Nov 9 10:59:37 newsfeed overchan[10695]: input line too long, skipping
Nov 9 12:40:21 newsfeed overchan[10695]: input line too long, skipping
Nov 9 13:42:00 newsfeed overchan[10695]: input line too long, skipping
Nov 9 13:58:14 newsfeed overchan[10695]: input line too long, skipping
Nov 9 16:12:23 newsfeed overchan[10695]: input line too long, skipping
Nov 9 16:13:28 newsfeed overchan[10695]: input line too long, skipping
Nov 9 16:19:00 newsfeed overchan[10695]: input line too long, skipping
Nov 9 16:52:16 newsfeed overchan[10695]: input line too long, skipping
Nov 9 17:40:25 newsfeed overchan[10695]: input line too long, skipping
Nov 9 21:04:58 newsfeed overchan[10695]: input line too long, skipping
Nov 9 22:15:32 newsfeed nnrpd[15689]: SERVER perl filter not defined
Nov 9 22:49:28 newsfeed overchan[10695]: input line too long, skipping
Nov 10 03:52:04 newsfeed overchan[10695]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn2730353 97.5%329.1 MB 97.3%
innfeed 46052 1.6%5.5 MB 1.6%
innd 19139 0.7%3.2 MB 0.9%
nnrpd 4898 0.2%517.4 KB 0.1%
overchan 15 0.0%2.1 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 62800458 100.0%338.3 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2714790 91.6%
Positive hits 148435 5.0%
Negative hits 99751 3.4%
Cache misses 1529 0.1%
TOTAL: 4 2964505 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:03.442 0.0%154 0.000 22.351 141.000
article cleanup00:00:36.194 0.0%2728627 0.009 0.013 0.019
article logging00:02:07.315 0.1%2729295 0.033 0.047 0.100
article parse00:50:36.313 3.5%87643895 0.018 0.035 0.049
article write00:19:48.085 1.4%2718853 0.213 0.437 0.980
artlog/artcncl00:00:00.006 0.0%154 0.000 0.039 1.000
artlog/artparse00:00:00.000 0.0%1 0.000 0.000 0.000
data move00:05:00.393 0.3%102803579 0.001 0.003 0.004
hisgrep/artcncl00:00:00.971 0.0%153 0.000 6.346 30.000
hishave/artcncl00:00:00.137 0.0%154 0.000 0.890 6.500
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup02:05:30.443 8.7%2972394 0.701 2.533 8.651
history sync00:00:00.016 0.0%290 0.000 0.055 1.000
history write03:49:43.116 16.0%2720916 1.416 5.066 14.099
hiswrite/artcncl00:00:00.012 0.0%1 12.000 12.000 12.000
idle09:46:56.711 40.8%58677241 0.216 0.600 1.459
nntp read01:18:58.287 5.5%85712899 0.042 0.055 0.074
overview write00:00:14.931 0.0%2718853 0.004 0.005 0.008
perl filter05:05:29.547 21.2%2720913 4.128 6.737 9.522
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:06:25.205 0.4%5823388 0.048 0.066 0.084
TOTAL: 24:00:02.63123:31:31.124 98.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:02.408 0.0%750348 0.001 0.003 0.006
article prepare00:00:00.274 0.0%997792 0.000 0.000 0.001
article read00:02:46.453 0.2%226161 0.258 0.736 9.929
backlog stats00:00:02.899 0.0%3014776 0.000 0.001 0.002
callbacks00:00:00.000 0.0%3 0.000 0.000 0.000
data read00:00:35.037 0.0%1516571 0.011 0.023 0.043
data write00:08:10.813 0.6%1591408 0.108 0.308 0.461
idle23:36:12.227 99.0%3014776 13.157 28.185 110.300
status file00:00:58.658 0.1%16952 0.949 3.460 126.520
TOTAL: 23:50:19.45223:48:48.769 99.9%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:30:33.512 98.8%3192 0.000 574.409600065.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.662 0.0%7376 0.000 0.090 51.000
readart00:00:01.535 0.1%195 0.000 7.872 12.765
TOTAL: 00:30:55.71400:30:35.709 98.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 702 27162682709349 3313 3606 99%519:59:41
2news.nask.pl 19 154363 9063 139126 6174 5%25:14:41
3news.icm.edu.pl 49 225 114 95 16 50%16:56:55
4localhost 2 1 1 0 0 100%00:00:00
TOTAL: 4 772 28708572718527 142534 9796 94%562:11:17
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl748.8 GB204.6 MB211.1 MB749.2 GB 99%289.6 KB
2news.nask.pl1.0 GB883.3 MB893.4 MB2.8 GB 37%190.6 KB
3news.icm.edu.pl2.0 MB9.5 KB243.8 KB2.3 MB 89%18.0 KB
4localhost1.4 KB0.0 KB0.0 KB1.4 KB100%1.4 KB
TOTAL: 4749.8 GB1.1 GB1.1 GB751.9 GB 99%289.0 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Nov 9 04:15:03 - 04:59:59 73485 2.7% 27.2519.1 GB 2.5%7415.41
Nov 9 05:00:00 - 05:59:59 143222 5.3% 39.7837.5 GB 5.0%10922.00
Nov 9 06:00:00 - 06:59:59 119822 4.4% 33.2840.8 GB 5.4%11883.24
Nov 9 07:00:00 - 07:59:59 116195 4.3% 32.2840.3 GB 5.4%11748.84
Nov 9 08:00:00 - 08:59:59 113789 4.2% 31.6134.5 GB 4.6%10044.12
Nov 9 09:00:00 - 09:59:59 112063 4.1% 31.1330.3 GB 4.0%8838.30
Nov 9 10:00:00 - 10:59:59 148690 5.5% 41.3037.4 GB 5.0%10879.24
Nov 9 11:00:00 - 11:59:59 156574 5.8% 43.4940.7 GB 5.4%11841.42
Nov 9 12:00:00 - 12:59:59 114990 4.2% 31.9438.1 GB 5.1%11110.21
Nov 9 13:00:00 - 13:59:59 90283 3.3% 25.0824.1 GB 3.2%7006.83
Nov 9 14:00:00 - 14:59:59 114238 4.2% 31.7329.5 GB 3.9%8595.89
Nov 9 15:00:00 - 15:59:59 77802 2.9% 21.6126.6 GB 3.5%7743.13
Nov 9 16:00:00 - 16:59:59 102622 3.8% 28.5124.8 GB 3.3%7214.38
Nov 9 17:00:00 - 17:59:59 127881 4.7% 35.5230.7 GB 4.1%8938.57
Nov 9 18:00:00 - 18:59:59 128695 4.7% 35.7530.1 GB 4.0%8768.88
Nov 9 19:00:00 - 19:59:59 102723 3.8% 28.5330.2 GB 4.0%8801.75
Nov 9 20:00:00 - 20:59:59 109010 4.0% 30.2834.3 GB 4.6%9979.26
Nov 9 21:00:00 - 21:59:59 116518 4.3% 32.3732.3 GB 4.3%9396.11
Nov 9 22:00:00 - 22:59:59 108869 4.0% 30.2435.8 GB 4.8%10441.20
Nov 9 23:00:00 - 23:59:59 96588 3.6% 26.8333.7 GB 4.5%9811.63
Nov 10 00:00:00 - 00:59:59 76555 2.8% 21.2718.0 GB 2.4%5239.72
Nov 10 01:00:00 - 01:59:59 115593 4.3% 32.1124.3 GB 3.2%7068.18
Nov 10 02:00:00 - 02:59:59 156817 5.8% 43.5629.4 GB 3.9%8554.43
Nov 10 03:00:00 - 03:59:59 79919 2.9% 22.2022.4 GB 3.0%6530.46
Nov 10 04:00:00 - 04:15:03 16822 0.6% 18.635.7 GB 0.8%6659.69
TOTAL: 24:00:00 2719765 100.0% 31.48750.4 GB 100.0%9107.65
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nask.pl 6266 6 0 4305 6 0 0 0 1949
2newsfeed.pionier.net.pl 4141 145 0 3410 3 0 0 0 583
3news.icm.edu.pl 27 0 0 1 0 0 0 0 26
TOTAL: 3 10434 151 0 7716 9 0 0 0 2558

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 48
it.tlc.cellulari.android 38
it.comp.os.win.windows10 37
comp.mobile.android 9
it.comp.os.linux.ubuntu 5
uk.rec.cycling.moderated 4
it.comp.os.win.windows7 2
comp.mobile.ipad 2
comp.sys.raspberry-pi 2
hr.rec.glazba.hifi 1
nl.comp.os.linux.techniek 1
de.rec.sf.startrek 1
de.comp.office-pakete.ms-office.word 1
TOTAL: 13 151

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

GroupsCount
pl.praca.oferowana 7
bit.listserv.catholic 1
pl.sci.matematyka 1
TOTAL: 3 9

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1655
EMP (phl) 114
Binary in non-binary group 84
Scoring filter (16) 16
Too many newsgroups 7
Scoring filter (9) 4
Scoring filter (8) 4
Scoring filter (10) 3
HTML post 2
Angle-bracket bot 1
Scoring filter (18) 1
TOTAL: 11 1891

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Article posted in the future
newsfeed.pionier.net.pl 2
TOTAL: 1 2
Bad command received
news.nask.pl 145
TOTAL: 1 145
Including strange strings
newsfeed.pionier.net.pl 517
news.nask.pl 136
news.icm.edu.pl 11
TOTAL: 3 664
TOTAL: 3 811

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nask 475058 224224 147333 113 0 5769 47%23:59:56
2panorama 385858 1002 356383 244 4 391748 0%23:59:57
3poznan 8663 99 6141 16 0 0 1%23:59:57
4icm 442372 12 360033 26658 0 13 0%23:59:57
5okapi 0 0 0 0 0 1294 0%23:57:50
TOTAL: 51311951 225337 869890 27031 4 398824 17%119:57:37
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nask80.7 GB36.4 MB80.8 GB980.4 KB/s377.6 KB23:59:56
2panorama58.0 MB18.8 MB76.8 MB0.9 KB/s63.1 KB23:59:57
3poznan370.6 KB96.1 KB466.7 KB0.0 KB/s4.1 KB23:59:57
4icm279.0 KB9.6 GB9.6 GB116.0 KB/s375.7 KB23:59:57
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:50
TOTAL: 580.8 GB9.6 GB90.4 GB219.5 KB/s375.7 KB119:57:37
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
panorama488.4 KB
TOTAL: 1488.4 KB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1ool-4578089a.dyn.optonline.net 1 11974.9 KB 2 0 000:00:19
2web.man.lodz.pl 288 7676.6 KB 36 0 000:00:21
3isvcs01.man.lodz.pl 1 00.0 KB 0 1 000:00:00
TOTAL: 3 290 195151.5 KB 38 1 000:30:54

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.dyn.optonline.net 1 11974.9 KB 2 0 000:00:19
2*.man.lodz.pl 577 7676.6 KB 36 1 000:00:33
3unresolved 97 00.0 KB 0 0 000:30:01
TOTAL: 3 675 195151.5 KB 38 1 000:30:54

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
46.229.151.131 0.028 0.020 0.00000:30:00
web.man.lodz.pl 1.476 5.820 0.00000:00:21
ool-4578089a.dyn.optonline.net 0.036 0.072 0.00000:00:19
mon.man.lodz.pl 1.872 11.992 0.00000:00:11
212.51.192.185 0.492 2.088 0.00000:00:01
isvcs01.man.lodz.pl 0.008 0.020 0.00000:00:00
TOTAL: 6 3.912 20.012 0.00000:30:54

Curious NNRP server explorers [Top 20]:

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

NNRP client timeouts [Top 20]:

SystemConnPeer
46.229.151.131 1 0
TOTAL: 1 1 0

Newsgroup request counts (by category):

CategoryCountPct
1lodman 119 61.0%
2pl 76 39.0%
TOTAL: 2 195100.0%

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

NewsgroupCount
1lodman.info 105
2pl.comp.pecet 48
3pl.comp.os.ms-windows.winnt 28
4lodman.test 14
TOTAL: 4 195
Valid XHTML 1.1 Strict CSS is valid!
innreport 3.1.0 (c) 1996-1999 by Fabien Tassin <fta@sofaraway.org>