Daily Usenet report

Jul 25 04:15:02 -- Jul 26 04:15:01

Unknown entries from news log file:

First 21 / 21 lines (100.0%)

Jul 25 04:22:12 newsfeed overchan[1614481]: input line too long, skipping
Jul 25 04:24:45 newsfeed overchan[1614481]: input line too long, skipping
Jul 25 04:26:01 newsfeed overchan[1614481]: input line too long, skipping
Jul 25 04:27:15 newsfeed overchan[1614481]: input line too long, skipping
Jul 25 04:29:04 newsfeed overchan[1614481]: input line too long, skipping
Jul 25 04:32:32 newsfeed overchan[1614481]: input line too long, skipping
Jul 25 04:34:41 newsfeed overchan[1614481]: input line too long, skipping
Jul 25 04:38:30 newsfeed overchan[1614481]: input line too long, skipping
Jul 25 04:40:44 newsfeed overchan[1614481]: input line too long, skipping
Jul 25 04:43:59 newsfeed overchan[1614481]: input line too long, skipping
Jul 25 11:13:15 newsfeed overchan[1614481]: input line too long, skipping
Jul 25 18:38:20 newsfeed overchan[1614481]: input line too long, skipping
Jul 25 18:49:23 newsfeed overchan[1614481]: input line too long, skipping
Jul 25 18:52:18 newsfeed overchan[1614481]: input line too long, skipping
Jul 25 18:54:46 newsfeed overchan[1614481]: input line too long, skipping
Jul 25 19:32:32 newsfeed overchan[1614481]: input line too long, skipping
Jul 25 19:36:17 newsfeed overchan[1614481]: input line too long, skipping
Jul 26 00:47:45 newsfeed overchan[1614481]: input line too long, skipping
Jul 26 00:49:03 newsfeed overchan[1614481]: input line too long, skipping
Jul 26 00:50:29 newsfeed overchan[1614481]: input line too long, skipping
Jul 26 00:54:11 newsfeed overchan[1614481]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn 83856 88.8%9.6 MB 90.0%
nnrpd 7657 8.1%762.5 KB 7.0%
innd 1598 1.7%198.7 KB 1.8%
innfeed 1341 1.4%130.7 KB 1.2%
overchan 22 0.0%2.1 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 6 94475 100.0%10.7 MB100.0%

History cache:

ReasonCount%Count
Do not exist 83336 98.4%
Negative hits 1039 1.2%
Positive hits 293 0.3%
Cache misses 18 0.0%
TOTAL: 4 84686 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.492 0.0%216 0.000 6.907 52.000
article cleanup00:00:00.664 0.0%83613 0.000 0.008 0.021
article logging00:00:02.404 0.0%83862 0.007 0.029 0.054
article parse00:00:03.693 0.0%433880 0.003 0.009 0.015
article write00:00:14.743 0.0%83438 0.065 0.177 2.564
artlog/artcncl00:00:00.003 0.0%216 0.000 0.014 1.000
data move00:00:00.654 0.0%625557 0.000 0.001 0.004
hisgrep/artcncl00:00:00.163 0.0%47 0.000 3.468 17.000
hishave/artcncl00:00:00.239 0.0%216 0.000 1.106 6.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:04:12.980 0.3%84903 1.475 2.980 33.722
history sync00:00:00.020 0.0%302 0.000 0.066 1.000
history write00:08:43.314 0.6%83592 4.362 6.260 35.127
hiswrite/artcncl00:00:00.818 0.0%169 0.000 4.840 13.667
idle23:43:57.955 98.9%509915 17.858 167.553 529.550
nntp read00:00:08.949 0.0%431341 0.010 0.021 0.033
overview write00:00:00.343 0.0%83438 0.000 0.004 0.015
perl filter00:02:11.712 0.2%83592 0.879 1.576 2.047
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:03.222 0.0%177896 0.010 0.018 0.033
TOTAL: 24:00:22.90223:59:43.368100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.028 0.0%11020 0.000 0.003 0.029
article prepare00:00:00.000 0.0%80 0.000 0.000 0.000
article read00:00:00.000 0.0%20 0.000 0.000 0.000
backlog stats00:00:00.038 0.0%48927 0.000 0.001 0.011
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.313 0.0%25733 0.000 0.012 0.042
data write00:00:00.693 0.0%14869 0.000 0.047 0.113
idle23:59:42.828100.0%48927 675.736 1765.545 4480.015
status file00:00:00.405 0.0%351 0.000 1.154 33.000
TOTAL: 23:59:45.18123:59:44.305100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:49:37.746 97.1%4211 0.000 707.135291161.857
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:01:07.845 2.2%19458 0.000 3.487 11.802
readart00:00:00.074 0.0%20 0.000 3.700 14.000
TOTAL: 00:51:05.93800:50:45.665 99.3%----

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 79 83499 83342 6 151 99%40:29:05
2news.icm.edu.pl 44 315 23 269 23 7%17:24:28
3localhost 1 0 0 0 0 0%00:00:00
TOTAL: 3 124 83814 83365 275 174 99%57:53:33
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl1.2 GB324.6 KB698.2 KB1.2 GB 99%15.1 KB
2news.icm.edu.pl1.0 MB98.3 KB470.7 KB1.6 MB 64%34.7 KB
3localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 31.2 GB423.0 KB1.1 MB1.2 GB 99%15.1 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jul 25 04:15:02 - 04:59:59 1447 1.7% 0.5419.7 MB 1.6% 7.49
Jul 25 05:00:00 - 05:59:59 3002 3.6% 0.8325.4 MB 2.1% 7.21
Jul 25 06:00:00 - 06:59:59 3217 3.9% 0.8945.1 MB 3.7% 12.83
Jul 25 07:00:00 - 07:59:59 2669 3.2% 0.7435.4 MB 2.9% 10.08
Jul 25 08:00:00 - 08:59:59 4325 5.2% 1.2077.3 MB 6.3% 21.99
Jul 25 09:00:00 - 09:59:59 3559 4.3% 0.9953.7 MB 4.4% 15.26
Jul 25 10:00:00 - 10:59:59 3221 3.9% 0.8935.8 MB 2.9% 10.19
Jul 25 11:00:00 - 11:59:59 2651 3.2% 0.7434.6 MB 2.8% 9.85
Jul 25 12:00:00 - 12:59:59 4361 5.2% 1.2175.1 MB 6.1% 21.35
Jul 25 13:00:00 - 13:59:59 4267 5.1% 1.19129.0 MB 10.5% 36.71
Jul 25 14:00:00 - 14:59:59 2655 3.2% 0.7431.5 MB 2.6% 8.96
Jul 25 15:00:00 - 15:59:59 2954 3.5% 0.8247.5 MB 3.9% 13.52
Jul 25 16:00:00 - 16:59:59 3609 4.3% 1.0045.3 MB 3.7% 12.90
Jul 25 17:00:00 - 17:59:59 4397 5.3% 1.2267.0 MB 5.5% 19.07
Jul 25 18:00:00 - 18:59:59 5017 6.0% 1.3982.3 MB 6.7% 23.42
Jul 25 19:00:00 - 19:59:59 4771 5.7% 1.3373.6 MB 6.0% 20.93
Jul 25 20:00:00 - 20:59:59 3830 4.6% 1.0650.8 MB 4.1% 14.46
Jul 25 21:00:00 - 21:59:59 3500 4.2% 0.9747.5 MB 3.9% 13.52
Jul 25 22:00:00 - 22:59:59 3628 4.4% 1.0142.9 MB 3.5% 12.19
Jul 25 23:00:00 - 23:59:59 4072 4.9% 1.1354.4 MB 4.4% 15.47
Jul 26 00:00:00 - 00:59:59 3537 4.3% 0.9841.7 MB 3.4% 11.86
Jul 26 01:00:00 - 01:59:59 3076 3.7% 0.8541.9 MB 3.4% 11.92
Jul 26 02:00:00 - 02:59:59 2386 2.9% 0.6630.2 MB 2.5% 8.58
Jul 26 03:00:00 - 03:59:59 2379 2.9% 0.6630.3 MB 2.5% 8.62
Jul 26 04:00:00 - 04:15:01 688 0.8% 0.768.9 MB 0.7% 10.06
TOTAL: 23:59:59 83218 100.0% 0.961.2 GB 100.0% 14.54
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 399 136 0 15 0 0 0 0 248
2news.icm.edu.pl 23 0 0 5 0 0 0 0 18
TOTAL: 2 422 136 0 20 0 0 0 0 266

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 60
comp.mobile.android 56
it.tlc.cellulari.android 8
alt.comp.software.firefox 4
it.comp.os.win.windows10 3
it.discussioni.energia 2
alt.comp.software.seamonkey 1
it.tlc.cellulari.iphone 1
comp.sys.raspberry-pi 1
TOTAL: 9 136

