Daily Usenet report

Sep 15 04:15:02 -- Sep 16 04:15:01

Unknown entries from news log file:

First 18 / 18 lines (100.0%)

Sep 15 05:50:17 newsfeed overchan[1500065]: input line too long, skipping
Sep 15 06:45:52 newsfeed overchan[1500065]: input line too long, skipping
Sep 15 09:19:22 newsfeed overchan[1500065]: input line too long, skipping
Sep 15 10:30:43 newsfeed overchan[1500065]: input line too long, skipping
Sep 15 11:39:43 newsfeed overchan[1500065]: input line too long, skipping
Sep 15 12:20:05 newsfeed overchan[1500065]: input line too long, skipping
Sep 15 12:47:37 newsfeed nnrpd[1525573]: SERVER perl filter not defined
Sep 15 15:06:42 newsfeed overchan[1500065]: input line too long, skipping
Sep 15 15:24:56 newsfeed overchan[1500065]: input line too long, skipping
Sep 15 15:45:02 newsfeed overchan[1500065]: input line too long, skipping
Sep 15 17:00:05 newsfeed pgpverify[1537885]: pgpverify: /usr/bin/gpg1: no such file
Sep 15 17:00:06 newsfeed pgpverify[1537898]: pgpverify: /usr/bin/gpg1: no such file
Sep 15 18:16:57 newsfeed overchan[1500065]: input line too long, skipping
Sep 15 18:55:06 newsfeed overchan[1500065]: input line too long, skipping
Sep 15 19:47:19 newsfeed overchan[1500065]: input line too long, skipping
Sep 15 22:11:19 newsfeed nnrpd[1552901]: SERVER perl filter not defined
Sep 15 22:37:58 newsfeed nnrpd[1554180]: SERVER perl filter not defined
Sep 15 22:38:56 newsfeed nnrpd[1554189]: SERVER perl filter not defined

Log entries by program:

Program nameLines%LinesSize%Size
inn 580268 93.9%63.9 MB 93.1%
innfeed 25477 4.1%3.0 MB 4.4%
innd 8797 1.4%1.3 MB 1.9%
nnrpd 3520 0.6%337.4 KB 0.5%
controlchan 23 0.0%4.9 KB 0.0%
overchan 13 0.0%2.0 KB 0.0%
pgpverify 2 0.0%0.4 KB 0.0%
TOTAL: 7 618100 100.0%68.6 MB100.0%

History cache:

ReasonCount%Count
Do not exist 576193 99.7%
Negative hits 1560 0.3%
Positive hits 212 0.0%
Cache misses 37 0.0%
TOTAL: 4 578002 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.048 0.0%70 0.000 14.971 68.000
article cleanup00:00:04.084 0.0%579527 0.004 0.007 0.011
article logging00:00:12.629 0.0%579926 0.015 0.022 0.027
article parse00:07:29.626 0.5%108429784 0.003 0.004 0.006
article write00:04:31.157 0.3%579357 0.285 0.468 0.597
artlog/artcncl00:00:00.000 0.0%70 0.000 0.000 0.000
data move00:00:30.624 0.0%113181303 0.000 0.000 0.000
hisgrep/artcncl00:00:00.076 0.0%70 0.000 1.086 14.000
hishave/artcncl00:00:00.007 0.0%70 0.000 0.100 0.500
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:02:52.430 0.2%581267 0.156 0.297 1.112
history sync00:00:00.013 0.0%290 0.000 0.045 0.500
history write00:03:58.162 0.3%579518 0.248 0.411 2.814
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle22:29:37.919 93.7%108197735 0.548 0.748 1.323
nntp read00:22:58.896 1.6%108133651 0.011 0.013 0.016
overview write00:00:01.502 0.0%579357 0.000 0.003 0.006
perl filter00:32:29.848 2.3%579518 2.536 3.365 4.035
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:15.646 0.0%1169038 0.011 0.013 0.018
TOTAL: 24:00:07.13523:45:03.667 99.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.051 0.0%33390 0.000 0.002 0.014
article prepare00:00:00.001 0.0%8020 0.000 0.000 0.007
article read00:00:09.793 0.0%2025 0.000 4.836 23.500
backlog stats00:00:00.052 0.0%92293 0.000 0.001 0.004
callbacks00:00:00.000 0.0%26 0.000 0.000 0.000
data read00:00:00.474 0.0%43633 0.000 0.011 0.035
data write00:00:01.016 0.0%33720 0.004 0.030 0.071
idle23:54:57.110100.0%92293 456.843 932.867 1913.490
status file00:00:04.651 0.0%8383 0.367 0.555 1.677
TOTAL: 23:55:15.35423:55:13.148100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:34:31.815 99.6%2850 0.000 726.953304603.167
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:01.213 0.1%6246 0.000 0.194 34.286
readart00:00:00.027 0.0%79 0.000 0.342 26.000
TOTAL: 00:34:40.24800:34:33.055 99.7%----

