Video Screencast Help
Search Video Help Close Back
to help
New in the Rewards Catalog: Vouchers for "Symantec Technical Specialist" and "Symantec Certified Specialist" exams.

Clients disconnecting/reconnecting constantly

Updated: 21 May 2010 | 34 comments
BadAndy's picture
0 0 Votes
Login to vote

We made some IP address changes at our co-location in preparation for a company wide change. The IP address of our SEP server changed but all DNS entries have been updated and a majority of our computers connect just fine. Problem is that a lot of will connect/disconnect/connect/disconnect non stop, all day long. I have found no problems with settings. IP addresses are ok, sylink.xml lists the correct server name and IP address. It seemed like stopping and restarting the Symantec IIS web server seemed to let the clients connect but that only lasts about 15 minutes then start acting up again.  

Any suggestions on where to look next?

Comments

Jason1222's picture
16
Oct
2009
0 Votes 0
Login to vote

What server?

What is the server information?  (OS? Type (32 or 64)?
How do the computers that disconnect connect? Are they on a different subnet/IP range?
Any other changes having been made?
Version of SEPM?

BadAndy's picture
16
Oct
2009
0 Votes 0
Login to vote

Server 2003, 32bit They are

Server 2003, 32bit
They are on different subnets and IP range. (colo is 10.129.x.x now, rest of company is still 192.168.x.x)
Only other change has been upgrading management server to 11.0.5. Clients still running SEP version 11.0.4202 have the same issue.

Vikram Kumar-SAV to SEP's picture
16
Oct
2009
0 Votes 0
Login to vote

 IIS Logs might have

 IIS Logs might have something to say..

BadAndy's picture
16
Oct
2009
0 Votes 0
Login to vote

 Where are the logs located?

 Where are the logs located?

Vikram Kumar-SAV to SEP's picture
16
Oct
2009
0 Votes 0
Login to vote

 Start -run - logfiles   or

 Start -run - logfiles  
or WINDOWS\system32\Logfiles

before that i would suggest enable logging on secars
in the IIS manager -expand SEPM website -right click on secars-properties-check Log Visits.

Then in the log files directory
W3SVC1 ( for default website ) or W3SVCxxxxxx
there will be a dated file inside this folder.

http://service1.symantec.com/SUPPORT/ent-security.nsf/docid/2007090612034148

BadAndy's picture
16
Oct
2009
0 Votes 0
Login to vote

 Done.  Should I be looking

 Done. 

Should I be looking for any particular error messages?

Vikram Kumar-SAV to SEP's picture
16
Oct
2009
0 Votes 0
Login to vote

 htp://........./secars.dll

 htp://........./secars.dll  SMC 401 xx  or 500 etc 

BadAndy's picture
16
Oct
2009
0 Votes 0
Login to vote

 Only thing I see after the

 Only thing I see after the IP address of a machine I'm assuming is connecting is "Smc 200 0 1236"

Vikram Kumar-SAV to SEP's picture
16
Oct
2009
0 Votes 0
Login to vote

 hmm....200 means

 hmm....200 means communication is fine...but i think this is the log when client gets connected..
Any errors in exsecars.log ??
Program files\Sym...\Symantec Endpoint Protection Manager\data\inbox\log\exsecars.log

Ghent's picture
16
Oct
2009
0 Votes 0
Login to vote

Multiple Servers or Locations

Do you have muliple SEPM servers? Or multiple locations in a policy?
I have seen specific setups cause "endless sever/location switching". Usually it's caused by two SEPM servers that have different policies because they haven't replicated -- but it can happen for other reasons.

For additional information I would start with the exSecars.log (mentioned by Vikram) and the Sylink.log file on the client.
To enable the Sylink.log on the client, see:
http://service1.symantec.com/SUPPORT/ent-security.nsf/docid/2008041812561948

To enable the exSecars detailed logging, set this registry key:
[HKEY_LOCAL_MACHINE\SOFTWARE\Symantec\Symantec Endpoint Protection\SEPM]
"DebugLevel"="4"

And then restart IIS.

BadAndy's picture
16
Oct
2009
0 Votes 0
Login to vote

 There is only one SEPM

 There is only one SEPM server but there used to be two. I removed the secondary server some time ago but for some reason, the old server still shows a certificate or something in the sylink.xml file on all machines. Could that be messing with the policies? I have several locations set up by gateway addresses.

BadAndy's picture
16
Oct
2009
0 Votes 0
Login to vote

Also, I can ping the SEPM

Also, I can ping the SEPM server (by fqdn and ip address) and even telnet to it using the default port 8014. When I ran the SEP support tool, everything passed except it couldn't contact the SEPM server but for no obvious reason.

Ghent's picture
16
Oct
2009
0 Votes 0
Login to vote

Location Specific connection settings

Check if you have a location-specific connection setting in the policy. This can be a problem if you have multiple locations in a policy.
To check, Open Clients button --> Policy Tab.
At the bottom of each of your locations, there should be a blue bar with a + symbol that says "Location-specific connection settings:"
Open the plus symbol.
If the setting is Group-Push, or Group-Pull, it's fine. Move to the next location.
If you find a location that is in Local-Pull, Local-Push, or Standalone, then this could be a problem and you'll want to continue to investigate.

What we are looking for is a condition that makes the client switch to, say, Location B -- but once the client switches to that location some condition changes (such as the server connection) which makes the client unqualified to be in Location B. So it switches immediately back to Location A -- which qualifies it for Location B, etc. etc.

You can switch the group from "Local" to "Group" by click the blue "Task >>" link to the left and clicking the communication option.

See if that fixes the issue. If you do want a Local communication setting, perhaps it would be best to set them all to Push and then change the ones you want on Local one at a time to isolate which location causes the issue. Once you figure that out you can adjust the location switching rules for that location to fix the issue.

I'd put a screen shot in but I'm not near a server at the moment!

If all of the above does nothing for you, then it's time to start looking at the Sylink and exSecars log.
And about the 2nd certificate in the Sylink.xml file -- don't worry about it. It's left sort of like an "incase" you turn the other server back on. But it does not in and of itself contain a policy or in any way affect communication.

BadAndy's picture
20
Oct
2009
0 Votes 0
Login to vote

 All of the location specific

 All of the location specific settings are correct. None are set to Local.

Ghent's picture
20
Oct
2009
0 Votes 0
Login to vote

Down to the bottom

Then I'd say it's time to get those Sylink and exSecars logs and see what's happening.

As mentioned before,

For additional information I would start with the exSecars.log (mentioned by Vikram) and the Sylink.log file on the client.
To enable the Sylink.log on the client, see:
http://service1.symantec.com/SUPPORT/ent-security.nsf/docid/2008041812561948

To enable the exSecars detailed logging, set this registry key:
[HKEY_LOCAL_MACHINE\SOFTWARE\Symantec\Symantec Endpoint Protection\SEPM]
"DebugLevel"="4"
Open %SEPM Install Dir%\tomcat\etc\conf.properties
Add this line:
scm.log.loglevel=FINE
Save the file.

And then restart IIS.

You find the exSecars log file at %SEPM Install Dir%\data\inbox\log\exsecars.log

BadAndy's picture
22
Oct
2009
0 Votes 0
Login to vote

Here's what I got right after

Here's what I got right after enabling the sylink.log in the registry.

10/22 15:54:12 [3644] ~~~Sylink log started. (SEP Product Version in registry: 11.0.5002.333, Sylink File Version: 11.0.5002.301)

10/22 15:54:12 [3644] Stored HostGUID=FDBAB76B0A810A340048BC644189EFCA; outlen=16
10/22 15:54:12 [3644] <RestoreSettings>Stored UserGuid=0; outlen=2
10/22 15:54:12 [3644] <mfn_DecodeSSN>Sygate-SSN=938
10/22 15:54:12 [3644] <mfn_DecodeSSN>Read CSN=939
10/22 15:54:12 [3644] <mfn_DecodeSSN>Sygate-SSN=14
10/22 15:54:12 [3644] <mfn_DecodeSSN>Read CSN=15
10/22 15:54:12 [3644] Product Type=1,Major Ver=6,Minor Ver=1,Platform ID=2,OSType=17170690
10/22 15:54:12 [3644] OS=Windows 7 Ultimate Edition; number=6.1.7600
10/22 15:54:12 [3644] SyLinkCreateInstance => Instance created: 034803B8 Registry path: SOFTWARE\Symantec\Symantec Endpoint Protection\SMC\SYLINK
10/22 15:54:12 [3644] <GetOnlineNicInfo>:Netport Count=0
10/22 15:54:12 [3644] <GetOnlineNicInfo>:NicInfo
10/22 15:54:12 [3644] SyLinkCreateConfig => Created instance: 03469658
10/22 15:54:12 [3644] UseNewConfig => Created m_hNewConfig: 03469658
10/22 15:54:12 [3644] Importing ConfigObject: 033EEC28 into: 03469658
10/22 15:54:12 [3644] Importing ConfigObject: 033EEC28 into: 034683B8
10/22 15:54:12 [3644] <PostEvent>stopping...ignore event ID=EVENT_SYLINK_CONFIG_SETTING_CHANGED
10/22 15:54:12 [3644] SSA packageType is set as 105
10/22 15:54:12 [3644] SyLinkDeleteConfig => Deleting instance: 033EEC28
10/22 15:54:12 [3644] <SetHiStatus>HI status is changed to=3; reason=105; rule=Host Integrity check is disabled.
 Host Integrity policy has been disabled by the administrator.
10/22 15:54:12 [3644] SyLinkCreateConfig => Created instance: 036EE1D8
10/22 15:54:12 [3644] SetCurLocationName: Name is set to - Non-Otis Connection
10/22 15:54:12 [3644] SetCurLocationID: ID is set to - 9400E7C9C0A8811800C38EEFF27B01A9
10/22 15:54:12 [3644] SyLinkCreateConfig => Created instance: 0463B570
10/22 15:54:12 [3644] Importing ConfigObject: 0463B570 into: 03469658
10/22 15:54:12 [3644] Importing ConfigObject: 0463B570 into: 034683B8
10/22 15:54:12 [3644] <PostEvent>stopping...ignore event ID=EVENT_SYLINK_CONFIG_SETTING_CHANGED
10/22 15:54:12 [3644] SyLinkDeleteConfig => Deleting instance: 0463B570
10/22 15:54:13 [3644] SyLinkDeleteConfig => Deleting instance: 036EE1D8
10/22 15:54:13 [3644] <CSyLink::Start()>
10/22 15:54:13 [3644] <CSyLink::ImportConfigFile()>
10/22 15:54:13 [3644] CUpdateConfig::Dump
10/22 15:54:13 [3644] </CSyLink::ImportConfigFile()>
10/22 15:54:13 [3644] <GetDomainHostName>msz_DomainName is taken from szDomainName
10/22 15:54:13 [3644] <GetDomainHostName>DomainName (Final)=spunkmeyer.com
10/22 15:54:13 [3644] <HWID CSyLink::GetHardwareKey> Get Hardware ID
10/22 15:54:14 [3644] <HWID CSyLink::GetHardwareKey> Hardware ID assigned: A5FC356B3DB2E8DEADB0186873CBD6DA
10/22 15:54:14 [3644] <Start>Unable to create Session with 'User Proxy' settings - Proxy Server: Error Code: 87
10/22 15:54:14 [3644] <Start>Unable to create Session with 'No Proxies' settings - Error Code: 87
10/22 15:54:14 [3148] <HeartbeatThreadProc:>Thread is about to begin.. 
10/22 15:54:14 [3468] Successfully created the heartbeat thread
10/22 15:54:14 [3644] <Start>Started, contact SMS every 900 seconds
10/22 15:54:14 [3644] <PostEvent>going to post event=EVENT_SYLINK_CONFIG_SETTING_CHANGED
10/22 15:54:14 [3644] <PostEvent>done post event=EVENT_SYLINK_CONFIG_SETTING_CHANGED, return=0
10/22 15:54:14 [3644] </CSyLink::Start()>
10/22 15:54:14 [3644] <SetClientAuth>Received new User/Domain from SMC..  User: abrown User Domain: SPUNKMEYER
10/22 15:54:14 [3644] <SetClientAuth>Getting RDNS Domain Name (user domain in AD setup)..
10/22 15:54:14 [3644] <GetLoginRdnsDomain>DNS domain=SPUNKMEYER.COM
10/22 15:54:14 [3644] <SetClientAuth>Setting the User Domain to RDNS Domain .. 
10/22 15:54:14 [3644] <SetClientAuth>Logged in user info set to: SPUNKMEYER.COM/abrown
10/22 15:54:14 [3644] <SetClientAuth>Marking User Change Notify to redo registration.. 
10/22 15:54:14 [3148] <CheckHeartbeatTimer>====== Heartbeat loop starts at 15:54:14 ======
10/22 15:54:15 [3148] <GetOnlineNicInfo>:Netport Count=1
10/22 15:54:15 [3148] <GetOnlineNicInfo>:NicInfo<SSANICs><SSANIC Ip="192.168.145.20" Mac="00-21-86-9e-87-4b" Gateway="192.168.145.1" SubnetMask="255.255.255.0"/></SSANICs>
10/22 15:54:15 [3148] <CalcAgentHashKey>:CH=5322F62CC0A881180159A1970890FDA11CPL7885spunkmeyer.comA5FC356B3DB2E8DEADB0186873CBD6DA
10/22 15:54:15 [3148] <CalcAgentHashKey>:CHKey=0B5379118E45DCD95A99B6C71BC64296
10/22 15:54:15 [3148] <CalcAgentHashKey>:C=5322F62CC0A881180159A1970890FDA11CPL7885spunkmeyer.com
10/22 15:54:15 [3148] <CalcAgentHashKey>:CKey=60E66146113D5C0B5746C2E53928B817
10/22 15:54:15 [3148] <CalcAgentHashKey>:UCH=5322F62CC0A881180159A1970890FDA10abrownSPUNKMEYER.COMCPL7885spunkmeyer.comA5FC356B3DB2E8DEADB0186873CBD6DA
10/22 15:54:15 [3148] <CalcAgentHashKey>:UCHKey=1A6EF01B866B843A48B77AE989B7A6B7
10/22 15:54:15 [3148] <CalcAgentHashKey>:UC=5322F62CC0A881180159A1970890FDA10abrownSPUNKMEYER.COMCPL7885spunkmeyer.com
10/22 15:54:15 [3148] <CalcAgentHashKey>:UCKey=61C8D67F74EA7151614513AB78B9078E
10/22 15:54:15 [3148] <DoHeartbeat>HardwareID=A5FC356B3DB2E8DEADB0186873CBD6DA
10/22 15:54:15 [3148] <DoHeartbeat>CHKey=0B5379118E45DCD95A99B6C71BC64296
10/22 15:54:15 [3148] <DoHeartbeat>CKey=60E66146113D5C0B5746C2E53928B817
10/22 15:54:15 [3148] <DoHeartbeat>UCHKey=1A6EF01B866B843A48B77AE989B7A6B7
10/22 15:54:15 [3148] <DoHeartbeat>UCKey=61C8D67F74EA7151614513AB78B9078E
10/22 15:54:15 [3148] <DoHeartbeat> Set heartbeat event
10/22 15:54:15 [3148] Use new configuration
10/22 15:54:15 [3148] <RegHeartbeatProc>====== Reg Heartbeat loop starts at 15:54:15 ======
10/22 15:54:16 [3148] HEARTBEAT: Check Point 1
10/22 15:54:16 [3148] <GetFirstSEMServer> Selecting a random server
10/22 15:54:16 [3148] HEARTBEAT: Check Point 2
10/22 15:54:16 [3148] <PostEvent>going to post event=EVENT_SERVER_CONNECTING
10/22 15:54:16 [3280] SyLinkCreateConfig => Created instance: 032ED518
10/22 15:54:16 [3280] Importing ConfigObject: 034683B8 into: 032ED518
10/22 15:54:16 [3280] SyLinkDeleteConfig => Deleting instance: 032ED518
10/22 15:54:16 [3148] <PostEvent>done post event=EVENT_SERVER_CONNECTING, return=0
10/22 15:54:16 [3148] HEARTBEAT: Check Point 3
10/22 15:54:16 [3148] <RegHeartbeatProc>Setting the session timeout on Profile Session (Registration) to 30000
10/22 15:54:16 [3148] HEARTBEAT: Check Point 4
10/22 15:54:16 [3148] <RegHeartbeatProc>===Registration STAGE===
10/22 15:54:16 [3148] <MakeRegisterData:>logon id (domain/user)=SPUNKMEYER.COM/abrown
10/22 15:54:16 [3148] <MakeRegisterData:>XML data: <?xml version="1.0" encoding="UTF-8" ?><SSARegData NameSpace="rpc"><AgentInfo DomainID="5322F62CC0A881180159A1970890FDA1" AgentType="105" UserDomain="SPUNKMEYER.COM" LoginUser="abrown" ComputerDomain="spunkmeyer.com" ComputerName="CPL7885" PreferredGroup="My%20Company%5cDefault%20Group" PreferredMode="1" HardwareKey="A5FC356B3DB2E8DEADB0186873CBD6DA" SiteDomainName=""/>
 
<SSAHostInfo><NetworkIdentity UserDomain="SPUNKMEYER.COM" LogonUser="abrown" HostDomain="spunkmeyer.com" HostName="CPL7885" HostDesc="" />
 
<SSAProduct Version="11.0.5002.333" />
 
<SSAOS Version="6.1.7600" Desc="Windows%207%20Ultimate%20Edition" Type="17170690" ServicePack=""/>
 
<Processor ProcessorType="x86%20Family%206%20Model%2023%20Stepping%206" ProcessorClock="2527" ProcessorNum="2"/>
 
<Memory Size="3214962688"/>
 
<BIOS Version="LENOVO%20-%202070"/>
 
<TpmDevice Id="0"/>
 
<SSAProfile Version="5.0.0" SerialNumber="736E-10%2f17%2f2009%2015%3a34%3a23%20607"/>
 
<SSAIDS Version="" SerialNumber=""/>
 
<SSAUTC Bias="300" />
 
<DNSs><DNS Address="192.168.150.38"/><DNS Address="10.129.10.15"/></DNSs>
 
<DHCPServer Address="192.168.150.37"/><SSANICs><SSANIC Ip="192.168.145.20" Mac="00-21-86-9e-87-4b" Gateway="192.168.145.1" SubnetMask="255.255.255.0"/></SSANICs>
 
</SSAHostInfo>
 
</SSARegData>
10/22 15:54:16 [3148] <SyLink>[MakeRegisterData] registration Hardware Key=A5FC356B3DB2E8DEADB0186873CBD6DA
10/22 15:54:16 [3148] ************Reg CSN=16
10/22 15:54:16 [3148] <mfn_GenPostData (for Registration):>Request is: s_origin_length: 1270
 
s_session_id: A5FC356B3DB2E8DEADB0186873CBD6DA
 
Sygate-SSN: 16
 
<?xml version="1.0" encoding="UTF-8" ?><SSARegData NameSpace="rpc"><AgentInfo DomainID="5322F62CC0A881180159A1970890FDA1" AgentType="105" UserDomain="SPUNKMEYER.COM" LoginUser="abrown" ComputerDomain="spunkmeyer.com" ComputerName="CPL7885" PreferredGroup="My%20Company%5cDefault%20Group" PreferredMode="1" HardwareKey="A5FC356B3DB2E8DEADB0186873CBD6DA" SiteDomainName=""/>
 
<SSAHostInfo><NetworkIdentity UserDomain="SPUNKMEYER.COM" LogonUser="abrown" HostDomain="spunkmeyer.com" HostName="CPL7885" HostDesc="" />
 
<SSAProduct Version="11.0.5002.333" />
 
<SSAOS Version="6.1.7600" Desc="Windows%207%20Ultimate%20Edition" Type="17170690" ServicePack=""/>
 
<Processor ProcessorType="x86%20Family%206%20Model%2023%20Stepping%206" ProcessorClock="2527" ProcessorNum="2"/>
 
<Memory Size="3214962688"/>
 
<BIOS Version="LENOVO%20-%202070"/>
 
<TpmDevice Id="0"/>
 
<SSAProfile Version="5.0.0" SerialNumber="736E-10%2f17%2f2009%2015%3a34%3a23%20607"/>
 
<SSAIDS Version="" SerialNumber=""/>
 
<SSAUTC Bias="300" />
 
<DNSs><DNS Address="192.168.150.38"/><DNS Address="10.129.10.15"/></DNSs>
 
<DHCPServer Address="192.168.150.37"/><SSANICs><SSANIC Ip="192.168.145.20" Mac="00-21-86-9e-87-4b" Gateway="192.168.145.1" SubnetMask="255.255.255.0"/></SSANICs>
 
</SSAHostInfo>
 
</SSARegData>
10/22 15:54:16 [3148] <SendRegistrationRequest:>http://CAHQV302:8014 [encrypted data]
10/22 15:54:16 [3148] 15:54:16=>Send HTTP REQUEST
10/22 15:54:37 [3148] 15:54:37=>HTTP REQUEST sent
10/22 15:54:37 [3148] 15:54:37=>QUERY return code
10/22 15:55:07 [3148] <SendRegistrationRequest:>ERR to query SMS return code=-9
10/22 15:55:07 [3148] <SendRegistrationRequest:>LastError=12030
10/22 15:55:07 [3148] 15:55:7=>QUERY return code completed
10/22 15:55:07 [3148] <SendRegistrationRequest:>SMS return=0
10/22 15:55:07 [3148] <ParseHTTPStatusCode:>0=>Uninterpreted Status
10/22 15:55:07 [3148] <SendRegistrationRequest:>ERR to query content length
10/22 15:55:07 [3148] <SendRegistrationRequest:>Content Lenght => 
10/22 15:55:07 [3148] Throw Internet Exception, Error Code=12019;AH failed to read internet file
10/22 15:55:07 [3148] CInternetException: <SendRegistrationRequest:>: The handle is in the wrong state for the requested operation
 
 
10/22 15:55:07 [3148] <ScheduleNextUpdate>new scheduled heartbeat=32 seconds
10/22 15:55:07 [3148] <SendRegistrationRequest:>COMPLETED, returned 7
10/22 15:55:07 [3148] HEARTBEAT: Check Point 5.1
10/22 15:55:07 [3148] <ScheduleNextUpdate>new scheduled heartbeat=64 seconds
10/22 15:55:07 [3148] HEARTBEAT: Check Point 8
10/22 15:55:07 [3148] <PostEvent>going to post event=EVENT_SERVER_DISCONNECTED
10/22 15:55:07 [3148] <PostEvent>done post event=EVENT_SERVER_DISCONNECTED, return=0
10/22 15:55:07 [3148] <RegHeartbeatProc>====== Registration Procedure stops at 15:55:07 ======
10/22 15:55:07 [3148] HEARTBEAT: Check Point 10
10/22 15:55:08 [3148] HEARTBEAT: Check Point Complete
10/22 15:55:08 [3148] <RegHeartbeatProc>Done, Heartbeat=64seconds
10/22 15:55:08 [3148] HeartbeatProcFailed to get profile with proxy setting 1
10/22 15:55:08 [3148] <CheckHeartbeatTimer>====== Heartbeat loop stops at 15:55:08 ======
10/22 15:55:14 [2652] <CExpBackoff::CExpBackoff()>
10/22 15:55:14 [2652] </CExpBackoff::CExpBackoff()>
 
Ghent's picture
22
Oct
2009
0 Votes 0
Login to vote

The way it was installed

If Vikram is right, then this may happen because you automate the installation in such a way that the product is installed as the System user.
I don't reconize this error right away, but it appears to be saying, "I'm not even able to put data on the network -- something goes wrong before I send my first packet out."

BadAndy's picture
23
Oct
2009
0 Votes 0
Login to vote

 Can you be a bit more

 Can you be a bit more specific about "you automate installation in such a way that the product is installed as the System user"?

I have had a steady green dot since turning on my laptop this morning at 8am. In the system log it has not shown a single disconnection which is a bit unusual since it typically flickers quite often or just stays disconnected.

I will keep an eye on it and post a section from the sylink.log file if it disconnects again today. I can email the entire log since starting it yesterday to any of you to disect if you would like.

BadAndy's picture
23
Oct
2009
0 Votes 0
Login to vote

 Vikram, I checked the reg

 Vikram, I checked the reg key from that article and there is not a  GlobalUserOffline value.

BadAndy's picture
23
Oct
2009
0 Votes 0
Login to vote

 Here's some more of the log.

 Here's some more of the log. The green dot disappeared a little after 12pm and has not come back online yet.

10/23 12:07:38 [3148] <CheckHeartbeatTimer>====== Heartbeat loop starts at 12:07:38 ======
10/23 12:07:38 [3148] <GetOnlineNicInfo>:Netport Count=2
10/23 12:07:38 [3148] <GetOnlineNicInfo>:NicInfo<SSANICs><SSANIC Ip="192.168.145.20" Mac="00-21-86-9e-87-4b" Gateway="192.168.145.1" SubnetMask="255.255.255.0"/><SSANIC Ip="192.168.151.116" Mac="00-21-5d-89-fb-4c" Gateway="192.168.151.1" SubnetMask="255.255.255.0"/></SSANICs>
10/23 12:07:38 [3148] <CalcAgentHashKey>:CH=5322F62CC0A881180159A1970890FDA11CPL7885spunkmeyer.comA5FC356B3DB2E8DEADB0186873CBD6DA
10/23 12:07:38 [3148] <CalcAgentHashKey>:CHKey=0B5379118E45DCD95A99B6C71BC64296
10/23 12:07:38 [3148] <CalcAgentHashKey>:C=5322F62CC0A881180159A1970890FDA11CPL7885spunkmeyer.com
10/23 12:07:38 [3148] <CalcAgentHashKey>:CKey=60E66146113D5C0B5746C2E53928B817
10/23 12:07:38 [3148] <CalcAgentHashKey>:UCH=5322F62CC0A881180159A1970890FDA10abrownSPUNKMEYER.COMCPL7885spunkmeyer.comA5FC356B3DB2E8DEADB0186873CBD6DA
10/23 12:07:38 [3148] <CalcAgentHashKey>:UCHKey=1A6EF01B866B843A48B77AE989B7A6B7
10/23 12:07:38 [3148] <CalcAgentHashKey>:UC=5322F62CC0A881180159A1970890FDA10abrownSPUNKMEYER.COMCPL7885spunkmeyer.com
10/23 12:07:38 [3148] <CalcAgentHashKey>:UCKey=61C8D67F74EA7151614513AB78B9078E
10/23 12:07:38 [3148] <DoHeartbeat>HardwareID=A5FC356B3DB2E8DEADB0186873CBD6DA
10/23 12:07:38 [3148] <DoHeartbeat>CHKey=0B5379118E45DCD95A99B6C71BC64296
10/23 12:07:38 [3148] <DoHeartbeat>CKey=60E66146113D5C0B5746C2E53928B817
10/23 12:07:38 [3148] <DoHeartbeat>UCHKey=1A6EF01B866B843A48B77AE989B7A6B7
10/23 12:07:38 [3148] <DoHeartbeat>UCKey=61C8D67F74EA7151614513AB78B9078E
10/23 12:07:38 [3148] <DoHeartbeat> Set heartbeat event
10/23 12:07:38 [3148] Use new configuration
10/23 12:07:38 [3148] <CSyLink::IndexHeartbeatProc()>
10/23 12:07:38 [3148] <IndexHeartbeatProc> Got ConfigObject to proceed the operation.. pSylinkConfig: 034683B8
10/23 12:07:38 [3148] <IndexHeartbeatProc>====== Reg Heartbeat loop starts at 12:07:38 ======
10/23 12:07:39 [3148] HEARTBEAT: Check Point 1
10/23 12:07:39 [3148] Get First Server!
10/23 12:07:39 [3148] HEARTBEAT: Check Point 2
10/23 12:07:39 [3148] <PostEvent>going to post event=EVENT_SERVER_CONNECTING
10/23 12:07:39 [3148] <PostEvent>done post event=EVENT_SERVER_CONNECTING, return=0
10/23 12:07:39 [3148] HEARTBEAT: Check Point 3
10/23 12:07:39 [3148] <IndexHeartbeatProc>Setting the session timeout on Profile Session to 30000
10/23 12:07:39 [3148] HEARTBEAT: Check Point 4
10/23 12:07:39 [3148] <IndexHeartbeatProc>===Get Index STAGE===
10/23 12:07:39 [3148] ************CSN=968
10/23 12:07:39 [3148] <mfn_MakeGetIndexUrl:>Request is: action=12&hostid=FDBAB76B0A810A340048BC644189EFCA&chk=0B5379118E45DCD95A99B6C71BC64296&ck=60E66146113D5C0B5746C2E53928B817&uchk=1A6EF01B866B843A48B77AE989B7A6B7&uck=61C8D67F74EA7151614513AB78B9078E&hid=A5FC356B3DB2E8DEADB0186873CBD6DA&groupid=5322F62CC0A881180159A1970890FDA1&mode=0&hbt=1800&as=968&cn=[hex]43504C37383835&lun=[hex]6162726F776E&udn=[hex]5350554E4B4D455945522E434F4D
10/23 12:07:39 [3148] <GetIndexFileRequest:>http://10.129.10.52:8014/secars/secars.dll?h=7A05A1A4A0B901AF54DD1CF55E66A56BE8AA8B6E2953A56F64191AB9268D2346F8C4C86F2E3BCCE189E1DFADE741D677E13BB82EB567BE9350AA599B3452BF52EE5CD6D58D1DBF3E8E0D16DFA1D4504B4557C8189CC850F85A5EE854E5F4714DD868D7068050FD4B25A90C7BC4AE2494D636C497C74C0B4E8B52B3B5C717A5851D39619215C530A369834FDD787FE291354D00DC11A2A0930AFC9A33FDA0AC8C4B51BDE8B315686478481AEA979681ECD762E2A7D37795022D7C8D3DB1ACECA0AA13AD584C8595E65424E733F8EBCDD9AC95CE3CAC57EDEB88A5DA8CE91BCCEC01A0E842EF5D3624BA25BE583AFDF4ACDDBD964237A598CE8B4A3BA02A23DF0C26FEBFEF91219DE4BB0DFF75538269223D93FEBEFA786B137E773599B01A3F196C936433C98CD04440E0FA73B8F2913534CC8E6A6597FFF7952A4C3F620D254FE0AE135A0C63BF9A4D8EC85D3B0913CDD4627D40358E08DDE74098BC570771998AEB18768AE7BE9B73F7E5C22A9E962068EA4FC3CBB48AD507F42C0B7DA68B89E72C73D4B5B93682941EAEFBA7D8FEBD
10/23 12:07:39 [3148] 12:7:39=>Send HTTP REQUEST
10/23 12:07:51 [3148] 12:7:51=>HTTP REQUEST sent
10/23 12:07:51 [3148] <GetIndexFileRequest:>SMS return=200
10/23 12:07:51 [3148] <ParseHTTPStatusCode:>200=>200 OK
10/23 12:07:51 [3148] <FindHeader>Sem-HashKey:=>0B5379118E45DCD95A99B6C71BC64296
10/23 12:07:51 [3148] <FindHeader>Sem-LANSensor:=>0
10/23 12:07:51 [3148] <FindHeader>Sem-Signatue:=>11B7020384D8B08D58705E21B04C3CE8C6337973DFCCAA4148ACF2BFC6F0F19FE8D2A1D5854142E739B3EF9479CD36F4C130DF5529FBE5382E7B57B08A26DDE1197F283AD0B589E8F570D257ABE9A40BFAEAC7264CFE73D8DCF323F9435210EA57F195BE4BE731BCF07702AC96D51D808E2975C9B4860C241EC0183A4801A525
10/23 12:07:51 [3148] <mfn_DoGetIndexFile200>Content Lenght => 696
10/23 12:07:51 [3148] SignIf::VerifySignature(data, dataLen, sig, sigLen) => Verification Successful.. 
10/23 12:07:51 [3148] <mfn_DoGetIndexFile200>Index File: <?xml version="1.0" encoding="UTF-8" ?><GroupIndex SiteID="0E658A67C0A88118002FAFA23D019F6A" ServerID="C106F6F2C0A8811801E3C782EEC58793" GroupID="736E3DBDC0A88118007E794BA45F58CE" GroupCheckSum="842CEA790A323210858413531" LastModifiedTime = "23/10/2009 08:46:03"> <Profile Checksum="61E223D67DBD45A5787FC400ED2EC184" SerialNumber="736E-10/22/2009 11:51:52 868" LastModifiedTime="22/10/2009  11:51:57"/> <ConfigFile Checksum="DD02CFD412910C7CDE82A3FFD457355E" LastModifiedTime="22/10/2009  07:38:23"/> <IDSFile Checksum="703A0AE1B8EC84B36CDBAECB7E800283" LastModifiedTime="22/10/2009  11:51:57"/> <SylinkFile Checksum="19A10E71055AAEA8B5D434F3FDD8F4EE" LastModifiedTime="22/10/2009  11:51:57"/> <LSProfile Checksum="49F830BD6F63A79F557B41431D744FCA" SerialNumber ="736E-10/22/2009 11:51:52 868" LastModifiedTime ="22/10/2009  11:51:57"/>
<LiveUpdate>
<File Checksum="678825D48A8D685CE97DE733AC5D61C8" DeltaFlag="1" FullSize="59340249" LastModifiedTime="1256312705003" Moniker="{C60DC234-65F9-4674-94AE-62158EFCA433}" Seq="91023002"/>
  <File Checksum="154E5DC64694F0B2A103DE9540BC9EC0" DeltaFlag="1" FullSize="59531424" LastModifiedTime="1256312652000" Moniker="{1CD85198-26C6-4bac-8C72-5D34B025DE35}" Seq="91023002"/>
  <File Checksum="CB6C8B8942DE8592E7801E4A2D1DC16F" DeltaFlag="1" FullSize="650307" LastModifiedTime="1236865874038" Moniker="{ECCC5006-EF61-4c99-829A-417B6C6AD963}" Seq="2008021700"/>
</LiveUpdate>
</GroupIndex>
10/23 12:07:51 [3148] <mfn_DoGetIndexFile200>Parser Index File!
10/23 12:07:51 [3148] <GetIndexFileRequest:>RECEIVE STAGE COMPLETED
10/23 12:07:51 [3148] <GetIndexFileRequest:>COMPLETED
10/23 12:07:51 [3148] <IndexHeartbeatProc>GetIndexFile handling status: 0
10/23 12:07:51 [3148] <IndexHeartbeatProc>Switch Server flag=0
10/23 12:07:51 [3148] HEARTBEAT: Check Point 5.1
10/23 12:07:51 [3148] <IsInClientIPorOnLink> NextHop Not equal 10.129.10.52,return FALSE
10/23 12:07:51 [3148] <IsInClientIPorOnLink> NextHop is OnLink with 192.168.145.20,return TRUE
10/23 12:07:51 [3148] <mfn_GetOutIP> Next Hop IP is a local IP or ONLINK:192.168.145.20
10/23 12:07:51 [3148] <PostEvent>going to post event=EVENT_LU_REQUIRE_STATUS
10/23 12:07:51 [3148] <PostEvent>done post event=EVENT_LU_REQUIRE_STATUS, return=0
10/23 12:07:51 [3148] <mfn_LiveUpdate:> Agent returned closest matching seq: 91020051
10/23 12:07:51 [3148] <Add2LUFileList:>Adding LU Info to LU Download File List: {C60DC234-65F9-4674-94AE-62158EFCA433}91023002
10/23 12:07:51 [3148] <PostEvent>going to post event=EVENT_LU_REQUIRE_STATUS
10/23 12:07:51 [3148] <PostEvent>done post event=EVENT_LU_REQUIRE_STATUS, return=1
10/23 12:07:51 [3148] <mfn_LiveUpdate> EVENT_LU_REQUIRE_STATUS returned ERROR_SYSTEM_UNKNOWN - Ignore LU content. Moniker: {1CD85198-26C6-4bac-8C72-5D34B025DE35} Seq:91023002
10/23 12:07:51 [3148] <PostEvent>going to post event=EVENT_LU_REQUIRE_STATUS
10/23 12:07:51 [3148] <PostEvent>done post event=EVENT_LU_REQUIRE_STATUS, return=20
10/23 12:07:51 [3148] <mfn_PrepareLUContent:>Requesting LU Info for :  Moniker: {C60DC234-65F9-4674-94AE-62158EFCA433} Target Seq:91023002
10/23 12:07:51 [3148] ************CSN=969
10/23 12:07:51 [3148] <GetContentFileInfoUrl:> Request is: action=52&hostid=FDBAB76B0A810A340048BC644189EFCA&groupid=5322F62CC0A881180159A1970890FDA1&fn=[hex]7B43363044433233342D363546392D343637342D393441452D3632313538454643413433337D&cdn=[hex]43504C37383835&lun=[hex]6162726F776E&udn=[hex]5350554E4B4D455945522E434F4D&lu=6&luseq=91020051,91020006,91015050,&lulasttriedseq=&lutargetseq=91023002&lucontentstate=0
10/23 12:07:51 [3148] <mfn_RequestLUContentInfo:>URL: /secars/secars.dll?h=3B4F83888114BBE0FB8BC78F0B7F898CE8AA8B6E2953A56F64191AB9268D2346F8C4C86F2E3BCCE189E1DFADE741D677F0D4D1C4F749526D50F3D99619ECAA45C958F3B90E1732F7CD9ACC42AD5D9DB5AB372ED5E63FE3AA044E49D4EDDD26AB6A72585D10B06EA0DBDEF093D9EA384CC6FCE1CA96C151C26F38595A97503021E17CCBA769D3D03AEA4CC3FABBAC2BD45C5CB5E85D750640197E7EA869099A30CCAFFDA56698DC0EAF0D567F47E6D959E1689EDC4D741BB227855584B282A575EDBE5B5CDAF75BC8F9A9E165627DF28F2E6C997E1312432E472059D97FD0C9EF89621A1970F3B5069BA508A77AC76352B9A57566EF0DA51F06DF10694F9F11F900FA33CAFB14099B4B20AD22D106F71F1D607DD8CE3C10B98D8EF0D551A34DB5A542F65424ADBF232EE078FB685303A7514E819EBD2995F6E9C08925240323080E9787DB185AED97BDE9D87616E2BE5DE383F961892598CC2D1A2EE29B38C667A1884AA3CE9D26A93C220C2D17170CCC
10/23 12:07:51 [3148] <SendUrlAndReceiveResponse:>http://10.129.10.52:8014/secars/secars.dll?h=3B4F83888114BBE0FB8BC78F0B7F898CE8AA8B6E2953A56F64191AB9268D2346F8C4C86F2E3BCCE189E1DFADE741D677F0D4D1C4F749526D50F3D99619ECAA45C958F3B90E1732F7CD9ACC42AD5D9DB5AB372ED5E63FE3AA044E49D4EDDD26AB6A72585D10B06EA0DBDEF093D9EA384CC6FCE1CA96C151C26F38595A97503021E17CCBA769D3D03AEA4CC3FABBAC2BD45C5CB5E85D750640197E7EA869099A30CCAFFDA56698DC0EAF0D567F47E6D959E1689EDC4D741BB227855584B282A575EDBE5B5CDAF75BC8F9A9E165627DF28F2E6C997E1312432E472059D97FD0C9EF89621A1970F3B5069BA508A77AC76352B9A57566EF0DA51F06DF10694F9F11F900FA33CAFB14099B4B20AD22D106F71F1D607DD8CE3C10B98D8EF0D551A34DB5A542F65424ADBF232EE078FB685303A7514E819EBD2995F6E9C08925240323080E9787DB185AED97BDE9D87616E2BE5DE383F961892598CC2D1A2EE29B38C667A1884AA3CE9D26A93C220C2D17170CCC
10/23 12:07:51 [3148] 12:7:51=>Send HTTP REQUEST
10/23 12:07:54 [2652] SyLinkCreateConfig => Created instance: 031B04F0
10/23 12:07:54 [2652] Importing ConfigObject: 034683B8 into: 031B04F0
10/23 12:07:54 [2652] <LUThreadProc> Got ConfigObject to proceed the operation.. pSylinkConfig: 031B04F0
10/23 12:07:54 [2652] <CRandomDelay::CRandomDelay()>
10/23 12:07:54 [2652] Random delay window: 0hour 15min 0sec 
10/23 12:07:54 [2652] Computed random delay:0hour 4min 40sec 0millisec
10/23 12:07:54 [2652] </CRandomDelay::CRandomDelay()>
10/23 12:07:54 [2652] <LUThreadProc>Waiting for: 280000 milliseconds to start downloading LU contents
10/23 12:07:56 [3468] <CSyLink::mfn_DownloadNow()>
10/23 12:07:56 [3468] </CSyLink::mfn_DownloadNow()>
10/23 12:08:04 [3148] 12:8:4=>HTTP REQUEST sent
10/23 12:08:04 [3148] <SendUrlAndReceiveResponse:>SMS return=200
10/23 12:08:04 [3148] <ParseHTTPStatusCode:>200=>200 OK
10/23 12:08:04 [3148] <mfn_RequestLUContentInfo:>Received LU Info :  Moniker: {C60DC234-65F9-4674-94AE-62158EFCA433} Target Seq:91023002 Response header: HTTP/1.1 200 OK
 
Connection: close
 
Date: Fri, 23 Oct 2009 16:07:51 GMT
 
Server: Microsoft-IIS/6.0
 
X-Powered-By: ASP.NET
 
Content-Type: text/html
 
Content-Length:59340249
 
Sem-LUPath: /content/{C60DC234-65F9-4674-94AE-62158EFCA433}/91023002/Full.zip
 
Sem-LUFull: 1
 
Sem-LUDeltaBaseSeqName:
 
Sem-PackageType: 1
 
Sem-DeltaMethod: 1
 
 
 
 
10/23 12:08:04 [3148] <FindHeader>Sem-LUFull:=>1
10/23 12:08:04 [3148] <mfn_RequestLUContentInfo:>Sem-LUFull:1
10/23 12:08:04 [3148] <FindHeader>Sem-PackageType:=>1
10/23 12:08:04 [3148] <mfn_RequestLUContentInfo:>Sem-PackageType:1
10/23 12:08:04 [3148] <FindHeader>Sem-DeltaMethod:=>1
10/23 12:08:04 [3148] <FindHeader>Sem-LUPath:=>/content/{C60DC234-65F9-4674-94AE-62158EFCA433}/91023002/Full.zip
10/23 12:08:04 [3148] <FindHeader>Content-Length:=>59340249
10/23 12:08:04 [3148] <UpdateLUFileList:>Updating existing Download File List with : {C60DC234-65F9-4674-94AE-62158EFCA433}91023002
10/23 12:08:04 [3148] <PostEvent>going to post event=EVENT_SERVER_ONLINE
10/23 12:08:04 [3148] <PostEvent>done post event=EVENT_SERVER_ONLINE, return=0
10/23 12:08:04 [3148] <ScheduleNextUpdate>Reset Heartbeat factor index, hearbeat=1800 seconds
10/23 12:08:04 [3148] HEARTBEAT: Check Point 6
10/23 12:08:04 [3148] <mfn_PostAgentInfo>===REQUESTING PLUG-IN OP-STATE: AVMan
10/23 12:08:04 [3148] <mfn_PostAgentInfo>===REQUESTING PLUG-IN OP-STATE: GUP
10/23 12:08:04 [3148] <mfn_PostAgentInfo>===REQUESTING PLUG-IN OP-STATE: LUMan
10/23 12:08:04 [3148] <mfn_PostAgentInfo>===REQUESTING CMC OP-STATE ===
10/23 12:08:04 [3148] <PostEvent>going to post event=EVENT_SERVER_REQUIRES_CLIENT_SESTATE
10/23 12:08:04 [3148] <PostEvent>done post event=EVENT_SERVER_REQUIRES_CLIENT_SESTATE, return=0
10/23 12:08:04 [3148] ReasonDescForFailure*** = Host Integrity check is disabled.
10/23 12:08:04 [3148] ReasonDescForFailure*** = Host Integrity check is disabled.
10/23 12:08:04 [3148] *** = <SSAInfo NameSpace="rpc" AgentID="FDBAB76B0A810A340048BC644189EFCA" ComputerID="9EA1C37A0A810A340048BC64ED9DBA4A" HardwareKey="A5FC356B3DB2E8DEADB0186873CBD6DA" GroupID="736E3DBDC0A88118007E794BA45F58CE">
 
<AgentHIInfo Status="3" ReasonCode="105" ReasonDescForFailure="Host Integrity check is disabled."/>
 
<SSAHostInfo>
 
<NetworkIdentity UserDomain="SPUNKMEYER.COM" LogonUser="abrown" HostDomain="spunkmeyer.com" HostName="CPL7885" HostDesc=""/>
 
<SSAProduct Version="11.0.5002.333"/>
 
<SSAOS Version="6.1.7600" Desc="Windows 7 Ultimate Edition" Type="17170690" ServicePack="" Language="9"/>
 
<Processor ProcessorType="x86 Family 6 Model 23 Stepping 6" ProcessorClock="2527" ProcessorNum="2"/>
 
<Memory Size="3214962688"/>
 
<Disk Letter="C:\" Size="199942467584"/>
 
<BIOS Version="LENOVO - 2070"/>
 
<TpmDevice Id="0"/>
 
<SSAProfile Version="5.0.0" SerialNumber="736E-10/22/2009 11:51:52 868"/>
 
<SSAIDS Version="" SerialNumber=""/>
 
<SSAUTC Bias="300"/>
 
<DNSs><DNS Address="192.168.150.38"/><DNS Address="192.168.129.39"/></DNSs>
 
<DHCPServer Address="192.168.151.1"/><SSANICs><SSANIC Ip="192.168.145.20" Mac="00-21-86-9e-87-4b" Gateway="192.168.145.1" SubnetMask="255.255.255.0"/><SSANIC Ip="192.168.151.116" Mac="00-21-5d-89-fb-4c" Gateway="192.168.151.1" SubnetMask="255.255.255.0"/></SSANICs><Firewall OnOff="0" Installed="0"/>
 
</SSAHostInfo>
 
<RebootRequired Status="0"></RebootRequired>
 
<InstalledFeatures><Feature Id ="256"/></InstalledFeatures>
 
</SSAInfo>
 
 
10/23 12:08:04 [3148] <mfn_PostAgentInfo>Volatile op-state damper: 0, Interval passed: 1827
10/23 12:08:04 [3148] <mfn_PostAgentInfo>Free memory difference: 185896960, Threshold: 311802465
10/23 12:08:04 [3148] <mfn_PostAgentInfo>Free disk space difference: 5947392, Threshold: 25316111145
10/23 12:08:04 [3148] <PostEvent>going to post event=EVENT_SYLINK_QUERY_COMMANDSTATUS
10/23 12:08:04 [3148] <PostEvent>done post event=EVENT_SYLINK_QUERY_COMMANDSTATUS, return=0
10/23 12:08:04 [3148] <IndexHeartbeatProc>===UPLOAD STAGE===
10/23 12:08:04 [3148] <PostEvent>going to post event=EVENT_SERVER_READY_TO_UPLOAD_EVENT_LOG
10/23 12:08:04 [3148] <PostEvent>done post event=EVENT_SERVER_READY_TO_UPLOAD_EVENT_LOG, return=0
10/23 12:08:04 [3148] <IndexHeartbeatProc>===PREPARE EVENT LOG STAGE===
10/23 12:08:04 [3148] <PrepareEventLog>initialized technology extension processing ok
10/23 12:08:04 [3148] <PrepareEventLog>Allow total logs to send=0
10/23 12:08:04 [3148] <IndexHeartbeatProc>Communication Mode=1(Pull mode)
10/23 12:08:04 [3148] HEARTBEAT: Check Point 8
10/23 12:08:04 [3148] <PostEvent>going to post event=EVENT_SERVER_DISCONNECTED
10/23 12:08:04 [3148] <PostEvent>done post event=EVENT_SERVER_DISCONNECTED, return=0
10/23 12:08:04 [3148] <IndexHeartbeatProc>====== IndexHeartbeat Procedure stops at 12:08:04 ======
10/23 12:08:04 [3148] <IndexHeartbeatProc>Set Heartbeat Result= 2
10/23 12:08:04 [3148] <IndexHeartbeatProc>Sylink Comm.Flags: 'Connection Failed' = 0, 'Using Backup Sylink' = 0, 'Using Location Config' = 0
10/23 12:08:04 [3148] Use new configuration
10/23 12:08:04 [3148] HEARTBEAT: Check Point Complete
10/23 12:08:04 [3148] <IndexHeartbeatProc>Done, Heartbeat=1800seconds
10/23 12:08:04 [3148] </CSyLink::IndexHeartbeatProc()>
10/23 12:08:04 [3148] <CheckHeartbeatTimer>====== Heartbeat loop stops at 12:08:04 ======
10/23 12:08:59 [3468] <CSyLink::mfn_DownloadNow()>
10/23 12:08:59 [3468] </CSyLink::mfn_DownloadNow()>
10/23 12:10:02 [3468] <CSyLink::mfn_DownloadNow()>
10/23 12:10:02 [3468] </CSyLink::mfn_DownloadNow()>
10/23 12:11:05 [3468] <CSyLink::mfn_DownloadNow()>
10/23 12:11:05 [3468] </CSyLink::mfn_DownloadNow()>
10/23 12:12:08 [3468] <CSyLink::mfn_DownloadNow()>
10/23 12:12:08 [3468] </CSyLink::mfn_DownloadNow()>
10/23 12:12:34 [2652] <LUThreadProc>Starting LU download.
10/23 12:12:34 [2652] SyLinkCreateConfig => Created instance: 031B1790
10/23 12:12:34 [2652] Importing ConfigObject: 034683B8 into: 031B1790
10/23 12:12:34 [2652] <UpdateGUPList>Setting the session timeout on GlobalItemsSession to 2 min.
10/23 12:12:34 [2652] ************CSN=970
10/23 12:12:34 [2652] <MakeGetGlobalIndexUrl:>Request is: action=310&hostid=FDBAB76B0A810A340048BC644189EFCA&chk=0B5379118E45DCD95A99B6C71BC64296&ck=60E66146113D5C0B5746C2E53928B817&uchk=1A6EF01B866B843A48B77AE989B7A6B7&uck=61C8D67F74EA7151614513AB78B9078E&groupid=5322F62CC0A881180159A1970890FDA1&as=970&cn=[hex]43504C37383835&lun=[hex]6162726F776E&udn=[hex]5350554E4B4D455945522E434F4D
10/23 12:12:34 [2652] <GetGlobalIndex:>http://10.129.10.52:8014/secars/secars.dll?h=FB8019813B03354E1244DDEB18AB73FA52063F0FD44D0653D79CD862B6DC8510ACF0BCAD07A723A2257177E3638CA5FE42AA17361BC686AB603E9C8B043DBCA189DE95700F73FC7A0AD78C21AE5CF882C94AFA0B1FA92DED17B9240C3ADB7DF754A7C5B6D016227AD21692AE24BE92E80902BB12CB184FFCFBE49A22B8402543FF1BB9A82C1ECC8147BE325AC60250D610A95B75909193C05DB2C85109A8FC9EDE46D14A733A21EF7DD42E142BDBE2D08BF886717B5B9446347F3A54692234DD3E62848263C602383C0C16F6648EA9693539625EE298B4F7CE17B262A6C3459836CE1FA1D3821206B95D4294E1B11548B404105653240D42FEF3A5B1EEA642A71C6F946D3DFE37F6C55517E43C62A88ECC2E5B6A2171F05F370F5367E53444BAEC47FCF1014E9C522DC4FDB281879DE1BAFA47416DFA0A9C8F775D3A7BC6701FD7275D98A623866ABE0C36E340A74B81
10/23 12:12:34 [2652] 12:12:34=>Send HTTP REQUEST
10/23 12:12:49 [2652] 12:12:49=>HTTP REQUEST sent
10/23 12:12:49 [2652] <GetGlobalIndex:>SMS return=200
10/23 12:12:49 [2652] <ParseHTTPStatusCode:>200=>200 OK
10/23 12:12:49 [2652] <FindHeader>Sem-Signatue:=>4000D69898BA264BFF79C2AAC2AF13944E79FDC3A5EA505623A9D203B5808059457BFE2F9F5360EAC979DA075D1696096256B8C857E85EAFB35B355A27FEE7095CC2870110E1B5F58D825980A8935548499A90CBB769AEE564209C9540F16D04AE79774A2319E3F10D0D0C0A201656D9BB84E4684172B635F6013891B5A63120
10/23 12:12:49 [2652] <DoGetGlobalIndex200>Content Lenght => 196
10/23 12:12:49 [2652] <DoGetGlobalIndex200>Got Global Index from server, read bytes=196
10/23 12:12:49 [2652] SignIf::VerifySignature(data, dataLen, sig, sigLen) => Verification Successful.. 
10/23 12:12:49 [2652] <DoGetGlobalIndex200>completed
10/23 12:12:49 [2652] <GetGlobalIndex:>RECEIVE STAGE COMPLETED
10/23 12:12:49 [2652] <GetGlobalIndex:>COMPLETED
10/23 12:12:49 [2652] ************CSN=971
10/23 12:12:49 [2652] <mfn_MakeGetGupListUrl:>Request is: action=320&hostid=FDBAB76B0A810A340048BC644189EFCA&chk=0B5379118E45DCD95A99B6C71BC64296&ck=60E66146113D5C0B5746C2E53928B817&uchk=1A6EF01B866B843A48B77AE989B7A6B7&uck=61C8D67F74EA7151614513AB78B9078E&groupid=5322F62CC0A881180159A1970890FDA1&as=971&cn=[hex]43504C37383835&lun=[hex]6162726F776E&udn=[hex]5350554E4B4D455945522E434F4D
10/23 12:12:49 [2652] <GetGupList:>http://10.129.10.52:8014/secars/secars.dll?h=B189ADFF12C850A66EBC023C2E7D4ECD52063F0FD44D0653D79CD862B6DC8510ACF0BCAD07A723A2257177E3638CA5FE42AA17361BC686AB603E9C8B043DBCA189DE95700F73FC7A0AD78C21AE5CF882C94AFA0B1FA92DED17B9240C3ADB7DF754A7C5B6D016227AD21692AE24BE92E80902BB12CB184FFCFBE49A22B8402543FF1BB9A82C1ECC8147BE325AC60250D610A95B75909193C05DB2C85109A8FC9EDE46D14A733A21EF7DD42E142BDBE2D08BF886717B5B9446347F3A54692234DD3E62848263C602383C0C16F6648EA9693539625EE298B4F7CE17B262A6C3459836CE1FA1D3821206B95D4294E1B1154899E8EDD51BFD46CC920A512BC01AABBA1C6F946D3DFE37F6C55517E43C62A88ECC2E5B6A2171F05F370F5367E53444BAEC47FCF1014E9C522DC4FDB281879DE1BAFA47416DFA0A9C8F775D3A7BC6701FD7275D98A623866ABE0C36E340A74B81
10/23 12:12:49 [2652] 12:12:49=>Send HTTP REQUEST
10/23 12:13:04 [2652] 12:13:4=>HTTP REQUEST sent
10/23 12:13:04 [2652] <GetGupList:>SMS return=200
10/23 12:13:04 [2652] <ParseHTTPStatusCode:>200=>200 OK
10/23 12:13:04 [2652] <mfn_DoGetGupList200>Content Lenght => 100
10/23 12:13:04 [2652] <mfn_DoGetGupList200>Got Gup List from server, read bytes=100
10/23 12:13:04 [2652] <mfn_DoGetGupList200>completed
10/23 12:13:04 [2652] <GetGupList:>RECEIVE STAGE COMPLETED
10/23 12:13:04 [2652] <GetGupList:>COMPLETED
10/23 12:13:04 [2652] SyLinkDeleteConfig => Deleting instance: 031B1790
10/23 12:13:04 [2652] <SetupTempLUFilePath:>NEW download: C:\Program Files\Symantec\Symantec Endpoint Protection\LiveUpdate\LUF{C60DC234-65F9-4674-94AE-62158EFCA433}910230021.TMP
10/23 12:13:04 [2652] <CHttpFileDownload::CHttpFileDownload()>
10/23 12:13:04 [2652] </CHttpFileDownload::CHttpFileDownload()>
10/23 12:13:04 [2652] <CHttpFileDownload::Do()>
10/23 12:13:04 [2652] <CHttpFileDownload::getRemainingBytesToDownload()>
10/23 12:13:04 [2652] Remaining bytes to download: 59340249
10/23 12:13:04 [2652] </CHttpFileDownload::getRemainingBytesToDownload()>
10/23 12:13:04 [2652] <CHttpConnector::SendRequest()>
10/23 12:13:04 [2652] Request> http://SCCPB301:2967/content/{C60DC234-65F9-4674-94AE-62158EFCA433}/91023002/Full.zip
10/23 12:13:04 [2652] SendRequest() failed.
10/23 12:13:04 [2652] </CHttpConnector::SendRequest()>
10/23 12:13:04 [2652] </CHttpFileDownload::Do()>
10/23 12:13:04 [2652] <LUDownloader::GetContentToFile> completed. 
10/23 12:13:04 [2652] <CHttpFileDownload::~CHttpFileDownload()>
10/23 12:13:04 [2652] </CHttpFileDownload::~CHttpFileDownload()>
10/23 12:13:04 [2652] <LUThreadProc>LU file download failed due to HTTP error:0
10/23 12:13:04 [2652] <CExpBackoff::Increment()>
10/23 12:13:04 [2652] Backoff index incremented
10/23 12:13:04 [2652] Backoff wait index: 1
10/23 12:13:04 [2652] </CExpBackoff::Increment()>
10/23 12:13:04 [2652] <CExpBackoff::Wait()>
10/23 12:13:04 [2652] CExpBackoff wait time in seconds: 32
10/23 12:13:12 [3468] <CSyLink::mfn_DownloadNow()>
10/23 12:13:12 [3468] </CSyLink::mfn_DownloadNow()>
10/23 12:13:37 [2652] </CExpBackoff::Wait()>
10/23 12:13:37 [2652] <IsLUTempFileValid:> File: C:\Program Files\Symantec\Symantec Endpoint Protection\LiveUpdate\LUF{C60DC234-65F9-4674-94AE-62158EFCA433}910220021.TMP is currently not used
10/23 12:13:37 [2652] <IsLUTempFileValid:> File: C:\Program Files\Symantec\Symantec Endpoint Protection\LiveUpdate\LUF{C60DC234-65F9-4674-94AE-62158EFCA433}910230021.TMP is currently used
10/23 12:14:15 [3468] <CSyLink::mfn_DownloadNow()>
10/23 12:14:15 [3468] </CSyLink::mfn_DownloadNow()>
10/23 12:14:36 [2652] SyLinkDeleteConfig => Deleting instance: 031B04F0
10/23 12:14:36 [2652] SyLinkCreateConfig => Created instance: 031B04F0
10/23 12:14:36 [2652] Importing ConfigObject: 034683B8 into: 031B04F0
10/23 12:14:36 [2652] <LUThreadProc> Got ConfigObject to proceed the operation.. pSylinkConfig: 031B04F0
10/23 12:14:36 [2652] <CRandomDelay::CRandomDelay()>
10/23 12:14:36 [2652] Random delay window: 0hour 15min 0sec 
10/23 12:14:36 [2652] Computed random delay:0hour 0min 28sec 0millisec
10/23 12:14:36 [2652] </CRandomDelay::CRandomDelay()>
10/23 12:14:36 [2652] <LUThreadProc>Waiting for: 28000 milliseconds to start downloading LU contents
10/23 12:15:04 [2652] <LUThreadProc>Starting LU download.
10/23 12:15:04 [2652] SyLinkCreateConfig => Created instance: 031B1790
10/23 12:15:04 [2652] Importing ConfigObject: 034683B8 into: 031B1790
10/23 12:15:04 [2652] <UpdateGUPList>Setting the session timeout on GlobalItemsSession to 2 min.
10/23 12:15:04 [2652] ************CSN=972
10/23 12:15:04 [2652] <MakeGetGlobalIndexUrl:>Request is: action=310&hostid=FDBAB76B0A810A340048BC644189EFCA&chk=0B5379118E45DCD95A99B6C71BC64296&ck=60E66146113D5C0B5746C2E53928B817&uchk=1A6EF01B866B843A48B77AE989B7A6B7&uck=61C8D67F74EA7151614513AB78B9078E&groupid=5322F62CC0A881180159A1970890FDA1&as=972&cn=[hex]43504C37383835&lun=[hex]6162726F776E&udn=[hex]5350554E4B4D455945522E434F4D
10/23 12:15:04 [2652] <GetGlobalIndex:>http://10.129.10.52:8014/secars/secars.dll?h=FB8019813B03354E1244DDEB18AB73FA52063F0FD44D0653D79CD862B6DC8510ACF0BCAD07A723A2257177E3638CA5FE42AA17361BC686AB603E9C8B043DBCA189DE95700F73FC7A0AD78C21AE5CF882C94AFA0B1FA92DED17B9240C3ADB7DF754A7C5B6D016227AD21692AE24BE92E80902BB12CB184FFCFBE49A22B8402543FF1BB9A82C1ECC8147BE325AC60250D610A95B75909193C05DB2C85109A8FC9EDE46D14A733A21EF7DD42E142BDBE2D08BF886717B5B9446347F3A54692234DD3E62848263C602383C0C16F6648EA9693539625EE298B4F7CE17B262A6C3459836CE1FA1D3821206B95D4294E1B1154827C5123DF76332B154CEC80889D1FF8C1C6F946D3DFE37F6C55517E43C62A88ECC2E5B6A2171F05F370F5367E53444BAEC47FCF1014E9C522DC4FDB281879DE1BAFA47416DFA0A9C8F775D3A7BC6701FD7275D98A623866ABE0C36E340A74B81
10/23 12:15:04 [2652] 12:15:4=>Send HTTP REQUEST
10/23 12:15:18 [3468] <CSyLink::mfn_DownloadNow()>
10/23 12:15:18 [3468] </CSyLink::mfn_DownloadNow()>
10/23 12:15:20 [2652] 12:15:20=>HTTP REQUEST sent
10/23 12:15:20 [2652] <GetGlobalIndex:>SMS return=200
10/23 12:15:20 [2652] <ParseHTTPStatusCode:>200=>200 OK
10/23 12:15:20 [2652] <FindHeader>Sem-Signatue:=>4000D69898BA264BFF79C2AAC2AF13944E79FDC3A5EA505623A9D203B5808059457BFE2F9F5360EAC979DA075D1696096256B8C857E85EAFB35B355A27FEE7095CC2870110E1B5F58D825980A8935548499A90CBB769AEE564209C9540F16D04AE79774A2319E3F10D0D0C0A201656D9BB84E4684172B635F6013891B5A63120
10/23 12:15:20 [2652] <DoGetGlobalIndex200>Content Lenght => 196
10/23 12:15:20 [2652] <DoGetGlobalIndex200>Got Global Index from server, read bytes=196
10/23 12:15:20 [2652] SignIf::VerifySignature(data, dataLen, sig, sigLen) => Verification Successful.. 
10/23 12:15:20 [2652] <DoGetGlobalIndex200>completed
10/23 12:15:20 [2652] <GetGlobalIndex:>RECEIVE STAGE COMPLETED
10/23 12:15:20 [2652] <GetGlobalIndex:>COMPLETED
10/23 12:15:20 [2652] ************CSN=973
10/23 12:15:20 [2652] <mfn_MakeGetGupListUrl:>Request is: action=320&hostid=FDBAB76B0A810A340048BC644189EFCA&chk=0B5379118E45DCD95A99B6C71BC64296&ck=60E66146113D5C0B5746C2E53928B817&uchk=1A6EF01B866B843A48B77AE989B7A6B7&uck=61C8D67F74EA7151614513AB78B9078E&groupid=5322F62CC0A881180159A1970890FDA1&as=973&cn=[hex]43504C37383835&lun=[hex]6162726F776E&udn=[hex]5350554E4B4D455945522E434F4D
10/23 12:15:20 [2652] <GetGupList:>http://10.129.10.52:8014/secars/secars.dll?h=B189ADFF12C850A66EBC023C2E7D4ECD52063F0FD44D0653D79CD862B6DC8510ACF0BCAD07A723A2257177E3638CA5FE42AA17361BC686AB603E9C8B043DBCA189DE95700F73FC7A0AD78C21AE5CF882C94AFA0B1FA92DED17B9240C3ADB7DF754A7C5B6D016227AD21692AE24BE92E80902BB12CB184FFCFBE49A22B8402543FF1BB9A82C1ECC8147BE325AC60250D610A95B75909193C05DB2C85109A8FC9EDE46D14A733A21EF7DD42E142BDBE2D08BF886717B5B9446347F3A54692234DD3E62848263C602383C0C16F6648EA9693539625EE298B4F7CE17B262A6C3459836CE1FA1D3821206B95D4294E1B1154814DE593C8C2FAC3748B9C3F73930E2DD1C6F946D3DFE37F6C55517E43C62A88ECC2E5B6A2171F05F370F5367E53444BAEC47FCF1014E9C522DC4FDB281879DE1BAFA47416DFA0A9C8F775D3A7BC6701FD7275D98A623866ABE0C36E340A74B81
10/23 12:15:20 [2652] 12:15:20=>Send HTTP REQUEST
10/23 12:15:36 [2652] 12:15:36=>HTTP REQUEST sent
10/23 12:15:36 [2652] <GetGupList:>SMS return=200
10/23 12:15:36 [2652] <ParseHTTPStatusCode:>200=>200 OK
10/23 12:15:36 [2652] <mfn_DoGetGupList200>Content Lenght => 100
10/23 12:15:36 [2652] <mfn_DoGetGupList200>Got Gup List from server, read bytes=100
10/23 12:15:36 [2652] <mfn_DoGetGupList200>completed
10/23 12:15:36 [2652] <GetGupList:>RECEIVE STAGE COMPLETED
10/23 12:15:36 [2652] <GetGupList:>COMPLETED
10/23 12:15:36 [2652] SyLinkDeleteConfig => Deleting instance: 031B1790
10/23 12:15:36 [2652] <SetupTempLUFilePath:>NEW download: C:\Program Files\Symantec\Symantec Endpoint Protection\LiveUpdate\LUF{C60DC234-65F9-4674-94AE-62158EFCA433}910230021.TMP
10/23 12:15:36 [2652] <CHttpFileDownload::CHttpFileDownload()>
10/23 12:15:36 [2652] </CHttpFileDownload::CHttpFileDownload()>
10/23 12:15:36 [2652] <CHttpFileDownload::Do()>
10/23 12:15:36 [2652] <CHttpFileDownload::getRemainingBytesToDownload()>
10/23 12:15:36 [2652] Remaining bytes to download: 59340249
10/23 12:15:36 [2652] </CHttpFileDownload::getRemainingBytesToDownload()>
10/23 12:15:36 [2652] <CHttpConnector::SendRequest()>
10/23 12:15:36 [2652] Request> http://SCCPB301:2967/content/{C60DC234-65F9-4674-94AE-62158EFCA433}/91023002/Full.zip
10/23 12:15:36 [2652] SendRequest() failed.
10/23 12:15:36 [2652] </CHttpConnector::SendRequest()>
10/23 12:15:36 [2652] </CHttpFileDownload::Do()>
10/23 12:15:36 [2652] <LUDownloader::GetContentToFile> completed. 
10/23 12:15:36 [2652] <CHttpFileDownload::~CHttpFileDownload()>
10/23 12:15:36 [2652] </CHttpFileDownload::~CHttpFileDownload()>
10/23 12:15:36 [2652] <LUThreadProc>LU file download failed due to HTTP error:0
10/23 12:15:36 [2652] <CExpBackoff::Increment()>
10/23 12:15:36 [2652] Backoff index incremented
10/23 12:15:36 [2652] Backoff wait index: 2
10/23 12:15:36 [2652] </CExpBackoff::Increment()>
10/23 12:15:36 [2652] <CExpBackoff::Wait()>
10/23 12:15:36 [2652] CExpBackoff wait time in seconds: 64
10/23 12:16:21 [3468] <CSyLink::mfn_DownloadNow()>
10/23 12:16:21 [3468] </CSyLink::mfn_DownloadNow()>
10/23 12:16:40 [2652] </CExpBackoff::Wait()>
10/23 12:16:40 [2652] <IsLUTempFileValid:> File: C:\Program Files\Symantec\Symantec Endpoint Protection\LiveUpdate\LUF{C60DC234-65F9-4674-94AE-62158EFCA433}910230021.TMP is currently used
10/23 12:17:24 [3468] <CSyLink::mfn_DownloadNow()>
10/23 12:17:24 [3468] </CSyLink::mfn_DownloadNow()>
10/23 12:17:40 [2652] SyLinkDeleteConfig => Deleting instance: 031B04F0
10/23 12:17:40 [2652] SyLinkCreateConfig => Created instance: 031B04F0
10/23 12:17:40 [2652] Importing ConfigObject: 034683B8 into: 031B04F0
10/23 12:17:40 [2652] <LUThreadProc> Got ConfigObject to proceed the operation.. pSylinkConfig: 031B04F0
10/23 12:17:40 [2652] <CRandomDelay::CRandomDelay()>
10/23 12:17:40 [2652] Random delay window: 0hour 15min 0sec 
10/23 12:17:40 [2652] Computed random delay:0hour 7min 44sec 0millisec
10/23 12:17:40 [2652] </CRandomDelay::CRandomDelay()>
10/23 12:17:40 [2652] <LUThreadProc>Waiting for: 464000 milliseconds to start downloading LU contents
10/23 12:18:28 [3468] <CSyLink::mfn_DownloadNow()>
10/23 12:18:28 [3468] </CSyLink::mfn_DownloadNow()>
10/23 12:19:31 [3468] <CSyLink::mfn_DownloadNow()>
10/23 12:19:31 [3468] </CSyLink::mfn_DownloadNow()>
10/23 12:20:34 [3468] <CSyLink::mfn_DownloadNow()>
10/23 12:20:34 [3468] </CSyLink::mfn_DownloadNow()>
10/23 12:21:37 [3468] <CSyLink::mfn_DownloadNow()>
10/23 12:21:37 [3468] </CSyLink::mfn_DownloadNow()>
10/23 12:22:40 [3468] <CSyLink::mfn_DownloadNow()>
10/23 12:22:40 [3468] </CSyLink::mfn_DownloadNow()>
10/23 12:23:43 [3468] <CSyLink::mfn_DownloadNow()>
10/23 12:23:43 [3468] </CSyLink::mfn_DownloadNow()>
10/23 12:24:47 [3468] <CSyLink::mfn_DownloadNow()>
10/23 12:24:47 [3468] </CSyLink::mfn_DownloadNow()>
10/23 12:25:24 [2652] <LUThreadProc>Starting LU download.
10/23 12:25:24 [2652] SyLinkCreateConfig => Created instance: 031B1790
10/23 12:25:24 [2652] Importing ConfigObject: 034683B8 into: 031B1790
10/23 12:25:24 [2652] <UpdateGUPList>Setting the session timeout on GlobalItemsSession to 2 min.
10/23 12:25:24 [2652] ************CSN=974
10/23 12:25:24 [2652] <MakeGetGlobalIndexUrl:>Request is: action=310&hostid=FDBAB76B0A810A340048BC644189EFCA&chk=0B5379118E45DCD95A99B6C71BC64296&ck=60E66146113D5C0B5746C2E53928B817&uchk=1A6EF01B866B843A48B77AE989B7A6B7&uck=61C8D67F74EA7151614513AB78B9078E&groupid=5322F62CC0A881180159A1970890FDA1&as=974&cn=[hex]43504C37383835&lun=[hex]6162726F776E&udn=[hex]5350554E4B4D455945522E434F4D
10/23 12:25:24 [2652] <GetGlobalIndex:>http://10.129.10.52:8014/secars/secars.dll?h=FB8019813B03354E1244DDEB18AB73FA52063F0FD44D0653D79CD862B6DC8510ACF0BCAD07A723A2257177E3638CA5FE42AA17361BC686AB603E9C8B043DBCA189DE95700F73FC7A0AD78C21AE5CF882C94AFA0B1FA92DED17B9240C3ADB7DF754A7C5B6D016227AD21692AE24BE92E80902BB12CB184FFCFBE49A22B8402543FF1BB9A82C1ECC8147BE325AC60250D610A95B75909193C05DB2C85109A8FC9EDE46D14A733A21EF7DD42E142BDBE2D08BF886717B5B9446347F3A54692234DD3E62848263C602383C0C16F6648EA9693539625EE298B4F7CE17B262A6C3459836CE1FA1D3821206B95D4294E1B1154895C8299AF62836A28107709FCBBA933E1C6F946D3DFE37F6C55517E43C62A88ECC2E5B6A2171F05F370F5367E53444BAEC47FCF1014E9C522DC4FDB281879DE1BAFA47416DFA0A9C8F775D3A7BC6701FD7275D98A623866ABE0C36E340A74B81
10/23 12:25:24 [2652] 12:25:24=>Send HTTP REQUEST
10/23 12:25:45 [2652] 12:25:45=>HTTP REQUEST sent
10/23 12:25:45 [2652] <GetGlobalIndex:>SMS return=200
10/23 12:25:45 [2652] <ParseHTTPStatusCode:>200=>200 OK
10/23 12:25:45 [2652] <FindHeader>Sem-Signatue:=>4000D69898BA264BFF79C2AAC2AF13944E79FDC3A5EA505623A9D203B5808059457BFE2F9F5360EAC979DA075D1696096256B8C857E85EAFB35B355A27FEE7095CC2870110E1B5F58D825980A8935548499A90CBB769AEE564209C9540F16D04AE79774A2319E3F10D0D0C0A201656D9BB84E4684172B635F6013891B5A63120
10/23 12:25:45 [2652] <DoGetGlobalIndex200>Content Lenght => 196
10/23 12:25:45 [2652] <DoGetGlobalIndex200>Got Global Index from server, read bytes=196
10/23 12:25:45 [2652] SignIf::VerifySignature(data, dataLen, sig, sigLen) => Verification Successful.. 
10/23 12:25:45 [2652] <DoGetGlobalIndex200>completed
10/23 12:25:45 [2652] <GetGlobalIndex:>RECEIVE STAGE COMPLETED
10/23 12:25:45 [2652] <GetGlobalIndex:>COMPLETED
10/23 12:25:45 [2652] ************CSN=975
10/23 12:25:45 [2652] <mfn_MakeGetGupListUrl:>Request is: action=320&hostid=FDBAB76B0A810A340048BC644189EFCA&chk=0B5379118E45DCD95A99B6C71BC64296&ck=60E66146113D5C0B5746C2E53928B817&uchk=1A6EF01B866B843A48B77AE989B7A6B7&uck=61C8D67F74EA7151614513AB78B9078E&groupid=5322F62CC0A881180159A1970890FDA1&as=975&cn=[hex]43504C37383835&lun=[hex]6162726F776E&udn=[hex]5350554E4B4D455945522E434F4D
10/23 12:25:45 [2652] <GetGupList:>http://10.129.10.52:8014/secars/secars.dll?h=B189ADFF12C850A66EBC023C2E7D4ECD52063F0FD44D0653D79CD862B6DC8510ACF0BCAD07A723A2257177E3638CA5FE42AA17361BC686AB603E9C8B043DBCA189DE95700F73FC7A0AD78C21AE5CF882C94AFA0B1FA92DED17B9240C3ADB7DF754A7C5B6D016227AD21692AE24BE92E80902BB12CB184FFCFBE49A22B8402543FF1BB9A82C1ECC8147BE325AC60250D610A95B75909193C05DB2C85109A8FC9EDE46D14A733A21EF7DD42E142BDBE2D08BF886717B5B9446347F3A54692234DD3E62848263C602383C0C16F6648EA9693539625EE298B4F7CE17B262A6C3459836CE1FA1D3821206B95D4294E1B115487116B84CD549AD86D86978B7A9D923FC1C6F946D3DFE37F6C55517E43C62A88ECC2E5B6A2171F05F370F5367E53444BAEC47FCF1014E9C522DC4FDB281879DE1BAFA47416DFA0A9C8F775D3A7BC6701FD7275D98A623866ABE0C36E340A74B81
10/23 12:25:45 [2652] 12:25:45=>Send HTTP REQUEST
10/23 12:25:50 [3468] <CSyLink::mfn_DownloadNow()>
10/23 12:25:50 [3468] </CSyLink::mfn_DownloadNow()>
10/23 12:26:06 [2652] 12:26:6=>HTTP REQUEST sent
10/23 12:26:06 [2652] <GetGupList:>SMS return=200
10/23 12:26:06 [2652] <ParseHTTPStatusCode:>200=>200 OK
10/23 12:26:06 [2652] <mfn_DoGetGupList200>Content Lenght => 100
10/23 12:26:06 [2652] <mfn_DoGetGupList200>Got Gup List from server, read bytes=100
10/23 12:26:06 [2652] <mfn_DoGetGupList200>completed
10/23 12:26:06 [2652] <GetGupList:>RECEIVE STAGE COMPLETED
10/23 12:26:06 [2652] <GetGupList:>COMPLETED
10/23 12:26:06 [2652] SyLinkDeleteConfig => Deleting instance: 031B1790
10/23 12:26:06 [2652] <SetupTempLUFilePath:>NEW download: C:\Program Files\Symantec\Symantec Endpoint Protection\LiveUpdate\LUF{C60DC234-65F9-4674-94AE-62158EFCA433}910230021.TMP
10/23 12:26:06 [2652] <CHttpFileDownload::CHttpFileDownload()>
10/23 12:26:06 [2652] </CHttpFileDownload::CHttpFileDownload()>
10/23 12:26:06 [2652] <CHttpFileDownload::Do()>
10/23 12:26:06 [2652] <CHttpFileDownload::getRemainingBytesToDownload()>
10/23 12:26:06 [2652] Remaining bytes to download: 59340249
10/23 12:26:06 [2652] </CHttpFileDownload::getRemainingBytesToDownload()>
10/23 12:26:06 [2652] <CHttpConnector::SendRequest()>
10/23 12:26:06 [2652] Request> http://SCCPB301:2967/content/{C60DC234-65F9-4674-94AE-62158EFCA433}/91023002/Full.zip
10/23 12:26:06 [2652] SendRequest() failed.
10/23 12:26:06 [2652] </CHttpConnector::SendRequest()>
10/23 12:26:06 [2652] </CHttpFileDownload::Do()>
10/23 12:26:06 [2652] <LUDownloader::GetContentToFile> completed. 
10/23 12:26:06 [2652] <CHttpFileDownload::~CHttpFileDownload()>
10/23 12:26:06 [2652] </CHttpFileDownload::~CHttpFileDownload()>
10/23 12:26:06 [2652] <LUThreadProc>LU file download failed due to HTTP error:0
Ghent's picture
26
Oct
2009
0 Votes 0
Login to vote

Sylink: first look

Hi,
Taking a look at your Sylink file I see:
1) You had a successful connection to the SEPM server in Pull mode at 12:06. The heartbeat is set to half-an-hour so your next beat would be around 12:36.
2) There was a failure attempting to communicate with the GUP server to get content.
3) Even though there is a failure to communicate with the GUP server, communication with the SEPM server is working just fine.

