Daily Usenet report

Sep 9 04:15:02 -- Sep 10 04:15:01

Unknown entries from news log file:

First 15 / 15 lines (100.0%)

Sep 9 08:37:24 newsfeed overchan[230730]: input line too long, skipping
Sep 9 12:42:14 newsfeed overchan[230730]: input line too long, skipping
Sep 9 12:42:35 newsfeed overchan[230730]: input line too long, skipping
Sep 9 12:45:50 newsfeed overchan[230730]: input line too long, skipping
Sep 9 12:46:52 newsfeed overchan[230730]: input line too long, skipping
Sep 9 14:47:17 newsfeed overchan[230730]: input line too long, skipping
Sep 9 15:07:00 newsfeed overchan[230730]: input line too long, skipping
Sep 9 15:11:48 newsfeed overchan[230730]: input line too long, skipping
Sep 9 15:36:22 newsfeed overchan[230730]: input line too long, skipping
Sep 9 15:43:37 newsfeed overchan[230730]: input line too long, skipping
Sep 9 19:43:40 newsfeed overchan[230730]: input line too long, skipping
Sep 9 19:45:05 newsfeed overchan[230730]: input line too long, skipping
Sep 9 20:54:09 newsfeed overchan[230730]: input line too long, skipping
Sep 9 21:37:05 newsfeed overchan[230730]: input line too long, skipping
Sep 9 21:37:35 newsfeed overchan[230730]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn 278853 95.9%31.1 MB 96.1%
nnrpd 7629 2.6%753.9 KB 2.3%
innd 3001 1.0%387.2 KB 1.2%
innfeed 1346 0.5%149.4 KB 0.5%
overchan 16 0.0%1.7 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 6 290846 100.0%32.4 MB100.0%

History cache:

ReasonCount%Count
Do not exist 278422 98.4%
Negative hits 3828 1.4%
Positive hits 620 0.2%
Cache misses 44 0.0%
TOTAL: 4 282914 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.503 0.0%27 0.000 18.630 37.333
article cleanup00:00:01.309 0.0%278918 0.000 0.005 0.013
article logging00:00:04.560 0.0%278928 0.011 0.016 0.037
article parse00:00:08.196 0.0%1975586 0.003 0.004 0.012
article write00:00:30.697 0.0%278678 0.041 0.110 4.267
artlog/artcncl00:00:00.001 0.0%27 0.000 0.037 1.000
data move00:00:01.386 0.0%2632442 0.000 0.001 0.003
hisgrep/artcncl00:00:00.107 0.0%27 0.000 3.963 13.000
hishave/artcncl00:00:00.026 0.0%27 0.000 0.963 6.667
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:12:24.232 0.9%283335 0.564 2.627 35.981
history sync00:00:00.079 0.0%379 0.000 0.208 30.000
history write00:15:27.399 1.1%278861 1.284 3.326 36.941
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:27:19.539 97.7%2056645 0.617 41.057 287.338
nntp read00:00:17.184 0.0%1881517 0.005 0.009 0.029
overview write00:00:00.639 0.0%278678 0.000 0.002 0.008
perl filter00:03:49.739 0.3%278861 0.568 0.824 1.409
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:05.815 0.0%566758 0.006 0.010 0.022
TOTAL: 24:00:53.63324:00:11.411100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.025 0.0%9402 0.000 0.003 0.021
article prepare00:00:00.000 0.0%148 0.000 0.000 0.000
article read00:00:00.000 0.0%37 0.000 0.000 0.000
backlog stats00:00:00.026 0.0%49588 0.000 0.001 0.007
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.185 0.0%24412 0.000 0.008 0.033
data write00:00:00.631 0.0%15143 0.000 0.042 0.125
idle23:58:26.689100.0%49588 811.455 1740.475 4710.305
status file00:00:00.486 0.0%360 0.000 1.350 13.200
TOTAL: 23:58:28.83023:58:28.042100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:14.847 46.6%4163 0.000 3.566 1858.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.130 0.4%10067 0.000 0.013 0.333
readart00:00:00.000 0.0%2 0.000 0.000 0.000
TOTAL: 00:00:31.87600:00:14.977 47.0%----

