/var/log/Otclient01/28:19:04:56: /tvbin/TClient:  inside TClient
01/28:19:04:56: /tvbin/TClient:  TClient: invoking init w/argv=-h -backup
01/28:19:04:56: /tvbin/TClient:  writing In Progress to LastCallStatus
01/28:19:04:56: /tvbin/TClient:  TClient init: 0
01/28:19:04:57: /tvbin/TClient:  dial config code: (845) found
01/28:19:04:57: /tvbin/TClient:  matched 845, returning value = 08451112111:194.1.151.205:80:guest@0845.uk.com:guest
01/28:19:04:57: /tvbin/TClient:  updateStatus: In Progress EX| 29 48
01/28:19:04:57: /tvbin/TClient:  writing In Progress to LastCallStatus
01/28:19:04:57: /tvbin/TClient:  SendDialupEvent 30 9 EX|29
01/28:19:04:57: /tvbin/TClient:  Host = 194.1.151.205
01/28:19:04:57: /tvbin/TClient:  Port = 80
01/28:19:04:57: /tvbin/TClient:  Phone = 08451112111
01/28:19:04:57: /tvbin/TClient:  updateStatus: In Progress EX| 29 8
01/28:19:04:57: /tvbin/TClient:  SendDialupEvent 30 9 EX|29
retrying after errTmBackgroundHoldoff ...
retrying after errTmBackgroundHoldoff ...
01/28:19:05:04: /tvbin/TClient:  DataSets may not be enabled, could not find GenreVersion: DataSet GenreVersion does not exist.
01/28:19:05:04: /tvbin/TClient:  DataSets may not be enabled, could not find LogoVersion: DataSet LogoVersion does not exist.
01/28:19:05:04: /tvbin/TClient:  DataSets may not be enabled, could not find AffiliationVersion: DataSet AffiliationVersion does not exist.
01/28:19:05:04: /tvbin/TClient:  DataSets may not be enabled, could not find ShowcaseVersion: DataSet ShowcaseVersion does not exist.
01/28:19:05:04: /tvbin/TClient:  mrsigtype=3 mrconnector=7...
01/28:19:05:04: /tvbin/TClient:  mrsigtype=1 mrconnector=1...
01/28:19:05:05: /tvbin/TClient:  Using error search string: [Ee]xception([^/]| [^P]| P[^O]| PO[^S]| POS[^T])|[Aa]ssert|[B]acktrace|[Ss]egmentation|[K]ernel panic|[D]riveStatusError|[e]rrDbNoMemory
01/28:19:05:13: /tvbin/TClient:  encryptLog called with infile = /var/persist/thumbslog.log.gz outfile = /var/persist/thumbslog.log.gz.bfg keyname = BACKHAUL_THUMB
01/28:19:05:13: /tvbin/TClient:  EncryptLog Could not find /var/persist/thumbslog.log.gz
01/28:19:05:13: /tvbin/TClient:  encryptLog called with infile = /var/log/tivoLog.prv.gz outfile = /var/log/tivoLog.prv.gz.bfg keyname = BACKHAUL_PRIVATE
01/28:19:05:14: /tvbin/TClient:  bfkey = 0xE4B564E4A53C041B102176D8E3103553
01/28:19:05:14: /tvbin/TClient:  Key value = 
01/28:19:05:14: /tvbin/TClient:  EncryptLog could not super-encrypt the key because key was empty
01/28:19:05:14: /tvbin/TClient:  updateStatus: In Progress EX| 28 7
01/28:19:05:14: /tvbin/TClient:  SendDialupEvent 30 9 EX|28
01/28:19:05:14: /tvbin/TClient:  creating keyserver query
01/28:19:05:22: /tvbin/TClient:  setting up Ident Block
01/28:19:05:22: /tvbin/TClient:  returning messages: 
01/28:19:05:22: /tvbin/TClient:  getting current software
01/28:19:05:22: /tvbin/TClient:  found name = 2.5.5-01-1-023
01/28:19:05:23: /tvbin/TClient:  found name = ACTIVE
01/28:19:05:23: /tvbin/TClient:  Found 2.5.5-01-1 running
01/28:19:05:23: /tvbin/TClient:  getting current showcases
01/28:19:05:23: /tvbin/TClient:  getting locationID
01/28:19:05:23: /tvbin/TClient:  Removing DBS objects in guided setup.
01/28:19:05:23: /tvbin/TClient:  Current postalcode: 01000
01/28:19:05:23: /tvbin/TClient:  Nothing found so returning: |DBS-0
01/28:19:05:23: /tvbin/TClient:  getting headendID
01/28:19:05:23: /tvbin/TClient:  skipping getHeadendIDList on SETUPHEAD call
01/28:19:05:23: /tvbin/TClient:  setting up TCD411 block
01/28:19:05:23: /tvbin/TClient:  setting area code: 
01/28:19:05:23: /tvbin/TClient:  
01/28:19:05:23: /tvbin/TClient:       Start TCD411 Request ==================
01/28:19:05:23: /tvbin/TClient:              areaCode: 
01/28:19:05:23: /tvbin/TClient:                prefix: {}
01/28:19:05:23: /tvbin/TClient:        dialInAreaCode: {}
01/28:19:05:23: /tvbin/TClient:              curPhNum: 0
01/28:19:05:23: /tvbin/TClient:                objVer: 
01/28:19:05:23: /tvbin/TClient:                 tcdId: 0230000601E8783
01/28:19:05:23: /tvbin/TClient:                callId: 1327777497
01/28:19:05:23: /tvbin/TClient:            dialPrefix: 
01/28:19:05:23: /tvbin/TClient:         dialToneCheck: 
01/28:19:05:23: /tvbin/TClient:         offHookDetect: 1
01/28:19:05:23: /tvbin/TClient:         tonePulseDial: P
01/28:19:05:23: /tvbin/TClient:        callWaitPrefix: 
01/28:19:05:23: /tvbin/TClient:          tollFreeAuth: 
01/28:19:05:23: /tvbin/TClient:              callType: TS_SR_ACCEPTED
01/28:19:05:23: /tvbin/TClient:             swVerName: 2.5.5-01-1-023
01/28:19:05:23: /tvbin/TClient:         End TCD411 Request ==================
01/28:19:05:24: /tvbin/TClient:  
01/28:19:05:24: /tvbin/TClient:   Start Ident     =====================
01/28:19:05:24: /tvbin/TClient:              version: 3
01/28:19:05:24: /tvbin/TClient:             centerID: 0230000601E8783
01/28:19:05:24: /tvbin/TClient:           reasonCode: 6
01/28:19:05:24: /tvbin/TClient:         softwareDesc: 468089-51|468071-1|468073-1|468075-1|468077-1|468079-1|468081-1|468083-1|468085-1|468087-1|468090-1|468091-1|468092-1|468093-1|468094-1|468095-1|468096-1|468097-1|468098-1|468099-1|468100-1|468101-1|468102-1|468103-1|468104-1|468105-1|468106-1|468107-1|468108-1|468109-1|468110-1|468111-1|468112-1|468113-1|468114-1|468115-1|468116-1|468117-1|468118-1|468119-1|468120-1|468121-1|468122-1|468123-1|468124-1|468125-1|468127-1|468128-1|468129-1|468130-1|468131-1|468132-1|468133-1|468134-1|468135-1|468136-1|468137-1|468138-1|468139-1|24252-77|24264-362|387715-4|61464-40|24273-54|24294-76|427608-4|24306-72|24337-54|24352-638|24358-36|24380-12|
01/28:19:05:24: /tvbin/TClient:           locationID: 01000-0|DBS-0
01/28:19:05:24: /tvbin/TClient:       sequenceCookie: 12345678
01/28:19:05:24: /tvbin/TClient:            headendID: 
01/28:19:05:24: /tvbin/TClient:         showcaseDesc: showcase0-0
01/28:19:05:24: /tvbin/TClient:        inventoryFile: 
01/28:19:05:24: /tvbin/TClient:         waitingCount: 0
01/28:19:05:24: /tvbin/TClient:           dialConfig: 845
01/28:19:05:24: /tvbin/TClient:             confInfo: 08451112111:194.1.151.205:80:guest@0845.uk.com:guest
01/28:19:05:24: /tvbin/TClient:          messageDesc: 
01/28:19:05:24: /tvbin/TClient:          irdbVersion: 317
01/28:19:05:24: /tvbin/TClient:         genreVersion: 
01/28:19:05:24: /tvbin/TClient:          logoVersion: 
01/28:19:05:24: /tvbin/TClient:   affiliationVersion: 
01/28:19:05:24: /tvbin/TClient:      showcaseVersion: 
01/28:19:05:24: /tvbin/TClient:            swVerName: 2.5.5-01-1-023
01/28:19:05:24: /tvbin/TClient:  configParameterList: zip=01000,dar=,rcq=,tz=,as=,tun=,suc=
01/28:19:05:24: /tvbin/TClient:  sourceParameterList: st=3,con=7,drm=2,ekr=,ccn=,brn=,lin=N,irs=;st=1,con=1,drm=,ekr=,ccn=,brn=,lin=N,irs=
01/28:19:05:24: /tvbin/TClient:              apgOnly: 
01/28:19:05:24: /tvbin/TClient:           useChksums: TRUE
01/28:19:05:24: /tvbin/TClient:     premiumShowcases: 
01/28:19:05:24: /tvbin/TClient:      captureRequests: 
01/28:19:05:24: /tvbin/TClient:            menuItems: 
01/28:19:05:24: /tvbin/TClient:        otherDataSets: HDGenre-4|MS_VMPromo06-4|MS_VMPromo06_pmi-4|MS_VMPromo07-4|MS_VMPromo07_pmi-4|MS_VMPromo08-4|MS_VMPromo08_pmi-4|MS_VMPromo09-4|MS_VMPromo09_pmi-4|RM_cleanTvShow-|
01/28:19:05:24: /tvbin/TClient:        rbBackgrounds: 
01/28:19:05:24: /tvbin/TClient:        dataGroupList: CR_Standard|DG_standard|MI_Standard|SF_SerialLogging|SW_released
01/28:19:05:24: /tvbin/TClient:   lstAvalancheDnload: 0,0 0,0
01/28:19:05:24: /tvbin/TClient:        userInitiated: 1
01/28:19:05:24: /tvbin/TClient:  End Ident        =====================
01/28:19:05:24: /tvbin/TClient:  
01/28:19:05:24: /tvbin/TClient:  Logging camid.
01/28:19:05:24: /tvbin/TClient:  CAM_ID not found.
01/28:19:05:24: /tvbin/TClient:  Ird Id.
01/28:19:05:24: /tvbin/TClient:  updateStatus: In Progress CL| 30 24
01/28:19:05:24: /tvbin/TClient:  SendDialupEvent 30 9 CL|30
01/28:19:05:24: /tvbin/TClient:  updateStatus: In Progress ST| 33 14
01/28:19:05:24: /tvbin/TClient:  SendDialupEvent 30 9 ST|33
01/28:19:05:24: /tvbin/TClient:  About to connect to 194.1.151.205:80
01/28:19:05:24: /tvbin/TClient:  Executing HTTP command: /tvbin/http_post /var/log/svclog 
http://194.1.151.205:80/tivo-service/mlog.cgi OFF OFF ON
01/28:19:05:24: /tvbin/TClient:  about to do TCD411 Call
01/28:19:05:24: /tvbin/TClient:  Executing HTTP command: /tvbin/tclient_post 194.1.151.205:80 /var/tmp/TCD411.send /var/tmp/TCD411.recv 300 ON
01/28:19:05:25: /tvbin/TClient:  doHttpCall returned: 1
01/28:19:05:25: /tvbin/TClient:  reading HTTP header...
01/28:19:05:25: /tvbin/TClient:  HTTP header: HTTP/1.1 200 OK
01/28:19:05:25: /tvbin/TClient:  HTTP header: Date: Sat, 28 Jan 2012 19:05:20 GMT
01/28:19:05:25: /tvbin/TClient:  HTTP header: Server: Apache/2.2.14 (Ubuntu)
01/28:19:05:25: /tvbin/TClient:  HTTP header: Vary: Accept-Encoding
01/28:19:05:25: /tvbin/TClient:  HTTP header: Connection: close
01/28:19:05:25: /tvbin/TClient:  HTTP header: Content-Type: text/plain; charset=ISO-8859-1
01/28:19:05:25: /tvbin/TClient:  Got end of Header
01/28:19:05:25: /tvbin/TClient:  TCD411RespCli::readBlock returned: 1
01/28:19:05:25: /tvbin/TClient:  
01/28:19:05:25: /tvbin/TClient:     Start TCD411Resp =====================
01/28:19:05:25: /tvbin/TClient:              errMsg: 
01/28:19:05:25: /tvbin/TClient:         areaCodeObj: OK
01/28:19:05:25: /tvbin/TClient:        tollFreeAuth: 0
01/28:19:05:25: /tvbin/TClient:         tollFreeNum: 0
01/28:19:05:25: /tvbin/TClient:       End TCD411Resp =====================
01/28:19:05:25: /tvbin/TClient:  about to do HServer Call
01/28:19:05:25: /tvbin/TClient:  Executing HTTP command: /tvbin/tclient_post 194.1.151.205:80 /var/tmp/HServer.send /var/tmp/HServer.recv 300 ON
01/28:19:10:25: /tvbin/TClient:  http POST command failed:  timeout waiting to read
01/28:19:10:25: /tvbin/TClient:  doHttpCall returned: 0
01/28:19:10:25: /tvbin/TClient:  Connect/POST has failed, we've warned the user, set status to Failed
01/28:19:10:25: /tvbin/TClient:  failed connect - aborting
01/28:19:10:25: /tvbin/TClient:  Will kill pppd
01/28:19:10:31: /tvbin/TClient:  updateStatus: Failed ST| 33 14
Saving 'ST|14' to result file
01/28:19:10:31: /tvbin/TClient:  writing Failed to LastCallStatus
01/28:19:10:31: /tvbin/TClient:  SendDialupEvent 30 9 EX|14
01/28:19:10:31: /tvbin/TClient:  TClient login: 1
01/28:19:10:31: /tvbin/TClient:  TClient backhaulDone: 0
01/28:20:58:34: /tvbin/TClient:  inside TClient
01/28:20:58:35: /tvbin/TClient:  No previously downloaded files remain in /var/packages
01/28:20:58:35: /tvbin/TClient:  TClient: invoking init w/argv=-h -backup
01/28:20:58:35: /tvbin/TClient:  writing In Progress to LastCallStatus
01/28:20:58:35: /tvbin/TClient:  TClient init: 0
01/28:20:58:35: /tvbin/TClient:  dial config code: (845) found
01/28:20:58:35: /tvbin/TClient:  matched 845, returning value = 08451112111:194.1.151.205:80:guest@0845.uk.com:guest
01/28:20:58:35: /tvbin/TClient:  updateStatus: In Progress EX| 29 48
01/28:20:58:35: /tvbin/TClient:  writing In Progress to LastCallStatus
01/28:20:58:35: /tvbin/TClient:  SendDialupEvent 30 9 EX|29
01/28:20:58:35: /tvbin/TClient:  Host = 194.1.151.205
01/28:20:58:35: /tvbin/TClient:  Port = 80
01/28:20:58:35: /tvbin/TClient:  Phone = 08451112111
01/28:20:58:35: /tvbin/TClient:  updateStatus: In Progress EX| 29 8
01/28:20:58:35: /tvbin/TClient:  SendDialupEvent 30 9 EX|29
retrying after errTmBackgroundHoldoff ...
retrying after errTmBackgroundHoldoff ...
01/28:20:58:42: /tvbin/TClient:  DataSets may not be enabled, could not find GenreVersion: DataSet GenreVersion does not exist.
01/28:20:58:42: /tvbin/TClient:  DataSets may not be enabled, could not find LogoVersion: DataSet LogoVersion does not exist.
01/28:20:58:42: /tvbin/TClient:  DataSets may not be enabled, could not find AffiliationVersion: DataSet AffiliationVersion does not exist.
01/28:20:58:42: /tvbin/TClient:  DataSets may not be enabled, could not find ShowcaseVersion: DataSet ShowcaseVersion does not exist.
01/28:20:58:42: /tvbin/TClient:  mrsigtype=3 mrconnector=7...
01/28:20:58:42: /tvbin/TClient:  mrsigtype=1 mrconnector=1...
01/28:20:58:43: /tvbin/TClient:  Using error search string: [Ee]xception([^/]| [^P]| P[^O]| PO[^S]| POS[^T])|[Aa]ssert|[B]acktrace|[Ss]egmentation|[K]ernel panic|[D]riveStatusError|[e]rrDbNoMemory
01/28:20:58:52: /tvbin/TClient:  encryptLog called with infile = /var/persist/thumbslog.log.gz outfile = /var/persist/thumbslog.log.gz.bfg keyname = BACKHAUL_THUMB
01/28:20:58:52: /tvbin/TClient:  EncryptLog Could not find /var/persist/thumbslog.log.gz
01/28:20:58:52: /tvbin/TClient:  encryptLog called with infile = /var/log/tivoLog.prv.gz outfile = /var/log/tivoLog.prv.gz.bfg keyname = BACKHAUL_PRIVATE
01/28:20:58:52: /tvbin/TClient:  bfkey = 0x22A23AE6F391FDF6AF50FC92D216B781
01/28:20:58:52: /tvbin/TClient:  Key value = 
01/28:20:58:52: /tvbin/TClient:  EncryptLog could not super-encrypt the key because key was empty
01/28:20:58:52: /tvbin/TClient:  updateStatus: In Progress EX| 28 7
01/28:20:58:52: /tvbin/TClient:  SendDialupEvent 30 9 EX|28
01/28:20:58:52: /tvbin/TClient:  creating keyserver query
01/28:20:59:01: /tvbin/TClient:  setting up Ident Block
01/28:20:59:01: /tvbin/TClient:  returning messages: 
01/28:20:59:01: /tvbin/TClient:  getting current software
01/28:20:59:01: /tvbin/TClient:  found name = 2.5.5-01-1-023
01/28:20:59:03: /tvbin/TClient:  found name = ACTIVE
01/28:20:59:03: /tvbin/TClient:  Found 2.5.5-01-1 running
01/28:20:59:03: /tvbin/TClient:  getting current showcases
01/28:20:59:03: /tvbin/TClient:  getting locationID
01/28:20:59:03: /tvbin/TClient:  Removing DBS objects in guided setup.
01/28:20:59:03: /tvbin/TClient:  Current postalcode: 01000
01/28:20:59:03: /tvbin/TClient:  Nothing found so returning: |DBS-0
01/28:20:59:03: /tvbin/TClient:  getting headendID
01/28:20:59:03: /tvbin/TClient:  skipping getHeadendIDList on SETUPHEAD call
01/28:20:59:03: /tvbin/TClient:  setting up TCD411 block
01/28:20:59:03: /tvbin/TClient:  setting area code: 
01/28:20:59:03: /tvbin/TClient:  
01/28:20:59:03: /tvbin/TClient:       Start TCD411 Request ==================
01/28:20:59:03: /tvbin/TClient:              areaCode: 
01/28:20:59:03: /tvbin/TClient:                prefix: {}
01/28:20:59:03: /tvbin/TClient:        dialInAreaCode: {}
01/28:20:59:03: /tvbin/TClient:              curPhNum: 0
01/28:20:59:03: /tvbin/TClient:                objVer: 
01/28:20:59:03: /tvbin/TClient:                 tcdId: 0230000601E8783
01/28:20:59:03: /tvbin/TClient:                callId: 1327784316
01/28:20:59:03: /tvbin/TClient:            dialPrefix: 
01/28:20:59:03: /tvbin/TClient:         dialToneCheck: 
01/28:20:59:03: /tvbin/TClient:         offHookDetect: 1
01/28:20:59:03: /tvbin/TClient:         tonePulseDial: P
01/28:20:59:03: /tvbin/TClient:        callWaitPrefix: 
01/28:20:59:03: /tvbin/TClient:          tollFreeAuth: 
01/28:20:59:03: /tvbin/TClient:              callType: TS_SR_ACCEPTED
01/28:20:59:03: /tvbin/TClient:             swVerName: 2.5.5-01-1-023
01/28:20:59:03: /tvbin/TClient:         End TCD411 Request ==================
01/28:20:59:04: /tvbin/TClient:  
01/28:20:59:04: /tvbin/TClient:   Start Ident     =====================
01/28:20:59:04: /tvbin/TClient:              version: 3
01/28:20:59:04: /tvbin/TClient:             centerID: 0230000601E8783
01/28:20:59:04: /tvbin/TClient:           reasonCode: 6
01/28:20:59:04: /tvbin/TClient:         softwareDesc: 468089-51|468071-1|468073-1|468075-1|468077-1|468079-1|468081-1|468083-1|468085-1|468087-1|468090-1|468091-1|468092-1|468093-1|468094-1|468095-1|468096-1|468097-1|468098-1|468099-1|468100-1|468101-1|468102-1|468103-1|468104-1|468105-1|468106-1|468107-1|468108-1|468109-1|468110-1|468111-1|468112-1|468113-1|468114-1|468115-1|468116-1|468117-1|468118-1|468119-1|468120-1|468121-1|468122-1|468123-1|468124-1|468125-1|468127-1|468128-1|468129-1|468130-1|468131-1|468132-1|468133-1|468134-1|468135-1|468136-1|468137-1|468138-1|468139-1|24252-77|24264-362|387715-4|61464-40|24273-54|24294-76|427608-4|24306-72|24337-54|24352-638|24358-36|24380-12|
01/28:20:59:04: /tvbin/TClient:           locationID: 01000-0|DBS-0
01/28:20:59:04: /tvbin/TClient:       sequenceCookie: 12345678
01/28:20:59:04: /tvbin/TClient:            headendID: 
01/28:20:59:04: /tvbin/TClient:         showcaseDesc: showcase0-0
01/28:20:59:04: /tvbin/TClient:        inventoryFile: 
01/28:20:59:04: /tvbin/TClient:         waitingCount: 0
01/28:20:59:04: /tvbin/TClient:           dialConfig: 845
01/28:20:59:04: /tvbin/TClient:             confInfo: 08451112111:194.1.151.205:80:guest@0845.uk.com:guest
01/28:20:59:04: /tvbin/TClient:          messageDesc: 
01/28:20:59:04: /tvbin/TClient:          irdbVersion: 317
01/28:20:59:04: /tvbin/TClient:         genreVersion: 
01/28:20:59:04: /tvbin/TClient:          logoVersion: 
01/28:20:59:04: /tvbin/TClient:   affiliationVersion: 
01/28:20:59:04: /tvbin/TClient:      showcaseVersion: 
01/28:20:59:04: /tvbin/TClient:            swVerName: 2.5.5-01-1-023
01/28:20:59:04: /tvbin/TClient:  configParameterList: zip=01000,dar=,rcq=,tz=,as=,tun=,suc=
01/28:20:59:04: /tvbin/TClient:  sourceParameterList: st=3,con=7,drm=2,ekr=,ccn=,brn=,lin=N,irs=;st=1,con=1,drm=,ekr=,ccn=,brn=,lin=N,irs=
01/28:20:59:04: /tvbin/TClient:              apgOnly: 
01/28:20:59:04: /tvbin/TClient:           useChksums: TRUE
01/28:20:59:04: /tvbin/TClient:     premiumShowcases: 
01/28:20:59:04: /tvbin/TClient:      captureRequests: 
01/28:20:59:04: /tvbin/TClient:            menuItems: 
01/28:20:59:04: /tvbin/TClient:        otherDataSets: HDGenre-4|MS_VMPromo06-4|MS_VMPromo06_pmi-4|MS_VMPromo07-4|MS_VMPromo07_pmi-4|MS_VMPromo08-4|MS_VMPromo08_pmi-4|MS_VMPromo09-4|MS_VMPromo09_pmi-4|RM_cleanTvShow-|
01/28:20:59:04: /tvbin/TClient:        rbBackgrounds: 
01/28:20:59:04: /tvbin/TClient:        dataGroupList: CR_Standard|DG_standard|MI_Standard|SF_SerialLogging|SW_released
01/28:20:59:04: /tvbin/TClient:   lstAvalancheDnload: 0,0 0,0
01/28:20:59:04: /tvbin/TClient:        userInitiated: 1
01/28:20:59:04: /tvbin/TClient:  End Ident        =====================
01/28:20:59:04: /tvbin/TClient:  
01/28:20:59:04: /tvbin/TClient:  Logging camid.
01/28:20:59:04: /tvbin/TClient:  CAM_ID not found.
01/28:20:59:04: /tvbin/TClient:  Ird Id.
01/28:20:59:04: /tvbin/TClient:  updateStatus: In Progress CL| 30 24
01/28:20:59:04: /tvbin/TClient:  SendDialupEvent 30 9 CL|30
01/28:20:59:04: /tvbin/TClient:  updateStatus: In Progress ST| 33 14
01/28:20:59:04: /tvbin/TClient:  SendDialupEvent 30 9 ST|33
01/28:20:59:04: /tvbin/TClient:  About to connect to 194.1.151.205:80
01/28:20:59:04: /tvbin/TClient:  Executing HTTP command: /tvbin/http_post /var/log/svclog 
http://194.1.151.205:80/tivo-service/mlog.cgi OFF OFF ON
01/28:20:59:10: /tvbin/TClient:  about to do TCD411 Call
01/28:20:59:10: /tvbin/TClient:  Executing HTTP command: /tvbin/tclient_post 194.1.151.205:80 /var/tmp/TCD411.send /var/tmp/TCD411.recv 300 ON
01/28:20:59:10: /tvbin/TClient:  doHttpCall returned: 1
01/28:20:59:10: /tvbin/TClient:  reading HTTP header...
01/28:20:59:10: /tvbin/TClient:  HTTP header: HTTP/1.1 200 OK
01/28:20:59:10: /tvbin/TClient:  HTTP header: Date: Sat, 28 Jan 2012 20:59:05 GMT
01/28:20:59:10: /tvbin/TClient:  HTTP header: Server: Apache/2.2.14 (Ubuntu)
01/28:20:59:10: /tvbin/TClient:  HTTP header: Vary: Accept-Encoding
01/28:20:59:10: /tvbin/TClient:  HTTP header: Connection: close
01/28:20:59:10: /tvbin/TClient:  HTTP header: Content-Type: text/plain; charset=ISO-8859-1
01/28:20:59:10: /tvbin/TClient:  Got end of Header
01/28:20:59:10: /tvbin/TClient:  TCD411RespCli::readBlock returned: 1
01/28:20:59:10: /tvbin/TClient:  
01/28:20:59:10: /tvbin/TClient:     Start TCD411Resp =====================
01/28:20:59:10: /tvbin/TClient:              errMsg: 
01/28:20:59:10: /tvbin/TClient:         areaCodeObj: OK
01/28:20:59:10: /tvbin/TClient:        tollFreeAuth: 0
01/28:20:59:10: /tvbin/TClient:         tollFreeNum: 0
01/28:20:59:10: /tvbin/TClient:       End TCD411Resp =====================
01/28:20:59:10: /tvbin/TClient:  about to do HServer Call
01/28:20:59:10: /tvbin/TClient:  Executing HTTP command: /tvbin/tclient_post 194.1.151.205:80 /var/tmp/HServer.send /var/tmp/HServer.recv 300 ON
01/28:20:59:14: /tvbin/TClient:  doHttpCall returned: 1
01/28:20:59:14: /tvbin/TClient:  reading HTTP header...
01/28:20:59:14: /tvbin/TClient:  HTTP header: HTTP/1.1 200 OK
01/28:20:59:14: /tvbin/TClient:  HTTP header: Date: Sat, 28 Jan 2012 20:59:06 GMT
01/28:20:59:14: /tvbin/TClient:  HTTP header: Server: Apache/2.2.14 (Ubuntu)
01/28:20:59:14: /tvbin/TClient:  HTTP header: Vary: Accept-Encoding
01/28:20:59:14: /tvbin/TClient:  HTTP header: Connection: close
01/28:20:59:14: /tvbin/TClient:  HTTP header: Content-Type: text/plain; charset=ISO-8859-1
01/28:20:59:14: /tvbin/TClient:  Got end of Header
01/28:20:59:14: /tvbin/TClient:  read 894 but Content-Length = 0
01/28:20:59:14: /tvbin/TClient:  SvrResp::readBlock returned: 1
01/28:20:59:14: /tvbin/TClient:  
01/28:20:59:14: /tvbin/TClient:       Start SvrResp =====================
01/28:20:59:14: /tvbin/TClient:         errMessage: 
01/28:20:59:14: /tvbin/TClient:            version: 3
01/28:20:59:14: /tvbin/TClient:               code: 8
01/28:20:59:14: /tvbin/TClient:       softwareList: &http://194.1.151.205:80/tivo-static/ukheadends/message-20000019.slice chksum#0xA21B48313F015FE3983E3FBD7723B022C9A2A2DA|&http://194.1.151.205:80/tivo-static/ukheadends/01000-19.slice.gz chksum#0x8FD09C9E5ADE0E78542EC432313E8655FC34859A|&http://194.1.151.205:80/tivo-static/ukheadends/DBS~01-8.slice.gz chksum#0x097F97BA7961DE6916DC88A127007F534B0109FB|
01/28:20:59:14: /tvbin/TClient:     backChannelPrv: NONE
01/28:20:59:14: /tvbin/TClient:     backChannelPub: NONE
01/28:20:59:14: /tvbin/TClient:     backChannelLog: NONE
01/28:20:59:14: /tvbin/TClient:     backChannelThumb: NONE
01/28:20:59:14: /tvbin/TClient:        timeService: NONE
01/28:20:59:14: /tvbin/TClient:     sequenceCookie: 12345678
01/28:20:59:14: /tvbin/TClient:      inventoryFile: 
01/28:20:59:14: /tvbin/TClient:     noPrivBackhaul: -1
01/28:20:59:14: /tvbin/TClient:       serviceState: 4
01/28:20:59:14: /tvbin/TClient:    stateExpiration: 0
01/28:20:59:14: /tvbin/TClient:       swSystemName: 2.5.5-01-1-023
01/28:20:59:14: /tvbin/TClient:           infoCode: 
01/28:20:59:14: /tvbin/TClient:         tcdMessage: 
01/28:20:59:14: /tvbin/TClient:     globalMessages: 
01/28:20:59:14: /tvbin/TClient:          keyServer: 
01/28:20:59:14: /tvbin/TClient:      forceBackhaul: 0
01/28:20:59:14: /tvbin/TClient:    publicLogFilter: [Ee]xception([^/]| [^P]| P[^O]| PO[^S]| POS[^T])|[Aa]ssert|[B]acktrace|[Ss]egmentation|[K]ernel panic|[D]riveStatusError|[e]rrDbNoMemory
01/28:20:59:14: /tvbin/TClient:        dbLoadOrder: PG.*
01/28:20:59:14: /tvbin/TClient:         regenToken: 0
01/28:20:59:14: /tvbin/TClient:     backhaulDataOn: 1
01/28:20:59:14: /tvbin/TClient:     personalDataOn: 0
01/28:20:59:14: /tvbin/TClient:      dataGroupList: 
01/28:20:59:14: /tvbin/TClient:         End SvrResp =======================
01/28:20:59:14: /tvbin/TClient:  Connect/POST(s) succeeded
01/28:20:59:14: /tvbin/TClient:  current AreaCode object is OK
01/28:20:59:14: /tvbin/TClient:  TFA is 0
01/28:20:59:14: /tvbin/TClient:  Not overwriting  with 0
01/28:20:59:14: /tvbin/TClient:  process411 returned: 0
01/28:20:59:14: /tvbin/TClient:  updateStatus: In Progress ST| 34 26
01/28:20:59:14: /tvbin/TClient:  SendDialupEvent 30 9 ST|34
01/28:20:59:14: /tvbin/TClient:  ServiceInfo DataGroupList attribute was not sent by the service
01/28:20:59:14: /tvbin/TClient:  Setting ServiceInfo PublicLogFilter attribute to [Ee]xception([^/]| [^P]| P[^O]| PO[^S]| POS[^T])|[Aa]ssert|[B]acktrace|[Ss]egmentation|[K]ernel panic|[D]riveStatusError|[e]rrDbNoMemory
01/28:20:59:14: /tvbin/TClient:  updateStatus: In Progress ST| 34 26
01/28:20:59:14: /tvbin/TClient:  SendDialupEvent 30 9 ST|34
01/28:20:59:15: /tvbin/TClient:  starting backhaul: 0
01/28:20:59:15: /tvbin/TClient:  Setting ServiceInfo ForceBackhaul attribute to 0
01/28:20:59:15: /tvbin/TClient:  starting backhaul2
01/28:20:59:15: /tvbin/TClient:  starting backhaul3
01/28:20:59:15: /tvbin/TClient:  updateStatus: In Progress ST| 35 21
01/28:20:59:15: /tvbin/TClient:  SendDialupEvent 30 9 ST|35
01/28:20:59:15: /tvbin/TClient:  updateStatus: In Progress DL| 36 23
01/28:20:59:15: /tvbin/TClient:  SendDialupEvent 30 9 DL|36
01/28:20:59:15: /tvbin/TClient:  updateStatus: In Progress DL| 38 16
01/28:20:59:15: /tvbin/TClient:  SendDialupEvent 30 9 DL|38
01/28:20:59:15: /tvbin/TClient:  sw system name 2.5.5-01-1-023 is already current
01/28:20:59:15: /tvbin/TClient:  Executing HTTP GET: /tvbin/http_get -U 
http://194.1.151.205:80/tivo-static/ukh ... 0019.slice -D /var/packages -T 0230000601E8783 -C 1327784316 -d 
01/28:20:59:16: /tvbin/TClient:  Executing HTTP GET: /tvbin/http_get -U 
http://194.1.151.205:80/tivo-static/ukh ... 9.slice.gz -D /var/packages -T 0230000601E8783 -C 1327784316 -d 
01/28:21:07:26: /tvbin/TClient:  http GET command failed:  timeout waiting for data
01/28:21:07:26: /tvbin/TClient:  Download failure: 1
01/28:21:07:26: /tvbin/TClient:  Executing HTTP command: /tvbin/http_post /var/log/svclog 
http://194.1.151.205:80/tivo-service/mlog.cgi OFF OFF ON
01/28:21:07:28: /tvbin/TClient:  no comm log file to post
01/28:21:07:28: /tvbin/TClient:  backHaulDone is 1
01/28:21:07:28: /tvbin/TClient:  registerNewSoftware: getting SwSystemName
retrying after errTmBackgroundHoldoff ...
retrying after errTmBackgroundHoldoff ...
01/28:21:07:35: /tvbin/TClient:  Software system 2.5.5-01-1-023 is present and active
01/28:21:07:35: /tvbin/TClient:  updateStatus: Failed DL| 38 16
Saving 'DL|16' to result file
01/28:21:07:35: /tvbin/TClient:  writing Failed to LastCallStatus
01/28:21:07:35: /tvbin/TClient:  SendDialupEvent 30 9 EX|16
01/28:21:07:35: /tvbin/TClient:  TClient login: 1
01/28:21:07:35: /tvbin/TClient:  TClient backhaulDone: 1