So, this brings up the question to me, "Does a GUP communication failure cause the green light to go out?" It should not. But here's how you can test it.
If the green light goes out, and you click "Update Policy", does it come right back on?
If yes, then the GUP failure may be causing the light to go out. (Need to match Secars log and light on/off event to be sure)
If no, then perhaps this log does not capture the moment your light went out. Did the light go out after 12:06 but before 12:26? Do you know within 10 minutes or so when the light went out?

What I mean by an automated installation:
Some companies use channels such as SMS to install product onto client computers. The can do this even if no 'user' is logged into the system. Under these conditions it's possible that the automation lauches as the "SYSTEM" user instead of an administrative user -- which could cause the installer to behave abnormally. This is just a 'possibility', I don't see any evidence for sure in this forum.

P.S. It looks better if you can post the log as a attachment, not a requirement though.

CT-219's picture
26
Oct
2009
1 Vote +1
Login to vote

A little out of the box, but

A little out of the box, but if you look at the management server list applied to your group, is your retired management server still listed? If so, were you configured for failover or load balancing? I have seen situations where retired servers remaining in a server list have caused "green dot" issues similar to yours.

snekul's picture
27
Oct
2009
0 Votes 0
Login to vote

That doesn't sound

That doesn't sound "out-of-the-box."  Out-of-the-box thinking would be more akin to asking if the server rack was installed up-side-down or something like that. :-)

