This discussion has been locked. The information referenced herein may be inaccurate due to age, software updates, or external references.
You can no longer post new replies to this discussion. If you have a similar question you can start a new discussion in this forum.

Profiler Agent not able to scan attachments in Exchange 2007

I have installed the agent on my exchange server.  We are running Server 2003 with Exchange Server 2007 SP2.  I am currently using my user account which has full admin rights on the exchange server to run the service.  When I look in the logs for the agent, I see the following error.

 

 

deviceType -> Exchange Server 2007

deviceName -> Exchange

serverName -> ms99exmail1.corp.company.org

status -> online

exchangeCluster -> false

attachmentAgeCats -> 7,30,90,180,365

aggregationFreqInSecs -> null

eaFrequency -> Daily

eaPublicFolders -> true

eaStartHour> -> 3

eaStartMin -> 5

esFreqInSecs -> 3600

exchStats -> true

exchVirtualName -> null

refreshMBRate -> 100

noattachments -> false

 

26 Jan 2011 15:12:47 - GPM Incoming Rules (PULL )

26 Jan 2011 15:12:47 - GenericParserModule.callhandleConditions(): Handling rules

26 Jan 2011 15:12:47 - Mail adding rule -> -1:100:size:0:0:

26 Jan 2011 15:12:47 - Mail query isNew value -> true

26 Jan 2011 15:12:47 - Mail adding rule -> -2:100:size:0:0::owners=Unknown

26 Jan 2011 15:12:47 - Mail query isNew value -> true

26 Jan 2011 15:12:47 - Mail adding rule -> null

26 Jan 2011 15:12:47 - Mail query isNew value -> true

26 Jan 2011 15:12:47 - Mail ruleID -> -1

26 Jan 2011 15:12:47 - Mail ruleID -> -2

26 Jan 2011 15:12:47 - PolicyEngineManager initializing the PolicyEngineManager with new Conditions

26 Jan 2011 15:12:47 - AppMailExchange module starting sub module in thread-->mod.sys.app.mail.Mail starter

26 Jan 2011 15:12:47 - AppMailExchange module done starting sub module from thread-->mod.sys.app.mail.Mail starter

26 Jan 2011 15:12:47 - ExchangeStorageWorker Gathering Exchange Storage data

26 Jan 2011 15:12:47 - WinExchange ExchangeDataGathering.exe storage params-->2

26 Jan 2011 15:12:47 -    WinExchange params[0]-->-servername=ms99exmail1.corp.company.org

26 Jan 2011 15:12:47 -    WinExchange params[1]-->-storage

26 Jan 2011 15:12:47 - ExchangeStorageWorker time taken to gather Exchange Storage data-->0 ms

26 Jan 2011 15:12:47 - Appending to environment vars: PATH=C:\Program Files\SolarWinds\Profiler Agent\systemic\mod.sys.app.mail.Mail_1.0 ===> PATH=C:\Program Files (x86)\Windows Resource Kits\Tools\;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\WINDOWS\system32\WindowsPowerShell\v1.0;C:\Program Files\NetApp\Windows Host Utilities\;C:\Program Files\NetApp\SnapDrive\;C:\Program Files\Microsoft\Exchange Server\bin;C:\Program Files\Microsoft\Exchange Server\Scripts;C:\Program Files\NetApp\SnapManager for Exchange\;C:\Program Files (x86)\McAfee\GroupShield for Exchange\bin\;C:\Program Files (x86)\McAfee\GroupShield for Exchange\bin\3rdParty\;C:\Program Files (x86)\ExchangeMapi\;C:/Program Files/SolarWinds/Profiler Agent/jre\bin;C:\Program Files\SolarWinds\Profiler Agent\systemic\mod.sys.app.mail.Mail_1.0

26 Jan 2011 15:12:47 - ExchangeAnalysisScheduler.run(): getNextTime (daily frequency) for exchange analysis -> Thu Jan 27 03:05:00 PST 2011

26 Jan 2011 15:12:47 - ExternalCommand requested to read from stderr stream for command-->C:\Program Files\SolarWinds\Profiler Agent\systemic\mod.sys.app.mail.Mail_1.0\ExchangeDataGathering.exe

26 Jan 2011 15:12:48 - WinExchange.handleErrorData(): Error msg from callback (ExchangeDataGathering_stores2007.0)-->ERROR *** AdsGetObject failed for ListStorageGroups-->-2147016656

