Daily Usenet report

Jan 23 04:15:03 -- Jan 24 04:15:02

Unknown entries from news log file:

First 16 / 16 lines (100.0%)

Jan 23 08:07:41 newsfeed overchan[23649]: input line too long, skipping
Jan 23 11:32:05 newsfeed nnrpd[8018]: SERVER perl filter not defined
Jan 23 12:15:57 newsfeed overchan[23649]: input line too long, skipping
Jan 23 12:51:15 newsfeed overchan[23649]: input line too long, skipping
Jan 23 13:32:35 newsfeed overchan[23649]: input line too long, skipping
Jan 23 15:01:22 newsfeed overchan[23649]: input line too long, skipping
Jan 23 16:14:39 newsfeed overchan[1051]: input line too long, skipping
Jan 23 17:07:46 newsfeed overchan[1051]: input line too long, skipping
Jan 23 17:10:47 newsfeed overchan[1051]: input line too long, skipping
Jan 23 17:13:17 newsfeed overchan[1051]: input line too long, skipping
Jan 23 18:15:46 newsfeed overchan[1051]: input line too long, skipping
Jan 23 18:48:56 newsfeed overchan[1051]: input line too long, skipping
Jan 23 20:57:05 newsfeed overchan[1051]: input line too long, skipping
Jan 23 21:09:35 newsfeed overchan[1051]: input line too long, skipping
Jan 23 23:57:24 newsfeed overchan[1051]: input line too long, skipping
Jan 24 00:40:13 newsfeed overchan[1051]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn3255287 97.8%372.7 MB 97.5%
innfeed 42339 1.3%5.0 MB 1.3%
innd 25442 0.8%4.0 MB 1.0%
nnrpd 4740 0.1%497.7 KB 0.1%
overchan 16 0.0%2.5 KB 0.0%
controlchan 3 0.0%0.2 KB 0.0%
TOTAL: 63327827 100.0%382.2 MB100.0%

History cache:

ReasonCount%Count
Do not exist 3201407 95.7%
Negative hits 132887 4.0%
Positive hits 11805 0.4%
Cache misses 87 0.0%
TOTAL: 4 3346186 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:06.886 0.0%185 0.000 37.222 1358.000
article cleanup00:00:31.434 0.0%3261574 0.007 0.010 0.014
article logging00:02:02.641 0.1%3262213 0.025 0.038 0.181
article parse00:37:35.710 2.6%119857972 0.009 0.019 0.061
article write00:16:53.443 1.2%3254718 0.186 0.311 0.670
artlog/artcncl00:00:00.003 0.0%182 0.000 0.016 0.200
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:03:24.616 0.2%135562915 0.001 0.002 0.003
hisgrep/artcncl00:00:01.298 0.0%163 0.000 7.963 330.000
hishave/artcncl00:00:00.214 0.0%185 0.000 1.157 11.667
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup01:57:41.281 8.2%3410346 0.390 2.071 7.386
history sync00:00:00.015 0.0%293 0.000 0.051 1.000
history write04:38:14.922 19.5%3260205 0.830 5.121 15.159
hiswrite/artcncl00:00:00.126 0.0%22 0.000 5.727 22.000
idle10:31:49.810 44.2%94204569 0.086 0.402 1.515
nntp read00:54:20.918 3.8%117357533 0.017 0.028 0.056
overview write00:00:14.177 0.0%3254718 0.003 0.004 0.006
perl filter04:19:42.375 18.2%3260205 3.267 4.780 7.453
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:05:03.507 0.4%6538042 0.031 0.046 0.070
TOTAL: 23:50:06.98723:27:43.376 98.4%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.173 0.0%66662 0.000 0.003 0.014
article prepare00:00:00.012 0.0%37560 0.000 0.000 0.008
article read00:01:48.291 0.1%9387 0.000 11.536 306.300
backlog stats00:00:00.156 0.0%212900 0.000 0.001 0.004
callbacks00:00:00.000 0.0%33 0.000 0.000 0.000
data read00:00:01.737 0.0%114128 0.000 0.015 0.039
data write00:00:07.145 0.0%92241 0.013 0.077 0.224
idle23:41:04.816 99.8%212900 60.552 400.492 1005.603
status file00:00:23.497 0.0%15509 0.477 1.515 21.258
TOTAL: 23:43:42.75723:43:25.827100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:01:32.781 89.7%3661 0.000 25.343 123.769
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.243 0.2%7954 0.000 0.031 0.500
readart00:00:00.004 0.0%44 0.000 0.091 0.500
TOTAL: 00:01:43.44300:01:33.028 89.9%----