Eric C. Lukens IT Security Policy and Risk Assessment Analyst University of Northern Iowa

BadAndy's picture
29
Oct
2009
0 Votes 0
Login to vote

 I will post the log from my

 I will post the log from my laptop tomorrow.

What doesn't make sense is that the client on my laptop stays connected all morning but will disconnect after noon. Some times it happens around 1pm, a couple days ago it disconnected around 4:30pm and wouldn't reconnect until the next morning. 

BadAndy's picture
29
Oct
2009
0 Votes 0
Login to vote

This part of the log has me

This part of the log has me wondering what's going on

Throw Internet Exception, Error Code=2;AH: failed to open internet.

CInternetException: <IndexHeartbeatProc>: The system cannot find the file specified. 

What file is it trying to open what does that error code mean? How did it fail to open internet?

JT_T's picture
29
Oct
2009
0 Votes 0
Login to vote

Could you try communication

Could you try communication without https/certificate, if you sometime have used one?

I've seen clients connecting/disconnecting/connecting/disconnecting when there's been certificate error and "Verify Server Certificate" box checked. You might not have that anymore on your management server list, but if it once was there, clients might have old sylink.xml file.

Ghent's picture
29
Oct
2009
0 Votes 0
Login to vote

Not using HTTPS

The sylink log shows that HTTPS, or SSL, is not being used.