Control commands to INND:

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 94 278504 278406 8 90 99%46:24:53
2news.icm.edu.pl 30 857 46 667 144 5%23:28:45
3localhost 1 0 0 0 0 0%00:00:00
TOTAL: 3 125 279361 278452 675 234 99%69:53:38
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl4.8 GB806.7 KB927.0 KB4.8 GB 99%18.1 KB
2news.icm.edu.pl1.3 MB109.4 KB2.7 MB4.0 MB 31%21.8 KB
3localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 34.8 GB916.1 KB3.6 MB4.8 GB 99%18.1 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 9 04:15:02 - 04:59:59 2646 0.9% 0.9829.0 MB 0.6% 11.00
Sep 9 05:00:00 - 05:59:59 3751 1.3% 1.0437.8 MB 0.8% 10.75
Sep 9 06:00:00 - 06:59:59 5466 2.0% 1.5270.8 MB 1.4% 20.13
Sep 9 07:00:00 - 07:59:59 6272 2.3% 1.7460.5 MB 1.2% 17.21
Sep 9 08:00:00 - 08:59:59 81732 29.3% 22.701.7 GB 34.7% 486.30
Sep 9 09:00:00 - 09:59:59 71492 25.7% 19.861.4 GB 29.2% 409.06
Sep 9 10:00:00 - 10:59:59 7548 2.7% 2.10133.9 MB 2.7% 38.07
Sep 9 11:00:00 - 11:59:59 5582 2.0% 1.5555.3 MB 1.1% 15.73
Sep 9 12:00:00 - 12:59:59 9709 3.5% 2.70187.2 MB 3.8% 53.25
Sep 9 13:00:00 - 13:59:59 6002 2.2% 1.6762.0 MB 1.3% 17.63
Sep 9 14:00:00 - 14:59:59 5288 1.9% 1.4780.5 MB 1.6% 22.91
Sep 9 15:00:00 - 15:59:59 5866 2.1% 1.6382.5 MB 1.7% 23.48
Sep 9 16:00:00 - 16:59:59 5898 2.1% 1.64101.1 MB 2.1% 28.75
Sep 9 17:00:00 - 17:59:59 6717 2.4% 1.87140.6 MB 2.9% 40.00
Sep 9 18:00:00 - 18:59:59 4929 1.8% 1.3762.9 MB 1.3% 17.90
Sep 9 19:00:00 - 19:59:59 5032 1.8% 1.4065.4 MB 1.3% 18.62
Sep 9 20:00:00 - 20:59:59 6366 2.3% 1.7776.8 MB 1.6% 21.85
Sep 9 21:00:00 - 21:59:59 5409 1.9% 1.5063.0 MB 1.3% 17.91
Sep 9 22:00:00 - 22:59:59 4929 1.8% 1.3765.5 MB 1.3% 18.62
Sep 9 23:00:00 - 23:59:59 5100 1.8% 1.4260.2 MB 1.2% 17.13
Sep 10 00:00:00 - 00:59:59 6442 2.3% 1.79116.9 MB 2.4% 33.26
Sep 10 01:00:00 - 01:59:59 4891 1.8% 1.3660.9 MB 1.2% 17.33
Sep 10 02:00:00 - 02:59:59 5592 2.0% 1.5571.6 MB 1.5% 20.36
Sep 10 03:00:00 - 03:59:59 4917 1.8% 1.3781.0 MB 1.6% 23.03
Sep 10 04:00:00 - 04:15:01 1000 0.4% 1.119.8 MB 0.2% 11.18
TOTAL: 23:59:59 278576 100.0% 3.224.8 GB 100.0% 58.35
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.icm.edu.pl 150 0 0 9 0 0 0 0 141
2newsfeed.pionier.net.pl 100 42 0 48 0 0 0 0 10
TOTAL: 2 250 42 0 57 0 0 0 0 151

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 23
comp.mobile.android 10
de.comp.office-pakete.ms-office.excel 3
de.comm.provider.telefonie 2
it.comp.dotnet 1
alt.comp.software.seamonkey 1
alt.comp.software.firefox 1
it.comp.os.win.windows10 1
TOTAL: 8 42