26 Jan 2011 15:12:48 - GenericParserSubModule.handleUnparsedData(): line NOT parsed-->ERROR *** ListStorageGroups Binding String-->LDAP://CN=InformationStore,CN=ms99exmail1.corp.company.org,CN=Servers,CN=Exchange Administrative Group (FYDIBOHF23SPDLT),CN=Administrative Groups,CN=Kitsap Credit Union,CN=Microsoft Exchange,CN=Services,CN=Configuration,DC=kcu,DC=root

26 Jan 2011 15:12:48 - GenericParserSubModule.handleUnparsedData(): line NOT parsed-->END***

26 Jan 2011 15:12:48 - ExternalCommand successfully executed command (return code: 0)-->C:\Program Files\SolarWinds\Profiler Agent\systemic\mod.sys.app.mail.Mail_1.0\ExchangeDataGathering.exe

26 Jan 2011 15:12:48 - Parser.stopNonParsingParser(): non-parsing parser detected, attempting to stop parser

26 Jan 2011 15:12:48 - Parser.stopParser(): stopping parser for callback-->ExchangeDataGathering_stores2007.0

26 Jan 2011 15:12:48 -    ExternalCommand requested to stop process-->C:\Program Files\SolarWinds\Profiler Agent\systemic\mod.sys.app.mail.Mail_1.0\ExchangeDataGathering.exe

26 Jan 2011 15:12:48 -       ExternalCommand process (C:\Program Files\SolarWinds\Profiler Agent\systemic\mod.sys.app.mail.Mail_1.0\ExchangeDataGathering.exe) requested to stop.  Destroying process

26 Jan 2011 15:12:48 -    Parser.stopParser(): stopping ParserThread for callback-->ExchangeDataGathering_stores2007.0

26 Jan 2011 15:12:48 - Parser.stopNonParsingParser(): stopping parser for callback (ExchangeDataGathering_stores2007.0) since no data has been parsed

26 Jan 2011 15:12:48 - Parser.stopNonParsingParser(): Parser (ExchangeDataGathering_stores2007.0) stopped at: Wed Jan 26 15:12:48 PST 2011

26 Jan 2011 15:12:48 - Parser.stopNonParsingParser(): Parser stats

26 Jan 2011 15:12:48 -    Parser.stopNonParsingParser(): start time: Wed Jan 26 15:12:47 PST 2011

26 Jan 2011 15:12:48 -    Parser.stopNonParsingParser(): end time: Wed Jan 26 15:12:48 PST 2011

26 Jan 2011 15:12:48 -    Parser.stopNonParsingParser(): parser duration: 375 ms

26 Jan 2011 15:12:48 -    Parser.stopNonParsingParser(): number of times parser started: 1

26 Jan 2011 15:12:48 -    Parser.stopNonParsingParser(): number of lines parser attempted to parse: 2

26 Jan 2011 15:12:48 -    Parser.stopNonParsingParser(): was any data parsed: false

26 Jan 2011 15:12:48 -    Parser.stopNonParsingParser(): last line seen by parser that wasn't parsed-->END***

26 Jan 2011 15:12:48 -    Parser.stopNonParsingParser(): Exception:

com.tektools.agent.parser.ParserNotParsingException: Parser finished, but no data parsed

at com.tektools.agent.parser.Parser.startParserInThread(Parser.java)

at com.tektools.agent.parser.Parser.access$6500(Parser.java)

at com.tektools.agent.parser.Parser$ParserThread.run(Parser.java)