JT_T's picture
29
Oct
2009
0 Votes 0
Login to vote

Ok, i didn't read the log,

Ok, i didn't read the log, just noticed message earlier saying;

"There is only one SEPM server but there used to be two. I removed the secondary server some time ago but for some reason, the old server still shows a certificate or something in the sylink.xml file on all machines"

BadAndy's picture
30
Oct
2009
0 Votes 0
Login to vote

I checked the properties of

I checked the properties of the clients designated as GUPs in the console and on the General tab, the last entry labeled "Group Update Provider" was showing False for all of the GUPs I assigned. Could that have been because I upgraded the management server to MR5 but didn't update the clients on the GUPs to the latest version as well? No clue but after installing the latest SEP client on them, GUP in the client properties shows "True" now. That may be a separate issue but hopefully that's what has been causing this problem.

Ghent's picture
30
Oct
2009
0 Votes 0
Login to vote

The MR4 clients did not have

The MR4 clients did not have the "Tell SEPM my GUP status" feature. So that's why MR4 clients show False but MR5 clients will show True.
Hopefully that's not related to your current issue. If you are not aware of the new GUP features in MR5, I think it would be worth your time to look into them.

The main thing we haven't seen yet is a Sylink log where you can say, "At x time in the slink log I'm showing now, the client's green light went out". That would be the most useful. Other than that, we are all still guessing here.