Control commands to INND:

CommandNumber
flush 27
flushlogs 2
go 2
logmode 2
lowmark 1
mode 149
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 193

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
group-admin@isc.org002202
TOTAL002202

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 188 579756 579596 0 160 99%87:01:13
2news.icm.edu.pl 48 189 9 171 9 4%14:23:29
3localhost 1445 4 4 0 0 100%00:00:00
TOTAL: 3 1681 579949 579609 171 169 99%101:24:42
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl276.7 GB739.0 KB1.1 MB276.7 GB 99%500.5 KB
2news.icm.edu.pl801.8 KB0.0 KB194.2 KB996.0 KB 80%55.3 KB
3localhost5.3 KB0.0 KB0.0 KB5.3 KB100%1.3 KB
TOTAL: 3276.7 GB739.0 KB1.3 MB276.7 GB 99%500.4 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 15 04:15:02 - 04:59:59 15629 2.7% 5.798.7 GB 3.1%3376.73
Sep 15 05:00:00 - 05:59:59 20960 3.6% 5.8211.4 GB 4.1%3320.54
Sep 15 06:00:00 - 06:59:59 20733 3.6% 5.7611.5 GB 4.1%3335.65
Sep 15 07:00:00 - 07:59:59 21977 3.8% 6.1012.9 GB 4.6%3745.55
Sep 15 08:00:00 - 08:59:59 23262 4.0% 6.4612.5 GB 4.5%3650.64
Sep 15 09:00:00 - 09:59:59 22052 3.8% 6.1311.2 GB 4.0%3250.97
Sep 15 10:00:00 - 10:59:59 22099 3.8% 6.1411.2 GB 4.0%3259.15
Sep 15 11:00:00 - 11:59:59 19774 3.4% 5.4910.4 GB 3.8%3042.34
Sep 15 12:00:00 - 12:59:59 20202 3.5% 5.6110.7 GB 3.8%3108.29
Sep 15 13:00:00 - 13:59:59 22975 4.0% 6.3811.8 GB 4.3%3442.59
Sep 15 14:00:00 - 14:59:59 25313 4.4% 7.0311.3 GB 4.1%3285.30
Sep 15 15:00:00 - 15:59:59 25185 4.3% 7.0011.3 GB 4.1%3284.67
Sep 15 16:00:00 - 16:59:59 27196 4.7% 7.5512.0 GB 4.3%3482.12
Sep 15 17:00:00 - 17:59:59 24391 4.2% 6.7810.5 GB 3.8%3060.24
Sep 15 18:00:00 - 18:59:59 22656 3.9% 6.2910.4 GB 3.8%3032.80
Sep 15 19:00:00 - 19:59:59 24624 4.2% 6.8410.3 GB 3.7%2988.35
Sep 15 20:00:00 - 20:59:59 28060 4.8% 7.7911.5 GB 4.2%3363.09
Sep 15 21:00:00 - 21:59:59 22123 3.8% 6.1510.8 GB 3.9%3150.61
Sep 15 22:00:00 - 22:59:59 28793 5.0% 8.0013.1 GB 4.7%3806.82
Sep 15 23:00:00 - 23:59:59 38400 6.6% 10.6713.4 GB 4.8%3899.94
Sep 16 00:00:00 - 00:59:59 25293 4.4% 7.0311.9 GB 4.3%3465.50
Sep 16 01:00:00 - 01:59:59 24842 4.3% 6.9012.4 GB 4.5%3609.82
Sep 16 02:00:00 - 02:59:59 27625 4.8% 7.6712.9 GB 4.7%3768.51
Sep 16 03:00:00 - 03:59:59 19354 3.3% 5.3810.4 GB 3.7%3018.48
Sep 16 04:00:00 - 04:15:01 6112 1.1% 6.783.1 GB 1.1%3602.90
TOTAL: 23:59:59 579630 100.0% 6.71277.4 GB 100.0%3366.89
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 558 149 0 9 2 0 0 0 398
2news.icm.edu.pl 10 0 0 0 0 0 0 0 10
TOTAL: 2 568 149 0 9 2 0 0 0 408

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 106
alt.comp.software.seamonkey 12
it.tlc.cellulari.android 11
it.comp.os.win.windows10 7
comp.sys.raspberry-pi 6
it.comp.os.win.windows7 2
it.discussioni.energia 1
de.rec.sf.startrek 1
nl.comp.os.linux.techniek 1
comp.mobile.android 1
de.comp.lang.perl 1
TOTAL: 11 149

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

