Daily Usenet report

Nov 10 04:15:03 -- Nov 11 04:15:03

Unknown entries from news log file:

First 15 / 15 lines (100.0%)

Nov 10 04:22:18 newsfeed nnrpd[28283]: mon.man.lodz.pl rejected Expiring process 28226
Nov 10 07:28:17 newsfeed overchan[27951]: input line too long, skipping
Nov 10 16:07:09 newsfeed overchan[27951]: input line too long, skipping
Nov 10 16:17:39 newsfeed overchan[27951]: input line too long, skipping
Nov 10 20:45:28 newsfeed overchan[27951]: input line too long, skipping
Nov 10 20:48:13 newsfeed overchan[27951]: input line too long, skipping
Nov 10 20:49:54 newsfeed overchan[27951]: input line too long, skipping
Nov 10 21:36:36 newsfeed overchan[27951]: input line too long, skipping
Nov 10 21:38:05 newsfeed overchan[27951]: input line too long, skipping
Nov 10 21:40:00 newsfeed overchan[27951]: input line too long, skipping
Nov 10 22:07:43 newsfeed overchan[27951]: input line too long, skipping
Nov 10 22:41:40 newsfeed overchan[27951]: input line too long, skipping
Nov 10 22:48:43 newsfeed overchan[27951]: input line too long, skipping
Nov 10 22:49:12 newsfeed overchan[27951]: input line too long, skipping
Nov 11 02:40:23 newsfeed overchan[27951]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn2780652 97.5%324.6 MB 97.2%
innfeed 47591 1.7%5.7 MB 1.7%
innd 19759 0.7%3.3 MB 1.0%
nnrpd 4839 0.2%508.9 KB 0.1%
overchan 15 0.0%2.5 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 62852857 100.0%334.1 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2745695 91.7%
Positive hits 160341 5.4%
Negative hits 87405 2.9%
Cache misses 1617 0.1%
TOTAL: 4 2995058 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:06.903 0.0%140 0.000 49.307 248.000
article cleanup00:00:36.378 0.0%2778743 0.009 0.013 0.023
article logging00:02:05.318 0.1%2779283 0.035 0.045 0.081
article parse00:56:11.125 3.9%92073239 0.024 0.037 0.060
article write00:21:06.653 1.5%2750120 0.287 0.461 0.668
artlog/artcncl00:00:00.008 0.0%139 0.000 0.058 0.500
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:04:06.015 0.3%107883056 0.001 0.002 0.004
hisgrep/artcncl00:00:02.366 0.0%140 0.000 16.900 57.778
hishave/artcncl00:00:00.649 0.0%140 0.000 4.636 24.750
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup03:19:12.290 13.8%3004728 1.008 3.978 9.760
history sync00:00:00.016 0.0%290 0.000 0.055 0.500
history write05:01:41.038 20.9%2753186 2.445 6.575 13.945
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle07:16:34.023 30.3%54389813 0.104 0.482 1.007
nntp read01:16:37.604 5.3%89866908 0.035 0.051 0.065
overview write00:00:14.584 0.0%2750120 0.004 0.005 0.008
perl filter05:11:45.879 21.6%2753186 4.679 6.794 9.790
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:06:14.546 0.4%5739339 0.049 0.065 0.092
TOTAL: 24:00:06.08523:36:35.395 98.4%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:01.473 0.0%491998 0.000 0.003 0.015
article prepare00:00:00.070 0.0%267072 0.000 0.000 0.002
article read00:00:48.322 0.1%60614 0.000 0.797 101.000
backlog stats00:00:01.366 0.0%1557967 0.000 0.001 0.006
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:20.080 0.0%888505 0.011 0.023 0.045
data write00:01:54.914 0.1%774712 0.027 0.148 0.449
idle23:48:10.420 99.7%1557967 13.766 55.001 1393.113
status file00:00:20.997 0.0%16615 0.637 1.264 9.317
TOTAL: 23:52:27.33723:51:37.642 99.9%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:12.036 47.6%3010 0.000 3.999 41.333
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.313 1.2%6665 0.000 0.047 1.333
readart00:00:00.060 0.2%34 0.000 1.765 17.500
TOTAL: 00:00:25.27800:00:12.409 49.1%----

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 532 27490312742560 1232 5239 99%513:53:24
2news.nask.pl 12 169028 9954 135781 23293 5%31:15:31
3news.icm.edu.pl 47 263 124 99 40 47%15:56:37
4localhost 1 0 0 0 0 0%00:00:00
TOTAL: 4 592 29183222752638 137112 28572 94%561:05:32
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl869.8 GB704.1 MB950.4 MB871.4 GB 99%332.5 KB
2news.nask.pl2.5 GB7.0 GB7.0 GB16.5 GB 14%519.8 KB
3news.icm.edu.pl2.4 MB93.7 KB2.6 MB5.2 MB 47%32.2 KB
4localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 4872.2 GB7.7 GB7.9 GB887.9 GB 98%334.7 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Nov 10 04:15:03 - 04:59:59 60734 2.2% 22.5221.8 GB 2.5%8458.49
Nov 10 05:00:00 - 05:59:59 113901 4.1% 31.6440.7 GB 4.7%11867.58
Nov 10 06:00:00 - 06:59:59 102466 3.7% 28.4638.2 GB 4.4%11126.38
Nov 10 07:00:00 - 07:59:59 102583 3.7% 28.5039.5 GB 4.5%11514.01
Nov 10 08:00:00 - 08:59:59 94942 3.5% 26.3732.7 GB 3.8%9518.13
Nov 10 09:00:00 - 09:59:59 104011 3.8% 28.8932.4 GB 3.7%9447.43
Nov 10 10:00:00 - 10:59:59 115900 4.2% 32.1942.3 GB 4.9%12312.75
Nov 10 11:00:00 - 11:59:59 116364 4.2% 32.3243.4 GB 5.0%12636.58
Nov 10 12:00:00 - 12:59:59 105195 3.8% 29.2237.1 GB 4.3%10806.57
Nov 10 13:00:00 - 13:59:59 143762 5.2% 39.9336.2 GB 4.2%10532.49
Nov 10 14:00:00 - 14:59:59 115051 4.2% 31.9638.4 GB 4.4%11178.16
Nov 10 15:00:00 - 15:59:59 91768 3.3% 25.4928.8 GB 3.3%8389.29
Nov 10 16:00:00 - 16:59:59 139773 5.1% 38.8332.5 GB 3.7%9465.46
Nov 10 17:00:00 - 17:59:59 104782 3.8% 29.1129.9 GB 3.4%8703.99
Nov 10 18:00:00 - 18:59:59 147008 5.3% 40.8435.7 GB 4.1%10410.78
Nov 10 19:00:00 - 19:59:59 101876 3.7% 28.3033.2 GB 3.8%9677.72
Nov 10 20:00:00 - 20:59:59 115811 4.2% 32.1741.0 GB 4.7%11945.14
Nov 10 21:00:00 - 21:59:59 133519 4.9% 37.0940.2 GB 4.6%11721.12
Nov 10 22:00:00 - 22:59:59 126093 4.6% 35.0341.6 GB 4.8%12120.42
Nov 10 23:00:00 - 23:59:59 147874 5.4% 41.0840.3 GB 4.6%11740.89
Nov 11 00:00:00 - 00:59:59 114689 4.2% 31.8629.8 GB 3.4%8686.81
Nov 11 01:00:00 - 01:59:59 135999 4.9% 37.7837.6 GB 4.3%10948.05
Nov 11 02:00:00 - 02:59:59 105818 3.8% 29.3937.2 GB 4.3%10835.75
Nov 11 03:00:00 - 03:59:59 90463 3.3% 25.1332.6 GB 3.7%9487.82
Nov 11 04:00:00 - 04:15:03 20985 0.8% 23.248.2 GB 0.9%9464.88
TOTAL: 24:00:00 2751367 100.0% 31.84871.3 GB 100.0%10574.34
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nask.pl 23405 3 0 21659 6 0 0 0 1737
2newsfeed.pionier.net.pl 5699 76 0 3878 8 0 0 0 1737
3news.icm.edu.pl 42 0 0 6 0 0 0 0 36
TOTAL: 3 29146 79 0 25543 14 0 0 0 3510

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 30
it.tlc.cellulari.android 15
it.comp.os.win.windows10 10
it.comp.os.linux.ubuntu 9
comp.mobile.android 5
comp.mobile.ipad 4
hr.rec.glazba.hifi 2
nl.comp.os.linux.techniek 2
it.comp.virtualizzazione 1
it.comp.os.win.windows7 1
TOTAL: 10 79

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

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

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1530
Binary in non-binary group 1276
EMP (phl) 81
Scoring filter (16) 38
Scoring filter (8) 15
Scoring filter (9) 10
Scoring filter (18) 7
Scoring filter (12) 3
Too many newsgroups 3
Scoring filter (10) 2
Scoring filter (11) 2
EMP (fsl) 1
Angle-bracket bot 1
TOTAL: 13 2969

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad command received
news.nask.pl 10
TOTAL: 1 10
Including strange strings
newsfeed.pionier.net.pl 477
news.nask.pl 62
news.icm.edu.pl 2
TOTAL: 3 541
TOTAL: 2 551

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nask 294993 55355 164164 3004 0 1566 18%23:59:58
2panorama 263357 199 235392 223 0 254463 0%23:59:58
3icm 250732 22 229061 7862 0 2 0%23:59:58
4poznan 4473 19 3268 20 0 0 0%23:22:53
5okapi 0 0 0 0 0 999 0%23:57:51
TOTAL: 5 813555 55595 631885 11109 0 257030 6%119:20:38
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nask19.5 GB1.0 GB20.5 GB248.9 KB/s368.5 KB23:59:58
2panorama4.0 MB1.5 MB5.5 MB0.1 KB/s13.5 KB23:59:58
3icm1.0 MB2.8 GB2.8 GB33.8 KB/s370.8 KB23:59:58
4poznan751.8 KB54.8 KB806.6 KB0.0 KB/s20.7 KB23:22:53
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:51
TOTAL: 519.5 GB3.8 GB23.3 GB56.9 KB/s366.3 KB119:20:38
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
panorama4.8 MB
TOTAL: 14.8 MB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 3430.0 KB 17 0 000:00:17
TOTAL: 1 288 3430.0 KB 17 0 000:00:24

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 577 3430.0 KB 17 0 000:00:22
2unresolved 96 00.0 KB 0 0 000:00:01
TOTAL: 2 673 3430.0 KB 17 0 000:00:24

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
web.man.lodz.pl 1.452 5.820 0.00000:00:17
mon.man.lodz.pl 1.612 6.556 0.00000:00:04
212.51.192.185 0.484 2.100 0.00000:00:01
TOTAL: 3 3.548 14.476 0.00000:00:24

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 289
212.51.192.185 96
TOTAL: 2 385

Newsgroup request counts (by category):

CategoryCountPct
1pl 34100.0%
TOTAL: 1 34100.0%

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

NewsgroupCount
1pl.comp.pecet 22
2pl.comp.os.ms-windows.winnt 12
TOTAL: 2 34