Daily Usenet report

May 21 04:15:01 -- May 22 04:15:01

Unknown entries from news log file:

First 20 / 20 lines (100.0%)

May 21 05:21:50 newsfeed nnrpd[816049]: SERVER perl filter not defined
May 21 05:21:50 newsfeed nnrpd[816049]: SERVER perl filter not defined
May 21 06:26:43 newsfeed nnrpd[824054]: SERVER perl filter not defined
May 21 06:26:43 newsfeed nnrpd[824054]: SERVER perl filter not defined
May 21 10:42:25 newsfeed nnrpd[854885]: SERVER perl filter not defined
May 21 10:42:25 newsfeed nnrpd[854885]: SERVER perl filter not defined
May 21 12:37:07 newsfeed nnrpd[868482]: SERVER perl filter not defined
May 21 12:37:07 newsfeed nnrpd[868482]: SERVER perl filter not defined
May 21 12:38:12 newsfeed nnrpd[868632]: SERVER perl filter not defined
May 21 12:38:12 newsfeed nnrpd[868632]: SERVER perl filter not defined
May 21 12:42:43 newsfeed nnrpd[869235]: SERVER perl filter not defined
May 21 12:42:43 newsfeed nnrpd[869235]: SERVER perl filter not defined
May 21 12:47:14 newsfeed nnrpd[869590]: SERVER perl filter not defined
May 21 12:47:14 newsfeed nnrpd[869590]: SERVER perl filter not defined
May 21 15:46:24 newsfeed nnrpd[891198]: SERVER perl filter not defined
May 21 15:46:24 newsfeed nnrpd[891198]: SERVER perl filter not defined
May 21 15:51:19 newsfeed nnrpd[891907]: SERVER perl filter not defined
May 21 15:51:19 newsfeed nnrpd[891907]: SERVER perl filter not defined
May 21 16:00:07 newsfeed nnrpd[892810]: SERVER perl filter not defined
May 21 16:00:07 newsfeed nnrpd[892810]: SERVER perl filter not defined

Log entries by program:

Program nameLines%LinesSize%Size
inn 195551 82.6%21.7 MB 84.7%
innd 23114 9.8%2.2 MB 8.5%
nnrpd 16848 7.1%1.6 MB 6.1%
innfeed 1240 0.5%155.4 KB 0.6%
controlchan 2 0.0%0.1 KB 0.0%
overchan 2 0.0%0.3 KB 0.0%
TOTAL: 6 236757 100.0%25.6 MB100.0%

History cache:

ReasonCount%Count
Do not exist 389278 99.7%
Negative hits 1062 0.3%
Positive hits 102 0.0%
Cache misses 36 0.0%
TOTAL: 4 390478 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.234 0.0%164 0.000 1.427 46.000
article cleanup00:00:02.156 0.0%390858 0.000 0.006 0.014
article logging00:00:07.940 0.0%390884 0.003 0.020 0.035
article parse00:00:22.174 0.0%1319636 0.007 0.017 0.026
article write00:01:41.360 0.1%390852 0.205 0.259 0.543
artlog/artcncl00:00:00.000 0.0%54 0.000 0.000 0.000
data move00:00:01.750 0.0%2047012 0.000 0.001 0.003
hisgrep/artcncl00:00:00.036 0.0%218 0.000 0.165 1.000
hishave/artcncl00:00:00.000 0.0%54 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:35.716 0.0%392004 0.008 0.091 0.506
history sync00:00:00.032 0.0%668 0.000 0.048 0.667
history write00:00:48.958 0.0%390856 0.031 0.125 0.429
idle47:48:50.204 99.6%1590050 24.665 108.255 610.659
nntp read00:00:22.010 0.0%1268978 0.010 0.017 0.031
overview write00:00:01.042 0.0%390852 0.000 0.003 0.011
perl filter00:07:13.164 0.3%390856 0.722 1.108 1.676
python filter00:00:00.100 0.0%390876 0.000 0.000 0.004
site send00:00:09.038 0.0%794030 0.003 0.011 0.018
TOTAL: 48:01:10.70448:00:15.914100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.022 0.0%12476 0.000 0.002 0.059
article prepare00:00:00.000 0.0%792 0.000 0.000 0.000
article read00:00:00.002 0.0%198 0.000 0.010 0.500
backlog stats00:00:00.018 0.0%75664 0.000 0.000 0.006
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.356 0.0%34974 0.000 0.010 0.041
data write00:00:01.058 0.0%22568 0.000 0.047 0.162
idle47:58:34.290100.0%75664 1052.730 2282.648 7717.397
status file00:00:00.106 0.0%320 0.000 0.331 1.000
TOTAL: 47:58:36.61647:58:35.852100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle01:42:40.016 99.3%28532 0.000 215.898 4659.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:02.296 0.0%107972 0.000 0.021 0.500
readart00:00:02.024 0.0%19192 0.000 0.105 0.108
TOTAL: 01:43:23.86601:42:44.336 99.4%----

