Daily Usenet report

Feb 5 04:15:02 -- Feb 6 04:15:03

Unknown entries from news log file:

First 15 / 15 lines (100.0%)

Feb 5 05:47:16 newsfeed overchan[799]: input line too long, skipping
Feb 5 06:32:32 newsfeed overchan[799]: input line too long, skipping
Feb 5 06:36:17 newsfeed overchan[799]: input line too long, skipping
Feb 5 09:10:10 newsfeed overchan[799]: input line too long, skipping
Feb 5 10:00:19 newsfeed overchan[799]: input line too long, skipping
Feb 5 14:28:06 newsfeed nnrpd[24372]: SERVER perl filter not defined
Feb 5 14:28:38 newsfeed nnrpd[24378]: SERVER perl filter not defined
Feb 5 14:28:56 newsfeed nnrpd[24386]: SERVER perl filter not defined
Feb 5 14:36:29 newsfeed nnrpd[24732]: SERVER perl filter not defined
Feb 5 16:55:37 newsfeed overchan[799]: input line too long, skipping
Feb 5 17:25:09 newsfeed overchan[799]: input line too long, skipping
Feb 5 19:52:24 newsfeed overchan[799]: input line too long, skipping
Feb 6 00:25:29 newsfeed overchan[799]: input line too long, skipping
Feb 6 00:25:40 newsfeed overchan[799]: input line too long, skipping
Feb 6 04:03:51 newsfeed overchan[799]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn2585937 97.2%303.1 MB 97.0%
innfeed 47001 1.8%5.5 MB 1.8%
innd 21836 0.8%3.5 MB 1.1%
nnrpd 4875 0.2%508.5 KB 0.2%
overchan 12 0.0%1.7 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 62659662 100.0%312.5 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2571005 95.5%
Negative hits 109885 4.1%
Positive hits 11137 0.4%
Cache misses 135 0.0%
TOTAL: 4 2692162 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:07.927 0.0%211 0.000 37.569 824.000
article cleanup00:00:32.657 0.0%2583124 0.010 0.013 0.018
article logging00:01:57.974 0.1%2583748 0.034 0.046 0.068
article parse00:48:54.106 3.4%75543901 0.025 0.039 0.062
article write00:18:36.032 1.3%2576963 0.242 0.433 0.905
artlog/artcncl00:00:00.006 0.0%204 0.000 0.029 0.500
artlog/artparse00:00:00.000 0.0%3 0.000 0.000 0.000
data move00:02:17.850 0.2%89398432 0.001 0.002 0.004
hisgrep/artcncl00:00:01.695 0.0%209 0.000 8.110 38.708
hishave/artcncl00:00:00.545 0.0%211 0.000 2.583 26.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup02:16:30.545 9.5%2704254 0.367 3.029 12.837
history sync00:00:00.015 0.0%290 0.000 0.052 1.000
history write05:00:39.445 20.9%2582227 1.902 6.986 20.648
hiswrite/artcncl00:00:00.027 0.0%2 0.000 13.500 27.000
idle09:21:16.395 39.0%49744301 0.096 0.677 1.374
nntp read00:51:07.919 3.6%73485251 0.032 0.042 0.050
overview write00:00:14.775 0.0%2576963 0.004 0.006 0.008
perl filter04:44:56.155 19.8%2582224 4.996 6.621 8.331
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:05:18.758 0.4%5175389 0.043 0.062 0.083
TOTAL: 24:00:05.05423:32:32.826 98.1%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.179 0.0%59933 0.000 0.003 0.015
article prepare00:00:00.003 0.0%10102 0.000 0.000 0.008
article read00:00:59.660 0.1%2547 0.000 23.424 225.545
backlog stats00:00:00.143 0.0%167926 0.000 0.001 0.004
callbacks00:00:00.001 0.0%45 0.000 0.022 1.000
data read00:00:01.531 0.0%91511 0.007 0.017 0.035
data write00:00:04.543 0.0%70581 0.021 0.064 0.295
idle23:51:59.181 99.8%167926 267.210 511.649 960.901
status file00:01:04.232 0.1%16188 0.786 3.968 50.125
TOTAL: 23:54:20.41223:54:09.473100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle01:22:30.668 99.7%33578 0.000 147.438 76244.500
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:02.156 0.0%67919 0.000 0.032 3.467
readart00:00:00.013 0.0%130 0.000 0.100 1.000
TOTAL: 01:22:45.91901:22:32.837 99.7%----

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 812 25783402568585 8657 1098 99%483:56:31
2news.nask.pl 2 16825 10394 1343 5088 61%24:10:57
3news.icm.edu.pl 40 210 26 179 5 12%15:24:22
4localhost 5 4 4 0 0 100%00:00:00
TOTAL: 4 859 25953792579009 10179 6191 99%523:31:50
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl702.7 GB16.0 MB33.1 MB702.7 GB 99%286.7 KB
2news.nask.pl49.9 MB308.9 KB30.8 MB81.0 MB 61%5.4 KB
3news.icm.edu.pl624.3 KB0.0 KB97.1 KB721.4 KB 86%23.3 KB
4localhost20.3 KB0.0 KB0.0 KB20.3 KB100%5.1 KB
TOTAL: 4702.7 GB16.3 MB64.1 MB702.8 GB 99%285.1 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Feb 5 04:15:02 - 04:59:59 67933 2.6% 25.1820.8 GB 3.0%8077.61
Feb 5 05:00:00 - 05:59:59 95550 3.7% 26.5429.2 GB 4.2%8500.96
Feb 5 06:00:00 - 06:59:59 93052 3.6% 25.8528.1 GB 4.0%8181.46
Feb 5 07:00:00 - 07:59:59 105619 4.1% 29.3432.1 GB 4.6%9345.22
Feb 5 08:00:00 - 08:59:59 120946 4.7% 33.6035.4 GB 5.0%10322.82
Feb 5 09:00:00 - 09:59:59 117038 4.5% 32.5133.7 GB 4.8%9812.09
Feb 5 10:00:00 - 10:59:59 106817 4.1% 29.6730.4 GB 4.3%8865.72
Feb 5 11:00:00 - 11:59:59 97620 3.8% 27.1228.3 GB 4.0%8239.32
Feb 5 12:00:00 - 12:59:59 88576 3.4% 24.6027.9 GB 4.0%8117.76
Feb 5 13:00:00 - 13:59:59 74642 2.9% 20.7327.0 GB 3.8%7868.48
Feb 5 14:00:00 - 14:59:59 63226 2.5% 17.5622.5 GB 3.2%6544.57
Feb 5 15:00:00 - 15:59:59 111479 4.3% 30.9723.4 GB 3.3%6822.13
Feb 5 16:00:00 - 16:59:59 89615 3.5% 24.8926.9 GB 3.8%7844.65
Feb 5 17:00:00 - 17:59:59 87881 3.4% 24.4128.6 GB 4.1%8344.30
Feb 5 18:00:00 - 18:59:59 86037 3.3% 23.9028.1 GB 4.0%8175.09
Feb 5 19:00:00 - 19:59:59 101693 3.9% 28.2531.1 GB 4.4%9058.21
Feb 5 20:00:00 - 20:59:59 87878 3.4% 24.4125.1 GB 3.6%7297.73
Feb 5 21:00:00 - 21:59:59 144349 5.6% 40.1025.5 GB 3.6%7422.12
Feb 5 22:00:00 - 22:59:59 156793 6.1% 43.5533.6 GB 4.8%9793.01
Feb 5 23:00:00 - 23:59:59 103865 4.0% 28.8532.2 GB 4.6%9365.66
Feb 6 00:00:00 - 00:59:59 119720 4.6% 33.2628.3 GB 4.0%8234.41
Feb 6 01:00:00 - 01:59:59 159334 6.2% 44.2632.9 GB 4.7%9582.21
Feb 6 02:00:00 - 02:59:59 155481 6.0% 43.1931.1 GB 4.4%9061.09
Feb 6 03:00:00 - 03:59:59 114255 4.4% 31.7431.6 GB 4.5%9211.50
Feb 6 04:00:00 - 04:15:03 29535 1.1% 32.718.8 GB 1.3%10206.25
TOTAL: 24:00:01 2578934 100.0% 29.85702.5 GB 100.0%8525.91
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nask.pl 5262 15 0 126 3 0 0 0 5118
2newsfeed.pionier.net.pl 1532 174 0 782 3 0 0 0 573
3news.icm.edu.pl 5 0 0 0 0 0 0 0 5
TOTAL: 3 6799 189 0 908 6 0 0 0 5696

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 102
it.comp.os.win.windows10 36
it.tlc.cellulari.android 18
comp.sys.raspberry-pi 13
comp.mobile.android 8
hr.ponuda.hardver.komponente 3
it.comp.os.win.windows7 3
de.comp.lang.vbclassic 3
de.comm.provider.telefonie 1
nl.comp.sys.android 1
de.comm.software.mailreader 1
TOTAL: 11 189

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

