7 Replies Latest reply on Jan 28, 2011 8:34 AM by animelov

    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)



        • Re: Profiler Agent not able to scan attachments in Exchange 2007
          animelov

          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+

              • Re: Profiler Agent not able to scan attachments in Exchange 2007

                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

                  • Re: Profiler Agent not able to scan attachments in Exchange 2007
                    animelov

                    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.

                      • Re: Profiler Agent not able to scan attachments in Exchange 2007

                        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