Control commands to INND:

CommandNumber
flush 54
flushlogs 4
go 4
logmode 4
lowmark 2
mode 298
name 4
pause 4
paused 4
reload 4
reserve 4
TOTAL: 11 386

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 152 390910 390908 0 2 99%71:28:14
2localhost 22 20 20 0 0 100%00:00:00
3news.icm.edu.pl 60 90 2 84 4 2%12:23:08
TOTAL: 3 234 391020 390930 84 6 99%83:51:22
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl4.5 GB22.5 KB0.0 KB4.5 GB 99%12.1 KB
2localhost33.9 KB0.0 KB0.0 KB33.9 KB100%1.7 KB
3news.icm.edu.pl22.4 KB0.0 KB293.2 KB315.6 KB 7%52.6 KB
TOTAL: 34.5 GB22.5 KB293.2 KB4.5 GB 99%12.1 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 21 04:15:01 - 04:59:59 4198 2.1% 1.5642.6 MB 1.8% 16.15
May 21 05:00:00 - 05:59:59 7227 3.7% 2.0189.5 MB 3.9% 25.46
May 21 06:00:00 - 06:59:59 5428 2.8% 1.5156.9 MB 2.5% 16.18
May 21 07:00:00 - 07:59:59 5471 2.8% 1.5266.4 MB 2.9% 18.90
May 21 08:00:00 - 08:59:59 7522 3.8% 2.0994.0 MB 4.1% 26.75
May 21 09:00:00 - 09:59:59 9987 5.1% 2.77104.0 MB 4.5% 29.58
May 21 10:00:00 - 10:59:59 10459 5.3% 2.91114.1 MB 5.0% 32.44
May 21 11:00:00 - 11:59:59 13161 6.7% 3.66147.7 MB 6.4% 42.02
May 21 12:00:00 - 12:59:59 8769 4.5% 2.4489.8 MB 3.9% 25.53
May 21 13:00:00 - 13:59:59 7477 3.8% 2.0877.5 MB 3.4% 22.06
May 21 14:00:00 - 14:59:59 7607 3.9% 2.1191.9 MB 4.0% 26.13
May 21 15:00:00 - 15:59:59 7594 3.9% 2.1194.3 MB 4.1% 26.84
May 21 16:00:00 - 16:59:59 13276 6.8% 3.69209.8 MB 9.1% 59.68
May 21 17:00:00 - 17:59:59 12011 6.1% 3.34195.2 MB 8.5% 55.54
May 21 18:00:00 - 18:59:59 9549 4.9% 2.65109.1 MB 4.7% 31.04
May 21 19:00:00 - 19:59:59 9177 4.7% 2.5593.7 MB 4.1% 26.64
May 21 20:00:00 - 20:59:59 10732 5.5% 2.98116.7 MB 5.1% 33.20
May 21 21:00:00 - 21:59:59 7558 3.9% 2.1087.6 MB 3.8% 24.91
May 21 22:00:00 - 22:59:59 6429 3.3% 1.7965.7 MB 2.9% 18.70
May 21 23:00:00 - 23:59:59 8459 4.3% 2.3592.1 MB 4.0% 26.21
May 22 00:00:00 - 00:59:59 5049 2.6% 1.4052.6 MB 2.3% 14.96
May 22 01:00:00 - 01:59:59 3479 1.8% 0.9745.9 MB 2.0% 13.05
May 22 02:00:00 - 02:59:59 8778 4.5% 2.44101.0 MB 4.4% 28.72
May 22 03:00:00 - 03:59:59 4950 2.5% 1.3854.3 MB 2.4% 15.45
May 22 04:00:00 - 04:15:01 1161 0.6% 1.2911.5 MB 0.5% 13.09
TOTAL: 24:00:00 195508 100.0% 2.262.2 GB 100.0% 27.31
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 14 0 0 1 0 0 0 0 13
2news.icm.edu.pl 2 0 0 0 0 0 0 0 2
TOTAL: 2 16 0 0 1 0 0 0 0 15