Control commands to INND:

CommandNumber
L 1
flush 27
flushlogs 2
go 2
logmode 2
mode 149
name 3
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 194

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 780 32456533234988 9260 1405 99%511:13:29
2news.nask.pl 4 18097 11581 1022 5494 63%24:03:53
3news.icm.edu.pl 56 239 26 202 11 10%17:13:59
4localhost 2 1 1 0 0 100%00:00:00
TOTAL: 4 842 32639903246596 10484 6910 99%552:31:21
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl647.4 GB19.5 MB25.0 MB647.4 GB 99%209.8 KB
2news.nask.pl55.3 MB703.6 KB28.4 MB84.4 MB 65%5.1 KB
3news.icm.edu.pl1.3 MB0.0 KB494.5 KB1.8 MB 72%49.4 KB
4localhost7.1 KB0.0 KB0.0 KB7.1 KB100%7.1 KB
TOTAL: 4647.4 GB20.2 MB53.9 MB647.5 GB 99%208.7 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jan 23 04:15:03 - 04:59:59 99479 3.1% 36.8925.2 GB 3.9%9786.53
Jan 23 05:00:00 - 05:59:59 124437 3.8% 34.5730.0 GB 4.6%8743.79
Jan 23 06:00:00 - 06:59:59 120438 3.7% 33.4534.1 GB 5.3%9923.67
Jan 23 07:00:00 - 07:59:59 124949 3.8% 34.7129.7 GB 4.6%8642.73
Jan 23 08:00:00 - 08:59:59 138793 4.3% 38.5529.2 GB 4.5%8501.14
Jan 23 09:00:00 - 09:59:59 126783 3.9% 35.2227.9 GB 4.3%8135.38
Jan 23 10:00:00 - 10:59:59 133196 4.1% 37.0033.5 GB 5.2%9770.02
Jan 23 11:00:00 - 11:59:59 106095 3.3% 29.4724.2 GB 3.7%7061.32
Jan 23 12:00:00 - 12:59:59 163413 5.0% 45.3936.1 GB 5.6%10521.61
Jan 23 13:00:00 - 13:59:59 100652 3.1% 27.9619.4 GB 3.0%5642.67
Jan 23 14:00:00 - 14:59:59 82123 2.5% 22.8114.8 GB 2.3%4300.00
Jan 23 15:00:00 - 15:59:59 74354 2.3% 20.6510.9 GB 1.7%3163.28
Jan 23 16:00:00 - 16:59:59 124847 3.8% 34.6821.3 GB 3.3%6216.38
Jan 23 17:00:00 - 17:59:59 147386 4.5% 40.9429.3 GB 4.5%8527.54
Jan 23 18:00:00 - 18:59:59 143631 4.4% 39.9030.7 GB 4.7%8937.06
Jan 23 19:00:00 - 19:59:59 125593 3.9% 34.8925.9 GB 4.0%7533.84
Jan 23 20:00:00 - 20:59:59 150250 4.6% 41.7426.2 GB 4.0%7618.50
Jan 23 21:00:00 - 21:59:59 145682 4.5% 40.4730.0 GB 4.6%8724.63
Jan 23 22:00:00 - 22:59:59 198433 6.1% 55.1229.8 GB 4.6%8669.97
Jan 23 23:00:00 - 23:59:59 155016 4.8% 43.0623.5 GB 3.6%6853.37
Jan 24 00:00:00 - 00:59:59 133232 4.1% 37.0121.4 GB 3.3%6224.00
Jan 24 01:00:00 - 01:59:59 174883 5.4% 48.5831.3 GB 4.8%9102.81
Jan 24 02:00:00 - 02:59:59 192829 5.9% 53.5631.5 GB 4.9%9185.66
Jan 24 03:00:00 - 03:59:59 143557 4.4% 39.8828.7 GB 4.4%8351.15
Jan 24 04:00:00 - 04:15:02 17353 0.5% 19.243.6 GB 0.6%4237.93
TOTAL: 23:59:59 3247404 100.0% 37.59648.0 GB 100.0%7864.43
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nask.pl 5889 40 0 208 4 0 0 0 5637
2newsfeed.pionier.net.pl 1800 152 0 1180 2 0 0 0 466
3news.icm.edu.pl 12 0 0 0 0 0 0 0 12
TOTAL: 3 7701 192 0 1388 6 0 0 0 6115

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 95
it.comp.os.win.windows10 30
comp.mobile.android 26
comp.sys.raspberry-pi 20
it.comp.os.win.windows7 6
it.tlc.cellulari.android 6
comp.mobile.ipad 2
hr.ponuda.hardver.komponente 2
it.discussioni.energia 2
de.rec.sf.startrek 1
de.comm.software.mailreader 1
nl.comp.os.linux.techniek 1
TOTAL: 12 192

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

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

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 5203
Binary in non-binary group 176
Scoring filter (9) 42
Scoring filter (8) 16
Scoring filter (10) 13
HTML post 9
Scoring filter (18) 3
Scoring filter (11) 2
Scoring filter (12) 2
TOTAL: 9 5466

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 395
news.nask.pl 252
news.icm.edu.pl 1
TOTAL: 3 648
TOTAL: 1 648

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1panorama 46467 1249 18985 347 74 46408 2%23:53:52
2poznan 17996 569 10831 22 0 0 3%23:45:37
3icm 26794 75 20908 825 0 0 0%23:45:37
4nask 8571 2 2575 5475 0 0 0%23:37:49
5okapi 0 0 0 0 0 1304 0%23:43:32
TOTAL: 5 99828 1895 53299 6669 74 47712 1%118:46:27
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm41.5 MB246.6 MB288.1 MB3.4 KB/s327.8 KB23:45:37
2panorama6.0 MB1.6 MB7.6 MB0.1 KB/s4.9 KB23:53:52
3poznan1.9 MB75.6 KB2.0 MB0.0 KB/s3.4 KB23:45:37
4nask80.8 KB1.3 GB1.3 GB16.1 KB/s250.0 KB23:37:49
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:43:32
TOTAL: 549.5 MB1.5 GB1.6 GB3.9 KB/s195.5 KB118:46:27
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
okapi488.4 KB
TOTAL: 1488.4 KB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 287 4448.5 KB 19 0 000:00:25
2isvcs01.man.lodz.pl 1 00.0 KB 0 1 000:00:00
TOTAL: 2 288 4448.5 KB 19 1 000:01:42

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 671 4448.5 KB 19 1 000:00:30
2unresolved 1 00.0 KB 0 0 000:01:12
TOTAL: 2 672 4448.5 KB 19 1 000:01:42

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
185.48.195.97 0.048 0.076 0.00000:01:12
web.man.lodz.pl 1.260 3.944 0.00000:00:25
mon.man.lodz.pl 1.096 3.860 0.00000:00:03
david.man.lodz.pl 0.356 1.216 0.00000:00:00
isvcs01.man.lodz.pl 0.012 0.008 0.00000:00:00
TOTAL: 5 2.772 9.104 0.00000:01:42

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 287
david.man.lodz.pl 96
185.48.195.97 1
TOTAL: 3 384

NNRP client timeouts [Top 20]:

SystemConnPeer
185.48.195.97 1 1
TOTAL: 1 1 1

Newsgroup request counts (by category):

CategoryCountPct
1pl 44100.0%
TOTAL: 1 44100.0%

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

NewsgroupCount
1pl.comp.pecet 36
2pl.comp.os.ms-windows.winnt 8
TOTAL: 2 44