Daily Usenet report

Oct 16 04:15:02 -- Oct 17 04:15:02

Unknown entries from news log file:

First 21 / 21 lines (100.0%)

Oct 16 09:41:29 newsfeed nnrpd[13987]: SERVER perl filter not defined
Oct 16 11:20:41 newsfeed overchan[32766]: input line too long, skipping
Oct 16 12:41:51 newsfeed overchan[32766]: input line too long, skipping
Oct 16 15:52:10 newsfeed overchan[32766]: input line too long, skipping
Oct 16 15:57:03 newsfeed overchan[32766]: input line too long, skipping
Oct 16 16:00:50 newsfeed nnrpd[27924]: SERVER perl filter not defined
Oct 16 16:01:24 newsfeed overchan[32766]: input line too long, skipping
Oct 16 16:20:47 newsfeed overchan[32766]: input line too long, skipping
Oct 16 16:23:48 newsfeed overchan[32766]: input line too long, skipping
Oct 16 19:00:17 newsfeed nnrpd[2107]: SERVER perl filter not defined
Oct 16 19:12:44 newsfeed overchan[32766]: input line too long, skipping
Oct 16 19:17:18 newsfeed overchan[32766]: input line too long, skipping
Oct 16 19:22:52 newsfeed overchan[32766]: input line too long, skipping
Oct 16 19:24:49 newsfeed overchan[32766]: input line too long, skipping
Oct 16 21:44:24 newsfeed overchan[32766]: input line too long, skipping
Oct 16 21:47:29 newsfeed overchan[32766]: input line too long, skipping
Oct 16 23:03:07 newsfeed nnrpd[10999]: SERVER perl filter not defined
Oct 17 00:01:16 newsfeed overchan[32766]: input line too long, skipping
Oct 17 00:14:49 newsfeed overchan[32766]: input line too long, skipping
Oct 17 00:54:40 newsfeed nnrpd[15056]: SERVER perl filter not defined
Oct 17 03:00:39 newsfeed overchan[32766]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn2449230 97.3%279.4 MB 97.0%
innfeed 45081 1.8%5.3 MB 1.8%
innd 17246 0.7%2.8 MB 1.0%
nnrpd 4883 0.2%515.4 KB 0.2%
overchan 17 0.0%3.0 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 62516458 100.0%288.1 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2431904 92.4%
Negative hits 101570 3.9%
Positive hits 97801 3.7%
Cache misses 472 0.0%
TOTAL: 4 2631747 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:04.746 0.0%94 0.000 50.489 1341.000
article cleanup00:00:31.671 0.0%2451764 0.009 0.013 0.020
article logging00:01:46.148 0.1%2452368 0.026 0.043 0.121
article parse00:43:08.249 3.0%84188320 0.019 0.031 0.050
article write00:16:49.394 1.2%2445888 0.185 0.413 0.686
artlog/artcncl00:00:00.003 0.0%93 0.000 0.032 0.500
artlog/artparse00:00:00.001 0.0%1 1.000 1.000 1.000
data move00:02:16.538 0.2%97783677 0.001 0.001 0.003
hisgrep/artcncl00:00:01.138 0.0%92 0.000 12.370 61.000
hishave/artcncl00:00:00.094 0.0%94 0.000 1.000 7.750
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup01:37:18.294 6.8%2648131 0.471 2.205 5.650
history sync00:00:00.019 0.0%291 0.000 0.065 0.500
history write03:41:37.366 15.4%2447273 1.441 5.434 14.486
hiswrite/artcncl00:00:00.017 0.0%2 8.500 8.500 8.500
idle11:43:14.384 48.8%61123585 0.313 0.690 1.383
nntp read01:02:22.028 4.3%82456983 0.033 0.045 0.066
overview write00:00:12.570 0.0%2445888 0.003 0.005 0.009
perl filter04:21:03.776 18.1%2447272 3.925 6.401 9.767
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:04:49.240 0.3%5123806 0.038 0.056 0.079
TOTAL: 24:00:04.21623:35:15.676 98.3%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:01.452 0.0%460019 0.000 0.003 0.006
article prepare00:00:00.145 0.0%567388 0.000 0.000 0.004
article read00:01:14.153 0.1%125863 0.338 0.589 4.750
backlog stats00:00:02.197 0.0%2154980 0.000 0.001 0.002
callbacks00:00:00.000 0.0%1 0.000 0.000 0.000
data read00:00:19.936 0.0%908067 0.012 0.022 0.035
data write00:08:06.089 0.6%1273594 0.126 0.382 0.541
idle23:39:17.507 99.2%2154980 21.909 39.517 110.600
status file00:00:22.280 0.0%17182 0.669 1.297 4.365
TOTAL: 23:50:27.13523:49:23.759 99.9%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:16.708 53.8%3057 0.000 5.465 170.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.370 1.2%6757 0.000 0.055 1.000
readart00:00:00.002 0.0%42 0.000 0.048 0.500
TOTAL: 00:00:31.04500:00:17.080 55.0%----

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 814 24414982436075 2687 2736 99%500:26:23
2news.nask.pl 2 101127 7069 90938 3120 6%23:55:09
3news.icm.edu.pl 44 206 101 100 5 49%15:37:02
4localhost 6 5 5 0 0 100%00:00:00
TOTAL: 4 866 25428362443250 93725 5861 96%539:58:34
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl679.7 GB67.7 MB102.1 MB679.9 GB 99%292.3 KB
2news.nask.pl1.1 GB402.8 MB414.3 MB1.9 GB 57%192.9 KB
3news.icm.edu.pl1.7 MB14.9 KB242.5 KB2.0 MB 87%19.0 KB
4localhost7.3 KB0.0 KB0.0 KB7.3 KB100%1.5 KB
TOTAL: 4680.8 GB470.5 MB516.7 MB681.7 GB 99%291.9 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 16 04:15:02 - 04:59:59 104164 4.3% 38.6124.8 GB 3.6%9657.46
Oct 16 05:00:00 - 05:59:59 120946 5.0% 33.6039.6 GB 5.8%11532.41
Oct 16 06:00:00 - 06:59:59 87011 3.6% 24.1729.8 GB 4.4%8682.46
Oct 16 07:00:00 - 07:59:59 64206 2.6% 17.8422.0 GB 3.2%6406.57
Oct 16 08:00:00 - 08:59:59 91206 3.7% 25.3429.2 GB 4.3%8495.45
Oct 16 09:00:00 - 09:59:59 82142 3.4% 22.8226.8 GB 3.9%7802.66
Oct 16 10:00:00 - 10:59:59 86925 3.6% 24.1522.7 GB 3.3%6605.72
Oct 16 11:00:00 - 11:59:59 97171 4.0% 26.9928.9 GB 4.2%8414.20
Oct 16 12:00:00 - 12:59:59 99963 4.1% 27.7729.0 GB 4.3%8445.25
Oct 16 13:00:00 - 13:59:59 87292 3.6% 24.2527.3 GB 4.0%7963.01
Oct 16 14:00:00 - 14:59:59 64228 2.6% 17.8418.5 GB 2.7%5377.85
Oct 16 15:00:00 - 15:59:59 86906 3.6% 24.1425.3 GB 3.7%7365.02
Oct 16 16:00:00 - 16:59:59 111297 4.6% 30.9224.2 GB 3.5%7036.78
Oct 16 17:00:00 - 17:59:59 165914 6.8% 46.0939.5 GB 5.8%11516.71
Oct 16 18:00:00 - 18:59:59 90961 3.7% 25.2720.1 GB 3.0%5857.86
Oct 16 19:00:00 - 19:59:59 129413 5.3% 35.9536.3 GB 5.3%10577.64
Oct 16 20:00:00 - 20:59:59 120634 4.9% 33.5134.1 GB 5.0%9928.42
Oct 16 21:00:00 - 21:59:59 74079 3.0% 20.5817.9 GB 2.6%5211.85
Oct 16 22:00:00 - 22:59:59 96649 4.0% 26.8523.3 GB 3.4%6798.26
Oct 16 23:00:00 - 23:59:59 95940 3.9% 26.6525.7 GB 3.8%7495.29
Oct 17 00:00:00 - 00:59:59 97910 4.0% 27.2025.3 GB 3.7%7354.96
Oct 17 01:00:00 - 01:59:59 124565 5.1% 34.6033.8 GB 5.0%9835.55
Oct 17 02:00:00 - 02:59:59 145508 6.0% 40.4238.9 GB 5.7%11333.76
Oct 17 03:00:00 - 03:59:59 92629 3.8% 25.7329.4 GB 4.3%8569.99
Oct 17 04:00:00 - 04:15:02 24985 1.0% 27.708.7 GB 1.3%10066.31
TOTAL: 24:00:00 2442644 100.0% 28.27681.0 GB 100.0%8265.32
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nask.pl 3265 9 0 2132 1 0 0 0 1123
2newsfeed.pionier.net.pl 3217 166 0 2377 0 0 0 0 674
3news.icm.edu.pl 11 0 0 3 0 0 0 0 8
TOTAL: 3 6493 175 0 4512 1 0 0 0 1805

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 75
it.comp.os.win.windows10 50
it.tlc.cellulari.android 24
comp.sys.raspberry-pi 8
comp.mobile.ipad 5
it.comp.os.win.windows7 3
de.comm.software.mailreader 2
comp.mobile.android 2
nl.comp.sys.android 2
nl.comp.os.linux.techniek 2
uk.rec.cycling.moderated 1
hr.ponuda.hardver.komponente 1
TOTAL: 12 175

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

