Daily Usenet report

Dec 15 04:15:02 -- Dec 16 04:15:05

Unknown entries from news log file:

First 15 / 15 lines (100.0%)

Dec 15 05:39:01 newsfeed overchan[6437]: input line too long, skipping
Dec 15 13:38:10 newsfeed nnrpd[28569]: SERVER perl filter not defined
Dec 15 13:44:50 newsfeed nnrpd[28836]: SERVER perl filter not defined
Dec 15 16:02:54 newsfeed nnrpd[1556]: SERVER perl filter not defined
Dec 15 16:07:03 newsfeed overchan[6437]: input line too long, skipping
Dec 15 17:00:05 newsfeed pgpverify[3764]: pgpverify: verification failed
Dec 15 18:54:01 newsfeed overchan[6437]: input line too long, skipping
Dec 15 22:04:23 newsfeed overchan[6437]: input line too long, skipping
Dec 15 22:04:33 newsfeed overchan[6437]: input line too long, skipping
Dec 15 22:09:56 newsfeed overchan[6437]: input line too long, skipping
Dec 15 23:11:16 newsfeed nnrpd[17844]: SERVER perl filter not defined
Dec 16 00:17:25 newsfeed nnrpd[20407]: SERVER perl filter not defined
Dec 16 03:33:49 newsfeed overchan[6437]: input line too long, skipping
Dec 16 03:33:59 newsfeed overchan[6437]: input line too long, skipping
Dec 16 03:53:07 newsfeed overchan[6437]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn2125975 97.9%253.0 MB 97.7%
innfeed 24555 1.1%3.0 MB 1.1%
innd 15401 0.7%2.5 MB 1.0%
nnrpd 4861 0.2%517.1 KB 0.2%
overchan 10 0.0%1.7 KB 0.0%
controlchan 4 0.0%0.5 KB 0.0%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 72170807 100.0%259.0 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2105771 92.6%
Negative hits 89458 3.9%
Positive hits 78399 3.4%
Cache misses 1238 0.1%
TOTAL: 4 2274866 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:02.333 0.0%67 0.000 34.821 365.000
article cleanup00:00:27.177 0.0%2127417 0.008 0.013 0.019
article logging00:01:42.427 0.1%2127927 0.037 0.048 0.209
article parse00:36:37.798 2.5%92900266 0.009 0.024 0.066
article write00:15:16.955 1.1%2121722 0.210 0.432 0.665
artlog/artcncl00:00:00.003 0.0%66 0.000 0.045 1.000
artlog/artparse00:00:00.000 0.0%3 0.000 0.000 0.000
data move00:03:32.561 0.2%104631255 0.001 0.002 0.004
hisgrep/artcncl00:00:00.520 0.0%66 0.000 7.879 39.000
hishave/artcncl00:00:00.307 0.0%67 0.000 4.582 27.333
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup03:58:00.829 16.5%2295665 0.717 6.221 16.851
history sync00:00:00.020 0.0%290 0.000 0.069 0.500
history write05:40:03.229 23.6%2122962 2.555 9.611 21.935
hiswrite/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
idle08:17:16.817 34.5%67844671 0.096 0.440 1.266
nntp read01:02:27.932 4.3%91191722 0.025 0.041 0.069
overview write00:00:11.193 0.0%2121722 0.003 0.005 0.008
perl filter03:40:09.755 15.3%2122957 3.982 6.222 9.534
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:04:42.236 0.3%4456253 0.037 0.063 0.100
TOTAL: 24:00:05.26723:40:32.092 98.6%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:01.395 0.0%410088 0.000 0.003 0.020
article prepare00:00:00.115 0.0%451916 0.000 0.000 0.001
article read00:03:12.470 0.2%111524 0.000 1.726 43.060
backlog stats00:00:01.717 0.0%1658633 0.000 0.001 0.004
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:16.103 0.0%788562 0.000 0.020 0.045
data write00:03:04.925 0.2%914052 0.021 0.202 0.414
idle23:44:30.063 99.5%1658633 22.212 51.530 2470.695
status file00:00:09.564 0.0%7939 0.000 1.205 13.194
TOTAL: 23:52:07.09523:51:16.352 99.9%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:21.236 58.0%3034 0.000 6.999 3409.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.245 0.7%6660 0.000 0.037 1.000
readart00:00:00.015 0.0%20 0.000 0.750 7.000
TOTAL: 00:00:36.61900:00:21.496 58.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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
group-admin@isc.org000100
TOTAL000100

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 754 21053672101040 2670 1657 99%510:31:09
2news.nask.pl 25 95496 18925 72504 4067 19%34:59:03
3news.icm.edu.pl 37 313 128 130 55 40%18:21:31
4localhost 6 5 5 0 0 100%00:00:00
TOTAL: 4 822 22011812120098 75304 5779 96%563:51:43
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl588.1 GB76.6 MB79.0 MB588.3 GB 99%293.4 KB
2news.nask.pl5.5 GB584.3 MB592.1 MB6.7 GB 82%304.2 KB
3news.icm.edu.pl3.3 MB165.7 KB1.9 MB5.4 MB 61%30.1 KB
4localhost7.1 KB0.0 KB0.0 KB7.1 KB100%1.4 KB
TOTAL: 4593.7 GB661.1 MB673.0 MB595.0 GB 99%293.5 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Dec 15 04:15:02 - 04:59:59 89694 4.2% 33.2426.0 GB 4.4%10112.83
Dec 15 05:00:00 - 05:59:59 86469 4.1% 24.0226.1 GB 4.4%7606.83
Dec 15 06:00:00 - 06:59:59 96849 4.6% 26.9027.5 GB 4.6%8023.11
Dec 15 07:00:00 - 07:59:59 58714 2.8% 16.3118.2 GB 3.1%5313.01
Dec 15 08:00:00 - 08:59:59 49968 2.4% 13.8815.3 GB 2.6%4467.37
Dec 15 09:00:00 - 09:59:59 87116 4.1% 24.2027.8 GB 4.7%8110.25
Dec 15 10:00:00 - 10:59:59 100928 4.8% 28.0420.3 GB 3.4%5904.83
Dec 15 11:00:00 - 11:59:59 103467 4.9% 28.7428.2 GB 4.7%8199.96
Dec 15 12:00:00 - 12:59:59 83661 3.9% 23.2419.4 GB 3.3%5660.54
Dec 15 13:00:00 - 13:59:59 74089 3.5% 20.5820.0 GB 3.4%5816.05
Dec 15 14:00:00 - 14:59:59 87123 4.1% 24.2026.1 GB 4.4%7597.66
Dec 15 15:00:00 - 15:59:59 74830 3.5% 20.7918.8 GB 3.2%5472.08
Dec 15 16:00:00 - 16:59:59 75037 3.5% 20.8419.9 GB 3.4%5795.92
Dec 15 17:00:00 - 17:59:59 73025 3.4% 20.2820.6 GB 3.5%5999.83
Dec 15 18:00:00 - 18:59:59 99348 4.7% 27.6031.1 GB 5.2%9049.45
Dec 15 19:00:00 - 19:59:59 100169 4.7% 27.8232.1 GB 5.4%9358.27
Dec 15 20:00:00 - 20:59:59 104694 4.9% 29.0833.3 GB 5.6%9699.93
Dec 15 21:00:00 - 21:59:59 125272 5.9% 34.8033.6 GB 5.7%9796.65
Dec 15 22:00:00 - 22:59:59 134138 6.3% 37.2629.7 GB 5.0%8660.75
Dec 15 23:00:00 - 23:59:59 84714 4.0% 23.5322.5 GB 3.8%6549.08
Dec 16 00:00:00 - 00:59:59 72990 3.4% 20.2720.7 GB 3.5%6032.35
Dec 16 01:00:00 - 01:59:59 85907 4.1% 23.8622.2 GB 3.7%6463.82
Dec 16 02:00:00 - 02:59:59 74094 3.5% 20.5823.4 GB 3.9%6816.02
Dec 16 03:00:00 - 03:59:59 77983 3.7% 21.6624.6 GB 4.2%7175.80
Dec 16 04:00:00 - 04:15:05 19425 0.9% 21.465.9 GB 1.0%6806.55
TOTAL: 24:00:03 2119704 100.0% 24.53593.5 GB 100.0%7202.24
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nask.pl 4065 0 0 3018 2 0 0 0 1045
2newsfeed.pionier.net.pl 2082 183 0 1425 3 0 0 0 471
3news.icm.edu.pl 58 0 0 12 0 0 0 0 46
TOTAL: 3 6205 183 0 4455 5 0 0 0 1562

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 111
it.comp.os.win.windows10 49
it.tlc.cellulari.android 9
it.comp.os.win.windows7 5
nl.comp.os.linux.techniek 5
uk.rec.cycling.moderated 3
comp.mobile.ipad 1
TOTAL: 7 183

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

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

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 887
Binary in non-binary group 64
Scoring filter (16) 45
Scoring filter (8) 16
Scoring filter (9) 13
Scoring filter (10) 7
Too many newsgroups 7
Scoring filter (11) 2
EMP (phl) 2
Scoring filter (18) 2
Scoring filter (12) 1
Scoring filter (14) 1
TOTAL: 12 1047

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Article posted in the future
newsfeed.pionier.net.pl 1
TOTAL: 1 1
Including strange strings
newsfeed.pionier.net.pl 426
news.nask.pl 81
news.icm.edu.pl 3
TOTAL: 3 510
TOTAL: 2 511

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nask 241575 111602 77087 833 0 6575 46%23:59:59
2panorama 199602 233 186440 94 0 221515 0%23:59:59
3icm 221901 68 211803 1578 0 104 0%23:59:59
4poznan 5328 39 4071 43 0 0 0%23:55:17
5okapi 0 0 0 0 0 877 0%23:57:52
TOTAL: 5 668406 111942 479401 2548 0 229071 16%119:53:06
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nask41.4 GB127.1 MB41.6 GB504.5 KB/s387.7 KB23:59:59
2panorama7.3 MB3.7 MB11.0 MB0.1 KB/s34.5 KB23:59:59
3icm785.7 KB599.6 MB600.4 MB7.1 KB/s373.5 KB23:59:59
4poznan185.1 KB109.6 KB294.8 KB0.0 KB/s3.6 KB23:55:17
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:52
TOTAL: 541.5 GB730.6 MB42.2 GB102.4 KB/s386.2 KB119:53:06
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 2027.3 KB 8 0 000:00:24
2isvcs01.man.lodz.pl 5 00.0 KB 0 5 000:00:00
TOTAL: 2 293 2027.3 KB 8 5 000:00:35

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 581 2027.3 KB 8 5 000:00:29
2unresolved 97 00.0 KB 0 0 000:00:05
TOTAL: 2 678 2027.3 KB 8 5 000:00:35

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
web.man.lodz.pl 1.352 4.432 0.00000:00:24
125.212.217.215 0.004 0.008 0.00000:00:04
mon.man.lodz.pl 1.292 4.700 0.00000:00:04
212.51.192.185 0.420 1.532 0.00000:00:01
isvcs01.man.lodz.pl 0.044 0.076 0.00000:00:00
TOTAL: 5 3.112 10.748 0.00000:00:35

Curious NNRP server explorers [Top 20]:

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

Newsgroup request counts (by category):

CategoryCountPct
1pl 20100.0%
TOTAL: 1 20100.0%

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

NewsgroupCount
1pl.comp.os.ms-windows.winnt 20
TOTAL: 1 20