GroupsCount
pl.praca.oferowana 5
bit.listserv.catholic 1
TOTAL: 2 6

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 4604
Binary in non-binary group 354
Scoring filter (8) 40
Scoring filter (9) 22
Scoring filter (18) 9
Too many newsgroups 9
Scoring filter (16) 7
Scoring filter (12) 5
HTML post 5
Scoring filter (11) 4
Scoring filter (10) 4
Scoring filter (13) 3
Scoring filter (14) 3
TOTAL: 13 5069

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 434
news.nask.pl 190
TOTAL: 2 624
TOTAL: 1 624

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1panorama 42670 738 14729 169 98 44350 1%23:59:58
2poznan 15755 487 9772 32 0 0 3%23:59:55
3icm 25720 46 20856 360 0 0 0%23:59:55
4nask 4214 0 3610 0 0 0 0%23:56:32
5okapi 0 0 0 0 0 1335 0%23:57:51
TOTAL: 5 88359 1271 48967 561 98 45685 1%119:54:11
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm7.4 MB111.2 MB118.6 MB1.4 KB/s299.2 KB23:59:55
2panorama4.6 MB2.6 MB7.2 MB0.1 KB/s8.1 KB23:59:58
3poznan1.7 MB121.6 KB1.8 MB0.0 KB/s3.6 KB23:59:55
4nask0.0 KB0.0 KB0.0 KB0.0 KB/s023:56:32
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:51
TOTAL: 513.7 MB114.0 MB127.6 MB0.3 KB/s71.3 KB119:54:11
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
panorama488.3 KB
TOTAL: 1488.3 KB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 130127.2 KB 52 0 000:00:26
2isvcs01.man.lodz.pl 4 00.0 KB 0 4 000:00:00
TOTAL: 2 292 130127.2 KB 52 4 001:22:45

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 676 130127.2 KB 52 4 000:00:32
2unresolved 5 00.0 KB 0 0 001:22:04
3*.centertel.pl 2 00.0 KB 0 0 000:00:08
TOTAL: 3 683 130127.2 KB 52 4 001:22:45

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
89.38.40.96 1.848 2.984 0.00001:14:26
213.195.159.33 0.008 0.060 0.00000:07:37
web.man.lodz.pl 1.600 6.888 0.00000:00:26
public-gprs351913.centertel.pl 0.020 0.048 0.00000:00:08
mon.man.lodz.pl 1.284 6.932 0.00000:00:03
david.man.lodz.pl 0.456 2.360 0.00000:00:01
isvcs01.man.lodz.pl 0.044 0.104 0.00000:00:00
TOTAL: 7 5.260 19.376 0.00001:22:45

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
david.man.lodz.pl 96
89.38.40.96 4
public-gprs351913.centertel.pl 2
213.195.159.33 1
TOTAL: 5 391

NNRP client timeouts [Top 20]:

SystemConnPeer
89.38.40.96 2 2
TOTAL: 1 2 2

Newsgroup request counts (by category):

CategoryCountPct
1pl 130100.0%
TOTAL: 1 130100.0%

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

NewsgroupCount
1pl.comp.os.ms-windows.winnt 66
2pl.comp.pecet 64
TOTAL: 2 130