GroupsCount
bit.listserv.catholic 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 858
Binary in non-binary group 200
EMP (phl) 73
Scoring filter (8) 24
Scoring filter (16) 12
Too many newsgroups 8
Scoring filter (9) 7
HTML post 5
EMP (fsl) 4
Scoring filter (10) 4
Scoring filter (11) 3
Scoring filter (18) 2
Scoring filter (12) 1
Angle-bracket bot 1
TOTAL: 14 1202

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 475
news.nask.pl 125
news.icm.edu.pl 2
TOTAL: 3 602
TOTAL: 1 602

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nask 289171 123666 95894 37 0 7 42%23:59:57
2panorama 244264 1041 214626 237 6 246542 0%23:59:57
3poznan 7047 57 5080 25 0 1 0%23:59:57
4icm 271188 36 214715 17755 0 2 0%23:59:57
5okapi 0 0 0 0 0 1146 0%23:57:50
TOTAL: 5 811670 124800 530315 18054 6 247698 15%119:57:38
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nask65.8 GB14.8 MB65.8 GB799.0 KB/s558.0 KB23:59:57
2panorama18.9 MB6.3 MB25.2 MB0.3 KB/s20.2 KB23:59:57
3icm4.5 MB9.0 GB9.0 GB109.4 KB/s531.4 KB23:59:57
4poznan470.8 KB69.7 KB540.5 KB0.0 KB/s6.6 KB23:59:57
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:50
TOTAL: 565.8 GB9.0 GB74.9 GB181.8 KB/s549.6 KB119:57:38
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 4237.1 KB 20 0 000:00:22
2isvcs01.man.lodz.pl 5 00.0 KB 0 5 000:00:01
TOTAL: 2 293 4237.1 KB 20 5 000:00:30

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 581 4237.1 KB 20 5 000:00:28
2unresolved 96 00.0 KB 0 0 000:00:01
TOTAL: 2 677 4237.1 KB 20 5 000:00:30

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
web.man.lodz.pl 1.472 7.600 0.00000:00:22
mon.man.lodz.pl 1.348 6.896 0.00000:00:04
isvcs01.man.lodz.pl 0.040 0.132 0.00000:00:01
212.51.192.185 0.368 2.160 0.00000:00:01
122.228.10.50 0.000 0.040 0.00000:00:00
TOTAL: 5 3.228 16.828 0.00000:00:30

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
212.51.192.185 95
122.228.10.50 1
TOTAL: 3 384

NNRP unrecognized commands (by host):

SystemConn
122.228.10.50 5
TOTAL: 1 5

NNRP unrecognized commands (by command):

CommandCount
Connection: keep-alive 1
Accept: */* 1
GET / HTTP/1.1 1
Host: 212.191.2.21:119 1
User-Agent: Mozilla/5.0 (Macintosh; Inte... 1
TOTAL: 5 5

Newsgroup request counts (by category):

CategoryCountPct
1pl 42100.0%
TOTAL: 1 42100.0%

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

NewsgroupCount
1pl.comp.pecet 24
2pl.comp.os.ms-windows.winnt 18
TOTAL: 2 42