@JT_T
It's true, there is an application level certificate present in the Sylink.xml file. And, you can turn off the validation the clients use this certificate for. So you idea is correct. But in this case, a) I doubt that's the issue (but I don't know for sure) and b) If that was the issue, normally it's only the symptom of a large issue. I'd prefer to see the larger issue first -- if there is one.

BadAndy's picture
31
Oct
2009
0 Votes 0
Login to vote

I looked through the log

I looked through the log after the green dot shut off and it looked just like the parts of the log I already posted. There wasn't a section that plainly stated that it disconnected, just that on it's next attempt it couldn't connect.

Ghent's picture
02
Nov
2009
0 Votes 0
Login to vote

If you are in a state where

If you are in a state where the "Update Policy" does not make the green dot appear, then every time you say "Update Policy", there should be some activity, such as an error, in the Sylink.log.
You mentioned "on it's next attempt it couldn't connect".
So a few things could be happening.
1) The server is returning an error code, like HTTP 500 -- this should be shown in the Sylink.log.
2) The server is not responding. This will be shown as a timeout. I don't think the Sylink.log file uses the word "timeout" -- but it does give an error, and you can see from the time delay that it is timing out. I think it would report this as HTTP 0. Not sure.
3) The client is having some internal error that prevents it from making the request -- we should also see something in the Sylink.log

If you have your client in a state where it won't connect, the best place to start looking is near the
<SendRegistrationRequest:> and <GetIndexFileRequest:> markers. This is where I expect to see the errors.
Your first Sylink.log posting shows an error near the SendRegistrationRequest marker. I would not expect the error to be near this point if the client is already connected and then disconnects abruptly. I really expect the error to be near a GetIndexFileRequest marker.

You should be able to see the error repeatedly by clicking "Update Policy".
I would recommend using a program such as BareTail ( http://www.baremetalsoft.com/baretail/ ) to watch your Sylink.log. It's a program that 'tails' your log file.
When the issue accures, launch BareTail. After you have the Sylink.log opened in BareTail, click "Update Policy" on SEP.
You should immediently see the new text scroll by. The error should be located within this new content. If you could give a posting from last successful heartbeat, to the failed error (probably about half-an-hour apart) that would be the most useful.

 

BadAndy's picture
03
Nov
2009
0 Votes 0
Login to vote

 Well, the issue appears to

 Well, the issue appears to have been resolved by installing the latest SEP client on the GUPs. Clients have been staying connected since without any issues.