INND Perl filter [Top 20]:

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

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 13
TOTAL: 1 13
TOTAL: 1 13

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1icm 22556 186 12132 12 0 144 0%47:59:56
2poznan 4 0 2 0 0 0 0%00:40:10
TOTAL: 2 22560 186 12134 12 0 144 0%48:40:06
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm844.0 KB49.7 KB893.7 KB0.0 KB/s4.5 KB47:59:56
2poznan0.0 KB0.0 KB0.0 KB0.0 KB/s000:40:10
TOTAL: 2844.0 KB49.7 KB893.7 KB0.0 KB/s4.5 KB48:40:06
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1syn-066-061-096-239.res.spectrum.com 40 1918421.2 MB 44 0 001:41:29
2172.16.2.4 576 814.5 KB 4 0 000:00:34
3isvcs01.man.lodz.pl 20 00.0 KB 0 20 000:00:01
TOTAL: 3 636 1919221.2 MB 48 20 001:43:24

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.res.spectrum.com 40 1918421.2 MB 44 0 001:41:29
2unresolved 794 814.5 KB 4 0 000:01:22
3*.man.lodz.pl2896 00.0 KB 0 20 000:00:32
TOTAL: 33730 1919221.2 MB 48 20 001:43:24

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
syn-066-061-096-239.res.spectrum.com 1.178 2.542 0.00001:41:29
172.16.2.4 2.110 3.912 0.00000:00:34
nms2.man.lodz.pl 10.386 18.932 0.00000:00:31
199.45.154.145 0.126 0.054 0.00000:00:13
198.235.24.142 0.010 0.016 0.00000:00:09
167.94.145.96 0.104 0.060 0.00000:00:08
205.210.31.241 0.008 0.016 0.00000:00:05
80.111.142.213 0.036 0.048 0.00000:00:04
89.248.163.221 0.010 0.004 0.00000:00:03
212.51.192.185 0.652 1.290 0.00000:00:02
isvcs01.man.lodz.pl 0.140 0.208 0.00000:00:01
TOTAL: 11 14.760 27.082 0.00001:43:24

Curious NNRP server explorers [Top 20]:

SystemConn
nms2.man.lodz.pl 2876
212.51.192.185 192
80.111.142.213 8
167.94.145.96 6
199.45.154.145 6
89.248.163.221 2
205.210.31.241 2
198.235.24.142 2
TOTAL: 8 3094

NNRP unrecognized commands (by host) [Top 20]:

SystemConn
199.45.154.145 2
167.94.145.96 2
TOTAL: 2 4

NNRP unrecognized commands (by command) [Top 20]:

CommandCount
EHLO www.censys.io 4
TOTAL: 1 4

NNRP client timeouts [Top 20]:

SystemConnPeer
167.94.145.96 2 4
199.45.154.145 2 4
TOTAL: 2 4 14

Newsgroup request counts (by hierarchy):

HierarchyCountPct
1lodman 18704 97.5%
2lodz 448 2.3%
3lodzman 32 0.2%
4pl 8 0.0%
TOTAL: 4 19192100.0%

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

NewsgroupCount
1lodman.info 17868
2lodman.test 836
3lodz.test 432
4lodzman.test 32
5lodz.ogloszenia 16
6pl.comp.os.ms-windows.winnt 8
TOTAL: 6 19192