INND Perl filter [Top 20]:

ReasonCount
Scoring filter (10) 70
Binary in non-binary group 40
Scoring filter (8) 24
EMP (md5) 2
Scoring filter (16) 1
Scoring filter (13) 1
Scoring filter (11) 1
Scoring filter (14) 1
Scoring filter (9) 1
TOTAL: 9 141

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1icm 14887 25 9183 12 0 0 0%23:50:01
2nask 0 0 0 0 0 6748 0%23:50:02
3panorama 0 0 0 0 0 9373 0%23:50:01
4poznan 56 0 37 0 0 0 0%03:50:21
5okapi 0 0 0 0 0 631 0%23:50:01
TOTAL: 5 14943 25 9220 12 0 16752 0%99:10:26
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm92.4 KB175.1 KB267.5 KB0.0 KB/s7.2 KB23:50:01
2nask0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:02
3panorama0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:01
4poznan0.0 KB0.0 KB0.0 KB0.0 KB/s003:50:21
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:01
TOTAL: 592.4 KB175.1 KB267.5 KB0.0 KB/s7.2 KB99:10:26
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
panorama976.8 KB
TOTAL: 1976.8 KB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 21.3 KB 1 0 000:00:09
TOTAL: 1 288 21.3 KB 1 0 000:00:32

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl1728 21.3 KB 1 0 000:00:22
2*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
3*.ch1.censys-scanner.com 1 00.0 KB 0 0 000:00:00
4unresolved 104 00.0 KB 0 0 000:00:07
TOTAL: 41834 21.3 KB 1 0 000:00:32

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
nms2.man.lodz.pl 3.238 8.363 0.00000:00:13
web.man.lodz.pl 0.705 1.769 0.00000:00:09
80.111.142.213 0.007 0.022 0.00000:00:02
71.6.146.186 0.000 0.009 0.00000:00:02
nifty.monitoring.internet-measurement.com 0.000 0.008 0.00000:00:01
212.51.192.185 0.257 0.528 0.00000:00:01
207.90.244.2 0.003 0.003 0.00000:00:00
107.170.240.50 0.000 0.008 0.00000:00:00
scanner-04.ch1.censys-scanner.com 0.003 0.007 0.00000:00:00
45.227.254.49 0.000 0.007 0.00000:00:00
TOTAL: 10 4.213 10.724 0.00000:00:32

Curious NNRP server explorers [Top 20]:

SystemConn
nms2.man.lodz.pl 1440
212.51.192.185 96
80.111.142.213 4
45.227.254.49 1
nifty.monitoring.internet-measurement.com 1
207.90.244.2 1
107.170.240.50 1
scanner-04.ch1.censys-scanner.com 1
71.6.146.186 1
TOTAL: 9 1546

NNRP unrecognized commands (by host):

SystemConn
107.170.240.50 1
45.227.254.49 1
scanner-04.ch1.censys-scanner.com 1
TOTAL: 3 3

NNRP unrecognized commands (by command):

CommandCount
EHLO www.censys.io 1
MGLNDD_212.191.2.21_119 1
#003 1
TOTAL: 3 3

NNRP client timeouts [Top 20]:

SystemConnPeer
45.227.254.49 1 1
TOTAL: 1 1 1

Newsgroup request counts (by category):

CategoryCountPct
1pl 2100.0%
TOTAL: 1 2100.0%

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

NewsgroupCount
1pl.comp.pecet 2
TOTAL: 1 2