Re: Method server logging abnormally large amounts...

MikeFoster · ‎May 07, 2012

All,

Recently our method server has been logging abnormally large amounts of data - It will fill a 9MB MethodServer log file in about 1 minute often from the actions of a single user who is not even checking data in. Some days over 1 GB of data will be logged and on other days with similar activity levels there might be less than 20 MB.

All the extra data falls into these two categories apparently having to do with Pro/E units and PV thumbnails:

Fri 5/4/12 15:07:24: SimpleTaskDispatcher6: INFO : com.ptc.windchill.uwgm.proesrv.util.UwgmNDUtilities - UwgmNDUtilities.getQuantityType :: kg/m**3: units = kg/m**3; symbol map = {kg=1, m=-3}
Fri 5/4/12 15:07:24: SimpleTaskDispatcher6: WARN : com.ptc.windchill.uwgm.proesrv.util.X12NeutralDataHelper - X12NeutralDataHelper.createModelParameters :: DENSITY: valueType = uwgmdb::DataType::RealWithUnits, kg/m**3
Fri 5/4/12 15:07:24: SimpleTaskDispatcher6: WARN : com.ptc.windchill.uwgm.proesrv.util.UwgmNDUtilities - UwgmNDUtilities.parseUnit :: unitB lookupSymbol = kg
Fri 5/4/12 15:07:24: SimpleTaskDispatcher6: WARN : com.ptc.windchill.uwgm.proesrv.util.UwgmNDUtilities - UwgmNDUtilities.parseUnit :: unitB 2 = kg, pow = 1
Fri 5/4/12 15:07:24: SimpleTaskDispatcher6: WARN : com.ptc.windchill.uwgm.proesrv.util.UwgmNDUtilities - UwgmNDUtilities.parseUnit :: unitB lookupSymbol = m

Fri 5/4/12 15:07:25: SimpleTaskDispatcher6: WARN : com.ptc.windchill.uwgm.proesrv.rrc.RRCContentHelper - RRCContentHelper.fillContentInfo :: 5712-133-032.prt: Skipping content item with role - THUMBNAIL3D
Fri 5/4/12 15:07:25: SimpleTaskDispatcher6: WARN : com.ptc.windchill.uwgm.proesrv.rrc.RRCContentHelper - RRCContentHelper.fillContentInfo :: 5712-133-032.prt: Skipping content item with role - THUMBNAIL
Fri 5/4/12 15:07:25: SimpleTaskDispatcher6: WARN : com.ptc.windchill.uwgm.proesrv.rrc.RRCContentHelper - RRCContentHelper.fillContentInfo :: 5712-133-032.prt: Skipping content item with role - THUMBNAIL_SMALL

Does anyone have any idea why this might occur?

I tried a PTC recommended setting:

xconfmanager -s log4j.logger.com.ptc.windchill.uwgm=ERROR -t C:\ptc\Windchill_9.1\Windchill\codebase\WEB-INF\log4jMethodServer.properties -p

But it didn't make any difference.

Thanks,

Mike Foster
ATK

jessh · ‎May 07, 2012

log4*.properties are not controlled by xconfmanager.

Also, apparently there is something odd about the handling of
com.ptc.windchill.uwgm.* loggers whereby INFO level is assumed for
com.ptc.windchill.uwgm unless log4jMethodServer.properties explicitly
sets the level to something /more /verbose than INFO. This means that
you will want to set the log level for child loggers to something less
verbose to rectify this, e.g. set the log level for
com.ptc.windchill.uwgm.proesrv to ERROR.

--
Jess Holle

On 5/7/2012 8:28 AM, Foster, Mike (Goleta) wrote:
>
> All,
>
> Recently our method server has been logging abnormally large amounts
> of data - It will fill a 9MB MethodServer log file in about 1 minute
> often from the actions of a single user who is not even checking data
> in. Some days over 1 GB of data will be logged and on other days with
> similar activity levels there might be less than 20 MB.
>
> All the extra data falls into these two categories apparently having
> to do with Pro/E units and PV thumbnails:
>
> Fri 5/4/12 15:07:24: SimpleTaskDispatcher6: INFO :
> com.ptc.windchill.uwgm.proesrv.util.UwgmNDUtilities -
> UwgmNDUtilities.getQuantityType :: kg/m**3: units = kg/m**3; symbol
> map = {kg=1, m=-3}
>
> Fri 5/4/12 15:07:24: SimpleTaskDispatcher6: WARN :
> com.ptc.windchill.uwgm.proesrv.util.X12NeutralDataHelper -
> X12NeutralDataHelper.createModelParameters :: DENSITY: valueType =
> uwgmdb::DataType::RealWithUnits, kg/m**3
>
> Fri 5/4/12 15:07:24: SimpleTaskDispatcher6: WARN :
> com.ptc.windchill.uwgm.proesrv.util.UwgmNDUtilities -
> UwgmNDUtilities.parseUnit :: unitB lookupSymbol = kg
>
> Fri 5/4/12 15:07:24: SimpleTaskDispatcher6: WARN :
> com.ptc.windchill.uwgm.proesrv.util.UwgmNDUtilities -
> UwgmNDUtilities.parseUnit :: unitB 2 = kg, pow = 1
>
> Fri 5/4/12 15:07:24: SimpleTaskDispatcher6: WARN :
> com.ptc.windchill.uwgm.proesrv.util.UwgmNDUtilities -
> UwgmNDUtilities.parseUnit :: unitB lookupSymbol = m
>
> Fri 5/4/12 15:07:25: SimpleTaskDispatcher6: WARN :
> com.ptc.windchill.uwgm.proesrv.rrc.RRCContentHelper -
> RRCContentHelper.fillContentInfo :: 5712-133-032.prt: Skipping content
> item with role - THUMBNAIL3D
>
> Fri 5/4/12 15:07:25: SimpleTaskDispatcher6: WARN :
> com.ptc.windchill.uwgm.proesrv.rrc.RRCContentHelper -
> RRCContentHelper.fillContentInfo :: 5712-133-032.prt: Skipping content
> item with role - THUMBNAIL
>
> Fri 5/4/12 15:07:25: SimpleTaskDispatcher6: WARN :
> com.ptc.windchill.uwgm.proesrv.rrc.RRCContentHelper -
> RRCContentHelper.fillContentInfo :: 5712-133-032.prt: Skipping content
> item with role - THUMBNAIL_SMALL
>
> Does anyone have any idea why this might occur?
>
> I tried a PTC recommended setting:
>
> xconfmanager -s log4j.logger.com.ptc.windchill.uwgm=ERROR -t
> C:\ptc\Windchill_9.1\Windchill\codebase\WEB-INF\log4jMethodServer.properties
> -p
>
> But it didn't make any difference.
>
> Thanks,
>
> Mike Foster
>
> ATK
>
>