GroupsCount
pl.praca.oferowana 2
TOTAL: 1 2

INND Perl filter [Top 20]:

ReasonCount
Binary in non-binary group 9
TOTAL: 1 9

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 398
news.icm.edu.pl 1
TOTAL: 2 399
TOTAL: 1 399

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1icm 18478 1144 8685 157 0 0 6%23:59:56
2panorama 24002 217 9788 172 46 23265 0%23:59:36
3nask 0 0 0 0 0 7040 0%23:57:47
4poznan 8 0 5 0 0 0 0%00:50:27
5okapi 0 0 0 0 0 754 0%23:57:47
TOTAL: 5 42488 1361 18478 329 46 31059 3%96:45:33
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm5.0 MB13.3 MB18.3 MB0.2 KB/s14.4 KB23:59:56
2panorama799.3 KB3.3 MB4.1 MB0.0 KB/s10.8 KB23:59:36
3nask0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:47
4poznan0.0 KB0.0 KB0.0 KB0.0 KB/s000:50:27
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:47
TOTAL: 55.8 MB16.6 MB22.4 MB0.1 KB/s13.6 KB96:45:33
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 294 7888.4 KB 35 0 000:00:09
289-71-88-74.dynamic.chello.pl 3 12.0 KB 1 0 000:34:01
3isvcs01.man.lodz.pl 4 00.0 KB 0 4 000:00:00
TOTAL: 3 301 7990.4 KB 36 4 000:34:40

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 298 7888.4 KB 35 4 000:00:09
2*.dynamic.chello.pl 3 12.0 KB 1 0 000:34:01
3unresolved 115 00.0 KB 0 0 000:00:28
TOTAL: 3 416 7990.4 KB 36 4 000:34:40

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
89-71-88-74.dynamic.chello.pl 0.056 0.023 0.00000:34:01
45.79.173.230 0.008 0.000 0.00000:00:10
192.241.219.105 0.001 0.006 0.00000:00:09
web.man.lodz.pl 0.893 1.756 0.00000:00:09
178.128.127.227 0.017 0.039 0.00000:00:03
80.111.142.213 0.015 0.022 0.00000:00:01
50.31.21.8 0.000 0.021 0.00000:00:01
212.51.192.185 0.212 0.544 0.00000:00:01
209.126.151.118 0.011 0.007 0.00000:00:00
192.241.208.29 0.005 0.007 0.00000:00:00
isvcs01.man.lodz.pl 0.010 0.041 0.00000:00:00
185.246.208.55 0.005 0.005 0.00000:00:00
TOTAL: 12 1.233 2.471 0.00000:34:40

Curious NNRP server explorers [Top 20]:

SystemConn
212.51.192.185 95
178.128.127.227 8
80.111.142.213 4
50.31.21.8 2
209.126.151.118 2
185.246.208.55 1
192.241.208.29 1
45.79.173.230 1
192.241.219.105 1
TOTAL: 9 115

NNRP unrecognized commands (by host):

SystemConn
178.128.127.227 42
192.241.208.29 1
185.246.208.55 1
TOTAL: 3 44

NNRP unrecognized commands (by command):

CommandCount
Accept-Encoding: gzip, deflate 7
Accept: */* 7
Connection: keep-alive 7
Host: 212.191.2.21:119 7
User-Agent: Mozilla/5.0 (Windows NT 10.0... 6
GET /c/version.js HTTP/1.1 1
MGLNDD_212.191.2.21_119 1
GET /stream/live.php HTTP/1.1 1
GET /system_api.php HTTP/1.1 1
User-Agent: Roku/DVP-9.10 (289.10E04111A... 1
GET /flu/403.html HTTP/1.1 1
GET / HTTP/1.1 1
GET /stalker_portal/c/version.js HTTP/1.... 1
#003 1
GET /streaming/clients_live.php HTTP/1.1 1
TOTAL: 15 44

NNRP client timeouts [Top 20]:

SystemConnPeer
185.246.208.55 1 1
89-71-88-74.dynamic.chello.pl 1 0
TOTAL: 2 2 1

Newsgroup request counts (by category):

CategoryCountPct
1pl 78 98.7%
2lodman 1 1.3%
TOTAL: 2 79100.0%

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

NewsgroupCount
1pl.comp.os.ms-windows.winnt 44
2pl.comp.pecet 34
3lodman.info 1
TOTAL: 3 79