at java.lang.Thread.run(Unknown Source)



  • Maybe I should read through the entire board first :).

    What version of Profiler are you currently running?  There should be some exchange fixes for 4.12.3+

  • Had to bounce the server last night for some other stuff.  Today when it tried to run the attachment analysis I had some different alerts.  It looks as though Attachment Analysis for Public folders completed, however I still see no information under public folders in profiler.  I do still see that ttMailboxes2007.exe keeps getting requested to stop and I am not sure why.

     

    27 Jan 2011 09:50:29 - ExternalCommand successfully executed command (return code: 0)-->C:\Program Files\SolarWinds\Profiler Agent\systemic\mod.sys.app.mail.Mail_1.0\ttMailboxes2007.exe

    27 Jan 2011 09:50:30 - WinExchange Attachment Analysis for Private Stores completed

    27 Jan 2011 09:50:30 - ExchangeStatBean adding new ExchangeStat

    27 Jan 2011 09:50:30 - ExchangeStatBean       type-->Private Stores

    27 Jan 2011 09:50:30 - ExchangeStatBean       status-->Completed

    27 Jan 2011 09:50:30 - ExchangeStatBean       startTime-->null

    27 Jan 2011 09:50:30 - ExchangeStatBean       endTime-->1296150630011

    27 Jan 2011 09:50:30 - ExchangeStatBean       mailboxesAnalyzed-->2

    27 Jan 2011 09:50:30 - ExchangeStatBean       attachmentsAnalyzed-->0

    27 Jan 2011 09:50:30 - Parser.ParserStats.logStats(): stats for callback-->ExchangeDataGathering_private2007.1

    27 Jan 2011 09:50:30 -    Parser.ParserStats.logStats(): num of times parser started-->1

    27 Jan 2011 09:50:30 -    Parser.ParserStats.logStats(): num of lines seen by parser-->528

    27 Jan 2011 09:50:30 -    Parser.ParserStats.logStats(): was any data parsed-->true

    27 Jan 2011 09:50:30 -    Parser.ParserStats.logStats(): is data ignored-->false

    27 Jan 2011 09:50:30 -    Parser.ParserStats.logStats(): is parser done-->true

    27 Jan 2011 09:50:30 -    Parser.ParserStats.logStats(): total time spent parsing individual lines-->78 ms

    27 Jan 2011 09:50:30 -    Parser.ParserStats.logStats(): total parser duration-->29890 ms

    27 Jan 2011 09:50:30 - Parser.MultiLineStats.logStats(): stats for callback-->ExchangeDataGathering_private2007.1

    27 Jan 2011 09:50:30 -    Parser.MultiLineStats.logStats(): mlRemap duration-->0 ms

    27 Jan 2011 09:50:30 -    Parser.MultiLineStats.logStats(): mlJoin duration-->0 ms

    27 Jan 2011 09:50:30 - ***DEv-9031: after the mailbox analysis***

    27 Jan 2011 09:50:30 - Exchange Private Stores Mailbox Analysis took 29969 ms to complete.

    27 Jan 2011 09:50:30 - ExchangeStatBean adding new ExchangeStat

    27 Jan 2011 09:50:30 - ExchangeStatBean       type-->Public Folders

    27 Jan 2011 09:50:30 - ExchangeStatBean       status-->Queued

    27 Jan 2011 09:50:30 - ExchangeStatBean       startTime-->null

    27 Jan 2011 09:50:30 - ExchangeStatBean       endTime-->null

    27 Jan 2011 09:50:30 - ExchangeStatBean       mailboxesAnalyzed-->null

    27 Jan 2011 09:50:30 - ExchangeStatBean       attachmentsAnalyzed-->null

    27 Jan 2011 09:50:30 - WinExchange Attachment Age Categories-->7,30,90,180,365

    27 Jan 2011 09:50:30 - ExchangePublicFolderAnalyzer.cstr(): ageCats[I@2da5f83b

    27 Jan 2011 09:50:30 - ExchangePublicFolderAnalyzer.cstr(): num of rules-->2

    27 Jan 2011 09:50:30 - ExchangePublicFolderAnalyzer.cstr(): ruleID -> -1

    27 Jan 2011 09:50:30 - ExchangePublicFolderAnalyzer.cstr(): ruleID -> -2

    27 Jan 2011 09:50:30 - ExchangeStatBean adding new ExchangeStat

    27 Jan 2011 09:50:30 - ExchangeStatBean       type-->Public Folders

    27 Jan 2011 09:50:30 - ExchangeStatBean       status-->In progress

    27 Jan 2011 09:50:30 - ExchangeStatBean       startTime-->1296150630011

    27 Jan 2011 09:50:30 - ExchangeStatBean       endTime-->null

    27 Jan 2011 09:50:30 - ExchangeStatBean       mailboxesAnalyzed-->0

    27 Jan 2011 09:50:30 - ExchangeStatBean       attachmentsAnalyzed-->0

    27 Jan 2011 09:50:30 - WinExchange2007.startExchangePublicFoldersAnalysis(): ExchangeDataGathering.exe public folders params-->4

    27 Jan 2011 09:50:30 - WinExchange2007.startExchangePublicFoldersAnalysis():    params[0]-->-servername=ms99exmail1

    27 Jan 2011 09:50:30 - WinExchange2007.startExchangePublicFoldersAnalysis():    params[1]-->-mailbox

    27 Jan 2011 09:50:30 - WinExchange2007.startExchangePublicFoldersAnalysis():    params[2]-->-publicfolders

    27 Jan 2011 09:50:30 - WinExchange2007.startExchangePublicFoldersAnalysis():    params[3]-->

    27 Jan 2011 09:50:30 - Appending to environment vars: PATH=C:\Program Files\SolarWinds\Profiler Agent\systemic\mod.sys.app.mail.Mail_1.0 ===> PATH=C:\Program Files (x86)\Windows Resource Kits\Tools\;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\WINDOWS\system32\WindowsPowerShell\v1.0;C:\Program Files\NetApp\Windows Host Utilities\;C:\Program Files\NetApp\SnapDrive\;C:\Program Files\Microsoft\Exchange Server\bin;C:\Program Files\Microsoft\Exchange Server\Scripts;C:\Program Files\NetApp\SnapManager for Exchange\;C:\Program Files (x86)\McAfee\GroupShield for Exchange\bin\;C:\Program Files (x86)\McAfee\GroupShield for Exchange\bin\3rdParty\;C:\Program Files (x86)\ExchangeMapi\;C:/Program Files/SolarWinds/Profiler Agent/jre\bin;C:\Program Files\SolarWinds\Profiler Agent\systemic\mod.sys.app.mail.Mail_1.0

    27 Jan 2011 09:50:30 - ExternalCommand requested to read from stderr stream for command-->C:\Program Files\SolarWinds\Profiler Agent\systemic\mod.sys.app.mail.Mail_1.0\ttMailboxes2007.exe

    27 Jan 2011 09:50:30 - ExternalCommand successfully executed command (return code: 0)-->C:\Program Files\SolarWinds\Profiler Agent\systemic\mod.sys.app.mail.Mail_1.0\ttMailboxes2007.exe

    27 Jan 2011 09:50:30 - WinExchange Attachment Analysis for Public folders completed

    27 Jan 2011 09:50:30 - ExchangeStatBean adding new ExchangeStat

    27 Jan 2011 09:50:30 - ExchangeStatBean       type-->Public Folders

    27 Jan 2011 09:50:30 - ExchangeStatBean       status-->Completed

    27 Jan 2011 09:50:30 - ExchangeStatBean       startTime-->null

    27 Jan 2011 09:50:30 - ExchangeStatBean       endTime-->1296150630245

    27 Jan 2011 09:50:30 - ExchangeStatBean       mailboxesAnalyzed-->0

    27 Jan 2011 09:50:30 - ExchangeStatBean       attachmentsAnalyzed-->0

    27 Jan 2011 09:50:30 - Data ready for mod.sys.app.mail.Mail_1.0, sending notification to server

    27 Jan 2011 09:50:30 - Parser.stopNonParsingParser(): non-parsing parser detected, attempting to stop parser

    27 Jan 2011 09:50:30 - Parser.stopParser(): stopping parser for callback-->ExchangeDataGathering_public2007.2

    27 Jan 2011 09:50:30 -    ExternalCommand requested to stop process-->C:\Program Files\SolarWinds\Profiler Agent\systemic\mod.sys.app.mail.Mail_1.0\ttMailboxes2007.exe

    27 Jan 2011 09:50:30 -       ExternalCommand process (C:\Program Files\SolarWinds\Profiler Agent\systemic\mod.sys.app.mail.Mail_1.0\ttMailboxes2007.exe) requested to stop.  Destroying process

    27 Jan 2011 09:50:30 -    Parser.stopParser(): stopping ParserThread for callback-->ExchangeDataGathering_public2007.2

    27 Jan 2011 09:50:30 - Parser.stopNonParsingParser(): stopping parser for callback (ExchangeDataGathering_public2007.2) since no data has been parsed

    27 Jan 2011 09:50:30 - Parser.stopNonParsingParser(): Parser (ExchangeDataGathering_public2007.2) stopped at: Thu Jan 27 09:50:30 PST 2011

    27 Jan 2011 09:50:30 - Parser.stopNonParsingParser(): Parser stats

    27 Jan 2011 09:50:30 -    Parser.stopNonParsingParser(): start time: Thu Jan 27 09:50:30 PST 2011

    27 Jan 2011 09:50:30 -    Parser.stopNonParsingParser(): end time: Thu Jan 27 09:50:30 PST 2011

    27 Jan 2011 09:50:30 -    Parser.stopNonParsingParser(): parser duration: 219 ms

    27 Jan 2011 09:50:30 -    Parser.stopNonParsingParser(): number of times parser started: 1

    27 Jan 2011 09:50:30 -    Parser.stopNonParsingParser(): number of lines parser attempted to parse: 0

    27 Jan 2011 09:50:30 -    Parser.stopNonParsingParser(): was any data parsed: false

    27 Jan 2011 09:50:30 -    Parser.stopNonParsingParser(): last line seen by parser that wasn't parsed-->null

    27 Jan 2011 09:50:30 -    Parser.stopNonParsingParser(): Exception:

    com.tektools.agent.parser.ParserNotParsingException: Parser finished, but no data parsed

    at com.tektools.agent.parser.Parser.startParserInThread(Parser.java)

    at com.tektools.agent.parser.Parser.access$6500(Parser.java)

    at com.tektools.agent.parser.Parser$ParserThread.run(Parser.java)

    at com.tektools.agent.parser.Parser$ParserThread.startParserThread(Parser.java)

    at com.tektools.agent.parser.Parser$ParserThread.access$2500(Parser.java)

    at com.tektools.agent.parser.Parser.startParser(Parser.java)

    at com.tektools.agent.parser.ParserMgr.startParser(ParserMgr.java)

    at mod.sys.app.mail.exchange2007.WinExchange2007.startExchangePublicFoldersAnalysis(WinExchange2007.java)

    at mod.sys.app.mail.exchange.utils.ExchangeAnalysisScheduler.run(ExchangeAnalysisScheduler.java)

    27 Jan 2011 09:50:30 - Exchange Public Folders Analysis took 234 ms to complete.

    27 Jan 2011 09:50:30 - ExchangeAnalysisScheduler.run(): getNextTime (daily frequency) for exchange analysis -> Fri Jan 28 09:50:00 PST 2011

  • The agent that is gathering the exchange information, is it running as a domain admin that can see the private stores?  If so, then you can set the logs to debug mode (in the wrench icon for the Exchange portion in profiler), and that should tell you line by line what the output of those commands are.  Let me know if you see anything interesting.

  • Yes, the user that is currently running the service is an enterprise admin, domain admin, local server admin, and exchange admin.

     

    27 Jan 2011 15:15:28 - ExternalCommand successfully executed command (return code: 0)-->C:\Program Files\SolarWinds\Profiler Agent\systemic\mod.sys.app.mail.Mail_1.0\ExchangeDataGathering.exe

    27 Jan 2011 15:15:28 - GenericParserSubModule.handleUnparsedData(): line NOT parsed-->ERROR *** ListStorageGroups Binding String-->LDAP://CN=InformationStore,CN=ms99exmail1.corp.organization.org,CN=Servers,CN=Exchange Administrative Group (FYDIBOHF23SPDLT),CN=Administrative Groups,CN=Organization,CN=Microsoft Exchange,CN=Services,CN=Configuration,DC=kcu,DC=root

    27 Jan 2011 15:15:28 - GenericParserSubModule.handleUnparsedData(): line NOT parsed-->END***

    27 Jan 2011 15:15:28 - Parser.stopNonParsingParser(): non-parsing parser detected, attempting to stop parser

    27 Jan 2011 15:15:28 - Parser.stopParser(): stopping parser for callback-->ExchangeDataGathering_stores2007.0

    27 Jan 2011 15:15:28 -    ExternalCommand requested to stop process-->C:\Program Files\SolarWinds\Profiler Agent\systemic\mod.sys.app.mail.Mail_1.0\ExchangeDataGathering.exe

    27 Jan 2011 15:15:28 -       ExternalCommand process (C:\Program Files\SolarWinds\Profiler Agent\systemic\mod.sys.app.mail.Mail_1.0\ExchangeDataGathering.exe) requested to stop.  Destroying process

  • This is interesting.  It seems that it scanned 2 mailboxes but that is all it shows.

     

  • Strange. can you open a case with support to investigate further?  Getting on a GoTo might be easier at this point.