INND Perl filter [Top 20]:

ReasonCount
Binary in non-binary group 11
Scoring filter (8) 7
TOTAL: 2 18

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1icm 14646 18 10832 2 0 0 0%23:56:25
2nask 0 0 0 0 0 7635 0%23:57:50
3panorama 0 0 0 0 0 11026 0%23:57:50
4okapi 0 0 0 0 0 931 0%23:57:50
5poznan 29 0 22 0 0 0 0%03:08:00
TOTAL: 5 14675 18 10854 2 0 19592 0%98:57:55
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm71.0 KB4.2 KB75.3 KB0.0 KB/s3.8 KB23:56:25
2nask0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:50
3panorama0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:50
4okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:50
5poznan0.0 KB0.0 KB0.0 KB0.0 KB/s003:08:00
TOTAL: 571.0 KB4.2 KB75.3 KB0.0 KB/s3.8 KB98:57:55
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 1413.5 KB 7 0 000:00:09
2188.146.254.83 3 69.0 KB 3 0 000:16:37
TOTAL: 2 291 2022.5 KB 10 0 000:51:06

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl1728 1413.5 KB 7 0 000:00:23
2unresolved 105 69.0 KB 3 0 000:16:41
3*.dynamic.gprs.plus.pl 1 00.0 KB 0 0 000:33:58
4*.us-west-1.compute.amazonaws.com 2 00.0 KB 0 0 000:00:00
5*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
TOTAL: 51837 2022.5 KB 10 0 000:51:06

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
apn-46-215-143-185.dynamic.gprs.plus.pl 0.044 0.024 0.00000:33:58
188.146.254.83 0.105 0.116 0.00000:16:37
nms2.man.lodz.pl 2.729 24.220 0.00000:00:14
web.man.lodz.pl 0.657 5.188 0.00000:00:09
80.111.142.213 0.000 0.082 0.00000:00:02
commended.monitoring.internet-measurement.com 0.008 0.015 0.00000:00:01
212.51.192.185 0.184 1.648 0.00000:00:01
188.147.4.45 0.018 0.032 0.00000:00:00
ec2-3-101-226-186.us-west-1.compute.amazonaws.com 0.004 0.039 0.00000:00:00
? 0.000 0.018 0.00000:00:00
TOTAL: 10 3.749 31.382 0.00000:51:06

Curious NNRP server explorers [Top 20]:

SystemConn
nms2.man.lodz.pl 1440
212.51.192.185 96
80.111.142.213 4
188.147.4.45 2
ec2-3-101-226-186.us-west-1.compute.amazonaws.com 2
commended.monitoring.internet-measurement.com 1
apn-46-215-143-185.dynamic.gprs.plus.pl 1
TOTAL: 7 1546

NNRP gethostbyaddr failures [Top 20]:

SystemConn
? (can't getpeername) 1
TOTAL: 1 1

NNRP client timeouts [Top 20]:

SystemConnPeer
apn-46-215-143-185.dynamic.gprs.plus.pl 1 0
TOTAL: 1 1 0

Newsgroup request counts (by category):

CategoryCountPct
1pl 14 70.0%
2lodman 6 30.0%
TOTAL: 2 20100.0%

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

NewsgroupCount
1pl.comp.os.ms-windows.winnt 8
2pl.comp.pecet 6
3lodman.info 5
4lodman.test 1
TOTAL: 4 20