/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