nsanderson · ‎May 08, 2012

https://www.ptc.com/appserver/cs/view/case.jsp?n=10583668}
The ultimate solution was to restart the server. Since then, I have had no issues.

taruchamy · ‎Jun 18, 2015

Hi Ned,

I am facing the same issue.

I do not have access to the article, can you share the same in PDF format for my reference.

Thanks

Murthi

ChrisSpartz · ‎Jun 18, 2015

The resolution to the case in Ned's link was to restart Windchill. Though, that shouldn't be necessary if you can figure out which logger is causing the high amount of logging, and reset that back to it's default level. What sort of messages are you seeing filling up your MS log? Are they the same com.ptc.windchill.uwgm.proesrv that Mike was seeing? If so, try adding the line to log4jMethodServer.properties that Mike mentioned below.

taruchamy · ‎Jun 20, 2015

Hi Chris,

Thanks for the reply.

The errors message are

From Foreground Method server :

Mon 6/15/15 10:30:53: PublisherQueue1.PollingThread: readComponent: Unexpected tag type 255
Mon 6/15/15 10:30:53: PublisherQueue1.PollingThread: skipTagBitData: tagSize -1 tagReadCount 0
Mon 6/15/15 10:30:53: PublisherQueue1.PollingThread: readComponent: Unexpected tag type 255
Mon 6/15/15 10:30:53: PublisherQueue1.PollingThread: skipTagBitData: tagSize -1 tagReadCount 0
Mon 6/15/15 10:30:53: PublisherQueue1.PollingThread: readComponent: Unexpected tag type 255
Mon 6/15/15 10:30:53: PublisherQueue1.PollingThread: skipTagBitData: tagSize -1 tagReadCount 0

From Background Method server :

Mon 6/15/15 10:22:46: SimpleTaskDispatcher3: WARN : com.ptc.windchill.uwgm.proesrv.rrc.RRCContentHelper - RRCContentHelper.fillContentInfo :: 11002749_clamp_sketch.prt: Skipping content item with role - THUMBNAIL3D
Mon 6/15/15 10:22:46: SimpleTaskDispatcher3: WARN : com.ptc.windchill.uwgm.proesrv.rrc.RRCContentHelper - RRCContentHelper.fillContentInfo :: ___5479289_____.prt: Skipping content item with role - THUMBNAIL3D
Mon 6/15/15 10:22:46: SimpleTaskDispatcher3: WARN : com.ptc.windchill.uwgm.proesrv.rrc.RRCContentHelper - RRCContentHelper.fillContentInfo :: ___5479289_____.prt: Skipping content item with role - THUMBNAIL
Mon 6/15/15 10:22:46: SimpleTaskDispatcher3: WARN : com.ptc.windchill.uwgm.proesrv.rrc.RRCContentHelper - RRCContentHelper.fillContentInfo :: ___5479289_____.prt: Skipping content item with role - THUMBNAIL_SMALL
Mon 6/15/15 10:22:46: SimpleTaskDispatcher3: WARN : com.ptc.windchill.uwgm.proesrv.rrc.RRCContentHelper - RRCContentHelper.fillContentInfo :: ___5498393_____.prt: Skipping content item with role - THUMBNAIL
Mon 6/15/15 10:22:46: SimpleTaskDispatcher3: WARN : com.ptc.windchill.uwgm.proesrv.rrc.RRCContentHelper - RRCContentHelper.fillContentInfo :: ___5498393_____.prt: Skipping content item with role - THUMBNAIL_SMALL
Mon 6/15/15 10:22:46: SimpleTaskDispatcher3: WARN : com.ptc.windchill.uwgm.proesrv.rrc.RRCContentHelper - RRCContentHelper.fillContentInfo :: ___5498393_____.prt: Skipping content item with role - THUMBNAIL3D

After enabling the ERROR for the MS, still receiving the WARN message logs.

I was asked to change the client level recipe file to convert viewable for the 9.1 compatible version.

Yet to verify in the server.

Will keep posted.

Thanks

MikeFoster · ‎May 09, 2012

Thanks to Jess for the fix:

I added the following line to the log4jMethodServer.properties file in C:\ptc\Windchill_9.1\Windchill\codebase\WEB-INF and the excessive logging seems to have stopped:

log4j.logger.com.ptc.windchill.uwgm.proesrv=ERROR

Mike Foster

Method server logging abnormally large amounts of data

Method server logging abnormally large amounts of data